=== Test case: mam_SUITE:messages_filtered_when_prefs_default_policy_is_roster/1 (click for source code)
=== Config value:
[{basic_group,prefs_cases},
{configuration,rdbms_mnesia_cache},
{mam_meta_opts,#{archive_chat_markers => false,
async_writer =>
#{batch_size => 30,enabled => false,
flush_interval => 2000,pool_size => 16},
backend => rdbms,
cache =>
#{module => internal,number_of_segments => 3,
strategy => fifo,time_to_live => 480},
cache_users => true,default_result_limit => 50,
full_text_search => true,
is_archivable_message => mod_mam_utils,
max_result_limit => 50,message_retraction => true,
no_stanzaid_element => false,
pm =>
#{archive_groupchats => false,
same_mam_id_for_peers => false},
send_message => mod_mam_utils,
user_prefs_store => mnesia}},
{watchdog,<0.16766.1>},
{mod_offline_loaded,false},
{escalus_user_db,{module,escalus_ejabberd}},
{mim_data_dir,"/home/circleci/project/big_tests/tests/mam_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.mam_SUITE.logs/run.2022-05-11_09.00.19/mam_suite.messages_filtered_when_prefs_default_policy_is_roster.124162.html"},
{tc_group_properties,[{name,rdbms_mnesia_cache_prefs_cases},parallel]},
{tc_group_path,[]},
{data_dir,"/home/circleci/project/big_tests/_build/default/lib/mongoose_tests/ebin/mam_SUITE_data/"},
{priv_dir,"/home/circleci/project/big_tests/ct_report/ct_run.test@df370d24fb6d.2022-05-11_08.56.40/big_tests.tests.mam_SUITE.logs/run.2022-05-11_09.00.19/log_private/"},
{config_backup,#{[shaper,fast] => {ok,#{max_rate => 50000}},
[shaper,mam_shaper] => {ok,#{max_rate => 1}},
[shaper,normal] => {ok,#{max_rate => 1000}},
[{access,<<"test type">>},max_user_sessions] =>
{ok,[#{acl => all,value => 10}]}}},
{{saved_modules,mongooseim@localhost,<<"test type">>},
#{mod_adhoc => #{iqdisc => one_queue,report_commands_node => false},
mod_amp => #{},
mod_bosh =>
#{backend => mnesia,inactivity => 30,max_pause => 120,
max_wait => infinity,server_acks => false},
mod_cache_users =>
#{number_of_segments => 5,strategy => fifo,time_to_live => 2},
mod_carboncopy => #{iqdisc => no_queue},
mod_commands => #{},
mod_disco =>
#{extra_domains => [],iqdisc => one_queue,server_info => [],
users_can_see_hidden_services => false},
mod_muc =>
#{access => muc,access_admin => none,access_create => muc_create,
access_persistent => all,backend => rdbms,
default_room =>
#{affiliations => [],allow_change_subj => true,
allow_multiple_sessions => false,
allow_private_messages => true,allow_query_users => true,
allow_user_invites => false,
allow_visitor_nickchange => true,
allow_visitor_status => true,anonymous => true,
description => <<>>,logging => false,max_users => 200,
maygetmemberlist => [],members_by_default => true,
members_only => false,moderated => true,password => <<>>,
password_protected => false,persistent => false,
public => true,public_list => true,subject => <<>>,
subject_author => <<>>,title => <<>>},
hibernate_timeout => 2000,
hibernated_room_check_interval => 1000,
hibernated_room_timeout => 2000,history_size => 20,
host => {prefix,<<"groupchats.">>},
http_auth_pool => none,load_permanent_rooms_at_startup => false,
max_room_desc => infinity,max_room_id => infinity,
max_room_name => infinity,max_user_conferences => 10,
max_users => 200,max_users_admin_threshold => 5,
min_message_interval => 0,min_presence_interval => 0,
room_shaper => none,user_message_shaper => none,
user_presence_shaper => none},
mod_muc_commands => #{},mod_muc_light_commands => #{},
mod_muc_log =>
#{access_log => muc,css_file => false,dirname => room_jid,
dirtype => subdirs,file_format => html,outdir => "/tmp/muclogs",
spam_prevention => true,timezone => local,
top_link => {"/","Home"}},
mod_register =>
#{access => register,
ip_access => [{allow,"127.0.0.0/8"},{deny,"0.0.0.0/0"}],
iqdisc => one_queue,password_strength => 0,
registration_watchers => []},
mod_roster =>
#{backend => rdbms,iqdisc => one_queue,store_current_id => false,
versioning => false},
mod_sic => #{iqdisc => one_queue},
mod_stream_management =>
#{ack => true,ack_freq => 1,backend => mnesia,buffer => true,
buffer_max => 100,resume_timeout => 600,
stale_h =>
#{enabled => false,geriatric => 3600,repeat_after => 1800}},
mod_vcard =>
#{backend => rdbms,
host => {prefix,<<"vjud.">>},
iqdisc => parallel,matches => 30,search => true}}},
{escalus_users,[{alice,[{username,<<"alicE">>},
{server,<<"domain.example.com">>},
{host,<<"localhost">>},
{password,<<"matygrysa">>}]},
{bob,[{username,<<"bOb">>},
{server,<<"domain.example.com">>},
{host,<<"localhost">>},
{password,<<"makrolika">>}]},
{kate,[{username,<<"kate">>},
{server,<<"domain.example.com">>},
{host,<<"localhost">>},
{password,<<"makrowe;p">>}]},
{carol,[{username,<<"carol">>},
{server,<<"domain.example.com">>},
{host,<<"localhost">>},
{password,<<"jinglebells">>},
{transport,escalus_bosh},
{path,<<"/http-bind">>},
{port,5280}]}]}]
=== 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:07
Could not find element props in Config.
*** User 2022-05-11 09:01:07.663 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@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:07.663 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='42296862dbd35d95' from='domain.example.com' version='1.0' xml:lang='en'>
*** User 2022-05-11 09:01:07.663 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@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:07.663 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGFsaWNFX21lc3NhZ2VzX2ZpbHRlcmVkX3doZW5fcHJlZnNfZGVmYXVsdF9wb2xpY3lfaXNfcm9zdGVyXzE2OTMAbWF0eWdyeXNh</auth>
*** User 2022-05-11 09:01:07.664 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
*** User 2022-05-11 09:01:07.664 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@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:07.665 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='683cabc05674b8bf' from='domain.example.com' version='1.0' xml:lang='en'>
*** User 2022-05-11 09:01:07.665 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@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:07.665 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<iq type='set' id='61cb2c9aa1cd091b40d2d10b035283b0'>
<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
<resource>res1</resource>
</bind>
</iq>
*** User 2022-05-11 09:01:07.666 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<iq id='61cb2c9aa1cd091b40d2d10b035283b0' type='result'>
<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
<jid>alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1</jid>
</bind>
</iq>
*** User 2022-05-11 09:01:07.666 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<iq type='set' id='633c545443825c3937777aac18d7a11b'>
<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>
*** User 2022-05-11 09:01:07.667 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<iq type='result' id='633c545443825c3937777aac18d7a11b'>
<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>
*** User 2022-05-11 09:01:07.667 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<presence/>
*** User 2022-05-11 09:01:07.668 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<presence from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='alice_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en'/>
*** User 2022-05-11 09:01:07.669 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@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:07.669 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='a39b95f7b39b2b91' from='domain.example.com' version='1.0' xml:lang='en'>
*** User 2022-05-11 09:01:07.669 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@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:07.669 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGJPYl9tZXNzYWdlc19maWx0ZXJlZF93aGVuX3ByZWZzX2RlZmF1bHRfcG9saWN5X2lzX3Jvc3Rlcl8xNjkzAG1ha3JvbGlrYQ==</auth>
*** User 2022-05-11 09:01:07.670 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
*** User 2022-05-11 09:01:07.670 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@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:07.670 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='1b365f4f9dc5ef81' from='domain.example.com' version='1.0' xml:lang='en'>
*** User 2022-05-11 09:01:07.670 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@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:07.670 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<iq type='set' id='243744cd5bad7f151d7f218fedad14b2'>
<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
<resource>res1</resource>
</bind>
</iq>
*** User 2022-05-11 09:01:07.670 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<iq id='243744cd5bad7f151d7f218fedad14b2' type='result'>
<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
<jid>bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1</jid>
</bind>
</iq>
*** User 2022-05-11 09:01:07.671 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<iq type='set' id='76bc063cb824063b0e3f56743080e550'>
<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>
*** User 2022-05-11 09:01:07.671 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<iq type='result' id='76bc063cb824063b0e3f56743080e550'>
<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>
*** User 2022-05-11 09:01:07.671 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<presence/>
*** User 2022-05-11 09:01:07.672 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<presence from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='bob_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en'/>
*** User 2022-05-11 09:01:07.673 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@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:07.673 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='956fbafcab45dd1d' from='domain.example.com' version='1.0' xml:lang='en'>
*** User 2022-05-11 09:01:07.673 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@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:07.673 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGthdGVfbWVzc2FnZXNfZmlsdGVyZWRfd2hlbl9wcmVmc19kZWZhdWx0X3BvbGljeV9pc19yb3N0ZXJfMTY5MwBtYWtyb3dlO3A=</auth>
*** User 2022-05-11 09:01:07.674 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
*** User 2022-05-11 09:01:07.674 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@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:07.674 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='d47e031b4cb0aa89' from='domain.example.com' version='1.0' xml:lang='en'>
*** User 2022-05-11 09:01:07.674 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@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:07.674 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<iq type='set' id='600bc82824699015e4af247063724de5'>
<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
<resource>res1</resource>
</bind>
</iq>
*** User 2022-05-11 09:01:07.674 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<iq id='600bc82824699015e4af247063724de5' type='result'>
<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
<jid>kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1</jid>
</bind>
</iq>
*** User 2022-05-11 09:01:07.674 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<iq type='set' id='02e0df92ef38c4320a49fffe936bb86d'>
<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>
*** User 2022-05-11 09:01:07.675 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<iq type='result' id='02e0df92ef38c4320a49fffe936bb86d'>
<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>
*** User 2022-05-11 09:01:07.676 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<presence/>
*** User 2022-05-11 09:01:07.677 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<presence from='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en'/>
*** User 2022-05-11 09:01:07.677 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<presence type='subscribe' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com'/>
*** User 2022-05-11 09:01:07.680 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<iq from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='push1110162a9e859fa1' type='set'>
<query xmlns='jabber:iq:roster'>
<item ask='subscribe' subscription='none' jid='bob_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com'/>
</query>
</iq>
*** User 2022-05-11 09:01:07.680 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<presence from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='subscribe'/>
*** User 2022-05-11 09:01:07.681 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<presence type='subscribed' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com'/>
*** User 2022-05-11 09:01:07.685 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<iq from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='pushd260251e2bbe13d3' type='set'>
<query xmlns='jabber:iq:roster'>
<item subscription='to' jid='bob_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com'/>
</query>
</iq>
*** User 2022-05-11 09:01:07.685 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<presence from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='subscribed'/>
*** User 2022-05-11 09:01:07.685 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<presence from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='alice_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en'/>
*** User 2022-05-11 09:01:07.685 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<iq from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='pushf2fa93bb9f7397b6' type='set'>
<query xmlns='jabber:iq:roster'>
<item subscription='from' jid='alice_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com'/>
</query>
</iq>
*** User 2022-05-11 09:01:07.685 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<presence type='subscribe' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com'/>
*** User 2022-05-11 09:01:07.689 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<iq from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='pushd6cf5b288845f1e4' type='set'>
<query xmlns='jabber:iq:roster'>
<item subscription='to' jid='bob_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com'/>
</query>
</iq>
*** User 2022-05-11 09:01:07.689 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<presence from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='subscribe'/>
*** User 2022-05-11 09:01:07.689 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<iq from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='pushf9646d921b3acd8b' type='set'>
<query xmlns='jabber:iq:roster'>
<item ask='subscribe' subscription='from' jid='alice_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com'/>
</query>
</iq>
*** User 2022-05-11 09:01:07.689 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<presence type='subscribed' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com'/>
*** User 2022-05-11 09:01:07.693 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<iq from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='push07007d494cf78b8c' type='set'>
<query xmlns='jabber:iq:roster'>
<item subscription='both' jid='bob_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com'/>
</query>
</iq>
*** User 2022-05-11 09:01:07.693 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<iq from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='pusha7514decc1b83cc4' type='set'>
<query xmlns='jabber:iq:roster'>
<item subscription='both' jid='alice_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com'/>
</query>
</iq>
*** User 2022-05-11 09:01:07.693 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<presence from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='subscribed'/>
*** User 2022-05-11 09:01:07.693 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<presence from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='bob_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en'/>
*** User 2022-05-11 09:01:07.693 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<iq type='set' id='4c8357e48d041281273e7b68359360c9'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always/>
<never/>
</prefs>
</iq>
*** User 2022-05-11 09:01:07.695 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<iq from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='4c8357e48d041281273e7b68359360c9' type='result'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always/>
<never/>
</prefs>
</iq>
*** User 2022-05-11 09:01:07.696 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=1, prefs={roster,[],[]}, now={1652,259667,696010}</body>
</message>
*** User 2022-05-11 09:01:07.696 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=2, prefs={roster,[],[]}, now={1652,259667,696020}</body>
</message>
*** User 2022-05-11 09:01:07.696 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=3, prefs={roster,[],[]}, now={1652,259667,696025}</body>
</message>
*** User 2022-05-11 09:01:07.696 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=4, prefs={roster,[],[]}, now={1652,259667,696029}</body>
</message>
*** User 2022-05-11 09:01:07.702 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=2, prefs={roster,[],[]}, now={1652,259667,696020}</body>
<stanza-id by='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' id='BNLOLQ8TUKG1' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:07.702 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=4, prefs={roster,[],[]}, now={1652,259667,696029}</body>
<stanza-id by='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' id='BNLOLQ8UQD81' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:07.702 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=3, prefs={roster,[],[]}, now={1652,259667,696025}</body>
</message>
*** User 2022-05-11 09:01:07.702 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=1, prefs={roster,[],[]}, now={1652,259667,696010}</body>
<stanza-id by='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' id='BNLOLQ8TVLG1' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:07.702 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<iq type='set' id='91c6f4350d4f09423ed33425a38f5434'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always>
<jid>bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com</jid>
</always>
<never/>
</prefs>
</iq>
*** User 2022-05-11 09:01:07.703 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<iq from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='91c6f4350d4f09423ed33425a38f5434' type='result'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always>
<jid>bob_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com</jid>
</always>
<never/>
</prefs>
</iq>
*** User 2022-05-11 09:01:07.703 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=1, prefs={roster,[bob],[]}, now={1652,259667,703356}</body>
</message>
*** User 2022-05-11 09:01:07.703 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=2, prefs={roster,[bob],[]}, now={1652,259667,703366}</body>
</message>
*** User 2022-05-11 09:01:07.703 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=3, prefs={roster,[bob],[]}, now={1652,259667,703373}</body>
</message>
*** User 2022-05-11 09:01:07.703 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=4, prefs={roster,[bob],[]}, now={1652,259667,703378}</body>
</message>
*** User 2022-05-11 09:01:07.706 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=2, prefs={roster,[bob],[]}, now={1652,259667,703366}</body>
<stanza-id by='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' id='BNLOLQ8VJ6G1' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:07.707 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=4, prefs={roster,[bob],[]}, now={1652,259667,703378}</body>
<stanza-id by='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' id='BNLOLQ905SG1' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:07.707 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=1, prefs={roster,[bob],[]}, now={1652,259667,703356}</body>
<stanza-id by='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' id='BNLOLQ8VJ681' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:07.707 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=3, prefs={roster,[bob],[]}, now={1652,259667,703373}</body>
</message>
*** User 2022-05-11 09:01:07.708 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<iq type='set' id='01ffac9138e3583021960953a29c0dae'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always>
<jid>kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com</jid>
</always>
<never/>
</prefs>
</iq>
*** User 2022-05-11 09:01:07.708 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<iq from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='01ffac9138e3583021960953a29c0dae' type='result'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always>
<jid>kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com</jid>
</always>
<never/>
</prefs>
</iq>
*** User 2022-05-11 09:01:07.708 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=1, prefs={roster,[kate],[]}, now={1652,259667,708850}</body>
</message>
*** User 2022-05-11 09:01:07.708 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=2, prefs={roster,[kate],[]}, now={1652,259667,708858}</body>
</message>
*** User 2022-05-11 09:01:07.709 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=3, prefs={roster,[kate],[]}, now={1652,259667,708862}</body>
</message>
*** User 2022-05-11 09:01:07.709 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=4, prefs={roster,[kate],[]}, now={1652,259667,708867}</body>
</message>
*** User 2022-05-11 09:01:07.713 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=2, prefs={roster,[kate],[]}, now={1652,259667,708858}</body>
<stanza-id by='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' id='BNLOLQ91DM01' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:07.736 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=4, prefs={roster,[kate],[]}, now={1652,259667,708867}</body>
<stanza-id by='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' id='BNLOLQ91SV81' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:07.736 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=3, prefs={roster,[kate],[]}, now={1652,259667,708862}</body>
<stanza-id by='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' id='BNLOLQ911G81' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:07.736 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=1, prefs={roster,[kate],[]}, now={1652,259667,708850}</body>
<stanza-id by='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' id='BNLOLQ91DLO1' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:07.736 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<iq type='set' id='7af83a2fd1f9cd832faba99d60238aee'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always>
<jid>kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com</jid>
<jid>bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com</jid>
</always>
<never/>
</prefs>
</iq>
*** User 2022-05-11 09:01:07.737 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<iq from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='7af83a2fd1f9cd832faba99d60238aee' type='result'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always>
<jid>kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com</jid>
<jid>bob_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com</jid>
</always>
<never/>
</prefs>
</iq>
*** User 2022-05-11 09:01:07.737 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=1, prefs={roster,[kate,bob],[]}, now={1652,259667,737336}</body>
</message>
*** User 2022-05-11 09:01:07.737 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=2, prefs={roster,[kate,bob],[]}, now={1652,259667,737350}</body>
</message>
*** User 2022-05-11 09:01:07.737 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=3, prefs={roster,[kate,bob],[]}, now={1652,259667,737355}</body>
</message>
*** User 2022-05-11 09:01:07.737 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=4, prefs={roster,[kate,bob],[]}, now={1652,259667,737360}</body>
</message>
*** User 2022-05-11 09:01:07.778 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=2, prefs={roster,[kate,bob],[]}, now={1652,259667,737350}</body>
<stanza-id by='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' id='BNLOLQ9HANG1' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:07.779 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=4, prefs={roster,[kate,bob],[]}, now={1652,259667,737360}</body>
<stanza-id by='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' id='BNLOLQ9HJ681' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:07.779 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=1, prefs={roster,[kate,bob],[]}, now={1652,259667,737336}</body>
<stanza-id by='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' id='BNLOLQ97OSO1' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:07.779 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=3, prefs={roster,[kate,bob],[]}, now={1652,259667,737355}</body>
<stanza-id by='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' id='BNLOLQ9HANO1' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:07.779 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<iq type='set' id='ae39929ffe46cdbe0606990cf9ef12c1'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always/>
<never>
<jid>bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com</jid>
</never>
</prefs>
</iq>
*** User 2022-05-11 09:01:07.780 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<iq from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='ae39929ffe46cdbe0606990cf9ef12c1' type='result'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always/>
<never>
<jid>bob_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com</jid>
</never>
</prefs>
</iq>
*** User 2022-05-11 09:01:07.780 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=1, prefs={roster,[],[bob]}, now={1652,259667,780713}</body>
</message>
*** User 2022-05-11 09:01:07.780 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=2, prefs={roster,[],[bob]}, now={1652,259667,780723}</body>
</message>
*** User 2022-05-11 09:01:07.780 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=3, prefs={roster,[],[bob]}, now={1652,259667,780727}</body>
</message>
*** User 2022-05-11 09:01:07.781 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=4, prefs={roster,[],[bob]}, now={1652,259667,780732}</body>
</message>
*** User 2022-05-11 09:01:07.782 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=2, prefs={roster,[],[bob]}, now={1652,259667,780723}</body>
<stanza-id by='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' id='BNLOLQ9IBHG1' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:07.783 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=4, prefs={roster,[],[bob]}, now={1652,259667,780732}</body>
<stanza-id by='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' id='BNLOLQ9IM4G1' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:07.784 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=1, prefs={roster,[],[bob]}, now={1652,259667,780713}</body>
</message>
*** User 2022-05-11 09:01:07.784 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=3, prefs={roster,[],[bob]}, now={1652,259667,780727}</body>
</message>
*** User 2022-05-11 09:01:07.784 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<iq type='set' id='0d8ab17ebb32a9cbef89978d99b19ba9'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always/>
<never>
<jid>kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com</jid>
</never>
</prefs>
</iq>
*** User 2022-05-11 09:01:07.785 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<iq from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='0d8ab17ebb32a9cbef89978d99b19ba9' type='result'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always/>
<never>
<jid>kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com</jid>
</never>
</prefs>
</iq>
*** User 2022-05-11 09:01:07.785 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=1, prefs={roster,[],[kate]}, now={1652,259667,785215}</body>
</message>
*** User 2022-05-11 09:01:07.785 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=2, prefs={roster,[],[kate]}, now={1652,259667,785224}</body>
</message>
*** User 2022-05-11 09:01:07.785 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=3, prefs={roster,[],[kate]}, now={1652,259667,785230}</body>
</message>
*** User 2022-05-11 09:01:07.785 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=4, prefs={roster,[],[kate]}, now={1652,259667,785235}</body>
</message>
*** User 2022-05-11 09:01:07.788 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=2, prefs={roster,[],[kate]}, now={1652,259667,785224}</body>
<stanza-id by='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' id='BNLOLQ9JKAG1' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:07.789 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=4, prefs={roster,[],[kate]}, now={1652,259667,785235}</body>
<stanza-id by='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' id='BNLOLQ9K1UG1' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:07.789 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=3, prefs={roster,[],[kate]}, now={1652,259667,785230}</body>
</message>
*** User 2022-05-11 09:01:07.789 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=1, prefs={roster,[],[kate]}, now={1652,259667,785215}</body>
<stanza-id by='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' id='BNLOLQ9JKAO1' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:07.789 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<iq type='set' id='bee4ac382b4beca06287dcb9c3ce109b'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always/>
<never>
<jid>bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com</jid>
<jid>kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com</jid>
</never>
</prefs>
</iq>
*** User 2022-05-11 09:01:07.790 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<iq from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='bee4ac382b4beca06287dcb9c3ce109b' type='result'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always/>
<never>
<jid>bob_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com</jid>
<jid>kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com</jid>
</never>
</prefs>
</iq>
*** User 2022-05-11 09:01:07.790 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=1, prefs={roster,[],[bob,kate]}, now={1652,259667,790583}</body>
</message>
*** User 2022-05-11 09:01:07.790 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=2, prefs={roster,[],[bob,kate]}, now={1652,259667,790598}</body>
</message>
*** User 2022-05-11 09:01:07.790 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=3, prefs={roster,[],[bob,kate]}, now={1652,259667,790606}</body>
</message>
*** User 2022-05-11 09:01:07.790 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<message to='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=4, prefs={roster,[],[bob,kate]}, now={1652,259667,790614}</body>
</message>
*** User 2022-05-11 09:01:07.792 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=2, prefs={roster,[],[bob,kate]}, now={1652,259667,790598}</body>
<stanza-id by='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' id='BNLOLQ9KO701' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:07.793 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=4, prefs={roster,[],[bob,kate]}, now={1652,259667,790614}</body>
<stanza-id by='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' id='BNLOLQ9L2JG1' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:07.793 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=1, prefs={roster,[],[bob,kate]}, now={1652,259667,790583}</body>
</message>
Could not find element wait_for_parallel_writer in Config.
*** User 2022-05-11 09:01:07.793 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=3, prefs={roster,[],[bob,kate]}, now={1652,259667,790606}</body>
</message>
Could not find element archive_wait in Config.
*** User 2022-05-11 09:01:07.793 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<iq type='set' id='0e1897c52674aac3212ef9147eed530e'>
<query xmlns='urn:xmpp:mam:1' queryid='page_rsm'>
<x xmlns='jabber:x:data'/>
<set>
<max>50</max>
<after/>
</set>
</query>
</iq>
*** User 2022-05-11 09:01:07.795 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='457dd1cc-b359-410d-ba14-256ff43e6bc4'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLQ8TUJG1'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:07.697486Z' from='alice_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1'/>
<message from='alice_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=2, prefs={roster,[],[]}, now={1652,259667,696020}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:07.795 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='d4074570-68a2-477b-a9a2-cef26a8ae73a'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLQ8TVLG1'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:07.697622Z' from='bob_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1'/>
<message from='bob_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=1, prefs={roster,[],[]}, now={1652,259667,696010}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:07.795 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='9959539d-4fa7-4084-944e-d1630195044d'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLQ8VGOG1'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:07.703906Z' from='alice_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1'/>
<message from='alice_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=2, prefs={roster,[bob],[]}, now={1652,259667,703366}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:07.796 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='11d17c40-9205-4af3-939e-a6417626ca40'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLQ8VJ681'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:07.704217Z' from='bob_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1'/>
<message from='bob_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=1, prefs={roster,[bob],[]}, now={1652,259667,703356}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:07.796 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='d0791f4d-1edd-43e1-9e25-42ba4cd312bf'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLQ911G81'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:07.710145Z' from='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1'/>
<message from='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=3, prefs={roster,[kate],[]}, now={1652,259667,708862}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:07.796 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='fe7c79fe-3b9b-476c-9ec5-81958ea56502'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLQ911H81'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:07.710149Z' from='alice_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1'/>
<message from='alice_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=2, prefs={roster,[kate],[]}, now={1652,259667,708858}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:07.796 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='c8a0c72c-2e35-4c8d-a37e-35a161b6199c'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLQ91DLO1'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:07.711703Z' from='bob_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1'/>
<message from='bob_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=1, prefs={roster,[kate],[]}, now={1652,259667,708850}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:07.796 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='62b57f01-c69f-4419-8ba5-2bada5587b5d'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLQ91OTO1'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:07.713143Z' from='alice_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1'/>
<message from='alice_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=4, prefs={roster,[kate],[]}, now={1652,259667,708867}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:07.796 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='9c98fc2e-cf0b-41bc-91a4-601f86e03476'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLQ97NM01'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:07.737560Z' from='alice_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1'/>
<message from='alice_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=2, prefs={roster,[kate,bob],[]}, now={1652,259667,737350}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:07.796 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='1afe88d5-6269-4949-98b3-ec80417e88c4'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLQ97OSO1'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:07.737715Z' from='bob_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1'/>
<message from='bob_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=1, prefs={roster,[kate,bob],[]}, now={1652,259667,737336}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:07.796 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='16a2b1fc-d2db-4beb-b8f3-1b40da75759e'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLQ9HANO1'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:07.776863Z' from='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1'/>
<message from='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=3, prefs={roster,[kate,bob],[]}, now={1652,259667,737355}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:07.796 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='2b28ba11-3e12-486c-97d4-6a31694e7f9a'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLQ9HJ601'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:07.777944Z' from='alice_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1'/>
<message from='alice_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=4, prefs={roster,[kate,bob],[]}, now={1652,259667,737360}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:07.796 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='da2d08e5-1b79-4206-89ff-409a955e6c4c'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLQ9JKA81'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:07.786281Z' from='alice_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1'/>
<message from='alice_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=2, prefs={roster,[],[kate]}, now={1652,259667,785224}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:07.797 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='77cebb0d-5aff-4727-b019-b8ce452951a4'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLQ9JKAO1'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:07.786283Z' from='bob_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1'/>
<message from='bob_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' type='chat'>
<body>n=1, prefs={roster,[],[kate]}, now={1652,259667,785215}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:07.797 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<iq from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='0e1897c52674aac3212ef9147eed530e' type='result'>
<fin xmlns='urn:xmpp:mam:1' complete='true'>
<set xmlns='http://jabber.org/protocol/rsm'>
<first index='0'>BNLOLQ8TUJG1</first>
<last>BNLOLQ9JKAO1</last>
<count>14</count>
</set>
</fin>
</iq>
*** User 2022-05-11 09:01:07.797 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
<iq type='set' id='71fca1c51b6dcfc286d743429b073bfc'>
<query xmlns='urn:xmpp:mam:1' queryid='page_rsm'>
<x xmlns='jabber:x:data'/>
<set>
<max>50</max>
<after>BNLOLQ9JKAO1</after>
</set>
</query>
</iq>
*** User 2022-05-11 09:01:07.798 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
<iq from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1' id='71fca1c51b6dcfc286d743429b073bfc' type='result'>
<fin xmlns='urn:xmpp:mam:1' complete='true'>
<set xmlns='http://jabber.org/protocol/rsm'>
<count>14</count>
</set>
</fin>
</iq>
*** User 2022-05-11 09:01:07.799 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
</stream:stream>
*** User 2022-05-11 09:01:07.799 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
</stream:stream>
*** User 2022-05-11 09:01:07.799 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
</stream:stream>
*** User 2022-05-11 09:01:07.799 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
</stream:stream>
*** User 2022-05-11 09:01:07.799 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 out
</stream:stream>
*** User 2022-05-11 09:01:07.800 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1693@domain.example.com/res1 in
</stream:stream>
=== Ended at 2022-05-11 09:01:07
=== successfully completed test case
=== === Returned value: ok