Ducking in vlc still seems to be buggy after the last PR. I have now sometimes - but not always - the situation that vlc volume ist at 30 when starting a ocp skill.
For example: When I start with a fresh started container and call a radio station. Then vlc ist starting with my standard volume of 65. When I then stop ocp and afterwards call the radio station again, then the volume of vlc ist suddenly at 30 (which means that you can hear nothing).
When I look at pavucontrol I can see, that sometimes when I give the "stop" command ovos is ducking to 30. And I think as vlc is killed by the stop command the vlc volume is then remembered with last value (=30)
2024-07-06 17:13:29.712 - skills - ovos_core.transformers:transform:64 - DEBUG - ovos-utterance-cancel: {}
2024-07-06 17:13:29.713 - skills - ovos_core.transformers:transform:64 - DEBUG - ovos-utterance-corrections: {'client_name': 'ovos_dinkum_listener', 'source': 'audio', 'destination': ['skills'], 'transcriptions': [['Spiele Radio Ton', 1.0]], 'lang': 'de-de'}
2024-07-06 17:13:29.715 - skills - ovos_core.transformers:transform:64 - DEBUG - ovos-utterance-normalizer: {'client_name': 'ovos_dinkum_listener', 'source': 'audio', 'destination': ['skills'], 'transcriptions': [['Spiele Radio Ton', 1.0]], 'lang': 'de-de'}
2024-07-06 17:13:29.715 - skills - ovos_core.intent_services:_handle_transformers:173 - DEBUG - utterances transformed: ['Spiele Radio Ton'] -> ['Spiele Radio Ton - Ostwürttemberg']
2024-07-06 17:13:29.718 - skills - ovos_core.intent_services:get_pipeline:251 - DEBUG - Session pipeline: ['stop_high', 'converse', 'ocp_high', 'padatious_high', 'adapt_high', 'ocp_medium', 'fallback_high', 'stop_medium', 'adapt_medium', 'padatious_medium', 'adapt_low', 'common_qa', 'fallback_medium', 'fallback_low']
2024-07-06 17:13:29.719 - skills - ovos_core.intent_services:handle_utterance:392 - DEBUG - no match from <bound method StopService.match_stop_high of <ovos_core.intent_services.stop_service.StopService object at 0x71bc3cd3ec50>>
2024-07-06 17:13:29.722 - skills - ovos_core.intent_services:handle_utterance:392 - DEBUG - no match from <bound method ConverseService.converse_with_skills of <ovos_core.intent_services.converse_service.ConverseService object at 0x71bc408b1510>>
2024-07-06 17:13:29.879 - skills - padacioso:calc_intent:255 - DEBUG - {'entities': {'query': 'radio ton - ostwürttemberg'}, 'conf': 0.96, 'name': 'play'}
2024-07-06 17:13:29.880 - skills - ovos_core.intent_services.ocp_service:match_high:366 - INFO - OCP exact match: {'entities': {'query': 'radio ton - ostwürttemberg'}, 'conf': 0.96, 'name': 'play'}
2024-07-06 17:13:29.899 - skills - ovos_core.intent_services.ocp_service:handle_m:814 - INFO - Session: default Available stream extractor plugins: ['news', 'youtube', 'ydl', 'youtube.channel.live', 'pytube', 'invidious', 'm3u', 'pls', 'rss']
2024-07-06 17:13:29.909 - skills - ovos_core.intent_services.ocp_service:classify_media:746 - INFO - OVOSCommonPlay MediaType prediction: radio confidence: 0.871
2024-07-06 17:13:29.910 - skills - ovos_core.intent_services.ocp_service:classify_media:747 - DEBUG - utterance: radio ton - ostwürttemberg
2024-07-06 17:13:29.913 - skills - ovos_core.intent_services:handle_utterance:380 - INFO - ocp_high match: IntentMatch(intent_service='OCP_intents', intent_type='ocp:play', intent_data={'media_type': <MediaType.RADIO: 7>, 'query': 'radio ton - ostwürttemberg', 'entities': {'radio_streaming_service': 'radio'}, 'skills': [], 'conf': 0.96, 'media_conf': 0.871, 'lang': 'de-de'}, skill_id='ovos.common_play', utterance='radio ton - ostwürttemberg')
2024-07-06 17:13:29.915 - skills - ovos_core.intent_services:handle_utterance:398 - DEBUG - intent matching took: 0.196272611618042
2024-07-06 17:13:29.953 - skills - ovos_workshop.skills.mycroft_skill:settings:193 - WARNING - with MycroftSkill self.settings may not respect self.skill_id, path: /home/ovos/.config/mycroft/skills/skill-openhab/settings.json
2024-07-06 17:13:29.967 - skills - ovos_bus_client.client.client:on_default_session_update:166 - DEBUG - synced default_session
2024-07-06 17:13:29.995 - skills - ovos_bus_client.apis.ocp:__init__:876 - DEBUG - Created RADIO query: radio ton - ostwürttemberg
2024-07-06 17:13:30.526 - skills - ovos_bus_client.client.client:on_default_session_update:166 - DEBUG - synced default_session
2024-07-06 17:13:30.781 - skills - ovos_bus_client.client.client:on_default_session_update:166 - DEBUG - synced default_session
2024-07-06 17:13:31.177 - skills - ovos_bus_client.client.client:on_default_session_update:166 - DEBUG - synced default_session
2024-07-06 17:13:31.582 - skills - ovos_bus_client.client.client:on_default_session_update:166 - DEBUG - synced default_session
2024-07-06 17:13:32.137 - skills - ovos_bus_client.client.client:on_default_session_update:166 - DEBUG - synced default_session
2024-07-06 17:13:36.047 - skills - ovos_bus_client.apis.ocp:register_events:935 - DEBUG - Registering Search Bus Events
2024-07-06 17:13:36.062 - skills - ovos_bus_client.apis.ocp:handle_skill_search_start:948 - DEBUG - skill-ovos-pyradios.femelo is searching
2024-07-06 17:13:36.074 - skills - ovos_bus_client.apis.ocp:handle_skill_search_start:948 - DEBUG - skill-ovos-youtube-music.openvoiceos is searching
2024-07-06 17:13:36.075 - skills - ovos_bus_client.apis.ocp:handle_skill_response:982 - DEBUG - got 0 results from skill-ovos-youtube-music.openvoiceos
2024-07-06 17:13:36.081 - skills - ovos_bus_client.apis.ocp:handle_skill_search_start:948 - DEBUG - skill-ovos-news.openvoiceos is searching
2024-07-06 17:13:36.109 - skills - ovos_skill_pyradios:search:94 - DEBUG - Pyradios: found relevant cached items
2024-07-06 17:13:36.120 - skills - ovos_bus_client.apis.ocp:handle_skill_response:982 - DEBUG - got 0 results from skill-ovos-news.openvoiceos
2024-07-06 17:13:36.121 - skills - ovos_skill_pyradios:search:94 - DEBUG - Pyradios: found relevant cached items
2024-07-06 17:13:36.122 - skills - ovos_bus_client.apis.ocp:handle_skill_response:982 - DEBUG - got 0 results from skill-ovos-youtube.openvoiceos
2024-07-06 17:13:36.122 - skills - ovos_bus_client.apis.ocp:handle_skill_search_end:1015 - DEBUG - skill-ovos-news.openvoiceos finished search
2024-07-06 17:13:36.123 - skills - ovos_bus_client.apis.ocp:handle_skill_search_start:948 - DEBUG - skill-ovos-youtube.openvoiceos is searching
2024-07-06 17:13:36.123 - skills - ovos_bus_client.apis.ocp:handle_skill_search_end:1015 - DEBUG - skill-ovos-youtube.openvoiceos finished search
2024-07-06 17:13:36.125 - skills - ovos_bus_client.apis.ocp:handle_skill_search_end:1015 - DEBUG - skill-ovos-youtube-music.openvoiceos finished search
2024-07-06 17:13:36.127 - skills - ovos_bus_client.apis.ocp:handle_skill_response:982 - DEBUG - got 1 results from skill-ovos-pyradios.femelo
2024-07-06 17:13:36.128 - skills - ovos_bus_client.apis.ocp:handle_skill_response:999 - INFO - Receiving very high confidence match, stopping search early
2024-07-06 17:13:36.128 - skills - ovos_bus_client.apis.ocp:handle_skill_response:1007 - DEBUG - - grace period: 0.5 seconds
2024-07-06 17:13:36.129 - skills - ovos_workshop.skills.common_play:__handle_ocp_query:491 - DEBUG - skipping skill-ovos-pyradios.femelo, it does not support media type: 7
2024-07-06 17:13:36.173 - skills - ovos_bus_client.apis.ocp:handle_skill_search_end:1015 - DEBUG - skill-ovos-pyradios.femelo finished search
2024-07-06 17:13:36.629 - skills - ovos_bus_client.apis.ocp:handle_skill_response:982 - DEBUG - got 1 results from skill-ovos-pyradios.femelo
2024-07-06 17:13:36.649 - skills - ovos_bus_client.apis.ocp:remove_events:941 - DEBUG - Removing Search Bus Events
2024-07-06 17:13:36.650 - skills - ovos_core.intent_services.ocp_service:_execute_query:973 - DEBUG - Returning 2 search results
2024-07-06 17:13:36.652 - skills - ovos_core.intent_services.ocp_service:_search:913 - DEBUG - Got 2 results
2024-07-06 17:13:36.652 - skills - ovos_core.intent_services.ocp_service:filter_results:843 - DEBUG - filtered 0 low confidence results
2024-07-06 17:13:36.653 - skills - ovos_core.intent_services.ocp_service:filter_results:851 - DEBUG - filtered 0 wrong MediaType results
2024-07-06 17:13:36.658 - skills - ovos_core.intent_services.ocp_service:_search:916 - DEBUG - Got 2 usable results
2024-07-06 17:13:36.659 - skills - ovos_core.intent_services.ocp_service:handle_play_intent:573 - DEBUG - Playing 2 results for: radio ton - ostwürttemberg
2024-07-06 17:13:36.660 - skills - ovos_core.intent_services.ocp_service:select_best:1004 - INFO - OVOSCommonPlay selected: skill-ovos-pyradios.femelo - 100
2024-07-06 17:13:36.662 - skills - ovos_core.intent_services.ocp_service:select_best:1005 - DEBUG - MediaEntry(uri='http://radioton-stream29.radiohost.de/radioton-owb_mp3-192?_art=dD0xNzE4Nzk0NzI3JmQ9M2EwMjM1YzA5MGI0MTNmMzRkYzA', title='Radio Ton - Ostwürttemberg', artist='Radio Ton - Ostwürttemberg', match_confidence=100, skill_id='skill-ovos-pyradios.femelo', playback=2, status=<TrackState.DISAMBIGUATION: 1>, media_type=7, length=0, image='https://www.radioton.de/storage/thumbs/192x192/r:1693564697/95010.png', skill_icon='/home/ovos/.venv/lib/python3.11/site-packages/ovos_skill_pyradios/res/radio-tuner-small.png', javascript='')
2024-07-06 17:13:36.665 - skills - ovos_core.intent_services.ocp_service:handle_play_intent:580 - DEBUG - OCP Best match: MediaEntry(uri='http://radioton-stream29.radiohost.de/radioton-owb_mp3-192?_art=dD0xNzE4Nzk0NzI3JmQ9M2EwMjM1YzA5MGI0MTNmMzRkYzA', title='Radio Ton - Ostwürttemberg', artist='Radio Ton - Ostwürttemberg', match_confidence=100, skill_id='skill-ovos-pyradios.femelo', playback=2, status=<TrackState.DISAMBIGUATION: 1>, media_type=7, length=0, image='https://www.radioton.de/storage/thumbs/192x192/r:1693564697/95010.png', skill_icon='/home/ovos/.venv/lib/python3.11/site-packages/ovos_skill_pyradios/res/radio-tuner-small.png', javascript='')
2024-07-06 17:13:40.155 - skills - ovos_core.intent_services.ocp_service:handle_track_state_update:329 - INFO - Session: default OCP PlayerState: PlayerState.PLAYING
2024-07-06 17:13:59.955 - skills - ovos_core.transformers:transform:64 - DEBUG - ovos-utterance-cancel: {}
2024-07-06 17:13:59.957 - skills - ovos_core.transformers:transform:64 - DEBUG - ovos-utterance-corrections: {'client_name': 'ovos_dinkum_listener', 'source': 'audio', 'destination': ['skills'], 'transcriptions': [['höre auf damit', 1.0]], 'lang': 'de-de'}
2024-07-06 17:13:59.958 - skills - ovos_core.transformers:transform:64 - DEBUG - ovos-utterance-normalizer: {'client_name': 'ovos_dinkum_listener', 'source': 'audio', 'destination': ['skills'], 'transcriptions': [['höre auf damit', 1.0]], 'lang': 'de-de'}
2024-07-06 17:13:59.964 - skills - ovos_core.intent_services:get_pipeline:251 - DEBUG - Session pipeline: ['stop_high', 'converse', 'ocp_high', 'padatious_high', 'adapt_high', 'ocp_medium', 'fallback_high', 'stop_medium', 'adapt_medium', 'padatious_medium', 'adapt_low', 'common_qa', 'fallback_medium', 'fallback_low']
2024-07-06 17:14:00.079 - skills - ovos_core.intent_services:handle_utterance:392 - DEBUG - no match from <bound method StopService.match_stop_high of <ovos_core.intent_services.stop_service.StopService object at 0x71bc3cd3ec50>>
2024-07-06 17:14:00.146 - skills - ovos_core.intent_services:handle_utterance:392 - DEBUG - no match from <bound method ConverseService.converse_with_skills of <ovos_core.intent_services.converse_service.ConverseService object at 0x71bc408b1510>>
2024-07-06 17:14:00.460 - skills - padacioso:calc_intent:240 - INFO - No match
2024-07-06 17:14:00.461 - skills - ovos_core.intent_services:handle_utterance:392 - DEBUG - no match from <bound method OCPPipelineMatcher.match_high of <ovos_core.intent_services.ocp_service.OCPPipelineMatcher object at 0x71bc3ffb3fd0>>
2024-07-06 17:14:00.461 - skills - ovos_core.intent_services.padatious_service:_match_level:51 - DEBUG - Padatious Matching confidence > 0.95
2024-07-06 17:14:00.463 - skills - ovos_core.intent_services:handle_utterance:392 - DEBUG - no match from <bound method PadatiousMatcher.match_high of <ovos_core.intent_services.padatious_service.PadatiousMatcher object at 0x71bbf027d710>>
2024-07-06 17:14:00.466 - skills - ovos_core.intent_services:handle_utterance:392 - DEBUG - no match from <bound method AdaptService.match_high of <ovos_core.intent_services.adapt_service.AdaptService object at 0x71bc3ffb4310>>
2024-07-06 17:14:00.470 - skills - ovos_core.intent_services.ocp_service:is_ocp_query:773 - INFO - OVOSCommonPlay prediction: other confidence: 0.961
2024-07-06 17:14:00.471 - skills - ovos_core.intent_services.ocp_service:is_ocp_query:774 - DEBUG - utterance: höre auf damit
2024-07-06 17:14:00.471 - skills - ovos_core.intent_services:handle_utterance:392 - DEBUG - no match from <bound method OCPPipelineMatcher.match_medium of <ovos_core.intent_services.ocp_service.OCPPipelineMatcher object at 0x71bc3ffb3fd0>>
2024-07-06 17:14:00.472 - skills - ovos_core.intent_services:handle_utterance:392 - DEBUG - no match from <bound method FallbackService.high_prio of <ovos_core.intent_services.fallback_service.FallbackService object at 0x71bc3dec6e90>>
2024-07-06 17:14:00.544 - skills - ovos_core.intent_services:handle_utterance:380 - INFO - stop_medium match: IntentMatch(intent_service='Stop', intent_type=True, intent_data={}, skill_id=None, utterance='höre auf damit')
2024-07-06 17:14:00.545 - skills - ovos_core.intent_services:handle_utterance:398 - DEBUG - intent matching took: 0.5812027454376221
common_query.openvoiceos stopped: None
ovos.common_play stopped: None
ovos-skill-iss-location.openvoiceos stopped: None
ovos-skill-cmd.forslund stopped: None
skill-ovos-youtube-music.openvoiceos stopped: None
skill-ovos-fallback-hugchat.femelo stopped: None
skill-ovos-youtube.openvoiceos stopped: None
ovos-skill-dictation.openvoiceos stopped: None
skill-ovos-pyradios.femelo stopped: None
skill-ovos-boot-finished.openvoiceos stopped: None
2024-07-06 17:14:00.634 - skills - ovos_bus_client.client.client:on_default_session_update:166 - DEBUG - synced default_session
skill-openhab stopped: None
skill-ovos-news.openvoiceos stopped: None
2024-07-06 17:14:00.638 - skills - ovos_workshop.skills.mycroft_skill:settings:193 - WARNING - with MycroftSkill self.settings may not respect self.skill_id, path: /home/ovos/.config/mycroft/skills/skill-openhab/settings.json
2024-07-06 17:14:00.794 - skills - ovos_workshop.skills.mycroft_skill:settings:193 - WARNING - with MycroftSkill self.settings may not respect self.skill_id, path: /home/ovos/.config/mycroft/skills/skill-openhab/settings.json
2024-07-06 17:14:10.531 - skills - ovos_core.transformers:transform:64 - DEBUG - ovos-utterance-cancel: {}
2024-07-06 17:14:10.532 - skills - ovos_core.transformers:transform:64 - DEBUG - ovos-utterance-corrections: {'client_name': 'ovos_dinkum_listener', 'source': 'audio', 'destination': ['skills'], 'transcriptions': [['Spiele Radio Ton', 1.0]], 'lang': 'de-de'}
2024-07-06 17:14:10.534 - skills - ovos_core.transformers:transform:64 - DEBUG - ovos-utterance-normalizer: {'client_name': 'ovos_dinkum_listener', 'source': 'audio', 'destination': ['skills'], 'transcriptions': [['Spiele Radio Ton', 1.0]], 'lang': 'de-de'}
2024-07-06 17:14:10.534 - skills - ovos_core.intent_services:_handle_transformers:173 - DEBUG - utterances transformed: ['Spiele Radio Ton'] -> ['Spiele Radio Ton - Ostwürttemberg']
2024-07-06 17:14:10.538 - skills - ovos_core.intent_services:get_pipeline:251 - DEBUG - Session pipeline: ['stop_high', 'converse', 'ocp_high', 'padatious_high', 'adapt_high', 'ocp_medium', 'fallback_high', 'stop_medium', 'adapt_medium', 'padatious_medium', 'adapt_low', 'common_qa', 'fallback_medium', 'fallback_low']
2024-07-06 17:14:10.538 - skills - ovos_core.intent_services:handle_utterance:392 - DEBUG - no match from <bound method StopService.match_stop_high of <ovos_core.intent_services.stop_service.StopService object at 0x71bc3cd3ec50>>
2024-07-06 17:14:10.551 - skills - ovos_core.intent_services:handle_utterance:392 - DEBUG - no match from <bound method ConverseService.converse_with_skills of <ovos_core.intent_services.converse_service.ConverseService object at 0x71bc408b1510>>
2024-07-06 17:14:10.846 - skills - padacioso:calc_intent:255 - DEBUG - {'entities': {'query': 'radio ton - ostwürttemberg'}, 'conf': 0.96, 'name': 'play'}
2024-07-06 17:14:10.847 - skills - ovos_core.intent_services.ocp_service:match_high:366 - INFO - OCP exact match: {'entities': {'query': 'radio ton - ostwürttemberg'}, 'conf': 0.96, 'name': 'play'}
2024-07-06 17:14:10.857 - skills - ovos_core.intent_services.ocp_service:classify_media:746 - INFO - OVOSCommonPlay MediaType prediction: radio confidence: 0.871
2024-07-06 17:14:10.858 - skills - ovos_core.intent_services.ocp_service:classify_media:747 - DEBUG - utterance: radio ton - ostwürttemberg
2024-07-06 17:14:10.858 - skills - ovos_core.intent_services:handle_utterance:380 - INFO - ocp_high match: IntentMatch(intent_service='OCP_intents', intent_type='ocp:play', intent_data={'media_type': <MediaType.RADIO: 7>, 'query': 'radio ton - ostwürttemberg', 'entities': {'radio_streaming_service': 'radio'}, 'skills': [], 'conf': 0.96, 'media_conf': 0.871, 'lang': 'de-de'}, skill_id='ovos.common_play', utterance='radio ton - ostwürttemberg')
2024-07-06 17:14:10.860 - skills - ovos_core.intent_services:handle_utterance:398 - DEBUG - intent matching took: 0.32249975204467773
2024-07-06 17:14:10.870 - skills - ovos_workshop.skills.mycroft_skill:settings:193 - WARNING - with MycroftSkill self.settings may not respect self.skill_id, path: /home/ovos/.config/mycroft/skills/skill-openhab/settings.json
2024-07-06 17:14:10.881 - skills - ovos_bus_client.client.client:on_default_session_update:166 - DEBUG - synced default_session
2024-07-06 17:14:10.892 - skills - ovos_bus_client.apis.ocp:__init__:876 - DEBUG - Created RADIO query: radio ton - ostwürttemberg
2024-07-06 17:14:16.940 - skills - ovos_bus_client.apis.ocp:register_events:935 - DEBUG - Registering Search Bus Events
2024-07-06 17:14:16.950 - skills - ovos_bus_client.apis.ocp:handle_skill_search_start:948 - DEBUG - skill-ovos-pyradios.femelo is searching
2024-07-06 17:14:16.959 - skills - ovos_bus_client.apis.ocp:handle_skill_search_start:948 - DEBUG - skill-ovos-youtube-music.openvoiceos is searching
2024-07-06 17:14:16.969 - skills - ovos_skill_pyradios:search:94 - DEBUG - Pyradios: found relevant cached items
2024-07-06 17:14:16.983 - skills - ovos_bus_client.apis.ocp:handle_skill_response:982 - DEBUG - got 0 results from skill-ovos-youtube-music.openvoiceos
2024-07-06 17:14:16.989 - skills - ovos_bus_client.apis.ocp:handle_skill_search_end:1015 - DEBUG - skill-ovos-youtube-music.openvoiceos finished search
2024-07-06 17:14:16.990 - skills - ovos_bus_client.apis.ocp:handle_skill_search_start:948 - DEBUG - skill-ovos-youtube.openvoiceos is searching
2024-07-06 17:14:16.992 - skills - ovos_bus_client.apis.ocp:handle_skill_response:982 - DEBUG - got 0 results from skill-ovos-youtube.openvoiceos
2024-07-06 17:14:16.993 - skills - ovos_bus_client.apis.ocp:handle_skill_search_end:1015 - DEBUG - skill-ovos-youtube.openvoiceos finished search
2024-07-06 17:14:16.994 - skills - ovos_bus_client.apis.ocp:handle_skill_search_start:948 - DEBUG - skill-ovos-news.openvoiceos is searching
2024-07-06 17:14:16.997 - skills - ovos_skill_pyradios:search:94 - DEBUG - Pyradios: found relevant cached items
2024-07-06 17:14:17.008 - skills - ovos_bus_client.apis.ocp:handle_skill_search_end:1015 - DEBUG - skill-ovos-news.openvoiceos finished search
2024-07-06 17:14:17.009 - skills - ovos_bus_client.apis.ocp:handle_skill_response:982 - DEBUG - got 0 results from skill-ovos-news.openvoiceos
2024-07-06 17:14:17.009 - skills - ovos_bus_client.apis.ocp:handle_skill_response:982 - DEBUG - got 1 results from skill-ovos-pyradios.femelo
2024-07-06 17:14:17.009 - skills - ovos_workshop.skills.common_play:__handle_ocp_query:491 - DEBUG - skipping skill-ovos-pyradios.femelo, it does not support media type: 7
2024-07-06 17:14:17.011 - skills - ovos_bus_client.apis.ocp:handle_skill_response:999 - INFO - Receiving very high confidence match, stopping search early
2024-07-06 17:14:17.013 - skills - ovos_bus_client.apis.ocp:handle_skill_response:1007 - DEBUG - - grace period: 0.5 seconds
2024-07-06 17:14:17.053 - skills - ovos_bus_client.apis.ocp:handle_skill_search_end:1015 - DEBUG - skill-ovos-pyradios.femelo finished search
2024-07-06 17:14:17.514 - skills - ovos_bus_client.apis.ocp:handle_skill_response:982 - DEBUG - got 1 results from skill-ovos-pyradios.femelo
2024-07-06 17:14:17.542 - skills - ovos_bus_client.apis.ocp:remove_events:941 - DEBUG - Removing Search Bus Events
2024-07-06 17:14:17.542 - skills - ovos_core.intent_services.ocp_service:_execute_query:973 - DEBUG - Returning 2 search results
2024-07-06 17:14:17.544 - skills - ovos_core.intent_services.ocp_service:_search:913 - DEBUG - Got 2 results
2024-07-06 17:14:17.545 - skills - ovos_core.intent_services.ocp_service:filter_results:843 - DEBUG - filtered 0 low confidence results
2024-07-06 17:14:17.545 - skills - ovos_core.intent_services.ocp_service:filter_results:851 - DEBUG - filtered 0 wrong MediaType results
2024-07-06 17:14:17.546 - skills - ovos_core.intent_services.ocp_service:_search:916 - DEBUG - Got 2 usable results
2024-07-06 17:14:17.547 - skills - ovos_core.intent_services.ocp_service:handle_play_intent:573 - DEBUG - Playing 2 results for: radio ton - ostwürttemberg
2024-07-06 17:14:17.548 - skills - ovos_core.intent_services.ocp_service:select_best:1004 - INFO - OVOSCommonPlay selected: skill-ovos-pyradios.femelo - 100
2024-07-06 17:14:17.548 - skills - ovos_core.intent_services.ocp_service:select_best:1005 - DEBUG - MediaEntry(uri='http://radioton-stream29.radiohost.de/radioton-owb_mp3-192?_art=dD0xNzE4Nzk0NzI3JmQ9M2EwMjM1YzA5MGI0MTNmMzRkYzA', title='Radio Ton - Ostwürttemberg', artist='Radio Ton - Ostwürttemberg', match_confidence=100, skill_id='skill-ovos-pyradios.femelo', playback=2, status=<TrackState.DISAMBIGUATION: 1>, media_type=7, length=0, image='https://www.radioton.de/storage/thumbs/192x192/r:1693564697/95010.png', skill_icon='/home/ovos/.venv/lib/python3.11/site-packages/ovos_skill_pyradios/res/radio-tuner-small.png', javascript='')
2024-07-06 17:14:17.554 - skills - ovos_core.intent_services.ocp_service:handle_play_intent:580 - DEBUG - OCP Best match: MediaEntry(uri='http://radioton-stream29.radiohost.de/radioton-owb_mp3-192?_art=dD0xNzE4Nzk0NzI3JmQ9M2EwMjM1YzA5MGI0MTNmMzRkYzA', title='Radio Ton - Ostwürttemberg', artist='Radio Ton - Ostwürttemberg', match_confidence=100, skill_id='skill-ovos-pyradios.femelo', playback=2, status=<TrackState.DISAMBIGUATION: 1>, media_type=7, length=0, image='https://www.radioton.de/storage/thumbs/192x192/r:1693564697/95010.png', skill_icon='/home/ovos/.venv/lib/python3.11/site-packages/ovos_skill_pyradios/res/radio-tuner-small.png', javascript='')
2024-07-06 17:13:29.707 - audio - ovos_audio.audio:_restore_volume_after_record:352 - DEBUG - No audio service to restore volume of
2024-07-06 17:13:29.945 - audio - ovos_bus_client.client.client:on_default_session_update:166 - DEBUG - synced default_session
2024-07-06 17:13:29.991 - audio - ovos_audio.service:execute_tts:384 - INFO - Speak: Einen Moment bitte, während ich danach suche
2024-07-06 17:13:29.997 - audio - ovos_plugin_manager.templates.tts:_get_ctxt:559 - DEBUG - TTS kwargs: {'lang': 'de-de', 'voice': 'thorsten-low'}
2024-07-06 17:13:30.002 - audio - ovos_plugin_manager.utils.tts_cache:load_persistent_cache:263 - INFO - Persistent TTS cache files loaded successfully.
2024-07-06 17:13:30.003 - audio - ovos_tts_plugin_server:_fetch_audio_data:86 - DEBUG - Chosen TTS server http://192.168.2.222:8089/v2/synthesize
2024-07-06 17:13:30.028 - audio - ovos_bus_client.session:get:605 - DEBUG - No message, use default session
2024-07-06 17:13:30.524 - audio - ovos_bus_client.client.client:on_default_session_update:166 - DEBUG - synced default_session
2024-07-06 17:13:30.777 - audio - ovos_bus_client.client.client:on_default_session_update:166 - DEBUG - synced default_session
2024-07-06 17:13:31.138 - audio - ovos_bus_client.client.client:on_default_session_update:166 - DEBUG - synced default_session
2024-07-06 17:13:31.542 - audio - ovos_bus_client.client.client:on_default_session_update:166 - DEBUG - synced default_session
2024-07-06 17:13:32.104 - audio - ovos_bus_client.client.client:on_default_session_update:166 - DEBUG - synced default_session
2024-07-06 17:13:36.682 - audio - ovos_plugin_common_play.ocp.player:handle_play_request:761 - DEBUG - Received external OVOS playback request
2024-07-06 17:13:36.689 - audio - ovos_utils.ocp:goto_track:552 - DEBUG - New playlist position: 0
2024-07-06 17:13:36.689 - audio - ovos_plugin_common_play.ocp.player:set_now_playing:250 - DEBUG - Playing: MediaEntry(uri='http://radioton-stream29.radiohost.de/radioton-owb_mp3-192?_art=dD0xNzE4Nzk0NzI3JmQ9M2EwMjM1YzA5MGI0MTNmMzRkYzA', title='Radio Ton - Ostwürttemberg', artist='Radio Ton - Ostwürttemberg', match_confidence=100, skill_id='skill-ovos-pyradios.femelo', playback=2, status=1, media_type=7, length=0, image='https://www.radioton.de/storage/thumbs/192x192/r:1693564697/95010.png', skill_icon='/home/ovos/.venv/lib/python3.11/site-packages/ovos_skill_pyradios/res/radio-tuner-small.png', javascript='')
2024-07-06 17:13:36.690 - audio - ovos_plugin_common_play.ocp.media:reset:476 - DEBUG - Resetting NowPlaying
2024-07-06 17:13:36.692 - audio - ovos_utils.ocp:goto_track:552 - DEBUG - New playlist position: 0
2024-07-06 17:13:36.692 - audio - ovos_plugin_common_play.ocp.media:extract_stream:528 - INFO - OCP plugins metadata: {'uri': 'http://radioton-stream29.radiohost.de/radioton-owb_mp3-192?_art=dD0xNzE4Nzk0NzI3JmQ9M2EwMjM1YzA5MGI0MTNmMzRkYzA'}
2024-07-06 17:13:40.111 - audio - ovos_plugin_common_play.ocp.gui:manage_display:217 - DEBUG - manage_display: page_requested: player
2024-07-06 17:13:40.112 - audio - ovos_plugin_common_play.ocp.gui:manage_display:218 - DEBUG - manage_display: player_status: 0
2024-07-06 17:13:40.113 - audio - ovos_plugin_common_play.ocp.gui:_get_pages_to_display:335 - DEBUG - pages to display backend: OVOSSyncPlayer
2024-07-06 17:13:40.121 - audio - ovos_bus_client.apis.gui:_pages2uri:375 - DEBUG - Requested page not resolved to a file: None
2024-07-06 17:13:40.126 - audio - ovos_bus_client.apis.gui:_pages2uri:375 - DEBUG - Requested page not resolved to a file: None
2024-07-06 17:13:40.127 - audio - ovos_bus_client.apis.gui:_pages2uri:376 - DEBUG - Resolved pages: []
2024-07-06 17:13:40.129 - audio - ovos_bus_client.apis.gui:show_pages:448 - DEBUG - Updating gui data: {'searchModel': {'data': [{'duration': 0, 'track': 'Radio Ton - Ostwürttemberg', 'image': 'https://www.radioton.de/storage/thumbs/192x192/r:1693564697/95010.png', 'album': 'skill-ovos-pyradios.femelo', 'source': '/home/ovos/.venv/lib/python3.11/site-packages/ovos_skill_pyradios/res/radio-tuner-small.png', 'uri': 'http://radioton-stream29.radiohost.de/radioton-owb_mp3-192?_art=dD0xNzE4Nzk0NzI3JmQ9M2EwMjM1YzA5MGI0MTNmMzRkYzA'}]}, 'canResume': True, 'canPause': True, 'canPrev': False, 'canNext': False, 'loopStatus': 'None', 'shuffleStatus': False, 'media': {'uri': 'http://radioton-stream29.radiohost.de/radioton-owb_mp3-192?_art=dD0xNzE4Nzk0NzI3JmQ9M2EwMjM1YzA5MGI0MTNmMzRkYzA', 'title': 'Radio Ton - Ostwürttemberg', 'artist': 'Radio Ton - Ostwürttemberg', 'match_confidence': 100, 'skill_id': 'skill-ovos-pyradios.femelo', 'playback': <PlaybackType.AUDIO_SERVICE: 3>, 'status': 1, 'media_type': 7, 'length': 0, 'image': 'https://www.radioton.de/storage/thumbs/192x192/r:1693564697/95010.png', 'skill_icon': '/home/ovos/.venv/lib/python3.11/site-packages/ovos_skill_pyradios/res/radio-tuner-small.png', 'javascript': '', 'position': 0, 'phrase': None, 'bg_image': '/home/ovos/.venv/lib/python3.11/site-packages/ovos_plugin_common_play/ocp/res/ui/images/ocp_bg.png', 'is_cps': False, 'data': {}, 'cps_data': {}, 'duration': 0, 'track': 'Radio Ton - Ostwürttemberg', 'album': 'skill-ovos-pyradios.femelo', 'source': '/home/ovos/.venv/lib/python3.11/site-packages/ovos_skill_pyradios/res/radio-tuner-small.png'}, 'uri': 'http://radioton-stream29.radiohost.de/radioton-owb_mp3-192?_art=dD0xNzE4Nzk0NzI3JmQ9M2EwMjM1YzA5MGI0MTNmMzRkYzA', 'title': 'Radio Ton - Ostwürttemberg', 'image': 'https://www.radioton.de/storage/thumbs/192x192/r:1693564697/95010.png', 'artist': 'Radio Ton - Ostwürttemberg', 'bg_image': '/home/ovos/.venv/lib/python3.11/site-packages/ovos_plugin_common_play/ocp/res/ui/images/ocp_bg.png', 'duration': 0, 'position': 0, 'javascript': '', 'javascriptCanOpenWindows': False, 'allowUrlChange': False, 'playlistModel': {'data': [{'duration': 0, 'track': 'Radio Ton - Ostwürttemberg', 'image': 'https://www.radioton.de/storage/thumbs/192x192/r:1693564697/95010.png', 'album': 'skill-ovos-pyradios.femelo', 'source': '/home/ovos/.venv/lib/python3.11/site-packages/ovos_skill_pyradios/res/radio-tuner-small.png', 'uri': 'http://radioton-stream29.radiohost.de/radioton-owb_mp3-192?_art=dD0xNzE4Nzk0NzI3JmQ9M2EwMjM1YzA5MGI0MTNmMzRkYzA'}]}, 'stream': 'http://radioton-stream29.radiohost.de/radioton-owb_mp3-192?_art=dD0xNzE4Nzk0NzI3JmQ9M2EwMjM1YzA5MGI0MTNmMzRkYzA', 'status': 'Stopped', 'app_view_timeout_enabled': False, 'app_view_timeout': 30, 'app_view_timeout_mode': 'all', 'playerBackend': 'OVOSSyncPlayer', 'displaySuggestionBar': True, '__from': 'ovos.common_play'}
2024-07-06 17:13:40.130 - audio - ovos_plugin_common_play.ocp.player:play:405 - DEBUG - Requesting playback: <PlaybackType.AUDIO_SERVICE: 3>
2024-07-06 17:13:40.132 - audio - ovos_plugin_common_play.ocp.player:play:411 - DEBUG - Handling playback via audio_service
2024-07-06 17:13:40.145 - audio - ovos_plugin_common_play.ocp.player:play:462 - DEBUG - self.active_backend=<PlaybackType.AUDIO_SERVICE: 3>
2024-07-06 17:13:40.147 - audio - ovos_audio.audio:_play:438 - DEBUG - vlc would be preferred
2024-07-06 17:13:40.148 - audio - ovos_plugin_manager.templates.audio:load_track:340 - DEBUG - queuing for OVOSVlcService playback: http://radioton-stream29.radiohost.de/radioton-owb_mp3-192?_art=dD0xNzE4Nzk0NzI3JmQ9M2EwMjM1YzA5MGI0MTNmMzRkYzA
2024-07-06 17:13:40.149 - audio - ovos_plugin_vlc:play:87 - DEBUG - VLCService Play
2024-07-06 17:13:40.155 - audio - ovos_plugin_common_play.ocp.media:handle_track_state_change:579 - INFO - TrackState changed: <TrackState.PLAYING_AUDIOSERVICE: 21>
2024-07-06 17:13:40.200 - audio - ovos_plugin_common_play.ocp.player:handle_player_media_update:721 - DEBUG - backend=<PlaybackType.AUDIO_SERVICE: 3>|msg_type=ovos.common_play.media.state
2024-07-06 17:13:40.201 - audio - ovos_plugin_common_play.ocp.media:handle_track_state_change:579 - INFO - TrackState changed: <TrackState.QUEUED_AUDIOSERVICE: 31>
2024-07-06 17:13:40.202 - audio - ovos_plugin_common_play.ocp.player:handle_player_media_update:721 - DEBUG - backend=<PlaybackType.AUDIO_SERVICE: 3>|msg_type=ovos.common_play.media.state
2024-07-06 17:13:40.202 - audio - ovos_plugin_common_play.ocp.media:handle_track_state_change:579 - INFO - TrackState changed: <TrackState.PLAYING_AUDIOSERVICE: 21>
2024-07-06 17:13:40.203 - audio - ovos_plugin_common_play.ocp.player:handle_player_media_update:733 - DEBUG - MediaState changed: <MediaState.LOADING_MEDIA: 2>
2024-07-06 17:13:40.203 - audio - ovos_plugin_common_play.ocp.player:handle_player_media_update:733 - DEBUG - MediaState changed: <MediaState.LOADED_MEDIA: 3>
2024-07-06 17:13:41.444 - audio - ovos_plugin_vlc:track_start:60 - DEBUG - VLC playback start
2024-07-06 17:13:41.444 - audio - ovos_audio.audio:track_start:210 - DEBUG - New track coming up!
2024-07-06 17:13:41.459 - audio - ovos_plugin_common_play.ocp.player:handle_player_media_update:721 - DEBUG - backend=<PlaybackType.AUDIO_SERVICE: 3>|msg_type=ovos.common_play.media.state
2024-07-06 17:13:56.574 - audio - ovos_audio.audio:_lower_volume:314 - DEBUG - lowering volume
2024-07-06 17:13:56.574 - audio - ovos_plugin_vlc:lower_volume:113 - INFO - vlc is volume currently at 65, lowering to 30
2024-07-06 17:13:56.574 - audio - ovos_plugin_common_play.ocp.player:pause:555 - DEBUG - Pausing playback: 3
2024-07-06 17:13:59.950 - audio - ovos_plugin_common_play.ocp.player:resume:576 - DEBUG - Resuming playback: 3
2024-07-06 17:14:00.009 - audio - ovos_plugin_vlc:track_start:60 - DEBUG - VLC playback start
2024-07-06 17:14:00.009 - audio - ovos_audio.audio:track_start:210 - DEBUG - New track coming up!
2024-07-06 17:14:00.069 - audio - ovos_plugin_common_play.ocp.player:handle_player_media_update:721 - DEBUG - backend=<PlaybackType.AUDIO_SERVICE: 3>|msg_type=ovos.common_play.media.state
2024-07-06 17:14:00.082 - audio - ovos_plugin_common_play.ocp.player:stop:612 - DEBUG - clearing playlist
2024-07-06 17:14:00.082 - audio - ovos_plugin_common_play.ocp.player:stop:615 - DEBUG - Stopping playback
2024-07-06 17:14:00.134 - audio - ovos_audio.audio:_stop:296 - DEBUG - stopping all playing services
2024-07-06 17:14:00.140 - audio - ovos_plugin_vlc:stop:94 - INFO - VLCService Stop
2024-07-06 17:14:00.156 - audio - ovos_audio.audio:_stop:303 - INFO - END Stop
2024-07-06 17:14:00.203 - audio - ovos_plugin_common_play.ocp.player:handle_player_media_update:721 - DEBUG - backend=<PlaybackType.AUDIO_SERVICE: 3>|msg_type=ovos.common_play.media.state
2024-07-06 17:14:00.206 - audio - ovos_plugin_common_play.ocp.player:handle_player_media_update:733 - DEBUG - MediaState changed: <MediaState.END_OF_MEDIA: 7>
2024-07-06 17:14:00.207 - audio - ovos_plugin_common_play.ocp.player:handle_playback_ended:755 - INFO - Playback ended
2024-07-06 17:14:00.408 - audio - ovos_plugin_common_play.ocp.gui:manage_display:217 - DEBUG - manage_display: page_requested: playlist
2024-07-06 17:14:00.409 - audio - ovos_plugin_common_play.ocp.gui:manage_display:218 - DEBUG - manage_display: player_status: 0
2024-07-06 17:14:00.410 - audio - ovos_bus_client.apis.gui:_pages2uri:375 - DEBUG - Requested page not resolved to a file: None
2024-07-06 17:14:00.417 - audio - ovos_bus_client.apis.gui:_pages2uri:376 - DEBUG - Resolved pages: []
2024-07-06 17:14:00.420 - audio - ovos_bus_client.apis.gui:show_pages:448 - DEBUG - Updating gui data: {'searchModel': {'data': [{'duration': 0, 'track': 'Radio Ton - Ostwürttemberg', 'image': 'https://www.radioton.de/storage/thumbs/192x192/r:1693564697/95010.png', 'album': 'skill-ovos-pyradios.femelo', 'source': '/home/ovos/.venv/lib/python3.11/site-packages/ovos_skill_pyradios/res/radio-tuner-small.png', 'uri': 'http://radioton-stream29.radiohost.de/radioton-owb_mp3-192?_art=dD0xNzE4Nzk0NzI3JmQ9M2EwMjM1YzA5MGI0MTNmMzRkYzA'}]}, 'canResume': True, 'canPause': True, 'canPrev': False, 'canNext': False, 'loopStatus': 'None', 'shuffleStatus': False, 'media': {'uri': 'http://radioton-stream29.radiohost.de/radioton-owb_mp3-192?_art=dD0xNzE4Nzk0NzI3JmQ9M2EwMjM1YzA5MGI0MTNmMzRkYzA', 'title': 'Radio Ton - Ostwürttemberg', 'artist': 'Radio Ton - Ostwürttemberg', 'match_confidence': 100, 'skill_id': 'skill-ovos-pyradios.femelo', 'playback': <PlaybackType.AUDIO_SERVICE: 3>, 'status': 1, 'media_type': 7, 'length': 0, 'image': 'https://www.radioton.de/storage/thumbs/192x192/r:1693564697/95010.png', 'skill_icon': '/home/ovos/.venv/lib/python3.11/site-packages/ovos_skill_pyradios/res/radio-tuner-small.png', 'javascript': '', 'position': 0, 'phrase': None, 'bg_image': '/home/ovos/.venv/lib/python3.11/site-packages/ovos_plugin_common_play/ocp/res/ui/images/ocp_bg.png', 'is_cps': False, 'data': {}, 'cps_data': {}, 'duration': 0, 'track': 'Radio Ton - Ostwürttemberg', 'album': 'skill-ovos-pyradios.femelo', 'source': '/home/ovos/.venv/lib/python3.11/site-packages/ovos_skill_pyradios/res/radio-tuner-small.png'}, 'uri': 'http://radioton-stream29.radiohost.de/radioton-owb_mp3-192?_art=dD0xNzE4Nzk0NzI3JmQ9M2EwMjM1YzA5MGI0MTNmMzRkYzA', 'title': 'Radio Ton - Ostwürttemberg', 'image': 'https://www.radioton.de/storage/thumbs/192x192/r:1693564697/95010.png', 'artist': 'Radio Ton - Ostwürttemberg', 'bg_image': '/home/ovos/.venv/lib/python3.11/site-packages/ovos_plugin_common_play/ocp/res/ui/images/ocp_bg.png', 'duration': 0, 'position': 15122, 'javascript': '', 'javascriptCanOpenWindows': False, 'allowUrlChange': False, 'playlistModel': {'data': [{'duration': 0, 'track': 'Radio Ton - Ostwürttemberg', 'image': 'https://www.radioton.de/storage/thumbs/192x192/r:1693564697/95010.png', 'album': 'skill-ovos-pyradios.femelo', 'source': '/home/ovos/.venv/lib/python3.11/site-packages/ovos_skill_pyradios/res/radio-tuner-small.png', 'uri': 'http://radioton-stream29.radiohost.de/radioton-owb_mp3-192?_art=dD0xNzE4Nzk0NzI3JmQ9M2EwMjM1YzA5MGI0MTNmMzRkYzA'}]}, 'stream': 'http://radioton-stream29.radiohost.de/radioton-owb_mp3-192?_art=dD0xNzE4Nzk0NzI3JmQ9M2EwMjM1YzA5MGI0MTNmMzRkYzA', 'status': 'Stopped', 'app_view_timeout_enabled': False, 'app_view_timeout': 30, 'app_view_timeout_mode': 'all', 'playerBackend': 'OVOSSyncPlayer', 'displaySuggestionBar': False, 'length': 0, '__from': 'ovos.common_play'}
2024-07-06 17:14:00.531 - audio - ovos_plugin_common_play.ocp.player:stop:612 - DEBUG - clearing playlist
2024-07-06 17:14:00.539 - audio - ovos_plugin_common_play.ocp.player:stop:615 - DEBUG - Stopping playback
ovos.common_play stopped: None
2024-07-06 17:14:00.596 - audio - ovos_plugin_common_play.ocp.player:stop:612 - DEBUG - clearing playlist
2024-07-06 17:14:00.607 - audio - ovos_bus_client.client.client:on_default_session_update:166 - DEBUG - synced default_session
2024-07-06 17:14:00.608 - audio - ovos_plugin_common_play.ocp.player:stop:615 - DEBUG - Stopping playback
ovos.common_play stopped: None
2024-07-06 17:14:00.611 - audio - ovos_audio.audio:_stop:296 - DEBUG - stopping all playing services
2024-07-06 17:14:00.613 - audio - ovos_audio.audio:_stop:303 - INFO - END Stop
2024-07-06 17:14:00.659 - audio - ovos_plugin_common_play.ocp.player:stop:612 - DEBUG - clearing playlist
2024-07-06 17:14:00.682 - audio - ovos_plugin_common_play.ocp.player:stop:612 - DEBUG - clearing playlist
2024-07-06 17:14:00.685 - audio - ovos_audio.audio:_stop:296 - DEBUG - stopping all playing services
2024-07-06 17:14:00.686 - audio - ovos_audio.audio:_stop:303 - INFO - END Stop
2024-07-06 17:14:00.686 - audio - ovos_plugin_common_play.ocp.player:stop:615 - DEBUG - Stopping playback
2024-07-06 17:14:00.688 - audio - ovos_plugin_common_play.ocp.player:stop:615 - DEBUG - Stopping playback
2024-07-06 17:14:00.703 - audio - ovos_plugin_common_play.ocp.player:stop:612 - DEBUG - clearing playlist
2024-07-06 17:14:00.703 - audio - ovos_plugin_common_play.ocp.player:stop:615 - DEBUG - Stopping playback
2024-07-06 17:14:00.816 - audio - ovos_audio.audio:_stop:296 - DEBUG - stopping all playing services
2024-07-06 17:14:00.817 - audio - ovos_audio.audio:_stop:303 - INFO - END Stop
2024-07-06 17:14:00.827 - audio - ovos_audio.audio:_stop:296 - DEBUG - stopping all playing services
2024-07-06 17:14:00.828 - audio - ovos_audio.audio:_stop:303 - INFO - END Stop
2024-07-06 17:14:00.842 - audio - ovos_audio.audio:_stop:296 - DEBUG - stopping all playing services
2024-07-06 17:14:00.848 - audio - ovos_audio.audio:_stop:303 - INFO - END Stop
2024-07-06 17:14:10.525 - audio - ovos_audio.audio:_restore_volume_after_record:352 - DEBUG - No audio service to restore volume of
2024-07-06 17:14:10.923 - audio - ovos_audio.service:execute_tts:384 - INFO - Speak: Augenblick
2024-07-06 17:14:10.925 - audio - ovos_bus_client.client.client:on_default_session_update:166 - DEBUG - synced default_session
2024-07-06 17:14:10.925 - audio - ovos_plugin_manager.templates.tts:_get_ctxt:559 - DEBUG - TTS kwargs: {'lang': 'de-de', 'voice': 'thorsten-low'}
2024-07-06 17:14:10.929 - audio - ovos_tts_plugin_server:_fetch_audio_data:86 - DEBUG - Chosen TTS server http://192.168.2.222:8089/v2/synthesize
2024-07-06 17:14:10.944 - audio - ovos_bus_client.session:get:605 - DEBUG - No message, use default session
2024-07-06 17:14:17.565 - audio - ovos_plugin_common_play.ocp.player:handle_play_request:761 - DEBUG - Received external OVOS playback request
2024-07-06 17:14:17.567 - audio - ovos_utils.ocp:goto_track:552 - DEBUG - New playlist position: 0
2024-07-06 17:14:17.576 - audio - ovos_plugin_common_play.ocp.player:set_now_playing:250 - DEBUG - Playing: MediaEntry(uri='http://radioton-stream29.radiohost.de/radioton-owb_mp3-192?_art=dD0xNzE4Nzk0NzI3JmQ9M2EwMjM1YzA5MGI0MTNmMzRkYzA', title='Radio Ton - Ostwürttemberg', artist='Radio Ton - Ostwürttemberg', match_confidence=100, skill_id='skill-ovos-pyradios.femelo', playback=2, status=1, media_type=7, length=0, image='https://www.radioton.de/storage/thumbs/192x192/r:1693564697/95010.png', skill_icon='/home/ovos/.venv/lib/python3.11/site-packages/ovos_skill_pyradios/res/radio-tuner-small.png', javascript='')
2024-07-06 17:14:17.577 - audio - ovos_plugin_common_play.ocp.media:reset:476 - DEBUG - Resetting NowPlaying
2024-07-06 17:14:17.579 - audio - ovos_utils.ocp:goto_track:552 - DEBUG - New playlist position: 0
2024-07-06 17:14:17.587 - audio - ovos_plugin_common_play.ocp.media:extract_stream:528 - INFO - OCP plugins metadata: {'uri': 'http://radioton-stream29.radiohost.de/radioton-owb_mp3-192?_art=dD0xNzE4Nzk0NzI3JmQ9M2EwMjM1YzA5MGI0MTNmMzRkYzA'}
2024-07-06 17:14:21.010 - audio - ovos_plugin_common_play.ocp.gui:manage_display:217 - DEBUG - manage_display: page_requested: player
2024-07-06 17:14:21.011 - audio - ovos_plugin_common_play.ocp.gui:manage_display:218 - DEBUG - manage_display: player_status: 0
2024-07-06 17:14:21.012 - audio - ovos_plugin_common_play.ocp.gui:_get_pages_to_display:335 - DEBUG - pages to display backend: OVOSSyncPlayer
2024-07-06 17:14:21.014 - audio - ovos_bus_client.apis.gui:_pages2uri:375 - DEBUG - Requested page not resolved to a file: None
2024-07-06 17:14:21.030 - audio - ovos_bus_client.apis.gui:_pages2uri:375 - DEBUG - Requested page not resolved to a file: None
2024-07-06 17:14:21.033 - audio - ovos_bus_client.apis.gui:_pages2uri:376 - DEBUG - Resolved pages: []
2024-07-06 17:14:21.034 - audio - ovos_bus_client.apis.gui:show_pages:448 - DEBUG - Updating gui data: {'searchModel': {'data': [{'duration': 0, 'track': 'Radio Ton - Ostwürttemberg', 'image': 'https://www.radioton.de/storage/thumbs/192x192/r:1693564697/95010.png', 'album': 'skill-ovos-pyradios.femelo', 'source': '/home/ovos/.venv/lib/python3.11/site-packages/ovos_skill_pyradios/res/radio-tuner-small.png', 'uri': 'http://radioton-stream29.radiohost.de/radioton-owb_mp3-192?_art=dD0xNzE4Nzk0NzI3JmQ9M2EwMjM1YzA5MGI0MTNmMzRkYzA'}]}, 'canResume': True, 'canPause': True, 'canPrev': False, 'canNext': False, 'loopStatus': 'None', 'shuffleStatus': False, 'media': {'uri': 'http://radioton-stream29.radiohost.de/radioton-owb_mp3-192?_art=dD0xNzE4Nzk0NzI3JmQ9M2EwMjM1YzA5MGI0MTNmMzRkYzA', 'title': 'Radio Ton - Ostwürttemberg', 'artist': 'Radio Ton - Ostwürttemberg', 'match_confidence': 100, 'skill_id': 'skill-ovos-pyradios.femelo', 'playback': <PlaybackType.AUDIO_SERVICE: 3>, 'status': 1, 'media_type': 7, 'length': 0, 'image': 'https://www.radioton.de/storage/thumbs/192x192/r:1693564697/95010.png', 'skill_icon': '/home/ovos/.venv/lib/python3.11/site-packages/ovos_skill_pyradios/res/radio-tuner-small.png', 'javascript': '', 'position': 0, 'phrase': None, 'bg_image': '/home/ovos/.venv/lib/python3.11/site-packages/ovos_plugin_common_play/ocp/res/ui/images/ocp_bg.png', 'is_cps': False, 'data': {}, 'cps_data': {}, 'duration': 0, 'track': 'Radio Ton - Ostwürttemberg', 'album': 'skill-ovos-pyradios.femelo', 'source': '/home/ovos/.venv/lib/python3.11/site-packages/ovos_skill_pyradios/res/radio-tuner-small.png'}, 'uri': 'http://radioton-stream29.radiohost.de/radioton-owb_mp3-192?_art=dD0xNzE4Nzk0NzI3JmQ9M2EwMjM1YzA5MGI0MTNmMzRkYzA', 'title': 'Radio Ton - Ostwürttemberg', 'image': 'https://www.radioton.de/storage/thumbs/192x192/r:1693564697/95010.png', 'artist': 'Radio Ton - Ostwürttemberg', 'bg_image': '/home/ovos/.venv/lib/python3.11/site-packages/ovos_plugin_common_play/ocp/res/ui/images/ocp_bg.png', 'duration': 0, 'position': 0, 'javascript': '', 'javascriptCanOpenWindows': False, 'allowUrlChange': False, 'playlistModel': {'data': [{'duration': 0, 'track': 'Radio Ton - Ostwürttemberg', 'image': 'https://www.radioton.de/storage/thumbs/192x192/r:1693564697/95010.png', 'album': 'skill-ovos-pyradios.femelo', 'source': '/home/ovos/.venv/lib/python3.11/site-packages/ovos_skill_pyradios/res/radio-tuner-small.png', 'uri': 'http://radioton-stream29.radiohost.de/radioton-owb_mp3-192?_art=dD0xNzE4Nzk0NzI3JmQ9M2EwMjM1YzA5MGI0MTNmMzRkYzA'}]}, 'stream': 'http://radioton-stream29.radiohost.de/radioton-owb_mp3-192?_art=dD0xNzE4Nzk0NzI3JmQ9M2EwMjM1YzA5MGI0MTNmMzRkYzA', 'status': 'Stopped', 'app_view_timeout_enabled': False, 'app_view_timeout': 30, 'app_view_timeout_mode': 'all', 'playerBackend': 'OVOSSyncPlayer', 'displaySuggestionBar': True, 'length': 0, '__from': 'ovos.common_play'}
2024-07-06 17:14:21.034 - audio - ovos_plugin_common_play.ocp.player:play:405 - DEBUG - Requesting playback: <PlaybackType.AUDIO_SERVICE: 3>
2024-07-06 17:14:21.035 - audio - ovos_plugin_common_play.ocp.player:play:411 - DEBUG - Handling playback via audio_service
2024-07-06 17:14:21.043 - audio - ovos_plugin_common_play.ocp.player:play:462 - DEBUG - self.active_backend=<PlaybackType.AUDIO_SERVICE: 3>
2024-07-06 17:14:21.059 - audio - ovos_plugin_common_play.ocp.media:handle_track_state_change:579 - INFO - TrackState changed: <TrackState.PLAYING_AUDIOSERVICE: 21>
2024-07-06 17:14:21.059 - audio - ovos_audio.audio:_play:438 - DEBUG - vlc would be preferred
2024-07-06 17:14:21.063 - audio - ovos_plugin_manager.templates.audio:load_track:340 - DEBUG - queuing for OVOSVlcService playback: http://radioton-stream29.radiohost.de/radioton-owb_mp3-192?_art=dD0xNzE4Nzk0NzI3JmQ9M2EwMjM1YzA5MGI0MTNmMzRkYzA
2024-07-06 17:14:21.063 - audio - ovos_plugin_vlc:play:87 - DEBUG - VLCService Play
2024-07-06 17:14:21.122 - audio - ovos_plugin_common_play.ocp.player:handle_player_media_update:721 - DEBUG - backend=<PlaybackType.AUDIO_SERVICE: 3>|msg_type=ovos.common_play.media.state
2024-07-06 17:14:21.126 - audio - ovos_plugin_common_play.ocp.media:handle_track_state_change:579 - INFO - TrackState changed: <TrackState.QUEUED_AUDIOSERVICE: 31>
2024-07-06 17:14:21.127 - audio - ovos_plugin_common_play.ocp.media:handle_track_state_change:579 - INFO - TrackState changed: <TrackState.PLAYING_AUDIOSERVICE: 21>
2024-07-06 17:14:21.127 - audio - ovos_plugin_common_play.ocp.player:handle_player_media_update:733 - DEBUG - MediaState changed: <MediaState.LOADING_MEDIA: 2>
2024-07-06 17:14:21.130 - audio - ovos_plugin_common_play.ocp.player:handle_player_media_update:721 - DEBUG - backend=<PlaybackType.AUDIO_SERVICE: 3>|msg_type=ovos.common_play.media.state
2024-07-06 17:14:21.131 - audio - ovos_plugin_common_play.ocp.player:handle_player_media_update:733 - DEBUG - MediaState changed: <MediaState.LOADED_MEDIA: 3>
2024-07-06 17:14:22.019 - audio - ovos_plugin_vlc:track_start:60 - DEBUG - VLC playback start
2024-07-06 17:14:22.019 - audio - ovos_audio.audio:track_start:210 - DEBUG - New track coming up!
2024-07-06 17:14:22.075 - audio - ovos_plugin_common_play.ocp.player:handle_player_media_update:721 - DEBUG - backend=<PlaybackType.AUDIO_SERVICE: 3>|msg_type=ovos.common_play.media.state