freeswitch@internal> recv 967 bytes from tls/[85.18.36.68]:55272 at 12:33:08.114408: ------------------------------------------------------------------------ INVITE sip:101@voice.wicube.net;transport=tls SIP/2.0 Via: SIP/2.0/TLS 172.22.55.229:23309;branch=z9hG4bK-d8754z-925e617a4fdb6345-1---d8754z-;rport Max-Forwards: 70 Contact: To: From: ;tag=6b71e108 Call-ID: NGEyMTUxZDI0YjUxNTI4MDljYjQ2NGQ2Y2ExMzRjODE CSeq: 1 INVITE Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO Content-Type: application/sdp Supported: replaces User-Agent: Bria 3 release 3.5.0b stamp 69410 Content-Length: 393 v=0 o=- 1362054788086402 1 IN IP4 172.22.55.229 s=Bria 3 release 3.5.0b stamp 69410 c=IN IP4 172.22.55.229 b=AS:2064 t=0 0 m=audio 57780 RTP/SAVP 8 0 101 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:R2NgmDwTbaaxPRUFErm1Jcm1Ci074YlExkSiMN3Q a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:R2NgmDwTbaaxPRUFErm1Jcm1Ci074YlExkSiMN3Q a=sendrecv ------------------------------------------------------------------------ send 384 bytes to tls/[85.18.36.68]:55272 at 12:33:08.114775: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/TLS 172.22.55.229:23309;branch=z9hG4bK-d8754z-925e617a4fdb6345-1---d8754z-;rport=55272;received=85.18.36.68 From: ;tag=6b71e108 To: Call-ID: NGEyMTUxZDI0YjUxNTI4MDljYjQ2NGQ2Y2ExMzRjODE CSeq: 1 INVITE User-Agent: FreeSWITCH-mod_sofia/1.2.6+git~20130104T154559Z~a4247651ca Content-Length: 0 ------------------------------------------------------------------------ 2013-02-28 13:12:33.438185 [NOTICE] switch_channel.c:968 New Channel sofia/internal/102@voice.wicube.net [019b7184-81a3-11e2-acb9-ad22efd2ace4] 2013-02-28 13:12:33.438185 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/102@voice.wicube.net [BREAK] 2013-02-28 13:12:33.438185 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/102@voice.wicube.net [BREAK] 2013-02-28 13:12:33.438185 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/102@voice.wicube.net) Running State Change CS_NEW 2013-02-28 13:12:33.438185 [DEBUG] switch_core_state_machine.c:433 (sofia/internal/102@voice.wicube.net) State NEW 2013-02-28 13:12:33.458149 [DEBUG] sofia.c:7729 IP 85.18.36.68 Rejected by acl "domains". Falling back to Digest auth. 2013-02-28 13:12:33.458149 [WARNING] sofia_reg.c:1502 SIP auth challenge (INVITE) on sofia profile 'internal' for [101@voice.wicube.net] from ip 85.18.36.68 send 887 bytes to tls/[85.18.36.68]:55272 at 12:33:08.141400: ------------------------------------------------------------------------ SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/TLS 172.22.55.229:23309;branch=z9hG4bK-d8754z-925e617a4fdb6345-1---d8754z-;rport=55272;received=85.18.36.68 From: ;tag=6b71e108 To: ;tag=QFaSHmF1X86Qg Call-ID: NGEyMTUxZDI0YjUxNTI4MDljYjQ2NGQ2Y2ExMzRjODE CSeq: 1 INVITE User-Agent: FreeSWITCH-mod_sofia/1.2.6+git~20130104T154559Z~a4247651ca Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Allow-Events: talk, hold, conference, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Proxy-Authenticate: Digest realm="voice.wicube.net", nonce="019ea14c-81a3-11e2-acba-ad22efd2ace4", algorithm=MD5, qop="auth" Content-Length: 0 ------------------------------------------------------------------------ 2013-02-28 13:12:33.458149 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/102@voice.wicube.net [BREAK] 2013-02-28 13:12:33.458149 [DEBUG] sofia.c:1750 detaching session 019b7184-81a3-11e2-acb9-ad22efd2ace4 recv 350 bytes from tls/[85.18.36.68]:55272 at 12:33:08.165867: ------------------------------------------------------------------------ ACK sip:101@voice.wicube.net;transport=tls SIP/2.0 Via: SIP/2.0/TLS 172.22.55.229:23309;branch=z9hG4bK-d8754z-925e617a4fdb6345-1---d8754z-;rport Max-Forwards: 70 To: ;tag=QFaSHmF1X86Qg From: ;tag=6b71e108 Call-ID: NGEyMTUxZDI0YjUxNTI4MDljYjQ2NGQ2Y2ExMzRjODE CSeq: 1 ACK Content-Length: 0 ------------------------------------------------------------------------ recv 1247 bytes from tls/[85.18.36.68]:55272 at 12:33:08.228403: ------------------------------------------------------------------------ INVITE sip:101@voice.wicube.net;transport=tls SIP/2.0 Via: SIP/2.0/TLS 172.22.55.229:23309;branch=z9hG4bK-d8754z-1ed3ac4e4ed2fb0d-1---d8754z-;rport Max-Forwards: 70 Contact: To: From: ;tag=6b71e108 Call-ID: NGEyMTUxZDI0YjUxNTI4MDljYjQ2NGQ2Y2ExMzRjODE CSeq: 2 INVITE Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO Content-Type: application/sdp Proxy-Authorization: Digest username="102",realm="voice.wicube.net",nonce="019ea14c-81a3-11e2-acba-ad22efd2ace4",uri="sip:101@voice.wicube.net;transport=tls",response="c4c265a7ed630f5baf61861f213ffa85",cnonce="c2d87f66e274b12b96063e5f6d1afa92",nc=00000001,qop=auth,algorithm=MD5 Supported: replaces User-Agent: Bria 3 release 3.5.0b stamp 69410 Content-Length: 393 v=0 o=- 1362054788086402 1 IN IP4 172.22.55.229 s=Bria 3 release 3.5.0b stamp 69410 c=IN IP4 172.22.55.229 b=AS:2064 t=0 0 m=audio 57780 RTP/SAVP 8 0 101 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:R2NgmDwTbaaxPRUFErm1Jcm1Ci074YlExkSiMN3Q a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:R2NgmDwTbaaxPRUFErm1Jcm1Ci074YlExkSiMN3Q a=sendrecv ------------------------------------------------------------------------ send 384 bytes to tls/[85.18.36.68]:55272 at 12:33:08.228742: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/TLS 172.22.55.229:23309;branch=z9hG4bK-d8754z-1ed3ac4e4ed2fb0d-1---d8754z-;rport=55272;received=85.18.36.68 From: ;tag=6b71e108 To: Call-ID: NGEyMTUxZDI0YjUxNTI4MDljYjQ2NGQ2Y2ExMzRjODE CSeq: 2 INVITE User-Agent: FreeSWITCH-mod_sofia/1.2.6+git~20130104T154559Z~a4247651ca Content-Length: 0 ------------------------------------------------------------------------ 2013-02-28 13:12:33.558196 [DEBUG] sofia.c:1842 Re-attaching to session 019b7184-81a3-11e2-acb9-ad22efd2ace4 2013-02-28 13:12:33.558196 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/102@voice.wicube.net [BREAK] 2013-02-28 13:12:33.558196 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/102@voice.wicube.net [BREAK] 2013-02-28 13:12:33.558196 [DEBUG] sofia.c:7729 IP 85.18.36.68 Rejected by acl "domains". Falling back to Digest auth. 2013-02-28 13:12:33.578143 [DEBUG] sofia.c:5599 Channel sofia/internal/102@voice.wicube.net entering state [received][100] 2013-02-28 13:12:33.578143 [DEBUG] sofia.c:5610 Remote SDP: v=0 o=- 1362054788086402 1 IN IP4 172.22.55.229 s=Bria 3 release 3.5.0b stamp 69410 c=IN IP4 172.22.55.229 b=AS:2064 t=0 0 m=audio 57780 RTP/SAVP 8 0 101 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:R2NgmDwTbaaxPRUFErm1Jcm1Ci074YlExkSiMN3Q a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:R2NgmDwTbaaxPRUFErm1Jcm1Ci074YlExkSiMN3Q 2013-02-28 13:12:33.578143 [DEBUG] sofia_glue.c:4978 Set Remote Key [1 AES_CM_128_HMAC_SHA1_80 inline:R2NgmDwTbaaxPRUFErm1Jcm1Ci074YlExkSiMN3Q] 2013-02-28 13:12:33.578143 [DEBUG] sofia_glue.c:3155 Set Local Key [1 AES_CM_128_HMAC_SHA1_80 inline:ctGA+qVyHINVQ/LypSDfJ+OdCPJPp57nGMPtm/nw] 2013-02-28 13:12:33.578143 [DEBUG] sofia_glue.c:5137 Audio Codec Compare [PCMA:8:8000:20:64000]/[G722:9:8000:20:64000] 2013-02-28 13:12:33.578143 [DEBUG] sofia_glue.c:5137 Audio Codec Compare [PCMA:8:8000:20:64000]/[PCMU:0:8000:20:64000] 2013-02-28 13:12:33.578143 [DEBUG] sofia_glue.c:5137 Audio Codec Compare [PCMA:8:8000:20:64000]/[PCMA:8:8000:20:64000] 2013-02-28 13:12:33.578143 [DEBUG] sofia_glue.c:3093 Set Codec sofia/internal/102@voice.wicube.net PCMA/8000 20 ms 160 samples 64000 bits 2013-02-28 13:12:33.578143 [DEBUG] switch_core_codec.c:111 sofia/internal/102@voice.wicube.net Original read codec set to PCMA:8 2013-02-28 13:12:33.578143 [DEBUG] sofia_glue.c:5266 Set 2833 dtmf send/recv payload to 101 2013-02-28 13:12:33.578143 [DEBUG] sofia.c:5827 (sofia/internal/102@voice.wicube.net) State Change CS_NEW -> CS_INIT 2013-02-28 13:12:33.578143 [DEBUG] switch_core_session.c:1283 Send signal sofia/internal/102@voice.wicube.net [BREAK] 2013-02-28 13:12:33.578143 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/102@voice.wicube.net) Running State Change CS_INIT 2013-02-28 13:12:33.578143 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/102@voice.wicube.net) State INIT 2013-02-28 13:12:33.578143 [DEBUG] mod_sofia.c:86 sofia/internal/102@voice.wicube.net SOFIA INIT 2013-02-28 13:12:33.578143 [DEBUG] mod_sofia.c:126 (sofia/internal/102@voice.wicube.net) State Change CS_INIT -> CS_ROUTING 2013-02-28 13:12:33.578143 [DEBUG] switch_core_session.c:1283 Send signal sofia/internal/102@voice.wicube.net [BREAK] 2013-02-28 13:12:33.578143 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/102@voice.wicube.net) State INIT going to sleep 2013-02-28 13:12:33.578143 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/102@voice.wicube.net) Running State Change CS_ROUTING 2013-02-28 13:12:33.578143 [DEBUG] switch_channel.c:2003 (sofia/internal/102@voice.wicube.net) Callstate Change DOWN -> RINGING 2013-02-28 13:12:33.578143 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/102@voice.wicube.net) State ROUTING 2013-02-28 13:12:33.578143 [DEBUG] mod_sofia.c:149 sofia/internal/102@voice.wicube.net SOFIA ROUTING 2013-02-28 13:12:33.578143 [DEBUG] switch_core_state_machine.c:117 sofia/internal/102@voice.wicube.net Standard ROUTING 2013-02-28 13:12:33.578143 [INFO] mod_dialplan_xml.c:557 Processing 102 <102>->101 in context default Dialplan: sofia/internal/102@voice.wicube.net parsing [default->101] continue=false Dialplan: sofia/internal/102@voice.wicube.net Regex (PASS) [101] destination_number(101) =~ /^101$/ break=on-false Dialplan: sofia/internal/102@voice.wicube.net Action bridge(USER/101@82.85.60.237) 2013-02-28 13:12:33.578143 [DEBUG] switch_core_state_machine.c:167 (sofia/internal/102@voice.wicube.net) State Change CS_ROUTING -> CS_EXECUTE 2013-02-28 13:12:33.578143 [DEBUG] switch_core_session.c:1283 Send signal sofia/internal/102@voice.wicube.net [BREAK] 2013-02-28 13:12:33.578143 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/102@voice.wicube.net) State ROUTING going to sleep 2013-02-28 13:12:33.578143 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/102@voice.wicube.net) Running State Change CS_EXECUTE 2013-02-28 13:12:33.578143 [DEBUG] switch_core_state_machine.c:477 (sofia/internal/102@voice.wicube.net) State EXECUTE 2013-02-28 13:12:33.578143 [DEBUG] mod_sofia.c:242 sofia/internal/102@voice.wicube.net SOFIA EXECUTE 2013-02-28 13:12:33.578143 [DEBUG] switch_core_state_machine.c:209 sofia/internal/102@voice.wicube.net Standard EXECUTE EXECUTE sofia/internal/102@voice.wicube.net bridge(USER/101@82.85.60.237) 2013-02-28 13:12:33.578143 [DEBUG] switch_channel.c:1089 sofia/internal/102@voice.wicube.net EXPORTING[export_vars] [domain_name]=[82.85.60.237] to event 2013-02-28 13:12:33.578143 [DEBUG] switch_ivr_originate.c:2022 Parsing global variables 2013-02-28 13:12:33.578143 [DEBUG] switch_channel.c:1089 sofia/internal/102@voice.wicube.net EXPORTING[export_vars] [domain_name]=[82.85.60.237] to event 2013-02-28 13:12:33.578143 [DEBUG] switch_ivr_originate.c:2022 Parsing global variables 2013-02-28 13:12:33.578143 [DEBUG] switch_event.c:1608 Parsing variable [sip_invite_domain]=[82.85.60.237] 2013-02-28 13:12:33.578143 [DEBUG] switch_event.c:1608 Parsing variable [presence_id]=[101@82.85.60.237] 2013-02-28 13:12:33.578143 [NOTICE] switch_channel.c:968 New Channel sofia/internal/sip:101@85.18.36.68:51187 [01b12f2e-81a3-11e2-acc3-ad22efd2ace4] 2013-02-28 13:12:33.578143 [DEBUG] mod_sofia.c:4970 (sofia/internal/sip:101@85.18.36.68:51187) State Change CS_NEW -> CS_INIT 2013-02-28 13:12:33.578143 [DEBUG] switch_core_session.c:1283 Send signal sofia/internal/sip:101@85.18.36.68:51187 [BREAK] 2013-02-28 13:12:33.578143 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/sip:101@85.18.36.68:51187) Running State Change CS_INIT 2013-02-28 13:12:33.578143 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/sip:101@85.18.36.68:51187) State INIT 2013-02-28 13:12:33.578143 [DEBUG] mod_sofia.c:86 sofia/internal/sip:101@85.18.36.68:51187 SOFIA INIT 2013-02-28 13:12:33.578143 [DEBUG] sofia_glue.c:2618 sip:101@85.18.36.68:51187;transport=TLS;ob Setting proxy route to sofia/internal/sip:101@85.18.36.68:51187 2013-02-28 13:12:33.578143 [DEBUG] sofia_glue.c:2647 Local SDP: v=0 o=FreeSWITCH 1362036675 1362036676 IN IP4 82.85.60.237 s=FreeSWITCH c=IN IP4 82.85.60.237 t=0 0 m=audio 16878 RTP/AVP 8 9 0 3 101 13 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv 2013-02-28 13:12:33.578143 [DEBUG] mod_sofia.c:126 (sofia/internal/sip:101@85.18.36.68:51187) State Change CS_INIT -> CS_ROUTING 2013-02-28 13:12:33.578143 [DEBUG] switch_core_session.c:1283 Send signal sofia/internal/sip:101@85.18.36.68:51187 [BREAK] 2013-02-28 13:12:33.578143 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/sip:101@85.18.36.68:51187) State INIT going to sleep 2013-02-28 13:12:33.578143 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/sip:101@85.18.36.68:51187) Running State Change CS_ROUTING 2013-02-28 13:12:33.578143 [DEBUG] switch_channel.c:2003 (sofia/internal/sip:101@85.18.36.68:51187) Callstate Change DOWN -> RINGING 2013-02-28 13:12:33.578143 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/sip:101@85.18.36.68:51187) State ROUTING 2013-02-28 13:12:33.578143 [DEBUG] mod_sofia.c:149 sofia/internal/sip:101@85.18.36.68:51187 SOFIA ROUTING 2013-02-28 13:12:33.578143 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/sip:101@85.18.36.68:51187) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2013-02-28 13:12:33.578143 [DEBUG] switch_core_session.c:1283 Send signal sofia/internal/sip:101@85.18.36.68:51187 [BREAK] 2013-02-28 13:12:33.578143 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/sip:101@85.18.36.68:51187) State ROUTING going to sleep 2013-02-28 13:12:33.578143 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/sip:101@85.18.36.68:51187) Running State Change CS_CONSUME_MEDIA 2013-02-28 13:12:33.578143 [DEBUG] switch_core_state_machine.c:489 (sofia/internal/sip:101@85.18.36.68:51187) State CONSUME_MEDIA 2013-02-28 13:12:33.578143 [DEBUG] switch_core_state_machine.c:489 (sofia/internal/sip:101@85.18.36.68:51187) State CONSUME_MEDIA going to sleep send 1182 bytes to tls/[85.18.36.68]:51187 at 12:33:08.259885: ------------------------------------------------------------------------ INVITE sip:101@85.18.36.68:51187;transport=TLS;ob SIP/2.0 Via: SIP/2.0/TLS 82.85.60.237;branch=z9hG4bK3taKZp7N8grXj Max-Forwards: 69 From: "Vic Mac Pro" ;tag=S1vaNaH8QtKXQ To: Call-ID: d90fbc70-fc45-1230-f395-005056b93f51 CSeq: 40691330 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.2.6+git~20130104T154559Z~a4247651ca Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Allow-Events: talk, hold, conference, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 207 X-FS-Support: update_display,send_info Remote-Party-ID: "Vic Mac Pro" ;party=calling;screen=yes;privacy=off v=0 o=FreeSWITCH 1362036675 1362036676 IN IP4 82.85.60.237 s=FreeSWITCH c=IN IP4 82.85.60.237 t=0 0 m=audio 16878 RTP/AVP 8 9 0 3 101 13 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ 2013-02-28 13:12:33.578143 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/sip:101@85.18.36.68:51187 [BREAK] 2013-02-28 13:12:33.578143 [DEBUG] sofia.c:5599 Channel sofia/internal/sip:101@85.18.36.68:51187 entering state [calling][0] recv 341 bytes from tls/[85.18.36.68]:51187 at 12:33:08.332434: ------------------------------------------------------------------------ SIP/2.0 488 Not Acceptable Here Via: SIP/2.0/TLS 82.85.60.237;received=82.85.60.237;branch=z9hG4bK3taKZp7N8grXj;alias Call-ID: d90fbc70-fc45-1230-f395-005056b93f51 From: "Vic Mac Pro" ;tag=S1vaNaH8QtKXQ To: ;tag=GSUFFx7sd2US6fRO3fWnz40WRNdua4Eo CSeq: 40691330 INVITE Content-Length: 0 ------------------------------------------------------------------------ send 350 bytes to tls/[85.18.36.68]:51187 at 12:33:08.332571: ------------------------------------------------------------------------ ACK sip:101@85.18.36.68:51187;transport=TLS;ob SIP/2.0 Via: SIP/2.0/TLS 82.85.60.237;branch=z9hG4bK3taKZp7N8grXj Max-Forwards: 69 From: "Vic Mac Pro" ;tag=S1vaNaH8QtKXQ To: ;tag=GSUFFx7sd2US6fRO3fWnz40WRNdua4Eo Call-ID: d90fbc70-fc45-1230-f395-005056b93f51 CSeq: 40691330 ACK Content-Length: 0 ------------------------------------------------------------------------ 2013-02-28 13:12:33.658123 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/sip:101@85.18.36.68:51187 [BREAK] 2013-02-28 13:12:33.658123 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/sip:101@85.18.36.68:51187 [BREAK] 2013-02-28 13:12:33.658123 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/sip:101@85.18.36.68:51187 [BREAK] 2013-02-28 13:12:33.658123 [DEBUG] sofia.c:5599 Channel sofia/internal/sip:101@85.18.36.68:51187 entering state [terminated][488] 2013-02-28 13:12:33.658123 [DEBUG] switch_channel.c:2994 (sofia/internal/sip:101@85.18.36.68:51187) Callstate Change RINGING -> HANGUP 2013-02-28 13:12:33.658123 [NOTICE] sofia.c:6383 Hangup sofia/internal/sip:101@85.18.36.68:51187 [CS_CONSUME_MEDIA] [INCOMPATIBLE_DESTINATION] 2013-02-28 13:12:33.658123 [DEBUG] switch_channel.c:3017 Send signal sofia/internal/sip:101@85.18.36.68:51187 [KILL] 2013-02-28 13:12:33.658123 [DEBUG] switch_core_session.c:1283 Send signal sofia/internal/sip:101@85.18.36.68:51187 [BREAK] 2013-02-28 13:12:33.658123 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/sip:101@85.18.36.68:51187) Running State Change CS_HANGUP 2013-02-28 13:12:33.658123 [DEBUG] switch_core_state_machine.c:667 (sofia/internal/sip:101@85.18.36.68:51187) State HANGUP 2013-02-28 13:12:33.658123 [DEBUG] mod_sofia.c:503 Channel sofia/internal/sip:101@85.18.36.68:51187 hanging up, cause: INCOMPATIBLE_DESTINATION 2013-02-28 13:12:33.658123 [DEBUG] switch_core_state_machine.c:48 sofia/internal/sip:101@85.18.36.68:51187 Standard HANGUP, cause: INCOMPATIBLE_DESTINATION 2013-02-28 13:12:33.658123 [DEBUG] switch_core_state_machine.c:667 (sofia/internal/sip:101@85.18.36.68:51187) State HANGUP going to sleep 2013-02-28 13:12:33.658123 [DEBUG] switch_core_state_machine.c:446 (sofia/internal/sip:101@85.18.36.68:51187) State Change CS_HANGUP -> CS_REPORTING 2013-02-28 13:12:33.658123 [DEBUG] switch_core_session.c:1283 Send signal sofia/internal/sip:101@85.18.36.68:51187 [BREAK] 2013-02-28 13:12:33.658123 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/sip:101@85.18.36.68:51187) Running State Change CS_REPORTING 2013-02-28 13:12:33.658123 [DEBUG] switch_ivr_originate.c:3533 Originate Resulted in Error Cause: 88 [INCOMPATIBLE_DESTINATION] 2013-02-28 13:12:33.658123 [NOTICE] switch_ivr_originate.c:2608 Cannot create outgoing channel of type [USER] cause: [INCOMPATIBLE_DESTINATION] 2013-02-28 13:12:33.658123 [DEBUG] switch_ivr_originate.c:3533 Originate Resulted in Error Cause: 88 [INCOMPATIBLE_DESTINATION] 2013-02-28 13:12:33.658123 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/sip:101@85.18.36.68:51187) State REPORTING 2013-02-28 13:12:33.658123 [DEBUG] switch_core_state_machine.c:92 sofia/internal/sip:101@85.18.36.68:51187 Standard REPORTING, cause: INCOMPATIBLE_DESTINATION 2013-02-28 13:12:33.658123 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/sip:101@85.18.36.68:51187) State REPORTING going to sleep 2013-02-28 13:12:33.658123 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/sip:101@85.18.36.68:51187) State Change CS_REPORTING -> CS_DESTROY 2013-02-28 13:12:33.658123 [DEBUG] switch_core_session.c:1283 Send signal sofia/internal/sip:101@85.18.36.68:51187 [BREAK] 2013-02-28 13:12:33.658123 [DEBUG] switch_core_session.c:1488 Session 24 (sofia/internal/sip:101@85.18.36.68:51187) Locked, Waiting on external entities 2013-02-28 13:12:33.658123 [NOTICE] switch_core_session.c:1506 Session 24 (sofia/internal/sip:101@85.18.36.68:51187) Ended 2013-02-28 13:12:33.658123 [NOTICE] switch_core_session.c:1510 Close Channel sofia/internal/sip:101@85.18.36.68:51187 [CS_DESTROY] 2013-02-28 13:12:33.658123 [DEBUG] switch_core_state_machine.c:556 (sofia/internal/sip:101@85.18.36.68:51187) Callstate Change HANGUP -> DOWN 2013-02-28 13:12:33.658123 [INFO] mod_dptools.c:3052 Originate Failed. Cause: INCOMPATIBLE_DESTINATION 2013-02-28 13:12:33.658123 [DEBUG] switch_channel.c:2994 (sofia/internal/102@voice.wicube.net) Callstate Change RINGING -> HANGUP 2013-02-28 13:12:33.658123 [NOTICE] mod_dptools.c:3172 Hangup sofia/internal/102@voice.wicube.net [CS_EXECUTE] [INCOMPATIBLE_DESTINATION] 2013-02-28 13:12:33.658123 [DEBUG] switch_channel.c:3017 Send signal sofia/internal/102@voice.wicube.net [KILL] 2013-02-28 13:12:33.658123 [DEBUG] switch_core_session.c:1283 Send signal sofia/internal/102@voice.wicube.net [BREAK] 2013-02-28 13:12:33.658123 [DEBUG] switch_core_state_machine.c:559 (sofia/internal/sip:101@85.18.36.68:51187) Running State Change CS_DESTROY 2013-02-28 13:12:33.658123 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/sip:101@85.18.36.68:51187) State DESTROY 2013-02-28 13:12:33.658123 [DEBUG] mod_sofia.c:396 sofia/internal/sip:101@85.18.36.68:51187 SOFIA DESTROY 2013-02-28 13:12:33.658123 [DEBUG] switch_core_state_machine.c:99 sofia/internal/sip:101@85.18.36.68:51187 Standard DESTROY 2013-02-28 13:12:33.658123 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/sip:101@85.18.36.68:51187) State DESTROY going to sleep 2013-02-28 13:12:33.658123 [DEBUG] switch_core_session.c:2678 sofia/internal/102@voice.wicube.net skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2013-02-28 13:12:33.658123 [DEBUG] switch_core_state_machine.c:477 (sofia/internal/102@voice.wicube.net) State EXECUTE going to sleep 2013-02-28 13:12:33.658123 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/102@voice.wicube.net) Running State Change CS_HANGUP 2013-02-28 13:12:33.658123 [DEBUG] switch_core_state_machine.c:667 (sofia/internal/102@voice.wicube.net) State HANGUP 2013-02-28 13:12:33.658123 [DEBUG] mod_sofia.c:497 sofia/internal/102@voice.wicube.net Overriding SIP cause 488 with 488 from the other leg 2013-02-28 13:12:33.658123 [DEBUG] mod_sofia.c:503 Channel sofia/internal/102@voice.wicube.net hanging up, cause: INCOMPATIBLE_DESTINATION 2013-02-28 13:12:33.678270 [DEBUG] mod_sofia.c:633 Responding to INVITE with: 488 2013-02-28 13:12:33.678270 [DEBUG] switch_core_state_machine.c:48 sofia/internal/102@voice.wicube.net Standard HANGUP, cause: INCOMPATIBLE_DESTINATION 2013-02-28 13:12:33.678270 [DEBUG] switch_core_state_machine.c:667 (sofia/internal/102@voice.wicube.net) State HANGUP going to sleep 2013-02-28 13:12:33.678270 [DEBUG] switch_core_state_machine.c:446 (sofia/internal/102@voice.wicube.net) State Change CS_HANGUP -> CS_REPORTING 2013-02-28 13:12:33.678270 [DEBUG] switch_core_session.c:1283 Send signal sofia/internal/102@voice.wicube.net [BREAK] 2013-02-28 13:12:33.678270 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/102@voice.wicube.net) Running State Change CS_REPORTING 2013-02-28 13:12:33.678270 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/102@voice.wicube.net) State REPORTING send 912 bytes to tls/[85.18.36.68]:55272 at 12:33:08.356137: ------------------------------------------------------------------------ SIP/2.0 488 Not Acceptable Here Via: SIP/2.0/TLS 172.22.55.229:23309;branch=z9hG4bK-d8754z-1ed3ac4e4ed2fb0d-1---d8754z-;rport=55272;received=85.18.36.68 Max-Forwards: 70 From: ;tag=6b71e108 To: ;tag=rr3HKF04tHXac Call-ID: NGEyMTUxZDI0YjUxNTI4MDljYjQ2NGQ2Y2ExMzRjODE CSeq: 2 INVITE User-Agent: FreeSWITCH-mod_sofia/1.2.6+git~20130104T154559Z~a4247651ca Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Allow-Events: talk, hold, conference, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Reason: Q.850;cause=88;text="INCOMPATIBLE_DESTINATION" Content-Length: 0 Remote-Party-ID: "101" ;party=calling;privacy=off;screen=no ------------------------------------------------------------------------ 2013-02-28 13:12:33.721161 [DEBUG] switch_core_state_machine.c:92 sofia/internal/102@voice.wicube.net Standard REPORTING, cause: INCOMPATIBLE_DESTINATION 2013-02-28 13:12:33.721161 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/102@voice.wicube.net) State REPORTING going to sleep 2013-02-28 13:12:33.721161 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/102@voice.wicube.net) State Change CS_REPORTING -> CS_DESTROY 2013-02-28 13:12:33.721161 [DEBUG] switch_core_session.c:1283 Send signal sofia/internal/102@voice.wicube.net [BREAK] 2013-02-28 13:12:33.721161 [DEBUG] switch_core_session.c:1488 Session 23 (sofia/internal/102@voice.wicube.net) Locked, Waiting on external entities 2013-02-28 13:12:33.721161 [NOTICE] switch_core_session.c:1506 Session 23 (sofia/internal/102@voice.wicube.net) Ended 2013-02-28 13:12:33.721161 [NOTICE] switch_core_session.c:1510 Close Channel sofia/internal/102@voice.wicube.net [CS_DESTROY] 2013-02-28 13:12:33.721161 [DEBUG] switch_core_state_machine.c:556 (sofia/internal/102@voice.wicube.net) Callstate Change HANGUP -> DOWN 2013-02-28 13:12:33.721161 [DEBUG] switch_core_state_machine.c:559 (sofia/internal/102@voice.wicube.net) Running State Change CS_DESTROY 2013-02-28 13:12:33.721161 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/102@voice.wicube.net) State DESTROY 2013-02-28 13:12:33.721161 [DEBUG] mod_sofia.c:396 sofia/internal/102@voice.wicube.net SOFIA DESTROY 2013-02-28 13:12:33.721161 [DEBUG] switch_core_state_machine.c:99 sofia/internal/102@voice.wicube.net Standard DESTROY 2013-02-28 13:12:33.721161 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/102@voice.wicube.net) State DESTROY going to sleep recv 350 bytes from tls/[85.18.36.68]:55272 at 12:33:08.426513: ------------------------------------------------------------------------ ACK sip:101@voice.wicube.net;transport=tls SIP/2.0 Via: SIP/2.0/TLS 172.22.55.229:23309;branch=z9hG4bK-d8754z-1ed3ac4e4ed2fb0d-1---d8754z-;rport Max-Forwards: 70 To: ;tag=rr3HKF04tHXac From: ;tag=6b71e108 Call-ID: NGEyMTUxZDI0YjUxNTI4MDljYjQ2NGQ2Y2ExMzRjODE CSeq: 2 ACK Content-Length: 0 ------------------------------------------------------------------------