Advertisement
highsea

局域网手机1001通话电脑1000

Mar 1st, 2018
2,996
0
Never
Not a member of Pastebin yet? Sign Up, it unlocks many cool features!
  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