Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250904220008\orcl_j001_18680_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:35013M/63366M, Ph+PgF:39848M/72582M 
Instance name: orcl
Redo thread mounted by this instance: 1
Oracle process number: 24
Windows thread id: 18680, image: ORACLE.EXE (J001)


*** 2025-09-04 22:00:08.273
*** SESSION ID:(193.51667) 2025-09-04 22:00:08.273
 

*** 2025-09-04 22:00:08.273
Process diagnostic dump for ORACLE.EXE (J001), OS id=18680,
pid: 24, proc_ser: 153, sid: 193, sess_ser: 51667 
-------------------------------------------------------------------------------
current sql: <none>
client details:
  O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 18680
  machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001)
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.250737 sec, exc=0.250737 sec, total=0.250737 sec
    wait times: max=0.500000 sec, heur=3.738545 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.000012 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.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.000008 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.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.000011 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.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
 4: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=2 seq_num=3 snap_id=1
    wait times: snap=0.502083 sec, exc=0.502083 sec, total=0.502083 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000010 sec of elapsed time
 5: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=1 seq_num=2 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.000012 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.485663 sec, exc=0.485663 sec, total=0.485663 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 193 serial 51667
---------------------------------------------------
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,                                              22:00:04 - 22:00:08]
    idle wait at each sample
  [session created at: 22:00:04]
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-09-04 22:00:08.273
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 24 (osid: 18680, J001)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-09-04 21:51:29.339829 :C90BB3C0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=24276 pso_num=24 pso_serial#=144
2025-09-04 21:51:29.339943 :C90BB3C1:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=24276 osp_idx=22 osp_ver=843528 osp_pg=0 (spawn #559126)
2025-09-04 21:51:29.339944 :C90BB3C2:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-04 21:51:29.339945 :C90BB3C3:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=1 time=1408340643
2025-09-04 21:51:29.339945 :C90BB3C4:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-04 21:51:29.339945 :C90BB3C5:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-04 21:51:29.339946 :C90BB3C6:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-04 21:51:29.339946 :C90BB3C7:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-04 21:51:29.339946 :C90BB3C8:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-04 21:51:29.340461 :C90BB3C9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-04 21:51:59.416142 :C90BBE27:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-04 21:52:29.432283 :C90BC815:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-04 21:52:29.432
2025-09-04 21:52:29.432284 :C90BC816:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13244 pso_num=24 pso_serial#=145
2025-09-04 21:52:29.432371 :C90BC817:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13244 osp_idx=22 osp_ver=843529 osp_pg=0 (spawn #559129)
2025-09-04 21:52:29.432371 :C90BC818:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-04 21:52:29.432371 :C90BC819:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=2 time=1408400721
2025-09-04 21:52:29.432371 :C90BC81A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-04 21:52:29.432371 :C90BC81B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-04 21:52:29.432371 :C90BC81C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-04 21:52:29.432371 :C90BC81D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-04 21:52:29.432372 :C90BC81E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-04 21:52:29.432690 :C90BC81F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-04 21:52:59.519525 :C90BD1F5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-04 21:53:29.554478 :C90BDBD9:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-04 21:53:29.543
2025-09-04 21:53:29.554481 :C90BDBDA:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23416 pso_num=24 pso_serial#=146
2025-09-04 21:53:29.554659 :C90BDBDB:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23416 osp_idx=22 osp_ver=843530 osp_pg=0 (spawn #559131)
2025-09-04 21:53:29.554660 :C90BDBDC:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-04 21:53:29.554660 :C90BDBDD:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=3 time=1408460846
2025-09-04 21:53:29.554660 :C90BDBDE:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-04 21:53:29.554660 :C90BDBDF:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-04 21:53:29.554661 :C90BDBE0:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-04 21:53:29.554661 :C90BDBE1:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-04 21:53:29.554662 :C90BDBE2:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-04 21:53:29.555158 :C90BDBE3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-04 21:53:59.614922 :C90BE5E1:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-04 21:54:14.623636 :C90BEAD4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-04 21:54:14.612
2025-09-04 21:54:14.623640 :C90BEAD5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23088 pso_num=24 pso_serial#=147
2025-09-04 21:54:14.623791 :C90BEAD6:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23088 osp_idx=22 osp_ver=843531 osp_pg=0 (spawn #559133)
2025-09-04 21:54:14.623792 :C90BEAD7:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-04 21:54:14.623792 :C90BEAD8:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=4 time=1408505924
2025-09-04 21:54:14.623792 :C90BEAD9:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-04 21:54:14.623793 :C90BEADA:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-04 21:54:14.623794 :C90BEADB:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-04 21:54:14.623794 :C90BEADC:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-04 21:54:14.623794 :C90BEADD:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-04 21:54:14.624308 :C90BEADE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-04 21:54:29.614634 :C90BEFDE:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-09-04 21:54:59.704275 :C90BF9E9:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-04 21:55:29.697599 :C90C040A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-04 21:55:29.686
2025-09-04 21:55:29.697601 :C90C040B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23620 pso_num=24 pso_serial#=148
2025-09-04 21:55:29.697719 :C90C040C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23620 osp_idx=22 osp_ver=843532 osp_pg=0 (spawn #559135)
2025-09-04 21:55:29.697719 :C90C040D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-04 21:55:29.697720 :C90C040E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=5 time=1408581002
2025-09-04 21:55:29.697720 :C90C040F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-04 21:55:29.697720 :C90C0410:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-04 21:55:29.697720 :C90C0411:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-04 21:55:29.697721 :C90C0412:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-04 21:55:29.697721 :C90C0413:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-04 21:55:29.698212 :C90C0414:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-04 21:55:59.743760 :C90C0E0E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-04 21:56:29.789768 :C90C17D9:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-04 21:56:29.778
2025-09-04 21:56:29.789771 :C90C17DA:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23572 pso_num=24 pso_serial#=149
2025-09-04 21:56:29.789892 :C90C17DB:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23572 osp_idx=22 osp_ver=843533 osp_pg=0 (spawn #559137)
2025-09-04 21:56:29.789893 :C90C17DC:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-04 21:56:29.789893 :C90C17DD:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=6 time=1408641080
2025-09-04 21:56:29.789893 :C90C17DE:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-04 21:56:29.789893 :C90C17DF:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-04 21:56:29.789894 :C90C17E0:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-04 21:56:29.789894 :C90C17E1:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-04 21:56:29.789894 :C90C17E2:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-04 21:56:29.790386 :C90C17E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-04 21:56:59.842178 :C90C22BF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-04 21:57:29.856119 :C90C2C95:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-04 21:57:29.845
2025-09-04 21:57:29.856121 :C90C2C96:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22148 pso_num=24 pso_serial#=150
2025-09-04 21:57:29.856239 :C90C2C97:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22148 osp_idx=22 osp_ver=843534 osp_pg=0 (spawn #559139)
2025-09-04 21:57:29.856239 :C90C2C98:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-04 21:57:29.856240 :C90C2C99:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=7 time=1408701158
2025-09-04 21:57:29.856240 :C90C2C9A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-04 21:57:29.856241 :C90C2C9B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-04 21:57:29.856241 :C90C2C9C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-04 21:57:29.856241 :C90C2C9D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-04 21:57:29.856242 :C90C2C9E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-04 21:57:29.856709 :C90C2C9F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-04 21:57:59.917302 :C90C3675:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-04 21:58:29.902467 :C90C407C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-04 21:58:29.891
2025-09-04 21:58:29.902469 :C90C407D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16692 pso_num=24 pso_serial#=151
2025-09-04 21:58:29.902599 :C90C407E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16692 osp_idx=22 osp_ver=843535 osp_pg=0 (spawn #559141)
2025-09-04 21:58:29.902599 :C90C407F:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-04 21:58:29.902600 :C90C4080:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=8 time=1408761205
2025-09-04 21:58:29.902600 :C90C4081:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-04 21:58:29.902601 :C90C4082:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-04 21:58:29.902601 :C90C4083:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-04 21:58:29.902601 :C90C4084:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-04 21:58:29.902602 :C90C4085:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-04 21:58:29.903108 :C90C4086:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-04 21:58:59.990402 :C90C4AE8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-04 21:59:29.980523 :C90C54DD:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-04 21:59:29.969
2025-09-04 21:59:29.980525 :C90C54DE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20324 pso_num=24 pso_serial#=152
2025-09-04 21:59:29.980665 :C90C54DF:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20324 osp_idx=22 osp_ver=843536 osp_pg=0 (spawn #559145)
2025-09-04 21:59:29.980666 :C90C54E0:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-04 21:59:29.980666 :C90C54E1:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=9 time=1408821283
2025-09-04 21:59:29.980667 :C90C54E2:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-04 21:59:29.980667 :C90C54E3:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-04 21:59:29.980668 :C90C54E4:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-04 21:59:29.980668 :C90C54E5:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-04 21:59:29.980668 :C90C54E6:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-04 21:59:29.981164 :C90C54E7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-04 22:00:00.066565 :C90C6053:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-04 22:00:02.030778 :C90C6107:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-04 22:00:02.019
2025-09-04 22:00:02.030780 :C90C6108:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18680 pso_num=24 pso_serial#=153
2025-09-04 22:00:02.030916 :C90C6109:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18680 osp_idx=22 osp_ver=843537 osp_pg=0 (spawn #559149)
2025-09-04 22:00:02.030916 :C90C610A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-04 22:00:02.030917 :C90C610B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=10 time=1408853330
2025-09-04 22:00:02.030917 :C90C610C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-04 22:00:02.030917 :C90C610D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-04 22:00:02.030918 :C90C610E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-04 22:00:02.030918 :C90C610F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-04 22:00:02.030918 :C90C6110:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-04 22:00:02.031431 :C90C6111:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-04 22:00:02.036404 :C90C6130:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:02.036440 :C90C6133:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203
2025-09-04 22:00:02.039848 :C90C6156:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:02.420798 :C90C64BE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:02.464135 :C90C64C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:02.479771 :C90C64D6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:02.536970 :C90C64DE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:02.669517 :C90C6511:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:02.784792 :C90C6519:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:02.794565 :C90C6521:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:02.810570 :C90C6529:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:02.826300 :C90C6531:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:02.842552 :C90C654D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:02.852120 :C90C656E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:02.867710 :C90C6576:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:02.877183 :C90C657E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:02.893160 :C90C6586:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:02.902828 :C90C658E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:02.912280 :C90C6596:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:02.928071 :C90C659E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:02.937686 :C90C65A6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:02.954133 :C90C65AE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:02.970360 :C90C65B6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:02.986273 :C90C65BE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:02.996683 :C90C65C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.004296 :C90C65CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.020446 :C90C65D6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.030283 :C90C65DE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.046553 :C90C65E6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.055965 :C90C65EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.066154 :C90C65F6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.082399 :C90C65FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.098319 :C90C6606:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.107709 :C90C660E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.118257 :C90C6616:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.134680 :C90C661E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.150641 :C90C6626:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.160718 :C90C662E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.177004 :C90C6636:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.192704 :C90C663E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.202334 :C90C6646:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.211966 :C90C664E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.227792 :C90C6656:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.237482 :C90C665E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.247976 :C90C6666:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.257723 :C90C666E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.274070 :C90C6676:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.285958 :C90C667E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.293549 :C90C6686:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.301059 :C90C668E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.308526 :C90C6696:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.316193 :C90C669E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.323785 :C90C66A6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.331659 :C90C66AE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.339538 :C90C66B6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.347112 :C90C66BE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.354688 :C90C66C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:03.473406*:C90C66E4:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000299C6918 estart=1756994402 eid=16777545 ctx=0x00000007831EA738 cwh=0x00000000299F0248 cpu=125000 buffg=30300 interb=114688 r=7 rb=114688 w=0 wb=0
2025-09-04 22:00:03.473406*:C90C66E5:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000299C6918 curCtx=0x000000005D541CD0 pubTabIdxCnt=0
2025-09-04 22:00:03.473406*:C90C66E6:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000299C6918
2025-09-04 22:00:03.473406*:C90C66E7:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000299C6918
2025-09-04 22:00:03.473406*:C90C66E8:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D5C288 estart=1756994401 eid=16777380 ctx=0x000000079F654AA8 cwh=0x0000000024D50158 cpu=1359375 buffg=117009 interb=27934720 r=1431 rb=25665536 w=12 wb=2269184
2025-09-04 22:00:03.473406*:C90C66E9:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D5C288 curCtx=0x000000005D564F98 pubTabIdxCnt=0
2025-09-04 22:00:03.473406*:C90C66EA:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D5C288
2025-09-04 22:00:03.473406*:C90C66EB:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D5C288
2025-09-04 22:00:03.910919*:C90C67FE:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000299C6918 curCtx=0x000000005D541CD0 xsc->flg4=65792 flags=1
2025-09-04 22:00:03.910919*:C90C67FF:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000299C6918
2025-09-04 22:00:04.520817 :C90C68D0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:04.521164 :C90C68D8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:04.521164*:C90C68E0:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D5C288 curCtx=0x000000005D564F98 xsc->flg4=65792 flags=2
2025-09-04 22:00:04.521164*:C90C68E1:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D5C288
2025-09-04 22:00:04.521435 :C90C68E2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203
2025-09-04 22:00:04.525928 :C90C68E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-04 22:00:04.526007 :C90C68E6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name=   type=0
-------------------------------------------------------------------------------
Trace Bucket Dump End: default bucket for process 24 (osid: 18680, J001)