[107065]: 260ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxThreadService' as '303073113'
[107065]: 261ms SPX_TRACE_WARNING: resource_manager.cpp:103 Failed to create 'CSpxCodecAdapter' as '199052375'. Are all required extension libraries loaded?
[107065]: 261ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:48 CSpxUspTtsEngineAdapter::CSpxUspTtsEngineAdapter
[107065]: 261ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxUspTtsEngineAdapter' as '919101823'
[107065]: 262ms SPX_DBG_TRACE_SCOPE_ENTER: com_init_and_uninit.cpp:18 ComInitAndUnInit ctor enter
[956950]: 263ms SPX_DBG_TRACE_SCOPE_ENTER: com_init_and_uninit.cpp:44 com worker_thread_proc() enter
[956950]: 263ms SPX_DBG_TRACE_VERBOSE: com_init_and_uninit.cpp:53 com successfully Initialized
[107065]: 263ms SPX_DBG_TRACE_SCOPE_EXIT: com_init_and_uninit.cpp:18 ComInitAndUnInit ctor done
[107065]: 263ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxDefaultSpeaker' as '853898539'
[107065]: 263ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxPullAudioOutputStream' as '853898539'
[107065]: 263ms SPX_DBG_TRACE_VERBOSE: cloud_tts_engine_adapter.cpp:131 CSpxCloudTtsEngineAdapter::SetOutput
[107065]: 263ms SPX_TRACE_INFO: cloud_tts_engine_adapter.cpp:209 CSpxCloudTtsEngineAdapter::SetOutput: request format [riff-16khz-16bit-mono-pcm] has header, using raw format instead for streaming.
[107065]: 263ms SPX_DBG_TRACE_VERBOSE: handle_table.h:106 CSpxHandleTable::TrackHandle p=0x00E78824
[107065]: 263ms SPX_DBG_TRACE_VERBOSE: handle_table.h:116 CSpxHandleTable::TrackHandle class=ISpxSynthesizer, h=0x00E78824, p=0x00E78824, tot=1
[107065]: 263ms SPX_DBG_TRACE_SCOPE_EXIT: speechapi_c_factory.cpp:480 synthesizer_create_speech_synthesizer_from_config
[107065]: 264ms SPX_TRACE_VERBOSE interop_factory.cs:83 Dispose(True) called for 00e288d4
[107065]: 264ms SPX_DBG_TRACE_VERBOSE: handle_table.h:175 CSpxHandleTable::StopTracking(h) h=0x00E288D4
[107065]: 264ms SPX_DBG_TRACE_VERBOSE: handle_table.h:190 CSpxHandleTable::StopTracking(h) class=ISpxNamedProperties, h=0x00E288D4, p=0x00E288D4, tot=1
[107065]: 265ms SPX_DBG_TRACE_VERBOSE: handle_table.h:106 CSpxHandleTable::TrackHandle p=0x00E78A84
[107065]: 265ms SPX_DBG_TRACE_VERBOSE: handle_table.h:116 CSpxHandleTable::TrackHandle class=ISpxNamedProperties, h=0x00E78A84, p=0x00E78A84, tot=2
[699469]: 280ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxAudioDataStream' as '418301898'
[699469]: 280ms SPX_DBG_TRACE_VERBOSE: null_audio_output.h:93 CSpxNullAudioOutput::SetFormat is called with format 0x00E846B0
[699469]: 280ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:25 CSpxSynthesisResult::CSpxSynthesisResult
[699469]: 280ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxSynthesisResult' as '3874248'
[699469]: 280ms SPX_TRACE_VERBOSE: synthesizer.cpp:228 CSpxSynthesizer::ExecuteSynthesis: synthesis started, request id: 520f08036a384bba900fa99ed701288c; text: 1
[207990]: 280ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:1070 DispatchEvent task started...
[699469]: 280ms SPX_DBG_TRACE_VERBOSE: synthesizer_timeout_management.cpp:30 SynthesisTimeoutManagement::SetTimeoutValues: RTF set to 2.00; frame timeout interval set to 3000 ms.
[207990]: 280ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:496 [00E78808]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[699469]: 280ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:80 CSpxUspTtsEngineAdapter::Speak
[207990]: 280ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxSynthesisEventArgs' as '657912386'
[699469]: 280ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:98 CSpxUspTtsEngineAdapter::Speak: start to send synthesis request, request id : 520f08036a384bba900fa99ed701288c, try: 0
[207990]: 280ms SPX_DBG_TRACE_VERBOSE: handle_table.h:106 CSpxHandleTable::TrackHandle p=0x057DB720
[699469]: 280ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:173 SSML sent to TTS cognitive service: 1
[207990]: 280ms SPX_DBG_TRACE_VERBOSE: handle_table.h:116 CSpxHandleTable::TrackHandle class=ISpxSynthesisEventArgs, h=0x057DB720, p=0x057DB720, tot=1
[699469]: 280ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:454 CSpxUspTtsEngineAdapter::UspInitialize: this=0x00E7D4C8
[699469]: 280ms SPX_DBG_TRACE_VERBOSE: named_properties.h:468 ISpxNamedProperties::GetStringValue: this=0x00E78A84; name='SPEECH-SubscriptionKey'; value='******************************a9'
[699469]: 280ms SPX_DBG_TRACE_VERBOSE: named_properties.h:468 ISpxNamedProperties::GetStringValue: this=0x00E7D4FC; name='AZAC-SDK-PROGRAMMING-LANGUAGE'; value='C#'
[699469]: 281ms SPX_DBG_TRACE_VERBOSE: named_properties.h:468 ISpxPropertyBagImpl::SetStringValue: this=0x00E7D4FC; name='HttpHeader#User-agent'; value='SpeechSDK-C#/1.23.0 Windows Client 8'
[699469]: 281ms SPX_DBG_TRACE_VERBOSE: named_properties.h:468 ISpxNamedProperties::FindPrefix: this=0x00E7D4FC; name='User-agent'; value='SpeechSDK-C#/1.23.0 Windows Client 8'
[699469]: 281ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxUspCallbackWrapper' as '978711522'
[699469]: 281ms SPX_DBG_TRACE_VERBOSE: named_properties.h:468 ISpxNamedProperties::GetStringValue: this=0x00E78A84; name='SPEECH-Region'; value='eastus'
[699469]: 281ms SPX_DBG_TRACE_VERBOSE: named_properties.h:468 ISpxNamedProperties::GetStringValue: this=0x00E78A84; name='SPEECH-ProxyHostBypass'; value=''
[699469]: 281ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxUspConnection' as '792041254'
[699469]: 281ms SPX_TRACE_INFO: usp_connection.cpp:460 Microsoft::CognitiveServices::Speech::USP::CSpxUspConnection::Connect: entering...
[699469]: 281ms SPX_TRACE_INFO: usp_connection.cpp:477 Adding subscription key headers
[699469]: 281ms SPX_TRACE_INFO: usp_connection.cpp:512 Set a user defined HTTP header 'User-agent':'SpeechSDK-C#/1.23.0 Windows Client 8'
[699469]: 281ms SPX_TRACE_INFO: usp_connection.cpp:518 Set an underlying io option 'tcp_nodelay'
[699469]: 281ms SPX_TRACE_INFO: usp_connection.cpp:527 connectionUrl=wss://eastus.tts.speech.microsoft.com/cognitiveservices/websocket/v1
[699469]: 281ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxTelemetry' as '151041793'
[699469]: 281ms SPX_DBG_TRACE_SCOPE_ENTER: web_socket.cpp:267 CSpxWebSocket::CSpxWebSocket
[699469]: 281ms SPX_DBG_TRACE_SCOPE_EXIT: web_socket.cpp:267 CSpxWebSocket::CSpxWebSocket
[699469]: 281ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxWebSocket' as '482598724'
[699469]: 281ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:31 WebSocketAdapter::UwsWebSocket::UwsWebSocket
[699469]: 281ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:31 WebSocketAdapter::UwsWebSocket::UwsWebSocket
[699469]: 281ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:43 WebSocketAdapter::UwsWebSocket::Initialize
[699469]: 281ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:43 WebSocketAdapter::UwsWebSocket::Initialize
[699469]: 281ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader
[699469]: 281ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader
[699469]: 281ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader
[699469]: 281ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader
[699469]: 281ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader
[699469]: 281ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader
[805609]: 281ms SPX_TRACE_INFO: web_socket.cpp:837 CSpxWebSocket::DoWork: open transport.
[699469]: 281ms SPX_DBG_TRACE_VERBOSE: named_properties.h:468 ISpxPropertyBagImpl::SetStringValue: this=0x00E78A84; name='SPEECH-ConnectionUrl'; value='wss://eastus.tts.speech.microsoft.com/cognitiveservices/websocket/v1'
[805609]: 282ms SPX_TRACE_INFO: web_socket.cpp:534 Start to open websocket. WebSocket: 0x57e2c28, wsio handle: 0x57e81bc
[805609]: 282ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:149 WebSocketAdapter::UwsWebSocket::Open
[699469]: 282ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:343 speech.config {"context":{"system":{"version":"1.23.0","name":"SpeechSDK","build":"Windows-x86"},"os":{"platform":"Windows","name":"Client","version":"8"}}}
[699469]: 282ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:394 speech.config='{"context":{"system":{"version":"1.23.0","name":"SpeechSDK","build":"Windows-x86"},"os":{"platform":"Windows","name":"Client","version":"8"}}}'
[699469]: 282ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:394 synthesis.context='{"synthesis":{"audio":{"outputFormat":"raw-16khz-16bit-mono-pcm","metadataOptions":{"visemeEnabled":false,"bookmarkEnabled":false,"wordBoundaryEnabled":true,"punctuationBoundaryEnabled":true,"sentenceBoundaryEnabled":false,"sessionEndEnabled":true}},"language":{"autoDetection":false}}}'
[699469]: 282ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:379 ssml 1
[699469]: 282ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:394 ssml='1'
[207990]: 283ms SPX_DBG_TRACE_VERBOSE: handle_table.h:106 CSpxHandleTable::TrackHandle p=0x057D5660
[207990]: 283ms SPX_DBG_TRACE_VERBOSE: handle_table.h:116 CSpxHandleTable::TrackHandle class=ISpxSynthesisResult, h=0x057D5660, p=0x057D5660, tot=1
[207990]: 286ms SPX_DBG_TRACE_VERBOSE: handle_table.h:106 CSpxHandleTable::TrackHandle p=0x057D5670
[207990]: 286ms SPX_DBG_TRACE_VERBOSE: handle_table.h:116 CSpxHandleTable::TrackHandle class=ISpxNamedProperties, h=0x057D5670, p=0x057D5670, tot=3
[207990]: 286ms SPX_TRACE_VERBOSE interop_factory.cs:83 Dispose(True) called for 057db720
[207990]: 286ms SPX_DBG_TRACE_VERBOSE: handle_table.h:175 CSpxHandleTable::StopTracking(h) h=0x057DB720
[207990]: 286ms SPX_DBG_TRACE_VERBOSE: handle_table.h:190 CSpxHandleTable::StopTracking(h) class=ISpxSynthesisEventArgs, h=0x057DB720, p=0x057DB720, tot=0
[207990]: 287ms SPX_TRACE_VERBOSE interop_factory.cs:83 Dispose(True) called for 057d5670
[207990]: 287ms SPX_DBG_TRACE_VERBOSE: handle_table.h:175 CSpxHandleTable::StopTracking(h) h=0x057D5670
[207990]: 287ms SPX_DBG_TRACE_VERBOSE: handle_table.h:190 CSpxHandleTable::StopTracking(h) class=ISpxNamedProperties, h=0x057D5670, p=0x057D5670, tot=2
[207990]: 287ms SPX_TRACE_VERBOSE interop_factory.cs:83 Dispose(True) called for 057d5660
[207990]: 287ms SPX_DBG_TRACE_VERBOSE: handle_table.h:175 CSpxHandleTable::StopTracking(h) h=0x057D5660
[207990]: 287ms SPX_DBG_TRACE_VERBOSE: handle_table.h:190 CSpxHandleTable::StopTracking(h) class=ISpxSynthesisResult, h=0x057D5660, p=0x057D5660, tot=0
[207990]: 287ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:1070 DispatchEvent task complete!
[805609]: 890ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:149 WebSocketAdapter::UwsWebSocket::Open
[805609]: 890ms SPX_TRACE_INFO: usp_connection.cpp:762 Create requestId for messageType 0
[699469]: 20284ms SPX_TRACE_ERROR: usp_tts_engine_adapter.cpp:232 USP error: timeout waiting for the first audio chunk
[699469]: 20285ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:25 CSpxSynthesisResult::CSpxSynthesisResult
[699469]: 20285ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxSynthesisResult' as '3874248'
[699469]: 20285ms SPX_DBG_TRACE_VERBOSE: named_properties.h:468 ISpxPropertyBagImpl::SetStringValue: this=0x057D5740; name='CancellationDetails_ReasonDetailedText'; value='USP error: timeout waiting for the first audio chunk'
[699469]: 20285ms SPX_DBG_TRACE_VERBOSE: named_properties.h:468 ISpxPropertyBagImpl::SetStringValue: this=0x057D5740; name='CancellationDetails_ReasonDetailedText'; value='USP error: timeout waiting for the first audio chunk'
[699469]: 20285ms SPX_TRACE_ERROR: usp_tts_engine_adapter.cpp:120 Synthesis cancelled without data received, retrying.
[699469]: 20286ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:98 CSpxUspTtsEngineAdapter::Speak: start to send synthesis request, request id : a1a45e095e7a4bf8a522636e41269ad0, try: 1
[699469]: 20286ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:173 SSML sent to TTS cognitive service: 1
[699469]: 20286ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x00E77124
[699469]: 20286ms SPX_DBG_TRACE_SCOPE_ENTER: usp_connection.cpp:144 Microsoft::CognitiveServices::Speech::USP::CSpxUspConnection::~CSpxUspConnection
[699469]: 20286ms SPX_DBG_TRACE_SCOPE_EXIT: usp_connection.cpp:144 Microsoft::CognitiveServices::Speech::USP::CSpxUspConnection::~CSpxUspConnection
[699469]: 20286ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:454 CSpxUspTtsEngineAdapter::UspInitialize: this=0x00E7D4C8
[699469]: 20286ms SPX_DBG_TRACE_VERBOSE: named_properties.h:468 ISpxNamedProperties::GetStringValue: this=0x00E78A84; name='SPEECH-SubscriptionKey'; value='******************************a9'
[699469]: 20286ms SPX_DBG_TRACE_VERBOSE: named_properties.h:468 ISpxNamedProperties::GetStringValue: this=0x00E7D4FC; name='AZAC-SDK-PROGRAMMING-LANGUAGE'; value='C#'
[699469]: 20287ms SPX_DBG_TRACE_VERBOSE: named_properties.h:468 ISpxNamedProperties::GetStringValue: this=0x00E7D4FC; name='HttpHeader#User-agent'; value='SpeechSDK-C#/1.23.0 Windows Client 8'
[699469]: 20287ms SPX_DBG_TRACE_VERBOSE: named_properties.h:468 ISpxNamedProperties::FindPrefix: this=0x00E7D4FC; name='User-agent'; value='SpeechSDK-C#/1.23.0 Windows Client 8'
[699469]: 20287ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxUspCallbackWrapper' as '978711522'
[699469]: 20287ms SPX_DBG_TRACE_VERBOSE: named_properties.h:468 ISpxNamedProperties::GetStringValue: this=0x00E78A84; name='SPEECH-Region'; value='eastus'
[699469]: 20287ms SPX_DBG_TRACE_VERBOSE: named_properties.h:468 ISpxNamedProperties::GetStringValue: this=0x00E78A84; name='SPEECH-ProxyHostBypass'; value=''
[699469]: 20287ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxUspConnection' as '792041254'
[699469]: 20287ms SPX_TRACE_INFO: usp_connection.cpp:460 Microsoft::CognitiveServices::Speech::USP::CSpxUspConnection::Connect: entering...
[699469]: 20287ms SPX_TRACE_INFO: usp_connection.cpp:477 Adding subscription key headers
[699469]: 20287ms SPX_TRACE_INFO: usp_connection.cpp:512 Set a user defined HTTP header 'User-agent':'SpeechSDK-C#/1.23.0 Windows Client 8'
[699469]: 20288ms SPX_TRACE_INFO: usp_connection.cpp:518 Set an underlying io option 'tcp_nodelay'
[699469]: 20288ms SPX_TRACE_INFO: usp_connection.cpp:527 connectionUrl=wss://eastus.tts.speech.microsoft.com/cognitiveservices/websocket/v1
[699469]: 20288ms SPX_DBG_TRACE_SCOPE_ENTER: web_socket.cpp:267 CSpxWebSocket::CSpxWebSocket
[699469]: 20288ms SPX_DBG_TRACE_SCOPE_EXIT: web_socket.cpp:267 CSpxWebSocket::CSpxWebSocket
[699469]: 20288ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxWebSocket' as '482598724'
[699469]: 20288ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:31 WebSocketAdapter::UwsWebSocket::UwsWebSocket
[699469]: 20288ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:31 WebSocketAdapter::UwsWebSocket::UwsWebSocket
[699469]: 20288ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:43 WebSocketAdapter::UwsWebSocket::Initialize
[699469]: 20288ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:43 WebSocketAdapter::UwsWebSocket::Initialize
[699469]: 20288ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader
[699469]: 20288ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader
[699469]: 20288ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader
[699469]: 20288ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader
[699469]: 20289ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader
[699469]: 20289ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader
[699469]: 20289ms SPX_DBG_TRACE_VERBOSE: named_properties.h:468 ISpxPropertyBagImpl::SetStringValue: this=0x00E78A84; name='SPEECH-ConnectionUrl'; value='wss://eastus.tts.speech.microsoft.com/cognitiveservices/websocket/v1'
[805609]: 20289ms SPX_TRACE_INFO: web_socket.cpp:837 CSpxWebSocket::DoWork: open transport.
[805609]: 20289ms SPX_TRACE_INFO: web_socket.cpp:534 Start to open websocket. WebSocket: 0x57ee0b8, wsio handle: 0x57e876c
[699469]: 20289ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:343 speech.config {"context":{"system":{"version":"1.23.0","name":"SpeechSDK","build":"Windows-x86"},"os":{"platform":"Windows","name":"Client","version":"8"}}}
[805609]: 20289ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:149 WebSocketAdapter::UwsWebSocket::Open
[699469]: 20289ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:394 speech.config='{"context":{"system":{"version":"1.23.0","name":"SpeechSDK","build":"Windows-x86"},"os":{"platform":"Windows","name":"Client","version":"8"}}}'
[699469]: 20290ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:394 synthesis.context='{"synthesis":{"audio":{"outputFormat":"raw-16khz-16bit-mono-pcm","metadataOptions":{"visemeEnabled":false,"bookmarkEnabled":false,"wordBoundaryEnabled":true,"punctuationBoundaryEnabled":true,"sentenceBoundaryEnabled":false,"sessionEndEnabled":true}},"language":{"autoDetection":false}}}'
[699469]: 20290ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:379 ssml 1
[699469]: 20290ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:394 ssml='1'
[805609]: 20542ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:149 WebSocketAdapter::UwsWebSocket::Open
[805609]: 20542ms SPX_TRACE_INFO: usp_connection.cpp:762 Create requestId for messageType 0
[805609]: 20542ms SPX_DBG_TRACE_SCOPE_ENTER: web_socket.cpp:272 CSpxWebSocket::~CSpxWebSocket
[805609]: 20543ms SPX_DBG_TRACE_SCOPE_EXIT: web_socket.cpp:272 CSpxWebSocket::~CSpxWebSocket
[805609]: 20543ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:36 WebSocketAdapter::UwsWebSocket::~UwsWebSocket
[805609]: 20543ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:36 WebSocketAdapter::UwsWebSocket::~UwsWebSocket
[699469]: 40302ms SPX_TRACE_ERROR: usp_tts_engine_adapter.cpp:232 USP error: timeout waiting for the first audio chunk
[699469]: 40302ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:25 CSpxSynthesisResult::CSpxSynthesisResult
[699469]: 40302ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxSynthesisResult' as '3874248'
[699469]: 40302ms SPX_DBG_TRACE_VERBOSE: named_properties.h:468 ISpxPropertyBagImpl::SetStringValue: this=0x057D59B0; name='CancellationDetails_ReasonDetailedText'; value='USP error: timeout waiting for the first audio chunk'
[699469]: 40302ms SPX_DBG_TRACE_VERBOSE: named_properties.h:468 ISpxPropertyBagImpl::SetStringValue: this=0x057D59B0; name='CancellationDetails_ReasonDetailedText'; value='USP error: timeout waiting for the first audio chunk'
[699469]: 40302ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:30 CSpxSynthesisResult::~CSpxSynthesisResult
[699469]: 40302ms SPX_TRACE_ERROR: usp_tts_engine_adapter.cpp:120 Synthesis cancelled without data received, retrying.
[699469]: 40302ms SPX_DBG_TRACE_VERBOSE: named_properties.h:468 ISpxPropertyBagImpl::SetStringValue: this=0x057E8D54; name='RESULT-SynthesisBackend'; value='online (websocket)'
[699469]: 40302ms SPX_DBG_TRACE_VERBOSE: named_properties.h:468 ISpxPropertyBagImpl::SetStringValue: this=0x057E8D54; name='RESULT-SynthesisFirstByteLatencyMs'; value='-1'
[699469]: 40303ms SPX_DBG_TRACE_VERBOSE: named_properties.h:468 ISpxPropertyBagImpl::SetStringValue: this=0x057E8D54; name='RESULT-SynthesisFinishLatencyMs'; value='40023'
[699469]: 40303ms SPX_DBG_TRACE_VERBOSE: named_properties.h:468 ISpxPropertyBagImpl::SetStringValue: this=0x057E8D54; name='RESULT-SynthesisUnderrunTimeMs'; value='0'
[699469]: 40303ms SPX_DBG_TRACE_VERBOSE: named_properties.h:468 ISpxPropertyBagImpl::SetStringValue: this=0x057E8D54; name='CancellationDetails_ReasonDetailedText'; value='USP error: timeout waiting for the first audio chunk'
[699469]: 40303ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:162 Signal End of Writing is called
[699469]: 40303ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:162 Signal End of Writing is called
[699469]: 40303ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:30 CSpxSynthesisResult::~CSpxSynthesisResult
[207990]: 40303ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:1070 DispatchEvent task started...
[699469]: 40303ms SPX_DBG_TRACE_VERBOSE: handle_table.h:106 CSpxHandleTable::TrackHandle p=0x057D59A0
[207990]: 40303ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:496 [00E78808]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[699469]: 40303ms SPX_DBG_TRACE_VERBOSE: handle_table.h:116 CSpxHandleTable::TrackHandle class=ISpxSynthesisResult, h=0x057D59A0, p=0x057D59A0, tot=1
[207990]: 40303ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxSynthesisEventArgs' as '657912386'
[207990]: 40303ms SPX_DBG_TRACE_VERBOSE: handle_table.h:106 CSpxHandleTable::TrackHandle p=0x057DB600
[699469]: 40303ms SPX_DBG_TRACE_VERBOSE: handle_table.h:106 CSpxHandleTable::TrackHandle p=0x057D59B0
[207990]: 40303ms SPX_DBG_TRACE_VERBOSE: handle_table.h:116 CSpxHandleTable::TrackHandle class=ISpxSynthesisEventArgs, h=0x057DB600, p=0x057DB600, tot=1
[699469]: 40303ms SPX_DBG_TRACE_VERBOSE: handle_table.h:116 CSpxHandleTable::TrackHandle class=ISpxNamedProperties, h=0x057D59B0, p=0x057D59B0, tot=3
[207990]: 40303ms SPX_DBG_TRACE_VERBOSE: handle_table.h:106 CSpxHandleTable::TrackHandle p=0x057D59A0
[207990]: 40303ms SPX_DBG_TRACE_VERBOSE: handle_table.h:116 CSpxHandleTable::TrackHandle class=ISpxSynthesisResult, h=0x057D59A0, p=0x057D59A0, tot=2
[207990]: 40303ms SPX_DBG_TRACE_VERBOSE: handle_table.h:106 CSpxHandleTable::TrackHandle p=0x057D59B0
[207990]: 40303ms SPX_DBG_TRACE_VERBOSE: handle_table.h:116 CSpxHandleTable::TrackHandle class=ISpxNamedProperties, h=0x057D59B0, p=0x057D59B0, tot=4
[207990]: 40303ms SPX_TRACE_VERBOSE interop_factory.cs:83 Dispose(True) called for 057db600
[207990]: 40303ms SPX_DBG_TRACE_VERBOSE: handle_table.h:175 CSpxHandleTable::StopTracking(h) h=0x057DB600
[207990]: 40303ms SPX_DBG_TRACE_VERBOSE: handle_table.h:190 CSpxHandleTable::StopTracking(h) class=ISpxSynthesisEventArgs, h=0x057DB600, p=0x057DB600, tot=0
[207990]: 40303ms SPX_TRACE_VERBOSE interop_factory.cs:83 Dispose(True) called for 057d59b0
[207990]: 40303ms SPX_DBG_TRACE_VERBOSE: handle_table.h:175 CSpxHandleTable::StopTracking(h) h=0x057D59B0
[207990]: 40303ms SPX_DBG_TRACE_VERBOSE: handle_table.h:190 CSpxHandleTable::StopTracking(h) class=ISpxNamedProperties, h=0x057D59B0, p=0x057D59B0, tot=3
[207990]: 40303ms SPX_TRACE_VERBOSE interop_factory.cs:83 Dispose(True) called for 057d59a0
[207990]: 40303ms SPX_DBG_TRACE_VERBOSE: handle_table.h:175 CSpxHandleTable::StopTracking(h) h=0x057D59A0
[207990]: 40303ms SPX_DBG_TRACE_VERBOSE: handle_table.h:190 CSpxHandleTable::StopTracking(h) class=ISpxSynthesisResult, h=0x057D59A0, p=0x057D59A0, tot=1
[207990]: 40303ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:1070 DispatchEvent task complete!
[699469]: 40304ms SPX_DBG_TRACE_VERBOSE: named_properties.h:468 ISpxNamedProperties::GetStringValue: this=0x057D59B0; name='CancellationDetails_ReasonDetailedText'; value='USP error: timeout waiting for the first audio chunk'
[699469]: 40314ms SPX_TRACE_VERBOSE interop_factory.cs:83 Dispose(True) called for 00e78a84
[699469]: 40314ms SPX_DBG_TRACE_VERBOSE: handle_table.h:175 CSpxHandleTable::StopTracking(h) h=0x00E78A84
[699469]: 40314ms SPX_DBG_TRACE_VERBOSE: handle_table.h:190 CSpxHandleTable::StopTracking(h) class=ISpxNamedProperties, h=0x00E78A84, p=0x00E78A84, tot=2
[699469]: 40314ms SPX_TRACE_VERBOSE interop_factory.cs:83 Dispose(True) called for 00e78824
[699469]: 40314ms SPX_DBG_TRACE_FUNCTION: synthesizer.cpp:474 CSpxSynthesizer::SetDisposing
[699469]: 40314ms SPX_DBG_TRACE_VERBOSE: handle_table.h:175 CSpxHandleTable::StopTracking(h) h=0x00E78824
[699469]: 40314ms SPX_DBG_TRACE_VERBOSE: handle_table.h:190 CSpxHandleTable::StopTracking(h) class=ISpxSynthesizer, h=0x00E78824, p=0x00E78824, tot=0
[699469]: 40314ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:41 CSpxSynthesizer::~CSpxSynthesizer
[699469]: 40314ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x00E7D4F4
[699469]: 40314ms SPX_DBG_TRACE_SCOPE_ENTER: usp_tts_engine_adapter.cpp:74 CSpxUspTtsEngineAdapter::Term
[699469]: 40314ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x057E854C
[699469]: 40314ms SPX_DBG_TRACE_SCOPE_ENTER: usp_connection.cpp:144 Microsoft::CognitiveServices::Speech::USP::CSpxUspConnection::~CSpxUspConnection
[699469]: 40314ms SPX_DBG_TRACE_SCOPE_EXIT: usp_connection.cpp:144 Microsoft::CognitiveServices::Speech::USP::CSpxUspConnection::~CSpxUspConnection
[699469]: 40314ms SPX_DBG_TRACE_SCOPE_ENTER: web_socket.cpp:272 CSpxWebSocket::~CSpxWebSocket
[699469]: 40314ms SPX_DBG_TRACE_SCOPE_EXIT: web_socket.cpp:272 CSpxWebSocket::~CSpxWebSocket
[699469]: 40314ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:36 WebSocketAdapter::UwsWebSocket::~UwsWebSocket
[699469]: 40315ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:36 WebSocketAdapter::UwsWebSocket::~UwsWebSocket
[699469]: 40315ms SPX_DBG_TRACE_SCOPE_EXIT: usp_tts_engine_adapter.cpp:74 CSpxUspTtsEngineAdapter::Term
[699469]: 40315ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:53 CSpxUspTtsEngineAdapter::~CSpxUspTtsEngineAdapter: this=0x00E7D4C8
[699469]: 40315ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x00E636D4
[699469]: 40315ms SPX_DBG_TRACE_SCOPE_ENTER: thread_service.cpp:50 CSpxThreadService::Term
[699469]: 40315ms SPX_DBG_TRACE_SCOPE_EXIT: thread_service.cpp:50 CSpxThreadService::Term
[699469]: 40315ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x00E0F1FC
[699469]: 40315ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:41 CSpxSynthesizer::~CSpxSynthesizer
[699469]: 40315ms SPX_DBG_TRACE_SCOPE_ENTER: thread_service.cpp:50 CSpxThreadService::Term
[699469]: 40315ms SPX_DBG_TRACE_SCOPE_EXIT: thread_service.cpp:50 CSpxThreadService::Term
[956950]: 40315ms SPX_DBG_TRACE_SCOPE_EXIT: com_init_and_uninit.cpp:44 com worker_thread_proc done