Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250426140626\orcl_j002_9348_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:37617M/63366M, Ph+PgF:45343M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 9348, image: ORACLE.EXE (J002) *** 2025-04-26 14:06:26.794 *** SESSION ID:(219.48445) 2025-04-26 14:06:26.794 *** 2025-04-26 14:06:26.794 Process diagnostic dump for ORACLE.EXE (J002), OS id=9348, pid: 27, proc_ser: 227, sid: 219, sess_ser: 48445 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 9348 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=16 seq_num=17 snap_id=1 wait times: snap=0.328519 sec, exc=0.328519 sec, total=0.328519 sec wait times: max=0.500000 sec, heur=8.345085 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.000009 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=15 seq_num=16 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.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 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.000010 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 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.000009 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.500113 sec, exc=0.500113 sec, total=0.500113 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=10 seq_num=11 snap_id=1 wait times: snap=0.500006 sec, exc=0.500006 sec, total=0.500006 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 6: 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.000008 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.508241 sec, exc=0.508241 sec, total=0.508241 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=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.000007 sec of elapsed time Sampled Session History of session 219 serial 48445 --------------------------------------------------- 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, 14:06:18 - 14:06:26] idle wait at each sample [session created at: 14:06:18] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-04-26 14:06:26.794 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 9348, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-04-26 14:01:41.982795 :8F08F414:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9996 (legacy spawn) 2025-04-26 14:01:41.989239 :8F08F415: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-04-26 14:01:41.989409 :8F08F41E: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-04-26 14:01:41.990219 :8F08F41F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:01:41.998791 :8F08F422:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:01:41.985 2025-04-26 14:01:41.998793 :8F08F423:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8812 pso_num=27 pso_serial#=201 2025-04-26 14:01:41.998934 :8F08F424:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8812 (legacy spawn) 2025-04-26 14:01:42.005382 :8F08F425: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-04-26 14:01:42.005545 :8F08F42D: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-04-26 14:01:42.006352 :8F08F42F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:02:15.100954 :8F08FF26:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:02:15.086 2025-04-26 14:02:15.100956 :8F08FF27:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=1156 pso_num=27 pso_serial#=202 2025-04-26 14:02:15.101094 :8F08FF28:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=1156 (legacy spawn) 2025-04-26 14:02:15.107563 :8F08FF29: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-04-26 14:02:15.107737 :8F08FF32: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-04-26 14:02:15.108526 :8F08FF33:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:02:15.117195 :8F08FF36:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:02:15.102 2025-04-26 14:02:15.117196 :8F08FF37:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11172 pso_num=27 pso_serial#=203 2025-04-26 14:02:15.117342 :8F08FF38:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11172 (legacy spawn) 2025-04-26 14:02:15.123790 :8F08FF39: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-04-26 14:02:15.123948 :8F08FF42: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-04-26 14:02:15.124740 :8F08FF43:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:02:18.276526 :8F090050:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:02:18.268 2025-04-26 14:02:18.276528 :8F090051:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=580 pso_num=27 pso_serial#=204 2025-04-26 14:02:18.276666 :8F090052:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=580 (legacy spawn) 2025-04-26 14:02:18.283073 :8F090053: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-04-26 14:02:18.283245 :8F09005C: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-04-26 14:02:18.284101 :8F09005D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:02:18.292845 :8F090060:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:02:18.284 2025-04-26 14:02:18.292848 :8F090061:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10620 pso_num=27 pso_serial#=205 2025-04-26 14:02:18.292982 :8F090062:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10620 (legacy spawn) 2025-04-26 14:02:18.299700 :8F090063: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-04-26 14:02:18.299859 :8F09006C: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-04-26 14:02:18.300715 :8F09006D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:02:18.302881 :8F090070:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:02:18.300 2025-04-26 14:02:18.302883 :8F090071:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9280 pso_num=27 pso_serial#=206 2025-04-26 14:02:18.303009 :8F090072:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9280 (legacy spawn) 2025-04-26 14:02:18.309435 :8F090073: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-04-26 14:02:18.309607 :8F09007C: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-04-26 14:02:18.310429 :8F09007D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:02:18.319116 :8F090080:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:02:18.315 2025-04-26 14:02:18.319118 :8F090081:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11188 pso_num=27 pso_serial#=207 2025-04-26 14:02:18.319261 :8F090082:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11188 (legacy spawn) 2025-04-26 14:02:18.325735 :8F090083: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-04-26 14:02:18.325897 :8F09008C: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-04-26 14:02:18.326715 :8F09008D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:02:20.833684 :8F090179:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:02:20.824 2025-04-26 14:02:20.833686 :8F09017A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8436 pso_num=27 pso_serial#=208 2025-04-26 14:02:20.833823 :8F09017B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8436 (legacy spawn) 2025-04-26 14:02:20.840312 :8F09017C: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-04-26 14:02:20.840475 :8F090181: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-04-26 14:02:20.841267 :8F090186:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:02:36.197120 :8F090654:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:02:36.187 2025-04-26 14:02:36.197122 :8F090655:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10320 pso_num=27 pso_serial#=209 2025-04-26 14:02:36.197257 :8F090656:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10320 (legacy spawn) 2025-04-26 14:02:36.203723 :8F090657: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-04-26 14:02:36.203892 :8F09065C: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-04-26 14:02:36.204724 :8F090661:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:03:27.659358 :8F091791:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:03:27.645 2025-04-26 14:03:27.659359 :8F091792:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11216 pso_num=27 pso_serial#=210 2025-04-26 14:03:27.659493 :8F091793:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11216 (legacy spawn) 2025-04-26 14:03:27.665995 :8F091794: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-04-26 14:03:27.666168 :8F091799: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-04-26 14:03:27.666959 :8F09179E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:03:27.675563 :8F0917A1:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:03:27.660 2025-04-26 14:03:27.675565 :8F0917A2:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=316 pso_num=27 pso_serial#=211 2025-04-26 14:03:27.675699 :8F0917A3:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=316 (legacy spawn) 2025-04-26 14:03:27.682139 :8F0917A4: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-04-26 14:03:27.682333 :8F0917AD: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-04-26 14:03:27.683100 :8F0917AE:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:03:29.241693 :8F091818:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:03:29.232 2025-04-26 14:03:29.241695 :8F091819:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11128 pso_num=27 pso_serial#=212 2025-04-26 14:03:29.241829 :8F09181A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11128 (legacy spawn) 2025-04-26 14:03:29.248326 :8F09181B: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-04-26 14:03:29.248478 :8F091820: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-04-26 14:03:29.249254 :8F091825:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:03:30.886394 :8F0918C3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:03:30.873 2025-04-26 14:03:30.886396 :8F0918C4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10316 pso_num=27 pso_serial#=213 2025-04-26 14:03:30.886531 :8F0918C5:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10316 (legacy spawn) 2025-04-26 14:03:30.892986 :8F0918C6: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-04-26 14:03:30.893152 :8F0918CF: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-04-26 14:03:30.893932 :8F0918D0:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:03:30.902603 :8F0918D3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:03:30.889 2025-04-26 14:03:30.902606 :8F0918D4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9528 pso_num=27 pso_serial#=214 2025-04-26 14:03:30.902739 :8F0918D5:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9528 (legacy spawn) 2025-04-26 14:03:30.909217 :8F0918D6: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-04-26 14:03:30.909390 :8F0918DB: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-04-26 14:03:30.910165 :8F0918E0:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:03:38.377604 :8F091B22:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:03:38.364 2025-04-26 14:03:38.377606 :8F091B23:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8508 pso_num=27 pso_serial#=215 2025-04-26 14:03:38.377746 :8F091B24:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8508 (legacy spawn) 2025-04-26 14:03:38.384244 :8F091B25: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-04-26 14:03:38.384419 :8F091B2A: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-04-26 14:03:38.385268 :8F091B2F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:04:37.256617 :8F092F61:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:04:37.243 2025-04-26 14:04:37.256619 :8F092F62:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9228 pso_num=27 pso_serial#=216 2025-04-26 14:04:37.256761 :8F092F63:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9228 (legacy spawn) 2025-04-26 14:04:37.263268 :8F092F64: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-04-26 14:04:37.263452 :8F092F69: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-04-26 14:04:37.264245 :8F092F6E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:04:40.489925 :8F09306E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:04:40.475 2025-04-26 14:04:40.489927 :8F09306F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10400 pso_num=27 pso_serial#=217 2025-04-26 14:04:40.490057 :8F093070:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10400 (legacy spawn) 2025-04-26 14:04:40.496463 :8F093071: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-04-26 14:04:40.496643 :8F093076: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-04-26 14:04:40.497433 :8F09307B:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:04:40.505993 :8F09307E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:04:40.490 2025-04-26 14:04:40.505995 :8F09307F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9868 pso_num=27 pso_serial#=218 2025-04-26 14:04:40.506127 :8F093080:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9868 (legacy spawn) 2025-04-26 14:04:40.512600 :8F0930A1: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-04-26 14:04:40.512789 :8F0930AA: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-04-26 14:04:40.513563 :8F0930AB:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:04:40.530260 :8F0930AE:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:04:40.522 2025-04-26 14:04:40.530262 :8F0930AF:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=5112 pso_num=27 pso_serial#=219 2025-04-26 14:04:40.530396 :8F0930B0:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=5112 (legacy spawn) 2025-04-26 14:04:40.536822 :8F0930B1: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-04-26 14:04:40.536980 :8F0930BA: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-04-26 14:04:40.537794 :8F0930BB:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:05:17.003754 :8F093D4C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:05:16.989 2025-04-26 14:05:17.003756 :8F093D4D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10332 pso_num=27 pso_serial#=220 2025-04-26 14:05:17.003887 :8F093D4E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10332 (legacy spawn) 2025-04-26 14:05:17.010290 :8F093D4F: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-04-26 14:05:17.010465 :8F093D54: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-04-26 14:05:17.011259 :8F093D59:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:05:17.029020 :8F093D5C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:05:17.020 2025-04-26 14:05:17.029022 :8F093D5D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10572 pso_num=27 pso_serial#=221 2025-04-26 14:05:17.029155 :8F093D5E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10572 (legacy spawn) 2025-04-26 14:05:17.035519 :8F093D5F: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-04-26 14:05:17.035707 :8F093D68: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-04-26 14:05:17.036499 :8F093D69:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:05:20.193042 :8F093F40:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:05:20.180 2025-04-26 14:05:20.193044 :8F093F41:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=6832 pso_num=27 pso_serial#=222 2025-04-26 14:05:20.193174 :8F093F42:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=6832 (legacy spawn) 2025-04-26 14:05:20.199619 :8F093F43: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-04-26 14:05:20.199816 :8F093F48: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-04-26 14:05:20.200626 :8F093F4D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:05:20.209273 :8F093F50:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:05:20.196 2025-04-26 14:05:20.209275 :8F093F51:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10268 pso_num=27 pso_serial#=223 2025-04-26 14:05:20.209410 :8F093F52:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10268 (legacy spawn) 2025-04-26 14:05:20.215857 :8F093F53: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-04-26 14:05:20.216013 :8F093F58: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-04-26 14:05:20.216788 :8F093F5D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:05:42.711169 :8F094BB1:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:05:42.698 2025-04-26 14:05:42.711171 :8F094BB2:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9600 pso_num=27 pso_serial#=224 2025-04-26 14:05:42.711312 :8F094BB3:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9600 (legacy spawn) 2025-04-26 14:05:42.717874 :8F094BB4: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-04-26 14:05:42.718053 :8F094BB9: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-04-26 14:05:42.718848 :8F094BBE:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:06:13.775296 :8F095614:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:06:13.767 2025-04-26 14:06:13.775298 :8F095615:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=2404 pso_num=27 pso_serial#=225 2025-04-26 14:06:13.775432 :8F095616:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=2404 (legacy spawn) 2025-04-26 14:06:13.801145 :8F095617: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-04-26 14:06:13.801315 :8F095620: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-04-26 14:06:13.802103 :8F095621:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:06:18.235269 :8F095791:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:06:18.230 2025-04-26 14:06:18.235270 :8F095792:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=7888 pso_num=27 pso_serial#=226 2025-04-26 14:06:18.235411 :8F095793:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=7888 osp_idx=25 osp_ver=24195 osp_pg=0 (spawn #120483) 2025-04-26 14:06:18.235412 :8F095794:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 14:06:18.235412 :8F095795:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=120 time=2947180031 2025-04-26 14:06:18.235413 :8F095796:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 14:06:18.235413 :8F095797:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 14:06:18.235413 :8F095798:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 14:06:18.235414 :8F095799:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 14:06:18.235414 :8F09579A:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 14:06:18.235921 :8F09579B: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-04-26 14:06:18.235969 :8F0957A1: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-04-26 14:06:18.256975 :8F095841:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 14:06:18.447945 :8F095C4A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 14:06:18.443 2025-04-26 14:06:18.447946 :8F095C4B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9348 pso_num=27 pso_serial#=227 2025-04-26 14:06:18.448076 :8F095C4C:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=9348 osp_idx=25 osp_ver=24196 osp_pg=0 (spawn #120486) 2025-04-26 14:06:18.448076 :8F095C4D:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 14:06:18.448077 :8F095C4E:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=121 time=2947180250 2025-04-26 14:06:18.448077 :8F095C4F:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 14:06:18.448077 :8F095C50:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 14:06:18.448078 :8F095C51:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 14:06:18.448078 :8F095C52:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 14:06:18.448078 :8F095C53:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 14:06:18.448643 :8F095C5D: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: 9348, J002)