=== Test case: muc_SUITE:hibernated_room_is_stopped_and_restored_by_presence/1 (click for source code)

=== Config value:

    [{watchdog,<0.26276.1>},
     {mim_data_dir,"/home/circleci/project/big_tests/tests/muc_SUITE_data"},
     {preset,"odbc_mssql_mnesia"},
     {tc_logfile,"/home/circleci/project/big_tests/ct_report/ct_run.test@ebe8a8f9fc53.2022-01-25_16.21.59/big_tests.tests.muc_SUITE.logs/run.2022-01-25_16.30.36/muc_suite.hibernated_room_is_stopped_and_restored_by_presence.34053.html"},
     {tc_group_properties,[{name,hibernation},
                           {repeat_until_all_ok,3},
                           parallel]},
     {tc_group_path,[]},
     {data_dir,"/home/circleci/project/big_tests/_build/default/lib/mongoose_tests/ebin/muc_SUITE_data/"},
     {priv_dir,"/home/circleci/project/big_tests/ct_report/ct_run.test@ebe8a8f9fc53.2022-01-25_16.21.59/big_tests.tests.muc_SUITE.logs/run.2022-01-25_16.30.36/log_private/"},
     {{saved_modules,mongooseim@localhost,<<"localhost">>},
      #{mod_adhoc => [],mod_amp => [],mod_bosh => [],
        mod_cache_users => [{number_of_segments,5},{time_to_live,2}],
        mod_carboncopy => [],mod_commands => [],
        mod_disco => [{users_can_see_hidden_services,false}],
        mod_muc_commands => [],mod_muc_light_commands => [],
        mod_register =>
            [{access,register},
             {ip_access,[{allow,"127.0.0.0/8"},{deny,"0.0.0.0/0"}]},
             {welcome_message,{[],[]}}],
        mod_roster => [{backend,rdbms}],
        mod_sic => [],mod_stream_management => [],
        mod_vcard => [{backend,rdbms},{host,{prefix,<<"vjud.">>}}]}}]

=== Current directory is "/home/circleci/project/big_tests/ct_report/ct_run.test@ebe8a8f9fc53.2022-01-25_16.21.59"

=== Started at 2022-01-25 16:31:09


*** CT 2022-01-25 16:31:09.389 *** View log from node mongooseim@localhost when started mongooseim@localhost_5377.html
*** CT 2022-01-25 16:31:09.390 *** View log from node ejabberd2@localhost when started ejabberd2@localhost_2339.html

*** User 2022-01-25 16:31:09.420 ***
alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2022-01-25 16:31:09.420 ***
alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='e05b43d4c17cb46d' from='localhost' version='1.0' xml:lang='en'>

*** User 2022-01-25 16:31:09.420 ***
alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<stream:features>
	<starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
	<compression xmlns='http://jabber.org/features/compress'>
		<method>zlib</method>
	</compression>
	<mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>
		<mechanism>SCRAM-SHA-256</mechanism>
		<mechanism>PLAIN</mechanism>
	</mechanisms>
	<register xmlns='http://jabber.org/features/iq-register'/>
	<amp xmlns='http://jabber.org/feature/amp'/>
	<sm xmlns='urn:xmpp:sm:3'/>
</stream:features>



*** User 2022-01-25 16:31:09.420 ***
alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 out
<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGFsaWNFX2hpYmVybmF0ZWRfcm9vbV9pc19zdG9wcGVkX2FuZF9yZXN0b3JlZF9ieV9wcmVzZW5jZV8xODIxAG1hdHlncnlzYQ==</auth>



*** User 2022-01-25 16:31:09.439 ***
alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>



*** User 2022-01-25 16:31:09.439 ***
alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2022-01-25 16:31:09.445 ***
alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='eb9e40bb464b493e' from='localhost' version='1.0' xml:lang='en'>

*** User 2022-01-25 16:31:09.446 ***
alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<stream:features>
	<compression xmlns='http://jabber.org/features/compress'>
		<method>zlib</method>
	</compression>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'/>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
	<register xmlns='http://jabber.org/features/iq-register'/>
	<amp xmlns='http://jabber.org/feature/amp'/>
	<sm xmlns='urn:xmpp:sm:3'/>
</stream:features>



*** User 2022-01-25 16:31:09.446 ***
alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 out
<iq type='set' id='4ca5a17c904d975d2b672e91191776d2'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<resource>res1</resource>
	</bind>
</iq>



*** User 2022-01-25 16:31:09.446 ***
alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<iq id='4ca5a17c904d975d2b672e91191776d2' type='result'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<jid>alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1</jid>
	</bind>
</iq>



*** User 2022-01-25 16:31:09.449 ***
alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 out
<iq type='set' id='e733209caf2e584d884dda6a0a9e6525'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2022-01-25 16:31:09.449 ***
alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<iq type='result' id='e733209caf2e584d884dda6a0a9e6525'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2022-01-25 16:31:09.449 ***
alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 out
<presence/>



*** User 2022-01-25 16:31:09.452 ***
alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<presence from='alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1' to='alice_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1' xml:lang='en'/>



*** User 2022-01-25 16:31:09.453 ***
bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2022-01-25 16:31:09.454 ***
bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='3a70aeb70a1e2730' from='localhost' version='1.0' xml:lang='en'>

*** User 2022-01-25 16:31:09.454 ***
bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<stream:features>
	<starttls xmlns='urn:ietf:params:xml:ns:xmpp-tls'/>
	<compression xmlns='http://jabber.org/features/compress'>
		<method>zlib</method>
	</compression>
	<mechanisms xmlns='urn:ietf:params:xml:ns:xmpp-sasl'>
		<mechanism>SCRAM-SHA-256</mechanism>
		<mechanism>PLAIN</mechanism>
	</mechanisms>
	<register xmlns='http://jabber.org/features/iq-register'/>
	<amp xmlns='http://jabber.org/feature/amp'/>
	<sm xmlns='urn:xmpp:sm:3'/>
</stream:features>



*** User 2022-01-25 16:31:09.454 ***
bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 out
<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGJPYl9oaWJlcm5hdGVkX3Jvb21faXNfc3RvcHBlZF9hbmRfcmVzdG9yZWRfYnlfcHJlc2VuY2VfMTgyMQBtYWtyb2xpa2E=</auth>



*** User 2022-01-25 16:31:09.455 ***
bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>



*** User 2022-01-25 16:31:09.455 ***
bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2022-01-25 16:31:09.456 ***
bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='ae208a24ebd350be' from='localhost' version='1.0' xml:lang='en'>

*** User 2022-01-25 16:31:09.456 ***
bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<stream:features>
	<compression xmlns='http://jabber.org/features/compress'>
		<method>zlib</method>
	</compression>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'/>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
	<register xmlns='http://jabber.org/features/iq-register'/>
	<amp xmlns='http://jabber.org/feature/amp'/>
	<sm xmlns='urn:xmpp:sm:3'/>
</stream:features>



*** User 2022-01-25 16:31:09.456 ***
bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 out
<iq type='set' id='2d4c14d6d6fb4984ebd4ce712c5f7347'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<resource>res1</resource>
	</bind>
</iq>



*** User 2022-01-25 16:31:09.456 ***
bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<iq id='2d4c14d6d6fb4984ebd4ce712c5f7347' type='result'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<jid>bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1</jid>
	</bind>
</iq>



*** User 2022-01-25 16:31:09.457 ***
bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 out
<iq type='set' id='64ba1ca93ee0662de577e6fe0ae6816b'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2022-01-25 16:31:09.457 ***
bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<iq type='result' id='64ba1ca93ee0662de577e6fe0ae6816b'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2022-01-25 16:31:09.457 ***
bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 out
<presence/>



*** User 2022-01-25 16:31:09.459 ***
bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<presence from='bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1' to='bob_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1' xml:lang='en'/>



*** User 2022-01-25 16:31:09.460 ***
RoomJID {jid,<<"room-ad27c079a2">>,<<"muc.localhost">>,<<>>,
             <<"room-ad27c079a2">>,<<"muc.localhost">>,<<>>}

*** User 2022-01-25 16:31:09.460 ***
alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 out
<presence to='room-ad27c079a2@muc.localhost/alicE_hibernated_room_is_stopped_and_restored_by_presence_1821'>
	<x xmlns='http://jabber.org/protocol/muc'/>
</presence>



*** User 2022-01-25 16:31:09.463 ***
alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<presence from='room-ad27c079a2@muc.localhost/alicE_hibernated_room_is_stopped_and_restored_by_presence_1821' to='alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1' xml:lang='en'>
	<x xmlns='http://jabber.org/protocol/muc#user'>
		<item jid='alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1' affiliation='owner' role='moderator'/>
		<status code='110'/>
		<status code='201'/>
	</x>
</presence>



*** User 2022-01-25 16:31:09.463 ***
alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<message from='room-ad27c079a2@muc.localhost' to='alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1' type='groupchat'>
	<subject/>
	<body/>
</message>



*** User 2022-01-25 16:31:09.464 ***
alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 out
<iq type='set' id='7bfcd18e336b9dad6de5f719ae78a0c8' to='room-ad27c079a2@muc.localhost'>
	<query xmlns='http://jabber.org/protocol/muc#owner'>
		<x xmlns='jabber:x:data' type='submit'>
			<field type='hidden' var='FORM_TYPE'>
				<value>http://jabber.org/protocol/muc#roomconfig</value>
			</field>
			<field type='boolean' var='muc#roomconfig_persistentroom'>
				<value>1</value>
			</field>
		</x>
	</query>
</iq>



*** User 2022-01-25 16:31:09.471 ***
alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<iq from='room-ad27c079a2@muc.localhost' to='alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1' id='7bfcd18e336b9dad6de5f719ae78a0c8' type='result'>
	<query xmlns='http://jabber.org/protocol/muc#owner'/>
</iq>



*** User 2022-01-25 16:31:09.471 ***
alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 out
<message type='groupchat' to='room-ad27c079a2@muc.localhost'>
	<subject>Restorable</subject>
</message>



*** User 2022-01-25 16:31:09.476 ***
alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<message from='room-ad27c079a2@muc.localhost/alicE_hibernated_room_is_stopped_and_restored_by_presence_1821' to='alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1' xml:lang='en' type='groupchat'>
	<subject>Restorable</subject>
</message>



*** User 2022-01-25 16:31:11.497 ***
bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 out
<presence to='room-ad27c079a2@muc.localhost/bOb_hibernated_room_is_stopped_and_restored_by_presence_1821'>
	<x xmlns='http://jabber.org/protocol/muc'/>
</presence>



*** User 2022-01-25 16:31:11.499 ***
bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<presence from='room-ad27c079a2@muc.localhost/alicE_hibernated_room_is_stopped_and_restored_by_presence_1821' to='bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1' xml:lang='en'>
	<x xmlns='http://jabber.org/protocol/muc#user'>
		<item affiliation='owner' role='moderator'/>
	</x>
</presence>



*** User 2022-01-25 16:31:11.499 ***
bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<presence from='room-ad27c079a2@muc.localhost/bOb_hibernated_room_is_stopped_and_restored_by_presence_1821' to='bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1' xml:lang='en'>
	<x xmlns='http://jabber.org/protocol/muc#user'>
		<item affiliation='none' role='participant'/>
		<status code='110'/>
	</x>
</presence>



*** User 2022-01-25 16:31:11.499 ***
bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<message from='room-ad27c079a2@muc.localhost/alicE_hibernated_room_is_stopped_and_restored_by_presence_1821' to='bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1' xml:lang='en' type='groupchat'>
	<subject>Restorable</subject>
	<delay xmlns='urn:xmpp:delay' stamp='2022-01-25T16:31:09Z' from='room-ad27c079a2@muc.localhost'/>
</message>



*** User 2022-01-25 16:31:11.499 ***
alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<presence from='room-ad27c079a2@muc.localhost/bOb_hibernated_room_is_stopped_and_restored_by_presence_1821' to='alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1' xml:lang='en'>
	<x xmlns='http://jabber.org/protocol/muc#user'>
		<item jid='bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1' affiliation='none' role='participant'/>
	</x>
</presence>



*** User 2022-01-25 16:31:13.519 ***
alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 out
<presence type='unavailable' to='room-ad27c079a2@muc.localhost/alicE_hibernated_room_is_stopped_and_restored_by_presence_1821'/>



*** User 2022-01-25 16:31:13.520 ***
alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<presence from='room-ad27c079a2@muc.localhost/alicE_hibernated_room_is_stopped_and_restored_by_presence_1821' to='alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1' xml:lang='en' type='unavailable'>
	<x xmlns='http://jabber.org/protocol/muc#user'>
		<item affiliation='owner' role='none'/>
		<status code='110'/>
	</x>
</presence>



*** User 2022-01-25 16:31:13.520 ***
bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<presence from='room-ad27c079a2@muc.localhost/alicE_hibernated_room_is_stopped_and_restored_by_presence_1821' to='bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1' xml:lang='en' type='unavailable'>
	<x xmlns='http://jabber.org/protocol/muc#user'>
		<item affiliation='owner' role='none'/>
	</x>
</presence>



*** User 2022-01-25 16:31:13.520 ***
bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 out
<presence type='unavailable' to='room-ad27c079a2@muc.localhost/bOb_hibernated_room_is_stopped_and_restored_by_presence_1821'/>



*** User 2022-01-25 16:31:13.521 ***
bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<presence from='room-ad27c079a2@muc.localhost/bOb_hibernated_room_is_stopped_and_restored_by_presence_1821' to='bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1' xml:lang='en' type='unavailable'>
	<x xmlns='http://jabber.org/protocol/muc#user'>
		<item affiliation='none' role='none'/>
		<status code='110'/>
	</x>
</presence>



*** User 2022-01-25 16:31:19.315 ***
bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 out
<presence to='room-ad27c079a2@muc.localhost/bob'>
	<x xmlns='http://jabber.org/protocol/muc'/>
</presence>



*** User 2022-01-25 16:31:19.318 ***
bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<presence from='room-ad27c079a2@muc.localhost/bob' to='bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1' xml:lang='en'>
	<x xmlns='http://jabber.org/protocol/muc#user'>
		<item affiliation='none' role='participant'/>
		<status code='110'/>
	</x>
</presence>



*** User 2022-01-25 16:31:19.318 ***
bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
<message from='room-ad27c079a2@muc.localhost' to='bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1' type='groupchat'>
	<subject>Restorable</subject>
	<body/>
</message>



*** User 2022-01-25 16:31:19.319 ***
bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 out
</stream:stream>

*** User 2022-01-25 16:31:19.319 ***
bOb_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
</stream:stream>

*** User 2022-01-25 16:31:19.319 ***
alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 out
</stream:stream>

*** User 2022-01-25 16:31:19.319 ***
alicE_hibernated_room_is_stopped_and_restored_by_presence_1821@localhost/res1 in
</stream:stream>
*** CT 2022-01-25 16:31:19.333 *** View log from node ejabberd2@localhost when finished ejabberd2@localhost_2339.html
*** CT 2022-01-25 16:31:19.334 *** View log from node mongooseim@localhost when finished mongooseim@localhost_5377.html



=== Ended at 2022-01-25 16:31:19
=== successfully completed test case
=== === Returned value: ok