[OpenSIPS-Users] Crach Opensips in failure d_routing after 50X response

Vlad Paiu vladpaiu at opensips.org
Mon Mar 19 12:33:15 CET 2012 

Hello,

Please send us a full SIP trace for the crash scenario, and also send us 
your OpenSIPS script ( publicly on the mailing list or privately to me, 
if you want ).

Regards,

Vlad Paiu
OpenSIPS Developer
http://www.opensips-solutions.com


On 03/19/2012 01:12 PM, mickael at winlux.fr wrote:
>> Hi list
>> I want expose my crash problem.
>>
>> *my sip network :*
>> I have two Opensips statefull mode in freebsd (8.1-RELEASE FreeBSD
>> 8.1-RELEASE) and two Mediaproxy in debian (Linux 2.6.32-5-686).
>>
>> *my problem :*
>> Randomly, first Opensips crash in failure route , after 50X response,
>> juste before t_relay. But the second proxy never crashed in this case.
>>
>> Look below :
>>
>> *Log opensips:*
>>
>> Mar 19 10:22:01 proxy1 /usr/local/sbin/opensips[59860]: NEW CALL : call-id
>> 539361d23447189d7db8614164c446c7 at domaincustomer
>>
>> Mar 19 10:22:01 proxy1 /usr/local/sbin/opensips[59860]:
>> 539361d23447189d7db8614164c446c7 at domaincustomerIn ROUTE DELXASTERISK - fu
>> : sip:+33689898989 at domaincustomer, ru : sip:+33898989898 at IP_proxy , si :
>> IP_customer
>>
>> Mar 19 10:22:01 proxy1 /usr/local/sbin/opensips[59860]:
>> 539361d23447189d7db8614164c446c7 at domaincustomerIn ROUTE FORMATRURI - fu :
>> sip:+33689898989 at domaincustomer, ru : sip:+33898989898 at IP_proxy , si :
>> IP_customer
>>
>> Mar 19 10:22:01 proxy1 /usr/local/sbin/opensips[59860]:
>> 539361d23447189d7db8614164c446c7 at domaincustomer
>>
>> Outgoing call for: [sip:+33689898989 at domaincustomer/sip:+33898989898
>> @IP_proxy/sip:+33898989898
>> @IP_proxy/539361d23447189d7db8614164c446c7 at domaincustomer]
>>
>> Mar 19 10:22:01 proxy1 /usr/local/sbin/opensips[59860]:
>> 539361d23447189d7db8614164c446c7 at domaincustomer Call control: countOUT is
>> OK [10/60/sip:+33689898989 at domaincustomer/domaincustomer]
>>
>> Mar 19 10:22:01 proxy1 /usr/local/sbin/opensips[59860]:
>> 539361d23447189d7db8614164c446c7 at domaincustomer In ROUTE DROUTING - fu :
>> sip:+33689898989 at domaincustomer, ru : sip:+33898989898 at IP_proxy , si :
>> IP_customer
>>
>> Mar 19 10:22:01 proxy1 /usr/local/sbin/opensips[59860]:
>> 539361d23447189d7db8614164c446c7 at domaincustomer We add the + prefix
>>
>> Mar 19 10:22:01 proxy1 /usr/local/sbin/opensips[59860]:
>> 539361d23447189d7db8614164c446c7 at domaincustomer In ROUTE FORMATOUT - fu :
>> sip:+33689898989 at domaincustomer, ru : sip:+33898989898 at IP_Provider1:5060
>> , si : IP_customer
>>
>> Mar 19 10:22:01 proxy1 /usr/local/sbin/opensips[59860]:
>> 539361d23447189d7db8614164c446c7 at domaincustomer We rewrite TO with ru :
>> sip:+33898989898 at IP_Provider1:5060
>>
>> Mar 19 10:22:01 proxy1 /usr/local/sbin/opensips[59860]:
>> 539361d23447189d7db8614164c446c7 at domaincustomer In fU ANONYMOUS or fU
>> with letters - fU : +33689898989
>>
>> Mar 19 10:22:01 proxy1 /usr/local/sbin/opensips[59860]:
>> 539361d23447189d7db8614164c446c7 at domaincustomer There's a PAI Header - fu
>> : sip:+33689898989 at domaincustomer, ru : sip:+33898989898 at IP_Provider1:5060
>> , si : IP_customer
>>
>> Mar 19 10:22:01 proxy1 /usr/local/sbin/opensips[59860]:
>> 539361d23447189d7db8614164c446c7 at domaincustomer ROUTE PAIAUTHORIZE - OUT
>> CALL AUTHORIZE - AI: sip:+33689898989 at 10.10.10.1:5060 - for: [sip:
>> +33689898989 at domaincustomer/sip:+33898989898 at IP_proxy/sip:+33898989898
>> @IP_Provider1:5060/539361d23447189d7db8614164c446c7 at domaincustomer]
>>
>> Mar 19 10:22:01 proxy1 /usr/local/sbin/opensips[59860]:
>> 539361d23447189d7db8614164c446c7 at domaincustomer
> T<539361d23447189d7db8614164c446c7 at domaincustomerT>o
>> ROUTE DELETECODDECS
>>
>> Mar 19 10:22:01 proxy1 /usr/local/sbin/opensips[59868]:
>> 539361d23447189d7db8614164c446c7 at domaincustomer In ONREPLY ROUTE 2 - fu :
>> sip:+33689898989 at IP_proxy , ru :<null>  , si : IP_Provider1, status : 100
>>
>> Mar 19 10:22:01 proxy1 /usr/local/sbin/opensips[59869]:
>> 539361d23447189d7db8614164c446c7 at domaincustomer In ONREPLY ROUTE 2 - fu :
>> sip:+33689898989 at IP_proxy , ru :<null>  , si : IP_Provider1, status : 183
>>
>> Mar 19 10:22:01 proxy1 /usr/local/sbin/opensips[59869]:
>> 539361d23447189d7db8614164c446c7 at domaincustomer DROP 183
>>
>> Mar 19 10:22:04 proxy1 /usr/local/sbin/opensips[59871]:
>> 539361d23447189d7db8614164c446c7 at domaincustomer In ONREPLY ROUTE 2 - fu :
>> sip:+33689898989 at IP_proxy , ru :<null>  , si : IP_Provider1, status : 180
>>
>> Mar 19 10:22:20 proxy1 /usr/local/sbin/opensips[59868]:
>> 539361d23447189d7db8614164c446c7 at domaincustomer In ONREPLY ROUTE 2 - fu :
>> sip:+33689898989 at IP_proxy , ru :<null>  , si : IP_Provider1, status : 183
>>
>> Mar 19 10:22:20 proxy1 /usr/local/sbin/opensips[59868]:
>> 539361d23447189d7db8614164c446c7 at domaincustomer DROP 183
>>
>> Mar 19 10:22:23 proxy1 /usr/local/sbin/opensips[59869]:
>> 539361d23447189d7db8614164c446c7 at domaincustomer In ONREPLY ROUTE 2 - fu :
>> sip:+33689898989 at IP_proxy , ru :<null>  , si : IP_Provider1, status : 183
>>
>> Mar 19 10:22:23 proxy1 /usr/local/sbin/opensips[59869]:
>> 539361d23447189d7db8614164c446c7 at domaincustomer DROP 183
>>
>> *Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59868]: **
>> 539361d23447189d7db8614164c446c7 at domaincustomer*<539361d23447189d7db8614164c446c7 at domaincustomer>
>> * In ONREPLY ROUTE 2 - fu : sip:+33689898989 at IP_proxy , ru :<null>  , si
>> : IP_Provider1, status : 500*
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59868]:
>> 539361d23447189d7db8614164c446c7 at domaincustomer In FAILURE ROUTE DROUTING
>> - fu : sip:+33689898989 at domaincustomer, ru :
> sip:+33898989898 at IP_Provider1:5060
>> , si : IP_customer
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59868]:
>> 539361d23447189d7db8614164c446c7 at domaincustomer DR use NEXT GW to go to
>> ru : sip:33898989898 at 81.23.34.140:5060
>>
>> *Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59868]: **
>> 539361d23447189d7db8614164c446c7 at domaincustomer*<539361d23447189d7db8614164c446c7 at domaincustomer>
>> * In isflaset29?, before command prefix + Ru modify to ru :
>> sip:+33898989898 at IP_BACKUP_PROVIDER_1:5060<http://sip:+33898989898@81.23.34.140:5060>
>> *
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59852]:
>> INFO:core:handle_sigs: child process 59868 exited by a signal 11
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59852]:
>> INFO:core:handle_sigs: core was generated
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59852]:
>> INFO:core:handle_sigs: terminating due to SIGCHLD
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59873]: INFO:core:sig_usr:
>> signal 15 received
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59871]: INFO:core:sig_usr:
>> signal 15 received
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59863]: INFO:core:sig_usr:
>> signal 15 received
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59870]: INFO:core:sig_usr:
>> signal 15 received
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59861]: INFO:core:sig_usr:
>> signal 15 received
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59869]: INFO:core:sig_usr:
>> signal 15 received
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59860]: INFO:core:sig_usr:
>> signal 15 received
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59862]: INFO:core:sig_usr:
>> signal 15 received
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59872]: INFO:core:sig_usr:
>> signal 15 received
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59864]: INFO:core:sig_usr:
>> signal 15 received
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59856]: INFO:core:sig_usr:
>> signal 15 received
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59855]: INFO:core:sig_usr:
>> signal 15 received
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59859]: INFO:core:sig_usr:
>> signal 15 received
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59865]: INFO:core:sig_usr:
>> signal 15 received
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59853]: INFO:core:sig_usr:
>> signal 15 received
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59857]: INFO:core:sig_usr:
>> signal 15 received
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59867]: INFO:core:sig_usr:
>> signal 15 received
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59866]: INFO:core:sig_usr:
>> signal 15 received
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59858]: INFO:core:sig_usr:
>> signal 15 received
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59852]:
>> INFO:pike:pike_exit: destroying...
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59852]:
>> INFO:snmpstats:mod_destroy: The SNMPStats module got the kill signal
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59852]:
>> INFO:snmpstats:mod_destroy: Shutting down the AgentX Sub-Agent!
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59852]:
>> INFO:db_mysql:re_init_statement: query is<update dialog set
>> state=?,timeout=?,caller_cseq=?,callee_cseq=?,vars=?,profiles=?,script_flags=?
>> where hash_entry=? AND hash_id=?>, ptr=0x0
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59852]:
>> INFO:db_mysql:switch_state_to_disconnected: disconnect event for 0x8250584
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59852]:
>> INFO:db_mysql:reset_all_statements: reseting all statements on connection:
>> (0x8250b14) 0x8250584
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59852]:
>> INFO:db_mysql:connect_with_retry: re-connected successful for 0x8250584
>>
>> Mar 19 10:22:40 proxy1 /usr/local/sbin/opensips[59852]:
>> INFO:db_mysql:re_init_statement: query is<delete from location where
>> expires<? AND expires!=?>, ptr=0x0
>>
>>
>> *Code "route failure"*
>>
>> failure_route[faildrouting]
>> {
>>          if($avp(s:debugcall)=~"yes"){ xlog("L_INFO","$ci In FAILURE ROUTE
>> DROUTING - fu : $fu , ru : $ru , si : $si \n"); }
>>          t_on_failure("faildrouting");
>>
>>          if (t_was_cancelled())
>>          {
>>                  exit;
>>          }
>>
>>          if (t_check_status("40[58]|50[0-5]")) {
>>
>>                  if(use_next_gw())
>>                  {
>>                          if($avp(s:debugcall)=~"yes"){ xlog("L_INFO","$ci
>> DR use NEXT GW to go to ru : $ru \n"); }
>>                          if(isflagset(29))
>>                          {
>>                                  prefix("+");
>>                                  *if($avp(s:debugcall)=~"yes"){
>> xlog("L_INFO","$ci In isflaset29?, before command prefix + Ru modify to ru
>> : $ru \n"); }*
>>                          }
>>
>>
>>                          if(t_relay())
>>                          {
>>                                  if($avp(s:debugcall)=~"yes"){
>> xlog("L_INFO","$ci In t_relay, And t_relay: OK to ru : $ru \n"); }
>>                          }
>>                  }
>>                  else
>>                  {
>>                          xlog("L_ERR","$ci DR NEXT GW not found\n");
>>                  }
>>          }
>> }
>>
>>
>>
>
> Thanks in advance.
>
>
>
> _______________________________________________
> Users mailing list
> Users at lists.opensips.org
> http://lists.opensips.org/cgi-bin/mailman/listinfo/users

More information about the Users mailing list