27 Aug
2023
27 Aug
'23
10:14 p.m.
arehbein has uploaded this change for review. (
https://gerrit.osmocom.org/c/libosmo-netif/+/34224 )
Change subject: stream tests: Eliminate timestamps from output
......................................................................
stream tests: Eliminate timestamps from output
Timestamps using override and osmo_gettimeofday() aren't guaranteed to be
predictable anymore for whatever reason. This removes all timestamps
from the test logs so checking against .err and .ok files doesn't fail
due to different timestamps.
Change-Id: I7faed932927d4f6e328a28c7f30a647a7272e89c
---
M tests/stream/stream_test.c
M tests/stream/stream_test.err
M tests/stream/stream_test.ok
3 files changed, 140 insertions(+), 135 deletions(-)
git pull ssh://gerrit.osmocom.org:29418/libosmo-netif refs/changes/24/34224/1
diff --git a/tests/stream/stream_test.c b/tests/stream/stream_test.c
index 20afc55..69f0450 100644
--- a/tests/stream/stream_test.c
+++ b/tests/stream/stream_test.c
@@ -63,9 +63,7 @@
/* client defs */
#define LOGCLI(cli, fmt, args...) do { \
- struct timeval tv; \
- osmo_gettimeofday(&tv, NULL); \
- printf("{%lu.%06lu} [%s] Client's %s(): " fmt, tv.tv_sec, tv.tv_usec, \
+ printf("[%s] Client's %s(): " fmt, \
osmo_stream_cli_get_data(cli) ? "OK" : "NA", __func__,
##args); \
} while (0)
@@ -240,9 +238,7 @@
printf("[%s] Server's %s(): " fmt, osmo_stream_srv_link_get_data(lnk) ?
"OK" : "NA", __func__, ##args)
#define LOGSRV(srv, fmt, args...) do { \
- struct timeval tv; \
- osmo_gettimeofday(&tv, NULL); \
- printf("{%lu.%06lu} [%s|%s] Server's %s(): " fmt, tv.tv_sec, tv.tv_usec,
\
+ printf("[%s|%s] Server's %s(): " fmt, \
osmo_stream_srv_get_data(srv) ? "OK" : "NA", \
osmo_stream_srv_link_get_data(osmo_stream_srv_get_master(srv)) ? "OK"
: "NA", \
__func__, ##args); \
@@ -339,7 +335,6 @@
static void test_recon(void *ctx, const char *host, unsigned port, unsigned steps, struct
osmo_stream_srv_link *lnk,
bool autoreconnect)
{
- struct timeval tv;
struct osmo_stream_cli *cli = make_client(ctx, host, port, autoreconnect);
if (!cli)
return;
@@ -353,22 +348,20 @@
while(steps--) {
osmo_gettimeofday_override_add(0, 1); /* small increment to easily spot iterations */
osmo_select_main(0);
- osmo_gettimeofday(&tv, NULL);
- fprintf(stderr, "\n{%lu.%06lu} %s test step %u [client %s, server %s], FD reg
%u\n",
- tv.tv_sec, tv.tv_usec, ASTR(autoreconnect), steps,
+ fprintf(stderr, "\n%s test step %u [client %s, server %s], FD reg %u\n",
+ ASTR(autoreconnect), steps,
osmo_stream_cli_get_data(cli) ? "OK" : "NA",
osmo_stream_srv_link_get_data(lnk) ? "OK" : "NA",
osmo_fd_is_registered(osmo_stream_cli_get_ofd(cli)));
if (test_stop_requested(lnk)) {
- printf("{%lu.%06lu} Server requested test termination\n",
- tv.tv_sec, tv.tv_usec);
+ printf("Server requested test termination\n");
steps = 0;
}
}
osmo_stream_cli_destroy(cli);
- printf("{%lu.%06lu} %s test complete.\n\n", tv.tv_sec, tv.tv_usec,
ASTR(autoreconnect));
+ printf("%s test complete.\n\n", ASTR(autoreconnect));
}
/* Segmentation test code (using IPA) */
@@ -645,8 +638,6 @@
void *tall_test = talloc_named_const(NULL, 1, "osmo_stream_test");
osmo_gettimeofday_override = true;
- osmo_gettimeofday_override_time.tv_sec = 2;
- osmo_gettimeofday_override_time.tv_usec = 0;
msgb_talloc_ctx_init(tall_test, 0);
osmo_init_logging2(tall_test, &osmo_stream_test_log_info);
diff --git a/tests/stream/stream_test.err b/tests/stream/stream_test.err
index 03ce0cf..f920258 100644
--- a/tests/stream/stream_test.err
+++ b/tests/stream/stream_test.err
@@ -1,51 +1,51 @@
SRV(srv_link_test,127.0.0.11:1111) accept()ed new link from 127.0.0.1:8976
CLICONN(cli_test,r=127.0.0.11:1111<->l=127.0.0.1:8976){CONNECTING} connection
established
-{2.000001} autoreconnecting test step 11 [client NA, server OK], FD reg 1
+autoreconnecting test step 11 [client NA, server OK], FD reg 1
-{2.000002} autoreconnecting test step 10 [client NA, server OK], FD reg 1
+autoreconnecting test step 10 [client NA, server OK], FD reg 1
-{2.000003} autoreconnecting test step 9 [client NA, server OK], FD reg 1
+autoreconnecting test step 9 [client NA, server OK], FD reg 1
-{2.000004} autoreconnecting test step 8 [client NA, server OK], FD reg 1
+autoreconnecting test step 8 [client NA, server OK], FD reg 1
-{2.000005} autoreconnecting test step 7 [client OK, server OK], FD reg 1
+autoreconnecting test step 7 [client OK, server OK], FD reg 1
-{2.000006} autoreconnecting test step 6 [client OK, server OK], FD reg 1
+autoreconnecting test step 6 [client OK, server OK], FD reg 1
-{2.000007} autoreconnecting test step 5 [client OK, server OK], FD reg 1
+autoreconnecting test step 5 [client OK, server OK], FD reg 1
CLICONN(cli_test,r=127.0.0.11:1111<->l=127.0.0.1:8976){CONNECTED} connection closed
with srv
CLICONN(cli_test,r=127.0.0.11:1111<->l=127.0.0.1:8976){WAIT_RECONNECT} retrying
reconnect in 9 seconds...
-{11.000008} autoreconnecting test step 4 [client OK, server OK], FD reg 0
+autoreconnecting test step 4 [client OK, server OK], FD reg 0
-{11.000009} autoreconnecting test step 3 [client OK, server OK], FD reg 1
+autoreconnecting test step 3 [client OK, server OK], FD reg 1
SRV(srv_link_test,127.0.0.11:1111) accept()ed new link from 127.0.0.1:8976
CLICONN(cli_test,r=127.0.0.11:1111<->l=127.0.0.1:8976){CONNECTING} connection
established
-{11.000010} autoreconnecting test step 2 [client OK, server OK], FD reg 0
+autoreconnecting test step 2 [client OK, server OK], FD reg 0
SRVCONN(srv_test,r=127.0.0.1:8976<->l=127.0.0.11:1111) connection closed with
client
-{11.000011} autoreconnecting test step 1 [client OK, server NA], FD reg 0
+autoreconnecting test step 1 [client OK, server NA], FD reg 0
SRV(srv_link_test,127.0.0.11:1111) accept()ed new link from 127.0.0.1:8976
CLICONN(cli_test,r=127.0.0.11:1111<->l=127.0.0.1:8976){CONNECTING} connection
established
-{11.000012} non-reconnecting test step 7 [client NA, server OK], FD reg 1
+non-reconnecting test step 7 [client NA, server OK], FD reg 1
-{11.000013} non-reconnecting test step 6 [client NA, server OK], FD reg 1
+non-reconnecting test step 6 [client NA, server OK], FD reg 1
-{11.000014} non-reconnecting test step 5 [client NA, server OK], FD reg 1
+non-reconnecting test step 5 [client NA, server OK], FD reg 1
-{11.000015} non-reconnecting test step 4 [client NA, server OK], FD reg 1
+non-reconnecting test step 4 [client NA, server OK], FD reg 1
-{11.000016} non-reconnecting test step 3 [client OK, server OK], FD reg 1
+non-reconnecting test step 3 [client OK, server OK], FD reg 1
-{11.000017} non-reconnecting test step 2 [client OK, server OK], FD reg 1
+non-reconnecting test step 2 [client OK, server OK], FD reg 1
-{11.000018} non-reconnecting test step 1 [client OK, server OK], FD reg 1
+non-reconnecting test step 1 [client OK, server OK], FD reg 1
CLICONN(cli_test,r=127.0.0.11:1111<->l=127.0.0.1:8976){CONNECTED} connection closed
with srv
CLICONN(cli_test,r=127.0.0.11:1111<->l=127.0.0.1:8976){CLOSED} not reconnecting,
disabled
-{20.000019} non-reconnecting test step 0 [client OK, server OK], FD reg 0
+non-reconnecting test step 0 [client OK, server OK], FD reg 0
SRV(srv_link_test,127.0.0.11:1111) accept()ed new link from 127.0.0.1:8977
CLICONN(,r=127.0.0.11:1111<->l=127.0.0.1:8977){CONNECTING} connection established
diff --git a/tests/stream/stream_test.ok b/tests/stream/stream_test.ok
index f21b486..5322d93 100644
--- a/tests/stream/stream_test.ok
+++ b/tests/stream/stream_test.ok
@@ -3,118 +3,118 @@
=======================================
Client/Server entering autoreconnecting event loop...
=======================================
-{2.000001} [NA] Client's connect_cb_cli(): callback triggered <initial>
-{2.000001} [NA] Client's connect_cb_cli(): sent 29 bytes message: 48 69 21 20 66 72
6f 6d 20 63 6f 6e 6e 65 63 74 20 63 61 6c 6c 62 61 63 6b 20 3a 2d 50
-{2.000003} [NA|OK] Server's read_cb_srv(): callback triggered
-{2.000003} [NA|OK] Server's read_cb_srv(): received 29(29) bytes: 48 69 21 20 66 72
6f 6d 20 63 6f 6e 6e 65 63 74 20 63 61 6c 6c 62 61 63 6b 20 3a 2d 50
-{2.000003} [NA|OK] Server's read_cb_srv(): sent 11 bytes message: 72 65 61 64 5f 63
62 5f 73 72 76
-{2.000003} [OK|OK] Server's read_cb_srv(): keep initial client connection
-{2.000005} [NA] Client's read_cb_cli(): callback triggered
-{2.000005} [NA] Client's read_cb_cli(): received 11(11) bytes: 72 65 61 64 5f 63 62
5f 73 72 76
-{2.000005} [NA] Client's read_cb_cli(): initial read, contacting server
-{2.000005} [OK] Client's read_cb_cli(): sent 29 bytes message: 44 6f 68 2c 20 72 65
73 70 6f 6e 64 69 6e 67 20 74 6f 20 73 65 72 76 65 72 20 3a 2d 44
-{2.000007} [OK|OK] Server's read_cb_srv(): callback triggered
-{2.000007} [OK|OK] Server's read_cb_srv(): received 29(29) bytes: 44 6f 68 2c 20 72
65 73 70 6f 6e 64 69 6e 67 20 74 6f 20 73 65 72 76 65 72 20 3a 2d 44
-{2.000007} [OK|OK] Server's read_cb_srv(): sent 11 bytes message: 72 65 61 64 5f 63
62 5f 73 72 76
-{2.000007} [OK|OK] Server's read_cb_srv(): force client disconnect on subsequent
call
-{2.000008} [OK] Client's read_cb_cli(): callback triggered
-{2.000008} [OK] Client's read_cb_cli(): 0-byte read, auto-reconnect will be triggered
if enabled
-{11.000010} [OK] Client's connect_cb_cli(): callback triggered <reconnected>
-{11.000010} [OK] Client's connect_cb_cli(): closing connection
-{11.000011} [NA|OK] Server's read_cb_srv(): callback triggered
-{11.000011} [NA|OK] Server's read_cb_srv(): client have already closed connection
-{11.000011} Server requested test termination
-{11.000011} autoreconnecting test complete.
+[NA] Client's connect_cb_cli(): callback triggered <initial>
+[NA] Client's connect_cb_cli(): sent 29 bytes message: 48 69 21 20 66 72 6f 6d 20 63
6f 6e 6e 65 63 74 20 63 61 6c 6c 62 61 63 6b 20 3a 2d 50
+[NA|OK] Server's read_cb_srv(): callback triggered
+[NA|OK] Server's read_cb_srv(): received 29(29) bytes: 48 69 21 20 66 72 6f 6d 20 63
6f 6e 6e 65 63 74 20 63 61 6c 6c 62 61 63 6b 20 3a 2d 50
+[NA|OK] Server's read_cb_srv(): sent 11 bytes message: 72 65 61 64 5f 63 62 5f 73 72
76
+[OK|OK] Server's read_cb_srv(): keep initial client connection
+[NA] Client's read_cb_cli(): callback triggered
+[NA] Client's read_cb_cli(): received 11(11) bytes: 72 65 61 64 5f 63 62 5f 73 72 76
+[NA] Client's read_cb_cli(): initial read, contacting server
+[OK] Client's read_cb_cli(): sent 29 bytes message: 44 6f 68 2c 20 72 65 73 70 6f 6e
64 69 6e 67 20 74 6f 20 73 65 72 76 65 72 20 3a 2d 44
+[OK|OK] Server's read_cb_srv(): callback triggered
+[OK|OK] Server's read_cb_srv(): received 29(29) bytes: 44 6f 68 2c 20 72 65 73 70 6f
6e 64 69 6e 67 20 74 6f 20 73 65 72 76 65 72 20 3a 2d 44
+[OK|OK] Server's read_cb_srv(): sent 11 bytes message: 72 65 61 64 5f 63 62 5f 73 72
76
+[OK|OK] Server's read_cb_srv(): force client disconnect on subsequent call
+[OK] Client's read_cb_cli(): callback triggered
+[OK] Client's read_cb_cli(): 0-byte read, auto-reconnect will be triggered if
enabled
+[OK] Client's connect_cb_cli(): callback triggered <reconnected>
+[OK] Client's connect_cb_cli(): closing connection
+[NA|OK] Server's read_cb_srv(): callback triggered
+[NA|OK] Server's read_cb_srv(): client have already closed connection
+Server requested test termination
+autoreconnecting test complete.
Prepare non-reconnecting stream client...
=======================================
Client/Server entering non-reconnecting event loop...
=======================================
-{11.000012} [NA] Client's connect_cb_cli(): callback triggered <initial>
-{11.000012} [NA] Client's connect_cb_cli(): sent 29 bytes message: 48 69 21 20 66 72
6f 6d 20 63 6f 6e 6e 65 63 74 20 63 61 6c 6c 62 61 63 6b 20 3a 2d 50
-{11.000014} [NA|OK] Server's read_cb_srv(): callback triggered
-{11.000014} [NA|OK] Server's read_cb_srv(): received 29(29) bytes: 48 69 21 20 66 72
6f 6d 20 63 6f 6e 6e 65 63 74 20 63 61 6c 6c 62 61 63 6b 20 3a 2d 50
-{11.000014} [NA|OK] Server's read_cb_srv(): sent 11 bytes message: 72 65 61 64 5f 63
62 5f 73 72 76
-{11.000014} [OK|OK] Server's read_cb_srv(): keep initial client connection
-{11.000016} [NA] Client's read_cb_cli(): callback triggered
-{11.000016} [NA] Client's read_cb_cli(): received 11(11) bytes: 72 65 61 64 5f 63 62
5f 73 72 76
-{11.000016} [NA] Client's read_cb_cli(): initial read, contacting server
-{11.000016} [OK] Client's read_cb_cli(): sent 29 bytes message: 44 6f 68 2c 20 72 65
73 70 6f 6e 64 69 6e 67 20 74 6f 20 73 65 72 76 65 72 20 3a 2d 44
-{11.000018} [OK|OK] Server's read_cb_srv(): callback triggered
-{11.000018} [OK|OK] Server's read_cb_srv(): received 29(29) bytes: 44 6f 68 2c 20 72
65 73 70 6f 6e 64 69 6e 67 20 74 6f 20 73 65 72 76 65 72 20 3a 2d 44
-{11.000018} [OK|OK] Server's read_cb_srv(): sent 11 bytes message: 72 65 61 64 5f 63
62 5f 73 72 76
-{11.000018} [OK|OK] Server's read_cb_srv(): force client disconnect on subsequent
call
-{11.000019} [OK] Client's read_cb_cli(): callback triggered
-{11.000019} [OK] Client's read_cb_cli(): 0-byte read, auto-reconnect will be
triggered if enabled
-{20.000019} non-reconnecting test complete.
+[NA] Client's connect_cb_cli(): callback triggered <initial>
+[NA] Client's connect_cb_cli(): sent 29 bytes message: 48 69 21 20 66 72 6f 6d 20 63
6f 6e 6e 65 63 74 20 63 61 6c 6c 62 61 63 6b 20 3a 2d 50
+[NA|OK] Server's read_cb_srv(): callback triggered
+[NA|OK] Server's read_cb_srv(): received 29(29) bytes: 48 69 21 20 66 72 6f 6d 20 63
6f 6e 6e 65 63 74 20 63 61 6c 6c 62 61 63 6b 20 3a 2d 50
+[NA|OK] Server's read_cb_srv(): sent 11 bytes message: 72 65 61 64 5f 63 62 5f 73 72
76
+[OK|OK] Server's read_cb_srv(): keep initial client connection
+[NA] Client's read_cb_cli(): callback triggered
+[NA] Client's read_cb_cli(): received 11(11) bytes: 72 65 61 64 5f 63 62 5f 73 72 76
+[NA] Client's read_cb_cli(): initial read, contacting server
+[OK] Client's read_cb_cli(): sent 29 bytes message: 44 6f 68 2c 20 72 65 73 70 6f 6e
64 69 6e 67 20 74 6f 20 73 65 72 76 65 72 20 3a 2d 44
+[OK|OK] Server's read_cb_srv(): callback triggered
+[OK|OK] Server's read_cb_srv(): received 29(29) bytes: 44 6f 68 2c 20 72 65 73 70 6f
6e 64 69 6e 67 20 74 6f 20 73 65 72 76 65 72 20 3a 2d 44
+[OK|OK] Server's read_cb_srv(): sent 11 bytes message: 72 65 61 64 5f 63 62 5f 73 72
76
+[OK|OK] Server's read_cb_srv(): force client disconnect on subsequent call
+[OK] Client's read_cb_cli(): callback triggered
+[OK] Client's read_cb_cli(): 0-byte read, auto-reconnect will be triggered if
enabled
+non-reconnecting test complete.
______________________________________Running test
test_segm_ipa_stream_srv______________________________________
-{20.000020} [OK] Client's test_segm_ipa_stream_srv_cli_connect_cb(): [1-cli]
Appending msg of type IPAC_MSGT_PING into buffer
-{20.000020} [OK] Client's test_segm_ipa_stream_srv_cli_connect_cb(): (msg dump: 00
01 fe 00 )
-{20.000020} [OK] Client's test_segm_ipa_stream_srv_cli_connect_cb(): [2-cli]
Appending msg of type IPAC_MSGT_PONG into buffer
-{20.000020} [OK] Client's test_segm_ipa_stream_srv_cli_connect_cb(): (msg dump: 00
01 fe 01 )
-{20.000020} [OK] Client's test_segm_ipa_stream_srv_cli_connect_cb(): [3-cli]
Appending msg of type IPAC_MSGT_PING into buffer
-{20.000020} [OK] Client's test_segm_ipa_stream_srv_cli_connect_cb(): (msg dump: 00
01 fe 00 )
-{20.000020} [OK] Client's test_segm_ipa_stream_srv_cli_connect_cb(): [4-cli]
Appending msg of type IPAC_MSGT_ID_RESP into buffer
-{20.000020} [OK] Client's test_segm_ipa_stream_srv_cli_connect_cb(): (msg dump: 00
07 fe 05 01 01 de ad be ef )
-{20.000020} [OK] Client's test_segm_ipa_stream_srv_cli_connect_cb(): [(04 + 1/3)-cli]
Appending 1st third of msg of type IPAC_MSGT_ID_GET into buffer
-{20.000020} [OK] Client's test_segm_ipa_stream_srv_cli_connect_cb(): (dump: 00 03 )
-{20.000020} [OK] Client's test_segm_ipa_stream_srv_cli_connect_cb(): Sending 4 + 1/3
messages as one:
-{20.000020} [OK] Client's test_segm_ipa_stream_srv_cli_connect_cb(): (msg dump: 00
01 fe 00 00 01 fe 01 00 01 fe 00 00 07 fe 05 01 01 de ad be ef 00 03 )
+[OK] Client's test_segm_ipa_stream_srv_cli_connect_cb(): [1-cli] Appending msg of
type IPAC_MSGT_PING into buffer
+[OK] Client's test_segm_ipa_stream_srv_cli_connect_cb(): (msg dump: 00 01 fe 00 )
+[OK] Client's test_segm_ipa_stream_srv_cli_connect_cb(): [2-cli] Appending msg of
type IPAC_MSGT_PONG into buffer
+[OK] Client's test_segm_ipa_stream_srv_cli_connect_cb(): (msg dump: 00 01 fe 01 )
+[OK] Client's test_segm_ipa_stream_srv_cli_connect_cb(): [3-cli] Appending msg of
type IPAC_MSGT_PING into buffer
+[OK] Client's test_segm_ipa_stream_srv_cli_connect_cb(): (msg dump: 00 01 fe 00 )
+[OK] Client's test_segm_ipa_stream_srv_cli_connect_cb(): [4-cli] Appending msg of
type IPAC_MSGT_ID_RESP into buffer
+[OK] Client's test_segm_ipa_stream_srv_cli_connect_cb(): (msg dump: 00 07 fe 05 01
01 de ad be ef )
+[OK] Client's test_segm_ipa_stream_srv_cli_connect_cb(): [(04 + 1/3)-cli] Appending
1st third of msg of type IPAC_MSGT_ID_GET into buffer
+[OK] Client's test_segm_ipa_stream_srv_cli_connect_cb(): (dump: 00 03 )
+[OK] Client's test_segm_ipa_stream_srv_cli_connect_cb(): Sending 4 + 1/3 messages as
one:
+[OK] Client's test_segm_ipa_stream_srv_cli_connect_cb(): (msg dump: 00 01 fe 00 00
01 fe 01 00 01 fe 00 00 07 fe 05 01 01 de ad be ef 00 03 )
-{20.000022} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): [1-srv] Received
IPA message from stream (payload len = 1)
-{20.000022} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): msg buff data
(including stripped headers): 00 01 fe 00
-{20.000022} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): IPA payload: 00
-{20.000022} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): Type:
IPAC_MSGT_PING
-{20.000022} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): (msg dump: 00
01 fe 00 )
-{20.000022} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): [2-srv] Received
IPA message from stream (payload len = 1)
-{20.000022} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): msg buff data
(including stripped headers): 00 01 fe 01
-{20.000022} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): IPA payload: 01
-{20.000022} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): Type:
IPAC_MSGT_PONG
-{20.000022} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): (msg dump: 00
01 fe 01 )
-{20.000022} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): [3-srv] Received
IPA message from stream (payload len = 1)
-{20.000022} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): msg buff data
(including stripped headers): 00 01 fe 00
-{20.000022} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): IPA payload: 00
-{20.000022} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): Type:
IPAC_MSGT_PING
-{20.000022} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): (msg dump: 00
01 fe 00 )
-{20.000022} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): [4-srv] Received
IPA message from stream (payload len = 7)
-{20.000022} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): msg buff data
(including stripped headers): 00 07 fe 05 01 01 de ad be ef
-{20.000022} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): IPA payload: 05
01 01 de ad be ef
-{20.000022} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): Type:
IPAC_MSGT_ID_RESP
-{20.000022} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): (msg dump: 00
07 fe 05 01 01 de ad be ef )
-{20.000022} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): Send
IPAC_MSGT_ID_GET to trigger client to send next third
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): [1-srv] Received IPA message
from stream (payload len = 1)
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): msg buff data (including
stripped headers): 00 01 fe 00
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): IPA payload: 00
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): Type: IPAC_MSGT_PING
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): (msg dump: 00 01 fe 00 )
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): [2-srv] Received IPA message
from stream (payload len = 1)
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): msg buff data (including
stripped headers): 00 01 fe 01
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): IPA payload: 01
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): Type: IPAC_MSGT_PONG
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): (msg dump: 00 01 fe 01 )
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): [3-srv] Received IPA message
from stream (payload len = 1)
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): msg buff data (including
stripped headers): 00 01 fe 00
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): IPA payload: 00
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): Type: IPAC_MSGT_PING
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): (msg dump: 00 01 fe 00 )
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): [4-srv] Received IPA message
from stream (payload len = 7)
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): msg buff data (including
stripped headers): 00 07 fe 05 01 01 de ad be ef
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): IPA payload: 05 01 01 de ad
be ef
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): Type: IPAC_MSGT_ID_RESP
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): (msg dump: 00 07 fe 05 01
01 de ad be ef )
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): Send IPAC_MSGT_ID_GET to
trigger client to send next third
-{20.000024} [OK] Client's test_segm_ipa_stream_srv_cli_read_cb(): Received message
from stream (total len = 6)
-{20.000024} [OK] Client's test_segm_ipa_stream_srv_cli_read_cb(): Type:
IPAC_MSGT_ID_GET
-{20.000024} [OK] Client's test_segm_ipa_stream_srv_cli_read_cb(): Got
IPAC_MSGT_ID_GET from server
-{20.000024} [OK] Client's test_segm_ipa_stream_srv_cli_read_cb(): [(4 + 2/3) -cli]
Appending: Second third of IPAC_MSGT_ID_GET
-{20.000024} [OK] Client's test_segm_ipa_stream_srv_cli_read_cb(): Sending: Second
third of IPAC_MSGT_ID_GET
-{20.000024} [OK] Client's test_segm_ipa_stream_srv_cli_read_cb(): (msg dump: fe 04
)
+[OK] Client's test_segm_ipa_stream_srv_cli_read_cb(): Received message from stream
(total len = 6)
+[OK] Client's test_segm_ipa_stream_srv_cli_read_cb(): Type: IPAC_MSGT_ID_GET
+[OK] Client's test_segm_ipa_stream_srv_cli_read_cb(): Got IPAC_MSGT_ID_GET from
server
+[OK] Client's test_segm_ipa_stream_srv_cli_read_cb(): [(4 + 2/3) -cli] Appending:
Second third of IPAC_MSGT_ID_GET
+[OK] Client's test_segm_ipa_stream_srv_cli_read_cb(): Sending: Second third of
IPAC_MSGT_ID_GET
+[OK] Client's test_segm_ipa_stream_srv_cli_read_cb(): (msg dump: fe 04 )
-{20.500024} [OK] Client's send_last_third(): Delay for sending last third of message
is over
-{20.500024} [OK] Client's send_last_third(): [5-cli] Appending: Last third of
IPAC_MSGT_ID_GET
-{20.500024} [OK] Client's send_last_third(): [6-cli] Appending msg of type
IPAC_MSGT_PONG into buffer
-{20.500024} [OK] Client's send_last_third(): (msg dump: 00 01 fe 01 )
-{20.500024} [OK] Client's send_last_third(): [7-cli] Appending msg of type
IPAC_MSGT_PONG into buffer
-{20.500024} [OK] Client's send_last_third(): (msg dump: 00 01 fe 01 )
-{20.500024} [OK] Client's send_last_third(): Sending:[ Last third of
IPAC_MSGT_ID_GET | IPAC_MSGT_PONG | IPAC_MSGT_PONG ]
-{20.500024} [OK] Client's send_last_third(): (msg dump: 01 01 00 01 fe 01 00 01 fe
01 )
+[OK] Client's send_last_third(): Delay for sending last third of message is over
+[OK] Client's send_last_third(): [5-cli] Appending: Last third of IPAC_MSGT_ID_GET
+[OK] Client's send_last_third(): [6-cli] Appending msg of type IPAC_MSGT_PONG into
buffer
+[OK] Client's send_last_third(): (msg dump: 00 01 fe 01 )
+[OK] Client's send_last_third(): [7-cli] Appending msg of type IPAC_MSGT_PONG into
buffer
+[OK] Client's send_last_third(): (msg dump: 00 01 fe 01 )
+[OK] Client's send_last_third(): Sending:[ Last third of IPAC_MSGT_ID_GET |
IPAC_MSGT_PONG | IPAC_MSGT_PONG ]
+[OK] Client's send_last_third(): (msg dump: 01 01 00 01 fe 01 00 01 fe 01 )
-{20.500026} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): [5-srv] Received
IPA message from stream (payload len = 3)
-{20.500026} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): msg buff data
(including stripped headers): 00 03 fe 04 01 01
-{20.500026} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): IPA payload: 04
01 01
-{20.500026} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): Type:
IPAC_MSGT_ID_GET
-{20.500026} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): (msg dump: 00
03 fe 04 01 01 )
-{20.500026} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): [6-srv] Received
IPA message from stream (payload len = 1)
-{20.500026} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): msg buff data
(including stripped headers): 00 01 fe 01
-{20.500026} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): IPA payload: 01
-{20.500026} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): Type:
IPAC_MSGT_PONG
-{20.500026} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): (msg dump: 00
01 fe 01 )
-{20.500026} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): [7-srv] Received
IPA message from stream (payload len = 1)
-{20.500026} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): msg buff data
(including stripped headers): 00 01 fe 01
-{20.500026} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): IPA payload: 01
-{20.500026} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): Type:
IPAC_MSGT_PONG
-{20.500026} [NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): (msg dump: 00
01 fe 01 )
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): [5-srv] Received IPA message
from stream (payload len = 3)
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): msg buff data (including
stripped headers): 00 03 fe 04 01 01
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): IPA payload: 04 01 01
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): Type: IPAC_MSGT_ID_GET
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): (msg dump: 00 03 fe 04 01
01 )
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): [6-srv] Received IPA message
from stream (payload len = 1)
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): msg buff data (including
stripped headers): 00 01 fe 01
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): IPA payload: 01
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): Type: IPAC_MSGT_PONG
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): (msg dump: 00 01 fe 01 )
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): [7-srv] Received IPA message
from stream (payload len = 1)
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): msg buff data (including
stripped headers): 00 01 fe 01
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): IPA payload: 01
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): Type: IPAC_MSGT_PONG
+[NA|OK] Server's test_segm_ipa_stream_srv_srv_read_cb(): (msg dump: 00 01 fe 01 )
==================================Test test_segm_ipa_stream_srv
complete========================================
Stream tests completed
--
To view, visit https://gerrit.osmocom.org/c/libosmo-netif/+/34224
To unsubscribe, or for help writing mail filters, visit
https://gerrit.osmocom.org/settings
Gerrit-Project: libosmo-netif
Gerrit-Branch: master
Gerrit-Change-Id: I7faed932927d4f6e328a28c7f30a647a7272e89c
Gerrit-Change-Number: 34224
Gerrit-PatchSet: 1
Gerrit-Owner: arehbein <arehbein(a)sysmocom.de>
Gerrit-MessageType: newchange