Return-Path: X-Original-To: apmail-db-derby-dev-archive@www.apache.org Delivered-To: apmail-db-derby-dev-archive@www.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id 548DC10D70 for ; Fri, 20 Dec 2013 01:26:07 +0000 (UTC) Received: (qmail 53283 invoked by uid 500); 20 Dec 2013 01:26:07 -0000 Delivered-To: apmail-db-derby-dev-archive@db.apache.org Received: (qmail 53268 invoked by uid 500); 20 Dec 2013 01:26:07 -0000 Mailing-List: contact derby-dev-help@db.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: Delivered-To: mailing list derby-dev@db.apache.org Received: (qmail 53260 invoked by uid 99); 20 Dec 2013 01:26:07 -0000 Received: from arcas.apache.org (HELO arcas.apache.org) (140.211.11.28) by apache.org (qpsmtpd/0.29) with ESMTP; Fri, 20 Dec 2013 01:26:07 +0000 Date: Fri, 20 Dec 2013 01:26:07 +0000 (UTC) From: "Myrna van Lunteren (JIRA)" To: derby-dev@db.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Comment Edited] (DERBY-6250) nightly regression test failure: testBasic(org.apache.derbyTesting.functionTests.tests.jdbc4.LoginTimeoutTest)junit.framework.AssertionFailedError: Should not have been able to connect! MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: 7bit X-JIRA-FingerPrint: 30527f35849b9dde25b450d4833f0394 [ https://issues.apache.org/jira/browse/DERBY-6250?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13853558#comment-13853558 ] Myrna van Lunteren edited comment on DERBY-6250 at 12/20/13 1:24 AM: --------------------------------------------------------------------- This is the errorstacktrace.out in the fail directory: [Error/failure logged at Wed Dec 18 21:11:48 PST 2013] junit.framework.AssertionFailedError: Should not have been able to connect! at junit.framework.Assert.fail(Assert.java:47) at org.apache.derbyTesting.functionTests.tests.jdbc4.LoginTimeoutTest.tryTimeout(LoginTimeoutTest.java:318) at org.apache.derbyTesting.functionTests.tests.jdbc4.LoginTimeoutTest.tryTimeout(LoginTimeoutTest.java:308) at org.apache.derbyTesting.functionTests.tests.jdbc4.LoginTimeoutTest.vetConnector(LoginTimeoutTest.java:295) at org.apache.derbyTesting.functionTests.tests.jdbc4.LoginTimeoutTest.vetServerTimeout(LoginTimeoutTest.java:384) at org.apache.derbyTesting.functionTests.tests.jdbc4.LoginTimeoutTest.vetServerTimeouts(LoginTimeoutTest.java:371) at org.apache.derbyTesting.functionTests.tests.jdbc4.LoginTimeoutTest.testBasic(LoginTimeoutTest.java:288) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:60) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37) at java.lang.reflect.Method.invoke(Method.java:611) at junit.framework.TestCase.runTest(TestCase.java:164) at junit.framework.TestCase.runBare(TestCase.java:130) at org.apache.derbyTesting.junit.BaseTestCase.runBare(BaseTestCase.java:118) at org.apache.derbyTesting.junit.BaseJDBCTestCase.runBareOverridable(BaseJDBCTestCase.java:440) at org.apache.derbyTesting.junit.BaseJDBCTestCase.runBare(BaseJDBCTestCase.java:457) at junit.framework.TestResult$1.protect(TestResult.java:106) at junit.framework.TestResult.runProtected(TestResult.java:124) at junit.framework.TestResult.run(TestResult.java:109) at junit.framework.TestCase.run(TestCase.java:120) at junit.framework.TestSuite.runTest(TestSuite.java:230) at junit.framework.TestSuite.run(TestSuite.java:225) at junit.extensions.TestDecorator.basicRun(TestDecorator.java:24) at junit.extensions.TestSetup$1.protect(TestSetup.java:21) at junit.framework.TestResult.runProtected(TestResult.java:124) at junit.extensions.TestSetup.run(TestSetup.java:25) at org.apache.derbyTesting.junit.BaseTestSetup.run(BaseTestSetup.java:57) at junit.extensions.TestDecorator.basicRun(TestDecorator.java:24) at junit.extensions.TestSetup$1.protect(TestSetup.java:21) at junit.framework.TestResult.runProtected(TestResult.java:124) at junit.extensions.TestSetup.run(TestSetup.java:25) at junit.extensions.TestDecorator.basicRun(TestDecorator.java:24) at junit.extensions.TestSetup$1.protect(TestSetup.java:21) at junit.framework.TestResult.runProtected(TestResult.java:124) at junit.extensions.TestSetup.run(TestSetup.java:25) at junit.extensions.TestDecorator.basicRun(TestDecorator.java:24) at junit.extensions.TestSetup$1.protect(TestSetup.java:21) at junit.framework.TestResult.runProtected(TestResult.java:124) at junit.extensions.TestSetup.run(TestSetup.java:25) at org.apache.derbyTesting.junit.BaseTestSetup.run(BaseTestSetup.java:57) at junit.framework.TestSuite.runTest(TestSuite.java:230) at junit.framework.TestSuite.run(TestSuite.java:225) at junit.framework.TestSuite.runTest(TestSuite.java:230) at junit.framework.TestSuite.run(TestSuite.java:225) at junit.framework.TestSuite.runTest(TestSuite.java:230) at junit.framework.TestSuite.run(TestSuite.java:225) at junit.framework.TestSuite.runTest(TestSuite.java:230) at junit.framework.TestSuite.run(TestSuite.java:225) at junit.textui.TestRunner.doRun(TestRunner.java:121) at junit.textui.TestRunner.start(TestRunner.java:185) at junit.textui.TestRunner.main(TestRunner.java:143) However, the timestamp in this is later than the content of derby.log, so I think the first test may be the problem. Here is the stack trace from derby.log in the fail directory: Wed Dec 18 21:10:31 PST 2013: Booting Derby version The Apache Software Foundation - Apache Derby - 10.11.0.0 alpha - (1552207): instance 67e28705-0143-0942-14ec-00000dcdcc80 on database directory C:\jartest\JarResults.2013-12-18\ibm16_suites.All\system\singleUse\oneuse113 with class loader sun.misc.Launcher$AppClassLoader@9cf09cf Loaded from file:/C:/jartest/jars/derby.jar java.vendor=IBM Corporation java.runtime.version=pwi3260sr15-20131017_01 (SR15) java.fullversion=JRE 1.6.0 IBM J9 2.4 Windows Server 2008 R2 x86-32 jvmwi3260sr15-20131016_170922 (JIT enabled, AOT enabled) J9VM - 20131016_170922 JIT - r9_20130920_46510ifx2 GC - GA24_Java6_SR15_20131016_1337_B170922 os.name=Windows Server 2008 R2 os.arch=x86 os.version=6.1 build 7601 Service Pack 1 derby.system.home=C:\jartest\JarResults.2013-12-18\ibm16_suites.All\system Database Class Loader started - derby.database.classpath='' Wed Dec 18 21:10:58 PST 2013 Thread[Thread-696,5,main] (XID = 191), (SESSIONID = 26), (DATABASE = singleUse/oneuse113), (DRDAID = null), Cleanup action starting java.sql.SQLNonTransientConnectionException: Connection authentication failure occurred. Reason: Invalid authentication.. at org.apache.derby.impl.jdbc.SQLExceptionFactory.getSQLException(Unknown Source) at org.apache.derby.impl.jdbc.Util.newEmbedSQLException(Unknown Source) at org.apache.derby.impl.jdbc.Util.newEmbedSQLException(Unknown Source) at org.apache.derby.impl.jdbc.Util.generateCsSQLException(Unknown Source) at org.apache.derby.impl.jdbc.EmbedConnection.newSQLException(Unknown Source) at org.apache.derby.impl.jdbc.EmbedConnection.checkUserCredentials(Unknown Source) at org.apache.derby.impl.jdbc.EmbedConnection.(Unknown Source) at org.apache.derby.jdbc.InternalDriver.getNewEmbedConnection(Unknown Source) at org.apache.derby.jdbc.InternalDriver$LoginCallable.call(Unknown Source) at org.apache.derby.jdbc.InternalDriver$LoginCallable.call(Unknown Source) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:314) at java.util.concurrent.FutureTask.run(FutureTask.java:149) at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:906) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:929) at java.lang.Thread.run(Thread.java:761) Caused by: java.sql.SQLException: Connection authentication failure occurred. Reason: Invalid authentication.. at org.apache.derby.impl.jdbc.SQLExceptionFactory.wrapArgsForTransportAcrossDRDA(Unknown Source) ... 15 more ============= begin nested exception, level (1) =========== java.sql.SQLException: Connection authentication failure occurred. Reason: Invalid authentication.. at org.apache.derby.impl.jdbc.SQLExceptionFactory.wrapArgsForTransportAcrossDRDA(Unknown Source) at org.apache.derby.impl.jdbc.SQLExceptionFactory.getSQLException(Unknown Source) at org.apache.derby.impl.jdbc.Util.newEmbedSQLException(Unknown Source) at org.apache.derby.impl.jdbc.Util.newEmbedSQLException(Unknown Source) at org.apache.derby.impl.jdbc.Util.generateCsSQLException(Unknown Source) at org.apache.derby.impl.jdbc.EmbedConnection.newSQLException(Unknown Source) at org.apache.derby.impl.jdbc.EmbedConnection.checkUserCredentials(Unknown Source) at org.apache.derby.impl.jdbc.EmbedConnection.(Unknown Source) at org.apache.derby.jdbc.InternalDriver.getNewEmbedConnection(Unknown Source) at org.apache.derby.jdbc.InternalDriver$LoginCallable.call(Unknown Source) at org.apache.derby.jdbc.InternalDriver$LoginCallable.call(Unknown Source) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:314) at java.util.concurrent.FutureTask.run(FutureTask.java:149) at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:906) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:929) at java.lang.Thread.run(Thread.java:761) was (Author: myrna): The timestamp in this latest failure is: [Error/failure logged at Wed Dec 18 21:11:48 PST 2013] I think the first test may be the problem. Here is the stack trace from derby.log in the fail directory: Wed Dec 18 21:10:31 PST 2013: Booting Derby version The Apache Software Foundation - Apache Derby - 10.11.0.0 alpha - (1552207): instance 67e28705-0143-0942-14ec-00000dcdcc80 on database directory C:\jartest\JarResults.2013-12-18\ibm16_suites.All\system\singleUse\oneuse113 with class loader sun.misc.Launcher$AppClassLoader@9cf09cf Loaded from file:/C:/jartest/jars/derby.jar java.vendor=IBM Corporation java.runtime.version=pwi3260sr15-20131017_01 (SR15) java.fullversion=JRE 1.6.0 IBM J9 2.4 Windows Server 2008 R2 x86-32 jvmwi3260sr15-20131016_170922 (JIT enabled, AOT enabled) J9VM - 20131016_170922 JIT - r9_20130920_46510ifx2 GC - GA24_Java6_SR15_20131016_1337_B170922 os.name=Windows Server 2008 R2 os.arch=x86 os.version=6.1 build 7601 Service Pack 1 derby.system.home=C:\jartest\JarResults.2013-12-18\ibm16_suites.All\system Database Class Loader started - derby.database.classpath='' Wed Dec 18 21:10:58 PST 2013 Thread[Thread-696,5,main] (XID = 191), (SESSIONID = 26), (DATABASE = singleUse/oneuse113), (DRDAID = null), Cleanup action starting java.sql.SQLNonTransientConnectionException: Connection authentication failure occurred. Reason: Invalid authentication.. at org.apache.derby.impl.jdbc.SQLExceptionFactory.getSQLException(Unknown Source) at org.apache.derby.impl.jdbc.Util.newEmbedSQLException(Unknown Source) at org.apache.derby.impl.jdbc.Util.newEmbedSQLException(Unknown Source) at org.apache.derby.impl.jdbc.Util.generateCsSQLException(Unknown Source) at org.apache.derby.impl.jdbc.EmbedConnection.newSQLException(Unknown Source) at org.apache.derby.impl.jdbc.EmbedConnection.checkUserCredentials(Unknown Source) at org.apache.derby.impl.jdbc.EmbedConnection.(Unknown Source) at org.apache.derby.jdbc.InternalDriver.getNewEmbedConnection(Unknown Source) at org.apache.derby.jdbc.InternalDriver$LoginCallable.call(Unknown Source) at org.apache.derby.jdbc.InternalDriver$LoginCallable.call(Unknown Source) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:314) at java.util.concurrent.FutureTask.run(FutureTask.java:149) at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:906) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:929) at java.lang.Thread.run(Thread.java:761) Caused by: java.sql.SQLException: Connection authentication failure occurred. Reason: Invalid authentication.. at org.apache.derby.impl.jdbc.SQLExceptionFactory.wrapArgsForTransportAcrossDRDA(Unknown Source) ... 15 more ============= begin nested exception, level (1) =========== java.sql.SQLException: Connection authentication failure occurred. Reason: Invalid authentication.. at org.apache.derby.impl.jdbc.SQLExceptionFactory.wrapArgsForTransportAcrossDRDA(Unknown Source) at org.apache.derby.impl.jdbc.SQLExceptionFactory.getSQLException(Unknown Source) at org.apache.derby.impl.jdbc.Util.newEmbedSQLException(Unknown Source) at org.apache.derby.impl.jdbc.Util.newEmbedSQLException(Unknown Source) at org.apache.derby.impl.jdbc.Util.generateCsSQLException(Unknown Source) at org.apache.derby.impl.jdbc.EmbedConnection.newSQLException(Unknown Source) at org.apache.derby.impl.jdbc.EmbedConnection.checkUserCredentials(Unknown Source) at org.apache.derby.impl.jdbc.EmbedConnection.(Unknown Source) at org.apache.derby.jdbc.InternalDriver.getNewEmbedConnection(Unknown Source) at org.apache.derby.jdbc.InternalDriver$LoginCallable.call(Unknown Source) at org.apache.derby.jdbc.InternalDriver$LoginCallable.call(Unknown Source) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:314) at java.util.concurrent.FutureTask.run(FutureTask.java:149) at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:906) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:929) at java.lang.Thread.run(Thread.java:761) > nightly regression test failure: testBasic(org.apache.derbyTesting.functionTests.tests.jdbc4.LoginTimeoutTest)junit.framework.AssertionFailedError: Should not have been able to connect! > ------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------ > > Key: DERBY-6250 > URL: https://issues.apache.org/jira/browse/DERBY-6250 > Project: Derby > Issue Type: Bug > Components: Test > Affects Versions: 10.10.1.3 > Reporter: Mike Matrigali > > intermittent nightly test failure: 10.10, windows, ibm16. > http://people.apache.org/~myrnavl/derby_test_results/v10_10/windows/testlog/ibm16/1488453-suites.All_diff.txt > 1) client/server LoginTimeoutTestjava.sql.SQLTimeoutException: DERBY SQL error: ERRORCODE: 40000, SQLSTATE: XBDA0, SQLERRMC: Login timeout exceeded. > at org.apache.derby.client.am.SQLExceptionFactory40.getSQLException(Unknown Source) > at org.apache.derby.client.am.SqlException.getSQLException(Unknown Source) > at org.apache.derby.jdbc.ClientDriver.connect(Unknown Source) > at java.sql.DriverManager.getConnection(DriverManager.java:419) > at java.sql.DriverManager.getConnection(DriverManager.java:391) > at org.apache.derbyTesting.junit.DriverManagerConnector.openConnection(DriverManagerConnector.java:100) > at org.apache.derbyTesting.junit.DriverManagerConnector.openConnection(DriverManagerConnector.java:67) > at org.apache.derbyTesting.junit.DriverManagerConnector.openConnection(DriverManagerConnector.java:47) > at org.apache.derbyTesting.junit.TestConfiguration.openConnection(TestConfiguration.java:1737) > at org.apache.derbyTesting.junit.DropDatabaseSetup.tearDown(DropDatabaseSetup.java:54) > at junit.extensions.TestSetup$1.protect(TestSetup.java:22) > at junit.extensions.TestSetup.run(TestSetup.java:25) > at org.apache.derbyTesting.junit.BaseTestSetup.run(BaseTestSetup.java:57) > at junit.extensions.TestDecorator.basicRun(TestDecorator.java:24) > at junit.extensions.TestSetup$1.protect(TestSetup.java:21) > at junit.extensions.TestSetup.run(TestSetup.java:25) > at junit.extensions.TestDecorator.basicRun(TestDecorator.java:24) > at junit.extensions.TestSetup$1.protect(TestSetup.java:21) > at junit.extensions.TestSetup.run(TestSetup.java:25) > at junit.extensions.TestDecorator.basicRun(TestDecorator.java:24) > at junit.extensions.TestSetup$1.protect(TestSetup.java:21) > at junit.extensions.TestSetup.run(TestSetup.java:25) > at org.apache.derbyTesting.junit.BaseTestSetup.run(BaseTestSetup.java:57) > Caused by: org.apache.derby.client.am.SqlException: DERBY SQL error: ERRORCODE: 40000, SQLSTATE: XBDA0, SQLERRMC: Login timeout exceeded. > at org.apache.derby.client.am.Connection.completeSqlca(Unknown Source) > at org.apache.derby.client.net.NetConnectionReply.parseRdbAccessFailed(Unknown Source) > at org.apache.derby.client.net.NetConnectionReply.parseAccessRdbError(Unknown Source) > at org.apache.derby.client.net.NetConnectionReply.parseACCRDBreply(Unknown Source) > at org.apache.derby.client.net.NetConnectionReply.readAccessDatabase(Unknown Source) > at org.apache.derby.client.net.NetConnection.readSecurityCheckAndAccessRdb(Unknown Source) > at org.apache.derby.client.net.NetConnection.flowSecurityCheckAndAccessRdb(Unknown Source) > at org.apache.derby.client.net.NetConnection.flowUSRIDPWDconnect(Unknown Source) > at org.apache.derby.client.net.NetConnection.flowConnect(Unknown Source) > at org.apache.derby.client.net.NetConnection.(Unknown Source) > at org.apache.derby.client.net.NetConnection40.(Unknown Source) > at org.apache.derby.client.net.ClientJDBCObjectFactoryImpl40.newNetConnection(Unknown Source) > ... 36 more > There was 1 failure: > 1) testBasic(org.apache.derbyTesting.functionTests.tests.jdbc4.LoginTimeoutTest)junit.framework.AssertionFailedError: Should not have been able to connect! > at org.apache.derbyTesting.functionTests.tests.jdbc4.LoginTimeoutTest.tryTimeout(LoginTimeoutTest.java:312) > at org.apache.derbyTesting.functionTests.tests.jdbc4.LoginTimeoutTest.tryTimeout(LoginTimeoutTest.java:302) > at org.apache.derbyTesting.functionTests.tests.jdbc4.LoginTimeoutTest.vetConnector(LoginTimeoutTest.java:288) > at org.apache.derbyTesting.functionTests.tests.jdbc4.LoginTimeoutTest.vetServerTimeout(LoginTimeoutTest.java:378) > at org.apache.derbyTesting.functionTests.tests.jdbc4.LoginTimeoutTest.vetServerTimeouts(LoginTimeoutTest.java:365) > at org.apache.derbyTesting.functionTests.tests.jdbc4.LoginTimeoutTest.testBasic(LoginTimeoutTest.java:282) > at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:60) > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37) > at org.apache.derbyTesting.junit.BaseTestCase.runBare(BaseTestCase.java:117) > at org.apache.derbyTesting.junit.BaseJDBCTestCase.runBareOverridable(BaseJDBCTestCase.java:442) > at org.apache.derbyTesting.junit.BaseJDBCTestCase.runBare(BaseJDBCTestCase.java:459) > at junit.extensions.TestDecorator.basicRun(TestDecorator.java:24) > at junit.extensions.TestSetup$1.protect(TestSetup.java:21) > at junit.extensions.TestSetup.run(TestSetup.java:25) > at org.apache.derbyTesting.junit.BaseTestSetup.run(BaseTestSetup.java:57) > at junit.extensions.TestDecorator.basicRun(TestDecorator.java:24) > at junit.extensions.TestSetup$1.protect(TestSetup.java:21) > at junit.extensions.TestSetup.run(TestSetup.java:25) > at junit.extensions.TestDecorator.basicRun(TestDecorator.java:24) > at junit.extensions.TestSetup$1.protect(TestSetup.java:21) > at junit.extensions.TestSetup.run(TestSetup.java:25) > at junit.extensions.TestDecorator.basicRun(TestDecorator.java:24) > at junit.extensions.TestSetup$1.protect(TestSetup.java:21) > at junit.extensions.TestSetup.run(TestSetup.java:25) > at org.apache.derbyTesting.junit.BaseTestSetup.run(BaseTestSetup.java:57) -- This message was sent by Atlassian JIRA (v6.1.4#6159)