Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250621180641\orcl_j002_16320_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:36417M/63366M, Ph+PgF:42843M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 16320, image: ORACLE.EXE (J002) *** 2025-06-21 18:06:41.526 *** SESSION ID:(219.46569) 2025-06-21 18:06:41.526 *** 2025-06-21 18:06:41.526 Process diagnostic dump for ORACLE.EXE (J002), OS id=16320, pid: 27, proc_ser: 5, sid: 219, sess_ser: 46569 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 16320 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=17 seq_num=18 snap_id=1 wait times: snap=0.032181 sec, exc=0.032181 sec, total=0.032181 sec wait times: max=0.500000 sec, heur=8.556368 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=16 seq_num=17 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=0.510085 sec, exc=0.510085 sec, total=0.510085 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 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.000011 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 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=11 seq_num=12 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.000013 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.510102 sec, exc=0.510102 sec, total=0.510102 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 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.000013 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time Sampled Session History of session 219 serial 46569 --------------------------------------------------- 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 --------------------------------------------------- [9 samples, 18:06:32 - 18:06:41] idle wait at each sample [session created at: 18:06:32] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-21 18:06:41.526 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 16320, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-21 18:02:39.971737 :A7C35963:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17980 (legacy spawn) 2025-06-21 18:02:39.978238 :A7C35964:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 18:02:39.978416 :A7C35969:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 18:02:39.979237 :A7C3596E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:02:41.492479 :A7C359F6:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:02:41.479 2025-06-21 18:02:41.492481 :A7C359F7:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14720 pso_num=27 pso_serial#=234 2025-06-21 18:02:41.492614 :A7C359F8:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14720 (legacy spawn) 2025-06-21 18:02:41.499014 :A7C359F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 18:02:41.499193 :A7C35A02:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 18:02:41.499973 :A7C35A03:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:02:41.519313 :A7C35A06:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:02:41.511 2025-06-21 18:02:41.519315 :A7C35A07:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18176 pso_num=27 pso_serial#=235 2025-06-21 18:02:41.519450 :A7C35A08:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18176 (legacy spawn) 2025-06-21 18:02:41.525986 :A7C35A09:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 18:02:41.526155 :A7C35A12:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 18:02:41.526952 :A7C35A13:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:02:52.715050 :A7C35D63:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:02:52.707 2025-06-21 18:02:52.715052 :A7C35D64:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16732 pso_num=27 pso_serial#=236 2025-06-21 18:02:52.715187 :A7C35D65:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16732 (legacy spawn) 2025-06-21 18:02:52.721695 :A7C35D66:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 18:02:52.721878 :A7C35D6B:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 18:02:52.722715 :A7C35D70:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:02:54.913247 :A7C35E5D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:02:54.899 2025-06-21 18:02:54.913249 :A7C35E5E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14356 pso_num=27 pso_serial#=237 2025-06-21 18:02:54.913383 :A7C35E5F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14356 (legacy spawn) 2025-06-21 18:02:54.919809 :A7C35E60:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 18:02:54.919971 :A7C35E69:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 18:02:54.920761 :A7C35E6A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:02:54.929317 :A7C35E6D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:02:54.915 2025-06-21 18:02:54.929319 :A7C35E6E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16000 pso_num=27 pso_serial#=238 2025-06-21 18:02:54.929453 :A7C35E6F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16000 (legacy spawn) 2025-06-21 18:02:54.935948 :A7C35E70:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 18:02:54.936109 :A7C35E79:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 18:02:54.936887 :A7C35E7A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:03:14.724524 :A7C36492:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:03:14.720 2025-06-21 18:03:14.724526 :A7C36493:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18048 pso_num=27 pso_serial#=239 2025-06-21 18:03:14.724680 :A7C36494:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18048 (legacy spawn) 2025-06-21 18:03:14.731573 :A7C36495:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 18:03:14.731745 :A7C3649D:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 18:03:14.732546 :A7C3649F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:03:14.741467 :A7C364A2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:03:14.735 2025-06-21 18:03:14.741469 :A7C364A3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17752 pso_num=27 pso_serial#=240 2025-06-21 18:03:14.741615 :A7C364A4:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17752 (legacy spawn) 2025-06-21 18:03:14.748186 :A7C364A5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 18:03:14.748353 :A7C364AE:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 18:03:14.749173 :A7C364AF:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:03:17.790075 :A7C36595:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:03:17.777 2025-06-21 18:03:17.790077 :A7C36596:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17088 pso_num=27 pso_serial#=241 2025-06-21 18:03:17.790213 :A7C36597:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17088 (legacy spawn) 2025-06-21 18:03:17.796629 :A7C36598:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 18:03:17.796807 :A7C365A1:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 18:03:17.797616 :A7C365A2:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:03:17.806186 :A7C365A5:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:03:17.792 2025-06-21 18:03:17.806188 :A7C365A6:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17584 pso_num=27 pso_serial#=242 2025-06-21 18:03:17.806329 :A7C365A7:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17584 (legacy spawn) 2025-06-21 18:03:17.812839 :A7C365A8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 18:03:17.813006 :A7C365B1:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 18:03:17.813830 :A7C365B2:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:03:19.644406 :A7C3662E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:03:19.636 2025-06-21 18:03:19.644408 :A7C3662F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16368 pso_num=27 pso_serial#=243 2025-06-21 18:03:19.644549 :A7C36630:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16368 (legacy spawn) 2025-06-21 18:03:19.651010 :A7C36631:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 18:03:19.651178 :A7C36636:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 18:03:19.651991 :A7C3663B:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:03:42.125127 :A7C36DCC:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:03:42.117 2025-06-21 18:03:42.125128 :A7C36DCD:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17152 pso_num=27 pso_serial#=244 2025-06-21 18:03:42.125267 :A7C36DCE:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17152 (legacy spawn) 2025-06-21 18:03:42.131846 :A7C36DCF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 18:03:42.132036 :A7C36DD8:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 18:03:42.132889 :A7C36DD9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:03:51.009666 :A7C37066:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:03:50.995 2025-06-21 18:03:51.009668 :A7C37067:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12508 pso_num=27 pso_serial#=245 2025-06-21 18:03:51.009820 :A7C37068:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12508 (legacy spawn) 2025-06-21 18:03:51.016212 :A7C37069:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 18:03:51.016389 :A7C37072:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 18:03:51.017189 :A7C37073:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:03:51.035255 :A7C37076:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:03:51.027 2025-06-21 18:03:51.035257 :A7C37077:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10836 pso_num=27 pso_serial#=246 2025-06-21 18:03:51.035398 :A7C37078:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10836 (legacy spawn) 2025-06-21 18:03:51.041783 :A7C37079:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 18:03:51.041948 :A7C37082:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 18:03:51.042766 :A7C37083:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:03:54.138412 :A7C3719E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:03:54.125 2025-06-21 18:03:54.138414 :A7C3719F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13968 pso_num=27 pso_serial#=247 2025-06-21 18:03:54.138552 :A7C371A0:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13968 (legacy spawn) 2025-06-21 18:03:54.144953 :A7C371C1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 18:03:54.145117 :A7C371CA:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 18:03:54.145900 :A7C371CB:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:03:54.154564 :A7C371CE:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:03:54.141 2025-06-21 18:03:54.154565 :A7C371CF:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18296 pso_num=27 pso_serial#=248 2025-06-21 18:03:54.154701 :A7C371D0:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18296 (legacy spawn) 2025-06-21 18:03:54.161127 :A7C371D1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 18:03:54.161296 :A7C371DA:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 18:03:54.162097 :A7C371DB:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:04:30.493024 :A7C37D87:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:04:30.480 2025-06-21 18:04:30.493027 :A7C37D88:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16544 pso_num=27 pso_serial#=249 2025-06-21 18:04:30.493164 :A7C37D89:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16544 (legacy spawn) 2025-06-21 18:04:30.499664 :A7C37D8A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 18:04:30.499839 :A7C37D92:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 18:04:30.500628 :A7C37D94:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:04:30.509175 :A7C37D97:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:04:30.496 2025-06-21 18:04:30.509177 :A7C37D98:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15912 pso_num=27 pso_serial#=250 2025-06-21 18:04:30.509317 :A7C37D99:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15912 (legacy spawn) 2025-06-21 18:04:30.515829 :A7C37D9A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 18:04:30.515998 :A7C37DA3:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 18:04:30.516784 :A7C37DA4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:04:34.736249 :A7C37EDB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:04:34.727 2025-06-21 18:04:34.736251 :A7C37EDC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17720 pso_num=27 pso_serial#=251 2025-06-21 18:04:34.736386 :A7C37EDD:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17720 (legacy spawn) 2025-06-21 18:04:34.742797 :A7C37EDE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 18:04:34.742974 :A7C37EE3:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 18:04:34.743794 :A7C37EE8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:04:44.296993 :A7C381F9:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:04:44.289 2025-06-21 18:04:44.296995 :A7C381FA:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15496 pso_num=27 pso_serial#=252 2025-06-21 18:04:44.297133 :A7C381FB:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15496 (legacy spawn) 2025-06-21 18:04:44.303586 :A7C381FC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 18:04:44.303766 :A7C38201:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 18:04:44.304592 :A7C38206:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:05:40.124107 :A7C39AF4:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:05:40.110 2025-06-21 18:05:40.124109 :A7C39AF5:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17484 pso_num=27 pso_serial#=253 2025-06-21 18:05:40.124247 :A7C39AF6:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17484 (legacy spawn) 2025-06-21 18:05:40.130960 :A7C39AF7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 18:05:40.131136 :A7C39AFC:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 18:05:40.131987 :A7C39B01:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:05:40.140815 :A7C39B04:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:05:40.141 2025-06-21 18:05:40.140817 :A7C39B05:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8672 pso_num=27 pso_serial#=254 2025-06-21 18:05:40.140971 :A7C39B06:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8672 (legacy spawn) 2025-06-21 18:05:40.147423 :A7C39B07:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 18:05:40.147580 :A7C39B10:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 18:05:40.148381 :A7C39B11:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:05:42.934160 :A7C39BE9:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:05:42.925 2025-06-21 18:05:42.934162 :A7C39BEA:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12000 pso_num=27 pso_serial#=255 2025-06-21 18:05:42.934300 :A7C39BEB:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12000 (legacy spawn) 2025-06-21 18:05:42.940756 :A7C39BEC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 18:05:42.940920 :A7C39BF1:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 18:05:42.941690 :A7C39BF6:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:05:43.188421 :A7C39C04:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:05:43.175 2025-06-21 18:05:43.188422 :A7C39C05:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17512 pso_num=27 pso_serial#=1 2025-06-21 18:05:43.188553 :A7C39C06:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17512 (legacy spawn) 2025-06-21 18:05:43.195142 :A7C39C07:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 18:05:43.195321 :A7C39C0F:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 18:05:43.196145 :A7C39C11:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:05:43.204762 :A7C39C14:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:05:43.191 2025-06-21 18:05:43.204764 :A7C39C15:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18236 pso_num=27 pso_serial#=2 2025-06-21 18:05:43.204902 :A7C39C16:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18236 (legacy spawn) 2025-06-21 18:05:43.211604 :A7C39C17:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 18:05:43.211766 :A7C39C20:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 18:05:43.212565 :A7C39C21:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:05:46.486944 :A7C39D27:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:05:46.479 2025-06-21 18:05:46.486947 :A7C39D28:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12772 pso_num=27 pso_serial#=3 2025-06-21 18:05:46.487080 :A7C39D29:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12772 (legacy spawn) 2025-06-21 18:05:46.493888 :A7C39D2A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-21 18:05:46.494073 :A7C39D2F:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 18:05:46.495106 :A7C39D34:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:06:32.767182 :A7C3AC5A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:06:32.762 2025-06-21 18:06:32.767184 :A7C3AC5B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17920 pso_num=27 pso_serial#=4 2025-06-21 18:06:32.767320 :A7C3AC5C:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=17920 osp_idx=25 osp_ver=88743 osp_pg=0 (spawn #308622) 2025-06-21 18:06:32.767321 :A7C3AC5D:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 18:06:32.767321 :A7C3AC5E:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=130 time=3504935954 2025-06-21 18:06:32.767322 :A7C3AC5F:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 18:06:32.767322 :A7C3AC60:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 18:06:32.767322 :A7C3AC61:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 18:06:32.767323 :A7C3AC62:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 18:06:32.767323 :A7C3AC63:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 18:06:32.767836 :A7C3AC64:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 18:06:32.767896 :A7C3AC6A:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-21 18:06:32.789610 :A7C3ACFF:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-21 18:06:32.970648 :A7C3B0F9:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-21 18:06:32.965 2025-06-21 18:06:32.970649 :A7C3B0FA:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16320 pso_num=27 pso_serial#=5 2025-06-21 18:06:32.970783 :A7C3B0FD:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=16320 osp_idx=25 osp_ver=88744 osp_pg=0 (spawn #308625) 2025-06-21 18:06:32.970784 :A7C3B0FE:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 18:06:32.970785 :A7C3B0FF:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=131 time=3504936157 2025-06-21 18:06:32.970785 :A7C3B100:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 18:06:32.970786 :A7C3B101:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 18:06:32.970786 :A7C3B102:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 18:06:32.970787 :A7C3B103:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 18:06:32.970787 :A7C3B104:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 18:06:32.971287 :A7C3B10E: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: 16320, J002)