Here is flags which i set on each sample:
markerInfo |= PVMF_MEDIA_DATA_MARKER_INFO_DURATION_AVAILABLE_BIT;
markerInfo |= PVMF_MEDIA_DATA_MARKER_INFO_M_BIT;
if ( bIsKeyFrame ) {
markerInfo |=
PVMF_MEDIA_DATA_MARKER_INFO_RANDOM_ACCESS_POINT_BIT;
}
Here is mine log:
=====================================================================
VLOG:TID(0xd528):Time=4152:PVMFOMXVideoDecNode::Run() - Calling
HandleProcessingState
10-06 04:12:17.142: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4152:PVMFOMXVideoDecNode::HandleProcessingState() In
10-06 04:12:17.142: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4152:PVMFOMXVideoDecNode::HandleProcessingState() Ready
To Decode start
10-06 04:12:17.142: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4152:PVMFOMXVideoDecNode::SendInputBufferToOMXComponent
() In
10-06 04:12:17.142: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4152:PVMFOMXVideoDecNode::SendInputBufferToOMXComponent
() - Buffer 0x4057c080 of size 1055, 1 frag out of tot. 1, TS=3200
10-06 04:12:17.142: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4153:PVMFOMXVideoDecNode::SendInputBufferToOMXComponent
() - END OF MESSAGE - Buffer 0x4057c080 MARKER bit set to 1, TS=3200,
Ticks=L
10-06 04:12:17.142: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4153:PVMFOMXVideoDecNode::SendInputBufferToOMXComponent
() - Sending Buffer 0x4057c080 to OMX Component MARKER field set to
30, TS=3200, Ticks=L
10-06 04:12:17.142: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4154:PVMFOMXVideoDecNode::SendInputBufferToOMXComponent
() Out
10-06 04:12:17.142: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4154:PVMFOMXVideoDecNode::HandleProcessingState() Out
10-06 04:12:17.142: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4154:PVMFOMXVideoDecNode::Run() - LoopCount = 1, Time
spent in loop(in ms) = 3, iNumOutstandingInputBuffers = 4,
iNumOutstandingOutputBuffers = 2
10-06 04:12:17.142: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4154:PVMFOMXVideoDecNode::Run() Out
10-06 04:12:17.509: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4515:PVMFMediaClock::Run Timer for regular callback
currentTime - 1403 callbackTime - 1401 callbackMargin - 0 queue size -
2 status - 4
10-06 04:12:17.509: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4515:PVMediaOutputNodePort::ProcessCallBack In Callback
id [5] CallbackStatus [1]
10-06 04:12:17.509: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4516:PVMediaOutputNodePort::CheckMediaTimeStamp - OnTime
- Fmt=X-YUV-420, Seq=4, Ts=1600, Clock=1403
10-06 04:12:17.509: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4516:AndroidSurfaceOutput::writeAsync() seqnum 4 ts 1600
context 578476
10-06 04:12:17.509: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4516:AndroidSurfaceOutput::writeAsync() Format Type 1
Format Index 1 length 38016
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4527:AndroidSurfaceOutput::writeAsync: Playback Progress
- frame 4
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4528:PVMediaOutputNodePort::SendMediaData - AsyncWrite -
Fmt=X-YUV-420, Seq=4, TS=1600, Dur=0, FIdx=1, ClnUpQSize=2
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4528:PVMFMediaClock::CalculateRunLTimerValue currtime
1416 top.timeOut 2038
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4528:PVMFMediaClock::AdjustScheduling Timer set for 622
msecs wall clock time
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4528:PVMediaOutputNodePort::writeComplete status 1 cmdId
11 context 0x8d3ac
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4528:PVMediaOutputNodePort::writeComplete - Fmt=X-
YUV-420, Seq=3, TS=1200, FIdx=0, ClnUpQSize=1
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4528:PVMFOMXVideoDecNode::freechunkavailable() Memory
chunk in OUTPUT mempool was deallocated, 1 out of 2 now available
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4529:PVMFOMXVideoDecNode::Run() In
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4529:PVMFOMXVideoDecNode::Run() - Input commands empty
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4529:PVMFOMXVideoDecNode::Run() - Calling
HandleProcessingState
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4529:PVMFOMXVideoDecNode::HandleProcessingState() In
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4529:PVMFOMXVideoDecNode::HandleProcessingState() Ready
To Decode start
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4529:PVMFOMXVideoDecNode::SendOutputBufferToOMXComponent
() In
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4533:PVMFOMXVideoDecNode::SendOutputBufferToOMXComponent
() Out
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4533:PVMFOMXVideoDecNode::HandleProcessingState() Out
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4533:PVMFOMXVideoDecNode::Run() - LoopCount = 1, Time
spent in loop(in ms) = 4, iNumOutstandingInputBuffers = 4,
iNumOutstandingOutputBuffers = 2
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4533:PVMFOMXVideoDecNode::Run() Out
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4533:EmptyBufferDoneThreadSafeCallbackAO::Run() In
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4533:EmptyBufferDoneThreadSafeCallbackAO::Run() - No
more events, call PendForExec()
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4533:EmptyBufferDoneThreadSafeCallbackAO::Run() -
Calling Process Event
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4533:PVMFOMXVideoDecNode::EmptyBufferDoneProcessing: In
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4533:PVMFOMXVideoDecNode::EmptyBufferDoneProcessing:
Release input buffer with Ticks=2000000 (with 0 refcount remaining of
input message)
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID(0xd528):Time=4534:MEMP
MKVPar(Video) Chunk freed, 4/8 in use
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4534:PVMFOMXVideoDecNode::EmptyBufferDoneProcessing:
Release input buffer bc848 back to mempool - pointing to buffer
4067c0d8
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4534:PVMFOMXVideoDecNode::freechunkavailable() Memory
chunk in INPUT mempool was deallocated, 7 out of 10 now available
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4534:EmptyBufferDoneThreadSafeCallbackAO::Run() Out
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID(0xd528):Time=4534:*
CMKVParserNode::Run: Enter
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID(0xd528):Time=4536:*
CMKVParserNode::Run: Checking HandleTrackState = 4, cmd = 1
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID(0xd528):Time=4536:*
CMKVParserNode::Run(): ( iInterfaceState == EPVMFNodeStarted && !
FlushPending() )
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID(0xd528):Time=4536:*
CMKVParserNode::HandleTrackState(): Enter
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID(0xd528):Time=4536:*
CMKVParserNode::HandleTrackState(): Port ID = 0; Port State = 2
10-06 04:12:17.521: ERROR/PV(554): PVLOG:TID(0xd528):Time=4536:*
CMKVParserNode::RetrieveTrackData(): Enter
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4537:MEMP
MKVPar(Video) Chunk allocated, 4/8 in use
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4537:*
CMKVParserNode::RetrieveTrackData(): currTicks = 2710
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4537:*
CMKVParserNode::RetrieveTrackData(): startTime = 2710
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4537:*
CMKVParserNode::RetrieveTrackData(): currTicks = 2711
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4537:*
CMKVParserNode::RetrieveTrackData(): endTime = 2711
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4538:*
CMKVParserNode::RetrieveTrackData():
blockNode.allocatedSize = 1048576
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4538:*
CMKVParserNode::RetrieveTrackData(): ReadNextBlockByStream returned 0
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4538:*
CMKVParserNode::RetrieveTrackData(): blockNode size=867, type=2,
buf=0x4067c0d8
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4538:*
CMKVParserNode::RetrieveTrackData(): blockNode absStartTime=36000000,
blkPeriod=4000000
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4539:*
CMKVParserNode::RetrieveTrackData(): blockNode.flags = 0
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4539:*
CMKVParserNode::RetrieveTrackData(): blockNode.blockBuffer = 0x00,
0x00, 0x01, 0xb6
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4539:*
CMKVParserNode::RetrieveTrackData(): Exit
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4539:*
CMKVParserNode::DeliverMediaSample(): Enter
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4539:*
CMKVParserNode::DeliverMediaSample(): before
aTrackPortInfo.m_MediaData->setFormatSpecificInfo
(aTrackPortInfo.m_FormatSpecificConfig)
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4539:*
CMKVParserNode::DeliverMediaSample(): aTrackPortInfo.m_MediaData =
0x0008d028
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4540:*
CMKVParserNode::DeliverMediaSample(): m_MediaData refcounter =
0x0008bfd8
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4540:*
CMKVParserNode::DeliverMediaSample(): before creating mediaMsgOut
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4540:0x85150
PvmfPortBaseImpl::QueueOutgoingMsg
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4540:0x98db0
PvmfPortBaseImpl::Receive
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4540:PORT
OMXVideoDecIn In Msg Received MediaData SeqNum 9, SId 0, TS 3600, Q-
depth 1/10
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4540:0x662d0
PVMFOMXBaseDecNode::PortActivity: port=0x98db0, type=5
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4540:PVMFOMXVideoDecNode::PortActivity:
IncomingMsgQueueSize=1
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4541:PORT
MKVParOut(Video) Msg Sent Directly MediaData SeqNum 9, SId 0, TS 3600,
Q-depth 0/10
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4541:*
CMKVParserNode::DeliverMediaSample(): before
aTrackPortInfo.m_MediaData.Unbind()
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4541:*
CMKVParserNode::DeliverMediaSample(): Exit = true
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4541:*
CMKVParserNode::HandleTrackState(): Exit
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4541:*
CMKVParserNode::Run: Exit
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4542:*
CMKVParserNode::Run: Enter
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4542:*
CMKVParserNode::Run: Checking HandleTrackState = 4, cmd = 1
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4542:*
CMKVParserNode::Run(): ( iInterfaceState == EPVMFNodeStarted && !
FlushPending() )
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4542:*
CMKVParserNode::HandleTrackState(): Enter
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4542:*
CMKVParserNode::HandleTrackState(): Port ID = 0; Port State = 2
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4543:*
CMKVParserNode::RetrieveTrackData(): Enter
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4543:*
CMKVParserNode::RetrieveTrackData(): Exit = FAILED -
mediaDataImplOut.GetRep == NULL
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4543:*
CMKVParserNode::HandleTrackState(): Exit
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4543:*
CMKVParserNode::Run: Exit
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4543:FillBufferDoneThreadSafeCallbackAO::Run() In
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4543:FillBufferDoneThreadSafeCallbackAO::Run() - No more
events, call PendForExec()
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4543:FillBufferDoneThreadSafeCallbackAO::Run() - Calling
Process Event
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4543:PVMFOMXVideoDecNode::FillBufferDoneProcessing: In
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4544:PVMFOMXVideoDecNode::FillBufferDoneProcessing:
Output frame 5 received
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4544:PVMFOMXVideoDecNode::FillBufferDoneProcessing:
Wrapping buffer 40785010 of size 38016
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4544:PVMFOMXVideoDecNode::QueueOutputFrame: In
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4544::PVMFOMXVideoDecNode::QueueOutputFrame(): -
SeqNum=6, TS=2000
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4544:0x9bea8
PvmfPortBaseImpl::QueueOutgoingMsg
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4544:0x8d1ec
PvmfPortBaseImpl::Receive
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4544:PORT
MediaOutIn(Video) In Msg Received MediaData SeqNum 5, SId 0, TS 2000,
Q-depth 1/10
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4544:0x8d1ec
PvmfPortBaseImpl::DequeueIncomingMsg
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4545:PORT
MediaOutIn(Video) In Msg De-Q'd MediaData SeqNum 5, SId 0, TS 2000, Q-
depth 0/10
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4545:PVMediaOutputNodePort::HPA - MediaMsg Recvd -
Seq=5, TS=2000, Fmt=X-YUV-420, Qs=0
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4545:PVMediaOutputNodePort::CheckMediaTimeStamp - Early
- Fmt=X-YUV-420, Seq=5, Ts=2000, Clock=1433, DeltaInMS=367,
ClkRate=100000, DeltaInClkUnits=367
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4545:PVMFMediaClock::SetCallbackCommon Setting regular
callback for time 1800 at time 1433
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4545:PVMFMediaClock::CalculateRunLTimerValue currtime
1433 top.timeOut 1800
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4546:PVMFMediaClock::AdjustScheduling Timer set for 367
msecs wall clock time
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID(0xd528):Time=4546:PORT
OMXVideoDecOut Msg Sent Directly MediaData SeqNum 5, SId 0, TS 2000, Q-
depth 0/10
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4546:PVMFOMXVideoDecNode::QueueOutputFrame(): Queued
frame OK
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4546:PVMFOMXVideoDecNode::FillBufferDoneProcessing:
Buffer 40785010 of size 38016 queued - reschedule the node to send out
10-06 04:12:17.531: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4546:FillBufferDoneThreadSafeCallbackAO::Run() Out
10-06 04:12:17.540: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4557:PVMFOMXVideoDecNode::Run() In
10-06 04:12:17.540: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4557:PVMFOMXVideoDecNode::Run() - Input commands empty
10-06 04:12:17.540: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4557:PVMFOMXVideoDecNode::Run() - Getting more input
10-06 04:12:17.540: ERROR/PV(554): PVLOG:TID(0xd528):Time=4557:0x662d0
PVMFOMXVideoDecNode::ProcessIncomingMsg: aPort=0x98db0
10-06 04:12:17.540: ERROR/PV(554): PVLOG:TID(0xd528):Time=4558:0x98db0
PvmfPortBaseImpl::DequeueIncomingMsg
10-06 04:12:17.540: ERROR/PV(554): PVLOG:TID(0xd528):Time=4558:PORT
OMXVideoDecIn In Msg De-Q'd MediaData SeqNum 9, SId 0, TS 3600, Q-
depth 0/10
10-06 04:12:17.540: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4558:PVMFOMXVideoDecNode::ProcessIncomingMsg() Received
11 frames
10-06 04:12:17.552: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4559:PVMFOMXVideoDecNode::Run() - Calling
HandleProcessingState
10-06 04:12:17.552: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4559:PVMFOMXVideoDecNode::HandleProcessingState() In
10-06 04:12:17.552: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4559:PVMFOMXVideoDecNode::HandleProcessingState() Ready
To Decode start
10-06 04:12:17.552: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4559:PVMFOMXVideoDecNode::SendInputBufferToOMXComponent
() In
10-06 04:12:17.552: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4559:PVMFOMXVideoDecNode::SendInputBufferToOMXComponent
() - Buffer 0x4067c0d8 of size 867, 1 frag out of tot. 1, TS=3600
10-06 04:12:17.552: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4559:PVMFOMXVideoDecNode::SendInputBufferToOMXComponent
() - END OF MESSAGE - Buffer 0x4067c0d8 MARKER bit set to 1, TS=3600,
Ticks=L
10-06 04:12:17.552: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4560:PVMFOMXVideoDecNode::SendInputBufferToOMXComponent
() - Sending Buffer 0x4067c0d8 to OMX Component MARKER field set to
30, TS=3600, Ticks=L
10-06 04:12:17.552: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4561:PVMFOMXVideoDecNode::SendInputBufferToOMXComponent
() Out
10-06 04:12:17.552: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4561:PVMFOMXVideoDecNode::HandleProcessingState() Out
10-06 04:12:17.552: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4561:PVMFOMXVideoDecNode::Run() - LoopCount = 1, Time
spent in loop(in ms) = 4, iNumOutstandingInputBuffers = 4,
iNumOutstandingOutputBuffers = 2
10-06 04:12:17.552: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4562:PVMFOMXVideoDecNode::Run() Out
10-06 04:12:17.681: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4695:PVPlayerEngine::GetPVPlayerStateSync()
10-06 04:12:17.681: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4695:PVPlayerEngine::DoGetPVPlayerState() In
10-06 04:12:17.681: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4696:PVPlayerEngine::DoGetPVPlayerState() Out
10-06 04:12:17.681: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4696:PVPlayerEngine::GetPVPlayerStateSync()
10-06 04:12:17.681: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4696:PVPlayerEngine::DoGetPVPlayerState() In
10-06 04:12:17.681: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4696:PVPlayerEngine::DoGetPVPlayerState()
Out
10-06 04:12:17.681: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4696:PVPlayerEngine::SetPlaybackRange()
10-06 04:12:17.681: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4696:PVPlayerEngine::ConvertFromMillisec() In
10-06 04:12:17.681: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4696:PVPlayerEngine::ConvertFromMillisec() Out
10-06 04:12:17.681: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4697:PVPlayerEngine::AddCommandToQueue() In CmdType 18,
CmdId 8
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4697:PVPlayerEngine::AddCommandToQueue() Type=18 ID=8
APIcmd=1 Tick=4697
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4697:PVPlayerEngine::Run() In
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4697:PVPlayerEngine::Run() Processing command with
type=18
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4698:PVPlayerEngine::DoSetPlaybackRange() Tick=4698
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4698:PVPlayerEngine::DoSetPlaybackRange() In
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4698:PVPlayerEngine::UpdateCurrentEndPosition() In
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4698:PVPlayerEngine::UpdateCurrentEndPosition() Out
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4698:PVPlayerEngine::UpdateCurrentBeginPosition() In
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4698:PVPlayerEngine::ConvertToMillisec() In
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4698:PVPlayerEngine::ConvertToMillisec() Out
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4699:PVPlayerEngine::UpdateCurrentBeginPosition()
Requested begin position is 0 ms
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4699:PVPlayerEngine::DoChangePlaybackPosition() In
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4699:PVPlayerEngine::DoSourceNodeSetDataSourcePositionDuringPlayback
() In
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID(0xd528):Time=4699:*
CMKVParserNode::ClockStateUpdated: Enter
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID(0xd528):Time=4699:*
CMKVParserNode::ClockStateUpdated: Exit
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4699:PVMediaOutputNodePort::ClockStateUpdated: Clock
Paused - Fmt=X-YUV-420
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4700:PVMediaOutputNodePort::ClockStateUpdated: Clock
Paused - Fmt=X-YUV-420
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4700:PVMFMediaClock::CalculateRunLTimerValue currtime
1587 top.timeOut 2038
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4700:PVMFMediaClock::AdjustScheduling Timer set for 451
msecs wall clock time
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4700:PVPlayerEngine::DoSourceNodeSetDataSourcePositionDuringPlayback
() Calling SetDataSourcePosition() on source node. TargetNPT 0 ms,
SeekToSyncPoint 1
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4701:PVPlayerEngine::DoSourceNodeSetDataSourcePositionDuringPlayback
() SetDataSourcePosition on iSourceNodePBCtrlIF - TargetNPT=0,
SeekToSyncPoint=1
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID(0xd528):Time=4701:*
CMKVParserNode::SetDataSourcePosition: Enter
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID(0xd528):Time=4701:*
CMKVParserNode::SetDataSourcePosition: Exit
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID(0xd528):Time=4701:*
CMKVParserNode::QueueCommandL: Enter
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID(0xd528):Time=4701:*
CMKVParserNode::QueueCommandL: command id = 1015
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID(0xd528):Time=4702:*
CMKVParserNode::QueueCommandL: Exit = OK
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4702:PVPlayerEngine::DoSourceNodeSetDataSourcePositionDuringPlayback
() Out
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4702:PVPlayerEngine::UpdateCurrentBeginPosition() Out
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4702:PVPlayerEngine::DoSetPlaybackRange() Out
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4702:PVPlayerEngine::Run() Out
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID(0xd528):Time=4702:*
CMKVParserNode::Run: Enter
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID(0xd528):Time=4702:*
CMKVParserNode::Run(): !m_InputCommands.empty()
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID(0xd528):Time=4703:*
CMKVParserNode::ProcessCommand: Enter
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID(0xd528):Time=4703:*
CMKVParserNode::ProcessCommand: command ID: 13
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID(0xd528):Time=4703:*
CMKVParserNode::DoSetDataSourcePosition(): Enter
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID(0xd528):Time=4703:*
CMKVParserNode::SetPosition(): Enter
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID(0xd528):Time=4703:*
CMKVParserNode::SetPosition(): Exit OK
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID(0xd528):Time=4704:*
CMKVParserNode::DoSetDataSourcePosition(): Exit: Current: 0
ActualNPT: 0 Target: 0
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID(0xd528):Time=4704:*
CMKVParserNode::ProcessCommand: MKV_NODE_CMD_SETDATASOURCEPOSITION =
SUCCESS
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID(0xd528):Time=4704:*
CMKVParserNode::CommandComplete: Enter
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID(0xd528):Time=4704:*
CMKVParserNode::CommandComplete: command ID = 13
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID(0xd528):Time=4704:*
CMKVParserNode::CommandComplete: bIsCompleteCancel = 0
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID(0xd528):Time=4704:*
CMKVParserNode::CommandComplete: create response
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID(0xd528):Time=4705:*
CMKVParserNode::CommandComplete: erase command from the queue
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID(0xd528):Time=4705:*
CMKVParserNode::CommandComplete: call ReportCmdCompleteEvent()
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4705:PVPlayerEngine::NodeCommandCompleted() In
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4705:PVPlayerEngine::HandleSourceNodeSetDataSourcePositionDuringPlayback
() In
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4705:PVPlayerEngine::HandleSourceNodeSetDataSourcePositionDuringPlayback
() SetDataSourcePosition() successful. StartMediaTS 0 ms, ActualPBPos
0 ms
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4705:PVPlayerEngine::HandleSourceNodeSetDataSourcePositionDuringPlayback
() After adjustment StartMediaTS 0 ms, AdjustedMediaTS 0 ms,
ActualPBPos 0 ms Clock 1587 ms
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4706:PVPlayerEngine::DoSinkNodeSkipMediaDataDuringPlayback
() Tick=4706
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4706:PVPlayerEngine::DoSinkNodeSkipMediaDataDuringPlayback
() In
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4706:PVPlayerEngine::DoSinkNodeSkipMediaDataDuringPlayback
() Calling SkipMediaData() on sink nodes. MediadataTS to flush to 0
ms, MediadataTS to skip to 0 ms
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4706:PVPlayerEngine::DoSinkNodeSkipMediaDataDuringPlayback
() Calling SkipMediaData() on sink nodes. MediadataTS to flush to 0
ms, MediadataTS to skip to 0 ms
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4706:PVMediaOutputNode::SkipMediaData() called
10-06 04:12:17.691: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4706:PVMediaOutputNode::SkipMediaData() called - Mime=X-
YUV-420
10-06 04:12:17.701: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4707:PVPlayerEngine::DoSinkNodeSkipMediaDataDuringPlayback
() Calling SkipMediaData() on sink nodes. MediadataTS to flush to 0
ms, MediadataTS to skip to 0 ms
10-06 04:12:17.701: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4714:PVPlayerEngine::DoSinkNodeSkipMediaDataDuringPlayback
() Calling SkipMediaData() on sink nodes. MediadataTS to flush to 0
ms, MediadataTS to skip to 0 ms
10-06 04:12:17.701: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4714:PVPlayerEngine::DoSinkNodeSkipMediaDataDuringPlayback
() Out
10-06 04:12:17.701: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4714:PVPlayerEngine::HandleSourceNodeSetDataSourcePositionDuringPlayback
() Out
10-06 04:12:17.701: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4714:PVPlayerEngine::NodeCommandCompleted() Out
10-06 04:12:17.701: ERROR/PV(554): PVLOG:TID(0xd528):Time=4715:*
CMKVParserNode::CommandComplete: Exit
10-06 04:12:17.701: ERROR/PV(554): PVLOG:TID(0xd528):Time=4716:*
CMKVParserNode::ProcessCommand: Exit
10-06 04:12:17.701: ERROR/PV(554): PVLOG:TID(0xd528):Time=4716:*
CMKVParserNode::Run: Checking HandleTrackState = 4, cmd = 1
10-06 04:12:17.701: ERROR/PV(554): PVLOG:TID(0xd528):Time=4716:*
CMKVParserNode::Run(): ( iInterfaceState == EPVMFNodeStarted && !
FlushPending() )
10-06 04:12:17.701: ERROR/PV(554): PVLOG:TID(0xd528):Time=4716:*
CMKVParserNode::HandleTrackState(): Enter
10-06 04:12:17.701: ERROR/PV(554): PVLOG:TID(0xd528):Time=4716:*
CMKVParserNode::HandleTrackState(): Port ID = 0; Port State = 4
10-06 04:12:17.711: ERROR/PV(554): PVLOG:TID(0xd528):Time=4716:*
CMKVParserNode::HandleTrackState(): Exit
10-06 04:12:17.711: ERROR/PV(554): PVLOG:TID(0xd528):Time=4717:*
CMKVParserNode::Run: Exit
10-06 04:12:17.711: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4717:PVMediaOutputNode::DoSkipMediaData - Mime=X-
YUV-420, SkipTS=0, StreamID=1, SFR=0
10-06 04:12:17.711: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4717:PVMediaOutputNodePort::SetSkipTimeStamp: TS=0,
Fmt=X-YUV-420
10-06 04:12:17.711: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4717:PVMediaOutputNodePort::SetSkipTimeStamp: TS=0,
Fmt=X-YUV-420
10-06 04:12:17.711: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4717:PVMediaOutputNodePort::SetSkipTimeStamp: MsgSkip -
StreamId=0, Seq=5, TS=2000, Fmt=X-YUV-420
10-06 04:12:17.711: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4717:PVMediaOutputNodePort::SetSkipTimeStamp: MsgSkip -
StreamId=0, Seq=5, TS=2000, Fmt=X-YUV-420
10-06
04:12:17.711: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4718:PVMFOMXVideoDecNode::freechunkavailable() Memory
chunk in OUTPUT mempool was deallocated, 1 out of 2 now available
10-06 04:12:17.711: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4718:PVMediaOutputNode::DoSkipMediaData - Calling
DiscardData - Mime=X-YUV-420
10-06 04:12:17.711: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4718:PVMediaOutputNode::SendMioRequest(EDiscard):
skipTimestamp=0
10-06 04:12:17.711: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4718:AndroidSurfaceOutput::DiscardData() called
10-06 04:12:17.711: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4718:PVMediaOutputNodePort::writeComplete status 1 cmdId
12 context 0x8d3ac
10-06 04:12:17.711: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4718:PVMediaOutputNodePort::writeComplete - Fmt=X-
YUV-420, Seq=4, TS=1600, FIdx=0, ClnUpQSize=0
10-06 04:12:17.711: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4718:PVMFOMXVideoDecNode::freechunkavailable() Memory
chunk in OUTPUT mempool was deallocated, 2 out of 2 now available
10-06 04:12:17.711: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4719:PVMediaOutputNode:SendMIORequest: Command Issued to
MIO component, waiting on response...
10-06 04:12:17.711: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4719:PVMediaOutputNode::RequestCompleted: Cmd ID=13
10-06 04:12:17.711: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4719:PVMediaOutputNode::ResuestCompleted - EDiscard
success - Mime=X-YUV-420
10-06 04:12:17.711: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4719:PVMFOMXVideoDecNode::Run() In
10-06 04:12:17.711: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4719:PVMFOMXVideoDecNode::Run() - Input commands empty
10-06 04:12:17.711: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4719:PVMFOMXVideoDecNode::Run() - Calling
HandleProcessingState
10-06 04:12:17.711: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4719:PVMFOMXVideoDecNode::HandleProcessingState() In
10-06 04:12:17.711: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4719:PVMFOMXVideoDecNode::HandleProcessingState() Ready
To Decode start
10-06 04:12:17.711: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4720:PVMFOMXVideoDecNode::SendOutputBufferToOMXComponent
() In
10-06 04:12:17.711: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4722:PVMFOMXVideoDecNode::SendOutputBufferToOMXComponent
() Out
10-06 04:12:17.711: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4722:PVMFOMXVideoDecNode::SendOutputBufferToOMXComponent
() In
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4729:PVMFOMXVideoDecNode::SendOutputBufferToOMXComponent
() Out
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4730:PVMFOMXVideoDecNode::HandleProcessingState() Out
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4730:PVMFOMXVideoDecNode::Run() - LoopCount = 1, Time
spent in loop(in ms) = 11, iNumOutstandingInputBuffers = 4,
iNumOutstandingOutputBuffers = 2
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4730:PVMFOMXVideoDecNode::Run() Out
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4730:EmptyBufferDoneThreadSafeCallbackAO::Run() In
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4730:EmptyBufferDoneThreadSafeCallbackAO::Run() -
Calling Process Event
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4730:PVMFOMXVideoDecNode::EmptyBufferDoneProcessing: In
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4730:PVMFOMXVideoDecNode::EmptyBufferDoneProcessing:
Release input buffer with Ticks=2400000 (with 0 refcount remaining of
input message)
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4731:MEMP
MKVPar(Video) Chunk freed, 4/8 in use
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4731:PVMFOMXVideoDecNode::EmptyBufferDoneProcessing:
Release input buffer bc868 back to mempool - pointing to buffer
407c9080
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4731:PVMFOMXVideoDecNode::freechunkavailable() Memory
chunk in INPUT mempool was deallocated, 7 out of 10 now available
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4731:EmptyBufferDoneThreadSafeCallbackAO::Run() - No
more events, call PendForExec()
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4731:EmptyBufferDoneThreadSafeCallbackAO::Run() -
Calling Process Event
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4731:PVMFOMXVideoDecNode::EmptyBufferDoneProcessing: In
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4731:PVMFOMXVideoDecNode::EmptyBufferDoneProcessing:
Release input buffer with Ticks=2800000 (with 0 refcount remaining of
input message)
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4731:MEMP
MKVPar(Video) Chunk freed, 3/8 in use
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4732:PVMFOMXVideoDecNode::EmptyBufferDoneProcessing:
Release input buffer bc878 back to mempool - pointing to buffer
408c90d8
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4732:PVMFOMXVideoDecNode::freechunkavailable() Memory
chunk in INPUT mempool was deallocated, 8 out of 10 now available
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4732:EmptyBufferDoneThreadSafeCallbackAO::Run() Out
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4732:*
CMKVParserNode::Run: Enter
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4732:*
CMKVParserNode::Run: Checking HandleTrackState = 4, cmd = 1
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4732:*
CMKVParserNode::Run(): ( iInterfaceState == EPVMFNodeStarted && !
FlushPending() )
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4733:*
CMKVParserNode::HandleTrackState(): Enter
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4733:*
CMKVParserNode::HandleTrackState(): Port ID = 0; Port State = 2
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4733:*
CMKVParserNode::DeliverDiscontinuty(): Enter
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4733:0x85150
PvmfPortBaseImpl::QueueOutgoingMsg
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4733:0x98db0
PvmfPortBaseImpl::Receive
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4733:PORT
OMXVideoDecIn In Msg Received MediaCmd FmtId BOS, SeqNum 10, SId 0,
TS 0, Q-depth 1/10
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4733:0x662d0
PVMFOMXBaseDecNode::PortActivity: port=0x98db0, type=5
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4734:PVMFOMXVideoDecNode::PortActivity:
IncomingMsgQueueSize=1
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4734:PORT
MKVParOut(Video) Msg Sent Directly MediaCmd FmtId BOS, SeqNum 10, SId
0, TS 0, Q-depth 0/10
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4734:*
CMKVParserNode::DeliverDiscontinuty(): Exit = true
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4734:*
CMKVParserNode::RetrieveTrackData(): Enter
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4734:MEMP
MKVPar(Video) Chunk allocated, 3/8 in use
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4734:*
CMKVParserNode::RetrieveTrackData(): currTicks = 2908
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4735:*
CMKVParserNode::RetrieveTrackData(): startTime = 2908
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4735:*
CMKVParserNode::RetrieveTrackData(): currTicks = 2908
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4735:*
CMKVParserNode::RetrieveTrackData(): endTime = 2908
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4735:*
CMKVParserNode::RetrieveTrackData(): blockNode.allocatedSize = 1048576
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4736:*
CMKVParserNode::RetrieveTrackData(): ReadNextBlockByStream returned 0
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4736:*
CMKVParserNode::RetrieveTrackData(): blockNode size=2011, type=2,
buf=0x407c9080
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4736:*
CMKVParserNode::RetrieveTrackData(): blockNode absStartTime=0,
blkPeriod=4000000
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4736:*
CMKVParserNode::RetrieveTrackData(): blockNode.flags = 16
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4737:*
CMKVParserNode::RetrieveTrackData(): blockNode.blockBuffer = 0x00,
0x00, 0x01, 0xb6
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4737:*
CMKVParserNode::RetrieveTrackData(): Exit
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4737:*
CMKVParserNode::DeliverMediaSample(): Enter
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4737:*
CMKVParserNode::DeliverMediaSample(): before
aTrackPortInfo.m_MediaData->setFormatSpecificInfo
(aTrackPortInfo.m_FormatSpecificConfig)
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4737:*
CMKVParserNode::DeliverMediaSample(): aTrackPortInfo.m_MediaData =
0x0008d028
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4737:*
CMKVParserNode::DeliverMediaSample(): m_MediaData refcounter =
0x0008be58
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4738:*
CMKVParserNode::DeliverMediaSample(): before creating mediaMsgOut
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4738:0x85150
PvmfPortBaseImpl::QueueOutgoingMsg
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4738:0x98db0
PvmfPortBaseImpl::Receive
10-06 04:12:17.720: ERROR/PV(554): PVLOG:TID(0xd528):Time=4738:PORT
OMXVideoDecIn In Msg Received MediaData SeqNum 10, SId 0, TS 0, Q-
depth 2/10
10-06 04:12:17.732: ERROR/PV(554): PVLOG:TID(0xd528):Time=4743:0x662d0
PVMFOMXBaseDecNode::PortActivity: port=0x98db0, type=5
10-06 04:12:17.732: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4743:PVMFOMXVideoDecNode::PortActivity:
IncomingMsgQueueSize=2
10-06 04:12:17.732: ERROR/PV(554): PVLOG:TID(0xd528):Time=4743:PORT
MKVParOut(Video) Msg Sent Directly MediaData SeqNum 10, SId 0, TS 0, Q-
depth 0/10
10-06 04:12:17.732: ERROR/PV(554): PVLOG:TID(0xd528):Time=4744:*
CMKVParserNode::DeliverMediaSample(): before
aTrackPortInfo.m_MediaData.Unbind()
10-06 04:12:17.732: ERROR/PV(554): PVLOG:TID(0xd528):Time=4744:*
CMKVParserNode::DeliverMediaSample(): Exit = true
10-06 04:12:17.732: ERROR/PV(554): PVLOG:TID(0xd528):Time=4745:*
CMKVParserNode::HandleTrackState(): Exit
10-06 04:12:17.732: ERROR/PV(554): PVLOG:TID(0xd528):Time=4745:*
CMKVParserNode::Run: Exit
10-06 04:12:17.732: ERROR/PV(554): PVLOG:TID(0xd528):Time=4745:*
CMKVParserNode::Run: Enter
10-06 04:12:17.732: ERROR/PV(554): PVLOG:TID(0xd528):Time=4745:*
CMKVParserNode::Run: Checking HandleTrackState = 4, cmd = 1
10-06 04:12:17.732: ERROR/PV(554): PVLOG:TID(0xd528):Time=4745:*
CMKVParserNode::Run(): ( iInterfaceState == EPVMFNodeStarted && !
FlushPending() )
10-06 04:12:17.732: ERROR/PV(554): PVLOG:TID(0xd528):Time=4746:*
CMKVParserNode::HandleTrackState(): Enter
10-06 04:12:17.732: ERROR/PV(554): PVLOG:TID(0xd528):Time=4746:*
CMKVParserNode::HandleTrackState(): Port ID = 0; Port State = 2
10-06 04:12:17.732: ERROR/PV(554): PVLOG:TID(0xd528):Time=4746:*
CMKVParserNode::RetrieveTrackData(): Enter
10-06 04:12:17.732: ERROR/PV(554): PVLOG:TID(0xd528):Time=4746:MEMP
MKVPar(Video) Chunk allocated, 4/8 in use
10-06 04:12:17.732: ERROR/PV(554): PVLOG:TID(0xd528):Time=4746:*
CMKVParserNode::RetrieveTrackData(): currTicks = 2920
10-06 04:12:17.732: ERROR/PV(554): PVLOG:TID(0xd528):Time=4747:*
CMKVParserNode::RetrieveTrackData(): startTime = 2920
10-06 04:12:17.732: ERROR/PV(554): PVLOG:TID(0xd528):Time=4747:*
CMKVParserNode::RetrieveTrackData(): currTicks = 2920
10-06 04:12:17.732: ERROR/PV(554): PVLOG:TID(0xd528):Time=4747:*
CMKVParserNode::RetrieveTrackData(): endTime = 2920
10-06 04:12:17.732: ERROR/PV(554): PVLOG:TID(0xd528):Time=4747:*
CMKVParserNode::RetrieveTrackData(): blockNode.allocatedSize = 1048576
10-06 04:12:17.732: ERROR/PV(554): PVLOG:TID(0xd528):Time=4747:*
CMKVParserNode::RetrieveTrackData(): ReadNextBlockByStream returned 0
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4748:*
CMKVParserNode::RetrieveTrackData(): blockNode size=1187, type=2,
buf=0x408c90d8
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4748:*
CMKVParserNode::RetrieveTrackData(): blockNode absStartTime=4000000,
blkPeriod=4000000
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4748:*
CMKVParserNode::RetrieveTrackData(): blockNode.flags = 0
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4748:*
CMKVParserNode::RetrieveTrackData(): blockNode.blockBuffer = 0x00,
0x00, 0x01, 0xb6
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4749:*
CMKVParserNode::RetrieveTrackData(): Exit
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4749:*
CMKVParserNode::DeliverMediaSample(): Enter
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4749:*
CMKVParserNode::DeliverMediaSample(): before
aTrackPortInfo.m_MediaData->setFormatSpecificInfo
(aTrackPortInfo.m_FormatSpecificConfig)
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4749:*
CMKVParserNode::DeliverMediaSample(): aTrackPortInfo.m_MediaData =
0x0008d028
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4749:*
CMKVParserNode::DeliverMediaSample(): m_MediaData refcounter =
0x0008bed8
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4749:*
CMKVParserNode::DeliverMediaSample(): before creating mediaMsgOut
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4750:0x85150
PvmfPortBaseImpl::QueueOutgoingMsg
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4750:0x98db0
PvmfPortBaseImpl::Receive
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4750:PORT
OMXVideoDecIn In Msg Received MediaData SeqNum 11, SId 0, TS 400, Q-
depth 3/10
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4750:0x662d0
PVMFOMXBaseDecNode::PortActivity: port=0x98db0, type=5
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4750:PVMFOMXVideoDecNode::PortActivity:
IncomingMsgQueueSize=3
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4750:PORT
MKVParOut(Video) Msg Sent Directly MediaData SeqNum 11, SId 0, TS 400,
Q-depth 0/10
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4750:*
CMKVParserNode::DeliverMediaSample(): before
aTrackPortInfo.m_MediaData.Unbind()
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4751:*
CMKVParserNode::DeliverMediaSample(): Exit = true
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4751:*
CMKVParserNode::HandleTrackState(): Exit
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4751:*
CMKVParserNode::Run: Exit
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4751:*
CMKVParserNode::Run: Enter
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4751:*
CMKVParserNode::Run: Checking HandleTrackState = 4, cmd = 1
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4751:*
CMKVParserNode::Run(): ( iInterfaceState == EPVMFNodeStarted && !
FlushPending() )
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4752:*
CMKVParserNode::HandleTrackState(): Enter
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4752:*
CMKVParserNode::HandleTrackState(): Port ID = 0; Port State = 2
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4752:*
CMKVParserNode::RetrieveTrackData(): Enter
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4752:*
CMKVParserNode::RetrieveTrackData(): Exit = FAILED -
mediaDataImplOut.GetRep == NULL
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4752:*
CMKVParserNode::HandleTrackState(): Exit
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4753:*
CMKVParserNode::Run: Exit
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4753:FillBufferDoneThreadSafeCallbackAO::Run() In
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4753:FillBufferDoneThreadSafeCallbackAO::Run() - Calling
Process Event
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4753:PVMFOMXVideoDecNode::FillBufferDoneProcessing: In
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4753:PVMFOMXVideoDecNode::FillBufferDoneProcessing:
Output frame 6 received
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4753:PVMFOMXVideoDecNode::FillBufferDoneProcessing:
Wrapping buffer 4078e498 of size 38016
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4753:PVMFOMXVideoDecNode::QueueOutputFrame: In
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4753::PVMFOMXVideoDecNode::QueueOutputFrame(): -
SeqNum=7, TS=2400
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4754:0x9bea8
PvmfPortBaseImpl::QueueOutgoingMsg
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4754:0x8d1ec
PvmfPortBaseImpl::Receive
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4754:PORT
MediaOutIn(Video) In Msg Received MediaData SeqNum 6, SId 0, TS 2400,
Q-depth 1/10
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4754:0x8d1ec
PvmfPortBaseImpl::DequeueIncomingMsg
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4754:PORT
MediaOutIn(Video) In Msg De-Q'd MediaData SeqNum 6, SId 0, TS 2400, Q-
depth 0/10
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4754:PVMediaOutputNodePort::HPA: MsgSkip - StreamId=0,
Seq=6, TS=2400, Fmt=X-YUV-420, Qs=0
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4755:PVMediaOutputNodePort::HPA: MsgSkip - StreamId=0,
Seq=6, TS=2400, Fmt=X-YUV-420, Qs=0
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID(0xd528):Time=4755:PORT
OMXVideoDecOut Msg Sent Directly MediaData SeqNum 6, SId 0, TS 2400, Q-
depth 0/10
10-06 04:12:17.742: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4755:PVMFOMXVideoDecNode::QueueOutputFrame(): Queued
frame OK
10-06 04:12:17.771: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4785:EmptyBufferDoneThreadSafeCallbackAO::Run() -
Calling Process Event
10-06 04:12:17.771: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4785:PVMFOMXVideoDecNode::EmptyBufferDoneProcessing: In
10-06 04:12:17.771: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4785:PVMFOMXVideoDecNode::EmptyBufferDoneProcessing:
Input buffer returned non-empty with 867 bytes still in it
10-06 04:12:17.771: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4786:PVMFOMXVideoDecNode::EmptyBufferDoneProcessing:
Release input buffer with Ticks=3600000 (with 0 refcount remaining of
input message)
10-06 04:12:17.771: ERROR/PV(554): PVLOG:TID(0xd528):Time=4786:MEMP
MKVPar(Video) Chunk freed, 3/8 in use
10-06 04:12:17.771: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4786:PVMFOMXVideoDecNode::EmptyBufferDoneProcessing:
Release input buffer bc848 back to mempool - pointing to buffer
4067c0d8
10-06 04:12:17.771: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4786:PVMFOMXVideoDecNode::freechunkavailable() Memory
chunk in INPUT mempool was deallocated, 10 out of 10 now available
10-06 04:12:17.771: ERROR/PV(554): PVLOG:TID
(0xd528):Time=4786:EmptyBufferDoneThreadSafeCallbackAO::Run() Out
=====================================================================
--
Best Regards,
Yuri
On Oct 6, 10:56 am, shadow <yuri.bul...@gmail.com> wrote:
> Hi RaviY,
>
> Thanks for the fast response. I've one question regarding
> timestamps:
> 1) How they could be always increased in case of I,P,B stream, for
> example?
> 2) What timestamp should i set on the very first sample, after seeking
> on the zero position? i would suppose 0, even the last timestamp
> before seeking was 4040, am i right?
>
> PS: i add the logs in the next post... hope they help you to help
> me :)
>
> --
> Best regards,
> Yuri
>
> On Oct 5, 6:51 pm, RaviY <yend...@pv.com> wrote:
>
> > There isn't enough information here to help. I can suggest looking at
> > the following things:
> > (a) The media timestamps sent by your splitter node -- They should
> > always be increasing.
> > (b) Make sure that the marker info masks (pvmi/pvmf/include/
> > pvmf_media_data.h) are set correctly.
> > (c) Getting (or sharing) logs would help you debug the problem much
> > more quickly.
>
> > -Ravi
>
> > On Oct 5, 2:17 am, shadow <yuri.bul...@gmail.com> wrote:
>
> > > Hi All,
>
> > > I've faced the issue with seeking in my source (splitter) node...
> > > after setposition() has been called, all frames seems to be skipped in
> > > the upstream filters (such as existed OMX MPEG-4 video decoder). I'm
> > > sure that is my splitter engine works correct, the problem seems to be
> > > in the my implementation of opencore API, around. Could someone advise
> > > me what should i look for?
>
> > > --
> > > Best regards,
> > > Yuri
--~--~---------~--~----~------------~-------~--~----~
unsubscribe: android-porting+unsubscr...@googlegroups.com
website: http://groups.google.com/group/android-porting
-~----------~----~----~----~------~----~------~--~---
