[
https://issues.apache.org/jira/browse/BEAM-4136?focusedWorklogId=511649&page=com.atlassian.jira.plugin.system.issuetabpanels:worklog-tabpanel#worklog-511649
]
ASF GitHub Bot logged work on BEAM-4136:
----------------------------------------
Author: ASF GitHub Bot
Created on: 14/Nov/20 01:44
Start Date: 14/Nov/20 01:44
Worklog Time Spent: 10m
Work Description: TheNeuralBit commented on a change in pull request
#13345:
URL: https://github.com/apache/beam/pull/13345#discussion_r523336104
##########
File path:
sdks/java/harness/src/test/java/org/apache/beam/fn/harness/logging/BeamFnLoggingClientTest.java
##########
@@ -146,20 +147,25 @@ public void testLogging() throws Exception {
apiServiceDescriptor,
(Endpoints.ApiServiceDescriptor descriptor) -> channel);
+ // Keep a strong reference to the loggers in this block. Otherwise the
call to client.close()
+ // removes the only reference and the logger may get GC'd (BEAM-4136).
+ Logger rootLogger = LogManager.getLogManager().getLogger("");
+ Logger configuredLogger =
LogManager.getLogManager().getLogger("ConfiguredLogger");
+
// Ensure that log levels were correctly set.
- assertEquals(Level.OFF,
LogManager.getLogManager().getLogger("").getLevel());
- assertEquals(Level.FINE,
LogManager.getLogManager().getLogger("ConfiguredLogger").getLevel());
+ assertEquals(Level.OFF, rootLogger.getLevel());
+ assertEquals(Level.FINE, configuredLogger.getLevel());
// Should be filtered because the default log level override is OFF
- LogManager.getLogManager().getLogger("").log(FILTERED_RECORD);
+ rootLogger.log(FILTERED_RECORD);
// Should not be filtered because the default log level override for
ConfiguredLogger is DEBUG
-
LogManager.getLogManager().getLogger("ConfiguredLogger").log(TEST_RECORD);
-
LogManager.getLogManager().getLogger("ConfiguredLogger").log(TEST_RECORD_WITH_EXCEPTION);
+ configuredLogger.log(TEST_RECORD);
+ configuredLogger.log(TEST_RECORD_WITH_EXCEPTION);
client.close();
// Verify that after close, log levels are reset.
- assertEquals(Level.INFO,
LogManager.getLogManager().getLogger("").getLevel());
-
assertNull(LogManager.getLogManager().getLogger("ConfiguredLogger").getLevel());
+ assertEquals(Level.INFO, rootLogger.getLevel());
+ assertNull(configuredLogger.getLevel());
assertTrue(clientClosedStream.get());
assertTrue(channel.isShutdown());
Review comment:
Out of time for today, but I just noticed this can happen in other tests
as well. For example in [this
run](https://github.com/apache/beam/pull/13128/checks?check_run_id=1398707923)
we see the same flake in `testWhenServerHangsUpEarlyThatClientIsAbleCleanup`
Leaving a note here so I remember to fix it later
----------------------------------------------------------------
This is an automated message from the Apache Git Service.
To respond to the message, please log on to GitHub and use the
URL above to go to the specific comment.
For queries about this service, please contact Infrastructure at:
[email protected]
Issue Time Tracking
-------------------
Worklog Id: (was: 511649)
Time Spent: 20m (was: 10m)
> org.apache.beam.fn.harness.logging.BeamFnLoggingClientTest.testLogging fails
> with NullPointerException
> ------------------------------------------------------------------------------------------------------
>
> Key: BEAM-4136
> URL: https://issues.apache.org/jira/browse/BEAM-4136
> Project: Beam
> Issue Type: Bug
> Components: sdk-java-harness
> Reporter: Etienne Chauchot
> Assignee: Brian Hulette
> Priority: P1
> Labels: Clarified, flake
> Time Spent: 20m
> Remaining Estimate: 0h
>
> java.lang.NullPointerException at
> org.apache.beam.fn.harness.logging.BeamFnLoggingClientTest.testLogging(BeamFnLoggingClientTest.java:158)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:498) at
> org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:50)
> at
> org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
> at
> org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:47)
> at
> org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
> at
> org.junit.rules.ExpectedException$ExpectedExceptionStatement.evaluate(ExpectedException.java:239)
> at org.junit.rules.RunRules.evaluate(RunRules.java:20) at
> org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:325) at
> org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:78)
> at
> org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:57)
> at org.junit.runners.ParentRunner$3.run(ParentRunner.java:290) at
> org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71) at
> org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288) at
> org.junit.runners.ParentRunner.access$000(ParentRunner.java:58) at
> org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268) at
> org.junit.runners.ParentRunner.run(ParentRunner.java:363) at
> org.gradle.api.internal.tasks.testing.junit.JUnitTestClassExecuter.runTestClass(JUnitTestClassExecuter.java:114)
> at
> org.gradle.api.internal.tasks.testing.junit.JUnitTestClassExecuter.execute(JUnitTestClassExecuter.java:57)
> at
> org.gradle.api.internal.tasks.testing.junit.JUnitTestClassProcessor.processTestClass(JUnitTestClassProcessor.java:66)
> at
> org.gradle.api.internal.tasks.testing.SuiteTestClassProcessor.processTestClass(SuiteTestClassProcessor.java:51)
> at sun.reflect.GeneratedMethodAccessor11.invoke(Unknown Source) at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:498) at
> org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:35)
> at
> org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24)
> at
> org.gradle.internal.dispatch.ContextClassLoaderDispatch.dispatch(ContextClassLoaderDispatch.java:32)
> at
> org.gradle.internal.dispatch.ProxyDispatchAdapter$DispatchingInvocationHandler.invoke(ProxyDispatchAdapter.java:93)
> at com.sun.proxy.$Proxy3.processTestClass(Unknown Source) at
> org.gradle.api.internal.tasks.testing.worker.TestWorker.processTestClass(TestWorker.java:108)
> at sun.reflect.GeneratedMethodAccessor10.invoke(Unknown Source) at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:498) at
> org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:35)
> at
> org.gradle.internal.dispatch.ReflectionDispatch.dispatch(ReflectionDispatch.java:24)
> at
> org.gradle.internal.remote.internal.hub.MessageHubBackedObjectConnection$DispatchWrapper.dispatch(MessageHubBackedObjectConnection.java:146)
> at
> org.gradle.internal.remote.internal.hub.MessageHubBackedObjectConnection$DispatchWrapper.dispatch(MessageHubBackedObjectConnection.java:128)
> at
> org.gradle.internal.remote.internal.hub.MessageHub$Handler.run(MessageHub.java:404)
> at
> org.gradle.internal.concurrent.ExecutorPolicy$CatchAndRecordFailures.onExecute(ExecutorPolicy.java:63)
> at
> org.gradle.internal.concurrent.ManagedExecutorImpl$1.run(ManagedExecutorImpl.java:46)
> at
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
> at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
> at
> org.gradle.internal.concurrent.ThreadFactoryImpl$ManagedThreadRunnable.run(ThreadFactoryImpl.java:55)
> at java.lang.Thread.run(Thread.java:748)
--
This message was sent by Atlassian Jira
(v8.3.4#803005)
