Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250518100016\orcl_j002_3560_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:37396M/63366M, Ph+PgF:44611M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 3560, image: ORACLE.EXE (J002) *** 2025-05-18 10:00:16.525 *** SESSION ID:(225.52871) 2025-05-18 10:00:16.525 *** 2025-05-18 10:00:16.525 Process diagnostic dump for ORACLE.EXE (J002), OS id=3560, pid: 28, proc_ser: 161, sid: 225, sess_ser: 52871 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 3560 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.284127 sec, exc=0.284127 sec, total=0.284127 sec wait times: max=0.500000 sec, heur=2.778970 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.000010 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.505106 sec, exc=0.505106 sec, total=0.505106 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=3 seq_num=4 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.000008 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499983 sec, exc=0.499983 sec, total=0.499983 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.500011 sec, exc=0.500011 sec, total=0.500011 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.489700 sec, exc=0.489700 sec, total=0.489700 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 225 serial 52871 --------------------------------------------------- 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 --------------------------------------------------- [3 samples, 10:00:13 - 10:00:16] idle wait at each sample [session created at: 10:00:13] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-18 10:00:16.525 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 3560, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-18 09:57:56.827616 :98AFD2B4:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13268 pso_num=28 pso_serial#=147 2025-05-18 09:57:56.827753 :98AFD2B5:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13268 (legacy spawn) 2025-05-18 09:57:56.834169 :98AFD2B6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 09:57:56.834341 :98AFD2BB:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 09:57:56.835158 :98AFD2C0:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-18 09:57:56.843802 :98AFD2C3:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-18 09:57:56.830 2025-05-18 09:57:56.843804 :98AFD2C4:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10028 pso_num=28 pso_serial#=148 2025-05-18 09:57:56.843945 :98AFD2C5:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10028 (legacy spawn) 2025-05-18 09:57:56.850406 :98AFD2C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 09:57:56.850584 :98AFD2CE:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 09:57:56.851394 :98AFD2D0:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-18 09:58:09.321880 :98AFD6AD:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-18 09:58:09.313 2025-05-18 09:58:09.321882 :98AFD6AE:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13708 pso_num=28 pso_serial#=149 2025-05-18 09:58:09.322024 :98AFD6AF:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13708 (legacy spawn) 2025-05-18 09:58:09.328476 :98AFD6B0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 09:58:09.328795 :98AFD6B9:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 09:58:09.329875 :98AFD6BA:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-18 09:58:47.798754 :98AFE375:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-18 09:58:47.785 2025-05-18 09:58:47.798756 :98AFE376:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13156 pso_num=28 pso_serial#=150 2025-05-18 09:58:47.798893 :98AFE377:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13156 (legacy spawn) 2025-05-18 09:58:47.805440 :98AFE378:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 09:58:47.805628 :98AFE37D:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 09:58:47.806433 :98AFE382:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-18 09:59:06.317494 :98AFE98B:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-18 09:59:06.303 2025-05-18 09:59:06.317496 :98AFE98C:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14060 pso_num=28 pso_serial#=151 2025-05-18 09:59:06.317634 :98AFE98D:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14060 (legacy spawn) 2025-05-18 09:59:06.324332 :98AFE98E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 09:59:06.324506 :98AFE993:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 09:59:06.325321 :98AFE998:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-18 09:59:06.333950 :98AFE99B:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-18 09:59:06.319 2025-05-18 09:59:06.333952 :98AFE99C:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13448 pso_num=28 pso_serial#=152 2025-05-18 09:59:06.334119 :98AFE99D:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13448 (legacy spawn) 2025-05-18 09:59:06.340559 :98AFE99E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 09:59:06.340740 :98AFE9A7:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 09:59:06.341542 :98AFE9A8:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-18 09:59:09.543690 :98AFEAA5:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-18 09:59:09.532 2025-05-18 09:59:09.543692 :98AFEAA6:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14132 pso_num=28 pso_serial#=153 2025-05-18 09:59:09.543825 :98AFEAA7:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14132 (legacy spawn) 2025-05-18 09:59:09.550365 :98AFEAB4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 09:59:09.550530 :98AFEAB9:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 09:59:09.551309 :98AFEABE:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-18 09:59:09.559864 :98AFEAC1:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-18 09:59:09.548 2025-05-18 09:59:09.559866 :98AFEAC2:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13396 pso_num=28 pso_serial#=154 2025-05-18 09:59:09.559996 :98AFEAC3:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13396 (legacy spawn) 2025-05-18 09:59:09.566524 :98AFEAC4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 09:59:09.566697 :98AFEACD:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 09:59:09.567507 :98AFEACE:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-18 09:59:45.707039 :98AFF71C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-18 09:59:45.705 2025-05-18 09:59:45.707041 :98AFF71D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14096 pso_num=28 pso_serial#=155 2025-05-18 09:59:45.707174 :98AFF71E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14096 (legacy spawn) 2025-05-18 09:59:45.713565 :98AFF71F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 09:59:45.713732 :98AFF728:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 09:59:45.714512 :98AFF729:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-18 09:59:45.723055 :98AFF72C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-18 09:59:45.720 2025-05-18 09:59:45.723058 :98AFF72D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13636 pso_num=28 pso_serial#=156 2025-05-18 09:59:45.723193 :98AFF72E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13636 (legacy spawn) 2025-05-18 09:59:45.741010 :98AFF72F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 09:59:45.741180 :98AFF738:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 09:59:45.742018 :98AFF739:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-18 09:59:49.920847 :98AFF869:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-18 09:59:49.912 2025-05-18 09:59:49.920849 :98AFF86A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=9724 pso_num=28 pso_serial#=157 2025-05-18 09:59:49.920982 :98AFF86B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=9724 (legacy spawn) 2025-05-18 09:59:49.927470 :98AFF86C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 09:59:49.927649 :98AFF871:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 09:59:49.928462 :98AFF876:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-18 09:59:51.556745 :98AFF929:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-18 09:59:51.548 2025-05-18 09:59:51.556747 :98AFF92A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13316 pso_num=28 pso_serial#=158 2025-05-18 09:59:51.556886 :98AFF92B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13316 (legacy spawn) 2025-05-18 09:59:51.563327 :98AFF92C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 09:59:51.563493 :98AFF931:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 09:59:51.564342 :98AFF936:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-18 10:00:11.960691 :98AFFFA5:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-18 10:00:11.955 2025-05-18 10:00:11.960693 :98AFFFA6:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12064 pso_num=28 pso_serial#=159 2025-05-18 10:00:11.960834 :98AFFFA7:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=12064 osp_idx=26 osp_ver=124598 osp_pg=0 (spawn #194172) 2025-05-18 10:00:11.960835 :98AFFFA8:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-18 10:00:11.960835 :98AFFFA9:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=186 time=538221829 2025-05-18 10:00:11.960835 :98AFFFAA:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-18 10:00:11.960836 :98AFFFAB:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-18 10:00:11.960836 :98AFFFAC:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-18 10:00:11.960836 :98AFFFAD:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-18 10:00:11.960837 :98AFFFAE:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-18 10:00:11.961338 :98AFFFAF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-18 10:00:11.961382 :98AFFFB5:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-18 10:00:11.965425 :98AFFFC8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-18 10:00:11.965473 :98AFFFC9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-18 10:00:11.965731 :98AFFFCA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-18 10:00:11.965747 :98AFFFCB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-18 10:00:11.965969 :98AFFFCC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-18 10:00:11.965983 :98AFFFCD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-18 10:00:11.966193 :98AFFFD0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-18 10:00:11.966206 :98AFFFD1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-18 10:00:11.966411 :98AFFFDA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-18 10:00:11.966424 :98AFFFDB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-18 10:00:11.966630 :98AFFFDC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-18 10:00:11.966642 :98AFFFDD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-18 10:00:11.969131 :98AFFFF9:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-18 10:00:11.979622 :98B00030:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-18 10:00:11.971 2025-05-18 10:00:11.979623 :98B00031:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10224 pso_num=28 pso_serial#=160 2025-05-18 10:00:11.979757 :98B00032:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=10224 osp_idx=26 osp_ver=124599 osp_pg=0 (spawn #194175) 2025-05-18 10:00:11.979758 :98B00033:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-18 10:00:11.979759 :98B00034:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=187 time=538221844 2025-05-18 10:00:11.979759 :98B00035:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-18 10:00:11.979759 :98B00036:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-18 10:00:11.979759 :98B00037:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-18 10:00:11.979760 :98B00038:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-18 10:00:11.979760 :98B00039:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-18 10:00:11.980361 :98B0003A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-18 10:00:11.980406 :98B00040:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-18 10:00:11.989119 :98B0008A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-18 10:00:12.085744 :98B001E9:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-18 10:00:12.080 2025-05-18 10:00:12.085745 :98B001EA:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=3560 pso_num=28 pso_serial#=161 2025-05-18 10:00:12.085894 :98B001EB:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=3560 osp_idx=26 osp_ver=124600 osp_pg=0 (spawn #194176) 2025-05-18 10:00:12.085895 :98B001EC:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-18 10:00:12.085896 :98B001ED:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=188 time=538221954 2025-05-18 10:00:12.085896 :98B001EE:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-18 10:00:12.085897 :98B001EF:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-18 10:00:12.085897 :98B001F0:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-18 10:00:12.085897 :98B001F1:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-18 10:00:12.085898 :98B001F2:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-18 10:00:12.086511 :98B001FC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-18 10:00:12.091681 :98B00206:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:12.091723 :98B00209:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:jslv.c:9203 2025-05-18 10:00:12.095596 :98B0020F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:12.203273 :98B003C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:12.331819 :98B003D9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:12.459009 :98B003EC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.572062 :98B004E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.573038 :98B004F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.574670 :98B004F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.576284 :98B00501:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.580221 :98B00509:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.581707 :98B00511:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.596542 :98B00519:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.597216 :98B00521:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.597800 :98B00529:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.598308 :98B00531:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.600974 :98B00539:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.601895 :98B00541:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-05-18 10:00:13.602654 :98B00542:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-05-18 10:00:13.603431 :98B00543:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-05-18 10:00:13.603872 :98B00544:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-05-18 10:00:13.603878 :98B00545:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-05-18 10:00:13.604517 :98B00546:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-05-18 10:00:13.605033 :98B00547:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-05-18 10:00:13.605340 :98B00548:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-05-18 10:00:13.606401 :98B00549:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-05-18 10:00:13.641205 :98B0054A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.641250 :98B0054D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-05-18 10:00:13.642218 :98B00553:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.643098 :98B0055B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-05-18 10:00:13.643524 :98B0055C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.662950 :98B00564:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.662998 :98B00567:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-05-18 10:00:13.663822 :98B0056D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.675333 :98B00575:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-05-18 10:00:13.675881 :98B0057D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.695618 :98B0058A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.695671 :98B0058D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-05-18 10:00:13.696496 :98B00593:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.697341 :98B0059B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-05-18 10:00:13.697795 :98B0059C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.717056 :98B005A4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.717103 :98B005A7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-05-18 10:00:13.718009 :98B005AD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.718872 :98B005B5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-05-18 10:00:13.719333 :98B005B6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.739864 :98B005BE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.739918 :98B005C1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-05-18 10:00:13.740815 :98B005C7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.741269 :98B005CF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.741627 :98B005D7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.741817 :98B005DF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.741949 :98B005E7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.742085 :98B005EF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:jslv.c:9203 2025-05-18 10:00:13.746103 :98B005F0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.746179 :98B005F3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-05-18 10:00:13.746259 :98B00600:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 28 (osid: 3560, J002)