导航网站某事务执行时间很长，是什么原因？【已解决】

cuizhihui

回放LR自带的导航网站脚本，执行到核对路线时，花费时间很长。
开始运行时设置的“超时时间”为120s，居然超时失败，修改为1000s后，才执行成功，但使用时间居然为235.8559，差不多4分钟了。好长啊！ 不知道为什么？
Action.c(74): 通知: Transaction "S01_T06_CheckItinerary" ended with "Pass" status (Duration: 235.8559).


下面是回放日志：

虚拟用户脚本已启动
正在开始操作 vuser_init。
Web Turbo 重播 LoadRunner 8.1.0 for WINXP; Web 内部版本 4788          [MsgId: MMSG-27143]
运行时设置文件: "D:\Mercury\LoadRunner\tutorial\demo_script\\default.cfg"          [MsgId: MMSG-27141]
//由于太长，省略init部分；//
正在结束操作 vuser_init。
正在运行 Vuser...
正在开始迭代 1。
正在开始操作 Action。
Action.c(3): 通知: Transaction "S01_T03_SearchFlight" started.
Action.c(5): 链接到“http://127.0.0.1:1080/MercuryWebTours/welcome.pl?page=search”的图像，目标帧=“body”          [MsgId: MMSG-27993]
Action.c(5): 在“http://127.0.0.1:1080/MercuryWebTours/welcome.pl?page=search”中检测到非资源“http://127.0.0.1:1080/MercuryWeb ... menu&in=flights”          [MsgId: MMSG-26574]
Action.c(5): 在“http://127.0.0.1:1080/MercuryWebTours/welcome.pl?page=search”中检测到非资源“http://127.0.0.1:1080/MercuryWeb ... ons.pl?page=welcome”          [MsgId: MMSG-26574]
Action.c(5): 正在下载资源“http://127.0.0.1:1080/MercuryWebTours/FormDateUpdate.class”(由参数编号 5 指定)          [MsgId: MMSG-26577]
Action.c(5): 正在下载资源“http://127.0.0.1:1080/MercuryWebTours/CalSelect.class”(由参数编号 8 指定)          [MsgId: MMSG-26577]
Action.c(5): 正在下载资源“http://127.0.0.1:1080/MercuryWebTours/Calendar.class”(由参数编号 11 指定)          [MsgId: MMSG-26577]
Action.c(5): 在 HTML“http://127.0.0.1:1080/MercuryWeb ... menu&in=flights”中找到资源“”          [MsgId: MMSG-26659]
Action.c(5): 在 HTML“http://127.0.0.1:1080/MercuryWeb ... menu&in=flights”中找到资源“”          [MsgId: MMSG-26659]
Action.c(5): 在 HTML“http://127.0.0.1:1080/MercuryWeb ... menu&in=flights”中找到资源“”          [MsgId: MMSG-26659]
Action.c(5): 在 HTML“http://127.0.0.1:1080/MercuryWeb ... menu&in=flights”中找到资源“”          [MsgId: MMSG-26659]
Action.c(5): 在 HTML“http://127.0.0.1:1080/MercuryWeb ... ons.pl?page=welcome”中找到资源“”          [MsgId: MMSG-26659]
Action.c(5): 在 HTML“http://127.0.0.1:1080/MercuryWeb ... ons.pl?page=welcome”中找到资源“”          [MsgId: MMSG-26659]
Action.c(5): web_image("Search Flights Button") 已成功，42429 个正文字节，2144 介标头字节          [MsgId: MMSG-26386]
Action.c(14): 通知: Transaction "S01_T03_SearchFlight" ended with "Pass" status (Duration: 0.8616).
Action.c(18): 通知: Transaction "S01_T04_BookFlight" started.
Action.c(20): 将表单提交到“http://127.0.0.1:1080/MercuryWebTours/reservations.pl”，目标帧=“”          [MsgId: MMSG-27978]
Action.c(20): 在 HTML“http://127.0.0.1:1080/MercuryWebTours/reservations.pl”中找到资源“”          [MsgId: MMSG-26659]
Action.c(20): 在 HTML“http://127.0.0.1:1080/MercuryWebTours/reservations.pl”中找到资源“”          [MsgId: MMSG-26659]
Action.c(20): web_submit_form("reservations") 已成功，29538 个正文字节，584 介标头字节          [MsgId: MMSG-26386]
Action.c(35): 将表单提交到“http://127.0.0.1:1080/MercuryWebTours/reservations.pl”，目标帧=“”          [MsgId: MMSG-27978]
Action.c(35): 在 HTML“http://127.0.0.1:1080/MercuryWebTours/reservations.pl”中找到资源“”          [MsgId: MMSG-26659]
Action.c(35): 在 HTML“http://127.0.0.1:1080/MercuryWebTours/reservations.pl”中找到资源“”          [MsgId: MMSG-26659]
Action.c(35): web_submit_form("reservation_options") 已成功，29868 个正文字节，584 介标头字节          [MsgId: MMSG-26386]
Action.c(43): 通知: Transaction "S01_T04_BookFlight" ended with "Pass" status (Duration: 0.5758).
Action.c(47): 通知: Transaction "S01_T05_PaymentDetails" started.
Action.c(49): 将表单提交到“http://127.0.0.1:1080/MercuryWebTours/reservations.pl”，目标帧=“”          [MsgId: MMSG-27978]
Action.c(49): 在 HTML“http://127.0.0.1:1080/MercuryWebTours/reservations.pl”中找到资源“”          [MsgId: MMSG-26659]
Action.c(49): 在 HTML“http://127.0.0.1:1080/MercuryWebTours/reservations.pl”中找到资源“”          [MsgId: MMSG-26659]
Action.c(49): web_submit_form("reservations.pl_3") 已成功，29660 个正文字节，739 介标头字节          [MsgId: MMSG-26386]
Action.c(64): 通知: Transaction "S01_T05_PaymentDetails" ended with "Pass" status (Duration: 0.2618).
Action.c(68): 通知: Transaction "S01_T06_CheckItinerary" started.
Action.c(70): 链接到“http://127.0.0.1:1080/MercuryWebTours/welcome.pl?page=itinerary”的图像，目标帧=“body”          [MsgId: MMSG-27993]
Action.c(70): 在“http://127.0.0.1:1080/MercuryWebTours/welcome.pl?page=itinerary”中检测到非资源“http://127.0.0.1:1080/MercuryWeb ... nu&in=itinerary”          [MsgId: MMSG-26574]
Action.c(70): 在“http://127.0.0.1:1080/MercuryWebTours/welcome.pl?page=itinerary”中检测到非资源“http://127.0.0.1:1080/MercuryWebTours/itinerary.pl”          [MsgId: MMSG-26574]
Action.c(70): 在 HTML“http://127.0.0.1:1080/MercuryWeb ... nu&in=itinerary”中找到资源“”          [MsgId: MMSG-26659]
Action.c(70): 在 HTML“http://127.0.0.1:1080/MercuryWeb ... nu&in=itinerary”中找到资源“”          [MsgId: MMSG-26659]
Action.c(70): 在 HTML“http://127.0.0.1:1080/MercuryWeb ... nu&in=itinerary”中找到资源“”          [MsgId: MMSG-26659]
Action.c(70): 在 HTML“http://127.0.0.1:1080/MercuryWeb ... nu&in=itinerary”中找到资源“”          [MsgId: MMSG-26659]
Action.c(70): 在 HTML“http://127.0.0.1:1080/MercuryWebTours/itinerary.pl”中找到资源“”          [MsgId: MMSG-26659]
Action.c(70): 在 HTML“http://127.0.0.1:1080/MercuryWebTours/itinerary.pl”中找到资源“”          [MsgId: MMSG-26659]
Action.c(70): 在 HTML“http://127.0.0.1:1080/MercuryWebTours/itinerary.pl”中找到资源“”          [MsgId: MMSG-26659]
Action.c(70): web_image("Itinerary Button") 已成功，4014100 个正文字节，1835 介标头字节          [MsgId: MMSG-26386]
Action.c(74): 通知: Transaction "S01_T06_CheckItinerary" ended with "Pass" status (Duration: 235.8559).
正在结束操作 Action。
正在结束迭代 1。
正在结束 Vuser...
正在开始操作 vuser_end。
vuser_end.c(3): 通知: Transaction "S01_T07_SignOff" started.
vuser_end.c(4): 链接到“http://127.0.0.1:1080/MercuryWebTours/welcome.pl?signOff=1”的图像，目标帧=“body”          [MsgId: MMSG-27993]
vuser_end.c(4): 在“http://127.0.0.1:1080/MercuryWebTours/welcome.pl?signOff=1”中检测到非资源“http://127.0.0.1:1080/MercuryWebTours/nav.pl?in=home”          [MsgId: MMSG-26574]
vuser_end.c(4): 在“http://127.0.0.1:1080/MercuryWebTours/welcome.pl?signOff=1”中检测到非资源“http://127.0.0.1:1080/MercuryWebTours/home.html”          [MsgId: MMSG-26574]
vuser_end.c(4): 在 HTML“http://127.0.0.1:1080/MercuryWebTours/home.html”中找到资源“”          [MsgId: MMSG-26659]
vuser_end.c(4): 在 HTML“http://127.0.0.1:1080/MercuryWebTours/nav.pl?in=home”中找到资源“”          [MsgId: MMSG-26659]
vuser_end.c(4): web_image("SignOff Button") 已成功，50157 个正文字节，1073 介标头字节          [MsgId: MMSG-26386]
vuser_end.c(8): 通知: Transaction "S01_T07_SignOff" ended with "Pass" status (Duration: 0.5046).
正在结束操作 vuser_end。
Vuser 已终止。

呵呵。。。  已经解决。
原来该页面太大导致的。
将Itinerary中预定取消，就可以了。
追究根本原因，可能就是本机测试本机，导致下载太大的页面时，速度会变很慢导致。

[ 本帖最后由 cuizhihui 于 2008-1-21 13:25 编辑 ]

cuizhihui

每次回放时，发现前面运行很快，到下载itinerary Button图像时就基本停住了一样。
从测试结果看，整个Action运行时间如下，而itinerary Button之前全部的事务执行只用了3s。
操作: Action  
运行开始: 2008-1-17 - 14:32:21
运行结束: 2008-1-17 - 14:36:03
运行到itinerary Button时，本机CPU基本100%。 去掉“模拟浏览器缓存”，播放think time，依然如此。

之前，最开始回放几次时，整个回放都很快就完成了。
不明白到底什么原因导致该事务这么慢，希望大家能帮忙分析下，多谢了！~

Zee

用本机测试本机？

cuizhihui

恩。在本机进行的测试。
不过，只用了一个用户循环一次进行回放。

cuizhihui

呵呵。。。  已经解决。
原来该页面太大导致的。
将Itinerary中预定取消，就可以了。
追究根本原因，可能就是本机测试本机，导致下载太大的页面时，速度会变很慢导致。