-
Notifications
You must be signed in to change notification settings - Fork 1
/
speech-logs.txt
1962 lines (1962 loc) · 243 KB
/
speech-logs.txt
1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
41
42
43
44
45
46
47
48
49
50
51
52
53
54
55
56
57
58
59
60
61
62
63
64
65
66
67
68
69
70
71
72
73
74
75
76
77
78
79
80
81
82
83
84
85
86
87
88
89
90
91
92
93
94
95
96
97
98
99
100
101
102
103
104
105
106
107
108
109
110
111
112
113
114
115
116
117
118
119
120
121
122
123
124
125
126
127
128
129
130
131
132
133
134
135
136
137
138
139
140
141
142
143
144
145
146
147
148
149
150
151
152
153
154
155
156
157
158
159
160
161
162
163
164
165
166
167
168
169
170
171
172
173
174
175
176
177
178
179
180
181
182
183
184
185
186
187
188
189
190
191
192
193
194
195
196
197
198
199
200
201
202
203
204
205
206
207
208
209
210
211
212
213
214
215
216
217
218
219
220
221
222
223
224
225
226
227
228
229
230
231
232
233
234
235
236
237
238
239
240
241
242
243
244
245
246
247
248
249
250
251
252
253
254
255
256
257
258
259
260
261
262
263
264
265
266
267
268
269
270
271
272
273
274
275
276
277
278
279
280
281
282
283
284
285
286
287
288
289
290
291
292
293
294
295
296
297
298
299
300
301
302
303
304
305
306
307
308
309
310
311
312
313
314
315
316
317
318
319
320
321
322
323
324
325
326
327
328
329
330
331
332
333
334
335
336
337
338
339
340
341
342
343
344
345
346
347
348
349
350
351
352
353
354
355
356
357
358
359
360
361
362
363
364
365
366
367
368
369
370
371
372
373
374
375
376
377
378
379
380
381
382
383
384
385
386
387
388
389
390
391
392
393
394
395
396
397
398
399
400
401
402
403
404
405
406
407
408
409
410
411
412
413
414
415
416
417
418
419
420
421
422
423
424
425
426
427
428
429
430
431
432
433
434
435
436
437
438
439
440
441
442
443
444
445
446
447
448
449
450
451
452
453
454
455
456
457
458
459
460
461
462
463
464
465
466
467
468
469
470
471
472
473
474
475
476
477
478
479
480
481
482
483
484
485
486
487
488
489
490
491
492
493
494
495
496
497
498
499
500
501
502
503
504
505
506
507
508
509
510
511
512
513
514
515
516
517
518
519
520
521
522
523
524
525
526
527
528
529
530
531
532
533
534
535
536
537
538
539
540
541
542
543
544
545
546
547
548
549
550
551
552
553
554
555
556
557
558
559
560
561
562
563
564
565
566
567
568
569
570
571
572
573
574
575
576
577
578
579
580
581
582
583
584
585
586
587
588
589
590
591
592
593
594
595
596
597
598
599
600
601
602
603
604
605
606
607
608
609
610
611
612
613
614
615
616
617
618
619
620
621
622
623
624
625
626
627
628
629
630
631
632
633
634
635
636
637
638
639
640
641
642
643
644
645
646
647
648
649
650
651
652
653
654
655
656
657
658
659
660
661
662
663
664
665
666
667
668
669
670
671
672
673
674
675
676
677
678
679
680
681
682
683
684
685
686
687
688
689
690
691
692
693
694
695
696
697
698
699
700
701
702
703
704
705
706
707
708
709
710
711
712
713
714
715
716
717
718
719
720
721
722
723
724
725
726
727
728
729
730
731
732
733
734
735
736
737
738
739
740
741
742
743
744
745
746
747
748
749
750
751
752
753
754
755
756
757
758
759
760
761
762
763
764
765
766
767
768
769
770
771
772
773
774
775
776
777
778
779
780
781
782
783
784
785
786
787
788
789
790
791
792
793
794
795
796
797
798
799
800
801
802
803
804
805
806
807
808
809
810
811
812
813
814
815
816
817
818
819
820
821
822
823
824
825
826
827
828
829
830
831
832
833
834
835
836
837
838
839
840
841
842
843
844
845
846
847
848
849
850
851
852
853
854
855
856
857
858
859
860
861
862
863
864
865
866
867
868
869
870
871
872
873
874
875
876
877
878
879
880
881
882
883
884
885
886
887
888
889
890
891
892
893
894
895
896
897
898
899
900
901
902
903
904
905
906
907
908
909
910
911
912
913
914
915
916
917
918
919
920
921
922
923
924
925
926
927
928
929
930
931
932
933
934
935
936
937
938
939
940
941
942
943
944
945
946
947
948
949
950
951
952
953
954
955
956
957
958
959
960
961
962
963
964
965
966
967
968
969
970
971
972
973
974
975
976
977
978
979
980
981
982
983
984
985
986
987
988
989
990
991
992
993
994
995
996
997
998
999
1000
[774417]: 64ms SPX_DBG_TRACE_FUNCTION: audio_stream_session.cpp:686 CSpxAudioStreamSession::AddRecognizer
[774417]: 64ms SPX_DBG_TRACE_VERBOSE: handle_table.h:100 CSpxHandleTable::TrackHandle p=0x000002067CF055B0
[774417]: 64ms SPX_DBG_TRACE_VERBOSE: handle_table.h:110 CSpxHandleTable::TrackHandle iid=733483882 h=0x000002067CF055B0, p=0x000002067CF055B0, tot=1
[774417]: 64ms SPX_DBG_TRACE_SCOPE_EXIT: speechapi_c_factory.cpp:158 recognizer_create_speech_recognizer_from_config
[774417]: 66ms SPX_TRACE_VERBOSE interop_factory.cs:72 Dispose(True) called for 00000000
[774417]: 67ms SPX_TRACE_VERBOSE recognizer.cs:120 Constructor for 2067cf055b0
[774417]: 67ms SPX_DBG_TRACE_VERBOSE: handle_table.h:100 CSpxHandleTable::TrackHandle p=0x000002067CF05AF8
[774417]: 67ms SPX_DBG_TRACE_VERBOSE: handle_table.h:110 CSpxHandleTable::TrackHandle iid=876984227 h=0x000002067CF05AF8, p=0x000002067CF05AF8, tot=3
[774417]: 68ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxAudioConfig' as '552907028'
[774417]: 68ms SPX_DBG_TRACE_VERBOSE: handle_table.h:100 CSpxHandleTable::TrackHandle p=0x000002067CEB32D8
[774417]: 68ms SPX_DBG_TRACE_VERBOSE: handle_table.h:110 CSpxHandleTable::TrackHandle iid=552907028 h=0x000002067CEB32D8, p=0x000002067CEB32D8, tot=2
[774417]: 68ms SPX_DBG_TRACE_VERBOSE: handle_table.h:100 CSpxHandleTable::TrackHandle p=0x000002067CEB3268
[774417]: 68ms SPX_DBG_TRACE_VERBOSE: handle_table.h:110 CSpxHandleTable::TrackHandle iid=876984227 h=0x000002067CEB3268, p=0x000002067CEB3268, tot=4
[774417]: 68ms SPX_DBG_TRACE_SCOPE_ENTER: speechapi_c_factory.cpp:478 synthesizer_create_speech_synthesizer_from_config
[774417]: 68ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSpeechSynthesisApiFactory' as '118875201'
[774417]: 68ms SPX_DBG_TRACE_VERBOSE: named_properties.h:62 ISpxNamedProperties::Copy from=0x7ac7bb28 to=0x7ceb8ae8
[774417]: 68ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067CEB8AE8; name='SPEECH-LogFilename'; value='speech-logs.txt'
[774417]: 68ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067CEB8AE8; name='SPEECH-Region'; value='westus'
[774417]: 68ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067CEB8AE8; name='SPEECH-SubscriptionKey'; value='******************************62'
[774417]: 68ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067CEB8AE8; name='SPEECHSDK-SPEECH-CONFIG-SYSTEM-LANGUAGE'; value='C#'
[774417]: 68ms SPX_DBG_TRACE_VERBOSE: named_properties.h:62 ISpxNamedProperties::Copy from=0x7ceb3268 to=0x7ceb8ae8
[774417]: 68ms SPX_RETURN_ON_FAIL: handle_helpers.h:52 hr = 0x21
[774417]: 68ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067AC7BB28; name='CARBON-INTERNAL-UseEmbeddedSpeechConfig'; value=''
[774417]: 68ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:34 CSpxSynthesizer::CSpxSynthesizer
[774417]: 68ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:34 CSpxSynthesizer::CSpxSynthesizer
[774417]: 68ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesizer' as '439569007'
[774417]: 68ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067CEB8AE8; name='SPEECH-LogFilename'; value='speech-logs.txt'
[774417]: 69ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxThreadService' as '303073113'
[774417]: 69ms SPX_TRACE_WARNING: resource_manager.cpp:100 Failed to create 'CSpxCodecAdapter' as '199052375'. Are all required extension libraries loaded?
[774417]: 69ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067CF0C768; name='SPEECH-SynthBackend'; value=''
[774417]: 69ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067CF0C768; name='SDK-INTERNAL-UseTtsEngine-Mock'; value='false'
[774417]: 69ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067CF0C768; name='CARBON-INTERNAL-UseTtsEngine-Mock'; value='false'
[774417]: 69ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067CF0C768; name='SDK-INTERNAL-UseTtsEngine-Rest'; value='false'
[774417]: 69ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067CF0C768; name='CARBON-INTERNAL-UseTtsEngine-Rest'; value='false'
[774417]: 69ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067CF0C768; name='SDK-INTERNAL-UseTtsEngine-Usp'; value='false'
[774417]: 69ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067CF0C768; name='CARBON-INTERNAL-UseTtsEngine-Usp'; value='false'
[774417]: 69ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:44 CSpxUspTtsEngineAdapter::CSpxUspTtsEngineAdapter
[774417]: 69ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxUspTtsEngineAdapter' as '919101823'
[774417]: 69ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxThreadService' as '303073113'
[774417]: 71ms SPX_DBG_TRACE_SCOPE_ENTER: com_init_and_uninit.cpp:18 ComInitAndUnInit ctor enter
[632265]: 71ms SPX_DBG_TRACE_SCOPE_ENTER: com_init_and_uninit.cpp:44 com worker_thread_proc() enter
[632265]: 71ms SPX_DBG_TRACE_VERBOSE: com_init_and_uninit.cpp:53 com successfully Initialized
[774417]: 71ms SPX_DBG_TRACE_SCOPE_EXIT: com_init_and_uninit.cpp:18 ComInitAndUnInit ctor done
[774417]: 71ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxDefaultSpeaker' as '853898539'
[774417]: 71ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxPullAudioOutputStream' as '853898539'
[774417]: 71ms SPX_DBG_TRACE_VERBOSE: cloud_tts_engine_adapter.cpp:121 CSpxCloudTtsEngineAdapter::SetOutput
[774417]: 71ms SPX_TRACE_INFO: cloud_tts_engine_adapter.cpp:170 CSpxCloudTtsEngineAdapter::SetOutput: request format [riff-16khz-16bit-mono-pcm] has header, using raw format instead for streaming.
[774417]: 71ms SPX_DBG_TRACE_VERBOSE: handle_table.h:100 CSpxHandleTable::TrackHandle p=0x000002067CF0C338
[774417]: 71ms SPX_DBG_TRACE_VERBOSE: handle_table.h:110 CSpxHandleTable::TrackHandle iid=439569007 h=0x000002067CF0C338, p=0x000002067CF0C338, tot=1
[774417]: 71ms SPX_DBG_TRACE_SCOPE_EXIT: speechapi_c_factory.cpp:478 synthesizer_create_speech_synthesizer_from_config
[774417]: 71ms SPX_TRACE_VERBOSE interop_factory.cs:72 Dispose(True) called for 2067ceb3268
[774417]: 71ms SPX_DBG_TRACE_VERBOSE: handle_table.h:169 CSpxHandleTable::StopTracking(h) h=0x000002067CEB3268
[774417]: 71ms SPX_DBG_TRACE_VERBOSE: handle_table.h:184 CSpxHandleTable::StopTracking(h) iid=876984227 h=0x000002067CEB3268, p=0x000002067CEB3268, tot=3
[774417]: 72ms SPX_DBG_TRACE_VERBOSE: handle_table.h:100 CSpxHandleTable::TrackHandle p=0x000002067CF0C768
[774417]: 72ms SPX_DBG_TRACE_VERBOSE: handle_table.h:110 CSpxHandleTable::TrackHandle iid=876984227 h=0x000002067CF0C768, p=0x000002067CF0C768, tot=4
[32914]: 114ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxAudioDataStream' as '418301898'
[32914]: 114ms SPX_DBG_TRACE_VERBOSE: null_audio_output.h:93 CSpxNullAudioOutput::SetFormat is called with format 0x000002067CEF9780
[32914]: 114ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[32914]: 114ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[32914]: 115ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:76 CSpxUspTtsEngineAdapter::Speak
[32914]: 115ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:94 CSpxUspTtsEngineAdapter::Speak: start to send synthesis request, request id : 8cbbd87c1a2c43c1a843e75c86d369bb, try: 0
[32914]: 115ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:167 SSML sent to TTS cognitive service: <speak version='1.0' xmlns='http://www.w3.org/2001/10/synthesis' xmlns:mstts='http://www.w3.org/2001/mstts' xmlns:emo='http://www.w3.org/2009/10/emotionml' xml:lang='en-us'><voice name='en-us-JennyNeural'>Welcome friend! Have you enrolled for voice verification before?</voice></speak>
[32914]: 115ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:443 CSpxUspTtsEngineAdapter::UspInitialize: this=0x000002067CEAD1F0
[849591]: 115ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[32914]: 115ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067CF0C768; name='SPEECH-SubscriptionKey'; value='******************************62'
[32914]: 115ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067CEAD258; name='SPEECHSDK-SPEECH-CONFIG-SYSTEM-LANGUAGE'; value='C#'
[849591]: 115ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[849591]: 115ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[32914]: 115ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxUspCallbackWrapper' as '978711522'
[849591]: 115ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[32914]: 115ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067CF0C768; name='SPEECH-Region'; value='westus'
[32914]: 115ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067CF0C768; name='SPEECH-ProxyHostBypass'; value=''
[32914]: 115ms SPX_TRACE_INFO: uspimpl.cpp:597 Microsoft::CognitiveServices::Speech::USP::Connection::Impl::Connect: entering...
[32914]: 115ms SPX_TRACE_INFO: uspimpl.cpp:614 Adding subscription key headers
[32914]: 115ms SPX_TRACE_INFO: uspimpl.cpp:649 Set a user defined HTTP header 'User-agent':'SpeechSDK-C#/1.18.0 Windows Client 10'
[32914]: 115ms SPX_TRACE_INFO: uspimpl.cpp:655 Set an underlying io option 'tcp_nodelay'
[32914]: 115ms SPX_TRACE_INFO: uspimpl.cpp:664 connectionUrl=wss://westus.tts.speech.microsoft.com/cognitiveservices/websocket/v1
[32914]: 115ms SPX_DBG_TRACE_SCOPE_ENTER: web_socket.cpp:224 Microsoft::CognitiveServices::Speech::USP::WebSocket::WebSocket
[32914]: 115ms SPX_DBG_TRACE_SCOPE_EXIT: web_socket.cpp:224 Microsoft::CognitiveServices::Speech::USP::WebSocket::WebSocket
[32914]: 115ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067CF0C768; name='SPEECH-ConnectionUrl'; value='wss://westus.tts.speech.microsoft.com/cognitiveservices/websocket/v1'
[891245]: 115ms SPX_TRACE_INFO: web_socket.cpp:783 Microsoft::CognitiveServices::Speech::USP::WebSocket::DoWork: open transport.
[32914]: 115ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:336 speech.config {"context":{"system":{"version":"1.18.0","name":"SpeechSDK","build":"Windows-x64"},"os":{"platform":"Windows","name":"Client","version":"10"}}}
[891245]: 115ms SPX_TRACE_INFO: web_socket.cpp:535 Start to open websocket. WebSocket: 0x7d0be100, wsio handle: 0x7ad398f0
[32914]: 115ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:383 speech.config='{"context":{"system":{"version":"1.18.0","name":"SpeechSDK","build":"Windows-x64"},"os":{"platform":"Windows","name":"Client","version":"10"}}}'
[32914]: 115ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:383 synthesis.context='{"synthesis":{"audio":{"outputFormat":"raw-16khz-16bit-mono-pcm","metadataOptions":{"visemeEnabled":false,"bookmarkEnabled":false,"wordBoundaryEnabled":false,"sentenceBoundaryEnabled":false}},"language":{"autoDetection":false}}}'
[32914]: 115ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:368 ssml <speak version='1.0' xmlns='http://www.w3.org/2001/10/synthesis' xmlns:mstts='http://www.w3.org/2001/mstts' xmlns:emo='http://www.w3.org/2009/10/emotionml' xml:lang='en-us'><voice name='en-us-JennyNeural'>Welcome friend! Have you enrolled for voice verification before?</voice></speak>
[32914]: 115ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:383 ssml='<speak version='1.0' xmlns='http://www.w3.org/2001/10/synthesis' xmlns:mstts='http://www.w3.org/2001/mstts' xmlns:emo='http://www.w3.org/2009/10/emotionml' xml:lang='en-us'><voice name='en-us-JennyNeural'>Welcome friend! Have you enrolled for voice verification before?</voice></speak>'
[891245]: 204ms SPX_TRACE_INFO: uspimpl.cpp:890 Create requestId for messageType 0
[891245]: 347ms SPX_TRACE_INFO: web_socket.cpp:859 Opening websocket completed. TransportRequest: 0x7d0be100, wsio handle: 0x7ad398f0
[891245]: 347ms SPX_TRACE_INFO: uspimpl.cpp:1005 TS:232, OnConnected: connection:0x7cf23140
[849591]: 347ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[849591]: 347ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:495 CSpxSynthesizer::FireConnectionChanged
[849591]: 347ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:496 CSpxSynthesizer::FireConnectionChanged: Connected
[849591]: 347ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxConnectionEventArgs' as '189265475'
[849591]: 347ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:495 CSpxSynthesizer::FireConnectionChanged
[849591]: 347ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 394ms SPX_TRACE_INFO: uspimpl.cpp:1243 Service originated request received with requestId: 8cbbd87c1a2c43c1a843e75c86d369bb
[891245]: 394ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: application/json; charset=utf-8.
[891245]: 394ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:279 Response Message: path: turn.start, size: 75.
[891245]: 394ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: application/json; charset=utf-8.
[891245]: 394ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:279 Response Message: path: response, size: 133.
[891245]: 394ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: audio/basic.
[891245]: 394ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:280 Response Message: path: audio, size: 4102.
[891245]: 394ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxPullAudioOutputStream' as '853898539'
[891245]: 394ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[891245]: 394ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[891245]: 395ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD870; name='RESULT-SynthesisBackend'; value='online (websocket)'
[891245]: 395ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D0E2CC7 size=4102
[891245]: 395ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:148 CSpxAudioDataStream::CSpxAudioDataStream::Write buffer 000002067D0E2CC7 size=4102
[849591]: 395ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[891245]: 395ms SPX_DBG_TRACE_SCOPE_ENTER: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[849591]: 395ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[891245]: 395ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067CEB8AE8; name='AudioConfig_DeviceNameForRender'; value=''
[849591]: 395ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[891245]: 395ms SPX_DBG_TRACE_VERBOSE: default_speaker.cpp:219 The device name of speaker as a property is ''
[849591]: 395ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 400ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D0E2CC7 size=4102
[891245]: 401ms SPX_DBG_TRACE_SCOPE_EXIT: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 401ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 4102
[891245]: 401ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[891245]: 401ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: audio/basic.
[891245]: 401ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:287 Response Message: path: audio, size: 4102.
[891245]: 402ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[891245]: 402ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[891245]: 402ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD190; name='RESULT-SynthesisBackend'; value='online (websocket)'
[891245]: 402ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 4102
[891245]: 402ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D0E2CC7 size=4102
[849591]: 402ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[891245]: 402ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:148 CSpxAudioDataStream::CSpxAudioDataStream::Write buffer 000002067D0E2CC7 size=4102
[849591]: 402ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[891245]: 402ms SPX_DBG_TRACE_SCOPE_ENTER: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[849591]: 402ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[891245]: 402ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D0E2CC7 size=4102
[849591]: 402ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 402ms SPX_DBG_TRACE_SCOPE_EXIT: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 402ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[891245]: 426ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: audio/basic.
[891245]: 426ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:311 Response Message: path: audio, size: 4102.
[891245]: 426ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[891245]: 426ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[891245]: 426ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD870; name='RESULT-SynthesisBackend'; value='online (websocket)'
[891245]: 426ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 8204
[891245]: 426ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D0E2CC7 size=4102
[849591]: 426ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[891245]: 426ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:148 CSpxAudioDataStream::CSpxAudioDataStream::Write buffer 000002067D0E2CC7 size=4102
[849591]: 426ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[891245]: 426ms SPX_DBG_TRACE_SCOPE_ENTER: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[849591]: 426ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[891245]: 426ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D0E2CC7 size=4102
[849591]: 426ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 426ms SPX_DBG_TRACE_SCOPE_EXIT: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 426ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[891245]: 427ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: audio/basic.
[891245]: 427ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:312 Response Message: path: audio, size: 4102.
[891245]: 427ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[891245]: 427ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[891245]: 427ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD870; name='RESULT-SynthesisBackend'; value='online (websocket)'
[891245]: 427ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 12306
[891245]: 427ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D0E2CC7 size=4102
[849591]: 427ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[891245]: 427ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:148 CSpxAudioDataStream::CSpxAudioDataStream::Write buffer 000002067D0E2CC7 size=4102
[849591]: 427ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[891245]: 427ms SPX_DBG_TRACE_SCOPE_ENTER: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[849591]: 427ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[891245]: 427ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D0E2CC7 size=4102
[849591]: 427ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 427ms SPX_DBG_TRACE_SCOPE_EXIT: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 427ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[891245]: 427ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: audio/basic.
[891245]: 427ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:313 Response Message: path: audio, size: 4102.
[891245]: 427ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[891245]: 427ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[891245]: 427ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD190; name='RESULT-SynthesisBackend'; value='online (websocket)'
[891245]: 427ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 16408
[891245]: 427ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D0E2CC7 size=4102
[849591]: 427ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[891245]: 427ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:148 CSpxAudioDataStream::CSpxAudioDataStream::Write buffer 000002067D0E2CC7 size=4102
[849591]: 428ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[891245]: 428ms SPX_DBG_TRACE_SCOPE_ENTER: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[849591]: 428ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[891245]: 428ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D0E2CC7 size=4102
[849591]: 428ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 428ms SPX_DBG_TRACE_SCOPE_EXIT: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 428ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[891245]: 428ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: audio/basic.
[891245]: 428ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:313 Response Message: path: audio, size: 4102.
[891245]: 428ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[891245]: 428ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[891245]: 428ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD190; name='RESULT-SynthesisBackend'; value='online (websocket)'
[891245]: 428ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 20510
[891245]: 428ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D0E2CC7 size=4102
[849591]: 428ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[891245]: 428ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:148 CSpxAudioDataStream::CSpxAudioDataStream::Write buffer 000002067D0E2CC7 size=4102
[849591]: 428ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[849591]: 428ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[849591]: 428ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 428ms SPX_DBG_TRACE_SCOPE_ENTER: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 428ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D0E2CC7 size=4102
[891245]: 428ms SPX_DBG_TRACE_SCOPE_EXIT: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 428ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[891245]: 428ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: audio/basic.
[891245]: 429ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:314 Response Message: path: audio, size: 4102.
[891245]: 429ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[891245]: 429ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[891245]: 429ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD870; name='RESULT-SynthesisBackend'; value='online (websocket)'
[891245]: 429ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 24612
[849591]: 429ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[891245]: 429ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D0E2CC7 size=4102
[849591]: 429ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[891245]: 429ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:148 CSpxAudioDataStream::CSpxAudioDataStream::Write buffer 000002067D0E2CC7 size=4102
[849591]: 429ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[891245]: 429ms SPX_DBG_TRACE_SCOPE_ENTER: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[849591]: 429ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 429ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D0E2CC7 size=4102
[891245]: 429ms SPX_DBG_TRACE_SCOPE_EXIT: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 429ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[673847]: 433ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:69 CSpxPullAudioOutputStream::Read: is called
[891245]: 441ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: audio/basic.
[891245]: 441ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:326 Response Message: path: audio, size: 24576.
[891245]: 441ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[891245]: 441ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[891245]: 441ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD190; name='RESULT-SynthesisBackend'; value='online (websocket)'
[891245]: 441ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 28714
[891245]: 441ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=24576
[849591]: 441ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[891245]: 441ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:148 CSpxAudioDataStream::CSpxAudioDataStream::Write buffer 000002067D117407 size=24576
[849591]: 441ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[849591]: 441ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[891245]: 441ms SPX_DBG_TRACE_SCOPE_ENTER: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[849591]: 441ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 442ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=24576
[891245]: 442ms SPX_DBG_TRACE_SCOPE_EXIT: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 442ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[673847]: 442ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:136 CSpxPullAudioOutputStream::Read: bytesRead=32000
[673847]: 442ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:69 CSpxPullAudioOutputStream::Read: is called
[891245]: 442ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: audio/basic.
[891245]: 442ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:327 Response Message: path: audio, size: 4096.
[891245]: 442ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[891245]: 442ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[891245]: 442ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD190; name='RESULT-SynthesisBackend'; value='online (websocket)'
[891245]: 442ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 53290
[849591]: 442ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[891245]: 442ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=4096
[849591]: 442ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[891245]: 442ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:148 CSpxAudioDataStream::CSpxAudioDataStream::Write buffer 000002067D117407 size=4096
[849591]: 442ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[891245]: 442ms SPX_DBG_TRACE_SCOPE_ENTER: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[849591]: 442ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 442ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=4096
[891245]: 442ms SPX_DBG_TRACE_SCOPE_EXIT: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 442ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[891245]: 442ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: audio/basic.
[891245]: 442ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:328 Response Message: path: audio, size: 4102.
[891245]: 442ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[891245]: 442ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[891245]: 442ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD190; name='RESULT-SynthesisBackend'; value='online (websocket)'
[891245]: 442ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 57386
[849591]: 443ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[891245]: 443ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=4102
[849591]: 443ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[849591]: 443ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[891245]: 443ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:148 CSpxAudioDataStream::CSpxAudioDataStream::Write buffer 000002067D117407 size=4102
[849591]: 443ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 443ms SPX_DBG_TRACE_SCOPE_ENTER: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 443ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=4102
[891245]: 443ms SPX_DBG_TRACE_SCOPE_EXIT: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 443ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[891245]: 443ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: audio/basic.
[891245]: 443ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:328 Response Message: path: audio, size: 4102.
[891245]: 443ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[891245]: 443ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[891245]: 443ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD190; name='RESULT-SynthesisBackend'; value='online (websocket)'
[891245]: 443ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 61488
[849591]: 443ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[891245]: 443ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=4102
[849591]: 443ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[891245]: 443ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:148 CSpxAudioDataStream::CSpxAudioDataStream::Write buffer 000002067D117407 size=4102
[849591]: 443ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[891245]: 443ms SPX_DBG_TRACE_SCOPE_ENTER: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[849591]: 443ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 444ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=4102
[891245]: 444ms SPX_DBG_TRACE_SCOPE_EXIT: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 444ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[673847]: 444ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:136 CSpxPullAudioOutputStream::Read: bytesRead=32000
[673847]: 444ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:69 CSpxPullAudioOutputStream::Read: is called
[891245]: 444ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: audio/basic.
[891245]: 444ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:329 Response Message: path: audio, size: 8192.
[891245]: 444ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[891245]: 444ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[891245]: 444ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD870; name='RESULT-SynthesisBackend'; value='online (websocket)'
[891245]: 444ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 65590
[849591]: 444ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[891245]: 444ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=8192
[849591]: 444ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[849591]: 444ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[849591]: 444ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 444ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:148 CSpxAudioDataStream::CSpxAudioDataStream::Write buffer 000002067D117407 size=8192
[891245]: 444ms SPX_DBG_TRACE_SCOPE_ENTER: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 444ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=8192
[891245]: 444ms SPX_DBG_TRACE_SCOPE_EXIT: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 444ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[891245]: 444ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: audio/basic.
[891245]: 445ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:330 Response Message: path: audio, size: 4096.
[891245]: 445ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[891245]: 445ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[891245]: 445ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD190; name='RESULT-SynthesisBackend'; value='online (websocket)'
[891245]: 445ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 73782
[849591]: 445ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[891245]: 445ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=4096
[849591]: 445ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[891245]: 445ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:148 CSpxAudioDataStream::CSpxAudioDataStream::Write buffer 000002067D117407 size=4096
[849591]: 445ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[891245]: 445ms SPX_DBG_TRACE_SCOPE_ENTER: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[849591]: 445ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 445ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=4096
[891245]: 445ms SPX_DBG_TRACE_SCOPE_EXIT: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 445ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[891245]: 456ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: audio/basic.
[891245]: 456ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:341 Response Message: path: audio, size: 4096.
[891245]: 456ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[891245]: 456ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[891245]: 456ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD870; name='RESULT-SynthesisBackend'; value='online (websocket)'
[891245]: 456ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 77878
[891245]: 456ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=4096
[849591]: 456ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[891245]: 456ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:148 CSpxAudioDataStream::CSpxAudioDataStream::Write buffer 000002067D117407 size=4096
[849591]: 456ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[891245]: 456ms SPX_DBG_TRACE_SCOPE_ENTER: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[849591]: 456ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[891245]: 456ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=4096
[849591]: 456ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 456ms SPX_DBG_TRACE_SCOPE_EXIT: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 456ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[891245]: 457ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: audio/basic.
[891245]: 457ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:342 Response Message: path: audio, size: 4096.
[891245]: 457ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[891245]: 457ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[891245]: 457ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD190; name='RESULT-SynthesisBackend'; value='online (websocket)'
[891245]: 457ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 81974
[849591]: 457ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[891245]: 457ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=4096
[849591]: 457ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[891245]: 457ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:148 CSpxAudioDataStream::CSpxAudioDataStream::Write buffer 000002067D117407 size=4096
[849591]: 457ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[891245]: 457ms SPX_DBG_TRACE_SCOPE_ENTER: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[849591]: 457ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 457ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=4096
[891245]: 457ms SPX_DBG_TRACE_SCOPE_EXIT: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 457ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[891245]: 457ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: audio/basic.
[891245]: 457ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:343 Response Message: path: audio, size: 4102.
[891245]: 457ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[891245]: 457ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[891245]: 457ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD870; name='RESULT-SynthesisBackend'; value='online (websocket)'
[891245]: 458ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 86070
[849591]: 458ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[891245]: 458ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=4102
[849591]: 458ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[891245]: 458ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:148 CSpxAudioDataStream::CSpxAudioDataStream::Write buffer 000002067D117407 size=4102
[849591]: 458ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[891245]: 458ms SPX_DBG_TRACE_SCOPE_ENTER: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[849591]: 458ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 458ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=4102
[891245]: 458ms SPX_DBG_TRACE_SCOPE_EXIT: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 458ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[891245]: 472ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: audio/basic.
[891245]: 472ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:357 Response Message: path: audio, size: 8192.
[891245]: 472ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[891245]: 472ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[891245]: 472ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD870; name='RESULT-SynthesisBackend'; value='online (websocket)'
[891245]: 472ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 90172
[891245]: 472ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=8192
[849591]: 472ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[891245]: 472ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:148 CSpxAudioDataStream::CSpxAudioDataStream::Write buffer 000002067D117407 size=8192
[849591]: 472ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[891245]: 472ms SPX_DBG_TRACE_SCOPE_ENTER: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[849591]: 472ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[891245]: 472ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=8192
[849591]: 472ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 472ms SPX_DBG_TRACE_SCOPE_EXIT: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 472ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[673847]: 472ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:136 CSpxPullAudioOutputStream::Read: bytesRead=32000
[673847]: 473ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:69 CSpxPullAudioOutputStream::Read: is called
[891245]: 473ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: audio/basic.
[891245]: 473ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:358 Response Message: path: audio, size: 4096.
[891245]: 473ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[891245]: 473ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[891245]: 473ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD870; name='RESULT-SynthesisBackend'; value='online (websocket)'
[891245]: 473ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 98364
[891245]: 473ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=4096
[849591]: 473ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[891245]: 473ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:148 CSpxAudioDataStream::CSpxAudioDataStream::Write buffer 000002067D117407 size=4096
[849591]: 473ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[891245]: 473ms SPX_DBG_TRACE_SCOPE_ENTER: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[849591]: 473ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[891245]: 473ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=4096
[849591]: 473ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 473ms SPX_DBG_TRACE_SCOPE_EXIT: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 473ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[891245]: 473ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: audio/basic.
[891245]: 473ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:359 Response Message: path: audio, size: 4096.
[891245]: 473ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[891245]: 473ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[891245]: 473ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD190; name='RESULT-SynthesisBackend'; value='online (websocket)'
[891245]: 473ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 102460
[849591]: 473ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[891245]: 473ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=4096
[849591]: 474ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[891245]: 474ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:148 CSpxAudioDataStream::CSpxAudioDataStream::Write buffer 000002067D117407 size=4096
[849591]: 474ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[891245]: 474ms SPX_DBG_TRACE_SCOPE_ENTER: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[849591]: 474ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 474ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=4096
[891245]: 474ms SPX_DBG_TRACE_SCOPE_EXIT: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 474ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[891245]: 474ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: audio/basic.
[891245]: 474ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:359 Response Message: path: audio, size: 4096.
[891245]: 474ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[891245]: 474ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[891245]: 474ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD190; name='RESULT-SynthesisBackend'; value='online (websocket)'
[891245]: 474ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 106556
[849591]: 474ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[891245]: 474ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=4096
[849591]: 474ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[891245]: 474ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:148 CSpxAudioDataStream::CSpxAudioDataStream::Write buffer 000002067D117407 size=4096
[849591]: 474ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[891245]: 474ms SPX_DBG_TRACE_SCOPE_ENTER: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[849591]: 474ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 474ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=4096
[891245]: 474ms SPX_DBG_TRACE_SCOPE_EXIT: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 474ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[891245]: 474ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: audio/basic.
[891245]: 474ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:360 Response Message: path: audio, size: 4102.
[891245]: 474ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[891245]: 475ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[891245]: 475ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD190; name='RESULT-SynthesisBackend'; value='online (websocket)'
[891245]: 475ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 110652
[849591]: 475ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[891245]: 475ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=4102
[849591]: 475ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[891245]: 475ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:148 CSpxAudioDataStream::CSpxAudioDataStream::Write buffer 000002067D117407 size=4102
[849591]: 475ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[891245]: 475ms SPX_DBG_TRACE_SCOPE_ENTER: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[849591]: 475ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 475ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=4102
[891245]: 475ms SPX_DBG_TRACE_SCOPE_EXIT: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 475ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[891245]: 475ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: audio/basic.
[891245]: 475ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:360 Response Message: path: audio, size: 4102.
[891245]: 475ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[891245]: 475ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[891245]: 475ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD870; name='RESULT-SynthesisBackend'; value='online (websocket)'
[891245]: 475ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 114754
[849591]: 475ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[891245]: 475ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=4102
[849591]: 475ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[891245]: 475ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:148 CSpxAudioDataStream::CSpxAudioDataStream::Write buffer 000002067D117407 size=4102
[849591]: 475ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[891245]: 475ms SPX_DBG_TRACE_SCOPE_ENTER: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[849591]: 475ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 475ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=4102
[891245]: 475ms SPX_DBG_TRACE_SCOPE_EXIT: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 475ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[891245]: 476ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: audio/basic.
[891245]: 476ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:361 Response Message: path: audio, size: 8192.
[891245]: 476ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[891245]: 476ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[891245]: 476ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD870; name='RESULT-SynthesisBackend'; value='online (websocket)'
[891245]: 476ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 118856
[849591]: 476ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[891245]: 476ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=8192
[849591]: 476ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[891245]: 476ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:148 CSpxAudioDataStream::CSpxAudioDataStream::Write buffer 000002067D117407 size=8192
[849591]: 476ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[891245]: 476ms SPX_DBG_TRACE_SCOPE_ENTER: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[849591]: 476ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 476ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=8192
[891245]: 476ms SPX_DBG_TRACE_SCOPE_EXIT: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 476ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[891245]: 476ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: audio/basic.
[891245]: 476ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:362 Response Message: path: audio, size: 4096.
[891245]: 476ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[891245]: 476ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[891245]: 476ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD190; name='RESULT-SynthesisBackend'; value='online (websocket)'
[891245]: 476ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 127048
[849591]: 476ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[891245]: 476ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=4096
[849591]: 476ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[891245]: 476ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:148 CSpxAudioDataStream::CSpxAudioDataStream::Write buffer 000002067D117407 size=4096
[849591]: 476ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[891245]: 477ms SPX_DBG_TRACE_SCOPE_ENTER: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[849591]: 477ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 477ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=4096
[891245]: 477ms SPX_DBG_TRACE_SCOPE_EXIT: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 477ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[673847]: 477ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:136 CSpxPullAudioOutputStream::Read: bytesRead=32000
[673847]: 477ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:69 CSpxPullAudioOutputStream::Read: is called
[891245]: 477ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: audio/basic.
[891245]: 477ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:363 Response Message: path: audio, size: 20486.
[891245]: 477ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[891245]: 477ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[891245]: 478ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD190; name='RESULT-SynthesisBackend'; value='online (websocket)'
[891245]: 478ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 131144
[849591]: 478ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[891245]: 478ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=20486
[849591]: 478ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[849591]: 478ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[891245]: 478ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:148 CSpxAudioDataStream::CSpxAudioDataStream::Write buffer 000002067D117407 size=20486
[849591]: 478ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 478ms SPX_DBG_TRACE_SCOPE_ENTER: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 478ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=20486
[891245]: 478ms SPX_DBG_TRACE_SCOPE_EXIT: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 478ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[891245]: 478ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: audio/basic.
[891245]: 478ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:363 Response Message: path: audio, size: 3978.
[891245]: 478ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[891245]: 478ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[891245]: 478ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD870; name='RESULT-SynthesisBackend'; value='online (websocket)'
[891245]: 478ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 151630
[849591]: 478ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[891245]: 478ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=3978
[849591]: 478ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[891245]: 478ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:148 CSpxAudioDataStream::CSpxAudioDataStream::Write buffer 000002067D117407 size=3978
[849591]: 478ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[891245]: 478ms SPX_DBG_TRACE_SCOPE_ENTER: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[849591]: 478ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[891245]: 478ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:26 CSpxPullAudioOutputStream::Write buffer 000002067D117407 size=3978
[891245]: 478ms SPX_DBG_TRACE_SCOPE_EXIT: default_speaker.cpp:107 CSpxDefaultSpeaker::Write
[891245]: 479ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[891245]: 479ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:364 Response Message: path: audio, size: 0.
[891245]: 479ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: application/json; charset=utf-8.
[891245]: 479ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:364 Response Message: path: turn.end, size: 2.
[891245]: 479ms SPX_DBG_TRACE_VERBOSE: uspimpl.cpp:1345 Got turn end, clear m_streamIdLangMap.
[891245]: 479ms SPX_TRACE_INFO: usp_metrics.cpp:429 Microsoft::CognitiveServices::Speech::USP::Telemetry::SendSerializedTelemetry: Send telemetry (requestId:8cbbd87c1a2c43c1a843e75c86d369bb): {"Metrics":[{"End":"2021-08-23T22:41:15.533Z","Id":"80b85818a3de49ab854080ea37509163","Name":"Connection","Start":"2021-08-23T22:41:15.301Z"},{"DeviceId":["57455683-d3db-41c7-a661-46e023365507"],"Name":"device"}],"ReceivedMessages":[]}
[891245]: 479ms SPX_TRACE_INFO: usp_metrics.cpp:429 Microsoft::CognitiveServices::Speech::USP::Telemetry::SendSerializedTelemetry: Send telemetry (requestId:8cbbd87c1a2c43c1a843e75c86d369bb): {"Metrics":[],"ReceivedMessages":[{"turn.start":["2021-08-23T22:41:15.580Z"]},{"turn.end":["2021-08-23T22:41:15.665Z"]},{"audio":["2021-08-23T22:41:15.580Z","2021-08-23T22:41:15.587Z","2021-08-23T22:41:15.612Z","2021-08-23T22:41:15.613Z","2021-08-23T22:41:15.613Z","2021-08-23T22:41:15.614Z","2021-08-23T22:41:15.615Z","2021-08-23T22:41:15.627Z","2021-08-23T22:41:15.628Z","2021-08-23T22:41:15.629Z","2021-08-23T22:41:15.630Z","2021-08-23T22:41:15.630Z","2021-08-23T22:41:15.631Z","2021-08-23T22:41:15.642Z","2021-08-23T22:41:15.643Z","2021-08-23T22:41:15.643Z","2021-08-23T22:41:15.658Z","2021-08-23T22:41:15.659Z","2021-08-23T22:41:15.659Z","2021-08-23T22:41:15.660Z","2021-08-23T22:41:15.661Z","2021-08-23T22:41:15.661Z","2021-08-23T22:41:15.662Z","2021-08-23T22:41:15.662Z","2021-08-23T22:41:15.663Z","2021-08-23T22:41:15.664Z","2021-08-23T22:41:15.665Z"]},{"response":["2021-08-23T22:41:15.580Z"]}]}
[32914]: 479ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[32914]: 479ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[32914]: 479ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:161 Signal End of Writing is called
[32914]: 479ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 155608
[32914]: 479ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:89 CSpxPullAudioOutputStream::AvailableSize: current available size: 155608
[32914]: 480ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:69 CSpxPullAudioOutputStream::Read: is called
[32914]: 480ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:136 CSpxPullAudioOutputStream::Read: bytesRead=155608
[32914]: 480ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD190; name='RESULT-SynthesisBackend'; value='online (websocket)'
[32914]: 480ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD190; name='RESULT-SynthesisFirstByteLatencyMs'; value='280'
[32914]: 480ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD190; name='RESULT-SynthesisFinishLatencyMs'; value='365'
[32914]: 480ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0AD190; name='RESULT-SyntheisisUnderrunTimeMs'; value='0'
[32914]: 480ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:161 Signal End of Writing is called
[32914]: 480ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:161 Signal End of Writing is called
[673847]: 480ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:136 CSpxPullAudioOutputStream::Read: bytesRead=27608
[673847]: 480ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:69 CSpxPullAudioOutputStream::Read: is called
[673847]: 480ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:136 CSpxPullAudioOutputStream::Read: bytesRead=0
[673847]: 5312ms SPX_DBG_TRACE_VERBOSE: default_speaker.cpp:264 CSpxDefaultSpeaker::AudioCompleteCallback
[32914]: 5312ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:29 CSpxSynthesisResult::~CSpxSynthesisResult
[849591]: 5312ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...
[32914]: 5312ms SPX_DBG_TRACE_VERBOSE: handle_table.h:100 CSpxHandleTable::TrackHandle p=0x000002067D0AD170
[849591]: 5312ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:423 [000002067CF0C300]CSpxSynthesizer::CSpxSynthesizer::FireResultEvent
[32914]: 5312ms SPX_DBG_TRACE_VERBOSE: handle_table.h:110 CSpxHandleTable::TrackHandle iid=3874248 h=0x000002067D0AD170, p=0x000002067D0AD170, tot=1
[849591]: 5312ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:459 No listener connected to event
[849591]: 5312ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:925 DispatchEvent task complete!
[32914]: 5320ms SPX_DBG_TRACE_VERBOSE: handle_table.h:100 CSpxHandleTable::TrackHandle p=0x000002067D0AD190
[32914]: 5320ms SPX_DBG_TRACE_VERBOSE: handle_table.h:110 CSpxHandleTable::TrackHandle iid=876984227 h=0x000002067D0AD190, p=0x000002067D0AD190, tot=5
[830554]: 5330ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067CF05AF8; name='SPEECH-RecoMode'; value=''
[830554]: 5330ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067CEB1C28; name='SPEECH-RecoMode'; value='INTERACTIVE'
[830554]: 5330ms SPX_DBG_TRACE_FUNCTION: audio_stream_session.cpp:844 CSpxAudioStreamSession::RecognizeAsync
[830554]: 5331ms SPX_DBG_TRACE_VERBOSE: handle_table.h:100 CSpxHandleTable::TrackHandle p=0x000002067D0D3F20
[830554]: 5331ms SPX_DBG_TRACE_VERBOSE: handle_table.h:110 CSpxHandleTable::TrackHandle iid=429707311 h=0x000002067D0D3F20, p=0x000002067D0D3F20, tot=1
[86313]: 5331ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:877 *** CSpxAudioStreamSession::RecognizeAsync kicked-off THREAD started ***
[86313]: 5331ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:1051 CSpxAudioStreamSession::StartRecognizing
[86313]: 5331ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1052 [000002067CEB1B40]CSpxAudioStreamSession::StartRecognizing
[86313]: 5331ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3096 [000002067CEB1B40]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 0/0 => 3/1
[86313]: 5331ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1073 [000002067CEB1B40]CSpxAudioStreamSession::StartRecognizing: Now WaitForPumpSetFormatStart ...
[86313]: 5331ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:2767 CSpxAudioStreamSession::StartAudioPump
[86313]: 5331ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:2768 [000002067CEB1B40]CSpxAudioStreamSession::StartAudioPump: RecognitionKind 3 | m_audioPump [000002067CEAC770]
[86313]: 5331ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1248 [000002067CEB1B40]CSpxAudioStreamSession::FireSessionStartedEvent: ...
[86313]: 5331ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1259 [000002067CEB1B40]CSpxAudioStreamSession::FireSessionStartedEvent: Firing SessionStarted event: SessionId: 59b95b62dd79457aa4e3e26ca22ec840
[86313]: 5331ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067CEB1C28; name='SPEECH-MaxBufferSizeMs'; value='66000'
[454582]: 5331ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:1519 DispatchEvent task started...
[86313]: 5331ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067CEB1C28; name='SPEECH-BufferSizePercentSwitchToLowRate'; value='50'
[86313]: 5331ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067CEB1C28; name='SPEECH-BufferSizePercentSwitchToHighRate'; value='9'
[454582]: 5331ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSessionEventArgs' as '418547108'
[454582]: 5331ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:1519 DispatchEvent task complete!
[86313]: 5331ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:106 [000002067CF09C00] 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%
[86313]: 5331ms SPX_DBG_TRACE_FUNCTION: audio_buffer.cpp:92 PcmAudioBuffer::NewTurn
[86313]: 5332ms SPX_DBG_TRACE_FUNCTION: audio_stream_session.cpp:2459 CSpxAudioStreamSession::EnsureInitRecoEngineAdapter
[86313]: 5332ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:2463 CSpxAudioStreamSession::EnsureInitRecoEngineAdapter EnsureResetEngineEngineAdapterComplete
[86313]: 5332ms SPX_DBG_TRACE_FUNCTION: audio_stream_session.cpp:2550 CSpxAudioStreamSession::EnsureResetEngineEngineAdapterComplete
[86313]: 5332ms SPX_DBG_TRACE_FUNCTION: audio_stream_session.cpp:2488 CSpxAudioStreamSession::InitRecoEngineAdapter
[86313]: 5332ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:45 CSpxUspRecoEngineAdapter::CSpxUspRecoEngineAdapter
[86313]: 5332ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:46 CSpxUspRecoEngineAdapter::CSpxUspRecoEngineAdapter: this=0x000002067AD3CC10
[86313]: 5332ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:45 CSpxUspRecoEngineAdapter::CSpxUspRecoEngineAdapter
[86313]: 5332ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxUspRecoEngineAdapter' as '806006714'
[86313]: 5332ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:59 CSpxUspRecoEngineAdapter::Init
[86313]: 5332ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:60 CSpxUspRecoEngineAdapter::Init: this=0x000002067AD3CC10
[86313]: 5332ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:59 CSpxUspRecoEngineAdapter::Init
[86313]: 5332ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067CEB1C28; name='SPEECH-Region'; value='westus'
[86313]: 5332ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067CEB1C28; name='INTENT-region'; value='westus'
[86313]: 5332ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:2838 [000002067CEB1B40]CSpxAudioStreamSession::StartAudioPump: Starting pump[000002067CEAC770]...
[86313]: 5332ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxBufferData' as '705421989'
[86313]: 5332ms SPX_TRACE_VERBOSE: buffer_data.cpp:114 [000002067D0CD730]CSpxBufferData::EnsureInitRingBuffer - Init
[86313]: 5332ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxBlockingReadWriteRingBuffer' as '956428994'
[86313]: 5332ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067CEB1C28; name='AudioConfig_NumberOfChannelsForCapture'; value='1'
[86313]: 5332ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067CEB1C28; name='AudioConfig_SampleRateForCapture'; value='16000'
[86313]: 5332ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067CEB1C28; name='AudioConfig_BitsPerSampleForCapture'; value='16'
[86313]: 5332ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxAudioProcessorWriteToAudioSourceBuffer' as '354155511'
[86313]: 5332ms SPX_DBG_TRACE_SCOPE_ENTER: microphone_pump_base.cpp:113 MicrophonePumpBase::StartPump() ...
[86313]: 5332ms SPX_DBG_TRACE_VERBOSE: microphone_pump_base.cpp:125 CSpxMicrophonePumpBase::StartPump starting audio input
[681515]: 5333ms SPX_DBG_TRACE_SCOPE_ENTER: microphone_pump_base.cpp:179 MicrophonePumpBase::UpdateState() ...
[681515]: 5333ms SPX_DBG_TRACE_VERBOSE: microphone_pump_base.cpp:183 CSpxMicrophonePumpBase::UpdateState: UpdateState with state as 0.
[681515]: 5333ms SPX_DBG_TRACE_SCOPE_EXIT: microphone_pump_base.cpp:179 MicrophonePumpBase::UpdateState ... Done!
[86313]: 5334ms SPX_DBG_TRACE_VERBOSE: microphone_pump_base.cpp:128 CSpxMicrophonePumpBase::StartPump audio input started!
[86313]: 5334ms SPX_DBG_TRACE_SCOPE_EXIT: microphone_pump_base.cpp:113 MicrophonePumpBase::StartPump ... Done!
[86313]: 5334ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:2767 CSpxAudioStreamSession::StartAudioPump
[86313]: 5334ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:1051 CSpxAudioStreamSession::StartRecognizing
[86313]: 5334ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:877 *** CSpxAudioStreamSession::RecognizeAsync kicked-off THREAD stopped ***
[86313]: 5334ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:448 [000002067CEB1B40]CSpxAudioStreamSession::SetFormat: format != nullptr
[86313]: 5334ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3096 [000002067CEB1B40]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 3/1 => 3/2
[86313]: 5334ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:453 [000002067CEB1B40]CSpxAudioStreamSession::SetFormat: Now ProcessingAudio ...
[86313]: 5334ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:2923 CSpxAudioStreamSession::InformAdapterSetFormatStarting
[86313]: 5334ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:108 CSpxUspRecoEngineAdapter::SetAdapterMode: singleShot=1
[86313]: 5334ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:220 CSpxUspRecoEngineAdapter::SetFormat
[86313]: 5334ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:221 CSpxUspRecoEngineAdapter::SetFormat: this=0x000002067AD3CC10
[86313]: 5335ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:232 CSpxUspRecoEngineAdapter::SetFormat
wFormatTag: PCM
nChannels: 1
nSamplesPerSec: 16000
nAvgBytesPerSec: 32000
nBlockAlign: 2
wBitsPerSample: 16
cbSize: 0
[86313]: 5335ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2732 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 0/0 => 1/0 USP-START
[86313]: 5335ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:254 CSpxUspRecoEngineAdapter::SetFormat: (0x000002067AD3CC10)->PrepareFirstAudioReadyState()
[86313]: 5335ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2894 CSpxUspRecoEngineAdapter::ResetBeforeFirstAudio: this=0x000002067AD3CC10 ... USP-RESET
[86313]: 5335ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2748 CSpxUspRecoEngineAdapter::PrepareCompressionCodec: Prepare compression codec.
[86313]: 5335ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067AD3CC80; name='SPEECH-Compression-Codec-Module'; value=''
[86313]: 5335ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2810 CSpxUspRecoEngineAdapter::PrepareFirstAudioReadyState: (0x000002067AD3CC10)->PrepareCompressionCodec() result: 4. Sending the audio uncompressed
[86313]: 5335ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067AD3CC80; name='SPEECHSDK-SPEECH-CONFIG-SYSTEM-LANGUAGE'; value='C#'
[86313]: 5335ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067CEB1C28; name='HttpHeader#User-agent'; value='SpeechSDK-C#/1.18.0 Windows Client 10'
[86313]: 5335ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:329 CSpxUspRecoEngineAdapter::UspInitialize: this=0x000002067AD3CC10
[86313]: 5335ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxUspCallbackWrapper' as '978711522'
[86313]: 5335ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067AD3CC80; name='SessionId'; value='59b95b62dd79457aa4e3e26ca22ec840'
[86313]: 5335ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1624 CSpxAudioStreamSession::GetScenarioCount: countSpeech=1; countIntent=0; countTranslation=0; countDialog=0, countTranscriber=0, countLanguageId=0
[86313]: 5335ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:665 CSpxUspRecoEngineAdapter::SetUspEndpointDefaultSpeechService: Endpoint type: Speech.
[86313]: 5335ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067AD3CC80; name='SPEECH-Region'; value='westus'
[86313]: 5335ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067CEB1C28; name='SPEECH-RecoLanguage'; value='en-us'
[86313]: 5336ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067AD3CC80; name='SPEECH-RecoLanguage'; value='en-us'
[86313]: 5336ms SPX_TRACE_INFO: usp_reco_engine_adapter.cpp:956 Unknown SpeechServiceConnection_SingleLanguageIdPriority value or SpeechServiceConnection_ContinuousLanguageIdPriority value .
[86313]: 5336ms SPX_TRACE_INFO: usp_reco_engine_adapter.cpp:1028 Unknown SpeechServiceConnection_SingleLanguageIdPriority value or SpeechServiceConnection_ContinuousLanguageIdPriority value .
[86313]: 5336ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:554 CSpxUspRecoEngineAdapter::SetUspLanguageIdModeAndPriority: languageIdMode=0, languageIdPriority=0
[86313]: 5336ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067AD3CC80; name='SPEECH-RecoMode'; value='INTERACTIVE'
[86313]: 5336ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:530 CSpxUspRecoEngineAdapter::SetUspRecoMode: recoMode=0
[86313]: 5336ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:517 CSpxUspRecoEngineAdapter::SetUspEndpoint: Setting Websocket Polling interval to 10
[86313]: 5336ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067AD3CC80; name='SPEECH-SubscriptionKey'; value='******************************62'
[86313]: 5336ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::FindPrefix: this=0x000002067AD3CC80; name='User-agent'; value='SpeechSDK-C#/1.18.0 Windows Client 10'
[86313]: 5336ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067AD3CC80; name='SPEECH-ProxyHostBypass'; value=''
[86313]: 5336ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067AD3CC80; name='SPEECHSDK-SPEECH-CONFIG-SYSTEM-LANGUAGE'; value='C#'
[86313]: 5336ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067AD3CC80; name='AudioConfig_AudioSource'; value='Microphones'
[86313]: 5336ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067AD3CC80; name='SPEECH-MicrophoneNiceName'; value='Mic In (Elgato Wave:3)'
[86313]: 5336ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067AD3CC80; name='AudioConfig_SampleRateForCapture'; value='16000'
[86313]: 5336ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067AD3CC80; name='AudioConfig_BitsPerSampleForCapture'; value='16'
[86313]: 5336ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067AD3CC80; name='AudioConfig_NumberOfChannelsForCapture'; value='1'
[86313]: 5336ms SPX_TRACE_INFO: uspimpl.cpp:597 Microsoft::CognitiveServices::Speech::USP::Connection::Impl::Connect: entering...
[86313]: 5336ms SPX_TRACE_INFO: uspimpl.cpp:614 Adding subscription key headers
[86313]: 5336ms SPX_TRACE_INFO: uspimpl.cpp:649 Set a user defined HTTP header 'User-agent':'SpeechSDK-C#/1.18.0 Windows Client 10'
[86313]: 5337ms SPX_TRACE_INFO: uspimpl.cpp:664 connectionUrl=wss://westus.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us
[86313]: 5337ms SPX_DBG_TRACE_SCOPE_ENTER: web_socket.cpp:224 Microsoft::CognitiveServices::Speech::USP::WebSocket::WebSocket
[86313]: 5337ms SPX_DBG_TRACE_SCOPE_EXIT: web_socket.cpp:224 Microsoft::CognitiveServices::Speech::USP::WebSocket::WebSocket
[86313]: 5337ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067CEB1C28; name='SPEECH-ConnectionUrl'; value='wss://westus.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us'
[86313]: 5337ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067CEB1C28; name='SPEECH-ConnectionUrl'; value='wss://westus.stt.speech.microsoft.com/speech/recognition/interactive/cognitiveservices/v1?language=en-us'
[86313]: 5337ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1076 speech.config {"context":{"system":{"version":"1.18.0","name":"SpeechSDK","build":"Windows-x64","lang":"C#"},"os":{"name":"Client","version":"10","platform":"Windows"},"audio":{"source":{"type":"Microphones","model":"Mic In (Elgato Wave:3)","samplerate":"16000","bitspersample":"16","channelcount":"1"}}}}
[86313]: 5337ms SPX_TRACE_INFO: uspimpl.cpp:890 Create requestId for messageType 0
[86313]: 5337ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:220 CSpxUspRecoEngineAdapter::SetFormat
[86313]: 5337ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:2923 CSpxAudioStreamSession::InformAdapterSetFormatStarting
[86313]: 5337ms SPX_TRACE_INFO: web_socket.cpp:783 Microsoft::CognitiveServices::Speech::USP::WebSocket::DoWork: open transport.
[86313]: 5337ms SPX_TRACE_INFO: web_socket.cpp:535 Start to open websocket. WebSocket: 0x7d116d60, wsio handle: 0x7ad3b560
[681515]: 5436ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxBufferProperties' as '809583448'
[681515]: 5436ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxReadWriteRingBuffer' as '956428994'
[681515]: 5436ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxReadWriteRingBuffer' as '956428994'
[681515]: 5436ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:500 Received audio chunk: time: 2021-08-23T22:41:20.6232985Z, size:3200.
[681515]: 5436ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:514 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 0 bytes (0 msec)
[681515]: 5436ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:164 [000002067CF09C00] m_bytesTransited = 3200, unacknowledgedAudioDuration = 0 msec, packetAudioDelay = 0 msec
[86313]: 5436ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2732 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 1/0 => 2/1000
[86313]: 5437ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:284 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002067AD3CC10)->PrepareUspAudioStream() ... size=3200
[86313]: 5437ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1158 speech.context='{"audio":{"streams":{"1":null}}}'
[86313]: 5437ms SPX_TRACE_INFO: uspimpl.cpp:737 RequestId: 'b05765402e5442109930740cedbd9c3c'
[86313]: 5437ms SPX_TRACE_INFO: uspimpl.cpp:890 Create requestId b05765402e5442109930740cedbd9c3c for messageType 1
[86313]: 5437ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1233 CSpxUspRecoEngineAdapter::UspWriteActual(..., 44)
[86313]: 5437ms SPX_TRACE_INFO: uspimpl.cpp:903 TS:100, Write 44 bytes audio data.
[86313]: 5437ms SPX_TRACE_INFO: uspimpl.cpp:927 The current speech request id is b05765402e5442109930740cedbd9c3c
[86313]: 5437ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1233 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200)
[86313]: 5437ms SPX_TRACE_INFO: uspimpl.cpp:903 TS:100, Write 3200 bytes audio data.
[86313]: 5437ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:288 CSpxUspRecoEngineAdapter::ProcessAudio: site->AdapterStartingTurn()
[86313]: 5437ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:657 [000002067CEB1B40]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks
[86313]: 5531ms SPX_TRACE_INFO: web_socket.cpp:859 Opening websocket completed. TransportRequest: 0x7d116d60, wsio handle: 0x7ad3b560
[86313]: 5531ms SPX_TRACE_INFO: uspimpl.cpp:1005 TS:194, OnConnected: connection:0x7d0fcc10
[86313]: 5531ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1298 [000002067CEB1B40]CSpxAudioStreamSession::AdapterConnected
[454582]: 5531ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:1519 DispatchEvent task started...
[454582]: 5531ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxConnectionEventArgs' as '189265475'
[454582]: 5531ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:1519 DispatchEvent task complete!
[681515]: 5536ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:500 Received audio chunk: time: 2021-08-23T22:41:20.7232974Z, size:3200.
[681515]: 5536ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:514 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 3200 bytes (100 msec)
[681515]: 5537ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:164 [000002067CF09C00] m_bytesTransited = 6400, unacknowledgedAudioDuration = 100 msec, packetAudioDelay = 0 msec
[86313]: 5537ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:293 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002067AD3CC10) Sending Audio ... size=3200
[86313]: 5537ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1233 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200)
[86313]: 5537ms SPX_TRACE_INFO: uspimpl.cpp:903 TS:200, Write 3200 bytes audio data.
[86313]: 5537ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:657 [000002067CEB1B40]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks
[86313]: 5577ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1325 [000002067CEB1B40]CSpxAudioStreamSession::FireConnectionMessageReceived; path=turn.start
[86313]: 5577ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: application/json; charset=utf-8.
[86313]: 5577ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:240 Response Message: path: turn.start, size: 75.
[86313]: 5577ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1895 Response: Turn.Start message. Context.ServiceTag: 7d2fbdb356814225a1d52c1e3dbec015
[86313]: 5577ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1896 CSpxUspRecoEngineAdapter::OnTurnStart: this=0x000002067AD3CC10
[86313]: 5577ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2732 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 2/1000 => 2/1200
[86313]: 5577ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1680 CSpxAudioStreamSession::AdapterStartedTurn: set m_expectAdapterStoppedTurn to true
[86313]: 5577ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067CEB1C28; name='SPEECH-UspContinuationServiceTag'; value='7d2fbdb356814225a1d52c1e3dbec015'
[681515]: 5642ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:500 Received audio chunk: time: 2021-08-23T22:41:20.8293631Z, size:3200.
[681515]: 5643ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:514 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 6400 bytes (200 msec)
[681515]: 5643ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:164 [000002067CF09C00] m_bytesTransited = 9600, unacknowledgedAudioDuration = 200 msec, packetAudioDelay = 0 msec
[86313]: 5643ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:293 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002067AD3CC10) Sending Audio ... size=3200
[86313]: 5643ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1233 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200)
[86313]: 5643ms SPX_TRACE_INFO: uspimpl.cpp:903 TS:306, Write 3200 bytes audio data.
[86313]: 5643ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:657 [000002067CEB1B40]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks
[681515]: 5736ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:500 Received audio chunk: time: 2021-08-23T22:41:20.9229547Z, size:3200.
[681515]: 5736ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:514 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 9600 bytes (300 msec)
[681515]: 5736ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:164 [000002067CF09C00] m_bytesTransited = 12800, unacknowledgedAudioDuration = 300 msec, packetAudioDelay = 0 msec
[86313]: 5736ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:293 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002067AD3CC10) Sending Audio ... size=3200
[86313]: 5736ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1233 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200)
[86313]: 5736ms SPX_TRACE_INFO: uspimpl.cpp:903 TS:400, Write 3200 bytes audio data.
[86313]: 5736ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:657 [000002067CEB1B40]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks
[681515]: 5837ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:500 Received audio chunk: time: 2021-08-23T22:41:21.0241518Z, size:3200.
[681515]: 5838ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:514 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 12800 bytes (400 msec)
[681515]: 5838ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:164 [000002067CF09C00] m_bytesTransited = 16000, unacknowledgedAudioDuration = 400 msec, packetAudioDelay = 0 msec
[86313]: 5838ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:293 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002067AD3CC10) Sending Audio ... size=3200
[86313]: 5838ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1233 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200)
[86313]: 5838ms SPX_TRACE_INFO: uspimpl.cpp:903 TS:501, Write 3200 bytes audio data.
[86313]: 5838ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:657 [000002067CEB1B40]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks
[681515]: 5937ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:500 Received audio chunk: time: 2021-08-23T22:41:21.1235781Z, size:3200.
[681515]: 5937ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:514 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 16000 bytes (500 msec)
[681515]: 5937ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:164 [000002067CF09C00] m_bytesTransited = 19200, unacknowledgedAudioDuration = 500 msec, packetAudioDelay = 0 msec
[86313]: 5937ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:293 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002067AD3CC10) Sending Audio ... size=3200
[86313]: 5937ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1233 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200)
[86313]: 5937ms SPX_TRACE_INFO: uspimpl.cpp:903 TS:600, Write 3200 bytes audio data.
[86313]: 5937ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:657 [000002067CEB1B40]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks
[681515]: 6037ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:500 Received audio chunk: time: 2021-08-23T22:41:21.2238958Z, size:3200.
[681515]: 6037ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:514 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 19200 bytes (600 msec)
[681515]: 6037ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:164 [000002067CF09C00] m_bytesTransited = 22400, unacknowledgedAudioDuration = 600 msec, packetAudioDelay = 0 msec
[86313]: 6037ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:293 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002067AD3CC10) Sending Audio ... size=3200
[86313]: 6037ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1233 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200)
[86313]: 6037ms SPX_TRACE_INFO: uspimpl.cpp:903 TS:701, Write 3200 bytes audio data.
[86313]: 6037ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:657 [000002067CEB1B40]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks
[681515]: 6136ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:500 Received audio chunk: time: 2021-08-23T22:41:21.3230191Z, size:3200.
[681515]: 6136ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:514 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 22400 bytes (700 msec)
[681515]: 6136ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:164 [000002067CF09C00] m_bytesTransited = 25600, unacknowledgedAudioDuration = 700 msec, packetAudioDelay = 0 msec
[86313]: 6136ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:293 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002067AD3CC10) Sending Audio ... size=3200
[86313]: 6136ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1233 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200)
[86313]: 6136ms SPX_TRACE_INFO: uspimpl.cpp:903 TS:800, Write 3200 bytes audio data.
[86313]: 6137ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:657 [000002067CEB1B40]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks
[681515]: 6237ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:500 Received audio chunk: time: 2021-08-23T22:41:21.4236698Z, size:3200.
[681515]: 6237ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:514 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 25600 bytes (800 msec)
[681515]: 6237ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:164 [000002067CF09C00] m_bytesTransited = 28800, unacknowledgedAudioDuration = 800 msec, packetAudioDelay = 0 msec
[86313]: 6237ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:293 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002067AD3CC10) Sending Audio ... size=3200
[86313]: 6237ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1233 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200)
[86313]: 6237ms SPX_TRACE_INFO: uspimpl.cpp:903 TS:900, Write 3200 bytes audio data.
[86313]: 6237ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:657 [000002067CEB1B40]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks
[86313]: 6276ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1325 [000002067CEB1B40]CSpxAudioStreamSession::FireConnectionMessageReceived; path=speech.startDetected
[86313]: 6276ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: application/json; charset=utf-8.
[86313]: 6276ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:940 Response Message: path: speech.startDetected, size: 17.
[86313]: 6277ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1288 Response: Speech.StartDetected message. Speech starts at offset 400000 (100ns).
[86313]: 6277ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1296 CSpxUspRecoEngineAdapter::OnSpeechStartDetected: (0x000002067AD3CC10) site->AdapterDetectedSpeechStart()
[86313]: 6277ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1341 [000002067CEB1B40]CSpxAudioStreamSession::FireSpeechStartDetectedEvent
[454582]: 6277ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:1519 DispatchEvent task started...
[454582]: 6277ms SPX_DBG_TRACE_VERBOSE: recognizer.cpp:344 No listener connected to event
[454582]: 6277ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:1519 DispatchEvent task complete!
[681515]: 6337ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:500 Received audio chunk: time: 2021-08-23T22:41:21.5234875Z, size:3200.
[681515]: 6337ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:514 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 28800 bytes (900 msec)
[681515]: 6337ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:164 [000002067CF09C00] m_bytesTransited = 32000, unacknowledgedAudioDuration = 900 msec, packetAudioDelay = 0 msec
[86313]: 6337ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:293 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002067AD3CC10) Sending Audio ... size=3200
[86313]: 6337ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1233 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200)
[86313]: 6337ms SPX_TRACE_INFO: uspimpl.cpp:903 TS:1000, Write 3200 bytes audio data.
[86313]: 6337ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:657 [000002067CEB1B40]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks
[86313]: 6415ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1325 [000002067CEB1B40]CSpxAudioStreamSession::FireConnectionMessageReceived; path=speech.hypothesis
[86313]: 6415ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: application/json; charset=utf-8.
[86313]: 6415ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:1078 Response Message: path: speech.hypothesis, size: 89.
[86313]: 6415ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1340 Response: Speech.Hypothesis message. Starts at offset 400000, with duration 2400000 (100ns). Text: yes
[86313]: 6415ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1348 CSpxUspRecoEngineAdapter::OnSpeechHypothesis: site->FireAdapterResult_Intermediate()
[86313]: 6415ms SPX_DBG_TRACE_FUNCTION: recognition_result.cpp:26 CSpxRecognitionResult::CSpxRecognitionResult
[86313]: 6415ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxRecognitionResult' as '175439361'
[86313]: 6415ms SPX_TRACE_VERBOSE: recognition_result.cpp:73 CSpxRecognitionResult::InitIntermediateResult: resultId=af6caa2e06ce43e98da3ff56a3931c87
[86313]: 6415ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D188A10; name='RESULT-Json'; value='{"Id":"d3751f03af09430d9b9a4835adba21f1","Text":"yes","Offset":400000,"Duration":2400000}'
[86313]: 6415ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D188A10; name='SpeechServiceResponse_IsTentativePhrase'; value='false'
[86313]: 6415ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067D188A10; name='RESULT-Json'; value='{"Id":"d3751f03af09430d9b9a4835adba21f1","Text":"yes","Offset":400000,"Duration":2400000}'
[86313]: 6415ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1396 CSpxUspRecoEngineAdapter::UpdateAdapterResult_JsonResult: before update: json='{"Id":"d3751f03af09430d9b9a4835adba21f1","Text":"yes","Offset":400000,"Duration":2400000}'
[86313]: 6415ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067CEB1C28; name='AudioConfig_AudioSource'; value='Microphones'
[86313]: 6415ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D188A10; name='RESULT-RecognitionLatencyMs'; value='793'
[86313]: 6415ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:2031 Storing indermidate result for possible promotion.
[86313]: 6416ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1376 [000002067CEB1B40]CSpxAudioStreamSession::FireResultEvent
[454582]: 6416ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:1519 DispatchEvent task started...
[454582]: 6416ms SPX_DBG_TRACE_SCOPE_ENTER: recognizer.cpp:289 CSpxRecognizer::FireResultEvent
[454582]: 6416ms SPX_DBG_TRACE_VERBOSE: recognizer.cpp:344 No listener connected to event
[454582]: 6416ms SPX_DBG_TRACE_SCOPE_EXIT: recognizer.cpp:289 CSpxRecognizer::FireResultEvent
[454582]: 6416ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:1519 DispatchEvent task complete!
[681515]: 6437ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:500 Received audio chunk: time: 2021-08-23T22:41:21.6237998Z, size:3200.
[681515]: 6437ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:514 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 32000 bytes (1000 msec)
[681515]: 6437ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:164 [000002067CF09C00] m_bytesTransited = 35200, unacknowledgedAudioDuration = 1000 msec, packetAudioDelay = 0 msec
[86313]: 6437ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:293 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002067AD3CC10) Sending Audio ... size=3200
[86313]: 6437ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1233 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200)
[86313]: 6437ms SPX_TRACE_INFO: uspimpl.cpp:903 TS:1101, Write 3200 bytes audio data.
[86313]: 6437ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:657 [000002067CEB1B40]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks
[681515]: 6536ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:500 Received audio chunk: time: 2021-08-23T22:41:21.7230416Z, size:3200.
[681515]: 6536ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:514 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 35200 bytes (1100 msec)
[681515]: 6536ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:164 [000002067CF09C00] m_bytesTransited = 38400, unacknowledgedAudioDuration = 1100 msec, packetAudioDelay = 0 msec
[86313]: 6536ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:293 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002067AD3CC10) Sending Audio ... size=3200
[86313]: 6537ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1233 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200)
[86313]: 6537ms SPX_TRACE_INFO: uspimpl.cpp:903 TS:1200, Write 3200 bytes audio data.
[86313]: 6537ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:657 [000002067CEB1B40]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks
[681515]: 6638ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:500 Received audio chunk: time: 2021-08-23T22:41:21.8249885Z, size:3200.
[681515]: 6638ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:514 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 38400 bytes (1200 msec)
[681515]: 6638ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:164 [000002067CF09C00] m_bytesTransited = 41600, unacknowledgedAudioDuration = 1200 msec, packetAudioDelay = 0 msec
[86313]: 6638ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:293 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002067AD3CC10) Sending Audio ... size=3200
[86313]: 6639ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1233 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200)
[86313]: 6639ms SPX_TRACE_INFO: uspimpl.cpp:903 TS:1302, Write 3200 bytes audio data.
[86313]: 6639ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:657 [000002067CEB1B40]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks
[681515]: 6738ms SPX_TRACE_VERBOSE: audio_stream_session.cpp:500 Received audio chunk: time: 2021-08-23T22:41:21.9247706Z, size:3200.
[681515]: 6738ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:514 CSpxAudioStreamSession::ProcessAudio: Non-acknowledged size = 41600 bytes (1300 msec)
[681515]: 6738ms SPX_DBG_TRACE_VERBOSE: audio_stream_session_throttle_logic.cpp:164 [000002067CF09C00] m_bytesTransited = 44800, unacknowledgedAudioDuration = 1300 msec, packetAudioDelay = 0 msec
[86313]: 6738ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:293 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002067AD3CC10) Sending Audio ... size=3200
[86313]: 6738ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1233 CSpxUspRecoEngineAdapter::UspWriteActual(..., 3200)
[86313]: 6738ms SPX_TRACE_INFO: uspimpl.cpp:903 TS:1402, Write 3200 bytes audio data.
[86313]: 6738ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:657 [000002067CEB1B40]CSpxAudioStreamSession::ProcessNextAudio: done processing all audio chunks
[86313]: 6774ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1325 [000002067CEB1B40]CSpxAudioStreamSession::FireConnectionMessageReceived; path=speech.endDetected
[86313]: 6774ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: application/json; charset=utf-8.
[86313]: 6775ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:1438 Response Message: path: speech.endDetected, size: 19.
[86313]: 6775ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1307 Response: Speech.EndDetected message. Speech ends at offset 11400000 (100ns)
[86313]: 6775ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2732 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 2/1200 => 9/1200
[86313]: 6775ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1319 CSpxUspRecoEngineAdapter::OnSpeechEndDetected: (0x000002067AD3CC10) site->AdapterDetectedSpeechEnd()
[86313]: 6775ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1348 [000002067CEB1B40]CSpxAudioStreamSession::FireSpeechEndDetectedEvent
[86313]: 6775ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1328 CSpxUspRecoEngineAdapter::OnSpeechEndDetected: Flush ... (audioState/uspState=9/1200) USP-FLUSH
[86313]: 6775ms SPX_TRACE_INFO: uspimpl.cpp:943 TS:1438, Flush audio buffer.
[454582]: 6775ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:1519 DispatchEvent task started...
[86313]: 6775ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1333 CSpxUspRecoEngineAdapter::OnSpeechEndDetected: site->AdapterRequestingAudioMute(true) ... (audioState/uspState=9/1200)
[454582]: 6775ms SPX_DBG_TRACE_VERBOSE: recognizer.cpp:344 No listener connected to event
[86313]: 6775ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:2210 CSpxAudioStreamSession::AdapterRequestingAudioMute: Muting audio (SingleShot or KwsSingleShot) ... recoKind/sessionState: 3/2
[454582]: 6775ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:1519 DispatchEvent task complete!
[86313]: 6790ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1325 [000002067CEB1B40]CSpxAudioStreamSession::FireConnectionMessageReceived; path=speech.phrase
[86313]: 6790ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: application/json; charset=utf-8.
[86313]: 6790ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:1453 Response Message: path: speech.phrase, size: 127.
[86313]: 6790ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1594 Response: Speech.Phrase message. Status: 0, Text: Yes., starts at 400000, with duration 5100000 (100ns).
[86313]: 6790ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1595 CSpxUspRecoEngineAdapter::OnSpeechPhrase: this=0x000002067AD3CC10
[86313]: 6790ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2732 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 9/1200 => 9/2999
[86313]: 6790ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1629 CSpxUspRecoEngineAdapter::OnSpeechPhrase: FireFinalResultNow()
[86313]: 6790ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:2661 FireFinalResultNow: Creating Result
[86313]: 6790ms SPX_DBG_TRACE_FUNCTION: recognition_result.cpp:26 CSpxRecognitionResult::CSpxRecognitionResult
[86313]: 6790ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxRecognitionResult' as '175439361'
[86313]: 6790ms SPX_DBG_TRACE_FUNCTION: recognition_result.cpp:83 CSpxRecognitionResult::InitFinalResult
[86313]: 6790ms SPX_TRACE_VERBOSE: recognition_result.cpp:91 CSpxRecognitionResult::InitFinalResult: resultId=dc05cbd8a94a41d7be56011c3259efee reason=3, text='Yes.'
[86313]: 6790ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0EFCF0; name='RESULT-Json'; value='{"Id":"34db7c114d884aa29abee78582014094","RecognitionStatus":"Success","DisplayText":"Yes.","Offset":400000,"Duration":5100000}'
[86313]: 6790ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067D0EFCF0; name='RESULT-Json'; value='{"Id":"34db7c114d884aa29abee78582014094","RecognitionStatus":"Success","DisplayText":"Yes.","Offset":400000,"Duration":5100000}'
[86313]: 6790ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1396 CSpxUspRecoEngineAdapter::UpdateAdapterResult_JsonResult: before update: json='{"Id":"34db7c114d884aa29abee78582014094","RecognitionStatus":"Success","DisplayText":"Yes.","Offset":400000,"Duration":5100000}'
[86313]: 6790ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067CEB1C28; name='AudioConfig_AudioSource'; value='Microphones'
[86313]: 6790ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D0EFCF0; name='RESULT-RecognitionLatencyMs'; value='904'
[86313]: 6790ms SPX_DBG_TRACE_FUNCTION: recognition_result.cpp:31 CSpxRecognitionResult::~CSpxRecognitionResult
[86313]: 6790ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1222 [000002067CEB1B40]CSpxAudioStreamSession::WaitForRecognition_Complete: ...
[86313]: 6790ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1376 [000002067CEB1B40]CSpxAudioStreamSession::FireResultEvent
[86313]: 6791ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:1130 CSpxAudioStreamSession::StopRecognizing
[86313]: 6791ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1131 [000002067CEB1B40]CSpxAudioStreamSession::StopRecognizing ...
[86313]: 6791ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3096 [000002067CEB1B40]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 3/2 => 3/4
[454582]: 6791ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:1519 DispatchEvent task started...
[86313]: 6791ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1188 [000002067CEB1B40]CSpxAudioStreamSession::StopRecognizing: We've been asked to stop whatever it is we're doing, while we're actively processing audio ...
[454582]: 6791ms SPX_DBG_TRACE_SCOPE_ENTER: recognizer.cpp:289 CSpxRecognizer::FireResultEvent
[86313]: 6791ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1191 [000002067CEB1B40]CSpxAudioStreamSession::StopRecognizing: Now StoppingPump[000002067CEAC770] ...
[454582]: 6791ms SPX_DBG_TRACE_VERBOSE: recognizer.cpp:305 CSpxRecognizer::FireResultEvent: No FinalResult event signal connected!! nobody listening...
[86313]: 6791ms SPX_DBG_TRACE_SCOPE_ENTER: microphone_pump_base.cpp:143 MicrophonePumpBase::StopPump ...
[454582]: 6791ms SPX_DBG_TRACE_VERBOSE: recognizer.cpp:344 No listener connected to event
[454582]: 6791ms SPX_DBG_TRACE_SCOPE_EXIT: recognizer.cpp:289 CSpxRecognizer::FireResultEvent
[454582]: 6791ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:1519 DispatchEvent task complete!
[681515]: 6793ms SPX_DBG_TRACE_SCOPE_ENTER: microphone_pump_base.cpp:179 MicrophonePumpBase::UpdateState() ...
[681515]: 6793ms SPX_DBG_TRACE_VERBOSE: microphone_pump_base.cpp:183 CSpxMicrophonePumpBase::UpdateState: UpdateState with state as 4.
[681515]: 6793ms SPX_DBG_TRACE_SCOPE_EXIT: microphone_pump_base.cpp:179 MicrophonePumpBase::UpdateState ... Done!
[86313]: 6794ms SPX_DBG_TRACE_SCOPE_EXIT: microphone_pump_base.cpp:143 MicrophonePumpBase::StopPump ... Done
[86313]: 6794ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:1130 CSpxAudioStreamSession::StopRecognizing
[86313]: 6794ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:2661 FireFinalResultNow: GetSite()->FireAdapterResult_FinalResult() complete!
[86313]: 6794ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2938 CSpxUspRecoEngineAdapter::OnAcknowledgedAudio: this=0x000002067AD3CC10 Service acknowledging to offset 5500000 (100ns).
[86313]: 6794ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067CEB1C28; name='SPEECH-UspContinuationOffset'; value='5500000'
[494308]: 6794ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067CEB1C28; name='SPEECH-StopRecognitionTimeoutInSeconds'; value=''
[86313]: 6794ms SPX_DBG_TRACE_VERBOSE: audio_buffer.cpp:114 PcmAudioBuffer::DiscardBytesUnlocked discarding 17600 bytes.
[494308]: 6794ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:765 CSpxAudioStreamSession::WaitForIdle
[494308]: 6794ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:766 [000002067CEB1B40]CSpxAudioStreamSession::WaitForIdle timeout: 10000 msec
[86313]: 6794ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1325 [000002067CEB1B40]CSpxAudioStreamSession::FireConnectionMessageReceived; path=turn.end
[494308]: 6794ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:771 CSpxAudioStreamSession::WaitForIdle m_cv.wait_for, m_sessionState: 4, m_recoKind: 3, m_sessionStarted: 1, m_sessionStopped: 0
[86313]: 6794ms SPX_TRACE_INFO: uspimpl.cpp:1258 Response Message: content type: application/json; charset=utf-8.
[86313]: 6794ms SPX_TRACE_INFO: uspimpl.cpp:1278 TS:1457 Response Message: path: turn.end, size: 2.
[86313]: 6794ms SPX_DBG_TRACE_VERBOSE: uspimpl.cpp:1345 Got turn end, clear m_streamIdLangMap.
[86313]: 6794ms SPX_TRACE_INFO: usp_metrics.cpp:429 Microsoft::CognitiveServices::Speech::USP::Telemetry::SendSerializedTelemetry: Send telemetry (requestId:b05765402e5442109930740cedbd9c3c): {"Metrics":[{"End":"2021-08-23T22:41:20.717Z","Id":"59b95b62dd79457aa4e3e26ca22ec840","Name":"Connection","Start":"2021-08-23T22:41:20.523Z"},{"DeviceId":["57455683-d3db-41c7-a661-46e023365507"],"Name":"device"}],"ReceivedMessages":[]}
[86313]: 6794ms SPX_TRACE_INFO: usp_metrics.cpp:429 Microsoft::CognitiveServices::Speech::USP::Telemetry::SendSerializedTelemetry: Send telemetry (requestId:b05765402e5442109930740cedbd9c3c): {"Metrics":[{"PhraseLatencyMs":[904]},{"FirstHypothesisLatencyMs":[793]},{"HypothesisLatencyMs":[793]}],"ReceivedMessages":[{"turn.start":["2021-08-23T22:41:20.763Z"]},{"turn.end":["2021-08-23T22:41:21.980Z"]},{"speech.startDetected":["2021-08-23T22:41:21.462Z"]},{"speech.endDetected":["2021-08-23T22:41:21.961Z"]},{"speech.hypothesis":["2021-08-23T22:41:21.601Z"]},{"speech.phrase":["2021-08-23T22:41:21.976Z"]}]}
[86313]: 6794ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:1929 CSpxUspRecoEngineAdapter::OnTurnEnd ... started...
[86313]: 6794ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1930 Response: Turn.End message.
[86313]: 6794ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2732 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 9/2999 => 9/0
[86313]: 6794ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:1981 CSpxUspRecoEngineAdapter::OnTurnEnd: site->AdapterStoppedTurn()
[86313]: 6794ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1688 CSpxAudioStreamSession::AdapterStoppedTurn: set m_expectAdapterStoppedTurn to false
[86313]: 6794ms SPX_DBG_TRACE_FUNCTION: audio_buffer.cpp:92 PcmAudioBuffer::NewTurn
[86313]: 6794ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1699 [000002067CEB1B40]CSpxAudioStreamSession::AdapterStoppedTurn: m_currentTurnGlobalOffset=5500000, previousTurnGlobalOffset=0 bufferedBytes=27200
[86313]: 6795ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:1929 CSpxUspRecoEngineAdapter::OnTurnEnd ... DONE!
[86313]: 6795ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:448 [000002067CEB1B40]CSpxAudioStreamSession::SetFormat: format == nullptr
[86313]: 6795ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3096 [000002067CEB1B40]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 3/4 => 3/5
[86313]: 6795ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:461 [000002067CEB1B40]CSpxAudioStreamSession::SetFormat: Now WaitForAdapterCompletedSetFormatStop (from StoppingPump)...
[86313]: 6795ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:2970 CSpxAudioStreamSession::InformAdapterSetFormatStopping
[494308]: 6795ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:771 CSpxAudioStreamSession::WaitForIdle m_cv.wait_for, m_sessionState: 5, m_recoKind: 3, m_sessionStarted: 1, m_sessionStopped: 0
[86313]: 6795ms SPX_TRACE_INFO: audio_stream_session.cpp:2981 [000002067CEB1B40]CSpxAudioStreamSession::InformAdapterSetFormatStoppingProcessingAudio - Send zero size audio, processor=000002067AD3CC68
[86313]: 6795ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:303 CSpxUspRecoEngineAdapter::ProcessAudio: (0x000002067AD3CC10) Ignoring audio size=0 ... (audioState/uspState=9/0)
[86313]: 6795ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:2986 CSpxAudioStreamSession::InformAdapterSetFormatStopping m_expectAdapterStartedTurn: false, m_expectAdapterStoppedTurn: false
[86313]: 6795ms SPX_TRACE_INFO: audio_stream_session.cpp:2991 [000002067CEB1B40]CSpxAudioStreamSession::InformAdapterSetFormatStoppingSetFormat(nullptr)
[86313]: 6795ms SPX_DBG_TRACE_SCOPE_ENTER: usp_reco_engine_adapter.cpp:220 CSpxUspRecoEngineAdapter::SetFormat
[86313]: 6795ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:221 CSpxUspRecoEngineAdapter::SetFormat: this=0x000002067AD3CC10
[86313]: 6795ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:223 CSpxUspRecoEngineAdapter::SetFormat - pformat == nullptr
[86313]: 6795ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:2732 CSpxUspRecoEngineAdapter::TryChangeState; audioState/uspState: 9/0 => 0/0 USP-DONE
[86313]: 6795ms SPX_DBG_TRACE_VERBOSE: usp_reco_engine_adapter.cpp:259 CSpxUspRecoEngineAdapter::SetFormat: (0x000002067AD3CC10) site->AdapterCompletedSetFormatStop()
[86313]: 6795ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:3019 *** CSpxAudioStreamSession::AdapterCompletedSetFormatStop kicked-off THREAD started ***
[86313]: 6795ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3020 [000002067CEB1B40]CSpxAudioStreamSession::AdapterCompletedSetFormatStop: kicked-off THREAD started ***
[86313]: 6795ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3106 [000002067CEB1B40]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 3/5 doesn't match
[86313]: 6795ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3106 [000002067CEB1B40]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 3/5 doesn't match
[86313]: 6795ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3106 [000002067CEB1B40]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 3/5 doesn't match
[86313]: 6795ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:3096 [000002067CEB1B40]CSpxAudioStreamSession::TryChangeState: recoKind/sessionState: 3/5 => 0/0
[86313]: 6795ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1274 [000002067CEB1B40]CSpxAudioStreamSession::FireSessionStoppedEvent: Firing SessionStopped event: SessionId: 59b95b62dd79457aa4e3e26ca22ec840
[86313]: 6795ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:1355 CSpxAudioStreamSession::EnsureFireResultEvent
[494308]: 6795ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:771 CSpxAudioStreamSession::WaitForIdle m_cv.wait_for, m_sessionState: 0, m_recoKind: 0, m_sessionStarted: 1, m_sessionStopped: 0
[86313]: 6795ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:1356 [000002067CEB1B40]CSpxAudioStreamSession::EnsureFireResultEvent
[86313]: 6796ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:1355 CSpxAudioStreamSession::EnsureFireResultEvent
[86313]: 6796ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:3019 *** CSpxAudioStreamSession::AdapterCompletedSetFormatStop kicked-off THREAD stopped ***
[86313]: 6796ms SPX_DBG_TRACE_SCOPE_EXIT: usp_reco_engine_adapter.cpp:220 CSpxUspRecoEngineAdapter::SetFormat
[454582]: 6796ms SPX_DBG_TRACE_SCOPE_ENTER: audio_stream_session.cpp:1519 DispatchEvent task started...
[86313]: 6796ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:2970 CSpxAudioStreamSession::InformAdapterSetFormatStopping
[454582]: 6796ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSessionEventArgs' as '418547108'
[454582]: 6796ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:1519 DispatchEvent task complete!
[494308]: 6796ms SPX_DBG_TRACE_VERBOSE: audio_stream_session.cpp:771 CSpxAudioStreamSession::WaitForIdle m_cv.wait_for, m_sessionState: 0, m_recoKind: 0, m_sessionStarted: 1, m_sessionStopped: 1
[494308]: 6796ms SPX_DBG_TRACE_SCOPE_EXIT: audio_stream_session.cpp:765 CSpxAudioStreamSession::WaitForIdle
[830554]: 6796ms SPX_DBG_TRACE_VERBOSE: handle_table.h:100 CSpxHandleTable::TrackHandle p=0x000002067D0EFC30
[830554]: 6796ms SPX_DBG_TRACE_VERBOSE: handle_table.h:110 CSpxHandleTable::TrackHandle iid=175439361 h=0x000002067D0EFC30, p=0x000002067D0EFC30, tot=1
[830554]: 6796ms SPX_RETURN_ON_FAIL: handle_helpers.h:52 hr = 0x21
[830554]: 6796ms SPX_DBG_TRACE_VERBOSE: handle_table.h:169 CSpxHandleTable::StopTracking(h) h=0x000002067D0D3F20
[830554]: 6796ms SPX_DBG_TRACE_VERBOSE: handle_table.h:184 CSpxHandleTable::StopTracking(h) iid=429707311 h=0x000002067D0D3F20, p=0x000002067D0D3F20, tot=0
[830554]: 6798ms SPX_DBG_TRACE_VERBOSE: handle_table.h:100 CSpxHandleTable::TrackHandle p=0x000002067D0EFCF0
[830554]: 6798ms SPX_DBG_TRACE_VERBOSE: handle_table.h:110 CSpxHandleTable::TrackHandle iid=876984227 h=0x000002067D0EFCF0, p=0x000002067D0EFCF0, tot=6
[830554]: 6857ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxVoiceProfile' as '268730634'
[830554]: 6857ms SPX_DBG_TRACE_VERBOSE: handle_table.h:100 CSpxHandleTable::TrackHandle p=0x000002067D132890
[830554]: 6857ms SPX_DBG_TRACE_VERBOSE: handle_table.h:110 CSpxHandleTable::TrackHandle iid=268730634 h=0x000002067D132890, p=0x000002067D132890, tot=1
[830554]: 6858ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxAudioConfig' as '552907028'
[830554]: 6858ms SPX_DBG_TRACE_VERBOSE: handle_table.h:100 CSpxHandleTable::TrackHandle p=0x000002067CEB2958
[830554]: 6858ms SPX_DBG_TRACE_VERBOSE: handle_table.h:110 CSpxHandleTable::TrackHandle iid=552907028 h=0x000002067CEB2958, p=0x000002067CEB2958, tot=3
[830554]: 6858ms SPX_DBG_TRACE_VERBOSE: handle_table.h:100 CSpxHandleTable::TrackHandle p=0x000002067CEB28E8
[830554]: 6858ms SPX_DBG_TRACE_VERBOSE: handle_table.h:110 CSpxHandleTable::TrackHandle iid=876984227 h=0x000002067CEB28E8, p=0x000002067CEB28E8, tot=7
[830554]: 6858ms SPX_DBG_TRACE_SCOPE_ENTER: speechapi_c_factory.cpp:710 recognizer_create_speaker_recognizer_from_config
[830554]: 6858ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSpeechApiFactory' as '818994146'
[830554]: 6858ms SPX_DBG_TRACE_VERBOSE: named_properties.h:62 ISpxNamedProperties::Copy from=0x7ac7bb28 to=0x7cf27848
[830554]: 6858ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067CF27848; name='SPEECH-LogFilename'; value='speech-logs.txt'
[830554]: 6858ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067CF27848; name='SPEECH-Region'; value='westus'
[830554]: 6858ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067CF27848; name='SPEECH-SubscriptionKey'; value='******************************62'
[830554]: 6858ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067CF27848; name='SPEECHSDK-SPEECH-CONFIG-SYSTEM-LANGUAGE'; value='C#'
[830554]: 6858ms SPX_DBG_TRACE_VERBOSE: named_properties.h:62 ISpxNamedProperties::Copy from=0x7ceb28e8 to=0x7cf27848
[830554]: 6858ms SPX_RETURN_ON_FAIL: handle_helpers.h:52 hr = 0x21
[830554]: 6858ms SPX_RETURN_ON_FAIL: handle_helpers.h:52 hr = 0x21
[830554]: 6858ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067AC7BB28; name='CARBON-INTERNAL-UseEmbeddedSpeechConfig'; value=''
[830554]: 6858ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxHttpAudioStreamSession' as '795440402'
[830554]: 6858ms SPX_DBG_TRACE_SCOPE_ENTER: http_audio_stream_session.cpp:27 CSpxHttpAudioStreamSession::Init
[830554]: 6859ms SPX_DBG_TRACE_SCOPE_EXIT: http_audio_stream_session.cpp:27 CSpxHttpAudioStreamSession::Init
[830554]: 6859ms SPX_DBG_TRACE_SCOPE_ENTER: voice_profile_client.cpp:30 CSpxVoiceProfileClient::CSpxVoiceProfileClient
[830554]: 6859ms SPX_DBG_TRACE_SCOPE_EXIT: voice_profile_client.cpp:30 CSpxVoiceProfileClient::CSpxVoiceProfileClient
[830554]: 6859ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxVoiceProfileClient' as '179233059'
[830554]: 6859ms SPX_DBG_TRACE_SCOPE_ENTER: voice_profile_client.cpp:35 CSpxVoiceProfileClient::Init
[830554]: 6859ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067D0C9B38; name='SPEECH-LogFilename'; value='speech-logs.txt'
[830554]: 6859ms SPX_DBG_TRACE_SCOPE_EXIT: voice_profile_client.cpp:35 CSpxVoiceProfileClient::Init
[830554]: 6859ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxInteractiveMicrophone' as '495859151'
[830554]: 6859ms SPX_DBG_TRACE_SCOPE_ENTER: com_init_and_uninit.cpp:18 ComInitAndUnInit ctor enter
[604807]: 6859ms SPX_DBG_TRACE_SCOPE_ENTER: com_init_and_uninit.cpp:44 com worker_thread_proc() enter
[604807]: 6860ms SPX_DBG_TRACE_VERBOSE: com_init_and_uninit.cpp:53 com successfully Initialized
[830554]: 6860ms SPX_DBG_TRACE_SCOPE_EXIT: com_init_and_uninit.cpp:18 ComInitAndUnInit ctor done
[830554]: 6860ms SPX_DBG_TRACE_SCOPE_ENTER: microphone_pump_base.cpp:27 CSpxMicrophonePumpBase::CSpxMicrophonePumpBase
[830554]: 6860ms SPX_DBG_TRACE_SCOPE_EXIT: microphone_pump_base.cpp:27 CSpxMicrophonePumpBase::CSpxMicrophonePumpBase
[830554]: 6860ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxMicrophonePump' as '495859151'
[830554]: 6860ms SPX_DBG_TRACE_VERBOSE: microphone_pump_base.cpp:231 The number of channels as a property is '0' in CSpxMicrophonePump
[830554]: 6860ms SPX_DBG_TRACE_VERBOSE: microphone_pump_base.cpp:231 The number of channels as a property is '0' in CSpxMicrophonePump
[830554]: 6860ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxNamedProperties::GetStringValue: this=0x000002067D133AE8; name='AudioConfig_DeviceNameForCapture'; value=''
[830554]: 6860ms SPX_DBG_TRACE_VERBOSE: microphone_pump_base.cpp:241 The device name of microphone as a property is ''
[830554]: 6889ms SPX_DBG_TRACE_VERBOSE: microphone_pump_base.cpp:59 Received 'Mic In (Elgato Wave:3)' as nice name of the audio device
[830554]: 6889ms SPX_DBG_TRACE_VERBOSE: named_properties.h:311 ISpxPropertyBagImpl::SetStringValue: this=0x000002067D133AE8; name='SPEECH-MicrophoneNiceName'; value='Mic In (Elgato Wave:3)'
[830554]: 6889ms SPX_DBG_TRACE_VERBOSE: http_audio_stream_session.cpp:94 [000002067D0C9AB0]InitFromMicrophone: Pump from microphone:[000002067D133B58]
[830554]: 6889ms SPX_DBG_TRACE_VERBOSE: handle_table.h:100 CSpxHandleTable::TrackHandle p=0x000002067D133048
[830554]: 6889ms SPX_DBG_TRACE_VERBOSE: handle_table.h:110 CSpxHandleTable::TrackHandle iid=179233059 h=0x000002067D133048, p=0x000002067D133048, tot=1
[830554]: 6889ms SPX_DBG_TRACE_SCOPE_EXIT: speechapi_c_factory.cpp:710 recognizer_create_speaker_recognizer_from_config
[830554]: 6889ms SPX_DBG_TRACE_VERBOSE: handle_table.h:100 CSpxHandleTable::TrackHandle p=0x000002067D133058
[830554]: 6889ms SPX_DBG_TRACE_VERBOSE: handle_table.h:110 CSpxHandleTable::TrackHandle iid=876984227 h=0x000002067D133058, p=0x000002067D133058, tot=8
[830554]: 6890ms SPX_DBG_TRACE_SCOPE_ENTER: speaker_verification_model.h:24 CSpxSpeakerVerificationModel::CSpxSpeakerVerificationModel
[830554]: 6890ms SPX_DBG_TRACE_SCOPE_EXIT: speaker_verification_model.h:24 CSpxSpeakerVerificationModel::CSpxSpeakerVerificationModel
[830554]: 6890ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSpeakerVerificationModel' as '894784543'
[830554]: 6890ms SPX_DBG_TRACE_VERBOSE: handle_table.h:100 CSpxHandleTable::TrackHandle p=0x000002067CF09840
[830554]: 6890ms SPX_DBG_TRACE_VERBOSE: handle_table.h:110 CSpxHandleTable::TrackHandle iid=894784543 h=0x000002067CF09840, p=0x000002067CF09840, tot=1
[703443]: 6891ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxAudioDataStream' as '418301898'
[703443]: 6891ms SPX_DBG_TRACE_VERBOSE: null_audio_output.h:93 CSpxNullAudioOutput::SetFormat is called with format 0x000002067CEF9780
[703443]: 6892ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:24 CSpxSynthesisResult::CSpxSynthesisResult
[703443]: 6892ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:92 Created 'CSpxSynthesisResult' as '3874248'
[703443]: 6892ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:76 CSpxUspTtsEngineAdapter::Speak
[703443]: 6892ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:94 CSpxUspTtsEngineAdapter::Speak: start to send synthesis request, request id : 64d4f331cb4b428280a92a8aad52f0aa, try: 0
[849591]: 6892ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:925 DispatchEvent task started...