Re: [Serusers] SER becomes very slow

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>]