From Jose Lopes, 7 Years ago, written in Plain Text.
- view diff
Embed
  1. 2017-02-24 11:47:36.668715 [NOTICE] mod_logfile.c:192 New log started.
  2. tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f6024004530): events IN
  3. tport.c:2864 tport_recv_event() tport_recv_event(0x7f6024004530)
  4. tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f6024004530) msg 0x7f602400fd20 from (udp/172.21.0.10:5080) has 1221 bytes, veclen = 1
  5. recv 1221 bytes from udp/[172.21.0.12]:5060 at 11:49:23.668644:
  6.    ------------------------------------------------------------------------
  7.    INVITE sip:3613612202@172.21.0.10:5080 SIP/2.0
  8.    Record-Route: <sip:172.21.0.12;lr=on;ftag=1;did=aac.deb2>
  9.    Via: SIP/2.0/UDP 172.21.0.12;branch=z9hG4bK1ecb.ff4d1330fdf889a72137635ea4ba63d8.0
  10.    Via: SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1765-1-0
  11.    Max-Forwards: 43
  12.    To: "3613612202" <sip:3613612202@webrtc.example.com>
  13.    From: "3613612209" <sip:3613612209@webrtc.example.com>;tag=1
  14.    Call-ID: 1-1765@172.21.0.11
  15.    CSeq: 1 INVITE
  16.    Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,NOTIFY,MESSAGE,SUBSCRIBE,INFO
  17.    Content-Type: application/sdp
  18.    User-Agent: eyeBeam release 9330a stamp 37653
  19.    Content-Length:   434
  20.    Server: SIPp Server
  21.    P-Asserted-Identity: "3613612209"  <sip:3613612209@webrtc.example.com>
  22.    Contact: <sip:siplb@172.21.0.11:5061;abcct=7369703a31302e3131322e32362e38353a35303630>
  23.    
  24.    v=0
  25.    o=ipjib 1487263187420 1487263187420 IN IP4 10.112.48.79
  26.    s=CounterPath eyeBeam 1.5
  27.    c=IN IP4 10.112.48.79
  28.    t=0 0
  29.    m=audio 60578 RTP/AVP 119 6 0 98 8 3 5 101
  30.    a=alt:1 2 : FwGzks7j aVPEMhBL 10.112.48.79 60578
  31.    a=alt:2 1 : NxVGnjOA 78sxYW0G 10.112.200.224 60578
  32.    a=fmtp:101 0-15
  33.    a=rtpmap:119 BV32-FEC/16000
  34.    a=rtpmap:98 iLBC/8000
  35.    a=rtpmap:101 telephone-event/8000
  36.    a=sendrecv
  37.    a=x-rtp-session-id:397A5FF0FB494996B741C71582F2FEAC
  38.    ------------------------------------------------------------------------
  39. tport.c:3023 tport_deliver() tport_deliver(0x7f6024004530): msg 0x7f602400fd20 (1221 bytes) from udp/172.21.0.12:5080/sip next=(nil)
  40. nta.c:2880 agent_recv_request() nta: received INVITE sip:3613612202@172.21.0.10:5080 SIP/2.0 (CSeq 1)
  41. nta.c:3248 agent_aliases() nta: canonizing sip:3613612202@172.21.0.10:5080 with contact
  42. nta.c:3085 agent_recv_request() nta: INVITE (1) going to a default leg
  43. nta.c:1350 set_timeout() nta: timer set to 200 ms
  44. nua_server.c:102 nua_stack_process_request() nua: nua_stack_process_request: entering
  45. nua_stack.c:899 nh_create() nua: nh_create: entering
  46. nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering
  47. nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
  48. soa.c:280 soa_clone() soa_clone(static::0x7f6024001930, 0x7f6024001130, 0x7f602400e290) called
  49. soa.c:403 soa_set_params() soa_set_params(static::0x7f602400eb60, ...) called
  50. nta.c:4417 nta_leg_tcreate() nta_leg_tcreate(0x7f602400f470)
  51. soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::0x7f602400eb60) called
  52. soa.c:1171 soa_set_remote_sdp() soa_set_remote_sdp(static::0x7f602400eb60, (nil), 0x7f6024006533, 434) called
  53. nua_dialog.c:338 nua_dialog_usage_add() nua(0x7f602400e290): adding session usage
  54. tport.c:3257 tport_tsend() tport_tsend(0x7f6024004530) tpn = UDP/172.21.0.12:5060
  55. tport.c:4046 tport_resolve() tport_resolve addrinfo = 172.21.0.12:5060
  56. tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7f6024004530): not found by name UDP/172.21.0.12:5060
  57. tport.c:3594 tport_vsend() tport_vsend(0x7f6024004530): 439 bytes of 439 to udp/172.21.0.12:5060
  58. tport.c:3492 tport_send_msg() tport_vsend returned 439
  59. send 439 bytes to udp/[172.21.0.12]:5060 at 11:49:23.669117:
  60.    ------------------------------------------------------------------------
  61.    SIP/2.0 100 Trying
  62.    Via: SIP/2.0/UDP 172.21.0.12;branch=z9hG4bK1ecb.ff4d1330fdf889a72137635ea4ba63d8.0
  63.    Via: SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1765-1-0
  64.    Record-Route: <sip:172.21.0.12;lr=on;ftag=1;did=aac.deb2>
  65.    From: "3613612209" <sip:3613612209@webrtc.example.com>;tag=1
  66.    To: "3613612202" <sip:3613612202@webrtc.example.com>
  67.    Call-ID: 1-1765@172.21.0.11
  68.    CSeq: 1 INVITE
  69.    User-Agent: Voicis WebRTC Gateway
  70.    Content-Length: 0
  71.    
  72.    ------------------------------------------------------------------------
  73. nta.c:6791 incoming_reply() nta: sent 100 Trying for INVITE (1)
  74. nua_stack.c:271 nua_stack_event() nua(0x7f602400e290): event i_invite 100 Trying
  75. nua_session.c:4139 signal_call_state_change() nua(0x7f602400e290): call state changed: init -> received, received offer
  76. soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::0x7f602400eb60, [0x7f604a04a8c8], [0x7f604a04a8d0], [(nil)]) called
  77. nua_stack.c:271 nua_stack_event() nua(0x7f602400e290): event i_state 100 Trying
  78. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  79. nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering
  80. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [NOTICE] switch_channel.c:1104 New Channel sofia/external/3613612209@webrtc.example.com [810f2514-ac39-4e49-b8fe-2e2adb046646]
  81. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  82. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_state_machine.c:584 (sofia/external/3613612209@webrtc.example.com) Running State Change CS_NEW (Cur 1 Tot 9)
  83. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  84. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] sofia.c:9819 sofia/external/3613612209@webrtc.example.com receiving invite from 172.21.0.12:5060 version: 1.6.15 -32-bec4538 64bit
  85. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  86. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  87. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] sofia.c:7042 Channel sofia/external/3613612209@webrtc.example.com entering state [received][100]
  88. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] sofia.c:7052 Remote SDP:
  89. 810f2514-ac39-4e49-b8fe-2e2adb046646 v=0
  90. 810f2514-ac39-4e49-b8fe-2e2adb046646 o=ipjib 1487263187420 1487263187420 IN IP4 10.112.48.79
  91. 810f2514-ac39-4e49-b8fe-2e2adb046646 s=CounterPath eyeBeam 1.5
  92. 810f2514-ac39-4e49-b8fe-2e2adb046646 c=IN IP4 10.112.48.79
  93. 810f2514-ac39-4e49-b8fe-2e2adb046646 t=0 0
  94. 810f2514-ac39-4e49-b8fe-2e2adb046646 m=audio 60578 RTP/AVP 119 6 0 98 8 3 5 101
  95. 810f2514-ac39-4e49-b8fe-2e2adb046646 a=rtpmap:119 BV32-FEC/16000
  96. 810f2514-ac39-4e49-b8fe-2e2adb046646 a=rtpmap:98 iLBC/8000
  97. 810f2514-ac39-4e49-b8fe-2e2adb046646 a=rtpmap:101 telephone-event/8000
  98. 810f2514-ac39-4e49-b8fe-2e2adb046646 a=fmtp:101 0-15
  99. 810f2514-ac39-4e49-b8fe-2e2adb046646 a=alt:1 2 : FwGzks7j aVPEMhBL 10.112.48.79 60578
  100. 810f2514-ac39-4e49-b8fe-2e2adb046646 a=alt:2 1 : NxVGnjOA 78sxYW0G 10.112.200.224 60578
  101. 810f2514-ac39-4e49-b8fe-2e2adb046646 a=x-rtp-session-id:397A5FF0FB494996B741C71582F2FEAC
  102. 810f2514-ac39-4e49-b8fe-2e2adb046646
  103. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [BV32-FEC:119:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
  104. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [BV32-FEC:119:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
  105. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [DVI4:6:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
  106. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [DVI4:6:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
  107. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  108. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  109. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_media.c:4455 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  110. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [iLBC:98:8000:30:13330:1]/[PCMA:8:8000:20:64000:1]
  111. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [iLBC:98:8000:30:13330:1]/[PCMU:0:8000:20:64000:1]
  112. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  113. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_media.c:4455 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  114. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  115. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  116. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  117. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [DVI4:5:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  118. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [DVI4:5:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  119. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_media.c:4316 Set telephone-event payload to 101@8000
  120. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_media.c:3047 Set Codec sofia/external/3613612209@webrtc.example.com PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  121. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_codec.c:111 sofia/external/3613612209@webrtc.example.com Original read codec set to PCMU:0
  122. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_media.c:4659 Set telephone-event payload to 101@8000
  123. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_media.c:4718 sofia/external/3613612209@webrtc.example.com Set 2833 dtmf send payload to 101 recv payload to 101
  124. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] sofia.c:7465 (sofia/external/3613612209@webrtc.example.com) State Change CS_NEW -> CS_INIT
  125. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  126. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_state_machine.c:603 (sofia/external/3613612209@webrtc.example.com) State NEW
  127. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_state_machine.c:584 (sofia/external/3613612209@webrtc.example.com) Running State Change CS_INIT (Cur 1 Tot 9)
  128. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_state_machine.c:627 (sofia/external/3613612209@webrtc.example.com) State INIT
  129. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] mod_sofia.c:90 sofia/external/3613612209@webrtc.example.com SOFIA INIT
  130. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_state_machine.c:40 sofia/external/3613612209@webrtc.example.com Standard INIT
  131. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_state_machine.c:48 (sofia/external/3613612209@webrtc.example.com) State Change CS_INIT -> CS_ROUTING
  132. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_state_machine.c:627 (sofia/external/3613612209@webrtc.example.com) State INIT going to sleep
  133. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_state_machine.c:584 (sofia/external/3613612209@webrtc.example.com) Running State Change CS_ROUTING (Cur 1 Tot 9)
  134. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_channel.c:2249 (sofia/external/3613612209@webrtc.example.com) Callstate Change DOWN -> RINGING
  135. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_state_machine.c:643 (sofia/external/3613612209@webrtc.example.com) State ROUTING
  136. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] mod_sofia.c:143 sofia/external/3613612209@webrtc.example.com SOFIA ROUTING
  137. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_state_machine.c:236 sofia/external/3613612209@webrtc.example.com Standard ROUTING
  138. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [INFO] mod_dialplan_xml.c:637 Processing 3613612209 <3613612209>->3613612202 in context public
  139. 810f2514-ac39-4e49-b8fe-2e2adb046646 Dialplan: sofia/external/3613612209@webrtc.example.com parsing [public->call_debug] continue=true
  140. 810f2514-ac39-4e49-b8fe-2e2adb046646 Dialplan: sofia/external/3613612209@webrtc.example.com Absolute Condition [call_debug]
  141. 810f2514-ac39-4e49-b8fe-2e2adb046646 Dialplan: sofia/external/3613612209@webrtc.example.com Action info()
  142. 810f2514-ac39-4e49-b8fe-2e2adb046646 Dialplan: sofia/external/3613612209@webrtc.example.com parsing [public->public_extensions] continue=false
  143. 810f2514-ac39-4e49-b8fe-2e2adb046646 Dialplan: sofia/external/3613612209@webrtc.example.com Absolute Condition [public_extensions]
  144. 810f2514-ac39-4e49-b8fe-2e2adb046646 Dialplan: sofia/external/3613612209@webrtc.example.com Action bridge(${verto_contact(${destination_number}@webrtc.example.com)})
  145. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_state_machine.c:286 (sofia/external/3613612209@webrtc.example.com) State Change CS_ROUTING -> CS_EXECUTE
  146. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_state_machine.c:643 (sofia/external/3613612209@webrtc.example.com) State ROUTING going to sleep
  147. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_state_machine.c:584 (sofia/external/3613612209@webrtc.example.com) Running State Change CS_EXECUTE (Cur 1 Tot 9)
  148. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_state_machine.c:650 (sofia/external/3613612209@webrtc.example.com) State EXECUTE
  149. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] mod_sofia.c:198 sofia/external/3613612209@webrtc.example.com SOFIA EXECUTE
  150. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_core_state_machine.c:328 sofia/external/3613612209@webrtc.example.com Standard EXECUTE
  151. 810f2514-ac39-4e49-b8fe-2e2adb046646 EXECUTE sofia/external/3613612209@webrtc.example.com info()
  152. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [INFO] mod_dptools.c:1740 CHANNEL_DATA:
  153. 810f2514-ac39-4e49-b8fe-2e2adb046646 Channel-State: [CS_EXECUTE]
  154. 810f2514-ac39-4e49-b8fe-2e2adb046646 Channel-Call-State: [RINGING]
  155. 810f2514-ac39-4e49-b8fe-2e2adb046646 Channel-State-Number: [4]
  156. 810f2514-ac39-4e49-b8fe-2e2adb046646 Channel-Name: [sofia/external/3613612209@webrtc.example.com]
  157. 810f2514-ac39-4e49-b8fe-2e2adb046646 Unique-ID: [810f2514-ac39-4e49-b8fe-2e2adb046646]
  158. 810f2514-ac39-4e49-b8fe-2e2adb046646 Call-Direction: [inbound]
  159. 810f2514-ac39-4e49-b8fe-2e2adb046646 Presence-Call-Direction: [inbound]
  160. 810f2514-ac39-4e49-b8fe-2e2adb046646 Channel-HIT-Dialplan: [true]
  161. 810f2514-ac39-4e49-b8fe-2e2adb046646 Channel-Call-UUID: [810f2514-ac39-4e49-b8fe-2e2adb046646]
  162. 810f2514-ac39-4e49-b8fe-2e2adb046646 Answer-State: [ringing]
  163. 810f2514-ac39-4e49-b8fe-2e2adb046646 Channel-Read-Codec-Name: [PCMU]
  164. 810f2514-ac39-4e49-b8fe-2e2adb046646 Channel-Read-Codec-Rate: [8000]
  165. 810f2514-ac39-4e49-b8fe-2e2adb046646 Channel-Read-Codec-Bit-Rate: [64000]
  166. 810f2514-ac39-4e49-b8fe-2e2adb046646 Channel-Write-Codec-Name: [PCMU]
  167. 810f2514-ac39-4e49-b8fe-2e2adb046646 Channel-Write-Codec-Rate: [8000]
  168. 810f2514-ac39-4e49-b8fe-2e2adb046646 Channel-Write-Codec-Bit-Rate: [64000]
  169. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Direction: [inbound]
  170. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Logical-Direction: [inbound]
  171. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Username: [3613612209]
  172. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Dialplan: [XML]
  173. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Caller-ID-Name: [3613612209]
  174. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Caller-ID-Number: [3613612209]
  175. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Orig-Caller-ID-Name: [3613612209]
  176. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Orig-Caller-ID-Number: [3613612209]
  177. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Network-Addr: [172.21.0.12]
  178. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-ANI: [3613612209]
  179. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Destination-Number: [3613612202]
  180. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Unique-ID: [810f2514-ac39-4e49-b8fe-2e2adb046646]
  181. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Source: [mod_sofia]
  182. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Context: [public]
  183. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Channel-Name: [sofia/external/3613612209@webrtc.example.com]
  184. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Profile-Index: [1]
  185. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Profile-Created-Time: [1487936963669056]
  186. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Channel-Created-Time: [1487936963669056]
  187. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Channel-Answered-Time: [0]
  188. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Channel-Progress-Time: [0]
  189. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Channel-Progress-Media-Time: [0]
  190. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Channel-Hangup-Time: [0]
  191. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Channel-Transfer-Time: [0]
  192. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Channel-Resurrect-Time: [0]
  193. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Channel-Bridged-Time: [0]
  194. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Channel-Last-Hold: [0]
  195. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Channel-Hold-Accum: [0]
  196. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Screen-Bit: [true]
  197. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Privacy-Hide-Name: [false]
  198. 810f2514-ac39-4e49-b8fe-2e2adb046646 Caller-Privacy-Hide-Number: [false]
  199. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_direction: [inbound]
  200. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_uuid: [810f2514-ac39-4e49-b8fe-2e2adb046646]
  201. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_session_id: [9]
  202. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_from_user: [3613612209]
  203. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_from_uri: [3613612209@webrtc.example.com]
  204. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_from_host: [webrtc.example.com]
  205. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_video_media_flow: [sendrecv]
  206. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_channel_name: [sofia/external/3613612209@webrtc.example.com]
  207. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_call_id: [1-1765@172.21.0.11]
  208. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_ep_codec_string: [CORE_PCM_MODULE.PCMU@8000h@20i@64000b,CORE_PCM_MODULE.PCMA@8000h@20i@64000b]
  209. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_local_network_addr: [172.21.0.10]
  210. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_network_ip: [172.21.0.12]
  211. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_network_port: [5060]
  212. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_received_ip: [172.21.0.12]
  213. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_received_port: [5060]
  214. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_via_protocol: [udp]
  215. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_from_user_stripped: [3613612209]
  216. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_from_tag: [1]
  217. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sofia_profile_name: [external]
  218. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_recovery_profile_name: [external]
  219. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_P-Asserted-Identity: ["3613612209" <sip:3613612209@webrtc.example.com>]
  220. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_cid_type: [pid]
  221. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_invite_route_uri: [<sip:172.21.0.12;lr=on;ftag=1;did=aac.deb2>]
  222. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_invite_record_route: [<sip:172.21.0.12;lr=on;ftag=1;did=aac.deb2>]
  223. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_full_via: [SIP/2.0/UDP 172.21.0.12;branch=z9hG4bK1ecb.ff4d1330fdf889a72137635ea4ba63d8.0,SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1765-1-0]
  224. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_from_display: [3613612209]
  225. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_full_from: ["3613612209" <sip:3613612209@webrtc.example.com>;tag=1]
  226. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_to_display: [3613612202]
  227. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_full_to: ["3613612202" <sip:3613612202@webrtc.example.com>]
  228. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_allow: [INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO]
  229. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_req_user: [3613612202]
  230. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_req_port: [5080]
  231. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_req_uri: [3613612202@172.21.0.10:5080]
  232. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_req_host: [172.21.0.10]
  233. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_to_user: [3613612202]
  234. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_to_uri: [3613612202@webrtc.example.com]
  235. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_to_host: [webrtc.example.com]
  236. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_contact_params: [abcct=7369703a31302e3131322e32362e38353a35303630]
  237. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_contact_user: [siplb]
  238. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_contact_port: [5061]
  239. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_contact_uri: [siplb@172.21.0.11:5061]
  240. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_contact_host: [172.21.0.11]
  241. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_user_agent: [eyeBeam release 9330a stamp 37653]
  242. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_via_host: [172.21.0.12]
  243. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_max_forwards: [43]
  244. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_i_from: ["3613612209" <sip:3613612209@webrtc.example.com>;tag=1]
  245. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_i_to: ["3613612202" <sip:3613612202@webrtc.example.com>]
  246. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_i_call_id: [1-1765@172.21.0.11]
  247. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_i_cseq: [1 INVITE]
  248. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_i_max_forwards: [43]
  249. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_i_contact: [<sip:siplb@172.21.0.11:5061;abcct=7369703a31302e3131322e32362e38353a35303630>]
  250. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_i_user_agent: [eyeBeam release 9330a stamp 37653]
  251. 810f2514-ac39-4e49-b8fe-2e2adb046646 variable_sip_i_allow: [INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO]
  252. variable_sip_i_content_type: [application/sdp]
  253. variable_sip_i_content_length: [434]
  254. variable_sip_i_via: [ARRAY::SIP/2.0/UDP 172.21.0.12;branch=z9hG4bK1ecb.ff4d1330fdf889a72137635ea4ba63d8.0|:SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1765-1-0]
  255. variable_sip_i_record_route: [<sip:172.21.0.12;lr=on;ftag=1;did=aac.deb2>]
  256. variable_sip_i_p_asserted_identity: ["3613612209" <sip:3613612209@webrtc.example.com>]
  257. variable_switch_r_sdp: [v=0
  258. o=ipjib 1487263187420 1487263187420 IN IP4 10.112.48.79
  259. s=CounterPath eyeBeam 1.5
  260. c=IN IP4 10.112.48.79
  261. t=0 0
  262. m=audio 60578 RTP/AVP 119 6 0 98 8 3 5 101
  263. a=rtpmap:119 BV32-FEC/16000
  264. a=rtpmap:98 iLBC/8000
  265. a=rtpmap:101 telephone-event/8000
  266. a=fmtp:101 0-15
  267. a=alt:1 2 : FwGzks7j aVPEMhBL 10.112.48.79 60578
  268. a=alt:2 1 : NxVGnjOA 78sxYW0G 10.112.200.224 60578
  269. a=x-rtp-session-id:397A5FF0FB494996B741C71582F2FEAC
  270. ]
  271. variable_audio_media_flow: [sendrecv]
  272. variable_rtp_use_codec_string: [PCMA,PCMU]
  273. variable_remote_media_ip: [10.112.48.79]
  274. variable_remote_media_port: [60578]
  275. variable_rtp_audio_recv_pt: [0]
  276. variable_rtp_use_codec_name: [PCMU]
  277. variable_rtp_use_codec_rate: [8000]
  278. variable_rtp_use_codec_ptime: [20]
  279. variable_rtp_use_codec_channels: [1]
  280. variable_rtp_last_audio_codec_string: [PCMU@8000h@20i@1c]
  281. variable_read_codec: [PCMU]
  282. variable_original_read_codec: [PCMU]
  283. variable_read_rate: [8000]
  284. variable_original_read_rate: [8000]
  285. variable_write_codec: [PCMU]
  286. variable_write_rate: [8000]
  287. variable_dtmf_type: [rfc2833]
  288. variable_endpoint_disposition: [RECEIVED]
  289. variable_call_uuid: [810f2514-ac39-4e49-b8fe-2e2adb046646]
  290. variable_current_application: [info]
  291.  
  292.  
  293.  
  294. 810f2514-ac39-4e49-b8fe-2e2adb046646 EXECUTE sofia/external/3613612209@webrtc.example.com bridge(verto.rtc/u:d5c3b5fa-9ccf-0b60-a6b7-027231713d4a)
  295. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] switch_ivr_originate.c:2138 Parsing global variables
  296. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:23.669056 [DEBUG] mod_rtc.c:389 () State Change CS_NEW -> CS_INIT
  297. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:23.669056 [NOTICE] switch_channel.c:1104 New Channel verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a [5749daf1-911f-4eff-81ba-d53899e6f055]
  298. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:23.669056 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) Running State Change CS_INIT (Cur 2 Tot 10)
  299. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:23.669056 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State INIT
  300. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:23.669056 [DEBUG] mod_verto.c:2218 Local verto.invite SDP verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a:
  301. 5749daf1-911f-4eff-81ba-d53899e6f055 v=0
  302. 5749daf1-911f-4eff-81ba-d53899e6f055 o=FreeSWITCH 1487909137 1487909138 IN IP4 172.21.0.10
  303. 5749daf1-911f-4eff-81ba-d53899e6f055 s=FreeSWITCH
  304. 5749daf1-911f-4eff-81ba-d53899e6f055 c=IN IP4 172.21.0.10
  305. 5749daf1-911f-4eff-81ba-d53899e6f055 t=0 0
  306. 5749daf1-911f-4eff-81ba-d53899e6f055 a=msid-semantic: WMS vvxOxmW8W9C6XtfQIdZ0zepSo3Ny1nfm
  307. 5749daf1-911f-4eff-81ba-d53899e6f055 m=audio 27826 RTP/SAVPF 0
  308. 5749daf1-911f-4eff-81ba-d53899e6f055 a=rtpmap:0 PCMU/8000
  309. 5749daf1-911f-4eff-81ba-d53899e6f055 a=fingerprint:sha-256 41:97:2A:DD:7A:AD:58:E8:BC:50:42:80:46:1D:35:55:CE:58:97:C3:93:7A:2F:93:70:0C:8B:CE:77:B8:1A:42
  310. 5749daf1-911f-4eff-81ba-d53899e6f055 a=setup:actpass
  311. 5749daf1-911f-4eff-81ba-d53899e6f055 a=rtcp-mux
  312. 5749daf1-911f-4eff-81ba-d53899e6f055 a=rtcp:27826 IN IP4 172.21.0.10
  313. 5749daf1-911f-4eff-81ba-d53899e6f055 a=ssrc:1018326419 cname:ID16PN4CNxzAQEgp
  314. 5749daf1-911f-4eff-81ba-d53899e6f055 a=ssrc:1018326419 msid:vvxOxmW8W9C6XtfQIdZ0zepSo3Ny1nfm a0
  315. 5749daf1-911f-4eff-81ba-d53899e6f055 a=ssrc:1018326419 mslabel:vvxOxmW8W9C6XtfQIdZ0zepSo3Ny1nfm
  316. 5749daf1-911f-4eff-81ba-d53899e6f055 a=ssrc:1018326419 label:vvxOxmW8W9C6XtfQIdZ0zepSo3Ny1nfma0
  317. 5749daf1-911f-4eff-81ba-d53899e6f055 a=ice-ufrag:I6f7buPpotOfGUsf
  318. 5749daf1-911f-4eff-81ba-d53899e6f055 a=ice-pwd:njb3X6vAtU09p7oX4uU5PAk3
  319. 5749daf1-911f-4eff-81ba-d53899e6f055 a=candidate:6230667560 1 udp 659136 172.21.0.10 27826 typ host generation 0
  320. 5749daf1-911f-4eff-81ba-d53899e6f055 a=candidate:6230667560 2 udp 659136 172.21.0.10 27826 typ host generation 0
  321. 5749daf1-911f-4eff-81ba-d53899e6f055 a=silenceSupp:off - - - -
  322. 5749daf1-911f-4eff-81ba-d53899e6f055 a=ptime:20
  323. 5749daf1-911f-4eff-81ba-d53899e6f055 a=sendrecv
  324. 5749daf1-911f-4eff-81ba-d53899e6f055
  325. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:23.669056 [NOTICE] mod_verto.c:2318 Ring-Ready verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a!
  326. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:23.669056 [DEBUG] switch_channel.c:3345 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) Callstate Change DOWN -> RINGING
  327. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:23.669056 [DEBUG] switch_core_state_machine.c:40 verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a Standard INIT
  328. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:23.669056 [DEBUG] switch_core_state_machine.c:48 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State Change CS_INIT -> CS_ROUTING
  329. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:23.669056 [DEBUG] switch_core_state_machine.c:627 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State INIT going to sleep
  330. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:23.669056 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) Running State Change CS_ROUTING (Cur 2 Tot 10)
  331. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:23.669056 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State ROUTING
  332. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:23.669056 [DEBUG] mod_rtc.c:89 verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a RTC ROUTING
  333. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:23.669056 [DEBUG] switch_ivr_originate.c:67 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  334. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:23.669056 [DEBUG] switch_core_state_machine.c:643 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State ROUTING going to sleep
  335. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:23.669056 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 10)
  336. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:23.669056 [DEBUG] switch_core_state_machine.c:662 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State CONSUME_MEDIA
  337. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:23.669056 [DEBUG] switch_core_state_machine.c:662 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State CONSUME_MEDIA going to sleep
  338. nua.c:879 nua_respond() nua: nua_respond: entering
  339. nua_stack.c:529 nua_signal() nua(0x7f602400e290): sent signal r_respond
  340. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [NOTICE] mod_sofia.c:2263 Ring-Ready sofia/external/3613612209@webrtc.example.com!
  341. nua_stack.c:573 nua_stack_signal() nua(0x7f602400e290): recv signal r_respond 180 Ringing
  342. nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
  343. soa.c:403 soa_set_params() soa_set_params(static::0x7f602400eb60, ...) called
  344. nua_session.c:2320 nua_invite_server_respond() nua: nua_invite_server_respond: entering
  345. tport.c:3257 tport_tsend() tport_tsend(0x7f6024004530) tpn = UDP/172.21.0.12:5060
  346. tport.c:4046 tport_resolve() tport_resolve addrinfo = 172.21.0.12:5060
  347. tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7f6024004530): not found by name UDP/172.21.0.12:5060
  348. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [NOTICE] switch_ivr_originate.c:525 Ring Ready sofia/external/3613612209@webrtc.example.com!
  349. tport.c:3594 tport_vsend() tport_vsend(0x7f6024004530): 810 bytes of 810 to udp/172.21.0.12:5060
  350. tport.c:3492 tport_send_msg() tport_vsend returned 810
  351. send 810 bytes to udp/[172.21.0.12]:5060 at 11:49:23.678125:
  352.    ------------------------------------------------------------------------
  353.    SIP/2.0 180 Ringing
  354.    Via: SIP/2.0/UDP 172.21.0.12;branch=z9hG4bK1ecb.ff4d1330fdf889a72137635ea4ba63d8.0
  355.    Via: SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1765-1-0
  356.    Record-Route: <sip:172.21.0.12;lr=on;ftag=1;did=aac.deb2>
  357.    From: "3613612209" <sip:3613612209@webrtc.example.com>;tag=1
  358.    To: "3613612202" <sip:3613612202@webrtc.example.com>;tag=1DK7cet40aNjD
  359.    Call-ID: 1-1765@172.21.0.11
  360.    CSeq: 1 INVITE
  361.    Contact: <sip:3613612202@172.21.0.10:5080;transport=udp>
  362.    User-Agent: Voicis WebRTC Gateway
  363.    Accept: application/sdp
  364.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  365.    Supported: timer, path, replaces
  366.    Allow-Events: talk, hold, conference, refer
  367.    Content-Length: 0
  368.    P-Asserted-Identity: "Outbound Call" <sip:d5c3b5fa-9ccf-0b60-a6b7-027231713d4a@webrtc.example.com>
  369.    
  370.    ------------------------------------------------------------------------
  371. nta.c:6791 incoming_reply() nta: sent 180 Ringing for INVITE (1)
  372. nua_session.c:4139 signal_call_state_change() nua(0x7f602400e290): call state changed: received -> early
  373. nua_stack.c:271 nua_stack_event() nua(0x7f602400e290): event i_state 180 Ringing
  374. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  375. 2017-02-24 11:49:23.669056 [ALERT] mod_verto.c:604 WRITE 172.21.0.1:45670 [{
  376.         "jsonrpc":      "2.0",
  377.         "id":   9,
  378.         "method":       "verto.invite",
  379.         "params":       {
  380.                 "callID":       "5749daf1-911f-4eff-81ba-d53899e6f055",
  381.                 "sdp":  "v=0\r\no=FreeSWITCH 1487909137 1487909138 IN IP4 172.21.0.10\r\ns=FreeSWITCH\r\nc=IN IP4 172.21.0.10\r\nt=0 0\r\na=msid-semantic: WMS vvxOxmW8W9C6XtfQIdZ0zepSo3Ny1nfm\r\nm=audio 27826 RTP/SAVPF 0\r\na=rtpmap:0 PCMU/8000\r\na=fingerprint:sha-256 41:97:2A:DD:7A:AD:58:E8:BC:50:42:80:46:1D:35:55:CE:58:97:C3:93:7A:2F:93:70:0C:8B:CE:77:B8:1A:42\r\na=setup:actpass\r\na=rtcp-mux\r\na=rtcp:27826 IN IP4 172.21.0.10\r\na=ssrc:1018326419 cname:ID16PN4CNxzAQEgp\r\na=ssrc:1018326419 msid:vvxOxmW8W9C6XtfQIdZ0zepSo3Ny1nfm a0\r\na=ssrc:1018326419 mslabel:vvxOxmW8W9C6XtfQIdZ0zepSo3Ny1nfm\r\na=ssrc:1018326419 label:vvxOxmW8W9C6XtfQIdZ0zepSo3Ny1nfma0\r\na=ice-ufrag:I6f7buPpotOfGUsf\r\na=ice-pwd:njb3X6vAtU09p7oX4uU5PAk3\r\na=candidate:6230667560 1 udp 659136 172.21.0.10 27826 typ host generation 0\r\na=candidate:6230667560 2 udp 659136 172.21.0.10 27826 typ host generation 0\r\na=silenceSupp:off - - - -\r\na=ptime:20\r\na=sendrecv\r\n",
  382.                 "caller_id_name":       "3613612209",
  383.                 "caller_id_number":     "3613612209",
  384.                 "callee_id_name":       "Outbound Call",
  385.                 "callee_id_number":     "d5c3b5fa-9ccf-0b60-a6b7-027231713d4a",
  386.                 "display_direction":    "outbound"
  387.         }
  388. }]
  389. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  390. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:23.669056 [DEBUG] sofia.c:7042 Channel sofia/external/3613612209@webrtc.example.com entering state [early][180]
  391. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  392. 2017-02-24 11:49:23.708713 [ALERT] mod_verto.c:1373 READ 172.21.0.1:45670 [{
  393.         "jsonrpc":      "2.0",
  394.         "id":   9,
  395.         "result":       {
  396.                 "method":       "verto.invite"
  397.         }
  398. }]
  399. nta.c:1296 agent_timer() nta: timer set next to 59810 ms
  400. 2017-02-24 11:49:30.348710 [ALERT] mod_verto.c:1373 READ 172.21.0.1:45670 [{
  401.         "jsonrpc":      "2.0",
  402.         "method":       "verto.answer",
  403.         "params":       {
  404.                 "sdp":  "v=0\r\no=- 2894448575661892366 2 IN IP4 127.0.0.1\r\ns=-\r\nt=0 0\r\na=msid-semantic: WMS EK0UNUUr9W2DIwXDN7aZDr7iN7wikXZ2hYlQ\r\nm=audio 42477 RTP/SAVPF 0\r\nc=IN IP4 172.17.0.1\r\na=rtcp:9 IN IP4 0.0.0.0\r\na=candidate:3885250869 1 udp 2122260223 172.17.0.1 42477 typ host generation 0 network-id 2 network-cost 50\r\na=candidate:410389623 1 udp 2122194687 172.21.0.1 45448 typ host generation 0 network-id 1 network-cost 50\r\na=candidate:88617392 1 udp 2122129151 10.178.17.174 41805 typ host generation 0 network-id 4\r\na=candidate:1019731727 1 udp 2122063615 192.168.1.104 33126 typ host generation 0 network-id 3\r\na=candidate:2836907461 1 tcp 1518280447 172.17.0.1 9 typ host tcptype active generation 0 network-id 2 network-cost 50\r\na=candidate:1458958471 1 tcp 1518214911 172.21.0.1 9 typ host tcptype active generation 0 network-id 1 network-cost 50\r\na=candidate:1271647552 1 tcp 1518149375 10.178.17.174 9 typ host tcptype active generation 0 network-id 4\r\na=candidate:1917068287 1 tcp 1518083839 192.168.1.104 9 typ host tcptype active generation 0 network-id 3\r\na=ice-ufrag:zcgf\r\na=ice-pwd:xnmyy4hBg/BhKkAWsWFt32H6\r\na=fingerprint:sha-256 94:5C:5D:F4:19:6A:97:A9:3B:06:A1:B5:E9:EB:DA:C0:E9:44:E9:90:1A:B3:D8:01:EB:51:A2:C3:73:65:A5:4F\r\na=setup:active\r\na=mid:audio\r\na=sendrecv\r\na=rtcp-mux\r\na=rtpmap:0 PCMU/8000\r\na=ssrc:568988862 cname:Rxz2Rr65tvR/99/O\r\na=ssrc:568988862 msid:EK0UNUUr9W2DIwXDN7aZDr7iN7wikXZ2hYlQ b524ddbc-ddbf-46b3-b0d0-641a54fb1d14\r\na=ssrc:568988862 mslabel:EK0UNUUr9W2DIwXDN7aZDr7iN7wikXZ2hYlQ\r\na=ssrc:568988862 label:b524ddbc-ddbf-46b3-b0d0-641a54fb1d14\r\n",
  405.                 "dialogParams": {
  406.                         "screenShare":  false,
  407.                         "useMic":       "any",
  408.                         "useSpeak":     "any",
  409.                         "tag":  "webcam",
  410.                         "localTag":     null,
  411.                         "login":        "3613612202@webrtc.example.com",
  412.                         "videoParams":  {
  413.                                 "minWidth":     "1280",
  414.                                 "minHeight":    "720",
  415.                                 "minFrameRate": 30
  416.                         },
  417.                         "callID":       "5749daf1-911f-4eff-81ba-d53899e6f055",
  418.                         "caller_id_name":       "3613612209",
  419.                         "caller_id_number":     "3613612209",
  420.                         "callee_id_name":       "3613612202",
  421.                         "callee_id_number":     "3613612202",
  422.                         "display_direction":    "outbound",
  423.                         "remote_caller_id_name":        "3613612209",
  424.                         "remote_caller_id_number":      "3613612209"
  425.                 },
  426.                 "sessid":       "d5c3b5fa-9ccf-0b60-a6b7-027231713d4a"
  427.         },
  428.         "id":   25
  429. }]
  430. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [DEBUG] mod_verto.c:2673 Remote SDP verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a:
  431. 5749daf1-911f-4eff-81ba-d53899e6f055 v=0
  432. 5749daf1-911f-4eff-81ba-d53899e6f055 o=- 2894448575661892366 2 IN IP4 127.0.0.1
  433. 5749daf1-911f-4eff-81ba-d53899e6f055 s=-
  434. 5749daf1-911f-4eff-81ba-d53899e6f055 t=0 0
  435. 5749daf1-911f-4eff-81ba-d53899e6f055 a=msid-semantic: WMS EK0UNUUr9W2DIwXDN7aZDr7iN7wikXZ2hYlQ
  436. 5749daf1-911f-4eff-81ba-d53899e6f055 m=audio 42477 RTP/SAVPF 0
  437. 5749daf1-911f-4eff-81ba-d53899e6f055 c=IN IP4 172.17.0.1
  438. 5749daf1-911f-4eff-81ba-d53899e6f055 a=rtcp:9 IN IP4 0.0.0.0
  439. 5749daf1-911f-4eff-81ba-d53899e6f055 a=candidate:3885250869 1 udp 2122260223 172.17.0.1 42477 typ host generation 0 network-id 2 network-cost 50
  440. 5749daf1-911f-4eff-81ba-d53899e6f055 a=candidate:410389623 1 udp 2122194687 172.21.0.1 45448 typ host generation 0 network-id 1 network-cost 50
  441. 5749daf1-911f-4eff-81ba-d53899e6f055 a=candidate:88617392 1 udp 2122129151 10.178.17.174 41805 typ host generation 0 network-id 4
  442. 5749daf1-911f-4eff-81ba-d53899e6f055 a=candidate:1019731727 1 udp 2122063615 192.168.1.104 33126 typ host generation 0 network-id 3
  443. 5749daf1-911f-4eff-81ba-d53899e6f055 a=candidate:2836907461 1 tcp 1518280447 172.17.0.1 9 typ host tcptype active generation 0 network-id 2 network-cost 50
  444. 5749daf1-911f-4eff-81ba-d53899e6f055 a=candidate:1458958471 1 tcp 1518214911 172.21.0.1 9 typ host tcptype active generation 0 network-id 1 network-cost 50
  445. 5749daf1-911f-4eff-81ba-d53899e6f055 a=candidate:1271647552 1 tcp 1518149375 10.178.17.174 9 typ host tcptype active generation 0 network-id 4
  446. 5749daf1-911f-4eff-81ba-d53899e6f055 a=candidate:1917068287 1 tcp 1518083839 192.168.1.104 9 typ host tcptype active generation 0 network-id 3
  447. 5749daf1-911f-4eff-81ba-d53899e6f055 a=ice-ufrag:zcgf
  448. 5749daf1-911f-4eff-81ba-d53899e6f055 a=ice-pwd:xnmyy4hBg/BhKkAWsWFt32H6
  449. 5749daf1-911f-4eff-81ba-d53899e6f055 a=fingerprint:sha-256 94:5C:5D:F4:19:6A:97:A9:3B:06:A1:B5:E9:EB:DA:C0:E9:44:E9:90:1A:B3:D8:01:EB:51:A2:C3:73:65:A5:4F
  450. 5749daf1-911f-4eff-81ba-d53899e6f055 a=setup:active
  451. 5749daf1-911f-4eff-81ba-d53899e6f055 a=mid:audio
  452. 5749daf1-911f-4eff-81ba-d53899e6f055 a=sendrecv
  453. 5749daf1-911f-4eff-81ba-d53899e6f055 a=rtcp-mux
  454. 5749daf1-911f-4eff-81ba-d53899e6f055 a=rtpmap:0 PCMU/8000
  455. 5749daf1-911f-4eff-81ba-d53899e6f055 a=ssrc:568988862 cname:Rxz2Rr65tvR/99/O
  456. 5749daf1-911f-4eff-81ba-d53899e6f055 a=ssrc:568988862 msid:EK0UNUUr9W2DIwXDN7aZDr7iN7wikXZ2hYlQ b524ddbc-ddbf-46b3-b0d0-641a54fb1d14
  457. 5749daf1-911f-4eff-81ba-d53899e6f055 a=ssrc:568988862 mslabel:EK0UNUUr9W2DIwXDN7aZDr7iN7wikXZ2hYlQ
  458. 5749daf1-911f-4eff-81ba-d53899e6f055 a=ssrc:568988862 label:b524ddbc-ddbf-46b3-b0d0-641a54fb1d14
  459. 5749daf1-911f-4eff-81ba-d53899e6f055
  460. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [WARNING] switch_ivr.c:3858 can't find user [3613612202@webrtc.example.com]
  461. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  462. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [DEBUG] switch_core_media.c:4455 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  463. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [DEBUG] switch_core_media.c:3047 Set Codec verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  464. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [DEBUG] switch_core_codec.c:111 verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a Original read codec set to PCMU:0
  465. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [DEBUG] switch_core_media.c:3455 Save audio Candidate cid: 1 proto: udp type: host addr: 172.17.0.1:42477
  466. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [DEBUG] switch_core_media.c:3455 Save audio Candidate cid: 1 proto: udp type: host addr: 172.21.0.1:45448
  467. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [DEBUG] switch_core_media.c:3455 Save audio Candidate cid: 1 proto: udp type: host addr: 10.178.17.174:41805
  468. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [DEBUG] switch_core_media.c:3455 Save audio Candidate cid: 1 proto: udp type: host addr: 192.168.1.104:33126
  469. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [DEBUG] switch_core_media.c:3497 Searching for rtp candidate.
  470. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [DEBUG] switch_core_media.c:3502 Choose rtp candidate, index 1, 172.21.0.1:45448
  471. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [DEBUG] switch_core_media.c:3276 verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a choosing family v4
  472. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [DEBUG] switch_core_media.c:3513 Choose same candidate, index 0, for rtcp based on rtcp-mux attribute 172.21.0.1:45448
  473. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [DEBUG] switch_core_media.c:3558 setting remote audio ice addr to index 1 172.21.0.1:45448 based on candidate
  474. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [DEBUG] switch_core_media.c:3579 Setting remote rtcp audio addr to 172.21.0.1:45448 based on candidate
  475. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [DEBUG] switch_core_media.c:4725 No 2833 in SDP.  Disable 2833 dtmf and switch to INFO
  476. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [DEBUG] switch_core_media.c:6732 AUDIO RTP [verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a] 172.21.0.10 port 27826 -> 172.21.0.1 port 45448 codec: 0 ms: 20
  477. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [DEBUG] switch_rtp.c:3878 Starting timer [soft] 160 bytes per 20ms
  478. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [INFO] switch_core_media.c:6914 Activating Audio ICE
  479. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [NOTICE] switch_rtp.c:4367 Activating RTP audio ICE: zcgf:I6f7buPpotOfGUsf 172.21.0.1:45448
  480. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [DEBUG] switch_core_media.c:6957 Activating RTCP PORT 45448
  481. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [DEBUG] switch_rtp.c:4264 RTCP send rate is: 5000 and packet rate is: 20000 Remote Port: 45448
  482. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [INFO] switch_core_media.c:6965 Skipping RTCP ICE (Same as RTP)
  483. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [INFO] switch_rtp.c:3371 Activate RTP/RTCP audio DTLS server
  484. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [INFO] switch_rtp.c:3505 Changing audio DTLS state from OFF to HANDSHAKE
  485. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [DEBUG] switch_core_media.c:2017 Setting Jitterbuffer to 20ms (1 frames) (50 max frames)
  486. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [DEBUG] switch_core_media.c:6715 Audio params are unchanged for verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a.
  487. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [NOTICE] mod_verto.c:2707 Channel [verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a] has been answered
  488. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.348710 [DEBUG] switch_channel.c:3772 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) Callstate Change RINGING -> ACTIVE
  489. 2017-02-24 11:49:30.348710 [ALERT] mod_verto.c:604 WRITE 172.21.0.1:45670 [{
  490.         "jsonrpc":      "2.0",
  491.         "id":   25,
  492.         "result":       {
  493.                 "sessid":       "d5c3b5fa-9ccf-0b60-a6b7-027231713d4a"
  494.         }
  495. }]
  496. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:30.368704 [DEBUG] switch_core_media.c:6732 AUDIO RTP [sofia/external/3613612209@webrtc.example.com] 172.21.0.10 port 18246 -> 10.112.48.79 port 60578 codec: 0 ms: 20
  497. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:30.368704 [DEBUG] switch_rtp.c:3878 Starting timer [soft] 160 bytes per 20ms
  498. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:30.368704 [DEBUG] switch_core_media.c:7038 sofia/external/3613612209@webrtc.example.com Set 2833 dtmf send payload to 101
  499. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:30.368704 [DEBUG] switch_core_media.c:7045 sofia/external/3613612209@webrtc.example.com Set 2833 dtmf receive payload to 101
  500. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:30.368704 [DEBUG] switch_core_media.c:7068 sofia/external/3613612209@webrtc.example.com Set rtp dtmf delay to 40
  501. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:30.368704 [DEBUG] mod_sofia.c:850 Local SDP sofia/external/3613612209@webrtc.example.com:
  502. 810f2514-ac39-4e49-b8fe-2e2adb046646 v=0
  503. 810f2514-ac39-4e49-b8fe-2e2adb046646 o=FreeSWITCH 1487918724 1487918725 IN IP4 172.21.0.10
  504. 810f2514-ac39-4e49-b8fe-2e2adb046646 s=FreeSWITCH
  505. 810f2514-ac39-4e49-b8fe-2e2adb046646 c=IN IP4 172.21.0.10
  506. 810f2514-ac39-4e49-b8fe-2e2adb046646 t=0 0
  507. 810f2514-ac39-4e49-b8fe-2e2adb046646 m=audio 18246 RTP/AVP 0 101
  508. 810f2514-ac39-4e49-b8fe-2e2adb046646 a=rtpmap:0 PCMU/8000
  509. 810f2514-ac39-4e49-b8fe-2e2adb046646 a=rtpmap:101 telephone-event/8000
  510. 810f2514-ac39-4e49-b8fe-2e2adb046646 a=fmtp:101 0-16
  511. 810f2514-ac39-4e49-b8fe-2e2adb046646 a=ptime:20
  512. 810f2514-ac39-4e49-b8fe-2e2adb046646 a=sendrecv
  513. 810f2514-ac39-4e49-b8fe-2e2adb046646
  514. nua.c:879 nua_respond() nua: nua_respond: entering
  515. nua_stack.c:529 nua_signal() nua(0x7f602400e290): sent signal r_respond
  516. nua_stack.c:573 nua_stack_signal() nua(0x7f602400e290): recv signal r_respond 200 OK
  517. nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
  518. soa.c:403 soa_set_params() soa_set_params(static::0x7f602400eb60, ...) called
  519. soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::0x7f602400eb60, (nil), 0x7f5fe400d517, -1) called
  520. soa.c:890 soa_set_capability_sdp() soa_set_capability_sdp(static::0x7f602400eb60, (nil), 0x7f5fe400d517, -1) called
  521. nua_session.c:2320 nua_invite_server_respond() nua: nua_invite_server_respond: entering
  522. soa.c:1515 soa_generate_answer() soa_generate_answer(static::0x7f602400eb60) called
  523. soa_static.c:1148 offer_answer_step() soa_static_offer_answer_action(0x7f602400eb60, soa_generate_answer): called
  524. soa_static.c:1189 offer_answer_step() soa_static(0x7f602400eb60, soa_generate_answer): generating local description
  525. soa_static.c:1230 offer_answer_step() soa_static(0x7f602400eb60, soa_generate_answer): upgrade with remote description
  526. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:30.368704 [NOTICE] switch_ivr_originate.c:3628 Channel [sofia/external/3613612209@webrtc.example.com] has been answered
  527. soa_static.c:1029 soa_sdp_mode_set() soa_sdp_mode_set(0x7f604a048ab0, 0x7f6024010c40, ""): called
  528. soa_static.c:1446 offer_answer_step() soa_static(0x7f602400eb60, soa_generate_answer): storing local description
  529. soa.c:1730 soa_activate() soa_activate(static::0x7f602400eb60, (nil)) called
  530. soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x7f602400eb60, [(nil)], [0x7f604a04ac38], [0x7f604a04ac34]) called
  531. tport.c:3257 tport_tsend() tport_tsend(0x7f6024004530) tpn = UDP/172.21.0.12:5060
  532. tport.c:4046 tport_resolve() tport_resolve addrinfo = 172.21.0.12:5060
  533. tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7f6024004530): not found by name UDP/172.21.0.12:5060
  534. tport.c:3594 tport_vsend() tport_vsend(0x7f6024004530): 1032 bytes of 1032 to udp/172.21.0.12:5060
  535. tport.c:3492 tport_send_msg() tport_vsend returned 1032
  536. send 1032 bytes to udp/[172.21.0.12]:5060 at 11:49:30.371405:
  537.    ------------------------------------------------------------------------
  538.    SIP/2.0 200 OK
  539.    Via: SIP/2.0/UDP 172.21.0.12;branch=z9hG4bK1ecb.ff4d1330fdf889a72137635ea4ba63d8.0
  540.    Via: SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1765-1-0
  541.    Record-Route: <sip:172.21.0.12;lr=on;ftag=1;did=aac.deb2>
  542.    From: "3613612209" <sip:3613612209@webrtc.example.com>;tag=1
  543.    To: "3613612202" <sip:3613612202@webrtc.example.com>;tag=1DK7cet40aNjD
  544.    Call-ID: 1-1765@172.21.0.11
  545.    CSeq: 1 INVITE
  546.    Contact: <sip:3613612202@172.21.0.10:5080;transport=udp>
  547.    User-Agent: Voicis WebRTC Gateway
  548.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  549.    Supported: timer, path, replaces
  550.    Allow-Events: talk, hold, conference, refer
  551.    Content-Type: application/sdp
  552.    Content-Disposition: session
  553.    Content-Length: 218
  554.    P-Asserted-Identity: "3613612202" <sip:3613612202@webrtc.example.com>
  555.    
  556.    v=0
  557.    o=FreeSWITCH 1487918724 1487918725 IN IP4 172.21.0.10
  558.    s=FreeSWITCH
  559.    c=IN IP4 172.21.0.10
  560.    t=0 0
  561.    m=audio 18246 RTP/AVP 0 101
  562.    a=rtpmap:0 PCMU/8000
  563.    a=rtpmap:101 telephone-event/8000
  564.    a=fmtp:101 0-16
  565.    a=ptime:20
  566.    ------------------------------------------------------------------------
  567. nta.c:6791 incoming_reply() nta: sent 200 OK for INVITE (1)
  568. nta.c:1348 set_timeout() nta: timer shortened to 500 ms
  569. nua_session.c:4139 signal_call_state_change() nua(0x7f602400e290): call state changed: early -> completed, sent answer
  570. soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x7f602400eb60, [0x7f604a04ace8], [0x7f604a04acf0], [(nil)]) called
  571. soa.c:616 soa_get_params() soa_get_params(static::0x7f602400eb60, ...) called
  572. nua_stack.c:271 nua_stack_event() nua(0x7f602400e290): event i_state 200 OK
  573. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  574. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:30.368704 [DEBUG] switch_channel.c:3772 (sofia/external/3613612209@webrtc.example.com) Callstate Change RINGING -> ACTIVE
  575. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  576. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:30.368704 [DEBUG] sofia.c:7042 Channel sofia/external/3613612209@webrtc.example.com entering state [completed][200]
  577. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  578. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:30.368704 [DEBUG] switch_ivr_originate.c:3686 Originate Resulted in Success: [verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a]
  579. tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f6024004530): events IN
  580. tport.c:2864 tport_recv_event() tport_recv_event(0x7f6024004530)
  581. tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f6024004530) msg 0x7f602400c080 from (udp/172.21.0.10:5080) has 559 bytes, veclen = 1
  582. recv 559 bytes from udp/[172.21.0.12]:5060 at 11:49:30.373852:
  583.    ------------------------------------------------------------------------
  584.    ACK sip:3613612202@172.21.0.10:5080 SIP/2.0
  585.    Via: SIP/2.0/UDP 172.21.0.12;branch=z9hG4bK1ecb.6e9fe01c3e93fa8fd334c24492f422d7.0
  586.    Via: SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1765-1-4
  587.    Max-Forwards: 43
  588.    To: "3613612202" <sip:3613612202@webrtc.example.com>;tag=1DK7cet40aNjD
  589.    From: "3613612209" <sip:3613612209@webrtc.example.com>;tag=1
  590.    Call-ID: 1-1765@172.21.0.11
  591.    CSeq: 1 ACK
  592.    User-Agent: eyeBeam release 9330a stamp 37653
  593.    Content-Length: 0
  594.    Server: SIPp Server
  595.    Contact: <sip:siplb@172.21.0.11:5061;abcct=7369703a31302e3131322e32362e38353a35303630>
  596.    
  597.    ------------------------------------------------------------------------
  598. tport.c:3023 tport_deliver() tport_deliver(0x7f6024004530): msg 0x7f602400c080 (559 bytes) from udp/172.21.0.12:5080/sip next=(nil)
  599. nta.c:2880 agent_recv_request() nta: received ACK sip:3613612202@172.21.0.10:5080 SIP/2.0 (CSeq 1)
  600. nta.c:3019 agent_recv_request() nta: ACK (1) is going to INVITE (1)
  601. nua_session.c:2569 process_ack_or_cancel() nua: process_ack_or_cancel: entering
  602. soa.c:1214 soa_clear_remote_sdp() soa_clear_remote_sdp(static::0x7f602400eb60) called
  603. nua_stack.c:271 nua_stack_event() nua(0x7f602400e290): event i_ack 200 OK
  604. nua_session.c:4139 signal_call_state_change() nua(0x7f602400e290): call state changed: completed -> ready
  605. nua_stack.c:271 nua_stack_event() nua(0x7f602400e290): event i_state 200 OK
  606. nua_stack.c:271 nua_stack_event() nua(0x7f602400e290): event i_active 200 Call active
  607. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  608. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  609. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  610. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  611. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  612. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  613. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:30.368704 [DEBUG] sofia.c:7042 Channel sofia/external/3613612209@webrtc.example.com entering state [ready][200]
  614. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  615. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  616. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  617. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.368704 [DEBUG] switch_core_media.c:9969 verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a PAUSE Jitterbuffer
  618. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.368704 [DEBUG] switch_ivr_bridge.c:1566 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  619. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.368704 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 10)
  620. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.368704 [DEBUG] switch_core_state_machine.c:653 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State EXCHANGE_MEDIA
  621. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.368704 [DEBUG] mod_rtc.c:146 RTC EXCHANGE_MEDIA
  622. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.408706 [INFO] switch_rtp.c:3162 Changing audio DTLS state from HANDSHAKE to SETUP
  623. 2017-02-24 11:49:30.408706 [ALERT] mod_verto.c:604 WRITE 172.21.0.1:45670 [{
  624.         "jsonrpc":      "2.0",
  625.         "id":   10,
  626.         "method":       "verto.display",
  627.         "params":       {
  628.                 "callID":       "5749daf1-911f-4eff-81ba-d53899e6f055",
  629.                 "display_name": "3613612209",
  630.                 "display_number":       "3613612209",
  631.                 "caller_id_name":       "3613612209",
  632.                 "caller_id_number":     "3613612209",
  633.                 "callee_id_name":       "3613612202",
  634.                 "callee_id_number":     "3613612202",
  635.                 "display_direction":    "outbound"
  636.         }
  637. }]
  638. 2017-02-24 11:49:30.408706 [ALERT] mod_verto.c:1373 READ 172.21.0.1:45670 [{
  639.         "jsonrpc":      "2.0",
  640.         "id":   10,
  641.         "result":       {
  642.                 "method":       "verto.display"
  643.         }
  644. }]
  645. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.428713 [INFO] switch_rtp.c:3071 audio Fingerprint Verified.
  646. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.428713 [INFO] switch_rtp.c:3690 Activating Audio Secure RTP SEND
  647. 2017-02-24 11:49:30.428713 [DEBUG] switch_core_sqldb.c:2617 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80
  648. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.428713 [INFO] switch_rtp.c:3668 Activating Audio Secure RTP RECV
  649. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.428713 [INFO] switch_rtp.c:3111 Changing audio DTLS state from SETUP to READY
  650. 2017-02-24 11:49:30.428713 [DEBUG] switch_core_sqldb.c:2617 Secure Type: srtp:dtls:AES_CM_128_HMAC_SHA1_80
  651. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:30.448708 [DEBUG] switch_rtp.c:1878 rtcp_stats_init: audio ssrc[568988862] base_seq[6658]
  652. nta.c:1296 agent_timer() nta: timer set next to 4502 ms
  653. tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f6024004530): events IN
  654. tport.c:2864 tport_recv_event() tport_recv_event(0x7f6024004530)
  655. tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f6024004530) msg 0x7f602400c080 from (udp/172.21.0.10:5080) has 1006 bytes, veclen = 1
  656. recv 1006 bytes from udp/[172.21.0.12]:5060 at 11:49:32.375481:
  657.    ------------------------------------------------------------------------
  658.    INVITE sip:3613612202@172.21.0.10:5080 SIP/2.0
  659.    Via: SIP/2.0/UDP 172.21.0.12;branch=z9hG4bKedcb.e53a29b4a9efba0ad34db660d2cff5d0.0
  660.    Via: SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1765-1-6
  661.    Max-Forwards: 43
  662.    To: "3613612202" <sip:3613612202@webrtc.example.com>;tag=1DK7cet40aNjD
  663.    From: "3613612209" <sip:3613612209@webrtc.example.com>;tag=1
  664.    Call-ID: 1-1765@172.21.0.11
  665.    CSeq: 2 INVITE
  666.    Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,NOTIFY,MESSAGE,SUBSCRIBE,INFO
  667.    Content-Type: application/sdp
  668.    User-Agent: eyeBeam release 9330a stamp 37653
  669.    Content-Length:   260
  670.    Server: SIPp Server
  671.    P-Asserted-Identity: "3613612209"  <sip:3613612209@webrtc.example.com>
  672.    Contact: <sip:siplb@172.21.0.11:5061;abcct=7369703a31302e3131322e32362e38353a35303630>
  673.    
  674.    v=0
  675.    o=ipjib 1487263187420 1487263187421 IN IP4 10.112.48.79
  676.    s=CounterPath eyeBeam 1.5
  677.    c=IN IP4 0.0.0.0
  678.    t=0 0
  679.    m=audio 60578 RTP/AVP 0 101
  680.    a=fmtp:101 0-15
  681.    a=rtpmap:101 telephone-event/8000
  682.    a=sendonly
  683.    a=x-rtp-session-id:397A5FF0FB494996B741C71582F2FEAC
  684.    ------------------------------------------------------------------------
  685. tport.c:3023 tport_deliver() tport_deliver(0x7f6024004530): msg 0x7f602400c080 (1006 bytes) from udp/172.21.0.12:5080/sip next=(nil)
  686. nta.c:2880 agent_recv_request() nta: received INVITE sip:3613612202@172.21.0.10:5080 SIP/2.0 (CSeq 2)
  687. nta.c:3248 agent_aliases() nta: canonizing sip:3613612202@172.21.0.10:5080 with contact
  688. nta.c:3060 agent_recv_request() nta: INVITE (2) going to existing leg
  689. nta.c:1348 set_timeout() nta: timer shortened to 200 ms
  690. nua_server.c:102 nua_stack_process_request() nua: nua_stack_process_request: entering
  691. soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::0x7f602400eb60) called
  692. soa.c:1171 soa_set_remote_sdp() soa_set_remote_sdp(static::0x7f602400eb60, (nil), 0x7f602400b45a, 260) called
  693. tport.c:3257 tport_tsend() tport_tsend(0x7f6024004530) tpn = UDP/172.21.0.12:5060
  694. tport.c:4046 tport_resolve() tport_resolve addrinfo = 172.21.0.12:5060
  695. tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7f6024004530): not found by name UDP/172.21.0.12:5060
  696. tport.c:3594 tport_vsend() tport_vsend(0x7f6024004530): 398 bytes of 398 to udp/172.21.0.12:5060
  697. tport.c:3492 tport_send_msg() tport_vsend returned 398
  698. send 398 bytes to udp/[172.21.0.12]:5060 at 11:49:32.375907:
  699.    ------------------------------------------------------------------------
  700.    SIP/2.0 100 Trying
  701.    Via: SIP/2.0/UDP 172.21.0.12;branch=z9hG4bKedcb.e53a29b4a9efba0ad34db660d2cff5d0.0
  702.    Via: SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1765-1-6
  703.    From: "3613612209" <sip:3613612209@webrtc.example.com>;tag=1
  704.    To: "3613612202" <sip:3613612202@webrtc.example.com>;tag=1DK7cet40aNjD
  705.    Call-ID: 1-1765@172.21.0.11
  706.    CSeq: 2 INVITE
  707.    User-Agent: Voicis WebRTC Gateway
  708.    Content-Length: 0
  709.    
  710.    ------------------------------------------------------------------------
  711. nta.c:6791 incoming_reply() nta: sent 100 Trying for INVITE (2)
  712. nua_stack.c:271 nua_stack_event() nua(0x7f602400e290): event i_invite 100 Trying
  713. nua_session.c:4145 signal_call_state_change() nua(0x7f602400e290): ready call updated: received received offer
  714. soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::0x7f602400eb60, [0x7f604a04a8c8], [0x7f604a04a8d0], [(nil)]) called
  715. nua_stack.c:271 nua_stack_event() nua(0x7f602400e290): event i_state 100 Trying
  716. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  717. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  718. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  719. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  720. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  721. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:32.388712 [DEBUG] sofia.c:7042 Channel sofia/external/3613612209@webrtc.example.com entering state [received][100]
  722. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:32.388712 [DEBUG] sofia.c:7052 Remote SDP:
  723. 810f2514-ac39-4e49-b8fe-2e2adb046646 v=0
  724. 810f2514-ac39-4e49-b8fe-2e2adb046646 o=ipjib 1487263187420 1487263187421 IN IP4 10.112.48.79
  725. 810f2514-ac39-4e49-b8fe-2e2adb046646 s=CounterPath eyeBeam 1.5
  726. 810f2514-ac39-4e49-b8fe-2e2adb046646 c=IN IP4 0.0.0.0
  727. 810f2514-ac39-4e49-b8fe-2e2adb046646 t=0 0
  728. 810f2514-ac39-4e49-b8fe-2e2adb046646 m=audio 60578 RTP/AVP 0 101
  729. 810f2514-ac39-4e49-b8fe-2e2adb046646 a=rtpmap:101 telephone-event/8000
  730. 810f2514-ac39-4e49-b8fe-2e2adb046646 a=fmtp:101 0-15
  731. 810f2514-ac39-4e49-b8fe-2e2adb046646 a=sendonly
  732. 810f2514-ac39-4e49-b8fe-2e2adb046646 a=x-rtp-session-id:397A5FF0FB494996B741C71582F2FEAC
  733. 810f2514-ac39-4e49-b8fe-2e2adb046646
  734. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:32.388712 [WARNING] switch_core_media.c:3895 RFC2543 from March 1999 called; They want their 0.0.0.0 hold method back.....
  735. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:32.388712 [DEBUG] switch_channel.c:1844 (sofia/external/3613612209@webrtc.example.com) Callstate Change ACTIVE -> HELD
  736. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:32.388712 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  737. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:32.388712 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  738. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:32.388712 [DEBUG] switch_core_media.c:4455 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  739. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:32.388712 [DEBUG] switch_core_media.c:4316 Set telephone-event payload to 101@8000
  740. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:32.388712 [DEBUG] switch_core_media.c:4659 Set telephone-event payload to 101@8000
  741. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:32.388712 [DEBUG] switch_core_media.c:4718 sofia/external/3613612209@webrtc.example.com Set 2833 dtmf send payload to 101 recv payload to 101
  742. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:32.388712 [DEBUG] switch_core_media.c:6721 Audio params changed for sofia/external/3613612209@webrtc.example.com from 10.112.48.79:60578 to 0.0.0.0:60578
  743. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:32.388712 [DEBUG] switch_core_media.c:6732 AUDIO RTP [sofia/external/3613612209@webrtc.example.com] 172.21.0.10 port 18246 -> 0.0.0.0 port 60578 codec: 0 ms: 20
  744. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:32.388712 [DEBUG] switch_core_media.c:6761 AUDIO RTP CHANGING DEST TO: [0.0.0.0:60578]
  745. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:32.388712 [DEBUG] sofia.c:7942 Processing updated SDP
  746. nua.c:879 nua_respond() nua: nua_respond: entering
  747. nua_stack.c:529 nua_signal() nua(0x7f602400e290): sent signal r_respond
  748. nua_stack.c:573 nua_stack_signal() nua(0x7f602400e290): recv signal r_respond 200 OK
  749. nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
  750. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  751. soa.c:403 soa_set_params() soa_set_params(static::0x7f602400eb60, ...) called
  752. soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::0x7f602400eb60, (nil), 0x7f5fe400c100, -1) called
  753. nua_session.c:2320 nua_invite_server_respond() nua: nua_invite_server_respond: entering
  754. soa.c:1515 soa_generate_answer() soa_generate_answer(static::0x7f602400eb60) called
  755. soa_static.c:1148 offer_answer_step() soa_static_offer_answer_action(0x7f602400eb60, soa_generate_answer): called
  756. soa_static.c:1230 offer_answer_step() soa_static(0x7f602400eb60, soa_generate_answer): upgrade with remote description
  757. soa_static.c:1029 soa_sdp_mode_set() soa_sdp_mode_set(0x7f604a048ab0, 0x7f6024015840, ""): called
  758. soa_static.c:1446 offer_answer_step() soa_static(0x7f602400eb60, soa_generate_answer): storing local description
  759. soa.c:1730 soa_activate() soa_activate(static::0x7f602400eb60, (nil)) called
  760. soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x7f602400eb60, [(nil)], [0x7f604a04ac38], [0x7f604a04ac34]) called
  761. tport.c:3257 tport_tsend() tport_tsend(0x7f6024004530) tpn = UDP/172.21.0.12:5060
  762. tport.c:4046 tport_resolve() tport_resolve addrinfo = 172.21.0.12:5060
  763. tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7f6024004530): not found by name UDP/172.21.0.12:5060
  764. tport.c:3594 tport_vsend() tport_vsend(0x7f6024004530): 894 bytes of 894 to udp/172.21.0.12:5060
  765. tport.c:3492 tport_send_msg() tport_vsend returned 894
  766. send 894 bytes to udp/[172.21.0.12]:5060 at 11:49:32.391571:
  767.    ------------------------------------------------------------------------
  768.    SIP/2.0 200 OK
  769.    Via: SIP/2.0/UDP 172.21.0.12;branch=z9hG4bKedcb.e53a29b4a9efba0ad34db660d2cff5d0.0
  770.    Via: SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1765-1-6
  771.    From: "3613612209" <sip:3613612209@webrtc.example.com>;tag=1
  772.    To: "3613612202" <sip:3613612202@webrtc.example.com>;tag=1DK7cet40aNjD
  773.    Call-ID: 1-1765@172.21.0.11
  774.    CSeq: 2 INVITE
  775.    Contact: <sip:3613612202@172.21.0.10:5080;transport=udp>
  776.    User-Agent: Voicis WebRTC Gateway
  777.    Accept: application/sdp
  778.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  779.    Supported: timer, path, replaces
  780.    Content-Type: application/sdp
  781.    Content-Disposition: session
  782.    Content-Length: 230
  783.    
  784.    v=0
  785.    o=FreeSWITCH 1487918724 1487918726 IN IP4 172.21.0.10
  786.    s=FreeSWITCH
  787.    c=IN IP4 172.21.0.10
  788.    t=0 0
  789.    m=audio 18246 RTP/AVP 0 101
  790.    a=rtpmap:0 PCMU/8000
  791.    a=rtpmap:101 telephone-event/8000
  792.    a=fmtp:101 0-16
  793.    a=recvonly
  794.    a=ptime:20
  795.    ------------------------------------------------------------------------
  796. nta.c:6791 incoming_reply() nta: sent 200 OK for INVITE (2)
  797. nua_session.c:4145 signal_call_state_change() nua(0x7f602400e290): ready call updated: completed sent answer
  798. soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x7f602400eb60, [0x7f604a04ace8], [0x7f604a04acf0], [(nil)]) called
  799. soa.c:616 soa_get_params() soa_get_params(static::0x7f602400eb60, ...) called
  800. nua_stack.c:271 nua_stack_event() nua(0x7f602400e290): event i_state 200 OK
  801. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  802. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  803. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:32.388712 [DEBUG] sofia.c:7042 Channel sofia/external/3613612209@webrtc.example.com entering state [completed][200]
  804. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  805. tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f6024004530): events IN
  806. tport.c:2864 tport_recv_event() tport_recv_event(0x7f6024004530)
  807. tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f6024004530) msg 0x7f6024015c40 from (udp/172.21.0.10:5080) has 560 bytes, veclen = 1
  808. recv 560 bytes from udp/[172.21.0.12]:5060 at 11:49:32.392578:
  809.    ------------------------------------------------------------------------
  810.    ACK sip:3613612202@172.21.0.10:5080 SIP/2.0
  811.    Via: SIP/2.0/UDP 172.21.0.12;branch=z9hG4bKedcb.b658716bcccbec13308c303df53936a9.0
  812.    Via: SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1765-1-10
  813.    Max-Forwards: 43
  814.    To: "3613612202" <sip:3613612202@webrtc.example.com>;tag=1DK7cet40aNjD
  815.    From: "3613612209" <sip:3613612209@webrtc.example.com>;tag=1
  816.    Call-ID: 1-1765@172.21.0.11
  817.    CSeq: 2 ACK
  818.    User-Agent: eyeBeam release 9330a stamp 37653
  819.    Content-Length: 0
  820.    Server: SIPp Server
  821.    Contact: <sip:siplb@172.21.0.11:5061;abcct=7369703a31302e3131322e32362e38353a35303630>
  822.    
  823.    ------------------------------------------------------------------------
  824. tport.c:3023 tport_deliver() tport_deliver(0x7f6024004530): msg 0x7f6024015c40 (560 bytes) from udp/172.21.0.12:5080/sip next=(nil)
  825. nta.c:2880 agent_recv_request() nta: received ACK sip:3613612202@172.21.0.10:5080 SIP/2.0 (CSeq 2)
  826. nta.c:3019 agent_recv_request() nta: ACK (2) is going to INVITE (2)
  827. nua_session.c:2569 process_ack_or_cancel() nua: process_ack_or_cancel: entering
  828. soa.c:1214 soa_clear_remote_sdp() soa_clear_remote_sdp(static::0x7f602400eb60) called
  829. nua_stack.c:271 nua_stack_event() nua(0x7f602400e290): event i_ack 200 OK
  830. nua_session.c:4145 signal_call_state_change() nua(0x7f602400e290): ready call updated: ready
  831. nua_stack.c:271 nua_stack_event() nua(0x7f602400e290): event i_state 200 OK
  832. nua_stack.c:271 nua_stack_event() nua(0x7f602400e290): event i_active 200 Call active
  833. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  834. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  835. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  836. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  837. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  838. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  839. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:32.428710 [DEBUG] sofia.c:7042 Channel sofia/external/3613612209@webrtc.example.com entering state [ready][200]
  840. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  841. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  842. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  843. nta.c:1296 agent_timer() nta: timer set next to 2798 ms
  844. tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f6024004530): events IN
  845. tport.c:2864 tport_recv_event() tport_recv_event(0x7f6024004530)
  846. tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f6024004530) msg 0x7f6024015c40 from (udp/172.21.0.10:5080) has 1180 bytes, veclen = 1
  847. recv 1180 bytes from udp/[172.21.0.12]:5060 at 11:49:34.393591:
  848.    ------------------------------------------------------------------------
  849.    INVITE sip:3613612202@172.21.0.10:5080 SIP/2.0
  850.    Via: SIP/2.0/UDP 172.21.0.12;branch=z9hG4bKfdcb.e6ec50a7476e7ee19c795a4bbcbefb85.0
  851.    Via: SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1765-1-12
  852.    Max-Forwards: 43
  853.    To: "3613612202" <sip:3613612202@webrtc.example.com>;tag=1DK7cet40aNjD
  854.    From: "3613612201" <sip:3613612209@webrtc.example.com>;tag=1
  855.    Call-ID: 1-1765@172.21.0.11
  856.    CSeq: 3 INVITE
  857.    Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,NOTIFY,MESSAGE,SUBSCRIBE,INFO
  858.    Content-Type: application/sdp
  859.    User-Agent: eyeBeam release 9330a stamp 37653
  860.    Content-Length:   434
  861.    Server: SIPp Server
  862.    P-Asserted-Identity: "3613612201" <sip:3613612201@webrtc.example.com>
  863.    Contact: <sip:siplb@172.21.0.11:5061;abcct=7369703a31302e3131322e32362e38353a35303630>
  864.    
  865.    v=0
  866.    o=ipjib 1487263187420 1487263187420 IN IP4 10.112.48.79
  867.    s=CounterPath eyeBeam 1.5
  868.    c=IN IP4 10.112.48.79
  869.    t=0 0
  870.    m=audio 60578 RTP/AVP 119 6 0 98 8 3 5 101
  871.    a=alt:1 2 : FwGzks7j aVPEMhBL 10.112.48.79 60578
  872.    a=alt:2 1 : NxVGnjOA 78sxYW0G 10.112.200.224 60578
  873.    a=fmtp:101 0-15
  874.    a=rtpmap:119 BV32-FEC/16000
  875.    a=rtpmap:98 iLBC/8000
  876.    a=rtpmap:101 telephone-event/8000
  877.    a=sendrecv
  878.    a=x-rtp-session-id:397A5FF0FB494996B741C71582F2FEAC
  879.    ------------------------------------------------------------------------
  880. tport.c:3023 tport_deliver() tport_deliver(0x7f6024004530): msg 0x7f6024015c40 (1180 bytes) from udp/172.21.0.12:5080/sip next=(nil)
  881. nta.c:2880 agent_recv_request() nta: received INVITE sip:3613612202@172.21.0.10:5080 SIP/2.0 (CSeq 3)
  882. nta.c:3248 agent_aliases() nta: canonizing sip:3613612202@172.21.0.10:5080 with contact
  883. nta.c:3060 agent_recv_request() nta: INVITE (3) going to existing leg
  884. nta.c:1348 set_timeout() nta: timer shortened to 200 ms
  885. nua_server.c:102 nua_stack_process_request() nua: nua_stack_process_request: entering
  886. soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::0x7f602400eb60) called
  887. soa.c:1171 soa_set_remote_sdp() soa_set_remote_sdp(static::0x7f602400eb60, (nil), 0x7f6024012dba, 434) called
  888. tport.c:3257 tport_tsend() tport_tsend(0x7f6024004530) tpn = UDP/172.21.0.12:5060
  889. tport.c:4046 tport_resolve() tport_resolve addrinfo = 172.21.0.12:5060
  890. tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7f6024004530): not found by name UDP/172.21.0.12:5060
  891. tport.c:3594 tport_vsend() tport_vsend(0x7f6024004530): 399 bytes of 399 to udp/172.21.0.12:5060
  892. tport.c:3492 tport_send_msg() tport_vsend returned 399
  893. send 399 bytes to udp/[172.21.0.12]:5060 at 11:49:34.393951:
  894.    ------------------------------------------------------------------------
  895.    SIP/2.0 100 Trying
  896.    Via: SIP/2.0/UDP 172.21.0.12;branch=z9hG4bKfdcb.e6ec50a7476e7ee19c795a4bbcbefb85.0
  897.    Via: SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1765-1-12
  898.    From: "3613612201" <sip:3613612209@webrtc.example.com>;tag=1
  899.    To: "3613612202" <sip:3613612202@webrtc.example.com>;tag=1DK7cet40aNjD
  900.    Call-ID: 1-1765@172.21.0.11
  901.    CSeq: 3 INVITE
  902.    User-Agent: Voicis WebRTC Gateway
  903.    Content-Length: 0
  904.    
  905.    ------------------------------------------------------------------------
  906. nta.c:6791 incoming_reply() nta: sent 100 Trying for INVITE (3)
  907. nua_stack.c:271 nua_stack_event() nua(0x7f602400e290): event i_invite 100 Trying
  908. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  909. nua_session.c:4145 signal_call_state_change() nua(0x7f602400e290): ready call updated: received received offer
  910. soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::0x7f602400eb60, [0x7f604a04a8c8], [0x7f604a04a8d0], [(nil)]) called
  911. nua_stack.c:271 nua_stack_event() nua(0x7f602400e290): event i_state 100 Trying
  912. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  913. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  914. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  915. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  916. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.388723 [DEBUG] sofia.c:7042 Channel sofia/external/3613612209@webrtc.example.com entering state [received][100]
  917. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.388723 [DEBUG] sofia.c:7052 Remote SDP:
  918. 810f2514-ac39-4e49-b8fe-2e2adb046646 v=0
  919. 810f2514-ac39-4e49-b8fe-2e2adb046646 o=ipjib 1487263187420 1487263187420 IN IP4 10.112.48.79
  920. 810f2514-ac39-4e49-b8fe-2e2adb046646 s=CounterPath eyeBeam 1.5
  921. 810f2514-ac39-4e49-b8fe-2e2adb046646 c=IN IP4 10.112.48.79
  922. 810f2514-ac39-4e49-b8fe-2e2adb046646 t=0 0
  923. 810f2514-ac39-4e49-b8fe-2e2adb046646 m=audio 60578 RTP/AVP 119 6 0 98 8 3 5 101
  924. 810f2514-ac39-4e49-b8fe-2e2adb046646 a=rtpmap:119 BV32-FEC/16000
  925. 810f2514-ac39-4e49-b8fe-2e2adb046646 a=rtpmap:98 iLBC/8000
  926. 810f2514-ac39-4e49-b8fe-2e2adb046646 a=rtpmap:101 telephone-event/8000
  927. 810f2514-ac39-4e49-b8fe-2e2adb046646 a=fmtp:101 0-15
  928. 810f2514-ac39-4e49-b8fe-2e2adb046646 a=alt:1 2 : FwGzks7j aVPEMhBL 10.112.48.79 60578
  929. 810f2514-ac39-4e49-b8fe-2e2adb046646 a=alt:2 1 : NxVGnjOA 78sxYW0G 10.112.200.224 60578
  930. 810f2514-ac39-4e49-b8fe-2e2adb046646 a=x-rtp-session-id:397A5FF0FB494996B741C71582F2FEAC
  931. 810f2514-ac39-4e49-b8fe-2e2adb046646
  932. nta.c:1296 agent_timer() nta: timer set next to 780 ms
  933. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.648726 [DEBUG] switch_channel.c:2029 (sofia/external/3613612209@webrtc.example.com) Callstate Change HELD -> UNHELD
  934. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.648726 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [BV32-FEC:119:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
  935. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.648726 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [BV32-FEC:119:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
  936. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.648726 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [DVI4:6:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
  937. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.648726 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [DVI4:6:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
  938. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.648726 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  939. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.648726 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  940. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.648726 [DEBUG] switch_core_media.c:4455 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  941. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.648726 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [iLBC:98:8000:30:13330:1]/[PCMA:8:8000:20:64000:1]
  942. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.648726 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [iLBC:98:8000:30:13330:1]/[PCMU:0:8000:20:64000:1]
  943. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.648726 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  944. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.648726 [DEBUG] switch_core_media.c:4455 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  945. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.648726 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  946. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.648726 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  947. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.648726 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  948. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.648726 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [DVI4:5:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  949. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.648726 [DEBUG] switch_core_media.c:4400 Audio Codec Compare [DVI4:5:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  950. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.648726 [DEBUG] switch_core_media.c:4316 Set telephone-event payload to 101@8000
  951. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.648726 [DEBUG] switch_core_media.c:4659 Set telephone-event payload to 101@8000
  952. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.648726 [DEBUG] switch_core_media.c:4718 sofia/external/3613612209@webrtc.example.com Set 2833 dtmf send payload to 101 recv payload to 101
  953. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.648726 [DEBUG] switch_core_media.c:6721 Audio params changed for sofia/external/3613612209@webrtc.example.com from 0.0.0.0:60578 to 10.112.48.79:60578
  954. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.648726 [DEBUG] switch_core_media.c:6732 AUDIO RTP [sofia/external/3613612209@webrtc.example.com] 172.21.0.10 port 18246 -> 10.112.48.79 port 60578 codec: 0 ms: 20
  955. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.648726 [DEBUG] switch_core_media.c:6761 AUDIO RTP CHANGING DEST TO: [10.112.48.79:60578]
  956. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.648726 [DEBUG] sofia.c:7942 Processing updated SDP
  957. nua.c:879 nua_respond() nua: nua_respond: entering
  958. nua_stack.c:529 nua_signal() nua(0x7f602400e290): sent signal r_respond
  959. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  960. nua_stack.c:573 nua_stack_signal() nua(0x7f602400e290): recv signal r_respond 200 OK
  961. nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
  962. soa.c:403 soa_set_params() soa_set_params(static::0x7f602400eb60, ...) called
  963. soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::0x7f602400eb60, (nil), 0x7f5fe4011560, -1) called
  964. nua_session.c:2320 nua_invite_server_respond() nua: nua_invite_server_respond: entering
  965. soa.c:1515 soa_generate_answer() soa_generate_answer(static::0x7f602400eb60) called
  966. soa_static.c:1148 offer_answer_step() soa_static_offer_answer_action(0x7f602400eb60, soa_generate_answer): called
  967. soa_static.c:1230 offer_answer_step() soa_static(0x7f602400eb60, soa_generate_answer): upgrade with remote description
  968. soa_static.c:1029 soa_sdp_mode_set() soa_sdp_mode_set(0x7f604a048ab0, 0x7f60240192a0, ""): called
  969. soa_static.c:1446 offer_answer_step() soa_static(0x7f602400eb60, soa_generate_answer): storing local description
  970. soa.c:1730 soa_activate() soa_activate(static::0x7f602400eb60, (nil)) called
  971. soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x7f602400eb60, [(nil)], [0x7f604a04ac38], [0x7f604a04ac34]) called
  972. tport.c:3257 tport_tsend() tport_tsend(0x7f6024004530) tpn = UDP/172.21.0.12:5060
  973. tport.c:4046 tport_resolve() tport_resolve addrinfo = 172.21.0.12:5060
  974. tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7f6024004530): not found by name UDP/172.21.0.12:5060
  975. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.648726 [DEBUG] switch_core_io.c:941 (sofia/external/3613612209@webrtc.example.com) Callstate Change UNHELD -> ACTIVE
  976. tport.c:3594 tport_vsend() tport_vsend(0x7f6024004530): 883 bytes of 883 to udp/172.21.0.12:5060
  977. tport.c:3492 tport_send_msg() tport_vsend returned 883
  978. send 883 bytes to udp/[172.21.0.12]:5060 at 11:49:34.653032:
  979.    ------------------------------------------------------------------------
  980.    SIP/2.0 200 OK
  981.    Via: SIP/2.0/UDP 172.21.0.12;branch=z9hG4bKfdcb.e6ec50a7476e7ee19c795a4bbcbefb85.0
  982.    Via: SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1765-1-12
  983.    From: "3613612201" <sip:3613612209@webrtc.example.com>;tag=1
  984.    To: "3613612202" <sip:3613612202@webrtc.example.com>;tag=1DK7cet40aNjD
  985.    Call-ID: 1-1765@172.21.0.11
  986.    CSeq: 3 INVITE
  987.    Contact: <sip:3613612202@172.21.0.10:5080;transport=udp>
  988.    User-Agent: Voicis WebRTC Gateway
  989.    Accept: application/sdp
  990.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  991.    Supported: timer, path, replaces
  992.    Content-Type: application/sdp
  993.    Content-Disposition: session
  994.    Content-Length: 218
  995.    
  996.    v=0
  997.    o=FreeSWITCH 1487918724 1487918727 IN IP4 172.21.0.10
  998.    s=FreeSWITCH
  999.    c=IN IP4 172.21.0.10
  1000.    t=0 0
  1001.    m=audio 18246 RTP/AVP 0 101
  1002.    a=rtpmap:0 PCMU/8000
  1003.    a=rtpmap:101 telephone-event/8000
  1004.    a=fmtp:101 0-16
  1005.    a=ptime:20
  1006.    ------------------------------------------------------------------------
  1007. nta.c:6791 incoming_reply() nta: sent 200 OK for INVITE (3)
  1008. nta.c:1348 set_timeout() nta: timer shortened to 500 ms
  1009. nua_session.c:4145 signal_call_state_change() nua(0x7f602400e290): ready call updated: completed sent answer
  1010. soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x7f602400eb60, [0x7f604a04ace8], [0x7f604a04acf0], [(nil)]) called
  1011. soa.c:616 soa_get_params() soa_get_params(static::0x7f602400eb60, ...) called
  1012. nua_stack.c:271 nua_stack_event() nua(0x7f602400e290): event i_state 200 OK
  1013. tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f6024004530): events IN
  1014. tport.c:2864 tport_recv_event() tport_recv_event(0x7f6024004530)
  1015. tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f6024004530) msg 0x7f6024015840 from (udp/172.21.0.10:5080) has 560 bytes, veclen = 1
  1016. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  1017. recv 560 bytes from udp/[172.21.0.12]:5060 at 11:49:34.653968:
  1018.    ------------------------------------------------------------------------
  1019.    ACK sip:3613612202@172.21.0.10:5080 SIP/2.0
  1020.    Via: SIP/2.0/UDP 172.21.0.12;branch=z9hG4bKfdcb.dd2f63e3d5c786fb7fbf66d16619b89f.0
  1021.    Via: SIP/2.0/UDP 172.21.0.11:5061;branch=z9hG4bK-1765-1-16
  1022.    Max-Forwards: 43
  1023.    To: "3613612202" <sip:3613612202@webrtc.example.com>;tag=1DK7cet40aNjD
  1024.    From: "3613612209" <sip:3613612209@webrtc.example.com>;tag=1
  1025.    Call-ID: 1-1765@172.21.0.11
  1026.    CSeq: 3 ACK
  1027.    User-Agent: eyeBeam release 9330a stamp 37653
  1028.    Content-Length: 0
  1029.    Server: SIPp Server
  1030.    Contact: <sip:siplb@172.21.0.11:5061;abcct=7369703a31302e3131322e32362e38353a35303630>
  1031.    
  1032.    ------------------------------------------------------------------------
  1033. tport.c:3023 tport_deliver() tport_deliver(0x7f6024004530): msg 0x7f6024015840 (560 bytes) from udp/172.21.0.12:5080/sip next=(nil)
  1034. nta.c:2880 agent_recv_request() nta: received ACK sip:3613612202@172.21.0.10:5080 SIP/2.0 (CSeq 3)
  1035. nta.c:3019 agent_recv_request() nta: ACK (3) is going to INVITE (3)
  1036. nua_session.c:2569 process_ack_or_cancel() nua: process_ack_or_cancel: entering
  1037. soa.c:1214 soa_clear_remote_sdp() soa_clear_remote_sdp(static::0x7f602400eb60) called
  1038. nua_stack.c:271 nua_stack_event() nua(0x7f602400e290): event i_ack 200 OK
  1039. nua_session.c:4145 signal_call_state_change() nua(0x7f602400e290): ready call updated: ready
  1040. nua_stack.c:271 nua_stack_event() nua(0x7f602400e290): event i_state 200 OK
  1041. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  1042. nua_stack.c:271 nua_stack_event() nua(0x7f602400e290): event i_active 200 Call active
  1043. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  1044. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  1045. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  1046. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.668709 [DEBUG] sofia.c:7042 Channel sofia/external/3613612209@webrtc.example.com entering state [completed][200]
  1047. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  1048. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  1049. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  1050. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  1051. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:34.668709 [DEBUG] sofia.c:7042 Channel sofia/external/3613612209@webrtc.example.com entering state [ready][200]
  1052. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  1053. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  1054. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  1055. nta.c:1296 agent_timer() nta: timer set next to 219 ms
  1056. nta.c:7134 _nta_incoming_timer() nta: timer I fired, terminate 200 response
  1057. nta.c:5825 incoming_reclaim_queued() incoming_reclaim_all((nil), (nil), 0x7f604a04ac60)
  1058. nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/3 term, 1/3 free
  1059. nta.c:1296 agent_timer() nta: timer set next to 2019 ms
  1060. nta.c:7134 _nta_incoming_timer() nta: timer I fired, terminate 200 response
  1061. nta.c:5825 incoming_reclaim_queued() incoming_reclaim_all((nil), (nil), 0x7f604a04ac60)
  1062. nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/2 term, 1/2 free
  1063. nta.c:1296 agent_timer() nta: timer set next to 2259 ms
  1064. nta.c:7134 _nta_incoming_timer() nta: timer I fired, terminate 200 response
  1065. nta.c:5825 incoming_reclaim_queued() incoming_reclaim_all((nil), (nil), 0x7f604a04ac60)
  1066. nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/1 term, 1/1 free
  1067. nta.c:1289 agent_timer() nta: timer not set
  1068. 2017-02-24 11:49:41.568711 [ALERT] mod_verto.c:1373 READ 172.21.0.1:45670 [{
  1069.         "jsonrpc":      "2.0",
  1070.         "method":       "verto.bye",
  1071.         "params":       {
  1072.                 "dialogParams": {
  1073.                         "screenShare":  false,
  1074.                         "useMic":       "any",
  1075.                         "useSpeak":     "any",
  1076.                         "tag":  "webcam",
  1077.                         "localTag":     null,
  1078.                         "login":        "3613612202@webrtc.example.com",
  1079.                         "videoParams":  {
  1080.                                 "minWidth":     "1280",
  1081.                                 "minHeight":    "720",
  1082.                                 "minFrameRate": 30
  1083.                         },
  1084.                         "callID":       "5749daf1-911f-4eff-81ba-d53899e6f055",
  1085.                         "caller_id_name":       "3613612209",
  1086.                         "caller_id_number":     "3613612209",
  1087.                         "callee_id_name":       "3613612202",
  1088.                         "callee_id_number":     "3613612202",
  1089.                         "display_direction":    "outbound",
  1090.                         "remote_caller_id_name":        "3613612209",
  1091.                         "remote_caller_id_number":      "3613612209"
  1092.                 },
  1093.                 "sessid":       "d5c3b5fa-9ccf-0b60-a6b7-027231713d4a"
  1094.         },
  1095.         "id":   26
  1096. }]
  1097. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:41.568711 [NOTICE] mod_verto.c:2764 Hangup verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  1098. 2017-02-24 11:49:41.568711 [ALERT] mod_verto.c:604 WRITE 172.21.0.1:45670 [{
  1099.         "jsonrpc":      "2.0",
  1100.         "id":   26,
  1101.         "result":       {
  1102.                 "callID":       "5749daf1-911f-4eff-81ba-d53899e6f055",
  1103.                 "message":      "CALL ENDED",
  1104.                 "causeCode":    16,
  1105.                 "cause":        "NORMAL_CLEARING",
  1106.                 "sessid":       "d5c3b5fa-9ccf-0b60-a6b7-027231713d4a"
  1107.         }
  1108. }]
  1109. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:41.568711 [DEBUG] switch_ivr_bridge.c:679 verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a ending bridge by request from read function
  1110. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:41.568711 [DEBUG] switch_ivr_bridge.c:752 BRIDGE THREAD DONE [verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a]
  1111. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:41.568711 [DEBUG] switch_core_state_machine.c:653 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State EXCHANGE_MEDIA going to sleep
  1112. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:41.568711 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) Running State Change CS_HANGUP (Cur 2 Tot 10)
  1113. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:41.568711 [DEBUG] switch_core_state_machine.c:850 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) Callstate Change ACTIVE -> HANGUP
  1114. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:41.568711 [DEBUG] switch_core_state_machine.c:852 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State HANGUP
  1115. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:41.568711 [DEBUG] switch_core_state_machine.c:60 verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a Standard HANGUP, cause: NORMAL_CLEARING
  1116. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:41.568711 [DEBUG] switch_core_state_machine.c:852 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State HANGUP going to sleep
  1117. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:41.568711 [DEBUG] switch_core_state_machine.c:619 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State Change CS_HANGUP -> CS_REPORTING
  1118. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:41.568711 [DEBUG] switch_core_state_machine.c:584 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) Running State Change CS_REPORTING (Cur 2 Tot 10)
  1119. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:41.568711 [DEBUG] switch_core_state_machine.c:938 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State REPORTING
  1120. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:41.568711 [DEBUG] switch_core_state_machine.c:174 verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a Standard REPORTING, cause: NORMAL_CLEARING
  1121. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:41.568711 [DEBUG] switch_core_state_machine.c:938 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State REPORTING going to sleep
  1122. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:41.568711 [DEBUG] switch_core_state_machine.c:610 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State Change CS_REPORTING -> CS_DESTROY
  1123. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:41.568711 [DEBUG] switch_core_session.c:1664 Session 10 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) Locked, Waiting on external entities
  1124. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:41.588708 [DEBUG] switch_ivr_bridge.c:752 BRIDGE THREAD DONE [sofia/external/3613612209@webrtc.example.com]
  1125. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:41.588708 [NOTICE] switch_core_session.c:1682 Session 10 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) Ended
  1126. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:41.588708 [NOTICE] switch_core_session.c:1686 Close Channel verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a [CS_DESTROY]
  1127. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:41.588708 [DEBUG] switch_core_state_machine.c:741 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) Running State Change CS_DESTROY (Cur 1 Tot 10)
  1128. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:41.588708 [DEBUG] switch_core_state_machine.c:751 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State DESTROY
  1129. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:41.588708 [DEBUG] mod_rtc.c:132 verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a RTC DESTROY
  1130. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:41.588708 [NOTICE] switch_core_state_machine.c:385 sofia/external/3613612209@webrtc.example.com has executed the last dialplan instruction, hanging up.
  1131. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:41.588708 [NOTICE] switch_core_state_machine.c:387 Hangup sofia/external/3613612209@webrtc.example.com [CS_EXECUTE] [NORMAL_CLEARING]
  1132. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:41.588708 [DEBUG] switch_core_state_machine.c:181 verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a Standard DESTROY
  1133. 5749daf1-911f-4eff-81ba-d53899e6f055 2017-02-24 11:49:41.588708 [DEBUG] switch_core_state_machine.c:751 (verto.rtc/d5c3b5fa-9ccf-0b60-a6b7-027231713d4a) State DESTROY going to sleep
  1134. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:41.588708 [DEBUG] switch_core_state_machine.c:650 (sofia/external/3613612209@webrtc.example.com) State EXECUTE going to sleep
  1135. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:41.588708 [DEBUG] switch_core_state_machine.c:584 (sofia/external/3613612209@webrtc.example.com) Running State Change CS_HANGUP (Cur 1 Tot 10)
  1136. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:41.588708 [DEBUG] switch_core_state_machine.c:850 (sofia/external/3613612209@webrtc.example.com) Callstate Change ACTIVE -> HANGUP
  1137. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:41.588708 [DEBUG] switch_core_state_machine.c:852 (sofia/external/3613612209@webrtc.example.com) State HANGUP
  1138. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:41.588708 [DEBUG] mod_sofia.c:438 Channel sofia/external/3613612209@webrtc.example.com hanging up, cause: NORMAL_CLEARING
  1139. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:41.588708 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/external/3613612209@webrtc.example.com
  1140. nua.c:645 nua_bye() nua: nua_bye: entering
  1141. nua_stack.c:529 nua_signal() nua(0x7f602400e290): sent signal r_bye
  1142. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:41.588708 [DEBUG] switch_core_state_machine.c:60 sofia/external/3613612209@webrtc.example.com Standard HANGUP, cause: NORMAL_CLEARING
  1143. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:41.588708 [DEBUG] switch_core_state_machine.c:852 (sofia/external/3613612209@webrtc.example.com) State HANGUP going to sleep
  1144. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:41.588708 [DEBUG] switch_core_state_machine.c:619 (sofia/external/3613612209@webrtc.example.com) State Change CS_HANGUP -> CS_REPORTING
  1145. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:41.588708 [DEBUG] switch_core_state_machine.c:584 (sofia/external/3613612209@webrtc.example.com) Running State Change CS_REPORTING (Cur 1 Tot 10)
  1146. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:41.588708 [DEBUG] switch_core_state_machine.c:938 (sofia/external/3613612209@webrtc.example.com) State REPORTING
  1147. nua_stack.c:569 nua_stack_signal() nua(0x7f602400e290): recv signal r_bye
  1148. nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
  1149. soa.c:403 soa_set_params() soa_set_params(static::0x7f602400eb60, ...) called
  1150. soa.c:1784 soa_terminate() soa_terminate(static::0x7f602400eb60) called
  1151. soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::0x7f602400eb60) called
  1152. nta.c:2665 nta_tpn_by_url() nta: selecting scheme sip
  1153. tport.c:3257 tport_tsend() tport_tsend(0x7f6024004530) tpn = */172.21.0.12:5060
  1154. tport.c:4046 tport_resolve() tport_resolve addrinfo = 172.21.0.12:5060
  1155. tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7f6024004530): not found by name */172.21.0.12:5060
  1156. tport.c:3594 tport_vsend() tport_vsend(0x7f6024004530): 639 bytes of 639 to udp/172.21.0.12:5060
  1157. tport.c:3492 tport_send_msg() tport_vsend returned 639
  1158. send 639 bytes to udp/[172.21.0.12]:5060 at 11:49:41.592938:
  1159.    ------------------------------------------------------------------------
  1160.    BYE sip:siplb@172.21.0.11:5061;abcct=7369703a31302e3131322e32362e38353a35303630 SIP/2.0
  1161.    Via: SIP/2.0/UDP 172.21.0.10:5080;rport;branch=z9hG4bKyBr8Fre4p84Bg
  1162.    Route: <sip:172.21.0.12;lr=on;ftag=1;did=aac.deb2>
  1163.    Max-Forwards: 70
  1164.    From: "3613612202" <sip:3613612202@webrtc.example.com>;tag=1DK7cet40aNjD
  1165.    To: "3613612209" <sip:3613612209@webrtc.example.com>;tag=1
  1166.    Call-ID: 1-1765@172.21.0.11
  1167.    CSeq: 103632426 BYE
  1168.    User-Agent: Voicis WebRTC Gateway
  1169.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  1170.    Supported: timer, path, replaces
  1171.    Reason: Q.850;cause=16;text="NORMAL_CLEARING"
  1172.    Content-Length: 0
  1173.    
  1174.    ------------------------------------------------------------------------
  1175. nta.c:8304 outgoing_send() nta: sent BYE (103632426) to */172.21.0.12:5060
  1176. tport.c:4160 tport_pend() tport_pend(0x7f6024004530): pending 0x7f6024011f80 for udp/172.21.0.10:5080 (already 0)
  1177. nta.c:1350 set_timeout() nta: timer set to 32000 ms
  1178. nta.c:1348 set_timeout() nta: timer shortened to 1000 ms
  1179. tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f6024004530): events IN
  1180. tport.c:2864 tport_recv_event() tport_recv_event(0x7f6024004530)
  1181. tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f6024004530) msg 0x7f602400c2e0 from (udp/172.21.0.10:5080) has 316 bytes, veclen = 1
  1182. recv 316 bytes from udp/[172.21.0.12]:5060 at 11:49:41.593928:
  1183.    ------------------------------------------------------------------------
  1184.    SIP/2.0 200 OK
  1185.    Via: SIP/2.0/UDP 172.21.0.10:5080;received=172.21.0.10;rport=5080;branch=z9hG4bKyBr8Fre4p84Bg
  1186.    From: "3613612202" <sip:3613612202@webrtc.example.com>;tag=1DK7cet40aNjD
  1187.    To: "3613612209" <sip:3613612209@webrtc.example.com>;tag=1
  1188.    Call-ID: 1-1765@172.21.0.11
  1189.    CSeq: 103632426 BYE
  1190.    Content-Length: 0
  1191.    
  1192.    ------------------------------------------------------------------------
  1193. tport.c:3023 tport_deliver() tport_deliver(0x7f6024004530): msg 0x7f602400c2e0 (316 bytes) from udp/172.21.0.12:5080/sip next=(nil)
  1194. nta.c:3299 agent_recv_response() nta: received 200 OK for BYE (103632426)
  1195. nta.c:3366 agent_recv_response() nta: 200 OK is going to a transaction
  1196. nta.c:9564 outgoing_estimate_delay() nta_outgoing: RTT is 1.137 ms
  1197. tport.c:4222 tport_release() tport_release(0x7f6024004530): 0x7f6024011f80 by 0x7f602400be70 with 0x7f602400c2e0
  1198. nua_stack.c:271 nua_stack_event() nua(0x7f602400e290): event r_bye 200 OK
  1199. nua_session.c:4139 signal_call_state_change() nua(0x7f602400e290): call state changed: terminating -> terminated
  1200. nua_stack.c:271 nua_stack_event() nua(0x7f602400e290): event i_state 200 to BYE
  1201. nua_stack.c:271 nua_stack_event() nua(0x7f602400e290): event i_terminated 200 to BYE
  1202. nua_dialog.c:397 nua_dialog_usage_remove_at() nua(0x7f602400e290): removing session usage
  1203. soa.c:356 soa_destroy() soa_destroy(static::0x7f602400eb60) called
  1204. nta.c:4470 nta_leg_destroy() nta_leg_destroy(0x7f602400f470)
  1205. nua_session.c:351 nua_session_usage_destroy() nua: terminated session 0x7f602400e290
  1206. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  1207. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  1208. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
  1209. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  1210. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  1211. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  1212. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  1213. nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering
  1214. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  1215. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
  1216. nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering
  1217. nua_stack.c:529 nua_signal() nua(0x7f602400e290): sent signal r_destroy
  1218. nua_stack.c:569 nua_stack_signal() nua(0x7f602400e290): recv signal r_destroy
  1219. nta.c:4470 nta_leg_destroy() nta_leg_destroy((nil))
  1220. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:41.588708 [DEBUG] switch_core_state_machine.c:174 sofia/external/3613612209@webrtc.example.com Standard REPORTING, cause: NORMAL_CLEARING
  1221. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:41.588708 [DEBUG] switch_core_state_machine.c:938 (sofia/external/3613612209@webrtc.example.com) State REPORTING going to sleep
  1222. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:41.588708 [DEBUG] switch_core_state_machine.c:610 (sofia/external/3613612209@webrtc.example.com) State Change CS_REPORTING -> CS_DESTROY
  1223. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:41.588708 [DEBUG] switch_core_session.c:1664 Session 9 (sofia/external/3613612209@webrtc.example.com) Locked, Waiting on external entities
  1224. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:41.588708 [NOTICE] switch_core_session.c:1682 Session 9 (sofia/external/3613612209@webrtc.example.com) Ended
  1225. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:41.588708 [NOTICE] switch_core_session.c:1686 Close Channel sofia/external/3613612209@webrtc.example.com [CS_DESTROY]
  1226. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:41.588708 [DEBUG] switch_core_state_machine.c:741 (sofia/external/3613612209@webrtc.example.com) Running State Change CS_DESTROY (Cur 0 Tot 10)
  1227. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:41.588708 [DEBUG] switch_core_state_machine.c:751 (sofia/external/3613612209@webrtc.example.com) State DESTROY
  1228. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:41.588708 [DEBUG] mod_sofia.c:343 sofia/external/3613612209@webrtc.example.com SOFIA DESTROY
  1229. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:41.588708 [DEBUG] switch_core_state_machine.c:181 sofia/external/3613612209@webrtc.example.com Standard DESTROY
  1230. 810f2514-ac39-4e49-b8fe-2e2adb046646 2017-02-24 11:49:41.588708 [DEBUG] switch_core_state_machine.c:751 (sofia/external/3613612209@webrtc.example.com) State DESTROY going to sleep
  1231. nta.c:1296 agent_timer() nta: timer set next to 4000 ms
  1232. nta.c:9101 outgoing_timer_dk() nta: timer K fired, terminate BYE (103632426)
  1233. nta.c:8799 outgoing_reclaim_queued() outgoing_reclaim_all((nil), (nil), 0x7f604a04ad40)
  1234. nta.c:8929 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 0/0 tout, 1/1 term, 1/1 free
  1235. nta.c:1289 agent_timer() nta: timer not set
  1236.