=== Test case: s2s_SUITE:timeout_waiting_for_message/1 (click for source code) === Config value: [{watchdog,<0.26443.1>}, {{s2s,mim}, #{listener => #{backlog => 100,hibernate_after => 0,ip_address => "0", ip_tuple => {0,0,0,0}, ip_version => 4,max_stanza_size => 131072, module => ejabberd_s2s_in,num_acceptors => 100,port => 5269, proto => tcp,proxy_protocol => false,shaper => s2s_shaper, tls => #{ciphers => "TLSv1.2:TLSv1.3", dhfile => "priv/ssl/fake_dh_server.pem", protocol_options => ["no_sslv2","no_sslv3","no_tlsv1","no_tlsv1_1"], verify_mode => peer}}, opts => #{address => #{<<"fed1">> => #{ip_address => "127.0.0.1"}}, certfile => "priv/ssl/fake_server.pem", ciphers => "TLSv1.2:TLSv1.3",default_policy => allow, dns => #{retries => 2,timeout => 10}, max_retry_delay => 300, outgoing => #{connection_timeout => 10000, ip_versions => [4,6], port => 5299}, use_starttls => optional}}}, {{s2s,fed}, #{listener => #{backlog => 100,hibernate_after => 0,ip_address => "0", ip_tuple => {0,0,0,0}, ip_version => 4,max_stanza_size => 131072, module => ejabberd_s2s_in,num_acceptors => 100,port => 5299, proto => tcp,proxy_protocol => false,shaper => s2s_shaper, tls => #{ciphers => "TLSv1.2:TLSv1.3", dhfile => "priv/ssl/fake_dh_server.pem", protocol_options => ["no_sslv2","no_sslv3","no_tlsv1","no_tlsv1_1"], verify_mode => peer}}, opts => #{address => #{<<"domain.example.com">> => #{ip_address => "127.0.0.1"}, <<"localhost">> => #{ip_address => "127.0.0.1"}, <<"localhost.bis">> => #{ip_address => "127.0.0.1"}, <<"muc.localhost">> => #{ip_address => "127.0.0.1"}, <<"pubsub.localhost">> => #{ip_address => "127.0.0.1"}}, certfile => "priv/ssl/fake_server.pem", ciphers => "TLSv1.2:TLSv1.3",default_policy => allow, dns => #{retries => 2,timeout => 10}, max_retry_delay => 300, outgoing => #{connection_timeout => 10000, ip_versions => [4,6], port => 5269}, use_starttls => optional}}}, {escalus_user_db,xmpp}, {mim_data_dir,"/home/circleci/project/big_tests/tests/s2s_SUITE_data"}, {preset,"riak_mnesia"}, {tc_logfile,"/home/circleci/project/big_tests/ct_report/ct_run.test@838f153dfbc9.2022-06-22_08.43.58/big_tests.tests.s2s_SUITE.logs/run.2022-06-22_08.58.01/s2s_suite.timeout_waiting_for_message.55555.html"}, {tc_group_properties,[{name,node1_tls_false_node2_tls_required}]}, {tc_group_path,[]}, {data_dir,"/home/circleci/project/big_tests/_build/default/lib/mongoose_tests/ebin/s2s_SUITE_data/"}, {priv_dir,"/home/circleci/project/big_tests/ct_report/ct_run.test@838f153dfbc9.2022-06-22_08.43.58/big_tests.tests.s2s_SUITE.logs/run.2022-06-22_08.58.01/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/project/big_tests/ct_report/ct_run.test@838f153dfbc9.2022-06-22_08.43.58" === Started at 2022-06-22 08:58:18
*** User 2022-06-22 08:58:18.858 ***🔗 alice_timeout_waiting_for_message_1761@fed1 out <stream:stream to='fed1' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>
*** User 2022-06-22 08:58:18.859 ***🔗 alice_timeout_waiting_for_message_1761@fed1 in <stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='2d5d3dad4d819965' from='fed1' version='1.0' xml:lang='en'>
*** User 2022-06-22 08:58:18.859 ***🔗 alice_timeout_waiting_for_message_1761@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 2022-06-22 08:58:18.859 ***🔗 alice_timeout_waiting_for_message_1761@fed1 out <iq type='get' id='82c8596f0a1aa57820f87f5212c0ffa3'> <query xmlns='jabber:iq:register'/> </iq>
*** User 2022-06-22 08:58:18.860 ***🔗 alice_timeout_waiting_for_message_1761@fed1 in <iq from='fed1' id='82c8596f0a1aa57820f87f5212c0ffa3' type='result'> <query xmlns='jabber:iq:register'> <instructions>Choose a username and password to register with this server</instructions> <username/> <password/> </query> </iq>
*** User 2022-06-22 08:58:18.860 ***🔗 alice_timeout_waiting_for_message_1761@fed1 out <iq type='set' id='be29de6eebe2a480138fe0e4df8a0b6e'> <query xmlns='jabber:iq:register'> <username>alice_timeout_waiting_for_message_1761</username> <password>makota2</password> </query> </iq>
*** User 2022-06-22 08:58:18.883 ***🔗 alice_timeout_waiting_for_message_1761@fed1 in <iq from='fed1' id='be29de6eebe2a480138fe0e4df8a0b6e' type='result'> <query xmlns='jabber:iq:register'> <username>alice_timeout_waiting_for_message_1761</username> <password>makota2</password> </query> </iq>
*** User 2022-06-22 08:58:18.883 ***🔗 alice_timeout_waiting_for_message_1761@fed1 out </stream:stream>
*** User 2022-06-22 08:58:18.883 ***🔗 alice_timeout_waiting_for_message_1761@fed1 in </stream:stream>
*** User 2022-06-22 08:58:18.884 ***🔗 alicE_timeout_waiting_for_message_1761@localhost out <stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>
*** User 2022-06-22 08:58:18.884 ***🔗 alicE_timeout_waiting_for_message_1761@localhost in <stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='a78b6c97ec80d621' from='localhost' version='1.0' xml:lang='en'>
*** User 2022-06-22 08:58:18.884 ***🔗 alicE_timeout_waiting_for_message_1761@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 2022-06-22 08:58:18.884 ***🔗 alicE_timeout_waiting_for_message_1761@localhost out <iq type='get' id='bd1bab7ede9b12461096e1f3a3f7d1fe'> <query xmlns='jabber:iq:register'/> </iq>
*** User 2022-06-22 08:58:18.885 ***🔗 alicE_timeout_waiting_for_message_1761@localhost in <iq from='localhost' id='bd1bab7ede9b12461096e1f3a3f7d1fe' type='result'> <query xmlns='jabber:iq:register'> <instructions>Choose a username and password to register with this server</instructions> <username/> <password/> </query> </iq>
*** User 2022-06-22 08:58:18.885 ***🔗 alicE_timeout_waiting_for_message_1761@localhost out <iq type='set' id='1d3e0dea6fb73245c82987bf22d0f799'> <query xmlns='jabber:iq:register'> <username>alicE_timeout_waiting_for_message_1761</username> <password>matygrysa</password> </query> </iq>
*** User 2022-06-22 08:58:18.888 ***🔗 alicE_timeout_waiting_for_message_1761@localhost in <iq from='localhost' id='1d3e0dea6fb73245c82987bf22d0f799' type='result'> <query xmlns='jabber:iq:register'> <username>alicE_timeout_waiting_for_message_1761</username> <password>matygrysa</password> </query> </iq>
*** User 2022-06-22 08:58:18.888 ***🔗 alicE_timeout_waiting_for_message_1761@localhost out </stream:stream>
*** User 2022-06-22 08:58:18.888 ***🔗 alicE_timeout_waiting_for_message_1761@localhost in </stream:stream>
*** User 2022-06-22 08:58:18.888 ***🔗 alice_timeout_waiting_for_message_1761@fed1/res1 out <stream:stream to='fed1' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>
*** User 2022-06-22 08:58:18.889 ***🔗 alice_timeout_waiting_for_message_1761@fed1/res1 in <stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='52c1fe7291ac1ade' from='fed1' version='1.0' xml:lang='en'>
*** User 2022-06-22 08:58:18.889 ***🔗 alice_timeout_waiting_for_message_1761@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 2022-06-22 08:58:18.889 ***🔗 alice_timeout_waiting_for_message_1761@fed1/res1 out <auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGFsaWNlX3RpbWVvdXRfd2FpdGluZ19mb3JfbWVzc2FnZV8xNzYxAG1ha290YTI=</auth>
*** User 2022-06-22 08:58:18.891 ***🔗 alice_timeout_waiting_for_message_1761@fed1/res1 in <success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
*** User 2022-06-22 08:58:18.891 ***🔗 alice_timeout_waiting_for_message_1761@fed1/res1 out <stream:stream to='fed1' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>
*** User 2022-06-22 08:58:18.892 ***🔗 alice_timeout_waiting_for_message_1761@fed1/res1 in <stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='10d3fcd6301c90dc' from='fed1' version='1.0' xml:lang='en'>
*** User 2022-06-22 08:58:18.892 ***🔗 alice_timeout_waiting_for_message_1761@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 2022-06-22 08:58:18.892 ***🔗 alice_timeout_waiting_for_message_1761@fed1/res1 out <iq type='set' id='cd3bf6235e176803c648a6f1d5eaa6b3'> <bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'> <resource>res1</resource> </bind> </iq>
*** User 2022-06-22 08:58:18.892 ***🔗 alice_timeout_waiting_for_message_1761@fed1/res1 in <iq id='cd3bf6235e176803c648a6f1d5eaa6b3' type='result'> <bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'> <jid>alice_timeout_waiting_for_message_1761@fed1/res1</jid> </bind> </iq>
*** User 2022-06-22 08:58:18.892 ***🔗 alice_timeout_waiting_for_message_1761@fed1/res1 out <iq type='set' id='6e71b29aeb37d11a088a98c43c509ab9'> <session xmlns='urn:ietf:params:xml:ns:xmpp-session'/> </iq>
*** User 2022-06-22 08:58:18.892 ***🔗 alice_timeout_waiting_for_message_1761@fed1/res1 in <iq type='result' id='6e71b29aeb37d11a088a98c43c509ab9'> <session xmlns='urn:ietf:params:xml:ns:xmpp-session'/> </iq>
*** User 2022-06-22 08:58:18.893 ***🔗 alice_timeout_waiting_for_message_1761@fed1/res1 out <presence/>
*** User 2022-06-22 08:58:18.893 ***🔗 alice_timeout_waiting_for_message_1761@fed1/res1 in <presence from='alice_timeout_waiting_for_message_1761@fed1/res1' to='alice_timeout_waiting_for_message_1761@fed1/res1' xml:lang='en'/>
*** User 2022-06-22 08:58:18.893 ***🔗 alicE_timeout_waiting_for_message_1761@localhost/res1 out <stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>
*** User 2022-06-22 08:58:18.894 ***🔗 alicE_timeout_waiting_for_message_1761@localhost/res1 in <stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='b9d9c469f74ab5d1' from='localhost' version='1.0' xml:lang='en'>
*** User 2022-06-22 08:58:18.894 ***🔗 alicE_timeout_waiting_for_message_1761@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 2022-06-22 08:58:18.894 ***🔗 alicE_timeout_waiting_for_message_1761@localhost/res1 out <auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGFsaWNFX3RpbWVvdXRfd2FpdGluZ19mb3JfbWVzc2FnZV8xNzYxAG1hdHlncnlzYQ==</auth>
*** User 2022-06-22 08:58:18.896 ***🔗 alicE_timeout_waiting_for_message_1761@localhost/res1 in <success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
*** User 2022-06-22 08:58:18.896 ***🔗 alicE_timeout_waiting_for_message_1761@localhost/res1 out <stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>
*** User 2022-06-22 08:58:18.896 ***🔗 alicE_timeout_waiting_for_message_1761@localhost/res1 in <stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='6a1a35d01d1cd067' from='localhost' version='1.0' xml:lang='en'>
*** User 2022-06-22 08:58:18.896 ***🔗 alicE_timeout_waiting_for_message_1761@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 2022-06-22 08:58:18.896 ***🔗 alicE_timeout_waiting_for_message_1761@localhost/res1 out <iq type='set' id='cfbec9ce64153c177fd99f2252c0784e'> <bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'> <resource>res1</resource> </bind> </iq>
*** User 2022-06-22 08:58:18.896 ***🔗 alicE_timeout_waiting_for_message_1761@localhost/res1 in <iq id='cfbec9ce64153c177fd99f2252c0784e' type='result'> <bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'> <jid>alice_timeout_waiting_for_message_1761@localhost/res1</jid> </bind> </iq>
*** User 2022-06-22 08:58:18.896 ***🔗 alice_timeout_waiting_for_message_1761@localhost/res1 out <iq type='set' id='72edba85bf8d439dfe3962def6189682'> <session xmlns='urn:ietf:params:xml:ns:xmpp-session'/> </iq>
*** User 2022-06-22 08:58:18.897 ***🔗 alice_timeout_waiting_for_message_1761@localhost/res1 in <iq type='result' id='72edba85bf8d439dfe3962def6189682'> <session xmlns='urn:ietf:params:xml:ns:xmpp-session'/> </iq>
*** User 2022-06-22 08:58:18.897 ***🔗 alice_timeout_waiting_for_message_1761@localhost/res1 out <presence/>
*** User 2022-06-22 08:58:18.898 ***🔗 alice_timeout_waiting_for_message_1761@localhost/res1 in <presence from='alice_timeout_waiting_for_message_1761@localhost/res1' to='alice_timeout_waiting_for_message_1761@localhost/res1' xml:lang='en'/>
*** User 2022-06-22 08:58:18.898 ***🔗 alice_timeout_waiting_for_message_1761@localhost/res1 out <message to='alice_timeout_waiting_for_message_1761@fed1/res1' type='chat'> <body>Hi, foreign Alice!</body> </message>
*** User 2022-06-22 08:58:18.901 ***🔗 alice_timeout_waiting_for_message_1761@fed1/res1 in <message from='alice_timeout_waiting_for_message_1761@localhost/res1' to='alice_timeout_waiting_for_message_1761@fed1/res1' xml:lang='en' type='chat'> <body>Hi, foreign Alice!</body> </message>
*** User 2022-06-22 08:58:18.901 ***🔗 alice_timeout_waiting_for_message_1761@fed1/res1 out <message to='alice_timeout_waiting_for_message_1761@localhost/res1' type='chat'> <body>Nice to meet you!</body> </message>
=== Ended at 2022-06-22 08:58:28 === successfully completed test case === === Returned value: ok
Test run history | Top level test index | Latest test result