rendered paste bodyfreeswitch@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: <sip:neurosys@192.168.1.100:5710>
To: <sip:1000@whistle1.vinixtech.com>
From: "1000"<sip:neurosys@whistle1.vinixtech.com>;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"<sip:neurosys@whistle1.vinixtech.com>;tag=fe48972f
To: <sip:1000@whistle1.vinixtech.com>
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"<sip:neurosys@whistle1.vinixtech.com>;tag=fe48972f
To: <sip:1000@whistle1.vinixtech.com>;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: <sip:1000@whistle1.vinixtech.com>;tag=F30NQXjmpp47e
From: "1000"<sip:neurosys@whistle1.vinixtech.com>;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: <sip:neurosys@192.168.1.100:5710>
To: <sip:1000@whistle1.vinixtech.com>
From: "1000"<sip:neurosys@whistle1.vinixtech.com>;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"<sip:neurosys@whistle1.vinixtech.com>;tag=fe48972f
To: <sip:1000@whistle1.vinixtech.com>
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 <document type="freeswitch/xml">
<section name="directory">
<domain name="whistle1.vinixtech.com">
<user id="neurosys">
<params>
<param name="password" value="iq150l8a"/>
</params>
<variables>
<variable name="ecallmgr_Authorizing-ID" value="e604a411005c38596be5a6277a002722" />
<variable name="ecallmgr_Realm" value="whistle1.vinixtech.com" />
<variable name="ecallmgr_Username" value="neurosys" />
<variable name="ecallmgr_Direction" value="outbound" />
<variable name="ecallmgr_Access-Group" value="ignore" />
<variable name="ecallmgr_Tenant-ID" value="ignore" />
</variables>
</user>
</domain>
</section>
</document> 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 <neurosys>->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"<sip:neurosys@whistle1.vinixtech.com>;tag=fe48972f
To: <sip:1000@whistle1.vinixtech.com>;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" <sip:1000@50.57.74.173>;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: <sip:1000@whistle1.vinixtech.com>;tag=gcteSr3QKZtta
From: "1000"<sip:neurosys@whistle1.vinixtech.com>;tag=fe48972f
Call-ID: MzNmZmU4MGI4MzJiY2IyYWIxN2JkZmYxZTFiMmVmNWY.
CSeq: 2 ACK
Content-Length: 0
------------------------------------------------------------------------