[
https://issues.apache.org/jira/browse/DERBY-6704?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]
Dag H. Wanvik updated DERBY-6704:
---------------------------------
Attachment: derby-6704.diff
Uploading a patch that collects std out and err for the spawned subprocesses,
and also forward the debug options "derby.tests.debug" and "derby.tests.trace"
to the subprocesses. If any of those are
set, we collect the std err and std out and print them interleaved with that of
the top process. For example, if the jdbcapi suite is run with
"derby.tests.trace=true", one would see the following out the tests std out:
{code}
run:
.
(emb)jdbcapi.AutoloadTest.spawnProcess:AutoloadTest
[ (stdout subprocess) .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testEmbeddedNotStarted used 4
ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testRegisteredDriver used 63 ms
.
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testAutoNetworkServerBoot used
5101 ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testSuccessfulConnect used 620
ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testEmbeddedStarted used 0 ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testUnsuccessfulConnect used 14
ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testExplicitLoad used 8 ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testAutoloadDriverUnregister
used 576 ms .
(stdout subprocess) (net)jdbcapi.AutoloadTest.testRegisteredDriver used 0 ms .
(stdout subprocess) (net)jdbcapi.AutoloadTest.testSuccessfulConnect used 290
ms .
(stdout subprocess) (net)jdbcapi.AutoloadTest.testUnsuccessfulConnect used 12
ms .
(stdout subprocess) (net)jdbcapi.AutoloadTest.testExplicitLoad used 24 ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testAssertShutdownOK used 183
ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testShutdownDeRegister used 3
ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testExplicitReload used 231 ms
(stdout subprocess) Time: 7.381
(stdout subprocess)
(stdout subprocess) OK (15 tests)
(stdout subprocess)
(stdout subprocess) ]
[ (stderr subprocess) ]
used 8709 ms .
(emb)jdbcapi.AutoloadTest.spawnProcess:JDBCDriversEmbeddedTest
[ (stdout subprocess) .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testRegisteredDriver used 459
ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testAutoNetworkServerBoot used
144 ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testSuccessfulConnect used 396
ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testEmbeddedStarted used 0 ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testUnsuccessfulConnect used 16
ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testExplicitLoad used 9 ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testAutoloadDriverUnregister
used 555 ms .
(stdout subprocess) (net)jdbcapi.AutoloadTest.testRegisteredDriver used 1 ms .
(stdout subprocess) (net)jdbcapi.AutoloadTest.testSuccessfulConnect used 279
ms .
(stdout subprocess) (net)jdbcapi.AutoloadTest.testUnsuccessfulConnect used 15
ms .
(stdout subprocess) (net)jdbcapi.AutoloadTest.testExplicitLoad used 21 ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testAssertShutdownOK used 173
ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testShutdownDeRegister used 3
ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testExplicitReload used 251 ms
(stdout subprocess) Time: 2.454
(stdout subprocess)
(stdout subprocess) OK (14 tests)
(stdout subprocess)
(stdout subprocess) ]
[ (stderr subprocess) ]
used 4008 ms .
(emb)jdbcapi.AutoloadTest.spawnProcess:JDBCDriversClientTest
[ (stdout subprocess) .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testEmbeddedNotStarted used 2
ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testRegisteredDriver used 71 ms
.
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testAutoNetworkServerBoot used
5104 ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testSuccessfulConnect used 608
ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testEmbeddedStarted used 0 ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testUnsuccessfulConnect used 15
ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testExplicitLoad used 7 ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testAutoloadDriverUnregister
used 600 ms .
(stdout subprocess) (net)jdbcapi.AutoloadTest.testRegisteredDriver used 1 ms .
(stdout subprocess) (net)jdbcapi.AutoloadTest.testSuccessfulConnect used 300
ms .
(stdout subprocess) (net)jdbcapi.AutoloadTest.testUnsuccessfulConnect used 9
ms .
(stdout subprocess) (net)jdbcapi.AutoloadTest.testExplicitLoad used 15 ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testAssertShutdownOK used 187
ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testShutdownDeRegister used 2
ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testExplicitReload used 236 ms
(stdout subprocess) Time: 7.426
(stdout subprocess)
(stdout subprocess) OK (15 tests)
(stdout subprocess)
(stdout subprocess) ]
[ (stderr subprocess) ]
used 8509 ms .
(emb)jdbcapi.AutoloadTest.spawnProcess:JDBCDriversAllTest
[ (stdout subprocess) .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testRegisteredDriver used 413
ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testAutoNetworkServerBoot used
5052 ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testSuccessfulConnect used 373
ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testEmbeddedStarted used 0 ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testUnsuccessfulConnect used 11
ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testExplicitLoad used 7 ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testAutoloadDriverUnregister
used 599 ms .
(stdout subprocess) (net)jdbcapi.AutoloadTest.testRegisteredDriver used 0 ms .
(stdout subprocess) (net)jdbcapi.AutoloadTest.testSuccessfulConnect used 298
ms .
(stdout subprocess) (net)jdbcapi.AutoloadTest.testUnsuccessfulConnect used 15
ms .
(stdout subprocess) (net)jdbcapi.AutoloadTest.testExplicitLoad used 23 ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testAssertShutdownOK used 193
ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testShutdownDeRegister used 2
ms .
(stdout subprocess) (emb)jdbcapi.AutoloadTest.testExplicitReload used 210 ms
(stdout subprocess) Time: 7.454
(stdout subprocess)
(stdout subprocess) OK (14 tests)
(stdout subprocess)
(stdout subprocess) ]
[ (stderr subprocess) ]
used 8508 ms .
(emb)jdbcapi.AutoloadTest.spawnProcess:ConcurrentAutoloadTest
[ (stdout subprocess) .
(stdout subprocess)
(emb)jdbcapi.ConcurrentAutoloadTest.testConcurrentAutoloading used 1054 ms
(stdout subprocess) Time: 1.09
(stdout subprocess)
(stdout subprocess) OK (1 test)
(stdout subprocess)
(stdout subprocess) ]
[ (stderr subprocess) ]
used 2006 ms
Time: 32.222
OK (5 tests)
BUILD SUCCESSFUL (total time: 32 seconds)
{code}
> Hang in AutoloadTest.spawnProcess:JDBCDriversEmbeddedTest on Mac/jdk7,8
> -----------------------------------------------------------------------
>
> Key: DERBY-6704
> URL: https://issues.apache.org/jira/browse/DERBY-6704
> Project: Derby
> Issue Type: Bug
> Reporter: Dag H. Wanvik
> Attachments: derby-6704.diff
>
>
> In our release tests for 10.11 we have seen this stack trace:
> {code}
>
> org.apache.derbyTesting.functionTests.tests.jdbcapi.AutoloadTest.spawnProcess:JDBCDriversEmbeddedTest
> 2701.548 s
> junit.framework.AssertionFailedError: Test process failed:Spawned
> org.apache.derbyTesting.functionTests.tests.jdbcapi.JDBCDriversEmbeddedTest
> exitCode=143
> STDOUT:
> ..
> at
> org.apache.derbyTesting.functionTests.tests.jdbcapi.AutoloadTest.spawnProcess(AutoloadTest.java:256)
> 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.extensions.TestDecorator.basicRun(TestDecorator.java:24)
> at junit.extensions.TestSetup$1.protect(TestSetup.java:21)
> at junit.extensions.TestSetup.run(TestSetup.java:25)
> {code}
> presumably because the spawned process is killed by the test framework; it
> had been executing for 2701 second, but we don't have much insight into what
> happened to the subprocess because we don't collect its std our or std err.
--
This message was sent by Atlassian JIRA
(v6.2#6252)
