Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250518060008\orcl_w000_7552_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:37375M/63366M, Ph+PgF:44536M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 7552, image: ORACLE.EXE (W000) *** 2025-05-18 06:00:08.582 *** SESSION ID:(177.23523) 2025-05-18 06:00:08.582 *** 2025-05-18 06:00:08.582 Process diagnostic dump for ORACLE.EXE (W000), OS id=7552, pid: 22, proc_ser: 127, sid: 177, sess_ser: 23523 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=4.437855 sec, exc=4.437855 sec, total=4.437855 sec wait times: max=5.000000 sec, heur=1 min 14 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.000027 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=5.002797 sec, exc=5.002797 sec, total=5.002797 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=5.003151 sec, exc=5.003151 sec, total=5.003151 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=5.005186 sec, exc=5.005186 sec, total=5.005186 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=5.004626 sec, exc=5.004626 sec, total=5.004626 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=5.009174 sec, exc=5.009174 sec, total=5.009174 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000035 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=5.005115 sec, exc=5.005115 sec, total=5.005115 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000039 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=5.003315 sec, exc=5.003315 sec, total=5.003315 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000039 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=5.012687 sec, exc=5.012687 sec, total=5.012687 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000060 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=5.011457 sec, exc=5.011457 sec, total=5.011457 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000030 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=5.004709 sec, exc=5.004709 sec, total=5.004709 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time Sampled Session History of session 177 serial 23523 --------------------------------------------------- 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 --------------------------------------------------- [75 samples, 05:58:54 - 06:00:08] idle wait at each sample [session created at: 05:58:54] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-18 06:00:08.582 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 7552, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-18 04:37:13.349069*:98961BE9:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000000FCCBB58 2025-05-18 04:37:13.349069*:98961BEA:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000000FCCBB58 curCtx=0x000000000FC8F300 xsc->flg4=65792 flags=2 2025-05-18 04:37:13.349069*:98961BEB:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000000FCCBB58 2025-05-18 04:37:13.349556 :98961BEC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 04:37:13.356907 :98961BF4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-18 04:37:13.357283 :98961BF5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 04:37:13.357324 :98961BF8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-18 04:37:13.358153 :98961BFE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 04:37:13.358271 :98961C07:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-18 04:37:43.408494 :989625F0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-18 04:37:54.159979 :9896298A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-18 04:37:54.157 2025-05-18 04:37:54.159981 :9896298B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13392 pso_num=22 pso_serial#=113 2025-05-18 04:37:54.160124 :9896298C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13392 (legacy spawn) 2025-05-18 04:37:54.166464 :9896298D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 04:37:54.166631 :98962996:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-18 04:37:54.167429 :98962997:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-18 04:37:54.176074 :9896299A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-18 04:37:54.173 2025-05-18 04:37:54.176076 :9896299B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10948 pso_num=22 pso_serial#=114 2025-05-18 04:37:54.176213 :9896299C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10948 (legacy spawn) 2025-05-18 04:37:54.182588 :9896299D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 04:37:54.182756 :989629A6:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-18 04:37:54.183562 :989629A7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-18 04:37:56.622915 :98962A68:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-18 04:37:56.615 2025-05-18 04:37:56.622917 :98962A69:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12740 pso_num=22 pso_serial#=115 2025-05-18 04:37:56.623051 :98962A6A:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12740 (legacy spawn) 2025-05-18 04:37:56.629583 :98962A6B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 04:37:56.629769 :98962A71:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-18 04:37:56.630590 :98962A75:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-18 04:37:57.315318 :98962AA0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-18 04:37:57.302 2025-05-18 04:37:57.315320 :98962AA1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11212 pso_num=22 pso_serial#=116 2025-05-18 04:37:57.315454 :98962AA2:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11212 (legacy spawn) 2025-05-18 04:37:57.321875 :98962AA3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 04:37:57.322050 :98962AA8:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-18 04:37:57.322859 :98962AAD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-18 04:37:57.331488 :98962AB0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-18 04:37:57.318 2025-05-18 04:37:57.331489 :98962AB1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=2168 pso_num=22 pso_serial#=117 2025-05-18 04:37:57.331619 :98962AB2:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=2168 (legacy spawn) 2025-05-18 04:37:57.338047 :98962AB3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 04:37:57.338213 :98962ABC:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-18 04:37:57.339032 :98962ABD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-18 04:38:00.046177 :98962BB9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-18 04:38:00.037 2025-05-18 04:38:00.046179 :98962BBA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7240 pso_num=22 pso_serial#=118 2025-05-18 04:38:00.046320 :98962BBB:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=7240 (legacy spawn) 2025-05-18 04:38:00.070302 :98962BBC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 04:38:00.070496 :98962BC1:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-18 04:38:00.071336 :98962BC6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-18 04:38:13.429798 :98962FD6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-18 04:38:13.424 2025-05-18 04:38:13.429800 :98962FD7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12748 pso_num=22 pso_serial#=119 2025-05-18 04:38:13.429934 :98962FD8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12748 osp_idx=0 osp_ver=207179 osp_pg=0 (spawn #193424) 2025-05-18 04:38:13.429935 :98962FD9:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-18 04:38:13.429935 :98962FDA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=88 time=518903297 2025-05-18 04:38:13.429936 :98962FDB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-18 04:38:13.429936 :98962FDC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-18 04:38:13.429937 :98962FDD:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-18 04:38:13.429937 :98962FDE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-18 04:38:13.429937 :98962FDF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-18 04:38:13.430421 :98962FE0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-18 04:38:13.431053 :98962FF5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 04:38:13.445362 :98963011:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-18 04:38:13.445447 :98963012:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-18 04:38:13.446228 :98963013:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-18 04:38:13.446560 :98963014:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 04:38:13.446605 :98963017:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-18 04:38:13.454497 :9896301D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 04:38:13.454569 :98963020:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:ktcc.c:879 2025-05-18 04:38:13.454608 :98963021:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:ktcc.c:879 2025-05-18 04:38:13.454955 :98963027:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-18 04:38:13.455310 :98963028:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-18 04:38:13.455552 :98963029:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-18 04:38:13.455821 :9896302A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 04:38:13.455847 :9896302D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-18 04:38:13.457446 :98963033:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-18 04:38:13.457658 :98963034:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 04:38:13.457686 :98963037:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-18 04:38:13.458583 :9896303D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 04:38:13.458698 :98963046:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-18 04:38:43.562518 :98963A3C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-18 04:38:43.629588 :98963A68:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-18 04:38:43.625 2025-05-18 04:38:43.629590 :98963A69:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12676 pso_num=22 pso_serial#=120 2025-05-18 04:38:43.629721 :98963A6A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12676 osp_idx=0 osp_ver=207180 osp_pg=0 (spawn #193426) 2025-05-18 04:38:43.629722 :98963A6B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-18 04:38:43.629722 :98963A6C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=89 time=518933500 2025-05-18 04:38:43.629723 :98963A6D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-18 04:38:43.629723 :98963A6E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-18 04:38:43.629724 :98963A6F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-18 04:38:43.629724 :98963A70:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-18 04:38:43.629724 :98963A71:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-18 04:38:43.630217 :98963A72:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-18 04:38:43.630262 :98963A7A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-18 04:48:44.665180 :989706D6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-18 04:48:45.030249 :9897072A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-18 04:48:45.024 2025-05-18 04:48:45.030251 :9897072B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10960 pso_num=22 pso_serial#=121 2025-05-18 04:48:45.030394 :9897072C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10960 osp_idx=0 osp_ver=207181 osp_pg=0 (spawn #193448) 2025-05-18 04:48:45.030395 :9897072D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-18 04:48:45.030395 :9897072E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=90 time=519534907 2025-05-18 04:48:45.030395 :9897072F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-18 04:48:45.030396 :98970730:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-18 04:48:45.030396 :98970731:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-18 04:48:45.030397 :98970732:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-18 04:48:45.030397 :98970733:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-18 04:48:45.030953 :98970734:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-18 04:48:45.031020 :9897073E:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-18 04:58:46.068312 :9897D416:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-18 04:58:46.385624 :9897D443:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-18 04:58:46.380 2025-05-18 04:58:46.385627 :9897D444:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12744 pso_num=22 pso_serial#=122 2025-05-18 04:58:46.385773 :9897D445:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12744 osp_idx=0 osp_ver=207182 osp_pg=0 (spawn #193471) 2025-05-18 04:58:46.385773 :9897D446:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-18 04:58:46.385774 :9897D447:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=91 time=520136250 2025-05-18 04:58:46.385775 :9897D448:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-18 04:58:46.385775 :9897D449:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-18 04:58:46.385775 :9897D44A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-18 04:58:46.385776 :9897D44B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-18 04:58:46.385776 :9897D44C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-18 04:58:46.386280 :9897D44D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-18 04:58:46.386328 :9897D457:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-18 05:08:47.371878 :9898A8F9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-18 05:08:47.716727 :9898A94A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-18 05:08:47.712 2025-05-18 05:08:47.716729 :9898A94B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6572 pso_num=22 pso_serial#=123 2025-05-18 05:08:47.716874 :9898A94C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=6572 osp_idx=0 osp_ver=207183 osp_pg=0 (spawn #193497) 2025-05-18 05:08:47.716874 :9898A94D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-18 05:08:47.716875 :9898A94E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=92 time=520737594 2025-05-18 05:08:47.716875 :9898A94F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-18 05:08:47.716875 :9898A950:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-18 05:08:47.716876 :9898A951:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-18 05:08:47.716876 :9898A952:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-18 05:08:47.716877 :9898A953:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-18 05:08:47.717373 :9898A954:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-18 05:08:47.717431 :9898A95B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-18 05:18:48.657566 :98997821:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-18 05:18:48.943550 :98997853:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-18 05:18:48.938 2025-05-18 05:18:48.943552 :98997854:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10828 pso_num=22 pso_serial#=124 2025-05-18 05:18:48.943689 :98997855:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10828 osp_idx=0 osp_ver=207184 osp_pg=0 (spawn #193519) 2025-05-18 05:18:48.943690 :98997856:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-18 05:18:48.943690 :98997857:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=93 time=521338813 2025-05-18 05:18:48.943690 :98997858:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-18 05:18:48.943691 :98997859:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-18 05:18:48.943691 :9899785A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-18 05:18:48.943692 :9899785B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-18 05:18:48.943692 :9899785C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-18 05:18:48.944209 :9899785D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-18 05:18:48.944252 :98997865:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-18 05:28:49.901330 :989A449E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-18 05:28:50.202808 :989A44CA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-18 05:28:50.198 2025-05-18 05:28:50.202810 :989A44CB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11948 pso_num=22 pso_serial#=125 2025-05-18 05:28:50.202945 :989A44CC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11948 osp_idx=0 osp_ver=207185 osp_pg=0 (spawn #193542) 2025-05-18 05:28:50.202946 :989A44CD:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-18 05:28:50.202946 :989A44CE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=94 time=521940079 2025-05-18 05:28:50.202947 :989A44CF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-18 05:28:50.202947 :989A44D0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-18 05:28:50.202948 :989A44D1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-18 05:28:50.202948 :989A44D2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-18 05:28:50.202949 :989A44D3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-18 05:28:50.203446 :989A44D4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-18 05:28:50.203492 :989A44DB:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-18 05:30:23.401191 :989A658A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-18 05:38:51.529555 :989B1167:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-18 05:48:52.543768 :989BDE7A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-18 05:48:52.798007 :989BDEA7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-18 05:48:52.793 2025-05-18 05:48:52.798009 :989BDEA8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=2752 pso_num=22 pso_serial#=126 2025-05-18 05:48:52.798173 :989BDEA9:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=2752 osp_idx=0 osp_ver=207186 osp_pg=0 (spawn #193586) 2025-05-18 05:48:52.798174 :989BDEAA:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-18 05:48:52.798174 :989BDEAB:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=95 time=523142672 2025-05-18 05:48:52.798175 :989BDEAC:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-18 05:48:52.798175 :989BDEAD:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-18 05:48:52.798176 :989BDEAE:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-18 05:48:52.798176 :989BDEAF:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-18 05:48:52.798177 :989BDEB0:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-18 05:48:52.798672 :989BDEB1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-18 05:48:52.798717 :989BDEB9:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-18 05:58:53.710765 :989CAB33:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-18 05:58:54.059979 :989CABAD:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-18 05:58:54.055 2025-05-18 05:58:54.059983 :989CABAE:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7552 pso_num=22 pso_serial#=127 2025-05-18 05:58:54.060123 :989CABAF:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=7552 osp_idx=0 osp_ver=207187 osp_pg=0 (spawn #193609) 2025-05-18 05:58:54.060123 :989CABB0:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-18 05:58:54.060124 :989CABB1:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=96 time=523743922 2025-05-18 05:58:54.060124 :989CABB2:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-18 05:58:54.060124 :989CABB3:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-18 05:58:54.060125 :989CABB4:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-18 05:58:54.060125 :989CABB5:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-18 05:58:54.060125 :989CABB6:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-18 05:58:54.060617 :989CABB7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-18 05:58:54.060668 :989CABBD:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 7552, W000)