Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250503180846\orcl_j002_11168_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:37604M/63366M, Ph+PgF:45167M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 11168, image: ORACLE.EXE (J002) *** 2025-05-03 18:08:46.550 *** SESSION ID:(219.26445) 2025-05-03 18:08:46.550 *** 2025-05-03 18:08:46.550 Process diagnostic dump for ORACLE.EXE (J002), OS id=11168, pid: 27, proc_ser: 52, sid: 219, sess_ser: 26445 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 11168 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=16 seq_num=17 snap_id=1 wait times: snap=0.036395 sec, exc=0.036395 sec, total=0.036395 sec wait times: max=0.500000 sec, heur=7.861411 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.000004 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=0.321338 sec, exc=0.321338 sec, total=0.321338 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=14 seq_num=15 snap_id=1 wait times: snap=0.501202 sec, exc=0.501202 sec, total=0.501202 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.499990 sec, exc=0.499990 sec, total=0.499990 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000016 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 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.000009 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 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=10 seq_num=11 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.000009 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.500004 sec, exc=0.500004 sec, total=0.500004 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500125 sec, exc=0.500125 sec, total=0.500125 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 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 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 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.000009 sec of elapsed time Sampled Session History of session 219 serial 26445 --------------------------------------------------- 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, 18:08:38 - 18:08:46] idle wait at each sample [session created at: 18:08:38] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-03 18:08:46.550 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 11168, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-03 18:03:02.508599 :92391BBE: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-05-03 18:03:02.508782 :92391BC3: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-05-03 18:03:02.509588 :92391BC8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:03:02.518235 :92391BCB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:03:02.503 2025-05-03 18:03:02.518237 :92391BCC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9988 pso_num=27 pso_serial#=25 2025-05-03 18:03:02.518397 :92391BCD:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9988 (legacy spawn) 2025-05-03 18:03:02.524809 :92391BCE: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-05-03 18:03:02.524979 :92391BD5: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-05-03 18:03:02.525751 :92391BD8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:03:05.891429 :92391CD3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:03:05.878 2025-05-03 18:03:05.891431 :92391CD4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4872 pso_num=27 pso_serial#=26 2025-05-03 18:03:05.891569 :92391CD5:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4872 (legacy spawn) 2025-05-03 18:03:05.898100 :92391CD6: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-05-03 18:03:05.898271 :92391CDB: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-05-03 18:03:05.910144 :92391CE0:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:03:05.918564 :92391CE3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:03:05.909 2025-05-03 18:03:05.918566 :92391CE4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8548 pso_num=27 pso_serial#=27 2025-05-03 18:03:05.918702 :92391CE5:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8548 (legacy spawn) 2025-05-03 18:03:05.925171 :92391CE6: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-05-03 18:03:05.925326 :92391CEF: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-05-03 18:03:05.926139 :92391CF0:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:04:04.235122 :9239307F:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:04:04.226 2025-05-03 18:04:04.235124 :92393080:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10264 pso_num=27 pso_serial#=28 2025-05-03 18:04:04.235268 :92393081:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10264 (legacy spawn) 2025-05-03 18:04:04.241731 :92393082: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-05-03 18:04:04.241909 :9239308B: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-05-03 18:04:04.242733 :9239308C:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:04:14.604422 :923933DF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:04:14.596 2025-05-03 18:04:14.604424 :923933E0:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12256 pso_num=27 pso_serial#=29 2025-05-03 18:04:14.604557 :923933E1:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12256 (legacy spawn) 2025-05-03 18:04:14.611037 :923933E2: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-05-03 18:04:14.611220 :923933E7: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-05-03 18:04:14.612085 :923933EC:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:04:15.485775 :9239341C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:04:15.471 2025-05-03 18:04:15.485777 :9239341D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=2676 pso_num=27 pso_serial#=30 2025-05-03 18:04:15.485913 :9239341E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=2676 (legacy spawn) 2025-05-03 18:04:15.504052 :9239342A: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-05-03 18:04:15.504213 :92393433: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-05-03 18:04:15.505070 :92393434:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:04:15.513912 :92393437:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:04:15.502 2025-05-03 18:04:15.513915 :92393438:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8824 pso_num=27 pso_serial#=31 2025-05-03 18:04:15.514043 :92393439:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8824 (legacy spawn) 2025-05-03 18:04:15.520607 :9239343A: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-05-03 18:04:15.520781 :92393443: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-05-03 18:04:15.521632 :92393444:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:04:45.704688 :92393EC2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:04:45.696 2025-05-03 18:04:45.704691 :92393EC3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8780 pso_num=27 pso_serial#=32 2025-05-03 18:04:45.704867 :92393EC4:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8780 (legacy spawn) 2025-05-03 18:04:45.711214 :92393EC5: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-05-03 18:04:45.711409 :92393ECA: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-05-03 18:04:45.712267 :92393EDA:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:04:51.667400 :923941DC:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:04:51.653 2025-05-03 18:04:51.667402 :923941DD:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10828 pso_num=27 pso_serial#=33 2025-05-03 18:04:51.667547 :923941DE:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10828 (legacy spawn) 2025-05-03 18:04:51.673990 :923941DF: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-05-03 18:04:51.674158 :923941E4: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-05-03 18:04:51.674973 :923941E9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:04:51.683714 :923941EC:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:04:51.684 2025-05-03 18:04:51.683716 :923941ED:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12232 pso_num=27 pso_serial#=34 2025-05-03 18:04:51.683856 :923941EE:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12232 (legacy spawn) 2025-05-03 18:04:51.690262 :923941EF: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-05-03 18:04:51.690423 :923941F7: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-05-03 18:04:51.691238 :923941F9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:04:54.993344 :92394311:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:04:54.980 2025-05-03 18:04:54.993346 :92394312:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12072 pso_num=27 pso_serial#=35 2025-05-03 18:04:54.993490 :92394313:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12072 (legacy spawn) 2025-05-03 18:04:54.999989 :92394314: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-05-03 18:04:55.000161 :9239431D: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-05-03 18:04:55.000966 :9239431E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:04:55.009595 :92394321:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:04:54.996 2025-05-03 18:04:55.009597 :92394322:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8016 pso_num=27 pso_serial#=36 2025-05-03 18:04:55.009726 :92394323:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8016 (legacy spawn) 2025-05-03 18:04:55.016069 :92394324: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-05-03 18:04:55.016244 :9239432D: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-05-03 18:04:55.017042 :9239432E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:05:12.539984 :923948A7:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:05:12.532 2025-05-03 18:05:12.539986 :923948A8:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=7460 pso_num=27 pso_serial#=37 2025-05-03 18:05:12.540126 :923948A9:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=7460 (legacy spawn) 2025-05-03 18:05:12.546557 :923948AA: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-05-03 18:05:12.546732 :923948AF: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-05-03 18:05:12.547597 :923948B4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:05:46.616914 :923959B9:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:05:46.612 2025-05-03 18:05:46.616916 :923959BA:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9484 pso_num=27 pso_serial#=38 2025-05-03 18:05:46.617064 :923959BB:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9484 (legacy spawn) 2025-05-03 18:05:46.623795 :923959BC: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-05-03 18:05:46.623977 :923959C5: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-05-03 18:05:46.624819 :923959C6:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:05:47.870385 :92395A43:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:05:47.862 2025-05-03 18:05:47.870388 :92395A44:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12164 pso_num=27 pso_serial#=39 2025-05-03 18:05:47.870527 :92395A45:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12164 (legacy spawn) 2025-05-03 18:05:47.876913 :92395A46: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-05-03 18:05:47.877080 :92395A4B: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-05-03 18:05:47.877889 :92395A50:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:06:04.500204 :923960E6:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:06:04.485 2025-05-03 18:06:04.500206 :923960E7:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11508 pso_num=27 pso_serial#=40 2025-05-03 18:06:04.500346 :923960E8:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11508 (legacy spawn) 2025-05-03 18:06:04.521457 :923960E9: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-05-03 18:06:04.521634 :923960F2: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-05-03 18:06:04.522466 :923960F3:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:06:04.531134 :923960F6:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:06:04.517 2025-05-03 18:06:04.531136 :923960F7:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=2472 pso_num=27 pso_serial#=41 2025-05-03 18:06:04.531274 :923960F8:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=2472 (legacy spawn) 2025-05-03 18:06:04.537805 :923960F9: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-05-03 18:06:04.537974 :92396102: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-05-03 18:06:04.538784 :92396103:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:06:07.685801 :92396205:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:06:07.672 2025-05-03 18:06:07.685803 :92396206:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9712 pso_num=27 pso_serial#=42 2025-05-03 18:06:07.685952 :92396207:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9712 (legacy spawn) 2025-05-03 18:06:07.692454 :92396208: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-05-03 18:06:07.692630 :92396211: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-05-03 18:06:07.693432 :92396212:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:06:07.701983 :92396215:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:06:07.688 2025-05-03 18:06:07.701986 :92396216:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10900 pso_num=27 pso_serial#=43 2025-05-03 18:06:07.702116 :92396217:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10900 (legacy spawn) 2025-05-03 18:06:07.708752 :92396218: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-05-03 18:06:07.708925 :92396221: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-05-03 18:06:07.709835 :92396222:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:06:50.036592 :92397061:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:06:50.028 2025-05-03 18:06:50.036595 :92397062:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10836 pso_num=27 pso_serial#=44 2025-05-03 18:06:50.036780 :92397063:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10836 (legacy spawn) 2025-05-03 18:06:50.043144 :92397064: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-05-03 18:06:50.043328 :9239706D: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-05-03 18:06:50.044155 :9239706E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:06:54.745411 :92397215:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:06:54.730 2025-05-03 18:06:54.745414 :92397216:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9660 pso_num=27 pso_serial#=45 2025-05-03 18:06:54.745556 :92397217:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9660 (legacy spawn) 2025-05-03 18:06:54.763558 :92397218: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-05-03 18:06:54.763729 :9239721D: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-05-03 18:06:54.764624 :92397222:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:07:52.159496 :9239858D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:07:52.152 2025-05-03 18:07:52.159498 :9239858E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9116 pso_num=27 pso_serial#=46 2025-05-03 18:07:52.159635 :9239858F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9116 (legacy spawn) 2025-05-03 18:07:52.166054 :92398590: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-05-03 18:07:52.166229 :92398599: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-05-03 18:07:52.167027 :9239859A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:07:53.947850 :92398621:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:07:53.933 2025-05-03 18:07:53.947851 :92398622:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8312 pso_num=27 pso_serial#=47 2025-05-03 18:07:53.947983 :92398623:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8312 (legacy spawn) 2025-05-03 18:07:53.954418 :92398624: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-05-03 18:07:53.954587 :9239862A: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-05-03 18:07:53.955416 :9239862E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:07:53.972975 :92398631:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:07:53.964 2025-05-03 18:07:53.972977 :92398632:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4728 pso_num=27 pso_serial#=48 2025-05-03 18:07:53.973113 :92398633:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4728 (legacy spawn) 2025-05-03 18:07:53.979552 :92398634: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-05-03 18:07:53.979732 :9239863C: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-05-03 18:07:53.980530 :9239863E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:07:57.091998 :9239873A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:07:57.078 2025-05-03 18:07:57.092000 :9239873B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10048 pso_num=27 pso_serial#=49 2025-05-03 18:07:57.092133 :9239873C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10048 (legacy spawn) 2025-05-03 18:07:57.098596 :9239873D: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-05-03 18:07:57.098762 :92398746: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-05-03 18:07:57.099574 :92398747:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:07:57.108236 :9239874A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:07:57.094 2025-05-03 18:07:57.108238 :9239874B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11648 pso_num=27 pso_serial#=50 2025-05-03 18:07:57.108378 :9239874C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11648 (legacy spawn) 2025-05-03 18:07:57.114774 :9239874D: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-05-03 18:07:57.114941 :92398756: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-05-03 18:07:57.115732 :92398757:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:08:02.826750 :9239891E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:08:02.817 2025-05-03 18:08:02.826752 :9239891F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4572 pso_num=27 pso_serial#=51 2025-05-03 18:08:02.826883 :92398920:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4572 (legacy spawn) 2025-05-03 18:08:02.833387 :92398921: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-05-03 18:08:02.833552 :92398926: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-05-03 18:08:02.834403 :9239892B:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 18:08:38.701885 :92399984:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 18:08:38.696 2025-05-03 18:08:38.701887 :92399985:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11168 pso_num=27 pso_serial#=52 2025-05-03 18:08:38.702017 :92399986:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=11168 osp_idx=25 osp_ver=45951 osp_pg=0 (spawn #144792) 2025-05-03 18:08:38.702018 :92399987:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-03 18:08:38.702018 :92399988:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=83 time=3566508187 2025-05-03 18:08:38.702019 :92399989:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-05-03 18:08:38.702019 :9239998A:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-05-03 18:08:38.702020 :9239998B:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 18:08:38.702020 :9239998C:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 18:08:38.702020 :9239998D:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 18:08:38.702561 :92399997: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-05-03 18:08:46.521881 :9239A000: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: 11168, J002)