loadrunner事务时间详解

一个事务的时间是指持续时间，事务会完全记录下从事务开始到事务结束之间的时间差，那么事务的时间能真实地反映业务操作的时间吗？不能，就好像人用手按秒表来记录短跑时间一样，得出的时间并不是完全准确，存在观察的误差和操作的误差，对于一个事务时间来说，一般由四部分组成，如图4所示。

图4事务时间组成

响应时间

这是事务的目的，通过事务记录业务操作所消耗的响应时间。

事务自身时间

事务中哪怕没有操作，也是需要时间的，不过这个时间一般在0.01秒左右，所以可以忽略。

1.           lr_start_transaction("thinktime");  

2.           lr_end_transaction("thinktime", LR_AUTO); 

运行上面的脚本后，可以看到：

1.           Action.c(5): Notify: Transaction "thinktime" started.  

2.           Action.c(9): Notify: Transaction "thinktime" ended with "Pass" status (Duration: 0.0121). 

思考时间（Think Time）

Think Time是LoadRunner提供的一种模拟用户等待的方式，通过lr_think_time()函数实现。在函数内写入对应的时间（单位是秒），当脚本在Controller中运行到该函数时就会等待相应的时间。注意在VuGen中，回放Think Time默认关闭。

Think Time在进行性能测试的时候需要打开，只有这样每个虚拟用户才是真正按照用户的操作速度来完成请求，才能得到在真实情况下的系统数据。如果不打开Think Time，测试获得的数据是在全负载下的一些理论峰值数据。

那么Think Time在事务中如何影响事务时间呢？编写如下脚本：

1.           lr_start_transaction("thinktime");  

2.           lr_think_time(5);  

3.           lr_end_transaction("thinktime", LR_AUTO); 

在Run-time Settings中设置Think Time，启用Replay Think Time功能，运行之后可以看到以下结果：

1.           Action.c(5): Notify: Transaction "thinktime" started.  

2.           Action.c(7): lr_think_time: 5.00 seconds.  

3.           Action.c(9): Notify: Transaction "thinktime" ended with
"Pass" status (Duration: 5.0254 Think Time: 4.9995).  

所以Think Time会被算在事务的时间内，不过在Analysis中可以设置过滤规则将其扣除，另外我们也建议尽量不要在事务内使用lr_think_time()函数。

浪费时间（Wasted Time）

在使用事务的时候，经常会看到在事务日志中有Wasted Time。Wasted Time是指事务中应该扣除的由于其他原因导致的时间浪费。在默认情况下LoadRunner会将自身脚本运行浪费的时间自动记入Wasted Time。例如执行关联、检查点等函数的时间。

除了脚本自身浪费的时间，某些时候使用C语言等外部接口进行处理所消耗的时间也会影响事务的时间，而这个时间LoadRunner无法处理，在这种情况下就需要人为地计算第三方时间开销，并且将这个开销的时间记入Wasted Time中。

运行一下下面的代码：

1.           Action()   

2.           {   

3.               int i;  

4.               int baseIter = 100;   

5.               char dude[1000];   

6.               merc_timer_handle_t timer;   

7.               // Examine the total elapsed time of the action   

8.               //Start transaction   

9.               lr_start_transaction("Demo");  

10.           timer=lr_start_timer();  

11.           for (i=0;i<=baseIter*1000;i++) {  

12.                 sprintf(dude,"This is the way we waste time in a script =               %d", i);  

13.                  }  

14.            wasteTime=lr_end_timer(timer); //时间单位为毫秒 

15.           lr_wasted_time(wasteTime*1000);//将wasteTime转换为秒并计入lr的Wasted Time，当在场景中运行的时候，事务的响应时间会自动扣除Wasted Time

16.           lr_end_transaction("Demo", LR_AUTO);   

17.           return 0;   

18.       } 

其中，lr_start_timer()是一个LoadRunner自带的时间计数器，它和lr_end_timer()相对应，能够返回这两个函数间的时间差。

运行脚本后，等待一段时间脚本运行结束，可以看到以下日志。

1.           Action.c(18): Notify: Transaction "Demo" started.  

2.           Action.c(27): wasted time is 85.860000  

3.           Action.c(28): Notify: Transaction "Demo" ended with 
"Pass" status (Duration: 85.8772 Wasted Time: 85.8600). 

通过上面这个日志可以看到，在VuGen运行脚本的时候这个1000次的C语言操作所消耗的时间会被算在Transaction时间内，导致Transaction的时间变长。当通过lr_start_timer()计时函数将这个消耗时间加入Wasted Time后，这个脚本就能正确地计算出事务的时间和该事务时间的Wasted Time了。当在场景中运行的时候，事务的响应时间会自动扣除Wasted Time。

为了确保响应时间的正确，需要扣除在运行脚本时自身的时间消耗，事务中尽量避免出现非请求的处理内容，如果无法避免请使用lr_wasted_time()函数将多余的时间开销扣除。

例如这样的脚本：

1.           merc_timer_handle_t timer; //变量声明 

2.           lr_start_transaction("Demo");   

3.           timer=lr_start_timer();  

4.           lr_load_dll("getkey.dll");  

5.           lr_save_string(getrandkey(),"key");  

6.           //通过调用dll获得密钥 

7.           wasteTime=lr_end_timer(timer);  

8.           lr_wasted_time(wasteTime*1000);   

9.           lr_end_transaction("Demo", LR_AUTO);  

计算密钥是很消耗时间的，那么可以使用timer这个变量来记录计算的时间，并将这个时间从整个事务中扣除。

在计算Wasted Time时不要直接使用lr_wasted_time()覆盖，而忘了加上脚本中LoadRunner函数的自身时间。通过lr_get_transaction_wasted_time()函数可以获得事务自身的Wasted Time，将这个时间累加上第三方统计的Wasted Time再通过lr_wasted_time()函数覆盖。