golang調(diào)試bug及性能監(jiān)控方式實(shí)踐總結(jié)
如何分析程序運(yùn)行所需時(shí)間及cpu的使用率?
使用shell內(nèi)置的time指令
最常見(jiàn)的方式便是linux中內(nèi)置的time指令,通過(guò)time go run '你的程序.go'即可。
$ time go run test.go real 0m0.802s user 0m0.320s sys 0m0.345s
使用time指令后,會(huì)返回三個(gè)指標(biāo),他們的含義分別是:
- real:程序?qū)嶋H運(yùn)行的時(shí)長(zhǎng)。
- user:程序在用戶態(tài)所消耗的時(shí)間
- sys:程序在系統(tǒng)態(tài)所消耗的時(shí)間
一般來(lái)說(shuō),real >= user + sys,這是因?yàn)橄到y(tǒng)在運(yùn)行當(dāng)前程序時(shí),可能會(huì)調(diào)用其他進(jìn)程,從而導(dǎo)致程序整體的運(yùn)行時(shí)增加。
使用/usr/bin/time指令
在linux中,還存在比time指令更為精準(zhǔn)詳細(xì)的time指令,相比于系統(tǒng)自帶的time指令,你還需要添加指令的絕對(duì)路徑以及參數(shù)-v。
$ /usr/bin/time -v go run test.go Command being timed: "go run test.go" User time (seconds): 0.12 System time (seconds): 0.06 Percent of CPU this job got: 115% Elapsed (wall clock) time (h:mm:ss or m:ss): 0:00.16 Average shared text size (kbytes): 0 Average unshared data size (kbytes): 0 Average stack size (kbytes): 0 Average total size (kbytes): 0 Maximum resident set size (kbytes): 41172 Average resident set size (kbytes): 0 Major (requiring I/O) page faults: 1 Minor (reclaiming a frame) page faults: 15880 Voluntary context switches: 897 Involuntary context switches: 183 Swaps: 0 File system inputs: 256 File system outputs: 2664 Socket messages sent: 0 Socket messages received: 0 Signals delivered: 0 Page size (bytes): 4096 Exit status: 0
可以看出,這種time指令比系統(tǒng)自帶的要擁有更多的參數(shù)。例如:CPU的占用率,內(nèi)存的使用情況等等。
如何分析go程序的內(nèi)存使用情況?
聊完了go程序的運(yùn)行時(shí)間的監(jiān)控方式,我們接下來(lái)要討論的是如何分析內(nèi)存使用情況的問(wèn)題。
先貼出一段測(cè)試用代碼
package main
import (
"log"
"runtime"
"time"
)
func main(){
log.Println("start...")
test()
log.Println("force gc...")
runtime.GC()//強(qiáng)調(diào)調(diào)用gc回收
log.Println("done...")
time.Sleep(3600*time.Second)
}
func test(){
//由于切片可以動(dòng)態(tài)擴(kuò)容,所以這里使用slice進(jìn)行測(cè)試
container := make([]int, 8)
log.Println("==> loop start...")
for i := 0; i < 32*1000*1000; i++ {
container = append(container, i)
}
log.Println("==> loop end...")
}將test.go文件編譯為可執(zhí)行的二進(jìn)制文件并執(zhí)行
$go build -o test && ./test
此時(shí),我們新開(kāi)一個(gè)終端窗口,并使用top命令查看進(jìn)程的內(nèi)存占用情況
$top -p $(pidof test)
結(jié)果如下:

如圖所示,可以看出我們的test進(jìn)程大概有520m的內(nèi)存占用,這顯然是不正常的,因?yàn)閠est()執(zhí)行完畢以后,container的內(nèi)存應(yīng)該被釋放了。
這時(shí),另一種內(nèi)存分析工具GODEBUG就能派上用場(chǎng)了。
GODEBUG與gctrace
首先,我們要開(kāi)啟打印垃圾回收信息的功能:
$ GODEBUG='gctrace=1' ./test
gctrace=1可以讓垃圾回收器在每次回收垃圾時(shí)收集用時(shí)和釋放空間的大小,并將其打印到終端上。
格式及其含義
gc # @#s #%: #+#+# ms clock, #+#/#/#+# ms cpu, #->#-># MB, # MB goal, # P gc # GC次數(shù)的編號(hào),每次GC時(shí)遞增 @#s 距離程序開(kāi)始執(zhí)行時(shí)的時(shí)間 #% GC占用的執(zhí)行時(shí)間百分比 #+...+# GC使用的時(shí)間 #->#-># MB GC開(kāi)始,結(jié)束,以及當(dāng)前活躍堆內(nèi)存的大小,單位M # MB goal 全局堆內(nèi)存大小 # P 使用processor的數(shù)量
如果是由runtime.GC調(diào)用觸發(fā)的,則消息會(huì)以forced結(jié)尾。
這里虛擬一條輸出作為示例:
gc 3 @0.134s 1%: 0.003+28+0.002 ms clock, 0.007+0/0.049/24+0.005 ms cpu, 178->178->100 MB, 180 MB goal, 2 P
該條輸出代表的信息如下
- gc 3:GC調(diào)試的編號(hào)為3。
- @0.134s:此時(shí)程序已經(jīng)運(yùn)行了0.134s。
- 1%:在全部的運(yùn)行時(shí)間中GC所占時(shí)間比例為1%。
以上就是golang調(diào)試bug及性能監(jiān)控方式實(shí)踐總結(jié)的詳細(xì)內(nèi)容,更多關(guān)于golang調(diào)試bug性能監(jiān)控的資料請(qǐng)關(guān)注腳本之家其它相關(guān)文章!
- golang?pprof?監(jiān)控goroutine?thread統(tǒng)計(jì)原理詳解
- golang?pprof監(jiān)控memory?block?mutex統(tǒng)計(jì)原理分析
- golang?pprof監(jiān)控memory?block?mutex使用指南
- golang?pprof?監(jiān)控系列?go?trace統(tǒng)計(jì)原理與使用解析
- prometheus?client_go為應(yīng)用程序自定義監(jiān)控指標(biāo)
- web項(xiàng)目中g(shù)olang性能監(jiān)控解析
- Go語(yǔ)言metrics應(yīng)用監(jiān)控指標(biāo)基本使用說(shuō)明
- Skywalking-go自動(dòng)監(jiān)控增強(qiáng)使用探究
相關(guān)文章
Go空結(jié)構(gòu)體struct{}的作用是什么
本文主要介紹了Go空結(jié)構(gòu)體struct{}的作用是什么,文中通過(guò)示例代碼介紹的非常詳細(xì),對(duì)大家的學(xué)習(xí)或者工作具有一定的參考學(xué)習(xí)價(jià)值,需要的朋友們下面隨著小編來(lái)一起學(xué)習(xí)學(xué)習(xí)吧2023-02-02
Golang基礎(chǔ)教程之字符串string實(shí)例詳解
這篇文章主要給大家介紹了關(guān)于Golang基礎(chǔ)教程之字符串string的相關(guān)資料,需要的朋友可以參考下2022-07-07
grom設(shè)置全局日志實(shí)現(xiàn)執(zhí)行并打印sql語(yǔ)句
本文主要介紹了grom設(shè)置全局日志實(shí)現(xiàn)執(zhí)行并打印sql語(yǔ)句,包括設(shè)置日志級(jí)別、實(shí)現(xiàn)自定義Logger接口以及如何使用GORM的默認(rèn)logger,通過(guò)這些方法,可以更好地控制和記錄數(shù)據(jù)庫(kù)操作的日志信息2025-03-03
淺析Golang如何向已關(guān)閉的chan讀寫數(shù)據(jù)
這篇文章主要為大家詳細(xì)介紹了Golang如何向已關(guān)閉的chan讀寫數(shù)據(jù),文中的示例代碼講解詳細(xì),感興趣的小伙伴可以跟隨小編一起學(xué)習(xí)一下2024-02-02
golang如何使用gos7讀取S7200Smart數(shù)據(jù)
文章介紹了如何使用Golang語(yǔ)言的Gos7工具庫(kù)讀取西門子S7200Smart系列PLC的數(shù)據(jù),通過(guò)指定數(shù)據(jù)塊號(hào)、起始字節(jié)偏移量和數(shù)據(jù)長(zhǎng)度,可以精確讀取所需的數(shù)據(jù),感興趣的朋友跟隨小編一起看看吧2024-12-12
Go語(yǔ)言Seeker接口與文件斷點(diǎn)續(xù)傳實(shí)戰(zhàn)教程
Go語(yǔ)言的io包中Seeker接口為大文件處理或需要隨機(jī)訪問(wèn)的場(chǎng)景提供了強(qiáng)大的支持,本文通過(guò)具體案例詳細(xì)介紹了Seeker接口的應(yīng)用,包括隨機(jī)訪問(wèn)大文件、斷點(diǎn)續(xù)傳等場(chǎng)景,以及如何使用Seeker接口進(jìn)行有效的文件讀寫操作2024-10-10
用golang實(shí)現(xiàn)一個(gè)定時(shí)器任務(wù)隊(duì)列實(shí)例
golang中提供了2種定時(shí)器timer和ticker,分別是一次性定時(shí)器和重復(fù)任務(wù)定時(shí)器。這篇文章主要介紹了用golang實(shí)現(xiàn)一個(gè)定時(shí)器任務(wù)隊(duì)列實(shí)例,非常具有實(shí)用價(jià)值,需要的朋友可以參考下2018-05-05
Golang簡(jiǎn)介與基本語(yǔ)法的學(xué)習(xí)
這篇文章主要介紹了Golang簡(jiǎn)介與基本語(yǔ)法的學(xué)習(xí),文中通過(guò)示例代碼介紹的非常詳細(xì),對(duì)大家的學(xué)習(xí)或者工作具有一定的參考學(xué)習(xí)價(jià)值,需要的朋友們下面隨著小編來(lái)一起學(xué)習(xí)學(xué)習(xí)吧2023-04-04
Go1.21新增內(nèi)置函數(shù)(built-in?functions)詳解
Go?1.21新增的內(nèi)置函數(shù)分別是?min、max?和?clear,這篇文章主要帶大家一起了解一下這幾個(gè)函數(shù)的用途和使用示例,感興趣的小伙伴可以學(xué)習(xí)一下2023-08-08

