Re: Release troubles and failing tests

Emmanuel Lécharny Mon, 07 May 2012 23:49:02 -0700

Le 5/8/12 8:05 AM, Selcuk AYA a écrit :

Hi,


On Sun, May 6, 2012 at 7:26 AM, Emmanuel Lécharny<[email protected]>  wrote:

Le 5/6/12 12:07 PM, Emmanuel Lécharny a écrit :

Ok, I think I have found the cursor that was not closed.

Still having the same failure :/ All the cursors are correctly closed. If I
disable the logs (that I use to check the pairing between open and close), I
get a failure, and if I activate the log, then I get no failure.

I will try to run all the server-integ tests with NOT_THEADSAFE to see if it
works better.

I was trying to build the trunk and see if I can chase the issue we
see with the caches. However  I am getting the following error. Note
that I tried cleaning the repository.  Can you let me know if there is
workaround?

Sure there is  : change line 47 in apacheds/pom.xml
<org.apache.directory.shared.version>1.0.0-M12</org.apache.directory.shared.version>

to
<org.apache.directory.shared.version>1.0.0-M12-SNAPSHOT</org.apache.directory.shared.version>

As I ws trying to cut a ApacheDS release, I made it to point on theshared that is currently being voted, 1.0.0-M12. Reverting it to1.0.0-M12-SNAPSHOT will let you refer to your local shared version(shared-1.0.0-M12 has not yet been propagated to the maven repository).

FYI, I have added some logs that dump all the cursor openings andclosings, and I wrote a small matcher that check that all the cursorsare correctly close. When the tests finish correctly, I have no remainigcursor in open state.


Here is the code that does the match :

import static org.junit.Assert.fail;

import java.io.BufferedReader;
import java.io.FileReader;
import java.io.IOException;
import java.io.Reader;
import java.util.HashMap;
import java.util.Map;

import org.junit.Test;

/**
 * Test parsing of a request
 */
public class CursorMatch extends AbstractTest
{

protected Map<String, String> creatings = new HashMap<String,String>();

    protected Map<String, String> closings = new HashMap<String, String>();


    /**
     * Test parsing of a request
     */
    @Test
    public void testCursorMatch()
    {
        try
        {
            BufferedReader reader = new  BufferedReader(

new FileReader( CursorMatch.class.getResource("cursor.txt" ).getFile() ) );


            readLines( reader );
        }
        catch ( Exception e )
        {
            System.out.println( e.getMessage() );
            fail( e.getMessage() );
        }
    }


    protected void readLines( Reader reader ) throws Exception
    {
        String line;
        int nbLine = 1;

        while ( ( line = getLine( reader ) ) != null )
        {
            String[] values = line.split( "@" );

            if ( values.length != 2 )
            {
                throw new Exception( "Bad line " + nbLine );
            }

            if ( values[0].startsWith( "Creating" ) )
            {
                creatings.put( values[1], values[0] );
            }
            else
            {
                closings.put( values[1], values[0] );
            }

            nbLine ++;
        }

        for ( String key : creatings.keySet() )
        {
            if ( closings.containsKey( key ) )
            {
                closings.remove( key );
            }
            else
            {

System.out.println( "Key " + key + " not found (" +creatings.get( key ) );

            }
        }
   }


    protected String getLine( Reader reader ) throws IOException
    {
        return ( ( BufferedReader ) reader ).readLine();
    }
}

What is strange is that server-integ is failing frequenty now that theserver is must faster. If I generate some logs on disk, or on sysout,then the tests are likely to succeed.

I just ran the server-integ tests without log, and it get blocked. Hereis a thread dump :(I ran mvn clean install -Dintegration -rf apacheds/server-integ, sothat you don't have to wait until everything is built to getserver-integ being executed. Of course, you need first to buildeverything, with mvn clean install -DskipTests)


-------------------------------------------------------
 T E S T S
-------------------------------------------------------
Running org.apache.directory.server.replication.ClientServerReplicationIT
Tests run: 5, Failures: 0, Errors: 0, Skipped: 1, Time elapsed: 29.203 sec
Running org.apache.directory.server.suites.StockServerISuite
2012-05-08 08:44:34

Full thread dump Java HotSpot(TM) 64-Bit Server VM (20.6-b01-415 mixedmode):"NioSocketAcceptor-123" prio=5 tid=7fd20cf2a000 nid=0x1132f0000 runnable[1132ef000]

   java.lang.Thread.State: RUNNABLE
    at sun.nio.ch.KQueueArrayWrapper.kevent0(Native Method)
    at sun.nio.ch.KQueueArrayWrapper.poll(KQueueArrayWrapper.java:136)
    at sun.nio.ch.KQueueSelectorImpl.doSelect(KQueueSelectorImpl.java:69)
    at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69)
    - locked <7bb08d898> (a sun.nio.ch.Util$2)
    - locked <7bb08d888> (a java.util.Collections$UnmodifiableSet)
    - locked <7bb08d680> (a sun.nio.ch.KQueueSelectorImpl)
    at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80)
    at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:84)

atorg.apache.mina.transport.socket.nio.NioSocketAcceptor.select(NioSocketAcceptor.java:238)atorg.apache.mina.core.polling.AbstractPollingIoAcceptor$Acceptor.run(AbstractPollingIoAcceptor.java:413)atorg.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:64)atjava.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)atjava.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)

    at java.lang.Thread.run(Thread.java:680)

"pool-62-thread-1" prio=5 tid=7fd20baf1000 nid=0x11503e000 waiting oncondition [11503d000]

   java.lang.Thread.State: WAITING (parking)
    at sun.misc.Unsafe.park(Native Method)

- parking to wait for <7c14a91e0> (ajava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)

    at java.util.concurrent.locks.LockSupport.park(LockSupport.java:156)

atjava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1987)atjava.util.concurrent.ArrayBlockingQueue.take(ArrayBlockingQueue.java:315)atjava.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:947)atjava.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)

    at java.lang.Thread.run(Thread.java:680)

"ExpiringMapExpirer-1" daemon prio=5 tid=7fd20ce02800 nid=0x115290000waiting on condition [11528f000]

   java.lang.Thread.State: TIMED_WAITING (sleeping)
    at java.lang.Thread.sleep(Native Method)
    at org.apache.mina.util.ExpiringMap$Expirer.run(ExpiringMap.java:301)
    at java.lang.Thread.run(Thread.java:680)

"NioSocketAcceptor-25" prio=5 tid=7fd20b2fa000 nid=0x114b2f000 runnable[114b2e000]

   java.lang.Thread.State: RUNNABLE
    at sun.nio.ch.KQueueArrayWrapper.kevent0(Native Method)
    at sun.nio.ch.KQueueArrayWrapper.poll(KQueueArrayWrapper.java:136)
    at sun.nio.ch.KQueueSelectorImpl.doSelect(KQueueSelectorImpl.java:69)
    at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69)
    - locked <7c1646420> (a sun.nio.ch.Util$2)
    - locked <7c1646438> (a java.util.Collections$UnmodifiableSet)
    - locked <7c16533d8> (a sun.nio.ch.KQueueSelectorImpl)
    at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80)
    at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:84)

    at java.lang.Thread.run(Thread.java:680)

"NioSocketAcceptor-24" prio=5 tid=7fd20b699000 nid=0x114a2c000 runnable[114a2b000]

   java.lang.Thread.State: RUNNABLE
    at sun.nio.ch.KQueueArrayWrapper.kevent0(Native Method)
    at sun.nio.ch.KQueueArrayWrapper.poll(KQueueArrayWrapper.java:136)
    at sun.nio.ch.KQueueSelectorImpl.doSelect(KQueueSelectorImpl.java:69)
    at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69)
    - locked <7c140c630> (a sun.nio.ch.Util$2)
    - locked <7c140c648> (a java.util.Collections$UnmodifiableSet)
    - locked <7c1573178> (a sun.nio.ch.KQueueSelectorImpl)
    at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80)
    at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:84)

    at java.lang.Thread.run(Thread.java:680)

"SynchWorkerThread" prio=5 tid=7fd20b691000 nid=0x114929000 waiting oncondition [114928000]

   java.lang.Thread.State: WAITING (parking)
    at sun.misc.Unsafe.park(Native Method)

- parking to wait for <7c14feec0> (ajava.util.concurrent.locks.ReentrantLock$NonfairSync)

    at java.util.concurrent.locks.LockSupport.park(LockSupport.java:156)

atjava.util.concurrent.locks.AbstractQueuedSynchronizer.parkAndCheckInterrupt(AbstractQueuedSynchronizer.java:811)atjava.util.concurrent.locks.AbstractQueuedSynchronizer.acquireQueued(AbstractQueuedSynchronizer.java:842)atjava.util.concurrent.locks.AbstractQueuedSynchronizer.acquire(AbstractQueuedSynchronizer.java:1178)atjava.util.concurrent.locks.ReentrantLock$NonfairSync.lock(ReentrantLock.java:186)atjava.util.concurrent.locks.ReentrantLock.lock(ReentrantLock.java:262)atjdbm.recman.SnapshotRecordManager.commit(SnapshotRecordManager.java:576)atorg.apache.directory.server.core.partition.impl.btree.jdbm.JdbmIndex.sync(JdbmIndex.java:676)- locked <7c1361758> (aorg.apache.directory.server.core.partition.impl.btree.jdbm.JdbmRdnIndex)atorg.apache.directory.server.core.partition.impl.btree.jdbm.JdbmPartition.sync(JdbmPartition.java:242)- locked <7c0621f00> (aorg.apache.directory.server.core.partition.impl.btree.jdbm.JdbmPartition)atorg.apache.directory.server.core.shared.partition.DefaultPartitionNexus.sync(DefaultPartitionNexus.java:418)atorg.apache.directory.server.core.DefaultDirectoryService$SynchWorker.run(DefaultDirectoryService.java:333)

    at java.lang.Thread.run(Thread.java:680)

"kdcReplayCache.data" daemon prio=5 tid=7fd20bb8b000 nid=0x114826000waiting on condition [114825000]

   java.lang.Thread.State: TIMED_WAITING (parking)
    at sun.misc.Unsafe.park(Native Method)

- parking to wait for <7c057d9b8> (ajava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)atjava.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)atjava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2025)

    at java.util.concurrent.DelayQueue.take(DelayQueue.java:164)

atjava.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:609)atjava.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:602)atjava.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:947)atjava.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907)

    at java.lang.Thread.run(Thread.java:680)

"changePwdReplayCache.data" daemon prio=5 tid=7fd20bb8a000nid=0x113941000 waiting on condition [113940000]

   java.lang.Thread.State: TIMED_WAITING (parking)
    at sun.misc.Unsafe.park(Native Method)

- parking to wait for <7c05db2b8> (ajava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)atjava.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)atjava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2025)

    at java.util.concurrent.DelayQueue.take(DelayQueue.java:164)

    at java.lang.Thread.run(Thread.java:680)

"groupCache.data" daemon prio=5 tid=7fd20bb89800 nid=0x1131ed000 waitingon condition [1131ec000]

   java.lang.Thread.State: TIMED_WAITING (parking)
    at sun.misc.Unsafe.park(Native Method)

- parking to wait for <7c057da18> (ajava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)atjava.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)atjava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2025)

    at java.util.concurrent.DelayQueue.take(DelayQueue.java:164)

    at java.lang.Thread.run(Thread.java:680)

"net.sf.ehcache.CacheManager@58c9e870" daemon prio=5 tid=7fd20b884800nid=0x112c7f000 in Object.wait() [112c7e000]

   java.lang.Thread.State: WAITING (on object monitor)
    at java.lang.Object.wait(Native Method)
    - waiting on <7c057da78> (a java.util.TaskQueue)
    at java.lang.Object.wait(Object.java:485)
    at java.util.TimerThread.mainLoop(Timer.java:483)
    - locked <7c057da78> (a java.util.TaskQueue)
    at java.util.TimerThread.run(Timer.java:462)

"Thread-18" daemon prio=5 tid=7fd20bef2000 nid=0x114723000 waiting oncondition [114722000]

   java.lang.Thread.State: WAITING (parking)
    at sun.misc.Unsafe.park(Native Method)

- parking to wait for <7c0361258> (ajava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)

    at java.util.concurrent.locks.LockSupport.park(LockSupport.java:156)

atjava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:1987)atjava.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:399)atorg.apache.directory.ldap.client.api.future.ResponseFuture.get(ResponseFuture.java:108)atorg.apache.directory.ldap.client.api.future.SearchFuture.get(SearchFuture.java:64)atorg.apache.directory.server.ldap.replication.consumer.ReplicationConsumerImpl.doSyncSearch(ReplicationConsumerImpl.java:630)atorg.apache.directory.server.ldap.replication.consumer.ReplicationConsumerImpl.startSync(ReplicationConsumerImpl.java:505)atorg.apache.directory.server.ldap.replication.consumer.ReplicationConsumerImpl.start(ReplicationConsumerImpl.java:548)atorg.apache.directory.server.ldap.LdapServer$2.run(LdapServer.java:660)

    at java.lang.Thread.run(Thread.java:680)

"Thread-17" daemon prio=5 tid=7fd20b0fc000 nid=0x114620000 waiting oncondition [11461f000]

   java.lang.Thread.State: WAITING (parking)
    at sun.misc.Unsafe.park(Native Method)

- parking to wait for <7c035f380> (ajava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)

    at java.util.concurrent.locks.LockSupport.park(LockSupport.java:156)

    at java.lang.Thread.run(Thread.java:680)

"Thread-16" daemon prio=5 tid=7fd20b694800 nid=0x11451d000 waiting oncondition [11451c000]

   java.lang.Thread.State: WAITING (parking)
    at sun.misc.Unsafe.park(Native Method)

- parking to wait for <7c03612b8> (ajava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)

    at java.util.concurrent.locks.LockSupport.park(LockSupport.java:156)

    at java.lang.Thread.run(Thread.java:680)

"Thread-15" daemon prio=5 tid=7fd20bef2800 nid=0x11441a000 waiting oncondition [114419000]

   java.lang.Thread.State: WAITING (parking)
    at sun.misc.Unsafe.park(Native Method)

- parking to wait for <7c035f350> (ajava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)

    at java.util.concurrent.locks.LockSupport.park(LockSupport.java:156)

    at java.lang.Thread.run(Thread.java:680)

"Thread-14" daemon prio=5 tid=7fd20b0fa000 nid=0x114317000 waiting oncondition [114316000]

   java.lang.Thread.State: WAITING (parking)
    at sun.misc.Unsafe.park(Native Method)

- parking to wait for <7c0361360> (ajava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)

    at java.util.concurrent.locks.LockSupport.park(LockSupport.java:156)

    at java.lang.Thread.run(Thread.java:680)

"Thread-13" daemon prio=5 tid=7fd20b0f5000 nid=0x114214000 waiting oncondition [114213000]

   java.lang.Thread.State: WAITING (parking)
    at sun.misc.Unsafe.park(Native Method)

- parking to wait for <7c035f2d8> (ajava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)

    at java.util.concurrent.locks.LockSupport.park(LockSupport.java:156)

    at java.lang.Thread.run(Thread.java:680)

"Thread-12" daemon prio=5 tid=7fd20b214800 nid=0x114111000 waiting oncondition [114110000]

   java.lang.Thread.State: WAITING (parking)
    at sun.misc.Unsafe.park(Native Method)

- parking to wait for <7c03627b8> (ajava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)

    at java.util.concurrent.locks.LockSupport.park(LockSupport.java:156)

    at java.lang.Thread.run(Thread.java:680)

"Thread-11" daemon prio=5 tid=7fd20b216000 nid=0x11400e000 waiting oncondition [11400d000]

   java.lang.Thread.State: WAITING (parking)
    at sun.misc.Unsafe.park(Native Method)

- parking to wait for <7c0370c40> (ajava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)

    at java.util.concurrent.locks.LockSupport.park(LockSupport.java:156)

    at java.lang.Thread.run(Thread.java:680)

"Thread-10" daemon prio=5 tid=7fd20b814800 nid=0x113a44000 waiting oncondition [113a43000]

   java.lang.Thread.State: WAITING (parking)
    at sun.misc.Unsafe.park(Native Method)

- parking to wait for <7c03075b8> (ajava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)

    at java.util.concurrent.locks.LockSupport.park(LockSupport.java:156)

    at java.lang.Thread.run(Thread.java:680)

"Thread-9" daemon prio=5 tid=7fd20b217800 nid=0x11383e000 waiting oncondition [11383d000]

   java.lang.Thread.State: WAITING (parking)
    at sun.misc.Unsafe.park(Native Method)

- parking to wait for <7c0302f20> (ajava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)

    at java.util.concurrent.locks.LockSupport.park(LockSupport.java:156)

    at java.lang.Thread.run(Thread.java:680)

"pool-1-thread-1" prio=5 tid=7fd20b128000 nid=0x113f0b000 waiting oncondition [113f0a000]

   java.lang.Thread.State: WAITING (parking)
    at sun.misc.Unsafe.park(Native Method)

- parking to wait for <7bebee5a0> (ajava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)

    at java.util.concurrent.locks.LockSupport.park(LockSupport.java:156)

    at java.lang.Thread.run(Thread.java:680)

"pool-11-thread-1" prio=5 tid=7fd20bc10800 nid=0x113b47000 waiting oncondition [113b44000]

   java.lang.Thread.State: TIMED_WAITING (sleeping)
    at java.lang.Thread.sleep(Native Method)
    at jdbm.helper.LRUCache.put(LRUCache.java:330)

atjdbm.recman.SnapshotRecordManager.update(SnapshotRecordManager.java:401)

    at jdbm.btree.BPage.remove(BPage.java:605)
    at jdbm.btree.BPage.remove(BPage.java:611)
    at jdbm.btree.BTree.remove(BTree.java:464)

atorg.apache.directory.server.core.partition.impl.btree.jdbm.JdbmTable.remove(JdbmTable.java:741)- locked <7c14e5da8> (aorg.apache.directory.server.core.partition.impl.btree.jdbm.JdbmTable)atorg.apache.directory.server.core.partition.impl.btree.jdbm.JdbmRdnIndex.drop(JdbmRdnIndex.java:156)atorg.apache.directory.server.core.partition.impl.btree.jdbm.JdbmRdnIndex.drop(JdbmRdnIndex.java:49)atorg.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.updateRdnIdx(AbstractBTreePartition.java:807)atorg.apache.directory.server.core.partition.impl.btree.AbstractBTreePartition.add(AbstractBTreePartition.java:644)atorg.apache.directory.server.core.shared.partition.DefaultPartitionNexus.add(DefaultPartitionNexus.java:450)atorg.apache.directory.server.core.api.interceptor.BaseInterceptor$1.add(BaseInterceptor.java:150)atorg.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:410)atorg.apache.directory.server.core.journal.JournalInterceptor.add(JournalInterceptor.java:139)atorg.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:410)atorg.apache.directory.server.core.changelog.ChangeLogInterceptor.add(ChangeLogInterceptor.java:113)atorg.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:410)atorg.apache.directory.server.core.trigger.TriggerInterceptor.add(TriggerInterceptor.java:300)atorg.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:410)atorg.apache.directory.server.core.event.EventInterceptor.add(EventInterceptor.java:172)atorg.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:410)atorg.apache.directory.server.core.subtree.SubentryInterceptor.add(SubentryInterceptor.java:992)atorg.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:410)atorg.apache.directory.server.core.collective.CollectiveAttributeInterceptor.add(CollectiveAttributeInterceptor.java:125)atorg.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:410)atorg.apache.directory.server.core.operational.OperationalAttributeInterceptor.add(OperationalAttributeInterceptor.java:234)atorg.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:410)atorg.apache.directory.server.core.schema.SchemaInterceptor.add(SchemaInterceptor.java:1286)atorg.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:410)atorg.apache.directory.server.core.exception.ExceptionInterceptor.add(ExceptionInterceptor.java:194)atorg.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:410)atorg.apache.directory.server.core.admin.AdministrativePointInterceptor.add(AdministrativePointInterceptor.java:1203)atorg.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:410)atorg.apache.directory.server.core.authz.AciAuthorizationInterceptor.add(AciAuthorizationInterceptor.java:527)atorg.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:410)atorg.apache.directory.server.core.referral.ReferralInterceptor.add(ReferralInterceptor.java:249)atorg.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:410)atorg.apache.directory.server.core.authn.AuthenticationInterceptor.add(AuthenticationInterceptor.java:317)atorg.apache.directory.server.core.api.interceptor.BaseInterceptor.next(BaseInterceptor.java:410)atorg.apache.directory.server.core.normalization.NormalizationInterceptor.add(NormalizationInterceptor.java:125)atorg.apache.directory.server.core.DefaultOperationManager.add(DefaultOperationManager.java:324)atorg.apache.directory.server.core.shared.DefaultCoreSession.add(DefaultCoreSession.java:163)atorg.apache.directory.server.core.shared.DefaultCoreSession.add(DefaultCoreSession.java:140)atorg.apache.directory.server.operations.search.ReferralSearchIT.setupReferrals(ReferralSearchIT.java:155)

    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

atsun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)atsun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)

    at java.lang.reflect.Method.invoke(Method.java:597)

atorg.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:45)atorg.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)atorg.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:42)atorg.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:27)atorg.apache.directory.junit.tools.MultiThreadedMultiInvoker$1$1.run(MultiThreadedMultiInvoker.java:160)atjava.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)atjava.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)

    at java.lang.Thread.run(Thread.java:680)

"Thread-7" daemon prio=5 tid=7fd20bb8d800 nid=0x11373b000 waiting oncondition [11373a000]

   java.lang.Thread.State: WAITING (parking)
    at sun.misc.Unsafe.park(Native Method)

- parking to wait for <7c00237e8> (ajava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)

    at java.util.concurrent.locks.LockSupport.park(LockSupport.java:156)

    at java.lang.Thread.run(Thread.java:680)

"SynchWorkerThread" prio=5 tid=7fd20ba77000 nid=0x112870000 inObject.wait() [11286f000]

   java.lang.Thread.State: TIMED_WAITING (on object monitor)
    at java.lang.Object.wait(Native Method)
    - waiting on <7bedf1958> (a java.lang.Object)

atorg.apache.directory.server.core.DefaultDirectoryService$SynchWorker.run(DefaultDirectoryService.java:323)

    - locked <7bedf1958> (a java.lang.Object)
    at java.lang.Thread.run(Thread.java:680)

"changePwdReplayCache.data" daemon prio=5 tid=7fd20b072000nid=0x11348f000 waiting on condition [11348e000]

   java.lang.Thread.State: TIMED_WAITING (parking)
    at sun.misc.Unsafe.park(Native Method)

- parking to wait for <7bf002370> (ajava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)atjava.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)atjava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2025)

    at java.util.concurrent.DelayQueue.take(DelayQueue.java:164)

    at java.lang.Thread.run(Thread.java:680)

"groupCache.data" daemon prio=5 tid=7fd20b071800 nid=0x11304d000 waitingon condition [11304c000]

   java.lang.Thread.State: TIMED_WAITING (parking)
    at sun.misc.Unsafe.park(Native Method)

- parking to wait for <7bf0023d0> (ajava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)atjava.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)atjava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2025)

    at java.util.concurrent.DelayQueue.take(DelayQueue.java:164)

    at java.lang.Thread.run(Thread.java:680)

"kdcReplayCache.data" daemon prio=5 tid=7fd20b0e4000 nid=0x112f4a000waiting on condition [112f49000]

   java.lang.Thread.State: TIMED_WAITING (parking)
    at sun.misc.Unsafe.park(Native Method)

- parking to wait for <7bf002430> (ajava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)atjava.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)atjava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2025)

    at java.util.concurrent.DelayQueue.take(DelayQueue.java:164)

    at java.lang.Thread.run(Thread.java:680)

"net.sf.ehcache.CacheManager@3979c7cf" daemon prio=5 tid=7fd20b0e1800nid=0x11145d000 in Object.wait() [11145c000]

   java.lang.Thread.State: WAITING (on object monitor)
    at java.lang.Object.wait(Native Method)
    - waiting on <7bf0035b8> (a java.util.TaskQueue)
    at java.lang.Object.wait(Object.java:485)
    at java.util.TimerThread.mainLoop(Timer.java:483)
    - locked <7bf0035b8> (a java.util.TaskQueue)
    at java.util.TimerThread.run(Timer.java:462)

"Thread-4" daemon prio=5 tid=7fd209cd1000 nid=0x112e47000 waiting oncondition [112e46000]

   java.lang.Thread.State: TIMED_WAITING (sleeping)
    at java.lang.Thread.sleep(Native Method)

atorg.apache.directory.server.ldap.replication.provider.SyncReplRequestHandler$1.run(SyncReplRequestHandler.java:972)

    at java.lang.Thread.run(Thread.java:680)

"SynchWorkerThread" prio=5 tid=7fd20ac1c000 nid=0x1129f3000 inObject.wait() [1129f2000]

   java.lang.Thread.State: TIMED_WAITING (on object monitor)
    at java.lang.Object.wait(Native Method)
    - waiting on <7bd8cf210> (a java.lang.Object)

atorg.apache.directory.server.core.DefaultDirectoryService$SynchWorker.run(DefaultDirectoryService.java:323)

    - locked <7bd8cf210> (a java.lang.Object)
    at java.lang.Thread.run(Thread.java:680)

"groupCache.data" daemon prio=5 tid=7fd20aab6800 nid=0x112b7c000 waitingon condition [112b7b000]

   java.lang.Thread.State: TIMED_WAITING (parking)
    at sun.misc.Unsafe.park(Native Method)

- parking to wait for <7bda4dcd8> (ajava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)atjava.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)atjava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2025)

    at java.util.concurrent.DelayQueue.take(DelayQueue.java:164)

    at java.lang.Thread.run(Thread.java:680)

"changePwdReplayCache.data" daemon prio=5 tid=7fd209ac2000nid=0x112316000 waiting on condition [112315000]

   java.lang.Thread.State: TIMED_WAITING (parking)
    at sun.misc.Unsafe.park(Native Method)

- parking to wait for <7bda2ed08> (ajava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)atjava.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)atjava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2025)

    at java.util.concurrent.DelayQueue.take(DelayQueue.java:164)

    at java.lang.Thread.run(Thread.java:680)

"kdcReplayCache.data" daemon prio=5 tid=7fd20aab5800 nid=0x1120e1000waiting on condition [1120e0000]

   java.lang.Thread.State: TIMED_WAITING (parking)
    at sun.misc.Unsafe.park(Native Method)

- parking to wait for <7bd823570> (ajava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)atjava.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:196)atjava.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2025)

    at java.util.concurrent.DelayQueue.take(DelayQueue.java:164)

    at java.lang.Thread.run(Thread.java:680)

"net.sf.ehcache.CacheManager@5f7779e3" daemon prio=5 tid=7fd20ae3b000nid=0x1125e5000 in Object.wait() [1125e4000]

   java.lang.Thread.State: WAITING (on object monitor)
    at java.lang.Object.wait(Native Method)
    - waiting on <7bda2ed68> (a java.util.TaskQueue)
    at java.lang.Object.wait(Object.java:485)
    at java.util.TimerThread.mainLoop(Timer.java:483)
    - locked <7bda2ed68> (a java.util.TaskQueue)
    at java.util.TimerThread.run(Timer.java:462)

"Poller SunPKCS11-Darwin" daemon prio=1 tid=7fd20ae3a000 nid=0x1124e2000waiting on condition [1124e1000]

   java.lang.Thread.State: TIMED_WAITING (sleeping)
    at java.lang.Thread.sleep(Native Method)
    at sun.security.pkcs11.SunPKCS11$TokenPoller.run(SunPKCS11.java:692)
    at java.lang.Thread.run(Thread.java:680)

"Low Memory Detector" daemon prio=5 tid=7fd20a8be000 nid=0x11196f000runnable [00000000]

   java.lang.Thread.State: RUNNABLE

"C2 CompilerThread1" daemon prio=9 tid=7fd20a8bd800 nid=0x11186c000waiting on condition [00000000]

   java.lang.Thread.State: RUNNABLE

"C2 CompilerThread0" daemon prio=9 tid=7fd20a8bc800 nid=0x111769000waiting on condition [00000000]

   java.lang.Thread.State: RUNNABLE

"Signal Dispatcher" daemon prio=9 tid=7fd20a8bc000 nid=0x111666000waiting on condition [00000000]

   java.lang.Thread.State: RUNNABLE

"Surrogate Locker Thread (Concurrent GC)" daemon prio=5 tid=7fd209803000nid=0x111563000 waiting on condition [00000000]

   java.lang.Thread.State: RUNNABLE

"Finalizer" daemon prio=8 tid=7fd20a8a6000 nid=0x11129f000 inObject.wait() [11129e000]

   java.lang.Thread.State: WAITING (on object monitor)
    at java.lang.Object.wait(Native Method)
    - waiting on <7bd7b0860> (a java.lang.ref.ReferenceQueue$Lock)
    at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:118)
    - locked <7bd7b0860> (a java.lang.ref.ReferenceQueue$Lock)
    at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:134)
    at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159)

"Reference Handler" daemon prio=10 tid=7fd20a8a5800 nid=0x11119c000 inObject.wait() [11119b000]

   java.lang.Thread.State: WAITING (on object monitor)
    at java.lang.Object.wait(Native Method)
    - waiting on <7bd7b0878> (a java.lang.ref.Reference$Lock)
    at java.lang.Object.wait(Object.java:485)
    at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:116)
    - locked <7bd7b0878> (a java.lang.ref.Reference$Lock)

"main" prio=5 tid=7fd20a800800 nid=0x1096ba000 waiting on condition[1096b8000]

   java.lang.Thread.State: TIMED_WAITING (sleeping)
    at java.lang.Thread.sleep(Native Method)

atorg.apache.directory.junit.tools.MultiThreadedMultiInvoker$1.evaluate(MultiThreadedMultiInvoker.java:189)

    at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263)

atorg.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:68)atorg.apache.directory.server.core.integ.FrameworkRunner.runChild(FrameworkRunner.java:488)atorg.apache.directory.server.core.integ.FrameworkRunner.runChild(FrameworkRunner.java:58)

    at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231)
    at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60)
    at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229)
    at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50)
    at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222)
    at org.junit.runners.ParentRunner.run(ParentRunner.java:300)

atorg.apache.directory.server.core.integ.FrameworkRunner.run(FrameworkRunner.java:256)

    at org.junit.runners.Suite.runChild(Suite.java:128)

atorg.apache.directory.server.core.integ.FrameworkSuite.runChild(FrameworkSuite.java:212)atorg.apache.directory.server.core.integ.FrameworkSuite.runChild(FrameworkSuite.java:49)

    at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231)
    at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60)
    at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229)
    at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50)
    at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222)
    at org.junit.runners.ParentRunner.run(ParentRunner.java:300)

atorg.apache.directory.server.core.integ.FrameworkSuite.run(FrameworkSuite.java:178)atorg.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:236)atorg.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:134)atorg.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:113)

    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)

atsun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)atsun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)

    at java.lang.reflect.Method.invoke(Method.java:597)

atorg.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:189)atorg.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:165)atorg.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:85)atorg.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:103)atorg.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:74)

"VM Thread" prio=9 tid=7fd20a8a0800 nid=0x111099000 runnable

"Gang worker#0 (Parallel GC Threads)" prio=9 tid=7fd20a802000nid=0x10ca0d000 runnable"Gang worker#1 (Parallel GC Threads)" prio=9 tid=7fd20a802800nid=0x10cb10000 runnable"Concurrent Mark-Sweep GC Thread" prio=9 tid=7fd20a84d000nid=0x110d39000 runnable"VM Periodic Task Thread" prio=10 tid=7fd20a8c7800 nid=0x111a72000waiting on condition"Exception Catcher Thread" prio=10 tid=7fd20a801800 nid=0x109834000runnable

JNI global references: 1133
Heap

par new generation total 19136K, used 16027K [7bae00000, 7bc2c0000,7bd790000)

  eden space 17024K,  85% used [7bae00000, 7bbc46c70, 7bbea0000)
  from space 2112K,  66% used [7bbea0000, 7bc000208, 7bc0b0000)
  to   space 2112K,   0% used [7bc0b0000, 7bc0b0000, 7bc2c0000)

concurrent mark-sweep generation total 178176K, used 130249K[7bd790000, 7c8590000, 7fae00000)concurrent-mark-sweep perm gen total 52740K, used 32381K [7fae00000,7fe181000, 800000000)



--
Regards,
Cordialement,
Emmanuel Lécharny
www.iktek.com

Re: Release troubles and failing tests

Reply via email to