Forum OpenACS Q&A: Re: New installation problems
Tried it again with debug true to get more info in case useful:
... PgBindCmd: query with bind variables substituted = select nextval('acs_object_id_seq') as nextval where (select relkind from pg_class where relname = 'acs_object_id_seq') = 'S' [21/Mar/2003:20:35:36][10710.4101][-conn1-] Notice: Querying ' select nextval('acs_object_id_seq') as nextval where (select relkind from pg_class where relname = 'acs_object_id_seq') = 'S';' [21/Mar/2003:20:35:36][10710.4101][-conn1-] Notice: dbinit: sql(localhost::ttcdev): ' select nextval('acs_object_id_seq') as nextval where (select relkind from pg_class where relname = 'acs_object_id_seq') = 'S' ' [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: db_nextval: sequence(acs_object_id_seq) is not a real sequence. perhaps it uses the view hack. [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: sql = select nextval from acs_object_id_seq [21/Mar/2003:20:35:36][10710.4101][-conn1-] Notice: Querying ' select nextval from acs_object_id_seq;' [21/Mar/2003:20:35:36][10710.4101][-conn1-] Notice: dbinit: sql(localhost::ttcdev): ' select nextval from acs_object_id_seq ' [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: sql = select package_key, pretty_name from apm_package_types where not (apm_package__singleton_p(package_key) = 1 and apm_package__num_instances(package_key) >= 1) order by pretty_name [21/Mar/2003:20:35:36][10710.4101][-conn1-] Notice: Querying ' select package_key, pretty_name from apm_package_types where not (apm_package__singleton_p(package_key) = 1 and apm_package__num_instances(package_key) >= 1) order by pretty_name;' [21/Mar/2003:20:35:36][10710.4101][-conn1-] Notice: dbinit: sql(localhost::ttcdev): ' select package_key, pretty_name from apm_package_types where not (apm_package__singleton_p(package_key) = 1 and apm_package__num_instances(package_key) >= 1) order by pretty_name ' [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: sql = select 1 where 't' = acs_permission__permission_p(:object_id, :party_id, :privilege) [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: bind var: object_id = 0 [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: bind var: party_id = 287 [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: bind var: privilege = admin [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: query with bind variables substituted = select 1 where 't' = acs_permission__permission_p('0', '287', 'admin') [21/Mar/2003:20:35:36][10710.4101][-conn1-] Notice: Querying ' select 1 where 't' = acs_permission__permission_p('0', '287', 'admin');' [21/Mar/2003:20:35:36][10710.4101][-conn1-] Notice: dbinit: sql(localhost::ttcdev): ' select 1 where 't' = acs_permission__permission_p('0', '287', 'admin') ' [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: sql = select package_id, ap.package_key, acs_object__name(package_id) as instance_name, apm_package_type__num_parameters(ap.package_key) as parameter_count from apm_packages ap, apm_package_types where ap.package_key = apm_package_types.package_key and package_type = 'apm_service' and ( acs_permission__permission_p(package_id, :user_id, 'read') = 't' or acs_permission__permission_p(package_id, acs__magic_object_id('the_public'), 'read') = 't' ) order by instance_name [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: bind var: user_id = 287 [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: query with bind variables substituted = select package_id, ap.package_key, acs_object__name(package_id) as instance_name, apm_package_type__num_parameters(ap.package_key) as parameter_count from apm_packages ap, apm_package_types where ap.package_key = apm_package_types.package_key and package_type = 'apm_service' and ( acs_permission__permission_p(package_id, '287', 'read') = 't' or acs_permission__permission_p(package_id, acs__magic_object_id('the_public'), 'read') = 't' ) order by instance_name [21/Mar/2003:20:35:36][10710.4101][-conn1-] Notice: Querying ' select package_id, ap.package_key, acs_object__name(package_id) as instance_name, apm_package_type__num_parameters(ap.package_key) as parameter_count from apm_packages ap, apm_package_types where ap.package_key = apm_package_types.package_key and package_type = 'apm_service' and ( acs_permission__permission_p(package_id, '287', 'read') = 't' or acs_permission__permission_p(package_id, acs__magic_object_id('the_public'), 'read') = 't' ) order by instance_name;' NOTICE: identifier "apm_package_type__num_parameters" will be truncated to "apm_package_type__num_parameter" [21/Mar/2003:20:35:36][10710.4101][-conn1-] Notice: dbinit: sql(localhost::ttcdev): ' select package_id, ap.package_key, acs_object__name(package_id) as instance_name, apm_package_type__num_parameters(ap.package_key) as parameter_count from apm_packages ap, apm_package_types where ap.package_key = apm_package_types.package_key and package_type = 'apm_service' and ( acs_permission__permission_p(package_id, '287', 'read') = 't' or acs_permission__permission_p(package_id, acs__magic_object_id('the_public'), 'read') = 't' ) order by instance_name ' [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: sql = select 1 where 't' = acs_permission__permission_p(:object_id, :party_id, :privilege) [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: bind var: object_id = 176 [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: bind var: party_id = 287 [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: bind var: privilege = admin [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: query with bind variables substituted = select 1 where 't' = acs_permission__permission_p('176', '287', 'admin') [21/Mar/2003:20:35:36][10710.4101][-conn1-] Notice: Querying ' select 1 where 't' = acs_permission__permission_p('176', '287', 'admin');' [21/Mar/2003:20:35:36][10710.4101][-conn1-] Notice: dbinit: sql(localhost::ttcdev): ' select 1 where 't' = acs_permission__permission_p('176', '287', 'admin') ' [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: sql = select 1 where 't' = acs_permission__permission_p(:object_id, :party_id, :privilege) [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: bind var: object_id = 50 [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: bind var: party_id = 287 [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: bind var: privilege = admin [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: query with bind variables substituted = select 1 where 't' = acs_permission__permission_p('50', '287', 'admin') [21/Mar/2003:20:35:36][10710.4101][-conn1-] Notice: Querying ' select 1 where 't' = acs_permission__permission_p('50', '287', 'admin');' [21/Mar/2003:20:35:36][10710.4101][-conn1-] Notice: dbinit: sql(localhost::ttcdev): ' select 1 where 't' = acs_permission__permission_p('50', '287', 'admin') ' [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: sql = select 1 where 't' = acs_permission__permission_p(:object_id, :party_id, :privilege) [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: bind var: object_id = 121 [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: bind var: party_id = 287 [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: bind var: privilege = admin [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: query with bind variables substituted = select 1 where 't' = acs_permission__permission_p('121', '287', 'admin') [21/Mar/2003:20:35:36][10710.4101][-conn1-] Notice: Querying ' select 1 where 't' = acs_permission__permission_p('121', '287', 'admin');' [21/Mar/2003:20:35:36][10710.4101][-conn1-] Notice: dbinit: sql(localhost::ttcdev): ' select 1 where 't' = acs_permission__permission_p('121', '287', 'admin') ' [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: sql = select 1 where 't' = acs_permission__permission_p(:object_id, :party_id, :privilege) [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: bind var: object_id = 253 [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: bind var: party_id = 287 [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: bind var: privilege = admin [21/Mar/2003:20:35:36][10710.4101][-conn1-] Debug: PgBindCmd: query with bind variables substituted = select 1 where 't' = acs_permission__permission_p('253', '287', 'admin') [21/Mar/2003:20:35:36][10710.4101][-conn1-] Notice: Querying ' select 1 where 't' = acs_permission__permission_p('253', '287', 'admin');' [21/Mar/2003:20:35:36][10710.4101][-conn1-] Notice: dbinit: sql(localhost::ttcdev): ' select 1 where 't' = acs_permission__permission_p('253', '287', 'admin') ' [21/Mar/2003:20:35:39][10710.7176][-conn4-] Debug: RP (5.957 ms): rp_filter: setting up request: GET /admin/site-map/package-new expand=281&root_id=281&node_id=289&new_package_id=347&instance_name=Untitled&package_key=acs-subsite [21/Mar/2003:20:35:39][10710.7176][-conn4-] Debug: Security: Getting token_id 100, value 510309EAE9E719C0F9A75EAF56DC11596DA605A0 [21/Mar/2003:20:35:39][10710.7176][-conn4-] Debug: Security: Expire_Time is 1048298125 (compare to 1048296939), hash is 5D9D68F1DDAC9A35F6525DBCE9235AEEB4E60152 [21/Mar/2003:20:35:39][10710.7176][-conn4-] Debug: Security: Done calling get_cookie 1,287 {100 1048298125 5D9D68F1DDAC9A35F6525DBCE9235AEEB4E60152} for ad_session_id; received 1048298125 expiration, getting 1,287 and 100 1048298125 5D9D68F1DDAC9A35F6525DBCE9235AEEB4E60152. [21/Mar/2003:20:35:39][10710.7176][-conn4-] Debug: PgBindCmd: sql = select 1 where 't' = acs_permission__permission_p(:object_id, :party_id, :privilege) [21/Mar/2003:20:35:39][10710.7176][-conn4-] Debug: PgBindCmd: bind var: object_id = 253 [21/Mar/2003:20:35:39][10710.7176][-conn4-] Debug: PgBindCmd: bind var: party_id = 287 [21/Mar/2003:20:35:39][10710.7176][-conn4-] Debug: PgBindCmd: bind var: privilege = admin [21/Mar/2003:20:35:39][10710.7176][-conn4-] Debug: PgBindCmd: query with bind variables substituted = select 1 where 't' = acs_permission__permission_p('253', '287', 'admin') [21/Mar/2003:20:35:39][10710.7176][-conn4-] Notice: Querying ' select 1 where 't' = acs_permission__permission_p('253', '287', 'admin');' [21/Mar/2003:20:35:39][10710.7176][-conn4-] Notice: dbinit: sql(localhost::ttcdev): ' select 1 where 't' = acs_permission__permission_p('253', '287', 'admin') ' [21/Mar/2003:20:35:39][10710.7176][-conn4-] Notice: ns_getform using encoding iso8859-1 for charset iso-8859-1 [21/Mar/2003:20:35:39][10710.7176][-conn4-] Debug: NO FULLQUERY FOR dbqd.acs-subsite.www.admin.site-map.package-new.context_id --> using default SQL [21/Mar/2003:20:35:39][10710.7176][-conn4-] Debug: PgBindCmd: sql = select parent.object_id as context_id from site_nodes parent, site_nodes child where child.node_id = :node_id and parent.node_id = child.parent_id [21/Mar/2003:20:35:39][10710.7176][-conn4-] Debug: PgBindCmd: bind var: node_id = 289 [21/Mar/2003:20:35:39][10710.7176][-conn4-] Debug: PgBindCmd: query with bind variables substituted = select parent.object_id as context_id from site_nodes parent, site_nodes child where child.node_id = '289' and parent.node_id = child.parent_id [21/Mar/2003:20:35:39][10710.7176][-conn4-] Notice: Querying ' select parent.object_id as context_id from site_nodes parent, site_nodes child where child.node_id = '289' and parent.node_id = child.parent_id;' [21/Mar/2003:20:35:39][10710.7176][-conn4-] Notice: dbinit: sql(localhost::ttcdev): ' select parent.object_id as context_id from site_nodes parent, site_nodes child where child.node_id = '289' and parent.node_id = child.parent_id ' [21/Mar/2003:20:35:39][10710.7176][-conn4-] Notice: Querying 'begin transaction;' [21/Mar/2003:20:35:39][10710.7176][-conn4-] Notice: Ns_PgExec: Entering transaction [21/Mar/2003:20:35:39][10710.7176][-conn4-] Notice: dbinit: sql(localhost::ttcdev): 'begin transaction' [21/Mar/2003:20:35:39][10710.7176][-conn4-] Debug: Deprecated proc site_node_create_package_instance used [21/Mar/2003:20:35:39][10710.7176][-conn4-] Debug: PLPGSQL: bypassed anon function [21/Mar/2003:20:35:39][10710.7176][-conn4-] Debug: PgBindCmd: sql = select apm_package__new( :package_id, :instance_name, :package_key, 'apm_package', now(), null, null, :context_id ); [21/Mar/2003:20:35:39][10710.7176][-conn4-] Debug: PgBindCmd: bind var: package_id = [21/Mar/2003:20:35:39][10710.7176][-conn4-] Debug: PgBindCmd: bind var: instance_name = Untitled [21/Mar/2003:20:35:39][10710.7176][-conn4-] Debug: PgBindCmd: bind var: package_key = acs-subsite [21/Mar/2003:20:35:39][10710.7176][-conn4-] Debug: PgBindCmd: bind var: context_id = 253 [21/Mar/2003:20:35:39][10710.7176][-conn4-] Debug: PgBindCmd: query with bind variables substituted = select apm_package__new( NULL, 'Untitled', 'acs-subsite', 'apm_package', now(), null, null, '253' ); [21/Mar/2003:20:35:39][10710.7176][-conn4-] Notice: Querying ' select apm_package__new( NULL, 'Untitled', 'acs-subsite', 'apm_package', now(), null, null, '253' );' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: dbinit: sql(localhost::ttcdev): ' select apm_package__new( NULL, 'Untitled', 'acs-subsite', 'apm_package', now(), null, null, '253' ); ' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: sql = select parameter_name, attr_value from apm_parameters p, apm_parameter_values v, apm_packages a where p.parameter_id = v.parameter_id and a.package_id = v.package_id and a.package_id = :package_id [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: bind var: package_id = 348 [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: query with bind variables substituted = select parameter_name, attr_value from apm_parameters p, apm_parameter_values v, apm_packages a where p.parameter_id = v.parameter_id and a.package_id = v.package_id and a.package_id = '348' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: Querying ' select parameter_name, attr_value from apm_parameters p, apm_parameter_values v, apm_packages a where p.parameter_id = v.parameter_id and a.package_id = v.package_id and a.package_id = '348';' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: dbinit: sql(localhost::ttcdev): ' select parameter_name, attr_value from apm_parameters p, apm_parameter_values v, apm_packages a where p.parameter_id = v.parameter_id and a.package_id = v.package_id and a.package_id = '348' ' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: sql = select version_id from apm_enabled_package_versions where package_key = :package_key [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: bind var: package_key = acs-subsite [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: query with bind variables substituted = select version_id from apm_enabled_package_versions where package_key = 'acs-subsite' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: Querying ' select version_id from apm_enabled_package_versions where package_key = 'acs-subsite';' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: dbinit: sql(localhost::ttcdev): ' select version_id from apm_enabled_package_versions where package_key = 'acs-subsite' ' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: sql = select proc from apm_package_callbacks where version_id = :version_id and type = :type [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: bind var: version_id = 216 [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: bind var: type = after-instantiate [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: query with bind variables substituted = select proc from apm_package_callbacks where version_id = '216' and type = 'after-instantiate' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: Querying ' select proc from apm_package_callbacks where version_id = '216' and type = 'after-instantiate';' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: dbinit: sql(localhost::ttcdev): ' select proc from apm_package_callbacks where version_id = '216' and type = 'after-instantiate' ' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: sql = update site_nodes set object_id = :object_id where node_id = :node_id [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: bind var: object_id = 348 [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: bind var: node_id = 289 [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: query with bind variables substituted = update site_nodes set object_id = '348' where node_id = '289' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: Querying ' update site_nodes set object_id = '348' where node_id = '289';' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: dbinit: sql(localhost::ttcdev): ' update site_nodes set object_id = '348' where node_id = '289' ' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: sql = select site_node__url(site_nodes.node_id) as url, site_nodes.node_id, site_nodes.parent_id, site_nodes.name, 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 where site_nodes.node_id = :node_id [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: bind var: node_id = 289 [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: query with bind variables substituted = select site_node__url(site_nodes.node_id) as url, site_nodes.node_id, site_nodes.parent_id, site_nodes.name, 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 where site_nodes.node_id = '289' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: Querying ' select site_node__url(site_nodes.node_id) as url, site_nodes.node_id, site_nodes.parent_id, site_nodes.name, 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 where site_nodes.node_id = '289';' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: dbinit: sql(localhost::ttcdev): ' select site_node__url(site_nodes.node_id) as url, site_nodes.node_id, site_nodes.parent_id, site_nodes.name, 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 where site_nodes.node_id = '289' ' [21/Mar/2003:20:35:40][10710.1024][-main-] Notice: tclinit: re-initalizing tcl [21/Mar/2003:20:35:40][10710.1024][-main-] Notice: tcl: generating interp init script [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: NO FULLQUERY FOR dbqd..NULL --> using default SQL [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: sql = select package_key from apm_packages where package_id = :package_id [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: bind var: package_id = 348 [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: query with bind variables substituted = select package_key from apm_packages where package_id = '348' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: Querying ' select package_key from apm_packages where package_id = '348';' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: dbinit: sql(localhost::ttcdev): ' select package_key from apm_packages where package_id = '348' ' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: sql = select version_id from apm_enabled_package_versions where package_key = :package_key [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: bind var: package_key = acs-subsite [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: query with bind variables substituted = select version_id from apm_enabled_package_versions where package_key = 'acs-subsite' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: Querying ' select version_id from apm_enabled_package_versions where package_key = 'acs-subsite';' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: dbinit: sql(localhost::ttcdev): ' select version_id from apm_enabled_package_versions where package_key = 'acs-subsite' ' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: sql = select proc from apm_package_callbacks where version_id = :version_id and type = :type [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: bind var: version_id = 216 [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: bind var: type = after-mount [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: query with bind variables substituted = select proc from apm_package_callbacks where version_id = '216' and type = 'after-mount' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: Querying ' select proc from apm_package_callbacks where version_id = '216' and type = 'after-mount';' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: dbinit: sql(localhost::ttcdev): ' select proc from apm_package_callbacks where version_id = '216' and type = 'after-mount' ' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: Invoking callback after-mount with command subsite::after_mount -package_id 348 -node_id 289 [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PLPGSQL: bypassed anon function [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: sql = select application_group__group_id_from_package_id ( :package_id, :no_complain_p ) [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: bind var: package_id = 348 [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: bind var: no_complain_p = f [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: query with bind variables substituted = select application_group__group_id_from_package_id ( '348', 'f' ) [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: Querying ' select application_group__group_id_from_package_id ( '348', 'f' );' NOTICE: identifier "application_group__group_id_from_package_id" will be truncated to "application_group__group_id_fro" [21/Mar/2003:20:35:40][10710.7176][-conn4-] Error: Ns_PgExec: result status: 7 message: ERROR: -20000: No group_id found for package 348 (Untitled) [21/Mar/2003:20:35:40][10710.7176][-conn4-] Error: dbinit: error(localhost::ttcdev,ERROR: -20000: No group_id found for package 348 (Untitled) ): ' select application_group__group_id_from_package_id ( '348', 'f' ) ' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: Querying 'abort transaction;' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: Ns_PgExec: Rolling back transaction [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: dbinit: sql(localhost::ttcdev): 'abort transaction' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: Querying 'begin transaction;' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: Ns_PgExec: Entering transaction [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: dbinit: sql(localhost::ttcdev): 'begin transaction' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: sql = select case when count(*) = 0 then 0 else 1 end from apm_packages where package_id = :new_package_id [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: bind var: new_package_id = 347 [21/Mar/2003:20:35:40][10710.7176][-conn4-] Debug: PgBindCmd: query with bind variables substituted = select case when count(*) = 0 then 0 else 1 end from apm_packages where package_id = '347' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: Querying ' select case when count(*) = 0 then 0 else 1 end from apm_packages where package_id = '347';' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: dbinit: sql(localhost::ttcdev): ' select case when count(*) = 0 then 0 else 1 end from apm_packages where package_id = '347' ' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: Ns_PgResetHandle: Rolling back transaction [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: Querying 'rollback;' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: Ns_PgExec: Rolling back transaction [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: Querying 'abort transaction;' NOTICE: ROLLBACK: no transaction in progress [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: Ns_PgExec: Rolling back transaction [21/Mar/2003:20:35:40][10710.7176][-conn4-] Notice: dbinit: sql(localhost::ttcdev): 'abort transaction' [21/Mar/2003:20:35:40][10710.7176][-conn4-] Error: Aborting transaction due to error: Database operation "0or1row" failed (exception NSDB, "Query was not a statement returning rows.") [21/Mar/2003:20:35:40][10710.7176][-conn4-] Warning: propagating abortion from /web/ttc-dev/packages/acs-subsite/www/admin/site-map/package-new.tcl (status 200): 'Problem with Your Input')