=== Test case: mam_SUITE:same_stanza_id/1 (click for source code)
=== Config value:
[{watchdog,<0.1009.1>},
{basic_group,mam_all},
{configuration,rdbms_async_pool},
{wait_for_parallel_writer,[pm]},
{mam_meta_opts,#{archive_chat_markers => false,
async_writer =>
#{batch_size => 30,enabled => true,
flush_interval => 1,pool_size => 16},
backend => rdbms,
cache =>
#{module => internal,number_of_segments => 3,
strategy => fifo,time_to_live => 480},
cache_users => false,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}},
{mod_offline_loaded,false},
{escalus_user_db,{module,escalus_ejabberd}},
{mim_data_dir,"/home/circleci/project/big_tests/tests/mam_SUITE_data"},
{preset,"odbc_mssql_mnesia"},
{tc_logfile,"/home/circleci/project/big_tests/ct_report/ct_run.test@cb94372a8ea7.2022-04-04_15.02.36/big_tests.tests.mam_SUITE.logs/run.2022-04-04_15.07.14/mam_suite.same_stanza_id.47747.html"},
{tc_group_properties,[{name,configurable_archiveid}]},
{tc_group_path,[[{name,rdbms_async_pool_mam_all},parallel]]},
{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@cb94372a8ea7.2022-04-04_15.02.36/big_tests.tests.mam_SUITE.logs/run.2022-04-04_15.07.14/log_private/"},
{config_backup,#{[shaper,fast] => {ok,#{max_rate => 50000}},
[shaper,mam_shaper] => {ok,#{max_rate => 1}},
[shaper,normal] => {ok,#{max_rate => 1000}},
[{access,<<"localhost">>},max_user_sessions] =>
{ok,[#{acl => all,value => 10}]}}},
{{saved_modules,mongooseim@localhost,<<"localhost">>},
#{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 => [],mod_commands => [],
mod_disco =>
#{extra_domains => [],iqdisc => one_queue,server_info => [],
users_can_see_hidden_services => false},
mod_mam =>
#{archive_chat_markers => false,archive_groupchats => false,
async_writer =>
#{batch_size => 30,enabled => true,flush_interval => 1,
pool_size => 16},
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,
same_mam_id_for_peers => false,send_message => mod_mam_utils},
mod_mam_meta =>
#{archive_chat_markers => false,
async_writer =>
#{batch_size => 30,enabled => true,flush_interval => 1,
pool_size => 16},
backend => rdbms,
cache =>
#{module => internal,number_of_segments => 3,
strategy => fifo,time_to_live => 480},
cache_users => false,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},
mod_mam_rdbms_arch =>
#{db_jid_format => mam_jid_mini,
db_message_format => mam_message_compressed_eterm,
no_writer => true},
mod_mam_rdbms_arch_async =>
#{batch_size => 30,enabled => true,flush_interval => 1,
pool_size => 16},
mod_mam_rdbms_prefs => #{pm => true},
mod_mam_rdbms_user => #{pm => true},
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,<<"muc.">>},
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"}]},
{welcome_message,{[],[]}}],
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,<<"localhost">>},
{password,<<"matygrysa">>}]},
{bob,[{username,<<"bOb">>},
{server,<<"localhost">>},
{password,<<"makrolika">>}]},
{kate,[{username,<<"kate">>},
{server,<<"localhost">>},
{password,<<"makrowe;p">>}]},
{carol,[{username,<<"carol">>},
{server,<<"localhost">>},
{password,<<"jinglebells">>},
{transport,escalus_bosh},
{path,<<"/http-bind">>},
{port,5280}]}]}]
=== Current directory is "/home/circleci/project/big_tests/ct_report/ct_run.test@cb94372a8ea7.2022-04-04_15.02.36"
=== Started at 2022-04-04 15:07:36
Could not find element props in Config.
*** User 2022-04-04 15:07:36.796 *** alicE_unnamed_1273_same_stanza_id_1274@localhost/res1 out <stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>
*** User 2022-04-04 15:07:36.796 *** alicE_unnamed_1273_same_stanza_id_1274@localhost/res1 in <stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='2415382017f6d957' from='localhost' version='1.0' xml:lang='en'>
*** User 2022-04-04 15:07:36.796 *** alicE_unnamed_1273_same_stanza_id_1274@localhost/res1 in <stream:features> <starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/> <compression xmlns='http://jabber.org/features/compress'> <method>zlib</method> </compression> <mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl'> <mechanism>SCRAM-SHA-256</mechanism> <mechanism>PLAIN</mechanism> </mechanisms> <register xmlns='http://jabber.org/features/iq-register'/> <amp xmlns='http://jabber.org/feature/amp'/> <sm xmlns='urn:xmpp:sm:3'/> </stream:features>
*** User 2022-04-04 15:07:36.796 *** alicE_unnamed_1273_same_stanza_id_1274@localhost/res1 out <auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGFsaWNFX3VubmFtZWRfMTI3M19zYW1lX3N0YW56YV9pZF8xMjc0AG1hdHlncnlzYQ==</auth>
*** User 2022-04-04 15:07:36.798 *** alicE_unnamed_1273_same_stanza_id_1274@localhost/res1 in <success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
*** User 2022-04-04 15:07:36.798 *** alicE_unnamed_1273_same_stanza_id_1274@localhost/res1 out <stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>
*** User 2022-04-04 15:07:36.798 *** alicE_unnamed_1273_same_stanza_id_1274@localhost/res1 in <stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='f4c5d0bfee0bc904' from='localhost' version='1.0' xml:lang='en'>
*** User 2022-04-04 15:07:36.798 *** alicE_unnamed_1273_same_stanza_id_1274@localhost/res1 in <stream:features> <compression xmlns='http://jabber.org/features/compress'> <method>zlib</method> </compression> <bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'/> <session xmlns='urn:ietf:params:xml:ns:xmpp-session'/> <register xmlns='http://jabber.org/features/iq-register'/> <amp xmlns='http://jabber.org/feature/amp'/> <sm xmlns='urn:xmpp:sm:3'/> </stream:features>
*** User 2022-04-04 15:07:36.798 *** alicE_unnamed_1273_same_stanza_id_1274@localhost/res1 out <iq type='set' id='2dd056cfab1ba6ac9e20d0f155e75d88'> <bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'> <resource>res1</resource> </bind> </iq>
*** User 2022-04-04 15:07:36.798 *** alicE_unnamed_1273_same_stanza_id_1274@localhost/res1 in <iq id='2dd056cfab1ba6ac9e20d0f155e75d88' type='result'> <bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'> <jid>alicE_unnamed_1273_same_stanza_id_1274@localhost/res1</jid> </bind> </iq>
*** User 2022-04-04 15:07:36.799 *** alicE_unnamed_1273_same_stanza_id_1274@localhost/res1 out <iq type='set' id='481c0a2d6b6b6ed5ff76f391c19cf788'> <session xmlns='urn:ietf:params:xml:ns:xmpp-session'/> </iq>
*** User 2022-04-04 15:07:36.799 *** alicE_unnamed_1273_same_stanza_id_1274@localhost/res1 in <iq type='result' id='481c0a2d6b6b6ed5ff76f391c19cf788'> <session xmlns='urn:ietf:params:xml:ns:xmpp-session'/> </iq>
*** User 2022-04-04 15:07:36.799 *** alicE_unnamed_1273_same_stanza_id_1274@localhost/res1 out <presence/>
*** User 2022-04-04 15:07:36.802 *** alicE_unnamed_1273_same_stanza_id_1274@localhost/res1 in <presence from='alicE_unnamed_1273_same_stanza_id_1274@localhost/res1' to='alice_unnamed_1273_same_stanza_id_1274@localhost/res1' xml:lang='en'/>
*** User 2022-04-04 15:07:36.803 *** bOb_unnamed_1273_same_stanza_id_1274@localhost/res1 out <stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>
*** User 2022-04-04 15:07:36.803 *** bOb_unnamed_1273_same_stanza_id_1274@localhost/res1 in <stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='81b969b8664895e3' from='localhost' version='1.0' xml:lang='en'>
*** User 2022-04-04 15:07:36.803 *** bOb_unnamed_1273_same_stanza_id_1274@localhost/res1 in <stream:features> <starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/> <compression xmlns='http://jabber.org/features/compress'> <method>zlib</method> </compression> <mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl'> <mechanism>SCRAM-SHA-256</mechanism> <mechanism>PLAIN</mechanism> </mechanisms> <register xmlns='http://jabber.org/features/iq-register'/> <amp xmlns='http://jabber.org/feature/amp'/> <sm xmlns='urn:xmpp:sm:3'/> </stream:features>
*** User 2022-04-04 15:07:36.804 *** bOb_unnamed_1273_same_stanza_id_1274@localhost/res1 out <auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGJPYl91bm5hbWVkXzEyNzNfc2FtZV9zdGFuemFfaWRfMTI3NABtYWtyb2xpa2E=</auth>
*** User 2022-04-04 15:07:36.805 *** bOb_unnamed_1273_same_stanza_id_1274@localhost/res1 in <success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>
*** User 2022-04-04 15:07:36.805 *** bOb_unnamed_1273_same_stanza_id_1274@localhost/res1 out <stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>
*** User 2022-04-04 15:07:36.806 *** bOb_unnamed_1273_same_stanza_id_1274@localhost/res1 in <stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='27237507655628b8' from='localhost' version='1.0' xml:lang='en'>
*** User 2022-04-04 15:07:36.806 *** bOb_unnamed_1273_same_stanza_id_1274@localhost/res1 in <stream:features> <compression xmlns='http://jabber.org/features/compress'> <method>zlib</method> </compression> <bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'/> <session xmlns='urn:ietf:params:xml:ns:xmpp-session'/> <register xmlns='http://jabber.org/features/iq-register'/> <amp xmlns='http://jabber.org/feature/amp'/> <sm xmlns='urn:xmpp:sm:3'/> </stream:features>
*** User 2022-04-04 15:07:36.806 *** bOb_unnamed_1273_same_stanza_id_1274@localhost/res1 out <iq type='set' id='baefa9582103e2b501d2cd67b7d6f3b4'> <bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'> <resource>res1</resource> </bind> </iq>
*** User 2022-04-04 15:07:36.806 *** bOb_unnamed_1273_same_stanza_id_1274@localhost/res1 in <iq id='baefa9582103e2b501d2cd67b7d6f3b4' type='result'> <bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'> <jid>bOb_unnamed_1273_same_stanza_id_1274@localhost/res1</jid> </bind> </iq>
*** User 2022-04-04 15:07:36.806 *** bOb_unnamed_1273_same_stanza_id_1274@localhost/res1 out <iq type='set' id='a32fb8f8d43515b630db4dacee1dcf7c'> <session xmlns='urn:ietf:params:xml:ns:xmpp-session'/> </iq>
*** User 2022-04-04 15:07:36.807 *** bOb_unnamed_1273_same_stanza_id_1274@localhost/res1 in <iq type='result' id='a32fb8f8d43515b630db4dacee1dcf7c'> <session xmlns='urn:ietf:params:xml:ns:xmpp-session'/> </iq>
*** User 2022-04-04 15:07:36.807 *** bOb_unnamed_1273_same_stanza_id_1274@localhost/res1 out <presence/>
*** User 2022-04-04 15:07:36.810 *** bOb_unnamed_1273_same_stanza_id_1274@localhost/res1 in <presence from='bOb_unnamed_1273_same_stanza_id_1274@localhost/res1' to='bob_unnamed_1273_same_stanza_id_1274@localhost/res1' xml:lang='en'/>
*** User 2022-04-04 15:07:36.810 *** alicE_unnamed_1273_same_stanza_id_1274@localhost/res1 out <message to='bOb_unnamed_1273_same_stanza_id_1274@localhost/res1' type='chat'> <body>OH, HAI!</body> </message>
*** System report during mam_SUITE:same_stanza_id/1 in configurable_archiveid 2022-04-04 15:07:36.814 ***
=WARNING REPORT==== 4-Apr-2022::15:07:36.814095 ===
host: <<"localhost">>
reason: {error,#{odbc_params =>
[{{sql_wlongvarchar,9},
[<<108,0,111,0,99,0,97,0,108,0,104,0,111,0,115,0,
116,0>>]},
{{sql_wlongvarchar,38},
[<<97,0,108,0,105,0,99,0,101,0,95,0,117,0,110,0,
110,0,97,0,109,0,101,0,100,0,95,0,49,0,50,0,
55,0,51,0,95,0,115,0,97,0,109,0,101,0,95,0,
115,0,116,0,97,0,110,0,122,0,97,0,95,0,105,0,
100,0,95,0,49,0,50,0,55,0,52,0>>]}],
odbc_query =>
<<"INSERT INTO mam_server_user (server, user_name) VALUES (?, ?)">>,
reason =>
"[FreeTDS][SQL Server]Violation of UNIQUE KEY constraint 'mam_server_user$uc_mam_server_user_name'. Cannot insert duplicate key in object 'dbo.mam_server_user'. The duplicate key value is (localhost, alice_unnamed_1273_same_stanza_id_1274).[FreeTDS][SQL Server]The statement has been terminated. SQLSTATE IS: 01000"}}
server: <<"localhost">>
user: <<"alice_unnamed_1273_same_stanza_id_1274">>
what: create_user_archive_failed
*** User 2022-04-04 15:07:36.920 *** alicE_unnamed_1273_same_stanza_id_1274@localhost/res1 out <iq type='set' id='a3c8ca1685315de298b77cd6e792e8c4'> <query xmlns='urn:xmpp:mam:1' queryid='q1'> <x xmlns='jabber:x:data'/> </query> </iq>
*** User 2022-04-04 15:07:36.923 *** alicE_unnamed_1273_same_stanza_id_1274@localhost/res1 in <message from='alicE_unnamed_1273_same_stanza_id_1274@localhost' to='alicE_unnamed_1273_same_stanza_id_1274@localhost/res1' id='b5fc7865-d9a7-456e-a830-dffe92be7b1d'> <result queryid='q1' xmlns='urn:xmpp:mam:1' id='BMULFKC3NUG1'> <forwarded xmlns='urn:xmpp:forward:0'> <delay xmlns='urn:xmpp:delay' stamp='2022-04-04T15:07:36.810490Z' from='alice_unnamed_1273_same_stanza_id_1274@localhost/res1'/> <message from='alice_unnamed_1273_same_stanza_id_1274@localhost/res1' xmlns='jabber:client' xml:lang='en' to='bOb_unnamed_1273_same_stanza_id_1274@localhost/res1' type='chat'> <body>OH, HAI!</body> </message> </forwarded> </result> </message>
*** User 2022-04-04 15:07:36.923 *** alicE_unnamed_1273_same_stanza_id_1274@localhost/res1 in <iq from='alicE_unnamed_1273_same_stanza_id_1274@localhost' to='alicE_unnamed_1273_same_stanza_id_1274@localhost/res1' id='a3c8ca1685315de298b77cd6e792e8c4' type='result'> <fin xmlns='urn:xmpp:mam:1' complete='true'> <set xmlns='http://jabber.org/protocol/rsm'> <first index='0'>BMULFKC3NUG1</first> <last>BMULFKC3NUG1</last> <count>1</count> </set> </fin> </iq>
*** User 2022-04-04 15:07:36.923 *** bOb_unnamed_1273_same_stanza_id_1274@localhost/res1 in <message from='alicE_unnamed_1273_same_stanza_id_1274@localhost/res1' to='bOb_unnamed_1273_same_stanza_id_1274@localhost/res1' xml:lang='en' type='chat'> <body>OH, HAI!</body> <stanza-id by='bOb_unnamed_1273_same_stanza_id_1274@localhost' id='BMULFKC3NUG1' xmlns='urn:xmpp:sid:0'/> </message>
*** User 2022-04-04 15:07:36.923 *** bOb_unnamed_1273_same_stanza_id_1274@localhost/res1 out </stream:stream>
*** User 2022-04-04 15:07:36.924 *** bOb_unnamed_1273_same_stanza_id_1274@localhost/res1 in </stream:stream>
*** User 2022-04-04 15:07:36.924 *** alicE_unnamed_1273_same_stanza_id_1274@localhost/res1 out </stream:stream>
*** User 2022-04-04 15:07:36.924 *** alicE_unnamed_1273_same_stanza_id_1274@localhost/res1 in </stream:stream>
=== Ended at 2022-04-04 15:07:36 === successfully completed test case === === Returned value: ok
Test run history | Top level test index | Latest test result