Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250504100101\orcl_j001_9756_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:37584M/63366M, Ph+PgF:45118M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 9756, image: ORACLE.EXE (J001) *** 2025-05-04 10:01:01.623 *** SESSION ID:(193.53841) 2025-05-04 10:01:01.623 *** 2025-05-04 10:01:01.623 Process diagnostic dump for ORACLE.EXE (J001), OS id=9756, pid: 24, proc_ser: 125, sid: 193, sess_ser: 53841 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 9756 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.296173 sec, exc=0.296173 sec, total=0.296173 sec wait times: max=0.500000 sec, heur=2.294093 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000011 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.503173 sec, exc=0.503173 sec, total=0.503173 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 1: 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.000012 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 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.000014 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.494706 sec, exc=0.494706 sec, total=0.494706 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 193 serial 53841 --------------------------------------------------- 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 --------------------------------------------------- [3 samples, 10:00:59 - 10:01:01] idle wait at each sample [session created at: 10:00:59] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-04 10:01:01.623 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 9756, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-04 09:53:16.069823 :92856797:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11460 pso_num=24 pso_serial#=114 2025-05-04 09:53:16.069953 :92856798:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11460 osp_idx=22 osp_ver=221708 osp_pg=0 (spawn #147024) 2025-05-04 09:53:16.069954 :92856799:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 09:53:16.069954 :9285679A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=158 time=3623173234 2025-05-04 09:53:16.069955 :9285679B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 09:53:16.069955 :9285679C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 09:53:16.069956 :9285679D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 09:53:16.069956 :9285679E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 09:53:16.069956 :9285679F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 09:53:16.070447 :928567A0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 09:53:46.132256 :928571BC:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 09:54:16.153684 :92857B75:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 09:54:16.141 2025-05-04 09:54:16.153686 :92857B76:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11956 pso_num=24 pso_serial#=115 2025-05-04 09:54:16.153823 :92857B77:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11956 osp_idx=22 osp_ver=221709 osp_pg=0 (spawn #147026) 2025-05-04 09:54:16.153824 :92857B78:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 09:54:16.153825 :92857B79:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=159 time=3623233312 2025-05-04 09:54:16.153825 :92857B7A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 09:54:16.153825 :92857B7B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 09:54:16.153826 :92857B7C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 09:54:16.153826 :92857B7D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 09:54:16.153826 :92857B7E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 09:54:16.154374 :92857B7F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 09:54:46.245107 :9285852F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 09:54:46.258724 :9285853F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 09:54:46.245 2025-05-04 09:54:46.258726 :92858540:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10120 pso_num=24 pso_serial#=116 2025-05-04 09:54:46.258869 :92858541:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10120 (legacy spawn) 2025-05-04 09:54:46.265680 :92858545:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 09:54:46.265841 :9285854D:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-04 09:54:46.266640 :9285854F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 09:55:16.231376 :92858EBC:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 09:55:16.220 2025-05-04 09:55:16.231378 :92858EBD:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=2936 pso_num=24 pso_serial#=117 2025-05-04 09:55:16.231523 :92858EBE:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=2936 osp_idx=22 osp_ver=221711 osp_pg=0 (spawn #147029) 2025-05-04 09:55:16.231524 :92858EBF:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 09:55:16.231524 :92858EC0:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=160 time=3623293390 2025-05-04 09:55:16.231524 :92858EC1:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 09:55:16.231525 :92858EC2:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 09:55:16.231525 :92858EC3:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 09:55:16.231526 :92858EC4:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 09:55:16.231526 :92858EC5:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 09:55:16.232005 :92858EC6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 09:55:46.335210 :92859867:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 09:56:11.321222 :9285A0AA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 09:56:11.310 2025-05-04 09:56:11.321224 :9285A0AB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10872 pso_num=24 pso_serial#=118 2025-05-04 09:56:11.321352 :9285A0AC:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10872 osp_idx=22 osp_ver=221712 osp_pg=0 (spawn #147031) 2025-05-04 09:56:11.321353 :9285A0AD:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 09:56:11.321353 :9285A0AE:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=161 time=3623348484 2025-05-04 09:56:11.321354 :9285A0AF:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 09:56:11.321355 :9285A0B0:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 09:56:11.321356 :9285A0B1:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 09:56:11.321356 :9285A0B2:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 09:56:11.321356 :9285A0B3:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 09:56:11.321835 :9285A0B4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 09:56:16.321142 :9285A28E:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-04 09:56:46.449967 :9285AC77:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 09:57:16.439174 :9285B64F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 09:57:16.428 2025-05-04 09:57:16.439176 :9285B650:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=6556 pso_num=24 pso_serial#=119 2025-05-04 09:57:16.439356 :9285B651:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=6556 osp_idx=22 osp_ver=221713 osp_pg=0 (spawn #147033) 2025-05-04 09:57:16.439357 :9285B652:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 09:57:16.439357 :9285B653:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=162 time=3623413593 2025-05-04 09:57:16.439357 :9285B654:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 09:57:16.439358 :9285B655:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 09:57:16.439358 :9285B656:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 09:57:16.439358 :9285B657:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 09:57:16.439359 :9285B658:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 09:57:16.439868 :9285B659:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 09:57:46.561302 :9285BFF2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 09:58:16.549102 :9285C9A3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 09:58:16.538 2025-05-04 09:58:16.549104 :9285C9A4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9368 pso_num=24 pso_serial#=120 2025-05-04 09:58:16.549248 :9285C9A5:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9368 osp_idx=22 osp_ver=221714 osp_pg=0 (spawn #147035) 2025-05-04 09:58:16.549248 :9285C9A6:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 09:58:16.549249 :9285C9A7:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=163 time=3623473703 2025-05-04 09:58:16.549249 :9285C9A8:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 09:58:16.549250 :9285C9A9:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 09:58:16.549250 :9285C9AA:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 09:58:16.549250 :9285C9AB:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 09:58:16.549250 :9285C9AC:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 09:58:16.549725 :9285C9AD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 09:58:46.636958 :9285D431:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 09:58:46.691810 :9285D434:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 09:58:46.684 2025-05-04 09:58:46.691812 :9285D435:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10736 pso_num=24 pso_serial#=121 2025-05-04 09:58:46.691951 :9285D436:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10736 (legacy spawn) 2025-05-04 09:58:46.698353 :9285D437:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 09:58:46.698522 :9285D43D:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-04 09:58:46.699325 :9285D441:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 09:59:16.629089 :9285DDF0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 09:59:16.618 2025-05-04 09:59:16.629092 :9285DDF1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10744 pso_num=24 pso_serial#=122 2025-05-04 09:59:16.629231 :9285DDF2:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10744 osp_idx=22 osp_ver=221716 osp_pg=0 (spawn #147037) 2025-05-04 09:59:16.629232 :9285DDF3:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 09:59:16.629232 :9285DDF4:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=164 time=3623533781 2025-05-04 09:59:16.629233 :9285DDF5:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 09:59:16.629233 :9285DDF6:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 09:59:16.629233 :9285DDF7:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 09:59:16.629234 :9285DDF8:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 09:59:16.629234 :9285DDF9:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 09:59:16.629702 :9285DDFA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 09:59:46.732165 :9285E7B9:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 10:00:16.716457 :9285F190:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 10:00:16.705 2025-05-04 10:00:16.716460 :9285F191:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=4872 pso_num=24 pso_serial#=123 2025-05-04 10:00:16.716594 :9285F192:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=4872 osp_idx=22 osp_ver=221717 osp_pg=0 (spawn #147039) 2025-05-04 10:00:16.716595 :9285F193:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 10:00:16.716595 :9285F194:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=165 time=3623593875 2025-05-04 10:00:16.716596 :9285F195:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 10:00:16.716596 :9285F196:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 10:00:16.716596 :9285F197:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 10:00:16.716597 :9285F198:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 10:00:16.716597 :9285F199:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 10:00:16.717098 :9285F19A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 10:00:46.784128 :9285FBFA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 10:00:57.538524 :9285FFB5:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 10:00:57.526 2025-05-04 10:00:57.538527 :9285FFB6:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11092 pso_num=24 pso_serial#=124 2025-05-04 10:00:57.538666 :9285FFB7:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11092 osp_idx=22 osp_ver=221718 osp_pg=0 (spawn #147041) 2025-05-04 10:00:57.538667 :9285FFB8:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 10:00:57.538667 :9285FFB9:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=166 time=3623634703 2025-05-04 10:00:57.538668 :9285FFBA:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 10:00:57.538668 :9285FFBB:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 10:00:57.538669 :9285FFBC:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 10:00:57.538669 :9285FFBD:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 10:00:57.538669 :9285FFBE:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 10:00:57.539178 :9285FFBF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 10:00:57.539219 :9285FFC5:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-04 10:00:57.543235 :9285FFD8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-05-04 10:00:57.543278 :9285FFD9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-05-04 10:00:57.543545 :9285FFDA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-05-04 10:00:57.543561 :9285FFDB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-05-04 10:00:57.543780 :9285FFDC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-05-04 10:00:57.543793 :9285FFDD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-05-04 10:00:57.544005 :9285FFDE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-05-04 10:00:57.544018 :9285FFDF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-05-04 10:00:57.544223 :9285FFE0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-05-04 10:00:57.544236 :9285FFE1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-05-04 10:00:57.544465 :9285FFE2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-05-04 10:00:57.544478 :9285FFE3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-05-04 10:00:57.547029 :92860002:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 10:00:57.678771 :928602BE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 10:00:57.667 2025-05-04 10:00:57.678773 :928602BF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9756 pso_num=24 pso_serial#=125 2025-05-04 10:00:57.678915 :928602C0:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9756 osp_idx=22 osp_ver=221719 osp_pg=0 (spawn #147046) 2025-05-04 10:00:57.678916 :928602C1:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 10:00:57.678917 :928602C2:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=167 time=3623634843 2025-05-04 10:00:57.678918 :928602C3:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 10:00:57.678918 :928602C4:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 10:00:57.678918 :928602C5:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 10:00:57.678919 :928602C6:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 10:00:57.678920 :928602C7:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 10:00:57.679434 :928602D1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 10:00:57.684422 :928602E6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 10:00:57.684461 :928602E9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-05-04 10:00:57.689264 :92860303:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 10:00:57.829022 :928604E1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 10:00:57.966511 :928604F5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 10:00:58.102761 :92860519:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 10:00:59.238286 :9286063E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 10:00:59.239474 :92860646:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 10:00:59.245326 :9286064E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 10:00:59.264451 :92860656:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 10:00:59.265340 :9286065E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 10:00:59.266013 :92860666:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 10:00:59.266669 :9286066E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 10:00:59.270143 :92860676:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 10:00:59.271484 :9286067E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-04 10:00:59.272539 :9286067F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-04 10:00:59.273608 :92860680:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-04 10:00:59.274178 :92860681:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-04 10:00:59.274186 :92860682:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-04 10:00:59.274970 :92860683:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-04 10:00:59.275677 :92860684:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-04 10:00:59.276135 :92860685:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-04 10:00:59.277491 :92860686:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-04 10:00:59.315808 :92860687:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 10:00:59.315858 :9286068A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-04 10:00:59.316714 :92860690:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 10:00:59.317680 :92860698:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-04 10:00:59.318135 :92860699:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 10:00:59.334736 :928606A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 10:00:59.334787 :928606A4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-04 10:00:59.335605 :928606AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 10:00:59.336063 :928606B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 10:00:59.336482 :928606BA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 10:00:59.336673 :928606C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 10:00:59.336815 :928606C5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 10:00:59.336951 :928606CC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-05-04 10:00:59.341206 :928606D8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 10:00:59.341290 :928606DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-05-04 10:00:59.341368 :928606E8:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 9756, J001)