Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250601180748\orcl_j002_13060_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:37147M/63366M, Ph+PgF:44005M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 13060, image: ORACLE.EXE (J002) *** 2025-06-01 18:07:48.979 *** SESSION ID:(225.13009) 2025-06-01 18:07:48.979 *** 2025-06-01 18:07:48.979 Process diagnostic dump for ORACLE.EXE (J002), OS id=13060, pid: 28, proc_ser: 83, sid: 225, sess_ser: 13009 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 13060 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.253378 sec, exc=0.253378 sec, total=0.253378 sec wait times: max=0.500000 sec, heur=6.754496 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.000010 sec since current wait 0: 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.000010 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 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=10 seq_num=11 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.000010 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 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 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.505089 sec, exc=0.505089 sec, total=0.505089 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=7 seq_num=8 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=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.000010 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 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=4 seq_num=5 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.000010 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 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.000007 sec of elapsed time Sampled Session History of session 225 serial 13009 --------------------------------------------------- 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 --------------------------------------------------- [7 samples, 18:07:42 - 18:07:48] idle wait at each sample [session created at: 18:07:42] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-01 18:07:48.979 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 13060, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-01 18:02:45.053220 :9EF9112B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14988 (legacy spawn) 2025-06-01 18:02:45.059650 :9EF9112C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:02:45.059812 :9EF91135:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 18:02:45.060591 :9EF91136:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:02:55.394127 :9EF9140E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:02:55.381 2025-06-01 18:02:55.394128 :9EF9140F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14876 pso_num=28 pso_serial#=57 2025-06-01 18:02:55.394264 :9EF91410:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14876 (legacy spawn) 2025-06-01 18:02:55.400872 :9EF91411:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:02:55.401057 :9EF91417:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 18:02:55.401881 :9EF9141B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:03:40.241259 :9EF92597:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:03:40.233 2025-06-01 18:03:40.241261 :9EF92598:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13548 pso_num=28 pso_serial#=58 2025-06-01 18:03:40.241397 :9EF92599:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13548 (legacy spawn) 2025-06-01 18:03:40.247903 :9EF9259A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:03:40.248071 :9EF925A3:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 18:03:40.248928 :9EF925A4:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:03:42.857430 :9EF9270F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:03:42.844 2025-06-01 18:03:42.857432 :9EF92710:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14268 pso_num=28 pso_serial#=59 2025-06-01 18:03:42.857625 :9EF92711:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14268 (legacy spawn) 2025-06-01 18:03:42.864025 :9EF92712:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:03:42.864212 :9EF9271B:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 18:03:42.865022 :9EF9271C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:03:42.873587 :9EF9271F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:03:42.860 2025-06-01 18:03:42.873589 :9EF92720:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11488 pso_num=28 pso_serial#=60 2025-06-01 18:03:42.873722 :9EF92721:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11488 (legacy spawn) 2025-06-01 18:03:42.880499 :9EF92722:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:03:42.880659 :9EF9272B:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 18:03:42.881675 :9EF9272C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:03:54.466332 :9EF92AB7:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:03:54.453 2025-06-01 18:03:54.466334 :9EF92AB8:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13584 pso_num=28 pso_serial#=61 2025-06-01 18:03:54.466506 :9EF92AB9:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13584 (legacy spawn) 2025-06-01 18:03:54.472949 :9EF92ABA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:03:54.473133 :9EF92ABF:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 18:03:54.473922 :9EF92AC4:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:03:54.482468 :9EF92AC7:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:03:54.468 2025-06-01 18:03:54.482470 :9EF92AC8:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13100 pso_num=28 pso_serial#=62 2025-06-01 18:03:54.482603 :9EF92AC9:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13100 (legacy spawn) 2025-06-01 18:03:54.489027 :9EF92ACA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:03:54.489199 :9EF92AD3:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 18:03:54.489980 :9EF92AD4:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:03:57.642220 :9EF92BBB:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:03:57.629 2025-06-01 18:03:57.642222 :9EF92BBC:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15072 pso_num=28 pso_serial#=63 2025-06-01 18:03:57.642356 :9EF92BBD:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15072 (legacy spawn) 2025-06-01 18:03:57.648842 :9EF92BBE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:03:57.649016 :9EF92BC3:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 18:03:57.649794 :9EF92BC8:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:03:57.658402 :9EF92BCB:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:03:57.645 2025-06-01 18:03:57.658405 :9EF92BCC:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13028 pso_num=28 pso_serial#=64 2025-06-01 18:03:57.658549 :9EF92BCD:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13028 (legacy spawn) 2025-06-01 18:03:57.665028 :9EF92BCE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:03:57.665204 :9EF92BD7:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 18:03:57.665994 :9EF92BD8:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:04:00.611417 :9EF92CC8:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:04:00.602 2025-06-01 18:04:00.611419 :9EF92CC9:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14680 pso_num=28 pso_serial#=65 2025-06-01 18:04:00.611557 :9EF92CCA:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14680 (legacy spawn) 2025-06-01 18:04:00.618015 :9EF92CCB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:04:00.618194 :9EF92CD0:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 18:04:00.618980 :9EF92CD5:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:04:03.485213 :9EF92DCC:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:04:03.472 2025-06-01 18:04:03.485215 :9EF92DCD:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12492 pso_num=28 pso_serial#=66 2025-06-01 18:04:03.485354 :9EF92DCE:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12492 (legacy spawn) 2025-06-01 18:04:03.491756 :9EF92DCF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:04:03.491924 :9EF92DD4:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 18:04:03.492724 :9EF92DD9:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:04:42.443154 :9EF93A92:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:04:42.435 2025-06-01 18:04:42.443156 :9EF93A93:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13972 pso_num=28 pso_serial#=67 2025-06-01 18:04:42.443288 :9EF93A94:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13972 (legacy spawn) 2025-06-01 18:04:42.449753 :9EF93A95:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:04:42.449924 :9EF93A9E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 18:04:42.450731 :9EF93A9F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:05:07.349997 :9EF9421B:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:05:07.337 2025-06-01 18:05:07.349999 :9EF9421C:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13364 pso_num=28 pso_serial#=68 2025-06-01 18:05:07.350138 :9EF9421D:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13364 (legacy spawn) 2025-06-01 18:05:07.356686 :9EF9421E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:05:07.356853 :9EF94227:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 18:05:07.357637 :9EF94228:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:05:07.366307 :9EF9422B:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:05:07.353 2025-06-01 18:05:07.366310 :9EF9422C:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14252 pso_num=28 pso_serial#=69 2025-06-01 18:05:07.366439 :9EF9422D:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14252 (legacy spawn) 2025-06-01 18:05:07.372943 :9EF9422E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:05:07.373108 :9EF94236:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 18:05:07.373890 :9EF94238:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:05:43.635733 :9EF94F0A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:05:43.620 2025-06-01 18:05:43.635735 :9EF94F0B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10856 pso_num=28 pso_serial#=70 2025-06-01 18:05:43.635898 :9EF94F0C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10856 (legacy spawn) 2025-06-01 18:05:43.642876 :9EF94F0D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:05:43.643063 :9EF94F12:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 18:05:43.644079 :9EF94F17:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:05:43.652818 :9EF94F1A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:05:43.651 2025-06-01 18:05:43.652821 :9EF94F1B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=9584 pso_num=28 pso_serial#=71 2025-06-01 18:05:43.652961 :9EF94F1C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=9584 (legacy spawn) 2025-06-01 18:05:43.659589 :9EF94F1D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:05:43.659761 :9EF94F25:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 18:05:43.660606 :9EF94F26:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:05:44.613115 :9EF94F93:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:05:44.605 2025-06-01 18:05:44.613117 :9EF94F94:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12276 pso_num=28 pso_serial#=72 2025-06-01 18:05:44.613252 :9EF94F95:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12276 (legacy spawn) 2025-06-01 18:05:44.619774 :9EF94F96:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:05:44.619938 :9EF94F9F:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 18:05:44.620825 :9EF94FA0:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:05:45.801437 :9EF95501:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:05:45.793 2025-06-01 18:05:45.801439 :9EF95502:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10824 pso_num=28 pso_serial#=73 2025-06-01 18:05:45.801571 :9EF95503:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10824 (legacy spawn) 2025-06-01 18:05:45.808020 :9EF95504:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:05:45.808268 :9EF95509:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 18:05:45.809140 :9EF9550E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:05:47.054477 :9EF95559:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:05:47.043 2025-06-01 18:05:47.054478 :9EF9555A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=8928 pso_num=28 pso_serial#=74 2025-06-01 18:05:47.054614 :9EF9555B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=8928 (legacy spawn) 2025-06-01 18:05:47.061102 :9EF9555C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:05:47.061278 :9EF95561:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 18:05:47.062057 :9EF95566:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:05:47.070736 :9EF95569:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:05:47.058 2025-06-01 18:05:47.070738 :9EF9556A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14016 pso_num=28 pso_serial#=75 2025-06-01 18:05:47.070879 :9EF9556B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14016 (legacy spawn) 2025-06-01 18:05:47.077294 :9EF9556C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:05:47.077464 :9EF95574:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 18:05:47.078272 :9EF95576:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:06:46.806560 :9EF9688F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:06:46.799 2025-06-01 18:06:46.806562 :9EF96890:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14532 pso_num=28 pso_serial#=76 2025-06-01 18:06:46.806703 :9EF96891:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14532 (legacy spawn) 2025-06-01 18:06:46.813228 :9EF96892:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:06:46.813420 :9EF9689B:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 18:06:46.814259 :9EF9689C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:06:53.994939 :9EF96B0E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:06:53.986 2025-06-01 18:06:53.994941 :9EF96B0F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11816 pso_num=28 pso_serial#=77 2025-06-01 18:06:53.995077 :9EF96B10:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11816 (legacy spawn) 2025-06-01 18:06:54.001554 :9EF96B11:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:06:54.001745 :9EF96B16:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 18:06:54.002527 :9EF96B1B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:06:56.469420 :9EF96BCE:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:06:56.455 2025-06-01 18:06:56.469422 :9EF96BCF:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14280 pso_num=28 pso_serial#=78 2025-06-01 18:06:56.469559 :9EF96BD0:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14280 (legacy spawn) 2025-06-01 18:06:56.475956 :9EF96BD1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:06:56.476135 :9EF96BD6:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 18:06:56.476959 :9EF96BDB:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:06:56.485637 :9EF96BDE:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:06:56.471 2025-06-01 18:06:56.485639 :9EF96BDF:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11896 pso_num=28 pso_serial#=79 2025-06-01 18:06:56.485776 :9EF96BE0:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11896 (legacy spawn) 2025-06-01 18:06:56.492231 :9EF96BE1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:06:56.492396 :9EF96BEA:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 18:06:56.493187 :9EF96BEB:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:06:59.638506 :9EF96CDD:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:06:59.626 2025-06-01 18:06:59.638508 :9EF96CDE:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11860 pso_num=28 pso_serial#=80 2025-06-01 18:06:59.638653 :9EF96CDF:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11860 (legacy spawn) 2025-06-01 18:06:59.645012 :9EF96CE0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:06:59.645183 :9EF96CE6:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 18:06:59.646029 :9EF96CEA:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:06:59.665021 :9EF96CED:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:06:59.657 2025-06-01 18:06:59.665023 :9EF96CEE:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14576 pso_num=28 pso_serial#=81 2025-06-01 18:06:59.665158 :9EF96CEF:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14576 (legacy spawn) 2025-06-01 18:06:59.671573 :9EF96CF0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-01 18:06:59.671736 :9EF96CF8:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 18:06:59.672661 :9EF96CFA:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:07:42.024270 :9EF97ACB:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:07:42.019 2025-06-01 18:07:42.024273 :9EF97ACC:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13440 pso_num=28 pso_serial#=82 2025-06-01 18:07:42.024417 :9EF97ACD:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=13440 osp_idx=26 osp_ver=170215 osp_pg=0 (spawn #241952) 2025-06-01 18:07:42.024418 :9EF97ACE:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-01 18:07:42.024419 :9EF97ACF:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=228 time=1777020672 2025-06-01 18:07:42.024419 :9EF97AD0:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-06-01 18:07:42.024419 :9EF97AD1:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-06-01 18:07:42.024420 :9EF97AD2:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-06-01 18:07:42.024420 :9EF97AD3:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-01 18:07:42.024420 :9EF97AD4:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-01 18:07:42.024911 :9EF97AD5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-01 18:07:42.024956 :9EF97ADB:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-01 18:07:42.048207 :9EF97B70:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-01 18:07:42.227586 :9EF97D31:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-01 18:07:42.222 2025-06-01 18:07:42.227588 :9EF97D32:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13060 pso_num=28 pso_serial#=83 2025-06-01 18:07:42.227718 :9EF97D33:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=13060 osp_idx=26 osp_ver=170216 osp_pg=0 (spawn #241955) 2025-06-01 18:07:42.227719 :9EF97D34:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-01 18:07:42.227719 :9EF97D35:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=229 time=1777020875 2025-06-01 18:07:42.227720 :9EF97D36:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-06-01 18:07:42.227721 :9EF97D37:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-06-01 18:07:42.227721 :9EF97D38:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-06-01 18:07:42.227721 :9EF97D39:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-01 18:07:42.227722 :9EF97D3A:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-01 18:07:42.228213 :9EF97D44:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] 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 28 (osid: 13060, J002)