You are viewing a plain text version of this content. The canonical link for it is here.
Posted to log4j-user@logging.apache.org by Sc...@sybase.com on 2001/11/15 20:40:03 UTC

Aliased component names?


I am pulling my hair out trying to figure out why I am getting incorrect
component names when logging with Log4j. The log starts out OK and
then I start to get incorrectly identified component in the log. My basic
design creates a single category as shown:

     AsyncAppender async = new AsyncAppender();
     _category = Category.getInstance( "vp.sybase" );
     _category.getRoot().addAppender( async );

     //   Set up the standard log appender
     ap = new JaguarLogAppender();
     async.addAppender( ap );

     //   Default priority is debug on all components...
     _category.getRoot().setPriority( Priority.DEBUG );

     //   Finish up...
     _category.info("LogService setup complete.");

This is done in the start() method of a Service component
within my Appserver ( Sybase EAServer 4.0 ).
Then all of the objects in our system derive from a base class
that has this in the default constructor:

     public VPObjectImpl()
     {
          _className = getClass().getName();
          // Create instance of the log class.
          _log = new Log(_className);
     }

The newly created Log class caches the class name in an
instance variable and the logging class does the following:

      public void debug (String messageData)
      {
           Category.getInstance(_classname).debug(messageData);
           //_cat.debug(messageData);
      }
      public void info (String messageData)
      {
           Category.getInstance(_classname).info(messageData);
           //_cat.info(messageData);
      }
               etc. .....


Everything starts out OK in the system with components initially
identifying themselves correctly. Then for some reason the components
start reporting a previous components name for some strange reason.

I may need to enable the "internal" log4j debugging info to figure this
one out. If someone could tell me what I need to do to turn that on
I would appreciate it greatly.

Here's the sample output with the initial place where the problem
is seen, you'll notice that I print out the log classes instance variable
just to confirm it is actually correct, and of course it is:
------------------------------------------------------------------------------------------------------------
Nov 15 14:26:18 2001:
Sybase Enterprise Application Server (Enterprise Edition)

Nov 15 14:26:18 2001: Jaguar CTS - Component Transaction Server/Version 4.0.0 (Build 40016)/P/PC Intel/Windows NT 4.0/DEBUG/Aug 30 2001 15:27:16

Confidential property of Sybase, Inc.
(c) Copyright Sybase Inc. 1987,2001
All rights reserved.

Use, duplication, or disclosure by the United States Government
is subject to restrictions as set forth in FAR subparagraphs
52.227-19 (a)-(d) for civilian agency contracts and DFARS 252.227-7013
(c)(1)(ii) for Department of Defense contracts. Sybase reserves all
unpublished rights under the copyright laws of the United States.
Sybase, Inc. 6475 Christie Avenue, Emeryville, CA 94608 USA.
Nov 15 14:26:18 2001:
2 Phase Commit  Option Licensed
Nov 15 14:26:18 2001: CLUSTERING Option Licensed
Nov 15 14:26:18 2001:
High Availability Option Licensed
Nov 15 14:26:18 2001:
Application Integrator for CICS Option Licensed
Nov 15 14:26:20 2001: SRVLIB Message: 16240/10/0: Net-Library routine net_listen(spkay 8081) failed in srv__start_listeners
Network error: status = 23 - Net-Lib protocol driver call to register a listener failed

Nov 15 14:26:22 2001: Listener #  1: Jaguar_tds: Active: yes  Protocol: 'TDS': 'spkay',7878 Security Profile: '*NONE*'
Nov 15 14:26:22 2001: Listener #  2: Jaguar_OpenServer: Active: yes  Protocol: 'TDS': 'spkay',7979 Security Profile: '*NONE*'
Nov 15 14:26:22 2001: Listener #  3: Jaguar_http: Active: yes  Protocol: 'HTTP': 'localhost',8080 Security Profile: '*NONE*'
Nov 15 14:26:22 2001: Listener #  4: Jaguar_https1: Active: no   Protocol: 'HTTPS': 'spkay',8081 Security Profile: 'sybpks_intl'
Nov 15 14:26:22 2001: Listener #  5: Jaguar_https2: Active: yes  Protocol: 'HTTPS': 'localhost',8082 Security Profile: 'sybpks_intl_mutual_auth'
Subject DN: C = US, ST = Some State, L = Some Locality, O = Some Organization, CN = Sample2
Nov 15 14:26:22 2001: Listener #  6: Jaguar_iiop: Active: yes  Protocol: 'IIOP': 'spkay',9000 Security Profile: '*NONE*'
Nov 15 14:26:22 2001: Listener #  7: Jaguar_iiops1: Active: yes  Protocol: 'IIOPS': 'spkay',9001 Security Profile: 'sybpks_intl'
Subject DN: C = US, ST = Some State, L = Some Locality, O = Some Organization, CN = Sample1
Nov 15 14:26:22 2001: Listener #  8: Jaguar_iiops2: Active: yes  Protocol: 'IIOPS': 'localhost',9002 Security Profile: 'sybpks_intl_mutual_auth'
Subject DN: C = US, ST = Some State, L = Some Locality, O = Some Organization, CN = Sample2
Nov 15 14:26:22 2001: Listener #  9: Jaguar_iiop1: Active: yes  Protocol: 'IIOP': 'localhost',9010 Security Profile: '*NONE*'
Nov 15 14:26:22 2001: cm__init(): List of All Connection Caches:
Nov 15 14:26:22 2001: Cache 1: SurfSideCache_ODBC, dba, sql, SurfSide Video DB, 0, 1031f50
Nov 15 14:26:22 2001: Cache 2: SampleCache, dba, sql, Jaguar SVU Sample, 0, 10303f0
Nov 15 14:26:22 2001: Cache 3: EASDemo, dba, sql, EAS Demo DB V3, 0, 10309e0
Nov 15 14:26:22 2001: cm__init(): List of installed XA Resources:
Nov 15 14:26:22 2001: cm__init(): List of Connection Caches:
Nov 15 14:26:22 2001: Cache 1: SurfSideCache_ODBC, dba, sql, SurfSide Video DB, 0, 1031f50
Nov 15 14:26:22 2001: Cache 2: SampleCache, dba, sql, Jaguar SVU Sample, 0, 10303f0
Nov 15 14:26:22 2001: Cache 3: EASDemo, dba, sql, EAS Demo DB V3, 0, 10309e0
Nov 15 14:26:22 2001: Server is running in 1PC Transaction mode.
Nov 15 14:26:22 2001: Starting Jaguar CTS
Nov 15 14:26:22 2001: Server's native codeset is utf8
ORB_init option = 'MemoryBytes' value = '255' from environment
Nov 15 14:26:22 2001: Library: 'libjjdk12.dll' for component type 'java'
Nov 15 14:26:22 2001: Java component debugging enabled
Nov 15 14:26:22 2001: Java JIT Compiler disabled
Nov 15 14:26:24 2001: Java virtual machine initialized: java version 1.3.1
Nov 15 14:26:24 2001: Java virtual machine initialized: Java HotSpot(TM) Server VM (version 1.3.1-b24, interpreted mode)
Nov 15 14:26:25 2001: Repository: E:\Program Files\Sybase\EAServer4.0\Repository
Nov 15 14:26:25 2001: Host IP: 199.93.182.28
Nov 15 14:26:25 2001: NameServiceURL: iiop://0:0
Nov 15 14:26:25 2001: Starting Services...
Nov 15 14:26:25 2001: JaguarClassLoader: Server level JaguarClassLoader initialized
Nov 15 14:26:26 2001: JCM Loading Connection Caches and/or XA Resources ...
Nov 15 14:26:26 2001: JCM Loading Connectors/Resource Adapters ...
Nov 15 14:26:27 2001: Started: Jaguar/Repository
Nov 15 14:26:27 2001: Started: Jaguar/GarbageCollector
Nov 15 14:26:27 2001: Started: Jaguar/JCM
Nov 15 14:26:27 2001: Starting Jaguar Naming Services...
Nov 15 14:26:27 2001: Started: CosNaming/JNameService
Nov 15 14:26:28 2001: Started: JaguarServlet/ServletService
Nov 15 14:26:30 2001: Log: isPublishToMessageServiceTrue false
Nov 15 14:26:30 2001: Log: isLog4jTrue true
Nov 15 14:26:30 2001: log4j:ERROR No appenders could be found for category (vp.sybase.util.LockObject).
Nov 15 14:26:30 2001: log4j:ERROR Please initialize the log4j system properly.
Nov 15 14:26:30 2001: Started: VantagePoint/LogService
Nov 15 14:26:30 2001: Running: services...
Nov 15 14:26:30 2001: Running: CosNaming/JNameService
Nov 15 14:26:30 2001: Running: Jaguar/GarbageCollector
Nov 15 14:26:30 2001: Running: JaguarServlet/ServletService
Nov 15 14:26:30 2001: Running: VantagePoint/LogService
Nov 15 14:26:30 2001: LogServiceBean run(): _running = true
Nov 15 14:26:30 2001: PermissionCache: Authorization cache timeout: 120 minutes
Nov 15 14:26:31 2001: [Thread-4  ] INFO  vp.sybase [] - LogService setup complete.
Nov 15 14:26:38 2001: Stopped: CosNaming/JNameService
Nov 15 14:26:50 2001: Authentication timeout period is 3600 seconds.
 Note: authentication timeout can be disabled.
Nov 15 14:26:50 2001: Authentication lockout period is 600 seconds.
 Note: authentication lockout can be disabled.
Nov 15 14:26:52 2001: [Thread-13 ] DEBUG vp.sybase.user.bl.EJBAuthenticationBean [] - Log4j class for vp.sybase.user.bl.EJBAuthenticationBean Category
 created.
Nov 15 14:26:52 2001: [Thread-13 ] INFO  vp.sybase.user.bl.EJBAuthenticationBean [] - jgalt tries to login
Nov 15 14:26:52 2001: [Thread-13 ] INFO  vp.sybase.user.bl.EJBAuthenticationBean [] - getConnection(): resource=java:comp/env/jdbc/dbCache, succeeded!
Nov 15 14:26:53 2001: [Thread-13 ] DEBUG vp.sybase.user.bl.authparts.DefaultAuthPart [] - Log4j class for vp.sybase.user.bl.authparts.DefaultAuthPart
Category created.
Nov 15 14:26:53 2001: [Thread-13 ] INFO  vp.sybase.user.bl.authparts.DefaultAuthPart [] - Instantiate a new authentication part
Nov 15 14:26:53 2001: [Thread-13 ] DEBUG vp.sybase.util.DBHelper [] - Log4j class for vp.sybase.util.DBHelper Category created.
Nov 15 14:26:53 2001: [Thread-13 ] INFO  vp.sybase.util.DBHelper [] - jgalt has been authenticated!
Nov 15 14:26:54 2001: [Thread-13 ] INFO  vp.sybase.util.DBHelper [] - VPEJB: getHome() lookup of java:comp/env/ejb/LoggedInUser succeeded!!
Nov 15 14:26:54 2001: [Thread-13 ] DEBUG vp.sybase.user.bl.LoggedInUserBean [] - Log4j class for vp.sybase.user.bl.LoggedInUserBean Category created.
Nov 15 14:26:55 2001: [Thread-13 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - getConnection(): resource=java:comp/env/jdbc/dbCache, succeeded!
Nov 15 14:26:55 2001: [Thread-13 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - The session of LoggedInUser 69 has been created
Nov 15 14:26:55 2001: [Thread-13 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - Create the LoggedInUser bean for jgalt
Nov 15 14:26:55 2001: [Thread-13 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - getConnection(): resource=java:comp/env/jdbc/dbCache, succeeded!
Nov 15 14:26:55 2001: [Thread-13 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - jgalt gets sessionHandle 8vLF5Hx991YLwUInXjU8KvAXAPA=
Nov 15 14:26:55 2001: [Thread-13 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - jgalt has successfully been logged in
Nov 15 14:26:55 2001: [Thread-16 ] DEBUG vp.sybase.user.bl.LoggedInUserBean [] - Log4j class for vp.sybase.user.bl.LoggedInUserBean Category created.
Nov 15 14:26:55 2001: [Thread-16 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - getConnection(): resource=java:comp/env/jdbc/dbCache, succeeded!
Nov 15 14:26:55 2001: [Thread-16 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - LoggedInUser with 8vLF5Hx991YLwUInXjU8KvAXAPA= has been found.
Nov 15 14:26:55 2001: [Thread-16 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - Find the user with sessionHandle 8vLF5Hx991YLwUInXjU8KvAXAPA=
Nov 15 14:26:55 2001: [Thread-16 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - getConnection(): resource=java:comp/env/jdbc/dbCache, succeeded!
Nov 15 14:26:55 2001: [Thread-16 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - getConnection(): resource=java:comp/env/jdbc/dbCache, succeeded!
Nov 15 14:26:55 2001: [Thread-16 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - 8vLF5Hx991YLwUInXjU8KvAXAPA= has been removed
Nov 15 14:26:55 2001: [Thread-16 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - User with SH 8vLF5Hx991YLwUInXjU8KvAXAPA= has been logged out
Nov 15 14:26:55 2001: [Thread-11 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - Invalid_user tries to login
Nov 15 14:26:55 2001: [Thread-11 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - getConnection(): resource=java:comp/env/jdbc/dbCache, succeeded!
Nov 15 14:26:55 2001: [Thread-11 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - Invalid_user has NOT been authenticated!!
Nov 15 14:26:55 2001: [Thread-19 ] DEBUG vp.sybase.user.bl.LoggedInUserBean [] - Log4j class for vp.sybase.user.bl.LoggedInUserBean Category created.
Nov 15 14:26:56 2001: [Thread-19 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - getConnection(): resource=java:comp/env/jdbc/dbCache, succeeded!
Nov 15 14:26:56 2001: [Thread-19 ] ERROR vp.sybase.user.bl.LoggedInUserBean [] - LoggedInUser with  could not been found
Nov 15 14:26:56 2001: [Thread-19 ] ERROR vp.sybase.user.bl.LoggedInUserBean [] - Logout: Error in finding loggedInUser.
Nov 15 14:26:59 2001: [Thread-10 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - adodger tries to login
Nov 15 14:26:59 2001: [Thread-10 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - getConnection(): resource=java:comp/env/jdbc/dbCache, succeeded!
Nov 15 14:26:59 2001: [Thread-10 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - adodger has been authenticated!
Nov 15 14:26:59 2001: [Thread-10 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - getConnection(): resource=java:comp/env/jdbc/dbCache, succeeded!
Nov 15 14:26:59 2001: [Thread-10 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - The session of LoggedInUser 4 has been created
Nov 15 14:26:59 2001: [Thread-10 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - Create the LoggedInUser bean for adodger
Nov 15 14:26:59 2001: [Thread-10 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - getConnection(): resource=java:comp/env/jdbc/dbCache, succeeded!
Nov 15 14:26:59 2001: [Thread-10 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - adodger gets sessionHandle aPXmACrt/q+96Xvfru+DaUvnfqQ=
Nov 15 14:26:59 2001: [Thread-10 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - adodger has successfully been logged in
Nov 15 14:26:59 2001: [Thread-32 ] DEBUG vp.sybase.user.bl.LoggedInUserBean [] - Log4j class for vp.sybase.user.bl.LoggedInUserBean Category created.
Nov 15 14:26:59 2001: [Thread-32 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - getConnection(): resource=java:comp/env/jdbc/dbCache, succeeded!
Nov 15 14:26:59 2001: [Thread-32 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - LoggedInUser with aPXmACrt/q+96Xvfru+DaUvnfqQ= has been found.
Nov 15 14:26:59 2001: [Thread-32 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - getConnection(): resource=java:comp/env/jdbc/dbCache, succeeded!
Nov 15 14:27:00 2001: [Thread-33 ] DEBUG vp.sybase.sm.bl.SysManDMBean [] - Log4j class for vp.sybase.sm.bl.SysManDMBean Category created.
Nov 15 14:27:00 2001: [Thread-33 ] DEBUG vp.sybase.misc.UserContext [] - Log4j class for vp.sybase.misc.UserContext Category created.
incorrect->Nov 15 14:27:00 2001: [Thread-33 ] INFO  vp.sybase.misc.UserContext [] - SysManDMExecTask::execute:
incorrect->Nov 15 14:27:00 2001: [Thread-33 ] INFO  vp.sybase.misc.UserContext [] -  Sanity check classname =  vp.sybase.sm.bl.SysManDMBean
incorrect->Nov 15 14:27:00 2001: [Thread-33 ] INFO  vp.sybase.misc.UserContext [] -  Log thinks className is = vp.sybase.sm.bl.SysManDMBean
incorrect->Nov 15 14:27:00 2001: SysManDMExecTask::execute called
incorrect->Nov 15 14:27:00 2001: [Thread-33 ] INFO  vp.sybase.misc.UserContext [] -  BaseClass thinks className is = vp.sybase.sm.bl.SysManDMBean
incorrect->Nov 15 14:27:00 2001: [Thread-33 ] INFO  vp.sybase.misc.UserContext [] - userContext Element:
Nov 15 14:27:00 2001: [Thread-33 ] INFO  vp.sybase.misc.UserContext [] - UserContext: userID=4, strUserID=4,
userIDSessionHandle=aPXmACrt/q+96Xvfru+DaUvnfqQ=
  onBehalfOfUserID=4, strOnBehalfOfUserID=4, userIDLocale=en
incorrect->Nov 15 14:27:00 2001: [Thread-33 ] INFO  vp.sybase.misc.UserContext [] - Task Element:
incorrect->Nov 15 14:27:00 2001: [Thread-33 ] INFO  vp.sybase.misc.UserContext [] - <SysManDMExecTask>

  <UserTask />

</SysManDMExecTask>
incorrect->Nov 15 14:27:00 2001: [Thread-33 ] INFO  vp.sybase.misc.UserContext [] - Creating AccessControl object for authorization check
incorrect->Nov 15 14:27:01 2001: [Thread-33 ] INFO  vp.sybase.misc.UserContext [] - VPEJB: getHome() lookup of java:comp/env/ejb/AccessControl
succeeded!!
incorrect->Nov 15 14:27:01 2001: [Thread-33 ] INFO  vp.sybase.misc.UserContext [] - Checking authorization to proceed
incorrect->Nov 15 14:27:01 2001: [Thread-33 ] INFO  vp.sybase.misc.UserContext [] - User ID = 4
Nov 15 14:27:01 2001: [Thread-33 ] DEBUG vp.sybase.role.bl.AccessControlBean [] - Log4j class for vp.sybase.role.bl.AccessControlBean Category
created.
Nov 15 14:27:01 2001: [Thread-33 ] INFO  vp.sybase.role.bl.AccessControlBean [] - getConnection(): resource=java:comp/env/jdbc/TelcoCache, succeeded!
Nov 15 14:27:01 2001: [Thread-33 ] INFO  vp.sybase.role.bl.AccessControlBean [] - isTaskAllowed( onBehalfOf )
Nov 15 14:27:01 2001: [Thread-33 ] INFO  vp.sybase.role.bl.AccessControlBean [] - CallerUserID = 4, OnBehalfOf = 4, SubSystem = 12, Action = 4
Nov 15 14:27:01 2001: [Thread-33 ] INFO  vp.sybase.role.bl.AccessControlBean [] - Caller Org= 11, Role= 1
Nov 15 14:27:01 2001: [Thread-33 ] INFO  vp.sybase.role.bl.AccessControlBean [] - getConnection(): resource=java:comp/env/jdbc/TelcoCache, succeeded!
Nov 15 14:27:01 2001: [Thread-33 ] INFO  vp.sybase.role.bl.AccessControlBean [] - Cursory validation of the <Context> and <Task> tags
Nov 15 14:27:01 2001: [Thread-33 ] INFO  vp.sybase.role.bl.AccessControlBean [] - UserContext
Nov 15 14:27:01 2001: [Thread-33 ] INFO  vp.sybase.role.bl.AccessControlBean [] - SysManDMExecTask::execute: ... found the <UserContext> tag
Nov 15 14:27:01 2001: [Thread-33 ] INFO  vp.sybase.role.bl.AccessControlBean [] - SysManDMExecTask::dispatch: looping through tasks...
Nov 15 14:27:01 2001: [Thread-33 ] INFO  vp.sybase.role.bl.AccessControlBean [] - SysManDMExecTask::dispatch: retrieving method UserTask from class
Nov 15 14:27:01 2001: [Thread-33 ] INFO  vp.sybase.role.bl.AccessControlBean [] - UserTask: called
Nov 15 14:27:01 2001: <?xml version="1.0" encoding="UTF-8"?>

<VantagePoint>

  <UserContext SessionHandle="aPXmACrt/q+96Xvfru+DaUvnfqQ=" Locale="en" />

  <UserTask />

</VantagePoint>



Nov 15 14:27:01 2001:
Nov 15 14:27:02 2001: [Thread-33 ] INFO  vp.sybase.role.bl.AccessControlBean [] - VPEJB: getHome() lookup of java:comp/env/ejb/BODispatcher
succeeded!!
Nov 15 14:27:02 2001: [Thread-33 ] INFO  vp.sybase.role.bl.AccessControlBean [] - Created BOD Object
Nov 15 14:27:02 2001: [Thread-33 ] DEBUG vp.sybase.bod.BODispatcherBean [] - Log4j class for vp.sybase.bod.BODispatcherBean Category created.
Nov 15 14:27:02 2001: [Thread-33 ] INFO  vp.sybase.bod.BODispatcherBean [] - setSessionContext: Calling lookup
Nov 15 14:27:02 2001: [Thread-33 ] INFO  vp.sybase.bod.BODispatcherBean [] - setSessionContext: Calling new DispatchTable with: vp/tasks.xml
Nov 15 14:27:02 2001: INFORMATION: DispatchTable.buildTable - build a new dispatch table
Nov 15 14:27:03 2001: [Thread-33 ] DEBUG vp.sybase.user.bod.LoginTask [] - Log4j class for vp.sybase.user.bod.LoginTask Category created.
Nov 15 14:27:03 2001: [Thread-33 ] DEBUG vp.sybase.tt.bod.TroubleTicketTask [] - Log4j class for vp.sybase.tt.bod.TroubleTicketTask Category created.
Nov 15 14:27:03 2001: [Thread-33 ] DEBUG vp.sybase.tt.bod.TTStartTask [] - Log4j class for vp.sybase.tt.bod.TTStartTask Category created.
Nov 15 14:27:03 2001: [Thread-33 ] DEBUG vp.sybase.user.bod.AddOrgTask [] - Log4j class for vp.sybase.user.bod.AddOrgTask Category created.
Nov 15 14:27:03 2001: [Thread-33 ] DEBUG vp.sybase.user.bod.AddUserTask [] - Log4j class for vp.sybase.user.bod.AddUserTask Category created.
Nov 15 14:27:03 2001: [Thread-33 ] DEBUG vp.sybase.user.bod.OrganizationTask [] - Log4j class for vp.sybase.user.bod.OrganizationTask Category
created.
Nov 15 14:27:03 2001: [Thread-33 ] DEBUG vp.sybase.user.bod.OrgHierarchyTask [] - Log4j class for vp.sybase.user.bod.OrgHierarchyTask Category
created.
Nov 15 14:27:03 2001: [Thread-33 ] DEBUG vp.sybase.user.bod.SearchUserTask [] - Log4j class for vp.sybase.user.bod.SearchUserTask Category created.
Nov 15 14:27:03 2001: [Thread-33 ] DEBUG vp.sybase.util.bod.GeographyTask [] - Log4j class for vp.sybase.util.bod.GeographyTask Category created.
Nov 15 14:27:03 2001: [Thread-33 ] DEBUG vp.sybase.user.bod.LogoutTask [] - Log4j class for vp.sybase.user.bod.LogoutTask Category created.
Nov 15 14:27:03 2001: [Thread-33 ] DEBUG vp.sybase.cat.bod.CatalogTask [] - Log4j class for vp.sybase.cat.bod.CatalogTask Category created.
Nov 15 14:27:03 2001: [Thread-33 ] DEBUG vp.sybase.cs.bod.CatalogPublisherTask [] - Log4j class for vp.sybase.cs.bod.CatalogPublisherTask Category
created.
Nov 15 14:27:03 2001: [Thread-33 ] DEBUG vp.sybase.bv.bod.BillViewTask [] - Log4j class for vp.sybase.bv.bod.BillViewTask Category created.
Nov 15 14:27:03 2001: [Thread-33 ] DEBUG vp.sybase.user.bod.UserTask [] - Log4j class for vp.sybase.user.bod.UserTask Category created.
Nov 15 14:27:03 2001: [Thread-33 ] DEBUG vp.sybase.user.bod.UpdateUserTask [] - Log4j class for vp.sybase.user.bod.UpdateUserTask Category created.
Nov 15 14:27:03 2001: [Thread-33 ] DEBUG vp.sybase.user.bod.UpdateUserStatusTask [] - Log4j class for vp.sybase.user.bod.UpdateUserStatusTask Category
 created.
Nov 15 14:27:03 2001: [Thread-33 ] DEBUG vp.sybase.user.bod.GetPasswordHintTask [] - Log4j class for vp.sybase.user.bod.GetPasswordHintTask Category
created.
Nov 15 14:27:03 2001: [Thread-33 ] DEBUG vp.sybase.user.bod.UpdateOrgTask [] - Log4j class for vp.sybase.user.bod.UpdateOrgTask Category created.
Nov 15 14:27:03 2001: [Thread-33 ] DEBUG vp.sybase.bos.in.bod.ProcessNotificationTask [] - Log4j class for
vp.sybase.bos.in.bod.ProcessNotificationTask Category created.
Nov 15 14:27:04 2001: [Thread-33 ] DEBUG vp.sybase.oe.bod.ShoppingCartTask [] - Log4j class for vp.sybase.oe.bod.ShoppingCartTask Category created.
Nov 15 14:27:04 2001: [Thread-33 ] DEBUG vp.sybase.oe.bod.SubmitOrderTask [] - Log4j class for vp.sybase.oe.bod.SubmitOrderTask Category created.
Nov 15 14:27:04 2001: [Thread-33 ] DEBUG vp.sybase.sm.bod.SysManDMExecTask [] - Log4j class for vp.sybase.sm.bod.SysManDMExecTask Category created.
Nov 15 14:27:04 2001: [Thread-33 ] DEBUG vp.sybase.sm.bod.SysManEnvExecTask [] - Log4j class for vp.sybase.sm.bod.SysManEnvExecTask Category created.
Nov 15 14:27:04 2001: [Thread-33 ] DEBUG vp.sybase.sm.bod.SysManMonExecTask [] - Log4j class for vp.sybase.sm.bod.SysManMonExecTask Category created.
Nov 15 14:27:04 2001: [Thread-33 ] DEBUG vp.sybase.mc.bod.MessageCenterTask [] - Log4j class for vp.sybase.mc.bod.MessageCenterTask Category created.
Nov 15 14:27:04 2001: [Thread-33 ] DEBUG vp.sybase.cp.bod.PortfolioTask [] - Log4j class for vp.sybase.cp.bod.PortfolioTask Category created.
Nov 15 14:27:04 2001: [Thread-33 ] DEBUG vp.sybase.bbv.bod.BasicBillViewTask [] - Log4j class for vp.sybase.bbv.bod.BasicBillViewTask Category
created.
Nov 15 14:27:04 2001: [Thread-33 ] DEBUG vp.sybase.bbv.bod.BasicBillViewTask [] - Log4j class for vp.sybase.bbv.bod.BasicBillViewTask Category
created.
Nov 15 14:27:04 2001: [Thread-33 ] INFO  vp.sybase.bbv.bod.BasicBillViewTask [] - setSessionContext: Leaving
Nov 15 14:27:04 2001: [Thread-33 ] INFO  vp.sybase.bbv.bod.BasicBillViewTask [] -

--------------------
dispatch:
<?xml version="1.0" encoding="UTF-8"?>

<VantagePoint>

  <UserContext SessionHandle="aPXmACrt/q+96Xvfru+DaUvnfqQ=" Locale="en" />

  <UserTask />

</VantagePoint>



Nov 15 14:27:04 2001: [Thread-33 ] INFO  vp.sybase.bbv.bod.BasicBillViewTask [] - dispatch: looking for <UserContext> tag...
Nov 15 14:27:04 2001: [Thread-33 ] INFO  vp.sybase.bbv.bod.BasicBillViewTask [] - dispatch: ... found the <UserContext> tag
Nov 15 14:27:04 2001: [Thread-33 ] INFO  vp.sybase.bbv.bod.BasicBillViewTask [] - VPEJB: getHome() lookup of java:comp/env/ejb/LoggedInUser
succeeded!!
Nov 15 14:27:04 2001: [Thread-33 ] DEBUG vp.sybase.user.bl.LoggedInUserBean [] - Log4j class for vp.sybase.user.bl.LoggedInUserBean Category created.
Nov 15 14:27:04 2001: [Thread-33 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - getConnection(): resource=java:comp/env/jdbc/dbCache, succeeded!
Nov 15 14:27:04 2001: [Thread-33 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - LoggedInUser with aPXmACrt/q+96Xvfru+DaUvnfqQ= has been found.
Nov 15 14:27:04 2001: [Thread-33 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - getConnection(): resource=java:comp/env/jdbc/dbCache, succeeded!
Nov 15 14:27:04 2001: [Thread-33 ] DEBUG vp.sybase.misc.UserContext [] - Log4j class for vp.sybase.misc.UserContext Category created.
Nov 15 14:27:04 2001: [Thread-33 ] ERROR vp.sybase.misc.UserContext [] - dispatch: No tasks in BOD request, subsystem "UserTask".
Nov 15 14:27:04 2001: [Thread-33 ] INFO  vp.sybase.misc.UserContext [] - dispatch: returning...
<?xml version="1.0" encoding="UTF-8"?>

<VP />



Nov 15 14:27:04 2001: <?xml version="1.0" encoding="UTF-8"?>

<VP />



Nov 15 14:27:04 2001:
Nov 15 14:27:04 2001: [Thread-33 ] INFO  vp.sybase.misc.UserContext [] - SysManDMExecTask::execute: returning
Nov 15 14:27:05 2001: [Thread-38 ] INFO  vp.sybase.misc.UserContext [] - adodger tries to login
Nov 15 14:27:05 2001: [Thread-38 ] INFO  vp.sybase.misc.UserContext [] - getConnection(): resource=java:comp/env/jdbc/dbCache, succeeded!
Nov 15 14:27:05 2001: [Thread-38 ] INFO  vp.sybase.misc.UserContext [] - adodger has been authenticated!
Nov 15 14:27:05 2001: [Thread-38 ] DEBUG vp.sybase.user.bl.LoggedInUserBean [] - Log4j class for vp.sybase.user.bl.LoggedInUserBean Category created.
Nov 15 14:27:05 2001: [Thread-38 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - getConnection(): resource=java:comp/env/jdbc/dbCache, succeeded!
Nov 15 14:27:05 2001: [Thread-38 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - The session of LoggedInUser 4 has been created
Nov 15 14:27:05 2001: [Thread-38 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - Create the LoggedInUser bean for adodger
Nov 15 14:27:05 2001: [Thread-38 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - getConnection(): resource=java:comp/env/jdbc/dbCache, succeeded!
Nov 15 14:27:05 2001: [Thread-38 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - adodger gets sessionHandle V2mCrPfMPm5mb54otaZbEOD+nGc=
Nov 15 14:27:05 2001: [Thread-38 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - adodger has successfully been logged in
Nov 15 14:27:05 2001: [Thread-40 ] DEBUG vp.sybase.user.bl.LoggedInUserBean [] - Log4j class for vp.sybase.user.bl.LoggedInUserBean Category created.
Nov 15 14:27:05 2001: [Thread-40 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - getConnection(): resource=java:comp/env/jdbc/dbCache, succeeded!
Nov 15 14:27:05 2001: [Thread-40 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - LoggedInUser with V2mCrPfMPm5mb54otaZbEOD+nGc= has been found.
Nov 15 14:27:05 2001: [Thread-40 ] INFO  vp.sybase.user.bl.LoggedInUserBean [] - getConnection(): resource=java:comp/env/jdbc/dbCache, succeeded!
Nov 15 14:27:05 2001: [Thread-9  ] DEBUG vp.sybase.misc.UserContext [] - Log4j class for vp.sybase.misc.UserContext Category created.
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] - SysManDMExecTask::execute:
Nov 15 14:27:05 2001: SysManDMExecTask::execute called
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] -  Sanity check classname =  vp.sybase.sm.bl.SysManDMBean
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] -  Log thinks className is = vp.sybase.sm.bl.SysManDMBean
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] -  BaseClass thinks className is = vp.sybase.sm.bl.SysManDMBean
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] - userContext Element:
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] - UserContext: userID=4, strUserID=4,
userIDSessionHandle=V2mCrPfMPm5mb54otaZbEOD+nGc=
  onBehalfOfUserID=4, strOnBehalfOfUserID=4, userIDLocale=en
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] - Task Element:
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] - <SysManDMExecTask>

  <ViewRoleTask RoleID="4" />

</SysManDMExecTask>
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] - Creating AccessControl object for authorization check
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] - VPEJB: getHome() lookup of java:comp/env/ejb/AccessControl succeeded!!
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] - Checking authorization to proceed
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] - User ID = 4
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] - getConnection(): resource=java:comp/env/jdbc/TelcoCache, succeeded!
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] - isTaskAllowed( onBehalfOf )
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] - CallerUserID = 4, OnBehalfOf = 4, SubSystem = 12, Action = 4
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] - Caller Org= 11, Role= 1
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] - getConnection(): resource=java:comp/env/jdbc/TelcoCache, succeeded!
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] - Cursory validation of the <Context> and <Task> tags
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] - UserContext
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] - SysManDMExecTask::execute: ... found the <UserContext> tag
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] - SysManDMExecTask::dispatch: looping through tasks...
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] - SysManDMExecTask::dispatch: retrieving method ViewRoleTask from class
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] - viewRoleTask() entered
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] - viewRoleTask(): calling accessControl.viewRole()
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] - viewRole() entered
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] - getConnection(): resource=java:comp/env/jdbc/TelcoCache, succeeded!
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] - getConnection(): resource=java:comp/env/jdbc/TelcoCache, succeeded!
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] - viewRoleTask(): creating response Doc
Nov 15 14:27:05 2001: [Thread-9  ] INFO  vp.sybase.misc.UserContext [] - SysManDMExecTask::execute: returning
Nov 15 14:27:06 2001: [Thread-42 ] INFO  vp.sybase.misc.UserContext [] - adodger tries to login
Nov 15 14:27:06 2001: [Thread-42 ] INFO  vp.sybase.misc.UserContext [] - getConnection(): resource=java:comp/env/jdbc/dbCache, succeeded!
Nov 15 14:27:06 2001: [Thread-42 ] INFO  vp.sybase.misc.UserContext [] - adodger has been authenticated!



-Scott



--
To unsubscribe, e-mail:   <ma...@jakarta.apache.org>
For additional commands, e-mail: <ma...@jakarta.apache.org>