=== Test case: mod_ping_SUITE:server_ping_pong/1 (click for source code)

=== Config value:

    [{watchdog,<0.25260.0>},
     {mim_data_dir,"/home/circleci/app/big_tests/tests/mod_ping_SUITE_data"},
     {preset,"riak_mnesia"},
     {tc_logfile,"/home/circleci/app/big_tests/ct_report/ct_run.test@default-1fa89e0e-5ac3-443b-a020-311fd7b0e3ce.2021-10-25_11.31.08/big_tests.tests.mod_ping_SUITE.logs/run.2021-10-25_11.40.32/mod_ping_suite.server_ping_pong.html"},
     {tc_group_properties,[{name,server_ping},{repeat_until_all_ok,3}]},
     {tc_group_path,[]},
     {data_dir,"/home/circleci/app/big_tests/_build/default/lib/mongoose_tests/ebin/mod_ping_SUITE_data/"},
     {priv_dir,"/home/circleci/app/big_tests/ct_report/ct_run.test@default-1fa89e0e-5ac3-443b-a020-311fd7b0e3ce.2021-10-25_11.31.08/big_tests.tests.mod_ping_SUITE.logs/run.2021-10-25_11.40.32/log_private/"}]

=== Current directory is "/home/circleci/app/big_tests/ct_report/ct_run.test@default-1fa89e0e-5ac3-443b-a020-311fd7b0e3ce.2021-10-25_11.31.08"

=== Started at 2021-10-25 11:40:41


*** CT 2021-10-25 11:40:41.245 *** View log from node mongooseim@localhost when started mongooseim@localhost_1257.html
*** CT 2021-10-25 11:40:41.249 *** View log from node ejabberd2@localhost when started ejabberd2@localhost_567.html

*** User 2021-10-25 11:40:41.279 ***
alicE_server_ping_pong_41.255411@localhost/res1 out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2021-10-25 11:40:41.285 ***
alicE_server_ping_pong_41.255411@localhost/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='62485694dfa0b3d9' from='localhost' version='1.0' xml:lang='en'>

*** User 2021-10-25 11:40:41.285 ***
alicE_server_ping_pong_41.255411@localhost/res1 in
<stream:features>
	<starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
	<compression xmlns='http://jabber.org/features/compress'>
		<method>zlib</method>
	</compression>
	<mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>
		<mechanism>SCRAM-SHA-256</mechanism>
		<mechanism>PLAIN</mechanism>
	</mechanisms>
	<register xmlns='http://jabber.org/features/iq-register'/>
	<amp xmlns='http://jabber.org/feature/amp'/>
	<sm xmlns='urn:xmpp:sm:3'/>
</stream:features>



*** User 2021-10-25 11:40:41.286 ***
alicE_server_ping_pong_41.255411@localhost/res1 out
<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGFsaWNFX3NlcnZlcl9waW5nX3BvbmdfNDEuMjU1NDExAG1hdHlncnlzYQ==</auth>



*** User 2021-10-25 11:40:41.293 ***
alicE_server_ping_pong_41.255411@localhost/res1 in
<success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>



*** User 2021-10-25 11:40:41.294 ***
alicE_server_ping_pong_41.255411@localhost/res1 out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2021-10-25 11:40:41.297 ***
alicE_server_ping_pong_41.255411@localhost/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='3f485aafdc6875be' from='localhost' version='1.0' xml:lang='en'>

*** User 2021-10-25 11:40:41.299 ***
alicE_server_ping_pong_41.255411@localhost/res1 in
<stream:features>
	<compression xmlns='http://jabber.org/features/compress'>
		<method>zlib</method>
	</compression>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'/>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
	<register xmlns='http://jabber.org/features/iq-register'/>
	<amp xmlns='http://jabber.org/feature/amp'/>
	<sm xmlns='urn:xmpp:sm:3'/>
</stream:features>



*** User 2021-10-25 11:40:41.299 ***
alicE_server_ping_pong_41.255411@localhost/res1 out
<iq type='set' id='d7ca2c56ad2c44fd90ed44773cb6498a'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<resource>res1</resource>
	</bind>
</iq>



*** User 2021-10-25 11:40:41.302 ***
alicE_server_ping_pong_41.255411@localhost/res1 in
<iq id='d7ca2c56ad2c44fd90ed44773cb6498a' type='result'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<jid>alicE_server_ping_pong_41.255411@localhost/res1</jid>
	</bind>
</iq>



*** User 2021-10-25 11:40:41.302 ***
alicE_server_ping_pong_41.255411@localhost/res1 out
<iq type='set' id='4b31fdb01651f07f6ca3afd85589e649'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2021-10-25 11:40:41.310 ***
alicE_server_ping_pong_41.255411@localhost/res1 in
<iq type='result' id='4b31fdb01651f07f6ca3afd85589e649'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2021-10-25 11:40:41.311 ***
alicE_server_ping_pong_41.255411@localhost/res1 out
<presence/>



*** User 2021-10-25 11:40:41.330 ***
alicE_server_ping_pong_41.255411@localhost/res1 in
<presence from='alicE_server_ping_pong_41.255411@localhost/res1' to='alice_server_ping_pong_41.255411@localhost/res1' xml:lang='en'/>



*** User 2021-10-25 11:40:41.332 ***
alicE_server_ping_pong_41.255411@localhost/res2 out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2021-10-25 11:40:41.337 ***
alicE_server_ping_pong_41.255411@localhost/res2 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='a8cbc588f8ebf496' from='localhost' version='1.0' xml:lang='en'>

*** User 2021-10-25 11:40:41.337 ***
alicE_server_ping_pong_41.255411@localhost/res2 in
<stream:features>
	<starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
	<compression xmlns='http://jabber.org/features/compress'>
		<method>zlib</method>
	</compression>
	<mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>
		<mechanism>SCRAM-SHA-256</mechanism>
		<mechanism>PLAIN</mechanism>
	</mechanisms>
	<register xmlns='http://jabber.org/features/iq-register'/>
	<amp xmlns='http://jabber.org/feature/amp'/>
	<sm xmlns='urn:xmpp:sm:3'/>
</stream:features>



*** User 2021-10-25 11:40:41.337 ***
alicE_server_ping_pong_41.255411@localhost/res2 out
<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGFsaWNFX3NlcnZlcl9waW5nX3BvbmdfNDEuMjU1NDExAG1hdHlncnlzYQ==</auth>



*** User 2021-10-25 11:40:41.347 ***
alicE_server_ping_pong_41.255411@localhost/res2 in
<success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>



*** User 2021-10-25 11:40:41.347 ***
alicE_server_ping_pong_41.255411@localhost/res2 out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2021-10-25 11:40:41.349 ***
alicE_server_ping_pong_41.255411@localhost/res2 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='76d7830823bee156' from='localhost' version='1.0' xml:lang='en'>

*** User 2021-10-25 11:40:41.350 ***
alicE_server_ping_pong_41.255411@localhost/res2 in
<stream:features>
	<compression xmlns='http://jabber.org/features/compress'>
		<method>zlib</method>
	</compression>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'/>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
	<register xmlns='http://jabber.org/features/iq-register'/>
	<amp xmlns='http://jabber.org/feature/amp'/>
	<sm xmlns='urn:xmpp:sm:3'/>
</stream:features>



*** User 2021-10-25 11:40:41.350 ***
alicE_server_ping_pong_41.255411@localhost/res2 out
<iq type='set' id='0f835953949a92583f397b6ba683c934'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<resource>res2</resource>
	</bind>
</iq>



*** User 2021-10-25 11:40:41.353 ***
alicE_server_ping_pong_41.255411@localhost/res2 in
<iq id='0f835953949a92583f397b6ba683c934' type='result'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<jid>alicE_server_ping_pong_41.255411@localhost/res2</jid>
	</bind>
</iq>



*** User 2021-10-25 11:40:41.353 ***
alicE_server_ping_pong_41.255411@localhost/res2 out
<iq type='set' id='03437e580862f8e8c224c5f514ce2de5'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2021-10-25 11:40:41.354 ***
alicE_server_ping_pong_41.255411@localhost/res2 in
<iq type='result' id='03437e580862f8e8c224c5f514ce2de5'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2021-10-25 11:40:41.354 ***
alicE_server_ping_pong_41.255411@localhost/res2 out
<presence/>



*** User 2021-10-25 11:40:41.381 ***
alicE_server_ping_pong_41.255411@localhost/res2 in
<presence from='alicE_server_ping_pong_41.255411@localhost/res2' to='alice_server_ping_pong_41.255411@localhost/res2' xml:lang='en'/>



*** User 2021-10-25 11:40:41.382 ***
alicE_server_ping_pong_41.255411@localhost/res2 in
<presence from='alice_server_ping_pong_41.255411@localhost/res1' to='alicE_server_ping_pong_41.255411@localhost/res2' xml:lang='en'>
	<delay xmlns='urn:xmpp:delay' stamp='2021-10-25T11:40:41Z' from='alice_server_ping_pong_41.255411@localhost/res1'/>
</presence>



*** User 2021-10-25 11:40:41.386 ***
alicE_server_ping_pong_41.255411@localhost/res3 out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2021-10-25 11:40:41.388 ***
alicE_server_ping_pong_41.255411@localhost/res3 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='25c5b7ac412bcda4' from='localhost' version='1.0' xml:lang='en'>

*** User 2021-10-25 11:40:41.388 ***
alicE_server_ping_pong_41.255411@localhost/res3 in
<stream:features>
	<starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
	<compression xmlns='http://jabber.org/features/compress'>
		<method>zlib</method>
	</compression>
	<mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>
		<mechanism>SCRAM-SHA-256</mechanism>
		<mechanism>PLAIN</mechanism>
	</mechanisms>
	<register xmlns='http://jabber.org/features/iq-register'/>
	<amp xmlns='http://jabber.org/feature/amp'/>
	<sm xmlns='urn:xmpp:sm:3'/>
</stream:features>



*** User 2021-10-25 11:40:41.389 ***
alicE_server_ping_pong_41.255411@localhost/res3 out
<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGFsaWNFX3NlcnZlcl9waW5nX3BvbmdfNDEuMjU1NDExAG1hdHlncnlzYQ==</auth>



*** User 2021-10-25 11:40:41.397 ***
alicE_server_ping_pong_41.255411@localhost/res3 in
<success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>



*** User 2021-10-25 11:40:41.397 ***
alicE_server_ping_pong_41.255411@localhost/res3 out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2021-10-25 11:40:41.399 ***
alicE_server_ping_pong_41.255411@localhost/res3 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='3cf8db5bb25a6f85' from='localhost' version='1.0' xml:lang='en'>

*** User 2021-10-25 11:40:41.399 ***
alicE_server_ping_pong_41.255411@localhost/res3 in
<stream:features>
	<compression xmlns='http://jabber.org/features/compress'>
		<method>zlib</method>
	</compression>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'/>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
	<register xmlns='http://jabber.org/features/iq-register'/>
	<amp xmlns='http://jabber.org/feature/amp'/>
	<sm xmlns='urn:xmpp:sm:3'/>
</stream:features>



*** User 2021-10-25 11:40:41.399 ***
alicE_server_ping_pong_41.255411@localhost/res3 out
<iq type='set' id='81e528e1c576b4de59aa37a6941f3079'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<resource>res3</resource>
	</bind>
</iq>



*** User 2021-10-25 11:40:41.400 ***
alicE_server_ping_pong_41.255411@localhost/res3 in
<iq id='81e528e1c576b4de59aa37a6941f3079' type='result'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<jid>alicE_server_ping_pong_41.255411@localhost/res3</jid>
	</bind>
</iq>



*** User 2021-10-25 11:40:41.400 ***
alicE_server_ping_pong_41.255411@localhost/res3 out
<iq type='set' id='310d76014afd70c370189ff255786229'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2021-10-25 11:40:41.402 ***
alicE_server_ping_pong_41.255411@localhost/res3 in
<iq type='result' id='310d76014afd70c370189ff255786229'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2021-10-25 11:40:41.402 ***
alicE_server_ping_pong_41.255411@localhost/res3 out
<presence/>



*** User 2021-10-25 11:40:41.427 ***
alicE_server_ping_pong_41.255411@localhost/res3 in
<presence from='alicE_server_ping_pong_41.255411@localhost/res3' to='alice_server_ping_pong_41.255411@localhost/res3' xml:lang='en'/>



*** User 2021-10-25 11:40:41.427 ***
alicE_server_ping_pong_41.255411@localhost/res3 in
<presence from='alice_server_ping_pong_41.255411@localhost/res2' to='alicE_server_ping_pong_41.255411@localhost/res3' xml:lang='en'>
	<delay xmlns='urn:xmpp:delay' stamp='2021-10-25T11:40:41Z' from='alice_server_ping_pong_41.255411@localhost/res2'/>
</presence>



*** User 2021-10-25 11:40:41.429 ***
alicE_server_ping_pong_41.255411@localhost/res3 in
<presence from='alice_server_ping_pong_41.255411@localhost/res1' to='alicE_server_ping_pong_41.255411@localhost/res3' xml:lang='en'>
	<delay xmlns='urn:xmpp:delay' stamp='2021-10-25T11:40:41Z' from='alice_server_ping_pong_41.255411@localhost/res1'/>
</presence>



*** User 2021-10-25 11:40:41.430 ***
alicE_server_ping_pong_41.255411@localhost/res4 out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2021-10-25 11:40:41.434 ***
alicE_server_ping_pong_41.255411@localhost/res4 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='865d6988a64b6b19' from='localhost' version='1.0' xml:lang='en'>

*** User 2021-10-25 11:40:41.435 ***
alicE_server_ping_pong_41.255411@localhost/res4 in
<stream:features>
	<starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
	<compression xmlns='http://jabber.org/features/compress'>
		<method>zlib</method>
	</compression>
	<mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>
		<mechanism>SCRAM-SHA-256</mechanism>
		<mechanism>PLAIN</mechanism>
	</mechanisms>
	<register xmlns='http://jabber.org/features/iq-register'/>
	<amp xmlns='http://jabber.org/feature/amp'/>
	<sm xmlns='urn:xmpp:sm:3'/>
</stream:features>



*** User 2021-10-25 11:40:41.435 ***
alicE_server_ping_pong_41.255411@localhost/res4 out
<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGFsaWNFX3NlcnZlcl9waW5nX3BvbmdfNDEuMjU1NDExAG1hdHlncnlzYQ==</auth>



*** User 2021-10-25 11:40:41.443 ***
alicE_server_ping_pong_41.255411@localhost/res4 in
<success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>



*** User 2021-10-25 11:40:41.443 ***
alicE_server_ping_pong_41.255411@localhost/res4 out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2021-10-25 11:40:41.444 ***
alicE_server_ping_pong_41.255411@localhost/res4 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='e17c8ec2ef4b1d0d' from='localhost' version='1.0' xml:lang='en'>

*** User 2021-10-25 11:40:41.444 ***
alicE_server_ping_pong_41.255411@localhost/res4 in
<stream:features>
	<compression xmlns='http://jabber.org/features/compress'>
		<method>zlib</method>
	</compression>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'/>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
	<register xmlns='http://jabber.org/features/iq-register'/>
	<amp xmlns='http://jabber.org/feature/amp'/>
	<sm xmlns='urn:xmpp:sm:3'/>
</stream:features>



*** User 2021-10-25 11:40:41.444 ***
alicE_server_ping_pong_41.255411@localhost/res4 out
<iq type='set' id='2e18c481191894fb69349197fd78139a'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<resource>res4</resource>
	</bind>
</iq>



*** User 2021-10-25 11:40:41.446 ***
alicE_server_ping_pong_41.255411@localhost/res4 in
<iq id='2e18c481191894fb69349197fd78139a' type='result'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<jid>alicE_server_ping_pong_41.255411@localhost/res4</jid>
	</bind>
</iq>



*** User 2021-10-25 11:40:41.447 ***
alicE_server_ping_pong_41.255411@localhost/res4 out
<iq type='set' id='321d163a54ceefa7727e2a2c35a24ec3'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2021-10-25 11:40:41.448 ***
alicE_server_ping_pong_41.255411@localhost/res4 in
<iq type='result' id='321d163a54ceefa7727e2a2c35a24ec3'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2021-10-25 11:40:41.449 ***
alicE_server_ping_pong_41.255411@localhost/res4 out
<presence/>



*** User 2021-10-25 11:40:41.473 ***
alicE_server_ping_pong_41.255411@localhost/res4 in
<presence from='alicE_server_ping_pong_41.255411@localhost/res4' to='alice_server_ping_pong_41.255411@localhost/res4' xml:lang='en'/>



*** User 2021-10-25 11:40:41.476 ***
alicE_server_ping_pong_41.255411@localhost/res4 in
<presence from='alice_server_ping_pong_41.255411@localhost/res3' to='alicE_server_ping_pong_41.255411@localhost/res4' xml:lang='en'>
	<delay xmlns='urn:xmpp:delay' stamp='2021-10-25T11:40:41Z' from='alice_server_ping_pong_41.255411@localhost/res3'/>
</presence>



*** User 2021-10-25 11:40:41.477 ***
alicE_server_ping_pong_41.255411@localhost/res4 in
<presence from='alice_server_ping_pong_41.255411@localhost/res2' to='alicE_server_ping_pong_41.255411@localhost/res4' xml:lang='en'>
	<delay xmlns='urn:xmpp:delay' stamp='2021-10-25T11:40:41Z' from='alice_server_ping_pong_41.255411@localhost/res2'/>
</presence>



*** User 2021-10-25 11:40:41.478 ***
alicE_server_ping_pong_41.255411@localhost/res4 in
<presence from='alice_server_ping_pong_41.255411@localhost/res1' to='alicE_server_ping_pong_41.255411@localhost/res4' xml:lang='en'>
	<delay xmlns='urn:xmpp:delay' stamp='2021-10-25T11:40:41Z' from='alice_server_ping_pong_41.255411@localhost/res1'/>
</presence>



*** User 2021-10-25 11:40:41.480 ***
alicE_server_ping_pong_41.255411@localhost/res5 out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2021-10-25 11:40:41.481 ***
alicE_server_ping_pong_41.255411@localhost/res5 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='35cc5ed4d2b9280a' from='localhost' version='1.0' xml:lang='en'>

*** User 2021-10-25 11:40:41.482 ***
alicE_server_ping_pong_41.255411@localhost/res5 in
<stream:features>
	<starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
	<compression xmlns='http://jabber.org/features/compress'>
		<method>zlib</method>
	</compression>
	<mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>
		<mechanism>SCRAM-SHA-256</mechanism>
		<mechanism>PLAIN</mechanism>
	</mechanisms>
	<register xmlns='http://jabber.org/features/iq-register'/>
	<amp xmlns='http://jabber.org/feature/amp'/>
	<sm xmlns='urn:xmpp:sm:3'/>
</stream:features>



*** User 2021-10-25 11:40:41.482 ***
alicE_server_ping_pong_41.255411@localhost/res5 out
<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGFsaWNFX3NlcnZlcl9waW5nX3BvbmdfNDEuMjU1NDExAG1hdHlncnlzYQ==</auth>



*** User 2021-10-25 11:40:41.489 ***
alicE_server_ping_pong_41.255411@localhost/res5 in
<success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>



*** User 2021-10-25 11:40:41.490 ***
alicE_server_ping_pong_41.255411@localhost/res5 out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2021-10-25 11:40:41.492 ***
alicE_server_ping_pong_41.255411@localhost/res5 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='2dcaef9ea750d984' from='localhost' version='1.0' xml:lang='en'>

*** User 2021-10-25 11:40:41.492 ***
alicE_server_ping_pong_41.255411@localhost/res5 in
<stream:features>
	<compression xmlns='http://jabber.org/features/compress'>
		<method>zlib</method>
	</compression>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'/>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
	<register xmlns='http://jabber.org/features/iq-register'/>
	<amp xmlns='http://jabber.org/feature/amp'/>
	<sm xmlns='urn:xmpp:sm:3'/>
</stream:features>



*** User 2021-10-25 11:40:41.493 ***
alicE_server_ping_pong_41.255411@localhost/res5 out
<iq type='set' id='bf45d6af41851daa8cb9651a433e25d8'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<resource>res5</resource>
	</bind>
</iq>



*** User 2021-10-25 11:40:41.494 ***
alicE_server_ping_pong_41.255411@localhost/res5 in
<iq id='bf45d6af41851daa8cb9651a433e25d8' type='result'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<jid>alicE_server_ping_pong_41.255411@localhost/res5</jid>
	</bind>
</iq>



*** User 2021-10-25 11:40:41.495 ***
alicE_server_ping_pong_41.255411@localhost/res5 out
<iq type='set' id='e70d69733011178696c4a84f077a5779'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2021-10-25 11:40:41.497 ***
alicE_server_ping_pong_41.255411@localhost/res5 in
<iq type='result' id='e70d69733011178696c4a84f077a5779'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2021-10-25 11:40:41.497 ***
alicE_server_ping_pong_41.255411@localhost/res5 out
<presence/>



*** User 2021-10-25 11:40:41.520 ***
alicE_server_ping_pong_41.255411@localhost/res5 in
<presence from='alicE_server_ping_pong_41.255411@localhost/res5' to='alice_server_ping_pong_41.255411@localhost/res5' xml:lang='en'/>



*** User 2021-10-25 11:40:41.521 ***
alicE_server_ping_pong_41.255411@localhost/res5 in
<presence from='alice_server_ping_pong_41.255411@localhost/res4' to='alicE_server_ping_pong_41.255411@localhost/res5' xml:lang='en'>
	<delay xmlns='urn:xmpp:delay' stamp='2021-10-25T11:40:41Z' from='alice_server_ping_pong_41.255411@localhost/res4'/>
</presence>



*** User 2021-10-25 11:40:41.521 ***
alicE_server_ping_pong_41.255411@localhost/res5 in
<presence from='alice_server_ping_pong_41.255411@localhost/res3' to='alicE_server_ping_pong_41.255411@localhost/res5' xml:lang='en'>
	<delay xmlns='urn:xmpp:delay' stamp='2021-10-25T11:40:41Z' from='alice_server_ping_pong_41.255411@localhost/res3'/>
</presence>



*** User 2021-10-25 11:40:41.521 ***
alicE_server_ping_pong_41.255411@localhost/res5 in
<presence from='alice_server_ping_pong_41.255411@localhost/res2' to='alicE_server_ping_pong_41.255411@localhost/res5' xml:lang='en'>
	<delay xmlns='urn:xmpp:delay' stamp='2021-10-25T11:40:41Z' from='alice_server_ping_pong_41.255411@localhost/res2'/>
</presence>



*** User 2021-10-25 11:40:41.522 ***
alicE_server_ping_pong_41.255411@localhost/res5 in
<presence from='alice_server_ping_pong_41.255411@localhost/res1' to='alicE_server_ping_pong_41.255411@localhost/res5' xml:lang='en'>
	<delay xmlns='urn:xmpp:delay' stamp='2021-10-25T11:40:41Z' from='alice_server_ping_pong_41.255411@localhost/res1'/>
</presence>



*** User 2021-10-25 11:40:41.522 ***
alicE_server_ping_pong_41.255411@localhost/res1 in
<presence from='alicE_server_ping_pong_41.255411@localhost/res2' to='alice_server_ping_pong_41.255411@localhost/res1' xml:lang='en'/>



*** User 2021-10-25 11:40:41.522 ***
alicE_server_ping_pong_41.255411@localhost/res1 in
<presence from='alicE_server_ping_pong_41.255411@localhost/res3' to='alice_server_ping_pong_41.255411@localhost/res1' xml:lang='en'/>



*** User 2021-10-25 11:40:41.522 ***
alicE_server_ping_pong_41.255411@localhost/res1 in
<presence from='alicE_server_ping_pong_41.255411@localhost/res4' to='alice_server_ping_pong_41.255411@localhost/res1' xml:lang='en'/>



*** User 2021-10-25 11:40:41.522 ***
alicE_server_ping_pong_41.255411@localhost/res1 in
<presence from='alicE_server_ping_pong_41.255411@localhost/res5' to='alice_server_ping_pong_41.255411@localhost/res1' xml:lang='en'/>



*** User 2021-10-25 11:40:41.522 ***
alicE_server_ping_pong_41.255411@localhost/res2 in
<presence from='alicE_server_ping_pong_41.255411@localhost/res3' to='alice_server_ping_pong_41.255411@localhost/res2' xml:lang='en'/>



*** User 2021-10-25 11:40:41.522 ***
alicE_server_ping_pong_41.255411@localhost/res2 in
<presence from='alicE_server_ping_pong_41.255411@localhost/res4' to='alice_server_ping_pong_41.255411@localhost/res2' xml:lang='en'/>



*** User 2021-10-25 11:40:41.522 ***
alicE_server_ping_pong_41.255411@localhost/res2 in
<presence from='alicE_server_ping_pong_41.255411@localhost/res5' to='alice_server_ping_pong_41.255411@localhost/res2' xml:lang='en'/>



*** User 2021-10-25 11:40:41.522 ***
alicE_server_ping_pong_41.255411@localhost/res3 in
<presence from='alicE_server_ping_pong_41.255411@localhost/res4' to='alice_server_ping_pong_41.255411@localhost/res3' xml:lang='en'/>



*** User 2021-10-25 11:40:41.522 ***
alicE_server_ping_pong_41.255411@localhost/res3 in
<presence from='alicE_server_ping_pong_41.255411@localhost/res5' to='alice_server_ping_pong_41.255411@localhost/res3' xml:lang='en'/>



*** User 2021-10-25 11:40:41.522 ***
alicE_server_ping_pong_41.255411@localhost/res4 in
<presence from='alicE_server_ping_pong_41.255411@localhost/res5' to='alice_server_ping_pong_41.255411@localhost/res4' xml:lang='en'/>



*** User 2021-10-25 11:40:44.141 ***
Uknown Info in bosh_gun: {gun_down,<0.7220.0>,http,closed,[]}

*** User 2021-10-25 11:40:44.143 ***
Uknown Info in bosh_gun: {gun_up,<0.7220.0>,http}

*** User 2021-10-25 11:40:44.145 ***
Uknown Info in bosh_gun: {gun_down,<0.7217.0>,http,closed,[]}

*** User 2021-10-25 11:40:44.147 ***
Uknown Info in bosh_gun: {gun_up,<0.7217.0>,http}

*** User 2021-10-25 11:40:44.331 ***
alicE_server_ping_pong_41.255411@localhost/res1 in
<iq from='localhost' to='alicE_server_ping_pong_41.255411@localhost/res1' id='2f392bf608e4de9a' type='get'>
	<ping xmlns='urn:xmpp:ping'/>
</iq>



*** User 2021-10-25 11:40:44.332 ***
alicE_server_ping_pong_41.255411@localhost/res1 out
<iq to='localhost' id='2f392bf608e4de9a' type='result'/>



*** User 2021-10-25 11:40:44.382 ***
alicE_server_ping_pong_41.255411@localhost/res2 in
<iq from='localhost' to='alicE_server_ping_pong_41.255411@localhost/res2' id='1c0318c3b4b21f81' type='get'>
	<ping xmlns='urn:xmpp:ping'/>
</iq>



*** User 2021-10-25 11:40:44.382 ***
alicE_server_ping_pong_41.255411@localhost/res2 out
<iq to='localhost' id='1c0318c3b4b21f81' type='result'/>



*** User 2021-10-25 11:40:44.430 ***
alicE_server_ping_pong_41.255411@localhost/res3 in
<iq from='localhost' to='alicE_server_ping_pong_41.255411@localhost/res3' id='02ef2d15098b555c' type='get'>
	<ping xmlns='urn:xmpp:ping'/>
</iq>



*** User 2021-10-25 11:40:44.430 ***
alicE_server_ping_pong_41.255411@localhost/res3 out
<iq to='localhost' id='02ef2d15098b555c' type='result'/>



*** User 2021-10-25 11:40:44.475 ***
alicE_server_ping_pong_41.255411@localhost/res4 in
<iq from='localhost' to='alicE_server_ping_pong_41.255411@localhost/res4' id='0cae5fcbb2840f72' type='get'>
	<ping xmlns='urn:xmpp:ping'/>
</iq>



*** User 2021-10-25 11:40:44.475 ***
alicE_server_ping_pong_41.255411@localhost/res4 out
<iq to='localhost' id='0cae5fcbb2840f72' type='result'/>



*** User 2021-10-25 11:40:44.521 ***
alicE_server_ping_pong_41.255411@localhost/res5 in
<iq from='localhost' to='alicE_server_ping_pong_41.255411@localhost/res5' id='3102dab59ba05be0' type='get'>
	<ping xmlns='urn:xmpp:ping'/>
</iq>



*** User 2021-10-25 11:40:44.521 ***
alicE_server_ping_pong_41.255411@localhost/res5 out
<iq to='localhost' id='3102dab59ba05be0' type='result'/>


*** CT 2021-10-25 11:40:44.539 *** Stanza Log: server_ping_pong.xml

*** CT Error Notification 2021-10-25 11:40:44.558 ***
escalus_mongooseim:post_story_check_metrics failed on line 74
Reason: {badmatch,[{[<<"localhost">>,mod_ping,ping_response],
  {exp...}

Full error description and stacktrace
*** CT 2021-10-25 11:40:44.574 *** View log from node ejabberd2@localhost when finished ejabberd2@localhost_567.html
*** CT 2021-10-25 11:40:44.580 *** View log from node mongooseim@localhost when finished mongooseim@localhost_1257.html



=== Ended at 2021-10-25 11:40:44
=== Location: [{escalus_mongooseim,post_story_check_metrics,74},
              {escalus_mongooseim,maybe_check_metrics_post_story,51},
              {escalus_story,story,75},
              {test_server,ts_tc,1783},
              {test_server,run_test_case_eval1,1292},
              {test_server,run_test_case_eval,1224}]
=== === Reason: no match of right hand side value 
                 [{[<<"localhost">>,mod_ping,ping_response],
                   {expected_diff,5},
                   {before_story,0},
                   {after_story,4}}]
  in function  escalus_mongooseim:post_story_check_metrics/1 (/home/circleci/app/big_tests/_build/default/lib/escalus/src/escalus_mongooseim.erl, line 74)
  in call from escalus_mongooseim:maybe_check_metrics_post_story/1 (/home/circleci/app/big_tests/_build/default/lib/escalus/src/escalus_mongooseim.erl, line 51)
  in call from escalus_story:story/4 (/home/circleci/app/big_tests/_build/default/lib/escalus/src/escalus_story.erl, line 75)
  in call from test_server:ts_tc/3 (test_server.erl, line 1783)
  in call from test_server:run_test_case_eval1/6 (test_server.erl, line 1292)
  in call from test_server:run_test_case_eval/9 (test_server.erl, line 1224)


Test run history | Top level test index | Latest test result