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

=== Config value:

    [{watchdog,<0.14557.0>},
     {mim_data_dir,"/home/circleci/app/big_tests/tests/jingle_SUITE_data"},
     {preset,"ldap_mnesia"},
     {tc_logfile,"/home/circleci/app/big_tests/ct_report/ct_run.test@default-969e87ad-cfc5-4965-b50e-e7052e416ecb.2021-10-27_12.42.57/big_tests.tests.jingle_SUITE.logs/run.2021-10-27_12.45.51/jingle_suite.resp_4xx_from_sip_proxy_results_in_session_terminate.4338.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-969e87ad-cfc5-4965-b50e-e7052e416ecb.2021-10-27_12.42.57/big_tests.tests.jingle_SUITE.logs/run.2021-10-27_12.45.51/log_private/"}]

=== Current directory is "/home/circleci/app/big_tests/ct_report/ct_run.test@default-969e87ad-cfc5-4965-b50e-e7052e416ecb.2021-10-27_12.42.57"

=== Started at 2021-10-27 12:45:53
*** CT 2021-10-27 12:45:53.319 *** View log from node mongooseim@localhost when started mongooseim@localhost_885.html 
*** CT 2021-10-27 12:45:53.320 *** View log from node ejabberd2@localhost when started
ejabberd2@localhost_483.html






*** User 2021-10-27 12:45:53.447 ***
alicE_resp_4xx_from_sip_proxy_results_in_session_terminate_53.407868@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 12:45:53.453 ***
alicE_resp_4xx_from_sip_proxy_results_in_session_terminate_53.407868@localhost/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='ec9d0eb825ecaccc' from='localhost' version='1.0' xml:lang='en'>







*** User 2021-10-27 12:45:53.453 ***
alicE_resp_4xx_from_sip_proxy_results_in_session_terminate_53.407868@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>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 12:45:53.453 ***
alicE_resp_4xx_from_sip_proxy_results_in_session_terminate_53.407868@localhost/res1 out
<auth xmlns='urn:ietf:params:xml:ns:xmpp-sasl' mechanism='PLAIN'>AGFsaWNFX3Jlc3BfNHh4X2Zyb21fc2lwX3Byb3h5X3Jlc3VsdHNfaW5fc2Vzc2lvbl90ZXJtaW5hdGVfNTMuNDA3ODY4AG1hdHlncnlzYQ==</auth>









*** User 2021-10-27 12:45:53.474 ***
alicE_resp_4xx_from_sip_proxy_results_in_session_terminate_53.407868@localhost/res1 in
<success xmlns='urn:ietf:params:xml:ns:xmpp-sasl'/>









*** User 2021-10-27 12:45:53.474 ***
alicE_resp_4xx_from_sip_proxy_results_in_session_terminate_53.407868@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 12:45:53.480 ***
alicE_resp_4xx_from_sip_proxy_results_in_session_terminate_53.407868@localhost/res1 in
<stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='7b5504fedd907915' from='localhost' version='1.0' xml:lang='en'>







*** User 2021-10-27 12:45:53.480 ***
alicE_resp_4xx_from_sip_proxy_results_in_session_terminate_53.407868@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 12:45:53.480 ***
alicE_resp_4xx_from_sip_proxy_results_in_session_terminate_53.407868@localhost/res1 out
<iq type='set' id='9037384b10aea784a262dcf9fc95dc24'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<resource>res1</resource>
	</bind>
</iq>









*** User 2021-10-27 12:45:53.486 ***
alicE_resp_4xx_from_sip_proxy_results_in_session_terminate_53.407868@localhost/res1 in
<iq id='9037384b10aea784a262dcf9fc95dc24' type='result'>
	<bind xmlns='urn:ietf:params:xml:ns:xmpp-bind'>
		<jid>alicE_resp_4xx_from_sip_proxy_results_in_session_terminate_53.407868@localhost/res1</jid>
	</bind>
</iq>









*** User 2021-10-27 12:45:53.487 ***
alicE_resp_4xx_from_sip_proxy_results_in_session_terminate_53.407868@localhost/res1 out
<iq type='set' id='b6e2f5d733ae17697120781e613cf5e4'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>









*** User 2021-10-27 12:45:53.489 ***
alicE_resp_4xx_from_sip_proxy_results_in_session_terminate_53.407868@localhost/res1 in
<iq type='result' id='b6e2f5d733ae17697120781e613cf5e4'>
	<session xmlns='urn:ietf:params:xml:ns:xmpp-session'/>
</iq>









*** User 2021-10-27 12:45:53.489 ***
alicE_resp_4xx_from_sip_proxy_results_in_session_terminate_53.407868@localhost/res1 out
<presence/>









*** User 2021-10-27 12:45:53.521 ***
alicE_resp_4xx_from_sip_proxy_results_in_session_terminate_53.407868@localhost/res1 in
<presence from='alicE_resp_4xx_from_sip_proxy_results_in_session_terminate_53.407868@localhost/res1' to='alice_resp_4xx_from_sip_proxy_results_in_session_terminate_53.407868@localhost/res1' xml:lang='en'/>









*** User 2021-10-27 12:45:53.522 ***
alicE_resp_4xx_from_sip_proxy_results_in_session_terminate_53.407868@localhost/res1 out
<iq xmlns='jabber:client' type='set' id='147380d6-088b-41c3-9906-6b1697fc6264' to='error.480@localhost'>
	<jingle action='session-initiate' sid='7ddb8ea7-b755-4987-a717-40466ec35b6d' 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 12:45:56.912 ***
alicE_resp_4xx_from_sip_proxy_results_in_session_terminate_53.407868@localhost/res1 in
<iq from='error.480@localhost' to='alice_resp_4xx_from_sip_proxy_results_in_session_terminate_53.407868@localhost/res1' id='7db5c0d4-49e3-454c-8fbe-6898ac417588' type='set'>
	<jingle xmlns='urn:xmpp:jingle:1' action='session-terminate' sid='7ddb8ea7-b755-4987-a717-40466ec35b6d'>
		<reason>
			<general-error/>
			<sip-error code='480'>Temporarily Unavailable</sip-error>
		</reason>
	</jingle>
</iq>





*** CT 2021-10-27 12:45:56.914 *** Stanza Log:
resp_4xx_from_sip_proxy_results_in_session_terminate.xml






*** CT Error Notification 2021-10-27 12:45:56.917 ***
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 12:45:56.919 *** View log from node ejabberd2@localhost when finished
ejabberd2@localhost_483.html


*** CT 2021-10-27 12:45:56.920 *** View log from node mongooseim@localhost when finished
mongooseim@localhost_885.html





=== Ended at 2021-10-27 12:45:56
=== Location: [{escalus_new_assert,assert_true,84},
              {jingle_SUITE,send_initiate_and_wait_for_first_iq_set,395},
              {jingle_SUITE,'-resp_4xx_from_sip_proxy_results_in_session_terminate/1-fun-0-',143},
              {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">>,<<"error.480@localhost">>},
                    {<<"to">>,
                     <<"alice_resp_4xx_from_sip_proxy_results_in_session_terminate_53.407868@localhost/res1">>},
                    {<<"id">>,<<"7db5c0d4-49e3-454c-8fbe-6898ac417588">>},
                    {<<"type">>,<<"set">>}],
                   [{xmlel,<<"jingle">>,
                     [{<<"xmlns">>,<<"urn:xmpp:jingle:1">>},
                      {<<"action">>,<<"session-terminate">>},
                      {<<"sid">>,<<"7ddb8ea7-b755-4987-a717-40466ec35b6d">>}],
                     [{xmlel,<<"reason">>,[],
                       [{xmlel,<<"general-error">>,[],[]},
                        {xmlel,<<"sip-error">>,
                         [{<<"code">>,<<"480">>}],
                         [{xmlcdata,<<"Temporarily Unavailable">>}]}]}]}]},
                  "<iq from='error.480@localhost' to='alice_resp_4xx_from_sip_proxy_results_in_session_terminate_53.407868@localhost/res1' id='7db5c0d4-49e3-454c-8fbe-6898ac417588' type='set'><jingle xmlns='urn:xmpp:jingle:1' action='session-terminate' sid='7ddb8ea7-b755-4987-a717-40466ec35b6d'><reason><general-error/><sip-error code='480'>Temporarily Unavailable</sip-error></reason></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:'-resp_4xx_from_sip_proxy_results_in_session_terminate/1-fun-0-'/1 (/home/circleci/app/big_tests/tests/jingle_SUITE.erl, line 143)
  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)