freeswitch@internal> recv 1333 bytes from tls/[85.18.36.68]:51187 at 12:27:39.121764: ------------------------------------------------------------------------ INVITE sip:102@voice.wicube.net SIP/2.0 Via: SIP/2.0/TLS 85.18.36.68:51187;rport;branch=z9hG4bKPjbSUoRnakXwBPZqfm76TRKwoxH-KVdl9a;alias Max-Forwards: 70 From: "101" ;tag=RQ-SQdhcZuhI5SYNSNKTY9F-9-.unsww To: Contact: ;+sip.ice Call-ID: 5xUNyMbEzeDbgO48GR2uPiITtdGkVHm9 CSeq: 31360 INVITE Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS Supported: replaces, 100rel, timer, norefersub User-Agent: Bria iOS 2.3.4 Content-Type: application/sdp Content-Length: 717 v=0 o=- 3571043268 3571043268 IN IP4 172.22.55.178 s=cpc_med c=IN IP4 172.22.55.178 t=0 0 m=audio 53530 RTP/SAVP 0 8 101 a=sendrecv a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:fqwD6Slr4snIn2sJPbAtfR+sLr/VRQbTOmtdi7JH a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:FvJ8slt1jidvluNFDVItIgmux+WiqgIFoAp+ItS5 a=ice-ufrag:7bec20f6 a=ice-pwd:4b683609 a=candidate:Hac1637b2 1 UDP 2130706431 172.22.55.178 53530 typ host a=candidate:H55ad1d9 1 UDP 2130706431 5.90.209.217 53530 typ host a=candidate:Hac1637b2 2 UDP 2130706430 172.22.55.178 58436 typ host a=candidate:H55ad1d9 2 UDP 2130706430 5.90.209.217 58436 typ host ------------------------------------------------------------------------ send 388 bytes to tls/[85.18.36.68]:51187 at 12:27:39.122154: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/TLS 85.18.36.68:51187;rport=51187;branch=z9hG4bKPjbSUoRnakXwBPZqfm76TRKwoxH-KVdl9a;alias From: "101" ;tag=RQ-SQdhcZuhI5SYNSNKTY9F-9-.unsww To: Call-ID: 5xUNyMbEzeDbgO48GR2uPiITtdGkVHm9 CSeq: 31360 INVITE User-Agent: FreeSWITCH-mod_sofia/1.2.6+git~20130104T154559Z~a4247651ca Content-Length: 0 ------------------------------------------------------------------------ 2013-02-28 13:07:04.438143 [NOTICE] switch_channel.c:968 New Channel sofia/internal/101@voice.wicube.net [3d835816-81a2-11e2-ac9e-ad22efd2ace4] 2013-02-28 13:07:04.438143 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/101@voice.wicube.net [BREAK] 2013-02-28 13:07:04.438143 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/101@voice.wicube.net [BREAK] 2013-02-28 13:07:04.458126 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/101@voice.wicube.net) Running State Change CS_NEW 2013-02-28 13:07:04.458126 [DEBUG] switch_core_state_machine.c:433 (sofia/internal/101@voice.wicube.net) State NEW 2013-02-28 13:07:04.478139 [DEBUG] sofia.c:7729 IP 85.18.36.68 Rejected by acl "domains". Falling back to Digest auth. 2013-02-28 13:07:04.478139 [WARNING] sofia_reg.c:1502 SIP auth challenge (INVITE) on sofia profile 'internal' for [102@voice.wicube.net] from ip 85.18.36.68 send 891 bytes to tls/[85.18.36.68]:51187 at 12:27:39.150958: ------------------------------------------------------------------------ SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/TLS 85.18.36.68:51187;rport=51187;branch=z9hG4bKPjbSUoRnakXwBPZqfm76TRKwoxH-KVdl9a;alias From: "101" ;tag=RQ-SQdhcZuhI5SYNSNKTY9F-9-.unsww To: ;tag=Dm4763BDQjZ6S Call-ID: 5xUNyMbEzeDbgO48GR2uPiITtdGkVHm9 CSeq: 31360 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="3d86ab38-81a2-11e2-ac9f-ad22efd2ace4", algorithm=MD5, qop="auth" Content-Length: 0 ------------------------------------------------------------------------ 2013-02-28 13:07:04.478139 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/101@voice.wicube.net [BREAK] 2013-02-28 13:07:04.478139 [DEBUG] sofia.c:1750 detaching session 3d835816-81a2-11e2-ac9e-ad22efd2ace4 recv 362 bytes from tls/[85.18.36.68]:51187 at 12:27:39.207888: ------------------------------------------------------------------------ ACK sip:102@voice.wicube.net SIP/2.0 Via: SIP/2.0/TLS 85.18.36.68:51187;rport;branch=z9hG4bKPjbSUoRnakXwBPZqfm76TRKwoxH-KVdl9a;alias Max-Forwards: 70 From: "101" ;tag=RQ-SQdhcZuhI5SYNSNKTY9F-9-.unsww To: ;tag=Dm4763BDQjZ6S Call-ID: 5xUNyMbEzeDbgO48GR2uPiITtdGkVHm9 CSeq: 31360 ACK Content-Length: 0 ------------------------------------------------------------------------ recv 1607 bytes from tls/[85.18.36.68]:51187 at 12:27:39.281632: ------------------------------------------------------------------------ INVITE sip:102@voice.wicube.net SIP/2.0 Via: SIP/2.0/TLS 85.18.36.68:51187;rport;branch=z9hG4bKPjVkpmhYfPXXJ.MmmYQ62ufvtZD0K5i8Lf;alias Max-Forwards: 70 From: "101" ;tag=RQ-SQdhcZuhI5SYNSNKTY9F-9-.unsww To: Contact: ;+sip.ice Call-ID: 5xUNyMbEzeDbgO48GR2uPiITtdGkVHm9 CSeq: 31361 INVITE Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS Supported: replaces, 100rel, timer, norefersub User-Agent: Bria iOS 2.3.4 Proxy-Authorization: Digest username="101", realm="voice.wicube.net", nonce="3d86ab38-81a2-11e2-ac9f-ad22efd2ace4", uri="sip:102@voice.wicube.net", response="6b0c7609274244fe82cf620ca92f32c4", algorithm=MD5, cnonce="GEwL.oSmgLolHXaIddxqHalqr5vm.JPF", qop=auth, nc=00000001 Content-Type: application/sdp Content-Length: 717 v=0 o=- 3571043268 3571043268 IN IP4 172.22.55.178 s=cpc_med c=IN IP4 172.22.55.178 t=0 0 m=audio 53530 RTP/SAVP 0 8 101 a=sendrecv a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:fqwD6Slr4snIn2sJPbAtfR+sLr/VRQbTOmtdi7JH a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:FvJ8slt1jidvluNFDVItIgmux+WiqgIFoAp+ItS5 a=ice-ufrag:7bec20f6 a=ice-pwd:4b683609 a=candidate:Hac1637b2 1 UDP 2130706431 172.22.55.178 53530 typ host a=candidate:H55ad1d9 1 UDP 2130706431 5.90.209.217 53530 typ host a=candidate:Hac1637b2 2 UDP 2130706430 172.22.55.178 58436 typ host a=candidate:H55ad1d9 2 UDP 2130706430 5.90.209.217 58436 typ host ------------------------------------------------------------------------ send 388 bytes to tls/[85.18.36.68]:51187 at 12:27:39.282486: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/TLS 85.18.36.68:51187;rport=51187;branch=z9hG4bKPjVkpmhYfPXXJ.MmmYQ62ufvtZD0K5i8Lf;alias From: "101" ;tag=RQ-SQdhcZuhI5SYNSNKTY9F-9-.unsww To: Call-ID: 5xUNyMbEzeDbgO48GR2uPiITtdGkVHm9 CSeq: 31361 INVITE User-Agent: FreeSWITCH-mod_sofia/1.2.6+git~20130104T154559Z~a4247651ca Content-Length: 0 ------------------------------------------------------------------------ 2013-02-28 13:07:04.598195 [DEBUG] sofia.c:1842 Re-attaching to session 3d835816-81a2-11e2-ac9e-ad22efd2ace4 2013-02-28 13:07:04.598195 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/101@voice.wicube.net [BREAK] 2013-02-28 13:07:04.598195 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/101@voice.wicube.net [BREAK] 2013-02-28 13:07:04.618122 [DEBUG] sofia.c:7729 IP 85.18.36.68 Rejected by acl "domains". Falling back to Digest auth. 2013-02-28 13:07:04.618122 [DEBUG] sofia.c:5599 Channel sofia/internal/101@voice.wicube.net entering state [received][100] 2013-02-28 13:07:04.618122 [DEBUG] sofia.c:5610 Remote SDP: v=0 o=- 3571043268 3571043268 IN IP4 172.22.55.178 s=cpc_med c=IN IP4 172.22.55.178 t=0 0 m=audio 53530 RTP/SAVP 0 8 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:fqwD6Slr4snIn2sJPbAtfR+sLr/VRQbTOmtdi7JH a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:FvJ8slt1jidvluNFDVItIgmux+WiqgIFoAp+ItS5 a=ice-ufrag:7bec20f6 a=ice-pwd:4b683609 a=candidate:Hac1637b2 1 UDP 2130706431 172.22.55.178 53530 typ host a=candidate:H55ad1d9 1 UDP 2130706431 5.90.209.217 53530 typ host a=candidate:Hac1637b2 2 UDP 2130706430 172.22.55.178 58436 typ host a=candidate:H55ad1d9 2 UDP 2130706430 5.90.209.217 58436 typ host 2013-02-28 13:07:04.618122 [DEBUG] sofia_glue.c:4978 Set Remote Key [1 AES_CM_128_HMAC_SHA1_80 inline:fqwD6Slr4snIn2sJPbAtfR+sLr/VRQbTOmtdi7JH] 2013-02-28 13:07:04.618122 [DEBUG] sofia_glue.c:3155 Set Local Key [1 AES_CM_128_HMAC_SHA1_80 inline:L+JO67wbK0cKnCTZ7xDdwJy7QKIJfK4x3VB07M5s] 2013-02-28 13:07:04.618122 [DEBUG] sofia_glue.c:5137 Audio Codec Compare [PCMU:0:8000:20:64000]/[G722:9:8000:20:64000] 2013-02-28 13:07:04.618122 [DEBUG] sofia_glue.c:5137 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000] 2013-02-28 13:07:04.618122 [DEBUG] sofia_glue.c:3093 Set Codec sofia/internal/101@voice.wicube.net PCMU/8000 20 ms 160 samples 64000 bits 2013-02-28 13:07:04.618122 [DEBUG] switch_core_codec.c:111 sofia/internal/101@voice.wicube.net Original read codec set to PCMU:0 2013-02-28 13:07:04.618122 [DEBUG] sofia_glue.c:5266 Set 2833 dtmf send/recv payload to 101 2013-02-28 13:07:04.618122 [DEBUG] sofia.c:5827 (sofia/internal/101@voice.wicube.net) State Change CS_NEW -> CS_INIT 2013-02-28 13:07:04.618122 [DEBUG] switch_core_session.c:1283 Send signal sofia/internal/101@voice.wicube.net [BREAK] 2013-02-28 13:07:04.618122 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/101@voice.wicube.net) Running State Change CS_INIT 2013-02-28 13:07:04.618122 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/101@voice.wicube.net) State INIT 2013-02-28 13:07:04.618122 [DEBUG] mod_sofia.c:86 sofia/internal/101@voice.wicube.net SOFIA INIT 2013-02-28 13:07:04.618122 [DEBUG] mod_sofia.c:126 (sofia/internal/101@voice.wicube.net) State Change CS_INIT -> CS_ROUTING 2013-02-28 13:07:04.618122 [DEBUG] switch_core_session.c:1283 Send signal sofia/internal/101@voice.wicube.net [BREAK] 2013-02-28 13:07:04.618122 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/101@voice.wicube.net) State INIT going to sleep 2013-02-28 13:07:04.618122 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/101@voice.wicube.net) Running State Change CS_ROUTING 2013-02-28 13:07:04.618122 [DEBUG] switch_channel.c:2003 (sofia/internal/101@voice.wicube.net) Callstate Change DOWN -> RINGING 2013-02-28 13:07:04.618122 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/101@voice.wicube.net) State ROUTING 2013-02-28 13:07:04.618122 [DEBUG] mod_sofia.c:149 sofia/internal/101@voice.wicube.net SOFIA ROUTING 2013-02-28 13:07:04.618122 [DEBUG] switch_core_state_machine.c:117 sofia/internal/101@voice.wicube.net Standard ROUTING 2013-02-28 13:07:04.618122 [INFO] mod_dialplan_xml.c:557 Processing 101 <101>->102 in context default Dialplan: sofia/internal/101@voice.wicube.net parsing [default->101] continue=false Dialplan: sofia/internal/101@voice.wicube.net Regex (FAIL) [101] destination_number(102) =~ /^101$/ break=on-false Dialplan: sofia/internal/101@voice.wicube.net parsing [default->102] continue=false Dialplan: sofia/internal/101@voice.wicube.net Regex (PASS) [102] destination_number(102) =~ /^102$/ break=on-false Dialplan: sofia/internal/101@voice.wicube.net Action bridge(USER/102@82.85.60.237) 2013-02-28 13:07:04.618122 [DEBUG] switch_core_state_machine.c:167 (sofia/internal/101@voice.wicube.net) State Change CS_ROUTING -> CS_EXECUTE 2013-02-28 13:07:04.618122 [DEBUG] switch_core_session.c:1283 Send signal sofia/internal/101@voice.wicube.net [BREAK] 2013-02-28 13:07:04.618122 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/101@voice.wicube.net) State ROUTING going to sleep 2013-02-28 13:07:04.618122 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/101@voice.wicube.net) Running State Change CS_EXECUTE 2013-02-28 13:07:04.618122 [DEBUG] switch_core_state_machine.c:477 (sofia/internal/101@voice.wicube.net) State EXECUTE 2013-02-28 13:07:04.618122 [DEBUG] mod_sofia.c:242 sofia/internal/101@voice.wicube.net SOFIA EXECUTE 2013-02-28 13:07:04.618122 [DEBUG] switch_core_state_machine.c:209 sofia/internal/101@voice.wicube.net Standard EXECUTE EXECUTE sofia/internal/101@voice.wicube.net bridge(USER/102@82.85.60.237) 2013-02-28 13:07:04.639117 [DEBUG] switch_channel.c:1089 sofia/internal/101@voice.wicube.net EXPORTING[export_vars] [domain_name]=[82.85.60.237] to event 2013-02-28 13:07:04.639117 [DEBUG] switch_ivr_originate.c:2022 Parsing global variables 2013-02-28 13:07:04.639117 [DEBUG] switch_channel.c:1089 sofia/internal/101@voice.wicube.net EXPORTING[export_vars] [domain_name]=[82.85.60.237] to event 2013-02-28 13:07:04.639117 [DEBUG] switch_ivr_originate.c:2022 Parsing global variables 2013-02-28 13:07:04.639117 [DEBUG] switch_event.c:1608 Parsing variable [sip_invite_domain]=[82.85.60.237] 2013-02-28 13:07:04.639117 [DEBUG] switch_event.c:1608 Parsing variable [presence_id]=[102@82.85.60.237] 2013-02-28 13:07:04.639117 [NOTICE] switch_channel.c:968 New Channel sofia/internal/sip:102@85.18.36.68:55272 [3d9f6736-81a2-11e2-aca8-ad22efd2ace4] 2013-02-28 13:07:04.639117 [DEBUG] mod_sofia.c:4970 (sofia/internal/sip:102@85.18.36.68:55272) State Change CS_NEW -> CS_INIT 2013-02-28 13:07:04.639117 [DEBUG] switch_core_session.c:1283 Send signal sofia/internal/sip:102@85.18.36.68:55272 [BREAK] 2013-02-28 13:07:04.639117 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/sip:102@85.18.36.68:55272) Running State Change CS_INIT 2013-02-28 13:07:04.639117 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/sip:102@85.18.36.68:55272) State INIT 2013-02-28 13:07:04.639117 [DEBUG] mod_sofia.c:86 sofia/internal/sip:102@85.18.36.68:55272 SOFIA INIT 2013-02-28 13:07:04.639117 [DEBUG] sofia_glue.c:2618 sip:102@85.18.36.68:55272;transport=tls;rinstance=2804dee11a135346 Setting proxy route to sofia/internal/sip:102@85.18.36.68:55272 2013-02-28 13:07:04.639117 [DEBUG] sofia_glue.c:2647 Local SDP: v=0 o=FreeSWITCH 1362024826 1362024827 IN IP4 82.85.60.237 s=FreeSWITCH c=IN IP4 82.85.60.237 t=0 0 m=audio 28398 RTP/AVP 0 9 8 3 101 13 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv 2013-02-28 13:07:04.639117 [DEBUG] mod_sofia.c:126 (sofia/internal/sip:102@85.18.36.68:55272) State Change CS_INIT -> CS_ROUTING 2013-02-28 13:07:04.639117 [DEBUG] switch_core_session.c:1283 Send signal sofia/internal/sip:102@85.18.36.68:55272 [BREAK] 2013-02-28 13:07:04.639117 [DEBUG] switch_core_state_machine.c:454 (sofia/internal/sip:102@85.18.36.68:55272) State INIT going to sleep 2013-02-28 13:07:04.639117 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/sip:102@85.18.36.68:55272) Running State Change CS_ROUTING 2013-02-28 13:07:04.639117 [DEBUG] switch_channel.c:2003 (sofia/internal/sip:102@85.18.36.68:55272) Callstate Change DOWN -> RINGING 2013-02-28 13:07:04.639117 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/sip:102@85.18.36.68:55272) State ROUTING 2013-02-28 13:07:04.639117 [DEBUG] mod_sofia.c:149 sofia/internal/sip:102@85.18.36.68:55272 SOFIA ROUTING 2013-02-28 13:07:04.639117 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/sip:102@85.18.36.68:55272) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2013-02-28 13:07:04.639117 [DEBUG] switch_core_session.c:1283 Send signal sofia/internal/sip:102@85.18.36.68:55272 [BREAK] 2013-02-28 13:07:04.639117 [DEBUG] switch_core_state_machine.c:470 (sofia/internal/sip:102@85.18.36.68:55272) State ROUTING going to sleep 2013-02-28 13:07:04.639117 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/sip:102@85.18.36.68:55272) Running State Change CS_CONSUME_MEDIA 2013-02-28 13:07:04.639117 [DEBUG] switch_core_state_machine.c:489 (sofia/internal/sip:102@85.18.36.68:55272) State CONSUME_MEDIA 2013-02-28 13:07:04.639117 [DEBUG] switch_core_state_machine.c:489 (sofia/internal/sip:102@85.18.36.68:55272) State CONSUME_MEDIA going to sleep send 1228 bytes to tls/[85.18.36.68]:55272 at 12:27:39.310041: ------------------------------------------------------------------------ INVITE sip:102@85.18.36.68:55272;transport=tls;rinstance=2804dee11a135346 SIP/2.0 Via: SIP/2.0/TLS 82.85.60.237;branch=z9hG4bK18Q1U05eeZBrB Max-Forwards: 69 From: "Vic Iphone" ;tag=F6pSatDmH4BcH To: Call-ID: 14fdf9be-fc45-1230-f395-005056b93f51 CSeq: 40691165 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 Iphone" ;party=calling;screen=yes;privacy=off v=0 o=FreeSWITCH 1362024826 1362024827 IN IP4 82.85.60.237 s=FreeSWITCH c=IN IP4 82.85.60.237 t=0 0 m=audio 28398 RTP/AVP 0 9 8 3 101 13 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ 2013-02-28 13:07:04.639117 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/sip:102@85.18.36.68:55272 [BREAK] 2013-02-28 13:07:04.639117 [DEBUG] sofia.c:5599 Channel sofia/internal/sip:102@85.18.36.68:55272 entering state [calling][0] recv 405 bytes from tls/[85.18.36.68]:55272 at 12:27:39.335865: ------------------------------------------------------------------------ SIP/2.0 415 Unsupported Media Type Via: SIP/2.0/TLS 82.85.60.237;branch=z9hG4bK18Q1U05eeZBrB To: ;tag=d85c4e58 From: "Vic Iphone";tag=F6pSatDmH4BcH Call-ID: 14fdf9be-fc45-1230-f395-005056b93f51 CSeq: 40691165 INVITE Accept: application/sdp User-Agent: Bria 3 release 3.5.0b stamp 69410 Content-Length: 0 ------------------------------------------------------------------------ send 393 bytes to tls/[85.18.36.68]:55272 at 12:27:39.336009: ------------------------------------------------------------------------ ACK sip:102@85.18.36.68:55272;transport=tls;rinstance=2804dee11a135346 SIP/2.0 Via: SIP/2.0/TLS 82.85.60.237;branch=z9hG4bK18Q1U05eeZBrB Max-Forwards: 69 From: "Vic Iphone" ;tag=F6pSatDmH4BcH To: ;tag=d85c4e58 Call-ID: 14fdf9be-fc45-1230-f395-005056b93f51 CSeq: 40691165 ACK Content-Length: 0 ------------------------------------------------------------------------ 2013-02-28 13:07:04.658143 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/sip:102@85.18.36.68:55272 [BREAK] 2013-02-28 13:07:04.658143 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/sip:102@85.18.36.68:55272 [BREAK] 2013-02-28 13:07:04.658143 [DEBUG] switch_core_session.c:975 Send signal sofia/internal/sip:102@85.18.36.68:55272 [BREAK] 2013-02-28 13:07:04.658143 [DEBUG] sofia.c:5599 Channel sofia/internal/sip:102@85.18.36.68:55272 entering state [terminated][415] 2013-02-28 13:07:04.658143 [DEBUG] switch_channel.c:2994 (sofia/internal/sip:102@85.18.36.68:55272) Callstate Change RINGING -> HANGUP 2013-02-28 13:07:04.658143 [NOTICE] sofia.c:6383 Hangup sofia/internal/sip:102@85.18.36.68:55272 [CS_CONSUME_MEDIA] [SERVICE_NOT_IMPLEMENTED] 2013-02-28 13:07:04.658143 [DEBUG] switch_channel.c:3017 Send signal sofia/internal/sip:102@85.18.36.68:55272 [KILL] 2013-02-28 13:07:04.658143 [DEBUG] switch_core_session.c:1283 Send signal sofia/internal/sip:102@85.18.36.68:55272 [BREAK] 2013-02-28 13:07:04.658143 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/sip:102@85.18.36.68:55272) Running State Change CS_HANGUP 2013-02-28 13:07:04.658143 [DEBUG] switch_core_state_machine.c:667 (sofia/internal/sip:102@85.18.36.68:55272) State HANGUP 2013-02-28 13:07:04.658143 [DEBUG] mod_sofia.c:503 Channel sofia/internal/sip:102@85.18.36.68:55272 hanging up, cause: SERVICE_NOT_IMPLEMENTED 2013-02-28 13:07:04.658143 [DEBUG] switch_core_state_machine.c:48 sofia/internal/sip:102@85.18.36.68:55272 Standard HANGUP, cause: SERVICE_NOT_IMPLEMENTED 2013-02-28 13:07:04.658143 [DEBUG] switch_core_state_machine.c:667 (sofia/internal/sip:102@85.18.36.68:55272) State HANGUP going to sleep 2013-02-28 13:07:04.658143 [DEBUG] switch_core_state_machine.c:446 (sofia/internal/sip:102@85.18.36.68:55272) State Change CS_HANGUP -> CS_REPORTING 2013-02-28 13:07:04.658143 [DEBUG] switch_core_session.c:1283 Send signal sofia/internal/sip:102@85.18.36.68:55272 [BREAK] 2013-02-28 13:07:04.658143 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/sip:102@85.18.36.68:55272) Running State Change CS_REPORTING 2013-02-28 13:07:04.658143 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/sip:102@85.18.36.68:55272) State REPORTING 2013-02-28 13:07:04.658143 [DEBUG] switch_core_state_machine.c:92 sofia/internal/sip:102@85.18.36.68:55272 Standard REPORTING, cause: SERVICE_NOT_IMPLEMENTED 2013-02-28 13:07:04.658143 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/sip:102@85.18.36.68:55272) State REPORTING going to sleep 2013-02-28 13:07:04.678496 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/sip:102@85.18.36.68:55272) State Change CS_REPORTING -> CS_DESTROY 2013-02-28 13:07:04.678496 [DEBUG] switch_core_session.c:1283 Send signal sofia/internal/sip:102@85.18.36.68:55272 [BREAK] 2013-02-28 13:07:04.678496 [DEBUG] switch_core_session.c:1488 Session 22 (sofia/internal/sip:102@85.18.36.68:55272) Locked, Waiting on external entities 2013-02-28 13:07:04.678496 [DEBUG] switch_ivr_originate.c:3533 Originate Resulted in Error Cause: 79 [SERVICE_NOT_IMPLEMENTED] 2013-02-28 13:07:04.678496 [NOTICE] switch_ivr_originate.c:2608 Cannot create outgoing channel of type [USER] cause: [SERVICE_NOT_IMPLEMENTED] 2013-02-28 13:07:04.678496 [DEBUG] switch_ivr_originate.c:3533 Originate Resulted in Error Cause: 79 [SERVICE_NOT_IMPLEMENTED] 2013-02-28 13:07:04.678496 [NOTICE] switch_core_session.c:1506 Session 22 (sofia/internal/sip:102@85.18.36.68:55272) Ended 2013-02-28 13:07:04.678496 [NOTICE] switch_core_session.c:1510 Close Channel sofia/internal/sip:102@85.18.36.68:55272 [CS_DESTROY] 2013-02-28 13:07:04.678496 [INFO] mod_dptools.c:3052 Originate Failed. Cause: SERVICE_NOT_IMPLEMENTED 2013-02-28 13:07:04.678496 [DEBUG] switch_channel.c:2994 (sofia/internal/101@voice.wicube.net) Callstate Change RINGING -> HANGUP 2013-02-28 13:07:04.678496 [NOTICE] mod_dptools.c:3172 Hangup sofia/internal/101@voice.wicube.net [CS_EXECUTE] [SERVICE_NOT_IMPLEMENTED] 2013-02-28 13:07:04.678496 [DEBUG] switch_channel.c:3017 Send signal sofia/internal/101@voice.wicube.net [KILL] 2013-02-28 13:07:04.678496 [DEBUG] switch_core_session.c:1283 Send signal sofia/internal/101@voice.wicube.net [BREAK] 2013-02-28 13:07:04.678496 [DEBUG] switch_core_session.c:2678 sofia/internal/101@voice.wicube.net skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 2013-02-28 13:07:04.678496 [DEBUG] switch_core_state_machine.c:477 (sofia/internal/101@voice.wicube.net) State EXECUTE going to sleep 2013-02-28 13:07:04.678496 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/101@voice.wicube.net) Running State Change CS_HANGUP 2013-02-28 13:07:04.678496 [DEBUG] switch_core_state_machine.c:667 (sofia/internal/101@voice.wicube.net) State HANGUP 2013-02-28 13:07:04.678496 [DEBUG] mod_sofia.c:497 sofia/internal/101@voice.wicube.net Overriding SIP cause 501 with 415 from the other leg 2013-02-28 13:07:04.678496 [DEBUG] mod_sofia.c:503 Channel sofia/internal/101@voice.wicube.net hanging up, cause: SERVICE_NOT_IMPLEMENTED 2013-02-28 13:07:04.678496 [DEBUG] switch_core_state_machine.c:556 (sofia/internal/sip:102@85.18.36.68:55272) Callstate Change HANGUP -> DOWN 2013-02-28 13:07:04.678496 [DEBUG] switch_core_state_machine.c:559 (sofia/internal/sip:102@85.18.36.68:55272) Running State Change CS_DESTROY 2013-02-28 13:07:04.678496 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/sip:102@85.18.36.68:55272) State DESTROY 2013-02-28 13:07:04.678496 [DEBUG] mod_sofia.c:396 sofia/internal/sip:102@85.18.36.68:55272 SOFIA DESTROY 2013-02-28 13:07:04.678496 [DEBUG] switch_core_state_machine.c:99 sofia/internal/sip:102@85.18.36.68:55272 Standard DESTROY 2013-02-28 13:07:04.678496 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/sip:102@85.18.36.68:55272) State DESTROY going to sleep 2013-02-28 13:07:04.678496 [DEBUG] mod_sofia.c:633 Responding to INVITE with: 415 2013-02-28 13:07:04.678496 [DEBUG] switch_core_state_machine.c:48 sofia/internal/101@voice.wicube.net Standard HANGUP, cause: SERVICE_NOT_IMPLEMENTED 2013-02-28 13:07:04.678496 [DEBUG] switch_core_state_machine.c:667 (sofia/internal/101@voice.wicube.net) State HANGUP going to sleep 2013-02-28 13:07:04.678496 [DEBUG] switch_core_state_machine.c:446 (sofia/internal/101@voice.wicube.net) State Change CS_HANGUP -> CS_REPORTING 2013-02-28 13:07:04.678496 [DEBUG] switch_core_session.c:1283 Send signal sofia/internal/101@voice.wicube.net [BREAK] 2013-02-28 13:07:04.678496 [DEBUG] switch_core_state_machine.c:415 (sofia/internal/101@voice.wicube.net) Running State Change CS_REPORTING 2013-02-28 13:07:04.678496 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/101@voice.wicube.net) State REPORTING send 918 bytes to tls/[85.18.36.68]:51187 at 12:27:39.358707: ------------------------------------------------------------------------ SIP/2.0 415 Unsupported Media Type Via: SIP/2.0/TLS 85.18.36.68:51187;rport=51187;branch=z9hG4bKPjVkpmhYfPXXJ.MmmYQ62ufvtZD0K5i8Lf;alias Max-Forwards: 70 From: "101" ;tag=RQ-SQdhcZuhI5SYNSNKTY9F-9-.unsww To: ;tag=eXX08yvgmUNSN Call-ID: 5xUNyMbEzeDbgO48GR2uPiITtdGkVHm9 CSeq: 31361 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=79;text="SERVICE_NOT_IMPLEMENTED" Content-Length: 0 Remote-Party-ID: "102" ;party=calling;privacy=off;screen=no ------------------------------------------------------------------------ 2013-02-28 13:07:04.718652 [DEBUG] switch_core_state_machine.c:92 sofia/internal/101@voice.wicube.net Standard REPORTING, cause: SERVICE_NOT_IMPLEMENTED 2013-02-28 13:07:04.718652 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/101@voice.wicube.net) State REPORTING going to sleep 2013-02-28 13:07:04.718652 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/101@voice.wicube.net) State Change CS_REPORTING -> CS_DESTROY 2013-02-28 13:07:04.718652 [DEBUG] switch_core_session.c:1283 Send signal sofia/internal/101@voice.wicube.net [BREAK] 2013-02-28 13:07:04.718652 [DEBUG] switch_core_session.c:1488 Session 21 (sofia/internal/101@voice.wicube.net) Locked, Waiting on external entities 2013-02-28 13:07:04.718652 [NOTICE] switch_core_session.c:1506 Session 21 (sofia/internal/101@voice.wicube.net) Ended 2013-02-28 13:07:04.718652 [NOTICE] switch_core_session.c:1510 Close Channel sofia/internal/101@voice.wicube.net [CS_DESTROY] 2013-02-28 13:07:04.718652 [DEBUG] switch_core_state_machine.c:556 (sofia/internal/101@voice.wicube.net) Callstate Change HANGUP -> DOWN 2013-02-28 13:07:04.718652 [DEBUG] switch_core_state_machine.c:559 (sofia/internal/101@voice.wicube.net) Running State Change CS_DESTROY 2013-02-28 13:07:04.718652 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/101@voice.wicube.net) State DESTROY 2013-02-28 13:07:04.718652 [DEBUG] mod_sofia.c:396 sofia/internal/101@voice.wicube.net SOFIA DESTROY 2013-02-28 13:07:04.718652 [DEBUG] switch_core_state_machine.c:99 sofia/internal/101@voice.wicube.net Standard DESTROY 2013-02-28 13:07:04.718652 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/101@voice.wicube.net) State DESTROY going to sleep recv 362 bytes from tls/[85.18.36.68]:51187 at 12:27:39.415726: ------------------------------------------------------------------------ ACK sip:102@voice.wicube.net SIP/2.0 Via: SIP/2.0/TLS 85.18.36.68:51187;rport;branch=z9hG4bKPjVkpmhYfPXXJ.MmmYQ62ufvtZD0K5i8Lf;alias Max-Forwards: 70 From: "101" ;tag=RQ-SQdhcZuhI5SYNSNKTY9F-9-.unsww To: ;tag=eXX08yvgmUNSN Call-ID: 5xUNyMbEzeDbgO48GR2uPiITtdGkVHm9 CSeq: 31361 ACK Content-Length: 0 ------------------------------------------------------------------------