Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250525100351\orcl_j002_13420_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:37327M/63366M, Ph+PgF:44373M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 13420, image: ORACLE.EXE (J002) *** 2025-05-25 10:03:51.753 *** SESSION ID:(225.29435) 2025-05-25 10:03:51.753 *** 2025-05-25 10:03:51.753 Process diagnostic dump for ORACLE.EXE (J002), OS id=13420, pid: 28, proc_ser: 30, sid: 225, sess_ser: 29435 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 13420 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.315179 sec, exc=0.315179 sec, total=0.315179 sec wait times: max=0.500000 sec, heur=3.823921 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000009 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.500114 sec, exc=0.500114 sec, total=0.500114 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=4 seq_num=5 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 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.000009 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.515158 sec, exc=0.515158 sec, total=0.515158 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499991 sec, exc=0.499991 sec, total=0.499991 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=0 seq_num=1 snap_id=1 wait times: snap=0.493421 sec, exc=0.493421 sec, total=0.493421 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 225 serial 29435 --------------------------------------------------- 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 --------------------------------------------------- [4 samples, 10:03:47 - 10:03:51] idle wait at each sample [session created at: 10:03:47] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-25 10:03:51.753 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 13420, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-25 09:58:47.054102 :9BC15B88:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 09:58:47.049 2025-05-25 09:58:47.054105 :9BC15B89:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13928 pso_num=28 pso_serial#=10 2025-05-25 09:58:47.054239 :9BC15B8A:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=13928 osp_idx=26 osp_ver=145613 osp_pg=0 (spawn #217497) 2025-05-25 09:58:47.054240 :9BC15B8B:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 09:58:47.054240 :9BC15B8C:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=145 time=1142899313 2025-05-25 09:58:47.054241 :9BC15B8D:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 09:58:47.054242 :9BC15B8E:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 09:58:47.054242 :9BC15B8F:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 09:58:47.054242 :9BC15B90:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 09:58:47.054243 :9BC15B91:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 09:58:47.054784 :9BC15B92:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 09:58:47.054828 :9BC15B98:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-25 09:58:47.074949 :9BC15BD2:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 09:59:36.337702 :9BC16BF9:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 09:59:36.330 2025-05-25 09:59:36.337704 :9BC16BFA:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14248 pso_num=28 pso_serial#=11 2025-05-25 09:59:36.337838 :9BC16BFB:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14248 (legacy spawn) 2025-05-25 09:59:36.344401 :9BC16BFC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 09:59:36.344585 :9BC16C02:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-25 09:59:36.345429 :9BC16C06:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 09:59:49.838321 :9BC17058:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 09:59:49.828 2025-05-25 09:59:49.838324 :9BC17059:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13676 pso_num=28 pso_serial#=12 2025-05-25 09:59:49.838467 :9BC1705A:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13676 (legacy spawn) 2025-05-25 09:59:49.845442 :9BC1705B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 09:59:49.845627 :9BC17060:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-25 09:59:49.846559 :9BC17065:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 09:59:49.855856 :9BC17068:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 09:59:49.843 2025-05-25 09:59:49.855858 :9BC17069:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10332 pso_num=28 pso_serial#=13 2025-05-25 09:59:49.856005 :9BC1706A:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10332 (legacy spawn) 2025-05-25 09:59:49.862760 :9BC1706B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 09:59:49.862950 :9BC17074:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-25 09:59:49.863779 :9BC17075:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 09:59:52.586744 :9BC17125:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 09:59:52.572 2025-05-25 09:59:52.586746 :9BC17126:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14552 pso_num=28 pso_serial#=14 2025-05-25 09:59:52.586880 :9BC17127:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14552 (legacy spawn) 2025-05-25 09:59:52.593282 :9BC17128:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 09:59:52.593451 :9BC1712D:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-25 09:59:52.594239 :9BC17132:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 09:59:53.033673 :9BC1717C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 09:59:53.026 2025-05-25 09:59:53.033676 :9BC1717D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=6400 pso_num=28 pso_serial#=15 2025-05-25 09:59:53.033813 :9BC1717E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=6400 (legacy spawn) 2025-05-25 09:59:53.040342 :9BC1717F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 09:59:53.040372 :9BC17182:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 09:59:53.040538 :9BC17189:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-25 09:59:53.041372 :9BC17196:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 09:59:53.050246 :9BC17199:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 09:59:53.041 2025-05-25 09:59:53.050248 :9BC1719A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14720 pso_num=28 pso_serial#=16 2025-05-25 09:59:53.050383 :9BC1719B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14720 (legacy spawn) 2025-05-25 09:59:53.056943 :9BC1719C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 09:59:53.057105 :9BC171A1:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-25 09:59:53.057991 :9BC171A6:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 10:00:38.486471 :9BC1811F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 10:00:38.478 2025-05-25 10:00:38.486473 :9BC18120:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14276 pso_num=28 pso_serial#=17 2025-05-25 10:00:38.486611 :9BC18121:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14276 (legacy spawn) 2025-05-25 10:00:38.493067 :9BC18122:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:00:38.493236 :9BC1812B:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-25 10:00:38.494053 :9BC1812C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 10:00:47.301352 :9BC1841B:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 10:00:47.296 2025-05-25 10:00:47.301354 :9BC1841C:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15108 pso_num=28 pso_serial#=18 2025-05-25 10:00:47.301493 :9BC1841D:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=15108 osp_idx=26 osp_ver=145621 osp_pg=0 (spawn #217502) 2025-05-25 10:00:47.301494 :9BC1841E:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 10:00:47.301494 :9BC1841F:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=146 time=1143019563 2025-05-25 10:00:47.301495 :9BC18420:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 10:00:47.301496 :9BC18421:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 10:00:47.301496 :9BC18422:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 10:00:47.301496 :9BC18423:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 10:00:47.301497 :9BC18424:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 10:00:47.302011 :9BC18425:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 10:00:47.302058 :9BC1842B:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-25 10:00:47.306481 :9BC1843E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:00:47.330351 :9BC18446:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:00:47.345342 :9BC1844E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:00:47.373218 :9BC18456:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:00:47.377182 :9BC1845E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:00:47.380823 :9BC18466:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:00:47.384875 :9BC1846E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:00:47.385898 :9BC18476:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:00:48.642183 :9BC184A3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:00:48.670161 :9BC184AB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:00:48.670481 :9BC184B3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:00:48.670807 :9BC184BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:00:48.670824 :9BC184BE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:00:48.670882 :9BC184CA:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-25 10:00:48.673730 :9BC184D8:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 10:01:00.985687 :9BC1892A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 10:01:00.972 2025-05-25 10:01:00.985689 :9BC1892B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12852 pso_num=28 pso_serial#=19 2025-05-25 10:01:00.985825 :9BC1892C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12852 (legacy spawn) 2025-05-25 10:01:00.992273 :9BC1892D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:01:00.992453 :9BC18936:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-25 10:01:00.993278 :9BC18937:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 10:01:02.762757 :9BC189EE:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 10:01:02.754 2025-05-25 10:01:02.762759 :9BC189EF:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14424 pso_num=28 pso_serial#=20 2025-05-25 10:01:02.762897 :9BC189F0:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14424 (legacy spawn) 2025-05-25 10:01:02.769282 :9BC189F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:01:02.769461 :9BC189FA:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-25 10:01:02.770325 :9BC189FF:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 10:01:02.779019 :9BC18A02:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 10:01:02.770 2025-05-25 10:01:02.779021 :9BC18A03:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13196 pso_num=28 pso_serial#=21 2025-05-25 10:01:02.779160 :9BC18A04:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13196 (legacy spawn) 2025-05-25 10:01:02.785497 :9BC18A05:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:01:02.785663 :9BC18A0E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-25 10:01:02.786516 :9BC18A0F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 10:01:34.897479 :9BC19439:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 10:01:34.888 2025-05-25 10:01:34.897481 :9BC1943A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13832 pso_num=28 pso_serial#=22 2025-05-25 10:01:34.897613 :9BC1943B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13832 (legacy spawn) 2025-05-25 10:01:34.904132 :9BC1943C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:01:34.904330 :9BC19441:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-25 10:01:34.905199 :9BC19446:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 10:01:39.034852 :9BC195AF:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 10:01:39.027 2025-05-25 10:01:39.034854 :9BC195B0:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15128 pso_num=28 pso_serial#=23 2025-05-25 10:01:39.034999 :9BC195B1:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15128 (legacy spawn) 2025-05-25 10:01:39.041434 :9BC195B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:01:39.041604 :9BC195B9:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-25 10:01:39.042403 :9BC195BC:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 10:01:39.051086 :9BC195BF:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 10:01:39.043 2025-05-25 10:01:39.051088 :9BC195C0:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13168 pso_num=28 pso_serial#=24 2025-05-25 10:01:39.051225 :9BC195C1:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13168 (legacy spawn) 2025-05-25 10:01:39.057659 :9BC195C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:01:39.057834 :9BC195CB:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-25 10:01:39.058689 :9BC195CC:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 10:01:40.895172 :9BC19644:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 10:01:40.887 2025-05-25 10:01:40.895174 :9BC19645:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=7888 pso_num=28 pso_serial#=25 2025-05-25 10:01:40.895355 :9BC19646:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=7888 (legacy spawn) 2025-05-25 10:01:40.901794 :9BC19647:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:01:40.901983 :9BC1964C:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-25 10:01:40.902809 :9BC19651:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 10:01:42.335665 :9BC196C2:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 10:01:42.325 2025-05-25 10:01:42.335667 :9BC196C3:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12084 pso_num=28 pso_serial#=26 2025-05-25 10:01:42.335806 :9BC196C4:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12084 (legacy spawn) 2025-05-25 10:01:42.342438 :9BC196C5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:01:42.342596 :9BC196CA:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-25 10:01:42.343431 :9BC196CF:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 10:01:42.352042 :9BC196D2:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 10:01:42.341 2025-05-25 10:01:42.352043 :9BC196D3:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14680 pso_num=28 pso_serial#=27 2025-05-25 10:01:42.352217 :9BC196D4:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14680 (legacy spawn) 2025-05-25 10:01:42.358701 :9BC196D5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:01:42.358894 :9BC196DE:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-25 10:01:42.359700 :9BC196DF:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 10:03:45.282491 :9BC1BF72:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 10:03:45.274 2025-05-25 10:03:45.282493 :9BC1BF73:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13408 pso_num=28 pso_serial#=28 2025-05-25 10:03:45.282635 :9BC1BF74:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13408 (legacy spawn) 2025-05-25 10:03:45.289412 :9BC1BF75:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:45.289595 :9BC1BF7A:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-25 10:03:45.290454 :9BC1BF7E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 10:03:47.727704 :9BC1C06C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 10:03:47.722 2025-05-25 10:03:47.727707 :9BC1C06D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13612 pso_num=28 pso_serial#=29 2025-05-25 10:03:47.727848 :9BC1C06E:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=13612 osp_idx=26 osp_ver=145632 osp_pg=0 (spawn #217511) 2025-05-25 10:03:47.727849 :9BC1C06F:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 10:03:47.727850 :9BC1C070:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=147 time=1143199985 2025-05-25 10:03:47.727850 :9BC1C071:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 10:03:47.727850 :9BC1C072:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 10:03:47.727851 :9BC1C073:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 10:03:47.727851 :9BC1C074:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 10:03:47.727852 :9BC1C075:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 10:03:47.728376 :9BC1C076:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 10:03:47.728420 :9BC1C07C:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-25 10:03:47.732527 :9BC1C093:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-25 10:03:47.732581 :9BC1C094:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-25 10:03:47.732854 :9BC1C095:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-25 10:03:47.732871 :9BC1C096:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-25 10:03:47.733090 :9BC1C097:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-25 10:03:47.733105 :9BC1C098:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-25 10:03:47.733321 :9BC1C099:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-25 10:03:47.733334 :9BC1C09A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-25 10:03:47.733549 :9BC1C09B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-25 10:03:47.733563 :9BC1C09C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-25 10:03:47.733772 :9BC1C09D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-25 10:03:47.733785 :9BC1C09E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-25 10:03:47.736402 :9BC1C0C2:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-25 10:03:47.930833 :9BC1C4EF:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-25 10:03:47.925 2025-05-25 10:03:47.930835 :9BC1C4F0:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13420 pso_num=28 pso_serial#=30 2025-05-25 10:03:47.931017 :9BC1C4F1:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=13420 osp_idx=26 osp_ver=145633 osp_pg=0 (spawn #217514) 2025-05-25 10:03:47.931017 :9BC1C4F2:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 10:03:47.931018 :9BC1C4F3:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=148 time=1143200188 2025-05-25 10:03:47.931019 :9BC1C4F4:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 10:03:47.931019 :9BC1C4F5:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 10:03:47.931019 :9BC1C4F6:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 10:03:47.931020 :9BC1C4F7:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 10:03:47.931020 :9BC1C4F8:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 10:03:47.931619 :9BC1C502:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] 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 28 (osid: 13420, J002)