Re: [OpenSBC] hold call in Full mode
Brought to you by:
joegenbaclor
Menu
▾
▴
Re: [OpenSBC] hold call in Full mode
|
From: OpenSBC F. <ope...@op...> - 2009-02-05 04:05:44
|
Thank you for replying me Below is the log about the case. what i have done is 1. [kw...@ta...] makes a call to [kwa...@ta...] 2. then, [kwa...@ta...] accept the call. then they talk each other 3. [kw...@ta...] holds the call, however, [kwa...@ta...] does not know the call is held by remote party. 4. [kw...@ta...] unholds the call 5 [kw...@ta...] hangs up the call hope that you can help me. thank you in advance 2009/02/05 11:48:30.453 INF: [CID=0x0a20] <<< INVITE sip:kwa...@ta... SIP/2.0 SRC: 172.17.7.90:49152:UDP enc=0 bytes=883 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] INVITE sip:kwa...@ta... SIP/2.0 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] From: "unknown" <sip:kw...@ta...>;tag=6842946254 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] To: <sip:kwa...@ta...> 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] Via: SIP/2.0/UDP 172.17.7.90:49152;branch=z9hG4bKac11075a00000021498a62280000215b00000005 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] CSeq: 1 INVITE 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] Call-ID: 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] Contact: <sip:kwan@172.17.7.90:49152> 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] User-Agent: SJphone/1.65.377a (SJ Labs) 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] Max-Forwards: 70 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] Supported: replaces, norefersub, timer 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] Content-Type: application/sdp 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] Content-Length: 362 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] v=0 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] o=- 3442794664 3442794664 IN IP4 172.17.7.90 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] s=SJphone 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] c=IN IP4 172.17.7.90 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] t=0 0 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] m=audio 49154 RTP/AVP 3 97 98 8 0 101 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] c=IN IP4 172.17.7.90 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] a=rtpmap:3 GSM/8000 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] a=rtpmap:97 iLBC/8000 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] a=rtpmap:98 iLBC/8000 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] a=fmtp:98 mode=20 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] a=rtpmap:8 PCMA/8000 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] a=rtpmap:0 PCMU/8000 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] a=rtpmap:101 telephone-event/8000 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] a=fmtp:101 0-16 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] a=setup:active 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] a=sendrecv 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] Finding transaction for INVITE sip:kwa...@ta... SIP/2.0 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] Setting Transaction ID to 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a|z9hG4bKac11075a00000021498a62280000215b00000005|INVITE 2009/02/05 11:48:30.453 DTL: [CID=0x0a20] IST(264062016) *** CREATED *** - IST|5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a|z9hG4bKac11075a00000021498a62280000215b00000005|INVITE 2009/02/05 11:48:30.453 DTL: [CID=0x0a20] IST(264062016) Event(SIPMessage) - INVITE sip:kwa...@ta... SIP/2.0 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] TRANSACTION: (IST) INVITE sip:kwa...@ta... SIP/2.0 State: 0 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] Event: SIPStack::Enqueue(INVITE sip:kwa...@ta... SIP/2.0) 2009/02/05 11:48:30.453 DTL: [CID=0x0a20] IST(264062016) StateIdle->StateProceeding 2009/02/05 11:48:30.453 DBG: [CID=0x0a20] Event: B2BUserAgent::ProcessEvent( INVITE sip:kwa...@ta... SIP/2.0 ) 2009/02/05 11:48:30.468 DTL: [CID=0x0a20] Event: ---> Inbound - INVITE sip:kwa...@ta... SIP/2.0 2009/02/05 11:48:30.468 DBG: [CID=0x0a20] Session CREATED 2009/02/05 11:48:30.468 INF: [CID=0x0a20] *** CREATED (UAS) CALL *** 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a 2009/02/05 11:48:30.468 DBG: [CID=0x0e7d] Multidirectional Session CREATED 2009/02/05 11:48:30.468 DBG: [CID=0x0e7d] B2BUAConnection Created 0x00D5A978 2009/02/05 11:48:30.468 DBG: [CID=0x0e7d] *** COUNTERS *** (Constructor)ICT=0 NICT=0 IST=1 NIST=4 TIMERS=8 CALL=1 CONN=1 REG=0 RTP=0 QUEUE=0 CACHE=1 GC=5 TOTALCONN=1 TOTALREG=0 TOTALSZR=0 AVGDUR=0 HIGHDUR=0 IDLETIME=0 2009/02/05 11:48:30.484 INF: [CID=0x0a20] *** SINGLE REGISTRATION FOUND *** -->> Callee: sip:kw...@ta... AOR: sip:kwa...@ta... BINDING:sip:kwan-lite2@172.17.7.90:24742;rinstance=2818a5703040a9c8 2009/02/05 11:48:30.484 DBG: [CID=0x0a20] *** MESSAGE ARRIVAL *** for SIP Session 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a 2009/02/05 11:48:30.484 DBG: [CID=0x0a20] Session CREATED 2009/02/05 11:48:30.484 INF: [CID=0x0a20] *** CREATED (UAC) CALL *** 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a-0x0001 2009/02/05 11:48:30.484 DBG: [CID=0x06cb] CREATED via=172.17.7.110:5060 for target=172.17.7.90 protocol=UDP 2009/02/05 11:48:30.484 INF: [CID=0x0a20] *** RTP Session CREATED *** l-addr=172.17.7.110 r-addr=172.17.7.90/172.17.7.90 r-port=49154 2009/02/05 11:48:30.484 DBG: [CID=0x06cb] CREATED via=172.17.7.110:5060 for target=172.17.7.90 protocol=UDP 2009/02/05 11:48:30.484 DBG: [CID=0x0a20] RTP: Second Leg offer being set from First Leg SDP 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] RTP: (Audio) First Leg Offer being translated to 172.17.7.110:30002 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] Finding transaction for INVITE sip:kwan-lite2@172.17.7.90:24742;rinstance=2818a5703040a9c8 SIP/2.0 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] Setting Transaction ID to 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a-0x0001|z9hG4bKf5e9cbb7a6fd18109efdd9a22df79cf9|INVITE 2009/02/05 11:48:30.500 DTL: [CID=0x0a20] ICT(264062017) *** CREATED *** - ICT|5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a-0x0001|z9hG4bKf5e9cbb7a6fd18109efdd9a22df79cf9|INVITE 2009/02/05 11:48:30.500 DTL: [CID=0x0a20] ICT(264062017) Event(SIPMessage) - INVITE sip:kwan-lite2@172.17.7.90:24742;rinstance=2818a5703040a9c8 SIP/2.0 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] TRANSACTION: (ICT) INVITE sip:kwan-lite2@172.17.7.90:24742;rinstance=2818a5703040a9c8 SIP/2.0 State: 0 2009/02/05 11:48:30.500 INF: [CID=0x0a20] >>> INVITE sip:kwan-lite2@172.17.7.90:24742;rinstance=2818a5703040a9c8 SIP/2.0 DST: 172.17.7.90:24742:UDP SRC: 172.17.7.110:5060 enc=0 bytes=929 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] INVITE sip:kwan-lite2@172.17.7.90:24742;rinstance=2818a5703040a9c8 SIP/2.0 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] From: "unknown" <sip:kw...@ta...>;tag=6842946254 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] To: <sip:kwa...@ta...> 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] Via: SIP/2.0/UDP 172.17.7.110:5060;iid=6051;branch=z9hG4bKf5e9cbb7a6fd18109efdd9a22df79cf9;uas-addr=172.17.7.90;rport 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] CSeq: 1 INVITE 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] Call-ID: 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a-0x0001 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] Contact: <sip:kwan@172.17.7.110:5060> 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] User-Agent: OpenSBC v1.1.5-42 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] Max-Forwards: 69 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] Supported: replaces, norefersub, timer 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] Content-Type: application/sdp 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] Content-Length: 364 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] v=0 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] o=- 3442794664 3442794664 IN IP4 172.17.7.90 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] s=SJphone 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] c=IN IP4 172.17.7.110 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] t=0 0 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] m=audio 30002 RTP/AVP 3 97 98 8 0 101 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] c=IN IP4 172.17.7.110 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] a=rtpmap:3 GSM/8000 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] a=rtpmap:97 iLBC/8000 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] a=rtpmap:98 iLBC/8000 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] a=fmtp:98 mode=20 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] a=rtpmap:8 PCMA/8000 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] a=rtpmap:0 PCMU/8000 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] a=rtpmap:101 telephone-event/8000 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] a=fmtp:101 0-16 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] a=setup:active 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] a=sendrecv 2009/02/05 11:48:30.500 DBG: [CID=0x0a20] 2009/02/05 11:48:30.515 DBG: [CID=0x0a20] Finding transaction for INVITE sip:kwan-lite2@172.17.7.90:24742;rinstance=2818a5703040a9c8 SIP/2.0 2009/02/05 11:48:30.515 DBG: [CID=0x0a20] Setting Transaction ID to 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a-0x0001|z9hG4bKf5e9cbb7a6fd18109efdd9a22df79cf9|INVITE 2009/02/05 11:48:30.515 DTL: [CID=0x0a20] Found ICT|5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a-0x0001|z9hG4bKf5e9cbb7a6fd18109efdd9a22df79cf9|INVITE for INVITE sip:kwan-lite2@172.17.7.90:24742;rinstance=2818a5703040a9c8 SIP/2.0 2009/02/05 11:48:30.515 DTL: [CID=0x0a20] ICT(264062017) Event(SIPMessage) - INVITE sip:kwan-lite2@172.17.7.90:24742;rinstance=2818a5703040a9c8 SIP/2.0 2009/02/05 11:48:30.515 DBG: [CID=0x0a20] TRANSACTION: (ICT) INVITE sip:kwan-lite2@172.17.7.90:24742;rinstance=2818a5703040a9c8 SIP/2.0 State: 0 2009/02/05 11:48:30.515 DTL: [CID=0x0a20] ICT(264062017) StateIdle->StateCalling(INVITE sip:kwan-lite2@172.17.7.90:24742;rinstance=2818a5703040a9c8 SIP/2.0) 2009/02/05 11:48:30.515 DBG: [CID=0x0a20] ICT(264062017) Timer A( 500 ms ) STARTED 2009/02/05 11:48:30.515 DBG: [CID=0x0a20] ICT(264062017) Timer B( 32000 ms ) STARTED 2009/02/05 11:48:30.515 DBG: [CID=0x0a20] Event: Setting UA Core [Call] to handle event INVITE 2009/02/05 11:48:30.515 DTL: [CID=0x0a20] Event: ---> TransportWrite - INVITE 2009/02/05 11:48:30.937 INF: [CID=0x0a20] <<< SIP/2.0 100 Trying SRC: 172.17.7.90:24742:UDP enc=0 bytes=352 2009/02/05 11:48:30.937 DBG: [CID=0x0a20] 2009/02/05 11:48:30.937 DBG: [CID=0x0a20] SIP/2.0 100 Trying 2009/02/05 11:48:30.937 DBG: [CID=0x0a20] From: "unknown" <sip:kw...@ta...>;tag=6842946254 2009/02/05 11:48:30.937 DBG: [CID=0x0a20] To: <sip:kwa...@ta...> 2009/02/05 11:48:30.937 DBG: [CID=0x0a20] Via: SIP/2.0/UDP 172.17.7.110:5060;iid=6051;branch=z9hG4bKf5e9cbb7a6fd18109efdd9a22df79cf9;uas-addr=172.17.7.90;rport=5060 2009/02/05 11:48:30.937 DBG: [CID=0x0a20] CSeq: 1 INVITE 2009/02/05 11:48:30.937 DBG: [CID=0x0a20] Call-ID: 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a-0x0001 2009/02/05 11:48:30.937 DBG: [CID=0x0a20] Content-Length: 0 2009/02/05 11:48:30.937 DBG: [CID=0x0a20] 2009/02/05 11:48:30.937 DBG: [CID=0x0a20] 2009/02/05 11:48:30.937 DBG: [CID=0x0a20] Finding transaction for SIP/2.0 100 Trying 2009/02/05 11:48:30.937 DBG: [CID=0x0a20] Setting Transaction ID to 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a-0x0001|z9hG4bKf5e9cbb7a6fd18109efdd9a22df79cf9|INVITE 2009/02/05 11:48:30.937 DTL: [CID=0x0a20] Found ICT|5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a-0x0001|z9hG4bKf5e9cbb7a6fd18109efdd9a22df79cf9|INVITE for SIP/2.0 100 Trying 2009/02/05 11:48:30.937 DTL: [CID=0x0a20] ICT(264062017) Event(SIPMessage) - SIP/2.0 100 Trying 2009/02/05 11:48:30.937 DBG: [CID=0x0a20] TRANSACTION: (ICT) SIP/2.0 100 Trying State: 1 2009/02/05 11:48:30.937 DBG: [CID=0x0a20] ICT(264062017) Timer A STOPPED 2009/02/05 11:48:30.937 DBG: [CID=0x0a20] ICT(264062017) Timer B STOPPED 2009/02/05 11:48:30.937 DTL: [CID=0x0a20] ICT(264062017) StateCalling->StateProceeding(SIP/2.0 100 Trying) 2009/02/05 11:48:31.125 INF: [CID=0x0a20] <<< INVITE sip:kwa...@ta... SIP/2.0 SRC: 172.17.7.90:49152:UDP enc=0 bytes=883 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] INVITE sip:kwa...@ta... SIP/2.0 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] From: "unknown" <sip:kw...@ta...>;tag=6842946254 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] To: <sip:kwa...@ta...> 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] Via: SIP/2.0/UDP 172.17.7.90:49152;branch=z9hG4bKac11075a00000021498a62280000215b00000005 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] CSeq: 1 INVITE 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] Call-ID: 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] Contact: <sip:kwan@172.17.7.90:49152> 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] User-Agent: SJphone/1.65.377a (SJ Labs) 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] Max-Forwards: 70 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] Supported: replaces, norefersub, timer 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] Content-Type: application/sdp 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] Content-Length: 362 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] v=0 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] o=- 3442794664 3442794664 IN IP4 172.17.7.90 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] s=SJphone 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] c=IN IP4 172.17.7.90 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] t=0 0 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] m=audio 49154 RTP/AVP 3 97 98 8 0 101 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] c=IN IP4 172.17.7.90 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] a=rtpmap:3 GSM/8000 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] a=rtpmap:97 iLBC/8000 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] a=rtpmap:98 iLBC/8000 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] a=fmtp:98 mode=20 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] a=rtpmap:8 PCMA/8000 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] a=rtpmap:0 PCMU/8000 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] a=rtpmap:101 telephone-event/8000 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] a=fmtp:101 0-16 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] a=setup:active 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] a=sendrecv 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] Finding transaction for INVITE sip:kwa...@ta... SIP/2.0 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] Setting Transaction ID to 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a|z9hG4bKac11075a00000021498a62280000215b00000005|INVITE 2009/02/05 11:48:31.125 DTL: [CID=0x0a20] Found IST|5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a|z9hG4bKac11075a00000021498a62280000215b00000005|INVITE for INVITE sip:kwa...@ta... SIP/2.0 2009/02/05 11:48:31.125 DTL: [CID=0x0a20] IST(264062016) Event(SIPMessage) - INVITE sip:kwa...@ta... SIP/2.0 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] TRANSACTION: (IST) INVITE sip:kwa...@ta... SIP/2.0 State: 2 2009/02/05 11:48:31.125 INF: [CID=0x0a20] >>> SIP/2.0 100 Trying DST: 172.17.7.90:49152:UDP SRC=172.17.7.110:5061 enc=0 bytes=346 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] SIP/2.0 100 Trying 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] From: "unknown" <sip:kw...@ta...>;tag=6842946254 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] To: <sip:kwa...@ta...> 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] Via: SIP/2.0/UDP 172.17.7.90:49152;branch=z9hG4bKac11075a00000021498a62280000215b00000005;rport=49152;received=172.17.7.90 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] CSeq: 1 INVITE 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] Call-ID: 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] Content-Length: 0 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] 2009/02/05 11:48:31.125 DBG: [CID=0x0a20] 2009/02/05 11:48:31.125 PWL: [CID=0x0000] >>> SIP/2.0 100 Trying DST: 172.17.7.90:49152:UDP SRC=172.17.7.110:5061 enc=0 bytes=346 2009/02/05 11:48:31.375 INF: [CID=0x0a20] <<< SIP/2.0 180 Ringing SRC: 172.17.7.90:24742:UDP enc=0 bytes=483 2009/02/05 11:48:31.375 DBG: [CID=0x0a20] 2009/02/05 11:48:31.375 DBG: [CID=0x0a20] SIP/2.0 180 Ringing 2009/02/05 11:48:31.375 DBG: [CID=0x0a20] From: "unknown" <sip:kw...@ta...>;tag=6842946254 2009/02/05 11:48:31.375 DBG: [CID=0x0a20] To: <sip:kwa...@ta...>;tag=bf20135a 2009/02/05 11:48:31.375 DBG: [CID=0x0a20] Via: SIP/2.0/UDP 172.17.7.110:5060;iid=6051;branch=z9hG4bKf5e9cbb7a6fd18109efdd9a22df79cf9;uas-addr=172.17.7.90;rport=5060 2009/02/05 11:48:31.375 DBG: [CID=0x0a20] CSeq: 1 INVITE 2009/02/05 11:48:31.375 DBG: [CID=0x0a20] Call-ID: 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a-0x0001 2009/02/05 11:48:31.375 DBG: [CID=0x0a20] Contact: <sip:kwan-lite2@172.17.7.90:24742;rinstance=2818a5703040a9c8> 2009/02/05 11:48:31.375 DBG: [CID=0x0a20] User-Agent: X-Lite release 1100l stamp 47546 2009/02/05 11:48:31.375 DBG: [CID=0x0a20] Content-Length: 0 2009/02/05 11:48:31.375 DBG: [CID=0x0a20] 2009/02/05 11:48:31.375 DBG: [CID=0x0a20] 2009/02/05 11:48:31.375 DBG: [CID=0x0a20] Finding transaction for SIP/2.0 180 Ringing 2009/02/05 11:48:31.375 DBG: [CID=0x0a20] Setting Transaction ID to 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a-0x0001|z9hG4bKf5e9cbb7a6fd18109efdd9a22df79cf9|INVITE 2009/02/05 11:48:31.375 DTL: [CID=0x0a20] Found ICT|5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a-0x0001|z9hG4bKf5e9cbb7a6fd18109efdd9a22df79cf9|INVITE for SIP/2.0 180 Ringing 2009/02/05 11:48:31.375 DTL: [CID=0x0a20] ICT(264062017) Event(SIPMessage) - SIP/2.0 180 Ringing 2009/02/05 11:48:31.375 DBG: [CID=0x0a20] TRANSACTION: (ICT) SIP/2.0 180 Ringing State: 2 2009/02/05 11:48:31.375 DBG: [CID=0x0a20] ICT(264062017) Timer A STOPPED 2009/02/05 11:48:31.375 DBG: [CID=0x0a20] ICT(264062017) Timer B STOPPED 2009/02/05 11:48:31.375 DBG: [CID=0x0a20] Event: SIPStack::Enqueue(SIP/2.0 180 Ringing) 2009/02/05 11:48:31.375 DBG: [CID=0x0a20] Event: B2BUserAgent::ProcessEvent( SIP/2.0 180 Ringing ) 2009/02/05 11:48:31.375 DBG: [CID=0x0a20] Event: Setting UA Core [Call] to handle event INVITE 2009/02/05 11:48:31.375 DTL: [CID=0x0a20] Event: ---> Inbound - SIP/2.0 180 Ringing 2009/02/05 11:48:31.375 DBG: [CID=0x0a20] *** MESSAGE ARRIVAL *** for SIP Session 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a-0x0001 2009/02/05 11:48:31.375 DTL: [CID=0x0a20] NAT: Setting target address from sip:kwan@172.17.7.90:49152 to 172.17.7.90:49152 2009/02/05 11:48:31.390 DBG: [CID=0x0a20] Finding transaction for SIP/2.0 180 Ringing 2009/02/05 11:48:31.390 DBG: [CID=0x0a20] Setting Transaction ID to 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a|z9hG4bKac11075a00000021498a62280000215b00000005|INVITE 2009/02/05 11:48:31.390 DTL: [CID=0x0a20] Found IST|5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a|z9hG4bKac11075a00000021498a62280000215b00000005|INVITE for SIP/2.0 180 Ringing 2009/02/05 11:48:31.390 DTL: [CID=0x0a20] IST(264062016) Event(SIPMessage) - SIP/2.0 180 Ringing 2009/02/05 11:48:31.390 DBG: [CID=0x0a20] TRANSACTION: (IST) SIP/2.0 180 Ringing State: 2 2009/02/05 11:48:31.390 INF: [CID=0x0a20] >>> SIP/2.0 180 Ringing DST: 172.17.7.90:49152:UDP SRC=172.17.7.110:5061 enc=0 bytes=511 2009/02/05 11:48:31.390 DBG: [CID=0x0a20] 2009/02/05 11:48:31.390 DBG: [CID=0x0a20] SIP/2.0 180 Ringing 2009/02/05 11:48:31.390 DBG: [CID=0x0a20] From: "unknown" <sip:kw...@ta...>;tag=6842946254 2009/02/05 11:48:31.390 DBG: [CID=0x0a20] To: <sip:kwa...@ta...>;tag=dc45cdb7a6fd18109efdd9a22df79cf9 2009/02/05 11:48:31.390 DBG: [CID=0x0a20] Via: SIP/2.0/UDP 172.17.7.90:49152;iid=6049;branch=z9hG4bKac11075a00000021498a62280000215b00000005;rport=49152;received=172.17.7.90 2009/02/05 11:48:31.390 DBG: [CID=0x0a20] CSeq: 1 INVITE 2009/02/05 11:48:31.390 DBG: [CID=0x0a20] Call-ID: 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a 2009/02/05 11:48:31.390 DBG: [CID=0x0a20] Contact: <sip:kwan-lite2@172.17.7.110:5060> 2009/02/05 11:48:31.390 DBG: [CID=0x0a20] Server: OpenSBC v1.1.5-42 2009/02/05 11:48:31.390 DBG: [CID=0x0a20] User-Agent: X-Lite release 1100l stamp 47546 2009/02/05 11:48:31.390 DBG: [CID=0x0a20] Content-Length: 0 2009/02/05 11:48:31.390 DBG: [CID=0x0a20] 2009/02/05 11:48:31.390 DBG: [CID=0x0a20] 2009/02/05 11:48:31.390 PWL: [CID=0x0000] >>> SIP/2.0 180 Ringing DST: 172.17.7.90:49152:UDP SRC=172.17.7.110:5061 enc=0 bytes=511 2009/02/05 11:48:32.796 INF: [CID=0x0a20] <<< SIP/2.0 200 OK SRC: 172.17.7.90:24742:UDP enc=0 bytes=804 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] SIP/2.0 200 OK 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] From: "unknown" <sip:kw...@ta...>;tag=6842946254 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] To: <sip:kwa...@ta...>;tag=bf20135a 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] Via: SIP/2.0/UDP 172.17.7.110:5060;iid=6051;branch=z9hG4bKf5e9cbb7a6fd18109efdd9a22df79cf9;uas-addr=172.17.7.90;rport=5060 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] CSeq: 1 INVITE 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] Call-ID: 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a-0x0001 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] Contact: <sip:kwan-lite2@172.17.7.90:24742;rinstance=2818a5703040a9c8> 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] User-Agent: X-Lite release 1100l stamp 47546 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] Content-Type: application/sdp 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] Content-Length: 210 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] v=0 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] o=- 9 2 IN IP4 172.17.7.90 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] s=CounterPath X-Lite 3.0 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] c=IN IP4 172.17.7.90 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] t=0 0 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] m=audio 4410 RTP/AVP 3 98 8 0 101 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] a=fmtp:101 0-15 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] a=rtpmap:98 iLBC/8000 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] a=rtpmap:101 telephone-event/8000 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] a=sendrecv 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] Finding transaction for SIP/2.0 200 OK 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] Setting Transaction ID to 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a-0x0001|z9hG4bKf5e9cbb7a6fd18109efdd9a22df79cf9|INVITE 2009/02/05 11:48:32.796 DTL: [CID=0x0a20] Found ICT|5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a-0x0001|z9hG4bKf5e9cbb7a6fd18109efdd9a22df79cf9|INVITE for SIP/2.0 200 OK 2009/02/05 11:48:32.796 DTL: [CID=0x0a20] ICT(264062017) Event(SIPMessage) - SIP/2.0 200 OK 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] TRANSACTION: (ICT) SIP/2.0 200 OK State: 2 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] ICT(264062017) Timer A STOPPED 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] ICT(264062017) Timer B STOPPED 2009/02/05 11:48:32.796 DTL: [CID=0x0a20] ICT(264062017) StateProceeding->StateConnected(SIP/2.0 200 OK) 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] Event: SIPStack::Enqueue(SIP/2.0 200 OK) 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] ICT(264062017) Timer D( 32000 ms ) STARTED 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] Event: B2BUserAgent::ProcessEvent( SIP/2.0 200 OK ) 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] Event: Setting UA Core [Call] to handle event INVITE 2009/02/05 11:48:32.796 DTL: [CID=0x0a20] Event: ---> Inbound - SIP/2.0 200 OK 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] *** MESSAGE ARRIVAL *** for SIP Session 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a-0x0001 2009/02/05 11:48:32.796 DTL: [CID=0x0a20] *** INIT DIALOG (UAC) *** OpenSBC Main Trunk 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a-0x0001 2009/02/05 11:48:32.796 DBG: [CID=0x06cb] CREATED via=172.17.7.110:5060 for target=172.17.7.90 protocol=UDP 2009/02/05 11:48:32.796 INF: [CID=0x0a20] *** RTP Session CREATED *** l-addr=172.17.7.110 r-addr=172.17.7.90/172.17.7.110 r-port=4410 2009/02/05 11:48:32.796 INF: [CID=0x0a20] *** CALL ESTABLISHED *** 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a-0x0001 2009/02/05 11:48:32.796 DTL: [CID=0x0a20] NAT: Setting target address from sip:kwan-lite2@172.17.7.90:24742;rinstance=2818a5703040a9c8 to 172.17.7.90:24742 2009/02/05 11:48:32.796 INF: [CID=0x0a20] >>> ACK sip:kwan-lite2@172.17.7.90:24742;rinstance=2818a5703040a9c8 SIP/2.0 DST: 172.17.7.90:24742:UDP SRC: 172.17.7.110:5060 enc=0 bytes=432 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] ACK sip:kwan-lite2@172.17.7.90:24742;rinstance=2818a5703040a9c8 SIP/2.0 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] From: <sip:kw...@ta...>;tag=6842946254 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] To: <sip:kwa...@ta...>;tag=bf20135a 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] Via: SIP/2.0/UDP 172.17.7.110:5060;branch=z9hG4bKf5e9cbb7a6fd18109efdd9a22df79cf9;uas-addr=172.17.7.90;rport 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] CSeq: 1 ACK 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] Call-ID: 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a-0x0001 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] Contact: <sip:kwan@172.17.7.110:5060> 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] Content-Length: 0 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] 2009/02/05 11:48:32.796 DBG: [CID=0x0a20] 2009/02/05 11:48:32.812 DTL: [CID=0x0a20] *** INIT DIALOG (UAS) *** OpenSBC Main Trunk 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] Finding transaction for SIP/2.0 200 OK 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] Setting Transaction ID to 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a|z9hG4bKac11075a00000021498a62280000215b00000005|INVITE 2009/02/05 11:48:32.812 DTL: [CID=0x0a20] Found IST|5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a|z9hG4bKac11075a00000021498a62280000215b00000005|INVITE for SIP/2.0 200 OK 2009/02/05 11:48:32.812 DTL: [CID=0x0a20] IST(264062016) Event(SIPMessage) - SIP/2.0 200 OK 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] TRANSACTION: (IST) SIP/2.0 200 OK State: 2 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] IST(264062016) Timer G STOPPED 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] IST(264062016) Timer H STOPPED 2009/02/05 11:48:32.812 INF: [CID=0x0a20] *** RTP PROXY STARTED *** 2009/02/05 11:48:32.812 INF: [CID=0x0a20] *** RTP PROXY STARTED *** 2009/02/05 11:48:32.812 INF: [CID=0x0a20] >>> SIP/2.0 200 OK DST: 172.17.7.90:49152:UDP SRC=172.17.7.110:5061 enc=0 bytes=888 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] SIP/2.0 200 OK 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] From: "unknown" <sip:kw...@ta...>;tag=6842946254 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] To: <sip:kwa...@ta...>;tag=dc45cdb7a6fd18109efdd9a22df79cf9 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] Via: SIP/2.0/UDP 172.17.7.90:49152;iid=6049;branch=z9hG4bKac11075a00000021498a62280000215b00000005;rport=49152;received=172.17.7.90 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] CSeq: 1 INVITE 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] Call-ID: 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] Contact: <sip:kwan-lite2@172.17.7.110:5060> 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] Record-Route: <sip:kwan-lite2@172.17.7.110:5060;lr> 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] Server: OpenSBC v1.1.5-42 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] User-Agent: X-Lite release 1100l stamp 47546 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] Content-Type: application/sdp 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] Content-Length: 213 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] v=0 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] o=- 9 2 IN IP4 172.17.7.110 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] s=CounterPath X-Lite 3.0 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] c=IN IP4 172.17.7.110 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] t=0 0 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] m=audio 30000 RTP/AVP 3 98 8 0 101 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] a=fmtp:101 0-15 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] a=rtpmap:98 iLBC/8000 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] a=rtpmap:101 telephone-event/8000 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] a=sendrecv 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] 2009/02/05 11:48:32.812 PWL: [CID=0x0000] >>> SIP/2.0 200 OK DST: 172.17.7.90:49152:UDP SRC=172.17.7.110:5061 enc=0 bytes=888 2009/02/05 11:48:32.812 DTL: [CID=0x0a20] IST(264062016) StateProceeding->StateConfirmed 2009/02/05 11:48:32.812 DBG: [CID=0x0a20] IST(264062016) Timer I( 5000 ms ) STARTED 2009/02/05 11:48:32.875 INF: [CID=0x0a20] <<< ACK sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 SRC: 172.17.7.90:49152:UDP enc=0 bytes=522 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] ACK sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] From: "unknown" <sip:kw...@ta...>;tag=6842946254 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] To: <sip:kwa...@ta...>;tag=dc45cdb7a6fd18109efdd9a22df79cf9 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] Via: SIP/2.0/UDP 172.17.7.90:49152;branch=z9hG4bKac11075a00000021498a622b000059500000000a 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] CSeq: 1 ACK 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] Call-ID: 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] Contact: <sip:kwan@172.17.7.90:49152> 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] Route: <sip:kwan-lite2@172.17.7.110:5060;lr> 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] User-Agent: SJphone/1.65.377a (SJ Labs) 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] Max-Forwards: 70 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] Content-Length: 0 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] Finding transaction for ACK sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] Unable to find ACK Transaction 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075adc45cdb7a6fd18109efdd9a22df79cf9|z9hG4bKac11075a00000021498a622b000059500000000a|ACK 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] Setting Transaction ID to 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a|z9hG4bKac11075a00000021498a622b000059500000000a|ACK 2009/02/05 11:48:32.875 DTL: [CID=0x0a20] Event: ---> UnknownTransaction - ACK 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] ACK sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] From: "unknown" <sip:kw...@ta...>;tag=6842946254 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] To: <sip:kwa...@ta...>;tag=dc45cdb7a6fd18109efdd9a22df79cf9 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] Via: SIP/2.0/UDP 172.17.7.90:49152;branch=z9hG4bKac11075a00000021498a622b000059500000000a;rport=49152;received=172.17.7.90 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] CSeq: 1 ACK 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] Call-ID: 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] Contact: <sip:kwan@172.17.7.90:49152> 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] Route: <sip:kwan-lite2@172.17.7.110:5060;lr> 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] User-Agent: SJphone/1.65.377a (SJ Labs) 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] Max-Forwards: 70 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] Content-Length: 0 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] 2009/02/05 11:48:32.875 DBG: [CID=0x0a20] 2009/02/05 11:48:35.375 DBG: [CID=0x0e79] NIST(264062013) Timer J( 32000 ms ) EXPIRED 2009/02/05 11:48:35.375 DTL: [CID=0x0e79] NIST(264062013) Event( Timer-J ) Interval: 32000 2009/02/05 11:48:35.375 DTL: [CID=0x0e79] NIST(264062013) StateCompleted->StateTerminated 2009/02/05 11:48:35.375 DTL: [CID=0x0e79] NIST(264062013) Event(Final) 2009/02/05 11:48:35.375 DTL: [CID=0x0000] *** REMOVED TRANSACTION *** NIST|N2Y5ODhmYmJjZWMxMDE0MjhjYTIzZGIwNGQ2NTJmMzM.|z9hG4bK-d8754z-ef46ab23cf445d20-1---d8754z-|REGISTER 2009/02/05 11:48:35.375 DBG: [CID=0x0000] GC: First Stale Object SIPTransaction 2009/02/05 11:48:35.375 DBG: [CID=0x0e79] TRANSACTION: (NIST) DESTROYED 2009/02/05 11:48:35.375 DTL: [CID=0x0e79] NIST(264062013) *** DESTROYED *** - NIST|N2Y5ODhmYmJjZWMxMDE0MjhjYTIzZGIwNGQ2NTJmMzM.|z9hG4bK-d8754z-ef46ab23cf445d20-1---d8754z-|REGISTER 2009/02/05 11:48:35.656 DBG: [CID=0x0e79] NIST(264062014) Timer J( 32000 ms ) EXPIRED 2009/02/05 11:48:35.656 DTL: [CID=0x0e79] NIST(264062014) Event( Timer-J ) Interval: 32000 2009/02/05 11:48:35.656 DTL: [CID=0x0e79] NIST(264062014) StateCompleted->StateTerminated 2009/02/05 11:48:35.656 DTL: [CID=0x0e79] NIST(264062014) Event(Final) 2009/02/05 11:48:35.656 DTL: [CID=0x0000] *** REMOVED TRANSACTION *** NIST|N2Y5ODhmYmJjZWMxMDE0MjhjYTIzZGIwNGQ2NTJmMzM.|z9hG4bK-d8754z-7c781e3f4b31c049-1---d8754z-|REGISTER 2009/02/05 11:48:35.656 DBG: [CID=0x0000] GC: First Stale Object SIPTransaction 2009/02/05 11:48:35.656 DBG: [CID=0x0e79] TRANSACTION: (NIST) DESTROYED 2009/02/05 11:48:35.656 DTL: [CID=0x0e79] NIST(264062014) *** DESTROYED *** - NIST|N2Y5ODhmYmJjZWMxMDE0MjhjYTIzZGIwNGQ2NTJmMzM.|z9hG4bK-d8754z-7c781e3f4b31c049-1---d8754z-|REGISTER 2009/02/05 11:48:35.765 DBG: [CID=0x0e9a] NIST(264062015) Timer J( 32000 ms ) EXPIRED 2009/02/05 11:48:35.765 DTL: [CID=0x0e9a] NIST(264062015) Event( Timer-J ) Interval: 32000 2009/02/05 11:48:35.765 DTL: [CID=0x0e9a] NIST(264062015) StateCompleted->StateTerminated 2009/02/05 11:48:35.765 DTL: [CID=0x0e9a] NIST(264062015) Event(Final) 2009/02/05 11:48:35.765 DTL: [CID=0x0000] *** REMOVED TRANSACTION *** NIST|NzI3OTc0MmFmZTU1MjIxZmI3ODU5ZWUxOWVkYzJjNzE.|z9hG4bK-d8754z-e56f9f4c33312102-1---d8754z-|SUBSCRIBE 2009/02/05 11:48:35.765 DBG: [CID=0x0000] GC: First Stale Object SIPTransaction 2009/02/05 11:48:35.765 DBG: [CID=0x0e9a] TRANSACTION: (NIST) DESTROYED 2009/02/05 11:48:35.765 DTL: [CID=0x0e9a] NIST(264062015) *** DESTROYED *** - NIST|NzI3OTc0MmFmZTU1MjIxZmI3ODU5ZWUxOWVkYzJjNzE.|z9hG4bK-d8754z-e56f9f4c33312102-1---d8754z-|SUBSCRIBE 2009/02/05 11:48:37.812 DBG: [CID=0x0a20] IST(264062016) Timer I( 5000 ms ) EXPIRED 2009/02/05 11:48:37.812 DTL: [CID=0x0a20] IST(264062016) Event( Timer-I ) Interval: 5000 2009/02/05 11:48:37.812 DTL: [CID=0x0a20] IST(264062016) Event(Final) 2009/02/05 11:48:37.812 DTL: [CID=0x0000] *** REMOVED TRANSACTION *** IST|5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a|z9hG4bKac11075a00000021498a62280000215b00000005|INVITE 2009/02/05 11:48:37.812 DBG: [CID=0x0000] GC: First Stale Object SIPTransaction 2009/02/05 11:48:37.812 DBG: [CID=0x0a20] TRANSACTION: (IST) DESTROYED 2009/02/05 11:48:37.812 DTL: [CID=0x0a20] IST(264062016) *** DESTROYED *** - IST|5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a|z9hG4bKac11075a00000021498a62280000215b00000005|INVITE 2009/02/05 11:48:44.156 INF: [CID=0x0a20] <<< INVITE sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 SRC: 172.17.7.90:49152:UDP enc=0 bytes=838 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] INVITE sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] From: "unknown" <sip:kw...@ta...>;tag=6842946254 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] To: <sip:kwa...@ta...>;tag=dc45cdb7a6fd18109efdd9a22df79cf9 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] Via: SIP/2.0/UDP 172.17.7.90:49152;branch=z9hG4bKac11075a00000023498a6236000061930000000b 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] CSeq: 2 INVITE 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] Call-ID: 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] Contact: <sip:kwan@172.17.7.90:49152> 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] Route: <sip:kwan-lite2@172.17.7.110:5060;lr> 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] User-Agent: SJphone/1.65.377a (SJ Labs) 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] Max-Forwards: 70 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] Supported: replaces, norefersub, timer 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] Content-Type: application/sdp 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] Content-Length: 239 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] v=0 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] o=- 3442794664 3442794665 IN IP4 172.17.7.90 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] s=SJphone 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] c=IN IP4 172.17.7.90 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] t=0 0 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] m=audio 49154 RTP/AVP 3 101 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] c=IN IP4 0.0.0.0 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] a=rtpmap:3 GSM/8000 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] a=rtpmap:101 telephone-event/8000 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] a=fmtp:101 0-16 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] a=setup:active 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] a=sendonly 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] Finding transaction for INVITE sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] Setting Transaction ID to 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a|z9hG4bKac11075a00000023498a6236000061930000000b|INVITE 2009/02/05 11:48:44.156 DTL: [CID=0x0a20] IST(264062018) *** CREATED *** - IST|5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a|z9hG4bKac11075a00000023498a6236000061930000000b|INVITE 2009/02/05 11:48:44.156 DTL: [CID=0x0a20] IST(264062018) Event(SIPMessage) - INVITE sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] TRANSACTION: (IST) INVITE sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 State: 0 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] Event: SIPStack::Enqueue(INVITE sip:kwan-lite2@172.17.7.110:5060 SIP/2.0) 2009/02/05 11:48:44.156 DTL: [CID=0x0a20] IST(264062018) StateIdle->StateProceeding 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] Event: B2BUserAgent::ProcessEvent( INVITE sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 ) 2009/02/05 11:48:44.156 DTL: [CID=0x0a20] Event: ---> Inbound - INVITE sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] *** MESSAGE ARRIVAL *** for SIP Session 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a 2009/02/05 11:48:44.156 DBG: [CID=0x06cb] CREATED via=172.17.7.110:5060 for target=172.17.7.90 protocol=UDP 2009/02/05 11:48:44.156 INF: [CID=0x0a20] *** RTP Session CREATED *** l-addr=172.17.7.110 r-addr=127.0.0.1/172.17.7.90 r-port=49154 2009/02/05 11:48:44.156 DTL: [CID=0x0a20] Found IST|5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a|z9hG4bKac11075a00000023498a6236000061930000000b|INVITE for SIP/2.0 200 OK 2009/02/05 11:48:44.156 DTL: [CID=0x0a20] IST(264062018) Event(SIPMessage) - SIP/2.0 200 OK 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] TRANSACTION: (IST) SIP/2.0 200 OK State: 2 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] IST(264062018) Timer G STOPPED 2009/02/05 11:48:44.156 DBG: [CID=0x0a20] IST(264062018) Timer H STOPPED 2009/02/05 11:48:44.171 INF: [CID=0x0a20] >>> SIP/2.0 200 OK DST: 172.17.7.90:49152:UDP SRC=172.17.7.110:5061 enc=0 bytes=812 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] SIP/2.0 200 OK 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] From: "unknown" <sip:kw...@ta...>;tag=6842946254 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] To: <sip:kwa...@ta...>;tag=dc45cdb7a6fd18109efdd9a22df79cf9 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] Via: SIP/2.0/UDP 172.17.7.90:49152;iid=6049;branch=z9hG4bKac11075a00000023498a6236000061930000000b;rport=49152;received=172.17.7.90 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] CSeq: 2 INVITE 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] Call-ID: 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] Server: OpenSBC v1.1.5-42 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] Content-Type: application/sdp 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] Content-Length: 364 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] v=0 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] o=- 3442794664 3442794664 IN IP4 172.17.7.90 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] s=SJphone 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] c=IN IP4 172.17.7.110 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] t=0 0 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] m=audio 30002 RTP/AVP 3 97 98 8 0 101 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] c=IN IP4 172.17.7.110 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] a=rtpmap:3 GSM/8000 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] a=rtpmap:97 iLBC/8000 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] a=rtpmap:98 iLBC/8000 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] a=fmtp:98 mode=20 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] a=rtpmap:8 PCMA/8000 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] a=rtpmap:0 PCMU/8000 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] a=rtpmap:101 telephone-event/8000 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] a=fmtp:101 0-16 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] a=setup:active 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] a=sendrecv 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] 2009/02/05 11:48:44.171 PWL: [CID=0x0000] >>> SIP/2.0 200 OK DST: 172.17.7.90:49152:UDP SRC=172.17.7.110:5061 enc=0 bytes=812 2009/02/05 11:48:44.171 DTL: [CID=0x0a20] IST(264062018) StateProceeding->StateConfirmed 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] IST(264062018) Timer I( 5000 ms ) STARTED 2009/02/05 11:48:44.171 INF: [CID=0x0a20] <<< ACK sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 SRC: 172.17.7.90:49152:UDP enc=0 bytes=522 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] ACK sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] From: "unknown" <sip:kw...@ta...>;tag=6842946254 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] To: <sip:kwa...@ta...>;tag=dc45cdb7a6fd18109efdd9a22df79cf9 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] Via: SIP/2.0/UDP 172.17.7.90:49152;branch=z9hG4bKac11075a00000023498a623600007d610000000e 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] CSeq: 2 ACK 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] Call-ID: 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] Contact: <sip:kwan@172.17.7.90:49152> 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] Route: <sip:kwan-lite2@172.17.7.110:5060;lr> 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] User-Agent: SJphone/1.65.377a (SJ Labs) 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] Max-Forwards: 70 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] Content-Length: 0 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] Finding transaction for ACK sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] Unable to find ACK Transaction 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075adc45cdb7a6fd18109efdd9a22df79cf9|z9hG4bKac11075a00000023498a623600007d610000000e|ACK 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] Setting Transaction ID to 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a|z9hG4bKac11075a00000023498a623600007d610000000e|ACK 2009/02/05 11:48:44.171 DTL: [CID=0x0a20] Event: ---> UnknownTransaction - ACK 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] ACK sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] From: "unknown" <sip:kw...@ta...>;tag=6842946254 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] To: <sip:kwa...@ta...>;tag=dc45cdb7a6fd18109efdd9a22df79cf9 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] Via: SIP/2.0/UDP 172.17.7.90:49152;branch=z9hG4bKac11075a00000023498a623600007d610000000e;rport=49152;received=172.17.7.90 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] CSeq: 2 ACK 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] Call-ID: 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] Contact: <sip:kwan@172.17.7.90:49152> 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] Route: <sip:kwan-lite2@172.17.7.110:5060;lr> 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] User-Agent: SJphone/1.65.377a (SJ Labs) 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] Max-Forwards: 70 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] Content-Length: 0 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] 2009/02/05 11:48:44.171 DBG: [CID=0x0a20] 2009/02/05 11:48:49.171 DBG: [CID=0x0a20] IST(264062018) Timer I( 5000 ms ) EXPIRED 2009/02/05 11:48:49.171 DTL: [CID=0x0a20] IST(264062018) Event( Timer-I ) Interval: 5000 2009/02/05 11:48:49.171 DTL: [CID=0x0a20] IST(264062018) Event(Final) 2009/02/05 11:48:49.171 DTL: [CID=0x0000] *** REMOVED TRANSACTION *** IST|5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a|z9hG4bKac11075a00000023498a6236000061930000000b|INVITE 2009/02/05 11:48:49.171 DBG: [CID=0x0000] GC: First Stale Object SIPTransaction 2009/02/05 11:48:49.171 DBG: [CID=0x0a20] TRANSACTION: (IST) DESTROYED 2009/02/05 11:48:49.171 DTL: [CID=0x0a20] IST(264062018) *** DESTROYED *** - IST|5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a|z9hG4bKac11075a00000023498a6236000061930000000b|INVITE 2009/02/05 11:48:55.593 INF: [CID=0x0a20] <<< INVITE sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 SRC: 172.17.7.90:49152:UDP enc=0 bytes=961 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] INVITE sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] From: "unknown" <sip:kw...@ta...>;tag=6842946254 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] To: <sip:kwa...@ta...>;tag=dc45cdb7a6fd18109efdd9a22df79cf9 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] Via: SIP/2.0/UDP 172.17.7.90:49152;branch=z9hG4bKac11075a00000025498a624100005a800000000f 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] CSeq: 3 INVITE 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] Call-ID: 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] Contact: <sip:kwan@172.17.7.90:49152> 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] Route: <sip:kwan-lite2@172.17.7.110:5060;lr> 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] User-Agent: SJphone/1.65.377a (SJ Labs) 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] Max-Forwards: 70 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] Supported: replaces, norefersub, timer 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] Content-Type: application/sdp 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] Content-Length: 362 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] v=0 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] o=- 3442794664 3442794666 IN IP4 172.17.7.90 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] s=SJphone 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] c=IN IP4 172.17.7.90 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] t=0 0 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] m=audio 49154 RTP/AVP 3 97 98 8 0 101 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] c=IN IP4 172.17.7.90 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] a=rtpmap:3 GSM/8000 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] a=rtpmap:97 iLBC/8000 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] a=rtpmap:98 iLBC/8000 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] a=fmtp:98 mode=20 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] a=rtpmap:8 PCMA/8000 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] a=rtpmap:0 PCMU/8000 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] a=rtpmap:101 telephone-event/8000 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] a=fmtp:101 0-16 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] a=setup:active 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] a=sendrecv 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] Finding transaction for INVITE sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] Setting Transaction ID to 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a|z9hG4bKac11075a00000025498a624100005a800000000f|INVITE 2009/02/05 11:48:55.593 DTL: [CID=0x0a20] IST(264062019) *** CREATED *** - IST|5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a|z9hG4bKac11075a00000025498a624100005a800000000f|INVITE 2009/02/05 11:48:55.593 DTL: [CID=0x0a20] IST(264062019) Event(SIPMessage) - INVITE sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] TRANSACTION: (IST) INVITE sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 State: 0 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] Event: SIPStack::Enqueue(INVITE sip:kwan-lite2@172.17.7.110:5060 SIP/2.0) 2009/02/05 11:48:55.593 DTL: [CID=0x0a20] IST(264062019) StateIdle->StateProceeding 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] Event: B2BUserAgent::ProcessEvent( INVITE sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 ) 2009/02/05 11:48:55.593 DTL: [CID=0x0a20] Event: ---> Inbound - INVITE sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] *** MESSAGE ARRIVAL *** for SIP Session 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a 2009/02/05 11:48:55.593 DBG: [CID=0x06cb] CREATED via=172.17.7.110:5060 for target=172.17.7.90 protocol=UDP 2009/02/05 11:48:55.593 INF: [CID=0x0a20] *** RTP Session CREATED *** l-addr=172.17.7.110 r-addr=172.17.7.90/172.17.7.90 r-port=49154 2009/02/05 11:48:55.593 DTL: [CID=0x0a20] Found IST|5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a|z9hG4bKac11075a00000025498a624100005a800000000f|INVITE for SIP/2.0 200 OK 2009/02/05 11:48:55.593 DTL: [CID=0x0a20] IST(264062019) Event(SIPMessage) - SIP/2.0 200 OK 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] TRANSACTION: (IST) SIP/2.0 200 OK State: 2 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] IST(264062019) Timer G STOPPED 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] IST(264062019) Timer H STOPPED 2009/02/05 11:48:55.593 INF: [CID=0x0a20] >>> SIP/2.0 200 OK DST: 172.17.7.90:49152:UDP SRC=172.17.7.110:5061 enc=0 bytes=812 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] SIP/2.0 200 OK 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] From: "unknown" <sip:kw...@ta...>;tag=6842946254 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] To: <sip:kwa...@ta...>;tag=dc45cdb7a6fd18109efdd9a22df79cf9 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] Via: SIP/2.0/UDP 172.17.7.90:49152;iid=6049;branch=z9hG4bKac11075a00000025498a624100005a800000000f;rport=49152;received=172.17.7.90 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] CSeq: 3 INVITE 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] Call-ID: 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] Server: OpenSBC v1.1.5-42 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] Content-Type: application/sdp 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] Content-Length: 364 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] v=0 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] o=- 3442794664 3442794664 IN IP4 172.17.7.90 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] s=SJphone 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] c=IN IP4 172.17.7.110 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] t=0 0 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] m=audio 30002 RTP/AVP 3 97 98 8 0 101 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] c=IN IP4 172.17.7.110 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] a=rtpmap:3 GSM/8000 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] a=rtpmap:97 iLBC/8000 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] a=rtpmap:98 iLBC/8000 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] a=fmtp:98 mode=20 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] a=rtpmap:8 PCMA/8000 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] a=rtpmap:0 PCMU/8000 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] a=rtpmap:101 telephone-event/8000 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] a=fmtp:101 0-16 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] a=setup:active 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] a=sendrecv 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] 2009/02/05 11:48:55.593 PWL: [CID=0x0000] >>> SIP/2.0 200 OK DST: 172.17.7.90:49152:UDP SRC=172.17.7.110:5061 enc=0 bytes=812 2009/02/05 11:48:55.593 DTL: [CID=0x0a20] IST(264062019) StateProceeding->StateConfirmed 2009/02/05 11:48:55.593 DBG: [CID=0x0a20] IST(264062019) Timer I( 5000 ms ) STARTED 2009/02/05 11:48:55.609 INF: [CID=0x0a20] <<< ACK sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 SRC: 172.17.7.90:49152:UDP enc=0 bytes=522 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] ACK sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] From: "unknown" <sip:kw...@ta...>;tag=6842946254 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] To: <sip:kwa...@ta...>;tag=dc45cdb7a6fd18109efdd9a22df79cf9 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] Via: SIP/2.0/UDP 172.17.7.90:49152;branch=z9hG4bKac11075a00000025498a624200000dc600000012 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] CSeq: 3 ACK 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] Call-ID: 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] Contact: <sip:kwan@172.17.7.90:49152> 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] Route: <sip:kwan-lite2@172.17.7.110:5060;lr> 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] User-Agent: SJphone/1.65.377a (SJ Labs) 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] Max-Forwards: 70 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] Content-Length: 0 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] Finding transaction for ACK sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] Unable to find ACK Transaction 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075adc45cdb7a6fd18109efdd9a22df79cf9|z9hG4bKac11075a00000025498a624200000dc600000012|ACK 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] Setting Transaction ID to 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a|z9hG4bKac11075a00000025498a624200000dc600000012|ACK 2009/02/05 11:48:55.609 DTL: [CID=0x0a20] Event: ---> UnknownTransaction - ACK 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] ACK sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] From: "unknown" <sip:kw...@ta...>;tag=6842946254 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] To: <sip:kwa...@ta...>;tag=dc45cdb7a6fd18109efdd9a22df79cf9 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] Via: SIP/2.0/UDP 172.17.7.90:49152;branch=z9hG4bKac11075a00000025498a624200000dc600000012;rport=49152;received=172.17.7.90 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] CSeq: 3 ACK 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] Call-ID: 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] Contact: <sip:kwan@172.17.7.90:49152> 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] Route: <sip:kwan-lite2@172.17.7.110:5060;lr> 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] User-Agent: SJphone/1.65.377a (SJ Labs) 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] Max-Forwards: 70 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] Content-Length: 0 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] 2009/02/05 11:48:55.609 DBG: [CID=0x0a20] 2009/02/05 11:48:55.828 PWL: [CID=0x0000] RTP Abnormal change of sequence numbers, adjusting to expect 7985 ssrc=946642959 2009/02/05 11:48:56.875 INF: [CID=0x0a20] <<< BYE sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 SRC: 172.17.7.90:49152:UDP enc=0 bytes=560 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] BYE sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] From: "unknown" <sip:kw...@ta...>;tag=6842946254 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] To: <sip:kwa...@ta...>;tag=dc45cdb7a6fd18109efdd9a22df79cf9 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] Via: SIP/2.0/UDP 172.17.7.90:49152;branch=z9hG4bKac11075a00000027498a62430000634400000013 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] CSeq: 4 BYE 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] Call-ID: 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] Contact: <sip:kwan@172.17.7.90:49152> 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] Route: <sip:kwan-lite2@172.17.7.110:5060;lr> 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] User-Agent: SJphone/1.65.377a (SJ Labs) 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] Max-Forwards: 70 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] Supported: replaces, norefersub, timer 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] Content-Length: 0 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] Finding transaction for BYE sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] Setting Transaction ID to 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a|z9hG4bKac11075a00000027498a62430000634400000013|BYE 2009/02/05 11:48:56.875 DTL: [CID=0x0a20] NIST(264062020) *** CREATED *** - NIST|5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a|z9hG4bKac11075a00000027498a62430000634400000013|BYE 2009/02/05 11:48:56.875 DTL: [CID=0x0a20] NIST(264062020) Event(SIPMessage) - BYE sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] TRANSACTION: (NIST) BYE sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 State: 0 2009/02/05 11:48:56.875 DTL: [CID=0x0a20] NIST(264062020) StateIdle->StateTrying 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] Event: SIPStack::Enqueue(BYE sip:kwan-lite2@172.17.7.110:5060 SIP/2.0) 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] Event: B2BUserAgent::ProcessEvent( BYE sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 ) 2009/02/05 11:48:56.875 DTL: [CID=0x0a20] Event: ---> Inbound - BYE sip:kwan-lite2@172.17.7.110:5060 SIP/2.0 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] *** MESSAGE ARRIVAL *** for SIP Session 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a 2009/02/05 11:48:56.875 DTL: [CID=0x0a20] Found NIST|5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a|z9hG4bKac11075a00000027498a62430000634400000013|BYE for SIP/2.0 200 OK 2009/02/05 11:48:56.875 DTL: [CID=0x0a20] NAT: Setting target address from sip:kwan-lite2@172.17.7.90:24742;rinstance=2818a5703040a9c8 to 172.17.7.90:24742 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] Finding transaction for BYE sip:kwan-lite2@172.17.7.90:24742;rinstance=2818a5703040a9c8 SIP/2.0 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] Setting Transaction ID to 5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a-0x0001|z9hG4bKcc2ef4b7a6fd18109efed9a22df79cf9|BYE 2009/02/05 11:48:56.875 DTL: [CID=0x0a20] NICT(264062021) *** CREATED *** - NICT|5AA21F9A8C014B018DC47F402AF6D7EE0xac11075a-0x0001|z9hG4bKcc2ef4b7a6fd18109efed9a22df79cf9|BYE 2009/02/05 11:48:56.875 DTL: [CID=0x0a20] NIST(264062020) Event(SIPMessage) - SIP/2.0 200 OK 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] TRANSACTION: (NIST) SIP/2.0 200 OK State: 1 2009/02/05 11:48:56.875 DTL: [CID=0x0a20] NIST(264062020)HandleStateTrying->StateCompleted 2009/02/05 11:48:56.875 INF: [CID=0x0a20] >>> SIP/2.0 200 OK DST: 172.17.7.90:49152:UDP SRC=172.17.7.110:5061 enc=0 bytes=412 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] SIP/2.0 200 OK 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] From: "unknown" <sip:kw...@ta...>;tag=6842946254 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] To: <sip:kwa...@ta...>;tag=dc45cdb7a6fd18109efdd9a22df79cf9 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] Via: SIP/2.0/UDP 172.17.7.90:49152;iid=6049;branch=z9hG4bKac11075a00000027498a62430000634400000013;rport=49152;received=172.17.7.90 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] CSeq: 4 BYE 2009/02/05 11:48:56.875 DBG: [CID=0x0a20] Call-ID: 5AA21F9A8C0... [truncated message content] |
