See http://xarg.net/writing/tuning/forums-scale for why this is here... -------------------------------------------------------------------------------- [13/Dec/2002:14:28:02][6744.1024][-main-] Notice: nsmain: AOLserver/3.3.1+ad13 starting [13/Dec/2002:14:28:02][6744.1024][-main-] Notice: nsmain: security info: uid=501, euid=501, gid=501, egid=501 [13/Dec/2002:14:28:02][6744.1024][-main-] Notice: nsmain: max files: FD_SETSIZE = 1024, rl_cur = 1024, rl_max = 1024 [13/Dec/2002:14:28:02][6744.1024][-main-] Notice: return: redirecting '404' to 'global/file-not-found' [13/Dec/2002:14:28:02][6744.1024][-main-] Notice: return: redirecting '403' to 'global/forbidden' [13/Dec/2002:14:28:02][6744.1024][-main-] Notice: modload: loading '/usr/local/aolserver/bin/postgres.so' [13/Dec/2002:14:28:02][6744.1024][-main-] Notice: PostgreSQL loaded. [13/Dec/2002:14:28:02][6744.1024][-main-] Notice: adp: mapped /*.adp [13/Dec/2002:14:28:02][6744.1024][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nssock.so' [13/Dec/2002:14:28:02][6744.1024][-main-] Debug: modload: initializing module 'nssock' [13/Dec/2002:14:28:02][6744.1024][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nslog.so' [13/Dec/2002:14:28:02][6744.1024][-main-] Notice: nslog: opened '/home/nsadmin/log/oatest.log' [13/Dec/2002:14:28:02][6744.1024][-main-] Debug: modload: initializing module 'nslog' [13/Dec/2002:14:28:02][6744.1024][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nssha1.so' [13/Dec/2002:14:28:02][6744.1024][-main-] Debug: modload: initializing module 'nssha1' [13/Dec/2002:14:28:02][6744.1024][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nscache.so' [13/Dec/2002:14:28:02][6744.1024][-main-] Notice: nscache module version @VER@ [13/Dec/2002:14:28:02][6744.1024][-main-] Debug: modload: initializing module 'nscache' [13/Dec/2002:14:28:02][6744.1024][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nsrewrite.so' [13/Dec/2002:14:28:02][6744.1024][-main-] Debug: modload: initializing module 'nsrewrite' [13/Dec/2002:14:28:02][6744.1024][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nsxml.so' [13/Dec/2002:14:28:02][6744.1024][-main-] Notice: nsxml module starting [13/Dec/2002:14:28:02][6744.1024][-main-] Debug: modload: initializing module 'nsxml' [13/Dec/2002:14:28:02][6744.1024][-main-] Notice: modload: loading '/usr/local/aolserver/bin/dqd_utils8.so' [13/Dec/2002:14:28:02][6744.1024][-main-] Notice: dqd_utils version 1.3 for Tcl 8 [13/Dec/2002:14:28:02][6744.1024][-main-] Debug: modload: initializing module 'dqd_utils' [13/Dec/2002:14:28:05][6744.1024][-main-] Notice: conf: [ns/server/oatest]enabletclpages = 1 [13/Dec/2002:14:28:05][6744.1024][-main-] Notice: tcl: enabling .tcl pages [13/Dec/2002:14:28:05][6744.1024][-main-] Notice: Loading OpenACS, rooted at /web/oatest [13/Dec/2002:14:28:05][6744.1024][-main-] Notice: Sourcing /web/oatest/packages/acs-bootstrap-installer/bootstrap.tcl [13/Dec/2002:14:28:05][6744.1024][-main-] Notice: Bootstrap: sourcing /web/oatest/packages/acs-bootstrap-installer/tcl/00-proc-procs.tcl [13/Dec/2002:14:28:05][6744.1024][-main-] Notice: Bootstrap: sourcing /web/oatest/packages/acs-bootstrap-installer/tcl/10-utilities-procs.tcl [13/Dec/2002:14:28:05][6744.1024][-main-] Notice: Bootstrap: sourcing /web/oatest/packages/acs-bootstrap-installer/tcl/20-db-bootstrap-procs.tcl [13/Dec/2002:14:28:05][6744.1024][-main-] Notice: Bootstrap: sourcing /web/oatest/packages/acs-bootstrap-installer/tcl/30-apm-load-procs.tcl [13/Dec/2002:14:28:05][6744.1024][-main-] Notice: Bootstrap: sourcing /web/oatest/packages/acs-bootstrap-installer/tcl/40-db-query-dispatcher-procs.tcl [13/Dec/2002:14:28:05][6744.1024][-main-] Notice: Database API: The following pools are available: subquery main log [13/Dec/2002:14:28:05][6744.1024][-main-] Notice: dbdrv: opening database 'postgres:localhost::oatest' [13/Dec/2002:14:28:05][6744.1024][-main-] Notice: Opening oatest on localhost [13/Dec/2002:14:28:05][6744.1024][-main-] Notice: Ns_PgOpenDb(postgres): Openned connection to localhost::oatest. [13/Dec/2002:14:28:05][6744.1024][-main-] Notice: dbdrv: opening database 'postgres:localhost::oatest' [13/Dec/2002:14:28:05][6744.1024][-main-] Notice: Opening oatest on localhost [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: Ns_PgOpenDb(postgres): Openned connection to localhost::oatest. [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: dbdrv: opening database 'postgres:localhost::oatest' [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: Opening oatest on localhost [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: Ns_PgOpenDb(postgres): Openned connection to localhost::oatest. [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: Querying 'select version();' [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): 'select version()' [13/Dec/2002:14:28:06][6744.1024][-main-] Debug: PgBindCmd: sql = select count(*) from pg_class [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: Querying 'select count(*) from pg_class;' [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): 'select count(*) from pg_class' [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: Querying 'select proname from pg_proc where proname = '__test__' and pronargs = 0;' [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): 'select proname from pg_proc where proname = '__test__' and pronargs = 0' [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: Querying 'create function __test__() returns integer as 'begin end;' language 'plpgsql';' [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): 'create function __test__() returns integer as 'begin end;' language 'plpgsql'' [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: Querying 'drop function __test__();' [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): 'drop function __test__();' [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: Loading acs-tcl [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/00-database-procs-postgresql.tcl... [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/00-database-procs.tcl... [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/10-charset-compat-procs.tcl... [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/30-xml-utils-procs.tcl... [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/acs-kernel-procs.tcl... [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/acs-permissions-procs.tcl... [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/acs-private-data-procs.tcl... [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/admin-procs.tcl... [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/adp-parser-procs.tcl... [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/aolserver-3-procs.tcl... [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/apm-file-procs.tcl... [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/apm-install-procs.tcl... [13/Dec/2002:14:28:06][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/apm-procs.tcl... [13/Dec/2002:14:28:07][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/apm-xml-procs.tcl... [13/Dec/2002:14:28:07][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/community-core-2-procs.tcl... [13/Dec/2002:14:28:07][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/community-core-procs.tcl... [13/Dec/2002:14:28:07][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/defs-procs.tcl... [13/Dec/2002:14:28:07][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/deprecated-utilities-procs.tcl... [13/Dec/2002:14:28:07][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/document-procs.tcl... [13/Dec/2002:14:28:07][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/exception-procs.tcl... [13/Dec/2002:14:28:07][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/form-processing-procs.tcl... [13/Dec/2002:14:28:07][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/membership-rel-procs.tcl... [13/Dec/2002:14:28:07][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/memoize-procs.tcl... [13/Dec/2002:14:28:07][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/navigation-procs.tcl... [13/Dec/2002:14:28:07][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/object-procs.tcl... [13/Dec/2002:14:28:07][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/object-type-procs.tcl... [13/Dec/2002:14:28:07][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/openacs-kernel-procs.tcl... [13/Dec/2002:14:28:07][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/parameter-procs.tcl... [13/Dec/2002:14:28:07][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/request-processor-procs.tcl... [13/Dec/2002:14:28:07][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/security-procs.tcl... [13/Dec/2002:14:28:07][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/server-cluster-procs.tcl... [13/Dec/2002:14:28:07][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/set-operation-procs.tcl... [13/Dec/2002:14:28:07][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/site-node-apm-integration-procs.tcl... [13/Dec/2002:14:28:07][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/site-node-object-map-procs.tcl... [13/Dec/2002:14:28:07][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/site-nodes-procs.tcl... [13/Dec/2002:14:28:07][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/sql-statement-procs.tcl... [13/Dec/2002:14:28:07][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/table-display-procs.tcl... [13/Dec/2002:14:28:07][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/tcl-documentation-procs.tcl... [13/Dec/2002:14:28:07][6744.1024][-main-] Warning: apm_boostrap_load_file skipping /web/oatest/packages/acs-tcl/tcl/tcl-documentation-tests.tcl because it isn't either a -procs.tcl or -init.tcl file [13/Dec/2002:14:28:07][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/text-html-procs.tcl... [13/Dec/2002:14:28:08][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/user-extensions-procs.tcl... [13/Dec/2002:14:28:08][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/utilities-procs.tcl... [13/Dec/2002:14:28:08][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/widgets-procs.tcl... [13/Dec/2002:14:28:08][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/xml-0-sgml-procs.tcl... [13/Dec/2002:14:28:08][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/xml-1-dom-procs.tcl... [13/Dec/2002:14:28:08][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/xml-2-procs.tcl... [13/Dec/2002:14:28:09][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-tcl.tcl.00-database-procs-postgresql.db_table_exists.table_count --> using default SQL [13/Dec/2002:14:28:09][6744.1024][-main-] Debug: PgBindCmd: sql = select count(*) from pg_class where relname = lower(:table_name) and relname !~ '^pg_' and relkind = 'r' [13/Dec/2002:14:28:09][6744.1024][-main-] Debug: PgBindCmd: bind var: table_name = apm_packages [13/Dec/2002:14:28:09][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select count(*) from pg_class where relname = lower('apm_packages') and relname !~ '^pg_' and relkind = 'r' [13/Dec/2002:14:28:09][6744.1024][-main-] Notice: Querying ' select count(*) from pg_class where relname = lower('apm_packages') and relname !~ '^pg_' and relkind = 'r';' [13/Dec/2002:14:28:09][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select count(*) from pg_class where relname = lower('apm_packages') and relname !~ '^pg_' and relkind = 'r' ' [13/Dec/2002:14:28:09][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-tcl.tcl.00-database-procs-postgresql.db_table_exists.table_count --> using default SQL [13/Dec/2002:14:28:09][6744.1024][-main-] Debug: PgBindCmd: sql = select count(*) from pg_class where relname = lower(:table_name) and relname !~ '^pg_' and relkind = 'r' [13/Dec/2002:14:28:09][6744.1024][-main-] Debug: PgBindCmd: bind var: table_name = site_nodes [13/Dec/2002:14:28:09][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select count(*) from pg_class where relname = lower('site_nodes') and relname !~ '^pg_' and relkind = 'r' [13/Dec/2002:14:28:09][6744.1024][-main-] Notice: Querying ' select count(*) from pg_class where relname = lower('site_nodes') and relname !~ '^pg_' and relkind = 'r';' [13/Dec/2002:14:28:09][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select count(*) from pg_class where relname = lower('site_nodes') and relname !~ '^pg_' and relkind = 'r' ' [13/Dec/2002:14:28:09][6744.1024][-main-] Debug: PgBindCmd: sql = select 1 from apm_package_versions where package_key = :package_key and installed_p = 't' [13/Dec/2002:14:28:09][6744.1024][-main-] Debug: PgBindCmd: bind var: package_key = acs-kernel [13/Dec/2002:14:28:09][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select 1 from apm_package_versions where package_key = 'acs-kernel' and installed_p = 't' [13/Dec/2002:14:28:09][6744.1024][-main-] Notice: Querying ' select 1 from apm_package_versions where package_key = 'acs-kernel' and installed_p = 't';' [13/Dec/2002:14:28:09][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select 1 from apm_package_versions where package_key = 'acs-kernel' and installed_p = 't' ' [13/Dec/2002:14:28:09][6744.1024][-main-] Debug: PgBindCmd: sql = select case when count(object_id) = 0 then 0 else 1 end from site_nodes where object_id = (select package_id from apm_packages where package_key = 'acs-admin') [13/Dec/2002:14:28:09][6744.1024][-main-] Notice: Querying ' select case when count(object_id) = 0 then 0 else 1 end from site_nodes where object_id = (select package_id from apm_packages where package_key = 'acs-admin');' [13/Dec/2002:14:28:10][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select case when count(object_id) = 0 then 0 else 1 end from site_nodes where object_id = (select package_id from apm_packages where package_key = 'acs-admin') ' [13/Dec/2002:14:28:10][6744.1024][-main-] Debug: PgBindCmd: sql = select 1 as admin_exists_p from dual where exists (select 1 from all_object_party_privilege_map m, users u where m.object_id = 0 and m.party_id = u.user_id and m.privilege = 'admin') [13/Dec/2002:14:28:10][6744.1024][-main-] Notice: Querying ' select 1 as admin_exists_p from dual where exists (select 1 from all_object_party_privilege_map m, users u where m.object_id = 0 and m.party_id = u.user_id and m.privilege = 'admin');' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select 1 as admin_exists_p from dual where exists (select 1 from all_object_party_privilege_map m, users u where m.object_id = 0 and m.party_id = u.user_id and m.privilege = 'admin') ' [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: Verifying Installation: Kernel Installed? 1 ACS Administration Link Id: 1 An Administrator? 1 [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/20-memoize-init.tcl... [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: sql = select package_id from apm_packages where package_key = 'acs-kernel' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Querying ' select package_id from apm_packages where package_key = 'acs-kernel';' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select package_id from apm_packages where package_key = 'acs-kernel' ' [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: sql = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = :package_id and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = :parameter_name [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: bind var: package_id = 173 [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: bind var: parameter_name = MaxSize [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '173' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'MaxSize' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Querying ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '173' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'MaxSize';' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '173' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'MaxSize' ' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/admin-init.tcl... [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/database-init.tcl... [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/document-init.tcl... [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/request-processor-init.tcl... [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd..NULL --> using default SQL [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: sql = select package_id from apm_packages where package_key = 'acs-kernel' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Querying ' select package_id from apm_packages where package_key = 'acs-kernel';' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select package_id from apm_packages where package_key = 'acs-kernel' ' [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd..NULL --> using default SQL [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: sql = select package_id from apm_packages where package_key = 'acs-admin' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Querying ' select package_id from apm_packages where package_key = 'acs-admin';' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select package_id from apm_packages where package_key = 'acs-admin' ' [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd..NULL --> using default SQL [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: sql = select package_key from apm_packages where package_id = :package_id [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: bind var: package_id = 173 [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select package_key from apm_packages where package_id = '173' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Querying ' select package_key from apm_packages where package_id = '173';' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select package_key from apm_packages where package_id = '173' ' [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: sql = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = :package_id and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = :parameter_name [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: bind var: package_id = 173 [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: bind var: parameter_name = PerformanceModeP [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '173' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'PerformanceModeP' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Querying ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '173' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'PerformanceModeP';' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '173' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'PerformanceModeP' ' [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: sql = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = :package_id and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = :parameter_name [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: bind var: package_id = 173 [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: bind var: parameter_name = PermissionCacheP [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '173' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'PermissionCacheP' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Querying ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '173' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'PermissionCacheP';' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '173' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'PermissionCacheP' ' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Registering rp_handle_tcl_request to handle files with extension tcl [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Registering rp_handle_adp_request to handle files with extension adp [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Registering rp_handle_tcl_request to handle files with extension vuh [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/security-init.tcl... [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: sql = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = :package_id and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = :parameter_name [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: bind var: package_id = 173 [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: bind var: parameter_name = SessionSweepInterval [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '173' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'SessionSweepInterval' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Querying ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '173' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'SessionSweepInterval';' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '173' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'SessionSweepInterval' ' [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: sql = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = :package_id and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = :parameter_name [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: bind var: package_id = 173 [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: bind var: parameter_name = ClusterEnabledP [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '173' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'ClusterEnabledP' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Querying ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '173' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'ClusterEnabledP';' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '173' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'ClusterEnabledP' ' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Scheduling proc sec_sweep_sessions [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: sql = select case when count(*) = 0 then 0 else 1 end from secret_tokens [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Querying ' select case when count(*) = 0 then 0 else 1 end from secret_tokens;' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select case when count(*) = 0 then 0 else 1 end from secret_tokens ' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Creating secret_tokens ns_cache... [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Populating secret_tokens ns_cache... [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: sql = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = :package_id and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = :parameter_name [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: bind var: package_id = 173 [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: bind var: parameter_name = NumberOfCachedSecretTokens [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '173' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'NumberOfCachedSecretTokens' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Querying ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '173' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'NumberOfCachedSecretTokens';' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '173' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'NumberOfCachedSecretTokens' ' [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-tcl.tcl.00-database-procs-postgresql.db_table_exists.table_count --> using default SQL [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: sql = select count(*) from pg_class where relname = lower(:table_name) and relname !~ '^pg_' and relkind = 'r' [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: bind var: table_name = secret_tokens [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select count(*) from pg_class where relname = lower('secret_tokens') and relname !~ '^pg_' and relkind = 'r' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Querying ' select count(*) from pg_class where relname = lower('secret_tokens') and relname !~ '^pg_' and relkind = 'r';' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select count(*) from pg_class where relname = lower('secret_tokens') and relname !~ '^pg_' and relkind = 'r' ' [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: sql = select token_id, token from secret_tokens, (select trunc(random()*(select count(*)-15 from secret_tokens))::integer as first) r where token_id >= r.first and r.first+15 > token_id; [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Querying ' select token_id, token from secret_tokens, (select trunc(random()*(select count(*)-15 from secret_tokens))::integer as first) r where token_id >= r.first and r.first+15 > token_id;' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select token_id, token from secret_tokens, (select trunc(random()*(select count(*)-15 from secret_tokens))::integer as first) r where token_id >= r.first and r.first+15 > token_id; ' [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: sql = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = :package_id and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = :parameter_name [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: bind var: package_id = 173 [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: bind var: parameter_name = SessionTimeout [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '173' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'SessionTimeout' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Querying ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '173' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'SessionTimeout';' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '173' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'SessionTimeout' ' [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: sql = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = :package_id and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = :parameter_name [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: bind var: package_id = 173 [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: bind var: parameter_name = SessionRenew [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '173' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'SessionRenew' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Querying ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '173' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'SessionRenew';' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '173' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'SessionRenew' ' [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/site-nodes-init.tcl... [13/Dec/2002:14:28:21][6744.1024][-main-] Debug: PgBindCmd: sql = select site_node__url(site_nodes.node_id) as url, site_nodes.node_id, site_nodes.parent_id, site_nodes.directory_p, site_nodes.pattern_p, site_nodes.object_id, (select acs_objects.object_type from acs_objects where acs_objects.object_id = site_nodes.object_id) as object_type, apm_packages.package_key, apm_packages.package_id, apm_packages.instance_name from site_nodes left join apm_packages on site_nodes.object_id = apm_packages.package_id [13/Dec/2002:14:28:21][6744.1024][-main-] Notice: Querying ' select site_node__url(site_nodes.node_id) as url, site_nodes.node_id, site_nodes.parent_id, site_nodes.directory_p, site_nodes.pattern_p, site_nodes.object_id, (select acs_objects.object_type from acs_objects where acs_objects.object_id = site_nodes.object_id) as object_type, apm_packages.package_key, apm_packages.package_id, apm_packages.instance_name from site_nodes left join apm_packages on site_nodes.object_id = apm_packages.package_id;' [13/Dec/2002:14:28:27][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select site_node__url(site_nodes.node_id) as url, site_nodes.node_id, site_nodes.parent_id, site_nodes.directory_p, site_nodes.pattern_p, site_nodes.object_id, (select acs_objects.object_type from acs_objects where acs_objects.object_id = site_nodes.object_id) as object_type, apm_packages.package_key, apm_packages.package_id, apm_packages.instance_name from site_nodes left join apm_packages on site_nodes.object_id = apm_packages.package_id ' [13/Dec/2002:14:28:27][6744.1024][-main-] Warning: apm_boostrap_load_file skipping /web/oatest/packages/acs-tcl/tcl/tcl-documentation-tests.tcl because it isn't either a -procs.tcl or -init.tcl file [13/Dec/2002:14:28:27][6744.1024][-main-] Notice: Loading packages/acs-tcl/tcl/utilities-init.tcl... [13/Dec/2002:14:28:27][6744.1024][-main-] Debug: NO FULLQUERY FOR package_keys_select --> using default SQL [13/Dec/2002:14:28:27][6744.1024][-main-] Debug: PgBindCmd: sql = select package_key from apm_enabled_package_versions [13/Dec/2002:14:28:27][6744.1024][-main-] Notice: Querying ' select package_key from apm_enabled_package_versions;' [13/Dec/2002:14:28:27][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select package_key from apm_enabled_package_versions ' [13/Dec/2002:14:28:27][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-tcl.tcl.apm-procs.apm_load_libraries.apm_enabled_packages_l --> using default SQL [13/Dec/2002:14:28:27][6744.1024][-main-] Debug: PgBindCmd: sql = select distinct package_key from apm_package_versions where enabled_p='t' [13/Dec/2002:14:28:27][6744.1024][-main-] Notice: Querying ' select distinct package_key from apm_package_versions where enabled_p='t';' [13/Dec/2002:14:28:27][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select distinct package_key from apm_package_versions where enabled_p='t' ' [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loading packages/acs-admin/tcl/apm-admin-procs.tcl... [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loaded packages/acs-admin/tcl/apm-admin-procs.tcl. [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loading packages/acs-api-browser/tcl/acs-api-documentation-procs.tcl... [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loaded packages/acs-api-browser/tcl/acs-api-documentation-procs.tcl. [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loading packages/acs-automated-testing/tcl/aa-test-procs.tcl... [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loaded packages/acs-automated-testing/tcl/aa-test-procs.tcl. [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loading packages/acs-automated-testing/tcl/example-procs.tcl... [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loaded packages/acs-automated-testing/tcl/example-procs.tcl. [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loading packages/acs-automated-testing/tcl/example-test-procs.tcl... [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loaded packages/acs-automated-testing/tcl/example-test-procs.tcl. [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loading packages/acs-automated-testing/tcl/filter-procs.tcl... [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loaded packages/acs-automated-testing/tcl/filter-procs.tcl. [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loading packages/acs-bootstrap-installer/tcl/00-proc-procs.tcl... [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loaded packages/acs-bootstrap-installer/tcl/00-proc-procs.tcl. [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loading packages/acs-bootstrap-installer/tcl/10-utilities-procs.tcl... [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loaded packages/acs-bootstrap-installer/tcl/10-utilities-procs.tcl. [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loading packages/acs-bootstrap-installer/tcl/20-db-bootstrap-procs.tcl... [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loaded packages/acs-bootstrap-installer/tcl/20-db-bootstrap-procs.tcl. [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loading packages/acs-bootstrap-installer/tcl/30-apm-load-procs.tcl... [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loaded packages/acs-bootstrap-installer/tcl/30-apm-load-procs.tcl. [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loading packages/acs-bootstrap-installer/tcl/40-db-query-dispatcher-procs.tcl... [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loaded packages/acs-bootstrap-installer/tcl/40-db-query-dispatcher-procs.tcl. [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loading packages/acs-content-repository/tcl/acs-content-repository-procs.tcl... [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loaded packages/acs-content-repository/tcl/acs-content-repository-procs.tcl. [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loading packages/acs-content-repository/tcl/content-procs.tcl... [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loaded packages/acs-content-repository/tcl/content-procs.tcl. [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loading packages/acs-content-repository/tcl/doc-procs.tcl... [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loaded packages/acs-content-repository/tcl/doc-procs.tcl. [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loading packages/acs-content-repository/tcl/filter-procs.tcl... [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loaded packages/acs-content-repository/tcl/filter-procs.tcl. [13/Dec/2002:14:28:29][6744.1024][-main-] Debug: Loading packages/acs-content-repository/tcl/item-procs.tcl... [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loaded packages/acs-content-repository/tcl/item-procs.tcl. [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loading packages/acs-content-repository/tcl/publish-procs.tcl... [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loaded packages/acs-content-repository/tcl/publish-procs.tcl. [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loading packages/acs-content-repository/tcl/revision-procs.tcl... [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loaded packages/acs-content-repository/tcl/revision-procs.tcl. [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loading packages/acs-content-repository/tcl/search-procs.tcl... [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loaded packages/acs-content-repository/tcl/search-procs.tcl. [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loading packages/acs-core-docs/tcl/acs-core-docs-procs.tcl... [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loaded packages/acs-core-docs/tcl/acs-core-docs-procs.tcl. [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loading packages/acs-datetime/tcl/acs-calendar-2-procs.tcl... [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loaded packages/acs-datetime/tcl/acs-calendar-2-procs.tcl. [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loading packages/acs-datetime/tcl/acs-calendar-procs.tcl... [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loaded packages/acs-datetime/tcl/acs-calendar-procs.tcl. [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loading packages/acs-datetime/tcl/acs-datetime-procs.tcl... [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loaded packages/acs-datetime/tcl/acs-datetime-procs.tcl. [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loading packages/acs-developer-support/tcl/acs-developer-support-procs.tcl... [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loaded packages/acs-developer-support/tcl/acs-developer-support-procs.tcl. [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loading packages/acs-lang/tcl/acs-lang-procs.tcl... [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loaded packages/acs-lang/tcl/acs-lang-procs.tcl. [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loading packages/acs-lang/tcl/lang-procs.tcl... [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loaded packages/acs-lang/tcl/lang-procs.tcl. [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loading packages/acs-lang/tcl/locale-procs.tcl... [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loaded packages/acs-lang/tcl/locale-procs.tcl. [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loading packages/acs-lang/tcl/localization-procs.tcl... [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loaded packages/acs-lang/tcl/localization-procs.tcl. [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loading packages/acs-mail/tcl/acs-mail-procs.tcl... [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loaded packages/acs-mail/tcl/acs-mail-procs.tcl. [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loading packages/acs-mail-lite/tcl/acs-mail-lite-procs.tcl... [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loaded packages/acs-mail-lite/tcl/acs-mail-lite-procs.tcl. [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loading packages/acs-messaging/tcl/acs-messaging-procs.tcl... [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loaded packages/acs-messaging/tcl/acs-messaging-procs.tcl. [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loading packages/acs-reference/tcl/acs-reference-procs.tcl... [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loaded packages/acs-reference/tcl/acs-reference-procs.tcl. [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loading packages/acs-service-contract/tcl/acs-service-contract-procs.tcl... [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loaded packages/acs-service-contract/tcl/acs-service-contract-procs.tcl. [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loading packages/acs-subsite/tcl/application-group-procs.tcl... [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loaded packages/acs-subsite/tcl/application-group-procs.tcl. [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loading packages/acs-subsite/tcl/attribute-procs.tcl... [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loaded packages/acs-subsite/tcl/attribute-procs.tcl. [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loading packages/acs-subsite/tcl/group-procs.tcl... [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loaded packages/acs-subsite/tcl/group-procs.tcl. [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loading packages/acs-subsite/tcl/group-type-procs.tcl... [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loaded packages/acs-subsite/tcl/group-type-procs.tcl. [13/Dec/2002:14:28:30][6744.1024][-main-] Debug: Loading packages/acs-subsite/tcl/package-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-subsite/tcl/package-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-subsite/tcl/party-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-subsite/tcl/party-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-subsite/tcl/plpgsql-utility-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-subsite/tcl/plpgsql-utility-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-subsite/tcl/plsql-utility-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-subsite/tcl/plsql-utility-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-subsite/tcl/rel-segments-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-subsite/tcl/rel-segments-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-subsite/tcl/rel-types-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-subsite/tcl/rel-types-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-subsite/tcl/relation-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-subsite/tcl/relation-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-subsite/tcl/subsite-callback-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-subsite/tcl/subsite-callback-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-subsite/tcl/subsite-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-subsite/tcl/subsite-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-templating/tcl/0-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-templating/tcl/0-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-templating/tcl/acs-integration-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-templating/tcl/acs-integration-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-templating/tcl/currency-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-templating/tcl/currency-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-templating/tcl/data-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-templating/tcl/data-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-templating/tcl/date-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-templating/tcl/date-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-templating/tcl/debug-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-templating/tcl/debug-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-templating/tcl/doc-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-templating/tcl/doc-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-templating/tcl/doc-tcl-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-templating/tcl/doc-tcl-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-templating/tcl/element-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-templating/tcl/element-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-templating/tcl/file-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-templating/tcl/file-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-templating/tcl/filter-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-templating/tcl/filter-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-templating/tcl/form-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-templating/tcl/form-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-templating/tcl/mime-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-templating/tcl/mime-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-templating/tcl/paginator-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-templating/tcl/paginator-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-templating/tcl/parse-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-templating/tcl/parse-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-templating/tcl/query-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-templating/tcl/query-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-templating/tcl/request-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-templating/tcl/request-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-templating/tcl/tab-procs.tcl... [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loaded packages/acs-templating/tcl/tab-procs.tcl. [13/Dec/2002:14:28:31][6744.1024][-main-] Debug: Loading packages/acs-templating/tcl/table-procs.tcl... [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loaded packages/acs-templating/tcl/table-procs.tcl. [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loading packages/acs-templating/tcl/tag-procs.tcl... [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loaded packages/acs-templating/tcl/tag-procs.tcl. [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loading packages/acs-templating/tcl/util-procs.tcl... [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loaded packages/acs-templating/tcl/util-procs.tcl. [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loading packages/acs-templating/tcl/widget-procs.tcl... [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loaded packages/acs-templating/tcl/widget-procs.tcl. [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loading packages/acs-templating/tcl/wizard-procs.tcl... [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loaded packages/acs-templating/tcl/wizard-procs.tcl. [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loading packages/acs-workflow/tcl/display-procs.tcl... [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loaded packages/acs-workflow/tcl/display-procs.tcl. [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loading packages/acs-workflow/tcl/expenses-procs.tcl... [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loaded packages/acs-workflow/tcl/expenses-procs.tcl. [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loading packages/acs-workflow/tcl/graph-procs.tcl... [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loaded packages/acs-workflow/tcl/graph-procs.tcl. [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loading packages/acs-workflow/tcl/wizard-procs.tcl... [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loaded packages/acs-workflow/tcl/wizard-procs.tcl. [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loading packages/acs-workflow/tcl/workflow-procs.tcl... [13/Dec/2002:14:28:32][6744.1024][-main-] Notice: Scheduling proc wf_sweep_time_events [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loaded packages/acs-workflow/tcl/workflow-procs.tcl. [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loading packages/adserver/tcl/adserver-ad-monitor-procs.tcl... [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loaded packages/adserver/tcl/adserver-ad-monitor-procs.tcl. [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loading packages/adserver/tcl/adserver-procs.tcl... [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loaded packages/adserver/tcl/adserver-procs.tcl. [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loading packages/attachments/tcl/attachments-procs.tcl... [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loaded packages/attachments/tcl/attachments-procs.tcl. [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loading packages/authorize-gateway/tcl/authorize-gateway-procs.tcl... [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loaded packages/authorize-gateway/tcl/authorize-gateway-procs.tcl. [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loading packages/bboard/tcl/bboard-procs.tcl... [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loaded packages/bboard/tcl/bboard-procs.tcl. [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loading packages/bm-portlet/tcl/bm-portlet-procs.tcl... [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loaded packages/bm-portlet/tcl/bm-portlet-procs.tcl. [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loading packages/bookmarks/tcl/bookmarks-procs.tcl... [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loaded packages/bookmarks/tcl/bookmarks-procs.tcl. [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loading packages/bug-tracker/tcl/bug-tracker-notification-procs.tcl... [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loaded packages/bug-tracker/tcl/bug-tracker-notification-procs.tcl. [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loading packages/bug-tracker/tcl/bug-tracker-procs.tcl... [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loaded packages/bug-tracker/tcl/bug-tracker-procs.tcl. [13/Dec/2002:14:28:32][6744.1024][-main-] Debug: Loading packages/bulk-mail/tcl/bulk-mail-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/bulk-mail/tcl/bulk-mail-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/calendar/tcl/cal-item-2-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/calendar/tcl/cal-item-2-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/calendar/tcl/cal-item-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/calendar/tcl/cal-item-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/calendar/tcl/calendar-2-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/calendar/tcl/calendar-2-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/calendar/tcl/calendar-community-core-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/calendar/tcl/calendar-community-core-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/calendar/tcl/calendar-display-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/calendar/tcl/calendar-display-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/calendar/tcl/calendar-outlook-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/calendar/tcl/calendar-outlook-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/calendar/tcl/calendar-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/calendar/tcl/calendar-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/calendar-portlet/tcl/calendar-admin-portlet-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/calendar-portlet/tcl/calendar-admin-portlet-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/calendar-portlet/tcl/calendar-full-portlet-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/calendar-portlet/tcl/calendar-full-portlet-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/calendar-portlet/tcl/calendar-list-portlet-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/calendar-portlet/tcl/calendar-list-portlet-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/calendar-portlet/tcl/calendar-portlet-display-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/calendar-portlet/tcl/calendar-portlet-display-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/calendar-portlet/tcl/calendar-portlet-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/calendar-portlet/tcl/calendar-portlet-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/clickthrough/tcl/clickthrough-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/clickthrough/tcl/clickthrough-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/cms/tcl/browser-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/cms/tcl/browser-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/cms/tcl/clipboard-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/cms/tcl/clipboard-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/cms/tcl/clipboard-ui-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/cms/tcl/clipboard-ui-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/cms/tcl/cms-folder-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/cms/tcl/cms-folder-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/cms/tcl/content-add-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/cms/tcl/content-add-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/cms/tcl/content-method-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/cms/tcl/content-method-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/cms/tcl/form-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/cms/tcl/form-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/cms/tcl/item-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Notice: Multiple definition of item::get_live_revision in /web/oatest/packages/acs-content-repository/tcl/item-procs.tcl and /web/oatest/packages/cms/tcl/item-procs.tcl [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/cms/tcl/item-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/cms/tcl/module-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/cms/tcl/module-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/cms/tcl/pagination-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/cms/tcl/pagination-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/cms/tcl/perm-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/cms/tcl/perm-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/cms/tcl/publish-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd..NULL --> using default SQL [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: PgBindCmd: sql = select package_id from apm_packages where package_key = :package_key [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: PgBindCmd: bind var: package_key = cms [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select package_id from apm_packages where package_key = 'cms' [13/Dec/2002:14:28:33][6744.1024][-main-] Notice: Querying ' select package_id from apm_packages where package_key = 'cms';' [13/Dec/2002:14:28:33][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select package_id from apm_packages where package_key = 'cms' ' [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd..NULL --> using default SQL [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: PgBindCmd: sql = select package_key from apm_packages where package_id = :package_id [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: PgBindCmd: bind var: package_id = 0 [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select package_key from apm_packages where package_id = '0' [13/Dec/2002:14:28:33][6744.1024][-main-] Notice: Querying ' select package_key from apm_packages where package_id = '0';' [13/Dec/2002:14:28:33][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select package_key from apm_packages where package_id = '0' ' [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: PgBindCmd: sql = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = :package_id and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = :parameter_name [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: PgBindCmd: bind var: package_id = 0 [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: PgBindCmd: bind var: parameter_name = StatusSweepInterval [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '0' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'StatusSweepInterval' [13/Dec/2002:14:28:33][6744.1024][-main-] Notice: Querying ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '0' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'StatusSweepInterval';' [13/Dec/2002:14:28:33][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '0' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'StatusSweepInterval' ' [13/Dec/2002:14:28:33][6744.1024][-main-] Notice: CMS publish::schedule_status_sweep: Scheduling status sweep every seconds [13/Dec/2002:14:28:33][6744.1024][-main-] Error: Error sourcing /web/oatest/packages/cms/tcl/publish-procs.tcl: expected integer but got "" while executing "ns_schedule_proc -thread $interval publish::track_publish_status" (procedure "publish::schedule_status_sweep" line 15) invoked from within "publish::schedule_status_sweep" (file "/web/oatest/packages/cms/tcl/publish-procs.tcl" line 454) invoked from within "source $__file " [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/cms/tcl/publish-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/cms/tcl/rel-procs.tcl... [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loaded packages/cms/tcl/rel-procs.tcl. [13/Dec/2002:14:28:33][6744.1024][-main-] Debug: Loading packages/cms/tcl/search-procs.tcl... [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loaded packages/cms/tcl/search-procs.tcl. [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loading packages/cms/tcl/user-procs.tcl... [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loaded packages/cms/tcl/user-procs.tcl. [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loading packages/cms/tcl/widget-procs.tcl... [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loaded packages/cms/tcl/widget-procs.tcl. [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loading packages/cms/tcl/workflow-procs.tcl... [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loaded packages/cms/tcl/workflow-procs.tcl. [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loading packages/cronjob/tcl/cronjob-procs.tcl... [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loaded packages/cronjob/tcl/cronjob-procs.tcl. [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loading packages/directory/tcl/directory-procs.tcl... [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loaded packages/directory/tcl/directory-procs.tcl. [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loading packages/dotlrn/tcl/applets-procs.tcl... [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loaded packages/dotlrn/tcl/applets-procs.tcl. [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loading packages/dotlrn/tcl/class-applets-procs.tcl... [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loaded packages/dotlrn/tcl/class-applets-procs.tcl. [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loading packages/dotlrn/tcl/class-procs.tcl... [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loaded packages/dotlrn/tcl/class-procs.tcl. [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loading packages/dotlrn/tcl/club-procs.tcl... [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loaded packages/dotlrn/tcl/club-procs.tcl. [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loading packages/dotlrn/tcl/community-procs.tcl... [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loaded packages/dotlrn/tcl/community-procs.tcl. [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loading packages/dotlrn/tcl/department-procs.tcl... [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loaded packages/dotlrn/tcl/department-procs.tcl. [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loading packages/dotlrn/tcl/dotlrn-main-portlet-procs.tcl... [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loaded packages/dotlrn/tcl/dotlrn-main-portlet-procs.tcl. [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loading packages/dotlrn/tcl/dotlrn-procs.tcl... [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loaded packages/dotlrn/tcl/dotlrn-procs.tcl. [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loading packages/dotlrn/tcl/dotlrn-security-procs.tcl... [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loaded packages/dotlrn/tcl/dotlrn-security-procs.tcl. [13/Dec/2002:14:28:34][6744.1024][-main-] Debug: Loading packages/dotlrn/tcl/dotlrn-user-extension-procs.tcl... [13/Dec/2002:14:28:35][6744.1024][-main-] Debug: Loaded packages/dotlrn/tcl/dotlrn-user-extension-procs.tcl. [13/Dec/2002:14:28:35][6744.1024][-main-] Debug: Loading packages/dotlrn/tcl/navigation-procs.tcl... [13/Dec/2002:14:28:35][6744.1024][-main-] Debug: Loaded packages/dotlrn/tcl/navigation-procs.tcl. [13/Dec/2002:14:28:35][6744.1024][-main-] Debug: Loading packages/dotlrn/tcl/site-nodes-procs.tcl... [13/Dec/2002:14:28:35][6744.1024][-main-] Debug: Loaded packages/dotlrn/tcl/site-nodes-procs.tcl. [13/Dec/2002:14:28:35][6744.1024][-main-] Debug: Loading packages/dotlrn/tcl/spam-procs.tcl... [13/Dec/2002:14:28:35][6744.1024][-main-] Debug: Loaded packages/dotlrn/tcl/spam-procs.tcl. [13/Dec/2002:14:28:35][6744.1024][-main-] Debug: Loading packages/dotlrn/tcl/term-procs.tcl... [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loaded packages/dotlrn/tcl/term-procs.tcl. [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loading packages/dotlrn/tcl/user-type-procs.tcl... [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loaded packages/dotlrn/tcl/user-type-procs.tcl. [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loading packages/dotlrn-bm/tcl/dotlrn-bm-procs.tcl... [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loaded packages/dotlrn-bm/tcl/dotlrn-bm-procs.tcl. [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loading packages/dotlrn-calendar/tcl/dotlrn-calendar-procs.tcl... [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loaded packages/dotlrn-calendar/tcl/dotlrn-calendar-procs.tcl. [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loading packages/dotlrn-dotlrn/tcl/dotlrn-dotlrn-procs.tcl... [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loaded packages/dotlrn-dotlrn/tcl/dotlrn-dotlrn-procs.tcl. [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loading packages/dotlrn-dotlrn/tcl/dotlrn-members-procs.tcl... [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loaded packages/dotlrn-dotlrn/tcl/dotlrn-members-procs.tcl. [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loading packages/dotlrn-dotlrn/tcl/dotlrn-members-staff-procs.tcl... [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loaded packages/dotlrn-dotlrn/tcl/dotlrn-members-staff-procs.tcl. [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loading packages/dotlrn-faq/tcl/dotlrn-faq-procs.tcl... [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loaded packages/dotlrn-faq/tcl/dotlrn-faq-procs.tcl. [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loading packages/dotlrn-forums/tcl/dotlrn-forums-admin-portlet-procs.tcl... [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loaded packages/dotlrn-forums/tcl/dotlrn-forums-admin-portlet-procs.tcl. [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loading packages/dotlrn-forums/tcl/dotlrn-forums-procs.tcl... [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loaded packages/dotlrn-forums/tcl/dotlrn-forums-procs.tcl. [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loading packages/dotlrn-fs/tcl/dotlrn-fs-procs.tcl... [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loaded packages/dotlrn-fs/tcl/dotlrn-fs-procs.tcl. [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loading packages/dotlrn-news/tcl/dotlrn-news-procs.tcl... [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loaded packages/dotlrn-news/tcl/dotlrn-news-procs.tcl. [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loading packages/dotlrn-portlet/tcl/dotlrn-admin-portlet-procs.tcl... [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loaded packages/dotlrn-portlet/tcl/dotlrn-admin-portlet-procs.tcl. [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loading packages/dotlrn-portlet/tcl/dotlrn-members-portlet-procs.tcl... [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loaded packages/dotlrn-portlet/tcl/dotlrn-members-portlet-procs.tcl. [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loading packages/dotlrn-portlet/tcl/dotlrn-members-staff-portlet-procs.tcl... [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loaded packages/dotlrn-portlet/tcl/dotlrn-members-staff-portlet-procs.tcl. [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loading packages/dotlrn-portlet/tcl/dotlrn-portlet-procs.tcl... [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loaded packages/dotlrn-portlet/tcl/dotlrn-portlet-procs.tcl. [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loading packages/dotlrn-static/tcl/dotlrn-static-procs.tcl... [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loaded packages/dotlrn-static/tcl/dotlrn-static-procs.tcl. [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loading packages/dotlrn-syllabus/tcl/dotlrn-syllabus-admin-procs.tcl... [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loaded packages/dotlrn-syllabus/tcl/dotlrn-syllabus-admin-procs.tcl. [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loading packages/dotlrn-syllabus/tcl/dotlrn-syllabus-procs.tcl... [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loaded packages/dotlrn-syllabus/tcl/dotlrn-syllabus-procs.tcl. [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loading packages/dotlrn-syllabus/tcl/syllabus-admin-portlet-procs.tcl... [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loaded packages/dotlrn-syllabus/tcl/syllabus-admin-portlet-procs.tcl. [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loading packages/dotlrn-syllabus/tcl/syllabus-portlet-procs.tcl... [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loaded packages/dotlrn-syllabus/tcl/syllabus-portlet-procs.tcl. [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loading packages/download/tcl/download-procs.tcl... [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loaded packages/download/tcl/download-procs.tcl. [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loading packages/edit-this-page/tcl/etp-procs.tcl... [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loaded packages/edit-this-page/tcl/etp-procs.tcl. [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loading packages/email-handler/tcl/email-handler-procs.tcl... [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd..NULL --> using default SQL [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: PgBindCmd: sql = select package_id from apm_packages where package_key = 'email-handler' [13/Dec/2002:14:28:36][6744.1024][-main-] Notice: Querying ' select package_id from apm_packages where package_key = 'email-handler';' [13/Dec/2002:14:28:36][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select package_id from apm_packages where package_key = 'email-handler' ' [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd..NULL --> using default SQL [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: PgBindCmd: sql = select package_key from apm_packages where package_id = :package_id [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: PgBindCmd: bind var: package_id = 4240 [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select package_key from apm_packages where package_id = '4240' [13/Dec/2002:14:28:36][6744.1024][-main-] Notice: Querying ' select package_key from apm_packages where package_id = '4240';' [13/Dec/2002:14:28:36][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select package_key from apm_packages where package_id = '4240' ' [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: PgBindCmd: sql = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = :package_id and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = :parameter_name [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: PgBindCmd: bind var: package_id = 4240 [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: PgBindCmd: bind var: parameter_name = QueueSweepInterval [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '4240' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'QueueSweepInterval' [13/Dec/2002:14:28:36][6744.1024][-main-] Notice: Querying ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '4240' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'QueueSweepInterval';' [13/Dec/2002:14:28:36][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '4240' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'QueueSweepInterval' ' [13/Dec/2002:14:28:36][6744.1024][-main-] Notice: email-handler.tcl scheduling process_email_queue to run every 600 seconds. [13/Dec/2002:14:28:36][6744.1024][-main-] Notice: Scheduling proc email_handler_process_email_queue [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loaded packages/email-handler/tcl/email-handler-procs.tcl. [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loading packages/email-handler/tcl/email-test-procs.tcl... [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loaded packages/email-handler/tcl/email-test-procs.tcl. [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loading packages/email-handler/tcl/email-util-procs.tcl... [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loaded packages/email-handler/tcl/email-util-procs.tcl. [13/Dec/2002:14:28:36][6744.1024][-main-] Debug: Loading packages/faq-portlet/tcl/faq-admin-portlet-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/faq-portlet/tcl/faq-admin-portlet-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/faq-portlet/tcl/faq-portlet-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/faq-portlet/tcl/faq-portlet-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/file-manager/tcl/file-manager-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/file-manager/tcl/file-manager-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/file-storage/tcl/file-storage-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/file-storage/tcl/file-storage-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/file-storage/tcl/file-storage-search-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/file-storage/tcl/file-storage-search-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/file-storage/tcl/fs-simple-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/file-storage/tcl/fs-simple-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/forums/tcl/forum-message-reply-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/forums/tcl/forum-message-reply-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/forums/tcl/forum-reply-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/forums/tcl/forum-reply-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/forums/tcl/forums-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/forums/tcl/forums-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/forums/tcl/forums-security-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/forums/tcl/forums-security-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/forums/tcl/messages-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/forums/tcl/messages-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/forums-portlet/tcl/forums-admin-portlet-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/forums-portlet/tcl/forums-admin-portlet-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/forums-portlet/tcl/forums-portlet-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/forums-portlet/tcl/forums-portlet-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/fs-portlet/tcl/fs-contents-portlet-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/fs-portlet/tcl/fs-contents-portlet-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/fs-portlet/tcl/fs-portlet-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/fs-portlet/tcl/fs-portlet-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/gatekeeper/tcl/gatekeeper-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/gatekeeper/tcl/gatekeeper-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/general-comments/tcl/general-comments-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/general-comments/tcl/general-comments-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/glossary/tcl/glossary-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/glossary/tcl/glossary-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/glossary/tcl/glossary-sc-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/glossary/tcl/glossary-sc-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/lars-blogger/tcl/entry-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/lars-blogger/tcl/entry-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/lars-blogger/tcl/lars-blogger-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/lars-blogger/tcl/lars-blogger-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/lars-blogger/tcl/pinds-blog-entry-sc-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/lars-blogger/tcl/pinds-blog-entry-sc-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/lars-blogger/tcl/rss-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/lars-blogger/tcl/rss-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/lars-blogger/tcl/weblogs-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/lars-blogger/tcl/weblogs-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/monitoring/tcl/cassandracle-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/monitoring/tcl/cassandracle-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/monitoring/tcl/monitoring-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/monitoring/tcl/monitoring-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/monitoring/tcl/watchdog-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/monitoring/tcl/watchdog-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/mp3-jukebox/tcl/mp3-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/mp3-jukebox/tcl/mp3-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/mp3-jukebox/tcl/scanner-procs.tcl... [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loaded packages/mp3-jukebox/tcl/scanner-procs.tcl. [13/Dec/2002:14:28:37][6744.1024][-main-] Debug: Loading packages/new-portal/tcl/portal-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/new-portal/tcl/portal-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/news/tcl/news-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/news/tcl/news-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/news-portlet/tcl/news-admin-portlet-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/news-portlet/tcl/news-admin-portlet-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/news-portlet/tcl/news-portlet-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/news-portlet/tcl/news-portlet-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/notes/tcl/notes-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Notice: Multiple definition of notes__url in /web/oatest/packages/glossary/tcl/glossary-sc-procs.tcl and /web/oatest/packages/notes/tcl/notes-procs.tcl [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/notes/tcl/notes-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/notifications/tcl/delivery-method-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/notifications/tcl/delivery-method-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/notifications/tcl/interval-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/notifications/tcl/interval-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/notifications/tcl/notification-display-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/notifications/tcl/notification-display-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/notifications/tcl/notification-email-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/notifications/tcl/notification-email-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/notifications/tcl/notification-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/notifications/tcl/notification-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/notifications/tcl/notification-reply-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/notifications/tcl/notification-reply-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/notifications/tcl/notification-request-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/notifications/tcl/notification-request-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/notifications/tcl/notification-type-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/notifications/tcl/notification-type-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/notifications/tcl/notifications-security-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/notifications/tcl/notifications-security-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/notifications/tcl/reply-sweep-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/notifications/tcl/reply-sweep-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/notifications/tcl/sweep-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/notifications/tcl/sweep-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/openfts-driver/tcl/openfts-driver-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/openfts-driver/tcl/openfts-driver-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/payflowpro/tcl/payflowpro-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/payflowpro/tcl/payflowpro-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/payment-gateway/tcl/payment-gateway-library-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/payment-gateway/tcl/payment-gateway-library-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/photo-album/tcl/photo-album-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/photo-album/tcl/photo-album-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/photo-album-lite/tcl/bulk-upload-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/photo-album-lite/tcl/bulk-upload-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/press/tcl/press-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/press/tcl/press-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/robot-detection/tcl/robot-detection-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/robot-detection/tcl/robot-detection-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/rss-support/tcl/rss-generation-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/rss-support/tcl/rss-generation-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/rss-support/tcl/rss-generation-service-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/rss-support/tcl/rss-generation-service-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/rss-support/tcl/rss-utilities-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/rss-support/tcl/rss-utilities-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/sample-gateway/tcl/sample-gateway-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/sample-gateway/tcl/sample-gateway-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/schema-browser/tcl/schema-browser-procs-postgresql.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/schema-browser/tcl/schema-browser-procs-postgresql.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/search/tcl/search-procs.tcl... [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loaded packages/search/tcl/search-procs.tcl. [13/Dec/2002:14:28:38][6744.1024][-main-] Debug: Loading packages/simple-survey/tcl/survsimp-procs.tcl... [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: Loaded packages/simple-survey/tcl/survsimp-procs.tcl. [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: Loading packages/static-pages/tcl/static-pages-procs.tcl... [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: Loaded packages/static-pages/tcl/static-pages-procs.tcl. [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: Loading packages/static-pages/tcl/static-pages-sc-procs.tcl... [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: Loaded packages/static-pages/tcl/static-pages-sc-procs.tcl. [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: Loading packages/static-portlet/tcl/static-admin-portlet-procs.tcl... [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: Loaded packages/static-portlet/tcl/static-admin-portlet-procs.tcl. [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: Loading packages/static-portlet/tcl/static-portal-content-procs.tcl... [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: Loaded packages/static-portlet/tcl/static-portal-content-procs.tcl. [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: Loading packages/static-portlet/tcl/static-portlet-procs.tcl... [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: Loaded packages/static-portlet/tcl/static-portlet-procs.tcl. [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: Loading packages/survey/tcl/survey-procs.tcl... [13/Dec/2002:14:28:39][6744.1024][-main-] Notice: Multiple definition of util_show_plain_text in /web/oatest/packages/simple-survey/tcl/survsimp-procs.tcl and /web/oatest/packages/survey/tcl/survey-procs.tcl [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: Loaded packages/survey/tcl/survey-procs.tcl. [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: Loading packages/ticket-tracker/tcl/ttracker-procs.tcl... [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: Loaded packages/ticket-tracker/tcl/ttracker-procs.tcl. [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: Loading packages/ticket-tracker-lite/tcl/ticket-tracker-lite-procs.tcl... [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: Loaded packages/ticket-tracker-lite/tcl/ticket-tracker-lite-procs.tcl. [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: Loading packages/user-preferences/tcl/preference-procs.tcl... [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: Loaded packages/user-preferences/tcl/preference-procs.tcl. [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: Loading packages/user-preferences/tcl/preference-types-procs.tcl... [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: Loaded packages/user-preferences/tcl/preference-types-procs.tcl. [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: Loading packages/value-based-shipping/tcl/value-based-shipping-procs.tcl... [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: Loaded packages/value-based-shipping/tcl/value-based-shipping-procs.tcl. [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: Loading packages/version-control/tcl/version-control-procs.tcl... [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: Loaded packages/version-control/tcl/version-control-procs.tcl. [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-tcl.tcl.apm-procs.apm_load_queries.apm_enabled_packages_q --> using default SQL [13/Dec/2002:14:28:39][6744.1024][-main-] Debug: PgBindCmd: sql = select distinct package_key from apm_package_versions where enabled_p='t' [13/Dec/2002:14:28:39][6744.1024][-main-] Notice: Querying ' select distinct package_key from apm_package_versions where enabled_p='t';' [13/Dec/2002:14:28:39][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select distinct package_key from apm_package_versions where enabled_p='t' ' [13/Dec/2002:14:29:04][6744.1024][-main-] Debug: Query dbqd.dotlrn.www.member-add-2.select_role is *NOT* compatible [13/Dec/2002:14:29:04][6744.1024][-main-] Debug: Query dbqd.dotlrn.www.unsubscribe-members.update_autosubscribe_p is *NOT* compatible [13/Dec/2002:14:29:06][6744.1024][-main-] Debug: Query dbqd.dotlrn-forums.www.user.select_messages is *NOT* compatible [13/Dec/2002:14:29:06][6744.1024][-main-] Debug: Query dbqd.dotlrn-forums.www.user.select_messages_by_forum is *NOT* compatible [13/Dec/2002:14:30:46][6744.1024][-main-] Notice: APM/QD = DONE looping through files to load queries from [13/Dec/2002:14:30:46][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-tcl.tcl.apm-procs.apm_load_libraries.apm_enabled_packages_l --> using default SQL [13/Dec/2002:14:30:46][6744.1024][-main-] Debug: PgBindCmd: sql = select distinct package_key from apm_package_versions where enabled_p='t' [13/Dec/2002:14:30:46][6744.1024][-main-] Notice: Querying ' select distinct package_key from apm_package_versions where enabled_p='t';' [13/Dec/2002:14:30:46][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select distinct package_key from apm_package_versions where enabled_p='t' ' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: Loading packages/acs-bootstrap-installer/installer-init.tcl... [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: Loaded packages/acs-bootstrap-installer/installer-init.tcl. [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: Loading packages/acs-content-repository/tcl/acs-content-repository-init.tcl... [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Scheduling proc cr_delete_scheduled_files [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Scheduling proc acs_cr_scheduled_release_exec [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: Loaded packages/acs-content-repository/tcl/acs-content-repository-init.tcl. [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: Loading packages/acs-developer-support/tcl/acs-developer-support-init.tcl... [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select package_id from apm_packages where package_key = 'acs-developer-support' limit 1 [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying ' select package_id from apm_packages where package_key = 'acs-developer-support' limit 1;' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select package_id from apm_packages where package_key = 'acs-developer-support' limit 1 ' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd..NULL --> using default SQL [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select package_key from apm_packages where package_id = :package_id [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: package_id = 27506 [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select package_key from apm_packages where package_id = '27506' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying ' select package_key from apm_packages where package_id = '27506';' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select package_key from apm_packages where package_id = '27506' ' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = :package_id and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = :parameter_name [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: package_id = 27506 [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: parameter_name = DataSweepInterval [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '27506' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'DataSweepInterval' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '27506' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'DataSweepInterval';' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '27506' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'DataSweepInterval' ' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Scheduling proc ds_sweep_data [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = :package_id and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = :parameter_name [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: package_id = 27506 [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: parameter_name = EnabledOnStartupP [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '27506' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'EnabledOnStartupP' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '27506' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'EnabledOnStartupP';' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '27506' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'EnabledOnStartupP' ' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = :package_id and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = :parameter_name [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: package_id = 27506 [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: parameter_name = DatabaseEnabledP [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '27506' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'DatabaseEnabledP' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '27506' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'DatabaseEnabledP';' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '27506' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'DatabaseEnabledP' ' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = :package_id and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = :parameter_name [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: package_id = 27506 [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: parameter_name = UserSwitchingEnabledP [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '27506' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'UserSwitchingEnabledP' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '27506' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'UserSwitchingEnabledP';' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select apm_parameter_values.attr_value from apm_parameters, apm_parameter_values where apm_parameter_values.package_id = '27506' and apm_parameter_values.parameter_id = apm_parameters.parameter_id and apm_parameters.parameter_name = 'UserSwitchingEnabledP' ' [13/Dec/2002:14:30:48][6744.1024][-main-] Warning: Developer-support user-switching disabled [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: Loaded packages/acs-developer-support/tcl/acs-developer-support-init.tcl. [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: Loading packages/acs-lang/tcl/lang-init.tcl... [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select key ,trim(trailing from lang) as lang ,message from lang_messages [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying ' select key ,trim(trailing from lang) as lang ,message from lang_messages;' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select key ,trim(trailing from lang) as lang ,message from lang_messages ' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Initialized message table; got 16 rows [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Scheduling proc lang_catalog_load [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: Loaded packages/acs-lang/tcl/lang-init.tcl. [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: Loading packages/acs-lang/tcl/locale-init.tcl... [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: Loaded packages/acs-lang/tcl/locale-init.tcl. [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: Loading packages/acs-lang/tcl/localization-data-init.tcl... [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: Loaded packages/acs-lang/tcl/localization-data-init.tcl. [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: Loading packages/acs-mail/tcl/acs-mail-init.tcl... [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Scheduling proc acs_mail_process_queue [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: acs-mail: ns_uuencode broken - will use the slow tcl version [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: Loaded packages/acs-mail/tcl/acs-mail-init.tcl. [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: Loading packages/acs-mail-lite/tcl/acs-mail-lite-init.tcl... [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Scheduling proc acs_mail_lite::sweeper [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: Loaded packages/acs-mail-lite/tcl/acs-mail-lite-init.tcl. [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: Loading packages/acs-messaging/tcl/acs-messaging-init.tcl... [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Scheduling proc acs_messaging_process_queue [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: Loaded packages/acs-messaging/tcl/acs-messaging-init.tcl. [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: Loading packages/acs-service-contract/tcl/acs-service-contract-init.tcl... [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-service-contract.tcl.acs-service-contract-init.impl_operation --> using default SQL [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select impl_contract_name, impl_operation_name, impl_name from acs_sc_impl_aliases [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying ' select impl_contract_name, impl_operation_name, impl_name from acs_sc_impl_aliases;' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select impl_contract_name, impl_operation_name, impl_name from acs_sc_impl_aliases ' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-service-contract.tcl.acs-service-contract-init.binding_exists_p --> using default SQL [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select acs_sc_binding__exists_p(:impl_contract_name,:impl_name) [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_contract_name = FtsContentProvider [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_name = content_revision [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select acs_sc_binding__exists_p('FtsContentProvider','content_revision') [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying 'select acs_sc_binding__exists_p('FtsContentProvider','content_revision');' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): 'select acs_sc_binding__exists_p('FtsContentProvider','content_revision')' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-service-contract.tcl.acs-service-contract-init.binding_exists_p --> using default SQL [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select acs_sc_binding__exists_p(:impl_contract_name,:impl_name) [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_contract_name = FtsContentProvider [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_name = content_revision [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select acs_sc_binding__exists_p('FtsContentProvider','content_revision') [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying 'select acs_sc_binding__exists_p('FtsContentProvider','content_revision');' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): 'select acs_sc_binding__exists_p('FtsContentProvider','content_revision')' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-service-contract.tcl.acs-service-contract-init.binding_exists_p --> using default SQL [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select acs_sc_binding__exists_p(:impl_contract_name,:impl_name) [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_contract_name = FtsContentProvider [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_name = image [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select acs_sc_binding__exists_p('FtsContentProvider','image') [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying 'select acs_sc_binding__exists_p('FtsContentProvider','image');' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): 'select acs_sc_binding__exists_p('FtsContentProvider','image')' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-service-contract.tcl.acs-service-contract-init.binding_exists_p --> using default SQL [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select acs_sc_binding__exists_p(:impl_contract_name,:impl_name) [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_contract_name = FtsContentProvider [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_name = image [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select acs_sc_binding__exists_p('FtsContentProvider','image') [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying 'select acs_sc_binding__exists_p('FtsContentProvider','image');' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): 'select acs_sc_binding__exists_p('FtsContentProvider','image')' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-service-contract.tcl.acs-service-contract-init.binding_exists_p --> using default SQL [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select acs_sc_binding__exists_p(:impl_contract_name,:impl_name) [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_contract_name = FtsContentProvider [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_name = content_template [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select acs_sc_binding__exists_p('FtsContentProvider','content_template') [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying 'select acs_sc_binding__exists_p('FtsContentProvider','content_template');' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): 'select acs_sc_binding__exists_p('FtsContentProvider','content_template')' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-service-contract.tcl.acs-service-contract-init.binding_exists_p --> using default SQL [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select acs_sc_binding__exists_p(:impl_contract_name,:impl_name) [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_contract_name = FtsContentProvider [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_name = content_template [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select acs_sc_binding__exists_p('FtsContentProvider','content_template') [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying 'select acs_sc_binding__exists_p('FtsContentProvider','content_template');' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): 'select acs_sc_binding__exists_p('FtsContentProvider','content_template')' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-service-contract.tcl.acs-service-contract-init.binding_exists_p --> using default SQL [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select acs_sc_binding__exists_p(:impl_contract_name,:impl_name) [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_contract_name = FtsContentProvider [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_name = file_storage_object [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select acs_sc_binding__exists_p('FtsContentProvider','file_storage_object') [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying 'select acs_sc_binding__exists_p('FtsContentProvider','file_storage_object');' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): 'select acs_sc_binding__exists_p('FtsContentProvider','file_storage_object')' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-service-contract.tcl.acs-service-contract-init.binding_exists_p --> using default SQL [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select acs_sc_binding__exists_p(:impl_contract_name,:impl_name) [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_contract_name = FtsContentProvider [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_name = file_storage_object [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select acs_sc_binding__exists_p('FtsContentProvider','file_storage_object') [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying 'select acs_sc_binding__exists_p('FtsContentProvider','file_storage_object');' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): 'select acs_sc_binding__exists_p('FtsContentProvider','file_storage_object')' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-service-contract.tcl.acs-service-contract-init.binding_exists_p --> using default SQL [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select acs_sc_binding__exists_p(:impl_contract_name,:impl_name) [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_contract_name = FtsContentProvider [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_name = note [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select acs_sc_binding__exists_p('FtsContentProvider','note') [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying 'select acs_sc_binding__exists_p('FtsContentProvider','note');' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): 'select acs_sc_binding__exists_p('FtsContentProvider','note')' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-service-contract.tcl.acs-service-contract-init.binding_exists_p --> using default SQL [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select acs_sc_binding__exists_p(:impl_contract_name,:impl_name) [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_contract_name = FtsContentProvider [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_name = note [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select acs_sc_binding__exists_p('FtsContentProvider','note') [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying 'select acs_sc_binding__exists_p('FtsContentProvider','note');' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): 'select acs_sc_binding__exists_p('FtsContentProvider','note')' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-service-contract.tcl.acs-service-contract-init.binding_exists_p --> using default SQL [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select acs_sc_binding__exists_p(:impl_contract_name,:impl_name) [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_contract_name = FtsEngineDriver [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_name = openfts-driver [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select acs_sc_binding__exists_p('FtsEngineDriver','openfts-driver') [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying 'select acs_sc_binding__exists_p('FtsEngineDriver','openfts-driver');' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): 'select acs_sc_binding__exists_p('FtsEngineDriver','openfts-driver')' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-service-contract.tcl.acs-service-contract-init.binding_exists_p --> using default SQL [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select acs_sc_binding__exists_p(:impl_contract_name,:impl_name) [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_contract_name = FtsEngineDriver [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_name = openfts-driver [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select acs_sc_binding__exists_p('FtsEngineDriver','openfts-driver') [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying 'select acs_sc_binding__exists_p('FtsEngineDriver','openfts-driver');' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): 'select acs_sc_binding__exists_p('FtsEngineDriver','openfts-driver')' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-service-contract.tcl.acs-service-contract-init.binding_exists_p --> using default SQL [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select acs_sc_binding__exists_p(:impl_contract_name,:impl_name) [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_contract_name = FtsEngineDriver [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_name = openfts-driver [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select acs_sc_binding__exists_p('FtsEngineDriver','openfts-driver') [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying 'select acs_sc_binding__exists_p('FtsEngineDriver','openfts-driver');' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): 'select acs_sc_binding__exists_p('FtsEngineDriver','openfts-driver')' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-service-contract.tcl.acs-service-contract-init.binding_exists_p --> using default SQL [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select acs_sc_binding__exists_p(:impl_contract_name,:impl_name) [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_contract_name = FtsEngineDriver [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_name = openfts-driver [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select acs_sc_binding__exists_p('FtsEngineDriver','openfts-driver') [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying 'select acs_sc_binding__exists_p('FtsEngineDriver','openfts-driver');' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): 'select acs_sc_binding__exists_p('FtsEngineDriver','openfts-driver')' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-service-contract.tcl.acs-service-contract-init.binding_exists_p --> using default SQL [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select acs_sc_binding__exists_p(:impl_contract_name,:impl_name) [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_contract_name = FtsEngineDriver [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_name = openfts-driver [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select acs_sc_binding__exists_p('FtsEngineDriver','openfts-driver') [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying 'select acs_sc_binding__exists_p('FtsEngineDriver','openfts-driver');' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): 'select acs_sc_binding__exists_p('FtsEngineDriver','openfts-driver')' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-service-contract.tcl.acs-service-contract-init.binding_exists_p --> using default SQL [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select acs_sc_binding__exists_p(:impl_contract_name,:impl_name) [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_contract_name = FtsEngineDriver [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_name = openfts-driver [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select acs_sc_binding__exists_p('FtsEngineDriver','openfts-driver') [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying 'select acs_sc_binding__exists_p('FtsEngineDriver','openfts-driver');' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): 'select acs_sc_binding__exists_p('FtsEngineDriver','openfts-driver')' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-service-contract.tcl.acs-service-contract-init.binding_exists_p --> using default SQL [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select acs_sc_binding__exists_p(:impl_contract_name,:impl_name) [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_contract_name = PaymentGateway [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl_name = authorize-gateway [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select acs_sc_binding__exists_p('PaymentGateway','authorize-gateway') [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying 'select acs_sc_binding__exists_p('PaymentGateway','authorize-gateway');' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): 'select acs_sc_binding__exists_p('PaymentGateway','authorize-gateway')' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-service-contract.tcl.acs-service-contract-procs.acs_sc_binding_exists_p.binding_exists_p --> using default SQL [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select acs_sc_binding__exists_p(:contract,:impl) [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: contract = PaymentGateway [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl = authorize-gateway [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select acs_sc_binding__exists_p('PaymentGateway','authorize-gateway') [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying 'select acs_sc_binding__exists_p('PaymentGateway','authorize-gateway');' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): 'select acs_sc_binding__exists_p('PaymentGateway','authorize-gateway')' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-service-contract.tcl.acs-service-contract-procs.acs_sc_get_alias.get_alias --> using default SQL [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select impl_alias, impl_pl from acs_sc_impl_aliases where impl_contract_name = :contract and impl_operation_name = :operation and impl_name = :impl [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: contract = PaymentGateway [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: operation = Authorize [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: impl = authorize-gateway [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select impl_alias, impl_pl from acs_sc_impl_aliases where impl_contract_name = 'PaymentGateway' and impl_operation_name = 'Authorize' and impl_name = 'authorize-gateway' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying ' select impl_alias, impl_pl from acs_sc_impl_aliases where impl_contract_name = 'PaymentGateway' and impl_operation_name = 'Authorize' and impl_name = 'authorize-gateway';' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select impl_alias, impl_pl from acs_sc_impl_aliases where impl_contract_name = 'PaymentGateway' and impl_operation_name = 'Authorize' and impl_name = 'authorize-gateway' ' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-service-contract.tcl.acs-service-contract-procs.acs_sc_proc.get_operation_definition --> using default SQL [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select operation_desc, operation_iscachable_p, operation_nargs, operation_inputtype_id, operation_outputtype_id from acs_sc_operations where contract_name = :contract and operation_name = :operation [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: contract = PaymentGateway [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: operation = Authorize [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select operation_desc, operation_iscachable_p, operation_nargs, operation_inputtype_id, operation_outputtype_id from acs_sc_operations where contract_name = 'PaymentGateway' and operation_name = 'Authorize' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying ' select operation_desc, operation_iscachable_p, operation_nargs, operation_inputtype_id, operation_outputtype_id from acs_sc_operations where contract_name = 'PaymentGateway' and operation_name = 'Authorize';' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select operation_desc, operation_iscachable_p, operation_nargs, operation_inputtype_id, operation_outputtype_id from acs_sc_operations where contract_name = 'PaymentGateway' and operation_name = 'Authorize' ' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-service-contract.tcl.acs-service-contract-procs.acs_sc_proc.operation_inputtype_element --> using default SQL [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select element_name, acs_sc_msg_type__get_name(element_msg_type_id) as element_msg_type_name, element_msg_type_isset_p, element_pos from acs_sc_msg_type_elements where msg_type_id = :operation_inputtype_id order by element_pos asc [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: bind var: operation_inputtype_id = 4923 [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: query with bind variables substituted = select element_name, acs_sc_msg_type__get_name(element_msg_type_id) as element_msg_type_name, element_msg_type_isset_p, element_pos from acs_sc_msg_type_elements where msg_type_id = '4923' order by element_pos asc [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: Querying ' select element_name, acs_sc_msg_type__get_name(element_msg_type_id) as element_msg_type_name, element_msg_type_isset_p, element_pos from acs_sc_msg_type_elements where msg_type_id = '4923' order by element_pos asc;' [13/Dec/2002:14:30:48][6744.1024][-main-] Notice: dbinit: sql(localhost::oatest): ' select element_name, acs_sc_msg_type__get_name(element_msg_type_id) as element_msg_type_name, element_msg_type_isset_p, element_pos from acs_sc_msg_type_elements where msg_type_id = '4923' order by element_pos asc ' [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: NO FULLQUERY FOR dbqd.acs-service-contract.tcl.acs-service-contract-procs.acs_sc_proc.operation_outputtype_element --> using default SQL [13/Dec/2002:14:30:48][6744.1024][-main-] Debug: PgBindCmd: sql = select element_name, acs_sc_msg_type__get_name(element_msg_type_id) as element_msg_type_name, element_msg_type_isset_p, element_pos from acs_sc_msg_type_elements where msg_type_id = :operation_outputtype