Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250509220008\orcl_j005_2752_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:37482M/63366M, Ph+PgF:44850M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 31 Windows thread id: 2752, image: ORACLE.EXE (J005) *** 2025-05-09 22:00:08.759 *** SESSION ID:(249.615) 2025-05-09 22:00:08.759 *** 2025-05-09 22:00:08.759 Process diagnostic dump for ORACLE.EXE (J005), OS id=2752, pid: 31, proc_ser: 44, sid: 249, sess_ser: 615 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 2752 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J005) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.160442 sec, exc=0.160442 sec, total=0.160442 sec wait times: max=0.500000 sec, heur=6.681981 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.000067 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.499977 sec, exc=0.499977 sec, total=0.499977 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.500015 sec, exc=0.500015 sec, total=0.500015 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.499972 sec, exc=0.499972 sec, total=0.499972 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.510093 sec, exc=0.510093 sec, total=0.510093 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000017 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500002 sec, exc=0.500002 sec, total=0.500002 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000018 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 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.000011 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499960 sec, exc=0.499960 sec, total=0.499960 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.510136 sec, exc=0.510136 sec, total=0.510136 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.504605 sec, exc=0.504605 sec, total=0.504605 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time Sampled Session History of session 249 serial 615 --------------------------------------------------- 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 --------------------------------------------------- [7 samples, 22:00:02 - 22:00:08] 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-09 22:00:08.759 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 31 (osid: 2752, J005) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-06 00:01:13.117818 :933A7E47:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 00:01:13.117818 :933A7E48:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 00:01:13.117819 :933A7E49:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 00:01:13.117819 :933A7E4A:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 00:01:13.117819 :933A7E4B:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 00:01:13.118365 :933A7E4C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 00:01:13.118405 :933A7E54:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-06 00:01:13.124962 :933A7E60:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-06 00:06:04.834492 :933AE4A0:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-06 00:06:04.822 2025-05-06 00:06:04.834494 :933AE4A1:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11372 pso_num=31 pso_serial#=25 2025-05-06 00:06:04.834632 :933AE4A2:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=11372 osp_idx=29 osp_ver=279 osp_pg=0 (spawn #152427) 2025-05-06 00:06:04.834632 :933AE4A3:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 00:06:04.834633 :933AE4A4:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=99 time=3760742000 2025-05-06 00:06:04.834633 :933AE4A5:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 00:06:04.834633 :933AE4A6:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 00:06:04.834634 :933AE4A7:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 00:06:04.834634 :933AE4A8:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 00:06:04.834634 :933AE4A9:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 00:06:04.835127 :933AE4AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 00:06:04.835168 :933AE4B0:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-06 00:06:04.839395 :933AE4D9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:kelt.c:517 2025-05-06 00:06:04.839438 :933AE4DA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:kelt.c:517 2025-05-06 00:06:04.839684 :933AE4DB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:kelt.c:517 2025-05-06 00:06:04.839700 :933AE4DC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:kelt.c:517 2025-05-06 00:06:04.839909 :933AE4DD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:kelt.c:517 2025-05-06 00:06:04.839925 :933AE4DE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:kelt.c:517 2025-05-06 00:06:04.840133 :933AE4DF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:kelt.c:517 2025-05-06 00:06:04.840146 :933AE4E0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:kelt.c:517 2025-05-06 00:06:04.840345 :933AE4E1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:kelt.c:517 2025-05-06 00:06:04.840357 :933AE4E2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:kelt.c:517 2025-05-06 00:06:04.840628 :933AE4E3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:kelt.c:517 2025-05-06 00:06:04.840647 :933AE4E4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:kelt.c:517 2025-05-06 00:06:04.843031 :933AE4E5:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-06 22:00:02.119272 :93A09A1B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-06 22:00:02.111 2025-05-06 22:00:02.119273 :93A09A1C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11480 pso_num=31 pso_serial#=26 2025-05-06 22:00:02.119414 :93A09A1D:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=11480 osp_idx=29 osp_ver=280 osp_pg=0 (spawn #155511) 2025-05-06 22:00:02.119415 :93A09A1E:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 22:00:02.119415 :93A09A1F:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=100 time=3839579281 2025-05-06 22:00:02.119416 :93A09A20:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 22:00:02.119416 :93A09A21:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 22:00:02.119417 :93A09A22:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 22:00:02.119417 :93A09A23:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 22:00:02.119417 :93A09A24:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 22:00:02.119968 :93A09A26:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 22:00:32.183037 :93A0ACBF:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-06 23:59:08.928020 :93A9D487:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-06 23:59:08.913 2025-05-06 23:59:08.928022 :93A9D488:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12016 pso_num=31 pso_serial#=27 2025-05-06 23:59:08.928153 :93A9D489:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=12016 (legacy spawn) 2025-05-06 23:59:08.934761 :93A9D48A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 23:59:08.934937 :93A9D493:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-06 23:59:08.935774 :93A9D494:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-06 23:59:08.944799 :93A9D497:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-06 23:59:08.944 2025-05-06 23:59:08.944801 :93A9D498:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=9092 pso_num=31 pso_serial#=28 2025-05-06 23:59:08.944940 :93A9D499:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=9092 (legacy spawn) 2025-05-06 23:59:08.951577 :93A9D49A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 23:59:08.951753 :93A9D4A2:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-06 23:59:08.952629 :93A9D4A4:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-06 23:59:10.044402 :93A9D52C:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-06 23:59:10.038 2025-05-06 23:59:10.044404 :93A9D52D:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=10188 pso_num=31 pso_serial#=29 2025-05-06 23:59:10.044548 :93A9D52E:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=10188 (legacy spawn) 2025-05-06 23:59:10.058563 :93A9D52F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 23:59:13.304444 :93A9D64B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 23:59:13.304647 :93A9D650:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-06 23:59:13.305804 :93A9D655:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-06 23:59:13.554879 :93A9D658:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-06 23:59:13.550 2025-05-06 23:59:13.554881 :93A9D659:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11776 pso_num=31 pso_serial#=30 2025-05-06 23:59:13.555027 :93A9D65A:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=11776 (legacy spawn) 2025-05-06 23:59:13.569285 :93A9D65B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 23:59:46.729026 :93A9E217:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-06 23:59:48.330484 :93A9E2E2:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-06 23:59:48.317 2025-05-06 23:59:48.330487 :93A9E2E3:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=4728 pso_num=31 pso_serial#=31 2025-05-06 23:59:48.330663 :93A9E2E4:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=4728 (legacy spawn) 2025-05-06 23:59:48.337188 :93A9E2E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 23:59:48.337369 :93A9E2EA:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-06 23:59:48.338185 :93A9E2EF:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-06 23:59:48.346935 :93A9E2F2:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-06 23:59:48.333 2025-05-06 23:59:48.346937 :93A9E2F3:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11736 pso_num=31 pso_serial#=32 2025-05-06 23:59:48.347073 :93A9E2F4:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=11736 (legacy spawn) 2025-05-06 23:59:48.353540 :93A9E2F5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 23:59:48.353700 :93A9E2FE:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-06 23:59:48.354500 :93A9E2FF:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-06 23:59:50.910707 :93A9E3C3:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-06 23:59:50.911 2025-05-06 23:59:50.910710 :93A9E3C4:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=8780 pso_num=31 pso_serial#=33 2025-05-06 23:59:50.910857 :93A9E3C5:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=8780 (legacy spawn) 2025-05-06 23:59:50.924718 :93A9E3C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 23:59:54.217721 :93A9E4FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 23:59:54.217895 :93A9E507:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-06 23:59:54.219125 :93A9E508:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-06 23:59:54.468635 :93A9E50B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-06 23:59:54.455 2025-05-06 23:59:54.468638 :93A9E50C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11548 pso_num=31 pso_serial#=34 2025-05-06 23:59:54.468773 :93A9E50D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=11548 (legacy spawn) 2025-05-06 23:59:54.482735 :93A9E50E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-07 00:00:27.647135 :93A9F480:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-07 22:00:02.078929 :9411EDCC:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-07 22:00:02.076 2025-05-07 22:00:02.078930 :9411EDCD:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=10684 pso_num=31 pso_serial#=35 2025-05-07 22:00:02.079062 :9411EDCE:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=10684 osp_idx=29 osp_ver=289 osp_pg=0 (spawn #158897) 2025-05-07 22:00:02.079063 :9411EDCF:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-07 22:00:02.079064 :9411EDD0:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=101 time=3925979234 2025-05-07 22:00:02.079065 :9411EDD1:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-07 22:00:02.079065 :9411EDD2:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-07 22:00:02.079066 :9411EDD3:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-07 22:00:02.079067 :9411EDD4:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-07 22:00:02.079068 :9411EDD5:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-07 22:00:02.079658 :9411EDE7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-07 22:00:32.173013 :94120249:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-07 23:59:13.678837 :941B75D0:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-07 23:59:13.667 2025-05-07 23:59:13.678840 :941B75D1:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=8868 pso_num=31 pso_serial#=36 2025-05-07 23:59:13.678956 :941B75D2:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=8868 osp_idx=29 osp_ver=290 osp_pg=0 (spawn #159178) 2025-05-07 23:59:13.678957 :941B75D3:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-07 23:59:13.678957 :941B75D4:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=102 time=3933130843 2025-05-07 23:59:13.678958 :941B75D5:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-07 23:59:13.678958 :941B75D6:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-07 23:59:13.678958 :941B75D7:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-07 23:59:13.678959 :941B75D8:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-07 23:59:13.678960 :941B75D9:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-07 23:59:13.679466 :941B75DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-07 23:59:43.677955 :941B815E:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-07 23:59:54.846601 :941B854B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-07 23:59:54.837 2025-05-07 23:59:54.846603 :941B854C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=13232 pso_num=31 pso_serial#=37 2025-05-07 23:59:54.846740 :941B854D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=13232 (legacy spawn) 2025-05-07 23:59:54.860858 :941B854E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-07 23:59:58.218088 :941B8641:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-07 23:59:58.218265 :941B8646:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-07 23:59:58.219403 :941B864B:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-07 23:59:58.477950 :941B864F:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-07 23:59:58.466 2025-05-07 23:59:58.477952 :941B8650:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=7708 pso_num=31 pso_serial#=38 2025-05-07 23:59:58.478089 :941B8651:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=7708 (legacy spawn) 2025-05-07 23:59:58.492698 :941B8652:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-08 00:06:18.452686 :941C10E7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-08 00:06:18.452856 :941C10F0:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-08 00:06:18.454092 :941C10F1:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-08 22:00:00.969021 :9482E6F4:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-08 22:00:00.966 2025-05-08 22:00:00.969022 :9482E6F5:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=10284 pso_num=31 pso_serial#=39 2025-05-08 22:00:00.969159 :9482E6F6:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=10284 osp_idx=29 osp_ver=293 osp_pg=0 (spawn #162282) 2025-05-08 22:00:00.969160 :9482E6F7:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 22:00:00.969160 :9482E6F8:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=103 time=4012378125 2025-05-08 22:00:00.969161 :9482E6F9:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 22:00:00.969162 :9482E6FA:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 22:00:00.969163 :9482E6FB:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 22:00:00.969164 :9482E6FC:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 22:00:00.969164 :9482E6FD:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 22:00:00.970015 :9482E6FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-08 22:00:31.073609 :9482FADE:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-08 23:59:16.045936 :948C687F:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-08 23:59:16.038 2025-05-08 23:59:16.045938 :948C6880:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=2132 pso_num=31 pso_serial#=40 2025-05-08 23:59:16.046079 :948C6881:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=2132 (legacy spawn) 2025-05-08 23:59:16.052512 :948C6882:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-08 23:59:16.052696 :948C688B:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-08 23:59:16.053494 :948C688C:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-08 23:59:18.042755 :948C6978:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-08 23:59:18.040 2025-05-08 23:59:18.042758 :948C6979:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11368 pso_num=31 pso_serial#=41 2025-05-08 23:59:18.042899 :948C697A:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=11368 (legacy spawn) 2025-05-08 23:59:18.057120 :948C697B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-08 23:59:51.316925 :948C75A4:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-08 23:59:54.866595 :948C76BF:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-08 23:59:54.858 2025-05-08 23:59:54.866597 :948C76C0:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=9648 pso_num=31 pso_serial#=42 2025-05-08 23:59:54.866734 :948C76C1:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=9648 (legacy spawn) 2025-05-08 23:59:54.880468 :948C76C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-09 00:00:28.030464 :948C84B5:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-09 00:00:38.167729 :948C88D1:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-09 00:00:38.164 2025-05-09 00:00:38.167730 :948C88D2:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11864 pso_num=31 pso_serial#=43 2025-05-09 00:00:38.167855 :948C88D3:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=11864 osp_idx=29 osp_ver=297 osp_pg=0 (spawn #162568) 2025-05-09 00:00:38.167855 :948C88D4:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 00:00:38.167856 :948C88D5:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=104 time=4019615312 2025-05-09 00:00:38.167856 :948C88D6:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 00:00:38.167857 :948C88D7:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 00:00:38.167858 :948C88D8:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 00:00:38.167858 :948C88D9:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 00:00:38.167859 :948C88DA:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 00:00:38.168363 :948C88DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-09 00:00:38.168402 :948C88E1:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-09 00:00:38.176707 :948C88F6:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-09 22:00:02.080454 :94F34206:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-09 22:00:02.078 2025-05-09 22:00:02.080455 :94F34207:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=2752 pso_num=31 pso_serial#=44 2025-05-09 22:00:02.080575 :94F34208:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=2752 osp_idx=29 osp_ver=298 osp_pg=0 (spawn #165668) 2025-05-09 22:00:02.080575 :94F34209:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-09 22:00:02.080576 :94F3420A:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=105 time=4098779234 2025-05-09 22:00:02.080577 :94F3420B:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-09 22:00:02.080577 :94F3420C:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-09 22:00:02.080578 :94F3420D:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-09 22:00:02.080579 :94F3420E:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-09 22:00:02.080579 :94F3420F:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-09 22:00:02.081101 :94F34221:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 31 (osid: 2752, J005)