[20190418]exclusive latch spin count.txt

来源:https://www.cnblogs.com/lfree/archive/2019/04/19/10733902.html
-Advertisement-
Play Games

[20190418]exclusive latch spin count.txt--//昨天測試"process allocation" latch,主要這個latch與其它拴鎖spin方式有點不同,但是預設都是spin 20000.如何驗證一直是困擾我的問題.--//而且現在的模式是spin 一定 ...


[20190418]exclusive latch spin count.txt

--//昨天測試"process allocation" latch,主要這個latch與其它拴鎖spin方式有點不同,但是預設都是spin 20000.如何驗證一直是困擾我的問題.
--//而且現在的模式是spin 一定數量後,調用semop睡眠,等待喚醒.在這步消耗cpu資源很少.而不是像以前反覆spin,指數回退.
--//鏈接:http://andreynikolaev.wordpress.com/2011/01/06/spin-tales-part-1-exclusive-latches-in-oracle-9-2-11g/
--//對方的伺服器Solaris,不管是Solaris X86還是 Solaris sparc CPU,都可以使用dtrace工具探究,我沒有也不熟悉這個工具.他監測調用的函數如下:
LATCH_FUNC ADDR     LNAME
---------- -------- ------------------------
kslgetl    500063D0 first_spare_latch
...
kslgetl          -  KSL  GET exclusive Latch
sskgslgf         -  immediate latch get
kslges           - wait latch get
skgslsgts
sskgslspin       - spin for the latch
sskgslspin
sskgslspin
sskgslspin
sskgslspin
--//latch spin 使用sskgslspin函數調用,可是linux下使用intel cpu並沒有對應的oracle內部函數.
(gdb) b sskgslspin
Function "sskgslspin" not defined.
Make breakpoint pending on future shared library load? (y or [n]) y
Breakpoint 3 (sskgslspin) pending.

--//鏈接:https://fritshoogland.wordpress.com/2015/07/17/oracle-12-and-latches/
Some searching around revealed that a CPU register reveals this information. Add this to the above gdb script:

--//一些搜索顯示CPU寄存器顯示了這些信息。將其添加到上面的gdb腳本中：

break *0xc29b51
  commands
    silent
    printf " kslges loop: %d\n", $ecx
    c
  end

--//他沒有講如何獲得這個地址,僅僅給出1個線索這些信息在CPU寄存器裡面,如何探究呢?我通過我的測試說明.
--//首先說明一下我並不熟悉gdb調式工具.也不要在生產系統做這樣的測試!!

1.環境:
SCOTT@book> @ ver1
PORT_STRING                    VERSION        BANNER
------------------------------ -------------- --------------------------------------------------------------------------------
x86_64/Linux 2.4.xx            11.2.0.4.0     Oracle Database 11g Enterprise Edition Release 11.2.0.4.0 - 64bit Production

SYS@book> @ hide spin_count
NAME              DESCRIPTION                        DEFAULT_VALUE SESSION_VALUE SYSTEM_VALUE
----------------- ---------------------------------- ------------- ------------- ------------
_mutex_spin_count Mutex spin count                   TRUE          255           255
_spin_count       Amount to spin waiting for a latch TRUE          2000          2000

SYS@book> select CLASS_KSLLT,decode(CLASS_KSLLT,2,KSLLTNAM,3,KSLLTNAM) name,count(*) from x$kslltr group by  CLASS_KSLLT,decode(CLASS_KSLLT,2,KSLLTNAM,3,KSLLTNAM);
CLASS_KSLLT NAME               COUNT(*)
----------- ------------------ --------
          0                         581
          2 process allocation        1
--//前面我已經說過,僅僅process allocation latch比較特殊,使用latch clase=2.我個人認為這樣設計避免登錄出現阻塞.響應更快一些.
--//其它拴鎖都是latch class="0".
SYS@book> select * from x$ksllclass ;
ADDR                   INDX    INST_ID       SPIN      YIELD   WAITTIME     SLEEP0     SLEEP1     SLEEP2     SLEEP3     SLEEP4     SLEEP5     SLEEP6     SLEEP7
---------------- ---------- ---------- ---------- ---------- ---------- ---------- ---------- ---------- ---------- ---------- ---------- ---------- ----------
00000000861986C0          0          1      20000          0          1       8000       8000       8000       8000       8000       8000       8000       8000
00000000861986EC          1          1      20000          0          1       1000       1000       1000       1000       1000       1000       1000       1000
0000000086198718          2          1      20000          0          1       8000       8000       8000       8000       8000       8000       8000       8000
0000000086198744          3          1      20000          0          1       1000       1000       1000       1000       1000       1000       1000       1000
0000000086198770          4          1      20000          0          1       8000       8000       8000       8000       8000       8000       8000       8000
000000008619879C          5          1      20000          0          1       8000       8000       8000       8000       8000       8000       8000       8000
00000000861987C8          6          1      20000          0          1       8000       8000       8000       8000       8000       8000       8000       8000
00000000861987F4          7          1      20000          0          1       8000       8000       8000       8000       8000       8000       8000       8000
8 rows selected.
--//不管什麼latch class,預設spin count=20000,註意不是2000.

$ cat exclusive_latch.txt
/* 參數如下: @ exclusive_latch.txt latch_name willing why where sleep_num */
--//connect / as sysdba
col laddr new_value laddr
SELECT addr laddr FROM v$latch_parent WHERE NAME='&&1';
oradebug setmypid
oradebug call kslgetl 0x&laddr &&2 &&3 &&4
host sleep &&5
oradebug call kslfre 0x&laddr
--//exit
--//註:我前幾天的測試腳本有connect / as sysdba,exit這兩行,我為了調式方便,先註解這2行,避免反覆退出進入會話.
--//session 1:
SYS@book> @ exclusive_latch.txt "test excl. parent l0" 1 1 2 100000
LADDR
----------------
00000000600098D8

Statement processed.
Function returned 1
--//後面的參數是sleep的秒數,數值大一些,避免跟蹤時退出.想繼續按ctrl+c就可以中斷sleep.

--//session 2:
SYS@book> @ spid
       SID    SERIAL# PROCESS                  SERVER    SPID       PID  P_SERIAL# C50
---------- ---------- ------------------------ --------- ------ ------- ---------- --------------------------------------------------
        44         45 37744                    DEDICATED 37745       27         21 alter system kill session '44,45' immediate;
--//記下SPID=37745.在打開一個終端視窗執行如下:
--//暫且稱為window 3:
$ gdb -p 37745

--//session 2:
SYS@book> @ exclusive_latch.txt "test excl. parent l0" 1 3 4 1
--//掛起!!

--//windows 3:
(gdb) c
Continuing.

--//session 2:
SYS@book> @ exclusive_latch.txt "test excl. parent l0" 1 3 4 1
LADDR
----------------
00000000600098D8

Statement processed.

--//停在oradebug call kslgetl 調用,因為session 1目前持有該拴鎖.調用前幾天測試使用latch_free.sql腳本:
SYS@book> @ latch_free
2019-04-18 11:41:09
Process 26
 holding: 00000000600098D8  "test excl. parent l0" lvl=0 whr=2 why=1, SID=30
  Process 27, waiting for: 00000000600098D8 whr=4 why=3

--//回到window 3,按ctrl+c中斷:
(gdb) c
Continuing.
^C
Program received signal SIGINT, Interrupt.
0x00000037990d6407 in semop () from /lib64/libc.so.6
(gdb)
(gdb) bt 6
#0  0x00000037990d6407 in semop () from /lib64/libc.so.6
#1  0x0000000009809c0f in sskgpwwait ()
#2  0x00000000098089ce in skgpwwait ()
#3  0x00000000093f9fe1 in kslges ()
#4  0x00000000093f997a in kslgetl ()
#5  0x0000000007d7402e in skdxcall ()
(More stack frames follow...)
--//可以確定函數調用的堆棧或者稱為順序,當前停在semop睡眠上,可以發現調用kslgetl後,緊接著的是kslges.這樣猜測spin計數在調用kslges函數裡面.

2.重覆前面測試,在gdb下設置斷點:
--//在session 1按ctrl+c,退出window 3的gdb程式,重新執行gdb.
--//window 3:
$ rlwrap gdb -p 37745
(gdb)
(gdb) break kslges
Breakpoint 1 at 0x93f9b74
--//設置斷點在kslges函數調用上.然後在session 1,2分別執行(後面不再說明):
--//註:我前面加入rlwrap,主要記憶一些命令~/.gdb_history,避免反覆打入(主要原因有時候要退出gdb界面),實際上gdb是支持方向鍵的.
--//session 1:
SYS@book> @ exclusive_latch.txt "test excl. parent l0" 1 1 2 100000

--//session 2:
SYS@book> @ exclusive_latch.txt "test excl. parent l0" 1 3 4 1

--//再次掛起!在window 3,執行如下:
(gdb) c
Continuing.
Breakpoint 1, 0x00000000093f9b74 in kslges ()
(gdb)

(gdb) info register
rax            0x0      0
rbx            0x0      0
rcx            0x3      3
rdx            0x0      0
rsi            0x0      0
rdi            0x600098d8       1610651864
rbp            0x7fff93de4f40   0x7fff93de4f40
rsp            0x7fff93de4f40   0x7fff93de4f40
r8             0x4      4
r9             0x0      0
r10            0x0      0
r11            0xa      10
r12            0x600098d8       1610651864
r13            0x1      1
r14            0x3      3
r15            0x4      4
rip            0x93f9b74        0x93f9b74 <kslges+4>
--//這裡應該指向下一條執行的地址.
eflags         0x246    [ PF ZF IF ]
cs             0x33     51
ss             0x2b     43
ds             0x0      0
es             0x0      0
fs             0x0      0
gs             0x0      0
fctrl          0x27f    639
fstat          0x0      0
ftag           0xffff   65535
fiseg          0x0      0
fioff          0x9847daa        159677866
foseg          0x7fff   32767
fooff          0x93de38d0       -1814153008
fop            0x0      0
mxcsr          0x1fa0   [ PE IM DM ZM OM UM PM ]

--//到底那個寄存器是spin計數呢?到目前根本看不出來.
(gdb) info register ecx
Invalid register `ecx'
--//昏,根本沒有ecx這個寄存器,難道對方伺服器不是intel系列的嗎?
(gdb) set pagination off
(gdb) help alias
Aliases of other commands.

List of commands:

ni -- Step one instruction
--//ni 表示 Step one instruction
rc -- Continue program being debugged but run it in reverse
rni -- Step backward one instruction
rsi -- Step backward exactly one instruction
si -- Step one instruction exactly
stepping -- Specify single-stepping behavior at a tracepoint
tp -- Set a tracepoint at specified line or function
tty -- Set terminal for future runs of program being debugged
where -- Print backtrace of all stack frames
ws -- Specify single-stepping behavior at a tracepoint

Type "help" followed by command name for full documentation.
Type "apropos word" to search for commands related to "word".
Command name abbreviations are allowed if unambiguous.

--//如果ni,info register交替執行明顯太慢.spin至少20000次呢,
--//如果我執行ni 1000應該不會錯過什麼,可以這時看寄存器應該猜測spin count在那個寄存器中.

(gdb) ni 1000
0x00000000093f9dfb in kslges ()

(gdb) info register
rax            0x4dc0   19904
rbx            0x0      0
rcx            0x4dbe   19902
rdx            0x100    256
rsi            0x0      0
rdi            0x1a     26
rbp            0x7fff93de4f40   0x7fff93de4f40
rsp            0x7fff93de4c00   0x7fff93de4c00
r8             0x861ca808       2250024968
r9             0x19c    412
r10            0x0      0
r11            0x1b     27
r12            0x8620f490       2250306704
r13            0x600098d8       1610651864
r14            0x4e20   20000
r15            0x1b     27
rip            0x93f9dfb        0x93f9dfb <kslges+651>
eflags         0x217    [ CF PF AF IF ]
cs             0x33     51
ss             0x2b     43
ds             0x0      0
es             0x0      0
fs             0x0      0
gs             0x0      0
fctrl          0x27f    639
fstat          0x0      0
ftag           0xffff   65535
fiseg          0x0      0
fioff          0x9847daa        159677866
foseg          0x7fff   32767
fooff          0x93de38d0       -1814153008
fop            0x0      0
mxcsr          0x1fa0   [ PE IM DM ZM OM UM PM ]
--//^_^,明顯spin count保存在rax,rcx寄存器中.現在必須通過rip地址確定迴圈的開頭(實際上直接拿這個地址測試也可以的,畢竟每次spin 循
--//環都會停在這裡)

(gdb) ni
0x00000000093f9dfe in kslges ()
(gdb) info register rax rcx rip
rax            0x4dbf   19903
rcx            0x4dbe   19902
rip            0x93f9dfe        0x93f9dfe <kslges+654>
(gdb) ni
0x00000000093f9ddc in kslges ()
(gdb) info register rax rcx rip
rax            0x4dbf   19903
rcx            0x4dbe   19902
rip            0x93f9ddc        0x93f9ddc <kslges+620>

--//運氣太好了!!註意看rax的變化從19904=>19903.以及rip變小了,可以確定迴圈開頭在0x93f9ddc地址.
--//下班,下午繼續.
--//看看1個spin迴圈需要多少指令.
(gdb) info register rax rcx rip
rax            0x4dbf   19903
rcx            0x4dbe   19902
rip            0x93f9dfe        0x93f9dfe <kslges+654>
(gdb) ni
0x00000000093f9ddc in kslges ()
(gdb) info register rax rcx rip
rax            0x4dbf   19903
rcx            0x4dbe   19902
rip            0x93f9ddc        0x93f9ddc <kslges+620>
(gdb) ni
0x00000000093f9dde in kslges ()
(gdb) info register rax rcx rip
rax            0x4dbf   19903
rcx            0x4dbe   19902
rip            0x93f9dde        0x93f9dde <kslges+622>
(gdb) ni
0x00000000093f9de4 in kslges ()
(gdb) info register rax rcx rip
rax            0x4dbf   19903
rcx            0x4dbe   19902
rip            0x93f9de4        0x93f9de4 <kslges+628>
(gdb) ni
0x00000000093f9deb in kslges ()
(gdb) info register rax rcx rip
rax            0x4dbf   19903
rcx            0x4dbe   19902
rip            0x93f9deb        0x93f9deb <kslges+635>
(gdb) ni
0x00000000093f9def in kslges ()
(gdb) info register rax rcx rip
rax            0x4dbf   19903
rcx            0x4dbe   19902
rip            0x93f9def        0x93f9def <kslges+639>
(gdb) ni
0x00000000093f9df2 in kslges ()
(gdb) info register rax rcx rip
rax            0x4dbf   19903
rcx            0x4dbe   19902
rip            0x93f9df2        0x93f9df2 <kslges+642>
(gdb) ni
0x00000000093f9df8 in kslges ()
(gdb) info register rax rcx rip
rax            0x4dbf   19903
rcx            0x4dbe   19902
rip            0x93f9df8        0x93f9df8 <kslges+648>
(gdb) ni
0x00000000093f9dfb in kslges ()
(gdb) info register rax rcx rip
rax            0x4dbf   19903
rcx            0x4dbd   19901
rip            0x93f9dfb        0x93f9dfb <kslges+651>
(gdb) ni
0x00000000093f9dfe in kslges ()
(gdb) info register rax rcx rip
rax            0x4dbe   19902
rcx            0x4dbd   19901
rip            0x93f9dfe        0x93f9dfe <kslges+654>
--//一共需要9條指令.有了這些信息,可以寫出gbd腳本.
(gdb) disassemble kslges
--//反彙編看看.
...
0x00000000093f9ddc <kslges+620>:        xor    %esi,%esi
0x00000000093f9dde <kslges+622>:        mov    %esi,-0xd8(%rbp)
0x00000000093f9de4 <kslges+628>:        mov    %sil,-0xa6(%rbp)
0x00000000093f9deb <kslges+635>:        mov    0x0(%r13),%rdi
0x00000000093f9def <kslges+639>:        test   %rdi,%rdi
0x00000000093f9df2 <kslges+642>:        je     0x93fa6c0 <kslges+2896>
0x00000000093f9df8 <kslges+648>:        add    $0xffffffffffffffff,%ecx
0x00000000093f9dfb <kslges+651>:        add    $0xffffffffffffffff,%eax
0x00000000093f9dfe <kslges+654>:        jne    0x93f9ddc <kslges+620>
...
--//^_^.看不懂,估計這個$0xffffffffffffffff表示-1,明白了我前面ecx對應這裡,如何顯示呢?不懂.
--//我寫入    printf " spin count loop: %d\n", $ecx報錯!!
Program received signal SIGSEGV, Segmentation fault.
0x0000000009805bd5 in slaac_int ()
--//實例崩潰了.
$ sqlplus -prelim /nolog
SQL*Plus: Release 11.2.0.4.0 Production on Thu Apr 18 16:23:45 2019
Copyright (c) 1982, 2013, Oracle.  All rights reserved.

@> connect sys as sysdba
Enter password:
Prelim connection established
SYS@book> shutdown immediate ;
ORA-01012: not logged on
SYS@book> shutdown abort;
ORACLE instance shut down.
--//註:作者有說明硬體=SGI system.

3.重覆前面測試,先寫出gdb腳本:
$ cat spin.gdb
break kslgetl
  commands
    silent
    printf "kslgetl %x, %d, %d, %d\n", $rdi, $rsi, $rdx, $rcx
    c
  end

break kslges
  commands
    silent
    printf "kslges %x, %d, %d, %d\n", $rdi, $rsi, $rdx, $rcx
    c
  end

break skgpwwait
  commands
    silent
    printf "skgpwwait %d, %d, %d, %d\n", $rdi, $rsi, $rdx, $rcx
    c
  end

break sskgpwwait
  commands
    silent
    printf "sskgpwwait %d, %d, %d, %d\n", $rdi, $rsi, $rdx, $rcx
    c
  end

break semop
  commands
    silent
    printf "semop %d, %d, %d, %d\n", $rdi, $rsi, $rdx, $rcx
    c
  end

break *0x93f9ddc
  commands
    silent
    printf " spin count loop: %d %d %x\n", $rax,$rcx,$rip
    c
  end

#0  0x00000037990d6407 in semop () from /lib64/libc.so.6
#1  0x0000000009809c0f in sskgpwwait ()
#2  0x00000000098089ce in skgpwwait ()
#3  0x00000000093f9fe1 in kslges ()
#4  0x00000000093f997a in kslgetl ()

--//重覆測試:
--//window 3:
$ gdb -p 37745 -x spin.gdb

--//session 1:
SYS@book> @ exclusive_latch.txt "test excl. parent l0" 1 1 2 100000

--//session 2:
SYS@book> @ exclusive_latch.txt "test excl. parent l0" 1 3 4 1

--//window 3:
kslgetl 600098d8, 1, 3, 4
kslges 600098d8, 0, 0, 3
 spin count loop: 20000 19999 93f9ddc
 spin count loop: 19999 19998 93f9ddc
 spin count loop: 19998 19997 93f9ddc
 spin count loop: 19997 19996 93f9ddc
 spin count loop: 19996 19995 93f9ddc
 spin count loop: 19995 19994 93f9ddc
 spin count loop: 19994 19993 93f9ddc
 spin count loop: 19993 19992 93f9ddc
 spin count loop: 19992 19991 93f9ddc
 spin count loop: 19991 19990 93f9ddc
 spin count loop: 19990 19989 93f9ddc
 spin count loop: 19989 19988 93f9ddc
.....
--//不斷按return繼續...
 spin count loop: 2 1 93f9ddc
 spin count loop: 1 0 93f9ddc
skgpwwait -1814147480, 202182304, -2044659696, 0
sskgpwwait -1814147480, 202182304, -2044659696, 0
semop 314408960, -1814148224, 1, -1
 spin count loop: 20000 19999 93f9ddc
 spin count loop: 19999 19998 93f9ddc
.....
--//不斷按return繼續...
 spin count loop: 4 3 93f9ddc
 spin count loop: 3 2 93f9ddc
 spin count loop: 2 1 93f9ddc
 spin count loop: 1 0 93f9ddc
skgpwwait -1814147480, 202182304, -2044659696, 0
sskgpwwait -1814147480, 202182304, -2044659696, 0
semop 314408960, -1814148224, 1, -1

--//session 1:
--//按ctrl+c中斷.
SYS@book> @ exclusive_latch.txt "test excl. parent l0" 1 1 2 100000
old   1: SELECT addr laddr FROM v$latch_parent WHERE NAME='&&1'
new   1: SELECT addr laddr FROM v$latch_parent WHERE NAME='test excl. parent l0'
LADDR
----------------
00000000600098D8
Statement processed.
Function returned 1
Function returned 0

--//session 2等1秒也執行完成.
SYS@book> @ exclusive_latch.txt "test excl. parent l0" 1 3 4 1
LADDR
----------------
00000000600098D8
Statement processed.
Function returned 1
Function returned 0
SYS@book>

--//window 3界面顯示如下:
semop 314408960, -1814148224, 1, -1

 spin count loop: 20000 19999 93f9ddc

--//執行完成.可以發現執行2次周期 ,每個周期20000次,第3次獲得.為什麼是2次呢?
--//我再次重覆測試:

(gdb) info break 6
Num     Type           Disp Enb Address            What
6       breakpoint     keep y   0x00000000093f9ddc <kslges+620>
        breakpoint already hit 20001 times
        silent
        printf " spin count loop: %d %d %x\n", $rax,$rcx,$rip
        c
--//就只有20001次.

4.換一種方式測試:
--//定製spin次數如下:
*._spin_count=20
SYS@book> startup pfile=/tmp/@.ora
ORACLE instance started.
Total System Global Area  643084288 bytes
Fixed Size                  2255872 bytes
Variable Size             205521920 bytes
Database Buffers          427819008 bytes
Redo Buffers                7487488 bytes
Database mounted.
Database opened.

SYS@book> select * from x$ksllclass ;
ADDR                   INDX    INST_ID       SPIN      YIELD   WAITTIME     SLEEP0     SLEEP1     SLEEP2     SLEEP3     SLEEP4     SLEEP5     SLEEP6     SLEEP7
---------------- ---------- ---------- ---------- ---------- ---------- ---------- ---------- ---------- ---------- ---------- ---------- ---------- ----------
00000000861986C0          0          1         20          0          1       8000       8000       8000       8000       8000       8000       8000       8000
00000000861986EC          1          1         20          0          1       1000       1000       1000       1000       1000       1000       1000       1000
0000000086198718          2          1         20          0          1       8000       8000       8000       8000       8000       8000       8000       8000
0000000086198744          3          1         20          0          1       1000       1000       1000       1000       1000       1000       1000       1000
0000000086198770          4          1         20          0          1       8000       8000       8000       8000       8000       8000       8000       8000
000000008619879C          5          1         20          0          1       8000       8000       8000       8000       8000       8000       8000       8000
00000000861987C8          6          1         20          0          1       8000       8000       8000       8000       8000       8000       8000       8000
00000000861987F4          7          1         20          0          1       8000       8000       8000       8000       8000       8000       8000       8000
8 rows selected.
--//重覆測試,細節不再列出,僅僅記錄gdb輸出.

(gdb) c
Continuing.
kslgetl 6010d860, 1, 2087607608, 3991
kslgetl 6010d860, 1, 2087558280, 3991
kslgetl 6010d860, 1, 0, 4039
kslgetl 6010d860, 1, 0, 3980
kslgetl 6010d860, 1, 0, 4039
kslgetl 6010d860, 1, 2087563160, 3991
kslgetl 6010d860, 1, 2087572104, 3991
kslgetl 600098d8, 1, 3, 4
kslges 600098d8, 0, 0, 3
 spin count loop: 20 19 93f9ddc
 spin count loop: 19 18 93f9ddc
 spin count loop: 18 17 93f9ddc
 spin count loop: 17 16 93f9ddc
 spin count loop: 16 15 93f9ddc
 spin count loop: 15 14 93f9ddc
 spin count loop: 14 13 93f9ddc
 spin count loop: 13 12 93f9ddc
 spin count loop: 12 11 93f9ddc
 spin count loop: 11 10 93f9ddc
 spin count loop: 10 9 93f9ddc
 spin count loop: 9 8 93f9ddc
 spin count loop: 8 7 93f9ddc
 spin count loop: 7 6 93f9ddc
 spin count loop: 6 5 93f9ddc
 spin count loop: 5 4 93f9ddc
 spin count loop: 4 3 93f9ddc
 spin count loop: 3 2 93f9ddc
 spin count loop: 2 1 93f9ddc
 spin count loop: 1 0 93f9ddc
skgpwwait -1031167592, 202182304, -2044672536, 0
sskgpwwait -1031167592, 202182304, -2044672536, 0
semop 314933248, -1031168336, 1, -1
 spin count loop: 20 19 93f9ddc
 spin count loop: 19 18 93f9ddc
 spin count loop: 18 17 93f9ddc
 spin count loop: 17 16 93f9ddc
 spin count loop: 16 15 93f9ddc
 spin count loop: 15 14 93f9ddc
 spin count loop: 14 13 93f9ddc
 spin count loop: 13 12 93f9ddc
 spin count loop: 12 11 93f9ddc
 spin count loop: 11 10 93f9ddc
 spin count loop: 10 9 93f9ddc
 spin count loop: 9 8 93f9ddc
 spin count loop: 8 7 93f9ddc
 spin count loop: 7 6 93f9ddc
 spin count loop: 6 5 93f9ddc
 spin count loop: 5 4 93f9ddc
 spin count loop: 4 3 93f9ddc
 spin count loop: 3 2 93f9ddc
 spin count loop: 2 1 93f9ddc
 spin count loop: 1 0 93f9ddc
skgpwwait -1031167592, 202182304, -2044672536, 0
sskgpwwait -1031167592, 202182304, -2044672536, 0
semop 314933248, -1031168336, 1, -1
 spin count loop: 20 19 93f9ddc
--//第1次執行一共2次spin周期2次,每個周期20次.從另外一個方面驗證spin 計數來之視圖select * from x$ksllclass ;.
(gdb) info break 6
Num     Type           Disp Enb Address            What
6       breakpoint     keep y   0x00000000093f9ddc <kslges+620>
        breakpoint already hit 41 times
        silent
        printf " spin count loop: %d %d %x\n", $rax,$rcx,$rip
        c
--//breakpoint already hit 41 times,如果接著重覆測試:
(gdb) c
Continuing.
kslgetl 600098d8, 1, 3, 4
kslges 600098d8, 0, 0, 3
 spin count loop: 20 19 93f9ddc
 spin count loop: 19 18 93f9ddc
 spin count loop: 18 17 93f9ddc
 spin count loop: 17 16 93f9ddc
 spin count loop: 16 15 93f9ddc
 spin count loop: 15 14 93f9ddc
 spin count loop: 14 13 93f9ddc
 spin count loop: 13 12 93f9ddc
 spin count loop: 12 11 93f9ddc
 spin count loop: 11 10 93f9ddc
 spin count loop: 10 9 93f9ddc
 spin count loop: 9 8 93f9ddc
 spin count loop: 8 7 93f9ddc
 spin count loop: 7 6 93f9ddc
 spin count loop: 6 5 93f9ddc
 spin count loop: 5 4 93f9ddc
 spin count loop: 4 3 93f9ddc
 spin count loop: 3 2 93f9ddc
 spin count loop: 2 1 93f9ddc
 spin count loop: 1 0 93f9ddc
skgpwwait -1031167592, 202182304, -2044672536, 0
sskgpwwait -1031167592, 202182304, -2044672536, 0
semop 314933248, -1031168336, 1, -1
 spin count loop: 20 19 93f9ddc
--//這次僅僅spin周期1次.
(gdb) info break 6
Num     Type           Disp Enb Address            What
6       breakpoint     keep y   0x00000000093f9ddc <kslges+620>
        breakpoint already hit 62 times
        silent
        printf " spin count loop: %d %d %x\n", $rax,$rcx,$rip
        c
--//breakpoint already hit 62 times,也就是最後1次執行break 21次.

5.繼續定製spin次數,採用不同類看看:
select addr,name,level#,latch#,gets,misses,sleeps,immediate_gets,immediate_misses,waiters_woken,waits_holding_latch,spin_gets,wait_time from v$latch_parent   where lower(name) like '%'||lower('test excl. parent l0')||'%'
ADDR             NAME                 LEVEL#     LATCH#       GETS     MISSES     SLEEPS IMMEDIATE_GETS IMMEDIATE_MISSES WAITERS_WOKEN WAITS_HOLDING_LATCH  SPIN_GETS  WAIT_TIME
---------------- -------------------- ------ ---------- ---------- ---------- ---------- -------------- ---------------- ------------- ------------------- ---------- ----------
00000000600098D8 test excl. parent l0      0          4         64         30         39              0                0             0                   0          7 1.4769E+10
--//LATCH#=4
--//定製spin次數如下,修改參數文件加入:
#*._spin_count=20
*._latch_classes='4:3'
*._latch_class_3='10 0 1 10000 20000 30000 40000 50000 60000 70000 50000'

SYS@book> startup pfile=/tmp/@.ora
ORACLE instance started.
Total System Global Area  643084288 bytes
Fixed Size                  2255872 bytes
Variable Size             205521920 bytes
Database Buffers          427819008 bytes
Redo Buffers                7487488 bytes
Database mounted.
Database opened.

SYS@book> select * from x$ksllclass ;
ADDR                   INDX    INST_ID       SPIN      YIELD   WAITTIME     SLEEP0     SLEEP1     SLEEP2     SLEEP3     SLEEP4     SLEEP5     SLEEP6     SLEEP7
---------------- ---------- ---------- ---------- ---------- ---------- ---------- ---------- ---------- ---------- ---------- ---------- ---------- ----------
00000000861986C0          0          1      20000          0          1       8000       8000       8000       8000       8000       8000       8000       8000
00000000861986EC          1          1      20000          0          1       1000       1000       1000       1000       1000       1000       1000       1000
0000000086198718          2          1      20000          0          1       8000       8000       8000       8000       8000       8000       8000       8000
0000000086198744          3          1         10          0          1      10000      20000      30000      40000      50000      60000      70000      50000
0000000086198770          4          1      20000          0          1       8000       8000       8000       8000       8000       8000       8000       8000
000000008619879C          5          1      20000          0          1       8000       8000       8000       8000       8000       8000       8000       8000
00000000861987C8          6          1      20000          0          1       8000       8000       8000       8000       8000       8000       8000       8000
00000000861987F4          7          1      20000          0          1       8000       8000       8000       8000       8000       8000       8000       8000
8 rows selected.
--//SPIN=10.
--//重覆測試,細節不再列出,僅僅記錄gdb輸出.

kslgetl 6010d860, 1, 2087529088, 3991
kslgetl 80641188, 1, 0, 4174
kslgetl 6010d860, 1, 2087479760, 3991
kslgetl 6010d860, 1, 0, 4039
kslgetl 6010d860, 1, 0, 3980
kslgetl 6010d860, 1, 0, 4039
kslgetl 6010d860, 1, 2087484640, 3991
kslgetl 6010d860, 1, 2087493584, 3991
kslgetl 600098d8, 1, 3, 4
kslges 600098d8, 0, 0, 3
 spin count loop: 10 9 93f9ddc
 spin count loop: 9 8 93f9ddc
 spin count loop: 8 7 93f9ddc
 spin count loop: 7 6 93f9ddc
 spin count loop: 6 5 93f9ddc
 spin count loop: 5 4 93f9ddc
 spin count loop: 4 3 93f9ddc
 spin count loop: 3 2 93f9ddc
 spin count loop: 2 1 93f9ddc
 spin count loop: 1 0 93f9ddc
skgpwwait 1986434424, 202182304, 0, 10000
 spin count loop: 10 9 93f9ddc
 spin count loop: 9 8 93f9ddc
 spin count loop: 8 7 93f9ddc
 spin count loop: 7 6 93f9ddc
 spin count loop: 6 5 93f9ddc
 spin count loop: 5 4 93f9ddc
 spin count loop: 4 3 93f9ddc
 spin count loop: 3 2 93f9ddc
 spin count loop: 2 1 93f9ddc
 spin count loop: 1 0 93f9ddc
skgpwwait 1986434424, 202182304, 0, 20000
 spin count loop: 10 9 93f9ddc
 spin count loop: 9 8 93f9ddc
 spin count loop: 8 7 93f9ddc
 spin count loop: 7 6 93f9ddc
 spin count loop: 6 5 93f9ddc
 spin count loop: 5 4 93f9ddc
 spin count loop: 4 3 93f9ddc
 spin count loop: 3 2 93f9ddc
 spin count loop: 2 1 93f9ddc
 spin count loop: 1 0 93f9ddc
skgpwwait 1986434424, 202182304, 0, 30000
 spin count loop: 10 9 93f9ddc
 spin count loop: 9 8 93f9ddc
 spin count loop: 8 7 93f9ddc
 spin count loop: 7 6 93f9ddc
 spin count loop: 6 5 93f9ddc
 spin count loop: 5 4 93f9ddc
 spin count loop: 4 3 93f9ddc
 spin count loop: 3 2 93f9ddc
 spin count loop: 2 1 93f9ddc
 spin count loop: 1 0 93f9ddc
skgpwwait 1986434424, 202182304, 0, 40000
 spin count loop: 10 9 93f9ddc
 spin count loop: 9 8 93f9ddc
 spin count loop: 8 7 93f9ddc
 spin count loop: 7 6 93f9ddc
 spin count loop: 6 5 93f9ddc
 spin count loop: 5 4 93f9ddc
 spin count loop: 4 3 93f9ddc
 spin count loop: 3 2 93f9ddc
 spin count loop: 2 1 93f9ddc
 spin count loop: 1 0 93f9ddc
skgpwwait 1986434424, 202182304, 0, 50000
 spin count loop: 10 9 93f9ddc
 spin count loop: 9 8 93f9ddc
 spin count loop: 8 7 93f9ddc
 spin count loop: 7 6 93f9ddc
 spin count loop: 6 5 93f9ddc
 spin count loop: 5 4 93f9ddc
 spin count loop: 4 3 93f9ddc
 spin count loop: 3 2 93f9ddc
 spin count loop: 2 1 93f9ddc
 spin count loop: 1 0 93f9ddc
skgpwwait 1986434424, 202182304, 0, 60000
 spin count loop: 10 9 93f9ddc
 spin count loop: 9 8 93f9ddc
 spin count loop: 8 7 93f9ddc
 spin count loop: 7 6 93f9ddc
 spin count loop: 6 5 93f9ddc
 spin count loop: 5 4 93f9ddc
 spin count loop: 4 3 93f9ddc
 spin count loop: 3 2 93f9ddc
 spin count loop: 2 1 93f9ddc
 spin count loop: 1 0 93f9ddc
skgpwwait 1986434424, 202182304, 0, 70000
 spin count loop: 10 9 93f9ddc
 spin count loop: 9 8 93f9ddc
 spin count loop: 8 7 93f9ddc
 spin count loop: 7 6 93f9ddc
 spin count loop: 6 5 93f9ddc
 spin count loop: 5 4 93f9ddc
 spin count loop: 4 3 93f9ddc
 spin count loop: 3 2 93f9ddc
 spin count loop: 2 1 93f9ddc
 spin count loop: 1 0 93f9ddc
skgpwwait 1986434424, 202182304, 0, 50000
 spin count loop: 10 9 93f9ddc
 spin count loop: 9 8 93f9ddc
 spin count loop: 8 7 93f9ddc
 spin count loop: 7 6 93f9ddc
 spin count loop: 6 5 93f9ddc
 spin count loop: 5 4 93f9ddc
 spin count loop: 4 3 93f9ddc
 spin count loop: 3 2 93f9ddc
 spin count loop: 2 1 93f9ddc
 spin count loop: 1 0 93f9ddc
skgpwwait 1986434424, 202182304, 0, 50000
 spin count loop: 10 9 93f9ddc
 spin count loop: 9 8 93f9ddc
 spin count loop: 8 7 93f9ddc
 spin count loop: 7 6 93f9ddc
 spin count loop: 6 5 93f9ddc
 spin count loop: 5 4 93f9ddc
 spin count loop: 4 3 93f9ddc
 spin count loop: 3 2 93f9ddc
 spin count loop: 2 1 93f9ddc
 spin count loop: 1 0 93f9ddc
...

(gdb) info break 6
Num     Type           Disp Enb Address            What
6       breakpoint     keep y   0x00000000093f9ddc <kslges+620>
        breakpoint already hit 199 times
        silent
        printf " spin count loop: %d %d %x\n", $rax,$rcx,$rip
        c
(gdb) bt
#0  0x00000037990ce183 in __select_nocancel () from /lib64/libc.so.6
#1  0x0000000002d9751c in skgpnap ()
#2  0x0000000009808a7a in skgpwwait ()
#3  0x00000000093fa63b in kslges ()
#4  0x00000000093f997a in kslgetl ()
#5  0x0000000007d7402e in skdxcall ()
#6  0x00000000076c96aa in ksdxcall ()
#7  0x00000000076cdbcb in ksdxen_int ()
#8  0x00000000076d11a0 in ksdxen ()
#9  0x00000000095bbdad in opiodr ()
#10 0x00000000097a629f in ttcpip ()
#11 0x000000000186470e in opitsk ()
#12 0x0000000001869235 in opiino ()
#13 0x00000000095bbdad in opiodr ()
#14 0x00000000018607ac in opidrv ()
#15 0x0000000001e3a48f in sou2o ()
#16 0x0000000000a29265 in opimai_real ()
#17 0x0000000001e407ad in ssthrdmain ()
#18 0x0000000000a291d1 in main ()
--//這種方式不斷的spin迴圈,獲取拴鎖.

總結:
1.不要在生產系統做這樣測試.
2.主要是自己不熟悉gdb調式工具
3.又寫太長,我主要記錄比較詳細,避免以後看不懂^_^.
4.可以看出latch 獲取的變化,預設都是20000次,對於exclusive latch.
5.我個人不主張定製化解決這類拴鎖的問題,應該從應用著手,比如優化sql語句,減少執行測試等等.
6.明天分析shared latch,按照andreynikolaev.wordpress.com介紹是2*_spin_count次數.明天驗證看看.
7.實際上僅僅記住現在latch機制與原來的不同,不再使用原來的指數回退sleep機制.而是僅僅spin 20000次,然後執行semop,等待喚醒.
--//為什麼前面測試出現2次spin周期,我還不是很清楚...
--//再補充一個例子說明:
$ cat exclusive_latch.txt
/* 參數如下: @ exclusive_latch.txt latch_name willing why where sleep_num */
--//connect / as sysdba
col laddr new_value laddr
SELECT addr laddr FROM v$latch_parent WHERE NAME='&&1';
oradebug setmypid
oradebug call kslgetl 0x&laddr &&2 &&3 &&4
host sleep &&5
oradebug call kslfre 0x&laddr
exit

$ cat p6.sh
#! /bin/bash
vdate=$(date '+%Y%m%d%H%M%S')
echo $vdate

source peek.sh "$1" 20 | timestamp.pl >| /tmp/peekx_${vdate}.txt &

sqlplus -s -l / as sysdba <<EOF  >| /tmp/latch_free_${vdate}.txt &
$(seq 20 | xargs -I {} echo -e '@latch_free \n host sleep 1')
EOF

sleep 1
# 參數如下: @ exclusive_latch.txt latch_name willing why where  sleep_num
sqlplus / as sysdba @ exclusive_latch.txt "$1" 1 4 5 10 > /dev/null &
p=$!
strace -fttT  -p $p -o /tmp/pp_${vdate}_${p}.txt > /dev/null &
sleep 2
sqlplus / as sysdba @ exclusive_latch.txt "$1" 1 6 7 5 > /dev/null &
p=$!
strace -fttT  -p $p -o /tmp/pp_${vdate}_${p}.txt > /dev/null &
wait

$ . p6.sh "test excl. parent l0"
20190419090713
Process 30017 attached - interrupt to quit
Process 30020 attached
Process 30023 attached
Process 30017 suspended
Process 30026 attached - interrupt to quit
Process 30028 attached
Process 30017 resumed
Process 30023 detached
Process 30047 attached
Process 30026 suspended
Process 30017 detached
Process 30020 detached
Process 30026 resumed
Process 30047 detached
Process 30026 detached
Process 30028 detached
[1]   Done                    source peek.sh "$1" 20 | timestamp.pl >|/tmp/peekx_${vdate}.txt
[3]   Done                    sqlplus / as sysdba @ exclusive_latch.txt "$1" 1 4 5 10 > /dev/null
[4]   Done                    strace -fttT -p $p -o /tmp/pp_${vdate}_${p}.txt > /dev/null
[5]-  Done                    sqlplus / as sysdba @ exclusive_latch.txt "$1" 1 6 7 5 > /dev/null
[6]+  Done                    strace -fttT -p $p -o /tmp/pp_${vdate}_${p}.txt > /dev/null
[2]+  Done                    sqlplus -s -l / as sysdba  >|/tmp/latch_free_${vdate}.txt <<EOF
$(seq 20 | xargs -I {} echo -e '@latch_free \n host sleep 1')
EOF

$ grep sem /tmp/pp_20190419090713*.txt
/tmp/pp_20190419090713_30017.txt:30020 09:07:25.053803 semctl(315195392, 33, SETVAL, 0x1) = 0 <0.000025>
/tmp/pp_20190419090713_30026.txt:30028 09:07:17.040321 semop(315195392, 0x7ffff4363890, 1) = 0 <8.013580>

--//你可以發現進程1發出kslfre時調用semctl時間在09:07:25.053803,進程2從09:07:17.040321開始執行semop.加上8.013580秒被喚醒.
--//在09:07:28:053901結束semop

9.select函數 屬於 strace -e desc 可以跟蹤到,-e ipc可以單獨跟蹤semop,semctl,semtimedop系統調用.


您的分享是我們最大的動力!

-Advertisement-
Play Games
更多相關文章
  • 說說MySQL索引
    前言 關於索引，這是一個非常重要的知識點，同樣，在面試的時候也會被經常的問到； 本文描述了索引的結構，介紹了InnoDB的索引方案等知識點，感興趣的可以看一下； 引入 本文參考文章：MySQL的索引 回顧 在上篇文章中我們說到 InnoDB的數據頁結構 ,瞭解到了 數據頁的 7 個組成部分，知道了各 ...
  • SQL server 存儲過程的建立和調用
    存儲過程的建立和調用 --1.1準備測試需要的資料庫:test,數據表:物料表,採購表if not exists (select * from master.dbo.sysdatabases where name='test')create database testgouse testgoif o ...
  • 讀書筆記——《redis入門指南（第2版）》第四章 進階——4.1-5
    .1事務 redis中事務是一組命令的集合。 事務同命令一樣都是redis的最小執行單位，Redis保證一個事務中的命令要麼都執行，要麼都不執行。如果redisClient在發送EXEC命令前掉線，則redis會清空事務隊列，事務中的所有命令都不會執行；如果redisClient在發送EXEC命令後 ...
  • 歷經15個小時，終於評出這8本最受歡迎的SQL書籍
    結構化查詢語言SQL在20世紀70年代發展起來之後，很快就成為關係資料庫的標準語言。雖然理解SQL可能很複雜，但幸運的是，有許多書籍可以幫助你快速瞭解SQL。 ...
  • SQLite新建資料庫及txt文件（CSV文件）導入
    1.安裝準備： Windows系統環境： 安裝：SQLiteExpert 及 官網的SQLite tool 我們要用到其中的SQLite.exe 地址：https://www.sqlite.org/download.html 2.新建資料庫 打開cmd：鍵入以下命令：D:\sqlite-tools- ...
  • [20190419]shared latch spin count 2.txt
    [20190419]shared latch spin count 2.txt--//上午測試shared latch XX模式的情況,鏈接:http://blog.itpub.net/267265/viewspace-2641902/--//繼續測試其它情況.--//鏈接:http://andre ...
  • [20190419]shared latch spin count.txt
    [20190419]shared latch spin count.txt--//昨天測試exclusive latch spin count = 20000(預設).--//今天測試shared latch spin count的情況,看了一些文章測試等於2 *_spin_count.--//有了 ...
  • RAC配置筆記
    Iscsi常用命令（我通過openfiler實現iscsi存儲） # iscsiadm -m discovery -t st -p IP:port //發現iSCSI存儲 # iscsiadm -m node -o delete -T TARGET -p IP:port //刪除iSCSI發現記錄 ...
一周排行
    -Advertisement-
    Play Games
  • 移動開發（一）：使用.NET MAUI開發第一個安卓APP
    移動開發（一）：使用.NET MAUI開發第一個安卓APP 對於工作多年的C#程式員來說，近來想嘗試開發一款安卓APP，考慮了很久最終選擇使用.NET MAUI這個微軟官方的框架來嘗試體驗開發安卓APP，畢竟是使用Visual Studio開發工具，使用起來也比較的順手，結合微軟官方的教程進行了安卓 ...
  • wpf ToggleButton選中效果和一個登錄界面
    前言 QuestPDF 是一個開源 .NET 庫，用於生成 PDF 文檔。使用了C# Fluent API方式可簡化開發、減少錯誤並提高工作效率。利用它可以輕鬆生成 PDF 報告、發票、導出文件等。 項目介紹 QuestPDF 是一個革命性的開源 .NET 庫，它徹底改變了我們生成 PDF 文檔的方 ...
  • 彈幕樹洞項目功能新增篇
    項目地址 項目後端地址： https://github.com/ZyPLJ/ZYTteeHole 項目前端頁面地址： ZyPLJ/TreeHoleVue (github.com) https://github.com/ZyPLJ/TreeHoleVue 目前項目測試訪問地址： http://tree ...
  • 第27篇 sqlserver2022詳細安裝步驟
    話不多說，直接開乾 一.下載 1.官方鏈接下載： https://www.microsoft.com/zh-cn/sql-server/sql-server-downloads 2.在下載目錄中找到下麵這個小的安裝包 SQL2022-SSEI-Dev.exe，運行開始下載SQL server； 二. ...
  • .NET 開源高性能 MQTT 類庫
    前言 隨著物聯網（IoT）技術的迅猛發展，MQTT（消息隊列遙測傳輸）協議憑藉其輕量級和高效性，已成為眾多物聯網應用的首選通信標準。 MQTTnet 作為一個高性能的 .NET 開源庫，為 .NET 平臺上的 MQTT 客戶端與伺服器開發提供了強大的支持。 本文將全面介紹 MQTTnet 的核心功能 ...
  • Serilog文檔翻譯系列（六） - 可用的接收器、增強器、格式化輸出
    Serilog支持多種接收器用於日誌存儲，增強器用於添加屬性，LogContext管理動態屬性，支持多種輸出格式包括純文本、JSON及ExpressionTemplate。還提供了自定義格式化選項，適用於不同需求。 ...
  • 警惕 Visual Studio 屬性求值副作用導致邏輯不符合預期
    目錄簡介獲取 HTML 文檔解析 HTML 文檔測試參考文章 簡介 動態內容網站使用 JavaScript 腳本動態檢索和渲染數據，爬取信息時需要模擬瀏覽器行為，否則獲取到的源碼基本是空的。 本文使用的爬取步驟如下： 使用 Selenium 獲取渲染後的 HTML 文檔 使用 HtmlAgility ...
  • [使用目前最新版]HybridCLR6.9.0+YooAsset2.2.4實現純C# Unity熱更新方案 (一)
    1.前言 什麼是熱更新 游戲或者軟體更新時，無需重新下載客戶端進行安裝，而是在應用程式啟動的情況下，在內部進行資源或者代碼更新 Unity目前常用熱更新解決方案 HybridCLR，Xlua，ILRuntime等 Unity目前常用資源管理解決方案 AssetBundles，Addressable， ...
  • 在 ASP.NET Core Web API 中使用操作篩選器統一處理通用操作
    本文章主要是在C# ASP.NET Core Web API框架實現向手機發送驗證碼簡訊功能。這裡我選擇是一個互億無線簡訊驗證碼平臺，其實像阿裡雲，騰訊雲上面也可以。 首先我們先去 互億無線 https://www.ihuyi.com/api/sms.html 去註冊一個賬號 註冊完成賬號後，它會送 ...
  • 第28篇 如何.net中實現高效可靠數據同步api
    通過以下方式可以高效，並保證數據同步的可靠性 1.API設計 使用RESTful設計，確保API端點明確，並使用適當的HTTP方法（如POST用於創建，PUT用於更新）。 設計清晰的請求和響應模型，以確保客戶端能夠理解預期格式。 2.數據驗證 在伺服器端進行嚴格的數據驗證，確保接收到的數據符合預期格 ...
所有分類