[OpenSIPS-Devel] Can't get 2.3 running with voiptests properly

[Maxim Sobolev]

Just a little big of more debug, the message processing on Alice side stops
after this exchange:

00:00:03.839/GLOBAL/alice_ua: RECEIVED message from 127.0.0.1:5060:
SIP/2.0 100 Giving a try
v: SIP/2.0/UDP 127.0.0.1:5061;received=127.0.0.1;branch=z9hG4bKfa99d5e33e5a65ffd3d6dff13e002248;rport=5061
f: "Alice Smith"
<sip:alice_9_ipv4 at 127.0.0.1>;tag=46ab5763086d0d7b59a49297c8088e99
t: <sip:bob_9 at 127.0.0.1>
i: c423f160e18369217e1bd71169afd781 at 172.17.0.7
CSeq: 200 INVITE
Server: OpenSIPS (2.3.1 (x86_64/linux))
Content-Length: 0

00:00:04.312/GLOBAL/alice_ua: SENDING message to [::1]:5060:
CANCEL sip:bob_early_cancel@[::1]:5060 SIP/2.0
Via: SIP/2.0/UDP [::1]:5061;rport;branch=z9hG4bK2ec62cc49f6f02cf2cbc93ec19e89ff5
Max-Forwards: 70
From: "Alice Smith"
<sip:alice_early_cancel_ipv6@[::1]>;tag=0eade29cb9f0b1a2aad797f2f052b9fc
To: <sip:bob_early_cancel@[::1]>
Call-ID: 61c307b71d7381e8283c5223f150e718 at 172.17.0.7
CSeq: 200 CANCEL
User-Agent: Sippy

Content-Length: 0

Then it pretty much stays mute for 30 seconds ignoring anything from
Alice until we get this:

00:00:34.185/GLOBAL/alice_ua: RECEIVED message from 127.0.0.1:5060:
SIP/2.0 408 Request Timeout
Via: SIP/2.0/UDP
127.0.0.1:5061;received=127.0.0.1;branch=z9hG4bK559f343d8acaf4f0255ffa7487b3d36e;rport=5061
From: "Alice Smith"
<sip:alice_4_ipv4 at 127.0.0.1>;tag=95f73c1ed722dbe6bf619dbd68147be5
To: <sip:bob_4 at 127.0.0.1>;tag=155c340f586c28d0300cf5a6ccf90d99-24f1
Call-ID: f8d8d410a7429a73ba92d6e2b186d9f8 at 172.17.0.7
CSeq: 200 INVITE
Server: OpenSIPS (2.3.1 (x86_64/linux))
Content-Length: 0

Now, looking at the bob side re-transmission of INVITEs continues
during the interval (looks like 100 Trying is being ignored).

Working hypothesis that I have is that this might be somehow related
to the early CANCEL transaction, I see some pattern of OpenSIPS going
mute after receiving that. Any thoughts? I might take that early
cancel test out and see if it helps.


On Thu, Jul 27, 2017 at 9:46 AM, Maxim Sobolev <sobomax at sippysoft.com>
wrote:

> Hi folks, we are getting some unexpected trouble with getting 2.3 to run
> properly with voiptests. The issue is that after brief time after starting
> up the proxy stops accepting any new requests/replies. As far as I can tell
> no error output is produced and the opensips eventually exits normally.
> I've added some debug output into the routing script to log replies, but I
> don't see anything logged after 100 Trying.
>
> Full log can be found here, it also dumps effective config.
>
> https://travis-ci.org/sippy/voiptests/jobs/257935671
>
> The only thing I can think of being unusual in our setup is that we are
> running in "foreground" mode, so that we can wait(1) on the pid and
> retrieve status code when it finishes.
>
> Any ideas are greatly appreciated. We can also add more debug if needed.
>
> Jul 26 23:53:38 [21441] OpenSIPS received a reply 100/INVITE from 0:0:0:0:0:0:0:1
>
> Jul 26 23:53:38 [21441] OpenSIPS received a reply 100/INVITE from 0:0:0:0:0:0:0:1
>
> Jul 26 23:53:38 [21441] OpenSIPS received a reply 100/INVITE from 0:0:0:0:0:0:0:1
>
> Jul 26 23:53:38 [21441] OpenSIPS received a reply 100/INVITE from 0:0:0:0:0:0:0:1
>
> Jul 26 23:53:38 [21441] OpenSIPS received a reply 100/INVITE from 0:0:0:0:0:0:0:1
>
> Jul 26 23:53:38 [21440] OpenSIPS received a reply 100/INVITE from 127.0.0.1
>
> Jul 26 23:53:38 [21440] OpenSIPS received a reply 100/INVITE from 127.0.0.1
>
> Jul 26 23:53:38 [21440] OpenSIPS received a reply 100/INVITE from 127.0.0.1
>
> Jul 26 23:53:38 [21441] OpenSIPS received a reply 100/INVITE from 0:0:0:0:0:0:0:1
>
> Jul 26 23:53:38 [21440] OpenSIPS received a reply 100/INVITE from 127.0.0.1
>
> Jul 26 23:53:38 [21441] OpenSIPS received a reply 100/INVITE from 0:0:0:0:0:0:0:1
>
> Jul 26 23:53:38 [21440] OpenSIPS received a reply 100/INVITE from 127.0.0.1
>
> Jul 26 23:53:38 [21440] OpenSIPS received a reply 100/INVITE from 127.0.0.1
>
> Jul 26 23:53:38 [21440] OpenSIPS received a reply 100/INVITE from 127.0.0.1
>
> Jul 26 23:53:38 [21441] OpenSIPS received a reply 100/INVITE from 0:0:0:0:0:0:0:1
>
> Jul 26 23:53:38 [21441] OpenSIPS received a reply 100/INVITE from 0:0:0:0:0:0:0:1
>
> Jul 26 23:53:38 [21441] OpenSIPS received a reply 100/INVITE from 0:0:0:0:0:0:0:1
>
> Jul 26 23:53:38 [21441] OpenSIPS received a reply 100/INVITE from 0:0:0:0:0:0:0:1
>
> Jul 26 23:53:38 [21441] OpenSIPS received a reply 100/INVITE from 0:0:0:0:0:0:0:1
>
> Jul 26 23:53:38 [21441] OpenSIPS received a reply 100/INVITE from 0:0:0:0:0:0:0:1
>
> Jul 26 23:53:38 [21440] OpenSIPS received a reply 100/INVITE from 127.0.0.1
>
> Jul 26 23:53:38 [21440] OpenSIPS received a reply 100/INVITE from 127.0.0.1
>
>
>
> +kill -TERM 21436
>
> +echo MM_PID: 21436
>
> MM_PID: 21436
>
> +wait 21436
>
> Thank you for flying opensips
>
> +MM_RC=0
>
>


-- 
Maksym Sobolyev
Sippy Software, Inc.
Internet Telephony (VoIP) Experts
Tel (Canada): +1-778-783-0474
Tel (Toll-Free): +1-855-747-7779
Fax: +1-866-857-6942
Web: http://www.sippysoft.com
MSN: sales at sippysoft.com
Skype: SippySoft
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.opensips.org/pipermail/devel/attachments/20170727/c79228a8/attachment.html>