Catch 183 Session Progress

classic Classic list List threaded Threaded
4 messages Options
Reply | Threaded
Open this post in threaded view
|

Catch 183 Session Progress

Spady#7
Hi all, in a trace like this, how can I catch the 183 message? I tried with this code

if (t_check_status("183")) {
     # no support for early media
           xlog("########LOG: Individuo il 183!!!!\n" );
        }

or

if (status=="183") {
     # no support for early media
           xlog("########LOG: Individuo il 183!!!!\n" );
        }

But never appear on opensips log that i found it. I can't understand where, in the script, i have to put it
What's wrong?


U 2012/10/15 11:46:48.362832 172.16.52.51:5060 -> 172.16.55.100:5060
INVITE sip:3707@172.16.55.100;user=phone SIP/2.0.
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, PRACK, REFER, NOTIFY, UPDATE.
Supported: 100rel,from-change,timer,histinfo.
User-Agent: OXO_GW_820/044.001.
Session-Expires: 43200.
P-Asserted-Identity: "Pippo" <sip:100@172.16.52.51;user=phone>.
History-Info: <sip:3707@172.16.55.100;user=phone>;index=1.
To: <sip:3707@172.16.55.100;user=phone>.
From: "Pippo" <sip:100@172.16.52.51;user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
Contact: "Pippo" <sip:100@172.16.52.51;transport=UDP;user=phone>.
Content-Type: application/sdp.
Call-ID: 3f9d299c479416397996522871b87398@172.16.52.51.
CSeq: 199290652 INVITE.
Via: SIP/2.0/UDP 172.16.52.51;rport;branch=z9hG4bK8deb021f4c19be2ec40d3d277d6a6684.
Max-Forwards: 70.
Content-Length: 215.
.
v=0.
o=default 1350294284 1350294284 IN IP4 172.16.52.51.
s=-.
c=IN IP4 172.16.52.51.
t=0 0.
m=audio 32000 RTP/AVP 8 106 0.
a=sendrecv.
a=rtpmap:106 telephone-event/8000.
a=fmtp:106 0-15.
a=ptime:20.
a=maxptime:90.


U 2012/10/15 11:46:48.366078 172.16.55.100:5060 -> 172.16.52.51:5060
SIP/2.0 100 Giving a try.
To: <sip:3707@172.16.55.100;user=phone>.
From: "Pippo" <sip:100@172.16.52.51;user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
Call-ID: 3f9d299c479416397996522871b87398@172.16.52.51.
CSeq: 199290652 INVITE.
Via: SIP/2.0/UDP 172.16.52.51;received=172.16.52.51;rport=5060;branch=z9hG4bK8deb021f4c19be2ec40d3d277d6a6684.
Server: OpenSIPS-Longwave.
Content-Length: 0.
.


T 2012/10/15 11:46:48.367179 172.16.55.100:44921 -> 10.9.101.163:5068 [AP]
INVITE sip:3707@lync.lwtec.eu:5068;user=phone SIP/2.0.
Record-Route: <sip:172.16.55.100;transport=tcp;r2=on;lr;did=9c5.414e9ea5>.
Record-Route: <sip:172.16.55.100;r2=on;lr;did=9c5.414e9ea5>.
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, PRACK, REFER, NOTIFY, UPDATE.
Supported: 100rel,from-change,timer,histinfo.
User-Agent: OXO_GW_820/044.001.
Session-Expires: 43200.
P-Asserted-Identity: "Pippo" <sip:100@172.16.52.51;user=phone>.
History-Info: <sip:3707@172.16.55.100;user=phone>;index=1.
To: <sip:3707@172.16.55.100;user=phone>.
From: "Pippo" <sip:100@172.16.52.51;user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
Contact: "Pippo" <sip:100@172.16.52.51;transport=UDP;user=phone>.
Content-Type: application/sdp.
Call-ID: 3f9d299c479416397996522871b87398@172.16.52.51.
CSeq: 199290652 INVITE.
Via: SIP/2.0/TCP 172.16.55.100;branch=z9hG4bKb16.90f5e873.0.
Via: SIP/2.0/UDP 172.16.52.51;received=172.16.52.51;rport=5060;branch=z9hG4bK8deb021f4c19be2ec40d3d277d6a6684.
Max-Forwards: 69.
Content-Length: 215.
.
v=0.
o=default 1350294284 1350294284 IN IP4 172.16.52.51.
s=-.
c=IN IP4 172.16.52.51.
t=0 0.
m=audio 32000 RTP/AVP 8 106 0.
a=sendrecv.
a=rtpmap:106 telephone-event/8000.
a=fmtp:106 0-15.
a=ptime:20.
a=maxptime:90.


T 2012/10/15 11:46:48.368471 10.9.101.163:5068 -> 172.16.55.100:44921 [AP]
SIP/2.0 100 Trying.
FROM: "Pippo"<sip:100@172.16.52.51;user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
TO: <sip:3707@172.16.55.100;user=phone>.
CSEQ: 199290652 INVITE.
CALL-ID: 3f9d299c479416397996522871b87398@172.16.52.51.
VIA: SIP/2.0/TCP 172.16.55.100;branch=z9hG4bKb16.90f5e873.0,SIP/2.0/UDP 172.16.52.51;received=172.16.52.51;branch=z9hG4bK8deb021f4c19be2ec40d3d277d6a6684;rport=5060.
CONTENT-LENGTH: 0.
.


T 2012/10/15 11:46:49.073898 10.9.101.163:5068 -> 172.16.55.100:44921 [AP]
SIP/2.0 183 Session Progress.
FROM: "Pippo"<sip:100@172.16.52.51;user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
TO: <sip:3707@172.16.55.100;user=phone>;tag=5a974d1727;epid=D430E933C4.
CSEQ: 199290652 INVITE.
CALL-ID: 3f9d299c479416397996522871b87398@172.16.52.51.
VIA: SIP/2.0/TCP 172.16.55.100;branch=z9hG4bKb16.90f5e873.0,SIP/2.0/UDP 172.16.52.51;received=172.16.52.51;branch=z9hG4bK8deb021f4c19be2ec40d3d277d6a6684;rport=5060.
RECORD-ROUTE: <sip:172.16.55.100;transport=tcp;r2=on;lr;did=9c5.414e9ea5>,<sip:172.16.55.100;r2=on;lr;did=9c5.414e9ea5>.
CONTACT: <sip:LYNC.lwtec.eu:5068;transport=Tcp;maddr=10.9.101.163>.
CONTENT-LENGTH: 255.
CONTENT-TYPE: application/sdp.
ALLOW: CANCEL.
ALLOW: BYE.
ALLOW: UPDATE.
ALLOW: PRACK.
REQUIRE: 100rel.
SERVER: RTCC/4.0.0.0 MediationServer.
Rseq: 1.
.
v=0.
o=- 322 1 IN IP4 10.9.101.163.
s=session.
c=IN IP4 10.9.101.163.
b=CT:1000.
t=0 0.
m=audio 49932 RTP/AVP 8 106.
c=IN IP4 10.9.101.163.
a=rtcp:49933.
a=label:Audio.
a=sendrecv.
a=rtpmap:8 PCMA/8000.
a=rtpmap:106 telephone-event/8000.
a=fmtp:106 0-16.


U 2012/10/15 11:46:49.075356 172.16.55.100:5060 -> 172.16.52.51:5060
SIP/2.0 183 Session Progress.
FROM: "Pippo"<sip:100@172.16.52.51;user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
TO: <sip:3707@172.16.55.100;user=phone>;tag=5a974d1727;epid=D430E933C4.
CSEQ: 199290652 INVITE.
CALL-ID: 3f9d299c479416397996522871b87398@172.16.52.51.
VIA: SIP/2.0/UDP 172.16.52.51;received=172.16.52.51;branch=z9hG4bK8deb021f4c19be2ec40d3d277d6a6684;rport=5060.
RECORD-ROUTE: <sip:172.16.55.100;transport=tcp;r2=on;lr;did=9c5.414e9ea5>,<sip:172.16.55.100;r2=on;lr;did=9c5.414e9ea5>.
CONTACT: <sip:LYNC.lwtec.eu:5068;transport=Tcp;maddr=10.9.101.163>.
CONTENT-LENGTH: 255.
CONTENT-TYPE: application/sdp.
ALLOW: CANCEL.
ALLOW: BYE.
ALLOW: UPDATE.
ALLOW: PRACK.
REQUIRE: 100rel.
SERVER: RTCC/4.0.0.0 MediationServer.
Rseq: 1.
.
v=0.
o=- 322 1 IN IP4 10.9.101.163.
s=session.
c=IN IP4 10.9.101.163.
b=CT:1000.
t=0 0.
m=audio 49932 RTP/AVP 8 106.
c=IN IP4 10.9.101.163.
a=rtcp:49933.
a=label:Audio.
a=sendrecv.
a=rtpmap:8 PCMA/8000.
a=rtpmap:106 telephone-event/8000.
a=fmtp:106 0-16.


U 2012/10/15 11:46:49.081243 172.16.52.51:5060 -> 172.16.55.100:5060
PRACK sip:LYNC.lwtec.eu:5068;transport=Tcp;maddr=10.9.101.163 SIP/2.0.
Route: <sip:172.16.55.100;r2=on;lr;did=9c5.414e9ea5>.
Route: <sip:172.16.55.100;transport=tcp;r2=on;lr;did=9c5.414e9ea5>.
RAck: 1 199290652 INVITE.
User-Agent: OXO_GW_820/044.001.
To: <sip:3707@172.16.55.100;user=phone>;tag=5a974d1727.
From: <sip:100@172.16.52.51;user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
Call-ID: 3f9d299c479416397996522871b87398@172.16.52.51.
CSeq: 199290653 PRACK.
Via: SIP/2.0/UDP 172.16.52.51;rport;branch=z9hG4bKc251164105a928a498f5929f05f70361.
Max-Forwards: 70.
Content-Length: 0.
.


T 2012/10/15 11:46:49.082991 172.16.55.100:44921 -> 10.9.101.163:5068 [AP]
PRACK sip:LYNC.lwtec.eu:5068;transport=Tcp;maddr=10.9.101.163 SIP/2.0.
RAck: 1 199290652 INVITE.
User-Agent: OXO_GW_820/044.001.
To: <sip:3707@172.16.55.100;user=phone>;tag=5a974d1727.
From: <sip:100@172.16.52.51;user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
Call-ID: 3f9d299c479416397996522871b87398@172.16.52.51.
CSeq: 199290653 PRACK.
Via: SIP/2.0/TCP 172.16.55.100;branch=z9hG4bKc16.62a48a97.0.
Via: SIP/2.0/UDP 172.16.52.51;received=172.16.52.51;rport=5060;branch=z9hG4bKc251164105a928a498f5929f05f70361.
Max-Forwards: 69.
Content-Length: 0.
.


T 2012/10/15 11:46:49.086412 10.9.101.163:5068 -> 172.16.55.100:44921 [AP]
SIP/2.0 200 OK.
FROM: <sip:100@172.16.52.51;user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
TO: <sip:3707@172.16.55.100;user=phone>;tag=5a974d1727;epid=D430E933C4.
CSEQ: 199290653 PRACK.
CALL-ID: 3f9d299c479416397996522871b87398@172.16.52.51.
VIA: SIP/2.0/TCP 172.16.55.100;branch=z9hG4bKc16.62a48a97.0,SIP/2.0/UDP 172.16.52.51;received=172.16.52.51;branch=z9hG4bKc251164105a928a498f5929f05f70361;rport=5060.
CONTENT-LENGTH: 0.
SERVER: RTCC/4.0.0.0 MediationServer.
.


U 2012/10/15 11:46:49.087375 172.16.55.100:5060 -> 172.16.52.51:5060
SIP/2.0 200 OK.
FROM: <sip:100@172.16.52.51;user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
TO: <sip:3707@172.16.55.100;user=phone>;tag=5a974d1727;epid=D430E933C4.
CSEQ: 199290653 PRACK.
CALL-ID: 3f9d299c479416397996522871b87398@172.16.52.51.
VIA: SIP/2.0/UDP 172.16.52.51;received=172.16.52.51;branch=z9hG4bKc251164105a928a498f5929f05f70361;rport=5060.
CONTENT-LENGTH: 0.
SERVER: RTCC/4.0.0.0 MediationServer.
.


T 2012/10/15 11:46:49.831450 10.9.101.163:5068 -> 172.16.55.100:44921 [AP]
SIP/2.0 183 Session Progress.
FROM: "Pippo"<sip:100@172.16.52.51;user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
TO: <sip:3707@172.16.55.100;user=phone>;tag=5a974d1727;epid=D430E933C4.
CSEQ: 199290652 INVITE.
CALL-ID: 3f9d299c479416397996522871b87398@172.16.52.51.
VIA: SIP/2.0/TCP 172.16.55.100;branch=z9hG4bKb16.90f5e873.0,SIP/2.0/UDP 172.16.52.51;received=172.16.52.51;branch=z9hG4bK8deb021f4c19be2ec40d3d277d6a6684;rport=5060.
RECORD-ROUTE: <sip:172.16.55.100;transport=tcp;r2=on;lr;did=9c5.414e9ea5>,<sip:172.16.55.100;r2=on;lr;did=9c5.414e9ea5>.
CONTACT: <sip:LYNC.lwtec.eu:5068;transport=Tcp;maddr=10.9.101.163>.
CONTENT-LENGTH: 0.
ALLOW: CANCEL.
ALLOW: BYE.
ALLOW: UPDATE.
ALLOW: PRACK.
SERVER: RTCC/4.0.0.0 MediationServer.
.


U 2012/10/15 11:46:49.832831 172.16.55.100:5060 -> 172.16.52.51:5060
SIP/2.0 183 Session Progress.
FROM: "Pippo"<sip:100@172.16.52.51;user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
TO: <sip:3707@172.16.55.100;user=phone>;tag=5a974d1727;epid=D430E933C4.
CSEQ: 199290652 INVITE.
CALL-ID: 3f9d299c479416397996522871b87398@172.16.52.51.
VIA: SIP/2.0/UDP 172.16.52.51;received=172.16.52.51;branch=z9hG4bK8deb021f4c19be2ec40d3d277d6a6684;rport=5060.
RECORD-ROUTE: <sip:172.16.55.100;transport=tcp;r2=on;lr;did=9c5.414e9ea5>,<sip:172.16.55.100;r2=on;lr;did=9c5.414e9ea5>.
CONTACT: <sip:LYNC.lwtec.eu:5068;transport=Tcp;maddr=10.9.101.163>.
CONTENT-LENGTH: 0.
ALLOW: CANCEL.
ALLOW: BYE.
ALLOW: UPDATE.
ALLOW: PRACK.
SERVER: RTCC/4.0.0.0 MediationServer.
.


T 2012/10/15 11:46:50.042796 10.9.101.163:5068 -> 172.16.55.100:44921 [AP]
SIP/2.0 180 Ringing.
FROM: "Pippo"<sip:100@172.16.52.51;user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
TO: <sip:3707@172.16.55.100;user=phone>;tag=5a974d1727;epid=D430E933C4.
CSEQ: 199290652 INVITE.
CALL-ID: 3f9d299c479416397996522871b87398@172.16.52.51.
VIA: SIP/2.0/TCP 172.16.55.100;branch=z9hG4bKb16.90f5e873.0,SIP/2.0/UDP 172.16.52.51;received=172.16.52.51;branch=z9hG4bK8deb021f4c19be2ec40d3d277d6a6684;rport=5060.
RECORD-ROUTE: <sip:172.16.55.100;transport=tcp;r2=on;lr;did=9c5.414e9ea5>,<sip:172.16.55.100;r2=on;lr;did=9c5.414e9ea5>.
CONTACT: <sip:LYNC.lwtec.eu:5068;transport=Tcp;maddr=10.9.101.163>.
CONTENT-LENGTH: 0.
ALLOW: CANCEL.
ALLOW: BYE.
ALLOW: UPDATE.
ALLOW: PRACK.
SERVER: RTCC/4.0.0.0 MediationServer.
.


T 2012/10/15 11:46:50.043132 10.9.101.163:5068 -> 172.16.55.100:44921 [AP]
SIP/2.0 183 Session Progress.
FROM: "Pippo"<sip:100@172.16.52.51;user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
TO: <sip:3707@172.16.55.100;user=phone>;tag=5a974d1727;epid=D430E933C4.
CSEQ: 199290652 INVITE.
CALL-ID: 3f9d299c479416397996522871b87398@172.16.52.51.
VIA: SIP/2.0/TCP 172.16.55.100;branch=z9hG4bKb16.90f5e873.0,SIP/2.0/UDP 172.16.52.51;received=172.16.52.51;branch=z9hG4bK8deb021f4c19be2ec40d3d277d6a6684;rport=5060.
RECORD-ROUTE: <sip:172.16.55.100;transport=tcp;r2=on;lr;did=9c5.414e9ea5>,<sip:172.16.55.100;r2=on;lr;did=9c5.414e9ea5>.
CONTACT: <sip:LYNC.lwtec.eu:5068;transport=Tcp;maddr=10.9.101.163>.
CONTENT-LENGTH: 0.
ALLOW: CANCEL.
ALLOW: BYE.
ALLOW: UPDATE.
ALLOW: PRACK.
SERVER: RTCC/4.0.0.0 MediationServer.
.


U 2012/10/15 11:46:50.044785 172.16.55.100:5060 -> 172.16.52.51:5060
SIP/2.0 180 Ringing.
FROM: "Pippo"<sip:100@172.16.52.51;user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
TO: <sip:3707@172.16.55.100;user=phone>;tag=5a974d1727;epid=D430E933C4.
CSEQ: 199290652 INVITE.
CALL-ID: 3f9d299c479416397996522871b87398@172.16.52.51.
VIA: SIP/2.0/UDP 172.16.52.51;received=172.16.52.51;branch=z9hG4bK8deb021f4c19be2ec40d3d277d6a6684;rport=5060.
RECORD-ROUTE: <sip:172.16.55.100;transport=tcp;r2=on;lr;did=9c5.414e9ea5>,<sip:172.16.55.100;r2=on;lr;did=9c5.414e9ea5>.
CONTACT: <sip:LYNC.lwtec.eu:5068;transport=Tcp;maddr=10.9.101.163>.
CONTENT-LENGTH: 0.
ALLOW: CANCEL.
ALLOW: BYE.
ALLOW: UPDATE.
ALLOW: PRACK.
SERVER: RTCC/4.0.0.0 MediationServer.
.


U 2012/10/15 11:46:50.045966 172.16.55.100:5060 -> 172.16.52.51:5060
SIP/2.0 183 Session Progress.
FROM: "Pippo"<sip:100@172.16.52.51;user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
TO: <sip:3707@172.16.55.100;user=phone>;tag=5a974d1727;epid=D430E933C4.
CSEQ: 199290652 INVITE.
CALL-ID: 3f9d299c479416397996522871b87398@172.16.52.51.
VIA: SIP/2.0/UDP 172.16.52.51;received=172.16.52.51;branch=z9hG4bK8deb021f4c19be2ec40d3d277d6a6684;rport=5060.
RECORD-ROUTE: <sip:172.16.55.100;transport=tcp;r2=on;lr;did=9c5.414e9ea5>,<sip:172.16.55.100;r2=on;lr;did=9c5.414e9ea5>.
CONTACT: <sip:LYNC.lwtec.eu:5068;transport=Tcp;maddr=10.9.101.163>.
CONTENT-LENGTH: 0.
ALLOW: CANCEL.
ALLOW: BYE.
ALLOW: UPDATE.
ALLOW: PRACK.
SERVER: RTCC/4.0.0.0 MediationServer.
Reply | Threaded
Open this post in threaded view
|

Re: Catch 183 Session Progress

SamyGo
Hi,

Is this code in your reply route ? make sure you set the reply route before t_relay()'ing the call so that any responses from the other end go through the designated relpy route and there this condition will work.

route {
....
t_on_reply("1");
t_relay()
....

}

onreply_route[1] {
if (t_check_status("183")) {
     # no support for early media
           xlog("########LOG: Individuo il 183!!!!\n" );
        }
}

Also can you tell what is the debug=X in your configurations.

Thanks,
Sammy




On Mon, Oct 15, 2012 at 3:44 PM, spady <[hidden email]> wrote:
Hi all, in a trace like this, how can I catch the 183 message? I tried with
this code

if (t_check_status("183")) {
     # no support for early media
           xlog("########LOG: Individuo il 183!!!!\n" );
        }

or

if (status=="183") {
     # no support for early media
           xlog("########LOG: Individuo il 183!!!!\n" );
        }

But never appear on opensips log that i found it. I can't understand where,
in the script, i have to put it
What's wrong?


U 2012/10/15 11:46:48.362832 172.16.52.51:5060 -> 172.16.55.100:5060
INVITE [hidden email];user=phone SIP/2.0.
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, PRACK, REFER, NOTIFY, UPDATE.
Supported: 100rel,from-change,timer,histinfo.
User-Agent: OXO_GW_820/044.001.
Session-Expires: 43200.
P-Asserted-Identity: "Pippo" <[hidden email];user=phone>.
History-Info: <[hidden email];user=phone>;index=1.
To: <[hidden email];user=phone>.
From: "Pippo"
<[hidden email];user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
Contact: "Pippo" <[hidden email];transport=UDP;user=phone>.
Content-Type: application/sdp.
Call-ID: [hidden email].
CSeq: 199290652 INVITE.
Via: SIP/2.0/UDP
172.16.52.51;rport;branch=z9hG4bK8deb021f4c19be2ec40d3d277d6a6684.
Max-Forwards: 70.
Content-Length: 215.
.
v=0.
o=default 1350294284 1350294284 IN IP4 172.16.52.51.
s=-.
c=IN IP4 172.16.52.51.
t=0 0.
m=audio 32000 RTP/AVP 8 106 0.
a=sendrecv.
a=rtpmap:106 telephone-event/8000.
a=fmtp:106 0-15.
a=ptime:20.
a=maxptime:90.


U 2012/10/15 11:46:48.366078 172.16.55.100:5060 -> 172.16.52.51:5060
SIP/2.0 100 Giving a try.
To: <[hidden email];user=phone>.
From: "Pippo"
<[hidden email];user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
Call-ID: [hidden email].
CSeq: 199290652 INVITE.
Via: SIP/2.0/UDP
172.16.52.51;received=172.16.52.51;rport=5060;branch=z9hG4bK8deb021f4c19be2ec40d3d277d6a6684.
Server: OpenSIPS-Longwave.
Content-Length: 0.
.


T 2012/10/15 11:46:48.367179 172.16.55.100:44921 -> 10.9.101.163:5068 [AP]
INVITE sip:[hidden email]:5068;user=phone SIP/2.0.
Record-Route: <sip:172.16.55.100;transport=tcp;r2=on;lr;did=9c5.414e9ea5>.
Record-Route: <sip:172.16.55.100;r2=on;lr;did=9c5.414e9ea5>.
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, PRACK, REFER, NOTIFY, UPDATE.
Supported: 100rel,from-change,timer,histinfo.
User-Agent: OXO_GW_820/044.001.
Session-Expires: 43200.
P-Asserted-Identity: "Pippo" <[hidden email];user=phone>.
History-Info: <[hidden email];user=phone>;index=1.
To: <[hidden email];user=phone>.
From: "Pippo"
<[hidden email];user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
Contact: "Pippo" <[hidden email];transport=UDP;user=phone>.
Content-Type: application/sdp.
Call-ID: [hidden email].
CSeq: 199290652 INVITE.
Via: SIP/2.0/TCP 172.16.55.100;branch=z9hG4bKb16.90f5e873.0.
Via: SIP/2.0/UDP
172.16.52.51;received=172.16.52.51;rport=5060;branch=z9hG4bK8deb021f4c19be2ec40d3d277d6a6684.
Max-Forwards: 69.
Content-Length: 215.
.
v=0.
o=default 1350294284 1350294284 IN IP4 172.16.52.51.
s=-.
c=IN IP4 172.16.52.51.
t=0 0.
m=audio 32000 RTP/AVP 8 106 0.
a=sendrecv.
a=rtpmap:106 telephone-event/8000.
a=fmtp:106 0-15.
a=ptime:20.
a=maxptime:90.


T 2012/10/15 11:46:48.368471 10.9.101.163:5068 -> 172.16.55.100:44921 [AP]
SIP/2.0 100 Trying.
FROM:
"Pippo"<[hidden email];user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
TO: <[hidden email];user=phone>.
CSEQ: 199290652 INVITE.
CALL-ID: [hidden email].
VIA: SIP/2.0/TCP 172.16.55.100;branch=z9hG4bKb16.90f5e873.0,SIP/2.0/UDP
172.16.52.51;received=172.16.52.51;branch=z9hG4bK8deb021f4c19be2ec40d3d277d6a6684;rport=5060.
CONTENT-LENGTH: 0.
.


T 2012/10/15 11:46:49.073898 10.9.101.163:5068 -> 172.16.55.100:44921 [AP]
SIP/2.0 183 Session Progress.
FROM:
"Pippo"<[hidden email];user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
TO: <[hidden email];user=phone>;tag=5a974d1727;epid=D430E933C4.
CSEQ: 199290652 INVITE.
CALL-ID: [hidden email].
VIA: SIP/2.0/TCP 172.16.55.100;branch=z9hG4bKb16.90f5e873.0,SIP/2.0/UDP
172.16.52.51;received=172.16.52.51;branch=z9hG4bK8deb021f4c19be2ec40d3d277d6a6684;rport=5060.
RECORD-ROUTE:
<sip:172.16.55.100;transport=tcp;r2=on;lr;did=9c5.414e9ea5>,<sip:172.16.55.100;r2=on;lr;did=9c5.414e9ea5>.
CONTACT: <sip:LYNC.lwtec.eu:5068;transport=Tcp;maddr=10.9.101.163>.
CONTENT-LENGTH: 255.
CONTENT-TYPE: application/sdp.
ALLOW: CANCEL.
ALLOW: BYE.
ALLOW: UPDATE.
ALLOW: PRACK.
REQUIRE: 100rel.
SERVER: RTCC/4.0.0.0 MediationServer.
Rseq: 1.
.
v=0.
o=- 322 1 IN IP4 10.9.101.163.
s=session.
c=IN IP4 10.9.101.163.
b=CT:1000.
t=0 0.
m=audio 49932 RTP/AVP 8 106.
c=IN IP4 10.9.101.163.
a=rtcp:49933.
a=label:Audio.
a=sendrecv.
a=rtpmap:8 PCMA/8000.
a=rtpmap:106 telephone-event/8000.
a=fmtp:106 0-16.


U 2012/10/15 11:46:49.075356 172.16.55.100:5060 -> 172.16.52.51:5060
SIP/2.0 183 Session Progress.
FROM:
"Pippo"<[hidden email];user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
TO: <[hidden email];user=phone>;tag=5a974d1727;epid=D430E933C4.
CSEQ: 199290652 INVITE.
CALL-ID: [hidden email].
VIA: SIP/2.0/UDP
172.16.52.51;received=172.16.52.51;branch=z9hG4bK8deb021f4c19be2ec40d3d277d6a6684;rport=5060.
RECORD-ROUTE:
<sip:172.16.55.100;transport=tcp;r2=on;lr;did=9c5.414e9ea5>,<sip:172.16.55.100;r2=on;lr;did=9c5.414e9ea5>.
CONTACT: <sip:LYNC.lwtec.eu:5068;transport=Tcp;maddr=10.9.101.163>.
CONTENT-LENGTH: 255.
CONTENT-TYPE: application/sdp.
ALLOW: CANCEL.
ALLOW: BYE.
ALLOW: UPDATE.
ALLOW: PRACK.
REQUIRE: 100rel.
SERVER: RTCC/4.0.0.0 MediationServer.
Rseq: 1.
.
v=0.
o=- 322 1 IN IP4 10.9.101.163.
s=session.
c=IN IP4 10.9.101.163.
b=CT:1000.
t=0 0.
m=audio 49932 RTP/AVP 8 106.
c=IN IP4 10.9.101.163.
a=rtcp:49933.
a=label:Audio.
a=sendrecv.
a=rtpmap:8 PCMA/8000.
a=rtpmap:106 telephone-event/8000.
a=fmtp:106 0-16.


U 2012/10/15 11:46:49.081243 172.16.52.51:5060 -> 172.16.55.100:5060
PRACK sip:LYNC.lwtec.eu:5068;transport=Tcp;maddr=10.9.101.163 SIP/2.0.
Route: <sip:172.16.55.100;r2=on;lr;did=9c5.414e9ea5>.
Route: <sip:172.16.55.100;transport=tcp;r2=on;lr;did=9c5.414e9ea5>.
RAck: 1 199290652 INVITE.
User-Agent: OXO_GW_820/044.001.
To: <[hidden email];user=phone>;tag=5a974d1727.
From:
<[hidden email];user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
Call-ID: [hidden email].
CSeq: 199290653 PRACK.
Via: SIP/2.0/UDP
172.16.52.51;rport;branch=z9hG4bKc251164105a928a498f5929f05f70361.
Max-Forwards: 70.
Content-Length: 0.
.


T 2012/10/15 11:46:49.082991 172.16.55.100:44921 -> 10.9.101.163:5068 [AP]
PRACK sip:LYNC.lwtec.eu:5068;transport=Tcp;maddr=10.9.101.163 SIP/2.0.
RAck: 1 199290652 INVITE.
User-Agent: OXO_GW_820/044.001.
To: <[hidden email];user=phone>;tag=5a974d1727.
From:
<[hidden email];user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
Call-ID: [hidden email].
CSeq: 199290653 PRACK.
Via: SIP/2.0/TCP 172.16.55.100;branch=z9hG4bKc16.62a48a97.0.
Via: SIP/2.0/UDP
172.16.52.51;received=172.16.52.51;rport=5060;branch=z9hG4bKc251164105a928a498f5929f05f70361.
Max-Forwards: 69.
Content-Length: 0.
.


T 2012/10/15 11:46:49.086412 10.9.101.163:5068 -> 172.16.55.100:44921 [AP]
SIP/2.0 200 OK.
FROM:
<[hidden email];user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
TO: <[hidden email];user=phone>;tag=5a974d1727;epid=D430E933C4.
CSEQ: 199290653 PRACK.
CALL-ID: [hidden email].
VIA: SIP/2.0/TCP 172.16.55.100;branch=z9hG4bKc16.62a48a97.0,SIP/2.0/UDP
172.16.52.51;received=172.16.52.51;branch=z9hG4bKc251164105a928a498f5929f05f70361;rport=5060.
CONTENT-LENGTH: 0.
SERVER: RTCC/4.0.0.0 MediationServer.
.


U 2012/10/15 11:46:49.087375 172.16.55.100:5060 -> 172.16.52.51:5060
SIP/2.0 200 OK.
FROM:
<[hidden email];user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
TO: <[hidden email];user=phone>;tag=5a974d1727;epid=D430E933C4.
CSEQ: 199290653 PRACK.
CALL-ID: [hidden email].
VIA: SIP/2.0/UDP
172.16.52.51;received=172.16.52.51;branch=z9hG4bKc251164105a928a498f5929f05f70361;rport=5060.
CONTENT-LENGTH: 0.
SERVER: RTCC/4.0.0.0 MediationServer.
.


T 2012/10/15 11:46:49.831450 10.9.101.163:5068 -> 172.16.55.100:44921 [AP]
SIP/2.0 183 Session Progress.
FROM:
"Pippo"<[hidden email];user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
TO: <[hidden email];user=phone>;tag=5a974d1727;epid=D430E933C4.
CSEQ: 199290652 INVITE.
CALL-ID: [hidden email].
VIA: SIP/2.0/TCP 172.16.55.100;branch=z9hG4bKb16.90f5e873.0,SIP/2.0/UDP
172.16.52.51;received=172.16.52.51;branch=z9hG4bK8deb021f4c19be2ec40d3d277d6a6684;rport=5060.
RECORD-ROUTE:
<sip:172.16.55.100;transport=tcp;r2=on;lr;did=9c5.414e9ea5>,<sip:172.16.55.100;r2=on;lr;did=9c5.414e9ea5>.
CONTACT: <sip:LYNC.lwtec.eu:5068;transport=Tcp;maddr=10.9.101.163>.
CONTENT-LENGTH: 0.
ALLOW: CANCEL.
ALLOW: BYE.
ALLOW: UPDATE.
ALLOW: PRACK.
SERVER: RTCC/4.0.0.0 MediationServer.
.


U 2012/10/15 11:46:49.832831 172.16.55.100:5060 -> 172.16.52.51:5060
SIP/2.0 183 Session Progress.
FROM:
"Pippo"<[hidden email];user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
TO: <[hidden email];user=phone>;tag=5a974d1727;epid=D430E933C4.
CSEQ: 199290652 INVITE.
CALL-ID: [hidden email].
VIA: SIP/2.0/UDP
172.16.52.51;received=172.16.52.51;branch=z9hG4bK8deb021f4c19be2ec40d3d277d6a6684;rport=5060.
RECORD-ROUTE:
<sip:172.16.55.100;transport=tcp;r2=on;lr;did=9c5.414e9ea5>,<sip:172.16.55.100;r2=on;lr;did=9c5.414e9ea5>.
CONTACT: <sip:LYNC.lwtec.eu:5068;transport=Tcp;maddr=10.9.101.163>.
CONTENT-LENGTH: 0.
ALLOW: CANCEL.
ALLOW: BYE.
ALLOW: UPDATE.
ALLOW: PRACK.
SERVER: RTCC/4.0.0.0 MediationServer.
.


T 2012/10/15 11:46:50.042796 10.9.101.163:5068 -> 172.16.55.100:44921 [AP]
SIP/2.0 180 Ringing.
FROM:
"Pippo"<[hidden email];user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
TO: <[hidden email];user=phone>;tag=5a974d1727;epid=D430E933C4.
CSEQ: 199290652 INVITE.
CALL-ID: [hidden email].
VIA: SIP/2.0/TCP 172.16.55.100;branch=z9hG4bKb16.90f5e873.0,SIP/2.0/UDP
172.16.52.51;received=172.16.52.51;branch=z9hG4bK8deb021f4c19be2ec40d3d277d6a6684;rport=5060.
RECORD-ROUTE:
<sip:172.16.55.100;transport=tcp;r2=on;lr;did=9c5.414e9ea5>,<sip:172.16.55.100;r2=on;lr;did=9c5.414e9ea5>.
CONTACT: <sip:LYNC.lwtec.eu:5068;transport=Tcp;maddr=10.9.101.163>.
CONTENT-LENGTH: 0.
ALLOW: CANCEL.
ALLOW: BYE.
ALLOW: UPDATE.
ALLOW: PRACK.
SERVER: RTCC/4.0.0.0 MediationServer.
.


T 2012/10/15 11:46:50.043132 10.9.101.163:5068 -> 172.16.55.100:44921 [AP]
SIP/2.0 183 Session Progress.
FROM:
"Pippo"<[hidden email];user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
TO: <[hidden email];user=phone>;tag=5a974d1727;epid=D430E933C4.
CSEQ: 199290652 INVITE.
CALL-ID: [hidden email].
VIA: SIP/2.0/TCP 172.16.55.100;branch=z9hG4bKb16.90f5e873.0,SIP/2.0/UDP
172.16.52.51;received=172.16.52.51;branch=z9hG4bK8deb021f4c19be2ec40d3d277d6a6684;rport=5060.
RECORD-ROUTE:
<sip:172.16.55.100;transport=tcp;r2=on;lr;did=9c5.414e9ea5>,<sip:172.16.55.100;r2=on;lr;did=9c5.414e9ea5>.
CONTACT: <sip:LYNC.lwtec.eu:5068;transport=Tcp;maddr=10.9.101.163>.
CONTENT-LENGTH: 0.
ALLOW: CANCEL.
ALLOW: BYE.
ALLOW: UPDATE.
ALLOW: PRACK.
SERVER: RTCC/4.0.0.0 MediationServer.
.


U 2012/10/15 11:46:50.044785 172.16.55.100:5060 -> 172.16.52.51:5060
SIP/2.0 180 Ringing.
FROM:
"Pippo"<[hidden email];user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
TO: <[hidden email];user=phone>;tag=5a974d1727;epid=D430E933C4.
CSEQ: 199290652 INVITE.
CALL-ID: [hidden email].
VIA: SIP/2.0/UDP
172.16.52.51;received=172.16.52.51;branch=z9hG4bK8deb021f4c19be2ec40d3d277d6a6684;rport=5060.
RECORD-ROUTE:
<sip:172.16.55.100;transport=tcp;r2=on;lr;did=9c5.414e9ea5>,<sip:172.16.55.100;r2=on;lr;did=9c5.414e9ea5>.
CONTACT: <sip:LYNC.lwtec.eu:5068;transport=Tcp;maddr=10.9.101.163>.
CONTENT-LENGTH: 0.
ALLOW: CANCEL.
ALLOW: BYE.
ALLOW: UPDATE.
ALLOW: PRACK.
SERVER: RTCC/4.0.0.0 MediationServer.
.


U 2012/10/15 11:46:50.045966 172.16.55.100:5060 -> 172.16.52.51:5060
SIP/2.0 183 Session Progress.
FROM:
"Pippo"<[hidden email];user=phone>;tag=2dd6eb349c4b183f08a67d2ad065655e.
TO: <[hidden email];user=phone>;tag=5a974d1727;epid=D430E933C4.
CSEQ: 199290652 INVITE.
CALL-ID: [hidden email].
VIA: SIP/2.0/UDP
172.16.52.51;received=172.16.52.51;branch=z9hG4bK8deb021f4c19be2ec40d3d277d6a6684;rport=5060.
RECORD-ROUTE:
<sip:172.16.55.100;transport=tcp;r2=on;lr;did=9c5.414e9ea5>,<sip:172.16.55.100;r2=on;lr;did=9c5.414e9ea5>.
CONTACT: <sip:LYNC.lwtec.eu:5068;transport=Tcp;maddr=10.9.101.163>.
CONTENT-LENGTH: 0.
ALLOW: CANCEL.
ALLOW: BYE.
ALLOW: UPDATE.
ALLOW: PRACK.
SERVER: RTCC/4.0.0.0 MediationServer.



--
View this message in context: http://opensips-open-sip-server.1449251.n2.nabble.com/Catch-183-Session-Progress-tp7582272.html
Sent from the OpenSIPS - Users mailing list archive at Nabble.com.

_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users


_______________________________________________
Users mailing list
[hidden email]
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
Reply | Threaded
Open this post in threaded view
|

Re: Catch 183 Session Progress

Spady#7
This post has NOT been accepted by the mailing list yet.
Hi Sam, seems does not work
Here a snippet of used code:

route[1] {
        # for INVITEs enable some additional helper routes
        if (is_method("INVITE")) {
               

                t_on_branch("2");
                t_on_reply("3");
                t_on_failure("1");
        }


xlog("L_DBG",
                "########LOG: va qua ROUTE[1]!!!!!\n");
        if (!t_relay()) {
                send_reply("500","Internal Error");
        };
        exit;
        }
....
....

onreply_route[3] {
if (t_check_status("183")) {
     # no support for early media
           xlog( "L_ERR", "########LOG: Individuo il 183!!!!\n" );
        }
}


and here is the entire log of call:


Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:parse_msg: SIP Request:
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:parse_msg:  method:  <INVITE>
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:parse_msg:  uri:     <sip:3707@172.16.55.100;user=phone>
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:parse_msg:  version: <SIP/2.0>
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:parse_headers: flags=2
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:parse_to: end of header reached, state=10
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:parse_to: display={}, ruri={sip:3707@172.16.55.100;user=phone}
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:get_hdr_field: <To> [37]; uri=[sip:3707@172.16.55.100;user=phone]
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:get_hdr_field: to body [<sip:3707@172.16.55.100;user=phone>#015#012]
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:get_hdr_field: cseq <CSeq>: <1964882669> <INVITE>
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:parse_via_param: found param type 235, <rport> = <n/a>; state=6
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bKa4f96fcaf74d3d8fa90295d6976ff185>; sta
=16
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:parse_via: end of header reached, state=5
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:parse_headers: via found, flags=2
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:parse_headers: this is the first via
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:receive_msg: After parse_msg...
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:receive_msg: preparing to run routing scripts...
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:parse_headers: flags=100
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:maxfwd:is_maxfwd_present: value = 70
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:uri:has_totag: no totag
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:parse_headers: flags=78
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:tm:t_lookup_request: start searching: hash=25299, isACK=0
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:tm:matching_3261: RFC3261 transaction matching failed
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:tm:t_lookup_request: no transaction found
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:parse_to_param: tag=7a84d6b80b231ad128ab186bbe9e0711
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:parse_to: end of header reached, state=29
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:parse_to: display={"Pippo"}, ruri={sip:100@172.16.52.51;user=phone}
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:grep_sock_info: checking if host==us: 12==13 &&  [172.16.52.51] == [172.16.55.100]
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:grep_sock_info: checking if host==us: 12==13 &&  [172.16.52.51] == [172.16.55.100]
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:check_self: host != me
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:grep_sock_info: checking if host==us: 13==13 &&  [172.16.55.100] == [172.16.55.100]
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:parse_headers: flags=200
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:get_hdr_field: content_length=215
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:get_hdr_field: found end of header
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:rr:find_first_route: No Route headers found
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:rr:loose_route: There is no Route HF
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:buf_init: initializing...
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: ########LOG: La chiamata entra qua!!!!!
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:dialog:w_create_dialog2: bye on timeout activated
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:dialog:build_new_dlg: new dialog 0xaeeca400 (c=f926c9b48de593828fc602d2aae085f6@172.16.52.51,f=sip:10
172.16.52.51;user=phone,t=sip:3707@172.16.55.100;user=phone,ft=7a84d6b80b231ad128ab186bbe9e0711) on hash 3308
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:parse_headers: flags=ffffffffffffffff
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:dialog:init_leg_info: route_set , contact sip:100@172.16.52.51;transport=UDP;user=phone, cseq 1964882
9 and bind_addr udp:172.16.55.100:5060
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:dialog:dlg_add_leg_info: set leg 0 for 0xaeeca400: tag=<7a84d6b80b231ad128ab186bbe9e0711> rcseq=<0>
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:dialog:link_dlg: ref dlg 0xaeeca400 with 3 -> 3
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:rr:add_rr_param: adding (;did=cec.c4d7d922) 0xb71ece44
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:grep_sock_info: checking if host==us: 13==13 &&  [172.16.55.100] == [172.16.55.100]
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:tm:t_newtran: transaction on entrance=(nil)
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:parse_headers: flags=ffffffffffffffff
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:parse_headers: flags=78
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:tm:t_lookup_request: start searching: hash=25299, isACK=0
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:tm:matching_3261: RFC3261 transaction matching failed
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:tm:t_lookup_request: no transaction found
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:tm:run_reqin_callbacks: trans=0xaeedf790, callback type 1, id 1 entered
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:dialog:dlg_onreq: t hash_index = 25299, t label = 1169250091
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:dialog:get_dlg_timeout: invalid AVP value, use default timeout
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:tm:run_reqin_callbacks: trans=0xaeedf790, callback type 1, id 0 entered
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:parse_headers: flags=78
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:parse_headers: flags=ffffffffffffffff
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:_shm_resize: resize(0) called
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:tm:_reply_light: reply sent out. buf=0xb71ee62c: SIP/2.0 1..., shmem=0xaeedf3a8: SIP/2.0 1
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:tm:_reply_light: finished
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:parse_headers: flags=2000
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:tcp_send: tcp connection found (0xaeecd248), acquiring fd
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14091]: DBG:core:handle_ser_child: read response= aeecd248, 1, fd -1 from 4 (14083)
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:tcp_send: c= 0xaeecd248, n=8
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:tcp_send: after receive_fd: c= 0xaeecd248 n=4 fd=6
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:tcp_send: sending...
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:tcp_send: after write: c= 0xaeecd248 n=1222 fd=6
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:tcp_send: buf=#012INVITE sip:3707@lync.lwtec.eu:5068;user=phone SIP/2.0#015#012Record-Route: <si
172.16.55.100;transport=tcp;r2=on;lr;did=cec.c4d7d922>#015#012Record-Route: <sip:172.16.55.100;r2=on;lr;did=cec.c4d7d922>#015#012Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, PRACK,
FER, NOTIFY, UPDATE#015#012Supported: 100rel,from-change,timer,histinfo#015#012User-Agent: OXO_GW_820/044.001#015#012Session-Expires: 43200#015#012P-Asserted-Identity: "Pippo" <s
:100@172.16.52.51;user=phone>#015#012History-Info: <sip:3707@172.16.55.100;user=phone>;index=1#015#012To: <sip:3707@172.16.55.100;user=phone>#015#012From: "Pippo" <sip:100@172.16
2.51;user=phone>;tag=7a84d6b80b231ad128ab186bbe9e0711#015#012Contact: "Pippo" <sip:100@172.16.52.51;transport=UDP;user=phone>#015#012Content-Type: application/sdp#015#012Call-ID:
926c9b48de593828fc602d2aae085f6@172.16.52.51#015#012CSeq: 1964882669 INVITE#015#012Via: SIP/2.0/TCP 172.16.55.100;branch=z9hG4bK3d26.b2751b54.0#015#012Via: SIP/2.0/UDP 172.16.52.
;received=172.16.52.51;rport=5060;branch=z9hG4bKa4f96fcaf74d3d8fa90295d6976ff185#015#012Max-Forwards: 69#015#012Content-Length: 215#015#012#015#012v=0#015#012o=default 1350302151
350302151 IN IP4 172.16.52.51#015#012s=-#015#012c=IN IP4 172.16.52.51#015#012t=0 0#015#012m=audio 32000 RTP/AVP 8 106 0#015#012a=sendrecv#015#012a=rtpmap:106 telephone-event/8000
15#012a=fmtp:106 0-15#015#012a=ptime:20#015#012a=maxptime:90#015#012
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:tm:set_timer: relative timeout is 5
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:tm:insert_timer_unsafe: [0]: 0xaeedf8f8 (119)
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:tm:t_relay_to: new transaction fwd'ed
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:tm:t_unref: UNREF_UNSAFE: [0xaeedf790] after is 0
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:dialog:unref_dlg: unref dlg 0xaeeca400 with 1 -> 2
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:destroy_avp_list: destroying list (nil)
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14083]: DBG:core:receive_msg: cleaning up
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14091]: DBG:core:handle_tcpconn_ev: data available on 0xaeecd248 25
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14091]: DBG:core:io_watch_del: io_watch_del (0x81a0b80, 25, -1, 0x0) fd_no=19 called
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14091]: DBG:core:send2child: to tcp child 0 0(14087), 0xaeecd248
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:handle_io: received n=4 con=0xaeecd248, fd=19
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:io_watch_add: io_watch_add(0x81a0ce0, 19, 2, 0xaeecd248), fd_no=1
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:tcp_read_req: content-length= 0
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_msg: SIP Reply  (status):
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_msg:  version: <SIP/2.0>
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_msg:  status:  <100>
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_msg:  reason:  <Trying>
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: flags=2
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_to: end of header reached, state=10
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_to: display={}, ruri={sip:3707@172.16.55.100;user=phone}
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:get_hdr_field: <TO> [37]; uri=[sip:3707@172.16.55.100;user=phone]
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:get_hdr_field: to body [<sip:3707@172.16.55.100;user=phone>#015#012]
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:get_hdr_field: cseq <CSEQ>: <1964882669> <INVITE>
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bK3d26.b2751b54.0>; state=9
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via: next_via
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via_param: found param type 234, <received> = <172.16.52.51>; state=6
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bKa4f96fcaf74d3d8fa90295d6976ff185>; sta
=6
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via_param: found param type 235, <rport> = <5060>; state=16
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via: end of header reached, state=5
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: via found, flags=2
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: this is the first via
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:receive_msg: After parse_msg...
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:forward_reply: found module tm, passing reply to it
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_check: start=0xffffffff
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: flags=22
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: flags=8
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_reply_matching: hash 25299 label 1169250091 branch 0
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_reply_matching: REF_UNSAFE:[0xaeedf790] after is 1
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_reply_matching: reply matched (T=0xaeedf790)!
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:run_trans_callbacks: trans=0xaeedf790, callback type 2, id 1 entered
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_check: end=0xaeedf790
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:reply_received: org. status uas=100, uac[0]=0 local=0 is_invite=1)
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_should_relay_response: T_code=100, new_code=100
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:relay_reply: branch=0, save=0, relay=-1
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:set_timer: relative timeout is 30
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:insert_timer_unsafe: [1]: 0xaeedf8f8 (144)
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_unref: UNREF_UNSAFE: [0xaeedf790] after is 0
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:destroy_avp_list: destroying list (nil)
Oct 15 13:57:55 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:receive_msg: cleaning up
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:tcp_read_req: content-length= 255
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_msg: SIP Reply  (status):
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_msg:  version: <SIP/2.0>
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_msg:  status:  <183>
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_msg:  reason:  <Session Progress>
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: flags=2
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_to_param: tag=c5e02d43d2
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_to_param: epid=D430E933C4
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_to: end of header reached, state=29
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_to: display={}, ruri={sip:3707@172.16.55.100;user=phone}
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:get_hdr_field: <TO> [68]; uri=[sip:3707@172.16.55.100;user=phone]
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:get_hdr_field: to body [<sip:3707@172.16.55.100;user=phone>]
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:get_hdr_field: cseq <CSEQ>: <1964882669> <INVITE>
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bK3d26.b2751b54.0>; state=9
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via: next_via
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via_param: found param type 234, <received> = <172.16.52.51>; state=6
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bKa4f96fcaf74d3d8fa90295d6976ff185>; sta
=6
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via_param: found param type 235, <rport> = <5060>; state=16
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via: end of header reached, state=5
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: via found, flags=2
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: this is the first via
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:receive_msg: After parse_msg...
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:forward_reply: found module tm, passing reply to it
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_check: start=0xffffffff
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: flags=22
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: flags=8
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_reply_matching: hash 25299 label 1169250091 branch 0
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_reply_matching: REF_UNSAFE:[0xaeedf790] after is 1
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_reply_matching: reply matched (T=0xaeedf790)!
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:run_trans_callbacks: trans=0xaeedf790, callback type 2, id 1 entered
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_check: end=0xaeedf790
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:reply_received: org. status uas=100, uac[0]=100 local=0 is_invite=1)
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_should_relay_response: T_code=100, new_code=183
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:relay_reply: branch=0, save=0, relay=0
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:run_trans_callbacks: trans=0xaeedf790, callback type 8, id 0 entered
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:dialog:push_reply_in_dialog: 0xaeeca400 totag in rpl is <c5e02d43d2> (10)
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:dialog:push_reply_in_dialog: new branch with tag <c5e02d43d2>
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:dialog:init_leg_info: route_set , contact , cseq 1964882669 and bind_addr tcp:172.16.55.100:5060
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:dialog:dlg_add_leg_info: set leg 1 for 0xaeeca400: tag=<c5e02d43d2> rcseq=<1964882669>
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:build_res_buf_from_sip_res:  old size: 1053, new size: 997
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:build_res_buf_from_sip_res: copied size: orig:329, new: 273, rest: 724 msg=#012SIP/2.0 183 Sessi
 Progress#015#012FROM: "Pippo"<sip:100@172.16.52.51;user=phone>;tag=7a84d6b80b231ad128ab186bbe9e0711#015#012TO: <sip:3707@172.16.55.100;user=phone>;tag=c5e02d43d2;epid=D430E933C4
15#012CSEQ: 1964882669 INVITE#015#012CALL-ID: f926c9b48de593828fc602d2aae085f6@172.16.52.51#015#012VIA: SIP/2.0/UDP 172.16.52.51;received=172.16.52.51;branch=z9hG4bKa4f96fcaf74d3
fa90295d6976ff185;rport=5060#015#012RECORD-ROUTE: <sip:172.16.55.100;transport=tcp;r2=on;lr;did=cec.c4d7d922>,<sip:172.16.55.100;r2=on;lr;did=cec.c4d7d922>#015#012CONTACT: <sip:L
C.lwtec.eu:5068;transport=Tcp;maddr=10.9.101.163>#015#012CONTENT-LENGTH: 255#015#012CONTENT-TYPE: application/sdp#015#012ALLOW: CANCEL#015#012ALLOW: BYE#015#012ALLOW: UPDATE#015#
2ALLOW: PRACK#015#012REQUIRE: 100rel#015#012SERVER: RTCC/4.0.0.0 MediationServer#015#012Rseq: 1#015#012#015#012v=0#015#012o=- 349 1 IN IP4 10.9.101.163#015#012s=session#015#012c=
 IP4 10.9.101.163#015#012b=CT:1000#015#012t=0 0#015#012m=audio 56078 RTP/AVP 8 106#015#012c=IN IP4 10.9.101.163#015#012a=rtcp:56079#015#012a=label:Audio#015#012a=sendrecv#015#012
rtpmap:8 PCMA/8000#015#012a=rtpmap:106 telephone-event/8000#015#012a=fmtp:106 0-16#015#012
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:run_trans_callbacks: trans=0xaeedf790, callback type 64, id 0 entered
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:dialog:next_state_dlg: dialog 0xaeeca400 changed from state 1 to state 2, due event 2
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:relay_reply: sent buf=0xb71fa35c: SIP/2.0 1..., shmem=0xaeee076c: SIP/2.0 1
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:run_trans_callbacks: trans=0xaeedf790, callback type 128, id 1 entered
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:set_timer: relative timeout is 30
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:insert_timer_unsafe: [1]: 0xaeedf8f8 (144)
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_unref: UNREF_UNSAFE: [0xaeedf790] after is 0
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:destroy_avp_list: destroying list (nil)
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:receive_msg: cleaning up
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:parse_msg: SIP Request:
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:parse_msg:  method:  <PRACK>
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:parse_msg:  uri:     <sip:LYNC.lwtec.eu:5068;transport=Tcp;maddr=10.9.101.163>
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:parse_msg:  version: <SIP/2.0>
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:parse_headers: flags=2
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:parse_to_param: tag=c5e02d43d2
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:parse_to: end of header reached, state=29
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:parse_to: display={}, ruri={sip:3707@172.16.55.100;user=phone}
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:get_hdr_field: <To> [52]; uri=[sip:3707@172.16.55.100;user=phone]
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:get_hdr_field: to body [<sip:3707@172.16.55.100;user=phone>]
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:get_hdr_field: cseq <CSeq>: <1964882670> <PRACK>
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:parse_via_param: found param type 235, <rport> = <n/a>; state=6
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bKe1eca88f534e31ee00ade8b18bfa8fbe>; sta
=16
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:parse_via: end of header reached, state=5
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:parse_headers: via found, flags=2
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:parse_headers: this is the first via
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:receive_msg: After parse_msg...
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:receive_msg: preparing to run routing scripts...
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:parse_headers: flags=100
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:maxfwd:is_maxfwd_present: value = 70
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:uri:has_totag: totag found
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: ########LOG: Individuo il TO-TAG!!!!
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:parse_headers: flags=200
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:rr:is_preloaded: is_preloaded: No
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:grep_sock_info: checking if host==us: 13==13 &&  [LYNC.lwtec.eu] == [172.16.55.100]
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:grep_sock_info: checking if port 5060 matches port 5068
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:grep_sock_info: checking if host==us: 13==13 &&  [LYNC.lwtec.eu] == [172.16.55.100]
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:grep_sock_info: checking if port 5060 matches port 5068
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:check_self: host != me
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:grep_sock_info: checking if host==us: 13==13 &&  [172.16.55.100] == [172.16.55.100]
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:rr:after_loose: Topmost route URI: 'sip:172.16.55.100;r2=on;lr;did=cec.c4d7d922' is me
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:grep_sock_info: checking if host==us: 13==13 &&  [172.16.55.100] == [172.16.55.100]
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:parse_headers: flags=200
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:get_hdr_field: content_length=0
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:get_hdr_field: found end of header
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:rr:find_next_route: No next Route HF found
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:rr:after_loose: no next URI found
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:rr:run_rr_callbacks: callback id 1 entered with <r2=on;lr;did=cec.c4d7d922>
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:dialog:dlg_onroute: route param is 'cec.c4d7d922' (len=12)
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:dialog:lookup_dlg: ref dlg 0xaeeca400 with 1 -> 3
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:dialog:lookup_dlg: dialog id=580746572 found on entry 3308
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:parse_headers: flags=58
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:parse_to_param: tag=7a84d6b80b231ad128ab186bbe9e0711
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:parse_to: end of header reached, state=29
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:parse_to: display={}, ruri={sip:100@172.16.52.51;user=phone}
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:dialog:next_state_dlg: dialog 0xaeeca400 changed from state 2 to state 2, due event 5
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:dialog:dlg_onroute: PRACK successfully processed (dst_leg=1)
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:dialog:switch_cseqs: prev_cseq = 1964882669 for leg 1
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:dialog:dlg_update_cseq: dlg 0xaeeca400[1]: cseq is 1964882670
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:dialog:dlg_validate_dialog: CSEQ validation passed
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: ########LOG: va qua ROUTE[1]!!!!!
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:tm:t_newtran: transaction on entrance=0xffffffff
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:parse_headers: flags=ffffffffffffffff
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:parse_headers: flags=78
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:tm:t_lookup_request: start searching: hash=25515, isACK=0
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:tm:matching_3261: RFC3261 transaction matching failed
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:tm:t_lookup_request: no transaction found
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:tm:run_reqin_callbacks: trans=0xaeecaa6c, callback type 1, id 1 entered
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:dialog:ref_dlg: ref dlg 0xaeeca400 with 1 -> 4
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:tm:run_reqin_callbacks: trans=0xaeecaa6c, callback type 1, id 0 entered
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:mk_proxy: doing DNS lookup...
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:parse_headers: flags=2000
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:tcp_send: tcp connection found (0xaeecd248), acquiring fd
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14091]: DBG:core:handle_ser_child: read response= aeecd248, 1, fd -1 from 2 (14081)
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:tcp_send: c= 0xaeecd248, n=8
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:tcp_send: after receive_fd: c= 0xaeecd248 n=4 fd=6
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:tcp_send: sending...
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:tcp_send: after write: c= 0xaeecd248 n=556 fd=6
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:tcp_send: buf=#012PRACK sip:LYNC.lwtec.eu:5068;transport=Tcp;maddr=10.9.101.163 SIP/2.0#015#012R
k: 1 1964882669 INVITE#015#012User-Agent: OXO_GW_820/044.001#015#012To: <sip:3707@172.16.55.100;user=phone>;tag=c5e02d43d2#015#012From: <sip:100@172.16.52.51;user=phone>;tag=7a84
b80b231ad128ab186bbe9e0711#015#012Call-ID: f926c9b48de593828fc602d2aae085f6@172.16.52.51#015#012CSeq: 1964882670 PRACK#015#012Via: SIP/2.0/TCP 172.16.55.100;branch=z9hG4bKba36.d2
ad87.0#015#012Via: SIP/2.0/UDP 172.16.52.51;received=172.16.52.51;rport=5060;branch=z9hG4bKe1eca88f534e31ee00ade8b18bfa8fbe#015#012Max-Forwards: 69#015#012Content-Length: 0#015#0
#015#012
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:tm:set_timer: relative timeout is 5
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:tm:insert_timer_unsafe: [0]: 0xaeecabd4 (119)
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:tm:t_relay_to: new transaction fwd'ed
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:tm:t_unref: UNREF_UNSAFE: [0xaeecaa6c] after is 0
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:dialog:unref_dlg: unref dlg 0xaeeca400 with 1 -> 3
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:destroy_avp_list: destroying list (nil)
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14081]: DBG:core:receive_msg: cleaning up
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:tcp_read_req: content-length= 0
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_msg: SIP Reply  (status):
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_msg:  version: <SIP/2.0>
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_msg:  status:  <200>
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_msg:  reason:  <OK>
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: flags=2
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_to_param: tag=c5e02d43d2
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_to_param: epid=D430E933C4
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_to: end of header reached, state=29
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_to: display={}, ruri={sip:3707@172.16.55.100;user=phone}
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:get_hdr_field: <TO> [68]; uri=[sip:3707@172.16.55.100;user=phone]
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:get_hdr_field: to body [<sip:3707@172.16.55.100;user=phone>]
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:get_hdr_field: cseq <CSEQ>: <1964882670> <PRACK>
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bKba36.d221ad87.0>; state=9
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via: next_via
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via_param: found param type 234, <received> = <172.16.52.51>; state=6
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bKe1eca88f534e31ee00ade8b18bfa8fbe>; state=6
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via_param: found param type 235, <rport> = <5060>; state=16
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via: end of header reached, state=5
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: via found, flags=2
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: this is the first via
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:receive_msg: After parse_msg...
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:forward_reply: found module tm, passing reply to it
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_check: start=0xffffffff
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: flags=22
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_reply_matching: hash 25515 label 2027557421 branch 0
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_reply_matching: REF_UNSAFE:[0xaeecaa6c] after is 1
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_reply_matching: reply matched (T=0xaeecaa6c)!
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_check: end=0xaeecaa6c
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:reply_received: org. status uas=0, uac[0]=0 local=0 is_invite=0)
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_should_relay_response: T_code=0, new_code=200
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:relay_reply: branch=0, save=0, relay=0
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:build_res_buf_from_sip_res:  old size: 472, new size: 416
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:build_res_buf_from_sip_res: copied size: orig:307, new: 251, rest: 165 msg=#012SIP/2.0 200 OK#015#012FROM: <sip:100@172.16.52.51;user=phone>;tag=7a84d6b80b231ad128ab186bbe9e0711#015#012TO: <sip:3707@172.16.55.100;user=phone>;tag=c5e02d43d2;epid=D430E933C4#015#012CSEQ: 1964882670 PRACK#015#012CALL-ID: f926c9b48de593828fc602d2aae085f6@172.16.52.51#015#012VIA: SIP/2.0/UDP 172.16.52.51;received=172.16.52.51;branch=z9hG4bKe1eca88f534e31ee00ade8b18bfa8fbe;rport=5060#015#012CONTENT-LENGTH: 0#015#012SERVER: RTCC/4.0.0.0 MediationServer#015#012#015#012
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:_shm_resize: resize(0) called
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:insert_timer_unsafe: [2]: 0xaeecaab4 (119)
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:relay_reply: sent buf=0xb71fa35c: SIP/2.0 2..., shmem=0xaeedf5e4: SIP/2.0 2
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:cleanup_uac_timers: RETR/FR timers reset
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_unref: UNREF_UNSAFE: [0xaeecaa6c] after is 0
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:destroy_avp_list: destroying list (nil)
Oct 15 13:57:56 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:receive_msg: cleaning up
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:tcp_read_req: content-length= 0
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_msg: SIP Reply  (status):
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_msg:  version: <SIP/2.0>
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_msg:  status:  <183>
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_msg:  reason:  <Session Progress>
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: flags=2
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_to_param: tag=c5e02d43d2
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_to_param: epid=D430E933C4
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_to: end of header reached, state=29
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_to: display={}, ruri={sip:3707@172.16.55.100;user=phone}
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:get_hdr_field: <TO> [68]; uri=[sip:3707@172.16.55.100;user=phone]
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:get_hdr_field: to body [<sip:3707@172.16.55.100;user=phone>]
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:get_hdr_field: cseq <CSEQ>: <1964882669> <INVITE>
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bK3d26.b2751b54.0>; state=9
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via: next_via
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via_param: found param type 234, <received> = <172.16.52.51>; state=6
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bKa4f96fcaf74d3d8fa90295d6976ff185>; state=6
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via_param: found param type 235, <rport> = <5060>; state=16
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via: end of header reached, state=5
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: via found, flags=2
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: this is the first via
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:receive_msg: After parse_msg...
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:forward_reply: found module tm, passing reply to it
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_check: start=0xffffffff
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: flags=22
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: flags=8
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_reply_matching: hash 25299 label 1169250091 branch 0
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_reply_matching: REF_UNSAFE:[0xaeedf790] after is 1
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_reply_matching: reply matched (T=0xaeedf790)!
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:run_trans_callbacks: trans=0xaeedf790, callback type 2, id 1 entered
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_check: end=0xaeedf790
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:reply_received: org. status uas=183, uac[0]=183 local=0 is_invite=1)
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_should_relay_response: T_code=183, new_code=183
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:relay_reply: branch=0, save=0, relay=0
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:run_trans_callbacks: trans=0xaeedf790, callback type 8, id 0 entered
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:dialog:push_reply_in_dialog: 0xaeeca400 totag in rpl is <c5e02d43d2> (10)
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:dialog:push_reply_in_dialog: branch with tag <c5e02d43d2> already exists
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:build_res_buf_from_sip_res:  old size: 739, new size: 683
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:build_res_buf_from_sip_res: copied size: orig:329, new: 273, rest: 410 msg=#012SIP/2.0 183 Session Progress#015#012FROM: "Pippo"<sip:100@172.16.52.51;user=phone>;tag=7a84d6b80b231ad128ab186bbe9e0711#015#012TO: <sip:3707@172.16.55.100;user=phone>;tag=c5e02d43d2;epid=D430E933C4#015#012CSEQ: 1964882669 INVITE#015#012CALL-ID: f926c9b48de593828fc602d2aae085f6@172.16.52.51#015#012VIA: SIP/2.0/UDP 172.16.52.51;received=172.16.52.51;branch=z9hG4bKa4f96fcaf74d3d8fa90295d6976ff185;rport=5060#015#012RECORD-ROUTE: <sip:172.16.55.100;transport=tcp;r2=on;lr;did=cec.c4d7d922>,<sip:172.16.55.100;r2=on;lr;did=cec.c4d7d922>#015#012CONTACT: <sip:LYNC.lwtec.eu:5068;transport=Tcp;maddr=10.9.101.163>#015#012CONTENT-LENGTH: 0#015#012ALLOW: CANCEL#015#012ALLOW: BYE#015#012ALLOW: UPDATE#015#012ALLOW: PRACK#015#012SERVER: RTCC/4.0.0.0 MediationServer#015#012#015#012
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:run_trans_callbacks: trans=0xaeedf790, callback type 64, id 0 entered
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:dialog:next_state_dlg: dialog 0xaeeca400 changed from state 2 to state 2, due event 2
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:relay_reply: sent buf=0xb71fa750: SIP/2.0 1..., shmem=0xaeee076c: SIP/2.0 1
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:run_trans_callbacks: trans=0xaeedf790, callback type 128, id 1 entered
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_unref: UNREF_UNSAFE: [0xaeedf790] after is 0
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:destroy_avp_list: destroying list (nil)
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:receive_msg: cleaning up
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:tcp_read_req: content-length= 0
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_msg: SIP Reply  (status):
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_msg:  version: <SIP/2.0>
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_msg:  status:  <180>
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_msg:  reason:  <Ringing>
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: flags=2
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_to_param: tag=c5e02d43d2
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_to_param: epid=D430E933C4
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_to: end of header reached, state=29
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_to: display={}, ruri={sip:3707@172.16.55.100;user=phone}
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:get_hdr_field: <TO> [68]; uri=[sip:3707@172.16.55.100;user=phone]
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:get_hdr_field: to body [<sip:3707@172.16.55.100;user=phone>]
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:get_hdr_field: cseq <CSEQ>: <1964882669> <INVITE>
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bK3d26.b2751b54.0>; state=9
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via: next_via
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via_param: found param type 234, <received> = <172.16.52.51>; state=6
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bKa4f96fcaf74d3d8fa90295d6976ff185>; state=6
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via_param: found param type 235, <rport> = <5060>; state=16
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via: end of header reached, state=5
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: via found, flags=2
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: this is the first via
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:receive_msg: After parse_msg...
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:forward_reply: found module tm, passing reply to it
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_check: start=0xffffffff
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: flags=22
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: flags=8
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_reply_matching: hash 25299 label 1169250091 branch 0
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_reply_matching: REF_UNSAFE:[0xaeedf790] after is 1
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_reply_matching: reply matched (T=0xaeedf790)!
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:run_trans_callbacks: trans=0xaeedf790, callback type 2, id 1 entered
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_check: end=0xaeedf790
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:reply_received: org. status uas=183, uac[0]=183 local=0 is_invite=1)
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_should_relay_response: T_code=183, new_code=180
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:relay_reply: branch=0, save=0, relay=0
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:run_trans_callbacks: trans=0xaeedf790, callback type 8, id 0 entered
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:dialog:push_reply_in_dialog: 0xaeeca400 totag in rpl is <c5e02d43d2> (10)
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:dialog:push_reply_in_dialog: branch with tag <c5e02d43d2> already exists
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:build_res_buf_from_sip_res:  old size: 730, new size: 674
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:build_res_buf_from_sip_res: copied size: orig:320, new: 264, rest: 410 msg=#012SIP/2.0 180 Ringing#015#012FROM: "Pippo"<sip:100@172.16.52.51;user=phone>;tag=7a84d6b80b231ad128ab186bbe9e0711#015#012TO: <sip:3707@172.16.55.100;user=phone>;tag=c5e02d43d2;epid=D430E933C4#015#012CSEQ: 1964882669 INVITE#015#012CALL-ID: f926c9b48de593828fc602d2aae085f6@172.16.52.51#015#012VIA: SIP/2.0/UDP 172.16.52.51;received=172.16.52.51;branch=z9hG4bKa4f96fcaf74d3d8fa90295d6976ff185;rport=5060#015#012RECORD-ROUTE: <sip:172.16.55.100;transport=tcp;r2=on;lr;did=cec.c4d7d922>,<sip:172.16.55.100;r2=on;lr;did=cec.c4d7d922>#015#012CONTACT: <sip:LYNC.lwtec.eu:5068;transport=Tcp;maddr=10.9.101.163>#015#012CONTENT-LENGTH: 0#015#012ALLOW: CANCEL#015#012ALLOW: BYE#015#012ALLOW: UPDATE#015#012ALLOW: PRACK#015#012SERVER: RTCC/4.0.0.0 MediationServer#015#012#015#012
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:run_trans_callbacks: trans=0xaeedf790, callback type 64, id 0 entered
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:dialog:next_state_dlg: dialog 0xaeeca400 changed from state 2 to state 2, due event 2
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:relay_reply: sent buf=0xb71fa750: SIP/2.0 1..., shmem=0xaeee076c: SIP/2.0 1
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:run_trans_callbacks: trans=0xaeedf790, callback type 128, id 1 entered
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_unref: UNREF_UNSAFE: [0xaeedf790] after is 0
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:destroy_avp_list: destroying list (nil)
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:receive_msg: cleaning up
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:tcp_read_req: content-length= 0
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_msg: SIP Reply  (status):
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_msg:  version: <SIP/2.0>
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_msg:  status:  <183>
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_msg:  reason:  <Session Progress>
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: flags=2
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_to_param: tag=c5e02d43d2
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_to_param: epid=D430E933C4
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_to: end of header reached, state=29
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_to: display={}, ruri={sip:3707@172.16.55.100;user=phone}
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:get_hdr_field: <TO> [68]; uri=[sip:3707@172.16.55.100;user=phone]
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:get_hdr_field: to body [<sip:3707@172.16.55.100;user=phone>]
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:get_hdr_field: cseq <CSEQ>: <1964882669> <INVITE>
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bK3d26.b2751b54.0>; state=9
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via: next_via
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via_param: found param type 234, <received> = <172.16.52.51>; state=6
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bKa4f96fcaf74d3d8fa90295d6976ff185>; state=6
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via_param: found param type 235, <rport> = <5060>; state=16
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_via: end of header reached, state=5
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: via found, flags=2
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: this is the first via
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:receive_msg: After parse_msg...
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:forward_reply: found module tm, passing reply to it
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_check: start=0xffffffff
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: flags=22
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:parse_headers: flags=8
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_reply_matching: hash 25299 label 1169250091 branch 0
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_reply_matching: REF_UNSAFE:[0xaeedf790] after is 1
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_reply_matching: reply matched (T=0xaeedf790)!
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:run_trans_callbacks: trans=0xaeedf790, callback type 2, id 1 entered
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_check: end=0xaeedf790
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:reply_received: org. status uas=180, uac[0]=180 local=0 is_invite=1)
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_should_relay_response: T_code=180, new_code=183
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:relay_reply: branch=0, save=0, relay=0
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:run_trans_callbacks: trans=0xaeedf790, callback type 8, id 0 entered
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:dialog:push_reply_in_dialog: 0xaeeca400 totag in rpl is <c5e02d43d2> (10)
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:dialog:push_reply_in_dialog: branch with tag <c5e02d43d2> already exists
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:build_res_buf_from_sip_res:  old size: 739, new size: 683
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:build_res_buf_from_sip_res: copied size: orig:329, new: 273, rest: 410 msg=#012SIP/2.0 183 Session Progress#015#012FROM: "Pippo"<sip:100@172.16.52.51;user=phone>;tag=7a84d6b80b231ad128ab186bbe9e0711#015#012TO: <sip:3707@172.16.55.100;user=phone>;tag=c5e02d43d2;epid=D430E933C4#015#012CSEQ: 1964882669 INVITE#015#012CALL-ID: f926c9b48de593828fc602d2aae085f6@172.16.52.51#015#012VIA: SIP/2.0/UDP 172.16.52.51;received=172.16.52.51;branch=z9hG4bKa4f96fcaf74d3d8fa90295d6976ff185;rport=5060#015#012RECORD-ROUTE: <sip:172.16.55.100;transport=tcp;r2=on;lr;did=cec.c4d7d922>,<sip:172.16.55.100;r2=on;lr;did=cec.c4d7d922>#015#012CONTACT: <sip:LYNC.lwtec.eu:5068;transport=Tcp;maddr=10.9.101.163>#015#012CONTENT-LENGTH: 0#015#012ALLOW: CANCEL#015#012ALLOW: BYE#015#012ALLOW: UPDATE#015#012ALLOW: PRACK#015#012SERVER: RTCC/4.0.0.0 MediationServer#015#012#015#012
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:run_trans_callbacks: trans=0xaeedf790, callback type 64, id 0 entered
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:dialog:next_state_dlg: dialog 0xaeeca400 changed from state 2 to state 2, due event 2
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:relay_reply: sent buf=0xb71fa750: SIP/2.0 1..., shmem=0xaeee076c: SIP/2.0 1
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:run_trans_callbacks: trans=0xaeedf790, callback type 128, id 1 entered
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:set_timer: relative timeout is 30
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:insert_timer_unsafe: [1]: 0xaeedf8f8 (146)
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:tm:t_unref: UNREF_UNSAFE: [0xaeedf790] after is 0
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:destroy_avp_list: destroying list (nil)
Oct 15 13:57:57 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:receive_msg: cleaning up


Oct 15 13:58:00 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:timer_routine: timer routine:0,tl=0xaeecabd4 next=(nil), timeout=119
Oct 15 13:58:00 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:timer_routine: timer routine:2,tl=0xaeecaab4 next=(nil), timeout=119
Oct 15 13:58:00 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:wait_handler: removing 0xaeecaa6c from table
Oct 15 13:58:00 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:delete_cell: delete transaction 0xaeecaa6c
Oct 15 13:58:00 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:run_trans_callbacks: trans=0xaeecaa6c, callback type 4096, id 0 entered
Oct 15 13:58:00 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:dialog:unref_dlg: unref dlg 0xaeeca400 with 1 -> 2
Oct 15 13:58:00 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:wait_handler: done











Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:t_uac: next_hop=<sip:10.9.6.3:5060>
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:core:mk_proxy: doing DNS lookup...
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:dlg2hash: 23757
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:print_request_uri: sip:10.9.6.3:5060
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:t_uac: building sip_msg from buffer
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:core:parse_msg: SIP Request:
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:core:parse_msg:  method:  <OPTIONS>
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:core:parse_msg:  uri:     <sip:10.9.6.3:5060>
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:core:parse_msg:  version: <SIP/2.0>
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:core:parse_headers: flags=2
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bKdcc5.818c5bf2.0>; state=16
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:core:parse_via: end of header reached, state=5
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:core:parse_headers: via found, flags=2
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:core:parse_headers: this is the first via
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:core:mk_proxy: doing DNS lookup...
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:set_timer: relative timeout is 500000
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:insert_timer_unsafe: [4]: 0xaeecabb8 (120500000)
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:set_timer: relative timeout is 5
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:insert_timer_unsafe: [0]: 0xaeecabd4 (125)
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:t_uac: next_hop=<sip:10.9.6.40:5060>
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:core:mk_proxy: doing DNS lookup...
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:dlg2hash: 23771
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:core:parse_msg: SIP Reply  (status):
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:core:parse_msg:  version: <SIP/2.0>
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:core:parse_msg:  status:  <200>
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:core:parse_msg:  reason:  <OK>
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:core:parse_headers: flags=2
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:core:parse_to_param: tag=2793ff09384a87bc55bce6300bbe68b5
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:core:parse_to: end of header reached, state=29
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:core:parse_to: display={}, ruri={sip:10.9.6.3:5060}
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:core:get_hdr_field: <To> [56]; uri=[sip:10.9.6.3:5060]
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:core:get_hdr_field: to body [sip:10.9.6.3:5060]
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:core:get_hdr_field: cseq <CSeq>: <10> <OPTIONS>
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bKdcc5.818c5bf2.0>; state=16
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:core:parse_via: end of header reached, state=5
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:core:parse_headers: via found, flags=2
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:core:parse_headers: this is the first via
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:core:receive_msg: After parse_msg...
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:core:forward_reply: found module tm, passing reply to it
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:tm:t_check: start=0xffffffff
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:core:parse_headers: flags=22
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:tm:t_reply_matching: hash 23757 label 800442392 branch 0
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:tm:t_reply_matching: REF_UNSAFE:[0xaeecaa6c] after is 1
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:tm:t_reply_matching: reply matched (T=0xaeecaa6c)!
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:tm:t_check: end=0xaeecaa6c
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:tm:reply_received: org. status uas=0, uac[0]=0 local=2 is_invite=0)
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:tm:t_should_relay_response: T_code=0, new_code=200
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:tm:local_reply: branch=0, save=0, winner=0
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:tm:local_reply: local transaction completed
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:tm:run_trans_callbacks: trans=0xaeecaa6c, callback type 256, id 0 entered
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:tm:cleanup_uac_timers: RETR/FR timers reset
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:tm:insert_timer_unsafe: [2]: 0xaeecaab4 (125)
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:tm:t_unref: UNREF_UNSAFE: [0xaeecaa6c] after is 0
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:core:destroy_avp_list: destroying list (nil)
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14084]: DBG:core:receive_msg: cleaning up
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:print_request_uri: sip:10.9.6.40:5060
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:t_uac: building sip_msg from buffer
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:core:parse_msg: SIP Request:
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:core:parse_msg:  method:  <OPTIONS>
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:core:parse_msg:  uri:     <sip:10.9.6.40:5060>
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:core:parse_msg:  version: <SIP/2.0>
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:core:parse_headers: flags=2
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bKbdc5.be3498e7.0>; state=16
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:core:parse_via: end of header reached, state=5
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:core:parse_headers: via found, flags=2
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:core:parse_headers: this is the first via
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:core:mk_proxy: doing DNS lookup...
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:set_timer: relative timeout is 500000
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:insert_timer_unsafe: [4]: 0xaeedd97c (120500000)
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:set_timer: relative timeout is 5
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:insert_timer_unsafe: [0]: 0xaeedd998 (125)
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:core:parse_msg: SIP Reply  (status):
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:core:parse_msg:  version: <SIP/2.0>
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:core:parse_msg:  status:  <200>
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:core:parse_msg:  reason:  <Ok>
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:core:parse_headers: flags=2
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bKbdc5.be3498e7.0>; state=16
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:core:parse_via: end of header reached, state=5
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:core:parse_headers: via found, flags=2
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:core:parse_headers: this is the first via
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:core:receive_msg: After parse_msg...
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:core:forward_reply: found module tm, passing reply to it
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:tm:t_check: start=0xffffffff
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:core:parse_headers: flags=22
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:core:parse_to: end of header reached, state=9
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:core:parse_to: display={}, ruri={sip:10.9.6.40:5060}
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:core:get_hdr_field: <To> [20]; uri=[sip:10.9.6.40:5060]
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:core:get_hdr_field: to body [sip:10.9.6.40:5060#015#012]
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:core:get_hdr_field: cseq <CSeq>: <10> <OPTIONS>
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:tm:t_reply_matching: hash 23771 label 2122925035 branch 0
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:tm:t_reply_matching: REF_UNSAFE:[0xaeedd830] after is 1
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:tm:t_reply_matching: reply matched (T=0xaeedd830)!
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:tm:t_check: end=0xaeedd830
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:tm:reply_received: org. status uas=0, uac[0]=0 local=2 is_invite=0)
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:tm:t_should_relay_response: T_code=0, new_code=200
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:tm:local_reply: branch=0, save=0, winner=0
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:tm:local_reply: local transaction completed
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:tm:run_trans_callbacks: trans=0xaeedd830, callback type 256, id 0 entered
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:tm:cleanup_uac_timers: RETR/FR timers reset
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:tm:insert_timer_unsafe: [2]: 0xaeedd878 (125)
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:tm:t_unref: UNREF_UNSAFE: [0xaeedd830] after is 0
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:core:destroy_avp_list: destroying list (nil)
Oct 15 13:58:01 opensips /usr/local/opensips_proxy/sbin/opensips[14082]: DBG:core:receive_msg: cleaning up




Oct 15 13:58:02 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:utimer_routine: timer routine:4,tl=0xaeecabb8 next=0xaeedd97c, timeout=120500000
Oct 15 13:58:02 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:utimer_routine: timer routine:4,tl=0xaeedd97c next=(nil), timeout=120500000
Oct 15 13:58:03 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:tcp_receive_timeout: 0xaeecd248 expired (121, 122) lt=0
Oct 15 13:58:03 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:io_watch_del: io_watch_del (0x81a0ce0, 19, -1, 0x10) fd_no=2 called
Oct 15 13:58:03 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:release_tcpconn:  releasing con 0xaeecd248, state 0, fd=19, id=2
Oct 15 13:58:03 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:release_tcpconn:  extra_data (nil)
Oct 15 13:58:03 opensips /usr/local/opensips_proxy/sbin/opensips[14091]: DBG:core:handle_tcp_child: reader response= aeecd248, 0 from 0
Oct 15 13:58:03 opensips /usr/local/opensips_proxy/sbin/opensips[14091]: DBG:core:io_watch_add: io_watch_add(0x81a0b80, 25, 2, 0xaeecd248), fd_no=18
Oct 15 13:58:03 opensips /usr/local/opensips_proxy/sbin/opensips[14091]: DBG:core:handle_tcp_child: cmd CONN_RELEASE  0xaeecd248 refcnt= 0
Oct 15 13:58:05 opensips /usr/local/opensips_proxy/sbin/opensips[14091]: DBG:core:handle_tcpconn_ev: data available on 0xaeeba2e4 24
Oct 15 13:58:05 opensips /usr/local/opensips_proxy/sbin/opensips[14091]: DBG:core:io_watch_del: io_watch_del (0x81a0b80, 24, -1, 0x0) fd_no=19 called
Oct 15 13:58:05 opensips /usr/local/opensips_proxy/sbin/opensips[14091]: DBG:core:send2child: to tcp child 0 0(14087), 0xaeeba2e4
Oct 15 13:58:05 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:handle_io: received n=4 con=0xaeeba2e4, fd=19
Oct 15 13:58:05 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:io_watch_add: io_watch_add(0x81a0ce0, 19, 2, 0xaeeba2e4), fd_no=1
Oct 15 13:58:05 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:tcp_read: EOF on 0xaeeba2e4, FD 19
Oct 15 13:58:05 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:tcp_read_req: EOF
Oct 15 13:58:05 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:io_watch_del: io_watch_del (0x81a0ce0, 19, -1, 0x10) fd_no=2 called
Oct 15 13:58:05 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:release_tcpconn:  releasing con 0xaeeba2e4, state -1, fd=19, id=1
Oct 15 13:58:05 opensips /usr/local/opensips_proxy/sbin/opensips[14087]: DBG:core:release_tcpconn:  extra_data (nil)
Oct 15 13:58:05 opensips /usr/local/opensips_proxy/sbin/opensips[14091]: DBG:core:handle_tcp_child: reader response= aeeba2e4, -1 from 0
Oct 15 13:58:05 opensips /usr/local/opensips_proxy/sbin/opensips[14091]: DBG:core:tcpconn_destroy: destroying connection 0xaeeba2e4, flags 0002
Oct 15 13:58:06 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:timer_routine: timer routine:0,tl=0xaeecabd4 next=0xaeedd998, timeout=125
Oct 15 13:58:06 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:timer_routine: timer routine:0,tl=0xaeedd998 next=(nil), timeout=125
Oct 15 13:58:06 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:timer_routine: timer routine:2,tl=0xaeecaab4 next=0xaeedd878, timeout=125
Oct 15 13:58:06 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:wait_handler: removing 0xaeecaa6c from table
Oct 15 13:58:06 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:delete_cell: delete transaction 0xaeecaa6c
Oct 15 13:58:06 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:wait_handler: done
Oct 15 13:58:06 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:timer_routine: timer routine:2,tl=0xaeedd878 next=(nil), timeout=125
Oct 15 13:58:06 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:wait_handler: removing 0xaeedd830 from table
Oct 15 13:58:06 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:delete_cell: delete transaction 0xaeedd830
Oct 15 13:58:06 opensips /usr/local/opensips_proxy/sbin/opensips[14086]: DBG:tm:wait_handler: done
^C                                    


Thanks
Reply | Threaded
Open this post in threaded view
|

Re: Catch 183 Session Progress

Spady#7
In reply to this post by SamyGo
Ok Sam, solved.
The t_relay was called before, in a different part of script. Now it's working as you suggested me.
Thanks