Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250706100043\orcl_j002_10428_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:37105M/63366M, Ph+PgF:43122M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 10428, image: ORACLE.EXE (J002) *** 2025-07-06 10:00:43.857 *** SESSION ID:(219.10781) 2025-07-06 10:00:43.857 *** 2025-07-06 10:00:43.857 Process diagnostic dump for ORACLE.EXE (J002), OS id=10428, pid: 27, proc_ser: 193, sid: 219, sess_ser: 10781 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 10428 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=0.469363 sec, exc=0.469363 sec, total=0.469363 sec wait times: max=0.500000 sec, heur=7.964081 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.000011 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 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=13 seq_num=14 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.000010 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 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 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.501076 sec, exc=0.501076 sec, total=0.501076 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=10 seq_num=11 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 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 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 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 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.000013 sec of elapsed time Sampled Session History of session 219 serial 10781 --------------------------------------------------- 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, 10:00:35 - 10:00:43] idle wait at each sample [session created at: 10:00:35] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-06 10:00:43.857 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 10428, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-06 09:57:23.782667 :AE418023: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-06 09:57:23.783482 :AE418025:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 09:57:28.775691 :AE41814F:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 09:57:28.767 2025-07-06 09:57:28.775694 :AE418150:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13528 pso_num=27 pso_serial#=171 2025-07-06 09:57:28.775834 :AE418151:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13528 (legacy spawn) 2025-07-06 09:57:28.782463 :AE418152: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-06 09:57:28.782639 :AE418157: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-06 09:57:28.783477 :AE41815C:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 09:58:12.086292 :AE41906C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 09:58:12.076 2025-07-06 09:58:12.086294 :AE41906D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16076 pso_num=27 pso_serial#=172 2025-07-06 09:58:12.086429 :AE41906E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16076 (legacy spawn) 2025-07-06 09:58:12.093346 :AE41906F: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-06 09:58:12.093512 :AE419077: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-06 09:58:12.094403 :AE419078:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 09:58:17.395476 :AE4191AD:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 09:58:17.380 2025-07-06 09:58:17.395478 :AE4191AE:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14240 pso_num=27 pso_serial#=173 2025-07-06 09:58:17.395613 :AE4191AF:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14240 (legacy spawn) 2025-07-06 09:58:17.402231 :AE4191F6: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-06 09:58:17.402401 :AE4191FB: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-06 09:58:17.403224 :AE419200:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 09:58:17.420787 :AE41920E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 09:58:17.411 2025-07-06 09:58:17.420789 :AE41920F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18288 pso_num=27 pso_serial#=174 2025-07-06 09:58:17.420927 :AE419210:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18288 (legacy spawn) 2025-07-06 09:58:17.427627 :AE419211: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-06 09:58:17.427787 :AE41921A: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-06 09:58:17.428638 :AE41921B:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 09:58:20.694119 :AE41936F:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 09:58:20.680 2025-07-06 09:58:20.694120 :AE419370:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8508 pso_num=27 pso_serial#=175 2025-07-06 09:58:20.694269 :AE419371:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8508 (legacy spawn) 2025-07-06 09:58:20.700997 :AE419372: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-06 09:58:20.701166 :AE41937B: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-06 09:58:20.701956 :AE41937C:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 09:58:20.711009 :AE41937F:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 09:58:20.696 2025-07-06 09:58:20.711011 :AE419380:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15496 pso_num=27 pso_serial#=176 2025-07-06 09:58:20.711144 :AE419381:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15496 (legacy spawn) 2025-07-06 09:58:20.717851 :AE419382: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-06 09:58:20.718013 :AE41938B: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-06 09:58:20.718810 :AE41938C:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 09:58:30.927237 :AE419677:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 09:58:30.914 2025-07-06 09:58:30.927239 :AE419678:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17304 pso_num=27 pso_serial#=177 2025-07-06 09:58:30.927378 :AE419679:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17304 (legacy spawn) 2025-07-06 09:58:30.934193 :AE41967A: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-06 09:58:30.934361 :AE419680: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-06 09:58:30.935176 :AE419683:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 09:59:20.443182 :AE41A703:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 09:59:20.433 2025-07-06 09:59:20.443184 :AE41A704:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8240 pso_num=27 pso_serial#=178 2025-07-06 09:59:20.443322 :AE41A705:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8240 (legacy spawn) 2025-07-06 09:59:20.450100 :AE41A706: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-06 09:59:20.450288 :AE41A70B: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-06 09:59:20.451088 :AE41A710:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 09:59:30.463857 :AE41AAE1:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 09:59:30.461 2025-07-06 09:59:30.463859 :AE41AAE2:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=2960 pso_num=27 pso_serial#=179 2025-07-06 09:59:30.463993 :AE41AAE3:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=2960 (legacy spawn) 2025-07-06 09:59:30.470544 :AE41AAE4: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-06 09:59:30.470719 :AE41AAE9: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-06 09:59:30.471508 :AE41AAEE:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 09:59:30.480351 :AE41AAF1:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 09:59:30.477 2025-07-06 09:59:30.480353 :AE41AAF2:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13152 pso_num=27 pso_serial#=180 2025-07-06 09:59:30.480491 :AE41AAF3:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13152 (legacy spawn) 2025-07-06 09:59:30.487040 :AE41AAF4: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-06 09:59:30.487213 :AE41AAFC: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-06 09:59:30.488019 :AE41AAFE:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 09:59:33.130245 :AE41ABF9:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 09:59:33.122 2025-07-06 09:59:33.130247 :AE41ABFA:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12508 pso_num=27 pso_serial#=181 2025-07-06 09:59:33.130385 :AE41ABFB:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12508 (legacy spawn) 2025-07-06 09:59:33.137144 :AE41ABFC: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-06 09:59:33.137321 :AE41AC04: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-06 09:59:33.138222 :AE41AC06:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 09:59:33.603957 :AE41AC20:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 09:59:33.591 2025-07-06 09:59:33.603960 :AE41AC21:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13716 pso_num=27 pso_serial#=182 2025-07-06 09:59:33.604099 :AE41AC22:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13716 (legacy spawn) 2025-07-06 09:59:33.610781 :AE41AC23: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-06 09:59:33.610954 :AE41AC2C: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-06 09:59:33.611757 :AE41AC2D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 09:59:33.620594 :AE41AC30:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 09:59:33.607 2025-07-06 09:59:33.620596 :AE41AC31:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15736 pso_num=27 pso_serial#=183 2025-07-06 09:59:33.620729 :AE41AC32:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15736 (legacy spawn) 2025-07-06 09:59:33.627202 :AE41AC33: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-06 09:59:33.627366 :AE41AC3C: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-06 09:59:33.628199 :AE41AC3D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 10:00:04.239275 :AE41B6FD:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 10:00:04.231 2025-07-06 10:00:04.239277 :AE41B6FE:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4120 pso_num=27 pso_serial#=184 2025-07-06 10:00:04.239425 :AE41B6FF:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4120 (legacy spawn) 2025-07-06 10:00:04.245961 :AE41B700: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-06 10:00:04.246126 :AE41B709: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-06 10:00:04.246960 :AE41B70A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 10:00:06.693017 :AE41B7F8:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 10:00:06.679 2025-07-06 10:00:06.693019 :AE41B7F9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8336 pso_num=27 pso_serial#=185 2025-07-06 10:00:06.693164 :AE41B7FA:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8336 (legacy spawn) 2025-07-06 10:00:06.699753 :AE41B7FB: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-06 10:00:06.699927 :AE41B802: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-06 10:00:06.700744 :AE41B804:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 10:00:06.709560 :AE41B807:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 10:00:06.710 2025-07-06 10:00:06.709562 :AE41B808:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13328 pso_num=27 pso_serial#=186 2025-07-06 10:00:06.709703 :AE41B809:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13328 (legacy spawn) 2025-07-06 10:00:06.716241 :AE41B80A: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-06 10:00:06.716411 :AE41B813: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-06 10:00:06.717208 :AE41B814:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 10:00:09.915791 :AE41B90C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 10:00:09.902 2025-07-06 10:00:09.915793 :AE41B90D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13808 pso_num=27 pso_serial#=187 2025-07-06 10:00:09.915934 :AE41B90E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13808 (legacy spawn) 2025-07-06 10:00:09.922485 :AE41B90F: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-06 10:00:09.922664 :AE41B918: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-06 10:00:09.923468 :AE41B919:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 10:00:09.932534 :AE41B91C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 10:00:09.918 2025-07-06 10:00:09.932536 :AE41B91D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14940 pso_num=27 pso_serial#=188 2025-07-06 10:00:09.932681 :AE41B91E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14940 (legacy spawn) 2025-07-06 10:00:09.939282 :AE41B91F: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-06 10:00:09.939447 :AE41B928: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-06 10:00:09.940255 :AE41B929:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 10:00:10.602958 :AE41B943:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 10:00:10.590 2025-07-06 10:00:10.602961 :AE41B944:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16112 pso_num=27 pso_serial#=189 2025-07-06 10:00:10.603125 :AE41B945:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16112 (legacy spawn) 2025-07-06 10:00:10.624288 :AE41B946: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-06 10:00:10.624464 :AE41B94B: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-06 10:00:10.625261 :AE41B950:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 10:00:10.634081 :AE41B953:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 10:00:10.621 2025-07-06 10:00:10.634083 :AE41B954:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16544 pso_num=27 pso_serial#=190 2025-07-06 10:00:10.634233 :AE41B955:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16544 (legacy spawn) 2025-07-06 10:00:10.640860 :AE41B956: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-06 10:00:10.641024 :AE41B95F: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-06 10:00:10.641832 :AE41B960:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 10:00:11.610413 :AE41B9C3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 10:00:11.605 2025-07-06 10:00:11.610416 :AE41B9C4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17104 pso_num=27 pso_serial#=191 2025-07-06 10:00:11.610556 :AE41B9C5:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=17104 osp_idx=25 osp_ver=136369 osp_pg=0 (spawn #357503) 2025-07-06 10:00:11.610557 :AE41B9C6: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-06 10:00:11.610557 :AE41B9C7:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=111 time=476787501 2025-07-06 10:00:11.610558 :AE41B9C8:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 10:00:11.610558 :AE41B9C9:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 10:00:11.610558 :AE41B9CA:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 10:00:11.610559 :AE41B9CB:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 10:00:11.610559 :AE41B9CC:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 10:00:11.611100 :AE41B9CD: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-06 10:00:11.611145 :AE41B9D3: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-06 10:00:11.615608 :AE41B9E6: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-06 10:00:11.640622 :AE41B9F9:db_trace:ksb.c@6610:ksbcic_int(): [10254:27:219] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 4286505734 8461568) 2025-07-06 10:00:11.640629 :AE41B9FA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 10:00:11.640692 :AE41BA02:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-06 10:00:11.640733 :AE41BA12: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-06 10:00:11.649490 :AE41BA1A:db_trace:ktu.c@4871:ktuaex1r(): [10442:27:219] Extension usn 7, ext 1, nex 2 2025-07-06 10:00:11.649498 :AE41BA1B:db_trace:ktusm.c@2117:ktusmasp(): [10445:27:219] des1:tsn:1 rdba:0x00812d43 where: kdiwh22: kdifind 2025-07-06 10:00:11.651851 :AE41BA1C: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-06 10:00:11.651864 :AE41BA1D:db_trace:ktur.c@3079:ktuabt(): [10444:27:219] ABORT TRANSACTION - xid: 0x0007.01c.0000c694 2025-07-06 10:00:11.652158 :AE41BA25:db_trace:ktu.c@4871:ktuaex1r(): [10442:27:219] Extension usn 7, ext 2, nex 3 2025-07-06 10:00:11.652165 :AE41BA26:db_trace:ktusm.c@2117:ktusmasp(): [10445:27:219] des1:tsn:1 rdba:0x00812d49 where: kdiwh187: kdisprobe 2025-07-06 10:00:11.655045 :AE41BA27:db_trace:ktu.c@4871:ktuaex1r(): [10442:27:219] Extension usn 7, ext 3, nex 4 2025-07-06 10:00:11.655052 :AE41BA28:db_trace:ktusm.c@2117:ktusmasp(): [10445:27:219] des1:tsn:1 rdba:0x00812d4c where: kdiwh22: kdifind 2025-07-06 10:00:11.657563 :AE41BA29: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-06 10:00:11.688891 :AE41BA31: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-06 10:00:11.692748 :AE41BA39:db_trace:ktu.c@4871:ktuaex1r(): [10442:27:219] Extension usn 10, ext 1, nex 3 2025-07-06 10:00:11.692757 :AE41BA3A:db_trace:ktusm.c@2117:ktusmasp(): [10445:27:219] des1:tsn:1 rdba:0x00800f9d where: ktspbwh1: ktspfsrch 2025-07-06 10:00:11.692955 :AE41BA3B: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-06 10:00:11.715830 :AE41BA43: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-06 10:00:11.720986 :AE41BA4B: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-06 10:00:11.722073 :AE41BA53: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-06 10:00:13.080813 :AE41BAC8: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-06 10:00:13.116373 :AE41BAD0: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-06 10:00:13.116693 :AE41BAD8: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-06 10:00:13.116975 :AE41BAE0: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-06 10:00:13.116991 :AE41BAE3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 10:00:13.117063 :AE41BAF3: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-06 10:00:13.119973 :AE41BAFD:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 10:00:28.648851 :AE41C056:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 10:00:28.634 2025-07-06 10:00:28.648853 :AE41C057:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16516 pso_num=27 pso_serial#=192 2025-07-06 10:00:28.648997 :AE41C058:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16516 (legacy spawn) 2025-07-06 10:00:28.656013 :AE41C059: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-06 10:00:28.656182 :AE41C05E: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-06 10:00:28.657195 :AE41C063:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 10:00:35.892678 :AE41C7B8:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 10:00:35.887 2025-07-06 10:00:35.892680 :AE41C7B9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10428 pso_num=27 pso_serial#=193 2025-07-06 10:00:35.892826 :AE41C7BA:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=10428 osp_idx=25 osp_ver=136371 osp_pg=0 (spawn #357509) 2025-07-06 10:00:35.892827 :AE41C7BB: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-06 10:00:35.892828 :AE41C7BC:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=112 time=476811783 2025-07-06 10:00:35.892828 :AE41C7BD:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 10:00:35.892829 :AE41C7BE:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 10:00:35.892829 :AE41C7BF:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 10:00:35.892830 :AE41C7C0:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 10:00:35.892830 :AE41C7C1:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 10:00:35.893436 :AE41C7CB: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: 10428, J002)