Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250628060009\orcl_j002_16148_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250628060009\orcl_j002_16148_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:36357M/63366M, Ph+PgF:42457M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 16148, image: ORACLE.EXE (J002) *** 2025-06-28 06:00:09.434 *** SESSION ID:(219.15735) 2025-06-28 06:00:09.434 *** 2025-06-28 06:00:09.434 Process diagnostic dump for ORACLE.EXE (J002), OS id=16148, pid: 27, proc_ser: 3, sid: 219, sess_ser: 15735 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 16148 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.079139 sec, exc=0.079139 sec, total=0.079139 sec wait times: max=0.500000 sec, heur=7.181629 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.000006 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.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 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.505103 sec, exc=0.505103 sec, total=0.505103 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 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.000009 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.499990 sec, exc=0.499990 sec, total=0.499990 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 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.500004 sec, exc=0.500004 sec, total=0.500004 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 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.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 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.000009 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.503177 sec, exc=0.503177 sec, total=0.503177 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.499987 sec, exc=0.499987 sec, total=0.499987 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 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.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.000009 sec of elapsed time Sampled Session History of session 219 serial 15735 --------------------------------------------------- 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, 06:00:02 - 06:00:09] idle wait at each sample [session created at: 06:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-28 06:00:09.434 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 16148, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-28 01:56:39.722782 :AA91C8B8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 01:56:48.493837 :AA91CB6A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 01:56:48.486 2025-06-28 01:56:48.493840 :AA91CB6B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15664 pso_num=27 pso_serial#=236 2025-06-28 01:56:48.493983 :AA91CB6C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15664 (legacy spawn) 2025-06-28 01:56:48.500509 :AA91CB6D: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-06-28 01:56:48.500694 :AA91CB72: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-06-28 01:56:48.501495 :AA91CB77:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 01:57:07.633347 :AA91D1BA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 01:57:07.625 2025-06-28 01:57:07.633349 :AA91D1BB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17416 pso_num=27 pso_serial#=237 2025-06-28 01:57:07.633483 :AA91D1BC:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17416 (legacy spawn) 2025-06-28 01:57:07.657576 :AA91D1C9: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-06-28 01:57:07.657760 :AA91D1D2: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-06-28 01:57:07.658633 :AA91D1D3:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 01:57:12.995676 :AA91D365:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 01:57:12.992 2025-06-28 01:57:12.995679 :AA91D366:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17400 pso_num=27 pso_serial#=238 2025-06-28 01:57:12.995826 :AA91D367:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17400 (legacy spawn) 2025-06-28 01:57:13.002316 :AA91D368: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-06-28 01:57:13.002499 :AA91D36D: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-06-28 01:57:13.003301 :AA91D372:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 01:57:13.011904 :AA91D375:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 01:57:13.008 2025-06-28 01:57:13.011906 :AA91D376:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=1020 pso_num=27 pso_serial#=239 2025-06-28 01:57:13.012041 :AA91D377:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=1020 (legacy spawn) 2025-06-28 01:57:13.018473 :AA91D378: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-06-28 01:57:13.018503 :AA91D37B: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-06-28 01:57:13.018643 :AA91D382: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-06-28 01:57:13.019423 :AA91D38F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 01:57:16.114957 :AA91D477:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 01:57:16.102 2025-06-28 01:57:16.114959 :AA91D478:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18212 pso_num=27 pso_serial#=240 2025-06-28 01:57:16.115151 :AA91D479:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18212 (legacy spawn) 2025-06-28 01:57:16.121682 :AA91D47A: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-06-28 01:57:16.121861 :AA91D47F: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-06-28 01:57:16.122728 :AA91D484:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 01:57:16.131752 :AA91D487:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 01:57:16.117 2025-06-28 01:57:16.131754 :AA91D488:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18376 pso_num=27 pso_serial#=241 2025-06-28 01:57:16.131896 :AA91D489:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18376 (legacy spawn) 2025-06-28 01:57:16.138524 :AA91D48A: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-06-28 01:57:16.138690 :AA91D493: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-06-28 01:57:16.139522 :AA91D494:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 01:57:19.581301 :AA91D5C5:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 01:57:19.572 2025-06-28 01:57:19.581303 :AA91D5C6:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10676 pso_num=27 pso_serial#=242 2025-06-28 01:57:19.581455 :AA91D5C7:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10676 (legacy spawn) 2025-06-28 01:57:19.588874 :AA91D5C8: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-06-28 01:57:19.589069 :AA91D5D0: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-06-28 01:57:19.590154 :AA91D5D2:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 01:58:16.045654 :AA91E83D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 01:58:16.032 2025-06-28 01:58:16.045656 :AA91E83E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15788 pso_num=27 pso_serial#=243 2025-06-28 01:58:16.045792 :AA91E83F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15788 (legacy spawn) 2025-06-28 01:58:16.052255 :AA91E840: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-06-28 01:58:16.052453 :AA91E845: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-06-28 01:58:16.053242 :AA91E84A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 01:58:21.781361 :AA91EA0F:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 01:58:21.774 2025-06-28 01:58:21.781363 :AA91EA10:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4556 pso_num=27 pso_serial#=244 2025-06-28 01:58:21.781499 :AA91EA11:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4556 (legacy spawn) 2025-06-28 01:58:21.788044 :AA91EA12: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-06-28 01:58:21.788073 :AA91EA15: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-06-28 01:58:21.788222 :AA91EA18: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-06-28 01:58:21.789043 :AA91EA29:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 01:58:25.508082 :AA91EB3B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 01:58:25.493 2025-06-28 01:58:25.508084 :AA91EB3C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10376 pso_num=27 pso_serial#=245 2025-06-28 01:58:25.508220 :AA91EB3D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10376 (legacy spawn) 2025-06-28 01:58:25.514621 :AA91EB3E: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-06-28 01:58:25.514798 :AA91EB47: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-06-28 01:58:25.515583 :AA91EB48:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 01:58:25.524309 :AA91EB4B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 01:58:25.509 2025-06-28 01:58:25.524311 :AA91EB4C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10400 pso_num=27 pso_serial#=246 2025-06-28 01:58:25.524449 :AA91EB4D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10400 (legacy spawn) 2025-06-28 01:58:25.530920 :AA91EB4E: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-06-28 01:58:25.531087 :AA91EB57: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-06-28 01:58:25.531910 :AA91EB58:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 01:58:28.710140 :AA91EC46:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 01:58:28.697 2025-06-28 01:58:28.710142 :AA91EC47:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16700 pso_num=27 pso_serial#=247 2025-06-28 01:58:28.710285 :AA91EC48:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16700 (legacy spawn) 2025-06-28 01:58:28.716745 :AA91EC49: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-06-28 01:58:28.716920 :AA91EC52: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-06-28 01:58:28.717720 :AA91EC53:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 01:58:28.735710 :AA91EC7E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 01:58:28.728 2025-06-28 01:58:28.735712 :AA91EC7F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=3404 pso_num=27 pso_serial#=248 2025-06-28 01:58:28.735853 :AA91EC80:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=3404 (legacy spawn) 2025-06-28 01:58:28.742219 :AA91EC81: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-06-28 01:58:28.742401 :AA91EC8A: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-06-28 01:58:28.743216 :AA91EC8B:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 01:59:05.022374 :AA91F836:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 01:59:05.009 2025-06-28 01:59:05.022376 :AA91F837:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16928 pso_num=27 pso_serial#=249 2025-06-28 01:59:05.022515 :AA91F838:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16928 (legacy spawn) 2025-06-28 01:59:05.030926 :AA91F839: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-06-28 01:59:05.031105 :AA91F841: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-06-28 01:59:05.031807 :AA91F843:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 01:59:05.042742 :AA91F846:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 01:59:05.040 2025-06-28 01:59:05.042743 :AA91F847:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16896 pso_num=27 pso_serial#=250 2025-06-28 01:59:05.042833 :AA91F848:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16896 (legacy spawn) 2025-06-28 01:59:05.048780 :AA91F849: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-06-28 01:59:05.048951 :AA91F852: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-06-28 01:59:05.049737 :AA91F853:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 01:59:23.909254 :AA91FE03:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 01:59:23.896 2025-06-28 01:59:23.909257 :AA91FE04:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16168 pso_num=27 pso_serial#=251 2025-06-28 01:59:23.909392 :AA91FE05:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16168 (legacy spawn) 2025-06-28 01:59:23.915897 :AA91FE06: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-06-28 01:59:23.916070 :AA91FE0B: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-06-28 01:59:23.916882 :AA91FE10:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 01:59:24.045259 :AA91FE1F:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 01:59:24.037 2025-06-28 01:59:24.045260 :AA91FE20:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10508 pso_num=27 pso_serial#=252 2025-06-28 01:59:24.045403 :AA91FE21:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10508 (legacy spawn) 2025-06-28 01:59:24.051837 :AA91FE22: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-06-28 01:59:24.052008 :AA91FE2B: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-06-28 01:59:24.053201 :AA91FE2C:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 03:36:35.330244 :AA99A08A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 03:36:35.327 2025-06-28 03:36:35.330246 :AA99A08B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12644 pso_num=27 pso_serial#=253 2025-06-28 03:36:35.330386 :AA99A08C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12644 (legacy spawn) 2025-06-28 03:36:35.336844 :AA99A08D: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-06-28 03:36:35.337021 :AA99A092: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-06-28 03:36:35.337810 :AA99A097:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 03:36:35.346458 :AA99A09A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 03:36:35.343 2025-06-28 03:36:35.346461 :AA99A09B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=6572 pso_num=27 pso_serial#=254 2025-06-28 03:36:35.346604 :AA99A09C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=6572 (legacy spawn) 2025-06-28 03:36:35.353106 :AA99A09D: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-06-28 03:36:35.353291 :AA99A0A6: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-06-28 03:36:35.354084 :AA99A0A7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 03:36:37.039226 :AA99A17F:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 03:36:37.030 2025-06-28 03:36:37.039228 :AA99A180:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15328 pso_num=27 pso_serial#=255 2025-06-28 03:36:37.039413 :AA99A181:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15328 (legacy spawn) 2025-06-28 03:36:37.046259 :AA99A1A5: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-06-28 03:36:37.046418 :AA99A1AE: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-06-28 03:36:37.047230 :AA99A1AF:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 03:36:38.502710 :AA99A1D6:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 03:36:38.489 2025-06-28 03:36:38.502712 :AA99A1D7:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18156 pso_num=27 pso_serial#=1 2025-06-28 03:36:38.502843 :AA99A1D8:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18156 (legacy spawn) 2025-06-28 03:36:38.509375 :AA99A1D9: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-06-28 03:36:38.509551 :AA99A1DE: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-06-28 03:36:38.510339 :AA99A1E3:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 03:36:38.518891 :AA99A1E6:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 03:36:38.505 2025-06-28 03:36:38.518894 :AA99A1E7:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15596 pso_num=27 pso_serial#=2 2025-06-28 03:36:38.519030 :AA99A1E8:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15596 (legacy spawn) 2025-06-28 03:36:38.525461 :AA99A1E9: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-06-28 03:36:38.525628 :AA99A1F2: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-06-28 03:36:38.526422 :AA99A1F3:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 06:00:02.222601 :AAA4CD3E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 06:00:02.222 2025-06-28 06:00:02.222603 :AAA4CD3F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16148 pso_num=27 pso_serial#=3 2025-06-28 06:00:02.222743 :AAA4CD40:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=16148 osp_idx=25 osp_ver=106091 osp_pg=0 (spawn #330277) 2025-06-28 06:00:02.222744 :AAA4CD41:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-28 06:00:02.222744 :AAA4CD42:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=137 time=4066145407 2025-06-28 06:00:02.222745 :AAA4CD43:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-28 06:00:02.222745 :AAA4CD44:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-28 06:00:02.222746 :AAA4CD45:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-28 06:00:02.222746 :AAA4CD46:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-28 06:00:02.222746 :AAA4CD47:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-28 06:00:02.223228 :AAA4CD51: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 2025-06-28 06:00:02.229266 :AAA4CD8C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-06-28 06:00:02.230356 :AAA4CD9A: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-06-28 06:00:02.230411 :AAA4CD9D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:jslv.c:9203 2025-06-28 06:00:02.235779 :AAA4CDDB:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-06-28 06:00:02.236364 :AAA4CDDC:db_trace:kcc.c@13684:kccwbp(): [10021:27:219:1] 0115000000000000 0115000000000000 5c22120000000000 2025-06-28 06:00:02.238050 :AAA4CDDD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-28 06:00:02.238142 :AAA4CDEA:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-28 06:00:02.238153 :AAA4CDF1:db_trace:ksb.c@6610:ksbcic_int(): [10254:27:219] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 8397264 1) 2025-06-28 06:00:02.238159 :AAA4CDF2: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-06-28 06:00:02.238190 :AAA4CDFE: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-06-28 06:00:02.238911 :AAA4CE0A:db_trace:ksb.c@6610:ksbcic_int(): [10254:27:219] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (4 0 229711731) 2025-06-28 06:00:02.238916 :AAA4CE0B: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-06-28 06:00:02.238940 :AAA4CE17: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-06-28 06:00:02.238948 :AAA4CE23:db_trace:ksb.c@6610:ksbcic_int(): [10254:27:219] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (6 65563 1) 2025-06-28 06:00:02.238951 :AAA4CE24: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-06-28 06:00:02.238976 :AAA4CE31: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-06-28 06:00:02.238980 :AAA4CE3B:db_trace:ksb.c@6610:ksbcic_int(): [10254:27:219] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (1 2 65563) 2025-06-28 06:00:02.238982 :AAA4CE3C: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-06-28 06:00:02.239030 :AAA4CE5B: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-06-28 06:00:02.240069 :AAA4CEE0:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=15 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-28 06:00:02.240103 :AAA4CEEA:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-06-28 06:00:02.240962 :AAA4CF08:db_trace:kcc.c@13684:kccwbp(): [10021:27:219:1] 0115000000000000 0115000000000000 5d22120000000000 2025-06-28 06:00:02.241343 :AAA4CF1A:db_trace:ksb.c@6610:ksbcic_int(): [10254:27:219] KSBCIC: (USER) starting cic on opcode KSBCIBBS [20] with args (2 0 229711731) 2025-06-28 06:00:02.241349 :AAA4CF1C: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-06-28 06:00:02.241376 :AAA4CF33: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-06-28 06:00:02.249931 :AAA4CF60:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:jslv.c:9203 2025-06-28 06:00:02.251770 :AAA4CF61: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-06-28 06:00:02.251828 :AAA4CF64:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-06-28 06:00:02.251946 :AAA4CF71: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-06-28 06:00:02.347240 :AAA4D04A:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 27 (osid: 16148, J002)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de