Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250829220009\orcl_j005_22800_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:35129M/63366M, Ph+PgF:40143M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 31 Windows thread id: 22800, image: ORACLE.EXE (J005) *** 2025-08-29 22:00:09.436 *** SESSION ID:(249.1859) 2025-08-29 22:00:09.436 *** 2025-08-29 22:00:09.436 Process diagnostic dump for ORACLE.EXE (J005), OS id=22800, pid: 31, proc_ser: 140, sid: 249, sess_ser: 1859 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 22800 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.130974 sec, exc=0.130974 sec, total=0.130974 sec wait times: max=0.500000 sec, heur=8.129100 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.000016 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.499972 sec, exc=0.499972 sec, total=0.499972 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000024 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.502064 sec, exc=0.502064 sec, total=0.502064 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000019 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.500013 sec, exc=0.500013 sec, total=0.500013 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=12 seq_num=13 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 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.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 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.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 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 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.500115 sec, exc=0.500115 sec, total=0.500115 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 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.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 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.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.000009 sec of elapsed time Sampled Session History of session 249 serial 1859 --------------------------------------------------- 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:01 - 22:00:09] idle wait at each sample [session created at: 22:00:01] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-29 22:00:09.436 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 31 (osid: 22800, J005) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-24 23:59:58.284893 :C439C232:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=21968 (legacy spawn) 2025-08-24 23:59:58.300711 :C439C233: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-08-25 00:06:18.249283 :C43A49BF: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-08-25 00:06:18.249461 :C43A49C4: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-08-25 00:06:18.250542 :C43A49C9:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-25 22:00:01.483527 :C49FA1FD:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-25 22:00:01.476 2025-08-25 22:00:01.483528 :C49FA1FE:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=19052 pso_num=31 pso_serial#=118 2025-08-25 22:00:01.483660 :C49FA202:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=19052 osp_idx=29 osp_ver=882 osp_pg=0 (spawn #525826) 2025-08-25 22:00:01.483661 :C49FA203:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-25 22:00:01.483661 :C49FA204:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=4 time=544856221 2025-08-25 22:00:01.483662 :C49FA205:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-08-25 22:00:01.483662 :C49FA206:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-08-25 22:00:01.483663 :C49FA207:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-08-25 22:00:01.483663 :C49FA208:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-25 22:00:01.483663 :C49FA209:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-25 22:00:01.484222 :C49FA20F: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-08-25 22:00:31.566964 :C49FB53E:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-25 23:59:05.861524 :C4A8DEB5:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-25 23:59:05.848 2025-08-25 23:59:05.861526 :C4A8DEB6:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=23192 pso_num=31 pso_serial#=119 2025-08-25 23:59:05.861668 :C4A8DEB7:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=23192 (legacy spawn) 2025-08-25 23:59:05.868319 :C4A8DEB8: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-08-25 23:59:05.868509 :C4A8DEC1: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-08-25 23:59:05.869316 :C4A8DEC2:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-25 23:59:05.878253 :C4A8DEC5:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-25 23:59:05.864 2025-08-25 23:59:05.878255 :C4A8DEC6:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=22756 pso_num=31 pso_serial#=120 2025-08-25 23:59:05.878405 :C4A8DEC7:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=22756 (legacy spawn) 2025-08-25 23:59:05.885032 :C4A8DED4: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-08-25 23:59:05.885204 :C4A8DEDD: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-08-25 23:59:05.886063 :C4A8DEDE:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-25 23:59:09.608949 :C4A8E00B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-25 23:59:09.606 2025-08-25 23:59:09.608951 :C4A8E00C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=21508 pso_num=31 pso_serial#=121 2025-08-25 23:59:09.609094 :C4A8E00D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=21508 (legacy spawn) 2025-08-25 23:59:09.623621 :C4A8E00E: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-08-25 23:59:42.977224 :C4A8ED55:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-25 23:59:46.668955 :C4A8EE62:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-25 23:59:46.664 2025-08-25 23:59:46.668957 :C4A8EE63:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=22740 pso_num=31 pso_serial#=122 2025-08-25 23:59:46.669100 :C4A8EE64:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=22740 (legacy spawn) 2025-08-25 23:59:46.683377 :C4A8EE65: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-08-26 00:00:19.931996 :C4A8FA73:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-26 22:00:00.794713 :C50EFA9E:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-26 22:00:00.786 2025-08-26 22:00:00.794714 :C50EFA9F:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12684 pso_num=31 pso_serial#=123 2025-08-26 22:00:00.794855 :C50EFAA5:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=12684 osp_idx=29 osp_ver=887 osp_pg=0 (spawn #529164) 2025-08-26 22:00:00.794855 :C50EFAA6:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-26 22:00:00.794856 :C50EFAA7:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=5 time=631255533 2025-08-26 22:00:00.794856 :C50EFAA8:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-08-26 22:00:00.794857 :C50EFAA9:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-08-26 22:00:00.794857 :C50EFAAA:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-08-26 22:00:00.794858 :C50EFAAB:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-26 22:00:00.794858 :C50EFAAC:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-26 22:00:00.795509 :C50EFAB5: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-08-26 22:00:30.920966 :C50F0C7D:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-26 23:59:09.893620 :C5181EF5:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-26 23:59:09.893 2025-08-26 23:59:09.893622 :C5181EF6:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=20896 pso_num=31 pso_serial#=124 2025-08-26 23:59:09.893766 :C5181EF7:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=20896 (legacy spawn) 2025-08-26 23:59:09.906305 :C5181EF8: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-08-26 23:59:13.152086 :C5182008: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-08-26 23:59:13.152253 :C5182011: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-08-26 23:59:13.153409 :C5182012:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-26 23:59:13.371217 :C5182021:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-26 23:59:13.367 2025-08-26 23:59:13.371219 :C5182022:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=18472 pso_num=31 pso_serial#=125 2025-08-26 23:59:13.371336 :C5182023:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=18472 (legacy spawn) 2025-08-26 23:59:13.384146 :C5182024: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-08-26 23:59:46.639384 :C5182BF5:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-26 23:59:54.819469 :C5182F23:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-26 23:59:54.817 2025-08-26 23:59:54.819470 :C5182F24:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17496 pso_num=31 pso_serial#=126 2025-08-26 23:59:54.819615 :C5182F25:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=17496 (legacy spawn) 2025-08-26 23:59:54.833548 :C5182F29: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-08-26 23:59:58.185199 :C5183021: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-08-26 23:59:58.185378 :C518302A: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-08-26 23:59:58.186490 :C518302B:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-26 23:59:58.402049 :C518303A:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-26 23:59:58.392 2025-08-26 23:59:58.402051 :C518303B:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=22376 pso_num=31 pso_serial#=127 2025-08-26 23:59:58.402193 :C518303C:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=22376 (legacy spawn) 2025-08-26 23:59:58.414446 :C518303D: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-08-27 00:06:18.373545 :C518B99D: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-08-27 00:06:18.373711 :C518B9A2: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-08-27 00:06:18.374896 :C518B9A7:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-27 22:00:00.333899 :C57EA551:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-27 22:00:00.331 2025-08-27 22:00:00.333900 :C57EA552:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17404 pso_num=31 pso_serial#=128 2025-08-27 22:00:00.334029 :C57EA553:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=17404 osp_idx=29 osp_ver=892 osp_pg=0 (spawn #532492) 2025-08-27 22:00:00.334030 :C57EA554:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-27 22:00:00.334030 :C57EA555:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=6 time=717651627 2025-08-27 22:00:00.334030 :C57EA556:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-08-27 22:00:00.334031 :C57EA557:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-08-27 22:00:00.334031 :C57EA558:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-08-27 22:00:00.334031 :C57EA559:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-27 22:00:00.334032 :C57EA55A:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-27 22:00:00.334608 :C57EA56D: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-08-27 22:00:30.400984 :C57EB869:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-27 23:59:17.891570 :C587EA9A:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-27 23:59:17.887 2025-08-27 23:59:17.891571 :C587EA9B:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=19812 pso_num=31 pso_serial#=129 2025-08-27 23:59:17.891702 :C587EA9C:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=19812 (legacy spawn) 2025-08-27 23:59:17.905688 :C587EA9D: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-08-27 23:59:21.217119 :C587EBA9: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-08-27 23:59:21.217300 :C587EBAE: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-08-27 23:59:21.218549 :C587EBB3:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-27 23:59:21.346044 :C587EBB6:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-27 23:59:21.332 2025-08-27 23:59:21.346047 :C587EBB7:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=20152 pso_num=31 pso_serial#=130 2025-08-27 23:59:21.346196 :C587EBB8:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=20152 (legacy spawn) 2025-08-27 23:59:21.368308 :C587EBB9: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-08-27 23:59:21.368493 :C587EBC2: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-08-27 23:59:21.369424 :C587EBC3:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-27 23:59:21.379162 :C587EBC6:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-27 23:59:21.379 2025-08-27 23:59:21.379166 :C587EBC7:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=18624 pso_num=31 pso_serial#=131 2025-08-27 23:59:21.379324 :C587EBC8:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=18624 (legacy spawn) 2025-08-27 23:59:21.386525 :C587EBC9: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-08-27 23:59:21.386713 :C587EBD2: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-08-27 23:59:21.387676 :C587EBD3:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-27 23:59:21.448921 :C587EBEE:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-27 23:59:21.441 2025-08-27 23:59:21.448924 :C587EBEF:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=21564 pso_num=31 pso_serial#=132 2025-08-27 23:59:21.449091 :C587EBF0:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=21564 osp_idx=29 osp_ver=896 osp_pg=0 (spawn #532764) 2025-08-27 23:59:21.449092 :C587EBF1:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-27 23:59:21.449093 :C587EBF2:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=7 time=724812752 2025-08-27 23:59:21.449093 :C587EBF3:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-08-27 23:59:21.449094 :C587EBF4:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-08-27 23:59:21.449094 :C587EBF5:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-08-27 23:59:21.449095 :C587EBF6:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-27 23:59:21.449095 :C587EBF7:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-27 23:59:21.449932 :C587EBF8: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-08-27 23:59:21.450911 :C587EC0D: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-08-27 23:59:21.460368 :C587EC2A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235 2025-08-27 23:59:21.460813 :C587EC2B: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-08-27 23:59:21.460864 :C587EC2E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235 2025-08-27 23:59:21.461832 :C587EC34: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-08-27 23:59:21.461965 :C587EC3D: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-08-27 23:59:51.540228 :C587F6FB:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-27 23:59:54.496861 :C587F7ED:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-27 23:59:54.482 2025-08-27 23:59:54.496863 :C587F7EE:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=20692 pso_num=31 pso_serial#=133 2025-08-27 23:59:54.497007 :C587F7EF:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=20692 (legacy spawn) 2025-08-27 23:59:54.515145 :C587F7F0: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-08-27 23:59:54.515333 :C587F7F8: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-08-27 23:59:54.516308 :C587F7FA:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-27 23:59:54.525522 :C587F7FD:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-27 23:59:54.513 2025-08-27 23:59:54.525524 :C587F7FE:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=20704 pso_num=31 pso_serial#=134 2025-08-27 23:59:54.525672 :C587F7FF:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=20704 (legacy spawn) 2025-08-27 23:59:54.532617 :C587F800: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-08-27 23:59:54.532798 :C587F809: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-08-27 23:59:54.533636 :C587F80A:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-27 23:59:55.207688 :C587F86E:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-27 23:59:55.201 2025-08-27 23:59:55.207690 :C587F86F:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=22188 pso_num=31 pso_serial#=135 2025-08-27 23:59:55.207832 :C587F870:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=22188 (legacy spawn) 2025-08-27 23:59:55.221550 :C587F871: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-08-28 00:00:28.395754 :C5880574:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-28 22:00:02.059706 :C5EF2AF9:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-28 22:00:02.057 2025-08-28 22:00:02.059707 :C5EF2AFA:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=20640 pso_num=31 pso_serial#=136 2025-08-28 22:00:02.059830 :C5EF2AFB:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=20640 osp_idx=29 osp_ver=900 osp_pg=0 (spawn #535819) 2025-08-28 22:00:02.059831 :C5EF2AFC:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 22:00:02.059832 :C5EF2AFD:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=8 time=804053346 2025-08-28 22:00:02.059832 :C5EF2AFE:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 22:00:02.059832 :C5EF2AFF:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 22:00:02.059833 :C5EF2B00:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 22:00:02.059833 :C5EF2B01:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 22:00:02.059834 :C5EF2B02:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 22:00:02.060326 :C5EF2B14: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-08-28 22:00:32.153201 :C5EF3CE2:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-28 23:59:17.867299 :C5F87934:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-28 23:59:17.861 2025-08-28 23:59:17.867301 :C5F87935:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=23372 pso_num=31 pso_serial#=137 2025-08-28 23:59:17.867436 :C5F87936:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=23372 (legacy spawn) 2025-08-28 23:59:17.883387 :C5F8797A: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-08-28 23:59:21.140598 :C5F87A86: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-08-28 23:59:21.140781 :C5F87A8F: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-08-28 23:59:21.142207 :C5F87A90:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-28 23:59:21.396025 :C5F87AAA:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-28 23:59:21.382 2025-08-28 23:59:21.396026 :C5F87AAB:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=22852 pso_num=31 pso_serial#=138 2025-08-28 23:59:21.396171 :C5F87AAC:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=22852 (legacy spawn) 2025-08-28 23:59:21.411044 :C5F87AAD: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-08-28 23:59:54.633872 :C5F886F3:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-28 23:59:58.251029 :C5F887FB:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-28 23:59:58.243 2025-08-28 23:59:58.251031 :C5F887FC:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=22996 pso_num=31 pso_serial#=139 2025-08-28 23:59:58.251172 :C5F887FD:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=22996 (legacy spawn) 2025-08-28 23:59:58.265969 :C5F887FE: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-08-29 00:06:18.223544 :C5F90F73: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-08-29 00:06:18.223718 :C5F90F7C: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-08-29 00:06:18.224876 :C5F90F7D:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-29 22:00:01.309384 :C66086DB:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-29 22:00:01.306 2025-08-29 22:00:01.309385 :C66086DC:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=22800 pso_num=31 pso_serial#=140 2025-08-29 22:00:01.309525 :C66086DD:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=22800 osp_idx=29 osp_ver=904 osp_pg=0 (spawn #539147) 2025-08-29 22:00:01.309526 :C66086DE:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-29 22:00:01.309527 :C66086DF:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=9 time=890452596 2025-08-29 22:00:01.309527 :C66086E0:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-08-29 22:00:01.309527 :C66086E1:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-08-29 22:00:01.309528 :C66086E2:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-08-29 22:00:01.309528 :C66086E3:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-29 22:00:01.309528 :C66086E4:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-29 22:00:01.310120 :C66086E5: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: 22800, J005)