=== Test case: s2s_SUITE:simple_message/1 (click for source code)

=== Config value:

    [{watchdog,<0.12502.1>},
     {s2s_opts,{s2s_opts,"priv/ssl/fake_server.pem",optional,
                         {{5269,{0,0,0,0},tcp},
                          ejabberd_s2s_in,
                          [{max_stanza_size,131072},
                           {shaper,s2s_shaper},
                           {dhfile,"priv/ssl/fake_dh_server.pem"}]},
                         "priv/ssl/fake_server.pem",optional,
                         {{5299,{0,0,0,0},tcp},
                          ejabberd_s2s_in,
                          [{max_stanza_size,131072},
                           {shaper,s2s_shaper},
                           {dhfile,"priv/ssl/fake_dh_server.pem"}]}}},
     {escalus_user_db,xmpp},
     {mim_data_dir,"/home/circleci/app/big_tests/tests/s2s_SUITE_data"},
     {preset,"riak_mnesia"},
     {tc_logfile,"/home/circleci/app/big_tests/ct_report/ct_run.test@default-c2711f63-a9bd-4773-b8b6-e770c37627d6.2021-10-27_13.25.56/big_tests.tests.s2s_SUITE.logs/run.2021-10-27_13.42.40/s2s_suite.simple_message.11251.html"},
     {tc_group_properties,[{name,both_tls_optional},{repeat_until_all_ok,3}]},
     {tc_group_path,[]},
     {data_dir,"/home/circleci/app/big_tests/_build/default/lib/mongoose_tests/ebin/s2s_SUITE_data/"},
     {priv_dir,"/home/circleci/app/big_tests/ct_report/ct_run.test@default-c2711f63-a9bd-4773-b8b6-e770c37627d6.2021-10-27_13.25.56/big_tests.tests.s2s_SUITE.logs/run.2021-10-27_13.42.40/log_private/"},
     {escalus_users,[{alice2,[{username,<<"alice">>},
                              {server,<<"fed1">>},
                              {host,<<"localhost">>},
                              {port,5242},
                              {password,<<"makota2">>}]},
                     {alice,[{username,<<"alicE">>},
                             {server,<<"localhost">>},
                             {password,<<"matygrysa">>}]},
                     {bob,[{username,<<"bOb">>},
                           {server,<<"localhost">>},
                           {password,<<"makrolika">>}]}]}]

=== Current directory is "/home/circleci/app/big_tests/ct_report/ct_run.test@default-c2711f63-a9bd-4773-b8b6-e770c37627d6.2021-10-27_13.25.56"

=== Started at 2021-10-27 13:42:42


*** CT 2021-10-27 13:42:42.800 *** View log from node mongooseim@localhost when started mongooseim@localhost_2173.html
*** CT 2021-10-27 13:42:42.807 *** View log from node ejabberd2@localhost when started ejabberd2@localhost_601.html

*** User 2021-10-27 13:42:42.826 ***
alice_simple_message_62.814428@fed1 out
<stream:stream to='fed1' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2021-10-27 13:42:42.830 ***
alice_simple_message_62.814428@fed1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='ec00248ad5111616' from='fed1' version='1.0' xml:lang='en'>

*** User 2021-10-27 13:42:42.830 ***
alice_simple_message_62.814428@fed1 in
<stream:features>
	<starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
	<mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>
		<mechanism>SCRAM-SHA-512</mechanism>
		<mechanism>SCRAM-SHA-384</mechanism>
		<mechanism>SCRAM-SHA-256</mechanism>
		<mechanism>SCRAM-SHA-224</mechanism>
		<mechanism>SCRAM-SHA-1</mechanism>
		<mechanism>PLAIN</mechanism>
	</mechanisms>
	<register xmlns='http://jabber.org/features/iq-register'/>
	<sm xmlns='urn:xmpp:sm:3'/>
</stream:features>



*** User 2021-10-27 13:42:42.830 ***
alice_simple_message_62.814428@fed1 out
<iq type='get' id='873dbf5d203b48e2cc4491e0aa50ae3a'>
	<query xmlns='jabber:iq:register'/>
</iq>



*** User 2021-10-27 13:42:42.840 ***
alice_simple_message_62.814428@fed1 in
<iq from='fed1' id='873dbf5d203b48e2cc4491e0aa50ae3a' type='result'>
	<query xmlns='jabber:iq:register'>
		<instructions>Choose a username and password to register with this server</instructions>
		<username/>
		<password/>
	</query>
</iq>



*** User 2021-10-27 13:42:42.841 ***
alice_simple_message_62.814428@fed1 out
<iq type='set' id='3a642086e1de9a742d25b2a5a2b2d308'>
	<query xmlns='jabber:iq:register'>
		<username>alice_simple_message_62.814428</username>
		<password>makota2</password>
	</query>
</iq>



*** User 2021-10-27 13:42:42.919 ***
alice_simple_message_62.814428@fed1 in
<iq from='fed1' id='3a642086e1de9a742d25b2a5a2b2d308' type='result'>
	<query xmlns='jabber:iq:register'>
		<username>alice_simple_message_62.814428</username>
		<password>makota2</password>
	</query>
</iq>



*** User 2021-10-27 13:42:42.919 ***
alice_simple_message_62.814428@fed1 out
</stream:stream>

*** User 2021-10-27 13:42:42.922 ***
alice_simple_message_62.814428@fed1 in
</stream:stream>

*** User 2021-10-27 13:42:42.927 ***
alicE_simple_message_62.814428@localhost out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2021-10-27 13:42:42.929 ***
alicE_simple_message_62.814428@localhost in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='5075fd43d01f49d6' from='localhost' version='1.0' xml:lang='en'>

*** User 2021-10-27 13:42:42.930 ***
alicE_simple_message_62.814428@localhost 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-27 13:42:42.930 ***
alicE_simple_message_62.814428@localhost out
<iq type='get' id='885388b30f64767064feb7b5e398b3a7'>
	<query xmlns='jabber:iq:register'/>
</iq>



*** User 2021-10-27 13:42:42.932 ***
alicE_simple_message_62.814428@localhost in
<iq from='localhost' id='885388b30f64767064feb7b5e398b3a7' type='result'>
	<query xmlns='jabber:iq:register'>
		<instructions>Choose a username and password to register with this server</instructions>
		<username/>
		<password/>
	</query>
</iq>



*** User 2021-10-27 13:42:42.933 ***
alicE_simple_message_62.814428@localhost out
<iq type='set' id='d2140d2ef560703d5678874d9331088e'>
	<query xmlns='jabber:iq:register'>
		<username>alicE_simple_message_62.814428</username>
		<password>matygrysa</password>
	</query>
</iq>



*** User 2021-10-27 13:42:42.945 ***
alicE_simple_message_62.814428@localhost in
<iq from='localhost' id='d2140d2ef560703d5678874d9331088e' type='result'>
	<query xmlns='jabber:iq:register'>
		<username>alicE_simple_message_62.814428</username>
		<password>matygrysa</password>
	</query>
</iq>



*** User 2021-10-27 13:42:42.946 ***
alicE_simple_message_62.814428@localhost out
</stream:stream>

*** User 2021-10-27 13:42:42.947 ***
alicE_simple_message_62.814428@localhost in
</stream:stream>

*** User 2021-10-27 13:42:42.956 ***
alice_simple_message_62.814428@fed1/res1 out
<stream:stream to='fed1' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2021-10-27 13:42:42.959 ***
alice_simple_message_62.814428@fed1/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='bbc5a97589ae1474' from='fed1' version='1.0' xml:lang='en'>

*** User 2021-10-27 13:42:42.961 ***
alice_simple_message_62.814428@fed1/res1 in
<stream:features>
	<starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
	<mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>
		<mechanism>SCRAM-SHA-512</mechanism>
		<mechanism>SCRAM-SHA-384</mechanism>
		<mechanism>SCRAM-SHA-256</mechanism>
		<mechanism>SCRAM-SHA-224</mechanism>
		<mechanism>SCRAM-SHA-1</mechanism>
		<mechanism>PLAIN</mechanism>
	</mechanisms>
	<register xmlns='http://jabber.org/features/iq-register'/>
	<sm xmlns='urn:xmpp:sm:3'/>
</stream:features>



*** User 2021-10-27 13:42:42.964 ***
alice_simple_message_62.814428@fed1/res1 out
<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGFsaWNlX3NpbXBsZV9tZXNzYWdlXzYyLjgxNDQyOABtYWtvdGEy</auth>



*** User 2021-10-27 13:42:42.972 ***
alice_simple_message_62.814428@fed1/res1 in
<success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>



*** User 2021-10-27 13:42:42.972 ***
alice_simple_message_62.814428@fed1/res1 out
<stream:stream to='fed1' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2021-10-27 13:42:42.973 ***
alice_simple_message_62.814428@fed1/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='6b051845a573c9f9' from='fed1' version='1.0' xml:lang='en'>

*** User 2021-10-27 13:42:42.973 ***
alice_simple_message_62.814428@fed1/res1 in
<stream:features>
	<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'/>
	<sm xmlns='urn:xmpp:sm:3'/>
</stream:features>



*** User 2021-10-27 13:42:42.973 ***
alice_simple_message_62.814428@fed1/res1 out
<iq type='set' id='891f7459cc24bdee9e1c339126a9859e'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<resource>res1</resource>
	</bind>
</iq>



*** User 2021-10-27 13:42:42.976 ***
alice_simple_message_62.814428@fed1/res1 in
<iq id='891f7459cc24bdee9e1c339126a9859e' type='result'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<jid>alice_simple_message_62.814428@fed1/res1</jid>
	</bind>
</iq>



*** User 2021-10-27 13:42:42.978 ***
alice_simple_message_62.814428@fed1/res1 out
<iq type='set' id='665399ec40bda3e8b553e68fff7b9e67'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2021-10-27 13:42:42.981 ***
alice_simple_message_62.814428@fed1/res1 in
<iq type='result' id='665399ec40bda3e8b553e68fff7b9e67'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2021-10-27 13:42:42.985 ***
alice_simple_message_62.814428@fed1/res1 out
<presence/>



*** User 2021-10-27 13:42:42.995 ***
alice_simple_message_62.814428@fed1/res1 in
<presence from='alice_simple_message_62.814428@fed1/res1' to='alice_simple_message_62.814428@fed1/res1' xml:lang='en'/>



*** User 2021-10-27 13:42:42.999 ***
alicE_simple_message_62.814428@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-27 13:42:43.001 ***
alicE_simple_message_62.814428@localhost/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='0c7bc3bba3ba9891' from='localhost' version='1.0' xml:lang='en'>

*** User 2021-10-27 13:42:43.001 ***
alicE_simple_message_62.814428@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-27 13:42:43.001 ***
alicE_simple_message_62.814428@localhost/res1 out
<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGFsaWNFX3NpbXBsZV9tZXNzYWdlXzYyLjgxNDQyOABtYXR5Z3J5c2E=</auth>



*** User 2021-10-27 13:42:43.013 ***
alicE_simple_message_62.814428@localhost/res1 in
<success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>



*** User 2021-10-27 13:42:43.013 ***
alicE_simple_message_62.814428@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-27 13:42:43.018 ***
alicE_simple_message_62.814428@localhost/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='29ed0e55db2bd824' from='localhost' version='1.0' xml:lang='en'>

*** User 2021-10-27 13:42:43.019 ***
alicE_simple_message_62.814428@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'/>
	<ver xmlns='urn:xmpp:features:rosterver'/>
	<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-27 13:42:43.019 ***
alicE_simple_message_62.814428@localhost/res1 out
<iq type='set' id='47001ffd2db08e5ccfc54b2083720ca1'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<resource>res1</resource>
	</bind>
</iq>



*** User 2021-10-27 13:42:43.020 ***
alicE_simple_message_62.814428@localhost/res1 in
<iq id='47001ffd2db08e5ccfc54b2083720ca1' type='result'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<jid>alicE_simple_message_62.814428@localhost/res1</jid>
	</bind>
</iq>



*** User 2021-10-27 13:42:43.022 ***
alicE_simple_message_62.814428@localhost/res1 out
<iq type='set' id='afc3f4a387f9c4f8e8e09e3a4a408d07'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2021-10-27 13:42:43.029 ***
alicE_simple_message_62.814428@localhost/res1 in
<iq type='result' id='afc3f4a387f9c4f8e8e09e3a4a408d07'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2021-10-27 13:42:43.029 ***
alicE_simple_message_62.814428@localhost/res1 out
<presence/>



*** User 2021-10-27 13:42:43.063 ***
alicE_simple_message_62.814428@localhost/res1 in
<presence from='alicE_simple_message_62.814428@localhost/res1' to='alice_simple_message_62.814428@localhost/res1' xml:lang='en'/>



*** User 2021-10-27 13:42:43.064 ***
alicE_simple_message_62.814428@localhost/res1 out
<message to='alice_simple_message_62.814428@fed1/res1' type='chat'>
	<body>Hi, foreign Alice!</body>
</message>


*** CT 2021-10-27 13:42:53.067 *** Stanza Log: simple_message.xml

*** CT Error Notification 2021-10-27 13:42:53.069 ***
escalus_client:wait_for_stanza failed on line 136
Reason: {timeout_when_waiting_for_stanza,[{escalus_client,...},{...}|...]}

Full error description and stacktrace
*** CT 2021-10-27 13:42:53.077 *** View log from node ejabberd2@localhost when finished ejabberd2@localhost_601.html
*** CT 2021-10-27 13:42:53.082 *** View log from node mongooseim@localhost when finished mongooseim@localhost_2174.html



=== Ended at 2021-10-27 13:42:53
=== Location: [{escalus_client,wait_for_stanza,136},
              {s2s_SUITE,'-simple_message/1-fun-0-',124},
              {escalus_story,story,72},
              {test_server,ts_tc,1783},
              {test_server,run_test_case_eval1,1292},
              {test_server,run_test_case_eval,1224}]
=== === Reason: timeout_when_waiting_for_stanza
  in function  escalus_client:wait_for_stanza/2
     called as escalus_client:wait_for_stanza({client,
                                               <<"alice_simple_message_62.814428@fed1/res1">>,
                                               escalus_tcp,<0.12516.1>,
                                               [{event_manager,<0.12512.1>},
                                                {server,<<"fed1">>},
                                                {username,
                                                 <<"alice_simple_message_62.814428">>},
                                                {resource,<<"res1">>}],
                                               [{event_client,
                                                 [{event_manager,<0.12512.1>},
                                                  {server,<<"fed1">>},
                                                  {username,
                                                   <<"alice_simple_message_62.814428">>},
                                                  {resource,<<"res1">>}]},
                                                {resource,<<"res1">>},
                                                {username,
                                                 <<"alice_simple_message_62.814428">>},
                                                {server,<<"fed1">>},
                                                {host,<<"localhost">>},
                                                {port,5242},
                                                {auth,
                                                 {escalus_auth,auth_plain}},
                                                {wspath,undefined},
                                                {username,
                                                 <<"alice_simple_message_62.814428">>},
                                                {server,<<"fed1">>},
                                                {host,<<"localhost">>},
                                                {port,5242},
                                                {password,<<"makota2">>},
                                                {stream_id,
                                                 <<"6b051845a573c9f9">>}]},
                                              10000)
  in call from s2s_SUITE:'-simple_message/1-fun-0-'/2 (/home/circleci/app/big_tests/tests/s2s_SUITE.erl, line 124)
  in call from escalus_story:story/4 (/home/circleci/app/big_tests/_build/default/lib/escalus/src/escalus_story.erl, line 72)
  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