Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250721220007\orcl_j003_17876_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:36085M/63366M, Ph+PgF:41929M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 17876, image: ORACLE.EXE (J003) *** 2025-07-21 22:00:07.185 *** SESSION ID:(233.29723) 2025-07-21 22:00:07.185 *** 2025-07-21 22:00:07.185 Process diagnostic dump for ORACLE.EXE (J003), OS id=17876, pid: 29, proc_ser: 19, sid: 233, sess_ser: 29723 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 17876 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.117227 sec, exc=0.117227 sec, total=0.117227 sec wait times: max=0.500000 sec, heur=6.609220 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.000007 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.501089 sec, exc=0.501089 sec, total=0.501089 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 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.000008 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.500004 sec, exc=0.500004 sec, total=0.500004 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.500133 sec, exc=0.500133 sec, total=0.500133 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.502833 sec, exc=0.502833 sec, total=0.502833 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time Sampled Session History of session 233 serial 29723 --------------------------------------------------- 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 --------------------------------------------------- [7 samples, 22:00:00 - 22:00:07] idle wait at each sample [session created at: 22:00:00] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-21 22:00:07.185 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 17876, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-21 00:02:25.850197 :B4C4B926:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18900 pso_num=29 pso_serial#=252 2025-07-21 00:02:25.850335 :B4C4B927:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18900 (legacy spawn) 2025-07-21 00:02:25.874810 :B4C4B928: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-07-21 00:02:25.874985 :B4C4B931: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-07-21 00:02:25.875834 :B4C4B932:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-21 00:03:07.538150 :B4C4C6F8:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:03:07.523 2025-07-21 00:03:07.538152 :B4C4C6F9:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19064 pso_num=29 pso_serial#=253 2025-07-21 00:03:07.538289 :B4C4C6FA:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19064 (legacy spawn) 2025-07-21 00:03:07.545295 :B4C4C6FB: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-07-21 00:03:07.545482 :B4C4C700: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-07-21 00:03:07.546391 :B4C4C705:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-21 00:03:07.555718 :B4C4C708:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:03:07.554 2025-07-21 00:03:07.555720 :B4C4C709:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=8928 pso_num=29 pso_serial#=254 2025-07-21 00:03:07.555877 :B4C4C70A:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=8928 (legacy spawn) 2025-07-21 00:03:07.562457 :B4C4C70B: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-07-21 00:03:07.562630 :B4C4C714: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-07-21 00:03:07.563514 :B4C4C715:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-21 00:03:09.128240 :B4C4C776:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:03:09.119 2025-07-21 00:03:09.128242 :B4C4C777:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17100 pso_num=29 pso_serial#=255 2025-07-21 00:03:09.128385 :B4C4C778:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17100 (legacy spawn) 2025-07-21 00:03:09.134891 :B4C4C779: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-07-21 00:03:09.135116 :B4C4C77E: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-07-21 00:03:09.135914 :B4C4C783:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-21 00:03:10.690076 :B4C4C80B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:03:10.682 2025-07-21 00:03:10.690078 :B4C4C80C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15404 pso_num=29 pso_serial#=1 2025-07-21 00:03:10.690223 :B4C4C80D:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15404 (legacy spawn) 2025-07-21 00:03:10.696763 :B4C4C80E: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-07-21 00:03:10.696948 :B4C4C813: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-07-21 00:03:10.697822 :B4C4C818:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-21 00:03:10.706830 :B4C4C81B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:03:10.697 2025-07-21 00:03:10.706832 :B4C4C81C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19360 pso_num=29 pso_serial#=2 2025-07-21 00:03:10.706972 :B4C4C81D:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19360 (legacy spawn) 2025-07-21 00:03:10.713627 :B4C4C81E: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-07-21 00:03:10.713788 :B4C4C827: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-07-21 00:03:10.714684 :B4C4C828:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-21 00:04:17.350003 :B4C4DDBC:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:04:17.340 2025-07-21 00:04:17.350005 :B4C4DDBD:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16560 pso_num=29 pso_serial#=3 2025-07-21 00:04:17.350140 :B4C4DDBE:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16560 (legacy spawn) 2025-07-21 00:04:17.356624 :B4C4DDBF: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-07-21 00:04:17.356800 :B4C4DDC8: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-07-21 00:04:17.357588 :B4C4DDC9:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-21 00:04:20.884989 :B4C4DEC7:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:04:20.870 2025-07-21 00:04:20.884991 :B4C4DEC8:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13400 pso_num=29 pso_serial#=4 2025-07-21 00:04:20.885134 :B4C4DEC9:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13400 (legacy spawn) 2025-07-21 00:04:20.891590 :B4C4DECA: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-07-21 00:04:20.891620 :B4C4DECD: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-07-21 00:04:20.891764 :B4C4DED0: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-07-21 00:04:20.892585 :B4C4DEE1:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-21 00:04:20.901243 :B4C4DEE4:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:04:20.886 2025-07-21 00:04:20.901245 :B4C4DEE5:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13944 pso_num=29 pso_serial#=5 2025-07-21 00:04:20.901414 :B4C4DEE6:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13944 (legacy spawn) 2025-07-21 00:04:20.907888 :B4C4DEE7: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-07-21 00:04:20.908059 :B4C4DEF0: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-07-21 00:04:20.908843 :B4C4DEF1:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-21 00:04:24.009191 :B4C4E038:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:04:23.995 2025-07-21 00:04:24.009193 :B4C4E039:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19364 pso_num=29 pso_serial#=6 2025-07-21 00:04:24.009333 :B4C4E03A:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19364 (legacy spawn) 2025-07-21 00:04:24.015824 :B4C4E03B: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-07-21 00:04:24.015855 :B4C4E03E: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-07-21 00:04:24.016009 :B4C4E041: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-07-21 00:04:24.016792 :B4C4E052:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-21 00:04:24.025398 :B4C4E055:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:04:24.011 2025-07-21 00:04:24.025400 :B4C4E056:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15532 pso_num=29 pso_serial#=7 2025-07-21 00:04:24.025536 :B4C4E057:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15532 (legacy spawn) 2025-07-21 00:04:24.032038 :B4C4E058: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-07-21 00:04:24.032202 :B4C4E061: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-07-21 00:04:24.033022 :B4C4E062:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-21 00:05:00.495637 :B4C4EC64:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:05:00.482 2025-07-21 00:05:00.495639 :B4C4EC65:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=4428 pso_num=29 pso_serial#=8 2025-07-21 00:05:00.495777 :B4C4EC66:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=4428 (legacy spawn) 2025-07-21 00:05:00.502376 :B4C4EC67: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-07-21 00:05:00.502556 :B4C4EC6D: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-07-21 00:05:00.503366 :B4C4EC71:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-21 00:05:00.520963 :B4C4EC74:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:05:00.513 2025-07-21 00:05:00.520965 :B4C4EC75:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19076 pso_num=29 pso_serial#=9 2025-07-21 00:05:00.521105 :B4C4EC76:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19076 (legacy spawn) 2025-07-21 00:05:00.527593 :B4C4EC77: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-07-21 00:05:00.527758 :B4C4EC80: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-07-21 00:05:00.528559 :B4C4EC81:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-21 00:05:01.568972 :B4C4ECDD:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:05:01.560 2025-07-21 00:05:01.568974 :B4C4ECDE:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18456 pso_num=29 pso_serial#=10 2025-07-21 00:05:01.569119 :B4C4ECDF:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18456 (legacy spawn) 2025-07-21 00:05:01.575932 :B4C4ECE0: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-07-21 00:05:01.576116 :B4C4ECE5: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-07-21 00:05:01.577032 :B4C4ECEA:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-21 00:05:25.541240 :B4C4F73F:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:05:25.532 2025-07-21 00:05:25.541243 :B4C4F740:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=9144 pso_num=29 pso_serial#=11 2025-07-21 00:05:25.541394 :B4C4F741:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=9144 (legacy spawn) 2025-07-21 00:05:25.548317 :B4C4F76E: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-07-21 00:05:25.548515 :B4C4F773: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-07-21 00:05:25.549453 :B4C4F778:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-21 00:05:59.546944 :B4C507CE:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:05:59.538 2025-07-21 00:05:59.546946 :B4C507CF:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16632 pso_num=29 pso_serial#=12 2025-07-21 00:05:59.547084 :B4C507D0:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16632 (legacy spawn) 2025-07-21 00:05:59.553513 :B4C507D1: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-07-21 00:05:59.553707 :B4C507D9: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-07-21 00:05:59.554490 :B4C507DB:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-21 00:06:03.747635 :B4C50AC0:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:06:03.739 2025-07-21 00:06:03.747637 :B4C50AC1:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15788 pso_num=29 pso_serial#=13 2025-07-21 00:06:03.747774 :B4C50AC2:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15788 (legacy spawn) 2025-07-21 00:06:03.754278 :B4C50AC3: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-07-21 00:06:03.754466 :B4C50AC8: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-07-21 00:06:03.755288 :B4C50ACD:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-21 00:06:10.014034 :B4C50C9D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:06:09.999 2025-07-21 00:06:10.014037 :B4C50C9E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12108 pso_num=29 pso_serial#=14 2025-07-21 00:06:10.014180 :B4C50C9F:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12108 (legacy spawn) 2025-07-21 00:06:10.020752 :B4C50CA0: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-07-21 00:06:10.020929 :B4C50CA9: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-07-21 00:06:10.021767 :B4C50CAA:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-21 00:06:10.030451 :B4C50CAD:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:06:10.030 2025-07-21 00:06:10.030453 :B4C50CAE:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19020 pso_num=29 pso_serial#=15 2025-07-21 00:06:10.030591 :B4C50CAF:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19020 (legacy spawn) 2025-07-21 00:06:10.037151 :B4C50CB0: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-07-21 00:06:10.037316 :B4C50CB9: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-07-21 00:06:10.038142 :B4C50CBA:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-21 00:06:13.209779 :B4C50DB0:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:06:13.196 2025-07-21 00:06:13.209781 :B4C50DB1:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18924 pso_num=29 pso_serial#=16 2025-07-21 00:06:13.209915 :B4C50DB2:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18924 (legacy spawn) 2025-07-21 00:06:13.216386 :B4C50DB3: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-07-21 00:06:13.216582 :B4C50DB8: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-07-21 00:06:13.217352 :B4C50DBD:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-21 00:06:13.226015 :B4C50DC0:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 00:06:13.212 2025-07-21 00:06:13.226017 :B4C50DC1:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17140 pso_num=29 pso_serial#=17 2025-07-21 00:06:13.226148 :B4C50DC2:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17140 (legacy spawn) 2025-07-21 00:06:13.248245 :B4C50DC3: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-07-21 00:06:13.248412 :B4C50DCC: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-07-21 00:06:13.249200 :B4C50DCD:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-21 01:00:02.779115 :B4C937E9:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 01:00:02.770 2025-07-21 01:00:02.779118 :B4C937EA:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19440 pso_num=29 pso_serial#=18 2025-07-21 01:00:02.779240 :B4C937EB:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=19440 osp_idx=27 osp_ver=14807 osp_pg=0 (spawn #406249) 2025-07-21 01:00:02.779241 :B4C937EC:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-21 01:00:02.779241 :B4C937ED:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=255 time=1740378673 2025-07-21 01:00:02.779242 :B4C937EE:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-21 01:00:02.779242 :B4C937EF:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-21 01:00:02.779242 :B4C937F0:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-21 01:00:02.779242 :B4C937F1:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-21 01:00:02.779243 :B4C937F2:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-21 01:00:02.779862 :B4C937F3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-21 01:00:32.858661 :B4C9437C:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-21 22:00:00.531573 :B52BDB71:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-21 22:00:00.524 2025-07-21 22:00:00.531575 :B52BDB72:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17876 pso_num=29 pso_serial#=19 2025-07-21 22:00:00.531723 :B52BDB7C:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=17876 osp_idx=27 osp_ver=14808 osp_pg=0 (spawn #409159) 2025-07-21 22:00:00.531724 :B52BDB7D:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-21 22:00:00.531724 :B52BDB7E:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=0 time=1815976423 2025-07-21 22:00:00.531725 :B52BDB7F:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-21 22:00:00.531726 :B52BDB80:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-21 22:00:00.531726 :B52BDB81:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-21 22:00:00.531726 :B52BDB82:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-21 22:00:00.531727 :B52BDB83:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-21 22:00:00.532413 :B52BDB84:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-21 22:00:00.537572 :B52BDBB1: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-07-21 22:00:00.537608 :B52BDBB4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-07-21 22:00:00.555053 :B52BDC74:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-07-21 22:00:00.555638 :B52BDC82:db_trace:kcc.c@13684:kccwbp(): [10021:29:233:1] 0115000000000000 0115000000000000 7a51120000000000 2025-07-21 22:00:00.566342 :B52BDCC7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-21 22:00:00.566409 :B52BDCD5:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-21 22:00:00.566418 :B52BDCDB:db_trace:ksb.c@6610:ksbcic_int(): [10254:29:233] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 8397264 1) 2025-07-21 22:00:00.566423 :B52BDCDC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-21 22:00:00.566450 :B52BDCE8:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-21 22:00:00.567225 :B52BDCF4:db_trace:ksb.c@6610:ksbcic_int(): [10254:29:233] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (4 0 232056647) 2025-07-21 22:00:00.567229 :B52BDCF5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-21 22:00:00.567251 :B52BDD01:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-21 22:00:00.567259 :B52BDD0D:db_trace:ksb.c@6610:ksbcic_int(): [10254:29:233] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (6 65565 1) 2025-07-21 22:00:00.567261 :B52BDD0E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-21 22:00:00.567289 :B52BDD1A:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-21 22:00:00.567292 :B52BDD23:db_trace:ksb.c@6610:ksbcic_int(): [10254:29:233] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (1 2 65565) 2025-07-21 22:00:00.567294 :B52BDD27:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-21 22:00:00.567343 :B52BDD4F:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-21 22:00:00.569482 :B52BDFCE:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=15 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-21 22:00:00.569506 :B52BDFD3:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-07-21 22:00:00.570327 :B52BE018:db_trace:kcc.c@13684:kccwbp(): [10021:29:233:1] 0115000000000000 0115000000000000 7b51120000000000 2025-07-21 22:00:00.570695 :B52BE050:db_trace:ksb.c@6610:ksbcic_int(): [10254:29:233] KSBCIC: (USER) starting cic on opcode KSBCIBBS [20] with args (2 0 232056647) 2025-07-21 22:00:00.570701 :B52BE051:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-21 22:00:00.570726 :B52BE05C:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-21 22:00:00.579709 :B52BE0BC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:jslv.c:9203 2025-07-21 22:00:00.582633 :B52BE0BD: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-07-21 22:00:00.582689 :B52BE0C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-07-21 22:00:00.582823 :B52BE0CF: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 17876, J003)