[135946]: 50ms SPX_DBG_TRACE_FUNCTION: audio_stream_session.cpp:708 CSpxAudioStreamSession::AddRecognizer [135946]: 50ms SPX_DBG_TRACE_VERBOSE: handle_table.h:108 CSpxHandleTable::TrackHandle p=0x000002679AA01EF0 [135946]: 50ms SPX_DBG_TRACE_VERBOSE: handle_table.h:118 CSpxHandleTable::TrackHandle class=ISpxRecognizer, h=0x000002679AA01EF0, p=0x000002679AA01EF0, tot=1 [135946]: 50ms SPX_DBG_TRACE_SCOPE_EXIT: speechapi_c_factory.cpp:160 recognizer_create_speech_recognizer_from_config [135946]: 54ms SPX_DBG_TRACE_VERBOSE: handle_table.h:108 CSpxHandleTable::TrackHandle p=0x000002679AA02438 [135946]: 54ms SPX_DBG_TRACE_VERBOSE: handle_table.h:118 CSpxHandleTable::TrackHandle class=ISpxNamedProperties, h=0x000002679AA02438, p=0x000002679AA02438, tot=3 [763112]: 57ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679AA02438; name='SPEECH-RecoMode'; value='' [763112]: 57ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxPropertyBagImpl::SetStringValue: this=0x000002679903E038; name='SPEECH-RecoMode'; value='INTERACTIVE' [763112]: 57ms SPX_DBG_TRACE_FUNCTION: audio_stream_session.cpp:868 CSpxAudioStreamSession::RecognizeAsync [763112]: 57ms SPX_DBG_TRACE_VERBOSE: handle_table.h:108 CSpxHandleTable::TrackHandle p=0x0000026798AC0480 [763112]: 57ms SPX_DBG_TRACE_VERBOSE: handle_table.h:118 CSpxHandleTable::TrackHandle class=CSpxAsyncOp>, h=0x0000026798AC0480, p=0x0000026798AC0480, tot=1 [200808]: 57ms SPX_TRACE_INFO: thread_service.cpp:75 Started thread Background with ID [912532ll] [912532]: 57ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:901 *** CSpxAudioStreamSession::RecognizeAsync kicked-off THREAD started *** [912532]: 57ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:1155 CSpxAudioStreamSession::StartRecognizing [912532]: 57ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1156 [000002679903DF50]CSpxAudioStreamSession::StartRecognizing [912532]: 57ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3421 [000002679903DF50]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 0/0 => 3/1 [912532]: 57ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1176 [000002679903DF50]CSpxAudioStreamSession::StartRecognizing: Now WaitForPumpSetFormatStart ... [912532]: 57ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:3036 CSpxAudioStreamSession::StartAudioPump [912532]: 57ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3037 [000002679903DF50]CSpxAudioStreamSession::StartAudioPump: RecognitionKind 3 | m_audioPump [00000267987FDDD0] [912532]: 57ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1398 [000002679903DF50]CSpxAudioStreamSession::FireSessionStartedEvent: ... [912532]: 57ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1409 [000002679903DF50]CSpxAudioStreamSession::FireSessionStartedEvent: Firing SessionStarted event: SessionId: 236664cc8eb042298ab96089703f1a1e [912532]: 57ms SPX_TRACE_INFO: thread_service.cpp:75 Started thread User with ID [919924ll] [912532]: 57ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903E038; name='SPEECH-MaxBufferSizeMs'; value='66000' [912532]: 57ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903E038; name='SPEECH-BufferSizePercentSwitchToLowRate'; value='50' [919924]: 57ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:1676 DispatchEvent task started... [912532]: 57ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903E038; name='SPEECH-BufferSizePercentSwitchToHighRate'; value='9' [912532]: 57ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:100 [00000267989F1580] Is VAD Gating = 0, Is RNNT reco engine = 0, Is conversation transcriber = 0, Is async transcriber = 0, fastLane = 5000 msec (160000 bytes), maxDuration = 66000 msec, highDuration = 33000 msec, lowDuration = 5940 msec, lowRate = 100%, highRate = 200% [912532]: 57ms SPX_DBG_TRACE_FUNCTION: audio_buffer.cpp:92 PcmAudioBuffer::NewTurn [919924]: 57ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxSessionEventArgs' as '418547108' [912532]: 57ms SPX_DBG_TRACE_FUNCTION: audio_stream_session.cpp:2696 CSpxAudioStreamSession::EnsureInitRecoEngineAdapter [912532]: 57ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:2700 CSpxAudioStreamSession::EnsureInitRecoEngineAdapter EnsureResetEngineEngineAdapterComplete [919924]: 57ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:1676 DispatchEvent task complete! [912532]: 57ms SPX_DBG_TRACE_FUNCTION: audio_stream_session.cpp:2795 CSpxAudioStreamSession::EnsureResetEngineEngineAdapterComplete [912532]: 57ms SPX_DBG_TRACE_FUNCTION: audio_stream_session.cpp:2725 CSpxAudioStreamSession::InitRecoEngineAdapter [912532]: 57ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxUspRecoEngineAdapterRetry' as '806006714' [912532]: 57ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903E038; name='SPEECH-Region'; value='eastasia' [912532]: 57ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxPropertyBagImpl::SetStringValue: this=0x000002679903E038; name='INTENT-region'; value='eastasia' [912532]: 57ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3106 [000002679903DF50]CSpxAudioStreamSession::StartAudioPump: Starting pump[00000267987FDDD0]... [912532]: 57ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxBufferData' as '705421989' [912532]: 57ms SPX_TRACE_VERBOSE: buffer_data.cpp:114 [00000267989ED660]CSpxBufferData::EnsureInitRingBuffer - Init [912532]: 57ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxBlockingReadWriteRingBuffer' as '956428994' [912532]: 57ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903E038; name='AudioConfig_NumberOfChannelsForCapture'; value='1' [912532]: 57ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903E038; name='AudioConfig_SampleRateForCapture'; value='16000' [912532]: 57ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903E038; name='AudioConfig_BitsPerSampleForCapture'; value='16' [912532]: 57ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxAudioProcessorWriteToAudioSourceBuffer' as '354155511' [912532]: 57ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:109 [0000026798805720]CSpxAudioPump::StartPump(): [912532]: 58ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:513 [0000026798805720]CSpxAudioPump::WaitForPumpStart() ... pre m_cv.wait_for() [451715]: 58ms SPX_DBG_TRACE_SCOPE_ENTER: audio_pump.cpp:171 *** AudioPump THREAD started! *** [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:174 [0000026798805720]CSpxAudioPump::PumpThread(): getting format from reader... [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:184 CSpxAudioPump::PumpThread(): setting format on processor... [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:206 [0000026798805720]CSpxAudioPump::PumpThread(): allocating our first buffer [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:277 [0000026798805720]CSpxAudioPump::PumpThread(): Starting loop ... => frame size: 3200 | wFormatTag: 'PCM'| nChannels: 1 | nSamplesPerSec: 16000 | nAvgBytesPerSec: 32000 | nBlockAlign: 2 | wBitsPerSample: 16 | cbSize: 0 [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:242 [0000026798805720]CSpxAudioPump::PumpThread(), checkAndChangeState: changing states as requested: 'Idle' => 'Processing' [912532]: 58ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:519 [0000026798805720]CSpxAudioPump::WaitForPumpStart() ... post m_cv.wait_for(); state='Processing' (requestedState='Processing') [451715]: 58ms SPX_TRACE_INFO: wav_file_reader.cpp:256 AUDIO Data Chunk @46 length=100012 [912532]: 58ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:3036 CSpxAudioStreamSession::StartAudioPump [912532]: 58ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:1155 CSpxAudioStreamSession::StartRecognizing [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [451715]: 58ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxBufferProperties' as '809583448' [912532]: 58ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:901 *** CSpxAudioStreamSession::RecognizeAsync kicked-off THREAD stopped *** [912532]: 58ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:458 [000002679903DF50]CSpxAudioStreamSession::SetFormat: format != nullptr [451715]: 58ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxReadWriteRingBuffer' as '956428994' [912532]: 58ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3421 [000002679903DF50]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 3/1 => 3/2 [912532]: 58ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:463 [000002679903DF50]CSpxAudioStreamSession::SetFormat: Now ProcessingAudio ... [912532]: 58ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:3237 CSpxAudioStreamSession::InformAdapterSetFormatStarting [451715]: 58ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxReadWriteRingBuffer' as '956428994' [912532]: 58ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter_retry.cpp:19 CSpxUspRecoEngineAdapterRetry::InitDelegatePtr [912532]: 58ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:48 CSpxUspRecoEngineAdapter::CSpxUspRecoEngineAdapter [451715]: 58ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6454036Z, size:3200. [912532]: 58ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:49 CSpxUspRecoEngineAdapter::CSpxUspRecoEngineAdapter: this=0x0000026798BCFDF0 [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 0 bytes (0 msec) [912532]: 58ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:48 CSpxUspRecoEngineAdapter::CSpxUspRecoEngineAdapter [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 3200, unacknowledgedAudioDuration = 0 msec, packetAudioDelay = 0 msec [912532]: 58ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxUspRecoEngineAdapter' as '806006714' [912532]: 58ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:62 CSpxUspRecoEngineAdapter::Init [912532]: 58ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:63 CSpxUspRecoEngineAdapter::Init: this=0x0000026798BCFDF0 [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [912532]: 58ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:62 CSpxUspRecoEngineAdapter::Init [451715]: 58ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6454868Z, size:3200. [912532]: 58ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter_retry.cpp:19 CSpxUspRecoEngineAdapterRetry::InitDelegatePtr [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 0 bytes (0 msec) [912532]: 58ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:112 CSpxUspRecoEngineAdapter::SetAdapterMode: singleShot=1 [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 6400, unacknowledgedAudioDuration = 0 msec, packetAudioDelay = 0 msec [912532]: 58ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:216 CSpxUspRecoEngineAdapter::SetFormat [912532]: 58ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:217 CSpxUspRecoEngineAdapter::SetFormat: this=0x0000026798BCFDF0 [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [912532]: 58ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:228 CSpxUspRecoEngineAdapter::SetFormat wFormatTag: PCM nChannels: 1 nSamplesPerSec: 16000 nAvgBytesPerSec: 32000 nBlockAlign: 2 wBitsPerSample: 16 cbSize: 0 [912532]: 58ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2798 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 0/0 => 1/0 USP-START [451715]: 58ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6455440Z, size:3200. [912532]: 58ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:250 CSpxUspRecoEngineAdapter::SetFormat: (0x0000026798BCFDF0)->PrepareFirstAudioReadyState() [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 0 bytes (0 msec) [912532]: 58ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2967 CSpxUspRecoEngineAdapter::ResetBeforeFirstAudio: this=0x0000026798BCFDF0 ... USP-RESET [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 9600, unacknowledgedAudioDuration = 0 msec, packetAudioDelay = 0 msec [912532]: 58ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2814 CSpxUspRecoEngineAdapter::PrepareCompressionCodec: Prepare compression codec. [912532]: 58ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='SPEECH-Compression-Codec-Module'; value='' [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [912532]: 58ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2876 CSpxUspRecoEngineAdapter::PrepareFirstAudioReadyState: (0x0000026798BCFDF0)->PrepareCompressionCodec() result: 4. Sending the audio uncompressed [451715]: 58ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6455992Z, size:3200. [912532]: 58ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='AZAC-SDK-PROGRAMMING-LANGUAGE'; value='Java' [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 0 bytes (0 msec) [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 12800, unacknowledgedAudioDuration = 0 msec, packetAudioDelay = 0 msec [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [912532]: 58ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxPropertyBagImpl::SetStringValue: this=0x000002679903E038; name='HttpHeader#User-agent'; value='SpeechSDK-Java/1.25.0 Windows Client 10' [912532]: 58ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:340 CSpxUspRecoEngineAdapter::UspInitialize: this=0x0000026798BCFDF0 [451715]: 58ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6456593Z, size:3200. [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 0 bytes (0 msec) [912532]: 58ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxUspCallbackWrapper' as '978711522' [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 16000, unacknowledgedAudioDuration = 0 msec, packetAudioDelay = 0 msec [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [912532]: 58ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='SessionId'; value='236664cc8eb042298ab96089703f1a1e' [451715]: 58ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6457951Z, size:3200. [912532]: 58ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1812 CSpxAudioStreamSession::GetScenarioCount: countSpeech=1; countIntent=0; countTranslation=0; countDialog=0, countTranscriber=0, countLanguageId=0 [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 0 bytes (0 msec) [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 19200, unacknowledgedAudioDuration = 0 msec, packetAudioDelay = 0 msec [912532]: 58ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:734 CSpxUspRecoEngineAdapter::SetUspEndpointDefaultSpeechService: Endpoint type: Speech. [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [912532]: 58ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='SPEECH-Region'; value='eastasia' [451715]: 58ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6458870Z, size:3200. [912532]: 58ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxPropertyBagImpl::SetStringValue: this=0x000002679903E038; name='SPEECH-RecoLanguage'; value='en-us' [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 0 bytes (0 msec) [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 22400, unacknowledgedAudioDuration = 0 msec, packetAudioDelay = 0 msec [912532]: 58ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='SPEECH-RecoLanguage'; value='en-us' [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [451715]: 58ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6459436Z, size:3200. [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 0 bytes (0 msec) [912532]: 58ms SPX_TRACE_INFO: usp_reco_engine_adapter.cpp:1044 SpeechServiceConnection_LanguageIdMode not set or has invalid value . [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 25600, unacknowledgedAudioDuration = 0 msec, packetAudioDelay = 0 msec [912532]: 58ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:614 CSpxUspRecoEngineAdapter::SetUspLanguageIdModeAndPriority: languageIdMode=0, languageIdPriority=0 [912532]: 58ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='SPEECH-RecoMode'; value='INTERACTIVE' [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [912532]: 58ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:588 CSpxUspRecoEngineAdapter::SetUspRecoMode: recoMode=0 [912532]: 58ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:575 CSpxUspRecoEngineAdapter::SetUspEndpoint: Setting Websocket Polling interval to 10 [451715]: 58ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6459999Z, size:3200. [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 0 bytes (0 msec) [912532]: 58ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='SPEECH-SubscriptionKey'; value='******************************06' [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 28800, unacknowledgedAudioDuration = 0 msec, packetAudioDelay = 0 msec [912532]: 58ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::FindPrefix: this=0x0000026798BCFE60; name='User-agent'; value='SpeechSDK-Java/1.25.0 Windows Client 10' [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [912532]: 58ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='SPEECH-ProxyHostBypass'; value='' [451715]: 58ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6460896Z, size:3200. [451715]: 58ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 0 bytes (0 msec) [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 32000, unacknowledgedAudioDuration = 0 msec, packetAudioDelay = 0 msec [912532]: 59ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='AZAC-SDK-PROGRAMMING-LANGUAGE'; value='Java' [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [912532]: 59ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='AudioConfig_AudioSource'; value='File' [451715]: 59ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6461500Z, size:3200. [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 0 bytes (0 msec) [912532]: 59ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='AudioConfig_SampleRateForCapture'; value='16000' [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 35200, unacknowledgedAudioDuration = 0 msec, packetAudioDelay = 0 msec [912532]: 59ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='AudioConfig_BitsPerSampleForCapture'; value='16' [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [912532]: 59ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='AudioConfig_NumberOfChannelsForCapture'; value='1' [451715]: 59ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6462039Z, size:3200. [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 0 bytes (0 msec) [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 38400, unacknowledgedAudioDuration = 0 msec, packetAudioDelay = 0 msec [912532]: 59ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxUspConnection' as '792041254' [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [912532]: 59ms SPX_TRACE_INFO: usp_connection.cpp:460 Microsoft::CognitiveServices::Speech::USP::CSpxUspConnection::Connect: entering... [451715]: 59ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6462611Z, size:3200. [912532]: 59ms SPX_TRACE_INFO: usp_connection.cpp:477 Adding subscription key headers [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 0 bytes (0 msec) [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 41600, unacknowledgedAudioDuration = 0 msec, packetAudioDelay = 0 msec [912532]: 59ms SPX_TRACE_INFO: usp_connection.cpp:512 Set a user defined HTTP header 'User-agent':'SpeechSDK-Java/1.25.0 Windows Client 10' [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [912532]: 59ms SPX_TRACE_INFO: usp_connection.cpp:527 connectionUrl=wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us [451715]: 59ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6463184Z, size:3200. [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 0 bytes (0 msec) [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 44800, unacknowledgedAudioDuration = 0 msec, packetAudioDelay = 0 msec [912532]: 59ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxTelemetry' as '151041793' [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [451715]: 59ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6463726Z, size:3200. [912532]: 59ms SPX_DBG_TRACE_SCOPE_ENTER: web_socket.cpp:216 CSpxWebSocket::CSpxWebSocket [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 0 bytes (0 msec) [912532]: 59ms SPX_DBG_TRACE_SCOPE_EXIT: web_socket.cpp:216 CSpxWebSocket::CSpxWebSocket [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 48000, unacknowledgedAudioDuration = 0 msec, packetAudioDelay = 0 msec [912532]: 59ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxWebSocket' as '482598724' [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [451715]: 59ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6464314Z, size:3200. [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 0 bytes (0 msec) [912532]: 59ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:31 WebSocketAdapter::UwsWebSocket::UwsWebSocket [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 51200, unacknowledgedAudioDuration = 0 msec, packetAudioDelay = 0 msec [912532]: 59ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:31 WebSocketAdapter::UwsWebSocket::UwsWebSocket [912532]: 59ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:43 WebSocketAdapter::UwsWebSocket::Initialize [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [912532]: 59ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:43 WebSocketAdapter::UwsWebSocket::Initialize [451715]: 59ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6464960Z, size:3200. [912532]: 59ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 0 bytes (0 msec) [912532]: 59ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 54400, unacknowledgedAudioDuration = 0 msec, packetAudioDelay = 0 msec [912532]: 59ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 59ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 59ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 59ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [451715]: 59ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6465582Z, size:3200. [912532]: 59ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxPropertyBagImpl::SetStringValue: this=0x000002679903E038; name='SPEECH-ConnectionUrl'; value='wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us' [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 0 bytes (0 msec) [912532]: 59ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1097 speech.config {"context":{"system":{"version":"1.25.0","name":"SpeechSDK","build":"Windows-x64","lang":"Java"},"os":{"name":"Client","version":"10","platform":"Windows"},"audio":{"source":{"type":"File","model":"","samplerate":"16000","bitspersample":"16","channelcount":"1"}}}} [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 57600, unacknowledgedAudioDuration = 0 msec, packetAudioDelay = 0 msec [912532]: 59ms SPX_TRACE_INFO: usp_connection.cpp:762 Create requestId for messageType 0 [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [912532]: 59ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:216 CSpxUspRecoEngineAdapter::SetFormat [451715]: 59ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6466129Z, size:3200. [912532]: 59ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:3237 CSpxAudioStreamSession::InformAdapterSetFormatStarting [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 0 bytes (0 msec) [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 60800, unacknowledgedAudioDuration = 0 msec, packetAudioDelay = 0 msec [912532]: 59ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2798 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 1/0 => 2/1000 [912532]: 59ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:280 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0)->PrepareUspAudioStream() ... size=3200 [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [451715]: 59ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6466636Z, size:3200. [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 3200 bytes (100 msec) [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 64000, unacknowledgedAudioDuration = 100 msec, packetAudioDelay = 0 msec [912532]: 59ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1184 speech.context='{"audio":{"streams":{"1":null}}}' [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [451715]: 59ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6467156Z, size:3200. [912532]: 59ms SPX_TRACE_INFO: usp_connection.cpp:609 RequestId: 'bd88186f30a14f6e80523af5200851a6' [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 3200 bytes (100 msec) [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 67200, unacknowledgedAudioDuration = 100 msec, packetAudioDelay = 0 msec [912532]: 59ms SPX_TRACE_INFO: usp_connection.cpp:762 Create requestId bd88186f30a14f6e80523af5200851a6 for messageType 1 [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [912532]: 59ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 44) [912532]: 59ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 44 bytes audio data. [451715]: 59ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6468138Z, size:3200. [912532]: 59ms SPX_TRACE_INFO: usp_connection.cpp:799 The current speech request id is bd88186f30a14f6e80523af5200851a6 [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 3200 bytes (100 msec) [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 70400, unacknowledgedAudioDuration = 100 msec, packetAudioDelay = 0 msec [912532]: 59ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 59ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [912532]: 59ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:284 CSpxUspRecoEngineAdapter::ProcessAudio: site->AdapterStartingTurn() [912532]: 59ms SPX_DBG_TRACE_FUNCTION: audio_stream_session.cpp:1861 CSpxAudioStreamSession::AdapterStartingTurn [451715]: 59ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6468654Z, size:3200. [912532]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 3200 bytes (100 msec) [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 73600, unacknowledgedAudioDuration = 100 msec, packetAudioDelay = 0 msec [912532]: 59ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 59ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 59ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [912532]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [451715]: 59ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6469178Z, size:3200. [912532]: 59ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 9600 bytes (300 msec) [912532]: 59ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 76800, unacknowledgedAudioDuration = 300 msec, packetAudioDelay = 0 msec [912532]: 59ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [912532]: 59ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 59ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [451715]: 59ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6469726Z, size:3200. [912532]: 59ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 12800 bytes (400 msec) [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 80000, unacknowledgedAudioDuration = 400 msec, packetAudioDelay = 0 msec [912532]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [912532]: 59ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 59ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [912532]: 59ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [451715]: 59ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6470263Z, size:3200. [912532]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 16000 bytes (500 msec) [912532]: 59ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 83200, unacknowledgedAudioDuration = 500 msec, packetAudioDelay = 0 msec [912532]: 59ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 59ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [912532]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [912532]: 59ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [451715]: 59ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6470827Z, size:3200. [912532]: 59ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 22400 bytes (700 msec) [912532]: 59ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [451715]: 59ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 86400, unacknowledgedAudioDuration = 700 msec, packetAudioDelay = 0 msec [912532]: 60ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [451715]: 60ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [912532]: 60ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 60ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 60ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [451715]: 60ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6471382Z, size:3200. [451715]: 60ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 25600 bytes (800 msec) [912532]: 60ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [451715]: 60ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 89600, unacknowledgedAudioDuration = 800 msec, packetAudioDelay = 0 msec [912532]: 60ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 60ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [451715]: 60ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [912532]: 60ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [451715]: 60ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6471923Z, size:3200. [912532]: 60ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [451715]: 60ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 28800 bytes (900 msec) [912532]: 60ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [451715]: 60ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 92800, unacknowledgedAudioDuration = 900 msec, packetAudioDelay = 0 msec [912532]: 60ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 60ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [451715]: 60ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [912532]: 60ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [912532]: 60ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [451715]: 60ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6472453Z, size:3200. [912532]: 60ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [451715]: 60ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 35200 bytes (1100 msec) [912532]: 60ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [451715]: 60ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 96000, unacknowledgedAudioDuration = 1100 msec, packetAudioDelay = 0 msec [912532]: 60ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [912532]: 60ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [451715]: 60ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 3200 [912532]: 60ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 60ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [451715]: 60ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6473058Z, size:3200. [912532]: 60ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [451715]: 60ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 38400 bytes (1200 msec) [912532]: 60ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [451715]: 60ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 99200, unacknowledgedAudioDuration = 1200 msec, packetAudioDelay = 0 msec [912532]: 60ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 60ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 60ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [451715]: 60ms SPX_TRACE_INFO: wav_file_reader.cpp:300 AUDIO Data chunk read - NO MORE DATA!! Requested: 0, Actual: 0 (End of stream) [912532]: 60ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [451715]: 60ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 812 [912532]: 60ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 60ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [451715]: 60ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6473789Z, size:812. [912532]: 60ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [451715]: 60ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 44800 bytes (1400 msec) [912532]: 60ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [451715]: 60ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 100012, unacknowledgedAudioDuration = 1400 msec, packetAudioDelay = 0 msec [912532]: 60ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 60ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [451715]: 60ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:443 [0000026798805720]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 0 [912532]: 60ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [451715]: 60ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:526 Received audio chunk: time: 2023-04-10T08:24:15.6474259Z, size:0. [912532]: 60ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [451715]: 60ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:540 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 51200 bytes (1600 msec) [912532]: 60ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [451715]: 60ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:161 [00000267989F1580] m_bytesTransited = 100012, unacknowledgedAudioDuration = 1600 msec, packetAudioDelay = 0 msec [912532]: 60ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [451715]: 60ms SPX_TRACE_INFO: audio_pump.cpp:458 [0000026798805720]CSpxAudioPump::PumpThread(): m_reader->Read() read ZERO (0) bytes... Indicating end of stream based input. [912532]: 60ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [451715]: 60ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:242 [0000026798805720]CSpxAudioPump::PumpThread(), checkAndChangeState: changing states as requested: 'Processing' => 'Idle' [912532]: 60ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [451715]: 60ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:255 [0000026798805720]CSpxAudioPump::PumpThread(): checkAndChangeState: about to exit the while loop, pre-SetFormat(nullptr) [912532]: 60ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 60ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [451715]: 60ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:260 [0000026798805720]CSpxAudioPump::PumpThread(): checkAndChangeState: about to exit the while loop, post-SetFormat(nullptr) [451715]: 60ms SPX_DBG_TRACE_SCOPE_EXIT: audio_pump.cpp:171 *** AudioPump THREAD stopped! *** [912532]: 60ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [912532]: 60ms SPX_TRACE_INFO: web_socket.cpp:800 CSpxWebSocket::DoWork: open transport. [912532]: 60ms SPX_TRACE_INFO: web_socket.cpp:479 Start to open websocket. WebSocket: 0x98add880, wsio handle: 0x99a29800 [912532]: 60ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:149 WebSocketAdapter::UwsWebSocket::Open [912532]: 68ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:149 WebSocketAdapter::UwsWebSocket::Open [912532]: 68ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 68ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 68ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:9, Write 3200 bytes audio data. [912532]: 69ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 69ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:10, Write 3200 bytes audio data. [912532]: 69ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 69ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:10, Write 3200 bytes audio data. [912532]: 69ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 69ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:10, Write 3200 bytes audio data. [912532]: 69ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 69ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:10, Write 3200 bytes audio data. [912532]: 69ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 69ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:10, Write 3200 bytes audio data. [912532]: 69ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 69ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:10, Write 3200 bytes audio data. [912532]: 69ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 69ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:10, Write 3200 bytes audio data. [912532]: 69ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 69ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:10, Write 3200 bytes audio data. [912532]: 69ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 69ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:10, Write 3200 bytes audio data. [912532]: 69ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 69ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:10, Write 3200 bytes audio data. [912532]: 69ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 69ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:10, Write 3200 bytes audio data. [912532]: 69ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 69ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:10, Write 3200 bytes audio data. [912532]: 69ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 69ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:10, Write 3200 bytes audio data. [912532]: 69ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=812 [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 812) [912532]: 69ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:10, Write 812 bytes audio data. [912532]: 69ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [912532]: 69ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:294 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Flushing Audio ... size=0 USP-FLUSH [912532]: 69ms SPX_TRACE_INFO: usp_connection.cpp:815 TS:10, Flush audio buffer. [912532]: 69ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [912532]: 69ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:458 [000002679903DF50]CSpxAudioStreamSession::SetFormat: format == nullptr [912532]: 69ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3431 [000002679903DF50]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 3/2 doesn't match [912532]: 69ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3421 [000002679903DF50]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 3/2 => 3/6 [912532]: 69ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:477 [000002679903DF50]CSpxAudioStreamSession::SetFormat: Have seen the end of the stream on the client, processing audio leftovers ... [912532]: 5075ms SPX_TRACE_ERROR: AZ_LOG_ERROR: socketio_win32.c:543 Socketio_Failure: Receiving data from endpoint: 10054. [912532]: 5075ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:247 WebSocketAdapter::UwsWebSocket::OnWebSocketOpened [912532]: 5075ms SPX_TRACE_ERROR: web_socket.cpp:907 WS open operation failed with result=1(WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED), code=2[0x00000002], time=2023-04-10T08:24:20.6626760Z [912532]: 5075ms SPX_TRACE_INFO: usp_connection.cpp:908 TS:5016, TransportError: connection:0x98d31520, code=5, string=Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us X-ConnectionId: 236664cc8eb042298ab96089703f1a1e [912532]: 5075ms SPX_TRACE_ERROR: usp_reco_engine_adapter.cpp:2074 Response: On Error: Code:5, Message: Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us X-ConnectionId: 236664cc8eb042298ab96089703f1a1e. [912532]: 5075ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2798 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 2/1000 => 2/-1 USP-ERRORERROR [912532]: 5075ms SPX_TRACE_ERROR: usp_reco_engine_adapter.cpp:2088 CSpxUspRecoEngineAdapter::OnError: site->Error() ... error='Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us X-ConnectionId: 236664cc8eb042298ab96089703f1a1e' [912532]: 5075ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter_retry.cpp:134 CSpxUspRecoEngineAdapterRetry::ShouldReconnect: Should Reconnect: 1 [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter_retry.cpp:94 CSpxUspRecoEngineAdapterRetry::StartReconnect: Trying to reset the engine adapter [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x0000026798BCFE48 [912532]: 5341ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:91 Terminating CSpxUspRecoEngineAdapter... [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:92 CSpxUspRecoEngineAdapter::Term: this=0x0000026798BCFDF0 [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2798 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 2/-1 => 2/9998 USP-TERMINATING [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:96 CSpxUspRecoEngineAdapter::Term: Terminating USP Connection (0x0000026798D31568) [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x00000267992111A8 [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2798 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 2/9998 => 2/9999 USP-ZOMBIE [912532]: 5341ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:91 Terminating CSpxUspRecoEngineAdapter... Done! [912532]: 5341ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter_retry.cpp:19 CSpxUspRecoEngineAdapterRetry::InitDelegatePtr [912532]: 5341ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:48 CSpxUspRecoEngineAdapter::CSpxUspRecoEngineAdapter [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:49 CSpxUspRecoEngineAdapter::CSpxUspRecoEngineAdapter: this=0x000002679903D010 [912532]: 5341ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:48 CSpxUspRecoEngineAdapter::CSpxUspRecoEngineAdapter [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxUspRecoEngineAdapter' as '806006714' [912532]: 5341ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:62 CSpxUspRecoEngineAdapter::Init [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:63 CSpxUspRecoEngineAdapter::Init: this=0x000002679903D010 [912532]: 5341ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:62 CSpxUspRecoEngineAdapter::Init [912532]: 5341ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter_retry.cpp:19 CSpxUspRecoEngineAdapterRetry::InitDelegatePtr [912532]: 5341ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:216 CSpxUspRecoEngineAdapter::SetFormat [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:217 CSpxUspRecoEngineAdapter::SetFormat: this=0x000002679903D010 [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:228 CSpxUspRecoEngineAdapter::SetFormat wFormatTag: PCM nChannels: 1 nSamplesPerSec: 16000 nAvgBytesPerSec: 32000 nBlockAlign: 2 wBitsPerSample: 16 cbSize: 0 [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2798 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 0/0 => 1/0 USP-START [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:250 CSpxUspRecoEngineAdapter::SetFormat: (0x000002679903D010)->PrepareFirstAudioReadyState() [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2967 CSpxUspRecoEngineAdapter::ResetBeforeFirstAudio: this=0x000002679903D010 ... USP-RESET [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2814 CSpxUspRecoEngineAdapter::PrepareCompressionCodec: Prepare compression codec. [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='SPEECH-Compression-Codec-Module'; value='' [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2876 CSpxUspRecoEngineAdapter::PrepareFirstAudioReadyState: (0x000002679903D010)->PrepareCompressionCodec() result: 4. Sending the audio uncompressed [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='AZAC-SDK-PROGRAMMING-LANGUAGE'; value='Java' [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxPropertyBagImpl::SetStringValue: this=0x000002679903E038; name='HttpHeader#User-agent'; value='SpeechSDK-Java/1.25.0 Windows Client 10' [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:340 CSpxUspRecoEngineAdapter::UspInitialize: this=0x000002679903D010 [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxUspCallbackWrapper' as '978711522' [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='SessionId'; value='236664cc8eb042298ab96089703f1a1e' [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1812 CSpxAudioStreamSession::GetScenarioCount: countSpeech=1; countIntent=0; countTranslation=0; countDialog=0, countTranscriber=0, countLanguageId=0 [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:734 CSpxUspRecoEngineAdapter::SetUspEndpointDefaultSpeechService: Endpoint type: Speech. [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='SPEECH-Region'; value='eastasia' [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='SPEECH-RecoLanguage'; value='en-us' [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='SPEECH-RecoLanguage'; value='en-us' [912532]: 5341ms SPX_TRACE_INFO: usp_reco_engine_adapter.cpp:1044 SpeechServiceConnection_LanguageIdMode not set or has invalid value . [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:614 CSpxUspRecoEngineAdapter::SetUspLanguageIdModeAndPriority: languageIdMode=0, languageIdPriority=0 [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='SPEECH-RecoMode'; value='INTERACTIVE' [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:588 CSpxUspRecoEngineAdapter::SetUspRecoMode: recoMode=0 [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:575 CSpxUspRecoEngineAdapter::SetUspEndpoint: Setting Websocket Polling interval to 10 [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='SPEECH-SubscriptionKey'; value='******************************06' [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::FindPrefix: this=0x000002679903D080; name='User-agent'; value='SpeechSDK-Java/1.25.0 Windows Client 10' [912532]: 5341ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='SPEECH-ProxyHostBypass'; value='' [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='AZAC-SDK-PROGRAMMING-LANGUAGE'; value='Java' [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='AudioConfig_AudioSource'; value='File' [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='AudioConfig_SampleRateForCapture'; value='16000' [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='AudioConfig_BitsPerSampleForCapture'; value='16' [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='AudioConfig_NumberOfChannelsForCapture'; value='1' [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxUspConnection' as '792041254' [912532]: 5342ms SPX_TRACE_INFO: usp_connection.cpp:460 Microsoft::CognitiveServices::Speech::USP::CSpxUspConnection::Connect: entering... [912532]: 5342ms SPX_TRACE_INFO: usp_connection.cpp:477 Adding subscription key headers [912532]: 5342ms SPX_TRACE_INFO: usp_connection.cpp:512 Set a user defined HTTP header 'User-agent':'SpeechSDK-Java/1.25.0 Windows Client 10' [912532]: 5342ms SPX_TRACE_INFO: usp_connection.cpp:527 connectionUrl=wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us [912532]: 5342ms SPX_DBG_TRACE_SCOPE_ENTER: web_socket.cpp:216 CSpxWebSocket::CSpxWebSocket [912532]: 5342ms SPX_DBG_TRACE_SCOPE_EXIT: web_socket.cpp:216 CSpxWebSocket::CSpxWebSocket [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxWebSocket' as '482598724' [912532]: 5342ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:31 WebSocketAdapter::UwsWebSocket::UwsWebSocket [912532]: 5342ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:31 WebSocketAdapter::UwsWebSocket::UwsWebSocket [912532]: 5342ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:43 WebSocketAdapter::UwsWebSocket::Initialize [912532]: 5342ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:43 WebSocketAdapter::UwsWebSocket::Initialize [912532]: 5342ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 5342ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 5342ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 5342ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 5342ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 5342ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxPropertyBagImpl::SetStringValue: this=0x000002679903E038; name='SPEECH-ConnectionUrl'; value='wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us' [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1097 speech.config {"context":{"system":{"version":"1.25.0","name":"SpeechSDK","build":"Windows-x64","lang":"Java"},"os":{"name":"Client","version":"10","platform":"Windows"},"audio":{"source":{"type":"File","model":"","samplerate":"16000","bitspersample":"16","channelcount":"1"}}}} [912532]: 5342ms SPX_TRACE_INFO: usp_connection.cpp:762 Create requestId for messageType 0 [912532]: 5342ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:216 CSpxUspRecoEngineAdapter::SetFormat [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1875 CSpxAudioStreamSession::AdapterStoppedTurn: set m_adapterStreamingAudio to false [912532]: 5342ms SPX_DBG_TRACE_FUNCTION: audio_buffer.cpp:92 PcmAudioBuffer::NewTurn [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1897 [000002679903DF50]CSpxAudioStreamSession::AdapterStoppedTurn: m_currentTurnGlobalOffset=0, previousTurnGlobalOffset=0 bufferedBytes=100012 [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2798 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 1/0 => 2/1000 [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:280 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010)->PrepareUspAudioStream() ... size=3200 [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1184 speech.context='{"audio":{"streams":{"1":null}}}' [912532]: 5342ms SPX_TRACE_INFO: usp_connection.cpp:609 RequestId: 'b512c96f0ae44ac2b5076ab07389fffb' [912532]: 5342ms SPX_TRACE_INFO: usp_connection.cpp:762 Create requestId b512c96f0ae44ac2b5076ab07389fffb for messageType 1 [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 44) [912532]: 5342ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 44 bytes audio data. [912532]: 5342ms SPX_TRACE_INFO: usp_connection.cpp:799 The current speech request id is b512c96f0ae44ac2b5076ab07389fffb [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5342ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:284 CSpxUspRecoEngineAdapter::ProcessAudio: site->AdapterStartingTurn() [912532]: 5342ms SPX_DBG_TRACE_FUNCTION: audio_stream_session.cpp:1861 CSpxAudioStreamSession::AdapterStartingTurn [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5342ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5342ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5342ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5342ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5342ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5342ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5342ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5342ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5342ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5342ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5342ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 5342ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5343ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5343ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5343ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5343ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5343ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5343ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5343ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5343ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5343ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5343ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5343ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5343ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5343ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5343ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5343ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5343ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5343ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5343ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 5343ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=812 [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 812) [912532]: 5343ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 812 bytes audio data. [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:294 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Flushing Audio ... size=0 USP-FLUSH [912532]: 5343ms SPX_TRACE_INFO: usp_connection.cpp:815 TS:1, Flush audio buffer. [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1938 CSpxAudioStreamSession::AdapterStoppedTurn: set m_adapterAudioMuted to false [912532]: 5343ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:54 CSpxUspRecoEngineAdapter::~CSpxUspRecoEngineAdapter [912532]: 5343ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:55 CSpxUspRecoEngineAdapter::~CSpxUspRecoEngineAdapter: this=0x0000026798BCFDF0 [912532]: 5343ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:54 CSpxUspRecoEngineAdapter::~CSpxUspRecoEngineAdapter [912532]: 5343ms SPX_DBG_TRACE_SCOPE_ENTER: usp_connection.cpp:144 Microsoft::CognitiveServices::Speech::USP::CSpxUspConnection::~CSpxUspConnection [912532]: 5343ms SPX_DBG_TRACE_SCOPE_EXIT: usp_connection.cpp:144 Microsoft::CognitiveServices::Speech::USP::CSpxUspConnection::~CSpxUspConnection [912532]: 5343ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:247 WebSocketAdapter::UwsWebSocket::OnWebSocketOpened [912532]: 5343ms SPX_TRACE_INFO: web_socket.cpp:800 CSpxWebSocket::DoWork: open transport. [912532]: 5343ms SPX_TRACE_INFO: web_socket.cpp:479 Start to open websocket. WebSocket: 0x98adeac0, wsio handle: 0x99810800 [912532]: 5343ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:149 WebSocketAdapter::UwsWebSocket::Open [912532]: 5347ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:149 WebSocketAdapter::UwsWebSocket::Open [912532]: 5357ms SPX_DBG_TRACE_SCOPE_ENTER: web_socket.cpp:221 CSpxWebSocket::~CSpxWebSocket [912532]: 5357ms SPX_DBG_TRACE_SCOPE_EXIT: web_socket.cpp:221 CSpxWebSocket::~CSpxWebSocket [912532]: 5357ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:36 WebSocketAdapter::UwsWebSocket::~UwsWebSocket [912532]: 5357ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:36 WebSocketAdapter::UwsWebSocket::~UwsWebSocket [912532]: 10349ms SPX_TRACE_ERROR: AZ_LOG_ERROR: socketio_win32.c:543 Socketio_Failure: Receiving data from endpoint: 10054. [912532]: 10349ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:247 WebSocketAdapter::UwsWebSocket::OnWebSocketOpened [912532]: 10350ms SPX_TRACE_ERROR: web_socket.cpp:907 WS open operation failed with result=1(WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED), code=2[0x00000002], time=2023-04-10T08:24:25.9370943Z [912532]: 10350ms SPX_TRACE_INFO: usp_connection.cpp:908 TS:5008, TransportError: connection:0x98d32630, code=5, string=Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us X-ConnectionId: 236664cc8eb042298ab96089703f1a1e [912532]: 10350ms SPX_TRACE_ERROR: usp_reco_engine_adapter.cpp:2074 Response: On Error: Code:5, Message: Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us X-ConnectionId: 236664cc8eb042298ab96089703f1a1e. [912532]: 10350ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2798 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 2/1000 => 2/-1 USP-ERRORERROR [912532]: 10350ms SPX_TRACE_ERROR: usp_reco_engine_adapter.cpp:2088 CSpxUspRecoEngineAdapter::OnError: site->Error() ... error='Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us X-ConnectionId: 236664cc8eb042298ab96089703f1a1e' [912532]: 10350ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter_retry.cpp:134 CSpxUspRecoEngineAdapterRetry::ShouldReconnect: Should Reconnect: 1 [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter_retry.cpp:94 CSpxUspRecoEngineAdapterRetry::StartReconnect: Trying to reset the engine adapter [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x000002679903D068 [912532]: 10614ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:91 Terminating CSpxUspRecoEngineAdapter... [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:92 CSpxUspRecoEngineAdapter::Term: this=0x000002679903D010 [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2798 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 2/-1 => 2/9998 USP-TERMINATING [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:96 CSpxUspRecoEngineAdapter::Term: Terminating USP Connection (0x0000026798D32678) [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x000002679B02E218 [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2798 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 2/9998 => 2/9999 USP-ZOMBIE [912532]: 10614ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:91 Terminating CSpxUspRecoEngineAdapter... Done! [912532]: 10614ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter_retry.cpp:19 CSpxUspRecoEngineAdapterRetry::InitDelegatePtr [912532]: 10614ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:48 CSpxUspRecoEngineAdapter::CSpxUspRecoEngineAdapter [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:49 CSpxUspRecoEngineAdapter::CSpxUspRecoEngineAdapter: this=0x0000026798BCFDF0 [912532]: 10614ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:48 CSpxUspRecoEngineAdapter::CSpxUspRecoEngineAdapter [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxUspRecoEngineAdapter' as '806006714' [912532]: 10614ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:62 CSpxUspRecoEngineAdapter::Init [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:63 CSpxUspRecoEngineAdapter::Init: this=0x0000026798BCFDF0 [912532]: 10614ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:62 CSpxUspRecoEngineAdapter::Init [912532]: 10614ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter_retry.cpp:19 CSpxUspRecoEngineAdapterRetry::InitDelegatePtr [912532]: 10614ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:216 CSpxUspRecoEngineAdapter::SetFormat [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:217 CSpxUspRecoEngineAdapter::SetFormat: this=0x0000026798BCFDF0 [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:228 CSpxUspRecoEngineAdapter::SetFormat wFormatTag: PCM nChannels: 1 nSamplesPerSec: 16000 nAvgBytesPerSec: 32000 nBlockAlign: 2 wBitsPerSample: 16 cbSize: 0 [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2798 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 0/0 => 1/0 USP-START [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:250 CSpxUspRecoEngineAdapter::SetFormat: (0x0000026798BCFDF0)->PrepareFirstAudioReadyState() [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2967 CSpxUspRecoEngineAdapter::ResetBeforeFirstAudio: this=0x0000026798BCFDF0 ... USP-RESET [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2814 CSpxUspRecoEngineAdapter::PrepareCompressionCodec: Prepare compression codec. [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='SPEECH-Compression-Codec-Module'; value='' [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2876 CSpxUspRecoEngineAdapter::PrepareFirstAudioReadyState: (0x0000026798BCFDF0)->PrepareCompressionCodec() result: 4. Sending the audio uncompressed [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='AZAC-SDK-PROGRAMMING-LANGUAGE'; value='Java' [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxPropertyBagImpl::SetStringValue: this=0x000002679903E038; name='HttpHeader#User-agent'; value='SpeechSDK-Java/1.25.0 Windows Client 10' [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:340 CSpxUspRecoEngineAdapter::UspInitialize: this=0x0000026798BCFDF0 [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxUspCallbackWrapper' as '978711522' [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='SessionId'; value='236664cc8eb042298ab96089703f1a1e' [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1812 CSpxAudioStreamSession::GetScenarioCount: countSpeech=1; countIntent=0; countTranslation=0; countDialog=0, countTranscriber=0, countLanguageId=0 [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:734 CSpxUspRecoEngineAdapter::SetUspEndpointDefaultSpeechService: Endpoint type: Speech. [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='SPEECH-Region'; value='eastasia' [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='SPEECH-RecoLanguage'; value='en-us' [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='SPEECH-RecoLanguage'; value='en-us' [912532]: 10614ms SPX_TRACE_INFO: usp_reco_engine_adapter.cpp:1044 SpeechServiceConnection_LanguageIdMode not set or has invalid value . [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:614 CSpxUspRecoEngineAdapter::SetUspLanguageIdModeAndPriority: languageIdMode=0, languageIdPriority=0 [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='SPEECH-RecoMode'; value='INTERACTIVE' [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:588 CSpxUspRecoEngineAdapter::SetUspRecoMode: recoMode=0 [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:575 CSpxUspRecoEngineAdapter::SetUspEndpoint: Setting Websocket Polling interval to 10 [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='SPEECH-SubscriptionKey'; value='******************************06' [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::FindPrefix: this=0x0000026798BCFE60; name='User-agent'; value='SpeechSDK-Java/1.25.0 Windows Client 10' [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='SPEECH-ProxyHostBypass'; value='' [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='AZAC-SDK-PROGRAMMING-LANGUAGE'; value='Java' [912532]: 10614ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='AudioConfig_AudioSource'; value='File' [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='AudioConfig_SampleRateForCapture'; value='16000' [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='AudioConfig_BitsPerSampleForCapture'; value='16' [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='AudioConfig_NumberOfChannelsForCapture'; value='1' [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxUspConnection' as '792041254' [912532]: 10615ms SPX_TRACE_INFO: usp_connection.cpp:460 Microsoft::CognitiveServices::Speech::USP::CSpxUspConnection::Connect: entering... [912532]: 10615ms SPX_TRACE_INFO: usp_connection.cpp:477 Adding subscription key headers [912532]: 10615ms SPX_TRACE_INFO: usp_connection.cpp:512 Set a user defined HTTP header 'User-agent':'SpeechSDK-Java/1.25.0 Windows Client 10' [912532]: 10615ms SPX_TRACE_INFO: usp_connection.cpp:527 connectionUrl=wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us [912532]: 10615ms SPX_DBG_TRACE_SCOPE_ENTER: web_socket.cpp:216 CSpxWebSocket::CSpxWebSocket [912532]: 10615ms SPX_DBG_TRACE_SCOPE_EXIT: web_socket.cpp:216 CSpxWebSocket::CSpxWebSocket [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxWebSocket' as '482598724' [912532]: 10615ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:31 WebSocketAdapter::UwsWebSocket::UwsWebSocket [912532]: 10615ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:31 WebSocketAdapter::UwsWebSocket::UwsWebSocket [912532]: 10615ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:43 WebSocketAdapter::UwsWebSocket::Initialize [912532]: 10615ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:43 WebSocketAdapter::UwsWebSocket::Initialize [912532]: 10615ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 10615ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 10615ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 10615ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 10615ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 10615ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxPropertyBagImpl::SetStringValue: this=0x000002679903E038; name='SPEECH-ConnectionUrl'; value='wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us' [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1097 speech.config {"context":{"system":{"version":"1.25.0","name":"SpeechSDK","build":"Windows-x64","lang":"Java"},"os":{"name":"Client","version":"10","platform":"Windows"},"audio":{"source":{"type":"File","model":"","samplerate":"16000","bitspersample":"16","channelcount":"1"}}}} [912532]: 10615ms SPX_TRACE_INFO: usp_connection.cpp:762 Create requestId for messageType 0 [912532]: 10615ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:216 CSpxUspRecoEngineAdapter::SetFormat [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1875 CSpxAudioStreamSession::AdapterStoppedTurn: set m_adapterStreamingAudio to false [912532]: 10615ms SPX_DBG_TRACE_FUNCTION: audio_buffer.cpp:92 PcmAudioBuffer::NewTurn [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1897 [000002679903DF50]CSpxAudioStreamSession::AdapterStoppedTurn: m_currentTurnGlobalOffset=0, previousTurnGlobalOffset=0 bufferedBytes=100012 [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2798 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 1/0 => 2/1000 [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:280 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0)->PrepareUspAudioStream() ... size=3200 [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1184 speech.context='{"audio":{"streams":{"1":null}}}' [912532]: 10615ms SPX_TRACE_INFO: usp_connection.cpp:609 RequestId: '671cbc1d0b7347079e514d9192a51fa9' [912532]: 10615ms SPX_TRACE_INFO: usp_connection.cpp:762 Create requestId 671cbc1d0b7347079e514d9192a51fa9 for messageType 1 [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 44) [912532]: 10615ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 44 bytes audio data. [912532]: 10615ms SPX_TRACE_INFO: usp_connection.cpp:799 The current speech request id is 671cbc1d0b7347079e514d9192a51fa9 [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10615ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:284 CSpxUspRecoEngineAdapter::ProcessAudio: site->AdapterStartingTurn() [912532]: 10615ms SPX_DBG_TRACE_FUNCTION: audio_stream_session.cpp:1861 CSpxAudioStreamSession::AdapterStartingTurn [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10615ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10615ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10615ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10615ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10615ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10615ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10615ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10615ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10615ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10615ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10615ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10615ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10615ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10615ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10615ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10615ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:0, Write 3200 bytes audio data. [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10616ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10616ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10616ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10616ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10616ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10616ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10616ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10616ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10616ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10616ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10616ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10616ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10616ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10616ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 10616ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=812 [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 812) [912532]: 10616ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 812 bytes audio data. [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:294 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Flushing Audio ... size=0 USP-FLUSH [912532]: 10616ms SPX_TRACE_INFO: usp_connection.cpp:815 TS:1, Flush audio buffer. [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1938 CSpxAudioStreamSession::AdapterStoppedTurn: set m_adapterAudioMuted to false [912532]: 10616ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:54 CSpxUspRecoEngineAdapter::~CSpxUspRecoEngineAdapter [912532]: 10616ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:55 CSpxUspRecoEngineAdapter::~CSpxUspRecoEngineAdapter: this=0x000002679903D010 [912532]: 10616ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:54 CSpxUspRecoEngineAdapter::~CSpxUspRecoEngineAdapter [912532]: 10616ms SPX_DBG_TRACE_SCOPE_ENTER: usp_connection.cpp:144 Microsoft::CognitiveServices::Speech::USP::CSpxUspConnection::~CSpxUspConnection [912532]: 10616ms SPX_DBG_TRACE_SCOPE_EXIT: usp_connection.cpp:144 Microsoft::CognitiveServices::Speech::USP::CSpxUspConnection::~CSpxUspConnection [912532]: 10616ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:247 WebSocketAdapter::UwsWebSocket::OnWebSocketOpened [912532]: 10616ms SPX_TRACE_INFO: web_socket.cpp:800 CSpxWebSocket::DoWork: open transport. [912532]: 10616ms SPX_TRACE_INFO: web_socket.cpp:479 Start to open websocket. WebSocket: 0x98adbd20, wsio handle: 0x9b8ce720 [912532]: 10616ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:149 WebSocketAdapter::UwsWebSocket::Open [912532]: 10620ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:149 WebSocketAdapter::UwsWebSocket::Open [912532]: 10629ms SPX_DBG_TRACE_SCOPE_ENTER: web_socket.cpp:221 CSpxWebSocket::~CSpxWebSocket [912532]: 10629ms SPX_DBG_TRACE_SCOPE_EXIT: web_socket.cpp:221 CSpxWebSocket::~CSpxWebSocket [912532]: 10629ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:36 WebSocketAdapter::UwsWebSocket::~UwsWebSocket [912532]: 10629ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:36 WebSocketAdapter::UwsWebSocket::~UwsWebSocket [912532]: 15622ms SPX_TRACE_ERROR: AZ_LOG_ERROR: socketio_win32.c:543 Socketio_Failure: Receiving data from endpoint: 10054. [912532]: 15622ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:247 WebSocketAdapter::UwsWebSocket::OnWebSocketOpened [912532]: 15622ms SPX_TRACE_ERROR: web_socket.cpp:907 WS open operation failed with result=1(WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED), code=2[0x00000002], time=2023-04-10T08:24:31.2099328Z [912532]: 15622ms SPX_TRACE_INFO: usp_connection.cpp:908 TS:5007, TransportError: connection:0x98d317c0, code=5, string=Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us X-ConnectionId: 236664cc8eb042298ab96089703f1a1e [912532]: 15622ms SPX_TRACE_ERROR: usp_reco_engine_adapter.cpp:2074 Response: On Error: Code:5, Message: Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us X-ConnectionId: 236664cc8eb042298ab96089703f1a1e. [912532]: 15622ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2798 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 2/1000 => 2/-1 USP-ERRORERROR [912532]: 15622ms SPX_TRACE_ERROR: usp_reco_engine_adapter.cpp:2088 CSpxUspRecoEngineAdapter::OnError: site->Error() ... error='Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us X-ConnectionId: 236664cc8eb042298ab96089703f1a1e' [912532]: 15622ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter_retry.cpp:134 CSpxUspRecoEngineAdapterRetry::ShouldReconnect: Should Reconnect: 1 [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter_retry.cpp:94 CSpxUspRecoEngineAdapterRetry::StartReconnect: Trying to reset the engine adapter [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x0000026798BCFE48 [912532]: 15888ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:91 Terminating CSpxUspRecoEngineAdapter... [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:92 CSpxUspRecoEngineAdapter::Term: this=0x0000026798BCFDF0 [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2798 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 2/-1 => 2/9998 USP-TERMINATING [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:96 CSpxUspRecoEngineAdapter::Term: Terminating USP Connection (0x0000026798D31808) [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x0000026798C880F8 [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2798 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 2/9998 => 2/9999 USP-ZOMBIE [912532]: 15888ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:91 Terminating CSpxUspRecoEngineAdapter... Done! [912532]: 15888ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter_retry.cpp:19 CSpxUspRecoEngineAdapterRetry::InitDelegatePtr [912532]: 15888ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:48 CSpxUspRecoEngineAdapter::CSpxUspRecoEngineAdapter [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:49 CSpxUspRecoEngineAdapter::CSpxUspRecoEngineAdapter: this=0x000002679903D010 [912532]: 15888ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:48 CSpxUspRecoEngineAdapter::CSpxUspRecoEngineAdapter [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxUspRecoEngineAdapter' as '806006714' [912532]: 15888ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:62 CSpxUspRecoEngineAdapter::Init [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:63 CSpxUspRecoEngineAdapter::Init: this=0x000002679903D010 [912532]: 15888ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:62 CSpxUspRecoEngineAdapter::Init [912532]: 15888ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter_retry.cpp:19 CSpxUspRecoEngineAdapterRetry::InitDelegatePtr [912532]: 15888ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:216 CSpxUspRecoEngineAdapter::SetFormat [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:217 CSpxUspRecoEngineAdapter::SetFormat: this=0x000002679903D010 [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:228 CSpxUspRecoEngineAdapter::SetFormat wFormatTag: PCM nChannels: 1 nSamplesPerSec: 16000 nAvgBytesPerSec: 32000 nBlockAlign: 2 wBitsPerSample: 16 cbSize: 0 [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2798 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 0/0 => 1/0 USP-START [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:250 CSpxUspRecoEngineAdapter::SetFormat: (0x000002679903D010)->PrepareFirstAudioReadyState() [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2967 CSpxUspRecoEngineAdapter::ResetBeforeFirstAudio: this=0x000002679903D010 ... USP-RESET [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2814 CSpxUspRecoEngineAdapter::PrepareCompressionCodec: Prepare compression codec. [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='SPEECH-Compression-Codec-Module'; value='' [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2876 CSpxUspRecoEngineAdapter::PrepareFirstAudioReadyState: (0x000002679903D010)->PrepareCompressionCodec() result: 4. Sending the audio uncompressed [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='AZAC-SDK-PROGRAMMING-LANGUAGE'; value='Java' [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxPropertyBagImpl::SetStringValue: this=0x000002679903E038; name='HttpHeader#User-agent'; value='SpeechSDK-Java/1.25.0 Windows Client 10' [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:340 CSpxUspRecoEngineAdapter::UspInitialize: this=0x000002679903D010 [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxUspCallbackWrapper' as '978711522' [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='SessionId'; value='236664cc8eb042298ab96089703f1a1e' [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1812 CSpxAudioStreamSession::GetScenarioCount: countSpeech=1; countIntent=0; countTranslation=0; countDialog=0, countTranscriber=0, countLanguageId=0 [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:734 CSpxUspRecoEngineAdapter::SetUspEndpointDefaultSpeechService: Endpoint type: Speech. [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='SPEECH-Region'; value='eastasia' [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='SPEECH-RecoLanguage'; value='en-us' [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='SPEECH-RecoLanguage'; value='en-us' [912532]: 15888ms SPX_TRACE_INFO: usp_reco_engine_adapter.cpp:1044 SpeechServiceConnection_LanguageIdMode not set or has invalid value . [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:614 CSpxUspRecoEngineAdapter::SetUspLanguageIdModeAndPriority: languageIdMode=0, languageIdPriority=0 [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='SPEECH-RecoMode'; value='INTERACTIVE' [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:588 CSpxUspRecoEngineAdapter::SetUspRecoMode: recoMode=0 [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:575 CSpxUspRecoEngineAdapter::SetUspEndpoint: Setting Websocket Polling interval to 10 [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='SPEECH-SubscriptionKey'; value='******************************06' [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::FindPrefix: this=0x000002679903D080; name='User-agent'; value='SpeechSDK-Java/1.25.0 Windows Client 10' [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='SPEECH-ProxyHostBypass'; value='' [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='AZAC-SDK-PROGRAMMING-LANGUAGE'; value='Java' [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='AudioConfig_AudioSource'; value='File' [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='AudioConfig_SampleRateForCapture'; value='16000' [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='AudioConfig_BitsPerSampleForCapture'; value='16' [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903D080; name='AudioConfig_NumberOfChannelsForCapture'; value='1' [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxUspConnection' as '792041254' [912532]: 15888ms SPX_TRACE_INFO: usp_connection.cpp:460 Microsoft::CognitiveServices::Speech::USP::CSpxUspConnection::Connect: entering... [912532]: 15888ms SPX_TRACE_INFO: usp_connection.cpp:477 Adding subscription key headers [912532]: 15888ms SPX_TRACE_INFO: usp_connection.cpp:512 Set a user defined HTTP header 'User-agent':'SpeechSDK-Java/1.25.0 Windows Client 10' [912532]: 15888ms SPX_TRACE_INFO: usp_connection.cpp:527 connectionUrl=wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us [912532]: 15888ms SPX_DBG_TRACE_SCOPE_ENTER: web_socket.cpp:216 CSpxWebSocket::CSpxWebSocket [912532]: 15888ms SPX_DBG_TRACE_SCOPE_EXIT: web_socket.cpp:216 CSpxWebSocket::CSpxWebSocket [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxWebSocket' as '482598724' [912532]: 15888ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:31 WebSocketAdapter::UwsWebSocket::UwsWebSocket [912532]: 15888ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:31 WebSocketAdapter::UwsWebSocket::UwsWebSocket [912532]: 15888ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:43 WebSocketAdapter::UwsWebSocket::Initialize [912532]: 15888ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:43 WebSocketAdapter::UwsWebSocket::Initialize [912532]: 15888ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 15888ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 15888ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 15888ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 15888ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 15888ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxPropertyBagImpl::SetStringValue: this=0x000002679903E038; name='SPEECH-ConnectionUrl'; value='wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us' [912532]: 15888ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1097 speech.config {"context":{"system":{"version":"1.25.0","name":"SpeechSDK","build":"Windows-x64","lang":"Java"},"os":{"name":"Client","version":"10","platform":"Windows"},"audio":{"source":{"type":"File","model":"","samplerate":"16000","bitspersample":"16","channelcount":"1"}}}} [912532]: 15888ms SPX_TRACE_INFO: usp_connection.cpp:762 Create requestId for messageType 0 [912532]: 15888ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:216 CSpxUspRecoEngineAdapter::SetFormat [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1875 CSpxAudioStreamSession::AdapterStoppedTurn: set m_adapterStreamingAudio to false [912532]: 15889ms SPX_DBG_TRACE_FUNCTION: audio_buffer.cpp:92 PcmAudioBuffer::NewTurn [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1897 [000002679903DF50]CSpxAudioStreamSession::AdapterStoppedTurn: m_currentTurnGlobalOffset=0, previousTurnGlobalOffset=0 bufferedBytes=100012 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2798 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 1/0 => 2/1000 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:280 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010)->PrepareUspAudioStream() ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1184 speech.context='{"audio":{"streams":{"1":null}}}' [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:609 RequestId: '45a08bdb305843abb9c9ba022e5ce9c6' [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:762 Create requestId 45a08bdb305843abb9c9ba022e5ce9c6 for messageType 1 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 44) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 44 bytes audio data. [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:799 The current speech request id is 45a08bdb305843abb9c9ba022e5ce9c6 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:284 CSpxUspRecoEngineAdapter::ProcessAudio: site->AdapterStartingTurn() [912532]: 15889ms SPX_DBG_TRACE_FUNCTION: audio_stream_session.cpp:1861 CSpxAudioStreamSession::AdapterStartingTurn [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15889ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15889ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15890ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 15890ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15890ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15890ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:2, Write 3200 bytes audio data. [912532]: 15890ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=3200 [912532]: 15890ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 15890ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:2, Write 3200 bytes audio data. [912532]: 15890ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Sending Audio ... size=812 [912532]: 15890ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 812) [912532]: 15890ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:2, Write 812 bytes audio data. [912532]: 15890ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:294 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002679903D010) Flushing Audio ... size=0 USP-FLUSH [912532]: 15890ms SPX_TRACE_INFO: usp_connection.cpp:815 TS:2, Flush audio buffer. [912532]: 15890ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [912532]: 15890ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1938 CSpxAudioStreamSession::AdapterStoppedTurn: set m_adapterAudioMuted to false [912532]: 15890ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:54 CSpxUspRecoEngineAdapter::~CSpxUspRecoEngineAdapter [912532]: 15890ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:55 CSpxUspRecoEngineAdapter::~CSpxUspRecoEngineAdapter: this=0x0000026798BCFDF0 [912532]: 15890ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:54 CSpxUspRecoEngineAdapter::~CSpxUspRecoEngineAdapter [912532]: 15890ms SPX_DBG_TRACE_SCOPE_ENTER: usp_connection.cpp:144 Microsoft::CognitiveServices::Speech::USP::CSpxUspConnection::~CSpxUspConnection [912532]: 15890ms SPX_DBG_TRACE_SCOPE_EXIT: usp_connection.cpp:144 Microsoft::CognitiveServices::Speech::USP::CSpxUspConnection::~CSpxUspConnection [912532]: 15890ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:247 WebSocketAdapter::UwsWebSocket::OnWebSocketOpened [912532]: 15890ms SPX_TRACE_INFO: web_socket.cpp:800 CSpxWebSocket::DoWork: open transport. [912532]: 15890ms SPX_TRACE_INFO: web_socket.cpp:479 Start to open websocket. WebSocket: 0x98adcf60, wsio handle: 0x998106e0 [912532]: 15890ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:149 WebSocketAdapter::UwsWebSocket::Open [912532]: 15893ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:149 WebSocketAdapter::UwsWebSocket::Open [912532]: 15903ms SPX_DBG_TRACE_SCOPE_ENTER: web_socket.cpp:221 CSpxWebSocket::~CSpxWebSocket [912532]: 15903ms SPX_DBG_TRACE_SCOPE_EXIT: web_socket.cpp:221 CSpxWebSocket::~CSpxWebSocket [912532]: 15903ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:36 WebSocketAdapter::UwsWebSocket::~UwsWebSocket [912532]: 15903ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:36 WebSocketAdapter::UwsWebSocket::~UwsWebSocket [912532]: 20909ms SPX_TRACE_ERROR: AZ_LOG_ERROR: socketio_win32.c:543 Socketio_Failure: Receiving data from endpoint: 10054. [912532]: 20909ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:247 WebSocketAdapter::UwsWebSocket::OnWebSocketOpened [912532]: 20909ms SPX_TRACE_ERROR: web_socket.cpp:907 WS open operation failed with result=1(WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED), code=2[0x00000002], time=2023-04-10T08:24:36.4964353Z [912532]: 20909ms SPX_TRACE_INFO: usp_connection.cpp:908 TS:5021, TransportError: connection:0x98d31fa0, code=5, string=Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us X-ConnectionId: 236664cc8eb042298ab96089703f1a1e [912532]: 20909ms SPX_TRACE_ERROR: usp_reco_engine_adapter.cpp:2074 Response: On Error: Code:5, Message: Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us X-ConnectionId: 236664cc8eb042298ab96089703f1a1e. [912532]: 20909ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2798 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 2/1000 => 2/-1 USP-ERRORERROR [912532]: 20909ms SPX_TRACE_ERROR: usp_reco_engine_adapter.cpp:2088 CSpxUspRecoEngineAdapter::OnError: site->Error() ... error='Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us X-ConnectionId: 236664cc8eb042298ab96089703f1a1e' [912532]: 20909ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter_retry.cpp:134 CSpxUspRecoEngineAdapterRetry::ShouldReconnect: Should Reconnect: 1 [912532]: 21172ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter_retry.cpp:94 CSpxUspRecoEngineAdapterRetry::StartReconnect: Trying to reset the engine adapter [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x000002679903D068 [912532]: 21173ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:91 Terminating CSpxUspRecoEngineAdapter... [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:92 CSpxUspRecoEngineAdapter::Term: this=0x000002679903D010 [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2798 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 2/-1 => 2/9998 USP-TERMINATING [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:96 CSpxUspRecoEngineAdapter::Term: Terminating USP Connection (0x0000026798D31FE8) [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x0000026798C87218 [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2798 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 2/9998 => 2/9999 USP-ZOMBIE [912532]: 21173ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:91 Terminating CSpxUspRecoEngineAdapter... Done! [912532]: 21173ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter_retry.cpp:19 CSpxUspRecoEngineAdapterRetry::InitDelegatePtr [912532]: 21173ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:48 CSpxUspRecoEngineAdapter::CSpxUspRecoEngineAdapter [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:49 CSpxUspRecoEngineAdapter::CSpxUspRecoEngineAdapter: this=0x0000026798BCFDF0 [912532]: 21173ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:48 CSpxUspRecoEngineAdapter::CSpxUspRecoEngineAdapter [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxUspRecoEngineAdapter' as '806006714' [912532]: 21173ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:62 CSpxUspRecoEngineAdapter::Init [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:63 CSpxUspRecoEngineAdapter::Init: this=0x0000026798BCFDF0 [912532]: 21173ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:62 CSpxUspRecoEngineAdapter::Init [912532]: 21173ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter_retry.cpp:19 CSpxUspRecoEngineAdapterRetry::InitDelegatePtr [912532]: 21173ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:216 CSpxUspRecoEngineAdapter::SetFormat [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:217 CSpxUspRecoEngineAdapter::SetFormat: this=0x0000026798BCFDF0 [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:228 CSpxUspRecoEngineAdapter::SetFormat wFormatTag: PCM nChannels: 1 nSamplesPerSec: 16000 nAvgBytesPerSec: 32000 nBlockAlign: 2 wBitsPerSample: 16 cbSize: 0 [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2798 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 0/0 => 1/0 USP-START [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:250 CSpxUspRecoEngineAdapter::SetFormat: (0x0000026798BCFDF0)->PrepareFirstAudioReadyState() [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2967 CSpxUspRecoEngineAdapter::ResetBeforeFirstAudio: this=0x0000026798BCFDF0 ... USP-RESET [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2814 CSpxUspRecoEngineAdapter::PrepareCompressionCodec: Prepare compression codec. [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='SPEECH-Compression-Codec-Module'; value='' [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2876 CSpxUspRecoEngineAdapter::PrepareFirstAudioReadyState: (0x0000026798BCFDF0)->PrepareCompressionCodec() result: 4. Sending the audio uncompressed [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='AZAC-SDK-PROGRAMMING-LANGUAGE'; value='Java' [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxPropertyBagImpl::SetStringValue: this=0x000002679903E038; name='HttpHeader#User-agent'; value='SpeechSDK-Java/1.25.0 Windows Client 10' [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:340 CSpxUspRecoEngineAdapter::UspInitialize: this=0x0000026798BCFDF0 [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxUspCallbackWrapper' as '978711522' [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='SessionId'; value='236664cc8eb042298ab96089703f1a1e' [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1812 CSpxAudioStreamSession::GetScenarioCount: countSpeech=1; countIntent=0; countTranslation=0; countDialog=0, countTranscriber=0, countLanguageId=0 [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:734 CSpxUspRecoEngineAdapter::SetUspEndpointDefaultSpeechService: Endpoint type: Speech. [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='SPEECH-Region'; value='eastasia' [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='SPEECH-RecoLanguage'; value='en-us' [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='SPEECH-RecoLanguage'; value='en-us' [912532]: 21173ms SPX_TRACE_INFO: usp_reco_engine_adapter.cpp:1044 SpeechServiceConnection_LanguageIdMode not set or has invalid value . [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:614 CSpxUspRecoEngineAdapter::SetUspLanguageIdModeAndPriority: languageIdMode=0, languageIdPriority=0 [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='SPEECH-RecoMode'; value='INTERACTIVE' [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:588 CSpxUspRecoEngineAdapter::SetUspRecoMode: recoMode=0 [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:575 CSpxUspRecoEngineAdapter::SetUspEndpoint: Setting Websocket Polling interval to 10 [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='SPEECH-SubscriptionKey'; value='******************************06' [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::FindPrefix: this=0x0000026798BCFE60; name='User-agent'; value='SpeechSDK-Java/1.25.0 Windows Client 10' [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='SPEECH-ProxyHostBypass'; value='' [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='AZAC-SDK-PROGRAMMING-LANGUAGE'; value='Java' [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='AudioConfig_AudioSource'; value='File' [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='AudioConfig_SampleRateForCapture'; value='16000' [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='AudioConfig_BitsPerSampleForCapture'; value='16' [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x0000026798BCFE60; name='AudioConfig_NumberOfChannelsForCapture'; value='1' [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxUspConnection' as '792041254' [912532]: 21173ms SPX_TRACE_INFO: usp_connection.cpp:460 Microsoft::CognitiveServices::Speech::USP::CSpxUspConnection::Connect: entering... [912532]: 21173ms SPX_TRACE_INFO: usp_connection.cpp:477 Adding subscription key headers [912532]: 21173ms SPX_TRACE_INFO: usp_connection.cpp:512 Set a user defined HTTP header 'User-agent':'SpeechSDK-Java/1.25.0 Windows Client 10' [912532]: 21173ms SPX_TRACE_INFO: usp_connection.cpp:527 connectionUrl=wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us [912532]: 21173ms SPX_DBG_TRACE_SCOPE_ENTER: web_socket.cpp:216 CSpxWebSocket::CSpxWebSocket [912532]: 21173ms SPX_DBG_TRACE_SCOPE_EXIT: web_socket.cpp:216 CSpxWebSocket::CSpxWebSocket [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxWebSocket' as '482598724' [912532]: 21173ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:31 WebSocketAdapter::UwsWebSocket::UwsWebSocket [912532]: 21173ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:31 WebSocketAdapter::UwsWebSocket::UwsWebSocket [912532]: 21173ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:43 WebSocketAdapter::UwsWebSocket::Initialize [912532]: 21173ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:43 WebSocketAdapter::UwsWebSocket::Initialize [912532]: 21173ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 21173ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 21173ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 21173ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 21173ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 21173ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxPropertyBagImpl::SetStringValue: this=0x000002679903E038; name='SPEECH-ConnectionUrl'; value='wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us' [912532]: 21173ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1097 speech.config {"context":{"system":{"version":"1.25.0","name":"SpeechSDK","build":"Windows-x64","lang":"Java"},"os":{"name":"Client","version":"10","platform":"Windows"},"audio":{"source":{"type":"File","model":"","samplerate":"16000","bitspersample":"16","channelcount":"1"}}}} [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:762 Create requestId for messageType 0 [912532]: 21174ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:216 CSpxUspRecoEngineAdapter::SetFormat [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1875 CSpxAudioStreamSession::AdapterStoppedTurn: set m_adapterStreamingAudio to false [912532]: 21174ms SPX_DBG_TRACE_FUNCTION: audio_buffer.cpp:92 PcmAudioBuffer::NewTurn [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1897 [000002679903DF50]CSpxAudioStreamSession::AdapterStoppedTurn: m_currentTurnGlobalOffset=0, previousTurnGlobalOffset=0 bufferedBytes=100012 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2798 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 1/0 => 2/1000 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:280 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0)->PrepareUspAudioStream() ... size=3200 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1184 speech.context='{"audio":{"streams":{"1":null}}}' [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:609 RequestId: '034221513bef471ab880fa491847b61d' [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:762 Create requestId 034221513bef471ab880fa491847b61d for messageType 1 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 44) [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 44 bytes audio data. [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:799 The current speech request id is 034221513bef471ab880fa491847b61d [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:284 CSpxUspRecoEngineAdapter::ProcessAudio: site->AdapterStartingTurn() [912532]: 21174ms SPX_DBG_TRACE_FUNCTION: audio_stream_session.cpp:1861 CSpxAudioStreamSession::AdapterStartingTurn [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21174ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21174ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:1, Write 3200 bytes audio data. [912532]: 21175ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21175ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21175ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:2, Write 3200 bytes audio data. [912532]: 21175ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21175ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21175ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:2, Write 3200 bytes audio data. [912532]: 21175ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21175ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21175ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:2, Write 3200 bytes audio data. [912532]: 21175ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21175ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21175ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:2, Write 3200 bytes audio data. [912532]: 21175ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21175ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21175ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:2, Write 3200 bytes audio data. [912532]: 21175ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21175ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21175ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:2, Write 3200 bytes audio data. [912532]: 21175ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=3200 [912532]: 21175ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200) [912532]: 21175ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:2, Write 3200 bytes audio data. [912532]: 21175ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:289 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Sending Audio ... size=812 [912532]: 21175ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1259 CSpxUspRecoEngineAdapter::UspWriteActual(..., 812) [912532]: 21175ms SPX_TRACE_INFO: usp_connection.cpp:775 TS:2, Write 812 bytes audio data. [912532]: 21175ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:294 CSpxUspRecoEngineAdapter::ProcessAudio: (0x0000026798BCFDF0) Flushing Audio ... size=0 USP-FLUSH [912532]: 21175ms SPX_TRACE_INFO: usp_connection.cpp:815 TS:2, Flush audio buffer. [912532]: 21175ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:679 [000002679903DF50]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [912532]: 21175ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1938 CSpxAudioStreamSession::AdapterStoppedTurn: set m_adapterAudioMuted to false [912532]: 21175ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:54 CSpxUspRecoEngineAdapter::~CSpxUspRecoEngineAdapter [912532]: 21175ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:55 CSpxUspRecoEngineAdapter::~CSpxUspRecoEngineAdapter: this=0x000002679903D010 [912532]: 21175ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:54 CSpxUspRecoEngineAdapter::~CSpxUspRecoEngineAdapter [912532]: 21175ms SPX_DBG_TRACE_SCOPE_ENTER: usp_connection.cpp:144 Microsoft::CognitiveServices::Speech::USP::CSpxUspConnection::~CSpxUspConnection [912532]: 21175ms SPX_DBG_TRACE_SCOPE_EXIT: usp_connection.cpp:144 Microsoft::CognitiveServices::Speech::USP::CSpxUspConnection::~CSpxUspConnection [912532]: 21175ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:247 WebSocketAdapter::UwsWebSocket::OnWebSocketOpened [912532]: 21175ms SPX_TRACE_INFO: web_socket.cpp:800 CSpxWebSocket::DoWork: open transport. [912532]: 21175ms SPX_TRACE_INFO: web_socket.cpp:479 Start to open websocket. WebSocket: 0x98adef50, wsio handle: 0x978a0570 [912532]: 21175ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:149 WebSocketAdapter::UwsWebSocket::Open [912532]: 21179ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:149 WebSocketAdapter::UwsWebSocket::Open [912532]: 21188ms SPX_DBG_TRACE_SCOPE_ENTER: web_socket.cpp:221 CSpxWebSocket::~CSpxWebSocket [912532]: 21188ms SPX_DBG_TRACE_SCOPE_EXIT: web_socket.cpp:221 CSpxWebSocket::~CSpxWebSocket [912532]: 21188ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:36 WebSocketAdapter::UwsWebSocket::~UwsWebSocket [912532]: 21188ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:36 WebSocketAdapter::UwsWebSocket::~UwsWebSocket [912532]: 26198ms SPX_TRACE_ERROR: AZ_LOG_ERROR: socketio_win32.c:543 Socketio_Failure: Receiving data from endpoint: 10054. [912532]: 26198ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:247 WebSocketAdapter::UwsWebSocket::OnWebSocketOpened [912532]: 26198ms SPX_TRACE_ERROR: web_socket.cpp:907 WS open operation failed with result=1(WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED), code=2[0x00000002], time=2023-04-10T08:24:41.7854398Z [912532]: 26198ms SPX_TRACE_INFO: usp_connection.cpp:908 TS:5025, TransportError: connection:0x98d320f0, code=5, string=Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us X-ConnectionId: 236664cc8eb042298ab96089703f1a1e [912532]: 26198ms SPX_TRACE_ERROR: usp_reco_engine_adapter.cpp:2074 Response: On Error: Code:5, Message: Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us X-ConnectionId: 236664cc8eb042298ab96089703f1a1e. [912532]: 26198ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2798 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 2/1000 => 2/-1 USP-ERRORERROR [912532]: 26198ms SPX_TRACE_ERROR: usp_reco_engine_adapter.cpp:2088 CSpxUspRecoEngineAdapter::OnError: site->Error() ... error='Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us X-ConnectionId: 236664cc8eb042298ab96089703f1a1e' [912532]: 26198ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter_retry.cpp:134 CSpxUspRecoEngineAdapterRetry::ShouldReconnect: Should Reconnect: 0 [912532]: 26198ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxPropertyBagImpl::SetStringValue: this=0x000002679903E038; name='SPEECH-SingleUseEndpoint'; value='' [912532]: 26198ms SPX_DBG_TRACE_FUNCTION: audio_stream_session.cpp:2623 CSpxAudioStreamSession::Error [912532]: 26198ms SPX_DBG_TRACE_FUNCTION: recognition_result.cpp:26 CSpxRecognitionResult::CSpxRecognitionResult [912532]: 26199ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxRecognitionResult' as '175439361' [912532]: 26199ms SPX_DBG_TRACE_FUNCTION: recognition_result.cpp:119 CSpxRecognitionResult::InitErrorResult [912532]: 26199ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxPropertyBagImpl::SetStringValue: this=0x000002679A9FFCE0; name='RESULT-ErrorDetails'; value='Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us X-ConnectionId: 236664cc8eb042298ab96089703f1a1e' [912532]: 26199ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1349 [000002679903DF50]CSpxAudioStreamSession::WaitForRecognition_Complete: ... [912532]: 26199ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1533 [000002679903DF50]CSpxAudioStreamSession::FireResultEvent [912532]: 26199ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679A9FFCE0; name='RESULT-ErrorDetails'; value='Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us X-ConnectionId: 236664cc8eb042298ab96089703f1a1e' [912532]: 26199ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxPropertyBagImpl::SetStringValue: this=0x000002679A9FFCE0; name='RESULT-ErrorDetails'; value='Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us X-ConnectionId: 236664cc8eb042298ab96089703f1a1e SessionId: 236664cc8eb042298ab96089703f1a1e' [912532]: 26199ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:1234 CSpxAudioStreamSession::StopRecognizing [912532]: 26199ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1235 [000002679903DF50]CSpxAudioStreamSession::StopRecognizing ... [919924]: 26199ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:1676 DispatchEvent task started... [912532]: 26199ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3431 [000002679903DF50]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 3/6 doesn't match [919924]: 26200ms SPX_DBG_TRACE_SCOPE_ENTER: recognizer.cpp:323 CSpxRecognizer::FireResultEvent [912532]: 26200ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1327 [000002679903DF50]CSpxAudioStreamSession::StopRecognizing: Now (still) ProcessingAudioLeftovers ... [919924]: 26200ms SPX_DBG_TRACE_VERBOSE: recognizer.cpp:378 No listener connected to event [912532]: 26200ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:1234 CSpxAudioStreamSession::StopRecognizing [919924]: 26200ms SPX_DBG_TRACE_SCOPE_EXIT: recognizer.cpp:323 CSpxRecognizer::FireResultEvent [919924]: 26200ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:1676 DispatchEvent task complete! [912532]: 26200ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1424 [000002679903DF50]CSpxAudioStreamSession::FireSessionStoppedEvent: Firing SessionStopped event: SessionId: 236664cc8eb042298ab96089703f1a1e [912532]: 26200ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:1512 CSpxAudioStreamSession::EnsureFireResultEvent [912532]: 26200ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1513 [000002679903DF50]CSpxAudioStreamSession::EnsureFireResultEvent [200808]: 26200ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679903E038; name='SPEECH-StopRecognitionTimeoutInSeconds'; value='' [912532]: 26200ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:1512 CSpxAudioStreamSession::EnsureFireResultEvent [200808]: 26200ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:787 CSpxAudioStreamSession::WaitForIdle [912532]: 26200ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3421 [000002679903DF50]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 3/6 => 3/5 [200808]: 26200ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:788 [000002679903DF50]CSpxAudioStreamSession::WaitForIdle timeout: 10000 msec [912532]: 26200ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:216 CSpxUspRecoEngineAdapter::SetFormat [200808]: 26200ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:793 CSpxAudioStreamSession::WaitForIdle m_cv.wait_for, m_sessionState: 5, m_recoKind: 3, m_sessionStarted: 1, m_sessionStopped: 0 [919924]: 26200ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:1676 DispatchEvent task started... [912532]: 26200ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:217 CSpxUspRecoEngineAdapter::SetFormat: this=0x0000026798BCFDF0 [912532]: 26200ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:219 CSpxUspRecoEngineAdapter::SetFormat - pformat == nullptr [919924]: 26200ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxSessionEventArgs' as '418547108' [912532]: 26200ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:237 CSpxUspRecoEngineAdapter::SetFormat: (0x0000026798BCFDF0) IGNORING... (audioState/uspState=2/-1) [912532]: 26200ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:3336 *** CSpxAudioStreamSession::AdapterCompletedSetFormatStop kicked-off THREAD started *** [919924]: 26200ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:1676 DispatchEvent task complete! [912532]: 26200ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3337 [000002679903DF50]CSpxAudioStreamSession::AdapterCompletedSetFormatStop: kicked-off THREAD started *** [912532]: 26200ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3431 [000002679903DF50]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 3/5 doesn't match [200808]: 26200ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:793 CSpxAudioStreamSession::WaitForIdle m_cv.wait_for, m_sessionState: 5, m_recoKind: 3, m_sessionStarted: 1, m_sessionStopped: 1 [912532]: 26200ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3431 [000002679903DF50]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 3/5 doesn't match [912532]: 26200ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3431 [000002679903DF50]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 3/5 doesn't match [912532]: 26200ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3421 [000002679903DF50]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 3/5 => 0/0 [912532]: 26200ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:3336 *** CSpxAudioStreamSession::AdapterCompletedSetFormatStop kicked-off THREAD stopped *** [912532]: 26200ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:216 CSpxUspRecoEngineAdapter::SetFormat [912532]: 26201ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:1234 CSpxAudioStreamSession::StopRecognizing [200808]: 26201ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:793 CSpxAudioStreamSession::WaitForIdle m_cv.wait_for, m_sessionState: 0, m_recoKind: 0, m_sessionStarted: 1, m_sessionStopped: 1 [912532]: 26201ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1235 [000002679903DF50]CSpxAudioStreamSession::StopRecognizing ... [200808]: 26201ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:787 CSpxAudioStreamSession::WaitForIdle [912532]: 26201ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3431 [000002679903DF50]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 0/0 doesn't match [912532]: 26201ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1332 [000002679903DF50]CSpxAudioStreamSession::StopRecognizing: Now Idle/Idle already... [912532]: 26201ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:1234 CSpxAudioStreamSession::StopRecognizing [912532]: 26201ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:247 WebSocketAdapter::UwsWebSocket::OnWebSocketOpened [763112]: 26201ms SPX_DBG_TRACE_VERBOSE: handle_table.h:108 CSpxHandleTable::TrackHandle p=0x000002679A9FFC20 [763112]: 26201ms SPX_DBG_TRACE_VERBOSE: handle_table.h:118 CSpxHandleTable::TrackHandle class=ISpxRecognitionResult, h=0x000002679A9FFC20, p=0x000002679A9FFC20, tot=1 [763112]: 26201ms SPX_DBG_TRACE_VERBOSE: handle_table.h:177 CSpxHandleTable::StopTracking(h) h=0x0000026798AC0480 [763112]: 26201ms SPX_DBG_TRACE_VERBOSE: handle_table.h:192 CSpxHandleTable::StopTracking(h) class=CSpxAsyncOp>, h=0x0000026798AC0480, p=0x0000026798AC0480, tot=0 [763112]: 26204ms SPX_DBG_TRACE_VERBOSE: handle_table.h:108 CSpxHandleTable::TrackHandle p=0x000002679A9FFCE0 [763112]: 26204ms SPX_DBG_TRACE_VERBOSE: handle_table.h:118 CSpxHandleTable::TrackHandle class=ISpxNamedProperties, h=0x000002679A9FFCE0, p=0x000002679A9FFCE0, tot=4 [135946]: 26206ms SPX_DBG_TRACE_VERBOSE: named_properties.h:477 ISpxNamedProperties::GetStringValue: this=0x000002679A9FFCE0; name='RESULT-ErrorDetails'; value='Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED wss://eastasia.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us X-ConnectionId: 236664cc8eb042298ab96089703f1a1e SessionId: 236664cc8eb042298ab96089703f1a1e' [345017]: 86716ms SPX_DBG_TRACE_FUNCTION: memory_logger.cpp:127 MemoryLogger::Exit [345017]: 86716ms SPX_DBG_TRACE_FUNCTION: resource_manager.cpp:76 CSpxResourceManager::~CSpxResourceManager