enqueue:HW问题分析与解决

本文分析了Oracle数据库中出现的Enqueue HW等待问题,详细介绍了如何通过SQL查询定位问题源头,并探讨了可能的原因及解决方案,包括提前分配空间、使用自动段空间管理等。
平台:oracle 9.2.0.8 + hpux 11.31
现象描述:月底系统繁忙,问题频出,这个和上篇不是同一个系统。100多个enqueue等待事件,平日运行稳定是应该一个都没有


SID SERIAL# OSUSER USERNAME SVRPROC PROCESS EVENT P1 P2 P3
------ ---------- -------- -------- ------------ ------------ ------------------------------ --------------------- ---------- ----------
3893 65320 sadmin LDAPUSER 3150 21304:27520 enqueue 1213661190 14 3233832969
4030 32072 sadmin LDAPUSER 19910 32792:26336 enqueue 1213661190 14 3233832969
.....

根据p1快速定位到类型为HW mode6,在通过p2,p3可以定位到具体的数据块和对象,这里的p2,p3和v$lock中的ID1,ID2的值相同

SQL> @enqueue.sql

SID Lock Mode
------ -------- ----------------------------------------
52 HW 6
75 HW 6

SQL> SELECT chr(to_char(bitand(1213661190,-16777216))/16777215)||
2 chr(to_char(bitand(1213661190, 16711680))/65535) "Lock",
3 to_char( bitand(1213661190, 65535) ) "Mode" from dual;

Lo M
-- -
HW 6

根据p2定位的数据块
SQL> select DBMS_UTILITY.DATA_BLOCK_ADDRESS_FILE(3233832969) FILE#,
2 DBMS_UTILITY.DATA_BLOCK_ADDRESS_BLOCK(3233832969) BLOCK#
3 from dual;

FILE# BLOCK#
---------- ----------
771 24585

定位到具体的对象

SQL> select owner, segment_type, segment_name
2 from dba_extents
3 where file_id = 771
4 and 24585 between block_id and block_id + blocks -1;


OWNER SEGMENT_TYPE SEGMENT_NAME
------------------------------ ------------------ ----------------------------------------------------------------------------------------------------
SIEBEL TABLE CX_LOG3

从statspack已可以看到,top sql都是关于此对象的sql,也验证了前面的判断。

SQL> set linesize 200;
SQL> /

HV SQL_TEXT BUFFER_GETS EXECUTIONS ELAPSED_TIME_HOUR CPU_TIME_S DISK_READS AVG_TIME_S AVG_ROWS
---------- ------------------------------- ----------- ---------- ----------------- ---------- ---------- ---------- ----------
2196997211 INSERT INTO SIEBEL.CX_LOG3 ( 15247165 550447 19.29 412.25 1480 .126 1
3813933981 INSERT INTO SIEBEL.CX_LOG3 ( 13642539 474684 17.98 360.93 46049 .136 1
2105053489 INSERT INTO SIEBEL.CX_LOG3 ( 7461687 264451 11.35 222 2157 .155 1
4155853741 INSERT INTO SIEBEL.CX_LOG3 ( 7971754 241499 7.84 178.65 2315 .117 1
4019360030 SELECT T2.CONFLICT_ID, -3.791E+09 4288 7.11 18327.24 157042 5.969 .7



为什么会有Enqueue HW等待呢?metalink中HW多出现在并发对表大量进行DML操作时,当表含有Lob字段时,争用会更加严重。该表虽无lob,但有VARCHAR2(2000 CHAR)的长字符字段,估计效果相当于lob。

解决办法:
由于历史原因,该表空间是Manual Segment Space Management(MSSM)管理方式,当分配新的extent时,会导致enqueue HW
解决办法比较可行的看样子只能是alter table <TABNAME> allocate extent,提前分配空间了。不知道增加extent的大小,一次分配更大的extent能够解决问题.当然最好的办法还是使用ASSM。

alter table CX_LOG5 allocate extent ( size 1000M);
资料中解决此类问题的方法还有:
HW enqueue The HW enqueue is used to serialize the allocation of space beyond the high water mark of a segment.
If this is a point of contention for an object, then manual allocation of extents solves the problem.
Use Freelists:Cause multiple jumps in High Water Mark
Pre-Allocate Extents:Alter table XXXX allocate extent;
Hidden Parameter:bump_highwater_mark_count
ASSM:Automatic segment space management


Line 21601: 11-29 14:47:32.268357 1360 1744 I MtkCam/ppl_context: [dump] RequestNo:[-1,-1] (being) queued! Line 22436: 11-29 14:47:32.484651 1360 1744 I mtkcam-AppStreamMgr: [earlyCallbackMeta] early callback metadata, requestNo: 0 Line 22451: 11-29 14:47:32.484926 1360 1744 I mtkcam-PipelineModelSession: [submitRequest] <Default/0> requestNo:0 { repeating:0 control.aeTargetFpsRange:5,30 control.captureIntent:1 control.enableZsl:0 control.processRawEn:0 control.mode:1 control.sceneMode:0 control.videoStabilizationMode:0 edge.mode:1 } Line 22458: 11-29 14:47:32.487311 1360 1744 I mtkcam-InitFrameHandler: [enqueue] RequestNo:0 FrameNo:0 Line 22484: 11-29 14:47:32.500829 1360 25188 I mtkcam-InitFrameHandler: [enqueue] RequestNo:1 FrameNo:1 Line 22494: 11-29 14:47:32.509876 1360 1744 I mtkcam-InitFrameHandler: [enqueue] RequestNo:2 FrameNo:2 Line 23130: 11-29 14:47:32.676222 1360 12899 I mtkcam-dev3: [0-session::OplusSkipFDMeta] Skip FD meta of first frame,requestNo: 0, params.userId: 0x16,hasSkipFDmeta: 1, isFirstFrame: 0 Line 23131: 11-29 14:47:32.676245 1360 12899 I mtkcam-dev3: [0-session::onFrameCustomUpdated] remove FD meta of first frame, requestNo: 0 Line 23431: 11-29 14:47:32.754512 1360 12890 I MtkCam/Utils: {CamProfile}[CameraDevice3SessionImpl::onFrameUpdated] updateResult: requestNo:2 userId:0x1 OAppMeta#(left:2 this:1): (1-th) ===> ( overtime > 1 ms) [start-->now: 2 ms] [last-->now: 2 ms] Line 23442: 11-29 14:47:32.758630 1360 1744 I mtkcam-AppStreamMgr: [earlyCallbackMeta] early callback metadata, requestNo: 7 Line 23447: 11-29 14:47:32.758965 1360 1744 I mtkcam-PipelineModelSession: [submitRequest] <Default/0> requestNo:7 { repeating:0 control.aeTargetFpsRange:5,30 control.captureIntent:1 control.enableZsl:0 control.processRawEn:0 control.mode:1 control.sceneMode:0 control.videoStabilizationMode:0 edge.mode:1 } Line 23914: 11-29 14:47:32.909872 1360 16067 I mtkcam-AppStreamMgr: [earlyCallbackMeta] early callback metadata, requestNo: 11 Line 23920: 11-29 14:47:32.910127 1360 16067 I mtkcam-PipelineModelSession: [submitRequest] <Default/0> requestNo:11 { repeating:0 control.aeTargetFpsRange:5,30 control.captureIntent:1 control.enableZsl:0 control.processRawEn:0 control.mode:1 control.sceneMode:0 control.videoStabilizationMode:0 edge.mode:1 } Line 24212: 11-29 14:47:32.993007 1360 12899 I MtkCam/Utils: {CamProfile}[AppStreamMgr::ResultHandler::enqueResult] - requestNo:9 userId:0x16: (1-th) ===> ( overtime > 1 ms) [start-->now: 4 ms] [last-->now: 4 ms] Line 24213: 11-29 14:47:32.993145 1360 12899 I MtkCam/Utils: {CamProfile}[CameraDevice3SessionImpl::onFrameUpdated] updateResult: requestNo:9 userId:0x16 OAppMeta#(left:1 this:1): (1-th) ===> ( overtime > 1 ms) [start-->now: 4 ms] [last-->now: 4 ms] Line 25103: 11-29 14:47:33.368109 1360 12890 I MtkCam/Utils: {CamProfile}[AppStreamMgr::ResultHandler::enqueResult] - requestNo:18 userId:0x1: (1-th) ===> ( overtime > 1 ms) [start-->now: 6 ms] [last-->now: 6 ms] Line 25104: 11-29 14:47:33.368214 1360 12890 I MtkCam/Utils: {CamProfile}[CameraDevice3SessionImpl::onFrameUpdated] updateResult: requestNo:18 userId:0x1 OAppMeta#(left:2 this:1): (1-th) ===> ( overtime > 1 ms) [start-->now: 6 ms] [last-->now: 6 ms] Line 25539: 11-29 14:47:33.443314 1360 12890 I MtkCam/Utils: {CamProfile}[CameraDevice3SessionImpl::onFrameUpdated] updateResult: requestNo:21 userId:0x1 OAppMeta#(left:2 this:1): (1-th) ===> ( overtime > 1 ms) [start-->now: 16 ms] [last-->now: 16 ms] Line 25550: 11-29 14:47:33.451155 1360 12890 I MtkCam/Utils: {CamProfile}[CameraDevice3SessionImpl::onFrameUpdated] updateResult: requestNo:22 userId:0x1 OAppMeta#(left:2 this:1): (1-th) ===> ( overtime > 1 ms) [start-->now: 2 ms] [last-->now: 2 ms] Line 25912: 11-29 14:47:33.587879 1360 12890 I MtkCam/Utils: {CamProfile}[AppStreamMgr::ResultHandler::enqueResult] - requestNo:25 userId:0x1: (1-th) ===> ( overtime > 1 ms) [start-->now: 2 ms] [last-->now: 2 ms] Line 25913: 11-29 14:47:33.588112 1360 12890 I MtkCam/Utils: {CamProfile}[CameraDevice3SessionImpl::onFrameUpdated] updateResult: requestNo:25 userId:0x1 OAppMeta#(left:2 this:1): (1-th) ===> ( overtime > 1 ms) [start-->now: 2 ms] [last-->now: 2 ms] Line 26299: 11-29 14:47:33.715945 1360 1744 I mtkcam-AppStreamMgr: [earlyCallbackMeta] early callback metadata, requestNo: 34 Line 26305: 11-29 14:47:33.716280 1360 1744 I mtkcam-PipelineModelSession: [submitRequest] <Default/0> requestNo:34 { repeating:0 control.aeTargetFpsRange:5,30 control.captureIntent:1 control.enableZsl:0 control.processRawEn:0 control.mode:1 control.sceneMode:0 control.videoStabilizationMode:0 edge.mode:1 } Line 26312: 11-29 14:47:33.746889 1360 25188 I mtkcam-AppStreamMgr: [earlyCallbackMeta] early callback metadata, requestNo: 35 Line 26318: 11-29 14:47:33.747221 1360 25188 I mtkcam-PipelineModelSession: [submitRequest] <Default/0> requestNo:35 { repeating:0 control.aeTargetFpsRange:5,30 control.captureIntent:1 control.enableZsl:0 control.processRawEn:0 control.mode:1 control.sceneMode:0 control.videoStabilizationMode:0 edge.mode:1 } Line 26819: 11-29 14:47:34.081152 1360 25188 I mtkcam-AppStreamMgr: [earlyCallbackMeta] early callback metadata, requestNo: 43 Line 26820: 11-29 14:47:34.082035 1360 25188 I mtkcam-PipelineModelSession: [submitRequest] <Default/0> requestNo:43 { repeating:0 control.aeTargetFpsRange:5,30 control.captureIntent:1 control.enableZsl:0 control.processRawEn:0 control.mode:1 control.sceneMode:0 control.videoStabilizationMode:0 edge.mode:1 } Line 27108: 11-29 14:47:34.246460 1360 12890 I MtkCam/Utils: {CamProfile}[CameraDevice3SessionImpl::onFrameUpdated] updateResult: requestNo:43 userId:0x1 OAppMeta#(left:2 this:1): (1-th) ===> ( overtime > 1 ms) [start-->now: 3 ms] [last-->now: 3 ms] Line 29272: 11-29 14:47:35.024981 1360 13785 I MtkCam/ppl_context: [kickRootNodes] [requestNo:70 frameNo:70] last frame Line 29374: 11-29 14:47:35.038193 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Meta Stream Buffer] Error happens... - requestNo:66 streamId:0x100000027 App:Meta:DynamicP2 Line 29375: 11-29 14:47:35.038213 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Image Stream Buffer] Error happens and all users release - requestNo:66 streamId:0 s0:d0:App:YCrCb_420_SP:0|SW_READ_OFTEN|HW_TEXTURE state:IN-FLIGHT->ERROR Line 29377: 11-29 14:47:35.038268 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] requestNo:66 Request Error Line 29380: 11-29 14:47:35.038471 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateResult] requestNo:66 is not in FrameQueue; FrameQueue: 65 67 68 69 70; ResultQueue: 66 Line 29529: 11-29 14:47:35.072438 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Meta Stream Buffer] Error happens... - requestNo:67 streamId:0x100000024 App:Meta:DynamicP1_main1 Line 29534: 11-29 14:47:35.072763 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Meta Stream Buffer] Error happens... - requestNo:67 streamId:0x100000027 App:Meta:DynamicP2 Line 29535: 11-29 14:47:35.072779 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Image Stream Buffer] Error happens and all users release - requestNo:67 streamId:0 s0:d0:App:YCrCb_420_SP:0|SW_READ_OFTEN|HW_TEXTURE state:IN-FLIGHT->ERROR Line 29536: 11-29 14:47:35.072819 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] requestNo:67 Request Error Line 29539: 11-29 14:47:35.073004 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateResult] requestNo:67 is not in FrameQueue; FrameQueue: 68 69 70; ResultQueue: 67 Line 29543: 11-29 14:47:35.073184 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Meta Stream Buffer] Error happens... - requestNo:68 streamId:0x100000024 App:Meta:DynamicP1_main1 Line 29548: 11-29 14:47:35.073460 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Meta Stream Buffer] Error happens... - requestNo:68 streamId:0x100000027 App:Meta:DynamicP2 Line 29549: 11-29 14:47:35.073476 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Image Stream Buffer] Error happens and all users release - requestNo:68 streamId:0 s0:d0:App:YCrCb_420_SP:0|SW_READ_OFTEN|HW_TEXTURE state:IN-FLIGHT->ERROR Line 29551: 11-29 14:47:35.073518 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] requestNo:68 Request Error Line 29553: 11-29 14:47:35.073704 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateResult] requestNo:68 is not in FrameQueue; FrameQueue: 69 70; ResultQueue: 68 Line 29554: 11-29 14:47:35.073872 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Meta Stream Buffer] Error happens... - requestNo:69 streamId:0x100000024 App:Meta:DynamicP1_main1 Line 29559: 11-29 14:47:35.074041 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Image Stream Buffer] Error happens and all users release - requestNo:69 streamId:0 s0:d0:App:YCrCb_420_SP:0|SW_READ_OFTEN|HW_TEXTURE state:IN-FLIGHT->ERROR Line 29560: 11-29 14:47:35.074082 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] requestNo:69 Request Error Line 29565: 11-29 14:47:35.074218 1360 12891 W mtkcam-AppStreamMgr: [0-ResultHandler::enqueResult] requestNo:69 existed in mResultQueue[0] - hasLastPartial:0->1 Line 29566: 11-29 14:47:35.074242 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateResult] requestNo:69 is not in FrameQueue; FrameQueue: 70; ResultQueue: 69 Line 29567: 11-29 14:47:35.074714 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Meta Stream Buffer] Error happens... - requestNo:70 streamId:0x100000024 App:Meta:DynamicP1_main1 Line 29573: 11-29 14:47:35.074999 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Meta Stream Buffer] Error happens... - requestNo:70 streamId:0x100000027 App:Meta:DynamicP2 Line 29574: 11-29 14:47:35.075020 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Image Stream Buffer] Error happens and all users release - requestNo:70 streamId:0 s0:d0:App:YCrCb_420_SP:0|SW_READ_OFTEN|HW_TEXTURE state:IN-FLIGHT->ERROR Line 29577: 11-29 14:47:35.075069 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] requestNo:70 Request Error Line 31920: 11-29 14:47:35.652257 1360 13785 I MtkCam/ppl_context: [dump] RequestNo:[-1,-1] (being) queued! Line 32588: 11-29 14:47:35.717936 1360 16067 I mtkcam-AppStreamMgr: [earlyCallbackMeta] early callback metadata, requestNo: 71 Line 32592: 11-29 14:47:35.718055 1360 16067 I mtkcam-PipelineModelSession: [submitRequest] <Default/0> requestNo:71 { repeating:0 control.aeTargetFpsRange:5,30 control.captureIntent:1 control.enableZsl:0 control.processRawEn:0 control.mode:2 control.sceneMode:14 control.videoStabilizationMode:0 edge.mode:1 } Line 33492: 11-29 14:47:36.199338 1360 13050 I mtkcam-dev3: [0-session::OplusSkipFDMeta] Skip FD meta of first frame,requestNo: 71, params.userId: 0x16,hasSkipFDmeta: 1, isFirstFrame: 0 Line 33493: 11-29 14:47:36.199360 1360 13050 I mtkcam-dev3: [0-session::onFrameCustomUpdated] remove FD meta of first frame, requestNo: 71 Line 34257: 11-29 14:47:36.400983 1360 13785 I mtkcam-AppStreamMgr: [earlyCallbackMeta] early callback metadata, requestNo: 82 Line 34263: 11-29 14:47:36.401270 1360 13785 I mtkcam-PipelineModelSession: [submitRequest] <Default/0> requestNo:82 { repeating:0 control.aeTargetFpsRange:5,30 control.captureIntent:1 control.enableZsl:0 control.processRawEn:0 control.mode:2 control.sceneMode:14 control.videoStabilizationMode:0 edge.mode:1 } Line 36292: 11-29 14:47:37.361265 1360 25188 I mtkcam-PipelineModelSession: [submitRequest] <Default/0> requestNo:110 { repeating:0 control.aeTargetFpsRange:5,30 control.captureIntent:2 control.enableZsl:0 control.processRawEn:0 control.mode:2 control.sceneMode:14 control.videoStabilizationMode:0 edge.mode:2 } Line 36489: 11-29 14:47:37.419235 1360 25188 I mtkcam-PipelineModelSession: [submitRequest] <Default/0> requestNo:111 { repeating:0 control.aeTargetFpsRange:5,30 control.captureIntent:2 control.enableZsl:0 control.processRawEn:0 control.mode:2 control.sceneMode:14 control.videoStabilizationMode:0 edge.mode:2 } Line 36597: 11-29 14:47:37.443762 1360 25188 I mtkcam-PipelineModelSession: [submitRequest] <Default/0> requestNo:112 { repeating:0 control.aeTargetFpsRange:5,30 control.captureIntent:2 control.enableZsl:0 control.processRawEn:0 control.mode:2 control.sceneMode:14 control.videoStabilizationMode:0 edge.mode:2 } Line 36676: 11-29 14:47:37.480768 1360 25188 I mtkcam-PipelineModelSession: [submitRequest] <Default/0> requestNo:113 { repeating:0 control.aeTargetFpsRange:5,30 control.captureIntent:2 control.enableZsl:0 control.processRawEn:0 control.mode:2 control.sceneMode:14 control.videoStabilizationMode:0 edge.mode:2 } Line 36701: 11-29 14:47:37.489219 1360 25188 I mtkcam-AppStreamMgr: [earlyCallbackMeta] early callback metadata, requestNo: 114 Line 36706: 11-29 14:47:37.489355 1360 25188 I mtkcam-PipelineModelSession: [submitRequest] <Default/0> requestNo:114 { repeating:0 control.aeTargetFpsRange:5,30 control.captureIntent:1 control.enableZsl:0 control.processRawEn:0 control.mode:2 control.sceneMode:14 control.videoStabilizationMode:0 edge.mode:1 } Line 36903: 11-29 14:47:37.660991 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] Final meta blocked by requestNo:111 (0|2 partial:0) : 114 Line 36938: 11-29 14:47:37.666202 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] Final meta blocked by requestNo:111 (0|2 partial:0) : 114 Line 36948: 11-29 14:47:37.669472 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] Final meta blocked by requestNo:111 (0|2 partial:0) : 114 Line 37127: 11-29 14:47:37.699870 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] Final meta blocked by requestNo:111 (0|2 partial:0) : 114 Line 37131: 11-29 14:47:37.700711 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] Final meta blocked by requestNo:111 (0|2 partial:0) : 114 115 Line 37136: 11-29 14:47:37.702299 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] Final meta blocked by requestNo:111 (0|2 partial:0) : 114 115 Line 37181: 11-29 14:47:37.733870 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] Final meta blocked by requestNo:111 (0|2 partial:0) : 114 115 Line 37187: 11-29 14:47:37.735552 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] Final meta blocked by requestNo:111 (0|2 partial:0) : 114 115 116 Line 37193: 11-29 14:47:37.736968 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] Final meta blocked by requestNo:111 (0|3 partial:0) : 114 115 116 Line 37198: 11-29 14:47:37.740975 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] Final meta blocked by requestNo:112 (0|2 partial:0) : 114 115 116 Line 37216: 11-29 14:47:37.763918 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] Final meta blocked by requestNo:112 (0|2 partial:0) : 114 115 116 117 Line 37242: 11-29 14:47:37.768176 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] Final meta blocked by requestNo:112 (0|2 partial:0) : 114 115 116 117 Line 37255: 11-29 14:47:37.771103 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] Final meta blocked by requestNo:112 (0|2 partial:0) : 114 115 116 117 Line 37358: 11-29 14:47:37.800521 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] Final meta blocked by requestNo:112 (0|2 partial:0) : 114 115 116 117 Line 37385: 11-29 14:47:37.803861 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] Final meta blocked by requestNo:112 (0|2 partial:0) : 114 115 116 117 Line 38596: 11-29 14:47:39.542656 1360 25188 I mtkcam-AppStreamMgr: [earlyCallbackMeta] early callback metadata, requestNo: 176 Line 38602: 11-29 14:47:39.542999 1360 25188 I mtkcam-PipelineModelSession: [submitRequest] <Default/0> requestNo:176 { repeating:0 control.aeTargetFpsRange:5,30 control.captureIntent:1 control.enableZsl:0 control.processRawEn:0 control.mode:2 control.sceneMode:14 control.videoStabilizationMode:0 edge.mode:1 } Line 39520: 11-29 14:47:41.656656 1360 13033 I MtkCam/Utils: {CamProfile}[CameraDevice3SessionImpl::onFrameUpdated] updateResult: requestNo:234 userId:0x1 OAppMeta#(left:2 this:1): (1-th) ===> ( overtime > 1 ms) [start-->now: 2 ms] [last-->now: 2 ms] Line 45930: 11-29 14:47:43.762972 26542 20752 I APS_CORE: [INFO][ ALGO_ISPDEVICE ] ISPDeviceMgr.cpp: 2203 codecProcess() requestNo: 110, timestamp: 20406485863000, inputStream 0, inputBuffer: 0xb4000071b14a61c0 0xb4000071b14a43c0, codeType:0 Line 45931: 11-29 14:47:43.762981 26542 20752 I APS_CORE: [INFO][ ALGO_ISPDEVICE ] ISPDeviceMgr.cpp: 2216 codecProcess() requestNo: 110, timestamp: 20406485863000, outputStream 1, outputBuffer: 0xb4000071b14a6300 0x0 Line 46038: 11-29 14:47:43.782526 1360 25188 I MtkCam/ppl_context: [dump] RequestNo:[-1,-1] (being) queued! Line 46108: 11-29 14:47:43.798631 26542 20752 I APS_CORE: [INFO][ ALGO_ISPDEVICE ] ISPDeviceMgr.cpp: 2577 codecProcess() requestNo: 110, timestamp: 20406485863000, processImgRequest: 1 Line 46146: 11-29 14:47:43.815383 1360 13205 I MtkCam/Utils: {CamProfile}[requestStreamBuffer::operator()] [ requestNo:110 frameNo:0 streamId:0x100000010 ]: (0-th) ===> (overtime > 10 ms) [start-->now: 11 ms] Line 47022: 11-29 14:47:44.103935 26542 20803 I APS_CORE: [INFO][ ALGO_ISPDEVICE ] ISPDeviceMgr.cpp: 391 processResult() requestNo: 110, processResult: 0, entryCount: 22 Line 47023: 11-29 14:47:44.104020 26542 20803 I APS_CORE: [INFO][ ALGO_ISPDEVICE ] ISPDeviceMgr.cpp: 400 processResult() requestNo: 110, param: 0xb40000716bf5da40 Line 48154: 11-29 14:47:44.379581 1360 13033 I MtkCam/Utils: {CamProfile}[AppStreamMgr::ResultHandler::enqueResult] - requestNo:316 userId:0x1: (1-th) ===> ( overtime > 1 ms) [start-->now: 3 ms] [last-->now: 3 ms] Line 48155: 11-29 14:47:44.379624 1360 13033 I MtkCam/Utils: {CamProfile}[CameraDevice3SessionImpl::onFrameUpdated] updateResult: requestNo:316 userId:0x1 OAppMeta#(left:2 this:1): (1-th) ===> ( overtime > 1 ms) [start-->now: 3 ms] [last-->now: 3 ms] Line 48686: 11-29 14:47:44.618972 1360 13050 I MtkCam/Utils: {CamProfile}[CameraDevice3SessionImpl::onFrameUpdated] updateResult: requestNo:323 userId:0x16 OAppMeta#(left:1 this:1): (1-th) ===> ( overtime > 1 ms) [start-->now: 4 ms] [last-->now: 4 ms] Line 48899: 11-29 14:47:44.715239 1360 13033 I MtkCam/Utils: {CamProfile}[CameraDevice3SessionImpl::onFrameUpdated] updateResult: requestNo:326 userId:0x1 OAppMeta#(left:2 this:1): (1-th) ===> ( overtime > 1 ms) [start-->now: 2 ms] [last-->now: 2 ms] Line 50886: 11-29 14:47:45.537070 1360 13033 I MtkCam/Utils: {CamProfile}[AppStreamMgr::ResultHandler::enqueResult] - requestNo:350 userId:0x1: (1-th) ===> ( overtime > 1 ms) [start-->now: 3 ms] [last-->now: 3 ms] Line 50890: 11-29 14:47:45.537775 1360 13033 I MtkCam/Utils: {CamProfile}[CameraDevice3SessionImpl::onFrameUpdated] updateResult: requestNo:350 userId:0x1 OAppMeta#(left:2 this:1): (1-th) ===> ( overtime > 1 ms) [start-->now: 4 ms] [last-->now: 4 ms] Line 50954: 11-29 14:47:45.556422 1360 16070 I mtkcam-AppStreamMgr: [earlyCallbackMeta] early callback metadata, requestNo: 356 Line 50960: 11-29 14:47:45.556560 1360 16070 I mtkcam-PipelineModelSession: [submitRequest] <Default/0> requestNo:356 { repeating:0 control.aeTargetFpsRange:5,30 control.captureIntent:1 control.enableZsl:0 control.processRawEn:0 control.mode:2 control.sceneMode:14 control.videoStabilizationMode:0 edge.mode:1 } Line 51379: 11-29 14:47:45.628336 1360 16070 I MtkCam/ppl_context: [kickRootNodes] [requestNo:356 frameNo:285] last frame Line 51402: 11-29 14:47:45.636242 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Meta Stream Buffer] Error happens... - requestNo:353 streamId:0x100000024 App:Meta:DynamicP1_main1 Line 51407: 11-29 14:47:45.636762 1360 13034 W mtkcam-AppStreamMgr: [0-ResultHandler::enqueResult] requestNo:353 existed in mResultQueue[0] - hasLastPartial:0->0 Line 51409: 11-29 14:47:45.636967 1360 13034 W mtkcam-AppStreamMgr: [0-ResultHandler::enqueResult] requestNo:353 existed in mResultQueue[0] - hasLastPartial:0->1 Line 51456: 11-29 14:47:45.639975 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Image Stream Buffer] Error happens and all users release - requestNo:353 streamId:0x5 s5:d0:App:YCrCb_420_SP:0|SW_READ_OFTEN|HW_TEXTURE state:IN-FLIGHT->ERROR Line 51458: 11-29 14:47:45.640016 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] requestNo:353 Request Error Line 51465: 11-29 14:47:45.640282 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateResult] requestNo:353 is not in FrameQueue; FrameQueue: 354 355 356; ResultQueue: 353 Line 51515: 11-29 14:47:45.653246 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Meta Stream Buffer] Error happens... - requestNo:354 streamId:0x100000024 App:Meta:DynamicP1_main1 Line 51520: 11-29 14:47:45.653585 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Meta Stream Buffer] Error happens... - requestNo:354 streamId:0x100000027 App:Meta:DynamicP2 Line 51521: 11-29 14:47:45.653606 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Image Stream Buffer] Error happens and all users release - requestNo:354 streamId:0x5 s5:d0:App:YCrCb_420_SP:0|SW_READ_OFTEN|HW_TEXTURE state:IN-FLIGHT->ERROR Line 51523: 11-29 14:47:45.653651 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] requestNo:354 Request Error Line 51525: 11-29 14:47:45.653886 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateResult] requestNo:354 is not in FrameQueue; FrameQueue: 355 356; ResultQueue: 354 Line 51526: 11-29 14:47:45.653916 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Meta Stream Buffer] Error happens... - requestNo:355 streamId:0x100000024 App:Meta:DynamicP1_main1 Line 51533: 11-29 14:47:45.654188 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Meta Stream Buffer] Error happens... - requestNo:355 streamId:0x100000027 App:Meta:DynamicP2 Line 51534: 11-29 14:47:45.654205 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Image Stream Buffer] Error happens and all users release - requestNo:355 streamId:0x5 s5:d0:App:YCrCb_420_SP:0|SW_READ_OFTEN|HW_TEXTURE state:IN-FLIGHT->ERROR Line 51536: 11-29 14:47:45.654246 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] requestNo:355 Request Error Line 51538: 11-29 14:47:45.654421 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateResult] requestNo:355 is not in FrameQueue; FrameQueue: 356; ResultQueue: 355 Line 51824: 11-29 14:47:45.709742 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Meta Stream Buffer] Error happens... - requestNo:356 streamId:0x100000024 App:Meta:DynamicP1_main1 Line 51829: 11-29 14:47:45.710421 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Meta Stream Buffer] Error happens... - requestNo:356 streamId:0x100000027 App:Meta:DynamicP2 Line 51830: 11-29 14:47:45.710443 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Image Stream Buffer] Error happens and all users release - requestNo:356 streamId:0x5 s5:d0:App:YCrCb_420_SP:0|SW_READ_OFTEN|HW_TEXTURE state:IN-FLIGHT->ERROR Line 51831: 11-29 14:47:45.710486 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] requestNo:356 Request Error Line 53289: 11-29 14:47:46.033533 1360 16070 I MtkCam/ppl_context: [dump] RequestNo:[-1,-1] (being) queued! Line 54006: 11-29 14:47:46.122978 1360 16070 I mtkcam-AppStreamMgr: [earlyCallbackMeta] early callback metadata, requestNo: 357 Line 54010: 11-29 14:47:46.123095 1360 16070 I mtkcam-PipelineModelSession: [submitRequest] <Default/0> requestNo:357 { repeating:0 control.aeTargetFpsRange:5,30 control.captureIntent:1 control.enableZsl:0 control.processRawEn:0 control.mode:1 control.sceneMode:0 control.videoStabilizationMode:0 edge.mode:1 } Line 54015: 11-29 14:47:46.124049 1360 16070 I mtkcam-InitFrameHandler: [enqueue] RequestNo:357 FrameNo:0 Line 54027: 11-29 14:47:46.132376 1360 16070 I mtkcam-InitFrameHandler: [enqueue] RequestNo:358 FrameNo:1 Line 54046: 11-29 14:47:46.140151 1360 16070 I mtkcam-InitFrameHandler: [enqueue] RequestNo:359 FrameNo:2 Line 54321: 11-29 14:47:46.251606 1360 13293 I mtkcam-dev3: [0-session::OplusSkipFDMeta] Skip FD meta of first frame,requestNo: 357, params.userId: 0x16,hasSkipFDmeta: 1, isFirstFrame: 0 Line 54322: 11-29 14:47:46.251629 1360 13293 I mtkcam-dev3: [0-session::onFrameCustomUpdated] remove FD meta of first frame, requestNo: 357 Line 55204: 11-29 14:47:46.321560 1360 16070 I mtkcam-AppStreamMgr: [earlyCallbackMeta] early callback metadata, requestNo: 364 Line 55207: 11-29 14:47:46.321679 1360 16070 I mtkcam-PipelineModelSession: [submitRequest] <Default/0> requestNo:364 { repeating:0 control.aeTargetFpsRange:5,30 control.captureIntent:1 control.enableZsl:0 control.processRawEn:0 control.mode:1 control.sceneMode:0 control.videoStabilizationMode:0 edge.mode:1 } Line 55618: 11-29 14:47:46.456364 1360 25188 I mtkcam-AppStreamMgr: [earlyCallbackMeta] early callback metadata, requestNo: 368 Line 55625: 11-29 14:47:46.456655 1360 25188 I mtkcam-PipelineModelSession: [submitRequest] <Default/0> requestNo:368 { repeating:0 control.aeTargetFpsRange:5,30 control.captureIntent:1 control.enableZsl:0 control.processRawEn:0 control.mode:1 control.sceneMode:0 control.videoStabilizationMode:0 edge.mode:1 } Line 56733: 11-29 14:47:46.854379 1360 16070 I mtkcam-AppStreamMgr: [earlyCallbackMeta] early callback metadata, requestNo: 380 Line 56738: 11-29 14:47:46.854540 1360 16070 I mtkcam-PipelineModelSession: [submitRequest] <Default/0> requestNo:380 { repeating:0 control.aeTargetFpsRange:5,30 control.captureIntent:1 control.enableZsl:0 control.processRawEn:0 control.mode:1 control.sceneMode:0 control.videoStabilizationMode:0 edge.mode:1 } Line 57494: 11-29 14:47:47.023631 1360 16070 I mtkcam-AppStreamMgr: [earlyCallbackMeta] early callback metadata, requestNo: 385 Line 57499: 11-29 14:47:47.023979 1360 16070 I mtkcam-PipelineModelSession: [submitRequest] <Default/0> requestNo:385 { repeating:0 control.aeTargetFpsRange:5,30 control.captureIntent:1 control.enableZsl:0 control.processRawEn:0 control.mode:1 control.sceneMode:0 control.videoStabilizationMode:0 edge.mode:1 } Line 57529: 11-29 14:47:47.054080 1360 16070 I mtkcam-AppStreamMgr: [earlyCallbackMeta] early callback metadata, requestNo: 386 Line 57535: 11-29 14:47:47.054457 1360 16070 I mtkcam-PipelineModelSession: [submitRequest] <Default/0> requestNo:386 { repeating:0 control.aeTargetFpsRange:5,30 control.captureIntent:1 control.enableZsl:0 control.processRawEn:0 control.mode:1 control.sceneMode:0 control.videoStabilizationMode:0 edge.mode:1 } Line 57605: 11-29 14:47:47.097317 1360 13293 I MtkCam/Utils: {CamProfile}[AppStreamMgr::ResultHandler::enqueResult] - requestNo:381 userId:0x16: (1-th) ===> ( overtime > 1 ms) [start-->now: 3 ms] [last-->now: 3 ms] Line 57606: 11-29 14:47:47.097446 1360 13293 I MtkCam/Utils: {CamProfile}[CameraDevice3SessionImpl::onFrameUpdated] updateResult: requestNo:381 userId:0x16 OAppMeta#(left:1 this:1): (1-th) ===> ( overtime > 1 ms) [start-->now: 3 ms] [last-->now: 3 ms] Line 60169: 11-29 14:47:47.946322 1360 16070 I MtkCam/ppl_context: [kickRootNodes] [requestNo:410 frameNo:53] last frame Line 60233: 11-29 14:47:47.950939 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Meta Stream Buffer] Error happens... - requestNo:406 streamId:0x100000024 App:Meta:DynamicP1_main1 Line 60243: 11-29 14:47:47.951615 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Meta Stream Buffer] Error happens... - requestNo:406 streamId:0x100000027 App:Meta:DynamicP2 Line 60244: 11-29 14:47:47.951630 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Image Stream Buffer] Error happens and all users release - requestNo:406 streamId:0x8 s8:d0:App:YCrCb_420_SP:0|SW_READ_OFTEN|HW_TEXTURE state:IN-FLIGHT->ERROR Line 60245: 11-29 14:47:47.951736 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] requestNo:406 Request Error Line 60248: 11-29 14:47:47.951937 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateResult] requestNo:406 is not in FrameQueue; FrameQueue: 407 408 409 410; ResultQueue: 406 Line 60347: 11-29 14:47:47.956132 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Meta Stream Buffer] Error happens... - requestNo:410 streamId:0x100000024 App:Meta:DynamicP1_main1 Line 60355: 11-29 14:47:47.956440 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Meta Stream Buffer] Error happens... - requestNo:410 streamId:0x100000027 App:Meta:DynamicP2 Line 60356: 11-29 14:47:47.956466 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Image Stream Buffer] Error happens and all users release - requestNo:410 streamId:0x8 s8:d0:App:YCrCb_420_SP:0|SW_READ_OFTEN|HW_TEXTURE state:IN-FLIGHT->ERROR Line 60358: 11-29 14:47:47.956513 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] requestNo:410 Request Error Line 60360: 11-29 14:47:47.956635 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateResult] requestNo:410 is not in FrameQueue; FrameQueue: 407 408 409; ResultQueue: 410 Line 60379: 11-29 14:47:47.958415 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Meta Stream Buffer] Error happens... - requestNo:407 streamId:0x100000024 App:Meta:DynamicP1_main1 Line 60387: 11-29 14:47:47.958855 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Meta Stream Buffer] Error happens... - requestNo:407 streamId:0x100000027 App:Meta:DynamicP2 Line 60388: 11-29 14:47:47.958870 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Image Stream Buffer] Error happens and all users release - requestNo:407 streamId:0x8 s8:d0:App:YCrCb_420_SP:0|SW_READ_OFTEN|HW_TEXTURE state:IN-FLIGHT->ERROR Line 60389: 11-29 14:47:47.958920 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] requestNo:407 Request Error Line 60396: 11-29 14:47:47.959217 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateResult] requestNo:407 is not in FrameQueue; FrameQueue: 408 409; ResultQueue: 407 Line 60398: 11-29 14:47:47.959483 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Meta Stream Buffer] Error happens... - requestNo:408 streamId:0x100000024 App:Meta:DynamicP1_main1 Line 60404: 11-29 14:47:47.959956 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Meta Stream Buffer] Error happens... - requestNo:408 streamId:0x100000027 App:Meta:DynamicP2 Line 60405: 11-29 14:47:47.959975 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Image Stream Buffer] Error happens and all users release - requestNo:408 streamId:0x8 s8:d0:App:YCrCb_420_SP:0|SW_READ_OFTEN|HW_TEXTURE state:IN-FLIGHT->ERROR Line 60407: 11-29 14:47:47.960031 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] requestNo:408 Request Error Line 60410: 11-29 14:47:47.960240 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateResult] requestNo:408 is not in FrameQueue; FrameQueue: 409; ResultQueue: 408 Line 60413: 11-29 14:47:47.960563 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Meta Stream Buffer] Error happens... - requestNo:409 streamId:0x100000024 App:Meta:DynamicP1_main1 Line 60418: 11-29 14:47:47.960881 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Meta Stream Buffer] Error happens... - requestNo:409 streamId:0x100000027 App:Meta:DynamicP2 Line 60419: 11-29 14:47:47.960897 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::operator()] [Image Stream Buffer] Error happens and all users release - requestNo:409 streamId:0x8 s8:d0:App:YCrCb_420_SP:0|SW_READ_OFTEN|HW_TEXTURE state:IN-FLIGHT->ERROR Line 60421: 11-29 14:47:47.960966 1360 12835 W mtkcam-AppStreamMgr: [0-FrameHandler::updateCallback] requestNo:409 Request Error Line 62601: 11-29 14:47:48.320269 1360 16070 I mtkcam-AppStreamMgr: [earlyCallbackMeta] early callback metadata, requestNo: 411 Line 62607: 11-29 14:47:48.320707 1360 16070 I mtkcam-PipelineModelSession: [submitRequest] <Default/0> requestNo:411 { repeating:0 control.aeTargetFpsRange:5,30 control.captureIntent:1 control.enableZsl:0 control.processRawEn:0 control.mode:2 control.sceneMode:14 control.videoStabilizationMode:0 edge.mode:1 } Line 63558: 11-29 14:47:48.657439 1360 13405 I mtkcam-dev3: [0-session::OplusSkipFDMeta] Skip FD meta of first frame,requestNo: 411, params.userId: 0x16,hasSkipFDmeta: 1, isFirstFrame: 0 Line 63559: 11-29 14:47:48.657456 1360 13405 I mtkcam-dev3: [0-session::onFrameCustomUpdated] remove FD meta of first frame, requestNo: 411 Line 64505: 11-29 14:47:48.858977 1360 16070 I mtkcam-AppStreamMgr: [earlyCallbackMeta] early callback metadata, requestNo: 422 Line 64511: 11-29 14:47:48.859225 1360 16070 I mtkcam-PipelineModelSession: [submitRequest] <Default/0> requestNo:422 { repeating:0 control.aeTargetFpsRange:5,30 control.captureIntent:1 control.enableZsl:0 control.processRawEn:0 control.mode:2 control.sceneMode:14 control.videoStabilizationMode:0 edge.mode:1 } Line 68769: 11-29 14:47:50.666509 1360 16070 I mtkcam-AppStreamMgr: [earlyCallbackMeta] early callback metadata, requestNo: 476 在14:47:37左右发生了定屏,分析
最新发布
12-05
评论
添加红包

请填写红包祝福语或标题

红包个数最小为10个

红包金额最低5元

当前余额3.43前往充值 >
需支付:10.00
成就一亿技术人!
领取后你会自动成为博主和红包主的粉丝 规则
hope_wisdom
发出的红包
实付
使用余额支付
点击重新获取
扫码支付
钱包余额 0

抵扣说明:

1.余额是钱包充值的虚拟货币,按照1:1的比例进行支付金额的抵扣。
2.余额无法直接购买下载,可以购买VIP、付费专栏及课程。

余额充值