Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250519220006\orcl_j004_13708_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:37334M/63366M, Ph+PgF:44456M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 30 Windows thread id: 13708, image: ORACLE.EXE (J004) *** 2025-05-19 22:00:06.954 *** SESSION ID:(241.1759) 2025-05-19 22:00:06.954 *** 2025-05-19 22:00:06.954 Process diagnostic dump for ORACLE.EXE (J004), OS id=13708, pid: 30, proc_ser: 83, sid: 241, sess_ser: 1759 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 13708 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J004) 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.076029 sec, exc=0.076029 sec, total=0.076029 sec wait times: max=0.500000 sec, heur=4.602053 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.000008 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.514331 sec, exc=0.514331 sec, total=0.514331 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.510106 sec, exc=0.510106 sec, total=0.510106 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=6 seq_num=7 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 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.000009 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.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 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.504576 sec, exc=0.504576 sec, total=0.504576 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 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.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 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.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.000007 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.496948 sec, exc=0.496948 sec, total=0.496948 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 241 serial 1759 --------------------------------------------------- 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, 22:00:02 - 22:00:06] idle wait at each sample [session created at: 22:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-19 22:00:06.954 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 30 (osid: 13708, J004) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-18 10:00:13.364960 :98B00480:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-18 10:00:13.372359 :98B0048E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.373404 :98B00496:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.373944 :98B0049E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.374391 :98B004A6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.374823 :98B004AE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.377286 :98B004B6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.378310 :98B004BE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.427960 :98B004C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.428881 :98B004CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.429107 :98B004D6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.429353 :98B004DE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.431299 :98B004E6:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-18 11:00:20.072318 :98B4D2AC:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-18 11:00:20.070 2025-05-18 11:00:20.072320 :98B4D2AD:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=13156 pso_num=30 pso_serial#=77 2025-05-18 11:00:20.072464 :98B4D2AE:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=13156 osp_idx=28 osp_ver=841 osp_pg=0 (spawn #194315) 2025-05-18 11:00:20.072464 :98B4D2AF:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-18 11:00:20.072465 :98B4D2B0:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=139 time=541829922 2025-05-18 11:00:20.072465 :98B4D2B1:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-05-18 11:00:20.072465 :98B4D2B2:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-05-18 11:00:20.072466 :98B4D2B3:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-05-18 11:00:20.072466 :98B4D2B4:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-18 11:00:20.072466 :98B4D2B5:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-18 11:00:20.072986 :98B4D2B6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-18 11:00:20.073030 :98B4D2BC:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-18 11:00:20.081185 :98B4D2DE:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-18 14:00:45.652862 :98C3466F:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-18 14:00:45.645 2025-05-18 14:00:45.652864 :98C34670:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=13740 pso_num=30 pso_serial#=78 2025-05-18 14:00:45.652998 :98C34671:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=13740 osp_idx=28 osp_ver=842 osp_pg=0 (spawn #194736) 2025-05-18 14:00:45.652998 :98C34672:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-18 14:00:45.652999 :98C34673:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=140 time=552655500 2025-05-18 14:00:45.652999 :98C34674:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-05-18 14:00:45.653000 :98C34675:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-05-18 14:00:45.653000 :98C34676:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-05-18 14:00:45.653000 :98C34677:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-18 14:00:45.653001 :98C34678:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-18 14:00:45.653492 :98C34679:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-18 14:00:45.653549 :98C3467F:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-18 14:00:45.660726 :98C3468D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 14:00:45.661784 :98C34695:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 14:00:45.662329 :98C3469D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 14:00:45.662869 :98C346A5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 14:00:45.663305 :98C346AD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 14:00:45.665712 :98C346B5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 14:00:45.666849 :98C346BD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 14:00:45.716801 :98C346CF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 14:00:45.717710 :98C346D7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 14:00:45.717940 :98C346DF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 14:00:45.718155 :98C346E7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 14:00:45.720327 :98C346EF:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-18 16:00:02.574319 :98CCB813:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-18 16:00:02.570 2025-05-18 16:00:02.574321 :98CCB814:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=12292 pso_num=30 pso_serial#=79 2025-05-18 16:00:02.574455 :98CCB815:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=12292 osp_idx=28 osp_ver=843 osp_pg=0 (spawn #195009) 2025-05-18 16:00:02.574455 :98CCB816:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-18 16:00:02.574456 :98CCB817:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=141 time=559812422 2025-05-18 16:00:02.574457 :98CCB818:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-05-18 16:00:02.574457 :98CCB819:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-05-18 16:00:02.574457 :98CCB81A:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-05-18 16:00:02.574457 :98CCB81B:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-18 16:00:02.574458 :98CCB81C:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-18 16:00:02.574956 :98CCB81D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-18 16:00:02.574998 :98CCB823:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-18 16:00:02.582327 :98CCB831:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 16:00:02.583379 :98CCB839:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 16:00:02.583925 :98CCB841:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 16:00:02.584459 :98CCB849:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 16:00:02.584921 :98CCB851:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 16:00:02.587402 :98CCB859:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 16:00:02.588540 :98CCB861:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 16:00:02.637995 :98CCB875:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 16:00:02.638905 :98CCB87D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 16:00:02.639151 :98CCB885:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 16:00:02.639369 :98CCB88D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 16:00:02.641046 :98CCB895:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-18 23:59:05.998809 :98F32577:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-18 23:59:05.992 2025-05-18 23:59:05.998811 :98F32578:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=8936 pso_num=30 pso_serial#=80 2025-05-18 23:59:05.998945 :98F32579:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=8936 (legacy spawn) 2025-05-18 23:59:06.013103 :98F3257A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 23:59:39.175933 :98F332A0:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-18 23:59:50.992863 :98F336E6:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-18 23:59:50.979 2025-05-18 23:59:50.992865 :98F336E7:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=8604 pso_num=30 pso_serial#=81 2025-05-18 23:59:50.993002 :98F336E8:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=8604 (legacy spawn) 2025-05-18 23:59:51.006775 :98F336E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 00:00:24.203669 :98F345E6:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-19 01:00:15.817341 :98F811DC:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-19 01:00:15.815 2025-05-19 01:00:15.817343 :98F811DD:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=12380 pso_num=30 pso_serial#=82 2025-05-19 01:00:15.817486 :98F811DE:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=12380 osp_idx=28 osp_ver=846 osp_pg=0 (spawn #196266) 2025-05-19 01:00:15.817487 :98F811DF:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-19 01:00:15.817487 :98F811E0:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=142 time=592225672 2025-05-19 01:00:15.817487 :98F811E1:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-05-19 01:00:15.817488 :98F811E2:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-05-19 01:00:15.817488 :98F811E3:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-05-19 01:00:15.817489 :98F811E4:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-19 01:00:15.817489 :98F811E5:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-19 01:00:15.817983 :98F811E6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-19 01:00:15.818027 :98F811EC:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-19 01:00:15.825922 :98F811FA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 01:00:15.827024 :98F81202:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 01:00:15.827580 :98F8120A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 01:00:15.828031 :98F81212:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 01:00:15.828466 :98F8121A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 01:00:15.831008 :98F81222:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 01:00:15.832084 :98F8122A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 01:00:15.882249 :98F81232:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 01:00:15.883218 :98F8123A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 01:00:15.883470 :98F81242:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 01:00:15.883696 :98F8124A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 01:00:15.885367 :98F81252:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-19 22:00:00.458005 :995BCF5E:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-19 22:00:00.446 2025-05-19 22:00:00.458007 :995BCF5F:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=13708 pso_num=30 pso_serial#=83 2025-05-19 22:00:00.458145 :995BCF65:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=13708 osp_idx=28 osp_ver=847 osp_pg=0 (spawn #199178) 2025-05-19 22:00:00.458146 :995BCF66:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-19 22:00:00.458147 :995BCF67:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=143 time=667810313 2025-05-19 22:00:00.458147 :995BCF68:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-05-19 22:00:00.458147 :995BCF69:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-05-19 22:00:00.458148 :995BCF6A:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-05-19 22:00:00.458148 :995BCF6B:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-19 22:00:00.458148 :995BCF6C:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-19 22:00:00.458722 :995BCF70:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-19 22:00:00.463846 :995BCFC1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:00.463896 :995BCFC4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:jslv.c:9203 2025-05-19 22:00:00.469742 :995BD122:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:00.663447 :995BD253:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:00.849360 :995BD263:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:00.987098 :995BD283:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:01.106471 :995BD293:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.102316 :995BD4B8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.103298 :995BD4C0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.107327 :995BD4C8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.109678 :995BD4D0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.111932 :995BD4D8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.113519 :995BD4E0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.114998 :995BD4E8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.133211 :995BD4F0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.134772 :995BD4F8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.135398 :995BD500:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.135906 :995BD508:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.136405 :995BD510:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.138962 :995BD518:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.139907 :995BD520:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-19 22:00:02.140659 :995BD521:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-19 22:00:02.141433 :995BD522:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-19 22:00:02.141824 :995BD523:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-19 22:00:02.141830 :995BD524:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-19 22:00:02.142369 :995BD525:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-19 22:00:02.142849 :995BD526:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-19 22:00:02.143121 :995BD527:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-19 22:00:02.144349 :995BD528:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-19 22:00:02.180794 :995BD533:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.180846 :995BD536:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-19 22:00:02.181774 :995BD53C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.182671 :995BD544:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-19 22:00:02.183118 :995BD545:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.184448 :995BD54D:db_trace:ktu.c@4871:ktuaex1r(): [10442:30:241] Extension usn 8, ext 2, nex 3 2025-05-19 22:00:02.184454 :995BD54E:db_trace:ktusm.c@2117:ktusmasp(): [10445:30:241] des1:tsn:3 rdba:0x00400b81 where: kddwh01: kdddel 2025-05-19 22:00:02.203831 :995BD57E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.203880 :995BD581:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-19 22:00:02.204752 :995BD587:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.216515 :995BD58F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-19 22:00:02.216994 :995BD590:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.237385 :995BD598:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.237432 :995BD59B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-19 22:00:02.238319 :995BD5A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.239169 :995BD5A9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-19 22:00:02.239641 :995BD5AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.257771 :995BD5B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.257840 :995BD5B5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-19 22:00:02.258899 :995BD5BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.259836 :995BD5C3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-19 22:00:02.260282 :995BD5C4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.282361 :995BD5CC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.282417 :995BD5CF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-19 22:00:02.283294 :995BD5D5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.284177 :995BD5DD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-19 22:00:02.284649 :995BD5DE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.304821 :995BD5E6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.304875 :995BD5E9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-19 22:00:02.305659 :995BD5EF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.356325 :995BD5FF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.356859 :995BD607:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.357222 :995BD60F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.357408 :995BD617:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.357542 :995BD61F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.357716 :995BD627:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:jslv.c:9203 2025-05-19 22:00:02.361592 :995BD628:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.361674 :995BD62B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-05-19 22:00:02.361758 :995BD638:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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 30 (osid: 13708, J004)