[reSIProcate-users] clientSubscription NOTIFY problem
Hi!
I try to write a simple watcher. I do:
1. registration handler and perform registration -> works.
2. perform outgoing subscription -> works, but incoming NOTIFY are
rejected with "405 Method not support".
For the clientSubscription I have done:
1. made a handler class:
class SubscriptionHandler : public ClientSubscriptionHandler {
virtual void onUpdatePending
...
}
2. define a handler and add it to DUM:
...
DialogUsageManager clientDum(stack);
SubscriptionHandler subscriptionHandler;
clientDum.addClientSubscriptionHandler("presence",
&subscriptionHandler);
...
3. subscribe
SharedPtr<SipMessage> subMessage =
clientDum.makeSubscription(presentityAor, "presence");
clientDum.send( subMessage );
The logs do not show an error (attached). The only strange thing I can
spot is "Failed RequestURI validation NOTIFY sip:test_test@xxxxx
.0.51:32036"
Any hints what I am doing wrong?
thanks
Klaus
PS: How can I log to a file?
C:\Dokumente und Einstellungen\darilion\Eigene Dateien\Visual Studio
2008\Projects\resiprocate-1.6\resip\dum\test\Debug>basicRegister.exe
sip:test_test@a1
.net testtest4711 sip:test_test@xxxxxxxxxxx
INFO | 20100608-123308.005 | | RESIP:DNS | 1432 | dnsutil.cxx:146 | local
hostname does not contain a domain part klauspc745
INFO | 20100608-123308.599 | basicRegister.exe | RESIP:DNS | 1432 |
aresdns.cxx:190 | DNS initialization: found 1 name servers
INFO | 20100608-123308.599 | basicRegister.exe | RESIP:DNS | 1432 |
aresdns.cxx:193 | name server: 10.10.0.1
DEBUG | 20100608-123308.630 | basicRegister.exe | RESIP | 1432 |
Compression.cxx:40 | COMPRESSION SUPPORT NOT COMPILED IN
DEBUG | 20100608-123308.646 | basicRegister.exe | RESIP | 1432 |
Compression.cxx:43 | Compression configuration object created; algorithm = 0
DEBUG | 20100608-123309.099 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
TransportSelector.cxx:93 | No compression library available
DEBUG | 20100608-123309.755 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
InternalTransport.cxx:86 | Creating fd=1744 V4/UDP
DEBUG | 20100608-123309.755 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
InternalTransport.cxx:94 | Binding to 0.0.0.0
INFO | 20100608-123309.755 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
UdpTransport.cxx:48 | Creating UDP transport host= port=32036 ipv4=1
DEBUG | 20100608-123309.771 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
UdpTransport.cxx:62 | No compression library available: Transport: [ V4 0.0.0.0:
32036 UDP target domain=unspecified mFlowKey=1744 ]
DEBUG | 20100608-123309.927 | basicRegister.exe | RESIP | 1432 |
SipStack.cxx:225 | Adding domain alias: 127.0.0.1:32036
DEBUG | 20100608-123309.927 | basicRegister.exe | RESIP | 1432 |
SipStack.cxx:225 | Adding domain alias: 127.0.0.1:32036
DEBUG | 20100608-123309.927 | basicRegister.exe | RESIP | 1432 |
SipStack.cxx:225 | Adding domain alias: 192.168.56.1:32036
DEBUG | 20100608-123309.943 | basicRegister.exe | RESIP | 1432 |
SipStack.cxx:225 | Adding domain alias: 192.168.124.1:32036
DEBUG | 20100608-123309.943 | basicRegister.exe | RESIP | 1432 |
SipStack.cxx:225 | Adding domain alias: 192.168.72.1:32036
DEBUG | 20100608-123309.943 | basicRegister.exe | RESIP | 1432 |
SipStack.cxx:225 | Adding domain alias: 10.10.0.51:32036
DEBUG | 20100608-123309.958 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
TransportSelector.cxx:196 | Adding transport: [ V4 0.0.0.0:32036 UDP target doma
in=unspecified mFlowKey=0 ]
DEBUG | 20100608-123309.974 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
ConnectionBase.cxx:49 | ConnectionBase::ConnectionBase, who: [ V4 0.0.0.0:0 UNKN
OWN_TRANSPORT target domain=unspecified mFlowKey=0 ] 003AD808
DEBUG | 20100608-123309.989 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
ConnectionBase.cxx:62 | No compression library available: 003AD808
DEBUG | 20100608-123309.989 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
ConnectionManager.cxx:24 | ConnectionManager::ConnectionManager() called
DEBUG | 20100608-123309.989 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
InternalTransport.cxx:86 | Creating fd=1536 V4/TCP
DEBUG | 20100608-123309.989 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
InternalTransport.cxx:94 | Binding to 0.0.0.0
INFO | 20100608-123310.005 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
TcpTransport.cxx:31 | Creating TCP transport host= port=8828 ipv4=1
DEBUG | 20100608-123310.005 | basicRegister.exe | RESIP | 1432 |
SipStack.cxx:225 | Adding domain alias: 127.0.0.1:8828
DEBUG | 20100608-123310.005 | basicRegister.exe | RESIP | 1432 |
SipStack.cxx:225 | Adding domain alias: 127.0.0.1:8828
DEBUG | 20100608-123310.021 | basicRegister.exe | RESIP | 1432 |
SipStack.cxx:225 | Adding domain alias: 192.168.56.1:8828
DEBUG | 20100608-123310.021 | basicRegister.exe | RESIP | 1432 |
SipStack.cxx:225 | Adding domain alias: 192.168.124.1:8828
DEBUG | 20100608-123310.021 | basicRegister.exe | RESIP | 1432 |
SipStack.cxx:225 | Adding domain alias: 192.168.72.1:8828
DEBUG | 20100608-123310.021 | basicRegister.exe | RESIP | 1432 |
SipStack.cxx:225 | Adding domain alias: 10.10.0.51:8828
DEBUG | 20100608-123310.021 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
TransportSelector.cxx:196 | Adding transport: [ V4 0.0.0.0:8828 TCP target domai
n=unspecified mFlowKey=0 ]
INFO | 20100608-123310.036 | basicRegister.exe | RESIP:TEST | 1432 |
basicRegister.cxx:159 | main | adding subscriptionHandler ...
INFO | 20100608-123310.036 | basicRegister.exe | RESIP:TEST | 1432 |
basicRegister.cxx:161 | main | adding subscriptionHandler ... done
DEBUG | 20100608-123310.052 | basicRegister.exe | RESIP:DUM | 1432 |
UserProfile.cxx:135 | Adding credential: realm=example.com user=test_test
DEBUG | 20100608-123310.177 | basicRegister.exe | RESIP:DUM | 1432 |
BaseCreator.cxx:155 | BaseCreator::makeInitialRequest:
003ADA10
DEBUG | 20100608-123310.193 | basicRegister.exe | RESIP:DUM | 1432 |
RegistrationCreator.cxx:36 | RegistrationCreator::RegistrationCreator: 003ADA10
STACK | 20100608-123310.224 | basicRegister.exe | RESIP:DUM | 1432 |
Handled.cxx:16 | &&&&&& Handled::Handled 1 this(01D60480) 000E7B60
DEBUG | 20100608-123310.333 | basicRegister.exe | RESIP:DUM | 1432 |
DialogSet.cxx:53 | ************* Created DialogSet(UAC) --
ZTUyNjk5ZGFkYjg1NzU4YWRiM
2JlMzM3OGM1NDQ2YzE.-a8095839*************
STACK | 20100608-123310.333 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:478 | ************* Adding DialogSet ***************
STACK | 20100608-123310.333 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:479 | Before: []
STACK | 20100608-123310.396 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:481 | After:
[ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.-a809
5839 -> 01D60508]
STACK | 20100608-123310.396 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1959 | Looking for dialogSet:
ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3O
GM1NDQ2YzE.-a8095839 in map:
STACK | 20100608-123310.411 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1960 |
[ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.-a8095839 -
> 01D60508]
DEBUG | 20100608-123310.427 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:867 | SEND:
REGISTER sip:example.com SIP/2.0
Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-4d1fda18e0575d27-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:test_test;rinstance=ba04316adc3d8669>
To: <sip:test_test@xxxxxxxxxxx>
From: <sip:test_test@xxxxxxxxxxx>;tag=a8095839
Call-ID: ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.
CSeq: 1 REGISTER
Expires: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE
Content-Length: 0
STACK | 20100608-123310.474 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1959 | Looking for dialogSet:
ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3O
GM1NDQ2YzE.-a8095839 in map:
STACK | 20100608-123310.489 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1960 |
[ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.-a8095839 -
> 01D60508]
DEBUG | 20100608-123310.489 | basicRegister.exe | RESIP:DUM | 1432 |
DialogId.cxx:50 | DialogId::DialogId:
ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.-a80
95839-
STACK | 20100608-123310.505 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1959 | Looking for dialogSet:
ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3O
GM1NDQ2YzE.-a8095839 in map:
STACK | 20100608-123310.505 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1960 |
[ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.-a8095839 -
> 01D60508]
STACK | 20100608-123310.521 | basicRegister.exe | RESIP:DUM | 1432 |
DialogSet.cxx:914 | findDialog:
ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.-a8095839-
in []
DEBUG | 20100608-123310.536 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:986 | Using outbound proxy: sip:sip.example.com;lr ->
SipReq: REGIS
TER example.com tid=4d1fda18e0575d27 cseq=REGISTER contact=test_test / 1
from(tu)
STACK | 20100608-123310.677 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:389 | No matching transaction for SipReq: REGISTER a1.ne
t tid=4d1fda18e0575d27 cseq=REGISTER contact=test_test / 1 from(tu)
STACK | 20100608-123310.693 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:56 | Creating new TransactionState: tid=4d1fda18e0575d27
[ ClientNonInvite/Trying reliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT
target domain=unspecified mFlowKey=0 ]]
STACK | 20100608-123310.708 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:661 | TransactionState::processClientNonInvite: SipReq:
REGISTER example.com tid=4d1fda18e0575d27 cseq=REGISTER contact=test_test / 1
from(tu)
DEBUG | 20100608-123310.739 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TimerQueue.cxx:85 | Adding timer: Timer F tid=4d1fda18e0575d27 ms=32000
STACK | 20100608-123310.739 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:2038 | sendToWire with no dns result: tid=4d1fda18e0575d2
7 [ ClientNonInvite/Trying reliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT
target domain=unspecified mFlowKey=0 ]]
DEBUG | 20100608-123310.880 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:207 | DnsResult::lookup sip:sip.example.com;lr
STACK | 20100608-123310.927 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:395 | DNS query of:sip.example.com NAPTR
STACK | 20100608-123310.927 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:427 | sip.example.com not cached. Doing external dns lookup
DEBUG | 20100608-123310.974 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:68 | sip.example.com lookup failed: DNS server returned answer with
no data
STACK | 20100608-123310.974 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:1293 | Received NAPTR result for: sip: target=sip.example.com
STACK | 20100608-123310.989 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:1294 | DnsResult::onDnsResult() 1
STACK | 20100608-123310.989 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:1239 | NAPTR lookup failed: sip.example.com DNS server returned
answer with n
o data
STACK | 20100608-123311.005 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:1278 | Doing SRV lookup of _sip._tcp.sip.example.com
STACK | 20100608-123311.005 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:1284 | Doing SRV lookup of _sip._udp.sip.example.com
STACK | 20100608-123311.114 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:395 | DNS query of:_sip._tcp.sip.example.com SRV
STACK | 20100608-123311.114 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:427 | _sip._tcp.sip.example.com not cached. Doing external dns
lookup
STACK | 20100608-123311.130 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:395 | DNS query of:_sip._udp.sip.example.com SRV
STACK | 20100608-123311.130 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:427 | _sip._udp.sip.example.com not cached. Doing external dns
lookup
DEBUG | 20100608-123311.130 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:62 | _sip._tcp.sip.example.com lookup failed: Domain name not found
STACK | 20100608-123311.146 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:982 | Received SRV result for: sip.example.com
STACK | 20100608-123311.146 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:985 | DnsResult::onDnsResult() 1 status=4
STACK | 20100608-123311.146 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:1048 | SRV lookup failed: _sip._tcp.sip.example.com 4
DEBUG | 20100608-123311.161 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:62 | _sip._udp.sip.example.com lookup failed: Domain name not found
STACK | 20100608-123311.161 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:982 | Received SRV result for: sip.example.com
STACK | 20100608-123311.177 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:985 | DnsResult::onDnsResult() 0 status=4
STACK | 20100608-123311.177 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:1048 | SRV lookup failed: _sip._udp.sip.example.com 4
STACK | 20100608-123311.177 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:1094 | No SRV records for sip.example.com. Trying A records
STACK | 20100608-123311.286 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:395 | DNS query of:sip.example.com A
STACK | 20100608-123311.302 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:427 | sip.example.com not cached. Doing external dns lookup
DEBUG | 20100608-123311.333 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:44 | sip.example.com(A)--> 33.44.55.109
STACK | 20100608-123311.333 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:772 | Received dns result for: sip.example.com
STACK | 20100608-123311.349 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:773 | DnsResult::onDnsResult() 0
STACK | 20100608-123311.349 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:795 | Adding [ V4 33.44.55.109:5060 UDP target
domain=sip.example.com mFlowKe
y=0 ] to result set
STACK | 20100608-123311.364 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:1865 | tid=4d1fda18e0575d27 [ ClientNonInvite/Trying reli
able target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified
mFlowKey=0 ]] got DNS result: sip.example.com --> [[ V4 33.44.55.109:5060 UDP
target doma
in=sip.example.com mFlowKey=0 ]]
STACK | 20100608-123311.380 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:187 | Returning next dns entry: [ V4 33.44.55.109:5060 UDP target
domain
=sip.example.com mFlowKey=0 ]
STACK | 20100608-123311.380 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:1906 | Unreliable transport: tid=4d1fda18e0575d27 [ Clien
tNonInvite/Trying unreliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target
domain=unspecified mFlowKey=0 ]]
DEBUG | 20100608-123311.396 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TimerQueue.cxx:85 | Adding timer: Timer E1 tid=4d1fda18e0575d27 ms=500
DEBUG | 20100608-123311.396 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
TransportSelector.cxx:566 | Looked up source for destination: [ V4 33.44.55.109:
5060 UDP target domain=sip.example.com mFlowKey=0 ] -> [ V4 10.10.0.51:0 UDP
target domain=sip.example.com mFlowKey=0 ] sent-by= sent-port=0
DEBUG | 20100608-123311.411 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
TransportSelector.cxx:1112 | findTransportBySource([ V4 10.10.0.51:0 UDP target
domain=sip.example.com mFlowKey=0 ])
DEBUG | 20100608-123311.427 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
TransportSelector.cxx:1224 | findTransport (any port, any interface) => Transpor
t: [ V4 0.0.0.0:32036 UDP target domain=unspecified mFlowKey=1744 ]
DEBUG | 20100608-123311.646 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
TransportSelector.cxx:945 | Transmitting to [ V4 33.44.55.109:5060 UDP target do
main=sip.example.com mFlowKey=0 ] tlsDomain= via [ V4 10.10.0.51:32036 UDP
target domain=sip.example.com mFlowKey=0 ]
REGISTER sip:example.com SIP/2.0
Via: SIP/2.0/UDP
10.10.0.51:32036;branch=z9hG4bK-d8754z-4d1fda18e0575d27-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:test_test@xxxxxxxxxx:32036;rinstance=ba04316adc3d8669>
To: <sip:test_test@xxxxxxxxxxx>
From: <sip:test_test@xxxxxxxxxxx>;tag=a8095839
Call-ID: ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.
CSeq: 1 REGISTER
Expires: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE
Content-Length: 0
sigcomp id=
DEBUG | 20100608-123311.677 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
Transport.cxx:213 | Adding message to tx buffer to: [ V4 33.44.55.109:5060 UDP t
arget domain=sip.example.com mFlowKey=0 ]
DEBUG | 20100608-123311.739 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
Transport.cxx:287 | incoming from: [ V4 33.44.55.109:5060 UDP target domain=unsp
ecified mFlowKey=1744 ]
STACK | 20100608-123311.739 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
Transport.cxx:288 |
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP
10.10.0.51:32036;received=11.222.33.3;branch=z9hG4bK-d8754z-4d1fda18e0575d27-1---d8754z-;rport=32036
To: <sip:test_test@xxxxxxxxxxx>;tag=SDq9aqc99-c5c748f403d183b0962387d0a4d9a0
From: <sip:test_test@xxxxxxxxxxx>;tag=a8095839
Call-ID: ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.
CSeq: 1 REGISTER
WWW-Authenticate: Digest
realm="example.com",domain="sip:ttcnserver@xxxxxxxxxxx",nonce="b06b43fa68a5449a3a28b11adeadcd58",stale=false,qop="auth",algorithm=MD5
Content-Length: 0
STACK | 20100608-123311.771 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:354 | Found matching transaction for SipResp: 401 tid=4d1
fda18e0575d27 cseq=REGISTER / 1 from(wire) -> tid=4d1fda18e0575d27 [
ClientNonInvite/Trying unreliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT
target domai
n=unspecified mFlowKey=0 ]]
STACK | 20100608-123311.771 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:661 | TransactionState::processClientNonInvite: SipResp:
401 tid=4d1fda18e0575d27 cseq=REGISTER / 1 from(wire)
DEBUG | 20100608-123311.771 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:199 | Whitelisting sip.example.com(1): 33.44.55.109
DEBUG | 20100608-123311.771 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:2149 | Send to TU: TU: DialogUsageManager size=0
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP
10.10.0.51:32036;received=11.222.33.3;branch=z9hG4bK-d8754z-4d1fda18e0575d27-1---d8754z-;rport=32036
To: <sip:test_test@xxxxxxxxxxx>;tag=SDq9aqc99-c5c748f403d183b0962387d0a4d9a0
From: <sip:test_test@xxxxxxxxxxx>;tag=a8095839
Call-ID: ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.
CSeq: 1 REGISTER
WWW-Authenticate: Digest
realm="example.com",domain="sip:ttcnserver@xxxxxxxxxxx",nonce="b06b43fa68a5449a3a28b11adeadcd58",stale=false,qop="auth",algorithm=MD5
Content-Length: 0
DEBUG | 20100608-123311.786 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TimerQueue.cxx:85 | Adding timer: Timer K tid=4d1fda18e0575d27 ms=5000
INFO | 20100608-123311.802 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1335 | Got: SipResp: 401 tid=4d1fda18e0575d27
cseq=REGISTER / 1
from(wire)
STACK | 20100608-123311.802 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1959 | Looking for dialogSet:
ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3O
GM1NDQ2YzE.-a8095839 in map:
STACK | 20100608-123311.802 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1960 |
[ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.-a8095839 -
> 01D60508]
DEBUG | 20100608-123311.802 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1843 | DialogUsageManager::processResponse:
SipResp: 401 tid=4d1fda18e0575d27 cseq=REGISTER / 1 from(wire)
DEBUG | 20100608-123311.818 | basicRegister.exe | RESIP:DUM | 1432 |
ClientAuthManager.cxx:229 | ClientAuthManager::RealmState::handleAuth: 01D69038
Digest
realm="example.com",domain="sip:ttcnserver@xxxxxxxxxxx",nonce="b06b43fa68a5449a3a28b11adeadcd58",stale=false,qop="auth",algorithm=MD5
is proxy: 0
DEBUG | 20100608-123311.818 | basicRegister.exe | RESIP:DUM | 1432 |
ClientAuthManager.cxx:203 | ClientAuthManager::RealmState::transition from
invalid to
current
DEBUG | 20100608-123311.818 | basicRegister.exe | RESIP:DUM | 1432 |
UserProfile.cxx:158 | Found credential for realm: realm=example.com
user=test_testexample.com
DEBUG | 20100608-123311.818 | basicRegister.exe | RESIP:DUM | 1432 |
ClientAuthManager.cxx:63 | Produced response to digest challenge for
UserProfile: <sip
:test_test@xxxxxxxxxxx>[realm=example.com user=test_test]
DEBUG | 20100608-123311.833 | basicRegister.exe | RESIP:DUM | 1432 |
DialogSet.cxx:256 | about to re-send request with digest credentials
STACK | 20100608-123311.833 | basicRegister.exe | RESIP:DUM | 1432 |
DialogSet.cxx:257 | 003ADA10
STACK | 20100608-123311.833 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1959 | Looking for dialogSet:
ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3O
GM1NDQ2YzE.-a8095839 in map:
STACK | 20100608-123311.833 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1960 |
[ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.-a8095839 -
> 01D60508]
DEBUG | 20100608-123311.849 | basicRegister.exe | RESIP:DUM | 1432 |
ClientAuthManager.cxx:324 | Add auth, 01D69038 in response to: Digest
realm="example.com",
domain="sip:ttcnserver@xxxxxxxxxxx",nonce="b06b43fa68a5449a3a28b11adeadcd58",stale=false,qop="auth",algorithm=MD5
DEBUG | 20100608-123311.989 | basicRegister.exe | RESIP | 1432 |
Helper.cxx:1308 | nonceCount is now: [00000001]
DEBUG | 20100608-123311.989 | basicRegister.exe | RESIP:DUM | 1432 |
ClientAuthManager.cxx:349 | ClientAuthManager::RealmState::addAuthentication,
proxy: 0
Digest
username="test_test",realm="example.com",nonce="b06b43fa68a5449a3a28b11adeadcd58",uri="sip:example.com",response="a7da71933f4fda951a59f47d947339b3",cnonce="
08127d033b3600130d2457167b7b7a0e",nc=00000001,qop=auth,algorithm=MD5
DEBUG | 20100608-123312.005 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:867 | SEND:
REGISTER sip:example.com SIP/2.0
Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-9f2d2c1370450a73-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:test_test;rinstance=ba04316adc3d8669>
To: <sip:test_test@xxxxxxxxxxx>
From: <sip:test_test@xxxxxxxxxxx>;tag=a8095839
Call-ID: ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.
CSeq: 2 REGISTER
Expires: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE
Authorization: Digest
username="test_test",realm="example.com",nonce="b06b43fa68a5449a3a28b11adeadcd58",uri="sip:example.com",response="a7da71933f4fda951a59f47d9473
39b3",cnonce="08127d033b3600130d2457167b7b7a0e",nc=00000001,qop=auth,algorithm=MD5
Content-Length: 0
STACK | 20100608-123312.021 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1959 | Looking for dialogSet:
ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3O
GM1NDQ2YzE.-a8095839 in map:
STACK | 20100608-123312.021 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1960 |
[ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.-a8095839 -
> 01D60508]
DEBUG | 20100608-123312.021 | basicRegister.exe | RESIP:DUM | 1432 |
DialogId.cxx:50 | DialogId::DialogId:
ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.-a80
95839-
STACK | 20100608-123312.036 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1959 | Looking for dialogSet:
ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3O
GM1NDQ2YzE.-a8095839 in map:
STACK | 20100608-123312.036 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1960 |
[ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.-a8095839 -
> 01D60508]
STACK | 20100608-123312.036 | basicRegister.exe | RESIP:DUM | 1432 |
DialogSet.cxx:914 | findDialog:
ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.-a8095839-
in []
DEBUG | 20100608-123312.036 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:986 | Using outbound proxy: sip:sip.example.com;lr ->
SipReq: REGIS
TER example.com tid=9f2d2c1370450a73 cseq=REGISTER contact=test_test / 2
from(tu)
STACK | 20100608-123312.208 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:389 | No matching transaction for SipReq: REGISTER a1.ne
t tid=9f2d2c1370450a73 cseq=REGISTER contact=test_test / 2 from(tu)
STACK | 20100608-123312.208 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:56 | Creating new TransactionState: tid=9f2d2c1370450a73
[ ClientNonInvite/Trying reliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT
target domain=unspecified mFlowKey=0 ]]
STACK | 20100608-123312.224 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:661 | TransactionState::processClientNonInvite: SipReq:
REGISTER example.com tid=9f2d2c1370450a73 cseq=REGISTER contact=test_test / 2
from(tu)
DEBUG | 20100608-123312.239 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TimerQueue.cxx:85 | Adding timer: Timer F tid=9f2d2c1370450a73 ms=32000
STACK | 20100608-123312.239 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:2038 | sendToWire with no dns result: tid=9f2d2c1370450a7
3 [ ClientNonInvite/Trying reliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT
target domain=unspecified mFlowKey=0 ]]
DEBUG | 20100608-123312.255 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:207 | DnsResult::lookup sip:sip.example.com;lr
STACK | 20100608-123312.271 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:354 | Found matching transaction for Timer: Timer E1 500
-> tid=4d1fda18e0575d27 [ ClientNonInvite/Completed unreliable target=[ V4
0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]]
STACK | 20100608-123312.271 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:661 | TransactionState::processClientNonInvite: Timer: Ti
mer E1 500
STACK | 20100608-123312.286 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:395 | DNS query of:sip.example.com NAPTR
DEBUG | 20100608-123312.286 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:68 | sip.example.com lookup failed: DNS server returned answer with
no data
STACK | 20100608-123312.302 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:1293 | Received NAPTR result for: sip: target=sip.example.com
STACK | 20100608-123312.302 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:1294 | DnsResult::onDnsResult() 1
STACK | 20100608-123312.318 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:1239 | NAPTR lookup failed: sip.example.com DNS server returned
answer with n
o data
STACK | 20100608-123312.318 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:1278 | Doing SRV lookup of _sip._tcp.sip.example.com
STACK | 20100608-123312.318 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:1284 | Doing SRV lookup of _sip._udp.sip.example.com
STACK | 20100608-123312.333 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:395 | DNS query of:_sip._tcp.sip.example.com SRV
DEBUG | 20100608-123312.333 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:62 | _sip._tcp.sip.example.com lookup failed: Domain name not found
STACK | 20100608-123312.349 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:982 | Received SRV result for: sip.example.com
STACK | 20100608-123312.349 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:985 | DnsResult::onDnsResult() 1 status=4
STACK | 20100608-123312.349 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:1048 | SRV lookup failed: _sip._tcp.sip.example.com 4
STACK | 20100608-123312.364 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:395 | DNS query of:_sip._udp.sip.example.com SRV
DEBUG | 20100608-123312.364 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:62 | _sip._udp.sip.example.com lookup failed: Domain name not found
STACK | 20100608-123312.364 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:982 | Received SRV result for: sip.example.com
STACK | 20100608-123312.380 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:985 | DnsResult::onDnsResult() 0 status=4
STACK | 20100608-123312.380 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:1048 | SRV lookup failed: _sip._udp.sip.example.com 4
STACK | 20100608-123312.380 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:1094 | No SRV records for sip.example.com. Trying A records
STACK | 20100608-123312.396 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:395 | DNS query of:sip.example.com A
DEBUG | 20100608-123312.396 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:44 | sip.example.com(A)--> 33.44.55.109
STACK | 20100608-123312.411 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:772 | Received dns result for: sip.example.com
STACK | 20100608-123312.411 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:773 | DnsResult::onDnsResult() 0
STACK | 20100608-123312.427 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:795 | Adding [ V4 33.44.55.109:5060 UDP target
domain=sip.example.com mFlowKe
y=0 ] to result set
STACK | 20100608-123312.427 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:1865 | tid=9f2d2c1370450a73 [ ClientNonInvite/Trying reli
able target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified
mFlowKey=0 ]] got DNS result: sip.example.com --> [[ V4 33.44.55.109:5060 UDP
target doma
in=sip.example.com mFlowKey=0 ]]
STACK | 20100608-123312.443 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:187 | Returning next dns entry: [ V4 33.44.55.109:5060 UDP target
domain
=sip.example.com mFlowKey=0 ]
STACK | 20100608-123312.458 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:1906 | Unreliable transport: tid=9f2d2c1370450a73 [ Clien
tNonInvite/Trying unreliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target
domain=unspecified mFlowKey=0 ]]
DEBUG | 20100608-123312.474 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TimerQueue.cxx:85 | Adding timer: Timer E1 tid=9f2d2c1370450a73 ms=500
DEBUG | 20100608-123312.489 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
TransportSelector.cxx:566 | Looked up source for destination: [ V4 33.44.55.109:
5060 UDP target domain=sip.example.com mFlowKey=0 ] -> [ V4 10.10.0.51:0 UDP
target domain=sip.example.com mFlowKey=0 ] sent-by= sent-port=0
DEBUG | 20100608-123312.489 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
TransportSelector.cxx:1112 | findTransportBySource([ V4 10.10.0.51:0 UDP target
domain=sip.example.com mFlowKey=0 ])
DEBUG | 20100608-123312.505 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
TransportSelector.cxx:1224 | findTransport (any port, any interface) => Transpor
t: [ V4 0.0.0.0:32036 UDP target domain=unspecified mFlowKey=1744 ]
DEBUG | 20100608-123312.521 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
TransportSelector.cxx:945 | Transmitting to [ V4 33.44.55.109:5060 UDP target do
main=sip.example.com mFlowKey=0 ] tlsDomain= via [ V4 10.10.0.51:32036 UDP
target domain=sip.example.com mFlowKey=0 ]
REGISTER sip:example.com SIP/2.0
Via: SIP/2.0/UDP
10.10.0.51:32036;branch=z9hG4bK-d8754z-9f2d2c1370450a73-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:test_test@xxxxxxxxxx:32036;rinstance=ba04316adc3d8669>
To: <sip:test_test@xxxxxxxxxxx>
From: <sip:test_test@xxxxxxxxxxx>;tag=a8095839
Call-ID: ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.
CSeq: 2 REGISTER
Expires: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE
Authorization: Digest
username="test_test",realm="example.com",nonce="b06b43fa68a5449a3a28b11adeadcd58",uri="sip:example.com",response="a7da71933f4fda951a59f47d9473
39b3",cnonce="08127d033b3600130d2457167b7b7a0e",nc=00000001,qop=auth,algorithm=MD5
Content-Length: 0
sigcomp id=
DEBUG | 20100608-123312.583 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
Transport.cxx:213 | Adding message to tx buffer to: [ V4 33.44.55.109:5060 UDP t
arget domain=sip.example.com mFlowKey=0 ]
DEBUG | 20100608-123312.677 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
Transport.cxx:287 | incoming from: [ V4 33.44.55.109:5060 UDP target domain=unsp
ecified mFlowKey=1744 ]
STACK | 20100608-123312.693 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
Transport.cxx:288 |
SIP/2.0 200 OK
Via: SIP/2.0/UDP
10.10.0.51:32036;received=11.222.33.3;branch=z9hG4bK-d8754z-9f2d2c1370450a73-1---d8754z-;rport=32036
Contact:
<sip:test_test@xxxxxxxxxx:32036;rinstance=ba04316adc3d8669>;expires=60;q=1
To: <sip:test_test@xxxxxxxxxxx>;tag=SDq9aqc99-c5c748f403d175c0962387d46017e4
From: <sip:test_test@xxxxxxxxxxx>;tag=a8095839
Call-ID: ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.
CSeq: 2 REGISTER
Authentication-Info:
nextnonce="b06b43fa68a5449a3a28b11adeadcd58",qop=auth,rspauth="4ab31292c9e8333e5697cbe427c2033f",cnonce="08127d033b3600130d2457167b7b7
a0e",nc=00000001
P-Associated-URI: <sip:test_test@xxxxxxxxxxx>
P-Associated-URI: <sip:+436641315576@xxxxxxxxxxx>
Content-Length: 0
STACK | 20100608-123312.755 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:354 | Found matching transaction for SipResp: 200 tid=9f2
d2c1370450a73 cseq=REGISTER contact=test_test@xxxxxxxxxx:32036 / 2 from(wire)
-> tid=9f2d2c1370450a73 [ ClientNonInvite/Trying unreliable target=[ V4 0.0.
0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]]
STACK | 20100608-123312.771 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:661 | TransactionState::processClientNonInvite: SipResp:
200 tid=9f2d2c1370450a73 cseq=REGISTER contact=test_test@xxxxxxxxxx:32036 / 2
from(wire)
DEBUG | 20100608-123312.786 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:199 | Whitelisting sip.example.com(1): 33.44.55.109
DEBUG | 20100608-123312.786 | basicRegister.exe | RESIP:DNS | 1432 |
rrvip.cxx:128 | updating an existing vip: 33.44.55.109 with 33.44.55.109
DEBUG | 20100608-123312.786 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:2149 | Send to TU: TU: DialogUsageManager size=0
SIP/2.0 200 OK
Via: SIP/2.0/UDP
10.10.0.51:32036;received=11.222.33.3;branch=z9hG4bK-d8754z-9f2d2c1370450a73-1---d8754z-;rport=32036
Contact:
<sip:test_test@xxxxxxxxxx:32036;rinstance=ba04316adc3d8669>;expires=60;q=1.0
To: <sip:test_test@xxxxxxxxxxx>;tag=SDq9aqc99-c5c748f403d175c0962387d46017e4
From: <sip:test_test@xxxxxxxxxxx>;tag=a8095839
Call-ID: ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.
CSeq: 2 REGISTER
Authentication-Info:
nextnonce="b06b43fa68a5449a3a28b11adeadcd58",qop=auth,rspauth="4ab31292c9e8333e5697cbe427c2033f",cnonce="08127d033b3600130d2457167b7b7
a0e",nc=00000001
P-Associated-URI: <sip:test_test@xxxxxxxxxxx>
P-Associated-URI: <sip:+436641315576@xxxxxxxxxxx>
Content-Length: 0
DEBUG | 20100608-123312.849 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TimerQueue.cxx:85 | Adding timer: Timer K tid=9f2d2c1370450a73 ms=5000
INFO | 20100608-123312.864 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1335 | Got: SipResp: 200 tid=9f2d2c1370450a73
cseq=REGISTER cont
act=test_test@xxxxxxxxxx:32036 / 2 from(wire)
STACK | 20100608-123312.880 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1959 | Looking for dialogSet:
ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3O
GM1NDQ2YzE.-a8095839 in map:
STACK | 20100608-123312.880 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1960 |
[ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.-a8095839 -
> 01D60508]
DEBUG | 20100608-123312.896 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1843 | DialogUsageManager::processResponse:
SipResp: 200 tid=9f2d2c1370450a73 cseq=REGISTER
contact=test_test@xxxxxxxxxx:32036 / 2 from(wire)
DEBUG | 20100608-123312.911 | basicRegister.exe | RESIP:DUM | 1432 |
ClientAuthManager.cxx:41 | ClientAuthManager::handle: transitioning
ZTUyNjk5ZGFkYjg1Nz
U4YWRiM2JlMzM3OGM1NDQ2YzE.-a8095839to cached
DEBUG | 20100608-123312.911 | basicRegister.exe | RESIP:DUM | 1432 |
ClientAuthManager.cxx:203 | ClientAuthManager::RealmState::transition from
current to
cached
DEBUG | 20100608-123312.943 | basicRegister.exe | RESIP:DUM | 1432 |
DialogId.cxx:50 | DialogId::DialogId:
ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.-a80
95839-SDq9aqc99-c5c748f403d175c0962387d46017e4
STACK | 20100608-123312.958 | basicRegister.exe | RESIP:DUM | 1432 |
DialogSet.cxx:535 | No matching dialog for
SIP/2.0 200 OK
Via: SIP/2.0/UDP
10.10.0.51:32036;received=11.222.33.3;branch=z9hG4bK-d8754z-9f2d2c1370450a73-1---d8754z-;rport=32036
Contact:
<sip:test_test@xxxxxxxxxx:32036;rinstance=ba04316adc3d8669>;expires=60;q=1.0
To: <sip:test_test@xxxxxxxxxxx>;tag=SDq9aqc99-c5c748f403d175c0962387d46017e4
From: <sip:test_test@xxxxxxxxxxx>;tag=a8095839
Call-ID: ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.
CSeq: 2 REGISTER
Authentication-Info:
nextnonce="b06b43fa68a5449a3a28b11adeadcd58",qop=auth,rspauth="4ab31292c9e8333e5697cbe427c2033f",cnonce="08127d033b3600130d2457167b7b7
a0e",nc=00000001
P-Associated-URI: <sip:test_test@xxxxxxxxxxx>
P-Associated-URI: <sip:+436641315576@xxxxxxxxxxx>
Content-Length: 0
STACK | 20100608-123313.021 | basicRegister.exe | RESIP:DUM | 1432 |
Handled.cxx:16 | &&&&&& Handled::Handled 2 this(01D6CFD8) 000E7B60
DEBUG | 20100608-123313.193 | basicRegister.exe | RESIP:DUM | 1432 |
KeepAliveManager.cxx:19 | First keep alive for: [ V4 33.44.55.109:5060 UDP
target doma
in=unspecified mFlowKey=1744 ]
DEBUG | 20100608-123313.193 | basicRegister.exe | RESIP:DUM | 1432 |
KeepAliveManager.cxx:20 | Keepalive interval 30 seconds
DEBUG | 20100608-123313.208 | basicRegister.exe | RESIP:DUM | 1432 |
KeepAliveManager.cxx:21 | Keepalive id 0
DEBUG | 20100608-123313.208 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TimerQueue.cxx:105 | Adding application timer: [ V4 33.44.55.109:5060 UDP targ
et domain=unspecified mFlowKey=1744 ](0)
DEBUG | 20100608-123313.224 | basicRegister.exe | RESIP:DUM | 1432 |
ClientRegistration.cxx:403 | Clearing service route ([])
DEBUG | 20100608-123313.224 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TimerQueue.cxx:105 | Adding application timer: DumTimeout::Registration Client
Registration sip:test_test@xxxxxxxxxxx: duration=54 seq=1
INFO | 20100608-123313.239 | basicRegister.exe | RESIP:TEST | 1432 |
basicRegister.cxx:34 | ClientHandler::onSuccess:
Pausing before unregister
DEBUG | 20100608-123313.239 | basicRegister.exe | RESIP:DUM | 1432 |
BaseCreator.cxx:155 | BaseCreator::makeInitialRequest:
01D6D298
STACK | 20100608-123313.286 | basicRegister.exe | RESIP:DUM | 1432 |
Handled.cxx:16 | &&&&&& Handled::Handled 3 this(01D6EDD0) 000E7B60
DEBUG | 20100608-123313.286 | basicRegister.exe | RESIP:DUM | 1432 |
DialogSet.cxx:53 | ************* Created DialogSet(UAC) --
NzI5MzUzMGUxNGMyZDg4YjY0Y
WY5MjhjMTQ0Zjc1ZmE.-7b5ee311*************
STACK | 20100608-123313.302 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:478 | ************* Adding DialogSet ***************
STACK | 20100608-123313.302 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:479 | Before:
[ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.-a80
95839 -> 01D60508]
STACK | 20100608-123313.318 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:481 | After:
[ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.-a809
5839 -> 01D60508, NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjMTQ0Zjc1ZmE.-7b5ee311 ->
01D652B8]
STACK | 20100608-123313.333 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1959 | Looking for dialogSet:
NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjM
TQ0Zjc1ZmE.-7b5ee311 in map:
STACK | 20100608-123313.333 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1960 |
[ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.-a8095839 -
> 01D60508, NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjMTQ0Zjc1ZmE.-7b5ee311 -> 01D652B8]
DEBUG | 20100608-123313.349 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:867 | SEND:
SUBSCRIBE sip:test_test@xxxxxxxxxxx SIP/2.0
Via: SIP/2.0/ ;branch=z9hG4bK-d8754z-d4317d33372cd46b-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:test_test>
To: <sip:test_test@xxxxxxxxxxx>
From: <sip:test_test@xxxxxxxxxxx>;tag=7b5ee311
Call-ID: NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjMTQ0Zjc1ZmE.
CSeq: 1 SUBSCRIBE
Expires: 3600
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE
Event: presence
Content-Length: 0
STACK | 20100608-123313.396 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1959 | Looking for dialogSet:
NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjM
TQ0Zjc1ZmE.-7b5ee311 in map:
STACK | 20100608-123313.411 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1960 |
[ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.-a8095839 -
> 01D60508, NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjMTQ0Zjc1ZmE.-7b5ee311 -> 01D652B8]
DEBUG | 20100608-123313.411 | basicRegister.exe | RESIP:DUM | 1432 |
DialogId.cxx:50 | DialogId::DialogId:
NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjMTQ0Zjc1ZmE.-7b5
ee311-
STACK | 20100608-123313.427 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1959 | Looking for dialogSet:
NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjM
TQ0Zjc1ZmE.-7b5ee311 in map:
STACK | 20100608-123313.443 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1960 |
[ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.-a8095839 -
> 01D60508, NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjMTQ0Zjc1ZmE.-7b5ee311 -> 01D652B8]
STACK | 20100608-123313.443 | basicRegister.exe | RESIP:DUM | 1432 |
DialogSet.cxx:914 | findDialog:
NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjMTQ0Zjc1ZmE.-7b5ee311-
in []
DEBUG | 20100608-123313.458 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:986 | Using outbound proxy: sip:sip.example.com;lr ->
SipReq: SUBSC
RIBE test_test@xxxxxxxxxxx tid=d4317d33372cd46b cseq=SUBSCRIBE
contact=test_test / 1 from(tu)
STACK | 20100608-123313.568 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:389 | No matching transaction for SipReq: SUBSCRIBE test
_test@xxxxxxxxxxx tid=d4317d33372cd46b cseq=SUBSCRIBE contact=test_test / 1
from(tu)
STACK | 20100608-123313.583 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:56 | Creating new TransactionState: tid=d4317d33372cd46b
[ ClientNonInvite/Trying reliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT
target domain=unspecified mFlowKey=0 ]]
STACK | 20100608-123313.599 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:661 | TransactionState::processClientNonInvite: SipReq:
SUBSCRIBE test_test@xxxxxxxxxxx tid=d4317d33372cd46b cseq=SUBSCRIBE
contact=test_test / 1 from(tu)
DEBUG | 20100608-123313.599 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TimerQueue.cxx:85 | Adding timer: Timer F tid=d4317d33372cd46b ms=32000
STACK | 20100608-123313.614 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:2038 | sendToWire with no dns result: tid=d4317d33372cd46
b [ ClientNonInvite/Trying reliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT
target domain=unspecified mFlowKey=0 ]]
DEBUG | 20100608-123313.614 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:207 | DnsResult::lookup sip:sip.example.com;lr
STACK | 20100608-123313.630 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:354 | Found matching transaction for Timer: Timer E1 500
-> tid=9f2d2c1370450a73 [ ClientNonInvite/Completed unreliable target=[ V4
0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]]
STACK | 20100608-123313.630 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:661 | TransactionState::processClientNonInvite: Timer: Ti
mer E1 500
STACK | 20100608-123313.646 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:395 | DNS query of:sip.example.com NAPTR
DEBUG | 20100608-123313.646 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:68 | sip.example.com lookup failed: DNS server returned answer with
no data
STACK | 20100608-123313.661 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:1293 | Received NAPTR result for: sip: target=sip.example.com
STACK | 20100608-123313.661 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:1294 | DnsResult::onDnsResult() 1
STACK | 20100608-123313.661 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:1239 | NAPTR lookup failed: sip.example.com DNS server returned
answer with n
o data
STACK | 20100608-123313.677 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:1278 | Doing SRV lookup of _sip._tcp.sip.example.com
STACK | 20100608-123313.677 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:1284 | Doing SRV lookup of _sip._udp.sip.example.com
STACK | 20100608-123313.693 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:395 | DNS query of:_sip._tcp.sip.example.com SRV
DEBUG | 20100608-123313.693 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:62 | _sip._tcp.sip.example.com lookup failed: Domain name not found
STACK | 20100608-123313.693 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:982 | Received SRV result for: sip.example.com
STACK | 20100608-123313.708 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:985 | DnsResult::onDnsResult() 1 status=4
STACK | 20100608-123313.708 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:1048 | SRV lookup failed: _sip._tcp.sip.example.com 4
STACK | 20100608-123313.708 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:395 | DNS query of:_sip._udp.sip.example.com SRV
DEBUG | 20100608-123313.724 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:62 | _sip._udp.sip.example.com lookup failed: Domain name not found
STACK | 20100608-123313.724 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:982 | Received SRV result for: sip.example.com
STACK | 20100608-123313.739 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:985 | DnsResult::onDnsResult() 0 status=4
STACK | 20100608-123313.739 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:1048 | SRV lookup failed: _sip._udp.sip.example.com 4
STACK | 20100608-123313.739 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:1094 | No SRV records for sip.example.com. Trying A records
STACK | 20100608-123313.755 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:395 | DNS query of:sip.example.com A
DEBUG | 20100608-123313.755 | basicRegister.exe | RESIP:DNS | 1432 |
dnsstub.cxx:44 | sip.example.com(A)--> 33.44.55.109
STACK | 20100608-123313.755 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:772 | Received dns result for: sip.example.com
STACK | 20100608-123313.771 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:773 | DnsResult::onDnsResult() 0
STACK | 20100608-123313.771 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:795 | Adding [ V4 33.44.55.109:5060 UDP target
domain=sip.example.com mFlowKe
y=0 ] to result set
STACK | 20100608-123313.786 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:1865 | tid=d4317d33372cd46b [ ClientNonInvite/Trying reli
able target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified
mFlowKey=0 ]] got DNS result: sip.example.com --> [[ V4 33.44.55.109:5060 UDP
target doma
in=sip.example.com mFlowKey=0 ]]
STACK | 20100608-123313.802 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:187 | Returning next dns entry: [ V4 33.44.55.109:5060 UDP target
domain
=sip.example.com mFlowKey=0 ]
STACK | 20100608-123313.802 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:1906 | Unreliable transport: tid=d4317d33372cd46b [ Clien
tNonInvite/Trying unreliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT target
domain=unspecified mFlowKey=0 ]]
DEBUG | 20100608-123313.818 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TimerQueue.cxx:85 | Adding timer: Timer E1 tid=d4317d33372cd46b ms=500
DEBUG | 20100608-123313.818 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
TransportSelector.cxx:566 | Looked up source for destination: [ V4 33.44.55.109:
5060 UDP target domain=sip.example.com mFlowKey=0 ] -> [ V4 10.10.0.51:0 UDP
target domain=sip.example.com mFlowKey=0 ] sent-by= sent-port=0
DEBUG | 20100608-123313.833 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
TransportSelector.cxx:1112 | findTransportBySource([ V4 10.10.0.51:0 UDP target
domain=sip.example.com mFlowKey=0 ])
DEBUG | 20100608-123313.849 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
TransportSelector.cxx:1224 | findTransport (any port, any interface) => Transpor
t: [ V4 0.0.0.0:32036 UDP target domain=unspecified mFlowKey=1744 ]
DEBUG | 20100608-123313.849 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
TransportSelector.cxx:945 | Transmitting to [ V4 33.44.55.109:5060 UDP target do
main=sip.example.com mFlowKey=0 ] tlsDomain= via [ V4 10.10.0.51:32036 UDP
target domain=sip.example.com mFlowKey=0 ]
SUBSCRIBE sip:test_test@xxxxxxxxxxx SIP/2.0
Via: SIP/2.0/UDP
10.10.0.51:32036;branch=z9hG4bK-d8754z-d4317d33372cd46b-1---d8754z-;rport
Max-Forwards: 70
Contact: <sip:test_test@xxxxxxxxxx:32036>
To: <sip:test_test@xxxxxxxxxxx>
From: <sip:test_test@xxxxxxxxxxx>;tag=7b5ee311
Call-ID: NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjMTQ0Zjc1ZmE.
CSeq: 1 SUBSCRIBE
Expires: 3600
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE
Event: presence
Content-Length: 0
sigcomp id=
DEBUG | 20100608-123313.911 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
Transport.cxx:213 | Adding message to tx buffer to: [ V4 33.44.55.109:5060 UDP t
arget domain=sip.example.com mFlowKey=0 ]
DEBUG | 20100608-123314.021 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
Transport.cxx:287 | incoming from: [ V4 33.44.55.109:5060 UDP target domain=unsp
ecified mFlowKey=1744 ]
STACK | 20100608-123314.021 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
Transport.cxx:288 |
SIP/2.0 200 OK
Via: SIP/2.0/UDP
10.10.0.51:32036;received=11.222.33.3;branch=z9hG4bK-d8754z-d4317d33372cd46b-1---d8754z-;rport=32036
Contact: <sip:test_test@xxxxxxxxxxxx:5060;transport=udp>
To: <sip:test_test@xxxxxxxxxxx>;tag=SDnd62d99-1109
From: <sip:test_test@xxxxxxxxxxx>;tag=7b5ee311
Call-ID: NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjMTQ0Zjc1ZmE.
CSeq: 1 SUBSCRIBE
Expires: 3600
Content-Length: 0
STACK | 20100608-123314.068 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:354 | Found matching transaction for SipResp: 200 tid=d43
17d33372cd46b cseq=SUBSCRIBE contact=test_test@xxxxxxxxxxxx:5060 / 1 from(wire)
-> tid=d4317d33372cd46b [ ClientNonInvite/Trying unreliable target=[ V4 0.
0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]]
STACK | 20100608-123314.083 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:661 | TransactionState::processClientNonInvite: SipResp:
200 tid=d4317d33372cd46b cseq=SUBSCRIBE contact=test_test@xxxxxxxxxxxx:5060 / 1
from(wire)
DEBUG | 20100608-123314.099 | basicRegister.exe | RESIP:DNS | 1432 |
DnsResult.cxx:199 | Whitelisting sip.example.com(1): 33.44.55.109
DEBUG | 20100608-123314.099 | basicRegister.exe | RESIP:DNS | 1432 |
rrvip.cxx:128 | updating an existing vip: 33.44.55.109 with 33.44.55.109
DEBUG | 20100608-123314.099 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:2149 | Send to TU: TU: DialogUsageManager size=0
SIP/2.0 200 OK
Via: SIP/2.0/UDP
10.10.0.51:32036;received=11.222.33.3;branch=z9hG4bK-d8754z-d4317d33372cd46b-1---d8754z-;rport=32036
Contact: <sip:test_test@xxxxxxxxxxxx:5060;transport=udp>
To: <sip:test_test@xxxxxxxxxxx>;tag=SDnd62d99-1109
From: <sip:test_test@xxxxxxxxxxx>;tag=7b5ee311
Call-ID: NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjMTQ0Zjc1ZmE.
CSeq: 1 SUBSCRIBE
Expires: 3600
Content-Length: 0
DEBUG | 20100608-123314.146 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TimerQueue.cxx:85 | Adding timer: Timer K tid=d4317d33372cd46b ms=5000
INFO | 20100608-123314.161 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1335 | Got: SipResp: 200 tid=d4317d33372cd46b
cseq=SUBSCRIBE con
tact=test_test@xxxxxxxxxxxx:5060 / 1 from(wire)
STACK | 20100608-123314.161 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1959 | Looking for dialogSet:
NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjM
TQ0Zjc1ZmE.-7b5ee311 in map:
STACK | 20100608-123314.177 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1960 |
[ZTUyNjk5ZGFkYjg1NzU4YWRiM2JlMzM3OGM1NDQ2YzE.-a8095839 -
> 01D60508, NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjMTQ0Zjc1ZmE.-7b5ee311 -> 01D652B8]
DEBUG | 20100608-123314.193 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1843 | DialogUsageManager::processResponse:
SipResp: 200 tid=d4317d33372cd46b cseq=SUBSCRIBE
contact=test_test@xxxxxxxxxxxx:5060 / 1 from(wire)
DEBUG | 20100608-123314.193 | basicRegister.exe | RESIP:DUM | 1432 |
DialogId.cxx:50 | DialogId::DialogId:
NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjMTQ0Zjc1ZmE.-7b5
ee311-SDnd62d99-1109
STACK | 20100608-123314.208 | basicRegister.exe | RESIP:DUM | 1432 |
DialogSet.cxx:535 | No matching dialog for
SIP/2.0 200 OK
Via: SIP/2.0/UDP
10.10.0.51:32036;received=11.222.33.3;branch=z9hG4bK-d8754z-d4317d33372cd46b-1---d8754z-;rport=32036
Contact: <sip:test_test@xxxxxxxxxxxx:5060;transport=udp>
To: <sip:test_test@xxxxxxxxxxx>;tag=SDnd62d99-1109
From: <sip:test_test@xxxxxxxxxxx>;tag=7b5ee311
Call-ID: NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjMTQ0Zjc1ZmE.
CSeq: 1 SUBSCRIBE
Expires: 3600
Content-Length: 0
DEBUG | 20100608-123314.255 | basicRegister.exe | RESIP:DUM | 1432 |
DialogSet.cxx:837 | mState == 3 Creating a new Dialog from msg:
SIP/2.0 200 OK
Via: SIP/2.0/UDP
10.10.0.51:32036;received=11.222.33.3;branch=z9hG4bK-d8754z-d4317d33372cd46b-1---d8754z-;rport=32036
Contact: <sip:test_test@xxxxxxxxxxxx:5060;transport=udp>
To: <sip:test_test@xxxxxxxxxxx>;tag=SDnd62d99-1109
From: <sip:test_test@xxxxxxxxxxx>;tag=7b5ee311
Call-ID: NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjMTQ0Zjc1ZmE.
CSeq: 1 SUBSCRIBE
Expires: 3600
Content-Length: 0
DEBUG | 20100608-123314.365 | basicRegister.exe | RESIP:DUM | 1432 |
DialogId.cxx:50 | DialogId::DialogId:
NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjMTQ0Zjc1ZmE.-7b5
ee311-SDnd62d99-1109
DEBUG | 20100608-123314.365 | basicRegister.exe | RESIP:DUM | 1432 |
Dialog.cxx:232 | ************** Created Dialog as UAC **************
DEBUG | 20100608-123314.380 | basicRegister.exe | RESIP:DUM | 1432 |
Dialog.cxx:233 | mRemoteNameAddr: <sip:test_test@xxxxxxxxxxx>;tag=SDnd62d99-1109
DEBUG | 20100608-123314.380 | basicRegister.exe | RESIP:DUM | 1432 |
Dialog.cxx:234 | mLocalNameAddr: <sip:test_test@xxxxxxxxxxx>;tag=7b5ee311
DEBUG | 20100608-123314.380 | basicRegister.exe | RESIP:DUM | 1432 |
Dialog.cxx:235 | mLocalContact: <sip:test_test>
DEBUG | 20100608-123314.396 | basicRegister.exe | RESIP:DUM | 1432 |
Dialog.cxx:236 | mRemoteTarget: <sip:test_test@xxxxxxxxxxxx:5060;transport=udp>
DEBUG | 20100608-123314.396 | basicRegister.exe | RESIP:DUM | 1432 |
Dialog.cxx:239 | Dialog::Dialog
NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjMTQ0Zjc1ZmE.-7b5ee311-
SDnd62d99-1109
DEBUG | 20100608-123314.411 | basicRegister.exe | RESIP:DUM | 1432 |
DialogSet.cxx:884 | ### Calling CreateAppDialog ###:
SIP/2.0 200 OK
Via: SIP/2.0/UDP
10.10.0.51:32036;received=11.222.33.3;branch=z9hG4bK-d8754z-d4317d33372cd46b-1---d8754z-;rport=32036
Contact: <sip:test_test@xxxxxxxxxxxx:5060;transport=udp>
To: <sip:test_test@xxxxxxxxxxx>;tag=SDnd62d99-1109
From: <sip:test_test@xxxxxxxxxxx>;tag=7b5ee311
Call-ID: NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjMTQ0Zjc1ZmE.
CSeq: 1 SUBSCRIBE
Expires: 3600
Content-Length: 0
STACK | 20100608-123314.458 | basicRegister.exe | RESIP:DUM | 1432 |
Handled.cxx:16 | &&&&&& Handled::Handled 4 this(01D74DF8) 000E7B60
DEBUG | 20100608-123314.458 | basicRegister.exe | RESIP:DUM | 1432 |
Dialog.cxx:370 | Dialog::dispatch: SipResp: 200 tid=d4317d33372cd46b
cseq=SUBSCRIBE co
ntact=test_test@xxxxxxxxxxxx:5060 / 1 from(wire)
DEBUG | 20100608-123314.474 | basicRegister.exe | RESIP:DUM | 1432 |
KeepAliveManager.cxx:40 | Association added for [ V4 33.44.55.109:5060 UDP
target doma
in=unspecified mFlowKey=1744 ]
DEBUG | 20100608-123314.474 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
Transport.cxx:287 | incoming from: [ V4 33.44.55.109:5060 UDP target domain=unsp
ecified mFlowKey=1744 ]
STACK | 20100608-123314.490 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
Transport.cxx:288 |
NOTIFY sip:test_test@xxxxxxxxxx:32036 SIP/2.0
Via: SIP/2.0/UDP 33.44.55.109:5060;branch=z9hG4bK6sfsi40008v0gqghu2k1.1
Max-Forwards: 67
Contact: <sip:test_test@xxxxxxxxxxxx:5060;transport=udp>
To: <sip:test_test@xxxxxxxxxxx>;tag=7b5ee311
From: <sip:test_test@xxxxxxxxxxx>;tag=SDnd62d99-1109
Call-ID: NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjMTQ0Zjc1ZmE.
CSeq: 2 NOTIFY
Content-Type: application/pidf+xml
Subscription-State: active;expires=3599;min-interval=0
Event: presence
Content-Length: 305
<?xml version="1.0" encoding="UTF-8" standalone="no"?><presence
xmlns="urn:ietf:params:xml:ns:pidf" entity="sip:test_test@xxxxxxxxxxx">
<tuple id="xx998877yy">
<status>
<basic>closed</basic>
</status>
<timestamp>2010-06-08T10:21:58Z</timestamp>
</tuple>
</presence>
STACK | 20100608-123314.568 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:389 | No matching transaction for SipReq: NOTIFY test_ip
com@xxxxxxxxxx:32036 tid=6sfsi40008v0gqghu2k1.1 cseq=NOTIFY
contact=test_test@xxxxxxxxxxxx:5060 / 2 from(wire)
DEBUG | 20100608-123314.583 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionUser.cxx:66 | Checking if SipReq: NOTIFY test_test@xxxxxxxxxx:320
36 tid=6sfsi40008v0gqghu2k1.1 cseq=NOTIFY contact=test_test@xxxxxxxxxxxx:5060 /
2 from(wire) is for me
DEBUG | 20100608-123314.583 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionUser.cxx:71 | Checking rule...
DEBUG | 20100608-123314.599 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
MessageFilterRule.cxx:45 | Matching rule for:
NOTIFY sip:test_test@xxxxxxxxxx:32036 SIP/2.0
Via: SIP/2.0/UDP 33.44.55.109:5060;branch=z9hG4bK6sfsi40008v0gqghu2k1.1
Max-Forwards: 67
Contact: <sip:test_test@xxxxxxxxxxxx:5060;transport=udp>
To: <sip:test_test@xxxxxxxxxxx>;tag=7b5ee311
From: <sip:test_test@xxxxxxxxxxx>;tag=SDnd62d99-1109
Call-ID: NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjMTQ0Zjc1ZmE.
CSeq: 2 NOTIFY
Content-Type: application/pidf+xml
Subscription-State: active;expires=3599;min-interval=0
Event: presence
Content-Length: 305
<?xml version="1.0" encoding="UTF-8" standalone="no"?><presence
xmlns="urn:ietf:params:xml:ns:pidf" entity="sip:test_test@xxxxxxxxxxx">
<tuple id="xx998877yy">
<status>
<basic>closed</basic>
</status>
<timestamp>2010-06-08T10:21:58Z</timestamp>
</tuple>
</presence>
DEBUG | 20100608-123314.677 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionUser.cxx:74 | Match!
STACK | 20100608-123314.677 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:56 | Creating new TransactionState: tid=6sfsi40008v0gqghu
2k1.1 [ ServerNonInvite/Trying reliable target=[ V4 0.0.0.0:0 UNKNOWN_TRANSPORT
target domain=unspecified mFlowKey=0 ]]
DEBUG | 20100608-123314.693 | basicRegister.exe | RESIP | 1432 | Helper.cxx:375
| Helper::makeResponse(SipReq: NOTIFY test_test@xxxxxxxxxx:32036 tid=6sfs
i40008v0gqghu2k1.1 cseq=NOTIFY contact=test_test@xxxxxxxxxxxx:5060 / 2
from(wire) code=100 reason=
DEBUG | 20100608-123314.708 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TimerQueue.cxx:85 | Adding timer: Timer Trying tid=6sfsi40008v0gqghu2k1.1 ms=3
500
DEBUG | 20100608-123314.708 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:2149 | Send to TU: TU: DialogUsageManager size=0
NOTIFY sip:test_test@xxxxxxxxxx:32036 SIP/2.0
Via: SIP/2.0/UDP 33.44.55.109:5060;branch=z9hG4bK6sfsi40008v0gqghu2k1.1
Max-Forwards: 67
Contact: <sip:test_test@xxxxxxxxxxxx:5060;transport=udp>
To: <sip:test_test@xxxxxxxxxxx>;tag=7b5ee311
From: <sip:test_test@xxxxxxxxxxx>;tag=SDnd62d99-1109
Call-ID: NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjMTQ0Zjc1ZmE.
CSeq: 2 NOTIFY
Content-Type: application/pidf+xml
Subscription-State: active;expires=3599;min-interval=0
Event: presence
Content-Length: 305
<?xml version="1.0" encoding="UTF-8" standalone="no"?><presence
xmlns="urn:ietf:params:xml:ns:pidf" entity="sip:test_test@xxxxxxxxxxx">
<tuple id="xx998877yy">
<status>
<basic>closed</basic>
</status>
<timestamp>2010-06-08T10:21:58Z</timestamp>
</tuple>
</presence>
STACK | 20100608-123314.786 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:354 | Found matching transaction for Timer: Timer E1 500
-> tid=d4317d33372cd46b [ ClientNonInvite/Completed unreliable target=[ V4
0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]]
STACK | 20100608-123314.802 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:661 | TransactionState::processClientNonInvite: Timer: Ti
mer E1 500
INFO | 20100608-123314.818 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1335 | Got: SipReq: NOTIFY test_test@xxxxxxxxxx:32036
tid=6sfs
i40008v0gqghu2k1.1 cseq=NOTIFY contact=test_test@xxxxxxxxxxxx:5060 / 2
from(wire)
INFO | 20100608-123314.833 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1466 | Received an unsupported method: SipReq: NOTIFY
test_ipco
m@xxxxxxxxxx:32036 tid=6sfsi40008v0gqghu2k1.1 cseq=NOTIFY
contact=test_test@xxxxxxxxxxxx:5060 / 2 from(wire)
DEBUG | 20100608-123314.833 | basicRegister.exe | RESIP | 1432 | Helper.cxx:375
| Helper::makeResponse(SipReq: NOTIFY test_test@xxxxxxxxxx:32036 tid=6sfs
i40008v0gqghu2k1.1 cseq=NOTIFY contact=test_test@xxxxxxxxxxxx:5060 / 2
from(wire) code=405 reason=
DEBUG | 20100608-123314.849 | basicRegister.exe | RESIP | 1432 |
SipStack.cxx:319 | SEND: SipResp: 405 tid=6sfsi40008v0gqghu2k1.1 cseq=NOTIFY /
2 from(tu)
DEBUG | 20100608-123314.849 | basicRegister.exe | RESIP:DUM | 1432 |
DialogUsageManager.cxx:1355 | Failed RequestURI validation NOTIFY
sip:test_test@xxxxx
.0.51:32036 SIP/2.0
Via: SIP/2.0/UDP 33.44.55.109:5060;branch=z9hG4bK6sfsi40008v0gqghu2k1.1
Max-Forwards: 67
Contact: <sip:test_test@xxxxxxxxxxxx:5060;transport=udp>
To: <sip:test_test@xxxxxxxxxxx>;tag=7b5ee311
From: <sip:test_test@xxxxxxxxxxx>;tag=SDnd62d99-1109
Call-ID: NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjMTQ0Zjc1ZmE.
CSeq: 2 NOTIFY
Content-Type: application/pidf+xml
Subscription-State: active;expires=3599;min-interval=0
Event: presence
Content-Length: 305
<?xml version="1.0" encoding="UTF-8" standalone="no"?><presence
xmlns="urn:ietf:params:xml:ns:pidf" entity="sip:test_test@xxxxxxxxxxx">
<tuple id="xx998877yy">
<status>
<basic>closed</basic>
</status>
<timestamp>2010-06-08T10:21:58Z</timestamp>
</tuple>
</presence>
DEBUG | 20100608-123314.927 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
Transport.cxx:287 | incoming from: [ V4 33.44.55.109:5060 UDP target domain=unsp
ecified mFlowKey=1744 ]
STACK | 20100608-123314.943 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
Transport.cxx:288 |
NOTIFY sip:test_test@xxxxxxxxxx:32036 SIP/2.0
Via: SIP/2.0/UDP 33.44.55.109:5060;branch=z9hG4bK6sfsi40008v0gqghu2k1.1
Max-Forwards: 67
Contact: <sip:test_test@xxxxxxxxxxxx:5060;transport=udp>
To: <sip:test_test@xxxxxxxxxxx>;tag=7b5ee311
From: <sip:test_test@xxxxxxxxxxx>;tag=SDnd62d99-1109
Call-ID: NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjMTQ0Zjc1ZmE.
CSeq: 2 NOTIFY
Content-Type: application/pidf+xml
Subscription-State: active;expires=3599;min-interval=0
Event: presence
Content-Length: 305
<?xml version="1.0" encoding="UTF-8" standalone="no"?><presence
xmlns="urn:ietf:params:xml:ns:pidf" entity="sip:test_test@xxxxxxxxxxx">
<tuple id="xx998877yy">
<status>
<basic>closed</basic>
</status>
<timestamp>2010-06-08T10:21:58Z</timestamp>
</tuple>
</presence>
STACK | 20100608-123315.021 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:354 | Found matching transaction for SipResp: 405 tid=6sf
si40008v0gqghu2k1.1 cseq=NOTIFY / 2 from(tu) -> tid=6sfsi40008v0gqghu2k1.1 [
ServerNonInvite/Trying unreliable target=[ V4 33.44.55.109:5060 UDP target dom
ain=unspecified mFlowKey=1744 ]]
STACK | 20100608-123315.036 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:1074 | TransactionState::processServerNonInvite: SipResp:
405 tid=6sfsi40008v0gqghu2k1.1 cseq=NOTIFY / 2 from(tu)
DEBUG | 20100608-123315.036 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TimerQueue.cxx:85 | Adding timer: Timer J tid=6sfsi40008v0gqghu2k1.1 ms=32000
STACK | 20100608-123315.052 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:2090 | tid=6sfsi40008v0gqghu2k1.1 sending to : [ V4 80.75
.55.109:5060 UDP target domain=unspecified mFlowKey=1744 ]
DEBUG | 20100608-123315.068 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
TransportSelector.cxx:566 | Looked up source for destination: [ V4 33.44.55.109:
5060 UDP target domain=unspecified mFlowKey=1744 ] -> [ V4 10.10.0.51:0 UDP
target domain=unspecified mFlowKey=1744 ] sent-by=33.44.55.109 sent-port=5060
DEBUG | 20100608-123315.068 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
TransportSelector.cxx:945 | Transmitting to [ V4 33.44.55.109:5060 UDP target do
main=unspecified mFlowKey=1744 ] tlsDomain= via [ V4 10.10.0.51:32036 UDP
target domain=unspecified mFlowKey=1744 ]
SIP/2.0 405 Method Not Allowed
Via: SIP/2.0/UDP 33.44.55.109:5060;branch=z9hG4bK6sfsi40008v0gqghu2k1.1
To: <sip:test_test@xxxxxxxxxxx>;tag=7b5ee311
From: <sip:test_test@xxxxxxxxxxx>;tag=SDnd62d99-1109
Call-ID: NzI5MzUzMGUxNGMyZDg4YjY0YWY5MjhjMTQ0Zjc1ZmE.
CSeq: 2 NOTIFY
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, UPDATE
Content-Length: 0
sigcomp id=
DEBUG | 20100608-123315.130 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
Transport.cxx:213 | Adding message to tx buffer to: [ V4 33.44.55.109:5060 UDP t
arget domain=unspecified mFlowKey=1744 ]
STACK | 20100608-123315.130 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:354 | Found matching transaction for SipReq: NOTIFY test
_test@xxxxxxxxxx:32036 tid=6sfsi40008v0gqghu2k1.1 cseq=NOTIFY
contact=test_test@xxxxxxxxxxxx:5060 / 2 from(wire) ->
tid=6sfsi40008v0gqghu2k1.1 [ ServerNo
nInvite/Completed unreliable target=[ V4 33.44.55.109:5060 UDP target
domain=unspecified mFlowKey=1744 ]]
STACK | 20100608-123315.146 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:1074 | TransactionState::processServerNonInvite: SipReq:
NOTIFY test_test@xxxxxxxxxx:32036 tid=6sfsi40008v0gqghu2k1.1 cseq=NOTIFY
contact=test_test@xxxxxxxxxxxx:5060 / 2 from(wire)
DEBUG | 20100608-123315.161 | basicRegister.exe | RESIP:TRANSPORT | 1432 |
Transport.cxx:213 | Adding message to tx buffer to: [ V4 33.44.55.109:5060 UDP t
arget domain=unspecified mFlowKey=1744 ]
STACK | 20100608-123316.786 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:354 | Found matching transaction for Timer: Timer K 5000
-> tid=4d1fda18e0575d27 [ ClientNonInvite/Completed unreliable target=[ V4
0.0.0.0:0 UNKNOWN_TRANSPORT target domain=unspecified mFlowKey=0 ]]
STACK | 20100608-123316.802 | basicRegister.exe | RESIP:TRANSACTION | 1432 |
TransactionState.cxx:661 | TransactionState::processClientNonInvite: Timer: Ti
mer K 5000
^C
C:\Dokumente und Einstellungen\darilion\Eigene Dateien\Visual Studio
2008\Projects\resiprocate-1.6\resip\dum\test\Debug>