Uploaded image for project: 'Flink'
  1. Flink
  2. FLINK-21104

UnalignedCheckpointITCase.execute failed with "IllegalStateException"

Attach filesAttach ScreenshotVotersWatch issueWatchersCreate sub-taskLinkCloneUpdate Comment AuthorReplace String in CommentUpdate Comment VisibilityDelete Comments
    XMLWordPrintableJSON

    Details

      Description

      https://dev.azure.com/apache-flink/apache-flink/_build/results?buildId=12383&view=logs&j=5c8e7682-d68f-54d1-16a2-a09310218a49&t=f508e270-48d6-5f1e-3138-42a17e0714f0

      2021-01-22T15:17:34.6711152Z [ERROR] execute[Parallel union, p = 10](org.apache.flink.test.checkpointing.UnalignedCheckpointITCase)  Time elapsed: 3.903 s  <<< ERROR!
      2021-01-22T15:17:34.6711736Z org.apache.flink.runtime.client.JobExecutionException: Job execution failed.
      2021-01-22T15:17:34.6712204Z 	at org.apache.flink.runtime.jobmaster.JobResult.toJobExecutionResult(JobResult.java:144)
      2021-01-22T15:17:34.6712779Z 	at org.apache.flink.runtime.minicluster.MiniClusterJobClient.lambda$getJobExecutionResult$2(MiniClusterJobClient.java:117)
      2021-01-22T15:17:34.6713344Z 	at java.util.concurrent.CompletableFuture.uniApply(CompletableFuture.java:616)
      2021-01-22T15:17:34.6713816Z 	at java.util.concurrent.CompletableFuture$UniApply.tryFire(CompletableFuture.java:591)
      2021-01-22T15:17:34.6714454Z 	at java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:488)
      2021-01-22T15:17:34.6714952Z 	at java.util.concurrent.CompletableFuture.complete(CompletableFuture.java:1975)
      2021-01-22T15:17:34.6715472Z 	at org.apache.flink.runtime.rpc.akka.AkkaInvocationHandler.lambda$invokeRpc$0(AkkaInvocationHandler.java:238)
      2021-01-22T15:17:34.6716026Z 	at java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:774)
      2021-01-22T15:17:34.6716631Z 	at java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:750)
      2021-01-22T15:17:34.6717128Z 	at java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:488)
      2021-01-22T15:17:34.6717616Z 	at java.util.concurrent.CompletableFuture.complete(CompletableFuture.java:1975)
      2021-01-22T15:17:34.6718105Z 	at org.apache.flink.runtime.concurrent.FutureUtils$1.onComplete(FutureUtils.java:1046)
      2021-01-22T15:17:34.6718596Z 	at akka.dispatch.OnComplete.internal(Future.scala:264)
      2021-01-22T15:17:34.6718973Z 	at akka.dispatch.OnComplete.internal(Future.scala:261)
      2021-01-22T15:17:34.6719364Z 	at akka.dispatch.japi$CallbackBridge.apply(Future.scala:191)
      2021-01-22T15:17:34.6719748Z 	at akka.dispatch.japi$CallbackBridge.apply(Future.scala:188)
      2021-01-22T15:17:34.6720155Z 	at scala.concurrent.impl.CallbackRunnable.run(Promise.scala:36)
      2021-01-22T15:17:34.6720641Z 	at org.apache.flink.runtime.concurrent.Executors$DirectExecutionContext.execute(Executors.java:73)
      2021-01-22T15:17:34.6721236Z 	at scala.concurrent.impl.CallbackRunnable.executeWithValue(Promise.scala:44)
      2021-01-22T15:17:34.6721706Z 	at scala.concurrent.impl.Promise$DefaultPromise.tryComplete(Promise.scala:252)
      2021-01-22T15:17:34.6722205Z 	at akka.pattern.PromiseActorRef.$bang(AskSupport.scala:572)
      2021-01-22T15:17:34.6722663Z 	at akka.pattern.PipeToSupport$PipeableFuture$$anonfun$pipeTo$1.applyOrElse(PipeToSupport.scala:22)
      2021-01-22T15:17:34.6723214Z 	at akka.pattern.PipeToSupport$PipeableFuture$$anonfun$pipeTo$1.applyOrElse(PipeToSupport.scala:21)
      2021-01-22T15:17:34.6723723Z 	at scala.concurrent.Future$$anonfun$andThen$1.apply(Future.scala:436)
      2021-01-22T15:17:34.6724146Z 	at scala.concurrent.Future$$anonfun$andThen$1.apply(Future.scala:435)
      2021-01-22T15:17:34.6724726Z 	at scala.concurrent.impl.CallbackRunnable.run(Promise.scala:36)
      2021-01-22T15:17:34.6725198Z 	at akka.dispatch.BatchingExecutor$AbstractBatch.processBatch(BatchingExecutor.scala:55)
      2021-01-22T15:17:34.6725861Z 	at akka.dispatch.BatchingExecutor$BlockableBatch$$anonfun$run$1.apply$mcV$sp(BatchingExecutor.scala:91)
      2021-01-22T15:17:34.6726525Z 	at akka.dispatch.BatchingExecutor$BlockableBatch$$anonfun$run$1.apply(BatchingExecutor.scala:91)
      2021-01-22T15:17:34.6727278Z 	at akka.dispatch.BatchingExecutor$BlockableBatch$$anonfun$run$1.apply(BatchingExecutor.scala:91)
      2021-01-22T15:17:34.6727773Z 	at scala.concurrent.BlockContext$.withBlockContext(BlockContext.scala:72)
      2021-01-22T15:17:34.6728484Z 	at akka.dispatch.BatchingExecutor$BlockableBatch.run(BatchingExecutor.scala:90)
      2021-01-22T15:17:34.6728969Z 	at akka.dispatch.TaskInvocation.run(AbstractDispatcher.scala:40)
      2021-01-22T15:17:34.6729666Z 	at akka.dispatch.ForkJoinExecutorConfigurator$AkkaForkJoinTask.exec(ForkJoinExecutorConfigurator.scala:44)
      2021-01-22T15:17:34.6730373Z 	at akka.dispatch.forkjoin.ForkJoinTask.doExec(ForkJoinTask.java:260)
      2021-01-22T15:17:34.6731022Z 	at akka.dispatch.forkjoin.ForkJoinPool$WorkQueue.runTask(ForkJoinPool.java:1339)
      2021-01-22T15:17:34.6731538Z 	at akka.dispatch.forkjoin.ForkJoinPool.runWorker(ForkJoinPool.java:1979)
      2021-01-22T15:17:34.6732005Z 	at akka.dispatch.forkjoin.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:107)
      2021-01-22T15:17:34.6732658Z Caused by: org.apache.flink.runtime.JobException: Recovery is suppressed by FixedDelayRestartBackoffTimeStrategy(maxNumberRestartAttempts=5, backoffTimeMS=100)
      2021-01-22T15:17:34.6733458Z 	at org.apache.flink.runtime.executiongraph.failover.flip1.ExecutionFailureHandler.handleFailure(ExecutionFailureHandler.java:118)
      2021-01-22T15:17:34.6734201Z 	at org.apache.flink.runtime.executiongraph.failover.flip1.ExecutionFailureHandler.getFailureHandlingResult(ExecutionFailureHandler.java:80)
      2021-01-22T15:17:34.6735053Z 	at org.apache.flink.runtime.scheduler.DefaultScheduler.handleTaskFailure(DefaultScheduler.java:221)
      2021-01-22T15:17:34.6735609Z 	at org.apache.flink.runtime.scheduler.DefaultScheduler.maybeHandleTaskFailure(DefaultScheduler.java:212)
      2021-01-22T15:17:34.6736307Z 	at org.apache.flink.runtime.scheduler.DefaultScheduler.updateTaskExecutionStateInternal(DefaultScheduler.java:203)
      2021-01-22T15:17:34.6736911Z 	at org.apache.flink.runtime.scheduler.SchedulerBase.updateTaskExecutionState(SchedulerBase.java:655)
      2021-01-22T15:17:34.6737449Z 	at org.apache.flink.runtime.scheduler.SchedulerNG.updateTaskExecutionState(SchedulerNG.java:81)
      2021-01-22T15:17:34.6737982Z 	at org.apache.flink.runtime.jobmaster.JobMaster.updateTaskExecutionState(JobMaster.java:434)
      2021-01-22T15:17:34.6738537Z 	at sun.reflect.GeneratedMethodAccessor19.invoke(Unknown Source)
      2021-01-22T15:17:34.6738959Z 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      2021-01-22T15:17:34.6739411Z 	at java.lang.reflect.Method.invoke(Method.java:498)
      2021-01-22T15:17:34.6739868Z 	at org.apache.flink.runtime.rpc.akka.AkkaRpcActor.handleRpcInvocation(AkkaRpcActor.java:306)
      2021-01-22T15:17:34.6740384Z 	at org.apache.flink.runtime.rpc.akka.AkkaRpcActor.handleRpcMessage(AkkaRpcActor.java:213)
      2021-01-22T15:17:34.6741074Z 	at org.apache.flink.runtime.rpc.akka.FencedAkkaRpcActor.handleRpcMessage(FencedAkkaRpcActor.java:77)
      2021-01-22T15:17:34.6741613Z 	at org.apache.flink.runtime.rpc.akka.AkkaRpcActor.handleMessage(AkkaRpcActor.java:159)
      2021-01-22T15:17:34.6742141Z 	at akka.japi.pf.UnitCaseStatement.apply(CaseStatements.scala:26)
      2021-01-22T15:17:34.6742562Z 	at akka.japi.pf.UnitCaseStatement.apply(CaseStatements.scala:21)
      2021-01-22T15:17:34.6742996Z 	at scala.PartialFunction$class.applyOrElse(PartialFunction.scala:123)
      2021-01-22T15:17:34.6743424Z 	at akka.japi.pf.UnitCaseStatement.applyOrElse(CaseStatements.scala:21)
      2021-01-22T15:17:34.6743880Z 	at scala.PartialFunction$OrElse.applyOrElse(PartialFunction.scala:170)
      2021-01-22T15:17:34.6744460Z 	at scala.PartialFunction$OrElse.applyOrElse(PartialFunction.scala:171)
      2021-01-22T15:17:34.6744893Z 	at scala.PartialFunction$OrElse.applyOrElse(PartialFunction.scala:171)
      2021-01-22T15:17:34.6745316Z 	at akka.actor.Actor$class.aroundReceive(Actor.scala:517)
      2021-01-22T15:17:34.6745743Z 	at akka.actor.AbstractActor.aroundReceive(AbstractActor.scala:225)
      2021-01-22T15:17:34.6746231Z 	at akka.actor.ActorCell.receiveMessage(ActorCell.scala:592)
      2021-01-22T15:17:34.6746634Z 	at akka.actor.ActorCell.invoke(ActorCell.scala:561)
      2021-01-22T15:17:34.6747025Z 	at akka.dispatch.Mailbox.processMailbox(Mailbox.scala:258)
      2021-01-22T15:17:34.6747539Z 	at akka.dispatch.Mailbox.run(Mailbox.scala:225)
      2021-01-22T15:17:34.6748083Z 	at akka.dispatch.Mailbox.exec(Mailbox.scala:235)
      2021-01-22T15:17:34.6748542Z 	... 4 more
      2021-01-22T15:17:34.6749084Z Caused by: java.lang.IllegalStateException: Internal error, #stopPersisting for last checkpoint has not been called.
      2021-01-22T15:17:34.6749801Z 	at org.apache.flink.util.Preconditions.checkState(Preconditions.java:193)
      2021-01-22T15:17:34.6750591Z 	at org.apache.flink.runtime.io.network.partition.consumer.ChannelStatePersister.startPersisting(ChannelStatePersister.java:76)
      2021-01-22T15:17:34.6751492Z 	at org.apache.flink.runtime.io.network.partition.consumer.LocalInputChannel.checkpointStarted(LocalInputChannel.java:127)
      2021-01-22T15:17:34.6752218Z 	at org.apache.flink.runtime.io.network.partition.consumer.IndexedInputGate.checkpointStarted(IndexedInputGate.java:36)
      2021-01-22T15:17:34.6753167Z 	at org.apache.flink.streaming.runtime.io.checkpointing.UnalignedController.preProcessFirstBarrier(UnalignedController.java:72)
      2021-01-22T15:17:34.6753967Z 	at org.apache.flink.streaming.runtime.io.checkpointing.AlternatingController.preProcessFirstBarrier(AlternatingController.java:116)
      2021-01-22T15:17:34.6754832Z 	at org.apache.flink.streaming.runtime.io.checkpointing.SingleCheckpointBarrierHandler.handleBarrier(SingleCheckpointBarrierHandler.java:170)
      2021-01-22T15:17:34.6755708Z 	at org.apache.flink.streaming.runtime.io.checkpointing.SingleCheckpointBarrierHandler.processBarrier(SingleCheckpointBarrierHandler.java:128)
      2021-01-22T15:17:34.6756473Z 	at org.apache.flink.streaming.runtime.io.checkpointing.CheckpointedInputGate.handleEvent(CheckpointedInputGate.java:179)
      2021-01-22T15:17:34.6757119Z 	at org.apache.flink.streaming.runtime.io.checkpointing.CheckpointedInputGate.pollNext(CheckpointedInputGate.java:156)
      2021-01-22T15:17:34.6757726Z 	at org.apache.flink.streaming.runtime.io.StreamTaskNetworkInput.emitNext(StreamTaskNetworkInput.java:180)
      2021-01-22T15:17:34.6758401Z 	at org.apache.flink.streaming.runtime.io.StreamOneInputProcessor.processInput(StreamOneInputProcessor.java:65)
      2021-01-22T15:17:34.6758943Z 	at org.apache.flink.streaming.runtime.tasks.StreamTask.processInput(StreamTask.java:400)
      2021-01-22T15:17:34.6759507Z 	at org.apache.flink.streaming.runtime.tasks.mailbox.MailboxProcessor.runMailboxLoop(MailboxProcessor.java:190)
      2021-01-22T15:17:34.6760075Z 	at org.apache.flink.streaming.runtime.tasks.StreamTask.runMailboxLoop(StreamTask.java:609)
      2021-01-22T15:17:34.6760567Z 	at org.apache.flink.streaming.runtime.tasks.StreamTask.invoke(StreamTask.java:573)
      2021-01-22T15:17:34.6761032Z 	at org.apache.flink.runtime.taskmanager.Task.doRun(Task.java:763)
      2021-01-22T15:17:34.6761601Z 	at org.apache.flink.runtime.taskmanager.Task.run(Task.java:565)
      2021-01-22T15:17:34.6761957Z 	at java.lang.Thread.run(Thread.java:748)
      

        Attachments

        Issue Links

          Activity

            People

            • Assignee:
              pnowojski Piotr Nowojski
              Reporter:
              hxbks2ks Huang Xingbo

              Dates

              • Created:
                Updated:
                Resolved:

                Issue deployment