-- Logs begin at Thu 2019-02-14 19:12:00 KST, end at Thu 2025-06-26 17:26:27 KST. -- Jun 26 17:25:00 volumio-ap2 volumio[7772]: info: Starting Streaming Service Transparent Proxy Jun 26 17:25:00 volumio-ap2 volumio[7772]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 26 17:25:00 volumio-ap2 volumio[7772]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 26 17:25:00 volumio-ap2 volumio[7772]: info: Streaming services startup Jun 26 17:25:00 volumio-ap2 volumio[7772]: info: Starting Streaming Daemon Jun 26 17:25:01 volumio-ap2 sudo[8101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 26 17:25:01 volumio-ap2 sudo[8101]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 17:25:01 volumio-ap2 volumio[7772]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 26 17:25:01 volumio-ap2 sudo[8101]: pam_unix(sudo:session): session closed for user root Jun 26 17:25:01 volumio-ap2 volumio[7772]: error: Cannot start Volumio Streaming Daemon Jun 26 17:25:01 volumio-ap2 volumio[7772]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 26 17:25:01 volumio-ap2 volumio[7772]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 26 17:25:02 volumio-ap2 volumio[7772]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 26 17:25:03 volumio-ap2 volumio[7772]: info: MyVolumio token set successfully Jun 26 17:25:03 volumio-ap2 volumio[7772]: info: MYVOLUMIO: Adding device Jun 26 17:25:03 volumio-ap2 volumio[7772]: info: MYVOLUMIO: Evaluating Server Jun 26 17:25:03 volumio-ap2 volumio[7772]: STREAMING PROXY: Starting server on port 3245 Jun 26 17:25:03 volumio-ap2 volumio[7772]: Node JS runtime: 14 Jun 26 17:25:03 volumio-ap2 volumio[7772]: info: MyVolumio status changed Jun 26 17:25:03 volumio-ap2 volumio[7772]: info: Streaming services startup Jun 26 17:25:03 volumio-ap2 volumio[7772]: info: Starting Streaming Daemon Jun 26 17:25:03 volumio-ap2 volumio[7772]: info: Removing browser output: myVolumio user plan is not superstar Jun 26 17:25:03 volumio-ap2 volumio[7772]: info: Removing audio output: Jun 26 17:25:03 volumio-ap2 volumio[7772]: info: Stoppping Tunnel 1 Jun 26 17:25:04 volumio-ap2 sudo[8129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 26 17:25:04 volumio-ap2 sudo[8129]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 17:25:04 volumio-ap2 sudo[8132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 26 17:25:04 volumio-ap2 sudo[8132]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 17:25:04 volumio-ap2 sudo[8129]: pam_unix(sudo:session): session closed for user root Jun 26 17:25:04 volumio-ap2 volumio[7772]: error: Cannot start Volumio Streaming Daemon Jun 26 17:25:04 volumio-ap2 volumio[7772]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 26 17:25:04 volumio-ap2 volumio[7772]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 26 17:25:04 volumio-ap2 sudo[8132]: pam_unix(sudo:session): session closed for user root Jun 26 17:25:04 volumio-ap2 volumio[7772]: info: Remote SSH Stopped Jun 26 17:25:04 volumio-ap2 volumio[7772]: info: Setting Geolocation for MyVolumio to as1 Jun 26 17:25:04 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 17:25:04 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 17:25:04 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 17:25:04 volumio-ap2 volumio[7772]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 26 17:25:05 volumio-ap2 volumio[7772]: info: Updating MyVolumio device info Jun 26 17:25:05 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 17:25:05 volumio-ap2 volumio[7772]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 26 17:25:08 volumio-ap2 volumio[7772]: info: MYVOLUMIO: Adding device Jun 26 17:25:08 volumio-ap2 volumio[7772]: info: MYVOLUMIO: Evaluating Server Jun 26 17:25:09 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 26 17:25:09 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 17:25:09 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 26 17:25:09 volumio-ap2 volumio-remote-updater[444]: No test mode Jun 26 17:25:09 volumio-ap2 volumio-remote-updater[444]: No alpha test mode Jun 26 17:25:09 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 26 17:25:09 volumio-ap2 volumio[7772]: info: Setting Geolocation for MyVolumio to as1 Jun 26 17:25:09 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 17:25:09 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 17:25:09 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 17:25:10 volumio-ap2 volumio[7772]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 26 17:25:10 volumio-ap2 volumio[7772]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 26 17:25:10 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 26 17:25:10 volumio-ap2 volumio[7772]: info: Updating MyVolumio device info Jun 26 17:25:10 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 17:25:10 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 17:25:10 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 17:25:11 volumio-ap2 volumio[7772]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 26 17:25:13 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 26 17:25:13 volumio-ap2 volumio[7772]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 26 17:25:14 volumio-ap2 volumio[7772]: info: CoreCommandRouter::volumioGetState Jun 26 17:25:14 volumio-ap2 volumio[7772]: info: CorePlayQueue::getTrack 0 Jun 26 17:25:18 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 17:25:18 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 17:25:18 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 26 17:25:18 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 26 17:25:18 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 26 17:25:18 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 26 17:25:18 volumio-ap2 volumio[7772]: info: CoreCommandRouter::volumioGetBrowseSources Jun 26 17:25:18 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 26 17:25:21 volumio-ap2 volumio[7772]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 26 17:25:21 volumio-ap2 volumio[7772]: info: Completed starting MyVolumio Plugin Jun 26 17:25:21 volumio-ap2 volumio[7772]: [Metrics] CommandRouter: 56s 900.61ms Jun 26 17:25:21 volumio-ap2 volumio[7772]: info: CoreCommandRouter::volumiosetStartupVolume Jun 26 17:25:21 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 17:25:21 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 17:25:21 volumio-ap2 volumio[7772]: info: CoreCommandRouter::Close All Modals sent Jun 26 17:25:21 volumio-ap2 volumio[7772]: info: CoreCommandRouter::Close All Modals sent Jun 26 17:25:22 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 26 17:25:22 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 26 17:25:22 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 26 17:25:28 volumio-ap2 volumio[7772]: info: BOOT COMPLETED Jun 26 17:25:40 volumio-ap2 wpa_supplicant[823]: RRM: Unsupported radio measurement type 9 Jun 26 17:26:00 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: han_radio , handleBrowseUri Jun 26 17:26:00 volumio-ap2 volumio[7772]: info: Preload queue cleared Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: Preload queue cleared Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: CoreStateMachine::ClearQueue Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: CoreStateMachine::stop Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: CorePlayQueue::clearPlayQueue Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: CorePlayQueue::saveQueue Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: CoreCommandRouter::volumioPushQueue Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: CoreStateMachine::addQueueItems Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: CorePlayQueue::addQueueItems Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: Preload queue cleared Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: Adding Item to queue: cbs/1 Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: Exploding uri cbs/1 in service han_radio Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: [1750926366073] [HanRadio] uris ===== cbs,1 Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: [1750926366074] [HanRadio] channel ===== 1 Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: CoreCommandRouter::volumioPushQueue Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: CorePlayQueue::saveQueue Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: CoreStateMachine::updateTrackBlock Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: CorePlayQueue::getTrackBlock Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: CoreCommandRouter::volumioPlay Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: CoreStateMachine::play index 0 Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: CoreStateMachine::stop Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: CoreStateMachine::play index undefined Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: CorePlayQueue::getTrack 0 Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: CoreStateMachine::startPlaybackTimer Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: CorePlayQueue::getTrack 0 Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: [1750926366848] [HanRadio] clearAddPlayTrack ===== + clearAddPlayTrack Jun 26 17:26:06 volumio-ap2 volumio[7772]: verbose: ControllerMpd::sendMpdCommand stop Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: sendMpdCommand stop took 11 milliseconds Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: [1750926366863] [HanRadio] clearAddPlayTrack ===== + stop Jun 26 17:26:06 volumio-ap2 volumio[7772]: verbose: ControllerMpd::sendMpdCommand clear Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: Jun 26 17:26:06 volumio-ap2 volumio[7772]: ---------------------------- MPD announces system playlist update Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: Ignoring MPD Status Update Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: sendMpdCommand clear took 7 milliseconds Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: [1750926366875] [HanRadio] clearAddPlayTrack ===== https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/chunklist.m3u8 Jun 26 17:26:06 volumio-ap2 volumio[7772]: verbose: ControllerMpd::sendMpdCommand add "https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/chunklist.m3u8" Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: Jun 26 17:26:06 volumio-ap2 volumio[7772]: ---------------------------- MPD announces system playlist update Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: Ignoring MPD Status Update Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: Jun 26 17:26:06 volumio-ap2 volumio[7772]: ---------------------------- MPD announces system playlist update Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: Ignoring MPD Status Update Jun 26 17:26:06 volumio-ap2 volumio[7772]: error: updateQueue error: null Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: Jun 26 17:26:06 volumio-ap2 volumio[7772]: ---------------------------- MPD announces system playlist update Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: Ignoring MPD Status Update Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: ------------------------------ 28ms Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: sendMpdCommand add "https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/chunklist.m3u8" took 20 milliseconds Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: ------------------------------ 20ms Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: ------------------------------ 16ms Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: [1750926366901] [HanRadio] clearAddPlayTrack ===== + info Jun 26 17:26:06 volumio-ap2 volumio[7772]: verbose: ControllerMpd::sendMpdCommand play Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: Jun 26 17:26:06 volumio-ap2 volumio[7772]: ---------------------------- MPD announces system playlist update Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: Ignoring MPD Status Update Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: Jun 26 17:26:06 volumio-ap2 volumio[7772]: ---------------------------- MPD announces system playlist update Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: Ignoring MPD Status Update Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: ------------------------------ 31ms Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: sendMpdCommand play took 15 milliseconds Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: ------------------------------ 13ms Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: ------------------------------ 10ms Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: [1750926366925] [HanRadio] clearAddPlayTrack ===== + play Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 26 17:26:06 volumio-ap2 volumio[7772]: info: [1750926366928] [HanRadio] clearAddPlayTrack setMetaData Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: Jun 26 17:26:08 volumio-ap2 volumio[7772]: ---------------------------- MPD announces state update: player Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: ControllerMpd::getState Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: ControllerMpd::sendMpdCommand status Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: Jun 26 17:26:08 volumio-ap2 volumio[7772]: ---------------------------- MPD announces state update: player Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: ControllerMpd::getState Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: ControllerMpd::sendMpdCommand status Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: Jun 26 17:26:08 volumio-ap2 volumio[7772]: ---------------------------- MPD announces state update: player Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: ControllerMpd::getState Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: ControllerMpd::sendMpdCommand status Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: Jun 26 17:26:08 volumio-ap2 volumio[7772]: ---------------------------- MPD announces state update: player Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: ControllerMpd::getState Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: ControllerMpd::sendMpdCommand status Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: sendMpdCommand status took 22 milliseconds Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: sendMpdCommand status took 17 milliseconds Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: sendMpdCommand status took 12 milliseconds Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: ControllerMpd::parseState Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: ControllerMpd::parseState Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: ControllerMpd::parseState Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: Jun 26 17:26:08 volumio-ap2 volumio[7772]: ---------------------------- MPD announces state update: player Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: ControllerMpd::getState Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: ControllerMpd::sendMpdCommand status Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: Jun 26 17:26:08 volumio-ap2 volumio[7772]: ---------------------------- MPD announces state update: player Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: ControllerMpd::getState Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: ControllerMpd::sendMpdCommand status Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: sendMpdCommand status took 45 milliseconds Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: sendMpdCommand playlistinfo took 34 milliseconds Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: sendMpdCommand playlistinfo took 33 milliseconds Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: sendMpdCommand playlistinfo took 25 milliseconds Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: sendMpdCommand status took 18 milliseconds Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: sendMpdCommand status took 15 milliseconds Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: ControllerMpd::parseState Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: ControllerMpd::parseTrackInfo Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: ControllerMpd::parseTrackInfo Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: ControllerMpd::parseTrackInfo Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: ControllerMpd::parseState Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: ControllerMpd::parseState Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: ControllerMpd::pushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreCommandRouter::servicePushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CorePlayQueue::getTrack 0 Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"chunklist.m3u8","artist":null,"album":null,"uri":"https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/chunklist.m3u8","trackType":"m3u8"} Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: CURRENT POSITION 0 Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreStateMachine::syncState stateService play Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreStateMachine::syncState currentStatus stop Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: ControllerMpd::pushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreCommandRouter::servicePushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CorePlayQueue::getTrack 0 Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"chunklist.m3u8","artist":null,"album":null,"uri":"https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/chunklist.m3u8","trackType":"m3u8"} Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: CURRENT POSITION 0 Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreStateMachine::syncState stateService play Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreStateMachine::syncState currentStatus play Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: Received an update from plugin. extracting info from payload Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreStateMachine::pushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CorePlayQueue::getTrack 0 Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreCommandRouter::volumioPushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreStateMachine::pushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreCommandRouter::volumioPushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: ControllerMpd::pushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreCommandRouter::servicePushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CorePlayQueue::getTrack 0 Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"chunklist.m3u8","artist":null,"album":null,"uri":"https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/chunklist.m3u8","trackType":"m3u8"} Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: CURRENT POSITION 0 Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreStateMachine::syncState stateService play Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreStateMachine::syncState currentStatus play Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: Received an update from plugin. extracting info from payload Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreStateMachine::pushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CorePlayQueue::getTrack 0 Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreCommandRouter::volumioPushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreStateMachine::pushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreCommandRouter::volumioPushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: ------------------------------ 173ms Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: ------------------------------ 206ms Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: ------------------------------ 205ms Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: sendMpdCommand playlistinfo took 143 milliseconds Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: sendMpdCommand playlistinfo took 135 milliseconds Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: sendMpdCommand playlistinfo took 134 milliseconds Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: ControllerMpd::parseTrackInfo Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: ControllerMpd::parseTrackInfo Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: ControllerMpd::parseTrackInfo Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: ControllerMpd::pushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreCommandRouter::servicePushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CorePlayQueue::getTrack 0 Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"chunklist.m3u8","artist":null,"album":null,"uri":"https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/chunklist.m3u8","trackType":"m3u8"} Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: CURRENT POSITION 0 Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreStateMachine::syncState stateService play Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreStateMachine::syncState currentStatus play Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: Received an update from plugin. extracting info from payload Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreStateMachine::pushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CorePlayQueue::getTrack 0 Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreCommandRouter::volumioPushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreStateMachine::pushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreCommandRouter::volumioPushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: ControllerMpd::pushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreCommandRouter::servicePushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CorePlayQueue::getTrack 0 Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"chunklist.m3u8","artist":null,"album":null,"uri":"https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/chunklist.m3u8","trackType":"m3u8"} Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: CURRENT POSITION 0 Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreStateMachine::syncState stateService play Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreStateMachine::syncState currentStatus play Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: Received an update from plugin. extracting info from payload Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreStateMachine::pushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CorePlayQueue::getTrack 0 Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreCommandRouter::volumioPushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreStateMachine::pushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreCommandRouter::volumioPushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: ControllerMpd::pushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreCommandRouter::servicePushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CorePlayQueue::getTrack 0 Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"chunklist.m3u8","artist":null,"album":null,"uri":"https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/chunklist.m3u8","trackType":"m3u8"} Jun 26 17:26:08 volumio-ap2 volumio[7772]: verbose: CURRENT POSITION 0 Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreStateMachine::syncState stateService play Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreStateMachine::syncState currentStatus play Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: Received an update from plugin. extracting info from payload Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreStateMachine::pushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CorePlayQueue::getTrack 0 Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreCommandRouter::volumioPushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreStateMachine::pushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: CoreCommandRouter::volumioPushState Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: ------------------------------ 340ms Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: ------------------------------ 309ms Jun 26 17:26:08 volumio-ap2 volumio[7772]: info: ------------------------------ 305ms Jun 26 17:26:26 volumio-ap2 volumio[7772]: info: Preload queue cleared Jun 26 17:26:26 volumio-ap2 volumio[7772]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 26 17:26:26 volumio-ap2 volumio[7772]: info: CoreStateMachine::ClearQueue Jun 26 17:26:26 volumio-ap2 volumio[7772]: info: CoreStateMachine::stop Jun 26 17:26:26 volumio-ap2 volumio[7772]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 26 17:26:26 volumio-ap2 volumio[7772]: info: CoreStateMachine::stPlaybackTimer Jun 26 17:26:26 volumio-ap2 volumio[7772]: info: CoreStateMachine::updateTrackBlock Jun 26 17:26:26 volumio-ap2 volumio[7772]: info: CorePlayQueue::getTrackBlock Jun 26 17:26:26 volumio-ap2 volumio[7772]: info: CoreStateMachine::pushState Jun 26 17:26:26 volumio-ap2 volumio[7772]: info: CorePlayQueue::getTrack 0 Jun 26 17:26:26 volumio-ap2 volumio[7772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 17:26:26 volumio-ap2 volumio[7772]: info: CoreCommandRouter::volumioPushState Jun 26 17:26:26 volumio-ap2 volumio[7772]: info: CoreStateMachine::serviceStop Jun 26 17:26:26 volumio-ap2 volumio[7772]: info: CorePlayQueue::getTrack 0 Jun 26 17:26:26 volumio-ap2 volumio[7772]: info: CoreCommandRouter::serviceStop Jun 26 17:26:26 volumio-ap2 volumio[7772]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 26 17:26:26 volumio-ap2 volumio[7772]: TypeError: Cannot read property 'logger' of undefined Jun 26 17:26:26 volumio-ap2 volumio[7772]: at ControllerHanRadio.stop (/data/plugins/music_service/han_radio/index.js:290:10) Jun 26 17:26:26 volumio-ap2 volumio[7772]: at CoreCommandRouter.serviceStop (/volumio/app/index.js:371:25) Jun 26 17:26:26 volumio-ap2 volumio[7772]: at CoreStateMachine.serviceStop (/volumio/app/statemachine.js:1269:33) Jun 26 17:26:26 volumio-ap2 volumio[7772]: at CoreStateMachine.stop (/volumio/app/statemachine.js:1244:19) Jun 26 17:26:26 volumio-ap2 volumio[7772]: at CoreStateMachine.clearQueue (/volumio/app/statemachine.js:273:8) Jun 26 17:26:26 volumio-ap2 volumio[7772]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:494:21) Jun 26 17:26:26 volumio-ap2 volumio[7772]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17) Jun 26 17:26:26 volumio-ap2 volumio[7772]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Jun 26 17:26:26 volumio-ap2 volumio[7772]: at Socket.emit (events.js:315:20) Jun 26 17:26:26 volumio-ap2 volumio[7772]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 26 17:26:26 volumio-ap2 volumio[7772]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 26 17:26:27 volumio-ap2 sudo[8333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-26 17:25 Jun 26 17:26:27 volumio-ap2 sudo[8333]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"