Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250627220008\orcl_j005_6364_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:36283M/63366M, Ph+PgF:42392M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 31 Windows thread id: 6364, image: ORACLE.EXE (J005) *** 2025-06-27 22:00:08.932 *** SESSION ID:(249.1195) 2025-06-27 22:00:08.932 *** 2025-06-27 22:00:08.932 Process diagnostic dump for ORACLE.EXE (J005), OS id=6364, pid: 31, proc_ser: 71, sid: 249, sess_ser: 1195 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 6364 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J005) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.362689 sec, exc=0.362689 sec, total=0.362689 sec wait times: max=0.500000 sec, heur=6.871692 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=12 seq_num=13 snap_id=1 wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 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.000011 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.500017 sec, exc=0.500017 sec, total=0.500017 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.499979 sec, exc=0.499979 sec, total=0.499979 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000018 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 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.000008 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.512897 sec, exc=0.512897 sec, total=0.512897 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.506015 sec, exc=0.506015 sec, total=0.506015 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.499988 sec, exc=0.499988 sec, total=0.499988 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000016 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499988 sec, exc=0.499988 sec, total=0.499988 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 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.000012 sec of elapsed time Sampled Session History of session 249 serial 1195 --------------------------------------------------- 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 --------------------------------------------------- [7 samples, 22:00:02 - 22:00:08] 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-27 22:00:08.932 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 31 (osid: 6364, J005) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 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 2025-06-26 22:00:32.087505 :AA0DC565:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-26 23:59:07.298682 :AA1724F1:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-26 23:59:07.285 2025-06-26 23:59:07.298684 :AA1724F2:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=14356 pso_num=31 pso_serial#=65 2025-06-26 23:59:07.298828 :AA1724F3:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=14356 (legacy spawn) 2025-06-26 23:59:07.305705 :AA1724F4: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 23:59:07.305880 :AA1724F9: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 23:59:07.306872 :AA1724FE:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-26 23:59:07.315441 :AA172501:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-26 23:59:07.301 2025-06-26 23:59:07.315443 :AA172502:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=13920 pso_num=31 pso_serial#=66 2025-06-26 23:59:07.315594 :AA172503:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=13920 (legacy spawn) 2025-06-26 23:59:07.333863 :AA172504: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 23:59:07.334028 :AA17250D: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 23:59:07.334883 :AA17250E:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-26 23:59:09.610340 :AA1725C3:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-26 23:59:09.608 2025-06-26 23:59:09.610342 :AA1725C4:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=5020 pso_num=31 pso_serial#=67 2025-06-26 23:59:09.610491 :AA1725C5:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=5020 (legacy spawn) 2025-06-26 23:59:09.625410 :AA1725D2: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 23:59:12.969910 :AA1726CD: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 23:59:12.970097 :AA1726D2: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 23:59:12.971264 :AA1726D7:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-26 23:59:13.219280 :AA172723:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-26 23:59:13.205 2025-06-26 23:59:13.219282 :AA172724:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17584 pso_num=31 pso_serial#=68 2025-06-26 23:59:13.219431 :AA172725:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=17584 (legacy spawn) 2025-06-26 23:59:13.233351 :AA172726: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 23:59:46.452672 :AA173349:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-26 23:59:46.471284 :AA17334C:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-26 23:59:46.465 2025-06-26 23:59:46.471287 :AA17334D:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17736 pso_num=31 pso_serial#=69 2025-06-26 23:59:46.471421 :AA17334E:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=17736 (legacy spawn) 2025-06-26 23:59:46.488406 :AA17334F: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 23:59:46.493979 :AA173354: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 23:59:49.834706 :AA1734B5: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 23:59:49.834889 :AA1734BA: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 23:59:49.836019 :AA1734BF:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-26 23:59:50.086680 :AA1734EA:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-26 23:59:50.076 2025-06-26 23:59:50.086683 :AA1734EB:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=18264 pso_num=31 pso_serial#=70 2025-06-26 23:59:50.086829 :AA1734EC:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=18264 (legacy spawn) 2025-06-26 23:59:50.101397 :AA1734ED: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-27 00:00:23.204373 :AA174136:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-27 22:00:02.061841 :AA7F4389:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-27 22:00:02.053 2025-06-27 22:00:02.061842 :AA7F438A:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=6364 pso_num=31 pso_serial#=71 2025-06-27 22:00:02.061970 :AA7F438B:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=6364 osp_idx=29 osp_ver=580 osp_pg=0 (spawn #329162) 2025-06-27 22:00:02.061971 :AA7F438C: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-27 22:00:02.061972 :AA7F438D:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=177 time=4037345250 2025-06-27 22:00:02.061972 :AA7F438E:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 22:00:02.061973 :AA7F438F:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 22:00:02.061973 :AA7F4390:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 22:00:02.061973 :AA7F4391:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 22:00:02.061974 :AA7F4392:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 22:00:02.062734 :AA7F4393: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: 6364, J005)