Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250706180141\orcl_j002_12656_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:37010M/63366M, Ph+PgF:43026M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 12656, image: ORACLE.EXE (J002) *** 2025-07-06 18:01:41.732 *** SESSION ID:(219.16387) 2025-07-06 18:01:41.732 *** 2025-07-06 18:01:41.732 Process diagnostic dump for ORACLE.EXE (J002), OS id=12656, pid: 27, proc_ser: 190, sid: 219, sess_ser: 16387 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 12656 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.392104 sec, exc=0.392104 sec, total=0.392104 sec wait times: max=0.500000 sec, heur=3.892598 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=6 seq_num=7 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.000009 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 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 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499986 sec, exc=0.499986 sec, total=0.499986 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.500008 sec, exc=0.500008 sec, total=0.500008 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=2 seq_num=3 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.000007 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.505102 sec, exc=0.505102 sec, total=0.505102 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.495346 sec, exc=0.495346 sec, total=0.495346 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 219 serial 16387 --------------------------------------------------- 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 --------------------------------------------------- [4 samples, 18:01:37 - 18:01:41] idle wait at each sample [session created at: 18:01:37] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-06 18:01:41.732 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 12656, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-06 17:56:08.213600 :AE67D056:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 17:56:08.201 2025-07-06 17:56:08.213602 :AE67D057:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17168 pso_num=27 pso_serial#=167 2025-07-06 17:56:08.213736 :AE67D058:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17168 (legacy spawn) 2025-07-06 17:56:08.220230 :AE67D059: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 17:56:08.220385 :AE67D062: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 17:56:08.221185 :AE67D063:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 17:56:55.410310 :AE67E107:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 17:56:55.402 2025-07-06 17:56:55.410312 :AE67E108:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16112 pso_num=27 pso_serial#=168 2025-07-06 17:56:55.410455 :AE67E109:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16112 (legacy spawn) 2025-07-06 17:56:55.417205 :AE67E10A: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 17:56:55.417375 :AE67E112: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 17:56:55.418240 :AE67E114:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 17:56:56.737012 :AE67E172:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 17:56:56.727 2025-07-06 17:56:56.737015 :AE67E173:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18036 pso_num=27 pso_serial#=169 2025-07-06 17:56:56.737163 :AE67E174:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18036 (legacy spawn) 2025-07-06 17:56:56.744008 :AE67E175: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 17:56:56.744182 :AE67E17A: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 17:56:56.744984 :AE67E17F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 17:57:53.910013 :AE67F430:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 17:57:53.896 2025-07-06 17:57:53.910015 :AE67F431:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17104 pso_num=27 pso_serial#=170 2025-07-06 17:57:53.910153 :AE67F432:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17104 (legacy spawn) 2025-07-06 17:57:53.916853 :AE67F433: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 17:57:53.917021 :AE67F438: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 17:57:53.917849 :AE67F43D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 17:57:53.926633 :AE67F440:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 17:57:53.911 2025-07-06 17:57:53.926635 :AE67F441:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12284 pso_num=27 pso_serial#=171 2025-07-06 17:57:53.926799 :AE67F442:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12284 (legacy spawn) 2025-07-06 17:57:53.933358 :AE67F443: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 17:57:53.933519 :AE67F44B: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 17:57:53.934348 :AE67F44C:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 17:57:57.295145 :AE67F545:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 17:57:57.281 2025-07-06 17:57:57.295147 :AE67F546:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13012 pso_num=27 pso_serial#=172 2025-07-06 17:57:57.295290 :AE67F547:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13012 (legacy spawn) 2025-07-06 17:57:57.301899 :AE67F548: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 17:57:57.302077 :AE67F54D: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 17:57:57.302858 :AE67F552:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 17:57:57.311858 :AE67F555:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 17:57:57.297 2025-07-06 17:57:57.311860 :AE67F556:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=7460 pso_num=27 pso_serial#=173 2025-07-06 17:57:57.311996 :AE67F557:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=7460 (legacy spawn) 2025-07-06 17:57:57.318618 :AE67F558: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 17:57:57.318777 :AE67F561: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 17:57:57.319599 :AE67F562:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 17:57:57.554954 :AE67F565:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 17:57:57.547 2025-07-06 17:57:57.554957 :AE67F566:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17988 pso_num=27 pso_serial#=174 2025-07-06 17:57:57.555093 :AE67F567:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17988 (legacy spawn) 2025-07-06 17:57:57.561713 :AE67F568: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 17:57:57.561881 :AE67F571: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 17:57:57.562721 :AE67F572:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 17:58:04.653198 :AE67F7E3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 17:58:04.640 2025-07-06 17:58:04.653200 :AE67F7E4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15800 pso_num=27 pso_serial#=175 2025-07-06 17:58:04.653337 :AE67F7E5:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15800 (legacy spawn) 2025-07-06 17:58:04.660049 :AE67F7E6: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 17:58:04.660227 :AE67F7EC: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 17:58:04.661064 :AE67F7F0:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 17:58:59.767500 :AE6809ED:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 17:58:59.759 2025-07-06 17:58:59.767503 :AE6809EE:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16664 pso_num=27 pso_serial#=176 2025-07-06 17:58:59.767677 :AE6809EF:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16664 (legacy spawn) 2025-07-06 17:58:59.774287 :AE6809F0: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 17:58:59.774468 :AE6809F8: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 17:58:59.775310 :AE6809FA:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 17:59:06.610767 :AE680C08:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 17:59:06.607 2025-07-06 17:59:06.610769 :AE680C09:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13924 pso_num=27 pso_serial#=177 2025-07-06 17:59:06.610909 :AE680C0A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13924 (legacy spawn) 2025-07-06 17:59:06.617531 :AE680C0B: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 17:59:06.617714 :AE680C10: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 17:59:06.618492 :AE680C15:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 17:59:06.627464 :AE680C18:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 17:59:06.623 2025-07-06 17:59:06.627466 :AE680C19:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14524 pso_num=27 pso_serial#=178 2025-07-06 17:59:06.627603 :AE680C1A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14524 (legacy spawn) 2025-07-06 17:59:06.634215 :AE680C1B: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 17:59:06.634383 :AE680C24: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 17:59:06.635186 :AE680C25:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 17:59:09.809357 :AE680D1C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 17:59:09.796 2025-07-06 17:59:09.809359 :AE680D1D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16372 pso_num=27 pso_serial#=179 2025-07-06 17:59:09.809503 :AE680D1E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16372 (legacy spawn) 2025-07-06 17:59:09.816182 :AE680D1F: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 17:59:09.816363 :AE680D24: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 17:59:09.817178 :AE680D29:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 17:59:09.826241 :AE680D2C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 17:59:09.811 2025-07-06 17:59:09.826243 :AE680D2D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17976 pso_num=27 pso_serial#=180 2025-07-06 17:59:09.826391 :AE680D2E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17976 (legacy spawn) 2025-07-06 17:59:09.833000 :AE680D2F: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 17:59:09.833168 :AE680D38: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 17:59:09.833976 :AE680D39:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 17:59:12.852240 :AE680E32:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 17:59:12.843 2025-07-06 17:59:12.852243 :AE680E33:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=3560 pso_num=27 pso_serial#=181 2025-07-06 17:59:12.852380 :AE680E34:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=3560 (legacy spawn) 2025-07-06 17:59:12.858933 :AE680E35: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 17:59:12.859096 :AE680E3A: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 17:59:12.859915 :AE680E3F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 18:00:01.954336 :AE681F44:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 18:00:01.941 2025-07-06 18:00:01.954338 :AE681F45:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4120 pso_num=27 pso_serial#=182 2025-07-06 18:00:01.954504 :AE681F46:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4120 (legacy spawn) 2025-07-06 18:00:01.961129 :AE681F47: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 18:00:01.961301 :AE681F50: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 18:00:01.962108 :AE681F51:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 18:00:13.481358 :AE682306:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 18:00:13.476 2025-07-06 18:00:13.481360 :AE682307:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4508 pso_num=27 pso_serial#=183 2025-07-06 18:00:13.481497 :AE682308:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=4508 osp_idx=25 osp_ver=139166 osp_pg=0 (spawn #358615) 2025-07-06 18:00:13.481497 :AE682309: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 18:00:13.481498 :AE68230A:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=141 time=505589376 2025-07-06 18:00:13.481499 :AE68230B:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 18:00:13.481499 :AE68230C:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 18:00:13.481499 :AE68230D:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 18:00:13.481500 :AE68230E:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 18:00:13.481500 :AE68230F:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 18:00:13.482074 :AE682310: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 18:00:13.482122 :AE682316: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 18:00:13.486569 :AE682329: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 18:00:13.512749 :AE68233C:db_trace:ksb.c@6610:ksbcic_int(): [10254:27:219] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 4286503234 8464064) 2025-07-06 18:00:13.512756 :AE68233D: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 18:00:13.512811 :AE682345: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 18:00:13.512859 :AE682355: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 18:00:13.523751 :AE68235D: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 18:00:13.523761 :AE68235E:db_trace:ktur.c@3079:ktuabt(): [10444:27:219] ABORT TRANSACTION - xid: 0x0004.003.0000c47f 2025-07-06 18:00:13.524228 :AE682366:db_trace:ktu.c@4871:ktuaex1r(): [10442:27:219] Extension usn 4, ext 2, nex 3 2025-07-06 18:00:13.524234 :AE682367:db_trace:ktusm.c@2117:ktusmasp(): [10445:27:219] des1:tsn:1 rdba:0x00814a15 where: kdiwh187: kdisprobe 2025-07-06 18:00:13.528503 :AE682368:db_trace:ktu.c@4871:ktuaex1r(): [10442:27:219] Extension usn 4, ext 3, nex 4 2025-07-06 18:00:13.528511 :AE682369:db_trace:ktusm.c@2117:ktusmasp(): [10445:27:219] des1:tsn:1 rdba:0x008149ec where: kdiwh22: kdifind 2025-07-06 18:00:13.528719 :AE68236A: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 18:00:13.557219 :AE682372: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 18:00:13.560971 :AE68237A: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 18:00:13.564862 :AE682382: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 18:00:13.569431 :AE68238A: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 18:00:13.570527 :AE682392: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 18:00:14.873752 :AE6823EC: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 18:00:14.910889 :AE6823F4: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 18:00:14.911220 :AE6823FC: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 18:00:14.911497 :AE682404: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 18:00:14.911513 :AE682407: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 18:00:14.911577 :AE682414: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 18:00:14.914538 :AE682421:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 18:00:55.339557 :AE6832A2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 18:00:55.330 2025-07-06 18:00:55.339560 :AE6832A3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16556 pso_num=27 pso_serial#=184 2025-07-06 18:00:55.339699 :AE6832A4:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16556 (legacy spawn) 2025-07-06 18:00:55.346332 :AE6832A5: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 18:00:55.346517 :AE6832AA: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 18:00:55.347307 :AE6832AF:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 18:00:56.017478 :AE68330D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 18:00:56.002 2025-07-06 18:00:56.017480 :AE68330E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10356 pso_num=27 pso_serial#=185 2025-07-06 18:00:56.017619 :AE68330F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10356 (legacy spawn) 2025-07-06 18:00:56.024299 :AE683310: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 18:00:56.024462 :AE683319: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 18:00:56.025270 :AE68331A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 18:00:56.034277 :AE68331D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 18:00:56.033 2025-07-06 18:00:56.034279 :AE68331E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13092 pso_num=27 pso_serial#=186 2025-07-06 18:00:56.034424 :AE68331F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13092 (legacy spawn) 2025-07-06 18:00:56.041200 :AE683320: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 18:00:56.041382 :AE683329: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 18:00:56.042256 :AE68332A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 18:00:59.109840 :AE683426:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 18:00:59.096 2025-07-06 18:00:59.109842 :AE683427:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11148 pso_num=27 pso_serial#=187 2025-07-06 18:00:59.109981 :AE683428:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11148 (legacy spawn) 2025-07-06 18:00:59.116571 :AE683429: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 18:00:59.116767 :AE683431: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 18:00:59.117555 :AE683433:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 18:00:59.126412 :AE683436:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 18:00:59.112 2025-07-06 18:00:59.126414 :AE683437:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14076 pso_num=27 pso_serial#=188 2025-07-06 18:00:59.126553 :AE683438:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14076 (legacy spawn) 2025-07-06 18:00:59.133116 :AE683439: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 18:00:59.133282 :AE683442: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 18:00:59.143998 :AE683443:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 18:01:04.163241 :AE683594:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 18:01:04.150 2025-07-06 18:01:04.163243 :AE683595:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=7628 pso_num=27 pso_serial#=189 2025-07-06 18:01:04.163383 :AE683596:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=7628 (legacy spawn) 2025-07-06 18:01:04.169961 :AE683597: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 18:01:04.170134 :AE68359C: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 18:01:04.170935 :AE6835A1:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 18:01:37.845674 :AE6845C4:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 18:01:37.840 2025-07-06 18:01:37.845676 :AE6845C5:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12656 pso_num=27 pso_serial#=190 2025-07-06 18:01:37.845817 :AE6845C6:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=12656 osp_idx=25 osp_ver=139173 osp_pg=0 (spawn #358623) 2025-07-06 18:01:37.845818 :AE6845C7: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 18:01:37.845819 :AE6845C8:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=142 time=505673736 2025-07-06 18:01:37.845819 :AE6845C9:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 18:01:37.845819 :AE6845CA:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 18:01:37.845820 :AE6845CB:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 18:01:37.845820 :AE6845CC:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 18:01:37.845821 :AE6845CD:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 18:01:37.846429 :AE6845D7: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: 12656, J002)