From FusionNewbie, 6 Years ago, written in FreeSWITCH.
- go back
Embed
Viewing differences between and Ring Group Issue - Not Respecting Timeout
  1. Version 4.2.4, Commit: 71f95776fc776dd79040278d8c0e6a09bfe889bc
  2. Switch Version 1.6.19 (64bit), Debian 3.16.43-2+deb8u5 (2017-09-19) x86_64 GNU/Linux
  3.  
  4. I am having an issue with Ring Groups in a newer installation of Fusionpbx and verified on 2 separate installations/machines that were setup at the same time using the install script.
  5.  
  6. Basically what is happening, is the varying Ring Groups are not respecting the call timeouts that are set on the destinations.
  7.  
  8. Expected function would be call come in from PSTN, to SBC, and then to PBX... and hits public dialplan where it is routed to the ring group for the domain(s).
  9. Call attempts to reach destinations and respects each destinations timeout individually but doesnt hunt through all of the destinations like a find me feature.
  10.  
  11. The captured call, was to a ring group setup using Rollover Strategy, and has 2 extensions listed as destinations (101 and 102, both active and registered) with orders of 0 and 5, and both are set to timeout after 30 seconds.
  12. Timeout destination is set to voicemail for the 101 extension. Account code and Context are set (properly), and ringback set to us-ring (basically the defaults when creating a group).
  13.  
  14. What happens instead, is the session gets a call_timeout=300 set when it goes into app.lua ring_group and that overrides the call_timeout=30 (I belive is affecting but maybe wrong?).
  15. I have tried commenting this out and it cause it to respect the timeouts on the destinations, but it had to go through both destinations first before timing out.
  16. I can see the calls are also being transfered to both destinations in sequence... and both must timeout before it goes to the timeout destination, which is completely different behavior from my older Fusionpbx installations.
  17. Enterprise strategy does work(ish), and Simultaneous strategy appears to function ok... really just Random, Rollover, and Sequence that are having a problem.
  18.  
  19. 67.X.X.237 = CPE (SPA122 running 1.2.1)
  20. X.X.X.69 = SBC (Freeswitch Version 1.2.24+git~20150504T151740Z~da04da87df~64bit (git da04da8 2015-05-04 15:17:40Z 64bit)
  21. X.X.X.67 = PBX (At very top)
  22.  
  23. Absolutely Zero NAT involved on the CPE, the PBX, or the SBC.
  24.  
  25.    ------------------------------------------------------------------------
  26. recv 460 bytes from udp/[67.X.X.237]:5060 at 21:45:33.125064:
  27.    ------------------------------------------------------------------------
  28.    NOTIFY sip:64.myvoipdomain.com SIP/2.0
  29.    Via: SIP/2.0/UDP 67.X.X.237:5060;branch=z9hG4bK-70283988;rport
  30.    From: "101" <sip:101@64.myvoipdomain.com>;tag=13f6156b3c36b54bo0
  31.    To: <sip:64.myvoipdomain.com>
  32.    Call-ID: 4a378aeb-5527bdcb@67.X.X.237
  33.    CSeq: 7 NOTIFY
  34.    Max-Forwards: 70
  35.    Contact: "101" <sip:101@67.X.X.237:5060;ref=101>
  36.    Event: keep-alive
  37.    User-Agent: Cisco/SPA122-1.2.1(004)
  38.    Allow-Events: talk, hold, conference
  39.    Content-Length: 0
  40.  
  41.    ------------------------------------------------------------------------
  42. send 657 bytes to udp/[67.X.X.237]:5060 at 21:45:33.125388:
  43.    ------------------------------------------------------------------------
  44.    SIP/2.0 200 OK
  45.    Via: SIP/2.0/UDP 67.X.X.237:5060;branch=z9hG4bK-70283988;rport=5060
  46.    From: "101" <sip:101@64.myvoipdomain.com>;tag=13f6156b3c36b54bo0
  47.    To: <sip:64.myvoipdomain.com>;tag=ypK5e1g79v5ja
  48.    Call-ID: 4a378aeb-5527bdcb@67.X.X.237
  49.    CSeq: 7 NOTIFY
  50.    Contact: <sip:X.X.X.67>
  51.    User-Agent: FreeSWITCH
  52.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  53.    Supported: timer, path, replaces
  54.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  55.    Content-Length: 0
  56.  
  57.    ------------------------------------------------------------------------
  58. recv 458 bytes from udp/[67.X.X.237]:5061 at 21:45:33.327505:
  59.    ------------------------------------------------------------------------
  60.    NOTIFY sip:64.myvoipdomain.com SIP/2.0
  61.    Via: SIP/2.0/UDP 67.X.X.237:5061;branch=z9hG4bK-497b416d;rport
  62.    From: "102" <sip:102@64.myvoipdomain.com>;tag=7c76146b421ec24bo1
  63.    To: <sip:64.myvoipdomain.com>
  64.    Call-ID: ca286b-d1b9be4b@67.X.X.237
  65.    CSeq: 7 NOTIFY
  66.    Max-Forwards: 70
  67.    Contact: "102" <sip:102@67.X.X.237:5061;ref=102>
  68.    Event: keep-alive
  69.    User-Agent: Cisco/SPA122-1.2.1(004)
  70.    Allow-Events: talk, hold, conference
  71.    Content-Length: 0
  72.  
  73.    ------------------------------------------------------------------------
  74. send 655 bytes to udp/[67.X.X.237]:5061 at 21:45:33.327782:
  75.    ------------------------------------------------------------------------
  76.    SIP/2.0 200 OK
  77.    Via: SIP/2.0/UDP 67.X.X.237:5061;branch=z9hG4bK-497b416d;rport=5061
  78.    From: "102" <sip:102@64.myvoipdomain.com>;tag=7c76146b421ec24bo1
  79.    To: <sip:64.myvoipdomain.com>;tag=ZZcygv1a75U5N
  80.    Call-ID: ca286b-d1b9be4b@67.X.X.237
  81.    CSeq: 7 NOTIFY
  82.    Contact: <sip:X.X.X.67>
  83.    User-Agent: FreeSWITCH
  84.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  85.    Supported: timer, path, replaces
  86.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  87.    Content-Length: 0
  88.  
  89.    ------------------------------------------------------------------------
  90. send 543 bytes to udp/[X.X.X.69]:5080 at 21:45:40.498449:
  91.    ------------------------------------------------------------------------
  92.    OPTIONS sip:sbc.myvoipdomain.com:5080;transport=udp SIP/2.0
  93.    Via: SIP/2.0/UDP X.X.X.67:5080;rport;branch=z9hG4bK1gDtp3Q288rUm
  94.    Max-Forwards: 70
  95.    From: <sip:sbc.myvoipdomain.com:5080>;tag=3620N0ZvS6BSH
  96.    To: <sip:sbc.myvoipdomain.com:5080>
  97.    Call-ID: 64e47dc6-9d2e-1236-bd8b-626338373834
  98.    CSeq: 119380027 OPTIONS
  99.    User-Agent: FreeSWITCH
  100.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  101.    Supported: timer, path, replaces
  102.    Allow-Events: talk, hold, conference, refer
  103.    Content-Length: 0
  104.  
  105.    ------------------------------------------------------------------------
  106. recv 739 bytes from udp/[X.X.X.69]:5080 at 21:45:40.499244:
  107.    ------------------------------------------------------------------------
  108.    SIP/2.0 200 OK
  109.    Via: SIP/2.0/UDP X.X.X.67:5080;rport=5080;branch=z9hG4bK1gDtp3Q288rUm
  110.    From: <sip:sbc.myvoipdomain.com:5080>;tag=3620N0ZvS6BSH
  111.    To: <sip:sbc.myvoipdomain.com:5080>;tag=tc4yDU20Qy1KF
  112.    Call-ID: 64e47dc6-9d2e-1236-bd8b-626338373834
  113.    CSeq: 119380027 OPTIONS
  114.    Contact: <sip:X.X.X.69:5080>
  115.    User-Agent: FreeSWITCH-mod_sofia/1.2.24+git~20150504T151740Z~da04da87df~64bit
  116.    Accept: application/sdp
  117.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  118.    Supported: timer, path, replaces
  119.    Allow-Events: talk, hold, conference, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  120.    Content-Length: 0
  121.  
  122.    ------------------------------------------------------------------------
  123. recv 1190 bytes from udp/[X.X.X.69]:5080 at 21:45:44.431191:
  124.    ------------------------------------------------------------------------
  125.    INVITE sip:5203810101@X.X.X.67:5080 SIP/2.0
  126.    Via: SIP/2.0/UDP X.X.X.69:5080;rport;branch=z9hG4bKp1Qv85aX6m6KD
  127.    Max-Forwards: 70
  128.    From: "5207050202" <sip:5207050202@X.X.X.69>;tag=UNXQFpK4m7Q6a
  129.    To: <sip:5203810101@X.X.X.67:5080>
  130.    Call-ID: 4ffaf53c-9db9-1236-91a0-623962333032
  131.    CSeq: 119935938 INVITE
  132.    Contact: <sip:mod_sofia@X.X.X.69:5080>
  133.    User-Agent: FreeSWITCH-mod_sofia/1.2.24+git~20150504T151740Z~da04da87df~64bit
  134.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  135.    Supported: timer, path, replaces
  136.    Allow-Events: talk, hold, conference, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  137.    Privacy: none
  138.    Content-Type: application/sdp
  139.    Content-Disposition: session
  140.    Content-Length: 256
  141.    X-FS-Support: update_display,send_info
  142.    P-Asserted-Identity: "5207050202" <sip:5207050202@X.X.X.69>
  143.  
  144.    v=0
  145.    o=FreeSWITCH 2424207514 2424207515 IN IP4 X.X.X.69
  146.    s=FreeSWITCH
  147.    c=IN IP4 X.X.X.69
  148.    t=0 0
  149.    m=audio 23212 RTP/AVP 8 0 101
  150.    a=rtpmap:8 PCMA/8000
  151.    a=rtpmap:0 PCMU/8000
  152.    a=rtpmap:101 telephone-event/8000
  153.    a=fmtp:101 0-15
  154.    a=ptime:20
  155.    a=rtcp:11051
  156.    ------------------------------------------------------------------------
  157. send 316 bytes to udp/[X.X.X.69]:5080 at 21:45:44.431683:
  158.    ------------------------------------------------------------------------
  159.    SIP/2.0 100 Trying
  160.    Via: SIP/2.0/UDP X.X.X.69:5080;rport=5080;branch=z9hG4bKp1Qv85aX6m6KD
  161.    From: "5207050202" <sip:5207050202@X.X.X.69>;tag=UNXQFpK4m7Q6a
  162.    To: <sip:5203810101@X.X.X.67:5080>
  163.    Call-ID: 4ffaf53c-9db9-1236-91a0-623962333032
  164.    CSeq: 119935938 INVITE
  165.    User-Agent: FreeSWITCH
  166.    Content-Length: 0
  167.  
  168.    ------------------------------------------------------------------------
  169. 2018-03-07 21:45:44.428980 [NOTICE] switch_channel.c:1104 New Channel sofia/external/5207050202@X.X.X.69 [e79c8124-9fc7-4144-8459-1f547f7930ed]
  170. 2018-03-07 21:45:44.428980 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5207050202@X.X.X.69) Running State Change CS_NEW (Cur 1 Tot 4868)
  171. 2018-03-07 21:45:44.428980 [DEBUG] sofia.c:9873 sofia/external/5207050202@X.X.X.69 receiving invite from X.X.X.69:5080 version: 1.6.19 -36-7a77e0b 64bit
  172. 2018-03-07 21:45:44.428980 [DEBUG] sofia.c:7084 Channel sofia/external/5207050202@X.X.X.69 entering state [received][100]
  173. 2018-03-07 21:45:44.428980 [DEBUG] sofia.c:7094 Remote SDP:
  174. v=0
  175. o=FreeSWITCH 2424207514 2424207515 IN IP4 X.X.X.69
  176. s=FreeSWITCH
  177. c=IN IP4 X.X.X.69
  178. t=0 0
  179. m=audio 23212 RTP/AVP 8 0 101
  180. a=rtpmap:8 PCMA/8000
  181. a=rtpmap:0 PCMU/8000
  182. a=rtpmap:101 telephone-event/8000
  183. a=fmtp:101 0-15
  184. a=ptime:20
  185. a=rtcp:11051
  186.  
  187. 2018-03-07 21:45:44.428980 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  188. 2018-03-07 21:45:44.428980 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  189. 2018-03-07 21:45:44.428980 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  190. 2018-03-07 21:45:44.428980 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
  191. 2018-03-07 21:45:44.428980 [DEBUG] switch_core_media.c:3061 Set Codec sofia/external/5207050202@X.X.X.69 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  192. 2018-03-07 21:45:44.428980 [DEBUG] switch_core_codec.c:111 sofia/external/5207050202@X.X.X.69 Original read codec set to PCMU:0
  193. 2018-03-07 21:45:44.428980 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
  194. 2018-03-07 21:45:44.428980 [DEBUG] switch_core_media.c:4767 sofia/external/5207050202@X.X.X.69 Set 2833 dtmf send payload to 101 recv payload to 101
  195. 2018-03-07 21:45:44.428980 [DEBUG] sofia.c:7507 (sofia/external/5207050202@X.X.X.69) State Change CS_NEW -> CS_INIT
  196. 2018-03-07 21:45:44.428980 [DEBUG] switch_core_state_machine.c:603 (sofia/external/5207050202@X.X.X.69) State NEW
  197. 2018-03-07 21:45:44.428980 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5207050202@X.X.X.69) Running State Change CS_INIT (Cur 1 Tot 4868)
  198. 2018-03-07 21:45:44.428980 [DEBUG] switch_core_state_machine.c:627 (sofia/external/5207050202@X.X.X.69) State INIT
  199. 2018-03-07 21:45:44.428980 [DEBUG] mod_sofia.c:90 sofia/external/5207050202@X.X.X.69 SOFIA INIT
  200. 2018-03-07 21:45:44.428980 [DEBUG] switch_core_state_machine.c:40 sofia/external/5207050202@X.X.X.69 Standard INIT
  201. 2018-03-07 21:45:44.428980 [DEBUG] switch_core_state_machine.c:48 (sofia/external/5207050202@X.X.X.69) State Change CS_INIT -> CS_ROUTING
  202. 2018-03-07 21:45:44.428980 [DEBUG] switch_core_state_machine.c:627 (sofia/external/5207050202@X.X.X.69) State INIT going to sleep
  203. 2018-03-07 21:45:44.428980 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5207050202@X.X.X.69) Running State Change CS_ROUTING (Cur 1 Tot 4868)
  204. 2018-03-07 21:45:44.428980 [DEBUG] switch_channel.c:2249 (sofia/external/5207050202@X.X.X.69) Callstate Change DOWN -> RINGING
  205. 2018-03-07 21:45:44.428980 [DEBUG] switch_core_state_machine.c:643 (sofia/external/5207050202@X.X.X.69) State ROUTING
  206. 2018-03-07 21:45:44.428980 [DEBUG] mod_sofia.c:143 sofia/external/5207050202@X.X.X.69 SOFIA ROUTING
  207. 2018-03-07 21:45:44.428980 [DEBUG] switch_core_state_machine.c:236 sofia/external/5207050202@X.X.X.69 Standard ROUTING
  208. 2018-03-07 21:45:44.428980 [INFO] mod_dialplan_xml.c:637 Processing 5207050202 <5207050202>->5203810101 in context public
  209. 2018-03-07 21:45:44.488387 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x27987a0 Connected.
  210. 2018-03-07 21:45:44.508387 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x27987a0 released.
  211. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [public->5203810101] continue=false
  212. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (PASS) [5203810101] destination_number(5203810101) =~ /^(5203810101)$/ break=on-false
  213. Dialplan: sofia/external/5207050202@X.X.X.69 Action set(call_direction=inbound) INLINE
  214. EXECUTE sofia/external/5207050202@X.X.X.69 set(call_direction=inbound)
  215. 2018-03-07 21:45:44.508387 [DEBUG] mod_dptools.c:1548 SET sofia/external/5207050202@X.X.X.69 [call_direction]=[inbound]
  216. Dialplan: sofia/external/5207050202@X.X.X.69 Action set(domain_uuid=52958648-0cdf-46da-8700-adf7814389c3) INLINE
  217. EXECUTE sofia/external/5207050202@X.X.X.69 set(domain_uuid=52958648-0cdf-46da-8700-adf7814389c3)
  218. 2018-03-07 21:45:44.508387 [DEBUG] mod_dptools.c:1548 SET sofia/external/5207050202@X.X.X.69 [domain_uuid]=[52958648-0cdf-46da-8700-adf7814389c3]
  219. Dialplan: sofia/external/5207050202@X.X.X.69 Action set(domain_name=64.myvoipdomain.com) INLINE
  220. EXECUTE sofia/external/5207050202@X.X.X.69 set(domain_name=64.myvoipdomain.com)
  221. 2018-03-07 21:45:44.508387 [DEBUG] mod_dptools.c:1548 SET sofia/external/5207050202@X.X.X.69 [domain_name]=[64.myvoipdomain.com]
  222. Dialplan: sofia/external/5207050202@X.X.X.69 Action set(accountcode=64)
  223. Dialplan: sofia/external/5207050202@X.X.X.69 Action transfer(7401 XML 64.myvoipdomain.com)
  224. 2018-03-07 21:45:44.508387 [DEBUG] switch_core_state_machine.c:286 (sofia/external/5207050202@X.X.X.69) State Change CS_ROUTING -> CS_EXECUTE
  225. 2018-03-07 21:45:44.508387 [DEBUG] switch_core_state_machine.c:643 (sofia/external/5207050202@X.X.X.69) State ROUTING going to sleep
  226. 2018-03-07 21:45:44.508387 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5207050202@X.X.X.69) Running State Change CS_EXECUTE (Cur 1 Tot 4868)
  227. 2018-03-07 21:45:44.508387 [DEBUG] switch_core_state_machine.c:650 (sofia/external/5207050202@X.X.X.69) State EXECUTE
  228. 2018-03-07 21:45:44.508387 [DEBUG] mod_sofia.c:198 sofia/external/5207050202@X.X.X.69 SOFIA EXECUTE
  229. 2018-03-07 21:45:44.508387 [DEBUG] switch_core_state_machine.c:328 sofia/external/5207050202@X.X.X.69 Standard EXECUTE
  230. EXECUTE sofia/external/5207050202@X.X.X.69 set(accountcode=64)
  231. 2018-03-07 21:45:44.508387 [DEBUG] mod_dptools.c:1548 SET sofia/external/5207050202@X.X.X.69 [accountcode]=[64]
  232. EXECUTE sofia/external/5207050202@X.X.X.69 transfer(7401 XML 64.myvoipdomain.com)
  233. 2018-03-07 21:45:44.508387 [DEBUG] switch_ivr.c:2165 (sofia/external/5207050202@X.X.X.69) State Change CS_EXECUTE -> CS_ROUTING
  234. 2018-03-07 21:45:44.508387 [NOTICE] switch_ivr.c:2172 Transfer sofia/external/5207050202@X.X.X.69 to XML[7401@64.myvoipdomain.com]
  235. 2018-03-07 21:45:44.508387 [DEBUG] switch_core_state_machine.c:650 (sofia/external/5207050202@X.X.X.69) State EXECUTE going to sleep
  236. 2018-03-07 21:45:44.508387 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5207050202@X.X.X.69) Running State Change CS_ROUTING (Cur 1 Tot 4868)
  237. 2018-03-07 21:45:44.508387 [DEBUG] switch_core_state_machine.c:643 (sofia/external/5207050202@X.X.X.69) State ROUTING
  238. 2018-03-07 21:45:44.508387 [DEBUG] mod_sofia.c:143 sofia/external/5207050202@X.X.X.69 SOFIA ROUTING
  239. 2018-03-07 21:45:44.508387 [DEBUG] switch_core_state_machine.c:236 sofia/external/5207050202@X.X.X.69 Standard ROUTING
  240. 2018-03-07 21:45:44.508387 [INFO] mod_dialplan_xml.c:637 Processing 5207050202 <5207050202>->7401 in context 64.myvoipdomain.com
  241. 2018-03-07 21:45:44.528382 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x27987a0 Connected.
  242. 2018-03-07 21:45:44.548405 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x27987a0 released.
  243. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->user_exists] continue=true
  244. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (PASS) [user_exists] () =~ // break=on-false
  245. Dialplan: sofia/external/5207050202@X.X.X.69 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
  246. 2018-03-07 21:45:44.568381 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x27987a0 Connected.
  247. 2018-03-07 21:45:44.568381 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x27987a0 released.
  248. EXECUTE sofia/external/5207050202@X.X.X.69 set(user_exists=false)
  249. 2018-03-07 21:45:44.568381 [DEBUG] mod_dptools.c:1548 SET sofia/external/5207050202@X.X.X.69 [user_exists]=[false]
  250. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
  251. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->call-direction] continue=true
  252. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (PASS) [call-direction] ${call_direction}(inbound) =~ /^(inbound|outbound|local)$/ break=never
  253. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->variables] continue=true
  254. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (PASS) [variables] () =~ // break=on-false
  255. Dialplan: sofia/external/5207050202@X.X.X.69 Action export(origination_callee_id_name=${destination_number})
  256. Dialplan: sofia/external/5207050202@X.X.X.69 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  257. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->user_record] continue=true
  258. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (PASS) [user_record] () =~ // break=on-false
  259. Dialplan: sofia/external/5207050202@X.X.X.69 Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE
  260. 2018-03-07 21:45:44.568381 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x27987a0 Connected.
  261. 2018-03-07 21:45:44.568381 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x27987a0 released.
  262. 2018-03-07 21:45:44.568381 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x27987a0 Connected.
  263. 2018-03-07 21:45:44.588568 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x27987a0 released.
  264. EXECUTE sofia/external/5207050202@X.X.X.69 set(user_record=)
  265. 2018-03-07 21:45:44.588568 [DEBUG] mod_dptools.c:1548 SET sofia/external/5207050202@X.X.X.69 [user_record]=[UNDEF]
  266. Dialplan: sofia/external/5207050202@X.X.X.69 Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
  267. 2018-03-07 21:45:44.588568 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x27987a0 Connected.
  268. 2018-03-07 21:45:44.588568 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x27987a0 released.
  269. EXECUTE sofia/external/5207050202@X.X.X.69 set(from_user_exists=false)
  270. 2018-03-07 21:45:44.588568 [DEBUG] mod_dptools.c:1548 SET sofia/external/5207050202@X.X.X.69 [from_user_exists]=[false]
  271. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  272. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
  273. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  274. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
  275. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
  276. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  277. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
  278. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
  279. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  280. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
  281. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
  282. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  283. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  284. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
  285. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  286. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
  287. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
  288. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  289. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
  290. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
  291. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  292. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
  293. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
  294. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
  295. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->redial] continue=true
  296. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [redial] destination_number(7401) =~ /^(redial|\*870)$/ break=on-true
  297. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (PASS) [redial] () =~ // break=never
  298. Dialplan: sofia/external/5207050202@X.X.X.69 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
  299. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->speed_dial] continue=false
  300. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [speed_dial] destination_number(7401) =~ /^\*0(.*)$/ break=on-false
  301. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->sbc.myvoipdomain.com.3911] continue=false
  302. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [sbc.myvoipdomain.com.3911] destination_number(7401) =~ /^([3-9]11)$/ break=on-false
  303. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->sbc.myvoipdomain.com.7d] continue=false
  304. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [sbc.myvoipdomain.com.7d] destination_number(7401) =~ /^(\d{7})$/ break=on-false
  305. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->sbc.myvoipdomain.com.10d] continue=false
  306. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [sbc.myvoipdomain.com.10d] destination_number(7401) =~ /^(\d{10})$/ break=on-false
  307. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->sbc.myvoipdomain.com.11d] continue=false
  308. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [sbc.myvoipdomain.com.11d] destination_number(7401) =~ /^1(\d{10})$/ break=on-false
  309. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->agent_status] continue=false
  310. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [agent_status] destination_number(7401) =~ /^\*22$/ break=on-false
  311. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->agent_status_id] continue=false
  312. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [agent_status_id] destination_number(7401) =~ /^\*23$/ break=on-false
  313. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->group-intercept] continue=false
  314. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [group-intercept] destination_number(7401) =~ /^\*8$/ break=on-false
  315. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->page-extension] continue=false
  316. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [page-extension] destination_number(7401) =~ /^\*8(\d{2,7})$/ break=on-false
  317. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->eavesdrop] continue=false
  318. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [eavesdrop] destination_number(7401) =~ /^\*33(\d{2,7})$/ break=on-false
  319. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->call_privacy] continue=false
  320. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [call_privacy] destination_number(7401) =~ /^\*67(\d+)$/ break=on-false
  321. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->call_return] continue=false
  322. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [call_return] destination_number(7401) =~ /^\*69$/ break=on-false
  323. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->extension_queue] continue=false
  324. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [extension_queue] destination_number(7401) =~ /^\*800(.*)$/ break=on-false
  325. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->intercept-ext] continue=false
  326. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [intercept-ext] destination_number(7401) =~ /^\*\*(\d+)$/ break=on-false
  327. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->intercept-ext-polycom] continue=false
  328. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [intercept-ext-polycom] destination_number(7401) =~ /^\*97(\d+)$/ break=on-false
  329. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->dx] continue=false
  330. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [dx] destination_number(7401) =~ /^dx$/ break=on-false
  331. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->att_xfer] continue=false
  332. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [att_xfer] destination_number(7401) =~ /^att_xfer$/ break=on-false
  333. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->extension-to-voicemail] continue=false
  334. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [extension-to-voicemail] ${user_exists}(false) =~ /^true$/ break=on-false
  335. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->send_to_voicemail] continue=false
  336. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [send_to_voicemail] destination_number(7401) =~ /^\*99(\d{2,10})$/ break=on-false
  337. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->vmain] continue=false
  338. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [vmain] destination_number(7401) =~ /^vmain$|^\*4000$|^\*98$/ break=on-false
  339. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->xfer_vm] continue=false
  340. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [xfer_vm] destination_number(7401) =~ /^xfer_vm$/ break=on-false
  341. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->is_transfer] continue=false
  342. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [is_transfer] destination_number(7401) =~ /^is_transfer$/ break=on-false
  343. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->vmain_user] continue=false
  344. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (FAIL) [vmain_user] destination_number(7401) =~ /^\*97$/ break=on-false
  345. Dialplan: sofia/external/5207050202@X.X.X.69 parsing [64.myvoipdomain.com->Group1] continue=
  346. Dialplan: sofia/external/5207050202@X.X.X.69 Regex (PASS) [Group1] destination_number(7401) =~ /^7401$/ break=on-false
  347. Dialplan: sofia/external/5207050202@X.X.X.69 Action set(ring_group_uuid=264e547d-425a-4655-96a4-e05fa08c85eb)
  348. Dialplan: sofia/external/5207050202@X.X.X.69 Action lua(app.lua ring_groups)
  349. 2018-03-07 21:45:44.588568 [DEBUG] switch_core_state_machine.c:286 (sofia/external/5207050202@X.X.X.69) State Change CS_ROUTING -> CS_EXECUTE
  350. 2018-03-07 21:45:44.588568 [DEBUG] switch_core_state_machine.c:643 (sofia/external/5207050202@X.X.X.69) State ROUTING going to sleep
  351. 2018-03-07 21:45:44.588568 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5207050202@X.X.X.69) Running State Change CS_EXECUTE (Cur 1 Tot 4868)
  352. 2018-03-07 21:45:44.588568 [DEBUG] switch_core_state_machine.c:650 (sofia/external/5207050202@X.X.X.69) State EXECUTE
  353. 2018-03-07 21:45:44.588568 [DEBUG] mod_sofia.c:198 sofia/external/5207050202@X.X.X.69 SOFIA EXECUTE
  354. 2018-03-07 21:45:44.588568 [DEBUG] switch_core_state_machine.c:328 sofia/external/5207050202@X.X.X.69 Standard EXECUTE
  355. EXECUTE sofia/external/5207050202@X.X.X.69 export(origination_callee_id_name=7401)
  356. 2018-03-07 21:45:44.588568 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[7401]
  357. EXECUTE sofia/external/5207050202@X.X.X.69 set(RFC2822_DATE=Wed, 07 Mar 2018 21:45:44 -0700)
  358. 2018-03-07 21:45:44.588568 [DEBUG] mod_dptools.c:1548 SET sofia/external/5207050202@X.X.X.69 [RFC2822_DATE]=[Wed, 07 Mar 2018 21:45:44 -0700]
  359. EXECUTE sofia/external/5207050202@X.X.X.69 hash(insert/64.myvoipdomain.com-last_dial/5207050202/7401)
  360. EXECUTE sofia/external/5207050202@X.X.X.69 set(ring_group_uuid=264e547d-425a-4655-96a4-e05fa08c85eb)
  361. 2018-03-07 21:45:44.588568 [DEBUG] mod_dptools.c:1548 SET sofia/external/5207050202@X.X.X.69 [ring_group_uuid]=[264e547d-425a-4655-96a4-e05fa08c85eb]
  362. EXECUTE sofia/external/5207050202@X.X.X.69 lua(app.lua ring_groups)
  363. 2018-03-07 21:45:44.588568 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x27987a0 Connected.
  364. 2018-03-07 21:45:44.588568 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable('call_timeout', '300')
  365. EXECUTE sofia/external/5207050202@X.X.X.69 ring_ready()
  366. 2018-03-07 21:45:44.588568 [NOTICE] mod_sofia.c:2273 Ring-Ready sofia/external/5207050202@X.X.X.69!
  367. send 771 bytes to udp/[X.X.X.69]:5080 at 21:45:44.605071:
  368.    ------------------------------------------------------------------------
  369.    SIP/2.0 180 Ringing
  370.    Via: SIP/2.0/UDP X.X.X.69:5080;rport=5080;branch=z9hG4bKp1Qv85aX6m6KD
  371.    From: "5207050202" <sip:5207050202@X.X.X.69>;tag=UNXQFpK4m7Q6a
  372.    To: <sip:5203810101@X.X.X.67:5080>;tag=4FvSQUg0pF2BD
  373.    Call-ID: 4ffaf53c-9db9-1236-91a0-623962333032
  374.    CSeq: 119935938 INVITE
  375.    Contact: <sip:5203810101@X.X.X.67:5080;transport=udp>
  376.    User-Agent: FreeSWITCH
  377.    Accept: application/sdp
  378.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  379.    Supported: timer, path, replaces
  380.    Allow-Events: talk, hold, conference, refer
  381.    Content-Length: 0
  382.    X-FS-Display-Name: 5203810101
  383.    X-FS-Display-Number: sip:5203810101@X.X.X.67
  384.    X-FS-Support: update_display,send_info
  385.    P-Asserted-Identity: "5203810101" <sip:5203810101@X.X.X.67>
  386.  
  387.    ------------------------------------------------------------------------
  388. 2018-03-07 21:45:44.588568 [DEBUG] sofia.c:7084 Channel sofia/external/5207050202@X.X.X.69 entering state [early][180]
  389. 2018-03-07 21:45:44.588568 [NOTICE] mod_dptools.c:1028 Ring Ready sofia/external/5207050202@X.X.X.69!
  390. 2018-03-07 21:45:44.608410 [NOTICE] switch_cpp.cpp:1365 [ring groups][call forward all] user_exists id 101 64.myvoipdomain.com
  391. 2018-03-07 21:45:44.608410 [NOTICE] switch_cpp.cpp:1365 [ring groups][call forward all] user_exists id 102 64.myvoipdomain.com
  392. 2018-03-07 21:45:44.648380 [NOTICE] switch_cpp.cpp:1365 [ring groups][call forward all] user_exists id 101 64.myvoipdomain.com
  393. 2018-03-07 21:45:44.648380 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable('ringback', 'tone_stream://%(2000,4000,440,480);loops=-1')
  394. 2018-03-07 21:45:44.648380 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable('transfer_ringback', 'tone_stream://%(2000,4000,440,480);loops=-1')
  395. EXECUTE sofia/external/5207050202@X.X.X.69 set(uuids=ba71fe4e-afad-49f2-a805-a849fc6a9719)
  396. 2018-03-07 21:45:44.648380 [DEBUG] mod_dptools.c:1548 SET sofia/external/5207050202@X.X.X.69 [uuids]=[ba71fe4e-afad-49f2-a805-a849fc6a9719]
  397. EXECUTE sofia/external/5207050202@X.X.X.69 export(sip_h_Alert-Info=)
  398. 2018-03-07 21:45:44.648380 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [sip_h_Alert-Info]=[UNDEF]
  399. EXECUTE sofia/external/5207050202@X.X.X.69 set(group_confirm_key=exec)
  400. 2018-03-07 21:45:44.648380 [DEBUG] mod_dptools.c:1548 SET sofia/external/5207050202@X.X.X.69 [group_confirm_key]=[exec]
  401. EXECUTE sofia/external/5207050202@X.X.X.69 set(group_confirm_file=lua /usr/share/freeswitch/scripts/confirm.lua)
  402. 2018-03-07 21:45:44.648380 [DEBUG] mod_dptools.c:1548 SET sofia/external/5207050202@X.X.X.69 [group_confirm_file]=[lua /usr/share/freeswitch/scripts/confirm.lua]
  403. 2018-03-07 21:45:44.668413 [NOTICE] switch_cpp.cpp:1365 [ring groups][call forward all] user_exists id 102 64.myvoipdomain.com
  404. 2018-03-07 21:45:44.668413 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable('ringback', 'tone_stream://%(2000,4000,440,480);loops=-1')
  405. 2018-03-07 21:45:44.668413 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable('transfer_ringback', 'tone_stream://%(2000,4000,440,480);loops=-1')
  406. EXECUTE sofia/external/5207050202@X.X.X.69 set(uuids=ba71fe4e-afad-49f2-a805-a849fc6a9719,f3116f7a-8408-4dea-b924-6d298d21112c)
  407. 2018-03-07 21:45:44.668413 [DEBUG] mod_dptools.c:1548 SET sofia/external/5207050202@X.X.X.69 [uuids]=[ba71fe4e-afad-49f2-a805-a849fc6a9719,f3116f7a-8408-4dea-b924-6d298d21112c]
  408. EXECUTE sofia/external/5207050202@X.X.X.69 export(sip_h_Alert-Info=)
  409. 2018-03-07 21:45:44.668413 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [sip_h_Alert-Info]=[UNDEF]
  410. EXECUTE sofia/external/5207050202@X.X.X.69 set(group_confirm_key=exec)
  411. 2018-03-07 21:45:44.668413 [DEBUG] mod_dptools.c:1548 SET sofia/external/5207050202@X.X.X.69 [group_confirm_key]=[exec]
  412. EXECUTE sofia/external/5207050202@X.X.X.69 set(group_confirm_file=lua /usr/share/freeswitch/scripts/confirm.lua)
  413. 2018-03-07 21:45:44.668413 [DEBUG] mod_dptools.c:1548 SET sofia/external/5207050202@X.X.X.69 [group_confirm_file]=[lua /usr/share/freeswitch/scripts/confirm.lua]
  414. EXECUTE sofia/external/5207050202@X.X.X.69 set(hangup_after_bridge=true)
  415. 2018-03-07 21:45:44.688412 [DEBUG] mod_dptools.c:1548 SET sofia/external/5207050202@X.X.X.69 [hangup_after_bridge]=[true]
  416. EXECUTE sofia/external/5207050202@X.X.X.69 set(continue_on_fail=true)
  417. 2018-03-07 21:45:44.688412 [DEBUG] mod_dptools.c:1548 SET sofia/external/5207050202@X.X.X.69 [continue_on_fail]=[true]
  418. EXECUTE sofia/external/5207050202@X.X.X.69 bind_digit_action(local,*1,exec:execute_extension,dx XML 64.myvoipdomain.com,peer)
  419. 2018-03-07 21:45:44.688412 [INFO] switch_ivr_async.c:214 Digit parser DPTOOLS: Setting realm to 'local'
  420. 2018-03-07 21:45:44.688412 [DEBUG] switch_ivr_async.c:323 Digit parser DPTOOLS: binding *1/local/0 callback: 0x7f0598551d30 data: 0x27c5018
  421. EXECUTE sofia/external/5207050202@X.X.X.69 bind_digit_action(local,*2,exec:record_session,/var/lib/freeswitch/recordings/64.myvoipdomain.com/archive/2018/Mar/07/e79c8124-9fc7-4144-8459-1f547f7930ed.wav,peer)
  422. 2018-03-07 21:45:44.688412 [DEBUG] switch_ivr_async.c:323 Digit parser DPTOOLS: binding *2/local/0 callback: 0x7f0598551d30 data: 0x27c51f8
  423. EXECUTE sofia/external/5207050202@X.X.X.69 bind_digit_action(local,*3,exec:execute_extension,cf XML 64.myvoipdomain.com,peer)
  424. 2018-03-07 21:45:44.688412 [DEBUG] switch_ivr_async.c:323 Digit parser DPTOOLS: binding *3/local/0 callback: 0x7f0598551d30 data: 0x27c5390
  425. EXECUTE sofia/external/5207050202@X.X.X.69 bind_digit_action(local,*4,exec:execute_extension,att_xfer XML 64.myvoipdomain.com,peer)
  426. 2018-03-07 21:45:44.688412 [DEBUG] switch_ivr_async.c:323 Digit parser DPTOOLS: binding *4/local/0 callback: 0x7f0598551d30 data: 0x27c54e0
  427. EXECUTE sofia/external/5207050202@X.X.X.69 digit_action_set_realm(local)
  428. EXECUTE sofia/external/5207050202@X.X.X.69 set(call_timeout=30)
  429. 2018-03-07 21:45:44.688412 [DEBUG] mod_dptools.c:1548 SET sofia/external/5207050202@X.X.X.69 [call_timeout]=[30]
  430. EXECUTE sofia/external/5207050202@X.X.X.69 bridge([confirm=false,sip_invite_domain=64.myvoipdomain.com,call_direction=inbound,dialed_extension=101,extension_uuid=2d044a82-b9b7-4027-990d-562c2cd6af72,domain_name=64.myvoipdomain.com,domain_uuid=52958648-0cdf-46da-8700-adf7814389c3]user/101@64.myvoipdomain.com)
  431. 2018-03-07 21:45:44.688412 [DEBUG] switch_channel.c:1250 sofia/external/5207050202@X.X.X.69 EXPORTING[export_vars] [origination_callee_id_name]=[7401] to event
  432. 2018-03-07 21:45:44.688412 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  433. 2018-03-07 21:45:44.688412 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables
  434. 2018-03-07 21:45:44.688412 [DEBUG] switch_channel.c:1250 sofia/external/5207050202@X.X.X.69 EXPORTING[export_vars] [origination_callee_id_name]=[7401] to event
  435. 2018-03-07 21:45:44.688412 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  436. 2018-03-07 21:45:44.688412 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/101@67.X.X.237:5060 [7881910b-3961-4e9a-8152-8aa959401e8d]
  437. 2018-03-07 21:45:44.688412 [DEBUG] mod_sofia.c:4819 (sofia/internal/101@67.X.X.237:5060) State Change CS_NEW -> CS_INIT
  438. 2018-03-07 21:45:44.688412 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/101@67.X.X.237:5060) Running State Change CS_INIT (Cur 2 Tot 4869)
  439. 2018-03-07 21:45:44.688412 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/101@67.X.X.237:5060) State INIT
  440. 2018-03-07 21:45:44.688412 [DEBUG] mod_sofia.c:90 sofia/internal/101@67.X.X.237:5060 SOFIA INIT
  441. 2018-03-07 21:45:44.688412 [DEBUG] sofia_glue.c:1295 sofia/internal/101@67.X.X.237:5060 sending invite version: 1.6.19 -36-7a77e0b 64bit
  442. Local SDP:
  443. v=0
  444. o=FreeSWITCH 1520457884 1520457885 IN IP4 X.X.X.67
  445. s=FreeSWITCH
  446. c=IN IP4 X.X.X.67
  447. t=0 0
  448. m=audio 26460 RTP/AVP 0 101 13
  449. a=rtpmap:0 PCMU/8000
  450. a=rtpmap:101 telephone-event/8000
  451. a=fmtp:101 0-16
  452. a=rtpmap:13 CN/8000
  453. a=ptime:20
  454. a=sendrecv
  455.  
  456. 2018-03-07 21:45:44.688412 [DEBUG] switch_core_state_machine.c:40 sofia/internal/101@67.X.X.237:5060 Standard INIT
  457. 2018-03-07 21:45:44.688412 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/101@67.X.X.237:5060) State Change CS_INIT -> CS_ROUTING
  458. 2018-03-07 21:45:44.688412 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/101@67.X.X.237:5060) State INIT going to sleep
  459. 2018-03-07 21:45:44.688412 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/101@67.X.X.237:5060) Running State Change CS_ROUTING (Cur 2 Tot 4869)
  460. send 1148 bytes to udp/[67.X.X.237]:5060 at 21:45:44.706379:
  461.    ------------------------------------------------------------------------
  462.    INVITE sip:101@67.X.X.237:5060 SIP/2.0
  463.    Via: SIP/2.0/UDP X.X.X.67;rport;branch=z9hG4bK4FmpZgeycD49r
  464.    Max-Forwards: 68
  465.    From: "5207050202" <sip:5207050202@64.myvoipdomain.com>;tag=33H1Q84rU9mgm
  466.    To: <sip:101@67.X.X.237:5060>
  467.    Call-ID: 67668f65-9d2e-1236-bd8b-626338373834
  468.    CSeq: 119906108 INVITE
  469.    Contact: <sip:mod_sofia@X.X.X.67:5060>
  470.    User-Agent: FreeSWITCH
  471.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  472.    Supported: timer, path, replaces
  473.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  474.    Privacy: none
  475.    Content-Type: application/sdp
  476.    Content-Disposition: session
  477.    Content-Length: 242
  478.    X-FS-Support: update_display,send_info
  479.    P-Asserted-Identity: "5207050202" <sip:5207050202@64.myvoipdomain.com>
  480.  
  481.    v=0
  482.    o=FreeSWITCH 1520457884 1520457885 IN IP4 X.X.X.67
  483.    s=FreeSWITCH
  484. 2018-03-07 21:45:44.688412 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/101@67.X.X.237:5060) State ROUTING
  485. 2018-03-07 21:45:44.688412 [DEBUG] mod_sofia.c:143 sofia/internal/101@67.X.X.237:5060 SOFIA ROUTING
  486. 2018-03-07 21:45:44.688412 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/101@67.X.X.237:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  487. 2018-03-07 21:45:44.688412 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/101@67.X.X.237:5060) State ROUTING going to sleep
  488.    c=IN IP4 X.X.X.67
  489.    t=0 0
  490.    m=audio 26460 RTP/AVP 0 101 13
  491.    a=rtpmap:0 PCMU/8000
  492.    a=rtpmap:101 telephone-event/8000
  493.    a=fmtp:101 0-16
  494.    a=rtpmap:13 CN/8000
  495.    a=ptime:20
  496.    ------------------------------------------------------------------------
  497. 2018-03-07 21:45:44.688412 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/101@67.X.X.237:5060) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 4869)
  498. 2018-03-07 21:45:44.688412 [DEBUG] sofia.c:7084 Channel sofia/internal/101@67.X.X.237:5060 entering state [calling][0]
  499. 2018-03-07 21:45:44.688412 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/101@67.X.X.237:5060) State CONSUME_MEDIA
  500. 2018-03-07 21:45:44.688412 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/101@67.X.X.237:5060) State CONSUME_MEDIA going to sleep
  501. recv 365 bytes from udp/[67.X.X.237]:5060 at 21:45:44.777358:
  502.    ------------------------------------------------------------------------
  503.    SIP/2.0 100 Trying
  504.    To: <sip:101@67.X.X.237:5060>
  505.    From: "5207050202" <sip:5207050202@64.myvoipdomain.com>;tag=33H1Q84rU9mgm
  506.    Call-ID: 67668f65-9d2e-1236-bd8b-626338373834
  507.    CSeq: 119906108 INVITE
  508.    Via: SIP/2.0/UDP X.X.X.67;branch=z9hG4bK4FmpZgeycD49r;rport=5060
  509.    Server: Cisco/SPA122-1.2.1(004)
  510.    Allow-Events: talk, hold, conference
  511.    Content-Length: 0
  512.  
  513.    ------------------------------------------------------------------------
  514. recv 515 bytes from udp/[67.X.X.237]:5060 at 21:45:44.877504:
  515.    ------------------------------------------------------------------------
  516.    SIP/2.0 180 Ringing
  517.    To: <sip:101@67.X.X.237:5060>;tag=1ead7478bd7ab35i0
  518.    From: "5207050202" <sip:5207050202@64.myvoipdomain.com>;tag=33H1Q84rU9mgm
  519.    Call-ID: 67668f65-9d2e-1236-bd8b-626338373834
  520.    CSeq: 119906108 INVITE
  521.    Via: SIP/2.0/UDP X.X.X.67;branch=z9hG4bK4FmpZgeycD49r;rport=5060
  522.    Contact: "101" <sip:101@67.X.X.237:5060>
  523.    Server: Cisco/SPA122-1.2.1(004)
  524.    Remote-Party-ID: "101" <sip:101@64.myvoipdomain.com>;screen=yes;party=called
  525.    Allow-Events: talk, hold, conference
  526.    Content-Length: 0
  527.  
  528.    ------------------------------------------------------------------------
  529. 2018-03-07 21:45:44.868381 [INFO] sofia.c:1279 sofia/internal/101@67.X.X.237:5060 Update Callee ID to "101" <101>
  530. 2018-03-07 21:45:44.868381 [DEBUG] sofia.c:7084 Channel sofia/internal/101@67.X.X.237:5060 entering state [proceeding][180]
  531. 2018-03-07 21:45:44.868381 [NOTICE] sofia.c:7192 Ring-Ready sofia/internal/101@67.X.X.237:5060!
  532. 2018-03-07 21:45:44.868381 [DEBUG] switch_channel.c:3346 (sofia/internal/101@67.X.X.237:5060) Callstate Change DOWN -> RINGING
  533. 2018-03-07 21:45:44.868381 [INFO] switch_ivr_originate.c:1215 Sending early media
  534. 2018-03-07 21:45:44.868381 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/external/5207050202@X.X.X.69] X.X.X.67 port 30332 -> X.X.X.69 port 23212 codec: 0 ms: 20
  535. 2018-03-07 21:45:44.868381 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms
  536. 2018-03-07 21:45:44.888381 [DEBUG] switch_core_media.c:7098 Activating RTCP PORT 11051
  537. 2018-03-07 21:45:44.888381 [DEBUG] switch_rtp.c:4507 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 11051
  538. 2018-03-07 21:45:44.888381 [DEBUG] switch_rtp.c:2567 Setting RTCP remote addr to X.X.X.69:11051 2
  539. 2018-03-07 21:45:44.888381 [DEBUG] switch_core_media.c:7179 sofia/external/5207050202@X.X.X.69 Set 2833 dtmf send payload to 101
  540. 2018-03-07 21:45:44.888381 [DEBUG] switch_core_media.c:7186 sofia/external/5207050202@X.X.X.69 Set 2833 dtmf receive payload to 101
  541. 2018-03-07 21:45:44.888381 [DEBUG] switch_core_media.c:7209 sofia/external/5207050202@X.X.X.69 Set rtp dtmf delay to 40
  542. 2018-03-07 21:45:44.888381 [DEBUG] mod_sofia.c:2364 Ring SDP:
  543. v=0
  544. o=FreeSWITCH 1520454012 1520454013 IN IP4 X.X.X.67
  545. s=FreeSWITCH
  546. c=IN IP4 X.X.X.67
  547. t=0 0
  548. m=audio 30332 RTP/AVP 0 101
  549. a=rtpmap:0 PCMU/8000
  550. a=rtpmap:101 telephone-event/8000
  551. a=fmtp:101 0-16
  552. a=ptime:20
  553. a=sendrecv
  554. a=rtcp:30333 IN IP4 X.X.X.67
  555.  
  556. 2018-03-07 21:45:44.888381 [NOTICE] mod_sofia.c:2367 Pre-Answer sofia/external/5207050202@X.X.X.69!
  557. 2018-03-07 21:45:44.888381 [DEBUG] switch_channel.c:3474 (sofia/external/5207050202@X.X.X.69) Callstate Change RINGING -> EARLY
  558. 2018-03-07 21:45:44.888381 [DEBUG] switch_ivr_originate.c:1273 Raw Codec Activation Success L16@8000hz 1 channel 20ms
  559. 2018-03-07 21:45:44.888381 [DEBUG] switch_core_codec.c:223 sofia/external/5207050202@X.X.X.69 Push codec L16:100
  560. 2018-03-07 21:45:44.888381 [DEBUG] switch_ivr_originate.c:1305 Play Ringback File [tone_stream://%(2000,4000,440,480);loops=-1]
  561. send 1069 bytes to udp/[X.X.X.69]:5080 at 21:45:44.890164:
  562.    ------------------------------------------------------------------------
  563.    SIP/2.0 183 Session Progress
  564.    Via: SIP/2.0/UDP X.X.X.69:5080;rport=5080;branch=z9hG4bKp1Qv85aX6m6KD
  565.    From: "5207050202" <sip:5207050202@X.X.X.69>;tag=UNXQFpK4m7Q6a
  566.    To: <sip:5203810101@X.X.X.67:5080>;tag=4FvSQUg0pF2BD
  567.    Call-ID: 4ffaf53c-9db9-1236-91a0-623962333032
  568.    CSeq: 119935938 INVITE
  569.    Contact: <sip:5203810101@X.X.X.67:5080;transport=udp>
  570.    User-Agent: FreeSWITCH
  571.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  572.    Supported: timer, path, replaces
  573.    Allow-Events: talk, hold, conference, refer
  574.    Content-Type: application/sdp
  575.    Content-Disposition: session
  576.    Content-Length: 251
  577.    X-FS-Display-Name: 5203810101
  578.    X-FS-Display-Number: sip:5203810101@X.X.X.67
  579.    X-FS-Support: update_display,send_info
  580.    P-Asserted-Identity: "5203810101" <sip:5203810101@X.X.X.67>
  581.  
  582.    v=0
  583.    o=FreeSWITCH 1520454012 1520454013 IN IP4 X.X.X.67
  584.    s=FreeSWITCH
  585.    c=IN IP4 X.X.X.67
  586.    t=0 0
  587.    m=audio 30332 RTP/AVP 0 101
  588.    a=rtpmap:0 PCMU/8000
  589.    a=rtpmap:101 telephone-event/8000
  590.    a=fmtp:101 0-16
  591.    a=ptime:20
  592.    a=rtcp:30333 IN IP4 X.X.X.67
  593.    ------------------------------------------------------------------------
  594. 2018-03-07 21:45:44.908399 [DEBUG] sofia.c:7084 Channel sofia/external/5207050202@X.X.X.69 entering state [early][183]
  595. 2018-03-07 21:45:45.008389 [DEBUG] switch_rtp.c:1887 rtcp_stats_init: audio ssrc[715411117] base_seq[40425]
  596. 2018-03-07 21:45:45.008389 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed.
  597.  
  598.    ------------------------------------------------------------------------
  599. recv 459 bytes from udp/[67.X.X.237]:5060 at 21:45:48.172461:
  600.    ------------------------------------------------------------------------
  601.    NOTIFY sip:64.myvoipdomain.com SIP/2.0
  602.    Via: SIP/2.0/UDP 67.X.X.237:5060;branch=z9hG4bK-d68a3d7;rport
  603.    From: "101" <sip:101@64.myvoipdomain.com>;tag=13f6156b3c36b54bo0
  604.    To: <sip:64.myvoipdomain.com>
  605.    Call-ID: 4a378aeb-5527bdcb@67.X.X.237
  606.    CSeq: 8 NOTIFY
  607.    Max-Forwards: 70
  608.    Contact: "101" <sip:101@67.X.X.237:5060;ref=101>
  609.    Event: keep-alive
  610.    User-Agent: Cisco/SPA122-1.2.1(004)
  611.    Allow-Events: talk, hold, conference
  612.    Content-Length: 0
  613.  
  614.    ------------------------------------------------------------------------
  615. send 656 bytes to udp/[67.X.X.237]:5060 at 21:45:48.172786:
  616.    ------------------------------------------------------------------------
  617.    SIP/2.0 200 OK
  618.    Via: SIP/2.0/UDP 67.X.X.237:5060;branch=z9hG4bK-d68a3d7;rport=5060
  619.    From: "101" <sip:101@64.myvoipdomain.com>;tag=13f6156b3c36b54bo0
  620.    To: <sip:64.myvoipdomain.com>;tag=5N4jUy6ZNU1NB
  621.    Call-ID: 4a378aeb-5527bdcb@67.X.X.237
  622.    CSeq: 8 NOTIFY
  623.    Contact: <sip:X.X.X.67>
  624.    User-Agent: FreeSWITCH
  625.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  626.    Supported: timer, path, replaces
  627.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  628.    Content-Length: 0
  629.  
  630.    ------------------------------------------------------------------------
  631. recv 458 bytes from udp/[67.X.X.237]:5061 at 21:45:48.380279:
  632.    ------------------------------------------------------------------------
  633.    NOTIFY sip:64.myvoipdomain.com SIP/2.0
  634.    Via: SIP/2.0/UDP 67.X.X.237:5061;branch=z9hG4bK-fc19c94b;rport
  635.    From: "102" <sip:102@64.myvoipdomain.com>;tag=7c76146b421ec24bo1
  636.    To: <sip:64.myvoipdomain.com>
  637.    Call-ID: ca286b-d1b9be4b@67.X.X.237
  638.    CSeq: 8 NOTIFY
  639.    Max-Forwards: 70
  640.    Contact: "102" <sip:102@67.X.X.237:5061;ref=102>
  641.    Event: keep-alive
  642.    User-Agent: Cisco/SPA122-1.2.1(004)
  643.    Allow-Events: talk, hold, conference
  644.    Content-Length: 0
  645.  
  646.    ------------------------------------------------------------------------
  647. send 655 bytes to udp/[67.X.X.237]:5061 at 21:45:48.380514:
  648.    ------------------------------------------------------------------------
  649.    SIP/2.0 200 OK
  650.    Via: SIP/2.0/UDP 67.X.X.237:5061;branch=z9hG4bK-fc19c94b;rport=5061
  651.    From: "102" <sip:102@64.myvoipdomain.com>;tag=7c76146b421ec24bo1
  652.    To: <sip:64.myvoipdomain.com>;tag=6yXBXSQ3j4Q8p
  653.    Call-ID: ca286b-d1b9be4b@67.X.X.237
  654.    CSeq: 8 NOTIFY
  655.    Contact: <sip:X.X.X.67>
  656.    User-Agent: FreeSWITCH
  657.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  658.    Supported: timer, path, replaces
  659.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  660.    Content-Length: 0
  661.  
  662.    ------------------------------------------------------------------------
  663. recv 460 bytes from udp/[67.X.X.237]:5060 at 21:46:03.232130:
  664.    ------------------------------------------------------------------------
  665.    NOTIFY sip:64.myvoipdomain.com SIP/2.0
  666.    Via: SIP/2.0/UDP 67.X.X.237:5060;branch=z9hG4bK-6bd3c834;rport
  667.    From: "101" <sip:101@64.myvoipdomain.com>;tag=13f6156b3c36b54bo0
  668.    To: <sip:64.myvoipdomain.com>
  669.    Call-ID: 4a378aeb-5527bdcb@67.X.X.237
  670.    CSeq: 9 NOTIFY
  671.    Max-Forwards: 70
  672.    Contact: "101" <sip:101@67.X.X.237:5060;ref=101>
  673.    Event: keep-alive
  674.    User-Agent: Cisco/SPA122-1.2.1(004)
  675.    Allow-Events: talk, hold, conference
  676.    Content-Length: 0
  677.  
  678.    ------------------------------------------------------------------------
  679. send 657 bytes to udp/[67.X.X.237]:5060 at 21:46:03.232602:
  680.    ------------------------------------------------------------------------
  681.    SIP/2.0 200 OK
  682.    Via: SIP/2.0/UDP 67.X.X.237:5060;branch=z9hG4bK-6bd3c834;rport=5060
  683.    From: "101" <sip:101@64.myvoipdomain.com>;tag=13f6156b3c36b54bo0
  684.    To: <sip:64.myvoipdomain.com>;tag=Bcv750BN4g75g
  685.    Call-ID: 4a378aeb-5527bdcb@67.X.X.237
  686.    CSeq: 9 NOTIFY
  687.    Contact: <sip:X.X.X.67>
  688.    User-Agent: FreeSWITCH
  689.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  690.    Supported: timer, path, replaces
  691.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  692.    Content-Length: 0
  693.  
  694.    ------------------------------------------------------------------------
  695. recv 458 bytes from udp/[67.X.X.237]:5061 at 21:46:03.457212:
  696.    ------------------------------------------------------------------------
  697.    NOTIFY sip:64.myvoipdomain.com SIP/2.0
  698.    Via: SIP/2.0/UDP 67.X.X.237:5061;branch=z9hG4bK-af5f2e73;rport
  699.    From: "102" <sip:102@64.myvoipdomain.com>;tag=7c76146b421ec24bo1
  700.    To: <sip:64.myvoipdomain.com>
  701.    Call-ID: ca286b-d1b9be4b@67.X.X.237
  702.    CSeq: 9 NOTIFY
  703.    Max-Forwards: 70
  704.    Contact: "102" <sip:102@67.X.X.237:5061;ref=102>
  705.    Event: keep-alive
  706.    User-Agent: Cisco/SPA122-1.2.1(004)
  707.    Allow-Events: talk, hold, conference
  708.    Content-Length: 0
  709.  
  710.    ------------------------------------------------------------------------
  711. send 655 bytes to udp/[67.X.X.237]:5061 at 21:46:03.457677:
  712.    ------------------------------------------------------------------------
  713.    SIP/2.0 200 OK
  714.    Via: SIP/2.0/UDP 67.X.X.237:5061;branch=z9hG4bK-af5f2e73;rport=5061
  715.    From: "102" <sip:102@64.myvoipdomain.com>;tag=7c76146b421ec24bo1
  716.    To: <sip:64.myvoipdomain.com>;tag=cNN07Uvr1SXrc
  717.    Call-ID: ca286b-d1b9be4b@67.X.X.237
  718.    CSeq: 9 NOTIFY
  719.    Contact: <sip:X.X.X.67>
  720.    User-Agent: FreeSWITCH
  721.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  722.    Supported: timer, path, replaces
  723.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  724.    Content-Length: 0
  725.  
  726.    ------------------------------------------------------------------------
  727. send 543 bytes to udp/[X.X.X.69]:5080 at 21:46:05.527515:
  728.    ------------------------------------------------------------------------
  729.    OPTIONS sip:sbc.myvoipdomain.com:5080;transport=udp SIP/2.0
  730.    Via: SIP/2.0/UDP X.X.X.67:5080;rport;branch=z9hG4bK2S6jry855HFeg
  731.    Max-Forwards: 70
  732.    From: <sip:sbc.myvoipdomain.com:5080>;tag=5rNjSp13Krryr
  733.    To: <sip:sbc.myvoipdomain.com:5080>
  734.    Call-ID: 73cf9fbd-9d2e-1236-bd8b-626338373834
  735.    CSeq: 119380028 OPTIONS
  736.    User-Agent: FreeSWITCH
  737.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  738.    Supported: timer, path, replaces
  739.    Allow-Events: talk, hold, conference, refer
  740.    Content-Length: 0
  741.  
  742.    ------------------------------------------------------------------------
  743. recv 739 bytes from udp/[X.X.X.69]:5080 at 21:46:05.528089:
  744.    ------------------------------------------------------------------------
  745.    SIP/2.0 200 OK
  746.    Via: SIP/2.0/UDP X.X.X.67:5080;rport=5080;branch=z9hG4bK2S6jry855HFeg
  747.    From: <sip:sbc.myvoipdomain.com:5080>;tag=5rNjSp13Krryr
  748.    To: <sip:sbc.myvoipdomain.com:5080>;tag=X7F9jcNBFS4Bj
  749.    Call-ID: 73cf9fbd-9d2e-1236-bd8b-626338373834
  750.    CSeq: 119380028 OPTIONS
  751.    Contact: <sip:X.X.X.69:5080>
  752.    User-Agent: FreeSWITCH-mod_sofia/1.2.24+git~20150504T151740Z~da04da87df~64bit
  753.    Accept: application/sdp
  754.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  755.    Supported: timer, path, replaces
  756.    Allow-Events: talk, hold, conference, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  757.    Content-Length: 0
  758.  
  759.    ------------------------------------------------------------------------
  760. 2018-03-07 21:46:14.008388 [DEBUG] switch_core_codec.c:248 sofia/external/5207050202@X.X.X.69 Restore previous codec PCMU:0.
  761. 2018-03-07 21:46:14.008388 [NOTICE] switch_ivr_originate.c:3612 Hangup sofia/internal/101@67.X.X.237:5060 [CS_CONSUME_MEDIA] [NO_ANSWER]
  762. 2018-03-07 21:46:14.008388 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/101@67.X.X.237:5060) Running State Change CS_HANGUP (Cur 2 Tot 4869)
  763. 2018-03-07 21:46:14.008388 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/101@67.X.X.237:5060) Callstate Change RINGING -> HANGUP
  764. 2018-03-07 21:46:14.008388 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/101@67.X.X.237:5060) State HANGUP
  765. 2018-03-07 21:46:14.008388 [DEBUG] mod_sofia.c:438 Channel sofia/internal/101@67.X.X.237:5060 hanging up, cause: NO_ANSWER
  766. 2018-03-07 21:46:14.008388 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [user] cause: [NO_ANSWER]
  767. 2018-03-07 21:46:14.008388 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 19 [NO_ANSWER]
  768. 2018-03-07 21:46:14.028385 [INFO] mod_dptools.c:3436 Originate Failed.  Cause: NO_ANSWER
  769. 2018-03-07 21:46:14.028385 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/internal/101@67.X.X.237:5060
  770. 2018-03-07 21:46:14.028385 [DEBUG] switch_core_state_machine.c:60 sofia/internal/101@67.X.X.237:5060 Standard HANGUP, cause: NO_ANSWER
  771. 2018-03-07 21:46:14.028385 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/101@67.X.X.237:5060) State HANGUP going to sleep
  772. 2018-03-07 21:46:14.028385 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/101@67.X.X.237:5060) State Change CS_HANGUP -> CS_REPORTING
  773. 2018-03-07 21:46:14.028385 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/101@67.X.X.237:5060) Running State Change CS_REPORTING (Cur 2 Tot 4869)
  774. 2018-03-07 21:46:14.028385 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/101@67.X.X.237:5060) State REPORTING
  775. send 370 bytes to udp/[67.X.X.237]:5060 at 21:46:14.029157:
  776.    ------------------------------------------------------------------------
  777.    CANCEL sip:101@67.X.X.237:5060 SIP/2.0
  778. 2018-03-07 21:46:14.028385 [DEBUG] switch_core_state_machine.c:174 sofia/internal/101@67.X.X.237:5060 Standard REPORTING, cause: NO_ANSWER
  779.    Via: SIP/2.0/UDP X.X.X.67;rport;branch=z9hG4bK4FmpZgeycD49r
  780. 2018-03-07 21:46:14.028385 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/101@67.X.X.237:5060) State REPORTING going to sleep
  781.    Max-Forwards: 68
  782.    From: "5207050202" <sip:5207050202@64.myvoipdomain.com>;tag=33H1Q84rU9mgm
  783.    To: <sip:101@67.X.X.237:5060>
  784.    Call-ID: 67668f65-9d2e-1236-bd8b-626338373834
  785.    CSeq: 119906108 CANCEL
  786.    Reason: Q.850;cause=19;text="NO_ANSWER"
  787.    Content-Length: 0
  788.  
  789.    ------------------------------------------------------------------------
  790. 2018-03-07 21:46:14.028385 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/101@67.X.X.237:5060) State Change CS_REPORTING -> CS_DESTROY
  791. 2018-03-07 21:46:14.028385 [DEBUG] switch_core_session.c:1665 Session 4869 (sofia/internal/101@67.X.X.237:5060) Locked, Waiting on external entities
  792. 2018-03-07 21:46:14.028385 [NOTICE] switch_core_session.c:1683 Session 4869 (sofia/internal/101@67.X.X.237:5060) Ended
  793. 2018-03-07 21:46:14.028385 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/101@67.X.X.237:5060 [CS_DESTROY]
  794. 2018-03-07 21:46:14.028385 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/101@67.X.X.237:5060) Running State Change CS_DESTROY (Cur 1 Tot 4869)
  795. 2018-03-07 21:46:14.028385 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/101@67.X.X.237:5060) State DESTROY
  796. 2018-03-07 21:46:14.028385 [DEBUG] mod_sofia.c:343 sofia/internal/101@67.X.X.237:5060 SOFIA DESTROY
  797. 2018-03-07 21:46:14.028385 [DEBUG] switch_core_state_machine.c:181 sofia/internal/101@67.X.X.237:5060 Standard DESTROY
  798. 2018-03-07 21:46:14.028385 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/101@67.X.X.237:5060) State DESTROY going to sleep
  799. EXECUTE sofia/external/5207050202@X.X.X.69 set(call_timeout=30)
  800. 2018-03-07 21:46:14.028385 [DEBUG] mod_dptools.c:1548 SET sofia/external/5207050202@X.X.X.69 [call_timeout]=[30]
  801. 2018-03-07 21:46:14.028385 [NOTICE] switch_cpp.cpp:1365 [ring group] app_data: {ignore_early_media=true}[sip_invite_domain=64.myvoipdomain.com,call_direction=inbound,confirm=false,leg_timeout=30,leg_delay_start=0,dialed_extension=101,extension_uuid=2d044a82-b9b7-4027-990d-562c2cd6af72]user/101@64.myvoipdomain.com|[sip_invite_domain=64.myvoipdomain.com,call_direction=inbound,confirm=false,leg_timeout=30,leg_delay_start=5,dialed_extension=102,extension_uuid=9f9c3c10-c7cc-4a45-b5ea-1810f685418d]user/102@64.myvoipdomain.com
  802. EXECUTE sofia/external/5207050202@X.X.X.69 bridge({ignore_early_media=true}[sip_invite_domain=64.myvoipdomain.com,call_direction=inbound,confirm=false,leg_timeout=30,leg_delay_start=0,dialed_extension=101,extension_uuid=2d044a82-b9b7-4027-990d-562c2cd6af72]user/101@64.myvoipdomain.com|[sip_invite_domain=64.myvoipdomain.com,call_direction=inbound,confirm=false,leg_timeout=30,leg_delay_start=5,dialed_extension=102,extension_uuid=9f9c3c10-c7cc-4a45-b5ea-1810f685418d]user/102@64.myvoipdomain.com)
  803. 2018-03-07 21:46:14.028385 [DEBUG] switch_channel.c:1250 sofia/external/5207050202@X.X.X.69 EXPORTING[export_vars] [origination_callee_id_name]=[7401] to event
  804. 2018-03-07 21:46:14.028385 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  805. 2018-03-07 21:46:14.028385 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables
  806. 2018-03-07 21:46:14.028385 [DEBUG] switch_channel.c:1250 sofia/external/5207050202@X.X.X.69 EXPORTING[export_vars] [origination_callee_id_name]=[7401] to event
  807. 2018-03-07 21:46:14.028385 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  808. 2018-03-07 21:46:14.028385 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/101@67.X.X.237:5060 [8489494b-7f2f-4ed6-b573-972aa267836c]
  809. 2018-03-07 21:46:14.028385 [DEBUG] mod_sofia.c:4819 (sofia/internal/101@67.X.X.237:5060) State Change CS_NEW -> CS_INIT
  810. 2018-03-07 21:46:14.028385 [DEBUG] switch_ivr_originate.c:2963 sofia/internal/101@67.X.X.237:5060 Setting leg timeout to 30
  811. 2018-03-07 21:46:14.028385 [DEBUG] switch_ivr_originate.c:2963 sofia/internal/101@67.X.X.237:5060 Setting leg timeout to 30
  812. 2018-03-07 21:46:14.028385 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/101@67.X.X.237:5060) Running State Change CS_INIT (Cur 2 Tot 4870)
  813. 2018-03-07 21:46:14.028385 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/101@67.X.X.237:5060) State INIT
  814. 2018-03-07 21:46:14.028385 [DEBUG] mod_sofia.c:90 sofia/internal/101@67.X.X.237:5060 SOFIA INIT
  815. 2018-03-07 21:46:14.028385 [DEBUG] sofia_glue.c:1295 sofia/internal/101@67.X.X.237:5060 sending invite version: 1.6.19 -36-7a77e0b 64bit
  816. Local SDP:
  817. v=0
  818. o=FreeSWITCH 1520457894 1520457895 IN IP4 X.X.X.67
  819. s=FreeSWITCH
  820. c=IN IP4 X.X.X.67
  821. t=0 0
  822. m=audio 26480 RTP/AVP 0 101 13
  823. a=rtpmap:0 PCMU/8000
  824. a=rtpmap:101 telephone-event/8000
  825. a=fmtp:101 0-16
  826. a=rtpmap:13 CN/8000
  827. a=ptime:20
  828. a=sendrecv
  829.  
  830. 2018-03-07 21:46:14.028385 [DEBUG] switch_core_state_machine.c:40 sofia/internal/101@67.X.X.237:5060 Standard INIT
  831. 2018-03-07 21:46:14.028385 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/101@67.X.X.237:5060) State Change CS_INIT -> CS_ROUTING
  832. 2018-03-07 21:46:14.028385 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/101@67.X.X.237:5060) State INIT going to sleep
  833. 2018-03-07 21:46:14.028385 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/101@67.X.X.237:5060) Running State Change CS_ROUTING (Cur 2 Tot 4870)
  834. 2018-03-07 21:46:14.028385 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/101@67.X.X.237:5060) State ROUTING
  835. send 1148 bytes to udp/[67.X.X.237]:5060 at 21:46:14.043810:
  836.    ------------------------------------------------------------------------
  837.    INVITE sip:101@67.X.X.237:5060 SIP/2.0
  838.    Via: SIP/2.0/UDP X.X.X.67;rport;branch=z9hG4bK5rDF1BZ19Ntvm
  839. 2018-03-07 21:46:14.028385 [DEBUG] mod_sofia.c:143 sofia/internal/101@67.X.X.237:5060 SOFIA ROUTING
  840.    Max-Forwards: 68
  841.    From: "5207050202" <sip:5207050202@64.myvoipdomain.com>;tag=gSt3e8Z6NXp3a
  842.    To: <sip:101@67.X.X.237:5060>
  843. 2018-03-07 21:46:14.028385 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/101@67.X.X.237:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  844.    Call-ID: 78e31ab7-9d2e-1236-bd8b-626338373834
  845.    CSeq: 119906123 INVITE
  846. 2018-03-07 21:46:14.028385 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/101@67.X.X.237:5060) State ROUTING going to sleep
  847.    Contact: <sip:mod_sofia@X.X.X.67:5060>
  848.    User-Agent: FreeSWITCH
  849.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  850.    Supported: timer, path, replaces
  851.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  852.    Privacy: none
  853.    Content-Type: application/sdp
  854.    Content-Disposition: session
  855.    Content-Length: 242
  856.    X-FS-Support: update_display,send_info
  857.    P-Asserted-Identity: "5207050202" <sip:5207050202@64.myvoipdomain.com>
  858.  
  859.    v=0
  860.    o=FreeSWITCH 1520457894 1520457895 IN IP4 X.X.X.67
  861.    s=FreeSWITCH
  862.    c=IN IP4 X.X.X.67
  863.    t=0 0
  864.    m=audio 26480 RTP/AVP 0 101 13
  865.    a=rtpmap:0 PCMU/8000
  866.    a=rtpmap:101 telephone-event/8000
  867.    a=fmtp:101 0-16
  868.    a=rtpmap:13 CN/8000
  869.    a=ptime:20
  870.    ------------------------------------------------------------------------
  871. 2018-03-07 21:46:14.028385 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/101@67.X.X.237:5060) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 4870)
  872. 2018-03-07 21:46:14.028385 [DEBUG] sofia.c:7084 Channel sofia/internal/101@67.X.X.237:5060 entering state [calling][0]
  873. 2018-03-07 21:46:14.028385 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/101@67.X.X.237:5060) State CONSUME_MEDIA
  874. 2018-03-07 21:46:14.028385 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/101@67.X.X.237:5060) State CONSUME_MEDIA going to sleep
  875. recv 399 bytes from udp/[67.X.X.237]:5060 at 21:46:14.074853:
  876.    ------------------------------------------------------------------------
  877.    SIP/2.0 487 Request Terminated
  878.    To: <sip:101@67.X.X.237:5060>;tag=1ead7478bd7ab35i0
  879.    From: "5207050202" <sip:5207050202@64.myvoipdomain.com>;tag=33H1Q84rU9mgm
  880.    Call-ID: 67668f65-9d2e-1236-bd8b-626338373834
  881.    CSeq: 119906108 INVITE
  882.    Via: SIP/2.0/UDP X.X.X.67;branch=z9hG4bK4FmpZgeycD49r;rport=5060
  883.    Server: Cisco/SPA122-1.2.1(004)
  884.    Allow-Events: talk, hold, conference
  885.    Content-Length: 0
  886.  
  887.    ------------------------------------------------------------------------
  888. send 345 bytes to udp/[67.X.X.237]:5060 at 21:46:14.075029:
  889.    ------------------------------------------------------------------------
  890.    ACK sip:101@67.X.X.237:5060 SIP/2.0
  891.    Via: SIP/2.0/UDP X.X.X.67;rport;branch=z9hG4bK4FmpZgeycD49r
  892.    Max-Forwards: 68
  893.    From: "5207050202" <sip:5207050202@64.myvoipdomain.com>;tag=33H1Q84rU9mgm
  894.    To: <sip:101@67.X.X.237:5060>;tag=1ead7478bd7ab35i0
  895.    Call-ID: 67668f65-9d2e-1236-bd8b-626338373834
  896.    CSeq: 119906108 ACK
  897.    Content-Length: 0
  898.  
  899.    ------------------------------------------------------------------------
  900. recv 383 bytes from udp/[67.X.X.237]:5060 at 21:46:14.082335:
  901.    ------------------------------------------------------------------------
  902.    SIP/2.0 200 OK
  903.    To: <sip:101@67.X.X.237:5060>;tag=1ead7478bd7ab35i0
  904.    From: "5207050202" <sip:5207050202@64.myvoipdomain.com>;tag=33H1Q84rU9mgm
  905.    Call-ID: 67668f65-9d2e-1236-bd8b-626338373834
  906.    CSeq: 119906108 CANCEL
  907.    Via: SIP/2.0/UDP X.X.X.67;branch=z9hG4bK4FmpZgeycD49r;rport=5060
  908.    Server: Cisco/SPA122-1.2.1(004)
  909.    Allow-Events: talk, hold, conference
  910.    Content-Length: 0
  911.  
  912.    ------------------------------------------------------------------------
  913. recv 365 bytes from udp/[67.X.X.237]:5060 at 21:46:14.162682:
  914.    ------------------------------------------------------------------------
  915.    SIP/2.0 100 Trying
  916.    To: <sip:101@67.X.X.237:5060>
  917.    From: "5207050202" <sip:5207050202@64.myvoipdomain.com>;tag=gSt3e8Z6NXp3a
  918.    Call-ID: 78e31ab7-9d2e-1236-bd8b-626338373834
  919.    CSeq: 119906123 INVITE
  920.    Via: SIP/2.0/UDP X.X.X.67;branch=z9hG4bK5rDF1BZ19Ntvm;rport=5060
  921.    Server: Cisco/SPA122-1.2.1(004)
  922.    Allow-Events: talk, hold, conference
  923.    Content-Length: 0
  924.  
  925.      ------------------------------------------------------------------------
  926. recv 461 bytes from udp/[67.X.X.237]:5060 at 21:46:18.282453:
  927.    ------------------------------------------------------------------------
  928.    NOTIFY sip:64.myvoipdomain.com SIP/2.0
  929.    Via: SIP/2.0/UDP 67.X.X.237:5060;branch=z9hG4bK-36a2e23d;rport
  930.    From: "101" <sip:101@64.myvoipdomain.com>;tag=13f6156b3c36b54bo0
  931.    To: <sip:64.myvoipdomain.com>
  932.    Call-ID: 4a378aeb-5527bdcb@67.X.X.237
  933.    CSeq: 10 NOTIFY
  934.    Max-Forwards: 70
  935.    Contact: "101" <sip:101@67.X.X.237:5060;ref=101>
  936.    Event: keep-alive
  937.    User-Agent: Cisco/SPA122-1.2.1(004)
  938.    Allow-Events: talk, hold, conference
  939.    Content-Length: 0
  940.  
  941.    ------------------------------------------------------------------------
  942. send 658 bytes to udp/[67.X.X.237]:5060 at 21:46:18.282770:
  943.    ------------------------------------------------------------------------
  944.    SIP/2.0 200 OK
  945.    Via: SIP/2.0/UDP 67.X.X.237:5060;branch=z9hG4bK-36a2e23d;rport=5060
  946.    From: "101" <sip:101@64.myvoipdomain.com>;tag=13f6156b3c36b54bo0
  947.    To: <sip:64.myvoipdomain.com>;tag=jBDNjy1DgF38H
  948.    Call-ID: 4a378aeb-5527bdcb@67.X.X.237
  949.    CSeq: 10 NOTIFY
  950.    Contact: <sip:X.X.X.67>
  951.    User-Agent: FreeSWITCH
  952.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  953.    Supported: timer, path, replaces
  954.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  955.    Content-Length: 0
  956.  
  957.    ------------------------------------------------------------------------
  958. recv 459 bytes from udp/[67.X.X.237]:5061 at 21:46:18.545246:
  959.    ------------------------------------------------------------------------
  960.    NOTIFY sip:64.myvoipdomain.com SIP/2.0
  961.    Via: SIP/2.0/UDP 67.X.X.237:5061;branch=z9hG4bK-595339e4;rport
  962.    From: "102" <sip:102@64.myvoipdomain.com>;tag=7c76146b421ec24bo1
  963.    To: <sip:64.myvoipdomain.com>
  964.    Call-ID: ca286b-d1b9be4b@67.X.X.237
  965.    CSeq: 10 NOTIFY
  966.    Max-Forwards: 70
  967.    Contact: "102" <sip:102@67.X.X.237:5061;ref=102>
  968.    Event: keep-alive
  969.    User-Agent: Cisco/SPA122-1.2.1(004)
  970.    Allow-Events: talk, hold, conference
  971.    Content-Length: 0
  972.  
  973.    ------------------------------------------------------------------------
  974. send 656 bytes to udp/[67.X.X.237]:5061 at 21:46:18.545601:
  975.    ------------------------------------------------------------------------
  976.    SIP/2.0 200 OK
  977.    Via: SIP/2.0/UDP 67.X.X.237:5061;branch=z9hG4bK-595339e4;rport=5061
  978.    From: "102" <sip:102@64.myvoipdomain.com>;tag=7c76146b421ec24bo1
  979.    To: <sip:64.myvoipdomain.com>;tag=Km6DmSjHDrSUD
  980.    Call-ID: ca286b-d1b9be4b@67.X.X.237
  981.    CSeq: 10 NOTIFY
  982.    Contact: <sip:X.X.X.67>
  983.    User-Agent: FreeSWITCH
  984.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  985.    Supported: timer, path, replaces
  986.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  987.    Content-Length: 0
  988.  
  989.      ------------------------------------------------------------------------
  990. send 543 bytes to udp/[X.X.X.69]:5080 at 21:46:30.553937:
  991.    ------------------------------------------------------------------------
  992.    OPTIONS sip:sbc.myvoipdomain.com:5080;transport=udp SIP/2.0
  993.    Via: SIP/2.0/UDP X.X.X.67:5080;rport;branch=z9hG4bK32ZBtSS92t50B
  994.    Max-Forwards: 70
  995.    From: <sip:sbc.myvoipdomain.com:5080>;tag=61eBUHj7g1eHm
  996.    To: <sip:sbc.myvoipdomain.com:5080>
  997.    Call-ID: 82ba5b1d-9d2e-1236-bd8b-626338373834
  998.    CSeq: 119380029 OPTIONS
  999.    User-Agent: FreeSWITCH
  1000.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  1001.    Supported: timer, path, replaces
  1002.    Allow-Events: talk, hold, conference, refer
  1003.    Content-Length: 0
  1004.  
  1005.    ------------------------------------------------------------------------
  1006. recv 739 bytes from udp/[X.X.X.69]:5080 at 21:46:30.554579:
  1007.    ------------------------------------------------------------------------
  1008.    SIP/2.0 200 OK
  1009.    Via: SIP/2.0/UDP X.X.X.67:5080;rport=5080;branch=z9hG4bK32ZBtSS92t50B
  1010.    From: <sip:sbc.myvoipdomain.com:5080>;tag=61eBUHj7g1eHm
  1011.    To: <sip:sbc.myvoipdomain.com:5080>;tag=ZS2tp2pj9aHHS
  1012.    Call-ID: 82ba5b1d-9d2e-1236-bd8b-626338373834
  1013.    CSeq: 119380029 OPTIONS
  1014.    Contact: <sip:X.X.X.69:5080>
  1015.    User-Agent: FreeSWITCH-mod_sofia/1.2.24+git~20150504T151740Z~da04da87df~64bit
  1016.    Accept: application/sdp
  1017.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1018.    Supported: timer, path, replaces
  1019.    Allow-Events: talk, hold, conference, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  1020.    Content-Length: 0
  1021.  
  1022.      ------------------------------------------------------------------------
  1023. recv 461 bytes from udp/[67.X.X.237]:5060 at 21:46:33.384962:
  1024.    ------------------------------------------------------------------------
  1025.    NOTIFY sip:64.myvoipdomain.com SIP/2.0
  1026.    Via: SIP/2.0/UDP 67.X.X.237:5060;branch=z9hG4bK-6fe333c7;rport
  1027.    From: "101" <sip:101@64.myvoipdomain.com>;tag=13f6156b3c36b54bo0
  1028.    To: <sip:64.myvoipdomain.com>
  1029.    Call-ID: 4a378aeb-5527bdcb@67.X.X.237
  1030.    CSeq: 11 NOTIFY
  1031.    Max-Forwards: 70
  1032.    Contact: "101" <sip:101@67.X.X.237:5060;ref=101>
  1033.    Event: keep-alive
  1034.    User-Agent: Cisco/SPA122-1.2.1(004)
  1035.    Allow-Events: talk, hold, conference
  1036.    Content-Length: 0
  1037.  
  1038.    ------------------------------------------------------------------------
  1039. send 658 bytes to udp/[67.X.X.237]:5060 at 21:46:33.385235:
  1040.    ------------------------------------------------------------------------
  1041.    SIP/2.0 200 OK
  1042.    Via: SIP/2.0/UDP 67.X.X.237:5060;branch=z9hG4bK-6fe333c7;rport=5060
  1043.    From: "101" <sip:101@64.myvoipdomain.com>;tag=13f6156b3c36b54bo0
  1044.    To: <sip:64.myvoipdomain.com>;tag=r149v062y48rQ
  1045.    Call-ID: 4a378aeb-5527bdcb@67.X.X.237
  1046.    CSeq: 11 NOTIFY
  1047.    Contact: <sip:X.X.X.67>
  1048.    User-Agent: FreeSWITCH
  1049.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1050.    Supported: timer, path, replaces
  1051.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  1052.    Content-Length: 0
  1053.  
  1054.    ------------------------------------------------------------------------
  1055. recv 459 bytes from udp/[67.X.X.237]:5061 at 21:46:33.642109:
  1056.    ------------------------------------------------------------------------
  1057.    NOTIFY sip:64.myvoipdomain.com SIP/2.0
  1058.    Via: SIP/2.0/UDP 67.X.X.237:5061;branch=z9hG4bK-b6a70bc8;rport
  1059.    From: "102" <sip:102@64.myvoipdomain.com>;tag=7c76146b421ec24bo1
  1060.    To: <sip:64.myvoipdomain.com>
  1061.    Call-ID: ca286b-d1b9be4b@67.X.X.237
  1062.    CSeq: 11 NOTIFY
  1063.    Max-Forwards: 70
  1064.    Contact: "102" <sip:102@67.X.X.237:5061;ref=102>
  1065.    Event: keep-alive
  1066.    User-Agent: Cisco/SPA122-1.2.1(004)
  1067.    Allow-Events: talk, hold, conference
  1068.    Content-Length: 0
  1069.  
  1070.    ------------------------------------------------------------------------
  1071. send 656 bytes to udp/[67.X.X.237]:5061 at 21:46:33.642477:
  1072.    ------------------------------------------------------------------------
  1073.    SIP/2.0 200 OK
  1074.    Via: SIP/2.0/UDP 67.X.X.237:5061;branch=z9hG4bK-b6a70bc8;rport=5061
  1075.    From: "102" <sip:102@64.myvoipdomain.com>;tag=7c76146b421ec24bo1
  1076.    To: <sip:64.myvoipdomain.com>;tag=Say2yUQ6UDZBK
  1077.    Call-ID: ca286b-d1b9be4b@67.X.X.237
  1078.    CSeq: 11 NOTIFY
  1079.    Contact: <sip:X.X.X.67>
  1080.    User-Agent: FreeSWITCH
  1081.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1082.    Supported: timer, path, replaces
  1083.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  1084.    Content-Length: 0
  1085.  
  1086.      ------------------------------------------------------------------------
  1087. recv 879 bytes from udp/[67.X.X.237]:5060 at 21:46:42.187510:
  1088.    ------------------------------------------------------------------------
  1089.    SIP/2.0 200 OK
  1090.    To: <sip:101@67.X.X.237:5060>;tag=8f156975d6339a5bi0
  1091.    From: "5207050202" <sip:5207050202@64.myvoipdomain.com>;tag=gSt3e8Z6NXp3a
  1092.    Call-ID: 78e31ab7-9d2e-1236-bd8b-626338373834
  1093.    CSeq: 119906123 INVITE
  1094.    Via: SIP/2.0/UDP X.X.X.67;branch=z9hG4bK5rDF1BZ19Ntvm;rport=5060
  1095.    Contact: "101" <sip:101@67.X.X.237:5060>
  1096.    Server: Cisco/SPA122-1.2.1(004)
  1097.    Remote-Party-ID: "101" <sip:101@64.myvoipdomain.com>;screen=yes;party=called
  1098.    Allow-Events: talk, hold, conference
  1099.    Content-Length: 251
  1100.   Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER
  1101.    Supported: replaces
  1102.    Content-Type: application/sdp
  1103.  
  1104.    v=0
  1105.    o=- 19149 19149 IN IP4 67.X.X.237
  1106.    s=-
  1107.    c=IN IP4 67.X.X.237
  1108.    t=0 0
  1109.    m=audio 16460 RTP/AVP 0 100 101
  1110.    a=rtpmap:0 PCMU/8000
  1111.    a=rtpmap:100 NSE/8000
  1112.    a=fmtp:100 192-193
  1113.    a=rtpmap:101 telephone-event/8000
  1114.    a=fmtp:101 0-15
  1115.    a=ptime:20
  1116.    a=sendrecv
  1117.    ------------------------------------------------------------------------
  1118. 2018-03-07 21:46:42.188380 [INFO] sofia.c:1279 sofia/internal/101@67.X.X.237:5060 Update Callee ID to "101" <101>
  1119. 2018-03-07 21:46:42.188380 [DEBUG] sofia.c:7084 Channel sofia/internal/101@67.X.X.237:5060 entering state [completing][200]
  1120. 2018-03-07 21:46:42.188380 [DEBUG] sofia.c:7094 Remote SDP:
  1121. v=0
  1122. o=- 19149 19149 IN IP4 67.X.X.237
  1123. s=-
  1124. c=IN IP4 67.X.X.237
  1125. t=0 0
  1126. m=audio 16460 RTP/AVP 0 100 101
  1127. a=rtpmap:0 PCMU/8000
  1128. a=rtpmap:100 NSE/8000
  1129. a=fmtp:100 192-193
  1130. a=rtpmap:101 telephone-event/8000
  1131. a=fmtp:101 0-15
  1132. a=ptime:20
  1133.  
  1134. send 389 bytes to udp/[67.X.X.237]:5060 at 21:46:42.189703:
  1135.    ------------------------------------------------------------------------
  1136.    ACK sip:101@67.X.X.237:5060 SIP/2.0
  1137.    Via: SIP/2.0/UDP X.X.X.67;rport;branch=z9hG4bK616726F56ygFg
  1138.    Max-Forwards: 70
  1139.    From: "5207050202" <sip:5207050202@64.myvoipdomain.com>;tag=gSt3e8Z6NXp3a
  1140.    To: <sip:101@67.X.X.237:5060>;tag=8f156975d6339a5bi0
  1141.    Call-ID: 78e31ab7-9d2e-1236-bd8b-626338373834
  1142.    CSeq: 119906123 ACK
  1143.    Contact: <sip:mod_sofia@X.X.X.67:5060>
  1144.    Content-Length: 0
  1145.  
  1146.    ------------------------------------------------------------------------
  1147. 2018-03-07 21:46:42.188380 [DEBUG] sofia.c:7084 Channel sofia/internal/101@67.X.X.237:5060 entering state [ready][200]
  1148. 2018-03-07 21:46:42.188380 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  1149. 2018-03-07 21:46:42.188380 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  1150. 2018-03-07 21:46:42.188380 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [NSE:100:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  1151. 2018-03-07 21:46:42.188380 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
  1152. 2018-03-07 21:46:42.188380 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/101@67.X.X.237:5060 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  1153. 2018-03-07 21:46:42.188380 [DEBUG] switch_core_codec.c:111 sofia/internal/101@67.X.X.237:5060 Original read codec set to PCMU:0
  1154. 2018-03-07 21:46:42.188380 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
  1155. 2018-03-07 21:46:42.188380 [DEBUG] switch_core_media.c:4767 sofia/internal/101@67.X.X.237:5060 Set 2833 dtmf send payload to 101 recv payload to 101
  1156. 2018-03-07 21:46:42.188380 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/101@67.X.X.237:5060] X.X.X.67 port 26480 -> 67.X.X.237 port 16460 codec: 0 ms: 20
  1157. 2018-03-07 21:46:42.188380 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms
  1158. 2018-03-07 21:46:42.188380 [DEBUG] switch_core_media.c:7179 sofia/internal/101@67.X.X.237:5060 Set 2833 dtmf send payload to 101
  1159. 2018-03-07 21:46:42.188380 [DEBUG] switch_core_media.c:7186 sofia/internal/101@67.X.X.237:5060 Set 2833 dtmf receive payload to 101
  1160. 2018-03-07 21:46:42.188380 [DEBUG] switch_core_media.c:7209 sofia/internal/101@67.X.X.237:5060 Set rtp dtmf delay to 40
  1161. 2018-03-07 21:46:42.188380 [NOTICE] sofia.c:8218 Channel [sofia/internal/101@67.X.X.237:5060] has been answered
  1162. 2018-03-07 21:46:42.188380 [DEBUG] switch_channel.c:3773 (sofia/internal/101@67.X.X.237:5060) Callstate Change DOWN -> ACTIVE
  1163. EXECUTE sofia/internal/101@67.X.X.237:5060 lua(/usr/share/freeswitch/scripts/confirm.lua)
  1164. 2018-03-07 21:46:42.208386 [DEBUG] switch_cpp.cpp:1112 sofia/internal/101@67.X.X.237:5060 destroy/unlink session from object
  1165. 2018-03-07 21:46:42.208386 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/external/5207050202@X.X.X.69.
  1166. 2018-03-07 21:46:42.208386 [DEBUG] mod_sofia.c:850 Local SDP sofia/external/5207050202@X.X.X.69:
  1167. v=0
  1168. o=FreeSWITCH 1520454012 1520454014 IN IP4 X.X.X.67
  1169. s=FreeSWITCH
  1170. c=IN IP4 X.X.X.67
  1171. t=0 0
  1172. m=audio 30332 RTP/AVP 0 101
  1173. a=rtpmap:0 PCMU/8000
  1174. a=rtpmap:101 telephone-event/8000
  1175. a=fmtp:101 0-16
  1176. a=ptime:20
  1177. a=sendrecv
  1178. a=rtcp:30333 IN IP4 X.X.X.67
  1179.  
  1180. send 1027 bytes to udp/[X.X.X.69]:5080 at 21:46:42.227592:
  1181.    ------------------------------------------------------------------------
  1182.    SIP/2.0 200 OK
  1183.    Via: SIP/2.0/UDP X.X.X.69:5080;rport=5080;branch=z9hG4bKp1Qv85aX6m6KD
  1184.    From: "5207050202" <sip:5207050202@X.X.X.69>;tag=UNXQFpK4m7Q6a
  1185.    To: <sip:5203810101@X.X.X.67:5080>;tag=4FvSQUg0pF2BD
  1186.    Call-ID: 4ffaf53c-9db9-1236-91a0-623962333032
  1187.    CSeq: 119935938 INVITE
  1188.    Contact: <sip:5203810101@X.X.X.67:5080;transport=udp>
  1189.    User-Agent: FreeSWITCH
  1190.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  1191.    Supported: timer, path, replaces
  1192.    Allow-Events: talk, hold, conference, refer
  1193.    Content-Type: application/sdp
  1194.    Content-Disposition: session
  1195.    Content-Length: 251
  1196.    X-FS-Display-Name: 101
  1197.    X-FS-Display-Number: sip:101@X.X.X.67
  1198.    X-FS-Support: update_display,send_info
  1199.    P-Asserted-Identity: "101" <sip:101@X.X.X.67>
  1200.  
  1201. 2018-03-07 21:46:42.208386 [NOTICE] switch_ivr_originate.c:3647 Channel [sofia/external/5207050202@X.X.X.69] has been answered
  1202.    v=0
  1203.    o=FreeSWITCH 1520454012 1520454013 IN IP4 X.X.X.67
  1204.    s=FreeSWITCH
  1205.    c=IN IP4 X.X.X.67
  1206.    t=0 0
  1207.    m=audio 30332 RTP/AVP 0 101
  1208.    a=rtpmap:0 PCMU/8000
  1209.    a=rtpmap:101 telephone-event/8000
  1210.    a=fmtp:101 0-16
  1211.    a=ptime:20
  1212.    a=rtcp:30333 IN IP4 X.X.X.67
  1213.    ------------------------------------------------------------------------
  1214. 2018-03-07 21:46:42.208386 [DEBUG] switch_channel.c:3773 (sofia/external/5207050202@X.X.X.69) Callstate Change EARLY -> ACTIVE
  1215. 2018-03-07 21:46:42.228709 [DEBUG] sofia.c:7084 Channel sofia/external/5207050202@X.X.X.69 entering state [completed][200]
  1216. 2018-03-07 21:46:42.228709 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/101@67.X.X.237:5060]
  1217. 2018-03-07 21:46:42.228709 [DEBUG] switch_ivr_bridge.c:1614 (sofia/internal/101@67.X.X.237:5060) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  1218. 2018-03-07 21:46:42.228709 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/101@67.X.X.237:5060) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 4870)
  1219. 2018-03-07 21:46:42.228709 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/101@67.X.X.237:5060) State EXCHANGE_MEDIA
  1220. 2018-03-07 21:46:42.228709 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
  1221. recv 402 bytes from udp/[X.X.X.69]:5080 at 21:46:42.231121:
  1222.    ------------------------------------------------------------------------
  1223.    ACK sip:5203810101@X.X.X.67:5080;transport=udp SIP/2.0
  1224.    Via: SIP/2.0/UDP X.X.X.69:5080;rport;branch=z9hG4bKQaHNa1U03Xv6r
  1225.    Max-Forwards: 70
  1226.    From: "5207050202" <sip:5207050202@X.X.X.69>;tag=UNXQFpK4m7Q6a
  1227.    To: <sip:5203810101@X.X.X.67:5080>;tag=4FvSQUg0pF2BD
  1228.    Call-ID: 4ffaf53c-9db9-1236-91a0-623962333032
  1229.    CSeq: 119935938 ACK
  1230.    Contact: <sip:mod_sofia@X.X.X.69:5080>
  1231.    Content-Length: 0
  1232.  
  1233.    ------------------------------------------------------------------------
  1234. 2018-03-07 21:46:42.228709 [DEBUG] sofia.c:7084 Channel sofia/external/5207050202@X.X.X.69 entering state [ready][200]
  1235. send 640 bytes to udp/[X.X.X.69]:5080 at 21:46:42.267257:
  1236.    ------------------------------------------------------------------------
  1237.   INFO sip:mod_sofia@X.X.X.69:5080 SIP/2.0
  1238.    Via: SIP/2.0/UDP X.X.X.67:5080;rport;branch=z9hG4bK4BS4UmaD03UKQ
  1239.    Max-Forwards: 70
  1240.    From: <sip:5203810101@X.X.X.67:5080>;tag=4FvSQUg0pF2BD
  1241.    To: "5207050202" <sip:5207050202@X.X.X.69>;tag=UNXQFpK4m7Q6a
  1242.    Call-ID: 4ffaf53c-9db9-1236-91a0-623962333032
  1243.   CSeq: 119906137 INFO
  1244.    Contact: <sip:5203810101@X.X.X.67:5080;transport=udp>
  1245.    User-Agent: FreeSWITCH
  1246.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  1247.    Supported: timer, path, replaces
  1248.    Content-Type: message/update_display
  1249.    Content-Length: 0
  1250.    X-FS-Display-Name: 101
  1251.    X-FS-Display-Number: 101
  1252.  
  1253.    ------------------------------------------------------------------------
  1254. recv 527 bytes from udp/[X.X.X.69]:5080 at 21:46:42.269495:
  1255.    ------------------------------------------------------------------------
  1256.    SIP/2.0 200 OK
  1257.    Via: SIP/2.0/UDP X.X.X.67:5080;rport=5080;branch=z9hG4bK4BS4UmaD03UKQ
  1258.    From: <sip:5203810101@X.X.X.67:5080>;tag=4FvSQUg0pF2BD
  1259.    To: "5207050202" <sip:5207050202@X.X.X.69>;tag=UNXQFpK4m7Q6a
  1260.    Call-ID: 4ffaf53c-9db9-1236-91a0-623962333032
  1261.   CSeq: 119906137 INFO
  1262.    User-Agent: FreeSWITCH-mod_sofia/1.2.24+git~20150504T151740Z~da04da87df~64bit
  1263.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1264.    Supported: timer, path, replaces
  1265.    Content-Length: 0
  1266.  
  1267.    ------------------------------------------------------------------------
  1268. recv 729 bytes from udp/[X.X.X.69]:5080 at 21:46:42.278288:
  1269.    ------------------------------------------------------------------------
  1270.   INFO sip:5203810101@X.X.X.67:5080;transport=udp SIP/2.0
  1271.    Via: SIP/2.0/UDP X.X.X.69:5080;rport;branch=z9hG4bKrKaecvc406jSm
  1272.    Max-Forwards: 70
  1273.    From: "5207050202" <sip:5207050202@X.X.X.69>;tag=UNXQFpK4m7Q6a
  1274.    To: <sip:5203810101@X.X.X.67:5080>;tag=4FvSQUg0pF2BD
  1275.    Call-ID: 4ffaf53c-9db9-1236-91a0-623962333032
  1276.   CSeq: 119935939 INFO
  1277.    Contact: <sip:mod_sofia@X.X.X.69:5080>
  1278.    User-Agent: FreeSWITCH-mod_sofia/1.2.24+git~20150504T151740Z~da04da87df~64bit
  1279.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1280.    Supported: timer, path, replaces
  1281.    Content-Type: message/update_display
  1282.    Content-Length: 0
  1283.    X-FS-Display-Name: 5207050202
  1284.    X-FS-Display-Number: 5207050202
  1285.  
  1286.    ------------------------------------------------------------------------
  1287. 2018-03-07 21:46:42.268832 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed.
  1288. send 452 bytes to udp/[X.X.X.69]:5080 at 21:46:42.288901:
  1289.    ------------------------------------------------------------------------
  1290.    SIP/2.0 200 OK
  1291.    Via: SIP/2.0/UDP X.X.X.69:5080;rport=5080;branch=z9hG4bKrKaecvc406jSm
  1292.    From: "5207050202" <sip:5207050202@X.X.X.69>;tag=UNXQFpK4m7Q6a
  1293.    To: <sip:5203810101@X.X.X.67:5080>;tag=4FvSQUg0pF2BD
  1294.    Call-ID: 4ffaf53c-9db9-1236-91a0-623962333032
  1295.   CSeq: 119935939 INFO
  1296.    User-Agent: FreeSWITCH
  1297.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  1298.    Supported: timer, path, replaces
  1299.    Content-Length: 0
  1300.  
  1301.    ------------------------------------------------------------------------
  1302. 2018-03-07 21:46:42.328402 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed.
  1303.    ------------------------------------------------------------------------
  1304. recv 461 bytes from udp/[67.X.X.237]:5060 at 21:46:48.442608:
  1305.    ------------------------------------------------------------------------
  1306.    NOTIFY sip:64.myvoipdomain.com SIP/2.0
  1307.    Via: SIP/2.0/UDP 67.X.X.237:5060;branch=z9hG4bK-32a0b183;rport
  1308.    From: "101" <sip:101@64.myvoipdomain.com>;tag=13f6156b3c36b54bo0
  1309.    To: <sip:64.myvoipdomain.com>
  1310.    Call-ID: 4a378aeb-5527bdcb@67.X.X.237
  1311.    CSeq: 12 NOTIFY
  1312.    Max-Forwards: 70
  1313.    Contact: "101" <sip:101@67.X.X.237:5060;ref=101>
  1314.    Event: keep-alive
  1315.    User-Agent: Cisco/SPA122-1.2.1(004)
  1316.    Allow-Events: talk, hold, conference
  1317.    Content-Length: 0
  1318.  
  1319.    ------------------------------------------------------------------------
  1320. send 658 bytes to udp/[67.X.X.237]:5060 at 21:46:48.442907:
  1321.    ------------------------------------------------------------------------
  1322.    SIP/2.0 200 OK
  1323.    Via: SIP/2.0/UDP 67.X.X.237:5060;branch=z9hG4bK-32a0b183;rport=5060
  1324.    From: "101" <sip:101@64.myvoipdomain.com>;tag=13f6156b3c36b54bo0
  1325.    To: <sip:64.myvoipdomain.com>;tag=yQvy72BrDte9c
  1326.    Call-ID: 4a378aeb-5527bdcb@67.X.X.237
  1327.    CSeq: 12 NOTIFY
  1328.    Contact: <sip:X.X.X.67>
  1329.    User-Agent: FreeSWITCH
  1330.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1331.    Supported: timer, path, replaces
  1332.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  1333.    Content-Length: 0
  1334.  
  1335.    ------------------------------------------------------------------------
  1336. recv 459 bytes from udp/[67.X.X.237]:5061 at 21:46:48.727452:
  1337.    ------------------------------------------------------------------------
  1338.    NOTIFY sip:64.myvoipdomain.com SIP/2.0
  1339.    Via: SIP/2.0/UDP 67.X.X.237:5061;branch=z9hG4bK-86434f22;rport
  1340.    From: "102" <sip:102@64.myvoipdomain.com>;tag=7c76146b421ec24bo1
  1341.    To: <sip:64.myvoipdomain.com>
  1342.    Call-ID: ca286b-d1b9be4b@67.X.X.237
  1343.    CSeq: 12 NOTIFY
  1344.    Max-Forwards: 70
  1345.    Contact: "102" <sip:102@67.X.X.237:5061;ref=102>
  1346.    Event: keep-alive
  1347.    User-Agent: Cisco/SPA122-1.2.1(004)
  1348.    Allow-Events: talk, hold, conference
  1349.    Content-Length: 0
  1350.  
  1351.    ------------------------------------------------------------------------
  1352. send 656 bytes to udp/[67.X.X.237]:5061 at 21:46:48.727807:
  1353.    ------------------------------------------------------------------------
  1354.    SIP/2.0 200 OK
  1355.    Via: SIP/2.0/UDP 67.X.X.237:5061;branch=z9hG4bK-86434f22;rport=5061
  1356.    From: "102" <sip:102@64.myvoipdomain.com>;tag=7c76146b421ec24bo1
  1357.    To: <sip:64.myvoipdomain.com>;tag=Z0NQ9XvUa34Ur
  1358.    Call-ID: ca286b-d1b9be4b@67.X.X.237
  1359.    CSeq: 12 NOTIFY
  1360.    Contact: <sip:X.X.X.67>
  1361.    User-Agent: FreeSWITCH
  1362.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1363.    Supported: timer, path, replaces
  1364.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  1365.    Content-Length: 0
  1366.  
  1367.    ------------------------------------------------------------------------
  1368. recv 649 bytes from udp/[X.X.X.69]:5080 at 21:46:50.886134:
  1369.    ------------------------------------------------------------------------
  1370.    BYE sip:5203810101@X.X.X.67:5080;transport=udp SIP/2.0
  1371.    Via: SIP/2.0/UDP X.X.X.69:5080;rport;branch=z9hG4bKSv36DQX7XF9Bg
  1372.    Max-Forwards: 70
  1373.    From: "5207050202" <sip:5207050202@X.X.X.69>;tag=UNXQFpK4m7Q6a
  1374.    To: <sip:5203810101@X.X.X.67:5080>;tag=4FvSQUg0pF2BD
  1375.    Call-ID: 4ffaf53c-9db9-1236-91a0-623962333032
  1376.    CSeq: 119935940 BYE
  1377.    Contact: <sip:mod_sofia@X.X.X.69:5080>
  1378.    User-Agent: FreeSWITCH-mod_sofia/1.2.24+git~20150504T151740Z~da04da87df~64bit
  1379.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1380.    Supported: timer, path, replaces
  1381.    Reason: Q.850;cause=16
  1382.    Content-Length: 0
  1383.  
  1384.    ------------------------------------------------------------------------
  1385. 2018-03-07 21:46:50.868389 [NOTICE] sofia.c:1012 Hangup sofia/external/5207050202@X.X.X.69 [CS_EXECUTE] [NORMAL_CLEARING]
  1386. send 451 bytes to udp/[X.X.X.69]:5080 at 21:46:50.888559:
  1387.    ------------------------------------------------------------------------
  1388.    SIP/2.0 200 OK
  1389.    Via: SIP/2.0/UDP X.X.X.69:5080;rport=5080;branch=z9hG4bKSv36DQX7XF9Bg
  1390.    From: "5207050202" <sip:5207050202@X.X.X.69>;tag=UNXQFpK4m7Q6a
  1391.    To: <sip:5203810101@X.X.X.67:5080>;tag=4FvSQUg0pF2BD
  1392.    Call-ID: 4ffaf53c-9db9-1236-91a0-623962333032
  1393.    CSeq: 119935940 BYE
  1394.    User-Agent: FreeSWITCH
  1395.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  1396.    Supported: timer, path, replaces
  1397.    Content-Length: 0
  1398.  
  1399.    ------------------------------------------------------------------------
  1400. 2018-03-07 21:46:50.888379 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/external/5207050202@X.X.X.69]
  1401. 2018-03-07 21:46:50.888379 [DEBUG] switch_ivr_bridge.c:706 sofia/external/5207050202@X.X.X.69 ending bridge by request from write function
  1402. 2018-03-07 21:46:50.888379 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/internal/101@67.X.X.237:5060]
  1403. 2018-03-07 21:46:50.888379 [NOTICE] switch_ivr_bridge.c:904 Hangup sofia/internal/101@67.X.X.237:5060 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  1404. 2018-03-07 21:46:50.888379 [DEBUG] switch_ivr_bridge.c:1712 sofia/internal/101@67.X.X.237:5060 skip receive message [UNBRIDGE] (channel is hungup already)
  1405. 2018-03-07 21:46:50.888379 [DEBUG] switch_ivr_bridge.c:1715 sofia/external/5207050202@X.X.X.69 skip receive message [UNBRIDGE] (channel is hungup already)
  1406. 2018-03-07 21:46:50.888379 [DEBUG] switch_core_session.c:2815 sofia/external/5207050202@X.X.X.69 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  1407. 2018-03-07 21:46:50.888379 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x27987a0 released.
  1408. 2018-03-07 21:46:50.888379 [DEBUG] switch_cpp.cpp:1112 sofia/external/5207050202@X.X.X.69 destroy/unlink session from object
  1409. 2018-03-07 21:46:50.888379 [DEBUG] switch_core_session.c:2815 sofia/external/5207050202@X.X.X.69 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  1410. 2018-03-07 21:46:50.888379 [DEBUG] switch_core_state_machine.c:650 (sofia/external/5207050202@X.X.X.69) State EXECUTE going to sleep
  1411. 2018-03-07 21:46:50.888379 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5207050202@X.X.X.69) Running State Change CS_HANGUP (Cur 2 Tot 4870)
  1412. 2018-03-07 21:46:50.888379 [DEBUG] switch_core_state_machine.c:850 (sofia/external/5207050202@X.X.X.69) Callstate Change ACTIVE -> HANGUP
  1413. 2018-03-07 21:46:50.888379 [DEBUG] switch_core_state_machine.c:852 (sofia/external/5207050202@X.X.X.69) State HANGUP
  1414. 2018-03-07 21:46:50.888379 [DEBUG] mod_sofia.c:438 Channel sofia/external/5207050202@X.X.X.69 hanging up, cause: NORMAL_CLEARING
  1415. 2018-03-07 21:46:50.888379 [DEBUG] switch_core_state_machine.c:60 sofia/external/5207050202@X.X.X.69 Standard HANGUP, cause: NORMAL_CLEARING
  1416. 2018-03-07 21:46:50.888379 [DEBUG] switch_core_state_machine.c:852 (sofia/external/5207050202@X.X.X.69) State HANGUP going to sleep
  1417. 2018-03-07 21:46:50.888379 [DEBUG] switch_core_state_machine.c:619 (sofia/external/5207050202@X.X.X.69) State Change CS_HANGUP -> CS_REPORTING
  1418. 2018-03-07 21:46:50.888379 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5207050202@X.X.X.69) Running State Change CS_REPORTING (Cur 2 Tot 4870)
  1419. 2018-03-07 21:46:50.888379 [DEBUG] switch_core_state_machine.c:938 (sofia/external/5207050202@X.X.X.69) State REPORTING
  1420. 2018-03-07 21:46:50.909008 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/101@67.X.X.237:5060) State EXCHANGE_MEDIA going to sleep
  1421. 2018-03-07 21:46:50.909008 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/101@67.X.X.237:5060) Running State Change CS_HANGUP (Cur 2 Tot 4870)
  1422. 2018-03-07 21:46:50.909008 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/101@67.X.X.237:5060) Callstate Change ACTIVE -> HANGUP
  1423. 2018-03-07 21:46:50.909008 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/101@67.X.X.237:5060) State HANGUP
  1424. 2018-03-07 21:46:50.909008 [DEBUG] mod_sofia.c:432 sofia/internal/101@67.X.X.237:5060 Overriding SIP cause 480 with 200 from the other leg
  1425. 2018-03-07 21:46:50.909008 [DEBUG] mod_sofia.c:438 Channel sofia/internal/101@67.X.X.237:5060 hanging up, cause: NORMAL_CLEARING
  1426. 2018-03-07 21:46:50.909008 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/internal/101@67.X.X.237:5060
  1427. 2018-03-07 21:46:50.909008 [DEBUG] switch_core_state_machine.c:60 sofia/internal/101@67.X.X.237:5060 Standard HANGUP, cause: NORMAL_CLEARING
  1428. 2018-03-07 21:46:50.909008 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/101@67.X.X.237:5060) State HANGUP going to sleep
  1429. 2018-03-07 21:46:50.909008 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/101@67.X.X.237:5060) State Change CS_HANGUP -> CS_REPORTING
  1430. 2018-03-07 21:46:50.909008 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/101@67.X.X.237:5060) Running State Change CS_REPORTING (Cur 2 Tot 4870)
  1431. 2018-03-07 21:46:50.909008 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/101@67.X.X.237:5060) State REPORTING
  1432. 2018-03-07 21:46:50.909008 [DEBUG] switch_core_state_machine.c:174 sofia/internal/101@67.X.X.237:5060 Standard REPORTING, cause: NORMAL_CLEARING
  1433. 2018-03-07 21:46:50.909008 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/101@67.X.X.237:5060) State REPORTING going to sleep
  1434. 2018-03-07 21:46:50.909008 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/101@67.X.X.237:5060) State Change CS_REPORTING -> CS_DESTROY
  1435. 2018-03-07 21:46:50.909008 [DEBUG] switch_core_session.c:1665 Session 4870 (sofia/internal/101@67.X.X.237:5060) Locked, Waiting on external entities
  1436. 2018-03-07 21:46:50.909008 [NOTICE] switch_core_session.c:1683 Session 4870 (sofia/internal/101@67.X.X.237:5060) Ended
  1437. 2018-03-07 21:46:50.909008 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/101@67.X.X.237:5060 [CS_DESTROY]
  1438. 2018-03-07 21:46:50.909008 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/101@67.X.X.237:5060) Running State Change CS_DESTROY (Cur 1 Tot 4870)
  1439. 2018-03-07 21:46:50.909008 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/101@67.X.X.237:5060) State DESTROY
  1440. 2018-03-07 21:46:50.909008 [DEBUG] mod_sofia.c:343 sofia/internal/101@67.X.X.237:5060 SOFIA DESTROY
  1441. 2018-03-07 21:46:50.909008 [DEBUG] switch_core_state_machine.c:181 sofia/internal/101@67.X.X.237:5060 Standard DESTROY
  1442. 2018-03-07 21:46:50.909008 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/101@67.X.X.237:5060) State DESTROY going to sleep
  1443. send 538 bytes to udp/[67.X.X.237]:5060 at 21:46:50.919483:
  1444.    ------------------------------------------------------------------------
  1445.    BYE sip:101@67.X.X.237:5060 SIP/2.0
  1446.    Via: SIP/2.0/UDP X.X.X.67;rport;branch=z9hG4bK7a0041083761B
  1447.    Max-Forwards: 70
  1448.    From: "5207050202" <sip:5207050202@64.myvoipdomain.com>;tag=gSt3e8Z6NXp3a
  1449.    To: <sip:101@67.X.X.237:5060>;tag=8f156975d6339a5bi0
  1450.    Call-ID: 78e31ab7-9d2e-1236-bd8b-626338373834
  1451.    CSeq: 119906124 BYE
  1452.    User-Agent: FreeSWITCH
  1453.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1454.    Supported: timer, path, replaces
  1455.    Reason: Q.850;cause=16
  1456.    Content-Length: 0
  1457.  
  1458.    ------------------------------------------------------------------------
  1459. recv 464 bytes from udp/[67.X.X.237]:5060 at 21:46:51.002664:
  1460.    ------------------------------------------------------------------------
  1461.    SIP/2.0 200 OK
  1462.    To: <sip:101@67.X.X.237:5060>;tag=8f156975d6339a5bi0
  1463.    From: "5207050202" <sip:5207050202@64.myvoipdomain.com>;tag=gSt3e8Z6NXp3a
  1464.    Call-ID: 78e31ab7-9d2e-1236-bd8b-626338373834
  1465.    CSeq: 119906124 BYE
  1466.    Via: SIP/2.0/UDP X.X.X.67;branch=z9hG4bK7a0041083761B;rport=5060
  1467.    Server: Cisco/SPA122-1.2.1(004)
  1468.    Allow-Events: talk, hold, conference
  1469.    P-RTP-Stat: PS=434,OS=69440,PR=428,OR=68480,PL=3,JI=0,LA=0,DU=8,EN=G711u,DE=G711u
  1470.    Content-Length: 0
  1471.  
  1472.    ------------------------------------------------------------------------
  1473. 2018-03-07 21:46:51.008396 [DEBUG] switch_core_state_machine.c:174 sofia/external/5207050202@X.X.X.69 Standard REPORTING, cause: NORMAL_CLEARING
  1474. 2018-03-07 21:46:51.008396 [DEBUG] switch_core_state_machine.c:938 (sofia/external/5207050202@X.X.X.69) State REPORTING going to sleep
  1475. 2018-03-07 21:46:51.008396 [DEBUG] switch_core_state_machine.c:610 (sofia/external/5207050202@X.X.X.69) State Change CS_REPORTING -> CS_DESTROY
  1476. 2018-03-07 21:46:51.008396 [DEBUG] switch_core_session.c:1665 Session 4868 (sofia/external/5207050202@X.X.X.69) Locked, Waiting on external entities
  1477. 2018-03-07 21:46:51.008396 [NOTICE] switch_core_session.c:1683 Session 4868 (sofia/external/5207050202@X.X.X.69) Ended
  1478. 2018-03-07 21:46:51.008396 [NOTICE] switch_core_session.c:1687 Close Channel sofia/external/5207050202@X.X.X.69 [CS_DESTROY]
  1479. 2018-03-07 21:46:51.008396 [DEBUG] switch_core_state_machine.c:741 (sofia/external/5207050202@X.X.X.69) Running State Change CS_DESTROY (Cur 0 Tot 4870)
  1480. 2018-03-07 21:46:51.008396 [DEBUG] switch_core_state_machine.c:751 (sofia/external/5207050202@X.X.X.69) State DESTROY
  1481. 2018-03-07 21:46:51.008396 [DEBUG] mod_sofia.c:343 sofia/external/5207050202@X.X.X.69 SOFIA DESTROY
  1482. 2018-03-07 21:46:51.008396 [DEBUG] switch_core_state_machine.c:181 sofia/external/5207050202@X.X.X.69 Standard DESTROY
  1483. 2018-03-07 21:46:51.008396 [DEBUG] switch_core_state_machine.c:751 (sofia/external/5207050202@X.X.X.69) State DESTROY going to sleep
  1484. send 543 bytes to udp/[X.X.X.69]:5080 at 21:46:55.581628:
  1485.    ------------------------------------------------------------------------
  1486.    OPTIONS sip:sbc.myvoipdomain.com:5080;transport=udp SIP/2.0
  1487.    Via: SIP/2.0/UDP X.X.X.67:5080;rport;branch=z9hG4bK5mjXXFUgXcj6j
  1488.    Max-Forwards: 70
  1489.    From: <sip:sbc.myvoipdomain.com:5080>;tag=7a83vc3aea53F
  1490.    To: <sip:sbc.myvoipdomain.com:5080>
  1491.    Call-ID: 91a546df-9d2e-1236-bd8b-626338373834
  1492.    CSeq: 119380030 OPTIONS
  1493.    User-Agent: FreeSWITCH
  1494.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  1495.    Supported: timer, path, replaces
  1496.    Allow-Events: talk, hold, conference, refer
  1497.    Content-Length: 0
  1498.  
  1499.    ------------------------------------------------------------------------
  1500. recv 739 bytes from udp/[X.X.X.69]:5080 at 21:46:55.582374:
  1501.    ------------------------------------------------------------------------
  1502.    SIP/2.0 200 OK
  1503.    Via: SIP/2.0/UDP X.X.X.67:5080;rport=5080;branch=z9hG4bK5mjXXFUgXcj6j
  1504.    From: <sip:sbc.myvoipdomain.com:5080>;tag=7a83vc3aea53F
  1505.    To: <sip:sbc.myvoipdomain.com:5080>;tag=1BNctrrS3vXpg
  1506.    Call-ID: 91a546df-9d2e-1236-bd8b-626338373834
  1507.    CSeq: 119380030 OPTIONS
  1508.    Contact: <sip:X.X.X.69:5080>
  1509.    User-Agent: FreeSWITCH-mod_sofia/1.2.24+git~20150504T151740Z~da04da87df~64bit
  1510.    Accept: application/sdp
  1511.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1512.    Supported: timer, path, replaces
  1513.    Allow-Events: talk, hold, conference, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  1514.    Content-Length: 0
  1515.  
  1516.    ------------------------------------------------------------------------
  1517. recv 461 bytes from udp/[67.X.X.237]:5060 at 21:47:03.482640:
  1518.    ------------------------------------------------------------------------
  1519.    NOTIFY sip:64.myvoipdomain.com SIP/2.0
  1520.    Via: SIP/2.0/UDP 67.X.X.237:5060;branch=z9hG4bK-7accbbd3;rport
  1521.    From: "101" <sip:101@64.myvoipdomain.com>;tag=13f6156b3c36b54bo0
  1522.    To: <sip:64.myvoipdomain.com>
  1523.    Call-ID: 4a378aeb-5527bdcb@67.X.X.237
  1524.    CSeq: 13 NOTIFY
  1525.    Max-Forwards: 70
  1526.    Contact: "101" <sip:101@67.X.X.237:5060;ref=101>
  1527.    Event: keep-alive
  1528.    User-Agent: Cisco/SPA122-1.2.1(004)
  1529.    Allow-Events: talk, hold, conference
  1530.    Content-Length: 0
  1531.  
  1532.    ------------------------------------------------------------------------
  1533. send 658 bytes to udp/[67.X.X.237]:5060 at 21:47:03.483014:
  1534.    ------------------------------------------------------------------------
  1535.    SIP/2.0 200 OK
  1536.    Via: SIP/2.0/UDP 67.X.X.237:5060;branch=z9hG4bK-7accbbd3;rport=5060
  1537.    From: "101" <sip:101@64.myvoipdomain.com>;tag=13f6156b3c36b54bo0
  1538.    To: <sip:64.myvoipdomain.com>;tag=4DmKj5gDvFmSj
  1539.    Call-ID: 4a378aeb-5527bdcb@67.X.X.237
  1540.    CSeq: 13 NOTIFY
  1541.    Contact: <sip:X.X.X.67>
  1542.    User-Agent: FreeSWITCH
  1543.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1544.    Supported: timer, path, replaces
  1545.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  1546.    Content-Length: 0
  1547.  
  1548.    ------------------------------------------------------------------------
  1549. recv 459 bytes from udp/[67.X.X.237]:5061 at 21:47:03.780177:
  1550.    ------------------------------------------------------------------------
  1551.    NOTIFY sip:64.myvoipdomain.com SIP/2.0
  1552.    Via: SIP/2.0/UDP 67.X.X.237:5061;branch=z9hG4bK-d5964ef4;rport
  1553.    From: "102" <sip:102@64.myvoipdomain.com>;tag=7c76146b421ec24bo1
  1554.    To: <sip:64.myvoipdomain.com>
  1555.    Call-ID: ca286b-d1b9be4b@67.X.X.237
  1556.    CSeq: 13 NOTIFY
  1557.    Max-Forwards: 70
  1558.    Contact: "102" <sip:102@67.X.X.237:5061;ref=102>
  1559.    Event: keep-alive
  1560.    User-Agent: Cisco/SPA122-1.2.1(004)
  1561.    Allow-Events: talk, hold, conference
  1562.    Content-Length: 0
  1563.  
  1564.    ------------------------------------------------------------------------
  1565. send 656 bytes to udp/[67.X.X.237]:5061 at 21:47:03.780557:
  1566.    ------------------------------------------------------------------------
  1567.    SIP/2.0 200 OK
  1568.    Via: SIP/2.0/UDP 67.X.X.237:5061;branch=z9hG4bK-d5964ef4;rport=5061
  1569.    From: "102" <sip:102@64.myvoipdomain.com>;tag=7c76146b421ec24bo1
  1570.    To: <sip:64.myvoipdomain.com>;tag=5pDcm01gSrace
  1571.    Call-ID: ca286b-d1b9be4b@67.X.X.237
  1572.    CSeq: 13 NOTIFY
  1573.    Contact: <sip:X.X.X.67>
  1574.    User-Agent: FreeSWITCH
  1575.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1576.    Supported: timer, path, replaces
  1577.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  1578.    Content-Length: 0
  1579.  
  1580.    ------------------------------------------------------------------------
  1581.