[Kamailio-Users] openser - asterisk - methods field location table

Matteo Piazza matteo.piazza at trentinonetwork.it
Thu Nov 20 11:29:41 CET 2008


Hi,

Now I can compile the modules but openser crash at the start.
As attachment the output of the log message with debug=6.

Cheers,

Matteo



Il giorno lun, 17/11/2008 alle 14.53 +0100, Henning Westerholt ha
scritto:
> On Monday 17 November 2008, Matteo Piazza wrote:
> > I have an error during the compilation of the module. See the text
> > below. As attachment the two files after the application of the pacth
> 
> Hi Matteo,
> 
> sorry, there happened some variables renaming between the releases that caused 
> this problems. I've added a fixed patch.
> 
> Cheers,
> 
> Henning
-- 
====================================
Matteo Piazza
Trentino Network s.r.l. a Socio Unico
matteo.piazza at trentinonetwork.it
Via Gilli, 2 - 38100 TRENTO
Tel (+39) 0461.020224
==================================== 
-------------- next part --------------
Script started on gio 20 nov 2008 11:16:27 CET
openser2:/etc/openser# shutdown -h nowcat /proc/drbd mv asterisk asterisk.orgcd /etc/echo "ztdummy" >> /etc/modules
openser2:/etc/openser# make samplesclearmake && make installclearmake samplesecho "ztdummy" >> /etc/modules
openser2:/etc/openser# cd /etc/mv asterisk asterisk.orgcat /proc/drbd shutdown -h nowshutdown -h nowcat /proc/drbd mv asterisk asterisk.orgcat /proc/drbd shutdown -h now/etc/init.d/opesenser start
Starting openser: openserNov 20 11:16:44 [5162] DBG:core:yyparse: loading module //lib/openser/modules/postgres.so
Nov 20 11:16:44 [5162] DBG:core:yyparse: loading module //lib/openser/modules/sl.so
Nov 20 11:16:44 [5162] DBG:core:yyparse: loading module //lib/openser/modules/tm.so
Nov 20 11:16:44 [5162] DBG:core:register_module: register_pv: tm
Nov 20 11:16:44 [5162] DBG:core:pv_add_extra: extra items list is not initialized
Nov 20 11:16:44 [5162] DBG:core:yyparse: loading module //lib/openser/modules/rr.so
Nov 20 11:16:44 [5162] DBG:core:yyparse: loading module //lib/openser/modules/maxfwd.so
Nov 20 11:16:44 [5162] DBG:core:yyparse: loading module //lib/openser/modules/usrloc.so
Nov 20 11:16:44 [5162] DBG:core:yyparse: loading module //lib/openser/modules/registrar.so
Nov 20 11:16:44 [5162] DBG:core:yyparse: loading module //lib/openser/modules/textops.so
Nov 20 11:16:44 [5162] DBG:core:yyparse: loading module //lib/openser/modules/mi_fifo.so
Nov 20 11:16:44 [5162] DBG:core:yyparse: loading module //lib/openser/modules/uri_db.so
Nov 20 11:16:44 [5162] DBG:core:yyparse: loading module //lib/openser/modules/uri.so
Nov 20 11:16:44 [5162] DBG:core:yyparse: loading module //lib/openser/modules/xlog.so
Nov 20 11:16:44 [5162] DBG:core:register_module: register_pv: xlog
Nov 20 11:16:44 [5162] DBG:core:yyparse: loading module //lib/openser/modules/acc.so
Nov 20 11:16:44 [5162] DBG:core:yyparse: loading module //lib/openser/modules/auth.so
Nov 20 11:16:44 [5162] DBG:core:yyparse: loading module //lib/openser/modules/auth_db.so
Nov 20 11:16:44 [5162] DBG:core:yyparse: loading module //lib/openser/modules/alias_db.so
Nov 20 11:16:44 [5162] DBG:core:yyparse: loading module //lib/openser/modules/domain.so
Nov 20 11:16:44 [5162] DBG:core:yyparse: loading module //lib/openser/modules/lcr.so
Nov 20 11:16:44 [5162] DBG:core:yyparse: loading module //lib/openser/modules/avpops.so
Nov 20 11:16:44 [5162] DBG:core:yyparse: loading module //lib/openser/modules/pdt.so
Nov 20 11:16:44 [5162] DBG:core:yyparse: loading module //lib/openser/modules/permissions.so
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: mi_fifo matches module mi_fifo
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <fifo_name> in module mi_fifo [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: rr matches module rr
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <enable_full_lr> in module rr [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: rr matches module rr
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <append_fromtag> in module rr [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: registrar matches module registrar
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <method_filtering> in module registrar [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: uri_db matches module uri_db
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <use_uri_table> in module uri_db [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: uri_db matches module uri_db
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <db_url> in module uri_db [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: acc matches module acc
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <db_url> in module acc [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: acc matches module acc
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <early_media> in module acc [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: acc matches module acc
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <report_ack> in module acc [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: acc matches module acc
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <report_cancels> in module acc [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: acc matches module acc
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <detect_direction> in module acc [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: acc matches module acc
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <failed_transaction_flag> in module acc [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: acc matches module acc
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <log_flag> in module acc [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: acc matches module acc
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <log_missed_flag> in module acc [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: acc matches module acc
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <db_flag> in module acc [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: acc matches module acc
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <db_missed_flag> in module acc [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: acc matches module acc
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <db_extra> in module acc [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: usrloc matches module usrloc
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <db_mode> in module usrloc [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: usrloc matches module usrloc
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <db_url> in module usrloc [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: auth_db matches module auth_db
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <calculate_ha1> in module auth_db [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: auth_db matches module auth_db
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <password_column> in module auth_db [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: auth_db matches module auth_db
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <db_url> in module auth_db [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: auth_db matches module auth_db
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <load_credentials> in module auth_db [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: alias_db matches module alias_db
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <db_url> in module alias_db [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: domain matches module domain
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <db_url> in module domain [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: domain matches module domain
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <db_mode> in module domain [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: alias_db|auth_db|usrloc|uri_db matches module alias_db
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <use_domain> in module alias_db [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: alias_db|auth_db|usrloc|uri_db matches module auth_db
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <use_domain> in module auth_db [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: alias_db|auth_db|usrloc|uri_db matches module uri_db
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <use_domain> in module uri_db [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: alias_db|auth_db|usrloc|uri_db matches module usrloc
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <use_domain> in module usrloc [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: avpops matches module avpops
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <avp_url> in module avpops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: avpops matches module avpops
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <avp_table> in module avpops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: avpops matches module avpops
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <avp_table> in module avpops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: avpops matches module avpops
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <use_domain> in module avpops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: avpops matches module avpops
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <uuid_column> in module avpops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: avpops matches module avpops
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <username_column> in module avpops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: avpops matches module avpops
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <domain_column> in module avpops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: avpops matches module avpops
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <attribute_column> in module avpops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: avpops matches module avpops
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <value_column> in module avpops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: avpops matches module avpops
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <type_column> in module avpops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: lcr matches module lcr
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <db_url> in module lcr [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: lcr matches module lcr
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <fr_inv_timer> in module lcr [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: lcr matches module lcr
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <fr_inv_timer_next> in module lcr [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: lcr matches module lcr
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <dm_flag> in module lcr [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: lcr matches module lcr
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <ruri_user_avp> in module lcr [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: ^auth$|lcr matches module lcr
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <rpid_avp> in module lcr [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: ^auth$|lcr matches module auth
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <rpid_avp> in module auth [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: lcr matches module lcr
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <gw_uri_avp> in module lcr [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: lcr|tm matches module lcr
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <fr_inv_timer_avp> in module lcr [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: lcr|tm matches module tm
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <fr_inv_timer_avp> in module tm [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: lcr matches module lcr
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <contact_avp> in module lcr [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: pdt matches module pdt
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <db_url> in module pdt [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: pdt matches module pdt
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <db_table> in module pdt [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: pdt matches module pdt
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <domain_column> in module pdt [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: pdt matches module pdt
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <prefix_column> in module pdt [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: pdt matches module pdt
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <domain_column> in module pdt [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: permissions matches module permissions
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <db_url> in module permissions [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: permissions matches module permissions
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <db_mode> in module permissions [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: permissions matches module permissions
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <trusted_table> in module permissions [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: permissions matches module permissions
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <allow_suffix> in module permissions [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: permissions matches module permissions
Nov 20 11:16:44 [5162] DBG:core:set_mod_param_regex: found <deny_suffix> in module permissions [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <mf_process_maxfwd_header>(1) in module maxfwd [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <is_uri_host_local>(0) in module domain [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <has_totag>(0) in module uri [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <loose_route>(0) in module rr [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <is_method>(1) in module textops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <is_method>(1) in module textops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <t_check_trans>(0) in module tm [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <t_relay>(0) in module tm [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <is_method>(1) in module textops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <t_check_trans>(0) in module tm [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <t_relay>(0) in module tm [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <t_check_trans>(0) in module tm [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <is_method>(1) in module textops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <record_route>(0) in module rr [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <is_method>(1) in module textops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <is_method>(1) in module textops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <t_on_branch>(1) in module tm [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <t_on_reply>(1) in module tm [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <t_on_failure>(1) in module tm [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <t_relay>(0) in module tm [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <sl_reply_error>(0) in module sl [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <is_method>(1) in module textops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <t_on_branch>(1) in module tm [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <t_on_reply>(1) in module tm [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <t_on_failure>(1) in module tm [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <t_relay>(0) in module tm [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <sl_reply_error>(0) in module sl [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <is_from_local>(0) in module domain [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <www_authorize>(2) in module auth_db [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <www_challenge>(2) in module auth [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <save>(1) in module registrar [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <is_from_local>(0) in module domain [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <allow_trusted>(0) in module permissions [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <is_method>(1) in module textops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <append_hf>(1) in module textops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <append_hf>(1) in module textops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <xlog>(1) in module xlog [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <proxy_authorize>(2) in module auth_db [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <proxy_challenge>(2) in module auth [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <consume_credentials>(0) in module auth [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <prefix2domain>(2) in module pdt [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <xlog>(1) in module xlog [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <append_hf>(1) in module textops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <append_hf>(1) in module textops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <allow_routing>(1) in module permissions [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <xlog>(1) in module xlog [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <append_hf>(1) in module textops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <xlog>(1) in module xlog [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <load_gws>(0) in module lcr [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <next_gw>(0) in module lcr [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <append_hf>(1) in module textops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <xlog>(1) in module xlog [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <avp_db_load>(2) in module avpops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <avp_pushto>(2) in module avpops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <alias_db_lookup>(1) in module alias_db [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <lookup>(1) in module registrar [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <avp_pushto>(2) in module avpops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <append_hf>(1) in module textops [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <xlog>(1) in module xlog [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <xlog>(1) in module xlog [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <sl_send_reply>(2) in module sl [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <xlog>(1) in module xlog [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <xlog>(1) in module xlog [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <t_was_cancelled>(0) in module tm [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <next_gw>(0) in module lcr [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <t_reply>(2) in module tm [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <t_on_failure>(1) in module tm [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:find_cmd_export_t: found <t_relay>(0) in module tm [//lib/openser/modules/]
Nov 20 11:16:44 [5162] DBG:core:socket2str: <udp:127.0.0.1:5060>
Nov 20 11:16:44 [5162] WARNING:core:fix_socket_list: could not rev. resolve 172.25.18.162
Nov 20 11:16:44 [5162] DBG:core:socket2str: <udp:172.25.18.162:5060>
Nov 20 11:16:44 [5162] WARNING:core:fix_socket_list: could not rev. resolve 172.25.18.163
Nov 20 11:16:44 [5162] DBG:core:socket2str: <udp:172.25.18.163:5060>
Nov 20 11:16:44 [5162] DBG:core:socket2str: <tcp:127.0.0.1:5060>
Nov 20 11:16:44 [5162] WARNING:core:fix_socket_list: could not rev. resolve 172.25.18.162
Nov 20 11:16:44 [5162] DBG:core:socket2str: <tcp:172.25.18.162:5060>
Nov 20 11:16:44 [5162] WARNING:core:fix_socket_list: could not rev. resolve 172.25.18.163
Nov 20 11:16:44 [5162] DBG:core:socket2str: <tcp:172.25.18.163:5060>
Listening on 
             udp: 127.0.0.1 [127.0.0.1]:5060
             udp: 172.25.18.162 [172.25.18.162]:5060
             udp: 172.25.18.163 [172.25.18.163]:5060
             tcp: 127.0.0.1 [127.0.0.1]:5060
             tcp: 172.25.18.162 [172.25.18.162]:5060
             tcp: 172.25.18.163 [172.25.18.163]:5060
Aliases: 
             tcp: localhost:5060
             udp: localhost:5060
             *: voip.tsm.it:*
             *: novaslink.tnnet.it:*
             *: voip.tnnet.it:*
             *: multivoce.it:*
             *: ttnnet.it:*

Nov 20 11:16:44 [5162] DBG:core:fm_malloc_init: F_OPTIMIZE=16384, /ROUNDTO=2048
Nov 20 11:16:44 [5162] DBG:core:fm_malloc_init: F_HASH_SIZE=2067, fm_block size=16560
Nov 20 11:16:44 [5162] DBG:core:fm_malloc_init: params (0xb3a44000, 67108864), start=0xb3a44000
Nov 20 11:16:44 [5162] DBG:core:shm_mem_init_mallocs: success
Nov 20 11:16:44 [5162] INFO:core:init_tcp: using epoll_lt as the TCP io watch method (auto detected)
Nov 20 11:16:44 [5164] DBG:core:set_core_dump: core dump limits set to 4294967295
Nov 20 11:16:44 [5164] NOTICE:core:main: version: openser 1.3.3-notls (i386/linux)
Nov 20 11:16:44 [5164] INFO:core:main: using 64 Mb shared memory
Nov 20 11:16:44 [5164] INFO:core:main: using 1 Mb private memory per process
Nov 20 11:16:44 [5164] DBG:core:add_avp_galias: registering <serial_branch> for avp id <16725044>
Nov 20 11:16:44 [5164] DBG:core:init_stats_collector: statistics manager successfully initialized
Nov 20 11:16:44 [5164] DBG:core:init_mod: initializing module postgres
Nov 20 11:16:44 [5164] INFO:postgres:mod_init: initializing...
Nov 20 11:16:44 [5164] DBG:core:init_mod: initializing module sl
Nov 20 11:16:44 [5164] INFO:sl:mod_init: Initializing StateLess engine
Nov 20 11:16:44 [5164] DBG:core:MDStringArray: MD5 calculated: 329cfeaa6ded039da25ff8cbb8668bd2
Nov 20 11:16:44 [5164] DBG:core:init_mod: registering stats for sl
Nov 20 11:16:44 [5164] DBG:core:init_mod: initializing module tm
Nov 20 11:16:44 [5164] INFO:tm:mod_init: TM - initializing...
Nov 20 11:16:44 [5164] DBG:tm:init_callid: Call-ID initialization: '79dddf19'
.
openser2:/etc/openser# Nov 20 11:16:44 [5164] DBG:tm:lock_initialize: lock initialization started
Nov 20 11:16:44 [5164] DBG:core:MDStringArray: MD5 calculated: 533cb9e91f4b999cf76861cbb9ed54ed
Nov 20 11:16:44 [5164] DBG:core:MDStringArray: MD5 calculated: fa997f81440371de71ab448ebdb9af56
Nov 20 11:16:44 [5164] DBG:core:init_mod: registering stats for tm
Nov 20 11:16:44 [5164] DBG:core:init_mod: register MI for tm
Nov 20 11:16:44 [5164] DBG:core:init_mod: initializing module rr
Nov 20 11:16:44 [5164] DBG:rr:mod_init: rr - initializing
Nov 20 11:16:44 [5164] DBG:core:init_mod: initializing module maxfwd
Nov 20 11:16:44 [5164] INFO:maxfwd:mod_init: initializing...
Nov 20 11:16:44 [5164] DBG:core:init_mod: initializing module usrloc
Nov 20 11:16:44 [5164] DBG:usrloc:mod_init: initializing
Nov 20 11:16:44 [5164] INFO:usrloc:ul_init_locks: locks array size 512
Nov 20 11:16:44 [5164] DBG:core:find_mod_export: found <db_use_table> in module postgres [//lib/openser/modules/]
Nov 20 11:16:44 [5164] DBG:core:find_mod_export: found <db_init> in module postgres [//lib/openser/modules/]
Nov 20 11:16:44 [5164] DBG:core:find_mod_export: found <db_close> in module postgres [//lib/openser/modules/]
Nov 20 11:16:44 [5164] DBG:core:find_mod_export: found <db_query> in module postgres [//lib/openser/modules/]
Nov 20 11:16:44 [5164] DBG:core:find_mod_export: found <db_fetch_result> in module postgres [//lib/openser/modules/]
Nov 20 11:16:44 [5164] DBG:core:find_mod_export: found <db_raw_query> in module postgres [//lib/openser/modules/]
Nov 20 11:16:44 [5164] DBG:core:find_mod_export: found <db_free_result> in module postgres [//lib/openser/modules/]
Nov 20 11:16:44 [5164] DBG:core:find_mod_export: found <db_insert> in module postgres [//lib/openser/modules/]
Nov 20 11:16:44 [5164] DBG:core:find_mod_export: found <db_delete> in module postgres [//lib/openser/modules/]
Nov 20 11:16:44 [5164] DBG:core:find_mod_export: found <db_update> in module postgres [//lib/openser/modules/]
Nov 20 11:16:44 [5164] DBG:core:find_mod_export: <db_replace> in module postgres not found
Nov 20 11:16:44 [5164] DBG:core:find_mod_export: <db_last_inserted_id> in module postgres not found
Nov 20 11:16:44 [5164] DBG:core:find_mod_export: <db_insert_update> in module postgres not found
Nov 20 11:16:44 [5164] DBG:core:init_mod: registering stats for usrloc
Nov 20 11:16:44 [5164] DBG:core:init_mod: register MI for usrloc
Nov 20 11:16:44 [5164] DBG:core:init_mod: initializing module registrar
Nov 20 11:16:44 [5164] INFO:registrar:mod_init: initializing...
Nov 20 11:16:44 [5164] DBG:core:find_cmd_export_t: found <load_sl>(0) in module sl [//lib/openser/modules/]
Nov 20 11:16:44 [5164] DBG:core:find_cmd_export_t: found <ul_bind_usrloc>(1) in module usrloc [//lib/openser/modules/]
Nov 20 11:16:44 [5164] DBG:core:init_mod: registering stats for registrar
Nov 20 11:16:44 [5164] DBG:core:init_mod: initializing module textops
Nov 20 11:16:44 [5164] INFO:textops:mod_init: initializing...
Nov 20 11:16:44 [5164] DBG:core:init_mod: initializing module mi_fifo
Nov 20 11:16:44 [5164] DBG:mi_fifo:mi_mod_init: testing fifo existance ...
Nov 20 11:16:44 [5164] DBG:core:init_mod: initializing module uri_db
Nov 20 11:16:44 [5164] DBG:uri_db:mod_init: uri_db - initializing
Nov 20 11:16:44 [5164] DBG:core:find_mod_export: found <db_use_table> in module postgres [//lib/openser/modules/]
Nov 20 11:16:44 [5164] DBG:core:find_mod_export: found <db_init> in module postgres [//lib/openser/modules/]
Nov 20 11:16:44 [5164] DBG:core:find_mod_export: found <db_close> in module postgres [//lib/openser/modules/]
Nov 20 11:16:44 [5164] DBG:core:find_mod_export: found <db_query> in module postgres [//lib/openser/modules/]
Nov 20 11:16:44 [5164] DBG:core:find_mod_export: found <db_fetch_result> in module postgres [//lib/openser/modules/]
Nov 20 11:16:44 [5164] DBG:core:find_mod_export: found <db_raw_query> in module postgres [//lib/openser/modules/]
Nov 20 11:16:44 [5164] DBG:core:find_mod_export: found <db_free_result> in module postgres [//lib/openser/modules/]
Nov 20 11:16:44 [5164] DBG:core:find_mod_export: found <db_insert> in module postgres [//lib/openser/modules/]
Nov 20 11:16:44 [5164] DBG:core:find_mod_export: found <db_delete> in module postgres [//lib/openser/modules/]
Nov 20 11:16:44 [5164] DBG:core:find_mod_export: found <db_update> in module postgres [//lib/openser/modules/]
Nov 20 11:16:44 [5164] DBG:core:find_mod_export: <db_replace> in module postgres not found
Nov 20 11:16:44 [5164] DBG:core:find_mod_export: <db_last_inserted_id> in module postgres not found
Nov 20 11:16:44 [5164] DBG:core:find_mod_export: <db_insert_update> in module postgres not found
Nov 20 11:16:44 [5164] DBG:postgres:pg_init: 0x818a870=pkg_malloc(12) for database connection
Nov 20 11:16:44 [5164] DBG:postgres:pg_init: connection 0x818a888 not found in pool
Nov 20 11:16:44 [5164] DBG:postgres:pg_new_conn: db_id = 0x818a888
Nov 20 11:16:44 [5164] DBG:postgres:pg_new_conn: 0x818a958=pkg_malloc(44)
Nov 20 11:16:44 [5164] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:45 [5164] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82835a8)
Nov 20 11:16:45 [5164] DBG:postgres:val2str: PQescapeStringConn: in: 10 chars, out: 10 chars
Nov 20 11:16:45 [5164] DBG:postgres:pg_query: 0x818a870 0xbfe32510 select table_version from version where table_name='subscriber'
Nov 20 11:16:45 [5164] DBG:postgres:submit_query: 0x818a870 PQsendQuery(select table_version from version where table_name='subscriber')
Nov 20 11:16:45 [5164] DBG:postgres:pg_get_result: 0x818a870 PQresultStatus(PGRES_TUPLES_OK) PQgetResult(0x82a4670)
Nov 20 11:16:45 [5164] DBG:postgres:pg_get_columns: 1 column(s) returned from the query
Nov 20 11:16:45 [5164] DBG:postgres:pg_get_columns: 0x818a990=pkg_malloc(4) RES_NAMES
Nov 20 11:16:45 [5164] DBG:postgres:pg_get_columns: 0x818a9a0=pkg_malloc(4) RES_TYPES
Nov 20 11:16:45 [5164] DBG:postgres:pg_get_columns: 0x818a9b0=pkg_malloc(14) RES_NAMES[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818a9b0)[0]=[table_version]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: 0x818a9c8=pkg_malloc(4) row_buf 1 pointers
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: 0x818a9d8=pkg_malloc(8) RES_ROWS 1 rows
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: PQgetvalue(0x818a870,0,0)=[6]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: 0x818a9e8=pkg_malloc(1) row_buf[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: [0][0] Column[table_version]=[6]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_row: 0x818aa08=pkg_malloc(16) ROW_VALUES for 1 columns
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_row: col[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_str2val: converting INT [6]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: [0][0] Col[table_version] Type[0] Freeing row_buf[0x818a9e8]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: 0x818a9e8=pkg_free() row_buf[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: 0x818a9c8=pkg_free() row_buf
Nov 20 11:16:45 [5164] DBG:postgres:free_query: PQclear(0x82a4670) result set
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818a928)[0] -> free(0x818a9b0) 'table_version'
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_columns: 0x818a9b0=pkg_free() RES_NAMES[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_columns: 0x818a990=pkg_free() RES_NAMES
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_columns: 0x818a9a0=pkg_free() RES_TYPES
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_rows: freeing 1 rows
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_rows: row[0]=0x818a9d8
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_row: 0x818aa08=pkg_free() ROW_VALUES
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_rows: 0x818a9d8=pkg_free() RES_ROWS
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_result: 0x818a928=pkg_free() _res
Nov 20 11:16:45 [5164] DBG:core:pool_remove: removing connection from the pool
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_conn: PQfinish(0x82835a8)
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_conn: pkg_free(0x818a958)
Nov 20 11:16:45 [5164] DBG:postgres:pg_close: 0x818a870=pkg_free() _con
Nov 20 11:16:45 [5164] DBG:core:init_mod: initializing module xlog
Nov 20 11:16:45 [5164] INFO:xlog:mod_init: initializing...
Nov 20 11:16:45 [5164] DBG:core:init_mod: initializing module acc
Nov 20 11:16:45 [5164] INFO:acc:mod_init: initializing...
Nov 20 11:16:45 [5164] DBG:core:find_cmd_export_t: found <load_tm>(0) in module tm [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_use_table> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_init> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_close> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_query> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_fetch_result> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_raw_query> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_free_result> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_insert> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_delete> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_update> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: <db_replace> in module postgres not found
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: <db_last_inserted_id> in module postgres not found
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: <db_insert_update> in module postgres not found
Nov 20 11:16:45 [5164] DBG:core:init_mod: initializing module auth
Nov 20 11:16:45 [5164] INFO:auth:mod_init: initializing...
Nov 20 11:16:45 [5164] DBG:core:find_cmd_export_t: found <load_sl>(0) in module sl [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:init_mod: initializing module auth_db
Nov 20 11:16:45 [5164] INFO:auth_db:mod_init: initializing...
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_use_table> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_init> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_close> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_query> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_fetch_result> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_raw_query> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_free_result> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_insert> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_delete> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_update> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: <db_replace> in module postgres not found
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: <db_last_inserted_id> in module postgres not found
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: <db_insert_update> in module postgres not found
Nov 20 11:16:45 [5164] DBG:core:find_cmd_export_t: found <bind_auth>(0) in module auth [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_cmd_export_t: found <load_sl>(0) in module sl [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:init_mod: initializing module alias_db
Nov 20 11:16:45 [5164] INFO:alias_db:mod_init: initializing...
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_use_table> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_init> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_close> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_query> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_fetch_result> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_raw_query> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_free_result> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_insert> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_delete> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_update> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: <db_replace> in module postgres not found
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: <db_last_inserted_id> in module postgres not found
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: <db_insert_update> in module postgres not found
Nov 20 11:16:45 [5164] DBG:core:init_mod: initializing module domain
Nov 20 11:16:45 [5164] DBG:domain:mod_init: Initializing
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_use_table> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_init> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_close> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_query> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_fetch_result> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_raw_query> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_free_result> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_insert> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_delete> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_update> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: <db_replace> in module postgres not found
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: <db_last_inserted_id> in module postgres not found
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: <db_insert_update> in module postgres not found
Nov 20 11:16:45 [5164] DBG:postgres:pg_init: 0x818a870=pkg_malloc(12) for database connection
Nov 20 11:16:45 [5164] DBG:postgres:pg_init: connection 0x818a958 not found in pool
Nov 20 11:16:45 [5164] DBG:postgres:pg_new_conn: db_id = 0x818a958
Nov 20 11:16:45 [5164] DBG:postgres:pg_new_conn: 0x818a888=pkg_malloc(44)
Nov 20 11:16:45 [5164] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:45 [5164] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82a7458)
Nov 20 11:16:45 [5164] DBG:postgres:val2str: PQescapeStringConn: in: 6 chars, out: 6 chars
Nov 20 11:16:45 [5164] DBG:postgres:pg_query: 0x818a870 0xbfe32790 select table_version from version where table_name='domain'
Nov 20 11:16:45 [5164] DBG:postgres:submit_query: 0x818a870 PQsendQuery(select table_version from version where table_name='domain')
Nov 20 11:16:45 [5164] DBG:postgres:pg_get_result: 0x818a870 PQresultStatus(PGRES_TUPLES_OK) PQgetResult(0x82a4648)
Nov 20 11:16:45 [5164] DBG:postgres:pg_get_columns: 1 column(s) returned from the query
Nov 20 11:16:45 [5164] DBG:postgres:pg_get_columns: 0x818a9d8=pkg_malloc(4) RES_NAMES
Nov 20 11:16:45 [5164] DBG:postgres:pg_get_columns: 0x818a9a0=pkg_malloc(4) RES_TYPES
Nov 20 11:16:45 [5164] DBG:postgres:pg_get_columns: 0x818aa08=pkg_malloc(14) RES_NAMES[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818aa08)[0]=[table_version]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: 0x818a990=pkg_malloc(4) row_buf 1 pointers
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: 0x818a9c8=pkg_malloc(8) RES_ROWS 1 rows
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: PQgetvalue(0x818a870,0,0)=[1]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: 0x818a9e8=pkg_malloc(1) row_buf[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: [0][0] Column[table_version]=[1]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_row: 0x818a9b0=pkg_malloc(16) ROW_VALUES for 1 columns
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_row: col[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_str2val: converting INT [1]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: [0][0] Col[table_version] Type[0] Freeing row_buf[0x818a9e8]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: 0x818a9e8=pkg_free() row_buf[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: 0x818a990=pkg_free() row_buf
Nov 20 11:16:45 [5164] DBG:postgres:free_query: PQclear(0x82a4648) result set
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab58)[0] -> free(0x818aa08) 'table_version'
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_columns: 0x818aa08=pkg_free() RES_NAMES[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_columns: 0x818a9d8=pkg_free() RES_NAMES
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_columns: 0x818a9a0=pkg_free() RES_TYPES
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_rows: freeing 1 rows
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_rows: row[0]=0x818a9c8
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_row: 0x818a9b0=pkg_free() ROW_VALUES
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_rows: 0x818a9c8=pkg_free() RES_ROWS
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_result: 0x818ab58=pkg_free() _res
Nov 20 11:16:45 [5164] DBG:postgres:pg_query: 0x818a870 0xbfe327d4 select domain from domain 
Nov 20 11:16:45 [5164] DBG:postgres:submit_query: 0x818a870 PQsendQuery(select domain from domain )
Nov 20 11:16:45 [5164] DBG:postgres:pg_get_result: 0x818a870 PQresultStatus(PGRES_TUPLES_OK) PQgetResult(0x82a4648)
Nov 20 11:16:45 [5164] DBG:postgres:pg_get_columns: 1 column(s) returned from the query
Nov 20 11:16:45 [5164] DBG:postgres:pg_get_columns: 0x818a9c8=pkg_malloc(4) RES_NAMES
Nov 20 11:16:45 [5164] DBG:postgres:pg_get_columns: 0x818a9a0=pkg_malloc(4) RES_TYPES
Nov 20 11:16:45 [5164] DBG:postgres:pg_get_columns: 0x818a9d8=pkg_malloc(7) RES_NAMES[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818a9d8)[0]=[domain]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: 0x818a990=pkg_malloc(4) row_buf 1 pointers
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: 0x818ab80=pkg_malloc(48) RES_ROWS 6 rows
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: PQgetvalue(0x818a870,0,0)=[voip.tn.it]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: 0x818a9b0=pkg_malloc(10) row_buf[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: [0][0] Column[domain]=[voip.tn.it]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_row: 0x818aa08=pkg_malloc(16) ROW_VALUES for 1 columns
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_row: col[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_str2val: converting STRING [voip.tn.it]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: PQgetvalue(0x818a870,1,0)=[multivoce.it]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: 0x818abc0=pkg_malloc(12) row_buf[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: [1][0] Column[domain]=[multivoce.it]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_row: 0x818abd8=pkg_malloc(16) ROW_VALUES for 1 columns
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_row: col[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_str2val: converting STRING [multivoce.it]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: PQgetvalue(0x818a870,2,0)=[ttnnet.it]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: 0x818abf0=pkg_malloc(9) row_buf[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: [2][0] Column[domain]=[ttnnet.it]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_row: 0x818ac08=pkg_malloc(16) ROW_VALUES for 1 columns
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_row: col[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_str2val: converting STRING [ttnnet.it]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: PQgetvalue(0x818a870,3,0)=[voip.tnnet.it]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: 0x818ac20=pkg_malloc(13) row_buf[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: [3][0] Column[domain]=[voip.tnnet.it]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_row: 0x818ac38=pkg_malloc(16) ROW_VALUES for 1 columns
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_row: col[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_str2val: converting STRING [voip.tnnet.it]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: PQgetvalue(0x818a870,4,0)=[voip.tsm.it]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: 0x818ac50=pkg_malloc(11) row_buf[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: [4][0] Column[domain]=[voip.tsm.it]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_row: 0x818ac68=pkg_malloc(16) ROW_VALUES for 1 columns
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_row: col[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_str2val: converting STRING [voip.tsm.it]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: PQgetvalue(0x818a870,5,0)=[novaslink.tnnet.it]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: 0x818ac80=pkg_malloc(18) row_buf[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: [5][0] Column[domain]=[novaslink.tnnet.it]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_row: 0x818aca0=pkg_malloc(16) ROW_VALUES for 1 columns
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_row: col[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_str2val: converting STRING [novaslink.tnnet.it]
Nov 20 11:16:45 [5164] DBG:postgres:pg_convert_rows: 0x818a990=pkg_free() row_buf
Nov 20 11:16:45 [5164] DBG:postgres:free_query: PQclear(0x82a4648) result set
Nov 20 11:16:45 [5164] DBG:domain:reload_domain_table: Number of rows in domain table: 6
Nov 20 11:16:45 [5164] DBG:domain:reload_domain_table: Value: voip.tn.it inserted into domain hash table
Nov 20 11:16:45 [5164] DBG:domain:reload_domain_table: Value: multivoce.it inserted into domain hash table
Nov 20 11:16:45 [5164] DBG:domain:reload_domain_table: Value: ttnnet.it inserted into domain hash table
Nov 20 11:16:45 [5164] DBG:domain:reload_domain_table: Value: voip.tnnet.it inserted into domain hash table
Nov 20 11:16:45 [5164] DBG:domain:reload_domain_table: Value: voip.tsm.it inserted into domain hash table
Nov 20 11:16:45 [5164] DBG:domain:reload_domain_table: Value: novaslink.tnnet.it inserted into domain hash table
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab58)[0] -> free(0x818a9d8) 'domain'
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_columns: 0x818a9d8=pkg_free() RES_NAMES[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_columns: 0x818a9c8=pkg_free() RES_NAMES
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_columns: 0x818a9a0=pkg_free() RES_TYPES
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_rows: freeing 6 rows
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_rows: row[0]=0x818ab80
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_row: 0x818a9b0=pkg_free() VAL_STRING[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_row: 0x818aa08=pkg_free() ROW_VALUES
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_rows: row[1]=0x818ab88
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_row: 0x818abc0=pkg_free() VAL_STRING[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_row: 0x818abd8=pkg_free() ROW_VALUES
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_rows: row[2]=0x818ab90
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_row: 0x818abf0=pkg_free() VAL_STRING[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_row: 0x818ac08=pkg_free() ROW_VALUES
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_rows: row[3]=0x818ab98
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_row: 0x818ac20=pkg_free() VAL_STRING[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_row: 0x818ac38=pkg_free() ROW_VALUES
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_rows: row[4]=0x818aba0
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_row: 0x818ac50=pkg_free() VAL_STRING[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_row: 0x818ac68=pkg_free() ROW_VALUES
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_rows: row[5]=0x818aba8
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_row: 0x818ac80=pkg_free() VAL_STRING[0]
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_row: 0x818aca0=pkg_free() ROW_VALUES
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_rows: 0x818ab80=pkg_free() RES_ROWS
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_result: 0x818ab58=pkg_free() _res
Nov 20 11:16:45 [5164] DBG:core:pool_remove: removing connection from the pool
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_conn: PQfinish(0x82a7458)
Nov 20 11:16:45 [5164] DBG:postgres:pg_free_conn: pkg_free(0x818a888)
Nov 20 11:16:45 [5164] DBG:postgres:pg_close: 0x818a870=pkg_free() _con
Nov 20 11:16:45 [5164] DBG:core:init_mod: register MI for domain
Nov 20 11:16:45 [5164] DBG:core:init_mod: initializing module lcr
Nov 20 11:16:45 [5164] DBG:lcr:mod_init: Initializing
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_use_table> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_init> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_close> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_query> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_fetch_result> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_raw_query> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_free_result> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_insert> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_delete> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: found <db_update> in module postgres [//lib/openser/modules/]
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: <db_replace> in module postgres not found
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: <db_last_inserted_id> in module postgres not found
Nov 20 11:16:45 [5164] DBG:core:find_mod_export: <db_insert_update> in module postgres not found
Nov 20 11:16:45 [5164] DBG:postgres:pg_init: 0x818a870=pkg_malloc(12) for database connection
Nov 20 11:16:45 [5164] DBG:postgres:pg_init: connection 0x818ac80 not found in pool
Nov 20 11:16:45 [5164] DBG:postgres:pg_new_conn: db_id = 0x818ac80
Nov 20 11:16:45 [5164] DBG:postgres:pg_new_conn: 0x818a888=pkg_malloc(44)
Nov 20 11:16:45 [5164] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:46 [5164] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82a7458)
Nov 20 11:16:46 [5164] DBG:postgres:val2str: PQescapeStringConn: in: 2 chars, out: 2 chars
Nov 20 11:16:46 [5164] DBG:postgres:pg_query: 0x818a870 0xbfe326a0 select table_version from version where table_name='gw'
Nov 20 11:16:46 [5164] DBG:postgres:submit_query: 0x818a870 PQsendQuery(select table_version from version where table_name='gw')
Nov 20 11:16:46 [5164] DBG:postgres:pg_get_result: 0x818a870 PQresultStatus(PGRES_TUPLES_OK) PQgetResult(0x82a4648)
Nov 20 11:16:46 [5164] DBG:postgres:pg_get_columns: 1 column(s) returned from the query
Nov 20 11:16:46 [5164] DBG:postgres:pg_get_columns: 0x818a9a0=pkg_malloc(4) RES_NAMES
Nov 20 11:16:46 [5164] DBG:postgres:pg_get_columns: 0x818a9c8=pkg_malloc(4) RES_TYPES
Nov 20 11:16:46 [5164] DBG:postgres:pg_get_columns: 0x818ac68=pkg_malloc(14) RES_NAMES[0]
Nov 20 11:16:46 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818ac68)[0]=[table_version]
Nov 20 11:16:46 [5164] DBG:postgres:pg_convert_rows: 0x818a9d8=pkg_malloc(4) row_buf 1 pointers
Nov 20 11:16:46 [5164] DBG:postgres:pg_convert_rows: 0x818a990=pkg_malloc(8) RES_ROWS 1 rows
Nov 20 11:16:46 [5164] DBG:postgres:pg_convert_rows: PQgetvalue(0x818a870,0,0)=[5]
Nov 20 11:16:46 [5164] DBG:postgres:pg_convert_rows: 0x818a9e8=pkg_malloc(1) row_buf[0]
Nov 20 11:16:46 [5164] DBG:postgres:pg_convert_rows: [0][0] Column[table_version]=[5]
Nov 20 11:16:46 [5164] DBG:postgres:pg_convert_row: 0x818ac50=pkg_malloc(16) ROW_VALUES for 1 columns
Nov 20 11:16:46 [5164] DBG:postgres:pg_convert_row: col[0]
Nov 20 11:16:46 [5164] DBG:postgres:pg_str2val: converting INT [5]
Nov 20 11:16:46 [5164] DBG:postgres:pg_convert_rows: [0][0] Col[table_version] Type[0] Freeing row_buf[0x818a9e8]
Nov 20 11:16:46 [5164] DBG:postgres:pg_convert_rows: 0x818a9e8=pkg_free() row_buf[0]
Nov 20 11:16:46 [5164] DBG:postgres:pg_convert_rows: 0x818a9d8=pkg_free() row_buf
Nov 20 11:16:46 [5164] DBG:postgres:free_query: PQclear(0x82a4648) result set
Nov 20 11:16:46 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab58)[0] -> free(0x818ac68) 'table_version'
Nov 20 11:16:46 [5164] DBG:postgres:pg_free_columns: 0x818ac68=pkg_free() RES_NAMES[0]
Nov 20 11:16:46 [5164] DBG:postgres:pg_free_columns: 0x818a9a0=pkg_free() RES_NAMES
Nov 20 11:16:46 [5164] DBG:postgres:pg_free_columns: 0x818a9c8=pkg_free() RES_TYPES
Nov 20 11:16:46 [5164] DBG:postgres:pg_free_rows: freeing 1 rows
Nov 20 11:16:46 [5164] DBG:postgres:pg_free_rows: row[0]=0x818a990
Nov 20 11:16:46 [5164] DBG:postgres:pg_free_row: 0x818ac50=pkg_free() ROW_VALUES
Nov 20 11:16:46 [5164] DBG:postgres:pg_free_rows: 0x818a990=pkg_free() RES_ROWS
Nov 20 11:16:46 [5164] DBG:postgres:pg_free_result: 0x818ab58=pkg_free() _res
Nov 20 11:16:46 [5164] DBG:core:pool_remove: removing connection from the pool
Nov 20 11:16:46 [5164] DBG:postgres:pg_free_conn: PQfinish(0x82a7458)
Nov 20 11:16:46 [5164] DBG:postgres:pg_free_conn: pkg_free(0x818a888)
Nov 20 11:16:46 [5164] DBG:postgres:pg_close: 0x818a870=pkg_free() _con
Nov 20 11:16:46 [5164] DBG:postgres:pg_init: 0x818a870=pkg_malloc(12) for database connection
Nov 20 11:16:46 [5164] DBG:postgres:pg_init: connection 0x818ac80 not found in pool
Nov 20 11:16:46 [5164] DBG:postgres:pg_new_conn: db_id = 0x818ac80
Nov 20 11:16:46 [5164] DBG:postgres:pg_new_conn: 0x818a888=pkg_malloc(44)
Nov 20 11:16:46 [5164] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:46 [5164] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82a7458)
Nov 20 11:16:46 [5164] DBG:postgres:val2str: PQescapeStringConn: in: 3 chars, out: 3 chars
Nov 20 11:16:46 [5164] DBG:postgres:pg_query: 0x818a870 0xbfe326a0 select table_version from version where table_name='lcr'
Nov 20 11:16:47 [5164] DBG:postgres:submit_query: 0x818a870 PQsendQuery(select table_version from version where table_name='lcr')
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_result: 0x818a870 PQresultStatus(PGRES_TUPLES_OK) PQgetResult(0x82a4648)
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: 1 column(s) returned from the query
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: 0x818a990=pkg_malloc(4) RES_NAMES
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: 0x818a9c8=pkg_malloc(4) RES_TYPES
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: 0x818ac50=pkg_malloc(14) RES_NAMES[0]
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818ac50)[0]=[table_version]
Nov 20 11:16:47 [5164] DBG:postgres:pg_convert_rows: 0x818a9a0=pkg_malloc(4) row_buf 1 pointers
Nov 20 11:16:47 [5164] DBG:postgres:pg_convert_rows: 0x818a9d8=pkg_malloc(8) RES_ROWS 1 rows
Nov 20 11:16:47 [5164] DBG:postgres:pg_convert_rows: PQgetvalue(0x818a870,0,0)=[2]
Nov 20 11:16:47 [5164] DBG:postgres:pg_convert_rows: 0x818a9e8=pkg_malloc(1) row_buf[0]
Nov 20 11:16:47 [5164] DBG:postgres:pg_convert_rows: [0][0] Column[table_version]=[2]
Nov 20 11:16:47 [5164] DBG:postgres:pg_convert_row: 0x818ac68=pkg_malloc(16) ROW_VALUES for 1 columns
Nov 20 11:16:47 [5164] DBG:postgres:pg_convert_row: col[0]
Nov 20 11:16:47 [5164] DBG:postgres:pg_str2val: converting INT [2]
Nov 20 11:16:47 [5164] DBG:postgres:pg_convert_rows: [0][0] Col[table_version] Type[0] Freeing row_buf[0x818a9e8]
Nov 20 11:16:47 [5164] DBG:postgres:pg_convert_rows: 0x818a9e8=pkg_free() row_buf[0]
Nov 20 11:16:47 [5164] DBG:postgres:pg_convert_rows: 0x818a9a0=pkg_free() row_buf
Nov 20 11:16:47 [5164] DBG:postgres:free_query: PQclear(0x82a4648) result set
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab58)[0] -> free(0x818ac50) 'table_version'
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: 0x818ac50=pkg_free() RES_NAMES[0]
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: 0x818a990=pkg_free() RES_NAMES
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: 0x818a9c8=pkg_free() RES_TYPES
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_rows: freeing 1 rows
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_rows: row[0]=0x818a9d8
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_row: 0x818ac68=pkg_free() ROW_VALUES
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_rows: 0x818a9d8=pkg_free() RES_ROWS
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_result: 0x818ab58=pkg_free() _res
Nov 20 11:16:47 [5164] DBG:core:pool_remove: removing connection from the pool
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_conn: PQfinish(0x82a7458)
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_conn: pkg_free(0x818a888)
Nov 20 11:16:47 [5164] DBG:postgres:pg_close: 0x818a870=pkg_free() _con
Nov 20 11:16:47 [5164] DBG:postgres:pg_init: 0x818a870=pkg_malloc(12) for database connection
Nov 20 11:16:47 [5164] DBG:postgres:pg_init: connection 0x818ac80 not found in pool
Nov 20 11:16:47 [5164] DBG:postgres:pg_new_conn: db_id = 0x818ac80
Nov 20 11:16:47 [5164] DBG:postgres:pg_new_conn: 0x818a888=pkg_malloc(44)
Nov 20 11:16:47 [5164] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:47 [5164] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82a7458)
Nov 20 11:16:47 [5164] DBG:postgres:pg_query: 0x818a870 0xbfe326e4 select ip_addr,port,uri_scheme,transport,strip,prefix,grp_id,dm from gw 
Nov 20 11:16:47 [5164] DBG:postgres:submit_query: 0x818a870 PQsendQuery(select ip_addr,port,uri_scheme,transport,strip,prefix,grp_id,dm from gw )
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_result: 0x818a870 PQresultStatus(PGRES_TUPLES_OK) PQgetResult(0x82a4648)
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: 8 column(s) returned from the query
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: 0x818aca0=pkg_malloc(32) RES_NAMES
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: 0x818a958=pkg_malloc(32) RES_TYPES
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: 0x818a9d8=pkg_malloc(8) RES_NAMES[0]
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818a9d8)[0]=[ip_addr]
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: 0x818a9c8=pkg_malloc(5) RES_NAMES[1]
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818a9c8)[1]=[port]
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: 0x818ac68=pkg_malloc(11) RES_NAMES[2]
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818ac68)[2]=[uri_scheme]
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: 0x818ac50=pkg_malloc(10) RES_NAMES[3]
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818ac50)[3]=[transport]
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: 0x818a990=pkg_malloc(6) RES_NAMES[4]
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818a990)[4]=[strip]
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: 0x818a9a0=pkg_malloc(7) RES_NAMES[5]
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818a9a0)[5]=[prefix]
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: 0x818a9e8=pkg_malloc(7) RES_NAMES[6]
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818a9e8)[6]=[grp_id]
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: 0x818a9f8=pkg_malloc(3) RES_NAMES[7]
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818a9f8)[7]=[dm]
Nov 20 11:16:47 [5164] DBG:postgres:pg_convert_result: no rows in the query
Nov 20 11:16:47 [5164] DBG:postgres:free_query: PQclear(0x82a4648) result set
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab58)[0] -> free(0x818a9d8) 'ip_addr'
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: 0x818a9d8=pkg_free() RES_NAMES[0]
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab58)[1] -> free(0x818a9c8) 'port'
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: 0x818a9c8=pkg_free() RES_NAMES[1]
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab58)[2] -> free(0x818ac68) 'uri_scheme'
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: 0x818ac68=pkg_free() RES_NAMES[2]
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab58)[3] -> free(0x818ac50) 'transport'
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: 0x818ac50=pkg_free() RES_NAMES[3]
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab58)[4] -> free(0x818a990) 'strip'
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: 0x818a990=pkg_free() RES_NAMES[4]
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab58)[5] -> free(0x818a9a0) 'prefix'
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: 0x818a9a0=pkg_free() RES_NAMES[5]
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab58)[6] -> free(0x818a9e8) 'grp_id'
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: 0x818a9e8=pkg_free() RES_NAMES[6]
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab58)[7] -> free(0x818a9f8) 'dm'
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: 0x818a9f8=pkg_free() RES_NAMES[7]
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: 0x818aca0=pkg_free() RES_NAMES
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: 0x818a958=pkg_free() RES_TYPES
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_rows: freeing 0 rows
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_result: 0x818ab58=pkg_free() _res
Nov 20 11:16:47 [5164] DBG:postgres:pg_query: 0x818a870 0xbfe326e4 select prefix,from_uri,grp_id,priority from lcr 
Nov 20 11:16:47 [5164] DBG:postgres:submit_query: 0x818a870 PQsendQuery(select prefix,from_uri,grp_id,priority from lcr )
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_result: 0x818a870 PQresultStatus(PGRES_TUPLES_OK) PQgetResult(0x82a4648)
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: 4 column(s) returned from the query
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: 0x818ac50=pkg_malloc(16) RES_NAMES
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: 0x818ac68=pkg_malloc(16) RES_TYPES
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: 0x818a9f8=pkg_malloc(7) RES_NAMES[0]
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818a9f8)[0]=[prefix]
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: 0x818ac38=pkg_malloc(9) RES_NAMES[1]
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818ac38)[1]=[from_uri]
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: 0x818a9e8=pkg_malloc(7) RES_NAMES[2]
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818a9e8)[2]=[grp_id]
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: 0x818ac20=pkg_malloc(9) RES_NAMES[3]
Nov 20 11:16:47 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818ac20)[3]=[priority]
Nov 20 11:16:47 [5164] DBG:postgres:pg_convert_result: no rows in the query
Nov 20 11:16:47 [5164] DBG:postgres:free_query: PQclear(0x82a4648) result set
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab58)[0] -> free(0x818a9f8) 'prefix'
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: 0x818a9f8=pkg_free() RES_NAMES[0]
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab58)[1] -> free(0x818ac38) 'from_uri'
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: 0x818ac38=pkg_free() RES_NAMES[1]
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab58)[2] -> free(0x818a9e8) 'grp_id'
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: 0x818a9e8=pkg_free() RES_NAMES[2]
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab58)[3] -> free(0x818ac20) 'priority'
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: 0x818ac20=pkg_free() RES_NAMES[3]
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: 0x818ac50=pkg_free() RES_NAMES
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_columns: 0x818ac68=pkg_free() RES_TYPES
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_rows: freeing 0 rows
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_result: 0x818ab58=pkg_free() _res
Nov 20 11:16:47 [5164] DBG:core:pool_remove: removing connection from the pool
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_conn: PQfinish(0x82a7458)
Nov 20 11:16:47 [5164] DBG:postgres:pg_free_conn: pkg_free(0x818a888)
Nov 20 11:16:47 [5164] DBG:postgres:pg_close: 0x818a870=pkg_free() _con
Nov 20 11:16:47 [5164] DBG:core:init_mod: register MI for lcr
Nov 20 11:16:47 [5164] DBG:core:init_mod: initializing module avpops
Nov 20 11:16:47 [5164] INFO:avpops:avpops_init: initializing...
Nov 20 11:16:47 [5164] DBG:core:find_mod_export: found <db_use_table> in module postgres [//lib/openser/modules/]
Nov 20 11:16:47 [5164] DBG:core:find_mod_export: found <db_init> in module postgres [//lib/openser/modules/]
Nov 20 11:16:47 [5164] DBG:core:find_mod_export: found <db_close> in module postgres [//lib/openser/modules/]
Nov 20 11:16:47 [5164] DBG:core:find_mod_export: found <db_query> in module postgres [//lib/openser/modules/]
Nov 20 11:16:47 [5164] DBG:core:find_mod_export: found <db_fetch_result> in module postgres [//lib/openser/modules/]
Nov 20 11:16:47 [5164] DBG:core:find_mod_export: found <db_raw_query> in module postgres [//lib/openser/modules/]
Nov 20 11:16:47 [5164] DBG:core:find_mod_export: found <db_free_result> in module postgres [//lib/openser/modules/]
Nov 20 11:16:47 [5164] DBG:core:find_mod_export: found <db_insert> in module postgres [//lib/openser/modules/]
Nov 20 11:16:47 [5164] DBG:core:find_mod_export: found <db_delete> in module postgres [//lib/openser/modules/]
Nov 20 11:16:47 [5164] DBG:core:find_mod_export: found <db_update> in module postgres [//lib/openser/modules/]
Nov 20 11:16:47 [5164] DBG:core:find_mod_export: <db_replace> in module postgres not found
Nov 20 11:16:47 [5164] DBG:core:find_mod_export: <db_last_inserted_id> in module postgres not found
Nov 20 11:16:47 [5164] DBG:core:find_mod_export: <db_insert_update> in module postgres not found
Nov 20 11:16:47 [5164] DBG:core:init_mod: initializing module pdt
Nov 20 11:16:47 [5164] INFO:pdt:mod_init: initializing...
Nov 20 11:16:47 [5164] INFO:pdt:mod_init: pdt_char_list=0123456789 
Nov 20 11:16:47 [5164] DBG:core:find_mod_export: found <db_use_table> in module postgres [//lib/openser/modules/]
Nov 20 11:16:47 [5164] DBG:core:find_mod_export: found <db_init> in module postgres [//lib/openser/modules/]
Nov 20 11:16:47 [5164] DBG:core:find_mod_export: found <db_close> in module postgres [//lib/openser/modules/]
Nov 20 11:16:47 [5164] DBG:core:find_mod_export: found <db_query> in module postgres [//lib/openser/modules/]
Nov 20 11:16:47 [5164] DBG:core:find_mod_export: found <db_fetch_result> in module postgres [//lib/openser/modules/]
Nov 20 11:16:47 [5164] DBG:core:find_mod_export: found <db_raw_query> in module postgres [//lib/openser/modules/]
Nov 20 11:16:47 [5164] DBG:core:find_mod_export: found <db_free_result> in module postgres [//lib/openser/modules/]
Nov 20 11:16:47 [5164] DBG:core:find_mod_export: found <db_insert> in module postgres [//lib/openser/modules/]
Nov 20 11:16:47 [5164] DBG:core:find_mod_export: found <db_delete> in module postgres [//lib/openser/modules/]
Nov 20 11:16:47 [5164] DBG:core:find_mod_export: found <db_update> in module postgres [//lib/openser/modules/]
Nov 20 11:16:47 [5164] DBG:core:find_mod_export: <db_replace> in module postgres not found
Nov 20 11:16:47 [5164] DBG:core:find_mod_export: <db_last_inserted_id> in module postgres not found
Nov 20 11:16:47 [5164] DBG:core:find_mod_export: <db_insert_update> in module postgres not found
Nov 20 11:16:47 [5164] DBG:postgres:pg_init: 0x818a870=pkg_malloc(12) for database connection
Nov 20 11:16:47 [5164] DBG:postgres:pg_init: connection 0x818ac80 not found in pool
Nov 20 11:16:47 [5164] DBG:postgres:pg_new_conn: db_id = 0x818ac80
Nov 20 11:16:47 [5164] DBG:postgres:pg_new_conn: 0x818a888=pkg_malloc(44)
Nov 20 11:16:47 [5164] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:48 [5164] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82a7458)
Nov 20 11:16:48 [5164] DBG:pdt:mod_init: database connection opened successfully
Nov 20 11:16:48 [5164] DBG:postgres:pg_query: 0x818a870 0xbfe328f8 select sdomain,prefix,domain from pdt  order by sdomain
Nov 20 11:16:48 [5164] DBG:postgres:submit_query: 0x818a870 PQsendQuery(select sdomain,prefix,domain from pdt  order by sdomain)
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_result: 0x818a870 PQresultStatus(PGRES_TUPLES_OK) PQgetResult(0x82a4648)
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_columns: 3 column(s) returned from the query
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_columns: 0x818ac68=pkg_malloc(12) RES_NAMES
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_columns: 0x818ac50=pkg_malloc(12) RES_TYPES
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_columns: 0x818a9e8=pkg_malloc(8) RES_NAMES[0]
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818a9e8)[0]=[sdomain]
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_columns: 0x818a9f8=pkg_malloc(7) RES_NAMES[1]
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818a9f8)[1]=[prefix]
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_columns: 0x818a9a0=pkg_malloc(7) RES_NAMES[2]
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818a9a0)[2]=[domain]
Nov 20 11:16:48 [5164] DBG:postgres:pg_convert_result: no rows in the query
Nov 20 11:16:48 [5164] DBG:postgres:free_query: PQclear(0x82a4648) result set
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab58)[0] -> free(0x818a9e8) 'sdomain'
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_columns: 0x818a9e8=pkg_free() RES_NAMES[0]
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab58)[1] -> free(0x818a9f8) 'prefix'
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_columns: 0x818a9f8=pkg_free() RES_NAMES[1]
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab58)[2] -> free(0x818a9a0) 'domain'
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_columns: 0x818a9a0=pkg_free() RES_NAMES[2]
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_columns: 0x818ac68=pkg_free() RES_NAMES
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_columns: 0x818ac50=pkg_free() RES_TYPES
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_rows: freeing 0 rows
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_result: 0x818ab58=pkg_free() _res
Nov 20 11:16:48 [5164] DBG:core:pool_remove: removing connection from the pool
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_conn: PQfinish(0x82a7458)
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_conn: pkg_free(0x818a888)
Nov 20 11:16:48 [5164] DBG:postgres:pg_close: 0x818a870=pkg_free() _con
Nov 20 11:16:48 [5164] DBG:core:init_mod: register MI for pdt
Nov 20 11:16:48 [5164] DBG:core:init_mod: initializing module permissions
Nov 20 11:16:48 [5164] DBG:permissions:mod_init: initializing...
Nov 20 11:16:48 [5164] DBG:permissions:mod_init: default allow file (//etc/openser/permissions.allow) parsed
Nov 20 11:16:48 [5164] WARNING:permissions:parse_config_file: file not found: //etc/openser/permissions.deny
Nov 20 11:16:48 [5164] WARNING:permissions:mod_init: default deny file (//etc/openser/permissions.deny) not found => empty rule set
Nov 20 11:16:48 [5164] DBG:core:find_mod_export: found <db_use_table> in module postgres [//lib/openser/modules/]
Nov 20 11:16:48 [5164] DBG:core:find_mod_export: found <db_init> in module postgres [//lib/openser/modules/]
Nov 20 11:16:48 [5164] DBG:core:find_mod_export: found <db_close> in module postgres [//lib/openser/modules/]
Nov 20 11:16:48 [5164] DBG:core:find_mod_export: found <db_query> in module postgres [//lib/openser/modules/]
Nov 20 11:16:48 [5164] DBG:core:find_mod_export: found <db_fetch_result> in module postgres [//lib/openser/modules/]
Nov 20 11:16:48 [5164] DBG:core:find_mod_export: found <db_raw_query> in module postgres [//lib/openser/modules/]
Nov 20 11:16:48 [5164] DBG:core:find_mod_export: found <db_free_result> in module postgres [//lib/openser/modules/]
Nov 20 11:16:48 [5164] DBG:core:find_mod_export: found <db_insert> in module postgres [//lib/openser/modules/]
Nov 20 11:16:48 [5164] DBG:core:find_mod_export: found <db_delete> in module postgres [//lib/openser/modules/]
Nov 20 11:16:48 [5164] DBG:core:find_mod_export: found <db_update> in module postgres [//lib/openser/modules/]
Nov 20 11:16:48 [5164] DBG:core:find_mod_export: <db_replace> in module postgres not found
Nov 20 11:16:48 [5164] DBG:core:find_mod_export: <db_last_inserted_id> in module postgres not found
Nov 20 11:16:48 [5164] DBG:core:find_mod_export: <db_insert_update> in module postgres not found
Nov 20 11:16:48 [5164] DBG:postgres:pg_init: 0x818a870=pkg_malloc(12) for database connection
Nov 20 11:16:48 [5164] DBG:postgres:pg_init: connection 0x818a888 not found in pool
Nov 20 11:16:48 [5164] DBG:postgres:pg_new_conn: db_id = 0x818a888
Nov 20 11:16:48 [5164] DBG:postgres:pg_new_conn: 0x818a958=pkg_malloc(44)
Nov 20 11:16:48 [5164] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:48 [5164] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82a7458)
Nov 20 11:16:48 [5164] DBG:postgres:val2str: PQescapeStringConn: in: 7 chars, out: 7 chars
Nov 20 11:16:48 [5164] DBG:postgres:pg_query: 0x818a870 0xbfe328b0 select table_version from version where table_name='trusted'
Nov 20 11:16:48 [5164] DBG:postgres:submit_query: 0x818a870 PQsendQuery(select table_version from version where table_name='trusted')
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_result: 0x818a870 PQresultStatus(PGRES_TUPLES_OK) PQgetResult(0x82a4648)
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_columns: 1 column(s) returned from the query
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_columns: 0x818a9a0=pkg_malloc(4) RES_NAMES
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_columns: 0x818a9f8=pkg_malloc(4) RES_TYPES
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_columns: 0x818ac50=pkg_malloc(14) RES_NAMES[0]
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818ac50)[0]=[table_version]
Nov 20 11:16:48 [5164] DBG:postgres:pg_convert_rows: 0x818a9e8=pkg_malloc(4) row_buf 1 pointers
Nov 20 11:16:48 [5164] DBG:postgres:pg_convert_rows: 0x818a990=pkg_malloc(8) RES_ROWS 1 rows
Nov 20 11:16:48 [5164] DBG:postgres:pg_convert_rows: PQgetvalue(0x818a870,0,0)=[4]
Nov 20 11:16:48 [5164] DBG:postgres:pg_convert_rows: 0x818a9c8=pkg_malloc(1) row_buf[0]
Nov 20 11:16:48 [5164] DBG:postgres:pg_convert_rows: [0][0] Column[table_version]=[4]
Nov 20 11:16:48 [5164] DBG:postgres:pg_convert_row: 0x818ac68=pkg_malloc(16) ROW_VALUES for 1 columns
Nov 20 11:16:48 [5164] DBG:postgres:pg_convert_row: col[0]
Nov 20 11:16:48 [5164] DBG:postgres:pg_str2val: converting INT [4]
Nov 20 11:16:48 [5164] DBG:postgres:pg_convert_rows: [0][0] Col[table_version] Type[0] Freeing row_buf[0x818a9c8]
Nov 20 11:16:48 [5164] DBG:postgres:pg_convert_rows: 0x818a9c8=pkg_free() row_buf[0]
Nov 20 11:16:48 [5164] DBG:postgres:pg_convert_rows: 0x818a9e8=pkg_free() row_buf
Nov 20 11:16:48 [5164] DBG:postgres:free_query: PQclear(0x82a4648) result set
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab80)[0] -> free(0x818ac50) 'table_version'
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_columns: 0x818ac50=pkg_free() RES_NAMES[0]
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_columns: 0x818a9a0=pkg_free() RES_NAMES
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_columns: 0x818a9f8=pkg_free() RES_TYPES
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_rows: freeing 1 rows
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_rows: row[0]=0x818a990
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_row: 0x818ac68=pkg_free() ROW_VALUES
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_rows: 0x818a990=pkg_free() RES_ROWS
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_result: 0x818ab80=pkg_free() _res
Nov 20 11:16:48 [5164] DBG:postgres:pg_query: 0x818a870 0xbfe328b8 select src_ip,proto,from_pattern,tag from trusted 
Nov 20 11:16:48 [5164] DBG:postgres:submit_query: 0x818a870 PQsendQuery(select src_ip,proto,from_pattern,tag from trusted )
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_result: 0x818a870 PQresultStatus(PGRES_TUPLES_OK) PQgetResult(0x82a4648)
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_columns: 4 column(s) returned from the query
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_columns: 0x818ac68=pkg_malloc(16) RES_NAMES
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_columns: 0x818ac50=pkg_malloc(16) RES_TYPES
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_columns: 0x818a990=pkg_malloc(7) RES_NAMES[0]
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818a990)[0]=[src_ip]
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_columns: 0x818a9f8=pkg_malloc(6) RES_NAMES[1]
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818a9f8)[1]=[proto]
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_columns: 0x818ac20=pkg_malloc(13) RES_NAMES[2]
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818ac20)[2]=[from_pattern]
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_columns: 0x818a9a0=pkg_malloc(4) RES_NAMES[3]
Nov 20 11:16:48 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818a9a0)[3]=[tag]
Nov 20 11:16:48 [5164] DBG:postgres:pg_convert_result: no rows in the query
Nov 20 11:16:48 [5164] DBG:postgres:free_query: PQclear(0x82a4648) result set
Nov 20 11:16:48 [5164] DBG:permissions:reload_trusted_table: number of rows in trusted table: 0
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab80)[0] -> free(0x818a990) 'src_ip'
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_columns: 0x818a990=pkg_free() RES_NAMES[0]
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab80)[1] -> free(0x818a9f8) 'proto'
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_columns: 0x818a9f8=pkg_free() RES_NAMES[1]
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab80)[2] -> free(0x818ac20) 'from_pattern'
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_columns: 0x818ac20=pkg_free() RES_NAMES[2]
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab80)[3] -> free(0x818a9a0) 'tag'
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_columns: 0x818a9a0=pkg_free() RES_NAMES[3]
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_columns: 0x818ac68=pkg_free() RES_NAMES
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_columns: 0x818ac50=pkg_free() RES_TYPES
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_rows: freeing 0 rows
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_result: 0x818ab80=pkg_free() _res
Nov 20 11:16:48 [5164] DBG:permissions:reload_trusted_table: trusted table reloaded successfully.
Nov 20 11:16:48 [5164] DBG:core:pool_remove: removing connection from the pool
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_conn: PQfinish(0x82a7458)
Nov 20 11:16:48 [5164] DBG:postgres:pg_free_conn: pkg_free(0x818a958)
Nov 20 11:16:48 [5164] DBG:postgres:pg_close: 0x818a870=pkg_free() _con
Nov 20 11:16:48 [5164] DBG:core:find_mod_export: found <db_use_table> in module postgres [//lib/openser/modules/]
Nov 20 11:16:48 [5164] DBG:core:find_mod_export: found <db_init> in module postgres [//lib/openser/modules/]
Nov 20 11:16:48 [5164] DBG:core:find_mod_export: found <db_close> in module postgres [//lib/openser/modules/]
Nov 20 11:16:48 [5164] DBG:core:find_mod_export: found <db_query> in module postgres [//lib/openser/modules/]
Nov 20 11:16:48 [5164] DBG:core:find_mod_export: found <db_fetch_result> in module postgres [//lib/openser/modules/]
Nov 20 11:16:48 [5164] DBG:core:find_mod_export: found <db_raw_query> in module postgres [//lib/openser/modules/]
Nov 20 11:16:48 [5164] DBG:core:find_mod_export: found <db_free_result> in module postgres [//lib/openser/modules/]
Nov 20 11:16:48 [5164] DBG:core:find_mod_export: found <db_insert> in module postgres [//lib/openser/modules/]
Nov 20 11:16:48 [5164] DBG:core:find_mod_export: found <db_delete> in module postgres [//lib/openser/modules/]
Nov 20 11:16:48 [5164] DBG:core:find_mod_export: found <db_update> in module postgres [//lib/openser/modules/]
Nov 20 11:16:48 [5164] DBG:core:find_mod_export: <db_replace> in module postgres not found
Nov 20 11:16:48 [5164] DBG:core:find_mod_export: <db_last_inserted_id> in module postgres not found
Nov 20 11:16:48 [5164] DBG:core:find_mod_export: <db_insert_update> in module postgres not found
Nov 20 11:16:48 [5164] DBG:postgres:pg_init: 0x818a870=pkg_malloc(12) for database connection
Nov 20 11:16:48 [5164] DBG:postgres:pg_init: connection 0x818a958 not found in pool
Nov 20 11:16:48 [5164] DBG:postgres:pg_new_conn: db_id = 0x818a958
Nov 20 11:16:48 [5164] DBG:postgres:pg_new_conn: 0x818a888=pkg_malloc(44)
Nov 20 11:16:48 [5164] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:49 [5164] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82a7458)
Nov 20 11:16:49 [5164] DBG:postgres:val2str: PQescapeStringConn: in: 7 chars, out: 7 chars
Nov 20 11:16:49 [5164] DBG:postgres:pg_query: 0x818a870 0xbfe328a0 select table_version from version where table_name='address'
Nov 20 11:16:49 [5164] DBG:postgres:submit_query: 0x818a870 PQsendQuery(select table_version from version where table_name='address')
Nov 20 11:16:49 [5164] DBG:postgres:pg_get_result: 0x818a870 PQresultStatus(PGRES_TUPLES_OK) PQgetResult(0x82a4648)
Nov 20 11:16:49 [5164] DBG:postgres:pg_get_columns: 1 column(s) returned from the query
Nov 20 11:16:49 [5164] DBG:postgres:pg_get_columns: 0x818a9a0=pkg_malloc(4) RES_NAMES
Nov 20 11:16:49 [5164] DBG:postgres:pg_get_columns: 0x818a9f8=pkg_malloc(4) RES_TYPES
Nov 20 11:16:49 [5164] DBG:postgres:pg_get_columns: 0x818ac50=pkg_malloc(14) RES_NAMES[0]
Nov 20 11:16:49 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818ac50)[0]=[table_version]
Nov 20 11:16:49 [5164] DBG:postgres:pg_convert_rows: 0x818a990=pkg_malloc(4) row_buf 1 pointers
Nov 20 11:16:49 [5164] DBG:postgres:pg_convert_rows: 0x818a9e8=pkg_malloc(8) RES_ROWS 1 rows
Nov 20 11:16:49 [5164] DBG:postgres:pg_convert_rows: PQgetvalue(0x818a870,0,0)=[3]
Nov 20 11:16:49 [5164] DBG:postgres:pg_convert_rows: 0x818a9c8=pkg_malloc(1) row_buf[0]
Nov 20 11:16:49 [5164] DBG:postgres:pg_convert_rows: [0][0] Column[table_version]=[3]
Nov 20 11:16:49 [5164] DBG:postgres:pg_convert_row: 0x818ac68=pkg_malloc(16) ROW_VALUES for 1 columns
Nov 20 11:16:49 [5164] DBG:postgres:pg_convert_row: col[0]
Nov 20 11:16:49 [5164] DBG:postgres:pg_str2val: converting INT [3]
Nov 20 11:16:49 [5164] DBG:postgres:pg_convert_rows: [0][0] Col[table_version] Type[0] Freeing row_buf[0x818a9c8]
Nov 20 11:16:49 [5164] DBG:postgres:pg_convert_rows: 0x818a9c8=pkg_free() row_buf[0]
Nov 20 11:16:49 [5164] DBG:postgres:pg_convert_rows: 0x818a990=pkg_free() row_buf
Nov 20 11:16:49 [5164] DBG:postgres:free_query: PQclear(0x82a4648) result set
Nov 20 11:16:49 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab80)[0] -> free(0x818ac50) 'table_version'
Nov 20 11:16:49 [5164] DBG:postgres:pg_free_columns: 0x818ac50=pkg_free() RES_NAMES[0]
Nov 20 11:16:49 [5164] DBG:postgres:pg_free_columns: 0x818a9a0=pkg_free() RES_NAMES
Nov 20 11:16:49 [5164] DBG:postgres:pg_free_columns: 0x818a9f8=pkg_free() RES_TYPES
Nov 20 11:16:49 [5164] DBG:postgres:pg_free_rows: freeing 1 rows
Nov 20 11:16:49 [5164] DBG:postgres:pg_free_rows: row[0]=0x818a9e8
Nov 20 11:16:49 [5164] DBG:postgres:pg_free_row: 0x818ac68=pkg_free() ROW_VALUES
Nov 20 11:16:49 [5164] DBG:postgres:pg_free_rows: 0x818a9e8=pkg_free() RES_ROWS
Nov 20 11:16:49 [5164] DBG:postgres:pg_free_result: 0x818ab80=pkg_free() _res
Nov 20 11:16:49 [5164] DBG:postgres:pg_query: 0x818a870 0xbfe328a8 select grp,ip_addr,mask,port from address 
Nov 20 11:16:49 [5164] DBG:postgres:submit_query: 0x818a870 PQsendQuery(select grp,ip_addr,mask,port from address )
Nov 20 11:16:49 [5164] DBG:postgres:pg_get_result: 0x818a870 PQresultStatus(PGRES_TUPLES_OK) PQgetResult(0x82a4648)
Nov 20 11:16:49 [5164] DBG:postgres:pg_get_columns: 4 column(s) returned from the query
Nov 20 11:16:49 [5164] DBG:postgres:pg_get_columns: 0x818ac68=pkg_malloc(16) RES_NAMES
Nov 20 11:16:49 [5164] DBG:postgres:pg_get_columns: 0x818ac50=pkg_malloc(16) RES_TYPES
Nov 20 11:16:49 [5164] DBG:postgres:pg_get_columns: 0x818a9e8=pkg_malloc(4) RES_NAMES[0]
Nov 20 11:16:49 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818a9e8)[0]=[grp]
Nov 20 11:16:49 [5164] DBG:postgres:pg_get_columns: 0x818a9f8=pkg_malloc(8) RES_NAMES[1]
Nov 20 11:16:49 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818a9f8)[1]=[ip_addr]
Nov 20 11:16:49 [5164] DBG:postgres:pg_get_columns: 0x818a9a0=pkg_malloc(5) RES_NAMES[2]
Nov 20 11:16:49 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818a9a0)[2]=[mask]
Nov 20 11:16:49 [5164] DBG:postgres:pg_get_columns: 0x818a990=pkg_malloc(5) RES_NAMES[3]
Nov 20 11:16:49 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818a990)[3]=[port]
Nov 20 11:16:49 [5164] DBG:postgres:pg_convert_result: no rows in the query
Nov 20 11:16:49 [5164] DBG:postgres:free_query: PQclear(0x82a4648) result set
Nov 20 11:16:49 [5164] DBG:permissions:reload_address_table: Number of rows in address table: 0
Nov 20 11:16:49 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab80)[0] -> free(0x818a9e8) 'grp'
Nov 20 11:16:49 [5164] DBG:postgres:pg_free_columns: 0x818a9e8=pkg_free() RES_NAMES[0]
Nov 20 11:16:49 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab80)[1] -> free(0x818a9f8) 'ip_addr'
Nov 20 11:16:49 [5164] DBG:postgres:pg_free_columns: 0x818a9f8=pkg_free() RES_NAMES[1]
Nov 20 11:16:49 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab80)[2] -> free(0x818a9a0) 'mask'
Nov 20 11:16:49 [5164] DBG:postgres:pg_free_columns: 0x818a9a0=pkg_free() RES_NAMES[2]
Nov 20 11:16:49 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818ab80)[3] -> free(0x818a990) 'port'
Nov 20 11:16:49 [5164] DBG:postgres:pg_free_columns: 0x818a990=pkg_free() RES_NAMES[3]
Nov 20 11:16:49 [5164] DBG:postgres:pg_free_columns: 0x818ac68=pkg_free() RES_NAMES
Nov 20 11:16:49 [5164] DBG:postgres:pg_free_columns: 0x818ac50=pkg_free() RES_TYPES
Nov 20 11:16:49 [5164] DBG:postgres:pg_free_rows: freeing 0 rows
Nov 20 11:16:49 [5164] DBG:postgres:pg_free_result: 0x818ab80=pkg_free() _res
Nov 20 11:16:49 [5164] DBG:permissions:reload_address_table: address table reloaded successfully.
Nov 20 11:16:49 [5164] DBG:core:pool_remove: removing connection from the pool
Nov 20 11:16:49 [5164] DBG:postgres:pg_free_conn: PQfinish(0x82a7458)
Nov 20 11:16:49 [5164] DBG:postgres:pg_free_conn: pkg_free(0x818a888)
Nov 20 11:16:49 [5164] DBG:postgres:pg_close: 0x818a870=pkg_free() _con
Nov 20 11:16:49 [5164] DBG:core:init_mod: register MI for permissions
Nov 20 11:16:49 [5164] DBG:core:count_module_procs: modules require 1 extra processes
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing mf_process_maxfwd_header, line 202
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing sl_send_reply, line 203
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing sl_send_reply, line 208
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing is_uri_host_local, line 214
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing sl_send_reply, line 215
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing has_totag, line 221
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing loose_route, line 224
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing is_method, line 225
Nov 20 11:16:49 [5164] DBG:core:parse_methods: methods 0x8
Nov 20 11:16:49 [5164] DBG:textops:fixup_method: using id for method [BYE/8]
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing is_method, line 231
Nov 20 11:16:49 [5164] DBG:core:parse_methods: methods 0x4
Nov 20 11:16:49 [5164] DBG:textops:fixup_method: using id for method [ACK/4]
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing t_check_trans, line 232
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing t_relay, line 235
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing sl_send_reply, line 242
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing is_method, line 250
Nov 20 11:16:49 [5164] DBG:core:parse_methods: methods 0x2
Nov 20 11:16:49 [5164] DBG:textops:fixup_method: using id for method [CANCEL/2]
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing t_check_trans, line 252
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing t_relay, line 253
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing t_check_trans, line 257
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing is_method, line 260
Nov 20 11:16:49 [5164] DBG:core:parse_methods: methods 0x180
Nov 20 11:16:49 [5164] DBG:textops:fixup_method: using id for methods [REGISTER,MESSAGE/384]
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing record_route, line 261
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing is_method, line 271
Nov 20 11:16:49 [5164] DBG:core:parse_methods: methods 0x80
Nov 20 11:16:49 [5164] DBG:textops:fixup_method: using id for method [REGISTER/128]
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing is_method, line 282
Nov 20 11:16:49 [5164] DBG:core:parse_methods: methods 0x1
Nov 20 11:16:49 [5164] DBG:textops:fixup_method: using id for method [INVITE/1]
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing t_on_branch, line 283
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing t_on_reply, line 284
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing t_on_failure, line 285
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing t_relay, line 288
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing sl_reply_error, line 289
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing is_method, line 295
Nov 20 11:16:49 [5164] DBG:core:parse_methods: methods 0x1
Nov 20 11:16:49 [5164] DBG:textops:fixup_method: using id for method [INVITE/1]
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing t_on_branch, line 296
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing t_on_reply, line 297
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing t_on_failure, line 298
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing t_relay, line 301
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing sl_reply_error, line 302
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing is_from_local, line 313
Nov 20 11:16:49 [5164] DBG:core:fix_actions: fixing www_authorize, line 314
Nov 20 11:16:49 [5164] DBG:postgres:pg_init: 0x818a870=pkg_malloc(12) for database connection
Nov 20 11:16:49 [5164] DBG:postgres:pg_init: connection 0x818a888 not found in pool
Nov 20 11:16:49 [5164] DBG:postgres:pg_new_conn: db_id = 0x818a888
Nov 20 11:16:49 [5164] DBG:postgres:pg_new_conn: 0x818a958=pkg_malloc(44)
Nov 20 11:16:49 [5164] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:50 [5164] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82a7458)
Nov 20 11:16:50 [5164] DBG:postgres:val2str: PQescapeStringConn: in: 10 chars, out: 10 chars
Nov 20 11:16:50 [5164] DBG:postgres:pg_query: 0x818a870 0xbfe32650 select table_version from version where table_name='subscriber'
Nov 20 11:16:50 [5164] DBG:postgres:submit_query: 0x818a870 PQsendQuery(select table_version from version where table_name='subscriber')
Nov 20 11:16:50 [5164] DBG:postgres:pg_get_result: 0x818a870 PQresultStatus(PGRES_TUPLES_OK) PQgetResult(0x82a4648)
Nov 20 11:16:50 [5164] DBG:postgres:pg_get_columns: 1 column(s) returned from the query
Nov 20 11:16:50 [5164] DBG:postgres:pg_get_columns: 0x818ac68=pkg_malloc(4) RES_NAMES
Nov 20 11:16:50 [5164] DBG:postgres:pg_get_columns: 0x818ac20=pkg_malloc(4) RES_TYPES
Nov 20 11:16:50 [5164] DBG:postgres:pg_get_columns: 0x818ac38=pkg_malloc(14) RES_NAMES[0]
Nov 20 11:16:50 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818ac38)[0]=[table_version]
Nov 20 11:16:50 [5164] DBG:postgres:pg_convert_rows: 0x818ac08=pkg_malloc(4) row_buf 1 pointers
Nov 20 11:16:50 [5164] DBG:postgres:pg_convert_rows: 0x818abf0=pkg_malloc(8) RES_ROWS 1 rows
Nov 20 11:16:50 [5164] DBG:postgres:pg_convert_rows: PQgetvalue(0x818a870,0,0)=[6]
Nov 20 11:16:50 [5164] DBG:postgres:pg_convert_rows: 0x818abd8=pkg_malloc(1) row_buf[0]
Nov 20 11:16:50 [5164] DBG:postgres:pg_convert_rows: [0][0] Column[table_version]=[6]
Nov 20 11:16:50 [5164] DBG:postgres:pg_convert_row: 0x818abc0=pkg_malloc(16) ROW_VALUES for 1 columns
Nov 20 11:16:50 [5164] DBG:postgres:pg_convert_row: col[0]
Nov 20 11:16:50 [5164] DBG:postgres:pg_str2val: converting INT [6]
Nov 20 11:16:50 [5164] DBG:postgres:pg_convert_rows: [0][0] Col[table_version] Type[0] Freeing row_buf[0x818abd8]
Nov 20 11:16:50 [5164] DBG:postgres:pg_convert_rows: 0x818abd8=pkg_free() row_buf[0]
Nov 20 11:16:50 [5164] DBG:postgres:pg_convert_rows: 0x818ac08=pkg_free() row_buf
Nov 20 11:16:50 [5164] DBG:postgres:free_query: PQclear(0x82a4648) result set
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x8183248)[0] -> free(0x818ac38) 'table_version'
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_columns: 0x818ac38=pkg_free() RES_NAMES[0]
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_columns: 0x818ac68=pkg_free() RES_NAMES
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_columns: 0x818ac20=pkg_free() RES_TYPES
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_rows: freeing 1 rows
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_rows: row[0]=0x818abf0
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_row: 0x818abc0=pkg_free() ROW_VALUES
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_rows: 0x818abf0=pkg_free() RES_ROWS
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_result: 0x8183248=pkg_free() _res
Nov 20 11:16:50 [5164] DBG:core:pool_remove: removing connection from the pool
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_conn: PQfinish(0x82a7458)
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_conn: pkg_free(0x818a958)
Nov 20 11:16:50 [5164] DBG:postgres:pg_close: 0x818a870=pkg_free() _con
Nov 20 11:16:50 [5164] DBG:core:fix_actions: fixing www_challenge, line 315
Nov 20 11:16:50 [5164] DBG:core:fix_actions: fixing save, line 325
Nov 20 11:16:50 [5164] DBG:postgres:pg_init: 0x818a870=pkg_malloc(12) for database connection
Nov 20 11:16:50 [5164] DBG:postgres:pg_init: connection 0x8183248 not found in pool
Nov 20 11:16:50 [5164] DBG:postgres:pg_new_conn: db_id = 0x8183248
Nov 20 11:16:50 [5164] DBG:postgres:pg_new_conn: 0x818a958=pkg_malloc(44)
Nov 20 11:16:50 [5164] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:50 [5164] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82a7458)
Nov 20 11:16:50 [5164] DBG:postgres:val2str: PQescapeStringConn: in: 8 chars, out: 8 chars
Nov 20 11:16:50 [5164] DBG:postgres:pg_query: 0x818a870 0xbfe32730 select table_version from version where table_name='location'
Nov 20 11:16:50 [5164] DBG:postgres:submit_query: 0x818a870 PQsendQuery(select table_version from version where table_name='location')
Nov 20 11:16:50 [5164] DBG:postgres:pg_get_result: 0x818a870 PQresultStatus(PGRES_TUPLES_OK) PQgetResult(0x82a4648)
Nov 20 11:16:50 [5164] DBG:postgres:pg_get_columns: 1 column(s) returned from the query
Nov 20 11:16:50 [5164] DBG:postgres:pg_get_columns: 0x818abf0=pkg_malloc(4) RES_NAMES
Nov 20 11:16:50 [5164] DBG:postgres:pg_get_columns: 0x818abc0=pkg_malloc(4) RES_TYPES
Nov 20 11:16:50 [5164] DBG:postgres:pg_get_columns: 0x818ac20=pkg_malloc(14) RES_NAMES[0]
Nov 20 11:16:50 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818ac20)[0]=[table_version]
Nov 20 11:16:50 [5164] DBG:postgres:pg_convert_rows: 0x818ac68=pkg_malloc(4) row_buf 1 pointers
Nov 20 11:16:50 [5164] DBG:postgres:pg_convert_rows: 0x818ac38=pkg_malloc(8) RES_ROWS 1 rows
Nov 20 11:16:50 [5164] DBG:postgres:pg_convert_rows: PQgetvalue(0x818a870,0,0)=[1004]
Nov 20 11:16:50 [5164] DBG:postgres:pg_convert_rows: 0x818ac08=pkg_malloc(4) row_buf[0]
Nov 20 11:16:50 [5164] DBG:postgres:pg_convert_rows: [0][0] Column[table_version]=[1004]
Nov 20 11:16:50 [5164] DBG:postgres:pg_convert_row: 0x818abd8=pkg_malloc(16) ROW_VALUES for 1 columns
Nov 20 11:16:50 [5164] DBG:postgres:pg_convert_row: col[0]
Nov 20 11:16:50 [5164] DBG:postgres:pg_str2val: converting INT [1004]
Nov 20 11:16:50 [5164] DBG:postgres:pg_convert_rows: [0][0] Col[table_version] Type[0] Freeing row_buf[0x818ac08]
Nov 20 11:16:50 [5164] DBG:postgres:pg_convert_rows: 0x818ac08=pkg_free() row_buf[0]
Nov 20 11:16:50 [5164] DBG:postgres:pg_convert_rows: 0x818ac68=pkg_free() row_buf
Nov 20 11:16:50 [5164] DBG:postgres:free_query: PQclear(0x82a4648) result set
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818a888)[0] -> free(0x818ac20) 'table_version'
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_columns: 0x818ac20=pkg_free() RES_NAMES[0]
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_columns: 0x818abf0=pkg_free() RES_NAMES
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_columns: 0x818abc0=pkg_free() RES_TYPES
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_rows: freeing 1 rows
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_rows: row[0]=0x818ac38
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_row: 0x818abd8=pkg_free() ROW_VALUES
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_rows: 0x818ac38=pkg_free() RES_ROWS
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_result: 0x818a888=pkg_free() _res
Nov 20 11:16:50 [5164] DBG:postgres:val2str: PQescapeStringConn: in: 10 chars, out: 10 chars
Nov 20 11:16:50 [5164] DBG:postgres:pg_query: 0x818a870 0xbfe32730 select username from location where username='dummy_user'
Nov 20 11:16:50 [5164] DBG:postgres:submit_query: 0x818a870 PQsendQuery(select username from location where username='dummy_user')
Nov 20 11:16:50 [5164] DBG:postgres:pg_get_result: 0x818a870 PQresultStatus(PGRES_TUPLES_OK) PQgetResult(0x82a4648)
Nov 20 11:16:50 [5164] DBG:postgres:pg_get_columns: 1 column(s) returned from the query
Nov 20 11:16:50 [5164] DBG:postgres:pg_get_columns: 0x818ac38=pkg_malloc(4) RES_NAMES
Nov 20 11:16:50 [5164] DBG:postgres:pg_get_columns: 0x818abd8=pkg_malloc(4) RES_TYPES
Nov 20 11:16:50 [5164] DBG:postgres:pg_get_columns: 0x818abc0=pkg_malloc(9) RES_NAMES[0]
Nov 20 11:16:50 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818abc0)[0]=[username]
Nov 20 11:16:50 [5164] DBG:postgres:pg_convert_result: no rows in the query
Nov 20 11:16:50 [5164] DBG:postgres:free_query: PQclear(0x82a4648) result set
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x818a888)[0] -> free(0x818abc0) 'username'
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_columns: 0x818abc0=pkg_free() RES_NAMES[0]
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_columns: 0x818ac38=pkg_free() RES_NAMES
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_columns: 0x818abd8=pkg_free() RES_TYPES
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_rows: freeing 0 rows
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_result: 0x818a888=pkg_free() _res
Nov 20 11:16:50 [5164] DBG:core:pool_remove: removing connection from the pool
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_conn: PQfinish(0x82a7458)
Nov 20 11:16:50 [5164] DBG:postgres:pg_free_conn: pkg_free(0x818a958)
Nov 20 11:16:50 [5164] DBG:postgres:pg_close: 0x818a870=pkg_free() _con
Nov 20 11:16:50 [5164] DBG:core:fix_actions: fixing sl_send_reply, line 328
Nov 20 11:16:50 [5164] DBG:core:fix_actions: fixing is_from_local, line 337
Nov 20 11:16:50 [5164] DBG:core:fix_actions: fixing allow_trusted, line 338
Nov 20 11:16:50 [5164] DBG:core:fix_actions: fixing sl_send_reply, line 339
Nov 20 11:16:50 [5164] DBG:core:fix_actions: fixing is_method, line 344
Nov 20 11:16:50 [5164] DBG:core:parse_methods: methods 0x1
Nov 20 11:16:50 [5164] DBG:textops:fixup_method: using id for method [INVITE/1]
Nov 20 11:16:50 [5164] DBG:core:fix_actions: fixing append_hf, line 357
Nov 20 11:16:50 [5164] DBG:core:fix_actions: fixing append_hf, line 360
Nov 20 11:16:50 [5164] DBG:core:fix_actions: fixing xlog, line 368
Nov 20 11:16:50 [5164] DBG:core:fix_actions: fixing proxy_authorize, line 370
Nov 20 11:16:50 [5164] DBG:postgres:pg_init: 0x818a870=pkg_malloc(12) for database connection
Nov 20 11:16:50 [5164] DBG:postgres:pg_init: connection 0x818a958 not found in pool
Nov 20 11:16:50 [5164] DBG:postgres:pg_new_conn: db_id = 0x818a958
Nov 20 11:16:50 [5164] DBG:postgres:pg_new_conn: 0x818a888=pkg_malloc(44)
Nov 20 11:16:50 [5164] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:51 [5164] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82a7458)
Nov 20 11:16:51 [5164] DBG:postgres:val2str: PQescapeStringConn: in: 10 chars, out: 10 chars
Nov 20 11:16:51 [5164] DBG:postgres:pg_query: 0x818a870 0xbfe32730 select table_version from version where table_name='subscriber'
Nov 20 11:16:51 [5164] DBG:postgres:submit_query: 0x818a870 PQsendQuery(select table_version from version where table_name='subscriber')
Nov 20 11:16:51 [5164] DBG:postgres:pg_get_result: 0x818a870 PQresultStatus(PGRES_TUPLES_OK) PQgetResult(0x82a4648)
Nov 20 11:16:51 [5164] DBG:postgres:pg_get_columns: 1 column(s) returned from the query
Nov 20 11:16:51 [5164] DBG:postgres:pg_get_columns: 0x818ac38=pkg_malloc(4) RES_NAMES
Nov 20 11:16:51 [5164] DBG:postgres:pg_get_columns: 0x818abc0=pkg_malloc(4) RES_TYPES
Nov 20 11:16:51 [5164] DBG:postgres:pg_get_columns: 0x818abf0=pkg_malloc(14) RES_NAMES[0]
Nov 20 11:16:51 [5164] DBG:postgres:pg_get_columns: RES_NAMES(0x818abf0)[0]=[table_version]
Nov 20 11:16:51 [5164] DBG:postgres:pg_convert_rows: 0x818ac20=pkg_malloc(4) row_buf 1 pointers
Nov 20 11:16:51 [5164] DBG:postgres:pg_convert_rows: 0x818ac68=pkg_malloc(8) RES_ROWS 1 rows
Nov 20 11:16:51 [5164] DBG:postgres:pg_convert_rows: PQgetvalue(0x818a870,0,0)=[6]
Nov 20 11:16:51 [5164] DBG:postgres:pg_convert_rows: 0x818ac08=pkg_malloc(1) row_buf[0]
Nov 20 11:16:51 [5164] DBG:postgres:pg_convert_rows: [0][0] Column[table_version]=[6]
Nov 20 11:16:51 [5164] DBG:postgres:pg_convert_row: 0x818aa08=pkg_malloc(16) ROW_VALUES for 1 columns
Nov 20 11:16:51 [5164] DBG:postgres:pg_convert_row: col[0]
Nov 20 11:16:51 [5164] DBG:postgres:pg_str2val: converting INT [6]
Nov 20 11:16:51 [5164] DBG:postgres:pg_convert_rows: [0][0] Col[table_version] Type[0] Freeing row_buf[0x818ac08]
Nov 20 11:16:51 [5164] DBG:postgres:pg_convert_rows: 0x818ac08=pkg_free() row_buf[0]
Nov 20 11:16:51 [5164] DBG:postgres:pg_convert_rows: 0x818ac20=pkg_free() row_buf
Nov 20 11:16:51 [5164] DBG:postgres:free_query: PQclear(0x82a4648) result set
Nov 20 11:16:51 [5164] DBG:postgres:pg_free_columns: Freeing RES_NAMES(0x8182f78)[0] -> free(0x818abf0) 'table_version'
Nov 20 11:16:51 [5164] DBG:postgres:pg_free_columns: 0x818abf0=pkg_free() RES_NAMES[0]
Nov 20 11:16:51 [5164] DBG:postgres:pg_free_columns: 0x818ac38=pkg_free() RES_NAMES
Nov 20 11:16:51 [5164] DBG:postgres:pg_free_columns: 0x818abc0=pkg_free() RES_TYPES
Nov 20 11:16:51 [5164] DBG:postgres:pg_free_rows: freeing 1 rows
Nov 20 11:16:51 [5164] DBG:postgres:pg_free_rows: row[0]=0x818ac68
Nov 20 11:16:51 [5164] DBG:postgres:pg_free_row: 0x818aa08=pkg_free() ROW_VALUES
Nov 20 11:16:51 [5164] DBG:postgres:pg_free_rows: 0x818ac68=pkg_free() RES_ROWS
Nov 20 11:16:51 [5164] DBG:postgres:pg_free_result: 0x8182f78=pkg_free() _res
Nov 20 11:16:51 [5164] DBG:core:pool_remove: removing connection from the pool
Nov 20 11:16:51 [5164] DBG:postgres:pg_free_conn: PQfinish(0x82a7458)
Nov 20 11:16:51 [5164] DBG:postgres:pg_free_conn: pkg_free(0x818a888)
Nov 20 11:16:51 [5164] DBG:postgres:pg_close: 0x818a870=pkg_free() _con
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing proxy_challenge, line 371
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing consume_credentials, line 375
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing prefix2domain, line 377
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing xlog, line 381
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing append_hf, line 385
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing append_hf, line 388
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing allow_routing, line 389
Nov 20 11:16:51 [5164] DBG:permissions:load_fixup: file (//etc/openser/permissions.allow) already loaded, re-using
Nov 20 11:16:51 [5164] DBG:permissions:load_fixup: file (//etc/openser/permissions.deny) already loaded, re-using
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing xlog, line 400
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing sl_send_reply, line 406
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing append_hf, line 410
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing xlog, line 417
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing load_gws, line 424
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing sl_send_reply, line 425
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing next_gw, line 429
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing sl_send_reply, line 430
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing append_hf, line 434
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing xlog, line 442
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing avp_db_load, line 446
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing sl_send_reply, line 447
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing avp_pushto, line 455
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing sl_send_reply, line 456
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing alias_db_lookup, line 462
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing lookup, line 464
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing sl_send_reply, line 465
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing avp_pushto, line 471
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing sl_send_reply, line 472
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing append_hf, line 478
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing xlog, line 481
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing xlog, line 491
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing sl_send_reply, line 492
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing xlog, line 504
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing t_was_cancelled, line 509
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing next_gw, line 517
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing t_reply, line 518
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing t_on_failure, line 522
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing t_relay, line 523
Nov 20 11:16:51 [5164] DBG:core:fix_actions: fixing xlog, line 499
Nov 20 11:16:51 [5164] DBG:core:probe_max_receive_buffer: getsockopt SO_RCVBUF is initially 109568
Nov 20 11:16:51 [5164] DBG:core:probe_max_receive_buffer: trying SO_RCVBUF: 219136
Nov 20 11:16:51 [5164] DBG:core:probe_max_receive_buffer: setting SO_RCVBUF; set=219136,verify=219136
Nov 20 11:16:51 [5164] DBG:core:probe_max_receive_buffer: trying SO_RCVBUF: 221184
Nov 20 11:16:51 [5164] DBG:core:probe_max_receive_buffer: setting SO_RCVBUF; set=221184,verify=219136
Nov 20 11:16:51 [5164] DBG:core:probe_max_receive_buffer: setting SO_RCVBUF has no effect
Nov 20 11:16:51 [5164] INFO:core:probe_max_receive_buffer: using a UDP receive buffer of 214 kb
Nov 20 11:16:51 [5164] DBG:core:probe_max_receive_buffer: getsockopt SO_RCVBUF is initially 109568
Nov 20 11:16:51 [5164] DBG:core:probe_max_receive_buffer: trying SO_RCVBUF: 219136
Nov 20 11:16:51 [5164] DBG:core:probe_max_receive_buffer: setting SO_RCVBUF; set=219136,verify=219136
Nov 20 11:16:51 [5164] DBG:core:probe_max_receive_buffer: trying SO_RCVBUF: 221184
Nov 20 11:16:51 [5164] DBG:core:probe_max_receive_buffer: setting SO_RCVBUF; set=221184,verify=219136
Nov 20 11:16:51 [5164] DBG:core:probe_max_receive_buffer: setting SO_RCVBUF has no effect
Nov 20 11:16:51 [5164] INFO:core:probe_max_receive_buffer: using a UDP receive buffer of 214 kb
Nov 20 11:16:51 [5164] DBG:core:probe_max_receive_buffer: getsockopt SO_RCVBUF is initially 109568
Nov 20 11:16:51 [5164] DBG:core:probe_max_receive_buffer: trying SO_RCVBUF: 219136
Nov 20 11:16:51 [5164] DBG:core:probe_max_receive_buffer: setting SO_RCVBUF; set=219136,verify=219136
Nov 20 11:16:51 [5164] DBG:core:probe_max_receive_buffer: trying SO_RCVBUF: 221184
Nov 20 11:16:51 [5164] DBG:core:probe_max_receive_buffer: setting SO_RCVBUF; set=221184,verify=219136
Nov 20 11:16:51 [5164] DBG:core:probe_max_receive_buffer: setting SO_RCVBUF has no effect
Nov 20 11:16:51 [5164] INFO:core:probe_max_receive_buffer: using a UDP receive buffer of 214 kb
Nov 20 11:16:51 [5164] DBG:core:openser_fork: forking new process "UDP receiver"
Nov 20 11:16:51 [5177] DBG:core:init_mod_child: type=CHILD, rank=1, module=tm
Nov 20 11:16:51 [5177] DBG:tm:child_init_callid: callid: '79dddf19-5177 at 127.0.0.1'
Nov 20 11:16:51 [5177] DBG:core:init_mod_child: type=CHILD, rank=1, module=usrloc
Nov 20 11:16:51 [5177] DBG:postgres:pg_init: 0x818abd8=pkg_malloc(12) for database connection
Nov 20 11:16:51 [5177] DBG:postgres:pg_init: connection 0x8182f78 not found in pool
Nov 20 11:16:51 [5177] DBG:postgres:pg_new_conn: db_id = 0x8182f78
Nov 20 11:16:51 [5177] DBG:postgres:pg_new_conn: 0x818a888=pkg_malloc(44)
Nov 20 11:16:51 [5177] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:51 [5164] DBG:core:openser_fork: forking new process "UDP receiver"
Nov 20 11:16:51 [5179] DBG:core:init_mod_child: type=CHILD, rank=2, module=tm
Nov 20 11:16:51 [5179] DBG:tm:child_init_callid: callid: '79dddf19-5179 at 127.0.0.1'
Nov 20 11:16:51 [5179] DBG:core:init_mod_child: type=CHILD, rank=2, module=usrloc
Nov 20 11:16:51 [5179] DBG:core:init_mod_child: type=CHILD, rank=2, module=registrar
Nov 20 11:16:51 [5179] DBG:core:init_mod_child: type=CHILD, rank=2, module=mi_fifo
Nov 20 11:16:51 [5179] DBG:core:init_mod_child: type=CHILD, rank=2, module=uri_db
Nov 20 11:16:51 [5179] DBG:postgres:pg_init: 0x818abd8=pkg_malloc(12) for database connection
Nov 20 11:16:51 [5179] DBG:postgres:pg_init: connection 0x8182f78 not found in pool
Nov 20 11:16:51 [5179] DBG:postgres:pg_new_conn: db_id = 0x8182f78
Nov 20 11:16:51 [5179] DBG:postgres:pg_new_conn: 0x818a888=pkg_malloc(44)
Nov 20 11:16:51 [5179] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:51 [5164] DBG:core:openser_fork: forking new process "UDP receiver"
Nov 20 11:16:51 [5181] DBG:core:init_mod_child: type=CHILD, rank=3, module=tm
Nov 20 11:16:51 [5181] DBG:tm:child_init_callid: callid: '79dddf19-5181 at 127.0.0.1'
Nov 20 11:16:51 [5181] DBG:core:init_mod_child: type=CHILD, rank=3, module=usrloc
Nov 20 11:16:51 [5181] DBG:core:init_mod_child: type=CHILD, rank=3, module=registrar
Nov 20 11:16:51 [5181] DBG:core:init_mod_child: type=CHILD, rank=3, module=mi_fifo
Nov 20 11:16:51 [5181] DBG:core:init_mod_child: type=CHILD, rank=3, module=uri_db
Nov 20 11:16:51 [5181] DBG:postgres:pg_init: 0x818abd8=pkg_malloc(12) for database connection
Nov 20 11:16:51 [5181] DBG:postgres:pg_init: connection 0x8182f78 not found in pool
Nov 20 11:16:51 [5181] DBG:postgres:pg_new_conn: db_id = 0x8182f78
Nov 20 11:16:51 [5181] DBG:postgres:pg_new_conn: 0x818a888=pkg_malloc(44)
Nov 20 11:16:51 [5181] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:51 [5164] DBG:core:openser_fork: forking new process "UDP receiver"
Nov 20 11:16:51 [5183] DBG:core:init_mod_child: type=CHILD, rank=4, module=tm
Nov 20 11:16:51 [5183] DBG:tm:child_init_callid: callid: '79dddf19-5183 at 127.0.0.1'
Nov 20 11:16:51 [5183] DBG:core:init_mod_child: type=CHILD, rank=4, module=usrloc
Nov 20 11:16:51 [5164] DBG:core:openser_fork: forking new process "UDP receiver"
Nov 20 11:16:51 [5183] DBG:core:init_mod_child: type=CHILD, rank=4, module=registrar
Nov 20 11:16:52 [5183] DBG:core:init_mod_child: type=CHILD, rank=4, module=mi_fifo
Nov 20 11:16:52 [5183] DBG:core:init_mod_child: type=CHILD, rank=4, module=uri_db
Nov 20 11:16:52 [5183] DBG:postgres:pg_init: 0x818abd8=pkg_malloc(12) for database connection
Nov 20 11:16:52 [5183] DBG:postgres:pg_init: connection 0x8182f78 not found in pool
Nov 20 11:16:52 [5183] DBG:postgres:pg_new_conn: db_id = 0x8182f78
Nov 20 11:16:52 [5183] DBG:postgres:pg_new_conn: 0x818a888=pkg_malloc(44)
Nov 20 11:16:52 [5183] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:52 [5184] DBG:core:init_mod_child: type=CHILD, rank=5, module=tm
Nov 20 11:16:52 [5184] DBG:tm:child_init_callid: callid: '79dddf19-5184 at 172.25.18.162'
Nov 20 11:16:52 [5184] DBG:core:init_mod_child: type=CHILD, rank=5, module=usrloc
Nov 20 11:16:52 [5184] DBG:core:init_mod_child: type=CHILD, rank=5, module=registrar
Nov 20 11:16:52 [5184] DBG:core:init_mod_child: type=CHILD, rank=5, module=mi_fifo
Nov 20 11:16:52 [5164] DBG:core:openser_fork: forking new process "UDP receiver"
Nov 20 11:16:52 [5186] DBG:core:init_mod_child: type=CHILD, rank=6, module=tm
Nov 20 11:16:52 [5186] DBG:tm:child_init_callid: callid: '79dddf19-5186 at 172.25.18.162'
Nov 20 11:16:52 [5186] DBG:core:init_mod_child: type=CHILD, rank=6, module=usrloc
Nov 20 11:16:52 [5186] DBG:core:init_mod_child: type=CHILD, rank=6, module=registrar
Nov 20 11:16:52 [5186] DBG:core:init_mod_child: type=CHILD, rank=6, module=mi_fifo
Nov 20 11:16:52 [5186] DBG:core:init_mod_child: type=CHILD, rank=6, module=uri_db
Nov 20 11:16:52 [5186] DBG:postgres:pg_init: 0x818abd8=pkg_malloc(12) for database connection
Nov 20 11:16:52 [5186] DBG:postgres:pg_init: connection 0x8182f78 not found in pool
Nov 20 11:16:52 [5186] DBG:postgres:pg_new_conn: db_id = 0x8182f78
Nov 20 11:16:52 [5186] DBG:postgres:pg_new_conn: 0x818a888=pkg_malloc(44)
Nov 20 11:16:52 [5186] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:52 [5164] DBG:core:openser_fork: forking new process "UDP receiver"
Nov 20 11:16:52 [5188] DBG:core:init_mod_child: type=CHILD, rank=7, module=tm
Nov 20 11:16:52 [5188] DBG:tm:child_init_callid: callid: '79dddf19-5188 at 172.25.18.162'
Nov 20 11:16:52 [5188] DBG:core:init_mod_child: type=CHILD, rank=7, module=usrloc
Nov 20 11:16:52 [5188] DBG:core:init_mod_child: type=CHILD, rank=7, module=registrar
Nov 20 11:16:52 [5188] DBG:core:init_mod_child: type=CHILD, rank=7, module=mi_fifo
Nov 20 11:16:52 [5188] DBG:core:init_mod_child: type=CHILD, rank=7, module=uri_db
Nov 20 11:16:52 [5188] DBG:postgres:pg_init: 0x818abd8=pkg_malloc(12) for database connection
Nov 20 11:16:52 [5188] DBG:postgres:pg_init: connection 0x8182f78 not found in pool
Nov 20 11:16:52 [5188] DBG:postgres:pg_new_conn: db_id = 0x8182f78
Nov 20 11:16:52 [5188] DBG:postgres:pg_new_conn: 0x818a888=pkg_malloc(44)
Nov 20 11:16:52 [5188] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:52 [5164] DBG:core:openser_fork: forking new process "UDP receiver"
Nov 20 11:16:52 [5190] DBG:core:init_mod_child: type=CHILD, rank=8, module=tm
Nov 20 11:16:52 [5190] DBG:tm:child_init_callid: callid: '79dddf19-5190 at 172.25.18.162'
Nov 20 11:16:52 [5190] DBG:core:init_mod_child: type=CHILD, rank=8, module=usrloc
Nov 20 11:16:52 [5190] DBG:core:init_mod_child: type=CHILD, rank=8, module=registrar
Nov 20 11:16:52 [5190] DBG:core:init_mod_child: type=CHILD, rank=8, module=mi_fifo
Nov 20 11:16:52 [5190] DBG:core:init_mod_child: type=CHILD, rank=8, module=uri_db
Nov 20 11:16:52 [5190] DBG:postgres:pg_init: 0x818abd8=pkg_malloc(12) for database connection
Nov 20 11:16:52 [5190] DBG:postgres:pg_init: connection 0x8182f78 not found in pool
Nov 20 11:16:52 [5190] DBG:postgres:pg_new_conn: db_id = 0x8182f78
Nov 20 11:16:52 [5190] DBG:postgres:pg_new_conn: 0x818a888=pkg_malloc(44)
Nov 20 11:16:52 [5190] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:52 [5164] DBG:core:openser_fork: forking new process "UDP receiver"
Nov 20 11:16:52 [5191] DBG:core:init_mod_child: type=CHILD, rank=9, module=tm
Nov 20 11:16:52 [5191] DBG:tm:child_init_callid: callid: '79dddf19-5191 at 172.25.18.163'
Nov 20 11:16:52 [5191] DBG:core:init_mod_child: type=CHILD, rank=9, module=usrloc
Nov 20 11:16:52 [5191] DBG:core:init_mod_child: type=CHILD, rank=9, module=registrar
Nov 20 11:16:52 [5191] DBG:core:init_mod_child: type=CHILD, rank=9, module=mi_fifo
Nov 20 11:16:52 [5191] DBG:core:init_mod_child: type=CHILD, rank=9, module=uri_db
Nov 20 11:16:52 [5191] DBG:postgres:pg_init: 0x818abd8=pkg_malloc(12) for database connection
Nov 20 11:16:52 [5191] DBG:postgres:pg_init: connection 0x8182f78 not found in pool
Nov 20 11:16:52 [5191] DBG:postgres:pg_new_conn: db_id = 0x8182f78
Nov 20 11:16:52 [5191] DBG:postgres:pg_new_conn: 0x818a888=pkg_malloc(44)
Nov 20 11:16:52 [5191] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:52 [5164] DBG:core:openser_fork: forking new process "UDP receiver"
Nov 20 11:16:53 [5184] DBG:core:init_mod_child: type=CHILD, rank=5, module=uri_db
Nov 20 11:16:53 [5184] DBG:postgres:pg_init: 0x818abd8=pkg_malloc(12) for database connection
Nov 20 11:16:53 [5184] DBG:postgres:pg_init: connection 0x8182f78 not found in pool
Nov 20 11:16:53 [5184] DBG:postgres:pg_new_conn: db_id = 0x8182f78
Nov 20 11:16:53 [5184] DBG:postgres:pg_new_conn: 0x818a888=pkg_malloc(44)
Nov 20 11:16:53 [5184] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:54 [5193] DBG:core:init_mod_child: type=CHILD, rank=10, module=tm
Nov 20 11:16:54 [5193] DBG:tm:child_init_callid: callid: '79dddf19-5193 at 172.25.18.163'
Nov 20 11:16:54 [5193] DBG:core:init_mod_child: type=CHILD, rank=10, module=usrloc
Nov 20 11:16:54 [5193] DBG:core:init_mod_child: type=CHILD, rank=10, module=registrar
Nov 20 11:16:54 [5193] DBG:core:init_mod_child: type=CHILD, rank=10, module=mi_fifo
Nov 20 11:16:54 [5193] DBG:core:init_mod_child: type=CHILD, rank=10, module=uri_db
Nov 20 11:16:54 [5193] DBG:postgres:pg_init: 0x818abd8=pkg_malloc(12) for database connection
Nov 20 11:16:54 [5193] DBG:postgres:pg_init: connection 0x8182f78 not found in pool
Nov 20 11:16:54 [5193] DBG:postgres:pg_new_conn: db_id = 0x8182f78
Nov 20 11:16:54 [5193] DBG:postgres:pg_new_conn: 0x818a888=pkg_malloc(44)
Nov 20 11:16:54 [5193] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:54 [5164] DBG:core:openser_fork: forking new process "UDP receiver"
Nov 20 11:16:54 [5197] DBG:core:init_mod_child: type=CHILD, rank=11, module=tm
Nov 20 11:16:54 [5197] DBG:tm:child_init_callid: callid: '79dddf19-5197 at 172.25.18.163'
Nov 20 11:16:54 [5197] DBG:core:init_mod_child: type=CHILD, rank=11, module=usrloc
Nov 20 11:16:54 [5197] DBG:core:init_mod_child: type=CHILD, rank=11, module=registrar
Nov 20 11:16:54 [5197] DBG:core:init_mod_child: type=CHILD, rank=11, module=mi_fifo
Nov 20 11:16:54 [5197] DBG:core:init_mod_child: type=CHILD, rank=11, module=uri_db
Nov 20 11:16:54 [5197] DBG:postgres:pg_init: 0x818abd8=pkg_malloc(12) for database connection
Nov 20 11:16:54 [5197] DBG:postgres:pg_init: connection 0x8182f78 not found in pool
Nov 20 11:16:54 [5197] DBG:postgres:pg_new_conn: db_id = 0x8182f78
Nov 20 11:16:54 [5197] DBG:postgres:pg_new_conn: 0x818a888=pkg_malloc(44)
Nov 20 11:16:54 [5197] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:55 [5164] DBG:core:openser_fork: forking new process "UDP receiver"
Nov 20 11:16:55 [5199] DBG:core:init_mod_child: type=CHILD, rank=12, module=tm
Nov 20 11:16:55 [5199] DBG:tm:child_init_callid: callid: '79dddf19-5199 at 172.25.18.163'
Nov 20 11:16:55 [5199] DBG:core:init_mod_child: type=CHILD, rank=12, module=usrloc
Nov 20 11:16:55 [5199] DBG:core:init_mod_child: type=CHILD, rank=12, module=registrar
Nov 20 11:16:55 [5199] DBG:core:init_mod_child: type=CHILD, rank=12, module=mi_fifo
Nov 20 11:16:55 [5199] DBG:core:init_mod_child: type=CHILD, rank=12, module=uri_db
Nov 20 11:16:55 [5199] DBG:postgres:pg_init: 0x818abd8=pkg_malloc(12) for database connection
Nov 20 11:16:55 [5199] DBG:postgres:pg_init: connection 0x8182f78 not found in pool
Nov 20 11:16:55 [5199] DBG:postgres:pg_new_conn: db_id = 0x8182f78
Nov 20 11:16:55 [5199] DBG:postgres:pg_new_conn: 0x818a888=pkg_malloc(44)
Nov 20 11:16:55 [5199] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:55 [5164] DBG:core:openser_fork: forking new process "timer"
Nov 20 11:16:55 [5200] DBG:core:init_mod_child: type=PROC_TIMER, rank=-1, module=tm
Nov 20 11:16:55 [5200] DBG:tm:child_init_callid: callid: '79dddf19-5200 at 127.0.0.1'
Nov 20 11:16:55 [5200] DBG:core:init_mod_child: type=PROC_TIMER, rank=-1, module=usrloc
Nov 20 11:16:55 [5200] DBG:postgres:pg_init: 0x818abd8=pkg_malloc(12) for database connection
Nov 20 11:16:55 [5200] DBG:postgres:pg_init: connection 0x8182f78 not found in pool
Nov 20 11:16:55 [5200] DBG:postgres:pg_new_conn: db_id = 0x8182f78
Nov 20 11:16:55 [5200] DBG:postgres:pg_new_conn: 0x818a888=pkg_malloc(44)
Nov 20 11:16:55 [5200] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:55 [5164] DBG:core:start_module_procs: forking process "MI FIFO"/0 for module mi_fifo
Nov 20 11:16:55 [5164] DBG:core:openser_fork: forking new process "MI FIFO"
Nov 20 11:16:55 [5177] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82a7458)
Nov 20 11:16:55 [5177] DBG:postgres:pg_query: 0x818abd8 (nil) select username,contact,expires,q,callid,cseq,flags,cflags,user_agent,received,path,socket,methods,last_modified,domain from location 
Nov 20 11:16:55 [5177] DBG:postgres:submit_query: 0x818abd8 PQsendQuery(select username,contact,expires,q,callid,cseq,flags,cflags,user_agent,received,path,socket,methods,last_modified,domain from location )
Nov 20 11:16:55 [5177] DBG:postgres:pg_fetch_result: 0x818abd8 PQresultStatus(PGRES_TUPLES_OK) PQgetResult(0x82a4648)
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: 15 column(s) returned from the query
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: 0x8189d20=pkg_malloc(60) RES_NAMES
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: 0x818b110=pkg_malloc(60) RES_TYPES
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: 0x818aa08=pkg_malloc(9) RES_NAMES[0]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: RES_NAMES(0x818aa08)[0]=[username]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: 0x818abc0=pkg_malloc(8) RES_NAMES[1]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: RES_NAMES(0x818abc0)[1]=[contact]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: 0x818ac38=pkg_malloc(8) RES_NAMES[2]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: RES_NAMES(0x818ac38)[2]=[expires]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: 0x818abf0=pkg_malloc(2) RES_NAMES[3]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: RES_NAMES(0x818abf0)[3]=[q]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: 0x818ac20=pkg_malloc(7) RES_NAMES[4]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: RES_NAMES(0x818ac20)[4]=[callid]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: 0x818ac08=pkg_malloc(5) RES_NAMES[5]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: RES_NAMES(0x818ac08)[5]=[cseq]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: 0x818a9b0=pkg_malloc(6) RES_NAMES[6]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: RES_NAMES(0x818a9b0)[6]=[flags]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: 0x818b158=pkg_malloc(7) RES_NAMES[7]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: RES_NAMES(0x818b158)[7]=[cflags]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: 0x818b168=pkg_malloc(11) RES_NAMES[8]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: RES_NAMES(0x818b168)[8]=[user_agent]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: 0x818b180=pkg_malloc(9) RES_NAMES[9]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: RES_NAMES(0x818b180)[9]=[received]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: 0x818b198=pkg_malloc(5) RES_NAMES[10]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: RES_NAMES(0x818b198)[10]=[path]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: 0x818b1a8=pkg_malloc(7) RES_NAMES[11]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: RES_NAMES(0x818b1a8)[11]=[socket]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: 0x818b1b8=pkg_malloc(8) RES_NAMES[12]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: RES_NAMES(0x818b1b8)[12]=[methods]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: 0x818b1c8=pkg_malloc(14) RES_NAMES[13]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: RES_NAMES(0x818b1c8)[13]=[last_modified]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: 0x818b1e0=pkg_malloc(7) RES_NAMES[14]
Nov 20 11:16:55 [5177] DBG:postgres:pg_get_columns: RES_NAMES(0x818b1e0)[14]=[domain]
Nov 20 11:16:55 [5177] DBG:postgres:pg_fetch_result: converting row 0 of 1 count 1
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: 0x818b1f0=pkg_malloc(60) row_buf 15 pointers
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: 0x818b238=pkg_malloc(8) RES_ROWS 1 rows
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: PQgetvalue(0x818abd8,0,0)=[50001]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: 0x818b248=pkg_malloc(5) row_buf[0]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: [0][0] Column[username]=[50001]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: PQgetvalue(0x818abd8,0,1)=[sip:s at 172.25.18.161]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: 0x818b258=pkg_malloc(19) row_buf[1]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: [0][1] Column[contact]=[sip:s at 172.25.18.161]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: PQgetvalue(0x818abd8,0,2)=[2008-11-20 11:18:33]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: 0x818b278=pkg_malloc(19) row_buf[2]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: [0][2] Column[expires]=[2008-11-20 11:18:33]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: PQgetvalue(0x818abd8,0,3)=[-1]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: 0x818b298=pkg_malloc(2) row_buf[3]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: [0][3] Column[q]=[-1]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: PQgetvalue(0x818abd8,0,4)=[7d25970e752e59041c423ec01c4244cb at 127.0.1.1]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: 0x818b2a8=pkg_malloc(42) row_buf[4]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: [0][4] Column[callid]=[7d25970e752e59041c423ec01c4244cb at 127.0.1.1]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: PQgetvalue(0x818abd8,0,5)=[103]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: 0x818b2e0=pkg_malloc(3) row_buf[5]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: [0][5] Column[cseq]=[103]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: PQgetvalue(0x818abd8,0,6)=[0]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: 0x818b2f0=pkg_malloc(1) row_buf[6]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: [0][6] Column[flags]=[0]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: PQgetvalue(0x818abd8,0,7)=[0]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: 0x818b300=pkg_malloc(1) row_buf[7]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: [0][7] Column[cflags]=[0]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: PQgetvalue(0x818abd8,0,8)=[Asterisk PBX 1.4 Test]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: 0x818b310=pkg_malloc(21) row_buf[8]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: [0][8] Column[user_agent]=[Asterisk PBX 1.4 Test]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: PQgetvalue(0x818abd8,0,9)=[]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: PQgetvalue(0x818abd8,0,10)=[]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: PQgetvalue(0x818abd8,0,11)=[udp:172.25.18.163:5060]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: 0x818b330=pkg_malloc(22) row_buf[11]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: [0][11] Column[socket]=[udp:172.25.18.163:5060]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: PQgetvalue(0x818abd8,0,12)=[]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: PQgetvalue(0x818abd8,0,13)=[2008-11-20 11:13:33]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: 0x818b350=pkg_malloc(19) row_buf[13]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: [0][13] Column[last_modified]=[2008-11-20 11:13:33]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: PQgetvalue(0x818abd8,0,14)=[voip.tnnet.it]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: 0x818b370=pkg_malloc(13) row_buf[14]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: [0][14] Column[domain]=[voip.tnnet.it]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_row: 0x818b3b8=pkg_malloc(240) ROW_VALUES for 15 columns
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_row: col[0]
Nov 20 11:16:55 [5177] DBG:postgres:pg_str2val: converting STRING [50001]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_row: col[1]
Nov 20 11:16:55 [5177] DBG:postgres:pg_str2val: converting STRING [sip:s at 172.25.18.161]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_row: col[2]
Nov 20 11:16:55 [5177] DBG:postgres:pg_str2val: converting DATETIME [2008-11-20 11:18:33]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_row: col[3]
Nov 20 11:16:55 [5177] DBG:postgres:pg_str2val: converting DOUBLE [-1]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_row: col[4]
Nov 20 11:16:55 [5177] DBG:postgres:pg_str2val: converting STRING [7d25970e752e59041c423ec01c4244cb at 127.0.1.1]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_row: col[5]
Nov 20 11:16:55 [5177] DBG:postgres:pg_str2val: converting INT [103]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_row: col[6]
Nov 20 11:16:55 [5177] DBG:postgres:pg_str2val: converting INT [0]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_row: col[7]
Nov 20 11:16:55 [5177] DBG:postgres:pg_str2val: converting INT [0]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_row: col[8]
Nov 20 11:16:55 [5177] DBG:postgres:pg_str2val: converting STRING [Asterisk PBX 1.4 Test]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_row: col[9]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_row: col[10]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_row: col[11]
Nov 20 11:16:55 [5177] DBG:postgres:pg_str2val: converting STRING [udp:172.25.18.163:5060]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_row: col[12]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_row: col[13]
Nov 20 11:16:55 [5177] DBG:postgres:pg_str2val: converting DATETIME [2008-11-20 11:13:33]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_row: col[14]
Nov 20 11:16:55 [5177] DBG:postgres:pg_str2val: converting STRING [voip.tnnet.it]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: [0][2] Col[expires] Type[4] Freeing row_buf[0x818b278]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: 0x818b278=pkg_free() row_buf[2]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: [0][3] Col[q] Type[1] Freeing row_buf[0x818b298]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: 0x818b298=pkg_free() row_buf[3]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: [0][5] Col[cseq] Type[0] Freeing row_buf[0x818b2e0]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: 0x818b2e0=pkg_free() row_buf[5]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: [0][6] Col[flags] Type[0] Freeing row_buf[0x818b2f0]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: 0x818b2f0=pkg_free() row_buf[6]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: [0][7] Col[cflags] Type[0] Freeing row_buf[0x818b300]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: 0x818b300=pkg_free() row_buf[7]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: [0][12] Col[methods] Type[0] Freeing row_buf[(nil)]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: (nil)=pkg_free() row_buf[12]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: [0][13] Col[last_modified] Type[4] Freeing row_buf[0x818b350]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: 0x818b350=pkg_free() row_buf[13]
Nov 20 11:16:55 [5177] DBG:postgres:pg_convert_rows: 0x818b1f0=pkg_free() row_buf
Nov 20 11:16:55 [5177] DBG:usrloc:preload_udomain: loading records - cycle [1]
Nov 20 11:16:55 [5177] DBG:core:grep_sock_info: checking if host==us: 13==9 &&  [172.25.18.163] == [127.0.0.1]
Nov 20 11:16:55 [5177] DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Nov 20 11:16:55 [5177] DBG:core:grep_sock_info: checking if host==us: 13==13 &&  [172.25.18.163] == [172.25.18.162]
Nov 20 11:16:55 [5177] DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Nov 20 11:16:55 [5177] DBG:core:grep_sock_info: checking if host==us: 13==13 &&  [172.25.18.163] == [172.25.18.163]
Nov 20 11:16:55 [5177] DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Nov 20 11:16:55 [5177] DBG:postgres:pg_free_rows: freeing 1 rows
Nov 20 11:16:55 [5177] DBG:postgres:pg_free_rows: row[0]=0x818b238
Nov 20 11:16:55 [5177] DBG:postgres:pg_free_row: 0x818b248=pkg_free() VAL_STRING[0]
Nov 20 11:16:55 [5177] DBG:postgres:pg_free_row: 0x818b258=pkg_free() VAL_STRING[1]
Nov 20 11:16:55 [5177] DBG:postgres:pg_free_row: 0x818b2a8=pkg_free() VAL_STRING[4]
Nov 20 11:16:55 [5177] DBG:postgres:pg_free_row: 0x818b310=pkg_free() VAL_STRING[8]
Nov 20 11:16:55 [5177] DBG:postgres:pg_free_row: 0xb7e6557d=pkg_free() VAL_STRING[9]
Nov 20 11:16:55 [5179] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82a7458)
Nov 20 11:16:55 [5179] DBG:core:init_mod_child: type=CHILD, rank=2, module=xlog
Nov 20 11:16:55 [5179] DBG:xlog:child_init: init_child [2]  pid [5179]
Nov 20 11:16:55 [5179] DBG:core:init_mod_child: type=CHILD, rank=2, module=acc
Nov 20 11:16:55 [5179] DBG:postgres:pg_init: 0x818aa08=pkg_malloc(12) for database connection
Nov 20 11:16:55 [5179] DBG:postgres:pg_init: connection 0x818a958 found in pool
Nov 20 11:16:55 [5179] DBG:core:init_mod_child: type=CHILD, rank=2, module=auth_db
Nov 20 11:16:55 [5179] DBG:postgres:pg_init: 0x818a9b0=pkg_malloc(12) for database connection
Nov 20 11:16:55 [5179] DBG:postgres:pg_init: connection 0x8189d20 found in pool
Nov 20 11:16:55 [5179] DBG:core:init_mod_child: type=CHILD, rank=2, module=alias_db
Nov 20 11:16:55 [5179] DBG:postgres:pg_init: 0x818b120=pkg_malloc(12) for database connection
Nov 20 11:16:55 [5179] DBG:postgres:pg_init: connection 0x818b138 found in pool
Nov 20 11:16:55 [5179] DBG:core:init_mod_child: type=CHILD, rank=2, module=domain
Nov 20 11:16:55 [5179] DBG:core:init_mod_child: type=CHILD, rank=2, module=lcr
Nov 20 11:16:55 [5179] DBG:postgres:pg_init: 0x818b1c8=pkg_malloc(12) for database connection
Nov 20 11:16:55 [5179] DBG:postgres:pg_init: connection 0x818b1e0 found in pool
Nov 20 11:16:55 [5179] DBG:core:init_mod_child: type=CHILD, rank=2, module=avpops
Nov 20 11:16:55 [5179] DBG:postgres:pg_init: 0x818b268=pkg_malloc(12) for database connection
Nov 20 11:16:55 [5179] DBG:postgres:pg_init: connection 0x818b280 found in pool
Nov 20 11:16:55 [5179] DBG:core:init_mod_child: type=CHILD, rank=2, module=pdt
Nov 20 11:16:55 [5179] DBG:postgres:pg_init: 0x818b308=pkg_malloc(12) for database connection
Nov 20 11:16:55 [5179] DBG:postgres:pg_init: connection 0x818b320 found in pool
Nov 20 11:16:55 [5179] DBG:pdt:mod_child_init: #2: database connection opened successfully
Nov 20 11:16:55 [5179] DBG:core:init_mod_child: type=CHILD, rank=2, module=permissions
Nov 20 11:16:55 [5181] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82a7458)
Nov 20 11:16:55 [5181] DBG:core:init_mod_child: type=CHILD, rank=3, module=xlog
Nov 20 11:16:55 [5181] DBG:xlog:child_init: init_child [3]  pid [5181]
Nov 20 11:16:55 [5181] DBG:core:init_mod_child: type=CHILD, rank=3, module=acc
Nov 20 11:16:55 [5181] DBG:postgres:pg_init: 0x818aa08=pkg_malloc(12) for database connection
Nov 20 11:16:55 [5181] DBG:postgres:pg_init: connection 0x818a958 found in pool
Nov 20 11:16:55 [5181] DBG:core:init_mod_child: type=CHILD, rank=3, module=auth_db
Nov 20 11:16:55 [5181] DBG:postgres:pg_init: 0x818a9b0=pkg_malloc(12) for database connection
Nov 20 11:16:55 [5181] DBG:postgres:pg_init: connection 0x8189d20 found in pool
Nov 20 11:16:55 [5181] DBG:core:init_mod_child: type=CHILD, rank=3, module=alias_db
Nov 20 11:16:55 [5181] DBG:postgres:pg_init: 0x818b120=pkg_malloc(12) for database connection
Nov 20 11:16:55 [5181] DBG:postgres:pg_init: connection 0x818b138 found in pool
Nov 20 11:16:55 [5181] DBG:core:init_mod_child: type=CHILD, rank=3, module=domain
Nov 20 11:16:55 [5181] DBG:core:init_mod_child: type=CHILD, rank=3, module=lcr
Nov 20 11:16:55 [5181] DBG:postgres:pg_init: 0x818b1c8=pkg_malloc(12) for database connection
Nov 20 11:16:55 [5181] DBG:postgres:pg_init: connection 0x818b1e0 found in pool
Nov 20 11:16:55 [5181] DBG:core:init_mod_child: type=CHILD, rank=3, module=avpops
Nov 20 11:16:55 [5181] DBG:postgres:pg_init: 0x818b268=pkg_malloc(12) for database connection
Nov 20 11:16:55 [5181] DBG:postgres:pg_init: connection 0x818b280 found in pool
Nov 20 11:16:55 [5181] DBG:core:init_mod_child: type=CHILD, rank=3, module=pdt
Nov 20 11:16:55 [5181] DBG:postgres:pg_init: 0x818b308=pkg_malloc(12) for database connection
Nov 20 11:16:55 [5181] DBG:postgres:pg_init: connection 0x818b320 found in pool
Nov 20 11:16:55 [5181] DBG:pdt:mod_child_init: #3: database connection opened successfully
Nov 20 11:16:55 [5181] DBG:core:init_mod_child: type=CHILD, rank=3, module=permissions
Nov 20 11:16:55 [5183] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82a7458)
Nov 20 11:16:55 [5183] DBG:core:init_mod_child: type=CHILD, rank=4, module=xlog
Nov 20 11:16:55 [5183] DBG:xlog:child_init: init_child [4]  pid [5183]
Nov 20 11:16:55 [5183] DBG:core:init_mod_child: type=CHILD, rank=4, module=acc
Nov 20 11:16:55 [5183] DBG:postgres:pg_init: 0x818aa08=pkg_malloc(12) for database connection
Nov 20 11:16:55 [5183] DBG:postgres:pg_init: connection 0x818a958 found in pool
Nov 20 11:16:55 [5183] DBG:core:init_mod_child: type=CHILD, rank=4, module=auth_db
Nov 20 11:16:55 [5183] DBG:postgres:pg_init: 0x818a9b0=pkg_malloc(12) for database connection
Nov 20 11:16:55 [5183] DBG:postgres:pg_init: connection 0x8189d20 found in pool
Nov 20 11:16:55 [5183] DBG:core:init_mod_child: type=CHILD, rank=4, module=alias_db
Nov 20 11:16:55 [5183] DBG:postgres:pg_init: 0x818b120=pkg_malloc(12) for database connection
Nov 20 11:16:55 [5183] DBG:postgres:pg_init: connection 0x818b138 found in pool
Nov 20 11:16:55 [5183] DBG:core:init_mod_child: type=CHILD, rank=4, module=domain
Nov 20 11:16:55 [5183] DBG:core:init_mod_child: type=CHILD, rank=4, module=lcr
Nov 20 11:16:55 [5183] DBG:postgres:pg_init: 0x818b1c8=pkg_malloc(12) for database connection
Nov 20 11:16:55 [5183] DBG:postgres:pg_init: connection 0x818b1e0 found in pool
Nov 20 11:16:55 [5183] DBG:core:init_mod_child: type=CHILD, rank=4, module=avpops
Nov 20 11:16:55 [5183] DBG:postgres:pg_init: 0x818b268=pkg_malloc(12) for database connection
Nov 20 11:16:55 [5183] DBG:postgres:pg_init: connection 0x818b280 found in pool
Nov 20 11:16:55 [5183] DBG:core:init_mod_child: type=CHILD, rank=4, module=pdt
Nov 20 11:16:55 [5183] DBG:postgres:pg_init: 0x818b308=pkg_malloc(12) for database connection
Nov 20 11:16:55 [5183] DBG:postgres:pg_init: connection 0x818b320 found in pool
Nov 20 11:16:55 [5183] DBG:pdt:mod_child_init: #4: database connection opened successfully
Nov 20 11:16:55 [5183] DBG:core:init_mod_child: type=CHILD, rank=4, module=permissions
Nov 20 11:16:55 [5186] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82a7458)
Nov 20 11:16:55 [5186] DBG:core:init_mod_child: type=CHILD, rank=6, module=xlog
Nov 20 11:16:55 [5186] DBG:xlog:child_init: init_child [6]  pid [5186]
Nov 20 11:16:55 [5186] DBG:core:init_mod_child: type=CHILD, rank=6, module=acc
Nov 20 11:16:55 [5186] DBG:postgres:pg_init: 0x818aa08=pkg_malloc(12) for database connection
Nov 20 11:16:55 [5186] DBG:postgres:pg_init: connection 0x818a958 found in pool
Nov 20 11:16:55 [5186] DBG:core:init_mod_child: type=CHILD, rank=6, module=auth_db
Nov 20 11:16:55 [5186] DBG:postgres:pg_init: 0x818a9b0=pkg_malloc(12) for database connection
Nov 20 11:16:55 [5186] DBG:postgres:pg_init: connection 0x8189d20 found in pool
Nov 20 11:16:55 [5186] DBG:core:init_mod_child: type=CHILD, rank=6, module=alias_db
Nov 20 11:16:55 [5186] DBG:postgres:pg_init: 0x818b120=pkg_malloc(12) for database connection
Nov 20 11:16:55 [5186] DBG:postgres:pg_init: connection 0x818b138 found in pool
Nov 20 11:16:55 [5186] DBG:core:init_mod_child: type=CHILD, rank=6, module=domain
Nov 20 11:16:55 [5186] DBG:core:init_mod_child: type=CHILD, rank=6, module=lcr
Nov 20 11:16:55 [5186] DBG:postgres:pg_init: 0x818b1c8=pkg_malloc(12) for database connection
Nov 20 11:16:55 [5186] DBG:postgres:pg_init: connection 0x818b1e0 found in pool
Nov 20 11:16:55 [5186] DBG:core:init_mod_child: type=CHILD, rank=6, module=avpops
Nov 20 11:16:55 [5186] DBG:postgres:pg_init: 0x818b268=pkg_malloc(12) for database connection
Nov 20 11:16:55 [5186] DBG:postgres:pg_init: connection 0x818b280 found in pool
Nov 20 11:16:55 [5186] DBG:core:init_mod_child: type=CHILD, rank=6, module=pdt
Nov 20 11:16:55 [5186] DBG:postgres:pg_init: 0x818b308=pkg_malloc(12) for database connection
Nov 20 11:16:55 [5186] DBG:postgres:pg_init: connection 0x818b320 found in pool
Nov 20 11:16:55 [5186] DBG:pdt:mod_child_init: #6: database connection opened successfully
Nov 20 11:16:55 [5186] DBG:core:init_mod_child: type=CHILD, rank=6, module=permissions
Nov 20 11:16:56 [5202] DBG:mi_fifo:fifo_process: new process with pid = 5202 created
Nov 20 11:16:56 [5202] DBG:mi_fifo:mi_init_fifo_server: FIFO created @ /tmp/openser_fifo
Nov 20 11:16:56 [5202] DBG:mi_fifo:mi_init_fifo_server: fifo /tmp/openser_fifo opened, mode=660
Nov 20 11:16:56 [5164] DBG:core:openser_fork: forking new process "SIP receiver TCP"
Nov 20 11:16:56 [5204] DBG:core:init_mod_child: type=CHILD, rank=13, module=tm
Nov 20 11:16:56 [5204] DBG:tm:child_init_callid: callid: '79dddf19-5204 at 127.0.0.1'
Nov 20 11:16:56 [5204] DBG:core:init_mod_child: type=CHILD, rank=13, module=usrloc
Nov 20 11:16:56 [5204] DBG:core:init_mod_child: type=CHILD, rank=13, module=registrar
Nov 20 11:16:56 [5204] DBG:core:init_mod_child: type=CHILD, rank=13, module=mi_fifo
Nov 20 11:16:56 [5204] DBG:core:init_mod_child: type=CHILD, rank=13, module=uri_db
Nov 20 11:16:56 [5204] DBG:postgres:pg_init: 0x818abd8=pkg_malloc(12) for database connection
Nov 20 11:16:56 [5204] DBG:postgres:pg_init: connection 0x8182f78 not found in pool
Nov 20 11:16:56 [5204] DBG:postgres:pg_new_conn: db_id = 0x8182f78
Nov 20 11:16:56 [5204] DBG:postgres:pg_new_conn: 0x818a888=pkg_malloc(44)
Nov 20 11:16:56 [5204] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:57 [5164] DBG:core:openser_fork: forking new process "SIP receiver TCP"
Nov 20 11:16:57 [5206] DBG:core:init_mod_child: type=CHILD, rank=14, module=tm
Nov 20 11:16:57 [5206] DBG:tm:child_init_callid: callid: '79dddf19-5206 at 127.0.0.1'
Nov 20 11:16:57 [5206] DBG:core:init_mod_child: type=CHILD, rank=14, module=usrloc
Nov 20 11:16:57 [5206] DBG:core:init_mod_child: type=CHILD, rank=14, module=registrar
Nov 20 11:16:57 [5206] DBG:core:init_mod_child: type=CHILD, rank=14, module=mi_fifo
Nov 20 11:16:57 [5206] DBG:core:init_mod_child: type=CHILD, rank=14, module=uri_db
Nov 20 11:16:57 [5206] DBG:postgres:pg_init: 0x818abd8=pkg_malloc(12) for database connection
Nov 20 11:16:57 [5206] DBG:postgres:pg_init: connection 0x8182f78 not found in pool
Nov 20 11:16:57 [5206] DBG:postgres:pg_new_conn: db_id = 0x8182f78
Nov 20 11:16:57 [5206] DBG:postgres:pg_new_conn: 0x818a888=pkg_malloc(44)
Nov 20 11:16:57 [5206] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:57 [5164] DBG:core:openser_fork: forking new process "SIP receiver TCP"
Nov 20 11:16:57 [5208] DBG:core:init_mod_child: type=CHILD, rank=15, module=tm
Nov 20 11:16:57 [5208] DBG:tm:child_init_callid: callid: '79dddf19-5208 at 127.0.0.1'
Nov 20 11:16:57 [5208] DBG:core:init_mod_child: type=CHILD, rank=15, module=usrloc
Nov 20 11:16:57 [5208] DBG:core:init_mod_child: type=CHILD, rank=15, module=registrar
Nov 20 11:16:57 [5208] DBG:core:init_mod_child: type=CHILD, rank=15, module=mi_fifo
Nov 20 11:16:57 [5208] DBG:core:init_mod_child: type=CHILD, rank=15, module=uri_db
Nov 20 11:16:57 [5208] DBG:postgres:pg_init: 0x818abd8=pkg_malloc(12) for database connection
Nov 20 11:16:57 [5208] DBG:postgres:pg_init: connection 0x8182f78 not found in pool
Nov 20 11:16:57 [5208] DBG:postgres:pg_new_conn: db_id = 0x8182f78
Nov 20 11:16:57 [5208] DBG:postgres:pg_new_conn: 0x818a888=pkg_malloc(44)
Nov 20 11:16:57 [5208] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:57 [5164] DBG:core:openser_fork: forking new process "SIP receiver TCP"
Nov 20 11:16:57 [5210] DBG:core:init_mod_child: type=CHILD, rank=16, module=tm
Nov 20 11:16:57 [5210] DBG:tm:child_init_callid: callid: '79dddf19-5210 at 127.0.0.1'
Nov 20 11:16:57 [5210] DBG:core:init_mod_child: type=CHILD, rank=16, module=usrloc
Nov 20 11:16:57 [5210] DBG:core:init_mod_child: type=CHILD, rank=16, module=registrar
Nov 20 11:16:57 [5210] DBG:core:init_mod_child: type=CHILD, rank=16, module=mi_fifo
Nov 20 11:16:57 [5210] DBG:core:init_mod_child: type=CHILD, rank=16, module=uri_db
Nov 20 11:16:57 [5210] DBG:postgres:pg_init: 0x818abd8=pkg_malloc(12) for database connection
Nov 20 11:16:57 [5210] DBG:postgres:pg_init: connection 0x8182f78 not found in pool
Nov 20 11:16:57 [5210] DBG:postgres:pg_new_conn: db_id = 0x8182f78
Nov 20 11:16:57 [5210] DBG:postgres:pg_new_conn: 0x818a888=pkg_malloc(44)
Nov 20 11:16:57 [5210] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:57 [5164] DBG:core:openser_fork: forking new process "TCP main"
Nov 20 11:16:57 [5188] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82a7458)
Nov 20 11:16:57 [5188] DBG:core:init_mod_child: type=CHILD, rank=7, module=xlog
Nov 20 11:16:57 [5188] DBG:xlog:child_init: init_child [7]  pid [5188]
Nov 20 11:16:57 [5188] DBG:core:init_mod_child: type=CHILD, rank=7, module=acc
Nov 20 11:16:57 [5188] DBG:postgres:pg_init: 0x818aa08=pkg_malloc(12) for database connection
Nov 20 11:16:57 [5188] DBG:postgres:pg_init: connection 0x818a958 found in pool
Nov 20 11:16:57 [5188] DBG:core:init_mod_child: type=CHILD, rank=7, module=auth_db
Nov 20 11:16:57 [5188] DBG:postgres:pg_init: 0x818a9b0=pkg_malloc(12) for database connection
Nov 20 11:16:57 [5188] DBG:postgres:pg_init: connection 0x8189d20 found in pool
Nov 20 11:16:57 [5188] DBG:core:init_mod_child: type=CHILD, rank=7, module=alias_db
Nov 20 11:16:57 [5188] DBG:postgres:pg_init: 0x818b120=pkg_malloc(12) for database connection
Nov 20 11:16:57 [5188] DBG:postgres:pg_init: connection 0x818b138 found in pool
Nov 20 11:16:57 [5188] DBG:core:init_mod_child: type=CHILD, rank=7, module=domain
Nov 20 11:16:57 [5188] DBG:core:init_mod_child: type=CHILD, rank=7, module=lcr
Nov 20 11:16:57 [5188] DBG:postgres:pg_init: 0x818b1c8=pkg_malloc(12) for database connection
Nov 20 11:16:57 [5188] DBG:postgres:pg_init: connection 0x818b1e0 found in pool
Nov 20 11:16:57 [5188] DBG:core:init_mod_child: type=CHILD, rank=7, module=avpops
Nov 20 11:16:57 [5188] DBG:postgres:pg_init: 0x818b268=pkg_malloc(12) for database connection
Nov 20 11:16:57 [5188] DBG:postgres:pg_init: connection 0x818b280 found in pool
Nov 20 11:16:57 [5188] DBG:core:init_mod_child: type=CHILD, rank=7, module=pdt
Nov 20 11:16:57 [5188] DBG:postgres:pg_init: 0x818b308=pkg_malloc(12) for database connection
Nov 20 11:16:57 [5188] DBG:postgres:pg_init: connection 0x818b320 found in pool
Nov 20 11:16:57 [5188] DBG:pdt:mod_child_init: #7: database connection opened successfully
Nov 20 11:16:57 [5188] DBG:core:init_mod_child: type=CHILD, rank=7, module=permissions
Nov 20 11:16:58 [5193] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82a7458)
Nov 20 11:16:58 [5193] DBG:core:init_mod_child: type=CHILD, rank=10, module=xlog
Nov 20 11:16:58 [5193] DBG:xlog:child_init: init_child [10]  pid [5193]
Nov 20 11:16:58 [5193] DBG:core:init_mod_child: type=CHILD, rank=10, module=acc
Nov 20 11:16:58 [5193] DBG:postgres:pg_init: 0x818aa08=pkg_malloc(12) for database connection
Nov 20 11:16:58 [5193] DBG:postgres:pg_init: connection 0x818a958 found in pool
Nov 20 11:16:58 [5193] DBG:core:init_mod_child: type=CHILD, rank=10, module=auth_db
Nov 20 11:16:58 [5193] DBG:postgres:pg_init: 0x818a9b0=pkg_malloc(12) for database connection
Nov 20 11:16:58 [5193] DBG:postgres:pg_init: connection 0x8189d20 found in pool
Nov 20 11:16:58 [5193] DBG:core:init_mod_child: type=CHILD, rank=10, module=alias_db
Nov 20 11:16:58 [5193] DBG:postgres:pg_init: 0x818b120=pkg_malloc(12) for database connection
Nov 20 11:16:58 [5193] DBG:postgres:pg_init: connection 0x818b138 found in pool
Nov 20 11:16:58 [5193] DBG:core:init_mod_child: type=CHILD, rank=10, module=domain
Nov 20 11:16:58 [5193] DBG:core:init_mod_child: type=CHILD, rank=10, module=lcr
Nov 20 11:16:58 [5193] DBG:postgres:pg_init: 0x818b1c8=pkg_malloc(12) for database connection
Nov 20 11:16:58 [5193] DBG:postgres:pg_init: connection 0x818b1e0 found in pool
Nov 20 11:16:58 [5193] DBG:core:init_mod_child: type=CHILD, rank=10, module=avpops
Nov 20 11:16:58 [5193] DBG:postgres:pg_init: 0x818b268=pkg_malloc(12) for database connection
Nov 20 11:16:58 [5193] DBG:postgres:pg_init: connection 0x818b280 found in pool
Nov 20 11:16:58 [5193] DBG:core:init_mod_child: type=CHILD, rank=10, module=pdt
Nov 20 11:16:58 [5193] DBG:postgres:pg_init: 0x818b308=pkg_malloc(12) for database connection
Nov 20 11:16:58 [5193] DBG:postgres:pg_init: connection 0x818b320 found in pool
Nov 20 11:16:58 [5193] DBG:pdt:mod_child_init: #10: database connection opened successfully
Nov 20 11:16:58 [5193] DBG:core:init_mod_child: type=CHILD, rank=10, module=permissions
Nov 20 11:16:58 [5202] DBG:postgres:pg_init: 0x818abd8=pkg_malloc(12) for database connection
Nov 20 11:16:58 [5202] DBG:postgres:pg_init: connection 0x818a888 not found in pool
Nov 20 11:16:58 [5202] DBG:postgres:pg_new_conn: db_id = 0x818a888
Nov 20 11:16:58 [5202] DBG:postgres:pg_new_conn: 0x818a958=pkg_malloc(44)
Nov 20 11:16:58 [5202] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:59 [5212] DBG:core:init_mod_child: type=PROC_TCP_MAIN, rank=-4, module=tm
Nov 20 11:16:59 [5212] DBG:tm:child_init_callid: callid: '79dddf19-5212 at 127.0.0.1'
Nov 20 11:16:59 [5212] DBG:core:init_mod_child: type=PROC_TCP_MAIN, rank=-4, module=usrloc
Nov 20 11:16:59 [5212] DBG:core:init_mod_child: type=PROC_TCP_MAIN, rank=-4, module=registrar
Nov 20 11:16:59 [5212] DBG:core:init_mod_child: type=PROC_TCP_MAIN, rank=-4, module=mi_fifo
Nov 20 11:16:59 [5164] DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=tm
Nov 20 11:16:59 [5164] DBG:tm:child_init_callid: callid: '79dddf19-5164 at 127.0.0.1'
Nov 20 11:16:59 [5164] DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=usrloc
Nov 20 11:16:59 [5164] DBG:postgres:pg_init: 0x818abd8=pkg_malloc(12) for database connection
Nov 20 11:16:59 [5164] DBG:postgres:pg_init: connection 0x8182f78 not found in pool
Nov 20 11:16:59 [5164] DBG:postgres:pg_new_conn: db_id = 0x8182f78
Nov 20 11:16:59 [5164] DBG:postgres:pg_new_conn: 0x818a888=pkg_malloc(44)
Nov 20 11:16:59 [5164] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:16:59 [5184] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82a7458)
Nov 20 11:16:59 [5184] DBG:core:init_mod_child: type=CHILD, rank=5, module=xlog
Nov 20 11:16:59 [5184] DBG:xlog:child_init: init_child [5]  pid [5184]
Nov 20 11:16:59 [5184] DBG:core:init_mod_child: type=CHILD, rank=5, module=acc
Nov 20 11:16:59 [5184] DBG:postgres:pg_init: 0x818aa08=pkg_malloc(12) for database connection
Nov 20 11:16:59 [5184] DBG:postgres:pg_init: connection 0x818a958 found in pool
Nov 20 11:16:59 [5184] DBG:core:init_mod_child: type=CHILD, rank=5, module=auth_db
Nov 20 11:16:59 [5184] DBG:postgres:pg_init: 0x818a9b0=pkg_malloc(12) for database connection
Nov 20 11:16:59 [5184] DBG:postgres:pg_init: connection 0x8189d20 found in pool
Nov 20 11:16:59 [5184] DBG:core:init_mod_child: type=CHILD, rank=5, module=alias_db
Nov 20 11:16:59 [5184] DBG:postgres:pg_init: 0x818b120=pkg_malloc(12) for database connection
Nov 20 11:16:59 [5184] DBG:postgres:pg_init: connection 0x818b138 found in pool
Nov 20 11:16:59 [5184] DBG:core:init_mod_child: type=CHILD, rank=5, module=domain
Nov 20 11:16:59 [5184] DBG:core:init_mod_child: type=CHILD, rank=5, module=lcr
Nov 20 11:16:59 [5184] DBG:postgres:pg_init: 0x818b1c8=pkg_malloc(12) for database connection
Nov 20 11:16:59 [5184] DBG:postgres:pg_init: connection 0x818b1e0 found in pool
Nov 20 11:16:59 [5184] DBG:core:init_mod_child: type=CHILD, rank=5, module=avpops
Nov 20 11:16:59 [5184] DBG:postgres:pg_init: 0x818b268=pkg_malloc(12) for database connection
Nov 20 11:16:59 [5184] DBG:postgres:pg_init: connection 0x818b280 found in pool
Nov 20 11:16:59 [5184] DBG:core:init_mod_child: type=CHILD, rank=5, module=pdt
Nov 20 11:16:59 [5184] DBG:postgres:pg_init: 0x818b308=pkg_malloc(12) for database connection
Nov 20 11:16:59 [5184] DBG:postgres:pg_init: connection 0x818b320 found in pool
Nov 20 11:16:59 [5184] DBG:pdt:mod_child_init: #5: database connection opened successfully
Nov 20 11:16:59 [5184] DBG:core:init_mod_child: type=CHILD, rank=5, module=permissions
Nov 20 11:16:59 [5190] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82a7458)
Nov 20 11:16:59 [5190] DBG:core:init_mod_child: type=CHILD, rank=8, module=xlog
Nov 20 11:16:59 [5190] DBG:xlog:child_init: init_child [8]  pid [5190]
Nov 20 11:16:59 [5190] DBG:core:init_mod_child: type=CHILD, rank=8, module=acc
Nov 20 11:16:59 [5190] DBG:postgres:pg_init: 0x818aa08=pkg_malloc(12) for database connection
Nov 20 11:16:59 [5190] DBG:postgres:pg_init: connection 0x818a958 found in pool
Nov 20 11:16:59 [5190] DBG:core:init_mod_child: type=CHILD, rank=8, module=auth_db
Nov 20 11:16:59 [5190] DBG:postgres:pg_init: 0x818a9b0=pkg_malloc(12) for database connection
Nov 20 11:16:59 [5190] DBG:postgres:pg_init: connection 0x8189d20 found in pool
Nov 20 11:16:59 [5190] DBG:core:init_mod_child: type=CHILD, rank=8, module=alias_db
Nov 20 11:16:59 [5190] DBG:postgres:pg_init: 0x818b120=pkg_malloc(12) for database connection
Nov 20 11:16:59 [5190] DBG:postgres:pg_init: connection 0x818b138 found in pool
Nov 20 11:16:59 [5190] DBG:core:init_mod_child: type=CHILD, rank=8, module=domain
Nov 20 11:16:59 [5190] DBG:core:init_mod_child: type=CHILD, rank=8, module=lcr
Nov 20 11:16:59 [5190] DBG:postgres:pg_init: 0x818b1c8=pkg_malloc(12) for database connection
Nov 20 11:16:59 [5190] DBG:postgres:pg_init: connection 0x818b1e0 found in pool
Nov 20 11:16:59 [5190] DBG:core:init_mod_child: type=CHILD, rank=8, module=avpops
Nov 20 11:16:59 [5190] DBG:postgres:pg_init: 0x818b268=pkg_malloc(12) for database connection
Nov 20 11:16:59 [5190] DBG:postgres:pg_init: connection 0x818b280 found in pool
Nov 20 11:16:59 [5190] DBG:core:init_mod_child: type=CHILD, rank=8, module=pdt
Nov 20 11:16:59 [5190] DBG:postgres:pg_init: 0x818b308=pkg_malloc(12) for database connection
Nov 20 11:16:59 [5190] DBG:postgres:pg_init: connection 0x818b320 found in pool
Nov 20 11:16:59 [5190] DBG:pdt:mod_child_init: #8: database connection opened successfully
Nov 20 11:16:59 [5190] DBG:core:init_mod_child: type=CHILD, rank=8, module=permissions
Nov 20 11:16:59 [5191] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82a7458)
Nov 20 11:16:59 [5191] DBG:core:init_mod_child: type=CHILD, rank=9, module=xlog
Nov 20 11:16:59 [5191] DBG:xlog:child_init: init_child [9]  pid [5191]
Nov 20 11:16:59 [5191] DBG:core:init_mod_child: type=CHILD, rank=9, module=acc
Nov 20 11:16:59 [5191] DBG:postgres:pg_init: 0x818aa08=pkg_malloc(12) for database connection
Nov 20 11:16:59 [5191] DBG:postgres:pg_init: connection 0x818a958 found in pool
Nov 20 11:16:59 [5191] DBG:core:init_mod_child: type=CHILD, rank=9, module=auth_db
Nov 20 11:16:59 [5191] DBG:postgres:pg_init: 0x818a9b0=pkg_malloc(12) for database connection
Nov 20 11:16:59 [5191] DBG:postgres:pg_init: connection 0x8189d20 found in pool
Nov 20 11:16:59 [5191] DBG:core:init_mod_child: type=CHILD, rank=9, module=alias_db
Nov 20 11:16:59 [5191] DBG:postgres:pg_init: 0x818b120=pkg_malloc(12) for database connection
Nov 20 11:16:59 [5191] DBG:postgres:pg_init: connection 0x818b138 found in pool
Nov 20 11:16:59 [5191] DBG:core:init_mod_child: type=CHILD, rank=9, module=domain
Nov 20 11:16:59 [5191] DBG:core:init_mod_child: type=CHILD, rank=9, module=lcr
Nov 20 11:16:59 [5191] DBG:postgres:pg_init: 0x818b1c8=pkg_malloc(12) for database connection
Nov 20 11:16:59 [5191] DBG:postgres:pg_init: connection 0x818b1e0 found in pool
Nov 20 11:16:59 [5191] DBG:core:init_mod_child: type=CHILD, rank=9, module=avpops
Nov 20 11:16:59 [5191] DBG:postgres:pg_init: 0x818b268=pkg_malloc(12) for database connection
Nov 20 11:16:59 [5191] DBG:postgres:pg_init: connection 0x818b280 found in pool
Nov 20 11:16:59 [5191] DBG:core:init_mod_child: type=CHILD, rank=9, module=pdt
Nov 20 11:16:59 [5191] DBG:postgres:pg_init: 0x818b308=pkg_malloc(12) for database connection
Nov 20 11:16:59 [5191] DBG:postgres:pg_init: connection 0x818b320 found in pool
Nov 20 11:16:59 [5191] DBG:pdt:mod_child_init: #9: database connection opened successfully
Nov 20 11:16:59 [5191] DBG:core:init_mod_child: type=CHILD, rank=9, module=permissions
Nov 20 11:16:59 [5197] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82a7458)
Nov 20 11:16:59 [5197] DBG:core:init_mod_child: type=CHILD, rank=11, module=xlog
Nov 20 11:16:59 [5197] DBG:xlog:child_init: init_child [11]  pid [5197]
Nov 20 11:16:59 [5197] DBG:core:init_mod_child: type=CHILD, rank=11, module=acc
Nov 20 11:16:59 [5197] DBG:postgres:pg_init: 0x818aa08=pkg_malloc(12) for database connection
Nov 20 11:16:59 [5197] DBG:postgres:pg_init: connection 0x818a958 found in pool
Nov 20 11:16:59 [5197] DBG:core:init_mod_child: type=CHILD, rank=11, module=auth_db
Nov 20 11:16:59 [5197] DBG:postgres:pg_init: 0x818a9b0=pkg_malloc(12) for database connection
Nov 20 11:16:59 [5197] DBG:postgres:pg_init: connection 0x8189d20 found in pool
Nov 20 11:16:59 [5197] DBG:core:init_mod_child: type=CHILD, rank=11, module=alias_db
Nov 20 11:16:59 [5197] DBG:postgres:pg_init: 0x818b120=pkg_malloc(12) for database connection
Nov 20 11:16:59 [5197] DBG:postgres:pg_init: connection 0x818b138 found in pool
Nov 20 11:16:59 [5197] DBG:core:init_mod_child: type=CHILD, rank=11, module=domain
Nov 20 11:16:59 [5197] DBG:core:init_mod_child: type=CHILD, rank=11, module=lcr
Nov 20 11:16:59 [5197] DBG:postgres:pg_init: 0x818b1c8=pkg_malloc(12) for database connection
Nov 20 11:16:59 [5197] DBG:postgres:pg_init: connection 0x818b1e0 found in pool
Nov 20 11:16:59 [5197] DBG:core:init_mod_child: type=CHILD, rank=11, module=avpops
Nov 20 11:16:59 [5197] DBG:postgres:pg_init: 0x818b268=pkg_malloc(12) for database connection
Nov 20 11:16:59 [5197] DBG:postgres:pg_init: connection 0x818b280 found in pool
Nov 20 11:16:59 [5197] DBG:core:init_mod_child: type=CHILD, rank=11, module=pdt
Nov 20 11:16:59 [5197] DBG:postgres:pg_init: 0x818b308=pkg_malloc(12) for database connection
Nov 20 11:16:59 [5197] DBG:postgres:pg_init: connection 0x818b320 found in pool
Nov 20 11:16:59 [5197] DBG:pdt:mod_child_init: #11: database connection opened successfully
Nov 20 11:16:59 [5197] DBG:core:init_mod_child: type=CHILD, rank=11, module=permissions
Nov 20 11:17:00 [5199] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82a7458)
Nov 20 11:17:00 [5199] DBG:core:init_mod_child: type=CHILD, rank=12, module=xlog
Nov 20 11:17:00 [5199] DBG:xlog:child_init: init_child [12]  pid [5199]
Nov 20 11:17:00 [5199] DBG:core:init_mod_child: type=CHILD, rank=12, module=acc
Nov 20 11:17:00 [5199] DBG:postgres:pg_init: 0x818aa08=pkg_malloc(12) for database connection
Nov 20 11:17:00 [5199] DBG:postgres:pg_init: connection 0x818a958 found in pool
Nov 20 11:17:00 [5199] DBG:core:init_mod_child: type=CHILD, rank=12, module=auth_db
Nov 20 11:17:00 [5199] DBG:postgres:pg_init: 0x818a9b0=pkg_malloc(12) for database connection
Nov 20 11:17:00 [5199] DBG:postgres:pg_init: connection 0x8189d20 found in pool
Nov 20 11:17:00 [5199] DBG:core:init_mod_child: type=CHILD, rank=12, module=alias_db
Nov 20 11:17:00 [5199] DBG:postgres:pg_init: 0x818b120=pkg_malloc(12) for database connection
Nov 20 11:17:00 [5199] DBG:postgres:pg_init: connection 0x818b138 found in pool
Nov 20 11:17:00 [5199] DBG:core:init_mod_child: type=CHILD, rank=12, module=domain
Nov 20 11:17:00 [5199] DBG:core:init_mod_child: type=CHILD, rank=12, module=lcr
Nov 20 11:17:00 [5199] DBG:postgres:pg_init: 0x818b1c8=pkg_malloc(12) for database connection
Nov 20 11:17:00 [5199] DBG:postgres:pg_init: connection 0x818b1e0 found in pool
Nov 20 11:17:00 [5199] DBG:core:init_mod_child: type=CHILD, rank=12, module=avpops
Nov 20 11:17:00 [5199] DBG:postgres:pg_init: 0x818b268=pkg_malloc(12) for database connection
Nov 20 11:17:00 [5199] DBG:postgres:pg_init: connection 0x818b280 found in pool
Nov 20 11:17:00 [5199] DBG:core:init_mod_child: type=CHILD, rank=12, module=pdt
Nov 20 11:17:00 [5199] DBG:postgres:pg_init: 0x818b308=pkg_malloc(12) for database connection
Nov 20 11:17:00 [5199] DBG:postgres:pg_init: connection 0x818b320 found in pool
Nov 20 11:17:00 [5199] DBG:pdt:mod_child_init: #12: database connection opened successfully
Nov 20 11:17:00 [5199] DBG:core:init_mod_child: type=CHILD, rank=12, module=permissions
Nov 20 11:17:00 [5200] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82a7458)
Nov 20 11:17:00 [5200] DBG:core:init_mod_child: type=PROC_TIMER, rank=-1, module=registrar
Nov 20 11:17:00 [5200] DBG:core:init_mod_child: type=PROC_TIMER, rank=-1, module=mi_fifo
Nov 20 11:17:00 [5200] DBG:core:init_mod_child: type=PROC_TIMER, rank=-1, module=uri_db
Nov 20 11:17:00 [5200] DBG:postgres:pg_init: 0x818aa08=pkg_malloc(12) for database connection
Nov 20 11:17:00 [5200] DBG:postgres:pg_init: connection 0x818a958 found in pool
Nov 20 11:17:00 [5200] DBG:core:init_mod_child: type=PROC_TIMER, rank=-1, module=xlog
Nov 20 11:17:00 [5200] DBG:xlog:child_init: init_child [-1]  pid [5200]
Nov 20 11:17:00 [5200] DBG:core:init_mod_child: type=PROC_TIMER, rank=-1, module=acc
Nov 20 11:17:00 [5200] DBG:postgres:pg_init: 0x818a9b0=pkg_malloc(12) for database connection
Nov 20 11:17:00 [5200] DBG:postgres:pg_init: connection 0x8189d20 found in pool
Nov 20 11:17:00 [5200] DBG:core:init_mod_child: type=PROC_TIMER, rank=-1, module=auth_db
Nov 20 11:17:00 [5200] DBG:postgres:pg_init: 0x818b120=pkg_malloc(12) for database connection
Nov 20 11:17:00 [5200] DBG:postgres:pg_init: connection 0x818b138 found in pool
Nov 20 11:17:00 [5200] DBG:core:init_mod_child: type=PROC_TIMER, rank=-1, module=alias_db
Nov 20 11:17:00 [5200] DBG:postgres:pg_init: 0x818b1c8=pkg_malloc(12) for database connection
Nov 20 11:17:00 [5200] DBG:postgres:pg_init: connection 0x818b1e0 found in pool
Nov 20 11:17:00 [5200] DBG:core:init_mod_child: type=PROC_TIMER, rank=-1, module=domain
Nov 20 11:17:00 [5200] DBG:core:init_mod_child: type=PROC_TIMER, rank=-1, module=lcr
Nov 20 11:17:00 [5200] DBG:core:init_mod_child: type=PROC_TIMER, rank=-1, module=avpops
Nov 20 11:17:00 [5200] DBG:postgres:pg_init: 0x818b268=pkg_malloc(12) for database connection
Nov 20 11:17:00 [5200] DBG:postgres:pg_init: connection 0x818b280 found in pool
Nov 20 11:17:00 [5200] DBG:core:init_mod_child: type=PROC_TIMER, rank=-1, module=pdt
Nov 20 11:17:00 [5200] DBG:postgres:pg_init: 0x818b308=pkg_malloc(12) for database connection
Nov 20 11:17:00 [5200] DBG:postgres:pg_init: connection 0x818b320 found in pool
Nov 20 11:17:00 [5200] DBG:pdt:mod_child_init: #-1: database connection opened successfully
Nov 20 11:17:00 [5200] DBG:core:init_mod_child: type=PROC_TIMER, rank=-1, module=permissions
Nov 20 11:17:00 [5200] DBG:core:run_timer_process: tv = 0, 100000 , m=10
Nov 20 11:17:00 [5204] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82a7458)
Nov 20 11:17:00 [5204] DBG:core:init_mod_child: type=CHILD, rank=13, module=xlog
Nov 20 11:17:00 [5204] DBG:xlog:child_init: init_child [13]  pid [5204]
Nov 20 11:17:00 [5204] DBG:core:init_mod_child: type=CHILD, rank=13, module=acc
Nov 20 11:17:00 [5204] DBG:postgres:pg_init: 0x818aa08=pkg_malloc(12) for database connection
Nov 20 11:17:00 [5204] DBG:postgres:pg_init: connection 0x818a958 found in pool
Nov 20 11:17:00 [5204] DBG:core:init_mod_child: type=CHILD, rank=13, module=auth_db
Nov 20 11:17:00 [5204] DBG:postgres:pg_init: 0x818a9b0=pkg_malloc(12) for database connection
Nov 20 11:17:00 [5204] DBG:postgres:pg_init: connection 0x8189d20 found in pool
Nov 20 11:17:00 [5204] DBG:core:init_mod_child: type=CHILD, rank=13, module=alias_db
Nov 20 11:17:00 [5204] DBG:postgres:pg_init: 0x818b120=pkg_malloc(12) for database connection
Nov 20 11:17:00 [5204] DBG:postgres:pg_init: connection 0x818b138 found in pool
Nov 20 11:17:00 [5204] DBG:core:init_mod_child: type=CHILD, rank=13, module=domain
Nov 20 11:17:00 [5204] DBG:core:init_mod_child: type=CHILD, rank=13, module=lcr
Nov 20 11:17:00 [5204] DBG:postgres:pg_init: 0x818b1c8=pkg_malloc(12) for database connection
Nov 20 11:17:00 [5204] DBG:postgres:pg_init: connection 0x818b1e0 found in pool
Nov 20 11:17:00 [5204] DBG:core:init_mod_child: type=CHILD, rank=13, module=avpops
Nov 20 11:17:00 [5204] DBG:postgres:pg_init: 0x818b268=pkg_malloc(12) for database connection
Nov 20 11:17:00 [5204] DBG:postgres:pg_init: connection 0x818b280 found in pool
Nov 20 11:17:00 [5204] DBG:core:init_mod_child: type=CHILD, rank=13, module=pdt
Nov 20 11:17:00 [5204] DBG:postgres:pg_init: 0x818b308=pkg_malloc(12) for database connection
Nov 20 11:17:00 [5204] DBG:postgres:pg_init: connection 0x818b320 found in pool
Nov 20 11:17:00 [5204] DBG:pdt:mod_child_init: #13: database connection opened successfully
Nov 20 11:17:00 [5204] DBG:core:init_mod_child: type=CHILD, rank=13, module=permissions
Nov 20 11:17:00 [5204] DBG:core:io_watch_add: io_watch_add(0x8154de0, 25, 1, (nil)), fd_no=0
Nov 20 11:17:00 [5212] DBG:core:init_mod_child: type=PROC_TCP_MAIN, rank=-4, module=uri_db
Nov 20 11:17:00 [5212] DBG:postgres:pg_init: 0x818abd8=pkg_malloc(12) for database connection
Nov 20 11:17:00 [5212] DBG:postgres:pg_init: connection 0x8182f78 not found in pool
Nov 20 11:17:00 [5212] DBG:postgres:pg_new_conn: db_id = 0x8182f78
Nov 20 11:17:00 [5212] DBG:postgres:pg_new_conn: 0x818a888=pkg_malloc(44)
Nov 20 11:17:00 [5212] DBG:postgres:pg_new_conn: opening connection: postgres://xxxx:xxxx@localhost/openser
Nov 20 11:17:01 [5202] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82a7458)
Nov 20 11:17:01 [5202] DBG:postgres:pg_init: 0x818aa08=pkg_malloc(12) for database connection
Nov 20 11:17:01 [5202] DBG:postgres:pg_init: connection 0x818b110 found in pool
Nov 20 11:17:01 [5202] DBG:postgres:pg_init: 0x818a9b0=pkg_malloc(12) for database connection
Nov 20 11:17:01 [5202] DBG:postgres:pg_init: connection 0x818b130 found in pool
Nov 20 11:17:01 [5202] DBG:postgres:pg_init: 0x818b1c8=pkg_malloc(12) for database connection
Nov 20 11:17:01 [5202] DBG:postgres:pg_init: connection 0x818b1e0 found in pool
Nov 20 11:17:01 [5202] DBG:postgres:pg_init: 0x818b268=pkg_malloc(12) for database connection
Nov 20 11:17:01 [5202] DBG:postgres:pg_init: connection 0x818b280 found in pool
Nov 20 11:17:01 [5202] DBG:postgres:pg_init: 0x818b308=pkg_malloc(12) for database connection
Nov 20 11:17:01 [5202] DBG:postgres:pg_init: connection 0x818b320 found in pool
Nov 20 11:17:01 [5164] DBG:postgres:pg_new_conn: PQsetdbLogin(0x82a7458)
Nov 20 11:17:01 [5164] DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=registrar
Nov 20 11:17:01 [5164] DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=mi_fifo
Nov 20 11:17:01 [5164] DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=uri_db
Nov 20 11:17:01 [5164] DBG:postgres:pg_init: 0x818aa08=pkg_malloc(12) for database connection
Nov 20 11:17:01 [5164] DBG:postgres:pg_init: connection 0x818a958 found in pool
Nov 20 11:17:01 [5164] DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=xlog
Nov 20 11:17:01 [5164] DBG:xlog:child_init: init_child [0]  pid [5164]
Nov 20 11:17:01 [5164] DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=acc
Nov 20 11:17:01 [5164] DBG:postgres:pg_init: 0x818a9b0=pkg_malloc(12) for database connection
Nov 20 11:17:01 [5164] DBG:postgres:pg_init: connection 0x8189d20 found in pool
Nov 20 11:17:01 [5164] DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=auth_db
Nov 20 11:17:01 [5164] DBG:postgres:pg_init: 0x818b120=pkg_malloc(12) for database connection
Nov 20 11:17:01 [5164] DBG:postgres:pg_init: connection 0x818b138 found in pool
Nov 20 11:17:01 [5164] DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=alias_db
Nov 20 11:17:01 [5164] DBG:postgres:pg_init: 0x818b1c8=pkg_malloc(12) for database connection
Nov 20 11:17:01 [5164] DBG:postgres:pg_init: connection 0x818b1e0 found in pool
Nov 20 11:17:01 [5164] DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=domain
Nov 20 11:17:01 [5164] DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=lcr
Nov 20 11:17:01 [5164] DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=avpops
Nov 20 11:17:01 [5164] DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=pdt
Nov 20 11:17:01 [5164] DBG:postgres:pg_init: 0x818b268=pkg_malloc(12) for database connection
Nov 20 11:17:01 [5164] DBG:postgres:pg_init: connection 0x818b280 found in pool
Nov 20 11:17:01 [5164] DBG:pdt:mod_child_init: #0: database connection opened successfully
Nov 20 11:17:01 [5164] DBG:core:init_mod_child: type=PROC_MAIN, rank=0, module=permissions
Nov 20 11:17:01 [5164] INFO:core:handle_sigs: child process 5177 exited by a signal 11
Nov 20 11:17:01 [5164] INFO:core:handle_sigs: core was not generated
Nov 20 11:17:01 [5164] INFO:core:handle_sigs: terminating due to SIGCHLD
Nov 20 11:17:01 [5200] INFO:core:sig_usr: signal 15 received
Memory status (pkg):
fm_status (0x8175b00):
 heap size= 1048576
 used= 78816, used+overhead=101968, free=946608
 max used (+overhead)= 101968
dumping free list:
hash =   1 fragments no.:     1, unused:     0
		 bucket size:         8 -         8 (first         8)
hash =  67 fragments no.:     1, unused:     0
		 bucket size:       536 -       536 (first       536)
hash =  70 fragments no.:     1, unused:     0
		 bucket size:       560 -       560 (first       560)
hash =  74 fragments no.:     1, unused:     0
		 bucket size:       592 -       592 (first       592)
hash =  77 fragments no.:     1, unused:     0
		 bucket size:       616 -       616 (first       616)
hash =  81 fragments no.:     1, unused:     0
		 bucket size:       648 -       648 (first       648)
hash =  84 fragments no.:     1, unused:     0
		 bucket size:       672 -       672 (first       672)
hash = 2054 fragments no.:     1, unused:     0
		 bucket size:    524288 -   1048576 (first    943064)
TOTAL:      8 free fragments = 946696 free bytes
-----------------------------
Nov 20 11:17:01 [5191] INFO:core:sig_usr: signal 15 received
Memory status (pkg):
fm_status (0x8175b00):
 heap size= 1048576
 used= 78848, used+overhead=102008, free=946568
 max used (+overhead)= 102008
dumping free list:
hash =   1 fragments no.:     1, unused:     0
		 bucket size:         8 -         8 (first         8)
hash =  62 fragments no.:     1, unused:     0
		 bucket size:       496 -       496 (first       496)
hash =  70 fragments no.:     1, unused:     0
		 bucket size:       560 -       560 (first       560)
hash =  74 fragments no.:     1, unused:     0
		 bucket size:       592 -       592 (first       592)
hash =  77 fragments no.:     1, unused:     0
		 bucket size:       616 -       616 (first       616)
hash =  81 fragments no.:     1, unused:     0
		 bucket size:       648 -       648 (first       648)
hash =  84 fragments no.:     1, unused:     0
		 bucket size:       672 -       672 (first       672)
hash = 2054 fragments no.:     1, unused:     0
		 bucket size:    524288 -   1048576 (first    943064)
TOTAL:      8 free fragments = 946656 free bytes
-----------------------------
Nov 20 11:17:01 [5190] INFO:core:sig_usr: signal 15 received
Memory status (pkg):
fm_status (0x8175b00):
 heap size= 1048576
 used= 78848, used+overhead=102008, free=946568
 max used (+overhead)= 102008
dumping free list:
hash =   1 fragments no.:     1, unused:     0
		 bucket size:         8 -         8 (first         8)
hash =  62 fragments no.:     1, unused:     0
		 bucket size:       496 -       496 (first       496)
hash =  70 fragments no.:     1, unused:     0
		 bucket size:       560 -       560 (first       560)
hash =  74 fragments no.:     1, unused:     0
		 bucket size:       592 -       592 (first       592)
hash =  77 fragments no.:     1, unused:     0
		 bucket size:       616 -       616 (first       616)
hash =  81 fragments no.:     1, unused:     0
		 bucket size:       648 -       648 (first       648)
hash =  84 fragments no.:     1, unused:     0
		 bucket size:       672 -       672 (first       672)
hash = 2054 fragments no.:     1, unused:     0
		 bucket size:    524288 -   1048576 (first    943064)
TOTAL:      8 free fragments = 946656 free bytes
-----------------------------
Nov 20 11:17:01 [5184] INFO:core:sig_usr: signal 15 received
Memory status (pkg):
fm_status (0x8175b00):
 heap size= 1048576
 used= 78848, used+overhead=102008, free=946568
 max used (+overhead)= 102008
dumping free list:
hash =   1 fragments no.:     1, unused:     0
		 bucket size:         8 -         8 (first         8)
hash =  62 fragments no.:     1, unused:     0
		 bucket size:       496 -       496 (first       496)
hash =  70 fragments no.:     1, unused:     0
		 bucket size:       560 -       560 (first       560)
hash =  74 fragments no.:     1, unused:     0
		 bucket size:       592 -       592 (first       592)
hash =  77 fragments no.:     1, unused:     0
		 bucket size:       616 -       616 (first       616)
hash =  81 fragments no.:     1, unused:     0
		 bucket size:       648 -       648 (first       648)
hash =  84 fragments no.:     1, unused:     0
		 bucket size:       672 -       672 (first       672)
hash = 2054 fragments no.:     1, unused:     0
		 bucket size:    524288 -   1048576 (first    943064)
TOTAL:      8 free fragments = 946656 free bytes
-----------------------------
Nov 20 11:17:01 [5204] INFO:core:sig_usr: signal 15 received
Memory status (pkg):
fm_status (0x8175b00):
 heap size= 1048576
 used= 129056, used+overhead=152224, free=896352
 max used (+overhead)= 152224
dumping free list:
hash =   1 fragments no.:     1, unused:     0
		 bucket size:         8 -         8 (first         8)
hash =  67 fragments no.:     1, unused:     0
		 bucket size:       536 -       536 (first       536)
hash =  70 fragments no.:     1, unused:     0
		 bucket size:       560 -       560 (first       560)
hash =  74 fragments no.:     1, unused:     0
		 bucket size:       592 -       592 (first       592)
hash =  77 fragments no.:     1, unused:     0
		 bucket size:       616 -       616 (first       616)
hash =  81 fragments no.:     1, unused:     0
		 bucket size:       648 -       648 (first       648)
hash =  84 fragments no.:     1, unused:     0
		 bucket size:       672 -       672 (first       672)
hash = 2054 fragments no.:     1, unused:     0
		 bucket size:    524288 -   1048576 (first    892808)
TOTAL:      8 free fragments = 896440 free bytes
-----------------------------
Nov 20 11:17:01 [5199] INFO:core:sig_usr: signal 15 received
Memory status (pkg):
fm_status (0x8175b00):
 heap size= 1048576
 used= 78848, used+overhead=102008, free=946568
 max used (+overhead)= 102008
dumping free list:
hash =   1 fragments no.:     1, unused:     0
		 bucket size:         8 -         8 (first         8)
hash =  62 fragments no.:     1, unused:     0
		 bucket size:       496 -       496 (first       496)
hash =  70 fragments no.:     1, unused:     0
		 bucket size:       560 -       560 (first       560)
hash =  74 fragments no.:     1, unused:     0
		 bucket size:       592 -       592 (first       592)
hash =  77 fragments no.:     1, unused:     0
		 bucket size:       616 -       616 (first       616)
hash =  81 fragments no.:     1, unused:     0
		 bucket size:       648 -       648 (first       648)
hash =  84 fragments no.:     1, unused:     0
		 bucket size:       672 -       672 (first       672)
hash = 2054 fragments no.:     1, unused:     0
		 bucket size:    524288 -   1048576 (first    943064)
TOTAL:      8 free fragments = 946656 free bytes
-----------------------------
Nov 20 11:17:01 [5197] INFO:core:sig_usr: signal 15 received
Memory status (pkg):
fm_status (0x8175b00):
 heap size= 1048576
 used= 78848, used+overhead=102008, free=946568
 max used (+overhead)= 102008
dumping free list:
hash =   1 fragments no.:     1, unused:     0
		 bucket size:         8 -         8 (first         8)
hash =  62 fragments no.:     1, unused:     0
		 bucket size:       496 -       496 (first       496)
hash =  70 fragments no.:     1, unused:     0
		 bucket size:       560 -       560 (first       560)
hash =  74 fragments no.:     1, unused:     0
		 bucket size:       592 -       592 (first       592)
hash =  77 fragments no.:     1, unused:     0
		 bucket size:       616 -       616 (first       616)
hash =  81 fragments no.:     1, unused:     0
		 bucket size:       648 -       648 (first       648)
hash =  84 fragments no.:     1, unused:     0
		 bucket size:       672 -       672 (first       672)
hash = 2054 fragments no.:     1, unused:     0
		 bucket size:    524288 -   1048576 (first    943064)
TOTAL:      8 free fragments = 946656 free bytes
-----------------------------
Nov 20 11:17:01 [5193] INFO:core:sig_usr: signal 15 received
Memory status (pkg):
fm_status (0x8175b00):
 heap size= 1048576
 used= 78848, used+overhead=102008, free=946568
 max used (+overhead)= 102008
dumping free list:
hash =   1 fragments no.:     1, unused:     0
		 bucket size:         8 -         8 (first         8)
hash =  62 fragments no.:     1, unused:     0
		 bucket size:       496 -       496 (first       496)
hash =  70 fragments no.:     1, unused:     0
		 bucket size:       560 -       560 (first       560)
hash =  74 fragments no.:     1, unused:     0
		 bucket size:       592 -       592 (first       592)
hash =  77 fragments no.:     1, unused:     0
		 bucket size:       616 -       616 (first       616)
hash =  81 fragments no.:     1, unused:     0
		 bucket size:       648 -       648 (first       648)
hash =  84 fragments no.:     1, unused:     0
		 bucket size:       672 -       672 (first       672)
hash = 2054 fragments no.:     1, unused:     0
		 bucket size:    524288 -   1048576 (first    943064)
TOTAL:      8 free fragments = 946656 free bytes
-----------------------------
Nov 20 11:17:01 [5188] INFO:core:sig_usr: signal 15 received
Memory status (pkg):
fm_status (0x8175b00):
 heap size= 1048576
 used= 78848, used+overhead=102008, free=946568
 max used (+overhead)= 102008
dumping free list:
hash =   1 fragments no.:     1, unused:     0
		 bucket size:         8 -         8 (first         8)
hash =  62 fragments no.:     1, unused:     0
		 bucket size:       496 -       496 (first       496)
hash =  70 fragments no.:     1, unused:     0
		 bucket size:       560 -       560 (first       560)
hash =  74 fragments no.:     1, unused:     0
		 bucket size:       592 -       592 (first       592)
hash =  77 fragments no.:     1, unused:     0
		 bucket size:       616 -       616 (first       616)
hash =  81 fragments no.:     1, unused:     0
		 bucket size:       648 -       648 (first       648)
hash =  84 fragments no.:     1, unused:     0
		 bucket size:       672 -       672 (first       672)
hash = 2054 fragments no.:     1, unused:     0
		 bucket size:    524288 -   1048576 (first    943064)
TOTAL:      8 free fragments = 946656 free bytes
-----------------------------
Nov 20 11:17:01 [5186] INFO:core:sig_usr: signal 15 received
Memory status (pkg):
fm_status (0x8175b00):
 heap size= 1048576
 used= 78848, used+overhead=102008, free=946568
 max used (+overhead)= 102008
dumping free list:
hash =   1 fragments no.:     1, unused:     0
		 bucket size:         8 -         8 (first         8)
hash =  62 fragments no.:     1, unused:     0
		 bucket size:       496 -       496 (first       496)
hash =  70 fragments no.:     1, unused:     0
		 bucket size:       560 -       560 (first       560)
hash =  74 fragments no.:     1, unused:     0
		 bucket size:       592 -       592 (first       592)
hash =  77 fragments no.:     1, unused:     0
		 bucket size:       616 -       616 (first       616)
hash =  81 fragments no.:     1, unused:     0
		 bucket size:       648 -       648 (first       648)
hash =  84 fragments no.:     1, unused:     0
		 bucket size:       672 -       672 (first       672)
hash = 2054 fragments no.:     1, unused:     0
		 bucket size:    524288 -   1048576 (first    943064)
TOTAL:      8 free fragments = 946656 free bytes
-----------------------------
Nov 20 11:17:01 [5183] INFO:core:sig_usr: signal 15 received
Memory status (pkg):
fm_status (0x8175b00):
 heap size= 1048576
 used= 78848, used+overhead=102008, free=946568
 max used (+overhead)= 102008
dumping free list:
hash =   1 fragments no.:     1, unused:     0
		 bucket size:         8 -         8 (first         8)
hash =  62 fragments no.:     1, unused:     0
		 bucket size:       496 -       496 (first       496)
hash =  70 fragments no.:     1, unused:     0
		 bucket size:       560 -       560 (first       560)
hash =  74 fragments no.:     1, unused:     0
		 bucket size:       592 -       592 (first       592)
hash =  77 fragments no.:     1, unused:     0
		 bucket size:       616 -       616 (first       616)
hash =  81 fragments no.:     1, unused:     0
		 bucket size:       648 -       648 (first       648)
hash =  84 fragments no.:     1, unused:     0
		 bucket size:       672 -       672 (first       672)
hash = 2054 fragments no.:     1, unused:     0
		 bucket size:    524288 -   1048576 (first    943064)
TOTAL:      8 free fragments = 946656 free bytes
-----------------------------
Nov 20 11:17:01 [5181] INFO:core:sig_usr: signal 15 received
Memory status (pkg):
fm_status (0x8175b00):
 heap size= 1048576
 used= 78848, used+overhead=102008, free=946568
 max used (+overhead)= 102008
dumping free list:
hash =   1 fragments no.:     1, unused:     0
		 bucket size:         8 -         8 (first         8)
hash =  62 fragments no.:     1, unused:     0
		 bucket size:       496 -       496 (first       496)
hash =  70 fragments no.:     1, unused:     0
		 bucket size:       560 -       560 (first       560)
hash =  74 fragments no.:     1, unused:     0
		 bucket size:       592 -       592 (first       592)
hash =  77 fragments no.:     1, unused:     0
		 bucket size:       616 -       616 (first       616)
hash =  81 fragments no.:     1, unused:     0
		 bucket size:       648 -       648 (first       648)
hash =  84 fragments no.:     1, unused:     0
		 bucket size:       672 -       672 (first       672)
hash = 2054 fragments no.:     1, unused:     0
		 bucket size:    524288 -   1048576 (first    943064)
TOTAL:      8 free fragments = 946656 free bytes
-----------------------------
Nov 20 11:17:01 [5179] INFO:core:sig_usr: signal 15 received
Memory status (pkg):
fm_status (0x8175b00):
 heap size= 1048576
 used= 78848, used+overhead=102008, free=946568
 max used (+overhead)= 102008
dumping free list:
hash =   1 fragments no.:     1, unused:     0
		 bucket size:         8 -         8 (first         8)
hash =  62 fragments no.:     1, unused:     0
		 bucket size:       496 -       496 (first       496)
hash =  70 fragments no.:     1, unused:     0
		 bucket size:       560 -       560 (first       560)
hash =  74 fragments no.:     1, unused:     0
		 bucket size:       592 -       592 (first       592)
hash =  77 fragments no.:     1, unused:     0
		 bucket size:       616 -       616 (first       616)
hash =  81 fragments no.:     1, unused:     0
		 bucket size:       648 -       648 (first       648)
hash =  84 fragments no.:     1, unused:     0
		 bucket size:       672 -       672 (first       672)
hash = 2054 fragments no.:     1, unused:     0
		 bucket size:    524288 -   1048576 (first    943064)
TOTAL:      8 free fragments = 946656 free bytes
-----------------------------
Nov 20 11:17:01 [5202] INFO:core:sig_usr: signal 15 received
Memory status (pkg):
fm_status (0x8175b00):
 heap size= 1048576
 used= 88072, used+overhead=111200, free=937376
 max used (+overhead)= 111200
dumping free list:
hash =   1 fragments no.:     1, unused:     0
		 bucket size:         8 -         8 (first         8)
hash =  67 fragments no.:     1, unused:     0
		 bucket size:       536 -       536 (first       536)
hash =  70 fragments no.:     1, unused:     0
		 bucket size:       560 -       560 (first       560)
hash =  74 fragments no.:     1, unused:     0
		 bucket size:       592 -       592 (first       592)
hash =  77 fragments no.:     1, unused:     0
		 bucket size:       616 -       616 (first       616)
hash =  81 fragments no.:     1, unused:     0
		 bucket size:       648 -       648 (first       648)
hash =  84 fragments no.:     1, unused:     0
		 bucket size:       672 -       672 (first       672)
hash = 2054 fragments no.:     1, unused:     0
		 bucket size:    524288 -   1048576 (first    933832)
TOTAL:      8 free fragments = 937464 free bytes
-----------------------------
Nov 20 11:17:02 [5208] INFO:core:sig_usr: signal 15 received
Memory status (pkg):
fm_status (0x8175b00):
 heap size= 1048576
 used= 78128, used+overhead=101024, free=947552
 max used (+overhead)= 101024
dumping free list:
hash =   2 fragments no.:     7, unused:     0
		 bucket size:        16 -        16 (first        16)
hash =   6 fragments no.:     1, unused:     0
		 bucket size:        48 -        48 (first        48)
hash =  16 fragments no.:     1, unused:     0
		 bucket size:       128 -       128 (first       128)
hash =  20 fragments no.:     1, unused:     0
		 bucket size:       160 -       160 (first       160)
hash =  63 fragments no.:     1, unused:     0
		 bucket size:       504 -       504 (first       504)
hash =  67 fragments no.:     1, unused:     0
		 bucket size:       536 -       536 (first       536)
hash =  70 fragments no.:     1, unused:     0
		 bucket size:       560 -       560 (first       560)
hash =  74 fragments no.:     1, unused:     0
		 bucket size:       592 -       592 (first       592)
hash =  77 fragments no.:     1, unused:     0
		 bucket size:       616 -       616 (first       616)
hash =  81 fragments no.:     1, unused:     0
		 bucket size:       648 -       648 (first       648)
hash =  84 fragments no.:     1, unused:     0
		 bucket size:       672 -       672 (first       672)
hash = 2054 fragments no.:     1, unused:     0
		 bucket size:    524288 -   1048576 (first    943064)
TOTAL:     18 free fragments = 947640 free bytes
-----------------------------
Nov 20 11:17:02 [5206] INFO:core:sig_usr: signal 15 received
Memory status (pkg):
fm_status (0x8175b00):
 heap size= 1048576
 used= 78128, used+overhead=101024, free=947552
 max used (+overhead)= 101024
dumping free list:
hash =   2 fragments no.:     7, unused:     0
		 bucket size:        16 -        16 (first        16)
hash =   6 fragments no.:     1, unused:     0
		 bucket size:        48 -        48 (first        48)
hash =  16 fragments no.:     1, unused:     0
		 bucket size:       128 -       128 (first       128)
hash =  20 fragments no.:     1, unused:     0
		 bucket size:       160 -       160 (first       160)
hash =  63 fragments no.:     1, unused:     0
		 bucket size:       504 -       504 (first       504)
hash =  67 fragments no.:     1, unused:     0
		 bucket size:       536 -       536 (first       536)
hash =  70 fragments no.:     1, unused:     0
		 bucket size:       560 -       560 (first       560)
hash =  74 fragments no.:     1, unused:     0
		 bucket size:       592 -       592 (first       592)
hash =  77 fragments no.:     1, unused:     0
		 bucket size:       616 -       616 (first       616)
hash =  81 fragments no.:     1, unused:     0
		 bucket size:       648 -       648 (first       648)
hash =  84 fragments no.:     1, unused:     0
		 bucket size:       672 -       672 (first       672)
hash = 2054 fragments no.:     1, unused:     0
		 bucket size:    524288 -   1048576 (first    943064)
TOTAL:     18 free fragments = 947640 free bytes
-----------------------------
Nov 20 11:17:02 [5210] INFO:core:sig_usr: signal 15 received
Memory status (pkg):
fm_status (0x8175b00):
 heap size= 1048576
 used= 78128, used+overhead=101024, free=947552
 max used (+overhead)= 101024
dumping free list:
hash =   2 fragments no.:     7, unused:     0
		 bucket size:        16 -        16 (first        16)
hash =   6 fragments no.:     1, unused:     0
		 bucket size:        48 -        48 (first        48)
hash =  16 fragments no.:     1, unused:     0
		 bucket size:       128 -       128 (first       128)
hash =  20 fragments no.:     1, unused:     0
		 bucket size:       160 -       160 (first       160)
hash =  63 fragments no.:     1, unused:     0
		 bucket size:       504 -       504 (first       504)
hash =  67 fragments no.:     1, unused:     0
		 bucket size:       536 -       536 (first       536)
hash =  70 fragments no.:     1, unused:     0
		 bucket size:       560 -       560 (first       560)
hash =  74 fragments no.:     1, unused:     0
		 bucket size:       592 -       592 (first       592)
hash =  77 fragments no.:     1, unused:     0
		 bucket size:       616 -       616 (first       616)
hash =  81 fragments no.:     1, unused:     0
		 bucket size:       648 -       648 (first       648)
hash =  84 fragments no.:     1, unused:     0
		 bucket size:       672 -       672 (first       672)
hash = 2054 fragments no.:     1, unused:     0
		 bucket size:    524288 -   1048576 (first    943064)
TOTAL:     18 free fragments = 947640 free bytes
-----------------------------
Nov 20 11:17:02 [5212] INFO:core:sig_usr: signal 15 received
Memory status (pkg):
fm_status (0x8175b00):
 heap size= 1048576
 used= 69936, used+overhead=92824, free=955752
 max used (+overhead)= 92824
dumping free list:
hash =   2 fragments no.:     7, unused:     0
		 bucket size:        16 -        16 (first        16)
hash =   6 fragments no.:     1, unused:     0
		 bucket size:        48 -        48 (first        48)
hash =  16 fragments no.:     1, unused:     0
		 bucket size:       128 -       128 (first       128)
hash =  20 fragments no.:     1, unused:     0
		 bucket size:       160 -       160 (first       160)
hash =  63 fragments no.:     1, unused:     0
		 bucket size:       504 -       504 (first       504)
hash =  67 fragments no.:     1, unused:     0
		 bucket size:       536 -       536 (first       536)
hash =  70 fragments no.:     1, unused:     0
		 bucket size:       560 -       560 (first       560)
hash =  74 fragments no.:     1, unused:     0
		 bucket size:       592 -       592 (first       592)
hash =  77 fragments no.:     1, unused:     0
		 bucket size:       616 -       616 (first       616)
hash =  81 fragments no.:     1, unused:     0
		 bucket size:       648 -       648 (first       648)
hash =  84 fragments no.:     1, unused:     0
		 bucket size:       672 -       672 (first       672)
hash = 2054 fragments no.:     1, unused:     0
		 bucket size:    524288 -   1048576 (first    951264)
TOTAL:     18 free fragments = 955840 free bytes
-----------------------------
Nov 20 11:17:02 [5164] DBG:pdt:mod_destroy: cleaning up
Nov 20 11:17:02 [5164] DBG:core:pool_remove: connection still kept in the pool
Nov 20 11:17:02 [5164] DBG:postgres:pg_close: 0x818b268=pkg_free() _con
Nov 20 11:17:02 [5164] DBG:core:pool_remove: connection still kept in the pool
Nov 20 11:17:02 [5164] DBG:postgres:pg_close: 0x818b1c8=pkg_free() _con
Nov 20 11:17:02 [5164] DBG:core:pool_remove: connection still kept in the pool
Nov 20 11:17:02 [5164] DBG:postgres:pg_close: 0x818b120=pkg_free() _con
Nov 20 11:17:02 [5164] DBG:core:pool_remove: connection still kept in the pool
Nov 20 11:17:02 [5164] DBG:postgres:pg_close: 0x818a9b0=pkg_free() _con
Nov 20 11:17:02 [5164] DBG:xlog:destroy: destroy module...
Nov 20 11:17:02 [5164] DBG:core:pool_remove: connection still kept in the pool
Nov 20 11:17:02 [5164] DBG:postgres:pg_close: 0x818aa08=pkg_free() _con
Nov 20 11:17:02 [5164] DBG:core:pool_remove: removing connection from the pool
Nov 20 11:17:02 [5164] DBG:postgres:pg_free_conn: PQfinish(0x82a7458)
Nov 20 11:17:02 [5164] DBG:postgres:pg_free_conn: pkg_free(0x818a888)
Nov 20 11:17:02 [5164] DBG:postgres:pg_close: 0x818abd8=pkg_free() _con
Nov 20 11:17:02 [5164] DBG:tm:tm_shutdown: tm_shutdown : start
Nov 20 11:17:02 [5164] DBG:tm:unlink_timer_lists: emptying DELETE list
Nov 20 11:17:02 [5164] DBG:tm:tm_shutdown: emptying hash table
Nov 20 11:17:02 [5164] DBG:tm:tm_shutdown: releasing timers
Nov 20 11:17:02 [5164] DBG:tm:tm_shutdown: removing semaphores
Nov 20 11:17:02 [5164] DBG:tm:tm_shutdown: destroying callback lists
Nov 20 11:17:02 [5164] DBG:tm:tm_shutdown: tm_shutdown : done
Memory status (pkg):
fm_status (0x8175b00):
 heap size= 1048576
 used= 65216, used+overhead=88304, free=960272
 max used (+overhead)= 93608
dumping free list:
hash =   1 fragments no.:     1, unused:     0
		 bucket size:         8 -         8 (first         8)
hash =   2 fragments no.:    33, unused:     0
		 bucket size:        16 -        16 (first        16)
hash =   3 fragments no.:     2, unused:     0
		 bucket size:        24 -        24 (first        24)
hash =   4 fragments no.:     4, unused:     0
		 bucket size:        32 -        32 (first        32)
hash =   5 fragments no.:     4, unused:     0
		 bucket size:        40 -        40 (first        40)
hash =   6 fragments no.:     1, unused:     0
		 bucket size:        48 -        48 (first        48)
hash =   7 fragments no.:     3, unused:     0
		 bucket size:        56 -        56 (first        56)
hash =  14 fragments no.:     1, unused:     0
		 bucket size:       112 -       112 (first       112)
hash =  21 fragments no.:     1, unused:     0
		 bucket size:       168 -       168 (first       168)
hash =  67 fragments no.:     1, unused:     0
		 bucket size:       536 -       536 (first       536)
hash =  70 fragments no.:     1, unused:     0
		 bucket size:       560 -       560 (first       560)
hash =  74 fragments no.:     1, unused:     0
		 bucket size:       592 -       592 (first       592)
hash =  77 fragments no.:     1, unused:     0
		 bucket size:       616 -       616 (first       616)
hash =  81 fragments no.:     1, unused:     0
		 bucket size:       648 -       648 (first       648)
hash =  84 fragments no.:     1, unused:     0
		 bucket size:       672 -       672 (first       672)
hash = 513 fragments no.:     1, unused:     0
		 bucket size:      4104 -      4104 (first      4104)
hash = 2054 fragments no.:     1, unused:     0
		 bucket size:    524288 -   1048576 (first    951264)
TOTAL:     58 free fragments = 960360 free bytes
-----------------------------
Memory status (shm):
fm_status (0xb3a44000):
 heap size= 67108864
 used= 150984, used+overhead=168768, free=66940096
 max used (+overhead)= 1777528
dumping free list:
hash =   1 fragments no.:    53, unused:     0
		 bucket size:         8 -         8 (first         8)
hash =   2 fragments no.:    15, unused:     0
		 bucket size:        16 -        16 (first        16)
hash =   3 fragments no.:     8, unused:     0
		 bucket size:        24 -        24 (first        24)
hash =   4 fragments no.:    43, unused:     0
		 bucket size:        32 -        32 (first        32)
hash =   5 fragments no.:     2, unused:     0
		 bucket size:        40 -        40 (first        40)
hash =   6 fragments no.:     1, unused:     0
		 bucket size:        48 -        48 (first        48)
hash =   8 fragments no.:     2, unused:     0
		 bucket size:        64 -        64 (first        64)
hash =  12 fragments no.:     1, unused:     0
		 bucket size:        96 -        96 (first        96)
hash =  15 fragments no.:     1, unused:     0
		 bucket size:       120 -       120 (first       120)
hash =  64 fragments no.:     7, unused:     0
		 bucket size:       512 -       512 (first       512)
hash = 130 fragments no.:     1, unused:     0
		 bucket size:      1040 -      1040 (first      1040)
hash = 144 fragments no.:     1, unused:     0
		 bucket size:      1152 -      1152 (first      1152)
hash = 257 fragments no.:     1, unused:     0
		 bucket size:      2056 -      2056 (first      2056)
hash = 258 fragments no.:     2, unused:     0
		 bucket size:      2064 -      2064 (first      2064)
hash = 350 fragments no.:     1, unused:     0
		 bucket size:      2800 -      2800 (first      2800)
hash = 512 fragments no.:     2, unused:     0
		 bucket size:      4096 -      4096 (first      4096)
hash = 1280 fragments no.:     1, unused:     0
		 bucket size:     10240 -     10240 (first     10240)
hash = 2055 fragments no.:     1, unused:     0
		 bucket size:   1048576 -   2097152 (first   1572864)
hash = 2060 fragments no.:     1, unused:     0
		 bucket size:  33554432 -  67108864 (first  65331336)
TOTAL:    144 free fragments = 66940096 free bytes
-----------------------------
Nov 20 11:17:02 [5164] DBG:core:shm_mem_destroy: 
Nov 20 11:17:02 [5164] DBG:core:shm_mem_destroy: destroying the shared memory lock
Nov 20 11:17:02 [5164] DBG:core:handle_sigs: terminating due to SIGCHLD

openser2:/etc/openser# script stop
Script started, file is stop
openser2:/etc/openser# 
openser2:/etc/openser# ps aux
USER       PID %CPU %MEM    VSZ   RSS TTY      STAT START   TIME COMMAND
root         1  0.0  0.1   1944   644 ?        Ss   10:27   0:02 init [2]  
root         2  0.0  0.0      0     0 ?        S    10:27   0:00 [migration/0]
root         3  0.0  0.0      0     0 ?        SN   10:27   0:00 [ksoftirqd/0]
root         4  0.0  0.0      0     0 ?        S<   10:27   0:00 [events/0]
root         5  0.0  0.0      0     0 ?        S<   10:27   0:00 [khelper]
root         6  0.0  0.0      0     0 ?        S<   10:27   0:00 [kthread]
root         9  0.0  0.0      0     0 ?        S<   10:27   0:00 [kblockd/0]
root        10  0.0  0.0      0     0 ?        S<   10:27   0:00 [kacpid]
root        41  0.0  0.0      0     0 ?        S<   10:27   0:00 [kseriod]
root        77  0.0  0.0      0     0 ?        S    10:27   0:00 [pdflush]
root        78  0.0  0.0      0     0 ?        S    10:27   0:02 [pdflush]
root        79  0.0  0.0      0     0 ?        S<   10:27   0:00 [kswapd0]
root        80  0.0  0.0      0     0 ?        S<   10:27   0:00 [aio/0]
root       547  0.0  0.0      0     0 ?        S<   10:27   0:00 [khubd]
root       810  0.0  0.0      0     0 ?        S<   10:27   0:01 [kjournald]
root       987  0.0  0.1   2180   592 ?        S<s  10:27   0:00 udevd --daemon
root      1231  0.0  0.0      0     0 ?        S<   10:27   0:00 [kpsmoused]
root      1504  0.0  0.0      0     0 ?        S<   10:27   0:00 [kmirrord]
daemon    1601  0.0  0.0   1684   364 ?        Ss   10:27   0:00 /sbin/portmap
root      1820  0.0  0.1   1728   700 ?        Ss   10:27   0:00 /sbin/syslogd
root      1826  0.0  0.0   1580   380 ?        Ss   10:27   0:00 /sbin/klogd -x
root      1898  0.0  0.1   1572   556 ?        Ss   10:27   0:00 /usr/sbin/acpid -c /etc/acpi/events -s /var/run/acpid.socket
100       1944  0.0  0.1   5344   996 ?        Ss   10:27   0:00 /usr/sbin/exim4 -bd -q30m
root      1956  0.0  0.1   1748   564 ?        Ss   10:27   0:00 /usr/sbin/inetd
root      1972  0.0  0.2   4932  1088 ?        Ss   10:27   0:00 /usr/sbin/sshd
statd     2000  0.0  0.1   1856   820 ?        Ss   10:27   0:00 /sbin/rpc.statd
root      2014  0.0  0.0      0     0 ?        S<   10:27   0:00 [cqueue/0]
root      2021  0.0  0.0      0     0 ?        S    10:27   0:00 [drbd0_worker]
daemon    2040  0.0  0.0   1832   424 ?        Ss   10:27   0:00 /usr/sbin/atd
root      2047  0.0  0.1   2196   872 ?        Ss   10:27   0:00 /usr/sbin/cron
root      2064  0.0  0.1   1704   584 ?        Ss   10:28   0:00 /usr/sbin/ucarp -p inter -i eth0 -s 172.25.18.162 -v 1 -a 172.25.18.163 -u /etc/ucarp/up.sh -d /etc/
root      2083  0.0  0.0   1576   496 tty1     Ss+  10:28   0:00 /sbin/getty 38400 tty1
root      2085  0.0  0.0   1572   492 tty2     Ss+  10:28   0:00 /sbin/getty 38400 tty2
root      2086  0.0  0.0   1572   492 tty3     Ss+  10:28   0:00 /sbin/getty 38400 tty3
root      2087  0.0  0.0   1572   492 tty4     Ss+  10:28   0:00 /sbin/getty 38400 tty4
root      2088  0.0  0.0   1576   496 tty5     Ss+  10:28   0:00 /sbin/getty 38400 tty5
root      2090  0.0  0.0   1572   492 tty6     Ss+  10:28   0:00 /sbin/getty 38400 tty6
root      2127  0.0  0.0      0     0 ?        S<   10:28   0:00 [kjournald]
postgres  2143  0.2  0.9  40960  5012 ?        S    10:28   0:07 /usr/lib/postgresql/8.3/bin/postgres -D /var/lib/postgresql/8.3/main -c config_file=/etc/postgresql/
postgres  2146  0.0  0.4  40960  2132 ?        Ss   10:28   0:00 postgres: writer process                                                                           
postgres  2147  0.0  0.2  40960  1320 ?        Ss   10:28   0:00 postgres: wal writer process                                                                       
postgres  2148  0.0  0.3  41104  1788 ?        Ss   10:28   0:00 postgres: autovacuum launcher process                                                              
postgres  2149  0.0  0.3  12420  1584 ?        Ss   10:28   0:00 postgres: stats collector process                                                                  
root      2229  0.4  0.4   7860  2368 ?        Ss   10:29   0:12 sshd: root at pts/0 
root      2232  0.1  0.3   4000  1736 pts/0    Ss   10:29   0:04 -bash
postgres  2425  0.0  1.1  42700  6164 ?        Ss   10:41   0:00 postgres: postgres postgres 172.25.18.97(53306) idle                                               
postgres  2426  0.2  1.6  43468  8548 ?        Ss   10:41   0:04 postgres: postgres openser 172.25.18.97(53307) idle                                                
root      5149  0.0  0.1   2764   580 pts/0    S+   11:16   0:00 script output.txt
root      5150  0.4  0.0   2768   396 pts/0    S+   11:16   0:00 script output.txt
root      5151  0.4  0.3   3996  1724 pts/1    Ss   11:16   0:00 bash -i
root      5219  0.4  0.1   2768   580 pts/1    S+   11:17   0:00 script stop
root      5220  0.0  0.0   2772   396 pts/1    S+   11:17   0:00 script stop
root      5221  2.0  0.3   3984  1672 pts/2    Ss   11:17   0:00 bash -i
root      5222  0.0  0.1   3428   984 pts/2    R+   11:1


More information about the Users mailing list