Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250718220005\orcl_j002_18756_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250718220005\orcl_j002_18756_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:36219M/63366M, Ph+PgF:42149M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 18756, image: ORACLE.EXE (J002) *** 2025-07-18 22:00:05.336 *** SESSION ID:(219.11161) 2025-07-18 22:00:05.336 *** 2025-07-18 22:00:05.336 Process diagnostic dump for ORACLE.EXE (J002), OS id=18756, pid: 27, proc_ser: 237, sid: 219, sess_ser: 11161 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 18756 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.169788 sec, exc=0.169788 sec, total=0.169788 sec wait times: max=0.500000 sec, heur=4.770996 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=9 seq_num=10 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.000012 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.499979 sec, exc=0.499979 sec, total=0.499979 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=7 seq_num=8 snap_id=1 wait times: snap=0.500004 sec, exc=0.500004 sec, total=0.500004 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.500006 sec, exc=0.500006 sec, total=0.500006 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.504581 sec, exc=0.504581 sec, total=0.504581 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.500012 sec, exc=0.500012 sec, total=0.500012 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.500005 sec, exc=0.500005 sec, total=0.500005 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499246 sec, exc=0.499246 sec, total=0.499246 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.097286 sec, exc=0.097286 sec, total=0.097286 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 11161 --------------------------------------------------- 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:00 - 22:00:05] idle wait at each sample [session created at: 22:00:00] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-18 22:00:05.336 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 18756, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-18 08:16:59.082362 :B3924C91:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-18 08:16:59.083218 :B3924C92: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-18 08:16:59.083271 :B3924C98: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-18 08:16:59.186379 :B3924CCA:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-18 08:16:59.315627 :B3924CCD:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-18 08:16:59.304 2025-07-18 08:16:59.315630 :B3924CCE:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8336 pso_num=27 pso_serial#=225 2025-07-18 08:16:59.315856 :B3924CCF:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8336 (legacy spawn) 2025-07-18 08:16:59.328900 :B3924CD0: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-18 08:16:59.329103 :B3924CD9: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-18 08:16:59.329880 :B3924CDA:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-18 08:16:59.735930 :B3924D0A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-18 08:16:59.728 2025-07-18 08:16:59.735932 :B3924D0B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18312 pso_num=27 pso_serial#=226 2025-07-18 08:16:59.736104 :B3924D0C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18312 (legacy spawn) 2025-07-18 08:16:59.742527 :B3924D0D: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-18 08:16:59.742709 :B3924D12: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-18 08:16:59.743549 :B3924D22:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-18 08:17:01.371557 :B3924DFD:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-18 08:17:01.369 2025-07-18 08:17:01.371559 :B3924DFE:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16156 pso_num=27 pso_serial#=227 2025-07-18 08:17:01.371688 :B3924DFF:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=16156 osp_idx=25 osp_ver=169300 osp_pg=0 (spawn #397261) 2025-07-18 08:17:01.371688 :B3924E00: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-18 08:17:01.371689 :B3924E01:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=40 time=1507397251 2025-07-18 08:17:01.371689 :B3924E02:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-18 08:17:01.371689 :B3924E03:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-18 08:17:01.371690 :B3924E04:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-18 08:17:01.371690 :B3924E05:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-18 08:17:01.371690 :B3924E06:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-18 08:17:01.372211 :B3924E07: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-18 08:17:01.372257 :B3924E0D: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-18 08:17:02.720030 :B392526E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-18 09:00:23.503947 :B395FEF9:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-18 09:00:23.503 2025-07-18 09:00:23.503949 :B395FEFA:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15536 pso_num=27 pso_serial#=228 2025-07-18 09:00:23.504085 :B395FEFB:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=15536 osp_idx=25 osp_ver=169301 osp_pg=0 (spawn #397361) 2025-07-18 09:00:23.504086 :B395FEFC: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-18 09:00:23.504087 :B395FEFD:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=41 time=1509999392 2025-07-18 09:00:23.504087 :B395FEFE:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-18 09:00:23.504087 :B395FEFF:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-18 09:00:23.504088 :B395FF00:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-18 09:00:23.504088 :B395FF01:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-18 09:00:23.504088 :B395FF02:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-18 09:00:23.504606 :B395FF03: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-18 09:00:23.504663 :B395FF09: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-18 09:00:23.512153 :B395FF17: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-18 09:00:23.513217 :B395FF1F: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-18 09:00:23.513775 :B395FF27: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-18 09:00:23.514241 :B395FF2F: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-18 09:00:23.514678 :B395FF37: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-18 09:00:23.517202 :B395FF3F: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-18 09:00:23.518312 :B395FF47: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-18 09:00:23.589342 :B395FF5A: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-18 09:00:23.590220 :B395FF62: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-18 09:00:23.590469 :B395FF6A: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-18 09:00:23.590679 :B395FF72: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-18 09:00:23.592629 :B395FF7A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-18 10:00:31.645156 :B39ADBBF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-18 10:00:31.639 2025-07-18 10:00:31.645158 :B39ADBC0:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14600 pso_num=27 pso_serial#=229 2025-07-18 10:00:31.645288 :B39ADBC1:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=14600 osp_idx=25 osp_ver=169302 osp_pg=0 (spawn #397500) 2025-07-18 10:00:31.645288 :B39ADBC2: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-18 10:00:31.645289 :B39ADBC3:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=42 time=1513607517 2025-07-18 10:00:31.645289 :B39ADBC4:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-18 10:00:31.645290 :B39ADBC5:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-18 10:00:31.645290 :B39ADBC6:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-18 10:00:31.645290 :B39ADBC7:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-18 10:00:31.645291 :B39ADBC8:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-18 10:00:31.645781 :B39ADBC9: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-18 10:00:31.645825 :B39ADBCF: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-18 10:00:31.653523 :B39ADBDD: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-18 10:00:31.654616 :B39ADBE5: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-18 10:00:31.655258 :B39ADBED: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-18 10:00:31.655737 :B39ADBF5: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-18 10:00:31.656175 :B39ADBFD: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-18 10:00:31.658676 :B39ADC05: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-18 10:00:31.659858 :B39ADC0D: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-18 10:00:31.710493 :B39ADC15: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-18 10:00:31.711395 :B39ADC1D: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-18 10:00:31.711640 :B39ADC25: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-18 10:00:31.711871 :B39ADC2D: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-18 10:00:31.713919 :B39ADC35:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-18 16:00:14.353608 :B3B7BEAC:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-18 16:00:14.344 2025-07-18 16:00:14.353610 :B3B7BEAD:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15540 pso_num=27 pso_serial#=230 2025-07-18 16:00:14.353752 :B3B7BEAE:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15540 (legacy spawn) 2025-07-18 16:00:14.360312 :B3B7BEAF: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-18 16:00:14.360472 :B3B7BEB8: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-18 16:00:14.361257 :B3B7BEB9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-18 16:00:17.646304 :B3B7C00B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-18 16:00:17.641 2025-07-18 16:00:17.646306 :B3B7C00C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18484 pso_num=27 pso_serial#=231 2025-07-18 16:00:17.646446 :B3B7C00D:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=18484 osp_idx=25 osp_ver=169304 osp_pg=0 (spawn #398329) 2025-07-18 16:00:17.646447 :B3B7C00E: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-18 16:00:17.646447 :B3B7C00F:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=43 time=1535193548 2025-07-18 16:00:17.646448 :B3B7C010:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-18 16:00:17.646448 :B3B7C011:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-18 16:00:17.646449 :B3B7C012:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-18 16:00:17.646449 :B3B7C013:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-18 16:00:17.646449 :B3B7C014:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-18 16:00:17.646951 :B3B7C015: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-18 16:00:17.647003 :B3B7C01B: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-18 16:00:17.651380 :B3B7C02E: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-18 16:00:17.677194 :B3B7C036:db_trace:ksb.c@6610:ksbcic_int(): [10254:27:219] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 4286493642 8473656) 2025-07-18 16:00:17.677201 :B3B7C037: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-18 16:00:17.677253 :B3B7C03F: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-18 16:00:17.677298 :B3B7C04F: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-18 16:00:17.692396 :B3B7C057: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-18 16:00:17.722424 :B3B7C05F: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-18 16:00:17.725988 :B3B7C067: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-18 16:00:17.729714 :B3B7C06F: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-18 16:00:17.733971 :B3B7C077: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-18 16:00:17.735064 :B3B7C07F: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-18 16:00:18.990582 :B3B7C0D2: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-18 16:00:19.029801 :B3B7C0DA: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-18 16:00:19.030119 :B3B7C0E2: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-18 16:00:19.030400 :B3B7C0EA: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-18 16:00:19.030416 :B3B7C0ED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 16:00:19.030478 :B3B7C0FA: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-18 16:00:19.033760 :B3B7C107:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-18 16:00:19.905716 :B3B7C1DF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-18 16:00:19.891 2025-07-18 16:00:19.905718 :B3B7C1E0:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19264 pso_num=27 pso_serial#=232 2025-07-18 16:00:19.905888 :B3B7C1E1:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=19264 (legacy spawn) 2025-07-18 16:00:19.912272 :B3B7C1E2: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-18 16:00:19.912442 :B3B7C1EA: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-18 16:00:19.913292 :B3B7C1EB:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-18 16:00:19.922013 :B3B7C1EE:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-18 16:00:19.907 2025-07-18 16:00:19.922015 :B3B7C1EF:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18896 pso_num=27 pso_serial#=233 2025-07-18 16:00:19.922151 :B3B7C1F0:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18896 (legacy spawn) 2025-07-18 16:00:20.288404 :B3B7C20D: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-18 16:00:20.288593 :B3B7C216: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-18 16:00:20.289528 :B3B7C217:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-18 16:00:20.745236 :B3B7C26C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-18 16:00:20.737 2025-07-18 16:00:20.745237 :B3B7C26D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16544 pso_num=27 pso_serial#=234 2025-07-18 16:00:20.745376 :B3B7C26E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16544 (legacy spawn) 2025-07-18 16:00:20.751872 :B3B7C26F: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-18 16:00:20.752036 :B3B7C277: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-18 16:00:20.752825 :B3B7C279:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-18 16:00:23.406678 :B3B7C32B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-18 16:00:23.393 2025-07-18 16:00:23.406680 :B3B7C32C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=6572 pso_num=27 pso_serial#=235 2025-07-18 16:00:23.406850 :B3B7C32D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=6572 (legacy spawn) 2025-07-18 16:00:23.413240 :B3B7C32E: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-18 16:00:23.413413 :B3B7C337: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-18 16:00:23.414278 :B3B7C338:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-18 16:00:23.422918 :B3B7C33B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-18 16:00:23.409 2025-07-18 16:00:23.422920 :B3B7C33C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=7000 pso_num=27 pso_serial#=236 2025-07-18 16:00:23.423061 :B3B7C33D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=7000 (legacy spawn) 2025-07-18 16:00:23.429607 :B3B7C33E: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-18 16:00:23.429783 :B3B7C347: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-18 16:00:23.430581 :B3B7C348:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-18 22:00:00.551834 :B3D46A92:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-18 22:00:00.550 2025-07-18 22:00:00.551837 :B3D46A93:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18756 pso_num=27 pso_serial#=237 2025-07-18 22:00:00.551973 :B3D46A94:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=18756 osp_idx=25 osp_ver=169310 osp_pg=0 (spawn #399160) 2025-07-18 22:00:00.551974 :B3D46A95: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-18 22:00:00.551974 :B3D46A96:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=44 time=1556776439 2025-07-18 22:00:00.551975 :B3D46A97:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-18 22:00:00.551975 :B3D46A98:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-18 22:00:00.551976 :B3D46A99:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-18 22:00:00.551976 :B3D46A9A:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-18 22:00:00.551977 :B3D46A9B:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-18 22:00:00.552489 :B3D46AA5: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-18 22:00:00.557433 :B3D46ACA: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-18 22:00:00.557465 :B3D46ACD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:jslv.c:9203 2025-07-18 22:00:00.562178 :B3D46AFA:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-07-18 22:00:00.562781 :B3D46AFB:db_trace:kcc.c@13684:kccwbp(): [10021:27:219:1] 0115000000000000 0115000000000000 944b120000000000 2025-07-18 22:00:00.564442 :B3D46B2C: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-18 22:00:00.564521 :B3D46B39: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-18 22:00:00.564531 :B3D46B3F: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-18 22:00:00.564536 :B3D46B40: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-18 22:00:00.564565 :B3D46B4C: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-18 22:00:00.565391 :B3D46B60:db_trace:ksb.c@6610:ksbcic_int(): [10254:27:219] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (4 0 231766423) 2025-07-18 22:00:00.565395 :B3D46B61: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-18 22:00:00.565419 :B3D46B6C: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-18 22:00:00.565427 :B3D46B79: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-18 22:00:00.565430 :B3D46B7A: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-18 22:00:00.565455 :B3D46B87: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-18 22:00:00.565458 :B3D46B92: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-18 22:00:00.565460 :B3D46B93: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-18 22:00:00.565511 :B3D46BB3: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-18 22:00:00.566091 :B3D46C33: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-18 22:00:00.566114 :B3D46C40:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-07-18 22:00:00.567006 :B3D46C47:db_trace:kcc.c@13684:kccwbp(): [10021:27:219:1] 0115000000000000 0115000000000000 954b120000000000 2025-07-18 22:00:00.567378 :B3D46C48:db_trace:ksb.c@6610:ksbcic_int(): [10254:27:219] KSBCIC: (USER) starting cic on opcode KSBCIBBS [20] with args (2 0 231766423) 2025-07-18 22:00:00.567384 :B3D46C49: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-18 22:00:00.567409 :B3D46C54: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-18 22:00:00.575981 :B3D46CB7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:jslv.c:9203 2025-07-18 22:00:00.576699 :B3D46CB8: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-18 22:00:00.578143 :B3D46CC0: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-18 22:00:00.578193 :B3D46CC3: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-18 22:00:00.578278 :B3D46CD0: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-18 22:00:00.676305 :B3D46DD9: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: 18756, J002)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de