=== Test case: socket_traffic_SUITE:traffic_ping_pong_small_send_and_recv_tcpL/1 (click for source code)
=== Config value:
[{watchdog,<0.125481.0>},
{kernel_factor,5},
{label,{host,undefined}},
{tc_logfile,"/buildroot/otp/lib/kernel/make_test_dir/ct_logs/ct_run.test_server@b2f73ffb0346.2025-02-25_20.49.42/make_test_dir.kernel_test.logs/run.2025-02-25_20.50.11/socket_traffic_suite.traffic_ping_pong_small_send_and_recv_tcpl.html"},
{tc_group_properties,[{name,pp_send_recv}]},
{tc_group_path,[[{name,ping_pong}]]},
{data_dir,"/buildroot/otp/lib/kernel/make_test_dir/kernel_test/socket_traffic_SUITE_data/"},
{priv_dir,"/buildroot/otp/lib/kernel/make_test_dir/ct_logs/ct_run.test_server@b2f73ffb0346.2025-02-25_20.49.42/make_test_dir.kernel_test.logs/run.2025-02-25_20.50.11/log_private/"},
{nodenames,[]}]
=== Current directory is "/buildroot/otp/lib/kernel/make_test_dir/ct_logs/ct_run.test_server@b2f73ffb0346.2025-02-25_20.49.42"
=== Started at 2025-02-25 21:51:19
init_per_testcase(traffic_ping_pong_small_send_and_recv_tcpL) -> entry with
Config: [{watchdog,<0.125501.0>},
{kernel_factor,5},
{label,{host,undefined}},
{tc_logfile,"/buildroot/otp/lib/kernel/make_test_dir/ct_logs/ct_run.test_server@b2f73ffb0346.2025-02-25_20.49.42/make_test_dir.kernel_test.logs/run.2025-02-25_20.50.11/socket_traffic_suite.traffic_ping_pong_small_send_and_recv_tcpl.html"},
{tc_group_properties,[{name,pp_send_recv}]},
{tc_group_path,[[{name,ping_pong}]]},
{data_dir,"/buildroot/otp/lib/kernel/make_test_dir/kernel_test/socket_traffic_SUITE_data/"},
{priv_dir,"/buildroot/otp/lib/kernel/make_test_dir/ct_logs/ct_run.test_server@b2f73ffb0346.2025-02-25_20.49.42/make_test_dir.kernel_test.logs/run.2025-02-25_20.50.11/log_private/"},
{nodenames,[]}]
[21:51:19.821] start server evaluator
[21:51:19][server][<0.125503.0>] evaluate command 1: await start
[21:51:19.821] start client evaluator(s)
[21:51:19][client][<0.125504.0>] evaluate command 1: await start
[21:51:19.821] start 'tester' evaluator
[21:51:19][tester][<0.125505.0>] evaluate command 1: monitor server
[21:51:19][tester][<0.125505.0>] evaluate command 2: monitor client
[21:51:19.822] await evaluator
[21:51:19][tester][<0.125505.0>] evaluate command 3: order server start
[21:51:19][tester][<0.125505.0>] evaluate command 4: await server ready (init)
[21:51:19][server][<0.125503.0>] evaluate command 2: monitor tester
[21:51:19][server][<0.125503.0>] evaluate command 3: which local address
[21:51:19][server][<0.125503.0>] evaluate command 4: create listen socket
[21:51:19][server][<0.125503.0>] evaluate command 5: bind to local address
[21:51:19][server][<0.125503.0>] bound to port: undefined
[21:51:19][server][<0.125503.0>] evaluate command 6: maybe init buffers
[21:51:19][server][<0.125503.0>] RcvBuf is 1048576 (needs at least 24)
[21:51:19][server][<0.125503.0>] SndBuf is 212992 (needs at least 24)
[21:51:19][server][<0.125503.0>] evaluate command 7: make listen socket
[21:51:19][server][<0.125503.0>] evaluate command 8: announce ready (init)
[21:51:19][server][<0.125503.0>] evaluate command 9: await continue (accept)
[21:51:19][tester][<0.125505.0>] evaluate command 5: order client start
[21:51:19][tester][<0.125505.0>] evaluate command 6: await client ready (init)
[21:51:19][client][<0.125504.0>] evaluate command 2: monitor tester
[21:51:19][client][<0.125504.0>] evaluate command 3: create node
[21:51:20][client][<0.125504.0>] Started node "client" - now (global) sync
[21:51:20][client][<0.125504.0>] ping proxy
[21:51:20][client][<0.125504.0>] evaluate command 4: monitor client node
[21:51:20][client][<0.125504.0>] evaluate command 5: create remote client
[21:51:20][client][<0.125504.0>] remote client created: <62316.96.0>
[21:51:20][client][<0.125504.0>] evaluate command 6: monitor remote client
[21:51:20][client][<0.125504.0>] evaluate command 7: order remote client to start
[21:51:20][client][<0.125504.0>] evaluate command 8: await remote client ready
[21:51:20][rclient][<0.96.0>] init
[21:51:20][rclient][<0.96.0>] await start
[21:51:20][rclient][<0.96.0>] RcvBuf is 1048576 (needs at least 24)
[21:51:20][rclient][<0.96.0>] SndBuf is 212992 (needs at least 24)
[21:51:20][rclient][<0.96.0>] announce ready (init)
[21:51:20][rclient][<0.96.0>] await continue (connect)
[21:51:20][client][<0.125504.0>] remote client started
[21:51:20][client][<0.125504.0>] evaluate command 9: announce ready (init)
[21:51:20][tester][<0.125505.0>] evaluate command 7: order server continue (accept)
[21:51:20][client][<0.125504.0>] evaluate command 10: await continue (connect)
[21:51:20][tester][<0.125505.0>] evaluate command 8: sleep
[21:51:20][server][<0.125503.0>] evaluate command 10: accept
[21:51:21][tester][<0.125505.0>] evaluate command 9: order client continue (connect)
[21:51:21][client][<0.125504.0>] evaluate command 11: order remote client to continue (connect)
[21:51:21][tester][<0.125505.0>] evaluate command 10: await server ready (accept)
[21:51:21][client][<0.125504.0>] evaluate command 12: await remote client ready (connect)
[21:51:21][rclient][<0.96.0>] continue (connect): ok
[21:51:21][rclient][<0.96.0>] announce ready (connect)
[21:51:21][rclient][<0.96.0>] await continue (send)
[21:51:21][server][<0.125503.0>] evaluate command 11: create handler
[21:51:21][client][<0.125504.0>] evaluate command 13: announce ready (connect)
[21:51:21][server][<0.125503.0>] handler created: <0.125511.0>
[21:51:21][client][<0.125504.0>] evaluate command 14: await continue (send)
[21:51:21][handler][<0.125511.0>] init
[21:51:21][server][<0.125503.0>] evaluate command 12: monitor handler
[21:51:21][server][<0.125503.0>] evaluate command 13: transfer connection socket ownership to handler
[21:51:21][handler][<0.125511.0>] await start
[21:51:21][server][<0.125503.0>] evaluate command 14: start handler
[21:51:21][server][<0.125503.0>] evaluate command 15: await handler ready (init)
[21:51:21][handler][<0.125511.0>] announce ready (init)
[21:51:21][handler][<0.125511.0>] await continue (recv)
[21:51:21][server][<0.125503.0>] evaluate command 16: announce ready (accept)
[21:51:21][server][<0.125503.0>] evaluate command 17: await continue (recv)
[21:51:21][tester][<0.125505.0>] evaluate command 11: await client ready (connect)
[21:51:21][tester][<0.125505.0>] evaluate command 12: order server continue (recv)
[21:51:21][tester][<0.125505.0>] evaluate command 13: sleep
[21:51:21][server][<0.125503.0>] evaluate command 18: order handler to recv
[21:51:21][server][<0.125503.0>] evaluate command 19: await handler ready (recv)
[21:51:22][tester][<0.125505.0>] evaluate command 14: order client continue (send)
[21:51:22][tester][<0.125505.0>] evaluate command 15: await client ready (send)
[21:51:22][client][<0.125504.0>] evaluate command 15: order remote client to continue (send)
[21:51:22][client][<0.125504.0>] evaluate command 16: await remote client ready (send)
[21:51:22][rclient][<0.96.0>] continue (send): ok with data
[21:51:22][handler][<0.125511.0>] closed - we are done: 1000, 16000, 16000
[21:51:22][handler][<0.125511.0>] announce ready (recv)
[21:51:22][server][<0.125503.0>] evaluate command 20: announce ready (recv)
[21:51:22][handler][<0.125511.0>] await terminate
[21:51:22][server][<0.125503.0>] evaluate command 21: await terminate (from tester)
[21:51:22][rclient][<0.96.0>] announce ready (send): {16000,16000,
#{counters =>
#{read_pkg_max =>
16,
read_waits => 0,
write_pkg_max =>
16,
acc_success => 0,
acc_fails => 0,
acc_tries => 0,
acc_waits => 0,
read_byte =>
16000,
read_fails => 0,
read_pkg => 1000,
read_tries =>
2000,
write_byte =>
16000,
write_fails => 0,
write_pkg =>
1000,
write_tries =>
1000,
write_waits =>
0},
owner => <0.96.0>,
type => stream,
protocol => ip,
domain => local,
ctype => normal,
num_acceptors => 0,
num_readers => 0,
num_writers => 0,
rstates =>
[bound,selected],
wstates =>
[connected]},
{1740,516682,215475},
{1740,516682,253034}}
[21:51:22][rclient][<0.96.0>] await terminate
[21:51:22][client][<0.125504.0>] evaluate command 17: announce ready (send)
[21:51:22][client][<0.125504.0>] evaluate command 18: await terminate (from tester)
[21:51:22][tester][<0.125505.0>] client result:
{16000,16000,
#{counters =>
#{read_pkg_max => 16,read_waits => 0,write_pkg_max => 16,
acc_success => 0,acc_fails => 0,acc_tries => 0,acc_waits => 0,
read_byte => 16000,read_fails => 0,read_pkg => 1000,
read_tries => 2000,write_byte => 16000,write_fails => 0,
write_pkg => 1000,write_tries => 1000,write_waits => 0},
owner => <62316.96.0>,type => stream,protocol => ip,domain => local,
ctype => normal,num_acceptors => 0,num_readers => 0,num_writers => 0,
rstates => [bound,selected],
wstates => [connected]},
{1740,516682,215475},
{1740,516682,253034}}
[21:51:22][tester][<0.125505.0>] evaluate command 16: await server ready (recv)
[21:51:22][tester][<0.125505.0>] server result:
{1000,16000,16000,{1740,516682,215549},{1740,516682,253257}}
[21:51:22][tester][<0.125505.0>] evaluate command 17: present result
[21:51:22][tester][<0.125505.0>] process result data:
Num: 1000
Server Sent: 16000
Server Recv: 16000
Server Start: {1740,516682,215549}
Server Stop: {1740,516682,253257}
Server Time: 38
Client Sent: 16000
Client Recv: 16000
Client Start: {1740,516682,215475}
Client Stop: {1740,516682,253034}
Client Time: 38
[21:51:22][tester][<0.125505.0>] Results: 1000 messages exchanged
Server: 38 msec
0.04 msec/message (roundtrip)
26.32 messages/msec (roundtrip)
421 bytes/msec sent
421 bytes/msec received
Client: 38 msec
0.04 msec/message (roundtrip)
26.32 messages/msec (roundtrip)
421 bytes/msec sent
421 bytes/msec received
[21:51:22][tester][<0.125505.0>] evaluate command 18: order client to terminate
[21:51:22][tester][<0.125505.0>] evaluate command 19: await client termination
[21:51:22][client][<0.125504.0>] evaluate command 19: stop remote client
[21:51:22][client][<0.125504.0>] evaluate command 20: await remote client termination
[21:51:22][rclient][<0.96.0>] termination received: normal
[21:51:22][rclient][<0.96.0>] terminating
[21:51:22][client][<0.125504.0>] evaluate command 21: stop client node
[21:51:22][client][<0.125504.0>] evaluate command 22: await client node termination
[21:51:22][client][<0.125504.0>] Success node stop - await nodedown
[21:51:22][client][<0.125504.0>] nodedown received - cleanup
[21:51:22][client][<0.125504.0>] evaluate command 23: finish
[21:51:22][tester][<0.125505.0>] evaluate command 20: order server to terminate
[21:51:22][tester][<0.125505.0>] evaluate command 21: await server termination
[21:51:22] evaluator 'client' (<0.125504.0>) success
[21:51:22][server][<0.125503.0>] evaluate command 22: stop handler
[21:51:22][server][<0.125503.0>] evaluate command 23: await handler termination
[21:51:22][handler][<0.125511.0>] terminating
[21:51:22][server][<0.125503.0>] evaluate command 24: close listen socket
[21:51:22][server][<0.125503.0>] try unlink path:
/tmp/esock_test_server_1740516679822472151
[21:51:22][server][<0.125503.0>] path unlinked:
Path: /tmp/esock_test_server_1740516679822472151
[21:51:22][server][<0.125503.0>] evaluate command 25: finish
[21:51:22] evaluator 'server' (<0.125503.0>) success
[21:51:22][tester][<0.125505.0>] evaluate command 22: finish
[21:51:22] evaluator 'tester' (<0.125505.0>) success
=== Ended at 2025-02-25 21:51:23
=== successfully completed test case
=== === Returned value: ok
Test run history | Top level test index | Latest test result