LR回放socket时候，lrs_receive接收到的buffer字节数不稳定，与录制时不一致

qicyt1812

这几天在学习使用LR录制socket脚本，不断的遇到问题，寻找了很多的解决办法，但是都是会出现问题。我录制的gtalk的发送和接收消息，回放时遇到这样两个问题：
1、接收消息的时候不是很正常，有时候是0bytes，有时候又会有bytes，但是总算和录制的时候不匹配，不晓得啥原因。
2、同事能够接收到我用lr发送过去的消息，为什么我却不能接收到同事回复的消息呢？一开始以为我没有开聊天窗口的原因，聊天窗口开启了也没有任何反映，请各位帮忙想想看是啥子原因？

下面附上我的脚本、data.ws、回放信息

qicyt1812

从扩展日志中能够接收到对方发过来的buffer，为什么我的客户端却看不到呢？

qicyt1812

明明一直是和hongmei791012@gmail.com交谈的，但是buf48接收到的却是另一个同事的帐号：larsa.wu@gmail.com，好奇怪哦，到底什么原因呢？

qicyt1812

Action.c(24): lrs_receive(socket3, buf49)
Action.c(24): fiInitRecv: socket: socket3 (372), buffer: buf49, expected size: 1266
Action.c(24): callRecv: Fri Mar 28 11:25:36.589: About to read 1266 bytes from socket3 (372)
Action.c(24): fiPerformReceive: Fri Mar 28 11:25:36.589: Waiting for readable socket 10 secs, 0 usecs
Action.c(24): fiPerformReceive: Fri Mar 28 11:25:39.833: Trying to read 8192 bytes
Action.c(24): fiPerformReceive: Fri Mar 28 11:25:39.833: store received data , 316 bytes
Action.c(24): fiPerformReceive: Fri Mar 28 11:25:39.833: Waiting for readable socket 10 secs, 0 usecs
Action.c(24): fiPerformReceive: Fri Mar 28 11:25:39.843: Trying to read 8192 bytes
Action.c(24): fiPerformReceive: Fri Mar 28 11:25:39.843: store received data , 313 bytes
Action.c(24): fiPerformReceive: Fri Mar 28 11:25:39.853: Waiting for readable socket 10 secs, 0 usecs
Action.c(24): fiPerformReceive: Fri Mar 28 11:25:39.863: Trying to read 8192 bytes
Action.c(24): fiPerformReceive: Fri Mar 28 11:25:39.863: store received data , 352 bytes
Action.c(24): fiPerformReceive: Fri Mar 28 11:25:39.863: Waiting for readable socket 10 secs, 0 usecs
Action.c(24): fiPerformReceive: Fri Mar 28 11:25:49.867: Select timed out
Action.c(24): Mismatch (expected 1266 bytes, 981 bytes actually received)
Action.c(24): fiCheckRecvMismatch: Fri Mar 28 11:25:49.887: reading buffer buf49
Action.c(24): fiCheckRecvMismatch: Fri Mar 28 11:25:49.887: calling parameterization routines
Action.c(24): Warning: The string 'message to=\"piglovepig999@gmail.com/Talk.v939B662828\" type=\"chat\" id=\"91\" from=\"hongmei791012@gmail.com/Talk.v104BE2BF082\"' with parameter delimiters is not a parameter.
Action.c(24): Warning: The string 'paused xmlns=\"http://jabber.org/protocol/chatstates\"/' with parameter delimiters is not a parameter.
Action.c(24): Warning: The string 'nos:x value=\"disabled\" xmlns:nos=\"google:nosave\"/' with parameter delimiters is not a parameter.
Action.c(24): Warning: The string 'arc:record otr=\"false\" xmlns:arc=\"http://jabber.org/protocol/archive\"/' with parameter delimiters is not a parameter.
Action.c(24): Warning: The string '/message' with parameter delimiters is not a parameter.
Action.c(24): Warning: The string 'message to=\"piglovepig999@gmail.com/Talk.v939B662828\" type=\"chat\" id=\"92\" from=\"hongmei791012@gmail.com/Talk.v104BE2BF082\"' with parameter delimiters is not a parameter.
Action.c(24): Warning: The string 'composing xmlns=\"http://jabber.org/protocol/chatstates\"/' with parameter delimiters is not a parameter.
Action.c(24): Warning: The string 'nos:x value=\"disabled\" xmlns:nos=\"google:nosave\"/' with parameter delimiters is not a parameter.
Action.c(24): Warning: The string 'arc:record otr=\"false\" xmlns:arc=\"http://jabber.org/protocol/archive\"/' with parameter delimiters is not a parameter.
Action.c(24): Warning: The string '/message' with parameter delimiters is not a parameter.
Action.c(24): Warning: The string 'message to=\"piglovepig999@gmail.com/Talk.v939B662828\" type=\"chat\" id=\"93\" from=\"hongmei791012@gmail.com/Talk.v104BE2BF082\"' with parameter delimiters is not a parameter.
Action.c(24): Warning: The string 'active xmlns=\"http://jabber.org/protocol/chatstates\"/' with parameter delimiters is not a parameter.
Action.c(24): Warning: The string 'nos:x value=\"disabled\" xmlns:nos=\"google:nosave\"/' with parameter delimiters is not a parameter.
Action.c(24): Warning: The string 'arc:record otr=\"false\" xmlns:arc=\"http://jabber.org/protocol/archive\"/' with parameter delimiters is not a parameter.
Action.c(24): Warning: The string '/message' with parameter delimiters is not a parameter.
Action.c(24): Warning: The string 'message to=\"piglovepig999@gmail.com/Talk.v939B662828\" type=\"chat\" id=\"94\" from=\"hongmei791012@gmail.com/Talk.v104BE2BF082\"' with parameter delimiters is not a parameter.
Action.c(24): Warning: The string 'body' with parameter delimiters is not a parameter.
Action.c(24): Warning: The string '/body' with parameter delimiters is not a parameter.
Action.c(24): Warning: The string 'active xmlns=\"http://jabber.org/protocol/chatstates\"/' with parameter delimiters is not a parameter.
Action.c(24): Warning: The string 'nos:x value=\"disabled\" xmlns:nos=\"google:nosave\"/' with parameter delimiters is not a parameter.
Action.c(24): Warning: The string 'arc:record otr=\"false\" xmlns:arc=\"http://jabber.org/protocol/archive\"/' with parameter delimiters is not a parameter.
Action.c(24): Warning: The string '/message' with parameter delimiters is not a parameter.
================================EXPECTED BUFFER================================
        "<message to=\"piglovepig999@gmail.com/Talk.v939B662828\" type=\"chat\" id="
        "\"91\" from=\"hongmei791012@gmail.com/Talk.v104BE2BF082\"><paused xmlns=\""
        "http://jabber.org/protocol/chatstates\"/><nos:x value=\"disabled\" xmlns:n"
        "os=\"google:nosave\"/><arc:record otr=\"false\" xmlns:arc=\"http://jabber."
        "org/protocol/archive\"/></message><message to=\"piglovepig999@gmail.com/Ta"
        "lk.v939B662828\" type=\"chat\" id=\"92\" from=\"hongmei791012@gmail.com/Ta"
        "lk.v104BE2BF082\"><composing xmlns=\"http://jabber.org/protocol/chatstates"
        "\"/><nos:x value=\"disabled\" xmlns:nos=\"google:nosave\"/><arc:record otr"
        "=\"false\" xmlns:arc=\"http://jabber.org/protocol/archive\"/></message><me"
        "ssage to=\"piglovepig999@gmail.com/Talk.v939B662828\" type=\"chat\" id=\"9"
        "3\" from=\"hongmei791012@gmail.com/Talk.v104BE2BF082\"><active xmlns=\"htt"
        "p://jabber.org/protocol/chatstates\"/><nos:x value=\"disabled\" xmlns:nos="
        "\"google:nosave\"/><arc:record otr=\"false\" xmlns:arc=\"http://jabber.org"
        "/protocol/archive\"/></message><message to=\"piglovepig999@gmail.com/Talk."
        "v939B662828\" type=\"chat\" id=\"94\" from=\"hongmei791012@gmail.com/Talk."
        "v104BE2BF082\"><body>ok</body><active xmlns=\"http://jabber.org/protocol/c"
        "hatstates\"/><nos:x value=\"disabled\" xmlns:nos=\"google:nosave\"/><arc:r"
        "ecord otr=\"false\" xmlns:arc=\"http://jabber.org/protocol/archive\"/></me"
        "ssage>"
===============================================================================
Action.c(24): getAsciiReceivedBuffer: Fri Mar 28 11:25:50.038: Translate data for printing
Action.c(24): getAsciiReceivedBuffer: Fri Mar 28 11:25:50.038: Binary to ascii
================================RECEIVED BUFFER================================
        "<message to=\"piglovepig999@gmail.com/Talk.v93BF7F0B80\" type=\"chat\" id="
        "\"148\" from=\"hongmei791012@gmail.com/Talk.v104BE2BF082\"><composing xmln"
        "s=\"http://jabber.org/protocol/chatstates\"/><nos:x value=\"disabled\" xml"
        "ns:nos=\"google:nosave\"/><arc:record otr=\"false\" xmlns:arc=\"http://jab"
        "ber.org/protocol/archive\"/></message><message to=\"piglovepig999@gmail.co"
        "m/Talk.v93BF7F0B80\" type=\"chat\" id=\"149\" from=\"hongmei791012@gmail.c"
        "om/Talk.v104BE2BF082\"><active xmlns=\"http://jabber.org/protocol/chatstat"
        "es\"/><nos:x value=\"disabled\" xmlns:nos=\"google:nosave\"/><arc:record o"
        "tr=\"false\" xmlns:arc=\"http://jabber.org/protocol/archive\"/></message><"
        "message to=\"piglovepig999@gmail.com/Talk.v93BF7F0B80\" type=\"chat\" id=\""
        "150\" from=\"hongmei791012@gmail.com/Talk.v104BE2BF082\"><body>hello sunny"
        "pig,up up study</body><active xmlns=\"http://jabber.org/protocol/chatstate"
        "s\"/><nos:x value=\"disabled\" xmlns:nos=\"google:nosave\"/><arc:record ot"
        "r=\"false\" xmlns:arc=\"http://jabber.org/protocol/archive\"/></message>"
===============================================================================
Action.c(24): callRecv:981 bytes were received

qicyt1812

扩展日志output.txt：
Action.c(22): lrs_receive(socket3, buf48)
Action.c(22): fiInitRecv: socket: socket3 (372), buffer: buf48, expected size: 315
Action.c(22): callRecv: Fri Mar 28 11:25:15.849: About to read 315 bytes from socket3 (372)
Action.c(22): fiPerformReceive: Fri Mar 28 11:25:15.849: Waiting for readable socket 10 secs, 0 usecs
Action.c(22): fiPerformReceive: Fri Mar 28 11:25:18.663: Trying to read 8192 bytes
Action.c(22): fiPerformReceive: Fri Mar 28 11:25:18.663: store received data , 395 bytes
Action.c(22): fiPerformReceive: Fri Mar 28 11:25:18.673: Waiting for readable socket 10 secs, 0 usecs
Action.c(22): fiPerformReceive: Fri Mar 28 11:25:26.444: Trying to read 8192 bytes
Action.c(22): fiPerformReceive: Fri Mar 28 11:25:26.454: store received data , 412 bytes
Action.c(22): fiPerformReceive: Fri Mar 28 11:25:26.454: Waiting for readable socket 10 secs, 0 usecs
Action.c(22): fiPerformReceive: Fri Mar 28 11:25:36.458: Select timed out
Action.c(22): Mismatch (expected 315 bytes, 807 bytes actually received)
Action.c(22): fiCheckRecvMismatch: Fri Mar 28 11:25:36.468: reading buffer buf48
Action.c(22): fiCheckRecvMismatch: Fri Mar 28 11:25:36.478: calling parameterization routines
Action.c(22): Warning: The string 'message to=\"piglovepig999@gmail.com/Talk.v939B662828\" type=\"chat\" id=\"90\" from=\"hongmei791012@gmail.com/Talk.v104BE2BF082\"' with parameter delimiters is not a parameter.
Action.c(22): Warning: The string 'composing xmlns=\"http://jabber.org/protocol/chatstates\"/' with parameter delimiters is not a parameter.
Action.c(22): Warning: The string 'nos:x value=\"disabled\" xmlns:nos=\"google:nosave\"/' with parameter delimiters is not a parameter.
Action.c(22): Warning: The string 'arc:record otr=\"false\" xmlns:arc=\"http://jabber.org/protocol/archive\"/' with parameter delimiters is not a parameter.
Action.c(22): Warning: The string '/message' with parameter delimiters is not a parameter.
================================EXPECTED BUFFER================================
        "<message to=\"piglovepig999@gmail.com/Talk.v939B662828\" type=\"chat\" id="
        "\"90\" from=\"hongmei791012@gmail.com/Talk.v104BE2BF082\"><composing xmlns"
        "=\"http://jabber.org/protocol/chatstates\"/><nos:x value=\"disabled\" xmln"
        "s:nos=\"google:nosave\"/><arc:record otr=\"false\" xmlns:arc=\"http://jabb"
        "er.org/protocol/archive\"/></message>"
===============================================================================
Action.c(22): getAsciiReceivedBuffer: Fri Mar 28 11:25:36.529: Translate data for printing
Action.c(22): getAsciiReceivedBuffer: Fri Mar 28 11:25:36.539: Binary to ascii
================================RECEIVED BUFFER================================
        "<presence from=\"larsa.wu@gmail.com/Talk.v93506AD80C\" to=\"piglovepig999@"
        "gmail.com\"><status/><priority>0</priority><c node=\"http://www.google.com"
        "/xmpp/client/caps\" ver=\"1.0.0.93\" ext=\"sidebar voice-v1\" xmlns=\"http"
        "://jabber.org/protocol/caps\"/><x stamp=\"20080328T03:25:17\" xmlns=\"jabb"
        "er:x:delay\"/><x xmlns=\"vcard-temp:x:update\"><photo>19937e4b2ba42ef739d2"
        "03509914fa48f058dcf5</photo></x></presence><presence from=\"larsa.wu@gmail"
        ".com/Talk.v93506AD80C\" to=\"piglovepig999@gmail.com\"><show>away</show><s"
        "tatus/><priority>0</priority><c node=\"http://www.google.com/xmpp/client/c"
        "aps\" ver=\"1.0.0.93\" ext=\"sidebar voice-v1\" xmlns=\"http://jabber.org/"
        "protocol/caps\"/><x stamp=\"20080328T03:25:25\" xmlns=\"jabber:x:delay\"/>"
        "<x xmlns=\"vcard-temp:x:update\"><photo>19937e4b2ba42ef739d203509914fa48f0"
        "58dcf5</photo></x></presence>"
===============================================================================
Action.c(22): callRecv:807 bytes were received

qicyt1812

我使用扩展日志后发现，如果buf48接收到消息了，buf49就接收不到了，buf48接收不到消息的时候，buf49就能接收到
data.ws：

recv  buf48 315
        "<message to=\"piglovepig999@gmail.com/Talk.v939B662828\" type=\"chat\" id="
        "\"90\" from=\"hongmei791012@gmail.com/Talk.v104BE2BF082\"><composing xmlns"
        "=\"http://jabber.org/protocol/chatstates\"/><nos:x value=\"disabled\" xmln"
        "s:nos=\"google:nosave\"/><arc:record otr=\"false\" xmlns:arc=\"http://jabb"
        "er.org/protocol/archive\"/></message>"

recv  buf49 1266
        "<message to=\"piglovepig999@gmail.com/Talk.v939B662828\" type=\"chat\" id="
        "\"91\" from=\"hongmei791012@gmail.com/Talk.v104BE2BF082\"><paused xmlns=\""
        "http://jabber.org/protocol/chatstates\"/><nos:x value=\"disabled\" xmlns:n"
        "os=\"google:nosave\"/><arc:record otr=\"false\" xmlns:arc=\"http://jabber."
        "org/protocol/archive\"/></message><message to=\"piglovepig999@gmail.com/Ta"
        "lk.v939B662828\" type=\"chat\" id=\"92\" from=\"hongmei791012@gmail.com/Ta"
        "lk.v104BE2BF082\"><composing xmlns=\"http://jabber.org/protocol/chatstates"
        "\"/><nos:x value=\"disabled\" xmlns:nos=\"google:nosave\"/><arc:record otr"
        "=\"false\" xmlns:arc=\"http://jabber.org/protocol/archive\"/></message><me"
        "ssage to=\"piglovepig999@gmail.com/Talk.v939B662828\" type=\"chat\" id=\"9"
        "3\" from=\"hongmei791012@gmail.com/Talk.v104BE2BF082\"><active xmlns=\"htt"
        "p://jabber.org/protocol/chatstates\"/><nos:x value=\"disabled\" xmlns:nos="
        "\"google:nosave\"/><arc:record otr=\"false\" xmlns:arc=\"http://jabber.org"
        "/protocol/archive\"/></message><message to=\"piglovepig999@gmail.com/Talk."
        "v939B662828\" type=\"chat\" id=\"94\" from=\"hongmei791012@gmail.com/Talk."
        "v104BE2BF082\"><body>ok</body><active xmlns=\"http://jabber.org/protocol/c"
        "hatstates\"/><nos:x value=\"disabled\" xmlns:nos=\"google:nosave\"/><arc:r"
        "ecord otr=\"false\" xmlns:arc=\"http://jabber.org/protocol/archive\"/></me"
        "ssage>"

qicyt1812

原帖由 Zee 于 2008-3-27 18:43 发表
打开扩展日志查看数据有什么不同。

别人给你回消息，应该不是你模拟出来的。

你可以设置一个循环一直等。然后让那边主动发消息过来。
试试。

请问我该如何设置循环呢？是不是在lrs_receive的前后加上循环语句，那边主动发消息过来，是不是指让对方收到我的信息后，通过gtalk窗口给我回复消息啊，这样就可以收到了吗？

liangjz

loadrunner 录制下来的是当时的(旧)的数据通信过程

如果回放是有一些数据包变化或者随时间戳变化，服务器响应的字节就不一定相同了，导致buffer mismatch。

对于socket协议，需要测试工程师非常清楚协议报格式

Zee

打开扩展日志查看数据有什么不同。

别人给你回消息，应该不是你模拟出来的。

你可以设置一个循环一直等。然后让那边主动发消息过来。
试试。

qicyt1812

请各位高手帮帮忙，帮偶看看到底是啥原因，俺该如何解决这个问题，这个mismatch着实让人头疼呢

qicyt1812

回放日志：

Starting iteration 1.
Starting action Action.
Action.c(12): Notify: Transaction "send_talk" started.
Action.c(14): lrs_send(socket3, buf45)
Action.c(16): lrs_receive(socket3, buf46)
Action.c(16): Mismatch (expected 460 bytes, 421 bytes actually received)
Action.c(18): lrs_send(socket3, buf47)
Action.c(20): Notify: Transaction "send_talk" ended with "Pass" status (Duration: 10.3563 Wasted Time: 10.0000).
Action.c(22): Notify: Transaction "recv_talk" started.
Action.c(24): lrs_set_receive_option
Action.c(26): lrs_receive(socket3, buf48)
Action.c(26): Mismatch (expected 957 bytes, 956 bytes actually received)
Action.c(28): lrs_save_param(socket3, buf48, recv_msg, 757, 5)
Action.c(30): the message that i received is ====: hello
Action.c(32): lrs_set_receive_option
Action.c(36): lrs_receive(socket3, buf50)
Action.c(36): Mismatch (expected 655 bytes, 0 bytes actually received)
Action.c(46): Notify: Transaction "recv_talk" ended with "Pass" status (Duration: 27.2004 Wasted Time: 20.0000).
Ending action Action.
Ending iteration 1.

Action.c(26)和Action.c(36)行接收buffer不规律，有时候能接收到有时候就接收不到，即使接收到了也与录制时候不匹配，我想可能与时间有关吧。

qicyt1812

与Action相关的data.ws：

send buf45 53
        "<presence type=\"probe\" to=\"hongmei791012@gmail.com\"/>"

recv buf46 460
        "<presence from=\"hongmei791012@gmail.com/Talk.v1048BA18485\" to=\"piglovep"
        "ig999@gmail.com/Talk.v935D8DCBE2\"><priority>24</priority><c node=\"http:/"
        "/www.google.com/xmpp/client/caps\" ver=\"1.0.0.104\" ext=\"share-v1 voice-"
        "v1\" xmlns=\"http://jabber.org/protocol/caps\"/><x stamp=\"20080327T03:48:"
        "59\" xmlns=\"jabber:x:delay\"/><status>鎭愰緳鍥�鍛�锛"
        "\x9f"
        "</status><show>dnd</show><x xmlns=\"vcard-temp:x:update\"><photo>b0f87098e"
        "2cba1a705fadc6330c11e391b2cf32c</photo></x></presence>"

send buf47 141
        "<message to=\"hongmei791012@gmail.com\" type=\"chat\" id=\"28\"><body>hell"
        "o</body><active xmlns=\"http://jabber.org/protocol/chatstates\"/></message"
        ">"

recv buf48 957
        "<message to=\"piglovepig999@gmail.com/Talk.v935D8DCBE2\" type=\"chat\" id="
        "\"76\" from=\"hongmei791012@gmail.com/Talk.v1048BA18485\"><composing xmlns"
        "=\"http://jabber.org/protocol/chatstates\"/><nos:x value=\"disabled\" xmln"
        "s:nos=\"google:nosave\"/><arc:record otr=\"false\" xmlns:arc=\"http://jabb"
        "er.org/protocol/archive\"/></message><message to=\"piglovepig999@gmail.com"
        "/Talk.v935D8DCBE2\" type=\"chat\" id=\"77\" from=\"hongmei791012@gmail.com"
        "/Talk.v1048BA18485\"><active xmlns=\"http://jabber.org/protocol/chatstates"
        "\"/><nos:x value=\"disabled\" xmlns:nos=\"google:nosave\"/><arc:record otr"
        "=\"false\" xmlns:arc=\"http://jabber.org/protocol/archive\"/></message><me"
        "ssage to=\"piglovepig999@gmail.com/Talk.v935D8DCBE2\" type=\"chat\" id=\"7"
        "8\" from=\"hongmei791012@gmail.com/Talk.v1048BA18485\"><body>hello</body><"
        "active xmlns=\"http://jabber.org/protocol/chatstates\"/><nos:x value=\"dis"
        "abled\" xmlns:nos=\"google:nosave\"/><arc:record otr=\"false\" xmlns:arc=\""
        "http://jabber.org/protocol/archive\"/></message>"

recv buf50 655
        "<message to=\"piglovepig999@gmail.com/Talk.v935D8DCBE2\" type=\"chat\" id="
        "\"80\" from=\"hongmei791012@gmail.com/Talk.v1048BA18485\"><active xmlns=\""
        "http://jabber.org/protocol/chatstates\"/><nos:x value=\"disabled\" xmlns:n"
        "os=\"google:nosave\"/><arc:record otr=\"false\" xmlns:arc=\"http://jabber."
        "org/protocol/archive\"/></message><message to=\"piglovepig999@gmail.com/Ta"
        "lk.v935D8DCBE2\" type=\"chat\" id=\"81\" from=\"hongmei791012@gmail.com/Ta"
        "lk.v1048BA18485\"><body>you are monkey sun</body><active xmlns=\"http://ja"
        "bber.org/protocol/chatstates\"/><nos:x value=\"disabled\" xmlns:nos=\"goog"
        "le:nosave\"/><arc:record otr=\"false\" xmlns:arc=\"http://jabber.org/proto"
        "col/archive\"/></message>"

-1

qicyt1812

Action：

/*********************************************************************
* Created by Mercury Interactive Windows Sockets Recorder
*
* Created on: Thu Mar 27 11:56:49
*********************************************************************/

#include "lrs.h"


Action()
{
    lr_start_transaction("send_talk");

    lrs_send("socket3", "buf45", LrsLastArg);

    lrs_receive("socket3", "buf46", LrsLastArg);

    lrs_send("socket3", "buf47", LrsLastArg);

    lr_end_transaction("send_talk", LR_AUTO);

    lr_start_transaction("recv_talk");

    lrs_set_receive_option(EndMarker, EndMarker_None);

    lrs_receive("socket3", "buf48", LrsLastArg);

    lrs_save_param("socket3", "buf48", "recv_msg", 757, 5);

    lr_output_message("the message that i received is ====: %s", lr_eval_string("<recv_msg>"));

    lrs_set_receive_option(EndMarker, EndMarker_None);

    lrs_receive("socket3", "buf50", LrsLastArg);

    lr_end_transaction("recv_talk", LR_AUTO);

    return 0;
}