Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250517220455\orcl_m002_13944_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:37360M/63366M, Ph+PgF:44533M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 13944, image: ORACLE.EXE (M002) *** 2025-05-17 22:04:55.843 *** SESSION ID:(233.22341) 2025-05-17 22:04:55.843 *** 2025-05-17 22:04:55.843 Process diagnostic dump for ORACLE.EXE (M002), OS id=13944, pid: 29, proc_ser: 179, sid: 233, sess_ser: 22341 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'db file sequential read' file#=0x2, block#=0x461a, blocks=0x1 wait_id=1619 seq_num=1620 snap_id=1 wait times: snap=0.004884 sec, exc=0.004884 sec, total=0.004884 sec wait times: max=infinite, heur=0.004884 sec wait counts: calls=0 os=0 in_wait=1 iflags=0x5a0 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000272 sec since current wait 0: waited for 'db file sequential read' file#=0x2, block#=0x467a, blocks=0x1 wait_id=1618 seq_num=1619 snap_id=1 wait times: snap=0.001480 sec, exc=0.001480 sec, total=0.001480 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000244 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0x4602, blocks=0x1 wait_id=1617 seq_num=1618 snap_id=1 wait times: snap=0.001755 sec, exc=0.001755 sec, total=0.001755 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000180 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x46ca, blocks=0x1 wait_id=1616 seq_num=1617 snap_id=1 wait times: snap=0.000082 sec, exc=0.000082 sec, total=0.000082 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000349 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0x455a, blocks=0x1 wait_id=1615 seq_num=1616 snap_id=1 wait times: snap=0.006002 sec, exc=0.006002 sec, total=0.006002 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000164 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x49f2, blocks=0x1 wait_id=1614 seq_num=1615 snap_id=1 wait times: snap=0.000050 sec, exc=0.000050 sec, total=0.000050 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000136 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x49e2, blocks=0x1 wait_id=1613 seq_num=1614 snap_id=1 wait times: snap=0.000046 sec, exc=0.000046 sec, total=0.000046 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000133 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x49d2, blocks=0x1 wait_id=1612 seq_num=1613 snap_id=1 wait times: snap=0.000048 sec, exc=0.000048 sec, total=0.000048 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000131 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x49c2, blocks=0x1 wait_id=1611 seq_num=1612 snap_id=1 wait times: snap=0.000050 sec, exc=0.000050 sec, total=0.000050 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000132 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0x49b2, blocks=0x1 wait_id=1610 seq_num=1611 snap_id=1 wait times: snap=0.000051 sec, exc=0.000051 sec, total=0.000051 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000138 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x499a, blocks=0x1 wait_id=1609 seq_num=1610 snap_id=1 wait times: snap=0.000407 sec, exc=0.000407 sec, total=0.000407 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000137 sec of elapsed time Sampled Session History of session 233 serial 22341 --------------------------------------------------- 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 --------------------------------------------------- [1 sample, 22:04:55] waited for 'ADR block file read', seq_num: 179 p1: ' '=0x0 p2: ' '=0x0 p3: ' '=0x0 time_waited: >= 0 sec (still in wait) [1 sample, 22:04:54] idle wait at each sample [session created at: 22:04:55] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'ADR block file read' [1 sample, 22:04:55 ] time_waited: >= 0 sec (still in wait) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-17 22:04:55.843 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 13944, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-17 09:16:49.974155 :98395502:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-17 09:16:49.974363 :98395503:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-05-17 09:16:49.974375 :98395504:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-05-17 09:16:49.976824 :98395505:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-17 11:32:37.209494 :9843F25D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-17 11:32:37.197 2025-05-17 11:32:37.209497 :9843F25E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12980 pso_num=29 pso_serial#=159 2025-05-17 11:32:37.209637 :9843F25F:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12980 (legacy spawn) 2025-05-17 11:32:37.230690 :9843F260:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 11:32:37.230857 :9843F269:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-17 11:32:37.231725 :9843F26A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-17 11:32:43.646641 :9843F450:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-17 11:32:43.637 2025-05-17 11:32:43.646642 :9843F451:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13992 pso_num=29 pso_serial#=160 2025-05-17 11:32:43.646777 :9843F452:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13992 (legacy spawn) 2025-05-17 11:32:43.653252 :9843F453:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 11:32:43.653410 :9843F45A:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-17 11:32:43.654208 :9843F45D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-17 11:32:52.226833 :9843F71F:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-17 11:32:52.213 2025-05-17 11:32:52.226835 :9843F720:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12636 pso_num=29 pso_serial#=161 2025-05-17 11:32:52.226970 :9843F721:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12636 (legacy spawn) 2025-05-17 11:32:52.233354 :9843F722:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 11:32:52.233538 :9843F72B:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-17 11:32:52.234342 :9843F72C:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-17 11:32:52.243003 :9843F72F:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-17 11:32:52.229 2025-05-17 11:32:52.243005 :9843F730:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13976 pso_num=29 pso_serial#=162 2025-05-17 11:32:52.243142 :9843F731:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13976 (legacy spawn) 2025-05-17 11:32:52.249575 :9843F732:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 11:32:52.249742 :9843F73B:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-17 11:32:52.250547 :9843F73C:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-17 11:32:55.430333 :9843F830:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-17 11:32:55.417 2025-05-17 11:32:55.430336 :9843F831:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13936 pso_num=29 pso_serial#=163 2025-05-17 11:32:55.430477 :9843F832:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13936 (legacy spawn) 2025-05-17 11:32:55.436973 :9843F833:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 11:32:55.437003 :9843F836:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 11:32:55.437152 :9843F83D:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-17 11:32:55.437952 :9843F84A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-17 11:32:55.446433 :9843F84D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-17 11:32:55.433 2025-05-17 11:32:55.446436 :9843F84E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14056 pso_num=29 pso_serial#=164 2025-05-17 11:32:55.446571 :9843F84F:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14056 (legacy spawn) 2025-05-17 11:32:55.453040 :9843F850:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 11:32:55.453208 :9843F859:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-17 11:32:55.454009 :9843F85A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-17 13:00:21.262182 :984AC96F:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-17 13:00:21.248 2025-05-17 13:00:21.262183 :984AC970:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12572 pso_num=29 pso_serial#=165 2025-05-17 13:00:21.262309 :984AC971:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=12572 osp_idx=27 osp_ver=11129 osp_pg=0 (spawn #191250) 2025-05-17 13:00:21.262310 :984AC972:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 13:00:21.262310 :984AC973:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=147 time=462631125 2025-05-17 13:00:21.262310 :984AC974:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 13:00:21.262311 :984AC975:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 13:00:21.262311 :984AC976:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 13:00:21.262312 :984AC977:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 13:00:21.262312 :984AC978:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 13:00:21.262833 :984AC979:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-17 13:00:21.262875 :984AC97F:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-17 13:00:21.270568 :984AC98D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 13:00:21.271728 :984AC995:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 13:00:21.272313 :984AC99D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 13:00:21.272787 :984AC9A5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 13:00:21.273229 :984AC9AD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 13:00:21.275785 :984AC9B5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 13:00:21.276900 :984AC9BD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 13:00:21.326991 :984AC9C5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 13:00:21.328436 :984AC9CD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 13:00:21.329405 :984AC9D5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 13:00:21.329650 :984AC9DD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 13:00:21.329893 :984AC9E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 13:00:21.331692 :984AC9ED:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-17 16:00:00.554613 :98590026:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-17 16:00:00.547 2025-05-17 16:00:00.554615 :98590027:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13716 pso_num=29 pso_serial#=166 2025-05-17 16:00:00.554751 :98590028:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13716 (legacy spawn) 2025-05-17 16:00:00.561265 :98590029:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 16:00:00.561424 :98590032:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-17 16:00:00.562236 :98590033:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-17 16:00:06.724005 :98590285:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-17 16:00:06.716 2025-05-17 16:00:06.724006 :98590286:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=2472 pso_num=29 pso_serial#=167 2025-05-17 16:00:06.724139 :98590287:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=2472 (legacy spawn) 2025-05-17 16:00:06.730635 :98590288:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 16:00:06.730822 :9859028D:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-17 16:00:06.731648 :98590292:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-17 16:00:09.620871 :9859038D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-17 16:00:09.606 2025-05-17 16:00:09.620873 :9859038E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12612 pso_num=29 pso_serial#=168 2025-05-17 16:00:09.621012 :9859038F:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12612 (legacy spawn) 2025-05-17 16:00:09.627422 :98590390:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 16:00:09.627597 :98590395:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-17 16:00:09.628379 :9859039A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-17 16:00:09.636999 :9859039D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-17 16:00:09.622 2025-05-17 16:00:09.637001 :9859039E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12876 pso_num=29 pso_serial#=169 2025-05-17 16:00:09.637139 :9859039F:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12876 (legacy spawn) 2025-05-17 16:00:09.643585 :985903A0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 16:00:09.643756 :985903A9:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-17 16:00:09.644552 :985903AA:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-17 16:00:12.732011 :98590495:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-17 16:00:12.719 2025-05-17 16:00:12.732013 :98590496:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=10296 pso_num=29 pso_serial#=170 2025-05-17 16:00:12.732150 :98590497:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=10296 (legacy spawn) 2025-05-17 16:00:12.738675 :98590498:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 16:00:12.738851 :9859049D:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-17 16:00:12.739646 :985904A2:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-17 16:00:12.748229 :985904A5:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-17 16:00:12.735 2025-05-17 16:00:12.748231 :985904A6:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=4872 pso_num=29 pso_serial#=171 2025-05-17 16:00:12.748365 :985904A7:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=4872 (legacy spawn) 2025-05-17 16:00:12.754868 :985904A8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 16:00:12.754898 :985904AB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 16:00:12.755051 :985904B2:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-17 16:00:12.755864 :985904BF:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-17 18:08:03.120637 :986352D0:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-17 18:08:03.107 2025-05-17 18:08:03.120639 :986352D1:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=5464 pso_num=29 pso_serial#=172 2025-05-17 18:08:03.120777 :986352D2:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=5464 (legacy spawn) 2025-05-17 18:08:03.127131 :986352D3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 18:08:03.127316 :986352DC:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-17 18:08:03.128085 :986352DD:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-17 18:08:03.136550 :986352E0:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-17 18:08:03.122 2025-05-17 18:08:03.136551 :986352E1:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=11620 pso_num=29 pso_serial#=173 2025-05-17 18:08:03.136673 :986352E2:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=11620 (legacy spawn) 2025-05-17 18:08:03.142973 :986352E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 18:08:03.143146 :986352EC:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-17 18:08:03.143930 :986352ED:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-17 18:08:06.299619 :98635575:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-17 18:08:06.286 2025-05-17 18:08:06.299621 :98635576:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14164 pso_num=29 pso_serial#=174 2025-05-17 18:08:06.299760 :98635577:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14164 (legacy spawn) 2025-05-17 18:08:06.306278 :98635578:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 18:08:06.306436 :98635581:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-17 18:08:06.307226 :98635582:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-17 18:08:06.315826 :98635585:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-17 18:08:06.301 2025-05-17 18:08:06.315828 :98635586:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12892 pso_num=29 pso_serial#=175 2025-05-17 18:08:06.315965 :98635587:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12892 (legacy spawn) 2025-05-17 18:08:06.322374 :98635588:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 18:08:06.322532 :98635591:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-17 18:08:06.323319 :98635592:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-17 18:08:09.267618 :986357F5:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-17 18:08:09.259 2025-05-17 18:08:09.267620 :986357F6:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=10576 pso_num=29 pso_serial#=176 2025-05-17 18:08:09.267752 :986357F7:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=10576 (legacy spawn) 2025-05-17 18:08:09.274260 :986357F8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 18:08:09.274421 :986357FD:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-17 18:08:09.275254 :98635802:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-17 22:00:34.908905 :9875D72B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-17 22:00:34.899 2025-05-17 22:00:34.908908 :9875D72C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12436 pso_num=29 pso_serial#=177 2025-05-17 22:00:34.909051 :9875D72D:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=12436 osp_idx=27 osp_ver=11141 osp_pg=0 (spawn #192502) 2025-05-17 22:00:34.909052 :9875D72E:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 22:00:34.909052 :9875D72F:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=148 time=495044782 2025-05-17 22:00:34.909053 :9875D730:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 22:00:34.909053 :9875D731:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 22:00:34.909053 :9875D732:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 22:00:34.909054 :9875D733:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 22:00:34.909054 :9875D734:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 22:00:34.909569 :9875D735:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-17 22:00:34.909612 :9875D73B:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-17 22:00:34.917305 :9875D749:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 22:00:34.918363 :9875D751:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 22:00:34.918904 :9875D759:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 22:00:34.919352 :9875D761:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 22:00:34.919792 :9875D769:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 22:00:34.922283 :9875D771:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 22:00:34.923356 :9875D779:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 22:00:34.973068 :9875D781:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 22:00:34.974016 :9875D789:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 22:00:34.974260 :9875D791:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 22:00:34.974479 :9875D799:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-17 22:00:34.976223 :9875D7A1:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-17 22:04:52.863897 :987668F4:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-17 22:04:52.864 2025-05-17 22:04:52.863898 :987668F5:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14020 pso_num=29 pso_serial#=178 2025-05-17 22:04:52.864057 :987668FC:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=14020 osp_idx=27 osp_ver=11142 osp_pg=0 (spawn #192514) 2025-05-17 22:04:52.864058 :987668FD:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 22:04:52.864059 :987668FE:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=149 time=495302719 2025-05-17 22:04:52.864059 :987668FF:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 22:04:52.864059 :98766900:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 22:04:52.864060 :98766901:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 22:04:52.864060 :98766902:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 22:04:52.864060 :98766903:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 22:04:52.865456 :98766941:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-17 22:04:52.865492 :98766947:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-17 22:04:52.976098 :98766BD9:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-17 22:04:55.169347 :98766CBC:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-17 22:04:55.155 2025-05-17 22:04:55.169350 :98766CBD:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13944 pso_num=29 pso_serial#=179 2025-05-17 22:04:55.169510 :98766CBE:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=13944 osp_idx=27 osp_ver=11143 osp_pg=0 (spawn #192516) 2025-05-17 22:04:55.169511 :98766CBF:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 22:04:55.169512 :98766CC0:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=150 time=495305032 2025-05-17 22:04:55.169512 :98766CC1:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 22:04:55.169512 :98766CC2:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 22:04:55.169513 :98766CC3:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 22:04:55.169513 :98766CC4:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 22:04:55.169513 :98766CC5:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 22:04:55.170039 :98766CC6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-17 22:04:55.170091 :98766CCC:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 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 29 (osid: 13944, M002)