=== 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_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 => rdbms}},
{watchdog,<0.13713.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.110850.html"},
{tc_group_properties,[{name,rdbms_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:00
Could not find element props in Config.
*** User 2022-05-11 09:01:00.803 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@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:00.803 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='9f1e83818ae65e89' from='domain.example.com' version='1.0' xml:lang='en'>
*** User 2022-05-11 09:01:00.803 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@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:00.803 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGFsaWNFX21lc3NhZ2VzX2ZpbHRlcmVkX3doZW5fcHJlZnNfZGVmYXVsdF9wb2xpY3lfaXNfcm9zdGVyXzE2MDkAbWF0eWdyeXNh</auth>
*** User 2022-05-11 09:01:00.804 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
*** User 2022-05-11 09:01:00.804 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@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:00.804 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='f516f2cb5f033c99' from='domain.example.com' version='1.0' xml:lang='en'>
*** User 2022-05-11 09:01:00.804 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@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:00.804 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<iq type='set' id='2d8b686883386834b26975359a5298dd'>
<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
<resource>res1</resource>
</bind>
</iq>
*** User 2022-05-11 09:01:00.805 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<iq id='2d8b686883386834b26975359a5298dd' type='result'>
<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
<jid>alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1</jid>
</bind>
</iq>
*** User 2022-05-11 09:01:00.805 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<iq type='set' id='7c35a622df51537be520d7bdc160f7d1'>
<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>
*** User 2022-05-11 09:01:00.805 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<iq type='result' id='7c35a622df51537be520d7bdc160f7d1'>
<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>
*** User 2022-05-11 09:01:00.806 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<presence/>
*** User 2022-05-11 09:01:00.807 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<presence from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='alice_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en'/>
*** User 2022-05-11 09:01:00.809 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@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:00.809 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='f853533eee5fe467' from='domain.example.com' version='1.0' xml:lang='en'>
*** User 2022-05-11 09:01:00.809 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@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:00.810 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGJPYl9tZXNzYWdlc19maWx0ZXJlZF93aGVuX3ByZWZzX2RlZmF1bHRfcG9saWN5X2lzX3Jvc3Rlcl8xNjA5AG1ha3JvbGlrYQ==</auth>
*** User 2022-05-11 09:01:00.810 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
*** User 2022-05-11 09:01:00.810 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@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:00.811 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='a28460cfb60a90ad' from='domain.example.com' version='1.0' xml:lang='en'>
*** User 2022-05-11 09:01:00.811 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@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:00.811 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<iq type='set' id='79151bcefac5ea07921cd8bede6d0b3b'>
<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
<resource>res1</resource>
</bind>
</iq>
*** User 2022-05-11 09:01:00.811 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<iq id='79151bcefac5ea07921cd8bede6d0b3b' type='result'>
<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
<jid>bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1</jid>
</bind>
</iq>
*** User 2022-05-11 09:01:00.812 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<iq type='set' id='af691d1609c9a8eb849a67e85176b09b'>
<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>
*** User 2022-05-11 09:01:00.812 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<iq type='result' id='af691d1609c9a8eb849a67e85176b09b'>
<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>
*** User 2022-05-11 09:01:00.812 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<presence/>
*** User 2022-05-11 09:01:00.814 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<presence from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='bob_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en'/>
*** User 2022-05-11 09:01:00.814 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@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:00.816 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='736fb468e84fcf11' from='domain.example.com' version='1.0' xml:lang='en'>
*** User 2022-05-11 09:01:00.816 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@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:00.816 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGthdGVfbWVzc2FnZXNfZmlsdGVyZWRfd2hlbl9wcmVmc19kZWZhdWx0X3BvbGljeV9pc19yb3N0ZXJfMTYwOQBtYWtyb3dlO3A=</auth>
*** User 2022-05-11 09:01:00.817 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
*** User 2022-05-11 09:01:00.818 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@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:00.818 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='3f53721818047855' from='domain.example.com' version='1.0' xml:lang='en'>
*** User 2022-05-11 09:01:00.818 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@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:00.818 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<iq type='set' id='93203419ae78067a739ae205c3a5121c'>
<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
<resource>res1</resource>
</bind>
</iq>
*** User 2022-05-11 09:01:00.818 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<iq id='93203419ae78067a739ae205c3a5121c' type='result'>
<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
<jid>kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1</jid>
</bind>
</iq>
*** User 2022-05-11 09:01:00.819 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<iq type='set' id='7520c65c2e549734f4b48ea441b54968'>
<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>
*** User 2022-05-11 09:01:00.819 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<iq type='result' id='7520c65c2e549734f4b48ea441b54968'>
<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>
*** User 2022-05-11 09:01:00.819 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<presence/>
*** User 2022-05-11 09:01:00.820 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<presence from='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en'/>
*** User 2022-05-11 09:01:00.821 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<presence type='subscribe' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com'/>
*** User 2022-05-11 09:01:00.825 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<iq from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='push9760150df8fedaf1' type='set'>
<query xmlns='jabber:iq:roster'>
<item ask='subscribe' subscription='none' jid='bob_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com'/>
</query>
</iq>
*** User 2022-05-11 09:01:00.825 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<presence from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='subscribe'/>
*** User 2022-05-11 09:01:00.825 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<presence type='subscribed' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com'/>
*** User 2022-05-11 09:01:00.828 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<iq from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='pushf18a6f565c31995f' type='set'>
<query xmlns='jabber:iq:roster'>
<item subscription='to' jid='bob_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com'/>
</query>
</iq>
*** User 2022-05-11 09:01:00.828 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<presence from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='subscribed'/>
*** User 2022-05-11 09:01:00.828 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<presence from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='alice_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en'/>
*** User 2022-05-11 09:01:00.828 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<iq from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='push1d3ee8df57c2f313' type='set'>
<query xmlns='jabber:iq:roster'>
<item subscription='from' jid='alice_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com'/>
</query>
</iq>
*** User 2022-05-11 09:01:00.828 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<presence type='subscribe' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com'/>
*** User 2022-05-11 09:01:00.833 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<iq from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='pushf7674e325facc8c5' type='set'>
<query xmlns='jabber:iq:roster'>
<item subscription='to' jid='bob_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com'/>
</query>
</iq>
*** User 2022-05-11 09:01:00.833 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<presence from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='subscribe'/>
*** User 2022-05-11 09:01:00.833 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<iq from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='pushe84fc548c0d3699b' type='set'>
<query xmlns='jabber:iq:roster'>
<item ask='subscribe' subscription='from' jid='alice_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com'/>
</query>
</iq>
*** User 2022-05-11 09:01:00.833 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<presence type='subscribed' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com'/>
*** User 2022-05-11 09:01:00.837 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<iq from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='push07f75182901b6b07' type='set'>
<query xmlns='jabber:iq:roster'>
<item subscription='both' jid='bob_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com'/>
</query>
</iq>
*** User 2022-05-11 09:01:00.837 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<iq from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='push07866ff9a6ffb327' type='set'>
<query xmlns='jabber:iq:roster'>
<item subscription='both' jid='alice_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com'/>
</query>
</iq>
*** User 2022-05-11 09:01:00.837 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<presence from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='subscribed'/>
*** User 2022-05-11 09:01:00.837 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<presence from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='bob_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en'/>
*** User 2022-05-11 09:01:00.837 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<iq type='set' id='7d13e39e2394c64d1fb2c7bdba4bd840'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always/>
<never/>
</prefs>
</iq>
*** User 2022-05-11 09:01:00.840 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<iq from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='7d13e39e2394c64d1fb2c7bdba4bd840' type='result'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always/>
<never/>
</prefs>
</iq>
*** User 2022-05-11 09:01:00.841 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=1, prefs={roster,[],[]}, now={1652,259660,840916}</body>
</message>
*** User 2022-05-11 09:01:00.841 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=2, prefs={roster,[],[]}, now={1652,259660,840929}</body>
</message>
*** User 2022-05-11 09:01:00.841 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=3, prefs={roster,[],[]}, now={1652,259660,840936}</body>
</message>
*** User 2022-05-11 09:01:00.841 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=4, prefs={roster,[],[]}, now={1652,259660,840942}</body>
</message>
*** User 2022-05-11 09:01:00.899 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=2, prefs={roster,[],[]}, now={1652,259660,840929}</body>
<stanza-id by='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' id='BNLOLOKKAA81' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:00.899 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=4, prefs={roster,[],[]}, now={1652,259660,840942}</body>
<stanza-id by='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' id='BNLOLOKVLH81' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:00.899 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=3, prefs={roster,[],[]}, now={1652,259660,840936}</body>
</message>
*** User 2022-05-11 09:01:00.899 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=1, prefs={roster,[],[]}, now={1652,259660,840916}</body>
<stanza-id by='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' id='BNLOLOKUSM01' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:00.900 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<iq type='set' id='265869edbce09fba936326003ca66872'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always>
<jid>bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com</jid>
</always>
<never/>
</prefs>
</iq>
*** User 2022-05-11 09:01:00.903 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<iq from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='265869edbce09fba936326003ca66872' type='result'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always>
<jid>bob_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com</jid>
</always>
<never/>
</prefs>
</iq>
*** User 2022-05-11 09:01:00.903 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=1, prefs={roster,[bob],[]}, now={1652,259660,903698}</body>
</message>
*** User 2022-05-11 09:01:00.903 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=2, prefs={roster,[bob],[]}, now={1652,259660,903708}</body>
</message>
*** User 2022-05-11 09:01:00.903 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=3, prefs={roster,[bob],[]}, now={1652,259660,903713}</body>
</message>
*** User 2022-05-11 09:01:00.903 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=4, prefs={roster,[bob],[]}, now={1652,259660,903717}</body>
</message>
*** User 2022-05-11 09:01:00.906 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=2, prefs={roster,[bob],[]}, now={1652,259660,903708}</body>
<stanza-id by='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' id='BNLOLOL3CH81' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:00.910 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=4, prefs={roster,[bob],[]}, now={1652,259660,903717}</body>
<stanza-id by='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' id='BNLOLOL40RG1' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:00.910 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=1, prefs={roster,[bob],[]}, now={1652,259660,903698}</body>
<stanza-id by='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' id='BNLOLOL3CHG1' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:00.910 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=3, prefs={roster,[bob],[]}, now={1652,259660,903713}</body>
</message>
*** User 2022-05-11 09:01:00.910 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<iq type='set' id='323b89141d27e242f9ff114340e79d25'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always>
<jid>kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com</jid>
</always>
<never/>
</prefs>
</iq>
*** User 2022-05-11 09:01:00.912 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<iq from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='323b89141d27e242f9ff114340e79d25' type='result'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always>
<jid>kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com</jid>
</always>
<never/>
</prefs>
</iq>
*** User 2022-05-11 09:01:00.912 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=1, prefs={roster,[kate],[]}, now={1652,259660,912500}</body>
</message>
*** User 2022-05-11 09:01:00.912 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=2, prefs={roster,[kate],[]}, now={1652,259660,912524}</body>
</message>
*** User 2022-05-11 09:01:00.912 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=3, prefs={roster,[kate],[]}, now={1652,259660,912529}</body>
</message>
*** User 2022-05-11 09:01:00.912 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=4, prefs={roster,[kate],[]}, now={1652,259660,912534}</body>
</message>
*** User 2022-05-11 09:01:00.921 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=2, prefs={roster,[kate],[]}, now={1652,259660,912524}</body>
<stanza-id by='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' id='BNLOLOL5RC81' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:00.923 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=4, prefs={roster,[kate],[]}, now={1652,259660,912534}</body>
<stanza-id by='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' id='BNLOLOL7KT81' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:00.923 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=3, prefs={roster,[kate],[]}, now={1652,259660,912529}</body>
<stanza-id by='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' id='BNLOLOL5RBG1' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:00.923 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=1, prefs={roster,[kate],[]}, now={1652,259660,912500}</body>
<stanza-id by='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' id='BNLOLOL5RCG1' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:00.924 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<iq type='set' id='78bb3e87e5dc5dc854893233e51da01a'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always>
<jid>kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com</jid>
<jid>bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com</jid>
</always>
<never/>
</prefs>
</iq>
*** User 2022-05-11 09:01:00.925 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<iq from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='78bb3e87e5dc5dc854893233e51da01a' type='result'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always>
<jid>kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com</jid>
<jid>bob_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com</jid>
</always>
<never/>
</prefs>
</iq>
*** User 2022-05-11 09:01:00.925 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=1, prefs={roster,[kate,bob],[]}, now={1652,259660,925901}</body>
</message>
*** User 2022-05-11 09:01:00.925 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=2, prefs={roster,[kate,bob],[]}, now={1652,259660,925912}</body>
</message>
*** User 2022-05-11 09:01:00.926 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=3, prefs={roster,[kate,bob],[]}, now={1652,259660,925917}</body>
</message>
*** User 2022-05-11 09:01:00.926 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=4, prefs={roster,[kate,bob],[]}, now={1652,259660,925921}</body>
</message>
*** User 2022-05-11 09:01:00.929 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=2, prefs={roster,[kate,bob],[]}, now={1652,259660,925912}</body>
<stanza-id by='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' id='BNLOLOL8Q7O1' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:00.930 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=4, prefs={roster,[kate,bob],[]}, now={1652,259660,925921}</body>
<stanza-id by='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' id='BNLOLOL9F001' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:00.931 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=1, prefs={roster,[kate,bob],[]}, now={1652,259660,925901}</body>
<stanza-id by='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' id='BNLOLOL8Q701' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:00.931 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=3, prefs={roster,[kate,bob],[]}, now={1652,259660,925917}</body>
<stanza-id by='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' id='BNLOLOL8Q7G1' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:00.931 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<iq type='set' id='8df30d0c9d1f40a8cffd204c82da9bc5'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always/>
<never>
<jid>bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com</jid>
</never>
</prefs>
</iq>
*** User 2022-05-11 09:01:00.933 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<iq from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='8df30d0c9d1f40a8cffd204c82da9bc5' type='result'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always/>
<never>
<jid>bob_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com</jid>
</never>
</prefs>
</iq>
*** User 2022-05-11 09:01:00.933 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=1, prefs={roster,[],[bob]}, now={1652,259660,933265}</body>
</message>
*** User 2022-05-11 09:01:00.933 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=2, prefs={roster,[],[bob]}, now={1652,259660,933300}</body>
</message>
*** User 2022-05-11 09:01:00.933 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=3, prefs={roster,[],[bob]}, now={1652,259660,933305}</body>
</message>
*** User 2022-05-11 09:01:00.933 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=4, prefs={roster,[],[bob]}, now={1652,259660,933309}</body>
</message>
*** User 2022-05-11 09:01:00.936 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=2, prefs={roster,[],[bob]}, now={1652,259660,933300}</body>
<stanza-id by='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' id='BNLOLOLAKK01' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:00.939 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=4, prefs={roster,[],[bob]}, now={1652,259660,933309}</body>
<stanza-id by='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' id='BNLOLOLB9QO1' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:00.939 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=1, prefs={roster,[],[bob]}, now={1652,259660,933265}</body>
</message>
*** User 2022-05-11 09:01:00.939 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=3, prefs={roster,[],[bob]}, now={1652,259660,933305}</body>
</message>
*** User 2022-05-11 09:01:00.939 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<iq type='set' id='6a8e8e81b62a9577d8df582a94eb9cbf'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always/>
<never>
<jid>kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com</jid>
</never>
</prefs>
</iq>
*** User 2022-05-11 09:01:00.941 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<iq from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='6a8e8e81b62a9577d8df582a94eb9cbf' type='result'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always/>
<never>
<jid>kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com</jid>
</never>
</prefs>
</iq>
*** User 2022-05-11 09:01:00.941 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=1, prefs={roster,[],[kate]}, now={1652,259660,941368}</body>
</message>
*** User 2022-05-11 09:01:00.941 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=2, prefs={roster,[],[kate]}, now={1652,259660,941381}</body>
</message>
*** User 2022-05-11 09:01:00.941 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=3, prefs={roster,[],[kate]}, now={1652,259660,941387}</body>
</message>
*** User 2022-05-11 09:01:00.941 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=4, prefs={roster,[],[kate]}, now={1652,259660,941393}</body>
</message>
*** User 2022-05-11 09:01:00.981 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=2, prefs={roster,[],[kate]}, now={1652,259660,941381}</body>
<stanza-id by='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' id='BNLOLOLCU881' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:00.981 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=4, prefs={roster,[],[kate]}, now={1652,259660,941393}</body>
<stanza-id by='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' id='BNLOLOLLOF01' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:00.981 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=3, prefs={roster,[],[kate]}, now={1652,259660,941387}</body>
</message>
*** User 2022-05-11 09:01:00.981 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=1, prefs={roster,[],[kate]}, now={1652,259660,941368}</body>
<stanza-id by='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' id='BNLOLOLLOF81' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:00.981 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<iq type='set' id='1bcf43fc0e5a224a02ff54c3a1094044'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always/>
<never>
<jid>bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com</jid>
<jid>kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com</jid>
</never>
</prefs>
</iq>
*** User 2022-05-11 09:01:00.984 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<iq from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='1bcf43fc0e5a224a02ff54c3a1094044' type='result'>
<prefs xmlns='urn:xmpp:mam:1' default='roster'>
<always/>
<never>
<jid>bob_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com</jid>
<jid>kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com</jid>
</never>
</prefs>
</iq>
*** User 2022-05-11 09:01:00.984 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=1, prefs={roster,[],[bob,kate]}, now={1652,259660,984329}</body>
</message>
*** User 2022-05-11 09:01:00.984 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=2, prefs={roster,[],[bob,kate]}, now={1652,259660,984340}</body>
</message>
*** User 2022-05-11 09:01:00.984 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=3, prefs={roster,[],[bob,kate]}, now={1652,259660,984369}</body>
</message>
*** User 2022-05-11 09:01:00.984 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<message to='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=4, prefs={roster,[],[bob,kate]}, now={1652,259660,984374}</body>
</message>
*** User 2022-05-11 09:01:00.987 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=2, prefs={roster,[],[bob,kate]}, now={1652,259660,984340}</body>
<stanza-id by='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' id='BNLOLOLN3F81' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:00.988 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=4, prefs={roster,[],[bob,kate]}, now={1652,259660,984374}</body>
<stanza-id by='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' id='BNLOLOLNI7G1' xmlns='urn:xmpp:sid:0'/>
</message>
*** User 2022-05-11 09:01:00.988 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=3, prefs={roster,[],[bob,kate]}, now={1652,259660,984369}</body>
</message>
Could not find element wait_for_parallel_writer in Config.
Could not find element archive_wait in Config.
*** User 2022-05-11 09:01:00.988 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xml:lang='en' type='chat'>
<body>n=1, prefs={roster,[],[bob,kate]}, now={1652,259660,984329}</body>
</message>
*** User 2022-05-11 09:01:00.988 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<iq type='set' id='c723915b0b59bddad12573bdabd17c66'>
<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:00.992 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='081cc0d1-6be7-4247-aa96-37e222cde926'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLOKK2N81'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:00.841309Z' from='alice_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1'/>
<message from='alice_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=2, prefs={roster,[],[]}, now={1652,259660,840929}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:00.992 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='1b8107d8-7955-4027-8a81-b7df7a6edfcf'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLOKUSM01'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:00.885592Z' from='bob_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1'/>
<message from='bob_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=1, prefs={roster,[],[]}, now={1652,259660,840916}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:00.992 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='37151b73-32ea-448f-8ea5-d25edd299ea1'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLOL3C101'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:00.903940Z' from='alice_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1'/>
<message from='alice_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=2, prefs={roster,[bob],[]}, now={1652,259660,903708}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:00.993 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='c32eaba8-522c-4054-bbf4-dde83bc875f0'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLOL3CHG1'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:00.904006Z' from='bob_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1'/>
<message from='bob_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=1, prefs={roster,[bob],[]}, now={1652,259660,903698}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:00.993 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='01d3cd26-bc48-4142-a310-90c68ee69bc2'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLOL5RBG1'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:00.914094Z' from='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1'/>
<message from='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=3, prefs={roster,[kate],[]}, now={1652,259660,912529}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:00.993 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='53f90efd-7837-4355-a272-b22e8cef0f97'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLOL5RBO1'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:00.914095Z' from='alice_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1'/>
<message from='alice_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=2, prefs={roster,[kate],[]}, now={1652,259660,912524}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:00.993 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='aee0202e-0477-44ed-88fd-ea492e1d3432'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLOL5RCG1'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:00.914098Z' from='bob_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1'/>
<message from='bob_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=1, prefs={roster,[kate],[]}, now={1652,259660,912500}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:00.993 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='12111076-ce9c-4945-a7bd-c26388681d31'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLOL7DUG1'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:00.920570Z' from='alice_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1'/>
<message from='alice_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=4, prefs={roster,[kate],[]}, now={1652,259660,912534}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:00.993 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='d9dcce97-200c-4abe-8ff6-15a9da402497'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLOL8P4G1'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:00.926098Z' from='alice_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1'/>
<message from='alice_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=2, prefs={roster,[kate,bob],[]}, now={1652,259660,925912}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:00.993 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='6cb5db7c-e6d0-4605-8d60-de09a1cc1ca5'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLOL8Q701'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:00.926236Z' from='bob_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1'/>
<message from='bob_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=1, prefs={roster,[kate,bob],[]}, now={1652,259660,925901}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:00.993 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='ddb4c5f7-75d5-4017-9114-ec7fcaec0dcd'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLOL8Q7G1'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:00.926238Z' from='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1'/>
<message from='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=3, prefs={roster,[kate,bob],[]}, now={1652,259660,925917}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:00.993 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='67d3e595-c669-4328-a8c4-f0f8ff29267a'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLOL9EV01'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:00.928892Z' from='alice_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1'/>
<message from='alice_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=4, prefs={roster,[kate,bob],[]}, now={1652,259660,925921}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:00.993 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='4e689fbd-ac38-4f17-9ed2-86e36e9e2def'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLOLCIF81'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:00.941629Z' from='alice_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1'/>
<message from='alice_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=2, prefs={roster,[],[kate]}, now={1652,259660,941381}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:00.993 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<message from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='8dfd69e8-3685-4e51-8c0a-9f35b7e9bc7a'>
<result queryid='page_rsm' xmlns='urn:xmpp:mam:1' id='BNLOLOLLOF81'>
<forwarded xmlns='urn:xmpp:forward:0'>
<delay xmlns='urn:xmpp:delay' stamp='2022-05-11T09:01:00.979261Z' from='bob_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1'/>
<message from='bob_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' xmlns='jabber:client' xml:lang='en' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' type='chat'>
<body>n=1, prefs={roster,[],[kate]}, now={1652,259660,941368}</body>
</message>
</forwarded>
</result>
</message>
*** User 2022-05-11 09:01:00.993 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<iq from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='c723915b0b59bddad12573bdabd17c66' type='result'>
<fin xmlns='urn:xmpp:mam:1' complete='true'>
<set xmlns='http://jabber.org/protocol/rsm'>
<first index='0'>BNLOLOKK2N81</first>
<last>BNLOLOLLOF81</last>
<count>14</count>
</set>
</fin>
</iq>
*** User 2022-05-11 09:01:00.994 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
<iq type='set' id='745e64ac79e476bb1e2303b30fc296a8'>
<query xmlns='urn:xmpp:mam:1' queryid='page_rsm'>
<x xmlns='jabber:x:data'/>
<set>
<max>50</max>
<after>BNLOLOLLOF81</after>
</set>
</query>
</iq>
*** User 2022-05-11 09:01:01.007 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
<iq from='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com' to='alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1' id='745e64ac79e476bb1e2303b30fc296a8' 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:01.007 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
</stream:stream>
*** User 2022-05-11 09:01:01.008 ***
kate_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
</stream:stream>
*** User 2022-05-11 09:01:01.008 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
</stream:stream>
*** User 2022-05-11 09:01:01.008 ***
bOb_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
</stream:stream>
*** User 2022-05-11 09:01:01.008 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 out
</stream:stream>
*** User 2022-05-11 09:01:01.008 ***
alicE_messages_filtered_when_prefs_default_policy_is_roster_1609@domain.example.com/res1 in
</stream:stream>
=== Ended at 2022-05-11 09:01:01
=== successfully completed test case
=== === Returned value: ok