如何调试bash脚本并获取每个命令的执行时间

Question

我有一个bash脚本，它需要将近5秒的时间才能运行。我想调试它，并确定哪些命令花费的时间最长。这样做的最好方法是什么？有没有可以设置的标志？设置#!/bin/bash -vx并没有真正的帮助。我想要的基本上是按行号执行的时间。

Answer 1

您可以使用set -x让脚本在执行前打印每个命令。我不知道如何获得自动添加的命令时序。您可以在整个脚本中使用date命令来标记时间。

Answer 2

可以使用time工具来衡量你的个人命令/功能的运行时间。

例如：

[ben@imac ~]$ cat times.sh 
#!/bin/bash 

test_func() 
{ 
    sleep 1 
    echo "test" 
} 

echo "Running test_func()" 
time test_func 
echo "Running a 5 second external command" 
time sleep 5 

运行在类似下面这个脚本的结果：

[ben@imac ~]$ ./times.sh 
Running test_func() 
test 

real 0m1.003s 
user 0m0.001s 
sys  0m0.001s 
Running a 5 second external command 

real 0m5.002s 
user 0m0.001s 
sys  0m0.001s 

Answer 3

试试这个：

sed 's/^\([^#]\)/time \1/' script.sh>tmp.sh && ./tmp.sh 

它预先考虑时间命令将所有非命令行

Answer 4

这是尽可能接近内置bash调试工具的答案，因为它可以从脚本执行开始时间提供整体计时信息。

在脚本的顶部添加此为第二计：

export PS4='+[${SECONDS}s][${BASH_SOURCE}:${LINENO}]: ${FUNCNAME[0]:+${FUNCNAME[0]}(): }'; set -x; 

相同，但用毫秒来代替：

N=`date +%s%N`; export PS4='+[$(((`date +%s%N`-$N)/1000000))ms][${BASH_SOURCE}:${LINENO}]: ${FUNCNAME[0]:+${FUNCNAME[0]}(): }'; set -x; 

的最后一个例子可以到微秒级精度，只要记住你正在使用bash :)。

Exampe脚本：

#!/bin/bash 
N=`date +%s%N` 
export PS4='+[$(((`date +%s%N`-$N)/1000000))ms][${BASH_SOURCE}:${LINENO}]: ${FUNCNAME[0]:+${FUNCNAME[0]}(): }'; set -x; 
sleep 1 
exit 

实施例的调试输出：

+[3ms][/root/db_test.sh:5]: sleep 1 
+[1012ms][/usr/local/bin/graphite_as_rand_stat.sh:6]: exit 

请注意，可以有选择地通过“设置-x”包围它调试脚本的特定部分在在调试结束时调试开始和'调试+ x'。计时数据仍将显示从执行开始正确计数。

附录

为了完整起见，如果你需要的差分时钟数据可以重定向调试信息到一个文件，随后对其进行处理。

鉴于这种示例脚本：

#!/bin/bash 
N=`date +%s%N` 
export PS4='+[$(((`date +%s%N`-$N)/1000000))ms][${BASH_SOURCE}:${LINENO}]: ${FUNCNAME[0]:+${FUNCNAME[0]}(): }'; set -x; 
sleep 1 
for ((i=0;i<2;i++)); do 
     o=$(($RANDOM*$RANDOM/$RANDOM)) 
     echo $o 
     sleep 0.$o 
done 
exit 

运行它，而重定向调试到一个文件：

./example.sh 2>example.dbg 

和输出与此差动调试定时（覆盖多线）：

p=0; cat example.dbg | while read l; do [[ ! ${l%%[*} =~ ^\+ ]] && echo $l && continue; i=`echo $l | sed 's#[^0-9]*\([0-9]\+\).*#\1#'`; echo $l | sed "s#${i}ms#${i}ms+$(($i-$p))ms#"; p=$i; done 

输出：

+[2ms+2ms][./example.sh:5]: sleep 1 
+[1006ms+1004ms][./example.sh:6]: ((i=0)) 
+[1009ms+3ms][./example.sh:6]: ((i<2)) 
+[1011ms+2ms][./example.sh:7]: o=19258 
+[1014ms+3ms][./example.sh:8]: echo 19258 
+[1016ms+2ms][./example.sh:9]: sleep 0.19258 
+[1213ms+197ms][./example.sh:6]: ((i++)) 
+[1217ms+4ms][./example.sh:6]: ((i<2)) 
+[1220ms+3ms][./example.sh:7]: o=176 
+[1226ms+6ms][./example.sh:8]: echo 176 
+[1229ms+3ms][./example.sh:9]: sleep 0.176 
+[1442ms+213ms][./example.sh:6]: ((i++)) 
+[1460ms+18ms][./example.sh:6]: ((i<2)) 
+[1502ms+42ms][./example.sh:11]: exit