[156016]: 22ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxThreadService' as '303073113' [156016]: 23ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxPropertyBagImpl::SetStringValue: this=0x0xc34dd780; name='CodecAdapterAvailable'; value='true' [156016]: 23ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:48 CSpxUspTtsEngineAdapter [156016]: 23ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxUspTtsEngineAdapter' as '919101823' [156016]: 23ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxNamedProperties::GetStringValue: this=0x0xbc6dd3e8; name='SPEECH-ProxyHostName'; value='proxyHost' [156016]: 23ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxNamedProperties::GetStringValue: this=0x0xbc6dd3e8; name='SPEECH-ProxyPort'; value='8080' [156016]: 23ms SPX_TRACE_INFO: AZ_LOG_INFO: tlsio_openssl.c:2179 Using OpenSSL 1.1.1n 15 Mar 2022: 101010ef [156016]: 23ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxNullAudioOutput' as '853898539' [156016]: 23ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxNamedProperties::GetStringValue: this=0x0xc34dd780; name='SPEECH-SynthOutputFormat'; value='raw-24khz-16bit-mono-pcm' [156016]: 23ms SPX_DBG_TRACE_VERBOSE: null_audio_output.h:93 SetFormat is called with format 0x0xc66d2520 [156016]: 23ms SPX_DBG_TRACE_VERBOSE: cloud_tts_engine_adapter.cpp:131 SetOutput [156016]: 23ms SPX_TRACE_INFO: cloud_tts_engine_adapter.cpp:181 SetOutput: UseCompressedAudioOnWire is set to true, will use compressed format for transmission. [156016]: 23ms SPX_DBG_TRACE_VERBOSE: handle_table.h:106 CSpxHandleTable::TrackHandle p=0x0xc34dd694 [156016]: 23ms SPX_DBG_TRACE_VERBOSE: handle_table.h:116 CSpxHandleTable::TrackHandle class=ISpxSynthesizer, h=0x0xc34dd694, p=0x0xc34dd694, tot=1 [156016]: 23ms SPX_DBG_TRACE_SCOPE_EXIT: speechapi_c_factory.cpp:476 synthesizer_create_speech_synthesizer_from_config [156016]: 24ms SPX_DBG_TRACE_VERBOSE: handle_table.h:106 CSpxHandleTable::TrackHandle p=0x0xc34dd780 [156016]: 24ms SPX_DBG_TRACE_VERBOSE: handle_table.h:116 CSpxHandleTable::TrackHandle class=ISpxNamedProperties, h=0x0xc34dd780, p=0x0xc34dd780, tot=2 [310128]: 43ms SPX_TRACE_VERBOSE interop_factory.cs:104 InteropSafeHandle Finalizer for 00000000 [310128]: 43ms SPX_TRACE_VERBOSE interop_factory.cs:83 Dispose(False) called for 00000000 [310128]: 43ms SPX_TRACE_VERBOSE interop_factory.cs:83 Dispose(False) called for 00000000 [629360]: 68ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxAudioDataStream' as '418301898' [629360]: 68ms SPX_DBG_TRACE_VERBOSE: null_audio_output.h:93 SetFormat is called with format 0x0xc66d2520 [629360]: 68ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:25 CSpxSynthesisResult [629360]: 68ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxSynthesisResult' as '3874248' [629360]: 68ms SPX_DBG_TRACE_VERBOSE: handle_table.h:106 CSpxHandleTable::TrackHandle p=0x0xe22108e0 [629360]: 68ms SPX_DBG_TRACE_VERBOSE: handle_table.h:116 CSpxHandleTable::TrackHandle class=ISpxSynthesisResult, h=0x0xe22108e0, p=0x0xe22108e0, tot=1 [609904]: 69ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:1072 DispatchEvent task started... [609904]: 69ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:495 [0xc34dd680]CSpxSynthesizer::FireResultEvent [609904]: 69ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:558 No listener connected to event [609904]: 69ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:1072 DispatchEvent task complete! [524016]: 69ms SPX_TRACE_VERBOSE: synthesizer.cpp:243 ExecuteSynthesis: synthesis started, request id: 84ade65a31a0459a84220ecc10073be1; text: 大家好,我是微软语音 [524016]: 69ms SPX_DBG_TRACE_VERBOSE: synthesizer_timeout_management.cpp:30 SetTimeoutValues: RTF set to 2.00; frame timeout interval set to 3000 ms. [524016]: 69ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:80 Speak [524016]: 69ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:98 Speak: start to send synthesis request, request id : 84ade65a31a0459a84220ecc10073be1, try: 0 [524016]: 69ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxNamedProperties::GetStringValue: this=0x0xc34dd780; name='SPEECH-SynthLanguage'; value='zh-CN' [524016]: 69ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxNamedProperties::GetStringValue: this=0x0xc34dd780; name='SPEECH-SynthVoice'; value='zh-CN-XiaomoNeural' [524016]: 69ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:173 SSML sent to TTS cognitive service: 大家好,我是微软语音 [524016]: 69ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:456 UspInitialize: this=0x0xbc6dd3c0 [524016]: 69ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxNamedProperties::GetStringValue: this=0x0xc34dd780; name='SPEECH-SubscriptionKey'; value='******************************a5' [524016]: 69ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxNamedProperties::GetStringValue: this=0x0xbc6dd3e8; name='AZAC-SDK-PROGRAMMING-LANGUAGE'; value='C#' [524016]: 70ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxPropertyBagImpl::SetStringValue: this=0x0xbc6dd3e8; name='HttpHeader#User-agent'; value='SpeechSDK-C#/1.21.0 Linux; Android Android 10 API 29 arm64-v8a Xiaomi/sirius/sirius:10/QKQ1.190828.002/V12.0.2.0.QEBCNXM:user/release-keys 10' [524016]: 70ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxNamedProperties::FindPrefix: this=0x0xbc6dd3e8; name='User-agent'; value='SpeechSDK-C#/1.21.0 Linux; Android Android 10 API 29 arm64-v8a Xiaomi/sirius/sirius:10/QKQ1.190828.002/V12.0.2.0.QEBCNXM:user/release-keys 10' [524016]: 70ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxUspCallbackWrapper' as '978711522' [524016]: 70ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxNamedProperties::GetStringValue: this=0x0xc34dd780; name='SPEECH-Region'; value='eastasia' [524016]: 70ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxNamedProperties::GetStringValue: this=0x0xc34dd780; name='SPEECH-ProxyHostBypass'; value='' [524016]: 71ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxNamedProperties::GetStringValue: this=0x0xc34dd780; name='OPENSSL_SINGLE_TRUSTED_CERT'; value='' [524016]: 71ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxUspConnection' as '792041254' [524016]: 71ms SPX_TRACE_INFO: usp_connection.cpp:460 Connect: entering... [524016]: 71ms SPX_TRACE_INFO: usp_connection.cpp:477 Adding subscription key headers [524016]: 71ms SPX_TRACE_INFO: usp_connection.cpp:512 Set a user defined HTTP header 'User-agent':'SpeechSDK-C#/1.21.0 Linux; Android Android 10 API 29 arm64-v8a Xiaomi/sirius/sirius:10/QKQ1.190828.002/V12.0.2.0.QEBCNXM:user/release-keys 10' [524016]: 71ms SPX_TRACE_INFO: usp_connection.cpp:518 Set an underlying io option 'tcp_nodelay' [524016]: 71ms SPX_TRACE_INFO: usp_connection.cpp:527 connectionUrl=wss://eastasia.tts.speech.microsoft.com/cognitiveservices/websocket/v1 [524016]: 71ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxTelemetry' as '151041793' [524016]: 71ms SPX_DBG_TRACE_SCOPE_ENTER: web_socket.cpp:284 CSpxWebSocket [524016]: 71ms SPX_DBG_TRACE_SCOPE_EXIT: web_socket.cpp:284 CSpxWebSocket [524016]: 71ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxWebSocket' as '482598724' [524016]: 71ms SPX_TRACE_INFO: AZ_LOG_INFO: http_proxy_io.c:187 http_proxy_io_create: Setting up proxy with host:port proxyHost:8080 [349104]: 71ms SPX_TRACE_INFO: web_socket.cpp:952 DoWork: open transport. [349104]: 71ms SPX_TRACE_INFO: web_socket.cpp:646 Start to open websocket. WebSocket: 0xbc6dfe40, wsio handle: 0xe8a78200 [349104]: 71ms SPX_TRACE_INFO: AZ_LOG_INFO: tlsio_openssl.c:2004 create_openssl_instance by TLS_method. [524016]: 71ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxPropertyBagImpl::SetStringValue: this=0x0xc34dd780; name='SPEECH-ConnectionUrl'; value='wss://eastasia.tts.speech.microsoft.com/cognitiveservices/websocket/v1' [524016]: 72ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:345 speech.config {"context":{"system":{"version":"1.21.0","name":"SpeechSDK","build":"Android-arm32"},"os":{"platform":"Linux; Android","name":"Android 10 API 29 arm64-v8a Xiaomi/sirius/sirius:10/QKQ1.190828.002/V12.0.2.0.QEBCNXM:user/release-keys","version":"10"}}} [524016]: 72ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:396 speech.config='{"context":{"system":{"version":"1.21.0","name":"SpeechSDK","build":"Android-arm32"},"os":{"platform":"Linux; Android","name":"Android 10 API 29 arm64-v8a Xiaomi/sirius/sirius:10/QKQ1.190828.002/V12.0.2.0.QEBCNXM:user/release-keys","version":"10"}}}' [524016]: 72ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:396 synthesis.context='{"synthesis":{"audio":{"outputFormat":"audio-24khz-48kbitrate-mono-mp3","metadataOptions":{"visemeEnabled":false,"bookmarkEnabled":false,"wordBoundaryEnabled":false,"punctuationBoundaryEnabled":false,"sentenceBoundaryEnabled":false,"sessionEndEnabled":true}},"language":{"autoDetection":false}}}' [524016]: 72ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:381 ssml 大家好,我是微软语音 [524016]: 72ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:396 ssml='大家好,我是微软语音' [629360]: 76ms SPX_DBG_TRACE_VERBOSE: handle_table.h:106 CSpxHandleTable::TrackHandle p=0x0xe22108e8 [629360]: 76ms SPX_DBG_TRACE_VERBOSE: handle_table.h:116 CSpxHandleTable::TrackHandle class=ISpxNamedProperties, h=0x0xe22108e8, p=0x0xe22108e8, tot=3 [156016]: 78ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxAudioDataStreamSharedAdapter' as '418301898' [156016]: 79ms SPX_DBG_TRACE_VERBOSE: handle_table.h:106 CSpxHandleTable::TrackHandle p=0x0xe8a78154 [156016]: 79ms SPX_DBG_TRACE_VERBOSE: handle_table.h:116 CSpxHandleTable::TrackHandle class=ISpxAudioDataStream, h=0x0xe8a78154, p=0x0xe8a78154, tot=1 [156016]: 80ms SPX_DBG_TRACE_VERBOSE: handle_table.h:106 CSpxHandleTable::TrackHandle p=0x0xe8a7815c [156016]: 80ms SPX_DBG_TRACE_VERBOSE: handle_table.h:116 CSpxHandleTable::TrackHandle class=ISpxNamedProperties, h=0x0xe8a7815c, p=0x0xe8a7815c, tot=4 [156016]: 89ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [349104]: 101ms SPX_TRACE_INFO: AZ_LOG_INFO: tlsio_openssl.c:1859 CRL check enabled. [349104]: 102ms SPX_TRACE_ERROR: AZ_LOG_ERROR: socketio_berkeley.c:609 Failure: socket create failure -1. [349104]: 102ms SPX_TRACE_ERROR: AZ_LOG_ERROR: http_proxy_io.c:285 Underlying IO open failed [349104]: 102ms SPX_TRACE_ERROR: AZ_LOG_ERROR: tlsio_openssl.c:780 Could not open underlying IO: 1 [349104]: 102ms SPX_TRACE_ERROR: web_socket.cpp:1055 WS open operation failed with result=1(WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED), code=-1[0xffffffff] [349104]: 102ms SPX_TRACE_INFO: usp_connection.cpp:907 TS:31, TransportError: connection:0xb988e640, code=5, string=Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED [0x80000001 | -1] wss://eastasia.tts.speech.microsoft.com/cognitiveservices/websocket/v1 X-ConnectionId: d3d2d04cef08442cbbce8b3bcd71e8db [349104]: 102ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:801 Response: On Error: Code:5, Message: Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED [0x80000001 | -1] wss://eastasia.tts.speech.microsoft.com/cognitiveservices/websocket/v1 X-ConnectionId: d3d2d04cef08442cbbce8b3bcd71e8db. [349104]: 102ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x0xbd65da2c [349104]: 102ms SPX_DBG_TRACE_SCOPE_ENTER: usp_connection.cpp:144 ~CSpxUspConnection [349104]: 102ms SPX_DBG_TRACE_SCOPE_EXIT: usp_connection.cpp:144 ~CSpxUspConnection [349104]: 102ms SPX_TRACE_ERROR: AZ_LOG_ERROR: http_proxy_io.c:779 Cannot open the underlying IO. [349104]: 102ms SPX_TRACE_ERROR: AZ_LOG_ERROR: tlsio_openssl.c:2374 Failed opening the underlying I/O. [524016]: 102ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:25 CSpxSynthesisResult [524016]: 102ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxSynthesisResult' as '3874248' [524016]: 102ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxPropertyBagImpl::SetStringValue: this=0x0xb988e468; name='CancellationDetails_ReasonDetailedText'; value='Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED [0x80000001 | -1] wss://eastasia.tts.speech.microsoft.com/cognitiveservices/websocket/v1 X-ConnectionId: d3d2d04cef08442cbbce8b3bcd71e8db USP state: 2. Received audio size: 0 bytes.' [524016]: 102ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxPropertyBagImpl::SetStringValue: this=0x0xb988e468; name='CancellationDetails_ReasonDetailedText'; value='Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED [0x80000001 | -1] wss://eastasia.tts.speech.microsoft.com/cognitiveservices/websocket/v1 X-ConnectionId: d3d2d04cef08442cbbce8b3bcd71e8db USP state: 2. Received audio size: 0 bytes.' [524016]: 102ms SPX_TRACE_ERROR: usp_tts_engine_adapter.cpp:120 Synthesis cancelled without data received, retrying. [524016]: 103ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:98 Speak: start to send synthesis request, request id : e2722557656d47789e591356c3b476e4, try: 1 [524016]: 103ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxNamedProperties::GetStringValue: this=0x0xc34dd780; name='SPEECH-SynthLanguage'; value='zh-CN' [524016]: 103ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxNamedProperties::GetStringValue: this=0x0xc34dd780; name='SPEECH-SynthVoice'; value='zh-CN-XiaomoNeural' [524016]: 103ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:173 SSML sent to TTS cognitive service: 大家好,我是微软语音 [524016]: 103ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:456 UspInitialize: this=0x0xbc6dd3c0 [524016]: 103ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxNamedProperties::GetStringValue: this=0x0xc34dd780; name='SPEECH-SubscriptionKey'; value='******************************a5' [524016]: 103ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxNamedProperties::GetStringValue: this=0x0xbc6dd3e8; name='AZAC-SDK-PROGRAMMING-LANGUAGE'; value='C#' [524016]: 103ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxNamedProperties::GetStringValue: this=0x0xbc6dd3e8; name='HttpHeader#User-agent'; value='SpeechSDK-C#/1.21.0 Linux; Android Android 10 API 29 arm64-v8a Xiaomi/sirius/sirius:10/QKQ1.190828.002/V12.0.2.0.QEBCNXM:user/release-keys 10' [524016]: 103ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxNamedProperties::FindPrefix: this=0x0xbc6dd3e8; name='User-agent'; value='SpeechSDK-C#/1.21.0 Linux; Android Android 10 API 29 arm64-v8a Xiaomi/sirius/sirius:10/QKQ1.190828.002/V12.0.2.0.QEBCNXM:user/release-keys 10' [524016]: 103ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxUspCallbackWrapper' as '978711522' [524016]: 103ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxNamedProperties::GetStringValue: this=0x0xc34dd780; name='SPEECH-Region'; value='eastasia' [524016]: 103ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxNamedProperties::GetStringValue: this=0x0xc34dd780; name='SPEECH-ProxyHostBypass'; value='' [524016]: 103ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxNamedProperties::GetStringValue: this=0x0xc34dd780; name='OPENSSL_SINGLE_TRUSTED_CERT'; value='' [524016]: 103ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxUspConnection' as '792041254' [524016]: 103ms SPX_TRACE_INFO: usp_connection.cpp:460 Connect: entering... [524016]: 103ms SPX_TRACE_INFO: usp_connection.cpp:477 Adding subscription key headers [524016]: 103ms SPX_TRACE_INFO: usp_connection.cpp:512 Set a user defined HTTP header 'User-agent':'SpeechSDK-C#/1.21.0 Linux; Android Android 10 API 29 arm64-v8a Xiaomi/sirius/sirius:10/QKQ1.190828.002/V12.0.2.0.QEBCNXM:user/release-keys 10' [524016]: 103ms SPX_TRACE_INFO: usp_connection.cpp:518 Set an underlying io option 'tcp_nodelay' [524016]: 103ms SPX_TRACE_INFO: usp_connection.cpp:527 connectionUrl=wss://eastasia.tts.speech.microsoft.com/cognitiveservices/websocket/v1 [524016]: 103ms SPX_DBG_TRACE_SCOPE_ENTER: web_socket.cpp:284 CSpxWebSocket [524016]: 103ms SPX_DBG_TRACE_SCOPE_EXIT: web_socket.cpp:284 CSpxWebSocket [524016]: 103ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxWebSocket' as '482598724' [524016]: 103ms SPX_TRACE_INFO: AZ_LOG_INFO: http_proxy_io.c:187 http_proxy_io_create: Setting up proxy with host:port proxyHost:8080 [524016]: 103ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxPropertyBagImpl::SetStringValue: this=0x0xc34dd780; name='SPEECH-ConnectionUrl'; value='wss://eastasia.tts.speech.microsoft.com/cognitiveservices/websocket/v1' [524016]: 103ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:345 speech.config {"context":{"system":{"version":"1.21.0","name":"SpeechSDK","build":"Android-arm32"},"os":{"platform":"Linux; Android","name":"Android 10 API 29 arm64-v8a Xiaomi/sirius/sirius:10/QKQ1.190828.002/V12.0.2.0.QEBCNXM:user/release-keys","version":"10"}}} [524016]: 103ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:396 speech.config='{"context":{"system":{"version":"1.21.0","name":"SpeechSDK","build":"Android-arm32"},"os":{"platform":"Linux; Android","name":"Android 10 API 29 arm64-v8a Xiaomi/sirius/sirius:10/QKQ1.190828.002/V12.0.2.0.QEBCNXM:user/release-keys","version":"10"}}}' [524016]: 103ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:396 synthesis.context='{"synthesis":{"audio":{"outputFormat":"audio-24khz-48kbitrate-mono-mp3","metadataOptions":{"visemeEnabled":false,"bookmarkEnabled":false,"wordBoundaryEnabled":false,"punctuationBoundaryEnabled":false,"sentenceBoundaryEnabled":false,"sessionEndEnabled":true}},"language":{"autoDetection":false}}}' [524016]: 103ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:381 ssml 大家好,我是微软语音 [524016]: 103ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:396 ssml='大家好,我是微软语音' [349104]: 104ms SPX_TRACE_ERROR: AZ_LOG_ERROR: uws_client.c:1781 Opening the underlying IO failed [349104]: 104ms SPX_TRACE_ERROR: web_socket.cpp:676 uws_client_open_async failed with result 1783 [349104]: 104ms SPX_TRACE_ERROR: web_socket.h:74 Failed to change state for WebSocket 0xbc6dfe40. From: 1, To: 0, Current: 0 [349104]: 105ms SPX_TRACE_ERROR: web_socket.cpp:956 Failed to open transport [349104]: 105ms SPX_TRACE_ERROR: usp_tts_engine_adapter.cpp:450 usp connection lost when trying to send message. [349104]: 105ms SPX_TRACE_ERROR: usp_tts_engine_adapter.cpp:450 usp connection lost when trying to send message. [349104]: 105ms SPX_TRACE_ERROR: usp_tts_engine_adapter.cpp:450 usp connection lost when trying to send message. [349104]: 105ms SPX_TRACE_INFO: web_socket.cpp:952 DoWork: open transport. [349104]: 105ms SPX_TRACE_INFO: web_socket.cpp:646 Start to open websocket. WebSocket: 0xbc6e00c0, wsio handle: 0xe8a78380 [349104]: 105ms SPX_TRACE_INFO: AZ_LOG_INFO: tlsio_openssl.c:2004 create_openssl_instance by TLS_method. [349104]: 123ms SPX_TRACE_INFO: AZ_LOG_INFO: tlsio_openssl.c:1859 CRL check enabled. [349104]: 123ms SPX_TRACE_ERROR: AZ_LOG_ERROR: socketio_berkeley.c:609 Failure: socket create failure -1. [349104]: 123ms SPX_TRACE_ERROR: AZ_LOG_ERROR: http_proxy_io.c:285 Underlying IO open failed [349104]: 123ms SPX_TRACE_ERROR: AZ_LOG_ERROR: tlsio_openssl.c:780 Could not open underlying IO: 1 [349104]: 123ms SPX_TRACE_ERROR: web_socket.cpp:1055 WS open operation failed with result=1(WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED), code=-1[0xffffffff] [349104]: 123ms SPX_TRACE_INFO: usp_connection.cpp:907 TS:20, TransportError: connection:0xb988e820, code=5, string=Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED [0x80000001 | -1] wss://eastasia.tts.speech.microsoft.com/cognitiveservices/websocket/v1 X-ConnectionId: 0d1987c99f8340ac9e745eafde4a0894 [349104]: 123ms SPX_DBG_TRACE_VERBOSE: usp_tts_engine_adapter.cpp:801 Response: On Error: Code:5, Message: Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED [0x80000001 | -1] wss://eastasia.tts.speech.microsoft.com/cognitiveservices/websocket/v1 X-ConnectionId: 0d1987c99f8340ac9e745eafde4a0894. [349104]: 123ms SPX_DBG_TRACE_VERBOSE: create_object_helpers.h:78 SpxTerm: ptr=0x0xbd65deb4 [349104]: 123ms SPX_DBG_TRACE_SCOPE_ENTER: usp_connection.cpp:144 ~CSpxUspConnection [349104]: 123ms SPX_DBG_TRACE_SCOPE_EXIT: usp_connection.cpp:144 ~CSpxUspConnection [349104]: 123ms SPX_TRACE_ERROR: AZ_LOG_ERROR: http_proxy_io.c:779 Cannot open the underlying IO. [349104]: 123ms SPX_TRACE_ERROR: AZ_LOG_ERROR: tlsio_openssl.c:2374 Failed opening the underlying I/O. [524016]: 123ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:25 CSpxSynthesisResult [524016]: 123ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxSynthesisResult' as '3874248' [524016]: 123ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxPropertyBagImpl::SetStringValue: this=0x0xb988e6e8; name='CancellationDetails_ReasonDetailedText'; value='Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED [0x80000001 | -1] wss://eastasia.tts.speech.microsoft.com/cognitiveservices/websocket/v1 X-ConnectionId: 0d1987c99f8340ac9e745eafde4a0894 USP state: 2. Received audio size: 0 bytes.' [524016]: 123ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxPropertyBagImpl::SetStringValue: this=0x0xb988e6e8; name='CancellationDetails_ReasonDetailedText'; value='Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED [0x80000001 | -1] wss://eastasia.tts.speech.microsoft.com/cognitiveservices/websocket/v1 X-ConnectionId: 0d1987c99f8340ac9e745eafde4a0894 USP state: 2. Received audio size: 0 bytes.' [524016]: 123ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:30 ~CSpxSynthesisResult [524016]: 123ms SPX_TRACE_ERROR: usp_tts_engine_adapter.cpp:120 Synthesis cancelled without data received, retrying. [524016]: 123ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxPropertyBagImpl::SetStringValue: this=0x0xe226b09c; name='RESULT-SynthesisBackend'; value='online (websocket)' [524016]: 123ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxPropertyBagImpl::SetStringValue: this=0x0xe226b09c; name='RESULT-SynthesisFirstByteLatencyMs'; value='-1' [524016]: 123ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxPropertyBagImpl::SetStringValue: this=0x0xe226b09c; name='RESULT-SynthesisFinishLatencyMs'; value='54' [524016]: 123ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxPropertyBagImpl::SetStringValue: this=0x0xe226b09c; name='RESULT-SynthesisUnderrunTimeMs'; value='0' [524016]: 123ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxPropertyBagImpl::SetStringValue: this=0x0xe226b09c; name='CancellationDetails_ReasonDetailedText'; value='Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED [0x80000001 | -1] wss://eastasia.tts.speech.microsoft.com/cognitiveservices/websocket/v1 X-ConnectionId: 0d1987c99f8340ac9e745eafde4a0894 USP state: 2. Received audio size: 0 bytes.' [524016]: 123ms SPX_DBG_TRACE_VERBOSE: pull_audio_output_stream.cpp:162 Signal End of Writing is called [156016]: 124ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [609904]: 124ms SPX_DBG_TRACE_SCOPE_ENTER: synthesizer.cpp:1072 DispatchEvent task started... [609904]: 124ms SPX_DBG_TRACE_VERBOSE: synthesizer.cpp:495 [0xc34dd680]CSpxSynthesizer::FireResultEvent [609904]: 124ms SPX_DBG_TRACE_VERBOSE: resource_manager.cpp:95 Created 'CSpxSynthesisEventArgs' as '657912386' [609904]: 124ms SPX_DBG_TRACE_VERBOSE: handle_table.h:106 CSpxHandleTable::TrackHandle p=0x0xc36445e0 [609904]: 124ms SPX_DBG_TRACE_VERBOSE: handle_table.h:116 CSpxHandleTable::TrackHandle class=ISpxSynthesisEventArgs, h=0x0xc36445e0, p=0x0xc36445e0, tot=1 [609904]: 125ms SPX_DBG_TRACE_VERBOSE: handle_table.h:106 CSpxHandleTable::TrackHandle p=0x0xb988e6e0 [609904]: 126ms SPX_DBG_TRACE_VERBOSE: handle_table.h:116 CSpxHandleTable::TrackHandle class=ISpxSynthesisResult, h=0x0xb988e6e0, p=0x0xb988e6e0, tot=2 [349104]: 126ms SPX_TRACE_ERROR: AZ_LOG_ERROR: uws_client.c:1781 Opening the underlying IO failed [349104]: 126ms SPX_TRACE_ERROR: web_socket.cpp:676 uws_client_open_async failed with result 1783 [349104]: 126ms SPX_TRACE_ERROR: web_socket.h:74 Failed to change state for WebSocket 0xbc6e00c0. From: 1, To: 0, Current: 0 [349104]: 126ms SPX_TRACE_ERROR: web_socket.cpp:956 Failed to open transport [609904]: 126ms SPX_DBG_TRACE_VERBOSE: handle_table.h:106 CSpxHandleTable::TrackHandle p=0x0xb988e6e8 [349104]: 126ms SPX_TRACE_ERROR: usp_tts_engine_adapter.cpp:450 usp connection lost when trying to send message. [609904]: 126ms SPX_DBG_TRACE_VERBOSE: handle_table.h:116 CSpxHandleTable::TrackHandle class=ISpxNamedProperties, h=0x0xb988e6e8, p=0x0xb988e6e8, tot=5 [349104]: 126ms SPX_TRACE_ERROR: usp_tts_engine_adapter.cpp:450 usp connection lost when trying to send message. [349104]: 126ms SPX_TRACE_ERROR: usp_tts_engine_adapter.cpp:450 usp connection lost when trying to send message. [349104]: 126ms SPX_DBG_TRACE_SCOPE_ENTER: web_socket.cpp:289 ~CSpxWebSocket [349104]: 126ms SPX_DBG_TRACE_SCOPE_EXIT: web_socket.cpp:289 ~CSpxWebSocket [609904]: 129ms SPX_DBG_TRACE_VERBOSE: named_properties.h:451 ISpxNamedProperties::GetStringValue: this=0x0xb988e6e8; name='CancellationDetails_ReasonDetailedText'; value='Connection failed (no connection to the remote host). Internal error: 1. Error details: Failed with error: WS_OPEN_ERROR_UNDERLYING_IO_OPEN_FAILED [0x80000001 | -1] wss://eastasia.tts.speech.microsoft.com/cognitiveservices/websocket/v1 X-ConnectionId: 0d1987c99f8340ac9e745eafde4a0894 USP state: 2. Received audio size: 0 bytes.' [609904]: 132ms SPX_TRACE_VERBOSE interop_factory.cs:83 Dispose(True) called for c36445e0 [609904]: 133ms SPX_DBG_TRACE_VERBOSE: handle_table.h:175 CSpxHandleTable::StopTracking(h) h=0x0xc36445e0 [609904]: 133ms SPX_DBG_TRACE_VERBOSE: handle_table.h:190 CSpxHandleTable::StopTracking(h) class=ISpxSynthesisEventArgs, h=0x0xc36445e0, p=0x0xc36445e0, tot=0 [609904]: 133ms SPX_TRACE_VERBOSE interop_factory.cs:83 Dispose(True) called for b988e6e8 [609904]: 135ms SPX_DBG_TRACE_VERBOSE: handle_table.h:175 CSpxHandleTable::StopTracking(h) h=0x0xb988e6e8 [609904]: 135ms SPX_DBG_TRACE_VERBOSE: handle_table.h:190 CSpxHandleTable::StopTracking(h) class=ISpxNamedProperties, h=0x0xb988e6e8, p=0x0xb988e6e8, tot=4 [609904]: 135ms SPX_TRACE_VERBOSE interop_factory.cs:83 Dispose(True) called for b988e6e0 [349104]: 135ms SPX_DBG_TRACE_SCOPE_ENTER: web_socket.cpp:289 ~CSpxWebSocket [349104]: 135ms SPX_DBG_TRACE_SCOPE_EXIT: web_socket.cpp:289 ~CSpxWebSocket [609904]: 136ms SPX_DBG_TRACE_VERBOSE: handle_table.h:175 CSpxHandleTable::StopTracking(h) h=0x0xb988e6e0 [609904]: 136ms SPX_DBG_TRACE_VERBOSE: handle_table.h:190 CSpxHandleTable::StopTracking(h) class=ISpxSynthesisResult, h=0x0xb988e6e0, p=0x0xb988e6e0, tot=1 [609904]: 136ms SPX_DBG_TRACE_SCOPE_EXIT: synthesizer.cpp:1072 DispatchEvent task complete! [609904]: 136ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:30 ~CSpxSynthesisResult [156016]: 324ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [156016]: 324ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [156016]: 524ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [156016]: 525ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [156016]: 732ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [156016]: 732ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [156016]: 933ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [310128]: 933ms SPX_TRACE_VERBOSE interop_factory.cs:104 InteropSafeHandle Finalizer for 00000000 [156016]: 933ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [310128]: 933ms SPX_TRACE_VERBOSE interop_factory.cs:83 Dispose(False) called for 00000000 [310128]: 933ms SPX_TRACE_VERBOSE interop_factory.cs:83 Dispose(False) called for 00000000 [310128]: 933ms SPX_TRACE_VERBOSE interop_factory.cs:104 InteropSafeHandle Finalizer for 00000000 [310128]: 933ms SPX_TRACE_VERBOSE interop_factory.cs:83 Dispose(False) called for 00000000 [310128]: 933ms SPX_TRACE_VERBOSE interop_factory.cs:83 Dispose(False) called for 00000000 [310128]: 933ms SPX_TRACE_VERBOSE interop_factory.cs:104 InteropSafeHandle Finalizer for 00000000 [310128]: 933ms SPX_TRACE_VERBOSE interop_factory.cs:83 Dispose(False) called for 00000000 [310128]: 933ms SPX_TRACE_VERBOSE interop_factory.cs:83 Dispose(False) called for 00000000 [156016]: 1133ms SPX_TRACE_VERBOSE interop_factory.cs:83 Dispose(True) called for e22108e8 [156016]: 1133ms SPX_DBG_TRACE_VERBOSE: handle_table.h:175 CSpxHandleTable::StopTracking(h) h=0x0xe22108e8 [156016]: 1133ms SPX_DBG_TRACE_VERBOSE: handle_table.h:190 CSpxHandleTable::StopTracking(h) class=ISpxNamedProperties, h=0x0xe22108e8, p=0x0xe22108e8, tot=3 [156016]: 1133ms SPX_TRACE_VERBOSE interop_factory.cs:83 Dispose(True) called for e22108e0 [156016]: 1133ms SPX_DBG_TRACE_VERBOSE: handle_table.h:175 CSpxHandleTable::StopTracking(h) h=0x0xe22108e0 [156016]: 1133ms SPX_DBG_TRACE_VERBOSE: handle_table.h:190 CSpxHandleTable::StopTracking(h) class=ISpxSynthesisResult, h=0x0xe22108e0, p=0x0xe22108e0, tot=0 [156016]: 1133ms SPX_DBG_TRACE_FUNCTION: synthesis_result.cpp:30 ~CSpxSynthesisResult [747824]: 1538ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 1538ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 1738ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 1739ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 1939ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 1939ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 2160ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 2160ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 2360ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 2360ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 2561ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 2561ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 2781ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 2782ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 2982ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 2982ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 3183ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 3184ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 3384ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 3384ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 3584ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 3584ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 3785ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 3785ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 4006ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 4006ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 4206ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 4206ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 4407ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 4407ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 4627ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 4628ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 4828ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 4828ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 5028ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 5028ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 5252ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 5252ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 5453ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 5453ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 5654ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 5654ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 5854ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 5855ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 6055ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 6055ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 6255ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 6256ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 6476ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 6476ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 6677ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 6677ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 6877ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 6877ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 7098ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 7098ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 7298ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 7298ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 7500ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 7500ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 7720ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 7720ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 7921ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 7921ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 8121ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 8121ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 8322ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 8322ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 8523ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 8523ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 8723ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 8723ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 8944ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 8944ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 9144ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 9145ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 9345ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 9345ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 9566ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 9566ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 9767ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 9767ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 9968ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 9968ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 10169ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 10169ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 10369ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 10369ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 10570ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 10570ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 10790ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 10791ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 10991ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 10991ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 11191ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 11191ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 11412ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 11412ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 11612ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 11612ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 11813ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 11814ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 12034ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 12034ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 12235ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 12235ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 12435ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 12436ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 12636ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 12636ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 12836ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 12836ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 13037ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 13037ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 13257ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 13257ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 13458ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 13458ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 13658ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 13658ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 13879ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 13879ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 14080ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 14080ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 14281ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 14281ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 14501ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 14501ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 14702ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 14702ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 14902ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 14902ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 15103ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 15103ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 15303ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 15303ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 15504ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 15504ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 15725ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 15725ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 15925ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 15925ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 16126ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 16126ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 16346ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 16347ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 16547ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 16547ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 16747ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 16747ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 16948ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 16948ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 17148ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 17148ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 17348ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 17349ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 17569ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 17569ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 17769ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 17770ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 17970ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 17970ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 18191ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 18191ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 18392ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 18392ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 18593ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 18593ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 18813ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 18813ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 19014ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 19014ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 19214ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 19215ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 19415ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 19415ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 19615ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 19615ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 19816ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 19816ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 20036ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 20036ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 20237ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 20237ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 20437ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 20437ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 20658ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 20659ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 20859ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 20859ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 21059ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 21060ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 21280ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 21280ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 21481ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 21481ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 21681ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 21681ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 21881ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 21881ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 22082ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 22082ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 22282ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 22282ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 22503ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 22503ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 22704ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 22704ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 22904ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 22904ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 23125ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 23125ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 23325ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 23325ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 23526ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 23526ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 23746ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 23747ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 23947ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 23947ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 24147ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 24147ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 24348ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 24348ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 24548ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 24548ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 24749ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 24749ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 24970ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 24970ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 25170ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 25171ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 25371ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 25371ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 25592ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 25592ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 25792ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 25792ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 25993ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 25993ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 26193ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 26194ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 26394ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 26394ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 26595ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 26595ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 26816ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 26816ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 27017ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 27018ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 27218ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 27218ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 27439ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 27439ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 27639ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 27640ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 27840ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 27840ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 28061ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 28061ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 28261ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 28262ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 28463ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 28463ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 28663ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 28663ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 28864ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 28864ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 29065ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 29065ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 29286ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 29286ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 29487ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 29487ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 29687ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 29725ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 29946ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 29946ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 30146ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 30146ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 30347ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 30347ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 30547ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 30547ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 30748ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 30748ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 30948ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 30948ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 31169ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 31169ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 31369ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 31369ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 31570ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 31570ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 31791ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 31791ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 31991ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 31991ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 32192ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 32192ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 32412ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 32413ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 32613ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 32613ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 32813ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 32813ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 33013ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 33014ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 33214ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 33214ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 33415ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 33415ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 33636ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 33636ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 33836ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 33836ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 34037ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 34037ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 34257ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 34257ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0 [747824]: 34458ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:137 CSpxAudioDataStream::Read: is called [747824]: 34458ms SPX_DBG_TRACE_VERBOSE: audio_data_stream.cpp:242 CSpxAudioDataStream::FillBuffer: bytesRead=0