Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

[Gradle + TestNg] Listener initialization failures not logged in the build log/console #25857

Closed
sravanmedarapu opened this issue Jul 21, 2023 · 11 comments · Fixed by #26169
Closed
Assignees
Milestone

Comments

@sravanmedarapu
Copy link

sravanmedarapu commented Jul 21, 2023

Expected Behavior

Context: Running TestNG Tests (e.g ./gradlew :clean :test -d)

Problem:
When build fail during test listener configuration stage/before launching the test, it's expected to see the failure reason in the build log.

However current build log doesn't contain details about why build failed. Setting log level to debug(-d) or info(-i) doesn't make much difference. (please refer to logs included)

Current Behavior (optional)

Build log(./gradlew clean test):

2023-07-21T12:49:27.042+0000 [ERROR] [org.gradle.internal.buildevents.BuildExceptionReporter] 
2023-07-21T12:49:27.043+0000 [ERROR] [org.gradle.internal.buildevents.BuildExceptionReporter] FAILURE: Build failed with an exception.
2023-07-21T12:49:27.043+0000 [ERROR] [org.gradle.internal.buildevents.BuildExceptionReporter] 
2023-07-21T12:49:27.043+0000 [ERROR] [org.gradle.internal.buildevents.BuildExceptionReporter] * What went wrong:
2023-07-21T12:49:27.043+0000 [ERROR] [org.gradle.internal.buildevents.BuildExceptionReporter] Execution failed for task ':test'.
2023-07-21T12:49:27.043+0000 [ERROR] [org.gradle.internal.buildevents.BuildExceptionReporter] > There were failing tests. See the results at: file:///build/default_junit/test-results/
2023-07-21T12:49:27.043+0000 [ERROR] [org.gradle.internal.buildevents.BuildExceptionReporter] 
2023-07-21T12:49:27.043+0000 [ERROR] [org.gradle.internal.buildevents.BuildExceptionReporter] * Try:
2023-07-21T12:49:27.043+0000 [ERROR] [org.gradle.internal.buildevents.BuildExceptionReporter] > Run with --scan to get full insights.
2023-07-21T12:49:27.043+0000 [ERROR] [org.gradle.internal.buildevents.BuildResultLogger] 
2023-07-21T12:49:27.043+0000 [ERROR] [org.gradle.internal.buildevents.BuildResultLogger] BUILD FAILED in 32s

XML report file:


<testsuite name="Gradle Test Executor 2" tests="1" skipped="0" failures="1" errors="0" timestamp="2023-07-21T12:45:40" hostname="1d22c846ba2a" time="43.566">
<properties/>
<testcase name="failed to execute tests" classname="Gradle Test Executor 2" time="43.566">
<failure message="org.gradle.api.internal.tasks.testing.TestSuiteExecutionException: Could not complete execution for Gradle Test Executor 2." type="org.gradle.api.internal.tasks.testing.TestSuiteExecutionException">
org.gradle.api.internal.tasks.testing.TestSuiteExecutionException: Could not complete execution for Gradle Test Executor 2.
 at org.gradle.api.internal.tasks.testing.SuiteTestClassProcessor.stop(SuiteTestClassProcessor.java:64) 
at java.base@17.0.3/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base@17.0.3/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) 
at java.base@17.0.3/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) 
at java.base@17.0.3/java.lang.reflect.Method.invoke(Method.java:568) 
at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:36) 
at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24) 
at org.gradle.internal.dispatch.ContextClassLoaderDispatch.dispatch(ContextClassLoaderDispatch.java:33) 
at org.gradle.internal.dispatch.ProxyDispatchAdapter$DispatchingInvocationHandler.invoke(ProxyDispatchAdapter.java:94) at jdk.proxy1/jdk.proxy1.$Proxy2.stop(Unknown Source)
at org.gradle.api.internal.tasks.testing.worker.TestWorker$3.run(TestWorker.java:193) 
at org.gradle.api.internal.tasks.testing.worker.TestWorker.executeAndMaintainThreadName(TestWorker.java:129) 
at org.gradle.api.internal.tasks.testing.worker.TestWorker.execute(TestWorker.java:100) 
at org.gradle.api.internal.tasks.testing.worker.TestWorker.execute(TestWorker.java:60) 
at org.gradle.process.internal.worker.child.ActionExecutionWorker.execute(ActionExecutionWorker.java:56) 
at org.gradle.process.internal.worker.child.SystemApplicationClassLoaderWorker.call(SystemApplicationClassLoaderWorker.java:113) 
at org.gradle.process.internal.worker.child.SystemApplicationClassLoaderWorker.call(SystemApplicationClassLoaderWorker.java:65) 
at app//worker.org.gradle.process.internal.worker.GradleWorkerMain.run(GradleWorkerMain.java:69) 
at app//worker.org.gradle.process.internal.worker.GradleWorkerMain.main(GradleWorkerMain.java:74)
 Caused by: org.testng.TestNGException: Cannot instantiate class test.listeners.teamcity.android.RealDeviceSuiteListener at app//org.testng.internal.objects.InstanceCreator.newInstance(InstanceCreator.java:41) 
at app//org.testng.internal.objects.InstanceCreator.newInstance(InstanceCreator.java:59) 
at app//org.testng.ITestObjectFactory.newInstance(ITestObjectFactory.java:10)
at app//org.testng.internal.objects.SimpleObjectDispenser.dispense(SimpleObjectDispenser.java:60) 
at app//org.testng.internal.objects.GuiceBasedObjectDispenser.dispense(GuiceBasedObjectDispenser.java:28) 
at app//org.testng.TestNG.addListeners(TestNG.java:1000) at app//org.testng.TestNG.initializeConfiguration(TestNG.java:957) at app//org.testng.TestNG.initializeEverything(TestNG.java:1048) at app//org.testng.TestNG.run(TestNG.java:1059) 
at org.gradle.api.internal.tasks.testing.testng.TestNGTestClassProcessor.runTests(TestNGTestClassProcessor.java:145) 
at org.gradle.api.internal.tasks.testing.testng.TestNGTestClassProcessor.stop(TestNGTestClassProcessor.java:92) 
at org.gradle.api.internal.tasks.testing.SuiteTestClassProcessor.stop(SuiteTestClassProcessor.java:62) ... 18 more Caused by: java.lang.reflect.InvocationTargetException at java.base/jdk.internal.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at java.base/jdk.internal.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:77) at java.base/jdk.internal.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45) at java.base/java.lang.reflect.Constructor.newInstanceWithCaller(Constructor.java:499) at java.base/java.lang.reflect.Constructor.newInstance(Constructor.java:480) at org.testng.internal.objects.InstanceCreator.newInstance(InstanceCreator.java:38) ... 29 more Caused by: java.lang.ExceptionInInitializerError at <init>(RealDeviceSuiteListener.kt:13) ...  ... 36 more </failure>
</testcase>

Context

We run large number of builds (in CI) and referring to external files to understand the build not really feasible option.

Environment:

Gradle: 8.2.1
TestNG: 7.6.1

build.gradle.kts:

tasks.test {
   
    testLogging.showStandardStreams = true
    testLogging.exceptionFormat = org.gradle.api.tasks.testing.logging.TestExceptionFormat.FULL

    useTestNG {
        suites(suite.file)
    }
}
@sravanmedarapu sravanmedarapu added a:feature A new functionality to-triage labels Jul 21, 2023
@ov7a
Copy link
Member

ov7a commented Jul 24, 2023

Sorry that you're having trouble with Gradle!

We appreciate the effort that went into filing this issue, but we must ask for more information.

As stated in our issue template, a minimal reproducible example is a must for us to be able to track down and fix your problem efficiently. Our available resources are severely limited, and we must be sure we are looking at the exact problem you are facing.

If we have a reproducer, we may be able also to suggest workarounds or ways to avoid the problem.

The ideal way to provide a reproducer is to leverage our reproducer template. You can also use Gradle Project Replicator to reproduce the structure of your project.

This issue will be closed after 7 days unless you provide more information.


We need a full reproducer to decide whether is should be a feature or it's just a misconfiguration

@ov7a ov7a added in:testing-testng pending:reproducer Indicates that the issue requires a reproducer or will be closed after 7 days and removed to-triage labels Jul 24, 2023
@github-actions github-actions bot removed the pending:reproducer Indicates that the issue requires a reproducer or will be closed after 7 days label Jul 24, 2023
@agileseph
Copy link

@ov7a Hi, Vlad.

As far as I see, people report the same issue here: https://youtrack.jetbrains.com/issue/IDEA-251105, and the intention is simple: just making it easier to see the exception in the console output.

When Gradle reports "FAILURE: Build failed with an exception." it is no clear details of the exception and people need to open the file (for TeamCity, you need to setup report publisher, and for local run, find the proper folder with XML and then check the error itself). All these make 3-4 steps (as people mentioned in https://youtrack.jetbrains.com/issue/IDEA-251105) to just understand what Exception has happened.

The same here: "Currently we throw a TestNGException . We can throw something else, but that doesn't change anything because Gradle is basically catching Throwable. So anything we throw is going to be gobbled up." - testng-team/testng#2937

So, I believe, the issue that was posted by @sravanmedarapu makes sense for any developer and we can see different references for it. This minor improvement can just help to get an easy understanding of the issue originally happened by TestNG and Gradle can simply show it instead of gobbling.

@ov7a ov7a added the to-triage label Jul 27, 2023
@ov7a
Copy link
Member

ov7a commented Jul 27, 2023

@agileseph Thanks for providing the context! It may take some time for us to return with an answer, but we’ll discuss this issue internally.

@sravanmedarapu
Copy link
Author

sravanmedarapu commented Jul 27, 2023

Including sample logs for reference.

Maven Logs: Maven prints the failure reason why the build failed at the end.

Example(Click)
[INFO] --- compiler:3.11.0:testCompile (default-testCompile) @ testng-sample ---
[INFO] Changes detected - recompiling the module! :dependency
[INFO] Compiling 2 source files with javac [debug target 11] to target/test-classes
[INFO] 
[INFO] --- surefire:3.1.2:test (default-test) @ testng-sample ---
[INFO] Using auto detected provider org.apache.maven.surefire.testng.TestNGProvider
[INFO] 
[INFO] -------------------------------------------------------
[INFO]  T E S T S
[INFO] -------------------------------------------------------
[INFO] Running org.testng.AppTest
SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
SLF4J: Defaulting to no-operation (NOP) logger implementation
SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
[ERROR] 
Couldn't find a constructor in class org.testng.MyListener
[INFO] 
[INFO] Results:
[INFO] 
[INFO] Tests run: 0, Failures: 0, Errors: 0, Skipped: 0
[INFO] 
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time:  3.115 s
[INFO] Finished at: 2023-07-26T17:38:24+05:30
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:3.1.2:test (default-test) on project testng-sample: 
[ERROR] 
[ERROR] Please refer to /Users/krishnamahadevan/temp/testng-sample/target/surefire-reports for the individual test results.
[ERROR] Please refer to dump files (if any exist) [date].dump, [date]-jvmRun[N].dump and [date].dumpstream.
[ERROR] There was an error in the forked process
[ERROR] 
[ERROR] Couldn't find a constructor in class org.testng.MyListener
[ERROR] org.apache.maven.surefire.booter.SurefireBooterForkException: There was an error in the forked process
[ERROR] 
[ERROR] Couldn't find a constructor in class org.testng.MyListener
[ERROR]         at org.apache.maven.plugin.surefire.booterclient.ForkStarter.fork(ForkStarter.java:628)

Gradle logs: Difficult to understand to what went wrong.

1. --info mode(./gradlew test -i)

Build log doesn't include build failure reason, instead refer to refer external file index.html

FAILURE: Build failed with an exception.

* What went wrong:
Execution failed for task ':test'.
> There were failing tests. See the report at: file:///build/reports/tests/test/index.html

* Try:
> Run with --scan to get full insights.

2. --debug mode:(./gradlew test -d)

Debug mode logs does include the reason for failure but build logs is very verbose

2023-07-25T17:54:06.429+0100 [DEBUG] [TestEventLogger]         Caused by:
2023-07-25T17:54:06.429+0100 [DEBUG] [TestEventLogger]         org.testng.TestNGException:
2023-07-25T17:54:06.429+0100 [DEBUG] [TestEventLogger]         Cannot instantiate class com.listeners.SuiteListener98
Full Log(Click)
2023-07-25T17:54:06.297+0100 [DEBUG] [TestEventLogger] Gradle Test Executor 5 STANDARD_ERROR
2023-07-25T17:54:06.297+0100 [DEBUG] [TestEventLogger]     SLF4J: No SLF4J providers were found.
2023-07-25T17:54:06.298+0100 [QUIET] [system.out] Test: Gradle Test Executor 5 : SLF4J: Defaulting to no-operation (NOP) logger implementation
2023-07-25T17:54:06.298+0100 [QUIET] [system.out] 
2023-07-25T17:54:06.298+0100 [DEBUG] [TestEventLogger]     SLF4J: Defaulting to no-operation (NOP) logger implementation
2023-07-25T17:54:06.298+0100 [QUIET] [system.out] Test: Gradle Test Executor 5 : SLF4J: See https://www.slf4j.org/codes.html#noProviders for further details.
2023-07-25T17:54:06.299+0100 [QUIET] [system.out] 
2023-07-25T17:54:06.299+0100 [DEBUG] [TestEventLogger]     SLF4J: See https://www.slf4j.org/codes.html#noProviders for further details.
2023-07-25T17:54:06.426+0100 [DEBUG] [TestEventLogger] 
2023-07-25T17:54:06.426+0100 [DEBUG] [TestEventLogger] Gradle Test Executor 5 FAILED
2023-07-25T17:54:06.426+0100 [DEBUG] [TestEventLogger]     org.gradle.api.internal.tasks.testing.TestSuiteExecutionException: Could not complete execution for Gradle Test Executor 5.
2023-07-25T17:54:06.426+0100 [DEBUG] [TestEventLogger]         at org.gradle.api.internal.tasks.testing.SuiteTestClassProcessor.stop(SuiteTestClassProcessor.java:64)
2023-07-25T17:54:06.426+0100 [DEBUG] [TestEventLogger]         at java.base@11.0.13/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
2023-07-25T17:54:06.426+0100 [DEBUG] [TestEventLogger]         at java.base@11.0.13/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
2023-07-25T17:54:06.427+0100 [DEBUG] [TestEventLogger]         at java.base@11.0.13/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
2023-07-25T17:54:06.427+0100 [DEBUG] [TestEventLogger]         at java.base@11.0.13/java.lang.reflect.Method.invoke(Method.java:566)
2023-07-25T17:54:06.427+0100 [DEBUG] [TestEventLogger]         at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:36)
2023-07-25T17:54:06.427+0100 [DEBUG] [TestEventLogger]         at org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24)
2023-07-25T17:54:06.427+0100 [DEBUG] [TestEventLogger]         at org.gradle.internal.dispatch.ContextClassLoaderDispatch.dispatch(ContextClassLoaderDispatch.java:33)
2023-07-25T17:54:06.427+0100 [DEBUG] [TestEventLogger]         at org.gradle.internal.dispatch.ProxyDispatchAdapter$DispatchingInvocationHandler.invoke(ProxyDispatchAdapter.java:94)
2023-07-25T17:54:06.427+0100 [DEBUG] [TestEventLogger]         at com.sun.proxy.$Proxy2.stop(Unknown Source)
2023-07-25T17:54:06.427+0100 [DEBUG] [TestEventLogger]         at org.gradle.api.internal.tasks.testing.worker.TestWorker$3.run(TestWorker.java:193)
2023-07-25T17:54:06.427+0100 [DEBUG] [TestEventLogger]         at org.gradle.api.internal.tasks.testing.worker.TestWorker.executeAndMaintainThreadName(TestWorker.java:129)
2023-07-25T17:54:06.428+0100 [DEBUG] [TestEventLogger]         at org.gradle.api.internal.tasks.testing.worker.TestWorker.execute(TestWorker.java:100)
2023-07-25T17:54:06.428+0100 [DEBUG] [TestEventLogger]         at org.gradle.api.internal.tasks.testing.worker.TestWorker.execute(TestWorker.java:60)
2023-07-25T17:54:06.428+0100 [DEBUG] [TestEventLogger]         at org.gradle.process.internal.worker.child.ActionExecutionWorker.execute(ActionExecutionWorker.java:56)
2023-07-25T17:54:06.428+0100 [DEBUG] [TestEventLogger]         at org.gradle.process.internal.worker.child.SystemApplicationClassLoaderWorker.call(SystemApplicationClassLoaderWorker.java:113)
2023-07-25T17:54:06.428+0100 [DEBUG] [TestEventLogger]         at org.gradle.process.internal.worker.child.SystemApplicationClassLoaderWorker.call(SystemApplicationClassLoaderWorker.java:65)
2023-07-25T17:54:06.428+0100 [DEBUG] [TestEventLogger]         at app//worker.org.gradle.process.internal.worker.GradleWorkerMain.run(GradleWorkerMain.java:69)
2023-07-25T17:54:06.428+0100 [DEBUG] [TestEventLogger]         at app//worker.org.gradle.process.internal.worker.GradleWorkerMain.main(GradleWorkerMain.java:74)
2023-07-25T17:54:06.428+0100 [DEBUG] [TestEventLogger] 
2023-07-25T17:54:06.429+0100 [DEBUG] [TestEventLogger]         Caused by:
2023-07-25T17:54:06.429+0100 [DEBUG] [TestEventLogger]         org.testng.TestNGException: 
2023-07-25T17:54:06.429+0100 [DEBUG] [TestEventLogger]         Cannot instantiate class com.listeners.SuiteListener
2023-07-25T17:54:06.429+0100 [DEBUG] [TestEventLogger]             at app//org.testng.internal.objects.InstanceCreator.newInstance(InstanceCreator.java:41)
2023-07-25T17:54:06.429+0100 [DEBUG] [TestEventLogger]             at app//org.testng.internal.objects.InstanceCreator.newInstance(InstanceCreator.java:59)
2023-07-25T17:54:06.429+0100 [DEBUG] [TestEventLogger]             at app//org.testng.ITestObjectFactory.newInstance(ITestObjectFactory.java:10)
2023-07-25T17:54:06.429+0100 [DEBUG] [TestEventLogger]             at app//org.testng.internal.objects.SimpleObjectDispenser.dispense(SimpleObjectDispenser.java:60)
2023-07-25T17:54:06.429+0100 [DEBUG] [TestEventLogger]             at app//org.testng.internal.objects.GuiceBasedObjectDispenser.dispense(GuiceBasedObjectDispenser.java:28)
2023-07-25T17:54:06.429+0100 [DEBUG] [TestEventLogger]             at app//org.testng.TestNG.addListeners(TestNG.java:1000)
2023-07-25T17:54:06.430+0100 [DEBUG] [TestEventLogger]             at app//org.testng.TestNG.initializeConfiguration(TestNG.java:957)
2023-07-25T17:54:06.430+0100 [DEBUG] [TestEventLogger]             at app//org.testng.TestNG.initializeEverything(TestNG.java:1048)
2023-07-25T17:54:06.430+0100 [DEBUG] [TestEventLogger]             at app//org.testng.TestNG.run(TestNG.java:1059)
2023-07-25T17:54:06.430+0100 [DEBUG] [TestEventLogger]             at org.gradle.api.internal.tasks.testing.testng.TestNGTestClassProcessor.runTests(TestNGTestClassProcessor.java:145)
2023-07-25T17:54:06.430+0100 [DEBUG] [TestEventLogger]             at org.gradle.api.internal.tasks.testing.testng.TestNGTestClassProcessor.stop(TestNGTestClassProcessor.java:92)
2023-07-25T17:54:06.430+0100 [DEBUG] [TestEventLogger]             at org.gradle.api.internal.tasks.testing.SuiteTestClassProcessor.stop(SuiteTestClassProcessor.java:62)
2023-07-25T17:54:06.430+0100 [DEBUG] [TestEventLogger]             ... 18 more
2023-07-25T17:54:06.430+0100 [DEBUG] [TestEventLogger] 
2023-07-25T17:54:06.430+0100 [DEBUG] [TestEventLogger]             Caused by:
2023-07-25T17:54:06.431+0100 [DEBUG] [TestEventLogger]             java.lang.reflect.InvocationTargetException
2023-07-25T17:54:06.431+0100 [DEBUG] [TestEventLogger]                 at java.base/jdk.internal.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
2023-07-25T17:54:06.431+0100 [DEBUG] [TestEventLogger]                 at java.base/jdk.internal.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
2023-07-25T17:54:06.431+0100 [DEBUG] [TestEventLogger]                 at java.base/jdk.internal.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
2023-07-25T17:54:06.431+0100 [DEBUG] [TestEventLogger]                 at java.base/java.lang.reflect.Constructor.newInstance(Constructor.java:490)
2023-07-25T17:54:06.431+0100 [DEBUG] [TestEventLogger]                 at org.testng.internal.objects.InstanceCreator.newInstance(InstanceCreator.java:38)
2023-07-25T17:54:06.431+0100 [DEBUG] [TestEventLogger]                 ... 29 more
2023-07-25T17:54:06.431+0100 [DEBUG] [TestEventLogger] 
2023-07-25T17:54:06.431+0100 [DEBUG] [TestEventLogger]                 Caused by:
2023-07-25T17:54:06.432+0100 [DEBUG] [TestEventLogger]                 kotlin.UninitializedPropertyAccessException: lateinit property platform has not been initialized
2023-07-25T17:54:06.432+0100 [DEBUG] [TestEventLogger]                     at com.listeners.SuiteBase$Companion.getPlatform(SuiteBase.kt:18)
2023-07-25T17:54:06.432+0100 [DEBUG] [TestEventLogger]                     at com.listeners.SuiteListener.<init>(SuiteListener.kt:4)
2023-07-25T17:54:06.432+0100 [DEBUG] [TestEventLogger]                     ... 34 more
2023-07-25T17:54:06.448+0100 [DEBUG] [org.gradle.process.internal.DefaultExecHandle] Changing state to: SUCCEEDED
2023-07-25T17:54:06.448+0100 [DEBUG] [org.gradle.process.internal.DefaultExecHandle] Process 'Gradle Test Executor 5' finished with exit value 0 (state: SUCCEEDED)
2023-07-25T17:54:06.449+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Dispatch org.gradle.api.internal.tasks.testing.processors.RestartEveryNTestClassProcessor@169ac4d1: released lock on worker lease
2023-07-25T17:54:06.450+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker: acquired lock on state of build :
2023-07-25T17:54:06.450+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker: acquired lock on worker lease
2023-07-25T17:54:06.451+0100 [DEBUG] [TestEventLogger] 
2023-07-25T17:54:06.451+0100 [DEBUG] [TestEventLogger] Gradle Test Run :test FAILED
2023-07-25T17:54:06.453+0100 [DEBUG] [org.gradle.internal.operations.DefaultBuildOperationRunner] Build operation 'Delete old JUnit XML results' completed
2023-07-25T17:54:06.455+0100 [DEBUG] [org.gradle.internal.operations.DefaultBuildOperationRunner] Build operation 'Generate junit XML test report for Gradle Test Executor 5' completed
2023-07-25T17:54:06.455+0100 [INFO] [org.gradle.api.internal.tasks.testing.junit.result.Binary2JUnitXmlReportGenerator] Finished generating test XML results (0.002 secs) into: /Users/sravenmedarapu/src/open_src/GradleTestNgScratchpad/build/test-results/test
2023-07-25T17:54:06.455+0100 [INFO] [org.gradle.api.internal.tasks.testing.report.DefaultTestReport] Generating HTML test report...
2023-07-25T17:54:06.457+0100 [DEBUG] [org.gradle.internal.operations.DefaultBuildOperationRunner] Build operation 'Delete old HTML results' completed
2023-07-25T17:54:06.460+0100 [DEBUG] [org.gradle.internal.operations.DefaultBuildOperationRunner] Build operation 'Generate HTML test report for Test Summary' completed
2023-07-25T17:54:06.460+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker: released lock on state of build :
2023-07-25T17:54:06.461+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker: released lock on worker lease
2023-07-25T17:54:06.468+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker: acquired lock on state of build :
2023-07-25T17:54:06.468+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker: acquired lock on worker lease
2023-07-25T17:54:06.469+0100 [INFO] [org.gradle.api.internal.tasks.testing.report.DefaultTestReport] Finished generating test html results (0.013 secs) into: /Users/sravenmedarapu/src/open_src/GradleTestNgScratchpad/build/reports/tests/test
2023-07-25T17:54:06.470+0100 [DEBUG] [org.gradle.internal.operations.DefaultBuildOperationRunner] Build operation 'Execute executeTests for :test' completed
2023-07-25T17:54:06.471+0100 [DEBUG] [org.gradle.internal.operations.DefaultBuildOperationRunner] Build operation 'Executing task ':test'' completed
2023-07-25T17:54:06.471+0100 [DEBUG] [org.gradle.internal.vfs.impl.AbstractVirtualFileSystem] Invalidating VFS paths: [/Users/sravenmedarapu/src/open_src/GradleTestNgScratchpad/build/test-results/test/binary, /Users/sravenmedarapu/src/open_src/GradleTestNgScratchpad/build/reports/tests/test, /Users/sravenmedarapu/src/open_src/GradleTestNgScratchpad/build/reports/tests/test, /Users/sravenmedarapu/src/open_src/GradleTestNgScratchpad/build/test-results/test, /Users/sravenmedarapu/src/open_src/GradleTestNgScratchpad/build/reports/tests/test]
2023-07-25T17:54:06.474+0100 [DEBUG] [org.gradle.internal.operations.DefaultBuildOperationRunner] Build operation 'Snapshot outputs after executing task ':test'' completed
2023-07-25T17:54:06.474+0100 [DEBUG] [org.gradle.api.internal.tasks.execution.ResolveTaskExecutionModeExecuter] Removed task artifact state for task ':test' from context.
2023-07-25T17:54:06.474+0100 [DEBUG] [org.gradle.internal.operations.DefaultBuildOperationRunner] Completing Build operation 'Task :test'
2023-07-25T17:54:06.475+0100 [DEBUG] [org.gradle.internal.operations.DefaultBuildOperationRunner] Build operation 'Task :test' completed
2023-07-25T17:54:06.475+0100 [DEBUG] [org.gradle.execution.plan.DefaultFinalizedExecutionPlan] Node :test completed, executed: true
2023-07-25T17:54:06.475+0100 [DEBUG] [org.gradle.execution.plan.DefaultFinalizedExecutionPlan] Node :test failed
2023-07-25T17:54:06.475+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker: released lock on state of build :
2023-07-25T17:54:06.475+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker: released lock on task execution for build :
2023-07-25T17:54:06.475+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker: released lock on worker lease
2023-07-25T17:54:06.475+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 13: acquired lock on worker lease
2023-07-25T17:54:06.475+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 13: released lock on worker lease
2023-07-25T17:54:06.476+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 15: acquired lock on worker lease
2023-07-25T17:54:06.476+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 15: released lock on worker lease
2023-07-25T17:54:06.476+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 12: acquired lock on worker lease
2023-07-25T17:54:06.476+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 12: released lock on worker lease
2023-07-25T17:54:06.476+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 11: acquired lock on worker lease
2023-07-25T17:54:06.476+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 11: released lock on worker lease
2023-07-25T17:54:06.476+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 10: acquired lock on worker lease
2023-07-25T17:54:06.476+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 10: released lock on worker lease
2023-07-25T17:54:06.476+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 8: acquired lock on worker lease
2023-07-25T17:54:06.477+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 8: released lock on worker lease
2023-07-25T17:54:06.477+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 7: acquired lock on worker lease
2023-07-25T17:54:06.477+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 7: released lock on worker lease
2023-07-25T17:54:06.477+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 6: acquired lock on worker lease
2023-07-25T17:54:06.477+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 6: released lock on worker lease
2023-07-25T17:54:06.477+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 5: acquired lock on worker lease
2023-07-25T17:54:06.477+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 5: released lock on worker lease
2023-07-25T17:54:06.477+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] included builds: acquired lock on worker lease
2023-07-25T17:54:06.477+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 14: acquired lock on worker lease
2023-07-25T17:54:06.478+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 14: released lock on worker lease
2023-07-25T17:54:06.480+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 4: acquired lock on worker lease
2023-07-25T17:54:06.480+0100 [DEBUG] [org.gradle.internal.operations.DefaultBuildOperationRunner] Completing Build operation 'Run tasks'
2023-07-25T17:54:06.480+0100 [DEBUG] [org.gradle.internal.operations.DefaultBuildOperationRunner] Build operation 'Run tasks' completed
2023-07-25T17:54:06.480+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 4: released lock on worker lease
2023-07-25T17:54:06.480+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 9: acquired lock on worker lease
2023-07-25T17:54:06.480+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 9: released lock on worker lease
2023-07-25T17:54:06.480+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 3: acquired lock on worker lease
2023-07-25T17:54:06.481+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 3: released lock on worker lease
2023-07-25T17:54:06.481+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 2: acquired lock on worker lease
2023-07-25T17:54:06.481+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 2: released lock on worker lease
2023-07-25T17:54:06.481+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] included builds: released lock on worker lease
2023-07-25T17:54:06.481+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker: acquired lock on worker lease
2023-07-25T17:54:06.481+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker: released lock on worker lease
2023-07-25T17:54:06.481+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Daemon worker Thread 5: acquired lock on worker lease
2023-07-25T17:54:06.481+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 2: acquired lock on worker lease
2023-07-25T17:54:06.481+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 2: released lock on worker lease
2023-07-25T17:54:06.482+0100 [DEBUG] [org.gradle.internal.operations.DefaultBuildOperationRunner] Completing Build operation 'Run main tasks'
2023-07-25T17:54:06.482+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 3: acquired lock on worker lease
2023-07-25T17:54:06.482+0100 [DEBUG] [org.gradle.internal.operations.DefaultBuildOperationRunner] Build operation 'Run main tasks' completed
2023-07-25T17:54:06.482+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 3: released lock on worker lease
2023-07-25T17:54:06.482+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 9: acquired lock on worker lease
2023-07-25T17:54:06.482+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 9: released lock on worker lease
2023-07-25T17:54:06.482+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 4: acquired lock on worker lease
2023-07-25T17:54:06.482+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 4: released lock on worker lease
2023-07-25T17:54:06.482+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 14: acquired lock on worker lease
2023-07-25T17:54:06.483+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 14: released lock on worker lease
2023-07-25T17:54:06.483+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 5: acquired lock on worker lease
2023-07-25T17:54:06.483+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 5: released lock on worker lease
2023-07-25T17:54:06.483+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 6: acquired lock on worker lease
2023-07-25T17:54:06.483+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 6: released lock on worker lease
2023-07-25T17:54:06.483+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 7: acquired lock on worker lease
2023-07-25T17:54:06.483+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 7: released lock on worker lease
2023-07-25T17:54:06.483+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 8: acquired lock on worker lease
2023-07-25T17:54:06.484+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 8: released lock on worker lease
2023-07-25T17:54:06.484+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 10: acquired lock on worker lease
2023-07-25T17:54:06.484+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 10: released lock on worker lease
2023-07-25T17:54:06.484+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 11: acquired lock on worker lease
2023-07-25T17:54:06.484+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 11: released lock on worker lease
2023-07-25T17:54:06.484+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 12: acquired lock on worker lease
2023-07-25T17:54:06.484+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 12: released lock on worker lease
2023-07-25T17:54:06.485+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 15: acquired lock on worker lease
2023-07-25T17:54:06.485+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 15: released lock on worker lease
2023-07-25T17:54:06.485+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 13: acquired lock on worker lease
2023-07-25T17:54:06.485+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 13: released lock on worker lease
2023-07-25T17:54:06.486+0100 [DEBUG] [org.jetbrains.kotlin.gradle.plugin.statistics.KotlinBuildStatHandler] Executing [class org.jetbrains.kotlin.gradle.plugin.statistics.KotlinBuildStatHandler.buildFinished]
2023-07-25T17:54:06.488+0100 [DEBUG] [org.gradle.internal.operations.DefaultBuildOperationRunner] Build operation 'Execute 'allprojects {}' action' started
2023-07-25T17:54:06.488+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Daemon worker Thread 5: acquired lock on state of build :
2023-07-25T17:54:06.488+0100 [DEBUG] [org.gradle.internal.operations.DefaultBuildOperationRunner] Build operation 'Cross-configure project :' started
2023-07-25T17:54:06.488+0100 [DEBUG] [org.gradle.internal.operations.DefaultBuildOperationRunner] Completing Build operation 'Cross-configure project :'
2023-07-25T17:54:06.488+0100 [DEBUG] [org.gradle.internal.operations.DefaultBuildOperationRunner] Build operation 'Cross-configure project :' completed
2023-07-25T17:54:06.488+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Daemon worker Thread 5: released lock on state of build :
2023-07-25T17:54:06.489+0100 [DEBUG] [org.gradle.internal.operations.DefaultBuildOperationRunner] Completing Build operation 'Execute 'allprojects {}' action'
2023-07-25T17:54:06.489+0100 [DEBUG] [org.gradle.internal.operations.DefaultBuildOperationRunner] Build operation 'Execute 'allprojects {}' action' completed
2023-07-25T17:54:06.489+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker: acquired lock on worker lease
2023-07-25T17:54:06.489+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker: released lock on worker lease
2023-07-25T17:54:06.489+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 13: acquired lock on worker lease
2023-07-25T17:54:06.489+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 13: released lock on worker lease
2023-07-25T17:54:06.489+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 15: acquired lock on worker lease
2023-07-25T17:54:06.490+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 15: released lock on worker lease
2023-07-25T17:54:06.490+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 12: acquired lock on worker lease
2023-07-25T17:54:06.490+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 12: released lock on worker lease
2023-07-25T17:54:06.490+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 11: acquired lock on worker lease
2023-07-25T17:54:06.490+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 11: released lock on worker lease
2023-07-25T17:54:06.490+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 10: acquired lock on worker lease
2023-07-25T17:54:06.490+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 10: released lock on worker lease
2023-07-25T17:54:06.490+0100 [DEBUG] [org.jetbrains.kotlin.gradle.plugin.KotlinGradleFinishBuildHandler] [KOTLIN] Deleted session-is-alive flag file: build/kotlin/sessions/kotlin-compiler-1680464928682447455.salive
2023-07-25T17:54:06.491+0100 [DEBUG] [org.jetbrains.kotlin.gradle.plugin.KotlinGradleBuildServices$Inject] [KOTLIN] Disposed KotlinGradleBuildServices
2023-07-25T17:54:06.491+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 8: acquired lock on worker lease
2023-07-25T17:54:06.491+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 8: released lock on worker lease
2023-07-25T17:54:06.491+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 7: acquired lock on worker lease
2023-07-25T17:54:06.491+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 7: released lock on worker lease
2023-07-25T17:54:06.491+0100 [ERROR] [org.gradle.internal.buildevents.BuildExceptionReporter] 
2023-07-25T17:54:06.492+0100 [ERROR] [org.gradle.internal.buildevents.BuildExceptionReporter] FAILURE: Build failed with an exception.
2023-07-25T17:54:06.492+0100 [ERROR] [org.gradle.internal.buildevents.BuildExceptionReporter] 
2023-07-25T17:54:06.492+0100 [ERROR] [org.gradle.internal.buildevents.BuildExceptionReporter] * What went wrong:
2023-07-25T17:54:06.492+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 6: acquired lock on worker lease
2023-07-25T17:54:06.492+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 6: released lock on worker lease
2023-07-25T17:54:06.492+0100 [ERROR] [org.gradle.internal.buildevents.BuildExceptionReporter] Execution failed for task ':test'.
2023-07-25T17:54:06.493+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 5: acquired lock on worker lease
2023-07-25T17:54:06.493+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 5: released lock on worker lease
2023-07-25T17:54:06.493+0100 [ERROR] [org.gradle.internal.buildevents.BuildExceptionReporter] > There were failing tests. See the report at: file:///Users/sravenmedarapu/src/open_src/GradleTestNgScratchpad/build/reports/tests/test/index.html
2023-07-25T17:54:06.493+0100 [ERROR] [org.gradle.internal.buildevents.BuildExceptionReporter] 
2023-07-25T17:54:06.494+0100 [ERROR] [org.gradle.internal.buildevents.BuildExceptionReporter] * Try:
2023-07-25T17:54:06.494+0100 [ERROR] [org.gradle.internal.buildevents.BuildExceptionReporter] > Run with --scan to get full insights.
2023-07-25T17:54:06.493+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 14: acquired lock on worker lease
2023-07-25T17:54:06.494+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 14: released lock on worker lease
2023-07-25T17:54:06.494+0100 [ERROR] [org.gradle.internal.buildevents.BuildResultLogger] 
2023-07-25T17:54:06.495+0100 [ERROR] [org.gradle.internal.buildevents.BuildResultLogger] BUILD FAILED in 3s
2023-07-25T17:54:06.495+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 4: acquired lock on worker lease
2023-07-25T17:54:06.495+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 4: released lock on worker lease
2023-07-25T17:54:06.495+0100 [LIFECYCLE] [org.gradle.internal.buildevents.TaskExecutionStatisticsReporter] 6 actionable tasks: 6 executed
2023-07-25T17:54:06.495+0100 [DEBUG] [org.gradle.internal.operations.DefaultBuildOperationRunner] Build operation 'Build finished for file system watching' started
2023-07-25T17:54:06.495+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 9: acquired lock on worker lease
2023-07-25T17:54:06.496+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 9: released lock on worker lease
2023-07-25T17:54:06.496+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 3: acquired lock on worker lease
2023-07-25T17:54:06.496+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 3: released lock on worker lease
2023-07-25T17:54:06.496+0100 [INFO] [org.gradle.internal.watch.registry.impl.HierarchicalFileWatcherUpdater] Watched directory hierarchies: [/Users/sravenmedarapu/src/open_src/GradleTestNgScratchpad]
2023-07-25T17:54:06.496+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 2: acquired lock on worker lease
2023-07-25T17:54:06.496+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Execution worker Thread 2: released lock on worker lease
2023-07-25T17:54:06.496+0100 [DEBUG] [org.gradle.internal.operations.DefaultBuildOperationRunner] Completing Build operation 'Build finished for file system watching'
2023-07-25T17:54:06.497+0100 [DEBUG] [org.gradle.internal.operations.DefaultBuildOperationRunner] Build operation 'Build finished for file system watching' completed
2023-07-25T17:54:06.498+0100 [DEBUG] [org.gradle.cache.internal.LockOnDemandCrossProcessCacheAccess] Releasing file lock for cache directory md-supplier (/Users/sravenmedarapu/.gradle/caches/8.2.1/md-supplier)
2023-07-25T17:54:06.498+0100 [DEBUG] [org.gradle.cache.internal.DefaultFileLockManager] Releasing lock on cache directory md-supplier (/Users/sravenmedarapu/.gradle/caches/8.2.1/md-supplier).
2023-07-25T17:54:06.499+0100 [DEBUG] [org.gradle.cache.internal.LockOnDemandCrossProcessCacheAccess] Releasing file lock for execution history cache (/Users/sravenmedarapu/src/open_src/GradleTestNgScratchpad/.gradle/8.2.1/executionHistory)
2023-07-25T17:54:06.499+0100 [DEBUG] [org.gradle.cache.internal.btree.BTreePersistentIndexedCache] Closing cache executionHistory.bin (/Users/sravenmedarapu/src/open_src/GradleTestNgScratchpad/.gradle/8.2.1/executionHistory/executionHistory.bin)
2023-07-25T17:54:06.499+0100 [DEBUG] [org.gradle.cache.internal.DefaultFileLockManager] Releasing lock on execution history cache (/Users/sravenmedarapu/src/open_src/GradleTestNgScratchpad/.gradle/8.2.1/executionHistory).
2023-07-25T17:54:06.500+0100 [DEBUG] [org.gradle.cache.internal.LockOnDemandCrossProcessCacheAccess] Releasing file lock for Build Output Cleanup Cache (/Users/sravenmedarapu/src/open_src/GradleTestNgScratchpad/.gradle/buildOutputCleanup)
2023-07-25T17:54:06.500+0100 [DEBUG] [org.gradle.cache.internal.btree.BTreePersistentIndexedCache] Closing cache outputFiles.bin (/Users/sravenmedarapu/src/open_src/GradleTestNgScratchpad/.gradle/buildOutputCleanup/outputFiles.bin)
2023-07-25T17:54:06.501+0100 [DEBUG] [org.gradle.cache.internal.DefaultFileLockManager] Releasing lock on Build Output Cleanup Cache (/Users/sravenmedarapu/src/open_src/GradleTestNgScratchpad/.gradle/buildOutputCleanup).
2023-07-25T17:54:06.501+0100 [DEBUG] [org.gradle.cache.internal.LockOnDemandCrossProcessCacheAccess] Releasing file lock for cache directory md-rule (/Users/sravenmedarapu/.gradle/caches/8.2.1/md-rule)
2023-07-25T17:54:06.501+0100 [DEBUG] [org.gradle.cache.internal.DefaultFileLockManager] Releasing lock on cache directory md-rule (/Users/sravenmedarapu/.gradle/caches/8.2.1/md-rule).
2023-07-25T17:54:06.504+0100 [DEBUG] [org.gradle.api.internal.artifacts.ivyservice.resolveengine.store.CachedStoreFactory] Resolution result cache closed. Cache reads: 0, disk reads: 0 (avg: 0.0 secs, total: 0.0 secs)
2023-07-25T17:54:06.505+0100 [DEBUG] [org.gradle.api.internal.artifacts.ivyservice.resolveengine.store.CachedStoreFactory] Resolution result cache closed. Cache reads: 0, disk reads: 0 (avg: 0.0 secs, total: 0.0 secs)
2023-07-25T17:54:06.506+0100 [DEBUG] [org.gradle.api.internal.artifacts.ivyservice.resolveengine.store.ResolutionResultsStoreFactory] Deleted 6 resolution results binary files in 0.001 secs
2023-07-25T17:54:06.507+0100 [DEBUG] [org.gradle.internal.operations.DefaultBuildOperationRunner] Completing Build operation 'Run build'
2023-07-25T17:54:06.507+0100 [DEBUG] [org.gradle.internal.operations.DefaultBuildOperationRunner] Build operation 'Run build' completed
2023-07-25T17:54:06.507+0100 [DEBUG] [org.gradle.internal.resources.AbstractTrackedResourceLock] Daemon worker Thread 5: released lock on worker lease
2023-07-25T17:54:06.512+0100 [DEBUG] [org.gradle.deployment.internal.DefaultDeploymentRegistry] Stopping 0 deployment handles
2023-07-25T17:54:06.512+0100 [DEBUG] [org.gradle.deployment.internal.DefaultDeploymentRegistry] Stopped deployment handles
2023-07-25T17:54:06.513+0100 [DEBUG] [org.gradle.cache.internal.LockOnDemandCrossProcessCacheAccess] Releasing file lock for checksums cache (/Users/sravenmedarapu/src/open_src/GradleTestNgScratchpad/.gradle/8.2.1/checksums)
2023-07-25T17:54:06.513+0100 [DEBUG] [org.gradle.cache.internal.DefaultFileLockManager] Releasing lock on checksums cache (/Users/sravenmedarapu/src/open_src/GradleTestNgScratchpad/.gradle/8.2.1/checksums).
2023-07-25T17:54:06.513+0100 [DEBUG] [org.gradle.cache.internal.LockOnDemandCrossProcessCacheAccess] Releasing file lock for file hash cache (/Users/sravenmedarapu/src/open_src/GradleTestNgScratchpad/.gradle/8.2.1/fileHashes)
2023-07-25T17:54:06.514+0100 [DEBUG] [org.gradle.cache.internal.btree.BTreePersistentIndexedCache] Closing cache fileHashes.bin (/Users/sravenmedarapu/src/open_src/GradleTestNgScratchpad/.gradle/8.2.1/fileHashes/fileHashes.bin)
2023-07-25T17:54:06.514+0100 [DEBUG] [org.gradle.cache.internal.DefaultFileLockManager] Releasing lock on file hash cache (/Users/sravenmedarapu/src/open_src/GradleTestNgScratchpad/.gradle/8.2.1/fileHashes).
2023-07-25T17:54:06.514+0100 [DEBUG] [org.gradle.cache.internal.DefaultPersistentDirectoryStore] VCS Checkout Cache (/Users/sravenmedarapu/src/open_src/GradleTestNgScratchpad/.gradle/vcs-1) has last been fully cleaned up 0 hours ago
2023-07-25T17:54:06.515+0100 [DEBUG] [org.gradle.cache.internal.DefaultCacheCoordinator] Cache VCS Checkout Cache (/Users/sravenmedarapu/src/open_src/GradleTestNgScratchpad/.gradle/vcs-1) was closed 0 times.
2023-07-25T17:54:06.515+0100 [DEBUG] [org.gradle.cache.internal.DefaultCacheCoordinator] Cache VCS metadata (/Users/sravenmedarapu/src/open_src/GradleTestNgScratchpad/.gradle/8.2.1/vcsMetadata) was closed 0 times.
2023-07-25T17:54:06.515+0100 [DEBUG] [org.gradle.cache.internal.LockOnDemandCrossProcessCacheAccess] Releasing file lock for dependencies-accessors (/Users/sravenmedarapu/src/open_src/GradleTestNgScratchpad/.gradle/8.2.1/dependencies-accessors)
2023-07-25T17:54:06.515+0100 [DEBUG] [org.gradle.cache.internal.DefaultFileLockManager] Releasing lock on dependencies-accessors (/Users/sravenmedarapu/src/open_src/GradleTestNgScratchpad/.gradle/8.2.1/dependencies-accessors).
2023-07-25T17:54:06.516+0100 [DEBUG] [org.gradle.cache.internal.DefaultPersistentDirectoryStore] dependencies-accessors (/Users/sravenmedarapu/src/open_src/GradleTestNgScratchpad/.gradle/8.2.1/dependencies-accessors) has last been fully cleaned up 0 hours ago
2023-07-25T17:54:06.520+0100 [DEBUG] [org.gradle.launcher.daemon.server.exec.ExecuteBuild] The daemon has finished executing the build.
2023-07-25T17:54:06.602+0100 [DEBUG] [org.gradle.launcher.daemon.client.DaemonClientInputForwarder] Dispatching close input message: org.gradle.launcher.daemon.protocol.CloseInput@7f58ad44
2023-07-25T17:54:06.603+0100 [DEBUG] [org.gradle.launcher.daemon.client.DaemonClientConnection] thread 24: dispatching class org.gradle.launcher.daemon.protocol.CloseInput
2023-07-25T17:54:06.604+0100 [DEBUG] [org.gradle.launcher.daemon.client.DaemonClient] Received result Success[value=org.gradle.launcher.exec.BuildActionResult@2dbf4cbd] from daemon DaemonInfo{pid=54378, address=[6f2e4a6b-1b3b-46f4-9321-d4b42edd2857 port:49890, addresses:[/127.0.0.1]], state=Idle, lastBusy=1690303947105, context=DefaultDaemonContext[uid=a34d55be-f9ca-4a38-b319-4cdbcc86da52,javaHome=/Library/Java/JavaVirtualMachines/amazon-corretto-11.jdk/Contents/Home,daemonRegistryDir=/Users/sravenmedarapu/.gradle/daemon,pid=54378,idleTimeout=10800000,priority=NORMAL,applyInstrumentationAgent=true,daemonOpts=--add-opens=java.base/java.util=ALL-UNNAMED,--add-opens=java.base/java.lang=ALL-UNNAMED,--add-opens=java.base/java.lang.invoke=ALL-UNNAMED,--add-opens=java.prefs/java.util.prefs=ALL-UNNAMED,--add-opens=java.base/java.nio.charset=ALL-UNNAMED,--add-opens=java.base/java.net=ALL-UNNAMED,--add-opens=java.base/java.util.concurrent.atomic=ALL-UNNAMED,-XX:MaxMetaspaceSize=384m,-XX:+HeapDumpOnOutOfMemoryError,-Xms256m,-Xmx512m,-Dfile.encoding=UTF-8,-Duser.country=GB,-Duser.language=en,-Duser.variant]} (build should be done).
2023-07-25T17:54:06.604+0100 [DEBUG] [org.gradle.launcher.daemon.client.DaemonClientConnection] thread 1: dispatching class org.gradle.launcher.daemon.protocol.Finished
2023-07-25T17:54:06.604+0100 [DEBUG] [org.gradle.launcher.daemon.client.DaemonClientConnection] thread 1: connection stop
2023-07-25T17:54:06.607+0100 [LIFECYCLE] [org.gradle.launcher.cli.DebugLoggerWarningAction] 
#############################################################################
   WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING WARNING

   Debug level logging will leak security sensitive information!

   For more details, please refer to https://docs.gradle.org/8.2.1/userguide/logging.html#sec:debug_security in the Gradle documentation.
#############################################################################

Sample project reference

@ov7a
Copy link
Member

ov7a commented Jul 31, 2023

This issue needs a decision from the team responsible for that area. They have been informed. Response time may vary.


JVM team, can/should we do something around here in order to make these early-stage exceptions to be more visible for the user? It would be nice to have consistent behavior with JUnit, but we don't know if JUnit behaves the same or not.

@ov7a ov7a added 👋 team-triage Issues that need to be triaged by a specific team and removed to-triage labels Jul 31, 2023
@jvandort jvandort added in:testing a:bug in:testing-testng and removed in:testing-testng 👋 team-triage Issues that need to be triaged by a specific team in:testing a:feature A new functionality labels Aug 1, 2023
@jvandort jvandort assigned ghale and unassigned ghale Aug 1, 2023
@jvandort
Copy link
Member

jvandort commented Aug 1, 2023

It seems we have an API to solve this, however after testing it does not appear to work with TestNG. For context, the below code would solve this issue for JUnit Jupiter. We have added this issue to our backlog, specifically to fix this for Test NG.

tasks.test {
    testLogging {
        showExceptions = true
        exceptionFormat "full"
        showStackTraces true
    }
}

@sravanmedarapu
Copy link
Author

Out of curiosity, do we have any estimated timelines for this task, @jvandort ?

@ghale ghale self-assigned this Aug 21, 2023
@ghale ghale added this to the 8.4 RC1 milestone Aug 21, 2023
@ghale
Copy link
Member

ghale commented Aug 21, 2023

@sravanmedarapu We're actively looking into this and we're targeting either 8.4 or 8.5 for a fix.

@ghale
Copy link
Member

ghale commented Aug 24, 2023

@sravanmedarapu After looking into this deeper, it's currently possible to get these errors by setting the granularity in the test logging to a value 1 or lower.

tasks.withType(Test).configureEach {
    testLogging {
        info {
            minGranularity = 1
            exceptionFormat = "full"
        }
    }
}

The granularity setting controls what test events are actually logged to the console. The default is to only log events to the console that are associated with individual test executions. Since this is an error associated with the test framework initialization, it is filtered out by default. By setting the minGranularity to 1, you're essentially saying show all events associated with test methods, test classes, and the test process initialization.

Having said that, there was an error associated with formatting exceptions coming from test events not associated with test classes. So this issue will be fixed in 8.4 and at that point, setting the granularity should work for the specific error you were seeing.

Longer term, this concept of granularity is a little difficult to reason about and dates back to ancient code from 2012 (Gradle 1.x I think). We should revisit test logging and think about how we can make this work more intuitively and capture these sorts of errors without having to fiddle with the configuration. However, that will require some thinking and might involve breaking changes. So a more fundamental improvement for this will likely come on a longer timeline.

@sravanmedarapu-bumble
Copy link

@ghale thanks for the update, out of curiosity when can we expect the next release with #26169 changes?

@ov7a
Copy link
Member

ov7a commented Aug 31, 2023

@sravanmedarapu the 8.4 RC1 is scheduled on September 11th and 8.4 GA should happen on September 18th. Note that these dates aren't set in stone and might be changed.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
Development

Successfully merging a pull request may close this issue.

6 participants