=== Test case: metrics_roster_SUITE:unsubscribe/1 (click for source code) === Config value: [{watchdog,<0.17987.1>}, {mongoose_metrics,[{[global,data,xmpp,received,xml_stanza_size],changed}, {[global,data,xmpp,sent,xml_stanza_size],changed}, {#Fun<metrics_roster_SUITE.0.124753187>, [global,data,rdbms,default], [{recv_oct,'>'},{send_oct,'>'}]}, {[global,backends,mod_roster,get_subscription_lists], changed}]}, {mim_data_dir,"/home/circleci/project/big_tests/tests/metrics_roster_SUITE_data"}, {preset,"pgsql_mnesia"}, {tc_logfile,"/home/circleci/project/big_tests/ct_report/ct_run.test@df370d24fb6d.2022-05-11_08.56.40/big_tests.tests.metrics_roster_SUITE.logs/run.2022-05-11_09.01.21/metrics_roster_suite.unsubscribe.html"}, {tc_group_properties,[{name,subscriptions},sequence]}, {tc_group_path,[]}, {data_dir,"/home/circleci/project/big_tests/_build/default/lib/mongoose_tests/ebin/metrics_roster_SUITE_data/"}, {priv_dir,"/home/circleci/project/big_tests/ct_report/ct_run.test@df370d24fb6d.2022-05-11_08.56.40/big_tests.tests.metrics_roster_SUITE.logs/run.2022-05-11_09.01.21/log_private/"}, {escalus_users,[{alice,[{username,<<"alicE">>}, {server,<<"domain.example.com">>}, {host,<<"localhost">>}, {password,<<"matygrysa">>}]}, {bob,[{username,<<"bOb">>}, {server,<<"domain.example.com">>}, {host,<<"localhost">>}, {password,<<"makrolika">>}]}]}] === Current directory is "/home/circleci/project/big_tests/ct_report/ct_run.test@df370d24fb6d.2022-05-11_08.56.40" === Started at 2022-05-11 09:01:21
*** User 2022-05-11 09:01:21.511 *** alicE_unsubscribe_1706@domain.example.com/res1 out <stream:stream to='domain.example.com' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>
*** User 2022-05-11 09:01:21.512 *** alicE_unsubscribe_1706@domain.example.com/res1 in <stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='5128a2661fc691d2' from='domain.example.com' version='1.0' xml:lang='en'>
*** User 2022-05-11 09:01:21.512 *** alicE_unsubscribe_1706@domain.example.com/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-05-11 09:01:21.512 *** alicE_unsubscribe_1706@domain.example.com/res1 out <auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGFsaWNFX3Vuc3Vic2NyaWJlXzE3MDYAbWF0eWdyeXNh</auth>
*** User 2022-05-11 09:01:21.513 *** alicE_unsubscribe_1706@domain.example.com/res1 in <success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
*** User 2022-05-11 09:01:21.513 *** alicE_unsubscribe_1706@domain.example.com/res1 out <stream:stream to='domain.example.com' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>
*** User 2022-05-11 09:01:21.513 *** alicE_unsubscribe_1706@domain.example.com/res1 in <stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='3f12398ac129a439' from='domain.example.com' version='1.0' xml:lang='en'>
*** User 2022-05-11 09:01:21.513 *** alicE_unsubscribe_1706@domain.example.com/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-05-11 09:01:21.513 *** alicE_unsubscribe_1706@domain.example.com/res1 out <iq type='set' id='cf46ee58fe81887c74dc6e8e02e2246f'> <bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'> <resource>res1</resource> </bind> </iq>
*** User 2022-05-11 09:01:21.513 *** alicE_unsubscribe_1706@domain.example.com/res1 in <iq id='cf46ee58fe81887c74dc6e8e02e2246f' type='result'> <bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'> <jid>alicE_unsubscribe_1706@domain.example.com/res1</jid> </bind> </iq>
*** User 2022-05-11 09:01:21.513 *** alicE_unsubscribe_1706@domain.example.com/res1 out <iq type='set' id='b184dfc7b243dbb2e695b17a7bac8df1'> <session xmlns='urn:ietf:params:xml:ns:xmpp-session'/> </iq>
*** User 2022-05-11 09:01:21.513 *** alicE_unsubscribe_1706@domain.example.com/res1 in <iq type='result' id='b184dfc7b243dbb2e695b17a7bac8df1'> <session xmlns='urn:ietf:params:xml:ns:xmpp-session'/> </iq>
*** User 2022-05-11 09:01:21.513 *** alicE_unsubscribe_1706@domain.example.com/res1 out <presence/>
*** User 2022-05-11 09:01:21.514 *** alicE_unsubscribe_1706@domain.example.com/res1 in <presence from='alicE_unsubscribe_1706@domain.example.com/res1' to='alice_unsubscribe_1706@domain.example.com/res1' xml:lang='en'/>
*** User 2022-05-11 09:01:21.515 *** bOb_unsubscribe_1706@domain.example.com/res1 out <stream:stream to='domain.example.com' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>
*** User 2022-05-11 09:01:21.515 *** bOb_unsubscribe_1706@domain.example.com/res1 in <stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='10a9996795c220a1' from='domain.example.com' version='1.0' xml:lang='en'>
*** User 2022-05-11 09:01:21.515 *** bOb_unsubscribe_1706@domain.example.com/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-05-11 09:01:21.515 *** bOb_unsubscribe_1706@domain.example.com/res1 out <auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGJPYl91bnN1YnNjcmliZV8xNzA2AG1ha3JvbGlrYQ==</auth>
*** User 2022-05-11 09:01:21.516 *** bOb_unsubscribe_1706@domain.example.com/res1 in <success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
*** User 2022-05-11 09:01:21.516 *** bOb_unsubscribe_1706@domain.example.com/res1 out <stream:stream to='domain.example.com' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>
*** User 2022-05-11 09:01:21.516 *** bOb_unsubscribe_1706@domain.example.com/res1 in <stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='78b7c9bd666c830d' from='domain.example.com' version='1.0' xml:lang='en'>
*** User 2022-05-11 09:01:21.516 *** bOb_unsubscribe_1706@domain.example.com/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-05-11 09:01:21.516 *** bOb_unsubscribe_1706@domain.example.com/res1 out <iq type='set' id='f8d238d8520d58b53ac8d88747eada0d'> <bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'> <resource>res1</resource> </bind> </iq>
*** User 2022-05-11 09:01:21.516 *** bOb_unsubscribe_1706@domain.example.com/res1 in <iq id='f8d238d8520d58b53ac8d88747eada0d' type='result'> <bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'> <jid>bOb_unsubscribe_1706@domain.example.com/res1</jid> </bind> </iq>
*** User 2022-05-11 09:01:21.516 *** bOb_unsubscribe_1706@domain.example.com/res1 out <iq type='set' id='3cbbe809480d4f5d212725d417977d99'> <session xmlns='urn:ietf:params:xml:ns:xmpp-session'/> </iq>
*** User 2022-05-11 09:01:21.517 *** bOb_unsubscribe_1706@domain.example.com/res1 in <iq type='result' id='3cbbe809480d4f5d212725d417977d99'> <session xmlns='urn:ietf:params:xml:ns:xmpp-session'/> </iq>
*** User 2022-05-11 09:01:21.517 *** bOb_unsubscribe_1706@domain.example.com/res1 out <presence/>
*** User 2022-05-11 09:01:21.517 *** bOb_unsubscribe_1706@domain.example.com/res1 in <presence from='bOb_unsubscribe_1706@domain.example.com/res1' to='bob_unsubscribe_1706@domain.example.com/res1' xml:lang='en'/>
*** User 2022-05-11 09:01:21.518 *** alicE_unsubscribe_1706@domain.example.com/res1 out <iq type='set' id='174593c94253d0a64cefef031f8b81bb'> <query xmlns='jabber:iq:roster'> <item jid='bOb_unsubscribe_1706@domain.example.com' name='generic :p name'> <group>friends</group> </item> </query> </iq>
*** User 2022-05-11 09:01:21.519 *** alicE_unsubscribe_1706@domain.example.com/res1 in <iq from='alicE_unsubscribe_1706@domain.example.com' to='alicE_unsubscribe_1706@domain.example.com/res1' id='push58f5a0318b063480' type='set'> <query xmlns='jabber:iq:roster'> <item subscription='none' name='generic :p name' jid='bob_unsubscribe_1706@domain.example.com'> <group>friends</group> </item> </query> </iq>
*** User 2022-05-11 09:01:21.519 *** alicE_unsubscribe_1706@domain.example.com/res1 out <iq to='alicE_unsubscribe_1706@domain.example.com' id='push58f5a0318b063480' type='result'/>
*** User 2022-05-11 09:01:21.519 *** alicE_unsubscribe_1706@domain.example.com/res1 in <iq from='alicE_unsubscribe_1706@domain.example.com' to='alicE_unsubscribe_1706@domain.example.com/res1' id='174593c94253d0a64cefef031f8b81bb' type='result'/>
*** User 2022-05-11 09:01:21.519 *** alicE_unsubscribe_1706@domain.example.com/res1 out <presence type='subscribe' to='bOb_unsubscribe_1706@domain.example.com'/>
*** User 2022-05-11 09:01:21.521 *** alicE_unsubscribe_1706@domain.example.com/res1 in <iq from='alicE_unsubscribe_1706@domain.example.com' to='alicE_unsubscribe_1706@domain.example.com/res1' id='push6dd216ba36fdc05e' type='set'> <query xmlns='jabber:iq:roster'> <item ask='subscribe' subscription='none' name='generic :p name' jid='bob_unsubscribe_1706@domain.example.com'> <group>friends</group> </item> </query> </iq>
*** User 2022-05-11 09:01:21.521 *** alicE_unsubscribe_1706@domain.example.com/res1 out <iq to='alicE_unsubscribe_1706@domain.example.com' id='push6dd216ba36fdc05e' type='result'/>
*** User 2022-05-11 09:01:21.521 *** bOb_unsubscribe_1706@domain.example.com/res1 in <presence from='alicE_unsubscribe_1706@domain.example.com' to='bOb_unsubscribe_1706@domain.example.com/res1' xml:lang='en' type='subscribe'/>
*** User 2022-05-11 09:01:21.522 *** bOb_unsubscribe_1706@domain.example.com/res1 out <iq type='set' id='44622e318149340de43729923dd4c999'> <query xmlns='jabber:iq:roster'> <item jid='alicE_unsubscribe_1706@domain.example.com' name='Alice'> <group>enemies</group> </item> </query> </iq>
*** User 2022-05-11 09:01:21.523 *** bOb_unsubscribe_1706@domain.example.com/res1 in <iq from='bOb_unsubscribe_1706@domain.example.com' to='bOb_unsubscribe_1706@domain.example.com/res1' id='pushdbaa86a26edebbbc' type='set'> <query xmlns='jabber:iq:roster'> <item subscription='none' name='Alice' jid='alice_unsubscribe_1706@domain.example.com'> <group>enemies</group> </item> </query> </iq>
*** User 2022-05-11 09:01:21.523 *** bOb_unsubscribe_1706@domain.example.com/res1 out <iq to='bOb_unsubscribe_1706@domain.example.com' id='pushdbaa86a26edebbbc' type='result'/>
*** User 2022-05-11 09:01:21.523 *** bOb_unsubscribe_1706@domain.example.com/res1 in <iq from='bOb_unsubscribe_1706@domain.example.com' to='bOb_unsubscribe_1706@domain.example.com/res1' id='44622e318149340de43729923dd4c999' type='result'/>
*** User 2022-05-11 09:01:21.523 *** bOb_unsubscribe_1706@domain.example.com/res1 out <presence type='subscribed' to='alicE_unsubscribe_1706@domain.example.com'/>
*** User 2022-05-11 09:01:21.525 *** alicE_unsubscribe_1706@domain.example.com/res1 in <iq from='alicE_unsubscribe_1706@domain.example.com' to='alicE_unsubscribe_1706@domain.example.com/res1' id='push0672a3b04adabd8e' type='set'> <query xmlns='jabber:iq:roster'> <item subscription='to' name='generic :p name' jid='bob_unsubscribe_1706@domain.example.com'> <group>friends</group> </item> </query> </iq>
*** User 2022-05-11 09:01:21.525 *** alicE_unsubscribe_1706@domain.example.com/res1 in <presence from='bOb_unsubscribe_1706@domain.example.com' to='alicE_unsubscribe_1706@domain.example.com/res1' xml:lang='en' type='subscribed'/>
*** User 2022-05-11 09:01:21.525 *** alicE_unsubscribe_1706@domain.example.com/res1 in <presence from='bOb_unsubscribe_1706@domain.example.com/res1' to='alice_unsubscribe_1706@domain.example.com/res1' xml:lang='en'/>
*** User 2022-05-11 09:01:21.525 *** bOb_unsubscribe_1706@domain.example.com/res1 in <iq from='bOb_unsubscribe_1706@domain.example.com' to='bOb_unsubscribe_1706@domain.example.com/res1' id='pushf97080a788298909' type='set'> <query xmlns='jabber:iq:roster'> <item subscription='from' name='Alice' jid='alice_unsubscribe_1706@domain.example.com'> <group>enemies</group> </item> </query> </iq>
*** User 2022-05-11 09:01:21.526 *** alicE_unsubscribe_1706@domain.example.com/res1 out <presence type='unsubscribe' to='bOb_unsubscribe_1706@domain.example.com'/>
*** User 2022-05-11 09:01:21.528 *** alicE_unsubscribe_1706@domain.example.com/res1 in <iq from='alicE_unsubscribe_1706@domain.example.com' to='alicE_unsubscribe_1706@domain.example.com/res1' id='push601513d3bb35c0be' type='set'> <query xmlns='jabber:iq:roster'> <item subscription='none' name='generic :p name' jid='bob_unsubscribe_1706@domain.example.com'> <group>friends</group> </item> </query> </iq>
*** User 2022-05-11 09:01:21.528 *** alicE_unsubscribe_1706@domain.example.com/res1 out <iq to='alicE_unsubscribe_1706@domain.example.com' id='push601513d3bb35c0be' type='result'/>
*** User 2022-05-11 09:01:21.528 *** bOb_unsubscribe_1706@domain.example.com/res1 in <iq from='bOb_unsubscribe_1706@domain.example.com' to='bOb_unsubscribe_1706@domain.example.com/res1' id='push0e23fd4b6c3a737d' type='set'> <query xmlns='jabber:iq:roster'> <item subscription='none' name='Alice' jid='alice_unsubscribe_1706@domain.example.com'> <group>enemies</group> </item> </query> </iq>
*** User 2022-05-11 09:01:21.529 *** bOb_unsubscribe_1706@domain.example.com/res1 in <presence from='alicE_unsubscribe_1706@domain.example.com' to='bOb_unsubscribe_1706@domain.example.com/res1' xml:lang='en' type='unsubscribe'/>
*** User 2022-05-11 09:01:21.529 *** bOb_unsubscribe_1706@domain.example.com/res1 out </stream:stream>
*** User 2022-05-11 09:01:21.530 *** bOb_unsubscribe_1706@domain.example.com/res1 in </stream:stream>
*** User 2022-05-11 09:01:21.530 *** alicE_unsubscribe_1706@domain.example.com/res1 out </stream:stream>
*** User 2022-05-11 09:01:21.530 *** alicE_unsubscribe_1706@domain.example.com/res1 in </stream:stream>
=== Ended at 2022-05-11 09:01:21 === successfully completed test case === === Returned value: ok
Test run history | Top level test index | Latest test result