Forum OpenACS Q&A: New installation problems

Collapse
1: New installation problems
Posted by Roger Metcalf on 03/22/03 02:14 AM
I just grabbed oacs-4-6, installed it, logged on as admin, and proceed to my first act -- create a new subsite.  I created the node, then proceeded to mount ACS-Subsite.  This fails! with (what else):

  Database operation "0or1row" failed (exception NSDB,
  "Query was not a statement returning rows.")

Error log shows

  21/Mar/2003:20:12:33][7837.6151][-conn3-] Error: Ns_PgExec: result status: 7
  message: ERROR:  -20000: No group_id found for package 291 (TTC)

I have a new site_nodes row.

What's going on?  Thanks

Collapse
2: Re: New installation problems (response to 1)
Posted by Roger Metcalf on 03/22/03 02:34 AM
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')
Collapse
3: Re: New installation problems (response to 1)
Posted by Don Baccus on 03/22/03 04:05 PM
APM semantics have changed and broke subsite.  I thought I fixed that, had it working here at least, maybe I screwed up my commit or perhaps I broke it later?

I'll look into this today, thanks for the head's up.

Collapse
4: Re: New installation problems (response to 1)
Posted by Don Baccus on 03/23/03 03:16 AM
This should be fixed, sorry for the inconvenience.  I've tested here and it's working, I forgot to test after making a one-line change, essentially (and I truly forgot, I always test my changes but have scrambling to get a bunch of stuff fixed by tonight's code freeze so things have been a bit frenetic)
Collapse
5: Re: New installation problems (response to 1)
Posted by Roger Metcalf on 03/26/03 07:30 PM
I grabbed oacs-4-6 about 5 hours ago, it installed fine. I installed a bunch of packages and everything seemed fine, packages got installed and mounted, until the very end. Bugtracker was the last package installed, then: 
Package enabled.

Mounted an instance of the package at /bug-tracker 

HTTP/1.0 500 Internal Server Error Content-Type: text/html; charset=iso-8859-1 MIME-Version: 1.0 Date: Wed, 26 Mar 2003 18:31:13 GMT Server: AOLserver/3.3.1+ad13 Content-Length: 547 Connection: close 
Server Error
The requested URL cannot be accessed due to a system error on this server.
The server log shows: 
[26/Mar/2003:13:31:11][10101.3076][-conn0-] Notice: Invoking callback after-instantiate with command bug_tracker::install::package_instantiate  -package_id 723
NOTICE:  identifier "content_folder__register_content_type" will be truncated to "content_folder__register_conten"
[26/Mar/2003:13:31:12][10101.3076][-conn0-] Notice: callback_name = bug-tracker.FormatLogTitle
[26/Mar/2003:13:31:12][10101.3076][-conn0-] Notice: callback_name = bug-tracker.BugNotificationInfo
[26/Mar/2003:13:31:13][10101.1024][-main-] Notice: tclinit: re-initalizing tcl
[26/Mar/2003:13:31:13][10101.1024][-main-] Notice: tcl: generating interp init script
[26/Mar/2003:13:31:13][10101.3076][-conn0-] Notice: RP (91446.724 ms): error in rp_handler: serving POST /acs-admin/apm/packages-install-4 
	ad_url "/acs-admin/apm/packages-install-4" maps to file "/web/ttc-dev3/packages/acs-admin/www/apm/packages-install-4.tcl"
errmsg is no such array: ds_properties
[26/Mar/2003:13:31:13][10101.3076][-conn0-] Error: no such array: ds_properties
no such array: ds_properties
    while executing
"nsv_get ds_properties user_switching_enabled_p"
    (procedure "ds_user_switching_enabled_p" line 2)
    invoked from within
"ds_user_switching_enabled_p"
    (procedure "ds_link" line 3)
    invoked from within
"ds_link"
    (procedure "ad_footer" line 19)
    invoked from within
"ad_footer"
    (procedure "rp_report_error" line 30)
    invoked from within
"rp_report_error"
    (procedure "rp_handler" line 121)
    invoked from within
"rp_handler"
What did I miss? Thanks!