=== Test case: mod_global_distrib_SUITE:test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first/1 (click for source code)

=== Config value:

    [{nodes_key,<<"localhost.bis#{nodes}">>},
     {escalus_user_db,xmpp},
     {redis_extra_config,[{refresh_after,3600}]},
     {watchdog,<0.20927.0>},
     {add_advertised_endpoints,[]},
     {extra_config,[]},
     {redis_extra_config,[no_opts]},
     {mim_data_dir,"/home/circleci/project/big_tests/tests/mod_global_distrib_SUITE_data"},
     {preset,"ldap_mnesia"},
     {tc_logfile,"/home/circleci/project/big_tests/ct_report/ct_run.test@d66972e2ee88.2022-01-27_15.24.27/big_tests.tests.mod_global_distrib_SUITE.logs/run.2022-01-27_15.27.41/mod_global_distrib_suite.test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first.html"},
     {tc_group_properties,[{name,mod_global_distrib},{repeat_until_all_ok,3}]},
     {tc_group_path,[]},
     {data_dir,"/home/circleci/project/big_tests/_build/default/lib/mongoose_tests/ebin/mod_global_distrib_SUITE_data/"},
     {priv_dir,"/home/circleci/project/big_tests/ct_report/ct_run.test@d66972e2ee88.2022-01-27_15.24.27/big_tests.tests.mod_global_distrib_SUITE.logs/run.2022-01-27_15.27.41/log_private/"},
     {{saved_modules,mongooseim@localhost,<<"localhost">>},
      #{mod_adhoc => [],mod_amp => [],mod_bosh => [],
        mod_cache_users => [{number_of_segments,5},{time_to_live,2}],
        mod_carboncopy => [],mod_commands => [],
        mod_disco => [{users_can_see_hidden_services,false}],
        mod_muc_commands => [],mod_muc_light_commands => [],
        mod_register =>
            [{access,register},
             {ip_access,[{allow,"127.0.0.0/8"},{deny,"0.0.0.0/0"}]},
             {welcome_message,{[],[]}}],
        mod_roster => [],mod_sic => [],mod_stream_management => [],
        mod_vcard =>
            [{backend,ldap},
             {host,{prefix,<<"vjud.">>}},
             {ldap_base,"ou=Users,dc=esl,dc=com"},
             {ldap_filter,<<"(objectClass=inetOrgPerson)">>}]}},
     {{saved_modules,mongooseim@localhost,<<"localhost.bis">>},
      #{mod_adhoc => [],mod_amp => [],mod_bosh => [],
        mod_cache_users => [{number_of_segments,5},{time_to_live,2}],
        mod_carboncopy => [],mod_commands => [],
        mod_disco => [{users_can_see_hidden_services,false}],
        mod_last => [],mod_muc_commands => [],mod_muc_light_commands => [],
        mod_register =>
            [{access,register},
             {ip_access,[{allow,"127.0.0.0/8"},{deny,"0.0.0.0/0"}]},
             {welcome_message,{[],[]}}],
        mod_roster => [],mod_sic => [],mod_stream_management => [],
        mod_vcard =>
            [{backend,ldap},
             {host,{prefix,<<"vjud.">>}},
             {ldap_base,"ou=Users,dc=esl,dc=com"},
             {ldap_filter,<<"(objectClass=inetOrgPerson)">>}]}},
     {{saved_modules,ejabberd2@localhost,<<"localhost">>},
      #{mod_adhoc => [],mod_bosh => [],mod_carboncopy => [],
        mod_commands => [],
        mod_disco => [{users_can_see_hidden_services,false}],
        mod_muc_commands => [],mod_muc_light_commands => [],
        mod_register =>
            [{access,register},
             {ip_access,[{allow,"127.0.0.0/8"},{deny,"0.0.0.0/0"}]},
             {welcome_message,{[],[]}}],
        mod_roster => [],mod_sic => [],mod_stream_management => [],
        mod_vcard =>
            [{backend,ldap},
             {host,{prefix,<<"vjud.">>}},
             {ldap_base,"ou=Users,dc=esl,dc=com"},
             {ldap_filter,<<"(objectClass=inetOrgPerson)">>}]}},
     {{saved_modules,ejabberd2@localhost,<<"localhost.bis">>},
      #{mod_adhoc => [],mod_bosh => [],mod_carboncopy => [],
        mod_commands => [],
        mod_disco => [{users_can_see_hidden_services,false}],
        mod_muc_commands => [],mod_muc_light_commands => [],
        mod_register =>
            [{access,register},
             {ip_access,[{allow,"127.0.0.0/8"},{deny,"0.0.0.0/0"}]},
             {welcome_message,{[],[]}}],
        mod_roster => [],mod_sic => [],mod_stream_management => [],
        mod_vcard =>
            [{backend,ldap},
             {host,{prefix,<<"vjud.">>}},
             {ldap_base,"ou=Users,dc=esl,dc=com"},
             {ldap_filter,<<"(objectClass=inetOrgPerson)">>}]}},
     {{saved_modules,reg1@localhost,<<"localhost">>},
      #{mod_adhoc => [],mod_bosh => [],mod_cache_users => [],
        mod_carboncopy => [],mod_commands => [],
        mod_disco => [{users_can_see_hidden_services,false}],
        mod_muc_commands => [],mod_muc_light_commands => [],
        mod_register =>
            [{access,register},
             {ip_access,[{allow,"127.0.0.0/8"},{deny,"0.0.0.0/0"}]},
             {welcome_message,{[],[]}}],
        mod_roster => [],mod_sic => [],mod_stream_management => [],
        mod_vcard =>
            [{backend,ldap},
             {host,{prefix,<<"vjud.">>}},
             {ldap_base,"ou=Users,dc=esl,dc=com"},
             {ldap_filter,<<"(objectClass=inetOrgPerson)">>}]}}]

=== Current directory is "/home/circleci/project/big_tests/ct_report/ct_run.test@d66972e2ee88.2022-01-27_15.24.27"

=== Started at 2022-01-27 15:27:50


*** CT 2022-01-27 15:27:50.297 *** View log from node mongooseim@localhost when started mongooseim@localhost_1195.html
*** CT 2022-01-27 15:27:50.297 *** View log from node ejabberd2@localhost when started ejabberd2@localhost_712.html

*** User 2022-01-27 15:27:50.299 ***
eve_unnamed_608@localhost out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2022-01-27 15:27:50.300 ***
eve_unnamed_608@localhost in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='706c7c8f4a70595a' from='localhost' version='1.0' xml:lang='en'>

*** User 2022-01-27 15:27:50.300 ***
eve_unnamed_608@localhost in
<stream:features>
	<starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
	<mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>
		<mechanism>PLAIN</mechanism>
	</mechanisms>
	<register xmlns='http://jabber.org/features/iq-register'/>
	<sm xmlns='urn:xmpp:sm:3'/>
</stream:features>



*** User 2022-01-27 15:27:50.300 ***
eve_unnamed_608@localhost out
<iq type='get' id='0219402d234eb13c94ef17dc72f7f4ed'>
	<query xmlns='jabber:iq:register'/>
</iq>



*** User 2022-01-27 15:27:50.300 ***
eve_unnamed_608@localhost in
<iq from='localhost' id='0219402d234eb13c94ef17dc72f7f4ed' 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-01-27 15:27:50.300 ***
eve_unnamed_608@localhost out
<iq type='set' id='ec5f8e297088f564decc4be2ddc7a135'>
	<query xmlns='jabber:iq:register'>
		<username>eve_unnamed_608</username>
		<password>password</password>
	</query>
</iq>



*** User 2022-01-27 15:27:50.302 ***
eve_unnamed_608@localhost in
<iq from='localhost' id='ec5f8e297088f564decc4be2ddc7a135' type='result'>
	<query xmlns='jabber:iq:register'>
		<username>eve_unnamed_608</username>
		<password>password</password>
	</query>
</iq>



*** User 2022-01-27 15:27:50.302 ***
eve_unnamed_608@localhost out
</stream:stream>

*** User 2022-01-27 15:27:50.302 ***
eve_unnamed_608@localhost in
</stream:stream>

*** User 2022-01-27 15:27:50.303 ***
eve_unnamed_608@localhost out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2022-01-27 15:27:50.304 ***
eve_unnamed_608@localhost in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='b63a28c0638fe7bd' from='localhost' version='1.0' xml:lang='en'>

*** User 2022-01-27 15:27:50.304 ***
eve_unnamed_608@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>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-01-27 15:27:50.304 ***
eve_unnamed_608@localhost out
<iq type='get' id='4a42befa609f0fd4a12470d62fa949fb'>
	<query xmlns='jabber:iq:register'/>
</iq>



*** User 2022-01-27 15:27:50.304 ***
eve_unnamed_608@localhost in
<iq from='localhost' id='4a42befa609f0fd4a12470d62fa949fb' 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-01-27 15:27:50.304 ***
eve_unnamed_608@localhost out
<iq type='set' id='173f0c27ca7e5ef6dc6f28c997698265'>
	<query xmlns='jabber:iq:register'>
		<username>eve_unnamed_608</username>
		<password>password</password>
	</query>
</iq>



*** User 2022-01-27 15:27:50.305 ***
eve_unnamed_608@localhost in
<iq from='localhost' id='173f0c27ca7e5ef6dc6f28c997698265' type='error'>
	<query xmlns='jabber:iq:register'>
		<username>eve_unnamed_608</username>
		<password>password</password>
	</query>
	<error code='409' type='cancel'>
		<conflict xmlns='urn:ietf:params:xml:ns:xmpp-stanzas'/>
	</error>
</iq>



*** User 2022-01-27 15:27:50.305 ***
eve_unnamed_608@localhost out
</stream:stream>

*** User 2022-01-27 15:27:50.305 ***
eve_unnamed_608@localhost in
</stream:stream>

*** System report during mod_global_distrib_SUITE:test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first/1 in mod_global_distrib 2022-01-27 15:27:50.305 ***
=INFO REPORT==== 27-Jan-2022::15:27:50.305605 ===
user already existed: eve_unnamed_608password



*** User 2022-01-27 15:27:50.307 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2022-01-27 15:27:50.307 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='1675b33249f647c7' from='localhost' version='1.0' xml:lang='en'>

*** User 2022-01-27 15:27:50.307 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@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>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-01-27 15:27:50.308 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost out
<iq type='get' id='07894c4d89892be252b29cdbaa311c95'>
	<query xmlns='jabber:iq:register'/>
</iq>



*** User 2022-01-27 15:27:50.308 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost in
<iq from='localhost' id='07894c4d89892be252b29cdbaa311c95' 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-01-27 15:27:50.308 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost out
<iq type='set' id='86089c963fa95ac8280ea5a203fdca82'>
	<query xmlns='jabber:iq:register'>
		<username>alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609</username>
		<password>matygrysa</password>
	</query>
</iq>



*** User 2022-01-27 15:27:50.309 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost in
<iq from='localhost' id='86089c963fa95ac8280ea5a203fdca82' type='result'>
	<query xmlns='jabber:iq:register'>
		<username>alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609</username>
		<password>matygrysa</password>
	</query>
</iq>



*** User 2022-01-27 15:27:50.309 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost out
</stream:stream>

*** User 2022-01-27 15:27:50.309 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost in
</stream:stream>

*** User 2022-01-27 15:27:50.310 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@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-01-27 15:27:50.310 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='88043b2beca26b24' from='localhost' version='1.0' xml:lang='en'>

*** User 2022-01-27 15:27:50.310 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@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>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-01-27 15:27:50.310 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/res1 out
<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGFsaWNFX3Rlc3RfcG1fd2l0aF91bmdyYWNlZnVsX3JlY29ubmVjdGlvbl90b19kaWZmZXJlbnRfc2VydmVyX3dpdGhfYXNpYV9yZWZyZXNoZXNfZmlyc3RfNjA5AG1hdHlncnlzYQ==</auth>



*** User 2022-01-27 15:27:50.311 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/res1 in
<success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>



*** User 2022-01-27 15:27:50.311 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@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-01-27 15:27:50.312 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='a3e943837adf6954' from='localhost' version='1.0' xml:lang='en'>

*** User 2022-01-27 15:27:50.312 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@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-01-27 15:27:50.312 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/res1 out
<iq type='set' id='aaa9e5115c65482f996e9053818be197'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<resource>res1</resource>
	</bind>
</iq>



*** User 2022-01-27 15:27:50.312 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/res1 in
<iq id='aaa9e5115c65482f996e9053818be197' type='result'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<jid>alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/res1</jid>
	</bind>
</iq>



*** User 2022-01-27 15:27:50.312 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/res1 out
<iq type='set' id='019c4587877beae4096d5c618c4a9846'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2022-01-27 15:27:50.312 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/res1 in
<iq type='result' id='019c4587877beae4096d5c618c4a9846'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2022-01-27 15:27:50.312 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/res1 out
<presence/>



*** User 2022-01-27 15:27:50.314 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/res1 in
<presence from='alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/res1' to='alice_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/res1' xml:lang='en'/>



*** User 2022-01-27 15:27:50.314 ***
eve_unnamed_608@localhost out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2022-01-27 15:27:50.315 ***
eve_unnamed_608@localhost in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='e63afee19d88d8b7' from='localhost' version='1.0' xml:lang='en'>

*** User 2022-01-27 15:27:50.315 ***
eve_unnamed_608@localhost in
<stream:features>
	<starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
	<mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>
		<mechanism>PLAIN</mechanism>
	</mechanisms>
	<register xmlns='http://jabber.org/features/iq-register'/>
	<sm xmlns='urn:xmpp:sm:3'/>
</stream:features>



*** User 2022-01-27 15:27:50.315 ***
eve_unnamed_608@localhost out
<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGV2ZV91bm5hbWVkXzYwOABwYXNzd29yZA==</auth>



*** User 2022-01-27 15:27:50.316 ***
eve_unnamed_608@localhost in
<success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>



*** User 2022-01-27 15:27:50.316 ***
eve_unnamed_608@localhost out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2022-01-27 15:27:50.316 ***
eve_unnamed_608@localhost in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='a9433e7fded7042b' from='localhost' version='1.0' xml:lang='en'>

*** User 2022-01-27 15:27:50.316 ***
eve_unnamed_608@localhost 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-01-27 15:27:50.316 ***
eve_unnamed_608@localhost out
<iq type='set' id='19188f47b879884cf9ce9dd5f4cf1a28'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<resource>escalus-default-resource</resource>
	</bind>
</iq>



*** User 2022-01-27 15:27:50.317 ***
eve_unnamed_608@localhost in
<iq id='19188f47b879884cf9ce9dd5f4cf1a28' type='result'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<jid>eve_unnamed_608@localhost/escalus-default-resource</jid>
	</bind>
</iq>



*** User 2022-01-27 15:27:50.317 ***
eve_unnamed_608@localhost/escalus-default-resource out
<iq type='set' id='07a514854dc8b03629eaac963be48c02'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2022-01-27 15:27:50.317 ***
eve_unnamed_608@localhost/escalus-default-resource in
<iq type='result' id='07a514854dc8b03629eaac963be48c02'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2022-01-27 15:27:50.317 ***
eve_unnamed_608@localhost/escalus-default-resource out
<enable xmlns='urn:xmpp:sm:3' resume='true'/>



*** User 2022-01-27 15:27:50.318 ***
eve_unnamed_608@localhost/escalus-default-resource in
<enabled xmlns='urn:xmpp:sm:3' id='LSYj9Y3oGNXTthSu8XJDN7vevJeR' resume='true'/>



*** User 2022-01-27 15:27:50.318 ***
eve_unnamed_608@localhost/escalus-default-resource out
<presence/>



*** User 2022-01-27 15:27:50.319 ***
eve_unnamed_608@localhost/escalus-default-resource in
<presence from='eve_unnamed_608@localhost/escalus-default-resource' to='eve_unnamed_608@localhost/escalus-default-resource' xml:lang='en'/>



*** User 2022-01-27 15:27:50.320 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/res1 out
<message to='eve_unnamed_608@localhost' type='chat' id='-576460528075428736'>
	<body>Hi from Europe1!</body>
</message>



*** User 2022-01-27 15:27:50.421 ***
eve_unnamed_608@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-01-27 15:27:50.422 ***
eve_unnamed_608@localhost/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='b4c9b28ad9c34437' from='localhost' version='1.0' xml:lang='en'>

*** User 2022-01-27 15:27:50.422 ***
eve_unnamed_608@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>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-01-27 15:27:50.422 ***
eve_unnamed_608@localhost/res1 out
<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGV2ZV91bm5hbWVkXzYwOABwYXNzd29yZA==</auth>



*** User 2022-01-27 15:27:50.424 ***
eve_unnamed_608@localhost/res1 in
<success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>



*** User 2022-01-27 15:27:50.424 ***
eve_unnamed_608@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-01-27 15:27:50.424 ***
eve_unnamed_608@localhost/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='68f0dd8acde136c7' from='localhost' version='1.0' xml:lang='en'>

*** User 2022-01-27 15:27:50.424 ***
eve_unnamed_608@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-01-27 15:27:50.424 ***
eve_unnamed_608@localhost/res1 out
<iq type='set' id='4df340ccc13b016715fdba8ff61d8d7c'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<resource>res1</resource>
	</bind>
</iq>



*** User 2022-01-27 15:27:50.424 ***
eve_unnamed_608@localhost/res1 in
<iq id='4df340ccc13b016715fdba8ff61d8d7c' type='result'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<jid>eve_unnamed_608@localhost/res1</jid>
	</bind>
</iq>



*** User 2022-01-27 15:27:50.425 ***
eve_unnamed_608@localhost/res1 out
<iq type='set' id='9f8c4603ede46085d628478ed05b064a'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2022-01-27 15:27:50.425 ***
eve_unnamed_608@localhost/res1 in
<iq type='result' id='9f8c4603ede46085d628478ed05b064a'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2022-01-27 15:27:50.425 ***
eve_unnamed_608@localhost/res1 out
<presence/>



*** User 2022-01-27 15:27:50.436 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/res1 out
<message to='eve_unnamed_608@localhost' type='chat' id='-576460527959228160'>
	<body>Hi again from Europe1!</body>
</message>



*** User 2022-01-27 15:27:50.436 ***
eve_unnamed_608@localhost/res1 out
<message to='alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/res1' type='chat'>
	<body>Hi from Europe!</body>
</message>



*** User 2022-01-27 15:27:50.437 ***
eve_unnamed_608@localhost/res1 in
<message from='alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/res1' to='eve_unnamed_608@localhost' xml:lang='en' type='chat' id='-576460527959228160'>
	<body>Hi again from Europe1!</body>
</message>



*** User 2022-01-27 15:27:50.439 ***
eve_unnamed_608@localhost/res1 in
<message from='alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/res1' to='eve_unnamed_608@localhost' xml:lang='en' type='chat' id='-576460528075428736'>
	<body>Hi from Europe1!</body>
	<delay xmlns='urn:xmpp:delay' stamp='2022-01-27T15:27:50.431518Z' from='localhost'>SM Storage</delay>
</message>



*** User 2022-01-27 15:27:50.440 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/res1 in
<message from='eve_unnamed_608@localhost/res1' to='alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/res1' xml:lang='en' type='chat'>
	<body>Hi from Europe!</body>
</message>



*** User 2022-01-27 15:27:50.440 ***
eve_unnamed_608@localhost/res1 out
</stream:stream>

*** User 2022-01-27 15:27:50.440 ***
eve_unnamed_608@localhost/res1 in
</stream:stream>

*** User 2022-01-27 15:27:50.440 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/res1 out
</stream:stream>

*** User 2022-01-27 15:27:50.440 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/res1 in
</stream:stream>

*** User 2022-01-27 15:27:50.443 ***
eve_unnamed_608@localhost out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2022-01-27 15:27:50.443 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2022-01-27 15:27:50.443 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='3fa33e284d9d1382' from='localhost' version='1.0' xml:lang='en'>

*** User 2022-01-27 15:27:50.443 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@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>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-01-27 15:27:50.443 ***
eve_unnamed_608@localhost in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='37f2345b990cd868' from='localhost' version='1.0' xml:lang='en'>

*** User 2022-01-27 15:27:50.443 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost out
<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGFsaWNFX3Rlc3RfcG1fd2l0aF91bmdyYWNlZnVsX3JlY29ubmVjdGlvbl90b19kaWZmZXJlbnRfc2VydmVyX3dpdGhfYXNpYV9yZWZyZXNoZXNfZmlyc3RfNjA5AG1hdHlncnlzYQ==</auth>



*** User 2022-01-27 15:27:50.443 ***
eve_unnamed_608@localhost in
<stream:features>
	<starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
	<mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>
		<mechanism>PLAIN</mechanism>
	</mechanisms>
	<register xmlns='http://jabber.org/features/iq-register'/>
	<sm xmlns='urn:xmpp:sm:3'/>
</stream:features>



*** User 2022-01-27 15:27:50.443 ***
eve_unnamed_608@localhost out
<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGV2ZV91bm5hbWVkXzYwOABwYXNzd29yZA==</auth>



*** User 2022-01-27 15:27:50.444 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost in
<success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>



*** User 2022-01-27 15:27:50.445 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2022-01-27 15:27:50.445 ***
eve_unnamed_608@localhost in
<success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>



*** User 2022-01-27 15:27:50.445 ***
eve_unnamed_608@localhost out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2022-01-27 15:27:50.445 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='aafd43ede1a78473' from='localhost' version='1.0' xml:lang='en'>

*** User 2022-01-27 15:27:50.445 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost 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-01-27 15:27:50.445 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost out
<iq type='set' id='69f4537380912cb28734ad86e5ac6c04'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<resource>escalus-default-resource</resource>
	</bind>
</iq>



*** User 2022-01-27 15:27:50.445 ***
eve_unnamed_608@localhost in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='86d1fa5bb24b0b09' from='localhost' version='1.0' xml:lang='en'>

*** User 2022-01-27 15:27:50.445 ***
eve_unnamed_608@localhost 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-01-27 15:27:50.445 ***
eve_unnamed_608@localhost out
<iq type='set' id='5cd59961068648453d7ebf5cf10990e7'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<resource>escalus-default-resource</resource>
	</bind>
</iq>



*** User 2022-01-27 15:27:50.445 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost in
<iq id='69f4537380912cb28734ad86e5ac6c04' type='result'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<jid>alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/escalus-default-resource</jid>
	</bind>
</iq>



*** User 2022-01-27 15:27:50.446 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/escalus-default-resource out
<iq type='set' id='b6080d51ed019ed829cc399b91b575ca'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2022-01-27 15:27:50.446 ***
eve_unnamed_608@localhost in
<iq id='5cd59961068648453d7ebf5cf10990e7' type='result'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<jid>eve_unnamed_608@localhost/escalus-default-resource</jid>
	</bind>
</iq>



*** User 2022-01-27 15:27:50.446 ***
eve_unnamed_608@localhost/escalus-default-resource out
<iq type='set' id='736cb03167622aabdc92ab776c455fc5'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2022-01-27 15:27:50.446 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/escalus-default-resource in
<iq type='result' id='b6080d51ed019ed829cc399b91b575ca'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2022-01-27 15:27:50.446 ***
eve_unnamed_608@localhost/escalus-default-resource in
<iq type='result' id='736cb03167622aabdc92ab776c455fc5'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2022-01-27 15:27:50.446 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/escalus-default-resource out
<iq type='set' id='8e02ff13fbf16539efda096aed3ae41b'>
	<query xmlns='jabber:iq:register'>
		<remove/>
	</query>
</iq>



*** User 2022-01-27 15:27:50.446 ***
eve_unnamed_608@localhost/escalus-default-resource out
<iq type='set' id='3ef344dd27cbb01c3e251021a116a3b9'>
	<query xmlns='jabber:iq:register'>
		<remove/>
	</query>
</iq>



*** User 2022-01-27 15:27:50.448 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/escalus-default-resource in
<iq from='' to='alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/escalus-default-resource' id='8e02ff13fbf16539efda096aed3ae41b' type='result'/>



*** User 2022-01-27 15:27:50.448 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/escalus-default-resource out
</stream:stream>

*** User 2022-01-27 15:27:50.448 ***
eve_unnamed_608@localhost/escalus-default-resource in
<iq from='' to='eve_unnamed_608@localhost/escalus-default-resource' id='3ef344dd27cbb01c3e251021a116a3b9' type='result'/>



*** User 2022-01-27 15:27:50.448 ***
eve_unnamed_608@localhost/escalus-default-resource out
</stream:stream>

*** User 2022-01-27 15:27:50.448 ***
alicE_test_pm_with_ungraceful_reconnection_to_different_server_with_asia_refreshes_first_609@localhost/escalus-default-resource in
</stream:stream>

*** User 2022-01-27 15:27:50.448 ***
eve_unnamed_608@localhost/escalus-default-resource in
</stream:stream>

*** User 2022-01-27 15:27:50.450 ***
eve_unnamed_608@localhost out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2022-01-27 15:27:50.450 ***
eve_unnamed_608@localhost in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='988863f06472c5c6' from='localhost' version='1.0' xml:lang='en'>

*** User 2022-01-27 15:27:50.450 ***
eve_unnamed_608@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>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-01-27 15:27:50.450 ***
eve_unnamed_608@localhost out
<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGV2ZV91bm5hbWVkXzYwOABwYXNzd29yZA==</auth>



*** User 2022-01-27 15:27:50.451 ***
eve_unnamed_608@localhost in
<failure xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>
	<not-authorized/>
</failure>


*** CT 2022-01-27 15:27:50.456 *** View log from node ejabberd2@localhost when finished ejabberd2@localhost_714.html
*** CT 2022-01-27 15:27:50.457 *** View log from node mongooseim@localhost when finished mongooseim@localhost_1226.html



=== Ended at 2022-01-27 15:27:50
=== successfully completed test case
=== === Returned value: ok


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