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

ProcesExecutionRandomizedPropertyTest:shouldExecuteProcessToEnd is flaky #11121

Closed
deepthidevaki opened this issue Nov 29, 2022 · 7 comments · Fixed by #11216
Closed

ProcesExecutionRandomizedPropertyTest:shouldExecuteProcessToEnd is flaky #11121

deepthidevaki opened this issue Nov 29, 2022 · 7 comments · Fixed by #11216
Assignees
Labels
kind/bug Categorizes an issue or PR as a bug kind/flake Categorizes issue or PR as related to a flaky test version:8.1.6 Marks an issue as being completely or in parts released in 8.1.6 version:8.2.0-alpha3 version:8.2.0 Marks an issue as being completely or in parts released in 8.2.0

Comments

@deepthidevaki
Copy link
Contributor

Failures

io.camunda.zeebe.test.util.stream.StreamWrapperException: No event found matching the criteria
	at io.camunda.zeebe.test.util.stream.StreamWrapper.await(StreamWrapper.java:72)
	at io.camunda.zeebe.engine.processing.randomized.ProcessExecutionRandomizedPropertyTest.shouldExecuteProcessToEnd(ProcessExecutionRandomizedPropertyTest.java:81)
	at jdk.internal.reflect.GeneratedMethodAccessor29.invoke(Unknown Source)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:568)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
	at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:61)
	at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:54)
	at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:54)
	at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:54)
	at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:54)
	at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:54)
	at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:54)
	at org.junit.rules.RunRules.evaluate(RunRules.java:20)
	at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:61)
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
	at org.junit.runners.BlockJUnit4ClassRunner$1.evaluate(BlockJUnit4ClassRunner.java:100)
	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:366)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:103)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:63)
	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
	at org.junit.runners.Suite.runChild(Suite.java:128)
	at org.junit.runners.Suite.runChild(Suite.java:27)
	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
	at org.junit.runner.JUnitCore.run(JUnitCore.java:137)
	at org.junit.runner.JUnitCore.run(JUnitCore.java:115)
	at org.junit.vintage.engine.execution.RunnerExecutor.execute(RunnerExecutor.java:42)
	at org.junit.vintage.engine.VintageTestEngine.executeAllChildren(VintageTestEngine.java:80)
	at org.junit.vintage.engine.VintageTestEngine.execute(VintageTestEngine.java:72)
	at org.junit.platform.launcher.core.EngineExecutionOrchestrator.execute(EngineExecutionOrchestrator.java:147)
	at org.junit.platform.launcher.core.EngineExecutionOrchestrator.execute(EngineExecutionOrchestrator.java:127)
	at org.junit.platform.launcher.core.EngineExecutionOrchestrator.execute(EngineExecutionOrchestrator.java:90)
	at org.junit.platform.launcher.core.EngineExecutionOrchestrator.lambda$execute$0(EngineExecutionOrchestrator.java:55)
	at org.junit.platform.launcher.core.EngineExecutionOrchestrator.withInterceptedStreams(EngineExecutionOrchestrator.java:102)
	at org.junit.platform.launcher.core.EngineExecutionOrchestrator.execute(EngineExecutionOrchestrator.java:54)
	at org.junit.platform.launcher.core.DefaultLauncher.execute(DefaultLauncher.java:114)
	at org.junit.platform.launcher.core.DefaultLauncher.execute(DefaultLauncher.java:86)
	at org.junit.platform.launcher.core.DefaultLauncherSession$DelegatingLauncher.execute(DefaultLauncherSession.java:86)
	at org.apache.maven.surefire.junitplatform.LazyLauncher.execute(LazyLauncher.java:55)
	at org.apache.maven.surefire.junitplatform.JUnitPlatformProvider.lambda$execute$1(JUnitPlatformProvider.java:234)
	at java.base/java.util.Iterator.forEachRemaining(Iterator.java:133)
	at org.apache.maven.surefire.junitplatform.JUnitPlatformProvider.execute(JUnitPlatformProvider.java:228)
	at org.apache.maven.surefire.junitplatform.JUnitPlatformProvider.invokeAllTests(JUnitPlatformProvider.java:175)
	at org.apache.maven.surefire.junitplatform.JUnitPlatformProvider.invoke(JUnitPlatformProvider.java:131)
	at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:456)
	at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:169)
	at org.apache.maven.surefire.booter.ForkedBooter.run(ForkedBooter.java:595)
	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:581)

Logs

Test results for Property Tests.zip

@deepthidevaki deepthidevaki added the kind/flake Categorizes issue or PR as related to a flaky test label Nov 29, 2022
@remcowesterhoud
Copy link
Contributor

Seeds: processSeed=2036895956970042930, executionPathSeed=1685396434835304029

relevant logging
15:41:24.245 [Broker-0-StreamProcessor-1] DEBUG io.camunda.zeebe.logstreams - Recovering state of partition 1 from snapshot
15:41:24.252 [Broker-0-StreamProcessor-1] INFO  io.camunda.zeebe.logstreams - Recovered state of partition 1 from snapshot at position -1
15:41:24.252 [Broker-0-StreamProcessor-1] INFO  org.camunda.feel.FeelEngine - Engine created. [value-mapper: CompositeValueMapper(List(io.camunda.zeebe.feel.impl.MessagePackValueMapper@a796b16)), function-provider: io.camunda.zeebe.feel.impl.FeelFunctionProvider@4be9429, clock: io.camunda.zeebe.el.impl.ZeebeFeelEngineClock@810066, configuration: Configuration(false)]
15:41:24.253 [Broker-0-StreamProcessor-1] INFO  org.camunda.feel.FeelEngine - Engine created. [value-mapper: CompositeValueMapper(List(io.camunda.zeebe.feel.impl.MessagePackValueMapper@45839e12)), function-provider: io.camunda.zeebe.feel.impl.FeelFunctionProvider@4637c6f, clock: io.camunda.zeebe.el.impl.ZeebeFeelEngineClock@740cbf16, configuration: Configuration(false)]
15:41:24.255 [Broker-0-StreamProcessor-1] INFO  org.camunda.dmn.DmnEngine - DMN-Engine created. [value-mapper: CompositeValueMapper(List(io.camunda.zeebe.feel.impl.MessagePackValueMapper@7c0603c1)), function-provider: org.camunda.feel.context.FunctionProvider$EmptyFunctionProvider$@737d2a01, audit-loggers: List(), configuration: Configuration(false,false,false)]
15:41:24.255 [Broker-0-StreamProcessor-1] INFO  org.camunda.feel.FeelEngine - Engine created. [value-mapper: CompositeValueMapper(List(org.camunda.dmn.NoUnpackValueMapper@25ac615d)), function-provider: org.camunda.feel.context.FunctionProvider$EmptyFunctionProvider$@737d2a01, clock: SystemClock, configuration: Configuration(false)]
15:41:24.255 [Broker-0-StreamProcessor-1] INFO  org.camunda.feel.FeelEngine - Engine created. [value-mapper: CompositeValueMapper(List(io.camunda.zeebe.feel.impl.MessagePackValueMapper@3a33e183)), function-provider: io.camunda.zeebe.feel.impl.FeelFunctionProvider@2bd61ddc, clock: io.camunda.zeebe.el.impl.ZeebeFeelEngineClock@67020b6c, configuration: Configuration(false)]
15:41:24.256 [Broker-0-StreamProcessor-1] INFO  org.camunda.feel.FeelEngine - Engine created. [value-mapper: CompositeValueMapper(List(io.camunda.zeebe.feel.impl.MessagePackValueMapper@21e23858)), function-provider: io.camunda.zeebe.feel.impl.FeelFunctionProvider@478ea1e, clock: io.camunda.zeebe.el.impl.ZeebeFeelEngineClock@1fdb2fb0, configuration: Configuration(false)]
15:41:24.257 [Broker-0-StreamProcessor-1] INFO  org.camunda.dmn.DmnEngine - DMN-Engine created. [value-mapper: CompositeValueMapper(List(io.camunda.zeebe.feel.impl.MessagePackValueMapper@68750e4c)), function-provider: org.camunda.feel.context.FunctionProvider$EmptyFunctionProvider$@737d2a01, audit-loggers: List(), configuration: Configuration(false,false,false)]
15:41:24.258 [Broker-0-StreamProcessor-1] INFO  org.camunda.feel.FeelEngine - Engine created. [value-mapper: CompositeValueMapper(List(org.camunda.dmn.NoUnpackValueMapper@58e5dbe2)), function-provider: org.camunda.feel.context.FunctionProvider$EmptyFunctionProvider$@737d2a01, clock: SystemClock, configuration: Configuration(false)]
15:41:24.259 [Broker-0-StreamProcessor-1] INFO  io.camunda.zeebe.processor - Processor starts replay of events. [snapshot-position: -1, replay-mode: PROCESSING]
15:41:24.259 [Broker-0-StreamProcessor-1] INFO  io.camunda.zeebe.processor - Processor finished replay, with [lastProcessedPosition: -1, lastWrittenPosition: -1]
15:41:24.297 [Broker-0-LogStream-1] DEBUG io.camunda.zeebe.logstreams - Configured log appender back pressure at partition 1 as AppenderVegasCfg{initialLimit=1024, maxConcurrency=32768, alphaLimit=0.7, betaLimit=0.95}. Window limiting is disabled
15:41:24.297 [Broker-0-StreamProcessor-1] INFO  io.camunda.zeebe.engine.state.migration - Starting processing of migration tasks (use LogLevel.DEBUG for more details) ... 
15:41:24.297 [Broker-0-StreamProcessor-1] DEBUG io.camunda.zeebe.engine.state.migration - Found 3 migration tasks: ProcessMessageSubscriptionSentTimeMigration, MessageSubscriptionSentTimeMigration, TemporaryVariableMigration
15:41:24.297 [Broker-0-StreamProcessor-1] INFO  io.camunda.zeebe.engine.state.migration - Skipping ProcessMessageSubscriptionSentTimeMigration migration (1/3).  It was determined it does not need to run right now.
15:41:24.297 [Broker-0-StreamProcessor-1] INFO  io.camunda.zeebe.engine.state.migration - Skipping MessageSubscriptionSentTimeMigration migration (2/3).  It was determined it does not need to run right now.
15:41:24.298 [Broker-0-StreamProcessor-1] INFO  io.camunda.zeebe.engine.state.migration - Skipping TemporaryVariableMigration migration (3/3).  It was determined it does not need to run right now.
15:41:24.298 [Broker-0-StreamProcessor-1] INFO  io.camunda.zeebe.engine.state.migration - Completed processing of migration tasks (use LogLevel.DEBUG for more details) ... 
15:41:24.298 [Broker-0-StreamProcessor-1] DEBUG io.camunda.zeebe.engine.state.migration - Executed 0 migration tasks: 
15:41:29.919 [] INFO  io.camunda.zeebe.test - Test failed, following records were exported:
15:41:30.050 [] INFO  io.camunda.zeebe.test - Compact log representation:
--------
	[Timestamp] ['C'ommand/'E'event/'R'ejection] [valueType] [intent] - #[position]->#[source record position] K[key] - [summary of value]
	P9K999 - key; #999 - record position; "ID" element/process id; @"elementid"/[P9K999] - element with ID and key
	Keys are decomposed into partition id and per partition key (e.g. 2251799813685253 -> P1K005). If single partition, the partition is omitted.
	Long IDs are shortened (e.g. 'startEvent_5d56488e-0570-416c-ba2d-36d2a3acea78' -> 'star..acea78'
--------
15:41:24 C DPLY         CREATE            - #001->  -1   -1 - 
15:41:24 E PROC         CREATED           - #002->#001 K001 - process.xml -> "process_id_31" (version:1)
15:41:24 E TIME         CREATED           - #003->#001 K003 -  @"id_33"[  -1]  in <process "K001"[?]> due T16:43:24.328
15:41:24 E DPLY         CREATED           - #004->#001 K002 - process.xml
15:41:24 E DPLY         FULLY_DISTRIBUTED - #005->#001 K002 - 
16:43:24 C TIME         TRIGGER           - #006->  -1 K003 -  @"id_33"[  -1]  in <process "K001"[?]> due T16:43:24.328
16:43:24 E TIME         TRIGGERED         - #007->#006 K003 -  @"id_33"[  -1]  in <process "K001"[K004]> due T16:43:24.328
16:43:24 E PROC_EVNT    TRIGGERING        - #008->#006 K005 -  @"id_33"[K001] in <process K001[K004]> (no vars)
16:43:24 C PI           ACTIVATE          - #009->#006 K004 - PROCESS "process_id_31" in <process "process_id_31"[K004]>
16:43:24 E PI           ACTIVATING        - #010->#009 K004 - PROCESS "process_id_31" in <process "process_id_31"[K004]>
16:43:24 E PI           ACTIVATED         - #011->#009 K004 - PROCESS "process_id_31" in <process "process_id_31"[K004]>
16:43:24 E PROC_EVNT    TRIGGERED         - #012->#009 K005 -  @"id_33"[K001] in <process K001[K004]> (no vars)
16:43:24 E PI           ACTIVATING        - #013->#009 K006 - START_EVENT "id_33" in <process "process_id_31"[K004]>
16:43:24 E PI           ACTIVATED         - #014->#009 K006 - START_EVENT "id_33" in <process "process_id_31"[K004]>
16:43:24 C PI           COMPLETE          - #015->#009 K006 - START_EVENT "id_33" in <process "process_id_31"[K004]>
16:43:24 E PI           COMPLETING        - #016->#015 K006 - START_EVENT "id_33" in <process "process_id_31"[K004]>
16:43:24 E PROC_MSG_SUB CREATING          - #017->#015 K007 - "message_eventSubProcess_id_32" (inter.) correlationKey: default_correlation_key @[K004] in <process "process_id_31"[K004]> (no vars)
16:43:24 E PI           COMPLETED         - #018->#015 K006 - START_EVENT "id_33" in <process "process_id_31"[K004]>
16:43:24 E PI           SEQ_FLOW_TAKEN    - #019->#015 K008 - SEQUENCE_FLOW "sequenc..62519df" in <process "process_id_31"[K004]>
16:43:24 C PI           ACTIVATE          - #020->#015 K009 - SERVICE_TASK "id_33_v..es_task" in <process "process_id_31"[K004]>
16:43:24 C MSG_SUB      CREATE            - #021->  -1   -1 - "message_eventSubProcess_id_32" (inter.) correlationKey: default_correlation_key @[K004] in <process "process_id_31"[K004]> (no vars)
16:43:24 E PI           ACTIVATING        - #022->#020 K009 - SERVICE_TASK "id_33_v..es_task" in <process "process_id_31"[K004]>
16:43:24 E JOB          CREATED           - #023->#020 K010 - K010 "id_33_variables" @"id_33_v..es_task"[K009] 3 retries, in <process "process_id_31"[K004]> (no vars)
16:43:24 E PI           ACTIVATED         - #024->#020 K009 - SERVICE_TASK "id_33_v..es_task" in <process "process_id_31"[K004]>
16:43:24 E MSG_SUB      CREATED           - #025->#021 K011 - "message_eventSubProcess_id_32" (inter.) correlationKey: default_correlation_key @[K004] in <process "process_id_31"[K004]> (no vars)
16:43:24 C JOB_BATCH    ACTIVATE          - #026->  -1   -1 - "id_33_variables" max: 10
16:43:24 C PROC_MSG_SUB CREATE            - #027->  -1   -1 - "message_eventSubProcess_id_32" (inter.) @[K004] in <process ?[K004]> (no vars)
16:43:24 E JOB_BATCH    ACTIVATED         - #028->#026 K012 - "id_33_variables" 1/10
                   K010 "id_33_variables" @"id_33_v..es_task"[K009] 3 retries, in <process "process_id_31"[K004]> (no vars)
16:43:24 C JOB          COMPLETE          - #029->  -1 K010 - K010 -1 retries, in <process ?[?]> with variables: {correlationKey=default_correlation_key}
16:43:24 E PROC_MSG_SUB CREATED           - #030->#027 K007 - "message_eventSubProcess_id_32" (inter.) correlationKey: default_correlation_key @[K004] in <process "process_id_31"[K004]> (no vars)
16:43:24 E JOB          COMPLETED         - #031->#029 K010 - K010 "id_33_variables" @"id_33_v..es_task"[K009] 3 retries, in <process "process_id_31"[K004]> with variables: {correlationKey=default_correlation_key}
16:43:24 E PROC_EVNT    TRIGGERING        - #032->#029 K013 -  @"id_33_v..es_task"[K009] in <process K001[K004]> with variables: {correlationKey=default_correlation_key}
16:43:24 C PI           COMPLETE          - #033->#029 K009 - SERVICE_TASK "id_33_v..es_task" in <process "process_id_31"[K004]>
16:43:24 E PI           COMPLETING        - #034->#033 K009 - SERVICE_TASK "id_33_v..es_task" in <process "process_id_31"[K004]>
16:43:24 E VAR          CREATED           - #035->#033 K014 - correlationKey->"default_correlation_key" in <process [K004]>
16:43:24 E PI           COMPLETED         - #036->#033 K009 - SERVICE_TASK "id_33_v..es_task" in <process "process_id_31"[K004]>
16:43:24 E PI           SEQ_FLOW_TAKEN    - #037->#033 K015 - SEQUENCE_FLOW "sequenc..1ce039f" in <process "process_id_31"[K004]>
16:43:24 C PI           ACTIVATE          - #038->#033 K016 - PARALLEL_GATEWAY "fork_id_1" in <process "process_id_31"[K004]>
16:43:24 E PI           ACTIVATING        - #039->#038 K016 - PARALLEL_GATEWAY "fork_id_1" in <process "process_id_31"[K004]>
16:43:24 E PI           ACTIVATED         - #040->#038 K016 - PARALLEL_GATEWAY "fork_id_1" in <process "process_id_31"[K004]>
16:43:24 E PI           COMPLETING        - #041->#038 K016 - PARALLEL_GATEWAY "fork_id_1" in <process "process_id_31"[K004]>
16:43:24 E PI           COMPLETED         - #042->#038 K016 - PARALLEL_GATEWAY "fork_id_1" in <process "process_id_31"[K004]>
16:43:24 E PI           SEQ_FLOW_TAKEN    - #043->#038 K017 - SEQUENCE_FLOW "id_17" in <process "process_id_31"[K004]>
16:43:24 C PI           ACTIVATE          - #044->#038 K018 - SEND_TASK "id_19" in <process "process_id_31"[K004]>
16:43:24 E PI           SEQ_FLOW_TAKEN    - #045->#038 K019 - SEQUENCE_FLOW "sequenc..b4e6d43" in <process "process_id_31"[K004]>
16:43:24 C PI           ACTIVATE          - #046->#038 K020 - EVENT_BASED_GATEWAY "fork_id_5" in <process "process_id_31"[K004]>
16:43:24 E PI           ACTIVATING        - #047->#044 K018 - SEND_TASK "id_19" in <process "process_id_31"[K004]>
16:43:24 E JOB          CREATED           - #048->#044 K021 - K021 "job_id_19" @"id_19"[K018] 3 retries, in <process "process_id_31"[K004]> (no vars)
16:43:24 E PI           ACTIVATED         - #049->#044 K018 - SEND_TASK "id_19" in <process "process_id_31"[K004]>
16:43:24 E PI           ACTIVATING        - #050->#046 K020 - EVENT_BASED_GATEWAY "fork_id_5" in <process "process_id_31"[K004]>
16:43:24 E PROC_MSG_SUB CREATING          - #051->#046 K022 - "message_id_11" (inter.) correlationKey: default_correlation_key @[K020] in <process "process_id_31"[K004]> (no vars)
16:43:24 E PROC_MSG_SUB CREATING          - #052->#046 K023 - "message_id_7" (inter.) correlationKey: default_correlation_key @[K020] in <process "process_id_31"[K004]> (no vars)
16:43:24 E PI           ACTIVATED         - #053->#046 K020 - EVENT_BASED_GATEWAY "fork_id_5" in <process "process_id_31"[K004]>
16:43:24 C MSG_SUB      CREATE            - #054->  -1   -1 - "message_id_11" (inter.) correlationKey: default_correlation_key @[K020] in <process "process_id_31"[K004]> (no vars)
16:43:24 C MSG_SUB      CREATE            - #055->  -1   -1 - "message_id_7" (inter.) correlationKey: default_correlation_key @[K020] in <process "process_id_31"[K004]> (no vars)
16:43:24 E MSG_SUB      CREATED           - #056->#054 K024 - "message_id_11" (inter.) correlationKey: default_correlation_key @[K020] in <process "process_id_31"[K004]> (no vars)
16:43:24 C PROC_MSG_SUB CREATE            - #057->  -1   -1 - "message_id_11" (inter.) @[K020] in <process ?[K004]> (no vars)
16:43:24 E MSG_SUB      CREATED           - #058->#055 K025 - "message_id_7" (inter.) correlationKey: default_correlation_key @[K020] in <process "process_id_31"[K004]> (no vars)
16:43:24 C PROC_MSG_SUB CREATE            - #059->  -1   -1 - "message_id_7" (inter.) @[K020] in <process ?[K004]> (no vars)
16:43:24 E PROC_MSG_SUB CREATED           - #060->#057 K022 - "message_id_11" (inter.) correlationKey: default_correlation_key @[K020] in <process "process_id_31"[K004]> (no vars)
16:43:24 C MSG          PUBLISH           - #061->  -1   -1 - "message_id_11" correlationKey: default_correlation_key with variables: {correlationKey=default_correlation_key}
16:43:24 E PROC_MSG_SUB CREATED           - #062->#059 K023 - "message_id_7" (inter.) correlationKey: default_correlation_key @[K020] in <process "process_id_31"[K004]> (no vars)
16:43:24 E MSG          PUBLISHED         - #063->#061 K026 - "message_id_11" correlationKey: default_correlation_key with variables: {correlationKey=default_correlation_key}
16:43:24 E MSG_SUB      CORRELATING       - #064->#061 K024 - "message_id_11" (inter.) correlationKey: default_correlation_key @[K020] in <process "process_id_31"[K004]> with variables: {correlationKey=default_correlation_key}
16:43:24 C PROC_MSG_SUB CORRELATE         - #065->  -1   -1 - "message_id_11" (inter.) correlationKey: default_correlation_key @[K020] in <process "process_id_31"[K004]> with variables: {correlationKey=default_correlation_key}
16:43:24 C MSG_SUB      CORRELATE         - #066->  -1   -1 - "message_id_11" (inter.) @[K020] in <process "process_id_31"[K004]> (no vars)
16:43:24 E PROC_MSG_SUB CORRELATED        - #067->#065 K022 - "message_id_11" (inter.) correlationKey: default_correlation_key @[K020] in <process "process_id_31"[K004]> with variables: {correlationKey=default_correlation_key}
16:43:24 E PROC_EVNT    TRIGGERING        - #068->#065 K027 -  @"id_11"[K020] in <process K001[K004]> with variables: {correlationKey=default_correlation_key}
16:43:24 C PI           COMPLETE          - #069->#065 K020 - EVENT_BASED_GATEWAY "fork_id_5" in <process "process_id_31"[K004]>
16:43:24 E MSG_SUB      CORRELATED        - #070->#066 K024 - "message_id_11" (inter.) correlationKey: default_correlation_key @[K020] in <process "process_id_31"[K004]> with variables: {correlationKey=default_correlation_key}
16:43:24 E PI           COMPLETING        - #071->#069 K020 - EVENT_BASED_GATEWAY "fork_id_5" in <process "process_id_31"[K004]>
16:43:24 E PROC_MSG_SUB DELETING          - #072->#069 K023 - "message_id_7" (inter.) correlationKey: default_correlation_key @[K020] in <process "process_id_31"[K004]> (no vars)
16:43:24 E PI           COMPLETED         - #073->#069 K020 - EVENT_BASED_GATEWAY "fork_id_5" in <process "process_id_31"[K004]>
16:43:24 E PROC_EVNT    TRIGGERED         - #074->#069 K027 -  @"id_11"[K020] in <process K001[K004]> (no vars)
16:43:24 E PI           ACTIVATING        - #075->#069 K028 - INTERMEDIATE_CATCH_EVENT "id_11" in <process "process_id_31"[K004]>
16:43:24 E PI           ACTIVATED         - #076->#069 K028 - INTERMEDIATE_CATCH_EVENT "id_11" in <process "process_id_31"[K004]>
16:43:24 E VAR          CREATED           - #077->#069 K029 - correlationKey->"default_correlation_key" in <process [K004] at [K028]>
16:43:24 C PI           COMPLETE          - #078->#069 K028 - INTERMEDIATE_CATCH_EVENT "id_11" in <process "process_id_31"[K004]>
16:43:24 C MSG_SUB      DELETE            - #079->  -1   -1 - "message_id_7" (inter.) @[K020] in <process ?[K004]> (no vars)
16:43:24 E PI           COMPLETING        - #080->#078 K028 - INTERMEDIATE_CATCH_EVENT "id_11" in <process "process_id_31"[K004]>
16:43:24 E PI           COMPLETED         - #081->#078 K028 - INTERMEDIATE_CATCH_EVENT "id_11" in <process "process_id_31"[K004]>
16:43:24 E PI           SEQ_FLOW_TAKEN    - #082->#078 K030 - SEQUENCE_FLOW "sequenc..a819695" in <process "process_id_31"[K004]>
16:43:24 C PI           ACTIVATE          - #083->#078 K031 - BUSINESS_RULE_TASK "id_13" in <process "process_id_31"[K004]>
16:43:24 E MSG_SUB      DELETED           - #084->#079 K025 - "message_id_7" (inter.) correlationKey: default_correlation_key @[K020] in <process "process_id_31"[K004]> (no vars)
16:43:24 C PROC_MSG_SUB DELETE            - #085->  -1   -1 - "message_id_7" (inter.) @[K020] in <process ?[K004]> (no vars)
16:43:24 E PI           ACTIVATING        - #086->#083 K031 - BUSINESS_RULE_TASK "id_13" in <process "process_id_31"[K004]>
16:43:24 E JOB          CREATED           - #087->#083 K032 - K032 "job_id_13" @"id_13"[K031] 3 retries, in <process "process_id_31"[K004]> (no vars)
16:43:24 E PI           ACTIVATED         - #088->#083 K031 - BUSINESS_RULE_TASK "id_13" in <process "process_id_31"[K004]>
16:43:24 E PROC_MSG_SUB DELETED           - #089->#085 K023 - "message_id_7" (inter.) correlationKey: default_correlation_key @[K020] in <process "process_id_31"[K004]> (no vars)
16:43:24 C JOB_BATCH    ACTIVATE          - #090->  -1   -1 - "job_id_13" max: 10
16:43:24 E JOB_BATCH    ACTIVATED         - #091->#090 K033 - "job_id_13" 1/10
                   K032 "job_id_13" @"id_13"[K031] 3 retries, in <process "process_id_31"[K004]> with variables: {correlationKey=default_correlation_key}
17:43:24 C JOB          TIME_OUT          - #092->  -1 K032 - K032 "job_id_13" @"id_13"[K031] 3 retries, in <process "process_id_31"[K004]> (no vars)
17:43:24 C JOB_BATCH    ACTIVATE          - #093->  -1   -1 - "job_id_13" max: 10
17:43:24 E JOB          TIMED_OUT         - #094->#092 K032 - K032 "job_id_13" @"id_13"[K031] 3 retries, in <process "process_id_31"[K004]> (no vars)
17:43:24 E JOB_BATCH    ACTIVATED         - #095->#093 K034 - "job_id_13" 1/10
                   K032 "job_id_13" @"id_13"[K031] 3 retries, in <process "process_id_31"[K004]> with variables: {correlationKey=default_correlation_key}
17:43:24 C JOB          FAIL              - #096->  -1 K032 - K032 3 retries, in <process ?[?]> (no vars)
17:43:24 E JOB          FAILED            - #097->#096 K032 - K032 "job_id_13" @"id_13"[K031] 3 retries, in <process "process_id_31"[K004]> (no vars)
17:43:24 C JOB_BATCH    ACTIVATE          - #098->  -1   -1 - "job_id_19" max: 10
17:43:24 E JOB_BATCH    ACTIVATED         - #099->#098 K035 - "job_id_19" 1/10
                   K021 "job_id_19" @"id_19"[K018] 3 retries, in <process "process_id_31"[K004]> with variables: {correlationKey=default_correlation_key}
17:43:24 C MSG          EXPIRE            - #100->  -1 K026 - "message_id_11" correlationKey: default_correlation_key with variables: {correlationKey=default_correlation_key}
17:43:24 E MSG          EXPIRED           - #101->#100 K026 - "message_id_11" correlationKey: default_correlation_key with variables: {correlationKey=default_correlation_key}
18:43:24 C JOB          TIME_OUT          - #102->  -1 K021 - K021 "job_id_19" @"id_19"[K018] 3 retries, in <process "process_id_31"[K004]> (no vars)
18:43:24 E JOB          TIMED_OUT         - #103->#102 K021 - K021 "job_id_19" @"id_19"[K018] 3 retries, in <process "process_id_31"[K004]> (no vars)
18:43:24 C JOB_BATCH    ACTIVATE          - #104->  -1   -1 - "job_id_13" max: 10
18:43:24 E JOB_BATCH    ACTIVATED         - #105->#104 K036 - "job_id_13" 1/10
                   K032 "job_id_13" @"id_13"[K031] 3 retries, in <process "process_id_31"[K004]> with variables: {correlationKey=default_correlation_key}
18:43:24 C JOB          COMPLETE          - #106->  -1 K032 - K032 -1 retries, in <process ?[?]> (no vars)
18:43:24 E JOB          COMPLETED         - #107->#106 K032 - K032 "job_id_13" @"id_13"[K031] 3 retries, in <process "process_id_31"[K004]> (no vars)
18:43:24 E PROC_EVNT    TRIGGERING        - #108->#106 K037 -  @"id_13"[K031] in <process K001[K004]> (no vars)
18:43:24 C PI           COMPLETE          - #109->#106 K031 - BUSINESS_RULE_TASK "id_13" in <process "process_id_31"[K004]>
18:43:24 C JOB_BATCH    ACTIVATE          - #110->  -1   -1 - "job_id_19" max: 10
18:43:24 E PI           COMPLETING        - #111->#109 K031 - BUSINESS_RULE_TASK "id_13" in <process "process_id_31"[K004]>
18:43:24 E PI           COMPLETED         - #112->#109 K031 - BUSINESS_RULE_TASK "id_13" in <process "process_id_31"[K004]>
18:43:24 E PI           SEQ_FLOW_TAKEN    - #113->#109 K038 - SEQUENCE_FLOW "sequenc..3e47e30" in <process "process_id_31"[K004]>
18:43:24 C PI           ACTIVATE          - #114->#109 K039 - RECEIVE_TASK "id_14" in <process "process_id_31"[K004]>
18:43:24 E JOB_BATCH    ACTIVATED         - #115->#110 K040 - "job_id_19" 1/10
                   K021 "job_id_19" @"id_19"[K018] 3 retries, in <process "process_id_31"[K004]> with variables: {correlationKey=default_correlation_key}
18:43:24 C JOB          FAIL              - #116->  -1 K021 - K021 0 retries, in <process ?[?]> (no vars)
18:43:24 E PI           ACTIVATING        - #117->#114 K039 - RECEIVE_TASK "id_14" in <process "process_id_31"[K004]>
18:43:24 E PROC_MSG_SUB CREATING          - #118->#114 K041 - "message_id_14" (inter.) correlationKey: default_correlation_key @[K039] in <process "process_id_31"[K004]> (no vars)
18:43:24 E PI           ACTIVATED         - #119->#114 K039 - RECEIVE_TASK "id_14" in <process "process_id_31"[K004]>
18:43:24 C MSG_SUB      CREATE            - #120->  -1   -1 - "message_id_14" (inter.) correlationKey: default_correlation_key @[K039] in <process "process_id_31"[K004]> (no vars)
18:43:24 E JOB          FAILED            - #121->#116 K021 - K021 "job_id_19" @"id_19"[K018] 0 retries, in <process "process_id_31"[K004]> (no vars)
18:43:24 E INCIDENT     CREATED           - #122->#116 K042 - JOB_NO_RETRIES No more retries left., joBKey: K021  @"id_19"[K018] in <process "process_id_31"[K004]>
18:43:24 C JOB          UPDATE_RETRIES    - #123->  -1 K021 - K021 3 retries, in <process ?[?]> (no vars)
18:43:24 E MSG_SUB      CREATED           - #124->#120 K043 - "message_id_14" (inter.) correlationKey: default_correlation_key @[K039] in <process "process_id_31"[K004]> (no vars)
18:43:24 C PROC_MSG_SUB CREATE            - #125->  -1   -1 - "message_id_14" (inter.) @[K039] in <process ?[K004]> (no vars)
18:43:24 E JOB          RETRIES_UPDATED   - #126->#123 K021 - K021 "job_id_19" @"id_19"[K018] 3 retries, in <process "process_id_31"[K004]> (no vars)
18:43:24 C INCIDENT     RESOLVE           - #127->  -1 K042 - K042
18:43:24 E PROC_MSG_SUB CREATED           - #128->#125 K041 - "message_id_14" (inter.) correlationKey: default_correlation_key @[K039] in <process "process_id_31"[K004]> (no vars)
18:43:24 E INCIDENT     RESOLVED          - #129->#127 K042 - JOB_NO_RETRIES No more retries left., joBKey: K021  @"id_19"[K018] in <process "process_id_31"[K004]>
18:43:24 C MSG          PUBLISH           - #130->  -1   -1 - "message_id_14" correlationKey: default_correlation_key with variables: {correlationKey=default_correlation_key}
18:43:24 E MSG          PUBLISHED         - #131->#130 K044 - "message_id_14" correlationKey: default_correlation_key with variables: {correlationKey=default_correlation_key}
18:43:24 E MSG_SUB      CORRELATING       - #132->#130 K043 - "message_id_14" (inter.) correlationKey: default_correlation_key @[K039] in <process "process_id_31"[K004]> with variables: {correlationKey=default_correlation_key}
18:43:24 C PROC_MSG_SUB CORRELATE         - #133->  -1   -1 - "message_id_14" (inter.) correlationKey: default_correlation_key @[K039] in <process "process_id_31"[K004]> with variables: {correlationKey=default_correlation_key}
18:43:24 C MSG_SUB      CORRELATE         - #134->  -1   -1 - "message_id_14" (inter.) @[K039] in <process "process_id_31"[K004]> (no vars)
18:43:24 E PROC_MSG_SUB CORRELATED        - #135->#133 K041 - "message_id_14" (inter.) correlationKey: default_correlation_key @[K039] in <process "process_id_31"[K004]> with variables: {correlationKey=default_correlation_key}
18:43:24 E PROC_EVNT    TRIGGERING        - #136->#133 K045 -  @"id_14"[K039] in <process K001[K004]> with variables: {correlationKey=default_correlation_key}
18:43:24 C PI           COMPLETE          - #137->#133 K039 - RECEIVE_TASK "id_14" in <process "process_id_31"[K004]>
18:43:24 E MSG_SUB      CORRELATED        - #138->#134 K043 - "message_id_14" (inter.) correlationKey: default_correlation_key @[K039] in <process "process_id_31"[K004]> with variables: {correlationKey=default_correlation_key}
18:43:24 E PI           COMPLETING        - #139->#137 K039 - RECEIVE_TASK "id_14" in <process "process_id_31"[K004]>
18:43:24 E PI           COMPLETED         - #140->#137 K039 - RECEIVE_TASK "id_14" in <process "process_id_31"[K004]>
18:43:24 E PI           SEQ_FLOW_TAKEN    - #141->#137 K046 - SEQUENCE_FLOW "sequenc..b33af0c" in <process "process_id_31"[K004]>
18:43:24 C PI           ACTIVATE          - #142->#137 K047 - BUSINESS_RULE_TASK "id_15" in <process "process_id_31"[K004]>
18:43:24 E PI           ACTIVATING        - #143->#142 K047 - BUSINESS_RULE_TASK "id_15" in <process "process_id_31"[K004]>
18:43:24 E JOB          CREATED           - #144->#142 K048 - K048 "job_id_15" @"id_15"[K047] 3 retries, in <process "process_id_31"[K004]> (no vars)
18:43:24 E PI           ACTIVATED         - #145->#142 K047 - BUSINESS_RULE_TASK "id_15" in <process "process_id_31"[K004]>
18:43:24 C JOB_BATCH    ACTIVATE          - #146->  -1   -1 - "job_id_15" max: 10
18:43:24 E JOB_BATCH    ACTIVATED         - #147->#146 K049 - "job_id_15" 1/10
                   K048 "job_id_15" @"id_15"[K047] 3 retries, in <process "process_id_31"[K004]> with variables: {correlationKey=default_correlation_key}
19:43:24 C JOB          TIME_OUT          - #148->  -1 K048 - K048 "job_id_15" @"id_15"[K047] 3 retries, in <process "process_id_31"[K004]> (no vars)
19:43:24 E JOB          TIMED_OUT         - #149->#148 K048 - K048 "job_id_15" @"id_15"[K047] 3 retries, in <process "process_id_31"[K004]> (no vars)
19:43:24 C JOB_BATCH    ACTIVATE          - #150->  -1   -1 - "job_id_15" max: 10
19:43:24 E JOB_BATCH    ACTIVATED         - #151->#150 K050 - "job_id_15" 1/10
                   K048 "job_id_15" @"id_15"[K047] 3 retries, in <process "process_id_31"[K004]> with variables: {correlationKey=default_correlation_key}
19:43:24 C JOB          FAIL              - #152->  -1 K048 - K048 0 retries, in <process ?[?]> (no vars)
19:43:24 E JOB          FAILED            - #153->#152 K048 - K048 "job_id_15" @"id_15"[K047] 0 retries, in <process "process_id_31"[K004]> (no vars)
19:43:24 E INCIDENT     CREATED           - #154->#152 K051 - JOB_NO_RETRIES No more retries left., joBKey: K048  @"id_15"[K047] in <process "process_id_31"[K004]>
19:43:24 C JOB          UPDATE_RETRIES    - #155->  -1 K048 - K048 3 retries, in <process ?[?]> (no vars)
19:43:24 E JOB          RETRIES_UPDATED   - #156->#155 K048 - K048 "job_id_15" @"id_15"[K047] 3 retries, in <process "process_id_31"[K004]> (no vars)
19:43:24 C INCIDENT     RESOLVE           - #157->  -1 K051 - K051
19:43:24 E INCIDENT     RESOLVED          - #158->#157 K051 - JOB_NO_RETRIES No more retries left., joBKey: K048  @"id_15"[K047] in <process "process_id_31"[K004]>
19:43:24 C JOB_BATCH    ACTIVATE          - #159->  -1   -1 - "job_id_15" max: 10
19:43:24 E JOB_BATCH    ACTIVATED         - #160->#159 K052 - "job_id_15" 1/10
                   K048 "job_id_15" @"id_15"[K047] 3 retries, in <process "process_id_31"[K004]> with variables: {correlationKey=default_correlation_key}
19:43:24 C JOB          THROW_ERROR       - #161->  -1 K048 - K048 -1 retries, error_id_15: in <process ?[?]> (no vars)
19:43:24 E JOB          ERROR_THROWN      - #162->#161 K048 - K048 "job_id_15" @"id_15"[K047] 3 retries, error_id_15: in <process "process_id_31"[K004]> (no vars)
19:43:24 E PROC_EVNT    TRIGGERING        - #163->#161 K053 -  @"boundar..r_id_15"[K047] in <process K001[K004]> (no vars)
19:43:24 C PI           TERMINATE         - #164->#161 K047 - BUSINESS_RULE_TASK "id_15" in <process "process_id_31"[K004]>
19:43:24 C JOB_BATCH    ACTIVATE          - #165->  -1   -1 - "job_id_19" max: 10
19:43:24 C MSG          EXPIRE            - #166->  -1 K044 - "message_id_14" correlationKey: default_correlation_key with variables: {correlationKey=default_correlation_key}
19:43:24 E PI           TERMINATING       - #167->#164 K047 - BUSINESS_RULE_TASK "id_15" in <process "process_id_31"[K004]>
19:43:24 E PI           TERMINATED        - #168->#164 K047 - BUSINESS_RULE_TASK "id_15" in <process "process_id_31"[K004]>
19:43:24 E PROC_EVNT    TRIGGERED         - #169->#164 K053 -  @"boundar..r_id_15"[K047] in <process K001[K004]> (no vars)
19:43:24 E PI           ACTIVATING        - #170->#164 K054 - BOUNDARY_EVENT "boundar..r_id_15" in <process "process_id_31"[K004]>
19:43:24 E PI           ACTIVATED         - #171->#164 K054 - BOUNDARY_EVENT "boundar..r_id_15" in <process "process_id_31"[K004]>
19:43:24 C PI           COMPLETE          - #172->#164 K054 - BOUNDARY_EVENT "boundar..r_id_15" in <process "process_id_31"[K004]>
19:43:24 E JOB_BATCH    ACTIVATED         - #173->#165 K055 - "job_id_19" 1/10
                   K021 "job_id_19" @"id_19"[K018] 3 retries, in <process "process_id_31"[K004]> with variables: {correlationKey=default_correlation_key}
19:43:24 C JOB          THROW_ERROR       - #174->  -1 K021 - K021 -1 retries, error_id_19: in <process ?[?]> (no vars)
19:43:24 E MSG          EXPIRED           - #175->#166 K044 - "message_id_14" correlationKey: default_correlation_key with variables: {correlationKey=default_correlation_key}
19:43:24 E PI           COMPLETING        - #176->#172 K054 - BOUNDARY_EVENT "boundar..r_id_15" in <process "process_id_31"[K004]>
19:43:24 E PI           COMPLETED         - #177->#172 K054 - BOUNDARY_EVENT "boundar..r_id_15" in <process "process_id_31"[K004]>
19:43:24 E PI           SEQ_FLOW_TAKEN    - #178->#172 K056 - SEQUENCE_FLOW "sequenc..eed2b7f" in <process "process_id_31"[K004]>
19:43:24 C PI           ACTIVATE          - #179->#172 K057 - EXCLUSIVE_GATEWAY "boundar..n_id_15" in <process "process_id_31"[K004]>
19:43:24 E JOB          ERROR_THROWN      - #180->#174 K021 - K021 "job_id_19" @"id_19"[K018] 3 retries, error_id_19: in <process "process_id_31"[K004]> (no vars)
19:43:24 E PROC_EVNT    TRIGGERING        - #181->#174 K058 -  @"boundar..r_id_19"[K018] in <process K001[K004]> (no vars)
19:43:24 C PI           TERMINATE         - #182->#174 K018 - SEND_TASK "id_19" in <process "process_id_31"[K004]>
19:43:24 E PI           ACTIVATING        - #183->#179 K057 - EXCLUSIVE_GATEWAY "boundar..n_id_15" in <process "process_id_31"[K004]>
19:43:24 E PI           ACTIVATED         - #184->#179 K057 - EXCLUSIVE_GATEWAY "boundar..n_id_15" in <process "process_id_31"[K004]>
19:43:24 E PI           COMPLETING        - #185->#179 K057 - EXCLUSIVE_GATEWAY "boundar..n_id_15" in <process "process_id_31"[K004]>
19:43:24 E PI           COMPLETED         - #186->#179 K057 - EXCLUSIVE_GATEWAY "boundar..n_id_15" in <process "process_id_31"[K004]>
19:43:24 E PI           SEQ_FLOW_TAKEN    - #187->#179 K059 - SEQUENCE_FLOW "sequenc..fe5a435" in <process "process_id_31"[K004]>
19:43:24 C PI           ACTIVATE          - #188->#179 K060 - EXCLUSIVE_GATEWAY "join_id_6" in <process "process_id_31"[K004]>
19:43:24 E PI           TERMINATING       - #189->#182 K018 - SEND_TASK "id_19" in <process "process_id_31"[K004]>
19:43:24 E PI           TERMINATED        - #190->#182 K018 - SEND_TASK "id_19" in <process "process_id_31"[K004]>
19:43:24 E PROC_EVNT    TRIGGERED         - #191->#182 K058 -  @"boundar..r_id_19"[K018] in <process K001[K004]> (no vars)
19:43:24 E PI           ACTIVATING        - #192->#182 K061 - BOUNDARY_EVENT "boundar..r_id_19" in <process "process_id_31"[K004]>
19:43:24 E PI           ACTIVATED         - #193->#182 K061 - BOUNDARY_EVENT "boundar..r_id_19" in <process "process_id_31"[K004]>
19:43:24 C PI           COMPLETE          - #194->#182 K061 - BOUNDARY_EVENT "boundar..r_id_19" in <process "process_id_31"[K004]>
19:43:24 E PI           ACTIVATING        - #195->#188 K060 - EXCLUSIVE_GATEWAY "join_id_6" in <process "process_id_31"[K004]>
19:43:24 E PI           ACTIVATED         - #196->#188 K060 - EXCLUSIVE_GATEWAY "join_id_6" in <process "process_id_31"[K004]>
19:43:24 E PI           COMPLETING        - #197->#188 K060 - EXCLUSIVE_GATEWAY "join_id_6" in <process "process_id_31"[K004]>
19:43:24 E PI           COMPLETED         - #198->#188 K060 - EXCLUSIVE_GATEWAY "join_id_6" in <process "process_id_31"[K004]>
19:43:24 E PI           SEQ_FLOW_TAKEN    - #199->#188 K062 - SEQUENCE_FLOW "sequenc..e639958" in <process "process_id_31"[K004]>
19:43:24 C PI           ACTIVATE          - #200->#188 K063 - INTERMEDIATE_THROW_EVENT "id_16" in <process "process_id_31"[K004]>
19:43:24 E PI           COMPLETING        - #201->#194 K061 - BOUNDARY_EVENT "boundar..r_id_19" in <process "process_id_31"[K004]>
19:43:24 E PI           COMPLETED         - #202->#194 K061 - BOUNDARY_EVENT "boundar..r_id_19" in <process "process_id_31"[K004]>
19:43:24 E PI           SEQ_FLOW_TAKEN    - #203->#194 K064 - SEQUENCE_FLOW "sequenc..63ca00e" in <process "process_id_31"[K004]>
19:43:24 C PI           ACTIVATE          - #204->#194 K065 - END_EVENT "endEven..434bb30" in <process "process_id_31"[K004]>
19:43:24 E PI           ACTIVATING        - #205->#200 K063 - INTERMEDIATE_THROW_EVENT "id_16" in <process "process_id_31"[K004]>
19:43:24 E PI           ACTIVATED         - #206->#200 K063 - INTERMEDIATE_THROW_EVENT "id_16" in <process "process_id_31"[K004]>
19:43:24 C PI           COMPLETE          - #207->#200 K063 - INTERMEDIATE_THROW_EVENT "id_16" in <process "process_id_31"[K004]>
19:43:24 E PI           ACTIVATING        - #208->#204 K065 - END_EVENT "endEven..434bb30" in <process "process_id_31"[K004]>
19:43:24 E PI           ACTIVATED         - #209->#204 K065 - END_EVENT "endEven..434bb30" in <process "process_id_31"[K004]>
19:43:24 E PI           COMPLETING        - #210->#204 K065 - END_EVENT "endEven..434bb30" in <process "process_id_31"[K004]>
19:43:24 E PI           COMPLETED         - #211->#204 K065 - END_EVENT "endEven..434bb30" in <process "process_id_31"[K004]>
19:43:24 C PI           TERMINATE         - #212->#204 K063 - INTERMEDIATE_THROW_EVENT "id_16" in <process "process_id_31"[K004]>
19:43:24 E PI           COMPLETING        - #213->#207 K063 - INTERMEDIATE_THROW_EVENT "id_16" in <process "process_id_31"[K004]>
19:43:24 E PI           COMPLETED         - #214->#207 K063 - INTERMEDIATE_THROW_EVENT "id_16" in <process "process_id_31"[K004]>
19:43:24 E PI           SEQ_FLOW_TAKEN    - #215->#207 K066 - SEQUENCE_FLOW "sequenc..b28b7bc" in <process "process_id_31"[K004]>
19:43:24 C PI           ACTIVATE          - #216->#207 K067 - PARALLEL_GATEWAY "join_id_2" in <process "process_id_31"[K004]>
19:43:24 R PI           TERMINATE         - #217->#212 K063 - INTERMEDIATE_THROW_EVENT "id_16" in <process "process_id_31"[K004]> !INVALID_STATE (Expected element instance with key '2251799813685311' to be present in state but not found.)
19:43:24 R PI           ACTIVATE          - #218->#216 K067 - PARALLEL_GATEWAY "join_id_2" in <process "process_id_31"[K004]> !INVALID_STATE (Expected to be able to activate parallel gateway 'join_id_2', but not all sequence flows have been taken.)

-------------- Deployed Processes ----------------------
process.xml -> "process_id_31" (version:1)[K001] ------
<?xml version="1.0" encoding="UTF-8" standalone="no"?>
<definitions xmlns:bpmndi="http://www.omg.org/spec/BPMN/20100524/DI" xmlns:dc="http://www.omg.org/spec/DD/20100524/DC" xmlns:di="http://www.omg.org/spec/DD/20100524/DI" xmlns:ns0="http://camunda.org/schema/zeebe/1.0" id="definitions_6d705a81-e50e-41b2-afaf-ec08463963c6" targetNamespace="http://www.omg.org/spec/BPMN/20100524/MODEL" xmlns="http://www.omg.org/spec/BPMN/20100524/MODEL">
  <process id="process_id_31" isExecutable="true">
    <subProcess id="eventSubProcess_id_32" name="eventSubProcess_id_32" triggeredByEvent="true">
      <startEvent id="start_event_eventSubProcess_id_32" isInterrupting="true" name="start_event_eventSubProcess_id_32">
        <outgoing>sequenceFlow_a9590150-e468-4ef5-a619-ea3d1c5c1185</outgoing>
        <messageEventDefinition id="messageEventDefinition_b3f871f0-0eba-452e-8649-14eb8e2dc825" messageRef="message_fbbd50d9-8ca7-4023-abc3-fea116f99f4c"/>
      </startEvent>
      <endEvent id="end_event_eventSubProcess_id_32" name="end_event_eventSubProcess_id_32">
        <incoming>sequenceFlow_a9590150-e468-4ef5-a619-ea3d1c5c1185</incoming>
      </endEvent>
      <sequenceFlow id="sequenceFlow_a9590150-e468-4ef5-a619-ea3d1c5c1185" sourceRef="start_event_eventSubProcess_id_32" targetRef="end_event_eventSubProcess_id_32"/>
    </subProcess>
    <startEvent id="id_33" name="id_33">
      <outgoing>sequenceFlow_45aaea09-9125-41e9-81bb-2b39e62519df</outgoing>
      <timerEventDefinition id="timerEventDefinition_c0aa8d9b-d3bf-458f-8dd0-5788f425ff93">
        <timeDate id="timeDate_bb4e9c84-3cc2-4e70-bb6b-3e7c9947d585">=now() + duration("PT1H2M")</timeDate>
      </timerEventDefinition>
    </startEvent>
    <serviceTask id="id_33_variables_task" name="id_33_variables_task">
      <extensionElements>
        <ns0:taskDefinition type="id_33_variables"/>
      </extensionElements>
      <incoming>sequenceFlow_45aaea09-9125-41e9-81bb-2b39e62519df</incoming>
      <outgoing>sequenceFlow_587dcc9d-0c19-4df7-9f03-cad471ce039f</outgoing>
    </serviceTask>
    <sequenceFlow id="sequenceFlow_45aaea09-9125-41e9-81bb-2b39e62519df" sourceRef="id_33" targetRef="id_33_variables_task"/>
    <parallelGateway id="fork_id_1" name="fork_id_1">
      <incoming>sequenceFlow_587dcc9d-0c19-4df7-9f03-cad471ce039f</incoming>
      <outgoing>sequenceFlow_29385c24-a2a7-4b40-bbb3-ee13ab4e6d43</outgoing>
      <outgoing>id_17</outgoing>
    </parallelGateway>
    <sequenceFlow id="sequenceFlow_587dcc9d-0c19-4df7-9f03-cad471ce039f" sourceRef="id_33_variables_task" targetRef="fork_id_1"/>
    <eventBasedGateway id="fork_id_5" name="fork_id_5">
      <incoming>sequenceFlow_29385c24-a2a7-4b40-bbb3-ee13ab4e6d43</incoming>
      <outgoing>sequenceFlow_aa4cd94b-f49b-49db-8dad-c7d9d13b55ac</outgoing>
      <outgoing>sequenceFlow_01977fa3-8137-4c19-b90f-e889a0dea876</outgoing>
    </eventBasedGateway>
    <sequenceFlow id="sequenceFlow_29385c24-a2a7-4b40-bbb3-ee13ab4e6d43" sourceRef="fork_id_1" targetRef="fork_id_5"/>
    <intermediateCatchEvent id="id_7" name="id_7">
      <incoming>sequenceFlow_aa4cd94b-f49b-49db-8dad-c7d9d13b55ac</incoming>
      <outgoing>sequenceFlow_f9dbd643-a9ec-42be-96ec-9273adf54916</outgoing>
      <messageEventDefinition id="messageEventDefinition_89b121de-846d-4e18-b8aa-27a73d470be3" messageRef="message_4b9def63-904f-46be-9ced-ace27971f9a9"/>
    </intermediateCatchEvent>
    <sequenceFlow id="sequenceFlow_aa4cd94b-f49b-49db-8dad-c7d9d13b55ac" sourceRef="fork_id_5" targetRef="id_7"/>
    <userTask id="id_9" name="id_9">
      <incoming>sequenceFlow_f9dbd643-a9ec-42be-96ec-9273adf54916</incoming>
      <outgoing>sequenceFlow_232708f4-46a5-4487-8c40-08b4a70a6575</outgoing>
    </userTask>
    <sequenceFlow id="sequenceFlow_f9dbd643-a9ec-42be-96ec-9273adf54916" sourceRef="id_7" targetRef="id_9"/>
    <receiveTask id="id_10" messageRef="message_e684e79e-87e1-4d6a-bdd5-150db066b2de" name="id_10">
      <incoming>sequenceFlow_232708f4-46a5-4487-8c40-08b4a70a6575</incoming>
      <outgoing>sequenceFlow_a668c4e1-e00d-497c-83fe-add77aacb7e4</outgoing>
    </receiveTask>
    <sequenceFlow id="sequenceFlow_232708f4-46a5-4487-8c40-08b4a70a6575" sourceRef="id_9" targetRef="id_10"/>
    <exclusiveGateway id="join_id_6" name="join_id_6">
      <incoming>sequenceFlow_a668c4e1-e00d-497c-83fe-add77aacb7e4</incoming>
      <incoming>sequenceFlow_be0153b1-6170-4881-bd5b-e366efe5a435</incoming>
      <outgoing>sequenceFlow_701050dd-9276-4d04-b5c7-4b928e639958</outgoing>
    </exclusiveGateway>
    <sequenceFlow id="sequenceFlow_a668c4e1-e00d-497c-83fe-add77aacb7e4" sourceRef="id_10" targetRef="join_id_6"/>
    <intermediateCatchEvent id="id_11" name="id_11">
      <incoming>sequenceFlow_01977fa3-8137-4c19-b90f-e889a0dea876</incoming>
      <outgoing>sequenceFlow_6a6bc975-df65-46b6-b0b8-e6fe3a819695</outgoing>
      <messageEventDefinition id="messageEventDefinition_8abdc436-bd90-4963-a2b3-95bd170b8328" messageRef="message_484be778-5a53-4f74-8069-38f5a20884c0"/>
    </intermediateCatchEvent>
    <sequenceFlow id="sequenceFlow_01977fa3-8137-4c19-b90f-e889a0dea876" sourceRef="fork_id_5" targetRef="id_11"/>
    <businessRuleTask id="id_13" name="id_13">
      <extensionElements>
        <ns0:taskDefinition retries="3" type="job_id_13"/>
      </extensionElements>
      <incoming>sequenceFlow_6a6bc975-df65-46b6-b0b8-e6fe3a819695</incoming>
      <outgoing>sequenceFlow_99f0bf65-7e28-403f-a60a-067393e47e30</outgoing>
    </businessRuleTask>
    <sequenceFlow id="sequenceFlow_6a6bc975-df65-46b6-b0b8-e6fe3a819695" sourceRef="id_11" targetRef="id_13"/>
    <receiveTask id="id_14" messageRef="message_f05d17fe-28a3-460f-add2-030902aaeb84" name="id_14">
      <incoming>sequenceFlow_99f0bf65-7e28-403f-a60a-067393e47e30</incoming>
      <outgoing>sequenceFlow_2eea8e09-abd7-465c-aefe-06befb33af0c</outgoing>
    </receiveTask>
    <sequenceFlow id="sequenceFlow_99f0bf65-7e28-403f-a60a-067393e47e30" sourceRef="id_13" targetRef="id_14"/>
    <businessRuleTask id="id_15" name="id_15">
      <extensionElements>
        <ns0:taskDefinition retries="3" type="job_id_15"/>
      </extensionElements>
      <incoming>sequenceFlow_2eea8e09-abd7-465c-aefe-06befb33af0c</incoming>
      <outgoing>sequenceFlow_1966e64e-1b19-4405-b1eb-07bb5cf274d6</outgoing>
    </businessRuleTask>
    <sequenceFlow id="sequenceFlow_2eea8e09-abd7-465c-aefe-06befb33af0c" sourceRef="id_14" targetRef="id_15"/>
    <exclusiveGateway id="boundary_join_id_15" name="boundary_join_id_15">
      <incoming>sequenceFlow_1966e64e-1b19-4405-b1eb-07bb5cf274d6</incoming>
      <incoming>sequenceFlow_6c36b7b7-807f-4134-9fe3-ad0a0eed2b7f</incoming>
      <outgoing>sequenceFlow_be0153b1-6170-4881-bd5b-e366efe5a435</outgoing>
    </exclusiveGateway>
    <sequenceFlow id="sequenceFlow_1966e64e-1b19-4405-b1eb-07bb5cf274d6" sourceRef="id_15" targetRef="boundary_join_id_15"/>
    <boundaryEvent attachedToRef="id_15" id="boundary_error_id_15" name="boundary_error_id_15">
      <outgoing>sequenceFlow_6c36b7b7-807f-4134-9fe3-ad0a0eed2b7f</outgoing>
      <errorEventDefinition errorRef="error_5ac37199-e28a-4ee2-a12a-ae8d03e9ae9c" id="errorEventDefinition_8dc95f22-17a0-43d7-9213-f9a0c42bae28"/>
    </boundaryEvent>
    <sequenceFlow id="sequenceFlow_6c36b7b7-807f-4134-9fe3-ad0a0eed2b7f" sourceRef="boundary_error_id_15" targetRef="boundary_join_id_15"/>
    <sequenceFlow id="sequenceFlow_be0153b1-6170-4881-bd5b-e366efe5a435" sourceRef="boundary_join_id_15" targetRef="join_id_6"/>
    <intermediateThrowEvent id="id_16" name="id_16">
      <incoming>sequenceFlow_701050dd-9276-4d04-b5c7-4b928e639958</incoming>
      <outgoing>sequenceFlow_87c43e7a-ddd2-454e-a9db-d4803b28b7bc</outgoing>
    </intermediateThrowEvent>
    <sequenceFlow id="sequenceFlow_701050dd-9276-4d04-b5c7-4b928e639958" sourceRef="join_id_6" targetRef="id_16"/>
    <parallelGateway id="join_id_2" name="join_id_2">
      <incoming>sequenceFlow_87c43e7a-ddd2-454e-a9db-d4803b28b7bc</incoming>
      <incoming>sequenceFlow_d6647ca8-a395-48e0-a239-be80e2fee42f</incoming>
      <outgoing>sequenceFlow_2f3b2566-baf3-43d3-8f17-b057828e0a0d</outgoing>
    </parallelGateway>
    <sequenceFlow id="sequenceFlow_87c43e7a-ddd2-454e-a9db-d4803b28b7bc" sourceRef="id_16" targetRef="join_id_2"/>
    <sequenceFlow id="id_17" sourceRef="fork_id_1" targetRef="id_19"/>
    <sendTask id="id_19" name="id_19">
      <extensionElements>
        <ns0:taskDefinition retries="3" type="job_id_19"/>
      </extensionElements>
      <incoming>id_17</incoming>
      <outgoing>sequenceFlow_312291fa-c13a-420b-b255-3c751a7e91db</outgoing>
    </sendTask>
    <boundaryEvent attachedToRef="id_19" id="boundary_error_id_19" name="boundary_error_id_19">
      <outgoing>sequenceFlow_a0b3dc68-5166-4603-9d0e-f7efe63ca00e</outgoing>
      <errorEventDefinition errorRef="error_6b8bab39-3974-48d2-93c3-e455451b4459" id="errorEventDefinition_f7270187-53a2-4013-8c24-7679f9a77981"/>
    </boundaryEvent>
    <endEvent id="endEvent_08886732-54b4-48bc-8a01-8c321434bb30">
      <incoming>sequenceFlow_a0b3dc68-5166-4603-9d0e-f7efe63ca00e</incoming>
      <terminateEventDefinition id="terminateEventDefinition_0b051d40-f954-4f84-aa89-f4e68f8ebd68"/>
    </endEvent>
    <sequenceFlow id="sequenceFlow_a0b3dc68-5166-4603-9d0e-f7efe63ca00e" sourceRef="boundary_error_id_19" targetRef="endEvent_08886732-54b4-48bc-8a01-8c321434bb30"/>
    <parallelGateway id="fork_id_20" name="fork_id_20">
      <incoming>sequenceFlow_312291fa-c13a-420b-b255-3c751a7e91db</incoming>
      <outgoing>sequenceFlow_0e67445f-02e7-487f-8fb8-31439e7d17d3</outgoing>
      <outgoing>id_25</outgoing>
    </parallelGateway>
    <sequenceFlow id="sequenceFlow_312291fa-c13a-420b-b255-3c751a7e91db" sourceRef="id_19" targetRef="fork_id_20"/>
    <receiveTask id="id_24" messageRef="message_c6569522-d503-4e5b-8a23-915ce760fae7" name="id_24">
      <incoming>sequenceFlow_0e67445f-02e7-487f-8fb8-31439e7d17d3</incoming>
      <outgoing>sequenceFlow_5ef5e0fb-456c-4497-892d-944791fd22d7</outgoing>
    </receiveTask>
    <sequenceFlow id="sequenceFlow_0e67445f-02e7-487f-8fb8-31439e7d17d3" sourceRef="fork_id_20" targetRef="id_24"/>
    <parallelGateway id="join_id_21" name="join_id_21">
      <incoming>sequenceFlow_5ef5e0fb-456c-4497-892d-944791fd22d7</incoming>
      <incoming>sequenceFlow_15331b20-64ac-45a9-a306-ec73c580ea7b</incoming>
      <outgoing>sequenceFlow_d6647ca8-a395-48e0-a239-be80e2fee42f</outgoing>
    </parallelGateway>
    <sequenceFlow id="sequenceFlow_5ef5e0fb-456c-4497-892d-944791fd22d7" sourceRef="id_24" targetRef="join_id_21"/>
    <sequenceFlow id="id_25" sourceRef="fork_id_20" targetRef="id_27"/>
    <sendTask id="id_27" name="id_27">
      <extensionElements>
        <ns0:taskDefinition retries="3" type="job_id_27"/>
      </extensionElements>
      <incoming>id_25</incoming>
      <outgoing>sequenceFlow_15331b20-64ac-45a9-a306-ec73c580ea7b</outgoing>
    </sendTask>
    <sequenceFlow id="sequenceFlow_15331b20-64ac-45a9-a306-ec73c580ea7b" sourceRef="id_27" targetRef="join_id_21"/>
    <sequenceFlow id="sequenceFlow_d6647ca8-a395-48e0-a239-be80e2fee42f" sourceRef="join_id_21" targetRef="join_id_2"/>
    <intermediateThrowEvent id="id_28" name="id_28">
      <incoming>sequenceFlow_2f3b2566-baf3-43d3-8f17-b057828e0a0d</incoming>
      <outgoing>sequenceFlow_69652bff-7acd-4ae9-8aa4-1e98aca8702d</outgoing>
    </intermediateThrowEvent>
    <sequenceFlow id="sequenceFlow_2f3b2566-baf3-43d3-8f17-b057828e0a0d" sourceRef="join_id_2" targetRef="id_28"/>
    <serviceTask id="id_29" name="id_29">
      <extensionElements>
        <ns0:taskDefinition retries="3" type="job_id_29"/>
      </extensionElements>
      <incoming>sequenceFlow_69652bff-7acd-4ae9-8aa4-1e98aca8702d</incoming>
      <outgoing>sequenceFlow_e257fa2a-40d3-451b-8103-d4f2b57da756</outgoing>
    </serviceTask>
    <sequenceFlow id="sequenceFlow_69652bff-7acd-4ae9-8aa4-1e98aca8702d" sourceRef="id_28" targetRef="id_29"/>
    <receiveTask id="id_30" messageRef="message_2f873a0d-6470-4a99-b6e4-9097f52e2fd7" name="id_30">
      <incoming>sequenceFlow_e257fa2a-40d3-451b-8103-d4f2b57da756</incoming>
      <outgoing>sequenceFlow_a6c68634-ccce-41f6-889a-e3c72213c77a</outgoing>
    </receiveTask>
    <sequenceFlow id="sequenceFlow_e257fa2a-40d3-451b-8103-d4f2b57da756" sourceRef="id_29" targetRef="id_30"/>
    <endEvent id="id_34" name="id_34">
      <incoming>sequenceFlow_a6c68634-ccce-41f6-889a-e3c72213c77a</incoming>
    </endEvent>
    <sequenceFlow id="sequenceFlow_a6c68634-ccce-41f6-889a-e3c72213c77a" sourceRef="id_30" targetRef="id_34"/>
  </process>
  <message id="message_fbbd50d9-8ca7-4023-abc3-fea116f99f4c" name="message_eventSubProcess_id_32">
    <extensionElements>
      <ns0:subscription correlationKey="=&quot;default_correlation_key&quot;"/>
    </extensionElements>
  </message>
  <message id="message_4b9def63-904f-46be-9ced-ace27971f9a9" name="message_id_7">
    <extensionElements>
      <ns0:subscription correlationKey="=correlationKey"/>
    </extensionElements>
  </message>
  <message id="message_e684e79e-87e1-4d6a-bdd5-150db066b2de" name="message_id_10">
    <extensionElements>
      <ns0:subscription correlationKey="=correlationKey"/>
    </extensionElements>
  </message>
  <message id="message_484be778-5a53-4f74-8069-38f5a20884c0" name="message_id_11">
    <extensionElements>
      <ns0:subscription correlationKey="=correlationKey"/>
    </extensionElements>
  </message>
  <message id="message_f05d17fe-28a3-460f-add2-030902aaeb84" name="message_id_14">
    <extensionElements>
      <ns0:subscription correlationKey="=correlationKey"/>
    </extensionElements>
  </message>
  <error errorCode="error_id_15" id="error_5ac37199-e28a-4ee2-a12a-ae8d03e9ae9c"/>
  <error errorCode="error_id_19" id="error_6b8bab39-3974-48d2-93c3-e455451b4459"/>
  <message id="message_c6569522-d503-4e5b-8a23-915ce760fae7" name="message_id_24">
    <extensionElements>
      <ns0:subscription correlationKey="=correlationKey"/>
    </extensionElements>
  </message>
  <message id="message_2f873a0d-6470-4a99-b6e4-9097f52e2fd7" name="message_id_30">
    <extensionElements>
      <ns0:subscription correlationKey="=correlationKey"/>
    </extensionElements>
  </message>
  <bpmndi:BPMNDiagram id="BPMNDiagram_e6a56cc6-ba9d-4746-8f29-80429be3ba30">
    <bpmndi:BPMNPlane bpmnElement="process_id_31" id="BPMNPlane_39207662-e995-4d14-bca2-743c18d0e87a">
      <bpmndi:BPMNShape bpmnElement="eventSubProcess_id_32" id="BPMNShape_c6cc6472-23c4-496f-a34c-d0f1700a52cc" isExpanded="true">
        <dc:Bounds height="200.0" width="350.0" x="100.0" y="250.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNShape bpmnElement="start_event_eventSubProcess_id_32" id="BPMNShape_6cfb394c-b94f-4217-aed4-c583037fb31d">
        <dc:Bounds height="36.0" width="36.0" x="150.0" y="332.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNShape bpmnElement="end_event_eventSubProcess_id_32" id="BPMNShape_14f98dce-a01b-4f80-b2c6-6d875846084f">
        <dc:Bounds height="36.0" width="36.0" x="236.0" y="332.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_a9590150-e468-4ef5-a619-ea3d1c5c1185" id="BPMNEdge_7867fb75-be11-4cb1-8839-9f277df9e62a">
        <di:waypoint x="186.0" y="350.0"/>
        <di:waypoint x="236.0" y="350.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="id_33" id="BPMNShape_cc9dc775-d109-4ace-8e76-0000d071dfbd">
        <dc:Bounds height="36.0" width="36.0" x="100.0" y="100.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNShape bpmnElement="id_33_variables_task" id="BPMNShape_7bdd6841-76ad-424d-8760-c2b42f008efd">
        <dc:Bounds height="80.0" width="100.0" x="186.0" y="78.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_45aaea09-9125-41e9-81bb-2b39e62519df" id="BPMNEdge_c51168fa-3235-4077-9911-242245f3d053">
        <di:waypoint x="136.0" y="118.0"/>
        <di:waypoint x="186.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="fork_id_1" id="BPMNShape_c6909932-662d-4ef7-8da8-4f09b84e26f1">
        <dc:Bounds height="50.0" width="50.0" x="336.0" y="93.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_587dcc9d-0c19-4df7-9f03-cad471ce039f" id="BPMNEdge_249d5b2c-e52d-40f3-a32b-8d8171cd9a00">
        <di:waypoint x="286.0" y="118.0"/>
        <di:waypoint x="336.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="fork_id_5" id="BPMNShape_5e2c4470-d809-4cf0-b8ce-603e9ae4968d">
        <dc:Bounds height="50.0" width="50.0" x="436.0" y="93.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_29385c24-a2a7-4b40-bbb3-ee13ab4e6d43" id="BPMNEdge_fa3c548f-80c9-4fe9-a623-8ca1a5884cbe">
        <di:waypoint x="386.0" y="118.0"/>
        <di:waypoint x="436.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="id_7" id="BPMNShape_6bb87031-3364-425a-aa50-95874c6d16dc">
        <dc:Bounds height="36.0" width="36.0" x="536.0" y="100.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_aa4cd94b-f49b-49db-8dad-c7d9d13b55ac" id="BPMNEdge_d7fe1c72-3843-441b-87fe-c76fa9b9a398">
        <di:waypoint x="486.0" y="118.0"/>
        <di:waypoint x="536.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="id_9" id="BPMNShape_f4d6761b-0e7c-4756-bf8c-a6338d1fda55">
        <dc:Bounds height="80.0" width="100.0" x="622.0" y="78.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_f9dbd643-a9ec-42be-96ec-9273adf54916" id="BPMNEdge_3278d731-35cc-48fa-bf3a-b36a145e8c3b">
        <di:waypoint x="572.0" y="118.0"/>
        <di:waypoint x="622.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="id_10" id="BPMNShape_e0a6e754-aaf4-42e0-bf27-b2a2ac16ef6d">
        <dc:Bounds height="80.0" width="100.0" x="772.0" y="78.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_232708f4-46a5-4487-8c40-08b4a70a6575" id="BPMNEdge_1362675d-4360-41de-8218-0582681faba1">
        <di:waypoint x="722.0" y="118.0"/>
        <di:waypoint x="772.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="join_id_6" id="BPMNShape_99a8b685-d8b1-4a08-bcdd-d2ec946e8cc3" isMarkerVisible="true">
        <dc:Bounds height="50.0" width="50.0" x="922.0" y="93.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_a668c4e1-e00d-497c-83fe-add77aacb7e4" id="BPMNEdge_7c13ce2c-3c98-48e4-b1de-e03aa51d4067">
        <di:waypoint x="872.0" y="118.0"/>
        <di:waypoint x="922.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="id_11" id="BPMNShape_813ce73d-1118-4344-bc64-0faff27bc01d">
        <dc:Bounds height="36.0" width="36.0" x="536.0" y="186.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_01977fa3-8137-4c19-b90f-e889a0dea876" id="BPMNEdge_5558f5b7-847f-4e8b-8124-129349dc7476">
        <di:waypoint x="461.0" y="143.0"/>
        <di:waypoint x="461.0" y="204.0"/>
        <di:waypoint x="536.0" y="204.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="id_13" id="BPMNShape_18cf768b-867e-411a-96e5-20710497bf1e">
        <dc:Bounds height="80.0" width="100.0" x="622.0" y="164.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_6a6bc975-df65-46b6-b0b8-e6fe3a819695" id="BPMNEdge_6d52e16d-9c7c-4497-9983-94b64cd0cc44">
        <di:waypoint x="572.0" y="204.0"/>
        <di:waypoint x="622.0" y="204.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="id_14" id="BPMNShape_39e218a0-f888-4114-9c66-1f6b99a8ea51">
        <dc:Bounds height="80.0" width="100.0" x="772.0" y="164.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_99f0bf65-7e28-403f-a60a-067393e47e30" id="BPMNEdge_b9a0d8a2-aee9-4f72-a4a1-d90d9f0df19e">
        <di:waypoint x="722.0" y="204.0"/>
        <di:waypoint x="772.0" y="204.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="id_15" id="BPMNShape_89e307b3-64e1-4c5b-83cc-084bfa574733">
        <dc:Bounds height="80.0" width="100.0" x="922.0" y="164.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_2eea8e09-abd7-465c-aefe-06befb33af0c" id="BPMNEdge_c524a2ec-a48d-4cc6-90e9-c16022e21b92">
        <di:waypoint x="872.0" y="204.0"/>
        <di:waypoint x="922.0" y="204.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="boundary_join_id_15" id="BPMNShape_403960d6-e103-4172-b58e-a4cc66e91860" isMarkerVisible="true">
        <dc:Bounds height="50.0" width="50.0" x="1072.0" y="179.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_1966e64e-1b19-4405-b1eb-07bb5cf274d6" id="BPMNEdge_75d49615-b4ef-4565-bacd-62f5936cb971">
        <di:waypoint x="1022.0" y="204.0"/>
        <di:waypoint x="1072.0" y="204.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="boundary_error_id_15" id="BPMNShape_a9367a1f-a09d-49ed-a8ff-0390975b830f">
        <dc:Bounds height="36.0" width="36.0" x="954.0" y="226.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_6c36b7b7-807f-4134-9fe3-ad0a0eed2b7f" id="BPMNEdge_752f8fbc-b1e3-492e-9db3-eacb9cbf6fcd">
        <di:waypoint x="972.0" y="262.0"/>
        <di:waypoint x="972.0" y="312.0"/>
        <di:waypoint x="1072.0" y="204.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_be0153b1-6170-4881-bd5b-e366efe5a435" id="BPMNEdge_458676aa-d3f9-4c83-8a84-cc59f73dd247">
        <di:waypoint x="1122.0" y="204.0"/>
        <di:waypoint x="922.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="id_16" id="BPMNShape_38400f30-9a79-4a6b-8b6a-018cbbb2c3ac">
        <dc:Bounds height="36.0" width="36.0" x="1022.0" y="100.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_701050dd-9276-4d04-b5c7-4b928e639958" id="BPMNEdge_72c66f5d-2790-4fd7-9e4a-83236603541d">
        <di:waypoint x="972.0" y="118.0"/>
        <di:waypoint x="1022.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="join_id_2" id="BPMNShape_0a0ea582-74e4-488c-aa6e-41474d7924d4">
        <dc:Bounds height="50.0" width="50.0" x="1108.0" y="93.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_87c43e7a-ddd2-454e-a9db-d4803b28b7bc" id="BPMNEdge_2c47a67f-330b-4cfa-9ad5-4795e008d585">
        <di:waypoint x="1058.0" y="118.0"/>
        <di:waypoint x="1108.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="id_19" id="BPMNShape_9c3bfc74-6bab-400f-b826-4adfc600e4e1">
        <dc:Bounds height="80.0" width="100.0" x="436.0" y="193.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="id_17" id="BPMNEdge_5aced077-4f03-4227-8d2c-8d163cdd03f2">
        <di:waypoint x="361.0" y="143.0"/>
        <di:waypoint x="361.0" y="233.0"/>
        <di:waypoint x="436.0" y="233.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="boundary_error_id_19" id="BPMNShape_b18576c2-a826-48ad-9fcd-617c16f0dfcb">
        <dc:Bounds height="36.0" width="36.0" x="468.0" y="255.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNShape bpmnElement="endEvent_08886732-54b4-48bc-8a01-8c321434bb30" id="BPMNShape_185de757-20a4-4051-a85f-250ac6f4c529">
        <dc:Bounds height="36.0" width="36.0" x="516.5" y="323.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_a0b3dc68-5166-4603-9d0e-f7efe63ca00e" id="BPMNEdge_caaf412e-ea98-4da1-877a-9b6e7e66622c">
        <di:waypoint x="486.0" y="291.0"/>
        <di:waypoint x="486.0" y="341.0"/>
        <di:waypoint x="516.5" y="341.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="fork_id_20" id="BPMNShape_c6f35014-29bf-4792-8627-6d7ea7296589">
        <dc:Bounds height="50.0" width="50.0" x="586.0" y="208.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_312291fa-c13a-420b-b255-3c751a7e91db" id="BPMNEdge_1b496df8-7ae1-45e0-9931-6841ed78757b">
        <di:waypoint x="536.0" y="233.0"/>
        <di:waypoint x="586.0" y="233.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="id_24" id="BPMNShape_1011739e-c910-44a0-9780-0c205e39d228">
        <dc:Bounds height="80.0" width="100.0" x="686.0" y="193.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_0e67445f-02e7-487f-8fb8-31439e7d17d3" id="BPMNEdge_015e1915-4a70-4ded-8aa9-ae63e5cf426e">
        <di:waypoint x="636.0" y="233.0"/>
        <di:waypoint x="686.0" y="233.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="join_id_21" id="BPMNShape_0d162c80-31c9-4337-a27a-ed633ee75469">
        <dc:Bounds height="50.0" width="50.0" x="836.0" y="208.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_5ef5e0fb-456c-4497-892d-944791fd22d7" id="BPMNEdge_88647946-bc98-44fb-b5c4-d4dd7b4d0a34">
        <di:waypoint x="786.0" y="233.0"/>
        <di:waypoint x="836.0" y="233.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="id_27" id="BPMNShape_bdd2da42-f643-4ad8-931d-e8a45b79e773">
        <dc:Bounds height="80.0" width="100.0" x="686.0" y="323.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="id_25" id="BPMNEdge_92087ec8-cd68-4813-9f82-7b9b9e40b566">
        <di:waypoint x="611.0" y="258.0"/>
        <di:waypoint x="611.0" y="363.0"/>
        <di:waypoint x="686.0" y="363.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_15331b20-64ac-45a9-a306-ec73c580ea7b" id="BPMNEdge_ded4f5af-8d2f-4b08-84dd-bc6218366092">
        <di:waypoint x="786.0" y="363.0"/>
        <di:waypoint x="836.0" y="233.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_d6647ca8-a395-48e0-a239-be80e2fee42f" id="BPMNEdge_a18b95b0-2ea0-4b3d-b0ae-17cf8a007390">
        <di:waypoint x="886.0" y="233.0"/>
        <di:waypoint x="1108.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="id_28" id="BPMNShape_78c0045b-ee1a-4f09-9a03-032751017108">
        <dc:Bounds height="36.0" width="36.0" x="1208.0" y="100.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_2f3b2566-baf3-43d3-8f17-b057828e0a0d" id="BPMNEdge_e461161a-07c9-4738-9fa3-2f2014860719">
        <di:waypoint x="1158.0" y="118.0"/>
        <di:waypoint x="1208.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="id_29" id="BPMNShape_4542f301-f47f-49f2-ba2f-9bb3475e3a32">
        <dc:Bounds height="80.0" width="100.0" x="1294.0" y="78.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_69652bff-7acd-4ae9-8aa4-1e98aca8702d" id="BPMNEdge_3510ee5f-298c-422d-b0df-e029943668cd">
        <di:waypoint x="1244.0" y="118.0"/>
        <di:waypoint x="1294.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="id_30" id="BPMNShape_d5701265-b457-487d-847a-2a66c1c276ea">
        <dc:Bounds height="80.0" width="100.0" x="1444.0" y="78.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_e257fa2a-40d3-451b-8103-d4f2b57da756" id="BPMNEdge_0c5cb4c9-817a-46c8-95e9-264ca973b9a8">
        <di:waypoint x="1394.0" y="118.0"/>
        <di:waypoint x="1444.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="id_34" id="BPMNShape_68c29df4-49dd-4958-bd4b-7c1106fa49d6">
        <dc:Bounds height="36.0" width="36.0" x="1594.0" y="100.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_a6c68634-ccce-41f6-889a-e3c72213c77a" id="BPMNEdge_1b2723b9-d2b0-4961-bf3a-524fd33a87e3">
        <di:waypoint x="1544.0" y="118.0"/>
        <di:waypoint x="1594.0" y="118.0"/>
      </bpmndi:BPMNEdge>
    </bpmndi:BPMNPlane>
  </bpmndi:BPMNDiagram>
</definitions>


--------------- Decomposed keys (for debugging) -----------------
  -1 <-> -1
K001 <-> 2251799813685249
K002 <-> 2251799813685250
K003 <-> 2251799813685251
K004 <-> 2251799813685252
K005 <-> 2251799813685253
K006 <-> 2251799813685254
K007 <-> 2251799813685255
K008 <-> 2251799813685256
K009 <-> 2251799813685257
K010 <-> 2251799813685258
K011 <-> 2251799813685259
K012 <-> 2251799813685260
K013 <-> 2251799813685261
K014 <-> 2251799813685262
K015 <-> 2251799813685263
K016 <-> 2251799813685264
K017 <-> 2251799813685265
K018 <-> 2251799813685266
K019 <-> 2251799813685267
K020 <-> 2251799813685268
K021 <-> 2251799813685269
K022 <-> 2251799813685270
K023 <-> 2251799813685271
K024 <-> 2251799813685272
K025 <-> 2251799813685273
K026 <-> 2251799813685274
K027 <-> 2251799813685275
K028 <-> 2251799813685276
K029 <-> 2251799813685277
K030 <-> 2251799813685278
K031 <-> 2251799813685279
K032 <-> 2251799813685280
K033 <-> 2251799813685281
K034 <-> 2251799813685282
K035 <-> 2251799813685283
K036 <-> 2251799813685284
K037 <-> 2251799813685285
K038 <-> 2251799813685286
K039 <-> 2251799813685287
K040 <-> 2251799813685288
K041 <-> 2251799813685289
K042 <-> 2251799813685290
K043 <-> 2251799813685291
K044 <-> 2251799813685292
K045 <-> 2251799813685293
K046 <-> 2251799813685294
K047 <-> 2251799813685295
K048 <-> 2251799813685296
K049 <-> 2251799813685297
K050 <-> 2251799813685298
K051 <-> 2251799813685299
K052 <-> 2251799813685300
K053 <-> 2251799813685301
K054 <-> 2251799813685302
K055 <-> 2251799813685303
K056 <-> 2251799813685304
K057 <-> 2251799813685305
K058 <-> 2251799813685306
K059 <-> 2251799813685307
K060 <-> 2251799813685308
K061 <-> 2251799813685309
K062 <-> 2251799813685310
K063 <-> 2251799813685311
K064 <-> 2251799813685312
K065 <-> 2251799813685313
K066 <-> 2251799813685314
K067 <-> 2251799813685315

15:41:30.053 [] DEBUG io.camunda.zeebe.broker.logstreams - Close stream processor
15:41:30.053 [Broker-0-StreamProcessor-1] DEBUG io.camunda.zeebe.logstreams - Closed stream processor controller Broker-0-StreamProcessor-1.
15:41:30.096 [Broker-0-LogStream-1] INFO  io.camunda.zeebe.logstreams - Close appender for log stream stream-1
15:41:30.099 [stream-1-write-buffer] DEBUG io.camunda.zeebe.dispatcher - Dispatcher closed
15:41:30.099 [Broker-0-LogStream-1] INFO  io.camunda.zeebe.logstreams - On closing logstream stream-1 close 2 readers
15:41:30.100 [] DEBUG io.camunda.zeebe.broker.test - Clean up test files on path /tmp/junit9602225376467888785
15:41:30.100 [] DEBUG io.camunda.zeebe.util.actor - Closing actor thread ground '-zb-fs-workers'
15:41:30.100 [] DEBUG io.camunda.zeebe.util.actor - Closing actor thread ground '-zb-actors'
15:41:30.101 [] DEBUG io.camunda.zeebe.util.actor - Closing actor thread ground '-zb-fs-workers': closed successfully
15:41:30.101 [] DEBUG io.camunda.zeebe.util.actor - Closing actor thread ground '-zb-actors': closed successfully
15:41:30.102 [] INFO  io.camunda.zeebe.engine.processing.randomized.FailedPropertyBasedTestDataPrinter - Data of failed test case: TestDataRecord{processSeed=2036895956970042930, executionPathSeed=1685396434835304029}
15:41:30.122 [] INFO  io.camunda.zeebe.engine.processing.randomized.FailedPropertyBasedTestDataPrinter - Test case failed at: ScheduledExecutionStep{step=StepActivateJobAndThrowError[elementId=id_19,errorCode=error_id_19,jobType=job_id_19,variables={}], activationTime=PT1H2M, startTime=PT4H2M, endTime=PT4H2M, activationDuration=PT3H}
15:41:30.125 [] INFO  io.camunda.zeebe.engine.processing.randomized.FailedPropertyBasedTestDataPrinter - Execution path of failed test case:
ScheduledExecutionStep{step=StepTriggerTimerStartEvent[timeToAdd=PT1H2M,variables={}], activationTime=PT0S, startTime=PT0S, endTime=PT1H2M, activationDuration=PT1H2M}
ScheduledExecutionStep{step=StepActivateAndCompleteJob[elementId=id_33_variables_task,jobType=id_33_variables,variables={correlationKey=default_correlation_key}], activationTime=PT1H2M, startTime=PT1H2M, endTime=PT1H2M, activationDuration=PT0S}
ScheduledExecutionStep{step=StepActivateBPMNElement[elementId=fork_id_1,variables={}], activationTime=PT1H2M, startTime=PT1H2M, endTime=PT1H2M, activationDuration=PT0S}
ScheduledExecutionStep{step=StepActivateBPMNElement[elementId=id_19,variables={}], activationTime=PT1H2M, startTime=PT1H2M, endTime=PT1H2M, activationDuration=PT0S}
ScheduledExecutionStep{step=StepPublishMessage[correlationKeyValue=default_correlation_key,messageName=message_id_11,variables={correlationKey=default_correlation_key}], activationTime=PT1H2M, startTime=PT1H2M, endTime=PT1H2M, activationDuration=PT0S}
ScheduledExecutionStep{step=StepActivateBPMNElement[elementId=id_13,variables={}], activationTime=PT1H2M, startTime=PT1H2M, endTime=PT1H2M, activationDuration=PT0S}
ScheduledExecutionStep{step=StepActivateAndTimeoutJob[elementId=id_13,jobType=job_id_13,variables={}], activationTime=PT1H2M, startTime=PT1H2M, endTime=PT2H2M, activationDuration=PT1H}
ScheduledExecutionStep{step=StepActivateAndFailJob[elementId=id_13,jobType=job_id_13,updateRetries=false,variables={}], activationTime=PT2H2M, startTime=PT2H2M, endTime=PT2H2M, activationDuration=PT0S}
ScheduledExecutionStep{step=StepActivateAndTimeoutJob[elementId=id_19,jobType=job_id_19,variables={}], activationTime=PT1H2M, startTime=PT2H2M, endTime=PT3H2M, activationDuration=PT2H}
ScheduledExecutionStep{step=StepActivateAndCompleteJob[elementId=id_13,jobType=job_id_13,variables={}], activationTime=PT1H2M, startTime=PT3H2M, endTime=PT3H2M, activationDuration=PT2H}
ScheduledExecutionStep{step=StepActivateAndFailJob[elementId=id_19,jobType=job_id_19,updateRetries=true,variables={}], activationTime=PT3H2M, startTime=PT3H2M, endTime=PT3H2M, activationDuration=PT0S}
ScheduledExecutionStep{step=StepPublishMessage[correlationKeyValue=default_correlation_key,messageName=message_id_14,variables={correlationKey=default_correlation_key}], activationTime=PT3H2M, startTime=PT3H2M, endTime=PT3H2M, activationDuration=PT0S}
ScheduledExecutionStep{step=StepActivateBPMNElement[elementId=id_15,variables={}], activationTime=PT3H2M, startTime=PT3H2M, endTime=PT3H2M, activationDuration=PT0S}
ScheduledExecutionStep{step=StepActivateAndTimeoutJob[elementId=id_15,jobType=job_id_15,variables={}], activationTime=PT3H2M, startTime=PT3H2M, endTime=PT4H2M, activationDuration=PT1H}
ScheduledExecutionStep{step=StepActivateAndFailJob[elementId=id_15,jobType=job_id_15,updateRetries=true,variables={}], activationTime=PT4H2M, startTime=PT4H2M, endTime=PT4H2M, activationDuration=PT0S}
ScheduledExecutionStep{step=StepActivateJobAndThrowError[elementId=id_15,errorCode=error_id_15,jobType=job_id_15,variables={}], activationTime=PT3H2M, startTime=PT4H2M, endTime=PT4H2M, activationDuration=PT1H}
ScheduledExecutionStep{step=StepActivateBPMNElement[elementId=id_16,variables={}], activationTime=PT4H2M, startTime=PT4H2M, endTime=PT4H2M, activationDuration=PT0S}
--failed here--> ScheduledExecutionStep{step=StepActivateJobAndThrowError[elementId=id_19,errorCode=error_id_19,jobType=job_id_19,variables={}], activationTime=PT1H2M, startTime=PT4H2M, endTime=PT4H2M, activationDuration=PT3H}

@remcowesterhoud
Copy link
Contributor

My quick initial analysis

  • We have 2 commands on the log stream. 1 ACTIVATE intermediate none event and 1 ACTIVATE terminate end event
  • We process the command for the intermediate none event. Processing does nothing, we write a COMPLETE command for this event
  • We process the command for the terminate end event. It writes a TERMINATE command for all active elements. One of which is the intermediate none event. The terminate end events is transitioned to COMPLETING and COMPLETED (no COMPLETE command is written for this)
  • We now have 2 commands on the log stream for the intermediate none event. 1 COMPLETE command and 1 TERMINATE command.
  • The COMPLETE command gets processed. The intermediate none event takes its outgoing sequence flows and activates the next element
  • The TERMINATE command gets rejected. The intermediate none event has already moved on and is no longer active. It cannot be terminated.
  • The process contains active elements. The process will not be completed.

@remcowesterhoud remcowesterhoud added the kind/bug Categorizes an issue or PR as a bug label Nov 29, 2022
@megglos
Copy link
Contributor

megglos commented Dec 1, 2022

happened another time TestDataRecord{processSeed=4020342130641592473, executionPathSeed=7049668446223977370

logs
09:19:49.794 [] INFO  io.camunda.zeebe.test - Test failed, following records were exported:
09:19:49.955 [] INFO  io.camunda.zeebe.test - Compact log representation:
--------
	[Timestamp] ['C'ommand/'E'event/'R'ejection] [valueType] [intent] - #[position]->#[source record position] K[key] - [summary of value]
	P9K999 - key; #999 - record position; "ID" element/process id; @"elementid"/[P9K999] - element with ID and key
	Keys are decomposed into partition id and per partition key (e.g. 2251799813685253 -> P1K005). If single partition, the partition is omitted.
	Long IDs are shortened (e.g. 'startEvent_5d56488e-0570-416c-ba2d-36d2a3acea78' -> 'star..acea78'
--------
09:19:44 C DPLY         CREATE            - #001->  -1   -1 - 
09:19:44 E PROC         CREATED           - #002->#001 K001 - process.xml -> "process_id_27" (version:1)
09:19:44 E PROC         CREATED           - #003->#001 K002 - process.xml -> "process..d_id_23" (version:1)
09:19:44 E TIME         CREATED           - #004->#001 K004 -  @"id_28"[  -1]  in <process "K001"[?]> due T09:26:44.522
09:19:44 E DPLY         CREATED           - #005->#001 K003 - process.xml, process.xml
09:19:44 E DPLY         FULLY_DISTRIBUTED - #006->#001 K003 - 
09:26:44 C TIME         TRIGGER           - #007->  -1 K004 -  @"id_28"[  -1]  in <process "K001"[?]> due T09:26:44.522
09:26:44 E TIME         TRIGGERED         - #008->#007 K004 -  @"id_28"[  -1]  in <process "K001"[K005]> due T09:26:44.522
09:26:44 E PROC_EVNT    TRIGGERING        - #009->#007 K006 -  @"id_28"[K001] in <process K001[K005]> (no vars)
09:26:44 C PI           ACTIVATE          - #010->#007 K005 - PROCESS "process_id_27" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATING        - #011->#010 K005 - PROCESS "process_id_27" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATED         - #012->#010 K005 - PROCESS "process_id_27" in <process "process_id_27"[K005]>
09:26:44 E PROC_EVNT    TRIGGERED         - #013->#010 K006 -  @"id_28"[K001] in <process K001[K005]> (no vars)
09:26:44 E PI           ACTIVATING        - #014->#010 K007 - START_EVENT "id_28" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATED         - #015->#010 K007 - START_EVENT "id_28" in <process "process_id_27"[K005]>
09:26:44 C PI           COMPLETE          - #016->#010 K007 - START_EVENT "id_28" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETING        - #017->#016 K007 - START_EVENT "id_28" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETED         - #018->#016 K007 - START_EVENT "id_28" in <process "process_id_27"[K005]>
09:26:44 E PI           SEQ_FLOW_TAKEN    - #019->#016 K008 - SEQUENCE_FLOW "sequenc..e9a7aef" in <process "process_id_27"[K005]>
09:26:44 C PI           ACTIVATE          - #020->#016 K009 - SERVICE_TASK "id_28_v..es_task" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATING        - #021->#020 K009 - SERVICE_TASK "id_28_v..es_task" in <process "process_id_27"[K005]>
09:26:44 E JOB          CREATED           - #022->#020 K010 - K010 "id_28_variables" @"id_28_v..es_task"[K009] 3 retries, in <process "process_id_27"[K005]> (no vars)
09:26:44 E PI           ACTIVATED         - #023->#020 K009 - SERVICE_TASK "id_28_v..es_task" in <process "process_id_27"[K005]>
09:26:44 C JOB_BATCH    ACTIVATE          - #024->  -1   -1 - "id_28_variables" max: 10
09:26:44 E JOB_BATCH    ACTIVATED         - #025->#024 K011 - "id_28_variables" 1/10
                   K010 "id_28_variables" @"id_28_v..es_task"[K009] 3 retries, in <process "process_id_27"[K005]> (no vars)
09:26:44 C JOB          COMPLETE          - #026->  -1 K010 - K010 -1 retries, in <process ?[?]> with variables: {boundary_timer_id_10=PT8760H, correlationKey=default_correlation_key}
09:26:44 E JOB          COMPLETED         - #027->#026 K010 - K010 "id_28_variables" @"id_28_v..es_task"[K009] 3 retries, in <process "process_id_27"[K005]> with variables: {boundary_timer_id_10=PT8760H, correlationKey=default_correlation_key}
09:26:44 E PROC_EVNT    TRIGGERING        - #028->#026 K012 -  @"id_28_v..es_task"[K009] in <process K001[K005]> with variables: {boundary_timer_id_10=PT8760H, correlationKey=default_correlation_key}
09:26:44 C PI           COMPLETE          - #029->#026 K009 - SERVICE_TASK "id_28_v..es_task" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETING        - #030->#029 K009 - SERVICE_TASK "id_28_v..es_task" in <process "process_id_27"[K005]>
09:26:44 E VAR          CREATED           - #031->#029 K013 - boundary_timer_id_10->"PT8760H" in <process [K005]>
09:26:44 E VAR          CREATED           - #032->#029 K014 - correlationKey->"default_correlation_key" in <process [K005]>
09:26:44 E PI           COMPLETED         - #033->#029 K009 - SERVICE_TASK "id_28_v..es_task" in <process "process_id_27"[K005]>
09:26:44 E PI           SEQ_FLOW_TAKEN    - #034->#029 K015 - SEQUENCE_FLOW "sequenc..ec76c6b" in <process "process_id_27"[K005]>
09:26:44 C PI           ACTIVATE          - #035->#029 K016 - INTERMEDIATE_CATCH_EVENT "id_1" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATING        - #036->#035 K016 - INTERMEDIATE_CATCH_EVENT "id_1" in <process "process_id_27"[K005]>
09:26:44 E PROC_MSG_SUB CREATING          - #037->#035 K017 - "message_id_1" (inter.) correlationKey: default_correlation_key @[K016] in <process "process_id_27"[K005]> (no vars)
09:26:44 E PI           ACTIVATED         - #038->#035 K016 - INTERMEDIATE_CATCH_EVENT "id_1" in <process "process_id_27"[K005]>
09:26:44 C MSG_SUB      CREATE            - #039->  -1   -1 - "message_id_1" (inter.) correlationKey: default_correlation_key @[K016] in <process "process_id_27"[K005]> (no vars)
09:26:44 E MSG_SUB      CREATED           - #040->#039 K018 - "message_id_1" (inter.) correlationKey: default_correlation_key @[K016] in <process "process_id_27"[K005]> (no vars)
09:26:44 C PROC_MSG_SUB CREATE            - #041->  -1   -1 - "message_id_1" (inter.) @[K016] in <process ?[K005]> (no vars)
09:26:44 E PROC_MSG_SUB CREATED           - #042->#041 K017 - "message_id_1" (inter.) correlationKey: default_correlation_key @[K016] in <process "process_id_27"[K005]> (no vars)
09:26:44 C MSG          PUBLISH           - #043->  -1   -1 - "message_id_1" correlationKey: default_correlation_key with variables: {correlationKey=default_correlation_key}
09:26:44 E MSG          PUBLISHED         - #044->#043 K019 - "message_id_1" correlationKey: default_correlation_key with variables: {correlationKey=default_correlation_key}
09:26:44 E MSG_SUB      CORRELATING       - #045->#043 K018 - "message_id_1" (inter.) correlationKey: default_correlation_key @[K016] in <process "process_id_27"[K005]> with variables: {correlationKey=default_correlation_key}
09:26:44 C PROC_MSG_SUB CORRELATE         - #046->  -1   -1 - "message_id_1" (inter.) correlationKey: default_correlation_key @[K016] in <process "process_id_27"[K005]> with variables: {correlationKey=default_correlation_key}
09:26:44 C MSG_SUB      CORRELATE         - #047->  -1   -1 - "message_id_1" (inter.) @[K016] in <process "process_id_27"[K005]> (no vars)
09:26:44 E PROC_MSG_SUB CORRELATED        - #048->#046 K017 - "message_id_1" (inter.) correlationKey: default_correlation_key @[K016] in <process "process_id_27"[K005]> with variables: {correlationKey=default_correlation_key}
09:26:44 E PROC_EVNT    TRIGGERING        - #049->#046 K020 -  @"id_1"[K016] in <process K001[K005]> with variables: {correlationKey=default_correlation_key}
09:26:44 C PI           COMPLETE          - #050->#046 K016 - INTERMEDIATE_CATCH_EVENT "id_1" in <process "process_id_27"[K005]>
09:26:44 E MSG_SUB      CORRELATED        - #051->#047 K018 - "message_id_1" (inter.) correlationKey: default_correlation_key @[K016] in <process "process_id_27"[K005]> with variables: {correlationKey=default_correlation_key}
09:26:44 E PI           COMPLETING        - #052->#050 K016 - INTERMEDIATE_CATCH_EVENT "id_1" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETED         - #053->#050 K016 - INTERMEDIATE_CATCH_EVENT "id_1" in <process "process_id_27"[K005]>
09:26:44 E PI           SEQ_FLOW_TAKEN    - #054->#050 K021 - SEQUENCE_FLOW "sequenc..07a3fcd" in <process "process_id_27"[K005]>
09:26:44 C PI           ACTIVATE          - #055->#050 K022 - MANUAL_TASK "id_2" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATING        - #056->#055 K022 - MANUAL_TASK "id_2" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATED         - #057->#055 K022 - MANUAL_TASK "id_2" in <process "process_id_27"[K005]>
09:26:44 C PI           COMPLETE          - #058->#055 K022 - MANUAL_TASK "id_2" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETING        - #059->#058 K022 - MANUAL_TASK "id_2" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETED         - #060->#058 K022 - MANUAL_TASK "id_2" in <process "process_id_27"[K005]>
09:26:44 E PI           SEQ_FLOW_TAKEN    - #061->#058 K023 - SEQUENCE_FLOW "sequenc..9f94b7d" in <process "process_id_27"[K005]>
09:26:44 C PI           ACTIVATE          - #062->#058 K024 - PARALLEL_GATEWAY "fork_id_3" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATING        - #063->#062 K024 - PARALLEL_GATEWAY "fork_id_3" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATED         - #064->#062 K024 - PARALLEL_GATEWAY "fork_id_3" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETING        - #065->#062 K024 - PARALLEL_GATEWAY "fork_id_3" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETED         - #066->#062 K024 - PARALLEL_GATEWAY "fork_id_3" in <process "process_id_27"[K005]>
09:26:44 E PI           SEQ_FLOW_TAKEN    - #067->#062 K025 - SEQUENCE_FLOW "id_8" in <process "process_id_27"[K005]>
09:26:44 C PI           ACTIVATE          - #068->#062 K026 - SERVICE_TASK "id_10" in <process "process_id_27"[K005]>
09:26:44 E PI           SEQ_FLOW_TAKEN    - #069->#062 K027 - SEQUENCE_FLOW "sequenc..d81a56d" in <process "process_id_27"[K005]>
09:26:44 C PI           ACTIVATE          - #070->#062 K028 - MANUAL_TASK "id_7" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATING        - #071->#068 K026 - SERVICE_TASK "id_10" in <process "process_id_27"[K005]>
09:26:44 E TIME         CREATED           - #072->#068 K029 -  @"boundar..r_id_10"[K026]  in <process "K001"[K005]> due 2023-12-01T09:26:44.747
09:26:44 E JOB          CREATED           - #073->#068 K030 - K030 "job_id_10" @"id_10"[K026] 3 retries, in <process "process_id_27"[K005]> (no vars)
09:26:44 E PI           ACTIVATED         - #074->#068 K026 - SERVICE_TASK "id_10" in <process "process_id_27"[K005]>
09:26:44 C JOB_BATCH    ACTIVATE          - #075->  -1   -1 - "job_id_10" max: 10
09:26:44 E PI           ACTIVATING        - #076->#070 K028 - MANUAL_TASK "id_7" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATED         - #077->#070 K028 - MANUAL_TASK "id_7" in <process "process_id_27"[K005]>
09:26:44 C PI           COMPLETE          - #078->#070 K028 - MANUAL_TASK "id_7" in <process "process_id_27"[K005]>
09:26:44 E JOB_BATCH    ACTIVATED         - #079->#075 K031 - "job_id_10" 1/10
                   K030 "job_id_10" @"id_10"[K026] 3 retries, in <process "process_id_27"[K005]> with variables: {boundary_timer_id_10=PT8760H, correlationKey=default_correlation_key}
09:26:44 C JOB          COMPLETE          - #080->  -1 K030 - K030 -1 retries, in <process ?[?]> (no vars)
09:26:44 E PI           COMPLETING        - #081->#078 K028 - MANUAL_TASK "id_7" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETED         - #082->#078 K028 - MANUAL_TASK "id_7" in <process "process_id_27"[K005]>
09:26:44 E PI           SEQ_FLOW_TAKEN    - #083->#078 K032 - SEQUENCE_FLOW "sequenc..8bc957c" in <process "process_id_27"[K005]>
09:26:44 C PI           ACTIVATE          - #084->#078 K033 - PARALLEL_GATEWAY "join_id_4" in <process "process_id_27"[K005]>
09:26:44 E JOB          COMPLETED         - #085->#080 K030 - K030 "job_id_10" @"id_10"[K026] 3 retries, in <process "process_id_27"[K005]> (no vars)
09:26:44 E PROC_EVNT    TRIGGERING        - #086->#080 K034 -  @"id_10"[K026] in <process K001[K005]> (no vars)
09:26:44 C PI           COMPLETE          - #087->#080 K026 - SERVICE_TASK "id_10" in <process "process_id_27"[K005]>
09:26:44 R PI           ACTIVATE          - #088->#084 K033 - PARALLEL_GATEWAY "join_id_4" in <process "process_id_27"[K005]> !INVALID_STATE (Expected to be able to activate parallel gateway 'join_id_4', but not all sequence flows have been taken.)
09:26:44 E PI           COMPLETING        - #089->#087 K026 - SERVICE_TASK "id_10" in <process "process_id_27"[K005]>
09:26:44 E TIME         CANCELED          - #090->#087 K029 -  @"boundar..r_id_10"[K026]  in <process "K001"[K005]> due 2023-12-01T09:26:44.747
09:26:44 E PI           COMPLETED         - #091->#087 K026 - SERVICE_TASK "id_10" in <process "process_id_27"[K005]>
09:26:44 E PI           SEQ_FLOW_TAKEN    - #092->#087 K035 - SEQUENCE_FLOW "sequenc..ee12809" in <process "process_id_27"[K005]>
09:26:44 C PI           ACTIVATE          - #093->#087 K036 - EXCLUSIVE_GATEWAY "fork_id_11" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATING        - #094->#093 K036 - EXCLUSIVE_GATEWAY "fork_id_11" in <process "process_id_27"[K005]>
09:26:44 E INCIDENT     CREATED           - #095->#093 K037 - EXTRACT_VALUE_ERROR failed to evaluate expression 'id_11_branch = "edge_id_16"': no variable found for name 'id_11_branch',  @"fork_id_11"[K036] in <process "process_id_27"[K005]>
09:26:44 C VAR_DOCUMENT UPDATE            - #096->  -1   -1 - VariableDocumentRecord {"variables":{"id_11_branch":"edge_id_16"},"updateSemantics":"LOCAL","scopeKey":2251799813685253}
09:26:44 E VAR          CREATED           - #097->#096 K038 - id_11_branch->"edge_id_16" in <process [K005]>
09:26:44 E VAR_DOCUMENT UPDATED           - #098->#096 K039 - VariableDocumentRecord {"variables":{"id_11_branch":"edge_id_16"},"updateSemantics":"LOCAL","scopeKey":2251799813685253}
09:26:44 C INCIDENT     RESOLVE           - #099->  -1 K037 - K037
09:26:44 E INCIDENT     RESOLVED          - #100->#099 K037 - EXTRACT_VALUE_ERROR failed to evaluate expression 'id_11_branch = "edge_id_16"': no variable found for name 'id_11_branch',  @"fork_id_11"[K036] in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATED         - #101->#099 K036 - EXCLUSIVE_GATEWAY "fork_id_11" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETING        - #102->#099 K036 - EXCLUSIVE_GATEWAY "fork_id_11" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETED         - #103->#099 K036 - EXCLUSIVE_GATEWAY "fork_id_11" in <process "process_id_27"[K005]>
09:26:44 E PI           SEQ_FLOW_TAKEN    - #104->#099 K040 - SEQUENCE_FLOW "edge_id_16" in <process "process_id_27"[K005]>
09:26:44 C PI           ACTIVATE          - #105->#099 K041 - RECEIVE_TASK "id_18" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATING        - #106->#105 K041 - RECEIVE_TASK "id_18" in <process "process_id_27"[K005]>
09:26:44 E PROC_MSG_SUB CREATING          - #107->#105 K042 - "message_id_18" (inter.) correlationKey: default_correlation_key @[K041] in <process "process_id_27"[K005]> (no vars)
09:26:44 E PI           ACTIVATED         - #108->#105 K041 - RECEIVE_TASK "id_18" in <process "process_id_27"[K005]>
09:26:44 C MSG_SUB      CREATE            - #109->  -1   -1 - "message_id_18" (inter.) correlationKey: default_correlation_key @[K041] in <process "process_id_27"[K005]> (no vars)
09:26:44 E MSG_SUB      CREATED           - #110->#109 K043 - "message_id_18" (inter.) correlationKey: default_correlation_key @[K041] in <process "process_id_27"[K005]> (no vars)
09:26:44 C PROC_MSG_SUB CREATE            - #111->  -1   -1 - "message_id_18" (inter.) @[K041] in <process ?[K005]> (no vars)
09:26:44 E PROC_MSG_SUB CREATED           - #112->#111 K042 - "message_id_18" (inter.) correlationKey: default_correlation_key @[K041] in <process "process_id_27"[K005]> (no vars)
09:26:44 C MSG          PUBLISH           - #113->  -1   -1 - "message_id_18" correlationKey: default_correlation_key with variables: {correlationKey=default_correlation_key}
09:26:44 E MSG          PUBLISHED         - #114->#113 K044 - "message_id_18" correlationKey: default_correlation_key with variables: {correlationKey=default_correlation_key}
09:26:44 E MSG_SUB      CORRELATING       - #115->#113 K043 - "message_id_18" (inter.) correlationKey: default_correlation_key @[K041] in <process "process_id_27"[K005]> with variables: {correlationKey=default_correlation_key}
09:26:44 C PROC_MSG_SUB CORRELATE         - #116->  -1   -1 - "message_id_18" (inter.) correlationKey: default_correlation_key @[K041] in <process "process_id_27"[K005]> with variables: {correlationKey=default_correlation_key}
09:26:44 C MSG_SUB      CORRELATE         - #117->  -1   -1 - "message_id_18" (inter.) @[K041] in <process "process_id_27"[K005]> (no vars)
09:26:44 E PROC_MSG_SUB CORRELATED        - #118->#116 K042 - "message_id_18" (inter.) correlationKey: default_correlation_key @[K041] in <process "process_id_27"[K005]> with variables: {correlationKey=default_correlation_key}
09:26:44 E PROC_EVNT    TRIGGERING        - #119->#116 K045 -  @"id_18"[K041] in <process K001[K005]> with variables: {correlationKey=default_correlation_key}
09:26:44 C PI           COMPLETE          - #120->#116 K041 - RECEIVE_TASK "id_18" in <process "process_id_27"[K005]>
09:26:44 E MSG_SUB      CORRELATED        - #121->#117 K043 - "message_id_18" (inter.) correlationKey: default_correlation_key @[K041] in <process "process_id_27"[K005]> with variables: {correlationKey=default_correlation_key}
09:26:44 E PI           COMPLETING        - #122->#120 K041 - RECEIVE_TASK "id_18" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETED         - #123->#120 K041 - RECEIVE_TASK "id_18" in <process "process_id_27"[K005]>
09:26:44 E PI           SEQ_FLOW_TAKEN    - #124->#120 K046 - SEQUENCE_FLOW "sequenc..eba46d1" in <process "process_id_27"[K005]>
09:26:44 C PI           ACTIVATE          - #125->#120 K047 - EXCLUSIVE_GATEWAY "join_id_12" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATING        - #126->#125 K047 - EXCLUSIVE_GATEWAY "join_id_12" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATED         - #127->#125 K047 - EXCLUSIVE_GATEWAY "join_id_12" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETING        - #128->#125 K047 - EXCLUSIVE_GATEWAY "join_id_12" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETED         - #129->#125 K047 - EXCLUSIVE_GATEWAY "join_id_12" in <process "process_id_27"[K005]>
09:26:44 E PI           SEQ_FLOW_TAKEN    - #130->#125 K048 - SEQUENCE_FLOW "sequenc..74e63c5" in <process "process_id_27"[K005]>
09:26:44 C PI           ACTIVATE          - #131->#125 K049 - PARALLEL_GATEWAY "join_id_4" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATING        - #132->#131 K049 - PARALLEL_GATEWAY "join_id_4" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATED         - #133->#131 K049 - PARALLEL_GATEWAY "join_id_4" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETING        - #134->#131 K049 - PARALLEL_GATEWAY "join_id_4" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETED         - #135->#131 K049 - PARALLEL_GATEWAY "join_id_4" in <process "process_id_27"[K005]>
09:26:44 E PI           SEQ_FLOW_TAKEN    - #136->#131 K050 - SEQUENCE_FLOW "sequenc..20b5298" in <process "process_id_27"[K005]>
09:26:44 C PI           ACTIVATE          - #137->#131 K051 - PARALLEL_GATEWAY "fork_id_19" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATING        - #138->#137 K051 - PARALLEL_GATEWAY "fork_id_19" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATED         - #139->#137 K051 - PARALLEL_GATEWAY "fork_id_19" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETING        - #140->#137 K051 - PARALLEL_GATEWAY "fork_id_19" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETED         - #141->#137 K051 - PARALLEL_GATEWAY "fork_id_19" in <process "process_id_27"[K005]>
09:26:44 E PI           SEQ_FLOW_TAKEN    - #142->#137 K052 - SEQUENCE_FLOW "id_24" in <process "process_id_27"[K005]>
09:26:44 C PI           ACTIVATE          - #143->#137 K053 - BUSINESS_RULE_TASK "id_26" in <process "process_id_27"[K005]>
09:26:44 E PI           SEQ_FLOW_TAKEN    - #144->#137 K054 - SEQUENCE_FLOW "sequenc..0d4a61b" in <process "process_id_27"[K005]>
09:26:44 C PI           ACTIVATE          - #145->#137 K055 - CALL_ACTIVITY "id_23" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATING        - #146->#143 K053 - BUSINESS_RULE_TASK "id_26" in <process "process_id_27"[K005]>
09:26:44 E JOB          CREATED           - #147->#143 K056 - K056 "job_id_26" @"id_26"[K053] 3 retries, in <process "process_id_27"[K005]> (no vars)
09:26:44 E PI           ACTIVATED         - #148->#143 K053 - BUSINESS_RULE_TASK "id_26" in <process "process_id_27"[K005]>
09:26:44 C JOB_BATCH    ACTIVATE          - #149->  -1   -1 - "job_id_26" max: 10
09:26:44 E PI           ACTIVATING        - #150->#145 K055 - CALL_ACTIVITY "id_23" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATED         - #151->#145 K055 - CALL_ACTIVITY "id_23" in <process "process_id_27"[K005]>
09:26:44 C PI           ACTIVATE          - #152->#145 K057 - PROCESS "process..d_id_23" in <process "process..d_id_23"[K057]>
09:26:44 E VAR          CREATED           - #153->#145 K058 - boundary_timer_id_10->"PT8760H" in <process [K057]>
09:26:44 E VAR          CREATED           - #154->#145 K059 - correlationKey->"default_correlation_key" in <process [K057]>
09:26:44 E VAR          CREATED           - #155->#145 K060 - id_11_branch->"edge_id_16" in <process [K057]>
09:26:44 E JOB_BATCH    ACTIVATED         - #156->#149 K061 - "job_id_26" 1/10
                   K056 "job_id_26" @"id_26"[K053] 3 retries, in <process "process_id_27"[K005]> with variables: {id_11_branch=edge_id_16, boundary_timer_id_10=PT8760H, correlationKey=default_correlation_key}
09:26:44 C JOB          FAIL              - #157->  -1 K056 - K056 3 retries, in <process ?[?]> (no vars)
09:26:44 E PI           ACTIVATING        - #158->#152 K057 - PROCESS "process..d_id_23" in <process "process..d_id_23"[K057]>
09:26:44 E PI           ACTIVATED         - #159->#152 K057 - PROCESS "process..d_id_23" in <process "process..d_id_23"[K057]>
09:26:44 C PI           ACTIVATE          - #160->#152   -1 - START_EVENT "escalat..t_id_23" in <process "process..d_id_23"[K057]>
09:26:44 E JOB          FAILED            - #161->#157 K056 - K056 "job_id_26" @"id_26"[K053] 3 retries, in <process "process_id_27"[K005]> (no vars)
09:26:44 C JOB_BATCH    ACTIVATE          - #162->  -1   -1 - "job_id_26" max: 10
09:26:44 E PI           ACTIVATING        - #163->#160 K062 - START_EVENT "escalat..t_id_23" in <process "process..d_id_23"[K057]>
09:26:44 E PI           ACTIVATED         - #164->#160 K062 - START_EVENT "escalat..t_id_23" in <process "process..d_id_23"[K057]>
09:26:44 C PI           COMPLETE          - #165->#160 K062 - START_EVENT "escalat..t_id_23" in <process "process..d_id_23"[K057]>
09:26:44 E JOB_BATCH    ACTIVATED         - #166->#162 K063 - "job_id_26" 1/10
                   K056 "job_id_26" @"id_26"[K053] 3 retries, in <process "process_id_27"[K005]> with variables: {id_11_branch=edge_id_16, boundary_timer_id_10=PT8760H, correlationKey=default_correlation_key}
09:26:44 C JOB          THROW_ERROR       - #167->  -1 K056 - K056 -1 retries, error_id_26: in <process ?[?]> (no vars)
09:26:44 E PI           COMPLETING        - #168->#165 K062 - START_EVENT "escalat..t_id_23" in <process "process..d_id_23"[K057]>
09:26:44 E PI           COMPLETED         - #169->#165 K062 - START_EVENT "escalat..t_id_23" in <process "process..d_id_23"[K057]>
09:26:44 E PI           SEQ_FLOW_TAKEN    - #170->#165 K064 - SEQUENCE_FLOW "sequenc..b5f94c0" in <process "process..d_id_23"[K057]>
09:26:44 C PI           ACTIVATE          - #171->#165 K065 - END_EVENT "escalat..t_id_23" in <process "process..d_id_23"[K057]>
09:26:44 E JOB          ERROR_THROWN      - #172->#167 K056 - K056 "job_id_26" @"id_26"[K053] 3 retries, error_id_26: in <process "process_id_27"[K005]> (no vars)
09:26:44 E PROC_EVNT    TRIGGERING        - #173->#167 K066 -  @"boundar..r_id_26"[K053] in <process K001[K005]> (no vars)
09:26:44 C PI           TERMINATE         - #174->#167 K053 - BUSINESS_RULE_TASK "id_26" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATING        - #175->#171 K065 - END_EVENT "escalat..t_id_23" in <process "process..d_id_23"[K057]>
09:26:44 E PI           ACTIVATED         - #176->#171 K065 - END_EVENT "escalat..t_id_23" in <process "process..d_id_23"[K057]>
09:26:44 E PROC_EVNT    TRIGGERING        - #177->#171 K068 -  @"boundar..t_id_23"[K055] in <process K001[K005]> (no vars)
09:26:44 E PROC_EVNT    TRIGGERED         - #178->#171 K068 -  @"boundar..t_id_23"[K055] in <process K001[K005]> (no vars)
09:26:44 E PI           ACTIVATING        - #179->#171 K069 - BOUNDARY_EVENT "boundar..t_id_23" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATED         - #180->#171 K069 - BOUNDARY_EVENT "boundar..t_id_23" in <process "process_id_27"[K005]>
09:26:44 C PI           COMPLETE          - #181->#171 K069 - BOUNDARY_EVENT "boundar..t_id_23" in <process "process_id_27"[K005]>
09:26:44 E ESCALATION   ESCALATED         - #182->#171 K067 - EscalationRecord {"processInstanceKey":2251799813685305,"escalationCode":"escalation_code_id_23","throwElementId":"escalation_throw_end_event_id_23","catchElementId":"boundary_escalation_catch_event_id_23"}
09:26:44 C PI           COMPLETE          - #183->#171 K065 - END_EVENT "escalat..t_id_23" in <process "process..d_id_23"[K057]>
09:26:44 E PI           TERMINATING       - #184->#174 K053 - BUSINESS_RULE_TASK "id_26" in <process "process_id_27"[K005]>
09:26:44 E PI           TERMINATED        - #185->#174 K053 - BUSINESS_RULE_TASK "id_26" in <process "process_id_27"[K005]>
09:26:44 E PROC_EVNT    TRIGGERED         - #186->#174 K066 -  @"boundar..r_id_26"[K053] in <process K001[K005]> (no vars)
09:26:44 E PI           ACTIVATING        - #187->#174 K070 - BOUNDARY_EVENT "boundar..r_id_26" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATED         - #188->#174 K070 - BOUNDARY_EVENT "boundar..r_id_26" in <process "process_id_27"[K005]>
09:26:44 C PI           COMPLETE          - #189->#174 K070 - BOUNDARY_EVENT "boundar..r_id_26" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETING        - #190->#181 K069 - BOUNDARY_EVENT "boundar..t_id_23" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETED         - #191->#181 K069 - BOUNDARY_EVENT "boundar..t_id_23" in <process "process_id_27"[K005]>
09:26:44 E PI           SEQ_FLOW_TAKEN    - #192->#181 K071 - SEQUENCE_FLOW "sequenc..86f212a" in <process "process_id_27"[K005]>
09:26:44 C PI           ACTIVATE          - #193->#181 K072 - PARALLEL_GATEWAY "boundar..n_id_23" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETING        - #194->#183 K065 - END_EVENT "escalat..t_id_23" in <process "process..d_id_23"[K057]>
09:26:44 E PI           COMPLETED         - #195->#183 K065 - END_EVENT "escalat..t_id_23" in <process "process..d_id_23"[K057]>
09:26:44 C PI           COMPLETE          - #196->#183 K057 - PROCESS "process..d_id_23" in <process "process..d_id_23"[K057]>
09:26:44 E PI           COMPLETING        - #197->#189 K070 - BOUNDARY_EVENT "boundar..r_id_26" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETED         - #198->#189 K070 - BOUNDARY_EVENT "boundar..r_id_26" in <process "process_id_27"[K005]>
09:26:44 E PI           SEQ_FLOW_TAKEN    - #199->#189 K073 - SEQUENCE_FLOW "sequenc..c6050b0" in <process "process_id_27"[K005]>
09:26:44 C PI           ACTIVATE          - #200->#189 K074 - END_EVENT "endEven..bf3eb55" in <process "process_id_27"[K005]>
09:26:44 R PI           ACTIVATE          - #201->#193 K072 - PARALLEL_GATEWAY "boundar..n_id_23" in <process "process_id_27"[K005]> !INVALID_STATE (Expected to be able to activate parallel gateway 'boundary_join_id_23', but not all sequence flows have been taken.)
09:26:44 E PI           COMPLETING        - #202->#196 K057 - PROCESS "process..d_id_23" in <process "process..d_id_23"[K057]>
09:26:44 E PI           COMPLETED         - #203->#196 K057 - PROCESS "process..d_id_23" in <process "process..d_id_23"[K057]>
09:26:44 C PI           COMPLETE          - #204->#196 K055 - CALL_ACTIVITY "id_23" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATING        - #205->#200 K074 - END_EVENT "endEven..bf3eb55" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATED         - #206->#200 K074 - END_EVENT "endEven..bf3eb55" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETING        - #207->#200 K074 - END_EVENT "endEven..bf3eb55" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETED         - #208->#200 K074 - END_EVENT "endEven..bf3eb55" in <process "process_id_27"[K005]>
09:26:44 C PI           TERMINATE         - #209->#200 K055 - CALL_ACTIVITY "id_23" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETING        - #210->#204 K055 - CALL_ACTIVITY "id_23" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETED         - #211->#204 K055 - CALL_ACTIVITY "id_23" in <process "process_id_27"[K005]>
09:26:44 E PI           SEQ_FLOW_TAKEN    - #212->#204 K075 - SEQUENCE_FLOW "sequenc..3dd0b1e" in <process "process_id_27"[K005]>
09:26:44 C PI           ACTIVATE          - #213->#204 K076 - PARALLEL_GATEWAY "boundar..n_id_23" in <process "process_id_27"[K005]>
09:26:44 R PI           TERMINATE         - #214->#209 K055 - CALL_ACTIVITY "id_23" in <process "process_id_27"[K005]> !INVALID_STATE (Expected element instance with key '2251799813685303' to be present in state but not found.)
09:26:44 E PI           ACTIVATING        - #215->#213 K076 - PARALLEL_GATEWAY "boundar..n_id_23" in <process "process_id_27"[K005]>
09:26:44 E PI           ACTIVATED         - #216->#213 K076 - PARALLEL_GATEWAY "boundar..n_id_23" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETING        - #217->#213 K076 - PARALLEL_GATEWAY "boundar..n_id_23" in <process "process_id_27"[K005]>
09:26:44 E PI           COMPLETED         - #218->#213 K076 - PARALLEL_GATEWAY "boundar..n_id_23" in <process "process_id_27"[K005]>
09:26:44 E PI           SEQ_FLOW_TAKEN    - #219->#213 K077 - SEQUENCE_FLOW "sequenc..0bf9bff" in <process "process_id_27"[K005]>
09:26:44 C PI           ACTIVATE          - #220->#213 K078 - PARALLEL_GATEWAY "join_id_20" in <process "process_id_27"[K005]>
09:26:44 R PI           ACTIVATE          - #221->#220 K078 - PARALLEL_GATEWAY "join_id_20" in <process "process_id_27"[K005]> !INVALID_STATE (Expected to be able to activate parallel gateway 'join_id_20', but not all sequence flows have been taken.)

-------------- Deployed Processes ----------------------
process.xml -> "process_id_27" (version:1)[K001] ------
<?xml version="1.0" encoding="UTF-8" standalone="no"?>
<definitions xmlns:bpmndi="http://www.omg.org/spec/BPMN/20100524/DI" xmlns:dc="http://www.omg.org/spec/DD/20100524/DC" xmlns:di="http://www.omg.org/spec/DD/20100524/DI" xmlns:ns0="http://camunda.org/schema/zeebe/1.0" id="definitions_b4a42a56-0d19-4538-8611-8588378d0721" targetNamespace="http://www.omg.org/spec/BPMN/20100524/MODEL" xmlns="http://www.omg.org/spec/BPMN/20100524/MODEL">
  <process id="process_id_27" isExecutable="true">
    <startEvent id="id_28" name="id_28">
      <outgoing>sequenceFlow_bf5680a6-80ea-4911-8813-d722ae9a7aef</outgoing>
      <timerEventDefinition id="timerEventDefinition_caca80aa-2bc2-4a00-9156-35ac0f468593">
        <timeDate id="timeDate_c9d009fb-17c0-4356-be78-6f9fa540979f">=now() + duration("PT7M")</timeDate>
      </timerEventDefinition>
    </startEvent>
    <serviceTask id="id_28_variables_task" name="id_28_variables_task">
      <extensionElements>
        <ns0:taskDefinition type="id_28_variables"/>
      </extensionElements>
      <incoming>sequenceFlow_bf5680a6-80ea-4911-8813-d722ae9a7aef</incoming>
      <outgoing>sequenceFlow_d0cf80da-25a2-42c1-86ae-6f5e4ec76c6b</outgoing>
    </serviceTask>
    <sequenceFlow id="sequenceFlow_bf5680a6-80ea-4911-8813-d722ae9a7aef" sourceRef="id_28" targetRef="id_28_variables_task"/>
    <intermediateCatchEvent id="id_1" name="id_1">
      <incoming>sequenceFlow_d0cf80da-25a2-42c1-86ae-6f5e4ec76c6b</incoming>
      <outgoing>sequenceFlow_b340a2fa-546f-431e-bd22-8b59207a3fcd</outgoing>
      <messageEventDefinition id="messageEventDefinition_f76be5e9-854f-4dce-b290-ae88c779d08e" messageRef="message_57f9bea0-239c-4c05-9e96-ea48b78a61e7"/>
    </intermediateCatchEvent>
    <sequenceFlow id="sequenceFlow_d0cf80da-25a2-42c1-86ae-6f5e4ec76c6b" sourceRef="id_28_variables_task" targetRef="id_1"/>
    <manualTask id="id_2" name="id_2">
      <incoming>sequenceFlow_b340a2fa-546f-431e-bd22-8b59207a3fcd</incoming>
      <outgoing>sequenceFlow_71f540a7-6068-407d-87a7-ab3269f94b7d</outgoing>
    </manualTask>
    <sequenceFlow id="sequenceFlow_b340a2fa-546f-431e-bd22-8b59207a3fcd" sourceRef="id_1" targetRef="id_2"/>
    <parallelGateway id="fork_id_3" name="fork_id_3">
      <incoming>sequenceFlow_71f540a7-6068-407d-87a7-ab3269f94b7d</incoming>
      <outgoing>sequenceFlow_59a968ed-d59a-42fb-9a4f-d7b06d81a56d</outgoing>
      <outgoing>id_8</outgoing>
    </parallelGateway>
    <sequenceFlow id="sequenceFlow_71f540a7-6068-407d-87a7-ab3269f94b7d" sourceRef="id_2" targetRef="fork_id_3"/>
    <manualTask id="id_7" name="id_7">
      <incoming>sequenceFlow_59a968ed-d59a-42fb-9a4f-d7b06d81a56d</incoming>
      <outgoing>sequenceFlow_618ddaaf-0186-4731-ba8f-823858bc957c</outgoing>
    </manualTask>
    <sequenceFlow id="sequenceFlow_59a968ed-d59a-42fb-9a4f-d7b06d81a56d" sourceRef="fork_id_3" targetRef="id_7"/>
    <parallelGateway id="join_id_4" name="join_id_4">
      <incoming>sequenceFlow_618ddaaf-0186-4731-ba8f-823858bc957c</incoming>
      <incoming>sequenceFlow_8d727ae2-33bf-474c-96e3-ced7d74e63c5</incoming>
      <outgoing>sequenceFlow_e79c7d6d-399c-4775-810f-fd47720b5298</outgoing>
    </parallelGateway>
    <sequenceFlow id="sequenceFlow_618ddaaf-0186-4731-ba8f-823858bc957c" sourceRef="id_7" targetRef="join_id_4"/>
    <sequenceFlow id="id_8" sourceRef="fork_id_3" targetRef="id_10"/>
    <serviceTask id="id_10" name="id_10">
      <extensionElements>
        <ns0:taskDefinition retries="3" type="job_id_10"/>
      </extensionElements>
      <incoming>id_8</incoming>
      <outgoing>sequenceFlow_9625ab87-2805-44c1-b2d2-221b6ee12809</outgoing>
    </serviceTask>
    <boundaryEvent attachedToRef="id_10" id="boundary_timer_id_10" name="boundary_timer_id_10">
      <outgoing>sequenceFlow_26dff327-7402-4305-89e5-a1a7971b36c4</outgoing>
      <timerEventDefinition id="timerEventDefinition_a20613f6-2d0e-4c40-b0a7-068eaf1399d2">
        <timeDuration id="timeDuration_e0a615c8-683b-4656-829a-9c6b3b89c524">=boundary_timer_id_10</timeDuration>
      </timerEventDefinition>
    </boundaryEvent>
    <endEvent id="endEvent_898d348c-6f69-459e-b9e8-26c80954d68e">
      <incoming>sequenceFlow_26dff327-7402-4305-89e5-a1a7971b36c4</incoming>
      <terminateEventDefinition id="terminateEventDefinition_329d2863-97f6-4d39-8ace-47883e459e5e"/>
    </endEvent>
    <sequenceFlow id="sequenceFlow_26dff327-7402-4305-89e5-a1a7971b36c4" sourceRef="boundary_timer_id_10" targetRef="endEvent_898d348c-6f69-459e-b9e8-26c80954d68e"/>
    <exclusiveGateway default="sequenceFlow_918713b6-5b61-4918-a6cf-359768eb238f" id="fork_id_11" name="fork_id_11">
      <incoming>sequenceFlow_9625ab87-2805-44c1-b2d2-221b6ee12809</incoming>
      <outgoing>sequenceFlow_918713b6-5b61-4918-a6cf-359768eb238f</outgoing>
      <outgoing>edge_id_16</outgoing>
    </exclusiveGateway>
    <sequenceFlow id="sequenceFlow_9625ab87-2805-44c1-b2d2-221b6ee12809" sourceRef="id_10" targetRef="fork_id_11"/>
    <sequenceFlow id="sequenceFlow_918713b6-5b61-4918-a6cf-359768eb238f" sourceRef="fork_id_11" targetRef="id_15"/>
    <userTask id="id_15" name="id_15">
      <incoming>sequenceFlow_918713b6-5b61-4918-a6cf-359768eb238f</incoming>
      <outgoing>sequenceFlow_85dfbe64-5b0d-4bfa-b414-3b4360c100e6</outgoing>
    </userTask>
    <exclusiveGateway id="join_id_12" name="join_id_12">
      <incoming>sequenceFlow_85dfbe64-5b0d-4bfa-b414-3b4360c100e6</incoming>
      <incoming>sequenceFlow_63e2a2ba-2a97-4f3c-9254-e2df9eba46d1</incoming>
      <outgoing>sequenceFlow_8d727ae2-33bf-474c-96e3-ced7d74e63c5</outgoing>
    </exclusiveGateway>
    <sequenceFlow id="sequenceFlow_85dfbe64-5b0d-4bfa-b414-3b4360c100e6" sourceRef="id_15" targetRef="join_id_12"/>
    <sequenceFlow id="edge_id_16" sourceRef="fork_id_11" targetRef="id_18">
      <conditionExpression id="conditionExpression_3048c20b-bfcf-4c2b-8552-831397bbe382">=id_11_branch = "edge_id_16"</conditionExpression>
    </sequenceFlow>
    <receiveTask id="id_18" messageRef="message_656de2ea-759d-46ae-b65a-f3b74a6bd687" name="id_18">
      <incoming>edge_id_16</incoming>
      <outgoing>sequenceFlow_63e2a2ba-2a97-4f3c-9254-e2df9eba46d1</outgoing>
    </receiveTask>
    <sequenceFlow id="sequenceFlow_63e2a2ba-2a97-4f3c-9254-e2df9eba46d1" sourceRef="id_18" targetRef="join_id_12"/>
    <sequenceFlow id="sequenceFlow_8d727ae2-33bf-474c-96e3-ced7d74e63c5" sourceRef="join_id_12" targetRef="join_id_4"/>
    <parallelGateway id="fork_id_19" name="fork_id_19">
      <incoming>sequenceFlow_e79c7d6d-399c-4775-810f-fd47720b5298</incoming>
      <outgoing>sequenceFlow_38c2b607-3e27-4c14-a80d-f77820d4a61b</outgoing>
      <outgoing>id_24</outgoing>
    </parallelGateway>
    <sequenceFlow id="sequenceFlow_e79c7d6d-399c-4775-810f-fd47720b5298" sourceRef="join_id_4" targetRef="fork_id_19"/>
    <callActivity id="id_23" name="id_23">
      <extensionElements>
        <ns0:calledElement processId="process_child_id_23"/>
      </extensionElements>
      <incoming>sequenceFlow_38c2b607-3e27-4c14-a80d-f77820d4a61b</incoming>
      <outgoing>sequenceFlow_5226a2b9-88c7-470f-a964-08aec3dd0b1e</outgoing>
    </callActivity>
    <sequenceFlow id="sequenceFlow_38c2b607-3e27-4c14-a80d-f77820d4a61b" sourceRef="fork_id_19" targetRef="id_23"/>
    <parallelGateway id="boundary_join_id_23" name="boundary_join_id_23">
      <incoming>sequenceFlow_5226a2b9-88c7-470f-a964-08aec3dd0b1e</incoming>
      <incoming>sequenceFlow_5e448d22-a4a8-44eb-8b8f-333ec86f212a</incoming>
      <outgoing>sequenceFlow_2048461e-a391-4885-81ba-598100bf9bff</outgoing>
    </parallelGateway>
    <sequenceFlow id="sequenceFlow_5226a2b9-88c7-470f-a964-08aec3dd0b1e" sourceRef="id_23" targetRef="boundary_join_id_23"/>
    <boundaryEvent attachedToRef="id_23" cancelActivity="false" id="boundary_escalation_catch_event_id_23" name="boundary_escalation_catch_event_id_23">
      <outgoing>sequenceFlow_5e448d22-a4a8-44eb-8b8f-333ec86f212a</outgoing>
      <escalationEventDefinition escalationRef="escalation_fc33dee1-b8a6-46b1-8676-b88d21d14b21" id="escalationEventDefinition_72b93474-bca8-4699-a6de-11d0658bdea4"/>
    </boundaryEvent>
    <sequenceFlow id="sequenceFlow_5e448d22-a4a8-44eb-8b8f-333ec86f212a" sourceRef="boundary_escalation_catch_event_id_23" targetRef="boundary_join_id_23"/>
    <parallelGateway id="join_id_20" name="join_id_20">
      <incoming>sequenceFlow_2048461e-a391-4885-81ba-598100bf9bff</incoming>
      <incoming>sequenceFlow_5712ed38-c5a4-40ee-afd2-76077be8e478</incoming>
      <outgoing>sequenceFlow_d6a36022-c655-4d74-8791-257db29537c9</outgoing>
    </parallelGateway>
    <sequenceFlow id="sequenceFlow_2048461e-a391-4885-81ba-598100bf9bff" sourceRef="boundary_join_id_23" targetRef="join_id_20"/>
    <sequenceFlow id="id_24" sourceRef="fork_id_19" targetRef="id_26"/>
    <businessRuleTask id="id_26" name="id_26">
      <extensionElements>
        <ns0:taskDefinition retries="3" type="job_id_26"/>
      </extensionElements>
      <incoming>id_24</incoming>
      <outgoing>sequenceFlow_5712ed38-c5a4-40ee-afd2-76077be8e478</outgoing>
    </businessRuleTask>
    <boundaryEvent attachedToRef="id_26" id="boundary_error_id_26" name="boundary_error_id_26">
      <outgoing>sequenceFlow_937b36c8-b090-42e4-ac3b-f00e9c6050b0</outgoing>
      <errorEventDefinition errorRef="error_2b24b6b0-08e7-4b8c-b06e-125ccdf9b093" id="errorEventDefinition_3d340bb3-190d-45ee-b3b1-4649fad5ae6b"/>
    </boundaryEvent>
    <endEvent id="endEvent_41ad459b-944d-4ac7-b41f-250d5bf3eb55">
      <incoming>sequenceFlow_937b36c8-b090-42e4-ac3b-f00e9c6050b0</incoming>
      <terminateEventDefinition id="terminateEventDefinition_a7a0a4ca-b660-43a9-a04a-bf176a8941a2"/>
    </endEvent>
    <sequenceFlow id="sequenceFlow_937b36c8-b090-42e4-ac3b-f00e9c6050b0" sourceRef="boundary_error_id_26" targetRef="endEvent_41ad459b-944d-4ac7-b41f-250d5bf3eb55"/>
    <sequenceFlow id="sequenceFlow_5712ed38-c5a4-40ee-afd2-76077be8e478" sourceRef="id_26" targetRef="join_id_20"/>
    <endEvent id="id_29" name="id_29">
      <incoming>sequenceFlow_d6a36022-c655-4d74-8791-257db29537c9</incoming>
    </endEvent>
    <sequenceFlow id="sequenceFlow_d6a36022-c655-4d74-8791-257db29537c9" sourceRef="join_id_20" targetRef="id_29"/>
  </process>
  <message id="message_57f9bea0-239c-4c05-9e96-ea48b78a61e7" name="message_id_1">
    <extensionElements>
      <ns0:subscription correlationKey="=correlationKey"/>
    </extensionElements>
  </message>
  <message id="message_656de2ea-759d-46ae-b65a-f3b74a6bd687" name="message_id_18">
    <extensionElements>
      <ns0:subscription correlationKey="=correlationKey"/>
    </extensionElements>
  </message>
  <escalation escalationCode="escalation_code_id_23" id="escalation_fc33dee1-b8a6-46b1-8676-b88d21d14b21"/>
  <error errorCode="error_id_26" id="error_2b24b6b0-08e7-4b8c-b06e-125ccdf9b093"/>
  <bpmndi:BPMNDiagram id="BPMNDiagram_f67dc8eb-0668-47ce-985d-e41ae6dd5b11">
    <bpmndi:BPMNPlane bpmnElement="process_id_27" id="BPMNPlane_ca5e8011-3127-49f3-8538-e8ba1dd2a9f5">
      <bpmndi:BPMNShape bpmnElement="id_28" id="BPMNShape_4a201d27-7883-4e73-a760-baa773f04aaf">
        <dc:Bounds height="36.0" width="36.0" x="100.0" y="100.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNShape bpmnElement="id_28_variables_task" id="BPMNShape_84816829-6a0e-4126-834d-3f55634f5a37">
        <dc:Bounds height="80.0" width="100.0" x="186.0" y="78.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_bf5680a6-80ea-4911-8813-d722ae9a7aef" id="BPMNEdge_b27345ea-246f-44ee-a363-754c3202175a">
        <di:waypoint x="136.0" y="118.0"/>
        <di:waypoint x="186.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="id_1" id="BPMNShape_3587be1e-0f50-416a-9822-9aeb45f30c1a">
        <dc:Bounds height="36.0" width="36.0" x="336.0" y="100.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_d0cf80da-25a2-42c1-86ae-6f5e4ec76c6b" id="BPMNEdge_8542b8bf-e702-4776-8fc7-6adc11e503a6">
        <di:waypoint x="286.0" y="118.0"/>
        <di:waypoint x="336.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="id_2" id="BPMNShape_9d2a1bcc-3a04-489b-b1e1-4cc3313ba12a">
        <dc:Bounds height="80.0" width="100.0" x="422.0" y="78.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_b340a2fa-546f-431e-bd22-8b59207a3fcd" id="BPMNEdge_acb4d453-766a-4b99-9e40-7d4804ab64b2">
        <di:waypoint x="372.0" y="118.0"/>
        <di:waypoint x="422.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="fork_id_3" id="BPMNShape_a786d7ea-5de0-431b-ae9a-c3bb18d18958">
        <dc:Bounds height="50.0" width="50.0" x="572.0" y="93.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_71f540a7-6068-407d-87a7-ab3269f94b7d" id="BPMNEdge_1cefb670-4778-4aba-9f4f-4624cee779d1">
        <di:waypoint x="522.0" y="118.0"/>
        <di:waypoint x="572.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="id_7" id="BPMNShape_8b157852-d40a-4a09-802b-d33e9d304d5b">
        <dc:Bounds height="80.0" width="100.0" x="672.0" y="78.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_59a968ed-d59a-42fb-9a4f-d7b06d81a56d" id="BPMNEdge_3f6e12af-912e-4609-9693-1f7a963edc35">
        <di:waypoint x="622.0" y="118.0"/>
        <di:waypoint x="672.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="join_id_4" id="BPMNShape_aa46e156-b7c7-4b8b-8165-e9de9586cfc7">
        <dc:Bounds height="50.0" width="50.0" x="822.0" y="93.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_618ddaaf-0186-4731-ba8f-823858bc957c" id="BPMNEdge_360c1400-09fd-4913-bb45-bba664e8b0ef">
        <di:waypoint x="772.0" y="118.0"/>
        <di:waypoint x="822.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="id_10" id="BPMNShape_f4226e32-1277-43c3-8337-663e45d56deb">
        <dc:Bounds height="80.0" width="100.0" x="672.0" y="208.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="id_8" id="BPMNEdge_8db2e831-472e-42d6-8754-bee3309b3e97">
        <di:waypoint x="597.0" y="143.0"/>
        <di:waypoint x="597.0" y="248.0"/>
        <di:waypoint x="672.0" y="248.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="boundary_timer_id_10" id="BPMNShape_39e6f975-2f3a-497e-9bf8-d8419dff8122">
        <dc:Bounds height="36.0" width="36.0" x="704.0" y="270.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNShape bpmnElement="endEvent_898d348c-6f69-459e-b9e8-26c80954d68e" id="BPMNShape_86990764-55ff-4f9c-9299-de7db9966dc9">
        <dc:Bounds height="36.0" width="36.0" x="752.5" y="338.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_26dff327-7402-4305-89e5-a1a7971b36c4" id="BPMNEdge_eb2c898c-573c-400b-94a4-861273f0da4e">
        <di:waypoint x="722.0" y="306.0"/>
        <di:waypoint x="722.0" y="356.0"/>
        <di:waypoint x="752.5" y="356.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="fork_id_11" id="BPMNShape_ec29de4f-bed6-4733-9137-262644712e2f" isMarkerVisible="true">
        <dc:Bounds height="50.0" width="50.0" x="822.0" y="223.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_9625ab87-2805-44c1-b2d2-221b6ee12809" id="BPMNEdge_625c0fef-6610-465d-81f8-8b25d81efbb8">
        <di:waypoint x="772.0" y="248.0"/>
        <di:waypoint x="822.0" y="248.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="id_15" id="BPMNShape_de06d975-8c6e-469c-ab3b-bfb2fd389094">
        <dc:Bounds height="80.0" width="100.0" x="922.0" y="208.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_918713b6-5b61-4918-a6cf-359768eb238f" id="BPMNEdge_7f365e28-0c24-475f-a61a-d04a7554b0c4">
        <di:waypoint x="872.0" y="248.0"/>
        <di:waypoint x="922.0" y="248.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="join_id_12" id="BPMNShape_1f112ec0-819d-4356-bc0c-5a52db4507d5" isMarkerVisible="true">
        <dc:Bounds height="50.0" width="50.0" x="1072.0" y="223.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_85dfbe64-5b0d-4bfa-b414-3b4360c100e6" id="BPMNEdge_a1f5673d-ffd6-4683-a307-d587dc210bc8">
        <di:waypoint x="1022.0" y="248.0"/>
        <di:waypoint x="1072.0" y="248.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="id_18" id="BPMNShape_005a6b7a-1ac3-4acd-8f99-dbc0af5db9de">
        <dc:Bounds height="80.0" width="100.0" x="922.0" y="338.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="edge_id_16" id="BPMNEdge_006ec6cc-ecdd-45e0-bbbc-04c7db5fc876">
        <di:waypoint x="847.0" y="273.0"/>
        <di:waypoint x="847.0" y="378.0"/>
        <di:waypoint x="922.0" y="378.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_63e2a2ba-2a97-4f3c-9254-e2df9eba46d1" id="BPMNEdge_46136d32-fd0e-4d25-aae9-8cb260a44ecb">
        <di:waypoint x="1022.0" y="378.0"/>
        <di:waypoint x="1072.0" y="248.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_8d727ae2-33bf-474c-96e3-ced7d74e63c5" id="BPMNEdge_d7e88865-bc12-403d-96f2-408fd70c6b39">
        <di:waypoint x="1122.0" y="248.0"/>
        <di:waypoint x="822.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="fork_id_19" id="BPMNShape_27ef3d28-4728-48e8-8552-fce6be8fc5b0">
        <dc:Bounds height="50.0" width="50.0" x="922.0" y="93.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_e79c7d6d-399c-4775-810f-fd47720b5298" id="BPMNEdge_4493fdbe-b363-44e1-9bca-94a14981e937">
        <di:waypoint x="872.0" y="118.0"/>
        <di:waypoint x="922.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="id_23" id="BPMNShape_9178103d-bb4b-4775-b4db-db0e8947ae09">
        <dc:Bounds height="80.0" width="100.0" x="1022.0" y="78.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_38c2b607-3e27-4c14-a80d-f77820d4a61b" id="BPMNEdge_20841ed1-da81-4c35-9219-fb0ee91390c7">
        <di:waypoint x="972.0" y="118.0"/>
        <di:waypoint x="1022.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="boundary_join_id_23" id="BPMNShape_fd537a67-c14c-4495-975a-c006a35eb97f">
        <dc:Bounds height="50.0" width="50.0" x="1172.0" y="93.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_5226a2b9-88c7-470f-a964-08aec3dd0b1e" id="BPMNEdge_810c255e-c962-4dc8-b25a-a29413ef8f00">
        <di:waypoint x="1122.0" y="118.0"/>
        <di:waypoint x="1172.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="boundary_escalation_catch_event_id_23" id="BPMNShape_cab6593f-fa6c-4566-94b0-207f3d94991a">
        <dc:Bounds height="36.0" width="36.0" x="1054.0" y="140.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_5e448d22-a4a8-44eb-8b8f-333ec86f212a" id="BPMNEdge_977d430f-c526-42ee-8e10-42df093992fe">
        <di:waypoint x="1072.0" y="176.0"/>
        <di:waypoint x="1072.0" y="226.0"/>
        <di:waypoint x="1172.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="join_id_20" id="BPMNShape_da1ee9b2-5d1b-4b62-abd8-160f378b8486">
        <dc:Bounds height="50.0" width="50.0" x="1272.0" y="93.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_2048461e-a391-4885-81ba-598100bf9bff" id="BPMNEdge_67a68c30-0dfe-4c3c-a586-16af412af392">
        <di:waypoint x="1222.0" y="118.0"/>
        <di:waypoint x="1272.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="id_26" id="BPMNShape_a1c47255-d3f4-47be-b06e-3ff19e7241ee">
        <dc:Bounds height="80.0" width="100.0" x="1022.0" y="208.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="id_24" id="BPMNEdge_569d0bfa-bb1e-4b0c-8e96-e502ef1a852c">
        <di:waypoint x="947.0" y="143.0"/>
        <di:waypoint x="947.0" y="248.0"/>
        <di:waypoint x="1022.0" y="248.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="boundary_error_id_26" id="BPMNShape_61b4051d-f511-4920-9208-44411c277094">
        <dc:Bounds height="36.0" width="36.0" x="1054.0" y="270.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNShape bpmnElement="endEvent_41ad459b-944d-4ac7-b41f-250d5bf3eb55" id="BPMNShape_83d5ab00-24b7-4565-a3d6-7a232ead17dd">
        <dc:Bounds height="36.0" width="36.0" x="1102.5" y="338.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_937b36c8-b090-42e4-ac3b-f00e9c6050b0" id="BPMNEdge_0908e3ba-4961-4fde-844f-0d51d71d4e06">
        <di:waypoint x="1072.0" y="306.0"/>
        <di:waypoint x="1072.0" y="356.0"/>
        <di:waypoint x="1102.5" y="356.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_5712ed38-c5a4-40ee-afd2-76077be8e478" id="BPMNEdge_c1723f56-24f6-4331-a524-f6c978c2af7d">
        <di:waypoint x="1122.0" y="248.0"/>
        <di:waypoint x="1272.0" y="118.0"/>
      </bpmndi:BPMNEdge>
      <bpmndi:BPMNShape bpmnElement="id_29" id="BPMNShape_ea8f3a63-06e3-4be0-a98a-c104b1428c02">
        <dc:Bounds height="36.0" width="36.0" x="1372.0" y="100.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_d6a36022-c655-4d74-8791-257db29537c9" id="BPMNEdge_007a431c-d367-42ab-8c16-3e09cb1f55a1">
        <di:waypoint x="1322.0" y="118.0"/>
        <di:waypoint x="1372.0" y="118.0"/>
      </bpmndi:BPMNEdge>
    </bpmndi:BPMNPlane>
  </bpmndi:BPMNDiagram>
</definitions>

process.xml -> "process..d_id_23" (version:1)[K002] ------
<?xml version="1.0" encoding="UTF-8" standalone="no"?>
<definitions xmlns:bpmndi="http://www.omg.org/spec/BPMN/20100524/DI" xmlns:dc="http://www.omg.org/spec/DD/20100524/DC" xmlns:di="http://www.omg.org/spec/DD/20100524/DI" id="definitions_84213553-1ab9-4655-ac75-68b1e05cdbd4" targetNamespace="http://www.omg.org/spec/BPMN/20100524/MODEL" xmlns="http://www.omg.org/spec/BPMN/20100524/MODEL">
  <process id="process_child_id_23" isExecutable="true">
    <startEvent id="escalation_throw_start_event_id_23" name="escalation_throw_start_event_id_23">
      <outgoing>sequenceFlow_b2da7e19-d1bb-44e0-8677-207b2b5f94c0</outgoing>
    </startEvent>
    <endEvent id="escalation_throw_end_event_id_23" name="escalation_throw_end_event_id_23">
      <incoming>sequenceFlow_b2da7e19-d1bb-44e0-8677-207b2b5f94c0</incoming>
      <escalationEventDefinition escalationRef="escalation_d99afc28-890c-42e1-8945-7855af06d802" id="escalationEventDefinition_de8da094-cd6e-457b-9bd2-87136b4b4d93"/>
    </endEvent>
    <sequenceFlow id="sequenceFlow_b2da7e19-d1bb-44e0-8677-207b2b5f94c0" sourceRef="escalation_throw_start_event_id_23" targetRef="escalation_throw_end_event_id_23"/>
  </process>
  <escalation escalationCode="escalation_code_id_23" id="escalation_d99afc28-890c-42e1-8945-7855af06d802"/>
  <bpmndi:BPMNDiagram id="BPMNDiagram_7adca242-856e-46af-bea6-8b65ff01858d">
    <bpmndi:BPMNPlane bpmnElement="process_child_id_23" id="BPMNPlane_f487040e-ff24-47c2-bf1b-1e02abb9da84">
      <bpmndi:BPMNShape bpmnElement="escalation_throw_start_event_id_23" id="BPMNShape_2e055624-44b1-4c26-9c9a-b23c83fed87e">
        <dc:Bounds height="36.0" width="36.0" x="100.0" y="100.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNShape bpmnElement="escalation_throw_end_event_id_23" id="BPMNShape_a5482cd9-7337-47ae-a152-1371535a4e04">
        <dc:Bounds height="36.0" width="36.0" x="186.0" y="100.0"/>
      </bpmndi:BPMNShape>
      <bpmndi:BPMNEdge bpmnElement="sequenceFlow_b2da7e19-d1bb-44e0-8677-207b2b5f94c0" id="BPMNEdge_4573e70b-a181-4815-91ae-1ad18a1842b0">
        <di:waypoint x="136.0" y="118.0"/>
        <di:waypoint x="186.0" y="118.0"/>
      </bpmndi:BPMNEdge>
    </bpmndi:BPMNPlane>
  </bpmndi:BPMNDiagram>
</definitions>


--------------- Decomposed keys (for debugging) -----------------
  -1 <-> -1
K001 <-> 2251799813685249
K002 <-> 2251799813685250
K003 <-> 2251799813685251
K004 <-> 2251799813685252
K005 <-> 2251799813685253
K006 <-> 2251799813685254
K007 <-> 2251799813685255
K008 <-> 2251799813685256
K009 <-> 2251799813685257
K010 <-> 2251799813685258
K011 <-> 2251799813685259
K012 <-> 2251799813685260
K013 <-> 2251799813685261
K014 <-> 2251799813685262
K015 <-> 2251799813685263
K016 <-> 2251799813685264
K017 <-> 2251799813685265
K018 <-> 2251799813685266
K019 <-> 2251799813685267
K020 <-> 2251799813685268
K021 <-> 2251799813685269
K022 <-> 2251799813685270
K023 <-> 2251799813685271
K024 <-> 2251799813685272
K025 <-> 2251799813685273
K026 <-> 2251799813685274
K027 <-> 2251799813685275
K028 <-> 2251799813685276
K029 <-> 2251799813685277
K030 <-> 2251799813685278
K031 <-> 2251799813685279
K032 <-> 2251799813685280
K033 <-> 2251799813685281
K034 <-> 2251799813685282
K035 <-> 2251799813685283
K036 <-> 2251799813685284
K037 <-> 2251799813685285
K038 <-> 2251799813685286
K039 <-> 2251799813685287
K040 <-> 2251799813685288
K041 <-> 2251799813685289
K042 <-> 2251799813685290
K043 <-> 2251799813685291
K044 <-> 2251799813685292
K045 <-> 2251799813685293
K046 <-> 2251799813685294
K047 <-> 2251799813685295
K048 <-> 2251799813685296
K049 <-> 2251799813685297
K050 <-> 2251799813685298
K051 <-> 2251799813685299
K052 <-> 2251799813685300
K053 <-> 2251799813685301
K054 <-> 2251799813685302
K055 <-> 2251799813685303
K056 <-> 2251799813685304
K057 <-> 2251799813685305
K058 <-> 2251799813685306
K059 <-> 2251799813685307
K060 <-> 2251799813685308
K061 <-> 2251799813685309
K062 <-> 2251799813685310
K063 <-> 2251799813685311
K064 <-> 2251799813685312
K065 <-> 2251799813685313
K066 <-> 2251799813685314
K067 <-> 2251799813685315
K068 <-> 2251799813685316
K069 <-> 2251799813685317
K070 <-> 2251799813685318
K071 <-> 2251799813685319
K072 <-> 2251799813685320
K073 <-> 2251799813685321
K074 <-> 2251799813685322
K075 <-> 2251799813685323
K076 <-> 2251799813685324
K077 <-> 2251799813685325
K078 <-> 2251799813685326

09:19:49.957 [] DEBUG io.camunda.zeebe.broker.logstreams - Close stream processor
09:19:49.957 [Broker-0-StreamProcessor-1] DEBUG io.camunda.zeebe.logstreams - Closed stream processor controller Broker-0-StreamProcessor-1.
09:19:49.990 [Broker-0-LogStream-1] INFO  io.camunda.zeebe.logstreams - Close appender for log stream stream-1
09:19:49.996 [stream-1-write-buffer] DEBUG io.camunda.zeebe.dispatcher - Dispatcher closed
09:19:49.996 [Broker-0-LogStream-1] INFO  io.camunda.zeebe.logstreams - On closing logstream stream-1 close 2 readers
09:19:49.996 [] DEBUG io.camunda.zeebe.broker.test - Clean up test files on path /tmp/junit1541274684531871281
09:19:49.997 [] DEBUG io.camunda.zeebe.util.actor - Closing actor thread ground '-zb-fs-workers'
09:19:49.997 [] DEBUG io.camunda.zeebe.util.actor - Closing actor thread ground '-zb-actors'
09:19:49.998 [] DEBUG io.camunda.zeebe.util.actor - Closing actor thread ground '-zb-fs-workers': closed successfully
09:19:49.998 [] DEBUG io.camunda.zeebe.util.actor - Closing actor thread ground '-zb-actors': closed successfully
09:19:49.998 [] INFO  io.camunda.zeebe.engine.processing.randomized.FailedPropertyBasedTestDataPrinter - Data of failed test case: TestDataRecord{processSeed=4020342130641592473, executionPathSeed=7049668446223977370}
09:19:50.026 [] INFO  io.camunda.zeebe.engine.processing.randomized.FailedPropertyBasedTestDataPrinter - Test case failed at: ScheduledExecutionStep{step=StepActivateJobAndThrowError[elementId=id_26,errorCode=error_id_26,jobType=job_id_26,variables={}], activationTime=PT7M, startTime=PT7M, endTime=PT7M, activationDuration=PT0S}
09:19:50.029 [] INFO  io.camunda.zeebe.engine.processing.randomized.FailedPropertyBasedTestDataPrinter - Execution path of failed test case:
ScheduledExecutionStep{step=StepTriggerTimerStartEvent[timeToAdd=PT7M,variables={}], activationTime=PT0S, startTime=PT0S, endTime=PT7M, activationDuration=PT7M}
ScheduledExecutionStep{step=StepActivateAndCompleteJob[elementId=id_28_variables_task,jobType=id_28_variables,variables={correlationKey=default_correlation_key, boundary_timer_id_10=PT8760H}], activationTime=PT7M, startTime=PT7M, endTime=PT7M, activationDuration=PT0S}
ScheduledExecutionStep{step=StepPublishMessage[correlationKeyValue=default_correlation_key,messageName=message_id_1,variables={correlationKey=default_correlation_key}], activationTime=PT7M, startTime=PT7M, endTime=PT7M, activationDuration=PT0S}
ScheduledExecutionStep{step=StepActivateBPMNElement[elementId=id_2,variables={}], activationTime=PT7M, startTime=PT7M, endTime=PT7M, activationDuration=PT0S}
ScheduledExecutionStep{step=StepActivateBPMNElement[elementId=fork_id_3,variables={}], activationTime=PT7M, startTime=PT7M, endTime=PT7M, activationDuration=PT0S}
ScheduledExecutionStep{step=StepActivateBPMNElement[elementId=id_7,variables={}], activationTime=PT7M, startTime=PT7M, endTime=PT7M, activationDuration=PT0S}
ScheduledExecutionStep{step=StepActivateBPMNElement[elementId=id_10,variables={}], activationTime=PT7M, startTime=PT7M, endTime=PT7M, activationDuration=PT0S}
ScheduledExecutionStep{step=StepActivateAndCompleteJob[elementId=id_10,jobType=job_id_10,variables={}], activationTime=PT7M, startTime=PT7M, endTime=PT7M, activationDuration=PT0S}
ScheduledExecutionStep{step=StepRaiseIncidentThenResolveAndPickConditionCase[edgeId=edge_id_16,forkingGatewayId=fork_id_11,gatewayConditionVariable=id_11_branch,variables={}], activationTime=PT7M, startTime=PT7M, endTime=PT7M, activationDuration=PT0S}
ScheduledExecutionStep{step=StepPublishMessage[correlationKeyValue=default_correlation_key,messageName=message_id_18,variables={correlationKey=default_correlation_key}], activationTime=PT7M, startTime=PT7M, endTime=PT7M, activationDuration=PT0S}
ScheduledExecutionStep{step=StepActivateBPMNElement[elementId=fork_id_19,variables={}], activationTime=PT7M, startTime=PT7M, endTime=PT7M, activationDuration=PT0S}
ScheduledExecutionStep{step=StepActivateBPMNElement[elementId=id_23,variables={}], activationTime=PT7M, startTime=PT7M, endTime=PT7M, activationDuration=PT0S}
ScheduledExecutionStep{step=StepActivateBPMNElement[elementId=id_26,variables={}], activationTime=PT7M, startTime=PT7M, endTime=PT7M, activationDuration=PT0S}
ScheduledExecutionStep{step=StepActivateAndFailJob[elementId=id_26,jobType=job_id_26,updateRetries=false,variables={}], activationTime=PT7M, startTime=PT7M, endTime=PT7M, activationDuration=PT0S}
--failed here--> ScheduledExecutionStep{step=StepActivateJobAndThrowError[elementId=id_26,errorCode=error_id_26,jobType=job_id_26,variables={}], activationTime=PT7M, startTime=PT7M, endTime=PT7M, activationDuration=PT0S}

@Zelldon
Copy link
Member

Zelldon commented Dec 2, 2022

Failed again:

[INFO] --- maven-surefire-plugin:3.0.0-M7:test (default-test) @ zeebe-elasticsearch-exporter ---
Error:  Tests run: 706, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 306.834 s <<< FAILURE! - in io.camunda.zeebe.engine.processing.randomized.ProcessExecutionRandomizedPropertyTest
Error:  ProcessExecutionRandomizedPropertyTest.shouldExecuteProcessToEnd  Time elapsed: 5.585 s  <<< ERROR!
io.camunda.zeebe.test.util.stream.StreamWrapperException: No event found matching the criteria
	at io.camunda.zeebe.test.util.stream.StreamWrapper.await(StreamWrapper.java:72)
	at io.camunda.zeebe.engine.processing.randomized.ProcessExecutionRandomizedPropertyTest.shouldExecuteProcessToEnd(ProcessExecutionRandomizedPropertyTest.java:81)
	at jdk.internal.reflect.GeneratedMethodAccessor29.invoke(Unknown Source)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:568)
	at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59)
	at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12)
	at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56)
	at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17)
	at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:61)
	at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:54)
	at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:54)
	at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:54)
	at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:54)
	at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:54)
	at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:54)
	at org.junit.rules.RunRules.evaluate(RunRules.java:20)
	at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:61)
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
	at org.junit.runners.BlockJUnit4ClassRunner$1.evaluate(BlockJUnit4ClassRunner.java:100)
	at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:366)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:103)
	at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:63)
	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
	at org.junit.runners.Suite.runChild(Suite.java:128)
	at org.junit.runners.Suite.runChild(Suite.java:27)
	at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331)
	at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79)
	at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329)
	at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66)
	at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293)
	at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306)
	at org.junit.runners.ParentRunner.run(ParentRunner.java:413)
	at org.junit.runner.JUnitCore.run(JUnitCore.java:137)
	at org.junit.runner.JUnitCore.run(JUnitCore.java:115)
	at org.junit.vintage.engine.execution.RunnerExecutor.execute(RunnerExecutor.java:42)
	at org.junit.vintage.engine.VintageTestEngine.executeAllChildren(VintageTestEngine.java:80)
	at org.junit.vintage.engine.VintageTestEngine.execute(VintageTestEngine.java:72)
	at org.junit.platform.launcher.core.EngineExecutionOrchestrator.execute(EngineExecutionOrchestrator.java:147)
	at org.junit.platform.launcher.core.EngineExecutionOrchestrator.execute(EngineExecutionOrchestrator.java:127)
	at org.junit.platform.launcher.core.EngineExecutionOrchestrator.execute(EngineExecutionOrchestrator.java:90)
	at org.junit.platform.launcher.core.EngineExecutionOrchestrator.lambda$execute$0(EngineExecutionOrchestrator.java:55)
	at org.junit.platform.launcher.core.EngineExecutionOrchestrator.withInterceptedStreams(EngineExecutionOrchestrator.java:102)
	at org.junit.platform.launcher.core.EngineExecutionOrchestrator.execute(EngineExecutionOrchestrator.java:54)
	at org.junit.platform.launcher.core.DefaultLauncher.execute(DefaultLauncher.java:114)
	at org.junit.platform.launcher.core.DefaultLauncher.execute(DefaultLauncher.java:86)
	at org.junit.platform.launcher.core.DefaultLauncherSession$DelegatingLauncher.execute(DefaultLauncherSession.java:86)
	at org.apache.maven.surefire.junitplatform.LazyLauncher.execute(LazyLauncher.java:55)
	at org.apache.maven.surefire.junitplatform.JUnitPlatformProvider.lambda$execute$1(JUnitPlatformProvider.java:234)
	at java.base/java.util.Iterator.forEachRemaining(Iterator.java:133)
	at org.apache.maven.surefire.junitplatform.JUnitPlatformProvider.execute(JUnitPlatformProvider.java:228)
	at org.apache.maven.surefire.junitplatform.JUnitPlatformProvider.invokeAllTests(JUnitPlatformProvider.java:175)
	at org.apache.maven.surefire.junitplatform.JUnitPlatformProvider.invoke(JUnitPlatformProvider.java:131)
	at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:456)
	at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:169)
	at org.apache.maven.surefire.booter.ForkedBooter.run(ForkedBooter.java:595)
	at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:581)

https://github.com/camunda/zeebe/actions/runs/3601529537/jobs/6067440685

@Zelldon
Copy link
Member

Zelldon commented Dec 2, 2022

@remcowesterhoud
Copy link
Contributor

Assuming the issue is the same here as I described in #11121 (comment), yes. I will fix it on Monday.

@Zelldon
Copy link
Member

Zelldon commented Dec 2, 2022

Thanks @remcowesterhoud 👍

remcowesterhoud added a commit that referenced this issue Dec 2, 2022
We have a bug in Zeebe. This escalation block builders makes it so there is a high chance that we run into this bug.

For now let's disable this until the bug is fixed.
#11121 (comment)
ghost pushed a commit that referenced this issue Dec 2, 2022
11166: Temporarily disable escalation events in process generation r=remcowesterhoud a=remcowesterhoud

## Description

<!-- Please explain the changes you made here. -->
We have a bug in Zeebe. This escalation block builders makes it so there is a high chance that we run into this bug.

For now let's disable this until the bug is fixed. #11121 (comment)

## Related issues

<!-- Which issues are closed by this PR or are related -->

Related to #1121



Co-authored-by: Remco Westerhoud <remco@westerhoud.nl>
ghost pushed a commit that referenced this issue Dec 7, 2022
11178: Interrupt flow scope when a terminate end event is reached r=koevskinikola a=remcowesterhoud

## Description

<!-- Please explain the changes you made here. -->
The terminate end event should mark its flow scope as interrupted when it's completed. Having an interrupted flow scope means other elements in this scope can no longer be activated or completed. Any ACTIVATE/COMPLETE command will be rejected.

If we do not interrupt the flowscope it is possible for the engine write a TERMINATE command as a result of the end event. It could then go on to process a COMPLETE command. This will not be rejected, and it will take outgoing sequence flows for this element. The TERMINATE will then be rejected as the element is no longer active. Since the flowscope now has an outgoing sequence flows it is considered to have active elements, it will not be terminated, as it should upon reaching a terminate end event.

Since the flow scope gets interrupted we will also have to change the logic in the processors of these possible flow scopes (`ProcessProcessor`, `SubProcessProcessors`, `EventSubProcessProcessor`). Here we need to verify that the scope was interrupted by a terminate end event, and if it is we need to make sure the scope gets completed, instead of terminated.

## Related issues

<!-- Which issues are closed by this PR or are related -->

relates to #11121 



Co-authored-by: Remco Westerhoud <remco@westerhoud.nl>
ghost pushed a commit that referenced this issue Dec 7, 2022
11214: [Backport stable/8.1] Interrupt flow scope when a terminate end event is reached r=remcowesterhoud a=backport-action

# Description
Backport of #11178 to `stable/8.1`.

relates to #11121

Co-authored-by: Remco Westerhoud <remco@westerhoud.nl>
ghost pushed a commit that referenced this issue Dec 7, 2022
11214: [Backport stable/8.1] Interrupt flow scope when a terminate end event is reached r=remcowesterhoud a=backport-action

# Description
Backport of #11178 to `stable/8.1`.

relates to #11121

Co-authored-by: Remco Westerhoud <remco@westerhoud.nl>
@ghost ghost closed this as completed in 36c0947 Dec 15, 2022
@Zelldon Zelldon added the version:8.1.6 Marks an issue as being completely or in parts released in 8.1.6 label Jan 4, 2023
@npepinpe npepinpe added the version:8.2.0 Marks an issue as being completely or in parts released in 8.2.0 label Apr 5, 2023
This issue was closed.
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
kind/bug Categorizes an issue or PR as a bug kind/flake Categorizes issue or PR as related to a flaky test version:8.1.6 Marks an issue as being completely or in parts released in 8.1.6 version:8.2.0-alpha3 version:8.2.0 Marks an issue as being completely or in parts released in 8.2.0
Projects
None yet
Development

Successfully merging a pull request may close this issue.

8 participants