Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250720220704\orcl_j002_15164_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:36135M/63366M, Ph+PgF:42009M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 15164, image: ORACLE.EXE (J002) *** 2025-07-20 22:07:04.118 *** SESSION ID:(219.39937) 2025-07-20 22:07:04.118 *** 2025-07-20 22:07:04.118 Process diagnostic dump for ORACLE.EXE (J002), OS id=15164, pid: 27, proc_ser: 83, sid: 219, sess_ser: 39937 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15164 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.138011 sec, exc=0.138011 sec, total=0.138011 sec wait times: max=0.500000 sec, heur=7.162208 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000013 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 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 1: 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.000012 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.510090 sec, exc=0.510090 sec, total=0.510090 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.499985 sec, exc=0.499985 sec, total=0.499985 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 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.000008 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 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.000011 sec of elapsed time 6: 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 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 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 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.510089 sec, exc=0.510089 sec, total=0.510089 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=4 seq_num=5 snap_id=1 wait times: snap=0.510104 sec, exc=0.510104 sec, total=0.510104 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 39937 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [8 samples, 22:06:56 - 22:07:04] idle wait at each sample [session created at: 22:06:56] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-20 22:07:04.118 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 15164, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-20 22:03:12.919137 :B4BB4AB9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-20 22:03:12.927911 :B4BB4ABC:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-20 22:03:12.914 2025-07-20 22:03:12.927914 :B4BB4ABD:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18576 pso_num=27 pso_serial#=60 2025-07-20 22:03:12.928056 :B4BB4ABE:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18576 (legacy spawn) 2025-07-20 22:03:12.934495 :B4BB4ABF: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-20 22:03:12.934664 :B4BB4AC7: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-20 22:03:12.935454 :B4BB4AC9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-20 22:03:49.286745 :B4BB56A5:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-20 22:03:49.273 2025-07-20 22:03:49.286748 :B4BB56A6:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9288 pso_num=27 pso_serial#=61 2025-07-20 22:03:49.286894 :B4BB56A7:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9288 (legacy spawn) 2025-07-20 22:03:49.293557 :B4BB56A8: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-20 22:03:49.293734 :B4BB56B1: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-20 22:03:49.294569 :B4BB56B2:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-20 22:03:49.303545 :B4BB56B5:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-20 22:03:49.289 2025-07-20 22:03:49.303547 :B4BB56B6:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18936 pso_num=27 pso_serial#=62 2025-07-20 22:03:49.303684 :B4BB56B7:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18936 (legacy spawn) 2025-07-20 22:03:49.310356 :B4BB56C4: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-20 22:03:49.310536 :B4BB56CD: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-20 22:03:49.311384 :B4BB56CE:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-20 22:04:14.912892 :B4BB5F17:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-20 22:04:14.899 2025-07-20 22:04:14.912895 :B4BB5F18:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=7608 pso_num=27 pso_serial#=63 2025-07-20 22:04:14.913039 :B4BB5F19:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=7608 (legacy spawn) 2025-07-20 22:04:14.919931 :B4BB5F1A: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-20 22:04:14.920122 :B4BB5F1F: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-20 22:04:14.921040 :B4BB5F24:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-20 22:04:52.626785 :B4BB6BF4:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-20 22:04:52.616 2025-07-20 22:04:52.626787 :B4BB6BF5:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17528 pso_num=27 pso_serial#=64 2025-07-20 22:04:52.626936 :B4BB6BF6:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17528 (legacy spawn) 2025-07-20 22:04:52.633834 :B4BB6BF7: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-20 22:04:52.634017 :B4BB6C00: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-20 22:04:52.634908 :B4BB6C01:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-20 22:04:58.705448 :B4BB6DE2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-20 22:04:58.691 2025-07-20 22:04:58.705451 :B4BB6DE3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=2696 pso_num=27 pso_serial#=65 2025-07-20 22:04:58.705641 :B4BB6DE4:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=2696 (legacy spawn) 2025-07-20 22:04:58.712035 :B4BB6DE5: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-20 22:04:58.712204 :B4BB6DEE: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-20 22:04:58.713037 :B4BB6DEF:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-20 22:04:58.721765 :B4BB6DF2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-20 22:04:58.706 2025-07-20 22:04:58.721768 :B4BB6DF3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18496 pso_num=27 pso_serial#=66 2025-07-20 22:04:58.721903 :B4BB6DF4:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18496 (legacy spawn) 2025-07-20 22:04:58.728396 :B4BB6DF5: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-20 22:04:58.728563 :B4BB6DFA: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-20 22:04:58.729355 :B4BB6DFF:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-20 22:05:01.798038 :B4BB6EFF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-20 22:05:01.785 2025-07-20 22:05:01.798041 :B4BB6F00:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16512 pso_num=27 pso_serial#=67 2025-07-20 22:05:01.798172 :B4BB6F01:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16512 (legacy spawn) 2025-07-20 22:05:01.804707 :B4BB6F02: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-20 22:05:01.804872 :B4BB6F0B: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-20 22:05:01.805688 :B4BB6F0C:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-20 22:05:01.814349 :B4BB6F0F:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-20 22:05:01.801 2025-07-20 22:05:01.814350 :B4BB6F10:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16012 pso_num=27 pso_serial#=68 2025-07-20 22:05:01.814493 :B4BB6F11:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16012 (legacy spawn) 2025-07-20 22:05:01.821051 :B4BB6F12: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-20 22:05:01.821219 :B4BB6F1B: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-20 22:05:01.822015 :B4BB6F1C:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-20 22:05:09.296562 :B4BB712C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-20 22:05:09.288 2025-07-20 22:05:09.296565 :B4BB712D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18728 pso_num=27 pso_serial#=69 2025-07-20 22:05:09.296700 :B4BB712E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18728 (legacy spawn) 2025-07-20 22:05:09.303399 :B4BB712F: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-20 22:05:09.303580 :B4BB7138: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-20 22:05:09.304404 :B4BB7139:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-20 22:05:48.205927 :B4BB83FF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-20 22:05:48.198 2025-07-20 22:05:48.205929 :B4BB8400:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17516 pso_num=27 pso_serial#=70 2025-07-20 22:05:48.206072 :B4BB8401:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17516 (legacy spawn) 2025-07-20 22:05:48.212540 :B4BB8402: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-20 22:05:48.212719 :B4BB8407: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-20 22:05:48.213615 :B4BB840C:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-20 22:06:00.949784 :B4BB8990:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-20 22:06:00.941 2025-07-20 22:06:00.949786 :B4BB8991:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17544 pso_num=27 pso_serial#=71 2025-07-20 22:06:00.949927 :B4BB8992:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17544 (legacy spawn) 2025-07-20 22:06:00.956496 :B4BB8993: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-20 22:06:00.956670 :B4BB8998: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-20 22:06:00.957503 :B4BB899D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-20 22:06:11.304135 :B4BB8CC2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-20 22:06:11.300 2025-07-20 22:06:11.304138 :B4BB8CC3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15516 pso_num=27 pso_serial#=72 2025-07-20 22:06:11.304292 :B4BB8CC4:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15516 (legacy spawn) 2025-07-20 22:06:11.311322 :B4BB8CC5: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-20 22:06:11.311502 :B4BB8CCC: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-20 22:06:11.312428 :B4BB8CCF:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-20 22:06:11.321860 :B4BB8CD2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-20 22:06:11.316 2025-07-20 22:06:11.321862 :B4BB8CD3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4284 pso_num=27 pso_serial#=73 2025-07-20 22:06:11.322001 :B4BB8CD4:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4284 (legacy spawn) 2025-07-20 22:06:11.328836 :B4BB8CD5: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-20 22:06:11.329013 :B4BB8CDD: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-20 22:06:11.329982 :B4BB8CDF:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-20 22:06:14.409774 :B4BB8DCC:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-20 22:06:14.396 2025-07-20 22:06:14.409776 :B4BB8DCD:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17412 pso_num=27 pso_serial#=74 2025-07-20 22:06:14.409917 :B4BB8DCE:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17412 (legacy spawn) 2025-07-20 22:06:14.416783 :B4BB8DCF: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-20 22:06:14.416972 :B4BB8DD7: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-20 22:06:14.417846 :B4BB8DD9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-20 22:06:14.426963 :B4BB8DDC:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-20 22:06:14.412 2025-07-20 22:06:14.426964 :B4BB8DDD:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9904 pso_num=27 pso_serial#=75 2025-07-20 22:06:14.427129 :B4BB8DDE:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9904 (legacy spawn) 2025-07-20 22:06:14.433966 :B4BB8DDF: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-20 22:06:14.434152 :B4BB8DE7: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-20 22:06:14.435030 :B4BB8DE9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-20 22:06:47.507826 :B4BB98A4:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-20 22:06:47.493 2025-07-20 22:06:47.507828 :B4BB98A5:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19080 pso_num=27 pso_serial#=76 2025-07-20 22:06:47.507967 :B4BB98A6:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=19080 (legacy spawn) 2025-07-20 22:06:47.514478 :B4BB98A7: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-20 22:06:47.514647 :B4BB98B0: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-20 22:06:47.515474 :B4BB98B1:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-20 22:06:47.524379 :B4BB98B4:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-20 22:06:47.524 2025-07-20 22:06:47.524380 :B4BB98B5:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18740 pso_num=27 pso_serial#=77 2025-07-20 22:06:47.524516 :B4BB98B6:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18740 (legacy spawn) 2025-07-20 22:06:47.531002 :B4BB98B7: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-20 22:06:47.531171 :B4BB98BF: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-20 22:06:47.531974 :B4BB98C1:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-20 22:06:47.734128 :B4BB98E8:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-20 22:06:47.729 2025-07-20 22:06:47.734131 :B4BB98E9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13380 pso_num=27 pso_serial#=78 2025-07-20 22:06:47.734267 :B4BB98EA:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=13380 osp_idx=25 osp_ver=183686 osp_pg=0 (spawn #405850) 2025-07-20 22:06:47.734267 :B4BB98EB:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 22:06:47.734268 :B4BB98EC:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=36 time=1729983626 2025-07-20 22:06:47.734268 :B4BB98ED:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 22:06:47.734269 :B4BB98EE:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 22:06:47.734269 :B4BB98EF:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 22:06:47.734270 :B4BB98F0:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 22:06:47.734270 :B4BB98F1:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 22:06:47.734851 :B4BB98F2: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-20 22:06:47.734898 :B4BB98F8: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-20 22:06:47.754890 :B4BB9909:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-20 22:06:50.424676 :B4BB99CC:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-20 22:06:50.416 2025-07-20 22:06:50.424678 :B4BB99CD:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19280 pso_num=27 pso_serial#=79 2025-07-20 22:06:50.424818 :B4BB99CE:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=19280 (legacy spawn) 2025-07-20 22:06:50.431325 :B4BB99CF: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-20 22:06:50.431501 :B4BB99D8: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-20 22:06:50.432360 :B4BB99D9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-20 22:06:50.814710 :B4BB99FF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-20 22:06:50.801 2025-07-20 22:06:50.814713 :B4BB9A00:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19440 pso_num=27 pso_serial#=80 2025-07-20 22:06:50.814858 :B4BB9A01:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=19440 (legacy spawn) 2025-07-20 22:06:50.821355 :B4BB9A0E: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-20 22:06:50.821520 :B4BB9A13: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-20 22:06:50.822305 :B4BB9A18:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-20 22:06:50.830955 :B4BB9A1B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-20 22:06:50.817 2025-07-20 22:06:50.830957 :B4BB9A1C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18288 pso_num=27 pso_serial#=81 2025-07-20 22:06:50.831093 :B4BB9A1D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18288 (legacy spawn) 2025-07-20 22:06:50.837481 :B4BB9A1E: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-20 22:06:50.837650 :B4BB9A27: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-20 22:06:50.838443 :B4BB9A28:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-20 22:06:56.764439 :B4BB9C0D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-20 22:06:56.759 2025-07-20 22:06:56.764441 :B4BB9C0E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18160 pso_num=27 pso_serial#=82 2025-07-20 22:06:56.764584 :B4BB9C0F:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=18160 osp_idx=25 osp_ver=183690 osp_pg=0 (spawn #405851) 2025-07-20 22:06:56.764584 :B4BB9C10:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 22:06:56.764585 :B4BB9C11:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=37 time=1729992658 2025-07-20 22:06:56.764585 :B4BB9C12:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 22:06:56.764586 :B4BB9C13:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 22:06:56.764586 :B4BB9C14:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 22:06:56.764586 :B4BB9C15:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 22:06:56.764587 :B4BB9C16:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 22:06:56.765109 :B4BB9C17: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-20 22:06:56.765159 :B4BB9C1D: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-20 22:06:56.769367 :B4BB9C2F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-20 22:06:56.769421 :B4BB9C30:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-20 22:06:56.769692 :B4BB9C31:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-20 22:06:56.769710 :B4BB9C32:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-20 22:06:56.769927 :B4BB9C33:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-20 22:06:56.769941 :B4BB9C34:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-20 22:06:56.770154 :B4BB9C35:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-20 22:06:56.770166 :B4BB9C36:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-20 22:06:56.770371 :B4BB9C37:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-20 22:06:56.770383 :B4BB9C38:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-20 22:06:56.770587 :B4BB9C39:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-20 22:06:56.770600 :B4BB9C3A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-20 22:06:56.773230 :B4BB9C68:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-20 22:06:56.967565 :B4BBA095:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-20 22:06:56.962 2025-07-20 22:06:56.967567 :B4BBA096:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15164 pso_num=27 pso_serial#=83 2025-07-20 22:06:56.967713 :B4BBA097:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=15164 osp_idx=25 osp_ver=183691 osp_pg=0 (spawn #405853) 2025-07-20 22:06:56.967714 :B4BBA098: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-20 22:06:56.967715 :B4BBA099:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=38 time=1729992861 2025-07-20 22:06:56.967716 :B4BBA09A:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 22:06:56.967716 :B4BBA09B:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 22:06:56.967717 :B4BBA09C:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 22:06:56.967717 :B4BBA09D:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 22:06:56.967718 :B4BBA09E:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 22:06:56.968224 :B4BBA0A8: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: 15164, J002)