From Bistre Dove, 2 Years ago, written in FreeSWITCH.
- view diff
Embed
  1. freeswitch@freeswitch>
  2. recv 1030 bytes from tcp/[192.168.1.33]:48236 at 16:05:14.785916:
  3.    ------------------------------------------------------------------------
  4.    PUBLISH sip:9110327518@192.168.1.118;transport=TCP SIP/2.0
  5.    Via: SIP/2.0/TCP 192.168.1.33:58810;branch=z9hG4bK-d8754z-3efec5cd215b6465-1---d8754z-
  6.    Max-Forwards: 70
  7.    Contact: <sip:9110327518@117.247.91.162:58810;transport=TCP>
  8.    To: <sip:9110327518@192.168.1.118;transport=TCP>
  9.    From: <sip:9110327518@192.168.1.118;transport=TCP>;tag=98ff5d68
  10.    Call-ID: MmU2ZGFlMWUyZDBiMDQyMTExOWFkNjRhNmEyODgxZjY.
  11.    CSeq: 14 PUBLISH
  12.    Expires: 600
  13.   Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
  14.    Content-Type: application/pidf+xml
  15.    SIP-If-Match: 98ZLJPmk
  16.    Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri
  17.    User-Agent: Z 3.3.25608 r25552
  18.    Event: presence
  19.    Allow-Events: presence, kpml
  20.    Content-Length: 279
  21.    
  22.    <?xml version="1.0" encoding="UTF-8"?>
  23.    <presence xmlns="urn:ietf:params:xml:ns:pidf"
  24.              entity="sip:9110327518@192.168.1.118;transport=TCP">
  25.      <tuple id="9110327518" >
  26.         <status><basic>open</basic></status>
  27.         <note>On the phone</note>
  28.      </tuple>
  29.    </presence>
  30.    ------------------------------------------------------------------------
  31. recv 1000 bytes from tcp/[192.168.1.33]:48236 at 16:05:14.825631:
  32.    ------------------------------------------------------------------------
  33.    PUBLISH sip:97265@192.168.1.118;transport=TCP SIP/2.0
  34.    Via: SIP/2.0/TCP 192.168.1.33:58810;branch=z9hG4bK-d8754z-b8b48820f0bb4937-1---d8754z-
  35.    Max-Forwards: 70
  36.    Contact: <sip:97265@117.247.91.162:58810;transport=TCP>
  37.    To: <sip:97265@192.168.1.118;transport=TCP>
  38.    From: <sip:97265@192.168.1.118;transport=TCP>;tag=1368e36f
  39.    Call-ID: ZDI1YTJhZGM3ODQyY2Q3NzEyOTQyYzRkZjg0M2U3OWM.
  40.    CSeq: 10 PUBLISH
  41.    Expires: 600
  42.   Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
  43.    Content-Type: application/pidf+xml
  44.    SIP-If-Match: M1gl83MI
  45.    Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri
  46.    User-Agent: Z 3.3.25608 r25552
  47.    Event: presence
  48.    Allow-Events: presence, kpml
  49.    Content-Length: 269
  50.    
  51.    <?xml version="1.0" encoding="UTF-8"?>
  52.    <presence xmlns="urn:ietf:params:xml:ns:pidf"
  53.              entity="sip:97265@192.168.1.118;transport=TCP">
  54.      <tuple id="97265" >
  55.         <status><basic>open</basic></status>
  56.         <note>On the phone</note>
  57.      </tuple>
  58.    </presence>
  59.    ------------------------------------------------------------------------
  60. recv 921 bytes from tcp/[192.168.1.33]:48236 at 16:05:14.986351:
  61.    ------------------------------------------------------------------------
  62.    INVITE sip:97264@192.168.1.118;transport=TCP SIP/2.0
  63.    Via: SIP/2.0/TCP 192.168.1.33:58810;branch=z9hG4bK-d8754z-67ee28e05cbeb2f4-1---d8754z-
  64.    Max-Forwards: 70
  65.    Contact: <sip:9110327518@117.247.91.162:58810;transport=TCP>
  66.    To: <sip:97264@192.168.1.118;transport=TCP>
  67.    From: <sip:9110327518@192.168.1.118;transport=TCP>;tag=c325a037
  68.    Call-ID: ZmJhZTBkOGRhZTFhYzQyZWRlNjIzMmRmZThmMTAwYzM.
  69.    CSeq: 1 INVITE
  70.   Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
  71.    Content-Type: application/sdp
  72.    Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri
  73.    User-Agent: Z 3.3.25608 r25552
  74.    Allow-Events: presence, kpml
  75.    Content-Length: 243
  76.    
  77.    v=0
  78.    o=Z 0 0 IN IP4 117.247.91.162
  79.    s=Z
  80.    c=IN IP4 117.247.91.162
  81.    t=0 0
  82.    m=audio 8000 RTP/AVP 3 110 8 0 98 101
  83.    a=rtpmap:110 speex/8000
  84.    a=rtpmap:98 iLBC/8000
  85.    a=fmtp:98 mode=20
  86.    a=rtpmap:101 telephone-event/8000
  87.    a=fmtp:101 0-15
  88.    a=sendrecv
  89.    ------------------------------------------------------------------------
  90. send 341 bytes to tcp/[192.168.1.33]:48236 at 16:05:14.986665:
  91.    ------------------------------------------------------------------------
  92.    SIP/2.0 100 Trying
  93.    Via: SIP/2.0/TCP 192.168.1.33:58810;branch=z9hG4bK-d8754z-67ee28e05cbeb2f4-1---d8754z-;rport=48236
  94.    From: <sip:9110327518@192.168.1.118;transport=TCP>;tag=c325a037
  95.    To: <sip:97264@192.168.1.118;transport=TCP>
  96.    Call-ID: ZmJhZTBkOGRhZTFhYzQyZWRlNjIzMmRmZThmMTAwYzM.
  97.    CSeq: 1 INVITE
  98.    User-Agent: Local
  99.    Content-Length: 0
  100.    
  101.    ------------------------------------------------------------------------
  102. 2017-11-06 16:05:14.981576 [NOTICE] switch_channel.c:1104 New Channel sofia/default/9110327518@192.168.1.118 [d486e76a-fc5b-414d-81a2-0c62494e2a0e]
  103. 2017-11-06 16:05:14.981576 [DEBUG] switch_core_state_machine.c:584 (sofia/default/9110327518@192.168.1.118) Running State Change CS_NEW (Cur 1 Tot 274)
  104. 2017-11-06 16:05:14.981576 [DEBUG] sofia.c:9837 sofia/default/9110327518@192.168.1.118 receiving invite from 192.168.1.33:48236 version: 1.6.18  64bit
  105. 2017-11-06 16:05:14.981576 [DEBUG] sofia.c:9953 IP 192.168.1.33 Approved by acl "default[]". Access Granted.
  106. send 734 bytes to tcp/[192.168.1.33]:48236 at 16:05:15.095728:
  107.    ------------------------------------------------------------------------
  108.    SIP/2.0 200 OK
  109.    Via: SIP/2.0/TCP 192.168.1.33:58810;branch=z9hG4bK-d8754z-3efec5cd215b6465-1---d8754z-;rport=48236
  110.    From: <sip:9110327518@192.168.1.118;transport=TCP>;tag=98ff5d68
  111.    To: <sip:9110327518@192.168.1.118;transport=TCP>;tag=QD0carUKS6X8a
  112.    Call-ID: MmU2ZGFlMWUyZDBiMDQyMTExOWFkNjRhNmEyODgxZjY.
  113.    CSeq: 14 PUBLISH
  114.    Contact: <sip:192.168.1.118>
  115.    Expires: 600
  116.    User-Agent: Local
  117.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  118.    Supported: path, replaces
  119.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  120.    SIP-ETag: q5Hu6egp
  121.    Content-Length: 0
  122.    
  123.    ------------------------------------------------------------------------
  124. send 605 bytes to udp/[1.1.1.1]:5060 at 16:05:15.106664:
  125.    ------------------------------------------------------------------------
  126.    OPTIONS sip:1.1.1.1;transport=udp SIP/2.0
  127.    Via: SIP/2.0/UDP 192.168.1.118;rport;branch=z9hG4bKytFU7Xg3Qr7ge
  128.    Max-Forwards: 70
  129.    From: <sip:1.1.1.1>;tag=t8BQF9Dyg100D
  130.    To: <sip:1.1.1.1>
  131.    Call-ID: 045637c8-3d81-1236-c4b6-000c29f46286
  132.    CSeq: 114465670 OPTIONS
  133.    User-Agent: Local
  134.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  135.    Supported: path, replaces
  136.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  137.    Content-Length: 0
  138.    
  139.    ------------------------------------------------------------------------
  140. send 724 bytes to tcp/[192.168.1.33]:48236 at 16:05:15.345909:
  141.    ------------------------------------------------------------------------
  142.    SIP/2.0 200 OK
  143.    Via: SIP/2.0/TCP 192.168.1.33:58810;branch=z9hG4bK-d8754z-b8b48820f0bb4937-1---d8754z-;rport=48236
  144.    From: <sip:97265@192.168.1.118;transport=TCP>;tag=1368e36f
  145.    To: <sip:97265@192.168.1.118;transport=TCP>;tag=rpS5BKcQpFmUp
  146.    Call-ID: ZDI1YTJhZGM3ODQyY2Q3NzEyOTQyYzRkZjg0M2U3OWM.
  147.    CSeq: 10 PUBLISH
  148.    Contact: <sip:192.168.1.118>
  149.    Expires: 600
  150.    User-Agent: Local
  151.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  152.    Supported: path, replaces
  153.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  154.    SIP-ETag: aLzKWQqp
  155.    Content-Length: 0
  156.    
  157.    ------------------------------------------------------------------------
  158. 2017-11-06 16:05:15.421582 [DEBUG] sofia.c:11132 Setting NAT mode based on via port
  159. 2017-11-06 16:05:15.421582 [DEBUG] sofia.c:7048 Channel sofia/default/9110327518@192.168.1.118 entering state [received][100]
  160. 2017-11-06 16:05:15.421582 [DEBUG] sofia.c:7058 Remote SDP:
  161. v=0
  162. o=Z 0 0 IN IP4 117.247.91.162
  163. s=Z
  164. c=IN IP4 117.247.91.162
  165. t=0 0
  166. m=audio 8000 RTP/AVP 3 110 8 0 98 101
  167. a=rtpmap:110 speex/8000
  168. a=rtpmap:98 iLBC/8000
  169. a=fmtp:98 mode=20
  170. a=rtpmap:101 telephone-event/8000
  171. a=fmtp:101 0-15
  172.  
  173. 2017-11-06 16:05:15.421582 [DEBUG] sofia.c:7450 (sofia/default/9110327518@192.168.1.118) State Change CS_NEW -> CS_INIT
  174. 2017-11-06 16:05:15.421582 [DEBUG] switch_core_state_machine.c:603 (sofia/default/9110327518@192.168.1.118) State NEW
  175. 2017-11-06 16:05:15.421582 [DEBUG] switch_core_state_machine.c:584 (sofia/default/9110327518@192.168.1.118) Running State Change CS_INIT (Cur 1 Tot 274)
  176. 2017-11-06 16:05:15.421582 [DEBUG] switch_core_state_machine.c:627 (sofia/default/9110327518@192.168.1.118) State INIT
  177. 2017-11-06 16:05:15.421582 [DEBUG] mod_sofia.c:90 sofia/default/9110327518@192.168.1.118 SOFIA INIT
  178. 2017-11-06 16:05:15.421582 [DEBUG] switch_core_state_machine.c:40 sofia/default/9110327518@192.168.1.118 Standard INIT
  179. 2017-11-06 16:05:15.421582 [DEBUG] switch_core_state_machine.c:48 (sofia/default/9110327518@192.168.1.118) State Change CS_INIT -> CS_ROUTING
  180. 2017-11-06 16:05:15.421582 [DEBUG] switch_core_state_machine.c:627 (sofia/default/9110327518@192.168.1.118) State INIT going to sleep
  181. 2017-11-06 16:05:15.421582 [DEBUG] switch_core_state_machine.c:584 (sofia/default/9110327518@192.168.1.118) Running State Change CS_ROUTING (Cur 1 Tot 274)
  182. 2017-11-06 16:05:15.421582 [DEBUG] switch_channel.c:2249 (sofia/default/9110327518@192.168.1.118) Callstate Change DOWN -> RINGING
  183. 2017-11-06 16:05:15.421582 [DEBUG] switch_core_state_machine.c:643 (sofia/default/9110327518@192.168.1.118) State ROUTING
  184. 2017-11-06 16:05:15.421582 [DEBUG] mod_sofia.c:143 sofia/default/9110327518@192.168.1.118 SOFIA ROUTING
  185. 2017-11-06 16:05:15.421582 [DEBUG] switch_core_state_machine.c:236 sofia/default/9110327518@192.168.1.118 Standard ROUTING
  186. 2017-11-06 16:05:15.421582 [INFO] mod_dialplan_xml.c:637 Processing 9110327518 <9110327518>->97264 in context default
  187. 2017-11-06 16:05:15.441577 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fa6f415e830 Connected.
  188. 2017-11-06 16:05:15.441577 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM system WHERE group_title IN ('global','opensips','callingcard')
  189. 2017-11-06 16:05:15.441577 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [xml_handler] Params:
  190. 'Event-Name: REQUEST_PARAMS
  191. Core-UUID: b8b0770f-83a5-4654-b268-e2c6ae265441
  192. FreeSWITCH-Hostname: astpptest1
  193. FreeSWITCH-Switchname: freeswitch
  194. FreeSWITCH-IPv4: 192.168.1.118
  195. FreeSWITCH-IPv6: %3A%3A1
  196. Event-Date-Local: 2017-11-06%2016%3A05%3A15
  197. Event-Date-GMT: Mon,%2006%20Nov%202017%2010%3A35%3A15%20GMT
  198. Event-Date-Timestamp: 1509964515421582
  199. Event-Calling-File: mod_dialplan_xml.c
  200. Event-Calling-Function: dialplan_xml_locate
  201. Event-Calling-Line-Number: 608
  202. Event-Sequence: 74948
  203. Channel-State: CS_ROUTING
  204. Channel-Call-State: RINGING
  205. Channel-State-Number: 2
  206. Channel-Name: sofia/default/9110327518%40192.168.1.118
  207. Unique-ID: d486e76a-fc5b-414d-81a2-0c62494e2a0e
  208. Call-Direction: inbound
  209. Presence-Call-Direction: inbound
  210. Channel-HIT-Dialplan: true
  211. Channel-Presence-ID: 9110327518%40192.168.1.118
  212. Channel-Call-UUID: d486e76a-fc5b-414d-81a2-0c62494e2a0e
  213. Answer-State: ringing
  214. Caller-Direction: inbound
  215. Caller-Logical-Direction: inbound
  216. Caller-Username: 9110327518
  217. Caller-Dialplan: XML
  218. Caller-Caller-ID-Name: 9110327518
  219. Caller-Caller-ID-Number: 9110327518
  220. Caller-Orig-Caller-ID-Name: 9110327518
  221. Caller-Orig-Caller-ID-Number: 9110327518
  222. Caller-Network-Addr: 192.168.1.33
  223. Caller-ANI: 9110327518
  224. Caller-Destination-Number: 97264
  225. Caller-Unique-ID: d486e76a-fc5b-414d-81a2-0c62494e2a0e
  226. Caller-Source: mod_sofia
  227. Caller-Context: default
  228. Caller-Channel-Name: sofia/default/9110327518%40192.168.1.118
  229. Caller-Profile-Index: 1
  230. Caller-Profile-Created-Time: 1509964514981576
  231. Caller-Channel-Created-Time: 1509964514981576
  232. Caller-Channel-Answered-Time: 0
  233. Caller-Channel-Progress-Time: 0
  234. Caller-Channel-Progress-Media-Time: 0
  235. Caller-Channel-Hangup-Time: 0
  236. Caller-Channel-Transfer-Time: 0
  237. Caller-Channel-Resurrect-Time: 0
  238. Caller-Channel-Bridged-Time: 0
  239. Caller-Channel-Last-Hold: 0
  240. Caller-Channel-Hold-Accum: 0
  241. Caller-Screen-Bit: true
  242. Caller-Privacy-Hide-Name: false
  243. Caller-Privacy-Hide-Number: false
  244. variable_direction: inbound
  245. variable_uuid: d486e76a-fc5b-414d-81a2-0c62494e2a0e
  246. variable_session_id: 274
  247. variable_sip_from_params: transport%3DTCP
  248. variable_sip_from_user: 9110327518
  249. variable_sip_from_uri: 9110327518%40192.168.1.118
  250. variable_sip_from_host: 192.168.1.118
  251. variable_video_media_flow: sendrecv
  252. variable_audio_media_flow: sendrecv
  253. variable_channel_name: sofia/default/9110327518%40192.168.1.118
  254. variable_sip_call_id: ZmJhZTBkOGRhZTFhYzQyZWRlNjIzMmRmZThmMTAwYzM.
  255. variable_ep_codec_string: CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b
  256. variable_sip_local_network_addr: 192.168.1.118
  257. variable_sip_network_ip: 192.168.1.33
  258. variable_sip_network_port: 48236
  259. variable_sip_invite_stamp: 1509964514981576
  260. variable_sip_received_ip: 192.168.1.33
  261. variable_sip_received_port: 48236
  262. variable_sip_via_protocol: tcp
  263. variable_sip_authorized: true
  264. variable_sip_acl_authed_by: default
  265. variable_sip_from_user_stripped: 9110327518
  266. variable_sip_from_tag: c325a037
  267. variable_sofia_profile_name: default
  268. variable_recovery_profile_name: default
  269. variable_sip_full_via: SIP/2.0/TCP%20192.168.1.33%3A58810%3Bbranch%3Dz9hG4bK-d8754z-67ee28e05cbeb2f4-1---d8754z-%3Brport%3D48236
  270. variable_sip_recover_via: SIP/2.0/TCP%20192.168.1.33%3A58810%3Bbranch%3Dz9hG4bK-d8754z-67ee28e05cbeb2f4-1---d8754z-%3Brport%3D48236
  271. variable_sip_full_from: %3Csip%3A9110327518%40192.168.1.118%3Btransport%3DTCP%3E%3Btag%3Dc325a037
  272. variable_sip_full_to: %3Csip%3A97264%40192.168.1.118%3Btransport%3DTCP%3E
  273. variable_sip_allow: INVITE,%20ACK,%20CANCEL,%20BYE,%20NOTIFY,%20REFER,%20MESSAGE,%20OPTIONS,%20INFO,%20SUBSCRIBE
  274. variable_sip_req_params: transport%3DTCP
  275. variable_sip_req_user: 97264
  276. variable_sip_req_uri: 97264%40192.168.1.118
  277. variable_sip_req_host: 192.168.1.118
  278. variable_sip_to_params: transport%3DTCP
  279. variable_sip_to_user: 97264
  280. variable_sip_to_uri: 97264%40192.168.1.118
  281. variable_sip_to_host: 192.168.1.118
  282. variable_sip_contact_params: transport%3DTCP
  283. variable_sip_contact_user: 9110327518
  284. variable_sip_contact_port: 58810
  285. variable_sip_contact_uri: 9110327518%40117.247.91.162%3A58810
  286. variable_sip_contact_host: 117.247.91.162
  287. variable_rtp_use_codec_string: PCMA,PCMU
  288. variable_sip_user_agent: Z%203.3.25608%20r25552
  289. variable_sip_via_host: 192.168.1.33
  290. variable_sip_via_port: 58810
  291. variable_sip_via_rport: 48236
  292. variable_max_forwards: 70
  293. variable_presence_id: 9110327518%40192.168.1.118
  294. variable_sip_nat_detected: true
  295. variable_switch_r_sdp: v%3D0%0D%0Ao%3DZ%200%200%20IN%20IP4%20117.247.91.162%0D%0As%3DZ%0D%0Ac%3DIN%20IP4%20117.247.91.162%0D%0At%3D0%200%0D%0Am%3Daudio%208000%20RTP/AVP%203%20110%208%200%2098%20101%0D%0Aa%3Drtpmap%3A110%20speex/8000%0D%0Aa%3Drtpmap%3A98%20iLBC/8000%0D%0Aa%3Dfmtp%3A98%20mode%3D20%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-15%0D%0A
  296. variable_endpoint_disposition: DELAYED%20NEGOTIATION
  297. variable_call_uuid: d486e76a-fc5b-414d-81a2-0c62494e2a0e
  298. Hunt-Direction: inbound
  299. Hunt-Logical-Direction: inbound
  300. Hunt-Username: 9110327518
  301. Hunt-Dialplan: XML
  302. Hunt-Caller-ID-Name: 9110327518
  303. Hunt-Caller-ID-Number: 9110327518
  304. Hunt-Orig-Caller-ID-Name: 9110327518
  305. Hunt-Orig-Caller-ID-Number: 9110327518
  306. Hunt-Network-Addr: 192.168.1.33
  307. Hunt-ANI: 9110327518
  308. Hunt-Destination-Number: 97264
  309. Hunt-Unique-ID: d486e76a-fc5b-414d-81a2-0c62494e2a0e
  310. Hunt-Source: mod_sofia
  311. Hunt-Context: default
  312. Hunt-Channel-Name: sofia/default/9110327518%40192.168.1.118
  313. Hunt-Profile-Index: 1
  314. Hunt-Profile-Created-Time: 1509964514981576
  315. Hunt-Channel-Created-Time: 1509964514981576
  316. Hunt-Channel-Answered-Time: 0
  317. Hunt-Channel-Progress-Time: 0
  318. Hunt-Channel-Progress-Media-Time: 0
  319. Hunt-Channel-Hangup-Time: 0
  320. Hunt-Channel-Transfer-Time: 0
  321. Hunt-Channel-Resurrect-Time: 0
  322. Hunt-Channel-Bridged-Time: 0
  323. Hunt-Channel-Last-Hold: 0
  324. Hunt-Channel-Hold-Accum: 0
  325. Hunt-Screen-Bit: true
  326. Hunt-Privacy-Hide-Name: false
  327. Hunt-Privacy-Hide-Number: false
  328.  
  329. '
  330. 2017-11-06 16:05:15.441577 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] section: dialplan
  331. 2017-11-06 16:05:15.441577 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] key_value:
  332. 2017-11-06 16:05:15.441577 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] key_name:
  333. 2017-11-06 16:05:15.441577 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] tag_name:
  334. 2017-11-06 16:05:15.441577 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Dialed number : 97264
  335. 2017-11-06 16:05:15.441577 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Caller Id name / number  : 9110327518 / 9110327518
  336. 2017-11-06 16:05:15.441577 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,B.id as accountid,B.number as account_code,B.did_cid_translation as did_cid_translation,A.number as  did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout FROM dids AS A,accounts AS B WHERE A.status=0 AND B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="97264" LIMIT 1
  337. 2017-11-06 16:05:15.441577 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND username="97264" limit 1
  338. 2017-11-06 16:05:15.441577 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Call direction : local
  339. 2017-11-06 16:05:15.441577 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [IPAUTHENTICATION] Query :SELECT ip_map.*, (SELECT number FROM accounts where id=accountid AND status=0 AND deleted=0) AS account_code FROM ip_map WHERE ((INET_ATON("192.168.1.33") & (0xFFFFFFFF & (-1 << 32 - SUBSTRING_INDEX(ip, '/',-1)))) =  ((0xFFFFFFFF & (-1 << 32 - SUBSTRING_INDEX(ip, '/',-1))) & INET_ATON(SUBSTRING_INDEX(ip,'/',1)))) AND ((SUBSTRING( ip, 1, CHAR_LENGTH( ip ) -3 ) = "192.168.1.33" AND prefix IN (NULL,'')) OR (SUBSTRING( ip, 1, CHAR_LENGTH( ip ) -3 ) = "192.168.1.33" AND "97264"  RLIKE prefix)) ORDER BY LENGTH(prefix) DESC LIMIT 1
  340. 2017-11-06 16:05:15.441577 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [Accountcode : 6833949103]
  341. 2017-11-06 16:05:15.441577 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE (number = "6833949103" OR id="6833949103") AND status=0 AND deleted=0 AND (expiry >= '2017-11-06 10:35:15' OR expiry = '0000-00-00 00:00:00') limit 1
  342. 2017-11-06 16:05:15.461566 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT * FROM packages  as P inner join package_patterns as PKGPTR on P.id = PKGPTR.package_id WHERE (patterns ='^97264.*' OR patterns ='^9726.*' OR patterns ='^972.*' OR patterns ='^97.*' OR patterns ='^9.*' OR patterns ='--') AND status = 0 AND pricelist_id = 2 ORDER BY LENGTH(PKGPTR.patterns) DESC LIMIT 1
  343. 2017-11-06 16:05:15.461566 [INFO] switch_cpp.cpp:1365 [ASTPP] =============== Account Information ===================
  344. 2017-11-06 16:05:15.461566 [INFO] switch_cpp.cpp:1365 [ASTPP] User id : 4
  345. 2017-11-06 16:05:15.461566 [INFO] switch_cpp.cpp:1365 [ASTPP] Account code : 6833949103
  346. 2017-11-06 16:05:15.461566 [INFO] switch_cpp.cpp:1365 [ASTPP] Balance : 100
  347. 2017-11-06 16:05:15.461566 [INFO] switch_cpp.cpp:1365 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
  348. 2017-11-06 16:05:15.461566 [INFO] switch_cpp.cpp:1365 [ASTPP] Ratecard id : 2
  349. 2017-11-06 16:05:15.461566 [INFO] switch_cpp.cpp:1365 [ASTPP] ========================================================
  350. 2017-11-06 16:05:15.461566 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 2 AND status = 0
  351. 2017-11-06 16:05:15.461566 [INFO] switch_cpp.cpp:1365 [ASTPP] [FIND_MAXLENGTH] Call is free - assigning max length!!! :: 100
  352. 2017-11-06 16:05:15.461566 [INFO] switch_cpp.cpp:1365 [ASTPP] Call Max length duration : 100 minutes
  353. 2017-11-06 16:05:15.461566 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND username="97264" limit 1
  354. 2017-11-06 16:05:15.461566 [WARNING] switch_cpp.cpp:1365 [ASTPP] [FSXMLLOCAL] Caller ID Translation Starts
  355. 2017-11-06 16:05:15.461566 [WARNING] switch_cpp.cpp:1365 [ASTPP] [FSXMLLOCAL] Caller ID Translation Ends
  356. 2017-11-06 16:05:15.461566 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Generated XML:
  357. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  358. <document type="freeswitch/xml">
  359. <section name="dialplan" description="ASTPP Dialplan">
  360. <context name="default">
  361. <extension name="att_xfer">
  362. <condition field="destination_number" expression="^att_xfer$">
  363. <action application="read" data="1 10 'tone_stream://%(10000,0,350,440)' digits 20000 #"/>
  364. <action application="transfer" data="-bleg ${digits}"/>
  365. </condition>
  366. </extension>
  367. <extension name="97264">
  368. <condition field="destination_number" expression="97264">
  369. <action application="set" data="effective_destination_number=97264"/>
  370. <action application="sched_hangup" data="+6000 normal_clearing"/>
  371. <action application="set" data="callstart=2017-11-06 10:35:15"/>
  372. <action application="set" data="hangup_after_bridge=true"/>
  373. <action application="set" data="continue_on_fail=true"/>
  374. <action application="set" data="account_id=4"/>
  375. <action application="set" data="parent_id=0"/>
  376. <action application="set" data="entity_id=0"/>
  377. <action application="set" data="call_processed=internal"/>
  378. <action application="set" data="call_direction=local"/>
  379. <action application="set" data="accountname=ketank"/>
  380. <action application="set" data="origination_rates=ID:0|CODE:97264|DESTINATION:Local|CONNECTIONCOST:0|INCLUDEDSECONDS:0|COST:0|INC:60|INITIALBLOCK:0|RATEGROUP:2|MARKUP:0|ACCID:4"/>
  381. <action application="set" data="original_caller_id_name=9110327518"/>
  382. <action application="set" data="original_caller_id_number=9110327518"/>
  383. <action application="set" data="effective_caller_id_name=9110327518"/>
  384. <action application="set" data="effective_caller_id_number=9110327518"/>
  385. <action application="set" data="calltype=LOCAL"/>
  386. <action application="set" data="receiver_accid=2"/>
  387. <action application="bind_meta_app" data="2 ab ab execute_extension::att_xfer XML default"/>
  388. <action application="bridge" data="[leg_timeout=30]user/97264@${domain_name}"/>
  389. <condition field="${cond(${user_data 97264@${domain_name} param vm-enabled} == true ? YES : NO)}" expression="^YES$">
  390. <action application="export" data="voicemail_alternate_greet_id=97264"/>
  391. <action application="answer"/>
  392. <action application="voicemail" data="default $${domain_name} 97264"/>
  393. <anti-action application="hangup" data="${originate_disposition}"/>
  394. </condition>
  395. </condition>
  396. </extension>
  397. </context>
  398. </section>
  399. </document>
  400. 2017-11-06 16:05:15.461566 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fa6f415e830 released.
  401. Dialplan: sofia/default/9110327518@192.168.1.118 parsing [default->att_xfer] continue=false
  402. Dialplan: sofia/default/9110327518@192.168.1.118 Regex (FAIL) [att_xfer] destination_number(97264) =~ /^att_xfer$/ break=on-false
  403. Dialplan: sofia/default/9110327518@192.168.1.118 parsing [default->97264] continue=false
  404. Dialplan: sofia/default/9110327518@192.168.1.118 Regex (PASS) [97264] destination_number(97264) =~ /97264/ break=on-false
  405. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(effective_destination_number=97264)
  406. Dialplan: sofia/default/9110327518@192.168.1.118 Action sched_hangup(+6000 normal_clearing)
  407. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(callstart=2017-11-06 10:35:15)
  408. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(hangup_after_bridge=true)
  409. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(continue_on_fail=true)
  410. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(account_id=4)
  411. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(parent_id=0)
  412. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(entity_id=0)
  413. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(call_processed=internal)
  414. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(call_direction=local)
  415. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(accountname=ketank)
  416. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(origination_rates=ID:0|CODE:97264|DESTINATION:Local|CONNECTIONCOST:0|INCLUDEDSECONDS:0|COST:0|INC:60|INITIALBLOCK:0|RATEGROUP:2|MARKUP:0|ACCID:4)
  417. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(original_caller_id_name=9110327518)
  418. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(original_caller_id_number=9110327518)
  419. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(effective_caller_id_name=9110327518)
  420. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(effective_caller_id_number=9110327518)
  421. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(calltype=LOCAL)
  422. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(receiver_accid=2)
  423. Dialplan: sofia/default/9110327518@192.168.1.118 Action bind_meta_app(2 ab ab execute_extension::att_xfer XML default)
  424. Dialplan: sofia/default/9110327518@192.168.1.118 Action bridge([leg_timeout=30]user/97264@${domain_name})
  425. |--- Dialplan: Processing recursive conditions level:1 [97264_recur_1] require-nested=TRUE
  426. |--- Dialplan: sofia/default/9110327518@192.168.1.118 Regex (PASS) [97264_recur_1] ${cond(${user_data 97264@${domain_name} param vm-enabled} == true ? YES : NO)}(YES) =~ /^YES$/ break=on-false
  427. |--- Dialplan: sofia/default/9110327518@192.168.1.118 Action export(voicemail_alternate_greet_id=97264)
  428. |--- Dialplan: sofia/default/9110327518@192.168.1.118 Action answer()
  429. |--- Dialplan: sofia/default/9110327518@192.168.1.118 Action voicemail(default $${domain_name} 97264)
  430. 2017-11-06 16:05:15.481576 [DEBUG] switch_core_state_machine.c:286 (sofia/default/9110327518@192.168.1.118) State Change CS_ROUTING -> CS_EXECUTE
  431. 2017-11-06 16:05:15.481576 [DEBUG] switch_core_state_machine.c:643 (sofia/default/9110327518@192.168.1.118) State ROUTING going to sleep
  432. 2017-11-06 16:05:15.481576 [DEBUG] switch_core_state_machine.c:584 (sofia/default/9110327518@192.168.1.118) Running State Change CS_EXECUTE (Cur 1 Tot 274)
  433. 2017-11-06 16:05:15.481576 [DEBUG] switch_core_state_machine.c:650 (sofia/default/9110327518@192.168.1.118) State EXECUTE
  434. 2017-11-06 16:05:15.481576 [DEBUG] mod_sofia.c:198 sofia/default/9110327518@192.168.1.118 SOFIA EXECUTE
  435. 2017-11-06 16:05:15.481576 [DEBUG] switch_core_state_machine.c:328 sofia/default/9110327518@192.168.1.118 Standard EXECUTE
  436. EXECUTE sofia/default/9110327518@192.168.1.118 set(effective_destination_number=97264)
  437. 2017-11-06 16:05:15.481576 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [effective_destination_number]=[97264]
  438. EXECUTE sofia/default/9110327518@192.168.1.118 sched_hangup(+6000 normal_clearing)
  439. 2017-11-06 16:05:15.481576 [DEBUG] switch_scheduler.c:249 Added task 142 switch_ivr_schedule_hangup (d486e76a-fc5b-414d-81a2-0c62494e2a0e) to run at 1509970515
  440. EXECUTE sofia/default/9110327518@192.168.1.118 set(callstart=2017-11-06 10:35:15)
  441. 2017-11-06 16:05:15.481576 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [callstart]=[2017-11-06 10:35:15]
  442. EXECUTE sofia/default/9110327518@192.168.1.118 set(hangup_after_bridge=true)
  443. 2017-11-06 16:05:15.481576 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [hangup_after_bridge]=[true]
  444. EXECUTE sofia/default/9110327518@192.168.1.118 set(continue_on_fail=true)
  445. 2017-11-06 16:05:15.481576 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [continue_on_fail]=[true]
  446. EXECUTE sofia/default/9110327518@192.168.1.118 set(account_id=4)
  447. 2017-11-06 16:05:15.481576 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [account_id]=[4]
  448. EXECUTE sofia/default/9110327518@192.168.1.118 set(parent_id=0)
  449. 2017-11-06 16:05:15.481576 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [parent_id]=[0]
  450. EXECUTE sofia/default/9110327518@192.168.1.118 set(entity_id=0)
  451. 2017-11-06 16:05:15.481576 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [entity_id]=[0]
  452. EXECUTE sofia/default/9110327518@192.168.1.118 set(call_processed=internal)
  453. 2017-11-06 16:05:15.481576 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [call_processed]=[internal]
  454. EXECUTE sofia/default/9110327518@192.168.1.118 set(call_direction=local)
  455. 2017-11-06 16:05:15.481576 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [call_direction]=[local]
  456. EXECUTE sofia/default/9110327518@192.168.1.118 set(accountname=ketank)
  457. 2017-11-06 16:05:15.481576 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [accountname]=[ketank]
  458. EXECUTE sofia/default/9110327518@192.168.1.118 set(origination_rates=ID:0|CODE:97264|DESTINATION:Local|CONNECTIONCOST:0|INCLUDEDSECONDS:0|COST:0|INC:60|INITIALBLOCK:0|RATEGROUP:2|MARKUP:0|ACCID:4)
  459. 2017-11-06 16:05:15.481576 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [origination_rates]=[ID:0|CODE:97264|DESTINATION:Local|CONNECTIONCOST:0|INCLUDEDSECONDS:0|COST:0|INC:60|INITIALBLOCK:0|RATEGROUP:2|MARKUP:0|ACCID:4]
  460. EXECUTE sofia/default/9110327518@192.168.1.118 set(original_caller_id_name=9110327518)
  461. 2017-11-06 16:05:15.481576 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [original_caller_id_name]=[9110327518]
  462. EXECUTE sofia/default/9110327518@192.168.1.118 set(original_caller_id_number=9110327518)
  463. 2017-11-06 16:05:15.481576 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [original_caller_id_number]=[9110327518]
  464. EXECUTE sofia/default/9110327518@192.168.1.118 set(effective_caller_id_name=9110327518)
  465. 2017-11-06 16:05:15.481576 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [effective_caller_id_name]=[9110327518]
  466. EXECUTE sofia/default/9110327518@192.168.1.118 set(effective_caller_id_number=9110327518)
  467. 2017-11-06 16:05:15.481576 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [effective_caller_id_number]=[9110327518]
  468. EXECUTE sofia/default/9110327518@192.168.1.118 set(calltype=LOCAL)
  469. 2017-11-06 16:05:15.481576 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [calltype]=[LOCAL]
  470. EXECUTE sofia/default/9110327518@192.168.1.118 set(receiver_accid=2)
  471. 2017-11-06 16:05:15.481576 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [receiver_accid]=[2]
  472. EXECUTE sofia/default/9110327518@192.168.1.118 bind_meta_app(2 ab ab execute_extension::att_xfer XML default)
  473. 2017-11-06 16:05:15.481576 [ERR] mod_dptools.c:747 Cannot bind execute to multiple legs
  474. 2017-11-06 16:05:15.481576 [INFO] switch_ivr_async.c:4165 Bound A-Leg: *2 execute_extension::att_xfer XML default
  475. 2017-11-06 16:05:15.481576 [INFO] switch_ivr_async.c:4173 Bound B-Leg: *2 execute_extension::att_xfer XML default
  476. EXECUTE sofia/default/9110327518@192.168.1.118 bridge([leg_timeout=30]user/97264@192.168.1.118)
  477. 2017-11-06 16:05:15.481576 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  478. 2017-11-06 16:05:15.481576 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables
  479. 2017-11-06 16:05:15.501572 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  480. 2017-11-06 16:05:15.501572 [NOTICE] switch_channel.c:1104 New Channel sofia/default/97264@192.168.1.161:5060 [a36afe8f-cd01-462f-a412-7eb301532ad6]
  481. 2017-11-06 16:05:15.501572 [DEBUG] mod_sofia.c:4819 (sofia/default/97264@192.168.1.161:5060) State Change CS_NEW -> CS_INIT
  482. 2017-11-06 16:05:15.501572 [DEBUG] switch_ivr_originate.c:2963 sofia/default/97264@192.168.1.161:5060 Setting leg timeout to 30
  483. 2017-11-06 16:05:15.501572 [DEBUG] switch_core_state_machine.c:584 (sofia/default/97264@192.168.1.161:5060) Running State Change CS_INIT (Cur 2 Tot 275)
  484. 2017-11-06 16:05:15.501572 [DEBUG] switch_core_state_machine.c:627 (sofia/default/97264@192.168.1.161:5060) State INIT
  485. 2017-11-06 16:05:15.501572 [DEBUG] mod_sofia.c:90 sofia/default/97264@192.168.1.161:5060 SOFIA INIT
  486. 2017-11-06 16:05:15.501572 [DEBUG] sofia_glue.c:1295 sofia/default/97264@192.168.1.161:5060 sending invite version: 1.6.18  64bit
  487. Local SDP:
  488. v=0
  489. o=FreeSWITCH 1509940855 1509940856 IN IP4 192.168.1.118
  490. s=FreeSWITCH
  491. c=IN IP4 192.168.1.118
  492. t=0 0
  493. m=audio 23660 RTP/AVP 8 0 101
  494. a=rtpmap:8 PCMA/8000
  495. a=rtpmap:0 PCMU/8000
  496. a=rtpmap:101 telephone-event/8000
  497. a=fmtp:101 0-16
  498. a=ptime:20
  499. a=sendrecv
  500.  
  501. send 1106 bytes to udp/[192.168.1.161]:5060 at 16:05:15.508554:
  502.    ------------------------------------------------------------------------
  503.    INVITE sip:97264@192.168.1.161:5060 SIP/2.0
  504.    Via: SIP/2.0/UDP 192.168.1.118;rport;branch=z9hG4bKZ38K9r16m1X3S
  505.    Max-Forwards: 69
  506.    From: "9110327518" <sip:9110327518@192.168.1.118>;tag=UH5FH4y1DaQKS
  507.    To: <sip:97264@192.168.1.161:5060>
  508.    Call-ID: 0493884d-3d81-1236-c4b6-000c29f46286
  509.    CSeq: 114646193 INVITE
  510.    Contact: <sip:mod_sofia@192.168.1.118:5060>
  511.    User-Agent: Local
  512.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  513.    Supported: path, replaces
  514.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  515.    Content-Type: application/sdp
  516.    Content-Disposition: session
  517.    Content-Length: 246
  518.    Remote-Party-ID: "9110327518" <sip:9110327518@192.168.1.118>;party=calling;screen=yes;privacy=off
  519.    
  520.    v=0
  521.    o=FreeSWITCH 1509940855 1509940856 IN IP4 192.168.1.118
  522.    s=FreeSWITCH
  523.    c=IN IP4 192.168.1.118
  524.    t=0 0
  525.    m=audio 23660 RTP/AVP 8 0 101
  526.    a=rtpmap:8 PCMA/8000
  527.    a=rtpmap:0 PCMU/8000
  528.    a=rtpmap:101 telephone-event/8000
  529.    a=fmtp:101 0-16
  530.    a=ptime:20
  531.    ------------------------------------------------------------------------
  532. 2017-11-06 16:05:15.501572 [DEBUG] switch_core_state_machine.c:40 sofia/default/97264@192.168.1.161:5060 Standard INIT
  533. 2017-11-06 16:05:15.501572 [DEBUG] switch_core_state_machine.c:48 (sofia/default/97264@192.168.1.161:5060) State Change CS_INIT -> CS_ROUTING
  534. 2017-11-06 16:05:15.501572 [DEBUG] switch_core_state_machine.c:627 (sofia/default/97264@192.168.1.161:5060) State INIT going to sleep
  535. 2017-11-06 16:05:15.501572 [DEBUG] switch_core_state_machine.c:584 (sofia/default/97264@192.168.1.161:5060) Running State Change CS_ROUTING (Cur 2 Tot 275)
  536. 2017-11-06 16:05:15.501572 [DEBUG] sofia.c:7048 Channel sofia/default/97264@192.168.1.161:5060 entering state [calling][0]
  537. 2017-11-06 16:05:15.501572 [DEBUG] switch_core_state_machine.c:643 (sofia/default/97264@192.168.1.161:5060) State ROUTING
  538. 2017-11-06 16:05:15.501572 [DEBUG] mod_sofia.c:143 sofia/default/97264@192.168.1.161:5060 SOFIA ROUTING
  539. 2017-11-06 16:05:15.501572 [DEBUG] switch_ivr_originate.c:67 (sofia/default/97264@192.168.1.161:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  540. 2017-11-06 16:05:15.501572 [DEBUG] switch_core_state_machine.c:643 (sofia/default/97264@192.168.1.161:5060) State ROUTING going to sleep
  541. 2017-11-06 16:05:15.501572 [DEBUG] switch_core_state_machine.c:584 (sofia/default/97264@192.168.1.161:5060) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 275)
  542. 2017-11-06 16:05:15.501572 [DEBUG] switch_core_state_machine.c:662 (sofia/default/97264@192.168.1.161:5060) State CONSUME_MEDIA
  543. 2017-11-06 16:05:15.501572 [DEBUG] switch_core_state_machine.c:662 (sofia/default/97264@192.168.1.161:5060) State CONSUME_MEDIA going to sleep
  544. recv 455 bytes from udp/[192.168.1.161]:5060 at 16:05:15.524651:
  545.    ------------------------------------------------------------------------
  546.    SIP/2.0 100 Trying
  547.    Via: SIP/2.0/UDP 192.168.1.118;rport=5060;branch=z9hG4bKZ38K9r16m1X3S
  548.    From: "9110327518" <sip:9110327518@192.168.1.118>;tag=UH5FH4y1DaQKS
  549.    To: <sip:97264@192.168.1.161:5060>
  550.    Call-ID: 0493884d-3d81-1236-c4b6-000c29f46286
  551.    CSeq: 114646193 INVITE
  552.    Supported: replaces, path, timer
  553.    User-Agent: Grandstream GXP1620 1.0.0.38
  554.   Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
  555.    Content-Length: 0
  556.    
  557.    ------------------------------------------------------------------------
  558. recv 538 bytes from udp/[192.168.1.161]:5060 at 16:05:15.530792:
  559.    ------------------------------------------------------------------------
  560.    SIP/2.0 180 Ringing
  561.    Via: SIP/2.0/UDP 192.168.1.118;rport=5060;branch=z9hG4bKZ38K9r16m1X3S
  562.    From: "9110327518" <sip:9110327518@192.168.1.118>;tag=UH5FH4y1DaQKS
  563.    To: <sip:97264@192.168.1.161:5060>;tag=1987421158
  564.    Call-ID: 0493884d-3d81-1236-c4b6-000c29f46286
  565.    CSeq: 114646193 INVITE
  566.    Contact: <sip:97264@192.168.1.161:5060>
  567.    Supported: replaces, path, timer
  568.    User-Agent: Grandstream GXP1620 1.0.0.38
  569.    Allow-Events: talk, hold
  570.   Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
  571.    Content-Length: 0
  572.    
  573.    ------------------------------------------------------------------------
  574. 2017-11-06 16:05:15.701619 [DEBUG] sofia.c:7048 Channel sofia/default/97264@192.168.1.161:5060 entering state [proceeding][180]
  575. 2017-11-06 16:05:15.701619 [NOTICE] sofia.c:7156 Ring-Ready sofia/default/97264@192.168.1.161:5060!
  576. 2017-11-06 16:05:15.701619 [DEBUG] switch_channel.c:3346 (sofia/default/97264@192.168.1.161:5060) Callstate Change DOWN -> RINGING
  577. send 748 bytes to tcp/[192.168.1.33]:48236 at 16:05:15.730865:
  578.    ------------------------------------------------------------------------
  579.    SIP/2.0 180 Ringing
  580.    Via: SIP/2.0/TCP 192.168.1.33:58810;branch=z9hG4bK-d8754z-67ee28e05cbeb2f4-1---d8754z-;rport=48236
  581.    From: <sip:9110327518@192.168.1.118;transport=TCP>;tag=c325a037
  582.    To: <sip:97264@192.168.1.118;transport=TCP>;tag=SZjyDeXtKraej
  583.    Call-ID: ZmJhZTBkOGRhZTFhYzQyZWRlNjIzMmRmZThmMTAwYzM.
  584.    CSeq: 1 INVITE
  585.    Contact: <sip:97264@192.168.1.118:5060;transport=tcp>
  586.    User-Agent: Local
  587.    Accept: application/sdp
  588.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  589.    Supported: path, replaces
  590.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  591.    Content-Length: 0
  592.    
  593.    ------------------------------------------------------------------------
  594. 2017-11-06 16:05:15.721622 [NOTICE] mod_sofia.c:2273 Ring-Ready sofia/default/9110327518@192.168.1.118!
  595. 2017-11-06 16:05:15.721622 [DEBUG] sofia.c:7048 Channel sofia/default/9110327518@192.168.1.118 entering state [early][180]
  596. 2017-11-06 16:05:15.721622 [NOTICE] switch_ivr_originate.c:525 Ring Ready sofia/default/9110327518@192.168.1.118!
  597. send 605 bytes to udp/[1.1.1.1]:5060 at 16:05:16.107439:
  598.    ------------------------------------------------------------------------
  599.    OPTIONS sip:1.1.1.1;transport=udp SIP/2.0
  600.    Via: SIP/2.0/UDP 192.168.1.118;rport;branch=z9hG4bKytFU7Xg3Qr7ge
  601.    Max-Forwards: 70
  602.    From: <sip:1.1.1.1>;tag=t8BQF9Dyg100D
  603.    To: <sip:1.1.1.1>
  604.    Call-ID: 045637c8-3d81-1236-c4b6-000c29f46286
  605.    CSeq: 114465670 OPTIONS
  606.    User-Agent: Local
  607.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  608.    Supported: path, replaces
  609.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  610.    Content-Length: 0
  611.    
  612.    ------------------------------------------------------------------------
  613. 2017-11-06 16:05:16.301622 [WARNING] sofia.c:6098 Ping failed KK with code 503 - count 1/0/1, state DOWN
  614. recv 336 bytes from udp/[192.168.1.119]:6060 at 16:05:18.074261:
  615.    ------------------------------------------------------------------------
  616.    OPTIONS sip:192.168.1.118:5060 SIP/2.0
  617.    Via: SIP/2.0/UDP 117.247.91.162:6060;branch=z9hG4bKf048.fc4e53e2.0
  618.    To: sip:192.168.1.118:5060
  619.    From: <sip:ping@192.168.1.119:5060>;tag=709be372f8df6ad02e74147e04c36617-9838
  620.    CSeq: 14 OPTIONS
  621.    Call-ID: 19f406c45d424ea9-10323@192.168.1.119
  622.    Max-Forwards: 70
  623.    Content-Length: 0
  624.    User-Agent: SBC
  625.    
  626.    ------------------------------------------------------------------------
  627. send 702 bytes to udp/[192.168.1.119]:6060 at 16:05:18.074740:
  628.    ------------------------------------------------------------------------
  629.    SIP/2.0 200 OK
  630.    Via: SIP/2.0/UDP 117.247.91.162:6060;branch=z9hG4bKf048.fc4e53e2.0;received=192.168.1.119
  631.    From: <sip:ping@192.168.1.119:5060>;tag=709be372f8df6ad02e74147e04c36617-9838
  632.    To: <sip:192.168.1.118:5060>;tag=X3Q1mt087U3rg
  633.    Call-ID: 19f406c45d424ea9-10323@192.168.1.119
  634.    CSeq: 14 OPTIONS
  635.    Contact: <sip:192.168.1.118>
  636.    User-Agent: Local
  637.    Accept: application/sdp
  638.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  639.    Supported: path, replaces
  640.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  641.    Content-Length: 0
  642.    
  643.    ------------------------------------------------------------------------
  644. freeswitch@freeswitch>
  645. freeswitch@freeswitch>
  646. freeswitch@freeswitch>
  647. recv 780 bytes from udp/[192.168.1.161]:5060 at 16:05:24.395817:
  648.    ------------------------------------------------------------------------
  649.    SIP/2.0 200 OK
  650.    Via: SIP/2.0/UDP 192.168.1.118;rport=5060;branch=z9hG4bKZ38K9r16m1X3S
  651.    From: "9110327518" <sip:9110327518@192.168.1.118>;tag=UH5FH4y1DaQKS
  652.    To: <sip:97264@192.168.1.161:5060>;tag=1987421158
  653.    Call-ID: 0493884d-3d81-1236-c4b6-000c29f46286
  654.    CSeq: 114646193 INVITE
  655.    Contact: <sip:97264@192.168.1.161:5060>
  656.    Supported: replaces, path, timer
  657.    User-Agent: Grandstream GXP1620 1.0.0.38
  658.   Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
  659.    Content-Type: application/sdp
  660.    Content-Length:   238
  661.    
  662.    v=0
  663.    o=97264 8000 8000 IN IP4 192.168.1.161
  664.    s=SIP Call
  665.    c=IN IP4 192.168.1.161
  666.    t=0 0
  667.    m=audio 5004 RTP/AVP 8 0 101
  668.    a=sendrecv
  669.    a=rtpmap:8 PCMA/8000
  670.    a=ptime:20
  671.    a=rtpmap:0 PCMU/8000
  672.    a=rtpmap:101 telephone-event/8000
  673.    a=fmtp:101 0-15
  674.    ------------------------------------------------------------------------
  675. 2017-11-06 16:05:24.421622 [DEBUG] sofia.c:7048 Channel sofia/default/97264@192.168.1.161:5060 entering state [completing][200]
  676. 2017-11-06 16:05:24.421622 [DEBUG] sofia.c:7058 Remote SDP:
  677. v=0
  678. o=97264 8000 8000 IN IP4 192.168.1.161
  679. s=SIP Call
  680. c=IN IP4 192.168.1.161
  681. t=0 0
  682. m=audio 5004 RTP/AVP 8 0 101
  683. a=rtpmap:8 PCMA/8000
  684. a=rtpmap:0 PCMU/8000
  685. a=rtpmap:101 telephone-event/8000
  686. a=fmtp:101 0-15
  687. a=ptime:20
  688.  
  689. send 380 bytes to udp/[192.168.1.161]:5060 at 16:05:24.430877:
  690.    ------------------------------------------------------------------------
  691.    ACK sip:97264@192.168.1.161:5060 SIP/2.0
  692.    Via: SIP/2.0/UDP 192.168.1.118;rport;branch=z9hG4bK0c2cBmjajampN
  693.    Max-Forwards: 70
  694.    From: "9110327518" <sip:9110327518@192.168.1.118>;tag=UH5FH4y1DaQKS
  695.    To: <sip:97264@192.168.1.161:5060>;tag=1987421158
  696.    Call-ID: 0493884d-3d81-1236-c4b6-000c29f46286
  697.    CSeq: 114646193 ACK
  698.    Contact: <sip:mod_sofia@192.168.1.118:5060>
  699.    Content-Length: 0
  700.    
  701.    ------------------------------------------------------------------------
  702. 2017-11-06 16:05:24.421622 [DEBUG] sofia.c:7048 Channel sofia/default/97264@192.168.1.161:5060 entering state [ready][200]
  703. 2017-11-06 16:05:24.421622 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  704. 2017-11-06 16:05:24.421622 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  705. 2017-11-06 16:05:24.421622 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  706. 2017-11-06 16:05:24.421622 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  707. 2017-11-06 16:05:24.421622 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  708. 2017-11-06 16:05:24.421622 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  709. 2017-11-06 16:05:24.421622 [DEBUG] switch_core_media.c:4361 Set telephone-event payload to 101@8000
  710. 2017-11-06 16:05:24.421622 [DEBUG] switch_core_media.c:3057 Set Codec sofia/default/97264@192.168.1.161:5060 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  711. 2017-11-06 16:05:24.421622 [DEBUG] switch_core_codec.c:111 sofia/default/97264@192.168.1.161:5060 Original read codec set to PCMA:8
  712. 2017-11-06 16:05:24.421622 [DEBUG] switch_core_media.c:4704 Set telephone-event payload to 101@8000
  713. 2017-11-06 16:05:24.421622 [DEBUG] switch_core_media.c:4763 sofia/default/97264@192.168.1.161:5060 Set 2833 dtmf send payload to 101 recv payload to 101
  714. 2017-11-06 16:05:24.421622 [DEBUG] switch_core_media.c:6874 AUDIO RTP [sofia/default/97264@192.168.1.161:5060] 192.168.1.118 port 23660 -> 192.168.1.161 port 5004 codec: 8 ms: 20
  715. 2017-11-06 16:05:24.421622 [DEBUG] switch_rtp.c:4108 Starting timer [soft] 160 bytes per 20ms
  716. 2017-11-06 16:05:24.421622 [DEBUG] switch_core_media.c:7175 sofia/default/97264@192.168.1.161:5060 Set 2833 dtmf send payload to 101
  717. 2017-11-06 16:05:24.421622 [DEBUG] switch_core_media.c:7182 sofia/default/97264@192.168.1.161:5060 Set 2833 dtmf receive payload to 101
  718. 2017-11-06 16:05:24.421622 [DEBUG] switch_core_media.c:7205 sofia/default/97264@192.168.1.161:5060 Set rtp dtmf delay to 40
  719. 2017-11-06 16:05:24.421622 [NOTICE] sofia.c:8182 Channel [sofia/default/97264@192.168.1.161:5060] has been answered
  720. 2017-11-06 16:05:24.421622 [DEBUG] switch_channel.c:3773 (sofia/default/97264@192.168.1.161:5060) Callstate Change RINGING -> ACTIVE
  721. 2017-11-06 16:05:24.461569 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  722. 2017-11-06 16:05:24.461569 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  723. 2017-11-06 16:05:24.461569 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  724. 2017-11-06 16:05:24.461569 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  725. 2017-11-06 16:05:24.461569 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  726. 2017-11-06 16:05:24.461569 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  727. 2017-11-06 16:05:24.461569 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  728. 2017-11-06 16:05:24.461569 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  729. 2017-11-06 16:05:24.461569 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  730. 2017-11-06 16:05:24.461569 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  731. 2017-11-06 16:05:24.461569 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
  732. 2017-11-06 16:05:24.461569 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
  733. 2017-11-06 16:05:24.461569 [DEBUG] switch_core_media.c:4361 Set telephone-event payload to 101@8000
  734. 2017-11-06 16:05:24.461569 [DEBUG] switch_core_media.c:3057 Set Codec sofia/default/9110327518@192.168.1.118 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  735. 2017-11-06 16:05:24.461569 [DEBUG] switch_core_codec.c:111 sofia/default/9110327518@192.168.1.118 Original read codec set to PCMA:8
  736. 2017-11-06 16:05:24.461569 [DEBUG] switch_core_media.c:4704 Set telephone-event payload to 101@8000
  737. 2017-11-06 16:05:24.461569 [DEBUG] switch_core_media.c:4763 sofia/default/9110327518@192.168.1.118 Set 2833 dtmf send payload to 101 recv payload to 101
  738. 2017-11-06 16:05:24.461569 [DEBUG] switch_core_media.c:6874 AUDIO RTP [sofia/default/9110327518@192.168.1.118] 192.168.1.118 port 31498 -> 117.247.91.162 port 8000 codec: 8 ms: 20
  739. 2017-11-06 16:05:24.461569 [DEBUG] switch_rtp.c:4108 Starting timer [soft] 160 bytes per 20ms
  740. 2017-11-06 16:05:24.461569 [DEBUG] switch_core_media.c:7175 sofia/default/9110327518@192.168.1.118 Set 2833 dtmf send payload to 101
  741. 2017-11-06 16:05:24.461569 [DEBUG] switch_core_media.c:7182 sofia/default/9110327518@192.168.1.118 Set 2833 dtmf receive payload to 101
  742. 2017-11-06 16:05:24.461569 [DEBUG] switch_core_media.c:7205 sofia/default/9110327518@192.168.1.118 Set rtp dtmf delay to 40
  743. 2017-11-06 16:05:24.461569 [NOTICE] sofia_media.c:92 Pre-Answer sofia/default/9110327518@192.168.1.118!
  744. 2017-11-06 16:05:24.461569 [DEBUG] switch_channel.c:3474 (sofia/default/9110327518@192.168.1.118) Callstate Change RINGING -> EARLY
  745. 2017-11-06 16:05:24.461569 [DEBUG] switch_core_media.c:6857 Audio params are unchanged for sofia/default/9110327518@192.168.1.118.
  746. 2017-11-06 16:05:24.461569 [DEBUG] mod_sofia.c:850 Local SDP sofia/default/9110327518@192.168.1.118:
  747. v=0
  748. o=FreeSWITCH 1509933026 1509933027 IN IP4 192.168.1.118
  749. s=FreeSWITCH
  750. c=IN IP4 192.168.1.118
  751. t=0 0
  752. m=audio 31498 RTP/AVP 8 101
  753. a=rtpmap:8 PCMA/8000
  754. a=rtpmap:101 telephone-event/8000
  755. a=fmtp:101 0-16
  756. a=ptime:20
  757. a=sendrecv
  758.  
  759. send 1003 bytes to tcp/[192.168.1.33]:48236 at 16:05:24.476627:
  760.    ------------------------------------------------------------------------
  761.    SIP/2.0 200 OK
  762.    Via: SIP/2.0/TCP 192.168.1.33:58810;branch=z9hG4bK-d8754z-67ee28e05cbeb2f4-1---d8754z-;rport=48236
  763.    From: <sip:9110327518@192.168.1.118;transport=TCP>;tag=c325a037
  764.    To: <sip:97264@192.168.1.118;transport=TCP>;tag=SZjyDeXtKraej
  765.    Call-ID: ZmJhZTBkOGRhZTFhYzQyZWRlNjIzMmRmZThmMTAwYzM.
  766.    CSeq: 1 INVITE
  767.    Contact: <sip:97264@192.168.1.118:5060;transport=tcp>
  768.    User-Agent: Local
  769.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  770.    Supported: path, replaces
  771.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  772.    Content-Type: application/sdp
  773.    Content-Disposition: session
  774.    Content-Length: 222
  775.    
  776.    v=0
  777.    o=FreeSWITCH 1509933026 1509933027 IN IP4 192.168.1.118
  778.    s=FreeSWITCH
  779.    c=IN IP4 192.168.1.118
  780.    t=0 0
  781.    m=audio 31498 RTP/AVP 8 101
  782.    a=rtpmap:8 PCMA/8000
  783.    a=rtpmap:101 telephone-event/8000
  784.    a=fmtp:101 0-16
  785.    a=ptime:20
  786.    ------------------------------------------------------------------------
  787. 2017-11-06 16:05:24.461569 [DEBUG] sofia.c:7048 Channel sofia/default/9110327518@192.168.1.118 entering state [completed][200]
  788. 2017-11-06 16:05:24.461569 [NOTICE] switch_ivr_originate.c:3647 Channel [sofia/default/9110327518@192.168.1.118] has been answered
  789. 2017-11-06 16:05:24.461569 [DEBUG] switch_channel.c:3773 (sofia/default/9110327518@192.168.1.118) Callstate Change EARLY -> ACTIVE
  790. 2017-11-06 16:05:24.461569 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/default/97264@192.168.1.161:5060]
  791. 2017-11-06 16:05:24.461569 [DEBUG] switch_ivr_originate.c:2963 sofia/default/97264@192.168.1.161:5060 Setting leg timeout to 30
  792. 2017-11-06 16:05:24.461569 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/default/97264@192.168.1.161:5060]
  793. recv 473 bytes from tcp/[192.168.1.33]:48236 at 16:05:24.480742:
  794.    ------------------------------------------------------------------------
  795.    ACK sip:97264@192.168.1.118:5060;transport=tcp SIP/2.0
  796.    Via: SIP/2.0/TCP 192.168.1.33:58810;branch=z9hG4bK-d8754z-e1b7840bc29c2aeb-1---d8754z-
  797.    Max-Forwards: 70
  798.    Contact: <sip:9110327518@117.247.91.162:58810;transport=TCP>
  799.    To: <sip:97264@192.168.1.118;transport=TCP>;tag=SZjyDeXtKraej
  800.    From: <sip:9110327518@192.168.1.118;transport=TCP>;tag=c325a037
  801.    Call-ID: ZmJhZTBkOGRhZTFhYzQyZWRlNjIzMmRmZThmMTAwYzM.
  802.    CSeq: 1 ACK
  803.    User-Agent: Z 3.3.25608 r25552
  804.    Content-Length: 0
  805.    
  806.    ------------------------------------------------------------------------
  807. 2017-11-06 16:05:24.481563 [DEBUG] sofia.c:7048 Channel sofia/default/9110327518@192.168.1.118 entering state [ready][200]
  808. 2017-11-06 16:05:24.521620 [DEBUG] switch_ivr_bridge.c:1614 (sofia/default/97264@192.168.1.161:5060) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  809. 2017-11-06 16:05:24.521620 [DEBUG] switch_core_state_machine.c:584 (sofia/default/97264@192.168.1.161:5060) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 275)
  810. 2017-11-06 16:05:24.521620 [DEBUG] switch_core_state_machine.c:653 (sofia/default/97264@192.168.1.161:5060) State EXCHANGE_MEDIA
  811. 2017-11-06 16:05:24.521620 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
  812. send 917 bytes to udp/[192.168.1.161]:5060 at 16:05:24.534322:
  813.    ------------------------------------------------------------------------
  814.    UPDATE sip:97264@192.168.1.161:5060 SIP/2.0
  815.    Via: SIP/2.0/UDP 192.168.1.118;rport;branch=z9hG4bK1NU5cF3DFKa9g
  816.    Max-Forwards: 70
  817.    From: "9110327518" <sip:9110327518@192.168.1.118>;tag=UH5FH4y1DaQKS
  818.    To: <sip:97264@192.168.1.161:5060>;tag=1987421158
  819.    Call-ID: 0493884d-3d81-1236-c4b6-000c29f46286
  820.    CSeq: 114646194 UPDATE
  821.    Contact: <sip:mod_sofia@192.168.1.118:5060>
  822.    User-Agent: Local
  823.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  824.    Supported: path, replaces
  825.    Content-Type: application/sdp
  826.    Content-Disposition: session
  827.    Content-Length: 246
  828.    P-Asserted-Identity: "9110327518" <sip:9110327518@192.168.1.118>
  829.    
  830.    v=0
  831.    o=FreeSWITCH 1509940855 1509940856 IN IP4 192.168.1.118
  832.    s=FreeSWITCH
  833.    c=IN IP4 192.168.1.118
  834.    t=0 0
  835.    m=audio 23660 RTP/AVP 8 0 101
  836.    a=rtpmap:8 PCMA/8000
  837.    a=rtpmap:0 PCMU/8000
  838.    a=rtpmap:101 telephone-event/8000
  839.    a=fmtp:101 0-16
  840.    a=ptime:20
  841.    ------------------------------------------------------------------------
  842. 2017-11-06 16:05:24.521620 [DEBUG] sofia.c:7048 Channel sofia/default/97264@192.168.1.161:5060 entering state [calling][0]
  843. 2017-11-06 16:05:24.601651 [DEBUG] switch_rtp.c:7254 Correct audio ip/port confirmed.
  844. 2017-11-06 16:05:24.721576 [INFO] switch_rtp.c:7214 Auto Changing audio port from 117.247.91.162:8000 to 192.168.1.33:8000
  845. freeswitch@freeswitch>
  846. freeswitch@freeswitch>
  847. freeswitch@freeswitch>
  848. freeswitch@freeswitch>
  849. freeswitch@freeswitch>
  850. recv 780 bytes from udp/[192.168.1.161]:5060 at 16:05:25.394068:
  851.    ------------------------------------------------------------------------
  852.    SIP/2.0 200 OK
  853.    Via: SIP/2.0/UDP 192.168.1.118;rport=5060;branch=z9hG4bK1NU5cF3DFKa9g
  854.    From: "9110327518" <sip:9110327518@192.168.1.118>;tag=UH5FH4y1DaQKS
  855.    To: <sip:97264@192.168.1.161:5060>;tag=1987421158
  856.    Call-ID: 0493884d-3d81-1236-c4b6-000c29f46286
  857.    CSeq: 114646194 UPDATE
  858.    Contact: <sip:97264@192.168.1.161:5060>
  859.    Supported: replaces, path, timer
  860.    User-Agent: Grandstream GXP1620 1.0.0.38
  861.   Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
  862.    Content-Type: application/sdp
  863.    Content-Length:   238
  864.    
  865.    v=0
  866.    o=97264 8000 8001 IN IP4 192.168.1.161
  867.    s=SIP Call
  868.    c=IN IP4 192.168.1.161
  869.    t=0 0
  870.    m=audio 5004 RTP/AVP 8 0 101
  871.    a=sendrecv
  872.    a=rtpmap:8 PCMA/8000
  873.    a=ptime:20
  874.    a=rtpmap:0 PCMU/8000
  875.    a=rtpmap:101 telephone-event/8000
  876.    a=fmtp:101 0-15
  877.    ------------------------------------------------------------------------
  878. 2017-11-06 16:05:25.381620 [DEBUG] sofia.c:7048 Channel sofia/default/97264@192.168.1.161:5060 entering state [ready][200]
  879. 2017-11-06 16:05:25.381620 [DEBUG] sofia.c:7058 Remote SDP:
  880. v=0
  881. o=97264 8000 8001 IN IP4 192.168.1.161
  882. s=SIP Call
  883. c=IN IP4 192.168.1.161
  884. t=0 0
  885. m=audio 5004 RTP/AVP 8 0 101
  886. a=rtpmap:8 PCMA/8000
  887. a=rtpmap:0 PCMU/8000
  888. a=rtpmap:101 telephone-event/8000
  889. a=fmtp:101 0-15
  890. a=ptime:20
  891.  
  892. 2017-11-06 16:05:25.381620 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  893. 2017-11-06 16:05:25.381620 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  894. 2017-11-06 16:05:25.381620 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  895. 2017-11-06 16:05:25.381620 [DEBUG] switch_core_media.c:4361 Set telephone-event payload to 101@8000
  896. 2017-11-06 16:05:25.381620 [DEBUG] switch_core_media.c:4704 Set telephone-event payload to 101@8000
  897. 2017-11-06 16:05:25.381620 [DEBUG] switch_core_media.c:4763 sofia/default/97264@192.168.1.161:5060 Set 2833 dtmf send payload to 101 recv payload to 101
  898. 2017-11-06 16:05:25.381620 [DEBUG] sofia.c:8025 Processing updated SDP
  899. 2017-11-06 16:05:25.381620 [DEBUG] switch_core_media.c:6857 Audio params are unchanged for sofia/default/97264@192.168.1.161:5060.
  900. freeswitch@freeswitch>
  901. send 605 bytes to udp/[1.1.1.1]:5060 at 16:05:26.138487:
  902.    ------------------------------------------------------------------------
  903.    OPTIONS sip:1.1.1.1;transport=udp SIP/2.0
  904.    Via: SIP/2.0/UDP 192.168.1.118;rport;branch=z9hG4bK2ymyeamHcv0Uc
  905.    Max-Forwards: 70
  906.    From: <sip:1.1.1.1>;tag=ZNaKrg2F2DgyQ
  907.    To: <sip:1.1.1.1>
  908.    Call-ID: 0ae98937-3d81-1236-c4b6-000c29f46286
  909.    CSeq: 114465671 OPTIONS
  910.    User-Agent: Local
  911.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  912.    Supported: path, replaces
  913.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  914.    Content-Length: 0
  915.    
  916.    ------------------------------------------------------------------------
  917. freeswitch@freeswitch>
  918. freeswitch@freeswitch>
  919. freeswitch@freeswitch>
  920. freeswitch@freeswitch>
  921. freeswitch@freeswitch>
  922. freeswitch@freeswitch>
  923. freeswitch@freeswitch>
  924. freeswitch@freeswitch>
  925. freeswitch@freeswitch>
  926. freeswitch@freeswitch>
  927. freeswitch@freeswitch>
  928. freeswitch@freeswitch>
  929. freeswitch@freeswitch>
  930. freeswitch@freeswitch>
  931. freeswitch@freeswitch>
  932. freeswitch@freeswitch>
  933. send 605 bytes to udp/[1.1.1.1]:5060 at 16:05:27.138748:
  934.    ------------------------------------------------------------------------
  935.    OPTIONS sip:1.1.1.1;transport=udp SIP/2.0
  936.    Via: SIP/2.0/UDP 192.168.1.118;rport;branch=z9hG4bK2ymyeamHcv0Uc
  937.    Max-Forwards: 70
  938.    From: <sip:1.1.1.1>;tag=ZNaKrg2F2DgyQ
  939.    To: <sip:1.1.1.1>
  940.    Call-ID: 0ae98937-3d81-1236-c4b6-000c29f46286
  941.    CSeq: 114465671 OPTIONS
  942.    User-Agent: Local
  943.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  944.    Supported: path, replaces
  945.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  946.    Content-Length: 0
  947.    
  948.    ------------------------------------------------------------------------
  949. 2017-11-06 16:05:27.181571 [WARNING] sofia.c:6098 Ping failed KK with code 503 - count 1/0/1, state DOWN
  950. recv 336 bytes from udp/[192.168.1.119]:6060 at 16:05:27.983777:
  951.    ------------------------------------------------------------------------
  952.    OPTIONS sip:192.168.1.118:5060 SIP/2.0
  953.    Via: SIP/2.0/UDP 117.247.91.162:6060;branch=z9hG4bKe348.0a4b0bf7.0
  954.    To: sip:192.168.1.118:5060
  955.    From: <sip:ping@192.168.1.119:5060>;tag=709be372f8df6ad02e74147e04c36617-6dde
  956.    CSeq: 14 OPTIONS
  957.    Call-ID: 19f406c45d424eaa-10323@192.168.1.119
  958.    Max-Forwards: 70
  959.    Content-Length: 0
  960.    User-Agent: SBC
  961.    
  962.    ------------------------------------------------------------------------
  963. send 702 bytes to udp/[192.168.1.119]:6060 at 16:05:27.984246:
  964.    ------------------------------------------------------------------------
  965.    SIP/2.0 200 OK
  966.    Via: SIP/2.0/UDP 117.247.91.162:6060;branch=z9hG4bKe348.0a4b0bf7.0;received=192.168.1.119
  967.    From: <sip:ping@192.168.1.119:5060>;tag=709be372f8df6ad02e74147e04c36617-6dde
  968.    To: <sip:192.168.1.118:5060>;tag=17v4U63pvZv3e
  969.    Call-ID: 19f406c45d424eaa-10323@192.168.1.119
  970.    CSeq: 14 OPTIONS
  971.    Contact: <sip:192.168.1.118>
  972.    User-Agent: Local
  973.    Accept: application/sdp
  974.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  975.    Supported: path, replaces
  976.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  977.    Content-Length: 0
  978.    
  979.    ------------------------------------------------------------------------
  980. recv 871 bytes from udp/[192.168.1.161]:5060 at 16:05:28.915748:
  981.    ------------------------------------------------------------------------
  982.    INVITE sip:mod_sofia@192.168.1.118:5060 SIP/2.0
  983.    Via: SIP/2.0/UDP 192.168.1.161:5060;branch=z9hG4bK1547255204;rport
  984.    From: <sip:97264@192.168.1.161:5060>;tag=1987421158
  985.    To: "9110327518" <sip:9110327518@192.168.1.118>;tag=UH5FH4y1DaQKS
  986.    Call-ID: 0493884d-3d81-1236-c4b6-000c29f46286
  987.    CSeq: 114646194 INVITE
  988.    Contact: <sip:97264@192.168.1.161:5060>
  989.    Max-Forwards: 70
  990.    Supported: replaces, path, timer
  991.    User-Agent: Grandstream GXP1620 1.0.0.38
  992.   Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
  993.    Content-Type: application/sdp
  994.    Accept: application/sdp, application/dtmf-relay
  995.    Content-Length:   232
  996.    
  997.    v=0
  998.    o=97264 8000 8002 IN IP4 192.168.1.161
  999.    s=SIP Call
  1000.    c=IN IP4 0.0.0.0
  1001.    t=0 0
  1002.    m=audio 5004 RTP/AVP 0 8 101
  1003.    a=sendonly
  1004.    a=rtpmap:0 PCMU/8000
  1005.    a=ptime:20
  1006.    a=rtpmap:8 PCMA/8000
  1007.    a=rtpmap:101 telephone-event/8000
  1008.    a=fmtp:101 0-15
  1009.    ------------------------------------------------------------------------
  1010. send 324 bytes to udp/[192.168.1.161]:5060 at 16:05:28.916040:
  1011.    ------------------------------------------------------------------------
  1012.    SIP/2.0 100 Trying
  1013.    Via: SIP/2.0/UDP 192.168.1.161:5060;branch=z9hG4bK1547255204;rport=5060
  1014.    From: <sip:97264@192.168.1.161:5060>;tag=1987421158
  1015.    To: "9110327518" <sip:9110327518@192.168.1.118>;tag=UH5FH4y1DaQKS
  1016.    Call-ID: 0493884d-3d81-1236-c4b6-000c29f46286
  1017.    CSeq: 114646194 INVITE
  1018.    User-Agent: Local
  1019.    Content-Length: 0
  1020.    
  1021.    ------------------------------------------------------------------------
  1022. 2017-11-06 16:05:28.921568 [DEBUG] sofia.c:7048 Channel sofia/default/97264@192.168.1.161:5060 entering state [received][100]
  1023. 2017-11-06 16:05:28.921568 [DEBUG] sofia.c:7058 Remote SDP:
  1024. v=0
  1025. o=97264 8000 8002 IN IP4 192.168.1.161
  1026. s=SIP Call
  1027. c=IN IP4 0.0.0.0
  1028. t=0 0
  1029. m=audio 5004 RTP/AVP 0 8 101
  1030. a=rtpmap:0 PCMU/8000
  1031. a=rtpmap:8 PCMA/8000
  1032. a=rtpmap:101 telephone-event/8000
  1033. a=fmtp:101 0-15
  1034. a=sendonly
  1035. a=ptime:20
  1036.  
  1037. 2017-11-06 16:05:28.921568 [WARNING] switch_core_media.c:3947 RFC2543 from March 1999 called; They want their 0.0.0.0 hold method back.....
  1038. 2017-11-06 16:05:28.921568 [DEBUG] switch_channel.c:1844 (sofia/default/97264@192.168.1.161:5060) Callstate Change ACTIVE -> HELD
  1039. 2017-11-06 16:05:29.081567 [DEBUG] switch_ivr.c:623 sofia/default/9110327518@192.168.1.118 Command Execute playback(local_stream://moh)
  1040. EXECUTE sofia/default/9110327518@192.168.1.118 playback(local_stream://moh)
  1041. 2017-11-06 16:05:29.081567 [DEBUG] mod_local_stream.c:871 Opening Stream [moh/8000] 8000hz
  1042. 2017-11-06 16:05:29.081567 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
  1043. 2017-11-06 16:05:29.181619 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1044. 2017-11-06 16:05:29.181619 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1045. 2017-11-06 16:05:29.181619 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  1046. 2017-11-06 16:05:29.181619 [DEBUG] switch_core_media.c:4361 Set telephone-event payload to 101@8000
  1047. 2017-11-06 16:05:29.181619 [DEBUG] switch_core_media.c:4704 Set telephone-event payload to 101@8000
  1048. 2017-11-06 16:05:29.181619 [DEBUG] switch_core_media.c:4763 sofia/default/97264@192.168.1.161:5060 Set 2833 dtmf send payload to 101 recv payload to 101
  1049. 2017-11-06 16:05:29.181619 [DEBUG] switch_core_media.c:6863 Audio params changed for sofia/default/97264@192.168.1.161:5060 from 192.168.1.161:5004 to 0.0.0.0:5004
  1050. 2017-11-06 16:05:29.181619 [DEBUG] switch_core_media.c:6874 AUDIO RTP [sofia/default/97264@192.168.1.161:5060] 192.168.1.118 port 23660 -> 0.0.0.0 port 5004 codec: 8 ms: 20
  1051. 2017-11-06 16:05:29.181619 [DEBUG] switch_core_media.c:6903 AUDIO RTP CHANGING DEST TO: [0.0.0.0:5004]
  1052. 2017-11-06 16:05:29.181619 [DEBUG] sofia.c:7963 Processing updated SDP
  1053. send 824 bytes to udp/[192.168.1.161]:5060 at 16:05:29.187408:
  1054.    ------------------------------------------------------------------------
  1055.    SIP/2.0 200 OK
  1056.    Via: SIP/2.0/UDP 192.168.1.161:5060;branch=z9hG4bK1547255204;rport=5060
  1057.    From: <sip:97264@192.168.1.161:5060>;tag=1987421158
  1058.    To: "9110327518" <sip:9110327518@192.168.1.118>;tag=UH5FH4y1DaQKS
  1059.    Call-ID: 0493884d-3d81-1236-c4b6-000c29f46286
  1060.    CSeq: 114646194 INVITE
  1061.    Contact: <sip:mod_sofia@192.168.1.118:5060>
  1062.    User-Agent: Local
  1063.    Accept: application/sdp
  1064.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1065.    Supported: path, replaces
  1066.    Content-Type: application/sdp
  1067.    Content-Disposition: session
  1068.    Content-Length: 234
  1069.    
  1070.    v=0
  1071.    o=FreeSWITCH 1509940855 1509940857 IN IP4 192.168.1.118
  1072.    s=FreeSWITCH
  1073.    c=IN IP4 192.168.1.118
  1074.    t=0 0
  1075.    m=audio 23660 RTP/AVP 8 101
  1076.    a=rtpmap:8 PCMA/8000
  1077.    a=rtpmap:101 telephone-event/8000
  1078.    a=fmtp:101 0-16
  1079.    a=recvonly
  1080.    a=ptime:20
  1081.    ------------------------------------------------------------------------
  1082. 2017-11-06 16:05:29.181619 [DEBUG] sofia.c:7048 Channel sofia/default/97264@192.168.1.161:5060 entering state [completed][200]
  1083. recv 548 bytes from udp/[192.168.1.161]:5060 at 16:05:29.201926:
  1084.    ------------------------------------------------------------------------
  1085.    ACK sip:mod_sofia@192.168.1.118:5060 SIP/2.0
  1086.    Via: SIP/2.0/UDP 192.168.1.161:5060;branch=z9hG4bK102781381;rport
  1087.    From: <sip:97264@192.168.1.161:5060>;tag=1987421158
  1088.    To: "9110327518" <sip:9110327518@192.168.1.118>;tag=UH5FH4y1DaQKS
  1089.    Call-ID: 0493884d-3d81-1236-c4b6-000c29f46286
  1090.    CSeq: 114646194 ACK
  1091.    Contact: <sip:97264@192.168.1.161:5060>
  1092.    Max-Forwards: 70
  1093.    Supported: replaces, path, timer
  1094.    User-Agent: Grandstream GXP1620 1.0.0.38
  1095.   Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
  1096.    Content-Length: 0
  1097.    
  1098.    ------------------------------------------------------------------------
  1099. 2017-11-06 16:05:29.201572 [DEBUG] sofia.c:7048 Channel sofia/default/97264@192.168.1.161:5060 entering state [ready][200]
  1100. recv 629 bytes from udp/[192.168.1.161]:5060 at 16:05:33.438325:
  1101.    ------------------------------------------------------------------------
  1102.    REFER sip:mod_sofia@192.168.1.118:5060 SIP/2.0
  1103.    Via: SIP/2.0/UDP 192.168.1.161:5060;branch=z9hG4bK203492676;rport
  1104.    From: <sip:97264@192.168.1.161:5060>;tag=1987421158
  1105.    To: "9110327518" <sip:9110327518@192.168.1.118>;tag=UH5FH4y1DaQKS
  1106.    Call-ID: 0493884d-3d81-1236-c4b6-000c29f46286
  1107.    CSeq: 114646195 REFER
  1108.    Contact: <sip:97264@192.168.1.161:5060>
  1109.    Max-Forwards: 70
  1110.    Supported: replaces, path, timer
  1111.    User-Agent: Grandstream GXP1620 1.0.0.38
  1112.    Refer-To: <sip:97265@192.168.1.118>
  1113.    Referred-By: <sip:97264@192.168.1.118>
  1114.   Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
  1115.    Content-Length: 0
  1116.    
  1117.    ------------------------------------------------------------------------
  1118. send 690 bytes to udp/[192.168.1.161]:5060 at 16:05:33.445796:
  1119.    ------------------------------------------------------------------------
  1120.    SIP/2.0 202 Accepted
  1121.    Via: SIP/2.0/UDP 192.168.1.161:5060;branch=z9hG4bK203492676;rport=5060
  1122.    From: <sip:97264@192.168.1.161:5060>;tag=1987421158
  1123.    To: "9110327518" <sip:9110327518@192.168.1.118>;tag=UH5FH4y1DaQKS
  1124.    Call-ID: 0493884d-3d81-1236-c4b6-000c29f46286
  1125.    CSeq: 114646195 REFER
  1126.    Contact: <sip:mod_sofia@192.168.1.118:5060>
  1127.    Expires: 60
  1128.    User-Agent: Local
  1129.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1130.    Supported: path, replaces
  1131.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  1132.    Content-Length: 0
  1133.    
  1134.    ------------------------------------------------------------------------
  1135. 2017-11-06 16:05:33.441570 [DEBUG] sofia.c:8508 Process REFER to [97265@192.168.1.118]
  1136. send 850 bytes to udp/[192.168.1.161]:5060 at 16:05:33.446138:
  1137.    ------------------------------------------------------------------------
  1138.    NOTIFY sip:97264@192.168.1.161:5060 SIP/2.0
  1139.    Via: SIP/2.0/UDP 192.168.1.118;rport;branch=z9hG4bK37DQg54m94per
  1140.    Max-Forwards: 70
  1141.    From: "9110327518" <sip:9110327518@192.168.1.118>;tag=UH5FH4y1DaQKS
  1142.    To: <sip:97264@192.168.1.161:5060>;tag=1987421158
  1143.    Call-ID: 0493884d-3d81-1236-c4b6-000c29f46286
  1144.    CSeq: 114646195 NOTIFY
  1145.    Contact: <sip:mod_sofia@192.168.1.118:5060>
  1146.    User-Agent: Local
  1147.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1148.    Supported: path, replaces
  1149.    Event: refer;id=114646195
  1150.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  1151.    Subscription-State: terminated;reason=noresource
  1152.    Content-Type: message/sipfrag;version=2.0
  1153.    Content-Length: 16
  1154.    
  1155.    SIP/2.0 200 OK
  1156.    ------------------------------------------------------------------------
  1157. 2017-11-06 16:05:33.441570 [DEBUG] switch_ivr.c:2165 (sofia/default/9110327518@192.168.1.118) State Change CS_EXECUTE -> CS_ROUTING
  1158. 2017-11-06 16:05:33.441570 [NOTICE] switch_ivr.c:2172 Transfer sofia/default/9110327518@192.168.1.118 to XML[97265@default]
  1159. 2017-11-06 16:05:33.441570 [DEBUG] switch_ivr_play_say.c:1942 done playing file local_stream://moh
  1160. 2017-11-06 16:05:33.461580 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/default/97264@192.168.1.161:5060]
  1161. 2017-11-06 16:05:33.461580 [DEBUG] switch_channel.c:2029 (sofia/default/97264@192.168.1.161:5060) Callstate Change HELD -> UNHELD
  1162. 2017-11-06 16:05:33.461580 [NOTICE] switch_ivr_bridge.c:904 Hangup sofia/default/97264@192.168.1.161:5060 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  1163. 2017-11-06 16:05:33.461580 [DEBUG] switch_core_state_machine.c:653 (sofia/default/97264@192.168.1.161:5060) State EXCHANGE_MEDIA going to sleep
  1164. 2017-11-06 16:05:33.461580 [DEBUG] switch_core_state_machine.c:584 (sofia/default/97264@192.168.1.161:5060) Running State Change CS_HANGUP (Cur 2 Tot 275)
  1165. 2017-11-06 16:05:33.461580 [DEBUG] switch_core_state_machine.c:850 (sofia/default/97264@192.168.1.161:5060) Callstate Change UNHELD -> HANGUP
  1166. 2017-11-06 16:05:33.461580 [DEBUG] switch_core_state_machine.c:852 (sofia/default/97264@192.168.1.161:5060) State HANGUP
  1167. 2017-11-06 16:05:33.461580 [DEBUG] mod_sofia.c:438 Channel sofia/default/97264@192.168.1.161:5060 hanging up, cause: NORMAL_CLEARING
  1168. 2017-11-06 16:05:33.461580 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/default/9110327518@192.168.1.118]
  1169. 2017-11-06 16:05:33.461580 [DEBUG] switch_ivr_bridge.c:1712 sofia/default/97264@192.168.1.161:5060 skip receive message [UNBRIDGE] (channel is hungup already)
  1170. 2017-11-06 16:05:33.461580 [DEBUG] switch_core_state_machine.c:650 (sofia/default/9110327518@192.168.1.118) State EXECUTE going to sleep
  1171. 2017-11-06 16:05:33.461580 [DEBUG] switch_core_state_machine.c:584 (sofia/default/9110327518@192.168.1.118) Running State Change CS_ROUTING (Cur 2 Tot 275)
  1172. 2017-11-06 16:05:33.461580 [DEBUG] switch_channel.c:2249 (sofia/default/9110327518@192.168.1.118) Callstate Change ACTIVE -> RINGING
  1173. 2017-11-06 16:05:33.461580 [DEBUG] switch_core_state_machine.c:643 (sofia/default/9110327518@192.168.1.118) State ROUTING
  1174. 2017-11-06 16:05:33.461580 [DEBUG] mod_sofia.c:143 sofia/default/9110327518@192.168.1.118 SOFIA ROUTING
  1175. 2017-11-06 16:05:33.461580 [DEBUG] switch_core_state_machine.c:236 sofia/default/9110327518@192.168.1.118 Standard ROUTING
  1176. 2017-11-06 16:05:33.461580 [INFO] mod_dialplan_xml.c:637 Processing 9110327518 <9110327518>->97265 in context default
  1177. 2017-11-06 16:05:33.461580 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fa6d801e830 Connected.
  1178. 2017-11-06 16:05:33.461580 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM system WHERE group_title IN ('global','opensips','callingcard')
  1179. 2017-11-06 16:05:33.481581 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [xml_handler] Params:
  1180. 'Event-Name: REQUEST_PARAMS
  1181. Core-UUID: b8b0770f-83a5-4654-b268-e2c6ae265441
  1182. FreeSWITCH-Hostname: astpptest1
  1183. FreeSWITCH-Switchname: freeswitch
  1184. FreeSWITCH-IPv4: 192.168.1.118
  1185. FreeSWITCH-IPv6: %3A%3A1
  1186. Event-Date-Local: 2017-11-06%2016%3A05%3A33
  1187. Event-Date-GMT: Mon,%2006%20Nov%202017%2010%3A35%3A33%20GMT
  1188. Event-Date-Timestamp: 1509964533461580
  1189. Event-Calling-File: mod_dialplan_xml.c
  1190. Event-Calling-Function: dialplan_xml_locate
  1191. Event-Calling-Line-Number: 608
  1192. Event-Sequence: 75044
  1193. Channel-State: CS_ROUTING
  1194. Channel-Call-State: RINGING
  1195. Channel-State-Number: 2
  1196. Channel-Name: sofia/default/9110327518%40192.168.1.118
  1197. Unique-ID: d486e76a-fc5b-414d-81a2-0c62494e2a0e
  1198. Call-Direction: inbound
  1199. Presence-Call-Direction: inbound
  1200. Channel-HIT-Dialplan: true
  1201. Channel-Presence-ID: 9110327518%40192.168.1.118
  1202. Channel-Call-UUID: d486e76a-fc5b-414d-81a2-0c62494e2a0e
  1203. Answer-State: answered
  1204. Channel-Read-Codec-Name: PCMA
  1205. Channel-Read-Codec-Rate: 8000
  1206. Channel-Read-Codec-Bit-Rate: 64000
  1207. Channel-Write-Codec-Name: PCMA
  1208. Channel-Write-Codec-Rate: 8000
  1209. Channel-Write-Codec-Bit-Rate: 64000
  1210. Caller-Direction: inbound
  1211. Caller-Logical-Direction: inbound
  1212. Caller-Username: 9110327518
  1213. Caller-Dialplan: XML
  1214. Caller-Caller-ID-Name: 9110327518
  1215. Caller-Caller-ID-Number: 9110327518
  1216. Caller-Orig-Caller-ID-Name: 9110327518
  1217. Caller-Orig-Caller-ID-Number: 9110327518
  1218. Caller-Callee-ID-Name: Outbound%20Call
  1219. Caller-Callee-ID-Number: 97264
  1220. Caller-Network-Addr: 192.168.1.33
  1221. Caller-ANI: 9110327518
  1222. Caller-Destination-Number: 97265
  1223. Caller-Unique-ID: d486e76a-fc5b-414d-81a2-0c62494e2a0e
  1224. Caller-Source: mod_sofia
  1225. Caller-Transfer-Source: 1509964533%3A196e5e30-f8ea-4f36-8b0e-1330c770d8f8%3Abl_xfer%3A97265/default/XML
  1226. Caller-Context: default
  1227. Caller-RDNIS: 97264
  1228. Caller-Channel-Name: sofia/default/9110327518%40192.168.1.118
  1229. Caller-Profile-Index: 2
  1230. Caller-Profile-Created-Time: 1509964533441570
  1231. Caller-Channel-Created-Time: 1509964514981576
  1232. Caller-Channel-Answered-Time: 1509964524461569
  1233. Caller-Channel-Progress-Time: 1509964515701619
  1234. Caller-Channel-Progress-Media-Time: 1509964524461569
  1235. Caller-Channel-Hangup-Time: 0
  1236. Caller-Channel-Transfer-Time: 0
  1237. Caller-Channel-Resurrect-Time: 0
  1238. Caller-Channel-Bridged-Time: 0
  1239. Caller-Channel-Last-Hold: 0
  1240. Caller-Channel-Hold-Accum: 0
  1241. Caller-Screen-Bit: true
  1242. Caller-Privacy-Hide-Name: false
  1243. Caller-Privacy-Hide-Number: false
  1244. variable_direction: inbound
  1245. variable_uuid: d486e76a-fc5b-414d-81a2-0c62494e2a0e
  1246. variable_session_id: 274
  1247. variable_sip_from_params: transport%3DTCP
  1248. variable_sip_from_user: 9110327518
  1249. variable_sip_from_uri: 9110327518%40192.168.1.118
  1250. variable_sip_from_host: 192.168.1.118
  1251. variable_video_media_flow: sendrecv
  1252. variable_channel_name: sofia/default/9110327518%40192.168.1.118
  1253. variable_ep_codec_string: CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b
  1254. variable_sip_local_network_addr: 192.168.1.118
  1255. variable_sip_network_ip: 192.168.1.33
  1256. variable_sip_network_port: 48236
  1257. variable_sip_invite_stamp: 1509964514981576
  1258. variable_sip_received_ip: 192.168.1.33
  1259. variable_sip_received_port: 48236
  1260. variable_sip_via_protocol: tcp
  1261. variable_sip_authorized: true
  1262. variable_sip_acl_authed_by: default
  1263. variable_sip_from_user_stripped: 9110327518
  1264. variable_sofia_profile_name: default
  1265. variable_recovery_profile_name: default
  1266. variable_sip_allow: INVITE,%20ACK,%20CANCEL,%20BYE,%20NOTIFY,%20REFER,%20MESSAGE,%20OPTIONS,%20INFO,%20SUBSCRIBE
  1267. variable_sip_req_params: transport%3DTCP
  1268. variable_sip_req_user: 97264
  1269. variable_sip_req_uri: 97264%40192.168.1.118
  1270. variable_sip_req_host: 192.168.1.118
  1271. variable_sip_to_params: transport%3DTCP
  1272. variable_sip_to_user: 97264
  1273. variable_sip_to_uri: 97264%40192.168.1.118
  1274. variable_sip_to_host: 192.168.1.118
  1275. variable_sip_contact_params: transport%3DTCP
  1276. variable_sip_contact_user: 9110327518
  1277. variable_sip_contact_port: 58810
  1278. variable_sip_contact_uri: 9110327518%40117.247.91.162%3A58810
  1279. variable_sip_contact_host: 117.247.91.162
  1280. variable_sip_user_agent: Z%203.3.25608%20r25552
  1281. variable_sip_via_host: 192.168.1.33
  1282. variable_sip_via_port: 58810
  1283. variable_sip_via_rport: 48236
  1284. variable_presence_id: 9110327518%40192.168.1.118
  1285. variable_switch_r_sdp: v%3D0%0D%0Ao%3DZ%200%200%20IN%20IP4%20117.247.91.162%0D%0As%3DZ%0D%0Ac%3DIN%20IP4%20117.247.91.162%0D%0At%3D0%200%0D%0Am%3Daudio%208000%20RTP/AVP%203%20110%208%200%2098%20101%0D%0Aa%3Drtpmap%3A110%20speex/8000%0D%0Aa%3Drtpmap%3A98%20iLBC/8000%0D%0Aa%3Dfmtp%3A98%20mode%3D20%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-15%0D%0A
  1286. variable_effective_destination_number: 97264
  1287. variable_callstart: 2017-11-06%2010%3A35%3A15
  1288. variable_hangup_after_bridge: true
  1289. variable_continue_on_fail: true
  1290. variable_account_id: 4
  1291. variable_parent_id: 0
  1292. variable_entity_id: 0
  1293. variable_call_processed: internal
  1294. variable_call_direction: local
  1295. variable_accountname: ketank
  1296. variable_origination_rates: ID%3A0%7CCODE%3A97264%7CDESTINATION%3ALocal%7CCONNECTIONCOST%3A0%7CINCLUDEDSECONDS%3A0%7CCOST%3A0%7CINC%3A60%7CINITIALBLOCK%3A0%7CRATEGROUP%3A2%7CMARKUP%3A0%7CACCID%3A4
  1297. variable_original_caller_id_name: 9110327518
  1298. variable_original_caller_id_number: 9110327518
  1299. variable_effective_caller_id_name: 9110327518
  1300. variable_effective_caller_id_number: 9110327518
  1301. variable_calltype: LOCAL
  1302. variable_receiver_accid: 2
  1303. variable_dialed_user: 97264
  1304. variable_dialed_domain: 192.168.1.118
  1305. variable_originated_legs: ARRAY%3A%3Aa36afe8f-cd01-462f-a412-7eb301532ad6%3BOutbound%20Call%3B97264%7C%3Aa36afe8f-cd01-462f-a412-7eb301532ad6%3BOutbound%20Call%3B97264
  1306. variable_rtp_use_codec_string: PCMA,PCMU
  1307. variable_audio_media_flow: sendrecv
  1308. variable_rtp_audio_recv_pt: 8
  1309. variable_rtp_use_codec_name: PCMA
  1310. variable_rtp_use_codec_rate: 8000
  1311. variable_rtp_use_codec_ptime: 20
  1312. variable_rtp_use_codec_channels: 1
  1313. variable_rtp_last_audio_codec_string: PCMA%408000h%4020i%401c
  1314. variable_read_codec: PCMA
  1315. variable_original_read_codec: PCMA
  1316. variable_read_rate: 8000
  1317. variable_original_read_rate: 8000
  1318. variable_write_codec: PCMA
  1319. variable_write_rate: 8000
  1320. variable_dtmf_type: rfc2833
  1321. variable_local_media_ip: 192.168.1.118
  1322. variable_local_media_port: 31498
  1323. variable_advertised_media_ip: 192.168.1.118
  1324. variable_rtp_use_timer_name: soft
  1325. variable_rtp_use_pt: 8
  1326. variable_rtp_use_ssrc: 1174983138
  1327. variable_rtp_2833_send_payload: 101
  1328. variable_rtp_2833_recv_payload: 101
  1329. variable_remote_media_ip: 117.247.91.162
  1330. variable_remote_media_port: 8000
  1331. variable_rtp_local_sdp_str: v%3D0%0D%0Ao%3DFreeSWITCH%201509933026%201509933027%20IN%20IP4%20192.168.1.118%0D%0As%3DFreeSWITCH%0D%0Ac%3DIN%20IP4%20192.168.1.118%0D%0At%3D0%200%0D%0Am%3Daudio%2031498%20RTP/AVP%208%20101%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0Aa%3Dptime%3A20%0D%0Aa%3Dsendrecv%0D%0A
  1332. variable_sip_nat_detected: true
  1333. variable_endpoint_disposition: ANSWER
  1334. variable_originate_causes: ARRAY%3A%3Aa36afe8f-cd01-462f-a412-7eb301532ad6%3BNONE%7C%3Aa36afe8f-cd01-462f-a412-7eb301532ad6%3BNONE
  1335. variable_originate_disposition: SUCCESS
  1336. variable_DIALSTATUS: SUCCESS
  1337. variable_sip_to_tag: SZjyDeXtKraej
  1338. variable_sip_from_tag: c325a037
  1339. variable_sip_cseq: 1
  1340. variable_sip_call_id: ZmJhZTBkOGRhZTFhYzQyZWRlNjIzMmRmZThmMTAwYzM.
  1341. variable_sip_full_via: SIP/2.0/TCP%20192.168.1.33%3A58810%3Bbranch%3Dz9hG4bK-d8754z-e1b7840bc29c2aeb-1---d8754z-%3Brport%3D48236
  1342. variable_sip_recover_via: SIP/2.0/TCP%20192.168.1.33%3A58810%3Bbranch%3Dz9hG4bK-d8754z-e1b7840bc29c2aeb-1---d8754z-%3Brport%3D48236
  1343. variable_sip_full_from: %3Csip%3A9110327518%40192.168.1.118%3Btransport%3DTCP%3E%3Btag%3Dc325a037
  1344. variable_sip_full_to: %3Csip%3A97264%40192.168.1.118%3Btransport%3DTCP%3E%3Btag%3DSZjyDeXtKraej
  1345. variable_last_bridge_to: a36afe8f-cd01-462f-a412-7eb301532ad6
  1346. variable_bridge_channel: sofia/default/97264%40192.168.1.161%3A5060
  1347. variable_bridge_uuid: a36afe8f-cd01-462f-a412-7eb301532ad6
  1348. variable_last_sent_callee_id_name: Outbound%20Call
  1349. variable_last_sent_callee_id_number: 97264
  1350. variable_remote_audio_ip_reported: 117.247.91.162
  1351. variable_remote_audio_ip: 192.168.1.33
  1352. variable_remote_audio_port_reported: 8000
  1353. variable_remote_audio_port: 8000
  1354. variable_rtp_auto_adjust_audio: true
  1355. variable_switch_m_sdp: v%3D0%0D%0Ao%3D97264%208000%208002%20IN%20IP4%20192.168.1.161%0D%0As%3DSIP%20Call%0D%0Ac%3DIN%20IP4%200.0.0.0%0D%0At%3D0%200%0D%0Am%3Daudio%205004%20RTP/AVP%200%208%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-15%0D%0Aa%3Dsendonly%0D%0Aa%3Dptime%3A20%0D%0A
  1356. variable_current_application_data: local_stream%3A//moh
  1357. variable_current_application: playback
  1358. variable_sip_h_Referred-By: %3Csip%3A97264%40192.168.1.118%3E
  1359. variable_sip_refer_to: %3Csip%3A97265%40192.168.1.118%3E
  1360. variable_max_forwards: 69
  1361. variable_transfer_history: 1509964533%3A196e5e30-f8ea-4f36-8b0e-1330c770d8f8%3Abl_xfer%3A97265/default/XML
  1362. variable_transfer_source: 1509964533%3A196e5e30-f8ea-4f36-8b0e-1330c770d8f8%3Abl_xfer%3A97265/default/XML
  1363. variable_playback_last_offset_pos: 34880
  1364. variable_playback_seconds: 4
  1365. variable_playback_ms: 4570
  1366. variable_playback_samples: 36560
  1367. variable_current_application_response: PLAYBACK%20ERROR
  1368. variable_bridge_hangup_cause: NORMAL_CLEARING
  1369. variable_call_uuid: d486e76a-fc5b-414d-81a2-0c62494e2a0e
  1370. Hunt-Direction: inbound
  1371. Hunt-Logical-Direction: inbound
  1372. Hunt-Username: 9110327518
  1373. Hunt-Dialplan: XML
  1374. Hunt-Caller-ID-Name: 9110327518
  1375. Hunt-Caller-ID-Number: 9110327518
  1376. Hunt-Orig-Caller-ID-Name: 9110327518
  1377. Hunt-Orig-Caller-ID-Number: 9110327518
  1378. Hunt-Callee-ID-Name: Outbound%20Call
  1379. Hunt-Callee-ID-Number: 97264
  1380. Hunt-Network-Addr: 192.168.1.33
  1381. Hunt-ANI: 9110327518
  1382. Hunt-Destination-Number: 97265
  1383. Hunt-Unique-ID: d486e76a-fc5b-414d-81a2-0c62494e2a0e
  1384. Hunt-Source: mod_sofia
  1385. Hunt-Transfer-Source: 1509964533%3A196e5e30-f8ea-4f36-8b0e-1330c770d8f8%3Abl_xfer%3A97265/default/XML
  1386. Hunt-Context: default
  1387. Hunt-RDNIS: 97264
  1388. Hunt-Channel-Name: sofia/default/9110327518%40192.168.1.118
  1389. Hunt-Profile-Index: 2
  1390. Hunt-Profile-Created-Time: 1509964533441570
  1391. Hunt-Channel-Created-Time: 1509964514981576
  1392. Hunt-Channel-Answered-Time: 1509964524461569
  1393. Hunt-Channel-Progress-Time: 1509964515701619
  1394. Hunt-Channel-Progress-Media-Time: 1509964524461569
  1395. Hunt-Channel-Hangup-Time: 0
  1396. Hunt-Channel-Transfer-Time: 0
  1397. Hunt-Channel-Resurrect-Time: 0
  1398. Hunt-Channel-Bridged-Time: 0
  1399. Hunt-Channel-Last-Hold: 0
  1400. Hunt-Channel-Hold-Accum: 0
  1401. Hunt-Screen-Bit: true
  1402. Hunt-Privacy-Hide-Name: false
  1403. Hunt-Privacy-Hide-Number: false
  1404.  
  1405. '
  1406. 2017-11-06 16:05:33.481581 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] key_name:
  1407. 2017-11-06 16:05:33.481581 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] key_value:
  1408. 2017-11-06 16:05:33.481581 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] section: dialplan
  1409. 2017-11-06 16:05:33.481581 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] tag_name:
  1410. 2017-11-06 16:05:33.481581 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Dialed number : 97265
  1411. 2017-11-06 16:05:33.481581 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Caller Id name / number  : 9110327518 / 9110327518
  1412. 2017-11-06 16:05:33.481581 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,B.id as accountid,B.number as account_code,B.did_cid_translation as did_cid_translation,A.number as  did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout FROM dids AS A,accounts AS B WHERE A.status=0 AND B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="97265" LIMIT 1
  1413. 2017-11-06 16:05:33.481581 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND username="97265" limit 1
  1414. 2017-11-06 16:05:33.481581 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Call direction : local
  1415. 2017-11-06 16:05:33.481581 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [IPAUTHENTICATION] Query :SELECT ip_map.*, (SELECT number FROM accounts where id=accountid AND status=0 AND deleted=0) AS account_code FROM ip_map WHERE ((INET_ATON("192.168.1.33") & (0xFFFFFFFF & (-1 << 32 - SUBSTRING_INDEX(ip, '/',-1)))) =  ((0xFFFFFFFF & (-1 << 32 - SUBSTRING_INDEX(ip, '/',-1))) & INET_ATON(SUBSTRING_INDEX(ip,'/',1)))) AND ((SUBSTRING( ip, 1, CHAR_LENGTH( ip ) -3 ) = "192.168.1.33" AND prefix IN (NULL,'')) OR (SUBSTRING( ip, 1, CHAR_LENGTH( ip ) -3 ) = "192.168.1.33" AND "97265"  RLIKE prefix)) ORDER BY LENGTH(prefix) DESC LIMIT 1
  1416. 2017-11-06 16:05:33.481581 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [Accountcode : 6833949103]
  1417. 2017-11-06 16:05:33.481581 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE (number = "6833949103" OR id="6833949103") AND status=0 AND deleted=0 AND (expiry >= '2017-11-06 10:35:33' OR expiry = '0000-00-00 00:00:00') limit 1
  1418. recv 507 bytes from udp/[192.168.1.161]:5060 at 16:05:33.491003:
  1419.    ------------------------------------------------------------------------
  1420.    SIP/2.0 200 OK
  1421.    Via: SIP/2.0/UDP 192.168.1.118;rport=5060;branch=z9hG4bK37DQg54m94per
  1422.    From: "9110327518" <sip:9110327518@192.168.1.118>;tag=UH5FH4y1DaQKS
  1423.    To: <sip:97264@192.168.1.161:5060>;tag=1987421158
  1424.    Call-ID: 0493884d-3d81-1236-c4b6-000c29f46286
  1425.    CSeq: 114646195 NOTIFY
  1426.    Contact: <sip:97264@192.168.1.161:5060>
  1427.    Supported: replaces, path, timer
  1428.    User-Agent: Grandstream GXP1620 1.0.0.38
  1429.   Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
  1430.    Content-Length: 0
  1431.    
  1432.    ------------------------------------------------------------------------
  1433. 2017-11-06 16:05:33.501565 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT * FROM packages  as P inner join package_patterns as PKGPTR on P.id = PKGPTR.package_id WHERE (patterns ='^97265.*' OR patterns ='^9726.*' OR patterns ='^972.*' OR patterns ='^97.*' OR patterns ='^9.*' OR patterns ='--') AND status = 0 AND pricelist_id = 2 ORDER BY LENGTH(PKGPTR.patterns) DESC LIMIT 1
  1434. recv 549 bytes from udp/[192.168.1.161]:5060 at 16:05:33.506922:
  1435.    ------------------------------------------------------------------------
  1436.    BYE sip:mod_sofia@192.168.1.118:5060 SIP/2.0
  1437.    Via: SIP/2.0/UDP 192.168.1.161:5060;branch=z9hG4bK1887183128;rport
  1438.    From: <sip:97264@192.168.1.161:5060>;tag=1987421158
  1439.    To: "9110327518" <sip:9110327518@192.168.1.118>;tag=UH5FH4y1DaQKS
  1440.    Call-ID: 0493884d-3d81-1236-c4b6-000c29f46286
  1441.    CSeq: 114646196 BYE
  1442.    Contact: <sip:97264@192.168.1.161:5060>
  1443.    Max-Forwards: 70
  1444.    Supported: replaces, path, timer
  1445.    User-Agent: Grandstream GXP1620 1.0.0.38
  1446.   Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
  1447.    Content-Length: 0
  1448.    
  1449.    ------------------------------------------------------------------------
  1450. 2017-11-06 16:05:33.501565 [INFO] switch_cpp.cpp:1365 [ASTPP] =============== Account Information ===================
  1451. 2017-11-06 16:05:33.501565 [INFO] switch_cpp.cpp:1365 [ASTPP] User id : 4
  1452. 2017-11-06 16:05:33.501565 [INFO] switch_cpp.cpp:1365 [ASTPP] Account code : 6833949103
  1453. 2017-11-06 16:05:33.501565 [INFO] switch_cpp.cpp:1365 [ASTPP] Balance : 100
  1454. 2017-11-06 16:05:33.501565 [INFO] switch_cpp.cpp:1365 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
  1455. 2017-11-06 16:05:33.501565 [INFO] switch_cpp.cpp:1365 [ASTPP] Ratecard id : 2
  1456. 2017-11-06 16:05:33.501565 [INFO] switch_cpp.cpp:1365 [ASTPP] ========================================================
  1457. 2017-11-06 16:05:33.501565 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 2 AND status = 0
  1458. 2017-11-06 16:05:33.501565 [INFO] switch_cpp.cpp:1365 [ASTPP] [FIND_MAXLENGTH] Call is free - assigning max length!!! :: 100
  1459. 2017-11-06 16:05:33.501565 [INFO] switch_cpp.cpp:1365 [ASTPP] Call Max length duration : 100 minutes
  1460. 2017-11-06 16:05:33.501565 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND username="97265" limit 1
  1461. 2017-11-06 16:05:33.501565 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/default/97264@192.168.1.161:5060
  1462. send 538 bytes to udp/[192.168.1.161]:5060 at 16:05:33.514880:
  1463.    ------------------------------------------------------------------------
  1464.    BYE sip:97264@192.168.1.161:5060 SIP/2.0
  1465.    Via: SIP/2.0/UDP 192.168.1.118;rport;branch=z9hG4bK4g7Fj0Nr6DD1K
  1466.    Max-Forwards: 70
  1467.    From: "9110327518" <sip:9110327518@192.168.1.118>;tag=UH5FH4y1DaQKS
  1468.    To: <sip:97264@192.168.1.161:5060>;tag=1987421158
  1469.    Call-ID: 0493884d-3d81-1236-c4b6-000c29f46286
  1470.    CSeq: 114646196 BYE
  1471.    User-Agent: Local
  1472.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1473.    Supported: path, replaces
  1474.    Reason: Q.850;cause=16;text="NORMAL_CLEARING"
  1475.    Content-Length: 0
  1476.    
  1477.    ------------------------------------------------------------------------
  1478. 2017-11-06 16:05:33.501565 [DEBUG] switch_core_state_machine.c:60 sofia/default/97264@192.168.1.161:5060 Standard HANGUP, cause: NORMAL_CLEARING
  1479. 2017-11-06 16:05:33.501565 [DEBUG] switch_core_state_machine.c:852 (sofia/default/97264@192.168.1.161:5060) State HANGUP going to sleep
  1480. 2017-11-06 16:05:33.501565 [DEBUG] switch_core_state_machine.c:619 (sofia/default/97264@192.168.1.161:5060) State Change CS_HANGUP -> CS_REPORTING
  1481. 2017-11-06 16:05:33.501565 [DEBUG] switch_core_state_machine.c:584 (sofia/default/97264@192.168.1.161:5060) Running State Change CS_REPORTING (Cur 2 Tot 275)
  1482. 2017-11-06 16:05:33.501565 [DEBUG] switch_core_state_machine.c:938 (sofia/default/97264@192.168.1.161:5060) State REPORTING
  1483. 2017-11-06 16:05:33.501565 [DEBUG] switch_core_state_machine.c:174 sofia/default/97264@192.168.1.161:5060 Standard REPORTING, cause: NORMAL_CLEARING
  1484. 2017-11-06 16:05:33.501565 [DEBUG] switch_core_state_machine.c:938 (sofia/default/97264@192.168.1.161:5060) State REPORTING going to sleep
  1485. 2017-11-06 16:05:33.501565 [DEBUG] switch_core_state_machine.c:610 (sofia/default/97264@192.168.1.161:5060) State Change CS_REPORTING -> CS_DESTROY
  1486. 2017-11-06 16:05:33.501565 [DEBUG] switch_core_session.c:1664 Session 275 (sofia/default/97264@192.168.1.161:5060) Locked, Waiting on external entities
  1487. 2017-11-06 16:05:33.501565 [NOTICE] switch_core_session.c:1682 Session 275 (sofia/default/97264@192.168.1.161:5060) Ended
  1488. 2017-11-06 16:05:33.501565 [NOTICE] switch_core_session.c:1686 Close Channel sofia/default/97264@192.168.1.161:5060 [CS_DESTROY]
  1489. 2017-11-06 16:05:33.501565 [DEBUG] switch_core_state_machine.c:741 (sofia/default/97264@192.168.1.161:5060) Running State Change CS_DESTROY (Cur 1 Tot 275)
  1490. 2017-11-06 16:05:33.501565 [DEBUG] switch_core_state_machine.c:751 (sofia/default/97264@192.168.1.161:5060) State DESTROY
  1491. 2017-11-06 16:05:33.501565 [DEBUG] mod_sofia.c:343 sofia/default/97264@192.168.1.161:5060 SOFIA DESTROY
  1492. 2017-11-06 16:05:33.501565 [DEBUG] switch_core_state_machine.c:181 sofia/default/97264@192.168.1.161:5060 Standard DESTROY
  1493. 2017-11-06 16:05:33.501565 [DEBUG] switch_core_state_machine.c:751 (sofia/default/97264@192.168.1.161:5060) State DESTROY going to sleep
  1494. 2017-11-06 16:05:33.501565 [WARNING] switch_cpp.cpp:1365 [ASTPP] [FSXMLLOCAL] Caller ID Translation Starts
  1495. 2017-11-06 16:05:33.501565 [WARNING] switch_cpp.cpp:1365 [ASTPP] [FSXMLLOCAL] Caller ID Translation Ends
  1496. 2017-11-06 16:05:33.501565 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Generated XML:
  1497. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  1498. <document type="freeswitch/xml">
  1499. <section name="dialplan" description="ASTPP Dialplan">
  1500. <context name="default">
  1501. <extension name="att_xfer">
  1502. <condition field="destination_number" expression="^att_xfer$">
  1503. <action application="read" data="1 10 'tone_stream://%(10000,0,350,440)' digits 20000 #"/>
  1504. <action application="transfer" data="-bleg ${digits}"/>
  1505. </condition>
  1506. </extension>
  1507. <extension name="97265">
  1508. <condition field="destination_number" expression="97265">
  1509. <action application="set" data="effective_destination_number=97265"/>
  1510. <action application="sched_hangup" data="+6000 normal_clearing"/>
  1511. <action application="set" data="callstart=2017-11-06 10:35:33"/>
  1512. <action application="set" data="hangup_after_bridge=true"/>
  1513. <action application="set" data="continue_on_fail=true"/>
  1514. <action application="set" data="account_id=4"/>
  1515. <action application="set" data="parent_id=0"/>
  1516. <action application="set" data="entity_id=0"/>
  1517. <action application="set" data="call_processed=internal"/>
  1518. <action application="set" data="call_direction=local"/>
  1519. <action application="set" data="accountname=ketank"/>
  1520. <action application="set" data="origination_rates=ID:0|CODE:97265|DESTINATION:Local|CONNECTIONCOST:0|INCLUDEDSECONDS:0|COST:0|INC:60|INITIALBLOCK:0|RATEGROUP:2|MARKUP:0|ACCID:4"/>
  1521. <action application="set" data="original_caller_id_name=9110327518"/>
  1522. <action application="set" data="original_caller_id_number=9110327518"/>
  1523. <action application="set" data="effective_caller_id_name=9110327518"/>
  1524. <action application="set" data="effective_caller_id_number=9110327518"/>
  1525. <action application="set" data="calltype=LOCAL"/>
  1526. <action application="set" data="receiver_accid=2"/>
  1527. <action application="bind_meta_app" data="2 ab ab execute_extension::att_xfer XML default"/>
  1528. <action application="bridge" data="[leg_timeout=30]user/97265@${domain_name}"/>
  1529. <condition field="${cond(${user_data 97265@${domain_name} param vm-enabled} == true ? YES : NO)}" expression="^YES$">
  1530. <action application="export" data="voicemail_alternate_greet_id=97265"/>
  1531. <action application="answer"/>
  1532. <action application="voicemail" data="default $${domain_name} 97265"/>
  1533. <anti-action application="hangup" data="${originate_disposition}"/>
  1534. </condition>
  1535. </condition>
  1536. </extension>
  1537. </context>
  1538. </section>
  1539. </document>
  1540. 2017-11-06 16:05:33.501565 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fa6d801e830 released.
  1541. Dialplan: sofia/default/9110327518@192.168.1.118 parsing [default->att_xfer] continue=false
  1542. Dialplan: sofia/default/9110327518@192.168.1.118 Regex (FAIL) [att_xfer] destination_number(97265) =~ /^att_xfer$/ break=on-false
  1543. Dialplan: sofia/default/9110327518@192.168.1.118 parsing [default->97265] continue=false
  1544. Dialplan: sofia/default/9110327518@192.168.1.118 Regex (PASS) [97265] destination_number(97265) =~ /97265/ break=on-false
  1545. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(effective_destination_number=97265)
  1546. Dialplan: sofia/default/9110327518@192.168.1.118 Action sched_hangup(+6000 normal_clearing)
  1547. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(callstart=2017-11-06 10:35:33)
  1548. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(hangup_after_bridge=true)
  1549. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(continue_on_fail=true)
  1550. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(account_id=4)
  1551. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(parent_id=0)
  1552. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(entity_id=0)
  1553. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(call_processed=internal)
  1554. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(call_direction=local)
  1555. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(accountname=ketank)
  1556. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(origination_rates=ID:0|CODE:97265|DESTINATION:Local|CONNECTIONCOST:0|INCLUDEDSECONDS:0|COST:0|INC:60|INITIALBLOCK:0|RATEGROUP:2|MARKUP:0|ACCID:4)
  1557. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(original_caller_id_name=9110327518)
  1558. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(original_caller_id_number=9110327518)
  1559. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(effective_caller_id_name=9110327518)
  1560. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(effective_caller_id_number=9110327518)
  1561. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(calltype=LOCAL)
  1562. Dialplan: sofia/default/9110327518@192.168.1.118 Action set(receiver_accid=2)
  1563. Dialplan: sofia/default/9110327518@192.168.1.118 Action bind_meta_app(2 ab ab execute_extension::att_xfer XML default)
  1564. Dialplan: sofia/default/9110327518@192.168.1.118 Action bridge([leg_timeout=30]user/97265@${domain_name})
  1565. |--- Dialplan: Processing recursive conditions level:1 [97265_recur_1] require-nested=TRUE
  1566. |--- Dialplan: sofia/default/9110327518@192.168.1.118 Regex (PASS) [97265_recur_1] ${cond(${user_data 97265@${domain_name} param vm-enabled} == true ? YES : NO)}(YES) =~ /^YES$/ break=on-false
  1567. |--- Dialplan: sofia/default/9110327518@192.168.1.118 Action export(voicemail_alternate_greet_id=97265)
  1568. |--- Dialplan: sofia/default/9110327518@192.168.1.118 Action answer()
  1569. |--- Dialplan: sofia/default/9110327518@192.168.1.118 Action voicemail(default $${domain_name} 97265)
  1570. 2017-11-06 16:05:33.521567 [DEBUG] switch_core_state_machine.c:286 (sofia/default/9110327518@192.168.1.118) State Change CS_ROUTING -> CS_EXECUTE
  1571. 2017-11-06 16:05:33.521567 [DEBUG] switch_core_state_machine.c:643 (sofia/default/9110327518@192.168.1.118) State ROUTING going to sleep
  1572. 2017-11-06 16:05:33.521567 [DEBUG] switch_core_state_machine.c:584 (sofia/default/9110327518@192.168.1.118) Running State Change CS_EXECUTE (Cur 1 Tot 275)
  1573. 2017-11-06 16:05:33.521567 [DEBUG] switch_channel.c:2251 (sofia/default/9110327518@192.168.1.118) Callstate Change RINGING -> ACTIVE
  1574. 2017-11-06 16:05:33.521567 [DEBUG] switch_core_state_machine.c:650 (sofia/default/9110327518@192.168.1.118) State EXECUTE
  1575. 2017-11-06 16:05:33.521567 [DEBUG] mod_sofia.c:198 sofia/default/9110327518@192.168.1.118 SOFIA EXECUTE
  1576. 2017-11-06 16:05:33.521567 [DEBUG] switch_core_state_machine.c:328 sofia/default/9110327518@192.168.1.118 Standard EXECUTE
  1577. EXECUTE sofia/default/9110327518@192.168.1.118 set(effective_destination_number=97265)
  1578. 2017-11-06 16:05:33.521567 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [effective_destination_number]=[97265]
  1579. EXECUTE sofia/default/9110327518@192.168.1.118 sched_hangup(+6000 normal_clearing)
  1580. 2017-11-06 16:05:33.521567 [DEBUG] switch_scheduler.c:249 Added task 143 switch_ivr_schedule_hangup (d486e76a-fc5b-414d-81a2-0c62494e2a0e) to run at 1509970533
  1581. EXECUTE sofia/default/9110327518@192.168.1.118 set(callstart=2017-11-06 10:35:33)
  1582. 2017-11-06 16:05:33.521567 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [callstart]=[2017-11-06 10:35:33]
  1583. EXECUTE sofia/default/9110327518@192.168.1.118 set(hangup_after_bridge=true)
  1584. 2017-11-06 16:05:33.521567 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [hangup_after_bridge]=[true]
  1585. EXECUTE sofia/default/9110327518@192.168.1.118 set(continue_on_fail=true)
  1586. 2017-11-06 16:05:33.521567 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [continue_on_fail]=[true]
  1587. EXECUTE sofia/default/9110327518@192.168.1.118 set(account_id=4)
  1588. 2017-11-06 16:05:33.521567 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [account_id]=[4]
  1589. EXECUTE sofia/default/9110327518@192.168.1.118 set(parent_id=0)
  1590. 2017-11-06 16:05:33.521567 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [parent_id]=[0]
  1591. EXECUTE sofia/default/9110327518@192.168.1.118 set(entity_id=0)
  1592. 2017-11-06 16:05:33.521567 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [entity_id]=[0]
  1593. EXECUTE sofia/default/9110327518@192.168.1.118 set(call_processed=internal)
  1594. 2017-11-06 16:05:33.521567 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [call_processed]=[internal]
  1595. EXECUTE sofia/default/9110327518@192.168.1.118 set(call_direction=local)
  1596. 2017-11-06 16:05:33.521567 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [call_direction]=[local]
  1597. EXECUTE sofia/default/9110327518@192.168.1.118 set(accountname=ketank)
  1598. 2017-11-06 16:05:33.521567 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [accountname]=[ketank]
  1599. EXECUTE sofia/default/9110327518@192.168.1.118 set(origination_rates=ID:0|CODE:97265|DESTINATION:Local|CONNECTIONCOST:0|INCLUDEDSECONDS:0|COST:0|INC:60|INITIALBLOCK:0|RATEGROUP:2|MARKUP:0|ACCID:4)
  1600. 2017-11-06 16:05:33.521567 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [origination_rates]=[ID:0|CODE:97265|DESTINATION:Local|CONNECTIONCOST:0|INCLUDEDSECONDS:0|COST:0|INC:60|INITIALBLOCK:0|RATEGROUP:2|MARKUP:0|ACCID:4]
  1601. EXECUTE sofia/default/9110327518@192.168.1.118 set(original_caller_id_name=9110327518)
  1602. 2017-11-06 16:05:33.521567 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [original_caller_id_name]=[9110327518]
  1603. EXECUTE sofia/default/9110327518@192.168.1.118 set(original_caller_id_number=9110327518)
  1604. recv 537 bytes from udp/[192.168.1.161]:5060 at 16:05:33.535326:
  1605.    ------------------------------------------------------------------------
  1606.    SIP/2.0 481 Call Leg/Transaction Does Not Exist
  1607.    Via: SIP/2.0/UDP 192.168.1.118;rport=5060;branch=z9hG4bK4g7Fj0Nr6DD1K
  1608.    From: "9110327518" <sip:9110327518@192.168.1.118>;tag=UH5FH4y1DaQKS
  1609.    To: <sip:97264@192.168.1.161:5060>;tag=1987421158
  1610.    Call-ID: 0493884d-3d81-1236-c4b6-000c29f46286
  1611.    CSeq: 114646196 BYE
  1612.    Contact: <sip:97264@192.168.1.161:5060>
  1613.    Supported: replaces, path, timer
  1614.    User-Agent: Grandstream GXP1620 1.0.0.38
  1615.   Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
  1616.    Content-Length: 0
  1617.    
  1618.    ------------------------------------------------------------------------
  1619. 2017-11-06 16:05:33.521567 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [original_caller_id_number]=[9110327518]
  1620. EXECUTE sofia/default/9110327518@192.168.1.118 set(effective_caller_id_name=9110327518)
  1621. 2017-11-06 16:05:33.521567 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [effective_caller_id_name]=[9110327518]
  1622. EXECUTE sofia/default/9110327518@192.168.1.118 set(effective_caller_id_number=9110327518)
  1623. 2017-11-06 16:05:33.521567 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [effective_caller_id_number]=[9110327518]
  1624. EXECUTE sofia/default/9110327518@192.168.1.118 set(calltype=LOCAL)
  1625. 2017-11-06 16:05:33.521567 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [calltype]=[LOCAL]
  1626. EXECUTE sofia/default/9110327518@192.168.1.118 set(receiver_accid=2)
  1627. 2017-11-06 16:05:33.521567 [DEBUG] mod_dptools.c:1530 SET sofia/default/9110327518@192.168.1.118 [receiver_accid]=[2]
  1628. EXECUTE sofia/default/9110327518@192.168.1.118 bind_meta_app(2 ab ab execute_extension::att_xfer XML default)
  1629. 2017-11-06 16:05:33.521567 [ERR] mod_dptools.c:747 Cannot bind execute to multiple legs
  1630. 2017-11-06 16:05:33.521567 [INFO] switch_ivr_async.c:4165 Bound A-Leg: *2 execute_extension::att_xfer XML default
  1631. 2017-11-06 16:05:33.521567 [INFO] switch_ivr_async.c:4173 Bound B-Leg: *2 execute_extension::att_xfer XML default
  1632. EXECUTE sofia/default/9110327518@192.168.1.118 bridge([leg_timeout=30]user/97265@192.168.1.118)
  1633. 2017-11-06 16:05:33.521567 [DEBUG] switch_channel.c:1823 (sofia/default/9110327518@192.168.1.118) Callstate Change ACTIVE -> RING_WAIT
  1634. 2017-11-06 16:05:33.521567 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  1635. 2017-11-06 16:05:33.521567 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables
  1636. send 298 bytes to udp/[192.168.1.161]:5060 at 16:05:33.540192:
  1637.    ------------------------------------------------------------------------
  1638.    SIP/2.0 200 OK
  1639.    Via: SIP/2.0/UDP 192.168.1.161:5060;branch=z9hG4bK1887183128;rport=5060
  1640.    From: <sip:97264@192.168.1.161:5060>;tag=1987421158
  1641.    To: "9110327518" <sip:9110327518@192.168.1.118>;tag=UH5FH4y1DaQKS
  1642.    Call-ID: 0493884d-3d81-1236-c4b6-000c29f46286
  1643.    CSeq: 114646196 BYE
  1644.    Content-Length: 0
  1645.    
  1646.    ------------------------------------------------------------------------
  1647. 2017-11-06 16:05:33.541565 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  1648. 2017-11-06 16:05:33.541565 [NOTICE] switch_channel.c:1104 New Channel sofia/default/97265@192.168.1.156:5062 [16619ecf-c164-4b6c-96a3-e57619545cd2]
  1649. 2017-11-06 16:05:33.541565 [DEBUG] mod_sofia.c:4819 (sofia/default/97265@192.168.1.156:5062) State Change CS_NEW -> CS_INIT
  1650. 2017-11-06 16:05:33.541565 [DEBUG] switch_ivr_originate.c:2963 sofia/default/97265@192.168.1.156:5062 Setting leg timeout to 30
  1651. 2017-11-06 16:05:33.541565 [DEBUG] switch_core_state_machine.c:584 (sofia/default/97265@192.168.1.156:5062) Running State Change CS_INIT (Cur 2 Tot 276)
  1652. 2017-11-06 16:05:33.541565 [DEBUG] switch_core_state_machine.c:627 (sofia/default/97265@192.168.1.156:5062) State INIT
  1653. 2017-11-06 16:05:33.541565 [DEBUG] mod_sofia.c:90 sofia/default/97265@192.168.1.156:5062 SOFIA INIT
  1654. 2017-11-06 16:05:33.541565 [DEBUG] sofia_glue.c:1295 sofia/default/97265@192.168.1.156:5062 sending invite version: 1.6.18  64bit
  1655. Local SDP:
  1656. v=0
  1657. o=FreeSWITCH 1509937239 1509937240 IN IP4 192.168.1.118
  1658. s=FreeSWITCH
  1659. c=IN IP4 192.168.1.118
  1660. t=0 0
  1661. m=audio 27294 RTP/AVP 8 101
  1662. a=rtpmap:8 PCMA/8000
  1663. a=rtpmap:101 telephone-event/8000
  1664. a=fmtp:101 0-16
  1665. a=ptime:20
  1666. a=sendrecv
  1667.  
  1668. send 1122 bytes to udp/[192.168.1.156]:5062 at 16:05:33.548929:
  1669.    ------------------------------------------------------------------------
  1670.    INVITE sip:97265@192.168.1.156:5062 SIP/2.0
  1671.    Via: SIP/2.0/UDP 192.168.1.118;rport;branch=z9hG4bK5S08KU6U3p3KF
  1672.    Max-Forwards: 68
  1673.    From: "9110327518" <sip:9110327518@192.168.1.118>;tag=2gpXX1mtS8jpa
  1674.    To: <sip:97265@192.168.1.156:5062>
  1675.    Call-ID: 0f544714-3d81-1236-c4b6-000c29f46286
  1676.    CSeq: 114646202 INVITE
  1677.    Contact: <sip:mod_sofia@192.168.1.118:5060>
  1678.    User-Agent: Local
  1679.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1680.    Supported: path, replaces
  1681.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  1682.    Referred-By: <sip:97264@192.168.1.118>
  1683.    Content-Type: application/sdp
  1684.    Content-Disposition: session
  1685.    Content-Length: 222
  1686.    Remote-Party-ID: "9110327518" <sip:9110327518@192.168.1.118>;party=calling;screen=yes;privacy=off
  1687.    
  1688.    v=0
  1689.    o=FreeSWITCH 1509937239 1509937240 IN IP4 192.168.1.118
  1690.    s=FreeSWITCH
  1691.    c=IN IP4 192.168.1.118
  1692.    t=0 0
  1693.    m=audio 27294 RTP/AVP 8 101
  1694.    a=rtpmap:8 PCMA/8000
  1695.    a=rtpmap:101 telephone-event/8000
  1696.    a=fmtp:101 0-16
  1697.    a=ptime:20
  1698.    ------------------------------------------------------------------------
  1699. 2017-11-06 16:05:33.541565 [DEBUG] switch_core_state_machine.c:40 sofia/default/97265@192.168.1.156:5062 Standard INIT
  1700. 2017-11-06 16:05:33.541565 [DEBUG] switch_core_state_machine.c:48 (sofia/default/97265@192.168.1.156:5062) State Change CS_INIT -> CS_ROUTING
  1701. 2017-11-06 16:05:33.541565 [DEBUG] switch_core_state_machine.c:627 (sofia/default/97265@192.168.1.156:5062) State INIT going to sleep
  1702. 2017-11-06 16:05:33.541565 [DEBUG] switch_core_state_machine.c:584 (sofia/default/97265@192.168.1.156:5062) Running State Change CS_ROUTING (Cur 2 Tot 276)
  1703. 2017-11-06 16:05:33.541565 [DEBUG] sofia.c:7048 Channel sofia/default/97265@192.168.1.156:5062 entering state [calling][0]
  1704. 2017-11-06 16:05:33.541565 [DEBUG] switch_core_state_machine.c:643 (sofia/default/97265@192.168.1.156:5062) State ROUTING
  1705. 2017-11-06 16:05:33.541565 [DEBUG] mod_sofia.c:143 sofia/default/97265@192.168.1.156:5062 SOFIA ROUTING
  1706. 2017-11-06 16:05:33.541565 [DEBUG] switch_ivr_originate.c:67 (sofia/default/97265@192.168.1.156:5062) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  1707. 2017-11-06 16:05:33.541565 [DEBUG] switch_core_state_machine.c:643 (sofia/default/97265@192.168.1.156:5062) State ROUTING going to sleep
  1708. 2017-11-06 16:05:33.541565 [DEBUG] switch_core_state_machine.c:584 (sofia/default/97265@192.168.1.156:5062) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 276)
  1709. 2017-11-06 16:05:33.541565 [DEBUG] switch_core_state_machine.c:662 (sofia/default/97265@192.168.1.156:5062) State CONSUME_MEDIA
  1710. 2017-11-06 16:05:33.541565 [DEBUG] switch_core_state_machine.c:662 (sofia/default/97265@192.168.1.156:5062) State CONSUME_MEDIA going to sleep
  1711. recv 324 bytes from udp/[192.168.1.156]:5062 at 16:05:33.628351:
  1712.    ------------------------------------------------------------------------
  1713.    SIP/2.0 100 Trying
  1714.    Via: SIP/2.0/UDP 192.168.1.118;rport;branch=z9hG4bK5S08KU6U3p3KF
  1715.    From: "9110327518" <sip:9110327518@192.168.1.118>;tag=2gpXX1mtS8jpa
  1716.    To: <sip:97265@192.168.1.156:5062>
  1717.    Call-ID: 0f544714-3d81-1236-c4b6-000c29f46286
  1718.    CSeq: 114646202 INVITE
  1719.    User-Agent: Yealink SIP-T21P 34.72.0.75
  1720.    Content-Length: 0
  1721.    
  1722.    ------------------------------------------------------------------------
  1723. recv 551 bytes from udp/[192.168.1.156]:5062 at 16:05:33.769923:
  1724.    ------------------------------------------------------------------------
  1725.    SIP/2.0 180 Ringing
  1726.    Via: SIP/2.0/UDP 192.168.1.118;rport;branch=z9hG4bK5S08KU6U3p3KF
  1727.    From: "9110327518" <sip:9110327518@192.168.1.118>;tag=2gpXX1mtS8jpa
  1728.    To: <sip:97265@192.168.1.156:5062>;tag=1787539068
  1729.    Call-ID: 0f544714-3d81-1236-c4b6-000c29f46286
  1730.    CSeq: 114646202 INVITE
  1731.    Contact: <sip:97265@192.168.1.156:5062>
  1732.   Allow: INVITE, INFO, PRACK, ACK, BYE, CANCEL, OPTIONS, NOTIFY, REGISTER, SUBSCRIBE, REFER, PUBLISH, UPDATE, MESSAGE
  1733.    User-Agent: Yealink SIP-T21P 34.72.0.75
  1734.    Allow-Events: talk,hold,conference,refer,check-sync
  1735.    Content-Length: 0
  1736.    
  1737.    ------------------------------------------------------------------------
  1738. 2017-11-06 16:05:33.821576 [DEBUG] sofia.c:7048 Channel sofia/default/97265@192.168.1.156:5062 entering state [proceeding][180]
  1739. 2017-11-06 16:05:33.821576 [NOTICE] sofia.c:7156 Ring-Ready sofia/default/97265@192.168.1.156:5062!
  1740. 2017-11-06 16:05:33.821576 [DEBUG] switch_channel.c:3346 (sofia/default/97265@192.168.1.156:5062) Callstate Change DOWN -> RINGING
  1741. freeswitch@freeswitch>
  1742. freeswitch@freeswitch>
  1743. freeswitch@freeswitch>
  1744. freeswitch@freeswitch>
  1745. freeswitch@freeswitch>
  1746. freeswitch@freeswitch>
  1747. freeswitch@freeswitch>
  1748. freeswitch@freeswitch>
  1749. freeswitch@freeswitch>
  1750. freeswitch@freeswitch>
  1751. freeswitch@freeswitch>
  1752. freeswitch@freeswitch>
  1753. freeswitch@freeswitch>
  1754. freeswitch@freeswitch>
  1755. freeswitch@freeswitch>
  1756. send 605 bytes to udp/[1.1.1.1]:5060 at 16:05:37.166959:
  1757.    ------------------------------------------------------------------------
  1758.    OPTIONS sip:1.1.1.1;transport=udp SIP/2.0
  1759.    Via: SIP/2.0/UDP 192.168.1.118;rport;branch=z9hG4bK62S1NpQZ0ZS6a
  1760.    Max-Forwards: 70
  1761.    From: <sip:1.1.1.1>;tag=3SFpZv5XpH98N
  1762.    To: <sip:1.1.1.1>
  1763.    Call-ID: 117c5946-3d81-1236-c4b6-000c29f46286
  1764.    CSeq: 114465672 OPTIONS
  1765.    User-Agent: Local
  1766.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1767.    Supported: path, replaces
  1768.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  1769.    Content-Length: 0
  1770.    
  1771.    ------------------------------------------------------------------------
  1772. recv 739 bytes from udp/[192.168.1.156]:5062 at 16:05:37.915935:
  1773.    ------------------------------------------------------------------------
  1774.    SIP/2.0 200 OK
  1775.    Via: SIP/2.0/UDP 192.168.1.118;rport;branch=z9hG4bK5S08KU6U3p3KF
  1776.    From: "9110327518" <sip:9110327518@192.168.1.118>;tag=2gpXX1mtS8jpa
  1777.    To: <sip:97265@192.168.1.156:5062>;tag=1787539068
  1778.    Call-ID: 0f544714-3d81-1236-c4b6-000c29f46286
  1779.    CSeq: 114646202 INVITE
  1780.    Contact: <sip:97265@192.168.1.156:5062>
  1781.    Content-Type: application/sdp
  1782.   Allow: INVITE, INFO, PRACK, ACK, BYE, CANCEL, OPTIONS, NOTIFY, REGISTER, SUBSCRIBE, REFER, PUBLISH, UPDATE, MESSAGE
  1783.    User-Agent: Yealink SIP-T21P 34.72.0.75
  1784.    Content-Length: 213
  1785.    
  1786.    v=0
  1787.    o=- 20007 20007 IN IP4 192.168.1.156
  1788.    s=SDP data
  1789.    c=IN IP4 192.168.1.156
  1790.    t=0 0
  1791.    m=audio 11794 RTP/AVP 8 101
  1792.    a=rtpmap:8 PCMA/8000
  1793.    a=sendrecv
  1794.    a=ptime:20
  1795.    a=fmtp:101 0-15
  1796.    a=rtpmap:101 telephone-event/8000
  1797.    ------------------------------------------------------------------------
  1798. 2017-11-06 16:05:37.961621 [DEBUG] sofia.c:7048 Channel sofia/default/97265@192.168.1.156:5062 entering state [completing][200]
  1799. 2017-11-06 16:05:37.961621 [DEBUG] sofia.c:7058 Remote SDP:
  1800. v=0
  1801. o=- 20007 20007 IN IP4 192.168.1.156
  1802. s=SDP data
  1803. c=IN IP4 192.168.1.156
  1804. t=0 0
  1805. m=audio 11794 RTP/AVP 8 101
  1806. a=rtpmap:8 PCMA/8000
  1807. a=rtpmap:101 telephone-event/8000
  1808. a=fmtp:101 0-15
  1809. a=ptime:20
  1810.  
  1811. send 380 bytes to udp/[192.168.1.156]:5062 at 16:05:37.973960:
  1812.    ------------------------------------------------------------------------
  1813.    ACK sip:97265@192.168.1.156:5062 SIP/2.0
  1814.    Via: SIP/2.0/UDP 192.168.1.118;rport;branch=z9hG4bK7BKtQH82X8FSp
  1815.    Max-Forwards: 70
  1816.    From: "9110327518" <sip:9110327518@192.168.1.118>;tag=2gpXX1mtS8jpa
  1817.    To: <sip:97265@192.168.1.156:5062>;tag=1787539068
  1818.    Call-ID: 0f544714-3d81-1236-c4b6-000c29f46286
  1819.    CSeq: 114646202 ACK
  1820.    Contact: <sip:mod_sofia@192.168.1.118:5060>
  1821.    Content-Length: 0
  1822.    
  1823.    ------------------------------------------------------------------------
  1824. 2017-11-06 16:05:37.961621 [DEBUG] sofia.c:7048 Channel sofia/default/97265@192.168.1.156:5062 entering state [ready][200]
  1825. 2017-11-06 16:05:37.961621 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1826. 2017-11-06 16:05:37.961621 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  1827. 2017-11-06 16:05:37.961621 [DEBUG] switch_core_media.c:4361 Set telephone-event payload to 101@8000
  1828. 2017-11-06 16:05:37.961621 [DEBUG] switch_core_media.c:3057 Set Codec sofia/default/97265@192.168.1.156:5062 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  1829. 2017-11-06 16:05:37.961621 [DEBUG] switch_core_codec.c:111 sofia/default/97265@192.168.1.156:5062 Original read codec set to PCMA:8
  1830. 2017-11-06 16:05:37.961621 [DEBUG] switch_core_media.c:4704 Set telephone-event payload to 101@8000
  1831. 2017-11-06 16:05:37.961621 [DEBUG] switch_core_media.c:4763 sofia/default/97265@192.168.1.156:5062 Set 2833 dtmf send payload to 101 recv payload to 101
  1832. 2017-11-06 16:05:37.961621 [DEBUG] switch_core_media.c:6874 AUDIO RTP [sofia/default/97265@192.168.1.156:5062] 192.168.1.118 port 27294 -> 192.168.1.156 port 11794 codec: 8 ms: 20
  1833. 2017-11-06 16:05:37.961621 [DEBUG] switch_rtp.c:4108 Starting timer [soft] 160 bytes per 20ms
  1834. 2017-11-06 16:05:37.961621 [DEBUG] switch_core_media.c:7175 sofia/default/97265@192.168.1.156:5062 Set 2833 dtmf send payload to 101
  1835. 2017-11-06 16:05:37.961621 [DEBUG] switch_core_media.c:7182 sofia/default/97265@192.168.1.156:5062 Set 2833 dtmf receive payload to 101
  1836. 2017-11-06 16:05:37.961621 [DEBUG] switch_core_media.c:7205 sofia/default/97265@192.168.1.156:5062 Set rtp dtmf delay to 40
  1837. 2017-11-06 16:05:37.961621 [NOTICE] sofia.c:8182 Channel [sofia/default/97265@192.168.1.156:5062] has been answered
  1838. 2017-11-06 16:05:37.961621 [DEBUG] switch_channel.c:3773 (sofia/default/97265@192.168.1.156:5062) Callstate Change RINGING -> ACTIVE
  1839. 2017-11-06 16:05:37.981565 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/default/97265@192.168.1.156:5062]
  1840. 2017-11-06 16:05:37.981565 [DEBUG] switch_channel.c:2047 (sofia/default/9110327518@192.168.1.118) Callstate Change RING_WAIT -> ACTIVE
  1841. 2017-11-06 16:05:37.981565 [DEBUG] switch_ivr_originate.c:2963 sofia/default/97265@192.168.1.156:5062 Setting leg timeout to 30
  1842. 2017-11-06 16:05:37.981565 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/default/97265@192.168.1.156:5062]
  1843. 2017-11-06 16:05:37.981565 [DEBUG] switch_ivr_bridge.c:1614 (sofia/default/97265@192.168.1.156:5062) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  1844. 2017-11-06 16:05:37.981565 [DEBUG] switch_core_state_machine.c:584 (sofia/default/97265@192.168.1.156:5062) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 276)
  1845. 2017-11-06 16:05:37.981565 [DEBUG] switch_core_state_machine.c:653 (sofia/default/97265@192.168.1.156:5062) State EXCHANGE_MEDIA
  1846. 2017-11-06 16:05:37.981565 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
  1847. send 893 bytes to udp/[192.168.1.156]:5062 at 16:05:38.019360:
  1848.    ------------------------------------------------------------------------
  1849.    UPDATE sip:97265@192.168.1.156:5062 SIP/2.0
  1850.    Via: SIP/2.0/UDP 192.168.1.118;rport;branch=z9hG4bK8mcKScS6tH6Bj
  1851.    Max-Forwards: 70
  1852.    From: "9110327518" <sip:9110327518@192.168.1.118>;tag=2gpXX1mtS8jpa
  1853.    To: <sip:97265@192.168.1.156:5062>;tag=1787539068
  1854.    Call-ID: 0f544714-3d81-1236-c4b6-000c29f46286
  1855.    CSeq: 114646203 UPDATE
  1856.    Contact: <sip:mod_sofia@192.168.1.118:5060>
  1857.    User-Agent: Local
  1858.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1859.    Supported: path, replaces
  1860.    Content-Type: application/sdp
  1861.    Content-Disposition: session
  1862.    Content-Length: 222
  1863.    P-Asserted-Identity: "9110327518" <sip:9110327518@192.168.1.118>
  1864.    
  1865.    v=0
  1866.    o=FreeSWITCH 1509937239 1509937240 IN IP4 192.168.1.118
  1867.    s=FreeSWITCH
  1868.    c=IN IP4 192.168.1.118
  1869.    t=0 0
  1870.    m=audio 27294 RTP/AVP 8 101
  1871.    a=rtpmap:8 PCMA/8000
  1872.    a=rtpmap:101 telephone-event/8000
  1873.    a=fmtp:101 0-16
  1874.    a=ptime:20
  1875.    ------------------------------------------------------------------------
  1876. 2017-11-06 16:05:38.001569 [DEBUG] sofia.c:7048 Channel sofia/default/97265@192.168.1.156:5062 entering state [calling][0]
  1877. 2017-11-06 16:05:38.041570 [DEBUG] switch_rtp.c:7254 Correct audio ip/port confirmed.
  1878. send 605 bytes to udp/[1.1.1.1]:5060 at 16:05:38.167128:
  1879.    ------------------------------------------------------------------------
  1880.    OPTIONS sip:1.1.1.1;transport=udp SIP/2.0
  1881.    Via: SIP/2.0/UDP 192.168.1.118;rport;branch=z9hG4bK62S1NpQZ0ZS6a
  1882.    Max-Forwards: 70
  1883.    From: <sip:1.1.1.1>;tag=3SFpZv5XpH98N
  1884.    To: <sip:1.1.1.1>
  1885.    Call-ID: 117c5946-3d81-1236-c4b6-000c29f46286
  1886.    CSeq: 114465672 OPTIONS
  1887.    User-Agent: Local
  1888.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1889.    Supported: path, replaces
  1890.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  1891.    Content-Length: 0
  1892.    
  1893.    ------------------------------------------------------------------------
  1894. recv 739 bytes from udp/[192.168.1.156]:5062 at 16:05:38.460661:
  1895.    ------------------------------------------------------------------------
  1896.    SIP/2.0 200 OK
  1897.    Via: SIP/2.0/UDP 192.168.1.118;rport;branch=z9hG4bK8mcKScS6tH6Bj
  1898.    From: "9110327518" <sip:9110327518@192.168.1.118>;tag=2gpXX1mtS8jpa
  1899.    To: <sip:97265@192.168.1.156:5062>;tag=1787539068
  1900.    Call-ID: 0f544714-3d81-1236-c4b6-000c29f46286
  1901.    CSeq: 114646203 UPDATE
  1902.    Contact: <sip:97265@192.168.1.156:5062>
  1903.    Content-Type: application/sdp
  1904.   Allow: INVITE, INFO, PRACK, ACK, BYE, CANCEL, OPTIONS, NOTIFY, REGISTER, SUBSCRIBE, REFER, PUBLISH, UPDATE, MESSAGE
  1905.    User-Agent: Yealink SIP-T21P 34.72.0.75
  1906.    Content-Length: 213
  1907.    
  1908.    v=0
  1909.    o=- 20007 20008 IN IP4 192.168.1.156
  1910.    s=SDP data
  1911.    c=IN IP4 192.168.1.156
  1912.    t=0 0
  1913.    m=audio 11794 RTP/AVP 8 101
  1914.    a=rtpmap:8 PCMA/8000
  1915.    a=sendrecv
  1916.    a=ptime:20
  1917.    a=fmtp:101 0-15
  1918.    a=rtpmap:101 telephone-event/8000
  1919.    ------------------------------------------------------------------------
  1920. 2017-11-06 16:05:38.461571 [DEBUG] sofia.c:7048 Channel sofia/default/97265@192.168.1.156:5062 entering state [ready][200]
  1921. 2017-11-06 16:05:38.461571 [DEBUG] sofia.c:7058 Remote SDP:
  1922. v=0
  1923. o=- 20007 20008 IN IP4 192.168.1.156
  1924. s=SDP data
  1925. c=IN IP4 192.168.1.156
  1926. t=0 0
  1927. m=audio 11794 RTP/AVP 8 101
  1928. a=rtpmap:8 PCMA/8000
  1929. a=rtpmap:101 telephone-event/8000
  1930. a=fmtp:101 0-15
  1931. a=ptime:20
  1932.  
  1933. 2017-11-06 16:05:38.481571 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1934. 2017-11-06 16:05:38.481571 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  1935. 2017-11-06 16:05:38.481571 [DEBUG] switch_core_media.c:4361 Set telephone-event payload to 101@8000
  1936. 2017-11-06 16:05:38.481571 [DEBUG] switch_core_media.c:4704 Set telephone-event payload to 101@8000
  1937. 2017-11-06 16:05:38.481571 [DEBUG] switch_core_media.c:4763 sofia/default/97265@192.168.1.156:5062 Set 2833 dtmf send payload to 101 recv payload to 101
  1938. 2017-11-06 16:05:38.481571 [DEBUG] sofia.c:8025 Processing updated SDP
  1939. 2017-11-06 16:05:38.481571 [DEBUG] switch_core_media.c:6857 Audio params are unchanged for sofia/default/97265@192.168.1.156:5062.
  1940. recv 336 bytes from udp/[192.168.1.119]:6060 at 16:05:38.826819:
  1941.    ------------------------------------------------------------------------
  1942.    OPTIONS sip:192.168.1.118:5060 SIP/2.0
  1943.    Via: SIP/2.0/UDP 117.247.91.162:6060;branch=z9hG4bK1867.ead25d91.0
  1944.    To: sip:192.168.1.118:5060
  1945.    From: <sip:ping@192.168.1.119:5060>;tag=709be372f8df6ad02e74147e04c36617-95be
  1946.    CSeq: 14 OPTIONS
  1947.    Call-ID: 19f406c45d424e8e-10320@192.168.1.119
  1948.    Max-Forwards: 70
  1949.    Content-Length: 0
  1950.    User-Agent: SBC
  1951.    
  1952.    ------------------------------------------------------------------------
  1953. send 702 bytes to udp/[192.168.1.119]:6060 at 16:05:38.827299:
  1954.    ------------------------------------------------------------------------
  1955.    SIP/2.0 200 OK
  1956.    Via: SIP/2.0/UDP 117.247.91.162:6060;branch=z9hG4bK1867.ead25d91.0;received=192.168.1.119
  1957.    From: <sip:ping@192.168.1.119:5060>;tag=709be372f8df6ad02e74147e04c36617-95be
  1958.    To: <sip:192.168.1.118:5060>;tag=428e1Qp1KtZUH
  1959.    Call-ID: 19f406c45d424e8e-10320@192.168.1.119
  1960.    CSeq: 14 OPTIONS
  1961.    Contact: <sip:192.168.1.118>
  1962.    User-Agent: Local
  1963.    Accept: application/sdp
  1964.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1965.    Supported: path, replaces
  1966.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  1967.    Content-Length: 0
  1968.    
  1969.    ------------------------------------------------------------------------
  1970. 2017-11-06 16:05:39.381622 [WARNING] sofia.c:6098 Ping failed KK with code 503 - count 1/0/1, state DOWN
  1971. freeswitch@freeswitch>
  1972. freeswitch@freeswitch>
  1973. freeswitch@freeswitch>
  1974. freeswitch@freeswitch>
  1975. freeswitch@freeswitch>
  1976. recv 336 bytes from udp/[192.168.1.119]:6060 at 16:05:48.736548:
  1977.    ------------------------------------------------------------------------
  1978.    OPTIONS sip:192.168.1.118:5060 SIP/2.0
  1979.    Via: SIP/2.0/UDP 117.247.91.162:6060;branch=z9hG4bKf348.5e597242.0
  1980.    To: sip:192.168.1.118:5060
  1981.    From: <sip:ping@192.168.1.119:5060>;tag=709be372f8df6ad02e74147e04c36617-264b
  1982.    CSeq: 14 OPTIONS
  1983.    Call-ID: 19f406c45d424eab-10323@192.168.1.119
  1984.    Max-Forwards: 70
  1985.    Content-Length: 0
  1986.    User-Agent: SBC
  1987.    
  1988.    ------------------------------------------------------------------------
  1989. send 702 bytes to udp/[192.168.1.119]:6060 at 16:05:48.737245:
  1990.    ------------------------------------------------------------------------
  1991.    SIP/2.0 200 OK
  1992.    Via: SIP/2.0/UDP 117.247.91.162:6060;branch=z9hG4bKf348.5e597242.0;received=192.168.1.119
  1993.    From: <sip:ping@192.168.1.119:5060>;tag=709be372f8df6ad02e74147e04c36617-264b
  1994.    To: <sip:192.168.1.118:5060>;tag=6mU04Dr8Dcc1r
  1995.    Call-ID: 19f406c45d424eab-10323@192.168.1.119
  1996.    CSeq: 14 OPTIONS
  1997.    Contact: <sip:192.168.1.118>
  1998.    User-Agent: Local
  1999.    Accept: application/sdp
  2000.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  2001.    Supported: path, replaces
  2002.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  2003.    Content-Length: 0
  2004.    
  2005.    ------------------------------------------------------------------------
  2006. send 605 bytes to udp/[1.1.1.1]:5060 at 16:05:49.195922:
  2007.    ------------------------------------------------------------------------
  2008.    OPTIONS sip:1.1.1.1;transport=udp SIP/2.0
  2009.    Via: SIP/2.0/UDP 192.168.1.118;rport;branch=z9hG4bK9X5BU799QtvyD
  2010.    Max-Forwards: 70
  2011.    From: <sip:1.1.1.1>;tag=7XmS688BBN2Km
  2012.    To: <sip:1.1.1.1>
  2013.    Call-ID: 18a7d274-3d81-1236-c4b6-000c29f46286
  2014.    CSeq: 114465673 OPTIONS
  2015.    User-Agent: Local
  2016.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  2017.    Supported: path, replaces
  2018.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  2019.    Content-Length: 0
  2020.    
  2021.    ------------------------------------------------------------------------
  2022. 2017-11-06 16:05:50.001567 [WARNING] sofia.c:6098 Ping failed KK with code 503 - count 1/0/1, state DOWN
  2023. freeswitch@freeswitch> /exit
  2024.  
  2025. root@astpptest1:/home/inextrix#
  2026.