Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250508220008\orcl_j005_10284_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:37507M/63366M, Ph+PgF:44876M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 31 Windows thread id: 10284, image: ORACLE.EXE (J005) *** 2025-05-08 22:00:08.146 *** SESSION ID:(249.605) 2025-05-08 22:00:08.146 *** 2025-05-08 22:00:08.146 Process diagnostic dump for ORACLE.EXE (J005), OS id=10284, pid: 31, proc_ser: 39, sid: 249, sess_ser: 605 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 10284 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J005) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.161730 sec, exc=0.161730 sec, total=0.161730 sec wait times: max=0.500000 sec, heur=7.181272 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.000012 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.499980 sec, exc=0.499980 sec, total=0.499980 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=12 seq_num=13 snap_id=1 wait times: snap=0.501119 sec, exc=0.501119 sec, total=0.501119 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 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.000011 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 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.000008 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500020 sec, exc=0.500020 sec, total=0.500020 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=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.000009 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500141 sec, exc=0.500141 sec, total=0.500141 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.500129 sec, exc=0.500129 sec, total=0.500129 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.500017 sec, exc=0.500017 sec, total=0.500017 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time Sampled Session History of session 249 serial 605 --------------------------------------------------- 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 --------------------------------------------------- [8 samples, 22:00:00 - 22:00:08] 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-05-08 22:00:08.146 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 31 (osid: 10284, J005) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-05 22:00:02.061252 :9330F134:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 22:00:02.061253 :9330F135:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 22:00:02.061253 :9330F136:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 22:00:02.061761 :9330F137: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-05 22:00:32.145855 :93310422:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-05 23:59:07.438100 :933A4C48:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-05 23:59:07.429 2025-05-05 23:59:07.438101 :933A4C49:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=10928 pso_num=31 pso_serial#=19 2025-05-05 23:59:07.438244 :933A4C4A:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=10928 (legacy spawn) 2025-05-05 23:59:07.444748 :933A4C4B: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-05 23:59:07.444919 :933A4C50: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-05 23:59:07.445733 :933A4C55:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-05 23:59:09.843266 :933A4D2A:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-05 23:59:09.833 2025-05-05 23:59:09.843268 :933A4D2B:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=10496 pso_num=31 pso_serial#=20 2025-05-05 23:59:09.843400 :933A4D2C:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=10496 (legacy spawn) 2025-05-05 23:59:09.857355 :933A4D2D: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-05 23:59:13.151908 :933A4E2A: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-05 23:59:13.152093 :933A4E33: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-05 23:59:13.153340 :933A4E34:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-05 23:59:13.399735 :933A4E64:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-05 23:59:13.393 2025-05-05 23:59:13.399737 :933A4E65:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=10712 pso_num=31 pso_serial#=21 2025-05-05 23:59:13.399877 :933A4E66:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=10712 (legacy spawn) 2025-05-05 23:59:13.413874 :933A4E67: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-05 23:59:46.584144 :933A5ADB:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-05 23:59:46.653689 :933A5AEA:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-05 23:59:46.643 2025-05-05 23:59:46.653691 :933A5AEB:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=8444 pso_num=31 pso_serial#=22 2025-05-05 23:59:46.653814 :933A5AEC:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=8444 (legacy spawn) 2025-05-05 23:59:46.672190 :933A5AED: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-05 23:59:49.957938 :933A5C12: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-05 23:59:49.958116 :933A5C17: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-05 23:59:49.959427 :933A5C1C:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-05 23:59:50.213856 :933A5C2F:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-05 23:59:50.206 2025-05-05 23:59:50.213859 :933A5C30:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11644 pso_num=31 pso_serial#=23 2025-05-05 23:59:50.214004 :933A5C31:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=11644 (legacy spawn) 2025-05-05 23:59:50.228254 :933A5C32: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 00:00:23.411247 :933A68FA:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-06 00:01:13.117667 :933A7E42:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-06 00:01:13.112 2025-05-06 00:01:13.117669 :933A7E43:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=1164 pso_num=31 pso_serial#=24 2025-05-06 00:01:13.117816 :933A7E44:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=1164 osp_idx=29 osp_ver=278 osp_pg=0 (spawn #152415) 2025-05-06 00:01:13.117817 :933A7E45: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:01:13.117817 :933A7E46:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=98 time=3760450281 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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 31 (osid: 10284, J005)