Does SER do any DNS query on startup? In case of enum DSN query the DNS server responded within one second according to the log. I saw that the parsing of ser.cfg was also slow when watching the log file with tail -f /var/log/messages: Aug 30 10:00:42 iptel1 ser: loading module /usr/lib/ser/modules/sl.so ... Aug 30 10:00:43 iptel1 ser: routing table 0: Aug 30 10:00:43 iptel1 ser: if ( ... Aug 30 10:01:02 iptel1 ser: }; ... 20 seconds :( This section of the log is attached. An interesting line in the log: DEBUG: open_fifo_server: FIFO stat failed: Inappropriate ioctl for device Thanks, Miklos On 08/30/04 15:31, Jan Janak wrote: -- --------------------------------------------------------------------------- Miklos Tirpak Computer and Automation Research Institute e-mail : mtirpak(a)sztaki.hu Hungarian Academy of Sciences phone : (361) 279-6011 H-1132 Budapest, Victor Hugo u 18-22 fax : (361) 279-6021 Aug 30 10:00:42 iptel1 ser: loading module /usr/lib/ser/modules/sl.so Aug 30 10:00:42 iptel1 ser: loading module /usr/lib/ser/modules/tm.so Aug 30 10:00:42 iptel1 ser: loading module /usr/lib/ser/modules/rr.so Aug 30 10:00:42 iptel1 ser: loading module /usr/lib/ser/modules/maxfwd.so Aug 30 10:00:42 iptel1 ser: loading module /usr/lib/ser/modules/permissions.so Aug 30 10:00:42 iptel1 ser: loading module /usr/lib/ser/modules/enum.so Aug 30 10:00:42 iptel1 ser: set_mod_param_regex: rr matches module rr Aug 30 10:00:42 iptel1 ser: set_mod_param_regex: found <enable_full_lr> in module rr [/usr/lib/ser/modules/rr.so] Aug 30 10:00:42 iptel1 ser: set_mod_param_regex: enum matches module enum Aug 30 10:00:42 iptel1 ser: set_mod_param_regex: found <domain_suffix> in module enum [/usr/lib/ser/modules/enum.so] Aug 30 10:00:42 iptel1 ser: set_mod_param_regex: permissions matches module permissions Aug 30 10:00:42 iptel1 ser: set_mod_param_regex: found <allow_file> in module permissions [/usr/lib/ser/modules/permissions.so] Aug 30 10:00:42 iptel1 ser: set_mod_param_regex: permissions matches module permissions Aug 30 10:00:42 iptel1 ser: set_mod_param_regex: found <deny_file> in module permissions [/usr/lib/ser/modules/permissions.so] Aug 30 10:00:42 iptel1 ser: find_export: found <mf_process_maxfwd_header> in module maxfwd_module [/usr/lib/ser/modules/maxfwd.so] Aug 30 10:00:42 iptel1 ser: find_export: found <sl_send_reply> in module sl_module [/usr/lib/ser/modules/sl.so] Aug 30 10:00:42 iptel1 ser: find_export: found <sl_send_reply> in module sl_module [/usr/lib/ser/modules/sl.so] Aug 30 10:00:43 iptel1 ser: find_export: found <record_route> in module rr [/usr/lib/ser/modules/rr.so] Aug 30 10:00:43 iptel1 ser: find_export: found <loose_route> in module rr [/usr/lib/ser/modules/rr.so] Aug 30 10:00:43 iptel1 ser: find_export: found <t_relay> in module tm [/usr/lib/ser/modules/tm.so] Aug 30 10:00:43 iptel1 ser: find_export: found <allow_routing> in module permissions [/usr/lib/ser/modules/permissions.so] Aug 30 10:00:43 iptel1 ser: find_export: found <sl_send_reply> in module sl_module [/usr/lib/ser/modules/sl.so] Aug 30 10:00:43 iptel1 ser: find_export: found <enum_query> in module enum [/usr/lib/ser/modules/enum.so] Aug 30 10:00:43 iptel1 ser: find_export: found <sl_send_reply> in module sl_module [/usr/lib/ser/modules/sl.so] Aug 30 10:00:43 iptel1 ser: find_export: found <t_relay> in module tm [/usr/lib/ser/modules/tm.so] Aug 30 10:00:43 iptel1 ser: find_export: found <sl_reply_error> in module sl_module [/usr/lib/ser/modules/sl.so] Aug 30 10:00:43 iptel1 ser: routing table 0: Aug 30 10:00:43 iptel1 ser: if ( Aug 30 10:00:43 iptel1 ser: NOT( Aug 30 10:00:43 iptel1 ser: external_module_call( Aug 30 10:00:43 iptel1 ser: f_ptr<0x40075e80> Aug 30 10:00:43 iptel1 ser: , "10" Aug 30 10:00:43 iptel1 ser: ); Aug 30 10:00:43 iptel1 ser: external_module_call( Aug 30 10:00:43 iptel1 ser: f_ptr<0x40075e80> Aug 30 10:00:43 iptel1 ser: , "10" Aug 30 10:00:43 iptel1 ser: ); Aug 30 10:00:43 iptel1 ser: ) Aug 30 10:00:43 iptel1 ser: ) { Aug 30 10:00:43 iptel1 ser: external_module_call( Aug 30 10:00:43 iptel1 ser: f_ptr<0x4002e780> Aug 30 10:00:43 iptel1 ser: , "483" Aug 30 10:00:43 iptel1 ser: , "Too Many Hops" Aug 30 10:00:43 iptel1 ser: ); Aug 30 10:00:43 iptel1 ser: drop( Aug 30 10:00:43 iptel1 ser: type<0> Aug 30 10:00:43 iptel1 ser: ); Aug 30 10:00:43 iptel1 ser: } else { Aug 30 10:00:43 iptel1 ser: }; Aug 30 10:00:43 iptel1 ser: if ( Aug 30 10:00:43 iptel1 ser: UNKNOWN Aug 30 10:00:43 iptel1 ser: <UNKNOWN> Aug 30 10:00:43 iptel1 ser: 65535 Aug 30 10:00:43 iptel1 ser: ) { Aug 30 10:00:43 iptel1 ser: external_module_call( Aug 30 10:00:43 iptel1 ser: f_ptr<0x4002e780> Aug 30 10:00:43 iptel1 ser: , "513" Aug 30 10:00:43 iptel1 ser: , "Message too big" Aug 30 10:00:43 iptel1 ser: ); Aug 30 10:00:43 iptel1 ser: drop( Aug 30 10:00:43 iptel1 ser: type<0> Aug 30 10:00:43 iptel1 ser: ); Aug 30 10:00:43 iptel1 ser: } else { Aug 30 10:00:43 iptel1 ser: }; Aug 30 10:00:43 iptel1 ser: external_module_call( Aug 30 10:00:44 iptel1 ser: f_ptr<0x4006fab0> Aug 30 10:00:44 iptel1 ser: ); Aug 30 10:00:44 iptel1 ser: if ( Aug 30 10:00:44 iptel1 ser: external_module_call( Aug 30 10:00:44 iptel1 ser: f_ptr<0x4006ce70> Aug 30 10:00:44 iptel1 ser: ); Aug 30 10:00:44 iptel1 ser: external_module_call( Aug 30 10:00:44 iptel1 ser: f_ptr<0x4006ce70> Aug 30 10:00:44 iptel1 ser: ); Aug 30 10:00:44 iptel1 ser: ) { Aug 30 10:00:44 iptel1 ser: log( Aug 30 10:00:44 iptel1 ser: 1 Aug 30 10:00:44 iptel1 ser: , "Loose route processing " Aug 30 10:00:44 iptel1 ser: ); Aug 30 10:00:44 iptel1 ser: external_module_call( Aug 30 10:00:44 iptel1 ser: f_ptr<0x40047810> Aug 30 10:00:44 iptel1 ser: ); Aug 30 10:00:44 iptel1 ser: drop( Aug 30 10:00:44 iptel1 ser: type<0> Aug 30 10:00:44 iptel1 ser: ); Aug 30 10:00:44 iptel1 ser: } else { Aug 30 10:00:44 iptel1 ser: }; Aug 30 10:00:44 iptel1 ser: if ( Aug 30 10:00:44 iptel1 ser: method Aug 30 10:00:44 iptel1 ser: == Aug 30 10:00:44 iptel1 ser: "INVITE" Aug 30 10:00:44 iptel1 ser: ) { Aug 30 10:00:44 iptel1 ser: if ( Aug 30 10:00:44 iptel1 ser: uri Aug 30 10:00:44 iptel1 ser: =~ Aug 30 10:00:44 iptel1 ser: "^sip:[0-9][0-9][0-9]\*[\*#0-9]+@" Aug 30 10:00:44 iptel1 ser: ) { Aug 30 10:00:44 iptel1 ser: log( Aug 30 10:00:44 iptel1 ser: 1 Aug 30 10:00:44 iptel1 ser: , "REINVITE found " Aug 30 10:00:44 iptel1 ser: ); Aug 30 10:00:44 iptel1 ser: strip( Aug 30 10:00:44 iptel1 ser: 4 Aug 30 10:00:44 iptel1 ser: ); Aug 30 10:00:44 iptel1 ser: } else { Aug 30 10:00:44 iptel1 ser: }; Aug 30 10:00:44 iptel1 ser: if ( Aug 30 10:00:44 iptel1 ser: NOT( Aug 30 10:00:44 iptel1 ser: external_module_call( Aug 30 10:00:44 iptel1 ser: f_ptr<0x4007ab10> Aug 30 10:00:44 iptel1 ser: ); Aug 30 10:00:44 iptel1 ser: external_module_call( Aug 30 10:00:45 iptel1 ser: f_ptr<0x4007ab10> Aug 30 10:00:45 iptel1 ser: ); Aug 30 10:00:45 iptel1 ser: ) Aug 30 10:00:45 iptel1 ser: ) { Aug 30 10:00:45 iptel1 ser: external_module_call( Aug 30 10:00:45 iptel1 ser: f_ptr<0x4002e780> Aug 30 10:00:45 iptel1 ser: , "402" Aug 30 10:00:45 iptel1 ser: , "Payment Required" Aug 30 10:00:45 iptel1 ser: ); Aug 30 10:00:45 iptel1 ser: log( Aug 30 10:00:45 iptel1 ser: 1 Aug 30 10:00:45 iptel1 ser: , "Routing denied " Aug 30 10:00:45 iptel1 ser: ); Aug 30 10:00:45 iptel1 ser: drop( Aug 30 10:00:45 iptel1 ser: type<0> Aug 30 10:00:45 iptel1 ser: ); Aug 30 10:00:45 iptel1 ser: } else { Aug 30 10:00:45 iptel1 ser: }; Aug 30 10:00:45 iptel1 ser: } else { Aug 30 10:00:45 iptel1 ser: }; Aug 30 10:00:45 iptel1 ser: if ( Aug 30 10:00:45 iptel1 ser: OR( Aug 30 10:00:45 iptel1 ser: uri Aug 30 10:00:45 iptel1 ser: =~ Aug 30 10:00:45 iptel1 ser: "hbone\.hu([:;\?].*)?$" Aug 30 10:00:45 iptel1 ser: , Aug 30 10:00:45 iptel1 ser: uri Aug 30 10:00:45 iptel1 ser: =~ Aug 30 10:00:45 iptel1 ser: "@193\.225\.14\.136([:;\?].*)?$" Aug 30 10:00:45 iptel1 ser: ) Aug 30 10:00:45 iptel1 ser: ) { Aug 30 10:00:45 iptel1 ser: log( Aug 30 10:00:45 iptel1 ser: 1 Aug 30 10:00:45 iptel1 ser: , "MY URI " Aug 30 10:00:45 iptel1 ser: ); Aug 30 10:00:45 iptel1 ser: if ( Aug 30 10:00:45 iptel1 ser: uri Aug 30 10:00:45 iptel1 ser: =~ Aug 30 10:00:45 iptel1 ser: "^sip:[\*#0-9]+@" Aug 30 10:00:45 iptel1 ser: ) { Aug 30 10:00:45 iptel1 ser: prefix( Aug 30 10:00:45 iptel1 ser: "+" Aug 30 10:00:45 iptel1 ser: ); Aug 30 10:00:45 iptel1 ser: } else { Aug 30 10:00:45 iptel1 ser: }; Aug 30 10:00:45 iptel1 ser: if ( Aug 30 10:00:45 iptel1 ser: NOT( Aug 30 10:00:46 iptel1 ser: external_module_call( Aug 30 10:00:46 iptel1 ser: f_ptr<0x4007efe0> Aug 30 10:00:46 iptel1 ser: , "" Aug 30 10:00:46 iptel1 ser: ); Aug 30 10:00:46 iptel1 ser: external_module_call( Aug 30 10:00:46 iptel1 ser: f_ptr<0x4007efe0> Aug 30 10:00:46 iptel1 ser: , "" Aug 30 10:00:46 iptel1 ser: ); Aug 30 10:00:46 iptel1 ser: ) Aug 30 10:00:46 iptel1 ser: ) { Aug 30 10:00:46 iptel1 ser: log( Aug 30 10:00:46 iptel1 ser: 1 Aug 30 10:00:46 iptel1 ser: , "ENUM FAILED " Aug 30 10:00:46 iptel1 ser: ); Aug 30 10:00:46 iptel1 ser: external_module_call( Aug 30 10:00:46 iptel1 ser: f_ptr<0x4002e780> Aug 30 10:00:46 iptel1 ser: , "404" Aug 30 10:00:46 iptel1 ser: , "Not Found" Aug 30 10:00:46 iptel1 ser: ); Aug 30 10:00:46 iptel1 ser: drop( Aug 30 10:00:46 iptel1 ser: type<0> Aug 30 10:00:46 iptel1 ser: ); Aug 30 10:00:46 iptel1 ser: } else { Aug 30 10:00:46 iptel1 ser: }; Aug 30 10:00:46 iptel1 ser: if ( Aug 30 10:00:46 iptel1 ser: uri Aug 30 10:00:46 iptel1 ser: =~ Aug 30 10:00:46 iptel1 ser: "^sip:\+[\*#0-9]+@" Aug 30 10:00:46 iptel1 ser: ) { Aug 30 10:00:46 iptel1 ser: strip( Aug 30 10:00:46 iptel1 ser: 1 Aug 30 10:00:46 iptel1 ser: ); Aug 30 10:00:46 iptel1 ser: } else { Aug 30 10:00:46 iptel1 ser: }; Aug 30 10:00:46 iptel1 ser: } else { Aug 30 10:00:46 iptel1 ser: log( Aug 30 10:00:46 iptel1 ser: 1 Aug 30 10:00:46 iptel1 ser: , "NOT MY URI " Aug 30 10:00:46 iptel1 ser: ); Aug 30 10:00:46 iptel1 ser: }; Aug 30 10:00:46 iptel1 ser: log( Aug 30 10:00:46 iptel1 ser: 1 Aug 30 10:00:46 iptel1 ser: , "Sending request... " Aug 30 10:00:46 iptel1 ser: ); Aug 30 10:00:46 iptel1 ser: if ( Aug 30 10:00:46 iptel1 ser: NOT( Aug 30 10:00:46 iptel1 ser: external_module_call( Aug 30 10:00:46 iptel1 ser: f_ptr<0x40047810> Aug 30 10:00:46 iptel1 ser: ); Aug 30 10:00:46 iptel1 ser: external_module_call( Aug 30 10:00:46 iptel1 ser: f_ptr<0x40047810> Aug 30 10:00:46 iptel1 ser: ); Aug 30 10:00:46 iptel1 ser: ) Aug 30 10:00:47 iptel1 ser: ) { Aug 30 10:00:47 iptel1 ser: log( Aug 30 10:00:47 iptel1 ser: 1 Aug 30 10:00:47 iptel1 ser: , "t_relay FAILED " Aug 30 10:00:47 iptel1 ser: ); Aug 30 10:00:47 iptel1 ser: external_module_call( Aug 30 10:00:47 iptel1 ser: f_ptr<0x4002e7b0> Aug 30 10:00:47 iptel1 ser: ); Aug 30 10:00:47 iptel1 ser: } else { Aug 30 10:00:47 iptel1 ser: }; Aug 30 10:00:47 iptel1 ser: if ( Aug 30 10:00:47 iptel1 ser: UNKNOWN Aug 30 10:00:47 iptel1 ser: <UNKNOWN> Aug 30 10:00:47 iptel1 ser: 65535 Aug 30 10:00:47 iptel1 ser: ) { Aug 30 10:00:47 iptel1 ser: external_module_call( Aug 30 10:00:47 iptel1 ser: f_ptr<0x4002e780> Aug 30 10:00:47 iptel1 ser: , "513" Aug 30 10:00:47 iptel1 ser: , "Message too big" Aug 30 10:00:47 iptel1 ser: ); Aug 30 10:00:47 iptel1 ser: drop( Aug 30 10:00:47 iptel1 ser: type<0> Aug 30 10:00:47 iptel1 ser: ); Aug 30 10:00:47 iptel1 ser: } else { Aug 30 10:00:47 iptel1 ser: }; Aug 30 10:00:47 iptel1 ser: external_module_call( Aug 30 10:00:47 iptel1 ser: f_ptr<0x4006fab0> Aug 30 10:00:47 iptel1 ser: ); Aug 30 10:00:47 iptel1 ser: if ( Aug 30 10:00:47 iptel1 ser: external_module_call( Aug 30 10:00:47 iptel1 ser: f_ptr<0x4006ce70> Aug 30 10:00:47 iptel1 ser: ); Aug 30 10:00:47 iptel1 ser: external_module_call( Aug 30 10:00:47 iptel1 ser: f_ptr<0x4006ce70> Aug 30 10:00:47 iptel1 ser: ); Aug 30 10:00:47 iptel1 ser: ) { Aug 30 10:00:47 iptel1 ser: log( Aug 30 10:00:47 iptel1 ser: 1 Aug 30 10:00:47 iptel1 ser: , "Loose route processing " Aug 30 10:00:47 iptel1 ser: ); Aug 30 10:00:47 iptel1 ser: external_module_call( Aug 30 10:00:47 iptel1 ser: f_ptr<0x40047810> Aug 30 10:00:47 iptel1 ser: ); Aug 30 10:00:47 iptel1 ser: drop( Aug 30 10:00:47 iptel1 ser: type<0> Aug 30 10:00:47 iptel1 ser: ); Aug 30 10:00:47 iptel1 ser: } else { Aug 30 10:00:47 iptel1 ser: }; Aug 30 10:00:47 iptel1 ser: if ( Aug 30 10:00:48 iptel1 ser: method Aug 30 10:00:48 iptel1 ser: == Aug 30 10:00:48 iptel1 ser: "INVITE" Aug 30 10:00:48 iptel1 ser: ) { Aug 30 10:00:48 iptel1 ser: if ( Aug 30 10:00:48 iptel1 ser: uri Aug 30 10:00:48 iptel1 ser: =~ Aug 30 10:00:48 iptel1 ser: "^sip:[0-9][0-9][0-9]\*[\*#0-9]+@" Aug 30 10:00:48 iptel1 ser: ) { Aug 30 10:00:48 iptel1 ser: log( Aug 30 10:00:48 iptel1 ser: 1 Aug 30 10:00:48 iptel1 ser: , "REINVITE found " Aug 30 10:00:48 iptel1 ser: ); Aug 30 10:00:48 iptel1 ser: strip( Aug 30 10:00:48 iptel1 ser: 4 Aug 30 10:00:48 iptel1 ser: ); Aug 30 10:00:48 iptel1 ser: } else { Aug 30 10:00:48 iptel1 ser: }; Aug 30 10:00:48 iptel1 ser: if ( Aug 30 10:00:48 iptel1 ser: NOT( Aug 30 10:00:48 iptel1 ser: external_module_call( Aug 30 10:00:48 iptel1 ser: f_ptr<0x4007ab10> Aug 30 10:00:48 iptel1 ser: ); Aug 30 10:00:48 iptel1 ser: external_module_call( Aug 30 10:00:48 iptel1 ser: f_ptr<0x4007ab10> Aug 30 10:00:48 iptel1 ser: ); Aug 30 10:00:48 iptel1 ser: ) Aug 30 10:00:48 iptel1 ser: ) { Aug 30 10:00:48 iptel1 ser: external_module_call( Aug 30 10:00:48 iptel1 ser: f_ptr<0x4002e780> Aug 30 10:00:48 iptel1 ser: , "402" Aug 30 10:00:48 iptel1 ser: , "Payment Required" Aug 30 10:00:48 iptel1 ser: ); Aug 30 10:00:48 iptel1 ser: log( Aug 30 10:00:48 iptel1 ser: 1 Aug 30 10:00:48 iptel1 ser: , "Routing denied " Aug 30 10:00:48 iptel1 ser: ); Aug 30 10:00:48 iptel1 ser: drop( Aug 30 10:00:48 iptel1 ser: type<0> Aug 30 10:00:48 iptel1 ser: ); Aug 30 10:00:48 iptel1 ser: } else { Aug 30 10:00:48 iptel1 ser: }; Aug 30 10:00:48 iptel1 ser: } else { Aug 30 10:00:48 iptel1 ser: }; Aug 30 10:00:48 iptel1 ser: if ( Aug 30 10:00:48 iptel1 ser: OR( Aug 30 10:00:49 iptel1 ser: uri Aug 30 10:00:49 iptel1 ser: =~ Aug 30 10:00:49 iptel1 ser: "hbone\.hu([:;\?].*)?$" Aug 30 10:00:49 iptel1 ser: , Aug 30 10:00:49 iptel1 ser: uri Aug 30 10:00:49 iptel1 ser: =~ Aug 30 10:00:49 iptel1 ser: "@193\.225\.14\.136([:;\?].*)?$" Aug 30 10:00:49 iptel1 ser: ) Aug 30 10:00:49 iptel1 ser: ) { Aug 30 10:00:49 iptel1 ser: log( Aug 30 10:00:49 iptel1 ser: 1 Aug 30 10:00:49 iptel1 ser: , "MY URI " Aug 30 10:00:49 iptel1 ser: ); Aug 30 10:00:49 iptel1 ser: if ( Aug 30 10:00:49 iptel1 ser: uri Aug 30 10:00:49 iptel1 ser: =~ Aug 30 10:00:49 iptel1 ser: "^sip:[\*#0-9]+@" Aug 30 10:00:49 iptel1 ser: ) { Aug 30 10:00:49 iptel1 ser: prefix( Aug 30 10:00:49 iptel1 ser: "+" Aug 30 10:00:49 iptel1 ser: ); Aug 30 10:00:49 iptel1 ser: } else { Aug 30 10:00:49 iptel1 ser: }; Aug 30 10:00:49 iptel1 ser: if ( Aug 30 10:00:49 iptel1 ser: NOT( Aug 30 10:00:49 iptel1 ser: external_module_call( Aug 30 10:00:49 iptel1 ser: f_ptr<0x4007efe0> Aug 30 10:00:49 iptel1 ser: , "" Aug 30 10:00:49 iptel1 ser: ); Aug 30 10:00:49 iptel1 ser: external_module_call( Aug 30 10:00:49 iptel1 ser: f_ptr<0x4007efe0> Aug 30 10:00:49 iptel1 ser: , "" Aug 30 10:00:49 iptel1 ser: ); Aug 30 10:00:49 iptel1 ser: ) Aug 30 10:00:49 iptel1 ser: ) { Aug 30 10:00:49 iptel1 ser: log( Aug 30 10:00:49 iptel1 ser: 1 Aug 30 10:00:49 iptel1 ser: , "ENUM FAILED " Aug 30 10:00:49 iptel1 ser: ); Aug 30 10:00:49 iptel1 ser: external_module_call( Aug 30 10:00:49 iptel1 ser: f_ptr<0x4002e780> Aug 30 10:00:49 iptel1 ser: , "404" Aug 30 10:00:49 iptel1 ser: , "Not Found" Aug 30 10:00:49 iptel1 ser: ); Aug 30 10:00:49 iptel1 ser: drop( Aug 30 10:00:49 iptel1 ser: type<0> Aug 30 10:00:49 iptel1 ser: ); Aug 30 10:00:50 iptel1 ser: } else { Aug 30 10:00:50 iptel1 ser: }; Aug 30 10:00:50 iptel1 ser: if ( Aug 30 10:00:50 iptel1 ser: uri Aug 30 10:00:50 iptel1 ser: =~ Aug 30 10:00:50 iptel1 ser: "^sip:\+[\*#0-9]+@" Aug 30 10:00:50 iptel1 ser: ) { Aug 30 10:00:50 iptel1 ser: strip( Aug 30 10:00:50 iptel1 ser: 1 Aug 30 10:00:50 iptel1 ser: ); Aug 30 10:00:50 iptel1 ser: } else { Aug 30 10:00:50 iptel1 ser: }; Aug 30 10:00:50 iptel1 ser: } else { Aug 30 10:00:50 iptel1 ser: log( Aug 30 10:00:50 iptel1 ser: 1 Aug 30 10:00:50 iptel1 ser: , "NOT MY URI " Aug 30 10:00:50 iptel1 ser: ); Aug 30 10:00:50 iptel1 ser: }; Aug 30 10:00:50 iptel1 ser: log( Aug 30 10:00:50 iptel1 ser: 1 Aug 30 10:00:50 iptel1 ser: , "Sending request... " Aug 30 10:00:50 iptel1 ser: ); Aug 30 10:00:50 iptel1 ser: if ( Aug 30 10:00:50 iptel1 ser: NOT( Aug 30 10:00:50 iptel1 ser: external_module_call( Aug 30 10:00:50 iptel1 ser: f_ptr<0x40047810> Aug 30 10:00:50 iptel1 ser: ); Aug 30 10:00:50 iptel1 ser: external_module_call( Aug 30 10:00:50 iptel1 ser: f_ptr<0x40047810> Aug 30 10:00:50 iptel1 ser: ); Aug 30 10:00:50 iptel1 ser: ) Aug 30 10:00:50 iptel1 ser: ) { Aug 30 10:00:50 iptel1 ser: log( Aug 30 10:00:50 iptel1 ser: 1 Aug 30 10:00:50 iptel1 ser: , "t_relay FAILED " Aug 30 10:00:50 iptel1 ser: ); Aug 30 10:00:50 iptel1 ser: external_module_call( Aug 30 10:00:50 iptel1 ser: f_ptr<0x4002e7b0> Aug 30 10:00:50 iptel1 ser: ); Aug 30 10:00:50 iptel1 ser: } else { Aug 30 10:00:50 iptel1 ser: }; Aug 30 10:00:50 iptel1 ser: external_module_call( Aug 30 10:00:50 iptel1 ser: f_ptr<0x4006fab0> Aug 30 10:00:50 iptel1 ser: ); Aug 30 10:00:50 iptel1 ser: if ( Aug 30 10:00:50 iptel1 ser: external_module_call( Aug 30 10:00:50 iptel1 ser: f_ptr<0x4006ce70> Aug 30 10:00:50 iptel1 ser: ); Aug 30 10:00:50 iptel1 ser: external_module_call( Aug 30 10:00:50 iptel1 ser: f_ptr<0x4006ce70> Aug 30 10:00:50 iptel1 ser: ); Aug 30 10:00:50 iptel1 ser: ) { Aug 30 10:00:51 iptel1 ser: log( Aug 30 10:00:51 iptel1 ser: 1 Aug 30 10:00:51 iptel1 ser: , "Loose route processing " Aug 30 10:00:51 iptel1 ser: ); Aug 30 10:00:51 iptel1 ser: external_module_call( Aug 30 10:00:51 iptel1 ser: f_ptr<0x40047810> Aug 30 10:00:51 iptel1 ser: ); Aug 30 10:00:51 iptel1 ser: drop( Aug 30 10:00:51 iptel1 ser: type<0> Aug 30 10:00:51 iptel1 ser: ); Aug 30 10:00:51 iptel1 ser: } else { Aug 30 10:00:51 iptel1 ser: }; Aug 30 10:00:51 iptel1 ser: if ( Aug 30 10:00:51 iptel1 ser: method Aug 30 10:00:51 iptel1 ser: == Aug 30 10:00:51 iptel1 ser: "INVITE" Aug 30 10:00:51 iptel1 ser: ) { Aug 30 10:00:51 iptel1 ser: if ( Aug 30 10:00:51 iptel1 ser: uri Aug 30 10:00:51 iptel1 ser: =~ Aug 30 10:00:51 iptel1 ser: "^sip:[0-9][0-9][0-9]\*[\*#0-9]+@" Aug 30 10:00:51 iptel1 ser: ) { Aug 30 10:00:51 iptel1 ser: log( Aug 30 10:00:51 iptel1 ser: 1 Aug 30 10:00:51 iptel1 ser: , "REINVITE found " Aug 30 10:00:51 iptel1 ser: ); Aug 30 10:00:51 iptel1 ser: strip( Aug 30 10:00:51 iptel1 ser: 4 Aug 30 10:00:51 iptel1 ser: ); Aug 30 10:00:51 iptel1 ser: } else { Aug 30 10:00:51 iptel1 ser: }; Aug 30 10:00:51 iptel1 ser: if ( Aug 30 10:00:51 iptel1 ser: NOT( Aug 30 10:00:51 iptel1 ser: external_module_call( Aug 30 10:00:51 iptel1 ser: f_ptr<0x4007ab10> Aug 30 10:00:51 iptel1 ser: ); Aug 30 10:00:51 iptel1 ser: external_module_call( Aug 30 10:00:51 iptel1 ser: f_ptr<0x4007ab10> Aug 30 10:00:51 iptel1 ser: ); Aug 30 10:00:51 iptel1 ser: ) Aug 30 10:00:51 iptel1 ser: ) { Aug 30 10:00:51 iptel1 ser: external_module_call( Aug 30 10:00:51 iptel1 ser: f_ptr<0x4002e780> Aug 30 10:00:51 iptel1 ser: , "402" Aug 30 10:00:51 iptel1 ser: , "Payment Required" Aug 30 10:00:51 iptel1 ser: ); Aug 30 10:00:51 iptel1 ser: log( Aug 30 10:00:51 iptel1 ser: 1 Aug 30 10:00:51 iptel1 ser: , "Routing denied " Aug 30 10:00:51 iptel1 ser: ); Aug 30 10:00:52 iptel1 ser: drop( Aug 30 10:00:52 iptel1 ser: type<0> Aug 30 10:00:52 iptel1 ser: ); Aug 30 10:00:52 iptel1 ser: } else { Aug 30 10:00:52 iptel1 ser: }; Aug 30 10:00:52 iptel1 ser: } else { Aug 30 10:00:52 iptel1 ser: }; Aug 30 10:00:52 iptel1 ser: if ( Aug 30 10:00:52 iptel1 ser: OR( Aug 30 10:00:52 iptel1 ser: uri Aug 30 10:00:52 iptel1 ser: =~ Aug 30 10:00:52 iptel1 ser: "hbone\.hu([:;\?].*)?$" Aug 30 10:00:52 iptel1 ser: , Aug 30 10:00:52 iptel1 ser: uri Aug 30 10:00:52 iptel1 ser: =~ Aug 30 10:00:52 iptel1 ser: "@193\.225\.14\.136([:;\?].*)?$" Aug 30 10:00:52 iptel1 ser: ) Aug 30 10:00:52 iptel1 ser: ) { Aug 30 10:00:52 iptel1 ser: log( Aug 30 10:00:52 iptel1 ser: 1 Aug 30 10:00:52 iptel1 ser: , "MY URI " Aug 30 10:00:52 iptel1 ser: ); Aug 30 10:00:52 iptel1 ser: if ( Aug 30 10:00:52 iptel1 ser: uri Aug 30 10:00:52 iptel1 ser: =~ Aug 30 10:00:52 iptel1 ser: "^sip:[\*#0-9]+@" Aug 30 10:00:52 iptel1 ser: ) { Aug 30 10:00:52 iptel1 ser: prefix( Aug 30 10:00:52 iptel1 ser: "+" Aug 30 10:00:52 iptel1 ser: ); Aug 30 10:00:52 iptel1 ser: } else { Aug 30 10:00:52 iptel1 ser: }; Aug 30 10:00:52 iptel1 ser: if ( Aug 30 10:00:52 iptel1 ser: NOT( Aug 30 10:00:52 iptel1 ser: external_module_call( Aug 30 10:00:52 iptel1 ser: f_ptr<0x4007efe0> Aug 30 10:00:52 iptel1 ser: , "" Aug 30 10:00:52 iptel1 ser: ); Aug 30 10:00:52 iptel1 ser: external_module_call( Aug 30 10:00:52 iptel1 ser: f_ptr<0x4007efe0> Aug 30 10:00:52 iptel1 ser: , "" Aug 30 10:00:52 iptel1 ser: ); Aug 30 10:00:52 iptel1 ser: ) Aug 30 10:00:52 iptel1 ser: ) { Aug 30 10:00:52 iptel1 ser: log( Aug 30 10:00:52 iptel1 ser: 1 Aug 30 10:00:53 iptel1 ser: , "ENUM FAILED " Aug 30 10:00:53 iptel1 ser: ); Aug 30 10:00:53 iptel1 ser: external_module_call( Aug 30 10:00:53 iptel1 ser: f_ptr<0x4002e780> Aug 30 10:00:53 iptel1 ser: , "404" Aug 30 10:00:53 iptel1 ser: , "Not Found" Aug 30 10:00:53 iptel1 ser: ); Aug 30 10:00:53 iptel1 ser: drop( Aug 30 10:00:53 iptel1 ser: type<0> Aug 30 10:00:53 iptel1 ser: ); Aug 30 10:00:53 iptel1 ser: } else { Aug 30 10:00:53 iptel1 ser: }; Aug 30 10:00:53 iptel1 ser: if ( Aug 30 10:00:53 iptel1 ser: uri Aug 30 10:00:53 iptel1 ser: =~ Aug 30 10:00:53 iptel1 ser: "^sip:\+[\*#0-9]+@" Aug 30 10:00:53 iptel1 ser: ) { Aug 30 10:00:53 iptel1 ser: strip( Aug 30 10:00:53 iptel1 ser: 1 Aug 30 10:00:53 iptel1 ser: ); Aug 30 10:00:53 iptel1 ser: } else { Aug 30 10:00:53 iptel1 ser: }; Aug 30 10:00:53 iptel1 ser: } else { Aug 30 10:00:53 iptel1 ser: log( Aug 30 10:00:53 iptel1 ser: 1 Aug 30 10:00:53 iptel1 ser: , "NOT MY URI " Aug 30 10:00:53 iptel1 ser: ); Aug 30 10:00:53 iptel1 ser: }; Aug 30 10:00:53 iptel1 ser: log( Aug 30 10:00:53 iptel1 ser: 1 Aug 30 10:00:53 iptel1 ser: , "Sending request... " Aug 30 10:00:53 iptel1 ser: ); Aug 30 10:00:53 iptel1 ser: if ( Aug 30 10:00:53 iptel1 ser: NOT( Aug 30 10:00:53 iptel1 ser: external_module_call( Aug 30 10:00:53 iptel1 ser: f_ptr<0x40047810> Aug 30 10:00:53 iptel1 ser: ); Aug 30 10:00:53 iptel1 ser: external_module_call( Aug 30 10:00:53 iptel1 ser: f_ptr<0x40047810> Aug 30 10:00:53 iptel1 ser: ); Aug 30 10:00:53 iptel1 ser: ) Aug 30 10:00:53 iptel1 ser: ) { Aug 30 10:00:53 iptel1 ser: log( Aug 30 10:00:53 iptel1 ser: 1 Aug 30 10:00:53 iptel1 ser: , "t_relay FAILED " Aug 30 10:00:53 iptel1 ser: ); Aug 30 10:00:53 iptel1 ser: external_module_call( Aug 30 10:00:54 iptel1 ser: f_ptr<0x4002e7b0> Aug 30 10:00:54 iptel1 ser: ); Aug 30 10:00:54 iptel1 ser: } else { Aug 30 10:00:54 iptel1 ser: }; Aug 30 10:00:54 iptel1 ser: if ( Aug 30 10:00:54 iptel1 ser: external_module_call( Aug 30 10:00:54 iptel1 ser: f_ptr<0x4006ce70> Aug 30 10:00:54 iptel1 ser: ); Aug 30 10:00:54 iptel1 ser: external_module_call( Aug 30 10:00:54 iptel1 ser: f_ptr<0x4006ce70> Aug 30 10:00:54 iptel1 ser: ); Aug 30 10:00:54 iptel1 ser: ) { Aug 30 10:00:54 iptel1 ser: log( Aug 30 10:00:54 iptel1 ser: 1 Aug 30 10:00:54 iptel1 ser: , "Loose route processing " Aug 30 10:00:54 iptel1 ser: ); Aug 30 10:00:54 iptel1 ser: external_module_call( Aug 30 10:00:54 iptel1 ser: f_ptr<0x40047810> Aug 30 10:00:54 iptel1 ser: ); Aug 30 10:00:54 iptel1 ser: drop( Aug 30 10:00:54 iptel1 ser: type<0> Aug 30 10:00:54 iptel1 ser: ); Aug 30 10:00:54 iptel1 ser: } else { Aug 30 10:00:54 iptel1 ser: }; Aug 30 10:00:54 iptel1 ser: if ( Aug 30 10:00:54 iptel1 ser: method Aug 30 10:00:54 iptel1 ser: == Aug 30 10:00:54 iptel1 ser: "INVITE" Aug 30 10:00:54 iptel1 ser: ) { Aug 30 10:00:54 iptel1 ser: if ( Aug 30 10:00:54 iptel1 ser: uri Aug 30 10:00:54 iptel1 ser: =~ Aug 30 10:00:54 iptel1 ser: "^sip:[0-9][0-9][0-9]\*[\*#0-9]+@" Aug 30 10:00:54 iptel1 ser: ) { Aug 30 10:00:54 iptel1 ser: log( Aug 30 10:00:54 iptel1 ser: 1 Aug 30 10:00:54 iptel1 ser: , "REINVITE found " Aug 30 10:00:54 iptel1 ser: ); Aug 30 10:00:54 iptel1 ser: strip( Aug 30 10:00:54 iptel1 ser: 4 Aug 30 10:00:54 iptel1 ser: ); Aug 30 10:00:54 iptel1 ser: } else { Aug 30 10:00:54 iptel1 ser: }; Aug 30 10:00:54 iptel1 ser: if ( Aug 30 10:00:54 iptel1 ser: NOT( Aug 30 10:00:54 iptel1 ser: external_module_call( Aug 30 10:00:55 iptel1 ser: f_ptr<0x4007ab10> Aug 30 10:00:55 iptel1 ser: ); Aug 30 10:00:55 iptel1 ser: external_module_call( Aug 30 10:00:55 iptel1 ser: f_ptr<0x4007ab10> Aug 30 10:00:55 iptel1 ser: ); Aug 30 10:00:55 iptel1 ser: ) Aug 30 10:00:55 iptel1 ser: ) { Aug 30 10:00:55 iptel1 ser: external_module_call( Aug 30 10:00:55 iptel1 ser: f_ptr<0x4002e780> Aug 30 10:00:55 iptel1 ser: , "402" Aug 30 10:00:55 iptel1 ser: , "Payment Required" Aug 30 10:00:55 iptel1 ser: ); Aug 30 10:00:55 iptel1 ser: log( Aug 30 10:00:55 iptel1 ser: 1 Aug 30 10:00:55 iptel1 ser: , "Routing denied " Aug 30 10:00:55 iptel1 ser: ); Aug 30 10:00:55 iptel1 ser: drop( Aug 30 10:00:55 iptel1 ser: type<0> Aug 30 10:00:55 iptel1 ser: ); Aug 30 10:00:55 iptel1 ser: } else { Aug 30 10:00:55 iptel1 ser: }; Aug 30 10:00:55 iptel1 ser: } else { Aug 30 10:00:55 iptel1 ser: }; Aug 30 10:00:55 iptel1 ser: if ( Aug 30 10:00:55 iptel1 ser: OR( Aug 30 10:00:55 iptel1 ser: uri Aug 30 10:00:55 iptel1 ser: =~ Aug 30 10:00:55 iptel1 ser: "hbone\.hu([:;\?].*)?$" Aug 30 10:00:55 iptel1 ser: , Aug 30 10:00:55 iptel1 ser: uri Aug 30 10:00:55 iptel1 ser: =~ Aug 30 10:00:55 iptel1 ser: "@193\.225\.14\.136([:;\?].*)?$" Aug 30 10:00:55 iptel1 ser: ) Aug 30 10:00:55 iptel1 ser: ) { Aug 30 10:00:55 iptel1 ser: log( Aug 30 10:00:55 iptel1 ser: 1 Aug 30 10:00:55 iptel1 ser: , "MY URI " Aug 30 10:00:55 iptel1 ser: ); Aug 30 10:00:55 iptel1 ser: if ( Aug 30 10:00:55 iptel1 ser: uri Aug 30 10:00:55 iptel1 ser: =~ Aug 30 10:00:55 iptel1 ser: "^sip:[\*#0-9]+@" Aug 30 10:00:55 iptel1 ser: ) { Aug 30 10:00:55 iptel1 ser: prefix( Aug 30 10:00:55 iptel1 ser: "+" Aug 30 10:00:55 iptel1 ser: ); Aug 30 10:00:55 iptel1 ser: } else { Aug 30 10:00:55 iptel1 ser: }; Aug 30 10:00:55 iptel1 ser: if ( Aug 30 10:00:55 iptel1 ser: NOT( Aug 30 10:00:55 iptel1 ser: external_module_call( Aug 30 10:00:56 iptel1 ser: f_ptr<0x4007efe0> Aug 30 10:00:56 iptel1 ser: , "" Aug 30 10:00:56 iptel1 ser: ); Aug 30 10:00:56 iptel1 ser: external_module_call( Aug 30 10:00:56 iptel1 ser: f_ptr<0x4007efe0> Aug 30 10:00:56 iptel1 ser: , "" Aug 30 10:00:56 iptel1 ser: ); Aug 30 10:00:56 iptel1 ser: ) Aug 30 10:00:56 iptel1 ser: ) { Aug 30 10:00:56 iptel1 ser: log( Aug 30 10:00:56 iptel1 ser: 1 Aug 30 10:00:56 iptel1 ser: , "ENUM FAILED " Aug 30 10:00:56 iptel1 ser: ); Aug 30 10:00:56 iptel1 ser: external_module_call( Aug 30 10:00:56 iptel1 ser: f_ptr<0x4002e780> Aug 30 10:00:56 iptel1 ser: , "404" Aug 30 10:00:56 iptel1 ser: , "Not Found" Aug 30 10:00:56 iptel1 ser: ); Aug 30 10:00:56 iptel1 ser: drop( Aug 30 10:00:56 iptel1 ser: type<0> Aug 30 10:00:56 iptel1 ser: ); Aug 30 10:00:56 iptel1 ser: } else { Aug 30 10:00:56 iptel1 ser: }; Aug 30 10:00:56 iptel1 ser: if ( Aug 30 10:00:56 iptel1 ser: uri Aug 30 10:00:56 iptel1 ser: =~ Aug 30 10:00:56 iptel1 ser: "^sip:\+[\*#0-9]+@" Aug 30 10:00:56 iptel1 ser: ) { Aug 30 10:00:56 iptel1 ser: strip( Aug 30 10:00:56 iptel1 ser: 1 Aug 30 10:00:56 iptel1 ser: ); Aug 30 10:00:56 iptel1 ser: } else { Aug 30 10:00:56 iptel1 ser: }; Aug 30 10:00:56 iptel1 ser: } else { Aug 30 10:00:56 iptel1 ser: log( Aug 30 10:00:56 iptel1 ser: 1 Aug 30 10:00:56 iptel1 ser: , "NOT MY URI " Aug 30 10:00:56 iptel1 ser: ); Aug 30 10:00:56 iptel1 ser: }; Aug 30 10:00:56 iptel1 ser: log( Aug 30 10:00:56 iptel1 ser: 1 Aug 30 10:00:56 iptel1 ser: , "Sending request... " Aug 30 10:00:56 iptel1 ser: ); Aug 30 10:00:56 iptel1 ser: if ( Aug 30 10:00:56 iptel1 ser: NOT( Aug 30 10:00:56 iptel1 ser: external_module_call( Aug 30 10:00:56 iptel1 ser: f_ptr<0x40047810> Aug 30 10:00:56 iptel1 ser: ); Aug 30 10:00:57 iptel1 ser: external_module_call( Aug 30 10:00:57 iptel1 ser: f_ptr<0x40047810> Aug 30 10:00:57 iptel1 ser: ); Aug 30 10:00:57 iptel1 ser: ) Aug 30 10:00:57 iptel1 ser: ) { Aug 30 10:00:57 iptel1 ser: log( Aug 30 10:00:57 iptel1 ser: 1 Aug 30 10:00:57 iptel1 ser: , "t_relay FAILED " Aug 30 10:00:57 iptel1 ser: ); Aug 30 10:00:57 iptel1 ser: external_module_call( Aug 30 10:00:57 iptel1 ser: f_ptr<0x4002e7b0> Aug 30 10:00:57 iptel1 ser: ); Aug 30 10:00:57 iptel1 ser: } else { Aug 30 10:00:57 iptel1 ser: }; Aug 30 10:00:57 iptel1 ser: if ( Aug 30 10:00:57 iptel1 ser: method Aug 30 10:00:57 iptel1 ser: == Aug 30 10:00:57 iptel1 ser: "INVITE" Aug 30 10:00:57 iptel1 ser: ) { Aug 30 10:00:57 iptel1 ser: if ( Aug 30 10:00:57 iptel1 ser: uri Aug 30 10:00:57 iptel1 ser: =~ Aug 30 10:00:57 iptel1 ser: "^sip:[0-9][0-9][0-9]\*[\*#0-9]+@" Aug 30 10:00:57 iptel1 ser: ) { Aug 30 10:00:57 iptel1 ser: log( Aug 30 10:00:57 iptel1 ser: 1 Aug 30 10:00:57 iptel1 ser: , "REINVITE found " Aug 30 10:00:57 iptel1 ser: ); Aug 30 10:00:57 iptel1 ser: strip( Aug 30 10:00:57 iptel1 ser: 4 Aug 30 10:00:57 iptel1 ser: ); Aug 30 10:00:57 iptel1 ser: } else { Aug 30 10:00:57 iptel1 ser: }; Aug 30 10:00:57 iptel1 ser: if ( Aug 30 10:00:57 iptel1 ser: NOT( Aug 30 10:00:57 iptel1 ser: external_module_call( Aug 30 10:00:57 iptel1 ser: f_ptr<0x4007ab10> Aug 30 10:00:57 iptel1 ser: ); Aug 30 10:00:57 iptel1 ser: external_module_call( Aug 30 10:00:57 iptel1 ser: f_ptr<0x4007ab10> Aug 30 10:00:57 iptel1 ser: ); Aug 30 10:00:57 iptel1 ser: ) Aug 30 10:00:57 iptel1 ser: ) { Aug 30 10:00:57 iptel1 ser: external_module_call( Aug 30 10:00:57 iptel1 ser: f_ptr<0x4002e780> Aug 30 10:00:57 iptel1 ser: , "402" Aug 30 10:00:58 iptel1 ser: , "Payment Required" Aug 30 10:00:58 iptel1 ser: ); Aug 30 10:00:58 iptel1 ser: log( Aug 30 10:00:58 iptel1 ser: 1 Aug 30 10:00:58 iptel1 ser: , "Routing denied " Aug 30 10:00:58 iptel1 ser: ); Aug 30 10:00:58 iptel1 ser: drop( Aug 30 10:00:58 iptel1 ser: type<0> Aug 30 10:00:58 iptel1 ser: ); Aug 30 10:00:58 iptel1 ser: } else { Aug 30 10:00:58 iptel1 ser: }; Aug 30 10:00:58 iptel1 ser: } else { Aug 30 10:00:58 iptel1 ser: }; Aug 30 10:00:58 iptel1 ser: if ( Aug 30 10:00:58 iptel1 ser: OR( Aug 30 10:00:58 iptel1 ser: uri Aug 30 10:00:58 iptel1 ser: =~ Aug 30 10:00:58 iptel1 ser: "hbone\.hu([:;\?].*)?$" Aug 30 10:00:58 iptel1 ser: , Aug 30 10:00:58 iptel1 ser: uri Aug 30 10:00:58 iptel1 ser: =~ Aug 30 10:00:58 iptel1 ser: "@193\.225\.14\.136([:;\?].*)?$" Aug 30 10:00:58 iptel1 ser: ) Aug 30 10:00:58 iptel1 ser: ) { Aug 30 10:00:58 iptel1 ser: log( Aug 30 10:00:58 iptel1 ser: 1 Aug 30 10:00:58 iptel1 ser: , "MY URI " Aug 30 10:00:58 iptel1 ser: ); Aug 30 10:00:58 iptel1 ser: if ( Aug 30 10:00:58 iptel1 ser: uri Aug 30 10:00:58 iptel1 ser: =~ Aug 30 10:00:58 iptel1 ser: "^sip:[\*#0-9]+@" Aug 30 10:00:58 iptel1 ser: ) { Aug 30 10:00:58 iptel1 ser: prefix( Aug 30 10:00:58 iptel1 ser: "+" Aug 30 10:00:58 iptel1 ser: ); Aug 30 10:00:58 iptel1 ser: } else { Aug 30 10:00:58 iptel1 ser: }; Aug 30 10:00:58 iptel1 ser: if ( Aug 30 10:00:58 iptel1 ser: NOT( Aug 30 10:00:58 iptel1 ser: external_module_call( Aug 30 10:00:58 iptel1 ser: f_ptr<0x4007efe0> Aug 30 10:00:58 iptel1 ser: , "" Aug 30 10:00:58 iptel1 ser: ); Aug 30 10:00:59 iptel1 ser: external_module_call( Aug 30 10:00:59 iptel1 ser: f_ptr<0x4007efe0> Aug 30 10:00:59 iptel1 ser: , "" Aug 30 10:00:59 iptel1 ser: ); Aug 30 10:00:59 iptel1 ser: ) Aug 30 10:00:59 iptel1 ser: ) { Aug 30 10:00:59 iptel1 ser: log( Aug 30 10:00:59 iptel1 ser: 1 Aug 30 10:00:59 iptel1 ser: , "ENUM FAILED " Aug 30 10:00:59 iptel1 ser: ); Aug 30 10:00:59 iptel1 ser: external_module_call( Aug 30 10:00:59 iptel1 ser: f_ptr<0x4002e780> Aug 30 10:00:59 iptel1 ser: , "404" Aug 30 10:00:59 iptel1 ser: , "Not Found" Aug 30 10:00:59 iptel1 ser: ); Aug 30 10:00:59 iptel1 ser: drop( Aug 30 10:00:59 iptel1 ser: type<0> Aug 30 10:00:59 iptel1 ser: ); Aug 30 10:00:59 iptel1 ser: } else { Aug 30 10:00:59 iptel1 ser: }; Aug 30 10:00:59 iptel1 ser: if ( Aug 30 10:00:59 iptel1 ser: uri Aug 30 10:00:59 iptel1 ser: =~ Aug 30 10:00:59 iptel1 ser: "^sip:\+[\*#0-9]+@" Aug 30 10:00:59 iptel1 ser: ) { Aug 30 10:00:59 iptel1 ser: strip( Aug 30 10:00:59 iptel1 ser: 1 Aug 30 10:00:59 iptel1 ser: ); Aug 30 10:00:59 iptel1 ser: } else { Aug 30 10:00:59 iptel1 ser: }; Aug 30 10:00:59 iptel1 ser: } else { Aug 30 10:00:59 iptel1 ser: log( Aug 30 10:00:59 iptel1 ser: 1 Aug 30 10:00:59 iptel1 ser: , "NOT MY URI " Aug 30 10:00:59 iptel1 ser: ); Aug 30 10:00:59 iptel1 ser: }; Aug 30 10:00:59 iptel1 ser: log( Aug 30 10:00:59 iptel1 ser: 1 Aug 30 10:00:59 iptel1 ser: , "Sending request... " Aug 30 10:00:59 iptel1 ser: ); Aug 30 10:00:59 iptel1 ser: if ( Aug 30 10:00:59 iptel1 ser: NOT( Aug 30 10:00:59 iptel1 ser: external_module_call( Aug 30 10:00:59 iptel1 ser: f_ptr<0x40047810> Aug 30 10:00:59 iptel1 ser: ); Aug 30 10:00:59 iptel1 ser: external_module_call( Aug 30 10:00:59 iptel1 ser: f_ptr<0x40047810> Aug 30 10:00:59 iptel1 ser: ); Aug 30 10:00:59 iptel1 ser: ) Aug 30 10:00:59 iptel1 ser: ) { Aug 30 10:00:59 iptel1 ser: log( Aug 30 10:01:00 iptel1 ser: 1 Aug 30 10:01:00 iptel1 ser: , "t_relay FAILED " Aug 30 10:01:00 iptel1 ser: ); Aug 30 10:01:00 iptel1 ser: external_module_call( Aug 30 10:01:00 iptel1 ser: f_ptr<0x4002e7b0> Aug 30 10:01:00 iptel1 ser: ); Aug 30 10:01:00 iptel1 ser: } else { Aug 30 10:01:00 iptel1 ser: }; Aug 30 10:01:00 iptel1 ser: if ( Aug 30 10:01:00 iptel1 ser: OR( Aug 30 10:01:00 iptel1 ser: uri Aug 30 10:01:00 iptel1 ser: =~ Aug 30 10:01:00 iptel1 ser: "hbone\.hu([:;\?].*)?$" Aug 30 10:01:00 iptel1 ser: , Aug 30 10:01:00 iptel1 ser: uri Aug 30 10:01:00 iptel1 ser: =~ Aug 30 10:01:00 iptel1 ser: "@193\.225\.14\.136([:;\?].*)?$" Aug 30 10:01:00 iptel1 ser: ) Aug 30 10:01:00 iptel1 ser: ) { Aug 30 10:01:00 iptel1 ser: log( Aug 30 10:01:00 iptel1 ser: 1 Aug 30 10:01:00 iptel1 ser: , "MY URI " Aug 30 10:01:00 iptel1 ser: ); Aug 30 10:01:00 iptel1 ser: if ( Aug 30 10:01:00 iptel1 ser: uri Aug 30 10:01:00 iptel1 ser: =~ Aug 30 10:01:00 iptel1 ser: "^sip:[\*#0-9]+@" Aug 30 10:01:00 iptel1 ser: ) { Aug 30 10:01:00 iptel1 ser: prefix( Aug 30 10:01:00 iptel1 ser: "+" Aug 30 10:01:00 iptel1 ser: ); Aug 30 10:01:00 iptel1 ser: } else { Aug 30 10:01:00 iptel1 ser: }; Aug 30 10:01:00 iptel1 ser: if ( Aug 30 10:01:00 iptel1 ser: NOT( Aug 30 10:01:00 iptel1 ser: external_module_call( Aug 30 10:01:00 iptel1 ser: f_ptr<0x4007efe0> Aug 30 10:01:00 iptel1 ser: , "" Aug 30 10:01:00 iptel1 ser: ); Aug 30 10:01:00 iptel1 ser: external_module_call( Aug 30 10:01:00 iptel1 ser: f_ptr<0x4007efe0> Aug 30 10:01:00 iptel1 ser: , "" Aug 30 10:01:00 iptel1 ser: ); Aug 30 10:01:00 iptel1 ser: ) Aug 30 10:01:00 iptel1 ser: ) { Aug 30 10:01:00 iptel1 ser: log( Aug 30 10:01:01 iptel1 ser: 1 Aug 30 10:01:01 iptel1 ser: , "ENUM FAILED " Aug 30 10:01:01 iptel1 ser: ); Aug 30 10:01:01 iptel1 ser: external_module_call( Aug 30 10:01:01 iptel1 ser: f_ptr<0x4002e780> Aug 30 10:01:01 iptel1 ser: , "404" Aug 30 10:01:01 iptel1 ser: , "Not Found" Aug 30 10:01:01 iptel1 ser: ); Aug 30 10:01:01 iptel1 ser: drop( Aug 30 10:01:01 iptel1 ser: type<0> Aug 30 10:01:01 iptel1 ser: ); Aug 30 10:01:01 iptel1 ser: } else { Aug 30 10:01:01 iptel1 ser: }; Aug 30 10:01:01 iptel1 ser: if ( Aug 30 10:01:01 iptel1 ser: uri Aug 30 10:01:01 iptel1 ser: =~ Aug 30 10:01:01 iptel1 ser: "^sip:\+[\*#0-9]+@" Aug 30 10:01:01 iptel1 ser: ) { Aug 30 10:01:01 iptel1 ser: strip( Aug 30 10:01:01 iptel1 ser: 1 Aug 30 10:01:01 iptel1 ser: ); Aug 30 10:01:01 iptel1 ser: } else { Aug 30 10:01:01 iptel1 ser: }; Aug 30 10:01:01 iptel1 ser: } else { Aug 30 10:01:01 iptel1 ser: log( Aug 30 10:01:01 iptel1 ser: 1 Aug 30 10:01:01 iptel1 ser: , "NOT MY URI " Aug 30 10:01:01 iptel1 ser: ); Aug 30 10:01:01 iptel1 ser: }; Aug 30 10:01:01 iptel1 ser: log( Aug 30 10:01:01 iptel1 ser: 1 Aug 30 10:01:01 iptel1 ser: , "Sending request... " Aug 30 10:01:01 iptel1 ser: ); Aug 30 10:01:01 iptel1 ser: if ( Aug 30 10:01:01 iptel1 ser: NOT( Aug 30 10:01:01 iptel1 ser: external_module_call( Aug 30 10:01:01 iptel1 ser: f_ptr<0x40047810> Aug 30 10:01:01 iptel1 ser: ); Aug 30 10:01:01 iptel1 ser: external_module_call( Aug 30 10:01:01 iptel1 ser: f_ptr<0x40047810> Aug 30 10:01:01 iptel1 ser: ); Aug 30 10:01:01 iptel1 ser: ) Aug 30 10:01:01 iptel1 ser: ) { Aug 30 10:01:01 iptel1 ser: log( Aug 30 10:01:01 iptel1 ser: 1 Aug 30 10:01:01 iptel1 ser: , "t_relay FAILED " Aug 30 10:01:01 iptel1 ser: ); Aug 30 10:01:02 iptel1 ser: external_module_call( Aug 30 10:01:02 iptel1 ser: f_ptr<0x4002e7b0> Aug 30 10:01:02 iptel1 ser: ); Aug 30 10:01:02 iptel1 ser: } else { Aug 30 10:01:02 iptel1 ser: }; Aug 30 10:01:02 iptel1 ser: log( Aug 30 10:01:02 iptel1 ser: 1 Aug 30 10:01:02 iptel1 ser: , "Sending request... " Aug 30 10:01:02 iptel1 ser: ); Aug 30 10:01:02 iptel1 ser: if ( Aug 30 10:01:02 iptel1 ser: NOT( Aug 30 10:01:02 iptel1 ser: external_module_call( Aug 30 10:01:02 iptel1 ser: f_ptr<0x40047810> Aug 30 10:01:02 iptel1 ser: ); Aug 30 10:01:02 iptel1 ser: external_module_call( Aug 30 10:01:02 iptel1 ser: f_ptr<0x40047810> Aug 30 10:01:02 iptel1 ser: ); Aug 30 10:01:02 iptel1 ser: ) Aug 30 10:01:02 iptel1 ser: ) { Aug 30 10:01:02 iptel1 ser: log( Aug 30 10:01:02 iptel1 ser: 1 Aug 30 10:01:02 iptel1 ser: , "t_relay FAILED " Aug 30 10:01:02 iptel1 ser: ); Aug 30 10:01:02 iptel1 ser: external_module_call( Aug 30 10:01:02 iptel1 ser: f_ptr<0x4002e7b0> Aug 30 10:01:02 iptel1 ser: ); Aug 30 10:01:02 iptel1 ser: } else { Aug 30 10:01:02 iptel1 ser: }; Aug 30 10:01:02 iptel1 ser: if ( Aug 30 10:01:02 iptel1 ser: NOT( Aug 30 10:01:02 iptel1 ser: external_module_call( Aug 30 10:01:02 iptel1 ser: f_ptr<0x40047810> Aug 30 10:01:02 iptel1 ser: ); Aug 30 10:01:02 iptel1 ser: external_module_call( Aug 30 10:01:02 iptel1 ser: f_ptr<0x40047810> Aug 30 10:01:02 iptel1 ser: ); Aug 30 10:01:02 iptel1 ser: ) Aug 30 10:01:02 iptel1 ser: ) { Aug 30 10:01:02 iptel1 ser: log( Aug 30 10:01:02 iptel1 ser: 1 Aug 30 10:01:02 iptel1 ser: , "t_relay FAILED " Aug 30 10:01:02 iptel1 ser: ); Aug 30 10:01:02 iptel1 ser: external_module_call( Aug 30 10:01:02 iptel1 ser: f_ptr<0x4002e7b0> Aug 30 10:01:02 iptel1 ser: ); Aug 30 10:01:02 iptel1 ser: } else { Aug 30 10:01:02 iptel1 ser: }; Aug 30 10:01:02 iptel1 ser: Aug 30 10:01:02 iptel1 /usr/sbin/ser[6632]: DEBUG: init_mod: sl_module Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: DEBUG: register_fifo_cmd: new command (sl_stats) registered Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: DEBUG: MD5 calculated: c8699519c4d72430f4c8a1a523a09352 Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: DEBUG: init_mod: tm Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: TM - initializing... Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: Call-ID initialization: '55dfffed' Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: DEBUG: register_fifo_cmd: new command (t_uac_dlg) registered Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: DEBUG: register_fifo_cmd: new command (t_hash) registered Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: DEBUG: lock_initialize: lock initialization started Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: DEBUG: register_fifo_cmd: new command (t_stats) registered Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: DEBUG: MD5 calculated: ae5c5e99e0b818a815cff126db2b6a9b Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: DEBUG: MD5 calculated: f7eeb7b606bf1ccc817606b6e2e5544e Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: DEBUG: init_mod: rr Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: rr - initializing Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: DEBUG: init_mod: maxfwd_module Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: DEBUG: init_mod: permissions Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: Allow file (/etc/ser2/permissions.allow) parsed Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: Deny file (/etc/ser2/permissions.deny) parsed Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: DEBUG: init_mod: enum Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: enum module - initializing Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: fixing /usr/lib/ser/modules/maxfwd.so mf_process_maxfwd_header Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: fixing /usr/lib/ser/modules/sl.so sl_send_reply Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: fixing /usr/lib/ser/modules/sl.so sl_send_reply Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: fixing /usr/lib/ser/modules/rr.so record_route Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: fixing /usr/lib/ser/modules/rr.so loose_route Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: fixing /usr/lib/ser/modules/tm.so t_relay Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: fixing /usr/lib/ser/modules/permissions.so allow_routing Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: fixing /usr/lib/ser/modules/sl.so sl_send_reply Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: fixing /usr/lib/ser/modules/enum.so enum_query Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: fixing /usr/lib/ser/modules/sl.so sl_send_reply Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: fixing /usr/lib/ser/modules/tm.so t_relay Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: fixing /usr/lib/ser/modules/sl.so sl_reply_error Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: INFO: udp_init: SO_RCVBUF is initially 65535 Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: DEBUG: udp_init: trying SO_RCVBUF: 131070 Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: DEBUG: setting SO_RCVBUF; set=131070,verify=262140 Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: DEBUG: udp_init: trying SO_RCVBUF: 262140 Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: DEBUG: setting SO_RCVBUF; set=262140,verify=262142 Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: INFO: udp_init: SO_RCVBUF is finally 262142 Aug 30 10:01:03 iptel1 /usr/sbin/ser[6633]: DEBUG: init_mod_child (1): tm Aug 30 10:01:03 iptel1 /usr/sbin/ser[6634]: DEBUG: init_mod_child (2): tm Aug 30 10:01:03 iptel1 /usr/sbin/ser[6635]: DEBUG: init_mod_child (3): tm Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: DBG: open_uac_fifo: opening fifo... Aug 30 10:01:03 iptel1 /usr/sbin/ser[6636]: DEBUG: init_mod_child (4): tm Aug 30 10:01:03 iptel1 /usr/sbin/ser[6633]: DEBUG: callid: &#39;55dfffed-6633(a)193.225.14.136&#39; Aug 30 10:01:03 iptel1 /usr/sbin/ser[6634]: DEBUG: callid: &#39;55dfffed-6634(a)193.225.14.136&#39; Aug 30 10:01:03 iptel1 /usr/sbin/ser[6635]: DEBUG: callid: &#39;55dfffed-6635(a)193.225.14.136&#39; Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: DEBUG: open_fifo_server: FIFO stat failed: Inappropriate ioctl for device Aug 30 10:01:03 iptel1 /usr/sbin/ser[6636]: DEBUG: callid: &#39;55dfffed-6636(a)193.225.14.136&#39; Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: DEBUG: FIFO created @ /tmp/ser2_fifo Aug 30 10:01:03 iptel1 /usr/sbin/ser[6632]: DEBUG: fifo /tmp/ser2_fifo opened, mode=432 Aug 30 10:01:04 iptel1 /usr/sbin/ser[6637]: INFO: fifo process starting: 6637 Aug 30 10:01:04 iptel1 /usr/sbin/ser[6637]: DEBUG: init_mod_child (-2): tm Aug 30 10:01:04 iptel1 /usr/sbin/ser[6637]: DEBUG: callid: &#39;55dfffed-6637(a)193.225.14.136&#39; Aug 30 10:01:04 iptel1 /usr/sbin/ser[6637]: SER: open_uac_fifo: fifo server up at /tmp/ser2_fifo... Aug 30 10:01:04 iptel1 /usr/sbin/ser[6638]: DEBUG: init_mod_child (-1): tm Aug 30 10:01:04 iptel1 /usr/sbin/ser[6639]: DEBUG: init_mod_child (5): tm Aug 30 10:01:04 iptel1 /usr/sbin/ser[6640]: DEBUG: init_mod_child (6): tm Aug 30 10:01:04 iptel1 /usr/sbin/ser[6641]: DEBUG: init_mod_child (7): tm Aug 30 10:01:04 iptel1 /usr/sbin/ser[6642]: DEBUG: init_mod_child (8): tm Aug 30 10:01:04 iptel1 /usr/sbin/ser[6632]: DEBUG: init_mod_child (0): tm Aug 30 10:01:04 iptel1 /usr/sbin/ser[6643]: DEBUG: init_mod_child (-4): tm Aug 30 10:01:04 iptel1 /usr/sbin/ser[6638]: DEBUG: callid: &#39;55dfffed-6638(a)193.225.14.136&#39; Aug 30 10:01:04 iptel1 /usr/sbin/ser[6639]: DEBUG: callid: &#39;55dfffed-6639(a)193.225.14.136&#39; Aug 30 10:01:04 iptel1 /usr/sbin/ser[6640]: DEBUG: callid: &#39;55dfffed-6640(a)193.225.14.136&#39; Aug 30 10:01:04 iptel1 /usr/sbin/ser[6641]: DEBUG: callid: &#39;55dfffed-6641(a)193.225.14.136&#39; Aug 30 10:01:04 iptel1 /usr/sbin/ser[6642]: DEBUG: callid: &#39;55dfffed-6642(a)193.225.14.136&#39; Aug 30 10:01:04 iptel1 /usr/sbin/ser[6632]: DEBUG: callid: &#39;55dfffed-6632(a)193.225.14.136&#39; Aug 30 10:01:04 iptel1 /usr/sbin/ser[6643]: DEBUG: callid: &#39;55dfffed-6643(a)193.225.14.136&#39; Aug 30 10:01:05 iptel1 /usr/sbin/ser[6633]: SIP Request: Aug 30 10:01:05 iptel1 /usr/sbin/ser[6633]: method: <INVITE> Aug 30 10:01:05 iptel1 /usr/sbin/ser[6633]: uri: <sip:3614032804@voip.hbone.hu:5070> Aug 30 10:01:05 iptel1 /usr/sbin/ser[6633]: version: <SIP/2.0> Aug 30 10:01:05 iptel1 /usr/sbin/ser[6633]: parse_headers: flags=1 Aug 30 10:01:05 iptel1 /usr/sbin/ser[6633]: end of header reached, state=5 Aug 30 10:01:05 iptel1 /usr/sbin/ser[6633]: parse_headers: Via found, flags=1 Aug 30 10:01:05 iptel1 /usr/sbin/ser[6633]: parse_headers: this is the first via Aug 30 10:01:05 iptel1 /usr/sbin/ser[6633]: After parse_msg... Aug 30 10:01:05 iptel1 /usr/sbin/ser[6633]: preparing to run routing scripts... Aug 30 10:01:05 iptel1 /usr/sbin/ser[6633]: DEBUG : is_maxfwd_present: searching for max_forwards header Aug 30 10:01:05 iptel1 /usr/sbin/ser[6633]: parse_headers: flags=128 Aug 30 10:01:05 iptel1 /usr/sbin/ser[6633]: end of header reached, state=9 Aug 30 10:01:05 iptel1 /usr/sbin/ser[6633]: DEBUG: get_hdr_field: <To> [32]; uri=[sip:3614032804@voip.hbone.hu] Aug 30 10:01:05 iptel1 /usr/sbin/ser[6633]: DEBUG: to body [<sip:3614032804@voip.hbone.hu>^M ] Aug 30 10:01:05 iptel1 /usr/sbin/ser[6633]: get_hdr_field: cseq <CSeq>: <101> <INVITE> Aug 30 10:01:05 iptel1 /usr/sbin/ser[6633]: DEBUG: is_maxfwd_present: value = 6 Aug 30 10:01:05 iptel1 /usr/sbin/ser[6633]: DEBUG: add_param: tag=C54B2498-15D5 Aug 30 10:01:05 iptel1 /usr/sbin/ser[6633]: end of header reached, state=29 Aug 30 10:01:05 iptel1 /usr/sbin/ser[6633]: parse_headers: flags=256 Aug 30 10:01:05 iptel1 /usr/sbin/ser[6633]: DEBUG: get_hdr_body : content_length=369 Aug 30 10:01:05 iptel1 /usr/sbin/ser[6633]: found end of header Aug 30 10:01:05 iptel1 /usr/sbin/ser[6633]: find_first_route(): No Route headers found Aug 30 10:01:05 iptel1 /usr/sbin/ser[6633]: loose_route(): There is no Route HF Aug 30 10:01:05 iptel1 /usr/sbin/ser[6633]: allow_ruting(): (module permissions) looking for FROM: sip:043*3612252800@195.111.104.182 Request URI: sip:3614032804@voip.hbone.hu Aug 30 10:01:06 iptel1 /usr/sbin/ser[6633]: allow_ruting(): (module permissions) allow roule found => routing is allowed Aug 30 10:01:06 iptel1 /usr/sbin/ser[6633]: MY URI Aug 30 10:01:06 iptel1 /usr/sbin/ser[6633]: enum_query(): order 10, pref 10, flen 1, flags 'u', slen 7, services 'e2u+sip', rlen 37, regexp '!^\+(.*)$!sip:1514\*\1@195.111.104.8!' Aug 30 10:01:06 iptel1 /usr/sbin/ser[6633]: reg_replace(): pattern: '^\+(.*)$', replacement: 'sip:1514\*\1@195.111.104.8', string: '+3614032804' Aug 30 10:01:06 iptel1 /usr/sbin/ser[6633]: enum_query(): resulted in replacement: 'sip:1514*3614032804@195.111.104.8' Aug 30 10:01:06 iptel1 /usr/sbin/ser[6633]: Sending request... Aug 30 10:01:06 iptel1 /usr/sbin/ser[6633]: DEBUG: t_addifnew: msg id=1 , global msg id=0 , T on entrance=0xffffffff Aug 30 10:01:06 iptel1 /usr/sbin/ser[6633]: parse_headers: flags=-1 Aug 30 10:01:06 iptel1 /usr/sbin/ser[6633]: parse_headers: flags=60 Aug 30 10:01:06 iptel1 /usr/sbin/ser[6633]: t_lookup_request: start searching: hash=52551, isACK=0 Aug 30 10:01:06 iptel1 /usr/sbin/ser[6633]: DEBUG: proceeding to pre-RFC3261 transaction matching Aug 30 10:01:06 iptel1 /usr/sbin/ser[6634]: SIP Request: Aug 30 10:01:06 iptel1 /usr/sbin/ser[6633]: DEBUG: t_lookup_request: no transaction found Aug 30 10:01:06 iptel1 /usr/sbin/ser[6634]: method: <INVITE> Aug 30 10:01:06 iptel1 /usr/sbin/ser[6633]: SER: new INVITE Aug 30 10:01:06 iptel1 /usr/sbin/ser[6634]: uri: <sip:3614032804@voip.hbone.hu:5070> Aug 30 10:01:06 iptel1 /usr/sbin/ser[6633]: parse_headers: flags=-1 Aug 30 10:01:06 iptel1 /usr/sbin/ser[6634]: version: <SIP/2.0> Aug 30 10:01:06 iptel1 /usr/sbin/ser[6633]: check_via_address(195.111.104.182, 195.111.104.182, 0) Aug 30 10:01:06 iptel1 /usr/sbin/ser[6634]: parse_headers: flags=1 Aug 30 10:01:06 iptel1 /usr/sbin/ser[6633]: WARNING:vqm_resize: resize(0) called Aug 30 10:01:06 iptel1 /usr/sbin/ser[6634]: end of header reached, state=5 Aug 30 10:01:06 iptel1 /usr/sbin/ser[6633]: DEBUG: reply sent out. buf=0x80d57f8: SIP/2.0 1..., shmem=0x422b9b78: SIP/2.0 1 Aug 30 10:01:06 iptel1 /usr/sbin/ser[6634]: parse_headers: Via found, flags=1 Aug 30 10:01:06 iptel1 /usr/sbin/ser[6633]: DEBUG: t_reply: finished Aug 30 10:01:06 iptel1 /usr/sbin/ser[6634]: parse_headers: this is the first via Aug 30 10:01:06 iptel1 /usr/sbin/ser[6633]: DEBUG: mk_proxy: doing DNS lookup... Aug 30 10:01:06 iptel1 /usr/sbin/ser[6634]: After parse_msg... Aug 30 10:01:06 iptel1 /usr/sbin/ser[6633]: check_via_address(195.111.104.182, 195.111.104.182, 0) Aug 30 10:01:06 iptel1 /usr/sbin/ser[6634]: preparing to run routing scripts... Aug 30 10:01:06 iptel1 /usr/sbin/ser[6633]: DEBUG: add_to_tail_of_timer[4]: 0x422b8324 Aug 30 10:01:06 iptel1 /usr/sbin/ser[6635]: SIP Reply (status): Aug 30 10:01:06 iptel1 /usr/sbin/ser[6634]: DEBUG : is_maxfwd_present: searching for max_forwards header Aug 30 10:01:06 iptel1 /usr/sbin/ser[6633]: DEBUG: add_to_tail_of_timer[0]: 0x422b8338 Aug 30 10:01:06 iptel1 /usr/sbin/ser[6635]: version: <SIP/2.0> Aug 30 10:01:06 iptel1 /usr/sbin/ser[6634]: parse_headers: flags=128 Aug 30 10:01:06 iptel1 /usr/sbin/ser[6633]: SER: new transaction fwd'ed Aug 30 10:01:06 iptel1 /usr/sbin/ser[6635]: status: <100> Aug 30 10:01:06 iptel1 /usr/sbin/ser[6634]: end of header reached, state=9 Aug 30 10:01:06 iptel1 /usr/sbin/ser[6633]: receive_msg: cleaning up Aug 30 10:01:06 iptel1 /usr/sbin/ser[6635]: reason: <Trying> Aug 30 10:01:06 iptel1 /usr/sbin/ser[6634]: DEBUG: get_hdr_field: <To> [32]; uri=[sip:3614032804@voip.hbone.hu] Aug 30 10:01:06 iptel1 /usr/sbin/ser[6635]: parse_headers: flags=1 Aug 30 10:01:06 iptel1 /usr/sbin/ser[6634]: DEBUG: to body [<sip:3614032804@voip.hbone.hu>^M ] Aug 30 10:01:06 iptel1 /usr/sbin/ser[6635]: Found param type 232, <branch> = <z9hG4bK74dc.40904841.0>; state=9 Aug 30 10:01:06 iptel1 /usr/sbin/ser[6634]: get_hdr_field: cseq <CSeq>: <101> <INVITE> Aug 30 10:01:06 iptel1 /usr/sbin/ser[6635]: parse_via: next_via Aug 30 10:01:06 iptel1 /usr/sbin/ser[6634]: DEBUG: is_maxfwd_present: value = 6 Aug 30 10:01:07 iptel1 /usr/sbin/ser[6635]: end of header reached, state=5 Aug 30 10:01:07 iptel1 /usr/sbin/ser[6634]: DEBUG: add_param: tag=C54B2498-15D5 Aug 30 10:01:07 iptel1 /usr/sbin/ser[6635]: parse_headers: Via found, flags=1 Aug 30 10:01:07 iptel1 /usr/sbin/ser[6634]: end of header reached, state=29 Aug 30 10:01:07 iptel1 /usr/sbin/ser[6635]: parse_headers: this is the first via Aug 30 10:01:07 iptel1 /usr/sbin/ser[6634]: parse_headers: flags=256 Aug 30 10:01:07 iptel1 /usr/sbin/ser[6635]: After parse_msg... Aug 30 10:01:07 iptel1 /usr/sbin/ser[6634]: DEBUG: get_hdr_body : content_length=369 Aug 30 10:01:07 iptel1 /usr/sbin/ser[6635]: forward_reply: found module tm, passing reply to it Aug 30 10:01:07 iptel1 /usr/sbin/ser[6634]: found end of header Aug 30 10:01:07 iptel1 /usr/sbin/ser[6635]: DEBUG: t_check: msg id=1 global id=0 T start=0xffffffff Aug 30 10:01:07 iptel1 /usr/sbin/ser[6634]: find_first_route(): No Route headers found Aug 30 10:01:07 iptel1 /usr/sbin/ser[6635]: parse_headers: flags=17 Aug 30 10:01:07 iptel1 /usr/sbin/ser[6634]: loose_route(): There is no Route HF Aug 30 10:01:07 iptel1 /usr/sbin/ser[6636]: SIP Reply (status): Aug 30 10:01:07 iptel1 /usr/sbin/ser[6635]: DEBUG: add_param: tag=EF306448-1A25 Aug 30 10:01:07 iptel1 /usr/sbin/ser[6638]: DEBUG: timer routine:4,tl=0x422b8324 next=(nil) Aug 30 10:01:07 iptel1 /usr/sbin/ser[6634]: allow_ruting(): (module permissions) looking for FROM: sip:043*3612252800@195.111.104.182 Request URI: sip:3614032804@voip.hbone.hu Aug 30 10:01:07 iptel1 /usr/sbin/ser[6636]: version: <SIP/2.0> Aug 30 10:01:07 iptel1 /usr/sbin/ser[6635]: end of header reached, state=29 Aug 30 10:01:07 iptel1 /usr/sbin/ser[6638]: DEBUG: retransmission_handler : request resending (t=0x422b8200, INVITE si ... ) Aug 30 10:01:07 iptel1 /usr/sbin/ser[6634]: allow_ruting(): (module permissions) allow roule found => routing is allowed Aug 30 10:01:07 iptel1 /usr/sbin/ser[6636]: status: <183> Aug 30 10:01:07 iptel1 /usr/sbin/ser[6635]: DEBUG: get_hdr_field: <To> [50]; uri=[sip:3614032804@voip.hbone.hu] Aug 30 10:01:07 iptel1 /usr/sbin/ser[6638]: DEBUG: add_to_tail_of_timer[5]: 0x422b8324 Aug 30 10:01:07 iptel1 /usr/sbin/ser[6633]: SIP Reply (status): Aug 30 10:01:07 iptel1 /usr/sbin/ser[6634]: MY URI Aug 30 10:01:07 iptel1 /usr/sbin/ser[6636]: reason: <Session Progress> Aug 30 10:01:07 iptel1 /usr/sbin/ser[6635]: DEBUG: to body [<sip:3614032804@voip.hbone.hu>] Aug 30 10:01:07 iptel1 /usr/sbin/ser[6638]: DEBUG: retransmission_handler : done Aug 30 10:01:07 iptel1 /usr/sbin/ser[6633]: version: <SIP/2.0> Aug 30 10:01:07 iptel1 /usr/sbin/ser[6634]: enum_query(): order 10, pref 10, flen 1, flags 'u', slen 7, services 'e2u+sip', rlen 37, regexp '!^\+(.*)$!sip:1514\*\1@195.111.104.8!' Aug 30 10:01:07 iptel1 /usr/sbin/ser[6636]: parse_headers: flags=1 Aug 30 10:01:07 iptel1 /usr/sbin/ser[6635]: get_hdr_field: cseq <CSeq>: <101> <INVITE> Aug 30 10:01:07 iptel1 /usr/sbin/ser[6633]: status: <183> Aug 30 10:01:07 iptel1 /usr/sbin/ser[6634]: reg_replace(): pattern: '^\+(.*)$', replacement: 'sip:1514\*\1@195.111.104.8', string: '+3614032804' Aug 30 10:01:07 iptel1 /usr/sbin/ser[6636]: Found param type 232, <branch> = <z9hG4bK74dc.40904841.0>; state=9 Aug 30 10:01:07 iptel1 /usr/sbin/ser[6635]: parse_headers: flags=4 Aug 30 10:01:07 iptel1 /usr/sbin/ser[6633]: reason: <Session Progress> Aug 30 10:01:07 iptel1 /usr/sbin/ser[6634]: enum_query(): resulted in replacement: 'sip:1514*3614032804@195.111.104.8' Aug 30 10:01:07 iptel1 /usr/sbin/ser[6636]: parse_via: next_via Aug 30 10:01:07 iptel1 /usr/sbin/ser[6635]: DEBUG: t_reply_matching: hash 52551 label 344197380 branch 0 Aug 30 10:01:07 iptel1 /usr/sbin/ser[6633]: parse_headers: flags=1 Aug 30 10:01:07 iptel1 /usr/sbin/ser[6634]: Sending request... Aug 30 10:01:07 iptel1 /usr/sbin/ser[6636]: end of header reached, state=5 Aug 30 10:01:07 iptel1 /usr/sbin/ser[6635]: DEBUG: t_reply_matching: reply matched (T=0x422b8200)! Aug 30 10:01:07 iptel1 /usr/sbin/ser[6633]: Found param type 232, <branch> = <z9hG4bK74dc.40904841.0>; state=9 Aug 30 10:01:07 iptel1 /usr/sbin/ser[6634]: DEBUG: t_addifnew: msg id=1 , global msg id=0 , T on entrance=0xffffffff Aug 30 10:01:07 iptel1 /usr/sbin/ser[6636]: parse_headers: Via found, flags=1 Aug 30 10:01:07 iptel1 /usr/sbin/ser[6635]: DEBUG: t_check: msg id=1 global id=1 T end=0x422b8200 Aug 30 10:01:07 iptel1 /usr/sbin/ser[6633]: parse_via: next_via Aug 30 10:01:07 iptel1 /usr/sbin/ser[6634]: parse_headers: flags=-1 Aug 30 10:01:07 iptel1 /usr/sbin/ser[6636]: parse_headers: this is the first via Aug 30 10:01:08 iptel1 /usr/sbin/ser[6635]: DEBUG: t_on_reply: org. status uas=100, uac[0]=0 local=0 is_invite=1) Aug 30 10:01:08 iptel1 /usr/sbin/ser[6633]: end of header reached, state=5 Aug 30 10:01:08 iptel1 /usr/sbin/ser[6634]: parse_headers: flags=60 Aug 30 10:01:08 iptel1 /usr/sbin/ser[6636]: After parse_msg... Aug 30 10:01:08 iptel1 /usr/sbin/ser[6635]: ->>>>>>>>> T_code=100, new_code=100 Aug 30 10:01:08 iptel1 /usr/sbin/ser[6633]: parse_headers: Via found, flags=1 Aug 30 10:01:08 iptel1 /usr/sbin/ser[6634]: t_lookup_request: start searching: hash=52551, isACK=0 Aug 30 10:01:08 iptel1 /usr/sbin/ser[6636]: forward_reply: found module tm, passing reply to it Aug 30 10:01:08 iptel1 /usr/sbin/ser[6635]: DEBUG: relay_reply: branch=0, save=0, relay=-1 Aug 30 10:01:08 iptel1 /usr/sbin/ser[6633]: parse_headers: this is the first via Aug 30 10:01:08 iptel1 /usr/sbin/ser[6634]: DEBUG: proceeding to pre-RFC3261 transaction matching Aug 30 10:01:08 iptel1 /usr/sbin/ser[6636]: DEBUG: t_check: msg id=1 global id=0 T start=0xffffffff Aug 30 10:01:08 iptel1 /usr/sbin/ser[6635]: DEBUG: add_to_tail_of_timer[1]: 0x422b8338 Aug 30 10:01:08 iptel1 /usr/sbin/ser[6633]: After parse_msg... Aug 30 10:01:08 iptel1 /usr/sbin/ser[6634]: DEBUG: non-ACK matched Aug 30 10:01:08 iptel1 /usr/sbin/ser[6636]: parse_headers: flags=17 Aug 30 10:01:08 iptel1 /usr/sbin/ser[6633]: forward_reply: found module tm, passing reply to it Aug 30 10:01:08 iptel1 /usr/sbin/ser[6634]: DEBUG: t_lookup_request: transaction found (T=0x422b8200) Aug 30 10:01:08 iptel1 /usr/sbin/ser[6635]: receive_msg: cleaning up Aug 30 10:01:08 iptel1 /usr/sbin/ser[6636]: DEBUG: add_param: tag=EF306448-1A25 Aug 30 10:01:08 iptel1 /usr/sbin/ser[6633]: DEBUG: t_check: msg id=2 global id=1 T start=0xffffffff Aug 30 10:01:08 iptel1 /usr/sbin/ser[6634]: DEBUG: reply retransmitted. buf=0x4005a9a0: SIP/2.0 1..., shmem=0x422b9b78: SIP/2.0 1 Aug 30 10:01:08 iptel1 /usr/sbin/ser[6635]: SIP Reply (status): Aug 30 10:01:08 iptel1 /usr/sbin/ser[6636]: end of header reached, state=29 Aug 30 10:01:08 iptel1 /usr/sbin/ser[6633]: parse_headers: flags=17 Aug 30 10:01:08 iptel1 /usr/sbin/ser[6634]: receive_msg: cleaning up Aug 30 10:01:08 iptel1 /usr/sbin/ser[6635]: version: <SIP/2.0> Aug 30 10:01:08 iptel1 /usr/sbin/ser[6636]: DEBUG: get_hdr_field: <To> [50]; uri=[sip:3614032804@voip.hbone.hu] Aug 30 10:01:08 iptel1 /usr/sbin/ser[6633]: DEBUG: add_param: tag=EF306448-1A25 Aug 30 10:01:08 iptel1 /usr/sbin/ser[6638]: DEBUG: timer routine:5,tl=0x422b8324 next=(nil) Aug 30 10:01:08 iptel1 /usr/sbin/ser[6635]: status: <183> Aug 30 10:01:08 iptel1 /usr/sbin/ser[6636]: DEBUG: to body [<sip:3614032804@voip.hbone.hu>] Aug 30 10:01:08 iptel1 /usr/sbin/ser[6633]: end of header reached, state=29 Aug 30 10:01:08 iptel1 /usr/sbin/ser[6635]: reason: <Session Progress> Aug 30 10:01:08 iptel1 /usr/sbin/ser[6636]: get_hdr_field: cseq <CSeq>: <101> <INVITE> Aug 30 10:01:08 iptel1 /usr/sbin/ser[6634]: SIP Reply (status): Aug 30 10:01:08 iptel1 /usr/sbin/ser[6633]: DEBUG: get_hdr_field: <To> [50]; uri=[sip:3614032804@voip.hbone.hu] Aug 30 10:01:08 iptel1 /usr/sbin/ser[6635]: parse_headers: flags=1 Aug 30 10:01:08 iptel1 /usr/sbin/ser[6636]: parse_headers: flags=4 Aug 30 10:01:08 iptel1 /usr/sbin/ser[6634]: version: <SIP/2.0> Aug 30 10:01:08 iptel1 /usr/sbin/ser[6633]: DEBUG: to body [<sip:3614032804@voip.hbone.hu>] Aug 30 10:01:08 iptel1 /usr/sbin/ser[6635]: Found param type 232, <branch> = <z9hG4bK74dc.40904841.0>; state=9 Aug 30 10:01:08 iptel1 /usr/sbin/ser[6636]: DEBUG: t_reply_matching: hash 52551 label 344197380 branch 0 Aug 30 10:01:08 iptel1 /usr/sbin/ser[6634]: status: <183> Aug 30 10:01:08 iptel1 /usr/sbin/ser[6633]: get_hdr_field: cseq <CSeq>: <101> <INVITE> Aug 30 10:01:08 iptel1 /usr/sbin/ser[6635]: parse_via: next_via Aug 30 10:01:08 iptel1 /usr/sbin/ser[6636]: DEBUG: t_reply_matching: reply matched (T=0x422b8200)! Aug 30 10:01:08 iptel1 /usr/sbin/ser[6634]: reason: <Session Progress> Aug 30 10:01:09 iptel1 /usr/sbin/ser[6633]: parse_headers: flags=4 Aug 30 10:01:09 iptel1 /usr/sbin/ser[6635]: end of header reached, state=5 Aug 30 10:01:09 iptel1 /usr/sbin/ser[6636]: DEBUG: t_check: msg id=1 global id=1 T end=0x422b8200 Aug 30 10:01:09 iptel1 /usr/sbin/ser[6634]: parse_headers: flags=1 Aug 30 10:01:09 iptel1 /usr/sbin/ser[6633]: DEBUG: t_reply_matching: hash 52551 label 344197380 branch 0 Aug 30 10:01:09 iptel1 /usr/sbin/ser[6635]: parse_headers: Via found, flags=1 Aug 30 10:01:09 iptel1 /usr/sbin/ser[6636]: DEBUG: t_on_reply: org. status uas=100, uac[0]=100 local=0 is_invite=1) Aug 30 10:01:09 iptel1 /usr/sbin/ser[6634]: Found param type 232, <branch> = <z9hG4bK74dc.40904841.0>; state=9 Aug 30 10:01:09 iptel1 /usr/sbin/ser[6633]: DEBUG: t_reply_matching: reply matched (T=0x422b8200)! Aug 30 10:01:09 iptel1 /usr/sbin/ser[6635]: parse_headers: this is the first via Aug 30 10:01:09 iptel1 /usr/sbin/ser[6636]: ->>>>>>>>> T_code=100, new_code=183 Aug 30 10:01:09 iptel1 /usr/sbin/ser[6634]: parse_via: next_via Aug 30 10:01:09 iptel1 /usr/sbin/ser[6633]: DEBUG: t_check: msg id=2 global id=2 T end=0x422b8200 Aug 30 10:01:09 iptel1 /usr/sbin/ser[6635]: After parse_msg... Aug 30 10:01:09 iptel1 /usr/sbin/ser[6636]: DEBUG: relay_reply: branch=0, save=0, relay=0 Aug 30 10:01:09 iptel1 /usr/sbin/ser[6634]: end of header reached, state=5 Aug 30 10:01:09 iptel1 /usr/sbin/ser[6633]: DEBUG: t_on_reply: org. status uas=100, uac[0]=183 local=0 is_invite=1) Aug 30 10:01:09 iptel1 /usr/sbin/ser[6635]: forward_reply: found module tm, passing reply to it Aug 30 10:01:09 iptel1 /usr/sbin/ser[6636]: old size: 999, new size: 937 Aug 30 10:01:09 iptel1 /usr/sbin/ser[6634]: parse_headers: Via found, flags=1 Aug 30 10:01:09 iptel1 /usr/sbin/ser[6635]: DEBUG: t_check: msg id=2 global id=1 T start=0xffffffff Aug 30 10:01:09 iptel1 /usr/sbin/ser[6636]: build_res_from_sip_res: copied size: orig:97, new: 35, rest: 902 msg= SIP/2.0 183 Session Progress^M Via: SIP/2.0/UDP 195.111.104.182:5060^M From: <sip:043*3612252800@195.111.104.182>;tag=C54B2498-15D5^M To: <sip:3614032804@voip.hbone.hu>;tag=EF306448-1A25^M Date: Mon, 30 Aug 2004 08:01:06 GMT^M Call-ID: 94136467-F99111D8-AD83FF3F-C1E8A16C(a)195.111.104.182^M Timestamp: 1093852865^M Server: Cisco-SIPGateway/IOS-12.x^M CSeq: 101 INVITE^M Require: 100rel^M RSeq: 8889^M Allow-Events: telephone-event^M Contact: <sip:1514*3614032804@195.111.104.8:5060>^M Record-Route: <sip:3614032804@193.225.14.136:5070;ftag=C54B2498-15D5;lr=on>^M Content-Type: application/sdp^M Content-Disposition: session;handling=required^M Content-Length: 286^M ^M v=0^M o=CiscoSystemsSIP-GW-UserAgent 6428 271 IN IP4 195.111.104.8^M s=SIP Call^M c=IN IP4 195.111.104.8^M t=0 0^M m=audio 17592 RTP/AVP 8 101 100^M c=IN IP4 195.111.104.8^M a=rtpmap:8 PCMA/8000^M a=rtpmap:101 telephone-event/8000^M a=fmtp:101 0 Aug 30 10:01:09 iptel1 /usr/sbin/ser[6634]: parse_headers: this is the first via Aug 30 10:01:09 iptel1 /usr/sbin/ser[6635]: parse_headers: flags=17 Aug 30 10:01:09 iptel1 /usr/sbin/ser[6636]: DEBUG: reply relayed. buf=0x80d5310: SIP/2.0 1..., shmem=0x422ba380: SIP/2.0 1 Aug 30 10:01:09 iptel1 /usr/sbin/ser[6633]: ->>>>>>>>> T_code=183, new_code=183 Aug 30 10:01:09 iptel1 /usr/sbin/ser[6634]: After parse_msg... Aug 30 10:01:09 iptel1 /usr/sbin/ser[6635]: DEBUG: add_param: tag=EF306448-1A25 Aug 30 10:01:09 iptel1 /usr/sbin/ser[6636]: DEBUG: add_to_tail_of_timer[1]: 0x422b8338 Aug 30 10:01:09 iptel1 /usr/sbin/ser[6633]: DEBUG: relay_reply: branch=0, save=0, relay=0 Aug 30 10:01:09 iptel1 /usr/sbin/ser[6634]: forward_reply: found module tm, passing reply to it Aug 30 10:01:09 iptel1 /usr/sbin/ser[6635]: end of header reached, state=29 Aug 30 10:01:09 iptel1 /usr/sbin/ser[6636]: receive_msg: cleaning up Aug 30 10:01:09 iptel1 /usr/sbin/ser[6633]: old size: 999, new size: 937 Aug 30 10:01:09 iptel1 /usr/sbin/ser[6634]: DEBUG: t_check: msg id=2 global id=1 T start=0xffffffff Aug 30 10:01:09 iptel1 /usr/sbin/ser[6635]: DEBUG: get_hdr_field: <To> [50]; uri=[sip:3614032804@voip.hbone.hu] Aug 30 10:01:09 iptel1 /usr/sbin/ser[6636]: SIP Request: Aug 30 10:01:09 iptel1 /usr/sbin/ser[6633]: build_res_from_sip_res: copied size: orig:97, new: 35, rest: 902 msg= SIP/2.0 183 Session Progress^M Via: SIP/2.0/UDP 195.111.104.182:5060^M From: <sip:043*3612252800@195.111.104.182>;tag=C54B2498-15D5^M To: <sip:3614032804@voip.hbone.hu>;tag=EF306448-1A25^M Date: Mon, 30 Aug 2004 08:01:07 GMT^M Call-ID: 94136467-F99111D8-AD83FF3F-C1E8A16C(a)195.111.104.182^M Timestamp: 1093852865^M Server: Cisco-SIPGateway/IOS-12.x^M CSeq: 101 INVITE^M Require: 100rel^M RSeq: 8889^M Allow-Events: telephone-event^M Contact: <sip:1514*3614032804@195.111.104.8:5060>^M Record-Route: <sip:3614032804@193.225.14.136:5070;ftag=C54B2498-15D5;lr=on>^M Content-Type: application/sdp^M Content-Disposition: session;handling=required^M Content-Length: 286^M ^M v=0^M o=CiscoSystemsSIP-GW-UserAgent 6428 271 IN IP4 195.111.104.8^M s=SIP Call^M c=IN IP4 195.111.104.8^M t=0 0^M m=audio 17592 RTP/AVP 8 101 100^M c=IN IP4 195.111.104.8^M a=rtpmap:8 PCMA/8000^M a=rtpmap:101 telephone-event/8000^M a=fmtp:101 0 Aug 30 10:01:09 iptel1 /usr/sbin/ser[6634]: parse_headers: flags=17 Aug 30 10:01:09 iptel1 sshd(pam_unix)[6466]: session opened for user szesz by (uid=0) Aug 30 10:01:09 iptel1 /usr/sbin/ser[6635]: DEBUG: to body [<sip:3614032804@voip.hbone.hu>] Aug 30 10:01:09 iptel1 /usr/sbin/ser[6636]: method: <PRACK> Aug 30 10:01:09 iptel1 /usr/sbin/ser[6633]: DEBUG: reply relayed. buf=0x80d5bd0: SIP/2.0 1..., shmem=0x422ba380: SIP/2.0 1 Aug 30 10:01:09 iptel1 /usr/sbin/ser[6634]: DEBUG: add_param: tag=EF306448-1A25 Aug 30 10:01:09 iptel1 /usr/sbin/ser[6635]: get_hdr_field: cseq <CSeq>: <101> <INVITE> Aug 30 10:01:10 iptel1 /usr/sbin/ser[6636]: uri: <sip:3614032804@193.225.14.136:5070;ftag=C54B2498-15D5;lr=on> Aug 30 10:01:10 iptel1 /usr/sbin/ser[6633]: DEBUG: add_to_tail_of_timer[1]: 0x422b8338 Aug 30 10:01:10 iptel1 /usr/sbin/ser[6634]: end of header reached, state=29 Aug 30 10:01:10 iptel1 /usr/sbin/ser[6635]: parse_headers: flags=4 Aug 30 10:01:10 iptel1 /usr/sbin/ser[6636]: version: <SIP/2.0> Aug 30 10:01:10 iptel1 /usr/sbin/ser[6633]: receive_msg: cleaning up Aug 30 10:01:10 iptel1 /usr/sbin/ser[6634]: DEBUG: get_hdr_field: <To> [50]; uri=[sip:3614032804@voip.hbone.hu] Aug 30 10:01:10 iptel1 /usr/sbin/ser[6635]: DEBUG: t_reply_matching: hash 52551 label 344197380 branch 0 Aug 30 10:01:10 iptel1 /usr/sbin/ser[6636]: parse_headers: flags=1 Aug 30 10:01:10 iptel1 /usr/sbin/ser[6633]: SIP Request: Aug 30 10:01:10 iptel1 /usr/sbin/ser[6634]: DEBUG: to body [<sip:3614032804@voip.hbone.hu>] Aug 30 10:01:10 iptel1 /usr/sbin/ser[6635]: DEBUG: t_reply_matching: reply matched (T=0x422b8200)! Aug 30 10:01:10 iptel1 /usr/sbin/ser[6636]: end of header reached, state=5 Aug 30 10:01:10 iptel1 /usr/sbin/ser[6633]: method: <PRACK> Aug 30 10:01:10 iptel1 /usr/sbin/ser[6634]: get_hdr_field: cseq <CSeq>: <101> <INVITE> Aug 30 10:01:10 iptel1 /usr/sbin/ser[6635]: DEBUG: t_check: msg id=2 global id=2 T end=0x422b8200 Aug 30 10:01:10 iptel1 /usr/sbin/ser[6636]: parse_headers: Via found, flags=1 Aug 30 10:01:10 iptel1 /usr/sbin/ser[6633]: uri: <sip:3614032804@193.225.14.136:5070;ftag=C54B2498-15D5;lr=on> Aug 30 10:01:10 iptel1 /usr/sbin/ser[6634]: parse_headers: flags=4 Aug 30 10:01:10 iptel1 /usr/sbin/ser[6635]: DEBUG: t_on_reply: org. status uas=183, uac[0]=183 local=0 is_invite=1) Aug 30 10:01:10 iptel1 /usr/sbin/ser[6636]: parse_headers: this is the first via Aug 30 10:01:10 iptel1 /usr/sbin/ser[6633]: version: <SIP/2.0> Aug 30 10:01:10 iptel1 /usr/sbin/ser[6634]: DEBUG: t_reply_matching: hash 52551 label 344197380 branch 0 Aug 30 10:01:10 iptel1 /usr/sbin/ser[6635]: ->>>>>>>>> T_code=183, new_code=183 Aug 30 10:01:10 iptel1 /usr/sbin/ser[6636]: After parse_msg... Aug 30 10:01:10 iptel1 /usr/sbin/ser[6633]: parse_headers: flags=1 Aug 30 10:01:10 iptel1 /usr/sbin/ser[6634]: DEBUG: t_reply_matching: reply matched (T=0x422b8200)! Aug 30 10:01:10 iptel1 /usr/sbin/ser[6635]: DEBUG: relay_reply: branch=0, save=0, relay=0 Aug 30 10:01:10 iptel1 /usr/sbin/ser[6636]: preparing to run routing scripts... Aug 30 10:01:10 iptel1 /usr/sbin/ser[6633]: end of header reached, state=5 Aug 30 10:01:10 iptel1 /usr/sbin/ser[6634]: DEBUG: t_check: msg id=2 global id=2 T end=0x422b8200 Aug 30 10:01:10 iptel1 /usr/sbin/ser[6635]: old size: 999, new size: 937 Aug 30 10:01:10 iptel1 /usr/sbin/ser[6636]: DEBUG : is_maxfwd_present: searching for max_forwards header Aug 30 10:01:10 iptel1 /usr/sbin/ser[6633]: parse_headers: Via found, flags=1 Aug 30 10:01:10 iptel1 /usr/sbin/ser[6634]: DEBUG: t_on_reply: org. status uas=183, uac[0]=183 local=0 is_invite=1) Aug 30 10:01:10 iptel1 /usr/sbin/ser[6635]: build_res_from_sip_res: copied size: orig:97, new: 35, rest: 902 msg= SIP/2.0 183 Session Progress^M Via: SIP/2.0/UDP 195.111.104.182:5060^M From: <sip:043*3612252800@195.111.104.182>;tag=C54B2498-15D5^M To: <sip:3614032804@voip.hbone.hu>;tag=EF306448-1A25^M Date: Mon, 30 Aug 2004 08:01:07 GMT^M Call-ID: 94136467-F99111D8-AD83FF3F-C1E8A16C(a)195.111.104.182^M Timestamp: 1093852865^M Server: Cisco-SIPGateway/IOS-12.x^M CSeq: 101 INVITE^M Require: 100rel^M RSeq: 8889^M Allow-Events: telephone-event^M Contact: <sip:1514*3614032804@195.111.104.8:5060>^M Record-Route: <sip:3614032804@193.225.14.136:5070;ftag=C54B2498-15D5;lr=on>^M Content-Type: application/sdp^M Content-Disposition: session;handling=required^M Content-Length: 286^M ^M v=0^M o=CiscoSystemsSIP-GW-UserAgent 6428 271 IN IP4 195.111.104.8^M s=SIP Call^M c=IN IP4 195.111.104.8^M t=0 0^M m=audio 17592 RTP/AVP 8 101 100^M c=IN IP4 195.111.104.8^M a=rtpmap:8 PCMA/8000^M a=rtpmap:101 telephone-event/8000^M a=fmtp:101 0 Aug 30 10:01:10 iptel1 /usr/sbin/ser[6636]: parse_headers: flags=128 Aug 30 10:01:10 iptel1 /usr/sbin/ser[6633]: parse_headers: this is the first via Aug 30 10:01:10 iptel1 /usr/sbin/ser[6635]: DEBUG: reply relayed. buf=0x80d5310: SIP/2.0 1..., shmem=0x422ba380: SIP/2.0 1 Aug 30 10:01:10 iptel1 /usr/sbin/ser[6634]: ->>>>>>>>> T_code=183, new_code=183 Aug 30 10:01:10 iptel1 /usr/sbin/ser[6636]: DEBUG: add_param: tag=EF306448-1A25 Aug 30 10:01:10 iptel1 /usr/sbin/ser[6633]: After parse_msg... Aug 30 10:01:10 iptel1 /usr/sbin/ser[6635]: DEBUG: add_to_tail_of_timer[1]: 0x422b8338 Aug 30 10:01:10 iptel1 /usr/sbin/ser[6634]: DEBUG: relay_reply: branch=0, save=0, relay=0 Aug 30 10:01:10 iptel1 /usr/sbin/ser[6636]: end of header reached, state=29 Aug 30 10:01:10 iptel1 /usr/sbin/ser[6633]: preparing to run routing scripts... Aug 30 10:01:10 iptel1 /usr/sbin/ser[6635]: receive_msg: cleaning up Aug 30 10:01:11 iptel1 /usr/sbin/ser[6634]: old size: 999, new size: 937 Aug 30 10:01:11 iptel1 /usr/sbin/ser[6636]: DEBUG: get_hdr_field: <To> [50]; uri=[sip:3614032804@voip.hbone.hu] Aug 30 10:01:11 iptel1 /usr/sbin/ser[6633]: DEBUG : is_maxfwd_present: searching for max_forwards header Aug 30 10:01:11 iptel1 /usr/sbin/ser[6635]: SIP Request: Aug 30 10:01:11 iptel1 /usr/sbin/ser[6634]: build_res_from_sip_res: copied size: orig:97, new: 35, rest: 902 msg= SIP/2.0 183 Session Progress^M Via: SIP/2.0/UDP 195.111.104.182:5060^M From: <sip:043*3612252800@195.111.104.182>;tag=C54B2498-15D5^M To: <sip:3614032804@voip.hbone.hu>;tag=EF306448-1A25^M Date: Mon, 30 Aug 2004 08:01:07 GMT^M Call-ID: 94136467-F99111D8-AD83FF3F-C1E8A16C(a)195.111.104.182^M Timestamp: 1093852865^M Server: Cisco-SIPGateway/IOS-12.x^M CSeq: 101 INVITE^M Require: 100rel^M RSeq: 8889^M Allow-Events: telephone-event^M Contact: <sip:1514*3614032804@195.111.104.8:5060>^M Record-Route: <sip:3614032804@193.225.14.136:5070;ftag=C54B2498-15D5;lr=on>^M Content-Type: application/sdp^M Content-Disposition: session;handling=required^M Content-Length: 286^M ^M v=0^M o=CiscoSystemsSIP-GW-UserAgent 6428 271 IN IP4 195.111.104.8^M s=SIP Call^M c=IN IP4 195.111.104.8^M t=0 0^M m=audio 17592 RTP/AVP 8 101 100^M c=IN IP4 195.111.104.8^M a=rtpmap:8 PCMA/8000^M a=rtpmap:101 telephone-event/8000^M a=fmtp:101 0 Aug 30 10:01:11 iptel1 /usr/sbin/ser[6636]: DEBUG: to body [<sip:3614032804@voip.hbone.hu>] Aug 30 10:01:11 iptel1 /usr/sbin/ser[6633]: parse_headers: flags=128 Aug 30 10:01:11 iptel1 /usr/sbin/ser[6635]: method: <INVITE> Aug 30 10:01:11 iptel1 /usr/sbin/ser[6634]: DEBUG: reply relayed. buf=0x80d5bd0: SIP/2.0 1..., shmem=0x422ba380: SIP/2.0 1 Aug 30 10:01:11 iptel1 /usr/sbin/ser[6636]: get_hdr_field: cseq <CSeq>: <102> <PRACK> Aug 30 10:01:11 iptel1 /usr/sbin/ser[6633]: DEBUG: add_param: tag=EF306448-1A25 Aug 30 10:01:11 iptel1 /usr/sbin/ser[6635]: uri: <sip:3621408507@voip.hbone.hu:5070> Aug 30 10:01:11 iptel1 /usr/sbin/ser[6634]: DEBUG: add_to_tail_of_timer[1]: 0x422b8338 Aug 30 10:01:11 iptel1 /usr/sbin/ser[6636]: DEBUG: get_hdr_body : content_length=0 Aug 30 10:01:11 iptel1 /usr/sbin/ser[6633]: end of header reached, state=29 Aug 30 10:01:11 iptel1 /usr/sbin/ser[6635]: version: <SIP/2.0> Aug 30 10:01:11 iptel1 /usr/sbin/ser[6634]: receive_msg: cleaning up Aug 30 10:01:11 iptel1 /usr/sbin/ser[6636]: found end of header Aug 30 10:01:11 iptel1 /usr/sbin/ser[6633]: DEBUG: get_hdr_field: <To> [50]; uri=[sip:3614032804@voip.hbone.hu] Aug 30 10:01:11 iptel1 /usr/sbin/ser[6635]: parse_headers: flags=1 Aug 30 10:01:11 iptel1 /usr/sbin/ser[6634]: SIP Reply (status): Aug 30 10:01:11 iptel1 /usr/sbin/ser[6636]: DEBUG: is_maxfwd_present: max_forwards header not found! Aug 30 10:01:11 iptel1 /usr/sbin/ser[6633]: DEBUG: to body [<sip:3614032804@voip.hbone.hu>]

Hello Jan! On 08/31/04 14:56, Jan Janak wrote: No, it kept on running. Yes, debugging is set to 7 and the /var/log/messages file is 130 Mb (rotated daily). I will set debugging to 3 and wait some days. We use nagios to control the service which measures the response time. Usually it is about 70 msec, let us see if any alert messeage occures. The file exists, but I think that SER rewrites it. It does not matter in case of this. Thank you, Miklos -- --------------------------------------------------------------------------- Miklos Tirpak Computer and Automation Research Institute e-mail : mtirpak(a)sztaki.hu Hungarian Academy of Sciences phone : (361) 279-6011 H-1132 Budapest, Victor Hugo u 18-22 fax : (361) 279-6021

On 08/31/04 22:22, Jiri Kuthan wrote: I have found it on this mailing list, it is written by Alex Mayrhofer and based on sipsak. My colleagues have modified some part in it, and I have written an UA which simply responds 200 OK to every request. We use this way: Nagios plugin (OPTIONS) -> SER -> UA (OK) -> SER -> Nagios Miklos

Hello! On 09/01/04 10:41, Alexander Mayrhofer wrote: Of course. I do not find the original to make a patch file, so I attach the modified version. Miklos #! /usr/bin/perl -wT # # check_ser: Nagios plugin for checking SER using sipsak. # Alex Mayrhofer, nic.at <axelm at nic.at> # no warranty, licensed GPL2 use lib "/usr/lib/nagios/plugins"; use vars qw($opt_H $opt_w $opt_c $opt_U $opt_r $param $PROGNAME $warning $url $host $password $runtimedir $remoteport); BEGIN { if ($0 =~ m/^(.*?)[\/\\]([^\/\\]+)$/) { $runtimedir = $1; $PROGNAME = $2; } } use Getopt::Long; use lib $main::runtimedir; use utils qw(%ERRORS &print_revision &support &usage); use strict; sub print_help (); sub print_usage (); delete @ENV{'PATH', 'IFS', 'CDPATH', 'ENV', 'BASH_ENV'}; Getopt::Long::Configure('bundling', 'no_ignore_case'); GetOptions ("V|version" => \&version, "h|help" => \&help, "w|warning=s" => \$opt_w, "c|critical=s" => \$opt_c, "H|hostname=s" => \$opt_H, "U|url=s" => \$opt_U, "r|remoteport=s" => \$opt_r); ($opt_r) || ($opt_r = shift); $remoteport = $1 if ($opt_r =~ /([0-9]+)$/); ($remoteport) || usage("Invalid remote port: $opt_r\n"); ($opt_U) || ($opt_U = shift) || usage("URL not specified\n"); # TODO: allow port and parameters? my $url = $1 if ($opt_U =~ m/^(sip:[a-zA-Z0-9\+\*#]+\@[a-zA-Z0-9\.]+)$/); ($url) || usage("Invalid URL: $opt_U\n"); ($opt_H) || ($opt_H = shift); $host = $1 if ($opt_H =~ /^([a-z\.0-9]+)$/); ($host) || usage("Invalid host: $opt_H\n"); ($opt_w) || ($opt_w = shift) || usage("Warning threshold not specified\n"); $warning = $1 if ($opt_w =~ /([0-9]+)$/); ($warning) || usage("Invalid warning threshold: $opt_w\n"); ($opt_c) || ($opt_c = shift) || usage("Critical threshold not specified\n"); my $critical = $1 if ($opt_c =~ /([0-9]+)$/); ($critical) || usage("Invalid critical threshold: $opt_c\n"); my $sig=0; my $param=0; $param = ($host) ? "-H $host -s $url -r $remoteport -vv" : "-s $url -vv"; $sig = `/usr/bin/sipsak $param`; if ($sig !~ m/SIP\/2\.0 200 OK/) { print "SIP CRITICAL - missing 200 reply\n"; exit $ERRORS{'CRITICAL'}; } if ($sig !~ m/reply received after ([0-9\.]+) ms/) { if ($sig !~ m/reply received ([0-9\.]+) ms after first send/) { print "SIP CRITICAL - missing duration value\n"; exit $ERRORS{'CRITICAL'}; } else { $sig = $1; } } else { $sig = $1; } if ($sig>$critical) { print "SIP CRITICAL - Test Duration: $sig ms\n"; exit $ERRORS{'CRITICAL'}; } if ($sig>$warning) { print "SIP WARNING - Test Duration: $sig ms\n"; exit $ERRORS{'WARNING'} } print "SIP OK - Test Duration: $sig ms\n"; exit $ERRORS{'OK'}; sub print_usage () { print "Usage: $PROGNAME -H <host> -U <sip url> -w <warn> -c <crit>\n"; } sub print_help () { print_revision($PROGNAME,'version 0.1'); print "Copyright (c) 2003 nic.at (Alexander Mayrhofer) This plugin tests a SIP eXpress Router. "; print_usage(); print " -U, --url=STRING SIP URL to use for check -w, --warning=INTEGER response time in ms above which a WARNING status will result -c, --critical=INTEGER response time in ms above which a CRITICAL status will result "; support(); } sub version () { print_revision($PROGNAME,'Version: 0.1'); exit $ERRORS{'OK'}; } sub help () { print_help(); exit $ERRORS{'OK'}; }