Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250608060007\orcl_j002_13928_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:36789M/63366M, Ph+PgF:43371M/72582M 
Instance name: orcl
Redo thread mounted by this instance: 1
Oracle process number: 27
Windows thread id: 13928, image: ORACLE.EXE (J002)


*** 2025-06-08 06:00:07.884
*** SESSION ID:(219.31161) 2025-06-08 06:00:07.884
 

*** 2025-06-08 06:00:07.884
Process diagnostic dump for ORACLE.EXE (J002), OS id=13928,
pid: 27, proc_ser: 114, sid: 219, sess_ser: 31161 
-------------------------------------------------------------------------------
current sql: <none>
client details:
  O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 13928
  machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002)
Current Wait Stack:
 0: waiting for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=14 seq_num=15 snap_id=1
    wait times: snap=0.495364 sec, exc=0.495364 sec, total=0.495364 sec
    wait times: max=0.500000 sec, heur=7.086620 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.000007 sec since current wait
 0: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=13 seq_num=14 snap_id=1
    wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000010 sec of elapsed time
 1: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=12 seq_num=13 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.000008 sec of elapsed time
 2: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=11 seq_num=12 snap_id=1
    wait times: snap=0.499966 sec, exc=0.499966 sec, total=0.499966 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000017 sec of elapsed time
 3: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=10 seq_num=11 snap_id=1
    wait times: snap=0.500022 sec, exc=0.500022 sec, total=0.500022 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000011 sec of elapsed time
 4: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=9 seq_num=10 snap_id=1
    wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 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=8 seq_num=9 snap_id=1
    wait times: snap=0.500103 sec, exc=0.500103 sec, total=0.500103 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000008 sec of elapsed time
 6: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=7 seq_num=8 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
 7: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=6 seq_num=7 snap_id=1
    wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000008 sec of elapsed time
 8: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=5 seq_num=6 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.000008 sec of elapsed time
 9: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=4 seq_num=5 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.000008 sec of elapsed time
Sampled Session History of session 219 serial 31161
---------------------------------------------------
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
---------------------------------------------------
  [8 samples,                                              06:00:00 - 06:00:07]
    idle wait at each sample
  [session created at: 06:00:00]
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-06-08 06:00:07.884
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 27 (osid: 13928, J002)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-06-08 01:58:20.729678 :A1C22CA7:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12004 (legacy spawn)
2025-06-08 01:58:20.736243 :A1C22CA8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 01:58:20.736422 :A1C22CAD:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:58:20.737224 :A1C22CB2:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-06-08 01:58:23.491362 :A1C22D98:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 01:58:23.483
2025-06-08 01:58:23.491364 :A1C22D99:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16024 pso_num=27 pso_serial#=99
2025-06-08 01:58:23.491509 :A1C22D9A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16024 (legacy spawn)
2025-06-08 01:58:23.498184 :A1C22D9B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 01:58:23.498424 :A1C22DA0:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:58:23.499249 :A1C22DA5:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-06-08 01:58:35.896580 :A1C23165:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 01:58:35.894
2025-06-08 01:58:35.896582 :A1C23166:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12636 pso_num=27 pso_serial#=100
2025-06-08 01:58:35.896716 :A1C23167:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12636 (legacy spawn)
2025-06-08 01:58:35.903225 :A1C23168:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 01:58:35.903398 :A1C2316D:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:58:35.904198 :A1C23172:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-06-08 01:58:35.918940 :A1C23175:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 01:58:35.909
2025-06-08 01:58:35.918942 :A1C23176:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14392 pso_num=27 pso_serial#=101
2025-06-08 01:58:35.919075 :A1C23177:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14392 (legacy spawn)
2025-06-08 01:58:35.925600 :A1C23178:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 01:58:35.925768 :A1C23181:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:58:35.926556 :A1C23182:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-06-08 01:59:25.289914 :A1C2418C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 01:59:25.275
2025-06-08 01:59:25.289917 :A1C2418D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13436 pso_num=27 pso_serial#=102
2025-06-08 01:59:25.290058 :A1C2418E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13436 (legacy spawn)
2025-06-08 01:59:25.296733 :A1C2418F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 01:59:25.296940 :A1C24194:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:59:25.297769 :A1C24199:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-06-08 01:59:25.306502 :A1C2419C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 01:59:25.306
2025-06-08 01:59:25.306504 :A1C2419D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15708 pso_num=27 pso_serial#=103
2025-06-08 01:59:25.306644 :A1C2419E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15708 (legacy spawn)
2025-06-08 01:59:25.313145 :A1C2419F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 01:59:25.313312 :A1C241A8:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:59:25.314150 :A1C241A9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-06-08 01:59:25.673797 :A1C241B9:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 01:59:25.666
2025-06-08 01:59:25.673799 :A1C241BA:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14532 pso_num=27 pso_serial#=104
2025-06-08 01:59:25.673946 :A1C241BB:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14532 (legacy spawn)
2025-06-08 01:59:25.680404 :A1C241BC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 01:59:25.680573 :A1C241C5:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:59:25.681424 :A1C241C6:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-06-08 01:59:28.501312 :A1C242CF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 01:59:28.498
2025-06-08 01:59:28.501315 :A1C242D0:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14420 pso_num=27 pso_serial#=105
2025-06-08 01:59:28.501454 :A1C242D1:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14420 (legacy spawn)
2025-06-08 01:59:28.507937 :A1C242D2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 01:59:28.508117 :A1C242D7:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:59:28.508934 :A1C242DC:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-06-08 01:59:28.517674 :A1C242DF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 01:59:28.514
2025-06-08 01:59:28.517676 :A1C242E0:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15824 pso_num=27 pso_serial#=106
2025-06-08 01:59:28.517808 :A1C242E1:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15824 (legacy spawn)
2025-06-08 01:59:28.524326 :A1C242E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 01:59:28.524492 :A1C242EA:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:59:28.525355 :A1C242EC:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-06-08 01:59:28.829897 :A1C242EF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 01:59:28.821
2025-06-08 01:59:28.829900 :A1C242F0:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14516 pso_num=27 pso_serial#=107
2025-06-08 01:59:28.830035 :A1C242F1:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14516 (legacy spawn)
2025-06-08 01:59:28.836623 :A1C242F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 01:59:28.836782 :A1C242FA:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 01:59:28.837585 :A1C242FC:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-06-08 03:34:23.533267 :A1C98756:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 03:34:23.523
2025-06-08 03:34:23.533269 :A1C98757:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15580 pso_num=27 pso_serial#=108
2025-06-08 03:34:23.533411 :A1C98758:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15580 (legacy spawn)
2025-06-08 03:34:23.557835 :A1C98766:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 03:34:23.558028 :A1C9876B:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 03:34:23.558951 :A1C98770:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-06-08 03:34:28.848722 :A1C98940:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 03:34:28.837
2025-06-08 03:34:28.848723 :A1C98941:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16340 pso_num=27 pso_serial#=109
2025-06-08 03:34:28.848868 :A1C98942:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16340 (legacy spawn)
2025-06-08 03:34:28.855800 :A1C98943:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 03:34:28.855970 :A1C9894C:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 03:34:28.856896 :A1C9894D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-06-08 03:34:31.979477 :A1C98A31:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 03:34:31.966
2025-06-08 03:34:31.979479 :A1C98A32:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15708 pso_num=27 pso_serial#=110
2025-06-08 03:34:31.979619 :A1C98A33:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15708 (legacy spawn)
2025-06-08 03:34:31.986147 :A1C98A34:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 03:34:31.986320 :A1C98A39:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 03:34:31.987149 :A1C98A3E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-06-08 03:34:31.995759 :A1C98A41:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 03:34:31.981
2025-06-08 03:34:31.995761 :A1C98A42:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15996 pso_num=27 pso_serial#=111
2025-06-08 03:34:31.995897 :A1C98A43:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15996 (legacy spawn)
2025-06-08 03:34:32.002354 :A1C98A44:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 03:34:32.002524 :A1C98A4D:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 03:34:32.003361 :A1C98A4E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-06-08 03:34:45.189897 :A1C98E7A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 03:34:45.183
2025-06-08 03:34:45.189899 :A1C98E7B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13760 pso_num=27 pso_serial#=112
2025-06-08 03:34:45.190038 :A1C98E7C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13760 (legacy spawn)
2025-06-08 03:34:45.196566 :A1C98E7D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 03:34:45.196737 :A1C98E82:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 03:34:45.197534 :A1C98E87:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-06-08 06:00:00.040640 :A1D4B3D2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 06:00:00.032
2025-06-08 06:00:00.040642 :A1D4B3D3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12588 pso_num=27 pso_serial#=113
2025-06-08 06:00:00.040772 :A1D4B3D4:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=12588 osp_idx=25 osp_ver=48307 osp_pg=0 (spawn #263588)
2025-06-08 06:00:00.040773 :A1D4B3D5:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 06:00:00.040773 :A1D4B3D6:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=100 time=2338143219
2025-06-08 06:00:00.040773 :A1D4B3D7:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 06:00:00.040774 :A1D4B3D8:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 06:00:00.040774 :A1D4B3D9:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 06:00:00.040774 :A1D4B3DA:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 06:00:00.040775 :A1D4B3DB:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 06:00:00.041263 :A1D4B3DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.041306 :A1D4B3E3:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-06-08 06:00:00.041381 :A1D4B3F7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.045315 :A1D4B406:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.046521 :A1D4B40E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.046546 :A1D4B411:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.046589 :A1D4B41C:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-06-08 06:00:00.048358 :A1D4B427:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.048382 :A1D4B42A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.048417 :A1D4B435:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-06-08 06:00:00.048877 :A1D4B440:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.051549 :A1D4B448:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.056829 :A1D4B450:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.060802 :A1D4B458:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.062704 :A1D4B460:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.064162 :A1D4B474:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.067231 :A1D4B47C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.068766 :A1D4B484:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.071847 :A1D4B48C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.075826 :A1D4B494:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.076231 :A1D4B4A3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.076279 :A1D4B4A6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:ktcc.c:879
2025-06-08 06:00:00.076293 :A1D4B4A7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:ktcc.c:879
2025-06-08 06:00:00.079068 :A1D4B4AD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.079358 :A1D4B4BC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.079405 :A1D4B4BF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:ktcc.c:879
2025-06-08 06:00:00.079418 :A1D4B4C0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:ktcc.c:879
2025-06-08 06:00:00.082087 :A1D4B4C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.082454 :A1D4B4D5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.082504 :A1D4B4D8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:ktcc.c:879
2025-06-08 06:00:00.082518 :A1D4B4D9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:ktcc.c:879
2025-06-08 06:00:00.082696 :A1D4B4DF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.082708 :A1D4B4E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.082730 :A1D4B4EE:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-06-08 06:00:00.084239 :A1D4B4F8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-06-08 06:00:00.771893 :A1D4B597:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 06:00:00.766
2025-06-08 06:00:00.771894 :A1D4B598:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13928 pso_num=27 pso_serial#=114
2025-06-08 06:00:00.772031 :A1D4B599:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=13928 osp_idx=25 osp_ver=48308 osp_pg=0 (spawn #263589)
2025-06-08 06:00:00.772032 :A1D4B59A:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 06:00:00.772032 :A1D4B59B:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=101 time=2338143954
2025-06-08 06:00:00.772032 :A1D4B59C:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 06:00:00.772033 :A1D4B59D:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 06:00:00.772033 :A1D4B59E:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 06:00:00.772033 :A1D4B59F:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 06:00:00.772034 :A1D4B5A0:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 06:00:00.772539 :A1D4B5A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.777985 :A1D4B5D7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.778034 :A1D4B5DA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:jslv.c:9203
2025-06-08 06:00:00.783017 :A1D4B5FD:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7
2025-06-08 06:00:00.783624 :A1D4B607:db_trace:kcc.c@13684:kccwbp(): [10021:27:219:1] 0115000000000000 0115000000000000 81fa110000000000
2025-06-08 06:00:00.785466 :A1D4B62F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.785544 :A1D4B63C:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-06-08 06:00:00.785555 :A1D4B643:db_trace:ksb.c@6610:ksbcic_int(): [10254:27:219] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 8397264 1)
2025-06-08 06:00:00.785559 :A1D4B644:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.785585 :A1D4B650:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-06-08 06:00:00.786338 :A1D4B65C:db_trace:ksb.c@6610:ksbcic_int(): [10254:27:219] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (4 0 227728127)
2025-06-08 06:00:00.786343 :A1D4B65D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.786366 :A1D4B66B:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-06-08 06:00:00.786375 :A1D4B674:db_trace:ksb.c@6610:ksbcic_int(): [10254:27:219] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (6 65563 1)
2025-06-08 06:00:00.786377 :A1D4B675:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.786405 :A1D4B681:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-06-08 06:00:00.786408 :A1D4B68A:db_trace:ksb.c@6610:ksbcic_int(): [10254:27:219] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (1 2 65563)
2025-06-08 06:00:00.786410 :A1D4B68C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.786452 :A1D4B6AC:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-06-08 06:00:00.787288 :A1D4B76A:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=15 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-06-08 06:00:00.787317 :A1D4B76B:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7
2025-06-08 06:00:00.788259 :A1D4B776:db_trace:kcc.c@13684:kccwbp(): [10021:27:219:1] 0115000000000000 0115000000000000 82fa110000000000
2025-06-08 06:00:00.788701 :A1D4B777:db_trace:ksb.c@6610:ksbcic_int(): [10254:27:219] KSBCIC: (USER) starting cic on opcode KSBCIBBS [20] with args (2 0 227728127)
2025-06-08 06:00:00.788708 :A1D4B778:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.788740 :A1D4B781:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-06-08 06:00:00.797186 :A1D4B7E4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:jslv.c:9203
2025-06-08 06:00:00.797803 :A1D4B7EB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=24 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.797891 :A1D4B7ED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=24 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.798402 :A1D4B7EF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=24 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.798463 :A1D4B7F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=24 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.798499 :A1D4B7F3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=24 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.798959 :A1D4B7F5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.799003 :A1D4B7F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name=   type=0
2025-06-08 06:00:00.799143 :A1D4B80E:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 06:00:00.891649 :A1D4B8FE:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
-------------------------------------------------------------------------------
Trace Bucket Dump End: default bucket for process 27 (osid: 13928, J002)