[Freeswitch-users] freeswitch changes c= to FS's IP with bypass_media=true
Juan Backson
juanbackson at gmail.com
Tue Dec 1 20:07:22 PST 2009
Hi Mike,
Here is a very strange SIP outgoing INVITES from freeswitch:
The call path is this:
192.168.1.104 (phonerlite) -> 192.168.1.102 ( freeswitch )
192.168.1.102 -> 192.168.1.116 (sipp gives back 403)
192.168.1.102 -> 192.168.1.13 ( phone )
The first INVITE to 192.168.1.13 has the right c= and o= ( both is pointing
to 192.168.1.104). But the for some unknown reason, Freeswitch sends
INVITES again. But in the INVITE resend, the o = becomes fs's ip.
I have no idea. This is only bypass_meida.
Attached is the fs log.
ngrep -q -p -W byline port 5060
interface: eth0 (192.168.1.0/255.255.255.0)
filter: (ip) and ( port 5060 )
U 192.168.1.104:5060 -> 192.168.1.102:5060
INVITE sip:90964111 at 192.168.1.102 <sip%3A90964111 at 192.168.1.102> SIP/2.0.
Via: SIP/2.0/UDP 192.168.1.104:5060
;branch=z9hG4bK80f2b42464ddde1180d3001a805656a5;rport.
From: <sip:PhonerLite at 192.168.1.102 <sip%3APhonerLite at 192.168.1.102>
>;tag=2563216860.
To: <sip:90964111 at 192.168.1.102 <sip%3A90964111 at 192.168.1.102>>.
Call-ID: 80F2B424-64DD-DE11-80D2-001A805656A5 at 192.168.1.104.
CSeq: 17 INVITE.
Contact: <sip:PhonerLite at 192.168.1.104:5060>.
Content-Type: application/sdp.
Allow: INVITE, OPTIONS, ACK, BYE, CANCEL, INFO, NOTIFY, MESSAGE, UPDATE.
Max-Forwards: 70.
Supported: 100rel, replaces.
User-Agent: SIPPER for PhonerLite.
Content-Length: 396.
.
v=0.
o=- 478760567 0 IN IP4 192.168.1.104.
s=SIPPER for PhonerLite.
c=IN IP4 192.168.1.104.
t=0 0.
m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101.
a=rtpmap:8 PCMA/8000.
a=rtpmap:0 PCMU/8000.
a=rtpmap:2 G726-32/8000.
a=rtpmap:3 GSM/8000.
a=rtpmap:97 iLBC/8000.
a=rtpmap:110 speex/8000.
a=rtpmap:111 speex/16000.
a=rtpmap:9 G722/8000.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-16.
a=sendrecv.
U 192.168.1.102:5060 -> 192.168.1.104:5060
SIP/2.0 100 Trying.
Via: SIP/2.0/UDP 192.168.1.104:5060
;branch=z9hG4bK80f2b42464ddde1180d3001a805656a5;rport=5060.
From: <sip:PhonerLite at 192.168.1.102 <sip%3APhonerLite at 192.168.1.102>
>;tag=2563216860.
To: <sip:90964111 at 192.168.1.102 <sip%3A90964111 at 192.168.1.102>>.
Call-ID: 80F2B424-64DD-DE11-80D2-001A805656A5 at 192.168.1.104.
CSeq: 17 INVITE.
User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095.
Content-Length: 0.
.
U 192.168.1.102:5060 -> 192.168.1.116:9390
INVITE sip:90964111 at 192.168.1.116:9390 SIP/2.0.
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKS9Qm26ZS4U93m.
Max-Forwards: 69.
From: "PhonerLite" <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
>;tag=r0pv05c0848ae.
To: <sip:90964111 at 192.168.1.116:9390>.
Call-ID: a76ed230-59db-122d-ff84-0022190e9476.
CSeq: 123758962 INVITE.
Contact: <sip:PhonerLite at 192.168.1.102:5060>.
User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095.
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER,
REFER, NOTIFY.
Supported: timer, precondition, path, replaces.
Allow-Events: talk, refer.
Content-Type: application/sdp.
Content-Disposition: session.
Content-Length: 401.
Remote-Party-ID: "PhonerLite"
<sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
>;party=calling;screen=yes;privacy=off.
.
v=0.
o=- 478760567 523740055483911509 IN IP4 192.168.1.104.
s=SIPPER for PhonerLite.
c=IN IP4 192.168.1.104.
t=0 0.
m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101.
a=rtpmap:8 PCMA/8000.
a=rtpmap:0 PCMU/8000.
a=rtpmap:2 G726-32/8000.
a=rtpmap:3 GSM/8000.
a=rtpmap:97 iLBC/8000.
a=rtpmap:110 speex/8000.
a=rtpmap:111 speex/16000.
a=rtpmap:9 G722/8000.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-16.
U 192.168.1.116:9390 -> 192.168.1.102:5060
SIP/2.0 403 Because.
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKS9Qm26ZS4U93m.
From: "PhonerLite" <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
>;tag=r0pv05c0848ae.
To: <sip:90964111 at 192.168.1.116:9390>;tag=6.
Call-ID: a76ed230-59db-122d-ff84-0022190e9476.
CSeq: 123758962 INVITE.
Contact: <sip:192.168.1.116:9390;transport=UDP>.
Content-Length: 0.
.
U 192.168.1.102:5060 -> 192.168.1.116:9390
ACK sip:90964111 at 192.168.1.116:9390 SIP/2.0.
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKS9Qm26ZS4U93m.
Max-Forwards: 69.
From: "PhonerLite" <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
>;tag=r0pv05c0848ae.
To: <sip:90964111 at 192.168.1.116:9390>;tag=6.
Call-ID: a76ed230-59db-122d-ff84-0022190e9476.
CSeq: 123758962 ACK.
Content-Length: 0.
.
U 192.168.1.102:5060 -> 192.168.1.116:9391
INVITE sip:90964111 at 192.168.1.116:9391 SIP/2.0.
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKtjHD41gX14Zpg.
Max-Forwards: 69.
From: "PhonerLite" <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
>;tag=S9FN20X35DZXS.
To: <sip:90964111 at 192.168.1.116:9391>.
Call-ID: a771eccc-59db-122d-ff84-0022190e9476.
CSeq: 123758962 INVITE.
Contact: <sip:PhonerLite at 192.168.1.102:5060>.
User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095.
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER,
REFER, NOTIFY.
Supported: timer, precondition, path, replaces.
Allow-Events: talk, refer.
Content-Type: application/sdp.
Content-Disposition: session.
Content-Length: 402.
Remote-Party-ID: "PhonerLite"
<sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
>;party=calling;screen=yes;privacy=off.
.
v=0.
o=- 478760567 5392773558290384508 IN IP4 192.168.1.104.
s=SIPPER for PhonerLite.
c=IN IP4 192.168.1.104.
t=0 0.
m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101.
a=rtpmap:8 PCMA/8000.
a=rtpmap:0 PCMU/8000.
a=rtpmap:2 G726-32/8000.
a=rtpmap:3 GSM/8000.
a=rtpmap:97 iLBC/8000.
a=rtpmap:110 speex/8000.
a=rtpmap:111 speex/16000.
a=rtpmap:9 G722/8000.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-16.
U 192.168.1.116:9391 -> 192.168.1.102:5060
SIP/2.0 403 Because.
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKtjHD41gX14Zpg.
From: "PhonerLite" <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
>;tag=S9FN20X35DZXS.
To: <sip:90964111 at 192.168.1.116:9391>;tag=6.
Call-ID: a771eccc-59db-122d-ff84-0022190e9476.
CSeq: 123758962 INVITE.
Contact: <sip:192.168.1.116:9391;transport=UDP>.
Content-Length: 0.
.
U 192.168.1.102:5060 -> 192.168.1.116:9391
ACK sip:90964111 at 192.168.1.116:9391 SIP/2.0.
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKtjHD41gX14Zpg.
Max-Forwards: 69.
From: "PhonerLite" <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
>;tag=S9FN20X35DZXS.
To: <sip:90964111 at 192.168.1.116:9391>;tag=6.
Call-ID: a771eccc-59db-122d-ff84-0022190e9476.
CSeq: 123758962 ACK.
Content-Length: 0.
.
U 192.168.1.102:5060 -> 192.168.1.13:5060
INVITE sip:90964111 at 192.168.1.13:5060 SIP/2.0.
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKUUa65v10yDp9B.
Max-Forwards: 69.
From: "PhonerLite" <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
>;tag=tj9D4Ue72pNgN.
To: <sip:90964111 at 192.168.1.13:5060>.
Call-ID: a776ce22-59db-122d-ff84-0022190e9476.
CSeq: 123758962 INVITE.
Contact: <sip:PhonerLite at 192.168.1.102:5060>.
User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095.
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER,
REFER, NOTIFY.
Supported: timer, precondition, path, replaces.
Allow-Events: talk, refer.
Content-Type: application/sdp.
Content-Disposition: session.
Content-Length: 402.
Remote-Party-ID: "PhonerLite"
<sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
>;party=calling;screen=yes;privacy=off.
.
v=0.
o=- 478760567 3248233194293522444 IN IP4 192.168.1.104.
s=SIPPER for PhonerLite.
c=IN IP4 192.168.1.104.
t=0 0.
m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101.
a=rtpmap:8 PCMA/8000.
a=rtpmap:0 PCMU/8000.
a=rtpmap:2 G726-32/8000.
a=rtpmap:3 GSM/8000.
a=rtpmap:97 iLBC/8000.
a=rtpmap:110 speex/8000.
a=rtpmap:111 speex/16000.
a=rtpmap:9 G722/8000.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-16.
U 192.168.1.13:5060 -> 192.168.1.102:5060
SIP/2.0 100 Trying.
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKUUa65v10yDp9B.
From: PhonerLite <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
>;tag=tj9D4Ue72pNgN.
To: <sip:90964111 at 192.168.1.13:5060>.
Call-ID: a776ce22-59db-122d-ff84-0022190e9476.
CSeq: 123758962 INVITE.
Content-Length: 0.
.
U 192.168.1.13:5060 -> 192.168.1.102:5060
SIP/2.0 180 Ringing.
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKUUa65v10yDp9B.
From: PhonerLite <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
>;tag=tj9D4Ue72pNgN.
To: <sip:90964111 at 192.168.1.13:5060>;tag=2900712955.
Call-ID: a776ce22-59db-122d-ff84-0022190e9476.
CSeq: 123758962 INVITE.
Contact: <sip:192.168.1.13:5060>.
Content-Length: 0.
.
U 192.168.1.102:5060 -> 192.168.1.104:5060
SIP/2.0 180 Ringing.
Via: SIP/2.0/UDP 192.168.1.104:5060
;branch=z9hG4bK80f2b42464ddde1180d3001a805656a5;rport=5060.
From: <sip:PhonerLite at 192.168.1.102 <sip%3APhonerLite at 192.168.1.102>
>;tag=2563216860.
To: <sip:90964111 at 192.168.1.102 <sip%3A90964111 at 192.168.1.102>
>;tag=QQX3yavvBvjrj.
Call-ID: 80F2B424-64DD-DE11-80D2-001A805656A5 at 192.168.1.104.
CSeq: 17 INVITE.
Contact: <sip:90964111 at 192.168.1.102:5060;transport=udp>.
User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095.
Accept: application/sdp.
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER,
REFER, NOTIFY.
Supported: timer, precondition, path, replaces.
Allow-Events: talk, refer.
Content-Length: 0.
.
U 192.168.1.13:5060 -> 192.168.1.102:5060
SIP/2.0 200 OK.
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKUUa65v10yDp9B.
From: PhonerLite <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
>;tag=tj9D4Ue72pNgN.
To: <sip:90964111 at 192.168.1.13:5060>;tag=2900712955.
Call-ID: a776ce22-59db-122d-ff84-0022190e9476.
CSeq: 123758962 INVITE.
Contact: <sip:192.168.1.13:5060>.
Supported: replaces.
Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, REFER, NOTIFY, INFO, PRACK,
UPDATE, MESSAGE.
Content-Type: application/sdp.
Content-Length: 256.
.
v=0.
o=sdp_admin 59711527 21326134 IN IP4 192.168.1.13.
s=A conversation.
c=IN IP4 192.168.1.13.
t=0 0.
m=audio 10098 RTP/AVP 8 0 9 101.
a=rtpmap:8 PCMA/8000.
a=rtpmap:0 PCMU/8000.
a=rtpmap:9 G722/16000.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-15.
U 192.168.1.102:5060 -> 192.168.1.13:5060
ACK sip:192.168.1.13:5060 SIP/2.0.
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKv43y7Qj4UpcvQ.
Max-Forwards: 70.
From: "PhonerLite" <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
>;tag=tj9D4Ue72pNgN.
To: <sip:90964111 at 192.168.1.13:5060>;tag=2900712955.
Call-ID: a776ce22-59db-122d-ff84-0022190e9476.
CSeq: 123758962 ACK.
Contact: <sip:PhonerLite at 192.168.1.102:5060>.
Content-Length: 0.
.
U 192.168.1.102:5060 -> 192.168.1.104:5060
SIP/2.0 200 OK.
Via: SIP/2.0/UDP 192.168.1.104:5060
;branch=z9hG4bK80f2b42464ddde1180d3001a805656a5;rport=5060.
From: <sip:PhonerLite at 192.168.1.102 <sip%3APhonerLite at 192.168.1.102>
>;tag=2563216860.
To: <sip:90964111 at 192.168.1.102 <sip%3A90964111 at 192.168.1.102>
>;tag=QQX3yavvBvjrj.
Call-ID: 80F2B424-64DD-DE11-80D2-001A805656A5 at 192.168.1.104.
CSeq: 17 INVITE.
Contact: <sip:90964111 at 192.168.1.102:5060;transport=udp>.
User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095.
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER,
REFER, NOTIFY.
Supported: timer, precondition, path, replaces.
Allow-Events: talk, refer.
Content-Type: application/sdp.
Content-Disposition: session.
Content-Length: 207.
.
v=0.
o=sdp_admin 59711527 21326134 IN IP4 192.168.1.13.
s=A conversation.
c=IN IP4 192.168.1.13.
t=0 0.
m=audio 10098 RTP/AVP 8 101.
a=rtpmap:8 PCMA/8000.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-15.
U 192.168.1.104:5060 -> 192.168.1.102:5060
ACK sip:90964111 at 192.168.1.102:5060;transport=udp SIP/2.0.
Via: SIP/2.0/UDP 192.168.1.104:5060
;branch=z9hG4bK00b67e2664ddde1180d3001a805656a5;rport.
From: <sip:PhonerLite at 192.168.1.102 <sip%3APhonerLite at 192.168.1.102>
>;tag=2563216860.
To: <sip:90964111 at 192.168.1.102 <sip%3A90964111 at 192.168.1.102>
>;tag=QQX3yavvBvjrj.
Call-ID: 80F2B424-64DD-DE11-80D2-001A805656A5 at 192.168.1.104.
CSeq: 17 ACK.
Contact: <sip:PhonerLite at 192.168.1.104:5060>.
Max-Forwards: 70.
Content-Length: 0.
.
U 192.168.1.102:5060 -> 192.168.1.13:5060
INVITE sip:192.168.1.13:5060 SIP/2.0.
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKXDXQ9j37rZ2eK.
Max-Forwards: 69.
From: "PhonerLite" <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
>;tag=tj9D4Ue72pNgN.
To: <sip:90964111 at 192.168.1.13:5060>;tag=2900712955.
Call-ID: a776ce22-59db-122d-ff84-0022190e9476.
CSeq: 123758963 INVITE.
Contact: <sip:PhonerLite at 192.168.1.102:5060>.
User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095.
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER,
REFER, NOTIFY.
Supported: timer, precondition, path, replaces.
Content-Type: application/sdp.
Content-Disposition: session.
Content-Length: 223.
Remote-Party-ID: "PhonerLite"
<sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
>;party=calling;screen=yes;privacy=off.
.
v=0.
o=- 478760567 3248233194293522445 IN IP4 192.168.1.104.
s=SIPPER for PhonerLite.
c=IN IP4 192.168.1.102.
t=0 0.
m=audio 33352 RTP/AVP 101.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-16.
a=silenceSupp:off - - - -.
U 192.168.1.104:43488 -> 192.168.1.102:5060
.
.
..............
U 192.168.1.102:5060 -> 192.168.1.13:5060
INVITE sip:192.168.1.13:5060 SIP/2.0.
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKXDXQ9j37rZ2eK.
Max-Forwards: 69.
From: "PhonerLite" <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
>;tag=tj9D4Ue72pNgN.
To: <sip:90964111 at 192.168.1.13:5060>;tag=2900712955.
Call-ID: a776ce22-59db-122d-ff84-0022190e9476.
CSeq: 123758963 INVITE.
Contact: <sip:PhonerLite at 192.168.1.102:5060>.
User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095.
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER,
REFER, NOTIFY.
Supported: timer, precondition, path, replaces.
Content-Type: application/sdp.
Content-Disposition: session.
Content-Length: 223.
Remote-Party-ID: "PhonerLite"
<sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
>;party=calling;screen=yes;privacy=off.
.
v=0.
o=- 478760567 3248233194293522445 IN IP4 192.168.1.104.
s=SIPPER for PhonerLite.
c=IN IP4 192.168.1.102.
t=0 0.
m=audio 33352 RTP/AVP 101.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-16.
a=silenceSupp:off - - - -.
U 192.168.1.13:5060 -> 192.168.1.102:5060
SIP/2.0 400 Bad Request.
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKXDXQ9j37rZ2eK.
From: PhonerLite <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
>;tag=tj9D4Ue72pNgN.
To: <sip:90964111 at 192.168.1.13:5060>;tag=2900712955.
Call-ID: a776ce22-59db-122d-ff84-0022190e9476.
CSeq: 123758963 INVITE.
Contact: <sip:192.168.1.13:5060>.
Content-Length: 0.
.
U 192.168.1.102:5060 -> 192.168.1.13:5060
ACK sip:192.168.1.13:5060 SIP/2.0.
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKXDXQ9j37rZ2eK.
Max-Forwards: 69.
From: "PhonerLite" <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
>;tag=tj9D4Ue72pNgN.
To: <sip:90964111 at 192.168.1.13:5060>;tag=2900712955.
Call-ID: a776ce22-59db-122d-ff84-0022190e9476.
CSeq: 123758963 ACK.
Content-Length: 0.
.
U 192.168.1.102:5060 -> 192.168.1.13:5060
BYE sip:192.168.1.13:5060 SIP/2.0.
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKyppgBemBp8r1e.
Max-Forwards: 70.
From: "PhonerLite" <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
>;tag=tj9D4Ue72pNgN.
To: <sip:90964111 at 192.168.1.13:5060>;tag=2900712955.
Call-ID: a776ce22-59db-122d-ff84-0022190e9476.
CSeq: 123758964 BYE.
Contact: <sip:PhonerLite at 192.168.1.102:5060>.
User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095.
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER,
REFER, NOTIFY.
Supported: timer, precondition, path, replaces.
Reason: Q.850;cause=88;text="INCOMPATIBLE_DESTINATION".
Content-Length: 0.
.
U 192.168.1.102:5060 -> 192.168.1.104:5060
BYE sip:PhonerLite at 192.168.1.104:5060 SIP/2.0.
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKZZF9c94eKHFma.
Max-Forwards: 70.
From: <sip:90964111 at 192.168.1.102 <sip%3A90964111 at 192.168.1.102>
>;tag=QQX3yavvBvjrj.
To: <sip:PhonerLite at 192.168.1.102 <sip%3APhonerLite at 192.168.1.102>
>;tag=2563216860.
Call-ID: 80F2B424-64DD-DE11-80D2-001A805656A5 at 192.168.1.104.
CSeq: 123758964 BYE.
Contact: <sip:90964111 at 192.168.1.102:5060;transport=udp>.
User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095.
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER,
REFER, NOTIFY.
Supported: timer, precondition, path, replaces.
Reason: Q.850;cause=88;text="INCOMPATIBLE_DESTINATION".
Content-Length: 0.
.
U 192.168.1.104:5060 -> 192.168.1.102:5060
SIP/2.0 200 OK.
Via: SIP/2.0/UDP 192.168.1.102;rport=5060;branch=z9hG4bKZZF9c94eKHFma.
From: <sip:90964111 at 192.168.1.102 <sip%3A90964111 at 192.168.1.102>
>;tag=QQX3yavvBvjrj.
To: <sip:PhonerLite at 192.168.1.102 <sip%3APhonerLite at 192.168.1.102>
>;tag=2563216860.
Call-ID: 80F2B424-64DD-DE11-80D2-001A805656A5 at 192.168.1.104.
CSeq: 123758964 BYE.
Contact: <sip:PhonerLite at 192.168.1.104:5060>.
User-Agent: SIPPER for PhonerLite.
Content-Length: 0.
.
U 192.168.1.13:5060 -> 192.168.1.102:5060
SIP/2.0 400 Bad Request.
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKXDXQ9j37rZ2eK.
From: PhonerLite <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
>;tag=tj9D4Ue72pNgN.
To: <sip:90964111 at 192.168.1.13:5060>;tag=2900712955.
Call-ID: a776ce22-59db-122d-ff84-0022190e9476.
CSeq: 123758963 INVITE.
Contact: <sip:192.168.1.13:5060>.
Content-Length: 0.
.
On Wed, Dec 2, 2009 at 11:26 AM, Michael Jerris <mike at jerris.com> wrote:
> how are you sending both invites here? can you explain the full call path
> and how you are originating these calls?
>
>
> On Dec 1, 2009, at 10:11 PM, Juan Backson wrote:
>
> Hi,
>
> I also did try to set only bypass_media, but it still does not work。
> freeswitch still modifies the c= line, causing the call to fail.
>
> Could someone please help?
>
>
> send 1155 bytes to udp/[192.168.1.13]:5060 at 10:56:57.516650:
> ------------------------------------------------------------------------
> INVITE sip:90964111 at 192.168.1.13:5060 SIP/2.0
> Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKmvSrSZB8jFt6a
> Max-Forwards: 69
> From: "PhonerLite" <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
> >;tag=jaZ7N37atF3tr
> To: <sip:90964111 at 192.168.1.13:5060>
> Call-ID: 40563247-59d4-122d-ff84-0022190e9476
> CSeq: 123757372 INVITE
> Contact: <sip:PhonerLite at 192.168.1.102:5060>
> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095
> Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
> REGISTER, REFER, NOTIFY
> Supported: timer, precondition, path, replaces
> Allow-Events: talk, refer
> Content-Type: application/sdp
> Content-Disposition: session
> Content-Length: 402
> Remote-Party-ID: "PhonerLite" <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
> >;party=calling;screen=yes;privacy=off
>
> v=0
> o=- 794697697 5289748556544955553 IN IP4 192.168.1.104
> s=SIPPER for PhonerLite
> c=IN IP4 192.168.1.104
> t=0 0
> m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101
> a=rtpmap:8 PCMA/8000
> a=rtpmap:0 PCMU/8000
> a=rtpmap:2 G726-32/8000
> a=rtpmap:3 GSM/8000
> a=rtpmap:97 iLBC/8000
> a=rtpmap:110 speex/8000
> a=rtpmap:111 speex/16000
> a=rtpmap:9 G722/8000
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-16
> ------------------------------------------------------------------------
> 2009-12-02 18:56:57.516831 [DEBUG] mod_sofia.c:111 (sofia/external_1/
> 90964111 at 192.168.1.13:5060) State Change CS_INIT -> CS_ROUTING
> 2009-12-02 18:56:57.516831 [DEBUG] switch_core_session.c:932 Send signal
> sofia/external_1/90964111 at 192.168.1.13:5060 [BREAK]
> 2009-12-02 18:56:57.516831 [DEBUG] sofia.c:3359 Channel sofia/external_1/
> 90964111 at 192.168.1.13:5060 entering state [calling][0]
> 2009-12-02 18:56:57.516831 [DEBUG] switch_core_state_machine.c:330
> (sofia/external_1/90964111 at 192.168.1.13:5060) State INIT going to sleep
> 2009-12-02 18:56:57.516831 [DEBUG] switch_core_state_machine.c:306
> (sofia/external_1/90964111 at 192.168.1.13:5060) Running State Change
> CS_ROUTING
> 2009-12-02 18:56:57.516831 [DEBUG] switch_core_state_machine.c:333
> (sofia/external_1/90964111 at 192.168.1.13:5060) State ROUTING
> 2009-12-02 18:56:57.516831 [DEBUG] mod_sofia.c:130 sofia/external_1/
> 90964111 at 192.168.1.13:5060 SOFIA ROUTING
> 2009-12-02 18:56:57.516831 [DEBUG] switch_ivr_originate.c:66
> (sofia/external_1/90964111 at 192.168.1.13:5060) State Change CS_ROUTING ->
> CS_CONSUME_MEDIA
> 2009-12-02 18:56:57.516831 [DEBUG] switch_core_session.c:932 Send signal
> sofia/external_1/90964111 at 192.168.1.13:5060 [BREAK]
> 2009-12-02 18:56:57.516831 [DEBUG] switch_core_state_machine.c:333
> (sofia/external_1/90964111 at 192.168.1.13:5060) State ROUTING going to sleep
> 2009-12-02 18:56:57.516831 [DEBUG] switch_core_state_machine.c:306
> (sofia/external_1/90964111 at 192.168.1.13:5060) Running State Change
> CS_CONSUME_MEDIA
> 2009-12-02 18:56:57.516831 [DEBUG] switch_core_state_machine.c:352
> (sofia/external_1/90964111 at 192.168.1.13:5060) State CONSUME_MEDIA
> 2009-12-02 18:56:57.516831 [DEBUG] switch_core_state_machine.c:352
> (sofia/external_1/90964111 at 192.168.1.13:5060) State CONSUME_MEDIA going to
> sleep
> recv 283 bytes from udp/[192.168.1.13]:5060 at 10:56:57.721536:
> ------------------------------------------------------------------------
> SIP/2.0 100 Trying
> Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKmvSrSZB8jFt6a
> From: PhonerLite <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
> >;tag=jaZ7N37atF3tr
> To: <sip:90964111 at 192.168.1.13:5060>
> Call-ID: 40563247-59d4-122d-ff84-0022190e9476
> CSeq: 123757372 INVITE
> Content-Length: 0
>
> ------------------------------------------------------------------------
> recv 330 bytes from udp/[192.168.1.13]:5060 at 10:56:57.736450:
> ------------------------------------------------------------------------
> SIP/2.0 180 Ringing
> Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKmvSrSZB8jFt6a
> From: PhonerLite <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
> >;tag=jaZ7N37atF3tr
> To: <sip:90964111 at 192.168.1.13:5060>;tag=8849584
> Call-ID: 40563247-59d4-122d-ff84-0022190e9476
> CSeq: 123757372 INVITE
> Contact: <sip:192.168.1.13:5060>
> Content-Length: 0
>
> ------------------------------------------------------------------------
> 2009-12-02 18:56:57.736234 [DEBUG] sofia.c:3359 Channel sofia/external_1/
> 90964111 at 192.168.1.13:5060 entering state [proceeding][180]
> 2009-12-02 18:56:57.736234 [NOTICE] sofia.c:3423 Ring-Ready
> sofia/external_1/90964111 at 192.168.1.13:5060!
> 2009-12-02 18:56:57.736234 [DEBUG] sofia.c:3432 sofia/external_1/
> PhonerLite at 192.168.1.102 receive message [RINGING]
> 2009-12-02 18:56:57.736234 [NOTICE] mod_sofia.c:1461 Ring-Ready
> sofia/external_1/PhonerLite at 192.168.1.102!
> 2009-12-02 18:56:57.736234 [DEBUG] switch_core_session.c:630 Send signal
> sofia/external_1/PhonerLite at 192.168.1.102 [BREAK]
> send 618 bytes to udp/[192.168.1.104]:5060 at 10:56:57.737121:
> ------------------------------------------------------------------------
> SIP/2.0 180 Ringing
> Via: SIP/2.0/UDP 192.168.1.104:5060
> ;branch=z9hG4bK000be0bd5cddde1180d2001a805656a5;rport=5060
> From: <sip:PhonerLite at 192.168.1.102 <sip%3APhonerLite at 192.168.1.102>
> >;tag=2454193703
> To: <sip:90964111 at 192.168.1.102 <sip%3A90964111 at 192.168.1.102>
> >;tag=FFKXgjN02m02N
> Call-ID: 000BE0BD-5CDD-DE11-80D1-001A805656A5 at 192.168.1.104
> CSeq: 15 INVITE
> Contact: <sip:90964111 at 192.168.1.102:5060;transport=udp>
> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095
> Accept: application/sdp
> Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
> REGISTER, REFER, NOTIFY
> Supported: timer, precondition, path, replaces
> Allow-Events: talk, refer
> Content-Length: 0
>
> ------------------------------------------------------------------------
> 2009-12-02 18:56:57.737317 [DEBUG] switch_ivr_originate.c:1931
> sofia/external_1/PhonerLite at 192.168.1.102 receive message [RINGING]
> 2009-12-02 18:56:57.737317 [DEBUG] sofia.c:3359 Channel sofia/external_1/
> PhonerLite at 192.168.1.102 entering state [early][180]
> 2009-12-02 18:56:57.737317 [DEBUG] switch_core_session.c:630 Send signal
> sofia/external_1/PhonerLite at 192.168.1.102 [BREAK]
> 2009-12-02 18:56:57.737317 [NOTICE] switch_ivr_originate.c:1931 Ring Ready
> sofia/external_1/PhonerLite at 192.168.1.102!
> recv 722 bytes from udp/[192.168.1.13]:5060 at 10:56:59.381338:
> ------------------------------------------------------------------------
> SIP/2.0 200 OK
> Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKmvSrSZB8jFt6a
> From: PhonerLite <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
> >;tag=jaZ7N37atF3tr
> To: <sip:90964111 at 192.168.1.13:5060>;tag=8849584
> Call-ID: 40563247-59d4-122d-ff84-0022190e9476
> CSeq: 123757372 INVITE
> Contact: <sip:192.168.1.13:5060>
> Supported: replaces
> Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, REFER, NOTIFY, INFO, PRACK,
> UPDATE, MESSAGE
> Content-Type: application/sdp
> Content-Length: 256
>
> v=0
> o=sdp_admin 11293200 10985190 IN IP4 192.168.1.13
> s=A conversation
> c=IN IP4 192.168.1.13
> t=0 0
> m=audio 10096 RTP/AVP 8 0 9 101
> a=rtpmap:8 PCMA/8000
> a=rtpmap:0 PCMU/8000
> a=rtpmap:9 G722/16000
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-15
> ------------------------------------------------------------------------
> send 373 bytes to udp/[192.168.1.13]:5060 at 10:56:59.381739:
> ------------------------------------------------------------------------
> ACK sip:192.168.1.13:5060 SIP/2.0
> Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKN5jHUtvBgrgSp
> Max-Forwards: 70
> From: "PhonerLite" <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
> >;tag=jaZ7N37atF3tr
> To: <sip:90964111 at 192.168.1.13:5060>;tag=8849584
> Call-ID: 40563247-59d4-122d-ff84-0022190e9476
> CSeq: 123757372 ACK
> Contact: <sip:PhonerLite at 192.168.1.102:5060>
> Content-Length: 0
>
> ------------------------------------------------------------------------
> 2009-12-02 18:56:59.381828 [DEBUG] sofia.c:3359 Channel sofia/external_1/
> 90964111 at 192.168.1.13:5060 entering state [ready][200]
> 2009-12-02 18:56:59.381828 [DEBUG] sofia.c:3366 Remote SDP:
> v=0
> o=sdp_admin 11293200 10985190 IN IP4 192.168.1.13
> s=A conversation
> c=IN IP4 192.168.1.13
> t=0 0
> m=audio 10096 RTP/AVP 8 0 9 101
> a=rtpmap:8 PCMA/8000
> a=rtpmap:0 PCMU/8000
> a=rtpmap:9 G722/16000
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-15
>
> 2009-12-02 18:56:59.381828 [DEBUG] switch_channel.c:1935 Send signal
> sofia/external_1/PhonerLite at 192.168.1.102 [BREAK]
> 2009-12-02 18:56:59.381828 [NOTICE] sofia.c:3834 Channel [sofia/external_1/
> 90964111 at 192.168.1.13:5060] has been answered
> 2009-12-02 18:56:59.381828 [DEBUG] switch_channel.c:1971 sofia/external_1/
> 90964111 at 192.168.1.13:5060 execute on answer: incre_call_stat(203 621 201
> 256 25 2591585 1)
> EXECUTE sofia/external_1/90964111 at 192.168.1.13:5060 incre_call_stat(203
> 621 201 256 25 2591585 1)
>
> 2009-12-02 18:56:59.382721 [NOTICE] switch_ivr_originate.c:2152 Channel
> [sofia/external_1/PhonerLite at 192.168.1.102] has been answered
> 2009-12-02 18:56:59.382721 [DEBUG] switch_ivr_originate.c:2196 Originate
> Resulted in Success: [sofia/external_1/90964111 at 192.168.1.13:5060]
> send 858 bytes to udp/[192.168.1.104]:5060 at 10:56:59.382955:
> ------------------------------------------------------------------------
> SIP/2.0 200 OK
> Via: SIP/2.0/UDP 192.168.1.104:5060
> ;branch=z9hG4bK000be0bd5cddde1180d2001a805656a5;rport=5060
> From: <sip:PhonerLite at 192.168.1.102 <sip%3APhonerLite at 192.168.1.102>
> >;tag=2454193703
> To: <sip:90964111 at 192.168.1.102 <sip%3A90964111 at 192.168.1.102>>;tag=FFKXgjN02m02N2009-12-02
> 18:56:59.382721 [DEBUG] switch_ivr_bridge.c:806 (sofia/external_1/
> PhonerLite at 192.168.1.102) State Change CS_EXECUTE -> CS_HIBERNATE
>
> Call-ID: 000BE0BD-5CDD-DE11-80D1-001A805656A5 at 192.168.1.104
> CSeq: 15 INVITE
> Contact: <sip:90964111 at 192.168.1.102:5060;transport=udp>
> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095
> 2009-12-02 18:56:59.382721 [DEBUG] mod_limit.c:474 bypass_media=[true]
> Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
> REGISTER, REFER, NOTIFY
> Supported: timer, precondition, path, replaces
> Allow-Events: talk, refer
> 2009-12-02 18:56:59.382721 [DEBUG] mod_limit.c:486
> originate_disposition=[SUCCESS]
> Content-Type: application/sdp
> Content-Disposition: session
> 2009-12-02 18:56:59.382721 [DEBUG] switch_core_session.c:932 Send signal
> sofia/external_1/PhonerLite at 192.168.1.102 [BREAK]
> Content-Length: 207
>
> v=0
> 2009-12-02 18:56:59.382721 [DEBUG] switch_ivr_bridge.c:807
> (sofia/external_1/90964111 at 192.168.1.13:5060) State Change
> CS_CONSUME_MEDIA -> CS_HIBERNATE
> o=sdp_admin 11293200 10985190 IN IP4 192.168.1.13
> s=A conversation
> c=IN IP4 192.168.1.13
> t=0 0
> 2009-12-02 18:56:59.382721 [DEBUG] switch_core_session.c:932 Send signal
> sofia/external_1/90964111 at 192.168.1.13:5060 [BREAK]
> m=audio 10096 RTP/AVP 8 101
> a=rtpmap:8 PCMA/8000
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-15
> ------------------------------------------------------------------------
> 2009-12-02 18:56:59.382721 [DEBUG] switch_core_state_machine.c:306
> (sofia/external_1/90964111 at 192.168.1.13:5060) Running State Change
> CS_HIBERNATE
> 2009-12-02 18:56:59.382721 [DEBUG] switch_core_state_machine.c:355
> (sofia/external_1/90964111 at 192.168.1.13:5060) State HIBERNATE
> 2009-12-02 18:56:59.382721 [DEBUG] mod_sofia.c:160 sofia/external_1/
> 90964111 at 192.168.1.13:5060 SOFIA HIBERNATE
> 2009-12-02 18:56:59.382721 [DEBUG] switch_core_state_machine.c:212
> sofia/external_1/90964111 at 192.168.1.13:5060 Standard HIBERNATE
> 2009-12-02 18:56:59.382721 [DEBUG] switch_core_state_machine.c:355
> (sofia/external_1/90964111 at 192.168.1.13:5060) State HIBERNATE going to
> sleep
> EXECUTE sofia/external_1/PhonerLite at 192.168.1.102 get_next_route()
> 2009-12-02 18:56:59.382721 [DEBUG] mod_class4.c:2458 Starting to get next
> route...
> EXECUTE sofia/external_1/PhonerLite at 192.168.1.102 info()
> 2009-12-02 18:56:59.383439 [INFO] mod_dptools.c:955 CHANNEL_DATA:
> Channel-State: [CS_HIBERNATE]
> Channel-State-Number: [8]
> Channel-Name: [sofia/external_1/PhonerLite at 192.168.1.102]
> Unique-ID: [01149fda-c473-4c17-859f-66c016b1e376]
> Call-Direction: [inbound]
> Presence-Call-Direction: [inbound]
> Answer-State: [answered]
> Caller-Username: [PhonerLite]
> Caller-Dialplan: [class4]
> Caller-Caller-ID-Name: [PhonerLite]
> Caller-Caller-ID-Number: [PhonerLite]
> Caller-Network-Addr: [192.168.1.104]
> Caller-Destination-Number: [90964111]
> Caller-Unique-ID: [01149fda-c473-4c17-859f-66c016b1e376]
> Caller-Source: [mod_sofia]
> Caller-Context: [default]
> Caller-Channel-Name: [sofia/external_1/PhonerLite at 192.168.1.102]
> Caller-Profile-Index: [1]
> Caller-Profile-Created-Time: [1259751417450336]
> Caller-Channel-Created-Time: [1259751417450336]
> Caller-Channel-Answered-Time: [1259751419381828]
> Caller-Channel-Progress-Time: [1259751417736234]
> Caller-Channel-Progress-Media-Time: [0]
> Caller-Channel-Hangup-Time: [0]
> Caller-Channel-Transfer-Time: [0]
> Caller-Screen-Bit: [true]
> Caller-Privacy-Hide-Name: [false]
> Caller-Privacy-Hide-Number: [false]
> Other-Leg-Username: [PhonerLite]
> Other-Leg-Caller-ID-Name: [PhonerLite]
> Other-Leg-Caller-ID-Number: [PhonerLite]
> Other-Leg-Network-Addr: [192.168.1.104]
> Other-Leg-Destination-Number: [90964111 at 192.168.1.13:5060]
> Other-Leg-Unique-ID: [4db93c42-909f-4299-96a6-416335744dbe]
> Other-Leg-Source: [mod_sofia]
> Other-Leg-Channel-Name: [sofia/external_1/90964111 at 192.168.1.13:5060]
> Other-Leg-Screen-Bit: [true]
> Other-Leg-Privacy-Hide-Name: [false]
> Other-Leg-Privacy-Hide-Number: [false]
> variable_sip_received_ip: [192.168.1.104]
> variable_sip_received_port: [5060]
> variable_sip_via_protocol: [udp]
> variable_sip_from_user: [PhonerLite]
> variable_sip_from_uri: [PhonerLite at 192.168.1.102]
> variable_sip_from_host: [192.168.1.102]
> variable_sip_from_user_stripped: [PhonerLite]
> variable_sip_from_tag: [2454193703]
> variable_sofia_profile_name: [external_1]
> variable_sip_req_user: [90964111]
> variable_sip_req_uri: [90964111 at 192.168.1.102]
> variable_sip_req_host: [192.168.1.102]
> variable_sip_to_user: [90964111]
> variable_sip_to_uri: [90964111 at 192.168.1.102]
> variable_sip_to_host: [192.168.1.102]
> variable_sip_contact_port: [5060]
> variable_sip_contact_uri: [PhonerLite at 192.168.1.104:5060]
> variable_sip_contact_host: [192.168.1.104]
> variable_channel_name: [sofia/external_1/PhonerLite at 192.168.1.102]
> variable_sip_call_id: [000BE0BD-5CDD-DE11-80D1-001A805656A5 at 192.168.1.104]
> variable_sip_user_agent: [SIPPER for PhonerLite]
> variable_sip_via_host: [192.168.1.104]
> variable_sip_via_port: [5060]
> variable_sip_via_rport: [5060]
> variable_max_forwards: [70]
> variable_switch_r_sdp: [v=0
> o=- 794697697 0 IN IP4 192.168.1.104
> s=SIPPER for PhonerLite
> c=IN IP4 192.168.1.104
> t=0 0
> m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101
> a=rtpmap:8 PCMA/8000
> a=rtpmap:0 PCMU/8000
> a=rtpmap:2 G726-32/8000
> a=rtpmap:3 GSM/8000
> a=rtpmap:97 iLBC/8000
> a=rtpmap:110 speex/8000
> a=rtpmap:111 speex/16000
> a=rtpmap:9 G722/8000
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-16
> ]
> variable_ep_codec_string: [PCMA at 8000h,PCMU at 8000h]
> variable_effective_caller_id_number: [PhonerLite]
> variable_effective_caller_id_name: [PhonerLite]
> variable_<![CDATA[sip_h_Remote-Party-ID: [PhonerLite><sip:]
> variable_routing_digit: [90964111]
> variable_continue_on_fail: [true]
> variable_hangup_after_bridge: [true]
> variable_sip_contact_user: [PhonerLite]
> variable_proto_specific_hangup_cause: [sip:403]
> variable_sip_hangup_phrase: [Because]
> variable_bypass_media: [true]
> variable_success_bridge: [true]
> variable_signal_bond: [4db93c42-909f-4299-96a6-416335744dbe]
> variable_switch_m_sdp: [v=0
> o=sdp_admin 11293200 10985190 IN IP4 192.168.1.13
> s=A conversation
> c=IN IP4 192.168.1.13
> t=0 0
> m=audio 10096 RTP/AVP 8 0 9 101
> a=rtpmap:8 PCMA/8000
> a=rtpmap:0 PCMU/8000
> a=rtpmap:9 G722/16000
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-15
> ]
> variable_endpoint_disposition: [ANSWER]
> variable_originate_disposition: [SUCCESS]
> variable_signal_bridge_to: [4db93c42-909f-4299-96a6-416335744dbe]
> variable_current_application: [info]
>
> recv 414 bytes from udp/[192.168.1.104]:5060 at 10:56:59.384444:
> ------------------------------------------------------------------------
> ACK sip:90964111 at 192.168.1.102:5060;transport=udp SIP/2.0
> Via: SIP/2.0/UDP 192.168.1.104:5060
> ;branch=z9hG4bK003811bf5cddde1180d2001a805656a5;rport
> From: <sip:PhonerLite at 192.168.1.102 <sip%3APhonerLite at 192.168.1.102>
> >;tag=2454193703
> To: <sip:90964111 at 192.168.1.102 <sip%3A90964111 at 192.168.1.102>
> >;tag=FFKXgjN02m02N
> Call-ID: 000BE0BD-5CDD-DE11-80D1-001A805656A5 at 192.168.1.104
> CSeq: 15 ACK2009-12-02 18:56:59.383439 [DEBUG] mod_dptools.c:752
> sofia/external_1/PhonerLite at 192.168.1.102 SET [final_digits]=[90964111]
>
> Contact: <sip:PhonerLite at 192.168.1.104:5060>
> Max-Forwards: 70
> Content-Length: 0
>
> ------------------------------------------------------------------------
>
> EXECUTE sofia/external_1/PhonerLite at 192.168.1.102 bridge(sofia/external_1/
> 90964111 at 192.168.1.116:9392)
> 2009-12-02 18:56:59.390427 [DEBUG] switch_ivr.c:1159 sofia/external_1/
> PhonerLite at 192.168.1.102 receive message [MEDIA]
> 2009-12-02 18:56:59.390427 [DEBUG] switch_core_session.c:630 Send signal
> sofia/external_1/PhonerLite at 192.168.1.102 [BREAK]
> 2009-12-02 18:56:59.390427 [CRIT] switch_core_io.c:115 sofia/external_1/
> PhonerLite at 192.168.1.102 reading on a session with no media!
> 2009-12-02 18:56:59.390427 [DEBUG] sofia.c:3359 Channel sofia/external_1/
> PhonerLite at 192.168.1.102 entering state [completed][200]
> 2009-12-02 18:56:59.390427 [DEBUG] sofia.c:3359 Channel sofia/external_1/
> PhonerLite at 192.168.1.102 entering state [ready][200]
> 2009-12-02 18:56:59.393411 [DEBUG] switch_ivr.c:1174 sofia/external_1/
> 90964111 at 192.168.1.13:5060 receive message [MEDIA]
> 2009-12-02 18:56:59.393411 [DEBUG] switch_core_session.c:630 Send signal
> sofia/external_1/90964111 at 192.168.1.13:5060 [BREAK]
> send 952 bytes to udp/[192.168.1.13]:5060 at 10:56:59.393770:
> ------------------------------------------------------------------------
> INVITE sip:192.168.1.13:5060 SIP/2.0
> Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKpecaXNDFD16Bj
> Max-Forwards: 69
> From: "PhonerLite" <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
> >;tag=jaZ7N37atF3tr
> To: <sip:90964111 at 192.168.1.13:5060>;tag=8849584
> Call-ID: 40563247-59d4-122d-ff84-0022190e9476
> CSeq: 123757373 INVITE
> Contact: <sip:PhonerLite at 192.168.1.102:5060>
> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095
> Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
> REGISTER, REFER, NOTIFY
> Supported: timer, precondition, path, replaces
> Content-Type: application/sdp
> Content-Disposition: session
> Content-Length: 223
> Remote-Party-ID: "PhonerLite" <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
> >;party=calling;screen=yes;privacy=off
>
> v=0
> o=- 794697697 5289748556544955554 IN IP4 192.168.1.104
> s=SIPPER for PhonerLite
> c=IN IP4 192.168.1.102
> t=0 0
> m=audio 30632 RTP/AVP 101
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-16
> a=silenceSupp:off - - - -
> ------------------------------------------------------------------------
> 2009-12-02 18:56:59.393411 [DEBUG] sofia.c:3359 Channel sofia/external_1/
> 90964111 at 192.168.1.13:5060 entering state [calling][0]
> send 952 bytes to udp/[192.168.1.13]:5060 at 10:56:59.894252:
> ------------------------------------------------------------------------
>
> On Wed, Dec 2, 2009 at 3:59 AM, Anthony Minessale <
> anthony.minessale at gmail.com> wrote:
>
>> yes he did you can see it in his trace.
>> you can not use both of them together......
>>
>>
>>
>> On Tue, Dec 1, 2009 at 10:46 AM, Michael Jerris <mike at jerris.com> wrote:
>>
>>> The only way this would happen would be if this is set to proxy media not
>>> bypass. Are you setting both?
>>>
>>> Mike
>>>
>>> On Dec 1, 2009, at 10:08 AM, Juan Backson <juanbackson at gmail.com> wrote:
>>>
>>> In the following trace, 102 is FS IP, 104 is calling party and 13 is
>>> called party.
>>>
>>> with bypass_media, FS still changes c=IN IP4 192.168.1.102
>>>
>>> Any idea why?
>>>
>>>
>>> freeswitch at localhost.localdomain> recv 951 bytes from
>>> udp/[192.168.1.104]:5060 at 22:56:33.782715:
>>>
>>> ------------------------------------------------------------------------
>>> INVITE sip:90964111 at 192.168.1.102 <sip%3A90964111 at 192.168.1.102>SIP/2.0
>>> Via: SIP/2.0/UDP 192.168.1.104:5060
>>> ;branch=z9hG4bK003c8e1bf8dcde11a854001a805656a5;rport
>>> From: <sip:PhonerLite at 192.168.1.102 <sip%3APhonerLite at 192.168.1.102>
>>> >;tag=786224322
>>> To: <sip:90964111 at 192.168.1.102 <sip%3A90964111 at 192.168.1.102>>
>>> Call-ID: 003C8E1B-F8DC-DE11-A853-001A805656A5 at 192.168.1.104
>>> CSeq: 37 INVITE
>>> Contact: <sip:PhonerLite at 192.168.1.104:5060>
>>> Content-Type: application/sdp
>>> Allow: INVITE, OPTIONS, ACK, BYE, CANCEL, INFO, NOTIFY, MESSAGE,
>>> UPDATE
>>> Max-Forwards: 70
>>> Supported: 100rel, replaces
>>> User-Agent: SIPPER for PhonerLite
>>> Content-Length: 397
>>>
>>> v=0
>>> o=- 3393406017 0 IN IP4 192.168.1.104
>>> s=SIPPER for PhonerLite
>>> c=IN IP4 192.168.1.104
>>> t=0 0
>>> m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101
>>> a=rtpmap:8 PCMA/8000
>>> a=rtpmap:0 PCMU/8000
>>> a=rtpmap:2 G726-32/8000
>>> a=rtpmap:3 GSM/8000
>>> a=rtpmap:97 iLBC/8000
>>> a=rtpmap:110 speex/8000
>>> a=rtpmap:111 speex/16000
>>> a=rtpmap:9 G722/8000
>>> a=rtpmap:101 telephone-event/8000
>>> a=fmtp:101 0-16
>>> a=sendrecv
>>>
>>> ------------------------------------------------------------------------
>>> send 350 bytes to udp/[192.168.1.104]:5060 at 22:56:33.783145:
>>>
>>> ------------------------------------------------------------------------
>>> SIP/2.0 100 Trying
>>> Via: SIP/2.0/UDP 192.168.1.104:5060
>>> ;branch=z9hG4bK003c8e1bf8dcde11a854001a805656a5;rport=5060
>>> From: <sip:PhonerLite at 192.168.1.102 <sip%3APhonerLite at 192.168.1.102>
>>> >;tag=786224322
>>> To: <sip:90964111 at 192.168.1.102 <sip%3A90964111 at 192.168.1.102>>
>>> Call-ID: 003C8E1B-F8DC-DE11-A853-001A805656A5 at 192.168.1.104
>>> CSeq: 37 INVITE
>>> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095
>>> Content-Length: 0
>>>
>>>
>>> ------------------------------------------------------------------------
>>> 2009-12-02 06:56:33.783162 [NOTICE] switch_channel.c:613 New Channel
>>> sofia/internal/PhonerLite at 192.168.1.102[d4233c9a-ee3b-40d4-910d-3b1579f9a273]
>>> 2009-12-02 06:56:33.783162 [DEBUG] sofia.c:3359 Channel sofia/internal/
>>> PhonerLite at 192.168.1.102 entering state [received][100]
>>> 2009-12-02 06:56:33.783162 [DEBUG] sofia.c:3366 Remote SDP:
>>> v=0
>>> o=- 3393406017 0 IN IP4 192.168.1.104
>>> s=SIPPER for PhonerLite
>>> c=IN IP4 192.168.1.104
>>> t=0 0
>>> m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101
>>> a=rtpmap:8 PCMA/8000
>>> a=rtpmap:0 PCMU/8000
>>> a=rtpmap:2 G726-32/8000
>>> a=rtpmap:3 GSM/8000
>>> a=rtpmap:97 iLBC/8000
>>> a=rtpmap:110 speex/8000
>>> a=rtpmap:111 speex/16000
>>> a=rtpmap:9 G722/8000
>>> a=rtpmap:101 telephone-event/8000
>>> a=fmtp:101 0-16
>>>
>>>
>>> EXECUTE sofia/internal/PhonerLite at 192.168.1.102 info()
>>> 2009-12-02 06:56:33.825198 [INFO] mod_dptools.c:955 CHANNEL_DATA:
>>> Channel-State: [CS_EXECUTE]
>>> Channel-State-Number: [4]
>>> Channel-Name: [sofia/internal/PhonerLite at 192.168.1.102]
>>> Unique-ID: [d4233c9a-ee3b-40d4-910d-3b1579f9a273]
>>> Call-Direction: [inbound]
>>> Presence-Call-Direction: [inbound]
>>> Answer-State: [ringing]
>>> Caller-Username: [PhonerLite]
>>> Caller-Dialplan: [class4]
>>> Caller-Caller-ID-Name: [PhonerLite]
>>> Caller-Caller-ID-Number: [PhonerLite]
>>> Caller-Network-Addr: [192.168.1.104]
>>> Caller-Destination-Number: [90964111]
>>> Caller-Unique-ID: [d4233c9a-ee3b-40d4-910d-3b1579f9a273]
>>> Caller-Source: [mod_sofia]
>>> Caller-Context: [default]
>>> Caller-Channel-Name: [sofia/internal/PhonerLite at 192.168.1.102]
>>> Caller-Profile-Index: [1]
>>> Caller-Profile-Created-Time: [1259708193783162]
>>> Caller-Channel-Created-Time: [1259708193783162]
>>> Caller-Channel-Answered-Time: [0]
>>> Caller-Channel-Progress-Time: [0]
>>> Caller-Channel-Progress-Media-Time: [0]
>>> Caller-Channel-Hangup-Time: [0]
>>> Caller-Channel-Transfer-Time: [0]
>>> Caller-Screen-Bit: [true]
>>> Caller-Privacy-Hide-Name: [false]
>>> Caller-Privacy-Hide-Number: [false]
>>> variable_sip_received_ip: [192.168.1.104]
>>> variable_sip_received_port: [5060]
>>> variable_sip_via_protocol: [udp]
>>> variable_sip_from_user: [PhonerLite]
>>> variable_sip_from_uri: [PhonerLite at 192.168.1.102]
>>> variable_sip_from_host: [192.168.1.102]
>>> variable_sip_from_user_stripped: [PhonerLite]
>>> variable_sip_from_tag: [786224322]
>>> variable_sofia_profile_name: [internal]
>>> variable_sip_req_user: [90964111]
>>> variable_sip_req_uri: [90964111 at 192.168.1.102]
>>> variable_sip_req_host: [192.168.1.102]
>>> variable_sip_to_user: [90964111]
>>> variable_sip_to_uri: [90964111 at 192.168.1.102]
>>> variable_sip_to_host: [192.168.1.102]
>>> variable_sip_contact_port: [5060]
>>> variable_sip_contact_uri: [PhonerLite at 192.168.1.104:5060]
>>> variable_sip_contact_host: [192.168.1.104]
>>> variable_channel_name: [sofia/internal/PhonerLite at 192.168.1.102]
>>> variable_sip_call_id: [
>>> 003C8E1B-F8DC-DE11-A853-001A805656A5 at 192.168.1.104]
>>> variable_sip_user_agent: [SIPPER for PhonerLite]
>>> variable_sip_via_host: [192.168.1.104]
>>> variable_sip_via_port: [5060]
>>> variable_bypass_media: [true]
>>> variable_proxy_media: [true]
>>> variable_sip_via_rport: [5060]
>>> variable_max_forwards: [70]
>>> variable_switch_r_sdp: [v=0
>>> o=- 3393406017 0 IN IP4 192.168.1.104
>>> s=SIPPER for PhonerLite
>>> c=IN IP4 192.168.1.104
>>> t=0 0
>>> m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101
>>> a=rtpmap:8 PCMA/8000
>>> a=rtpmap:0 PCMU/8000
>>> a=rtpmap:2 G726-32/8000
>>> a=rtpmap:3 GSM/8000
>>> a=rtpmap:97 iLBC/8000
>>> a=rtpmap:110 speex/8000
>>> a=rtpmap:111 speex/16000
>>> a=rtpmap:9 G722/8000
>>> a=rtpmap:101 telephone-event/8000
>>> a=fmtp:101 0-16
>>> ]
>>> variable_ep_codec_string: [PCMA at 8000h,PCMU at 8000h]
>>> variable_endpoint_disposition: [RECEIVED_NOMEDIA]
>>> variable_effective_caller_id_number: [PhonerLite]
>>> variable_effective_caller_id_name: [PhonerLite]
>>> variable_<![CDATA[sip_h_Remote-Party-ID: [PhonerLite><sip:]
>>> variable_routing_digit: [90964111]
>>> variable_continue_on_fail: [true]
>>> variable_hangup_after_bridge: [true]
>>> variable_sip_contact_user: [PhonerLite]
>>>
>>>
>>> 2009-12-02 06:56:33.842639 [DEBUG] sofia_glue.c:1322 sofia/internal/
>>> 90964111 at 192.168.1.116:9390 Patched SDP
>>> ---
>>> v=0
>>> o=- 3393406017 0 IN IP4 192.168.1.104
>>> s=SIPPER for PhonerLite
>>> c=IN IP4 192.168.1.104
>>> t=0 0
>>> m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101
>>> a=rtpmap:8 PCMA/8000
>>> a=rtpmap:0 PCMU/8000
>>> a=rtpmap:2 G726-32/8000
>>> a=rtpmap:3 GSM/8000
>>> a=rtpmap:97 iLBC/8000
>>> a=rtpmap:110 speex/8000
>>> a=rtpmap:111 speex/16000
>>> a=rtpmap:9 G722/8000
>>> a=rtpmap:101 telephone-event/8000
>>> a=fmtp:101 0-16
>>>
>>> +++
>>> v=0
>>> o=- 3393406017 0 IN IP4 192.168.1.104
>>> s=SIPPER for PhonerLite
>>> c=IN IP4 192.168.1.102
>>> t=0 0
>>> m=audio 34846 RTP/AVP 8 0 2 3 97 110 111 9 101
>>> a=rtpmap:8 PCMA/8000
>>> a=rtpmap:0 PCMU/8000
>>> a=rtpmap:2 G726-32/8000
>>> a=rtpmap:3 GSM/8000
>>> a=rtpmap:97 iLBC/8000
>>> a=rtpmap:110 speex/8000
>>> a=rtpmap:111 speex/16000
>>> a=rtpmap:9 G722/8000
>>> a=rtpmap:101 telephone-event/8000
>>> a=fmtp:101 0-16
>>>
>>> 2009-12-02 06:56:33.842639 [DEBUG] mod_sofia.c:111 (sofia/internal/
>>> 90964111 at 192.168.1.116:9390) State Change CS_INIT -> CS_ROUTING
>>> 2009-12-02 06:56:33.842639 [DEBUG] switch_core_session.c:932 Send signal
>>> sofia/internal/90964111 at 192.168.1.116:9390 [BREAK]
>>> 2009-12-02 06:56:33.842639 [DEBUG] switch_core_state_machine.c:330
>>> (sofia/internal/90964111 at 192.168.1.116:9390) State INIT going to sleep
>>> 2009-12-02 06:56:33.842639 [DEBUG] switch_core_state_machine.c:306
>>> (sofia/internal/90964111 at 192.168.1.116:9390) Running State Change
>>> CS_ROUTING
>>> 2009-12-02 06:56:33.842639 [DEBUG] switch_core_state_machine.c:333
>>> (sofia/internal/90964111 at 192.168.1.116:9390) State ROUTING
>>> 2009-12-02 06:56:33.842639 [DEBUG] mod_sofia.c:130 sofia/internal/
>>> 90964111 at 192.168.1.116:9390 SOFIA ROUTING
>>> 2009-12-02 06:56:33.842639 [DEBUG] switch_ivr_originate.c:66
>>> (sofia/internal/90964111 at 192.168.1.116:9390) State Change CS_ROUTING ->
>>> CS_CONSUME_MEDIA
>>> 2009-12-02 06:56:33.842639 [DEBUG] switch_core_session.c:932 Send signal
>>> sofia/internal/90964111 at 192.168.1.116:9390 [BREAK]
>>> 2009-12-02 06:56:33.842639 [DEBUG] switch_core_state_machine.c:333
>>> (sofia/internal/90964111 at 192.168.1.116:9390) State ROUTING going to
>>> sleep
>>> 2009-12-02 06:56:33.842639 [DEBUG] switch_core_state_machine.c:306
>>> (sofia/internal/90964111 at 192.168.1.116:9390) Running State Change
>>> CS_CONSUME_MEDIA
>>> 2009-12-02 06:56:33.842639 [DEBUG] switch_core_state_machine.c:352
>>> (sofia/internal/90964111 at 192.168.1.116:9390) State CONSUME_MEDIA
>>> 2009-12-02 06:56:33.842639 [DEBUG] switch_core_state_machine.c:352
>>> (sofia/internal/90964111 at 192.168.1.116:9390) State CONSUME_MEDIA going
>>> to sleep
>>> send 1159 bytes to udp/[192.168.1.116]:9390 at 22:56:33.843976:
>>>
>>> ------------------------------------------------------------------------
>>> INVITE sip:90964111 at 192.168.1.116:9390 SIP/2.0
>>> Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKaNKUU3DjZjB1g
>>> Max-Forwards: 69
>>> From: "PhonerLite" <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
>>> >;tag=8tH6Xjt2XaU9F
>>> To: <sip:90964111 at 192.168.1.116:9390>
>>> Call-ID: 9d052856-596f-122d-1b98-0022190e9476
>>> CSeq: 123735760 INVITE
>>> Contact: <sip:PhonerLite at 192.168.1.102:5060>
>>> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095
>>> Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO,
>>> REGISTER, REFER, NOTIFY
>>> Supported: timer, precondition, path, replaces
>>> Allow-Events: talk, refer
>>> Content-Type: application/sdp
>>> Content-Disposition: session
>>> Content-Length: 404
>>> Remote-Party-ID: "PhonerLite" <sip:PhonerLite at 192.168.1.102<sip%3APhonerLite at 192.168.1.102>
>>> >;party=calling;screen=yes;privacy=off
>>>
>>> v=0
>>> o=- 3393406017 6776849011794265802 IN IP4 192.168.1.104
>>> s=SIPPER for PhonerLite
>>> c=IN IP4 192.168.1.102
>>> t=0 0
>>> m=audio 34846 RTP/AVP 8 0 2 3 97 110 111 9 101
>>> a=rtpmap:8 PCMA/8000
>>> a=rtpmap:0 PCMU/8000
>>> a=rtpmap:2 G726-32/8000
>>> a=rtpmap:3 GSM/8000
>>> a=rtpmap:97 iLBC/8000
>>> a=rtpmap:110 speex/8000
>>> a=rtpmap:111 speex/16000
>>> a=rtpmap:9 G722/8000
>>> a=rtpmap:101 telephone-event/8000
>>> a=fmtp:101 0-16
>>>
>>> _______________________________________________
>>> FreeSWITCH-users mailing list
>>> FreeSWITCH-users at lists.freeswitch.org
>>> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
>>> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
>>> http://www.freeswitch.org
>>>
>>>
>>> _______________________________________________
>>> FreeSWITCH-users mailing list
>>> FreeSWITCH-users at lists.freeswitch.org
>>> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
>>> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
>>> http://www.freeswitch.org
>>>
>>>
>>
>>
>> --
>> Anthony Minessale II
>>
>> FreeSWITCH http://www.freeswitch.org/
>> ClueCon http://www.cluecon.com/
>> Twitter: http://twitter.com/FreeSWITCH_wire
>>
>> AIM: anthm
>> MSN:anthony_minessale at hotmail.com <MSN%3Aanthony_minessale at hotmail.com>
>> GTALK/JABBER/PAYPAL:anthony.minessale at gmail.com<PAYPAL%3Aanthony.minessale at gmail.com>
>> IRC: irc.freenode.net #freeswitch
>>
>> FreeSWITCH Developer Conference
>> sip:888 at conference.freeswitch.org <sip%3A888 at conference.freeswitch.org>
>> iax:guest at conference.freeswitch.org/888
>> googletalk:conf+888 at conference.freeswitch.org<googletalk%3Aconf%2B888 at conference.freeswitch.org>
>> pstn:213-799-1400
>>
>> _______________________________________________
>> FreeSWITCH-users mailing list
>> FreeSWITCH-users at lists.freeswitch.org
>> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
>> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
>> http://www.freeswitch.org
>>
>>
> _______________________________________________
> FreeSWITCH-users mailing list
> FreeSWITCH-users at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> http://www.freeswitch.org
>
>
>
> _______________________________________________
> FreeSWITCH-users mailing list
> FreeSWITCH-users at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> http://www.freeswitch.org
>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20091202/db91a3af/attachment-0002.html
-------------- next part --------------
freeswitch at localhost.localdomain>
freeswitch at localhost.localdomain>
freeswitch at localhost.localdomain>
freeswitch at localhost.localdomain>
freeswitch at localhost.localdomain>
freeswitch at localhost.localdomain>
freeswitch at localhost.localdomain>
freeswitch at localhost.localdomain>
freeswitch at localhost.localdomain>
freeswitch at localhost.localdomain>
freeswitch at localhost.localdomain>
freeswitch at localhost.localdomain>
freeswitch at localhost.localdomain>
freeswitch at localhost.localdomain>
freeswitch at localhost.localdomain>
freeswitch at localhost.localdomain>
freeswitch at localhost.localdomain>
freeswitch at localhost.localdomain>
freeswitch at localhost.localdomain>
freeswitch at localhost.localdomain>
freeswitch at localhost.localdomain>
freeswitch at localhost.localdomain>
freeswitch at localhost.localdomain>
freeswitch at localhost.localdomain> recv 951 bytes from udp/[192.168.1.104]:5060 at 11:49:56.930364:
------------------------------------------------------------------------
INVITE sip:90964111 at 192.168.1.102 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.104:5060;branch=z9hG4bK80f2b42464ddde1180d3001a805656a5;rport
From: <sip:PhonerLite at 192.168.1.102>;tag=2563216860
To: <sip:90964111 at 192.168.1.102>
Call-ID: 80F2B424-64DD-DE11-80D2-001A805656A5 at 192.168.1.104
CSeq: 17 INVITE
Contact: <sip:PhonerLite at 192.168.1.104:5060>
Content-Type: application/sdp
Allow: INVITE, OPTIONS, ACK, BYE, CANCEL, INFO, NOTIFY, MESSAGE, UPDATE
Max-Forwards: 70
Supported: 100rel, replaces
User-Agent: SIPPER for PhonerLite
Content-Length: 396
v=0
o=- 478760567 0 IN IP4 192.168.1.104
s=SIPPER for PhonerLite
c=IN IP4 192.168.1.104
t=0 0
m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:3 GSM/8000
a=rtpmap:97 iLBC/8000
a=rtpmap:110 speex/8000
a=rtpmap:111 speex/16000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=sendrecv
------------------------------------------------------------------------
send 351 bytes to udp/[192.168.1.104]:5060 at 11:49:56.930781:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.1.104:5060;branch=z9hG4bK80f2b42464ddde1180d3001a805656a5;rport=5060
From: <sip:PhonerLite at 192.168.1.102>;tag=2563216860
To: <sip:90964111 at 192.168.1.102>
Call-ID: 80F2B424-64DD-DE11-80D2-001A805656A5 at 192.168.1.104
CSeq: 17 INVITE
User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095
Content-Length: 0
------------------------------------------------------------------------
2009-12-02 19:49:56.930444 [NOTICE] switch_channel.c:613 New Channel sofia/internal/PhonerLite at 192.168.1.102 [85042f75-de46-4fe5-b8d8-53e4ca0d26e1]
2009-12-02 19:49:56.930444 [DEBUG] sofia.c:3359 Channel sofia/internal/PhonerLite at 192.168.1.102 entering state [received][100]
2009-12-02 19:49:56.930444 [DEBUG] sofia.c:3366 Remote SDP:
v=0
o=- 478760567 0 IN IP4 192.168.1.104
s=SIPPER for PhonerLite
c=IN IP4 192.168.1.104
t=0 0
m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:3 GSM/8000
a=rtpmap:97 iLBC/8000
a=rtpmap:110 speex/8000
a=rtpmap:111 speex/16000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
2009-12-02 19:49:56.930444 [DEBUG] sofia.c:3490 (sofia/internal/PhonerLite at 192.168.1.102) State Change CS_NEW -> CS_INIT
2009-12-02 19:49:56.930444 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/PhonerLite at 192.168.1.102 [BREAK]
2009-12-02 19:49:56.931815 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/PhonerLite at 192.168.1.102) Running State Change CS_INIT
2009-12-02 19:49:56.931815 [DEBUG] switch_core_state_machine.c:330 (sofia/internal/PhonerLite at 192.168.1.102) State INIT
2009-12-02 19:49:56.931815 [DEBUG] mod_sofia.c:83 sofia/internal/PhonerLite at 192.168.1.102 SOFIA INIT
2009-12-02 19:49:56.931815 [DEBUG] mod_sofia.c:111 (sofia/internal/PhonerLite at 192.168.1.102) State Change CS_INIT -> CS_ROUTING
2009-12-02 19:49:56.931815 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/PhonerLite at 192.168.1.102 [BREAK]
2009-12-02 19:49:56.931815 [DEBUG] switch_core_state_machine.c:330 (sofia/internal/PhonerLite at 192.168.1.102) State INIT going to sleep
2009-12-02 19:49:56.931815 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/PhonerLite at 192.168.1.102) Running State Change CS_ROUTING
2009-12-02 19:49:56.931815 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/PhonerLite at 192.168.1.102) State ROUTING
2009-12-02 19:49:56.931815 [DEBUG] mod_sofia.c:130 sofia/internal/PhonerLite at 192.168.1.102 SOFIA ROUTING
2009-12-02 19:49:56.931815 [DEBUG] switch_core_state_machine.c:78 sofia/internal/PhonerLite at 192.168.1.102 Standard ROUTING
2009-12-02 19:49:56.946388 [DEBUG] switch_core_state_machine.c:114 (sofia/internal/PhonerLite at 192.168.1.102) State Change CS_ROUTING -> CS_EXECUTE
2009-12-02 19:49:56.946388 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/PhonerLite at 192.168.1.102 [BREAK]
2009-12-02 19:49:56.946388 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/PhonerLite at 192.168.1.102) State ROUTING going to sleep
2009-12-02 19:49:56.946388 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/PhonerLite at 192.168.1.102) Running State Change CS_EXECUTE
2009-12-02 19:49:56.947392 [DEBUG] switch_core_state_machine.c:340 (sofia/internal/PhonerLite at 192.168.1.102) State EXECUTE
2009-12-02 19:49:56.947392 [DEBUG] mod_sofia.c:173 sofia/internal/PhonerLite at 192.168.1.102 SOFIA EXECUTE
2009-12-02 19:49:56.947392 [DEBUG] switch_core_state_machine.c:151 sofia/internal/PhonerLite at 192.168.1.102 Standard EXECUTE
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 set(sip_contact_user=PhonerLite)
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 info()
2009-12-02 19:49:56.957384 [INFO] mod_dptools.c:955 CHANNEL_DATA:
Channel-State: [CS_EXECUTE]
Channel-State-Number: [4]
Channel-Name: [sofia/internal/PhonerLite at 192.168.1.102]
Unique-ID: [85042f75-de46-4fe5-b8d8-53e4ca0d26e1]
Call-Direction: [inbound]
Presence-Call-Direction: [inbound]
Answer-State: [ringing]
Caller-Username: [PhonerLite]
Caller-Dialplan: [class4]
Caller-Caller-ID-Name: [PhonerLite]
Caller-Caller-ID-Number: [PhonerLite]
Caller-Network-Addr: [192.168.1.104]
Caller-Destination-Number: [90964111]
Caller-Unique-ID: [85042f75-de46-4fe5-b8d8-53e4ca0d26e1]
Caller-Source: [mod_sofia]
Caller-Context: [default]
Caller-Channel-Name: [sofia/internal/PhonerLite at 192.168.1.102]
Caller-Profile-Index: [1]
Caller-Profile-Created-Time: [1259754596930444]
Caller-Channel-Created-Time: [1259754596930444]
Caller-Channel-Answered-Time: [0]
Caller-Channel-Progress-Time: [0]
Caller-Channel-Progress-Media-Time: [0]
Caller-Channel-Hangup-Time: [0]
Caller-Channel-Transfer-Time: [0]
Caller-Screen-Bit: [true]
Caller-Privacy-Hide-Name: [false]
Caller-Privacy-Hide-Number: [false]
variable_sip_received_ip: [192.168.1.104]
variable_sip_received_port: [5060]
variable_sip_via_protocol: [udp]
variable_sip_from_user: [PhonerLite]
variable_sip_from_uri: [PhonerLite at 192.168.1.102]
variable_sip_from_host: [192.168.1.102]
variable_sip_from_user_stripped: [PhonerLite]
variable_sip_from_tag: [2563216860]
variable_sofia_profile_name: [internal]
variable_sip_req_user: [90964111]
variable_sip_req_uri: [90964111 at 192.168.1.102]
variable_sip_req_host: [192.168.1.102]
variable_sip_to_user: [90964111]
variable_sip_to_uri: [90964111 at 192.168.1.102]
variable_sip_to_host: [192.168.1.102]
variable_sip_contact_port: [5060]
variable_sip_contact_uri: [PhonerLite at 192.168.1.104:5060]
variable_sip_contact_host: [192.168.1.104]
variable_channel_name: [sofia/internal/PhonerLite at 192.168.1.102]
variable_sip_call_id: [80F2B424-64DD-DE11-80D2-001A805656A5 at 192.168.1.104]
variable_sip_user_agent: [SIPPER for PhonerLite]
variable_sip_via_host: [192.168.1.104]
variable_sip_via_port: [5060]
variable_sip_via_rport: [5060]
variable_max_forwards: [70]
variable_switch_r_sdp: [v=0
o=- 478760567 0 IN IP4 192.168.1.104
s=SIPPER for PhonerLite
c=IN IP4 192.168.1.104
t=0 0
m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:3 GSM/8000
a=rtpmap:97 iLBC/8000
a=rtpmap:110 speex/8000
a=rtpmap:111 speex/16000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
]
variable_ep_codec_string: [PCMA at 8000h,PCMU at 8000h]
variable_endpoint_disposition: [RECEIVED_NOMEDIA]
variable_effective_caller_id_number: [PhonerLite]
variable_effective_caller_id_name: [PhonerLite]
variable_<![CDATA[sip_h_Remote-Party-ID: [PhonerLite><sip:]
variable_routing_digit: [90964111]
variable_continue_on_fail: [true]
variable_hangup_after_bridge: [true]
variable_sip_contact_user: [PhonerLite]
variable_ingress_gateway: [192.168.1.104]
variable_ingress_alias: [7390]
variable_pure_dest_num: [90964111]
variable_ingress_res_id: [201]
variable_ingress_gw_id: [256]
variable_bypass_media: [true]
variable_class4_sip_from_user: [PhonerLite]
variable_class4_sip_received_ip: [192.168.1.104]
variable_class4_req_user: [90964111]
variable_class4_req_host: [192.168.1.102]
variable_class4_call_id: [80F2B424-64DD-DE11-80D2-001A805656A5 at 192.168.1.104]
variable_final_digits: [90964111]
variable_interm_digits: [90964111]
variable_egress_alias: [9390]
variable_prefix_id: [2591585]
variable_product_id: [25]
variable_product_name: [classfun]
variable_prefix_name: [116]
variable_egress_gateway: [192.168.1.116]
variable_egress_port: [9390]
variable_attempt_type: [I]
variable_egress_res_id: [202]
variable_egress_gw_id: [257]
variable_export_vars: [sip_contact_user,ingress_gateway,ingress_alias,pure_dest_num,ingress_res_id,ingress_gw_id,bypass_media,class4_sip_from_user,class4_sip_received_ip,class4_req_user,class4_req_host,class4_call_id,final_digits,interm_digits,egress_alias,prefix_id,product_id,product_name,prefix_name,egress_gateway,egress_port,attempt_type,egress_res_id,egress_gw_id]
variable_route_0: [9390;192.168.1.116;9390;classfun;116;2591585;90964111;90964111;100000;100000;25;202;257;10000;1000;t;t;201;256]
variable_route_1: [9390;192.168.1.116;9391;classfun;116;2591585;90964111;90964111;100000;100000;25;202;258;10000;1000;t;t;201;256]
variable_route_2: [9392;192.168.1.13;5060;classfun;116;2591585;90964111;90964111;10000;100;25;203;621;10;10;t;t;201;256]
variable_route_3: [9392;192.168.1.116;9392;classfun;116;2591585;90964111;90964111;10000;100;25;203;622;10;10;t;t;201;256]
variable_current_route_num: [0]
variable_total_route_cnt: [4]
variable_current_application: [info]
2009-12-02 19:49:56.959386 [DEBUG] mod_sofia.c:2893 (sofia/internal/90964111 at 192.168.1.116:9390) State Change CS_NEW -> CS_INIT
2009-12-02 19:49:56.959386 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111 at 192.168.1.116:9390 [BREAK]
2009-12-02 19:49:56.959386 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111 at 192.168.1.116:9390) Running State Change CS_INIT
2009-12-02 19:49:56.959386 [DEBUG] switch_core_state_machine.c:330 (sofia/internal/90964111 at 192.168.1.116:9390) State INIT
2009-12-02 19:49:56.959386 [DEBUG] mod_sofia.c:83 sofia/internal/90964111 at 192.168.1.116:9390 SOFIA INIT
2009-12-02 19:49:56.959386 [DEBUG] mod_sofia.c:111 (sofia/internal/90964111 at 192.168.1.116:9390) State Change CS_INIT -> CS_ROUTING
2009-12-02 19:49:56.959386 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111 at 192.168.1.116:9390 [BREAK]
2009-12-02 19:49:56.959386 [DEBUG] switch_core_state_machine.c:330 (sofia/internal/90964111 at 192.168.1.116:9390) State INIT going to sleep
2009-12-02 19:49:56.959386 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111 at 192.168.1.116:9390) Running State Change CS_ROUTING
2009-12-02 19:49:56.959386 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/90964111 at 192.168.1.116:9390) State ROUTING
2009-12-02 19:49:56.959386 [DEBUG] mod_sofia.c:130 sofia/internal/90964111 at 192.168.1.116:9390 SOFIA ROUTING
2009-12-02 19:49:56.959386 [DEBUG] switch_ivr_originate.c:66 (sofia/internal/90964111 at 192.168.1.116:9390) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2009-12-02 19:49:56.959386 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111 at 192.168.1.116:9390 [BREAK]
2009-12-02 19:49:56.959386 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/90964111 at 192.168.1.116:9390) State ROUTING going to sleep
2009-12-02 19:49:56.959386 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111 at 192.168.1.116:9390) Running State Change CS_CONSUME_MEDIA
2009-12-02 19:49:56.959386 [DEBUG] switch_core_state_machine.c:352 (sofia/internal/90964111 at 192.168.1.116:9390) State CONSUME_MEDIA
2009-12-02 19:49:56.959386 [DEBUG] switch_core_state_machine.c:352 (sofia/internal/90964111 at 192.168.1.116:9390) State CONSUME_MEDIA going to sleep
send 1156 bytes to udp/[192.168.1.116]:9390 at 11:49:56.960484:
------------------------------------------------------------------------
INVITE sip:90964111 at 192.168.1.116:9390 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKS9Qm26ZS4U93m
Max-Forwards: 69
From: "PhonerLite" <sip:PhonerLite at 192.168.1.102>;tag=r0pv05c0848ae
To: <sip:90964111 at 192.168.1.116:9390>
Call-ID: a76ed230-59db-122d-ff84-0022190e9476
CSeq: 123758962 INVITE
Contact: <sip:PhonerLite at 192.168.1.102:5060>
User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY
Supported: timer, precondition, path, replaces
Allow-Events: talk, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 401
Remote-Party-ID: "PhonerLite" <sip:PhonerLite at 192.168.1.102>;party=calling;screen=yes;privacy=off
v=0
o=- 478760567 523740055483911509 IN IP4 192.168.1.104
s=SIPPER for PhonerLite
c=IN IP4 192.168.1.104
t=0 0
m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:3 GSM/8000
a=rtpmap:97 iLBC/8000
a=rtpmap:110 speex/8000
a=rtpmap:111 speex/16000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
------------------------------------------------------------------------
2009-12-02 19:49:56.960392 [DEBUG] sofia.c:3359 Channel sofia/internal/90964111 at 192.168.1.116:9390 entering state [calling][0]
recv 342 bytes from udp/[192.168.1.116]:9390 at 11:49:56.962288:
------------------------------------------------------------------------
SIP/2.0 403 Because
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKS9Qm26ZS4U93m
From: "PhonerLite" <sip:PhonerLite at 192.168.1.102>;tag=r0pv05c0848ae
To: <sip:90964111 at 192.168.1.116:9390>;tag=6
Call-ID: a76ed230-59db-122d-ff84-0022190e9476
CSeq: 123758962 INVITE
Contact: <sip:192.168.1.116:9390;transport=UDP>
Content-Length: 0
------------------------------------------------------------------------
send 332 bytes to udp/[192.168.1.116]:9390 at 11:49:56.962463:
------------------------------------------------------------------------
ACK sip:90964111 at 192.168.1.116:9390 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKS9Qm26ZS4U93m
Max-Forwards: 69
From: "PhonerLite" <sip:PhonerLite at 192.168.1.102>;tag=r0pv05c0848ae
To: <sip:90964111 at 192.168.1.116:9390>;tag=6
Call-ID: a76ed230-59db-122d-ff84-0022190e9476
CSeq: 123758962 ACK
Content-Length: 0
------------------------------------------------------------------------
2009-12-02 19:49:56.962396 [DEBUG] sofia.c:3359 Channel sofia/internal/90964111 at 192.168.1.116:9390 entering state [terminated][403]
2009-12-02 19:49:56.962396 [NOTICE] sofia.c:3925 Hangup sofia/internal/90964111 at 192.168.1.116:9390 [CS_CONSUME_MEDIA] [CALL_REJECTED]
2009-12-02 19:49:56.962396 [DEBUG] switch_channel.c:1726 Send signal sofia/internal/90964111 at 192.168.1.116:9390 [KILL]
2009-12-02 19:49:56.962396 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111 at 192.168.1.116:9390 [BREAK]
2009-12-02 19:49:56.962396 [DEBUG] switch_core_state_machine.c:437 thread mismatch skipping state handler.
2009-12-02 19:49:56.962396 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111 at 192.168.1.116:9390) Running State Change CS_HANGUP
2009-12-02 19:49:56.962396 [DEBUG] switch_core_state_machine.c:464 (sofia/internal/90964111 at 192.168.1.116:9390) State HANGUP
2009-12-02 19:49:56.962396 [DEBUG] mod_sofia.c:306 sofia/internal/90964111 at 192.168.1.116:9390 Overriding SIP cause 603 with 403 from the other leg
2009-12-02 19:49:56.962396 [DEBUG] mod_sofia.c:338 Channel sofia/internal/90964111 at 192.168.1.116:9390 hanging up, cause: CALL_REJECTED
2009-12-02 19:49:56.962396 [DEBUG] switch_core_state_machine.c:46 sofia/internal/90964111 at 192.168.1.116:9390 Standard HANGUP, cause: CALL_REJECTED
2009-12-02 19:49:56.962396 [DEBUG] switch_core_state_machine.c:464 (sofia/internal/90964111 at 192.168.1.116:9390) State HANGUP going to sleep
2009-12-02 19:49:56.962396 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/90964111 at 192.168.1.116:9390) State Change CS_HANGUP -> CS_REPORTING
2009-12-02 19:49:56.962396 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111 at 192.168.1.116:9390 [BREAK]
2009-12-02 19:49:56.962396 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111 at 192.168.1.116:9390) Running State Change CS_REPORTING
2009-12-02 19:49:56.962396 [DEBUG] switch_core_state_machine.c:555 (sofia/internal/90964111 at 192.168.1.116:9390) State REPORTING
2009-12-02 19:49:56.962396 [DEBUG] switch_core_state_machine.c:53 sofia/internal/90964111 at 192.168.1.116:9390 Standard REPORTING, cause: CALL_REJECTED
2009-12-02 19:49:56.962396 [DEBUG] switch_core_state_machine.c:555 (sofia/internal/90964111 at 192.168.1.116:9390) State REPORTING going to sleep
2009-12-02 19:49:56.962396 [DEBUG] switch_core_state_machine.c:319 (sofia/internal/90964111 at 192.168.1.116:9390) State Change CS_REPORTING -> CS_DESTROY
2009-12-02 19:49:56.962396 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111 at 192.168.1.116:9390 [BREAK]
2009-12-02 19:49:56.962396 [DEBUG] switch_core_session.c:1069 Session 11 (sofia/internal/90964111 at 192.168.1.116:9390) Locked, Waiting on external entities
2009-12-02 19:49:56.963391 [DEBUG] switch_ivr_originate.c:2273 Originate Resulted in Error Cause: 21 [CALL_REJECTED]
2009-12-02 19:49:56.963391 [INFO] mod_dptools.c:2106 Originate Failed. Cause: CALL_REJECTED
2009-12-02 19:49:56.963391 [DEBUG] mod_dptools.c:2128 Continue on fail [true]: Cause: CALL_REJECTED
2009-12-02 19:49:56.963391 [INFO] mod_dptools.c:955 CHANNEL_DATA:
Channel-State: [CS_EXECUTE]
Channel-State-Number: [4]
Channel-Name: [sofia/internal/PhonerLite at 192.168.1.102]
Unique-ID: [85042f75-de46-4fe5-b8d8-53e4ca0d26e1]
Call-Direction: [inbound]
Presence-Call-Direction: [inbound]
Answer-State: [ringing]
Caller-Username: [PhonerLite]
Caller-Dialplan: [class4]
Caller-Caller-ID-Name: [PhonerLite]
Caller-Caller-ID-Number: [PhonerLite]
Caller-Network-Addr: [192.168.1.104]
Caller-Destination-Number: [90964111]
Caller-Unique-ID: [85042f75-de46-4fe5-b8d8-53e4ca0d26e1]
Caller-Source: [mod_sofia]
Caller-Context: [default]
Caller-Channel-Name: [sofia/internal/PhonerLite at 192.168.1.102]
Caller-Profile-Index: [1]
Caller-Profile-Created-Time: [1259754596930444]
Caller-Channel-Created-Time: [1259754596930444]
Caller-Channel-Answered-Time: [0]
Caller-Channel-Progress-Time: [0]
Caller-Channel-Progress-Media-Time: [0]
Caller-Channel-Hangup-Time: [0]
Caller-Channel-Transfer-Time: [0]
Caller-Screen-Bit: [true]
Caller-Privacy-Hide-Name: [false]
Caller-Privacy-Hide-Number: [false]
Other-Leg-Username: [PhonerLite]
Other-Leg-Caller-ID-Name: [PhonerLite]
Other-Leg-Caller-ID-Number: [PhonerLite]
Other-Leg-Network-Addr: [192.168.1.104]
Other-Leg-Destination-Number: [90964111 at 192.168.1.116:9390]
Other-Leg-Unique-ID: [aca42f97-803b-40b4-93b5-79531cdd47e7]
Other-Leg-Source: [mod_sofia]
Other-Leg-Channel-Name: [sofia/internal/90964111 at 192.168.1.116:9390]
Other-Leg-Screen-Bit: [true]
Other-Leg-Privacy-Hide-Name: [false]
Other-Leg-Privacy-Hide-Number: [false]
variable_sip_received_ip: [192.168.1.104]
variable_sip_received_port: [5060]
variable_sip_via_protocol: [udp]
variable_sip_from_user: [PhonerLite]
variable_sip_from_uri: [PhonerLite at 192.168.1.102]
variable_sip_from_host: [192.168.1.102]
variable_sip_from_user_stripped: [PhonerLite]
variable_sip_from_tag: [2563216860]
variable_sofia_profile_name: [internal]
variable_sip_req_user: [90964111]
variable_sip_req_uri: [90964111 at 192.168.1.102]
variable_sip_req_host: [192.168.1.102]
variable_sip_to_user: [90964111]
variable_sip_to_uri: [90964111 at 192.168.1.102]
variable_sip_to_host: [192.168.1.102]
variable_sip_contact_port: [5060]
variable_sip_contact_uri: [PhonerLite at 192.168.1.104:5060]
variable_sip_contact_host: [192.168.1.104]
variable_channel_name: [sofia/internal/PhonerLite at 192.168.1.102]
variable_sip_call_id: [80F2B424-64DD-DE11-80D2-001A805656A5 at 192.168.1.104]
variable_sip_user_agent: [SIPPER for PhonerLite]
variable_sip_via_host: [192.168.1.104]
variable_sip_via_port: [5060]
variable_sip_via_rport: [5060]
variable_max_forwards: [70]
variable_switch_r_sdp: [v=0
o=- 478760567 0 IN IP4 192.168.1.104
s=SIPPER for PhonerLite
c=IN IP4 192.168.1.104
t=0 0
m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:3 GSM/8000
a=rtpmap:97 iLBC/8000
a=rtpmap:110 speex/8000
a=rtpmap:111 speex/16000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
]
variable_ep_codec_string: [PCMA at 8000h,PCMU at 8000h]
variable_endpoint_disposition: [RECEIVED_NOMEDIA]
variable_effective_caller_id_number: [PhonerLite]
variable_effective_caller_id_name: [PhonerLite]
variable_<![CDATA[sip_h_Remote-Party-ID: [PhonerLite><sip:]
variable_routing_digit: [90964111]
variable_continue_on_fail: [true]
variable_hangup_after_bridge: [true]
variable_sip_contact_user: [PhonerLite]
variable_ingress_gateway: [192.168.1.104]
variable_ingress_alias: [7390]
variable_pure_dest_num: [90964111]
variable_ingress_res_id: [201]
variable_ingress_gw_id: [256]
variable_bypass_media: [true]
variable_class4_sip_from_user: [PhonerLite]
variable_class4_sip_received_ip: [192.168.1.104]
variable_class4_req_user: [90964111]
variable_class4_req_host: [192.168.1.102]
variable_class4_call_id: [80F2B424-64DD-DE11-80D2-001A805656A5 at 192.168.1.104]
variable_final_digits: [90964111]
variable_interm_digits: [90964111]
variable_egress_alias: [9390]
variable_prefix_id: [2591585]
variable_product_id: [25]
variable_product_name: [classfun]
variable_prefix_name: [116]
variable_egress_gateway: [192.168.1.116]
variable_egress_port: [9390]
variable_attempt_type: [I]
variable_egress_res_id: [202]
variable_egress_gw_id: [257]
variable_route_0: [9390;192.168.1.116;9390;classfun;116;2591585;90964111;90964111;100000;100000;25;202;257;10000;1000;t;t;201;256]
variable_route_1: [9390;192.168.1.116;9391;classfun;116;2591585;90964111;90964111;100000;100000;25;202;258;10000;1000;t;t;201;256]
variable_route_2: [9392;192.168.1.13;5060;classfun;116;2591585;90964111;90964111;10000;100;25;203;621;10;10;t;t;201;256]
variable_route_3: [9392;192.168.1.116;9392;classfun;116;2591585;90964111;90964111;10000;100;25;203;622;10;10;t;t;201;256]
variable_current_route_num: [0]
variable_total_route_cnt: [4]
variable_nolocal:execute_on_answer: [incre_call_stat 203 622 201 256 25 2591585 1]
variable_export_vars: [sip_contact_user,ingress_gateway,ingress_alias,pure_dest_num,ingress_res_id,ingress_gw_id,bypass_media,class4_sip_from_user,class4_sip_received_ip,class4_req_user,class4_req_host,class4_call_id,final_digits,interm_digits,egress_alias,prefix_id,product_id,product_name,prefix_name,egress_gateway,egress_port,attempt_type,egress_res_id,egress_gw_id,nolocal:execute_on_answer]
variable_limit_usage_3_25_0: [1]
variable_limit_rate_3_25_0: [1]
variable_limit_usage: [1]
variable_limit_usage_3_25_2591585: [1]
variable_limit_rate: [1]
variable_limit_rate_3_25_2591585: [1]
variable_success_bridge: [true]
variable_signal_bond: [aca42f97-803b-40b4-93b5-79531cdd47e7]
variable_proto_specific_hangup_cause: [sip:403]
variable_sip_hangup_phrase: [Because]
variable_originate_disposition: [CALL_REJECTED]
variable_current_application: [info]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 set(bypass_media=true)
2009-12-02 19:49:56.964394 [DEBUG] mod_dptools.c:752 sofia/internal/PhonerLite at 192.168.1.102 SET [bypass_media]=[true]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 export(bypass_media=true)
2009-12-02 19:49:56.964394 [DEBUG] mod_dptools.c:890 EXPORT [bypass_media]=[true]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 set(final_digits=90964111)
2009-12-02 19:49:56.964394 [DEBUG] mod_dptools.c:752 sofia/internal/PhonerLite at 192.168.1.102 SET [final_digits]=[90964111]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 set(interm_digits=90964111)
2009-12-02 19:49:56.964394 [DEBUG] mod_dptools.c:752 sofia/internal/PhonerLite at 192.168.1.102 SET [interm_digits]=[90964111]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 set(egress_alias=9390)
2009-12-02 19:49:56.964394 [DEBUG] mod_dptools.c:752 sofia/internal/PhonerLite at 192.168.1.102 SET [egress_alias]=[9390]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 set(prefix_id=2591585)
2009-12-02 19:49:56.965382 [DEBUG] mod_dptools.c:752 sofia/internal/PhonerLite at 192.168.1.102 SET [prefix_id]=[2591585]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 set(product_name=classfun)
2009-12-02 19:49:56.965382 [DEBUG] mod_dptools.c:752 sofia/internal/PhonerLite at 192.168.1.102 SET [product_name]=[classfun]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 set(prefix_name=116)
2009-12-02 19:49:56.965382 [DEBUG] mod_dptools.c:752 sofia/internal/PhonerLite at 192.168.1.102 SET [prefix_name]=[116]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 set(egress_gateway=192.168.1.116)
2009-12-02 19:49:56.965382 [DEBUG] mod_dptools.c:752 sofia/internal/PhonerLite at 192.168.1.102 SET [egress_gateway]=[192.168.1.116]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 set(egress_port=9391)
2009-12-02 19:49:56.965382 [DEBUG] mod_dptools.c:752 sofia/internal/PhonerLite at 192.168.1.102 SET [egress_port]=[9391]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 set(attempt_type=I)
2009-12-02 19:49:56.965382 [DEBUG] mod_dptools.c:752 sofia/internal/PhonerLite at 192.168.1.102 SET [attempt_type]=[I]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 set(product_id=25)
2009-12-02 19:49:56.965382 [DEBUG] mod_dptools.c:752 sofia/internal/PhonerLite at 192.168.1.102 SET [product_id]=[25]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 set(egress_res_id=202)
2009-12-02 19:49:56.965382 [DEBUG] mod_dptools.c:752 sofia/internal/PhonerLite at 192.168.1.102 SET [egress_res_id]=[202]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 set(egress_gw_id=258)
2009-12-02 19:49:56.965382 [DEBUG] mod_dptools.c:752 sofia/internal/PhonerLite at 192.168.1.102 SET [egress_gw_id]=[258]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 export(class4_sip_from_user=PhonerLite)
2009-12-02 19:49:56.967380 [DEBUG] mod_dptools.c:890 EXPORT [class4_sip_from_user]=[PhonerLite]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 export(class4_sip_received_ip=192.168.1.104)
2009-12-02 19:49:56.967380 [DEBUG] mod_dptools.c:890 EXPORT [class4_sip_received_ip]=[192.168.1.104]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 export(class4_req_user=90964111)
2009-12-02 19:49:56.967380 [DEBUG] mod_dptools.c:890 EXPORT [class4_req_user]=[90964111]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 export(class4_req_host=192.168.1.102)
2009-12-02 19:49:56.967380 [DEBUG] mod_dptools.c:890 EXPORT [class4_req_host]=[192.168.1.102]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 export(class4_call_id=80F2B424-64DD-DE11-80D2-001A805656A5 at 192.168.1.104)
2009-12-02 19:49:56.968392 [DEBUG] mod_dptools.c:890 EXPORT [class4_call_id]=[80F2B424-64DD-DE11-80D2-001A805656A5 at 192.168.1.104]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 export(final_digits=90964111)
2009-12-02 19:49:56.968392 [DEBUG] mod_dptools.c:890 EXPORT [final_digits]=[90964111]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 export(interm_digits=90964111)
2009-12-02 19:49:56.968392 [DEBUG] mod_dptools.c:890 EXPORT [interm_digits]=[90964111]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 export(egress_alias=9390)
2009-12-02 19:49:56.969386 [DEBUG] mod_dptools.c:890 EXPORT [egress_alias]=[9390]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 export(prefix_id=2591585)
2009-12-02 19:49:56.969386 [DEBUG] mod_dptools.c:890 EXPORT [prefix_id]=[2591585]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 export(product_name=classfun)
2009-12-02 19:49:56.969386 [DEBUG] mod_dptools.c:890 EXPORT [product_name]=[classfun]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 export(prefix_name=116)
2009-12-02 19:49:56.969386 [DEBUG] mod_dptools.c:890 EXPORT [prefix_name]=[116]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 export(egress_gateway=192.168.1.116)
2009-12-02 19:49:56.970392 [DEBUG] mod_dptools.c:890 EXPORT [egress_gateway]=[192.168.1.116]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 export(egress_port=9391)
2009-12-02 19:49:56.970392 [DEBUG] mod_dptools.c:890 EXPORT [egress_port]=[9391]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 export(attempt_type=I)
2009-12-02 19:49:56.970392 [DEBUG] mod_dptools.c:890 EXPORT [attempt_type]=[I]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 export(product_id=25)
2009-12-02 19:49:56.970392 [DEBUG] mod_dptools.c:890 EXPORT [product_id]=[25]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 export(egress_res_id=202)
2009-12-02 19:49:56.971382 [DEBUG] mod_dptools.c:890 EXPORT [egress_res_id]=[202]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 export(egress_gw_id=258)
2009-12-02 19:49:56.971382 [DEBUG] mod_dptools.c:890 EXPORT [egress_gw_id]=[258]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 set(current_route_num=1)
2009-12-02 19:49:56.971382 [DEBUG] mod_dptools.c:752 sofia/internal/PhonerLite at 192.168.1.102 SET [current_route_num]=[1]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 set(total_route_cnt=4)
2009-12-02 19:49:56.971382 [DEBUG] mod_dptools.c:752 sofia/internal/PhonerLite at 192.168.1.102 SET [total_route_cnt]=[4]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 export(current_route_num=1)
2009-12-02 19:49:56.971382 [DEBUG] mod_dptools.c:890 EXPORT [current_route_num]=[1]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 export(total_route_cnt=4)
2009-12-02 19:49:56.971382 [DEBUG] mod_dptools.c:890 EXPORT [total_route_cnt]=[4]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 export(nolocal:execute_on_answer=incre_call_stat 202 258 201 256 25 2591585 1)
2009-12-02 19:49:56.971382 [DEBUG] mod_dptools.c:890 EXPORT (REMOTE ONLY) [execute_on_answer]=[incre_call_stat 202 258 201 256 25 2591585 1]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 class4_limit_hash_execute(9390 192.168.1.116 OUT 100000 100000 10000 1000 25 2591585 bridge sofia/internal/90964111 at 192.168.1.116:9391)
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 record_prefix_info()
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 set(success_bridge=true)
2009-12-02 19:49:56.973380 [DEBUG] mod_dptools.c:752 sofia/internal/PhonerLite at 192.168.1.102 SET [success_bridge]=[true]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 bridge(sofia/internal/90964111 at 192.168.1.116:9391)
2009-12-02 19:49:56.973380 [NOTICE] switch_channel.c:613 New Channel sofia/internal/90964111 at 192.168.1.116:9391 [990cc7dc-e7ea-4b82-850c-9c8c254136f9]
2009-12-02 19:49:56.973380 [DEBUG] mod_sofia.c:2893 (sofia/internal/90964111 at 192.168.1.116:9391) State Change CS_NEW -> CS_INIT
2009-12-02 19:49:56.973380 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111 at 192.168.1.116:9391 [BREAK]
2009-12-02 19:49:56.974397 [NOTICE] switch_core_session.c:1087 Session 11 (sofia/internal/90964111 at 192.168.1.116:9390) Ended
2009-12-02 19:49:56.974397 [NOTICE] switch_core_session.c:1089 Close Channel sofia/internal/90964111 at 192.168.1.116:9390 [CS_DESTROY]
2009-12-02 19:49:56.974397 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/90964111 at 192.168.1.116:9390) Running State Change CS_DESTROY
2009-12-02 19:49:56.974397 [DEBUG] switch_core_state_machine.c:412 (sofia/internal/90964111 at 192.168.1.116:9390) State DESTROY
2009-12-02 19:49:56.974397 [DEBUG] mod_sofia.c:255 sofia/internal/90964111 at 192.168.1.116:9390 SOFIA DESTROY
2009-12-02 19:49:56.974397 [DEBUG] switch_core_state_machine.c:60 sofia/internal/90964111 at 192.168.1.116:9390 Standard DESTROY
2009-12-02 19:49:56.974397 [DEBUG] switch_core_state_machine.c:412 (sofia/internal/90964111 at 192.168.1.116:9390) State DESTROY going to sleep
2009-12-02 19:49:56.974397 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111 at 192.168.1.116:9391) Running State Change CS_INIT
2009-12-02 19:49:56.974397 [DEBUG] switch_core_state_machine.c:330 (sofia/internal/90964111 at 192.168.1.116:9391) State INIT
2009-12-02 19:49:56.974397 [DEBUG] mod_sofia.c:83 sofia/internal/90964111 at 192.168.1.116:9391 SOFIA INIT
2009-12-02 19:49:56.974397 [DEBUG] mod_sofia.c:111 (sofia/internal/90964111 at 192.168.1.116:9391) State Change CS_INIT -> CS_ROUTING
2009-12-02 19:49:56.974397 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111 at 192.168.1.116:9391 [BREAK]
2009-12-02 19:49:56.974397 [DEBUG] switch_core_state_machine.c:330 (sofia/internal/90964111 at 192.168.1.116:9391) State INIT going to sleep
2009-12-02 19:49:56.974397 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111 at 192.168.1.116:9391) Running State Change CS_ROUTING
2009-12-02 19:49:56.974397 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/90964111 at 192.168.1.116:9391) State ROUTING
2009-12-02 19:49:56.974397 [DEBUG] mod_sofia.c:130 sofia/internal/90964111 at 192.168.1.116:9391 SOFIA ROUTING
2009-12-02 19:49:56.974397 [DEBUG] switch_ivr_originate.c:66 (sofia/internal/90964111 at 192.168.1.116:9391) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2009-12-02 19:49:56.974397 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111 at 192.168.1.116:9391 [BREAK]
2009-12-02 19:49:56.974397 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/90964111 at 192.168.1.116:9391) State ROUTING going to sleep
2009-12-02 19:49:56.974397 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111 at 192.168.1.116:9391) Running State Change CS_CONSUME_MEDIA
2009-12-02 19:49:56.974397 [DEBUG] switch_core_state_machine.c:352 (sofia/internal/90964111 at 192.168.1.116:9391) State CONSUME_MEDIA
2009-12-02 19:49:56.974397 [DEBUG] switch_core_state_machine.c:352 (sofia/internal/90964111 at 192.168.1.116:9391) State CONSUME_MEDIA going to sleep
send 1157 bytes to udp/[192.168.1.116]:9391 at 11:49:56.980816:
------------------------------------------------------------------------
INVITE sip:90964111 at 192.168.1.116:9391 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKtjHD41gX14Zpg
Max-Forwards: 69
From: "PhonerLite" <sip:PhonerLite at 192.168.1.102>;tag=S9FN20X35DZXS
To: <sip:90964111 at 192.168.1.116:9391>
Call-ID: a771eccc-59db-122d-ff84-0022190e9476
CSeq: 123758962 INVITE
Contact: <sip:PhonerLite at 192.168.1.102:5060>
User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY
Supported: timer, precondition, path, replaces
Allow-Events: talk, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 402
Remote-Party-ID: "PhonerLite" <sip:PhonerLite at 192.168.1.102>;party=calling;screen=yes;privacy=off
v=0
o=- 478760567 5392773558290384508 IN IP4 192.168.1.104
s=SIPPER for PhonerLite
c=IN IP4 192.168.1.104
t=0 0
m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:3 GSM/8000
a=rtpmap:97 iLBC/8000
a=rtpmap:110 speex/8000
a=rtpmap:111 speex/16000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
------------------------------------------------------------------------
2009-12-02 19:49:56.980394 [DEBUG] sofia.c:3359 Channel sofia/internal/90964111 at 192.168.1.116:9391 entering state [calling][0]
recv 342 bytes from udp/[192.168.1.116]:9391 at 11:49:56.990534:
------------------------------------------------------------------------
SIP/2.0 403 Because
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKtjHD41gX14Zpg
From: "PhonerLite" <sip:PhonerLite at 192.168.1.102>;tag=S9FN20X35DZXS
To: <sip:90964111 at 192.168.1.116:9391>;tag=6
Call-ID: a771eccc-59db-122d-ff84-0022190e9476
CSeq: 123758962 INVITE
Contact: <sip:192.168.1.116:9391;transport=UDP>
Content-Length: 0
------------------------------------------------------------------------
send 332 bytes to udp/[192.168.1.116]:9391 at 11:49:56.990729:
------------------------------------------------------------------------
ACK sip:90964111 at 192.168.1.116:9391 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKtjHD41gX14Zpg
Max-Forwards: 69
From: "PhonerLite" <sip:PhonerLite at 192.168.1.102>;tag=S9FN20X35DZXS
To: <sip:90964111 at 192.168.1.116:9391>;tag=6
Call-ID: a771eccc-59db-122d-ff84-0022190e9476
CSeq: 123758962 ACK
Content-Length: 0
------------------------------------------------------------------------
2009-12-02 19:49:56.990410 [DEBUG] sofia.c:3359 Channel sofia/internal/90964111 at 192.168.1.116:9391 entering state [terminated][403]
2009-12-02 19:49:56.990410 [NOTICE] sofia.c:3925 Hangup sofia/internal/90964111 at 192.168.1.116:9391 [CS_CONSUME_MEDIA] [CALL_REJECTED]
2009-12-02 19:49:56.990410 [DEBUG] switch_channel.c:1726 Send signal sofia/internal/90964111 at 192.168.1.116:9391 [KILL]
2009-12-02 19:49:56.990410 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111 at 192.168.1.116:9391 [BREAK]
2009-12-02 19:49:56.990410 [DEBUG] switch_core_state_machine.c:437 thread mismatch skipping state handler.
2009-12-02 19:49:56.990410 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111 at 192.168.1.116:9391) Running State Change CS_HANGUP
2009-12-02 19:49:56.991392 [DEBUG] switch_core_state_machine.c:464 (sofia/internal/90964111 at 192.168.1.116:9391) State HANGUP
2009-12-02 19:49:56.991392 [DEBUG] mod_sofia.c:306 sofia/internal/90964111 at 192.168.1.116:9391 Overriding SIP cause 603 with 403 from the other leg
2009-12-02 19:49:56.991392 [DEBUG] mod_sofia.c:338 Channel sofia/internal/90964111 at 192.168.1.116:9391 hanging up, cause: CALL_REJECTED
2009-12-02 19:49:56.991392 [DEBUG] switch_core_state_machine.c:46 sofia/internal/90964111 at 192.168.1.116:9391 Standard HANGUP, cause: CALL_REJECTED
2009-12-02 19:49:56.991392 [DEBUG] switch_core_state_machine.c:464 (sofia/internal/90964111 at 192.168.1.116:9391) State HANGUP going to sleep
2009-12-02 19:49:56.991392 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/90964111 at 192.168.1.116:9391) State Change CS_HANGUP -> CS_REPORTING
2009-12-02 19:49:56.991392 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111 at 192.168.1.116:9391 [BREAK]
2009-12-02 19:49:56.991392 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111 at 192.168.1.116:9391) Running State Change CS_REPORTING
2009-12-02 19:49:56.991392 [DEBUG] switch_core_state_machine.c:555 (sofia/internal/90964111 at 192.168.1.116:9391) State REPORTING
2009-12-02 19:49:56.991392 [DEBUG] switch_core_state_machine.c:53 sofia/internal/90964111 at 192.168.1.116:9391 Standard REPORTING, cause: CALL_REJECTED
2009-12-02 19:49:56.991392 [DEBUG] switch_core_state_machine.c:555 (sofia/internal/90964111 at 192.168.1.116:9391) State REPORTING going to sleep
2009-12-02 19:49:56.991392 [DEBUG] switch_core_state_machine.c:319 (sofia/internal/90964111 at 192.168.1.116:9391) State Change CS_REPORTING -> CS_DESTROY
2009-12-02 19:49:56.991392 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111 at 192.168.1.116:9391 [BREAK]
2009-12-02 19:49:56.991392 [DEBUG] switch_core_session.c:1069 Session 12 (sofia/internal/90964111 at 192.168.1.116:9391) Locked, Waiting on external entities
2009-12-02 19:49:56.991392 [DEBUG] switch_ivr_originate.c:2273 Originate Resulted in Error Cause: 21 [CALL_REJECTED]
2009-12-02 19:49:56.991392 [INFO] mod_dptools.c:2106 Originate Failed. Cause: CALL_REJECTED
2009-12-02 19:49:56.991392 [DEBUG] mod_dptools.c:2128 Continue on fail [true]: Cause: CALL_REJECTED
2009-12-02 19:49:56.992388 [INFO] mod_dptools.c:955 CHANNEL_DATA:
Channel-State: [CS_EXECUTE]
Channel-State-Number: [4]
Channel-Name: [sofia/internal/PhonerLite at 192.168.1.102]
Unique-ID: [85042f75-de46-4fe5-b8d8-53e4ca0d26e1]
Call-Direction: [inbound]
Presence-Call-Direction: [inbound]
Answer-State: [ringing]
Caller-Username: [PhonerLite]
Caller-Dialplan: [class4]
Caller-Caller-ID-Name: [PhonerLite]
Caller-Caller-ID-Number: [PhonerLite]
Caller-Network-Addr: [192.168.1.104]
Caller-Destination-Number: [90964111]
Caller-Unique-ID: [85042f75-de46-4fe5-b8d8-53e4ca0d26e1]
Caller-Source: [mod_sofia]
Caller-Context: [default]
Caller-Channel-Name: [sofia/internal/PhonerLite at 192.168.1.102]
Caller-Profile-Index: [1]
Caller-Profile-Created-Time: [1259754596930444]
Caller-Channel-Created-Time: [1259754596930444]
Caller-Channel-Answered-Time: [0]
Caller-Channel-Progress-Time: [0]
Caller-Channel-Progress-Media-Time: [0]
Caller-Channel-Hangup-Time: [0]
Caller-Channel-Transfer-Time: [0]
Caller-Screen-Bit: [true]
Caller-Privacy-Hide-Name: [false]
Caller-Privacy-Hide-Number: [false]
Other-Leg-Username: [PhonerLite]
Other-Leg-Caller-ID-Name: [PhonerLite]
Other-Leg-Caller-ID-Number: [PhonerLite]
Other-Leg-Network-Addr: [192.168.1.104]
Other-Leg-Destination-Number: [90964111 at 192.168.1.116:9391]
Other-Leg-Unique-ID: [990cc7dc-e7ea-4b82-850c-9c8c254136f9]
Other-Leg-Source: [mod_sofia]
Other-Leg-Channel-Name: [sofia/internal/90964111 at 192.168.1.116:9391]
Other-Leg-Screen-Bit: [true]
Other-Leg-Privacy-Hide-Name: [false]
Other-Leg-Privacy-Hide-Number: [false]
variable_sip_received_ip: [192.168.1.104]
variable_sip_received_port: [5060]
variable_sip_via_protocol: [udp]
variable_sip_from_user: [PhonerLite]
variable_sip_from_uri: [PhonerLite at 192.168.1.102]
variable_sip_from_host: [192.168.1.102]
variable_sip_from_user_stripped: [PhonerLite]
variable_sip_from_tag: [2563216860]
variable_sofia_profile_name: [internal]
variable_sip_req_user: [90964111]
variable_sip_req_uri: [90964111 at 192.168.1.102]
variable_sip_req_host: [192.168.1.102]
variable_sip_to_user: [90964111]
variable_sip_to_uri: [90964111 at 192.168.1.102]
variable_sip_to_host: [192.168.1.102]
variable_sip_contact_port: [5060]
variable_sip_contact_uri: [PhonerLite at 192.168.1.104:5060]
variable_sip_contact_host: [192.168.1.104]
variable_channel_name: [sofia/internal/PhonerLite at 192.168.1.102]
variable_sip_call_id: [80F2B424-64DD-DE11-80D2-001A805656A5 at 192.168.1.104]
variable_sip_user_agent: [SIPPER for PhonerLite]
variable_sip_via_host: [192.168.1.104]
variable_sip_via_port: [5060]
variable_sip_via_rport: [5060]
variable_max_forwards: [70]
variable_switch_r_sdp: [v=0
o=- 478760567 0 IN IP4 192.168.1.104
s=SIPPER for PhonerLite
c=IN IP4 192.168.1.104
t=0 0
m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:3 GSM/8000
a=rtpmap:97 iLBC/8000
a=rtpmap:110 speex/8000
a=rtpmap:111 speex/16000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
]
variable_ep_codec_string: [PCMA at 8000h,PCMU at 8000h]
variable_endpoint_disposition: [RECEIVED_NOMEDIA]
variable_effective_caller_id_number: [PhonerLite]
variable_effective_caller_id_name: [PhonerLite]
variable_<![CDATA[sip_h_Remote-Party-ID: [PhonerLite><sip:]
variable_routing_digit: [90964111]
variable_continue_on_fail: [true]
variable_hangup_after_bridge: [true]
variable_sip_contact_user: [PhonerLite]
variable_ingress_gateway: [192.168.1.104]
variable_ingress_alias: [7390]
variable_pure_dest_num: [90964111]
variable_ingress_res_id: [201]
variable_ingress_gw_id: [256]
variable_route_0: [9390;192.168.1.116;9390;classfun;116;2591585;90964111;90964111;100000;100000;25;202;257;10000;1000;t;t;201;256]
variable_route_1: [9390;192.168.1.116;9391;classfun;116;2591585;90964111;90964111;100000;100000;25;202;258;10000;1000;t;t;201;256]
variable_route_2: [9392;192.168.1.13;5060;classfun;116;2591585;90964111;90964111;10000;100;25;203;621;10;10;t;t;201;256]
variable_route_3: [9392;192.168.1.116;9392;classfun;116;2591585;90964111;90964111;10000;100;25;203;622;10;10;t;t;201;256]
variable_bypass_media: [true]
variable_class4_sip_from_user: [PhonerLite]
variable_class4_sip_received_ip: [192.168.1.104]
variable_class4_req_user: [90964111]
variable_class4_req_host: [192.168.1.102]
variable_class4_call_id: [80F2B424-64DD-DE11-80D2-001A805656A5 at 192.168.1.104]
variable_final_digits: [90964111]
variable_interm_digits: [90964111]
variable_egress_alias: [9390]
variable_prefix_id: [2591585]
variable_product_name: [classfun]
variable_prefix_name: [116]
variable_egress_gateway: [192.168.1.116]
variable_egress_port: [9391]
variable_attempt_type: [I]
variable_product_id: [25]
variable_egress_res_id: [202]
variable_egress_gw_id: [258]
variable_current_route_num: [1]
variable_total_route_cnt: [4]
variable_nolocal:execute_on_answer: [incre_call_stat 202 258 201 256 25 2591585 1]
variable_export_vars: [sip_contact_user,ingress_gateway,ingress_alias,pure_dest_num,ingress_res_id,ingress_gw_id,bypass_media,class4_sip_from_user,class4_sip_received_ip,class4_req_user,class4_req_host,class4_call_id,final_digits,interm_digits,egress_alias,prefix_id,product_id,product_name,prefix_name,egress_gateway,egress_port,attempt_type,egress_res_id,egress_gw_id,nolocal:execute_on_answer,bypass_media,class4_sip_from_user,class4_sip_received_ip,class4_req_user,class4_req_host,class4_call_id,final_digits,interm_digits,egress_alias,prefix_id,product_name,prefix_name,egress_gateway,egress_port,attempt_type,product_id,egress_res_id,egress_gw_id,current_route_num,total_route_cnt,nolocal:execute_on_answer]
variable_limit_usage_3_25_0: [1]
variable_limit_rate_3_25_0: [2]
variable_limit_usage: [1]
variable_limit_usage_3_25_2591585: [1]
variable_limit_rate: [2]
variable_limit_rate_3_25_2591585: [2]
variable_success_bridge: [true]
variable_signal_bond: [990cc7dc-e7ea-4b82-850c-9c8c254136f9]
variable_proto_specific_hangup_cause: [sip:403]
variable_sip_hangup_phrase: [Because]
variable_originate_disposition: [CALL_REJECTED]
variable_current_application: [info]
2009-12-02 19:49:57.003383 [DEBUG] mod_sofia.c:2893 (sofia/internal/90964111 at 192.168.1.13:5060) State Change CS_NEW -> CS_INIT
2009-12-02 19:49:57.003383 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111 at 192.168.1.13:5060 [BREAK]
2009-12-02 19:49:57.004386 [NOTICE] switch_core_session.c:1087 Session 12 (sofia/internal/90964111 at 192.168.1.116:9391) Ended
2009-12-02 19:49:57.004386 [NOTICE] switch_core_session.c:1089 Close Channel sofia/internal/90964111 at 192.168.1.116:9391 [CS_DESTROY]
2009-12-02 19:49:57.004386 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/90964111 at 192.168.1.116:9391) Running State Change CS_DESTROY
2009-12-02 19:49:57.004386 [DEBUG] switch_core_state_machine.c:412 (sofia/internal/90964111 at 192.168.1.116:9391) State DESTROY
2009-12-02 19:49:57.004386 [DEBUG] mod_sofia.c:255 sofia/internal/90964111 at 192.168.1.116:9391 SOFIA DESTROY
2009-12-02 19:49:57.004386 [DEBUG] switch_core_state_machine.c:60 sofia/internal/90964111 at 192.168.1.116:9391 Standard DESTROY
2009-12-02 19:49:57.004386 [DEBUG] switch_core_state_machine.c:412 (sofia/internal/90964111 at 192.168.1.116:9391) State DESTROY going to sleep
2009-12-02 19:49:57.004386 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111 at 192.168.1.13:5060) Running State Change CS_INIT
2009-12-02 19:49:57.004386 [DEBUG] switch_core_state_machine.c:330 (sofia/internal/90964111 at 192.168.1.13:5060) State INIT
2009-12-02 19:49:57.004386 [DEBUG] mod_sofia.c:83 sofia/internal/90964111 at 192.168.1.13:5060 SOFIA INIT
2009-12-02 19:49:57.004386 [DEBUG] mod_sofia.c:111 (sofia/internal/90964111 at 192.168.1.13:5060) State Change CS_INIT -> CS_ROUTING
2009-12-02 19:49:57.004386 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111 at 192.168.1.13:5060 [BREAK]
2009-12-02 19:49:57.004386 [DEBUG] switch_core_state_machine.c:330 (sofia/internal/90964111 at 192.168.1.13:5060) State INIT going to sleep
2009-12-02 19:49:57.004386 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111 at 192.168.1.13:5060) Running State Change CS_ROUTING
2009-12-02 19:49:57.004386 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/90964111 at 192.168.1.13:5060) State ROUTING
2009-12-02 19:49:57.004386 [DEBUG] mod_sofia.c:130 sofia/internal/90964111 at 192.168.1.13:5060 SOFIA ROUTING
2009-12-02 19:49:57.004386 [DEBUG] switch_ivr_originate.c:66 (sofia/internal/90964111 at 192.168.1.13:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2009-12-02 19:49:57.004386 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111 at 192.168.1.13:5060 [BREAK]
2009-12-02 19:49:57.004386 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/90964111 at 192.168.1.13:5060) State ROUTING going to sleep
2009-12-02 19:49:57.004386 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111 at 192.168.1.13:5060) Running State Change CS_CONSUME_MEDIA
2009-12-02 19:49:57.004386 [DEBUG] switch_core_state_machine.c:352 (sofia/internal/90964111 at 192.168.1.13:5060) State CONSUME_MEDIA
2009-12-02 19:49:57.004386 [DEBUG] switch_core_state_machine.c:352 (sofia/internal/90964111 at 192.168.1.13:5060) State CONSUME_MEDIA going to sleep
send 1155 bytes to udp/[192.168.1.13]:5060 at 11:49:57.012792:
------------------------------------------------------------------------
INVITE sip:90964111 at 192.168.1.13:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKUUa65v10yDp9B
Max-Forwards: 69
From: "PhonerLite" <sip:PhonerLite at 192.168.1.102>;tag=tj9D4Ue72pNgN
To: <sip:90964111 at 192.168.1.13:5060>
Call-ID: a776ce22-59db-122d-ff84-0022190e9476
CSeq: 123758962 INVITE
Contact: <sip:PhonerLite at 192.168.1.102:5060>
User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY
Supported: timer, precondition, path, replaces
Allow-Events: talk, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 402
Remote-Party-ID: "PhonerLite" <sip:PhonerLite at 192.168.1.102>;party=calling;screen=yes;privacy=off
v=0
o=- 478760567 3248233194293522444 IN IP4 192.168.1.104
s=SIPPER for PhonerLite
c=IN IP4 192.168.1.104
t=0 0
m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:3 GSM/8000
a=rtpmap:97 iLBC/8000
a=rtpmap:110 speex/8000
a=rtpmap:111 speex/16000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
------------------------------------------------------------------------
2009-12-02 19:49:57.012392 [DEBUG] sofia.c:3359 Channel sofia/internal/90964111 at 192.168.1.13:5060 entering state [calling][0]
recv 283 bytes from udp/[192.168.1.13]:5060 at 11:49:57.219574:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKUUa65v10yDp9B
From: PhonerLite <sip:PhonerLite at 192.168.1.102>;tag=tj9D4Ue72pNgN
To: <sip:90964111 at 192.168.1.13:5060>
Call-ID: a776ce22-59db-122d-ff84-0022190e9476
CSeq: 123758962 INVITE
Content-Length: 0
------------------------------------------------------------------------
recv 333 bytes from udp/[192.168.1.13]:5060 at 11:49:57.234550:
------------------------------------------------------------------------
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKUUa65v10yDp9B
From: PhonerLite <sip:PhonerLite at 192.168.1.102>;tag=tj9D4Ue72pNgN
To: <sip:90964111 at 192.168.1.13:5060>;tag=2900712955
Call-ID: a776ce22-59db-122d-ff84-0022190e9476
CSeq: 123758962 INVITE
Contact: <sip:192.168.1.13:5060>
Content-Length: 0
------------------------------------------------------------------------
2009-12-02 19:49:57.233383 [DEBUG] sofia.c:3359 Channel sofia/internal/90964111 at 192.168.1.13:5060 entering state [proceeding][180]
2009-12-02 19:49:57.233383 [NOTICE] sofia.c:3423 Ring-Ready sofia/internal/90964111 at 192.168.1.13:5060!
2009-12-02 19:49:57.233383 [DEBUG] sofia.c:3432 sofia/internal/PhonerLite at 192.168.1.102 receive message [RINGING]
2009-12-02 19:49:57.233383 [NOTICE] mod_sofia.c:1461 Ring-Ready sofia/internal/PhonerLite at 192.168.1.102!
2009-12-02 19:49:57.233383 [DEBUG] switch_core_session.c:630 Send signal sofia/internal/PhonerLite at 192.168.1.102 [BREAK]
send 618 bytes to udp/[192.168.1.104]:5060 at 11:49:57.235092:
------------------------------------------------------------------------
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 192.168.1.104:5060;branch=z9hG4bK80f2b42464ddde1180d3001a805656a5;rport=5060
From: <sip:PhonerLite at 192.168.1.102>;tag=2563216860
To: <sip:90964111 at 192.168.1.102>;tag=QQX3yavvBvjrj
Call-ID: 80F2B424-64DD-DE11-80D2-001A805656A5 at 192.168.1.104
CSeq: 17 INVITE
Contact: <sip:90964111 at 192.168.1.102:5060;transport=udp>
User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095
Accept: application/sdp
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY
Supported: timer, precondition, path, replaces
Allow-Events: talk, refer
Content-Length: 0
------------------------------------------------------------------------
2009-12-02 19:49:57.233383 [DEBUG] sofia.c:3359 Channel sofia/internal/PhonerLite at 192.168.1.102 entering state [early][180]
2009-12-02 19:49:57.233383 [DEBUG] switch_ivr_originate.c:1931 sofia/internal/PhonerLite at 192.168.1.102 receive message [RINGING]
2009-12-02 19:49:57.233383 [DEBUG] switch_core_session.c:630 Send signal sofia/internal/PhonerLite at 192.168.1.102 [BREAK]
2009-12-02 19:49:57.233383 [NOTICE] switch_ivr_originate.c:1931 Ring Ready sofia/internal/PhonerLite at 192.168.1.102!
recv 725 bytes from udp/[192.168.1.13]:5060 at 11:49:59.906802:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKUUa65v10yDp9B
From: PhonerLite <sip:PhonerLite at 192.168.1.102>;tag=tj9D4Ue72pNgN
To: <sip:90964111 at 192.168.1.13:5060>;tag=2900712955
Call-ID: a776ce22-59db-122d-ff84-0022190e9476
CSeq: 123758962 INVITE
Contact: <sip:192.168.1.13:5060>
Supported: replaces
Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, REFER, NOTIFY, INFO, PRACK, UPDATE, MESSAGE
Content-Type: application/sdp
Content-Length: 256
v=0
o=sdp_admin 59711527 21326134 IN IP4 192.168.1.13
s=A conversation
c=IN IP4 192.168.1.13
t=0 0
m=audio 10098 RTP/AVP 8 0 9 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:9 G722/16000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
------------------------------------------------------------------------
send 376 bytes to udp/[192.168.1.13]:5060 at 11:49:59.907203:
------------------------------------------------------------------------
ACK sip:192.168.1.13:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKv43y7Qj4UpcvQ
Max-Forwards: 70
From: "PhonerLite" <sip:PhonerLite at 192.168.1.102>;tag=tj9D4Ue72pNgN
To: <sip:90964111 at 192.168.1.13:5060>;tag=2900712955
Call-ID: a776ce22-59db-122d-ff84-0022190e9476
CSeq: 123758962 ACK
Contact: <sip:PhonerLite at 192.168.1.102:5060>
Content-Length: 0
------------------------------------------------------------------------
2009-12-02 19:49:59.907094 [DEBUG] sofia.c:3359 Channel sofia/internal/90964111 at 192.168.1.13:5060 entering state [ready][200]
2009-12-02 19:49:59.907094 [DEBUG] sofia.c:3366 Remote SDP:
v=0
o=sdp_admin 59711527 21326134 IN IP4 192.168.1.13
s=A conversation
c=IN IP4 192.168.1.13
t=0 0
m=audio 10098 RTP/AVP 8 0 9 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:9 G722/16000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
2009-12-02 19:49:59.907094 [DEBUG] switch_channel.c:1935 Send signal sofia/internal/PhonerLite at 192.168.1.102 [BREAK]
2009-12-02 19:49:59.907094 [NOTICE] sofia.c:3834 Channel [sofia/internal/90964111 at 192.168.1.13:5060] has been answered
2009-12-02 19:49:59.907094 [DEBUG] switch_channel.c:1971 sofia/internal/90964111 at 192.168.1.13:5060 execute on answer: incre_call_stat(203 621 201 256 25 2591585 1)
EXECUTE sofia/internal/90964111 at 192.168.1.13:5060 incre_call_stat(203 621 201 256 25 2591585 1)
[1259754599]
2009-12-02 19:49:59.907094 [DEBUG] sofia.c:3847 sofia/internal/PhonerLite at 192.168.1.102 receive message [ANSWER]
2009-12-02 19:49:59.907094 [DEBUG] switch_core_session.c:630 Send signal sofia/internal/PhonerLite at 192.168.1.102 [BREAK]
2009-12-02 19:49:59.907911 [NOTICE] sofia.c:3847 Channel [sofia/internal/PhonerLite at 192.168.1.102] has been answered
2009-12-02 19:49:59.907911 [DEBUG] switch_ivr_originate.c:2196 Originate Resulted in Success: [sofia/internal/90964111 at 192.168.1.13:5060]
2009-12-02 19:49:59.907911 [DEBUG] switch_ivr_bridge.c:806 (sofia/internal/PhonerLite at 192.168.1.102) State Change CS_EXECUTE -> CS_HIBERNATE
2009-12-02 19:49:59.907911 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/PhonerLite at 192.168.1.102 [BREAK]
2009-12-02 19:49:59.907911 [DEBUG] switch_ivr_bridge.c:807 (sofia/internal/90964111 at 192.168.1.13:5060) State Change CS_CONSUME_MEDIA -> CS_HIBERNATE
2009-12-02 19:49:59.907911 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111 at 192.168.1.13:5060 [BREAK]
send 858 bytes to udp/[192.168.1.104]:5060 at 11:49:59.908359:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.104:5060;branch=z9hG4bK80f2b42464ddde1180d3001a805656a5;rport=5060
From: <sip:PhonerLite at 192.168.1.102>;tag=2563216860
To: <sip:90964111 at 192.168.1.102>;tag=QQX3yavvBvjrj
Call-ID: 80F2B424-64DD-DE11-80D2-001A805656A5 at 192.168.1.104
CSeq: 17 INVITE
Contact: <sip:90964111 at 192.168.1.102:5060;transport=udp>
User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY
Supported: timer, precondition, path, replaces
Allow-Events: talk, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 207
v=0
o=sdp_admin 59711527 21326134 IN IP4 192.168.1.13
s=A conversation
c=IN IP4 192.168.1.13
t=0 0
m=audio 10098 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
------------------------------------------------------------------------
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 get_next_route()
2009-12-02 19:49:59.907911 [DEBUG] sofia.c:3359 Channel sofia/internal/PhonerLite at 192.168.1.102 entering state [completed][200]
EXECUTE sofia/internal/PhonerLite at 192.168.1.102 info()
2009-12-02 19:49:59.907911 [INFO] mod_dptools.c:955 CHANNEL_DATA:
Channel-State: [CS_HIBERNATE]
Channel-State-Number: [8]
Channel-Name: [sofia/internal/PhonerLite at 192.168.1.102]
Unique-ID: [85042f75-de46-4fe5-b8d8-53e4ca0d26e1]
Call-Direction: [inbound]
Presence-Call-Direction: [inbound]
Answer-State: [answered]
Caller-Username: [PhonerLite]
Caller-Dialplan: [class4]
Caller-Caller-ID-Name: [PhonerLite]
Caller-Caller-ID-Number: [PhonerLite]
Caller-Network-Addr: [192.168.1.104]
Caller-Destination-Number: [90964111]
Caller-Unique-ID: [85042f75-de46-4fe5-b8d8-53e4ca0d26e1]
Caller-Source: [mod_sofia]
Caller-Context: [default]
Caller-Channel-Name: [sofia/internal/PhonerLite at 192.168.1.102]
Caller-Profile-Index: [1]
Caller-Profile-Created-Time: [1259754596930444]
Caller-Channel-Created-Time: [1259754596930444]
Caller-Channel-Answered-Time: [1259754599907094]
Caller-Channel-Progress-Time: [1259754597233383]
Caller-Channel-Progress-Media-Time: [0]
Caller-Channel-Hangup-Time: [0]
Caller-Channel-Transfer-Time: [0]
Caller-Screen-Bit: [true]
Caller-Privacy-Hide-Name: [false]
Caller-Privacy-Hide-Number: [false]
Other-Leg-Username: [PhonerLite]
Other-Leg-Caller-ID-Name: [PhonerLite]
Other-Leg-Caller-ID-Number: [PhonerLite]
Other-Leg-Network-Addr: [192.168.1.104]
Other-Leg-Destination-Number: [90964111 at 192.168.1.13:5060]
Other-Leg-Unique-ID: [e73fbd5b-a2ca-4685-bc6e-e1f848e8c7e5]
Other-Leg-Source: [mod_sofia]
Other-Leg-Channel-Name: [sofia/internal/90964111 at 192.168.1.13:5060]
Other-Leg-Screen-Bit: [true]
Other-Leg-Privacy-Hide-Name: [false]
Other-Leg-Privacy-Hide-Number: [false]
variable_sip_received_ip: [192.168.1.104]
variable_sip_received_port: [5060]
variable_sip_via_protocol: [udp]
variable_sip_from_user: [PhonerLite]
variable_sip_from_uri: [PhonerLite at 192.168.1.102]
variable_sip_from_host: [192.168.1.102]
variable_sip_from_user_stripped: [PhonerLite]
variable_sip_from_tag: [2563216860]
variable_sofia_profile_name: [internal]
variable_sip_req_user: [90964111]
variable_sip_req_uri: [90964111 at 192.168.1.102]
variable_sip_req_host: [192.168.1.102]
variable_sip_to_user: [90964111]
variable_sip_to_uri: [90964111 at 192.168.1.102]
variable_sip_to_host: [192.168.1.102]
variable_sip_contact_port: [5060]
variable_sip_contact_uri: [PhonerLite at 192.168.1.104:5060]
variable_sip_contact_host: [192.168.1.104]
variable_channel_name: [sofia/internal/PhonerLite at 192.168.1.102]
variable_sip_call_id: [80F2B424-64DD-DE11-80D2-001A805656A5 at 192.168.1.104]
variable_sip_user_agent: [SIPPER for PhonerLite]
variable_sip_via_host: [192.168.1.104]
variable_sip_via_port: [5060]
variable_sip_via_rport: [5060]
variable_max_forwards: [70]
variable_switch_r_sdp: [v=0
o=- 478760567 0 IN IP4 192.168.1.104
s=SIPPER for PhonerLite
c=IN IP4 192.168.1.104
t=0 0
m=audio 5062 RTP/AVP 8 0 2 3 97 110 111 9 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:3 GSM/8000
a=rtpmap:97 iLBC/8000
a=rtpmap:110 speex/8000
a=rtpmap:111 speex/16000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
]
variable_ep_codec_string: [PCMA at 8000h,PCMU at 8000h]
variable_effective_caller_id_number: [PhonerLite]
variable_effective_caller_id_name: [PhonerLite]
variable_<![CDATA[sip_h_Remote-Party-ID: [PhonerLite><sip:]
variable_routing_digit: [90964111]
variable_continue_on_fail: [true]
variable_hangup_after_bridge: [true]
variable_sip_contact_user: [PhonerLite]
variable_ingress_gateway: [192.168.1.104]
variable_ingress_alias: [7390]
variable_pure_dest_num: [90964111]
variable_ingress_res_id: [201]
variable_ingress_gw_id: [256]
variable_route_0: [9390;192.168.1.116;9390;classfun;116;2591585;90964111;90964111;100000;100000;25;202;257;10000;1000;t;t;201;256]
variable_route_1: [9390;192.168.1.116;9391;classfun;116;2591585;90964111;90964111;100000;100000;25;202;258;10000;1000;t;t;201;256]
variable_route_2: [9392;192.168.1.13;5060;classfun;116;2591585;90964111;90964111;10000;100;25;203;621;10;10;t;t;201;256]
variable_route_3: [9392;192.168.1.116;9392;classfun;116;2591585;90964111;90964111;10000;100;25;203;622;10;10;t;t;201;256]
variable_proto_specific_hangup_cause: [sip:403]
variable_sip_hangup_phrase: [Because]
variable_bypass_media: [true]
variable_class4_sip_from_user: [PhonerLite]
variable_class4_sip_received_ip: [192.168.1.104]
variable_class4_req_user: [90964111]
variable_class4_req_host: [192.168.1.102]
variable_class4_call_id: [80F2B424-64DD-DE11-80D2-001A805656A5 at 192.168.1.104]
variable_final_digits: [90964111]
variable_interm_digits: [90964111]
variable_egress_alias: [9392]
variable_prefix_id: [2591585]
variable_product_name: [classfun]
variable_prefix_name: [116]
variable_egress_gateway: [192.168.1.13]
variable_egress_port: [5060]
variable_attempt_type: [I]
variable_product_id: [25]
variable_egress_res_id: [203]
variable_egress_gw_id: [621]
variable_current_route_num: [2]
variable_total_route_cnt: [4]
variable_nolocal:execute_on_answer: [incre_call_stat 203 621 201 256 25 2591585 1]
variable_export_vars: [sip_contact_user,ingress_gateway,ingress_alias,pure_dest_num,ingress_res_id,ingress_gw_id,bypass_media,class4_sip_from_user,class4_sip_received_ip,class4_req_user,class4_req_host,class4_call_id,final_digits,interm_digits,egress_alias,prefix_id,product_id,product_name,prefix_name,egress_gateway,egress_port,attempt_type,egress_res_id,egress_gw_id,nolocal:execute_on_answer,bypass_media,class4_sip_from_user,class4_sip_received_ip,class4_req_user,class4_req_host,class4_call_id,final_digits,interm_digits,egress_alias,prefix_id,product_name,prefix_name,egress_gateway,egress_port,attempt_type,product_id,egress_res_id,egress_gw_id,current_route_num,total_route_cnt,nolocal:execute_on_answer,bypass_media,class4_sip_from_user,class4_sip_received_ip,class4_req_user,class4_req_host,class4_call_id,final_digits,interm_digits,egress_alias,prefix_id,product_name,prefix_name,egress_gateway,egress_port,attempt_type,product_id,egress_res_id,egress_gw_id,current_route_num,total_route_cnt,nolocal:execute_on_answer]
variable_limit_usage_3_25_0: [1]
variable_limit_rate_3_25_0: [1]
variable_limit_usage: [1]
variable_limit_usage_3_25_2591585: [1]
variable_limit_rate: [1]
variable_limit_rate_3_25_2591585: [1]
variable_success_bridge: [true]
variable_signal_bond: [e73fbd5b-a2ca-4685-bc6e-e1f848e8c7e5]
variable_switch_m_sdp: [v=0
o=sdp_admin 59711527 21326134 IN IP4 192.168.1.13
s=A conversation
c=IN IP4 192.168.1.13
t=0 0
m=audio 10098 RTP/AVP 8 0 9 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:9 G722/16000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
]
variable_endpoint_disposition: [ANSWER]
variable_originate_disposition: [SUCCESS]
variable_signal_bridge_to: [e73fbd5b-a2ca-4685-bc6e-e1f848e8c7e5]
variable_current_application: [info]
recv 414 bytes from udp/[192.168.1.104]:5060 at 11:49:59.909938:
------------------------------------------------------------------------
ACK sip:90964111 at 192.168.1.102:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.104:5060;branch=z9hG4bK00b67e2664ddde1180d3001a805656a5;rport
From: <sip:PhonerLite at 192.168.1.102>;tag=2563216860
To: <sip:90964111 at 192.168.1.102>;tag=QQX3yavvBvjrj
Call-ID: 80F2B424-64DD-DE11-80D2-001A805656A5 at 192.168.1.104
CSeq: 17 ACK
Contact: <sip:PhonerLite at 192.168.1.104:5060>
Max-Forwards: 70
Content-Length: 0
------------------------------------------------------------------------
2009-12-02 19:49:59.907911 [DEBUG] mod_dptools.c:752 sofia/internal/PhonerLite at 192.168.1.102 SET [egress_alias]=[9392]
2009-12-02 19:49:59.907911 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111 at 192.168.1.13:5060) Running State Change CS_HIBERNATE
2009-12-02 19:49:59.907911 [DEBUG] switch_core_state_machine.c:355 (sofia/internal/90964111 at 192.168.1.13:5060) State HIBERNATE
2009-12-02 19:49:59.907911 [DEBUG] mod_sofia.c:160 sofia/internal/90964111 at 192.168.1.13:5060 SOFIA HIBERNATE
2009-12-02 19:49:59.907911 [DEBUG] switch_core_state_machine.c:212 sofia/internal/90964111 at 192.168.1.13:5060 Standard HIBERNATE
2009-12-02 19:49:59.907911 [DEBUG] switch_core_state_machine.c:355 (sofia/internal/90964111 at 192.168.1.13:5060) State HIBERNATE going to sleep
2009-12-02 19:49:59.907911 [DEBUG] sofia.c:3359 Channel sofia/internal/PhonerLite at 192.168.1.102 entering state [ready][200]
2009-12-02 19:49:59.907911 [DEBUG] switch_ivr.c:1159 sofia/internal/PhonerLite at 192.168.1.102 receive message [MEDIA]
2009-12-02 19:49:59.917033 [DEBUG] switch_core_session.c:630 Send signal sofia/internal/PhonerLite at 192.168.1.102 [BREAK]
2009-12-02 19:49:59.917033 [CRIT] switch_core_io.c:115 sofia/internal/PhonerLite at 192.168.1.102 reading on a session with no media!
2009-12-02 19:49:59.918616 [DEBUG] switch_ivr.c:1174 sofia/internal/90964111 at 192.168.1.13:5060 receive message [MEDIA]
2009-12-02 19:49:59.918616 [DEBUG] switch_core_session.c:630 Send signal sofia/internal/90964111 at 192.168.1.13:5060 [BREAK]
send 955 bytes to udp/[192.168.1.13]:5060 at 11:49:59.922755:
------------------------------------------------------------------------
INVITE sip:192.168.1.13:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKXDXQ9j37rZ2eK
Max-Forwards: 69
From: "PhonerLite" <sip:PhonerLite at 192.168.1.102>;tag=tj9D4Ue72pNgN
To: <sip:90964111 at 192.168.1.13:5060>;tag=2900712955
Call-ID: a776ce22-59db-122d-ff84-0022190e9476
CSeq: 123758963 INVITE
Contact: <sip:PhonerLite at 192.168.1.102:5060>
User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY
Supported: timer, precondition, path, replaces
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 223
Remote-Party-ID: "PhonerLite" <sip:PhonerLite at 192.168.1.102>;party=calling;screen=yes;privacy=off
v=0
o=- 478760567 3248233194293522445 IN IP4 192.168.1.104
s=SIPPER for PhonerLite
c=IN IP4 192.168.1.102
t=0 0
m=audio 33352 RTP/AVP 101
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
------------------------------------------------------------------------
2009-12-02 19:49:59.922846 [DEBUG] sofia.c:3359 Channel sofia/internal/90964111 at 192.168.1.13:5060 entering state [calling][0]
send 955 bytes to udp/[192.168.1.13]:5060 at 11:50:00.423821:
------------------------------------------------------------------------
INVITE sip:192.168.1.13:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKXDXQ9j37rZ2eK
Max-Forwards: 69
From: "PhonerLite" <sip:PhonerLite at 192.168.1.102>;tag=tj9D4Ue72pNgN
To: <sip:90964111 at 192.168.1.13:5060>;tag=2900712955
Call-ID: a776ce22-59db-122d-ff84-0022190e9476
CSeq: 123758963 INVITE
Contact: <sip:PhonerLite at 192.168.1.102:5060>
User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY
Supported: timer, precondition, path, replaces
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 223
Remote-Party-ID: "PhonerLite" <sip:PhonerLite at 192.168.1.102>;party=calling;screen=yes;privacy=off
v=0
o=- 478760567 3248233194293522445 IN IP4 192.168.1.104
s=SIPPER for PhonerLite
c=IN IP4 192.168.1.102
t=0 0
m=audio 33352 RTP/AVP 101
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
------------------------------------------------------------------------
recv 337 bytes from udp/[192.168.1.13]:5060 at 11:50:00.444374:
------------------------------------------------------------------------
SIP/2.0 400 Bad Request
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKXDXQ9j37rZ2eK
From: PhonerLite <sip:PhonerLite at 192.168.1.102>;tag=tj9D4Ue72pNgN
To: <sip:90964111 at 192.168.1.13:5060>;tag=2900712955
Call-ID: a776ce22-59db-122d-ff84-0022190e9476
CSeq: 123758963 INVITE
Contact: <sip:192.168.1.13:5060>
Content-Length: 0
------------------------------------------------------------------------
send 330 bytes to udp/[192.168.1.13]:5060 at 11:50:00.444561:
------------------------------------------------------------------------
ACK sip:192.168.1.13:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKXDXQ9j37rZ2eK
Max-Forwards: 69
From: "PhonerLite" <sip:PhonerLite at 192.168.1.102>;tag=tj9D4Ue72pNgN
To: <sip:90964111 at 192.168.1.13:5060>;tag=2900712955
Call-ID: a776ce22-59db-122d-ff84-0022190e9476
CSeq: 123758963 ACK
Content-Length: 0
------------------------------------------------------------------------
2009-12-02 19:50:00.445918 [DEBUG] sofia.c:3359 Channel sofia/internal/90964111 at 192.168.1.13:5060 entering state [ready][400]
2009-12-02 19:50:00.445918 [NOTICE] sofia.c:3891 Hangup sofia/internal/90964111 at 192.168.1.13:5060 [CS_HIBERNATE] [INCOMPATIBLE_DESTINATION]
2009-12-02 19:50:00.445918 [DEBUG] switch_channel.c:1726 Send signal sofia/internal/90964111 at 192.168.1.13:5060 [KILL]
2009-12-02 19:50:00.445918 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111 at 192.168.1.13:5060 [BREAK]
2009-12-02 19:50:00.445918 [DEBUG] switch_core_state_machine.c:437 thread mismatch skipping state handler.
2009-12-02 19:50:00.445918 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111 at 192.168.1.13:5060) Running State Change CS_HANGUP
2009-12-02 19:50:00.445918 [DEBUG] switch_core_state_machine.c:464 (sofia/internal/90964111 at 192.168.1.13:5060) State HANGUP
2009-12-02 19:50:00.445918 [DEBUG] mod_sofia.c:338 Channel sofia/internal/90964111 at 192.168.1.13:5060 hanging up, cause: INCOMPATIBLE_DESTINATION
2009-12-02 19:50:00.445918 [DEBUG] mod_sofia.c:376 Sending BYE to sofia/internal/90964111 at 192.168.1.13:5060
2009-12-02 19:50:00.445918 [NOTICE] switch_ivr_bridge.c:727 Hangup sofia/internal/PhonerLite at 192.168.1.102 [CS_HIBERNATE] [INCOMPATIBLE_DESTINATION]
2009-12-02 19:50:00.445918 [DEBUG] switch_channel.c:1726 Send signal sofia/internal/PhonerLite at 192.168.1.102 [KILL]
2009-12-02 19:50:00.445918 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/PhonerLite at 192.168.1.102 [BREAK]
2009-12-02 19:50:00.445918 [DEBUG] switch_core_state_machine.c:437 thread mismatch skipping state handler.
2009-12-02 19:50:00.445918 [DEBUG] switch_core_state_machine.c:46 sofia/internal/90964111 at 192.168.1.13:5060 Standard HANGUP, cause: INCOMPATIBLE_DESTINATION
2009-12-02 19:50:00.445918 [DEBUG] switch_core_state_machine.c:464 (sofia/internal/90964111 at 192.168.1.13:5060) State HANGUP going to sleep
2009-12-02 19:50:00.445918 [DEBUG] switch_core_state_machine.c:494 Hangup Command decre_call_stat(203 621 201 256 25 2591585 1):
2009-12-02 19:50:00.445918 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/90964111 at 192.168.1.13:5060) State Change CS_HANGUP -> CS_REPORTING
2009-12-02 19:50:00.445918 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111 at 192.168.1.13:5060 [BREAK]
2009-12-02 19:50:00.445918 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/90964111 at 192.168.1.13:5060) Running State Change CS_REPORTING
2009-12-02 19:50:00.445918 [DEBUG] switch_core_state_machine.c:555 (sofia/internal/90964111 at 192.168.1.13:5060) State REPORTING
2009-12-02 19:50:00.445918 [DEBUG] switch_core_state_machine.c:53 sofia/internal/90964111 at 192.168.1.13:5060 Standard REPORTING, cause: INCOMPATIBLE_DESTINATION
2009-12-02 19:50:00.445918 [DEBUG] switch_core_state_machine.c:555 (sofia/internal/90964111 at 192.168.1.13:5060) State REPORTING going to sleep
2009-12-02 19:50:00.445918 [DEBUG] switch_core_state_machine.c:319 (sofia/internal/90964111 at 192.168.1.13:5060) State Change CS_REPORTING -> CS_DESTROY
2009-12-02 19:50:00.445918 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/90964111 at 192.168.1.13:5060 [BREAK]
2009-12-02 19:50:00.445918 [DEBUG] switch_core_session.c:1069 Session 13 (sofia/internal/90964111 at 192.168.1.13:5060) Locked, Waiting on external entities
2009-12-02 19:50:00.447416 [ERR] switch_core_io.c:120 sofia/internal/90964111 at 192.168.1.13:5060 has no read codec.
2009-12-02 19:50:00.447416 [DEBUG] switch_ivr_bridge.c:1210 originator uuid 85042f75-de46-4fe5-b8d8-53e4ca0d26e1 is not present
2009-12-02 19:50:00.447416 [NOTICE] switch_core_session.c:1087 Session 13 (sofia/internal/90964111 at 192.168.1.13:5060) Ended
2009-12-02 19:50:00.447416 [NOTICE] switch_core_session.c:1089 Close Channel sofia/internal/90964111 at 192.168.1.13:5060 [CS_DESTROY]
2009-12-02 19:50:00.447416 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/90964111 at 192.168.1.13:5060) Running State Change CS_DESTROY
2009-12-02 19:50:00.447416 [DEBUG] switch_core_state_machine.c:412 (sofia/internal/90964111 at 192.168.1.13:5060) State DESTROY
2009-12-02 19:50:00.447416 [DEBUG] mod_sofia.c:255 sofia/internal/90964111 at 192.168.1.13:5060 SOFIA DESTROY
2009-12-02 19:50:00.447416 [DEBUG] switch_core_state_machine.c:60 sofia/internal/90964111 at 192.168.1.13:5060 Standard DESTROY
2009-12-02 19:50:00.447416 [DEBUG] switch_core_state_machine.c:412 (sofia/internal/90964111 at 192.168.1.13:5060) State DESTROY going to sleep
2009-12-02 19:50:00.449755 [DEBUG] switch_ivr_originate.c:2273 Originate Resulted in Error Cause: 88 [INCOMPATIBLE_DESTINATION]
2009-12-02 19:50:00.449755 [INFO] mod_dptools.c:2106 Originate Failed. Cause: INCOMPATIBLE_DESTINATION
2009-12-02 19:50:00.449755 [DEBUG] mod_dptools.c:2128 Continue on fail [true]: Cause: INCOMPATIBLE_DESTINATION
2009-12-02 19:50:00.449755 [DEBUG] switch_core_session.c:1371 Channel is hungup, aborting execution of application: get_next_route
2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:340 (sofia/internal/PhonerLite at 192.168.1.102) State EXECUTE going to sleep
2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/PhonerLite at 192.168.1.102) Running State Change CS_HANGUP
2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:464 (sofia/internal/PhonerLite at 192.168.1.102) State HANGUP
2009-12-02 19:50:00.449755 [DEBUG] mod_sofia.c:306 sofia/internal/PhonerLite at 192.168.1.102 Overriding SIP cause 488 with 403 from the other leg
2009-12-02 19:50:00.449755 [DEBUG] mod_sofia.c:338 Channel sofia/internal/PhonerLite at 192.168.1.102 hanging up, cause: INCOMPATIBLE_DESTINATION
2009-12-02 19:50:00.449755 [DEBUG] mod_sofia.c:376 Sending BYE to sofia/internal/PhonerLite at 192.168.1.102
2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:46 sofia/internal/PhonerLite at 192.168.1.102 Standard HANGUP, cause: INCOMPATIBLE_DESTINATION
2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:464 (sofia/internal/PhonerLite at 192.168.1.102) State HANGUP going to sleep
2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:325 (sofia/internal/PhonerLite at 192.168.1.102) State Change CS_HANGUP -> CS_REPORTING
2009-12-02 19:50:00.449755 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/PhonerLite at 192.168.1.102 [BREAK]
2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:306 (sofia/internal/PhonerLite at 192.168.1.102) Running State Change CS_REPORTING
2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:555 (sofia/internal/PhonerLite at 192.168.1.102) State REPORTING
2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:53 sofia/internal/PhonerLite at 192.168.1.102 Standard REPORTING, cause: INCOMPATIBLE_DESTINATION
2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:555 (sofia/internal/PhonerLite at 192.168.1.102) State REPORTING going to sleep
2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:319 (sofia/internal/PhonerLite at 192.168.1.102) State Change CS_REPORTING -> CS_DESTROY
2009-12-02 19:50:00.449755 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/PhonerLite at 192.168.1.102 [BREAK]
2009-12-02 19:50:00.449755 [DEBUG] switch_core_session.c:1069 Session 10 (sofia/internal/PhonerLite at 192.168.1.102) Locked, Waiting on external entities
2009-12-02 19:50:00.449755 [NOTICE] switch_core_session.c:1087 Session 10 (sofia/internal/PhonerLite at 192.168.1.102) Ended
2009-12-02 19:50:00.449755 [NOTICE] switch_core_session.c:1089 Close Channel sofia/internal/PhonerLite at 192.168.1.102 [CS_DESTROY]
2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:401 (sofia/internal/PhonerLite at 192.168.1.102) Running State Change CS_DESTROY
2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:412 (sofia/internal/PhonerLite at 192.168.1.102) State DESTROY
2009-12-02 19:50:00.449755 [DEBUG] mod_sofia.c:255 sofia/internal/PhonerLite at 192.168.1.102 SOFIA DESTROY
2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:60 sofia/internal/PhonerLite at 192.168.1.102 Standard DESTROY
2009-12-02 19:50:00.449755 [DEBUG] switch_core_state_machine.c:412 (sofia/internal/PhonerLite at 192.168.1.102) State DESTROY going to sleep
send 620 bytes to udp/[192.168.1.13]:5060 at 11:50:00.452239:
------------------------------------------------------------------------
BYE sip:192.168.1.13:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKyppgBemBp8r1e
Max-Forwards: 70
From: "PhonerLite" <sip:PhonerLite at 192.168.1.102>;tag=tj9D4Ue72pNgN
To: <sip:90964111 at 192.168.1.13:5060>;tag=2900712955
Call-ID: a776ce22-59db-122d-ff84-0022190e9476
CSeq: 123758964 BYE
Contact: <sip:PhonerLite at 192.168.1.102:5060>
User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY
Supported: timer, precondition, path, replaces
Reason: Q.850;cause=88;text="INCOMPATIBLE_DESTINATION"
Content-Length: 0
------------------------------------------------------------------------
send 641 bytes to udp/[192.168.1.104]:5060 at 11:50:00.452431:
------------------------------------------------------------------------
BYE sip:PhonerLite at 192.168.1.104:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKZZF9c94eKHFma
Max-Forwards: 70
From: <sip:90964111 at 192.168.1.102>;tag=QQX3yavvBvjrj
To: <sip:PhonerLite at 192.168.1.102>;tag=2563216860
Call-ID: 80F2B424-64DD-DE11-80D2-001A805656A5 at 192.168.1.104
CSeq: 123758964 BYE
Contact: <sip:90964111 at 192.168.1.102:5060;transport=udp>
User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15095
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY
Supported: timer, precondition, path, replaces
Reason: Q.850;cause=88;text="INCOMPATIBLE_DESTINATION"
Content-Length: 0
------------------------------------------------------------------------
recv 376 bytes from udp/[192.168.1.104]:5060 at 11:50:00.453188:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.102;rport=5060;branch=z9hG4bKZZF9c94eKHFma
From: <sip:90964111 at 192.168.1.102>;tag=QQX3yavvBvjrj
To: <sip:PhonerLite at 192.168.1.102>;tag=2563216860
Call-ID: 80F2B424-64DD-DE11-80D2-001A805656A5 at 192.168.1.104
CSeq: 123758964 BYE
Contact: <sip:PhonerLite at 192.168.1.104:5060>
User-Agent: SIPPER for PhonerLite
Content-Length: 0
------------------------------------------------------------------------
recv 337 bytes from udp/[192.168.1.13]:5060 at 11:50:00.530378:
------------------------------------------------------------------------
SIP/2.0 400 Bad Request
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKXDXQ9j37rZ2eK
From: PhonerLite <sip:PhonerLite at 192.168.1.102>;tag=tj9D4Ue72pNgN
To: <sip:90964111 at 192.168.1.13:5060>;tag=2900712955
Call-ID: a776ce22-59db-122d-ff84-0022190e9476
CSeq: 123758963 INVITE
Contact: <sip:192.168.1.13:5060>
Content-Length: 0
------------------------------------------------------------------------
send 330 bytes to udp/[192.168.1.13]:5060 at 11:50:00.530547:
------------------------------------------------------------------------
ACK sip:192.168.1.13:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKXDXQ9j37rZ2eK
Max-Forwards: 69
From: "PhonerLite" <sip:PhonerLite at 192.168.1.102>;tag=tj9D4Ue72pNgN
To: <sip:90964111 at 192.168.1.13:5060>;tag=2900712955
Call-ID: a776ce22-59db-122d-ff84-0022190e9476
CSeq: 123758963 ACK
Content-Length: 0
------------------------------------------------------------------------
recv 325 bytes from udp/[192.168.1.13]:5060 at 11:50:00.821952:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.102;rport;branch=z9hG4bKyppgBemBp8r1e
From: PhonerLite <sip:PhonerLite at 192.168.1.102>;tag=tj9D4Ue72pNgN
To: <sip:90964111 at 192.168.1.13:5060>;tag=2900712955
Call-ID: a776ce22-59db-122d-ff84-0022190e9476
CSeq: 123758964 BYE
Contact: <sip:192.168.1.13:5060>
Content-Length: 0
------------------------------------------------------------------------
More information about the FreeSWITCH-users
mailing list