Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250503220929\orcl_j002_356_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:37567M/63366M, Ph+PgF:45089M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 356, image: ORACLE.EXE (J002) *** 2025-05-03 22:09:29.283 *** SESSION ID:(225.31561) 2025-05-03 22:09:29.283 *** 2025-05-03 22:09:29.283 Process diagnostic dump for ORACLE.EXE (J002), OS id=356, pid: 28, proc_ser: 129, sid: 225, sess_ser: 31561 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 356 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.270097 sec, exc=0.270097 sec, total=0.270097 sec wait times: max=0.500000 sec, heur=6.276050 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.000012 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 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 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.500100 sec, exc=0.500100 sec, total=0.500100 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 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.000014 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500007 sec, exc=0.500007 sec, total=0.500007 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499989 sec, exc=0.499989 sec, total=0.499989 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 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 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.500124 sec, exc=0.500124 sec, total=0.500124 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.509616 sec, exc=0.509616 sec, total=0.509616 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 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.000011 sec of elapsed time Sampled Session History of session 225 serial 31561 --------------------------------------------------- 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, 22:09:23 - 22:09:29] idle wait at each sample [session created at: 22:09:23] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-03 22:09:29.283 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 356, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-03 22:05:34.105872 :924CB59E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=8140 pso_num=28 pso_serial#=105 2025-05-03 22:05:34.106016 :924CB59F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=8140 (legacy spawn) 2025-05-03 22:05:34.112474 :924CB5A0: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-05-03 22:05:34.112507 :924CB5A3: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-05-03 22:05:34.112669 :924CB5AA: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-05-03 22:05:34.113493 :924CB5B7:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-03 22:05:34.132127 :924CB5BA:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-03 22:05:34.124 2025-05-03 22:05:34.132129 :924CB5BB:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=8368 pso_num=28 pso_serial#=106 2025-05-03 22:05:34.132262 :924CB5BC:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=8368 (legacy spawn) 2025-05-03 22:05:34.138634 :924CB5BD: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-05-03 22:05:34.138808 :924CB5C5: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-05-03 22:05:34.139653 :924CB5C7:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-03 22:06:10.616911 :924CC2B4:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-03 22:06:10.603 2025-05-03 22:06:10.616912 :924CC2B5:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11340 pso_num=28 pso_serial#=107 2025-05-03 22:06:10.617040 :924CC2B6:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11340 (legacy spawn) 2025-05-03 22:06:10.638411 :924CC2E1: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-05-03 22:06:10.638446 :924CC2E4: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-05-03 22:06:10.638593 :924CC2EA: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-05-03 22:06:10.639425 :924CC2F8:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-03 22:06:10.648037 :924CC2FB:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-03 22:06:10.634 2025-05-03 22:06:10.648039 :924CC2FC:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11000 pso_num=28 pso_serial#=108 2025-05-03 22:06:10.648173 :924CC2FD:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11000 (legacy spawn) 2025-05-03 22:06:10.654724 :924CC31F: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-05-03 22:06:10.654894 :924CC327: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-05-03 22:06:10.655719 :924CC328:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-03 22:06:29.990369 :924CCC39:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-03 22:06:29.981 2025-05-03 22:06:29.990371 :924CCC3A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10764 pso_num=28 pso_serial#=109 2025-05-03 22:06:29.990508 :924CCC3B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10764 (legacy spawn) 2025-05-03 22:06:29.996944 :924CCC3C: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-05-03 22:06:29.997143 :924CCC41: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-05-03 22:06:29.997929 :924CCC46:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-03 22:06:32.751235 :924CCD25:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-03 22:06:32.743 2025-05-03 22:06:32.751237 :924CCD26:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=5220 pso_num=28 pso_serial#=110 2025-05-03 22:06:32.751370 :924CCD27:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=5220 (legacy spawn) 2025-05-03 22:06:32.757831 :924CCD28: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-05-03 22:06:32.758005 :924CCD2D: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-05-03 22:06:32.758808 :924CCD32:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-03 22:07:20.019113 :924CDD05:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-03 22:07:20.019 2025-05-03 22:07:20.019116 :924CDD06:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10600 pso_num=28 pso_serial#=111 2025-05-03 22:07:20.019279 :924CDD07:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10600 (legacy spawn) 2025-05-03 22:07:20.026243 :924CDD08: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-05-03 22:07:20.026427 :924CDD0D: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-05-03 22:07:20.027321 :924CDD12:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-03 22:07:20.036221 :924CDD15:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-03 22:07:20.035 2025-05-03 22:07:20.036224 :924CDD16:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11316 pso_num=28 pso_serial#=112 2025-05-03 22:07:20.036357 :924CDD17:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11316 (legacy spawn) 2025-05-03 22:07:20.043011 :924CDD18: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-05-03 22:07:20.043185 :924CDD21: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-05-03 22:07:20.044088 :924CDD22:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-03 22:07:23.308028 :924CDE30:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-03 22:07:23.295 2025-05-03 22:07:23.308030 :924CDE31:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10876 pso_num=28 pso_serial#=113 2025-05-03 22:07:23.308158 :924CDE32:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10876 (legacy spawn) 2025-05-03 22:07:23.314689 :924CDE33: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-05-03 22:07:23.314874 :924CDE3C: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-05-03 22:07:23.315702 :924CDE3D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-03 22:07:23.324643 :924CDE40:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-03 22:07:23.311 2025-05-03 22:07:23.324646 :924CDE41:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11388 pso_num=28 pso_serial#=114 2025-05-03 22:07:23.324782 :924CDE42:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11388 (legacy spawn) 2025-05-03 22:07:23.331412 :924CDE43: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-05-03 22:07:23.331578 :924CDE4C: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-05-03 22:07:23.332445 :924CDE4D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-03 22:07:34.973827 :924CE216:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-03 22:07:34.965 2025-05-03 22:07:34.973829 :924CE217:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=9996 pso_num=28 pso_serial#=115 2025-05-03 22:07:34.973964 :924CE218:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=9996 (legacy spawn) 2025-05-03 22:07:34.980406 :924CE219: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-05-03 22:07:34.980605 :924CE21F: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-05-03 22:07:34.981417 :924CE223:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-03 22:07:38.252148 :924CE331:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-03 22:07:38.243 2025-05-03 22:07:38.252150 :924CE332:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12116 pso_num=28 pso_serial#=116 2025-05-03 22:07:38.252286 :924CE333:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12116 (legacy spawn) 2025-05-03 22:07:38.258824 :924CE334: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-05-03 22:07:38.258989 :924CE33D: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-05-03 22:07:38.259807 :924CE33E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-03 22:08:12.384529 :924CEEC2:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-03 22:08:12.374 2025-05-03 22:08:12.384531 :924CEEC3:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=9804 pso_num=28 pso_serial#=117 2025-05-03 22:08:12.384664 :924CEEC4:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=9804 (legacy spawn) 2025-05-03 22:08:12.391121 :924CEEC5: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-05-03 22:08:12.391290 :924CEECA: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-05-03 22:08:12.392103 :924CEECF:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-03 22:08:32.718839 :924CF556:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-03 22:08:32.704 2025-05-03 22:08:32.718841 :924CF557:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11828 pso_num=28 pso_serial#=118 2025-05-03 22:08:32.718972 :924CF558:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11828 (legacy spawn) 2025-05-03 22:08:33.082013 :924CF57F: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-05-03 22:08:33.082187 :924CF584: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-05-03 22:08:33.082981 :924CF589:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-03 22:08:33.091731 :924CF58C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-03 22:08:33.079 2025-05-03 22:08:33.091733 :924CF58D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12172 pso_num=28 pso_serial#=119 2025-05-03 22:08:33.091865 :924CF58E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12172 (legacy spawn) 2025-05-03 22:08:33.098285 :924CF58F: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-05-03 22:08:33.098453 :924CF598: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-05-03 22:08:33.099237 :924CF599:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-03 22:08:36.211889 :924CF694:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-03 22:08:36.198 2025-05-03 22:08:36.211891 :924CF695:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=8232 pso_num=28 pso_serial#=120 2025-05-03 22:08:36.212028 :924CF696:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=8232 (legacy spawn) 2025-05-03 22:08:36.218447 :924CF697: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-05-03 22:08:36.218620 :924CF69C: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-05-03 22:08:36.219432 :924CF6A1:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-03 22:08:36.228065 :924CF6A4:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-03 22:08:36.214 2025-05-03 22:08:36.228068 :924CF6A5:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11452 pso_num=28 pso_serial#=121 2025-05-03 22:08:36.228209 :924CF6A6:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11452 (legacy spawn) 2025-05-03 22:08:36.234681 :924CF6A7: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-05-03 22:08:36.234859 :924CF6B0: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-05-03 22:08:36.235643 :924CF6B1:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-03 22:08:37.185900 :924CF70C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-03 22:08:37.177 2025-05-03 22:08:37.185901 :924CF70D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10416 pso_num=28 pso_serial#=122 2025-05-03 22:08:37.186037 :924CF70E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10416 (legacy spawn) 2025-05-03 22:08:37.211613 :924CF70F: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-05-03 22:08:37.211785 :924CF715: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-05-03 22:08:37.212601 :924CF719:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-03 22:08:38.612548 :924CF784:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-03 22:08:38.599 2025-05-03 22:08:38.612550 :924CF785:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=7776 pso_num=28 pso_serial#=123 2025-05-03 22:08:38.612685 :924CF786:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=7776 (legacy spawn) 2025-05-03 22:08:38.619177 :924CF792: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-05-03 22:08:38.619346 :924CF797: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-05-03 22:08:38.620120 :924CF79C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-03 22:08:38.628819 :924CF79F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-03 22:08:38.615 2025-05-03 22:08:38.628822 :924CF7A0:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=2404 pso_num=28 pso_serial#=124 2025-05-03 22:08:38.628961 :924CF7A1:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=2404 (legacy spawn) 2025-05-03 22:08:38.635429 :924CF7A2: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-05-03 22:08:38.635458 :924CF7A5: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-05-03 22:08:38.635600 :924CF7AC: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-05-03 22:08:38.636396 :924CF7B9:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-03 22:09:12.717696 :924D0314:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-03 22:09:12.705 2025-05-03 22:09:12.717698 :924D0315:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12176 pso_num=28 pso_serial#=125 2025-05-03 22:09:12.717837 :924D0316:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12176 (legacy spawn) 2025-05-03 22:09:12.724309 :924D0317: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-05-03 22:09:12.724485 :924D031C: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-05-03 22:09:12.725306 :924D0321:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-03 22:09:12.733843 :924D0324:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-03 22:09:12.721 2025-05-03 22:09:12.733845 :924D0325:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11216 pso_num=28 pso_serial#=126 2025-05-03 22:09:12.733978 :924D0326:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11216 (legacy spawn) 2025-05-03 22:09:12.756146 :924D0327: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-05-03 22:09:12.756310 :924D0330: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-05-03 22:09:12.757133 :924D0331:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-03 22:09:20.669758 :924D0615:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-03 22:09:20.660 2025-05-03 22:09:20.669760 :924D0616:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11056 pso_num=28 pso_serial#=127 2025-05-03 22:09:20.669898 :924D0617:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11056 (legacy spawn) 2025-05-03 22:09:20.676428 :924D0618: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-05-03 22:09:20.676601 :924D061D: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-05-03 22:09:20.677414 :924D0622:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-03 22:09:22.807660 :924D06EE:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-03 22:09:22.802 2025-05-03 22:09:22.807662 :924D06EF:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=7100 pso_num=28 pso_serial#=128 2025-05-03 22:09:22.807793 :924D06F0:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=7100 osp_idx=26 osp_ver=81217 osp_pg=0 (spawn #145356) 2025-05-03 22:09:22.807794 :924D06F1:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-03 22:09:22.807794 :924D06F2:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=147 time=3580939968 2025-05-03 22:09:22.807795 :924D06F3:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-03 22:09:22.807795 :924D06F4:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-03 22:09:22.807795 :924D06F5:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 22:09:22.807795 :924D06F6:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 22:09:22.807796 :924D06F7:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 22:09:22.808283 :924D06F8: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-05-03 22:09:22.808334 :924D06FE: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-05-03 22:09:22.812529 :924D0714:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-03 22:09:22.812586 :924D0715:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-03 22:09:22.812864 :924D0716:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-03 22:09:22.812885 :924D0717:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-03 22:09:22.813101 :924D0718:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-03 22:09:22.813114 :924D0719:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-03 22:09:22.813324 :924D071A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-03 22:09:22.813337 :924D071B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-03 22:09:22.813542 :924D071C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-03 22:09:22.813555 :924D071D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-03 22:09:22.813761 :924D071E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-03 22:09:22.813774 :924D071F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-03 22:09:22.816376 :924D0752:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-03 22:09:23.010487 :924D08CE:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-03 22:09:23.005 2025-05-03 22:09:23.010488 :924D08CF:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=356 pso_num=28 pso_serial#=129 2025-05-03 22:09:23.010621 :924D08D2:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=356 osp_idx=26 osp_ver=81218 osp_pg=0 (spawn #145358) 2025-05-03 22:09:23.010622 :924D08D3:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-03 22:09:23.010623 :924D08D4:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=148 time=3580940171 2025-05-03 22:09:23.010623 :924D08D5:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-03 22:09:23.010624 :924D08D6:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-03 22:09:23.010624 :924D08D7:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 22:09:23.010625 :924D08D8:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 22:09:23.010625 :924D08D9:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 22:09:23.011110 :924D08DE: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: 356, J002)