[Freeswitch-dev] [Fwd: Re: second leg of call isnt hanged up by Freeswitch]

8 Apr 2008

      This is my e-mail without too big dump :-)

Anthony Minessale napsal(a):
...
yes do you have both a pcap and a console trace of the call.
start freeswitch with TPORT_LOG=1
TPORT_LOG=1 /usr/local/freeswitch/bin/freeswitch
set debug level
...
console loglevel debug
capture all the text on the console
Now I try current trunk and everything works as in previous version -
bad :-)

Scenario is very similar to the one a have described before. It is
appended at the end of e-mail. Pcap dump is also included.

And than I have two questions:
1. How to capture log from console? All I can copy is included, but it
is only very small fragment.
2. I have never used IRC, could you recommend me some proven client? Now
I'm on windows xp machine.

Best regards,

kokoska.rokoska

---------------------------------------------------------------
SCENARIO DESCRIPTION
---------------------------------------------------------------

User status:

Users 21 and 22 are registered, user 22 not.

Call flow:

User 21 calls number 23, recieves back 181 and phone 22 starts ringing
(Diversion header is properly appended).
Than user 22 answers (sends 200 OK and recives ACK) and after short
conversation hangs up.
---------------------------------------------------------------

---------------------------------------------------------------
FS CONSOLE DUMP
---------------------------------------------------------------
     Content-Type: application/sdp

     v=0
     o=- 18939677 18939677 IN IP4 192.168.1.151
     s=-
     c=IN IP4 192.168.1.151
     t=0 0
     m=audio 16424 RTP/AVP 8 100 101
     a=rtpmap:8 PCMA/8000
     a=rtpmap:100 NSE/8000
     a=fmtp:100 192-193
     a=rtpmap:101 telephone-event/8000
     a=fmtp:101 0-15
     a=ptime:20
     a=sendrecv

------------------------------------------------------------------------
send 389 bytes to udp/[192.168.1.151]:5060 at 18:19:53.331062:

------------------------------------------------------------------------
     ACK sip:0002*21@192.168.1.151:5060 SIP/2.0
     Via: SIP/2.0/UDP 192.168.1.128:5065;rport;branch=z9hG4bKZZQcSggZXDemS
     Max-Forwards: 70
     From: "Test User 3" <sip:23@192.168.1.128>;tag=ce79mFZ7jK58a
     To: <sip:0002*21@192.168.1.151:5060>;tag=b3f2fc6784f98861i0
     Call-ID: 386fd56b-803b-122b-ceb3-000854d1424f
     CSeq: 97721059 ACK
     Contact: <sip:mod_sofia@192.168.1.128:5065>
     Content-Length: 0

------------------------------------------------------------------------
2008-04-08 20:19:53 [NOTICE] sofia.c:1945 sofia_handle_sip_i_state()
Channel [sofia/default/0002*21] has been answered
2008-04-08 20:19:53 [NOTICE] switch_ivr_originate.c:1171
switch_ivr_originate() Channel
[sofia/default/0002*23@192.168.1.128:5065] has been answered
send 1053 bytes to udp/[192.168.1.101]:5060 at 18:19:53.357290:

------------------------------------------------------------------------
     SIP/2.0 200 OK
     Via: SIP/2.0/UDP
192.168.1.101;branch=z9hG4bKc0a801650000002147fbb74a000074b400000008;rport=5060
     From: "unknown" <sip:0002*23@192.168.1.128:5065>;tag=4ccb2895c44
     To: <sip:23@192.168.1.128:5065>;tag=B5DHKme4NaFpF
     Call-ID: 10846033E50C413DB8B409E8542BEFEC0xc0a80165
     CSeq: 2 INVITE
     Contact: <sip:mod_sofia@192.168.1.128:5065;transport=udp>
     User-Agent: FreeSWITCH-mod_sofia/1.0.pre4-8057
     Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE,
NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH
     Supported: 100rel, precondition, timer
     Allow-Events: talk, presence, dialog, call-info, sla,
include-session-description, presence.winfo, message-summary
     Min-SE: 120
     Content-Type: application/sdp
     Content-Disposition: session
     Content-Length: 276

     v=0
     o=FreeSWITCH 1578779309514953988 5635600553835196538 IN IP4
192.168.1.128
     s=FreeSWITCH
     c=IN IP4 192.168.1.128
     t=0 0
     a=sendrecv
     m=audio 22042 RTP/AVP 8 101 13
     a=rtpmap:8 PCMA/8000
     a=rtpmap:101 telephone-event/8000
     a=fmtp:101 0-16
     a=rtpmap:13 CN/8000
     a=ptime:20

------------------------------------------------------------------------
recv 701 bytes from udp/[192.168.1.101]:5060 at 18:19:53.366118:

------------------------------------------------------------------------
     ACK sip:mod_sofia@192.168.1.128:5065;transport=udp SIP/2.0
     Via: SIP/2.0/UDP
192.168.1.101;branch=z9hG4bKc0a801650000002147fbb74c00002a6e00000010;rport
     From: "unknown" <sip:0002*23@192.168.1.128:5065>;tag=4ccb2895c44
     To: <sip:23@192.168.1.128:5065>;tag=B5DHKme4NaFpF
     Contact: <sip:0002*23@192.168.1.101>
     Call-ID: 10846033E50C413DB8B409E8542BEFEC0xc0a80165
     CSeq: 2 ACK
     Max-Forwards: 70
     User-Agent: SJphone/1.65.377a (SJ Labs)
     Content-Length: 0
     Proxy-Authorization: Digest
username="0002*23",realm="192.168.1.128",nonce="a9ceee76-0dd0-411e-8a4b-129b8c5605ef",uri="sip:23@192.168.1.128:5065",response="8469ad6ffe3aaeba4f941a791faeeb87",algorithm=MD5,cnonce="524b2895d0f",qop=auth,nc=00000001

------------------------------------------------------------------------
recv 358 bytes from udp/[192.168.1.151]:5060 at 18:20:07.809148:

------------------------------------------------------------------------
     NOTIFY sip:192.168.1.128:5065 SIP/2.0
     Via: SIP/2.0/UDP 192.168.1.151:5060;branch=z9hG4bK-66076daf
     From: 0002*21 <sip:0002*21@192.168.1.128:5065>;tag=b8d3d5adb9311150o0
     To: <sip:192.168.1.128:5065>
     Call-ID: ea137ee1-a72859ac@192.168.1.151
     CSeq: 12618 NOTIFY
     Max-Forwards: 70
     Event: keep-alive
     User-Agent: Linksys/PAP2T-5.1.3(LS)
     Content-Length: 0

------------------------------------------------------------------------
send 635 bytes to udp/[192.168.1.151]:5060 at 18:20:07.812145:

------------------------------------------------------------------------
     SIP/2.0 200 OK
     Via: SIP/2.0/UDP 192.168.1.151:5060;branch=z9hG4bK-66076daf
     From: 0002*21 <sip:0002*21@192.168.1.128:5065>;tag=b8d3d5adb9311150o0
     To: <sip:192.168.1.128:5065>;tag=e0SUr50eD5Hej
     Call-ID: ea137ee1-a72859ac@192.168.1.151
     CSeq: 12618 NOTIFY
     Contact: <sip:192.168.1.128:5065>
     User-Agent: FreeSWITCH-mod_sofia/1.0.pre4-8057
     Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE,
NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH
     Supported: 100rel, precondition, timer
     Allow-Events: talk, presence, dialog, call-info, sla,
include-session-description, presence.winfo, message-summary
     Content-Length: 0

------------------------------------------------------------------------
recv 368 bytes from udp/[192.168.1.151]:5060 at 18:20:08.900049:

------------------------------------------------------------------------
     BYE sip:mod_sofia@192.168.1.128:5065 SIP/2.0
     Via: SIP/2.0/UDP 192.168.1.151:5060;branch=z9hG4bK-204a8e99
     From: <sip:0002*21@192.168.1.151:5060>;tag=b3f2fc6784f98861i0
     To: "Test User 3" <sip:23@192.168.1.128>;tag=ce79mFZ7jK58a
     Call-ID: 386fd56b-803b-122b-ceb3-000854d1424f
     CSeq: 101 BYE
     Max-Forwards: 70
     User-Agent: Linksys/PAP2T-5.1.3(LS)
     Content-Length: 0

------------------------------------------------------------------------
send 488 bytes to udp/[192.168.1.151]:5060 at 18:20:08.902211:

------------------------------------------------------------------------
     SIP/2.0 200 OK
     Via: SIP/2.0/UDP 192.168.1.151:5060;branch=z9hG4bK-204a8e99
     From: <sip:0002*21@192.168.1.151:5060>;tag=b3f2fc6784f98861i0
     To: "Test User 3" <sip:23@192.168.1.128>;tag=ce79mFZ7jK58a
     Call-ID: 386fd56b-803b-122b-ceb3-000854d1424f
     CSeq: 101 BYE
     User-Agent: FreeSWITCH-mod_sofia/1.0.pre4-8057
     Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE,
NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH
     Supported: 100rel, precondition, timer
     Content-Length: 0

------------------------------------------------------------------------
2008-04-08 20:20:08 [NOTICE] sofia.c:1992 sofia_handle_sip_i_state()
Hangup sofia/default/0002*21 [CS_LOOPBACK] [NORMAL_CLEARING]
2008-04-08 20:20:08 [NOTICE] switch_ivr_bridge.c:802
switch_ivr_multi_threaded_bridge() Hangup
sofia/default/0002*23@192.168.1.128:5065 [CS_EXECUTE] [NORMAL_CLEARING]
2008-04-08 20:20:08 [NOTICE] switch_core_session.c:748
switch_core_session_thread() Session 2 (sofia/default/0002*21) Ended
2008-04-08 20:20:08 [NOTICE] switch_core_session.c:750
switch_core_session_thread() Close Channel sofia/default/0002*21 [CS_HANGUP]
2008-04-08 20:20:08 [NOTICE] switch_core_session.c:748
switch_core_session_thread() Session 1
(sofia/default/0002*23@192.168.1.128:5065) Ended
2008-04-08 20:20:08 [NOTICE] switch_core_session.c:750
switch_core_session_thread() Close Channel
sofia/default/0002*23@192.168.1.128:5065 [CS_HANGUP]
recv 358 bytes from udp/[192.168.1.151]:5060 at 18:20:22.818241:

------------------------------------------------------------------------
     NOTIFY sip:192.168.1.128:5065 SIP/2.0
     Via: SIP/2.0/UDP 192.168.1.151:5060;branch=z9hG4bK-edd97c73
     From: 0002*21 <sip:0002*21@192.168.1.128:5065>;tag=b8d3d5adb9311150o0
     To: <sip:192.168.1.128:5065>
     Call-ID: ea137ee1-a72859ac@192.168.1.151
     CSeq: 12619 NOTIFY
     Max-Forwards: 70
     Event: keep-alive
     User-Agent: Linksys/PAP2T-5.1.3(LS)
     Content-Length: 0

------------------------------------------------------------------------
send 635 bytes to udp/[192.168.1.151]:5060 at 18:20:22.820767:

------------------------------------------------------------------------
     SIP/2.0 200 OK
     Via: SIP/2.0/UDP 192.168.1.151:5060;branch=z9hG4bK-edd97c73
     From: 0002*21 <sip:0002*21@192.168.1.128:5065>;tag=b8d3d5adb9311150o0
     To: <sip:192.168.1.128:5065>;tag=F9jmt0Hjae80D
     Call-ID: ea137ee1-a72859ac@192.168.1.151
     CSeq: 12619 NOTIFY
     Contact: <sip:192.168.1.128:5065>
     User-Agent: FreeSWITCH-mod_sofia/1.0.pre4-8057
     Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE,
NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH
     Supported: 100rel, precondition, timer
     Allow-Events: talk, presence, dialog, call-info, sla,
include-session-description, presence.winfo, message-summary
     Content-Length: 0

------------------------------------------------------------------------
recv 492 bytes from udp/[192.168.1.101]:5060 at 18:20:26.640239:

------------------------------------------------------------------------
     BYE sip:mod_sofia@192.168.1.128:5065;transport=udp SIP/2.0
     Via: SIP/2.0/UDP
192.168.1.101;branch=z9hG4bKc0a801650000002347fbb76d00001c7800000011;rport
     From: "unknown" <sip:0002*23@192.168.1.128:5065>;tag=4ccb2895c44
     To: <sip:23@192.168.1.128:5065>;tag=B5DHKme4NaFpF
     Contact: <sip:0002*23@192.168.1.101>
     Call-ID: 10846033E50C413DB8B409E8542BEFEC0xc0a80165
     CSeq: 3 BYE
     Max-Forwards: 70
     User-Agent: SJphone/1.65.377a (SJ Labs)
     Content-Length: 0
     Supported: replaces,norefersub,timer

------------------------------------------------------------------------
send 523 bytes to udp/[192.168.1.101]:5060 at 18:20:26.642513:

------------------------------------------------------------------------
     SIP/2.0 200 OK
     Via: SIP/2.0/UDP
192.168.1.101;branch=z9hG4bKc0a801650000002347fbb76d00001c7800000011;rport=5060
     From: "unknown" <sip:0002*23@192.168.1.128:5065>;tag=4ccb2895c44
     To: <sip:23@192.168.1.128:5065>;tag=B5DHKme4NaFpF
     Call-ID: 10846033E50C413DB8B409E8542BEFEC0xc0a80165
     CSeq: 3 BYE
     User-Agent: FreeSWITCH-mod_sofia/1.0.pre4-8057
     Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE,
NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH
     Supported: 100rel, precondition, timer
     Content-Length: 0

------------------------------------------------------------------------

---------------------------------------------------------------

[Freeswitch-dev] [Fwd: Re: second leg of call isnt hanged up by Freeswitch]

kokoska rokoska