Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250726100348\orcl_j002_11720_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:36016M/63366M, Ph+PgF:41788M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 11720, image: ORACLE.EXE (J002) *** 2025-07-26 10:03:48.986 *** SESSION ID:(219.59865) 2025-07-26 10:03:48.986 *** 2025-07-26 10:03:49.001 Process diagnostic dump for ORACLE.EXE (J002), OS id=11720, pid: 27, proc_ser: 92, sid: 219, sess_ser: 59865 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 11720 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) 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.468939 sec, exc=0.468939 sec, total=0.468939 sec wait times: max=0.500000 sec, heur=6.524235 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000010 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.500005 sec, exc=0.500005 sec, total=0.500005 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 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.499994 sec, exc=0.499994 sec, total=0.499994 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=10 seq_num=11 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.000007 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.510093 sec, exc=0.510093 sec, total=0.510093 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 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.510103 sec, exc=0.510103 sec, total=0.510103 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 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.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.000007 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.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 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.498942 sec, exc=0.498942 sec, total=0.498942 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 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.032294 sec, exc=0.032294 sec, total=0.032294 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 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.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time Sampled Session History of session 219 serial 59865 --------------------------------------------------- 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, 10:03:42 - 10:03:49] idle wait at each sample [session created at: 10:03:42] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-26 10:03:49.001 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 11720, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-26 09:58:46.031820 :B72B77FF: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-26 09:58:46.031994 :B72B7808: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-26 09:58:46.032786 :B72B7809:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 09:58:46.041440 :B72B780C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 09:58:46.028 2025-07-26 09:58:46.041441 :B72B780D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4152 pso_num=27 pso_serial#=65 2025-07-26 09:58:46.041583 :B72B780E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4152 (legacy spawn) 2025-07-26 09:58:46.048165 :B72B780F: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-26 09:58:46.048340 :B72B7817: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-26 09:58:46.049164 :B72B7819:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 09:58:48.586831 :B72B78F3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 09:58:48.579 2025-07-26 09:58:48.586834 :B72B78F4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11764 pso_num=27 pso_serial#=66 2025-07-26 09:58:48.586972 :B72B78F5:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11764 (legacy spawn) 2025-07-26 09:58:48.593454 :B72B78F6: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-26 09:58:48.593623 :B72B78FB: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-26 09:58:48.594424 :B72B7900:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 09:59:01.954794 :B72B7D11:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 09:59:01.941 2025-07-26 09:59:01.954796 :B72B7D12:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10656 pso_num=27 pso_serial#=67 2025-07-26 09:59:01.954936 :B72B7D13:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10656 (legacy spawn) 2025-07-26 09:59:01.961466 :B72B7D14: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-26 09:59:01.961651 :B72B7D19: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-26 09:59:01.962464 :B72B7D1E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 09:59:01.971145 :B72B7D21:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 09:59:01.957 2025-07-26 09:59:01.971147 :B72B7D22:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18312 pso_num=27 pso_serial#=68 2025-07-26 09:59:01.971284 :B72B7D23:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18312 (legacy spawn) 2025-07-26 09:59:01.977732 :B72B7D24: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-26 09:59:01.977901 :B72B7D2D: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-26 09:59:01.978690 :B72B7D2E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 09:59:19.199536 :B72B8287:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 09:59:19.196 2025-07-26 09:59:19.199538 :B72B8288:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=1740 pso_num=27 pso_serial#=69 2025-07-26 09:59:19.199676 :B72B8289:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=1740 (legacy spawn) 2025-07-26 09:59:19.206102 :B72B828A: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-26 09:59:19.206279 :B72B828F: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-26 09:59:19.207108 :B72B8294:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 09:59:19.215798 :B72B8297:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 09:59:19.212 2025-07-26 09:59:19.215800 :B72B8298:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=3292 pso_num=27 pso_serial#=70 2025-07-26 09:59:19.215946 :B72B8299:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=3292 (legacy spawn) 2025-07-26 09:59:19.222411 :B72B829A: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-26 09:59:19.222573 :B72B82A2: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-26 09:59:19.223398 :B72B82A4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 09:59:19.683120 :B72B82A7:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 09:59:19.675 2025-07-26 09:59:19.683122 :B72B82A8:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11728 pso_num=27 pso_serial#=71 2025-07-26 09:59:19.683262 :B72B82A9:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11728 (legacy spawn) 2025-07-26 09:59:19.689710 :B72B82AA: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-26 09:59:19.689880 :B72B82B3: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-26 09:59:19.690739 :B72B82B4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 09:59:19.934152 :B72B8304:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 09:59:19.925 2025-07-26 09:59:19.934155 :B72B8305:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13728 pso_num=27 pso_serial#=72 2025-07-26 09:59:19.934298 :B72B8306:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13728 (legacy spawn) 2025-07-26 09:59:19.940786 :B72B8307: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-26 09:59:19.940951 :B72B8310: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-26 09:59:19.941747 :B72B8311:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 09:59:22.596339 :B72B83AC:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 09:59:22.583 2025-07-26 09:59:22.596342 :B72B83AD:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=2768 pso_num=27 pso_serial#=73 2025-07-26 09:59:22.596478 :B72B83AE:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=2768 (legacy spawn) 2025-07-26 09:59:22.602914 :B72B83AF: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-26 09:59:22.603082 :B72B83B4: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-26 09:59:22.603930 :B72B83B9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 09:59:22.612630 :B72B83BC:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 09:59:22.599 2025-07-26 09:59:22.612632 :B72B83BD:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14620 pso_num=27 pso_serial#=74 2025-07-26 09:59:22.612764 :B72B83BE:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14620 (legacy spawn) 2025-07-26 09:59:22.619317 :B72B83BF: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-26 09:59:22.619480 :B72B83C8: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-26 09:59:22.620306 :B72B83C9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 09:59:50.738843 :B72B8CFD:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 09:59:50.730 2025-07-26 09:59:50.738845 :B72B8CFE:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18604 pso_num=27 pso_serial#=75 2025-07-26 09:59:50.738988 :B72B8CFF:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18604 (legacy spawn) 2025-07-26 09:59:50.745429 :B72B8D00: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-26 09:59:50.745596 :B72B8D05: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-26 09:59:50.746447 :B72B8D0A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 09:59:54.078124 :B72B8E1D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 09:59:54.067 2025-07-26 09:59:54.078126 :B72B8E1E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14016 pso_num=27 pso_serial#=76 2025-07-26 09:59:54.078259 :B72B8E1F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14016 (legacy spawn) 2025-07-26 09:59:54.084770 :B72B8E20: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-26 09:59:54.084940 :B72B8E29: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-26 09:59:54.085774 :B72B8E2A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 09:59:55.691369 :B72B8E86:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 09:59:55.676 2025-07-26 09:59:55.691372 :B72B8E87:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14852 pso_num=27 pso_serial#=77 2025-07-26 09:59:55.691532 :B72B8E88:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14852 (legacy spawn) 2025-07-26 09:59:55.698556 :B72B8E89: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-26 09:59:55.698747 :B72B8E8E: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-26 09:59:55.699626 :B72B8E93:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 09:59:55.715721 :B72B8E96:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 09:59:55.707 2025-07-26 09:59:55.715723 :B72B8E97:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16104 pso_num=27 pso_serial#=78 2025-07-26 09:59:55.715868 :B72B8E98:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16104 (legacy spawn) 2025-07-26 09:59:55.722524 :B72B8E99: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-26 09:59:55.722714 :B72B8EA2: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-26 09:59:55.723571 :B72B8EA3:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 09:59:58.911994 :B72B8FCC:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 09:59:58.898 2025-07-26 09:59:58.911996 :B72B8FCD:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17124 pso_num=27 pso_serial#=79 2025-07-26 09:59:58.912135 :B72B8FCE:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17124 (legacy spawn) 2025-07-26 09:59:58.918660 :B72B8FCF: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-26 09:59:58.918842 :B72B8FD4: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-26 09:59:58.919663 :B72B8FD9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 09:59:58.928274 :B72B8FDC:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 09:59:58.914 2025-07-26 09:59:58.928276 :B72B8FDD:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18888 pso_num=27 pso_serial#=80 2025-07-26 09:59:58.928412 :B72B8FDE:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18888 (legacy spawn) 2025-07-26 09:59:58.934976 :B72B8FDF: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-26 09:59:58.935139 :B72B8FE8: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-26 09:59:58.935938 :B72B8FE9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 10:00:52.895842 :B72BA2A5:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 10:00:52.887 2025-07-26 10:00:52.895844 :B72BA2A6:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17880 pso_num=27 pso_serial#=81 2025-07-26 10:00:52.895979 :B72BA2A7:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17880 (legacy spawn) 2025-07-26 10:00:52.902805 :B72BA2A8: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-26 10:00:52.902999 :B72BA2AD: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-26 10:00:52.903992 :B72BA2B2:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 10:01:02.117637 :B72BA5C1:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 10:01:02.108 2025-07-26 10:01:02.117640 :B72BA5C2:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15164 pso_num=27 pso_serial#=82 2025-07-26 10:01:02.117776 :B72BA5C3:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15164 (legacy spawn) 2025-07-26 10:01:02.124300 :B72BA5C4: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-26 10:01:02.124501 :B72BA5C9: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-26 10:01:02.125393 :B72BA5CE:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 10:01:08.311985 :B72BA794:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 10:01:08.298 2025-07-26 10:01:08.311987 :B72BA795:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17856 pso_num=27 pso_serial#=83 2025-07-26 10:01:08.312124 :B72BA796:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17856 (legacy spawn) 2025-07-26 10:01:08.318827 :B72BA797: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-26 10:01:08.319006 :B72BA79C: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-26 10:01:08.319797 :B72BA7A1:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 10:01:08.328490 :B72BA7A4:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 10:01:08.329 2025-07-26 10:01:08.328492 :B72BA7A5:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15732 pso_num=27 pso_serial#=84 2025-07-26 10:01:08.328646 :B72BA7A6:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15732 (legacy spawn) 2025-07-26 10:01:08.335025 :B72BA7A7: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-26 10:01:08.335207 :B72BA7B0: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-26 10:01:08.336046 :B72BA7B1:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 10:01:11.389586 :B72BA8A5:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 10:01:11.376 2025-07-26 10:01:11.389589 :B72BA8A6:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18808 pso_num=27 pso_serial#=85 2025-07-26 10:01:11.389719 :B72BA8A7:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18808 (legacy spawn) 2025-07-26 10:01:11.396171 :B72BA8A8: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-26 10:01:11.396353 :B72BA8AD: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-26 10:01:11.397191 :B72BA8B2:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 10:01:11.405888 :B72BA8B5:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 10:01:11.392 2025-07-26 10:01:11.405890 :B72BA8B6:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=6356 pso_num=27 pso_serial#=86 2025-07-26 10:01:11.406065 :B72BA8B7:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=6356 (legacy spawn) 2025-07-26 10:01:11.427713 :B72BA8B8: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-26 10:01:11.427884 :B72BA8C1: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-26 10:01:11.428676 :B72BA8C2:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 10:01:44.597414 :B72BB339:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 10:01:44.583 2025-07-26 10:01:44.597417 :B72BB33A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16940 pso_num=27 pso_serial#=87 2025-07-26 10:01:44.597549 :B72BB33B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16940 (legacy spawn) 2025-07-26 10:01:44.604005 :B72BB33C: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-26 10:01:44.604171 :B72BB344: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-26 10:01:44.604982 :B72BB346:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 10:01:44.613665 :B72BB349:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 10:01:44.599 2025-07-26 10:01:44.613667 :B72BB34A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17488 pso_num=27 pso_serial#=88 2025-07-26 10:01:44.613807 :B72BB34B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17488 (legacy spawn) 2025-07-26 10:01:44.620242 :B72BB34C: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-26 10:01:44.620401 :B72BB355: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-26 10:01:44.621251 :B72BB356:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 10:01:47.694987 :B72BB439:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 10:01:47.682 2025-07-26 10:01:47.694989 :B72BB43A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18036 pso_num=27 pso_serial#=89 2025-07-26 10:01:47.695127 :B72BB43B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18036 (legacy spawn) 2025-07-26 10:01:47.714824 :B72BB43C: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-26 10:01:47.715004 :B72BB445: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-26 10:01:47.715906 :B72BB446:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 10:01:47.724673 :B72BB449:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 10:01:47.713 2025-07-26 10:01:47.724675 :B72BB44A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=3560 pso_num=27 pso_serial#=90 2025-07-26 10:01:47.724817 :B72BB44B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=3560 (legacy spawn) 2025-07-26 10:01:47.731309 :B72BB44C: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-26 10:01:47.731484 :B72BB455: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-26 10:01:47.732317 :B72BB456:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 10:02:10.413075 :B72BBB2F:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 10:02:10.413 2025-07-26 10:02:10.413077 :B72BBB30:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4800 pso_num=27 pso_serial#=91 2025-07-26 10:02:10.413215 :B72BBB31:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4800 (legacy spawn) 2025-07-26 10:02:10.419738 :B72BBB32: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-26 10:02:10.419908 :B72BBB37: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-26 10:02:10.420726 :B72BBB3C:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-26 10:03:42.476301 :B72BDDE1:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-26 10:03:42.471 2025-07-26 10:03:42.476303 :B72BDDE2:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11720 pso_num=27 pso_serial#=92 2025-07-26 10:03:42.476458 :B72BDDE3:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=11720 osp_idx=25 osp_ver=193645 osp_pg=0 (spawn #424176) 2025-07-26 10:03:42.476460 :B72BDDE4: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-26 10:03:42.476460 :B72BDDE5:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=29 time=2204998376 2025-07-26 10:03:42.476461 :B72BDDE6:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 10:03:42.476461 :B72BDDE7:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 10:03:42.476462 :B72BDDE8:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 10:03:42.476462 :B72BDDE9:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 10:03:42.476463 :B72BDDEA:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 10:03:42.477011 :B72BDDF4: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 2025-07-26 10:03:44.513101 :B72BE00A:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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 27 (osid: 11720, J002)