Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250626220010\orcl_j005_16500_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:36330M/63366M, Ph+PgF:42495M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 31 Windows thread id: 16500, image: ORACLE.EXE (J005) *** 2025-06-26 22:00:10.449 *** SESSION ID:(249.1181) 2025-06-26 22:00:10.449 *** 2025-06-26 22:00:10.449 Process diagnostic dump for ORACLE.EXE (J005), OS id=16500, pid: 31, proc_ser: 64, sid: 249, sess_ser: 1181 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 16500 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J005) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=16 seq_num=17 snap_id=1 wait times: snap=0.392269 sec, exc=0.392269 sec, total=0.392269 sec wait times: max=0.500000 sec, heur=8.389396 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.000012 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 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.000008 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.500208 sec, exc=0.500208 sec, total=0.500208 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 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=11 seq_num=12 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 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=10 seq_num=11 snap_id=1 wait times: snap=0.499989 sec, exc=0.499989 sec, total=0.499989 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.500120 sec, exc=0.500120 sec, total=0.500120 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500004 sec, exc=0.500004 sec, total=0.500004 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.506285 sec, exc=0.506285 sec, total=0.506285 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000016 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 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 Sampled Session History of session 249 serial 1181 --------------------------------------------------- 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 --------------------------------------------------- [9 samples, 22:00:02 - 22:00:10] idle wait at each sample [session created at: 22:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-26 22:00:10.449 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 31 (osid: 16500, J005) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-23 00:00:13.169670 :A8508A91: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-06-23 00:00:13.170811 :A8508A99: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-06-23 00:00:13.171661 :A8508AA1: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-06-23 00:00:13.172240 :A8508AA9: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-06-23 00:00:13.172698 :A8508AB1: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-06-23 00:00:13.173219 :A8508AB9: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-06-23 00:00:13.173776 :A8508AC1: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-06-23 00:00:13.174485 :A8508AC9: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-06-23 00:00:13.175020 :A8508AD1: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-06-23 00:00:13.175457 :A8508AD9: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-06-23 00:00:13.177104 :A8508AE1: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-06-23 00:00:13.177705 :A8508AE9: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-06-23 00:00:13.178912 :A8508AF1: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-06-23 00:00:13.182623 :A8508AF9: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-06-23 00:00:13.183331 :A8508B01: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-06-23 00:00:13.184359 :A8508B09: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-06-23 00:00:13.184939 :A8508B11: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-06-23 00:00:13.185657 :A8508B19: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-06-23 00:00:13.186273 :A8508B21: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-06-23 00:00:13.187226 :A8508B29: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-06-23 00:00:13.187855 :A8508B31: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-06-23 00:00:13.188515 :A8508B39: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-06-23 00:00:13.189652 :A8508B41: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-06-23 00:00:13.189752 :A8508B49:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235 2025-06-23 00:00:13.190042 :A8508B4A: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-06-23 00:00:13.190073 :A8508B4D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235 2025-06-23 00:00:13.192389 :A8508B53: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-06-23 00:00:13.192546 :A8508B5C: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-06-23 00:00:43.267352 :A850969C:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-23 01:00:27.350533 :A85538EF:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-23 01:00:27.348 2025-06-23 01:00:27.350535 :A85538F0:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17600 pso_num=31 pso_serial#=44 2025-06-23 01:00:27.350691 :A85538F1:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=17600 osp_idx=29 osp_ver=553 osp_pg=0 (spawn #312926) 2025-06-23 01:00:27.350691 :A85538F2:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 01:00:27.350692 :A85538F3:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=172 time=3616170532 2025-06-23 01:00:27.350693 :A85538F4:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 01:00:27.350693 :A85538F5:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 01:00:27.350693 :A85538F6:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 01:00:27.350694 :A85538F7:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 01:00:27.350694 :A85538F8:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 01:00:27.351209 :A85538F9: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-06-23 01:00:27.351255 :A85538FF: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-06-23 01:00:27.359471 :A8553911:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-23 22:00:02.080382 :A8B8BD04:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-23 22:00:02.076 2025-06-23 22:00:02.080384 :A8B8BD05:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17696 pso_num=31 pso_serial#=45 2025-06-23 22:00:02.080516 :A8B8BD15:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=17696 osp_idx=29 osp_ver=554 osp_pg=0 (spawn #315847) 2025-06-23 22:00:02.080517 :A8B8BD16:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 22:00:02.080517 :A8B8BD17:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=173 time=3691745250 2025-06-23 22:00:02.080518 :A8B8BD18:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 22:00:02.080519 :A8B8BD19:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 22:00:02.080519 :A8B8BD1A:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 22:00:02.080519 :A8B8BD1B:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 22:00:02.080520 :A8B8BD1C:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 22:00:02.081080 :A8B8BD1F: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-06-23 22:00:32.159015 :A8B8D204:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-23 23:59:18.096936 :A8C23539:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-23 23:59:18.095 2025-06-23 23:59:18.096938 :A8C2353A:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11420 pso_num=31 pso_serial#=46 2025-06-23 23:59:18.097078 :A8C2353B:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=11420 (legacy spawn) 2025-06-23 23:59:18.111281 :A8C2353C: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-06-23 23:59:51.276256 :A8C241B3:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-23 23:59:54.895443 :A8C242F3:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-23 23:59:54.881 2025-06-23 23:59:54.895445 :A8C242F4:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=15368 pso_num=31 pso_serial#=47 2025-06-23 23:59:54.895583 :A8C242F5:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=15368 (legacy spawn) 2025-06-23 23:59:54.909393 :A8C242F6: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-06-24 00:00:28.080027 :A8C251C4:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-24 22:00:00.153173 :A92A7555:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-24 22:00:00.144 2025-06-24 22:00:00.153174 :A92A7556:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17500 pso_num=31 pso_serial#=48 2025-06-24 22:00:00.153295 :A92A755C:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=17500 osp_idx=29 osp_ver=557 osp_pg=0 (spawn #319174) 2025-06-24 22:00:00.153296 :A92A755D:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 22:00:00.153296 :A92A755E:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=174 time=3778143344 2025-06-24 22:00:00.153297 :A92A755F:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 22:00:00.153297 :A92A7560:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 22:00:00.153297 :A92A7561:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 22:00:00.153297 :A92A7562:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 22:00:00.153298 :A92A7563:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 22:00:00.153882 :A92A7564: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-06-24 22:00:30.296813 :A92A881D:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-24 23:59:15.974406 :A933C85F:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-24 23:59:15.962 2025-06-24 23:59:15.974408 :A933C860:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=16132 pso_num=31 pso_serial#=49 2025-06-24 23:59:15.974535 :A933C861:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=16132 (legacy spawn) 2025-06-24 23:59:15.981242 :A933C862: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-06-24 23:59:15.981419 :A933C86A: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-06-24 23:59:15.982227 :A933C86B:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-24 23:59:15.991131 :A933C86E:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-24 23:59:15.977 2025-06-24 23:59:15.991133 :A933C86F:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=15304 pso_num=31 pso_serial#=50 2025-06-24 23:59:15.991279 :A933C870:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=15304 (legacy spawn) 2025-06-24 23:59:15.997917 :A933C871: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-06-24 23:59:15.998084 :A933C87A: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-06-24 23:59:15.998905 :A933C87B:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-24 23:59:16.456153 :A933C8EB:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-24 23:59:16.446 2025-06-24 23:59:16.456155 :A933C8EC:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=14500 pso_num=31 pso_serial#=51 2025-06-24 23:59:16.456301 :A933C8ED:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=14500 (legacy spawn) 2025-06-24 23:59:16.463433 :A933C8EE: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-06-24 23:59:16.463620 :A933C8F6: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-06-24 23:59:16.464665 :A933C8F8:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-24 23:59:18.033600 :A933C95B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-24 23:59:18.027 2025-06-24 23:59:18.033603 :A933C95C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12372 pso_num=31 pso_serial#=52 2025-06-24 23:59:18.033738 :A933C95D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=12372 (legacy spawn) 2025-06-24 23:59:18.050211 :A933C95E: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-06-24 23:59:51.362846 :A933D5DE:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-24 23:59:52.197138 :A933D61B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-24 23:59:52.188 2025-06-24 23:59:52.197140 :A933D61C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=13792 pso_num=31 pso_serial#=53 2025-06-24 23:59:52.197289 :A933D61D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=13792 (legacy spawn) 2025-06-24 23:59:52.203930 :A933D61E: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-06-24 23:59:52.204102 :A933D627: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-06-24 23:59:52.204916 :A933D628:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-24 23:59:52.213622 :A933D62B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-24 23:59:52.204 2025-06-24 23:59:52.213624 :A933D62C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=16372 pso_num=31 pso_serial#=54 2025-06-24 23:59:52.213759 :A933D62D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=16372 (legacy spawn) 2025-06-24 23:59:52.220219 :A933D62E: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-06-24 23:59:52.220387 :A933D636: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-06-24 23:59:52.221204 :A933D637:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-24 23:59:54.870039 :A933D71D:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-24 23:59:54.866 2025-06-24 23:59:54.870040 :A933D71E:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17740 pso_num=31 pso_serial#=55 2025-06-24 23:59:54.870166 :A933D71F:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=17740 (legacy spawn) 2025-06-24 23:59:54.884598 :A933D733: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-06-25 00:00:28.155240 :A933E2C3:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-25 22:00:02.045011 :A99B94F2:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-25 22:00:02.037 2025-06-25 22:00:02.045012 :A99B94F3:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17724 pso_num=31 pso_serial#=56 2025-06-25 22:00:02.045144 :A99B94F4:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=17724 osp_idx=29 osp_ver=565 osp_pg=0 (spawn #322493) 2025-06-25 22:00:02.045145 :A99B94F5:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-25 22:00:02.045145 :A99B94F6:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=175 time=3864545235 2025-06-25 22:00:02.045146 :A99B94F7:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-06-25 22:00:02.045146 :A99B94F8:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-06-25 22:00:02.045147 :A99B94F9:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-06-25 22:00:02.045147 :A99B94FA:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-25 22:00:02.045147 :A99B94FB:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-25 22:00:02.045649 :A99B94FD: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-06-25 22:00:32.139474 :A99BA8B8:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-25 23:59:09.833613 :A9A51CEB:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-25 23:59:09.825 2025-06-25 23:59:09.833615 :A9A51CEC:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=7692 pso_num=31 pso_serial#=57 2025-06-25 23:59:09.833749 :A9A51CED:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=7692 (legacy spawn) 2025-06-25 23:59:09.851814 :A9A51CEE: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-06-25 23:59:09.854965 :A9A51CF7: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-06-25 23:59:13.193943 :A9A51E19: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-06-25 23:59:13.194120 :A9A51E21: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-06-25 23:59:13.195374 :A9A51E23:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-25 23:59:17.740295 :A9A51FF3:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-25 23:59:17.727 2025-06-25 23:59:17.740297 :A9A51FF4:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11636 pso_num=31 pso_serial#=58 2025-06-25 23:59:17.740433 :A9A51FF5:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=11636 (legacy spawn) 2025-06-25 23:59:17.746951 :A9A51FF6: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-06-25 23:59:17.747127 :A9A51FFB: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-06-25 23:59:17.747998 :A9A52000:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-25 23:59:18.039408 :A9A52003:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-25 23:59:18.024 2025-06-25 23:59:18.039410 :A9A52004:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=13328 pso_num=31 pso_serial#=59 2025-06-25 23:59:18.039547 :A9A52005:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=13328 (legacy spawn) 2025-06-25 23:59:18.054047 :A9A52006: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-06-25 23:59:21.343734 :A9A520F6: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-06-25 23:59:21.343910 :A9A520FB: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-06-25 23:59:21.345119 :A9A52100:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-25 23:59:21.436270 :A9A52103:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-25 23:59:21.427 2025-06-25 23:59:21.436272 :A9A52104:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=4280 pso_num=31 pso_serial#=60 2025-06-25 23:59:21.436419 :A9A52105:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=4280 (legacy spawn) 2025-06-25 23:59:21.443421 :A9A52106: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-06-25 23:59:21.443649 :A9A5210F: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-06-25 23:59:21.444583 :A9A52110:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-25 23:59:21.594633 :A9A52113:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-25 23:59:21.583 2025-06-25 23:59:21.594634 :A9A52114:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=18028 pso_num=31 pso_serial#=61 2025-06-25 23:59:21.594759 :A9A52115:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=18028 (legacy spawn) 2025-06-25 23:59:21.608387 :A9A52116: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-06-25 23:59:54.780073 :A9A52DCE:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-25 23:59:54.808467 :A9A52DD1:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-25 23:59:54.809 2025-06-25 23:59:54.808469 :A9A52DD2:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=10992 pso_num=31 pso_serial#=62 2025-06-25 23:59:54.808616 :A9A52DD3:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=10992 (legacy spawn) 2025-06-25 23:59:54.823159 :A9A52DD4: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-06-25 23:59:58.116476 :A9A52F0B: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-06-25 23:59:58.116650 :A9A52F14: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-06-25 23:59:58.118188 :A9A52F15:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-25 23:59:58.365293 :A9A52F23:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-25 23:59:58.354 2025-06-25 23:59:58.365295 :A9A52F24:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=14644 pso_num=31 pso_serial#=63 2025-06-25 23:59:58.365459 :A9A52F25:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=14644 (legacy spawn) 2025-06-25 23:59:58.379884 :A9A52F26: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-06-26 00:06:18.336199 :A9A5BBF9: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-06-26 00:06:18.336383 :A9A5BBFE: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-06-26 00:06:18.337533 :A9A5BC03:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-26 22:00:02.060772 :AA0DB393:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-26 22:00:02.051 2025-06-26 22:00:02.060774 :AA0DB394:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=16500 pso_num=31 pso_serial#=64 2025-06-26 22:00:02.060964 :AA0DB39D:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=16500 osp_idx=29 osp_ver=573 osp_pg=0 (spawn #325828) 2025-06-26 22:00:02.060965 :AA0DB39E:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-26 22:00:02.060966 :AA0DB39F:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=176 time=3950945250 2025-06-26 22:00:02.060966 :AA0DB3A0:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-06-26 22:00:02.060967 :AA0DB3A1:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-06-26 22:00:02.060967 :AA0DB3A2:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-06-26 22:00:02.060967 :AA0DB3A3:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-26 22:00:02.060968 :AA0DB3A4:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-26 22:00:02.061552 :AA0DB3A5: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: 16500, J005)