=== Test case: presence_SUITE:subscribe_relog/1 (click for source code)
=== Config value:
[{watchdog,<0.32174.1>},
{mim_data_dir,"/home/circleci/project/big_tests/tests/presence_SUITE_data"},
{preset,"odbc_mssql_mnesia"},
{tc_logfile,"/home/circleci/project/big_tests/ct_report/ct_run.test@43a5deef6262.2022-01-27_15.25.11/big_tests.tests.presence_SUITE.logs/run.2022-01-27_15.34.54/presence_suite.subscribe_relog.103299.html"},
{tc_group_properties,[{name,subscribe_group},
{repeat_until_all_ok,3},
parallel]},
{tc_group_path,[]},
{data_dir,"/home/circleci/project/big_tests/_build/default/lib/mongoose_tests/ebin/presence_SUITE_data/"},
{priv_dir,"/home/circleci/project/big_tests/ct_report/ct_run.test@43a5deef6262.2022-01-27_15.25.11/big_tests.tests.presence_SUITE.logs/run.2022-01-27_15.34.54/log_private/"},
{escalus_users,[{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@43a5deef6262.2022-01-27_15.25.11"
=== Started at 2022-01-27 15:34:56
*** User 2022-01-27 15:34:56.637 ***
alicE_subscribe_relog_1840@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:34:56.638 ***
alicE_subscribe_relog_1840@localhost/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='3845aba3c2654254' from='localhost' version='1.0' xml:lang='en'>
*** User 2022-01-27 15:34:56.638 ***
alicE_subscribe_relog_1840@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-01-27 15:34:56.638 ***
alicE_subscribe_relog_1840@localhost/res1 out
<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGFsaWNFX3N1YnNjcmliZV9yZWxvZ18xODQwAG1hdHlncnlzYQ==</auth>
*** User 2022-01-27 15:34:56.639 ***
alicE_subscribe_relog_1840@localhost/res1 in
<success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
*** User 2022-01-27 15:34:56.639 ***
alicE_subscribe_relog_1840@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:34:56.640 ***
alicE_subscribe_relog_1840@localhost/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='c262052bd9e17475' from='localhost' version='1.0' xml:lang='en'>
*** User 2022-01-27 15:34:56.640 ***
alicE_subscribe_relog_1840@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:34:56.640 ***
alicE_subscribe_relog_1840@localhost/res1 out
<iq type='set' id='17878e3a541e1f7d6ddac05ad8653d27'>
<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
<resource>res1</resource>
</bind>
</iq>
*** User 2022-01-27 15:34:56.640 ***
alicE_subscribe_relog_1840@localhost/res1 in
<iq id='17878e3a541e1f7d6ddac05ad8653d27' type='result'>
<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
<jid>alicE_subscribe_relog_1840@localhost/res1</jid>
</bind>
</iq>
*** User 2022-01-27 15:34:56.640 ***
alicE_subscribe_relog_1840@localhost/res1 out
<iq type='set' id='22d088ca4ea59f2a40aaac8fd3129cc7'>
<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>
*** User 2022-01-27 15:34:56.641 ***
alicE_subscribe_relog_1840@localhost/res1 in
<iq type='result' id='22d088ca4ea59f2a40aaac8fd3129cc7'>
<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>
*** User 2022-01-27 15:34:56.641 ***
alicE_subscribe_relog_1840@localhost/res1 out
<presence/>
*** User 2022-01-27 15:34:56.642 ***
alicE_subscribe_relog_1840@localhost/res1 in
<presence from='alicE_subscribe_relog_1840@localhost/res1' to='alice_subscribe_relog_1840@localhost/res1' xml:lang='en'/>
*** User 2022-01-27 15:34:56.643 ***
bOb_subscribe_relog_1840@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:34:56.644 ***
bOb_subscribe_relog_1840@localhost/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='cda029f7f0ad7a92' from='localhost' version='1.0' xml:lang='en'>
*** User 2022-01-27 15:34:56.644 ***
bOb_subscribe_relog_1840@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-01-27 15:34:56.644 ***
bOb_subscribe_relog_1840@localhost/res1 out
<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGJPYl9zdWJzY3JpYmVfcmVsb2dfMTg0MABtYWtyb2xpa2E=</auth>
*** User 2022-01-27 15:34:56.645 ***
bOb_subscribe_relog_1840@localhost/res1 in
<success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
*** User 2022-01-27 15:34:56.645 ***
bOb_subscribe_relog_1840@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:34:56.646 ***
bOb_subscribe_relog_1840@localhost/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='b8b52011fd3655fa' from='localhost' version='1.0' xml:lang='en'>
*** User 2022-01-27 15:34:56.646 ***
bOb_subscribe_relog_1840@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:34:56.646 ***
bOb_subscribe_relog_1840@localhost/res1 out
<iq type='set' id='25e0d3deecdc675e842082eb50bee1f1'>
<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
<resource>res1</resource>
</bind>
</iq>
*** User 2022-01-27 15:34:56.646 ***
bOb_subscribe_relog_1840@localhost/res1 in
<iq id='25e0d3deecdc675e842082eb50bee1f1' type='result'>
<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
<jid>bOb_subscribe_relog_1840@localhost/res1</jid>
</bind>
</iq>
*** User 2022-01-27 15:34:56.646 ***
bOb_subscribe_relog_1840@localhost/res1 out
<iq type='set' id='9782e70fbd43f2dc5842fb469512fdd7'>
<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>
*** User 2022-01-27 15:34:56.647 ***
bOb_subscribe_relog_1840@localhost/res1 in
<iq type='result' id='9782e70fbd43f2dc5842fb469512fdd7'>
<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>
*** User 2022-01-27 15:34:56.647 ***
bOb_subscribe_relog_1840@localhost/res1 out
<presence/>
*** User 2022-01-27 15:34:56.649 ***
bOb_subscribe_relog_1840@localhost/res1 in
<presence from='bOb_subscribe_relog_1840@localhost/res1' to='bob_subscribe_relog_1840@localhost/res1' xml:lang='en'/>
*** User 2022-01-27 15:34:56.649 ***
alicE_subscribe_relog_1840@localhost/res1 out
<iq type='set' id='547e1fad51faca3d9012ed24a6d2f82e'>
<query xmlns='jabber:iq:roster'>
<item jid='bOb_subscribe_relog_1840@localhost' name='Bobby'>
<group>friends</group>
</item>
</query>
</iq>
*** User 2022-01-27 15:34:56.664 ***
alicE_subscribe_relog_1840@localhost/res1 in
<iq from='alicE_subscribe_relog_1840@localhost' to='alicE_subscribe_relog_1840@localhost/res1' id='pushbc53633ab9728102' type='set'>
<query xmlns='jabber:iq:roster'>
<item subscription='none' name='Bobby' jid='bob_subscribe_relog_1840@localhost'>
<group>friends</group>
</item>
</query>
</iq>
*** User 2022-01-27 15:34:56.664 ***
alicE_subscribe_relog_1840@localhost/res1 in
<iq from='alicE_subscribe_relog_1840@localhost' to='alicE_subscribe_relog_1840@localhost/res1' id='547e1fad51faca3d9012ed24a6d2f82e' type='result'/>
*** User 2022-01-27 15:34:56.664 ***
alicE_subscribe_relog_1840@localhost/res1 out
<iq to='alicE_subscribe_relog_1840@localhost' id='pushbc53633ab9728102' type='result'/>
*** User 2022-01-27 15:34:56.664 ***
alicE_subscribe_relog_1840@localhost/res1 out
<presence type='subscribe' to='bOb_subscribe_relog_1840@localhost'/>
*** User 2022-01-27 15:34:56.672 ***
alicE_subscribe_relog_1840@localhost/res1 in
<iq from='alicE_subscribe_relog_1840@localhost' to='alicE_subscribe_relog_1840@localhost/res1' id='push12e4aa23db49a3aa' type='set'>
<query xmlns='jabber:iq:roster'>
<item ask='subscribe' subscription='none' name='Bobby' jid='bob_subscribe_relog_1840@localhost'>
<group>friends</group>
</item>
</query>
</iq>
*** User 2022-01-27 15:34:56.672 ***
alicE_subscribe_relog_1840@localhost/res1 out
<iq to='alicE_subscribe_relog_1840@localhost' id='push12e4aa23db49a3aa' type='result'/>
*** User 2022-01-27 15:34:56.672 ***
bOb_subscribe_relog_1840@localhost/res1 in
<presence from='alicE_subscribe_relog_1840@localhost' to='bOb_subscribe_relog_1840@localhost/res1' xml:lang='en' type='subscribe'/>
*** User 2022-01-27 15:34:56.673 ***
bOb_subscribe_relog_1840@localhost/newbob 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:34:56.673 ***
bOb_subscribe_relog_1840@localhost/newbob in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='62a2eb2b1b98cef4' from='localhost' version='1.0' xml:lang='en'>
*** User 2022-01-27 15:34:56.673 ***
bOb_subscribe_relog_1840@localhost/newbob 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-01-27 15:34:56.673 ***
bOb_subscribe_relog_1840@localhost/newbob out
<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGJPYl9zdWJzY3JpYmVfcmVsb2dfMTg0MABtYWtyb2xpa2E=</auth>
*** User 2022-01-27 15:34:56.680 ***
bOb_subscribe_relog_1840@localhost/newbob in
<success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
*** User 2022-01-27 15:34:56.680 ***
bOb_subscribe_relog_1840@localhost/newbob 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:34:56.680 ***
bOb_subscribe_relog_1840@localhost/newbob in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='00ea28447a3ef9c5' from='localhost' version='1.0' xml:lang='en'>
*** User 2022-01-27 15:34:56.681 ***
bOb_subscribe_relog_1840@localhost/newbob 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:34:56.681 ***
bOb_subscribe_relog_1840@localhost/newbob out
<iq type='set' id='0e5ed0d83379fa04760d9a701267b0ba'>
<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
<resource>newbob</resource>
</bind>
</iq>
*** User 2022-01-27 15:34:56.681 ***
bOb_subscribe_relog_1840@localhost/newbob in
<iq id='0e5ed0d83379fa04760d9a701267b0ba' type='result'>
<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
<jid>bOb_subscribe_relog_1840@localhost/newbob</jid>
</bind>
</iq>
*** User 2022-01-27 15:34:56.681 ***
bOb_subscribe_relog_1840@localhost/newbob out
<iq type='set' id='afb6a8e47200e1198eb6105d9b182b26'>
<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>
*** User 2022-01-27 15:34:56.681 ***
bOb_subscribe_relog_1840@localhost/newbob in
<iq type='result' id='afb6a8e47200e1198eb6105d9b182b26'>
<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>
*** User 2022-01-27 15:34:56.681 ***
bOb_subscribe_relog_1840@localhost/newbob out
<presence/>
*** User 2022-01-27 15:34:56.683 ***
bOb_subscribe_relog_1840@localhost/res1 in
<presence from='bOb_subscribe_relog_1840@localhost/newbob' to='bob_subscribe_relog_1840@localhost/res1' xml:lang='en'/>
*** User 2022-01-27 15:34:56.683 ***
bOb_subscribe_relog_1840@localhost/newbob in
<presence from='alice_subscribe_relog_1840@localhost' to='bOb_subscribe_relog_1840@localhost' type='subscribe'>
<status/>
</presence>
*** User 2022-01-27 15:34:56.683 ***
bOb_subscribe_relog_1840@localhost/newbob in
<presence from='bOb_subscribe_relog_1840@localhost/newbob' to='bob_subscribe_relog_1840@localhost/newbob' xml:lang='en'/>
*** User 2022-01-27 15:34:56.683 ***
bOb_subscribe_relog_1840@localhost/newbob in
<presence from='bob_subscribe_relog_1840@localhost/res1' to='bOb_subscribe_relog_1840@localhost/newbob' xml:lang='en'>
<delay xmlns='urn:xmpp:delay' stamp='2022-01-27T15:34:56Z' from='bob_subscribe_relog_1840@localhost/res1'/>
</presence>
*** User 2022-01-27 15:34:56.683 ***
bOb_subscribe_relog_1840@localhost/newbob out
</stream:stream>
*** User 2022-01-27 15:34:56.683 ***
bOb_subscribe_relog_1840@localhost/newbob in
</stream:stream>
*** User 2022-01-27 15:34:56.683 ***
bOb_subscribe_relog_1840@localhost/res1 out
<presence type='unsubscribed' to='alicE_subscribe_relog_1840@localhost'/>
*** User 2022-01-27 15:34:56.683 ***
bOb_subscribe_relog_1840@localhost/res1 out
</stream:stream>
*** User 2022-01-27 15:34:56.700 ***
bOb_subscribe_relog_1840@localhost/res1 in
</stream:stream>
*** User 2022-01-27 15:34:56.700 ***
alicE_subscribe_relog_1840@localhost/res1 out
</stream:stream>
*** User 2022-01-27 15:34:56.700 ***
alicE_subscribe_relog_1840@localhost/res1 in
</stream:stream>
=== Ended at 2022-01-27 15:34:56
=== successfully completed test case
=== === Returned value: ok