-- Logs begin at Wed 2025-03-19 06:34:21 UTC, end at Wed 2025-03-19 07:48:39 UTC. -- Mar 19 07:47:00 volumio wpa_supplicant[2652]: wlan0: Trying to associate with 00:07:89:83:84:95 (SSID='KT_GiGA_2G_Wave2_8492' freq=2442 MHz) Mar 19 07:47:00 volumio wpa_supplicant[2652]: wlan0: Association request to the driver failed Mar 19 07:47:00 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:00 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:00 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:02 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:02 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:02 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:04 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:04 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:04 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:06 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:06 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:06 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:07 volumio volumio[1010]: info: Downloading plugin at http://plugins.volumio.org/plugins/volumio/armhf/music_service/personal_radio/personal_radio.zip Mar 19 07:47:08 volumio shairport-sync[5451]: no RTSP header received Mar 19 07:47:08 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:08 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:08 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:09 volumio volumio[1010]: info: END DOWNLOAD: http://plugins.volumio.org/plugins/volumio/armhf/music_service/personal_radio/personal_radio.zip Mar 19 07:47:09 volumio volumio[1010]: info: Folder /tmp/plugins removed Mar 19 07:47:10 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:10 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:10 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:10 volumio volumio[1010]: info: Checking if plugin already exists Mar 19 07:47:10 volumio volumio[1010]: info: Rename folder Mar 19 07:47:10 volumio volumio[1010]: info: Folder /tmp/downloaded_plugin.zip removed Mar 19 07:47:10 volumio volumio[1010]: info: Move to category Mar 19 07:47:11 volumio wpa_supplicant[2652]: wlan0: Trying to associate with 00:07:89:83:84:95 (SSID='KT_GiGA_2G_Wave2_8492' freq=2442 MHz) Mar 19 07:47:11 volumio wpa_supplicant[2652]: wlan0: Association request to the driver failed Mar 19 07:47:12 volumio volumio[1010]: info: Checking if install.sh is present Mar 19 07:47:12 volumio volumio[1010]: info: Executing install.sh Mar 19 07:47:12 volumio sudo[32163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/personal_radio/install.sh Mar 19 07:47:12 volumio sudo[32163]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 19 07:47:12 volumio sudo[32163]: pam_unix(sudo:session): session closed for user root Mar 19 07:47:12 volumio volumio[1010]: info: Installing PersonalRadio Plugin Dependencies Mar 19 07:47:12 volumio volumio[1010]: info: PersonalRadio plugin installed Mar 19 07:47:12 volumio volumio[1010]: info: Install script completed Mar 19 07:47:12 volumio volumio[1010]: info: Adding reference to registry Mar 19 07:47:12 volumio volumio[1010]: info: Done installing plugin. Mar 19 07:47:12 volumio volumio[1010]: info: Folder /tmp/plugins removed Mar 19 07:47:12 volumio volumio[1010]: info: Folder /tmp/downloaded_plugin.zip removed Mar 19 07:47:12 volumio volumio[1010]: info: Folder /data/temp removed Mar 19 07:47:12 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:12 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:12 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:14 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:14 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:14 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:16 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:16 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:16 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:18 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:18 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:18 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:20 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:20 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:20 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:21 volumio volumio[1010]: info: Enabling plugin personal_radio Mar 19 07:47:21 volumio volumio[1010]: info: Loading plugin "personal_radio"... Mar 19 07:47:23 volumio wpa_supplicant[2652]: wlan0: Trying to associate with 00:07:89:83:84:95 (SSID='KT_GiGA_2G_Wave2_8492' freq=2442 MHz) Mar 19 07:47:23 volumio wpa_supplicant[2652]: wlan0: Association request to the driver failed Mar 19 07:47:23 volumio volumio[1010]: info: ControllerPersonalRadio::constructor Mar 19 07:47:23 volumio volumio[1010]: info: PLUGIN START: personal_radio Mar 19 07:47:23 volumio volumio[1010]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 19 07:47:23 volumio volumio[1010]: info: [1742370443425] CoreMusicLibrary::Adding element Personal Radio Mar 19 07:47:23 volumio volumio[1010]: info: Done. Mar 19 07:47:23 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:23 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:23 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:24 volumio volumio[1010]: info: Enabling plugin radio_paradise Mar 19 07:47:24 volumio volumio[1010]: info: Loading plugin "radio_paradise"... Mar 19 07:47:24 volumio volumio[1010]: info: [1742370444510] [RadioParadise] API delay: 5 Mar 19 07:47:24 volumio volumio[1010]: info: PLUGIN START: radio_paradise Mar 19 07:47:24 volumio volumio[1010]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 19 07:47:24 volumio volumio[1010]: info: [1742370444520] CoreMusicLibrary::Adding element Radio Paradise Mar 19 07:47:24 volumio volumio[1010]: info: Done. Mar 19 07:47:25 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:25 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:25 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:26 volumio volumio[1010]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 19 07:47:27 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:27 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:27 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:29 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:29 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:29 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:30 volumio volumio[1010]: info: [{"prettyName":"Autoplay","name":"auto_play","category":"miscellanea","version":"1.0.0","icon":"fa-play-circle-o","enabled":true,"active":true},{"prettyName":"NanoSound by Nanomesher","name":"nanosound","category":"miscellanea","version":"1.1.5","icon":"fa-play-circle-o","enabled":true,"active":true},{"prettyName":"Personal Radio","name":"personal_radio","category":"music_service","version":"1.1.5","icon":"fa-headphones","enabled":true,"active":true},{"prettyName":"Radio Paradise","name":"radio_paradise","category":"music_service","version":"1.1.5","icon":"fa-headphones","enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"3.0.3","icon":"fa-spotify","enabled":false,"active":false}] Mar 19 07:47:31 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:31 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:31 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:33 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:33 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:33 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:34 volumio wpa_supplicant[2652]: wlan0: Trying to associate with 00:07:89:83:84:95 (SSID='KT_GiGA_2G_Wave2_8492' freq=2442 MHz) Mar 19 07:47:34 volumio wpa_supplicant[2652]: wlan0: Association request to the driver failed Mar 19 07:47:35 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:35 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:35 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:36 volumio volumio[1010]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 19 07:47:37 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:37 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:37 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:39 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:39 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:39 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:40 volumio volumio[1010]: info: [{"prettyName":"Autoplay","name":"auto_play","category":"miscellanea","version":"1.0.0","icon":"fa-play-circle-o","enabled":true,"active":true},{"prettyName":"NanoSound by Nanomesher","name":"nanosound","category":"miscellanea","version":"1.1.5","icon":"fa-play-circle-o","enabled":true,"active":true},{"prettyName":"Personal Radio","name":"personal_radio","category":"music_service","version":"1.1.5","icon":"fa-headphones","enabled":true,"active":true},{"prettyName":"Radio Paradise","name":"radio_paradise","category":"music_service","version":"1.1.5","icon":"fa-headphones","enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"3.0.3","icon":"fa-spotify","enabled":false,"active":false}] Mar 19 07:47:41 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:41 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:41 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:43 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:43 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:43 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:45 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:45 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:45 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:45 volumio volumio[1010]: info: No input data Mar 19 07:47:45 volumio volumio[1010]: info: No input data Mar 19 07:47:46 volumio wpa_supplicant[2652]: wlan0: Trying to associate with 00:07:89:83:84:95 (SSID='KT_GiGA_2G_Wave2_8492' freq=2442 MHz) Mar 19 07:47:46 volumio wpa_supplicant[2652]: wlan0: Association request to the driver failed Mar 19 07:47:47 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:47 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:47 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:47 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , handleBrowseUri Mar 19 07:47:49 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:49 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:49 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:50 volumio volumio[1010]: info: CoreCommandRouter::volumioAddQueueItems Mar 19 07:47:50 volumio volumio[1010]: info: CoreStateMachine::addQueueItems Mar 19 07:47:50 volumio volumio[1010]: info: CorePlayQueue::addQueueItems Mar 19 07:47:50 volumio volumio[1010]: info: Adding Item to queue: webrp/1 Mar 19 07:47:50 volumio volumio[1010]: info: Exploding uri webrp/1 in service radio_paradise Mar 19 07:47:50 volumio volumio[1010]: info: CorePlayQueue::saveQueue Mar 19 07:47:50 volumio volumio[1010]: info: CoreCommandRouter::volumioPushQueue Mar 19 07:47:50 volumio volumio[1010]: info: CoreStateMachine::updateTrackBlock Mar 19 07:47:50 volumio volumio[1010]: info: CorePlayQueue::getTrackBlock Mar 19 07:47:50 volumio volumio[1010]: info: CoreCommandRouter::volumioPlay Mar 19 07:47:50 volumio volumio[1010]: UNSET VOLATILE Mar 19 07:47:50 volumio volumio[1010]: info: CoreStateMachine::play index 171 Mar 19 07:47:50 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 07:47:50 volumio volumio[1010]: info: CoreStateMachine::stop Mar 19 07:47:50 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 07:47:50 volumio volumio[1010]: UNSET VOLATILE Mar 19 07:47:50 volumio volumio[1010]: info: CoreStateMachine::stPlaybackTimer Mar 19 07:47:50 volumio volumio[1010]: info: CoreStateMachine::updateTrackBlock Mar 19 07:47:50 volumio volumio[1010]: info: CorePlayQueue::getTrackBlock Mar 19 07:47:50 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:47:50 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:50 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:50 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:47:50 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:47:50 volumio volumio[1010]: info: CoreStateMachine::serviceStop Mar 19 07:47:50 volumio volumio[1010]: info: CorePlayQueue::getTrack 96 Mar 19 07:47:50 volumio volumio[1010]: info: CoreCommandRouter::serviceStop Mar 19 07:47:50 volumio volumio[1010]: info: ControllerMpd::stop Mar 19 07:47:50 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand stop Mar 19 07:47:50 volumio volumio[1010]: info: sending command... Mar 19 07:47:51 volumio volumio[1010]: info: Mar 19 07:47:51 volumio volumio[1010]: ---------------------------- MPD announces state update: player Mar 19 07:47:51 volumio volumio[1010]: info: parsing response... Mar 19 07:47:51 volumio volumio[1010]: info: ControllerMpd::getState Mar 19 07:47:51 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand status Mar 19 07:47:51 volumio volumio[1010]: info: CoreStateMachine::play index undefined Mar 19 07:47:51 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 07:47:51 volumio volumio[1010]: info: sending command... Mar 19 07:47:51 volumio volumio[1010]: info: CorePlayQueue::getTrack 171 Mar 19 07:47:51 volumio volumio[1010]: info: CoreStateMachine::startPlaybackTimer Mar 19 07:47:51 volumio volumio[1010]: info: CorePlayQueue::getTrack 171 Mar 19 07:47:51 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand stop Mar 19 07:47:51 volumio volumio[1010]: info: sending command... Mar 19 07:47:51 volumio volumio[1010]: info: parsing response... Mar 19 07:47:51 volumio volumio[1010]: info: parsing response... Mar 19 07:47:51 volumio volumio[1010]: info: ControllerMpd::parseState Mar 19 07:47:51 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:47:51 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand clear Mar 19 07:47:51 volumio volumio[1010]: info: sending command... Mar 19 07:47:51 volumio volumio[1010]: info: sending command... Mar 19 07:47:51 volumio volumio[1010]: info: Mar 19 07:47:51 volumio volumio[1010]: ---------------------------- MPD announces system playlist update Mar 19 07:47:51 volumio volumio[1010]: info: Ignoring MPD Status Update Mar 19 07:47:51 volumio volumio[1010]: info: parsing response... Mar 19 07:47:51 volumio volumio[1010]: info: parsing response... Mar 19 07:47:51 volumio volumio[1010]: info: ControllerMpd::parseTrackInfo Mar 19 07:47:51 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand consume 1 Mar 19 07:47:51 volumio volumio[1010]: info: ControllerMpd::pushState Mar 19 07:47:51 volumio volumio[1010]: info: CoreCommandRouter::servicePushState Mar 19 07:47:51 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:47:51 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:47:51 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:51 volumio volumio[1010]: info: CorePlayQueue::getTrack 171 Mar 19 07:47:51 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:47:51 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:47:51 volumio volumio[1010]: info: CorePlayQueue::getTrack 171 Mar 19 07:47:51 volumio volumio[1010]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 19 07:47:51 volumio volumio[1010]: info: sending command... Mar 19 07:47:51 volumio volumio[1010]: info: ------------------------------ 82ms Mar 19 07:47:51 volumio volumio[1010]: info: Mar 19 07:47:51 volumio volumio[1010]: info: ------------------------------ 30ms Mar 19 07:47:51 volumio volumio[1010]: info: parsing response... Mar 19 07:47:51 volumio volumio[1010]: info: [1742370471102] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/mellow-flac Mar 19 07:47:51 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" Mar 19 07:47:51 volumio volumio[1010]: info: sending command... Mar 19 07:47:51 volumio volumio[1010]: info: Mar 19 07:47:51 volumio volumio[1010]: ---------------------------- MPD announces system playlist update Mar 19 07:47:51 volumio volumio[1010]: info: Ignoring MPD Status Update Mar 19 07:47:51 volumio volumio[1010]: info: parsing response... Mar 19 07:47:51 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand play Mar 19 07:47:51 volumio volumio[1010]: info: sending command... Mar 19 07:47:51 volumio volumio[1010]: info: ------------------------------ 13ms Mar 19 07:47:51 volumio volumio[1010]: info: parsing response... Mar 19 07:47:51 volumio volumio[1010]: info: [1742370471129] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Mar 19 07:47:51 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:51 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:51 volumio volumio[1010]: info: CorePlayQueue::getTrack 171 Mar 19 07:47:51 volumio volumio[1010]: info: [1742370471734] [RadioParadise] received new metadata: {"time":168,"artist":"Fruit Bats","title":"Today","album":"Siamese Dream","year":"2020","cover":"https://img.radioparadise.com/covers/l/18862_75116c95-1d06-4684-af7d-6e92280fd05a.jpg","cover_med":"https://img.radioparadise.com/covers/m/18862_75116c95-1d06-4684-af7d-6e92280fd05a.jpg","cover_small":"https://img.radioparadise.com/covers/s/18862_75116c95-1d06-4684-af7d-6e92280fd05a.jpg"} Mar 19 07:47:51 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:51 volumio volumio[1010]: info: CorePlayQueue::getTrack 171 Mar 19 07:47:51 volumio volumio[1010]: info: CoreCommandRouter::servicePushState Mar 19 07:47:51 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:47:51 volumio volumio[1010]: info: CorePlayQueue::getTrack 171 Mar 19 07:47:51 volumio volumio[1010]: info: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/18862_75116c95-1d06-4684-af7d-6e92280fd05a.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Today","title":"Today","artist":"Fruit Bats","album":"Siamese Dream","streaming":true,"disableUiControls":true,"duration":173,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 19 07:47:51 volumio volumio[1010]: info: CURRENT POSITION 171 Mar 19 07:47:51 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play Mar 19 07:47:51 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus stop Mar 19 07:47:51 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:47:51 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:51 volumio volumio[1010]: info: CorePlayQueue::getTrack 171 Mar 19 07:47:51 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:47:51 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:47:51 volumio volumio[1010]: info: [1742370471758] [RadioParadise] setting new timer with duration of 173000 seconds. Mar 19 07:47:51 volumio volumio[1010]: info: CorePlayQueue::getTrack 171 Mar 19 07:47:51 volumio volumio[1010]: info: CorePlayQueue::getTrack 172 Mar 19 07:47:53 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:53 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:53 volumio volumio[1010]: info: CorePlayQueue::getTrack 171 Mar 19 07:47:53 volumio volumio[1010]: info: Mar 19 07:47:53 volumio volumio[1010]: ---------------------------- MPD announces state update: player Mar 19 07:47:53 volumio volumio[1010]: info: ControllerMpd::getState Mar 19 07:47:53 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand status Mar 19 07:47:53 volumio volumio[1010]: info: sending command... Mar 19 07:47:53 volumio volumio[1010]: info: Mar 19 07:47:53 volumio volumio[1010]: ---------------------------- MPD announces state update: player Mar 19 07:47:53 volumio volumio[1010]: info: parsing response... Mar 19 07:47:53 volumio volumio[1010]: info: ControllerMpd::getState Mar 19 07:47:53 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand status Mar 19 07:47:53 volumio volumio[1010]: info: ControllerMpd::parseState Mar 19 07:47:53 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:47:53 volumio volumio[1010]: info: sending command... Mar 19 07:47:53 volumio volumio[1010]: info: sending command... Mar 19 07:47:53 volumio volumio[1010]: info: Mar 19 07:47:53 volumio volumio[1010]: ---------------------------- MPD announces system playlist update Mar 19 07:47:53 volumio volumio[1010]: info: Ignoring MPD Status Update Mar 19 07:47:53 volumio volumio[1010]: info: parsing response... Mar 19 07:47:53 volumio volumio[1010]: info: parsing response... Mar 19 07:47:53 volumio volumio[1010]: info: ControllerMpd::parseState Mar 19 07:47:53 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:47:53 volumio volumio[1010]: info: ControllerMpd::parseTrackInfo Mar 19 07:47:53 volumio volumio[1010]: info: sending command... Mar 19 07:47:53 volumio volumio[1010]: info: ControllerMpd::pushState Mar 19 07:47:53 volumio volumio[1010]: info: CoreCommandRouter::servicePushState Mar 19 07:47:53 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:47:53 volumio volumio[1010]: info: CorePlayQueue::getTrack 171 Mar 19 07:47:53 volumio volumio[1010]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 19 07:47:53 volumio volumio[1010]: info: ------------------------------ 64ms Mar 19 07:47:53 volumio volumio[1010]: info: ------------------------------ 29ms Mar 19 07:47:53 volumio volumio[1010]: info: parsing response... Mar 19 07:47:53 volumio volumio[1010]: info: ControllerMpd::parseTrackInfo Mar 19 07:47:53 volumio volumio[1010]: info: ControllerMpd::pushState Mar 19 07:47:53 volumio volumio[1010]: info: CoreCommandRouter::servicePushState Mar 19 07:47:53 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:47:53 volumio volumio[1010]: info: CorePlayQueue::getTrack 171 Mar 19 07:47:53 volumio volumio[1010]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 19 07:47:53 volumio volumio[1010]: info: ------------------------------ 54ms Mar 19 07:47:55 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:55 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:55 volumio volumio[1010]: info: CorePlayQueue::getTrack 171 Mar 19 07:47:57 volumio wpa_supplicant[2652]: wlan0: Trying to associate with 00:07:89:83:84:95 (SSID='KT_GiGA_2G_Wave2_8492' freq=2442 MHz) Mar 19 07:47:57 volumio wpa_supplicant[2652]: wlan0: Association request to the driver failed Mar 19 07:47:57 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:57 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:57 volumio volumio[1010]: info: CorePlayQueue::getTrack 171 Mar 19 07:47:59 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:47:59 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:47:59 volumio volumio[1010]: info: CorePlayQueue::getTrack 171 Mar 19 07:48:01 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:48:01 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:01 volumio volumio[1010]: info: CorePlayQueue::getTrack 171 Mar 19 07:48:02 volumio volumio[1010]: info: CoreCommandRouter::volumioAddQueueItems Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::addQueueItems Mar 19 07:48:02 volumio volumio[1010]: info: CorePlayQueue::addQueueItems Mar 19 07:48:02 volumio volumio[1010]: info: Adding Item to queue: webrp/5 Mar 19 07:48:02 volumio volumio[1010]: info: Exploding uri webrp/5 in service radio_paradise Mar 19 07:48:02 volumio volumio[1010]: info: CorePlayQueue::saveQueue Mar 19 07:48:02 volumio volumio[1010]: info: CoreCommandRouter::volumioPushQueue Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::updateTrackBlock Mar 19 07:48:02 volumio volumio[1010]: info: CorePlayQueue::getTrackBlock Mar 19 07:48:02 volumio volumio[1010]: info: CoreCommandRouter::volumioPlay Mar 19 07:48:02 volumio volumio[1010]: UNSET VOLATILE Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::play index 172 Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::stop Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 07:48:02 volumio volumio[1010]: UNSET VOLATILE Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::stPlaybackTimer Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::updateTrackBlock Mar 19 07:48:02 volumio volumio[1010]: info: CorePlayQueue::getTrackBlock Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:02 volumio volumio[1010]: info: CorePlayQueue::getTrack 171 Mar 19 07:48:02 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:02 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::serviceStop Mar 19 07:48:02 volumio volumio[1010]: info: CorePlayQueue::getTrack 171 Mar 19 07:48:02 volumio volumio[1010]: info: CoreCommandRouter::serviceStop Mar 19 07:48:02 volumio volumio[1010]: info: ControllerMpd::stop Mar 19 07:48:02 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand stop Mar 19 07:48:02 volumio volumio[1010]: info: sending command... Mar 19 07:48:02 volumio volumio[1010]: info: Mar 19 07:48:02 volumio volumio[1010]: ---------------------------- MPD announces state update: player Mar 19 07:48:02 volumio volumio[1010]: info: parsing response... Mar 19 07:48:02 volumio volumio[1010]: info: ControllerMpd::getState Mar 19 07:48:02 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand status Mar 19 07:48:02 volumio volumio[1010]: info: CoreCommandRouter::servicePushState Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:02 volumio volumio[1010]: info: CorePlayQueue::getTrack 171 Mar 19 07:48:02 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:02 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:02 volumio volumio[1010]: info: CorePlayQueue::getTrack 171 Mar 19 07:48:02 volumio volumio[1010]: info: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/18862_75116c95-1d06-4684-af7d-6e92280fd05a.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Today","title":"Today","artist":"Fruit Bats","album":"Siamese Dream","streaming":true,"disableUiControls":true,"duration":173,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 19 07:48:02 volumio volumio[1010]: info: CURRENT POSITION 171 Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::syncState stateService stop Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus stop Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:02 volumio volumio[1010]: info: CorePlayQueue::getTrack 171 Mar 19 07:48:02 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:02 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:02 volumio volumio[1010]: info: No code Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:02 volumio volumio[1010]: info: CorePlayQueue::getTrack 171 Mar 19 07:48:02 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:02 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:02 volumio volumio[1010]: info: sending command... Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::play index undefined Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 07:48:02 volumio volumio[1010]: info: CorePlayQueue::getTrack 172 Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::startPlaybackTimer Mar 19 07:48:02 volumio volumio[1010]: info: CorePlayQueue::getTrack 172 Mar 19 07:48:02 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand stop Mar 19 07:48:02 volumio volumio[1010]: info: sending command... Mar 19 07:48:02 volumio volumio[1010]: info: parsing response... Mar 19 07:48:02 volumio volumio[1010]: info: parsing response... Mar 19 07:48:02 volumio volumio[1010]: info: ControllerMpd::parseState Mar 19 07:48:02 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:48:02 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand clear Mar 19 07:48:02 volumio volumio[1010]: info: sending command... Mar 19 07:48:02 volumio volumio[1010]: info: sending command... Mar 19 07:48:02 volumio volumio[1010]: info: Mar 19 07:48:02 volumio volumio[1010]: ---------------------------- MPD announces system playlist update Mar 19 07:48:02 volumio volumio[1010]: info: Ignoring MPD Status Update Mar 19 07:48:02 volumio volumio[1010]: info: parsing response... Mar 19 07:48:02 volumio volumio[1010]: info: parsing response... Mar 19 07:48:02 volumio volumio[1010]: info: ControllerMpd::parseTrackInfo Mar 19 07:48:02 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand add "http://stream-uk1.radioparadise.com/aac-128" Mar 19 07:48:02 volumio volumio[1010]: info: ControllerMpd::pushState Mar 19 07:48:02 volumio volumio[1010]: info: CoreCommandRouter::servicePushState Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:02 volumio volumio[1010]: info: CorePlayQueue::getTrack 172 Mar 19 07:48:02 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:02 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:02 volumio volumio[1010]: info: CorePlayQueue::getTrack 172 Mar 19 07:48:02 volumio volumio[1010]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 19 07:48:02 volumio volumio[1010]: info: sending command... Mar 19 07:48:02 volumio volumio[1010]: info: ------------------------------ 107ms Mar 19 07:48:02 volumio volumio[1010]: info: Mar 19 07:48:02 volumio volumio[1010]: info: Mar 19 07:48:02 volumio volumio[1010]: ---------------------------- MPD announces system playlist update Mar 19 07:48:02 volumio volumio[1010]: info: Ignoring MPD Status Update Mar 19 07:48:02 volumio volumio[1010]: info: ------------------------------ 29ms Mar 19 07:48:02 volumio volumio[1010]: info: parsing response... Mar 19 07:48:02 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand play Mar 19 07:48:02 volumio volumio[1010]: info: sending command... Mar 19 07:48:02 volumio volumio[1010]: info: ------------------------------ 23ms Mar 19 07:48:02 volumio volumio[1010]: info: parsing response... Mar 19 07:48:02 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 19 07:48:03 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:48:03 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:04 volumio volumio[1010]: info: Mar 19 07:48:04 volumio volumio[1010]: ---------------------------- MPD announces state update: player Mar 19 07:48:04 volumio volumio[1010]: info: ControllerMpd::getState Mar 19 07:48:04 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand status Mar 19 07:48:04 volumio volumio[1010]: info: sending command... Mar 19 07:48:04 volumio volumio[1010]: info: Mar 19 07:48:04 volumio volumio[1010]: ---------------------------- MPD announces system playlist update Mar 19 07:48:04 volumio volumio[1010]: info: Ignoring MPD Status Update Mar 19 07:48:04 volumio volumio[1010]: info: Mar 19 07:48:04 volumio volumio[1010]: ---------------------------- MPD announces state update: player Mar 19 07:48:04 volumio volumio[1010]: info: parsing response... Mar 19 07:48:04 volumio volumio[1010]: info: ControllerMpd::getState Mar 19 07:48:04 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand status Mar 19 07:48:04 volumio volumio[1010]: info: ControllerMpd::parseState Mar 19 07:48:04 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:48:04 volumio volumio[1010]: info: sending command... Mar 19 07:48:04 volumio volumio[1010]: info: sending command... Mar 19 07:48:04 volumio volumio[1010]: info: ------------------------------ 15ms Mar 19 07:48:04 volumio volumio[1010]: info: parsing response... Mar 19 07:48:04 volumio volumio[1010]: info: parsing response... Mar 19 07:48:04 volumio volumio[1010]: info: ControllerMpd::parseState Mar 19 07:48:04 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:48:04 volumio volumio[1010]: info: ControllerMpd::parseTrackInfo Mar 19 07:48:04 volumio volumio[1010]: info: sending command... Mar 19 07:48:04 volumio volumio[1010]: info: ControllerMpd::pushState Mar 19 07:48:04 volumio volumio[1010]: info: CoreCommandRouter::servicePushState Mar 19 07:48:04 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:48:04 volumio volumio[1010]: info: CorePlayQueue::getTrack 172 Mar 19 07:48:04 volumio volumio[1010]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Yola - Stand for Myself","artist":"Radio Paradise (128k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-128","trackType":"com/aac-128"} Mar 19 07:48:04 volumio volumio[1010]: info: CURRENT POSITION 172 Mar 19 07:48:04 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play Mar 19 07:48:04 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus stop Mar 19 07:48:04 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:04 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:04 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:04 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:04 volumio volumio[1010]: info: ------------------------------ 87ms Mar 19 07:48:04 volumio volumio[1010]: info: parsing response... Mar 19 07:48:04 volumio volumio[1010]: info: ControllerMpd::parseTrackInfo Mar 19 07:48:04 volumio volumio[1010]: info: ControllerMpd::pushState Mar 19 07:48:04 volumio volumio[1010]: info: CoreCommandRouter::servicePushState Mar 19 07:48:04 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:48:04 volumio volumio[1010]: info: CorePlayQueue::getTrack 172 Mar 19 07:48:04 volumio volumio[1010]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Yola - Stand for Myself","artist":"Radio Paradise (128k aac)","album":null,"uri":"http://stream-uk1.radioparadise.com/aac-128","trackType":"com/aac-128"} Mar 19 07:48:04 volumio volumio[1010]: info: CURRENT POSITION 172 Mar 19 07:48:04 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play Mar 19 07:48:04 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus play Mar 19 07:48:04 volumio volumio[1010]: info: Received an update from plugin. extracting info from payload Mar 19 07:48:04 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:04 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:04 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:04 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:04 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:04 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:04 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:04 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:04 volumio volumio[1010]: info: ------------------------------ 78ms Mar 19 07:48:05 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:48:05 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:07 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:48:07 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:08 volumio wpa_supplicant[2652]: wlan0: Trying to associate with 00:07:89:83:84:95 (SSID='KT_GiGA_2G_Wave2_8492' freq=2442 MHz) Mar 19 07:48:08 volumio wpa_supplicant[2652]: wlan0: Association request to the driver failed Mar 19 07:48:09 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:48:09 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:09 volumio volumio[1010]: info: CoreCommandRouter::volumioAddQueueItems Mar 19 07:48:09 volumio volumio[1010]: info: CoreStateMachine::addQueueItems Mar 19 07:48:09 volumio volumio[1010]: info: CorePlayQueue::addQueueItems Mar 19 07:48:09 volumio volumio[1010]: info: Adding Item to queue: webrp/7 Mar 19 07:48:09 volumio volumio[1010]: info: Exploding uri webrp/7 in service radio_paradise Mar 19 07:48:09 volumio volumio[1010]: info: CorePlayQueue::saveQueue Mar 19 07:48:09 volumio volumio[1010]: info: CoreCommandRouter::volumioPushQueue Mar 19 07:48:09 volumio volumio[1010]: info: CoreStateMachine::updateTrackBlock Mar 19 07:48:09 volumio volumio[1010]: info: CorePlayQueue::getTrackBlock Mar 19 07:48:09 volumio volumio[1010]: info: CoreCommandRouter::volumioPlay Mar 19 07:48:09 volumio volumio[1010]: UNSET VOLATILE Mar 19 07:48:09 volumio volumio[1010]: info: CoreStateMachine::play index 173 Mar 19 07:48:09 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 07:48:09 volumio volumio[1010]: info: CoreStateMachine::stop Mar 19 07:48:09 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 07:48:09 volumio volumio[1010]: UNSET VOLATILE Mar 19 07:48:09 volumio volumio[1010]: info: CoreStateMachine::stPlaybackTimer Mar 19 07:48:09 volumio volumio[1010]: info: CoreStateMachine::updateTrackBlock Mar 19 07:48:09 volumio volumio[1010]: info: CorePlayQueue::getTrackBlock Mar 19 07:48:09 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:09 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:09 volumio volumio[1010]: info: CorePlayQueue::getTrack 172 Mar 19 07:48:09 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:09 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:09 volumio volumio[1010]: info: CoreStateMachine::serviceStop Mar 19 07:48:09 volumio volumio[1010]: info: CorePlayQueue::getTrack 172 Mar 19 07:48:09 volumio volumio[1010]: info: CoreCommandRouter::serviceStop Mar 19 07:48:09 volumio volumio[1010]: info: ControllerMpd::stop Mar 19 07:48:09 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand stop Mar 19 07:48:09 volumio volumio[1010]: info: sending command... Mar 19 07:48:10 volumio volumio[1010]: info: Mar 19 07:48:10 volumio volumio[1010]: ---------------------------- MPD announces state update: player Mar 19 07:48:10 volumio volumio[1010]: info: parsing response... Mar 19 07:48:10 volumio volumio[1010]: info: ControllerMpd::getState Mar 19 07:48:10 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand status Mar 19 07:48:10 volumio volumio[1010]: info: CoreCommandRouter::servicePushState Mar 19 07:48:10 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:48:10 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:10 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:10 volumio volumio[1010]: info: CorePlayQueue::getTrack 172 Mar 19 07:48:10 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:10 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:10 volumio volumio[1010]: info: CorePlayQueue::getTrack 172 Mar 19 07:48:10 volumio volumio[1010]: info: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/18862_75116c95-1d06-4684-af7d-6e92280fd05a.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Today","title":"Today","artist":"Fruit Bats","album":"Siamese Dream","streaming":true,"disableUiControls":true,"duration":173,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 19 07:48:10 volumio volumio[1010]: info: CURRENT POSITION 172 Mar 19 07:48:10 volumio volumio[1010]: info: CoreStateMachine::syncState stateService stop Mar 19 07:48:10 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus stop Mar 19 07:48:10 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:10 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:10 volumio volumio[1010]: info: CorePlayQueue::getTrack 172 Mar 19 07:48:10 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:10 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:10 volumio volumio[1010]: info: No code Mar 19 07:48:10 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:10 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:10 volumio volumio[1010]: info: CorePlayQueue::getTrack 172 Mar 19 07:48:10 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:10 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:10 volumio volumio[1010]: info: sending command... Mar 19 07:48:10 volumio volumio[1010]: info: CoreStateMachine::play index undefined Mar 19 07:48:10 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 07:48:10 volumio volumio[1010]: info: CorePlayQueue::getTrack 173 Mar 19 07:48:10 volumio volumio[1010]: info: CoreStateMachine::startPlaybackTimer Mar 19 07:48:10 volumio volumio[1010]: info: CorePlayQueue::getTrack 173 Mar 19 07:48:10 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand stop Mar 19 07:48:10 volumio volumio[1010]: info: sending command... Mar 19 07:48:10 volumio volumio[1010]: info: parsing response... Mar 19 07:48:10 volumio volumio[1010]: info: ControllerMpd::parseState Mar 19 07:48:10 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:48:10 volumio volumio[1010]: info: sending command... Mar 19 07:48:10 volumio volumio[1010]: info: parsing response... Mar 19 07:48:10 volumio volumio[1010]: info: parsing response... Mar 19 07:48:10 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand clear Mar 19 07:48:10 volumio volumio[1010]: info: ControllerMpd::parseTrackInfo Mar 19 07:48:10 volumio volumio[1010]: info: sending command... Mar 19 07:48:10 volumio volumio[1010]: info: ControllerMpd::pushState Mar 19 07:48:10 volumio volumio[1010]: info: CoreCommandRouter::servicePushState Mar 19 07:48:10 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:48:10 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:10 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:10 volumio volumio[1010]: info: CorePlayQueue::getTrack 173 Mar 19 07:48:10 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:10 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:10 volumio volumio[1010]: info: CorePlayQueue::getTrack 173 Mar 19 07:48:10 volumio volumio[1010]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 19 07:48:10 volumio volumio[1010]: info: ------------------------------ 114ms Mar 19 07:48:10 volumio volumio[1010]: info: Mar 19 07:48:10 volumio volumio[1010]: ---------------------------- MPD announces system playlist update Mar 19 07:48:10 volumio volumio[1010]: info: Ignoring MPD Status Update Mar 19 07:48:10 volumio volumio[1010]: info: parsing response... Mar 19 07:48:10 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand add "http://stream-uk1.radioparadise.com/mp3-192" Mar 19 07:48:10 volumio volumio[1010]: info: sending command... Mar 19 07:48:10 volumio volumio[1010]: info: Mar 19 07:48:10 volumio volumio[1010]: info: Mar 19 07:48:10 volumio volumio[1010]: ---------------------------- MPD announces system playlist update Mar 19 07:48:10 volumio volumio[1010]: info: Ignoring MPD Status Update Mar 19 07:48:10 volumio volumio[1010]: info: ------------------------------ 15ms Mar 19 07:48:10 volumio volumio[1010]: info: parsing response... Mar 19 07:48:10 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand play Mar 19 07:48:10 volumio volumio[1010]: info: sending command... Mar 19 07:48:10 volumio volumio[1010]: info: ------------------------------ 16ms Mar 19 07:48:10 volumio volumio[1010]: info: parsing response... Mar 19 07:48:10 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 19 07:48:11 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:48:11 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:11 volumio volumio[1010]: info: Mar 19 07:48:11 volumio volumio[1010]: ---------------------------- MPD announces state update: player Mar 19 07:48:11 volumio volumio[1010]: info: ControllerMpd::getState Mar 19 07:48:11 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand status Mar 19 07:48:11 volumio volumio[1010]: info: sending command... Mar 19 07:48:11 volumio volumio[1010]: info: parsing response... Mar 19 07:48:11 volumio volumio[1010]: info: ControllerMpd::parseState Mar 19 07:48:11 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:48:11 volumio volumio[1010]: info: sending command... Mar 19 07:48:11 volumio volumio[1010]: info: Mar 19 07:48:11 volumio volumio[1010]: ---------------------------- MPD announces system playlist update Mar 19 07:48:11 volumio volumio[1010]: info: Ignoring MPD Status Update Mar 19 07:48:11 volumio volumio[1010]: info: Mar 19 07:48:11 volumio volumio[1010]: ---------------------------- MPD announces state update: player Mar 19 07:48:11 volumio volumio[1010]: info: ControllerMpd::getState Mar 19 07:48:11 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand status Mar 19 07:48:11 volumio volumio[1010]: info: parsing response... Mar 19 07:48:11 volumio volumio[1010]: info: sending command... Mar 19 07:48:11 volumio volumio[1010]: info: ControllerMpd::parseTrackInfo Mar 19 07:48:11 volumio volumio[1010]: info: ControllerMpd::pushState Mar 19 07:48:11 volumio volumio[1010]: info: CoreCommandRouter::servicePushState Mar 19 07:48:11 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:48:11 volumio volumio[1010]: info: CorePlayQueue::getTrack 173 Mar 19 07:48:11 volumio volumio[1010]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Yola - Stand for Myself","artist":"Radio Paradise (192k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-192","trackType":"com/mp3-192"} Mar 19 07:48:11 volumio volumio[1010]: info: CURRENT POSITION 173 Mar 19 07:48:11 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play Mar 19 07:48:11 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus stop Mar 19 07:48:11 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:11 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:11 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:11 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:11 volumio volumio[1010]: info: ------------------------------ 70ms Mar 19 07:48:11 volumio volumio[1010]: info: ------------------------------ 25ms Mar 19 07:48:11 volumio volumio[1010]: info: parsing response... Mar 19 07:48:11 volumio volumio[1010]: info: ControllerMpd::parseState Mar 19 07:48:11 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:48:11 volumio volumio[1010]: info: sending command... Mar 19 07:48:11 volumio volumio[1010]: info: parsing response... Mar 19 07:48:11 volumio volumio[1010]: info: ControllerMpd::parseTrackInfo Mar 19 07:48:11 volumio volumio[1010]: info: ControllerMpd::pushState Mar 19 07:48:11 volumio volumio[1010]: info: CoreCommandRouter::servicePushState Mar 19 07:48:11 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:48:11 volumio volumio[1010]: info: CorePlayQueue::getTrack 173 Mar 19 07:48:11 volumio volumio[1010]: info: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":0,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Yola - Stand for Myself","artist":"Radio Paradise (192k mp3)","album":null,"uri":"http://stream-uk1.radioparadise.com/mp3-192","trackType":"com/mp3-192"} Mar 19 07:48:11 volumio volumio[1010]: info: CURRENT POSITION 173 Mar 19 07:48:11 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play Mar 19 07:48:11 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus play Mar 19 07:48:11 volumio volumio[1010]: info: Received an update from plugin. extracting info from payload Mar 19 07:48:11 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:11 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:11 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:11 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:11 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:11 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:11 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:11 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:11 volumio volumio[1010]: info: ------------------------------ 63ms Mar 19 07:48:12 volumio volumio[1010]: info: CoreCommandRouter::volumioAddQueueItems Mar 19 07:48:12 volumio volumio[1010]: info: CoreStateMachine::addQueueItems Mar 19 07:48:12 volumio volumio[1010]: info: CorePlayQueue::addQueueItems Mar 19 07:48:12 volumio volumio[1010]: info: Adding Item to queue: webrp/0 Mar 19 07:48:12 volumio volumio[1010]: info: Exploding uri webrp/0 in service radio_paradise Mar 19 07:48:12 volumio volumio[1010]: info: CorePlayQueue::saveQueue Mar 19 07:48:12 volumio volumio[1010]: info: CoreCommandRouter::volumioPushQueue Mar 19 07:48:12 volumio volumio[1010]: info: CoreStateMachine::updateTrackBlock Mar 19 07:48:12 volumio volumio[1010]: info: CorePlayQueue::getTrackBlock Mar 19 07:48:12 volumio volumio[1010]: info: CoreCommandRouter::volumioPlay Mar 19 07:48:12 volumio volumio[1010]: UNSET VOLATILE Mar 19 07:48:12 volumio volumio[1010]: info: CoreStateMachine::play index 174 Mar 19 07:48:12 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 07:48:12 volumio volumio[1010]: info: CoreStateMachine::stop Mar 19 07:48:12 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 07:48:12 volumio volumio[1010]: UNSET VOLATILE Mar 19 07:48:12 volumio volumio[1010]: info: CoreStateMachine::stPlaybackTimer Mar 19 07:48:12 volumio volumio[1010]: info: CoreStateMachine::updateTrackBlock Mar 19 07:48:12 volumio volumio[1010]: info: CorePlayQueue::getTrackBlock Mar 19 07:48:12 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:12 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:12 volumio volumio[1010]: info: CorePlayQueue::getTrack 173 Mar 19 07:48:12 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:12 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:12 volumio volumio[1010]: info: CoreStateMachine::serviceStop Mar 19 07:48:12 volumio volumio[1010]: info: CorePlayQueue::getTrack 173 Mar 19 07:48:12 volumio volumio[1010]: info: CoreCommandRouter::serviceStop Mar 19 07:48:12 volumio volumio[1010]: info: ControllerMpd::stop Mar 19 07:48:12 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand stop Mar 19 07:48:12 volumio volumio[1010]: info: sending command... Mar 19 07:48:12 volumio volumio[1010]: info: Mar 19 07:48:12 volumio volumio[1010]: ---------------------------- MPD announces state update: player Mar 19 07:48:12 volumio volumio[1010]: info: parsing response... Mar 19 07:48:12 volumio volumio[1010]: info: ControllerMpd::getState Mar 19 07:48:12 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand status Mar 19 07:48:12 volumio volumio[1010]: info: CoreCommandRouter::servicePushState Mar 19 07:48:12 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:48:12 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:12 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:12 volumio volumio[1010]: info: CorePlayQueue::getTrack 173 Mar 19 07:48:12 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:12 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:12 volumio volumio[1010]: info: CorePlayQueue::getTrack 173 Mar 19 07:48:12 volumio volumio[1010]: info: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/18862_75116c95-1d06-4684-af7d-6e92280fd05a.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Today","title":"Today","artist":"Fruit Bats","album":"Siamese Dream","streaming":true,"disableUiControls":true,"duration":173,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 19 07:48:12 volumio volumio[1010]: info: CURRENT POSITION 173 Mar 19 07:48:12 volumio volumio[1010]: info: CoreStateMachine::syncState stateService stop Mar 19 07:48:12 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus stop Mar 19 07:48:12 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:12 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:12 volumio volumio[1010]: info: CorePlayQueue::getTrack 173 Mar 19 07:48:12 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:12 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:12 volumio volumio[1010]: info: No code Mar 19 07:48:12 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:12 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:12 volumio volumio[1010]: info: CorePlayQueue::getTrack 173 Mar 19 07:48:12 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:12 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:12 volumio volumio[1010]: info: sending command... Mar 19 07:48:12 volumio volumio[1010]: info: CoreStateMachine::play index undefined Mar 19 07:48:12 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 07:48:12 volumio volumio[1010]: info: CorePlayQueue::getTrack 174 Mar 19 07:48:12 volumio volumio[1010]: info: CoreStateMachine::startPlaybackTimer Mar 19 07:48:12 volumio volumio[1010]: info: CorePlayQueue::getTrack 174 Mar 19 07:48:12 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand stop Mar 19 07:48:12 volumio volumio[1010]: info: sending command... Mar 19 07:48:12 volumio volumio[1010]: info: parsing response... Mar 19 07:48:12 volumio volumio[1010]: info: parsing response... Mar 19 07:48:12 volumio volumio[1010]: info: ControllerMpd::parseState Mar 19 07:48:12 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:48:12 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand clear Mar 19 07:48:12 volumio volumio[1010]: info: sending command... Mar 19 07:48:12 volumio volumio[1010]: info: sending command... Mar 19 07:48:12 volumio volumio[1010]: info: Mar 19 07:48:12 volumio volumio[1010]: ---------------------------- MPD announces system playlist update Mar 19 07:48:12 volumio volumio[1010]: info: Ignoring MPD Status Update Mar 19 07:48:12 volumio volumio[1010]: info: parsing response... Mar 19 07:48:12 volumio volumio[1010]: info: parsing response... Mar 19 07:48:12 volumio volumio[1010]: info: ControllerMpd::parseTrackInfo Mar 19 07:48:12 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand consume 1 Mar 19 07:48:12 volumio volumio[1010]: info: ControllerMpd::pushState Mar 19 07:48:12 volumio volumio[1010]: info: CoreCommandRouter::servicePushState Mar 19 07:48:12 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:48:12 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:12 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:12 volumio volumio[1010]: info: CorePlayQueue::getTrack 174 Mar 19 07:48:12 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:12 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:12 volumio volumio[1010]: info: CorePlayQueue::getTrack 174 Mar 19 07:48:12 volumio volumio[1010]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 19 07:48:12 volumio volumio[1010]: info: sending command... Mar 19 07:48:12 volumio volumio[1010]: info: ------------------------------ 128ms Mar 19 07:48:12 volumio volumio[1010]: info: Mar 19 07:48:12 volumio volumio[1010]: info: ------------------------------ 26ms Mar 19 07:48:12 volumio volumio[1010]: info: parsing response... Mar 19 07:48:12 volumio volumio[1010]: info: [1742370492768] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flac Mar 19 07:48:12 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flac" Mar 19 07:48:12 volumio volumio[1010]: info: sending command... Mar 19 07:48:12 volumio volumio[1010]: info: Mar 19 07:48:12 volumio volumio[1010]: ---------------------------- MPD announces system playlist update Mar 19 07:48:12 volumio volumio[1010]: info: Ignoring MPD Status Update Mar 19 07:48:12 volumio volumio[1010]: info: parsing response... Mar 19 07:48:12 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand play Mar 19 07:48:12 volumio volumio[1010]: info: sending command... Mar 19 07:48:12 volumio volumio[1010]: info: ------------------------------ 18ms Mar 19 07:48:12 volumio volumio[1010]: info: parsing response... Mar 19 07:48:12 volumio volumio[1010]: info: [1742370492803] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 Mar 19 07:48:13 volumio volumio[1010]: info: [1742370493236] [RadioParadise] received new metadata: {"time":54,"artist":"Yola","title":"Stand for Myself","album":"Stand For Myself","year":"2021","cover":"https://img.radioparadise.com/covers/l/19315_446ac6cb-db6b-4fbf-aafd-6e3cc988300b.jpg","cover_med":"https://img.radioparadise.com/covers/m/19315_446ac6cb-db6b-4fbf-aafd-6e3cc988300b.jpg","cover_small":"https://img.radioparadise.com/covers/s/19315_446ac6cb-db6b-4fbf-aafd-6e3cc988300b.jpg"} Mar 19 07:48:13 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:13 volumio volumio[1010]: info: CorePlayQueue::getTrack 174 Mar 19 07:48:13 volumio volumio[1010]: info: CoreCommandRouter::servicePushState Mar 19 07:48:13 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:48:13 volumio volumio[1010]: info: CorePlayQueue::getTrack 174 Mar 19 07:48:13 volumio volumio[1010]: info: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/19315_446ac6cb-db6b-4fbf-aafd-6e3cc988300b.jpg","uri":"https://stream.radioparadise.com/flac","name":"Stand for Myself","title":"Stand for Myself","artist":"Yola","album":"Stand For Myself","streaming":true,"disableUiControls":true,"duration":59,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 19 07:48:13 volumio volumio[1010]: info: CURRENT POSITION 174 Mar 19 07:48:13 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play Mar 19 07:48:13 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus stop Mar 19 07:48:13 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:13 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:13 volumio volumio[1010]: info: CorePlayQueue::getTrack 174 Mar 19 07:48:13 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:13 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:13 volumio volumio[1010]: info: [1742370493255] [RadioParadise] setting new timer with duration of 59000 seconds. Mar 19 07:48:13 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:48:13 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:13 volumio volumio[1010]: info: CorePlayQueue::getTrack 174 Mar 19 07:48:15 volumio volumio[1010]: info: Mar 19 07:48:15 volumio volumio[1010]: ---------------------------- MPD announces state update: player Mar 19 07:48:15 volumio volumio[1010]: info: ControllerMpd::getState Mar 19 07:48:15 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand status Mar 19 07:48:15 volumio volumio[1010]: info: sending command... Mar 19 07:48:15 volumio volumio[1010]: info: parsing response... Mar 19 07:48:15 volumio volumio[1010]: info: ControllerMpd::parseState Mar 19 07:48:15 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:48:15 volumio volumio[1010]: info: sending command... Mar 19 07:48:15 volumio volumio[1010]: info: Mar 19 07:48:15 volumio volumio[1010]: ---------------------------- MPD announces state update: player Mar 19 07:48:15 volumio volumio[1010]: info: Mar 19 07:48:15 volumio volumio[1010]: ---------------------------- MPD announces system playlist update Mar 19 07:48:15 volumio volumio[1010]: info: Ignoring MPD Status Update Mar 19 07:48:15 volumio volumio[1010]: info: ControllerMpd::getState Mar 19 07:48:15 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand status Mar 19 07:48:15 volumio volumio[1010]: info: parsing response... Mar 19 07:48:15 volumio volumio[1010]: info: sending command... Mar 19 07:48:15 volumio volumio[1010]: info: ControllerMpd::parseTrackInfo Mar 19 07:48:15 volumio volumio[1010]: info: ControllerMpd::pushState Mar 19 07:48:15 volumio volumio[1010]: info: CoreCommandRouter::servicePushState Mar 19 07:48:15 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:48:15 volumio volumio[1010]: info: CorePlayQueue::getTrack 174 Mar 19 07:48:15 volumio volumio[1010]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 19 07:48:15 volumio volumio[1010]: info: ------------------------------ 83ms Mar 19 07:48:15 volumio volumio[1010]: info: parsing response... Mar 19 07:48:15 volumio volumio[1010]: info: ------------------------------ 34ms Mar 19 07:48:15 volumio volumio[1010]: info: ControllerMpd::parseState Mar 19 07:48:15 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:48:15 volumio volumio[1010]: info: sending command... Mar 19 07:48:15 volumio volumio[1010]: info: parsing response... Mar 19 07:48:15 volumio volumio[1010]: info: ControllerMpd::parseTrackInfo Mar 19 07:48:15 volumio volumio[1010]: info: ControllerMpd::pushState Mar 19 07:48:15 volumio volumio[1010]: info: CoreCommandRouter::servicePushState Mar 19 07:48:15 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:48:15 volumio volumio[1010]: info: CorePlayQueue::getTrack 174 Mar 19 07:48:15 volumio volumio[1010]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 19 07:48:15 volumio volumio[1010]: info: ------------------------------ 46ms Mar 19 07:48:15 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:48:15 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:15 volumio volumio[1010]: info: CorePlayQueue::getTrack 174 Mar 19 07:48:17 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:48:17 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:17 volumio volumio[1010]: info: CorePlayQueue::getTrack 174 Mar 19 07:48:19 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:48:19 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:19 volumio volumio[1010]: info: CorePlayQueue::getTrack 174 Mar 19 07:48:19 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Mar 19 07:48:20 volumio wpa_supplicant[2652]: wlan0: Trying to associate with 00:07:89:83:84:95 (SSID='KT_GiGA_2G_Wave2_8492' freq=2442 MHz) Mar 19 07:48:20 volumio wpa_supplicant[2652]: wlan0: Association request to the driver failed Mar 19 07:48:21 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:48:21 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:21 volumio volumio[1010]: info: CorePlayQueue::getTrack 174 Mar 19 07:48:21 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Mar 19 07:48:23 volumio volumio[1010]: info: CoreCommandRouter::volumioAddQueueItems Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::addQueueItems Mar 19 07:48:23 volumio volumio[1010]: info: CorePlayQueue::addQueueItems Mar 19 07:48:23 volumio volumio[1010]: info: Adding Item to queue: weblinn/0 Mar 19 07:48:23 volumio volumio[1010]: info: Exploding uri weblinn/0 in service personal_radio Mar 19 07:48:23 volumio volumio[1010]: info: CorePlayQueue::saveQueue Mar 19 07:48:23 volumio volumio[1010]: info: CoreCommandRouter::volumioPushQueue Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::updateTrackBlock Mar 19 07:48:23 volumio volumio[1010]: info: CorePlayQueue::getTrackBlock Mar 19 07:48:23 volumio volumio[1010]: info: CoreCommandRouter::volumioPlay Mar 19 07:48:23 volumio volumio[1010]: UNSET VOLATILE Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::play index 175 Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::stop Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 07:48:23 volumio volumio[1010]: UNSET VOLATILE Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::stPlaybackTimer Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::updateTrackBlock Mar 19 07:48:23 volumio volumio[1010]: info: CorePlayQueue::getTrackBlock Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:23 volumio volumio[1010]: info: CorePlayQueue::getTrack 174 Mar 19 07:48:23 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:23 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::serviceStop Mar 19 07:48:23 volumio volumio[1010]: info: CorePlayQueue::getTrack 174 Mar 19 07:48:23 volumio volumio[1010]: info: CoreCommandRouter::serviceStop Mar 19 07:48:23 volumio volumio[1010]: info: ControllerMpd::stop Mar 19 07:48:23 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand stop Mar 19 07:48:23 volumio volumio[1010]: info: sending command... Mar 19 07:48:23 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:23 volumio volumio[1010]: info: CorePlayQueue::getTrack 174 Mar 19 07:48:23 volumio volumio[1010]: info: Mar 19 07:48:23 volumio volumio[1010]: ---------------------------- MPD announces state update: player Mar 19 07:48:23 volumio volumio[1010]: info: parsing response... Mar 19 07:48:23 volumio volumio[1010]: info: ControllerMpd::getState Mar 19 07:48:23 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand status Mar 19 07:48:23 volumio volumio[1010]: info: CoreCommandRouter::servicePushState Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:23 volumio volumio[1010]: info: CorePlayQueue::getTrack 174 Mar 19 07:48:23 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:23 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:23 volumio volumio[1010]: info: CorePlayQueue::getTrack 174 Mar 19 07:48:23 volumio volumio[1010]: info: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/19315_446ac6cb-db6b-4fbf-aafd-6e3cc988300b.jpg","uri":"https://stream.radioparadise.com/flac","name":"Stand for Myself","title":"Stand for Myself","artist":"Yola","album":"Stand For Myself","streaming":true,"disableUiControls":true,"duration":59,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 19 07:48:23 volumio volumio[1010]: info: CURRENT POSITION 174 Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::syncState stateService stop Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus stop Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:23 volumio volumio[1010]: info: CorePlayQueue::getTrack 174 Mar 19 07:48:23 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:23 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:23 volumio volumio[1010]: info: No code Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:23 volumio volumio[1010]: info: CorePlayQueue::getTrack 174 Mar 19 07:48:23 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:23 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:23 volumio volumio[1010]: info: sending command... Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::play index undefined Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 07:48:23 volumio volumio[1010]: info: CorePlayQueue::getTrack 175 Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::startPlaybackTimer Mar 19 07:48:23 volumio volumio[1010]: info: CorePlayQueue::getTrack 175 Mar 19 07:48:23 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand stop Mar 19 07:48:23 volumio volumio[1010]: info: sending command... Mar 19 07:48:23 volumio volumio[1010]: info: parsing response... Mar 19 07:48:23 volumio volumio[1010]: info: parsing response... Mar 19 07:48:23 volumio volumio[1010]: info: ControllerMpd::parseState Mar 19 07:48:23 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:48:23 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand clear Mar 19 07:48:23 volumio volumio[1010]: info: sending command... Mar 19 07:48:23 volumio volumio[1010]: info: sending command... Mar 19 07:48:23 volumio volumio[1010]: info: Mar 19 07:48:23 volumio volumio[1010]: ---------------------------- MPD announces system playlist update Mar 19 07:48:23 volumio volumio[1010]: info: Ignoring MPD Status Update Mar 19 07:48:23 volumio volumio[1010]: info: parsing response... Mar 19 07:48:23 volumio volumio[1010]: info: parsing response... Mar 19 07:48:23 volumio volumio[1010]: info: ControllerMpd::parseTrackInfo Mar 19 07:48:23 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand add "http://radio.linnrecords.com:8000/stream" Mar 19 07:48:23 volumio volumio[1010]: info: ControllerMpd::pushState Mar 19 07:48:23 volumio volumio[1010]: info: CoreCommandRouter::servicePushState Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:23 volumio volumio[1010]: info: CorePlayQueue::getTrack 175 Mar 19 07:48:23 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:23 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:23 volumio volumio[1010]: info: CorePlayQueue::getTrack 175 Mar 19 07:48:23 volumio volumio[1010]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Mar 19 07:48:23 volumio volumio[1010]: info: sending command... Mar 19 07:48:23 volumio volumio[1010]: info: ------------------------------ 100ms Mar 19 07:48:23 volumio volumio[1010]: info: Mar 19 07:48:23 volumio volumio[1010]: info: Mar 19 07:48:23 volumio volumio[1010]: ---------------------------- MPD announces system playlist update Mar 19 07:48:23 volumio volumio[1010]: info: Ignoring MPD Status Update Mar 19 07:48:23 volumio volumio[1010]: info: ------------------------------ 27ms Mar 19 07:48:23 volumio volumio[1010]: info: parsing response... Mar 19 07:48:23 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand play Mar 19 07:48:23 volumio volumio[1010]: info: sending command... Mar 19 07:48:23 volumio volumio[1010]: info: ------------------------------ 21ms Mar 19 07:48:23 volumio volumio[1010]: info: parsing response... Mar 19 07:48:23 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 19 07:48:25 volumio volumio[1010]: info: Mar 19 07:48:25 volumio volumio[1010]: ---------------------------- MPD announces state update: player Mar 19 07:48:25 volumio volumio[1010]: info: ControllerMpd::getState Mar 19 07:48:25 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand status Mar 19 07:48:25 volumio volumio[1010]: info: sending command... Mar 19 07:48:25 volumio volumio[1010]: info: Mar 19 07:48:25 volumio volumio[1010]: ---------------------------- MPD announces state update: player Mar 19 07:48:25 volumio volumio[1010]: info: parsing response... Mar 19 07:48:25 volumio volumio[1010]: info: ControllerMpd::getState Mar 19 07:48:25 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand status Mar 19 07:48:25 volumio volumio[1010]: info: ControllerMpd::parseState Mar 19 07:48:25 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:48:25 volumio volumio[1010]: info: sending command... Mar 19 07:48:25 volumio volumio[1010]: info: sending command... Mar 19 07:48:25 volumio volumio[1010]: info: Mar 19 07:48:25 volumio volumio[1010]: ---------------------------- MPD announces system playlist update Mar 19 07:48:25 volumio volumio[1010]: info: Ignoring MPD Status Update Mar 19 07:48:25 volumio volumio[1010]: info: parsing response... Mar 19 07:48:25 volumio volumio[1010]: info: parsing response... Mar 19 07:48:25 volumio volumio[1010]: info: ControllerMpd::parseState Mar 19 07:48:25 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:48:25 volumio volumio[1010]: info: ControllerMpd::parseTrackInfo Mar 19 07:48:25 volumio volumio[1010]: info: sending command... Mar 19 07:48:25 volumio volumio[1010]: info: ControllerMpd::pushState Mar 19 07:48:25 volumio volumio[1010]: info: CoreCommandRouter::servicePushState Mar 19 07:48:25 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:48:25 volumio volumio[1010]: info: CorePlayQueue::getTrack 175 Mar 19 07:48:25 volumio volumio[1010]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Clark Tracey - Lounge Blues [5F]","artist":"Linn Jazz","album":null,"uri":"http://radio.linnrecords.com:8000/stream","trackType":"com:8000/stream"} Mar 19 07:48:25 volumio volumio[1010]: info: CURRENT POSITION 175 Mar 19 07:48:25 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play Mar 19 07:48:25 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus stop Mar 19 07:48:25 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:25 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:25 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:25 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:25 volumio volumio[1010]: info: ------------------------------ 76ms Mar 19 07:48:25 volumio volumio[1010]: info: ------------------------------ 28ms Mar 19 07:48:25 volumio volumio[1010]: info: parsing response... Mar 19 07:48:25 volumio volumio[1010]: info: ControllerMpd::parseTrackInfo Mar 19 07:48:25 volumio volumio[1010]: info: ControllerMpd::pushState Mar 19 07:48:25 volumio volumio[1010]: info: CoreCommandRouter::servicePushState Mar 19 07:48:25 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:48:25 volumio volumio[1010]: info: CorePlayQueue::getTrack 175 Mar 19 07:48:25 volumio volumio[1010]: info: STATE SERVICE {"status":"play","position":0,"seek":481,"duration":0,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Clark Tracey - Lounge Blues [5F]","artist":"Linn Jazz","album":null,"uri":"http://radio.linnrecords.com:8000/stream","trackType":"com:8000/stream"} Mar 19 07:48:25 volumio volumio[1010]: info: CURRENT POSITION 175 Mar 19 07:48:25 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play Mar 19 07:48:25 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus play Mar 19 07:48:25 volumio volumio[1010]: info: Received an update from plugin. extracting info from payload Mar 19 07:48:25 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:25 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:25 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:25 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:25 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:25 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:25 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:25 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:25 volumio volumio[1010]: info: ------------------------------ 68ms Mar 19 07:48:25 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:48:25 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:26 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Mar 19 07:48:27 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:48:27 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:29 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:48:29 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:29 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Mar 19 07:48:31 volumio volumio[1010]: info: CoreCommandRouter::volumioAddQueueItems Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::addQueueItems Mar 19 07:48:31 volumio volumio[1010]: info: CorePlayQueue::addQueueItems Mar 19 07:48:31 volumio volumio[1010]: info: Adding Item to queue: webmbc/1 Mar 19 07:48:31 volumio volumio[1010]: info: Exploding uri webmbc/1 in service personal_radio Mar 19 07:48:31 volumio volumio[1010]: info: CorePlayQueue::saveQueue Mar 19 07:48:31 volumio volumio[1010]: info: CoreCommandRouter::volumioPushQueue Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::updateTrackBlock Mar 19 07:48:31 volumio volumio[1010]: info: CorePlayQueue::getTrackBlock Mar 19 07:48:31 volumio volumio[1010]: info: CoreCommandRouter::volumioPlay Mar 19 07:48:31 volumio volumio[1010]: UNSET VOLATILE Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::play index 176 Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::stop Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 07:48:31 volumio volumio[1010]: UNSET VOLATILE Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::stPlaybackTimer Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::updateTrackBlock Mar 19 07:48:31 volumio volumio[1010]: info: CorePlayQueue::getTrackBlock Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:31 volumio volumio[1010]: info: CorePlayQueue::getTrack 175 Mar 19 07:48:31 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:31 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::serviceStop Mar 19 07:48:31 volumio volumio[1010]: info: CorePlayQueue::getTrack 175 Mar 19 07:48:31 volumio volumio[1010]: info: CoreCommandRouter::serviceStop Mar 19 07:48:31 volumio volumio[1010]: info: ControllerMpd::stop Mar 19 07:48:31 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand stop Mar 19 07:48:31 volumio volumio[1010]: info: sending command... Mar 19 07:48:31 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:31 volumio volumio[1010]: info: CorePlayQueue::getTrack 175 Mar 19 07:48:31 volumio volumio[1010]: info: parsing response... Mar 19 07:48:31 volumio volumio[1010]: info: ControllerMpd::getState Mar 19 07:48:31 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand status Mar 19 07:48:31 volumio volumio[1010]: info: sending command... Mar 19 07:48:31 volumio volumio[1010]: info: Mar 19 07:48:31 volumio volumio[1010]: ---------------------------- MPD announces state update: player Mar 19 07:48:31 volumio volumio[1010]: info: ControllerMpd::getState Mar 19 07:48:31 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand status Mar 19 07:48:31 volumio volumio[1010]: info: parsing response... Mar 19 07:48:31 volumio volumio[1010]: info: sending command... Mar 19 07:48:31 volumio volumio[1010]: info: ControllerMpd::parseState Mar 19 07:48:31 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:48:31 volumio volumio[1010]: info: sending command... Mar 19 07:48:31 volumio volumio[1010]: info: parsing response... Mar 19 07:48:31 volumio volumio[1010]: info: parsing response... Mar 19 07:48:31 volumio volumio[1010]: info: ControllerMpd::parseState Mar 19 07:48:31 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:48:31 volumio volumio[1010]: info: ControllerMpd::parseTrackInfo Mar 19 07:48:31 volumio volumio[1010]: info: sending command... Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:31 volumio volumio[1010]: info: CorePlayQueue::getTrack 175 Mar 19 07:48:31 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:31 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:31 volumio volumio[1010]: info: CorePlayQueue::getTrack 175 Mar 19 07:48:31 volumio volumio[1010]: info: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Clark Tracey - Lounge Blues [5F]","artist":"Linn Jazz","album":null,"uri":"http://radio.linnrecords.com:8000/stream","trackType":"com:8000/stream"} Mar 19 07:48:31 volumio volumio[1010]: info: CURRENT POSITION 175 Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::syncState stateService stop Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus stop Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:31 volumio volumio[1010]: info: CorePlayQueue::getTrack 175 Mar 19 07:48:31 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:31 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:31 volumio volumio[1010]: info: No code Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:31 volumio volumio[1010]: info: CorePlayQueue::getTrack 175 Mar 19 07:48:31 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:31 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::play index undefined Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 07:48:31 volumio volumio[1010]: info: CorePlayQueue::getTrack 176 Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::startPlaybackTimer Mar 19 07:48:31 volumio volumio[1010]: info: CorePlayQueue::getTrack 176 Mar 19 07:48:31 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand stop Mar 19 07:48:31 volumio volumio[1010]: info: sending command... Mar 19 07:48:31 volumio volumio[1010]: info: parsing response... Mar 19 07:48:31 volumio volumio[1010]: info: parsing response... Mar 19 07:48:31 volumio volumio[1010]: info: ControllerMpd::parseTrackInfo Mar 19 07:48:31 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand clear Mar 19 07:48:31 volumio volumio[1010]: info: ControllerMpd::pushState Mar 19 07:48:31 volumio volumio[1010]: info: CoreCommandRouter::servicePushState Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:31 volumio volumio[1010]: info: CorePlayQueue::getTrack 176 Mar 19 07:48:31 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:31 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:31 volumio volumio[1010]: info: CorePlayQueue::getTrack 176 Mar 19 07:48:31 volumio volumio[1010]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Mar 19 07:48:31 volumio volumio[1010]: info: sending command... Mar 19 07:48:31 volumio volumio[1010]: info: ------------------------------ 97ms Mar 19 07:48:31 volumio volumio[1010]: info: Mar 19 07:48:31 volumio volumio[1010]: ---------------------------- MPD announces system playlist update Mar 19 07:48:31 volumio volumio[1010]: info: Ignoring MPD Status Update Mar 19 07:48:31 volumio volumio[1010]: info: parsing response... Mar 19 07:48:31 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand add "http://mfmtunein.imbc.com/tmfm/_definst_/tmfm.stream/playlist.m3u8" Mar 19 07:48:31 volumio volumio[1010]: info: sending command... Mar 19 07:48:31 volumio volumio[1010]: info: Mar 19 07:48:31 volumio volumio[1010]: info: Mar 19 07:48:31 volumio volumio[1010]: ---------------------------- MPD announces system playlist update Mar 19 07:48:31 volumio volumio[1010]: info: Ignoring MPD Status Update Mar 19 07:48:31 volumio volumio[1010]: info: ------------------------------ 9ms Mar 19 07:48:31 volumio volumio[1010]: info: parsing response... Mar 19 07:48:31 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand play Mar 19 07:48:31 volumio volumio[1010]: info: sending command... Mar 19 07:48:31 volumio volumio[1010]: info: ------------------------------ 12ms Mar 19 07:48:31 volumio volumio[1010]: info: parsing response... Mar 19 07:48:31 volumio volumio[1010]: info: ControllerMpd::getState Mar 19 07:48:31 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand status Mar 19 07:48:31 volumio volumio[1010]: info: sending command... Mar 19 07:48:31 volumio volumio[1010]: info: parsing response... Mar 19 07:48:31 volumio volumio[1010]: info: ControllerMpd::parseState Mar 19 07:48:31 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:48:31 volumio volumio[1010]: info: sending command... Mar 19 07:48:31 volumio volumio[1010]: info: parsing response... Mar 19 07:48:31 volumio volumio[1010]: info: ControllerMpd::parseTrackInfo Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:48:31 volumio volumio[1010]: info: CorePlayQueue::getTrack 176 Mar 19 07:48:31 volumio volumio[1010]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"playlist.m3u8","artist":null,"album":null,"uri":"http://mfmtunein.imbc.com/tmfm/_definst_/tmfm.stream/playlist.m3u8","trackType":"m3u8"} Mar 19 07:48:31 volumio volumio[1010]: info: CURRENT POSITION 176 Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus stop Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:31 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:31 volumio volumio[1010]: info: CorePlayQueue::getTrack 176 Mar 19 07:48:31 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:31 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:31 volumio nmbd[803]: [2025/03/19 07:48:31.721102, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 19 07:48:31 volumio nmbd[803]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Mar 19 07:48:31 volumio wpa_supplicant[2652]: wlan0: Trying to associate with 00:07:89:83:84:95 (SSID='KT_GiGA_2G_Wave2_8492' freq=2442 MHz) Mar 19 07:48:31 volumio wpa_supplicant[2652]: wlan0: Association request to the driver failed Mar 19 07:48:32 volumio volumio[1010]: info: Mar 19 07:48:32 volumio volumio[1010]: ---------------------------- MPD announces state update: player Mar 19 07:48:32 volumio volumio[1010]: info: ControllerMpd::getState Mar 19 07:48:32 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand status Mar 19 07:48:32 volumio volumio[1010]: info: sending command... Mar 19 07:48:32 volumio volumio[1010]: info: parsing response... Mar 19 07:48:32 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand clearerror Mar 19 07:48:32 volumio volumio[1010]: info: sending command... Mar 19 07:48:32 volumio volumio[1010]: info: ControllerMpd::parseState Mar 19 07:48:32 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:48:32 volumio volumio[1010]: info: sending command... Mar 19 07:48:32 volumio volumio[1010]: info: parsing response... Mar 19 07:48:32 volumio volumio[1010]: info: parsing response... Mar 19 07:48:32 volumio volumio[1010]: info: ControllerMpd::parseTrackInfo Mar 19 07:48:32 volumio volumio[1010]: info: ControllerMpd::pushState Mar 19 07:48:32 volumio volumio[1010]: info: CoreCommandRouter::servicePushState Mar 19 07:48:32 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:48:32 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:32 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:32 volumio volumio[1010]: info: CorePlayQueue::getTrack 176 Mar 19 07:48:32 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:32 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:32 volumio volumio[1010]: info: CorePlayQueue::getTrack 176 Mar 19 07:48:32 volumio volumio[1010]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Mar 19 07:48:32 volumio volumio[1010]: info: ------------------------------ 33ms Mar 19 07:48:33 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:48:33 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:33 volumio volumio[1010]: info: CorePlayQueue::getTrack 176 Mar 19 07:48:34 volumio volumio[1010]: info: CoreCommandRouter::volumioAddQueueItems Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::addQueueItems Mar 19 07:48:34 volumio volumio[1010]: info: CorePlayQueue::addQueueItems Mar 19 07:48:34 volumio volumio[1010]: info: Adding Item to queue: webmbc/0 Mar 19 07:48:34 volumio volumio[1010]: info: Exploding uri webmbc/0 in service personal_radio Mar 19 07:48:34 volumio volumio[1010]: info: CorePlayQueue::saveQueue Mar 19 07:48:34 volumio volumio[1010]: info: CoreCommandRouter::volumioPushQueue Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::updateTrackBlock Mar 19 07:48:34 volumio volumio[1010]: info: CorePlayQueue::getTrackBlock Mar 19 07:48:34 volumio volumio[1010]: info: CoreCommandRouter::volumioPlay Mar 19 07:48:34 volumio volumio[1010]: UNSET VOLATILE Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::play index 177 Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::stop Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 07:48:34 volumio volumio[1010]: UNSET VOLATILE Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::stPlaybackTimer Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::updateTrackBlock Mar 19 07:48:34 volumio volumio[1010]: info: CorePlayQueue::getTrackBlock Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:34 volumio volumio[1010]: info: CorePlayQueue::getTrack 176 Mar 19 07:48:34 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:34 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::serviceStop Mar 19 07:48:34 volumio volumio[1010]: info: CorePlayQueue::getTrack 176 Mar 19 07:48:34 volumio volumio[1010]: info: CoreCommandRouter::serviceStop Mar 19 07:48:34 volumio volumio[1010]: info: ControllerMpd::stop Mar 19 07:48:34 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand stop Mar 19 07:48:34 volumio volumio[1010]: info: sending command... Mar 19 07:48:34 volumio volumio[1010]: info: parsing response... Mar 19 07:48:34 volumio volumio[1010]: info: ControllerMpd::getState Mar 19 07:48:34 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand status Mar 19 07:48:34 volumio volumio[1010]: info: sending command... Mar 19 07:48:34 volumio volumio[1010]: info: parsing response... Mar 19 07:48:34 volumio volumio[1010]: info: ControllerMpd::parseState Mar 19 07:48:34 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:48:34 volumio volumio[1010]: info: sending command... Mar 19 07:48:34 volumio volumio[1010]: info: parsing response... Mar 19 07:48:34 volumio volumio[1010]: info: ControllerMpd::parseTrackInfo Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:34 volumio volumio[1010]: info: CorePlayQueue::getTrack 176 Mar 19 07:48:34 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:34 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:34 volumio volumio[1010]: info: CorePlayQueue::getTrack 176 Mar 19 07:48:34 volumio volumio[1010]: info: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"playlist.m3u8","artist":null,"album":null,"uri":"http://mfmtunein.imbc.com/tmfm/_definst_/tmfm.stream/playlist.m3u8","trackType":"m3u8"} Mar 19 07:48:34 volumio volumio[1010]: info: CURRENT POSITION 176 Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::syncState stateService stop Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus stop Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:34 volumio volumio[1010]: info: CorePlayQueue::getTrack 176 Mar 19 07:48:34 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:34 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:34 volumio volumio[1010]: info: No code Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:34 volumio volumio[1010]: info: CorePlayQueue::getTrack 176 Mar 19 07:48:34 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:34 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::play index undefined Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 19 07:48:34 volumio volumio[1010]: info: CorePlayQueue::getTrack 177 Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::startPlaybackTimer Mar 19 07:48:34 volumio volumio[1010]: info: CorePlayQueue::getTrack 177 Mar 19 07:48:34 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand stop Mar 19 07:48:34 volumio volumio[1010]: info: sending command... Mar 19 07:48:34 volumio volumio[1010]: info: parsing response... Mar 19 07:48:34 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand clear Mar 19 07:48:34 volumio volumio[1010]: info: sending command... Mar 19 07:48:34 volumio volumio[1010]: info: Mar 19 07:48:34 volumio volumio[1010]: ---------------------------- MPD announces system playlist update Mar 19 07:48:34 volumio volumio[1010]: info: Ignoring MPD Status Update Mar 19 07:48:34 volumio volumio[1010]: info: parsing response... Mar 19 07:48:34 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand add "http://sfmtunein.imbc.com/tsfm/_definst_/tsfm.stream/playlist.m3u8" Mar 19 07:48:34 volumio volumio[1010]: info: sending command... Mar 19 07:48:34 volumio volumio[1010]: info: Mar 19 07:48:34 volumio volumio[1010]: info: Mar 19 07:48:34 volumio volumio[1010]: ---------------------------- MPD announces system playlist update Mar 19 07:48:34 volumio volumio[1010]: info: Ignoring MPD Status Update Mar 19 07:48:34 volumio volumio[1010]: info: ------------------------------ 10ms Mar 19 07:48:34 volumio volumio[1010]: info: parsing response... Mar 19 07:48:34 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand play Mar 19 07:48:34 volumio volumio[1010]: info: sending command... Mar 19 07:48:34 volumio volumio[1010]: info: ------------------------------ 13ms Mar 19 07:48:34 volumio volumio[1010]: info: parsing response... Mar 19 07:48:34 volumio volumio[1010]: info: ControllerMpd::getState Mar 19 07:48:34 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand status Mar 19 07:48:34 volumio volumio[1010]: info: sending command... Mar 19 07:48:34 volumio volumio[1010]: info: parsing response... Mar 19 07:48:34 volumio volumio[1010]: info: ControllerMpd::parseState Mar 19 07:48:34 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:48:34 volumio volumio[1010]: info: sending command... Mar 19 07:48:34 volumio volumio[1010]: info: parsing response... Mar 19 07:48:34 volumio volumio[1010]: info: ControllerMpd::parseTrackInfo Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:48:34 volumio volumio[1010]: info: CorePlayQueue::getTrack 177 Mar 19 07:48:34 volumio volumio[1010]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"playlist.m3u8","artist":null,"album":null,"uri":"http://sfmtunein.imbc.com/tsfm/_definst_/tsfm.stream/playlist.m3u8","trackType":"m3u8"} Mar 19 07:48:34 volumio volumio[1010]: info: CURRENT POSITION 177 Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::syncState stateService play Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::syncState currentStatus stop Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:34 volumio volumio[1010]: info: CorePlayQueue::getTrack 177 Mar 19 07:48:34 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:34 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:34 volumio volumio[1010]: info: Mar 19 07:48:34 volumio volumio[1010]: ---------------------------- MPD announces state update: player Mar 19 07:48:34 volumio volumio[1010]: info: ControllerMpd::getState Mar 19 07:48:34 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand status Mar 19 07:48:34 volumio volumio[1010]: info: sending command... Mar 19 07:48:34 volumio volumio[1010]: info: parsing response... Mar 19 07:48:34 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand clearerror Mar 19 07:48:34 volumio volumio[1010]: info: sending command... Mar 19 07:48:34 volumio volumio[1010]: info: ControllerMpd::parseState Mar 19 07:48:34 volumio volumio[1010]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 19 07:48:34 volumio volumio[1010]: info: sending command... Mar 19 07:48:34 volumio volumio[1010]: info: parsing response... Mar 19 07:48:34 volumio volumio[1010]: info: parsing response... Mar 19 07:48:34 volumio volumio[1010]: info: ControllerMpd::parseTrackInfo Mar 19 07:48:34 volumio volumio[1010]: info: ControllerMpd::pushState Mar 19 07:48:34 volumio volumio[1010]: info: CoreCommandRouter::servicePushState Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::syncState Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::pushState Mar 19 07:48:34 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:34 volumio volumio[1010]: info: CorePlayQueue::getTrack 177 Mar 19 07:48:34 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Mar 19 07:48:34 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 07:48:34 volumio volumio[1010]: info: CorePlayQueue::getTrack 177 Mar 19 07:48:34 volumio volumio[1010]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd Mar 19 07:48:34 volumio volumio[1010]: info: ------------------------------ 50ms Mar 19 07:48:35 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Mar 19 07:48:35 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:48:35 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:35 volumio volumio[1010]: info: CorePlayQueue::getTrack 177 Mar 19 07:48:36 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Mar 19 07:48:37 volumio volumio[1010]: info: CoreCommandRouter::volumioAddQueueItems Mar 19 07:48:37 volumio volumio[1010]: info: CoreStateMachine::addQueueItems Mar 19 07:48:37 volumio volumio[1010]: info: CorePlayQueue::addQueueItems Mar 19 07:48:37 volumio volumio[1010]: info: Adding Item to queue: webkbs/0 Mar 19 07:48:37 volumio volumio[1010]: info: Exploding uri webkbs/0 in service personal_radio Mar 19 07:48:37 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Mar 19 07:48:37 volumio volumio[1010]: info: CoreStateMachine::getState Mar 19 07:48:37 volumio volumio[1010]: info: CorePlayQueue::getTrack 177 Mar 19 07:48:37 volumio volumio[1010]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 19 07:48:37 volumio volumio[1010]: ReferenceError: self is not defined Mar 19 07:48:37 volumio volumio[1010]: at ControllerPersonalRadio.errorToast (/data/plugins/music_service/personal_radio/index.js:575:22) Mar 19 07:48:37 volumio volumio[1010]: at /data/plugins/music_service/personal_radio/index.js:487:12 Mar 19 07:48:37 volumio volumio[1010]: at Request.handleRequestResponse [as _callback] (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:355:15) Mar 19 07:48:37 volumio volumio[1010]: at self.callback (/data/plugins/music_service/personal_radio/node_modules/request/request.js:187:22) Mar 19 07:48:37 volumio volumio[1010]: at emitOne (events.js:116:13) Mar 19 07:48:37 volumio volumio[1010]: at Request.emit (events.js:211:7) Mar 19 07:48:37 volumio volumio[1010]: at Request.onRequestError (/data/plugins/music_service/personal_radio/node_modules/request/request.js:813:8) Mar 19 07:48:37 volumio volumio[1010]: at emitOne (events.js:116:13) Mar 19 07:48:37 volumio volumio[1010]: at ClientRequest.emit (events.js:211:7) Mar 19 07:48:37 volumio volumio[1010]: at Socket.socketErrorListener (_http_client.js:387:9) Mar 19 07:48:37 volumio volumio[1010]: at emitOne (events.js:116:13) Mar 19 07:48:37 volumio volumio[1010]: at Socket.emit (events.js:211:7) Mar 19 07:48:37 volumio volumio[1010]: at emitErrorNT (internal/streams/destroy.js:64:8) Mar 19 07:48:37 volumio volumio[1010]: at _combinedTickCallback (internal/process/next_tick.js:138:11) Mar 19 07:48:37 volumio volumio[1010]: at process._tickCallback (internal/process/next_tick.js:180:9) Mar 19 07:48:37 volumio volumio[1010]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 19 07:48:39 volumio sudo[32758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-19 07:47 Mar 19 07:48:39 volumio sudo[32758]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="c00a19098320053824010d132d6bfb39774a7d2f" VOLUMIO_FE_VERSION="e2f152c30d370c9c7c86508a02c0323bef5c243b" VOLUMIO_BE_VERSION="909cc76a7609428f005f8e2878227062cb5328a6" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Sep 17 00:18:22 CEST 2018" VOLUMIO_VERSION="2.457" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="cdde21c6e3d40ce352c051f661950d1b"