Skip Headers
Oracle® Fusion Middleware Troubleshooting Guide for Oracle Directory Server Enterprise Edition
11g Release 1 (11.1.1.7.0)

Part Number E28966-01
Go to Documentation Home
Home
Go to Table of Contents
Contents
Go to Index
Index
Go to Feedback page
Contact Us

Go to previous page
Previous
Go to next page
Next
PDF · Mobi · ePub

4 Troubleshooting Directory Proxy Server

This chapter describes how to troubleshoot problem you encounter with Directory Proxy Server. It contains the following sections:

4.1 Collecting Generic Directory Proxy Server Data

No matter the type of problem you are encountering, there is a minimum set of data that needs to be collected and, if necessary, provided to Sun Support.

4.1.1 Collecting Version Information for Directory Proxy Server

The following sections describe how to collect configuration information on current and previous versions of Directory Proxy Server.

You can collect the Directory Proxy Server version information using any of the following ways:

  • Use the $dpadm -V command to get the detailed information about the Directory Proxy Server version. It displays the output similar to the following output:

    [dpadm]
    dpadm               : 7.0                  B2009.0219.2158 NAT
    
    Copyright 2009 Sun Microsystems, Inc. All Rights Reserved.
    SUN PROPRIETARY/CONFIDENTIAL.
    Use is subject to license terms.
    
    
    [DPS]
    Sun Microsystems, Inc.
    Sun-Java(tm)-System-Directory-Proxy-Server/7.0 B2009.0219.2146
    
  • The version information is available in the instance-dir/logs/error file. For example, the error log displays the version information as follows:

    [31/Mar/2009:18:45:34 +0530] - STARTUP    - INFO  - \
    Sun-Directory-Proxy-Server/7.0 B2009.0219.2146 started \
    on host server1 in directory /local/dps.3333
    

4.1.2 Running the dpadm Command in Verbose Mode

Running the dpadm command in verbose mode will provide information to help troubleshoot problems that occur during instance creation or deletion, data backup, and so on. Run the dpadm is verbose mode as follows:

# dpadm -v

4.1.3 Collecting Directory Proxy Server Configuration Information

Collect the Directory Proxy Server configuration information. This information is available in the instance-dir/logs/errors file. For example, the error log displays the configuration information as follows:

user@server1 local]$ more dps.3333/logs/errors
[31/Mar/2009:18:45:33 +0530] - STARTUP    - INFO  - \
Global log level INFO (from config)
[31/Mar/2009:18:45:33 +0530] - STARTUP    - INFO  - \
Logging Service configured
[31/Mar/2009:18:45:33 +0530] - STARTUP    - INFO  - \
Java Version: 1.5.0_12 (Java Home: /usr/jdk/instances/jdk1.5.0/jre)
[31/Mar/2009:18:45:33 +0530] - STARTUP    - INFO  - \
Java(TM) 2 Runtime Environment, Standard Edition (build 1.5.0_12-b04)
[31/Mar/2009:18:45:33 +0530] - STARTUP    - INFO  - \
Java HotSpot(TM) 64-Bit Server VM (build 1.5.0_12-b04, mixed mode)
[31/Mar/2009:18:45:33 +0530] - STARTUP    - INFO  - \
Java Heap Space: Total Memory (-Xms) = 241MB, Max Memory (-Xmx) = 241MB
[31/Mar/2009:18:45:33 +0530] - STARTUP    - INFO  - \
Operating System: SunOS/sparcv9 5.9
[31/Mar/2009:18:45:33 +0530] - STARTUP    - INFO  - \
SSL initialization succeeded.
[31/Mar/2009:18:45:33 +0530] - CONFIG     - WARN  - \
Attribute certMappingDataViewPolicy in entry \
cn=LDAPS Listener,cn=Client Listeners,cn=config missing. Using ALL_DATA_VIEW
[31/Mar/2009:18:45:33 +0530] - STARTUP    - INFO  - \
Creating 50 worker threads.
[31/Mar/2009:18:45:34 +0530] - BACKEND    - WARN  - \
Can't retrieve LDAP schema (LDAP error code: 32) \
No data view were found to process the search request.
[31/Mar/2009:18:45:34 +0530] - STARTUP    - INFO  - \

4.1.4 Collecting Directory Proxy Server Log Information

Collect the Directory Proxy Server logs. By default, the logs are stored in the following directory:

instance-path/logs

If you are providing this information to Sun Support, you should also include the generic Directory Server data from the various Directory Servers involved. This generic data includes the Directory Server version and the Directory Server access, error, and audit logs. For more information about collecting the Directory Server generic information, see Collecting Generic Data.

Include generic information about any other backend servers you may be using, such as JDBC backhands, a SQL database, or an Oracle database.

4.2 Troubleshooting Problems With the Directory Proxy Server Process

This section describes procedures for the following:

4.2.1 Overview of Process Troubleshooting Tools

Some tools are provided with Solaris and Java which may help you troubleshoot process issues. The following sections provide an overview of some of the most useful tools

4.2.1.1 Using Java Tools With Directory Proxy Server 11g Release 1 (11.1.1.6.0)

As Directory Proxy Server 11g Release 1 (11.1.1.6.0) is a pure Java application, you can use the Java tools that are delivered with the JDK 1.5 to help troubleshoot problems. These tools include the following:

  • jstack. This tool provides information about the Directory Proxy Server thread stack.

  • jmap. This tool provides information about memory. For example, running jmap —histo PID prints a histogram of the heap.

  • jinfo. This tool provides you with information about the JVM environment.

  • jstat. This tool displays performance statistics for a JVM.

The JVM also includes a graphical tool for monitoring the Java virtual machine called the Java Monitoring and Management Console (JConsole) tool. This tool uses the Java virtual machine to provide information on performance and resource consumption of applications running on the Java platform using Java Management Extension (JMX) technology. JConsole can be used to observe information about an application running on the Java platform. The JConsole provides information and charts about memory use, thread use, class loading, and JVM parameters

On Unix platforms, if the kill -QUIT process-id command is used to get thread dump and it does not work, use jstack.

4.2.1.2 Using Solaris Tools With Directory Proxy Server

Solaris includes a collection of process tools to help you collect more information about process problems, such as a hung process, crashed process, or memory usage problems. These tools include the following:

  • pmap — shows the process map, which includes a list of virtual addresses, where the dynamic libraries are loaded, and where the variables are declared.

  • pstack — shows the process stack. For each thread in the process, it describes the exact stack of instruction the thread was executing at the moment when the process died or when the pstack command was executed.

  • pfiles— reports information about all open files in each process.

  • pldd — list the dynamic libraries linked into each process.

4.2.2 Troubleshooting a Hung or Unresponsive Directory Proxy Server Process

This section describes how to troubleshoot a unresponsive or hung Directory Proxy Server process. A totally unresponsive process is called a hang. The remainder of this section describes how to collect and analyze data about a hang.

4.2.2.1 Collecting Data About a Directory Proxy Server 11g Release 1 (11.1.1.6.0) Hang on Solaris

The jstat tool tells you the amount of CPU being used for each thread. If you collect a thread stack using the jstack utility at the same time you run the jstat tool, you can then use the jstack output to see what the thread was doing when it had trouble. If you run the jstack and jstat tools simultaneously several times, you can see over time if the same thread was causing the problem and if it was encountering the problem during the same function call.

To get the process ID of the running Directory Proxy Server, use the jps command. For example, the command is run as follows on Solaris:

# jps
8393 DistributionServerMain
2115 ContainerPrivate
21535 startup.jar
16672 Jps
13953 swupna.jar

The following script automates the process of running these tools:

cat scpTools
#!/bin/sh  

i=0 
while [ "$i" -lt "10" ] 
do
         echo "$i\n"
         date=`date "+%y%m%d:%H%M%S"`
         prstat -L -p $1 0 1> /tmp/prstat.$date
         pstack $1> /tmp/pstack.$date
         i=`expr $i + 1`;
         sleep 1 
done  

The value 10 in the [ "$i" -lt "10" ] line can be increased or decreased to suit the time during which the problem you are troubleshooting occurs. This adjustment allows to you collect a full set of process data to help troubleshoot the issue. Thus enabling a full process data set to be captured around the issue.

Collect usage information as follows:

# ./scpTools DPS-PID

The DPS-PID field specifies the PID of the unresponsive process. The Directory Proxy Server PID contains the line DistributionServerMain.

On Solaris and other UNIX platforms, show system calls that occur during the crash using the truss command as follows:

truss -o /tmp/trace.txt -ealf -rall -wall -vall -p 21362

The value 21362 corresponds to the PID of the unresponsive process.

4.2.3 Troubleshooting Directory Proxy Server for Refused Connections

With the use of the following diagram, this section describes how operations are processed within the server and which resources are being involved in such processing. The resource usage can be dumped to the error log file by sending a USR2 signal to Directory Proxy Server process.

Description of working_threads.png follows
Description of the illustration working_threads.png

The Clientlistener detects any new incoming connections from the clients and stores them in a buffer of pending connections. From time to time, the ConnectionHandler fetches all the pending connection and put them in the list of connections to process (a Java Selector). The following resource dump excerpt shows some figures around incoming connections:

0.0.0.0:2389 useSSL:false

 Thread[Connection Handler 0 for Listener Thread 0.0.0.0:2389,5,main]
 ConnectionHandler pending connections     = 0
 ConnectionHandler pending connections 2   = 0
 ConnectionHandler connections in selector = 1
 Thread[Connection Handler 1 for Listener Thread 0.0.0.0:2389,5,main]
 ConnectionHandler pending connections     = 0
 ConnectionHandler pending connections 2   = 0
 ConnectionHandler connections in selector = 0

By default, Directory Proxy Server has two client listeners, one for normal connection and one for secure connection, and each client listener has two connection handlers.

The ConnectionHandler reads bytes in the file descriptor and puts them in the WorkQueue after getting a full LDAP operation. The operations in the queue are retrieved by the WorkerThreads for processing. At any time, the WorkQueue keeps the following information available to the resource dumper:

WorkQueue Norm inQ        = 0        number of operations in the Q
 WorkQueue Norm peak       = 1        the peak of operations in the Q
 WorkQueue Norm totalIn    = 1875     the total # of operations put by 
                                      the connection handlers
 WorkQueue Norm totalOut   = 1875     the total # of operations get by the workers
 WorkQueue High inQ        = 0        -- same but foe the "high priority" Q
 WorkQueue High peak       = 0        -- same but foe the "high priority" Q
 WorkQueue High totalIn    = 0        -- same but foe the "high priority" Q
 WorkQueue High totalOut   = 0        -- same but foe the "high priority" Q
 WorkQueue abandonRequests = 0        the number of abandon requests
 WorkQueue abandonSuccess  = 0        the number of succeeded abandons

When the WorkQueue is empty, the WorkerThreads are idle. As soon as a WorkerThread has got an operation from the WorkQueue it becomes busy. The resource dumper provides the state of the WorkerThreads:

WorkerThread: idle = 49    -> all the WorkerThreads are idle but 1
 WorkerThread: busy = 1

In the first step of processing, the WorkerThread gets a list of data views where the operation can be routed to. This step is not described here. Then each elected data view goes through a data source pool to get an LDAP server. The choice of the LDAP server is done by the Load Balancing algorithm. For example, if the Proportional load balancing was in use then the statistics would look like the following:

Data Source Pool pool1
 pool1 - ProportionalLB - total connections - Bind     (provided=0 refused=0)
 pool1 - ProportionalLB - total connections - Add      (provided=0 refused=0)
 pool1 - ProportionalLB - total connections - Search   (provided=0 refused=0)
 pool1 - ProportionalLB - total connections - Compare  (provided=0 refused=0)
 pool1 - ProportionalLB - total connections - Delete   (provided=0 refused=0)
 pool1 - ProportionalLB - total connections - Modify   (provided=0 refused=0)
 pool1 - ProportionalLB - total connections - ModifyDN (provided=0 refused=0)
 pool1 - ProportionalLB - Connections per server for Bind
 pool1 - ProportionalLB - ds1 (provided=0 refused=0)
 pool1 - ProportionalLB - Connections per server for Add
 pool1 - ProportionalLB - ds1 (provided=0 refused=0)
 pool1 - ProportionalLB - Connections per server for Search
 pool1 - ProportionalLB - ds1 (provided=0 refused=0)
 pool1 - ProportionalLB - Connections per server for Compare
 pool1 - ProportionalLB - ds1 (provided=0 refused=0)
 pool1 - ProportionalLB - Connections per server for Delete
 pool1 - ProportionalLB - ds1 (provided=0 refused=0)
 pool1 - ProportionalLB - Connections per server for Modify
 pool1 - ProportionalLB - ds1 (provided=0 refused=0)
 pool1 - ProportionalLB - Connections per server for ModifyDN
 pool1 - ProportionalLB - ds1 (provided=0 refused=0)

The chosen LDAP server is requested to provide a connection to the remote backend. The connections to remote backends are managed through a two pools of connections (ConnectionPool). One pool for the normal connections and another for the secure connections, for example. If Directory Proxy Server is configured to have only secured connections to remote backends then the second pool is not used and the first pool contains the secured connections. Each pool contains connections dedicated to BIND operations, READ operations, and WRITE operations. For each of these sets, the resource dumper reports the current number of connections in the pool and the number of the connections available. The number of connections can be increased when needed but cannot exceed the maximum number of connections, that is, 1024 by default.

BackendConnectionPool [woz:8389/:pool1-DS1] BIND  (max=1024 cur=10 avail=10)
 BackendConnectionPool [woz:8389/:pool1-DS1] READ  (max=1024 cur=10 avail=10)
 BackendConnectionPool [woz:8389/:pool1-DS1] WRITE (max=1024 cur=10 avail=10)
 BackendConnectionPool [woz:8389/:pool1-DS1] Bound connections = 0
 BackendConnectionPool [woz:8389/:pool2-DS1] BIND  (max=1024 cur=0 avail=0)
 BackendConnectionPool [woz:8389/:pool2-DS1] READ  (max=1024 cur=0 avail=0)
 BackendConnectionPool [woz:8389/:pool2-DS1] WRITE (max=1024 cur=0 avail=0)
 BackendConnectionPool [woz:8389/:pool2-DS1] Bound connections = 0

The LDAP server keeps some statistics around the usage of the pools.

bindConnectionsRequested        = 0
 bindConnectionsProvided         = 0
 bindConnectionsRefused          = 0
 bindConnectionWaitsRequired     = 0
 bindConnectionsReturnedValid    = 0
 bindConnectionsReturnedInvalid  = 0
 readConnectionsRequested        = 0
 readConnectionsProvided         = 0
 readConnectionsRefused          = 0
 readConnectionWaitsRequired     = 0
 readConnectionsReturnedValid    = 0
 readConnectionsReturnedInvalid  = 0
 writeConnectionsRequested       = 0
 writeConnectionsProvided        = 0
 writeConnectionsRefused         = 0
 writeConnectionWaitsRequired    = 0
 writeConnectionsReturnedValid   = 0
 writeConnectionsReturnedInvalid = 0

At any time, we have the requested number of connections as requested = provided + refused. Sometimes, the WorkerThread has to wait a bit for a connection to be available. The WorkerThread after completing its job, returns the connection to the pool. If the connection is no more valid, then the connection is returned as invalid and cannot be reused.

These figures around connections to backend can help in the server resource tuning. For example:

totalReadConnections: 1024
availableReadConnections: 0
readConnectionsRequested: 2121
readConnectionsProvided: 1612
readConnectionsRefused: 509
readConnectionWaitsRequired: 1019
readConnectionsReturnedValid: 1612
readConnectionsReturnedInvalid: 0

After analyzing the data provided, the following is concluded:

  • There are no more connections available in the pool, and the pool has reached its maximum size, that is, 1024 connections.

  • There are 2121 requests and only 1612 connections are provided, which is bad for scalability.

  • The worker threads had to wait 1019 times for a connection to be available, which is bad for performance.

  • Any refused connection will end with a SERVER_ERROR returned to the client.

To avoid the refused connections, raise the maximum number of connections allowed in a pool to avoid the available connections to be exhausted. If this cannot be done, for example, the server has not enough file descriptors then reduce the number of WorkerThreads using the following command:

$ dpconf set-server-prop -e -h host -p port number-of-worker-threads:number

This command sets the numWorkerThreads attribute in cn=config in the conf.ldif file.

The client will not receive SERVER_ERROR status code anymore, at the expense of response time though.

4.3 Troubleshooting Directory Proxy Server Using Data Under cn=monitor

The data under cn=monitor DIT helps in identifying and fixing various underlying problems. The cn=monitor provides information to find various problems such as performance and usage, LDAP operations or services for a Directory Proxy Server instance, remote services, connections, load balancing, JVM, connection handler thread, work queue, and various other threads.

To understand the layout of cn=monitor and description of each entry under it, refer to Monitoring Directory Proxy Server in Reference for Oracle Directory Server Enterprise Edition.