Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250529220006\orcl_j001_14548_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:37263M/63366M, Ph+PgF:44179M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 14548, image: ORACLE.EXE (J001) *** 2025-05-29 22:00:06.149 *** SESSION ID:(209.56793) 2025-05-29 22:00:06.149 *** 2025-05-29 22:00:06.149 Process diagnostic dump for ORACLE.EXE (J001), OS id=14548, pid: 26, proc_ser: 94, sid: 209, sess_ser: 56793 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 14548 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.139823 sec, exc=0.139823 sec, total=0.139823 sec wait times: max=0.500000 sec, heur=2.665024 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.000005 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 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.000004 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.510098 sec, exc=0.510098 sec, total=0.510098 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.500006 sec, exc=0.500006 sec, total=0.500006 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499523 sec, exc=0.499523 sec, total=0.499523 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000004 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.016095 sec, exc=0.016095 sec, total=0.016095 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=0 seq_num=1 snap_id=1 wait times: snap=0.499449 sec, exc=0.499449 sec, total=0.499449 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 209 serial 56793 --------------------------------------------------- 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, 22:00:03 - 22:00:06] idle wait at each sample [session created at: 22:00:03] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-29 22:00:06.149 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 14548, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-29 21:57:01.555473 :9DBA8C26:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-29 21:57:01.556098 :9DBA8C27:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-29 21:57:01.563650 :9DBA8C2A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-29 21:57:01.562 2025-05-29 21:57:01.563651 :9DBA8C2B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13128 pso_num=26 pso_serial#=78 2025-05-29 21:57:01.563769 :9DBA8C2C:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13128 (legacy spawn) 2025-05-29 21:57:01.568974 :9DBA8C2D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 21:57:01.569096 :9DBA8C36:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-29 21:57:01.569661 :9DBA8C37:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-29 21:57:11.552905 :9DBA8F2D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-29 21:57:11.544 2025-05-29 21:57:11.552907 :9DBA8F2E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14224 pso_num=26 pso_serial#=79 2025-05-29 21:57:11.553055 :9DBA8F2F:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14224 (legacy spawn) 2025-05-29 21:57:11.559676 :9DBA8F30:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 21:57:11.559842 :9DBA8F35:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-29 21:57:11.560545 :9DBA8F3A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-29 21:57:18.286056 :9DBA918A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-29 21:57:18.272 2025-05-29 21:57:18.286058 :9DBA918B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12988 pso_num=26 pso_serial#=80 2025-05-29 21:57:18.286202 :9DBA918C:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12988 (legacy spawn) 2025-05-29 21:57:18.292654 :9DBA918D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 21:57:18.292795 :9DBA9196:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-29 21:57:18.293486 :9DBA9197:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-29 21:57:18.301280 :9DBA919A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-29 21:57:18.288 2025-05-29 21:57:18.301281 :9DBA919B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11320 pso_num=26 pso_serial#=81 2025-05-29 21:57:18.301416 :9DBA919C:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11320 (legacy spawn) 2025-05-29 21:57:18.307250 :9DBA919D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 21:57:18.307356 :9DBA91A6:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-29 21:57:18.307969 :9DBA91A7:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-29 21:57:27.233896 :9DBA9464:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-29 21:57:27.226 2025-05-29 21:57:27.233897 :9DBA9465:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14116 pso_num=26 pso_serial#=82 2025-05-29 21:57:27.234045 :9DBA9466:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14116 (legacy spawn) 2025-05-29 21:57:27.240701 :9DBA9467:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 21:57:27.240838 :9DBA9470:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-29 21:57:27.241545 :9DBA9471:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-29 21:58:10.728690 :9DBAA303:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-29 21:58:10.714 2025-05-29 21:58:10.728691 :9DBAA304:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14544 pso_num=26 pso_serial#=83 2025-05-29 21:58:10.728837 :9DBAA305:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14544 (legacy spawn) 2025-05-29 21:58:10.735329 :9DBAA306:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 21:58:10.735482 :9DBAA30C:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-29 21:58:10.736170 :9DBAA310:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-29 21:58:10.743891 :9DBAA313:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-29 21:58:10.729 2025-05-29 21:58:10.743893 :9DBAA314:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=10024 pso_num=26 pso_serial#=84 2025-05-29 21:58:10.744028 :9DBAA315:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=10024 (legacy spawn) 2025-05-29 21:58:10.750030 :9DBAA316:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 21:58:10.750153 :9DBAA31F:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-29 21:58:10.750769 :9DBAA320:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-29 21:58:13.740328 :9DBAA41A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-29 21:58:13.732 2025-05-29 21:58:13.740329 :9DBAA41B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11372 pso_num=26 pso_serial#=85 2025-05-29 21:58:13.740467 :9DBAA41C:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11372 (legacy spawn) 2025-05-29 21:58:13.746579 :9DBAA41D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 21:58:13.746716 :9DBAA422:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-29 21:58:13.747381 :9DBAA427:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-29 21:58:13.775550 :9DBAA42A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-29 21:58:13.764 2025-05-29 21:58:13.775552 :9DBAA42B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13436 pso_num=26 pso_serial#=86 2025-05-29 21:58:13.775686 :9DBAA42C:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13436 (legacy spawn) 2025-05-29 21:58:13.781384 :9DBAA42D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 21:58:13.781509 :9DBAA436:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-29 21:58:13.782126 :9DBAA437:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-29 21:58:13.789516 :9DBAA43A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-29 21:58:13.779 2025-05-29 21:58:13.789517 :9DBAA43B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14612 pso_num=26 pso_serial#=87 2025-05-29 21:58:13.789650 :9DBAA43C:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14612 (legacy spawn) 2025-05-29 21:58:13.795256 :9DBAA43D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 21:58:13.795362 :9DBAA446:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-29 21:58:13.795976 :9DBAA447:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-29 21:59:09.601135 :9DBAB755:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-29 21:59:09.589 2025-05-29 21:59:09.601136 :9DBAB756:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=8784 pso_num=26 pso_serial#=88 2025-05-29 21:59:09.601283 :9DBAB757:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=8784 (legacy spawn) 2025-05-29 21:59:09.607874 :9DBAB758:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 21:59:09.608017 :9DBAB760:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-29 21:59:09.608731 :9DBAB761:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-29 21:59:15.934352 :9DBAB971:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-29 21:59:15.926 2025-05-29 21:59:15.934353 :9DBAB972:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15104 pso_num=26 pso_serial#=89 2025-05-29 21:59:15.934500 :9DBAB973:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=15104 (legacy spawn) 2025-05-29 21:59:15.941190 :9DBAB974:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 21:59:15.941335 :9DBAB97D:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-29 21:59:15.942053 :9DBAB97E:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-29 21:59:22.949355 :9DBABBA6:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-29 21:59:22.934 2025-05-29 21:59:22.949356 :9DBABBA7:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9960 pso_num=26 pso_serial#=90 2025-05-29 21:59:22.949498 :9DBABBA8:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=9960 (legacy spawn) 2025-05-29 21:59:22.955882 :9DBABBA9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 21:59:22.956026 :9DBABBAF:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-29 21:59:22.956701 :9DBABBB3:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-29 21:59:22.964470 :9DBABBB6:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-29 21:59:22.950 2025-05-29 21:59:22.964471 :9DBABBB7:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13508 pso_num=26 pso_serial#=91 2025-05-29 21:59:22.964610 :9DBABBB8:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13508 (legacy spawn) 2025-05-29 21:59:22.970364 :9DBABBB9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 21:59:22.970470 :9DBABBC2:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-29 21:59:22.971088 :9DBABBC3:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-29 21:59:25.988401 :9DBABCB3:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-29 21:59:25.976 2025-05-29 21:59:25.988403 :9DBABCB4:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14772 pso_num=26 pso_serial#=92 2025-05-29 21:59:25.988539 :9DBABCB5:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14772 (legacy spawn) 2025-05-29 21:59:25.994553 :9DBABCB6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 21:59:25.994683 :9DBABCBB:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-29 21:59:25.995338 :9DBABCC0:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-29 21:59:26.002874 :9DBABCC3:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-29 21:59:25.991 2025-05-29 21:59:26.002875 :9DBABCC4:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15072 pso_num=26 pso_serial#=93 2025-05-29 21:59:26.003010 :9DBABCC5:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=15072 (legacy spawn) 2025-05-29 21:59:26.008630 :9DBABCC6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 21:59:26.008758 :9DBABCCF:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-29 21:59:26.009383 :9DBABCD0:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-29 22:00:01.639769 :9DBAC9E9:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-29 22:00:01.629 2025-05-29 22:00:01.639770 :9DBAC9EA:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14548 pso_num=26 pso_serial#=94 2025-05-29 22:00:01.639904 :9DBAC9EB:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=14548 osp_idx=24 osp_ver=354799 osp_pg=0 (spawn #232473) 2025-05-29 22:00:01.639904 :9DBAC9EC:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-29 22:00:01.639905 :9DBAC9ED:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=66 time=1531762407 2025-05-29 22:00:01.639905 :9DBAC9EE:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-29 22:00:01.639905 :9DBAC9EF:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-29 22:00:01.639906 :9DBAC9F0:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-29 22:00:01.639906 :9DBAC9F1:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-29 22:00:01.639906 :9DBAC9F2:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-29 22:00:01.640339 :9DBAC9F3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-29 22:00:01.645424 :9DBACA12:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:01.645456 :9DBACA15:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:jslv.c:9203 2025-05-29 22:00:01.650595 :9DBACA34:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:01.855387 :9DBACEC3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:02.030110 :9DBACED3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:02.143120 :9DBACEFF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.254565 :9DBAD0F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.255598 :9DBAD0F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.259605 :9DBAD101:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.262590 :9DBAD109:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.265542 :9DBAD124:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.267478 :9DBAD12C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.269204 :9DBAD134:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.274619 :9DBAD13C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.298398 :9DBAD144:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.299124 :9DBAD14C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.299786 :9DBAD154:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.300435 :9DBAD15C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.303490 :9DBAD164:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.304431 :9DBAD16C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-29 22:00:03.305254 :9DBAD16D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-29 22:00:03.306208 :9DBAD16E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-29 22:00:03.306651 :9DBAD16F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-29 22:00:03.306659 :9DBAD170:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-29 22:00:03.307266 :9DBAD171:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-29 22:00:03.307826 :9DBAD172:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-29 22:00:03.308132 :9DBAD173:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-29 22:00:03.309335 :9DBAD174:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-29 22:00:03.341373 :9DBAD175:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.341415 :9DBAD178:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-29 22:00:03.342339 :9DBAD17E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.343213 :9DBAD186:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-29 22:00:03.343620 :9DBAD187:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.361085 :9DBAD18F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.361125 :9DBAD192:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-29 22:00:03.361846 :9DBAD198:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.370579 :9DBAD1A0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-29 22:00:03.370958 :9DBAD1A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.390321 :9DBAD1A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.390357 :9DBAD1AC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-29 22:00:03.391119 :9DBAD1B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.391881 :9DBAD1BA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-29 22:00:03.392306 :9DBAD1BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.410419 :9DBAD1EB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.410457 :9DBAD1EE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-29 22:00:03.411271 :9DBAD1F4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.412109 :9DBAD1FC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-29 22:00:03.412570 :9DBAD1FD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.433959 :9DBAD205:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.434003 :9DBAD208:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-29 22:00:03.434808 :9DBAD20E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.435602 :9DBAD216:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-29 22:00:03.436152 :9DBAD217:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.456331 :9DBAD21F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.456368 :9DBAD222:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-29 22:00:03.457138 :9DBAD228:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.466755 :9DBAD230:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-05-29 22:00:03.467193 :9DBAD231:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.489351 :9DBAD239:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.489390 :9DBAD23C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-05-29 22:00:03.490176 :9DBAD242:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.490604 :9DBAD24A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.491006 :9DBAD252:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.491225 :9DBAD25A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.491394 :9DBAD262:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.491542 :9DBAD26A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:jslv.c:9203 2025-05-29 22:00:03.495654 :9DBAD26B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-29 22:00:03.495714 :9DBAD26E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-05-29 22:00:04.014559 :9DBAD36E:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 26 (osid: 14548, J001)