[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.org[16Pcd /etc/echo "ztdummy" >> /etc/modules
openser2:/etc/openser# [18Pmake samples[7Pclearmake && make installclear[Kmake samplesecho "ztdummy" >> /etc/modules
openser2:/etc/openser# cd /etc/[Kmv asterisk asterisk.org[9Pcat /proc/drbd shutdown -h now[Kshutdown -h nowcat /proc/drbd mv asterisk asterisk.org[9Pcat /proc/drbd shutdown -h now[K/etc/init.d/opese[K[Knser 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