2026-04-05 15:01:24.351 | INFO | src.core.logging:configure_logging:193 | ============================================================ 2026-04-05 15:01:24.351 | INFO | src.core.logging:configure_logging:194 | MAYA PIPECAT SERVER - STARTUP INITIATED 2026-04-05 15:01:24.351 | INFO | src.core.logging:configure_logging:195 | ============================================================ 2026-04-05 15:01:24.351 | INFO | src.core.logging:configure_logging:196 | STARTUP_CONFIG: env=development host=0.0.0.0 port=3006 2026-04-05 15:01:24.420 | INFO | pipecat::14 | ᓚᘏᗢ Pipecat 0.0.106 (Python 3.12.12 (main, Oct 9 2025, 11:07:00) [Clang 17.0.0 (clang-1700.0.13.3)]) ᓚᘏᗢ 2026-04-05 15:01:24.750 | WARNING | warnings:_showwarnmsg:112 | /Users/thippareddysaicharanreddy/Desktop/Prod_Livekit_Pipecat/venv/lib/python3.12/site-packages/pipecat/audio/utils.py:14: DeprecationWarning: 'audioop' is deprecated and slated for removal in Python 3.13 import audioop 2026-04-05 15:01:28.161 | WARNING | warnings:_showwarnmsg:112 | /Users/thippareddysaicharanreddy/Desktop/Prod_Livekit_Pipecat/src/bot/core/pipeline.py:13: DeprecationWarning: Module `pipecat.services.google` is deprecated, use `pipecat.services.google.[frames,image,llm,openai,vertex,rtvi,stt,tts]` instead. from pipecat.services.google.llm_vertex import GoogleVertexLLMService 2026-04-05 15:01:28.162 | WARNING | warnings:_showwarnmsg:112 | /Users/thippareddysaicharanreddy/Desktop/Prod_Livekit_Pipecat/src/bot/core/pipeline.py:13: DeprecationWarning: Module `pipecat.services.google.llm_vertex` is deprecated, use `pipecat.services.google.vertex.llm` instead. from pipecat.services.google.llm_vertex import GoogleVertexLLMService 2026-04-05 15:01:28.407 | WARNING | warnings:_showwarnmsg:112 | :488: DeprecationWarning: builtin type SwigPyPacked has no __module__ attribute 2026-04-05 15:01:28.407 | WARNING | warnings:_showwarnmsg:112 | :488: DeprecationWarning: builtin type SwigPyObject has no __module__ attribute 2026-04-05 15:01:28.438 | WARNING | warnings:_showwarnmsg:112 | :488: DeprecationWarning: builtin type swigvarlink has no __module__ attribute 2026-04-05 15:01:32.840 | INFO | src.service.device_service:save_notification_token:10 | NOTIFICATION_TOKEN_REQUEST: device_id=8ffb37ab9eefc78c 2026-04-05 15:01:32.864 | INFO | src.service.device_service:save_notification_token:13 | NOTIFICATION_TOKEN_SAVED: device_id=8ffb37ab9eefc78c success=true 2026-04-05 15:01:33.552 | WARNING | pipecat.pipeline.task:wait_for_cancel:760 | PipelineTask#1: timeout waiting for CancelFrame#1(reason: None) to reach the end of the pipeline (being blocked somewhere?). 2026-04-05 15:01:33.554 | WARNING | pipecat.pipeline.task:_print_dangling_tasks:1080 | Dangling tasks detected: ['SmallWebRTCInputTransport#1::_audio_task_handler', 'SmallWebRTCInputTransport#1::_receive_audio', 'GoogleUserContextAggregator#1::_aggregation_task_handler', 'RTVIProcessor#1::_action_task_handler', 'RTVIProcessor#1::_message_task_handler', 'CustomTTSService#1::_audio_context_task_handler', 'SmallWebRTCOutputTransport#1::_clock_task_handler', 'SmallWebRTCOutputTransport#1::_audio_task_handler'] 2026-04-05 15:01:33.555 | INFO | src.bot.core.main:run_bot_webrtc_global_agent:273 | CLEANUP_TRIGGER: session_id=3f14e952-b16e-423e-831d-4dbdc67c6621 transport=smallwebrtc reason=runner_finally threads=13 2026-04-05 15:01:33.555 | INFO | src.bot.core.main:run_bot_webrtc_global_agent:274 | RUNNER_CLEANUP_START: session_id=3f14e952-b16e-423e-831d-4dbdc67c6621 transport=smallwebrtc threads=13 2026-04-05 15:01:33.555 | INFO | src.bot.core.config:cleanup_session:420 | CLEANUP_START: session_id=3f14e952-b16e-423e-831d-4dbdc67c6621 threads=13 2026-04-05 15:01:33.555 | INFO | src.bot.core.config:cleanup_session:614 | CLEANUP_END: session_id=3f14e952-b16e-423e-831d-4dbdc67c6621 threads_at_start=13 threads_at_end=13 delta=0 2026-04-05 15:01:33.555 | INFO | src.bot.core.main:run_bot_webrtc_global_agent:292 | CLEANUP_THREADS: session_id=3f14e952-b16e-423e-831d-4dbdc67c6621 transport=smallwebrtc threads_before=13 threads_after=13 delta=0 2026-04-05 15:01:33.555 | INFO | src.bot.core.main:run_bot_webrtc_global_agent:307 | RUNNER_CLEANUP_COMPLETE: session_id=3f14e952-b16e-423e-831d-4dbdc67c6621 transport=smallwebrtc threads=13 2026-04-05 15:01:33.556 | INFO | src.routes.bot:cleanup_session:148 | TASK_COMPLETE: session=3f14e952-b16e-423e-831d-4dbdc67c6621 transport=smallwebrtc remaining_active_sessions=0 2026-04-05 15:01:34.537 | ERROR | src.core.redis:init_redis:66 | REDIS_CONNECT_FAILED: error=Timeout connecting to server error_type=TimeoutError 2026-04-05 15:01:34.539 | INFO | src.service.config_sync_service:start:44 | ConfigSyncManager started (redis=disabled) 2026-04-05 15:01:34.539 | INFO | src.core.logging:log_startup_complete:200 | ============================================================ 2026-04-05 15:01:34.539 | INFO | src.core.logging:log_startup_complete:201 | MAYA PIPECAT SERVER - STARTUP COMPLETE 2026-04-05 15:01:34.539 | INFO | src.core.logging:log_startup_complete:203 | STARTUP_TIME: 10193.55ms 2026-04-05 15:01:34.539 | INFO | src.core.logging:log_startup_complete:204 | ============================================================ 2026-04-05 15:01:34.550 | ERROR | uvicorn.server:startup:170 | [Errno 48] error while attempting to bind on address ('0.0.0.0', 3009): address already in use 2026-04-05 15:01:34.550 | INFO | src.core.logging:log_shutdown:208 | MAYA PIPECAT SERVER - SHUTDOWN INITIATED 2026-04-05 15:01:34.551 | INFO | src.service.config_sync_service:stop:57 | ConfigSyncManager stopped 2026-04-05 15:01:34.551 | INFO | src.core.redis:close_redis:214 | REDIS_CLOSED: connection pool closed 2026-04-05 15:01:34.551 | INFO | server:lifespan:56 | SERVER_STOPPED: status=graceful 2026-04-05 15:01:35.668 | INFO | src.repository.persona_repository:_get_persona_by_id_db:77 | DB_PERSONA_FETCHED: persona=edit_photos name=Edit Photos duration_ms=24.67 2026-04-05 15:01:35.792 | INFO | src.routes.bot:get_transport_type:174 | TRANSPORT_TYPE_CHECK: mode=smallwebrtc 2026-04-05 15:01:36.962 | INFO | src.routes.bot:bot_connect:186 | CONNECT_INPUT_BODY: {'device_id': '8ffb37ab9eefc78c', 'version': 'explore', 'character_id': None, 'persona_id': 'edit_photos', 'app_key': 'app_v2', 'language': 'hi', 'source': None, 'app_version': 5} 2026-04-05 15:01:36.962 | INFO | src.routes.bot:bot_connect:198 | SESSION_START: device_id=8ffb37ab9eefc78c version=explore persona_id=edit_photos app_key=app_v2 language=hi source=app app_version=5 transport_mode=smallwebrtc 2026-04-05 15:01:36.963 | WARNING | src.routes.bot:_get_ice_servers:52 | NO_TURN_SERVER: WebRTC will fail behind NAT/K8s without TURN 2026-04-05 15:01:36.963 | INFO | src.routes.bot:_start_smallwebrtc_session:229 | SESSION_CREATED: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d transport=smallwebrtc device_id=8ffb37ab9eefc78c 2026-04-05 15:01:36.963 | WARNING | src.routes.bot:_get_ice_servers:52 | NO_TURN_SERVER: WebRTC will fail behind NAT/K8s without TURN 2026-04-05 15:01:42.191 | INFO | src.repository.persona_repository:_get_persona_by_id_db:77 | DB_PERSONA_FETCHED: persona=edit_photos name=Edit Photos duration_ms=26.43 2026-04-05 15:01:42.191 | INFO | src.bot.core.main:run_bot_webrtc_global_agent:55 | SESSION_START: persona=edit_photos name=Edit Photos content_type=image_gen tools=['cf_image'] transport=smallwebrtc 2026-04-05 15:01:42.214 | INFO | src.bot.core.main:run_bot_webrtc_global_agent:68 | LANGUAGE_FROM_DB: device=8ffb37ab9eefc78c language=hi 2026-04-05 15:01:42.238 | INFO | src.repository.config_repository:get_active_prompt:491 | DB_PROMPT_FETCHED: prompt_name=character_prompt version=2 chars=828 duration_ms=23.94 2026-04-05 15:01:42.287 | INFO | src.bot.core.config:load_system_instruction:411 | PROMPT_LOADED: persona=edit_photos lang=hi global=828 lang_inst=213 intro=145 personality=272 total=1532 2026-04-05 15:01:42.306 | INFO | aioice.ice:__log_info:1199 | Connection(6) Discovered peer reflexive candidate Candidate(xPaHXuwsdr 1 udp 1853827327 2405:201:c010:f81e:e8ce:d460:7980:1d84 39024 typ prflx) 2026-04-05 15:01:42.306 | INFO | aioice.ice:__log_info:1199 | Connection(6) Check CandidatePair(('2405:201:c010:f81e:1c69:d88:ea86:48cd', 63687) -> ('2405:201:c010:f81e:e8ce:d460:7980:1d84', 39024)) State.WAITING -> State.IN_PROGRESS 2026-04-05 15:01:42.312 | INFO | aioice.ice:__log_info:1199 | Connection(6) Check CandidatePair(('2405:201:c010:f81e:1c69:d88:ea86:48cd', 63687) -> ('2405:201:c010:f81e:e8ce:d460:7980:1d84', 39024)) State.IN_PROGRESS -> State.SUCCEEDED 2026-04-05 15:01:42.312 | INFO | aioice.ice:__log_info:1199 | Connection(6) ICE completed 2026-04-05 15:01:42.369 | INFO | src.routes.bot:handle_ice_candidates:351 | ICE_PATCH_RAW: session=37eb6d04-ba31-4154-89c1-364bfa77b28d body={'pc_id': 'SmallWebRTCConnection#2-6a68a684e9d64e958dacd289fb69c506', 'candidates': [{'candidate': 'candidate:2594445826 1 udp 2122194687 192.168.29.205 51171 typ host generation 0 ufrag WOyH network-id 6 network-cost 10', 'sdp_mid': '0', 'sdp_mline_index': 0}, {'candidate': 'candidate:4006687970 1 udp 2122262783 2405:201:c010:f81e:fc8e:9cff:fe3e:b724 39024 typ host generation 0 ufrag WOyH network-id 7 network-cost 10', 'sdp_mid': '0', 'sdp_mline_index': 0}, {'candidate': 'candidate:2594445826 1 udp 2122194687 192.168.29.205 33823 typ host generation 0 ufrag WOyH network-id 6 network-cost 10', 'sdp_mid': '1', 'sdp_mline_index': 1}, {'candidate': 'candidate: udp 2122262783 2405:201:c010:f81e:fc8e:9cff:fe3e:b724 43154 typ host generation 0 ufrag WOyH network-id 7 network-cost 10', 'sdp_mid': '1', 'sdp_mline_index': 1}, {'candidate': 'candidate:2594445826 1 udp 2122194687 192.168.29.205 54137 typ host generation 0 ufrag WOyH network-id 6 network-cost 10', 'sdp_mid': '2', 'sdp_mline_index': 2}, {'candidate': 'candidate:4006687970 1 udp 2122262783 2405:201:c010:f81e:fc8e:9cff:fe3e:b724 40603 typ host generation 0 ufrag WOyH network-id 7 network-cost 10', 'sdp_mid': '2', 'sdp_mline_index': 2}, {'candidate': 'candidate:2594445826 1 udp 2122194687 192.168.29.205 34217 typ host generation 0 ufrag WOyH network-id 6 network-cost 10', 'sdp_mid': '3', 'sdp_mline_index': 3}, {'candidate': 'candidate:4006687970 1 udp 2122262783 2405:201:c010:f81e:fc8e:9cff:fe3e:b724 46033 typ host generation 0 ufrag WOyH network-id 7 network-cost 10', 'sdp_mid': '3', 'sdp_mline_index': 3}, {'candidate': 'candidate:2689430948 1 tcp 1518018303 100.64.196.182 9 typ host tcptype active generation 0 ufrag WOyH network-id 2 network-cost 900', 'sdp_mid': '0', 'sdp_mline_index': 0}, {'candidate': 'candidate:3894305661 1 tcp 1518151935 2401:4900:6518:f6c4:f143:e60f:7b1a:7c41 9 typ host tcptype active generation 0 ufrag WOyH network-id 1 network-cost 900', 'sdp_mid': '0', 'sdp_mline_index': 0}, {'candidate': 'candidate:2368882030 1 tcp 1518086399 2401:4900:658f:e0d6:4713:ec53:14cc:ced6 9 typ host tcptype active generation 0 ufrag WOyH network-id 3 network-cost 900', 'sdp_mid': '0', 'sdp_mline_index': 0}, {'candidate': 'candidate:2689430948 1 tcp 1518018303 100.64.196.182 9 typ host tcptype active generation 0 ufrag WOyH network-id 2 network-cost 900', 'sdp_mid': '1', 'sdp_mline_index': 1}, {'candidate': 'candidate:3894305661 1 tcp 1518151935 2401:4900:6518:f6c4:f143:e60f:7b1a:7c41 9 typ host tcptype active generation 0 ufrag WOyH network-id 1 network-cost 900', 'sdp_mid': '1', 'sdp_mline_index': 1}, {'candidate': 'candidate:2368882030 1 tcp 1518086399 2401:4900:658f:e0d6:4713:ec53:14cc:ced6 9 typ host tcptype active generation 0 ufrag WOyH network-id 3 network-cost 900', 'sdp_mid': '1', 'sdp_mline_index': 1}, {'candidate': 'candidate:2689430948 1 tcp 1518018303 100.64.196.182 9 typ host tcptype active generation 0 ufrag WOyH network-id 2 network-cost 900', 'sdp_mid': '2', 'sdp_mline_index': 2}, {'candidate': 'candidate:3894305661 1 tcp 1518151935 2401:4900:6518:f6c4:f143:e60f:7b1a:7c41 9 typ host tcptype active generation 0 ufrag WOyH network-id 1 network-cost 900', 'sdp_mid': '2', 'sdp_mline_index': 2}, {'candidate': 'candidate:2368882030 1 tcp 1518086399 2401:4900:658f:e0d6:4713:ec53:14cc:ced6 9 typ host tcptype active generation 0 ufrag WOyH network-id 3 network-cost 900', 'sdp_mid': '2', 'sdp_mline_index': 2}, {'candidate': 'candidate:2689430948 1 tcp 1518018303 100.64.196.182 9 typ host tcptype active generation 0 ufrag WOyH network-id 2 network-cost 900', 'sdp_mid': '3', 'sdp_mline_index': 3}, {'candidate': 'candidate:3894305661 1 tcp 1518151935 2401:4900:6518:f6c4:f143:e60f:7b1a:7c41 9 typ host tcptype active generation 0 ufrag WOyH network-id 1 network-cost 900', 'sdp_mid': '3', 'sdp_mline_index': 3}, {'candidate': 'candidate:2368882030 1 tcp 1518086399 2401:4900:658f:e0d6:4713:ec53:14cc:ced6 9 typ host tcptype active generation 0 ufrag WOyH network-id 3 network-cost 900', 'sdp_mid': '3', 'sdp_mline_index': 3}]} 2026-04-05 15:01:42.385 | INFO | src.bot.core.main:run_bot_webrtc_global_agent:136 | USAGE_STATE: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d device_id=8ffb37ab9eefc78c persona=edit_photos content=image_gen free=999999/999999 plan=0 topup=9 payments=False cooldown=30m transport=smallwebrtc 2026-04-05 15:01:42.385 | INFO | src.bot.core.main:run_bot_webrtc_global_agent:147 | PIPELINE_MODE: language=hi use_gemini_live=False 2026-04-05 15:01:42.440 | INFO | src.bot.core.pipeline:create_vad_analyzer:76 | VAD_CREATED: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d 2026-04-05 15:01:42.440 | INFO | src.bot.core.main:run_bot_webrtc_global_agent:159 | TRANSPORT_CREATED: type=smallwebrtc session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d 2026-04-05 15:01:42.440 | WARNING | warnings:_showwarnmsg:112 | /Users/thippareddysaicharanreddy/Desktop/Prod_Livekit_Pipecat/venv/lib/python3.12/site-packages/pipecat/processors/transcript_processor.py:294: DeprecationWarning: `TranscriptProcessor` is deprecated and will be removed in a future version. Use `LLMUserAggregator`'s and `LLMAssistantAggregator`'s new events instead. warnings.warn( 2026-04-05 15:01:42.444 | INFO | src.bot.core.pipeline:_create_openai_stt_service:126 | STT_SERVICE_CREATED: provider=openai model=gpt-4o-mini-transcribe-2025-12-15 2026-04-05 15:01:42.444 | INFO | src.bot.core.pipeline:_create_gemini_llm_service:313 | GCP_ACCOUNT: OLD account active | project=mayaresearchgcp | credentials=gcp-service-account.json 2026-04-05 15:01:42.719 | WARNING | warnings:_showwarnmsg:112 | /Users/thippareddysaicharanreddy/Desktop/Prod_Livekit_Pipecat/src/bot/core/pipeline.py:317: DeprecationWarning: The `model` parameter is deprecated. Use `settings=GoogleVertexLLMService.Settings(model=...)` instead. If both are provided, `settings` takes precedence. return GoogleVertexLLMService( 2026-04-05 15:01:42.719 | WARNING | warnings:_showwarnmsg:112 | /Users/thippareddysaicharanreddy/Desktop/Prod_Livekit_Pipecat/src/bot/core/pipeline.py:317: DeprecationWarning: The `system_instruction` parameter is deprecated. Use `settings=GoogleVertexLLMService.Settings(system_instruction=...)` instead. If both are provided, `settings` takes precedence. return GoogleVertexLLMService( 2026-04-05 15:01:42.719 | WARNING | warnings:_showwarnmsg:112 | /Users/thippareddysaicharanreddy/Desktop/Prod_Livekit_Pipecat/src/bot/core/pipeline.py:317: DeprecationWarning: The `params` parameter is deprecated. Use `settings=GoogleVertexLLMService.Settings(...)` instead. If both are provided, `settings` takes precedence. return GoogleVertexLLMService( 2026-04-05 15:01:42.720 | INFO | google.genai._api_client:__init__:625 | The user provided Google Cloud credentials will take precedence over the API key from the environment variable. 2026-04-05 15:01:42.743 | INFO | src.bot.core.pipeline:create_tts_service:382 | TTS_SERVICE_CREATED: speaker=Taru 2026-04-05 15:01:42.743 | WARNING | warnings:_showwarnmsg:112 | /Users/thippareddysaicharanreddy/Desktop/Prod_Livekit_Pipecat/src/bot/core/pipeline.py:459: DeprecationWarning: OpenAILLMContext is deprecated and will be removed in a future version. Use the universal LLMContext and LLMContextAggregatorPair instead. See OpenAILLMContext docstring for migration guide. context = OpenAILLMContext( 2026-04-05 15:01:42.744 | WARNING | warnings:_showwarnmsg:112 | /Users/thippareddysaicharanreddy/Desktop/Prod_Livekit_Pipecat/venv/lib/python3.12/site-packages/pipecat/processors/aggregators/llm_response.py:326: DeprecationWarning: GoogleUserContextAggregator (likely created with create_context_aggregator()) is deprecated and will be removed in a future version. Use the universal LLMContext and LLMContextAggregatorPair instead. See OpenAILLMContext docstring for migration guide. super().__init__(**kwargs) 2026-04-05 15:01:42.744 | WARNING | warnings:_showwarnmsg:112 | /Users/thippareddysaicharanreddy/Desktop/Prod_Livekit_Pipecat/venv/lib/python3.12/site-packages/pipecat/processors/aggregators/llm_response.py:326: DeprecationWarning: GoogleAssistantContextAggregator (likely created with create_context_aggregator()) is deprecated and will be removed in a future version. Use the universal LLMContext and LLMContextAggregatorPair instead. See OpenAILLMContext docstring for migration guide. super().__init__(**kwargs) 2026-04-05 15:01:42.744 | WARNING | warnings:_showwarnmsg:112 | /Users/thippareddysaicharanreddy/Desktop/Prod_Livekit_Pipecat/venv/lib/python3.12/site-packages/pipecat/transports/base_input.py:152: DeprecationWarning: Parameter 'vad_analyzer' is deprecated. Use `LLMUserAggregator`'s `vad_analyzer` parameter, or `VADProcessor` if no `LLMUserAggregator` is needed. warnings.warn( 2026-04-05 15:01:42.744 | INFO | src.bot.core.pipeline:create_pipeline:510 | PIPELINE: Traditional STT mode (Audio → STT → Text → LLM → TTS) | text_aggregation=2-sentence aggregation 2026-04-05 15:01:42.745 | WARNING | warnings:_showwarnmsg:112 | /Users/thippareddysaicharanreddy/Desktop/Prod_Livekit_Pipecat/src/bot/core/pipeline.py:526: DeprecationWarning: UserBotLatencyLogObserver is deprecated and will be removed in a future version. Use UserBotLatencyObserver directly with its on_latency_measured event handler instead. UserBotLatencyLogObserver() 2026-04-05 15:01:42.745 | WARNING | pipecat.pipeline.task:__init__:348 | PipelineTask#2: RTVIProcessor and RTVIObserver found, skipping default ones. They are both added by default, no need to add them yourself. 2026-04-05 15:01:42.745 | INFO | src.bot.core.main:run_bot_webrtc_global_agent:238 | FUNCTION_REGISTERED: cf_image 2026-04-05 15:01:42.746 | INFO | src.bot.core.main:run_bot_webrtc_global_agent:264 | NO_USER_TIMEOUT_STARTED: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d transport=smallwebrtc timeout=240s 2026-04-05 15:01:42.746 | INFO | aioice.ice:__log_info:1199 | Connection(6) Check CandidatePair(('2405:201:c010:f81e:1c69:d88:ea86:48cd', 63687) -> ('2405:201:c010:f81e:fc8e:9cff:fe3e:b724', 39024)) State.FROZEN -> State.FAILED 2026-04-05 15:01:42.746 | INFO | aioice.ice:__log_info:1199 | Connection(6) Check CandidatePair(('2405:201:c010:f81e:8d47:e226:3338:722c', 60027) -> ('2405:201:c010:f81e:fc8e:9cff:fe3e:b724', 39024)) State.FROZEN -> State.FAILED 2026-04-05 15:0 | INFO | aioice.ice:__log_info:1199 | Connection(6) Check CandidatePair(('192.168.29.67', 52181) -> ('192.168.29.205', 51171)) State.FROZEN -> State.FAILED 2026-04-05 15:01:42.748 | INFO | aioice.ice:__log_info:1199 | Connection(6) Check CandidatePair(('2405:201:c010:f81e:8d47:e226:3338:722c', 60027) -> ('2405:201:c010:f81e:e8ce:d460:7980:1d84', 39024)) State.WAITING -> State.IN_PROGRESS 2026-04-05 15:01:42.749 | INFO | aioice.ice:__log_info:1199 | Connection(6) Check CandidatePair(('192.168.29.67', 52181) -> ('192.168.29.205', 51171)) State.FAILED -> State.IN_PROGRESS 2026-04-05 15:01:42.750 | INFO | pipecat.transports.smallwebrtc.transport:connect:451 | Connecting to Small WebRTC 2026-04-05 15:01:42.751 | INFO | src.bot.core.session_handlers:on_first_client_connected:222 | USER_JOINED: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d participant_id=SmallWebRTCConnection#2-6a68a684e9d64e958dacd289fb69c506 elapsed=0.6s transport=smallwebrtc 2026-04-05 15:01:42.751 | INFO | src.bot.core.session_handlers:handle_first_participant_joined:335 | SESSION_STARTED_IN_MEMORY: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d device_id=8ffb37ab9eefc78c transport=smallwebrtc 2026-04-05 15:01:42.757 | INFO | aioice.ice:__log_info:1199 | Connection(6) Check CandidatePair(('2405:201:c010:f81e:8d47:e226:3338:722c', 60027) -> ('2405:201:c010:f81e:e8ce:d460:7980:1d84', 39024)) State.IN_PROGRESS -> State.SUCCEEDED 2026-04-05 15:01:42.757 | INFO | aioice.ice:__log_info:1199 | Connection(6) Check CandidatePair(('192.168.29.67', 52181) -> ('192.168.29.205', 51171)) State.IN_PROGRESS -> State.SUCCEEDED 2026-04-05 15:01:42.759 | WARNING | warnings:_showwarnmsg:112 | :4: DeprecationWarning: OpenAILLMContextFrame is deprecated and will be removed in a future version. Use LLMContextFrame with the universal `LLMContext` and `LLMContextAggregatorPair` instead. See OpenAILLMContext docstring for migration guide. 2026-04-05 15:01:42.760 | WARNING | pipecat.services.google.llm:_stream_content:1020 | GeminiLLM-OLD: Both system_instruction and a system message in context are set. Using system_instruction. 2026-04-05 15:01:42.761 | WARNING | warnings:_showwarnmsg:112 | /Users/thippareddysaicharanreddy/Desktop/Prod_Livekit_Pipecat/venv/lib/python3.12/site-packages/google/genai/_api_client.py:771: DeprecationWarning: Inheritance class AiohttpClientSession from ClientSession is discouraged class AiohttpClientSession(aiohttp.ClientSession): # type: ignore[misc] 2026-04-05 15:01:42.852 | INFO | src.repository.device_repository:_should_show_app_review_db:145 | DB_REVIEW_ELIGIBILITY: device_id=8ffb37ab9eefc78c sessions=260 generations=130 should_show=True total_duration_ms=98.92 queries=4 2026-04-05 15:01:42.870 | INFO | pipecat.transports.smallwebrtc.request_handler:handle_web_request:199 | Reusing existing connection for pc_id: SmallWebRTCConnection#2-6a68a684e9d64e958dacd289fb69c506 2026-04-05 15:01:43.752 | INFO | aioice.ice:__log_info:1199 | Connection(6) Check CandidatePair(('2405:201:c010:f81e:1c69:d88:ea86:48cd', 63687) -> ('2405:201:c010:f81e:fc8e:9cff:fe3e:b724', 46033)) State.FROZEN -> State.FAILED 2026-04-05 15:01:43.752 | INFO | aioice.ice:__log_info:1199 | Connection(6) Check CandidatePair(('2405:201:c010:f81e:8d47:e226:3338:722c', 60027) -> ('2405:201:c010:f81e:fc8e:9cff:fe3e:b724', 46033)) State.FROZEN -> State.FAILED 2026-04-05 15:01:43.752 | INFO | aioice.ice:__log_info:1199 | Connection(6) Check CandidatePair(('192.168.29.67', 52181) -> ('192.168.29.205', 34217)) State.FROZEN -> State.FAILED 2026-04-05 15:01:43.777 | INFO | src.bot.core.observers:on_process_frame:83 | METRICS: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d device_id=8ffb37ab9eefc78c processor=GeminiLLM-OLD type=TTFB (gemini-2.5-flash) value=1.01566s 2026-04-05 15:01:43.841 | WARNING | warnings:_showwarnmsg:112 | /Users/thippareddysaicharanreddy/Desktop/Prod_Livekit_Pipecat/venv/lib/python3.12/site-packages/aiohttp/connector.py:993: DeprecationWarning: enable_cleanup_closed ignored because https://github.com/python/cpython/pull/118960 is fixed in Python version sys.version_info(major=3, minor=12, micro=12, releaselevel='final', serial=0) super().__init__( 2026-04-05 15:01:43.842 | INFO | src.bot.core.observers:on_process_frame:83 | METRICS: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d device_id=8ffb37ab9eefc78c processor=CustomTTSService#2 type=TTS (veena3-tts) characters=22 2026-04-05 15:01:43.895 | INFO | src.bot.core.observers:on_process_frame:83 | METRICS: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d device_id=8ffb37ab9eefc78c processor=GeminiLLM-OLD type=LLM (gemini-2.5-flash) prompt_tokens=573 completion_tokens=35 cache_read=0 2026-04-05 15:01:45.136 | INFO | src.bot.core.observers:on_process_frame:83 | METRICS: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d device_id=8ffb37ab9eefc78c processor=CustomTTSService#2 type=TTFB (veena3-tts) value=1.29448s 2026-04-05 15:01:45.593 | INFO | src.bot.core.observers:on_process_frame:83 | METRICS: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d device_id=8ffb37ab9eefc78c processor=CustomTTSService#2 type=Processing (veena3-tts) value=1.75110s 2026-04-05 15:01:45.593 | INFO | src.bot.core.observers:on_process_frame:83 | METRICS: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d device_id=8ffb37ab9eefc78c processor=CustomTTSService#2 type=TTS (veena3-tts) characters=122 2026-04-05 15:01:46.365 | INFO | src.bot.core.observers:on_process_frame:83 | METRICS: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d device_id=8ffb37ab9eefc78c processor=CustomTTSService#2 type=TTFB (veena3-tts) value=0.77262s 2026-04-05 15:01:47.573 | INFO | src.bot.core.observers:on_process_frame:83 | METRICS: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d device_id=8ffb37ab9eefc78c processor=CustomTTSService#2 type=Processing (veena3-tts) value=1.98026s 2026-04-05 15:01:50.061 | WARNING | warnings:_showwarnmsg:112 | :7: DeprecationWarning: TranscriptionMessage is deprecated and will be removed in a future version. Use `LLMUserAggregator`'s and `LLMAssistantAggregator`'s new events instead. 2026-04-05 15:01:50.061 | WARNING | warnings:_showwarnmsg:112 | :4: DeprecationWarning: TranscriptionUpdateFrame is deprecated and will be removed in a future version. Use `LLMUserAggregator`'s and `LLMAssistantAggregator`'s new events instead. 2026-04-05 15:01:50.062 | WARNING | warnings:_showwarnmsg:112 | :4: DeprecationWarning: OpenAILLMContextFrame is deprecated and will be removed in a future version. Use LLMContextFrame with the universal `LLMContext` and `LLMContextAggregatorPair` instead. See OpenAILLMContext docstring for migration guide. 2026-04-05 15:01:50.062 | WARNING | warnings:_showwarnmsg:112 | :4: DeprecationWarning: OpenAILLMContextAssistantTimestampFrame is deprecated and will be removed in a future version. Use LLMContextAssistantTimestampFrame with the universal LLMContext and LLMContextAggregatorPair instead. See OpenAILLMContext docstring for migration guide. 2026-04-05 15:01:54.105 | INFO | src.bot.core.observers:on_process_frame:83 | METRICS: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d device_id=8ffb37ab9eefc78c processor=OpenAISTTService#2 type=Processing (gpt-4o-mini-transcribe-2025-12-15) value=1.97255s 2026-04-05 15:01:54.105 | INFO | src.bot.core.observers:on_process_frame:83 | METRICS: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d device_id=8ffb37ab9eefc78c processor=OpenAISTTService#2 type=TTFB (gpt-4o-mini-transcribe-2025-12-15) value=2.77306s 2026-04-05 15:01:54.108 | WARNING | warnings:_showwarnmsg:112 | :7: DeprecationWarning: TranscriptionMessage is deprecated and will be removed in a future version. Use `LLMUserAggregator`'s and `LLMAssistantAggregator`'s new events instead. 2026-04-05 15:01:54.108 | WARNING | warnings:_showwarnmsg:112 | :4: DeprecationWarning: TranscriptionUpdateFrame is deprecated and will be removed in a future version. Use `LLMUserAggregator`'s and `LLMAssistantAggregator`'s new events instead. 2026-04-05 15:01:54.614 | WARNING | warnings:_showwarnmsg:112 | :4: DeprecationWarning: OpenAILLMContextFrame is deprecated and will be removed in a future version. Use LLMContextFrame with the universal `LLMContext` and `LLMContextAggregatorPair` instead. See OpenAILLMContext docstring for migration guide. 2026-04-05 15:01:54.615 | WARNING | pipecat.services.google.llm:_stream_content:1020 | GeminiLLM-OLD: Both system_instruction and a system message in context are set. Using system_instruction. 2026-04-05 15:01:56.435 | INFO | src.bot.core.observers:on_process_frame:83 | METRICS: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d device_id=8ffb37ab9eefc78c processor=GeminiLLM-OLD type=TTFB (gemini-2.5-flash) value=1.81960s 2026-04-05 15:01:56.749 | INFO | src.bot.core.observers:on_process_frame:83 | METRICS: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d device_id=8ffb37ab9eefc78c processor=GeminiLLM-OLD type=LLM (gemini-2.5-flash) prompt_tokens=584 completion_tokens=12 cache_read=0 2026-04-05 15:01:56.750 | INFO | src.bot.core.observers:on_process_frame:83 | METRICS: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d device_id=8ffb37ab9eefc78c processor=CustomTTSService#2 type=TTS (veena3-tts) characters=36 2026-04-05 15:01:57.593 | INFO | src.bot.core.observers:on_process_frame:83 | METRICS: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d device_id=8ffb37ab9eefc78c processor=CustomTTSService#2 type=TTFB (veena3-tts) value=0.84238s 2026-04-05 15:01:57.926 | INFO | src.bot.core.observers:on_process_frame:83 | METRICS: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d device_id=8ffb37ab9eefc78c processor=CustomTTSService#2 type=Processing (veena3-tts) value=1.17642s 2026-04-05 15:02:03.083 | WARNING | pipecat.transports.smallwebrtc.transport:read_audio_frame:370 | Received an unexpected media stream error while reading the audio. 2026-04-05 15:02:03.086 | INFO | pipecat.transports.smallwebrtc.request_handler:handle_disconnected:211 | Discarding peer connection for pc_id: SmallWebRTCConnection#2-6a68a684e9d64e958dacd289fb69c506 2026-04-05 15:02:03.086 | INFO | src.bot.core.session_handlers:handle_participant_left:388 | USER_LEFT: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d elapsed=20.9s duration=20.3s reason=disconnected transport=smallwebrtc 2026-04-05 15:02:04.520 | INFO | src.repository.session_repository:save_session_complete:246 | DB_SESSION_COMPLETE_SAVED: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d images=0 videos=0 ppts=0 music=0 statements=1 duration_ms=41.19 2026-04-05 15:02:04.520 | INFO | src.bot.core.session_handlers:handle_participant_left:545 | SESSION_SAVED: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d transport=smallwebrtc persona=edit_photos images=0 videos=0 ppts=0 music=0 2026-04-05 15:02:04.520 | INFO | src.bot.core.session_handlers:handle_participant_left:565 | CLEANUP_TRIGGER: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d transport=smallwebrtc reason=participant_left 2026-04-05 15:02:04.520 | INFO | src.bot.core.config:cleanup_session:420 | CLEANUP_START: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d threads=14 2026-04-05 15:02:04.522 | INFO | src.bot.core.config:cleanup_session:527 | VAD_DESTROYED: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d (memory freed) 2026-04-05 15:02:04.522 | INFO | src.bot.core.config:cleanup_session:614 | CLEANUP_END: session_id=37eb6d04-ba31-4154-89c1-364bfa77b28d threads_at_start=14 threads_at_end=14 delta=0 2026-04-05 15:02:04.522 | INFO | pipecat.observers.loggers.user_bot_latency_log_observer:_log_summary:97 | ⏱️ LATENCY FROM USER STOPPED SPEAKING TO BOT STARTED SPEAKING - Avg: 6.264s, Min: 6.264s, Max: 6.264s