API tools faq
paste
Advertisement
highsea

局域网手机1001通话电脑1000

highsea
Mar 1st, 2018
3,105
0
Never
Add comment
Not a member of Pastebin yet? Sign Up, it unlocks many cool features!
FreeSWITCH 113.62 KB | None | 0 0
raw download clone embed print report
  1. recv 1138 bytes from udp/[192.168.199.147]:50452 at 23:18:58.814180:
  2.    ------------------------------------------------------------------------
  3.    INVITE sip:1000@192.168.199.209 SIP/2.0
  4.    Via: SIP/2.0/UDP 192.168.199.147:50452;rport;branch=z9hG4bKPjZXNvascPfYDmhdOPhWVWSHpnG31mfrSk
  5.    Max-Forwards: 70
  6.    From: sip:1001@192.168.199.209;tag=ncjBVBvrLoZYKZG9hB-Dw9pc-zMQ6-X0
  7.    To: sip:1000@192.168.199.209
  8.    Contact: <sip:1001@192.168.199.147:50452;ob>
  9.    Call-ID: TLCkh5yLFXegYOSvMqEDr6xV1AxFjRNm
  10.    CSeq: 6682 INVITE
  11.    Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
  12.    Supported: replaces, 100rel, timer, norefersub
  13.    Session-Expires: 1800
  14.    Min-SE: 128
  15.    User-Agent: Siphon PjSip v2.0.0-beta/arm-apple-darwin9
  16.    Content-Type: application/sdp
  17.    Content-Length:   489
  18.    
  19.    v=0
  20.    o=- 3728874429 3728874429 IN IP4 192.168.199.147
  21.    s=pjmedia
  22.    c=IN IP4 192.168.199.147
  23.    t=0 0
  24.    a=X-nat:0
  25.    m=audio 40002 RTP/AVP 98 97 99 104 18 3 0 8 96
  26.    c=IN IP4 192.168.199.147
  27.    a=rtcp:40003 IN IP4 192.168.199.147
  28.    a=sendrecv
  29.    a=rtpmap:98 speex/16000
  30.    a=rtpmap:97 speex/8000
  31.    a=rtpmap:99 speex/32000
  32.    a=rtpmap:104 iLBC/8000
  33.    a=fmtp:104 mode=30
  34.    a=rtpmap:18 G729/8000
  35.    a=rtpmap:3 GSM/8000
  36.    a=rtpmap:0 PCMU/8000
  37.    a=rtpmap:8 PCMA/8000
  38.    a=rtpmap:96 telephone-event/8000
  39.    a=fmtp:96 0-15
  40.    ------------------------------------------------------------------------
  41. send 350 bytes to udp/[192.168.199.147]:50452 at 23:18:58.814740:
  42.    ------------------------------------------------------------------------
  43.    SIP/2.0 100 Trying
  44.    Via: SIP/2.0/UDP 192.168.199.147:50452;rport=50452;branch=z9hG4bKPjZXNvascPfYDmhdOPhWVWSHpnG31mfrSk
  45.    From: sip:1001@192.168.199.209;tag=ncjBVBvrLoZYKZG9hB-Dw9pc-zMQ6-X0
  46.    To: sip:1000@192.168.199.209
  47.    Call-ID: TLCkh5yLFXegYOSvMqEDr6xV1AxFjRNm
  48.    CSeq: 6682 INVITE
  49.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  50.    Content-Length: 0
  51.    
  52.    ------------------------------------------------------------------------
  53. 2018-02-28 23:18:58.797838 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1001@192.168.199.209 [b2fb430e-1c9a-11e8-b916-1f706e5b1202]
  54. 2018-02-28 23:18:58.797838 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1001@192.168.199.209) Running State Change CS_NEW (Cur 1 Tot 36)
  55. 2018-02-28 23:18:58.797838 [DEBUG] sofia.c:9834 sofia/internal/1001@192.168.199.209 receiving invite from 192.168.199.147:50452 version: 1.6.17  64bit
  56. 2018-02-28 23:18:58.797838 [DEBUG] sofia.c:10005 IP 192.168.199.147 Rejected by acl "domains". Falling back to Digest auth.
  57. 2018-02-28 23:18:58.797838 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/1001@192.168.199.209) State NEW
  58. send 858 bytes to udp/[192.168.199.147]:50452 at 23:18:58.816723:
  59.    ------------------------------------------------------------------------
  60.    SIP/2.0 407 Proxy Authentication Required
  61.    Via: SIP/2.0/UDP 192.168.199.147:50452;rport=50452;branch=z9hG4bKPjZXNvascPfYDmhdOPhWVWSHpnG31mfrSk
  62.    From: sip:1001@192.168.199.209;tag=ncjBVBvrLoZYKZG9hB-Dw9pc-zMQ6-X0
  63.    To: <sip:1000@192.168.199.209>;tag=4DeBjKjt18yUF
  64.    Call-ID: TLCkh5yLFXegYOSvMqEDr6xV1AxFjRNm
  65.    CSeq: 6682 INVITE
  66.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  67.    Accept: application/sdp
  68.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  69.    Supported: timer, path, replaces
  70.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  71.    Proxy-Authenticate: Digest realm="192.168.199.209", nonce="b2fb59d4-1c9a-11e8-b917-1f706e5b1202", algorithm=MD5, qop="auth"
  72.    Content-Length: 0
  73.    
  74.    ------------------------------------------------------------------------
  75. 2018-02-28 23:18:58.797838 [DEBUG] sofia.c:2334 detaching session b2fb430e-1c9a-11e8-b916-1f706e5b1202
  76. recv 349 bytes from udp/[192.168.199.147]:50452 at 23:18:58.908549:
  77.    ------------------------------------------------------------------------
  78.    ACK sip:1000@192.168.199.209 SIP/2.0
  79.    Via: SIP/2.0/UDP 192.168.199.147:50452;rport;branch=z9hG4bKPjZXNvascPfYDmhdOPhWVWSHpnG31mfrSk
  80.    Max-Forwards: 70
  81.    From: sip:1001@192.168.199.209;tag=ncjBVBvrLoZYKZG9hB-Dw9pc-zMQ6-X0
  82.    To: sip:1000@192.168.199.209;tag=4DeBjKjt18yUF
  83.    Call-ID: TLCkh5yLFXegYOSvMqEDr6xV1AxFjRNm
  84.    CSeq: 6682 ACK
  85.    Content-Length:  0
  86.    
  87.    ------------------------------------------------------------------------
  88. recv 1412 bytes from udp/[192.168.199.147]:50452 at 23:18:58.909156:
  89.    ------------------------------------------------------------------------
  90.    INVITE sip:1000@192.168.199.209 SIP/2.0
  91.    Via: SIP/2.0/UDP 192.168.199.147:50452;rport;branch=z9hG4bKPjWwb7EBSODC0Uv9QMh4iITNWOfTYFdl4A
  92.    Max-Forwards: 70
  93.    From: sip:1001@192.168.199.209;tag=ncjBVBvrLoZYKZG9hB-Dw9pc-zMQ6-X0
  94.    To: sip:1000@192.168.199.209
  95.    Contact: <sip:1001@192.168.199.147:50452;ob>
  96.    Call-ID: TLCkh5yLFXegYOSvMqEDr6xV1AxFjRNm
  97.    CSeq: 6683 INVITE
  98.    Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
  99.    Supported: replaces, 100rel, timer, norefersub
  100.    Session-Expires: 1800
  101.    Min-SE: 128
  102.    User-Agent: Siphon PjSip v2.0.0-beta/arm-apple-darwin9
  103.    Proxy-Authorization: Digest username="1001", realm="192.168.199.209", nonce="b2fb59d4-1c9a-11e8-b917-1f706e5b1202", uri="sip:1000@192.168.199.209", response="61fc6ed9445f93a73c97f04ead0da742", algorithm=MD5, cnonce="Z2pp446FWAM-Eu5sm.p5pv8p306L6.xQ", qop=auth, nc=00000001
  104.    Content-Type: application/sdp
  105.    Content-Length:   489
  106.    
  107.    v=0
  108.    o=- 3728874429 3728874429 IN IP4 192.168.199.147
  109.    s=pjmedia
  110.    c=IN IP4 192.168.199.147
  111.    t=0 0
  112.    a=X-nat:0
  113.    m=audio 40002 RTP/AVP 98 97 99 104 18 3 0 8 96
  114.    c=IN IP4 192.168.199.147
  115.    a=rtcp:40003 IN IP4 192.168.199.147
  116.    a=sendrecv
  117.    a=rtpmap:98 speex/16000
  118.    a=rtpmap:97 speex/8000
  119.    a=rtpmap:99 speex/32000
  120.    a=rtpmap:104 iLBC/8000
  121.    a=fmtp:104 mode=30
  122.    a=rtpmap:18 G729/8000
  123.    a=rtpmap:3 GSM/8000
  124.    a=rtpmap:0 PCMU/8000
  125.    a=rtpmap:8 PCMA/8000
  126.    a=rtpmap:96 telephone-event/8000
  127.    a=fmtp:96 0-15
  128.    ------------------------------------------------------------------------
  129. send 350 bytes to udp/[192.168.199.147]:50452 at 23:18:58.909584:
  130.    ------------------------------------------------------------------------
  131.    SIP/2.0 100 Trying
  132.    Via: SIP/2.0/UDP 192.168.199.147:50452;rport=50452;branch=z9hG4bKPjWwb7EBSODC0Uv9QMh4iITNWOfTYFdl4A
  133.    From: sip:1001@192.168.199.209;tag=ncjBVBvrLoZYKZG9hB-Dw9pc-zMQ6-X0
  134.    To: sip:1000@192.168.199.209
  135.    Call-ID: TLCkh5yLFXegYOSvMqEDr6xV1AxFjRNm
  136.    CSeq: 6683 INVITE
  137.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  138.    Content-Length: 0
  139.    
  140.    ------------------------------------------------------------------------
  141. 2018-02-28 23:18:58.897385 [DEBUG] sofia.c:2442 Re-attaching to session b2fb430e-1c9a-11e8-b916-1f706e5b1202
  142. 2018-02-28 23:18:58.917835 [DEBUG] sofia.c:9834 sofia/internal/1001@192.168.199.209 receiving invite from 192.168.199.147:50452 version: 1.6.17  64bit
  143. 2018-02-28 23:18:58.917835 [DEBUG] sofia.c:10005 IP 192.168.199.147 Rejected by acl "domains". Falling back to Digest auth.
  144. 2018-02-28 23:18:58.917835 [DEBUG] sofia.c:7048 Channel sofia/internal/1001@192.168.199.209 entering state [received][100]
  145. 2018-02-28 23:18:58.917835 [DEBUG] sofia.c:7058 Remote SDP:
  146. v=0
  147. o=- 3728874429 3728874429 IN IP4 192.168.199.147
  148. s=pjmedia
  149. c=IN IP4 192.168.199.147
  150. t=0 0
  151. a=X-nat:0
  152. m=audio 40002 RTP/AVP 98 97 99 104 18 3 0 8 96
  153. c=IN IP4 192.168.199.147
  154. a=rtpmap:98 speex/16000
  155. a=rtpmap:97 speex/8000
  156. a=rtpmap:99 speex/32000
  157. a=rtpmap:104 iLBC/8000
  158. a=fmtp:104 mode=30
  159. a=rtpmap:18 G729/8000
  160. a=rtpmap:3 GSM/8000
  161. a=rtpmap:0 PCMU/8000
  162. a=rtpmap:8 PCMA/8000
  163. a=rtpmap:96 telephone-event/8000
  164. a=fmtp:96 0-15
  165. a=rtcp:40003 IN IP4 192.168.199.147
  166.  
  167. 2018-02-28 23:18:58.917835 [DEBUG] sofia.c:7450 (sofia/internal/1001@192.168.199.209) State Change CS_NEW -> CS_INIT
  168. 2018-02-28 23:18:58.917835 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1001@192.168.199.209) Running State Change CS_INIT (Cur 1 Tot 36)
  169. 2018-02-28 23:18:58.917835 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1001@192.168.199.209) State INIT
  170. 2018-02-28 23:18:58.917835 [DEBUG] mod_sofia.c:90 sofia/internal/1001@192.168.199.209 SOFIA INIT
  171. 2018-02-28 23:18:58.917835 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1001@192.168.199.209 Standard INIT
  172. 2018-02-28 23:18:58.917835 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1001@192.168.199.209) State Change CS_INIT -> CS_ROUTING
  173. 2018-02-28 23:18:58.917835 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1001@192.168.199.209) State INIT going to sleep
  174. 2018-02-28 23:18:58.917835 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1001@192.168.199.209) Running State Change CS_ROUTING (Cur 1 Tot 36)
  175. 2018-02-28 23:18:58.917835 [DEBUG] switch_channel.c:2249 (sofia/internal/1001@192.168.199.209) Callstate Change DOWN -> RINGING
  176. 2018-02-28 23:18:58.917835 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1001@192.168.199.209) State ROUTING
  177. 2018-02-28 23:18:58.917835 [DEBUG] mod_sofia.c:143 sofia/internal/1001@192.168.199.209 SOFIA ROUTING
  178. 2018-02-28 23:18:58.917835 [DEBUG] switch_core_state_machine.c:236 sofia/internal/1001@192.168.199.209 Standard ROUTING
  179. 2018-02-28 23:18:58.917835 [INFO] mod_dialplan_xml.c:637 Processing 1001 <1001>->1000 in context default
  180. Dialplan: sofia/internal/1001@192.168.199.209 parsing [default->unloop] continue=false
  181. Dialplan: sofia/internal/1001@192.168.199.209 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  182. Dialplan: sofia/internal/1001@192.168.199.209 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  183. Dialplan: sofia/internal/1001@192.168.199.209 parsing [default->tod_example] continue=true
  184. Dialplan: sofia/internal/1001@192.168.199.209 Date/TimeMatch (FAIL) [tod_example] break=on-false
  185. Dialplan: sofia/internal/1001@192.168.199.209 parsing [default->holiday_example] continue=true
  186. Dialplan: sofia/internal/1001@192.168.199.209 Date/TimeMatch (FAIL) [holiday_example] break=on-false
  187. Dialplan: sofia/internal/1001@192.168.199.209 parsing [default->global-intercept] continue=false
  188. Dialplan: sofia/internal/1001@192.168.199.209 Regex (FAIL) [global-intercept] destination_number(1000) =~ /^886$/ break=on-false
  189. Dialplan: sofia/internal/1001@192.168.199.209 parsing [default->group-intercept] continue=false
  190. Dialplan: sofia/internal/1001@192.168.199.209 Regex (FAIL) [group-intercept] destination_number(1000) =~ /^\*8$/ break=on-false
  191. Dialplan: sofia/internal/1001@192.168.199.209 parsing [default->intercept-ext] continue=false
  192. Dialplan: sofia/internal/1001@192.168.199.209 Regex (FAIL) [intercept-ext] destination_number(1000) =~ /^\*\*(\d+)$/ break=on-false
  193. Dialplan: sofia/internal/1001@192.168.199.209 parsing [default->redial] continue=false
  194. Dialplan: sofia/internal/1001@192.168.199.209 Regex (FAIL) [redial] destination_number(1000) =~ /^(redial|870)$/ break=on-false
  195. Dialplan: sofia/internal/1001@192.168.199.209 parsing [default->global] continue=true
  196. Dialplan: sofia/internal/1001@192.168.199.209 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
  197. Dialplan: sofia/internal/1001@192.168.199.209 Regex (PASS) [global] ${default_password}(1234) =~ /^1234$/ break=never
  198. Dialplan: sofia/internal/1001@192.168.199.209 Action log(CRIT WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING )
  199. Dialplan: sofia/internal/1001@192.168.199.209 Action log(CRIT Open /root/freeswitch/etc/freeswitch/vars.xml and change the default_password.)
  200. Dialplan: sofia/internal/1001@192.168.199.209 Action log(CRIT Once changed type 'reloadxml' at the console.)
  201. Dialplan: sofia/internal/1001@192.168.199.209 Action log(CRIT WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING )
  202. Dialplan: sofia/internal/1001@192.168.199.209 Action sleep(10000)
  203. Dialplan: sofia/internal/1001@192.168.199.209 Regex (FAIL) [global] ${rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_SHA1_80|AES_CM_192_HMAC_SHA1_80|AES_CM_128_HMAC_SHA1_80|AES_CM_256_HMAC_SHA1_32|AES_CM_192_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_32|AES_CM_128_NULL_AUTH)$/ break=never
  204. Dialplan: sofia/internal/1001@192.168.199.209 Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false
  205. Dialplan: sofia/internal/1001@192.168.199.209 Regex (FAIL) [global] ${switch_r_sdp}(v=0
  206. o=- 3728874429 3728874429 IN IP4 192.168.199.147
  207. s=pjmedia
  208. c=IN IP4 192.168.199.147
  209. t=0 0
  210. a=X-nat:0
  211. m=audio 40002 RTP/AVP 98 97 99 104 18 3 0 8 96
  212. c=IN IP4 192.168.199.147
  213. a=rtpmap:98 speex/16000
  214. a=rtpmap:97 speex/8000
  215. a=rtpmap:99 speex/32000
  216. a=rtpmap:104 iLBC/8000
  217. a=fmtp:104 mode=30
  218. a=rtpmap:18 G729/8000
  219. a=rtpmap:3 GSM/8000
  220. a=rtpmap:0 PCMU/8000
  221. a=rtpmap:8 PCMA/8000
  222. a=rtpmap:96 telephone-event/8000
  223. a=fmtp:96 0-15
  224. a=rtcp:40003 IN IP4 192.168.199.147
  225. ) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never
  226. Dialplan: sofia/internal/1001@192.168.199.209 Absolute Condition [global]
  227. Dialplan: sofia/internal/1001@192.168.199.209 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
  228. Dialplan: sofia/internal/1001@192.168.199.209 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
  229. Dialplan: sofia/internal/1001@192.168.199.209 Action hash(insert/${domain_name}-last_dial/global/${uuid})
  230. Dialplan: sofia/internal/1001@192.168.199.209 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  231. Dialplan: sofia/internal/1001@192.168.199.209 parsing [default->snom-demo-2] continue=false
  232. Dialplan: sofia/internal/1001@192.168.199.209 Regex (FAIL) [snom-demo-2] destination_number(1000) =~ /^9001$/ break=on-false
  233. Dialplan: sofia/internal/1001@192.168.199.209 parsing [default->snom-demo-1] continue=false
  234. Dialplan: sofia/internal/1001@192.168.199.209 Regex (FAIL) [snom-demo-1] destination_number(1000) =~ /^9000$/ break=on-false
  235. Dialplan: sofia/internal/1001@192.168.199.209 parsing [default->eavesdrop] continue=false
  236. Dialplan: sofia/internal/1001@192.168.199.209 Regex (FAIL) [eavesdrop] destination_number(1000) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
  237. Dialplan: sofia/internal/1001@192.168.199.209 parsing [default->eavesdrop] continue=false
  238. Dialplan: sofia/internal/1001@192.168.199.209 Regex (FAIL) [eavesdrop] destination_number(1000) =~ /^779$/ break=on-false
  239. Dialplan: sofia/internal/1001@192.168.199.209 parsing [default->call_return] continue=false
  240. Dialplan: sofia/internal/1001@192.168.199.209 Regex (FAIL) [call_return] destination_number(1000) =~ /^\*69$|^869$|^lcr$/ break=on-false
  241. Dialplan: sofia/internal/1001@192.168.199.209 parsing [default->del-group] continue=false
  242. Dialplan: sofia/internal/1001@192.168.199.209 Regex (FAIL) [del-group] destination_number(1000) =~ /^80(\d{2})$/ break=on-false
  243. Dialplan: sofia/internal/1001@192.168.199.209 parsing [default->add-group] continue=false
  244. Dialplan: sofia/internal/1001@192.168.199.209 Regex (FAIL) [add-group] destination_number(1000) =~ /^81(\d{2})$/ break=on-false
  245. Dialplan: sofia/internal/1001@192.168.199.209 parsing [default->call-group-simo] continue=false
  246. Dialplan: sofia/internal/1001@192.168.199.209 Regex (FAIL) [call-group-simo] destination_number(1000) =~ /^82(\d{2})$/ break=on-false
  247. Dialplan: sofia/internal/1001@192.168.199.209 parsing [default->call-group-order] continue=false
  248. Dialplan: sofia/internal/1001@192.168.199.209 Regex (FAIL) [call-group-order] destination_number(1000) =~ /^83(\d{2})$/ break=on-false
  249. Dialplan: sofia/internal/1001@192.168.199.209 parsing [default->extension-intercom] continue=false
  250. Dialplan: sofia/internal/1001@192.168.199.209 Regex (FAIL) [extension-intercom] destination_number(1000) =~ /^8(10[01][0-9])$/ break=on-false
  251. Dialplan: sofia/internal/1001@192.168.199.209 parsing [default->Local_Extension] continue=false
  252. Dialplan: sofia/internal/1001@192.168.199.209 Regex (PASS) [Local_Extension] destination_number(1000) =~ /^(10[01][0-9])$/ break=on-false
  253. Dialplan: sofia/internal/1001@192.168.199.209 Action export(dialed_extension=1000)
  254. Dialplan: sofia/internal/1001@192.168.199.209 Action bind_meta_app(1 b s execute_extension::dx XML features)
  255. Dialplan: sofia/internal/1001@192.168.199.209 Action bind_meta_app(2 b s record_session::/root/freeswitch/var/lib/freeswitch/recordings/${caller_id_number}.${strftime(%Y-%m-%d-%H-%M-%S)}.wav)
  256. Dialplan: sofia/internal/1001@192.168.199.209 Action bind_meta_app(3 b s execute_extension::cf XML features)
  257. Dialplan: sofia/internal/1001@192.168.199.209 Action bind_meta_app(4 b s execute_extension::att_xfer XML features)
  258. Dialplan: sofia/internal/1001@192.168.199.209 Action set(ringback=${us-ring})
  259. Dialplan: sofia/internal/1001@192.168.199.209 Action set(transfer_ringback=local_stream://moh)
  260. Dialplan: sofia/internal/1001@192.168.199.209 Action set(call_timeout=30)
  261. Dialplan: sofia/internal/1001@192.168.199.209 Action set(hangup_after_bridge=true)
  262. Dialplan: sofia/internal/1001@192.168.199.209 Action set(continue_on_fail=true)
  263. Dialplan: sofia/internal/1001@192.168.199.209 Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number})
  264. Dialplan: sofia/internal/1001@192.168.199.209 Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid})
  265. Dialplan: sofia/internal/1001@192.168.199.209 Action set(called_party_callgroup=${user_data(${dialed_extension}@${domain_name} var callgroup)})
  266. Dialplan: sofia/internal/1001@192.168.199.209 Action hash(insert/${domain_name}-last_dial_ext/${called_party_callgroup}/${uuid})
  267. Dialplan: sofia/internal/1001@192.168.199.209 Action hash(insert/${domain_name}-last_dial_ext/global/${uuid})
  268. Dialplan: sofia/internal/1001@192.168.199.209 Action hash(insert/${domain_name}-last_dial/${called_party_callgroup}/${uuid})
  269. Dialplan: sofia/internal/1001@192.168.199.209 Action bridge(user/${dialed_extension}@${domain_name})
  270. Dialplan: sofia/internal/1001@192.168.199.209 Action answer()
  271. Dialplan: sofia/internal/1001@192.168.199.209 Action sleep(1000)
  272. Dialplan: sofia/internal/1001@192.168.199.209 Action bridge(loopback/app=voicemail:default ${domain_name} ${dialed_extension})
  273. 2018-02-28 23:18:58.917835 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/1001@192.168.199.209) State Change CS_ROUTING -> CS_EXECUTE
  274. 2018-02-28 23:18:58.917835 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1001@192.168.199.209) State ROUTING going to sleep
  275. 2018-02-28 23:18:58.917835 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1001@192.168.199.209) Running State Change CS_EXECUTE (Cur 1 Tot 36)
  276. 2018-02-28 23:18:58.917835 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1001@192.168.199.209) State EXECUTE
  277. 2018-02-28 23:18:58.917835 [DEBUG] mod_sofia.c:198 sofia/internal/1001@192.168.199.209 SOFIA EXECUTE
  278. 2018-02-28 23:18:58.917835 [DEBUG] switch_core_state_machine.c:328 sofia/internal/1001@192.168.199.209 Standard EXECUTE
  279. EXECUTE sofia/internal/1001@192.168.199.209 log(CRIT WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING )
  280. 2018-02-28 23:18:58.917835 [CRIT] mod_dptools.c:1724 WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING
  281. EXECUTE sofia/internal/1001@192.168.199.209 log(CRIT Open /root/freeswitch/etc/freeswitch/vars.xml and change the default_password.)
  282. 2018-02-28 23:18:58.917835 [CRIT] mod_dptools.c:1724 Open /root/freeswitch/etc/freeswitch/vars.xml and change the default_password.
  283. EXECUTE sofia/internal/1001@192.168.199.209 log(CRIT Once changed type 'reloadxml' at the console.)
  284. 2018-02-28 23:18:58.917835 [CRIT] mod_dptools.c:1724 Once changed type 'reloadxml' at the console.
  285. EXECUTE sofia/internal/1001@192.168.199.209 log(CRIT WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING )
  286. 2018-02-28 23:18:58.917835 [CRIT] mod_dptools.c:1724 WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING
  287. EXECUTE sofia/internal/1001@192.168.199.209 sleep(10000)
  288. send 1367 bytes to udp/[192.168.199.113]:64236 at 23:18:58.925062:
  289.    ------------------------------------------------------------------------
  290.    NOTIFY sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615 SIP/2.0
  291.    Via: SIP/2.0/UDP 192.168.199.209;rport;branch=z9hG4bK7BNUvmgp67N0B
  292.    Max-Forwards: 70
  293.    From: <sip:1001@192.168.199.209>;tag=Z4GBrKbDdmSR
  294.    To: "1000" <sip:1000@192.168.199.209>;tag=53819c6d
  295.    Call-ID: 90533M2VjZDgwMTFhOTA2ODdjNmYwMThmMThkOWM0M2M4Yzc
  296.    CSeq: 254756901 NOTIFY
  297.    Contact: <sip:1001@192.168.199.209:5060>
  298.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  299.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  300.    Supported: timer, path, replaces
  301.    Event: presence
  302.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  303.    Subscription-State: active;expires=3308
  304.    Content-Type: application/pidf+xml
  305.    Content-Length: 500
  306.    
  307.    <?xml version="1.0" encoding="ISO-8859-1"?>
  308.    <presence xmlns='urn:ietf:params:xml:ns:pidf'
  309.    xmlns:dm='urn:ietf:params:xml:ns:pidf:data-model'
  310.    xmlns:rpid='urn:ietf:params:xml:ns:pidf:rpid'
  311.    xmlns:c='urn:ietf:params:xml:ns:pidf:cipid' entity='sip:1001@192.168.199.209'>
  312.     <tuple id='t6a5ed77e'>
  313.      <status>
  314.       <basic>open</basic>
  315.      </status>
  316.     </tuple>
  317.     <dm:person id='p06360c4a'>
  318.      <rpid:activities>
  319.       <rpid:on-the-phone/>
  320.      </rpid:activities>
  321.      <dm:note>Call 1000</dm:note>
  322.     </dm:person>
  323.    </presence>
  324.  
  325.    ------------------------------------------------------------------------
  326. recv 411 bytes from udp/[192.168.199.113]:64236 at 23:18:58.927122:
  327.    ------------------------------------------------------------------------
  328.    SIP/2.0 200 OK
  329.    Via: SIP/2.0/UDP 192.168.199.209;rport=5060;branch=z9hG4bK7BNUvmgp67N0B
  330.    Contact: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>
  331.    To: "1000"<sip:1000@192.168.199.209>;tag=53819c6d
  332.    From: <sip:1001@192.168.199.209>;tag=Z4GBrKbDdmSR
  333.    Call-ID: 90533M2VjZDgwMTFhOTA2ODdjNmYwMThmMThkOWM0M2M4Yzc
  334.    CSeq: 254756901 NOTIFY
  335.    User-Agent: X-Lite release 5.2.0 stamp 90533
  336.    Content-Length: 0
  337.    
  338.    ------------------------------------------------------------------------
  339. recv 619 bytes from udp/[192.168.199.113]:64236 at 23:19:06.307366:
  340.    ------------------------------------------------------------------------
  341.    SUBSCRIBE sip:9664@192.168.199.209:5060 SIP/2.0
  342.    Via: SIP/2.0/UDP 192.168.199.113:64236;branch=z9hG4bK-524287-1---fc31a20281dfda03;rport
  343.    Max-Forwards: 70
  344.    Contact: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>
  345.    To: <sip:9664@192.168.199.209>;tag=Wt0rnE4uCOZl
  346.    From: "1000" <sip:1000@192.168.199.209>;tag=1430427d
  347.    Call-ID: 90533Y2E2Y2NhNDhkN2VjMDhiZTVhNzQzMDZjNjk5ZjNkZjc
  348.    CSeq: 8 SUBSCRIBE
  349.    Expires: 3599
  350.    Accept: application/pidf+xml
  351.   Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
  352.    User-Agent: X-Lite release 5.2.0 stamp 90533
  353.    Event: presence
  354.    Content-Length: 0
  355.    
  356.    ------------------------------------------------------------------------
  357. recv 619 bytes from udp/[192.168.199.113]:64236 at 23:19:06.308073:
  358.    ------------------------------------------------------------------------
  359.    SUBSCRIBE sip:9196@192.168.199.209:5060 SIP/2.0
  360.    Via: SIP/2.0/UDP 192.168.199.113:64236;branch=z9hG4bK-524287-1---c921681b3c9e033b;rport
  361.    Max-Forwards: 70
  362.    Contact: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>
  363.    To: <sip:9196@192.168.199.209>;tag=3aPMpMWBXM0F
  364.    From: "1000" <sip:1000@192.168.199.209>;tag=8869d805
  365.    Call-ID: 90533MjI5Yzg2ZmVlNzgxZjJmYzEyMjRmNDJjNWVkMmFhNGY
  366.    CSeq: 8 SUBSCRIBE
  367.    Expires: 3599
  368.    Accept: application/pidf+xml
  369.   Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
  370.    User-Agent: X-Lite release 5.2.0 stamp 90533
  371.    Event: presence
  372.    Content-Length: 0
  373.    
  374.    ------------------------------------------------------------------------
  375. recv 619 bytes from udp/[192.168.199.113]:64236 at 23:19:06.308319:
  376.    ------------------------------------------------------------------------
  377.    SUBSCRIBE sip:9195@192.168.199.209:5060 SIP/2.0
  378.    Via: SIP/2.0/UDP 192.168.199.113:64236;branch=z9hG4bK-524287-1---d5f6b0394c4a9534;rport
  379.    Max-Forwards: 70
  380.    Contact: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>
  381.    To: <sip:9195@192.168.199.209>;tag=LnV1BKYn4PFh
  382.    From: "1000" <sip:1000@192.168.199.209>;tag=9ec67706
  383.    Call-ID: 90533ZjNlOTc0MjM4NDM4YTFhNGZmYmM4NzBkMTRjNzI0MDc
  384.    CSeq: 8 SUBSCRIBE
  385.    Expires: 3599
  386.    Accept: application/pidf+xml
  387.   Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
  388.    User-Agent: X-Lite release 5.2.0 stamp 90533
  389.    Event: presence
  390.    Content-Length: 0
  391.    
  392.    ------------------------------------------------------------------------
  393. recv 619 bytes from udp/[192.168.199.113]:64236 at 23:19:06.308483:
  394.    ------------------------------------------------------------------------
  395.    SUBSCRIBE sip:9198@192.168.199.209:5060 SIP/2.0
  396.    Via: SIP/2.0/UDP 192.168.199.113:64236;branch=z9hG4bK-524287-1---8802a05ae4b69449;rport
  397.    Max-Forwards: 70
  398.    Contact: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>
  399.    To: <sip:9198@192.168.199.209>;tag=kzCMdrY7sEKH
  400.    From: "1000" <sip:1000@192.168.199.209>;tag=0d01e725
  401.    Call-ID: 90533OGQ2ZTU1OWM4MDE5OGM3YmU2YmVhZjg5NmI5OGM5OTU
  402.    CSeq: 8 SUBSCRIBE
  403.    Expires: 3600
  404.    Accept: application/pidf+xml
  405.   Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
  406.    User-Agent: X-Lite release 5.2.0 stamp 90533
  407.    Event: presence
  408.    Content-Length: 0
  409.    
  410.    ------------------------------------------------------------------------
  411. recv 619 bytes from udp/[192.168.199.113]:64236 at 23:19:06.308598:
  412.    ------------------------------------------------------------------------
  413.    SUBSCRIBE sip:9181@192.168.199.209:5060 SIP/2.0
  414.    Via: SIP/2.0/UDP 192.168.199.113:64236;branch=z9hG4bK-524287-1---385ac472de96b535;rport
  415.    Max-Forwards: 70
  416.    Contact: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>
  417.    To: <sip:9181@192.168.199.209>;tag=gw839ZzKclCD
  418.    From: "1000" <sip:1000@192.168.199.209>;tag=b76edc0b
  419.    Call-ID: 90533NGJlNjhkODk3ZDMyMTg3Y2UwYzRkOGRlNjcxOGU3OGM
  420.    CSeq: 8 SUBSCRIBE
  421.    Expires: 3600
  422.    Accept: application/pidf+xml
  423.   Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
  424.    User-Agent: X-Lite release 5.2.0 stamp 90533
  425.    Event: presence
  426.    Content-Length: 0
  427.    
  428.    ------------------------------------------------------------------------
  429. recv 619 bytes from udp/[192.168.199.113]:64236 at 23:19:06.308677:
  430.    ------------------------------------------------------------------------
  431.    SUBSCRIBE sip:9183@192.168.199.209:5060 SIP/2.0
  432.    Via: SIP/2.0/UDP 192.168.199.113:64236;branch=z9hG4bK-524287-1---54f0b6228840b239;rport
  433.    Max-Forwards: 70
  434.    Contact: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>
  435.    To: <sip:9183@192.168.199.209>;tag=VrRP6nXhMpUQ
  436.    From: "1000" <sip:1000@192.168.199.209>;tag=183b786d
  437.    Call-ID: 90533NzFlNTMwYjE0ZGNjZTQyZTcyYTY1YjViYmEzZWQzYTA
  438.    CSeq: 8 SUBSCRIBE
  439.    Expires: 3600
  440.    Accept: application/pidf+xml
  441.   Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
  442.    User-Agent: X-Lite release 5.2.0 stamp 90533
  443.    Event: presence
  444.    Content-Length: 0
  445.    
  446.    ------------------------------------------------------------------------
  447. recv 619 bytes from udp/[192.168.199.113]:64236 at 23:19:06.308740:
  448.    ------------------------------------------------------------------------
  449.    SUBSCRIBE sip:9184@192.168.199.209:5060 SIP/2.0
  450.    Via: SIP/2.0/UDP 192.168.199.113:64236;branch=z9hG4bK-524287-1---943df604c4b2d55b;rport
  451.    Max-Forwards: 70
  452.    Contact: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>
  453.    To: <sip:9184@192.168.199.209>;tag=HJOZdoxjLwdK
  454.    From: "1000" <sip:1000@192.168.199.209>;tag=de06aa66
  455.    Call-ID: 90533ODM2ZGNkNDUwZGRhZjZkZmEwYjIxZjc1ZTJkMTQ1ZDk
  456.    CSeq: 8 SUBSCRIBE
  457.    Expires: 3600
  458.    Accept: application/pidf+xml
  459.   Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
  460.    User-Agent: X-Lite release 5.2.0 stamp 90533
  461.    Event: presence
  462.    Content-Length: 0
  463.    
  464.    ------------------------------------------------------------------------
  465. recv 619 bytes from udp/[192.168.199.113]:64236 at 23:19:06.308797:
  466.    ------------------------------------------------------------------------
  467.    SUBSCRIBE sip:5000@192.168.199.209:5060 SIP/2.0
  468.    Via: SIP/2.0/UDP 192.168.199.113:64236;branch=z9hG4bK-524287-1---cc1e8f06a5af276f;rport
  469.    Max-Forwards: 70
  470.    Contact: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>
  471.    To: <sip:5000@192.168.199.209>;tag=mDloONSA4Ag1
  472.    From: "1000" <sip:1000@192.168.199.209>;tag=e7841062
  473.    Call-ID: 90533YTM1NDAzNjkwMmM4Zjk1NDM1NWVkNjllMDBiZGY3MjY
  474.    CSeq: 8 SUBSCRIBE
  475.    Expires: 3600
  476.    Accept: application/pidf+xml
  477.   Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
  478.    User-Agent: X-Lite release 5.2.0 stamp 90533
  479.    Event: presence
  480.    Content-Length: 0
  481.    
  482.    ------------------------------------------------------------------------
  483. send 779 bytes to udp/[192.168.199.113]:64236 at 23:19:06.308923:
  484.    ------------------------------------------------------------------------
  485.    SIP/2.0 202 Accepted
  486.    Via: SIP/2.0/UDP 192.168.199.113:64236;branch=z9hG4bK-524287-1---fc31a20281dfda03;rport=64236
  487.    From: "1000" <sip:1000@192.168.199.209>;tag=1430427d
  488.    To: <sip:9664@192.168.199.209>;tag=Wt0rnE4uCOZl
  489.    Call-ID: 90533Y2E2Y2NhNDhkN2VjMDhiZTVhNzQzMDZjNjk5ZjNkZjc
  490.    CSeq: 8 SUBSCRIBE
  491.    Contact: <sip:9664@192.168.199.209:5060>
  492.    Expires: 3599
  493.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  494.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  495.    Supported: timer, path, replaces
  496.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  497.    Subscription-State: active;expires=3599
  498.    Content-Length: 0
  499.    
  500.    ------------------------------------------------------------------------
  501. recv 619 bytes from udp/[192.168.199.113]:64236 at 23:19:06.309148:
  502.    ------------------------------------------------------------------------
  503.    SUBSCRIBE sip:4000@192.168.199.209:5060 SIP/2.0
  504.    Via: SIP/2.0/UDP 192.168.199.113:64236;branch=z9hG4bK-524287-1---278f0273c9b8135c;rport
  505.    Max-Forwards: 70
  506.    Contact: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>
  507.    To: <sip:4000@192.168.199.209>;tag=N39qjU7RtLHx
  508.    From: "1000" <sip:1000@192.168.199.209>;tag=1e8f204f
  509.    Call-ID: 90533MWQ0NWZiZjk5ZmJkNzI4ODBhMjU1N2FhYmYwMDFhZTc
  510.    CSeq: 8 SUBSCRIBE
  511.    Expires: 3600
  512.    Accept: application/pidf+xml
  513.   Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
  514.    User-Agent: X-Lite release 5.2.0 stamp 90533
  515.    Event: presence
  516.    Content-Length: 0
  517.    
  518.    ------------------------------------------------------------------------
  519. recv 619 bytes from udp/[192.168.199.113]:64236 at 23:19:06.309291:
  520.    ------------------------------------------------------------------------
  521.    SUBSCRIBE sip:1001@192.168.199.209:5060 SIP/2.0
  522.    Via: SIP/2.0/UDP 192.168.199.113:64236;branch=z9hG4bK-524287-1---06571717c1ac073a;rport
  523.    Max-Forwards: 70
  524.    Contact: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>
  525.    To: <sip:1001@192.168.199.209>;tag=Z4GBrKbDdmSR
  526.    From: "1000" <sip:1000@192.168.199.209>;tag=53819c6d
  527.    Call-ID: 90533M2VjZDgwMTFhOTA2ODdjNmYwMThmMThkOWM0M2M4Yzc
  528.    CSeq: 8 SUBSCRIBE
  529.    Expires: 3600
  530.    Accept: application/pidf+xml
  531.   Allow: SUBSCRIBE, NOTIFY, INVITE, ACK, CANCEL, BYE, REFER, INFO, OPTIONS, MESSAGE
  532.    User-Agent: X-Lite release 5.2.0 stamp 90533
  533.    Event: presence
  534.    Content-Length: 0
  535.    
  536.    ------------------------------------------------------------------------
  537. send 1306 bytes to udp/[192.168.199.113]:64236 at 23:19:06.311075:
  538.    ------------------------------------------------------------------------
  539.    NOTIFY sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615 SIP/2.0
  540.    Via: SIP/2.0/UDP 192.168.199.209;rport;branch=z9hG4bK8memyF1S3gcKQ
  541.    Max-Forwards: 70
  542.    From: <sip:9664@192.168.199.209>;tag=Wt0rnE4uCOZl
  543.    To: "1000" <sip:1000@192.168.199.209>;tag=1430427d
  544.    Call-ID: 90533Y2E2Y2NhNDhkN2VjMDhiZTVhNzQzMDZjNjk5ZjNkZjc
  545.    CSeq: 254757301 NOTIFY
  546.    Contact: <sip:9664@192.168.199.209:5060>
  547.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  548.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  549.    Supported: timer, path, replaces
  550.    Event: presence
  551.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  552.    Subscription-State: active;expires=3599
  553.    Content-Type: application/pidf+xml
  554.    Content-Length: 439
  555.    
  556.    <?xml version="1.0" encoding="ISO-8859-1"?>
  557.    <presence xmlns='urn:ietf:params:xml:ns:pidf'
  558.    xmlns:dm='urn:ietf:params:xml:ns:pidf:data-model'
  559.    xmlns:rpid='urn:ietf:params:xml:ns:pidf:rpid'
  560.    xmlns:c='urn:ietf:params:xml:ns:pidf:cipid' entity='sip:9664@192.168.199.209'>
  561.     <tuple id='t6a5ed77e'>
  562.      <status>
  563.       <basic>closed</basic>
  564.      </status>
  565.     </tuple>
  566.     <dm:person id='p06360c4a'>
  567.      <dm:note>Unregistered</dm:note>
  568.     </dm:person>
  569.    </presence>
  570.  
  571.    ------------------------------------------------------------------------
  572. send 779 bytes to udp/[192.168.199.113]:64236 at 23:19:06.311440:
  573.    ------------------------------------------------------------------------
  574.    SIP/2.0 202 Accepted
  575.    Via: SIP/2.0/UDP 192.168.199.113:64236;branch=z9hG4bK-524287-1---c921681b3c9e033b;rport=64236
  576.    From: "1000" <sip:1000@192.168.199.209>;tag=8869d805
  577.    To: <sip:9196@192.168.199.209>;tag=3aPMpMWBXM0F
  578.    Call-ID: 90533MjI5Yzg2ZmVlNzgxZjJmYzEyMjRmNDJjNWVkMmFhNGY
  579.    CSeq: 8 SUBSCRIBE
  580.    Contact: <sip:9196@192.168.199.209:5060>
  581.    Expires: 3599
  582.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  583.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  584.    Supported: timer, path, replaces
  585.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  586.    Subscription-State: active;expires=3599
  587.    Content-Length: 0
  588.    
  589.    ------------------------------------------------------------------------
  590. send 779 bytes to udp/[192.168.199.113]:64236 at 23:19:06.312345:
  591.    ------------------------------------------------------------------------
  592.    SIP/2.0 202 Accepted
  593.    Via: SIP/2.0/UDP 192.168.199.113:64236;branch=z9hG4bK-524287-1---d5f6b0394c4a9534;rport=64236
  594.    From: "1000" <sip:1000@192.168.199.209>;tag=9ec67706
  595.    To: <sip:9195@192.168.199.209>;tag=LnV1BKYn4PFh
  596.    Call-ID: 90533ZjNlOTc0MjM4NDM4YTFhNGZmYmM4NzBkMTRjNzI0MDc
  597.    CSeq: 8 SUBSCRIBE
  598.    Contact: <sip:9195@192.168.199.209:5060>
  599.    Expires: 3599
  600.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  601.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  602.    Supported: timer, path, replaces
  603.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  604.    Subscription-State: active;expires=3599
  605.    Content-Length: 0
  606.    
  607.    ------------------------------------------------------------------------
  608. send 1306 bytes to udp/[192.168.199.113]:64236 at 23:19:06.313063:
  609.    ------------------------------------------------------------------------
  610.    NOTIFY sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615 SIP/2.0
  611.    Via: SIP/2.0/UDP 192.168.199.209;rport;branch=z9hG4bK9X7c0ajX0S25j
  612.    Max-Forwards: 70
  613.    From: <sip:9196@192.168.199.209>;tag=3aPMpMWBXM0F
  614.    To: "1000" <sip:1000@192.168.199.209>;tag=8869d805
  615.    Call-ID: 90533MjI5Yzg2ZmVlNzgxZjJmYzEyMjRmNDJjNWVkMmFhNGY
  616.    CSeq: 254757302 NOTIFY
  617.    Contact: <sip:9196@192.168.199.209:5060>
  618.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  619.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  620.    Supported: timer, path, replaces
  621.    Event: presence
  622.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  623.    Subscription-State: active;expires=3599
  624.    Content-Type: application/pidf+xml
  625.    Content-Length: 439
  626.    
  627.    <?xml version="1.0" encoding="ISO-8859-1"?>
  628.    <presence xmlns='urn:ietf:params:xml:ns:pidf'
  629.    xmlns:dm='urn:ietf:params:xml:ns:pidf:data-model'
  630.    xmlns:rpid='urn:ietf:params:xml:ns:pidf:rpid'
  631.    xmlns:c='urn:ietf:params:xml:ns:pidf:cipid' entity='sip:9196@192.168.199.209'>
  632.     <tuple id='t6a5ed77e'>
  633.      <status>
  634.       <basic>closed</basic>
  635.      </status>
  636.     </tuple>
  637.     <dm:person id='p06360c4a'>
  638.      <dm:note>Unregistered</dm:note>
  639.     </dm:person>
  640.    </presence>
  641.  
  642.    ------------------------------------------------------------------------
  643. recv 411 bytes from udp/[192.168.199.113]:64236 at 23:19:06.313587:
  644.    ------------------------------------------------------------------------
  645.    SIP/2.0 200 OK
  646.    Via: SIP/2.0/UDP 192.168.199.209;rport=5060;branch=z9hG4bK8memyF1S3gcKQ
  647.    Contact: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>
  648.    To: "1000"<sip:1000@192.168.199.209>;tag=1430427d
  649.    From: <sip:9664@192.168.199.209>;tag=Wt0rnE4uCOZl
  650.    Call-ID: 90533Y2E2Y2NhNDhkN2VjMDhiZTVhNzQzMDZjNjk5ZjNkZjc
  651.    CSeq: 254757301 NOTIFY
  652.    User-Agent: X-Lite release 5.2.0 stamp 90533
  653.    Content-Length: 0
  654.    
  655.    ------------------------------------------------------------------------
  656. send 779 bytes to udp/[192.168.199.113]:64236 at 23:19:06.314139:
  657.    ------------------------------------------------------------------------
  658.    SIP/2.0 202 Accepted
  659.    Via: SIP/2.0/UDP 192.168.199.113:64236;branch=z9hG4bK-524287-1---8802a05ae4b69449;rport=64236
  660.    From: "1000" <sip:1000@192.168.199.209>;tag=0d01e725
  661.    To: <sip:9198@192.168.199.209>;tag=kzCMdrY7sEKH
  662.    Call-ID: 90533OGQ2ZTU1OWM4MDE5OGM3YmU2YmVhZjg5NmI5OGM5OTU
  663.    CSeq: 8 SUBSCRIBE
  664.    Contact: <sip:9198@192.168.199.209:5060>
  665.    Expires: 3600
  666.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  667.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  668.    Supported: timer, path, replaces
  669.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  670.    Subscription-State: active;expires=3600
  671.    Content-Length: 0
  672.    
  673.    ------------------------------------------------------------------------
  674. recv 411 bytes from udp/[192.168.199.113]:64236 at 23:19:06.314663:
  675.    ------------------------------------------------------------------------
  676.    SIP/2.0 200 OK
  677.    Via: SIP/2.0/UDP 192.168.199.209;rport=5060;branch=z9hG4bK9X7c0ajX0S25j
  678.    Contact: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>
  679.    To: "1000"<sip:1000@192.168.199.209>;tag=8869d805
  680.    From: <sip:9196@192.168.199.209>;tag=3aPMpMWBXM0F
  681.    Call-ID: 90533MjI5Yzg2ZmVlNzgxZjJmYzEyMjRmNDJjNWVkMmFhNGY
  682.    CSeq: 254757302 NOTIFY
  683.    User-Agent: X-Lite release 5.2.0 stamp 90533
  684.    Content-Length: 0
  685.    
  686.    ------------------------------------------------------------------------
  687. send 1306 bytes to udp/[192.168.199.113]:64236 at 23:19:06.315003:
  688.    ------------------------------------------------------------------------
  689.    NOTIFY sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615 SIP/2.0
  690.    Via: SIP/2.0/UDP 192.168.199.209;rport;branch=z9hG4bKa7051520X2rre
  691.    Max-Forwards: 70
  692.    From: <sip:9195@192.168.199.209>;tag=LnV1BKYn4PFh
  693.    To: "1000" <sip:1000@192.168.199.209>;tag=9ec67706
  694.    Call-ID: 90533ZjNlOTc0MjM4NDM4YTFhNGZmYmM4NzBkMTRjNzI0MDc
  695.    CSeq: 254757303 NOTIFY
  696.    Contact: <sip:9195@192.168.199.209:5060>
  697.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  698.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  699.    Supported: timer, path, replaces
  700.    Event: presence
  701.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  702.    Subscription-State: active;expires=3599
  703.    Content-Type: application/pidf+xml
  704.    Content-Length: 439
  705.    
  706.    <?xml version="1.0" encoding="ISO-8859-1"?>
  707.    <presence xmlns='urn:ietf:params:xml:ns:pidf'
  708.    xmlns:dm='urn:ietf:params:xml:ns:pidf:data-model'
  709.    xmlns:rpid='urn:ietf:params:xml:ns:pidf:rpid'
  710.    xmlns:c='urn:ietf:params:xml:ns:pidf:cipid' entity='sip:9195@192.168.199.209'>
  711.     <tuple id='t6a5ed77e'>
  712.      <status>
  713.       <basic>closed</basic>
  714.      </status>
  715.     </tuple>
  716.     <dm:person id='p06360c4a'>
  717.      <dm:note>Unregistered</dm:note>
  718.     </dm:person>
  719.    </presence>
  720.  
  721.    ------------------------------------------------------------------------
  722. recv 411 bytes from udp/[192.168.199.113]:64236 at 23:19:06.316007:
  723.    ------------------------------------------------------------------------
  724.    SIP/2.0 200 OK
  725.    Via: SIP/2.0/UDP 192.168.199.209;rport=5060;branch=z9hG4bKa7051520X2rre
  726.    Contact: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>
  727.    To: "1000"<sip:1000@192.168.199.209>;tag=9ec67706
  728.    From: <sip:9195@192.168.199.209>;tag=LnV1BKYn4PFh
  729.    Call-ID: 90533ZjNlOTc0MjM4NDM4YTFhNGZmYmM4NzBkMTRjNzI0MDc
  730.    CSeq: 254757303 NOTIFY
  731.    User-Agent: X-Lite release 5.2.0 stamp 90533
  732.    Content-Length: 0
  733.    
  734.    ------------------------------------------------------------------------
  735. send 1306 bytes to udp/[192.168.199.113]:64236 at 23:19:06.316684:
  736.    ------------------------------------------------------------------------
  737.    NOTIFY sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615 SIP/2.0
  738.    Via: SIP/2.0/UDP 192.168.199.209;rport;branch=z9hG4bKBgty30K4tBFBa
  739.    Max-Forwards: 70
  740.    From: <sip:9198@192.168.199.209>;tag=kzCMdrY7sEKH
  741.    To: "1000" <sip:1000@192.168.199.209>;tag=0d01e725
  742.    Call-ID: 90533OGQ2ZTU1OWM4MDE5OGM3YmU2YmVhZjg5NmI5OGM5OTU
  743.    CSeq: 254757304 NOTIFY
  744.    Contact: <sip:9198@192.168.199.209:5060>
  745.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  746.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  747.    Supported: timer, path, replaces
  748.    Event: presence
  749.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  750.    Subscription-State: active;expires=3600
  751.    Content-Type: application/pidf+xml
  752.    Content-Length: 439
  753.    
  754.    <?xml version="1.0" encoding="ISO-8859-1"?>
  755.    <presence xmlns='urn:ietf:params:xml:ns:pidf'
  756.    xmlns:dm='urn:ietf:params:xml:ns:pidf:data-model'
  757.    xmlns:rpid='urn:ietf:params:xml:ns:pidf:rpid'
  758.    xmlns:c='urn:ietf:params:xml:ns:pidf:cipid' entity='sip:9198@192.168.199.209'>
  759.     <tuple id='t6a5ed77e'>
  760.      <status>
  761.       <basic>closed</basic>
  762.      </status>
  763.     </tuple>
  764.     <dm:person id='p06360c4a'>
  765.      <dm:note>Unregistered</dm:note>
  766.     </dm:person>
  767.    </presence>
  768.  
  769.    ------------------------------------------------------------------------
  770. send 779 bytes to udp/[192.168.199.113]:64236 at 23:19:06.317162:
  771.    ------------------------------------------------------------------------
  772.    SIP/2.0 202 Accepted
  773.    Via: SIP/2.0/UDP 192.168.199.113:64236;branch=z9hG4bK-524287-1---385ac472de96b535;rport=64236
  774.    From: "1000" <sip:1000@192.168.199.209>;tag=b76edc0b
  775.    To: <sip:9181@192.168.199.209>;tag=gw839ZzKclCD
  776.    Call-ID: 90533NGJlNjhkODk3ZDMyMTg3Y2UwYzRkOGRlNjcxOGU3OGM
  777.    CSeq: 8 SUBSCRIBE
  778.    Contact: <sip:9181@192.168.199.209:5060>
  779.    Expires: 3600
  780.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  781.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  782.    Supported: timer, path, replaces
  783.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  784.    Subscription-State: active;expires=3600
  785.    Content-Length: 0
  786.    
  787.    ------------------------------------------------------------------------
  788. recv 411 bytes from udp/[192.168.199.113]:64236 at 23:19:06.317870:
  789.    ------------------------------------------------------------------------
  790.    SIP/2.0 200 OK
  791.    Via: SIP/2.0/UDP 192.168.199.209;rport=5060;branch=z9hG4bKBgty30K4tBFBa
  792.    Contact: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>
  793.    To: "1000"<sip:1000@192.168.199.209>;tag=0d01e725
  794.    From: <sip:9198@192.168.199.209>;tag=kzCMdrY7sEKH
  795.    Call-ID: 90533OGQ2ZTU1OWM4MDE5OGM3YmU2YmVhZjg5NmI5OGM5OTU
  796.    CSeq: 254757304 NOTIFY
  797.    User-Agent: X-Lite release 5.2.0 stamp 90533
  798.    Content-Length: 0
  799.    
  800.    ------------------------------------------------------------------------
  801. send 779 bytes to udp/[192.168.199.113]:64236 at 23:19:06.318231:
  802.    ------------------------------------------------------------------------
  803.    SIP/2.0 202 Accepted
  804.    Via: SIP/2.0/UDP 192.168.199.113:64236;branch=z9hG4bK-524287-1---54f0b6228840b239;rport=64236
  805.    From: "1000" <sip:1000@192.168.199.209>;tag=183b786d
  806.    To: <sip:9183@192.168.199.209>;tag=VrRP6nXhMpUQ
  807.    Call-ID: 90533NzFlNTMwYjE0ZGNjZTQyZTcyYTY1YjViYmEzZWQzYTA
  808.    CSeq: 8 SUBSCRIBE
  809.    Contact: <sip:9183@192.168.199.209:5060>
  810.    Expires: 3600
  811.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  812.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  813.    Supported: timer, path, replaces
  814.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  815.    Subscription-State: active;expires=3600
  816.    Content-Length: 0
  817.    
  818.    ------------------------------------------------------------------------
  819. send 779 bytes to udp/[192.168.199.113]:64236 at 23:19:06.319125:
  820.    ------------------------------------------------------------------------
  821.    SIP/2.0 202 Accepted
  822.    Via: SIP/2.0/UDP 192.168.199.113:64236;branch=z9hG4bK-524287-1---943df604c4b2d55b;rport=64236
  823.    From: "1000" <sip:1000@192.168.199.209>;tag=de06aa66
  824.    To: <sip:9184@192.168.199.209>;tag=HJOZdoxjLwdK
  825.    Call-ID: 90533ODM2ZGNkNDUwZGRhZjZkZmEwYjIxZjc1ZTJkMTQ1ZDk
  826.    CSeq: 8 SUBSCRIBE
  827.    Contact: <sip:9184@192.168.199.209:5060>
  828.    Expires: 3600
  829.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  830.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  831.    Supported: timer, path, replaces
  832.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  833.    Subscription-State: active;expires=3600
  834.    Content-Length: 0
  835.    
  836.    ------------------------------------------------------------------------
  837. send 1306 bytes to udp/[192.168.199.113]:64236 at 23:19:06.319404:
  838.    ------------------------------------------------------------------------
  839.    NOTIFY sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615 SIP/2.0
  840.    Via: SIP/2.0/UDP 192.168.199.209;rport;branch=z9hG4bKcSKQ5U47Qm5XN
  841.    Max-Forwards: 70
  842.    From: <sip:9181@192.168.199.209>;tag=gw839ZzKclCD
  843.    To: "1000" <sip:1000@192.168.199.209>;tag=b76edc0b
  844.    Call-ID: 90533NGJlNjhkODk3ZDMyMTg3Y2UwYzRkOGRlNjcxOGU3OGM
  845.    CSeq: 254757305 NOTIFY
  846.    Contact: <sip:9181@192.168.199.209:5060>
  847.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  848.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  849.    Supported: timer, path, replaces
  850.    Event: presence
  851.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  852.    Subscription-State: active;expires=3600
  853.    Content-Type: application/pidf+xml
  854.    Content-Length: 439
  855.    
  856.    <?xml version="1.0" encoding="ISO-8859-1"?>
  857.    <presence xmlns='urn:ietf:params:xml:ns:pidf'
  858.    xmlns:dm='urn:ietf:params:xml:ns:pidf:data-model'
  859.    xmlns:rpid='urn:ietf:params:xml:ns:pidf:rpid'
  860.    xmlns:c='urn:ietf:params:xml:ns:pidf:cipid' entity='sip:9181@192.168.199.209'>
  861.     <tuple id='t6a5ed77e'>
  862.      <status>
  863.       <basic>closed</basic>
  864.      </status>
  865.     </tuple>
  866.     <dm:person id='p06360c4a'>
  867.      <dm:note>Unregistered</dm:note>
  868.     </dm:person>
  869.    </presence>
  870.  
  871.    ------------------------------------------------------------------------
  872. send 779 bytes to udp/[192.168.199.113]:64236 at 23:19:06.320535:
  873.    ------------------------------------------------------------------------
  874.    SIP/2.0 202 Accepted
  875.    Via: SIP/2.0/UDP 192.168.199.113:64236;branch=z9hG4bK-524287-1---cc1e8f06a5af276f;rport=64236
  876.    From: "1000" <sip:1000@192.168.199.209>;tag=e7841062
  877.    To: <sip:5000@192.168.199.209>;tag=mDloONSA4Ag1
  878.    Call-ID: 90533YTM1NDAzNjkwMmM4Zjk1NDM1NWVkNjllMDBiZGY3MjY
  879.    CSeq: 8 SUBSCRIBE
  880.    Contact: <sip:5000@192.168.199.209:5060>
  881.    Expires: 3600
  882.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  883.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  884.    Supported: timer, path, replaces
  885.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  886.    Subscription-State: active;expires=3600
  887.    Content-Length: 0
  888.    
  889.    ------------------------------------------------------------------------
  890. recv 411 bytes from udp/[192.168.199.113]:64236 at 23:19:06.320702:
  891.    ------------------------------------------------------------------------
  892.    SIP/2.0 200 OK
  893.    Via: SIP/2.0/UDP 192.168.199.209;rport=5060;branch=z9hG4bKcSKQ5U47Qm5XN
  894.    Contact: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>
  895.    To: "1000"<sip:1000@192.168.199.209>;tag=b76edc0b
  896.    From: <sip:9181@192.168.199.209>;tag=gw839ZzKclCD
  897.    Call-ID: 90533NGJlNjhkODk3ZDMyMTg3Y2UwYzRkOGRlNjcxOGU3OGM
  898.    CSeq: 254757305 NOTIFY
  899.    User-Agent: X-Lite release 5.2.0 stamp 90533
  900.    Content-Length: 0
  901.    
  902.    ------------------------------------------------------------------------
  903. send 1306 bytes to udp/[192.168.199.113]:64236 at 23:19:06.321182:
  904.    ------------------------------------------------------------------------
  905.    NOTIFY sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615 SIP/2.0
  906.    Via: SIP/2.0/UDP 192.168.199.209;rport;branch=z9hG4bKD2cg7pNBNXUgH
  907.    Max-Forwards: 70
  908.    From: <sip:9183@192.168.199.209>;tag=VrRP6nXhMpUQ
  909.    To: "1000" <sip:1000@192.168.199.209>;tag=183b786d
  910.    Call-ID: 90533NzFlNTMwYjE0ZGNjZTQyZTcyYTY1YjViYmEzZWQzYTA
  911.    CSeq: 254757306 NOTIFY
  912.    Contact: <sip:9183@192.168.199.209:5060>
  913.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  914.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  915.    Supported: timer, path, replaces
  916.    Event: presence
  917.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  918.    Subscription-State: active;expires=3600
  919.    Content-Type: application/pidf+xml
  920.    Content-Length: 439
  921.    
  922.    <?xml version="1.0" encoding="ISO-8859-1"?>
  923.    <presence xmlns='urn:ietf:params:xml:ns:pidf'
  924.    xmlns:dm='urn:ietf:params:xml:ns:pidf:data-model'
  925.    xmlns:rpid='urn:ietf:params:xml:ns:pidf:rpid'
  926.    xmlns:c='urn:ietf:params:xml:ns:pidf:cipid' entity='sip:9183@192.168.199.209'>
  927.     <tuple id='t6a5ed77e'>
  928.      <status>
  929.       <basic>closed</basic>
  930.      </status>
  931.     </tuple>
  932.     <dm:person id='p06360c4a'>
  933.      <dm:note>Unregistered</dm:note>
  934.     </dm:person>
  935.    </presence>
  936.  
  937.    ------------------------------------------------------------------------
  938. recv 411 bytes from udp/[192.168.199.113]:64236 at 23:19:06.322133:
  939.    ------------------------------------------------------------------------
  940.    SIP/2.0 200 OK
  941.    Via: SIP/2.0/UDP 192.168.199.209;rport=5060;branch=z9hG4bKD2cg7pNBNXUgH
  942.    Contact: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>
  943.    To: "1000"<sip:1000@192.168.199.209>;tag=183b786d
  944.    From: <sip:9183@192.168.199.209>;tag=VrRP6nXhMpUQ
  945.    Call-ID: 90533NzFlNTMwYjE0ZGNjZTQyZTcyYTY1YjViYmEzZWQzYTA
  946.    CSeq: 254757306 NOTIFY
  947.    User-Agent: X-Lite release 5.2.0 stamp 90533
  948.    Content-Length: 0
  949.    
  950.    ------------------------------------------------------------------------
  951. send 779 bytes to udp/[192.168.199.113]:64236 at 23:19:06.323387:
  952.    ------------------------------------------------------------------------
  953.    SIP/2.0 202 Accepted
  954.    Via: SIP/2.0/UDP 192.168.199.113:64236;branch=z9hG4bK-524287-1---278f0273c9b8135c;rport=64236
  955.    From: "1000" <sip:1000@192.168.199.209>;tag=1e8f204f
  956.    To: <sip:4000@192.168.199.209>;tag=N39qjU7RtLHx
  957.    Call-ID: 90533MWQ0NWZiZjk5ZmJkNzI4ODBhMjU1N2FhYmYwMDFhZTc
  958.    CSeq: 8 SUBSCRIBE
  959.    Contact: <sip:4000@192.168.199.209:5060>
  960.    Expires: 3600
  961.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  962.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  963.    Supported: timer, path, replaces
  964.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  965.    Subscription-State: active;expires=3600
  966.    Content-Length: 0
  967.    
  968.    ------------------------------------------------------------------------
  969. send 1306 bytes to udp/[192.168.199.113]:64236 at 23:19:06.324264:
  970.    ------------------------------------------------------------------------
  971.    NOTIFY sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615 SIP/2.0
  972.    Via: SIP/2.0/UDP 192.168.199.209;rport;branch=z9hG4bKeB688H6ej6H3c
  973.    Max-Forwards: 70
  974.    From: <sip:9184@192.168.199.209>;tag=HJOZdoxjLwdK
  975.    To: "1000" <sip:1000@192.168.199.209>;tag=de06aa66
  976.    Call-ID: 90533ODM2ZGNkNDUwZGRhZjZkZmEwYjIxZjc1ZTJkMTQ1ZDk
  977.    CSeq: 254757307 NOTIFY
  978.    Contact: <sip:9184@192.168.199.209:5060>
  979.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  980.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  981.    Supported: timer, path, replaces
  982.    Event: presence
  983.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  984.    Subscription-State: active;expires=3600
  985.    Content-Type: application/pidf+xml
  986.    Content-Length: 439
  987.    
  988.    <?xml version="1.0" encoding="ISO-8859-1"?>
  989.    <presence xmlns='urn:ietf:params:xml:ns:pidf'
  990.    xmlns:dm='urn:ietf:params:xml:ns:pidf:data-model'
  991.    xmlns:rpid='urn:ietf:params:xml:ns:pidf:rpid'
  992.    xmlns:c='urn:ietf:params:xml:ns:pidf:cipid' entity='sip:9184@192.168.199.209'>
  993.     <tuple id='t6a5ed77e'>
  994.      <status>
  995.       <basic>closed</basic>
  996.      </status>
  997.     </tuple>
  998.     <dm:person id='p06360c4a'>
  999.      <dm:note>Unregistered</dm:note>
  1000.     </dm:person>
  1001.    </presence>
  1002.  
  1003.    ------------------------------------------------------------------------
  1004. send 779 bytes to udp/[192.168.199.113]:64236 at 23:19:06.324807:
  1005.    ------------------------------------------------------------------------
  1006.    SIP/2.0 202 Accepted
  1007.    Via: SIP/2.0/UDP 192.168.199.113:64236;branch=z9hG4bK-524287-1---06571717c1ac073a;rport=64236
  1008.    From: "1000" <sip:1000@192.168.199.209>;tag=53819c6d
  1009.    To: <sip:1001@192.168.199.209>;tag=Z4GBrKbDdmSR
  1010.    Call-ID: 90533M2VjZDgwMTFhOTA2ODdjNmYwMThmMThkOWM0M2M4Yzc
  1011.    CSeq: 8 SUBSCRIBE
  1012.    Contact: <sip:1001@192.168.199.209:5060>
  1013.    Expires: 3600
  1014.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  1015.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1016.    Supported: timer, path, replaces
  1017.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  1018.    Subscription-State: active;expires=3600
  1019.    Content-Length: 0
  1020.    
  1021.    ------------------------------------------------------------------------
  1022. recv 411 bytes from udp/[192.168.199.113]:64236 at 23:19:06.325333:
  1023.    ------------------------------------------------------------------------
  1024.    SIP/2.0 200 OK
  1025.    Via: SIP/2.0/UDP 192.168.199.209;rport=5060;branch=z9hG4bKeB688H6ej6H3c
  1026.    Contact: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>
  1027.    To: "1000"<sip:1000@192.168.199.209>;tag=de06aa66
  1028.    From: <sip:9184@192.168.199.209>;tag=HJOZdoxjLwdK
  1029.    Call-ID: 90533ODM2ZGNkNDUwZGRhZjZkZmEwYjIxZjc1ZTJkMTQ1ZDk
  1030.    CSeq: 254757307 NOTIFY
  1031.    User-Agent: X-Lite release 5.2.0 stamp 90533
  1032.    Content-Length: 0
  1033.    
  1034.    ------------------------------------------------------------------------
  1035. send 1306 bytes to udp/[192.168.199.113]:64236 at 23:19:06.325553:
  1036.    ------------------------------------------------------------------------
  1037.    NOTIFY sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615 SIP/2.0
  1038.    Via: SIP/2.0/UDP 192.168.199.209;rport;branch=z9hG4bKFmZ1aDQjFF8Nr
  1039.    Max-Forwards: 70
  1040.    From: <sip:5000@192.168.199.209>;tag=mDloONSA4Ag1
  1041.    To: "1000" <sip:1000@192.168.199.209>;tag=e7841062
  1042.    Call-ID: 90533YTM1NDAzNjkwMmM4Zjk1NDM1NWVkNjllMDBiZGY3MjY
  1043.    CSeq: 254757308 NOTIFY
  1044.    Contact: <sip:5000@192.168.199.209:5060>
  1045.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  1046.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1047.    Supported: timer, path, replaces
  1048.    Event: presence
  1049.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  1050.    Subscription-State: active;expires=3600
  1051.    Content-Type: application/pidf+xml
  1052.    Content-Length: 439
  1053.    
  1054.    <?xml version="1.0" encoding="ISO-8859-1"?>
  1055.    <presence xmlns='urn:ietf:params:xml:ns:pidf'
  1056.    xmlns:dm='urn:ietf:params:xml:ns:pidf:data-model'
  1057.    xmlns:rpid='urn:ietf:params:xml:ns:pidf:rpid'
  1058.    xmlns:c='urn:ietf:params:xml:ns:pidf:cipid' entity='sip:5000@192.168.199.209'>
  1059.     <tuple id='t6a5ed77e'>
  1060.      <status>
  1061.       <basic>closed</basic>
  1062.      </status>
  1063.     </tuple>
  1064.     <dm:person id='p06360c4a'>
  1065.      <dm:note>Unregistered</dm:note>
  1066.     </dm:person>
  1067.    </presence>
  1068.  
  1069.    ------------------------------------------------------------------------
  1070. recv 411 bytes from udp/[192.168.199.113]:64236 at 23:19:06.326540:
  1071.    ------------------------------------------------------------------------
  1072.    SIP/2.0 200 OK
  1073.    Via: SIP/2.0/UDP 192.168.199.209;rport=5060;branch=z9hG4bKFmZ1aDQjFF8Nr
  1074.    Contact: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>
  1075.    To: "1000"<sip:1000@192.168.199.209>;tag=e7841062
  1076.    From: <sip:5000@192.168.199.209>;tag=mDloONSA4Ag1
  1077.    Call-ID: 90533YTM1NDAzNjkwMmM4Zjk1NDM1NWVkNjllMDBiZGY3MjY
  1078.    CSeq: 254757308 NOTIFY
  1079.    User-Agent: X-Lite release 5.2.0 stamp 90533
  1080.    Content-Length: 0
  1081.    
  1082.    ------------------------------------------------------------------------
  1083. send 1306 bytes to udp/[192.168.199.113]:64236 at 23:19:06.327542:
  1084.    ------------------------------------------------------------------------
  1085.    NOTIFY sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615 SIP/2.0
  1086.    Via: SIP/2.0/UDP 192.168.199.209;rport;branch=z9hG4bKgXrtc87Ncry8K
  1087.    Max-Forwards: 70
  1088.    From: <sip:4000@192.168.199.209>;tag=N39qjU7RtLHx
  1089.    To: "1000" <sip:1000@192.168.199.209>;tag=1e8f204f
  1090.    Call-ID: 90533MWQ0NWZiZjk5ZmJkNzI4ODBhMjU1N2FhYmYwMDFhZTc
  1091.    CSeq: 254757309 NOTIFY
  1092.    Contact: <sip:4000@192.168.199.209:5060>
  1093.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  1094.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1095.    Supported: timer, path, replaces
  1096.    Event: presence
  1097.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  1098.    Subscription-State: active;expires=3600
  1099.    Content-Type: application/pidf+xml
  1100.    Content-Length: 439
  1101.    
  1102.    <?xml version="1.0" encoding="ISO-8859-1"?>
  1103.    <presence xmlns='urn:ietf:params:xml:ns:pidf'
  1104.    xmlns:dm='urn:ietf:params:xml:ns:pidf:data-model'
  1105.    xmlns:rpid='urn:ietf:params:xml:ns:pidf:rpid'
  1106.    xmlns:c='urn:ietf:params:xml:ns:pidf:cipid' entity='sip:4000@192.168.199.209'>
  1107.     <tuple id='t6a5ed77e'>
  1108.      <status>
  1109.       <basic>closed</basic>
  1110.      </status>
  1111.     </tuple>
  1112.     <dm:person id='p06360c4a'>
  1113.      <dm:note>Unregistered</dm:note>
  1114.     </dm:person>
  1115.    </presence>
  1116.  
  1117.    ------------------------------------------------------------------------
  1118. recv 411 bytes from udp/[192.168.199.113]:64236 at 23:19:06.328497:
  1119.    ------------------------------------------------------------------------
  1120.    SIP/2.0 200 OK
  1121.    Via: SIP/2.0/UDP 192.168.199.209;rport=5060;branch=z9hG4bKgXrtc87Ncry8K
  1122.    Contact: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>
  1123.    To: "1000"<sip:1000@192.168.199.209>;tag=1e8f204f
  1124.    From: <sip:4000@192.168.199.209>;tag=N39qjU7RtLHx
  1125.    Call-ID: 90533MWQ0NWZiZjk5ZmJkNzI4ODBhMjU1N2FhYmYwMDFhZTc
  1126.    CSeq: 254757309 NOTIFY
  1127.    User-Agent: X-Lite release 5.2.0 stamp 90533
  1128.    Content-Length: 0
  1129.    
  1130.    ------------------------------------------------------------------------
  1131. send 1301 bytes to udp/[192.168.199.113]:64236 at 23:19:06.425054:
  1132.    ------------------------------------------------------------------------
  1133.    NOTIFY sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615 SIP/2.0
  1134.    Via: SIP/2.0/UDP 192.168.199.209;rport;branch=z9hG4bKH6HKe3rS90mUF
  1135.    Max-Forwards: 70
  1136.    From: <sip:1001@192.168.199.209>;tag=Z4GBrKbDdmSR
  1137.    To: "1000" <sip:1000@192.168.199.209>;tag=53819c6d
  1138.    Call-ID: 90533M2VjZDgwMTFhOTA2ODdjNmYwMThmMThkOWM0M2M4Yzc
  1139.    CSeq: 254757310 NOTIFY
  1140.    Contact: <sip:1001@192.168.199.209:5060>
  1141.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  1142.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1143.    Supported: timer, path, replaces
  1144.    Event: presence
  1145.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  1146.    Subscription-State: active;expires=3600
  1147.    Content-Type: application/pidf+xml
  1148.    Content-Length: 434
  1149.    
  1150.    <?xml version="1.0" encoding="ISO-8859-1"?>
  1151.    <presence xmlns='urn:ietf:params:xml:ns:pidf'
  1152.    xmlns:dm='urn:ietf:params:xml:ns:pidf:data-model'
  1153.    xmlns:rpid='urn:ietf:params:xml:ns:pidf:rpid'
  1154.    xmlns:c='urn:ietf:params:xml:ns:pidf:cipid' entity='sip:1001@192.168.199.209'>
  1155.     <tuple id='t6a5ed77e'>
  1156.      <status>
  1157.       <basic>open</basic>
  1158.      </status>
  1159.     </tuple>
  1160.     <dm:person id='p06360c4a'>
  1161.      <dm:note>Available</dm:note>
  1162.     </dm:person>
  1163.    </presence>
  1164.  
  1165.    ------------------------------------------------------------------------
  1166. recv 411 bytes from udp/[192.168.199.113]:64236 at 23:19:06.427017:
  1167.    ------------------------------------------------------------------------
  1168.    SIP/2.0 200 OK
  1169.    Via: SIP/2.0/UDP 192.168.199.209;rport=5060;branch=z9hG4bKH6HKe3rS90mUF
  1170.    Contact: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>
  1171.    To: "1000"<sip:1000@192.168.199.209>;tag=53819c6d
  1172.    From: <sip:1001@192.168.199.209>;tag=Z4GBrKbDdmSR
  1173.    Call-ID: 90533M2VjZDgwMTFhOTA2ODdjNmYwMThmMThkOWM0M2M4Yzc
  1174.    CSeq: 254757310 NOTIFY
  1175.    User-Agent: X-Lite release 5.2.0 stamp 90533
  1176.    Content-Length: 0
  1177.    
  1178.    ------------------------------------------------------------------------
  1179. EXECUTE sofia/internal/1001@192.168.199.209 hash(insert/192.168.199.209-spymap/1001/b2fb430e-1c9a-11e8-b916-1f706e5b1202)
  1180. EXECUTE sofia/internal/1001@192.168.199.209 hash(insert/192.168.199.209-last_dial/1001/1000)
  1181. EXECUTE sofia/internal/1001@192.168.199.209 hash(insert/192.168.199.209-last_dial/global/b2fb430e-1c9a-11e8-b916-1f706e5b1202)
  1182. EXECUTE sofia/internal/1001@192.168.199.209 export(RFC2822_DATE=Wed, 28 Feb 2018 23:19:09 +0800)
  1183. 2018-02-28 23:19:09.177355 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Wed, 28 Feb 2018 23:19:09 +0800]
  1184. EXECUTE sofia/internal/1001@192.168.199.209 export(dialed_extension=1000)
  1185. 2018-02-28 23:19:09.177355 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [dialed_extension]=[1000]
  1186. EXECUTE sofia/internal/1001@192.168.199.209 bind_meta_app(1 b s execute_extension::dx XML features)
  1187. 2018-02-28 23:19:09.177355 [INFO] switch_ivr_async.c:4171 Bound B-Leg: *1 execute_extension::dx XML features
  1188. EXECUTE sofia/internal/1001@192.168.199.209 bind_meta_app(2 b s record_session::/root/freeswitch/var/lib/freeswitch/recordings/1001.2018-02-28-23-19-09.wav)
  1189. 2018-02-28 23:19:09.177355 [INFO] switch_ivr_async.c:4171 Bound B-Leg: *2 record_session::/root/freeswitch/var/lib/freeswitch/recordings/1001.2018-02-28-23-19-09.wav
  1190. EXECUTE sofia/internal/1001@192.168.199.209 bind_meta_app(3 b s execute_extension::cf XML features)
  1191. 2018-02-28 23:19:09.177355 [INFO] switch_ivr_async.c:4171 Bound B-Leg: *3 execute_extension::cf XML features
  1192. EXECUTE sofia/internal/1001@192.168.199.209 bind_meta_app(4 b s execute_extension::att_xfer XML features)
  1193. 2018-02-28 23:19:09.177355 [INFO] switch_ivr_async.c:4171 Bound B-Leg: *4 execute_extension::att_xfer XML features
  1194. EXECUTE sofia/internal/1001@192.168.199.209 set(ringback=%(2000,4000,440,480))
  1195. 2018-02-28 23:19:09.177355 [DEBUG] mod_dptools.c:1530 SET sofia/internal/1001@192.168.199.209 [ringback]=[%(2000,4000,440,480)]
  1196. EXECUTE sofia/internal/1001@192.168.199.209 set(transfer_ringback=local_stream://moh)
  1197. 2018-02-28 23:19:09.177355 [DEBUG] mod_dptools.c:1530 SET sofia/internal/1001@192.168.199.209 [transfer_ringback]=[local_stream://moh]
  1198. EXECUTE sofia/internal/1001@192.168.199.209 set(call_timeout=30)
  1199. 2018-02-28 23:19:09.177355 [DEBUG] mod_dptools.c:1530 SET sofia/internal/1001@192.168.199.209 [call_timeout]=[30]
  1200. EXECUTE sofia/internal/1001@192.168.199.209 set(hangup_after_bridge=true)
  1201. 2018-02-28 23:19:09.177355 [DEBUG] mod_dptools.c:1530 SET sofia/internal/1001@192.168.199.209 [hangup_after_bridge]=[true]
  1202. EXECUTE sofia/internal/1001@192.168.199.209 set(continue_on_fail=true)
  1203. 2018-02-28 23:19:09.177355 [DEBUG] mod_dptools.c:1530 SET sofia/internal/1001@192.168.199.209 [continue_on_fail]=[true]
  1204. EXECUTE sofia/internal/1001@192.168.199.209 hash(insert/192.168.199.209-call_return/1000/1001)
  1205. EXECUTE sofia/internal/1001@192.168.199.209 hash(insert/192.168.199.209-last_dial_ext/1000/b2fb430e-1c9a-11e8-b916-1f706e5b1202)
  1206. EXECUTE sofia/internal/1001@192.168.199.209 set(called_party_callgroup=techsupport)
  1207. 2018-02-28 23:19:09.177355 [DEBUG] mod_dptools.c:1530 SET sofia/internal/1001@192.168.199.209 [called_party_callgroup]=[techsupport]
  1208. EXECUTE sofia/internal/1001@192.168.199.209 hash(insert/192.168.199.209-last_dial_ext/techsupport/b2fb430e-1c9a-11e8-b916-1f706e5b1202)
  1209. EXECUTE sofia/internal/1001@192.168.199.209 hash(insert/192.168.199.209-last_dial_ext/global/b2fb430e-1c9a-11e8-b916-1f706e5b1202)
  1210. EXECUTE sofia/internal/1001@192.168.199.209 hash(insert/192.168.199.209-last_dial/techsupport/b2fb430e-1c9a-11e8-b916-1f706e5b1202)
  1211. EXECUTE sofia/internal/1001@192.168.199.209 bridge(user/1000@192.168.199.209)
  1212. 2018-02-28 23:19:09.177355 [DEBUG] switch_channel.c:1250 sofia/internal/1001@192.168.199.209 EXPORTING[export_vars] [RFC2822_DATE]=[Wed, 28 Feb 2018 23:19:09 +0800] to event
  1213. 2018-02-28 23:19:09.177355 [DEBUG] switch_channel.c:1250 sofia/internal/1001@192.168.199.209 EXPORTING[export_vars] [dialed_extension]=[1000] to event
  1214. 2018-02-28 23:19:09.177355 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  1215. 2018-02-28 23:19:09.177355 [DEBUG] switch_channel.c:1250 sofia/internal/1001@192.168.199.209 EXPORTING[export_vars] [RFC2822_DATE]=[Wed, 28 Feb 2018 23:19:09 +0800] to event
  1216. 2018-02-28 23:19:09.177355 [DEBUG] switch_channel.c:1250 sofia/internal/1001@192.168.199.209 EXPORTING[export_vars] [dialed_extension]=[1000] to event
  1217. 2018-02-28 23:19:09.177355 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  1218. 2018-02-28 23:19:09.177355 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1000@192.168.199.113:64236 [b92affda-1c9a-11e8-b939-1f706e5b1202]
  1219. 2018-02-28 23:19:09.177355 [DEBUG] mod_sofia.c:4818 (sofia/internal/1000@192.168.199.113:64236) State Change CS_NEW -> CS_INIT
  1220. 2018-02-28 23:19:09.177355 [NOTICE] switch_ivr_originate.c:2845 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
  1221. 2018-02-28 23:19:09.177355 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@192.168.199.113:64236) Running State Change CS_INIT (Cur 2 Tot 37)
  1222. 2018-02-28 23:19:09.177355 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1000@192.168.199.113:64236) State INIT
  1223. 2018-02-28 23:19:09.177355 [DEBUG] mod_sofia.c:90 sofia/internal/1000@192.168.199.113:64236 SOFIA INIT
  1224. 2018-02-28 23:19:09.177355 [DEBUG] sofia_glue.c:1295 sofia/internal/1000@192.168.199.113:64236 sending invite version: 1.6.17  64bit
  1225. Local SDP:
  1226. v=0
  1227. o=FreeSWITCH 1519811797 1519811798 IN IP4 192.168.199.209
  1228. s=FreeSWITCH
  1229. c=IN IP4 192.168.199.209
  1230. t=0 0
  1231. m=audio 19352 RTP/AVP 0 8 101
  1232. a=rtpmap:0 PCMU/8000
  1233. a=rtpmap:8 PCMA/8000
  1234. a=rtpmap:101 telephone-event/8000
  1235. a=fmtp:101 0-16
  1236. a=ptime:20
  1237. a=sendrecv
  1238.  
  1239. 2018-02-28 23:19:09.177355 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1000@192.168.199.113:64236 Standard INIT
  1240. 2018-02-28 23:19:09.177355 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1000@192.168.199.113:64236) State Change CS_INIT -> CS_ROUTING
  1241. 2018-02-28 23:19:09.177355 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1000@192.168.199.113:64236) State INIT going to sleep
  1242. 2018-02-28 23:19:09.177355 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@192.168.199.113:64236) Running State Change CS_ROUTING (Cur 2 Tot 37)
  1243. send 1247 bytes to udp/[192.168.199.113]:64236 at 23:19:09.195676:
  1244.    ------------------------------------------------------------------------
  1245.    INVITE sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615 SIP/2.0
  1246.    Via: SIP/2.0/UDP 192.168.199.209;rport;branch=z9hG4bKjFBcgy9v69aeB
  1247.    Max-Forwards: 69
  1248.    From: "Extension 1001" <sip:1001@192.168.199.209>;tag=6Z0vN9K1UtB1p
  1249.    To: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>
  1250.    Call-ID: 90896b6d-973d-1236-83b5-000c295e44db
  1251.    CSeq: 119579510 INVITE
  1252.    Contact: <sip:mod_sofia@192.168.199.209:5060>
  1253.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  1254.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1255.    Supported: timer, path, replaces
  1256.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  1257.    Content-Type: application/sdp
  1258.    Content-Disposition: session
  1259.    Content-Length: 250
  1260.    X-FS-Support: update_display,send_info
  1261.    Remote-Party-ID: "Extension 1001" <sip:1001@192.168.199.209>;party=calling;screen=yes;privacy=off
  1262.    
  1263.    v=0
  1264.    o=FreeSWITCH 1519811797 1519811798 IN IP4 192.168.199.209
  1265.    s=FreeSWITCH
  1266.    c=IN IP4 192.168.199.209
  1267.    t=0 0
  1268.    m=audio 19352 RTP/AVP 0 8 101
  1269.    a=rtpmap:0 PCMU/8000
  1270.    a=rtpmap:8 PCMA/8000
  1271.    a=rtpmap:101 telephone-event/8000
  1272.    a=fmtp:101 0-16
  1273.    a=ptime:20
  1274.    ------------------------------------------------------------------------
  1275. 2018-02-28 23:19:09.177355 [DEBUG] sofia.c:7048 Channel sofia/internal/1000@192.168.199.113:64236 entering state [calling][0]
  1276. 2018-02-28 23:19:09.177355 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1000@192.168.199.113:64236) State ROUTING
  1277. 2018-02-28 23:19:09.177355 [DEBUG] mod_sofia.c:143 sofia/internal/1000@192.168.199.113:64236 SOFIA ROUTING
  1278. 2018-02-28 23:19:09.177355 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/1000@192.168.199.113:64236) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  1279. 2018-02-28 23:19:09.177355 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1000@192.168.199.113:64236) State ROUTING going to sleep
  1280. 2018-02-28 23:19:09.177355 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@192.168.199.113:64236) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 37)
  1281. 2018-02-28 23:19:09.177355 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1000@192.168.199.113:64236) State CONSUME_MEDIA
  1282. 2018-02-28 23:19:09.177355 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1000@192.168.199.113:64236) State CONSUME_MEDIA going to sleep
  1283. recv 481 bytes from udp/[192.168.199.113]:64236 at 23:19:09.268445:
  1284.    ------------------------------------------------------------------------
  1285.    SIP/2.0 180 Ringing
  1286.    Via: SIP/2.0/UDP 192.168.199.209;rport=5060;branch=z9hG4bKjFBcgy9v69aeB
  1287.    Contact: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>
  1288.    To: "1000"<sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>;tag=0df79c6e
  1289.    From: "Extension 1001" <sip:1001@192.168.199.209>;tag=6Z0vN9K1UtB1p
  1290.    Call-ID: 90896b6d-973d-1236-83b5-000c295e44db
  1291.    CSeq: 119579510 INVITE
  1292.    User-Agent: X-Lite release 5.2.0 stamp 90533
  1293.    Allow-Events: talk, hold
  1294.    Content-Length: 0
  1295.    
  1296.    ------------------------------------------------------------------------
  1297. 2018-02-28 23:19:09.257364 [DEBUG] sofia.c:7048 Channel sofia/internal/1000@192.168.199.113:64236 entering state [proceeding][180]
  1298. 2018-02-28 23:19:09.257364 [NOTICE] sofia.c:7156 Ring-Ready sofia/internal/1000@192.168.199.113:64236!
  1299. 2018-02-28 23:19:09.257364 [DEBUG] switch_channel.c:3345 (sofia/internal/1000@192.168.199.113:64236) Callstate Change DOWN -> RINGING
  1300. 2018-02-28 23:19:09.277369 [INFO] switch_ivr_originate.c:1215 Sending early media
  1301. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [speex:98:16000:20:0:1]/[opus:116:48000:20:0:1]
  1302. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [speex:98:16000:20:0:1]/[G722:9:8000:20:64000:1]
  1303. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [speex:98:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
  1304. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [speex:98:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
  1305. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [speex:97:8000:20:0:1]/[opus:116:48000:20:0:1]
  1306. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [speex:97:8000:20:0:1]/[G722:9:8000:20:64000:1]
  1307. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [speex:97:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  1308. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [speex:97:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  1309. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [speex:99:32000:20:0:1]/[opus:116:48000:20:0:1]
  1310. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [speex:99:32000:20:0:1]/[G722:9:8000:20:64000:1]
  1311. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [speex:99:32000:20:0:1]/[PCMU:0:8000:20:64000:1]
  1312. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [speex:99:32000:20:0:1]/[PCMA:8:8000:20:64000:1]
  1313. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [iLBC:104:8000:30:0:1]/[opus:116:48000:20:0:1]
  1314. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [iLBC:104:8000:30:0:1]/[G722:9:8000:20:64000:1]
  1315. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [iLBC:104:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
  1316. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [iLBC:104:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
  1317. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1]
  1318. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
  1319. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  1320. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  1321. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [GSM:3:8000:20:13200:1]/[opus:116:48000:20:0:1]
  1322. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G722:9:8000:20:64000:1]
  1323. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  1324. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  1325. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  1326. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  1327. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  1328. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4491 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  1329. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1330. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
  1331. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  1332. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  1333. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4436 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  1334. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4491 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  1335. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4352 Set telephone-event payload to 96@8000
  1336. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:3056 Set Codec sofia/internal/1001@192.168.199.209 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  1337. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_codec.c:111 sofia/internal/1001@192.168.199.209 Original read codec set to PCMU:0
  1338. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4695 Set telephone-event payload to 96@8000
  1339. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:4754 sofia/internal/1001@192.168.199.209 Set 2833 dtmf send payload to 96 recv payload to 96
  1340. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:6865 AUDIO RTP [sofia/internal/1001@192.168.199.209] 192.168.199.209 port 26514 -> 192.168.199.147 port 40002 codec: 0 ms: 20
  1341. 2018-02-28 23:19:09.277369 [DEBUG] switch_rtp.c:4096 Starting timer [soft] 160 bytes per 20ms
  1342. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:7085 Activating RTCP PORT 40003
  1343. 2018-02-28 23:19:09.277369 [DEBUG] switch_rtp.c:4488 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 40003
  1344. 2018-02-28 23:19:09.277369 [DEBUG] switch_rtp.c:2560 Setting RTCP remote addr to 192.168.199.147:40003 2
  1345. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:7166 sofia/internal/1001@192.168.199.209 Set 2833 dtmf send payload to 96
  1346. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:7173 sofia/internal/1001@192.168.199.209 Set 2833 dtmf receive payload to 96
  1347. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:7196 sofia/internal/1001@192.168.199.209 Set rtp dtmf delay to 40
  1348. 2018-02-28 23:19:09.277369 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/1001@192.168.199.209!
  1349. 2018-02-28 23:19:09.277369 [DEBUG] switch_channel.c:3473 (sofia/internal/1001@192.168.199.209) Callstate Change RINGING -> EARLY
  1350. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_media.c:6848 Audio params are unchanged for sofia/internal/1001@192.168.199.209.
  1351. 2018-02-28 23:19:09.277369 [DEBUG] mod_sofia.c:2363 Ring SDP:
  1352. v=0
  1353. o=FreeSWITCH 1519804635 1519804636 IN IP4 192.168.199.209
  1354. s=FreeSWITCH
  1355. c=IN IP4 192.168.199.209
  1356. t=0 0
  1357. m=audio 26514 RTP/AVP 0 96
  1358. a=rtpmap:0 PCMU/8000
  1359. a=rtpmap:96 telephone-event/8000
  1360. a=fmtp:96 0-16
  1361. a=ptime:20
  1362. a=sendrecv
  1363. a=rtcp:26515 IN IP4 192.168.199.209
  1364.  
  1365. 2018-02-28 23:19:09.277369 [DEBUG] switch_ivr_originate.c:1273 Raw Codec Activation Success L16@8000hz 1 channel 20ms
  1366. 2018-02-28 23:19:09.277369 [DEBUG] switch_core_codec.c:223 sofia/internal/1001@192.168.199.209 Push codec L16:100
  1367. 2018-02-28 23:19:09.277369 [DEBUG] switch_ivr_originate.c:1342 Play Ringback Tone [%(2000,4000,440,480)]
  1368. send 1187 bytes to udp/[192.168.199.147]:50452 at 23:19:09.283023:
  1369.    ------------------------------------------------------------------------
  1370.    SIP/2.0 183 Session Progress
  1371.    Via: SIP/2.0/UDP 192.168.199.147:50452;rport=50452;branch=z9hG4bKPjWwb7EBSODC0Uv9QMh4iITNWOfTYFdl4A
  1372.    From: sip:1001@192.168.199.209;tag=ncjBVBvrLoZYKZG9hB-Dw9pc-zMQ6-X0
  1373.    To: <sip:1000@192.168.199.209>;tag=5p73Ke3XyHNeB
  1374.    Call-ID: TLCkh5yLFXegYOSvMqEDr6xV1AxFjRNm
  1375.    CSeq: 6683 INVITE
  1376.    Contact: <sip:1000@192.168.199.209:5060;transport=udp>
  1377.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  1378.    Accept: application/sdp
  1379.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1380.    Supported: timer, path, replaces
  1381.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  1382.    Content-Type: application/sdp
  1383.    Content-Disposition: session
  1384.    Content-Length: 260
  1385.    Remote-Party-ID: "1000" <sip:1000@192.168.199.209>;party=calling;privacy=off;screen=no
  1386.    
  1387.    v=0
  1388.    o=FreeSWITCH 1519804635 1519804636 IN IP4 192.168.199.209
  1389.    s=FreeSWITCH
  1390.    c=IN IP4 192.168.199.209
  1391.    t=0 0
  1392.    m=audio 26514 RTP/AVP 0 96
  1393.    a=rtpmap:0 PCMU/8000
  1394.    a=rtpmap:96 telephone-event/8000
  1395.    a=fmtp:96 0-16
  1396.    a=ptime:20
  1397.    a=rtcp:26515 IN IP4 192.168.199.209
  1398.    ------------------------------------------------------------------------
  1399. 2018-02-28 23:19:09.297976 [DEBUG] sofia.c:7048 Channel sofia/internal/1001@192.168.199.209 entering state [early][183]
  1400. 2018-02-28 23:19:09.317497 [DEBUG] switch_rtp.c:6679 Correct audio RTCP ip/port confirmed.
  1401. 2018-02-28 23:19:09.337374 [DEBUG] switch_rtp.c:1885 rtcp_stats_init: audio ssrc[1547994498] base_seq[19063]
  1402. 2018-02-28 23:19:09.337374 [DEBUG] switch_rtp.c:7229 Correct audio ip/port confirmed.
  1403. recv 381 bytes from udp/[192.168.199.113]:64236 at 23:19:11.544613:
  1404.    ------------------------------------------------------------------------
  1405.    SIP/2.0 486 Busy Here
  1406.    Via: SIP/2.0/UDP 192.168.199.209;rport=5060;branch=z9hG4bKjFBcgy9v69aeB
  1407.    To: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>;tag=0df79c6e
  1408.    From: "Extension 1001" <sip:1001@192.168.199.209>;tag=6Z0vN9K1UtB1p
  1409.    Call-ID: 90896b6d-973d-1236-83b5-000c295e44db
  1410.    CSeq: 119579510 INVITE
  1411.    User-Agent: X-Lite release 5.2.0 stamp 90533
  1412.    Content-Length: 0
  1413.    
  1414.    ------------------------------------------------------------------------
  1415. send 393 bytes to udp/[192.168.199.113]:64236 at 23:19:11.545120:
  1416.    ------------------------------------------------------------------------
  1417.    ACK sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615 SIP/2.0
  1418.    Via: SIP/2.0/UDP 192.168.199.209;rport;branch=z9hG4bKjFBcgy9v69aeB
  1419.    Max-Forwards: 69
  1420.    From: "Extension 1001" <sip:1001@192.168.199.209>;tag=6Z0vN9K1UtB1p
  1421.    To: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>;tag=0df79c6e
  1422.    Call-ID: 90896b6d-973d-1236-83b5-000c295e44db
  1423.    CSeq: 119579510 ACK
  1424.    Content-Length: 0
  1425.    
  1426.    ------------------------------------------------------------------------
  1427. 2018-02-28 23:19:11.537460 [DEBUG] sofia.c:7048 Channel sofia/internal/1000@192.168.199.113:64236 entering state [terminated][486]
  1428. 2018-02-28 23:19:11.537460 [NOTICE] sofia.c:8237 Hangup sofia/internal/1000@192.168.199.113:64236 [CS_CONSUME_MEDIA] [USER_BUSY]
  1429. 2018-02-28 23:19:11.537460 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@192.168.199.113:64236) Running State Change CS_HANGUP (Cur 2 Tot 37)
  1430. 2018-02-28 23:19:11.537460 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1000@192.168.199.113:64236) Callstate Change RINGING -> HANGUP
  1431. 2018-02-28 23:19:11.537460 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1000@192.168.199.113:64236) State HANGUP
  1432. 2018-02-28 23:19:11.537460 [DEBUG] mod_sofia.c:438 Channel sofia/internal/1000@192.168.199.113:64236 hanging up, cause: USER_BUSY
  1433. 2018-02-28 23:19:11.537460 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1000@192.168.199.113:64236 Standard HANGUP, cause: USER_BUSY
  1434. 2018-02-28 23:19:11.537460 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1000@192.168.199.113:64236) State HANGUP going to sleep
  1435. 2018-02-28 23:19:11.537460 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1000@192.168.199.113:64236) State Change CS_HANGUP -> CS_REPORTING
  1436. 2018-02-28 23:19:11.537460 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1000@192.168.199.113:64236) Running State Change CS_REPORTING (Cur 2 Tot 37)
  1437. 2018-02-28 23:19:11.537460 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1000@192.168.199.113:64236) State REPORTING
  1438. 2018-02-28 23:19:11.537460 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1000@192.168.199.113:64236 Standard REPORTING, cause: USER_BUSY
  1439. 2018-02-28 23:19:11.537460 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1000@192.168.199.113:64236) State REPORTING going to sleep
  1440. 2018-02-28 23:19:11.537460 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1000@192.168.199.113:64236) State Change CS_REPORTING -> CS_DESTROY
  1441. 2018-02-28 23:19:11.537460 [DEBUG] switch_core_session.c:1664 Session 37 (sofia/internal/1000@192.168.199.113:64236) Locked, Waiting on external entities
  1442. 2018-02-28 23:19:11.559836 [DEBUG] switch_core_codec.c:248 sofia/internal/1001@192.168.199.209 Restore previous codec PCMU:0.
  1443. 2018-02-28 23:19:11.559836 [DEBUG] switch_ivr_originate.c:3833 Originate Resulted in Error Cause: 17 [USER_BUSY]
  1444. 2018-02-28 23:19:11.559836 [NOTICE] switch_core_session.c:1682 Session 37 (sofia/internal/1000@192.168.199.113:64236) Ended
  1445. 2018-02-28 23:19:11.559836 [NOTICE] switch_core_session.c:1686 Close Channel sofia/internal/1000@192.168.199.113:64236 [CS_DESTROY]
  1446. 2018-02-28 23:19:11.559836 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1000@192.168.199.113:64236) Running State Change CS_DESTROY (Cur 1 Tot 37)
  1447. 2018-02-28 23:19:11.559836 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1000@192.168.199.113:64236) State DESTROY
  1448. 2018-02-28 23:19:11.559836 [DEBUG] mod_sofia.c:343 sofia/internal/1000@192.168.199.113:64236 SOFIA DESTROY
  1449. 2018-02-28 23:19:11.559836 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1000@192.168.199.113:64236 Standard DESTROY
  1450. 2018-02-28 23:19:11.559836 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1000@192.168.199.113:64236) State DESTROY going to sleep
  1451. 2018-02-28 23:19:11.559836 [NOTICE] switch_ivr_originate.c:2845 Cannot create outgoing channel of type [user] cause: [USER_BUSY]
  1452. 2018-02-28 23:19:11.559836 [DEBUG] switch_ivr_originate.c:3833 Originate Resulted in Error Cause: 17 [USER_BUSY]
  1453. 2018-02-28 23:19:11.559836 [INFO] mod_dptools.c:3418 Originate Failed.  Cause: USER_BUSY
  1454. EXECUTE sofia/internal/1001@192.168.199.209 answer()
  1455. 2018-02-28 23:19:11.559836 [DEBUG] switch_core_media.c:6848 Audio params are unchanged for sofia/internal/1001@192.168.199.209.
  1456. 2018-02-28 23:19:11.559836 [DEBUG] mod_sofia.c:850 Local SDP sofia/internal/1001@192.168.199.209:
  1457. v=0
  1458. o=FreeSWITCH 1519804635 1519804637 IN IP4 192.168.199.209
  1459. s=FreeSWITCH
  1460. c=IN IP4 192.168.199.209
  1461. t=0 0
  1462. m=audio 26514 RTP/AVP 0 96
  1463. a=rtpmap:0 PCMU/8000
  1464. a=rtpmap:96 telephone-event/8000
  1465. a=fmtp:96 0-16
  1466. a=ptime:20
  1467. a=sendrecv
  1468. a=rtcp:26515 IN IP4 192.168.199.209
  1469.  
  1470. send 1201 bytes to udp/[192.168.199.147]:50452 at 23:19:11.565576:
  1471.    ------------------------------------------------------------------------
  1472.    SIP/2.0 200 OK
  1473.    Via: SIP/2.0/UDP 192.168.199.147:50452;rport=50452;branch=z9hG4bKPjWwb7EBSODC0Uv9QMh4iITNWOfTYFdl4A
  1474.    From: sip:1001@192.168.199.209;tag=ncjBVBvrLoZYKZG9hB-Dw9pc-zMQ6-X0
  1475.    To: <sip:1000@192.168.199.209>;tag=5p73Ke3XyHNeB
  1476.    Call-ID: TLCkh5yLFXegYOSvMqEDr6xV1AxFjRNm
  1477.    CSeq: 6683 INVITE
  1478.    Contact: <sip:1000@192.168.199.209:5060;transport=udp>
  1479.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  1480.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1481.    Require: timer
  1482.    Supported: timer, path, replaces
  1483.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  1484.    Session-Expires: 1800;refresher=uac
  1485.    Content-Type: application/sdp
  1486.    Content-Disposition: session
  1487.    Content-Length: 260
  1488.    Remote-Party-ID: "1000" <sip:1000@192.168.199.209>;party=calling;privacy=off;screen=no
  1489.    
  1490.    v=0
  1491.    o=FreeSWITCH 1519804635 1519804636 IN IP4 192.168.199.209
  1492.    s=FreeSWITCH
  1493.    c=IN IP4 192.168.199.209
  1494.    t=0 0
  1495.    m=audio 26514 RTP/AVP 0 96
  1496.    a=rtpmap:0 PCMU/8000
  1497.    a=rtpmap:96 telephone-event/8000
  1498.    a=fmtp:96 0-16
  1499.    a=ptime:20
  1500.    a=rtcp:26515 IN IP4 192.168.199.209
  1501.    ------------------------------------------------------------------------
  1502. 2018-02-28 23:19:11.559836 [NOTICE] mod_dptools.c:1312 Channel [sofia/internal/1001@192.168.199.209] has been answered
  1503. 2018-02-28 23:19:11.559836 [DEBUG] switch_channel.c:3772 (sofia/internal/1001@192.168.199.209) Callstate Change EARLY -> ACTIVE
  1504. 2018-02-28 23:19:11.559836 [DEBUG] sofia.c:7048 Channel sofia/internal/1001@192.168.199.209 entering state [completed][200]
  1505. EXECUTE sofia/internal/1001@192.168.199.209 sleep(1000)
  1506. send 1367 bytes to udp/[192.168.199.113]:64236 at 23:19:11.569806:
  1507.    ------------------------------------------------------------------------
  1508.    NOTIFY sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615 SIP/2.0
  1509.    Via: SIP/2.0/UDP 192.168.199.209;rport;branch=z9hG4bKKr44HSt03j10p
  1510.    Max-Forwards: 70
  1511.    From: <sip:1001@192.168.199.209>;tag=Z4GBrKbDdmSR
  1512.    To: "1000" <sip:1000@192.168.199.209>;tag=53819c6d
  1513.    Call-ID: 90533M2VjZDgwMTFhOTA2ODdjNmYwMThmMThkOWM0M2M4Yzc
  1514.    CSeq: 254757551 NOTIFY
  1515.    Contact: <sip:1001@192.168.199.209:5060>
  1516.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  1517.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1518.    Supported: timer, path, replaces
  1519.    Event: presence
  1520.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  1521.    Subscription-State: active;expires=3595
  1522.    Content-Type: application/pidf+xml
  1523.    Content-Length: 500
  1524.    
  1525.    <?xml version="1.0" encoding="ISO-8859-1"?>
  1526.    <presence xmlns='urn:ietf:params:xml:ns:pidf'
  1527.    xmlns:dm='urn:ietf:params:xml:ns:pidf:data-model'
  1528.    xmlns:rpid='urn:ietf:params:xml:ns:pidf:rpid'
  1529.    xmlns:c='urn:ietf:params:xml:ns:pidf:cipid' entity='sip:1001@192.168.199.209'>
  1530.     <tuple id='t6a5ed77e'>
  1531.      <status>
  1532.       <basic>open</basic>
  1533.      </status>
  1534.     </tuple>
  1535.     <dm:person id='p06360c4a'>
  1536.      <rpid:activities>
  1537.       <rpid:on-the-phone/>
  1538.      </rpid:activities>
  1539.      <dm:note>Talk 1000</dm:note>
  1540.     </dm:person>
  1541.    </presence>
  1542.  
  1543.    ------------------------------------------------------------------------
  1544. recv 411 bytes from udp/[192.168.199.113]:64236 at 23:19:11.571596:
  1545.    ------------------------------------------------------------------------
  1546.    SIP/2.0 200 OK
  1547.    Via: SIP/2.0/UDP 192.168.199.209;rport=5060;branch=z9hG4bKKr44HSt03j10p
  1548.    Contact: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>
  1549.    To: "1000"<sip:1000@192.168.199.209>;tag=53819c6d
  1550.    From: <sip:1001@192.168.199.209>;tag=Z4GBrKbDdmSR
  1551.    Call-ID: 90533M2VjZDgwMTFhOTA2ODdjNmYwMThmMThkOWM0M2M4Yzc
  1552.    CSeq: 254757551 NOTIFY
  1553.    User-Agent: X-Lite release 5.2.0 stamp 90533
  1554.    Content-Length: 0
  1555.    
  1556.    ------------------------------------------------------------------------
  1557. recv 368 bytes from udp/[192.168.199.147]:50452 at 23:19:11.577975:
  1558.    ------------------------------------------------------------------------
  1559.    ACK sip:1000@192.168.199.209:5060;transport=udp SIP/2.0
  1560.    Via: SIP/2.0/UDP 192.168.199.147:50452;rport;branch=z9hG4bKPj1wvw9xS1CbeOoLPuHfacwJRLuKzMNYeI
  1561.    Max-Forwards: 70
  1562.    From: sip:1001@192.168.199.209;tag=ncjBVBvrLoZYKZG9hB-Dw9pc-zMQ6-X0
  1563.    To: sip:1000@192.168.199.209;tag=5p73Ke3XyHNeB
  1564.    Call-ID: TLCkh5yLFXegYOSvMqEDr6xV1AxFjRNm
  1565.    CSeq: 6683 ACK
  1566.    Content-Length:  0
  1567.    
  1568.    ------------------------------------------------------------------------
  1569. 2018-02-28 23:19:11.559836 [DEBUG] sofia.c:7048 Channel sofia/internal/1001@192.168.199.209 entering state [ready][200]
  1570. 2018-02-28 23:19:11.937596 [DEBUG] switch_rtp.c:7229 Correct audio ip/port confirmed.
  1571. EXECUTE sofia/internal/1001@192.168.199.209 bridge(loopback/app=voicemail:default 192.168.199.209 1000)
  1572. 2018-02-28 23:19:12.577819 [DEBUG] switch_channel.c:1823 (sofia/internal/1001@192.168.199.209) Callstate Change ACTIVE -> RING_WAIT
  1573. 2018-02-28 23:19:12.577819 [DEBUG] switch_channel.c:1250 sofia/internal/1001@192.168.199.209 EXPORTING[export_vars] [RFC2822_DATE]=[Wed, 28 Feb 2018 23:19:09 +0800] to event
  1574. 2018-02-28 23:19:12.577819 [DEBUG] switch_channel.c:1250 sofia/internal/1001@192.168.199.209 EXPORTING[export_vars] [dialed_extension]=[1000] to event
  1575. 2018-02-28 23:19:12.577819 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  1576. 2018-02-28 23:19:12.577819 [NOTICE] switch_channel.c:1104 New Channel loopback/app=voicemail:default 192.168.199.209 1000-a [bb2fc8b0-1c9a-11e8-b945-1f706e5b1202]
  1577. 2018-02-28 23:19:12.577819 [DEBUG] mod_loopback.c:158 loopback/app=voicemail:default 192.168.199.209 1000-a setup codec PCMU/8000/20
  1578. 2018-02-28 23:19:12.577819 [NOTICE] switch_channel.c:1102 Rename Channel loopback/app=voicemail:default 192.168.199.209 1000-a->loopback/voicemail-a [bb2fc8b0-1c9a-11e8-b945-1f706e5b1202]
  1579. 2018-02-28 23:19:12.577819 [DEBUG] mod_loopback.c:1174 (loopback/voicemail-a) State Change CS_NEW -> CS_INIT
  1580. 2018-02-28 23:19:12.577819 [DEBUG] mod_loopback.c:601 loopback/voicemail-a CHANNEL KILL
  1581. 2018-02-28 23:19:12.577819 [DEBUG] switch_core_state_machine.c:584 (loopback/voicemail-a) Running State Change CS_INIT (Cur 2 Tot 38)
  1582. 2018-02-28 23:19:12.577819 [DEBUG] switch_core_state_machine.c:627 (loopback/voicemail-a) State INIT
  1583. 2018-02-28 23:19:12.577819 [NOTICE] switch_channel.c:1104 New Channel loopback/voicemail-b [bb2ff1fa-1c9a-11e8-b949-1f706e5b1202]
  1584. 2018-02-28 23:19:12.577819 [DEBUG] mod_loopback.c:158 loopback/voicemail-b setup codec PCMU/8000/20
  1585. 2018-02-28 23:19:12.577819 [DEBUG] mod_loopback.c:276 (loopback/voicemail-b) State Change CS_NEW -> CS_INIT
  1586. 2018-02-28 23:19:12.577819 [DEBUG] mod_loopback.c:601 loopback/voicemail-b CHANNEL KILL
  1587. 2018-02-28 23:19:12.577819 [DEBUG] mod_loopback.c:362 (loopback/voicemail-a) State Change CS_INIT -> CS_ROUTING
  1588. 2018-02-28 23:19:12.577819 [DEBUG] mod_loopback.c:601 loopback/voicemail-a CHANNEL KILL
  1589. 2018-02-28 23:19:12.577819 [DEBUG] switch_core_state_machine.c:627 (loopback/voicemail-a) State INIT going to sleep
  1590. 2018-02-28 23:19:12.577819 [DEBUG] switch_core_state_machine.c:584 (loopback/voicemail-a) Running State Change CS_ROUTING (Cur 3 Tot 39)
  1591. 2018-02-28 23:19:12.577819 [DEBUG] switch_core_state_machine.c:643 (loopback/voicemail-a) State ROUTING
  1592. 2018-02-28 23:19:12.577819 [DEBUG] mod_loopback.c:394 loopback/voicemail-a CHANNEL ROUTING
  1593. 2018-02-28 23:19:12.577819 [DEBUG] switch_ivr_originate.c:67 (loopback/voicemail-a) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  1594. 2018-02-28 23:19:12.577819 [DEBUG] mod_loopback.c:601 loopback/voicemail-a CHANNEL KILL
  1595. 2018-02-28 23:19:12.577819 [DEBUG] switch_core_state_machine.c:643 (loopback/voicemail-a) State ROUTING going to sleep
  1596. 2018-02-28 23:19:12.577819 [DEBUG] switch_core_state_machine.c:584 (loopback/voicemail-a) Running State Change CS_CONSUME_MEDIA (Cur 3 Tot 39)
  1597. 2018-02-28 23:19:12.577819 [DEBUG] switch_core_state_machine.c:662 (loopback/voicemail-a) State CONSUME_MEDIA
  1598. 2018-02-28 23:19:12.577819 [DEBUG] mod_loopback.c:661 CHANNEL CONSUME_MEDIA
  1599. 2018-02-28 23:19:12.577819 [DEBUG] switch_core_state_machine.c:662 (loopback/voicemail-a) State CONSUME_MEDIA going to sleep
  1600. 2018-02-28 23:19:12.577819 [DEBUG] switch_core_state_machine.c:584 (loopback/voicemail-b) Running State Change CS_INIT (Cur 3 Tot 39)
  1601. 2018-02-28 23:19:12.577819 [DEBUG] switch_core_state_machine.c:627 (loopback/voicemail-b) State INIT
  1602. 2018-02-28 23:19:12.577819 [DEBUG] mod_loopback.c:362 (loopback/voicemail-b) State Change CS_INIT -> CS_ROUTING
  1603. 2018-02-28 23:19:12.577819 [DEBUG] mod_loopback.c:601 loopback/voicemail-b CHANNEL KILL
  1604. 2018-02-28 23:19:12.577819 [DEBUG] switch_core_state_machine.c:627 (loopback/voicemail-b) State INIT going to sleep
  1605. 2018-02-28 23:19:12.577819 [DEBUG] switch_core_state_machine.c:584 (loopback/voicemail-b) Running State Change CS_ROUTING (Cur 3 Tot 39)
  1606. 2018-02-28 23:19:12.577819 [DEBUG] switch_channel.c:2249 (loopback/voicemail-b) Callstate Change DOWN -> RINGING
  1607. 2018-02-28 23:19:12.577819 [DEBUG] switch_core_state_machine.c:643 (loopback/voicemail-b) State ROUTING
  1608. 2018-02-28 23:19:12.577819 [DEBUG] mod_loopback.c:394 loopback/voicemail-b CHANNEL ROUTING
  1609. 2018-02-28 23:19:12.577819 [DEBUG] mod_loopback.c:413 (loopback/voicemail-b) State Change CS_ROUTING -> CS_EXECUTE
  1610. 2018-02-28 23:19:12.577819 [DEBUG] mod_loopback.c:601 loopback/voicemail-b CHANNEL KILL
  1611. 2018-02-28 23:19:12.577819 [DEBUG] switch_core_state_machine.c:643 (loopback/voicemail-b) State ROUTING going to sleep
  1612. 2018-02-28 23:19:12.577819 [DEBUG] switch_core_state_machine.c:584 (loopback/voicemail-b) Running State Change CS_EXECUTE (Cur 3 Tot 39)
  1613. 2018-02-28 23:19:12.577819 [DEBUG] switch_core_state_machine.c:650 (loopback/voicemail-b) State EXECUTE
  1614. 2018-02-28 23:19:12.577819 [DEBUG] mod_loopback.c:436 loopback/voicemail-b CHANNEL EXECUTE
  1615. 2018-02-28 23:19:12.577819 [DEBUG] switch_core_state_machine.c:328 loopback/voicemail-b Standard EXECUTE
  1616. EXECUTE loopback/voicemail-b pre_answer()
  1617. 2018-02-28 23:19:12.577819 [NOTICE] mod_loopback.c:961 Pre-Answer loopback/voicemail-a!
  1618. 2018-02-28 23:19:12.577819 [DEBUG] switch_channel.c:3473 (loopback/voicemail-a) Callstate Change DOWN -> EARLY
  1619. 2018-02-28 23:19:12.577819 [DEBUG] mod_loopback.c:601 loopback/voicemail-b CHANNEL KILL
  1620. 2018-02-28 23:19:12.577819 [NOTICE] mod_dptools.c:1355 Pre-Answer loopback/voicemail-b!
  1621. 2018-02-28 23:19:12.577819 [DEBUG] switch_channel.c:3473 (loopback/voicemail-b) Callstate Change RINGING -> EARLY
  1622. 2018-02-28 23:19:12.577819 [DEBUG] mod_loopback.c:601 loopback/voicemail-b CHANNEL KILL
  1623. EXECUTE loopback/voicemail-b voicemail(default 192.168.199.209 1000)
  1624. 2018-02-28 23:19:12.577819 [DEBUG] mod_loopback.c:601 loopback/voicemail-b CHANNEL KILL
  1625. 2018-02-28 23:19:12.597624 [DEBUG] switch_ivr_originate.c:3690 Originate Resulted in Success: [loopback/voicemail-a]
  1626. 2018-02-28 23:19:12.597624 [DEBUG] mod_loopback.c:601 loopback/voicemail-a CHANNEL KILL
  1627. 2018-02-28 23:19:12.597624 [DEBUG] switch_channel.c:2047 (sofia/internal/1001@192.168.199.209) Callstate Change RING_WAIT -> ACTIVE
  1628. 2018-02-28 23:19:12.597624 [DEBUG] mod_loopback.c:601 loopback/voicemail-a CHANNEL KILL
  1629. 2018-02-28 23:19:12.597624 [DEBUG] mod_loopback.c:601 loopback/voicemail-a CHANNEL KILL
  1630. 2018-02-28 23:19:12.597624 [DEBUG] switch_ivr_bridge.c:1601 (loopback/voicemail-a) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  1631. 2018-02-28 23:19:12.597624 [DEBUG] mod_loopback.c:601 loopback/voicemail-a CHANNEL KILL
  1632. 2018-02-28 23:19:12.597624 [DEBUG] switch_core_state_machine.c:584 (loopback/voicemail-a) Running State Change CS_EXCHANGE_MEDIA (Cur 3 Tot 39)
  1633. 2018-02-28 23:19:12.597624 [DEBUG] switch_core_state_machine.c:653 (loopback/voicemail-a) State EXCHANGE_MEDIA
  1634. 2018-02-28 23:19:12.597624 [DEBUG] mod_loopback.c:623 CHANNEL LOOPBACK
  1635. 2018-02-28 23:19:12.697418 [DEBUG] switch_ivr_play_say.c:70 No language specified - Using [en]
  1636. 2018-02-28 23:19:12.697418 [DEBUG] switch_ivr_play_say.c:250 Handle play-file:[voicemail/vm-person.wav] (en:en)
  1637. 2018-02-28 23:19:12.697418 [DEBUG] mod_loopback.c:601 loopback/voicemail-b CHANNEL KILL
  1638. 2018-02-28 23:19:12.697418 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
  1639. 2018-02-28 23:19:14.058071 [DEBUG] switch_ivr_play_say.c:1942 done playing file /root/freeswitch/share/freeswitch/sounds/en/us/callie/voicemail/vm-person.wav
  1640. 2018-02-28 23:19:14.177805 [DEBUG] switch_ivr_play_say.c:250 Handle say:[1000] (en:en)
  1641. 2018-02-28 23:19:14.177805 [DEBUG] mod_loopback.c:601 loopback/voicemail-b CHANNEL KILL
  1642. 2018-02-28 23:19:14.177805 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
  1643. 2018-02-28 23:19:14.597633 [DEBUG] switch_rtp.c:6679 Correct audio RTCP ip/port confirmed.
  1644. recv 424 bytes from udp/[192.168.199.147]:50452 at 23:19:15.492727:
  1645.    ------------------------------------------------------------------------
  1646.    BYE sip:1000@192.168.199.209:5060;transport=udp SIP/2.0
  1647.    Via: SIP/2.0/UDP 192.168.199.147:50452;rport;branch=z9hG4bKPjo5RfxgyjWMW4bueMURWJgjZ5.K9zivgH
  1648.    Max-Forwards: 70
  1649.    From: sip:1001@192.168.199.209;tag=ncjBVBvrLoZYKZG9hB-Dw9pc-zMQ6-X0
  1650.    To: sip:1000@192.168.199.209;tag=5p73Ke3XyHNeB
  1651.    Call-ID: TLCkh5yLFXegYOSvMqEDr6xV1AxFjRNm
  1652.    CSeq: 6684 BYE
  1653.    User-Agent: Siphon PjSip v2.0.0-beta/arm-apple-darwin9
  1654.    Content-Length:  0
  1655.    
  1656.    ------------------------------------------------------------------------
  1657. 2018-02-28 23:19:15.497559 [DEBUG] switch_ivr_bridge.c:706 sofia/internal/1001@192.168.199.209 ending bridge by request from write function
  1658. 2018-02-28 23:19:15.497559 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [loopback/voicemail-a]
  1659. 2018-02-28 23:19:15.497559 [NOTICE] switch_ivr_bridge.c:889 Hangup loopback/voicemail-a [CS_EXCHANGE_MEDIA] [ORIGINATOR_CANCEL]
  1660. 2018-02-28 23:19:15.497559 [NOTICE] sofia.c:1012 Hangup sofia/internal/1001@192.168.199.209 [CS_EXECUTE] [NORMAL_CLEARING]
  1661. 2018-02-28 23:19:15.497559 [DEBUG] mod_loopback.c:601 loopback/voicemail-a CHANNEL KILL
  1662. 2018-02-28 23:19:15.497559 [DEBUG] mod_loopback.c:601 loopback/voicemail-a CHANNEL KILL
  1663. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:653 (loopback/voicemail-a) State EXCHANGE_MEDIA going to sleep
  1664. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:584 (loopback/voicemail-a) Running State Change CS_HANGUP (Cur 3 Tot 39)
  1665. 2018-02-28 23:19:15.497559 [DEBUG] switch_ivr_play_say.c:1942 done playing file file_string://digits/1.wav!digits/0.wav!digits/0.wav!digits/0.wav
  1666. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:850 (loopback/voicemail-a) Callstate Change EARLY -> HANGUP
  1667. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:852 (loopback/voicemail-a) State HANGUP
  1668. 2018-02-28 23:19:15.497559 [DEBUG] mod_loopback.c:548 loopback/voicemail-a CHANNEL HANGUP
  1669. 2018-02-28 23:19:15.497559 [NOTICE] mod_loopback.c:564 Hangup loopback/voicemail-b [CS_EXECUTE] [ORIGINATOR_CANCEL]
  1670. 2018-02-28 23:19:15.497559 [DEBUG] mod_loopback.c:601 loopback/voicemail-b CHANNEL KILL
  1671. 2018-02-28 23:19:15.497559 [DEBUG] mod_loopback.c:601 loopback/voicemail-b CHANNEL KILL
  1672. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:60 loopback/voicemail-a Standard HANGUP, cause: ORIGINATOR_CANCEL
  1673. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:852 (loopback/voicemail-a) State HANGUP going to sleep
  1674. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:619 (loopback/voicemail-a) State Change CS_HANGUP -> CS_REPORTING
  1675. 2018-02-28 23:19:15.497559 [DEBUG] mod_loopback.c:601 loopback/voicemail-a CHANNEL KILL
  1676. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:584 (loopback/voicemail-a) Running State Change CS_REPORTING (Cur 3 Tot 39)
  1677. send 505 bytes to udp/[192.168.199.147]:50452 at 23:19:15.503126:
  1678.    ------------------------------------------------------------------------
  1679. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:938 (loopback/voicemail-a) State REPORTING
  1680.    SIP/2.0 200 OK
  1681.    Via: SIP/2.0/UDP 192.168.199.147:50452;rport=50452;branch=z9hG4bKPjo5RfxgyjWMW4bueMURWJgjZ5.K9zivgH
  1682. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:174 loopback/voicemail-a Standard REPORTING, cause: ORIGINATOR_CANCEL
  1683.    From: sip:1001@192.168.199.209;tag=ncjBVBvrLoZYKZG9hB-Dw9pc-zMQ6-X0
  1684.    To: sip:1000@192.168.199.209;tag=5p73Ke3XyHNeB
  1685. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:938 (loopback/voicemail-a) State REPORTING going to sleep
  1686.    Call-ID: TLCkh5yLFXegYOSvMqEDr6xV1AxFjRNm
  1687.    CSeq: 6684 BYE
  1688.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  1689.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1690.    Supported: timer, path, replaces
  1691.    Content-Length: 0
  1692.    
  1693.    ------------------------------------------------------------------------
  1694. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:610 (loopback/voicemail-a) State Change CS_REPORTING -> CS_DESTROY
  1695. 2018-02-28 23:19:15.497559 [DEBUG] mod_loopback.c:601 loopback/voicemail-a CHANNEL KILL
  1696. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_session.c:1664 Session 38 (loopback/voicemail-a) Locked, Waiting on external entities
  1697. 2018-02-28 23:19:15.497559 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/internal/1001@192.168.199.209]
  1698. 2018-02-28 23:19:15.497559 [DEBUG] mod_loopback.c:601 loopback/voicemail-a CHANNEL KILL
  1699. 2018-02-28 23:19:15.497559 [DEBUG] switch_ivr_bridge.c:1702 sofia/internal/1001@192.168.199.209 skip receive message [UNBRIDGE] (channel is hungup already)
  1700. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_session.c:2814 sofia/internal/1001@192.168.199.209 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  1701. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/1001@192.168.199.209) State EXECUTE going to sleep
  1702. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1001@192.168.199.209) Running State Change CS_HANGUP (Cur 3 Tot 39)
  1703. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1001@192.168.199.209) Callstate Change ACTIVE -> HANGUP
  1704. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1001@192.168.199.209) State HANGUP
  1705. 2018-02-28 23:19:15.497559 [DEBUG] mod_sofia.c:432 sofia/internal/1001@192.168.199.209 Overriding SIP cause 480 with 486 from the other leg
  1706. 2018-02-28 23:19:15.497559 [DEBUG] mod_sofia.c:438 Channel sofia/internal/1001@192.168.199.209 hanging up, cause: NORMAL_CLEARING
  1707. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_session.c:2814 loopback/voicemail-b skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  1708. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:650 (loopback/voicemail-b) State EXECUTE going to sleep
  1709. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:584 (loopback/voicemail-b) Running State Change CS_HANGUP (Cur 3 Tot 39)
  1710. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:850 (loopback/voicemail-b) Callstate Change EARLY -> HANGUP
  1711. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:852 (loopback/voicemail-b) State HANGUP
  1712. 2018-02-28 23:19:15.497559 [DEBUG] mod_loopback.c:548 loopback/voicemail-b CHANNEL HANGUP
  1713. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:60 loopback/voicemail-b Standard HANGUP, cause: ORIGINATOR_CANCEL
  1714. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:852 (loopback/voicemail-b) State HANGUP going to sleep
  1715. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:619 (loopback/voicemail-b) State Change CS_HANGUP -> CS_REPORTING
  1716. 2018-02-28 23:19:15.497559 [DEBUG] mod_loopback.c:601 loopback/voicemail-b CHANNEL KILL
  1717. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:584 (loopback/voicemail-b) Running State Change CS_REPORTING (Cur 3 Tot 39)
  1718. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:938 (loopback/voicemail-b) State REPORTING
  1719. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:174 loopback/voicemail-b Standard REPORTING, cause: ORIGINATOR_CANCEL
  1720. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:938 (loopback/voicemail-b) State REPORTING going to sleep
  1721. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1001@192.168.199.209 Standard HANGUP, cause: NORMAL_CLEARING
  1722. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1001@192.168.199.209) State HANGUP going to sleep
  1723. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1001@192.168.199.209) State Change CS_HANGUP -> CS_REPORTING
  1724. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:610 (loopback/voicemail-b) State Change CS_REPORTING -> CS_DESTROY
  1725. 2018-02-28 23:19:15.497559 [DEBUG] mod_loopback.c:601 loopback/voicemail-b CHANNEL KILL
  1726. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_session.c:1664 Session 39 (loopback/voicemail-b) Locked, Waiting on external entities
  1727. 2018-02-28 23:19:15.497559 [NOTICE] switch_core_session.c:1682 Session 39 (loopback/voicemail-b) Ended
  1728. 2018-02-28 23:19:15.497559 [NOTICE] switch_core_session.c:1686 Close Channel loopback/voicemail-b [CS_DESTROY]
  1729. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1001@192.168.199.209) Running State Change CS_REPORTING (Cur 2 Tot 39)
  1730. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:741 (loopback/voicemail-b) Running State Change CS_DESTROY (Cur 2 Tot 39)
  1731. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:751 (loopback/voicemail-b) State DESTROY
  1732. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:181 loopback/voicemail-b Standard DESTROY
  1733. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:751 (loopback/voicemail-b) State DESTROY going to sleep
  1734. 2018-02-28 23:19:15.497559 [NOTICE] switch_core_session.c:1682 Session 38 (loopback/voicemail-a) Ended
  1735. 2018-02-28 23:19:15.497559 [NOTICE] switch_core_session.c:1686 Close Channel loopback/voicemail-a [CS_DESTROY]
  1736. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:741 (loopback/voicemail-a) Running State Change CS_DESTROY (Cur 1 Tot 39)
  1737. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:751 (loopback/voicemail-a) State DESTROY
  1738. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:181 loopback/voicemail-a Standard DESTROY
  1739. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:751 (loopback/voicemail-a) State DESTROY going to sleep
  1740. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1001@192.168.199.209) State REPORTING
  1741. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1001@192.168.199.209 Standard REPORTING, cause: NORMAL_CLEARING
  1742. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1001@192.168.199.209) State REPORTING going to sleep
  1743. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1001@192.168.199.209) State Change CS_REPORTING -> CS_DESTROY
  1744. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_session.c:1664 Session 36 (sofia/internal/1001@192.168.199.209) Locked, Waiting on external entities
  1745. 2018-02-28 23:19:15.497559 [NOTICE] switch_core_session.c:1682 Session 36 (sofia/internal/1001@192.168.199.209) Ended
  1746. 2018-02-28 23:19:15.497559 [NOTICE] switch_core_session.c:1686 Close Channel sofia/internal/1001@192.168.199.209 [CS_DESTROY]
  1747. send 1301 bytes to udp/[192.168.199.113]:64236 at 23:19:15.508405:
  1748.    ------------------------------------------------------------------------
  1749.    NOTIFY sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615 SIP/2.0
  1750.    Via: SIP/2.0/UDP 192.168.199.209;rport;branch=z9hG4bKm1XXKmB40UQKj
  1751.    Max-Forwards: 70
  1752.    From: <sip:1001@192.168.199.209>;tag=Z4GBrKbDdmSR
  1753.    To: "1000" <sip:1000@192.168.199.209>;tag=53819c6d
  1754.    Call-ID: 90533M2VjZDgwMTFhOTA2ODdjNmYwMThmMThkOWM0M2M4Yzc
  1755.    CSeq: 254757751 NOTIFY
  1756.    Contact: <sip:1001@192.168.199.209:5060>
  1757.    User-Agent: FreeSWITCH-mod_sofia/1.6.17~64bit
  1758.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1759.    Supported: timer, path, replaces
  1760.    Event: presence
  1761.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  1762.    Subscription-State: active;expires=3591
  1763.    Content-Type: application/pidf+xml
  1764.    Content-Length: 434
  1765.    
  1766.    <?xml version="1.0" encoding="ISO-8859-1"?>
  1767.    <presence xmlns='urn:ietf:params:xml:ns:pidf'
  1768.    xmlns:dm='urn:ietf:params:xml:ns:pidf:data-model'
  1769.    xmlns:rpid='urn:ietf:params:xml:ns:pidf:rpid'
  1770.    xmlns:c='urn:ietf:params:xml:ns:pidf:cipid' entity='sip:1001@192.168.199.209'>
  1771.     <tuple id='t6a5ed77e'>
  1772.      <status>
  1773.       <basic>open</basic>
  1774.      </status>
  1775.     </tuple>
  1776.     <dm:person id='p06360c4a'>
  1777.      <dm:note>Available</dm:note>
  1778.     </dm:person>
  1779.    </presence>
  1780.  
  1781.    ------------------------------------------------------------------------
  1782. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1001@192.168.199.209) Running State Change CS_DESTROY (Cur 0 Tot 39)
  1783. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1001@192.168.199.209) State DESTROY
  1784. 2018-02-28 23:19:15.497559 [DEBUG] mod_sofia.c:343 sofia/internal/1001@192.168.199.209 SOFIA DESTROY
  1785. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1001@192.168.199.209 Standard DESTROY
  1786. 2018-02-28 23:19:15.497559 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1001@192.168.199.209) State DESTROY going to sleep
  1787. recv 411 bytes from udp/[192.168.199.113]:64236 at 23:19:15.509945:
  1788.    ------------------------------------------------------------------------
  1789.    SIP/2.0 200 OK
  1790.    Via: SIP/2.0/UDP 192.168.199.209;rport=5060;branch=z9hG4bKm1XXKmB40UQKj
  1791.    Contact: <sip:1000@192.168.199.113:64236;rinstance=6558b3528a5b2615>
  1792.    To: "1000"<sip:1000@192.168.199.209>;tag=53819c6d
  1793.    From: <sip:1001@192.168.199.209>;tag=Z4GBrKbDdmSR
  1794.    Call-ID: 90533M2VjZDgwMTFhOTA2ODdjNmYwMThmMThkOWM0M2M4Yzc
  1795.    CSeq: 254757751 NOTIFY
  1796.    User-Agent: X-Lite release 5.2.0 stamp 90533
  1797.    Content-Length: 0
  1798.    
  1799.    ------------------------------------------------------------------------
Advertisement
Add Comment
Please, Sign In to add comment
Advertisement
Public Pastes
Advertisement
We use cookies for various purposes including analytics. By continuing to use Pastebin, you agree to our use of cookies as described in the Cookies Policy.  OK, I Understand
Not a member of Pastebin yet?
Sign Up, it unlocks many cool features!