Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250705140808\orcl_j002_16372_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:37418M/63366M, Ph+PgF:43383M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 16372, image: ORACLE.EXE (J002) *** 2025-07-05 14:08:08.499 *** SESSION ID:(219.64809) 2025-07-05 14:08:08.499 *** 2025-07-05 14:08:08.499 Process diagnostic dump for ORACLE.EXE (J002), OS id=16372, pid: 27, proc_ser: 49, sid: 219, sess_ser: 64809 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 16372 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.248567 sec, exc=0.248567 sec, total=0.248567 sec wait times: max=0.500000 sec, heur=5.262152 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.000011 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.499989 sec, exc=0.499989 sec, total=0.499989 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=8 seq_num=9 snap_id=1 wait times: snap=0.500007 sec, exc=0.500007 sec, total=0.500007 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 2: 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.000009 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 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.000007 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 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.000010 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.510108 sec, exc=0.510108 sec, total=0.510108 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=3 seq_num=4 snap_id=1 wait times: snap=0.510094 sec, exc=0.510094 sec, total=0.510094 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 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.000009 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.493303 sec, exc=0.493303 sec, total=0.493303 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 219 serial 64809 --------------------------------------------------- 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 --------------------------------------------------- [6 samples, 14:08:03 - 14:08:08] idle wait at each sample [session created at: 14:08:03] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-05 14:08:08.499 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 16372, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-05 14:03:20.443060 :ADE0BE94:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-05 14:03:20.443628 :ADE0BE95: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-05 14:03:20.443674 :ADE0BE9B: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-05 14:03:20.452230 :ADE0BEAA:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:03:32.414791 :ADE0C25E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:03:32.399 2025-07-05 14:03:32.414793 :ADE0C25F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16304 pso_num=27 pso_serial#=22 2025-07-05 14:03:32.414936 :ADE0C260:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16304 (legacy spawn) 2025-07-05 14:03:32.421718 :ADE0C261: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-05 14:03:32.421897 :ADE0C26A: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-05 14:03:32.422758 :ADE0C26B:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:03:32.431829 :ADE0C26E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:03:32.431 2025-07-05 14:03:32.431831 :ADE0C26F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8380 pso_num=27 pso_serial#=23 2025-07-05 14:03:32.431970 :ADE0C270:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8380 (legacy spawn) 2025-07-05 14:03:32.438837 :ADE0C271: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-05 14:03:32.439024 :ADE0C27A: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-05 14:03:32.439921 :ADE0C27B:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:03:35.605571 :ADE0C3CD:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:03:35.592 2025-07-05 14:03:35.605573 :ADE0C3CE:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15220 pso_num=27 pso_serial#=24 2025-07-05 14:03:35.605708 :ADE0C3CF:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15220 (legacy spawn) 2025-07-05 14:03:35.612357 :ADE0C3D0: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-05 14:03:35.612546 :ADE0C3D5: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-05 14:03:35.613361 :ADE0C3DA:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:03:35.622158 :ADE0C3DD:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:03:35.607 2025-07-05 14:03:35.622160 :ADE0C3DE:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18324 pso_num=27 pso_serial#=25 2025-07-05 14:03:35.622320 :ADE0C3DF:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18324 (legacy spawn) 2025-07-05 14:03:35.628906 :ADE0C3E0: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-05 14:03:35.629076 :ADE0C3E9: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-05 14:03:35.629902 :ADE0C3EA:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:03:37.340752 :ADE0C442:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:03:37.332 2025-07-05 14:03:37.340755 :ADE0C443:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13380 pso_num=27 pso_serial#=26 2025-07-05 14:03:37.340892 :ADE0C444:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13380 (legacy spawn) 2025-07-05 14:03:37.347634 :ADE0C445: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-05 14:03:37.347814 :ADE0C44A: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-05 14:03:37.348638 :ADE0C44F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:03:37.357441 :ADE0C452:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:03:37.348 2025-07-05 14:03:37.357442 :ADE0C453:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17600 pso_num=27 pso_serial#=27 2025-07-05 14:03:37.357589 :ADE0C454:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17600 (legacy spawn) 2025-07-05 14:03:37.364255 :ADE0C455: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-05 14:03:37.364420 :ADE0C45D: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-05 14:03:37.365234 :ADE0C45F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:03:38.060597 :ADE0C4A4:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:03:38.051 2025-07-05 14:03:38.060599 :ADE0C4A5:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15668 pso_num=27 pso_serial#=28 2025-07-05 14:03:38.060742 :ADE0C4A6:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15668 (legacy spawn) 2025-07-05 14:03:38.067420 :ADE0C4A7: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-05 14:03:38.067576 :ADE0C4B0: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-05 14:03:38.068374 :ADE0C4B1:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:04:22.735470 :ADE0D3C6:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:04:22.727 2025-07-05 14:04:22.735473 :ADE0D3C7:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16116 pso_num=27 pso_serial#=29 2025-07-05 14:04:22.735619 :ADE0D3C8:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16116 (legacy spawn) 2025-07-05 14:04:22.742532 :ADE0D3C9: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-05 14:04:22.742723 :ADE0D3CE: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-05 14:04:22.743591 :ADE0D3D3:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:04:44.945017 :ADE0DB09:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:04:44.929 2025-07-05 14:04:44.945019 :ADE0DB0A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17040 pso_num=27 pso_serial#=30 2025-07-05 14:04:44.945170 :ADE0DB0B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17040 (legacy spawn) 2025-07-05 14:04:44.951866 :ADE0DB0C: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-05 14:04:44.952039 :ADE0DB11: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-05 14:04:44.952818 :ADE0DB16:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:04:44.961784 :ADE0DB19:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:04:44.961 2025-07-05 14:04:44.961786 :ADE0DB1A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15688 pso_num=27 pso_serial#=31 2025-07-05 14:04:44.961932 :ADE0DB1B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15688 (legacy spawn) 2025-07-05 14:04:44.968562 :ADE0DB1C: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-05 14:04:44.968725 :ADE0DB25: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-05 14:04:44.969523 :ADE0DB26:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:04:46.468477 :ADE0DB7E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:04:46.454 2025-07-05 14:04:46.468479 :ADE0DB7F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18396 pso_num=27 pso_serial#=32 2025-07-05 14:04:46.468614 :ADE0DB80:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18396 (legacy spawn) 2025-07-05 14:04:46.475237 :ADE0DB81: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-05 14:04:46.475407 :ADE0DB86: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-05 14:04:46.476245 :ADE0DB8B:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:05:20.440986 :ADE0E6F5:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:05:20.431 2025-07-05 14:05:20.440988 :ADE0E6F6:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4640 pso_num=27 pso_serial#=33 2025-07-05 14:05:20.441128 :ADE0E6F7:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4640 (legacy spawn) 2025-07-05 14:05:20.447880 :ADE0E6F8: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-05 14:05:20.448051 :ADE0E701: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-05 14:05:20.448842 :ADE0E702:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:05:21.071529 :ADE0E767:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:05:21.072 2025-07-05 14:05:21.071531 :ADE0E768:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18276 pso_num=27 pso_serial#=34 2025-07-05 14:05:21.071673 :ADE0E769:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18276 (legacy spawn) 2025-07-05 14:05:21.078734 :ADE0E76A: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-05 14:05:21.078924 :ADE0E76F: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-05 14:05:21.079862 :ADE0E774:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:05:21.111997 :ADE0E777:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:05:21.103 2025-07-05 14:05:21.111998 :ADE0E778:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18216 pso_num=27 pso_serial#=35 2025-07-05 14:05:21.112134 :ADE0E779:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18216 (legacy spawn) 2025-07-05 14:05:21.118831 :ADE0E77A: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-05 14:05:21.119010 :ADE0E782: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-05 14:05:21.119815 :ADE0E784:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:05:24.201406 :ADE0EA7A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:05:24.188 2025-07-05 14:05:24.201408 :ADE0EA7B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=2696 pso_num=27 pso_serial#=36 2025-07-05 14:05:24.201544 :ADE0EA7C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=2696 (legacy spawn) 2025-07-05 14:05:24.208077 :ADE0EA7D: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-05 14:05:24.208107 :ADE0EA80: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-05 14:05:24.208266 :ADE0EA87: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-05 14:05:24.209045 :ADE0EA94:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:05:24.217957 :ADE0EA97:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:05:24.203 2025-07-05 14:05:24.217959 :ADE0EA98:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12740 pso_num=27 pso_serial#=37 2025-07-05 14:05:24.218094 :ADE0EA99:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12740 (legacy spawn) 2025-07-05 14:05:24.224597 :ADE0EA9A: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-05 14:05:24.224769 :ADE0EAA3: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-05 14:05:24.225563 :ADE0EAA4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:05:24.899513 :ADE0EABE:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:05:24.891 2025-07-05 14:05:24.899515 :ADE0EABF:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14620 pso_num=27 pso_serial#=38 2025-07-05 14:05:24.899656 :ADE0EAC0:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14620 (legacy spawn) 2025-07-05 14:05:24.906220 :ADE0EAC1: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-05 14:05:24.906379 :ADE0EAC6: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-05 14:05:24.907208 :ADE0EACB:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:06:27.092574 :ADE104B2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:06:27.084 2025-07-05 14:06:27.092576 :ADE104B3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18004 pso_num=27 pso_serial#=39 2025-07-05 14:06:27.092715 :ADE104B4:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18004 (legacy spawn) 2025-07-05 14:06:27.099612 :ADE104B5: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-05 14:06:27.099818 :ADE104BA: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-05 14:06:27.100784 :ADE104BF:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:06:28.854348 :ADE10521:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:06:28.845 2025-07-05 14:06:28.854350 :ADE10522:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8564 pso_num=27 pso_serial#=40 2025-07-05 14:06:28.854483 :ADE10523:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8564 (legacy spawn) 2025-07-05 14:06:28.861132 :ADE10524: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-05 14:06:28.961886 :ADE10529: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-05 14:06:28.962841 :ADE1052E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:06:33.992885 :ADE106CB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:06:33.978 2025-07-05 14:06:33.992887 :ADE106CC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10836 pso_num=27 pso_serial#=41 2025-07-05 14:06:33.993041 :ADE106CD:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10836 (legacy spawn) 2025-07-05 14:06:33.999691 :ADE106CE: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-05 14:06:33.999884 :ADE106D5: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-05 14:06:34.000710 :ADE106D7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:06:34.009864 :ADE106DA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:06:34.009 2025-07-05 14:06:34.009865 :ADE106DB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=6512 pso_num=27 pso_serial#=42 2025-07-05 14:06:34.010016 :ADE106DC:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=6512 (legacy spawn) 2025-07-05 14:06:34.016672 :ADE106DD: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-05 14:06:34.016853 :ADE106E6: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-05 14:06:34.017653 :ADE106E7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:06:37.194530 :ADE107EE:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:06:37.181 2025-07-05 14:06:37.194532 :ADE107EF:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=2512 pso_num=27 pso_serial#=43 2025-07-05 14:06:37.194667 :ADE107F0:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=2512 (legacy spawn) 2025-07-05 14:06:37.201691 :ADE107F1: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-05 14:06:37.201871 :ADE107F6: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-05 14:06:37.202783 :ADE107FB:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:06:37.212323 :ADE10824:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:06:37.212 2025-07-05 14:06:37.212324 :ADE10825:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15420 pso_num=27 pso_serial#=44 2025-07-05 14:06:37.212464 :ADE10826:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15420 (legacy spawn) 2025-07-05 14:06:37.219534 :ADE10827: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-05 14:06:37.219722 :ADE10830: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-05 14:06:37.220673 :ADE10831:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:07:29.510737 :ADE119CC:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:07:29.502 2025-07-05 14:07:29.510739 :ADE119CD:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17496 pso_num=27 pso_serial#=45 2025-07-05 14:07:29.510878 :ADE119CE:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17496 (legacy spawn) 2025-07-05 14:07:29.517626 :ADE119CF: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-05 14:07:29.517802 :ADE119D8: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-05 14:07:29.518629 :ADE119D9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:07:36.833872 :ADE11C2D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:07:36.824 2025-07-05 14:07:36.833874 :ADE11C2E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=7020 pso_num=27 pso_serial#=46 2025-07-05 14:07:36.834011 :ADE11C2F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=7020 (legacy spawn) 2025-07-05 14:07:36.840770 :ADE11C30: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-05 14:07:36.840942 :ADE11C39: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-05 14:07:36.841726 :ADE11C3A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:07:46.616138 :ADE11F69:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:07:46.612 2025-07-05 14:07:46.616140 :ADE11F6A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15604 pso_num=27 pso_serial#=47 2025-07-05 14:07:46.616278 :ADE11F6B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15604 (legacy spawn) 2025-07-05 14:07:46.622849 :ADE11F6C: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-05 14:07:46.623021 :ADE11F71: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-05 14:07:46.623827 :ADE11F76:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:07:46.632632 :ADE11F79:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:07:46.628 2025-07-05 14:07:46.632634 :ADE11F7A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18044 pso_num=27 pso_serial#=48 2025-07-05 14:07:46.632774 :ADE11F7B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18044 (legacy spawn) 2025-07-05 14:07:46.639344 :ADE11F7C: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-05 14:07:46.639515 :ADE11F85: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-05 14:07:46.640307 :ADE11F86:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 14:08:03.239545 :ADE129D1:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 14:08:03.234 2025-07-05 14:08:03.239547 :ADE129D2:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16372 pso_num=27 pso_serial#=49 2025-07-05 14:08:03.239738 :ADE129D4:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=16372 osp_idx=25 osp_ver=130617 osp_pg=0 (spawn #354738) 2025-07-05 14:08:03.239739 :ADE129D5: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-05 14:08:03.239740 :ADE129D6:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=237 time=405259126 2025-07-05 14:08:03.239740 :ADE129D7:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-05 14:08:03.239740 :ADE129D8:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-05 14:08:03.239741 :ADE129D9:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-05 14:08:03.239741 :ADE129DA:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-05 14:08:03.239741 :ADE129DB:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-05 14:08:03.240349 :ADE129E4: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 27 (osid: 16372, J002)