[Spce-user] Proxy error

Marcos Pytel marcos.pytel at cotesma.com.ar
Wed Jun 12 14:25:27 EDT 2019


I don't know what is happening... The LB don't work ok now. All subscribers fails or retry Registration...

       10.7.1.58:5060              10.7.255.253:5060               127.0.0.1:5060                127.0.0.1:5062  |Via: SIP/2.0/UDP 10.7.1.58:5060;branch=z9h
          ----------+---------          ----------+---------          ----------+---------          ----------+---------|bK*002e-0000000c-0ec0
                    |          REGISTER           |                             |                             |         |From: <sip:sip_1112 at sip00.cotesma.com.ar>;
  15:23:03.362084   | --------------------------> |                             |                             |         |g=b72e12N265d014304-7da7de5f
        +0.000711   |                             |                             |          REGISTER           |         |To: sip:sip_1112 at sip00.cotesma.com.ar
  15:23:03.362795   |                             |                             | --------------------------> |         |Call-ID: 01-REG-FSM-4716aa24iiapwfjjjk at 10.
        +0.000322   |                             |                             |         100 Trying          |         |1.58
  15:23:03.363117   |                             |                             | <-------------------------- |         |CSeq: 3 REGISTER
        +0.000753   |                             |                             |      401 Unauthorized       |         |Max-Forwards: 70
  15:23:03.363870   |                             |                             | <-------------------------- |         |Contact: sip:sip_1112 at 10.7.1.58:5060
        +0.502219   |          REGISTER           |                             |                             |         |Expires: 3600
  15:23:03.866089   | ------------------------>>> |                             |                             |         |Accept: application/sdp,application/simple
        +1.000002   |          REGISTER           |                             |                             |         |essage-summary
  15:23:04.866091   | ------------------------>>> |                             |                             |         |Allow: INVITE,ACK,CANCEL,BYE,PRACK,UPDATE,
        +2.004003   |          REGISTER           |                             |                             |         |TIFY,OPTIONS,SUBSCRIBE
  15:23:06.870094   | ------------------------>>> |                             |                             |         |Authorization: Digest username="sip_1112",
        +4.008028   |          REGISTER           |                             |                             |         |alm="sip00.cotesma.com.ar",nonce="XQE3610B
  15:23:10.878122   | ------------------------>>> |                             |                             |         |9Om0CmYQ+p9G9pfc35tEhc",uri="sip:sip00.cot
        +0.296026   |      401 Unauthorized       |                             |                             |         |ma.com.ar",response="c61658edb8ae6145c5c83
  15:23:11.174148   | <-------------------------- |                             |                             |         |5b5c98c18",opaque=""
        +0.151942   |          REGISTER           |                             |                             |         |Supported: path
  15:23:11.326090   | --------------------------> |                             |                             |         |User-Agent: Alcatel-Lucent MSAN
        +0.000530   |                             |                             |          REGISTER           |         |Content-Length: 0
  15:23:11.326620   |                             |                             | --------------------------> |         |
        +0.000302   |                             |                             |         100 Trying          |         |
  15:23:11.326922   |                             |                             | <-------------------------- |         |
        +0.006880   |                             |                             |           200 OK            |         |
  15:23:11.333802   |                             |                             | <-------------------------- |         |
        +0.492302   |          REGISTER           |                             |                             |         |
  15:23:11.826104   | ------------------------>>> |                             |                             |         |
        +1.004135   |          REGISTER           |                             |                             |         |
  15:23:12.830239   | ------------------------>>> |                             |                             |         |
        +0.924921   |           200 OK            |                             |                             |         |
  15:23:13.755160   | <-------------------------- |                             |                 


Jun 12 15:22:05 spce lb[2590]: CRITICAL: <core> [core/udp_server.c:613]: udp_send(): invalid sendtoparameters#012one possible reason is the server is bound to localhost and#012attempts to send to the net

Jun 12 15:22:24 spce proxy[18805]: NOTICE: <script>: Authentication failed, generic error - R=sip:sip00.cotesma.com.ar ID=13a4ba4-0001-0007-0001 at 10.7.255.250 UA='<null>' Auth=sip_0187
Jun 12 15:23:38 spce proxy[18807]: NOTICE: <script>: Authentication failed, generic error - R=sip:sip00.cotesma.com.ar ID=6e155cb-0001-0015-0022 at 10.7.255.250 UA='<null>' Auth=sip_0393
Jun 12 15:23:50 spce proxy[18800]: NOTICE: <script>: Authentication failed, generic error - R=sip:sip00.cotesma.com.ar ID=635a3d13-0001-0016-0016 at 10.7.255.250 UA='<null>' Auth=sip_1421
Jun 12 15:24:03 spce proxy[18800]: NOTICE: <script>: Authentication failed, generic error - R=sip:sip00.cotesma.com.ar ID=37224058-0001-0008-0019 at 10.7.255.250 UA='<null>' Auth=sip_0235


Jun 12 15:24:56 spce proxy[18803]: ERROR: uac [replace.c:265]: replace_uri(): called uac_replace_from() multiple times on the message
Jun 12 15:24:56 spce proxy[18803]: ERROR: uac [replace.c:265]: replace_uri(): called uac_replace_to() multiple times on the message



-----Mensaje original-----
De: Daniel Grotti <dgrotti at sipwise.com> 
Enviado el: miércoles 12 de junio del 2019 09:48
Para: Marcos Pytel <marcos.pytel at cotesma.com.ar>; spce-user at lists.sipwise.com
Asunto: Re: [Spce-user] Proxy error

Hi,
with sngrep you should sniff the REGISTER session and try to reproduce the ERROR message. Then check the trace.

The ERROR should not kill the proxy, but user may not receive any reply.



Daniel Grotti

Head of Customer Support                     Sipwise GmbH
e: dgrotti at sipwise.com                     Europaring F15
t: +43(0)130120332                A-2345 Brunn Am Gebirge
w: www.sipwise.com   FN: 305595f   FG: LG Wiener Neustadt

On 6/12/19 2:31 PM, Marcos Pytel wrote:
> Thank you Daniel for your quick reply!
> 
> This error can break the server?
> 
> In the proxy log for this called I have:
> 
> Jun 12 02:31:08 spce proxy[32004]: NOTICE: <script>: Authentication 
> failed, generic error - R=sip:sip00.cotesma.com.ar ID=11fb6a23-0001-0006-0002 at 10.7.255.250 UA='<null>' Auth=sip_0162 Jun 12 02:31:08 spce proxy[32004]: NOTICE: <script>: Sending reply S=401 fs='127.0.0.1:5062' du='127.0.0.1:5060' - ID=11fb6a23-0001-0006-0002 at 10.7.255.250 UA='<null>'
> Jun 12 02:31:08 spce proxy[32002]: NOTICE: <script>: New request on 
> proxy - M=REGISTER R=sip:sip00.cotesma.com.ar F=sip:sip_0162 at sip00.cotesma.com.ar T=sip:sip_0162 at sip00.cotesma.com.ar IP=10.7.255.250:5060 (127.0.0.1:5060) ID=11fb6a23-0001-0006-0002 at 10.7.255.250 UA='<null>' DESTIP=127.0.0.1:5062 Jun 12 02:31:08 spce proxy[32002]: NOTICE: <script>: Sending reply S=100 Trying fs='127.0.0.1:5062' du='127.0.0.1:5060' - R=sip:sip00.cotesma.com.ar ID=11fb6a23-0001-0006-0002 at 10.7.255.250 UA='<null>'
> Jun 12 02:31:08 spce proxy[32002]: NOTICE: <script>: Contacts successfully updated, expires in 3070s - R=sip:sip00.cotesma.com.ar ID=11fb6a23-0001-0006-0002 at 10.7.255.250 UA='<null>'
> Jun 12 02:31:08 spce proxy[32002]: NOTICE: <script>: Sending reply S=200 OK fs='127.0.0.1:5062' du='127.0.0.1:5060' - R=sip:sip00.cotesma.com.ar ID=11fb6a23-0001-0006-0002 at 10.7.255.250 UA='<null>'
> Jun 12 03:14:41 spce proxy[32008]: NOTICE: <script>: New request on 
> proxy - M=REGISTER R=sip:sip00.cotesma.com.ar F=sip:sip_0162 at sip00.cotesma.com.ar T=sip:sip_0162 at sip00.cotesma.com.ar IP=10.7.255.250:5060 (127.0.0.1:5060) ID=11fb6a23-0001-0006-0002 at 10.7.255.250 UA='<null>' DESTIP=127.0.0.1:5062 Jun 12 03:14:41 spce proxy[32008]: NOTICE: <script>: Sending reply S=100 Trying fs='127.0.0.1:5062' du='127.0.0.1:5060' - R=sip:sip00.cotesma.com.ar ID=11fb6a23-0001-0006-0002 at 10.7.255.250 UA='<null>'
> Jun 12 03:14:41 spce proxy[32008]: NOTICE: <script>: Authentication 
> failed, generic error - R=sip:sip00.cotesma.com.ar 
> ID=11fb6a23-0001-0006-0002 at 10.7.255.250 UA='<null>' Auth=sip_0162
> 
> I can prevent it?
> 
> Best Regards,
> Marcos.
> 
> 
> 
> 
> -----Mensaje original-----
> De: Spce-user <spce-user-bounces at lists.sipwise.com> En nombre de 
> Daniel Grotti Enviado el: miércoles 12 de junio del 2019 09:01
> Para: spce-user at lists.sipwise.com
> Asunto: Re: [Spce-user] Proxy error
> 
> Hi Marcos,
> 
> You have a bad REGISTER coming in with invalid Cseq header it seems:
> 
> Jun 11 14:28:53 spce proxy[17191]: ERROR: registrar [save.c:599]:
> test_max_contacts(): invalid cseq for aor <XXXX at XXXX>
> 
> 
> Would be nice to identify this message
> (CALLID=11fb6a23-0001-0006-0002 at 10.7.255.250) to see the full SIP 
> message. Looks like
> 
> Cheers,
> 
> 
> Daniel Grotti
> 
> Head of Customer Support                     Sipwise GmbH
> e: dgrotti at sipwise.com                     Europaring F15
> t: +43(0)130120332                A-2345 Brunn Am Gebirge
> w: www.sipwise.com   FN: 305595f   FG: LG Wiener Neustadt
> 
> On 6/12/19 1:56 PM, Marcos Pytel wrote:
>> Hi Team!
>>
>> Im using mr7.0.2
>>
>> Since yesterday my server don’t work well .For unknow reason stop 
>> responding sip messages. The services still running.
>>
>> After reset the ngcp-proxy, the server come back work again. This 
>> happen two times.
>>
>> This is the log.
>>
>> Jun 11 14:27:24 spce proxy[17194]: ERROR: <core>
>> [core/parser/parse_fline.c:257]: parse_first_line(): parse_first_line:
>> bad message (offset: 120)
>>
>> Jun 11 14:27:24 spce proxy[17194]: ERROR: <core>
>> [core/parser/msg_parser.c:675]: parse_msg(): ERROR: parse_msg:
>> message=<>
>>
>> Jun 11 14:27:24 spce proxy[17194]: ERROR: <core> [core/receive.c:199]:
>> receive_msg(): core parsing of SIP message failed (127.0.0.1:5060/1)
>>
>> Jun 11 14:28:53 spce proxy[17191]: ERROR: registrar [save.c:599]:
>> test_max_contacts(): invalid cseq for aor <XXXX at XXXX 
>> <mailto:sip_0162 at sip00.cotesma.com.ar>>
>>
>> Jun 11 14:28:53 spce proxy[17191]: ERROR: <script>: Contacts failed - 
>> R=sip:sip00.cotesma.com.ar ID=11fb6a23-0001-0006-0002 at 10.7.255.250
>> <mailto:ID=11fb6a23-0001-0006-0002 at 10.7.255.250> UA='<null>'
>>
>> Jun 11 14:28:53 spce proxy[17191]: ERROR: sl [sl_funcs.c:362]:
>> sl_reply_error(): stateless error reply used: I'm terribly sorry, 
>> server error occurred (1/SL)
>>
>> Jun 11 14:28:59 spce proxy[17194]: ERROR: registrar [save.c:599]:
>> test_max_contacts(): invalid cseq for aor <XXXXX at XXXXX>
>>
>> Jun 11 14:28:59 spce proxy[17194]: ERROR: <script>: Contacts failed - 
>> R=sip:sip00.cotesma.com.ar ID=01-REG-FSM-7acfedd4iiapwfimpp at 10.7.0.72
>> <mailto:ID=01-REG-FSM-7acfedd4iiapwfimpp at 10.7.0.72> UA='Alcatel-Lucent MSAN'
>>
>> Jun 11 14:28:59 spce proxy[17194]: ERROR: sl [sl_funcs.c:362]:
>> sl_reply_error(): stateless error reply used: I'm terribly sorry, 
>> server error occurred (1/SL)
>>
>> ---------------------------------------------------------------------
>> -
>> ---------------------------------------------------------------------
>> -
>> -------------------------------------------------------------------
>>
>> Jun 11 18:24:44 spce proxy[13009]: ERROR: <core>
>> [core/parser/parse_fline.c:257]: parse_first_line(): parse_first_line:
>> bad message (offset: 120)
>>
>> Jun 11 18:24:44 spce proxy[13009]: ERROR: <core>
>> [core/parser/msg_parser.c:675]: parse_msg(): ERROR: parse_msg:
>> message=<>
>>
>> Jun 11 18:24:44 spce proxy[13009]: ERROR: <core> [core/receive.c:199]:
>> receive_msg(): core parsing of SIP message failed (127.0.0.1:5060/1)
>>
>> What can be happening?
>>
>> Thanks in advance!
>>
>> Marcos.
>>
>>
>> _______________________________________________
>> Spce-user mailing list
>> Spce-user at lists.sipwise.com
>> https://lists.sipwise.com/listinfo/spce-user
>>
> _______________________________________________
> Spce-user mailing list
> Spce-user at lists.sipwise.com
> https://lists.sipwise.com/listinfo/spce-user
> 




More information about the Spce-user mailing list