=== Test case: socket_traffic_SUITE:traffic_ping_pong_small_send_and_recv_tcp4/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_tcp4.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:16
init_per_testcase(traffic_ping_pong_small_send_and_recv_tcp4) -> entry with
Config: [{watchdog,<0.125484.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_tcp4.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:16.319] start server evaluator
[21:51:16][server][<0.125486.0>] evaluate command 1: await start
[21:51:16.319] start client evaluator(s)
[21:51:16][client][<0.125487.0>] evaluate command 1: await start
[21:51:16.320] start 'tester' evaluator
[21:51:16][tester][<0.125488.0>] evaluate command 1: monitor server
[21:51:16][tester][<0.125488.0>] evaluate command 2: monitor client
[21:51:16.320] await evaluator
[21:51:16][tester][<0.125488.0>] evaluate command 3: order server start
[21:51:16][server][<0.125486.0>] evaluate command 2: monitor tester
[21:51:16][tester][<0.125488.0>] evaluate command 4: await server ready (init)
[21:51:16][server][<0.125486.0>] evaluate command 3: which local address
[21:51:16][server][<0.125486.0>] evaluate command 4: create listen socket
[21:51:16][server][<0.125486.0>] evaluate command 5: bind to local address
[21:51:16][server][<0.125486.0>] bound to port: 36583
[21:51:16][server][<0.125486.0>] evaluate command 6: maybe init buffers
[21:51:16][server][<0.125486.0>] RcvBuf is 131072 (needs at least 24)
[21:51:16][server][<0.125486.0>] SndBuf is 16384 (needs at least 24)
[21:51:16][server][<0.125486.0>] evaluate command 7: make listen socket
[21:51:16][server][<0.125486.0>] evaluate command 8: announce ready (init)
[21:51:16][server][<0.125486.0>] evaluate command 9: await continue (accept)
[21:51:16][tester][<0.125488.0>] evaluate command 5: order client start
[21:51:16][tester][<0.125488.0>] evaluate command 6: await client ready (init)
[21:51:16][client][<0.125487.0>] evaluate command 2: monitor tester
[21:51:16][client][<0.125487.0>] evaluate command 3: create node
[21:51:16][client][<0.125487.0>] Started node "client" - now (global) sync
[21:51:16][client][<0.125487.0>] ping proxy
[21:51:16][client][<0.125487.0>] evaluate command 4: monitor client node
[21:51:16][client][<0.125487.0>] evaluate command 5: create remote client
[21:51:16][client][<0.125487.0>] remote client created: <62316.96.0>
[21:51:16][client][<0.125487.0>] evaluate command 6: monitor remote client
[21:51:16][client][<0.125487.0>] evaluate command 7: order remote client to start
[21:51:16][client][<0.125487.0>] evaluate command 8: await remote client ready
[21:51:16][rclient][<0.96.0>] init
[21:51:16][rclient][<0.96.0>] await start
[21:51:16][rclient][<0.96.0>] RcvBuf is 131072 (needs at least 24)
[21:51:16][rclient][<0.96.0>] SndBuf is 16384 (needs at least 24)
[21:51:16][rclient][<0.96.0>] announce ready (init)
[21:51:16][rclient][<0.96.0>] await continue (connect)
[21:51:16][client][<0.125487.0>] remote client started
[21:51:16][client][<0.125487.0>] evaluate command 9: announce ready (init)
[21:51:16][tester][<0.125488.0>] evaluate command 7: order server continue (accept)
[21:51:16][client][<0.125487.0>] evaluate command 10: await continue (connect)
[21:51:16][server][<0.125486.0>] evaluate command 10: accept
[21:51:16][tester][<0.125488.0>] evaluate command 8: sleep
[21:51:17][tester][<0.125488.0>] evaluate command 9: order client continue (connect)
[21:51:17][tester][<0.125488.0>] evaluate command 10: await server ready (accept)
[21:51:17][client][<0.125487.0>] evaluate command 11: order remote client to continue (connect)
[21:51:17][client][<0.125487.0>] evaluate command 12: await remote client ready (connect)
[21:51:17][rclient][<0.96.0>] continue (connect): ok
[21:51:17][rclient][<0.96.0>] announce ready (connect)
[21:51:17][rclient][<0.96.0>] await continue (send)
[21:51:17][server][<0.125486.0>] evaluate command 11: create handler
[21:51:17][client][<0.125487.0>] evaluate command 13: announce ready (connect)
[21:51:17][server][<0.125486.0>] handler created: <0.125494.0>
[21:51:17][client][<0.125487.0>] evaluate command 14: await continue (send)
[21:51:17][handler][<0.125494.0>] init
[21:51:17][server][<0.125486.0>] evaluate command 12: monitor handler
[21:51:17][handler][<0.125494.0>] await start
[21:51:17][server][<0.125486.0>] evaluate command 13: transfer connection socket ownership to handler
[21:51:17][server][<0.125486.0>] evaluate command 14: start handler
[21:51:17][server][<0.125486.0>] evaluate command 15: await handler ready (init)
[21:51:17][handler][<0.125494.0>] announce ready (init)
[21:51:17][handler][<0.125494.0>] await continue (recv)
[21:51:17][server][<0.125486.0>] evaluate command 16: announce ready (accept)
[21:51:17][server][<0.125486.0>] evaluate command 17: await continue (recv)
[21:51:17][tester][<0.125488.0>] evaluate command 11: await client ready (connect)
[21:51:17][tester][<0.125488.0>] evaluate command 12: order server continue (recv)
[21:51:17][tester][<0.125488.0>] evaluate command 13: sleep
[21:51:17][server][<0.125486.0>] evaluate command 18: order handler to recv
[21:51:17][server][<0.125486.0>] evaluate command 19: await handler ready (recv)
[21:51:18][tester][<0.125488.0>] evaluate command 14: order client continue (send)
[21:51:18][tester][<0.125488.0>] evaluate command 15: await client ready (send)
[21:51:18][client][<0.125487.0>] evaluate command 15: order remote client to continue (send)
[21:51:18][client][<0.125487.0>] evaluate command 16: await remote client ready (send)
[21:51:18][rclient][<0.96.0>] continue (send): ok with data
[21:51:18][handler][<0.125494.0>] closed - we are done: 1000, 16000, 16000
[21:51:18][handler][<0.125494.0>] announce ready (recv)
[21:51:18][handler][<0.125494.0>] await terminate
[21:51:18][server][<0.125486.0>] evaluate command 20: announce ready (recv)
[21:51:18][server][<0.125486.0>] evaluate command 21: await terminate (from tester)
[21:51:18][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 => tcp,
domain => inet,
ctype => normal,
num_acceptors => 0,
num_readers => 0,
num_writers => 0,
rstates =>
[bound,selected],
wstates =>
[connected,
selected]},
{1740,516678,717567},
{1740,516678,766697}}
[21:51:18][rclient][<0.96.0>] await terminate
[21:51:18][client][<0.125487.0>] evaluate command 17: announce ready (send)
[21:51:18][client][<0.125487.0>] evaluate command 18: await terminate (from tester)
[21:51:18][tester][<0.125488.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 => tcp,domain => inet,
ctype => normal,num_acceptors => 0,num_readers => 0,num_writers => 0,
rstates => [bound,selected],
wstates => [connected,selected]},
{1740,516678,717567},
{1740,516678,766697}}
[21:51:18][tester][<0.125488.0>] evaluate command 16: await server ready (recv)
[21:51:18][tester][<0.125488.0>] server result:
{1000,16000,16000,{1740,516678,717656},{1740,516678,766910}}
[21:51:18][tester][<0.125488.0>] evaluate command 17: present result
[21:51:18][tester][<0.125488.0>] process result data:
Num: 1000
Server Sent: 16000
Server Recv: 16000
Server Start: {1740,516678,717656}
Server Stop: {1740,516678,766910}
Server Time: 49
Client Sent: 16000
Client Recv: 16000
Client Start: {1740,516678,717567}
Client Stop: {1740,516678,766697}
Client Time: 49
[21:51:18][tester][<0.125488.0>] Results: 1000 messages exchanged
Server: 49 msec
0.05 msec/message (roundtrip)
20.41 messages/msec (roundtrip)
326 bytes/msec sent
326 bytes/msec received
Client: 49 msec
0.05 msec/message (roundtrip)
20.41 messages/msec (roundtrip)
326 bytes/msec sent
326 bytes/msec received
[21:51:18][tester][<0.125488.0>] evaluate command 18: order client to terminate
[21:51:18][tester][<0.125488.0>] evaluate command 19: await client termination
[21:51:18][client][<0.125487.0>] evaluate command 19: stop remote client
[21:51:18][client][<0.125487.0>] evaluate command 20: await remote client termination
[21:51:18][rclient][<0.96.0>] termination received: normal
[21:51:18][rclient][<0.96.0>] terminating
[21:51:18][client][<0.125487.0>] evaluate command 21: stop client node
[21:51:18][client][<0.125487.0>] evaluate command 22: await client node termination
[21:51:18][client][<0.125487.0>] Success node stop - await nodedown
[21:51:18][client][<0.125487.0>] nodedown received - cleanup
[21:51:18][client][<0.125487.0>] evaluate command 23: finish
[21:51:18][tester][<0.125488.0>] evaluate command 20: order server to terminate
[21:51:18] evaluator 'client' (<0.125487.0>) success
[21:51:18][tester][<0.125488.0>] evaluate command 21: await server termination
[21:51:18][server][<0.125486.0>] evaluate command 22: stop handler
[21:51:18][server][<0.125486.0>] evaluate command 23: await handler termination
[21:51:18][handler][<0.125494.0>] terminating
[21:51:18][server][<0.125486.0>] evaluate command 24: close listen socket
[21:51:18][server][<0.125486.0>] evaluate command 25: finish
[21:51:18] evaluator 'server' (<0.125486.0>) success
[21:51:18][tester][<0.125488.0>] evaluate command 22: finish
[21:51:18] evaluator 'tester' (<0.125488.0>) success
=== Ended at 2025-02-25 21:51:19
=== successfully completed test case
=== === Returned value: ok
Test run history | Top level test index | Latest test result