< Previous by Date Date Index Next by Date >
< Previous in Thread Thread Index Next in Thread >

Re: [reSIProcate-users] The Dum says 400 Bad request when received a NOTIFY message


Hi Byron, please found the log in attachment and let me know if need I provide other information.

Thanks

On Mon, Jul 21, 2008 at 11:24 PM, Byron Campen <bcampen@xxxxxxxxxxxx> wrote:
Can you send the resip logging? Looking at the parse code, it looks like we are ignoring the "=on", so maybe that isn't what is going on.

Best regards,
Byron Campen

Hi Robert, I'm using resiprocate 1.3.4, and I have sent the log with my two previous emails.

I saw the counterpath bria using resiprocate, but why it's working fine with same server ?



On Mon, Jul 21, 2008 at 10:52 PM, Robert Sparks <rjsparks@xxxxxxxxxxx> wrote:
Values on the lr parameter have historically been encountered in the wild
(there was one stack (not this one) that would crash without them and it took a long long time to get that fixed)

Those values are not supposed to be there, but following Postel's maxim, they should be ignored if they occur.

I am making the right guesses after looking at the thread, its not a resiprocate based product thats generating
the 400, correct? (If that's not correct, and its something in the stack and not the application above the stack 
doing it, we should make things a little more gentle - assuming Byron's right about it being the lr parameter
value that toasting your flow in the first place).

RjS

On Jul 21, 2008, at 9:35 AM, Byron Campen wrote:

Then bria is broken. From 3261:

lr-param          =  "lr"

Best regards,
Byron Campen



But this NOTIFY is send by Bria, if both side using the bria, then it working fine, does not received this 400 bad request.

Thanks

On Mon, Jul 21, 2008 at 9:18 PM, Byron Campen <bcampen@xxxxxxxxxxxx> wrote:
I bet it is the "lr=on" in the Record-Route. This is invalid.

Best regards,
Byron Campen

Hi all, I try to implement the PRESENCE by reSIProcate - dum, but I send SUBSCRIBE to server, server replied me 202 accepted,
and it send NOTIFY to me later, but the DUM replied "400 Bad request " when it received the NOTIFY, how to do  ?


I have wrote these code:

    mMasterProfile->addSupportedMethod(NOTIFY);
    mMasterProfile->addSupportedMimeType(NOTIFY, Mime("message", "sipfrag"));
    mMasterProfile->addSupportedMimeType(NOTIFY, Mime("application", "simple-message-summary"));
    mMasterProfile->addSupportedMimeType(NOTIFY, Pidf::getStaticType());
    mMasterProfile->addSupportedMimeType(MESSAGE, Mime("text", "plain"));


There is the logs:


SUBSCRIBE sip:testuser2@xxxxxxxxx:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.128:6684;branch=z9hG4bK-d8754z-e563f4337b37c544-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:testuser1@xxxxxxxxxxxxx:6684>
To: <sip:testuser2@xxxxxxxxx:5060>
From: <sip:testuser1@xxxxxxxxx>;tag=e623f860
Call-ID: MTZmMDhlZjViZjEwZGE0NWQ4NWI4YzQwNDUxNmFlM2Y.
CSeq: 2 SUBSCRIBE
Expires: 3600
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, SUBSCRIBE, MESSAGE, INFO
Proxy-Authorization: Digest

username="testuser1",realm="iptel.org",nonce="48843fd748843fcdcc6b39d482de0048ab5656a87988b0a6",uri="sip:testuser2@xxxxxxxxx:5060",response="a3ed7c2d5c27de671c8c5e7c4bc1a84

3",algorithm=MD5
Supported: replaces
User-Agent: TEST PHONE
Event: presence
Content-Length: 0



SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/UDP 192.168.1.128:6684;branch=z9hG4bK-d8754z-6a7fdd0750244763-1---d8754z-;rport=2591;received=222.244.116.11
To: <sip:testuser2@xxxxxxxxx:5060>;tag=b98f1230ac1a7949bf499aa7f9ea7038.49b5
From: <sip:testuser1@xxxxxxxxx>;tag=e623f860
Call-ID: MTZmMDhlZjViZjEwZGE0NWQ4NWI4YzQwNDUxNmFlM2Y.
CSeq: 1 SUBSCRIBE
Proxy-Authenticate: Digest realm="iptel.org", nonce="48843fd748843fcdcc6b39d482de0048ab5656a87988b0a6"
Server: Sip EXpress router (2.1.0-dev21-tcp (i386/linux))
Content-Length: 0
Warning: 392 213.192.59.75:5060 "Noisy feedback tells:  pid=442 req_src_ip=222.244.116.11 req_src_port=2591 in_uri=sip:testuser2@xxxxxxxxx:5060

out_uri=sip:testuser2@xxxxxxxxx:5060 via_cnt==1"




SUBSCRIBE sip:testuser2@xxxxxxxxx:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.128:6684;branch=z9hG4bK-d8754z-e563f4337b37c544-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:testuser1@xxxxxxxxxxxxx:6684>
To: <sip:testuser2@xxxxxxxxx:5060>
From: <sip:testuser1@xxxxxxxxx>;tag=e623f860
Call-ID: MTZmMDhlZjViZjEwZGE0NWQ4NWI4YzQwNDUxNmFlM2Y.
CSeq: 2 SUBSCRIBE
Expires: 3600
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, SUBSCRIBE, MESSAGE, INFO
Proxy-Authorization: Digest

username="testuser1",realm="iptel.org",nonce="48843fd748843fcdcc6b39d482de0048ab5656a87988b0a6",uri="sip:testuser2@xxxxxxxxx:5060",response="a3ed7c2d5c27de671c8c5e7c4bc1a84

3",algorithm=MD5
Supported: replaces
User-Agent: TEST PHONE
Event: presence
Content-Length: 0



SIP/2.0 202 Accepted
Via: SIP/2.0/UDP 192.168.1.128:6684;received=222.244.116.11;branch=z9hG4bK-d8754z-e563f4337b37c544-1---d8754z-;rport=2591
Record-Route: <sip:213.192.59.75;lr;avp=mV0BBwB1YWNfbmF0AQAAAA>
Contact: <sip:testuser2@xxxxxxxxxxxxxx:63999;rinstance=b6d39b43e1696b09>
To: <sip:testuser2@xxxxxxxxx:5060>;tag=a714b457
From: <sip:testuser1@xxxxxxxxx>;tag=e623f860
Call-ID: MTZmMDhlZjViZjEwZGE0NWQ4NWI4YzQwNDUxNmFlM2Y.
CSeq: 2 SUBSCRIBE
Expires: 3600
User-Agent: Bria release 2.3 stamp 47296
Content-Length: 0




NOTIFY sip:testuser1@xxxxxxxxxxxxxx:2591 SIP/2.0
Record-Route: <sip:213.192.59.75;avp=VEABBwB1YWNfbmF0AQAAAAEHAHVhY19uYXQBAAAA;lr=on>
Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK88ec.638e082.0
Via: SIP/2.0/UDP 192.168.1.30:40088;received=222.244.116.11;branch=z9hG4bK-d8754z-9078724d573ba36b-1---d8754z-;rport=63999
Max-Forwards: 16
Contact: <sip:testuser2@xxxxxxxxxxxxxx:63999;rinstance=b6d39b43e1696b09>
To: <sip:testuser1@xxxxxxxxx>;tag=e623f860
From: <sip:testuser2@xxxxxxxxx:5060>;tag=a714b457
Call-ID: MTZmMDhlZjViZjEwZGE0NWQ4NWI4YzQwNDUxNmFlM2Y.
CSeq: 2 NOTIFY
User-Agent: Bria release 2.3 stamp 47296
Subscription-State: pending;expires=3600
Event: presence
Content-Length: 0
P-hint: rr-enforced



SIP/2.0 400 Bad Request
Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK88ec.638e082.0
Via: SIP/2.0/UDP 192.168.1.30:40088;received=222.244.116.11;branch=z9hG4bK-d8754z-9078724d573ba36b-1---d8754z-;rport=63999
To: <sip:testuser1@xxxxxxxxx>;tag=e623f860
From: <sip:testuser2@xxxxxxxxx:5060>;tag=a714b457
Call-ID: MTZmMDhlZjViZjEwZGE0NWQ4NWI4YzQwNDUxNmFlM2Y.
CSeq: 2 NOTIFY
User-Agent: TEST PHONE
Content-Length: 0


NOTIFY sip:testuser1@xxxxxxxxxxxxxx:2591 SIP/2.0
Record-Route: <sip:213.192.59.75;avp=VEABBwB1YWNfbmF0AQAAAAEHAHVhY19uYXQBAAAA;lr=on>
Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK88ec.638e082.0
Via: SIP/2.0/UDP 192.168.1.30:40088;received=222.244.116.11;branch=z9hG4bK-d8754z-9078724d573ba36b-1---d8754z-;rport=63999
Max-Forwards: 16
Contact: <sip:testuser2@xxxxxxxxxxxxxx:63999;rinstance=b6d39b43e1696b09>
To: <sip:testuser1@xxxxxxxxx>;tag=e623f860
From: <sip:testuser2@xxxxxxxxx:5060>;tag=a714b457
Call-ID: MTZmMDhlZjViZjEwZGE0NWQ4NWI4YzQwNDUxNmFlM2Y.
CSeq: 2 NOTIFY
User-Agent: Bria release 2.3 stamp 47296
Subscription-State: pending;expires=3600
Event: presence
Content-Length: 0
P-hint: rr-enforced



SIP/2.0 400 Bad Request
Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK88ec.638e082.0
Via: SIP/2.0/UDP 192.168.1.30:40088;received=222.244.116.11;branch=z9hG4bK-d8754z-9078724d573ba36b-1---d8754z-;rport=63999
To: <sip:testuser1@xxxxxxxxx>;tag=e623f860
From: <sip:testuser2@xxxxxxxxx:5060>;tag=a714b457
Call-ID: MTZmMDhlZjViZjEwZGE0NWQ4NWI4YzQwNDUxNmFlM2Y.
CSeq: 2 NOTIFY
User-Agent: TEST PHONE
Content-Length: 0


_______________________________________________
resiprocate-users mailing list



_______________________________________________
resiprocate-users mailing list
resiprocate-users@xxxxxxxxxxxxxxx
List Archive: http://list.resiprocate.org/archive/resiprocate-users/




There are the logs:
INFO | 20080721-214556.745 | Mytest | RESIP:TRANSPORT | 1292 | 
UdpTransport.cxx:42 | Creating UDP transport host= port=5832 ipv4=1
DEBUG | 20080721-214556.746 | Mytest | RESIP:TRANSPORT | 1292 | 
InternalTransport.cxx:86 | Creating fd=660 V4/UDP
DEBUG | 20080721-214556.746 | Mytest | RESIP:TRANSPORT | 1292 | 
InternalTransport.cxx:94 | Binding to 0.0.0.0
DEBUG | 20080721-214556.746 | Mytest | RESIP:TRANSPORT | 1292 | 
UdpTransport.cxx:60 | No compression library available: Transport: [ V4 
0.0.0.0:5832 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214556.746 | Mytest | RESIP:TRANSPORT | 1292 | 
TransportSelector.cxx:187 | Adding transport: [ V4 0.0.0.0:5832 UDP target 
domain=unspecified mFlowKey=0 ]
DEBUG | 20080721-214556.756 | Mytest | RESIP:DUM | 1292 | UserProfile.cxx:135 | 
Adding credential: realm=iptel.org user=testuser1
DEBUG | 20080721-214556.757 | Mytest | RESIP:DUM | 1292 | UserProfile.cxx:135 | 
Adding credential: realm=iptel.org user=testuser1
INFO | 20080721-214556.758 | Mytest | RESIP:DNS | 1292 | dnsutil.cxx:145 | 
local hostname does not contain a domain part Solar
DEBUG | 20080721-214556.758 | Mytest | RESIP:DUM | 1292 | BaseCreator.cxx:140 | 
BaseCreator::makeInitialRequest: 049DAFB0
DEBUG | 20080721-214556.758 | Mytest | RESIP:DUM | 1292 | 
RegistrationCreator.cxx:31 | RegistrationCreator::RegistrationCreator: 049DAFB0
DEBUG | 20080721-214556.758 | Mytest | RESIP:DUM | 1292 | DialogSet.cxx:52 |  
************* Created DialogSet(UAC)  -- 
Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.-b1487334*************
DEBUG | 20080721-214556.759 | Mytest | RESIP:DUM | 1292 | 
DialogUsageManager.cxx:832 | SEND:

REGISTER sip:iptel.org SIP/2.0

Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-2a0d1f0f85157409-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1;rinstance=5a32c11814645e3b>


To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=b1487334

Call-ID: Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.

CSeq: 1 REGISTER

Expires: 90


Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, 
SUBSCRIBE, MESSAGE, INFO

Supported: replaces

User-Agent: My TESTPHONE

Content-Length: 0




DEBUG | 20080721-214556.759 | Mytest | RESIP:DUM | 1292 | DialogId.cxx:50 | 
DialogId::DialogId: Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.-b1487334-
DEBUG | 20080721-214556.759 | Mytest | RESIP:DUM | 1292 | 
DialogUsageManager.cxx:950 | Using outbound proxy: sip:sip.iptel.org:5060;lr -> 
SipReq:  REGISTER iptel.org tid=2a0d1f0f85157409 cseq=REGISTER 
contact=testuser1 / 1 from(tu)
DEBUG | 20080721-214556.772 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer F tid=2a0d1f0f85157409 ms=32000
DEBUG | 20080721-214556.772 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:221 | 
DnsResult::lookup sip:sip.iptel.org:5060;lr
DEBUG | 20080721-214556.815 | Mytest | RESIP:DNS | 3516 | dnsstub.cxx:47 | 
sip.iptel.org(A)--> 213.192.59.75
DEBUG | 20080721-214556.816 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer E1 tid=2a0d1f0f85157409 ms=500
DEBUG | 20080721-214556.819 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:559 | Looked up source for destination: [ V4 
213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ] -> [ V4 
192.168.1.128:0 UDP target domain=sip.iptel.org mFlowKey=0 ] sent-by= 
sent-port=0
DEBUG | 20080721-214556.819 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:1099 | findTransportBySource([ V4 192.168.1.128:0 UDP 
target domain=sip.iptel.org mFlowKey=0 ])
DEBUG | 20080721-214556.819 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:1211 | findTransport (any port, any interface) => 
Transport: [ V4 0.0.0.0:5832 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214556.819 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:932 | Transmitting to [ V4 213.192.59.75:5060 UDP target 
domain=sip.iptel.org mFlowKey=0 ] tlsDomain= via [ V4 192.168.1.128:5832 UDP 
target domain=sip.iptel.org mFlowKey=0 ]

REGISTER sip:iptel.org SIP/2.0

Via: SIP/2.0/UDP 
192.168.1.128:5832;branch=z9hG4bK-d8754z-2a0d1f0f85157409-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1@xxxxxxxxxxxxx:5832;rinstance=5a32c11814645e3b>


To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=b1487334

Call-ID: Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.

CSeq: 1 REGISTER

Expires: 90


Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, 
SUBSCRIBE, MESSAGE, INFO

Supported: replaces

User-Agent: My TESTPHONE

Content-Length: 0




DEBUG | 20080721-214556.819 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP 
target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214557.316 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer E1 tid=2a0d1f0f85157409 ms=1000
DEBUG | 20080721-214557.316 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP 
target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214557.329 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target 
domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214557.329 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:213 | 
Whitelisting sip.iptel.org(1): 213.192.59.75
DEBUG | 20080721-214557.329 | Mytest | RESIP:TRANSACTION | 3516 | 
TransactionState.cxx:1956 | Send to TU: TU: DialogUsageManager size=0

SIP/2.0 401 Unauthorized

Via: SIP/2.0/UDP 
192.168.1.128:5832;branch=z9hG4bK-d8754z-2a0d1f0f85157409-1---d8754z-;rport=6869;received=118.249.70.70

To: <sip:testuser1@xxxxxxxxx>;tag=b98f1230ac1a7949bf499aa7f9ea7038.8366

From: <sip:testuser1@xxxxxxxxx>;tag=b1487334

Call-ID: Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.

CSeq: 1 REGISTER

Expires: 600

Min-Expires: 240


Server: Sip EXpress router (2.1.0-dev21-tcp (i386/linux))

Warning: 392 213.192.59.75:5060 "Noisy feedback tells:  pid=446 
req_src_ip=118.249.70.70 req_src_port=6869 in_uri=sip:iptel.org 
out_uri=sip:iptel.org via_cnt==1"

WWW-Authenticate: Digest realm="iptel.org", 
nonce="48849323488493195018c1cb4abc781d84efbdd645340891"

Content-Length: 0




DEBUG | 20080721-214557.330 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer K tid=2a0d1f0f85157409 ms=5000
INFO | 20080721-214557.341 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:1299 | Got: SipResp: 401 tid=2a0d1f0f85157409 
cseq=REGISTER / 1 from(wire)
DEBUG | 20080721-214557.341 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:1807 | DialogUsageManager::processResponse:

SipResp: 401 tid=2a0d1f0f85157409 cseq=REGISTER / 1 from(wire)
DEBUG | 20080721-214557.341 | Mytest | RESIP:DUM | 3536 | 
ClientAuthManager.cxx:224 | ClientAuthManager::RealmState::handleAuth: 04B87168 
Digest 
realm="iptel.org",nonce="48849323488493195018c1cb4abc781d84efbdd645340891" is 
proxy: 0
DEBUG | 20080721-214557.341 | Mytest | RESIP:DUM | 3536 | 
ClientAuthManager.cxx:198 | ClientAuthManager::RealmState::transition from 
invalid to current
DEBUG | 20080721-214557.341 | Mytest | RESIP:DUM | 3536 | UserProfile.cxx:158 | 
Found credential for realm: realm=iptel.org user=testuser1iptel.org
DEBUG | 20080721-214557.341 | Mytest | RESIP:DUM | 3536 | 
ClientAuthManager.cxx:63 | Produced response to digest challenge for 
UserProfile: <sip:testuser1@xxxxxxxxx>[realm=iptel.org user=testuser1]
DEBUG | 20080721-214557.341 | Mytest | RESIP:DUM | 3536 | DialogSet.cxx:252 | 
about to re-send request with digest credentials
DEBUG | 20080721-214557.341 | Mytest | RESIP:DUM | 3536 | 
ClientAuthManager.cxx:319 |  Add auth, 04B87168 in response to: Digest 
realm="iptel.org",nonce="48849323488493195018c1cb4abc781d84efbdd645340891"
DEBUG | 20080721-214557.342 | Mytest | RESIP:DUM | 3536 | 
ClientAuthManager.cxx:344 | ClientAuthManager::RealmState::addAuthentication, 
proxy: 0 Digest 
username="testuser1",realm="iptel.org",nonce="48849323488493195018c1cb4abc781d84efbdd645340891",uri="sip:iptel.org",response="9926d72e1539e864d65a40ac9e93d5a8",algorithm=MD5
DEBUG | 20080721-214557.342 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:832 | SEND:

REGISTER sip:iptel.org SIP/2.0

Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-7b44914a11580078-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1;rinstance=5a32c11814645e3b>


To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=b1487334

Call-ID: Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.

CSeq: 2 REGISTER

Expires: 90


Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, 
SUBSCRIBE, MESSAGE, INFO

Supported: replaces

User-Agent: My TESTPHONE

Authorization: Digest 
username="testuser1",realm="iptel.org",nonce="48849323488493195018c1cb4abc781d84efbdd645340891",uri="sip:iptel.org",response="9926d72e1539e864d65a40ac9e93d5a8",algorithm=MD5

Content-Length: 0




DEBUG | 20080721-214557.342 | Mytest | RESIP:DUM | 3536 | DialogId.cxx:50 | 
DialogId::DialogId: Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.-b1487334-
DEBUG | 20080721-214557.342 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:950 | Using outbound proxy: sip:sip.iptel.org:5060;lr -> 
SipReq:  REGISTER iptel.org tid=7b44914a11580078 cseq=REGISTER 
contact=testuser1 / 2 from(tu)
DEBUG | 20080721-214557.355 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer F tid=7b44914a11580078 ms=32000
DEBUG | 20080721-214557.355 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:221 | 
DnsResult::lookup sip:sip.iptel.org:5060;lr
DEBUG | 20080721-214557.355 | Mytest | RESIP:DNS | 3516 | dnsstub.cxx:47 | 
sip.iptel.org(A)--> 213.192.59.75
DEBUG | 20080721-214557.355 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer E1 tid=7b44914a11580078 ms=500
DEBUG | 20080721-214557.358 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:559 | Looked up source for destination: [ V4 
213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ] -> [ V4 
192.168.1.128:0 UDP target domain=sip.iptel.org mFlowKey=0 ] sent-by= 
sent-port=0
DEBUG | 20080721-214557.358 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:1099 | findTransportBySource([ V4 192.168.1.128:0 UDP 
target domain=sip.iptel.org mFlowKey=0 ])
DEBUG | 20080721-214557.358 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:1211 | findTransport (any port, any interface) => 
Transport: [ V4 0.0.0.0:5832 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214557.359 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:932 | Transmitting to [ V4 213.192.59.75:5060 UDP target 
domain=sip.iptel.org mFlowKey=0 ] tlsDomain= via [ V4 192.168.1.128:5832 UDP 
target domain=sip.iptel.org mFlowKey=0 ]

REGISTER sip:iptel.org SIP/2.0

Via: SIP/2.0/UDP 
192.168.1.128:5832;branch=z9hG4bK-d8754z-7b44914a11580078-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1@xxxxxxxxxxxxx:5832;rinstance=5a32c11814645e3b>


To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=b1487334

Call-ID: Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.

CSeq: 2 REGISTER

Expires: 90


Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, 
SUBSCRIBE, MESSAGE, INFO

Supported: replaces

User-Agent: My TESTPHONE

Authorization: Digest 
username="testuser1",realm="iptel.org",nonce="48849323488493195018c1cb4abc781d84efbdd645340891",uri="sip:iptel.org",response="9926d72e1539e864d65a40ac9e93d5a8",algorithm=MD5

Content-Length: 0




DEBUG | 20080721-214557.359 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP 
target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214557.823 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target 
domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214557.855 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer E1 tid=7b44914a11580078 ms=1000
DEBUG | 20080721-214557.855 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP 
target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214557.870 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target 
domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214557.871 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:213 | 
Whitelisting sip.iptel.org(1): 213.192.59.75
DEBUG | 20080721-214557.871 | Mytest | RESIP:DNS | 3516 | rrvip.cxx:129 | 
updating an existing vip: 213.192.59.75 with 213.192.59.75
DEBUG | 20080721-214557.871 | Mytest | RESIP:TRANSACTION | 3516 | 
TransactionState.cxx:1956 | Send to TU: TU: DialogUsageManager size=0

SIP/2.0 200 OK

Via: SIP/2.0/UDP 
192.168.1.128:5832;branch=z9hG4bK-d8754z-7b44914a11580078-1---d8754z-;rport=6869;received=118.249.70.70

Contact: 
<sip:testuser1@xxxxxxxxxxxxx:5316;rinstance=ff37316fef4b991b>;expires=85;received="sip:118.249.70.70:6864;dstip=213.192.59.75;dstport=5060"

Contact: 
<sip:testuser1@xxxxxxxxxxxxx:5832;rinstance=5a32c11814645e3b>;expires=240;received="sip:118.249.70.70:6869;dstip=213.192.59.75;dstport=5060"

To: <sip:testuser1@xxxxxxxxx>;tag=b98f1230ac1a7949bf499aa7f9ea7038.2446

From: <sip:testuser1@xxxxxxxxx>;tag=b1487334

Call-ID: Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.

CSeq: 2 REGISTER

Expires: 600

Min-Expires: 240


Server: Sip EXpress router (2.1.0-dev21-tcp (i386/linux))

Warning: 392 213.192.59.75:5060 "Noisy feedback tells:  pid=446 
req_src_ip=118.249.70.70 req_src_port=6869 in_uri=sip:iptel.org 
out_uri=sip:iptel.org via_cnt==1"

Content-Length: 0




DEBUG | 20080721-214557.871 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer K tid=7b44914a11580078 ms=5000
INFO | 20080721-214557.884 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:1299 | Got: SipResp: 200 tid=7b44914a11580078 
cseq=REGISTER contact=testuser1@xxxxxxxxxxxxx:5316 / 2 from(wire)
DEBUG | 20080721-214557.884 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:1807 | DialogUsageManager::processResponse:

SipResp: 200 tid=7b44914a11580078 cseq=REGISTER 
contact=testuser1@xxxxxxxxxxxxx:5316 / 2 from(wire)
DEBUG | 20080721-214557.884 | Mytest | RESIP:DUM | 3536 | 
ClientAuthManager.cxx:41 | ClientAuthManager::handle: transitioning 
Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.-b1487334to cached
DEBUG | 20080721-214557.884 | Mytest | RESIP:DUM | 3536 | 
ClientAuthManager.cxx:198 | ClientAuthManager::RealmState::transition from 
current to cached
DEBUG | 20080721-214557.884 | Mytest | RESIP:DUM | 3536 | DialogId.cxx:50 | 
DialogId::DialogId: 
Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.-b1487334-b98f1230ac1a7949bf499aa7f9ea7038.2446
DEBUG | 20080721-214557.884 | Mytest | RESIP:DUM | 3536 | 
KeepAliveManager.cxx:19 | First keep alive for: [ V4 213.192.59.75:5060 UDP 
target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214557.884 | Mytest | RESIP:DUM | 3536 | 
KeepAliveManager.cxx:20 | Keepalive interval 35 seconds
DEBUG | 20080721-214557.884 | Mytest | RESIP:DUM | 3536 | 
KeepAliveManager.cxx:21 | Keepalive id 0
DEBUG | 20080721-214557.884 | Mytest | RESIP:TRANSACTION | 3536 | 
TimerQueue.cxx:105 | Adding application timer: [ V4 213.192.59.75:5060 UDP 
target domain=unspecified mFlowKey=660 ](0)
DEBUG | 20080721-214557.884 | Mytest | RESIP:DUM | 3536 | 
ClientRegistration.cxx:397 | Clearing service route ([])
DEBUG | 20080721-214557.885 | Mytest | RESIP:TRANSACTION | 3536 | 
TimerQueue.cxx:105 | Adding application timer: DumTimeout::Registration 
ClientRegistration sip:testuser1@xxxxxxxxx: duration=216 seq=1
DEBUG | 20080721-214557.886 | Mytest | RESIP:DUM | 3536 | BaseCreator.cxx:140 | 
BaseCreator::makeInitialRequest: 04B97730
DEBUG | 20080721-214557.886 | Mytest | RESIP:DUM | 3536 | DialogSet.cxx:52 |  
************* Created DialogSet(UAC)  -- 
ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.-303f4f0b*************
DEBUG | 20080721-214557.886 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:832 | SEND:

SUBSCRIBE sip:testuser1@xxxxxxxxx SIP/2.0

Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-ca443b51a31ccd30-1---d8754z-;rport


Max-Forwards: 70

Contact: <sip:testuser1>

To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=303f4f0b

Call-ID: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.


CSeq: 1 SUBSCRIBE

Expires: 3600

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, 
SUBSCRIBE, MESSAGE, INFO

Supported: replaces

User-Agent: My TESTPHONE

Event: message-summary

Content-Length: 0




DEBUG | 20080721-214557.887 | Mytest | RESIP:DUM | 3536 | DialogId.cxx:50 | 
DialogId::DialogId: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.-303f4f0b-
DEBUG | 20080721-214557.887 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:950 | Using outbound proxy: sip:sip.iptel.org:5060;lr -> 
SipReq:  SUBSCRIBE testuser1@xxxxxxxxx tid=ca443b51a31ccd30 cseq=SUBSCRIBE 
contact=testuser1 / 1 from(tu)
DEBUG | 20080721-214557.896 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer F tid=ca443b51a31ccd30 ms=32000
DEBUG | 20080721-214557.896 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:221 | 
DnsResult::lookup sip:sip.iptel.org:5060;lr
DEBUG | 20080721-214557.896 | Mytest | RESIP:DNS | 3516 | dnsstub.cxx:47 | 
sip.iptel.org(A)--> 213.192.59.75
DEBUG | 20080721-214557.896 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer E1 tid=ca443b51a31ccd30 ms=500
DEBUG | 20080721-214557.899 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:559 | Looked up source for destination: [ V4 
213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ] -> [ V4 
192.168.1.128:0 UDP target domain=sip.iptel.org mFlowKey=0 ] sent-by= 
sent-port=0
DEBUG | 20080721-214557.899 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:1099 | findTransportBySource([ V4 192.168.1.128:0 UDP 
target domain=sip.iptel.org mFlowKey=0 ])
DEBUG | 20080721-214557.899 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:1211 | findTransport (any port, any interface) => 
Transport: [ V4 0.0.0.0:5832 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214557.900 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:932 | Transmitting to [ V4 213.192.59.75:5060 UDP target 
domain=sip.iptel.org mFlowKey=0 ] tlsDomain= via [ V4 192.168.1.128:5832 UDP 
target domain=sip.iptel.org mFlowKey=0 ]

SUBSCRIBE sip:testuser1@xxxxxxxxx SIP/2.0

Via: SIP/2.0/UDP 
192.168.1.128:5832;branch=z9hG4bK-d8754z-ca443b51a31ccd30-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1@xxxxxxxxxxxxx:5832>


To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=303f4f0b

Call-ID: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.


CSeq: 1 SUBSCRIBE

Expires: 3600

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, 
SUBSCRIBE, MESSAGE, INFO

Supported: replaces

User-Agent: My TESTPHONE

Event: message-summary

Content-Length: 0




DEBUG | 20080721-214557.900 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP 
target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214558.369 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target 
domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214558.396 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer E1 tid=ca443b51a31ccd30 ms=1000
DEBUG | 20080721-214558.396 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP 
target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214558.410 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target 
domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214558.410 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:213 | 
Whitelisting sip.iptel.org(1): 213.192.59.75
DEBUG | 20080721-214558.410 | Mytest | RESIP:DNS | 3516 | rrvip.cxx:129 | 
updating an existing vip: 213.192.59.75 with 213.192.59.75
DEBUG | 20080721-214558.410 | Mytest | RESIP:TRANSACTION | 3516 | 
TransactionState.cxx:1956 | Send to TU: TU: DialogUsageManager size=0

SIP/2.0 407 Proxy Authentication Required

Via: SIP/2.0/UDP 
192.168.1.128:5832;branch=z9hG4bK-d8754z-ca443b51a31ccd30-1---d8754z-;rport=6869;received=118.249.70.70

Proxy-Authenticate: Digest realm="iptel.org", 
nonce="488493244884931a1a5be15421aee6ccb1849e65710a8d7a"

To: <sip:testuser1@xxxxxxxxx>;tag=b98f1230ac1a7949bf499aa7f9ea7038.94ed

From: <sip:testuser1@xxxxxxxxx>;tag=303f4f0b

Call-ID: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.

CSeq: 1 SUBSCRIBE


Server: Sip EXpress router (2.1.0-dev21-tcp (i386/linux))

Warning: 392 213.192.59.75:5060 "Noisy feedback tells:  pid=442 
req_src_ip=118.249.70.70 req_src_port=6869 in_uri=sip:testuser1@xxxxxxxxx 
out_uri=sip:testuser1@xxxxxxxxx via_cnt==1"

Content-Length: 0




DEBUG | 20080721-214558.411 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer K tid=ca443b51a31ccd30 ms=5000
INFO | 20080721-214558.428 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:1299 | Got: SipResp: 407 tid=ca443b51a31ccd30 
cseq=SUBSCRIBE / 1 from(wire)
DEBUG | 20080721-214558.428 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:1807 | DialogUsageManager::processResponse:

SipResp: 407 tid=ca443b51a31ccd30 cseq=SUBSCRIBE / 1 from(wire)
DEBUG | 20080721-214558.428 | Mytest | RESIP:DUM | 3536 | 
ClientAuthManager.cxx:224 | ClientAuthManager::RealmState::handleAuth: 04BA06F0 
Digest 
realm="iptel.org",nonce="488493244884931a1a5be15421aee6ccb1849e65710a8d7a" is 
proxy: 1
DEBUG | 20080721-214558.428 | Mytest | RESIP:DUM | 3536 | 
ClientAuthManager.cxx:198 | ClientAuthManager::RealmState::transition from 
invalid to current
DEBUG | 20080721-214558.428 | Mytest | RESIP:DUM | 3536 | UserProfile.cxx:158 | 
Found credential for realm: realm=iptel.org user=testuser1iptel.org
DEBUG | 20080721-214558.428 | Mytest | RESIP:DUM | 3536 | 
ClientAuthManager.cxx:63 | Produced response to digest challenge for 
UserProfile: <sip:testuser1@xxxxxxxxx>[realm=iptel.org user=testuser1]
DEBUG | 20080721-214558.428 | Mytest | RESIP:DUM | 3536 | DialogSet.cxx:252 | 
about to re-send request with digest credentials
DEBUG | 20080721-214558.428 | Mytest | RESIP:DUM | 3536 | 
ClientAuthManager.cxx:319 |  Add auth, 04BA06F0 in response to: Digest 
realm="iptel.org",nonce="488493244884931a1a5be15421aee6ccb1849e65710a8d7a"
DEBUG | 20080721-214558.428 | Mytest | RESIP:DUM | 3536 | 
ClientAuthManager.cxx:344 | ClientAuthManager::RealmState::addAuthentication, 
proxy: 1 Digest 
username="testuser1",realm="iptel.org",nonce="488493244884931a1a5be15421aee6ccb1849e65710a8d7a",uri="sip:testuser1@xxxxxxxxx",response="b979e4a9a11ef60728c52c056be6b8d4",algorithm=MD5
DEBUG | 20080721-214558.429 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:832 | SEND:

SUBSCRIBE sip:testuser1@xxxxxxxxx SIP/2.0

Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-3f10b27dee371b0d-1---d8754z-;rport


Max-Forwards: 70

Contact: <sip:testuser1>

To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=303f4f0b

Call-ID: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.


CSeq: 2 SUBSCRIBE

Expires: 3600

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, 
SUBSCRIBE, MESSAGE, INFO

Proxy-Authorization: Digest 
username="testuser1",realm="iptel.org",nonce="488493244884931a1a5be15421aee6ccb1849e65710a8d7a",uri="sip:testuser1@xxxxxxxxx",response="b979e4a9a11ef60728c52c056be6b8d4",algorithm=MD5

Supported: replaces

User-Agent: My TESTPHONE

Event: message-summary

Content-Length: 0




DEBUG | 20080721-214558.429 | Mytest | RESIP:DUM | 3536 | DialogId.cxx:50 | 
DialogId::DialogId: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.-303f4f0b-
DEBUG | 20080721-214558.429 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:950 | Using outbound proxy: sip:sip.iptel.org:5060;lr -> 
SipReq:  SUBSCRIBE testuser1@xxxxxxxxx tid=3f10b27dee371b0d cseq=SUBSCRIBE 
contact=testuser1 / 2 from(tu)
DEBUG | 20080721-214558.436 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer F tid=3f10b27dee371b0d ms=32000
DEBUG | 20080721-214558.436 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:221 | 
DnsResult::lookup sip:sip.iptel.org:5060;lr
DEBUG | 20080721-214558.436 | Mytest | RESIP:DNS | 3516 | dnsstub.cxx:47 | 
sip.iptel.org(A)--> 213.192.59.75
DEBUG | 20080721-214558.436 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer E1 tid=3f10b27dee371b0d ms=500
DEBUG | 20080721-214558.439 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:559 | Looked up source for destination: [ V4 
213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ] -> [ V4 
192.168.1.128:0 UDP target domain=sip.iptel.org mFlowKey=0 ] sent-by= 
sent-port=0
DEBUG | 20080721-214558.439 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:1099 | findTransportBySource([ V4 192.168.1.128:0 UDP 
target domain=sip.iptel.org mFlowKey=0 ])
DEBUG | 20080721-214558.439 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:1211 | findTransport (any port, any interface) => 
Transport: [ V4 0.0.0.0:5832 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214558.439 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:932 | Transmitting to [ V4 213.192.59.75:5060 UDP target 
domain=sip.iptel.org mFlowKey=0 ] tlsDomain= via [ V4 192.168.1.128:5832 UDP 
target domain=sip.iptel.org mFlowKey=0 ]

SUBSCRIBE sip:testuser1@xxxxxxxxx SIP/2.0

Via: SIP/2.0/UDP 
192.168.1.128:5832;branch=z9hG4bK-d8754z-3f10b27dee371b0d-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1@xxxxxxxxxxxxx:5832>


To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=303f4f0b

Call-ID: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.


CSeq: 2 SUBSCRIBE

Expires: 3600

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, 
SUBSCRIBE, MESSAGE, INFO

Proxy-Authorization: Digest 
username="testuser1",realm="iptel.org",nonce="488493244884931a1a5be15421aee6ccb1849e65710a8d7a",uri="sip:testuser1@xxxxxxxxx",response="b979e4a9a11ef60728c52c056be6b8d4",algorithm=MD5

Supported: replaces

User-Agent: My TESTPHONE

Event: message-summary

Content-Length: 0




DEBUG | 20080721-214558.440 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP 
target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214558.936 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer E1 tid=3f10b27dee371b0d ms=1000
DEBUG | 20080721-214558.936 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP 
target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214558.955 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target 
domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214558.955 | Mytest | RESIP:TRANSACTION | 3516 | 
TransactionUser.cxx:66 | Checking if SipReq:  SUBSCRIBE 
testuser1@xxxxxxxxxxxxx:5832 tid=601.ea9a0476.1 cseq=SUBSCRIBE 
contact=testuser1@xxxxxxxxxxxxx:6869 / 2 from(wire) is for me
DEBUG | 20080721-214558.955 | Mytest | RESIP:TRANSACTION | 3516 | 
TransactionUser.cxx:71 | Checking rule...
DEBUG | 20080721-214558.955 | Mytest | RESIP:TRANSACTION | 3516 | 
MessageFilterRule.cxx:42 | Matching rule for:

SUBSCRIBE sip:testuser1@xxxxxxxxxxxxx:5832;rinstance=5a32c11814645e3b SIP/2.0

Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK601.ea9a0476.1

Via: SIP/2.0/UDP 
192.168.1.128:5832;received=118.249.70.70;branch=z9hG4bK-d8754z-3f10b27dee371b0d-1---d8754z-;rport=6869

Max-Forwards: 16

Record-Route: <sip:213.192.59.75;avp=mV0BBwB1YWNfbmF0AQAAAA;lr=on>

Contact: <sip:testuser1@xxxxxxxxxxxxx:6869>


To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=303f4f0b

Call-ID: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.


CSeq: 2 SUBSCRIBE

Expires: 3600

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, 
SUBSCRIBE, MESSAGE, INFO

Supported: replaces

User-Agent: My TESTPHONE

Event: message-summary

Content-Length: 0

P-hint: usrloc applied




DEBUG | 20080721-214558.955 | Mytest | RESIP:TRANSACTION | 3516 | 
TransactionUser.cxx:74 | Match!
DEBUG | 20080721-214558.955 | Mytest | RESIP | 3516 | Helper.cxx:372 | 
Helper::makeResponse(SipReq:  SUBSCRIBE testuser1@xxxxxxxxxxxxx:5832 
tid=601.ea9a0476.1 cseq=SUBSCRIBE contact=testuser1@xxxxxxxxxxxxx:6869 / 2 
from(wire) code=100 reason=
DEBUG | 20080721-214558.956 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer Trying tid=601.ea9a0476.1 ms=3500
DEBUG | 20080721-214558.956 | Mytest | RESIP:TRANSACTION | 3516 | 
TransactionState.cxx:1956 | Send to TU: TU: DialogUsageManager size=0

SUBSCRIBE sip:testuser1@xxxxxxxxxxxxx:5832;rinstance=5a32c11814645e3b SIP/2.0

Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK601.ea9a0476.1

Via: SIP/2.0/UDP 
192.168.1.128:5832;received=118.249.70.70;branch=z9hG4bK-d8754z-3f10b27dee371b0d-1---d8754z-;rport=6869

Max-Forwards: 16

Record-Route: <sip:213.192.59.75;avp=mV0BBwB1YWNfbmF0AQAAAA;lr=on>

Contact: <sip:testuser1@xxxxxxxxxxxxx:6869>


To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=303f4f0b

Call-ID: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.


CSeq: 2 SUBSCRIBE

Expires: 3600

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, 
SUBSCRIBE, MESSAGE, INFO

Supported: replaces

User-Agent: My TESTPHONE

Event: message-summary

Content-Length: 0

P-hint: usrloc applied




INFO | 20080721-214558.972 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:1299 | Got: SipReq:  SUBSCRIBE 
testuser1@xxxxxxxxxxxxx:5832 tid=601.ea9a0476.1 cseq=SUBSCRIBE 
contact=testuser1@xxxxxxxxxxxxx:6869 / 2 from(wire)
DEBUG | 20080721-214558.972 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:1617 | DialogUsageManager::processRequest: SipReq:  
SUBSCRIBE testuser1@xxxxxxxxxxxxx:5832 tid=601.ea9a0476.1 cseq=SUBSCRIBE 
contact=testuser1@xxxxxxxxxxxxx:6869 / 2 from(wire)
DEBUG | 20080721-214558.972 | Mytest | RESIP:DUM | 3536 | DialogSetId.cxx:34 | 
********** Generated Local Tag ***********
INFO | 20080721-214558.972 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:1885 | No handler for event package for SUBSCRIBE: 
message-summary
DEBUG | 20080721-214558.972 | Mytest | RESIP | 3536 | Helper.cxx:372 | 
Helper::makeResponse(SipReq:  SUBSCRIBE testuser1@xxxxxxxxxxxxx:5832 
tid=601.ea9a0476.1 cseq=SUBSCRIBE contact=testuser1@xxxxxxxxxxxxx:6869 / 2 
from(wire) code=489 reason=
DEBUG | 20080721-214558.972 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:832 | SEND:

SIP/2.0 489 Event Package Not Supported

Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK601.ea9a0476.1

Via: SIP/2.0/UDP 
192.168.1.128:5832;received=118.249.70.70;branch=z9hG4bK-d8754z-3f10b27dee371b0d-1---d8754z-;rport=6869

To: <sip:testuser1@xxxxxxxxx>;tag=1b004f18

From: <sip:testuser1@xxxxxxxxx>;tag=303f4f0b

Call-ID: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.

CSeq: 2 SUBSCRIBE

User-Agent: My TESTPHONE

Content-Length: 0




DEBUG | 20080721-214558.972 | Mytest | RESIP | 3536 | SipStack.cxx:289 | SEND: 
SipResp: 489 tid=601.ea9a0476.1 cseq=SUBSCRIBE / 2 from(tu)
INFO | 20080721-214558.973 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:1725 | Rejecting request (unsupported package) SipReq:  
SUBSCRIBE testuser1@xxxxxxxxxxxxx:5832 tid=601.ea9a0476.1 cseq=SUBSCRIBE 
contact=testuser1@xxxxxxxxxxxxx:6869 / 2 from(wire)
DEBUG | 20080721-214558.981 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer J tid=601.ea9a0476.1 ms=32000
DEBUG | 20080721-214558.984 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:559 | Looked up source for destination: [ V4 
213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ] -> [ V4 
192.168.1.128:0 UDP target domain=unspecified mFlowKey=660 ] 
sent-by=213.192.59.75 sent-port=0
DEBUG | 20080721-214558.984 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:932 | Transmitting to [ V4 213.192.59.75:5060 UDP target 
domain=unspecified mFlowKey=660 ] tlsDomain= via [ V4 192.168.1.128:5832 UDP 
target domain=unspecified mFlowKey=660 ]

SIP/2.0 489 Event Package Not Supported

Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK601.ea9a0476.1

Via: SIP/2.0/UDP 
192.168.1.128:5832;received=118.249.70.70;branch=z9hG4bK-d8754z-3f10b27dee371b0d-1---d8754z-;rport=6869

To: <sip:testuser1@xxxxxxxxx>;tag=1b004f18

From: <sip:testuser1@xxxxxxxxx>;tag=303f4f0b

Call-ID: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.

CSeq: 2 SUBSCRIBE

User-Agent: My TESTPHONE

Content-Length: 0




DEBUG | 20080721-214558.984 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP 
target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214559.422 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target 
domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214559.423 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP 
target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214559.936 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer E1 tid=3f10b27dee371b0d ms=2000
DEBUG | 20080721-214559.936 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP 
target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214601.936 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer E1 tid=3f10b27dee371b0d ms=4000
DEBUG | 20080721-214601.936 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP 
target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214603.580 | Mytest | RESIP:DUM | 1292 | BaseCreator.cxx:140 | 
BaseCreator::makeInitialRequest: 04B8D5A0
DEBUG | 20080721-214603.580 | Mytest | RESIP:DUM | 1292 | DialogSet.cxx:52 |  
************* Created DialogSet(UAC)  -- 
ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.-5d10f801*************
DEBUG | 20080721-214603.580 | Mytest | RESIP:DUM | 1292 | 
DialogUsageManager.cxx:832 | SEND:

SUBSCRIBE sip:testuser2@xxxxxxxxx:5060 SIP/2.0

Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-fa6010448f69c01e-1---d8754z-;rport


Max-Forwards: 70

Contact: <sip:testuser1>

To: <sip:testuser2@xxxxxxxxx:5060>

From: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.


CSeq: 1 SUBSCRIBE

Expires: 3600

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, 
SUBSCRIBE, MESSAGE, INFO

Supported: replaces

User-Agent: My TESTPHONE


Event: presence

Content-Length: 0




DEBUG | 20080721-214603.581 | Mytest | RESIP:DUM | 1292 | DialogId.cxx:50 | 
DialogId::DialogId: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.-5d10f801-
DEBUG | 20080721-214603.581 | Mytest | RESIP:DUM | 1292 | 
DialogUsageManager.cxx:950 | Using outbound proxy: sip:sip.iptel.org:5060;lr -> 
SipReq:  SUBSCRIBE testuser2@xxxxxxxxx:5060 tid=fa6010448f69c01e cseq=SUBSCRIBE 
contact=testuser1 / 1 from(tu)
DEBUG | 20080721-214603.586 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer F tid=fa6010448f69c01e ms=32000
DEBUG | 20080721-214603.586 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:221 | 
DnsResult::lookup sip:sip.iptel.org:5060;lr
DEBUG | 20080721-214603.586 | Mytest | RESIP:DNS | 3516 | dnsstub.cxx:47 | 
sip.iptel.org(A)--> 213.192.59.75
DEBUG | 20080721-214603.586 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer E1 tid=fa6010448f69c01e ms=500
DEBUG | 20080721-214603.589 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:559 | Looked up source for destination: [ V4 
213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ] -> [ V4 
192.168.1.128:0 UDP target domain=sip.iptel.org mFlowKey=0 ] sent-by= 
sent-port=0
DEBUG | 20080721-214603.589 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:1099 | findTransportBySource([ V4 192.168.1.128:0 UDP 
target domain=sip.iptel.org mFlowKey=0 ])
DEBUG | 20080721-214603.589 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:1211 | findTransport (any port, any interface) => 
Transport: [ V4 0.0.0.0:5832 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214603.590 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:932 | Transmitting to [ V4 213.192.59.75:5060 UDP target 
domain=sip.iptel.org mFlowKey=0 ] tlsDomain= via [ V4 192.168.1.128:5832 UDP 
target domain=sip.iptel.org mFlowKey=0 ]


SUBSCRIBE sip:testuser2@xxxxxxxxx:5060 SIP/2.0

Via: SIP/2.0/UDP 
192.168.1.128:5832;branch=z9hG4bK-d8754z-fa6010448f69c01e-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1@xxxxxxxxxxxxx:5832>


To: <sip:testuser2@xxxxxxxxx:5060>

From: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.


CSeq: 1 SUBSCRIBE

Expires: 3600

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, 
SUBSCRIBE, MESSAGE, INFO

Supported: replaces

User-Agent: My TESTPHONE


Event: presence

Content-Length: 0




DEBUG | 20080721-214603.590 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP 
target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214604.086 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer E1 tid=fa6010448f69c01e ms=1000
DEBUG | 20080721-214604.086 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP 
target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214604.100 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target 
domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214604.100 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:213 | 
Whitelisting sip.iptel.org(1): 213.192.59.75
DEBUG | 20080721-214604.100 | Mytest | RESIP:DNS | 3516 | rrvip.cxx:129 | 
updating an existing vip: 213.192.59.75 with 213.192.59.75
DEBUG | 20080721-214604.101 | Mytest | RESIP:TRANSACTION | 3516 | 
TransactionState.cxx:1956 | Send to TU: TU: DialogUsageManager size=0

SIP/2.0 407 Proxy Authentication Required

Via: SIP/2.0/UDP 
192.168.1.128:5832;branch=z9hG4bK-d8754z-fa6010448f69c01e-1---d8754z-;rport=6869;received=118.249.70.70

Proxy-Authenticate: Digest realm="iptel.org", 
nonce="4884932a48849320a41c4cb969e2158398d8030bbd8103cd"

To: <sip:testuser2@xxxxxxxxx:5060>;tag=b98f1230ac1a7949bf499aa7f9ea7038.f2af

From: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.

CSeq: 1 SUBSCRIBE


Server: Sip EXpress router (2.1.0-dev21-tcp (i386/linux))

Warning: 392 213.192.59.75:5060 "Noisy feedback tells:  pid=445 
req_src_ip=118.249.70.70 req_src_port=6869 in_uri=sip:testuser2@xxxxxxxxx:5060 
out_uri=sip:testuser2@xxxxxxxxx:5060 via_cnt==1"

Content-Length: 0




DEBUG | 20080721-214604.101 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer K tid=fa6010448f69c01e ms=5000
INFO | 20080721-214604.109 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:1299 | Got: SipResp: 407 tid=fa6010448f69c01e 
cseq=SUBSCRIBE / 1 from(wire)
DEBUG | 20080721-214604.109 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:1807 | DialogUsageManager::processResponse:

SipResp: 407 tid=fa6010448f69c01e cseq=SUBSCRIBE / 1 from(wire)
DEBUG | 20080721-214604.109 | Mytest | RESIP:DUM | 3536 | 
ClientAuthManager.cxx:224 | ClientAuthManager::RealmState::handleAuth: 04B92348 
Digest 
realm="iptel.org",nonce="4884932a48849320a41c4cb969e2158398d8030bbd8103cd" is 
proxy: 1
DEBUG | 20080721-214604.109 | Mytest | RESIP:DUM | 3536 | 
ClientAuthManager.cxx:198 | ClientAuthManager::RealmState::transition from 
invalid to current
DEBUG | 20080721-214604.109 | Mytest | RESIP:DUM | 3536 | UserProfile.cxx:158 | 
Found credential for realm: realm=iptel.org user=testuser1iptel.org
DEBUG | 20080721-214604.109 | Mytest | RESIP:DUM | 3536 | 
ClientAuthManager.cxx:63 | Produced response to digest challenge for 
UserProfile: <sip:testuser1@xxxxxxxxx>[realm=iptel.org user=testuser1]
DEBUG | 20080721-214604.109 | Mytest | RESIP:DUM | 3536 | DialogSet.cxx:252 | 
about to re-send request with digest credentials
DEBUG | 20080721-214604.109 | Mytest | RESIP:DUM | 3536 | 
ClientAuthManager.cxx:319 |  Add auth, 04B92348 in response to: Digest 
realm="iptel.org",nonce="4884932a48849320a41c4cb969e2158398d8030bbd8103cd"
DEBUG | 20080721-214604.109 | Mytest | RESIP:DUM | 3536 | 
ClientAuthManager.cxx:344 | ClientAuthManager::RealmState::addAuthentication, 
proxy: 1 Digest 
username="testuser1",realm="iptel.org",nonce="4884932a48849320a41c4cb969e2158398d8030bbd8103cd",uri="sip:testuser2@xxxxxxxxx:5060",response="2651b9d65fbc93ea450823f0782e19a1",algorithm=MD5
DEBUG | 20080721-214604.110 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:832 | SEND:

SUBSCRIBE sip:testuser2@xxxxxxxxx:5060 SIP/2.0

Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-4236530a4e4f5c64-1---d8754z-;rport


Max-Forwards: 70

Contact: <sip:testuser1>

To: <sip:testuser2@xxxxxxxxx:5060>

From: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.


CSeq: 2 SUBSCRIBE

Expires: 3600

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, 
SUBSCRIBE, MESSAGE, INFO

Proxy-Authorization: Digest 
username="testuser1",realm="iptel.org",nonce="4884932a48849320a41c4cb969e2158398d8030bbd8103cd",uri="sip:testuser2@xxxxxxxxx:5060",response="2651b9d65fbc93ea450823f0782e19a1",algorithm=MD5

Supported: replaces

User-Agent: My TESTPHONE


Event: presence

Content-Length: 0




DEBUG | 20080721-214604.110 | Mytest | RESIP:DUM | 3536 | DialogId.cxx:50 | 
DialogId::DialogId: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.-5d10f801-
DEBUG | 20080721-214604.110 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:950 | Using outbound proxy: sip:sip.iptel.org:5060;lr -> 
SipReq:  SUBSCRIBE testuser2@xxxxxxxxx:5060 tid=4236530a4e4f5c64 cseq=SUBSCRIBE 
contact=testuser1 / 2 from(tu)
DEBUG | 20080721-214604.126 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer F tid=4236530a4e4f5c64 ms=32000
DEBUG | 20080721-214604.126 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:221 | 
DnsResult::lookup sip:sip.iptel.org:5060;lr
DEBUG | 20080721-214604.126 | Mytest | RESIP:DNS | 3516 | dnsstub.cxx:47 | 
sip.iptel.org(A)--> 213.192.59.75
DEBUG | 20080721-214604.126 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer E1 tid=4236530a4e4f5c64 ms=500
DEBUG | 20080721-214604.129 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:559 | Looked up source for destination: [ V4 
213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ] -> [ V4 
192.168.1.128:0 UDP target domain=sip.iptel.org mFlowKey=0 ] sent-by= 
sent-port=0
DEBUG | 20080721-214604.129 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:1099 | findTransportBySource([ V4 192.168.1.128:0 UDP 
target domain=sip.iptel.org mFlowKey=0 ])
DEBUG | 20080721-214604.129 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:1211 | findTransport (any port, any interface) => 
Transport: [ V4 0.0.0.0:5832 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214604.129 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:932 | Transmitting to [ V4 213.192.59.75:5060 UDP target 
domain=sip.iptel.org mFlowKey=0 ] tlsDomain= via [ V4 192.168.1.128:5832 UDP 
target domain=sip.iptel.org mFlowKey=0 ]


SUBSCRIBE sip:testuser2@xxxxxxxxx:5060 SIP/2.0

Via: SIP/2.0/UDP 
192.168.1.128:5832;branch=z9hG4bK-d8754z-4236530a4e4f5c64-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1@xxxxxxxxxxxxx:5832>


To: <sip:testuser2@xxxxxxxxx:5060>

From: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.


CSeq: 2 SUBSCRIBE

Expires: 3600

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, 
SUBSCRIBE, MESSAGE, INFO

Proxy-Authorization: Digest 
username="testuser1",realm="iptel.org",nonce="4884932a48849320a41c4cb969e2158398d8030bbd8103cd",uri="sip:testuser2@xxxxxxxxx:5060",response="2651b9d65fbc93ea450823f0782e19a1",algorithm=MD5

Supported: replaces

User-Agent: My TESTPHONE


Event: presence

Content-Length: 0




DEBUG | 20080721-214604.129 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP 
target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214604.594 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target 
domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214604.626 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer E1 tid=4236530a4e4f5c64 ms=1000
DEBUG | 20080721-214604.626 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP 
target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214605.259 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target 
domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214605.259 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:213 | 
Whitelisting sip.iptel.org(1): 213.192.59.75
DEBUG | 20080721-214605.259 | Mytest | RESIP:DNS | 3516 | rrvip.cxx:129 | 
updating an existing vip: 213.192.59.75 with 213.192.59.75
DEBUG | 20080721-214605.259 | Mytest | RESIP:TRANSACTION | 3516 | 
TransactionState.cxx:1956 | Send to TU: TU: DialogUsageManager size=0

SIP/2.0 202 Accepted

Via: SIP/2.0/UDP 
192.168.1.128:5832;received=118.249.70.70;branch=z9hG4bK-d8754z-4236530a4e4f5c64-1---d8754z-;rport=6869


Record-Route: <sip:213.192.59.75;lr;avp=mV0BBwB1YWNfbmF0AQAAAA>

Contact: <sip:testuser2@xxxxxxxxxxxxx:6859;rinstance=45696353bb08e939>

To: <sip:testuser2@xxxxxxxxx:5060>;tag=bc2f9e67

From: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.


CSeq: 2 SUBSCRIBE

Expires: 3600

User-Agent: Bria release 2.3 stamp 47296

Content-Length: 0




DEBUG | 20080721-214605.259 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer K tid=4236530a4e4f5c64 ms=5000
DEBUG | 20080721-214605.270 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target 
domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214605.270 | Mytest | RESIP:TRANSACTION | 3516 | 
TransactionUser.cxx:66 | Checking if SipReq:  NOTIFY 
testuser1@xxxxxxxxxxxxx:6869 tid=05d2.26e69a61.0 cseq=NOTIFY 
contact=testuser2@xxxxxxxxxxxxx:6859 / 2 from(wire) is for me
DEBUG | 20080721-214605.270 | Mytest | RESIP:TRANSACTION | 3516 | 
TransactionUser.cxx:71 | Checking rule...
DEBUG | 20080721-214605.270 | Mytest | RESIP:TRANSACTION | 3516 | 
MessageFilterRule.cxx:42 | Matching rule for:

NOTIFY sip:testuser1@xxxxxxxxxxxxx:6869 SIP/2.0

Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK05d2.26e69a61.0

Via: SIP/2.0/UDP 
192.168.1.128:25776;received=118.249.70.70;branch=z9hG4bK-d8754z-4c53f23c17333123-1---d8754z-;rport=6859

Max-Forwards: 16


Record-Route: 
<sip:213.192.59.75;avp=VEABBwB1YWNfbmF0AQAAAAEHAHVhY19uYXQBAAAA;lr=on>

Contact: <sip:testuser2@xxxxxxxxxxxxx:6859;rinstance=45696353bb08e939>

To: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

From: <sip:testuser2@xxxxxxxxx:5060>;tag=bc2f9e67

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.


CSeq: 2 NOTIFY

User-Agent: Bria release 2.3 stamp 47296

Subscription-State: pending;expires=3600

Event: presence

Content-Length: 0

P-hint: rr-enforced




DEBUG | 20080721-214605.271 | Mytest | RESIP:TRANSACTION | 3516 | 
TransactionUser.cxx:74 | Match!
DEBUG | 20080721-214605.271 | Mytest | RESIP | 3516 | Helper.cxx:372 | 
Helper::makeResponse(SipReq:  NOTIFY testuser1@xxxxxxxxxxxxx:6869 
tid=05d2.26e69a61.0 cseq=NOTIFY contact=testuser2@xxxxxxxxxxxxx:6859 / 2 
from(wire) code=100 reason=
DEBUG | 20080721-214605.271 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer Trying tid=05d2.26e69a61.0 ms=3500
DEBUG | 20080721-214605.271 | Mytest | RESIP:TRANSACTION | 3516 | 
TransactionState.cxx:1956 | Send to TU: TU: DialogUsageManager size=1

NOTIFY sip:testuser1@xxxxxxxxxxxxx:6869 SIP/2.0

Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK05d2.26e69a61.0

Via: SIP/2.0/UDP 
192.168.1.128:25776;received=118.249.70.70;branch=z9hG4bK-d8754z-4c53f23c17333123-1---d8754z-;rport=6859

Max-Forwards: 16


Record-Route: 
<sip:213.192.59.75;avp=VEABBwB1YWNfbmF0AQAAAAEHAHVhY19uYXQBAAAA;lr=on>

Contact: <sip:testuser2@xxxxxxxxxxxxx:6859;rinstance=45696353bb08e939>

To: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

From: <sip:testuser2@xxxxxxxxx:5060>;tag=bc2f9e67

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.


CSeq: 2 NOTIFY

User-Agent: Bria release 2.3 stamp 47296

Subscription-State: pending;expires=3600

Event: presence

Content-Length: 0

P-hint: rr-enforced




INFO | 20080721-214605.275 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:1299 | Got: SipResp: 202 tid=4236530a4e4f5c64 
cseq=SUBSCRIBE contact=testuser2@xxxxxxxxxxxxx:6859 / 2 from(wire)
DEBUG | 20080721-214605.275 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:1807 | DialogUsageManager::processResponse:

SipResp: 202 tid=4236530a4e4f5c64 cseq=SUBSCRIBE 
contact=testuser2@xxxxxxxxxxxxx:6859 / 2 from(wire)
DEBUG | 20080721-214605.275 | Mytest | RESIP:DUM | 3536 | 
ClientAuthManager.cxx:41 | ClientAuthManager::handle: transitioning 
ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.-5d10f801to cached
DEBUG | 20080721-214605.275 | Mytest | RESIP:DUM | 3536 | 
ClientAuthManager.cxx:198 | ClientAuthManager::RealmState::transition from 
current to cached
DEBUG | 20080721-214605.275 | Mytest | RESIP:DUM | 3536 | DialogId.cxx:50 | 
DialogId::DialogId: 
ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.-5d10f801-bc2f9e67
DEBUG | 20080721-214605.275 | Mytest | RESIP:DUM | 3536 | DialogSet.cxx:770 | 
mState == 3 Creating a new Dialog from msg:

SIP/2.0 202 Accepted

Via: SIP/2.0/UDP 
192.168.1.128:5832;received=118.249.70.70;branch=z9hG4bK-d8754z-4236530a4e4f5c64-1---d8754z-;rport=6869


Record-Route: <sip:213.192.59.75;lr;avp=mV0BBwB1YWNfbmF0AQAAAA>

Contact: <sip:testuser2@xxxxxxxxxxxxx:6859;rinstance=45696353bb08e939>

To: <sip:testuser2@xxxxxxxxx:5060>;tag=bc2f9e67

From: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.


CSeq: 2 SUBSCRIBE

Expires: 3600

User-Agent: Bria release 2.3 stamp 47296

Content-Length: 0




DEBUG | 20080721-214605.275 | Mytest | RESIP:DUM | 3536 | DialogId.cxx:50 | 
DialogId::DialogId: 
ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.-5d10f801-bc2f9e67
DEBUG | 20080721-214605.275 | Mytest | RESIP:DUM | 3536 | Dialog.cxx:220 | 
************** Created Dialog as UAC **************
DEBUG | 20080721-214605.275 | Mytest | RESIP:DUM | 3536 | Dialog.cxx:221 | 
mRemoteNameAddr: <sip:testuser2@xxxxxxxxx:5060>;tag=bc2f9e67
DEBUG | 20080721-214605.275 | Mytest | RESIP:DUM | 3536 | Dialog.cxx:222 | 
mLocalNameAddr: <sip:testuser1@xxxxxxxxx>;tag=5d10f801
DEBUG | 20080721-214605.275 | Mytest | RESIP:DUM | 3536 | Dialog.cxx:223 | 
mLocalContact: <sip:testuser1>
DEBUG | 20080721-214605.276 | Mytest | RESIP:DUM | 3536 | Dialog.cxx:224 | 
mRemoteTarget: <sip:testuser2@xxxxxxxxxxxxx:6859;rinstance=45696353bb08e939>
DEBUG | 20080721-214605.276 | Mytest | RESIP:DUM | 3536 | Dialog.cxx:227 | 
Dialog::Dialog ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.-5d10f801-bc2f9e67
DEBUG | 20080721-214605.276 | Mytest | RESIP:DUM | 3536 | DialogSet.cxx:808 | 
### Calling CreateAppDialog ###:

SIP/2.0 202 Accepted

Via: SIP/2.0/UDP 
192.168.1.128:5832;received=118.249.70.70;branch=z9hG4bK-d8754z-4236530a4e4f5c64-1---d8754z-;rport=6869


Record-Route: <sip:213.192.59.75;lr;avp=mV0BBwB1YWNfbmF0AQAAAA>

Contact: <sip:testuser2@xxxxxxxxxxxxx:6859;rinstance=45696353bb08e939>

To: <sip:testuser2@xxxxxxxxx:5060>;tag=bc2f9e67

From: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.


CSeq: 2 SUBSCRIBE

Expires: 3600

User-Agent: Bria release 2.3 stamp 47296

Content-Length: 0




DEBUG | 20080721-214605.276 | Mytest | RESIP:DUM | 3536 | Dialog.cxx:328 | 
Dialog::dispatch: SipResp: 202 tid=4236530a4e4f5c64 cseq=SUBSCRIBE 
contact=testuser2@xxxxxxxxxxxxx:6859 / 2 from(wire)
DEBUG | 20080721-214605.276 | Mytest | RESIP:DUM | 3536 | 
KeepAliveManager.cxx:40 | Association added for [ V4 213.192.59.75:5060 UDP 
target domain=unspecified mFlowKey=660 ]
INFO | 20080721-214605.276 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:1299 | Got: SipReq:  NOTIFY testuser1@xxxxxxxxxxxxx:6869 
tid=05d2.26e69a61.0 cseq=NOTIFY contact=testuser2@xxxxxxxxxxxxx:6859 / 2 
from(wire)
DEBUG | 20080721-214605.276 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:1617 | DialogUsageManager::processRequest: SipReq:  
NOTIFY testuser1@xxxxxxxxxxxxx:6869 tid=05d2.26e69a61.0 cseq=NOTIFY 
contact=testuser2@xxxxxxxxxxxxx:6859 / 2 from(wire)
INFO | 20080721-214605.276 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:1677 | Handling in-dialog request: SipReq:  NOTIFY 
testuser1@xxxxxxxxxxxxx:6869 tid=05d2.26e69a61.0 cseq=NOTIFY 
contact=testuser2@xxxxxxxxxxxxx:6859 / 2 from(wire)
DEBUG | 20080721-214605.276 | Mytest | RESIP:DUM | 3536 | DialogId.cxx:50 | 
DialogId::DialogId: 
ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.-5d10f801-bc2f9e67
DEBUG | 20080721-214605.276 | Mytest | RESIP:DUM | 3536 | DialogSet.cxx:467 | 
Found matching dialog mClientSubscriptions(0), mServerSubscriptions(0) for

NOTIFY sip:testuser1@xxxxxxxxxxxxx:6869 SIP/2.0

Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK05d2.26e69a61.0

Via: SIP/2.0/UDP 
192.168.1.128:25776;received=118.249.70.70;branch=z9hG4bK-d8754z-4c53f23c17333123-1---d8754z-;rport=6859

Max-Forwards: 16


Record-Route: 
<sip:213.192.59.75;avp=VEABBwB1YWNfbmF0AQAAAAEHAHVhY19uYXQBAAAA;lr=on>

Contact: <sip:testuser2@xxxxxxxxxxxxx:6859;rinstance=45696353bb08e939>

To: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

From: <sip:testuser2@xxxxxxxxx:5060>;tag=bc2f9e67

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.


CSeq: 2 NOTIFY

User-Agent: Bria release 2.3 stamp 47296

Subscription-State: pending;expires=3600

Event: presence

Content-Length: 0

P-hint: rr-enforced




DEBUG | 20080721-214605.277 | Mytest | RESIP:DUM | 3536 | Dialog.cxx:328 | 
Dialog::dispatch: SipReq:  NOTIFY testuser1@xxxxxxxxxxxxx:6869 
tid=05d2.26e69a61.0 cseq=NOTIFY contact=testuser2@xxxxxxxxxxxxx:6859 / 2 
from(wire)
DEBUG | 20080721-214605.277 | Mytest | RESIP:DUM | 3536 | Dialog.cxx:517 | 
Making subscription (from creator) request: SUBSCRIBE 
sip:testuser2@xxxxxxxxx:5060 SIP/2.0

Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-4236530a4e4f5c64-1---d8754z-;rport


Max-Forwards: 70

Contact: <sip:testuser1>

To: <sip:testuser2@xxxxxxxxx:5060>

From: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.


CSeq: 2 SUBSCRIBE

Expires: 3600

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, 
SUBSCRIBE, MESSAGE, INFO

Proxy-Authorization: Digest 
username="testuser1",realm="iptel.org",nonce="4884932a48849320a41c4cb969e2158398d8030bbd8103cd",uri="sip:testuser2@xxxxxxxxx:5060",response="2651b9d65fbc93ea450823f0782e19a1",algorithm=MD5

Supported: replaces

User-Agent: My TESTPHONE


Event: presence

Content-Length: 0




DEBUG | 20080721-214605.277 | Mytest | RESIP:DUM | 3536 | 
ClientSubscription.cxx:33 | ClientSubscription::ClientSubscription from SipReq: 
 SUBSCRIBE testuser2@xxxxxxxxx:5060 tid=4236530a4e4f5c64 cseq=SUBSCRIBE 
contact=testuser1 / 2 from(tu)
DEBUG | 20080721-214605.277 | Mytest | RESIP:DUM | 3536 | Dialog.cxx:962 | 
Dialog::makeRequest:

SUBSCRIBE sip:testuser2@xxxxxxxxxxxxx:6859;rinstance=45696353bb08e939 SIP/2.0

Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-97021349f01e6101-1---d8754z-;rport

Max-Forwards: 70

Route: <sip:213.192.59.75;lr;avp=mV0BBwB1YWNfbmF0AQAAAA>

Contact: <sip:testuser1>

To: <sip:testuser2@xxxxxxxxx:5060>;tag=bc2f9e67

From: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.

CSeq: 3 SUBSCRIBE


Event: presence

Content-Length: 0




DEBUG | 20080721-214605.278 | Mytest | RESIP:DUM | 3536 | 
ClientSubscription.cxx:59 | ClientSubscription::dispatch SipReq:  NOTIFY 
testuser1@xxxxxxxxxxxxx:6869 tid=05d2.26e69a61.0 cseq=NOTIFY 
contact=testuser2@xxxxxxxxxxxxx:6859 / 2 from(wire)
INFO | 20080721-214605.278 | Mytest | RESIP:DUM | 3536 | 
ClientSubscription.cxx:80 | [ClientSubscription] 
<sip:testuser2@xxxxxxxxx:5060>;tag=bc2f9e67
DEBUG | 20080721-214605.278 | Mytest | RESIP:DUM | 3536 | 
ClientSubscription.cxx:103 | no queued notify
DEBUG | 20080721-214605.278 | Mytest | RESIP:DUM | 3536 | 
ClientSubscription.cxx:282 | No expires header in last request, set to 3600
DEBUG | 20080721-214605.278 | Mytest | RESIP:TRANSACTION | 3536 | 
TimerQueue.cxx:105 | Adding application timer: DumTimeout::Subscription 
ClientSubscription sip:testuser1@xxxxxxxxx: duration=3240 seq=1
INFO | 20080721-214605.278 | Mytest | RESIP:DUM | 3536 | 
ClientSubscription.cxx:353 | [ClientSubscription] reSUBSCRIBE in 3240
DEBUG | 20080721-214605.279 | Mytest | RESIP | 3536 | Helper.cxx:372 | 
Helper::makeResponse(SipReq:  NOTIFY testuser1@xxxxxxxxxxxxx:6869 
tid=05d2.26e69a61.0 cseq=NOTIFY contact=testuser2@xxxxxxxxxxxxx:6859 / 2 
from(wire) code=400 reason=
DEBUG | 20080721-214605.279 | Mytest | RESIP:DUM | 3536 | Dialog.cxx:1025 | 
Dialog::makeResponse:

SIP/2.0 400 Bad Request

Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK05d2.26e69a61.0

Via: SIP/2.0/UDP 
192.168.1.128:25776;received=118.249.70.70;branch=z9hG4bK-d8754z-4c53f23c17333123-1---d8754z-;rport=6859

To: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

From: <sip:testuser2@xxxxxxxxx:5060>;tag=bc2f9e67

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.

CSeq: 2 NOTIFY

Content-Length: 0




DEBUG | 20080721-214605.279 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:832 | SEND:

SIP/2.0 400 Bad Request

Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK05d2.26e69a61.0

Via: SIP/2.0/UDP 
192.168.1.128:25776;received=118.249.70.70;branch=z9hG4bK-d8754z-4c53f23c17333123-1---d8754z-;rport=6859

To: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

From: <sip:testuser2@xxxxxxxxx:5060>;tag=bc2f9e67

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.

CSeq: 2 NOTIFY

User-Agent: My TESTPHONE

Content-Length: 0




DEBUG | 20080721-214605.279 | Mytest | RESIP | 3536 | SipStack.cxx:289 | SEND: 
SipResp: 400 tid=05d2.26e69a61.0 cseq=NOTIFY / 2 from(tu)
DEBUG | 20080721-214605.296 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer J tid=05d2.26e69a61.0 ms=32000
DEBUG | 20080721-214605.299 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:559 | Looked up source for destination: [ V4 
213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ] -> [ V4 
192.168.1.128:0 UDP target domain=unspecified mFlowKey=660 ] 
sent-by=213.192.59.75 sent-port=0
DEBUG | 20080721-214605.299 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:932 | Transmitting to [ V4 213.192.59.75:5060 UDP target 
domain=unspecified mFlowKey=660 ] tlsDomain= via [ V4 192.168.1.128:5832 UDP 
target domain=unspecified mFlowKey=660 ]


SIP/2.0 400 Bad Request

Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK05d2.26e69a61.0

Via: SIP/2.0/UDP 
192.168.1.128:25776;received=118.249.70.70;branch=z9hG4bK-d8754z-4c53f23c17333123-1---d8754z-;rport=6859

To: <sip:testuser1@xxxxxxxxx>;tag=5d10f801

From: <sip:testuser2@xxxxxxxxx:5060>;tag=bc2f9e67

Call-ID: ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.

CSeq: 2 NOTIFY

User-Agent: My TESTPHONE

Content-Length: 0




DEBUG | 20080721-214605.299 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP 
target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214605.735 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target 
domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214605.735 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP 
target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214605.936 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer E1 tid=3f10b27dee371b0d ms=4000
DEBUG | 20080721-214605.936 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP 
target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214609.936 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer E1 tid=3f10b27dee371b0d ms=4000
DEBUG | 20080721-214609.936 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP 
target domain=sip.iptel.org mFlowKey=0 ]
DEBUG | 20080721-214610.450 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target 
domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214610.450 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:213 | 
Whitelisting sip.iptel.org(1): 213.192.59.75
DEBUG | 20080721-214610.450 | Mytest | RESIP:DNS | 3516 | rrvip.cxx:129 | 
updating an existing vip: 213.192.59.75 with 213.192.59.75
DEBUG | 20080721-214610.450 | Mytest | RESIP:TRANSACTION | 3516 | 
TransactionState.cxx:1956 | Send to TU: TU: DialogUsageManager size=0

SIP/2.0 407 Proxy Authentication Required

Via: SIP/2.0/UDP 
192.168.1.128:5832;branch=z9hG4bK-d8754z-3f10b27dee371b0d-1---d8754z-;rport=6869;received=118.249.70.70

Proxy-Authenticate: Digest realm="iptel.org", 
nonce="488493304884932623933a4e2eb955b1cd068022fa1de5a7", stale=true

To: <sip:testuser1@xxxxxxxxx>;tag=b98f1230ac1a7949bf499aa7f9ea7038.de44

From: <sip:testuser1@xxxxxxxxx>;tag=303f4f0b

Call-ID: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.

CSeq: 2 SUBSCRIBE


Server: Sip EXpress router (2.1.0-dev21-tcp (i386/linux))

Warning: 392 213.192.59.75:5060 "Noisy feedback tells:  pid=442 
req_src_ip=118.249.70.70 req_src_port=6869 in_uri=sip:testuser1@xxxxxxxxx 
out_uri=sip:testuser1@xxxxxxxxx via_cnt==1"

Content-Length: 0




DEBUG | 20080721-214610.451 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer K tid=3f10b27dee371b0d ms=5000
INFO | 20080721-214610.470 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:1299 | Got: SipResp: 407 tid=3f10b27dee371b0d 
cseq=SUBSCRIBE / 2 from(wire)
DEBUG | 20080721-214610.470 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:1807 | DialogUsageManager::processResponse:

SipResp: 407 tid=3f10b27dee371b0d cseq=SUBSCRIBE / 2 from(wire)
DEBUG | 20080721-214610.470 | Mytest | RESIP:DUM | 3536 | 
ClientAuthManager.cxx:224 | ClientAuthManager::RealmState::handleAuth: 04BA06F0 
Digest 
realm="iptel.org",nonce="488493304884932623933a4e2eb955b1cd068022fa1de5a7",stale=true
 is proxy: 1
DEBUG | 20080721-214610.470 | Mytest | RESIP:DUM | 3536 | 
ClientAuthManager.cxx:236 | Stale nonce:Digest 
realm="iptel.org",nonce="488493304884932623933a4e2eb955b1cd068022fa1de5a7",stale=true
DEBUG | 20080721-214610.470 | Mytest | RESIP:DUM | 3536 | UserProfile.cxx:158 | 
Found credential for realm: realm=iptel.org user=testuser1iptel.org
DEBUG | 20080721-214610.470 | Mytest | RESIP:DUM | 3536 | 
ClientAuthManager.cxx:63 | Produced response to digest challenge for 
UserProfile: <sip:testuser1@xxxxxxxxx>[realm=iptel.org user=testuser1]
DEBUG | 20080721-214610.470 | Mytest | RESIP:DUM | 3536 | DialogSet.cxx:252 | 
about to re-send request with digest credentials
DEBUG | 20080721-214610.470 | Mytest | RESIP:DUM | 3536 | 
ClientAuthManager.cxx:319 |  Add auth, 04BA06F0 in response to: Digest 
realm="iptel.org",nonce="488493304884932623933a4e2eb955b1cd068022fa1de5a7",stale=true
DEBUG | 20080721-214610.470 | Mytest | RESIP:DUM | 3536 | 
ClientAuthManager.cxx:344 | ClientAuthManager::RealmState::addAuthentication, 
proxy: 1 Digest 
username="testuser1",realm="iptel.org",nonce="488493304884932623933a4e2eb955b1cd068022fa1de5a7",uri="sip:testuser1@xxxxxxxxx",response="43778613a42456afb3e15cbbfdc51004",algorithm=MD5
DEBUG | 20080721-214610.471 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:832 | SEND:

SUBSCRIBE sip:testuser1@xxxxxxxxx SIP/2.0

Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-b133ac649443aa33-1---d8754z-;rport


Max-Forwards: 70

Contact: <sip:testuser1>

To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=303f4f0b

Call-ID: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.


CSeq: 3 SUBSCRIBE

Expires: 3600

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, 
SUBSCRIBE, MESSAGE, INFO

Proxy-Authorization: Digest 
username="testuser1",realm="iptel.org",nonce="488493304884932623933a4e2eb955b1cd068022fa1de5a7",uri="sip:testuser1@xxxxxxxxx",response="43778613a42456afb3e15cbbfdc51004",algorithm=MD5

Supported: replaces

User-Agent: My TESTPHONE

Event: message-summary

Content-Length: 0




DEBUG | 20080721-214610.471 | Mytest | RESIP:DUM | 3536 | DialogId.cxx:50 | 
DialogId::DialogId: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.-303f4f0b-
DEBUG | 20080721-214610.471 | Mytest | RESIP:DUM | 3536 | 
DialogUsageManager.cxx:950 | Using outbound proxy: sip:sip.iptel.org:5060;lr -> 
SipReq:  SUBSCRIBE testuser1@xxxxxxxxx tid=b133ac649443aa33 cseq=SUBSCRIBE 
contact=testuser1 / 3 from(tu)
DEBUG | 20080721-214610.476 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer F tid=b133ac649443aa33 ms=32000
DEBUG | 20080721-214610.476 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:221 | 
DnsResult::lookup sip:sip.iptel.org:5060;lr
DEBUG | 20080721-214610.476 | Mytest | RESIP:DNS | 3516 | dnsstub.cxx:47 | 
sip.iptel.org(A)--> 213.192.59.75
DEBUG | 20080721-214610.476 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer E1 tid=b133ac649443aa33 ms=500
DEBUG | 20080721-214610.479 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:559 | Looked up source for destination: [ V4 
213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ] -> [ V4 
192.168.1.128:0 UDP target domain=sip.iptel.org mFlowKey=0 ] sent-by= 
sent-port=0
DEBUG | 20080721-214610.479 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:1099 | findTransportBySource([ V4 192.168.1.128:0 UDP 
target domain=sip.iptel.org mFlowKey=0 ])
DEBUG | 20080721-214610.479 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:1211 | findTransport (any port, any interface) => 
Transport: [ V4 0.0.0.0:5832 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214610.479 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:932 | Transmitting to [ V4 213.192.59.75:5060 UDP target 
domain=sip.iptel.org mFlowKey=0 ] tlsDomain= via [ V4 192.168.1.128:5832 UDP 
target domain=sip.iptel.org mFlowKey=0 ]

SUBSCRIBE sip:testuser1@xxxxxxxxx SIP/2.0

Via: SIP/2.0/UDP 
192.168.1.128:5832;branch=z9hG4bK-d8754z-b133ac649443aa33-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1@xxxxxxxxxxxxx:5832>


To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=303f4f0b

Call-ID: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.


CSeq: 3 SUBSCRIBE

Expires: 3600

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, 
SUBSCRIBE, MESSAGE, INFO

Proxy-Authorization: Digest 
username="testuser1",realm="iptel.org",nonce="488493304884932623933a4e2eb955b1cd068022fa1de5a7",uri="sip:testuser1@xxxxxxxxx",response="43778613a42456afb3e15cbbfdc51004",algorithm=MD5

Supported: replaces

User-Agent: My TESTPHONE

Event: message-summary

Content-Length: 0




DEBUG | 20080721-214610.479 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP 
target domain=sip.iptel.org mFlowKey=0 ]
INFO | 20080721-214610.771 | Mytest | RESIP:DUM | 1292 | 
ClientRegistration.cxx:189 | Removing binding
DEBUG | 20080721-214610.771 | Mytest | RESIP:DUM | 1292 | 
ClientAuthManager.cxx:319 |  Add auth, 04B87168 in response to: Digest 
realm="iptel.org",nonce="48849323488493195018c1cb4abc781d84efbdd645340891"
DEBUG | 20080721-214610.771 | Mytest | RESIP:DUM | 1292 | 
ClientAuthManager.cxx:344 | ClientAuthManager::RealmState::addAuthentication, 
proxy: 0 Digest 
username="testuser1",realm="iptel.org",nonce="48849323488493195018c1cb4abc781d84efbdd645340891",uri="sip:iptel.org",response="9926d72e1539e864d65a40ac9e93d5a8",algorithm=MD5
DEBUG | 20080721-214610.771 | Mytest | RESIP:DUM | 1292 | 
DialogUsageManager.cxx:832 | SEND:

REGISTER sip:iptel.org SIP/2.0

Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-e850ac775b4aee0c-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1;rinstance=5a32c11814645e3b>;expires=0


To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=b1487334

Call-ID: Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.

CSeq: 3 REGISTER


Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, 
SUBSCRIBE, MESSAGE, INFO

Supported: replaces

User-Agent: My TESTPHONE

Authorization: Digest 
username="testuser1",realm="iptel.org",nonce="48849323488493195018c1cb4abc781d84efbdd645340891",uri="sip:iptel.org",response="9926d72e1539e864d65a40ac9e93d5a8",algorithm=MD5

Content-Length: 0




DEBUG | 20080721-214610.772 | Mytest | RESIP:DUM | 1292 | DialogId.cxx:50 | 
DialogId::DialogId: Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.-b1487334-
DEBUG | 20080721-214610.772 | Mytest | RESIP:DUM | 1292 | 
DialogUsageManager.cxx:950 | Using outbound proxy: sip:sip.iptel.org:5060;lr -> 
SipReq:  REGISTER iptel.org tid=e850ac775b4aee0c cseq=REGISTER 
contact=testuser1 / 3 from(tu)
DEBUG | 20080721-214610.780 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer F tid=e850ac775b4aee0c ms=32000
DEBUG | 20080721-214610.780 | Mytest | RESIP:DNS | 3516 | DnsResult.cxx:221 | 
DnsResult::lookup sip:sip.iptel.org:5060;lr
DEBUG | 20080721-214610.780 | Mytest | RESIP:DNS | 3516 | dnsstub.cxx:47 | 
sip.iptel.org(A)--> 213.192.59.75
DEBUG | 20080721-214610.780 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer E1 tid=e850ac775b4aee0c ms=500
DEBUG | 20080721-214610.783 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:559 | Looked up source for destination: [ V4 
213.192.59.75:5060 UDP target domain=sip.iptel.org mFlowKey=0 ] -> [ V4 
192.168.1.128:0 UDP target domain=sip.iptel.org mFlowKey=0 ] sent-by= 
sent-port=0
DEBUG | 20080721-214610.783 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:1099 | findTransportBySource([ V4 192.168.1.128:0 UDP 
target domain=sip.iptel.org mFlowKey=0 ])
DEBUG | 20080721-214610.783 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:1211 | findTransport (any port, any interface) => 
Transport: [ V4 0.0.0.0:5832 UDP target domain=unspecified mFlowKey=660 ]
DEBUG | 20080721-214610.784 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:932 | Transmitting to [ V4 213.192.59.75:5060 UDP target 
domain=sip.iptel.org mFlowKey=0 ] tlsDomain= via [ V4 192.168.1.128:5832 UDP 
target domain=sip.iptel.org mFlowKey=0 ]

REGISTER sip:iptel.org SIP/2.0

Via: SIP/2.0/UDP 
192.168.1.128:5832;branch=z9hG4bK-d8754z-e850ac775b4aee0c-1---d8754z-;rport

Max-Forwards: 70

Contact: <sip:testuser1@xxxxxxxxxxxxx:5832;rinstance=5a32c11814645e3b>;expires=0


To: <sip:testuser1@xxxxxxxxx>

From: <sip:testuser1@xxxxxxxxx>;tag=b1487334

Call-ID: Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.

CSeq: 3 REGISTER


Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE, NOTIFY, REFER, REGISTER, 
SUBSCRIBE, MESSAGE, INFO

Supported: replaces

User-Agent: My TESTPHONE

Authorization: Digest 
username="testuser1",realm="iptel.org",nonce="48849323488493195018c1cb4abc781d84efbdd645340891",uri="sip:iptel.org",response="9926d72e1539e864d65a40ac9e93d5a8",algorithm=MD5

Content-Length: 0




DEBUG | 20080721-214610.784 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP 
target domain=sip.iptel.org mFlowKey=0 ]
WARNING | 20080721-214610.972 | Mytest | RESIP:DUM | 1292 | 
DialogUsageManager.cxx:243 | force shutdown
DEBUG | 20080721-214610.972 | Mytest | RESIP:DUM | 1292 | HandleManager.cxx:93 
| Waiting for usages to be deleted (6)
DEBUG | 20080721-214610.972 | Mytest | RESIP:DUM | 1292 | HandleManager.cxx:96 
| 1 -> AppDialogSet 1
DEBUG | 20080721-214610.972 | Mytest | RESIP:DUM | 1292 | HandleManager.cxx:96 
| 3 -> AppDialogSet 3
DEBUG | 20080721-214610.972 | Mytest | RESIP:DUM | 1292 | HandleManager.cxx:96 
| 5 -> AppDialog 5
DEBUG | 20080721-214610.972 | Mytest | RESIP:DUM | 1292 | HandleManager.cxx:96 
| 2 -> ClientRegistration sip:testuser1@xxxxxxxxx
DEBUG | 20080721-214610.972 | Mytest | RESIP:DUM | 1292 | HandleManager.cxx:96 
| 4 -> AppDialogSet 4
DEBUG | 20080721-214610.972 | Mytest | RESIP:DUM | 1292 | HandleManager.cxx:96 
| 6 -> ClientSubscription sip:testuser1@xxxxxxxxx
INFO | 20080721-214610.972 | Mytest | RESIP:DUM | 1292 | 
DialogUsageManager.cxx:207 | DialogUsageManager::onAllHandlesDestroyed: 
removing TU
DEBUG | 20080721-214610.976 | Mytest | RESIP:TRANSACTION | 3516 | 
TimerQueue.cxx:85 | Adding timer: Timer E1 tid=b133ac649443aa33 ms=1000
DEBUG | 20080721-214610.976 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP 
target domain=sip.iptel.org mFlowKey=0 ]
INFO | 20080721-214610.976 | Mytest | RESIP:TRANSACTION | 3516 | 
TuSelector.cxx:40 | TransactionUserMessage::RemoveTransactionUser TU: 
DialogUsageManager size=0
DEBUG | 20080721-214610.994 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:287 | incoming from: [ V4 213.192.59.75:5060 UDP target 
domain=unspecified mFlowKey=660 ]
INFO | 20080721-214610.994 | Mytest | RESIP:TRANSACTION | 3516 | 
TransactionState.cxx:366 | No TU found for message: SipReq:  SUBSCRIBE 
testuser1@xxxxxxxxxxxxx:5832 tid=701.ee5d38a3.1 cseq=SUBSCRIBE 
contact=testuser1@xxxxxxxxxxxxx:6869 / 3 from(wire)
DEBUG | 20080721-214610.994 | Mytest | RESIP | 3516 | Helper.cxx:372 | 
Helper::makeResponse(SipReq:  SUBSCRIBE testuser1@xxxxxxxxxxxxx:5832 
tid=701.ee5d38a3.1 cseq=SUBSCRIBE contact=testuser1@xxxxxxxxxxxxx:6869 / 3 
from(wire) code=500 reason=
DEBUG | 20080721-214610.998 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:559 | Looked up source for destination: [ V4 
213.192.59.75:5060 UDP target domain=unspecified mFlowKey=660 ] -> [ V4 
192.168.1.128:0 UDP target domain=unspecified mFlowKey=660 ] 
sent-by=213.192.59.75 sent-port=0
DEBUG | 20080721-214610.998 | Mytest | RESIP:TRANSPORT | 3516 | 
TransportSelector.cxx:932 | Transmitting to [ V4 213.192.59.75:5060 UDP target 
domain=unspecified mFlowKey=660 ] tlsDomain= via [ V4 192.168.1.128:5832 UDP 
target domain=unspecified mFlowKey=660 ]

SIP/2.0 500 Server Internal Error

Via: SIP/2.0/UDP 213.192.59.75;branch=z9hG4bK701.ee5d38a3.1

Via: SIP/2.0/UDP 
192.168.1.128:5832;received=118.249.70.70;branch=z9hG4bK-d8754z-b133ac649443aa33-1---d8754z-;rport=6869

To: <sip:testuser1@xxxxxxxxx>;tag=ab586811

From: <sip:testuser1@xxxxxxxxx>;tag=303f4f0b

Call-ID: ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.

CSeq: 3 SUBSCRIBE

Content-Length: 0




DEBUG | 20080721-214610.998 | Mytest | RESIP:TRANSPORT | 3516 | 
Transport.cxx:213 | Adding message to tx buffer to: [ V4 213.192.59.75:5060 UDP 
target domain=unspecified mFlowKey=660 ]
INFO | 20080721-214611.172 | Mytest | RESIP:DUM | 1292 | 
DialogUsageManager.cxx:1107 | TU unregistered
WARNING | 20080721-214611.174 | Mytest | RESIP | 3516 | StackThread.cxx:45 | 
Shutting down stack thread
DEBUG | 20080721-214611.792 | Mytest | RESIP:DUM | 1292 | 
DialogUsageManager.cxx:130 | DialogUsageManager::mDialogSetMap has 3 DialogSets
DEBUG | 20080721-214611.792 | Mytest | RESIP:DUM | 1292 | 
DialogUsageManager.cxx:134 | 
DialgSetId:Njc4MTY2ZDEzYTBmMDRlNDZhN2Q2OTNhZDE4NmFjODU.-b1487334
DEBUG | 20080721-214611.792 | Mytest | RESIP:DUM | 1292 | 
DialogUsageManager.cxx:134 | 
DialgSetId:ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.-5d10f801
DEBUG | 20080721-214611.792 | Mytest | RESIP:DUM | 1292 | 
DialogUsageManager.cxx:139 | 
DialogId:ZDI0ODM1MWI2NDMzODIzYjdmYmZlYTA4Yjc1ZDQ4MzI.-5d10f801-bc2f9e67, 
mClientSubscriptions(1), mServerSubscriptions(0)
DEBUG | 20080721-214611.792 | Mytest | RESIP:DUM | 1292 | 
DialogUsageManager.cxx:134 | 
DialgSetId:ZjMzMzZiYjdhOThjZGMxMTk4MWZhODMxOWJlYWFkYjU.-303f4f0b
DEBUG | 20080721-214611.792 | Mytest | RESIP:DUM | 1292 | 
ClientRegistration.cxx:59 | ClientRegistration::~ClientRegistration
DEBUG | 20080721-214611.792 | Mytest | RESIP:DUM | 1292 | Keep
...