[827406]: 14206ms SPX_TRACE_VERBOSE interop_factory.cs:84 Dispose(True) called for 06b7e81c [827406]: 14206ms SPX_DBG_TRACE_VERBOSE: handle_table.h:180 CSpxHandleTable::StopTracking(h) h=0x06B7E81C [827406]: 14206ms SPX_DBG_TRACE_VERBOSE: handle_table.h:195 CSpxHandleTable::StopTracking(h) class=ISpxNamedProperties, h=0x06B7E81C, p=0x06B7E81C, tot=3 [827406]: 14209ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxAutoDetectSourceLangConfig' as '272835792' [827406]: 14209ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxPropertyBagImpl::SetStringValue: this=0x06BE555C; name='Auto-Detect-Source-Languages'; value='es-HN,es-MX,es-PA' [827406]: 14209ms SPX_DBG_TRACE_VERBOSE: handle_table.h:111 CSpxHandleTable::TrackHandle p=0x06BE559C [827406]: 14209ms SPX_DBG_TRACE_VERBOSE: handle_table.h:121 CSpxHandleTable::TrackHandle class=ISpxAutoDetectSourceLangConfig, h=0x06BE559C, p=0x06BE559C, tot=1 [827406]: 14209ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxSpeechConfig' as '64701607' [827406]: 14209ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxPropertyBagImpl::SetStringValue: this=0x06BE53AC; name='SPEECH-SubscriptionKey'; value='******************************7b' [827406]: 14209ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxPropertyBagImpl::SetStringValue: this=0x06BE53AC; name='SPEECH-Region'; value='eastus' [827406]: 14209ms SPX_DBG_TRACE_VERBOSE: handle_table.h:111 CSpxHandleTable::TrackHandle p=0x06BE53EC [827406]: 14209ms SPX_DBG_TRACE_VERBOSE: handle_table.h:121 CSpxHandleTable::TrackHandle class=ISpxSpeechConfig, h=0x06BE53EC, p=0x06BE53EC, tot=2 [827406]: 14209ms SPX_DBG_TRACE_VERBOSE: handle_table.h:111 CSpxHandleTable::TrackHandle p=0x06BE53AC [827406]: 14209ms SPX_DBG_TRACE_VERBOSE: handle_table.h:121 CSpxHandleTable::TrackHandle class=ISpxNamedProperties, h=0x06BE53AC, p=0x06BE53AC, tot=4 [827406]: 14210ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxPropertyBagImpl::SetStringValue: this=0x06BE53AC; name='AZAC-SDK-PROGRAMMING-LANGUAGE'; value='C#' [827406]: 14210ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxPropertyBagImpl::SetStringValue: this=0x06BE53AC; name='SpeechServiceResponse_RequestDetailedResultTrueFalse'; value='true' [827406]: 14210ms SPX_DBG_TRACE_VERBOSE: handle_table.h:111 CSpxHandleTable::TrackHandle p=0x05472DB8 [827406]: 14210ms SPX_DBG_TRACE_VERBOSE: handle_table.h:121 CSpxHandleTable::TrackHandle class=SPXWAVEFORMATEX, h=0x05472DB8, p=0x05472DB8, tot=1 [827406]: 20375ms SPX_DBG_TRACE_SCOPE_ENTER: pull_audio_input_stream.cpp:23 CSpxPullAudioInputStream::CSpxPullAudioInputStream [827406]: 20375ms SPX_DBG_TRACE_SCOPE_EXIT: pull_audio_input_stream.cpp:23 CSpxPullAudioInputStream::CSpxPullAudioInputStream [827406]: 20375ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxPullAudioInputStream' as '184954048' [827406]: 20375ms SPX_DBG_TRACE_VERBOSE: handle_table.h:111 CSpxHandleTable::TrackHandle p=0x06C14AB8 [827406]: 20375ms SPX_DBG_TRACE_VERBOSE: handle_table.h:121 CSpxHandleTable::TrackHandle class=ISpxAudioStream, h=0x06C14AB8, p=0x06C14AB8, tot=2 [827406]: 20378ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxAudioConfig' as '552907028' [827406]: 20378ms SPX_DBG_TRACE_VERBOSE: handle_table.h:111 CSpxHandleTable::TrackHandle p=0x06C332BC [827406]: 20378ms SPX_DBG_TRACE_VERBOSE: handle_table.h:121 CSpxHandleTable::TrackHandle class=ISpxAudioConfig, h=0x06C332BC, p=0x06C332BC, tot=2 [827406]: 20379ms SPX_DBG_TRACE_VERBOSE: handle_table.h:111 CSpxHandleTable::TrackHandle p=0x06C3327C [827406]: 20379ms SPX_DBG_TRACE_VERBOSE: handle_table.h:121 CSpxHandleTable::TrackHandle class=ISpxNamedProperties, h=0x06C3327C, p=0x06C3327C, tot=5 [827406]: 20381ms SPX_DBG_TRACE_SCOPE_ENTER: speechapi_c_factory.cpp:186 recognizer_create_speech_recognizer_from_auto_detect_source_lang_config [827406]: 20381ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxSpeechApiFactory' as '818994146' [827406]: 20381ms SPX_DBG_TRACE_VERBOSE: named_properties.h:135 ISpxNamedProperties::Copy from=0x6be53ac to=0x6bcefa4 [827406]: 20381ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxPropertyBagImpl::SetStringValue: this=0x06BCEFA4; name='AZAC-SDK-PROGRAMMING-LANGUAGE'; value='C#' [827406]: 20381ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxPropertyBagImpl::SetStringValue: this=0x06BCEFA4; name='SPEECH-Region'; value='eastus' [827406]: 20381ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxPropertyBagImpl::SetStringValue: this=0x06BCEFA4; name='SPEECH-SubscriptionKey'; value='******************************7b' [827406]: 20381ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxPropertyBagImpl::SetStringValue: this=0x06BCEFA4; name='SpeechServiceResponse_RequestDetailedResultTrueFalse'; value='true' [827406]: 20381ms SPX_DBG_TRACE_VERBOSE: named_properties.h:135 ISpxNamedProperties::Copy from=0x6c3327c to=0x6bcefa4 [827406]: 20381ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x06BE555C; name='Auto-Detect-Source-Languages'; value='es-HN,es-MX,es-PA' [827406]: 20381ms SPX_DBG_TRACE_VERBOSE: named_properties.h:135 ISpxNamedProperties::Copy from=0x6be555c to=0x6bcefa4 [827406]: 20381ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxPropertyBagImpl::SetStringValue: this=0x06BCEFA4; name='Auto-Detect-Source-Languages'; value='es-HN,es-MX,es-PA' [827406]: 20381ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x06BE53AC; name='CARBON-INTERNAL-UseEmbeddedSpeechConfig'; value='' [827406]: 20381ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:162 [06C09CF0]CSpxAudioStreamSession::CSpxAudioStreamSession [827406]: 20381ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxAudioStreamSession' as '333401089' [827406]: 20382ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:176 [06C09CF0]CSpxAudioStreamSession::Init:... [827406]: 20382ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxThreadService' as '303073113' [827406]: 20382ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x06C09D64; name='CARBON-INTERNAL-DumpAudioToDir'; value='' [827406]: 20382ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:349 CSpxAudioStreamSession::InitFromStream: Now Idle ... [827406]: 20382ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxAudioSessionShim' as '748887941' [827406]: 20382ms SPX_DBG_TRACE_SCOPE_ENTER: audio_session_shim.cpp:10 CSpxAudioSessionShim::Init [827406]: 20382ms SPX_DBG_TRACE_SCOPE_EXIT: audio_session_shim.cpp:10 CSpxAudioSessionShim::Init [827406]: 20382ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxAudioSourceWrapper' as '718981771' [827406]: 20382ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxStreamAudioSourceAdapter' as '718981771' [827406]: 20382ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:41 [06BC9090]CSpxAudioPump::CSpxAudioPump [827406]: 20382ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxAudioPump' as '495859151' [827406]: 20382ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxPropertyBagImpl::SetStringValue: this=0x06C09D64; name='AudioConfig_AudioSource'; value='Stream' [827406]: 20382ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxPropertyBagImpl::SetStringValue: this=0x06C09D64; name='AudioConfig_NumberOfChannelsForCapture'; value='1' [827406]: 20382ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxPropertyBagImpl::SetStringValue: this=0x06C09D64; name='AudioConfig_SampleRateForCapture'; value='8000' [827406]: 20382ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxPropertyBagImpl::SetStringValue: this=0x06C09D64; name='AudioConfig_BitsPerSampleForCapture'; value='16' [827406]: 20382ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxPropertyBagImpl::SetStringValue: this=0x06C3327C; name='AudioConfig_AudioProcessingOptions'; value='' [827406]: 20382ms SPX_DBG_TRACE_SCOPE_ENTER: recognizer.cpp:30 CSpxRecognizer::CSpxRecognizer [827406]: 20382ms SPX_DBG_TRACE_SCOPE_EXIT: recognizer.cpp:30 CSpxRecognizer::CSpxRecognizer [827406]: 20382ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxRecognizer' as '733483882' [827406]: 20382ms SPX_DBG_TRACE_FUNCTION: recognizer.cpp:41 CSpxRecognizer::Init [827406]: 20382ms SPX_DBG_TRACE_FUNCTION: audio_stream_session.cpp:721 CSpxAudioStreamSession::AddRecognizer [827406]: 20382ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x06C2D49C; name='UnsupportedV2ServiceProperties'; value='' [827406]: 20382ms SPX_DBG_TRACE_VERBOSE: handle_table.h:111 CSpxHandleTable::TrackHandle p=0x06C2D1A0 [827406]: 20382ms SPX_DBG_TRACE_VERBOSE: handle_table.h:121 CSpxHandleTable::TrackHandle class=ISpxRecognizer, h=0x06C2D1A0, p=0x06C2D1A0, tot=1 [827406]: 20382ms SPX_DBG_TRACE_SCOPE_EXIT: speechapi_c_factory.cpp:186 recognizer_create_speech_recognizer_from_auto_detect_source_lang_config [827406]: 20384ms SPX_TRACE_VERBOSE recognizer.cs:120 Constructor for 06c2d1a0 [827406]: 20384ms SPX_DBG_TRACE_VERBOSE: handle_table.h:111 CSpxHandleTable::TrackHandle p=0x06C2D49C [827406]: 20384ms SPX_DBG_TRACE_VERBOSE: handle_table.h:121 CSpxHandleTable::TrackHandle class=ISpxNamedProperties, h=0x06C2D49C, p=0x06C2D49C, tot=6 [620726]: 21768ms SPX_TRACE_VERBOSE interop_factory.cs:105 InteropSafeHandle Finalizer for 00000000 [620726]: 21768ms SPX_TRACE_VERBOSE interop_factory.cs:84 Dispose(False) called for 00000000 [620726]: 21768ms SPX_TRACE_VERBOSE interop_factory.cs:84 Dispose(False) called for 00000000 [645139]: 25397ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x06C2D49C; name='SPEECH-RecoMode'; value='' [645139]: 25397ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxPropertyBagImpl::SetStringValue: this=0x06C09D64; name='SPEECH-RecoMode'; value='CONVERSATION' [645139]: 25398ms SPX_DBG_TRACE_FUNCTION: audio_stream_session.cpp:1116 CSpxAudioStreamSession::StartRecognitionAsync [645139]: 25398ms SPX_DBG_TRACE_VERBOSE: handle_table.h:111 CSpxHandleTable::TrackHandle p=0x054255FC [645139]: 25398ms SPX_DBG_TRACE_VERBOSE: handle_table.h:121 CSpxHandleTable::TrackHandle class=CSpxAsyncOp, h=0x054255FC, p=0x054255FC, tot=1 [282217]: 25398ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:1119 *** CSpxAudioStreamSession::StartRecognitionAsync kicked-off THREAD started *** [282217]: 25417ms SPX_TRACE_INFO: thread_service.cpp:96 Started thread Background with ID [560060ll] [560060]: 25417ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:1176 CSpxAudioStreamSession::StartRecognizing [560060]: 25428ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1177 [06C09CF0]CSpxAudioStreamSession::StartRecognizing [560060]: 25428ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3581 [06C09CF0]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 0/0 => 4/1 [560060]: 25428ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1219 [06C09CF0]CSpxAudioStreamSession::StartRecognizing: Now WaitForPumpSetFormatStart ... [560060]: 25428ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:3163 CSpxAudioStreamSession::StartAudioPump [560060]: 25428ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3164 [06C09CF0]CSpxAudioStreamSession::StartAudioPump: RecognitionKind 4 | m_audioPump [06C14378] [560060]: 25428ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1446 [06C09CF0]CSpxAudioStreamSession::FireSessionStartedEvent: ... [560060]: 25428ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1457 [06C09CF0]CSpxAudioStreamSession::FireSessionStartedEvent: Firing SessionStarted event: SessionId: 115ee7a71a22474eafa665f19789efa3 [560060]: 25428ms SPX_TRACE_INFO: thread_service.cpp:96 Started thread User with ID [696288ll] [560060]: 25429ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x06C09D64; name='SPEECH-MaxBufferSizeMs'; value='66000' [560060]: 25429ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x06C09D64; name='SPEECH-BufferSizePercentSwitchToLowRate'; value='50' [560060]: 25429ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x06C09D64; name='SPEECH-BufferSizePercentSwitchToHighRate'; value='9' [696288]: 25429ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:1724 DispatchEvent task started... [560060]: 25429ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:104 [06BF9668] Is VAD Gating = 0, Is RNNT reco engine = 0, Is conversation transcriber = 0, Is meeting transcriber = 0, Is async transcriber = 0, fastLane = 5000 msec (80000 bytes), maxDuration = 66000 msec, highDuration = 33000 msec, lowDuration = 5940 msec, lowRate = 100%, highRate = 200% [560060]: 25429ms SPX_DBG_TRACE_FUNCTION: audio_buffer.cpp:92 PcmAudioBuffer::NewTurn [560060]: 25429ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxPropertyBagImpl::SetStringValue: this=0x06C09D64; name='IsKeywordAndSpeechRecognizer'; value='false' [560060]: 25431ms SPX_DBG_TRACE_FUNCTION: audio_stream_session.cpp:2785 CSpxAudioStreamSession::EnsureInitRecoEngineAdapter [560060]: 25431ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:2789 CSpxAudioStreamSession::EnsureInitRecoEngineAdapter EnsureResetEngineEngineAdapterComplete [560060]: 25431ms SPX_DBG_TRACE_FUNCTION: audio_stream_session.cpp:2895 CSpxAudioStreamSession::EnsureResetEngineEngineAdapterComplete [560060]: 25431ms SPX_DBG_TRACE_FUNCTION: audio_stream_session.cpp:2814 CSpxAudioStreamSession::InitRecoEngineAdapter [560060]: 25431ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxUspRecoEngineAdapterRetry_OffsetFixupWrapper' as '806006714' [560060]: 25431ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x06C09D64; name='SPEECH-Region'; value='eastus' [560060]: 25431ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxPropertyBagImpl::SetStringValue: this=0x06C09D64; name='INTENT-region'; value='eastus' [560060]: 25432ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3247 [06C09CF0]CSpxAudioStreamSession::StartAudioPump: Starting pump[06C14378]... [560060]: 25432ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxBufferData' as '705421989' [560060]: 25432ms SPX_TRACE_VERBOSE: buffer_data.cpp:118 [06BCFEE0]CSpxBufferData::EnsureInitRingBuffer - Init [560060]: 25432ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxBlockingReadWriteRingBuffer' as '956428994' [560060]: 25432ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x06C09D64; name='AudioConfig_NumberOfChannelsForCapture'; value='1' [560060]: 25432ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x06C09D64; name='AudioConfig_SampleRateForCapture'; value='8000' [560060]: 25432ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x06C09D64; name='AudioConfig_BitsPerSampleForCapture'; value='16' [560060]: 25432ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxAudioProcessorWriteToAudioSourceBuffer' as '354155511' [560060]: 25432ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:111 [06BC9090]CSpxAudioPump::StartPump(): [560060]: 25432ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:516 [06BC9090]CSpxAudioPump::WaitForPumpStart() ... pre m_cv.wait_for() [876056]: 25433ms SPX_DBG_TRACE_SCOPE_ENTER: audio_pump.cpp:173 *** AudioPump THREAD started! *** [696288]: 25439ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxSessionEventArgs' as '418547108' [696288]: 25440ms SPX_DBG_TRACE_VERBOSE: handle_table.h:111 CSpxHandleTable::TrackHandle p=0x06C1A760 [696288]: 25440ms SPX_DBG_TRACE_VERBOSE: handle_table.h:121 CSpxHandleTable::TrackHandle class=ISpxSessionEventArgs, h=0x06C1A760, p=0x06C1A760, tot=1 [696288]: 25442ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:1724 DispatchEvent task complete! [876056]: 25446ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:176 [06BC9090]CSpxAudioPump::PumpThread(): getting format from reader... [876056]: 25446ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:186 CSpxAudioPump::PumpThread(): setting format on processor... [876056]: 25446ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:208 [06BC9090]CSpxAudioPump::PumpThread(): allocating our first buffer [876056]: 25446ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:279 [06BC9090]CSpxAudioPump::PumpThread(): Starting loop ... => frame size: 1600 | wFormatTag: 'PCM'| nChannels: 1 | nSamplesPerSec: 8000 | nAvgBytesPerSec: 16000 | nBlockAlign: 2 | wBitsPerSample: 16 | cbSize: 0 [876056]: 25446ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:244 [06BC9090]CSpxAudioPump::PumpThread(), checkAndChangeState: changing states as requested: 'Idle' => 'Processing' [560060]: 25446ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:522 [06BC9090]CSpxAudioPump::WaitForPumpStart() ... post m_cv.wait_for(); state='Processing' (requestedState='Processing') [560060]: 25446ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:3163 CSpxAudioStreamSession::StartAudioPump [560060]: 25446ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:1176 CSpxAudioStreamSession::StartRecognizing [560060]: 25446ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:466 [06C09CF0]CSpxAudioStreamSession::SetFormat: format != nullptr [282217]: 25446ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:1119 *** CSpxAudioStreamSession::StartRecognitionAsync kicked-off THREAD stopped *** [560060]: 25446ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3581 [06C09CF0]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 4/1 => 4/2 [560060]: 25446ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:471 [06C09CF0]CSpxAudioStreamSession::SetFormat: Now ProcessingAudio ... [560060]: 25446ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:3394 CSpxAudioStreamSession::InformAdapterSetFormatStarting [560060]: 25447ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxUspRecoEngineAdapterRetry' as '806006714' [645139]: 25447ms SPX_DBG_TRACE_VERBOSE: handle_table.h:180 CSpxHandleTable::StopTracking(h) h=0x054255FC [560060]: 25447ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter_retry.cpp:19 CSpxUspRecoEngineAdapterRetry::InitDelegatePtr [645139]: 25447ms SPX_DBG_TRACE_VERBOSE: handle_table.h:195 CSpxHandleTable::StopTracking(h) class=CSpxAsyncOp, h=0x054255FC, p=0x054255FC, tot=0 [560060]: 25447ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:49 CSpxUspRecoEngineAdapter::CSpxUspRecoEngineAdapter [560060]: 25447ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:50 CSpxUspRecoEngineAdapter::CSpxUspRecoEngineAdapter: this=0x05490910 [560060]: 25447ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:49 CSpxUspRecoEngineAdapter::CSpxUspRecoEngineAdapter [560060]: 25447ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxUspRecoEngineAdapter' as '806006714' [560060]: 25447ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:63 CSpxUspRecoEngineAdapter::Init [560060]: 25447ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:64 CSpxUspRecoEngineAdapter::Init: this=0x05490910 [560060]: 25447ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x05490948; name='CARBON-INTERNAL-DumpAudioToDir'; value='' [560060]: 25447ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:63 CSpxUspRecoEngineAdapter::Init [560060]: 25447ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter_retry.cpp:19 CSpxUspRecoEngineAdapterRetry::InitDelegatePtr [560060]: 25447ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:115 CSpxUspRecoEngineAdapter::SetAdapterMode: singleShot=0 [560060]: 25447ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:224 CSpxUspRecoEngineAdapter::SetFormat [560060]: 25447ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:225 CSpxUspRecoEngineAdapter::SetFormat: this=0x05490910 [560060]: 25447ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:236 CSpxUspRecoEngineAdapter::SetFormat wFormatTag: PCM nChannels: 1 nSamplesPerSec: 8000 nAvgBytesPerSec: 16000 nBlockAlign: 2 wBitsPerSample: 16 cbSize: 0 [560060]: 25447ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:3008 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 0/0 => 1/0 USP-START [876056]: 25447ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:446 [06BC9090]CSpxAudioPump::PumpThread(): read frame duration: 0 ms => sending audio buffer size 0 [560060]: 25447ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:258 CSpxUspRecoEngineAdapter::SetFormat: (0x05490910)->PrepareFirstAudioReadyState() [560060]: 25447ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:3175 CSpxUspRecoEngineAdapter::ResetBeforeFirstAudio: this=0x05490910 ... USP-RESET [876056]: 25447ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxBufferProperties' as '809583448' [560060]: 25447ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:3024 CSpxUspRecoEngineAdapter::PrepareCompressionCodec: Prepare compression codec. [876056]: 25447ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxReadWriteRingBuffer' as '956428994' [560060]: 25447ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x05490948; name='SPEECH-Compression-Codec-Module'; value='' [560060]: 25447ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:3086 CSpxUspRecoEngineAdapter::PrepareFirstAudioReadyState: (0x05490910)->PrepareCompressionCodec() result: 4. Sending the audio uncompressed [876056]: 25447ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxReadWriteRingBuffer' as '956428994' [560060]: 25447ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x05490948; name='AZAC-SDK-PROGRAMMING-LANGUAGE'; value='C#' [876056]: 25447ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:537 Received audio chunk: time: 2024-01-09T18:49:39.3812010Z, size:0. [876056]: 25447ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:551 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 0 bytes (0 msec) [876056]: 25447ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:165 [06BF9668] m_bytesTransited = 0, unacknowledgedAudioDuration = 0 msec, packetAudioDelay = 0 msec [560060]: 25447ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxPropertyBagImpl::SetStringValue: this=0x06C09D64; name='HttpHeader#User-agent'; value='SpeechSDK-C#/1.34.0 Windows Client 8' [876056]: 25447ms SPX_TRACE_INFO: audio_pump.cpp:461 [06BC9090]CSpxAudioPump::PumpThread(): m_reader->Read() read ZERO (0) bytes... Indicating end of stream based input. [560060]: 25447ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:351 CSpxUspRecoEngineAdapter::UspInitialize: this=0x05490910 [876056]: 25447ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:244 [06BC9090]CSpxAudioPump::PumpThread(), checkAndChangeState: changing states as requested: 'Processing' => 'Idle' [560060]: 25447ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxUspCallbackWrapper' as '710381509' [876056]: 25447ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:257 [06BC9090]CSpxAudioPump::PumpThread(): checkAndChangeState: about to exit the while loop, pre-SetFormat(nullptr) [876056]: 25448ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:262 [06BC9090]CSpxAudioPump::PumpThread(): checkAndChangeState: about to exit the while loop, post-SetFormat(nullptr) [560060]: 25448ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x05490948; name='SessionId'; value='115ee7a71a22474eafa665f19789efa3' [876056]: 25448ms SPX_DBG_TRACE_SCOPE_EXIT: audio_pump.cpp:173 *** AudioPump THREAD stopped! *** [560060]: 25448ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1874 CSpxAudioStreamSession::GetScenarioCount: countSpeech=1; countIntent=0; countTranslation=0; countDialog=0, countConversationTranscriber=0, countConversationTranscriberV2=0, countMeetingTranscriber=0, countLanguageId=0 [560060]: 25448ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:778 CSpxUspRecoEngineAdapter::SetUspEndpointDefaultSpeechService: Endpoint type: Speech. [560060]: 25448ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x05490948; name='SPEECH-Region'; value='eastus' [560060]: 25448ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxPropertyBagImpl::SetStringValue: this=0x06C09D64; name='SPEECH-RecoLanguage'; value='en-us' [560060]: 25448ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x05490948; name='SpeechServiceResponse_RequestDetailedResultTrueFalse'; value='true' [560060]: 25448ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxPropertyBagImpl::SetStringValue: this=0x06C09D64; name='SpeechServiceResponse_OutputFormatOption'; value='detailed' [560060]: 25448ms SPX_TRACE_INFO: usp_reco_engine_adapter.cpp:1103 SpeechServiceConnection_LanguageIdMode not set or has invalid value . [560060]: 25448ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:641 CSpxUspRecoEngineAdapter::SetUspLanguageIdModeAndPriority: languageIdMode=0, languageIdPriority=0 [560060]: 25448ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x05490948; name='SPEECH-RecoMode'; value='CONVERSATION' [560060]: 25448ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:615 CSpxUspRecoEngineAdapter::SetUspRecoMode: recoMode=1 [560060]: 25448ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:602 CSpxUspRecoEngineAdapter::SetUspEndpoint: Setting Websocket Polling interval to 10 [560060]: 25448ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x05490948; name='SPEECH-SubscriptionKey'; value='******************************7b' [560060]: 25448ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::FindPrefix: this=0x05490948; name='User-agent'; value='SpeechSDK-C#/1.34.0 Windows Client 8' [560060]: 25448ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x05490948; name='SPEECH-ProxyHostBypass'; value='' [560060]: 25448ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x05490948; name='AZAC-SDK-PROGRAMMING-LANGUAGE'; value='C#' [560060]: 25448ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x05490948; name='AudioConfig_AudioSource'; value='Stream' [560060]: 25449ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x05490948; name='AudioConfig_SampleRateForCapture'; value='8000' [560060]: 25449ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x05490948; name='AudioConfig_BitsPerSampleForCapture'; value='16' [560060]: 25449ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x05490948; name='AudioConfig_NumberOfChannelsForCapture'; value='1' [560060]: 25449ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxUspConnection' as '792041254' [560060]: 25449ms SPX_TRACE_INFO: usp_connection.cpp:485 Microsoft::CognitiveServices::Speech::USP::CSpxUspConnection::Connect: entering... [560060]: 25449ms SPX_TRACE_INFO: usp_connection.cpp:502 Adding subscription key headers [560060]: 25451ms SPX_TRACE_INFO: usp_connection.cpp:537 Set a user defined HTTP header 'User-agent':'SpeechSDK-C#/1.34.0 Windows Client 8' [560060]: 25451ms SPX_TRACE_INFO: usp_connection.cpp:552 connectionUrl=wss://eastus.stt.speech.microsoft.com/speech/universal/v2 [560060]: 25451ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxTelemetry' as '151041793' [560060]: 25451ms SPX_DBG_TRACE_SCOPE_ENTER: web_socket.cpp:216 CSpxWebSocket::CSpxWebSocket [560060]: 25451ms SPX_DBG_TRACE_SCOPE_EXIT: web_socket.cpp:216 CSpxWebSocket::CSpxWebSocket [560060]: 25451ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxWebSocket' as '482598724' [560060]: 25451ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:31 WebSocketAdapter::UwsWebSocket::UwsWebSocket [560060]: 25451ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:31 WebSocketAdapter::UwsWebSocket::UwsWebSocket [560060]: 25451ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:43 WebSocketAdapter::UwsWebSocket::Initialize [560060]: 25451ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:43 WebSocketAdapter::UwsWebSocket::Initialize [560060]: 25451ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [560060]: 25451ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [560060]: 25451ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [560060]: 25451ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [560060]: 25451ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [560060]: 25451ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:221 WebSocketAdapter::UwsWebSocket::SetRequestHeader [560060]: 25451ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxPropertyBagImpl::SetStringValue: this=0x06C09D64; name='SPEECH-ConnectionUrl'; value='wss://eastus.stt.speech.microsoft.com/speech/universal/v2' [560060]: 25451ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1156 speech.config {"context":{"system":{"version":"1.34.0","name":"SpeechSDK","build":"Windows-x86","lang":"C#"},"os":{"name":"Client","version":"8","platform":"Windows"},"audio":{"source":{"type":"Stream","model":"","samplerate":"8000","bitspersample":"16","channelcount":"1"}}}} [560060]: 25451ms SPX_TRACE_INFO: usp_connection.cpp:787 Create requestId for messageType 0 [560060]: 25451ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:224 CSpxUspRecoEngineAdapter::SetFormat [560060]: 25451ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:3394 CSpxAudioStreamSession::InformAdapterSetFormatStarting [560060]: 25451ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:310 CSpxUspRecoEngineAdapter::ProcessAudio: (0x05490910) Ignoring audio size=0 ... (audioState/uspState=1/0) [560060]: 25451ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:692 [06C09CF0]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks [560060]: 25451ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:466 [06C09CF0]CSpxAudioStreamSession::SetFormat: format == nullptr [560060]: 25451ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3591 [06C09CF0]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 4/2 doesn't match [560060]: 25451ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3581 [06C09CF0]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 4/2 => 4/6 [560060]: 25451ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:487 [06C09CF0]CSpxAudioStreamSession::SetFormat: Have seen the end of the stream on the client, processing audio leftovers ... [560060]: 25452ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:511 [06C09CF0]CSpxAudioStreamSession::SetFormat: Now WaitForAdapterCompletedSetFormatStop (from ProcessingAudio) ... [560060]: 25452ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3581 [06C09CF0]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 4/6 => 4/5 [560060]: 25452ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:3443 CSpxAudioStreamSession::InformAdapterSetFormatStopping [560060]: 25452ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3459 CSpxAudioStreamSession::InformAdapterSetFormatStopping m_adapterStreamingAudio: false [560060]: 25452ms SPX_TRACE_INFO: audio_stream_session.cpp:3464 [06C09CF0]CSpxAudioStreamSession::InformAdapterSetFormatStoppingSetFormat(nullptr) [560060]: 25452ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:224 CSpxUspRecoEngineAdapter::SetFormat [560060]: 25452ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:225 CSpxUspRecoEngineAdapter::SetFormat: this=0x05490910 [560060]: 25452ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:227 CSpxUspRecoEngineAdapter::SetFormat - pformat == nullptr [560060]: 25452ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:3008 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 1/0 => 0/0 USP-DONE [560060]: 25452ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:264 CSpxUspRecoEngineAdapter::SetFormat: (0x05490910) site->AdapterCompletedSetFormatStop() [560060]: 25452ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:3495 *** CSpxAudioStreamSession::AdapterCompletedSetFormatStop kicked-off THREAD started *** [560060]: 25452ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3496 [06C09CF0]CSpxAudioStreamSession::AdapterCompletedSetFormatStop: kicked-off THREAD started *** [560060]: 25452ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3591 [06C09CF0]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 4/5 doesn't match [560060]: 25452ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3591 [06C09CF0]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 4/5 doesn't match [560060]: 25452ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3591 [06C09CF0]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 4/5 doesn't match [560060]: 25452ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3581 [06C09CF0]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 4/5 => 0/0 [560060]: 25452ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1472 [06C09CF0]CSpxAudioStreamSession::FireSessionStoppedEvent: Firing SessionStopped event: SessionId: 115ee7a71a22474eafa665f19789efa3 [560060]: 25452ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:1560 CSpxAudioStreamSession::EnsureFireResultEvent [560060]: 25452ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1561 [06C09CF0]CSpxAudioStreamSession::EnsureFireResultEvent [560060]: 25452ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:1560 CSpxAudioStreamSession::EnsureFireResultEvent [560060]: 25452ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:3495 *** CSpxAudioStreamSession::AdapterCompletedSetFormatStop kicked-off THREAD stopped *** [696288]: 25452ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:1724 DispatchEvent task started... [560060]: 25452ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:224 CSpxUspRecoEngineAdapter::SetFormat [560060]: 25452ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:3443 CSpxAudioStreamSession::InformAdapterSetFormatStopping [696288]: 25452ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:133 Created 'CSpxSessionEventArgs' as '418547108' [560060]: 25452ms SPX_DBG_TRACE_FUNCTION: audio_stream_session.cpp:3482 CSpxAudioStreamSession::EncounteredEndOfStream [696288]: 25452ms SPX_DBG_TRACE_VERBOSE: handle_table.h:111 CSpxHandleTable::TrackHandle p=0x06C1A320 [560060]: 25452ms SPX_TRACE_INFO: web_socket.cpp:800 CSpxWebSocket::DoWork: open transport. [696288]: 25452ms SPX_DBG_TRACE_VERBOSE: handle_table.h:121 CSpxHandleTable::TrackHandle class=ISpxSessionEventArgs, h=0x06C1A320, p=0x06C1A320, tot=2 [560060]: 25452ms SPX_TRACE_INFO: web_socket.cpp:479 Start to open websocket. WebSocket: 0x6b12a08, wsio handle: 0x6b1e1fc [560060]: 25452ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:149 WebSocketAdapter::UwsWebSocket::Open [696288]: 25453ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:1724 DispatchEvent task complete! [827406]: 25454ms SPX_DBG_TRACE_FUNCTION: audio_stream_session.cpp:1138 CSpxAudioStreamSession::StopRecognitionAsync [827406]: 25454ms SPX_DBG_TRACE_VERBOSE: handle_table.h:111 CSpxHandleTable::TrackHandle p=0x06C297AC [827406]: 25454ms SPX_DBG_TRACE_VERBOSE: handle_table.h:121 CSpxHandleTable::TrackHandle class=CSpxAsyncOp, h=0x06C297AC, p=0x06C297AC, tot=1 [620726]: 26847ms SPX_TRACE_VERBOSE interop_factory.cs:105 InteropSafeHandle Finalizer for 06c1a320 [620726]: 26847ms SPX_TRACE_VERBOSE interop_factory.cs:84 Dispose(False) called for 06c1a320 [620726]: 26848ms SPX_DBG_TRACE_VERBOSE: handle_table.h:180 CSpxHandleTable::StopTracking(h) h=0x06C1A320 [620726]: 26848ms SPX_DBG_TRACE_VERBOSE: handle_table.h:195 CSpxHandleTable::StopTracking(h) class=ISpxSessionEventArgs, h=0x06C1A320, p=0x06C1A320, tot=1 [620726]: 26848ms SPX_TRACE_VERBOSE interop_factory.cs:84 Dispose(False) called for 00000000 [620726]: 26848ms SPX_TRACE_VERBOSE interop_factory.cs:105 InteropSafeHandle Finalizer for 06c1a760 [620726]: 26848ms SPX_TRACE_VERBOSE interop_factory.cs:84 Dispose(False) called for 06c1a760 [620726]: 26848ms SPX_DBG_TRACE_VERBOSE: handle_table.h:180 CSpxHandleTable::StopTracking(h) h=0x06C1A760 [620726]: 26848ms SPX_DBG_TRACE_VERBOSE: handle_table.h:195 CSpxHandleTable::StopTracking(h) class=ISpxSessionEventArgs, h=0x06C1A760, p=0x06C1A760, tot=0 [620726]: 26848ms SPX_TRACE_VERBOSE interop_factory.cs:84 Dispose(False) called for 00000000 [560060]: 37551ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:149 WebSocketAdapter::UwsWebSocket::Open [560060]: 37551ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:1155 *** CSpxAudioStreamSession::StopRecognitionAsync kicked-off THREAD started *** [560060]: 37551ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:1277 CSpxAudioStreamSession::StopRecognizing [560060]: 37551ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1278 [06C09CF0]CSpxAudioStreamSession::StopRecognizing ... [560060]: 37552ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3591 [06C09CF0]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 0/0 doesn't match [560060]: 37552ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1380 [06C09CF0]CSpxAudioStreamSession::StopRecognizing: Now Idle/Idle already... [560060]: 37552ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:1277 CSpxAudioStreamSession::StopRecognizing [560060]: 37552ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:1155 *** CSpxAudioStreamSession::StopRecognitionAsync kicked-off THREAD stopped *** [282217]: 37552ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x06C09D64; name='SPEECH-StopRecognitionTimeoutInSeconds'; value='' [282217]: 37552ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:808 CSpxAudioStreamSession::WaitForIdle [282217]: 37552ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:809 [06C09CF0]CSpxAudioStreamSession::WaitForIdle timeout: 10000 msec [282217]: 37553ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:814 CSpxAudioStreamSession::WaitForIdle m_cv.wait_for, m_sessionState: 0, m_recoKind: 0, m_sessionStarted: 1, m_sessionStopped: 1 [282217]: 37553ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:808 CSpxAudioStreamSession::WaitForIdle [827406]: 37553ms SPX_DBG_TRACE_VERBOSE: handle_table.h:180 CSpxHandleTable::StopTracking(h) h=0x06C297AC [827406]: 37553ms SPX_DBG_TRACE_VERBOSE: handle_table.h:195 CSpxHandleTable::StopTracking(h) class=CSpxAsyncOp, h=0x06C297AC, p=0x06C297AC, tot=0 [827406]: 37556ms SPX_TRACE_VERBOSE interop_factory.cs:84 Dispose(True) called for 06c2d1a0 [827406]: 37556ms SPX_DBG_TRACE_FUNCTION: audio_stream_session.cpp:752 CSpxAudioStreamSession::SetDisposing [827406]: 37556ms SPX_DBG_TRACE_FUNCTION: audio_stream_session.cpp:1138 CSpxAudioStreamSession::StopRecognitionAsync [827406]: 37556ms SPX_DBG_TRACE_VERBOSE: handle_table.h:111 CSpxHandleTable::TrackHandle p=0x06C29A2C [827406]: 37556ms SPX_DBG_TRACE_VERBOSE: handle_table.h:121 CSpxHandleTable::TrackHandle class=CSpxAsyncOp, h=0x06C29A2C, p=0x06C29A2C, tot=1 [560060]: 37556ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:1155 *** CSpxAudioStreamSession::StopRecognitionAsync kicked-off THREAD started *** [560060]: 37557ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:1277 CSpxAudioStreamSession::StopRecognizing [560060]: 37557ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1278 [06C09CF0]CSpxAudioStreamSession::StopRecognizing ... [560060]: 37557ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3591 [06C09CF0]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 0/0 doesn't match [560060]: 37557ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1380 [06C09CF0]CSpxAudioStreamSession::StopRecognizing: Now Idle/Idle already... [560060]: 37557ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:1277 CSpxAudioStreamSession::StopRecognizing [560060]: 37557ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:1155 *** CSpxAudioStreamSession::StopRecognitionAsync kicked-off THREAD stopped *** [282217]: 37557ms SPX_DBG_TRACE_VERBOSE: named_properties.h:479 ISpxNamedProperties::GetStringValue: this=0x06C09D64; name='SPEECH-StopRecognitionTimeoutInSeconds'; value='' [282217]: 37558ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:808 CSpxAudioStreamSession::WaitForIdle [282217]: 37558ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:809 [06C09CF0]CSpxAudioStreamSession::WaitForIdle timeout: 10000 msec [282217]: 37558ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:814 CSpxAudioStreamSession::WaitForIdle m_cv.wait_for, m_sessionState: 0, m_recoKind: 0, m_sessionStarted: 1, m_sessionStopped: 1 [282217]: 37558ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:808 CSpxAudioStreamSession::WaitForIdle [827406]: 37558ms SPX_DBG_TRACE_VERBOSE: handle_table.h:180 CSpxHandleTable::StopTracking(h) h=0x06C29A2C [827406]: 37558ms SPX_DBG_TRACE_VERBOSE: handle_table.h:195 CSpxHandleTable::StopTracking(h) class=CSpxAsyncOp, h=0x06C29A2C, p=0x06C29A2C, tot=0 [827406]: 37558ms SPX_DBG_TRACE_VERBOSE: handle_table.h:180 CSpxHandleTable::StopTracking(h) h=0x06C2D1A0 [827406]: 37558ms SPX_DBG_TRACE_VERBOSE: handle_table.h:195 CSpxHandleTable::StopTracking(h) class=ISpxRecognizer, h=0x06C2D1A0, p=0x06C2D1A0, tot=0 [827406]: 37559ms SPX_TRACE_VERBOSE interop_factory.cs:84 Dispose(True) called for 06c2d49c [827406]: 37559ms SPX_DBG_TRACE_VERBOSE: handle_table.h:180 CSpxHandleTable::StopTracking(h) h=0x06C2D49C [827406]: 37559ms SPX_DBG_TRACE_VERBOSE: handle_table.h:195 CSpxHandleTable::StopTracking(h) class=ISpxNamedProperties, h=0x06C2D49C, p=0x06C2D49C, tot=5 [827406]: 37559ms SPX_DBG_TRACE_SCOPE_ENTER: recognizer.cpp:35 CSpxRecognizer::~CSpxRecognizer [827406]: 37559ms SPX_DBG_TRACE_SCOPE_ENTER: recognizer.cpp:394 CSpxRecognizer::TermDefaultSession [827406]: 37559ms SPX_DBG_TRACE_FUNCTION: audio_stream_session.cpp:758 CSpxAudioStreamSession::RemoveRecognizer [827406]: 37559ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x06C09D24 [827406]: 37559ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:196 CSpxAudioStreamSession::Term [827406]: 37559ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:197 [06C09CF0]CSpxAudioStreamSession::Term:... [827406]: 37559ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3591 [06C09CF0]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 0/0 doesn't match [827406]: 37559ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:224 [06C09CF0]CSpxAudioStreamSession::Term: **NOT CALLED** StopPump[06C14378] - state: 0 [827406]: 37560ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x06B7E79C [827406]: 37560ms SPX_DBG_TRACE_SCOPE_ENTER: thread_service.cpp:45 CSpxThreadService::Term [827406]: 37560ms SPX_DBG_TRACE_SCOPE_EXIT: thread_service.cpp:45 CSpxThreadService::Term [827406]: 37562ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:252 [06C09CF0]CSpxAudioStreamSession::CancelPendingSingleShot [827406]: 37562ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x06C14378 [827406]: 37562ms SPX_DBG_TRACE_SCOPE_ENTER: audio_session_shim.cpp:17 CSpxAudioSessionShim::Term [827406]: 37562ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x06AF902C [827406]: 37562ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x054B4044 [827406]: 37562ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x06BC9090 [827406]: 37563ms SPX_DBG_TRACE_VERBOSE: audio_pump.cpp:46 [06BC9090]CSpxAudioPump::~CSpxAudioPump [827406]: 37572ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x054FD50C [827406]: 37572ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x06BCFF04 [827406]: 37573ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x06AD25B8 [827406]: 37573ms SPX_DBG_TRACE_SCOPE_EXIT: audio_session_shim.cpp:17 CSpxAudioSessionShim::Term [827406]: 37573ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x06BD7FB8 [827406]: 37573ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x06BD64E8 [827406]: 37573ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x0541F148 [827406]: 37573ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x0543AF68 [827406]: 37581ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x0549093C [827406]: 37582ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:97 Terminating CSpxUspRecoEngineAdapter... [827406]: 37582ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:98 CSpxUspRecoEngineAdapter::Term: this=0x05490910 [827406]: 37582ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:3008 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 0/0 => 0/9998 USP-TERMINATING [827406]: 37582ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:102 CSpxUspRecoEngineAdapter::Term: Terminating USP Connection (0x00D64784) [827406]: 37582ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x06BC3724 [827406]: 37582ms SPX_DBG_TRACE_SCOPE_ENTER: usp_connection.cpp:146 Microsoft::CognitiveServices::Speech::USP::CSpxUspConnection::~CSpxUspConnection [827406]: 37582ms SPX_DBG_TRACE_SCOPE_EXIT: usp_connection.cpp:146 Microsoft::CognitiveServices::Speech::USP::CSpxUspConnection::~CSpxUspConnection [827406]: 37582ms SPX_DBG_TRACE_SCOPE_ENTER: web_socket.cpp:221 CSpxWebSocket::~CSpxWebSocket [827406]: 37583ms SPX_DBG_TRACE_SCOPE_EXIT: web_socket.cpp:221 CSpxWebSocket::~CSpxWebSocket [827406]: 37583ms SPX_TRACE_SCOPE_ENTER: uws_web_socket.cpp:36 WebSocketAdapter::UwsWebSocket::~UwsWebSocket [827406]: 37584ms SPX_TRACE_SCOPE_EXIT: uws_web_socket.cpp:36 WebSocketAdapter::UwsWebSocket::~UwsWebSocket [827406]: 37584ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:3008 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 0/9998 => 0/9999 USP-ZOMBIE [827406]: 37584ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:97 Terminating CSpxUspRecoEngineAdapter... Done! [827406]: 37584ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:55 CSpxUspRecoEngineAdapter::~CSpxUspRecoEngineAdapter [827406]: 37584ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:56 CSpxUspRecoEngineAdapter::~CSpxUspRecoEngineAdapter: this=0x05490910 [827406]: 37584ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:55 CSpxUspRecoEngineAdapter::~CSpxUspRecoEngineAdapter [827406]: 37584ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x06BCEFE4 [827406]: 37584ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:196 CSpxAudioStreamSession::Term [827406]: 37584ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:167 [06C09CF0]CSpxAudioStreamSession::~CSpxAudioStreamSession [827406]: 37585ms SPX_DBG_TRACE_SCOPE_ENTER: thread_service.cpp:45 CSpxThreadService::Term [827406]: 37585ms SPX_DBG_TRACE_SCOPE_EXIT: thread_service.cpp:45 CSpxThreadService::Term [827406]: 37585ms SPX_DBG_TRACE_SCOPE_EXIT: recognizer.cpp:394 CSpxRecognizer::TermDefaultSession [827406]: 37585ms SPX_DBG_TRACE_SCOPE_EXIT: recognizer.cpp:35 CSpxRecognizer::~CSpxRecognizer [827406]: 37587ms SPX_TRACE_VERBOSE recognizer.cs:169 Dispose(True) called for 06c2d1a0 [827406]: 37589ms SPX_TRACE_VERBOSE interop_factory.cs:84 Dispose(True) called for 06c14ab8 [827406]: 37590ms SPX_DBG_TRACE_VERBOSE: handle_table.h:180 CSpxHandleTable::StopTracking(h) h=0x06C14AB8 [827406]: 37590ms SPX_DBG_TRACE_VERBOSE: handle_table.h:195 CSpxHandleTable::StopTracking(h) class=ISpxAudioStream, h=0x06C14AB8, p=0x06C14AB8, tot=1 [827406]: 37590ms SPX_TRACE_VERBOSE interop_factory.cs:84 Dispose(True) called for 06c3327c [827406]: 37590ms SPX_DBG_TRACE_VERBOSE: handle_table.h:180 CSpxHandleTable::StopTracking(h) h=0x06C3327C [827406]: 37590ms SPX_DBG_TRACE_VERBOSE: handle_table.h:195 CSpxHandleTable::StopTracking(h) class=ISpxNamedProperties, h=0x06C3327C, p=0x06C3327C, tot=4 [599046]: 56486ms SPX_DBG_TRACE_FUNCTION: memory_logger.cpp:130 MemoryLogger::Exit [599046]: 56486ms SPX_DBG_TRACE_FUNCTION: resource_manager.cpp:103 CSpxResourceManager::~CSpxResourceManager