freeswitch@internal> freeswitch@internal> freeswitch@internal> freeswitch@internal> freeswitch@internal> freeswitch@internal> freeswitch@internal> recv 754 bytes from udp/[98.254.245.79]:5710 at 02:14:17.120420: ------------------------------------------------------------------------ INVITE sip:1000@whistle1.vinixtech.com SIP/2.0 Via: SIP/2.0/UDP 192.168.1.100:5710;branch=z9hG4bK-d8754z-bf467076e1a73d77-1---d8754z-;rport Max-Forwards: 70 Contact: To: From: "1000";tag=fe48972f Call-ID: MzNmZmU4MGI4MzJiY2IyYWIxN2JkZmYxZTFiMmVmNWY. CSeq: 1 INVITE Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO Content-Type: application/sdp User-Agent: X-Lite Beta release 4.0 v3 stamp 55153 Content-Length: 187 v=0 o=- 7 2 IN IP4 192.168.1.100 s=CounterPath X-Lite 4.0 c=IN IP4 192.168.1.100 t=0 0 m=audio 27768 RTP/AVP 0 8 101 a=sendrecv a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 ------------------------------------------------------------------------ send 365 bytes to udp/[98.254.245.79]:5710 at 02:14:17.120686: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.100:5710;branch=z9hG4bK-d8754z-bf467076e1a73d77-1---d8754z-;rport=5710;received=98.254.245.79 From: "1000";tag=fe48972f To: Call-ID: MzNmZmU4MGI4MzJiY2IyYWIxN2JkZmYxZTFiMmVmNWY. CSeq: 1 INVITE User-Agent: sip.2600hz.com Content-Length: 0 ------------------------------------------------------------------------ 2011-06-03 02:14:17.106288 [DEBUG] sofia.c:6557 1 acls to check for proxy 2011-06-03 02:14:17.106288 [DEBUG] sofia.c:6562 checking 98.254.245.79 against acl authoritative 2011-06-03 02:14:17.106288 [DEBUG] sofia.c:6598 IP 98.254.245.79 Rejected by acl "authoritative". Falling back to Digest auth. 2011-06-03 02:14:17.106288 [WARNING] sofia_reg.c:1241 SIP auth challenge (INVITE) on sofia profile 'sipinterface_1' for [1000@whistle1.vinixtech.com] from ip 98.254.245.79 send 727 bytes to udp/[98.254.245.79]:5710 at 02:14:17.121868: ------------------------------------------------------------------------ SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 192.168.1.100:5710;branch=z9hG4bK-d8754z-bf467076e1a73d77-1---d8754z-;rport=5710;received=98.254.245.79 From: "1000";tag=fe48972f To: ;tag=F30NQXjmpp47e Call-ID: MzNmZmU4MGI4MzJiY2IyYWIxN2JkZmYxZTFiMmVmNWY. CSeq: 1 INVITE User-Agent: sip.2600hz.com Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Supported: precondition, path, replaces Allow-Events: talk, hold, refer Proxy-Authenticate: Digest realm="whistle1.vinixtech.com", nonce="54dd7ea3-fd2d-4884-84da-12a370740c75", algorithm=MD5, qop="auth" Content-Length: 0 ------------------------------------------------------------------------ recv 367 bytes from udp/[98.254.245.79]:5710 at 02:14:17.206447: ------------------------------------------------------------------------ ACK sip:1000@whistle1.vinixtech.com SIP/2.0 Via: SIP/2.0/UDP 192.168.1.100:5710;branch=z9hG4bK-d8754z-bf467076e1a73d77-1---d8754z-;rport Max-Forwards: 70 To: ;tag=F30NQXjmpp47e From: "1000";tag=fe48972f Call-ID: MzNmZmU4MGI4MzJiY2IyYWIxN2JkZmYxZTFiMmVmNWY. CSeq: 1 ACK Content-Length: 0 ------------------------------------------------------------------------ recv 1038 bytes from udp/[98.254.245.79]:5710 at 02:14:17.210295: ------------------------------------------------------------------------ INVITE sip:1000@whistle1.vinixtech.com SIP/2.0 Via: SIP/2.0/UDP 192.168.1.100:5710;branch=z9hG4bK-d8754z-4742fd4e1001a627-1---d8754z-;rport Max-Forwards: 70 Contact: To: From: "1000";tag=fe48972f Call-ID: MzNmZmU4MGI4MzJiY2IyYWIxN2JkZmYxZTFiMmVmNWY. CSeq: 2 INVITE Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO Content-Type: application/sdp Proxy-Authorization: Digest username="neurosys",realm="whistle1.vinixtech.com",nonce="54dd7ea3-fd2d-4884-84da-12a370740c75",uri="sip:1000@whistle1.vinixtech.com",response="81153f8d5854280583f1c11a8add0bcc",cnonce="1bbbe3f2f84d56bd18847fbd7ef41c93",nc=00000001,qop=auth,algorithm=MD5 User-Agent: X-Lite Beta release 4.0 v3 stamp 55153 Content-Length: 187 v=0 o=- 7 2 IN IP4 192.168.1.100 s=CounterPath X-Lite 4.0 c=IN IP4 192.168.1.100 t=0 0 m=audio 27768 RTP/AVP 0 8 101 a=sendrecv a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 ------------------------------------------------------------------------ send 365 bytes to udp/[98.254.245.79]:5710 at 02:14:17.210611: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.100:5710;branch=z9hG4bK-d8754z-4742fd4e1001a627-1---d8754z-;rport=5710;received=98.254.245.79 From: "1000";tag=fe48972f To: Call-ID: MzNmZmU4MGI4MzJiY2IyYWIxN2JkZmYxZTFiMmVmNWY. CSeq: 2 INVITE User-Agent: sip.2600hz.com Content-Length: 0 ------------------------------------------------------------------------ 2011-06-03 02:14:17.208315 [DEBUG] sofia.c:6557 1 acls to check for proxy 2011-06-03 02:14:17.208315 [DEBUG] sofia.c:6562 checking 98.254.245.79 against acl authoritative 2011-06-03 02:14:17.208315 [DEBUG] sofia.c:6598 IP 98.254.245.79 Rejected by acl "authoritative". Falling back to Digest auth. 2011-06-03 02:14:17.208315 [DEBUG] mod_erlang_event.c:380 looking for bindings 2011-06-03 02:14:17.208315 [DEBUG] mod_erlang_event.c:395 binding for domain in section directory with key name and value whistle1.vinixtech.com requested from node ecallmgr@whistle1.vinixtech.com 2011-06-03 02:14:17.208315 [DEBUG] handle_msg.c:190 Got reply for 02f6e61f-2281-4b52-b5bd-030f9f9c5c8b 2011-06-03 02:14:17.208315 [DEBUG] mod_erlang_event.c:466 got data
after 0 milliseconds from ecallmgr@whistle1.vinixtech.com for 02f6e61f-2281-4b52-b5bd-030f9f9c5c8b! 2011-06-03 02:14:17.208315 [DEBUG] mod_erlang_event.c:473 XML parsed OK! 2011-06-03 02:14:17.228615 [NOTICE] switch_channel.c:816 New Channel sofia/sipinterface_1/neurosys@whistle1.vinixtech.com [MzNmZmU4MGI4MzJiY2IyYWIxN2JkZmYxZTFiMmVmNWY.] 2011-06-03 02:14:17.228615 [DEBUG] sofia.c:4774 Channel sofia/sipinterface_1/neurosys@whistle1.vinixtech.com entering state [received][100] 2011-06-03 02:14:17.228615 [DEBUG] sofia.c:4785 Remote SDP: v=0 o=- 7 2 IN IP4 192.168.1.100 s=CounterPath X-Lite 4.0 c=IN IP4 192.168.1.100 t=0 0 m=audio 27768 RTP/AVP 0 8 101 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 2011-06-03 02:14:17.228615 [DEBUG] switch_core_state_machine.c:325 (sofia/sipinterface_1/neurosys@whistle1.vinixtech.com) Running State Change CS_NEW 2011-06-03 02:14:17.228615 [DEBUG] switch_core_state_machine.c:343 (sofia/sipinterface_1/neurosys@whistle1.vinixtech.com) State NEW 2011-06-03 02:14:17.228615 [DEBUG] sofia.c:4922 (sofia/sipinterface_1/neurosys@whistle1.vinixtech.com) State Change CS_NEW -> CS_INIT 2011-06-03 02:14:17.228615 [DEBUG] switch_core_session.c:1118 Send signal sofia/sipinterface_1/neurosys@whistle1.vinixtech.com [BREAK] 2011-06-03 02:14:17.228615 [DEBUG] switch_core_state_machine.c:325 (sofia/sipinterface_1/neurosys@whistle1.vinixtech.com) Running State Change CS_INIT 2011-06-03 02:14:17.228615 [DEBUG] switch_core_state_machine.c:361 (sofia/sipinterface_1/neurosys@whistle1.vinixtech.com) State INIT 2011-06-03 02:14:17.228615 [DEBUG] mod_sofia.c:85 sofia/sipinterface_1/neurosys@whistle1.vinixtech.com SOFIA INIT 2011-06-03 02:14:17.228615 [DEBUG] mod_sofia.c:125 (sofia/sipinterface_1/neurosys@whistle1.vinixtech.com) State Change CS_INIT -> CS_ROUTING 2011-06-03 02:14:17.228615 [DEBUG] switch_core_session.c:1118 Send signal sofia/sipinterface_1/neurosys@whistle1.vinixtech.com [BREAK] 2011-06-03 02:14:17.228615 [DEBUG] switch_core_state_machine.c:361 (sofia/sipinterface_1/neurosys@whistle1.vinixtech.com) State INIT going to sleep 2011-06-03 02:14:17.228615 [DEBUG] switch_core_state_machine.c:325 (sofia/sipinterface_1/neurosys@whistle1.vinixtech.com) Running State Change CS_ROUTING 2011-06-03 02:14:17.228615 [DEBUG] switch_channel.c:1715 (sofia/sipinterface_1/neurosys@whistle1.vinixtech.com) Callstate Change DOWN -> RINGING 2011-06-03 02:14:17.228615 [DEBUG] switch_core_state_machine.c:364 (sofia/sipinterface_1/neurosys@whistle1.vinixtech.com) State ROUTING 2011-06-03 02:14:17.228615 [DEBUG] mod_sofia.c:148 sofia/sipinterface_1/neurosys@whistle1.vinixtech.com SOFIA ROUTING 2011-06-03 02:14:17.228615 [DEBUG] switch_core_state_machine.c:77 sofia/sipinterface_1/neurosys@whistle1.vinixtech.com Standard ROUTING 2011-06-03 02:14:17.228615 [INFO] mod_dialplan_xml.c:336 Processing 1000 ->1000 in context context_2 2011-06-03 02:14:17.228615 [DEBUG] mod_erlang_event.c:380 looking for bindings 2011-06-03 02:14:17.228615 [DEBUG] mod_erlang_event.c:395 binding for (null) in section dialplan with key (null) and value (null) requested from node ecallmgr@whistle1.vinixtech.com 2011-06-03 02:14:22.217460 [WARNING] mod_erlang_event.c:439 Timed out after 4988 milliseconds when waiting for XML fetch response for 79a9f886-e5a0-4edd-b3c1-2aa031c1feef 2011-06-03 02:14:22.217460 [WARNING] mod_dialplan_xml.c:366 Context context_2 not found 2011-06-03 02:14:22.217460 [INFO] switch_core_state_machine.c:142 No Route, Aborting 2011-06-03 02:14:22.217460 [DEBUG] switch_channel.c:2620 (sofia/sipinterface_1/neurosys@whistle1.vinixtech.com) Callstate Change RINGING -> HANGUP 2011-06-03 02:14:22.217460 [NOTICE] switch_core_state_machine.c:143 Hangup sofia/sipinterface_1/neurosys@whistle1.vinixtech.com [CS_ROUTING] [NO_ROUTE_DESTINATION] 2011-06-03 02:14:22.217460 [DEBUG] switch_channel.c:2636 Send signal sofia/sipinterface_1/neurosys@whistle1.vinixtech.com [KILL] 2011-06-03 02:14:22.217460 [DEBUG] switch_core_session.c:1118 Send signal sofia/sipinterface_1/neurosys@whistle1.vinixtech.com [BREAK] 2011-06-03 02:14:22.217460 [DEBUG] switch_core_state_machine.c:364 (sofia/sipinterface_1/neurosys@whistle1.vinixtech.com) State ROUTING going to sleep 2011-06-03 02:14:22.217460 [DEBUG] switch_core_state_machine.c:325 (sofia/sipinterface_1/neurosys@whistle1.vinixtech.com) Running State Change CS_HANGUP 2011-06-03 02:14:22.217460 [DEBUG] switch_core_state_machine.c:565 (sofia/sipinterface_1/neurosys@whistle1.vinixtech.com) State HANGUP 2011-06-03 02:14:22.217460 [DEBUG] mod_sofia.c:458 Channel sofia/sipinterface_1/neurosys@whistle1.vinixtech.com hanging up, cause: NO_ROUTE_DESTINATION 2011-06-03 02:14:22.217460 [DEBUG] mod_sofia.c:520 Responding to INVITE with: 404 2011-06-03 02:14:22.217460 [DEBUG] switch_core_state_machine.c:46 sofia/sipinterface_1/neurosys@whistle1.vinixtech.com Standard HANGUP, cause: NO_ROUTE_DESTINATION 2011-06-03 02:14:22.217460 [DEBUG] switch_core_state_machine.c:565 (sofia/sipinterface_1/neurosys@whistle1.vinixtech.com) State HANGUP going to sleep 2011-06-03 02:14:22.217460 [DEBUG] switch_core_state_machine.c:356 (sofia/sipinterface_1/neurosys@whistle1.vinixtech.com) State Change CS_HANGUP -> CS_REPORTING send 711 bytes to udp/[98.254.245.79]:5710 at 02:14:22.233080: ------------------------------------------------------------------------ SIP/2.0 404 Not Found Via: SIP/2.0/UDP 192.168.1.100:5710;branch=z9hG4bK-d8754z-4742fd4e1001a627-1---d8754z-;rport=5710;received=98.254.245.79 From: "1000";tag=fe48972f To: ;tag=gcteSr3QKZtta Call-ID: MzNmZmU4MGI4MzJiY2IyYWIxN2JkZmYxZTFiMmVmNWY. CSeq: 2 INVITE User-Agent: sip.2600hz.com Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Supported: precondition, path, replaces Allow-Events: talk, hold, refer Reason: Q.850;cause=3;text="NO_ROUTE_DESTINATION" Content-Length: 0 Remote-Party-ID: "1000" ;party=calling;privacy=off;screen=no ------------------------------------------------------------------------ 2011-06-03 02:14:22.217460 [DEBUG] switch_core_session.c:1118 Send signal sofia/sipinterface_1/neurosys@whistle1.vinixtech.com [BREAK] 2011-06-03 02:14:22.217460 [DEBUG] switch_core_state_machine.c:325 (sofia/sipinterface_1/neurosys@whistle1.vinixtech.com) Running State Change CS_REPORTING 2011-06-03 02:14:22.217460 [DEBUG] switch_core_state_machine.c:625 (sofia/sipinterface_1/neurosys@whistle1.vinixtech.com) State REPORTING 2011-06-03 02:14:22.217460 [DEBUG] switch_core_state_machine.c:53 sofia/sipinterface_1/neurosys@whistle1.vinixtech.com Standard REPORTING, cause: NO_ROUTE_DESTINATION 2011-06-03 02:14:22.217460 [DEBUG] switch_core_state_machine.c:625 (sofia/sipinterface_1/neurosys@whistle1.vinixtech.com) State REPORTING going to sleep 2011-06-03 02:14:22.217460 [DEBUG] switch_core_state_machine.c:350 (sofia/sipinterface_1/neurosys@whistle1.vinixtech.com) State Change CS_REPORTING -> CS_DESTROY 2011-06-03 02:14:22.217460 [DEBUG] switch_core_session.c:1118 Send signal sofia/sipinterface_1/neurosys@whistle1.vinixtech.com [BREAK] 2011-06-03 02:14:22.217460 [DEBUG] switch_core_session.c:1290 Session 15 (sofia/sipinterface_1/neurosys@whistle1.vinixtech.com) Locked, Waiting on external entities 2011-06-03 02:14:22.217460 [NOTICE] switch_core_session.c:1308 Session 15 (sofia/sipinterface_1/neurosys@whistle1.vinixtech.com) Ended 2011-06-03 02:14:22.217460 [NOTICE] switch_core_session.c:1310 Close Channel sofia/sipinterface_1/neurosys@whistle1.vinixtech.com [CS_DESTROY] 2011-06-03 02:14:22.217460 [DEBUG] switch_core_state_machine.c:454 (sofia/sipinterface_1/neurosys@whistle1.vinixtech.com) Callstate Change HANGUP -> DOWN 2011-06-03 02:14:22.217460 [DEBUG] switch_core_state_machine.c:457 (sofia/sipinterface_1/neurosys@whistle1.vinixtech.com) Running State Change CS_DESTROY 2011-06-03 02:14:22.217460 [DEBUG] switch_core_state_machine.c:467 (sofia/sipinterface_1/neurosys@whistle1.vinixtech.com) State DESTROY 2011-06-03 02:14:22.217460 [DEBUG] mod_sofia.c:363 sofia/sipinterface_1/neurosys@whistle1.vinixtech.com SOFIA DESTROY 2011-06-03 02:14:22.217460 [DEBUG] switch_core_state_machine.c:60 sofia/sipinterface_1/neurosys@whistle1.vinixtech.com Standard DESTROY 2011-06-03 02:14:22.217460 [DEBUG] switch_core_state_machine.c:467 (sofia/sipinterface_1/neurosys@whistle1.vinixtech.com) State DESTROY going to sleep recv 367 bytes from udp/[98.254.245.79]:5710 at 02:14:22.319381: ------------------------------------------------------------------------ ACK sip:1000@whistle1.vinixtech.com SIP/2.0 Via: SIP/2.0/UDP 192.168.1.100:5710;branch=z9hG4bK-d8754z-4742fd4e1001a627-1---d8754z-;rport Max-Forwards: 70 To: ;tag=gcteSr3QKZtta From: "1000";tag=fe48972f Call-ID: MzNmZmU4MGI4MzJiY2IyYWIxN2JkZmYxZTFiMmVmNWY. CSeq: 2 ACK Content-Length: 0 ------------------------------------------------------------------------