FreeSWITCH Pastebin

no voice with innovaphone ICE disabled

From Daniel, 5 Years ago, written in FreeSWITCH.
- go back
URL https://pastebin.freeswitch.org/view/fddd86d4
Embed
Viewing differences between and no voice with innovaphone ICE disabled
  1. recv 763 bytes from udp/[172.19.50.4]:3861 at 21:39:24.424656:
  2.    ------------------------------------------------------------------------
  3.    REGISTER sip:pbx.[anonym].net SIP/2.0
  4.    Authorization: Digest username="9590206",realm="pbx.[anonym].net",nonce="d9fb4777-4364-4a78-b513-5925e62367b5",response="04f549169bce4aeaff37a10bcedaff1e",uri="sip:pbx.[anonym].net",qop=auth,cnonce="719b57f454db5b017ede009033060682",nc=0000037c,algorithm=MD5
  5.    Via: SIP/2.0/UDP 172.19.50.4:3861;branch=z9hG4bK-03B444A8;rport
  6.    From: <sip:9590206@pbx.[anonym].net>;tag=2770968073
  7.    To: <sip:9590206@pbx.[anonym].net>
  8.    Call-ID: c7e2bd8b47e55b014639009033060682
  9.    CSeq: 3458 REGISTER
  10.    Contact: <sip:9590206@172.19.50.4:3861;transport=UDP>;expires=120
  11.    Accept: innovaphone/data
  12.    Content-Length: 0
  13.    Expires: 120
  14.    Max-Forwards: 70
  15.    User-Agent: (innovaphone IP800/11r2 sr15 [11.3587/113587/106])
  16.    Allow-Events: reg,dialog,message-summary,presence
  17.  
  18.    ------------------------------------------------------------------------
  19. send 544 bytes to udp/[172.19.50.4]:3861 at 21:39:24.467160:
  20.    ------------------------------------------------------------------------
  21.    SIP/2.0 200 OK
  22.    Via: SIP/2.0/UDP 172.19.50.4:3861;branch=z9hG4bK-03B444A8;rport=3861
  23.    From: <sip:9590206@pbx.[anonym].net>;tag=2770968073
  24.    To: <sip:9590206@pbx.[anonym].net>;tag=Kr0DjHvjgKBee
  25.    Call-ID: c7e2bd8b47e55b014639009033060682
  26.    CSeq: 3458 REGISTER
  27.    Contact: <sip:9590206@172.19.50.4:3861;transport=UDP>;expires=120
  28.    Date: Sun, 11 Nov 2018 20:39:24 GMT
  29.    User-Agent: FreeSWITCH
  30.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  31.    Supported: timer, path, replaces
  32.    Content-Length: 0
  33.  
  34.    ------------------------------------------------------------------------
  35. recv 496 bytes from udp/[172.19.51.21]:5060 at 21:39:25.103011:
  36.    ------------------------------------------------------------------------
  37.    SUBSCRIBE sip:13@172.19.50.3:5060 SIP/2.0
  38.    Via: SIP/2.0/UDP 172.19.51.21:5060;branch=z9hG4bK-B89D31D5;rport
  39.    From: <sip:11@pbx.[anonym].net>;tag=201447844
  40.    To: <sip:13@pbx.[anonym].net;user=phone>;tag=IVbQih4CEMYf
  41.    Call-ID: 6dc598b07ce85b01209e0090332616fc@4
  42.    CSeq: 2067 SUBSCRIBE
  43.    Contact: <sip:11@172.19.51.21:5060;transport=UDP>
  44.    Accept: application/dialog-info+xml
  45.    Content-Length: 0
  46.    Expires: 119
  47.    Max-Forwards: 70
  48.    User-Agent: (innovaphone IP240/12r1 sr26 [12.1257/121257/301])
  49.    Event: dialog
  50.  
  51.    ------------------------------------------------------------------------
  52. send 710 bytes to udp/[172.19.51.21]:5060 at 21:39:25.106733:
  53.    ------------------------------------------------------------------------
  54.    SIP/2.0 202 Accepted
  55.    Via: SIP/2.0/UDP 172.19.51.21:5060;branch=z9hG4bK-B89D31D5;rport=5060
  56.    From: <sip:11@pbx.[anonym].net>;tag=201447844
  57.    To: <sip:13@pbx.[anonym].net;user=phone>;tag=IVbQih4CEMYf
  58.    Call-ID: 6dc598b07ce85b01209e0090332616fc@4
  59.    CSeq: 2067 SUBSCRIBE
  60.    Contact: <sip:13@172.19.50.3:5060>
  61.    Expires: 119
  62.    User-Agent: FreeSWITCH
  63.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  64.    Supported: timer, path, replaces
  65.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  66.    Subscription-State: active;expires=119
  67.    Content-Length: 0
  68.  
  69.    ------------------------------------------------------------------------
  70. send 953 bytes to udp/[172.19.51.21]:5060 at 21:39:25.110063:
  71.    ------------------------------------------------------------------------
  72.    NOTIFY sip:11@172.19.51.21:5060;transport=UDP SIP/2.0
  73.    Via: SIP/2.0/UDP 172.19.50.3;rport;branch=z9hG4bKgpm6BHy2eKp2K
  74.    Max-Forwards: 70
  75.    From: <sip:13@pbx.[anonym].net;user=phone>;tag=IVbQih4CEMYf
  76.    To: <sip:11@pbx.[anonym].net>;tag=201447844
  77.    Call-ID: 6dc598b07ce85b01209e0090332616fc@4
  78.    CSeq: 1360378251 NOTIFY
  79.    Contact: <sip:13@172.19.50.3:5060>
  80.    User-Agent: FreeSWITCH
  81.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  82.    Supported: timer, path, replaces
  83.    Event: dialog
  84.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  85.    Subscription-State: active;expires=119
  86.    Content-Type: application/dialog-info+xml
  87.    Content-Length: 150
  88.  
  89.    <?xml version="1.0"?>
  90.    <dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="67" state="full" entity="sip:13@pbx.[anonym].net">
  91.    </dialog-info>
  92.    ------------------------------------------------------------------------
  93. recv 352 bytes from udp/[172.19.51.21]:5060 at 21:39:25.113659:
  94.    ------------------------------------------------------------------------
  95.    SIP/2.0 200 OK
  96.    Via: SIP/2.0/UDP 172.19.50.3;rport;branch=z9hG4bKgpm6BHy2eKp2K;received=172.19.50.3
  97.    From: <sip:13@pbx.[anonym].net;user=phone>;tag=IVbQih4CEMYf
  98.    To: <sip:11@pbx.[anonym].net>;tag=201447844
  99.    Call-ID: 6dc598b07ce85b01209e0090332616fc@4
  100.    CSeq: 1360378251 NOTIFY
  101.    Content-Length: 0
  102.    Server: (innovaphone IP240/12r1 sr26 [12.1257/121257/301])
  103.  
  104.    ------------------------------------------------------------------------
  105. recv 496 bytes from udp/[172.19.51.21]:5060 at 21:39:25.443007:
  106.    ------------------------------------------------------------------------
  107.    SUBSCRIBE sip:12@172.19.50.3:5060 SIP/2.0
  108.    Via: SIP/2.0/UDP 172.19.51.21:5060;branch=z9hG4bK-B89D31D6;rport
  109.    From: <sip:11@pbx.[anonym].net>;tag=201447843
  110.    To: <sip:12@pbx.[anonym].net;user=phone>;tag=KwAnCbiD8uGu
  111.    Call-ID: e54e60b07ce85b011e9e0090332616fc@3
  112.    CSeq: 2067 SUBSCRIBE
  113.    Contact: <sip:11@172.19.51.21:5060;transport=UDP>
  114.    Accept: application/dialog-info+xml
  115.    Content-Length: 0
  116.    Expires: 119
  117.    Max-Forwards: 70
  118.    User-Agent: (innovaphone IP240/12r1 sr26 [12.1257/121257/301])
  119.    Event: dialog
  120.  
  121.    ------------------------------------------------------------------------
  122. send 710 bytes to udp/[172.19.51.21]:5060 at 21:39:25.446691:
  123.    ------------------------------------------------------------------------
  124.    SIP/2.0 202 Accepted
  125.    Via: SIP/2.0/UDP 172.19.51.21:5060;branch=z9hG4bK-B89D31D6;rport=5060
  126.    From: <sip:11@pbx.[anonym].net>;tag=201447843
  127.    To: <sip:12@pbx.[anonym].net;user=phone>;tag=KwAnCbiD8uGu
  128.    Call-ID: e54e60b07ce85b011e9e0090332616fc@3
  129.    CSeq: 2067 SUBSCRIBE
  130.    Contact: <sip:12@172.19.50.3:5060>
  131.    Expires: 119
  132.    User-Agent: FreeSWITCH
  133.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  134.    Supported: timer, path, replaces
  135.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  136.    Subscription-State: active;expires=119
  137.    Content-Length: 0
  138.  
  139.    ------------------------------------------------------------------------
  140. send 953 bytes to udp/[172.19.51.21]:5060 at 21:39:25.450086:
  141.    ------------------------------------------------------------------------
  142.    NOTIFY sip:11@172.19.51.21:5060;transport=UDP SIP/2.0
  143.    Via: SIP/2.0/UDP 172.19.50.3;rport;branch=z9hG4bKHZDZDcF6BvcNF
  144.    Max-Forwards: 70
  145.    From: <sip:12@pbx.[anonym].net;user=phone>;tag=KwAnCbiD8uGu
  146.    To: <sip:11@pbx.[anonym].net>;tag=201447843
  147.    Call-ID: e54e60b07ce85b011e9e0090332616fc@3
  148.    CSeq: 1360378252 NOTIFY
  149.    Contact: <sip:12@172.19.50.3:5060>
  150.    User-Agent: FreeSWITCH
  151.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  152.    Supported: timer, path, replaces
  153.    Event: dialog
  154.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  155.    Subscription-State: active;expires=119
  156.    Content-Type: application/dialog-info+xml
  157.    Content-Length: 150
  158.  
  159.    <?xml version="1.0"?>
  160.    <dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="71" state="full" entity="sip:12@pbx.[anonym].net">
  161.    </dialog-info>
  162.    ------------------------------------------------------------------------
  163. recv 352 bytes from udp/[172.19.51.21]:5060 at 21:39:25.456350:
  164.    ------------------------------------------------------------------------
  165.    SIP/2.0 200 OK
  166.    Via: SIP/2.0/UDP 172.19.50.3;rport;branch=z9hG4bKHZDZDcF6BvcNF;received=172.19.50.3
  167.    From: <sip:12@pbx.[anonym].net;user=phone>;tag=KwAnCbiD8uGu
  168.    To: <sip:11@pbx.[anonym].net>;tag=201447843
  169.    Call-ID: e54e60b07ce85b011e9e0090332616fc@3
  170.    CSeq: 1360378252 NOTIFY
  171.    Content-Length: 0
  172.    Server: (innovaphone IP240/12r1 sr26 [12.1257/121257/301])
  173.  
  174.    ------------------------------------------------------------------------
  175. recv 1308 bytes from udp/[172.19.50.4]:3861 at 21:39:47.946806:
  176.    ------------------------------------------------------------------------
  177.    INVITE sip:0108008080800@pbx.[anonym].net;user=phone SIP/2.0
  178.    Via: SIP/2.0/UDP 172.19.50.4:3861;branch=z9hG4bK-03B444AA;rport
  179.    From: <sip:9590206@pbx.[anonym].net>;tag=2770968075
  180.    To: <sip:0108008080800@pbx.[anonym].net;user=phone>
  181.    Call-ID: 10d5c69393e85b01a048009033060682@410
  182.    CSeq: 1133815520 INVITE
  183.    Contact: <sip:9590206@172.19.50.4:3861;transport=UDP>
  184.   Allow: REGISTER,SUBSCRIBE,NOTIFY,INVITE,ACK,PRACK,OPTIONS,BYE,CANCEL,REFER,INFO,UPDATE,PUBLISH
  185.    Content-Length: 559
  186.    Content-Type: application/sdp
  187.    Max-Forwards: 69
  188.    Supported: 100rel,replaces,privacy,timer,from-change,histinfo,answermode
  189.    User-Agent: (innovaphone IP800/11r2 sr15 [11.3587/113587/106])
  190.    P-Preferred-Identity: <sip:%0631@pbx.[anonym].net;user=phone>
  191.    P-Sig-Options: Overlap-Dialing
  192.  
  193.    v=0
  194.    o=- 1040 1 IN IP4 172.19.50.4
  195.    s=-
  196.    t=0 0
  197.    m=audio 16686 RTP/SAVP 8 18 4 0 97 101
  198.    c=IN IP4 172.19.50.4
  199.    a=rtpmap:8 PCMA/8000
  200.    a=rtpmap:18 G729/8000
  201.    a=rtpmap:4 G723/8000
  202.    a=rtpmap:0 PCMU/8000
  203.    a=rtpmap:97 CLEARMODE/8000
  204.    a=rtpmap:101 telephone-event/8000
  205.    a=fmtp:18 annexb=no
  206.    a=fmtp:101 0-15
  207.    a=ptime:30
  208.    a=silenceSupp:off - - - -
  209.    a=sendrecv
  210.    a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:X0jxiZ/k7DPggObqIRzhtGG43JCiVNd94vDSguFd|2^31
  211.    a=fingerprint:sha-256 A9:4C:E4:69:65:05:D5:43:C7:E5:0A:99:E5:5B:2E:01:80:EE:81:49:C0:C3:47:3C:10:66:B5:FF:8B:5D:BA:5F
  212.    ------------------------------------------------------------------------
  213. 2018-11-11 21:39:47.935673 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/9590206@pbx.[anonym].net [8e1aa3c0-0313-4d15-baa1-1f2fa9ab32b7]
  214. 2018-11-11 21:39:47.935673 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/9590206@pbx.[anonym].net) Running State Change CS_NEW (Cur 1 Tot 21)
  215. 2018-11-11 21:39:47.935673 [DEBUG] sofia.c:10080 sofia/internal/9590206@pbx.[anonym].net receiving invite from 172.19.50.4:3861 version: 1.8.2 -3-a98a958ac3 64bit
  216. 2018-11-11 21:39:47.935673 [DEBUG] sofia.c:10251 IP 172.19.50.4 Rejected by acl "domains". Falling back to Digest auth.
  217. 2018-11-11 21:39:47.935673 [WARNING] sofia_reg.c:1793 SIP auth challenge (INVITE) on sofia profile 'internal' for [0108008080800@pbx.[anonym].net] from ip 172.19.50.4
  218. 2018-11-11 21:39:47.935673 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/9590206@pbx.[anonym].net) State NEW
  219. send 811 bytes to udp/[172.19.50.4]:3861 at 21:39:47.952458:
  220.    ------------------------------------------------------------------------
  221.    SIP/2.0 407 Proxy Authentication Required
  222.    Via: SIP/2.0/UDP 172.19.50.4:3861;branch=z9hG4bK-03B444AA;rport=3861
  223.    From: <sip:9590206@pbx.[anonym].net>;tag=2770968075
  224.    To: <sip:0108008080800@pbx.[anonym].net;user=phone>;tag=m1S6KcDpDv10S
  225.    Call-ID: 10d5c69393e85b01a048009033060682@410
  226.    CSeq: 1133815520 INVITE
  227.    User-Agent: FreeSWITCH
  228.    Accept: application/sdp
  229.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  230.    Supported: timer, path, replaces
  231.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  232.    Proxy-Authenticate: Digest realm="pbx.[anonym].net", nonce="05c25e77-130a-4164-aae4-06486050c13a", algorithm=MD5, qop="auth"
  233.    Content-Length: 0
  234.  
  235.    ------------------------------------------------------------------------
  236. 2018-11-11 21:39:47.935673 [DEBUG] sofia.c:2413 detaching session 8e1aa3c0-0313-4d15-baa1-1f2fa9ab32b7
  237. recv 402 bytes from udp/[172.19.50.4]:3861 at 21:39:47.954818:
  238.    ------------------------------------------------------------------------
  239.    ACK sip:0108008080800@pbx.[anonym].net;user=phone SIP/2.0
  240.    Via: SIP/2.0/UDP 172.19.50.4:3861;branch=z9hG4bK-03B444AA;rport
  241.    From: <sip:9590206@pbx.[anonym].net>;tag=2770968075
  242.    To: <sip:0108008080800@pbx.[anonym].net;user=phone>;tag=m1S6KcDpDv10S
  243.    Call-ID: 10d5c69393e85b01a048009033060682@410
  244.    CSeq: 1133815520 ACK
  245.    Contact: <sip:9590206@172.19.50.4:3861;transport=UDP>
  246.    Content-Length: 0
  247.    Max-Forwards: 70
  248.  
  249.    ------------------------------------------------------------------------
  250. recv 1593 bytes from udp/[172.19.50.4]:3861 at 21:39:47.957573:
  251.    ------------------------------------------------------------------------
  252.    INVITE sip:0108008080800@pbx.[anonym].net;user=phone SIP/2.0
  253.    Proxy-Authorization: Digest username="9590206",realm="pbx.[anonym].net",nonce="05c25e77-130a-4164-aae4-06486050c13a",response="5bc96013997736bfe9ae31f77fbf613e",uri="sip:0108008080800@pbx.[anonym].net;user=phone",qop=auth,cnonce="719b57f454db5b017ede009033060682",nc=00000001,algorithm=MD5
  254.    Via: SIP/2.0/UDP 172.19.50.4:3861;branch=z9hG4bK-03B444AB;rport
  255.    From: <sip:9590206@pbx.[anonym].net>;tag=2770968075
  256.    To: <sip:0108008080800@pbx.[anonym].net;user=phone>
  257.    Call-ID: 10d5c69393e85b01a048009033060682@410
  258.    CSeq: 1133815521 INVITE
  259.    Contact: <sip:9590206@172.19.50.4:3861;transport=UDP>
  260.   Allow: REGISTER,SUBSCRIBE,NOTIFY,INVITE,ACK,PRACK,OPTIONS,BYE,CANCEL,REFER,INFO,UPDATE,PUBLISH
  261.    Content-Length: 559
  262.    Content-Type: application/sdp
  263.    Max-Forwards: 69
  264.    Supported: 100rel,replaces,privacy,timer,from-change,histinfo,answermode
  265.    User-Agent: (innovaphone IP800/11r2 sr15 [11.3587/113587/106])
  266.    P-Preferred-Identity: <sip:%0631@pbx.[anonym].net;user=phone>
  267.    P-Sig-Options: Overlap-Dialing
  268.  
  269.    v=0
  270.    o=- 1040 1 IN IP4 172.19.50.4
  271.    s=-
  272.    t=0 0
  273.    m=audio 16686 RTP/SAVP 8 18 4 0 97 101
  274.    c=IN IP4 172.19.50.4
  275.    a=rtpmap:8 PCMA/8000
  276.    a=rtpmap:18 G729/8000
  277.    a=rtpmap:4 G723/8000
  278.    a=rtpmap:0 PCMU/8000
  279.    a=rtpmap:97 CLEARMODE/8000
  280.    a=rtpmap:101 telephone-event/8000
  281.    a=fmtp:18 annexb=no
  282.    a=fmtp:101 0-15
  283.    a=ptime:30
  284.    a=silenceSupp:off - - - -
  285.    a=sendrecv
  286.    a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:X0jxiZ/k7DPggObqIRzhtGG43JCiVNd94vDSguFd|2^31
  287.    a=fingerprint:sha-256 A9:4C:E4:69:65:05:D5:43:C7:E5:0A:99:E5:5B:2E:01:80:EE:81:49:C0:C3:47:3C:10:66:B5:FF:8B:5D:BA:5F
  288.    ------------------------------------------------------------------------
  289. 2018-11-11 21:39:47.955698 [DEBUG] sofia.c:2522 Re-attaching to session 8e1aa3c0-0313-4d15-baa1-1f2fa9ab32b7
  290. 2018-11-11 21:39:47.955698 [DEBUG] sofia.c:10080 sofia/internal/9590206@pbx.[anonym].net receiving invite from 172.19.50.4:3861 version: 1.8.2 -3-a98a958ac3 64bit
  291. 2018-11-11 21:39:47.955698 [DEBUG] sofia.c:10251 IP 172.19.50.4 Rejected by acl "domains". Falling back to Digest auth.
  292. 2018-11-11 21:39:48.015666 [DEBUG] sofia.c:7291 Channel sofia/internal/9590206@pbx.[anonym].net entering state [received][100]
  293. 2018-11-11 21:39:48.015666 [DEBUG] sofia.c:7301 Remote SDP:
  294. v=0
  295. o=- 1040 1 IN IP4 172.19.50.4
  296. s=-
  297. t=0 0
  298. m=audio 16686 RTP/SAVP 8 18 4 0 97 101
  299. c=IN IP4 172.19.50.4
  300. a=rtpmap:8 PCMA/8000
  301. a=rtpmap:18 G729/8000
  302. a=fmtp:18 annexb=no
  303. a=rtpmap:4 G723/8000
  304. a=rtpmap:0 PCMU/8000
  305. a=rtpmap:97 CLEARMODE/8000
  306. a=rtpmap:101 telephone-event/8000
  307. a=fmtp:101 0-15
  308. a=ptime:30
  309. a=silenceSupp:off - - - -
  310. a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:X0jxiZ/k7DPggObqIRzhtGG43JCiVNd94vDSguFd|2^31
  311. a=fingerprint:sha-256 A9:4C:E4:69:65:05:D5:43:C7:E5:0A:99:E5:5B:2E:01:80:EE:81:49:C0:C3:47:3C:10:66:B5:FF:8B:5D:BA:5F
  312.  
  313. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMA:8:8000:30:64000:1]/[opus:116:48000:20:0:1]
  314. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMA:8:8000:30:64000:1]/[G722:9:8000:20:64000:1]
  315. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMA:8:8000:30:64000:1]/[PCMA:8:8000:20:64000:1]
  316. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_media.c:5514 Audio Codec Compare [PCMA:8:8000:20:64000:1] is saved as a near-match
  317. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G729:18:8000:30:8000:1]/[opus:116:48000:20:0:1]
  318. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G729:18:8000:30:8000:1]/[G722:9:8000:20:64000:1]
  319. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G729:18:8000:30:8000:1]/[PCMA:8:8000:20:64000:1]
  320. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G723:4:8000:30:6300:1]/[opus:116:48000:20:0:1]
  321. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G723:4:8000:30:6300:1]/[G722:9:8000:20:64000:1]
  322. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [G723:4:8000:30:6300:1]/[PCMA:8:8000:20:64000:1]
  323. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMU:0:8000:30:64000:1]/[opus:116:48000:20:0:1]
  324. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMU:0:8000:30:64000:1]/[G722:9:8000:20:64000:1]
  325. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [PCMU:0:8000:30:64000:1]/[PCMA:8:8000:20:64000:1]
  326. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [CLEARMODE:97:8000:30:0:1]/[opus:116:48000:20:0:1]
  327. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [CLEARMODE:97:8000:30:0:1]/[G722:9:8000:20:64000:1]
  328. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_media.c:5478 Audio Codec Compare [CLEARMODE:97:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
  329. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_media.c:5394 Set telephone-event payload to 101@8000
  330. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_media.c:5585 Substituting codec PCMA@30i@8000h@1c
  331. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_media.c:3781 Set Codec sofia/internal/9590206@pbx.[anonym].net PCMA/8000 30 ms 240 samples 64000 bits 1 channels
  332. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_codec.c:111 sofia/internal/9590206@pbx.[anonym].net Original read codec set to PCMA:8
  333. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_media.c:5737 Set telephone-event payload to 101@8000
  334. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_media.c:5795 sofia/internal/9590206@pbx.[anonym].net Set 2833 dtmf send payload to 101 recv payload to 101
  335. 2018-11-11 21:39:48.015666 [DEBUG] sofia.c:7716 (sofia/internal/9590206@pbx.[anonym].net) State Change CS_NEW -> CS_INIT
  336. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/9590206@pbx.[anonym].net) Running State Change CS_INIT (Cur 1 Tot 21)
  337. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/9590206@pbx.[anonym].net) State INIT
  338. 2018-11-11 21:39:48.015666 [DEBUG] mod_sofia.c:93 sofia/internal/9590206@pbx.[anonym].net SOFIA INIT
  339. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_state_machine.c:40 sofia/internal/9590206@pbx.[anonym].net Standard INIT
  340. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/9590206@pbx.[anonym].net) State Change CS_INIT -> CS_ROUTING
  341. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/9590206@pbx.[anonym].net) State INIT going to sleep
  342. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/9590206@pbx.[anonym].net) Running State Change CS_ROUTING (Cur 1 Tot 21)
  343. 2018-11-11 21:39:48.015666 [DEBUG] switch_channel.c:2249 (sofia/internal/9590206@pbx.[anonym].net) Callstate Change DOWN -> RINGING
  344. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/9590206@pbx.[anonym].net) State ROUTING
  345. 2018-11-11 21:39:48.015666 [DEBUG] mod_sofia.c:154 sofia/internal/9590206@pbx.[anonym].net SOFIA ROUTING
  346. 2018-11-11 21:39:48.015666 [DEBUG] switch_core_state_machine.c:236 sofia/internal/9590206@pbx.[anonym].net Standard ROUTING
  347. 2018-11-11 21:39:48.015666 [INFO] mod_dialplan_xml.c:637 Processing 9590206 < 31>->0108008080800 in context pbx.[anonym].net
  348. send 307 bytes to udp/[172.19.50.4]:3861 at 21:39:48.022253:
  349.    ------------------------------------------------------------------------
  350.    SIP/2.0 100 Trying
  351.    Via: SIP/2.0/UDP 172.19.50.4:3861;branch=z9hG4bK-03B444AB;rport=3861
  352.    From: <sip:9590206@pbx.[anonym].net>;tag=2770968075
  353.    To: <sip:0108008080800@pbx.[anonym].net;user=phone>
  354.    Call-ID: 10d5c69393e85b01a048009033060682@410
  355.    CSeq: 1133815521 INVITE
  356.    User-Agent: FreeSWITCH
  357.    Content-Length: 0
  358.  
  359.    ------------------------------------------------------------------------
  360. Dialplan: sofia/internal/9590206@pbx.[anonym].net parsing [pbx.[anonym].net->user_exists] continue=true
  361. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (PASS) [user_exists] () =~ // break=on-false
  362. Dialplan: sofia/internal/9590206@pbx.[anonym].net Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
  363. EXECUTE sofia/internal/9590206@pbx.[anonym].net set(user_exists=false)
  364. 2018-11-11 21:39:48.115661 [DEBUG] mod_dptools.c:1598 SET sofia/internal/9590206@pbx.[anonym].net [user_exists]=[false]
  365. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
  366. Dialplan: sofia/internal/9590206@pbx.[anonym].net parsing [pbx.[anonym].net->caller-details] continue=true
  367. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (PASS) [caller-details] ${caller_destination}() =~ /^$/ break=never
  368. Dialplan: sofia/internal/9590206@pbx.[anonym].net Action set(caller_destination=${destination_number}) INLINE
  369. EXECUTE sofia/internal/9590206@pbx.[anonym].net set(caller_destination=0108008080800)
  370. 2018-11-11 21:39:48.115661 [DEBUG] mod_dptools.c:1598 SET sofia/internal/9590206@pbx.[anonym].net [caller_destination]=[0108008080800]
  371. Dialplan: sofia/internal/9590206@pbx.[anonym].net Action set(caller_id_name=${caller_id_name}) INLINE
  372. EXECUTE sofia/internal/9590206@pbx.[anonym].net set(caller_id_name=9590206)
  373. 2018-11-11 21:39:48.115661 [DEBUG] mod_dptools.c:1598 SET sofia/internal/9590206@pbx.[anonym].net [caller_id_name]=[9590206]
  374. Dialplan: sofia/internal/9590206@pbx.[anonym].net Action set(caller_id_number=${caller_id_number}) INLINE
  375. EXECUTE sofia/internal/9590206@pbx.[anonym].net set(caller_id_number=9590206)
  376. 2018-11-11 21:39:48.115661 [DEBUG] mod_dptools.c:1598 SET sofia/internal/9590206@pbx.[anonym].net [caller_id_number]=[9590206]
  377. Dialplan: sofia/internal/9590206@pbx.[anonym].net parsing [pbx.[anonym].net->variables] continue=true
  378. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (PASS) [variables] () =~ // break=on-false
  379. Dialplan: sofia/internal/9590206@pbx.[anonym].net Action export(origination_callee_id_name=${destination_number})
  380. Dialplan: sofia/internal/9590206@pbx.[anonym].net Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  381. Dialplan: sofia/internal/9590206@pbx.[anonym].net parsing [pbx.[anonym].net->call-direction] continue=true
  382. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=never
  383. Dialplan: sofia/internal/9590206@pbx.[anonym].net ANTI-Action export(call_direction=local)
  384. Dialplan: sofia/internal/9590206@pbx.[anonym].net parsing [pbx.[anonym].net->user_record] continue=true
  385. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (PASS) [user_record] () =~ // break=on-false
  386. Dialplan: sofia/internal/9590206@pbx.[anonym].net Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE
  387. EXECUTE sofia/internal/9590206@pbx.[anonym].net set(user_record=)
  388. 2018-11-11 21:39:48.175690 [DEBUG] mod_dptools.c:1598 SET sofia/internal/9590206@pbx.[anonym].net [user_record]=[UNDEF]
  389. Dialplan: sofia/internal/9590206@pbx.[anonym].net Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
  390. EXECUTE sofia/internal/9590206@pbx.[anonym].net set(from_user_exists=true)
  391. 2018-11-11 21:39:48.195724 [DEBUG] mod_dptools.c:1598 SET sofia/internal/9590206@pbx.[anonym].net [from_user_exists]=[true]
  392. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  393. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
  394. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  395. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never
  396. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
  397. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  398. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never
  399. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
  400. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
  401. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never
  402. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
  403. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  404. Dialplan: sofia/internal/9590206@pbx.[anonym].net Action set(from_user_record=${user_data ${sip_from_user}@${sip_from_host} var user_record}) INLINE
  405. EXECUTE sofia/internal/9590206@pbx.[anonym].net set(from_user_record=)
  406. 2018-11-11 21:39:48.215720 [DEBUG] mod_dptools.c:1598 SET sofia/internal/9590206@pbx.[anonym].net [from_user_record]=[UNDEF]
  407. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  408. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
  409. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  410. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never
  411. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
  412. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  413. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never
  414. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
  415. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  416. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never
  417. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
  418. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
  419. Dialplan: sofia/internal/9590206@pbx.[anonym].net parsing [pbx.[anonym].net->redial] continue=true
  420. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (FAIL) [redial] destination_number(0108008080800) =~ /^(redial|\*870)$/ break=on-true
  421. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (PASS) [redial] () =~ // break=never
  422. Dialplan: sofia/internal/9590206@pbx.[anonym].net Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
  423. Dialplan: sofia/internal/9590206@pbx.[anonym].net parsing [pbx.[anonym].net->speed_dial] continue=false
  424. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (FAIL) [speed_dial] destination_number(0108008080800) =~ /^\*0(.*)$/ break=on-false
  425. Dialplan: sofia/internal/9590206@pbx.[anonym].net parsing [pbx.[anonym].net->default_caller_id] continue=true
  426. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (PASS) [default_caller_id] ${emergency_caller_id_number}() =~ /^$/ break=never
  427. Dialplan: sofia/internal/9590206@pbx.[anonym].net Action set(emergency_caller_id_name=${default_emergency_caller_id_name}) INLINE
  428. EXECUTE sofia/internal/9590206@pbx.[anonym].net set(emergency_caller_id_name=)
  429. 2018-11-11 21:39:48.215720 [DEBUG] mod_dptools.c:1598 SET sofia/internal/9590206@pbx.[anonym].net [emergency_caller_id_name]=[UNDEF]
  430. Dialplan: sofia/internal/9590206@pbx.[anonym].net Action set(emergency_caller_id_number=${default_emergency_caller_id_number}) INLINE
  431. EXECUTE sofia/internal/9590206@pbx.[anonym].net set(emergency_caller_id_number=)
  432. 2018-11-11 21:39:48.215720 [DEBUG] mod_dptools.c:1598 SET sofia/internal/9590206@pbx.[anonym].net [emergency_caller_id_number]=[UNDEF]
  433. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (PASS) [default_caller_id] ${outbound_caller_id_number}() =~ /^$/ break=never
  434. Dialplan: sofia/internal/9590206@pbx.[anonym].net Action set(outbound_caller_id_name=${default_outbound_caller_id_name}) INLINE
  435. EXECUTE sofia/internal/9590206@pbx.[anonym].net set(outbound_caller_id_name=)
  436. 2018-11-11 21:39:48.215720 [DEBUG] mod_dptools.c:1598 SET sofia/internal/9590206@pbx.[anonym].net [outbound_caller_id_name]=[UNDEF]
  437. Dialplan: sofia/internal/9590206@pbx.[anonym].net Action set(outbound_caller_id_number=${default_outbound_caller_id_number}) INLINE
  438. EXECUTE sofia/internal/9590206@pbx.[anonym].net set(outbound_caller_id_number=)
  439. 2018-11-11 21:39:48.215720 [DEBUG] mod_dptools.c:1598 SET sofia/internal/9590206@pbx.[anonym].net [outbound_caller_id_number]=[UNDEF]
  440. Dialplan: sofia/internal/9590206@pbx.[anonym].net parsing [pbx.[anonym].net->49[anonym]156.d] continue=false
  441. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (PASS) [49[anonym]156.d] ${user_exists}(false) =~ /false/ break=on-false
  442. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (FAIL) [49[anonym]156.d] ${sip_from_user}(9590206) =~ /12/ break=on-false
  443. Dialplan: sofia/internal/9590206@pbx.[anonym].net parsing [pbx.[anonym].net->+49[anonym]206.01d] continue=false
  444. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (PASS) [+49[anonym]206.01d] ${user_exists}(false) =~ /false/ break=on-false
  445. Dialplan: sofia/internal/9590206@pbx.[anonym].net Regex (PASS) [+49[anonym]206.01d] destination_number(0108008080800) =~ /^01(\d+)$/ break=on-false
  446. Dialplan: sofia/internal/9590206@pbx.[anonym].net Action set(sip_h_X-accountcode=${accountcode})
  447. Dialplan: sofia/internal/9590206@pbx.[anonym].net Action export(call_direction=outbound)
  448. Dialplan: sofia/internal/9590206@pbx.[anonym].net Action unset(call_timeout)
  449. Dialplan: sofia/internal/9590206@pbx.[anonym].net Action set(hangup_after_bridge=true)
  450. Dialplan: sofia/internal/9590206@pbx.[anonym].net Action set(effective_caller_id_name=${outbound_caller_id_name})
  451. Dialplan: sofia/internal/9590206@pbx.[anonym].net Action set(effective_caller_id_number=${outbound_caller_id_number})
  452. Dialplan: sofia/internal/9590206@pbx.[anonym].net Action set(inherit_codec=true)
  453. Dialplan: sofia/internal/9590206@pbx.[anonym].net Action set(ignore_display_updates=true)
  454. Dialplan: sofia/internal/9590206@pbx.[anonym].net Action set(callee_id_number=08008080800)
  455. Dialplan: sofia/internal/9590206@pbx.[anonym].net Action set(continue_on_fail=true)
  456. Dialplan: sofia/internal/9590206@pbx.[anonym].net Action set(outbound_prefix=01)
  457. Dialplan: sofia/internal/9590206@pbx.[anonym].net Action bridge(sofia/gateway/737888c4-6119-4b50-9b6d-176732c5fb2d/08008080800)
  458. 2018-11-11 21:39:48.215720 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/9590206@pbx.[anonym].net) State Change CS_ROUTING -> CS_EXECUTE
  459. 2018-11-11 21:39:48.215720 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/9590206@pbx.[anonym].net) State ROUTING going to sleep
  460. 2018-11-11 21:39:48.215720 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/9590206@pbx.[anonym].net) Running State Change CS_EXECUTE (Cur 1 Tot 21)
  461. 2018-11-11 21:39:48.215720 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/9590206@pbx.[anonym].net) State EXECUTE
  462. 2018-11-11 21:39:48.215720 [DEBUG] mod_sofia.c:209 sofia/internal/9590206@pbx.[anonym].net SOFIA EXECUTE
  463. 2018-11-11 21:39:48.215720 [DEBUG] switch_core_state_machine.c:328 sofia/internal/9590206@pbx.[anonym].net Standard EXECUTE
  464. EXECUTE sofia/internal/9590206@pbx.[anonym].net export(origination_callee_id_name=0108008080800)
  465. 2018-11-11 21:39:48.215720 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[0108008080800]
  466. EXECUTE sofia/internal/9590206@pbx.[anonym].net set(RFC2822_DATE=Sun, 11 Nov 2018 21:39:48 +0100)
  467. 2018-11-11 21:39:48.215720 [DEBUG] mod_dptools.c:1598 SET sofia/internal/9590206@pbx.[anonym].net [RFC2822_DATE]=[Sun, 11 Nov 2018 21:39:48 +0100]
  468. EXECUTE sofia/internal/9590206@pbx.[anonym].net export(call_direction=local)
  469. 2018-11-11 21:39:48.215720 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [call_direction]=[local]
  470. EXECUTE sofia/internal/9590206@pbx.[anonym].net hash(insert/pbx.[anonym].net-last_dial/9590206/0108008080800)
  471. EXECUTE sofia/internal/9590206@pbx.[anonym].net set(sip_h_X-accountcode=)
  472. 2018-11-11 21:39:48.235726 [DEBUG] mod_dptools.c:1598 SET sofia/internal/9590206@pbx.[anonym].net [sip_h_X-accountcode]=[UNDEF]
  473. EXECUTE sofia/internal/9590206@pbx.[anonym].net export(call_direction=outbound)
  474. 2018-11-11 21:39:48.235726 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [call_direction]=[outbound]
  475. EXECUTE sofia/internal/9590206@pbx.[anonym].net unset(call_timeout)
  476. 2018-11-11 21:39:48.235726 [DEBUG] mod_dptools.c:1743 UNSET [call_timeout]
  477. EXECUTE sofia/internal/9590206@pbx.[anonym].net set(hangup_after_bridge=true)
  478. 2018-11-11 21:39:48.235726 [DEBUG] mod_dptools.c:1598 SET sofia/internal/9590206@pbx.[anonym].net [hangup_after_bridge]=[true]
  479. EXECUTE sofia/internal/9590206@pbx.[anonym].net set(effective_caller_id_name=)
  480. 2018-11-11 21:39:48.235726 [DEBUG] mod_dptools.c:1598 SET sofia/internal/9590206@pbx.[anonym].net [effective_caller_id_name]=[UNDEF]
  481. EXECUTE sofia/internal/9590206@pbx.[anonym].net set(effective_caller_id_number=)
  482. 2018-11-11 21:39:48.235726 [DEBUG] mod_dptools.c:1598 SET sofia/internal/9590206@pbx.[anonym].net [effective_caller_id_number]=[UNDEF]
  483. EXECUTE sofia/internal/9590206@pbx.[anonym].net set(inherit_codec=true)
  484. 2018-11-11 21:39:48.235726 [DEBUG] mod_dptools.c:1598 SET sofia/internal/9590206@pbx.[anonym].net [inherit_codec]=[true]
  485. EXECUTE sofia/internal/9590206@pbx.[anonym].net set(ignore_display_updates=true)
  486. 2018-11-11 21:39:48.235726 [DEBUG] mod_dptools.c:1598 SET sofia/internal/9590206@pbx.[anonym].net [ignore_display_updates]=[true]
  487. EXECUTE sofia/internal/9590206@pbx.[anonym].net set(callee_id_number=08008080800)
  488. 2018-11-11 21:39:48.235726 [DEBUG] mod_dptools.c:1598 SET sofia/internal/9590206@pbx.[anonym].net [callee_id_number]=[08008080800]
  489. EXECUTE sofia/internal/9590206@pbx.[anonym].net set(continue_on_fail=true)
  490. 2018-11-11 21:39:48.235726 [DEBUG] mod_dptools.c:1598 SET sofia/internal/9590206@pbx.[anonym].net [continue_on_fail]=[true]
  491. EXECUTE sofia/internal/9590206@pbx.[anonym].net set(outbound_prefix=01)
  492. 2018-11-11 21:39:48.235726 [DEBUG] mod_dptools.c:1598 SET sofia/internal/9590206@pbx.[anonym].net [outbound_prefix]=[01]
  493. EXECUTE sofia/internal/9590206@pbx.[anonym].net bridge(sofia/gateway/737888c4-6119-4b50-9b6d-176732c5fb2d/08008080800)
  494. 2018-11-11 21:39:48.235726 [DEBUG] switch_channel.c:1250 sofia/internal/9590206@pbx.[anonym].net EXPORTING[export_vars] [domain_name]=[pbx.[anonym].net] to event
  495. 2018-11-11 21:39:48.235726 [DEBUG] switch_channel.c:1250 sofia/internal/9590206@pbx.[anonym].net EXPORTING[export_vars] [origination_callee_id_name]=[0108008080800] to event
  496. 2018-11-11 21:39:48.235726 [DEBUG] switch_channel.c:1250 sofia/internal/9590206@pbx.[anonym].net EXPORTING[export_vars] [call_direction]=[outbound] to event
  497. 2018-11-11 21:39:48.235726 [DEBUG] switch_channel.c:1250 sofia/internal/9590206@pbx.[anonym].net EXPORTING[export_vars] [call_direction]=[outbound] to event
  498. 2018-11-11 21:39:48.235726 [DEBUG] switch_ivr_originate.c:2204 Parsing global variables
  499. 2018-11-11 21:39:48.235726 [NOTICE] switch_channel.c:1104 New Channel sofia/external/08008080800 [6f89fc02-3687-4576-a315-739df3bd4ed3]
  500. 2018-11-11 21:39:48.235726 [DEBUG] mod_sofia.c:5028 (sofia/external/08008080800) State Change CS_NEW -> CS_INIT
  501. 2018-11-11 21:39:48.235726 [DEBUG] switch_core_state_machine.c:584 (sofia/external/08008080800) Running State Change CS_INIT (Cur 2 Tot 22)
  502. 2018-11-11 21:39:48.235726 [DEBUG] switch_core_state_machine.c:627 (sofia/external/08008080800) State INIT
  503. 2018-11-11 21:39:48.235726 [DEBUG] mod_sofia.c:93 sofia/external/08008080800 SOFIA INIT
  504. 2018-11-11 21:39:48.255701 [DEBUG] sofia_glue.c:1299 sofia/external/08008080800 sending invite version: 1.8.2 -3-a98a958ac3 64bit
  505. Local SDP:
  506. v=0
  507. o=FreeSWITCH 1541947070 1541947071 IN IP4 172.19.50.3
  508. s=FreeSWITCH
  509. c=IN IP4 172.19.50.3
  510. t=0 0
  511. m=audio 21718 RTP/AVP 8 101 13
  512. a=rtpmap:8 PCMA/8000
  513. a=rtpmap:101 telephone-event/8000
  514. a=fmtp:101 0-16
  515. a=rtpmap:13 CN/8000
  516. a=ptime:30
  517. a=sendrecv
  518. m=audio 21718 RTP/AVP 8 101 13
  519. a=rtpmap:8 PCMA/8000
  520. a=rtpmap:101 telephone-event/8000
  521. a=fmtp:101 0-16
  522. a=rtpmap:13 CN/8000
  523. a=ptime:20
  524. a=sendrecv
  525.  
  526. 2018-11-11 21:39:48.255701 [DEBUG] switch_core_state_machine.c:40 sofia/external/08008080800 Standard INIT
  527. 2018-11-11 21:39:48.255701 [DEBUG] switch_core_state_machine.c:48 (sofia/external/08008080800) State Change CS_INIT -> CS_ROUTING
  528. 2018-11-11 21:39:48.255701 [DEBUG] switch_core_state_machine.c:627 (sofia/external/08008080800) State INIT going to sleep
  529. 2018-11-11 21:39:48.255701 [DEBUG] switch_core_state_machine.c:584 (sofia/external/08008080800) Running State Change CS_ROUTING (Cur 2 Tot 22)
  530. send 1264 bytes to udp/[217.0.27.32]:5060 at 21:39:48.257450:
  531.    ------------------------------------------------------------------------
  532.    INVITE sip:08008080800@tel.t-online.de SIP/2.0
  533.    Via: SIP/2.0/UDP 172.19.50.3:5080;rport;branch=z9hG4bKjvmm45ygZaUcc
  534.    Max-Forwards: 68
  535.    From: "9590206" <sip:+49[anonym]206@tel.t-online.de>;tag=4vBe8B7SDN8SQ
  536.    To: <sip:08008080800@tel.t-online.de>
  537.    Call-ID: c5a934a7-6094-1237-1193-000db9478a88
  538.    CSeq: 130648330 INVITE
  539.    Contact: <sip:gw+737888c4-6119-4b50-9b6d-176732c5fb2d@172.19.50.3:5080;transport=udp;gw=737888c4-6119-4b50-9b6d-176732c5fb2d>
  540.    User-Agent: FreeSWITCH
  541.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  542.    Supported: timer, path, replaces
  543.    Allow-Events: talk, hold, conference, refer
  544.    Content-Type: application/sdp
  545.    Content-Disposition: session
  546.    Content-Length: 381
  547.    P-Sig-Options: Overlap-Dialing
  548.    X-FS-Support: update_display,send_info
  549.    Remote-Party-ID: "9590206" <sip: 31@tel.t-online.de>;party=calling;screen=yes;privacy=off
  550.  
  551.    v=0
  552.    o=FreeSWITCH 1541947070 1541947071 IN IP4 172.19.50.3
  553.    s=FreeSWITCH
  554.    c=IN IP4 172.19.50.3
  555.    t=0 0
  556.    m=audio 21718 RTP/AVP 8 101 13
  557.    a=rtpmap:8 PCMA/8000
  558.    a=rtpmap:101 telephone-event/8000
  559.    a=fmtp:101 0-16
  560.    a=rtpmap:13 CN/8000
  561.    a=ptime:30
  562.    m=audio 21718 RTP/AVP 8 101 13
  563.    a=rtpmap:8 PCMA/8000
  564.    a=rtpmap:101 telephone-event/8000
  565.    a=fmtp:101 0-16
  566.    a=rtpmap:13 CN/8000
  567.    a=ptime:20
  568.    ------------------------------------------------------------------------
  569. 2018-11-11 21:39:48.255701 [DEBUG] switch_core_state_machine.c:643 (sofia/external/08008080800) State ROUTING
  570. 2018-11-11 21:39:48.255701 [DEBUG] mod_sofia.c:154 sofia/external/08008080800 SOFIA ROUTING
  571. 2018-11-11 21:39:48.255701 [DEBUG] switch_ivr_originate.c:67 (sofia/external/08008080800) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  572. 2018-11-11 21:39:48.255701 [DEBUG] switch_core_state_machine.c:643 (sofia/external/08008080800) State ROUTING going to sleep
  573. 2018-11-11 21:39:48.255701 [DEBUG] switch_core_state_machine.c:584 (sofia/external/08008080800) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 22)
  574. 2018-11-11 21:39:48.255701 [DEBUG] sofia.c:7291 Channel sofia/external/08008080800 entering state [calling][0]
  575. 2018-11-11 21:39:48.255701 [DEBUG] switch_core_state_machine.c:662 (sofia/external/08008080800) State CONSUME_MEDIA
  576. 2018-11-11 21:39:48.255701 [DEBUG] switch_core_state_machine.c:662 (sofia/external/08008080800) State CONSUME_MEDIA going to sleep
  577. recv 525 bytes from udp/[217.0.27.32]:5060 at 21:39:48.373846:
  578.    ------------------------------------------------------------------------
  579.    SIP/2.0 407 Proxy Authentication Required 02035034C
  580.    Via: SIP/2.0/UDP 172.19.50.3:5080;received=172.19.50.3;rport=5080;branch=z9hG4bKjvmm45ygZaUcc
  581.    To: <sip:08008080800@tel.t-online.de>;tag=h7g4Esbg_662a8891012f38ace0d410e96e5db5dc
  582.    From: "9590206" <sip:+49[anonym]206@tel.t-online.de>;tag=4vBe8B7SDN8SQ
  583.    Call-ID: c5a934a7-6094-1237-1193-000db9478a88
  584.    CSeq: 130648330 INVITE
  585.    Content-Length: 0
  586.    Proxy-Authenticate: Digest nonce="4E391DE4A093E85B00000000C8904334",realm="tel.t-online.de",algorithm=MD5,qop="auth",stale=true
  587.  
  588.    ------------------------------------------------------------------------
  589. send 377 bytes to udp/[217.0.27.32]:5060 at 21:39:48.374475:
  590.    ------------------------------------------------------------------------
  591.    ACK sip:08008080800@tel.t-online.de SIP/2.0
  592.    Via: SIP/2.0/UDP 172.19.50.3:5080;rport;branch=z9hG4bKjvmm45ygZaUcc
  593.    Max-Forwards: 68
  594.    From: "9590206" <sip:+49[anonym]206@tel.t-online.de>;tag=4vBe8B7SDN8SQ
  595.    To: <sip:08008080800@tel.t-online.de>;tag=h7g4Esbg_662a8891012f38ace0d410e96e5db5dc
  596.    Call-ID: c5a934a7-6094-1237-1193-000db9478a88
  597.    CSeq: 130648330 ACK
  598.    Content-Length: 0
  599.  
  600.    ------------------------------------------------------------------------
  601. send 1539 bytes to udp/[217.0.27.32]:5060 at 21:39:48.375770:
  602.    ------------------------------------------------------------------------
  603.    INVITE sip:08008080800@tel.t-online.de SIP/2.0
  604.    Via: SIP/2.0/UDP 172.19.50.3:5080;rport;branch=z9hG4bKK5DD60FmvKHZQ
  605.    Max-Forwards: 68
  606.    From: "9590206" <sip:+49[anonym]206@tel.t-online.de>;tag=4vBe8B7SDN8SQ
  607.    To: <sip:08008080800@tel.t-online.de>
  608.    Call-ID: c5a934a7-6094-1237-1193-000db9478a88
  609.    CSeq: 130648331 INVITE
  610.    Contact: <sip:gw+737888c4-6119-4b50-9b6d-176732c5fb2d@172.19.50.3:5080;transport=udp;gw=737888c4-6119-4b50-9b6d-176732c5fb2d>
  611.    User-Agent: FreeSWITCH
  612.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  613.    Supported: timer, path, replaces
  614.    Allow-Events: talk, hold, conference, refer
  615.    Proxy-Authorization: Digest username="551124935083", realm="tel.t-online.de", nonce="4E391DE4A093E85B00000000C8904334", cnonce="xbs4KGCUEjeTEQANuUeKiA", algorithm=MD5, uri="sip:08008080800@tel.t-online.de", response="f1fa764442e0d8c357c9166be3d16313", qop=auth, nc=00000001
  616.    Content-Type: application/sdp
  617.    Content-Disposition: session
  618.    Content-Length: 381
  619.    P-Sig-Options: Overlap-Dialing
  620.    X-FS-Support: update_display,send_info
  621.    Remote-Party-ID: "9590206" <sip: 31@tel.t-online.de>;party=calling;screen=yes;privacy=off
  622.  
  623.    v=0
  624.    o=FreeSWITCH 1541947070 1541947071 IN IP4 172.19.50.3
  625.    s=FreeSWITCH
  626.    c=IN IP4 172.19.50.3
  627.    t=0 0
  628.    m=audio 21718 RTP/AVP 8 101 13
  629.    a=rtpmap:8 PCMA/8000
  630.    a=rtpmap:101 telephone-event/8000
  631.    a=fmtp:101 0-16
  632.    a=rtpmap:13 CN/8000
  633.    a=ptime:30
  634.    m=audio 21718 RTP/AVP 8 101 13
  635.    a=rtpmap:8 PCMA/8000
  636.    a=rtpmap:101 telephone-event/8000
  637.    a=fmtp:101 0-16
  638.    a=rtpmap:13 CN/8000
  639.    a=ptime:20
  640.    ------------------------------------------------------------------------
  641. 2018-11-11 21:39:48.375669 [DEBUG] sofia.c:7291 Channel sofia/external/08008080800 entering state [calling][0]
  642. recv 317 bytes from udp/[217.0.27.32]:5060 at 21:39:48.588607:
  643.    ------------------------------------------------------------------------
  644.    SIP/2.0 100 Trying
  645.    Via: SIP/2.0/UDP 172.19.50.3:5080;received=172.19.50.3;rport=5080;branch=z9hG4bKK5DD60FmvKHZQ
  646.    To: <sip:08008080800@tel.t-online.de>
  647.    From: "9590206" <sip:+49[anonym]206@tel.t-online.de>;tag=4vBe8B7SDN8SQ
  648.    Call-ID: c5a934a7-6094-1237-1193-000db9478a88
  649.    CSeq: 130648331 INVITE
  650.    Content-Length: 0
  651.  
  652.    ------------------------------------------------------------------------
  653. recv 847 bytes from udp/[217.0.27.32]:5060 at 21:39:48.629995:
  654.    ------------------------------------------------------------------------
  655.    SIP/2.0 183 Session Progress
  656.    Via: SIP/2.0/UDP 172.19.50.3:5080;received=172.19.50.3;rport=5080;branch=z9hG4bKK5DD60FmvKHZQ
  657.    To: <sip:08008080800@tel.t-online.de>;tag=h7g4Esbg_p65555t1541968788m476486c374126833s1_2514769627-1285358402
  658.    From: "9590206" <sip:+49[anonym]206@tel.t-online.de>;tag=4vBe8B7SDN8SQ
  659.    Call-ID: c5a934a7-6094-1237-1193-000db9478a88
  660.    CSeq: 130648331 INVITE
  661.    Contact: <sip:sgc_c@217.0.27.32;transport=udp>
  662.    Record-Route: <sip:217.0.27.32;transport=udp;lr>
  663.    P-Early-Media: sendonly
  664.    Supported: timer
  665.    Content-Type: application/sdp
  666.    Content-Length: 207
  667.    Allow: UPDATE, PRACK, OPTIONS, BYE, ACK, CANCEL, INVITE, REGISTER
  668.  
  669.    v=0
  670.    o=- 1221829659 2514947857 IN IP4 0.0.0.0
  671.    s=SIP Call
  672.    c=IN IP4 0.0.0.0
  673.    t=0 0
  674.    m=audio 0 RTP/AVP 8 101
  675.    a=rtpmap:8 PCMA/8000
  676.    a=rtpmap:101 telephone-event/8000
  677.    a=fmtp:101 0-15
  678.    a=ptime:20
  679.    a=sendrecv
  680.    ------------------------------------------------------------------------
  681. 2018-11-11 21:39:48.615677 [DEBUG] sofia.c:7291 Channel sofia/external/08008080800 entering state [proceeding][183]
  682. 2018-11-11 21:39:48.615677 [DEBUG] sofia.c:7301 Remote SDP:
  683. v=0
  684. o=- 1221829659 2514947857 IN IP4 0.0.0.0
  685. s=SIP Call
  686. c=IN IP4 0.0.0.0
  687. t=0 0
  688. m=audio 0 RTP/AVP 8 101
  689.  
  690. 2018-11-11 21:39:48.615677 [NOTICE] sofia.c:7304 Pre-Answer sofia/external/08008080800!
  691. 2018-11-11 21:39:48.635662 [DEBUG] switch_channel.c:3482 (sofia/external/08008080800) Callstate Change DOWN -> EARLY
  692. 2018-11-11 21:39:48.635662 [WARNING] switch_core_media.c:4877 RFC2543 from March 1999 called; They want their 0.0.0.0 hold method back.....
  693. 2018-11-11 21:39:48.635662 [NOTICE] sofia.c:7482 Hangup sofia/external/08008080800 [CS_CONSUME_MEDIA] [INCOMPATIBLE_DESTINATION]
  694. 2018-11-11 21:39:48.635662 [DEBUG] switch_ivr_originate.c:3941 Originate Resulted in Error Cause: 88 [INCOMPATIBLE_DESTINATION]
  695. 2018-11-11 21:39:48.635662 [DEBUG] switch_core_state_machine.c:584 (sofia/external/08008080800) Running State Change CS_HANGUP (Cur 2 Tot 22)
  696. 2018-11-11 21:39:48.635662 [DEBUG] switch_core_state_machine.c:847 (sofia/external/08008080800) Callstate Change EARLY -> HANGUP
  697. 2018-11-11 21:39:48.635662 [DEBUG] switch_core_state_machine.c:849 (sofia/external/08008080800) State HANGUP
  698. 2018-11-11 21:39:48.635662 [DEBUG] mod_sofia.c:449 Channel sofia/external/08008080800 hanging up, cause: INCOMPATIBLE_DESTINATION
  699. 2018-11-11 21:39:48.635662 [DEBUG] mod_sofia.c:513 Sending CANCEL to sofia/external/08008080800
  700. 2018-11-11 21:39:48.635662 [DEBUG] switch_core_state_machine.c:60 sofia/external/08008080800 Standard HANGUP, cause: INCOMPATIBLE_DESTINATION
  701. 2018-11-11 21:39:48.635662 [DEBUG] switch_core_state_machine.c:849 (sofia/external/08008080800) State HANGUP going to sleep
  702. 2018-11-11 21:39:48.635662 [DEBUG] switch_core_state_machine.c:619 (sofia/external/08008080800) State Change CS_HANGUP -> CS_REPORTING
  703. 2018-11-11 21:39:48.635662 [DEBUG] switch_core_state_machine.c:584 (sofia/external/08008080800) Running State Change CS_REPORTING (Cur 2 Tot 22)
  704. 2018-11-11 21:39:48.635662 [INFO] mod_dptools.c:3518 Originate Failed.  Cause: INCOMPATIBLE_DESTINATION
  705. send 393 bytes to udp/[217.0.27.32]:5060 at 21:39:48.639891:
  706.    ------------------------------------------------------------------------
  707.    CANCEL sip:08008080800@tel.t-online.de SIP/2.0
  708.    Via: SIP/2.0/UDP 172.19.50.3:5080;rport;branch=z9hG4bKK5DD60FmvKHZQ
  709.    Max-Forwards: 68
  710.    From: "9590206" <sip:+49[anonym]206@tel.t-online.de>;tag=4vBe8B7SDN8SQ
  711.    To: <sip:08008080800@tel.t-online.de>
  712.    Call-ID: c5a934a7-6094-1237-1193-000db9478a88
  713.    CSeq: 130648331 CANCEL
  714.    Reason: Q.850;cause=88;text="INCOMPATIBLE_DESTINATION"
  715.    Content-Length: 0
  716.  
  717.    ------------------------------------------------------------------------
  718. 2018-11-11 21:39:48.635662 [DEBUG] switch_core_state_machine.c:935 (sofia/external/08008080800) State REPORTING
  719. 2018-11-11 21:39:48.635662 [DEBUG] switch_core_state_machine.c:174 sofia/external/08008080800 Standard REPORTING, cause: INCOMPATIBLE_DESTINATION
  720. 2018-11-11 21:39:48.635662 [NOTICE] switch_core_state_machine.c:385 sofia/internal/9590206@pbx.[anonym].net has executed the last dialplan instruction, hanging up.
  721. 2018-11-11 21:39:48.635662 [DEBUG] switch_core_state_machine.c:935 (sofia/external/08008080800) State REPORTING going to sleep
  722. 2018-11-11 21:39:48.635662 [NOTICE] switch_core_state_machine.c:387 Hangup sofia/internal/9590206@pbx.[anonym].net [CS_EXECUTE] [NORMAL_CLEARING]
  723. 2018-11-11 21:39:48.635662 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/9590206@pbx.[anonym].net) State EXECUTE going to sleep
  724. 2018-11-11 21:39:48.635662 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/9590206@pbx.[anonym].net) Running State Change CS_HANGUP (Cur 2 Tot 22)
  725. 2018-11-11 21:39:48.635662 [DEBUG] switch_core_state_machine.c:610 (sofia/external/08008080800) State Change CS_REPORTING -> CS_DESTROY
  726. 2018-11-11 21:39:48.635662 [DEBUG] switch_core_session.c:1714 Session 22 (sofia/external/08008080800) Locked, Waiting on external entities
  727. 2018-11-11 21:39:48.635662 [NOTICE] switch_core_session.c:1732 Session 22 (sofia/external/08008080800) Ended
  728. 2018-11-11 21:39:48.635662 [NOTICE] switch_core_session.c:1736 Close Channel sofia/external/08008080800 [CS_DESTROY]
  729. 2018-11-11 21:39:48.635662 [DEBUG] switch_core_state_machine.c:847 (sofia/internal/9590206@pbx.[anonym].net) Callstate Change RINGING -> HANGUP
  730. 2018-11-11 21:39:48.635662 [DEBUG] switch_core_state_machine.c:738 (sofia/external/08008080800) Running State Change CS_DESTROY (Cur 1 Tot 22)
  731. 2018-11-11 21:39:48.635662 [DEBUG] switch_core_state_machine.c:849 (sofia/internal/9590206@pbx.[anonym].net) State HANGUP
  732. 2018-11-11 21:39:48.635662 [DEBUG] mod_sofia.c:449 Channel sofia/internal/9590206@pbx.[anonym].net hanging up, cause: NORMAL_CLEARING
  733. 2018-11-11 21:39:48.635662 [DEBUG] switch_core_state_machine.c:748 (sofia/external/08008080800) State DESTROY
  734. 2018-11-11 21:39:48.635662 [DEBUG] mod_sofia.c:354 sofia/external/08008080800 SOFIA DESTROY
  735. 2018-11-11 21:39:48.635662 [DEBUG] switch_core_state_machine.c:181 sofia/external/08008080800 Standard DESTROY
  736. 2018-11-11 21:39:48.635662 [DEBUG] switch_core_state_machine.c:748 (sofia/external/08008080800) State DESTROY going to sleep
  737. 2018-11-11 21:39:48.635662 [DEBUG] mod_sofia.c:588 Responding to INVITE with: 480
  738. 2018-11-11 21:39:48.635662 [DEBUG] switch_core_state_machine.c:60 sofia/internal/9590206@pbx.[anonym].net Standard HANGUP, cause: NORMAL_CLEARING
  739. 2018-11-11 21:39:48.635662 [DEBUG] switch_core_state_machine.c:849 (sofia/internal/9590206@pbx.[anonym].net) State HANGUP going to sleep
  740. 2018-11-11 21:39:48.635662 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/9590206@pbx.[anonym].net) State Change CS_HANGUP -> CS_REPORTING
  741. 2018-11-11 21:39:48.635662 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/9590206@pbx.[anonym].net) Running State Change CS_REPORTING (Cur 1 Tot 22)
  742. send 815 bytes to udp/[172.19.50.4]:3861 at 21:39:48.645438:
  743.    ------------------------------------------------------------------------
  744.    SIP/2.0 480 Temporarily Unavailable
  745.    Via: SIP/2.0/UDP 172.19.50.4:3861;branch=z9hG4bK-03B444AB;rport=3861
  746.    Max-Forwards: 69
  747.    From: <sip:9590206@pbx.[anonym].net>;tag=2770968075
  748.    To: <sip:0108008080800@pbx.[anonym].net;user=phone>;tag=NaKZN7XSa5QKN
  749.    Call-ID: 10d5c69393e85b01a048009033060682@410
  750.    CSeq: 1133815521 INVITE
  751.    User-Agent: FreeSWITCH
  752.    Accept: application/sdp
  753.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  754.    Supported: timer, path, replaces
  755.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  756.    Reason: Q.850;cause=16;text="NORMAL_CLEARING"
  757.    Content-Length: 0
  758.    P-Asserted-Identity: "08008080800" <sip:08008080800@pbx.[anonym].net>
  759.  
  760.    ------------------------------------------------------------------------
  761. 2018-11-11 21:39:48.635662 [DEBUG] switch_core_state_machine.c:935 (sofia/internal/9590206@pbx.[anonym].net) State REPORTING
  762. recv 687 bytes from udp/[172.19.50.4]:3861 at 21:39:48.648063:
  763.    ------------------------------------------------------------------------
  764.    ACK sip:0108008080800@pbx.[anonym].net;user=phone SIP/2.0
  765.    Proxy-Authorization: Digest username="9590206",realm="pbx.[anonym].net",nonce="05c25e77-130a-4164-aae4-06486050c13a",response="5bc96013997736bfe9ae31f77fbf613e",uri="sip:0108008080800@pbx.[anonym].net;user=phone",qop=auth,cnonce="719b57f454db5b017ede009033060682",nc=00000001,algorithm=MD5
  766.    Via: SIP/2.0/UDP 172.19.50.4:3861;branch=z9hG4bK-03B444AB;rport
  767.    From: <sip:9590206@pbx.[anonym].net>;tag=2770968075
  768.    To: <sip:0108008080800@pbx.[anonym].net;user=phone>;tag=NaKZN7XSa5QKN
  769.    Call-ID: 10d5c69393e85b01a048009033060682@410
  770.    CSeq: 1133815521 ACK
  771.    Contact: <sip:9590206@172.19.50.4:3861;transport=UDP>
  772.    Content-Length: 0
  773.    Max-Forwards: 70
  774.  
  775.    ------------------------------------------------------------------------
  776. recv 410 bytes from udp/[217.0.27.32]:5060 at 21:39:48.650984:
  777.    ------------------------------------------------------------------------
  778.    SIP/2.0 200 OK
  779.    Via: SIP/2.0/UDP 172.19.50.3:5080;received=172.19.50.3;rport=5080;branch=z9hG4bKK5DD60FmvKHZQ
  780.    To: <sip:08008080800@tel.t-online.de>;tag=h7g4Esbg_p65555t1541968788m476486c374126833s1_2514769627-1285358402
  781.    From: "9590206" <sip:+49[anonym]206@tel.t-online.de>;tag=4vBe8B7SDN8SQ
  782.    Call-ID: c5a934a7-6094-1237-1193-000db9478a88
  783.    CSeq: 130648331 CANCEL
  784.    P-Early-Media: sendonly
  785.    Content-Length: 0
  786.  
  787.    ------------------------------------------------------------------------
  788. 2018-11-11 21:39:48.795699 [DEBUG] switch_core_state_machine.c:174 sofia/internal/9590206@pbx.[anonym].net Standard REPORTING, cause: NORMAL_CLEARING
  789. 2018-11-11 21:39:48.795699 [DEBUG] switch_core_state_machine.c:935 (sofia/internal/9590206@pbx.[anonym].net) State REPORTING going to sleep
  790. 2018-11-11 21:39:48.795699 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/9590206@pbx.[anonym].net) State Change CS_REPORTING -> CS_DESTROY
  791. 2018-11-11 21:39:48.795699 [DEBUG] switch_core_session.c:1714 Session 21 (sofia/internal/9590206@pbx.[anonym].net) Locked, Waiting on external entities
  792. 2018-11-11 21:39:48.795699 [NOTICE] switch_core_session.c:1732 Session 21 (sofia/internal/9590206@pbx.[anonym].net) Ended
  793. 2018-11-11 21:39:48.795699 [NOTICE] switch_core_session.c:1736 Close Channel sofia/internal/9590206@pbx.[anonym].net [CS_DESTROY]
  794. 2018-11-11 21:39:48.795699 [DEBUG] switch_core_state_machine.c:738 (sofia/internal/9590206@pbx.[anonym].net) Running State Change CS_DESTROY (Cur 0 Tot 22)
  795. 2018-11-11 21:39:48.795699 [DEBUG] switch_core_state_machine.c:748 (sofia/internal/9590206@pbx.[anonym].net) State DESTROY
  796. 2018-11-11 21:39:48.795699 [DEBUG] mod_sofia.c:354 sofia/internal/9590206@pbx.[anonym].net SOFIA DESTROY
  797. 2018-11-11 21:39:48.795699 [DEBUG] switch_core_state_machine.c:181 sofia/internal/9590206@pbx.[anonym].net Standard DESTROY
  798. 2018-11-11 21:39:48.795699 [DEBUG] switch_core_state_machine.c:748 (sofia/internal/9590206@pbx.[anonym].net) State DESTROY going to sleep
  799. recv 467 bytes from udp/[217.0.27.32]:5060 at 21:39:53.702057:
  800.    ------------------------------------------------------------------------
  801.    SIP/2.0 487 Request Terminated
  802.    Via: SIP/2.0/UDP 172.19.50.3:5080;received=172.19.50.3;rport=5080;branch=z9hG4bKK5DD60FmvKHZQ
  803.    To: <sip:08008080800@tel.t-online.de>;tag=h7g4Esbg_p65555t1541968788m476486c374126833s1_2514769627-1285358402
  804.    From: "9590206" <sip:+49[anonym]206@tel.t-online.de>;tag=4vBe8B7SDN8SQ
  805.    Call-ID: c5a934a7-6094-1237-1193-000db9478a88
  806.    CSeq: 130648331 INVITE
  807.    Contact: <sip:sgc_c@217.0.27.32;transport=udp>
  808.    Supported: timer
  809.    Content-Length: 0
  810.  
  811.    ------------------------------------------------------------------------
  812. send 403 bytes to udp/[217.0.27.32]:5060 at 21:39:53.702670:
  813.    ------------------------------------------------------------------------
  814.    ACK sip:08008080800@tel.t-online.de SIP/2.0
  815.    Via: SIP/2.0/UDP 172.19.50.3:5080;rport;branch=z9hG4bKK5DD60FmvKHZQ
  816.    Max-Forwards: 68
  817.    From: "9590206" <sip:+49[anonym]206@tel.t-online.de>;tag=4vBe8B7SDN8SQ
  818.    To: <sip:08008080800@tel.t-online.de>;tag=h7g4Esbg_p65555t1541968788m476486c374126833s1_2514769627-1285358402
  819.    Call-ID: c5a934a7-6094-1237-1193-000db9478a88
  820.    CSeq: 130648331 ACK
  821.    Content-Length: 0
  822.  

Reply to "no voice with innovaphone ICE disabled"

captcha