Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250712060009\orcl_j002_10520_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:36392M/63366M, Ph+PgF:42421M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 10520, image: ORACLE.EXE (J002) *** 2025-07-12 06:00:09.130 *** SESSION ID:(219.36513) 2025-07-12 06:00:09.130 *** 2025-07-12 06:00:09.130 Process diagnostic dump for ORACLE.EXE (J002), OS id=10520, pid: 27, proc_ser: 45, sid: 219, sess_ser: 36513 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 10520 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=0.110745 sec, exc=0.110745 sec, total=0.110745 sec wait times: max=0.500000 sec, heur=7.032432 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.000013 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.500150 sec, exc=0.500150 sec, total=0.500150 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.500002 sec, exc=0.500002 sec, total=0.500002 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=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.000011 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.499976 sec, exc=0.499976 sec, total=0.499976 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=10 seq_num=11 snap_id=1 wait times: snap=0.500014 sec, exc=0.500014 sec, total=0.500014 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.512311 sec, exc=0.512311 sec, total=0.512311 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 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 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.500005 sec, exc=0.500005 sec, total=0.500005 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=6 seq_num=7 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 9: 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.000010 sec of elapsed time Sampled Session History of session 219 serial 36513 --------------------------------------------------- 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:02 - 06:00:09] idle wait at each sample [session created at: 06:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-12 06:00:09.130 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 10520, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-12 01:57:33.405096 :B0CA558C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=1168 pso_num=27 pso_serial#=26 2025-07-12 01:57:33.405248 :B0CA558D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=1168 (legacy spawn) 2025-07-12 01:57:33.411780 :B0CA558E: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-07-12 01:57:33.411949 :B0CA5597: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-07-12 01:57:33.412791 :B0CA5598:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 01:57:48.844969 :B0CA5A9A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 01:57:48.837 2025-07-12 01:57:48.844971 :B0CA5A9B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15780 pso_num=27 pso_serial#=27 2025-07-12 01:57:48.845102 :B0CA5A9C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15780 (legacy spawn) 2025-07-12 01:57:48.851562 :B0CA5A9D: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-07-12 01:57:48.851737 :B0CA5AA2: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-07-12 01:57:48.852601 :B0CA5AA7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 01:57:51.248906 :B0CA5B9A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 01:57:51.241 2025-07-12 01:57:51.248908 :B0CA5B9B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15216 pso_num=27 pso_serial#=28 2025-07-12 01:57:51.249037 :B0CA5B9C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15216 (legacy spawn) 2025-07-12 01:57:51.255483 :B0CA5B9D: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-07-12 01:57:51.255655 :B0CA5BA6: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-07-12 01:57:51.256493 :B0CA5BA7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 01:58:06.520369 :B0CA6043:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 01:58:06.517 2025-07-12 01:58:06.520371 :B0CA6044:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17540 pso_num=27 pso_serial#=29 2025-07-12 01:58:06.520511 :B0CA6045:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17540 (legacy spawn) 2025-07-12 01:58:06.526929 :B0CA6046: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-07-12 01:58:06.527108 :B0CA604F: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-07-12 01:58:06.527919 :B0CA6050:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 01:58:06.536655 :B0CA6053:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 01:58:06.532 2025-07-12 01:58:06.536657 :B0CA6054:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18100 pso_num=27 pso_serial#=30 2025-07-12 01:58:06.536842 :B0CA6055:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18100 (legacy spawn) 2025-07-12 01:58:06.543282 :B0CA6056: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-07-12 01:58:06.543453 :B0CA605F: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-07-12 01:58:06.544284 :B0CA6060:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 01:58:09.847132 :B0CA6149:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 01:58:09.834 2025-07-12 01:58:09.847134 :B0CA614A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=6652 pso_num=27 pso_serial#=31 2025-07-12 01:58:09.847277 :B0CA614B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=6652 (legacy spawn) 2025-07-12 01:58:09.853669 :B0CA6159: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-07-12 01:58:09.853853 :B0CA6160: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-07-12 01:58:09.854630 :B0CA6163:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 01:58:09.873934 :B0CA6166:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 01:58:09.865 2025-07-12 01:58:09.873936 :B0CA6167:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=6104 pso_num=27 pso_serial#=32 2025-07-12 01:58:09.874080 :B0CA6168:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=6104 (legacy spawn) 2025-07-12 01:58:09.880544 :B0CA6169: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-07-12 01:58:09.880732 :B0CA6172: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-07-12 01:58:09.881550 :B0CA6173:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 01:58:42.981429 :B0CA6C73:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 01:58:42.967 2025-07-12 01:58:42.981431 :B0CA6C74:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=2860 pso_num=27 pso_serial#=33 2025-07-12 01:58:42.981564 :B0CA6C75:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=2860 (legacy spawn) 2025-07-12 01:58:42.988015 :B0CA6C76: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-07-12 01:58:42.988184 :B0CA6C7B: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-07-12 01:58:42.989029 :B0CA6C80:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 01:58:42.997677 :B0CA6C83:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 01:58:42.982 2025-07-12 01:58:42.997679 :B0CA6C84:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16108 pso_num=27 pso_serial#=34 2025-07-12 01:58:42.997824 :B0CA6C85:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16108 (legacy spawn) 2025-07-12 01:58:43.016277 :B0CA6C86: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-07-12 01:58:43.016441 :B0CA6C8F: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-07-12 01:58:43.017256 :B0CA6C90:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 01:58:46.130662 :B0CA6D84:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 01:58:46.118 2025-07-12 01:58:46.130664 :B0CA6D85:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17976 pso_num=27 pso_serial#=35 2025-07-12 01:58:46.130808 :B0CA6D86:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17976 (legacy spawn) 2025-07-12 01:58:46.137214 :B0CA6D87: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-07-12 01:58:46.137411 :B0CA6D90: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-07-12 01:58:46.138246 :B0CA6D91:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 01:58:46.146910 :B0CA6D94:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 01:58:46.133 2025-07-12 01:58:46.146912 :B0CA6D95:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=1484 pso_num=27 pso_serial#=36 2025-07-12 01:58:46.147049 :B0CA6D96:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=1484 (legacy spawn) 2025-07-12 01:58:46.153524 :B0CA6D97: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-07-12 01:58:46.153555 :B0CA6D9A: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-07-12 01:58:46.153702 :B0CA6DA1: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-07-12 01:58:46.154545 :B0CA6DAE:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 01:58:53.447384 :B0CA6FD3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 01:58:53.439 2025-07-12 01:58:53.447387 :B0CA6FD4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16028 pso_num=27 pso_serial#=37 2025-07-12 01:58:53.447522 :B0CA6FD5:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16028 (legacy spawn) 2025-07-12 01:58:53.453966 :B0CA6FD6: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-07-12 01:58:53.454147 :B0CA6FDB: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-07-12 01:58:53.454975 :B0CA6FE0:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 01:58:57.031080 :B0CA7133:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 01:58:57.022 2025-07-12 01:58:57.031083 :B0CA7134:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9384 pso_num=27 pso_serial#=38 2025-07-12 01:58:57.031218 :B0CA7135:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9384 (legacy spawn) 2025-07-12 01:58:57.037716 :B0CA7136: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-07-12 01:58:57.037883 :B0CA713F: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-07-12 01:58:57.038675 :B0CA7140:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 01:59:55.604828 :B0CA83D3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 01:59:55.596 2025-07-12 01:59:55.604830 :B0CA83D4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4556 pso_num=27 pso_serial#=39 2025-07-12 01:59:55.604984 :B0CA83D5:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4556 (legacy spawn) 2025-07-12 01:59:55.611828 :B0CA83D6: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-07-12 01:59:55.611999 :B0CA83DC: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-07-12 01:59:55.612846 :B0CA83E0:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 01:59:55.697650 :B0CA83E3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 01:59:55.689 2025-07-12 01:59:55.697653 :B0CA83E4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14300 pso_num=27 pso_serial#=40 2025-07-12 01:59:55.697794 :B0CA83E5:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14300 (legacy spawn) 2025-07-12 01:59:55.704259 :B0CA83E6: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-07-12 01:59:55.704424 :B0CA83EF: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-07-12 01:59:55.705259 :B0CA83F0:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 01:59:55.714047 :B0CA83F3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 01:59:55.705 2025-07-12 01:59:55.714049 :B0CA83F4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12148 pso_num=27 pso_serial#=41 2025-07-12 01:59:55.714179 :B0CA83F5:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12148 (legacy spawn) 2025-07-12 01:59:55.720736 :B0CA83F6: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-07-12 01:59:55.720901 :B0CA83FF: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-07-12 01:59:55.721717 :B0CA8400:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 01:59:58.790939 :B0CA84E3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 01:59:58.778 2025-07-12 01:59:58.790941 :B0CA84E4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=5196 pso_num=27 pso_serial#=42 2025-07-12 01:59:58.791080 :B0CA84E5:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=5196 (legacy spawn) 2025-07-12 01:59:58.797524 :B0CA84E6: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-07-12 01:59:58.797696 :B0CA84EF: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-07-12 01:59:58.798469 :B0CA84F0:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 01:59:58.807154 :B0CA84F3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 01:59:58.793 2025-07-12 01:59:58.807156 :B0CA84F4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11340 pso_num=27 pso_serial#=43 2025-07-12 01:59:58.807301 :B0CA84F5:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11340 (legacy spawn) 2025-07-12 01:59:58.813874 :B0CA84F6: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-07-12 01:59:58.814053 :B0CA84FF: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-07-12 01:59:58.814879 :B0CA8500:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 02:00:00.027047 :B0CA859B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 02:00:00.017 2025-07-12 02:00:00.027049 :B0CA859C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15404 pso_num=27 pso_serial#=44 2025-07-12 02:00:00.027182 :B0CA859D:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=15404 osp_idx=25 osp_ver=149227 osp_pg=0 (spawn #376378) 2025-07-12 02:00:00.027183 :B0CA859E:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 02:00:00.027183 :B0CA859F:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=88 time=966375923 2025-07-12 02:00:00.027184 :B0CA85A0:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 02:00:00.027184 :B0CA85A1:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 02:00:00.027184 :B0CA85A2:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 02:00:00.027185 :B0CA85A3:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 02:00:00.027185 :B0CA85A4:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 02:00:00.027674 :B0CA85A5: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-07-12 02:00:00.027718 :B0CA85AC: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-07-12 02:00:00.027802 :B0CA85C0: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-07-12 02:00:00.031778 :B0CA85CF: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-07-12 02:00:00.084274 :B0CA8612: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-07-12 02:00:00.086129 :B0CA861A: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-07-12 02:00:00.088325 :B0CA8622: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-07-12 02:00:00.088784 :B0CA862A: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-07-12 02:00:00.088805 :B0CA862D: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-07-12 02:00:00.088847 :B0CA8638: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-07-12 02:00:00.089479 :B0CA8643: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-07-12 02:00:00.089492 :B0CA8646: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-07-12 02:00:00.089516 :B0CA8652: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-07-12 02:00:00.090338 :B0CA865C: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-07-12 02:00:00.090350 :B0CA865F: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-07-12 02:00:00.090373 :B0CA866B: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-07-12 02:00:00.090420 :B0CA8676: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-07-12 02:00:00.091591 :B0CA8677:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 06:00:02.071678 :B0DD6D04:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 06:00:02.070 2025-07-12 06:00:02.071680 :B0DD6D05:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10520 pso_num=27 pso_serial#=45 2025-07-12 06:00:02.071818 :B0DD6D06:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=10520 osp_idx=25 osp_ver=149228 osp_pg=0 (spawn #376932) 2025-07-12 06:00:02.071819 :B0DD6D07:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 06:00:02.071819 :B0DD6D08:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=89 time=980777954 2025-07-12 06:00:02.071820 :B0DD6D09:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 06:00:02.071820 :B0DD6D0A:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 06:00:02.071821 :B0DD6D0B:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 06:00:02.071821 :B0DD6D0C:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 06:00:02.071822 :B0DD6D0D:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 06:00:02.072317 :B0DD6D17: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-07-12 06:00:02.077193 :B0DD6D34: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-07-12 06:00:02.077229 :B0DD6D37:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:jslv.c:9203 2025-07-12 06:00:02.081984 :B0DD6D64:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-07-12 06:00:02.082598 :B0DD6D65:db_trace:kcc.c@13684:kccwbp(): [10021:27:219:1] 0115000000000000 0115000000000000 3a3e120000000000 2025-07-12 06:00:02.084276 :B0DD6D9E: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-07-12 06:00:02.084352 :B0DD6DAC: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-07-12 06:00:02.084360 :B0DD6DB2:db_trace:ksb.c@6610:ksbcic_int(): [10254:27:219] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 8397264 1) 2025-07-12 06:00:02.084364 :B0DD6DB3: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-07-12 06:00:02.084393 :B0DD6DBF: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-07-12 06:00:02.085071 :B0DD6DCB:db_trace:ksb.c@6610:ksbcic_int(): [10254:27:219] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (4 0 231099082) 2025-07-12 06:00:02.085075 :B0DD6DCC: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-07-12 06:00:02.085097 :B0DD6DD7: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-07-12 06:00:02.085104 :B0DD6DE1:db_trace:ksb.c@6610:ksbcic_int(): [10254:27:219] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (6 65563 1) 2025-07-12 06:00:02.085107 :B0DD6DE4: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-07-12 06:00:02.085127 :B0DD6DF0: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-07-12 06:00:02.085130 :B0DD6DFB:db_trace:ksb.c@6610:ksbcic_int(): [10254:27:219] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (1 2 65563) 2025-07-12 06:00:02.085133 :B0DD6DFD: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-07-12 06:00:02.085172 :B0DD6E21: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-07-12 06:00:02.085824 :B0DD6EAF: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-07-12 06:00:02.085860 :B0DD6EBF:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-07-12 06:00:02.086870 :B0DD6EE8:db_trace:kcc.c@13684:kccwbp(): [10021:27:219:1] 0115000000000000 0115000000000000 3b3e120000000000 2025-07-12 06:00:02.087264 :B0DD6EE9:db_trace:ksb.c@6610:ksbcic_int(): [10254:27:219] KSBCIC: (USER) starting cic on opcode KSBCIBBS [20] with args (2 0 231099082) 2025-07-12 06:00:02.087270 :B0DD6EEA: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-07-12 06:00:02.087299 :B0DD6EF3: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-07-12 06:00:02.095997 :B0DD6F58:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:jslv.c:9203 2025-07-12 06:00:02.097953 :B0DD6F59: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-07-12 06:00:02.098019 :B0DD6F5C: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-07-12 06:00:02.098146 :B0DD6F69: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-07-12 06:00:02.196051 :B0DD7002: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 2025-07-12 06:00:02.508854 :B0DD7065: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: 10520, J002)