FreeSWITCH Pastebin

First call attempt always fails

From Shane, 4 Years ago, written in FreeSWITCH.
- view diff
URL https://pastebin.freeswitch.org/view/9bcda348
Embed
Download Paste or View Raw
  1. INVITE sip:3500@167.xxx.xxx.xxx SIP/2.0
  2. Via: SIP/2.0/WSS 1lunmvqyg6qc.invalid;branch=z9hG4bK7771690900000000
  3. Max-Forwards: 69
  4. To: <sip:3500@167.xxx.xxx.xxx>
  5. From: "Shane" <sip:1003@167.xxx.xxx.xxx>;tag=5314g11e14
  6. Call-ID: 716221w85pj113033g41
  7. CSeq: 2055 INVITE
  8. Contact: <sip:u6yucgwm@1lunmvqyg6qc.invalid;transport=ws;ob>
  9. Content-Type: application/sdp
  10. Session-Expires: 120
  11. Allow: INVITE,ACK,CANCEL,BYE,UPDATE,MESSAGE,OPTIONS,REFER,INFO
  12. Supported: timer,ice,replaces,outbound
  13. User-Agent: dart-sip-ua v0.0.1
  14. Content-Length: 2034
  15.  
  16. v=0
  17. o=- 6843851528902463715 2 IN IP4 127.0.0.1
  18. s=-
  19. t=0 0
  20. a=group:BUNDLE audio
  21. a=msid-semantic: WMS 2947f368-71dc-4e98-a171-cf7242b8f5a1
  22. m=audio 44953 UDP/TLS/RTP/SAVPF 111 103 104 9 102 0 8 106 105 13 110 112 113 126
  23. c=IN IP4 213.233.150.56
  24. a=rtcp:9 IN IP4 0.0.0.0
  25. a=candidate:2840189678 1 udp 2122260223 10.232.87.227 44953 typ host generation 0 network-id 3 network-cost 900
  26. a=candidate:559267639 1 udp 2122202367 ::1 38968 typ host generation 0 network-id 2
  27. a=candidate:1510613869 1 udp 2122129151 127.0.0.1 51553 typ host generation 0 network-id 1
  28. a=candidate:842163049 1 udp 1686052607 213.233.150.56 44953 typ srflx raddr 10.232.87.227 rport 44953 generation 0 network-id 3 network-cost 900
  29. a=candidate:1876313031 1 tcp 1518222591 ::1 34633 typ host tcptype passive generation 0 network-id 2
  30. a=candidate:344579997 1 tcp 1518149375 127.0.0.1 43401 typ host tcptype passive generation 0 network-id 1
  31. a=ice-ufrag:p4IB
  32. a=ice-pwd:ZHQICtSp4uNc8je5csTTHBl0
  33. a=ice-options:trickle renomination
  34. a=fingerprint:sha-256 60:B9:7C:9C:30:57:7B:EE:45:57:50:8F:CE:A5:D0:06:83:21:7D:F9:B7:E6:2F:B3:B3:8B:D3:7E:02:18:FD:CD
  35. a=setup:actpass
  36. a=mid:audio
  37. a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level
  38. a=extmap:2 http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01
  39. a=sendrecv
  40. a=rtcp-mux
  41. a=rtpmap:111 opus/48000/2
  42. a=rtcp-fb:111 transport-cc
  43. a=fmtp:111 minptime=10;useinbandfec=1
  44. a=rtpmap:103 ISAC/16000
  45. a=rtpmap:104 ISAC/32000
  46. a=rtpmap:9 G722/8000
  47. a=rtpmap:102 ILBC/8000
  48. a=rtpmap:0 PCMU/8000
  49. a=rtpmap:8 PCMA/8000
  50. a=rtpmap:106 CN/32000
  51. a=rtpmap:105 CN/16000
  52. a=rtpmap:13 CN/8000
  53. a=rtpmap:110 telephone-event/48000
  54. a=rtpmap:112 telephone-event/32000
  55. a=rtpmap:113 telephone-event/16000
  56. a=rtpmap:126 telephone-event/8000
  57. a=ssrc:2623371499 cname:U0wUz1a/Hla159cR
  58. a=ssrc:2623371499 msid:2947f368-71dc-4e98-a171-cf7242b8f5a1 b7b406b3-f908-4d49-b8f0-5f599e623d69
  59. a=ssrc:2623371499 mslabel:2947f368-71dc-4e98-a171-cf7242b8f5a1
  60. a=ssrc:2623371499 label:b7b406b3-f908-4d49-b8f0-5f599e623d69
  61. tport.c:3039 tport_deliver() tport_deliver(0x7f7e480db280): msg 0x7f7e4805a610 (2580 bytes) from wss/213.233.150.56:17788/sips next=(nil)
  62. nta.c:2880 agent_recv_request() nta: received INVITE sip:3500@ 167.xxx.xxx.xxx
  63.  SIP/2.0 (CSeq 2055)
  64. nta.c:3174 agent_check_request_via() nta: Via check: received=213.233.150.56
  65. nta.c:3248 agent_aliases() nta: canonizing sip:3500@ 167.xxx.xxx.xxx with contact
  66. nta.c:3085 agent_recv_request() nta: INVITE (2055) going to a default leg
  67. nua_server.c:102 nua_stack_process_request() nua: nua_stack_process_request: entering
  68. nua_stack.c:899 nh_create() nua: nh_create: entering
  69. nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering
  70. nua_params.c:482 nua_stack_set_params() nua: nua_stack_set_params: entering
  71. soa.c:280 soa_clone() soa_clone(static::0x7f7e4800b8f0, 0x7f7e4800b7f0, 0x7f7e48056bf0) called
  72. soa.c:403 soa_set_params() soa_set_params(static::0x7f7e480cf7d0, ...) called
  73. nta.c:4417 nta_leg_tcreate() nta_leg_tcreate(0x7f7e4810ea40)
  74. soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::0x7f7e480cf7d0) called
  75. soa.c:1171 soa_set_remote_sdp() soa_set_remote_sdp(static::0x7f7e480cf7d0, (nil), 0x7f7e480d7d42, 2034) called
  76. nua_dialog.c:338 nua_dialog_usage_add() nua(0x7f7e48056bf0): adding session usage
  77. nua_stack.c:271 nua_stack_event() nua(0x7f7e48056bf0): event i_invite 100 Trying
  78. nua_session.c:4143 signal_call_state_change() nua(0x7f7e48056bf0): call state changed: init -> received, received offer
  79. soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::0x7f7e480cf7d0, [0x7f7e580838b8], [0x7f7e580838c0], [(nil)]) called
  80. nua_stack.c:271 nua_stack_event() nua(0x7f7e48056bf0): event i_state 100 Trying
  81. tport.c:2302 tport_set_secondary_timer() tport(0x7f7e480db280): reset timer
  82. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  83. nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering
  84. 9b440888-88c9-4ff4-b1cb-7031c24df293 2020-04-18 10:15:06.359057 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/1003@167.xxx.xxx.xxx[9b440888-88c9-4ff4-b1cb-7031c24df293]
  85. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  86. 9b440888-88c9-4ff4-b1cb-7031c24df293 2020-04-18 10:15:06.359057 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/1003@167.xxx.xxx.xxx) Running State Change CS_NEW (Cur 5 Tot 87)
  87. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  88. 9b440888-88c9-4ff4-b1cb-7031c24df293 2020-04-18 10:15:06.359057 [DEBUG] sofia.c:10255 sofia/internal/1003@ 167.xxx.xxx.xxx receiving invite from 213.233.150.56:17788 version: 1.10.2 -release-14-f7bdd3845a 64bit
  89. 9b440888-88c9-4ff4-b1cb-7031c24df293 2020-04-18 10:15:06.359057 [ALERT] switch_core_media.c:491 Looking for zrtp-hash
  90. 9b440888-88c9-4ff4-b1cb-7031c24df293 2020-04-18 10:15:06.359057 [ALERT] switch_core_media.c:444 Deciding whether to pass zrtp-hash between legs
  91. 9b440888-88c9-4ff4-b1cb-7031c24df293 2020-04-18 10:15:06.359057 [ALERT] switch_core_media.c:446 CF_ZRTP_PASSTHRU_REQ not set, so not propagating zrtp-hash
  92. 2020-04-18 10:15:06.359057 [ALERT] switch_core_media.c:940 ADD PMAP REQ opus 111
  93. 2020-04-18 10:15:06.359057 [ALERT] switch_core_media.c:940 ADD PMAP REQ ISAC 103
  94. 2020-04-18 10:15:06.359057 [ALERT] switch_core_media.c:940 ADD PMAP REQ ISAC 104
  95. 2020-04-18 10:15:06.359057 [ALERT] switch_core_media.c:940 ADD PMAP REQ G722 9
  96. 2020-04-18 10:15:06.359057 [ALERT] switch_core_media.c:940 ADD PMAP REQ ILBC 102
  97. 2020-04-18 10:15:06.359057 [ALERT] switch_core_media.c:940 ADD PMAP REQ PCMU 0
  98. 2020-04-18 10:15:06.359057 [ALERT] switch_core_media.c:940 ADD PMAP REQ PCMA 8
  99. 2020-04-18 10:15:06.359057 [ALERT] switch_core_media.c:940 ADD PMAP REQ CN 106
  100. 2020-04-18 10:15:06.359057 [ALERT] switch_core_media.c:940 ADD PMAP REQ CN 105
  101. 2020-04-18 10:15:06.359057 [ALERT] switch_core_media.c:940 ADD PMAP REQ CN 13
  102. 2020-04-18 10:15:06.359057 [ALERT] switch_core_media.c:940 ADD PMAP REQ telephone-event 110
  103. 2020-04-18 10:15:06.359057 [ALERT] switch_core_media.c:940 ADD PMAP REQ telephone-event 112
  104. 2020-04-18 10:15:06.359057 [ALERT] switch_core_media.c:940 ADD PMAP REQ telephone-event 113
  105. 2020-04-18 10:15:06.359057 [ALERT] switch_core_media.c:940 ADD PMAP REQ telephone-event 126
  106. 2020-04-18 10:15:06.359057 [DEBUG] sofia.c:10349 verifying acl "domains" for ip/port 213.233.150.56:0.
  107. nua.c:879 nua_respond() nua: nua_respond: entering
  108. nua_stack.c:573 nua_stack_signal() nua(0x7f7e48056bf0): recv signal r_respond 407 Proxy Authentication Required
  109. nua_params.c:482 nua_stack_set_params() nua: nua_stack_set_params: entering
  110. soa.c:403 soa_set_params() soa_set_params(static::0x7f7e480cf7d0, ...) called
  111. nua_session.c:2324 nua_invite_server_respond() nua: nua_invite_server_respond: entering
  112. soa.c:1214 soa_clear_remote_sdp() soa_clear_remote_sdp(static::0x7f7e480cf7d0) called
  113. tport.c:3273 tport_tsend() tport_tsend(0x7f7e480db280) tpn = WSS/213.233.150.56:17788
  114. tport_type_ws.c:312 tport_send_stream_ws() tport_ws_writevec: vec 0x7f7e480db470 0x7f7e4811ce50 149 (0)
  115. tport_type_ws.c:312 tport_send_stream_ws() tport_ws_writevec: vec 0x7f7e480db470 0x7f7e480d7bbf 62 (0)
  116. tport_type_ws.c:312 tport_send_stream_ws() tport_ws_writevec: vec 0x7f7e480db470 0x7f7e4811cee5 49 (0)
  117. tport_type_ws.c:312 tport_send_stream_ws() tport_ws_writevec: vec 0x7f7e480db470 0x7f7e480d7bfd 50 (0)
  118. tport_type_ws.c:312 tport_send_stream_ws() tport_ws_writevec: vec 0x7f7e480db470 0x7f7e4811cf16 554 (0)
  119. tport.c:3610 tport_vsend() tport_vsend(0x7f7e480db280): 864 bytes of 864 to wss/213.233.150.56:17788
  120. tport.c:3508 tport_send_msg() tport_vsend returned 864
  121. send 864 bytes to wss/[213.233.150.56]:17788 at 10:15:06.372525:
  122. ------------------------------------------------------------------------
  123. SIP/2.0 407 Proxy Authentication Required
  124. Via: SIP/2.0/WSS 1lunmvqyg6qc.invalid;branch=z9hG4bK7771690900000000;received=213.233.150.56;rport=17788
  125. From: "Shane" <sip:1003@167.xxx.xxx.xxx>;tag=5314g11e14
  126. To: <sip:3500@167.xxx.xxx.xxx>;tag=F30rjU5XN59XN
  127. Call-ID: 716221w85pj113033g41
  128. CSeq: 2055 INVITE
  129. User-Agent: FreeSWITCH-mod_sofia/1.10.2-release-14-f7bdd3845a~64bit
  130. Accept: application/sdp
  131. Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  132. Supported: timer, path, replaces
  133. Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  134. Proxy-Authenticate: Digest realm="167.xxx.xxx.xxx", nonce="3a177f74-010e-4109-9b7b-3aacde32ab91", algorithm=MD5, qop="auth"
  135. Content-Length: 0
  136.  
  137. tport.c:2302 tport_set_secondary_timer() tport(0x7f7e480db280): reset timer
  138. nta.c:6797 incoming_reply() nta: sent 407 Proxy Authentication Required for INVITE (2055)
  139. nua_dialog.c:397 nua_dialog_usage_remove_at() nua(0x7f7e48056bf0): removing session usage
  140. nua_session.c:4143 signal_call_state_change() nua(0x7f7e48056bf0): call state changed: received -> terminated
  141. nua_stack.c:271 nua_stack_event() nua(0x7f7e48056bf0): event i_state 407 Proxy Authentication Required
  142. nua_stack.c:271 nua_stack_event() nua(0x7f7e48056bf0): event i_terminated 407 Proxy Authentication Required
  143. soa.c:356 soa_destroy() soa_destroy(static::0x7f7e480cf7d0) called
  144. nta.c:4470 nta_leg_destroy() nta_leg_destroy(0x7f7e4810ea40)
  145. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  146. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  147. 2020-04-18 10:15:06.359057 [DEBUG] sofia.c:2434 detaching session 9b440888-88c9-4ff4-b1cb-7031c24df293
  148. nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering
  149. nua_stack.c:529 nua_signal() nua(0x7f7e48056bf0): sent signal r_destroy
  150. nua_stack.c:569 nua_stack_signal() nua(0x7f7e48056bf0): recv signal r_destroy
  151. nta.c:4470 nta_leg_destroy() nta_leg_destroy((nil))
  152. nua_stack.c:529 nua_signal() nua(0x7f7e48056bf0): sent signal r_respond
  153. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  154. nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering
  155. nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering
  156. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  157. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  158. nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering
  159. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  160. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  161. nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering
  162. 9b440888-88c9-4ff4-b1cb-7031c24df293 2020-04-18 10:15:06.359057 [DEBUG] switch_core_state_machine.c:604 (sofia/internal/1003@167.xxx.xxx.xxx) State NEW
  163. nta.c:7002 _nta_incoming_timer() nta: timer G fired, retransmitting 407 reply
  164. tport.c:3273 tport_tsend() tport_tsend(0x7f7e48014340) tpn = UDP/103.145.12.70:64731
  165. tport.c:4062 tport_resolve() tport_resolve addrinfo = 103.145.12.70:64731
  166. tport.c:4696 tport_by_addrinfo() tport_by_addrinfo(0x7f7e48014340): not found by name UDP/103.145.12.70:64731
  167. tport.c:3610 tport_vsend() tport_vsend(0x7f7e48014340): 819 bytes of 819 to udp/103.145.12.70:64731
  168. tport.c:3508 tport_send_msg() tport_vsend returned 819
  169. send 819 bytes to udp/[103.145.12.70]:64731 at 10:15:06.452550:
  170. ------------------------------------------------------------------------
  171. ACK sip:3500@ 167.xxx.xxx.xxx SIP/2.0
  172. Via: SIP/2.0/WSS 1lunmvqyg6qc.invalid;branch=z9hG4bK7771690900000000
  173. Max-Forwards: 69
  174. To: <sip:3500@167.xxx.xxx.xxx>;tag=F30rjU5XN59XN
  175. From: "Shane" <sip:1003@167.xxx.xxx.xxx>;tag=5314g11e14
  176. Call-ID: 716221w85pj113033g41
  177. CSeq: 2055 ACK
  178. Allow: INVITE,ACK,CANCEL,BYE,UPDATE,MESSAGE,OPTIONS,REFER,INFO
  179. Supported: outbound
  180. User-Agent: dart-sip-ua v0.0.1
  181. Content-Length: 0
  182.  
  183. tport.c:3039 tport_deliver() tport_deliver(0x7f7e480db280): msg 0x7f7e4803f330 (421 bytes) from wss/213.233.150.56:17788/sips next=(nil)
  184. nta.c:2880 agent_recv_request() nta: received ACK sip:3500@ 167.xxx.xxx.xxx SIP/2.0 (CSeq 2055)
  185. nta.c:3174 agent_check_request_via() nta: Via check: received=213.233.150.56
  186. nta.c:3019 agent_recv_request() nta: ACK (2055) is going to INVITE (2055)
  187. tport.c:2302 tport_set_secondary_timer() tport(0x7f7e480db280): reset timer
  188. nta.c:7002 _nta_incoming_timer() nta: timer G fired, retransmitting 407 reply
  189. tport.c:3273 tport_tsend() tport_tsend(0x7f7e48014340) tpn = UDP/103.145.12.69:60593
  190. tport.c:4062 tport_resolve() tport_resolve addrinfo = 103.145.12.69:60593
  191. tport.c:4696 tport_by_addrinfo() tport_by_addrinfo(0x7f7e48014340): not found by name UDP/103.145.12.69:60593
  192. tport.c:3610 tport_vsend() tport_vsend(0x7f7e48014340): 826 bytes of 826 to udp/103.145.12.69:60593
  193. tport.c:3508 tport_send_msg() tport_vsend returned 826
  194. send 826 bytes to udp/[103.145.12.69]:60593 at 10:15:06.708549:
  195. ------------------------------------------------------------------------

Reply to "First call attempt always fails"