Forum OpenACS Q&A: Re: New installation problems

Collapse
Posted by Roger Metcalf on
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')