1 Replies Last post: Jul 23, 2008 2:13 AM by joegen  
Click to view jmatthewsr's profile   5 posts since
Jul 22, 2008

Jul 22, 2008 2:07 PM

crash when processing an inbound REGISTER


On Windows 2003 Server, binary: opensbc-1.1.4-setup.msi.

Mode: b2bUpperReg

Upper registration and b2bua routes created.

OpenSBC and my SIP server are running on the same interface, openSBC = UDP port 5080 and mysipserver = UDP port 5060.

The REGISTER does not reach the sip server listening on port 5060.

Available log entries before the process died:

thanks!

186:44:32.823 INF: CID=0x0000 SIP Stack CREATED
186:44:32.842 PWL: CID=0x0000 Running in Back To Back with Upper Registration Mode
186:44:32.850 PWL: CID=0x0000 OpalMan Created manager.
186:44:32.850 PWL: CID=0x0000 OpenSBC STARTED
186:44:32.860 PWL: CID=0x0000 Opened master socket for HTTP: 9999
186:44:32.862 PWL: CID=0x0000 Configuration change detected
186:44:32.863 PWL: CID=0x0000 Setting SIP Log Level to 5
186:44:32.863 PWL: CID=0x0000 Setting PTRACE Log Level to 5
186:44:32.866 PWL: CID=0x0000 OpalMan Created manager.
186:44:32.867 PWL: CID=0x0000 UA Thread 4212 Started
186:44:32.868 PWL: CID=0x0000 UA Thread 5828 Started
186:44:32.868 PWL: CID=0x0000 UA Thread 2760 Started
186:44:32.868 PWL: CID=0x0000 UA Thread 4732 Started
186:44:32.868 PWL: CID=0x0000 UA Thread 1944 Started
186:44:32.868 PWL: CID=0x0000 UA Thread 4932 Started
186:44:32.868 PWL: CID=0x0000 UA Thread 5012 Started
186:44:32.868 PWL: CID=0x0000 UA Thread 5020 Started
186:44:32.870 DTL: CID=0x0000 Starting UDP Transport on <opensbcANDsipproxyIP>:5080
186:44:32.870 PWL: CID=0x0000 Opening Listener at <opensbcANDsipproxyIP>:5080
186:44:32.874 PWL: CID=0x0000 *** LISTENER STARTED *** <opensbcANDsipproxyIP>:5080 *** DEFAULT LISTENER ***
186:44:32.874 DTL: CID=0x0000 *** TRANSPORT STARTED *** Listener: <opensbcANDsipproxyIP>:5080
186:44:32.874 DTL: CID=0x0000 Starting UDP Transport on <opensbcANDsipproxyIP>:65100
186:44:32.875 PWL: CID=0x0000 UA Thread 5028 Started
186:44:32.875 PWL: CID=0x0000 UA Thread 5032 Started
186:44:32.875 PWL: CID=0x0000 Opening Listener at <opensbcANDsipproxyIP>:65100
186:44:32.876 PWL: CID=0x0000 *** LISTENER STARTED *** <opensbcANDsipproxyIP>:65100
186:44:32.876 DTL: CID=0x0000 *** TRANSPORT STARTED *** Listener: <opensbcANDsipproxyIP>:65100
186:44:32.876 INF: CID=0x0000 Media Server SIP Stack Initialization Started
186:44:32.876 PWL: CID=0x0000 OpalEP Created endpoint: ivr
186:44:32.876 PWL: CID=0x0000 IVR Created endpoint.
186:44:32.876 PWL: CID=0x0000 OpalEP Created endpoint: sip
186:44:32.877 PWL: CID=0x0000 Transaction Thread 5040 Started
186:44:32.878 PWL: CID=0x0000 Transaction Thread 4960 Started
186:44:32.878 PWL: CID=0x0000 Transaction Thread 3748 Started
186:44:32.878 PWL: CID=0x0000 Transaction Thread 3312 Started
186:44:32.878 PWL: CID=0x0000 Transaction Thread 2684 Started
186:44:32.878 PWL: CID=0x0000 Transaction Thread 1396 Started
186:44:32.878 PWL: CID=0x0000 Transaction Thread 5060 Started
186:44:32.878 INF: CID=0x0000 SIP Stack CREATED
186:44:32.882 PWL: CID=0x0000 Transaction Thread 5068 Started
186:44:32.882 PWL: CID=0x0000 Transaction Thread 840 Started
186:44:32.882 PWL: CID=0x0000 Transaction Thread 3956 Started
186:44:32.882 PWL: CID=0x0000 Transaction Cleaner Thread 4684 Started
186:44:32.885 PWL: CID=0x0000 OpalMan Added route "ivr:.*=sip:<da>"
186:44:32.885 PWL: CID=0x0000 OpalMan Added route "sip:.*=ivr:<da>"
186:44:32.893 DTL: CID=0x0000 Starting UDP Transport on <opensbcANDsipproxyIP>:65110
186:44:32.893 PWL: CID=0x0000 Opening Listener at <opensbcANDsipproxyIP>:65110
186:44:32.895 PWL: CID=0x0000 *** LISTENER STARTED *** <opensbcANDsipproxyIP>:65110 *** DEFAULT LISTENER ***
186:44:32.896 DTL: CID=0x0000 *** TRANSPORT STARTED *** Listener: <opensbcANDsipproxyIP>:65110
186:44:32.896 PWL: CID=0x0000 UA Thread 4244 Started
186:44:32.899 PWL: CID=0x0000 Appending route: <sip:5000@*> sip:<opensbcANDsipproxyIP>:65110
186:44:32.904 PWL: CID=0x0000 Appending route: <sip:*@<mydomain>:*> <opensbcANDsipproxyIP>:5060
186:44:32.905 PWL: CID=0x0000 Appending route: <sip:*@<mydomain>:*> sip:<opensbcANDsipproxyIP>:5060
186:44:36.717 PWL: CID=0x0000 HTTPServer Persistant connection has no content length
186:44:36.717 PWL: CID=0x0000 HTTPServer Setting direct persistant response
186:44:36.737 PWL: CID=0x0000 HTTPServer Persistant connection has no content length
186:44:36.737 PWL: CID=0x0000 HTTPServer Setting direct persistant response
186:44:43.227 DBG: CID=0x0e89 RCV: enc=0 575 Bytes from RCVADDR: <xliteclientpublicNATIp>:RCVPORT: 28990:UDP (REGISTER sip:<mydomain>:5080 SIP/2.0)
186:44:43.227 PWL: CID=0x0000 RCV: enc=0 575 Bytes from RCVADDR: <xliteclientpublicNATIp>:RCVPORT: 28990:UDP (REGISTER sip:<mydomain>:5080 SIP/2.0)

REGISTER sip:<mydomain>:5080 SIP/2.0

From: "My Name" <sip:0309@<mydomain>:5080>;tag=433c2754

To: "My Name" <sip:0309@<mydomain>:5080>

Via: SIP/2.0/UDP 192.168.0.3:29390;branch=z9hG4bK-d8754z-b77118267c055503-1---d8754z-

CSeq: 1 REGISTER

Call-ID: NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.

Contact: <sip:0309@192.168.0.3:29390;rinstance=19d7acf005699b27>

User-Agent: X-Lite release 1100l stamp 47546

Expires: 3600

Max-Forwards: 70

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

Content-Length: 0

186:44:43.228 DBG: CID=0x0e89
186:44:43.228 DBG: CID=0x0e89 RECEIVE: REQ: REGISTER (1)
186:44:43.228 DBG: CID=0x0e89 FROM: sip:0309@<mydomain>:5080
186:44:43.228 DBG: CID=0x0e89 TO: sip:0309@<mydomain>:5080
186:44:43.228 DBG: CID=0x0e89 XOR: FALSE
186:44:43.228 DBG: CID=0x0e89 SRC: <xliteclientpublicNATIp> PORT: 28990
186:44:43.228 DBG: CID=0x0e89
186:44:43.228 DBG: CID=0x0e89 Finding transaction for REGISTER sip:<mydomain>:5080 SIP/2.0
186:44:43.228 DBG: CID=0x0e89 Setting Transaction ID to NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.|z9hG4bK-d8754z-b77118267c055503-1---d8754z-|REGISTER
186:44:43.228 DBG: CID=0x0e89
186:44:43.228 DBG: CID=0x0e89 *** CREATING TRANSACTION (NIST) ***
186:44:43.228 DBG: CID=0x0e89 Message: REGISTER sip:<mydomain>:5080 SIP/2.0
186:44:43.228 DBG: CID=0x0e89 Call-Id: NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.
186:44:43.228 DBG: CID=0x0e89
186:44:43.228 DTL: CID=0x0e89 NIST(672283229) *** CREATED *** - NIST|NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.|z9hG4bK-d8754z-b77118267c055503-1---d8754z-|REGISTER
186:44:43.229 DTL: CID=0x0e89 NIST(672283229) Event(SIPMessage) - REGISTER sip:<mydomain>:5080 SIP/2.0
186:44:43.229 DBG: CID=0x0e89 TRANSACTION: (NIST) REGISTER sip:<mydomain>:5080 SIP/2.0 State: 0
186:44:43.229 DTL: CID=0x0e89 NIST(672283229) StateIdle->StateTrying
186:44:43.229 DBG: CID=0x0e89 Event: SIPStack::Enqueue(REGISTER sip:<mydomain>:5080 SIP/2.0)
186:44:43.230 DBG: CID=0x0e89 Event: B2BUserAgent::ProcessEvent( REGISTER sip:<mydomain>:5080 SIP/2.0 )
186:44:43.557 INF: CID=0x0e89 Event: Setting UA Core Proxy to handle event REGISTER
186:44:43.558 DTL: CID=0x0e89 Event: ---> Inbound - REGISTER sip:<mydomain>:5080 SIP/2.0
186:44:43.558 INF: CID=0x0e89 Session CREATED
186:44:43.558 INF: CID=0x0e89 *** CREATED *** PROXY Session NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.
186:44:43.559 DTL: CID=0x0e89 *** MESSAGE ARRIVAL *** for SIP Session NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.
186:44:43.559 DBG: CID=0x0e89
186:44:43.559 DBG: CID=0x0e89 REGISTER sip:<mydomain>:5080 SIP/2.0
186:44:43.559 DBG: CID=0x0e89 From: "My Name" <sip:0309@<mydomain>:5080>;tag=433c2754
186:44:43.559 DBG: CID=0x0e89 To: "My Name" <sip:0309@<mydomain>:5080>
186:44:43.559 DBG: CID=0x0e89 Via: SIP/2.0/UDP 192.168.0.3:29390;branch=z9hG4bK-d8754z-b77118267c055503-1---d8754z-;rport=28990;received=<xliteclientpublicNATIp>
186:44:43.559 DBG: CID=0x0e89 CSeq: 1 REGISTER
186:44:43.559 DBG: CID=0x0e89 Call-ID: NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.
186:44:43.559 DBG: CID=0x0e89 Contact: <sip:0309@192.168.0.3:29390;rinstance=19d7acf005699b27>
186:44:43.559 DBG: CID=0x0e89 User-Agent: X-Lite release 1100l stamp 47546
186:44:43.559 DBG: CID=0x0e89 Expires: 3600
186:44:43.559 DBG: CID=0x0e89 Max-Forwards: 70
186:44:43.559 DBG: CID=0x0e89 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
186:44:43.559 DBG: CID=0x0e89 Content-Length: 0
186:44:43.559 DBG: CID=0x0e89
186:44:43.559 DBG: CID=0x0e89
186:44:43.560 DBG: CID=0x0e89 Finding transaction for SIP/2.0 100 Trying
186:44:43.560 DBG: CID=0x0e89 Setting Transaction ID to NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.|z9hG4bK-d8754z-b77118267c055503-1---d8754z-|REGISTER
186:44:43.561 DTL: CID=0x0e89 Found NIST|NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.|z9hG4bK-d8754z-b77118267c055503-1---d8754z-|REGISTER for SIP/2.0 100 Trying
186:44:43.561 DTL: CID=0x0e89 NIST(672283229) Event(SIPMessage) - SIP/2.0 100 Trying
186:44:43.561 DBG: CID=0x0e89 TRANSACTION: (NIST) SIP/2.0 100 Trying State: 1
186:44:43.561 DTL: CID=0x0e89 NIST(672283229) StateTrying->StateProceeding
186:44:43.562 DBG: CID=0x0e89
186:44:43.562 DBG: CID=0x0e89 TRANSMIT: RESPONSE: SIP/2.0 100 Trying
186:44:43.562 DBG: CID=0x0e89 TO: sip:0309@<mydomain>:5080
186:44:43.562 DBG: CID=0x0e89 REQ: REGISTER (1)
186:44:43.562 DBG: CID=0x0e89 XOR: FALSE
186:44:43.562 DBG: CID=0x0e89 DST: <xliteclientpublicNATIp> PORT: 28990
186:44:43.562 DBG: CID=0x0e89
186:44:43.563 DBG: CID=0x0e89
186:44:43.563 DBG: CID=0x0e89 SEND: enc=0 395 Bytes to <xliteclientpublicNATIp>:28990:UDP (SIP/2.0 100 Trying) Interface Address=<opensbcANDsipproxyIP>
186:44:43.563 DBG: CID=0x0e89 SIP/2.0 100 Trying
186:44:43.563 DBG: CID=0x0e89 From: "My Name" <sip:0309@<mydomain>:5080>;tag=433c2754
186:44:43.563 DBG: CID=0x0e89 To: "My Name" <sip:0309@<mydomain>:5080>
186:44:43.563 DBG: CID=0x0e89 Via: SIP/2.0/UDP 192.168.0.3:29390;iid=3289;branch=z9hG4bK-d8754z-b77118267c055503-1---d8754z-;rport=28990;received=<xliteclientpublicNATIp>
186:44:43.563 DBG: CID=0x0e89 CSeq: 1 REGISTER
186:44:43.563 DBG: CID=0x0e89 Call-ID: NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.
186:44:43.563 DBG: CID=0x0e89 Server: OpenSIPStack-1.1.7-1
186:44:43.563 DBG: CID=0x0e89 Content-Length: 0
186:44:43.563 DBG: CID=0x0e89
186:44:43.563 DBG: CID=0x0e89
186:44:43.566 PWL: CID=0x0000 Using Iface: <opensbcANDsipproxyIP> to send to Dest: <xliteclientpublicNATIp>
186:44:43.570 INF: CID=0x0e89 Routing REGISTER for URI sip:0309@<mydomain>:5080
186:44:43.570 PWL: CID=0x0000 *** MATCH *** sip:@<mydomain>: --> sip:0309@<mydomain>:5080
186:44:43.570 PWL: CID=0x0000 -->> From: sip:0309@<mydomain>:5080 Target: REGISTER sip:0309@<opensbcANDsipproxyIP>:5060
186:44:43.572 INF: CID=0x0e89 *** UPPER REGISTRATION ENABLE *** for sip:0309@<mydomain>:5080
186:44:43.582 DBG: CID=0x06cb CREATED via=<opensbcANDsipproxyIP>:5080 for target=<opensbcANDsipproxyIP> protocol=UDP
186:44:43.582 DBG: CID=0x0e89 Proxying request REGISTER sip:<opensbcANDsipproxyIP>:5060 SIP/2.0
186:44:43.583 DBG: CID=0x0e89 Finding transaction for REGISTER sip:<opensbcANDsipproxyIP>:5060 SIP/2.0
186:44:43.583 DBG: CID=0x0e89 Setting Transaction ID to NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.|z9hG4bKe51dfe9119fc18109c939a57207ba7f4-8ca9956d8b1150f8aa57be421c656a7e|REGISTER
186:44:43.583 DBG: CID=0x0e89
186:44:43.583 DBG: CID=0x0e89 *** CREATING TRANSACTION (NICT) ***
186:44:43.583 DBG: CID=0x0e89 Message: REGISTER sip:<opensbcANDsipproxyIP>:5060 SIP/2.0
186:44:43.583 DBG: CID=0x0e89 Call-Id: NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.
186:44:43.583 DBG: CID=0x0e89
186:44:43.583 DTL: CID=0x0e89 NICT(672283230) *** CREATED *** - NICT|NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.|z9hG4bKe51dfe9119fc18109c939a57207ba7f4-8ca9956d8b1150f8aa57be421c656a7e|REGISTER
186:44:43.584 DTL: CID=0x0e89 NICT(672283230) Event(SIPMessage) - REGISTER sip:<opensbcANDsipproxyIP>:5060 SIP/2.0
186:44:43.584 DBG: CID=0x0e89 TRANSACTION: (NICT) REGISTER sip:<opensbcANDsipproxyIP>:5060 SIP/2.0 State: 0
186:44:43.584 DTL: CID=0x0e89 NICT(672283230) StateIdle->StateTrying(REGISTER sip:<opensbcANDsipproxyIP>:5060 SIP/2.0)
186:44:43.584 DBG: CID=0x0e89 NICT(672283230) Timer E( 500 ms ) STARTED
186:44:43.584 DBG: CID=0x0e89 NICT(672283230) Timer F( 10000 ms ) STARTED
186:44:43.585 DBG: CID=0x0e89
186:44:43.585 DBG: CID=0x0e89 TRANSMIT: REQ: REGISTER (1)
186:44:43.585 DBG: CID=0x0e89 TO: sip:0309@<mydomain>:5080
186:44:43.585 DBG: CID=0x0e89 XOR: FALSE
186:44:43.585 DBG: CID=0x0e89 DST: <opensbcANDsipproxyIP> PORT: 5060
186:44:43.585 DBG: CID=0x0e89
186:44:47.038 DBG: CID=0x0e89 RCV: enc=0 575 Bytes from RCVADDR: <xliteclientpublicNATIp>:RCVPORT: 28990:UDP (REGISTER sip:<mydomain>:5080 SIP/2.0)
186:44:47.038 PWL: CID=0x0000 RCV: enc=0 575 Bytes from RCVADDR: <xliteclientpublicNATIp>:RCVPORT: 28990:UDP (REGISTER sip:<mydomain>:5080 SIP/2.0)

REGISTER sip:<mydomain>:5080 SIP/2.0

From: "My Name" <sip:0309@<mydomain>:5080>;tag=433c2754

To: "My Name" <sip:0309@<mydomain>:5080>

Via: SIP/2.0/UDP 192.168.0.3:29390;branch=z9hG4bK-d8754z-b77118267c055503-1---d8754z-

CSeq: 1 REGISTER

Call-ID: NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.

Contact: <sip:0309@192.168.0.3:29390;rinstance=19d7acf005699b27>

User-Agent: X-Lite release 1100l stamp 47546

Expires: 3600

Max-Forwards: 70

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

Content-Length: 0

186:44:47.038 DBG: CID=0x0e89
186:44:47.038 DBG: CID=0x0e89 RECEIVE: REQ: REGISTER (1)
186:44:47.038 DBG: CID=0x0e89 FROM: sip:0309@<mydomain>:5080
186:44:47.038 DBG: CID=0x0e89 TO: sip:0309@<mydomain>:5080
186:44:47.038 DBG: CID=0x0e89 XOR: FALSE
186:44:47.038 DBG: CID=0x0e89 SRC: <xliteclientpublicNATIp> PORT: 28990
186:44:47.038 DBG: CID=0x0e89
186:44:47.038 DBG: CID=0x0e89 Finding transaction for REGISTER sip:<mydomain>:5080 SIP/2.0
186:44:47.038 DBG: CID=0x0e89 Setting Transaction ID to NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.|z9hG4bK-d8754z-b77118267c055503-1---d8754z-|REGISTER
186:44:47.039 DTL: CID=0x0e89 Found NIST|NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.|z9hG4bK-d8754z-b77118267c055503-1---d8754z-|REGISTER for REGISTER sip:<mydomain>:5080 SIP/2.0
186:44:47.039 DBG: CID=0x0e89 RCV: enc=0 575 Bytes from RCVADDR: <xliteclientpublicNATIp>:RCVPORT: 28990:UDP (REGISTER sip:<mydomain>:5080 SIP/2.0)
186:44:47.040 PWL: CID=0x0000 RCV: enc=0 575 Bytes from RCVADDR: <xliteclientpublicNATIp>:RCVPORT: 28990:UDP (REGISTER sip:<mydomain>:5080 SIP/2.0)

REGISTER sip:<mydomain>:5080 SIP/2.0

From: "My Name" <sip:0309@<mydomain>:5080>;tag=433c2754

To: "My Name" <sip:0309@<mydomain>:5080>

Via: SIP/2.0/UDP 192.168.0.3:29390;branch=z9hG4bK-d8754z-b77118267c055503-1---d8754z-

CSeq: 1 REGISTER

Call-ID: NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.

Contact: <sip:0309@192.168.0.3:29390;rinstance=19d7acf005699b27>

User-Agent: X-Lite release 1100l stamp 47546

Expires: 3600

Max-Forwards: 70

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

Content-Length: 0

186:44:47.040 DBG: CID=0x0e89
186:44:47.040 DBG: CID=0x0e89 RECEIVE: REQ: REGISTER (1)
186:44:47.040 DBG: CID=0x0e89 FROM: sip:0309@<mydomain>:5080
186:44:47.040 DBG: CID=0x0e89 TO: sip:0309@<mydomain>:5080
186:44:47.040 DBG: CID=0x0e89 XOR: FALSE
186:44:47.040 DBG: CID=0x0e89 SRC: <xliteclientpublicNATIp> PORT: 28990
186:44:47.040 DBG: CID=0x0e89
186:44:47.040 DBG: CID=0x0e89 Finding transaction for REGISTER sip:<mydomain>:5080 SIP/2.0
186:44:47.040 DBG: CID=0x0e89 Setting Transaction ID to NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.|z9hG4bK-d8754z-b77118267c055503-1---d8754z-|REGISTER
186:44:47.040 DTL: CID=0x0e89 Found NIST|NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.|z9hG4bK-d8754z-b77118267c055503-1---d8754z-|REGISTER for REGISTER sip:<mydomain>:5080 SIP/2.0
186:44:47.041 DBG: CID=0x0e89 RCV: enc=0 575 Bytes from RCVADDR: <xliteclientpublicNATIp>:RCVPORT: 28990:UDP (REGISTER sip:<mydomain>:5080 SIP/2.0)
186:44:47.041 PWL: CID=0x0000 RCV: enc=0 575 Bytes from RCVADDR: <xliteclientpublicNATIp>:RCVPORT: 28990:UDP (REGISTER sip:<mydomain>:5080 SIP/2.0)

REGISTER sip:<mydomain>:5080 SIP/2.0

From: "My Name" <sip:0309@<mydomain>:5080>;tag=433c2754

To: "My Name" <sip:0309@<mydomain>:5080>

Via: SIP/2.0/UDP 192.168.0.3:29390;branch=z9hG4bK-d8754z-b77118267c055503-1---d8754z-

CSeq: 1 REGISTER

Call-ID: NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.

Contact: <sip:0309@192.168.0.3:29390;rinstance=19d7acf005699b27>

User-Agent: X-Lite release 1100l stamp 47546

Expires: 3600

Max-Forwards: 70

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

Content-Length: 0

186:44:47.042 DBG: CID=0x0e89
186:44:47.042 DBG: CID=0x0e89 RECEIVE: REQ: REGISTER (1)
186:44:47.042 DBG: CID=0x0e89 FROM: sip:0309@<mydomain>:5080
186:44:47.042 DBG: CID=0x0e89 TO: sip:0309@<mydomain>:5080
186:44:47.042 DBG: CID=0x0e89 XOR: FALSE
186:44:47.042 DBG: CID=0x0e89 SRC: <xliteclientpublicNATIp> PORT: 28990
186:44:47.042 DBG: CID=0x0e89
186:44:47.042 DBG: CID=0x0e89 Finding transaction for REGISTER sip:<mydomain>:5080 SIP/2.0
186:44:47.042 DBG: CID=0x0e89 Setting Transaction ID to NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.|z9hG4bK-d8754z-b77118267c055503-1---d8754z-|REGISTER
186:44:47.042 DTL: CID=0x0e89 Found NIST|NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.|z9hG4bK-d8754z-b77118267c055503-1---d8754z-|REGISTER for REGISTER sip:<mydomain>:5080 SIP/2.0
186:44:47.047 DBG: CID=0x0e89 NICT(672283230) Timer E( 500 ms ) EXPIRED
186:44:47.047 DTL: CID=0x0e89 NIST(672283229) Event(SIPMessage) - REGISTER sip:<mydomain>:5080 SIP/2.0
186:44:47.047 DBG: CID=0x0e89 TRANSACTION: (NIST) REGISTER sip:<mydomain>:5080 SIP/2.0 State: 2
186:44:47.048 DBG: CID=0x0e89
186:44:47.048 DBG: CID=0x0e89 TRANSMIT: RESPONSE: SIP/2.0 100 Trying
186:44:47.048 DBG: CID=0x0e89 TO: sip:0309@<mydomain>:5080
186:44:47.048 DBG: CID=0x0e89 REQ: REGISTER (1)
186:44:47.048 DBG: CID=0x0e89 XOR: FALSE
186:44:47.048 DBG: CID=0x0e89 DST: <xliteclientpublicNATIp> PORT: 28990
186:44:47.048 DBG: CID=0x0e89
186:44:47.049 DTL: CID=0x0e89 NIST(672283229) Event(SIPMessage) - REGISTER sip:<mydomain>:5080 SIP/2.0
186:44:47.049 DBG: CID=0x0e89 TRANSACTION: (NIST) REGISTER sip:<mydomain>:5080 SIP/2.0 State: 2
186:44:47.049 DBG: CID=0x0e89
186:44:47.049 DBG: CID=0x0e89 TRANSMIT: RESPONSE: SIP/2.0 100 Trying
186:44:47.049 DBG: CID=0x0e89 TO: sip:0309@<mydomain>:5080
186:44:47.049 DBG: CID=0x0e89 REQ: REGISTER (1)
186:44:47.049 DBG: CID=0x0e89 XOR: FALSE
186:44:47.049 DBG: CID=0x0e89 DST: <xliteclientpublicNATIp> PORT: 28990
186:44:47.049 DBG: CID=0x0e89
186:44:47.050 DTL: CID=0x0e89 NIST(672283229) Event(SIPMessage) - REGISTER sip:<mydomain>:5080 SIP/2.0
186:44:47.050 DBG: CID=0x0e89 TRANSACTION: (NIST) REGISTER sip:<mydomain>:5080 SIP/2.0 State: 2
186:44:47.051 DBG: CID=0x0e89
186:44:47.051 DBG: CID=0x0e89 TRANSMIT: RESPONSE: SIP/2.0 100 Trying
186:44:47.051 DBG: CID=0x0e89 TO: sip:0309@<mydomain>:5080
186:44:47.051 DBG: CID=0x0e89 REQ: REGISTER (1)
186:44:47.051 DBG: CID=0x0e89 XOR: FALSE
186:44:47.051 DBG: CID=0x0e89 DST: <xliteclientpublicNATIp> PORT: 28990
186:44:47.051 DBG: CID=0x0e89
186:44:47.051 DTL: CID=0x0e89 NICT(672283230) Event( Timer-E ) Interval: 500
186:44:47.052 DBG: CID=0x0e89
186:44:47.052 DBG: CID=0x0e89 TRANSMIT: REQ: REGISTER (1)
186:44:47.052 DBG: CID=0x0e89 TO: sip:0309@<mydomain>:5080
186:44:47.052 DBG: CID=0x0e89 XOR: FALSE
186:44:47.052 DBG: CID=0x0e89 DST: <opensbcANDsipproxyIP> PORT: 5060
186:44:47.052 DBG: CID=0x0e89
186:44:47.052 DBG: CID=0x0e89 NICT(672283230) Timer E( 1000 ms ) STARTED

Click to view joegen's profile   136 posts since
Apr 28, 2007
1. Jul 23, 2008 2:13 AM in response to: jmatthewsr
Re: crash when processing an inbound REGISTER
This is a very old version. I suggest that you use the latest repository code. This bug might have already been fixed. If it recurs using the current source, then resend the logs.

Joegen

jmatthewsr wrote:

On Windows 2003 Server, binary: opensbc-1.1.4-setup.msi.

Mode: b2bUpperReg

Upper registration and b2bua routes created.

OpenSBC and my SIP server are running on the same interface, openSBC = UDP port 5080 and mysipserver = UDP port 5060.

The REGISTER does not reach the sip server listening on port 5060.

Available log entries before the process died:

thanks!

186:44:32.823 INF: CID=0x0000 SIP Stack CREATED
186:44:32.842 PWL: CID=0x0000 Running in Back To Back with Upper Registration Mode
186:44:32.850 PWL: CID=0x0000 OpalMan Created manager.
186:44:32.850 PWL: CID=0x0000 OpenSBC STARTED
186:44:32.860 PWL: CID=0x0000 Opened master socket for HTTP: 9999
186:44:32.862 PWL: CID=0x0000 Configuration change detected
186:44:32.863 PWL: CID=0x0000 Setting SIP Log Level to 5
186:44:32.863 PWL: CID=0x0000 Setting PTRACE Log Level to 5
186:44:32.866 PWL: CID=0x0000 OpalMan Created manager.
186:44:32.867 PWL: CID=0x0000 UA Thread 4212 Started
186:44:32.868 PWL: CID=0x0000 UA Thread 5828 Started
186:44:32.868 PWL: CID=0x0000 UA Thread 2760 Started
186:44:32.868 PWL: CID=0x0000 UA Thread 4732 Started
186:44:32.868 PWL: CID=0x0000 UA Thread 1944 Started
186:44:32.868 PWL: CID=0x0000 UA Thread 4932 Started
186:44:32.868 PWL: CID=0x0000 UA Thread 5012 Started
186:44:32.868 PWL: CID=0x0000 UA Thread 5020 Started
186:44:32.870 DTL: CID=0x0000 Starting UDP Transport on <opensbcANDsipproxyIP>:5080
186:44:32.870 PWL: CID=0x0000 Opening Listener at <opensbcANDsipproxyIP>:5080
186:44:32.874 PWL: CID=0x0000 *** LISTENER STARTED *** <opensbcANDsipproxyIP>:5080 *** DEFAULT LISTENER ***
186:44:32.874 DTL: CID=0x0000 *** TRANSPORT STARTED *** Listener: <opensbcANDsipproxyIP>:5080
186:44:32.874 DTL: CID=0x0000 Starting UDP Transport on <opensbcANDsipproxyIP>:65100
186:44:32.875 PWL: CID=0x0000 UA Thread 5028 Started
186:44:32.875 PWL: CID=0x0000 UA Thread 5032 Started
186:44:32.875 PWL: CID=0x0000 Opening Listener at <opensbcANDsipproxyIP>:65100
186:44:32.876 PWL: CID=0x0000 *** LISTENER STARTED *** <opensbcANDsipproxyIP>:65100
186:44:32.876 DTL: CID=0x0000 *** TRANSPORT STARTED *** Listener: <opensbcANDsipproxyIP>:65100
186:44:32.876 INF: CID=0x0000 Media Server SIP Stack Initialization Started
186:44:32.876 PWL: CID=0x0000 OpalEP Created endpoint: ivr
186:44:32.876 PWL: CID=0x0000 IVR Created endpoint.
186:44:32.876 PWL: CID=0x0000 OpalEP Created endpoint: sip
186:44:32.877 PWL: CID=0x0000 Transaction Thread 5040 Started
186:44:32.878 PWL: CID=0x0000 Transaction Thread 4960 Started
186:44:32.878 PWL: CID=0x0000 Transaction Thread 3748 Started
186:44:32.878 PWL: CID=0x0000 Transaction Thread 3312 Started
186:44:32.878 PWL: CID=0x0000 Transaction Thread 2684 Started
186:44:32.878 PWL: CID=0x0000 Transaction Thread 1396 Started
186:44:32.878 PWL: CID=0x0000 Transaction Thread 5060 Started
186:44:32.878 INF: CID=0x0000 SIP Stack CREATED
186:44:32.882 PWL: CID=0x0000 Transaction Thread 5068 Started
186:44:32.882 PWL: CID=0x0000 Transaction Thread 840 Started
186:44:32.882 PWL: CID=0x0000 Transaction Thread 3956 Started
186:44:32.882 PWL: CID=0x0000 Transaction Cleaner Thread 4684 Started
186:44:32.885 PWL: CID=0x0000 OpalMan Added route "ivr:.*=sip:<da>"
186:44:32.885 PWL: CID=0x0000 OpalMan Added route "sip:.*=ivr:<da>"
186:44:32.893 DTL: CID=0x0000 Starting UDP Transport on <opensbcANDsipproxyIP>:65110
186:44:32.893 PWL: CID=0x0000 Opening Listener at <opensbcANDsipproxyIP>:65110
186:44:32.895 PWL: CID=0x0000 *** LISTENER STARTED *** <opensbcANDsipproxyIP>:65110 *** DEFAULT LISTENER ***
186:44:32.896 DTL: CID=0x0000 *** TRANSPORT STARTED *** Listener: <opensbcANDsipproxyIP>:65110
186:44:32.896 PWL: CID=0x0000 UA Thread 4244 Started
186:44:32.899 PWL: CID=0x0000 Appending route: <sip:5000@*> sip:<opensbcANDsipproxyIP>:65110
186:44:32.904 PWL: CID=0x0000 Appending route: <sip:*@<mydomain>:*> <opensbcANDsipproxyIP>:5060
186:44:32.905 PWL: CID=0x0000 Appending route: <sip:*@<mydomain>:*> sip:<opensbcANDsipproxyIP>:5060
186:44:36.717 PWL: CID=0x0000 HTTPServer Persistant connection has no content length
186:44:36.717 PWL: CID=0x0000 HTTPServer Setting direct persistant response
186:44:36.737 PWL: CID=0x0000 HTTPServer Persistant connection has no content length
186:44:36.737 PWL: CID=0x0000 HTTPServer Setting direct persistant response
186:44:43.227 DBG: CID=0x0e89 RCV: enc=0 575 Bytes from RCVADDR: <xliteclientpublicNATIp>:RCVPORT: 28990:UDP (REGISTER sip:<mydomain>:5080 SIP/2.0)
186:44:43.227 PWL: CID=0x0000 RCV: enc=0 575 Bytes from RCVADDR: <xliteclientpublicNATIp>:RCVPORT: 28990:UDP (REGISTER sip:<mydomain>:5080 SIP/2.0)

REGISTER sip:<mydomain>:5080 SIP/2.0

From: "My Name" <sip:0309@<mydomain>:5080>;tag=433c2754

To: "My Name" <sip:0309@<mydomain>:5080>

Via: SIP/2.0/UDP 192.168.0.3:29390;branch=z9hG4bK-d8754z-b77118267c055503-1---d8754z-

CSeq: 1 REGISTER

Call-ID: NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.

Contact: <sip:0309@192.168.0.3:29390;rinstance=19d7acf005699b27>

User-Agent: X-Lite release 1100l stamp 47546

Expires: 3600

Max-Forwards: 70

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

Content-Length: 0

186:44:43.228 DBG: CID=0x0e89
186:44:43.228 DBG: CID=0x0e89 RECEIVE: REQ: REGISTER (1)
186:44:43.228 DBG: CID=0x0e89 FROM: sip:0309@<mydomain>:5080
186:44:43.228 DBG: CID=0x0e89 TO: sip:0309@<mydomain>:5080
186:44:43.228 DBG: CID=0x0e89 XOR: FALSE
186:44:43.228 DBG: CID=0x0e89 SRC: <xliteclientpublicNATIp> PORT: 28990
186:44:43.228 DBG: CID=0x0e89
186:44:43.228 DBG: CID=0x0e89 Finding transaction for REGISTER sip:<mydomain>:5080 SIP/2.0
186:44:43.228 DBG: CID=0x0e89 Setting Transaction ID to NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.|z9hG4bK-d8754z-b77118267c055503-1---d8754z-|REGISTER
186:44:43.228 DBG: CID=0x0e89
186:44:43.228 DBG: CID=0x0e89 *** CREATING TRANSACTION (NIST) ***
186:44:43.228 DBG: CID=0x0e89 Message: REGISTER sip:<mydomain>:5080 SIP/2.0
186:44:43.228 DBG: CID=0x0e89 Call-Id: NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.
186:44:43.228 DBG: CID=0x0e89
186:44:43.228 DTL: CID=0x0e89 NIST(672283229) *** CREATED *** - NIST|NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.|z9hG4bK-d8754z-b77118267c055503-1---d8754z-|REGISTER
186:44:43.229 DTL: CID=0x0e89 NIST(672283229) Event(SIPMessage) - REGISTER sip:<mydomain>:5080 SIP/2.0
186:44:43.229 DBG: CID=0x0e89 TRANSACTION: (NIST) REGISTER sip:<mydomain>:5080 SIP/2.0 State: 0
186:44:43.229 DTL: CID=0x0e89 NIST(672283229) StateIdle->StateTrying
186:44:43.229 DBG: CID=0x0e89 Event: SIPStack::Enqueue(REGISTER sip:<mydomain>:5080 SIP/2.0)
186:44:43.230 DBG: CID=0x0e89 Event: B2BUserAgent::ProcessEvent( REGISTER sip:<mydomain>:5080 SIP/2.0 )
186:44:43.557 INF: CID=0x0e89 Event: Setting UA Core Proxy to handle event REGISTER
186:44:43.558 DTL: CID=0x0e89 Event: ---> Inbound - REGISTER sip:<mydomain>:5080 SIP/2.0
186:44:43.558 INF: CID=0x0e89 Session CREATED
186:44:43.558 INF: CID=0x0e89 *** CREATED *** PROXY Session NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.
186:44:43.559 DTL: CID=0x0e89 *** MESSAGE ARRIVAL *** for SIP Session NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.
186:44:43.559 DBG: CID=0x0e89
186:44:43.559 DBG: CID=0x0e89 REGISTER sip:<mydomain>:5080 SIP/2.0
186:44:43.559 DBG: CID=0x0e89 From: "My Name" <sip:0309@<mydomain>:5080>;tag=433c2754
186:44:43.559 DBG: CID=0x0e89 To: "My Name" <sip:0309@<mydomain>:5080>
186:44:43.559 DBG: CID=0x0e89 Via: SIP/2.0/UDP 192.168.0.3:29390;branch=z9hG4bK-d8754z-b77118267c055503-1---d8754z-;rport=28990;received=<xliteclientpublicNATIp>
186:44:43.559 DBG: CID=0x0e89 CSeq: 1 REGISTER
186:44:43.559 DBG: CID=0x0e89 Call-ID: NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.
186:44:43.559 DBG: CID=0x0e89 Contact: <sip:0309@192.168.0.3:29390;rinstance=19d7acf005699b27>
186:44:43.559 DBG: CID=0x0e89 User-Agent: X-Lite release 1100l stamp 47546
186:44:43.559 DBG: CID=0x0e89 Expires: 3600
186:44:43.559 DBG: CID=0x0e89 Max-Forwards: 70
186:44:43.559 DBG: CID=0x0e89 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO
186:44:43.559 DBG: CID=0x0e89 Content-Length: 0
186:44:43.559 DBG: CID=0x0e89
186:44:43.559 DBG: CID=0x0e89
186:44:43.560 DBG: CID=0x0e89 Finding transaction for SIP/2.0 100 Trying
186:44:43.560 DBG: CID=0x0e89 Setting Transaction ID to NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.|z9hG4bK-d8754z-b77118267c055503-1---d8754z-|REGISTER
186:44:43.561 DTL: CID=0x0e89 Found NIST|NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.|z9hG4bK-d8754z-b77118267c055503-1---d8754z-|REGISTER for SIP/2.0 100 Trying
186:44:43.561 DTL: CID=0x0e89 NIST(672283229) Event(SIPMessage) - SIP/2.0 100 Trying
186:44:43.561 DBG: CID=0x0e89 TRANSACTION: (NIST) SIP/2.0 100 Trying State: 1
186:44:43.561 DTL: CID=0x0e89 NIST(672283229) StateTrying->StateProceeding
186:44:43.562 DBG: CID=0x0e89
186:44:43.562 DBG: CID=0x0e89 TRANSMIT: RESPONSE: SIP/2.0 100 Trying
186:44:43.562 DBG: CID=0x0e89 TO: sip:0309@<mydomain>:5080
186:44:43.562 DBG: CID=0x0e89 REQ: REGISTER (1)
186:44:43.562 DBG: CID=0x0e89 XOR: FALSE
186:44:43.562 DBG: CID=0x0e89 DST: <xliteclientpublicNATIp> PORT: 28990
186:44:43.562 DBG: CID=0x0e89
186:44:43.563 DBG: CID=0x0e89
186:44:43.563 DBG: CID=0x0e89 SEND: enc=0 395 Bytes to <xliteclientpublicNATIp>:28990:UDP (SIP/2.0 100 Trying) Interface Address=<opensbcANDsipproxyIP>
186:44:43.563 DBG: CID=0x0e89 SIP/2.0 100 Trying
186:44:43.563 DBG: CID=0x0e89 From: "My Name" <sip:0309@<mydomain>:5080>;tag=433c2754
186:44:43.563 DBG: CID=0x0e89 To: "My Name" <sip:0309@<mydomain>:5080>
186:44:43.563 DBG: CID=0x0e89 Via: SIP/2.0/UDP 192.168.0.3:29390;iid=3289;branch=z9hG4bK-d8754z-b77118267c055503-1---d8754z-;rport=28990;received=<xliteclientpublicNATIp>
186:44:43.563 DBG: CID=0x0e89 CSeq: 1 REGISTER
186:44:43.563 DBG: CID=0x0e89 Call-ID: NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.
186:44:43.563 DBG: CID=0x0e89 Server: OpenSIPStack-1.1.7-1
186:44:43.563 DBG: CID=0x0e89 Content-Length: 0
186:44:43.563 DBG: CID=0x0e89
186:44:43.563 DBG: CID=0x0e89
186:44:43.566 PWL: CID=0x0000 Using Iface: <opensbcANDsipproxyIP> to send to Dest: <xliteclientpublicNATIp>
186:44:43.570 INF: CID=0x0e89 Routing REGISTER for URI sip:0309@<mydomain>:5080
186:44:43.570 PWL: CID=0x0000 *** MATCH *** sip:@<mydomain>: --> sip:0309@<mydomain>:5080
186:44:43.570 PWL: CID=0x0000 -->> From: sip:0309@<mydomain>:5080 Target: REGISTER sip:0309@<opensbcANDsipproxyIP>:5060
186:44:43.572 INF: CID=0x0e89 *** UPPER REGISTRATION ENABLE *** for sip:0309@<mydomain>:5080
186:44:43.582 DBG: CID=0x06cb CREATED via=<opensbcANDsipproxyIP>:5080 for target=<opensbcANDsipproxyIP> protocol=UDP
186:44:43.582 DBG: CID=0x0e89 Proxying request REGISTER sip:<opensbcANDsipproxyIP>:5060 SIP/2.0
186:44:43.583 DBG: CID=0x0e89 Finding transaction for REGISTER sip:<opensbcANDsipproxyIP>:5060 SIP/2.0
186:44:43.583 DBG: CID=0x0e89 Setting Transaction ID to NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.|z9hG4bKe51dfe9119fc18109c939a57207ba7f4-8ca9956d8b1150f8aa57be421c656a7e|REGISTER
186:44:43.583 DBG: CID=0x0e89
186:44:43.583 DBG: CID=0x0e89 *** CREATING TRANSACTION (NICT) ***
186:44:43.583 DBG: CID=0x0e89 Message: REGISTER sip:<opensbcANDsipproxyIP>:5060 SIP/2.0
186:44:43.583 DBG: CID=0x0e89 Call-Id: NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.
186:44:43.583 DBG: CID=0x0e89
186:44:43.583 DTL: CID=0x0e89 NICT(672283230) *** CREATED *** - NICT|NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.|z9hG4bKe51dfe9119fc18109c939a57207ba7f4-8ca9956d8b1150f8aa57be421c656a7e|REGISTER
186:44:43.584 DTL: CID=0x0e89 NICT(672283230) Event(SIPMessage) - REGISTER sip:<opensbcANDsipproxyIP>:5060 SIP/2.0
186:44:43.584 DBG: CID=0x0e89 TRANSACTION: (NICT) REGISTER sip:<opensbcANDsipproxyIP>:5060 SIP/2.0 State: 0
186:44:43.584 DTL: CID=0x0e89 NICT(672283230) StateIdle->StateTrying(REGISTER sip:<opensbcANDsipproxyIP>:5060 SIP/2.0)
186:44:43.584 DBG: CID=0x0e89 NICT(672283230) Timer E( 500 ms ) STARTED
186:44:43.584 DBG: CID=0x0e89 NICT(672283230) Timer F( 10000 ms ) STARTED
186:44:43.585 DBG: CID=0x0e89
186:44:43.585 DBG: CID=0x0e89 TRANSMIT: REQ: REGISTER (1)
186:44:43.585 DBG: CID=0x0e89 TO: sip:0309@<mydomain>:5080
186:44:43.585 DBG: CID=0x0e89 XOR: FALSE
186:44:43.585 DBG: CID=0x0e89 DST: <opensbcANDsipproxyIP> PORT: 5060
186:44:43.585 DBG: CID=0x0e89
186:44:47.038 DBG: CID=0x0e89 RCV: enc=0 575 Bytes from RCVADDR: <xliteclientpublicNATIp>:RCVPORT: 28990:UDP (REGISTER sip:<mydomain>:5080 SIP/2.0)
186:44:47.038 PWL: CID=0x0000 RCV: enc=0 575 Bytes from RCVADDR: <xliteclientpublicNATIp>:RCVPORT: 28990:UDP (REGISTER sip:<mydomain>:5080 SIP/2.0)

REGISTER sip:<mydomain>:5080 SIP/2.0

From: "My Name" <sip:0309@<mydomain>:5080>;tag=433c2754

To: "My Name" <sip:0309@<mydomain>:5080>

Via: SIP/2.0/UDP 192.168.0.3:29390;branch=z9hG4bK-d8754z-b77118267c055503-1---d8754z-

CSeq: 1 REGISTER

Call-ID: NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.

Contact: <sip:0309@192.168.0.3:29390;rinstance=19d7acf005699b27>

User-Agent: X-Lite release 1100l stamp 47546

Expires: 3600

Max-Forwards: 70

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

Content-Length: 0

186:44:47.038 DBG: CID=0x0e89
186:44:47.038 DBG: CID=0x0e89 RECEIVE: REQ: REGISTER (1)
186:44:47.038 DBG: CID=0x0e89 FROM: sip:0309@<mydomain>:5080
186:44:47.038 DBG: CID=0x0e89 TO: sip:0309@<mydomain>:5080
186:44:47.038 DBG: CID=0x0e89 XOR: FALSE
186:44:47.038 DBG: CID=0x0e89 SRC: <xliteclientpublicNATIp> PORT: 28990
186:44:47.038 DBG: CID=0x0e89
186:44:47.038 DBG: CID=0x0e89 Finding transaction for REGISTER sip:<mydomain>:5080 SIP/2.0
186:44:47.038 DBG: CID=0x0e89 Setting Transaction ID to NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.|z9hG4bK-d8754z-b77118267c055503-1---d8754z-|REGISTER
186:44:47.039 DTL: CID=0x0e89 Found NIST|NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.|z9hG4bK-d8754z-b77118267c055503-1---d8754z-|REGISTER for REGISTER sip:<mydomain>:5080 SIP/2.0
186:44:47.039 DBG: CID=0x0e89 RCV: enc=0 575 Bytes from RCVADDR: <xliteclientpublicNATIp>:RCVPORT: 28990:UDP (REGISTER sip:<mydomain>:5080 SIP/2.0)
186:44:47.040 PWL: CID=0x0000 RCV: enc=0 575 Bytes from RCVADDR: <xliteclientpublicNATIp>:RCVPORT: 28990:UDP (REGISTER sip:<mydomain>:5080 SIP/2.0)

REGISTER sip:<mydomain>:5080 SIP/2.0

From: "My Name" <sip:0309@<mydomain>:5080>;tag=433c2754

To: "My Name" <sip:0309@<mydomain>:5080>

Via: SIP/2.0/UDP 192.168.0.3:29390;branch=z9hG4bK-d8754z-b77118267c055503-1---d8754z-

CSeq: 1 REGISTER

Call-ID: NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.

Contact: <sip:0309@192.168.0.3:29390;rinstance=19d7acf005699b27>

User-Agent: X-Lite release 1100l stamp 47546

Expires: 3600

Max-Forwards: 70

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

Content-Length: 0

186:44:47.040 DBG: CID=0x0e89
186:44:47.040 DBG: CID=0x0e89 RECEIVE: REQ: REGISTER (1)
186:44:47.040 DBG: CID=0x0e89 FROM: sip:0309@<mydomain>:5080
186:44:47.040 DBG: CID=0x0e89 TO: sip:0309@<mydomain>:5080
186:44:47.040 DBG: CID=0x0e89 XOR: FALSE
186:44:47.040 DBG: CID=0x0e89 SRC: <xliteclientpublicNATIp> PORT: 28990
186:44:47.040 DBG: CID=0x0e89
186:44:47.040 DBG: CID=0x0e89 Finding transaction for REGISTER sip:<mydomain>:5080 SIP/2.0
186:44:47.040 DBG: CID=0x0e89 Setting Transaction ID to NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.|z9hG4bK-d8754z-b77118267c055503-1---d8754z-|REGISTER
186:44:47.040 DTL: CID=0x0e89 Found NIST|NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.|z9hG4bK-d8754z-b77118267c055503-1---d8754z-|REGISTER for REGISTER sip:<mydomain>:5080 SIP/2.0
186:44:47.041 DBG: CID=0x0e89 RCV: enc=0 575 Bytes from RCVADDR: <xliteclientpublicNATIp>:RCVPORT: 28990:UDP (REGISTER sip:<mydomain>:5080 SIP/2.0)
186:44:47.041 PWL: CID=0x0000 RCV: enc=0 575 Bytes from RCVADDR: <xliteclientpublicNATIp>:RCVPORT: 28990:UDP (REGISTER sip:<mydomain>:5080 SIP/2.0)

REGISTER sip:<mydomain>:5080 SIP/2.0

From: "My Name" <sip:0309@<mydomain>:5080>;tag=433c2754

To: "My Name" <sip:0309@<mydomain>:5080>

Via: SIP/2.0/UDP 192.168.0.3:29390;branch=z9hG4bK-d8754z-b77118267c055503-1---d8754z-

CSeq: 1 REGISTER

Call-ID: NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.

Contact: <sip:0309@192.168.0.3:29390;rinstance=19d7acf005699b27>

User-Agent: X-Lite release 1100l stamp 47546

Expires: 3600

Max-Forwards: 70

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

Content-Length: 0

186:44:47.042 DBG: CID=0x0e89
186:44:47.042 DBG: CID=0x0e89 RECEIVE: REQ: REGISTER (1)
186:44:47.042 DBG: CID=0x0e89 FROM: sip:0309@<mydomain>:5080
186:44:47.042 DBG: CID=0x0e89 TO: sip:0309@<mydomain>:5080
186:44:47.042 DBG: CID=0x0e89 XOR: FALSE
186:44:47.042 DBG: CID=0x0e89 SRC: <xliteclientpublicNATIp> PORT: 28990
186:44:47.042 DBG: CID=0x0e89
186:44:47.042 DBG: CID=0x0e89 Finding transaction for REGISTER sip:<mydomain>:5080 SIP/2.0
186:44:47.042 DBG: CID=0x0e89 Setting Transaction ID to NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.|z9hG4bK-d8754z-b77118267c055503-1---d8754z-|REGISTER
186:44:47.042 DTL: CID=0x0e89 Found NIST|NjNiZmE4YmQwZjIxODZjNWQwMTk2MDI1NWRiMDk3NjE.|z9hG4bK-d8754z-b77118267c055503-1---d8754z-|REGISTER for REGISTER sip:<mydomain>:5080 SIP/2.0
186:44:47.047 DBG: CID=0x0e89 NICT(672283230) Timer E( 500 ms ) EXPIRED
186:44:47.047 DTL: CID=0x0e89 NIST(672283229) Event(SIPMessage) - REGISTER sip:<mydomain>:5080 SIP/2.0
186:44:47.047 DBG: CID=0x0e89 TRANSACTION: (NIST) REGISTER sip:<mydomain>:5080 SIP/2.0 State: 2
186:44:47.048 DBG: CID=0x0e89
186:44:47.048 DBG: CID=0x0e89 TRANSMIT: RESPONSE: SIP/2.0 100 Trying
186:44:47.048 DBG: CID=0x0e89 TO: sip:0309@<mydomain>:5080
186:44:47.048 DBG: