jmeter-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From sebb <seb...@gmail.com>
Subject Re: Build failed in Jenkins: JMeter Ubuntu #929
Date Sun, 03 Nov 2019 11:17:44 GMT
The log output from a test run is unnecessarily hard to debug, because:
- the log does not give the source line number of the failure
- the test itself uses the same text for various different errors.

Whilst it would in theory be possible to change all test error
messages so that they are unique within a test class, that seems like
a lot of effort, and it would be ongoing.

So is there a way to show the line number in the test log?

AFAICT there is no way to find the exact error line from the console log alone.

I eventually found the line (*) by finding the pointer to the test
results in the log.
Then navigate to the test results file in the workspace and open the
failure link.
That is very tedious, and the workspace file is replaced after every
run so it may well have disappeared by the time anyone has time to
investigate the error.

In this case, I think the error may be due to a timing issue, though
it's not immediately obvious what the test is trying to check.

Sebb.
(*) org.apache.jmeter.protocol.http.control.TestCacheManagerBase.testPrivateCache(TestCacheManagerBase.java:264)

On Sun, 3 Nov 2019 at 07:42, Apache Jenkins Server
<jenkins@builds.apache.org> wrote:
>
> See <https://builds.apache.org/job/JMeter%20Ubuntu/929/display/redirect?page=changes>
>
> Changes:
>
> [sebb] temporary test code
>
> [sebb] Add header
>
> [sebb] Pacify checkstyle
>
> [sebb] Drop test code
>
>
> ------------------------------------------
> [...truncated 171.47 KB...]
> Starting standalone test @ Sun Nov 03 07:42:10 UTC 2019 (1572766930316)
> Waiting for possible Shutdown/StopTestNow/HeapDump/ThreadDump message on port 4445
>
> > Task :src:protocol:http:checkstyleTest
> > Task :src:protocol:http:spotbugsMain SKIPPED
> > Task :src:protocol:http:spotbugsTest SKIPPED
>
> > Task :src:functions:test
>
> org.apache.jmeter.functions.gui.FunctionHelperSpec > construct correct call string
for parameters #parameters: SKIPPED
>
> > Task :src:functions:check
>
> > Task :src:protocol:http:test
>
> Gradle Test Executor 18 STANDARD_OUT
>     Setting JMeterHome: <https://builds.apache.org/job/JMeter%20Ubuntu/ws/src/protocol/http/../../../.>
>     Initializing Properties: ../../.././bin/jmeter.properties
>     JMeterVersion=5.2-SNAPSHOT 4758742
>     java.version=1.8.0_191
>     java.vm.name=Java HotSpot(TM) 64-Bit Server VM
>     java.vendor=Oracle Corporation
>     java.home=/usr/local/asfpackages/java/jdk1.8.0_191/jre
>     file.encoding=ISO-8859-1
>     default encoding=ISO-8859-1
>     user.home=/home/jenkins
>     user.dir=<https://builds.apache.org/job/JMeter%20Ubuntu/ws/src/protocol/http>
>     user.language=en
>     user.region=null
>     user.country=US
>     user.variant=
>     Locale=en_US
>     java.class.version=52.0
>     java.awt.headless=true
>     os.name=Linux
>     os.version=4.15.0-66-generic
>     os.arch=amd64
>
> org.apache.jmeter.protocol.http.control.TestCacheManagerUrlConnection > testPrivateCache():
FAILURE
>
> org.apache.jmeter.protocol.http.control.TestCacheManagerUrlConnection > testPrivateCache()
FAILED
>     org.opentest4j.AssertionFailedError: Should not find valid entry ==> expected:
<false> but was: <true>
>
> > Task :src:dist-check:batchBatchTestLocal
> summary =    132 in 00:00:06 =   22.4/s Avg:     5 Min:     1 Max:   101 Err:    16 (12.12%)
> Tidying up ...    @ Sun Nov 03 07:42:16 UTC 2019 (1572766936753)
> ... end of run
> No errors present in the logfile <https://builds.apache.org/job/JMeter%20Ubuntu/ws/bin/BatchTestLocal.log>
(the file is empty)
>
> > Task :src:protocol:http:test
>
> org.apache.jmeter.protocol.http.control.TestCacheManagerHC4 > testNoCacheControlNoMaxAgeNoExpire():
FAILURE
>
> org.apache.jmeter.protocol.http.control.TestCacheManagerHC4 > testNoCacheControlNoMaxAgeNoExpire()
FAILED
>     org.opentest4j.AssertionFailedError: Should not find valid entry ==> expected:
<false> but was: <true>
>
> > Task :src:dist-check:batchBug50898
> Creating summariser <summary>
> Created the tree successfully using <https://builds.apache.org/job/JMeter%20Ubuntu/ws/bin/testfiles/Bug50898.jmx>
> Starting standalone test @ Sun Nov 03 07:42:19 UTC 2019 (1572766939289)
> Waiting for possible Shutdown/StopTestNow/HeapDump/ThreadDump message on port 4445
> summary =      2 in 00:00:00 =   11.3/s Avg:     6 Min:     6 Max:     7 Err:     0 (0.00%)
> Tidying up ...    @ Sun Nov 03 07:42:20 UTC 2019 (1572766940223)
> ... end of run
> No errors present in the logfile <https://builds.apache.org/job/JMeter%20Ubuntu/ws/bin/Bug50898.log>
(the file is empty)
>
> > Task :src:dist-check:batchBug52968
> Creating summariser <summary>
> Created the tree successfully using <https://builds.apache.org/job/JMeter%20Ubuntu/ws/bin/testfiles/Bug52968.jmx>
> Starting standalone test @ Sun Nov 03 07:42:22 UTC 2019 (1572766942857)
> Waiting for possible Shutdown/StopTestNow/HeapDump/ThreadDump message on port 4445
> summary =     30 in 00:00:01 =   49.7/s Avg:     6 Min:     5 Max:     8 Err:     9 (30.00%)
> Tidying up ...    @ Sun Nov 03 07:42:24 UTC 2019 (1572766944219)
> ... end of run
> No errors present in the logfile <https://builds.apache.org/job/JMeter%20Ubuntu/ws/bin/Bug52968.log>
(the file is empty)
>
> > Task :src:dist-check:batchBug54685
> Creating summariser <summary>
> Created the tree successfully using <https://builds.apache.org/job/JMeter%20Ubuntu/ws/bin/testfiles/Bug54685.jmx>
> Starting standalone test @ Sun Nov 03 07:42:26 UTC 2019 (1572766946831)
> Waiting for possible Shutdown/StopTestNow/HeapDump/ThreadDump message on port 4445
> summary =      1 in 00:00:00 =   10.2/s Avg:     5 Min:     5 Max:     5 Err:     0 (0.00%)
> Tidying up ...    @ Sun Nov 03 07:42:27 UTC 2019 (1572766947660)
> ... end of run
> No errors present in the logfile <https://builds.apache.org/job/JMeter%20Ubuntu/ws/bin/Bug54685.log>
(the file is empty)
>
> > Task :src:protocol:http:test
>
> org.apache.jmeter.protocol.http.control.DnsManagerTest > badDnsInCustomResolverShouldFailHttpSampler(String,
WireMockServer)[2] [[2] Java]: SKIPPED
>
> org.apache.jmeter.protocol.http.sampler.SamplingNamingTest > testBug63364[Run 0: implementation:HttpClient4]:
SKIPPED
>
> org.apache.jmeter.protocol.http.sampler.SamplingNamingTest > testBug63364[Run 1: implementation:Java]:
SKIPPED
>
> > Task :src:dist-check:batchBug55375
> Creating summariser <summary>
> Created the tree successfully using <https://builds.apache.org/job/JMeter%20Ubuntu/ws/bin/testfiles/Bug55375.jmx>
> Starting standalone test @ Sun Nov 03 07:42:30 UTC 2019 (1572766950247)
> Waiting for possible Shutdown/StopTestNow/HeapDump/ThreadDump message on port 4445
> summary +      1 in 00:00:00 =    9.2/s Avg:     7 Min:     7 Max:     7 Err:     0 (0.00%)
Active: 1 Started: 1 Finished: 0
> summary =      1 in 00:00:00 =    9.1/s Avg:     7 Min:     7 Max:     7 Err:     0 (0.00%)
> Tidying up ...    @ Sun Nov 03 07:42:31 UTC 2019 (1572766951097)
> ... end of run
> No errors present in the logfile <https://builds.apache.org/job/JMeter%20Ubuntu/ws/bin/Bug55375.log>
(the file is empty)
>
> > Task :src:protocol:http:test FAILED
>
> 629 tests completed, 2 failed, 3 skipped
>
> > Task :src:dist-check:batchBug56243
> Creating summariser <summary>
> Created the tree successfully using <https://builds.apache.org/job/JMeter%20Ubuntu/ws/bin/testfiles/Bug56243.jmx>
> Starting standalone test @ Sun Nov 03 07:42:33 UTC 2019 (1572766953764)
> Waiting for possible Shutdown/StopTestNow/HeapDump/ThreadDump message on port 4445
> summary +      1 in 00:00:00 =    6.0/s Avg:     7 Min:     7 Max:     7 Err:     0 (0.00%)
Active: 1 Started: 1 Finished: 0
> summary +      8 in 00:00:00 =  210.5/s Avg:     0 Min:     0 Max:     1 Err:     0 (0.00%)
Active: 0 Started: 1 Finished: 1
> summary =      9 in 00:00:00 =   43.3/s Avg:     1 Min:     0 Max:     7 Err:     0 (0.00%)
> Tidying up ...    @ Sun Nov 03 07:42:34 UTC 2019 (1572766954674)
> ... end of run
> No errors present in the logfile <https://builds.apache.org/job/JMeter%20Ubuntu/ws/bin/Bug56243.log>
(the file is empty)
> checksum-dependency elapsed time: 28250ms, configurations processed: 261
>     SHA-512 computation time: 1755ms (goes in parallel, it might exceed wall-clock time),
files processed: 50, processed: 7MiB, skipped: 3126MiB
>     PGP signature resolution time: 5611ms (wall-clock), resolution requests: 155, signatures
resolved: 225
>     PGP key resolution time: 16519ms (wall-clock), resolution requests: 155, download
time: 0ms (goes in parallel, it might exceed wall-clock time), keys downloaded: 0
>         PGP signature verification time: 47722ms (goes in parallel, it might exceed wall-clock
time), files processed: 685, processed: 613MiB, skipped: 2492MiB
>
> FAILURE: Build failed with an exception.
>
> * What went wrong:
> Execution failed for task ':src:protocol:http:test'.
> > There were failing tests. See the report at: file:///home/jenkins/jenkins-slave/workspace/JMeter%20Ubuntu/src/protocol/http/build/reports/tests/test/index.html
>
> * Try:
> Run with --info or --debug option to get more log output. Run with --scan to get full
insights.
>
> * Exception is:
> org.gradle.api.tasks.TaskExecutionException: Execution failed for task ':src:protocol:http:test'.
>         at org.gradle.api.internal.tasks.execution.ExecuteActionsTaskExecuter$3.accept(ExecuteActionsTaskExecuter.java:166)
>         at org.gradle.api.internal.tasks.execution.ExecuteActionsTaskExecuter$3.accept(ExecuteActionsTaskExecuter.java:163)
>         at org.gradle.internal.Try$Failure.ifSuccessfulOrElse(Try.java:191)
>         at org.gradle.api.internal.tasks.execution.ExecuteActionsTaskExecuter.execute(ExecuteActionsTaskExecuter.java:156)
>         at org.gradle.api.internal.tasks.execution.ValidatingTaskExecuter.execute(ValidatingTaskExecuter.java:62)
>         at org.gradle.api.internal.tasks.execution.SkipEmptySourceFilesTaskExecuter.execute(SkipEmptySourceFilesTaskExecuter.java:108)
>         at org.gradle.api.internal.tasks.execution.ResolveBeforeExecutionOutputsTaskExecuter.execute(ResolveBeforeExecutionOutputsTaskExecuter.java:67)
>         at org.gradle.api.internal.tasks.execution.ResolveAfterPreviousExecutionStateTaskExecuter.execute(ResolveAfterPreviousExecutionStateTaskExecuter.java:46)
>         at org.gradle.api.internal.tasks.execution.CleanupStaleOutputsExecuter.execute(CleanupStaleOutputsExecuter.java:94)
>         at org.gradle.api.internal.tasks.execution.FinalizePropertiesTaskExecuter.execute(FinalizePropertiesTaskExecuter.java:46)
>         at org.gradle.api.internal.tasks.execution.ResolveTaskExecutionModeExecuter.execute(ResolveTaskExecutionModeExecuter.java:95)
>         at org.gradle.api.internal.tasks.execution.SkipTaskWithNoActionsExecuter.execute(SkipTaskWithNoActionsExecuter.java:57)
>         at org.gradle.api.internal.tasks.execution.SkipOnlyIfTaskExecuter.execute(SkipOnlyIfTaskExecuter.java:56)
>         at org.gradle.api.internal.tasks.execution.CatchExceptionTaskExecuter.execute(CatchExceptionTaskExecuter.java:36)
>         at org.gradle.api.internal.tasks.execution.EventFiringTaskExecuter$1.executeTask(EventFiringTaskExecuter.java:77)
>         at org.gradle.api.internal.tasks.execution.EventFiringTaskExecuter$1.call(EventFiringTaskExecuter.java:55)
>         at org.gradle.api.internal.tasks.execution.EventFiringTaskExecuter$1.call(EventFiringTaskExecuter.java:52)
>         at org.gradle.internal.operations.DefaultBuildOperationExecutor$CallableBuildOperationWorker.execute(DefaultBuildOperationExecutor.java:416)
>         at org.gradle.internal.operations.DefaultBuildOperationExecutor$CallableBuildOperationWorker.execute(DefaultBuildOperationExecutor.java:406)
>         at org.gradle.internal.operations.DefaultBuildOperationExecutor$1.execute(DefaultBuildOperationExecutor.java:165)
>         at org.gradle.internal.operations.DefaultBuildOperationExecutor.execute(DefaultBuildOperationExecutor.java:250)
>         at org.gradle.internal.operations.DefaultBuildOperationExecutor.execute(DefaultBuildOperationExecutor.java:158)
>         at org.gradle.internal.operations.DefaultBuildOperationExecutor.call(DefaultBuildOperationExecutor.java:102)
>         at org.gradle.internal.operations.DelegatingBuildOperationExecutor.call(DelegatingBuildOperationExecutor.java:36)
>         at org.gradle.api.internal.tasks.execution.EventFiringTaskExecuter.execute(EventFiringTaskExecuter.java:52)
>         at org.gradle.execution.plan.LocalTaskNodeExecutor.execute(LocalTaskNodeExecutor.java:43)
>         at org.gradle.execution.taskgraph.DefaultTaskExecutionGraph$InvokeNodeExecutorsAction.execute(DefaultTaskExecutionGraph.java:355)
>         at org.gradle.execution.taskgraph.DefaultTaskExecutionGraph$InvokeNodeExecutorsAction.execute(DefaultTaskExecutionGraph.java:343)
>         at org.gradle.execution.taskgraph.DefaultTaskExecutionGraph$BuildOperationAwareExecutionAction.execute(DefaultTaskExecutionGraph.java:336)
>         at org.gradle.execution.taskgraph.DefaultTaskExecutionGraph$BuildOperationAwareExecutionAction.execute(DefaultTaskExecutionGraph.java:322)
>         at org.gradle.execution.plan.DefaultPlanExecutor$ExecutorWorker$1.execute(DefaultPlanExecutor.java:134)
>         at org.gradle.execution.plan.DefaultPlanExecutor$ExecutorWorker$1.execute(DefaultPlanExecutor.java:129)
>         at org.gradle.execution.plan.DefaultPlanExecutor$ExecutorWorker.execute(DefaultPlanExecutor.java:202)
>         at org.gradle.execution.plan.DefaultPlanExecutor$ExecutorWorker.executeNextNode(DefaultPlanExecutor.java:193)
>         at org.gradle.execution.plan.DefaultPlanExecutor$ExecutorWorker.run(DefaultPlanExecutor.java:129)
>         at org.gradle.internal.concurrent.ExecutorPolicy$CatchAndRecordFailures.onExecute(ExecutorPolicy.java:64)
>         at org.gradle.internal.concurrent.ManagedExecutorImpl$1.run(ManagedExecutorImpl.java:48)
>         at org.gradle.internal.concurrent.ThreadFactoryImpl$ManagedThreadRunnable.run(ThreadFactoryImpl.java:56)
> Caused by: org.gradle.api.GradleException: There were failing tests. See the report at:
file:///home/jenkins/jenkins-slave/workspace/JMeter%20Ubuntu/src/protocol/http/build/reports/tests/test/index.html
>         at org.gradle.api.tasks.testing.AbstractTestTask.handleTestFailures(AbstractTestTask.java:632)
>         at org.gradle.api.tasks.testing.AbstractTestTask.executeTests(AbstractTestTask.java:501)
>         at org.gradle.api.tasks.testing.Test.executeTests(Test.java:613)
>         at org.gradle.internal.reflect.JavaMethod.invoke(JavaMethod.java:103)
>         at org.gradle.api.internal.project.taskfactory.StandardTaskAction.doExecute(StandardTaskAction.java:49)
>         at org.gradle.api.internal.project.taskfactory.StandardTaskAction.execute(StandardTaskAction.java:42)
>         at org.gradle.api.internal.project.taskfactory.StandardTaskAction.execute(StandardTaskAction.java:28)
>         at org.gradle.api.internal.AbstractTask$TaskActionWrapper.execute(AbstractTask.java:717)
>         at org.gradle.api.internal.AbstractTask$TaskActionWrapper.execute(AbstractTask.java:684)
>         at org.gradle.api.internal.tasks.execution.ExecuteActionsTaskExecuter$5.run(ExecuteActionsTaskExecuter.java:476)
>         at org.gradle.internal.operations.DefaultBuildOperationExecutor$RunnableBuildOperationWorker.execute(DefaultBuildOperationExecutor.java:402)
>         at org.gradle.internal.operations.DefaultBuildOperationExecutor$RunnableBuildOperationWorker.execute(DefaultBuildOperationExecutor.java:394)
>         at org.gradle.internal.operations.DefaultBuildOperationExecutor$1.execute(DefaultBuildOperationExecutor.java:165)
>         at org.gradle.internal.operations.DefaultBuildOperationExecutor.execute(DefaultBuildOperationExecutor.java:250)
>         at org.gradle.internal.operations.DefaultBuildOperationExecutor.execute(DefaultBuildOperationExecutor.java:158)
>         at org.gradle.internal.operations.DefaultBuildOperationExecutor.run(DefaultBuildOperationExecutor.java:92)
>         at org.gradle.internal.operations.DelegatingBuildOperationExecutor.run(DelegatingBuildOperationExecutor.java:31)
>         at org.gradle.api.internal.tasks.execution.ExecuteActionsTaskExecuter.executeAction(ExecuteActionsTaskExecuter.java:461)
>         at org.gradle.api.internal.tasks.execution.ExecuteActionsTaskExecuter.executeActions(ExecuteActionsTaskExecuter.java:444)
>         at org.gradle.api.internal.tasks.execution.ExecuteActionsTaskExecuter.access$200(ExecuteActionsTaskExecuter.java:93)
>         at org.gradle.api.internal.tasks.execution.ExecuteActionsTaskExecuter$TaskExecution.execute(ExecuteActionsTaskExecuter.java:237)
>         at org.gradle.internal.execution.steps.ExecuteStep.lambda$execute$1(ExecuteStep.java:33)
>         at org.gradle.internal.execution.steps.ExecuteStep.execute(ExecuteStep.java:33)
>         at org.gradle.internal.execution.steps.ExecuteStep.execute(ExecuteStep.java:26)
>         at org.gradle.internal.execution.steps.CleanupOutputsStep.execute(CleanupOutputsStep.java:58)
>         at org.gradle.internal.execution.steps.CleanupOutputsStep.execute(CleanupOutputsStep.java:35)
>         at org.gradle.internal.execution.steps.ResolveInputChangesStep.execute(ResolveInputChangesStep.java:48)
>         at org.gradle.internal.execution.steps.ResolveInputChangesStep.execute(ResolveInputChangesStep.java:33)
>         at org.gradle.internal.execution.steps.CancelExecutionStep.execute(CancelExecutionStep.java:39)
>         at org.gradle.internal.execution.steps.TimeoutStep.executeWithoutTimeout(TimeoutStep.java:73)
>         at org.gradle.internal.execution.steps.TimeoutStep.execute(TimeoutStep.java:54)
>         at org.gradle.internal.execution.steps.CatchExceptionStep.execute(CatchExceptionStep.java:35)
>         at org.gradle.internal.execution.steps.CreateOutputsStep.execute(CreateOutputsStep.java:51)
>         at org.gradle.internal.execution.steps.SnapshotOutputsStep.execute(SnapshotOutputsStep.java:45)
>         at org.gradle.internal.execution.steps.SnapshotOutputsStep.execute(SnapshotOutputsStep.java:31)
>         at org.gradle.internal.execution.steps.CacheStep.executeWithoutCache(CacheStep.java:208)
>         at org.gradle.internal.execution.steps.CacheStep.execute(CacheStep.java:70)
>         at org.gradle.internal.execution.steps.CacheStep.execute(CacheStep.java:45)
>         at org.gradle.internal.execution.steps.BroadcastChangingOutputsStep.execute(BroadcastChangingOutputsStep.java:49)
>         at org.gradle.internal.execution.steps.StoreSnapshotsStep.execute(StoreSnapshotsStep.java:43)
>         at org.gradle.internal.execution.steps.StoreSnapshotsStep.execute(StoreSnapshotsStep.java:32)
>         at org.gradle.internal.execution.steps.RecordOutputsStep.execute(RecordOutputsStep.java:38)
>         at org.gradle.internal.execution.steps.RecordOutputsStep.execute(RecordOutputsStep.java:24)
>         at org.gradle.internal.execution.steps.SkipUpToDateStep.executeBecause(SkipUpToDateStep.java:96)
>         at org.gradle.internal.execution.steps.SkipUpToDateStep.lambda$execute$0(SkipUpToDateStep.java:89)
>         at org.gradle.internal.execution.steps.SkipUpToDateStep.execute(SkipUpToDateStep.java:54)
>         at org.gradle.internal.execution.steps.SkipUpToDateStep.execute(SkipUpToDateStep.java:38)
>         at org.gradle.internal.execution.steps.ResolveChangesStep.execute(ResolveChangesStep.java:76)
>         at org.gradle.internal.execution.steps.ResolveChangesStep.execute(ResolveChangesStep.java:37)
>         at org.gradle.internal.execution.steps.legacy.MarkSnapshottingInputsFinishedStep.execute(MarkSnapshottingInputsFinishedStep.java:36)
>         at org.gradle.internal.execution.steps.legacy.MarkSnapshottingInputsFinishedStep.execute(MarkSnapshottingInputsFinishedStep.java:26)
>         at org.gradle.internal.execution.steps.ResolveCachingStateStep.execute(ResolveCachingStateStep.java:90)
>         at org.gradle.internal.execution.steps.ResolveCachingStateStep.execute(ResolveCachingStateStep.java:48)
>         at org.gradle.internal.execution.steps.CaptureStateBeforeExecutionStep.execute(CaptureStateBeforeExecutionStep.java:69)
>         at org.gradle.internal.execution.steps.CaptureStateBeforeExecutionStep.execute(CaptureStateBeforeExecutionStep.java:47)
>         at org.gradle.internal.execution.impl.DefaultWorkExecutor.execute(DefaultWorkExecutor.java:33)
>         at org.gradle.api.internal.tasks.execution.ExecuteActionsTaskExecuter.execute(ExecuteActionsTaskExecuter.java:140)
>         ... 34 more
>
>
> * Get more help at https://help.gradle.org
>
> Deprecated Gradle features were used in this build, making it incompatible with Gradle
6.0.
> Use '--warning-mode all' to show the individual deprecation warnings.
> See https://docs.gradle.org/5.6.2/userguide/command_line_interface.html#sec:command_line_warnings
>
> BUILD FAILED in 2m 3s
> 248 actionable tasks: 145 executed, 103 up-to-date
> Build step 'Invoke Gradle script' changed build result to FAILURE
> Build step 'Invoke Gradle script' marked build as failure
> WARN: Unable to locate 'report-task.txt' in the workspace. Did the SonarScanner succeeded?
> [locks-and-latches] Releasing all the locks
> [locks-and-latches] All the locks released

Mime
View raw message