Get Stuck in Troubleshooting #347

armyja · 2024-10-18T12:13:12Z

armyja
Oct 18, 2024

I tried to check the log after executing make run-server in ten_agent_dev contianer.
I cannot find the reason why the chat item is not showing in the browser.
It seems that onAudioTrackPublishSuccess means the audio has been uploaded.

root@dd4c2f96b83f:/app# make run-server
>> run server
server/bin/api
[GIN-debug] [WARNING] Creating an Engine instance with the Logger and Recovery middleware already attached.

[GIN-debug] [WARNING] Running in "debug" mode. Switch to "release" mode in production.
 - using env:   export GIN_MODE=release
 - using code:  gin.SetMode(gin.ReleaseMode)

[GIN-debug] GET    /                         --> app/internal.(*HttpServer).handlerHealth-fm (4 handlers)
[GIN-debug] GET    /health                   --> app/internal.(*HttpServer).handlerHealth-fm (4 handlers)
[GIN-debug] GET    /list                     --> app/internal.(*HttpServer).handlerList-fm (4 handlers)
[GIN-debug] POST   /start                    --> app/internal.(*HttpServer).handlerStart-fm (4 handlers)
[GIN-debug] POST   /stop                     --> app/internal.(*HttpServer).handlerStop-fm (4 handlers)
[GIN-debug] POST   /ping                     --> app/internal.(*HttpServer).handlerPing-fm (4 handlers)
[GIN-debug] POST   /token/generate           --> app/internal.(*HttpServer).handlerGenerateToken-fm (4 handlers)
[GIN-debug] GET    /vector/document/preset/list --> app/internal.(*HttpServer).handlerVectorDocumentPresetList-fm (4 handlers)
[GIN-debug] POST   /vector/document/update   --> app/internal.(*HttpServer).handlerVectorDocumentUpdate-fm (4 handlers)
[GIN-debug] POST   /vector/document/upload   --> app/internal.(*HttpServer).handlerVectorDocumentUpload-fm (4 handlers)
2024/10/18 11:32:25 INFO server start port=8080 service=HTTP_SERVER
[GIN-debug] [WARNING] You trusted all proxies, this is NOT safe. We recommend you to set a value.
Please check https://pkg.go.dev/github.com/gin-gonic/gin#readme-don-t-trust-all-proxies for details.
[GIN-debug] Listening and serving HTTP on :8080
2024/10/18 11:32:25 INFO handlerPing start channelName=agora_o8rn3l requestId=9e3b898e-a4b4-4c11-a8e3-5528db3fb153 service=HTTP_SERVER
2024/10/18 11:32:25 ERROR handlerPing channel not existed channelName=agora_o8rn3l requestId=9e3b898e-a4b4-4c11-a8e3-5528db3fb153 service=HTTP_SERVER
[GIN] 2024/10/18 - 11:32:25 | 200 |     248.878µs |   10.58.148.161 | POST     "/ping"
2024/10/18 11:32:26 INFO handlerStop start req="{RequestId:f9a26bca-9878-494e-96d2-b566200cc49f ChannelName:agora_o8rn3l}" service=HTTP_SERVER
2024/10/18 11:32:26 ERROR handlerStop channel not existed channelName=agora_o8rn3l requestId=f9a26bca-9878-494e-96d2-b566200cc49f service=HTTP_SERVER
[GIN] 2024/10/18 - 11:32:26 | 200 |     132.232µs |   10.58.148.161 | POST     "/stop"
2024/10/18 11:32:27 INFO handlerStart start workersRunning=0 service=HTTP_SERVER
2024/10/18 11:32:27 INFO Worker start requestId=91ff4d09-45a2-4be9-b9d1-8af48d87a79e shell="cd /app/agents && /app/agents/bin/start --property /tmp/ten_agent/property-agora_o8rn3l-20241018_113227_000.json" service=HTTP_SERVER
2024/10/18 11:32:27 INFO Worker get pid requestId=91ff4d09-45a2-4be9-b9d1-8af48d87a79e shell="pgrep -P 89728" service=HTTP_SERVER
2024/10/18 11:32:27 INFO handlerStart end workersRunning=1 worker="&{ChannelName:agora_o8rn3l HttpServerPort:10001 LogFile:/tmp/ten_agent/app-agora_o8rn3l-20241018_113227_000.log Log2Stdout:true PropertyJsonFile:/tmp/ten_agent/property-agora_o8rn3l-20241018_113227_000.json Pid:89728 QuitTimeoutSeconds:60 CreateTs:1729251147 UpdateTs:1729251147}" requestId=91ff4d09-45a2-4be9-b9d1-8af48d87a79e service=HTTP_SERVER
[GIN] 2024/10/18 - 11:32:27 | 200 |    5.725744ms |      172.18.0.3 | POST     "/start"
[agora_o8rn3l] 2024/10/18 11:32:27 INFO interrupt_detector extension init extension=INTERRUPT_DETECTOR_EXTENSION
[agora_o8rn3l] 2024/10/18 11:32:27 ten OnInitDone
[agora_o8rn3l] 2024/10/18 11:32:27 INFO elevenlabs_tts extension init extension=ELEVENLABS_TTS_EXTENSION
[agora_o8rn3l] 2024/10/18 11:32:27 ten OnInitDone
[agora_o8rn3l] 2024/10/18 11:32:27 INFO init
[agora_o8rn3l] 2024/10/18 11:32:27 ten OnInitDone
[agora_o8rn3l] 2024/10/18 11:32:27.287183 ten OnInitDone
[agora_o8rn3l] 10-18 11:32:27.293 89730 89740 I TEN on_init@main.cc:105 py_init_addon_t::on_init
[agora_o8rn3l] 10-18 11:32:27.309 89730 89740 I TEN on_init@main.cc:137 python initialized, sys.path: /usr/lib/python310.zip:/usr/lib/python3.10:/usr/lib/python3.10/lib-dynload
[agora_o8rn3l]
[agora_o8rn3l] 10-18 11:32:27.361 89730 89740 I TEN ten_addon_register_extension@extension.c:194 Registered addon 'aliyun_analyticdb_vector_storage' as extension
[agora_o8rn3l] 2024-10-18 11:32:27,361 - aliyun_analyticdb_vector_storage - INFO - 89730 - [__init__.py:4] - aliyun_analyticdb_vector_storage extension loaded
[agora_o8rn3l] 10-18 11:32:27.362 89730 89740 I TEN ten_addon_register_extension@extension.c:194 Registered addon 'llama_index_chat_engine' as extension
[agora_o8rn3l] 2024-10-18 11:32:27,362 - llama_index_chat_engine - INFO - 89730 - [__init__.py:4] - llama_index_chat_engine extension loaded
[agora_o8rn3l] 10-18 11:32:27.383 89730 89740 I TEN ten_addon_register_extension@extension.c:194 Registered addon 'http_server_python' as extension
[agora_o8rn3l] 2024-10-18 11:32:27,383 - http_server_python - INFO - 89730 - [__init__.py:4] - http_server_python extension loaded
[agora_o8rn3l] 10-18 11:32:27.384 89730 89740 I TEN ten_addon_register_extension@extension.c:194 Registered addon 'qwen_llm_python' as extension
[agora_o8rn3l] 2024-10-18 11:32:27,384 - qwen_llm_python - INFO - 89730 - [__init__.py:4] - qwen_llm_python extension loaded
[agora_o8rn3l] 10-18 11:32:27.384 89730 89740 I TEN ten_addon_register_extension@extension.c:194 Registered addon 'elevenlabs_tts_python' as extension
[agora_o8rn3l] 2024-10-18 11:32:27,384 - elevenlabs_tts_python - INFO - 89730 - [__init__.py:6] - elevenlabs_tts_python extension loaded
[agora_o8rn3l] 10-18 11:32:27.385 89730 89740 I TEN ten_addon_register_extension@extension.c:194 Registered addon 'aliyun_text_embedding' as extension
[agora_o8rn3l] 2024-10-18 11:32:27,385 - aliyun_text_embedding - INFO - 89730 - [__init__.py:4] - aliyun_text_embedding extension loaded
[agora_o8rn3l] 10-18 11:32:27.386 89730 89740 I TEN ten_addon_register_extension@extension.c:194 Registered addon 'cosy_tts' as extension
[agora_o8rn3l] 10-18 11:32:27.516 89730 89740 I TEN ten_addon_register_extension@extension.c:194 Registered addon 'openai_v2v_python' as extension
[agora_o8rn3l] 2024-10-18 11:32:27,516 - openai_v2v_python - INFO - 89730 - [__init__.py:11] - openai_v2v_python extension loaded
[agora_o8rn3l] 10-18 11:32:27.517 89730 89740 I TEN ten_addon_register_extension@extension.c:194 Registered addon 'message_collector' as extension
[agora_o8rn3l] 2024-10-18 11:32:27,517 - message_collector - INFO - 89730 - [__init__.py:11] - message_collector extension loaded
[agora_o8rn3l] 10-18 11:32:27.625 89730 89740 I TEN ten_addon_register_extension@extension.c:194 Registered addon 'polly_tts' as extension
[agora_o8rn3l] 2024-10-18 11:32:27,625 - polly_tts - INFO - 89730 - [__init__.py:5] - polly_tts extension loaded
[agora_o8rn3l] 10-18 11:32:27.695 89730 89740 I TEN ten_addon_register_extension@extension.c:194 Registered addon 'weatherapi_tool_python' as extension
[agora_o8rn3l] 2024-10-18 11:32:27,695 - weatherapi_tool_python - INFO - 89730 - [__init__.py:11] - weatherapi_tool_python extension loaded
[agora_o8rn3l] 10-18 11:32:27.696 89730 89740 I TEN ten_addon_register_extension@extension.c:194 Registered addon 'interrupt_detector_python' as extension
[agora_o8rn3l] 2024-10-18 11:32:27,696 - interrupt_detector_python - INFO - 89730 - [__init__.py:4] - interrupt_detector_python extension loaded
[agora_o8rn3l] 10-18 11:32:27.703 89730 89740 I TEN ten_addon_register_extension@extension.c:194 Registered addon 'transcribe_asr_python' as extension
[agora_o8rn3l] 2024-10-18 11:32:27,704 - transcribe_asr_python - INFO - 89730 - [__init__.py:5] - transcribe_asr_python extension loaded
[agora_o8rn3l] 10-18 11:32:27.705 89730 89740 I TEN ten_addon_register_extension@extension.c:194 Registered addon 'gemini_llm_python' as extension
[agora_o8rn3l] 2024-10-18 11:32:27,705 - gemini_llm_python - INFO - 89730 - [__init__.py:6] - gemini_llm_python extension loaded
[agora_o8rn3l] 10-18 11:32:27.706 89730 89740 I TEN ten_addon_register_extension@extension.c:194 Registered addon 'bingsearch_tool_python' as extension
[agora_o8rn3l] 2024-10-18 11:32:27,706 - bingsearch_tool_python - INFO - 89730 - [__init__.py:11] - bingsearch_tool_python extension loaded
[agora_o8rn3l] 10-18 11:32:28.226 89730 89740 I TEN ten_addon_register_extension@extension.c:194 Registered addon 'openai_chatgpt_python' as extension
[agora_o8rn3l] 2024-10-18 11:32:28,226 - openai_chatgpt_python - INFO - 89730 - [__init__.py:11] - openai_chatgpt_python extension loaded
[agora_o8rn3l] 10-18 11:32:28.227 89730 89740 I TEN ten_addon_register_extension@extension.c:194 Registered addon 'chat_transcriber_python' as extension
[agora_o8rn3l] 2024-10-18 11:32:28,227 - chat_transcriber_python - INFO - 89730 - [__init__.py:4] - chat_transcriber_python extension loaded
[agora_o8rn3l] 10-18 11:32:28.228 89730 89740 I TEN ten_addon_register_extension@extension.c:194 Registered addon 'bedrock_llm_python' as extension
[agora_o8rn3l] 2024-10-18 11:32:28,228 - bedrock_llm_python - INFO - 89730 - [__init__.py:4] - bedrock_llm_python extension loaded
[agora_o8rn3l] 10-18 11:32:28.229 89730 89740 I TEN ten_addon_register_extension@extension.c:194 Registered addon 'file_chunker' as extension
[agora_o8rn3l] 2024-10-18 11:32:28,229 - file_chunker - INFO - 89730 - [__init__.py:4] - file_chunker extension loaded
[agora_o8rn3l] 10-18 11:32:28.231 89730 89740 I TEN ten_addon_register_extension@extension.c:194 Registered addon 'fashionai' as extension
[agora_o8rn3l] 2024-10-18 11:32:28,231 - fashionai - INFO - 89730 - [__init__.py:11] - fashionai extension loaded
[agora_o8rn3l] 10-18 11:32:28.231 89730 89740 I TEN ten_addon_register_extension@extension.c:194 Registered addon 'py_init_extension_cpp' as extension
[agora_o8rn3l] 10-18 11:32:28.231 89730 89740 I TEN load_all_dynamic_libraries_under_path@addon_autoload.c:95 Loaded module: /app/agents/ten_packages/extension/py_init_extension_cpp/lib/libpy_init_extension_cpp.so
[agora_o8rn3l] 10-18 11:32:28.232 89730 89740 I TEN ten_addon_register_extension@extension.c:194 Registered addon 'azure_tts' as extension
[agora_o8rn3l] 10-18 11:32:28.232 89730 89740 I TEN load_all_dynamic_libraries_under_path@addon_autoload.c:95 Loaded module: /app/agents/ten_packages/extension/azure_tts/lib/libazure_tts.so
[agora_o8rn3l] 10-18 11:32:28.236 89730 89740 I TEN load_all_dynamic_libraries_under_path@addon_autoload.c:95 Loaded module: /app/agents/ten_packages/extension/agora_rtc/lib/libagora_stt_ms_extension.so
[agora_o8rn3l] 10-18 11:32:28.236 89730 89740 I TEN ten_addon_register_extension@extension.c:194 Registered addon 'agora_rtc' as extension
[agora_o8rn3l] 10-18 11:32:28.236 89730 89740 I TEN load_all_dynamic_libraries_under_path@addon_autoload.c:95 Loaded module: /app/agents/ten_packages/extension/agora_rtc/lib/libagora_rtc.so
[agora_o8rn3l] 10-18 11:32:28.237 89730 89740 I TEN ten_addon_register_extension_group@extension_group.c:43 Registered addon 'default_extension_group' as extension group
[agora_o8rn3l] 10-18 11:32:28.237 89730 89740 I TEN load_all_dynamic_libraries_under_path@addon_autoload.c:95 Loaded module: /app/agents/ten_packages/extension_group/default_extension_group/lib/libdefault_extension_group.so
[agora_o8rn3l] 2024-10-18 11:32:28,242 - message_collector - INFO - 89730 - [addon.py:21] - MessageCollectorExtensionAddon on_create_instance
[agora_o8rn3l] 2024/10/18 11:32:28.242461 ten OnInitDone
[agora_o8rn3l] 2024/10/18 11:32:28.242461 ten OnInitDone
[agora_o8rn3l] 2024-10-18 11:32:28,242 - message_collector - INFO - 89730 - [extension.py:39] - MessageCollectorExtension on_init
[agora_o8rn3l] 10-18 11:32:28.246 89730 89777 I TEN ten_extension_handle_ten_namespace_properties@metadata.c:322 [message_collector] `_ten` section is not found in the property, skip.
[agora_o8rn3l] 10-18 11:32:28.246 89730 89775 I TEN ten_extension_handle_ten_namespace_properties@metadata.c:322 [azure_tts] `_ten` section is not found in the property, skip.
[agora_o8rn3l] 10-18 11:32:28.247 89730 89773 I TEN ten_extension_handle_ten_namespace_properties@metadata.c:322 [openai_chatgpt] `_ten` section is not found in the property, skip.
[agora_o8rn3l] 10-18 11:32:28.249 89730 89772 I TEN ten_extension_handle_ten_namespace_properties@metadata.c:322 [agora_rtc] `_ten` section is not found in the property, skip.
[agora_o8rn3l] 10-18 11:32:28.254 89730 89772 I TEN ten_extension_handle_ten_namespace_properties@metadata.c:322 [interrupt_detector] `_ten` section is not found in the property, skip.
[agora_o8rn3l] 10-18 11:32:28.254 89730 89772 I TEN ten_extension_on_start@extension.c:900 [agora_rtc] on_start().
[agora_o8rn3l] 10-18 11:32:28.254 89730 89775 I TEN ten_extension_on_start@extension.c:900 [azure_tts] on_start().
[agora_o8rn3l] 10-18 11:32:28.254 89730 89773 I TEN ten_extension_on_start@extension.c:900 [openai_chatgpt] on_start().
[agora_o8rn3l] 10-18 11:32:28.254 89730 89775 I AZURE_TTS_EXTENSION on_start@main.cc:32 start
[agora_o8rn3l] 10-18 11:32:28.254 89730 89777 I TEN ten_extension_on_start@extension.c:900 [message_collector] on_start().
[agora_o8rn3l] 10-18 11:32:28.254 89730 89772 I AGORA_RTC_EXTENSION InitProperties@predefined_props.cc:126 init from property, agora_asr_session_control_file_path=session_control.conf
[agora_o8rn3l] 10-18 11:32:28.254 89730 89772 I AGORA_RTC_EXTENSION InitProperties@predefined_props.cc:126 init from property, app_id=xxx
[agora_o8rn3l] 10-18 11:32:28.254 89730 89772 I AGORA_RTC_EXTENSION InitProperties@predefined_props.cc:126 init from property, token=xxxx==
[agora_o8rn3l] 10-18 11:32:28.254 89730 89772 I AGORA_RTC_EXTENSION InitProperties@predefined_props.cc:126 init from property, encryption_salt=
[agora_o8rn3l] 10-18 11:32:28.254 89730 89772 I AGORA_RTC_EXTENSION InitProperties@predefined_props.cc:126 init from property, channel=agora_o8rn3l
[agora_o8rn3l] 10-18 11:32:28.254 89730 89772 I AGORA_RTC_EXTENSION InitProperties@predefined_props.cc:126 init from property, subscribe_audio_frame_source=on_playback_before_mixing
[agora_o8rn3l] 10-18 11:32:28.254 89730 89772 I AGORA_RTC_EXTENSION InitProperties@predefined_props.cc:126 init from property, agora_asr_vendor_name=microsoft
[agora_o8rn3l] 10-18 11:32:28.254 89730 89772 I AGORA_RTC_EXTENSION InitProperties@predefined_props.cc:126 init from property, agora_asr_language=zh-CN
[agora_o8rn3l] 2024/10/18 11:32:28.254326 INFO OnStart extension=OPENAI_CHATGPT_EXTENSION
[agora_o8rn3l] 10-18 11:32:28.254 89730 89772 I AGORA_RTC_EXTENSION InitProperties@predefined_props.cc:126 init from property, agora_asr_vendor_key=xxx
[agora_o8rn3l] 10-18 11:32:28.254 89730 89772 I AGORA_RTC_EXTENSION InitProperties@predefined_props.cc:126 init from property, agora_asr_vendor_region=eastasia
[agora_o8rn3l] 10-18 11:32:28.254 89730 89772 I AGORA_RTC_EXTENSION InitProperties@predefined_props.cc:126 init from property, encryption_key=
[agora_o8rn3l] 10-18 11:32:28.254 89730 89772 I AGORA_RTC_EXTENSION InitProperties@predefined_props.cc:134 init from property, enable_agora_asr=1
[agora_o8rn3l] 10-18 11:32:28.254 89730 89772 I AGORA_RTC_EXTENSION InitProperties@predefined_props.cc:134 init from property, publish_data=1
[agora_o8rn3l] 10-18 11:32:28.254 89730 89772 I AGORA_RTC_EXTENSION InitProperties@predefined_props.cc:134 init from property, publish_audio=1
[agora_o8rn3l] 10-18 11:32:28.254 89730 89772 I AGORA_RTC_EXTENSION InitProperties@predefined_props.cc:134 init from property, subscribe_audio=1
[agora_o8rn3l] 10-18 11:32:28.254 89730 89772 I AGORA_RTC_EXTENSION InitProperties@predefined_props.cc:141 init from property, encryption_mode=0
[agora_o8rn3l] 10-18 11:32:28.254 89730 89772 I AGORA_RTC_EXTENSION InitProperties@predefined_props.cc:141 init from property, area_code=-1
[agora_o8rn3l] 10-18 11:32:28.254 89730 89772 I AGORA_RTC_EXTENSION InitProperties@predefined_props.cc:141 init from property, subscribe_audio_num_of_channels=1
[agora_o8rn3l] 10-18 11:32:28.254 89730 89772 I AGORA_RTC_EXTENSION InitProperties@predefined_props.cc:141 init from property, subscribe_audio_sample_rate=16000
[agora_o8rn3l] 10-18 11:32:28.254 89730 89772 I AGORA_RTC_EXTENSION InitProperties@predefined_props.cc:148 init from property, remote_stream_id=172000
[agora_o8rn3l] 2024-10-18 11:32:28,254 - message_collector - INFO - 89730 - [extension.py:43] - MessageCollectorExtension on_start
[agora_o8rn3l] 10-18 11:32:28.254 89730 89772 I AGORA_RTC_EXTENSION InitProperties@predefined_props.cc:148 init from property, stream_id=1234
[agora_o8rn3l] 10-18 11:32:28.254 89730 89777 I TEN ten_extension_on_start_done@on_xxx.c:71 [message_collector] on_start() done.
[agora_o8rn3l] 2024/10/18 11:32:28.254463 WARN GetProperty optional presence_penalty failed, err: err_no: 1, err_msg:  extension=OPENAI_CHATGPT_EXTENSION
[agora_o8rn3l] 2024/10/18 11:32:28.254478 WARN GetProperty optional temperature failed, err: err_no: 1, err_msg:  extension=OPENAI_CHATGPT_EXTENSION
[agora_o8rn3l] 10-18 11:32:28.254 89730 89772 I AGORA_RTC_EXTENSION on_start@rtc_extension.cc:27 {"agora_asr_language":"zh-CN","agora_asr_session_control_file_path":"session_control.conf","agora_asr_vendor_app_id":"","agora_asr_vendor_key":"xxx","agora_asr_vendor_name":"microsoft","agora_asr_vendor_region":"eastasia","agora_asr_vendor_secret":"","agora_asr_vendor_uri":"","app_id":"xxx","area_code":-1,"audio_scenario":0,"auto_join":true,"channel":"agora_o8rn3l","enable_agora_asr":true,"enable_string_uid":false,"encryption_key":"","encryption_mode":0,"encryption_salt":"","publish_audio":true,"publish_audio_auto_publishing":true,"publish_data":true,"publish_video":false,"publish_video_auto_publishing":true,"publish_video_encoded":false,"publish_video_frame_rate":15,"publish_video_height":480,"publish_video_width":640,"remote_stream_id":172000,"remote_user_id":"","sdk_log_file_path":"","sdk_params":"","sid":"","stream_id":1234,"subscribe_audio":true,"subscribe_audio_frame_source":"on_playback_before_mixing","subscribe_audio_num_of_channels":1,"subscribe_audio_sample_rate":16000,"subscribe_audio_samples_per_frame":0,"subscribe_video":false,"subscribe_video_encoded":false,"subscribe_video_pix_fmt":0,"token":"xxx==","user_id":""}
[agora_o8rn3l] 2024/10/18 11:32:28.254487 WARN GetProperty optional top_p failed, err: err_no: 1, err_msg:  extension=OPENAI_CHATGPT_EXTENSION
[agora_o8rn3l] 2024/10/18 11:32:28.254525 INFO newOpenaiChatGPT succeed with max_tokens: 512, model: gpt-4o-mini extension=OPENAI_CHATGPT_EXTENSION
[agora_o8rn3l] 2024/10/18 11:32:28.254615 INFO greeting [TEN Agent connected. How can I help you today?] sent extension=OPENAI_CHATGPT_EXTENSION
[agora_o8rn3l] 10-18 11:32:28.254 89730 89773 I TEN ten_extension_on_start_done@on_xxx.c:71 [openai_chatgpt] on_start() done.
[agora_o8rn3l] 2024-10-18 11:32:28,254 - message_collector - INFO - 89730 - [extension.py:77] - on_data
[agora_o8rn3l] 10-18 11:32:28.262 89730 89775 I AZURE_TTS_EXTENSION Start@tts.cc:19 speech_synthesizer created
[agora_o8rn3l] 10-18 11:32:28.262 89730 89775 I AZURE_TTS_EXTENSION Start@tts.cc:24 speech_synthesizer opened
[agora_o8rn3l] 10-18 11:32:28.262 89730 89775 I TEN ten_extension_on_start_done@on_xxx.c:71 [azure_tts] on_start() done.
[agora_o8rn3l] 10-18 11:32:28.262 89730 89775 I AZURE_TTS_EXTENSION on_start@main.cc:95 start done
[agora_o8rn3l] 10-18 11:32:28.262 89730 89797 I AZURE_TTS_EXTENSION operator()@tts.cc:32 tts_thread started
[agora_o8rn3l] 10-18 11:32:28.262 89730 89775 I AZURE_TTS_EXTENSION on_data@main.cc:135 input text: [TEN Agent connected. How can I help you today?]
[agora_o8rn3l] 10-18 11:32:28.269 89730 89772 I AGORA_RTC_EXTENSION auto_join_channel@rtc_service.cc:250 role_type is 1
[agora_o8rn3l] 10-18 11:32:28.274 89730 89772 I AGORA_RTC_EXTENSION prepare_to_send@rtc_connection.cc:832 audio track published
[agora_o8rn3l] 10-18 11:32:28.274 89730 89772 I AGORA_RTC_EXTENSION prepare_to_send@rtc_connection.cc:851 data_stream_id 49153 created
[agora_o8rn3l] 10-18 11:32:28.274 89730 89772 I AGORA_RTC_EXTENSION connect@rtc_connection.cc:322 Connect to Agora channel(agora_o8rn3l) with user id(1234), token(xxx/KLE+HyLojzjHAYGQAAAAP//+8IkPg==)
[agora_o8rn3l] 10-18 11:32:28.275 89730 89772 I TEN ten_extension_on_start@extension.c:900 [interrupt_detector] on_start().
[agora_o8rn3l] 10-18 11:32:28.275 89730 89772 I TEN ten_extension_on_start_done@on_xxx.c:71 [interrupt_detector] on_start() done.
[agora_o8rn3l] 10-18 11:32:28.275 89730 89783 I AGORA_RTC_EXTENSION onConnecting@connection_observer.cc:130 onConnecting: id 0, channelId agora_o8rn3l, localUserId 1234, state 2, reason 0
[agora_o8rn3l]
[agora_o8rn3l] [VSC](0x7fee081ac210) AudioFilterVirtualSoundCard: name virtual_soundcard_pcm
[agora_o8rn3l] [VSC-GAIN](0x7fee081aa6a0) VscGainApplier
[agora_o8rn3l] [VSC-MIC](0x7fee081aa640) VscMicEqTuner
[agora_o8rn3l] [VSC-ENHANCER](0x7fee081aa350) VscVoiceEnhancer
[agora_o8rn3l] [VSC-EFFECT](0x7fee081abe50) VscAudioEffect
[agora_o8rn3l] [VSC](0x7fee081ac210) Initialize
[agora_o8rn3l] [VSC-GAIN](0x7fee081aa6a0) Initialize: gain -1.000000
[agora_o8rn3l] [VSC-MIC](0x7fee081aa640) Initialize: preset -1
[agora_o8rn3l] [VSC-ENHANCER](0x7fee081aa350) Initialize: gender -1
[agora_o8rn3l] [VSC-EFFECT](0x7fee081abe50) Initialize: gender -1, effect -1
2024/10/18 11:32:30 INFO handlerPing start channelName=agora_o8rn3l requestId=a8685cda-09fc-494c-9d6c-614ef34a8e53 service=HTTP_SERVER
2024/10/18 11:32:30 INFO handlerPing end worker="&{ChannelName:agora_o8rn3l HttpServerPort:10001 LogFile:/tmp/ten_agent/app-agora_o8rn3l-20241018_113227_000.log Log2Stdout:true PropertyJsonFile:/tmp/ten_agent/property-agora_o8rn3l-20241018_113227_000.json Pid:89728 QuitTimeoutSeconds:60 CreateTs:1729251147 UpdateTs:1729251150}" requestId=a8685cda-09fc-494c-9d6c-614ef34a8e53 service=HTTP_SERVER
[GIN] 2024/10/18 - 11:32:30 | 200 |     169.448µs |   10.58.148.161 | POST     "/ping"
[agora_o8rn3l] 10-18 11:32:30.393 89730 89797 I AZURE_TTS_EXTENSION SpeechText@tts.cc:182 task finished for text: [TEN Agent connected. How can I help you today?], text_recv_ts: 1729251148262853, read_bytes: 136000, sent_frames: 425, first_frame_latency: 2035ms, finish_latency: 2130ms, synthesis_first_byte_latency: 2035ms, synthesis_finish_latency: 2130ms
[agora_o8rn3l] 10-18 11:32:30.831 89730 89783 I AGORA_RTC_EXTENSION onConnected@connection_observer.cc:66 onConnected: id 0, channelId agora_o8rn3l, localUserId 1234, state 3, reason 1
[agora_o8rn3l]
[agora_o8rn3l] 10-18 11:32:30.831 89730 89772 I AGORA_RTC_EXTENSION operator()@rtc_extension.cc:36 join connect callback, connected 1
[agora_o8rn3l] 10-18 11:32:30.831 89730 89772 I TEN ten_extension_on_start_done@on_xxx.c:71 [agora_rtc] on_start() done.
[agora_o8rn3l] 10-18 11:32:30.831 89730 89783 I AGORA_RTC_EXTENSION onAudioTrackPublishSuccess@local_user_observer.cc:81 onAudioTrackPublishSuccess
2024/10/18 11:32:33 INFO handlerPing start channelName=agora_o8rn3l requestId=bc6fa80f-7974-4fa8-a13f-83df683a4b57 service=HTTP_SERVER
2024/10/18 11:32:33 INFO handlerPing end worker="&{ChannelName:agora_o8rn3l HttpServerPort:10001 LogFile:/tmp/ten_agent/app-agora_o8rn3l-20241018_113227_000.log Log2Stdout:true PropertyJsonFile:/tmp/ten_agent/property-agora_o8rn3l-20241018_113227_000.json Pid:89728 QuitTimeoutSeconds:60 CreateTs:1729251147 UpdateTs:1729251153}" requestId=bc6fa80f-7974-4fa8-a13f-83df683a4b57 service=HTTP_SERVER
[GIN] 2024/10/18 - 11:32:33 | 200 |     138.332µs |   10.58.148.161 | POST     "/ping"
[agora_o8rn3l] 10-18 11:32:35.331 89730 89783 I AGORA_RTC_EXTENSION onReconnecting@connection_observer.cc:140 onReconnecting: id 0, channelId agora_o8rn3l, localUserId 1234, state 4, reason 14
[agora_o8rn3l]
[agora_o8rn3l] 10-18 11:32:35.490 89730 89783 I AGORA_RTC_EXTENSION onReconnected@connection_observer.cc:150 onReconnected: id 0, channelId agora_o8rn3l, localUserId 1234, state 3, reason 15
[agora_o8rn3l]
[agora_o8rn3l] 10-18 11:32:35.502 89730 89783 I AGORA_RTC_EXTENSION onUserJoined@connection_observer.cc:220 onUserJoined: userId 172000
[agora_o8rn3l]
[agora_o8rn3l] 10-18 11:32:35.503 89730 89783 I AGORA_RTC_EXTENSION onUserAudioTrackSubscribed@local_user_observer.cc:87 onUserAudioTrackSubscribed, useId 172000
[agora_o8rn3l] [SttMs] construct
[agora_o8rn3l] SttClientMs::setProperty, key:start, value:{"enable_auto_detect":true,"region":"eastasia","segmentation_silence_timeout":100,"source_languages":"zh-CN","subscription":"xxx"}
[agora_o8rn3l] 10-18 11:32:35.506 89730 89783 I AGORA_RTC_EXTENSION onEventWithContext@asr_extension_observer.cc:23 onEventWithContext uid: 172000, key:started
[agora_o8rn3l] 10-18 11:32:35.506 89730 89783 I AGORA_RTC_EXTENSION handle_stt_started@rtc_service.cc:509 uid 172000
[agora_o8rn3l] 10-18 11:32:35.670 89730 89783 I AGORA_RTC_EXTENSION onFirstRemoteAudioFrame@local_user_observer.cc:419 onFirstRemoteAudioFrame: userId 172000, elapsed 7229
[agora_o8rn3l] 10-18 11:32:35.670 89730 89783 I AGORA_RTC_EXTENSION onUserAudioTrackStateChanged@local_user_observer.cc:107 onUserAudioTrackStateChanged: userId 172000, state 1, reason 6, elapsed 7229
[agora_o8rn3l] 10-18 11:32:35.670 89730 89783 I AGORA_RTC_EXTENSION onFirstRemoteAudioDecoded@local_user_observer.cc:430 onFirstRemoteAudioDecoded: userId 172000, elapsed 7231
[agora_o8rn3l] 10-18 11:32:35.670 89730 89783 I AGORA_RTC_EXTENSION onUserAudioTrackStateChanged@local_user_observer.cc:107 onUserAudioTrackStateChanged: userId 172000, state 2, reason 6, elapsed 7231
[agora_o8rn3l] [SttMs] OnSessionStarted
2024/10/18 11:32:36 INFO handlerPing start channelName=agora_o8rn3l requestId=8a4e2269-ee9f-4e60-8c86-9c0083983738 service=HTTP_SERVER
2024/10/18 11:32:36 INFO handlerPing end worker="&{ChannelName:agora_o8rn3l HttpServerPort:10001 LogFile:/tmp/ten_agent/app-agora_o8rn3l-20241018_113227_000.log Log2Stdout:true PropertyJsonFile:/tmp/ten_agent/property-agora_o8rn3l-20241018_113227_000.json Pid:89728 QuitTimeoutSeconds:60 CreateTs:1729251147 UpdateTs:1729251156}" requestId=8a4e2269-ee9f-4e60-8c86-9c0083983738 service=HTTP_SERVER
[GIN] 2024/10/18 - 11:32:36 | 200 |      192.76µs |   10.58.148.161 | POST     "/ping"
[agora_o8rn3l] 10-18 11:32:37.870 89730 89783 I AGORA_RTC_EXTENSION onUserAudioTrackStateChanged@local_user_observer.cc:107 onUserAudioTrackStateChanged: userId 172000, state 3, reason 1, elapsed 9396
2024/10/18 11:32:39 INFO handlerPing start channelName=agora_o8rn3l requestId=89ab3ee5-3603-411e-8396-c8f2d53dbdfb service=HTTP_SERVER
2024/10/18 11:32:39 INFO handlerPing end worker="&{ChannelName:agora_o8rn3l HttpServerPort:10001 LogFile:/tmp/ten_agent/app-agora_o8rn3l-20241018_113227_000.log Log2Stdout:true PropertyJsonFile:/tmp/ten_agent/property-agora_o8rn3l-20241018_113227_000.json Pid:89728 QuitTimeoutSeconds:60 CreateTs:1729251147 UpdateTs:1729251159}" requestId=89ab3ee5-3603-411e-8396-c8f2d53dbdfb service=HTTP_SERVER
[GIN] 2024/10/18 - 11:32:39 | 200 |     140.922µs |   10.58.148.161 | POST     "/ping"
[agora_o8rn3l] 10-18 11:32:39.990 89730 89783 I AGORA_RTC_EXTENSION onReconnecting@connection_observer.cc:140 onReconnecting: id 0, channelId agora_o8rn3l, localUserId 1234, state 4, reason 14
[agora_o8rn3l]
[agora_o8rn3l] 10-18 11:32:40.151 89730 89783 I AGORA_RTC_EXTENSION onReconnected@connection_observer.cc:150 onReconnected: id 0, channelId agora_o8rn3l, localUserId 1234, state 3, reason 15
[agora_o8rn3l]
2024/10/18 11:32:42 INFO handlerPing start channelName=agora_o8rn3l requestId=d8430c25-4503-4baa-9645-6678600df177 service=HTTP_SERVER
2024/10/18 11:32:42 INFO handlerPing end worker="&{ChannelName:agora_o8rn3l HttpServerPort:10001 LogFile:/tmp/ten_agent/app-agora_o8rn3l-20241018_113227_000.log Log2Stdout:true PropertyJsonFile:/tmp/ten_agent/property-agora_o8rn3l-20241018_113227_000.json Pid:89728 QuitTimeoutSeconds:60 CreateTs:1729251147 UpdateTs:1729251162}" requestId=d8430c25-4503-4baa-9645-6678600df177 service=HTTP_SERVER
[GIN] 2024/10/18 - 11:32:42 | 200 |     141.857µs |   10.58.148.161 | POST     "/ping"
[agora_o8rn3l] 10-18 11:32:44.651 89730 89783 I AGORA_RTC_EXTENSION onReconnecting@connection_observer.cc:140 onReconnecting: id 0, channelId agora_o8rn3l, localUserId 1234, state 4, reason 14
[agora_o8rn3l]
[agora_o8rn3l] 10-18 11:32:44.817 89730 89783 I AGORA_RTC_EXTENSION onReconnected@connection_observer.cc:150 onReconnected: id 0, channelId agora_o8rn3l, localUserId 1234, state 3, reason 15
[agora_o8rn3l]
2024/10/18 11:32:45 INFO handlerPing start channelName=agora_o8rn3l requestId=7febfa0f-8f24-473d-b776-849974d648ed service=HTTP_SERVER
2024/10/18 11:32:45 INFO handlerPing end worker="&{ChannelName:agora_o8rn3l HttpServerPort:10001 LogFile:/tmp/ten_agent/app-agora_o8rn3l-20241018_113227_000.log Log2Stdout:true PropertyJsonFile:/tmp/ten_agent/property-agora_o8rn3l-20241018_113227_000.json Pid:89728 QuitTimeoutSeconds:60 CreateTs:1729251147 UpdateTs:1729251165}" requestId=7febfa0f-8f24-473d-b776-849974d648ed service=HTTP_SERVER
[GIN] 2024/10/18 - 11:32:45 | 200 |     179.146µs |   10.58.148.161 | POST     "/ping"
[agora_o8rn3l] 10-18 11:32:47.071 89730 89783 I AGORA_RTC_EXTENSION onUserAudioTrackStateChanged@local_user_observer.cc:107 onUserAudioTrackStateChanged: userId 172000, state 3, reason 1, elapsed 18595
2024/10/18 11:32:48 INFO handlerPing start channelName=agora_o8rn3l requestId=281628f0-cf93-4ab4-b137-9a4ac94dd39f service=HTTP_SERVER
2024/10/18 11:32:48 INFO handlerPing end worker="&{ChannelName:agora_o8rn3l HttpServerPort:10001 LogFile:/tmp/ten_agent/app-agora_o8rn3l-20241018_113227_000.log Log2Stdout:true PropertyJsonFile:/tmp/ten_agent/property-agora_o8rn3l-20241018_113227_000.json Pid:89728 QuitTimeoutSeconds:60 CreateTs:1729251147 UpdateTs:1729251168}" requestId=281628f0-cf93-4ab4-b137-9a4ac94dd39f service=HTTP_SERVER
[GIN] 2024/10/18 - 11:32:48 | 200 |     127.432µs |   10.58.148.161 | POST     "/ping"
[agora_o8rn3l] 10-18 11:32:49.070 89730 89783 I AGORA_RTC_EXTENSION onUserAudioTrackStateChanged@local_user_observer.cc:107 onUserAudioTrackStateChanged: userId 172000, state 2, reason 2, elapsed 20596
[agora_o8rn3l] 10-18 11:32:49.317 89730 89783 I AGORA_RTC_EXTENSION onReconnecting@connection_observer.cc:140 onReconnecting: id 0, channelId agora_o8rn3l, localUserId 1234, state 4, reason 14
[agora_o8rn3l]
[agora_o8rn3l] 10-18 11:32:49.501 89730 89783 I AGORA_RTC_EXTENSION onReconnected@connection_observer.cc:150 onReconnected: id 0, channelId agora_o8rn3l, localUserId 1234, state 3, reason 15
[agora_o8rn3l]
2024/10/18 11:32:51 INFO handlerPing start channelName=agora_o8rn3l requestId=36ab6e01-4ab6-4004-95fd-b483015c6890 service=HTTP_SERVER
2024/10/18 11:32:51 INFO handlerPing end worker="&{ChannelName:agora_o8rn3l HttpServerPort:10001 LogFile:/tmp/ten_agent/app-agora_o8rn3l-20241018_113227_000.log Log2Stdout:true PropertyJsonFile:/tmp/ten_agent/property-agora_o8rn3l-20241018_113227_000.json Pid:89728 QuitTimeoutSeconds:60 CreateTs:1729251147 UpdateTs:1729251171}" requestId=36ab6e01-4ab6-4004-95fd-b483015c6890 service=HTTP_SERVER
[GIN] 2024/10/18 - 11:32:51 | 200 |     134.629µs |   10.58.148.161 | POST     "/ping"
[agora_o8rn3l] 10-18 11:32:54.001 89730 89783 I AGORA_RTC_EXTENSION onReconnecting@connection_observer.cc:140 onReconnecting: id 0, channelId agora_o8rn3l, localUserId 1234, state 4, reason 14
[agora_o8rn3l]
[agora_o8rn3l] 10-18 11:32:54.208 89730 89783 I AGORA_RTC_EXTENSION onReconnected@connection_observer.cc:150 onReconnected: id 0, channelId agora_o8rn3l, localUserId 1234, state 3, reason 15
[agora_o8rn3l]
2024/10/18 11:32:54 INFO handlerPing start channelName=agora_o8rn3l requestId=ba4fca02-12d9-40ac-a994-aa4b54955eed service=HTTP_SERVER
2024/10/18 11:32:54 INFO handlerPing end worker="&{ChannelName:agora_o8rn3l HttpServerPort:10001 LogFile:/tmp/ten_agent/app-agora_o8rn3l-20241018_113227_000.log Log2Stdout:true PropertyJsonFile:/tmp/ten_agent/property-agora_o8rn3l-20241018_113227_000.json Pid:89728 QuitTimeoutSeconds:60 CreateTs:1729251147 UpdateTs:1729251174}" requestId=ba4fca02-12d9-40ac-a994-aa4b54955eed service=HTTP_SERVER
[GIN] 2024/10/18 - 11:32:54 | 200 |     129.044µs |   10.58.148.161 | POST     "/ping"

armyja · 2024-10-24T01:07:39Z

armyja
Oct 24, 2024
Author

maybe network problem, use another network can solve it.

0 replies

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

Get Stuck in Troubleshooting #347

{{title}}

Replies: 1 comment

{{title}}

Select a reply

Get Stuck in Troubleshooting #347

armyja Oct 18, 2024

Replies: 1 comment

armyja Oct 24, 2024 Author

armyja
Oct 18, 2024

armyja
Oct 24, 2024
Author