Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250727220750\orcl_j002_17264_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:36004M/63366M, Ph+PgF:41761M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 17264, image: ORACLE.EXE (J002) *** 2025-07-27 22:07:50.521 *** SESSION ID:(219.17441) 2025-07-27 22:07:50.521 *** 2025-07-27 22:07:50.521 Process diagnostic dump for ORACLE.EXE (J002), OS id=17264, pid: 27, proc_ser: 169, sid: 219, sess_ser: 17441 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 17264 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.345492 sec, exc=0.345492 sec, total=0.345492 sec wait times: max=0.500000 sec, heur=3.839192 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.000009 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.500169 sec, exc=0.500169 sec, total=0.500169 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499986 sec, exc=0.499986 sec, total=0.499986 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000017 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.499991 sec, exc=0.499991 sec, total=0.499991 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.500009 sec, exc=0.500009 sec, total=0.500009 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.500002 sec, exc=0.500002 sec, total=0.500002 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.493480 sec, exc=0.493480 sec, total=0.493480 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 219 serial 17441 --------------------------------------------------- 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 --------------------------------------------------- [4 samples, 22:07:46 - 22:07:50] idle wait at each sample [session created at: 22:07:46] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-27 22:07:50.521 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 17264, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-27 22:03:01.812106 :B7D584F3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-27 22:03:01.812 2025-07-27 22:03:01.812108 :B7D584F4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19032 pso_num=27 pso_serial#=146 2025-07-27 22:03:01.812253 :B7D584F5:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=19032 (legacy spawn) 2025-07-27 22:03:01.819365 :B7D584F6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:03:01.819536 :B7D584FE:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-07-27 22:03:01.820415 :B7D58500:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-27 22:03:02.508560 :B7D58574:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-27 22:03:02.499 2025-07-27 22:03:02.508562 :B7D58575:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4728 pso_num=27 pso_serial#=147 2025-07-27 22:03:02.508707 :B7D58576:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4728 (legacy spawn) 2025-07-27 22:03:02.515494 :B7D58577:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:03:02.515674 :B7D58580:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-07-27 22:03:02.516507 :B7D58581:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-27 22:03:03.992897 :B7D585E5:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-27 22:03:03.987 2025-07-27 22:03:03.992899 :B7D585E6:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10656 pso_num=27 pso_serial#=148 2025-07-27 22:03:03.993041 :B7D585E7:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=10656 osp_idx=25 osp_ver=205176 osp_pg=0 (spawn #429163) 2025-07-27 22:03:03.993042 :B7D585E8:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-27 22:03:03.993043 :B7D585E9:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=27 time=2334559892 2025-07-27 22:03:03.993043 :B7D585EA:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-27 22:03:03.993043 :B7D585EB:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-27 22:03:03.993044 :B7D585EC:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-27 22:03:03.993044 :B7D585ED:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-27 22:03:03.993045 :B7D585EE:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-27 22:03:03.993577 :B7D585EF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-27 22:03:03.993631 :B7D585F5:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-07-27 22:03:04.002322 :B7D58604:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-27 22:03:38.045957 :B7D59163:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-27 22:03:38.032 2025-07-27 22:03:38.045959 :B7D59164:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13548 pso_num=27 pso_serial#=149 2025-07-27 22:03:38.046102 :B7D59165:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13548 (legacy spawn) 2025-07-27 22:03:38.052617 :B7D59166:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:03:38.052787 :B7D5916F:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-07-27 22:03:38.053560 :B7D59170:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-27 22:03:38.062346 :B7D59173:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-27 22:03:38.048 2025-07-27 22:03:38.062347 :B7D59174:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18716 pso_num=27 pso_serial#=150 2025-07-27 22:03:38.062485 :B7D59175:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18716 (legacy spawn) 2025-07-27 22:03:38.068988 :B7D59176:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:03:38.069146 :B7D5917E:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-07-27 22:03:38.069957 :B7D59180:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-27 22:03:52.138611 :B7D5965B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-27 22:03:52.130 2025-07-27 22:03:52.138613 :B7D5965C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4492 pso_num=27 pso_serial#=151 2025-07-27 22:03:52.138785 :B7D5965D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4492 (legacy spawn) 2025-07-27 22:03:52.145315 :B7D5965E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:03:52.145500 :B7D59663:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-07-27 22:03:52.146335 :B7D59674:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-27 22:04:44.835228 :B7D5A813:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-27 22:04:44.826 2025-07-27 22:04:44.835230 :B7D5A814:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19368 pso_num=27 pso_serial#=152 2025-07-27 22:04:44.835367 :B7D5A815:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=19368 (legacy spawn) 2025-07-27 22:04:44.841997 :B7D5A816:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:04:44.842165 :B7D5A81B:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-07-27 22:04:44.842958 :B7D5A820:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-27 22:04:47.795985 :B7D5A919:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-27 22:04:47.781 2025-07-27 22:04:47.795987 :B7D5A91A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9808 pso_num=27 pso_serial#=153 2025-07-27 22:04:47.796125 :B7D5A91B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9808 (legacy spawn) 2025-07-27 22:04:47.802640 :B7D5A91C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:04:47.802815 :B7D5A921:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-07-27 22:04:47.803596 :B7D5A926:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-27 22:04:47.821253 :B7D5A929:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-27 22:04:47.812 2025-07-27 22:04:47.821256 :B7D5A92A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18604 pso_num=27 pso_serial#=154 2025-07-27 22:04:47.821392 :B7D5A92B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18604 (legacy spawn) 2025-07-27 22:04:47.827883 :B7D5A92C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:04:47.828061 :B7D5A935:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-07-27 22:04:47.828858 :B7D5A936:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-27 22:04:50.998828 :B7D5AA54:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-27 22:04:50.985 2025-07-27 22:04:50.998830 :B7D5AA55:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18980 pso_num=27 pso_serial#=155 2025-07-27 22:04:50.998971 :B7D5AA56:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18980 (legacy spawn) 2025-07-27 22:04:51.005512 :B7D5AA57:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:04:51.005688 :B7D5AA5C:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-07-27 22:04:51.006499 :B7D5AA61:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-27 22:04:51.015286 :B7D5AA64:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-27 22:04:51.001 2025-07-27 22:04:51.015288 :B7D5AA65:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4196 pso_num=27 pso_serial#=156 2025-07-27 22:04:51.015426 :B7D5AA66:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4196 (legacy spawn) 2025-07-27 22:04:51.021932 :B7D5AA67:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:04:51.021961 :B7D5AA6A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:04:51.022103 :B7D5AA71:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-07-27 22:04:51.022883 :B7D5AA7E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-27 22:04:54.309337 :B7D5AB75:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-27 22:04:54.301 2025-07-27 22:04:54.309339 :B7D5AB76:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17932 pso_num=27 pso_serial#=157 2025-07-27 22:04:54.309476 :B7D5AB77:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17932 (legacy spawn) 2025-07-27 22:04:54.315934 :B7D5AB78:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:04:54.316112 :B7D5AB7D:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-07-27 22:04:54.316927 :B7D5AB82:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-27 22:05:52.952448 :B7D5C55C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-27 22:05:52.943 2025-07-27 22:05:52.952450 :B7D5C55D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17856 pso_num=27 pso_serial#=158 2025-07-27 22:05:52.952592 :B7D5C55E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17856 (legacy spawn) 2025-07-27 22:05:52.959221 :B7D5C55F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:05:52.959399 :B7D5C564:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-07-27 22:05:52.960175 :B7D5C569:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-27 22:05:56.499525 :B7D5C67B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-27 22:05:56.491 2025-07-27 22:05:56.499527 :B7D5C67C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13804 pso_num=27 pso_serial#=159 2025-07-27 22:05:56.499663 :B7D5C67D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13804 (legacy spawn) 2025-07-27 22:05:56.506212 :B7D5C67E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:05:56.506388 :B7D5C683:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-07-27 22:05:56.507253 :B7D5C69D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-27 22:06:00.412214 :B7D5C7F3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-27 22:06:00.397 2025-07-27 22:06:00.412216 :B7D5C7F4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17272 pso_num=27 pso_serial#=160 2025-07-27 22:06:00.412367 :B7D5C7F5:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17272 (legacy spawn) 2025-07-27 22:06:00.418861 :B7D5C7F6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:06:00.419033 :B7D5C7FB:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-07-27 22:06:00.419865 :B7D5C800:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-27 22:06:00.428790 :B7D5C803:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-27 22:06:00.429 2025-07-27 22:06:00.428792 :B7D5C804:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12684 pso_num=27 pso_serial#=161 2025-07-27 22:06:00.428936 :B7D5C805:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12684 (legacy spawn) 2025-07-27 22:06:00.435496 :B7D5C806:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:06:00.435663 :B7D5C80F:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-07-27 22:06:00.436424 :B7D5C810:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-27 22:06:03.488972 :B7D5C908:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-27 22:06:03.476 2025-07-27 22:06:03.488975 :B7D5C909:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18544 pso_num=27 pso_serial#=162 2025-07-27 22:06:03.489112 :B7D5C90A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18544 (legacy spawn) 2025-07-27 22:06:03.495690 :B7D5C90B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:06:03.495865 :B7D5C910:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-07-27 22:06:03.496681 :B7D5C915:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-27 22:06:03.515577 :B7D5C918:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-27 22:06:03.507 2025-07-27 22:06:03.515579 :B7D5C919:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17204 pso_num=27 pso_serial#=163 2025-07-27 22:06:03.515711 :B7D5C91A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17204 (legacy spawn) 2025-07-27 22:06:03.522261 :B7D5C91B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:06:03.522496 :B7D5C924:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-07-27 22:06:03.523337 :B7D5C925:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-27 22:06:39.905859 :B7D5D524:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-27 22:06:39.893 2025-07-27 22:06:39.905862 :B7D5D525:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19280 pso_num=27 pso_serial#=164 2025-07-27 22:06:39.905998 :B7D5D526:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=19280 (legacy spawn) 2025-07-27 22:06:39.912594 :B7D5D527:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:06:39.912764 :B7D5D52C:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-07-27 22:06:39.913543 :B7D5D531:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-27 22:06:39.922345 :B7D5D534:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-27 22:06:39.908 2025-07-27 22:06:39.922347 :B7D5D535:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17956 pso_num=27 pso_serial#=165 2025-07-27 22:06:39.922485 :B7D5D536:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17956 (legacy spawn) 2025-07-27 22:06:39.929097 :B7D5D537:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:06:39.929258 :B7D5D540:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-07-27 22:06:39.930046 :B7D5D541:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-27 22:06:58.676246 :B7D5DB41:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-27 22:06:58.668 2025-07-27 22:06:58.676248 :B7D5DB42:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19336 pso_num=27 pso_serial#=166 2025-07-27 22:06:58.676384 :B7D5DB43:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=19336 (legacy spawn) 2025-07-27 22:06:58.683099 :B7D5DB44:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:06:58.683271 :B7D5DB49:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-07-27 22:06:58.684171 :B7D5DB4E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-27 22:07:01.133605 :B7D5DBEF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-27 22:07:01.125 2025-07-27 22:07:01.133607 :B7D5DBF0:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9904 pso_num=27 pso_serial#=167 2025-07-27 22:07:01.133749 :B7D5DBF1:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9904 (legacy spawn) 2025-07-27 22:07:01.140415 :B7D5DBF2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:01.140587 :B7D5DBF7:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-07-27 22:07:01.141369 :B7D5DBFC:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-27 22:07:46.478520 :B7D5EB78:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-27 22:07:46.473 2025-07-27 22:07:46.478522 :B7D5EB79:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=7564 pso_num=27 pso_serial#=168 2025-07-27 22:07:46.478665 :B7D5EB7A:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=7564 osp_idx=25 osp_ver=205196 osp_pg=0 (spawn #429175) 2025-07-27 22:07:46.478665 :B7D5EB7B:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-27 22:07:46.478666 :B7D5EB7C:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=28 time=2334842376 2025-07-27 22:07:46.478666 :B7D5EB7D:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-27 22:07:46.478667 :B7D5EB7E:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-27 22:07:46.478667 :B7D5EB7F:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-27 22:07:46.478667 :B7D5EB80:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-27 22:07:46.478667 :B7D5EB81:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-27 22:07:46.479168 :B7D5EB82:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-27 22:07:46.479216 :B7D5EB88:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-07-27 22:07:46.483425 :B7D5EB9A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-27 22:07:46.483476 :B7D5EB9B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-27 22:07:46.483745 :B7D5EB9C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-27 22:07:46.483762 :B7D5EB9D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-27 22:07:46.483982 :B7D5EB9E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-27 22:07:46.483996 :B7D5EB9F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-27 22:07:46.484205 :B7D5EBA0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-27 22:07:46.484220 :B7D5EBA1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-27 22:07:46.484429 :B7D5EBA2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-27 22:07:46.484442 :B7D5EBA3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-27 22:07:46.484645 :B7D5EBA4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-27 22:07:46.484657 :B7D5EBA5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-27 22:07:46.487384 :B7D5EBD3:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-27 22:07:46.681913 :B7D5EFF3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-27 22:07:46.677 2025-07-27 22:07:46.681915 :B7D5EFF4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17264 pso_num=27 pso_serial#=169 2025-07-27 22:07:46.682049 :B7D5EFF9:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=17264 osp_idx=25 osp_ver=205197 osp_pg=0 (spawn #429177) 2025-07-27 22:07:46.682050 :B7D5EFFA:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-27 22:07:46.682050 :B7D5EFFB:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=29 time=2334842579 2025-07-27 22:07:46.682051 :B7D5EFFC:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-27 22:07:46.682051 :B7D5EFFD:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-27 22:07:46.682052 :B7D5EFFE:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-27 22:07:46.682052 :B7D5EFFF:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-27 22:07:46.682052 :B7D5F000:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-27 22:07:46.682574 :B7D5F006:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 27 (osid: 17264, J002)