Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250713060006\orcl_j002_12148_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:36382M/63366M, Ph+PgF:42432M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 12148, image: ORACLE.EXE (J002) *** 2025-07-13 06:00:06.311 *** SESSION ID:(219.50899) 2025-07-13 06:00:06.311 *** 2025-07-13 06:00:06.311 Process diagnostic dump for ORACLE.EXE (J002), OS id=12148, pid: 27, proc_ser: 96, sid: 219, sess_ser: 50899 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 12148 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.297327 sec, exc=0.297327 sec, total=0.297327 sec wait times: max=0.500000 sec, heur=4.413697 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.000007 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.510100 sec, exc=0.510100 sec, total=0.510100 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=7 seq_num=8 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.000008 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 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.000007 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.510111 sec, exc=0.510111 sec, total=0.510111 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=3 seq_num=4 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 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.505100 sec, exc=0.505100 sec, total=0.505100 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499154 sec, exc=0.499154 sec, total=0.499154 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.091845 sec, exc=0.091845 sec, total=0.091845 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 50899 --------------------------------------------------- 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 --------------------------------------------------- [5 samples, 06:00:01 - 06:00:06] idle wait at each sample [session created at: 06:00:01] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-13 06:00:06.311 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 12148, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-13 01:58:07.711311 :B13C3906:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8296 pso_num=27 pso_serial#=81 2025-07-13 01:58:07.711446 :B13C3907:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8296 (legacy spawn) 2025-07-13 01:58:07.718041 :B13C3908: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-13 01:58:07.718213 :B13C3911: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-13 01:58:07.719025 :B13C3912:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 01:58:08.231129 :B13C3953:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 01:58:08.222 2025-07-13 01:58:08.231131 :B13C3954:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17612 pso_num=27 pso_serial#=82 2025-07-13 01:58:08.231264 :B13C3955:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17612 (legacy spawn) 2025-07-13 01:58:08.237804 :B13C3956: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-13 01:58:08.237969 :B13C395F: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-13 01:58:08.238841 :B13C3960:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 01:58:10.798000 :B13C3A16:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 01:58:10.785 2025-07-13 01:58:10.798002 :B13C3A17:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=1220 pso_num=27 pso_serial#=83 2025-07-13 01:58:10.798138 :B13C3A18:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=1220 (legacy spawn) 2025-07-13 01:58:10.804800 :B13C3A19: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-13 01:58:10.804978 :B13C3A1E: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-13 01:58:10.805774 :B13C3A23:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 01:58:10.814557 :B13C3A26:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 01:58:10.800 2025-07-13 01:58:10.814559 :B13C3A27:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16944 pso_num=27 pso_serial#=84 2025-07-13 01:58:10.814697 :B13C3A28:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16944 (legacy spawn) 2025-07-13 01:58:10.821320 :B13C3A35: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-13 01:58:10.821510 :B13C3A3C: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-13 01:58:10.822405 :B13C3A3F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 01:58:21.466141 :B13C3DAD:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 01:58:21.457 2025-07-13 01:58:21.466143 :B13C3DAE:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15216 pso_num=27 pso_serial#=85 2025-07-13 01:58:21.466283 :B13C3DAF:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15216 (legacy spawn) 2025-07-13 01:58:21.472689 :B13C3DB0: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-13 01:58:21.472869 :B13C3DB5: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-13 01:58:21.473682 :B13C3DBA:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 01:59:16.471243 :B13C4F78:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 01:59:16.464 2025-07-13 01:59:16.471246 :B13C4F79:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18140 pso_num=27 pso_serial#=86 2025-07-13 01:59:16.471406 :B13C4F7A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18140 (legacy spawn) 2025-07-13 01:59:16.478479 :B13C4F7B: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-13 01:59:16.478671 :B13C4F80: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-13 01:59:16.479604 :B13C4F85:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 01:59:20.088620 :B13C50D8:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 01:59:20.074 2025-07-13 01:59:20.088623 :B13C50D9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17248 pso_num=27 pso_serial#=87 2025-07-13 01:59:20.088759 :B13C50DA:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17248 (legacy spawn) 2025-07-13 01:59:20.095303 :B13C50DB: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-13 01:59:20.095474 :B13C50E4: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-13 01:59:20.096276 :B13C50E5:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 01:59:20.105126 :B13C50E8:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 01:59:20.105 2025-07-13 01:59:20.105128 :B13C50E9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13528 pso_num=27 pso_serial#=88 2025-07-13 01:59:20.105261 :B13C50EA:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13528 (legacy spawn) 2025-07-13 01:59:20.111719 :B13C50EB: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-13 01:59:20.111875 :B13C50F4: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-13 01:59:20.112674 :B13C50F5:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 01:59:23.208804 :B13C51D5:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 01:59:23.201 2025-07-13 01:59:23.208806 :B13C51D6:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9536 pso_num=27 pso_serial#=89 2025-07-13 01:59:23.208940 :B13C51D7:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9536 (legacy spawn) 2025-07-13 01:59:23.215467 :B13C51D8: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-13 01:59:23.215497 :B13C51DB: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-13 01:59:23.215654 :B13C51DE: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-13 01:59:23.216444 :B13C51EF:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 01:59:23.225330 :B13C51F2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 01:59:23.216 2025-07-13 01:59:23.225332 :B13C51F3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14300 pso_num=27 pso_serial#=90 2025-07-13 01:59:23.225466 :B13C51F4:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14300 (legacy spawn) 2025-07-13 01:59:23.232018 :B13C51F5: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-13 01:59:23.232181 :B13C51FE: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-13 01:59:23.233027 :B13C51FF:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 01:59:23.663597 :B13C5257:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 01:59:23.655 2025-07-13 01:59:23.663599 :B13C5258:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12164 pso_num=27 pso_serial#=91 2025-07-13 01:59:23.663732 :B13C5259:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12164 (legacy spawn) 2025-07-13 01:59:23.670179 :B13C525A: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-13 01:59:23.670345 :B13C525F: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-13 01:59:23.671208 :B13C5264:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 01:59:54.803010 :B13C5C67:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 01:59:54.788 2025-07-13 01:59:54.803012 :B13C5C68:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13716 pso_num=27 pso_serial#=92 2025-07-13 01:59:54.803183 :B13C5C6B:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=13716 osp_idx=25 osp_ver=156415 osp_pg=0 (spawn #379718) 2025-07-13 01:59:54.803184 :B13C5C6C: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-13 01:59:54.803184 :B13C5C6D:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=236 time=1052770689 2025-07-13 01:59:54.803185 :B13C5C6E:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 01:59:54.803186 :B13C5C6F:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 01:59:54.803187 :B13C5C70:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 01:59:54.803187 :B13C5C71:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 01:59:54.803187 :B13C5C72:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 01:59:54.803765 :B13C5C7B: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-07-13 02:00:24.904002 :B13C685E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 03:59:14.956680 :B145DA72:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 03:59:14.946 2025-07-13 03:59:14.956682 :B145DA73:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10356 pso_num=27 pso_serial#=93 2025-07-13 03:59:14.956823 :B145DA74:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10356 (legacy spawn) 2025-07-13 03:59:14.968372 :B145DA83: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-13 03:59:14.969190 :B145DA8B:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 04:26:31.960226 :B1480B8F:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 04:26:31.947 2025-07-13 04:26:31.960228 :B1480B90:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16212 pso_num=27 pso_serial#=94 2025-07-13 04:26:31.960367 :B1480B91:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16212 (legacy spawn) 2025-07-13 04:26:31.966867 :B1480B92: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-13 04:26:31.975566 :B1480BA1: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-13 04:26:31.976312 :B1480BA9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 06:00:00.044504 :B14F772C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 06:00:00.036 2025-07-13 06:00:00.044506 :B14F772D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15780 pso_num=27 pso_serial#=95 2025-07-13 06:00:00.044640 :B14F772E:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=15780 osp_idx=25 osp_ver=156418 osp_pg=0 (spawn #380273) 2025-07-13 06:00:00.044641 :B14F772F: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-13 06:00:00.044641 :B14F7730:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=237 time=1067175939 2025-07-13 06:00:00.044642 :B14F7731:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 06:00:00.044642 :B14F7732:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 06:00:00.044642 :B14F7733:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 06:00:00.044643 :B14F7734:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 06:00:00.044643 :B14F7735:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 06:00:00.045138 :B14F7736: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-13 06:00:00.045181 :B14F773D: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-13 06:00:00.045256 :B14F7750:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-13 06:00:00.049226 :B14F775F: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-13 06:00:00.050478 :B14F7767: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-13 06:00:00.050503 :B14F776A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-13 06:00:00.050547 :B14F7776:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-13 06:00:00.052370 :B14F7780: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-13 06:00:00.052398 :B14F7783:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-13 06:00:00.052434 :B14F778E:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-13 06:00:00.052891 :B14F7799: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-13 06:00:00.055427 :B14F77A1: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-13 06:00:00.060685 :B14F77A9: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-13 06:00:00.066234 :B14F77B1: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-13 06:00:00.067637 :B14F77B9: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-13 06:00:00.070690 :B14F77C1: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-13 06:00:00.071960 :B14F77C9: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-13 06:00:00.074958 :B14F77D1: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-13 06:00:00.078812 :B14F77D9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-13 06:00:00.079222 :B14F77E8: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-13 06:00:00.079270 :B14F77EB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:ktcc.c:879 2025-07-13 06:00:00.079284 :B14F77EC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:ktcc.c:879 2025-07-13 06:00:00.081995 :B14F77F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-13 06:00:00.082324 :B14F7801: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-13 06:00:00.082392 :B14F7804:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:ktcc.c:879 2025-07-13 06:00:00.082414 :B14F7805:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:ktcc.c:879 2025-07-13 06:00:00.085174 :B14F780B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-13 06:00:00.085460 :B14F781A: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-13 06:00:00.085508 :B14F781D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:ktcc.c:879 2025-07-13 06:00:00.085522 :B14F781E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:ktcc.c:879 2025-07-13 06:00:00.085704 :B14F7824: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-13 06:00:00.085717 :B14F7827:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-13 06:00:00.085741 :B14F7833:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-13 06:00:00.087392 :B14F783D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 06:00:01.869535 :B14F7913:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 06:00:01.864 2025-07-13 06:00:01.869536 :B14F7914:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12148 pso_num=27 pso_serial#=96 2025-07-13 06:00:01.869676 :B14F7915:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=12148 osp_idx=25 osp_ver=156419 osp_pg=0 (spawn #380274) 2025-07-13 06:00:01.869676 :B14F7916: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-13 06:00:01.869677 :B14F7917:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=238 time=1067177767 2025-07-13 06:00:01.869677 :B14F7918:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 06:00:01.869678 :B14F7919:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 06:00:01.869678 :B14F791A:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 06:00:01.869678 :B14F791B:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 06:00:01.869679 :B14F791C:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 06:00:01.870202 :B14F792A: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-07-13 06:00:01.875475 :B14F795C: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-13 06:00:01.875518 :B14F795F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:jslv.c:9203 2025-07-13 06:00:01.880197 :B14F797A:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-07-13 06:00:01.880774 :B14F797B:db_trace:kcc.c@13684:kccwbp(): [10021:27:219:1] 0115000000000000 0115000000000000 3c40120000000000 2025-07-13 06:00:01.882596 :B14F79A4: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-07-13 06:00:01.882673 :B14F79B2: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-07-13 06:00:01.882683 :B14F79B8:db_trace:ksb.c@6610:ksbcic_int(): [10254:27:219] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 8397264 1) 2025-07-13 06:00:01.882688 :B14F79B9: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-13 06:00:01.882716 :B14F79C5: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-13 06:00:01.883387 :B14F79D9:db_trace:ksb.c@6610:ksbcic_int(): [10254:27:219] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (4 0 231199749) 2025-07-13 06:00:01.883391 :B14F79DA: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-13 06:00:01.883413 :B14F79E5: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-13 06:00:01.883420 :B14F79F2:db_trace:ksb.c@6610:ksbcic_int(): [10254:27:219] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (6 65563 1) 2025-07-13 06:00:01.883423 :B14F79F3: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-13 06:00:01.883453 :B14F79FF: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-13 06:00:01.883457 :B14F7A08:db_trace:ksb.c@6610:ksbcic_int(): [10254:27:219] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (1 2 65563) 2025-07-13 06:00:01.883459 :B14F7A0C: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-13 06:00:01.883505 :B14F7A2B: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-13 06:00:01.884531 :B14F7AFA: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-07-13 06:00:01.884559 :B14F7B0A:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-07-13 06:00:01.885470 :B14F7B0B:db_trace:kcc.c@13684:kccwbp(): [10021:27:219:1] 0115000000000000 0115000000000000 3d40120000000000 2025-07-13 06:00:01.885837 :B14F7B0C:db_trace:ksb.c@6610:ksbcic_int(): [10254:27:219] KSBCIC: (USER) starting cic on opcode KSBCIBBS [20] with args (2 0 231199749) 2025-07-13 06:00:01.885844 :B14F7B0D: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-13 06:00:01.885872 :B14F7B1A: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-13 06:00:01.894559 :B14F7B5A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:jslv.c:9203 2025-07-13 06:00:01.895963 :B14F7B72:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=24 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.895974 :B14F7B74:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=24 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.895987 :B14F7B76:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=24 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-13 06:00:01.896026 :B14F7B78:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=24 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-13 06:00:01.896137 :B14F7B7A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=24 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 2025-07-13 06:00:01.896714 :B14F7B7F: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-13 06:00:01.896758 :B14F7B86: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-07-13 06:00:01.896839 :B14F7B94: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-13 06:00:01.989390 :B14F7C9B: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: 12148, J002)