Skip to content

Instantly share code, notes, and snippets.

@boyce-xx

boyce-xx/log.txt

Created January 2, 2018 10:41
Show Gist options
  • Save boyce-xx/2121eedca65ac71f9cbfa9c4dc15466e to your computer and use it in GitHub Desktop.
Save boyce-xx/2121eedca65ac71f9cbfa9c4dc15466e to your computer and use it in GitHub Desktop.
Download ZIP
The waiting time is too long(about 8 seconds) between TTS and Music playing when playing music; but Echo will take less than 1 seond
Raw
log.txt
*** wakeWordTrigger:: onKeyWordDetected
stopMediaPlayer 11111
stopLocalMediaPlayer(Local) 22222 --> sourceID = 0
stopSpeakMediaPlayer(TTS) 33333 --> sourceID = 0
stopRendererMediaPlayer(Alert) 44444 --> sourceID = 0
stopMediaPlayer 55555
---> reset state! --> 2018-01-02 18:28:39:197
openFMDuringTest : isFMOpened = 0
Nut2AVS King:: wakeWordTrigger::onKeyWordDetected.....beginIndex = 169680, endIndex = 177120,
Nut2AVS King:: wakeWordTrigger::Sensory recognize.....beginIndex = 169680, endIndex = 177120
executeResetState m_expectingSpeechTimer.stop...
*** AudioInputProcessor::executeRecognize initiatorPayloadJson="wakeWordIndices":{"startIndexInSamples":8000,"endIndexInSamples":15440}
2018-01-02 10:28:39.202 [ a] 0 AudioInputProcessor:setState:from=IDLE,to=RECOGNIZING
DialogUXStateAggregator::onStateChanged state = 2
2018-01-02 10:28:39.203 [ a] 0 ContextManager:updateStateLocked:action=updatedState,state={"wakeword" \: "alexa"},namespace=SpeechRecognizer,name=RecognizerState
m_expectingSpeechTimer.stop...
2018-01-02 10:28:39.203 [ 10] 0 DialogUXStateAggregator:setState:from=IDLE,to=LISTENING
*** WisStatusChangedInterface::onDialogUXStateChanged:: *** oldState = 1, newState = 1
start:: turn on LED， ledType = 1
2018-01-02 10:28:39.206 [ 7] 0 ContextManager:buildContextSuccessful:context={"context"\:[{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"PlaybackState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"IDLE"}}\,{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"SpeechState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"}}\,{"header"\:{"namespace"\:"Alerts"\,"name"\:"AlertsState"}\,"payload"\:{"allAlerts"\:[]\,"activeAlerts"\:[]}}\,{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"RecognizerState"}\,"payload"\:{"wakeword"\:"alexa"}}]} Start to open < LISTENING > LED --> 2018-01-02 18:28:39:206
AudioInputProcessor::executeOnDialogUXStateChanged newState1 = 1
##### Start recording!
2018-01-02 10:28:39.223 [ a] 0 AudioInputProcessor:executeOnContextAvailable:jsonContext={"context"\:[{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"PlaybackState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"IDLE"}}\,{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"SpeechState"}\,"payload"\:{"token"\:""\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"}}\,{"header"\:{"namespace"\:"Alerts"\,"name"\:"AlertsState"}\,"payload"\:{"allAlerts"\:[]\,"activeAlerts"\:[]}}\,{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"RecognizerState"}\,"payload"\:{"wakeword"\:"alexa"}}]}
playPreRecSoundPrompt thread: detachCode = 0
Start to play med_ui_voice_start_listening.wav --> 2018-01-02 18:28:39:225
2018-01-02 18:28:39.225 [ a] 1 FocusManager:acquireChannel:channelName=Dialog,activityId=SpeechRecognizer.Recognize
2018-01-02 10:28:39.227 [ a] I DirectiveProcessor:setDialogRequestIdLocked:oldValue=,newValue=96b9e45d-3f64-499d-85c3-7b50771ede45
2018-01-02 10:28:39.227 [ a] 0 DirectiveProcessor:scrubDialogRequestIdLocked:reason=emptyDialogRequestId
2018-01-02 10:28:39.227 [ 11] 9 AudioInputProcessor:onFocusChanged:newFocus=FOREGROUND
2018-01-02 10:28:39.228 [ a] 0 EventBuilder:buildJsonEventString:messageId=0dd5d92e-77a7-4560-bc15-41e7f021af9c,namespace=SpeechRecognizer,name=Recognize
SKU detect: CX/SB
Current SKU is: CX
stopMediaPlayer 11111
stopLocalMediaPlayer(Local) 22222 --> sourceID = 0
stopSpeakMediaPlayer(TTS) 33333 --> sourceID = 0
stopRendererMediaPlayer(Alert) 44444 --> sourceID = 0
stopMediaPlayer 55555
AudioInputProcessor::executeOnFocusChanged***********************
2018-01-02 10:28:39.231 [ a] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=FOREGROUND
AVSConnectionManager::sendMessage {"context":[{"header":{"namespace":"AudioPlayer","name":"PlaybackState"},"payload":{"token":"","offsetInMilliseconds":0,"playerActivity":"IDLE"}},{"header":{"namespace":"SpeechSynthesizer","name":"SpeechState"},"payload":{"token":"","offsetInMilliseconds":0,"playerActivity":"FINISHED"}},{"header":{"namespace":"Alerts","name":"AlertsState"},"payload":{"allAlerts":[],"activeAlerts":[]}},{"header":{"namespace":"SpeechRecognizer","name":"RecognizerState"},"payload":{"wakeword":"alexa"}}],"event":{"header":{"namespace":"SpeechRecognizer","name":"Recognize","messageId":"0dd5d92e-77a7-4560-bc15-41e7f021af9c","dialogRequestId":"96b9e45d-3f64-499d-85c3-7b50771ede45"},"payload":{"profile":"NEAR_FIELD","format":"AUDIO_L16_RATE_16000_CHANNELS_1","initiator":{"type":"WAKEWORD","payload":{"wakeWordIndices":{"startIndexInSamples":8000,"endIndexInSamples":15440}}}}}}
2018-01-02 10:28:39.240 [ a] 9 HTTP2Transport:enqueueRequest
2018-01-02 10:28:39.253 [ e] 0 HTTP2Transport:processNextOutgoingMessage
2018-01-02 10:28:39.254 [ e] 0 HTTP2StreamPool:getStream:streamId=9,numAcquiredStreams=2
2018-01-02 10:28:39.254 [ e] I HTTP2Transport:insertActiveStream:handle=0xa65f0330
^^^^^^ ______ ^^^^^^ HTTP2Transport::processNextOutgoingMessage insertActiveStream id= 9
consumer is done
sensoryKeyWordDetected = 1, isExpectDirectStatus = 0, exitAVS = 0
exitAVS = 0, m_wisStatusChanged->isStopCaptureFlag = 0, isRecordingFinish = 0
SKU is CX: To play preRecording sound cue
end:: turn on LED
Playing sample: 2 ch, 48000 hz, 16 bit
End to delay......
mWisAvs.m_connectionStatusObserver->getConnectionStatus: status = 2, AVSConnectionManager::isEnabled = 1, AVSConnectionManager::isConnected = 1
End play med_ui_voice_start_listening.wav --> 2018-01-02 18:28:40:198
2018-01-02 10:28:43.101 [ 12] 0 MessageInterpreter:receive:messageId=74b688a2-fc6e-4fa7-a891-d1a43a01dafb:No dialogRequestId attached to message.
2018-01-02 10:28:43.103 [ 12] I DirectiveSequencer:onDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"StopCapture"\,"messageId"\:"74b688a2-fc6e-4fa7-a891-d1a43a01dafb"}\,"payload"\:{}}}
...onDirectiveArrived...
onDirectiveArrived: To stop recording..., sensoryKeyWordDetected = 1
2018-01-02 10:28:43.105 [ 13] I DirectiveRouter:preHandleDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"SpeechRecognizer"\,"name"\:"StopCapture"\,"messageId"\:"74b688a2-fc6e-4fa7-a891-d1a43a01dafb"}\,"payload"\:{}}}
2018-01-02 10:28:43.105 [ 13] I DirectiveRouter:preHandleDirective:messageId=74b688a2-fc6e-4fa7-a891-d1a43a01dafb,action=calling
2018-01-02 10:28:43.106 [ 13] 0 CapabilityAgent:addingMessageIdToMap:messageId=74b688a2-fc6e-4fa7-a891-d1a43a01dafb
2018-01-02 10:28:43.108 [ 14] I DirectiveRouter:handleDirective:messageId=74b688a2-fc6e-4fa7-a891-d1a43a01dafb,action=calling
AudioInputProcessor::handleDirective --------StopCapture
2018-01-02 10:28:43.110 [ a] 0 AudioInputProcessor:stopCapture:stopImmediately=true
2018-01-02 10:28:43.110 [ a] 0 AudioInputProcessor:setState:from=RECOGNIZING,to=BUSY
DialogUXStateAggregator::onStateChanged state = 3
2018-01-02 10:28:43.110 [ 10] 0 DialogUXStateAggregator:setState:from=LISTENING,to=THINKING
*** WisStatusChangedInterface::onDialogUXStateChanged:: *** oldState = 2, newState = 2
2018-01-02 10:28:43.110 [ a] 0 DirectiveProcessor:onHandlingCompeted:messageId=74b688a2-fc6e-4fa7-a891-d1a43a01dafb,directiveBeingPreHandled=(nullptr)start:: turn on LED， ledType = 7
Start to open < THINKING > LED --> 2018-01-02 18:28:43:111
-----> For Vick: mThis->m_wisStatusChanged->isStopCaptureFlag
End Recording!!!!!!! --> 2018-01-02 18:28:43:111
closing input, handle(2457)
closing input
2018-01-02 10:28:43.111 [ a] 0 CapabilityAgent:removingMessageIdFromMap:messageId=74b688a2-fc6e-4fa7-a891-d1a43a01dafb
2018-01-02 10:28:43.118 [ e] I InProcessAttachmentReader:readFailed:reason=SDS is closed
AudioInputProcessor::executeOnDialogUXStateChanged newState1 = 2
-----> For Vick: qahw_in_standby...
-----> For Vick: qahw_close_input_stream...
The audio recording has been saved to /data/nut2_avs/hal_rec_test_rec_original.wav. Please use adb pull to get the file and play it using audacity. The audio data has the following characteristics:
Sample rate: 16000
Format: 1
Num channels: 1, handle(2457)
-----> For Vick: qahw_unload_module...
Done with hal record test
producer is done
##### End recording!
playPostRecSoundPrompt thread: detachCode = 0
Start to play med_ui_voice_stop_listening.wav --> 2018-01-02 18:28:43:166
--> WisRingBuffer:: To release RingBuffer...
WisRingBuffer: free()
WisRingBuffer: free rb->mBuffer
SKU detect: CX/SB
Current SKU is: CX
stopMediaPlayer 11111
stopLocalMediaPlayer(Local) 22222 --> sourceID = 0
stopSpeakMediaPlayer(TTS) 33333 --> sourceID = 0
stopRendererMediaPlayer(Alert) 44444 --> sourceID = 0
stopMediaPlayer 55555
WisRingBuffer: free rb
WisRingBuffer: free() done
end:: turn on LED
SKU is CX: To play postRecording sound cue
Playing sample: 2 ch, 48000 hz, 16 bit
--> m_wisStatusChanged->isStopCaptureFlag = 1
---> Recognize done! --> 2018-01-02 18:28:43:330
^_^^^_^^^^_^^^^^^_^^^^^^_^^^^^^^^_^^^^ testCycleIndex: 1
Start execute handleOneSpeechBySensory() --> 2018-01-02 18:28:43:381
WisRingBuffer: init()
===== RingBuffer: Allocated 524160 (bytes) for ring buffer pool
handleOneSpeechBySensory: produceRecordingThreadError = 0
produce_Recording thread: detachCode = 0
********** start hal_rec_test recording *********
-----> For Vick: qahw_load_module...
start first record thread
qahw_open_input_stream...... --> 2018-01-02 18:28:43:384
-----> For Vick: qahw_open_input_stream...
input opened, buffer 0xa6600ac8, size 640, handle(2457)
-----> For Vick: qahw_in_set_parameters...
Please speak into the microphone for 6 seconds, handle(2457)
Start Recording!!!!!!! Start Recording!!!!!!! Start Recording!!!!!!! --> 2018-01-02 18:28:43:390
ACDB -> send_audio_cal, acdb_id = 4, path = 1, app id = 0x11132, sample rate = 16000
ACDB -> send_asm_topology
ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TOPOLOGY_ID
ACDB -> send_adm_topology
ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TOPOLOGY_ID
ACDB -> send_audtable
ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE_SIZE
Failed to fetch the lookup information of the device 00000004
Error: ACDB_CMD_GET_AUDPROC_COMMON_TABLE_SIZE Returned = -19
ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE
Failed to fetch the lookup information of the device 00000004
Error: ACDB audproc returned = -19
ACDB -> AUDIO_SET_AUDPROC_CAL
ACDB -> send_audvoltable
ACDB -> ACDB_CMD_GET_AUDPROC_GAIN_DEP_STEP_TABLE, vol index 0
Failed to fetch the lookup information of the device 00000004
Error: ACDB AudProc vol returned = -19
ACDB -> AUDIO_SET_VOL_CAL cal type = 12
ACDB -> send_audstrmtable
ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TABLE_V2
ACDB -> audstrm_cal->cal_type.cal_data.cal_size = 16
ACDB -> send_afe_topology
ACDB -> ACDB_CMD_GET_AFE_TOPOLOGY_ID
ACDB -> GET_AFE_TOPOLOGY_ID for adcd_id 4, Topology Id 112fb
ACDB -> send_afe_cal
ACDB -> ACDB_CMD_GET_AFE_COMMON_TABLE
Failed to fetch the lookup information of the device 00000004
Error: ACDB AFE returned = -19
ACDB -> AUDIO_SET_AFE_CAL
ACDB -> send_hw_delay : acdb_id = 4 path = 1
ACDB -> ACDB_AVSYNC_INFO: ACDB_CMD_GET_DEVICE_PROPERTY
consume_wakewordDetect:: start read data from RingBuffer ...
End play med_ui_voice_stop_listening.wav --> 2018-01-02 18:28:44:169
get the ping content: PING avs-alexa-na.amazon.com (54.239.21.157): 56 data bytes
get the ping content:
get the ping content: --- avs-alexa-na.amazon.com ping statistics ---
get the ping content: 5 packets transmitted, 5 packets received, 0% packet loss
ping lost rate = 0%
WisCommon::isConnectedToInternet network is available.
get the ping content: round-trip min/avg/max = 264.746/271.082/277.404 ms
2018-01-02 10:28:44.473 [ e] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 0abcf4fffe907c26-00002033-000923c5-8dd5ba2d404bf894-069370d2-5
start to delay......
2018-01-02 10:28:44.739 [ 12] I DirectiveSequencer:onDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"Speaker"\,"name"\:"SetMute"\,"messageId"\:"ae4d29cc-5000-4ebc-8f19-1bb11c52e5d9"\,"dialogRequestId"\:"96b9e45d-3f64-499d-85c3-7b50771ede45"}\,"payload"\:{"mute"\:false}}}
...onDirectiveArrived...
namespace value: Speaker --> 2018-01-02 18:28:44:740
name value: SetMute --> 2018-01-02 18:28:44:740
payload value: {"mute":false} --> 2018-01-02 18:28:44:740
messageId value: ae4d29cc-5000-4ebc-8f19-1bb11c52e5d9 --> 2018-01-02 18:28:44:740
Start set product mute or unmute: {"mute":false} --> 2018-01-02 18:28:44:740
volume index: 7, 14
Mute value: 0 --> 2018-01-02 18:28:44:741
2018-01-02 10:28:44.763 [ e] 0 AudioInputProcessor:onSendCompleted:status=SUCCESS
2018-01-02 10:28:44.763 [ e] 0 HTTP2Transport:cleanupFinishedStream:streamId=9,result=200
^^^^^^ ______ ^^^^^^ HTTP2Transport::releaseStream id = 9
2018-01-02 10:28:44.764 [ e] 0 HTTP2StreamPool:releaseStream:streamId=9,numAcquiredStreams=1
4
4
current mediaPlayer voluem value: 4 --> 2018-01-02 18:28:44:866
Send volume event: {"event": {"header": {"namespace": "Speaker","name": "MuteChanged","messageId": "c3cc25d9-8bca-409f-ac36-bd903fdc30ad"},"payload": {"volume": 40,"muted": 0}}} --> 2018-01-02 18:28:44:866
AVSConnectionManager::sendMessage {"event": {"header": {"namespace": "Speaker","name": "MuteChanged","messageId": "c3cc25d9-8bca-409f-ac36-bd903fdc30ad"},"payload": {"volume": 40,"muted": 0}}}
2018-01-02 10:28:44.866 [ 12] 9 HTTP2Transport:enqueueRequest
4
current mediaPlayer voluem value: 4 --> 2018-01-02 18:28:44:935
WisCommon::sendUDPDataToSender senderIPAddress is null, so ignore this send.
2018-01-02 10:28:44.935 [ 13] W DirectiveRouter:handleDirectiveWithPolicyHandleImmediatelyFailed:messageId=ae4d29cc-5000-4ebc-8f19-1bb11c52e5d9,reason=noHandlerRegistered
2018-01-02 10:28:44.935 [ 13] I DirectiveRouter:preHandleDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"Speaker"\,"name"\:"SetMute"\,"messageId"\:"ae4d29cc-5000-4ebc-8f19-1bb11c52e5d9"\,"dialogRequestId"\:"96b9e45d-3f64-499d-85c3-7b50771ede45"}\,"payload"\:{"mute"\:false}}}
2018-01-02 10:28:44.935 [ 13] W DirectiveRouter:preHandleDirectiveFailed:messageId=ae4d29cc-5000-4ebc-8f19-1bb11c52e5d9,reason=noHandlerRegistered
2018-01-02 10:28:44.936 [ 13] I DirectiveSequencer:sendingExceptionEncountered:messageId=ae4d29cc-5000-4ebc-8f19-1bb11c52e5d9
2018-01-02 10:28:44.936 [ 12] I DirectiveSequencer:onDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"Speak"\,"messageId"\:"b43df3b1-5a1d-46db-8093-67d60080515c"\,"dialogRequestId"\:"96b9e45d-3f64-499d-85c3-7b50771ede45"}\,"payload"\:{"url"\:"cid\:DeviceTTSRendererV4_e77d6879-1165-4ad9-9a5a-e18df2b4c34e_470482696"\,"format"\:"AUDIO_MPEG"\,"token"\:"amzn1.as-ct.v1.Domain\:Application\:Music#ACRI#DeviceTTSRendererV4_e77d6879-1165-4ad9-9a5a-e18df2b4c34e"}}}
...onDirectiveArrived...
2018-01-02 10:28:44.936 [ 12] I DirectiveSequencer:onDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"Speaker"\,"name"\:"SetMute"\,"messageId"\:"8a6add07-926e-459d-9557-b95910853afd"\,"dialogRequestId"\:"96b9e45d-3f64-499d-85c3-7b50771ede45"}\,"payload"\:{"mute"\:false}}}
...onDirectiveArrived...
namespace value: Speaker --> 2018-01-02 18:28:44:936
name value: SetMute --> 2018-01-02 18:28:44:936
payload value: {"mute":false} --> 2018-01-02 18:28:44:936
messageId value: 8a6add07-926e-459d-9557-b95910853afd --> 2018-01-02 18:28:44:936
Start set product mute or unmute: {"mute":false} --> 2018-01-02 18:28:44:936
volume index: 7, 14
Mute value: 0 --> 2018-01-02 18:28:44:936
2018-01-02 10:28:44.966 [ e] 0 HTTP2Transport:processNextOutgoingMessage
2018-01-02 10:28:44.966 [ e] 0 HTTP2StreamPool:getStream:streamId=11,numAcquiredStreams=2
2018-01-02 10:28:44.967 [ e] I HTTP2Transport:insertActiveStream:handle=0xa65f0330
^^^^^^ ______ ^^^^^^ HTTP2Transport::processNextOutgoingMessage insertActiveStream id= 11
4
4
current mediaPlayer voluem value: 4 --> 2018-01-02 18:28:45:64
Send volume event: {"event": {"header": {"namespace": "Speaker","name": "MuteChanged","messageId": "761f2b03-693e-4551-829e-4ec8cf4a2545"},"payload": {"volume": 40,"muted": 0}}} --> 2018-01-02 18:28:45:64
AVSConnectionManager::sendMessage {"event": {"header": {"namespace": "Speaker","name": "MuteChanged","messageId": "761f2b03-693e-4551-829e-4ec8cf4a2545"},"payload": {"volume": 40,"muted": 0}}}
2018-01-02 10:28:45.064 [ 12] 9 HTTP2Transport:enqueueRequest
4
current mediaPlayer voluem value: 4 --> 2018-01-02 18:28:45:136
WisCommon::sendUDPDataToSender senderIPAddress is null, so ignore this send.
2018-01-02 10:28:45.136 [ 13] I DirectiveRouter:preHandleDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"SpeechSynthesizer"\,"name"\:"Speak"\,"messageId"\:"b43df3b1-5a1d-46db-8093-67d60080515c"\,"dialogRequestId"\:"96b9e45d-3f64-499d-85c3-7b50771ede45"}\,"payload"\:{"url"\:"cid\:DeviceTTSRendererV4_e77d6879-1165-4ad9-9a5a-e18df2b4c34e_470482696"\,"format"\:"AUDIO_MPEG"\,"token"\:"amzn1.as-ct.v1.Domain\:Application\:Music#ACRI#DeviceTTSRendererV4_e77d6879-1165-4ad9-9a5a-e18df2b4c34e"}}}
2018-01-02 10:28:45.136 [ 13] I DirectiveRouter:preHandleDirective:messageId=b43df3b1-5a1d-46db-8093-67d60080515c,action=calling
2018-01-02 10:28:45.136 [ 13] 0 CapabilityAgent:addingMessageIdToMap:messageId=b43df3b1-5a1d-46db-8093-67d60080515c
2018-01-02 10:28:45.137 [ 13] 9 SpeechSynthesizer:preHandleDirective:messageId=b43df3b1-5a1d-46db-8093-67d60080515c
2018-01-02 10:28:45.137 [ 12] I DirectiveSequencer:onDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"Play"\,"messageId"\:"011cf21e-4b7a-4f67-bce2-eeac45a92940"\,"dialogRequestId"\:"96b9e45d-3f64-499d-85c3-7b50771ede45"}\,"payload"\:{"audioItem"\:{"audioItemId"\:"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73\:1"\,"stream"\:{"offsetInMilliseconds"\:0\,"expiryTime"\:"2018-01-02T10\:29\:44+0000"\,"progressReport"\:{"progressReportDelayInMilliseconds"\:1000}\,"url"\:"https\://d29r7idq0wxsiz.cloudfront.net/DigitalMusicDeliveryService/HPS.m3u8?m\=m&dmid\=230035313&c\=cf&f\=ts&t\=10&bl\=256k&s\=true&e1\=1514889900000&e2\=1514889900000&v\=V2&h\=1a22ece80d19fc5d0a16f5d9c6ff7a6869b8705ba88fe4c0eca1f94a3462cb3e"\,"token"\:"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73\:1"}}\,"playBehavior"\:"REPLACE_ALL"}}}
...onDirectiveArrived...
2018-01-02 10:28:45.137 [ 13] W DirectiveRouter:handleDirectiveWithPolicyHandleImmediatelyFailed:messageId=8a6add07-926e-459d-9557-b95910853afd,reason=noHandlerRegistered
2018-01-02 10:28:45.137 [ 8] 0 SpeechSynthesizer:executePreHandle:messageId=b43df3b1-5a1d-46db-8093-67d60080515c
2018-01-02 10:28:45.137 [ 14] I DirectiveRouter:handleDirective:messageId=b43df3b1-5a1d-46db-8093-67d60080515c,action=calling
2018-01-02 10:28:45.137 [ 13] I DirectiveRouter:preHandleDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"Speaker"\,"name"\:"SetMute"\,"messageId"\:"8a6add07-926e-459d-9557-b95910853afd"\,"dialogRequestId"\:"96b9e45d-3f64-499d-85c3-7b50771ede45"}\,"payload"\:{"mute"\:false}}}
2018-01-02 10:28:45.138 [ 14] 9 SpeechSynthesizer:handleDirective:messageId=b43df3b1-5a1d-46db-8093-67d60080515c
2018-01-02 10:28:45.138 [ 13] W DirectiveRouter:preHandleDirectiveFailed:messageId=8a6add07-926e-459d-9557-b95910853afd,reason=noHandlerRegistered
2018-01-02 10:28:45.138 [ 13] I DirectiveSequencer:sendingExceptionEncountered:messageId=8a6add07-926e-459d-9557-b95910853afd
2018-01-02 10:28:45.138 [ 12] I DirectiveSequencer:onDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"TemplateRuntime"\,"name"\:"RenderPlayerInfo"\,"messageId"\:"5bc753ff-8b8f-498a-b8ad-624a8fb9b20b"\,"dialogRequestId"\:"96b9e45d-3f64-499d-85c3-7b50771ede45"}\,"payload"\:{"content"\:{"provider"\:{"name"\:"Amazon Music"\,"logo"\:{"sources"\:[{"url"\:"https\://s3.amazonaws.com/music-provider-logos/AmazonMusic.svg"}]}}\,"art"\:{"sources"\:[{"size"\:"large"\,"url"\:"https\://m.media-amazon.com/images/I/41SZUyrViuL._UL256_.jpg"}\,{"size"\:"x-large"\,"url"\:"https\://m.media-amazon.com/images/I/61v-D2RtAZL._UL600_.jpg"}]}\,"titleSubtext2"\:"Lost N Found"\,"mediaLengthInMilliseconds"\:219000\,"title"\:"Love U U"\,"titleSubtext1"\:"JJ Lin"}\,"audioItemId"\:"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73\:1"\,"controls"\:[{"selected"\:false\,"name"\:"PLAY_PAUSE"\,"enabled"\:true\,"type"\:"BUTTON"}\,{"selected"\:false\,"name"\:"PREVIOUS"\,"enabled"\:true\,"type"\:"BUTTON"}\,{"selected"\:false\,"name"\:"NEXT"\,"enabled"\:true\,"type"\:"BUTTON"}]}}}
...onDirectiveArrived...
***************************current Template Runtime directive name: RenderPlayerInfo
***************************current Template Runtime directive payload content: {"content":{"provider":{"name":"Amazon Music","logo":{"sources":[{"url":"https://s3.amazonaws.com/music-provider-logos/AmazonMusic.svg"}]}},"art":{"sources":[{"size":"large","url":"https://m.media-amazon.com/images/I/41SZUyrViuL._UL256_.jpg"},{"size":"x-large","url":"https://m.media-amazon.com/images/I/61v-D2RtAZL._UL600_.jpg"}]},"titleSubtext2":"Lost N Found","mediaLengthInMilliseconds":219000,"title":"Love U U","titleSubtext1":"JJ Lin"},"audioItemId":"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73:1","controls":[{"selected":false,"name":"PLAY_PAUSE","enabled":true,"type":"BUTTON"},{"selected":false,"name":"PREVIOUS","enabled":true,"type":"BUTTON"},{"selected":false,"name":"NEXT","enabled":true,"type":"BUTTON"}]}
providerDocument parse success!
artDocument parse success!
sources is array
size= large, url= https://m.media-amazon.com/images/I/41SZUyrViuL._UL256_.jpg
size= x-large, url= https://m.media-amazon.com/images/I/61v-D2RtAZL._UL600_.jpg
rapidjson parse success!
get User information content= {"provider":{"name":"Amazon Music","logo":{"sources":[{"url":"https://s3.amazonaws.com/music-provider-logos/AmazonMusic.svg"}]}},"art":{"sources":[{"size":"large","url":"https://m.media-amazon.com/images/I/41SZUyrViuL._UL256_.jpg"},{"size":"x-large","url":"https://m.media-amazon.com/images/I/61v-D2RtAZL._UL600_.jpg"}]},"titleSubtext2":"Lost N Found","mediaLengthInMilliseconds":219000,"title":"Love U U","titleSubtext1":"JJ Lin"}
get User information header=
get User information provider name= Amazon Music
get User information url= https://m.media-amazon.com/images/I/61v-D2RtAZL._UL600_.jpg
get User information titleSubtext2= Lost N Found
get User information mediaLengthInMilliseconds= 219000
get User information title= Love U U
get User information titleSubtext1= JJ Lin
send companion app content= play_music:header=
title=Love U U
titleSubtext1=JJ Lin
titleSubtext2=Lost N Found
url=https://m.media-amazon.com/images/I/61v-D2RtAZL._UL600_.jpg
medialengthinmilliseconds=219000
WisCommon::sendUDPDataToSender senderIPAddress is null, so ignore this send.
detectAndStartSendOffsetThread startSendCurrentOffSetThreadError = 0
startSendCurrentOffSet thread: detachCode = 0
audioplayer get current offset:: 0
send current offset content: play_music:offset=0
WisCommon::sendUDPDataToSender senderIPAddress is null, so ignore this send.
2018-01-02 10:28:45.141 [ 13] I DirectiveRouter:preHandleDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"AudioPlayer"\,"name"\:"Play"\,"messageId"\:"011cf21e-4b7a-4f67-bce2-eeac45a92940"\,"dialogRequestId"\:"96b9e45d-3f64-499d-85c3-7b50771ede45"}\,"payload"\:{"audioItem"\:{"audioItemId"\:"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73\:1"\,"stream"\:{"offsetInMilliseconds"\:0\,"expiryTime"\:"2018-01-02T10\:29\:44+0000"\,"progressReport"\:{"progressReportDelayInMilliseconds"\:1000}\,"url"\:"https\://d29r7idq0wxsiz.cloudfront.net/DigitalMusicDeliveryService/HPS.m3u8?m\=m&dmid\=230035313&c\=cf&f\=ts&t\=10&bl\=256k&s\=true&e1\=1514889900000&e2\=1514889900000&v\=V2&h\=1a22ece80d19fc5d0a16f5d9c6ff7a6869b8705ba88fe4c0eca1f94a3462cb3e"\,"token"\:"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73\:1"}}\,"playBehavior"\:"REPLACE_ALL"}}}
2018-01-02 10:28:45.141 [ 13] I DirectiveRouter:preHandleDirective:messageId=011cf21e-4b7a-4f67-bce2-eeac45a92940,action=calling
2018-01-02 10:28:45.142 [ 8] 0 SpeechSynthesizer:executeHandle:messageId=b43df3b1-5a1d-46db-8093-67d60080515c
2018-01-02 10:28:45.142 [ 13] 0 CapabilityAgent:addingMessageIdToMap:messageId=011cf21e-4b7a-4f67-bce2-eeac45a92940
2018-01-02 10:28:45.142 [ 8] 1 FocusManager:acquireChannel:channelName=Dialog,activityId=SpeechSynthesizer.Speak
2018-01-02 10:28:45.142 [ 13] W DirectiveRouter:handleDirectiveWithPolicyHandleImmediatelyFailed:messageId=5bc753ff-8b8f-498a-b8ad-624a8fb9b20b,reason=noHandlerRegistered
2018-01-02 10:28:45.143 [ 13] I DirectiveRouter:preHandleDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"TemplateRuntime"\,"name"\:"RenderPlayerInfo"\,"messageId"\:"5bc753ff-8b8f-498a-b8ad-624a8fb9b20b"\,"dialogRequestId"\:"96b9e45d-3f64-499d-85c3-7b50771ede45"}\,"payload"\:{"content"\:{"provider"\:{"name"\:"Amazon Music"\,"logo"\:{"sources"\:[{"url"\:"https\://s3.amazonaws.com/music-provider-logos/AmazonMusic.svg"}]}}\,"art"\:{"sources"\:[{"size"\:"large"\,"url"\:"https\://m.media-amazon.com/images/I/41SZUyrViuL._UL256_.jpg"}\,{"size"\:"x-large"\,"url"\:"https\://m.media-amazon.com/images/I/61v-D2RtAZL._UL600_.jpg"}]}\,"titleSubtext2"\:"Lost N Found"\,"mediaLengthInMilliseconds"\:219000\,"title"\:"Love U U"\,"titleSubtext1"\:"JJ Lin"}\,"audioItemId"\:"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73\:1"\,"controls"\:[{"selected"\:false\,"name"\:"PLAY_PAUSE"\,"enabled"\:true\,"type"\:"BUTTON"}\,{"selected"\:false\,"name"\:"PREVIOUS"\,"enabled"\:true\,"type"\:"BUTTON"}\,{"selected"\:false\,"name"\:"NEXT"\,"enabled"\:true\,"type"\:"BUTTON"}]}}}
2018-01-02 10:28:45.143 [ 11] 9 AudioInputProcessor:onFocusChanged:newFocus=NONE
2018-01-02 10:28:45.143 [ 13] W DirectiveRouter:preHandleDirectiveFailed:messageId=5bc753ff-8b8f-498a-b8ad-624a8fb9b20b,reason=noHandlerRegistered
2018-01-02 10:28:45.143 [ 13] I DirectiveSequencer:sendingExceptionEncountered:messageId=5bc753ff-8b8f-498a-b8ad-624a8fb9b20b
AudioInputProcessor::executeOnFocusChanged***********************
2018-01-02 10:28:45.143 [ 11] 0 SpeechSynthesizer:onFocusChanged:newFocus=FOREGROUND
2018-01-02 10:28:45.144 [ a] 0 AudioInputProcessor:executeOnFocusChanged:newFocus=NONE
2018-01-02 10:28:45.144 [ a] 0 AudioInputProcessor:executeOnFocusChanged:reason=Lost focus
executeResetState m_expectingSpeechTimer.stop...
2018-01-02 10:28:45.144 [ 11] 9 SpeechSynthesizer:setCurrentStateLocked:state=GAINING_FOCUS
2018-01-02 10:28:45.144 [ a] 0 AudioInputProcessor:setState:from=BUSY,to=IDLE
DialogUXStateAggregator::onStateChanged state = 0
TestSpeechSynthesizerObserver::onStateChanged currentSpeechSynthesizerState: 1, newState = 2
2018-01-02 10:28:45.146 [ 8] 0 SpeechSynthesizer:executeStateChange:newState=PLAYING
2018-01-02 10:28:45.146 [ 8] 9 SpeechSynthesizer:startPlaying
2018-01-02 10:28:45.146 [ 8] 9 MediaPlayer:setSourceCalled:sourceType=AttachmentReader
2018-01-02 10:28:45.147 [ 2] 0 MediaPlayer:handleSetSourceCalled
2018-01-02 10:28:45.147 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements
2018-01-02 10:28:45.147 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements 1
2018-01-02 10:28:45.147 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements 4
2018-01-02 10:28:45.149 [ 2] 9 BaseStreamSource:Creating decodebin for URL
2018-01-02 10:28:45.151 [ 2] 9 MediaPlayer:Jeter setAppSrc:m_appsrcCount=1
>>>>>>>>>>>MediaPlayer::play 1
>>>>>>>>>>>MediaPlayer::play 2
2018-01-02 10:28:45.151 [ 8] 9 MediaPlayer:playCalled
2018-01-02 10:28:45.151 [ 2] 0 MediaPlayer:handlePlayCalled:idPassed=2,currentId=2
2018-01-02 10:28:45.153 [ 2] 9 AttachmentReaderSource:handleSeekData:offset=0
2018-01-02 10:28:45.153 [ 2] 0 MediaPlayer:handlePlay:startingState=PLAYING,stateReturn=ASYNC
2018-01-02 10:28:45.154 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.154 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:45.154 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.154 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.155 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.155 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.155 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.155 [ 2] 9 MediaPlayer:State Change:oldState=NULL,newState=READY,pendingState=PLAYING
2018-01-02 10:28:45.155 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.155 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.156 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-status
2018-01-02 10:28:45.156 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.156 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-status
2018-01-02 10:28:45.156 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:45.156 [ 2] 9 BaseStreamSource:installOnReadDataHandler:action=newSourceId,sourceId=17
2018-01-02 10:28:45.157 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:45.157 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:45.157 [ 2] 9 AttachmentReaderSource:read:size=2941,status=0
2018-01-02 10:28:45.157 [ 2] I InProcessAttachmentReader:readFailed:reason=SDS is closed
2018-01-02 10:28:45.157 [ 2] 9 AttachmentReaderSource:read:size=0,status=3
2018-01-02 10:28:45.158 [ 2] 9 AttachmentReaderSource:handleReadData:info=signalingEndOfData
2018-01-02 10:28:45.158 [ 2] 9 BaseStreamSource:signalEndOfDataCalled
2018-01-02 10:28:45.158 [ 2] 9 BaseStreamSource:gstAppSrcEndOfStreamSuccess
2018-01-02 10:28:45.160 [ 2] 9 BaseStreamSource:clearOnReadDataHandlerCalled:sourceId=17
2018-01-02 10:28:45.178 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.179 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.182 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.182 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.185 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.186 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.187 [ 15] 9 MediaPlayer:onPadAddedCalled
2018-01-02 10:28:45.187 [ 2] 9 MediaPlayer:handlePadAddedSignalCalled
2018-01-02 10:28:45.189 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.189 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-start
2018-01-02 10:28:45.194 [ 2] 9 MediaPlayer:messageReceived:messageType=latency
2018-01-02 10:28:45.195 [ 2] 9 MediaPlayer:messageReceived:messageType=tag
2018-01-02 10:28:45.195 [ 2] 0 MediaPlayer:callingOnTags
2018-01-02 10:28:45.195 [ 2] 9 MediaPlayer:messageReceived:messageType=tag
2018-01-02 10:28:45.195 [ 2] 0 MediaPlayer:callingOnTags
2018-01-02 10:28:45.196 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.196 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.196 [ 2] 9 MediaPlayer:State Change:oldState=READY,newState=PAUSED,pendingState=PLAYING
2018-01-02 10:28:45.196 [ 2] 9 MediaPlayer:messageReceived:messageType=async-done
2018-01-02 10:28:45.196 [ 2] 9 MediaPlayer:messageReceived:messageType=new-clock
2018-01-02 10:28:45.197 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.203 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.205 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.205 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.205 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.205 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.205 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.205 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.206 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:45.206 [ 2] 9 MediaPlayer:State Change:oldState=PAUSED,newState=PLAYING,pendingState=VOID_PENDING
2018-01-02 10:28:45.206 [ 2] 0 MediaPlayer:callingOnPlaybackStarted:currentId=2
2018-01-02 10:28:45.206 [ 2] 9 SpeechSynthesizer:onPlaybackStarted:callbackSourceId=2
2018-01-02 10:28:45.206 [ 8] 0 SpeechSynthesizer:executePlaybackStarted
2018-01-02 10:28:45.207 [ 8] 9 SpeechSynthesizer:setCurrentStateLocked:state=PLAYING
2018-01-02 10:28:45.207 [ 8] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0
2018-01-02 10:28:45.207 [ 8] 9 MediaPlayer:getOffsetCalled
2018-01-02 10:28:45.208 [ 2] 0 MediaPlayer:handleGetOffsetCalled:idPassed=2,currentId=2
2018-01-02 10:28:45.209 [ 8] 0 ContextManager:updateStateLocked:action=updatedState,state={"token"\:"amzn1.as-ct.v1.Domain\:Application\:Music#ACRI#DeviceTTSRendererV4_e77d6879-1165-4ad9-9a5a-e18df2b4c34e"\,"offsetInMilliseconds"\:12\,"playerActivity"\:"PLAYING"},namespace=SpeechSynthesizer,name=SpeechState
TestSpeechSynthesizerObserver::onStateChanged currentSpeechSynthesizerState: 2, newState = 0
2018-01-02 10:28:45.210 [ 11] 9 SpeechSynthesizer:onFocusChangedSuccess
2018-01-02 10:28:45.210 [ 8] 0 EventBuilder:buildJsonEventString:messageId=c4fae9d8-e94d-4a40-a835-fd9d586abf69,namespace=SpeechSynthesizer,name=SpeechStarted
AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"SpeechSynthesizer","name":"SpeechStarted","messageId":"c4fae9d8-e94d-4a40-a835-fd9d586abf69"},"payload":{"token":"amzn1.as-ct.v1.Domain:Application:Music#ACRI#DeviceTTSRendererV4_e77d6879-1165-4ad9-9a5a-e18df2b4c34e"}}}
2018-01-02 10:28:45.211 [ 10] 0 DialogUXStateAggregator:setState:from=THINKING,to=SPEAKING
2018-01-02 10:28:45.211 [ 8] 9 HTTP2Transport:enqueueRequest
*** WisStatusChangedInterface::onDialogUXStateChanged:: *** oldState = 3, newState = 3
start:: turn on LED， ledType = 5
Start to open < SPEAKING > LED --> 2018-01-02 18:28:45:211
AudioInputProcessor::executeOnDialogUXStateChanged newState1 = 3
ACDB -> send_audio_cal, acdb_id = 14, path = 0, app id = 0x11130, sample rate = 48000
ACDB -> send_asm_topology
ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TOPOLOGY_ID
ACDB -> send_adm_topology
ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TOPOLOGY_ID
ACDB -> send_audtable
ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE_SIZE
ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE
ACDB -> AUDIO_SET_AUDPROC_CAL
ACDB -> send_audvoltable
ACDB -> ACDB_CMD_GET_AUDPROC_GAIN_DEP_STEP_TABLE, vol index 0
ACDB -> AUDIO_SET_VOL_CAL cal type = 12
ACDB -> send_audstrmtable
ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TABLE_V2
ACDB -> audstrm_cal->cal_type.cal_data.cal_size = 456
ACDB -> send_afe_topology
ACDB -> ACDB_CMD_GET_AFE_TOPOLOGY_ID
ACDB -> GET_AFE_TOPOLOGY_ID for adcd_id 14, Topology Id 112fa
ACDB -> send_afe_cal
ACDB -> ACDB_CMD_GET_AFE_COMMON_TABLE
ACDB -> AUDIO_SET_AFE_CAL
ACDB -> send_hw_delay : acdb_id = 14 path = 0
ACDB -> ACDB_AVSYNC_INFO: ACDB_CMD_GET_DEVICE_PROPERTY
end:: turn on LED
2018-01-02 10:28:45.366 [ e] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 0abcf4fffe907c26-00002033-000923c5-8dd5ba2d404bf894-069370d2-7
2018-01-02 10:28:45.366 [ e] 0 ObservableMessageRequest:onSendCompleted:status=SUCCESS_NO_CONTENT
2018-01-02 10:28:45.367 [ e] 0 HTTP2Transport:cleanupFinishedStream:streamId=11,result=204
^^^^^^ ______ ^^^^^^ HTTP2Transport::releaseStream id = 11
2018-01-02 10:28:45.367 [ e] 0 HTTP2StreamPool:releaseStream:streamId=11,numAcquiredStreams=1
2018-01-02 10:28:45.367 [ e] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
2018-01-02 10:28:45.367 [ e] 0 HTTP2Transport:processNextOutgoingMessage
2018-01-02 10:28:45.367 [ e] 0 HTTP2StreamPool:getStream:streamId=13,numAcquiredStreams=2
2018-01-02 10:28:45.368 [ e] I HTTP2Transport:insertActiveStream:handle=0xa65f0330
^^^^^^ ______ ^^^^^^ HTTP2Transport::processNextOutgoingMessage insertActiveStream id= 13
2018-01-02 10:28:45.465 [ 2] 9 MediaPlayer:messageReceived:messageType=tag
2018-01-02 10:28:45.465 [ 2] 0 MediaPlayer:callingOnTags
2018-01-02 10:28:45.772 [ e] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 0abcf4fffe907c26-00002033-000923c5-8dd5ba2d404bf894-069370d2-9
2018-01-02 10:28:45.775 [ e] 0 ObservableMessageRequest:onSendCompleted:status=SUCCESS_NO_CONTENT
2018-01-02 10:28:45.776 [ e] 0 HTTP2Transport:cleanupFinishedStream:streamId=13,result=204
^^^^^^ ______ ^^^^^^ HTTP2Transport::releaseStream id = 13
2018-01-02 10:28:45.779 [ e] 0 HTTP2StreamPool:releaseStream:streamId=13,numAcquiredStreams=1
2018-01-02 10:28:45.780 [ e] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
2018-01-02 10:28:45.781 [ e] 0 HTTP2Transport:processNextOutgoingMessage
2018-01-02 10:28:45.782 [ e] 0 HTTP2StreamPool:getStream:streamId=15,numAcquiredStreams=2
2018-01-02 10:28:45.785 [ e] I HTTP2Transport:insertActiveStream:handle=0xa65f0330
^^^^^^ ______ ^^^^^^ HTTP2Transport::processNextOutgoingMessage insertActiveStream id= 15
2018-01-02 10:28:46.140 [ e] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 0abcf4fffe907c26-00002033-000923c5-8dd5ba2d404bf894-069370d2-11
2018-01-02 10:28:46.140 [ e] 0 HTTP2Transport:cleanupFinishedStream:streamId=15,result=204
^^^^^^ ______ ^^^^^^ HTTP2Transport::releaseStream id = 15
2018-01-02 10:28:46.141 [ e] 0 HTTP2StreamPool:releaseStream:streamId=15,numAcquiredStreams=1
audioplayer get current offset:: 0
send current offset content: play_music:offset=0
WisCommon::sendUDPDataToSender senderIPAddress is null, so ignore this send.
2018-01-02 10:28:46.142 [ e] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
2018-01-02 10:28:47.104 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:47.104 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:47.105 [ 2] 9 MediaPlayer:messageReceived:messageType=eos
audioplayer get current offset:: 0
send current offset content: play_music:offset=0
WisCommon::sendUDPDataToSender senderIPAddress is null, so ignore this send.
2018-01-02 10:28:47.406 [ 2] 9 BaseStreamSource:~BaseStreamSource
2018-01-02 10:28:47.406 [ 2] 0 MediaPlayer:callingOnPlaybackFinished:currentId=2
2018-01-02 10:28:47.406 [ 2] 9 SpeechSynthesizer:onPlaybackFinished:callbackSourceId=2
2018-01-02 10:28:47.406 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements
2018-01-02 10:28:47.407 [ 8] 0 SpeechSynthesizer:executePlaybackFinished
gst_element_query_position = 1848000000
startStreamingPoint = 0
2018-01-02 10:28:47.407 [ 8] 9 SpeechSynthesizer:setCurrentStateLocked:state=FINISHED
2018-01-02 10:28:47.407 [ 2] 0 MediaPlayer:saveOffsetBeforeTeardown:offset=1848
m_offsetBeforeTeardown = 1848
2018-01-02 10:28:47.407 [ 8] 0 SpeechSynthesizer:executeProvideState:stateRequestToken=0
2018-01-02 10:28:47.407 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements 1
2018-01-02 10:28:47.407 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements 2
2018-01-02 10:28:47.407 [ 8] 0 ContextManager:updateStateLocked:action=updatedState,state={"token"\:"amzn1.as-ct.v1.Domain\:Application\:Music#ACRI#DeviceTTSRendererV4_e77d6879-1165-4ad9-9a5a-e18df2b4c34e"\,"offsetInMilliseconds"\:0\,"playerActivity"\:"FINISHED"},namespace=SpeechSynthesizer,name=SpeechState
TestSpeechSynthesizerObserver::onStateChanged currentSpeechSynthesizerState: 0, newState = 1
*** onSpeechSynthesizerObserverStateChanged:: isRecordingFinish = 0
openFMDuringTest : isFMOpened = 0 , mLastFMOpened = 0
2018-01-02 10:28:47.408 [ 8] 0 EventBuilder:buildJsonEventString:messageId=37e1ee59-0411-4482-a5a2-b5287f83ddd9,namespace=SpeechSynthesizer,name=SpeechFinished
AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"SpeechSynthesizer","name":"SpeechFinished","messageId":"37e1ee59-0411-4482-a5a2-b5287f83ddd9"},"payload":{"token":"amzn1.as-ct.v1.Domain:Application:Music#ACRI#DeviceTTSRendererV4_e77d6879-1165-4ad9-9a5a-e18df2b4c34e"}}}
2018-01-02 10:28:47.409 [ 8] 9 HTTP2Transport:enqueueRequest
2018-01-02 10:28:47.409 [ 8] 9 SpeechSynthesizer:setHandlingCompleted
2018-01-02 10:28:47.409 [ 8] 0 DirectiveProcessor:onHandlingCompeted:messageId=b43df3b1-5a1d-46db-8093-67d60080515c,directiveBeingPreHandled=(nullptr)
2018-01-02 10:28:47.410 [ 14] I DirectiveRouter:handleDirective:messageId=011cf21e-4b7a-4f67-bce2-eeac45a92940,action=calling
2018-01-02 10:28:47.410 [ 8] 0 CapabilityAgent:removingMessageIdFromMap:messageId=b43df3b1-5a1d-46db-8093-67d60080515c
2018-01-02 10:28:47.410 [ 14] 0 AudioPlayer:handleDirective:name=Play,messageId=011cf21e-4b7a-4f67-bce2-eeac45a92940
AudioPlayer::handleDirective getName = Play
2018-01-02 10:28:47.410 [ 14] 1 AudioPlayer:handlePlayDirective
2018-01-02 10:28:47.411 [ 14] 9 AudioPlayer:PLAY:payload={"audioItem"\:{"audioItemId"\:"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73\:1"\,"stream"\:{"offsetInMilliseconds"\:0\,"expiryTime"\:"2018-01-02T10\:29\:44+0000"\,"progressReport"\:{"progressReportDelayInMilliseconds"\:1000}\,"url"\:"https\://d29r7idq0wxsiz.cloudfront.net/DigitalMusicDeliveryService/HPS.m3u8?m\=m&dmid\=230035313&c\=cf&f\=ts&t\=10&bl\=256k&s\=true&e1\=1514889900000&e2\=1514889900000&v\=V2&h\=1a22ece80d19fc5d0a16f5d9c6ff7a6869b8705ba88fe4c0eca1f94a3462cb3e"\,"token"\:"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73\:1"}}\,"playBehavior"\:"REPLACE_ALL"}
2018-01-02 10:28:47.411 [ 14] E JsonUtils:findNodeFailed:reason=missingDirectChild,child=streamFormat
2018-01-02 10:28:47.412 [ 14] E JsonUtils:findNodeFailed:reason=missingDirectChild,child=progressReportIntervalInMilliseconds
2018-01-02 10:28:47.413 [ 14] E JsonUtils:findNodeFailed:reason=missingDirectChild,child=expectedPreviousToken
2018-01-02 10:28:47.413 [ 14] 0 DirectiveProcessor:onHandlingCompeted:messageId=011cf21e-4b7a-4f67-bce2-eeac45a92940,directiveBeingPreHandled=(nullptr)
2018-01-02 10:28:47.413 [ 14] 0 CapabilityAgent:removingMessageIdFromMap:messageId=011cf21e-4b7a-4f67-bce2-eeac45a92940
*** WisStatusChangedInterface::onHandleAudioPlayerPlayDirective:: ***
2018-01-02 10:28:47.414 [ 9] 1 AudioPlayer:executePlay:playBehavior=REPLACE_ALL
2018-01-02 10:28:47.414 [ 9] 1 AudioPlayer:executeStop:playNextItem=true,m_currentActivity=IDLE
2018-01-02 10:28:47.415 [ 9] 1 FocusManager:acquireChannel:channelName=Content,activityId=AudioPlayer.Play
2018-01-02 10:28:47.415 [ 11] 0 AudioPlayer:onFocusChanged:newFocus=BACKGROUND
2018-01-02 10:28:47.416 [ 9] 1 AudioPlayer:executeOnFocusChanged:from=NONE,to=BACKGROUND,m_currentActivity=IDLE
2018-01-02 10:28:47.416 [ 9] 1 AudioPlayer:executeOnFocusChanged:action=pauseMediaPlayer
2018-01-02 10:28:47.416 [ 9] 9 MediaPlayer:pausedCalled
2018-01-02 10:28:47.447 [ e] 0 HTTP2Transport:processNextOutgoingMessage
2018-01-02 10:28:47.447 [ e] 0 HTTP2StreamPool:getStream:streamId=17,numAcquiredStreams=2
2018-01-02 10:28:47.447 [ e] I HTTP2Transport:insertActiveStream:handle=0xa65f0330
^^^^^^ ______ ^^^^^^ HTTP2Transport::processNextOutgoingMessage insertActiveStream id= 17
2018-01-02 10:28:47.612 [ 10] 0 DialogUXStateAggregator:setState:from=SPEAKING,to=IDLE
*** WisStatusChangedInterface::onDialogUXStateChanged:: *** oldState = 0, newState = 0
start:: turn on LED， ledType = 8
Start to open < DEFAULT > LED --> 2018-01-02 18:28:47:613
2018-01-02 10:28:47.613 [ 8] 1 FocusManager:releaseChannel:channelName=Dialog
AudioInputProcessor::executeOnDialogUXStateChanged newState1 = 0
AudioInputProcessor::executeOnDialogUXStateChanged newState2 = 0
2018-01-02 10:28:47.624 [ 11] 0 SpeechSynthesizer:onFocusChanged:newFocus=NONE
2018-01-02 10:28:47.626 [ 11] 0 AudioPlayer:onFocusChanged:newFocus=FOREGROUND
2018-01-02 10:28:47.654 [ 2] 9 MediaPlayer:Jeter tearDownTransientPipelineElements:m_isLive=false
2018-01-02 10:28:47.655 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements 3
2018-01-02 10:28:47.655 [ 2] 9 MediaPlayer:Jeter tearDownTransientPipelineElements:m_appsrcCount=0
2018-01-02 10:28:47.657 [ 2] 9 MediaPlayer:resetPipeline
2018-01-02 10:28:47.658 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements 4
2018-01-02 10:28:47.658 [ 2] 0 MediaPlayer:handlePauseCalled:idPassed=0,currentId=0
2018-01-02 10:28:47.659 [ 2] E MediaPlayer:validateSourceAndIdFailed:reason=sourceNotSet
2018-01-02 10:28:47.659 [ 2] E MediaPlayer:handlePauseFailed
2018-01-02 10:28:47.660 [ 9] 1 AudioPlayer:executeOnFocusChanged:from=BACKGROUND,to=FOREGROUND,m_currentActivity=IDLE
2018-01-02 10:28:47.660 [ 9] 1 AudioPlayer:executeOnFocusChanged:action=playNextItem
2018-01-02 10:28:47.660 [ 9] 1 AudioPlayer:playNextItem:m_audioItems.size=1
2018-01-02 10:28:47.661 [ 9] 0 AudioPlayer:cancelTimers
2018-01-02 10:28:47.661 [ 9] 9 AudioPlayer:settingUrlSource:offset=0
2018-01-02 10:28:47.661 [ 9] 9 MediaPlayer:setSourceForUrlCalled
URL https://d29r7idq0wxsiz.cloudfront.net/DigitalMusicDeliveryService/HPS.m3u8?m=m&dmid=230035313&c=cf&f=ts&t=10&bl=256k&s=true&e1=1514889900000&e2=1514889900000&v=V2&h=1a22ece80d19fc5d0a16f5d9c6ff7a6869b8705ba88fe4c0eca1f94a3462cb3e
2018-01-02 10:28:47.661 [ 2] 0 MediaPlayer:handleSetSourceForUrlCalled
2018-01-02 10:28:47.662 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements
2018-01-02 10:28:47.662 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements 1
2018-01-02 10:28:47.662 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements 4
2018-01-02 10:28:47.684 [ 2] 0 MediaPlayer:handleSetSourceCalled
2018-01-02 10:28:47.685 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements
2018-01-02 10:28:47.685 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements 1
2018-01-02 10:28:47.686 [ 2] 9 MediaPlayer:tearDownTransientPipelineElements 4
2018-01-02 10:28:47.686 [ 2] 9 BaseStreamSource:Creating Playbin for URL
>>>>>>>>>>>MediaPlayer::play 1
>>>>>>>>>>>MediaPlayer::play 2
2018-01-02 10:28:47.693 [ 9] 9 MediaPlayer:playCalled
2018-01-02 10:28:47.694 [ 2] 0 MediaPlayer:handlePlayCalled:idPassed=3,currentId=3
2018-01-02 10:28:47.699 [ 2] 9 BaseStreamSource:onSourceFound
2018-01-02 10:28:47.700 [ 2] 9 BaseStreamSource:handleSourceFoundCalled
2018-01-02 10:28:47.700 [ 2] 9 BaseStreamSource:handleSourceFoundCalled 1
2018-01-02 10:28:47.700 [ 2] 9 BaseStreamSource:handleSourceFoundCalled 2
2018-01-02 10:28:47.700 [ 2] 9 BaseStreamSource:handleSourceFoundCalled 3
2018-01-02 10:28:47.700 [ 2] 9 MediaPlayer:Jeter setAppSrc:m_appsrcCount=1
2018-01-02 10:28:47.703 [ 2] 9 AttachmentReaderSource:handleSeekData:offset=0
2018-01-02 10:28:47.703 [ 2] 0 MediaPlayer:handlePlay:startingState=PLAYING,stateReturn=ASYNC
2018-01-02 10:28:47.704 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:47.704 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:47.704 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:47.704 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:47.704 [ 2] 9 MediaPlayer:State Change:oldState=NULL,newState=READY,pendingState=PLAYING
2018-01-02 10:28:47.705 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:47.705 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:47.706 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:47.706 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:47.706 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:47.706 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:47.707 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:47.707 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-status
2018-01-02 10:28:47.707 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:47.707 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-status
2018-01-02 10:28:47.708 [ 2] 9 BaseStreamSource:handleNeedDataCalled
end:: turn on LED
2018-01-02 10:28:47.708 [ 2] 9 BaseStreamSource:installOnReadDataHandler:action=newSourceId,sourceId=26
2018-01-02 10:28:47.876 [ e] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 0abcf4fffe907c26-00002033-000923c5-8dd5ba2d404bf894-069370d2-13
2018-01-02 10:28:47.877 [ e] 0 HTTP2Transport:cleanupFinishedStream:streamId=17,result=204
^^^^^^ ______ ^^^^^^ HTTP2Transport::releaseStream id = 17
2018-01-02 10:28:47.877 [ e] 0 HTTP2StreamPool:releaseStream:streamId=17,numAcquiredStreams=1
2018-01-02 10:28:47.877 [ e] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
combinationKeyDetect: wait a combination key------ --> 2018-01-02 18:28:47:951
2018-01-02 10:28:48.144 [ 16] 9 MediaPlayer:getOffsetCalled
2018-01-02 10:28:48.698 [ 9] 1 AudioPlayer:sendEventWithTokenAndOffset:eventName=ProgressReportDelayElapsed
2018-01-02 10:28:48.699 [ 9] 9 MediaPlayer:getOffsetCalled
End to delay......
mWisAvs.m_connectionStatusObserver->getConnectionStatus: status = 2, AVSConnectionManager::isEnabled = 1, AVSConnectionManager::isConnected = 1
2018-01-02 10:28:49.975 [ 17] 9 LibCurlHttpContentFetcher:getContent:responseCode=200
2018-01-02 10:28:49.977 [ 17] 9 LibCurlHttpContentFetcher:getContent:contentType=application/vnd.apple.mpegurl
2018-01-02 10:28:49.978 [ 18] 9 PlaylistParser:PlaylistParser:contentType=application/vnd.apple.mpegurl,length=-1
2018-01-02 10:28:50.934 [ 18] I InProcessAttachmentReader:readFailed:reason=SDS is closed
2018-01-02 10:28:50.950 [ 18] 9 PlaylistParser:isM3U8Playlist
2018-01-02 10:28:50.952 [ 18] 9 PlaylistParser:foundChildrenURLsInPlaylist:num=1
2018-01-02 10:28:50.953 [ 18] 9 PlaylistParser:encounteredMasterPlaylist
2018-01-02 10:28:52.145 [ 19] 9 LibCurlHttpContentFetcher:getContent:responseCode=200
2018-01-02 10:28:52.145 [ 19] 9 LibCurlHttpContentFetcher:getContent:contentType=application/vnd.apple.mpegurl
2018-01-02 10:28:52.146 [ 18] 9 PlaylistParser:PlaylistParser:contentType=application/vnd.apple.mpegurl,length=-1
2018-01-02 10:28:53.430 [ 18] I InProcessAttachmentReader:readFailed:reason=SDS is closed
2018-01-02 10:28:53.459 [ 18] 9 PlaylistParser:isM3U8Playlist
2018-01-02 10:28:53.461 [ 18] 9 PlaylistParser:foundChildrenURLsInPlaylist:num=22
get the ping content: PING avs-alexa-na.amazon.com (54.239.21.157): 56 data bytes
get the ping content:
get the ping content: --- avs-alexa-na.amazon.com ping statistics ---
get the ping content: 5 packets transmitted, 5 packets received, 0% packet loss
ping lost rate = 0%
WisCommon::isConnectedToInternet network is available.
get the ping content: round-trip min/avg/max = 256.637/260.045/266.882 ms
start to delay......
2018-01-02 10:28:54.557 [ 1a] 9 LibCurlHttpContentFetcher:getContent:responseCode=200
2018-01-02 10:28:54.557 [ 1a] 9 LibCurlHttpContentFetcher:getContent:contentType=video/MP2T
2018-01-02 10:28:54.557 [ 18] 9 PlaylistParser:PlaylistParser:contentType=video/MP2T,length=10000
2018-01-02 10:28:54.558 [ 18] 9 PlaylistParser:foundNonPlaylistURL
2018-01-02 10:28:54.558 [ 18] 9 UrlContentToAttachmentConverter:onPlaylistEntryParsed:status=STILL_ONGOING
2018-01-02 10:28:54.559 [ 1b] 9 UrlContentToAttachmentConverter:writeUrlContentIntoStream:info=beginning
2018-01-02 10:28:55.558 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.558 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.558 [ 2] 0 MediaPlayer:handleGetOffsetCalled:idPassed=3,currentId=3
2018-01-02 10:28:55.559 [ 2] I MediaPlayer:handleGetOffset:reason=getElementGetStateUnsuccessful,stateChangeReturn=ASYNC
2018-01-02 10:28:55.559 [ 2] 0 MediaPlayer:handleGetOffsetCalled:idPassed=3,currentId=3
2018-01-02 10:28:55.559 [ 2] I MediaPlayer:handleGetOffset:reason=getElementGetStateUnsuccessful,stateChangeReturn=ASYNC
2018-01-02 10:28:55.560 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.560 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
audioplayer get current offset:: 0
send current offset content: play_music:offset=0
WisCommon::sendUDPDataToSender senderIPAddress is null, so ignore this send.
2018-01-02 10:28:55.560 [ 9] 0 EventBuilder:buildJsonEventString:messageId=46e2c26f-1ea4-45e8-a348-253e07d9bf27,namespace=AudioPlayer,name=ProgressReportDelayElapsed
AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"AudioPlayer","name":"ProgressReportDelayElapsed","messageId":"46e2c26f-1ea4-45e8-a348-253e07d9bf27"},"payload":{"token":"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73:1","offsetInMilliseconds":0}}}
2018-01-02 10:28:55.561 [ 9] 9 HTTP2Transport:enqueueRequest
2018-01-02 10:28:55.589 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.589 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.589 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.589 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.599 [ e] 0 HTTP2Transport:processNextOutgoingMessage
2018-01-02 10:28:55.599 [ e] 0 HTTP2StreamPool:getStream:streamId=19,numAcquiredStreams=2
2018-01-02 10:28:55.600 [ e] I HTTP2Transport:insertActiveStream:handle=0xa65f0330
^^^^^^ ______ ^^^^^^ HTTP2Transport::processNextOutgoingMessage insertActiveStream id= 19
2018-01-02 10:28:55.606 [ 1c] 9 LibCurlHttpContentFetcher:getContent:responseCode=200
2018-01-02 10:28:55.680 [ 1c] 9 LibCurlHttpContentFetcher:getContent:contentType=video/MP2T
2018-01-02 10:28:55.681 [ 18] 9 PlaylistParser:PlaylistParser:contentType=video/MP2T,length=10000
2018-01-02 10:28:55.681 [ 18] 9 PlaylistParser:foundNonPlaylistURL
2018-01-02 10:28:55.681 [ 18] 9 UrlContentToAttachmentConverter:onPlaylistEntryParsed:status=STILL_ONGOING
2018-01-02 10:28:55.713 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:55.726 [ 2] 9 AttachmentReaderSource:read:size=3432,status=0
2018-01-02 10:28:55.727 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.727 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.727 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:55.728 [ 15] 9 BaseStreamSource:m_needDataCallbackId already set
2018-01-02 10:28:55.728 [ 2] 9 MediaPlayer:messageReceived:messageType=element
2018-01-02 10:28:55.728 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-collection
2018-01-02 10:28:55.728 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-status
2018-01-02 10:28:55.728 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.728 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.728 [ 2] 9 MediaPlayer:messageReceived:messageType=element
2018-01-02 10:28:55.729 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.729 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.729 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.729 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.729 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.729 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.729 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.729 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.729 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.729 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.730 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.730 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.730 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.730 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.730 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-status
2018-01-02 10:28:55.730 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.730 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-status
2018-01-02 10:28:55.730 [ 2] 9 MediaPlayer:messageReceived:messageType=stream-start
2018-01-02 10:28:55.730 [ 2] 9 MediaPlayer:messageReceived:messageType=latency
2018-01-02 10:28:55.730 [ 2] 9 MediaPlayer:messageReceived:messageType=tag
2018-01-02 10:28:55.730 [ 2] 0 MediaPlayer:callingOnTags
2018-01-02 10:28:55.731 [ 2] 0 AudioPlayer:onTags:id=3
2018-01-02 10:28:55.731 [ 2] 9 MediaPlayer:messageReceived:messageType=tag
2018-01-02 10:28:55.731 [ 9] 1 AudioPlayer:executeOnTags:id=3
2018-01-02 10:28:55.731 [ 2] 0 MediaPlayer:callingOnTags
2018-01-02 10:28:55.732 [ 2] 0 AudioPlayer:onTags:id=3
2018-01-02 10:28:55.732 [ 9] 0 EventBuilder:buildJsonEventString:messageId=7431d773-4ebb-42ff-8e07-d64ae5345760,namespace=AudioPlayer,name=StreamMetadataExtracted
2018-01-02 10:28:55.732 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.732 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"AudioPlayer","name":"StreamMetadataExtracted","messageId":"7431d773-4ebb-42ff-8e07-d64ae5345760"},"payload":{"token":"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73:1","metadata":{"audio-codec":"MPEG-2 AAC"}}}}
2018-01-02 10:28:55.732 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.733 [ 9] 9 HTTP2Transport:enqueueRequest
2018-01-02 10:28:55.733 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.733 [ 2] 9 MediaPlayer:State Change:oldState=READY,newState=PAUSED,pendingState=PLAYING
2018-01-02 10:28:55.733 [ 2] 9 MediaPlayer:messageReceived:messageType=async-done
2018-01-02 10:28:55.733 [ 9] 1 AudioPlayer:executeOnTags:id=3
2018-01-02 10:28:55.733 [ 2] 9 MediaPlayer:messageReceived:messageType=new-clock
2018-01-02 10:28:55.734 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.734 [ 9] 0 EventBuilder:buildJsonEventString:messageId=698510a5-8cb4-452b-9149-94567e708314,namespace=AudioPlayer,name=StreamMetadataExtracted
2018-01-02 10:28:55.734 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.734 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"AudioPlayer","name":"StreamMetadataExtracted","messageId":"698510a5-8cb4-452b-9149-94567e708314"},"payload":{"token":"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73:1","metadata":{"audio-codec":"MPEG-4 AAC"}}}}
2018-01-02 10:28:55.734 [ 9] 9 HTTP2Transport:enqueueRequest
2018-01-02 10:28:55.734 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.735 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.735 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.736 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.736 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.736 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.737 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.737 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.737 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.738 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.738 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.739 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.739 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.740 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.740 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.741 [ 2] 9 MediaPlayer:messageReceived:messageType=state-changed
2018-01-02 10:28:55.741 [ 2] 9 MediaPlayer:State Change:oldState=PAUSED,newState=PLAYING,pendingState=VOID_PENDING
2018-01-02 10:28:55.742 [ 2] 0 MediaPlayer:callingOnPlaybackStarted:currentId=3
2018-01-02 10:28:55.742 [ 2] 0 AudioPlayer:onPlaybackStarted:id=3
2018-01-02 10:28:55.743 [ 9] 1 AudioPlayer:executeOnPlaybackStarted:id=3
--> King:: AudioPlayer changeActivity from IDLE to PLAYING...
2018-01-02 10:28:55.744 [ 9] 0 AudioPlayer:changeActivity:from=IDLE,to=PLAYING
--> King:: AudioPlayer executeProvideState start ...
2018-01-02 10:28:55.744 [ 9] 0 AudioPlayer:executeProvideState:sendToken=false,stateRequestToken=0
2018-01-02 10:28:55.745 [ 9] 9 MediaPlayer:getOffsetCalled
ACDB -> send_audio_cal, acdb_id = 14, path = 0, app id = 0x11130, sample rate = 48000
ACDB -> send_asm_topology
ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TOPOLOGY_ID
ACDB -> send_adm_topology
ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TOPOLOGY_ID
ACDB -> send_audtable
ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE_SIZE
ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TABLE
ACDB -> AUDIO_SET_AUDPROC_CAL
ACDB -> send_audvoltable
ACDB -> ACDB_CMD_GET_AUDPROC_GAIN_DEP_STEP_TABLE, vol index 0
ACDB -> AUDIO_SET_VOL_CAL cal type = 12
ACDB -> send_audstrmtable
ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TABLE_V2
ACDB -> audstrm_cal->cal_type.cal_data.cal_size = 456
ACDB -> send_afe_topology
ACDB -> ACDB_CMD_GET_AFE_TOPOLOGY_ID
ACDB -> GET_AFE_TOPOLOGY_ID for adcd_id 14, Topology Id 112fa
ACDB -> send_afe_cal
ACDB -> ACDB_CMD_GET_AFE_COMMON_TABLE
ACDB -> AUDIO_SET_AFE_CAL
ACDB -> send_hw_delay : acdb_id = 14 path = 0
ACDB -> ACDB_AVSYNC_INFO: ACDB_CMD_GET_DEVICE_PROPERTY
2018-01-02 10:28:55.768 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.768 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:55.768 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.769 [ 2] 0 MediaPlayer:handleGetOffsetCalled:idPassed=3,currentId=3
2018-01-02 10:28:55.769 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:55.769 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
--> King:: AudioPlayer executeProvideState update payload to: {"token":"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73:1","offsetInMilliseconds":723,"playerActivity":"PLAYING"}
2018-01-02 10:28:55.769 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:55.770 [ 9] 0 ContextManager:updateStateLocked:action=updatedState,state={"token"\:"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73\:1"\,"offsetInMilliseconds"\:723\,"playerActivity"\:"PLAYING"},namespace=AudioPlayer,name=PlaybackState
--> King:: AudioPlayer executeProvideState update Success!!!
2018-01-02 10:28:55.770 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:55.770 [ 9] 9 MediaPlayer:getOffsetCalled
2018-01-02 10:28:55.770 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.770 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:55.770 [ 15] 9 BaseStreamSource:m_needDataCallbackId already set
2018-01-02 10:28:55.792 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.792 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:55.792 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:55.793 [ 2] 0 MediaPlayer:handleGetOffsetCalled:idPassed=3,currentId=3
2018-01-02 10:28:55.793 [ 9] 1 AudioPlayer:notifyObserver:playerActivity=PLAYING
2018-01-02 10:28:55.793 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
King:: onAudioPlayerPlayStatusChanged to 1
onAudioPlayerPlayStatusChanged payload token is: amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73:1
2018-01-02 10:28:55.794 [ 2] 9 BaseStreamSource:handleNeedDataCalled*****************************current is sending current off set.
WisCommon::sendUDPDataToSender senderIPAddress is null, so ignore this send.
2018-01-02 10:28:55.794 [ 9] 1 AudioPlayer:sendEventWithTokenAndOffset:eventName=PlaybackStarted
2018-01-02 10:28:55.794 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.794 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.795 [ 9] 0 EventBuilder:buildJsonEventString:messageId=e7d075ca-575b-4702-85bf-975e9511c7bb,namespace=AudioPlayer,name=PlaybackStarted
AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"AudioPlayer","name":"PlaybackStarted","messageId":"e7d075ca-575b-4702-85bf-975e9511c7bb"},"payload":{"token":"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73:1","offsetInMilliseconds":0}}}
2018-01-02 10:28:55.796 [ 9] 9 HTTP2Transport:enqueueRequest
2018-01-02 10:28:55.823 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.823 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.823 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.824 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.852 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.852 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.853 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.853 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.863 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:55.944 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.944 [ 2] 9 MediaPlayer:messageReceived:messageType=tag
2018-01-02 10:28:55.944 [ 2] 0 MediaPlayer:callingOnTags
2018-01-02 10:28:55.944 [ 2] 0 AudioPlayer:onTags:id=3
2018-01-02 10:28:55.945 [ 9] 1 AudioPlayer:executeOnTags:id=3
2018-01-02 10:28:55.945 [ 2] 9 MediaPlayer:messageReceived:messageType=tag
2018-01-02 10:28:55.945 [ 2] 0 MediaPlayer:callingOnTags
2018-01-02 10:28:55.945 [ 2] 0 AudioPlayer:onTags:id=3
2018-01-02 10:28:55.946 [ 9] 0 EventBuilder:buildJsonEventString:messageId=57bdf7d8-0687-4489-a40b-c21478b2e7f3,namespace=AudioPlayer,name=StreamMetadataExtracted
2018-01-02 10:28:55.946 [ 2] 9 MediaPlayer:messageReceived:messageType=tag
2018-01-02 10:28:55.946 [ 2] 0 MediaPlayer:callingOnTags
2018-01-02 10:28:55.946 [ 2] 0 AudioPlayer:onTags:id=3
AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"AudioPlayer","name":"StreamMetadataExtracted","messageId":"57bdf7d8-0687-4489-a40b-c21478b2e7f3"},"payload":{"token":"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73:1","metadata":{"audio-codec":"MPEG-4 AAC","minimum-bitrate":"253575","maximum-bitrate":"253575","bitrate":"253575"}}}}
2018-01-02 10:28:55.946 [ 9] 9 HTTP2Transport:enqueueRequest
2018-01-02 10:28:55.946 [ 2] 9 MediaPlayer:messageReceived:messageType=tag
2018-01-02 10:28:55.947 [ 2] 0 MediaPlayer:callingOnTags
2018-01-02 10:28:55.947 [ 2] 0 AudioPlayer:onTags:id=3
2018-01-02 10:28:55.947 [ 2] 9 MediaPlayer:messageReceived:messageType=tag
2018-01-02 10:28:55.947 [ 9] 1 AudioPlayer:executeOnTags:id=3
2018-01-02 10:28:55.947 [ 2] 0 MediaPlayer:callingOnTags
2018-01-02 10:28:55.945 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:55.948 [ 2] 0 AudioPlayer:onTags:id=3
2018-01-02 10:28:55.948 [ 9] 0 EventBuilder:buildJsonEventString:messageId=76e36a1c-5a4b-48b9-aeaa-a6487951936e,namespace=AudioPlayer,name=StreamMetadataExtracted
AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"AudioPlayer","name":"StreamMetadataExtracted","messageId":"76e36a1c-5a4b-48b9-aeaa-a6487951936e"},"payload":{"token":"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73:1","metadata":{"audio-codec":"MPEG-4 AAC","minimum-bitrate":"253575","maximum-bitrate":"253919","bitrate":"253588"}}}}
2018-01-02 10:28:55.949 [ 15] 9 BaseStreamSource:m_needDataCallbackId already set
2018-01-02 10:28:55.950 [ 9] 9 HTTP2Transport:enqueueRequest
2018-01-02 10:28:55.951 [ 9] 1 AudioPlayer:executeOnTags:id=3
2018-01-02 10:28:55.950 [ 2] 9 MediaPlayer:messageReceived:messageType=tag
2018-01-02 10:28:55.952 [ 9] 0 EventBuilder:buildJsonEventString:messageId=2c42cc85-2a17-4e18-b187-c15fd18dabc3,namespace=AudioPlayer,name=StreamMetadataExtracted
AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"AudioPlayer","name":"StreamMetadataExtracted","messageId":"2c42cc85-2a17-4e18-b187-c15fd18dabc3"},"payload":{"token":"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73:1","metadata":{"audio-codec":"MPEG-4 AAC","minimum-bitrate":"253575","maximum-bitrate":"268389","bitrate":"253847"}}}}
2018-01-02 10:28:55.952 [ 2] 0 MediaPlayer:callingOnTags
2018-01-02 10:28:55.952 [ 9] 9 HTTP2Transport:enqueueRequest
2018-01-02 10:28:55.953 [ 9] 1 AudioPlayer:executeOnTags:id=3
2018-01-02 10:28:55.953 [ 2] 0 AudioPlayer:onTags:id=3
2018-01-02 10:28:55.955 [ 2] 9 MediaPlayer:messageReceived:messageType=tag
2018-01-02 10:28:55.955 [ 9] 0 EventBuilder:buildJsonEventString:messageId=e75c27dc-3f8a-412d-9155-cbb933d0a127,namespace=AudioPlayer,name=StreamMetadataExtracted
2018-01-02 10:28:55.955 [ 2] 0 MediaPlayer:callingOnTags
2018-01-02 10:28:55.955 [ 2] 0 AudioPlayer:onTags:id=3
AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"AudioPlayer","name":"StreamMetadataExtracted","messageId":"e75c27dc-3f8a-412d-9155-cbb933d0a127"},"payload":{"token":"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73:1","metadata":{"audio-codec":"MPEG-4 AAC","minimum-bitrate":"253575","maximum-bitrate":"271490","bitrate":"254151"}}}}
2018-01-02 10:28:55.956 [ 9] 9 HTTP2Transport:enqueueRequest
2018-01-02 10:28:55.956 [ 2] 9 MediaPlayer:messageReceived:messageType=tag
2018-01-02 10:28:55.956 [ 9] 1 AudioPlayer:executeOnTags:id=3
2018-01-02 10:28:55.956 [ 2] 0 MediaPlayer:callingOnTags
2018-01-02 10:28:55.956 [ 2] 0 AudioPlayer:onTags:id=3
2018-01-02 10:28:55.957 [ 9] 0 EventBuilder:buildJsonEventString:messageId=a5848cf4-e082-4924-98ba-52943f2c1332,namespace=AudioPlayer,name=StreamMetadataExtracted
2018-01-02 10:28:55.957 [ 2] 9 MediaPlayer:messageReceived:messageType=tag
AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"AudioPlayer","name":"StreamMetadataExtracted","messageId":"a5848cf4-e082-4924-98ba-52943f2c1332"},"payload":{"token":"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73:1","metadata":{"audio-codec":"MPEG-4 AAC","minimum-bitrate":"246339","maximum-bitrate":"271490","bitrate":"254480"}}}}
2018-01-02 10:28:55.957 [ 2] 0 MediaPlayer:callingOnTags
2018-01-02 10:28:55.957 [ 9] 9 HTTP2Transport:enqueueRequest
2018-01-02 10:28:55.957 [ 2] 0 AudioPlayer:onTags:id=3
2018-01-02 10:28:55.958 [ 9] 1 AudioPlayer:executeOnTags:id=3
2018-01-02 10:28:55.958 [ 2] 9 MediaPlayer:messageReceived:messageType=tag
2018-01-02 10:28:55.958 [ 2] 0 MediaPlayer:callingOnTags
2018-01-02 10:28:55.958 [ 2] 0 AudioPlayer:onTags:id=3
2018-01-02 10:28:55.958 [ 9] 0 EventBuilder:buildJsonEventString:messageId=05af3563-c334-4312-be7c-4bdf741b338d,namespace=AudioPlayer,name=StreamMetadataExtracted
2018-01-02 10:28:55.958 [ 2] 9 MediaPlayer:messageReceived:messageType=tag
AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"AudioPlayer","name":"StreamMetadataExtracted","messageId":"05af3563-c334-4312-be7c-4bdf741b338d"},"payload":{"token":"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73:1","metadata":{"audio-codec":"MPEG-4 AAC","minimum-bitrate":"237037","maximum-bitrate":"271490","bitrate":"254124"}}}}
2018-01-02 10:28:55.959 [ 2] 0 MediaPlayer:callingOnTags
2018-01-02 10:28:55.959 [ 9] 9 HTTP2Transport:enqueueRequest
2018-01-02 10:28:55.959 [ 2] 0 AudioPlayer:onTags:id=3
2018-01-02 10:28:55.959 [ 9] 1 AudioPlayer:executeOnTags:id=3
2018-01-02 10:28:55.960 [ 2] 9 MediaPlayer:messageReceived:messageType=tag
2018-01-02 10:28:55.960 [ 2] 0 MediaPlayer:callingOnTags
2018-01-02 10:28:55.960 [ 2] 0 AudioPlayer:onTags:id=3
2018-01-02 10:28:55.960 [ 9] 0 EventBuilder:buildJsonEventString:messageId=84e9d840-f33a-4374-8c30-d8b5a4a068ac,namespace=AudioPlayer,name=StreamMetadataExtracted
2018-01-02 10:28:55.960 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"AudioPlayer","name":"StreamMetadataExtracted","messageId":"84e9d840-f33a-4374-8c30-d8b5a4a068ac"},"payload":{"token":"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73:1","metadata":{"audio-codec":"MPEG-4 AAC","minimum-bitrate":"233592","maximum-bitrate":"271490","bitrate":"253808"}}}}
2018-01-02 10:28:55.961 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:55.961 [ 9] 9 HTTP2Transport:enqueueRequest
2018-01-02 10:28:55.961 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:55.962 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.962 [ 9] 1 AudioPlayer:executeOnTags:id=3
2018-01-02 10:28:55.963 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:55.963 [ 15] 9 BaseStreamSource:m_needDataCallbackId already set
2018-01-02 10:28:55.963 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.963 [ 9] 0 EventBuilder:buildJsonEventString:messageId=ad8a008f-4a52-4668-b3c4-3ce80b4b620f,namespace=AudioPlayer,name=StreamMetadataExtracted
2018-01-02 10:28:55.964 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"AudioPlayer","name":"StreamMetadataExtracted","messageId":"ad8a008f-4a52-4668-b3c4-3ce80b4b620f"},"payload":{"token":"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73:1","metadata":{"audio-codec":"MPEG-4 AAC","minimum-bitrate":"233592","maximum-bitrate":"279414","bitrate":"253864"}}}}
2018-01-02 10:28:55.964 [ 15] 9 BaseStreamSource:m_needDataCallbackId already set
2018-01-02 10:28:55.964 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:55.964 [ 9] 9 HTTP2Transport:enqueueRequest
2018-01-02 10:28:55.965 [ 9] 1 AudioPlayer:executeOnTags:id=3
2018-01-02 10:28:55.966 [ 9] 0 EventBuilder:buildJsonEventString:messageId=84e0117d-e733-42d7-a516-c8ca283c699d,namespace=AudioPlayer,name=StreamMetadataExtracted
AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"AudioPlayer","name":"StreamMetadataExtracted","messageId":"84e0117d-e733-42d7-a516-c8ca283c699d"},"payload":{"token":"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73:1","metadata":{"audio-codec":"MPEG-4 AAC","minimum-bitrate":"228768","maximum-bitrate":"279414","bitrate":"253600"}}}}
2018-01-02 10:28:55.966 [ 9] 9 HTTP2Transport:enqueueRequest
2018-01-02 10:28:55.967 [ 9] 1 AudioPlayer:executeOnTags:id=3
2018-01-02 10:28:55.967 [ 9] 0 EventBuilder:buildJsonEventString:messageId=0aa8cf4a-264d-41d3-89a9-d5a0c2a49b3b,namespace=AudioPlayer,name=StreamMetadataExtracted
AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"AudioPlayer","name":"StreamMetadataExtracted","messageId":"0aa8cf4a-264d-41d3-89a9-d5a0c2a49b3b"},"payload":{"token":"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73:1","metadata":{"audio-codec":"MPEG-4 AAC","minimum-bitrate":"228768","maximum-bitrate":"281482","bitrate":"253834"}}}}
2018-01-02 10:28:55.968 [ 9] 9 HTTP2Transport:enqueueRequest
2018-01-02 10:28:55.968 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.968 [ 9] 1 AudioPlayer:executeOnTags:id=3
2018-01-02 10:28:55.969 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:55.969 [ 9] 0 EventBuilder:buildJsonEventString:messageId=2db7b29e-9926-460e-96da-02600c7de375,namespace=AudioPlayer,name=StreamMetadataExtracted
2018-01-02 10:28:55.969 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"AudioPlayer","name":"StreamMetadataExtracted","messageId":"2db7b29e-9926-460e-96da-02600c7de375"},"payload":{"token":"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73:1","metadata":{"audio-codec":"MPEG-4 AAC","minimum-bitrate":"228768","maximum-bitrate":"287683","bitrate":"254129"}}}}
2018-01-02 10:28:55.969 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:55.970 [ 15] 9 BaseStreamSource:m_needDataCallbackId already set
2018-01-02 10:28:55.970 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:55.970 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.969 [ 9] 9 HTTP2Transport:enqueueRequest
2018-01-02 10:28:55.971 [ 9] 1 AudioPlayer:executeOnTags:id=3
2018-01-02 10:28:55.971 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:55.971 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.971 [ 9] 0 EventBuilder:buildJsonEventString:messageId=e124903e-7cf8-48b8-8518-54ac9f86a7c8,namespace=AudioPlayer,name=StreamMetadataExtracted
2018-01-02 10:28:55.972 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"AudioPlayer","name":"StreamMetadataExtracted","messageId":"e124903e-7cf8-48b8-8518-54ac9f86a7c8"},"payload":{"token":"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73:1","metadata":{"audio-codec":"MPEG-4 AAC","minimum-bitrate":"221189","maximum-bitrate":"287683","bitrate":"253845"}}}}
2018-01-02 10:28:55.972 [ 15] 9 BaseStreamSource:m_needDataCallbackId already set
2018-01-02 10:28:55.972 [ 9] 9 HTTP2Transport:enqueueRequest
2018-01-02 10:28:55.993 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.993 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:55.993 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.993 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:55.994 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.994 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:55.995 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:55.995 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.005 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.005 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.006 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:56.006 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.006 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.006 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.007 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.007 [ 15] 9 BaseStreamSource:m_needDataCallbackId already set
2018-01-02 10:28:56.007 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:56.007 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.024 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.025 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.025 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.025 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:56.026 [ 15] 9 BaseStreamSource:m_needDataCallbackId already set
2018-01-02 10:28:56.026 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.027 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.028 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.040 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.040 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.040 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:56.041 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.041 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.042 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.058 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.058 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.058 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:56.058 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.058 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.059 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.059 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.059 [ 15] 9 BaseStreamSource:m_needDataCallbackId already set
2018-01-02 10:28:56.068 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.069 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:56.069 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.069 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.069 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.069 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.069 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:56.070 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.135 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.136 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.136 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:56.136 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.136 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.137 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.137 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:56.137 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.147 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.147 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.148 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:56.148 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.148 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.148 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.149 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.150 [ 15] 9 BaseStreamSource:m_needDataCallbackId already set
2018-01-02 10:28:56.171 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.172 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.172 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:56.172 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.172 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.172 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.173 [ e] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 0abcf4fffe907c26-00002033-000923c5-8dd5ba2d404bf894-069370d2-15
2018-01-02 10:28:56.173 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.173 [ 15] 9 BaseStreamSource:m_needDataCallbackId already set
2018-01-02 10:28:56.173 [ e] 0 HTTP2Transport:cleanupFinishedStream:streamId=19,result=204
^^^^^^ ______ ^^^^^^ HTTP2Transport::releaseStream id = 19
2018-01-02 10:28:56.174 [ e] 0 HTTP2StreamPool:releaseStream:streamId=19,numAcquiredStreams=1
2018-01-02 10:28:56.174 [ e] 0 CurlEasyHandleWrapper:reset:responseCode=HTTP_RESPONSE_SUCCESS_NO_CONTENT
2018-01-02 10:28:56.174 [ e] 0 HTTP2Transport:processNextOutgoingMessage
2018-01-02 10:28:56.174 [ e] 0 HTTP2StreamPool:getStream:streamId=21,numAcquiredStreams=2
2018-01-02 10:28:56.175 [ e] I HTTP2Transport:insertActiveStream:handle=0xa65f0330
^^^^^^ ______ ^^^^^^ HTTP2Transport::processNextOutgoingMessage insertActiveStream id= 21
2018-01-02 10:28:56.178 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.178 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.178 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:56.178 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.178 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.178 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.179 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.179 [ 15] 9 BaseStreamSource:m_needDataCallbackId already set
2018-01-02 10:28:56.185 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.185 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:56.186 [ 2] 9 MediaPlayer:messageReceived:messageType=tag
2018-01-02 10:28:56.186 [ 2] 0 MediaPlayer:callingOnTags
2018-01-02 10:28:56.186 [ 2] 0 AudioPlayer:onTags:id=3
2018-01-02 10:28:56.186 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.186 [ 9] 1 AudioPlayer:executeOnTags:id=3
2018-01-02 10:28:56.186 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.187 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:56.187 [ 9] 0 EventBuilder:buildJsonEventString:messageId=b50819c5-a6a2-4f5d-af6b-fe0eaa40e5da,namespace=AudioPlayer,name=StreamMetadataExtracted
2018-01-02 10:28:56.187 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.187 [ 2] 9 MediaPlayer:messageReceived:messageType=tag
2018-01-02 10:28:56.188 [ 2] 0 MediaPlayer:callingOnTags
AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"AudioPlayer","name":"StreamMetadataExtracted","messageId":"b50819c5-a6a2-4f5d-af6b-fe0eaa40e5da"},"payload":{"token":"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73:1","metadata":{"audio-codec":"MPEG-4 AAC","minimum-bitrate":"221189","maximum-bitrate":"305943","bitrate":"253752"}}}}
2018-01-02 10:28:56.188 [ 2] 0 AudioPlayer:onTags:id=3
2018-01-02 10:28:56.188 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.188 [ 9] 9 HTTP2Transport:enqueueRequest
2018-01-02 10:28:56.188 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.188 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:56.188 [ 9] 1 AudioPlayer:executeOnTags:id=3
2018-01-02 10:28:56.189 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.189 [ 9] 0 EventBuilder:buildJsonEventString:messageId=b78b7c98-a981-4109-a8e8-bef887fd5792,namespace=AudioPlayer,name=StreamMetadataExtracted
AVSConnectionManager::sendMessage {"event":{"header":{"namespace":"AudioPlayer","name":"StreamMetadataExtracted","messageId":"b78b7c98-a981-4109-a8e8-bef887fd5792"},"payload":{"token":"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73:1","metadata":{"audio-codec":"MPEG-4 AAC","minimum-bitrate":"204307","maximum-bitrate":"305943","bitrate":"253604"}}}}
2018-01-02 10:28:56.190 [ 9] 9 HTTP2Transport:enqueueRequest
2018-01-02 10:28:56.196 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.196 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.196 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:56.196 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.196 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.196 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.197 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.197 [ 15] 9 BaseStreamSource:m_needDataCallbackId already set
2018-01-02 10:28:56.205 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.206 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.206 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:56.206 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.206 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.206 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.206 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.206 [ 15] 9 BaseStreamSource:m_needDataCallbackId already set
2018-01-02 10:28:56.213 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.213 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:56.214 [ 15] 9 BaseStreamSource:onNeedDataCalled:size=4096
2018-01-02 10:28:56.214 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.214 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.215 [ 2] 9 BaseStreamSource:handleNeedDataCalled
2018-01-02 10:28:56.215 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.216 [ 1b] I InProcessAttachmentReader:readFailed:reason=SDS is closed
2018-01-02 10:28:56.216 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:56.216 [ 1b] I UrlContentToAttachmentConverter:readFinished:reason=CLOSED
2018-01-02 10:28:56.217 [ 1b] 9 UrlContentToAttachmentConverter:writeUrlContentIntoStreamSuccess
2018-01-02 10:28:56.217 [ 2] 9 AttachmentReaderSource:read:size=880,status=0
2018-01-02 10:28:56.230 [ 1b] 9 UrlContentToAttachmentConverter:writeUrlContentIntoStream:info=beginning
2018-01-02 10:28:56.562 [ 16] 9 MediaPlayer:getOffsetCalled
2018-01-02 10:28:56.680 [ 1d] 9 LibCurlHttpContentFetcher:getContent:responseCode=200
2018-01-02 10:28:56.685 [ 1d] 9 LibCurlHttpContentFetcher:getContent:contentType=video/MP2T
2018-01-02 10:28:56.686 [ 18] 9 PlaylistParser:PlaylistParser:contentType=video/MP2T,length=10000
2018-01-02 10:28:56.686 [ 18] 9 PlaylistParser:foundNonPlaylistURL
2018-01-02 10:28:56.687 [ 18] 9 UrlContentToAttachmentConverter:onPlaylistEntryParsed:status=STILL_ONGOING
2018-01-02 10:28:56.796 [ e] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 0abcf4fffe907c26-00002033-000923c5-8dd5ba2d404bf894-069370d2-17
2018-01-02 10:28:56.796 [ e] 0 HTTP2Transport:processNextOutgoingMessage
2018-01-02 10:28:56.796 [ e] 0 HTTP2StreamPool:getStream:streamId=23,numAcquiredStreams=3
2018-01-02 10:28:56.797 [ e] I HTTP2Transport:insertActiveStream:handle=0x99908060
^^^^^^ ______ ^^^^^^ HTTP2Transport::processNextOutgoingMessage insertActiveStream id= 23
2018-01-02 10:28:56.798 [ 12] 0 MessageInterpreter:receive:messageId=c8f0eed6-d657-4557-9fd0-e6b6e5d7e09b:No dialogRequestId attached to message.
2018-01-02 10:28:56.798 [ e] 0 HTTP2Transport:cleanupFinishedStream:streamId=21,result=200
^^^^^^ ______ ^^^^^^ HTTP2Transport::releaseStream id = 21
2018-01-02 10:28:56.799 [ 12] I DirectiveSequencer:onDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"TemplateRuntime"\,"name"\:"RenderPlayerInfo"\,"messageId"\:"c8f0eed6-d657-4557-9fd0-e6b6e5d7e09b"}\,"payload"\:{"content"\:{"provider"\:{"name"\:"Amazon Music"\,"logo"\:{"sources"\:[{"url"\:"https\://s3.amazonaws.com/music-provider-logos/AmazonMusic.svg"}]}}\,"art"\:{"sources"\:[{"size"\:"large"\,"url"\:"https\://m.media-amazon.com/images/I/41SZUyrViuL._UL256_.jpg"}\,{"size"\:"x-large"\,"url"\:"https\://m.media-amazon.com/images/I/61v-D2RtAZL._UL600_.jpg"}]}\,"titleSubtext2"\:"Lost N Found"\,"mediaLengthInMilliseconds"\:219000\,"title"\:"Love U U"\,"titleSubtext1"\:"JJ Lin"}\,"audioItemId"\:"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73\:1"\,"controls"\:[{"selected"\:false\,"name"\:"PLAY_PAUSE"\,"enabled"\:true\,"type"\:"BUTTON"}\,{"selected"\:false\,"name"\:"PREVIOUS"\,"enabled"\:true\,"type"\:"BUTTON"}\,{"selected"\:false\,"name"\:"NEXT"\,"enabled"\:true\,"type"\:"BUTTON"}]}}}
...onDirectiveArrived...
***************************current Template Runtime directive name: RenderPlayerInfo
***************************current Template Runtime directive payload content: {"content":{"provider":{"name":"Amazon Music","logo":{"sources":[{"url":"https://s3.amazonaws.com/music-provider-logos/AmazonMusic.svg"}]}},"art":{"sources":[{"size":"large","url":"https://m.media-amazon.com/images/I/41SZUyrViuL._UL256_.jpg"},{"size":"x-large","url":"https://m.media-amazon.com/images/I/61v-D2RtAZL._UL600_.jpg"}]},"titleSubtext2":"Lost N Found","mediaLengthInMilliseconds":219000,"title":"Love U U","titleSubtext1":"JJ Lin"},"audioItemId":"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73:1","controls":[{"selected":false,"name":"PLAY_PAUSE","enabled":true,"type":"BUTTON"},{"selected":false,"name":"PREVIOUS","enabled":true,"type":"BUTTON"},{"selected":false,"name":"NEXT","enabled":true,"type":"BUTTON"}]}
2018-01-02 10:28:56.799 [ e] 0 HTTP2StreamPool:releaseStream:streamId=21,numAcquiredStreams=2
providerDocument parse success!
artDocument parse success!
sources is array
size= large, url= https://m.media-amazon.com/images/I/41SZUyrViuL._UL256_.jpg
size= x-large, url= https://m.media-amazon.com/images/I/61v-D2RtAZL._UL600_.jpg
rapidjson parse success!
get User information content= {"provider":{"name":"Amazon Music","logo":{"sources":[{"url":"https://s3.amazonaws.com/music-provider-logos/AmazonMusic.svg"}]}},"art":{"sources":[{"size":"large","url":"https://m.media-amazon.com/images/I/41SZUyrViuL._UL256_.jpg"},{"size":"x-large","url":"https://m.media-amazon.com/images/I/61v-D2RtAZL._UL600_.jpg"}]},"titleSubtext2":"Lost N Found","mediaLengthInMilliseconds":219000,"title":"Love U U","titleSubtext1":"JJ Lin"}
get User information header=
get User information provider name= Amazon Music
get User information url= https://m.media-amazon.com/images/I/61v-D2RtAZL._UL600_.jpg
get User information titleSubtext2= Lost N Found
get User information mediaLengthInMilliseconds= 219000
get User information title= Love U U
get User information titleSubtext1= JJ Lin
send companion app content= play_music:header=
title=Love U U
titleSubtext1=JJ Lin
titleSubtext2=Lost N Found
url=https://m.media-amazon.com/images/I/61v-D2RtAZL._UL600_.jpg
medialengthinmilliseconds=219000
WisCommon::sendUDPDataToSender senderIPAddress is null, so ignore this send.
*****************************current is sending current off set.
2018-01-02 10:28:56.802 [ 13] W DirectiveRouter:handleDirectiveWithPolicyHandleImmediatelyFailed:messageId=c8f0eed6-d657-4557-9fd0-e6b6e5d7e09b,reason=noHandlerRegistered
2018-01-02 10:28:56.802 [ 13] I DirectiveRouter:preHandleDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"TemplateRuntime"\,"name"\:"RenderPlayerInfo"\,"messageId"\:"c8f0eed6-d657-4557-9fd0-e6b6e5d7e09b"}\,"payload"\:{"content"\:{"provider"\:{"name"\:"Amazon Music"\,"logo"\:{"sources"\:[{"url"\:"https\://s3.amazonaws.com/music-provider-logos/AmazonMusic.svg"}]}}\,"art"\:{"sources"\:[{"size"\:"large"\,"url"\:"https\://m.media-amazon.com/images/I/41SZUyrViuL._UL256_.jpg"}\,{"size"\:"x-large"\,"url"\:"https\://m.media-amazon.com/images/I/61v-D2RtAZL._UL600_.jpg"}]}\,"titleSubtext2"\:"Lost N Found"\,"mediaLengthInMilliseconds"\:219000\,"title"\:"Love U U"\,"titleSubtext1"\:"JJ Lin"}\,"audioItemId"\:"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73\:1"\,"controls"\:[{"selected"\:false\,"name"\:"PLAY_PAUSE"\,"enabled"\:true\,"type"\:"BUTTON"}\,{"selected"\:false\,"name"\:"PREVIOUS"\,"enabled"\:true\,"type"\:"BUTTON"}\,{"selected"\:false\,"name"\:"NEXT"\,"enabled"\:true\,"type"\:"BUTTON"}]}}}
2018-01-02 10:28:56.802 [ 13] W DirectiveRouter:preHandleDirectiveFailed:messageId=c8f0eed6-d657-4557-9fd0-e6b6e5d7e09b,reason=noHandlerRegistered
2018-01-02 10:28:56.802 [ 13] I DirectiveSequencer:sendingExceptionEncountered:messageId=c8f0eed6-d657-4557-9fd0-e6b6e5d7e09b
2018-01-02 10:28:57.232 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.233 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.233 [ 2] 0 MediaPlayer:handleGetOffsetCalled:idPassed=3,currentId=3
audioplayer get current offset:: 2215
send current offset content: play_music:offset=2215
WisCommon::sendUDPDataToSender senderIPAddress is null, so ignore this send.
2018-01-02 10:28:57.233 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.233 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.303 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.303 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.304 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.305 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.405 [ e] 0 HTTP2Stream:receivedRequestId:value=x-amzn-requestid\: 0abcf4fffe907c26-00002033-000923c5-8dd5ba2d404bf894-069370d2-19
2018-01-02 10:28:57.406 [ e] 0 HTTP2Transport:processNextOutgoingMessage
2018-01-02 10:28:57.406 [ e] 0 HTTP2StreamPool:getStream:streamId=25,numAcquiredStreams=3
2018-01-02 10:28:57.406 [ e] I HTTP2Transport:insertActiveStream:handle=0xa65f0330
^^^^^^ ______ ^^^^^^ HTTP2Transport::processNextOutgoingMessage insertActiveStream id= 25
2018-01-02 10:28:57.408 [ 12] 0 MessageInterpreter:receive:messageId=56b59577-d93a-4bbd-aa80-69dc58715dab:No dialogRequestId attached to message.
2018-01-02 10:28:57.408 [ e] 0 HTTP2Transport:cleanupFinishedStream:streamId=23,result=200
^^^^^^ ______ ^^^^^^ HTTP2Transport::releaseStream id = 23
2018-01-02 10:28:57.408 [ 12] I DirectiveSequencer:onDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"TemplateRuntime"\,"name"\:"RenderPlayerInfo"\,"messageId"\:"56b59577-d93a-4bbd-aa80-69dc58715dab"}\,"payload"\:{"content"\:{"provider"\:{"name"\:"Amazon Music"\,"logo"\:{"sources"\:[{"url"\:"https\://s3.amazonaws.com/music-provider-logos/AmazonMusic.svg"}]}}\,"art"\:{"sources"\:[{"size"\:"large"\,"url"\:"https\://m.media-amazon.com/images/I/41SZUyrViuL._UL256_.jpg"}\,{"size"\:"x-large"\,"url"\:"https\://m.media-amazon.com/images/I/61v-D2RtAZL._UL600_.jpg"}]}\,"titleSubtext2"\:"Lost N Found"\,"mediaLengthInMilliseconds"\:219000\,"title"\:"Love U U"\,"titleSubtext1"\:"JJ Lin"}\,"audioItemId"\:"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73\:1"\,"controls"\:[{"selected"\:false\,"name"\:"PLAY_PAUSE"\,"enabled"\:true\,"type"\:"BUTTON"}\,{"selected"\:false\,"name"\:"PREVIOUS"\,"enabled"\:true\,"type"\:"BUTTON"}\,{"selected"\:false\,"name"\:"NEXT"\,"enabled"\:true\,"type"\:"BUTTON"}]}}}
...onDirectiveArrived...
***************************current Template Runtime directive name: RenderPlayerInfo
***************************current Template Runtime directive payload content: {"content":{"provider":{"name":"Amazon Music","logo":{"sources":[{"url":"https://s3.amazonaws.com/music-provider-logos/AmazonMusic.svg"}]}},"art":{"sources":[{"size":"large","url":"https://m.media-amazon.com/images/I/41SZUyrViuL._UL256_.jpg"},{"size":"x-large","url":"https://m.media-amazon.com/images/I/61v-D2RtAZL._UL600_.jpg"}]},"titleSubtext2":"Lost N Found","mediaLengthInMilliseconds":219000,"title":"Love U U","titleSubtext1":"JJ Lin"},"audioItemId":"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73:1","controls":[{"selected":false,"name":"PLAY_PAUSE","enabled":true,"type":"BUTTON"},{"selected":false,"name":"PREVIOUS","enabled":true,"type":"BUTTON"},{"selected":false,"name":"NEXT","enabled":true,"type":"BUTTON"}]}
2018-01-02 10:28:57.409 [ e] 0 HTTP2StreamPool:releaseStream:streamId=23,numAcquiredStreams=2
providerDocument parse success!
artDocument parse success!
sources is array
size= large, url= https://m.media-amazon.com/images/I/41SZUyrViuL._UL256_.jpg
size= x-large, url= https://m.media-amazon.com/images/I/61v-D2RtAZL._UL600_.jpg
rapidjson parse success!
get User information content= {"provider":{"name":"Amazon Music","logo":{"sources":[{"url":"https://s3.amazonaws.com/music-provider-logos/AmazonMusic.svg"}]}},"art":{"sources":[{"size":"large","url":"https://m.media-amazon.com/images/I/41SZUyrViuL._UL256_.jpg"},{"size":"x-large","url":"https://m.media-amazon.com/images/I/61v-D2RtAZL._UL600_.jpg"}]},"titleSubtext2":"Lost N Found","mediaLengthInMilliseconds":219000,"title":"Love U U","titleSubtext1":"JJ Lin"}
get User information header=
get User information provider name= Amazon Music
get User information url= https://m.media-amazon.com/images/I/61v-D2RtAZL._UL600_.jpg
get User information titleSubtext2= Lost N Found
get User information mediaLengthInMilliseconds= 219000
get User information title= Love U U
get User information titleSubtext1= JJ Lin
send companion app content= play_music:header=
title=Love U U
titleSubtext1=JJ Lin
titleSubtext2=Lost N Found
url=https://m.media-amazon.com/images/I/61v-D2RtAZL._UL600_.jpg
medialengthinmilliseconds=219000
WisCommon::sendUDPDataToSender senderIPAddress is null, so ignore this send.
*****************************current is sending current off set.
2018-01-02 10:28:57.411 [ 13] W DirectiveRouter:handleDirectiveWithPolicyHandleImmediatelyFailed:messageId=56b59577-d93a-4bbd-aa80-69dc58715dab,reason=noHandlerRegistered
2018-01-02 10:28:57.411 [ 13] I DirectiveRouter:preHandleDirective:UnparsedDirective={"directive"\:{"header"\:{"namespace"\:"TemplateRuntime"\,"name"\:"RenderPlayerInfo"\,"messageId"\:"56b59577-d93a-4bbd-aa80-69dc58715dab"}\,"payload"\:{"content"\:{"provider"\:{"name"\:"Amazon Music"\,"logo"\:{"sources"\:[{"url"\:"https\://s3.amazonaws.com/music-provider-logos/AmazonMusic.svg"}]}}\,"art"\:{"sources"\:[{"size"\:"large"\,"url"\:"https\://m.media-amazon.com/images/I/41SZUyrViuL._UL256_.jpg"}\,{"size"\:"x-large"\,"url"\:"https\://m.media-amazon.com/images/I/61v-D2RtAZL._UL600_.jpg"}]}\,"titleSubtext2"\:"Lost N Found"\,"mediaLengthInMilliseconds"\:219000\,"title"\:"Love U U"\,"titleSubtext1"\:"JJ Lin"}\,"audioItemId"\:"amzn1.as-ct.v1.Dee-Domain-Music#ACRI#url#ACRI#7d61189f-b07a-47c1-87c3-e19912036b73\:1"\,"controls"\:[{"selected"\:false\,"name"\:"PLAY_PAUSE"\,"enabled"\:true\,"type"\:"BUTTON"}\,{"selected"\:false\,"name"\:"PREVIOUS"\,"enabled"\:true\,"type"\:"BUTTON"}\,{"selected"\:false\,"name"\:"NEXT"\,"enabled"\:true\,"type"\:"BUTTON"}]}}}
2018-01-02 10:28:57.412 [ 13] W DirectiveRouter:preHandleDirectiveFailed:messageId=56b59577-d93a-4bbd-aa80-69dc58715dab,reason=noHandlerRegistered
2018-01-02 10:28:57.412 [ 13] I DirectiveSequencer:sendingExceptionEncountered:messageId=56b59577-d93a-4bbd-aa80-69dc58715dab
2018-01-02 10:28:57.481 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.482 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.482 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.482 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.548 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.549 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.549 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.549 [ 2] 9 AttachmentReaderSource:read:size=3098,status=0
2018-01-02 10:28:57.603 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.603 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.603 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.603 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.671 [ 1e] 9 LibCurlHttpContentFetcher:getContent:responseCode=200
2018-01-02 10:28:57.674 [ 1e] 9 LibCurlHttpContentFetcher:getContent:contentType=video/MP2T
2018-01-02 10:28:57.674 [ 18] 9 PlaylistParser:PlaylistParser:contentType=video/MP2T,length=10000
2018-01-02 10:28:57.674 [ 18] 9 PlaylistParser:foundNonPlaylistURL
2018-01-02 10:28:57.674 [ 18] 9 UrlContentToAttachmentConverter:onPlaylistEntryParsed:status=STILL_ONGOING
2018-01-02 10:28:57.679 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.679 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.679 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.679 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.743 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.744 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.744 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.744 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.798 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.799 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.799 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.799 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.869 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.870 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.870 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.870 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.935 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.935 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.935 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.935 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.981 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.982 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.982 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:57.982 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:58.043 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:58.045 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:58.046 [ 2] 9 AttachmentReaderSource:read:size=4096,status=0
2018-01-02 10:28:58.046 [ 2] 9 AttachmentReaderSource:read:size=3720,status=0
2018-01-02 10:28:58.047 [ 2] 9 BaseStreamSource:onEnoughDataCalled
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment