=== Test case: jingle_SUITE:jingle_session_is_established_when_calling_a_number/1 (click for source code)

=== Config value:

    [{watchdog,<0.18189.0>},
     {mim_data_dir,"/home/circleci/app/big_tests/tests/jingle_SUITE_data"},
     {preset,"mysql_redis"},
     {tc_logfile,"/home/circleci/app/big_tests/ct_report/ct_run.test@default-1900524f-d062-4d10-8b90-500d63d79e64.2021-10-27_13.24.51/big_tests.tests.jingle_SUITE.logs/run.2021-10-27_13.28.22/jingle_suite.jingle_session_is_established_when_calling_a_number.4499.html"},
     {tc_group_properties,[{name,all},{repeat_until_all_ok,3},parallel]},
     {tc_group_path,[]},
     {data_dir,"/home/circleci/app/big_tests/_build/default/lib/mongoose_tests/ebin/jingle_SUITE_data/"},
     {priv_dir,"/home/circleci/app/big_tests/ct_report/ct_run.test@default-1900524f-d062-4d10-8b90-500d63d79e64.2021-10-27_13.24.51/big_tests.tests.jingle_SUITE.logs/run.2021-10-27_13.28.22/log_private/"}]

=== Current directory is "/home/circleci/app/big_tests/ct_report/ct_run.test@default-1900524f-d062-4d10-8b90-500d63d79e64.2021-10-27_13.24.51"

=== Started at 2021-10-27 13:28:24


*** CT 2021-10-27 13:28:25.049 *** View log from node mongooseim@localhost when started mongooseim@localhost_731.html
*** CT 2021-10-27 13:28:25.050 *** View log from node ejabberd2@localhost when started ejabberd2@localhost_148.html

*** User 2021-10-27 13:28:25.232 ***
alicE_jingle_session_is_established_when_calling_a_number_5.116170@localhost/res1 out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2021-10-27 13:28:25.241 ***
alicE_jingle_session_is_established_when_calling_a_number_5.116170@localhost/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='123f8b27925cad3d' from='localhost' version='1.0' xml:lang='en'>

*** User 2021-10-27 13:28:25.241 ***
alicE_jingle_session_is_established_when_calling_a_number_5.116170@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 2021-10-27 13:28:25.241 ***
alicE_jingle_session_is_established_when_calling_a_number_5.116170@localhost/res1 out
<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGFsaWNFX2ppbmdsZV9zZXNzaW9uX2lzX2VzdGFibGlzaGVkX3doZW5fY2FsbGluZ19hX251bWJlcl81LjExNjE3MABtYXR5Z3J5c2E=</auth>



*** User 2021-10-27 13:28:25.276 ***
alicE_jingle_session_is_established_when_calling_a_number_5.116170@localhost/res1 in
<success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>



*** User 2021-10-27 13:28:25.276 ***
alicE_jingle_session_is_established_when_calling_a_number_5.116170@localhost/res1 out
<stream:stream to='localhost' version='1.0' xml:lang='en' xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams'>

*** User 2021-10-27 13:28:25.279 ***
alicE_jingle_session_is_established_when_calling_a_number_5.116170@localhost/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='17fde216933dcc98' from='localhost' version='1.0' xml:lang='en'>

*** User 2021-10-27 13:28:25.280 ***
alicE_jingle_session_is_established_when_calling_a_number_5.116170@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 2021-10-27 13:28:25.280 ***
alicE_jingle_session_is_established_when_calling_a_number_5.116170@localhost/res1 out
<iq type='set' id='e22cfa53e5c7052f2e15adbe151082cb'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<resource>res1</resource>
	</bind>
</iq>



*** User 2021-10-27 13:28:25.283 ***
alicE_jingle_session_is_established_when_calling_a_number_5.116170@localhost/res1 in
<iq id='e22cfa53e5c7052f2e15adbe151082cb' type='result'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<jid>alicE_jingle_session_is_established_when_calling_a_number_5.116170@localhost/res1</jid>
	</bind>
</iq>



*** User 2021-10-27 13:28:25.283 ***
alicE_jingle_session_is_established_when_calling_a_number_5.116170@localhost/res1 out
<iq type='set' id='7ad2eea9431fbdca0baee1e4d562a1c0'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2021-10-27 13:28:25.286 ***
alicE_jingle_session_is_established_when_calling_a_number_5.116170@localhost/res1 in
<iq type='result' id='7ad2eea9431fbdca0baee1e4d562a1c0'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>



*** User 2021-10-27 13:28:25.292 ***
alicE_jingle_session_is_established_when_calling_a_number_5.116170@localhost/res1 out
<presence/>



*** User 2021-10-27 13:28:25.399 ***
alicE_jingle_session_is_established_when_calling_a_number_5.116170@localhost/res1 in
<presence from='alicE_jingle_session_is_established_when_calling_a_number_5.116170@localhost/res1' to='alice_jingle_session_is_established_when_calling_a_number_5.116170@localhost/res1' xml:lang='en'/>



*** User 2021-10-27 13:28:25.400 ***
alicE_jingle_session_is_established_when_calling_a_number_5.116170@localhost/res1 out
<iq xmlns='jabber:client' type='set' id='3d74dc64-213c-48fe-898a-efd6ab6fcadc' to='+488790@numbers.localhost'>
	<jingle action='session-initiate' sid='d78e4c91-a2c6-4ecd-98c3-4295db0b5415' xmlns='urn:xmpp:jingle:1'>
		<content creator='initiator' name='audio_1' senders='both'>
			<description xmlns='urn:xmpp:jingle:apps:rtp:1' media='audio' ssrc='948015790'>
				<payload-type id='111' name='opus' clockrate='48000' channels='2'>
					<parameter name='minptime' value='10'/>
					<parameter name='useinbandfec' value='1'/>
					<rtcp-fb xmlns='urn:xmpp:jingle:apps:rtp:rtcp-fb:0' type='transport-cc'/>
				</payload-type>
				<payload-type id='103' name='ISAC' clockrate='16000' channels='1'/>
				<payload-type id='104' name='ISAC' clockrate='32000' channels='1'/>
				<payload-type id='9' name='G722' clockrate='8000' channels='1'/>
				<payload-type id='102' name='ILBC' clockrate='8000' channels='1'/>
				<payload-type id='0' name='PCMU' clockrate='8000' channels='1'/>
				<payload-type id='8' name='PCMA' clockrate='8000' channels='1'/>
				<payload-type id='106' name='CN' clockrate='32000' channels='1'/>
				<payload-type id='105' name='CN' clockrate='16000' channels='1'/>
				<payload-type id='13' name='CN' clockrate='8000' channels='1'/>
				<payload-type id='110' name='telephone-event' clockrate='48000' channels='1'/>
				<payload-type id='112' name='telephone-event' clockrate='32000' channels='1'/>
				<payload-type id='113' name='telephone-event' clockrate='16000' channels='1'/>
				<payload-type id='126' name='telephone-event' clockrate='8000' channels='1'/>
				<rtp-hdrext xmlns='urn:xmpp:jingle:apps:rtp:rtp-hdrext:0' id='1' uri='urn:ietf:params:rtp-hdrext:ssrc-audio-level' senders='both'/>
				<rtcp-mux/>
				<source xmlns='urn:xmpp:jingle:apps:rtp:ssma:0' ssrc='948015790'>
					<parameter name='cname' value='3dYy6Ys3wP//8AoS'/>
					<parameter name='msid' value='c5f700e1-1897-41c2-9421-697103982067 a4423a33-ffb9-40e2-a300-8317d9d00a46'/>
				</source>
			</description>
			<transport xmlns='urn:xmpp:jingle:transports:ice-udp:1' ufrag='TIxp' pwd='MkQXObfhEelTbQdRV1e0ADGh'>
				<fingerprint xmlns='urn:xmpp:jingle:apps:dtls:0' hash='sha-256' setup='actpass'>08:D7:8E:6D:A6:40:77:4C:CC:F8:46:68:80:F2:2A:B1:7B:A0:AF:02:02:CA:2A:2A:F4:35:1A:95:11:75:B2:F7</fingerprint>
			</transport>
		</content>
		<content creator='initiator' name='video_1' senders='responder'>
			<description xmlns='urn:xmpp:jingle:apps:rtp:1' media='video'>
				<payload-type id='96' name='VP8' clockrate='90000' channels='1'>
					<rtcp-fb xmlns='urn:xmpp:jingle:apps:rtp:rtcp-fb:0' type='ccm' subtype='fir'/>
					<rtcp-fb xmlns='urn:xmpp:jingle:apps:rtp:rtcp-fb:0' type='nack'/>
					<rtcp-fb xmlns='urn:xmpp:jingle:apps:rtp:rtcp-fb:0' type='nack' subtype='pli'/>
					<rtcp-fb xmlns='urn:xmpp:jingle:apps:rtp:rtcp-fb:0' type='goog-remb'/>
					<rtcp-fb xmlns='urn:xmpp:jingle:apps:rtp:rtcp-fb:0' type='transport-cc'/>
				</payload-type>
				<payload-type id='98' name='VP9' clockrate='90000' channels='1'>
					<rtcp-fb xmlns='urn:xmpp:jingle:apps:rtp:rtcp-fb:0' type='ccm' subtype='fir'/>
					<rtcp-fb xmlns='urn:xmpp:jingle:apps:rtp:rtcp-fb:0' type='nack'/>
					<rtcp-fb xmlns='urn:xmpp:jingle:apps:rtp:rtcp-fb:0' type='nack' subtype='pli'/>
					<rtcp-fb xmlns='urn:xmpp:jingle:apps:rtp:rtcp-fb:0' type='goog-remb'/>
					<rtcp-fb xmlns='urn:xmpp:jingle:apps:rtp:rtcp-fb:0' type='transport-cc'/>
				</payload-type>
				<payload-type id='100' name='red' clockrate='90000' channels='1'/>
				<payload-type id='127' name='ulpfec' clockrate='90000' channels='1'/>
				<payload-type id='97' name='rtx' clockrate='90000' channels='1'>
					<parameter name='apt' value='96'/>
				</payload-type>
				<payload-type id='99' name='rtx' clockrate='90000' channels='1'>
					<parameter name='apt' value='98'/>
				</payload-type>
				<payload-type id='101' name='rtx' clockrate='90000' channels='1'>
					<parameter name='apt' value='100'/>
				</payload-type>
				<rtp-hdrext xmlns='urn:xmpp:jingle:apps:rtp:rtp-hdrext:0' id='2' uri='urn:ietf:params:rtp-hdrext:toffset' senders='both'/>
				<rtp-hdrext xmlns='urn:xmpp:jingle:apps:rtp:rtp-hdrext:0' id='3' uri='http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time' senders='both'/>
				<rtp-hdrext xmlns='urn:xmpp:jingle:apps:rtp:rtp-hdrext:0' id='4' uri='urn:3gpp:video-orientation' senders='both'/>
				<rtp-hdrext xmlns='urn:xmpp:jingle:apps:rtp:rtp-hdrext:0' id='5' uri='http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01' senders='both'/>
				<rtp-hdrext xmlns='urn:xmpp:jingle:apps:rtp:rtp-hdrext:0' id='6' uri='http://www.webrtc.org/experiments/rtp-hdrext/playout-delay' senders='both'/>
				<rtcp-mux/>
			</description>
			<transport xmlns='urn:xmpp:jingle:transports:ice-udp:1' ufrag='TIxp' pwd='MkQXObfhEelTbQdRV1e0ADGh'>
				<fingerprint xmlns='urn:xmpp:jingle:apps:dtls:0' hash='sha-256' setup='actpass'>08:D7:8E:6D:A6:40:77:4C:CC:F8:46:68:80:F2:2A:B1:7B:A0:AF:02:02:CA:2A:2A:F4:35:1A:95:11:75:B2:F7</fingerprint>
			</transport>
		</content>
		<group xmlns='urn:xmpp:jingle:apps:grouping:0' semantics='BUNDLE'>
			<content name='audio_1'/>
			<content name='video_1'/>
		</group>
	</jingle>
</iq>



*** User 2021-10-27 13:28:28.789 ***
alicE_jingle_session_is_established_when_calling_a_number_5.116170@localhost/res1 in
<iq from='+488790@numbers.localhost' to='alice_jingle_session_is_established_when_calling_a_number_5.116170@localhost/res1' id='9f6d4d4d-124d-4873-bf99-816e8ee272c6' type='set'>
	<jingle xmlns='urn:xmpp:jingle:1' action='session-info' sid='d78e4c91-a2c6-4ecd-98c3-4295db0b5415'>
		<ringing xmlns='urn:xmpp:jingle:apps:rtp:info:1'/>
	</jingle>
</iq>


*** CT 2021-10-27 13:28:28.797 *** Stanza Log: jingle_session_is_established_when_calling_a_number.xml

*** CT Error Notification 2021-10-27 13:28:28.797 ***
escalus_new_assert:assert_true failed on line 84
Reason: {{assertion_failed,assert,is_iq_result,...},
 [{escalus_new_assert,...},{...}|...]}

Full error description and stacktrace
*** CT 2021-10-27 13:28:28.799 *** View log from node ejabberd2@localhost when finished ejabberd2@localhost_148.html
*** CT 2021-10-27 13:28:28.801 *** View log from node mongooseim@localhost when finished mongooseim@localhost_731.html



=== Ended at 2021-10-27 13:28:28
=== Location: [{escalus_new_assert,assert_true,84},
              {jingle_SUITE,send_initiate_and_wait_for_first_iq_set,395},
              {jingle_SUITE,'-jingle_session_is_established_when_calling_a_number/1-fun-0-',150},
              {escalus_story,story,72},
              {test_server,ts_tc,1783},
              {test_server,run_test_case_eval1,1292},
              {test_server,run_test_case_eval,1224}]
=== === Reason: {assertion_failed,assert,is_iq_result,
                     {xmlel,<<"iq">>,
                         [{<<"from">>,<<"+488790@numbers.localhost">>},
                          {<<"to">>,
                           <<"alice_jingle_session_is_established_when_calling_a_number_5.116170@localhost/res1">>},
                          {<<"id">>,
                           <<"9f6d4d4d-124d-4873-bf99-816e8ee272c6">>},
                          {<<"type">>,<<"set">>}],
                         [{xmlel,<<"jingle">>,
                              [{<<"xmlns">>,<<"urn:xmpp:jingle:1">>},
                               {<<"action">>,<<"session-info">>},
                               {<<"sid">>,
                                <<"d78e4c91-a2c6-4ecd-98c3-4295db0b5415">>}],
                              [{xmlel,<<"ringing">>,
                                   [{<<"xmlns">>,
                                     <<"urn:xmpp:jingle:apps:rtp:info:1">>}],
                                   []}]}]},
                     "<iq from='+488790@numbers.localhost' to='alice_jingle_session_is_established_when_calling_a_number_5.116170@localhost/res1' id='9f6d4d4d-124d-4873-bf99-816e8ee272c6' type='set'><jingle xmlns='urn:xmpp:jingle:1' action='session-info' sid='d78e4c91-a2c6-4ecd-98c3-4295db0b5415'><ringing xmlns='urn:xmpp:jingle:apps:rtp:info:1'/></jingle></iq>"}
  in function  escalus_new_assert:assert_true/2 (/home/circleci/app/big_tests/_build/default/lib/escalus/src/escalus_new_assert.erl, line 84)
  in call from jingle_SUITE:send_initiate_and_wait_for_first_iq_set/2 (/home/circleci/app/big_tests/tests/jingle_SUITE.erl, line 395)
  in call from jingle_SUITE:'-jingle_session_is_established_when_calling_a_number/1-fun-0-'/1 (/home/circleci/app/big_tests/tests/jingle_SUITE.erl, line 150)
  in call from escalus_story:story/4 (/home/circleci/app/big_tests/_build/default/lib/escalus/src/escalus_story.erl, line 72)
  in call from test_server:ts_tc/3 (test_server.erl, line 1783)
  in call from test_server:run_test_case_eval1/6 (test_server.erl, line 1292)
  in call from test_server:run_test_case_eval/9 (test_server.erl, line 1224)