Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250907060004\orcl_j005_22460_bucket.trc Oracle Database 11g Enterprise Edition Release 11.2.0.1.0 - 64bit Production With the Partitioning, OLAP, Data Mining and Real Application Testing options Windows NT Version V6.2 CPU : 32 - type 8664, 16 Physical Cores Process Affinity : 0x0x0000000000000000 Memory (Avail/Total): Ph:34970M/63366M, Ph+PgF:39783M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 31 Windows thread id: 22460, image: ORACLE.EXE (J005) *** 2025-09-07 06:00:05.117 *** SESSION ID:(249.1953) 2025-09-07 06:00:05.117 *** 2025-09-07 06:00:05.117 Process diagnostic dump for ORACLE.EXE (J005), OS id=22460, pid: 31, proc_ser: 187, sid: 249, sess_ser: 1953 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 22460 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J005) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.238176 sec, exc=0.238176 sec, total=0.238176 sec wait times: max=0.500000 sec, heur=4.242218 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000009 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.500002 sec, exc=0.500002 sec, total=0.500002 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.500166 sec, exc=0.500166 sec, total=0.500166 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.507388 sec, exc=0.507388 sec, total=0.507388 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.496425 sec, exc=0.496425 sec, total=0.496425 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 249 serial 1953 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [5 samples, 06:00:00 - 06:00:05] idle wait at each sample [session created at: 06:00:00] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-07 06:00:05.117 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 31 (osid: 22460, J005) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-03 22:00:05.970442*:C899AE70:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000320A1E80 estart=1756908004 eid=16781162 ctx=0x0000000787AD5BF0 cwh=0x00000000320907D0 cpu=406250 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-09-03 22:00:05.970442*:C899AE71:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000320A1E80 curCtx=0x00000000326D2570 pubTabIdxCnt=0 2025-09-03 22:00:05.970442*:C899AE72:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000320A1E80 2025-09-03 22:00:05.970442*:C899AE73:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000320A1E80 2025-09-03 22:00:06.095428*:C899AE74:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000326D15D0 curCtx=0x00000000326CF9B8 xsc->flg4=65792 flags=1 2025-09-03 22:00:06.095428*:C899AE75:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000326D15D0 2025-09-03 22:00:06.095428*:C899AE76:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000320A1E80 curCtx=0x00000000326D2570 xsc->flg4=65792 flags=2 2025-09-03 22:00:06.095428*:C899AE77:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000320A1E80 2025-09-03 22:00:06.163884 :C899AE78:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-03 22:00:06.216220 :C899AE80:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-03 22:00:06.216519 :C899AE88:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-03 22:00:06.216817 :C899AE90:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-03 22:00:06.216834 :C899AE93:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-03 22:00:06.216920 :C899AEA5:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-03 22:00:06.221655 :C899AEAD:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-03 22:00:06.522655 :C899AF99:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-03 22:00:06.517 2025-09-03 22:00:06.522657 :C899AF9A:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=24288 pso_num=31 pso_serial#=166 2025-09-03 22:00:06.522801 :C899AF9B:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=24288 (legacy spawn) 2025-09-03 22:00:06.529944 :C899AF9C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-03 22:00:06.530124 :C899AFA5:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-03 22:00:06.531141 :C899AFA6:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-03 22:00:07.135967 :C899B05F:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-03 22:00:07.127 2025-09-03 22:00:07.135969 :C899B060:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=23140 pso_num=31 pso_serial#=167 2025-09-03 22:00:07.136102 :C899B061:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=23140 (legacy spawn) 2025-09-03 22:00:07.142687 :C899B066:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-03 22:00:07.142830 :C899B06F:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-03 22:00:07.143603 :C899B070:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-03 22:00:07.500778 :C899B080:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-03 22:00:07.486 2025-09-03 22:00:07.500780 :C899B081:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11772 pso_num=31 pso_serial#=168 2025-09-03 22:00:07.500924 :C899B082:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=11772 (legacy spawn) 2025-09-03 22:00:07.507598 :C899B083:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-03 22:00:07.507766 :C899B08C:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-03 22:00:07.508584 :C899B08D:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-03 22:00:07.517262 :C899B090:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-03 22:00:07.502 2025-09-03 22:00:07.517264 :C899B091:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=21880 pso_num=31 pso_serial#=169 2025-09-03 22:00:07.517414 :C899B092:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=21880 (legacy spawn) 2025-09-03 22:00:07.523846 :C899B093:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-03 22:00:07.524001 :C899B09C:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-03 22:00:07.524790 :C899B09D:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-03 23:59:10.072007 :C8A3AAAE:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-03 23:59:10.065 2025-09-03 23:59:10.072009 :C8A3AAAF:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=21724 pso_num=31 pso_serial#=170 2025-09-03 23:59:10.072150 :C8A3AAB0:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=21724 (legacy spawn) 2025-09-03 23:59:10.086538 :C8A3AAB1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-03 23:59:43.156944 :C8A3B72B:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-03 23:59:46.800806 :C8A3B871:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-03 23:59:46.798 2025-09-03 23:59:46.800808 :C8A3B872:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=21036 pso_num=31 pso_serial#=171 2025-09-03 23:59:46.800949 :C8A3B873:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=21036 (legacy spawn) 2025-09-03 23:59:46.815815 :C8A3B874:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-04 00:00:19.957950 :C8A3C45A:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-04 22:00:02.054257 :C90C630D:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-04 22:00:02.051 2025-09-04 22:00:02.054258 :C90C630E:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=22492 pso_num=31 pso_serial#=172 2025-09-04 22:00:02.054387 :C90C631E:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=22492 osp_idx=29 osp_ver=936 osp_pg=0 (spawn #559153) 2025-09-04 22:00:02.054387 :C90C631F:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-04 22:00:02.054388 :C90C6320:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=15 time=1408853346 2025-09-04 22:00:02.054389 :C90C6321:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-09-04 22:00:02.054389 :C90C6322:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-09-04 22:00:02.054390 :C90C6323:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-09-04 22:00:02.054390 :C90C6324:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-04 22:00:02.054390 :C90C6325:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-04 22:00:02.054971 :C90C6328:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-04 22:00:32.174217 :C90C750B:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-04 23:59:09.950478 :C91665D9:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-04 23:59:09.948 2025-09-04 23:59:09.950480 :C91665DA:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=7196 pso_num=31 pso_serial#=173 2025-09-04 23:59:09.950622 :C91665DB:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=7196 (legacy spawn) 2025-09-04 23:59:09.964577 :C91665DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-04 23:59:43.151258 :C916729D:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-04 23:59:46.826902 :C9167465:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-04 23:59:46.820 2025-09-04 23:59:46.826904 :C9167466:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=6008 pso_num=31 pso_serial#=174 2025-09-04 23:59:46.827043 :C9167467:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=6008 (legacy spawn) 2025-09-04 23:59:46.841561 :C9167468:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-05 00:00:19.967244 :C9168240:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-05 22:00:00.300693 :C97E150B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-05 22:00:00.293 2025-09-05 22:00:00.300694 :C97E150C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=19020 pso_num=31 pso_serial#=175 2025-09-05 22:00:00.300817 :C97E1514:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=19020 osp_idx=29 osp_ver=939 osp_pg=0 (spawn #562485) 2025-09-05 22:00:00.300817 :C97E1515:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-05 22:00:00.300818 :C97E1516:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=16 time=1495251596 2025-09-05 22:00:00.300818 :C97E1517:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-09-05 22:00:00.300819 :C97E1518:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-09-05 22:00:00.300819 :C97E1519:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-09-05 22:00:00.300819 :C97E151A:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-05 22:00:00.300820 :C97E151B:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-05 22:00:00.301398 :C97E1524:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-05 22:00:30.367188 :C97E25DC:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-05 23:59:09.753471 :C988093B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-05 23:59:09.753 2025-09-05 23:59:09.753473 :C988093C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=22072 pso_num=31 pso_serial#=176 2025-09-05 23:59:09.753611 :C988093D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=22072 (legacy spawn) 2025-09-05 23:59:09.767759 :C988093E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-05 23:59:13.067643 :C9880A86:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-05 23:59:13.067812 :C9880A8B:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-05 23:59:13.069055 :C9880A90:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-05 23:59:17.946615 :C9880C69:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-05 23:59:17.940 2025-09-05 23:59:17.946617 :C9880C6A:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=24244 pso_num=31 pso_serial#=177 2025-09-05 23:59:17.946752 :C9880C6B:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=24244 (legacy spawn) 2025-09-05 23:59:17.961380 :C9880C6C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-05 23:59:21.270106 :C9880D67:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-05 23:59:21.270280 :C9880D6C:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-05 23:59:21.271607 :C9880D70:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-05 23:59:21.523672 :C9880D73:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-05 23:59:21.513 2025-09-05 23:59:21.523674 :C9880D74:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=23248 pso_num=31 pso_serial#=178 2025-09-05 23:59:21.523856 :C9880D75:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=23248 (legacy spawn) 2025-09-05 23:59:21.538908 :C9880D76:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-05 23:59:54.698323 :C98819D1:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-05 23:59:55.221527 :C9881A31:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-05 23:59:55.211 2025-09-05 23:59:55.221529 :C9881A32:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=21176 pso_num=31 pso_serial#=179 2025-09-05 23:59:55.221663 :C9881A33:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=21176 (legacy spawn) 2025-09-05 23:59:55.235819 :C9881A34:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 00:00:28.640159 :C9882697:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-06 00:02:01.284428 :C988488C:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-06 00:02:01.281 2025-09-06 00:02:01.284429 :C988488D:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=6128 pso_num=31 pso_serial#=180 2025-09-06 00:02:01.284576 :C988488E:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=6128 osp_idx=29 osp_ver=944 osp_pg=0 (spawn #562774) 2025-09-06 00:02:01.284577 :C988488F:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 00:02:01.284578 :C9884890:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=17 time=1502572580 2025-09-06 00:02:01.284579 :C9884891:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 00:02:01.284579 :C9884892:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 00:02:01.284580 :C9884893:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 00:02:01.284580 :C9884894:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 00:02:01.284580 :C9884895:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 00:02:01.285102 :C9884896:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 00:02:01.285160 :C988489C:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-06 00:02:01.294173 :C98848B4:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-06 06:00:00.596332 :C9A453E3:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-06 06:00:00.593 2025-09-06 06:00:00.596333 :C9A453E4:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=24128 pso_num=31 pso_serial#=181 2025-09-06 06:00:00.596473 :C9A453E5:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=24128 osp_idx=29 osp_ver=945 osp_pg=0 (spawn #563604) 2025-09-06 06:00:00.596474 :C9A453E6:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 06:00:00.596475 :C9A453E7:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=18 time=1524051893 2025-09-06 06:00:00.596475 :C9A453E8:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 06:00:00.596476 :C9A453E9:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 06:00:00.596477 :C9A453EA:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 06:00:00.596477 :C9A453EB:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 06:00:00.596477 :C9A453EC:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 06:00:00.597005 :C9A453FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 06:00:30.629020 :C9A4653A:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-06 22:04:39.112743 :C9EF0888:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-06 22:04:39.101 2025-09-06 22:04:39.112745 :C9EF0889:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=20520 pso_num=31 pso_serial#=182 2025-09-06 22:04:39.112902 :C9EF088A:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=20520 osp_idx=29 osp_ver=946 osp_pg=0 (spawn #565844) 2025-09-06 22:04:39.112903 :C9EF088B:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 22:04:39.112904 :C9EF088C:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=19 time=1581930408 2025-09-06 22:04:39.112904 :C9EF088D:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 22:04:39.112904 :C9EF088E:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 22:04:39.112905 :C9EF088F:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 22:04:39.112905 :C9EF0890:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 22:04:39.112906 :C9EF0891:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 22:04:39.113576 :C9EF0895:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 22:04:39.113615 :C9EF089B:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-06 22:04:39.123065 :C9EF08AD:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-06 23:59:05.950297 :C9F87540:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-06 23:59:05.937 2025-09-06 23:59:05.950299 :C9F87541:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=20964 pso_num=31 pso_serial#=183 2025-09-06 23:59:05.950453 :C9F87542:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=20964 (legacy spawn) 2025-09-06 23:59:05.958160 :C9F87543:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 23:59:05.958332 :C9F87548:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 23:59:05.959330 :C9F8754D:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-06 23:59:10.130849 :C9F876D7:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-06 23:59:10.130 2025-09-06 23:59:10.130851 :C9F876D8:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=19120 pso_num=31 pso_serial#=184 2025-09-06 23:59:10.130991 :C9F876D9:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=19120 (legacy spawn) 2025-09-06 23:59:10.146077 :C9F876DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 23:59:43.628641 :C9F88388:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-06 23:59:44.822332 :C9F883CD:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-06 23:59:44.822 2025-09-06 23:59:44.822334 :C9F883CE:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=20404 pso_num=31 pso_serial#=185 2025-09-06 23:59:44.822483 :C9F883CF:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=20404 (legacy spawn) 2025-09-06 23:59:44.837449 :C9F883D0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 00:00:17.862558 :C9F88F27:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-07 00:04:53.304938 :C9F8EE08:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-07 00:04:53.300 2025-09-07 00:04:53.304939 :C9F8EE09:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=23488 pso_num=31 pso_serial#=186 2025-09-07 00:04:53.305090 :C9F8EE0A:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=23488 osp_idx=29 osp_ver=950 osp_pg=0 (spawn #566127) 2025-09-07 00:04:53.305091 :C9F8EE0B:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-07 00:04:53.305091 :C9F8EE0C:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=20 time=1589144612 2025-09-07 00:04:53.305092 :C9F8EE0D:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-09-07 00:04:53.305092 :C9F8EE0E:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-09-07 00:04:53.305093 :C9F8EE0F:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-09-07 00:04:53.305094 :C9F8EE10:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-07 00:04:53.305094 :C9F8EE11:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-07 00:04:53.305626 :C9F8EE12:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-07 00:04:53.305676 :C9F8EE18:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-07 00:04:53.315507 :C9F8EE43:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-09-07 06:00:00.878307 :CA14AC71:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 09-07 06:00:00.875 2025-09-07 06:00:00.878308 :CA14AC72:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=22460 pso_num=31 pso_serial#=187 2025-09-07 06:00:00.878442 :CA14AC73:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=22460 osp_idx=29 osp_ver=951 osp_pg=0 (spawn #566950) 2025-09-07 06:00:00.878442 :CA14AC74:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-07 06:00:00.878443 :CA14AC75:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=21 time=1610452174 2025-09-07 06:00:00.878443 :CA14AC76:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-09-07 06:00:00.878444 :CA14AC77:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-09-07 06:00:00.878444 :CA14AC78:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-09-07 06:00:00.878444 :CA14AC79:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-07 06:00:00.878445 :CA14AC7A:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-07 06:00:00.878986 :CA14AC7B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 31 (osid: 22460, J005)