Node ID






Columns











Log Level






Log Marker











Class














































node1 0.000ns 2025-09-25 10:57:53.809 1 INFO STARTUP <main> StaticPlatformBuilder:
////////////////////// // Node is Starting // //////////////////////
node1 85.000ms 2025-09-25 10:57:53.894 2 DEBUG STARTUP <main> StaticPlatformBuilder: main() started {} [com.swirlds.logging.legacy.payload.NodeStartPayload]
node1 101.000ms 2025-09-25 10:57:53.910 3 WARN STARTUP <main> PlatformConfigUtils: Configuration property 'reconnect.asyncOutputStreamFlushMilliseconds' was renamed to 'reconnect.asyncOutputStreamFlush'. This build is currently backwards compatible with the old name, but this may not be true in a future release, so it is important to switch to the new name.
node1 102.000ms 2025-09-25 10:57:53.911 4 WARN STARTUP <main> PlatformConfigUtils: Configuration property 'state.roundsExpired' was renamed to 'consensus.roundsExpired'. This build is currently backwards compatible with the old name, but this may not be true in a future release, so it is important to switch to the new name.
node1 215.000ms 2025-09-25 10:57:54.024 5 INFO STARTUP <main> Browser: The following nodes [1] are set to run locally
node1 221.000ms 2025-09-25 10:57:54.030 6 INFO STARTUP <main> PlatformTestingToolMain: Registering PlatformTestingToolState with ConstructableRegistry
node1 249.000ms 2025-09-25 10:57:54.058 7 INFO DEMO_INFO <main> ExpectedFCMFamily: Set ExpectedMap initial capacity to be: 4000
node1 250.000ms 2025-09-25 10:57:54.059 8 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountList initial capacity to be: 1000
node1 251.000ms 2025-09-25 10:57:54.060 9 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountSelfEntitiesList initial capacity to be: 1000
node1 251.000ms 2025-09-25 10:57:54.060 10 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqList initial capacity to be: 1000
node1 251.000ms 2025-09-25 10:57:54.060 11 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqSelfEntitiesList initial capacity to be: 1000
node1 252.000ms 2025-09-25 10:57:54.061 12 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountList initial capacity to be: 1000
node1 252.000ms 2025-09-25 10:57:54.061 13 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountSelfEntitiesList initial capacity to be: 1000
node1 253.000ms 2025-09-25 10:57:54.062 14 INFO DEMO_INFO <main> ExpectedFCMFamily: Set ExpectedMap initial capacity to be: 4000
node1 253.000ms 2025-09-25 10:57:54.062 15 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountList initial capacity to be: 1000
node1 254.000ms 2025-09-25 10:57:54.063 16 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountSelfEntitiesList initial capacity to be: 1000
node1 254.000ms 2025-09-25 10:57:54.063 17 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqList initial capacity to be: 1000
node1 255.000ms 2025-09-25 10:57:54.064 18 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqSelfEntitiesList initial capacity to be: 1000
node1 255.000ms 2025-09-25 10:57:54.064 19 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountList initial capacity to be: 1000
node1 255.000ms 2025-09-25 10:57:54.064 20 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountSelfEntitiesList initial capacity to be: 1000
node1 256.000ms 2025-09-25 10:57:54.065 21 INFO STARTUP <main> PlatformTestingToolMain: PlatformTestingToolState is registered with ConstructableRegistry: -4571139351850460298
node1 657.000ms 2025-09-25 10:57:54.466 44 DEBUG STARTUP <main> BootstrapUtils: Scanning the classpath for RuntimeConstructable classes
node2 1.122s 2025-09-25 10:57:54.931 1 INFO STARTUP <main> StaticPlatformBuilder:
////////////////////// // Node is Starting // //////////////////////
node2 1.217s 2025-09-25 10:57:55.026 2 DEBUG STARTUP <main> StaticPlatformBuilder: main() started {} [com.swirlds.logging.legacy.payload.NodeStartPayload]
node2 1.234s 2025-09-25 10:57:55.043 3 WARN STARTUP <main> PlatformConfigUtils: Configuration property 'reconnect.asyncOutputStreamFlushMilliseconds' was renamed to 'reconnect.asyncOutputStreamFlush'. This build is currently backwards compatible with the old name, but this may not be true in a future release, so it is important to switch to the new name.
node2 1.235s 2025-09-25 10:57:55.044 4 WARN STARTUP <main> PlatformConfigUtils: Configuration property 'state.roundsExpired' was renamed to 'consensus.roundsExpired'. This build is currently backwards compatible with the old name, but this may not be true in a future release, so it is important to switch to the new name.
node2 1.353s 2025-09-25 10:57:55.162 5 INFO STARTUP <main> Browser: The following nodes [2] are set to run locally
node2 1.361s 2025-09-25 10:57:55.170 6 INFO STARTUP <main> PlatformTestingToolMain: Registering PlatformTestingToolState with ConstructableRegistry
node0 1.380s 2025-09-25 10:57:55.189 1 INFO STARTUP <main> StaticPlatformBuilder:
////////////////////// // Node is Starting // //////////////////////
node2 1.392s 2025-09-25 10:57:55.201 7 INFO DEMO_INFO <main> ExpectedFCMFamily: Set ExpectedMap initial capacity to be: 4000
node2 1.393s 2025-09-25 10:57:55.202 8 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountList initial capacity to be: 1000
node2 1.394s 2025-09-25 10:57:55.203 9 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountSelfEntitiesList initial capacity to be: 1000
node2 1.394s 2025-09-25 10:57:55.203 10 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqList initial capacity to be: 1000
node2 1.395s 2025-09-25 10:57:55.204 11 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqSelfEntitiesList initial capacity to be: 1000
node2 1.395s 2025-09-25 10:57:55.204 12 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountList initial capacity to be: 1000
node2 1.396s 2025-09-25 10:57:55.205 13 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountSelfEntitiesList initial capacity to be: 1000
node2 1.397s 2025-09-25 10:57:55.206 14 INFO DEMO_INFO <main> ExpectedFCMFamily: Set ExpectedMap initial capacity to be: 4000
node2 1.397s 2025-09-25 10:57:55.206 15 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountList initial capacity to be: 1000
node2 1.397s 2025-09-25 10:57:55.206 16 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountSelfEntitiesList initial capacity to be: 1000
node2 1.398s 2025-09-25 10:57:55.207 17 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqList initial capacity to be: 1000
node2 1.398s 2025-09-25 10:57:55.207 18 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqSelfEntitiesList initial capacity to be: 1000
node2 1.399s 2025-09-25 10:57:55.208 19 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountList initial capacity to be: 1000
node2 1.399s 2025-09-25 10:57:55.208 20 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountSelfEntitiesList initial capacity to be: 1000
node2 1.400s 2025-09-25 10:57:55.209 21 INFO STARTUP <main> PlatformTestingToolMain: PlatformTestingToolState is registered with ConstructableRegistry: -4571139351850460298
node0 1.479s 2025-09-25 10:57:55.288 2 DEBUG STARTUP <main> StaticPlatformBuilder: main() started {} [com.swirlds.logging.legacy.payload.NodeStartPayload]
node0 1.496s 2025-09-25 10:57:55.305 3 WARN STARTUP <main> PlatformConfigUtils: Configuration property 'reconnect.asyncOutputStreamFlushMilliseconds' was renamed to 'reconnect.asyncOutputStreamFlush'. This build is currently backwards compatible with the old name, but this may not be true in a future release, so it is important to switch to the new name.
node0 1.498s 2025-09-25 10:57:55.307 4 WARN STARTUP <main> PlatformConfigUtils: Configuration property 'state.roundsExpired' was renamed to 'consensus.roundsExpired'. This build is currently backwards compatible with the old name, but this may not be true in a future release, so it is important to switch to the new name.
node1 1.540s 2025-09-25 10:57:55.349 45 DEBUG STARTUP <main> BootstrapUtils: Done with registerConstructables, time taken 883ms
node3 1.543s 2025-09-25 10:57:55.352 1 INFO STARTUP <main> StaticPlatformBuilder:
////////////////////// // Node is Starting // //////////////////////
node1 1.548s 2025-09-25 10:57:55.357 46 WARN STARTUP <main> PlatformConfigUtils: Configuration property 'reconnect.asyncOutputStreamFlushMilliseconds' was renamed to 'reconnect.asyncOutputStreamFlush'. This build is currently backwards compatible with the old name, but this may not be true in a future release, so it is important to switch to the new name.
node1 1.548s 2025-09-25 10:57:55.357 47 WARN STARTUP <main> PlatformConfigUtils: Configuration property 'state.roundsExpired' was renamed to 'consensus.roundsExpired'. This build is currently backwards compatible with the old name, but this may not be true in a future release, so it is important to switch to the new name.
node1 1.594s 2025-09-25 10:57:55.403 48 INFO STARTUP <main> PrometheusEndpoint: PrometheusEndpoint: Starting server listing on port: 9999
node0 1.624s 2025-09-25 10:57:55.433 5 INFO STARTUP <main> Browser: The following nodes [0] are set to run locally
node0 1.632s 2025-09-25 10:57:55.441 6 INFO STARTUP <main> PlatformTestingToolMain: Registering PlatformTestingToolState with ConstructableRegistry
node3 1.637s 2025-09-25 10:57:55.446 2 DEBUG STARTUP <main> StaticPlatformBuilder: main() started {} [com.swirlds.logging.legacy.payload.NodeStartPayload]
node3 1.654s 2025-09-25 10:57:55.463 3 WARN STARTUP <main> PlatformConfigUtils: Configuration property 'reconnect.asyncOutputStreamFlushMilliseconds' was renamed to 'reconnect.asyncOutputStreamFlush'. This build is currently backwards compatible with the old name, but this may not be true in a future release, so it is important to switch to the new name.
node1 1.655s 2025-09-25 10:57:55.464 49 WARN STARTUP <main> CryptoStatic: There are no keys on disk, Adhoc keys will be generated, but this is incompatible with DAB.
node1 1.655s 2025-09-25 10:57:55.464 50 DEBUG STARTUP <main> CryptoStatic: Started generating keys
node3 1.655s 2025-09-25 10:57:55.464 4 WARN STARTUP <main> PlatformConfigUtils: Configuration property 'state.roundsExpired' was renamed to 'consensus.roundsExpired'. This build is currently backwards compatible with the old name, but this may not be true in a future release, so it is important to switch to the new name.
node0 1.662s 2025-09-25 10:57:55.471 7 INFO DEMO_INFO <main> ExpectedFCMFamily: Set ExpectedMap initial capacity to be: 4000
node0 1.664s 2025-09-25 10:57:55.473 8 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountList initial capacity to be: 1000
node0 1.664s 2025-09-25 10:57:55.473 9 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountSelfEntitiesList initial capacity to be: 1000
node0 1.665s 2025-09-25 10:57:55.474 10 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqList initial capacity to be: 1000
node0 1.665s 2025-09-25 10:57:55.474 11 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqSelfEntitiesList initial capacity to be: 1000
node0 1.666s 2025-09-25 10:57:55.475 12 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountList initial capacity to be: 1000
node0 1.666s 2025-09-25 10:57:55.475 13 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountSelfEntitiesList initial capacity to be: 1000
node0 1.667s 2025-09-25 10:57:55.476 14 INFO DEMO_INFO <main> ExpectedFCMFamily: Set ExpectedMap initial capacity to be: 4000
node0 1.667s 2025-09-25 10:57:55.476 15 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountList initial capacity to be: 1000
node0 1.668s 2025-09-25 10:57:55.477 16 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountSelfEntitiesList initial capacity to be: 1000
node0 1.668s 2025-09-25 10:57:55.477 17 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqList initial capacity to be: 1000
node0 1.668s 2025-09-25 10:57:55.477 18 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqSelfEntitiesList initial capacity to be: 1000
node0 1.669s 2025-09-25 10:57:55.478 19 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountList initial capacity to be: 1000
node0 1.669s 2025-09-25 10:57:55.478 20 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountSelfEntitiesList initial capacity to be: 1000
node0 1.670s 2025-09-25 10:57:55.479 21 INFO STARTUP <main> PlatformTestingToolMain: PlatformTestingToolState is registered with ConstructableRegistry: -4571139351850460298
node3 1.776s 2025-09-25 10:57:55.585 5 INFO STARTUP <main> Browser: The following nodes [3] are set to run locally
node3 1.783s 2025-09-25 10:57:55.592 6 INFO STARTUP <main> PlatformTestingToolMain: Registering PlatformTestingToolState with ConstructableRegistry
node3 1.813s 2025-09-25 10:57:55.622 7 INFO DEMO_INFO <main> ExpectedFCMFamily: Set ExpectedMap initial capacity to be: 4000
node3 1.815s 2025-09-25 10:57:55.624 8 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountList initial capacity to be: 1000
node3 1.815s 2025-09-25 10:57:55.624 9 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountSelfEntitiesList initial capacity to be: 1000
node3 1.816s 2025-09-25 10:57:55.625 10 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqList initial capacity to be: 1000
node3 1.816s 2025-09-25 10:57:55.625 11 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqSelfEntitiesList initial capacity to be: 1000
node3 1.817s 2025-09-25 10:57:55.626 12 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountList initial capacity to be: 1000
node3 1.817s 2025-09-25 10:57:55.626 13 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountSelfEntitiesList initial capacity to be: 1000
node3 1.818s 2025-09-25 10:57:55.627 14 INFO DEMO_INFO <main> ExpectedFCMFamily: Set ExpectedMap initial capacity to be: 4000
node3 1.818s 2025-09-25 10:57:55.627 15 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountList initial capacity to be: 1000
node3 1.819s 2025-09-25 10:57:55.628 16 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountSelfEntitiesList initial capacity to be: 1000
node3 1.819s 2025-09-25 10:57:55.628 17 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqList initial capacity to be: 1000
node3 1.819s 2025-09-25 10:57:55.628 18 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqSelfEntitiesList initial capacity to be: 1000
node3 1.820s 2025-09-25 10:57:55.629 19 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountList initial capacity to be: 1000
node3 1.820s 2025-09-25 10:57:55.629 20 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountSelfEntitiesList initial capacity to be: 1000
node3 1.821s 2025-09-25 10:57:55.630 21 INFO STARTUP <main> PlatformTestingToolMain: PlatformTestingToolState is registered with ConstructableRegistry: -4571139351850460298
node2 1.833s 2025-09-25 10:57:55.642 44 DEBUG STARTUP <main> BootstrapUtils: Scanning the classpath for RuntimeConstructable classes
node0 2.100s 2025-09-25 10:57:55.909 44 DEBUG STARTUP <main> BootstrapUtils: Scanning the classpath for RuntimeConstructable classes
node3 2.241s 2025-09-25 10:57:56.050 44 DEBUG STARTUP <main> BootstrapUtils: Scanning the classpath for RuntimeConstructable classes
node2 2.830s 2025-09-25 10:57:56.639 45 DEBUG STARTUP <main> BootstrapUtils: Done with registerConstructables, time taken 997ms
node2 2.837s 2025-09-25 10:57:56.646 46 WARN STARTUP <main> PlatformConfigUtils: Configuration property 'reconnect.asyncOutputStreamFlushMilliseconds' was renamed to 'reconnect.asyncOutputStreamFlush'. This build is currently backwards compatible with the old name, but this may not be true in a future release, so it is important to switch to the new name.
node2 2.837s 2025-09-25 10:57:56.646 47 WARN STARTUP <main> PlatformConfigUtils: Configuration property 'state.roundsExpired' was renamed to 'consensus.roundsExpired'. This build is currently backwards compatible with the old name, but this may not be true in a future release, so it is important to switch to the new name.
node2 2.875s 2025-09-25 10:57:56.684 48 INFO STARTUP <main> PrometheusEndpoint: PrometheusEndpoint: Starting server listing on port: 9999
node2 2.937s 2025-09-25 10:57:56.746 49 WARN STARTUP <main> CryptoStatic: There are no keys on disk, Adhoc keys will be generated, but this is incompatible with DAB.
node2 2.937s 2025-09-25 10:57:56.746 50 DEBUG STARTUP <main> CryptoStatic: Started generating keys
node0 3.152s 2025-09-25 10:57:56.961 45 DEBUG STARTUP <main> BootstrapUtils: Done with registerConstructables, time taken 1050ms
node0 3.160s 2025-09-25 10:57:56.969 46 WARN STARTUP <main> PlatformConfigUtils: Configuration property 'reconnect.asyncOutputStreamFlushMilliseconds' was renamed to 'reconnect.asyncOutputStreamFlush'. This build is currently backwards compatible with the old name, but this may not be true in a future release, so it is important to switch to the new name.
node0 3.160s 2025-09-25 10:57:56.969 47 WARN STARTUP <main> PlatformConfigUtils: Configuration property 'state.roundsExpired' was renamed to 'consensus.roundsExpired'. This build is currently backwards compatible with the old name, but this may not be true in a future release, so it is important to switch to the new name.
node0 3.220s 2025-09-25 10:57:57.029 48 INFO STARTUP <main> PrometheusEndpoint: PrometheusEndpoint: Starting server listing on port: 9999
node0 3.293s 2025-09-25 10:57:57.102 49 WARN STARTUP <main> CryptoStatic: There are no keys on disk, Adhoc keys will be generated, but this is incompatible with DAB.
node0 3.294s 2025-09-25 10:57:57.103 50 DEBUG STARTUP <main> CryptoStatic: Started generating keys
node3 3.370s 2025-09-25 10:57:57.179 45 DEBUG STARTUP <main> BootstrapUtils: Done with registerConstructables, time taken 1128ms
node3 3.376s 2025-09-25 10:57:57.185 46 WARN STARTUP <main> PlatformConfigUtils: Configuration property 'reconnect.asyncOutputStreamFlushMilliseconds' was renamed to 'reconnect.asyncOutputStreamFlush'. This build is currently backwards compatible with the old name, but this may not be true in a future release, so it is important to switch to the new name.
node3 3.377s 2025-09-25 10:57:57.186 47 WARN STARTUP <main> PlatformConfigUtils: Configuration property 'state.roundsExpired' was renamed to 'consensus.roundsExpired'. This build is currently backwards compatible with the old name, but this may not be true in a future release, so it is important to switch to the new name.
node3 3.417s 2025-09-25 10:57:57.226 48 INFO STARTUP <main> PrometheusEndpoint: PrometheusEndpoint: Starting server listing on port: 9999
node3 3.479s 2025-09-25 10:57:57.288 49 WARN STARTUP <main> CryptoStatic: There are no keys on disk, Adhoc keys will be generated, but this is incompatible with DAB.
node3 3.479s 2025-09-25 10:57:57.288 50 DEBUG STARTUP <main> CryptoStatic: Started generating keys
node1 3.642s 2025-09-25 10:57:57.451 51 DEBUG STARTUP <main> CryptoStatic: Done generating keys
node1 3.730s 2025-09-25 10:57:57.539 54 INFO STARTUP <main> StartupStateUtils: No saved states were found on disk.
node1 3.731s 2025-09-25 10:57:57.540 55 INFO DEMO_INFO <main> ExpectedFCMFamily: Set ExpectedMap initial capacity to be: 4000
node1 3.731s 2025-09-25 10:57:57.540 56 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountList initial capacity to be: 1000
node1 3.732s 2025-09-25 10:57:57.541 57 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountSelfEntitiesList initial capacity to be: 1000
node1 3.732s 2025-09-25 10:57:57.541 58 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqList initial capacity to be: 1000
node1 3.732s 2025-09-25 10:57:57.541 59 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqSelfEntitiesList initial capacity to be: 1000
node1 3.732s 2025-09-25 10:57:57.541 60 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountList initial capacity to be: 1000
node1 3.733s 2025-09-25 10:57:57.542 61 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountSelfEntitiesList initial capacity to be: 1000
node1 3.736s 2025-09-25 10:57:57.545 69 INFO STARTUP <main> MerkleStateRoot: Putting states...
node1 3.739s 2025-09-25 10:57:57.548 70 INFO STARTUP <main> MerkleStateRoot: Put states! Service name: PlatformStateService
node1 3.740s 2025-09-25 10:57:57.549 71 INFO STARTUP <main> MerkleStateRoot: Setting child.. Service name: PlatformStateService / Number of children: 0 / node: com.swirlds.state.merkle.singleton.SingletonNode@65ec8b24
node1 4.415s 2025-09-25 10:57:58.224 79 INFO STARTUP <main> MerkleStateRoot: Putting states...
node1 4.416s 2025-09-25 10:57:58.225 80 INFO STARTUP <main> MerkleStateRoot: Put states! Service name: RosterService
node1 4.466s 2025-09-25 10:57:58.275 86 INFO STARTUP <main> MerkleStateRoot: Setting child.. Service name: RosterService / Number of children: 1 / node: com.swirlds.virtualmap.VirtualMap@433d9680
node1 4.471s 2025-09-25 10:57:58.280 94 INFO STARTUP <main> MerkleStateRoot: Putting states...
node1 4.472s 2025-09-25 10:57:58.281 95 INFO STARTUP <main> MerkleStateRoot: Put states! Service name: RosterService
node1 4.473s 2025-09-25 10:57:58.282 96 INFO STARTUP <main> MerkleStateRoot: Setting child.. Service name: RosterService / Number of children: 2 / node: com.swirlds.state.merkle.singleton.SingletonNode@2da81754
node1 4.515s 2025-09-25 10:57:58.324 99 INFO STARTUP <main> BootstrapUtils: Not upgrading software, current software is version SemanticVersion[major=1, minor=0, patch=0, pre=, build=].
node1 4.520s 2025-09-25 10:57:58.329 100 INFO STARTUP <main> AddressBookInitializer: Starting from genesis: using the config address book.
node2 5.042s 2025-09-25 10:57:58.851 51 DEBUG STARTUP <main> CryptoStatic: Done generating keys
node2 5.127s 2025-09-25 10:57:58.936 54 INFO STARTUP <main> StartupStateUtils: No saved states were found on disk.
node2 5.128s 2025-09-25 10:57:58.937 55 INFO DEMO_INFO <main> ExpectedFCMFamily: Set ExpectedMap initial capacity to be: 4000
node2 5.129s 2025-09-25 10:57:58.938 56 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountList initial capacity to be: 1000
node2 5.129s 2025-09-25 10:57:58.938 57 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountSelfEntitiesList initial capacity to be: 1000
node2 5.130s 2025-09-25 10:57:58.939 58 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqList initial capacity to be: 1000
node2 5.130s 2025-09-25 10:57:58.939 59 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqSelfEntitiesList initial capacity to be: 1000
node2 5.130s 2025-09-25 10:57:58.939 60 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountList initial capacity to be: 1000
node2 5.130s 2025-09-25 10:57:58.939 61 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountSelfEntitiesList initial capacity to be: 1000
node2 5.134s 2025-09-25 10:57:58.943 69 INFO STARTUP <main> MerkleStateRoot: Putting states...
node2 5.138s 2025-09-25 10:57:58.947 70 INFO STARTUP <main> MerkleStateRoot: Put states! Service name: PlatformStateService
node2 5.138s 2025-09-25 10:57:58.947 71 INFO STARTUP <main> MerkleStateRoot: Setting child.. Service name: PlatformStateService / Number of children: 0 / node: com.swirlds.state.merkle.singleton.SingletonNode@65ec8b24
node0 5.278s 2025-09-25 10:57:59.087 51 DEBUG STARTUP <main> CryptoStatic: Done generating keys
node0 5.367s 2025-09-25 10:57:59.176 54 INFO STARTUP <main> StartupStateUtils: No saved states were found on disk.
node0 5.368s 2025-09-25 10:57:59.177 55 INFO DEMO_INFO <main> ExpectedFCMFamily: Set ExpectedMap initial capacity to be: 4000
node0 5.368s 2025-09-25 10:57:59.177 56 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountList initial capacity to be: 1000
node0 5.369s 2025-09-25 10:57:59.178 57 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountSelfEntitiesList initial capacity to be: 1000
node0 5.369s 2025-09-25 10:57:59.178 58 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqList initial capacity to be: 1000
node0 5.369s 2025-09-25 10:57:59.178 59 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqSelfEntitiesList initial capacity to be: 1000
node0 5.370s 2025-09-25 10:57:59.179 60 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountList initial capacity to be: 1000
node0 5.370s 2025-09-25 10:57:59.179 61 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountSelfEntitiesList initial capacity to be: 1000
node0 5.374s 2025-09-25 10:57:59.183 69 INFO STARTUP <main> MerkleStateRoot: Putting states...
node0 5.378s 2025-09-25 10:57:59.187 70 INFO STARTUP <main> MerkleStateRoot: Put states! Service name: PlatformStateService
node0 5.378s 2025-09-25 10:57:59.187 71 INFO STARTUP <main> MerkleStateRoot: Setting child.. Service name: PlatformStateService / Number of children: 0 / node: com.swirlds.state.merkle.singleton.SingletonNode@65ec8b24
node3 5.520s 2025-09-25 10:57:59.329 51 DEBUG STARTUP <main> CryptoStatic: Done generating keys
node3 5.598s 2025-09-25 10:57:59.407 54 INFO STARTUP <main> StartupStateUtils: No saved states were found on disk.
node3 5.599s 2025-09-25 10:57:59.408 55 INFO DEMO_INFO <main> ExpectedFCMFamily: Set ExpectedMap initial capacity to be: 4000
node3 5.599s 2025-09-25 10:57:59.408 56 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountList initial capacity to be: 1000
node3 5.600s 2025-09-25 10:57:59.409 57 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountSelfEntitiesList initial capacity to be: 1000
node3 5.600s 2025-09-25 10:57:59.409 58 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqList initial capacity to be: 1000
node3 5.600s 2025-09-25 10:57:59.409 59 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqSelfEntitiesList initial capacity to be: 1000
node3 5.600s 2025-09-25 10:57:59.409 60 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountList initial capacity to be: 1000
node3 5.601s 2025-09-25 10:57:59.410 61 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountSelfEntitiesList initial capacity to be: 1000
node3 5.604s 2025-09-25 10:57:59.413 69 INFO STARTUP <main> MerkleStateRoot: Putting states...
node3 5.607s 2025-09-25 10:57:59.416 70 INFO STARTUP <main> MerkleStateRoot: Put states! Service name: PlatformStateService
node3 5.608s 2025-09-25 10:57:59.417 71 INFO STARTUP <main> MerkleStateRoot: Setting child.. Service name: PlatformStateService / Number of children: 0 / node: com.swirlds.state.merkle.singleton.SingletonNode@65ec8b24
node1 5.639s 2025-09-25 10:57:59.448 101 INFO STARTUP <main> OSHealthChecker:
PASSED - Clock Source Speed Check Report[callsPerSec=21023748] PASSED - Entropy Check Report[success=true, entropySource=Strong Instance, elapsedNanos=262040, randomLong=7721667995540952352, exception=null] PASSED - Entropy Check Report[success=true, entropySource=NativePRNGBlocking:SUN, elapsedNanos=10970, randomLong=4577805743057360793, exception=null] PASSED - File System Check Report[code=SUCCESS, readNanos=1138099, data=35, exception=null] OS Health Check Report - Complete (took 1019 ms)
node1 5.668s 2025-09-25 10:57:59.477 102 DEBUG STARTUP <main> BootstrapUtils: jvmPauseDetectorThread started
node1 5.675s 2025-09-25 10:57:59.484 103 INFO STARTUP <main> StandardScratchpad: Scratchpad platform.iss contents:
LAST_ISS_ROUND null
node1 5.679s 2025-09-25 10:57:59.488 104 INFO STARTUP <main> PlatformBuilder: Default platform pool parallelism: 8
node1 5.758s 2025-09-25 10:57:59.567 105 INFO STARTUP <main> SwirldsPlatform: Starting with roster history:
RosterHistory[ currentRosterRound: 0 ][ no previous roster set ] Current Roster: { "rosterEntries": [{ "weight": "3125000000", "gossipCaCertificate": "MIIDpzCCAg+gAwIBAgIJAK05TS8KZeb1MA0GCSqGSIb3DQEBDAUAMBIxEDAOBgNVBAMTB3Mtbm9kZTEwIBcNMDAwMTAxMDAwMDAwWhgPMjEwMDAxMDEwMDAwMDBaMBIxEDAOBgNVBAMTB3Mtbm9kZTEwggGiMA0GCSqGSIb3DQEBAQUAA4IBjwAwggGKAoIBgQDBoP9dI3K1PRLRK7h90D9eNCfgzuHTyJi70yDEs90XJXlE6jmgf1NE2av83VAhQHLxu8Ehc/55M9Ayx9IQc0zJLSS+IrRM9QwqoG8ZvNdRgNw+je3V/8rAK/mHId+cPnnyDplCyskyi5kWCv6kTULIewFH8/KVZwhe0/hB2+N6ujWixURrxjjGLHA6b2gPoGAb/nxiVOn+L0cWcOzcyiYShxagj0FBWV7AxKx65Ynzfe7eF0gOzBUA+IM10OM5KXJejk53Xz5KpEyGe8htO/bXFlpLdm3UzrYiIhY0oKPYKECAC1s+VAZA6i+MV0nDpqDgxHRRXD8O2arauPhEI6iVT9f05AtzElrs7U95HbpQUuP1sxkaQw+bLdMOQHHMVCgMgw2g0eDdVDAMJD7wjZ+Bs6kDc/EJELb0l1uy2GEnOZMiHkK4K1r4IyZ/ed6QpyIRKfBCNyT5IIpMoVpzRYxVXgjgFdudd8iErKyvSXHThU6nu92c+vSd+FLBFHPpb6ECAwEAATANBgkqhkiG9w0BAQwFAAOCAYEAdga5NYtV48uDCd4vIsmpGWpKuUHtDVDlCvzHc2ij8DxAR6OFp+hIRNEBXkzg1KS5qP8Wba5ptmGoV4f89HemP+AL3Azde+HjpYRtffdfTdQwmMbw7xJg2lKkEo11gDo5+zPZnVbfb3FsZ+IXKji0QshQBfg+ddTkFG3TJG1ttq3ZDw94RxFQivVnkj1p+Ogel/DuBNRWQobFVe5VrmJqbuwwN8AdrPae1dMrkZatF91On5+cpVLGfk96fYUhDohDt6KKQ6DdhvFk5rhd0vsHGMQq2gAW2+Or6ZVsKkHKx8CPINpJVKAdpE0tItI+loMO02jf9oRI/8cThWP1vNAeWnr0D6m275EZf/4qem/DdJ0FJIVou3P7tsq7eSdueDnj5RmcbW/vOBtvlXpD3SqsVRn6sltZ0sk24p+6ZMzopevCZEMf/nL3OzGvSadisXb39H9DgwkNLlefju1QLgHWf0TGfeNHluDgVDhU8+/1/KUGtr2SnZ5EVO1l59FWHALj", "gossipEndpoint": [{ "ipAddressV4": "IjeWHA==", "port": 30124 }, { "ipAddressV4": "CoAAFw==", "port": 30124 }] }, { "nodeId": "1", "weight": "16000000000", "gossipCaCertificate": "MIIDpjCCAg6gAwIBAgIIHWg7e2Q/smQwDQYJKoZIhvcNAQEMBQAwEjEQMA4GA1UEAxMHcy1ub2RlMjAgFw0wMDAxMDEwMDAwMDBaGA8yMTAwMDEwMTAwMDAwMFowEjEQMA4GA1UEAxMHcy1ub2RlMjCCAaIwDQYJKoZIhvcNAQEBBQADggGPADCCAYoCggGBAKr5WsBepS3+y/0/yfBjzMWje7zianEz7sszrNWV3cGu2KUlR7v2+9wp/EtX1+BdcGlTTojgFs5nEBN4lM76Cp6JjFH461yN8GSkIkpe8GZnb1w4KEjZj5UYMbq+qOUI6QmwmgLeO8RHAsS6lCP1AyGFalb2ZVJ09DcYDxCRXeFj4BqvNbtD5r5DTCtpVT4ax3eb3pzNSGsjQUG9zhyp/WcsAmwmzKdMl72tk6qF8tlAWXyzwiCujWHS0Kln0C5pyEjeFNsG299toC4pgT8juxijgseTeIFRnNHmGSeSmXpAkEELlwLKR8HOnqeiS5UXNqdbxNemx/EpJSc5rTB6kzLX24dIuRsgyIIFWx73goOzmaHUolN4xmenifoMYlSNNM07WrsvmjRC5OLc/uGhdWqhZGBCH6AJB8Cmw84QLXVdHE6LiueP1oMd7g++N4X880wJkuh0ebfV3i7etUIn0jLlM50AkRucG9kwZDJ/M4LY7FT2F85R1/o2FaB/537ARQIDAQABMA0GCSqGSIb3DQEBDAUAA4IBgQB5lTkqYw0hEW+BJTFsQ8jEHfIDNRJ0kNbVuibfP+u7kzlJy15lCEi+Qw6E3d8hA1QBX3xJMxNBlrtYPrdG26hh/tOwo5Np/OfxQC5jo0Q7n7hu7aLxZRUB/q7AfdDbOun4Za6rJhT3+EsFocyARWp8bYSk3YILBMkP+2VYDRkgQidzKgKtO5yv21Y9sEgziSprc+dQb/tqn5aQZLWavFwCLwnB3t4r4qwLHkkH00Jw51uOvLeM49/t333V5Caa7wmWzMcE+KSWW0QWFRxeJrodSyjPdmDi4D8lKN5WJHSAU5L2yWIODUyWD/cvsAapTv7xXk9ja/Ssb9DpMQnM1xh0hYaESajNeL1QbGuZgPxAwrw981h7kprR2P2iMGRVGA6u4ezxmhW3s7D+yJ3+Yxs/x2J/sw65Z16mRYXRWYWHQmhgaVQjIviiAkVB6CWZo1kHl/eYaVedQzKlrTpbr3JtmwGwhYEOnrkzsC63h8/AG9gRtIAIGWGqTPWbn2pEm8M=", "gossipEndpoint": [{ "ipAddressV4": "I+CnFQ==", "port": 30125 }, { "ipAddressV4": "CoAAHg==", "port": 30125 }] }, { "nodeId": "2", "weight": "15875000000", "gossipCaCertificate": "MIIDpzCCAg+gAwIBAgIJAJg3GRFp5bT9MA0GCSqGSIb3DQEBDAUAMBIxEDAOBgNVBAMTB3Mtbm9kZTMwIBcNMDAwMTAxMDAwMDAwWhgPMjEwMDAxMDEwMDAwMDBaMBIxEDAOBgNVBAMTB3Mtbm9kZTMwggGiMA0GCSqGSIb3DQEBAQUAA4IBjwAwggGKAoIBgQCl5ut2dCleDmgEneRYpAKa9Pe2qnXzgF+BEIuTfizG2OcPQi/ltv+6HxSrJXtuWNaiX/G4iP7iBzWj2ysaAYwfYj0ezTSMLRqM9hXzVgLtW0LJEF6a8vUXPsJt4GEJkUKiYCCO1MP1NLd3y/3SVJrFhwJSPqKYm2pQNg84WfPDWSkzSneOIO4Z0uWDXgs+vzSNyChWOxVieFQhLjcELtyj6narmLox+Jdo/SxUzPuktuFB3ebNgUqWPkjljgZpl00BTmbRIVHgHfDVulo2PBpXd0VplIDgdPr5zMKdTrKCuDKey8Mft72RkPKMe9LZVZ/21+rXVEh+olvvUCySsP2RkWPUJJD90c8wKo01rZsjAOXscJKQcBYlam5XXO4ZBRYzEdxuivbkPwsOoQ83swCR3alPvwfbg11Va+zXE6sRbUM9LqkYo/M3Hwg8tSIXu8oah6csputanz867dzWwyVJEPzmiXZ6ncVDQO31QlB7RndWCqKTjOQpnpblUMsrE9MCAwEAATANBgkqhkiG9w0BAQwFAAOCAYEAnUA8+kz7L+eSOm/iVvUNYF10PKO2nZtxWWL7R1vwK/2Up765PwqxKb0eSEM4bjgvZq1GuGXs9X/Y7dos42yntXvgeUY+/2JzCnw4J5tzxytZ+IKX6DR67NjDzDzVZQfptjLQrb8E7yzml0uxsqrhNPWl57Bmfe66Kg2lD11jImeeEhExlRggFukoiUWVwRNU21Q1jMUWrg2ZwfP+6fFTgRt0WR+X5zkyYPbvI6/yv7reYGjPDuZTOFhbwG8LUTQxdttDswPjnQ606kMyninL+aNelSdV/UIII7lpr/dTvgQAnrlBaGXvdy6brh3wWEwia0FZFZcKEs6M+jZ3MrFxvlTfUIdI3jRq12L10cCDi2VhORg4JmvlM+Tk6kJeSku30ZLAVo3S7GbTdvkuesOxz3UwnF7yfOA1KYOPvhv1oLxGV5z05glsn1OBKnXMdzsKFbAYYHj81bgBni2WLuIpv3oXlai2uc4y9m8LvWAQ+h/ivyog34Ai3Pvr5ZZOFgjy", "gossipEndpoint": [{ "ipAddressV4": "aJq9lg==", "port": 30126 }, { "ipAddressV4": "CoAAHw==", "port": 30126 }] }, { "nodeId": "3", "weight": "15000000000", "gossipCaCertificate": "MIIDpzCCAg+gAwIBAgIJAN7hww13zBZEMA0GCSqGSIb3DQEBDAUAMBIxEDAOBgNVBAMTB3Mtbm9kZTQwIBcNMDAwMTAxMDAwMDAwWhgPMjEwMDAxMDEwMDAwMDBaMBIxEDAOBgNVBAMTB3Mtbm9kZTQwggGiMA0GCSqGSIb3DQEBAQUAA4IBjwAwggGKAoIBgQDK/bVyv0ZUeJZ4cIOImM+wmqtYjCw4jPAC549WQPPV1vG0lzSpgV+nRKqmWBexhLlKN3bsvrfNCUpKSq8meFyCtdppT1dhUOmEZcoNhLZzqxXb2HYYqRPv82tR+tbh+27WFsBOOqYrYTvr72ECD7qDOuw/Xob6KImaw/b/SIAPecMoYy25fkgYkJSETwd8HUpwssYH/JTLBF8eGjjTTMuu14ARQKeH8BXSs+jjV1+3IItXERS8ryUGDjqc5vC8ZW1kDVQbb91IDxRjqZbFyhuasocCqTAcZuiEgE8Wilwp2g1vbAUnHnvKNfiaEAHoEV6vF4lelaWhOnN2U5tnox/ns6PiDqIbOfs0pmXxjAK0vxc6oZM3TwdRtzo6cSb/AYfQdnmQzkra980kHN12r3f7PK2PzGBuVUPT7fLGA4S3vQDYO4rqcgTc/OLobtqLtdBusOFjZscfIfUW4GVWJUI1j+fwvHacxWLmyZwlQ5Q47UtrtjWpFru7CTn5S477lqMCAwEAATANBgkqhkiG9w0BAQwFAAOCAYEAdW6AWDhT0eOJw+0O6MYngmCgkXfFsgBC/B1plaE596hHo58FHxzCNiLFdvfRj37rxujvqsDAkADWUmOzLzLHYMXu302HzDqAMNY6FZJc32y4ZDsIQpaUOAuiNHAHwFXuPRInVpCqztfJMgw4RhOhcCTEsoIJsqoIN1t4M0pEVAv6x3nJwFKZqSNOZrQ7sOW32FjwWS3kHwRsCTtqdk5n2KxU6wr/fggV3QsSPRMYro8sUfwu93mqggtswwWqfeKlsz5WiaR9aqLnb8z1R6HLvA0bcoPWzjgn8RdP+9we4z06iZ5vdBuNpwBjrCKUELWISyAoekLGGxyS8pPqYiSBRNUoaPITSuUjcCBbJ9EFvm72QgCBesbwF71KPabTPbMPhLmf+uAi+zmeu8ZeVvT6DrX9OHSkIvIEQFry9BrqOT3ce6KBHSO1HpXIetj5Wcd3WHXtz9ulBL9ikWC8eh7/+we51ucmLvFzNKznElhT2Dp+czXUVNEUjp3u/66pyRA4", "gossipEndpoint": [{ "ipAddressV4": "IkPM6Q==", "port": 30127 }, { "ipAddressV4": "CoAAIA==", "port": 30127 }] }] }
node1 5.865s 2025-09-25 10:57:59.674 106 INFO STARTUP <main> PlatformTestingToolState: Trigger and PreviousSoftwareVersion state received in init function {"trigger":"GENESIS","previousSoftwareVersion":"null"} [com.swirlds.logging.legacy.payload.SoftwareVersionPayload]
node1 5.866s 2025-09-25 10:57:59.675 107 INFO STARTUP <main> PlatformTestingToolState: Set QuorumResult from genesisInit()
node1 5.868s 2025-09-25 10:57:59.677 115 INFO STARTUP <main> MerkleStateRoot: Putting states...
node1 5.869s 2025-09-25 10:57:59.678 116 INFO STARTUP <main> MerkleStateRoot: Put states! Service name: PlatformStateService
node1 5.869s 2025-09-25 10:57:59.678 117 INFO STARTUP <main> MerkleStateRoot: Getting child.. Service name: PlatformStateService / Number of children: 13 / node: 0
node1 5.872s 2025-09-25 10:57:59.681 125 INFO STARTUP <main> MerkleStateRoot: Putting states...
node1 5.873s 2025-09-25 10:57:59.682 126 INFO STARTUP <main> MerkleStateRoot: Put states! Service name: RosterService
node1 5.873s 2025-09-25 10:57:59.682 127 INFO STARTUP <main> MerkleStateRoot: Getting child.. Service name: RosterService / Number of children: 13 / node: 1
node1 5.876s 2025-09-25 10:57:59.685 135 INFO STARTUP <main> MerkleStateRoot: Putting states...
node1 5.876s 2025-09-25 10:57:59.685 136 INFO STARTUP <main> MerkleStateRoot: Put states! Service name: RosterService
node1 5.876s 2025-09-25 10:57:59.685 137 INFO STARTUP <main> MerkleStateRoot: Getting child.. Service name: RosterService / Number of children: 13 / node: 2
node2 5.879s 2025-09-25 10:57:59.688 79 INFO STARTUP <main> MerkleStateRoot: Putting states...
node2 5.879s 2025-09-25 10:57:59.688 80 INFO STARTUP <main> MerkleStateRoot: Put states! Service name: RosterService
node1 5.891s 2025-09-25 10:57:59.700 138 INFO STARTUP <main> StateInitializer: The platform is using the following initial state:
Round: 0 Timestamp: 1970-01-01T00:00:00Z Next consensus number: 0 Legacy running event hash: null Legacy running event mnemonic: null Rounds non-ancient: 0 Creation version: SemanticVersion[major=1, minor=0, patch=0, pre=, build=] Minimum judge hash code: 1 Root hash: 5ee490a06e5e87a9e8eac8f767342769271033e4d04adadf530230b68820098ec32e7f8bc6aadcde3a9e22df179587ef First BR Version: SemanticVersion[major=0, minor=0, patch=0, pre=, build=] Last round before BR: 0 Lowest Judge Gen before BR 0 (root) PlatformTestingToolState / symptom-ugly-slim-april 0 SingletonNode PlatformStateService.PLATFORM_STATE /0 dwarf-staff-copper-this 1 VirtualMap RosterService.ROSTERS /1 step-utility-truth-odor 2 SingletonNode RosterService.ROSTER_STATE /2 anger-shell-eyebrow-boring 3 PayloadCfgSimple /3 potato-pill-swear-author 4 null /4 bid-belt-culture-decorate 5 null /5 bid-belt-culture-decorate 6 null /6 bid-belt-culture-decorate 7 IssLeaf /7 biology-capable-expose-purpose 8 null /8 bid-belt-culture-decorate 9 null /9 bid-belt-culture-decorate 10 null /10 bid-belt-culture-decorate 11 null /11 bid-belt-culture-decorate 12 QuorumResult /12 globe-math-mass-loop
node2 5.934s 2025-09-25 10:57:59.743 86 INFO STARTUP <main> MerkleStateRoot: Setting child.. Service name: RosterService / Number of children: 1 / node: com.swirlds.virtualmap.VirtualMap@433d9680
node2 5.939s 2025-09-25 10:57:59.748 94 INFO STARTUP <main> MerkleStateRoot: Putting states...
node2 5.939s 2025-09-25 10:57:59.748 95 INFO STARTUP <main> MerkleStateRoot: Put states! Service name: RosterService
node2 5.940s 2025-09-25 10:57:59.749 96 INFO STARTUP <main> MerkleStateRoot: Setting child.. Service name: RosterService / Number of children: 2 / node: com.swirlds.state.merkle.singleton.SingletonNode@2da81754
node2 5.986s 2025-09-25 10:57:59.795 99 INFO STARTUP <main> BootstrapUtils: Not upgrading software, current software is version SemanticVersion[major=1, minor=0, patch=0, pre=, build=].
node2 5.992s 2025-09-25 10:57:59.801 100 INFO STARTUP <main> AddressBookInitializer: Starting from genesis: using the config address book.
node0 6.105s 2025-09-25 10:57:59.914 79 INFO STARTUP <main> MerkleStateRoot: Putting states...
node0 6.105s 2025-09-25 10:57:59.914 80 INFO STARTUP <main> MerkleStateRoot: Put states! Service name: RosterService
node1 6.113s 2025-09-25 10:57:59.922 140 INFO EVENT_STREAM <main> DefaultConsensusEventStream: EventStreamManager::updateRunningHash: 38b060a751ac96384cd9327eb1b1e36a21fdb71114be07434c0cc7bf63f6e1da274edebfe76f65fbd51ad2f14898b95b
node1 6.117s 2025-09-25 10:57:59.926 141 INFO STARTUP <platformForkJoinThread-2> Shadowgraph: Shadowgraph starting from expiration threshold 1
node1 6.126s 2025-09-25 10:57:59.935 142 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: -Xms32g
node1 6.126s 2025-09-25 10:57:59.935 143 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: -Xmx118g
node1 6.126s 2025-09-25 10:57:59.935 144 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: -XX:+UnlockExperimentalVMOptions
node1 6.127s 2025-09-25 10:57:59.936 145 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: -XX:+UseZGC
node1 6.127s 2025-09-25 10:57:59.936 146 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: -XX:ZAllocationSpikeTolerance=2
node1 6.127s 2025-09-25 10:57:59.936 147 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: -XX:ConcGCThreads=14
node1 6.127s 2025-09-25 10:57:59.936 148 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: -XX:ZMarkStackSpaceLimit=16g
node1 6.128s 2025-09-25 10:57:59.937 149 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: -XX:MaxDirectMemorySize=64g
node1 6.128s 2025-09-25 10:57:59.937 150 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: -XX:MetaspaceSize=100M
node1 6.128s 2025-09-25 10:57:59.937 151 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: -XX:+ZGenerational
node1 6.128s 2025-09-25 10:57:59.937 152 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: -Xlog:gc*:gc.log
node1 6.128s 2025-09-25 10:57:59.937 153 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: --add-opens=java.base/jdk.internal.misc=ALL-UNNAMED
node1 6.129s 2025-09-25 10:57:59.938 154 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: --add-opens=java.base/java.nio=ALL-UNNAMED
node1 6.129s 2025-09-25 10:57:59.938 155 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: -Dio.netty.tryReflectionSetAccessible=true
node1 6.129s 2025-09-25 10:57:59.938 156 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: Parsing JSON FCM-VM-NIReconnect-125-10m.json
node1 6.146s 2025-09-25 10:57:59.955 157 INFO DEMO_INFO <<start-node-1>> PayloadConfig: insertSeq = true
node1 6.147s 2025-09-25 10:57:59.956 158 INFO DEMO_INFO <<start-node-1>> PayloadConfig: appendSig = true
node1 6.147s 2025-09-25 10:57:59.956 159 INFO DEMO_INFO <<start-node-1>> PayloadConfig: variedSize = false
node1 6.148s 2025-09-25 10:57:59.957 160 INFO DEMO_INFO <<start-node-1>> PayloadConfig: payloadByteSize = 100
node1 6.148s 2025-09-25 10:57:59.957 161 INFO DEMO_INFO <<start-node-1>> PayloadConfig: maxByteSize = 100
node1 6.148s 2025-09-25 10:57:59.957 162 INFO DEMO_INFO <<start-node-1>> PayloadConfig: type = TYPE_FCM_VIRTUAL_MIX
node1 6.149s 2025-09-25 10:57:59.958 163 INFO DEMO_INFO <<start-node-1>> PayloadConfig: invalidSigRatio = 0.0
node1 6.150s 2025-09-25 10:57:59.959 164 INFO DEMO_INFO <<start-node-1>> TransactionSubmitter: Goal is TRANS_PER_SECOND_PER_NODE targe 125.0
node1 6.155s 2025-09-25 10:57:59.964 165 INFO DEMO_INFO <<start-node-1>> VirtualMerkleStateInitializer: State = com.swirlds.demo.platform.PlatformTestingToolState@51eeca9
node1 6.156s 2025-09-25 10:57:59.965 166 INFO DEMO_INFO <<start-node-1>> VirtualMerkleStateInitializer: total accounts = 2400
node1 6.156s 2025-09-25 10:57:59.965 167 INFO DEMO_INFO <<start-node-1>> VirtualMerkleStateInitializer: Creating virtualmap for 2400 accounts.
node0 6.161s 2025-09-25 10:57:59.970 86 INFO STARTUP <main> MerkleStateRoot: Setting child.. Service name: RosterService / Number of children: 1 / node: com.swirlds.virtualmap.VirtualMap@433d9680
node1 6.163s 2025-09-25 10:57:59.972 172 INFO DEMO_INFO <<start-node-1>> VirtualMerkleStateInitializer: accounts VM = com.swirlds.virtualmap.VirtualMap@5a9098c9, DS = MerkleDbDataSource[initialCapacity=1000000,preferDiskBasedIndexes=false,pathToDiskLocationInternalNodes.size=0,pathToDiskLocationLeafNodes.size=0,hashesRamToDiskThreshold=8388608,hashStoreRam.size=0,hashStoreDisk=com.swirlds.merkledb.files.MemoryIndexDiskKeyValueStore@66288041,hasDiskStoreForHashes=true,keyToPath=com.swirlds.merkledb.files.hashmap.HalfDiskHashMap@4b39ed73,pathToKeyValue=com.swirlds.merkledb.files.MemoryIndexDiskKeyValueStore@609760a7,snapshotInProgress=false]
node0 6.166s 2025-09-25 10:57:59.975 94 INFO STARTUP <main> MerkleStateRoot: Putting states...
node0 6.167s 2025-09-25 10:57:59.976 95 INFO STARTUP <main> MerkleStateRoot: Put states! Service name: RosterService
node0 6.167s 2025-09-25 10:57:59.976 96 INFO STARTUP <main> MerkleStateRoot: Setting child.. Service name: RosterService / Number of children: 2 / node: com.swirlds.state.merkle.singleton.SingletonNode@2da81754
node1 6.170s 2025-09-25 10:57:59.979 173 INFO DEMO_INFO <<start-node-1>> VirtualMerkleStateInitializer: max KV pairs = 0
node1 6.170s 2025-09-25 10:57:59.979 174 INFO DEMO_INFO <<start-node-1>> VirtualMerkleStateInitializer: total SC = 0
node1 6.175s 2025-09-25 10:57:59.984 175 INFO DEMO_INFO <<start-node-1>> FCMTransactionPool: Random seed for FCMTransactionPool is -3899056746525492366
node1 6.175s 2025-09-25 10:57:59.984 176 INFO DEMO_INFO <<start-node-1>> FCMTransactionPool: This test will generate sequential FCM payload:
node0 6.217s 2025-09-25 10:58:00.026 99 INFO STARTUP <main> BootstrapUtils: Not upgrading software, current software is version SemanticVersion[major=1, minor=0, patch=0, pre=, build=].
node0 6.223s 2025-09-25 10:58:00.032 100 INFO STARTUP <main> AddressBookInitializer: Starting from genesis: using the config address book.
node1 6.251s 2025-09-25 10:58:00.060 177 INFO STARTUP <<start-node-1>> SwirldsPlatform: Starting platform 1
node1 6.254s 2025-09-25 10:58:00.063 178 INFO STARTUP <<platform: recycle-bin-cleanup>> RecycleBinImpl: Deleted 0 files from the recycle bin.
node1 6.257s 2025-09-25 10:58:00.066 179 INFO STARTUP <<start-node-1>> CycleFinder: No cyclical back pressure detected in wiring model.
node1 6.258s 2025-09-25 10:58:00.067 180 INFO STARTUP <<start-node-1>> DirectSchedulerChecks: No illegal direct scheduler use detected in the wiring model.
node1 6.259s 2025-09-25 10:58:00.068 181 INFO STARTUP <<start-node-1>> InputWireChecks: All input wires have been bound.
node1 6.260s 2025-09-25 10:58:00.069 182 WARN STARTUP <<start-node-1>> PcesFileTracker: No preconsensus event files available
node1 6.261s 2025-09-25 10:58:00.070 183 INFO STARTUP <<start-node-1>> SwirldsPlatform: replaying preconsensus event stream starting at 0
node1 6.262s 2025-09-25 10:58:00.071 184 INFO STARTUP <<start-node-1>> PcesReplayer: Replayed 0 preconsensus events. These events contained 0 transactions. 0 rounds reached consensus spanning 0.0 nanoseconds of consensus time. The latest round to reach consensus is round 0. Replay took 0.0 nanoseconds.
node1 6.263s 2025-09-25 10:58:00.072 185 INFO DEMO_INFO <<app: appMain 1>> PlatformTestingToolMain: Node 1 starts transactions ........
node1 6.263s 2025-09-25 10:58:00.072 186 INFO PLATFORM_STATUS <platformForkJoinThread-5> DefaultStatusStateMachine: Platform spent 312.0 ms in STARTING_UP. Now in REPLAYING_EVENTS
node1 6.267s 2025-09-25 10:58:00.076 187 INFO STARTUP <platformForkJoinThread-5> PlatformTestingToolMain: Platform Status Change REPLAYING_EVENTS
node1 6.267s 2025-09-25 10:58:00.076 188 INFO PLATFORM_STATUS <platformForkJoinThread-5> DefaultStatusStateMachine: Platform spent 3.0 ms in REPLAYING_EVENTS. Now in OBSERVING
node1 6.268s 2025-09-25 10:58:00.077 189 INFO STARTUP <platformForkJoinThread-5> PlatformTestingToolMain: Platform Status Change OBSERVING
node3 6.317s 2025-09-25 10:58:00.126 79 INFO STARTUP <main> MerkleStateRoot: Putting states...
node3 6.319s 2025-09-25 10:58:00.128 80 INFO STARTUP <main> MerkleStateRoot: Put states! Service name: RosterService
node3 6.381s 2025-09-25 10:58:00.190 86 INFO STARTUP <main> MerkleStateRoot: Setting child.. Service name: RosterService / Number of children: 1 / node: com.swirlds.virtualmap.VirtualMap@433d9680
node3 6.387s 2025-09-25 10:58:00.196 94 INFO STARTUP <main> MerkleStateRoot: Putting states...
node3 6.387s 2025-09-25 10:58:00.196 95 INFO STARTUP <main> MerkleStateRoot: Put states! Service name: RosterService
node3 6.388s 2025-09-25 10:58:00.197 96 INFO STARTUP <main> MerkleStateRoot: Setting child.. Service name: RosterService / Number of children: 2 / node: com.swirlds.state.merkle.singleton.SingletonNode@2da81754
node3 6.437s 2025-09-25 10:58:00.246 99 INFO STARTUP <main> BootstrapUtils: Not upgrading software, current software is version SemanticVersion[major=1, minor=0, patch=0, pre=, build=].
node3 6.443s 2025-09-25 10:58:00.252 100 INFO STARTUP <main> AddressBookInitializer: Starting from genesis: using the config address book.
node2 7.117s 2025-09-25 10:58:00.926 101 INFO STARTUP <main> OSHealthChecker:
PASSED - Clock Source Speed Check Report[callsPerSec=20851232] PASSED - Entropy Check Report[success=true, entropySource=Strong Instance, elapsedNanos=328900, randomLong=5876443626759015556, exception=null] PASSED - Entropy Check Report[success=true, entropySource=NativePRNGBlocking:SUN, elapsedNanos=22370, randomLong=7330545247774849059, exception=null] PASSED - File System Check Report[code=SUCCESS, readNanos=1493940, data=35, exception=null] OS Health Check Report - Complete (took 1023 ms)
node2 7.150s 2025-09-25 10:58:00.959 102 DEBUG STARTUP <main> BootstrapUtils: jvmPauseDetectorThread started
node2 7.159s 2025-09-25 10:58:00.968 103 INFO STARTUP <main> StandardScratchpad: Scratchpad platform.iss contents:
LAST_ISS_ROUND null
node2 7.165s 2025-09-25 10:58:00.974 104 INFO STARTUP <main> PlatformBuilder: Default platform pool parallelism: 8
node2 7.256s 2025-09-25 10:58:01.065 105 INFO STARTUP <main> SwirldsPlatform: Starting with roster history:
RosterHistory[ currentRosterRound: 0 ][ no previous roster set ] Current Roster: { "rosterEntries": [{ "weight": "3125000000", "gossipCaCertificate": "MIIDpzCCAg+gAwIBAgIJAK05TS8KZeb1MA0GCSqGSIb3DQEBDAUAMBIxEDAOBgNVBAMTB3Mtbm9kZTEwIBcNMDAwMTAxMDAwMDAwWhgPMjEwMDAxMDEwMDAwMDBaMBIxEDAOBgNVBAMTB3Mtbm9kZTEwggGiMA0GCSqGSIb3DQEBAQUAA4IBjwAwggGKAoIBgQDBoP9dI3K1PRLRK7h90D9eNCfgzuHTyJi70yDEs90XJXlE6jmgf1NE2av83VAhQHLxu8Ehc/55M9Ayx9IQc0zJLSS+IrRM9QwqoG8ZvNdRgNw+je3V/8rAK/mHId+cPnnyDplCyskyi5kWCv6kTULIewFH8/KVZwhe0/hB2+N6ujWixURrxjjGLHA6b2gPoGAb/nxiVOn+L0cWcOzcyiYShxagj0FBWV7AxKx65Ynzfe7eF0gOzBUA+IM10OM5KXJejk53Xz5KpEyGe8htO/bXFlpLdm3UzrYiIhY0oKPYKECAC1s+VAZA6i+MV0nDpqDgxHRRXD8O2arauPhEI6iVT9f05AtzElrs7U95HbpQUuP1sxkaQw+bLdMOQHHMVCgMgw2g0eDdVDAMJD7wjZ+Bs6kDc/EJELb0l1uy2GEnOZMiHkK4K1r4IyZ/ed6QpyIRKfBCNyT5IIpMoVpzRYxVXgjgFdudd8iErKyvSXHThU6nu92c+vSd+FLBFHPpb6ECAwEAATANBgkqhkiG9w0BAQwFAAOCAYEAdga5NYtV48uDCd4vIsmpGWpKuUHtDVDlCvzHc2ij8DxAR6OFp+hIRNEBXkzg1KS5qP8Wba5ptmGoV4f89HemP+AL3Azde+HjpYRtffdfTdQwmMbw7xJg2lKkEo11gDo5+zPZnVbfb3FsZ+IXKji0QshQBfg+ddTkFG3TJG1ttq3ZDw94RxFQivVnkj1p+Ogel/DuBNRWQobFVe5VrmJqbuwwN8AdrPae1dMrkZatF91On5+cpVLGfk96fYUhDohDt6KKQ6DdhvFk5rhd0vsHGMQq2gAW2+Or6ZVsKkHKx8CPINpJVKAdpE0tItI+loMO02jf9oRI/8cThWP1vNAeWnr0D6m275EZf/4qem/DdJ0FJIVou3P7tsq7eSdueDnj5RmcbW/vOBtvlXpD3SqsVRn6sltZ0sk24p+6ZMzopevCZEMf/nL3OzGvSadisXb39H9DgwkNLlefju1QLgHWf0TGfeNHluDgVDhU8+/1/KUGtr2SnZ5EVO1l59FWHALj", "gossipEndpoint": [{ "ipAddressV4": "IjeWHA==", "port": 30124 }, { "ipAddressV4": "CoAAFw==", "port": 30124 }] }, { "nodeId": "1", "weight": "16000000000", "gossipCaCertificate": "MIIDpjCCAg6gAwIBAgIIHWg7e2Q/smQwDQYJKoZIhvcNAQEMBQAwEjEQMA4GA1UEAxMHcy1ub2RlMjAgFw0wMDAxMDEwMDAwMDBaGA8yMTAwMDEwMTAwMDAwMFowEjEQMA4GA1UEAxMHcy1ub2RlMjCCAaIwDQYJKoZIhvcNAQEBBQADggGPADCCAYoCggGBAKr5WsBepS3+y/0/yfBjzMWje7zianEz7sszrNWV3cGu2KUlR7v2+9wp/EtX1+BdcGlTTojgFs5nEBN4lM76Cp6JjFH461yN8GSkIkpe8GZnb1w4KEjZj5UYMbq+qOUI6QmwmgLeO8RHAsS6lCP1AyGFalb2ZVJ09DcYDxCRXeFj4BqvNbtD5r5DTCtpVT4ax3eb3pzNSGsjQUG9zhyp/WcsAmwmzKdMl72tk6qF8tlAWXyzwiCujWHS0Kln0C5pyEjeFNsG299toC4pgT8juxijgseTeIFRnNHmGSeSmXpAkEELlwLKR8HOnqeiS5UXNqdbxNemx/EpJSc5rTB6kzLX24dIuRsgyIIFWx73goOzmaHUolN4xmenifoMYlSNNM07WrsvmjRC5OLc/uGhdWqhZGBCH6AJB8Cmw84QLXVdHE6LiueP1oMd7g++N4X880wJkuh0ebfV3i7etUIn0jLlM50AkRucG9kwZDJ/M4LY7FT2F85R1/o2FaB/537ARQIDAQABMA0GCSqGSIb3DQEBDAUAA4IBgQB5lTkqYw0hEW+BJTFsQ8jEHfIDNRJ0kNbVuibfP+u7kzlJy15lCEi+Qw6E3d8hA1QBX3xJMxNBlrtYPrdG26hh/tOwo5Np/OfxQC5jo0Q7n7hu7aLxZRUB/q7AfdDbOun4Za6rJhT3+EsFocyARWp8bYSk3YILBMkP+2VYDRkgQidzKgKtO5yv21Y9sEgziSprc+dQb/tqn5aQZLWavFwCLwnB3t4r4qwLHkkH00Jw51uOvLeM49/t333V5Caa7wmWzMcE+KSWW0QWFRxeJrodSyjPdmDi4D8lKN5WJHSAU5L2yWIODUyWD/cvsAapTv7xXk9ja/Ssb9DpMQnM1xh0hYaESajNeL1QbGuZgPxAwrw981h7kprR2P2iMGRVGA6u4ezxmhW3s7D+yJ3+Yxs/x2J/sw65Z16mRYXRWYWHQmhgaVQjIviiAkVB6CWZo1kHl/eYaVedQzKlrTpbr3JtmwGwhYEOnrkzsC63h8/AG9gRtIAIGWGqTPWbn2pEm8M=", "gossipEndpoint": [{ "ipAddressV4": "I+CnFQ==", "port": 30125 }, { "ipAddressV4": "CoAAHg==", "port": 30125 }] }, { "nodeId": "2", "weight": "15875000000", "gossipCaCertificate": "MIIDpzCCAg+gAwIBAgIJAJg3GRFp5bT9MA0GCSqGSIb3DQEBDAUAMBIxEDAOBgNVBAMTB3Mtbm9kZTMwIBcNMDAwMTAxMDAwMDAwWhgPMjEwMDAxMDEwMDAwMDBaMBIxEDAOBgNVBAMTB3Mtbm9kZTMwggGiMA0GCSqGSIb3DQEBAQUAA4IBjwAwggGKAoIBgQCl5ut2dCleDmgEneRYpAKa9Pe2qnXzgF+BEIuTfizG2OcPQi/ltv+6HxSrJXtuWNaiX/G4iP7iBzWj2ysaAYwfYj0ezTSMLRqM9hXzVgLtW0LJEF6a8vUXPsJt4GEJkUKiYCCO1MP1NLd3y/3SVJrFhwJSPqKYm2pQNg84WfPDWSkzSneOIO4Z0uWDXgs+vzSNyChWOxVieFQhLjcELtyj6narmLox+Jdo/SxUzPuktuFB3ebNgUqWPkjljgZpl00BTmbRIVHgHfDVulo2PBpXd0VplIDgdPr5zMKdTrKCuDKey8Mft72RkPKMe9LZVZ/21+rXVEh+olvvUCySsP2RkWPUJJD90c8wKo01rZsjAOXscJKQcBYlam5XXO4ZBRYzEdxuivbkPwsOoQ83swCR3alPvwfbg11Va+zXE6sRbUM9LqkYo/M3Hwg8tSIXu8oah6csputanz867dzWwyVJEPzmiXZ6ncVDQO31QlB7RndWCqKTjOQpnpblUMsrE9MCAwEAATANBgkqhkiG9w0BAQwFAAOCAYEAnUA8+kz7L+eSOm/iVvUNYF10PKO2nZtxWWL7R1vwK/2Up765PwqxKb0eSEM4bjgvZq1GuGXs9X/Y7dos42yntXvgeUY+/2JzCnw4J5tzxytZ+IKX6DR67NjDzDzVZQfptjLQrb8E7yzml0uxsqrhNPWl57Bmfe66Kg2lD11jImeeEhExlRggFukoiUWVwRNU21Q1jMUWrg2ZwfP+6fFTgRt0WR+X5zkyYPbvI6/yv7reYGjPDuZTOFhbwG8LUTQxdttDswPjnQ606kMyninL+aNelSdV/UIII7lpr/dTvgQAnrlBaGXvdy6brh3wWEwia0FZFZcKEs6M+jZ3MrFxvlTfUIdI3jRq12L10cCDi2VhORg4JmvlM+Tk6kJeSku30ZLAVo3S7GbTdvkuesOxz3UwnF7yfOA1KYOPvhv1oLxGV5z05glsn1OBKnXMdzsKFbAYYHj81bgBni2WLuIpv3oXlai2uc4y9m8LvWAQ+h/ivyog34Ai3Pvr5ZZOFgjy", "gossipEndpoint": [{ "ipAddressV4": "aJq9lg==", "port": 30126 }, { "ipAddressV4": "CoAAHw==", "port": 30126 }] }, { "nodeId": "3", "weight": "15000000000", "gossipCaCertificate": "MIIDpzCCAg+gAwIBAgIJAN7hww13zBZEMA0GCSqGSIb3DQEBDAUAMBIxEDAOBgNVBAMTB3Mtbm9kZTQwIBcNMDAwMTAxMDAwMDAwWhgPMjEwMDAxMDEwMDAwMDBaMBIxEDAOBgNVBAMTB3Mtbm9kZTQwggGiMA0GCSqGSIb3DQEBAQUAA4IBjwAwggGKAoIBgQDK/bVyv0ZUeJZ4cIOImM+wmqtYjCw4jPAC549WQPPV1vG0lzSpgV+nRKqmWBexhLlKN3bsvrfNCUpKSq8meFyCtdppT1dhUOmEZcoNhLZzqxXb2HYYqRPv82tR+tbh+27WFsBOOqYrYTvr72ECD7qDOuw/Xob6KImaw/b/SIAPecMoYy25fkgYkJSETwd8HUpwssYH/JTLBF8eGjjTTMuu14ARQKeH8BXSs+jjV1+3IItXERS8ryUGDjqc5vC8ZW1kDVQbb91IDxRjqZbFyhuasocCqTAcZuiEgE8Wilwp2g1vbAUnHnvKNfiaEAHoEV6vF4lelaWhOnN2U5tnox/ns6PiDqIbOfs0pmXxjAK0vxc6oZM3TwdRtzo6cSb/AYfQdnmQzkra980kHN12r3f7PK2PzGBuVUPT7fLGA4S3vQDYO4rqcgTc/OLobtqLtdBusOFjZscfIfUW4GVWJUI1j+fwvHacxWLmyZwlQ5Q47UtrtjWpFru7CTn5S477lqMCAwEAATANBgkqhkiG9w0BAQwFAAOCAYEAdW6AWDhT0eOJw+0O6MYngmCgkXfFsgBC/B1plaE596hHo58FHxzCNiLFdvfRj37rxujvqsDAkADWUmOzLzLHYMXu302HzDqAMNY6FZJc32y4ZDsIQpaUOAuiNHAHwFXuPRInVpCqztfJMgw4RhOhcCTEsoIJsqoIN1t4M0pEVAv6x3nJwFKZqSNOZrQ7sOW32FjwWS3kHwRsCTtqdk5n2KxU6wr/fggV3QsSPRMYro8sUfwu93mqggtswwWqfeKlsz5WiaR9aqLnb8z1R6HLvA0bcoPWzjgn8RdP+9we4z06iZ5vdBuNpwBjrCKUELWISyAoekLGGxyS8pPqYiSBRNUoaPITSuUjcCBbJ9EFvm72QgCBesbwF71KPabTPbMPhLmf+uAi+zmeu8ZeVvT6DrX9OHSkIvIEQFry9BrqOT3ce6KBHSO1HpXIetj5Wcd3WHXtz9ulBL9ikWC8eh7/+we51ucmLvFzNKznElhT2Dp+czXUVNEUjp3u/66pyRA4", "gossipEndpoint": [{ "ipAddressV4": "IkPM6Q==", "port": 30127 }, { "ipAddressV4": "CoAAIA==", "port": 30127 }] }] }
node0 7.345s 2025-09-25 10:58:01.154 101 INFO STARTUP <main> OSHealthChecker:
PASSED - Clock Source Speed Check Report[callsPerSec=20828961] PASSED - Entropy Check Report[success=true, entropySource=Strong Instance, elapsedNanos=301360, randomLong=-4938050240299981908, exception=null] PASSED - Entropy Check Report[success=true, entropySource=NativePRNGBlocking:SUN, elapsedNanos=13250, randomLong=2828242766784887942, exception=null] PASSED - File System Check Report[code=SUCCESS, readNanos=1520091, data=35, exception=null] OS Health Check Report - Complete (took 1020 ms)
node2 7.363s 2025-09-25 10:58:01.172 106 INFO STARTUP <main> PlatformTestingToolState: Trigger and PreviousSoftwareVersion state received in init function {"trigger":"GENESIS","previousSoftwareVersion":"null"} [com.swirlds.logging.legacy.payload.SoftwareVersionPayload]
node2 7.364s 2025-09-25 10:58:01.173 107 INFO STARTUP <main> PlatformTestingToolState: Set QuorumResult from genesisInit()
node2 7.367s 2025-09-25 10:58:01.176 115 INFO STARTUP <main> MerkleStateRoot: Putting states...
node2 7.367s 2025-09-25 10:58:01.176 116 INFO STARTUP <main> MerkleStateRoot: Put states! Service name: PlatformStateService
node2 7.367s 2025-09-25 10:58:01.176 117 INFO STARTUP <main> MerkleStateRoot: Getting child.. Service name: PlatformStateService / Number of children: 13 / node: 0
node2 7.371s 2025-09-25 10:58:01.180 125 INFO STARTUP <main> MerkleStateRoot: Putting states...
node2 7.371s 2025-09-25 10:58:01.180 126 INFO STARTUP <main> MerkleStateRoot: Put states! Service name: RosterService
node2 7.371s 2025-09-25 10:58:01.180 127 INFO STARTUP <main> MerkleStateRoot: Getting child.. Service name: RosterService / Number of children: 13 / node: 1
node2 7.373s 2025-09-25 10:58:01.182 135 INFO STARTUP <main> MerkleStateRoot: Putting states...
node2 7.374s 2025-09-25 10:58:01.183 136 INFO STARTUP <main> MerkleStateRoot: Put states! Service name: RosterService
node2 7.374s 2025-09-25 10:58:01.183 137 INFO STARTUP <main> MerkleStateRoot: Getting child.. Service name: RosterService / Number of children: 13 / node: 2
node0 7.377s 2025-09-25 10:58:01.186 102 DEBUG STARTUP <main> BootstrapUtils: jvmPauseDetectorThread started
node0 7.386s 2025-09-25 10:58:01.195 103 INFO STARTUP <main> StandardScratchpad: Scratchpad platform.iss contents:
LAST_ISS_ROUND null
node0 7.391s 2025-09-25 10:58:01.200 104 INFO STARTUP <main> PlatformBuilder: Default platform pool parallelism: 8
node2 7.391s 2025-09-25 10:58:01.200 138 INFO STARTUP <main> StateInitializer: The platform is using the following initial state:
Round: 0 Timestamp: 1970-01-01T00:00:00Z Next consensus number: 0 Legacy running event hash: null Legacy running event mnemonic: null Rounds non-ancient: 0 Creation version: SemanticVersion[major=1, minor=0, patch=0, pre=, build=] Minimum judge hash code: 1 Root hash: 5ee490a06e5e87a9e8eac8f767342769271033e4d04adadf530230b68820098ec32e7f8bc6aadcde3a9e22df179587ef First BR Version: SemanticVersion[major=0, minor=0, patch=0, pre=, build=] Last round before BR: 0 Lowest Judge Gen before BR 0 (root) PlatformTestingToolState / symptom-ugly-slim-april 0 SingletonNode PlatformStateService.PLATFORM_STATE /0 dwarf-staff-copper-this 1 VirtualMap RosterService.ROSTERS /1 step-utility-truth-odor 2 SingletonNode RosterService.ROSTER_STATE /2 anger-shell-eyebrow-boring 3 PayloadCfgSimple /3 potato-pill-swear-author 4 null /4 bid-belt-culture-decorate 5 null /5 bid-belt-culture-decorate 6 null /6 bid-belt-culture-decorate 7 IssLeaf /7 biology-capable-expose-purpose 8 null /8 bid-belt-culture-decorate 9 null /9 bid-belt-culture-decorate 10 null /10 bid-belt-culture-decorate 11 null /11 bid-belt-culture-decorate 12 QuorumResult /12 globe-math-mass-loop
node0 7.476s 2025-09-25 10:58:01.285 105 INFO STARTUP <main> SwirldsPlatform: Starting with roster history:
RosterHistory[ currentRosterRound: 0 ][ no previous roster set ] Current Roster: { "rosterEntries": [{ "weight": "3125000000", "gossipCaCertificate": "MIIDpzCCAg+gAwIBAgIJAK05TS8KZeb1MA0GCSqGSIb3DQEBDAUAMBIxEDAOBgNVBAMTB3Mtbm9kZTEwIBcNMDAwMTAxMDAwMDAwWhgPMjEwMDAxMDEwMDAwMDBaMBIxEDAOBgNVBAMTB3Mtbm9kZTEwggGiMA0GCSqGSIb3DQEBAQUAA4IBjwAwggGKAoIBgQDBoP9dI3K1PRLRK7h90D9eNCfgzuHTyJi70yDEs90XJXlE6jmgf1NE2av83VAhQHLxu8Ehc/55M9Ayx9IQc0zJLSS+IrRM9QwqoG8ZvNdRgNw+je3V/8rAK/mHId+cPnnyDplCyskyi5kWCv6kTULIewFH8/KVZwhe0/hB2+N6ujWixURrxjjGLHA6b2gPoGAb/nxiVOn+L0cWcOzcyiYShxagj0FBWV7AxKx65Ynzfe7eF0gOzBUA+IM10OM5KXJejk53Xz5KpEyGe8htO/bXFlpLdm3UzrYiIhY0oKPYKECAC1s+VAZA6i+MV0nDpqDgxHRRXD8O2arauPhEI6iVT9f05AtzElrs7U95HbpQUuP1sxkaQw+bLdMOQHHMVCgMgw2g0eDdVDAMJD7wjZ+Bs6kDc/EJELb0l1uy2GEnOZMiHkK4K1r4IyZ/ed6QpyIRKfBCNyT5IIpMoVpzRYxVXgjgFdudd8iErKyvSXHThU6nu92c+vSd+FLBFHPpb6ECAwEAATANBgkqhkiG9w0BAQwFAAOCAYEAdga5NYtV48uDCd4vIsmpGWpKuUHtDVDlCvzHc2ij8DxAR6OFp+hIRNEBXkzg1KS5qP8Wba5ptmGoV4f89HemP+AL3Azde+HjpYRtffdfTdQwmMbw7xJg2lKkEo11gDo5+zPZnVbfb3FsZ+IXKji0QshQBfg+ddTkFG3TJG1ttq3ZDw94RxFQivVnkj1p+Ogel/DuBNRWQobFVe5VrmJqbuwwN8AdrPae1dMrkZatF91On5+cpVLGfk96fYUhDohDt6KKQ6DdhvFk5rhd0vsHGMQq2gAW2+Or6ZVsKkHKx8CPINpJVKAdpE0tItI+loMO02jf9oRI/8cThWP1vNAeWnr0D6m275EZf/4qem/DdJ0FJIVou3P7tsq7eSdueDnj5RmcbW/vOBtvlXpD3SqsVRn6sltZ0sk24p+6ZMzopevCZEMf/nL3OzGvSadisXb39H9DgwkNLlefju1QLgHWf0TGfeNHluDgVDhU8+/1/KUGtr2SnZ5EVO1l59FWHALj", "gossipEndpoint": [{ "ipAddressV4": "IjeWHA==", "port": 30124 }, { "ipAddressV4": "CoAAFw==", "port": 30124 }] }, { "nodeId": "1", "weight": "16000000000", "gossipCaCertificate": "MIIDpjCCAg6gAwIBAgIIHWg7e2Q/smQwDQYJKoZIhvcNAQEMBQAwEjEQMA4GA1UEAxMHcy1ub2RlMjAgFw0wMDAxMDEwMDAwMDBaGA8yMTAwMDEwMTAwMDAwMFowEjEQMA4GA1UEAxMHcy1ub2RlMjCCAaIwDQYJKoZIhvcNAQEBBQADggGPADCCAYoCggGBAKr5WsBepS3+y/0/yfBjzMWje7zianEz7sszrNWV3cGu2KUlR7v2+9wp/EtX1+BdcGlTTojgFs5nEBN4lM76Cp6JjFH461yN8GSkIkpe8GZnb1w4KEjZj5UYMbq+qOUI6QmwmgLeO8RHAsS6lCP1AyGFalb2ZVJ09DcYDxCRXeFj4BqvNbtD5r5DTCtpVT4ax3eb3pzNSGsjQUG9zhyp/WcsAmwmzKdMl72tk6qF8tlAWXyzwiCujWHS0Kln0C5pyEjeFNsG299toC4pgT8juxijgseTeIFRnNHmGSeSmXpAkEELlwLKR8HOnqeiS5UXNqdbxNemx/EpJSc5rTB6kzLX24dIuRsgyIIFWx73goOzmaHUolN4xmenifoMYlSNNM07WrsvmjRC5OLc/uGhdWqhZGBCH6AJB8Cmw84QLXVdHE6LiueP1oMd7g++N4X880wJkuh0ebfV3i7etUIn0jLlM50AkRucG9kwZDJ/M4LY7FT2F85R1/o2FaB/537ARQIDAQABMA0GCSqGSIb3DQEBDAUAA4IBgQB5lTkqYw0hEW+BJTFsQ8jEHfIDNRJ0kNbVuibfP+u7kzlJy15lCEi+Qw6E3d8hA1QBX3xJMxNBlrtYPrdG26hh/tOwo5Np/OfxQC5jo0Q7n7hu7aLxZRUB/q7AfdDbOun4Za6rJhT3+EsFocyARWp8bYSk3YILBMkP+2VYDRkgQidzKgKtO5yv21Y9sEgziSprc+dQb/tqn5aQZLWavFwCLwnB3t4r4qwLHkkH00Jw51uOvLeM49/t333V5Caa7wmWzMcE+KSWW0QWFRxeJrodSyjPdmDi4D8lKN5WJHSAU5L2yWIODUyWD/cvsAapTv7xXk9ja/Ssb9DpMQnM1xh0hYaESajNeL1QbGuZgPxAwrw981h7kprR2P2iMGRVGA6u4ezxmhW3s7D+yJ3+Yxs/x2J/sw65Z16mRYXRWYWHQmhgaVQjIviiAkVB6CWZo1kHl/eYaVedQzKlrTpbr3JtmwGwhYEOnrkzsC63h8/AG9gRtIAIGWGqTPWbn2pEm8M=", "gossipEndpoint": [{ "ipAddressV4": "I+CnFQ==", "port": 30125 }, { "ipAddressV4": "CoAAHg==", "port": 30125 }] }, { "nodeId": "2", "weight": "15875000000", "gossipCaCertificate": "MIIDpzCCAg+gAwIBAgIJAJg3GRFp5bT9MA0GCSqGSIb3DQEBDAUAMBIxEDAOBgNVBAMTB3Mtbm9kZTMwIBcNMDAwMTAxMDAwMDAwWhgPMjEwMDAxMDEwMDAwMDBaMBIxEDAOBgNVBAMTB3Mtbm9kZTMwggGiMA0GCSqGSIb3DQEBAQUAA4IBjwAwggGKAoIBgQCl5ut2dCleDmgEneRYpAKa9Pe2qnXzgF+BEIuTfizG2OcPQi/ltv+6HxSrJXtuWNaiX/G4iP7iBzWj2ysaAYwfYj0ezTSMLRqM9hXzVgLtW0LJEF6a8vUXPsJt4GEJkUKiYCCO1MP1NLd3y/3SVJrFhwJSPqKYm2pQNg84WfPDWSkzSneOIO4Z0uWDXgs+vzSNyChWOxVieFQhLjcELtyj6narmLox+Jdo/SxUzPuktuFB3ebNgUqWPkjljgZpl00BTmbRIVHgHfDVulo2PBpXd0VplIDgdPr5zMKdTrKCuDKey8Mft72RkPKMe9LZVZ/21+rXVEh+olvvUCySsP2RkWPUJJD90c8wKo01rZsjAOXscJKQcBYlam5XXO4ZBRYzEdxuivbkPwsOoQ83swCR3alPvwfbg11Va+zXE6sRbUM9LqkYo/M3Hwg8tSIXu8oah6csputanz867dzWwyVJEPzmiXZ6ncVDQO31QlB7RndWCqKTjOQpnpblUMsrE9MCAwEAATANBgkqhkiG9w0BAQwFAAOCAYEAnUA8+kz7L+eSOm/iVvUNYF10PKO2nZtxWWL7R1vwK/2Up765PwqxKb0eSEM4bjgvZq1GuGXs9X/Y7dos42yntXvgeUY+/2JzCnw4J5tzxytZ+IKX6DR67NjDzDzVZQfptjLQrb8E7yzml0uxsqrhNPWl57Bmfe66Kg2lD11jImeeEhExlRggFukoiUWVwRNU21Q1jMUWrg2ZwfP+6fFTgRt0WR+X5zkyYPbvI6/yv7reYGjPDuZTOFhbwG8LUTQxdttDswPjnQ606kMyninL+aNelSdV/UIII7lpr/dTvgQAnrlBaGXvdy6brh3wWEwia0FZFZcKEs6M+jZ3MrFxvlTfUIdI3jRq12L10cCDi2VhORg4JmvlM+Tk6kJeSku30ZLAVo3S7GbTdvkuesOxz3UwnF7yfOA1KYOPvhv1oLxGV5z05glsn1OBKnXMdzsKFbAYYHj81bgBni2WLuIpv3oXlai2uc4y9m8LvWAQ+h/ivyog34Ai3Pvr5ZZOFgjy", "gossipEndpoint": [{ "ipAddressV4": "aJq9lg==", "port": 30126 }, { "ipAddressV4": "CoAAHw==", "port": 30126 }] }, { "nodeId": "3", "weight": "15000000000", "gossipCaCertificate": "MIIDpzCCAg+gAwIBAgIJAN7hww13zBZEMA0GCSqGSIb3DQEBDAUAMBIxEDAOBgNVBAMTB3Mtbm9kZTQwIBcNMDAwMTAxMDAwMDAwWhgPMjEwMDAxMDEwMDAwMDBaMBIxEDAOBgNVBAMTB3Mtbm9kZTQwggGiMA0GCSqGSIb3DQEBAQUAA4IBjwAwggGKAoIBgQDK/bVyv0ZUeJZ4cIOImM+wmqtYjCw4jPAC549WQPPV1vG0lzSpgV+nRKqmWBexhLlKN3bsvrfNCUpKSq8meFyCtdppT1dhUOmEZcoNhLZzqxXb2HYYqRPv82tR+tbh+27WFsBOOqYrYTvr72ECD7qDOuw/Xob6KImaw/b/SIAPecMoYy25fkgYkJSETwd8HUpwssYH/JTLBF8eGjjTTMuu14ARQKeH8BXSs+jjV1+3IItXERS8ryUGDjqc5vC8ZW1kDVQbb91IDxRjqZbFyhuasocCqTAcZuiEgE8Wilwp2g1vbAUnHnvKNfiaEAHoEV6vF4lelaWhOnN2U5tnox/ns6PiDqIbOfs0pmXxjAK0vxc6oZM3TwdRtzo6cSb/AYfQdnmQzkra980kHN12r3f7PK2PzGBuVUPT7fLGA4S3vQDYO4rqcgTc/OLobtqLtdBusOFjZscfIfUW4GVWJUI1j+fwvHacxWLmyZwlQ5Q47UtrtjWpFru7CTn5S477lqMCAwEAATANBgkqhkiG9w0BAQwFAAOCAYEAdW6AWDhT0eOJw+0O6MYngmCgkXfFsgBC/B1plaE596hHo58FHxzCNiLFdvfRj37rxujvqsDAkADWUmOzLzLHYMXu302HzDqAMNY6FZJc32y4ZDsIQpaUOAuiNHAHwFXuPRInVpCqztfJMgw4RhOhcCTEsoIJsqoIN1t4M0pEVAv6x3nJwFKZqSNOZrQ7sOW32FjwWS3kHwRsCTtqdk5n2KxU6wr/fggV3QsSPRMYro8sUfwu93mqggtswwWqfeKlsz5WiaR9aqLnb8z1R6HLvA0bcoPWzjgn8RdP+9we4z06iZ5vdBuNpwBjrCKUELWISyAoekLGGxyS8pPqYiSBRNUoaPITSuUjcCBbJ9EFvm72QgCBesbwF71KPabTPbMPhLmf+uAi+zmeu8ZeVvT6DrX9OHSkIvIEQFry9BrqOT3ce6KBHSO1HpXIetj5Wcd3WHXtz9ulBL9ikWC8eh7/+we51ucmLvFzNKznElhT2Dp+czXUVNEUjp3u/66pyRA4", "gossipEndpoint": [{ "ipAddressV4": "IkPM6Q==", "port": 30127 }, { "ipAddressV4": "CoAAIA==", "port": 30127 }] }] }
node0 7.577s 2025-09-25 10:58:01.386 106 INFO STARTUP <main> PlatformTestingToolState: Trigger and PreviousSoftwareVersion state received in init function {"trigger":"GENESIS","previousSoftwareVersion":"null"} [com.swirlds.logging.legacy.payload.SoftwareVersionPayload]
node0 7.577s 2025-09-25 10:58:01.386 107 INFO STARTUP <main> PlatformTestingToolState: Set QuorumResult from genesisInit()
node0 7.580s 2025-09-25 10:58:01.389 115 INFO STARTUP <main> MerkleStateRoot: Putting states...
node0 7.580s 2025-09-25 10:58:01.389 116 INFO STARTUP <main> MerkleStateRoot: Put states! Service name: PlatformStateService
node0 7.580s 2025-09-25 10:58:01.389 117 INFO STARTUP <main> MerkleStateRoot: Getting child.. Service name: PlatformStateService / Number of children: 13 / node: 0
node0 7.583s 2025-09-25 10:58:01.392 125 INFO STARTUP <main> MerkleStateRoot: Putting states...
node0 7.584s 2025-09-25 10:58:01.393 126 INFO STARTUP <main> MerkleStateRoot: Put states! Service name: RosterService
node0 7.584s 2025-09-25 10:58:01.393 127 INFO STARTUP <main> MerkleStateRoot: Getting child.. Service name: RosterService / Number of children: 13 / node: 1
node3 7.584s 2025-09-25 10:58:01.393 101 INFO STARTUP <main> OSHealthChecker:
PASSED - Clock Source Speed Check Report[callsPerSec=20202740] PASSED - Entropy Check Report[success=true, entropySource=Strong Instance, elapsedNanos=166720, randomLong=3843650075749540917, exception=null] PASSED - Entropy Check Report[success=true, entropySource=NativePRNGBlocking:SUN, elapsedNanos=12860, randomLong=8919094951661453781, exception=null] PASSED - File System Check Report[code=SUCCESS, readNanos=1352490, data=35, exception=null] OS Health Check Report - Complete (took 1020 ms)
node0 7.586s 2025-09-25 10:58:01.395 135 INFO STARTUP <main> MerkleStateRoot: Putting states...
node0 7.587s 2025-09-25 10:58:01.396 136 INFO STARTUP <main> MerkleStateRoot: Put states! Service name: RosterService
node0 7.587s 2025-09-25 10:58:01.396 137 INFO STARTUP <main> MerkleStateRoot: Getting child.. Service name: RosterService / Number of children: 13 / node: 2
node0 7.602s 2025-09-25 10:58:01.411 138 INFO STARTUP <main> StateInitializer: The platform is using the following initial state:
Round: 0 Timestamp: 1970-01-01T00:00:00Z Next consensus number: 0 Legacy running event hash: null Legacy running event mnemonic: null Rounds non-ancient: 0 Creation version: SemanticVersion[major=1, minor=0, patch=0, pre=, build=] Minimum judge hash code: 1 Root hash: 5ee490a06e5e87a9e8eac8f767342769271033e4d04adadf530230b68820098ec32e7f8bc6aadcde3a9e22df179587ef First BR Version: SemanticVersion[major=0, minor=0, patch=0, pre=, build=] Last round before BR: 0 Lowest Judge Gen before BR 0 (root) PlatformTestingToolState / symptom-ugly-slim-april 0 SingletonNode PlatformStateService.PLATFORM_STATE /0 dwarf-staff-copper-this 1 VirtualMap RosterService.ROSTERS /1 step-utility-truth-odor 2 SingletonNode RosterService.ROSTER_STATE /2 anger-shell-eyebrow-boring 3 PayloadCfgSimple /3 potato-pill-swear-author 4 null /4 bid-belt-culture-decorate 5 null /5 bid-belt-culture-decorate 6 null /6 bid-belt-culture-decorate 7 IssLeaf /7 biology-capable-expose-purpose 8 null /8 bid-belt-culture-decorate 9 null /9 bid-belt-culture-decorate 10 null /10 bid-belt-culture-decorate 11 null /11 bid-belt-culture-decorate 12 QuorumResult /12 globe-math-mass-loop
node3 7.613s 2025-09-25 10:58:01.422 102 DEBUG STARTUP <main> BootstrapUtils: jvmPauseDetectorThread started
node3 7.620s 2025-09-25 10:58:01.429 103 INFO STARTUP <main> StandardScratchpad: Scratchpad platform.iss contents:
LAST_ISS_ROUND null
node2 7.623s 2025-09-25 10:58:01.432 140 INFO EVENT_STREAM <main> DefaultConsensusEventStream: EventStreamManager::updateRunningHash: 38b060a751ac96384cd9327eb1b1e36a21fdb71114be07434c0cc7bf63f6e1da274edebfe76f65fbd51ad2f14898b95b
node3 7.625s 2025-09-25 10:58:01.434 104 INFO STARTUP <main> PlatformBuilder: Default platform pool parallelism: 8
node2 7.628s 2025-09-25 10:58:01.437 141 INFO STARTUP <platformForkJoinThread-2> Shadowgraph: Shadowgraph starting from expiration threshold 1
node2 7.638s 2025-09-25 10:58:01.447 142 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: -Xms32g
node2 7.639s 2025-09-25 10:58:01.448 143 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: -Xmx118g
node2 7.639s 2025-09-25 10:58:01.448 144 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: -XX:+UnlockExperimentalVMOptions
node2 7.640s 2025-09-25 10:58:01.449 145 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: -XX:+UseZGC
node2 7.640s 2025-09-25 10:58:01.449 146 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: -XX:ZAllocationSpikeTolerance=2
node2 7.640s 2025-09-25 10:58:01.449 147 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: -XX:ConcGCThreads=14
node2 7.640s 2025-09-25 10:58:01.449 148 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: -XX:ZMarkStackSpaceLimit=16g
node2 7.640s 2025-09-25 10:58:01.449 149 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: -XX:MaxDirectMemorySize=64g
node2 7.641s 2025-09-25 10:58:01.450 150 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: -XX:MetaspaceSize=100M
node2 7.641s 2025-09-25 10:58:01.450 151 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: -XX:+ZGenerational
node2 7.641s 2025-09-25 10:58:01.450 152 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: -Xlog:gc*:gc.log
node2 7.641s 2025-09-25 10:58:01.450 153 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: --add-opens=java.base/jdk.internal.misc=ALL-UNNAMED
node2 7.641s 2025-09-25 10:58:01.450 154 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: --add-opens=java.base/java.nio=ALL-UNNAMED
node2 7.642s 2025-09-25 10:58:01.451 155 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: -Dio.netty.tryReflectionSetAccessible=true
node2 7.642s 2025-09-25 10:58:01.451 156 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: Parsing JSON FCM-VM-NIReconnect-125-10m.json
node2 7.659s 2025-09-25 10:58:01.468 157 INFO DEMO_INFO <<start-node-2>> PayloadConfig: insertSeq = true
node2 7.660s 2025-09-25 10:58:01.469 158 INFO DEMO_INFO <<start-node-2>> PayloadConfig: appendSig = true
node2 7.660s 2025-09-25 10:58:01.469 159 INFO DEMO_INFO <<start-node-2>> PayloadConfig: variedSize = false
node2 7.661s 2025-09-25 10:58:01.470 160 INFO DEMO_INFO <<start-node-2>> PayloadConfig: payloadByteSize = 100
node2 7.661s 2025-09-25 10:58:01.470 161 INFO DEMO_INFO <<start-node-2>> PayloadConfig: maxByteSize = 100
node2 7.661s 2025-09-25 10:58:01.470 162 INFO DEMO_INFO <<start-node-2>> PayloadConfig: type = TYPE_FCM_VIRTUAL_MIX
node2 7.662s 2025-09-25 10:58:01.471 163 INFO DEMO_INFO <<start-node-2>> PayloadConfig: invalidSigRatio = 0.0
node2 7.663s 2025-09-25 10:58:01.472 164 INFO DEMO_INFO <<start-node-2>> TransactionSubmitter: Goal is TRANS_PER_SECOND_PER_NODE targe 125.0
node2 7.668s 2025-09-25 10:58:01.477 165 INFO DEMO_INFO <<start-node-2>> VirtualMerkleStateInitializer: State = com.swirlds.demo.platform.PlatformTestingToolState@222e4089
node2 7.669s 2025-09-25 10:58:01.478 166 INFO DEMO_INFO <<start-node-2>> VirtualMerkleStateInitializer: total accounts = 2400
node2 7.669s 2025-09-25 10:58:01.478 167 INFO DEMO_INFO <<start-node-2>> VirtualMerkleStateInitializer: Creating virtualmap for 2400 accounts.
node2 7.677s 2025-09-25 10:58:01.486 172 INFO DEMO_INFO <<start-node-2>> VirtualMerkleStateInitializer: accounts VM = com.swirlds.virtualmap.VirtualMap@2e89028e, DS = MerkleDbDataSource[initialCapacity=1000000,preferDiskBasedIndexes=false,pathToDiskLocationInternalNodes.size=0,pathToDiskLocationLeafNodes.size=0,hashesRamToDiskThreshold=8388608,hashStoreRam.size=0,hashStoreDisk=com.swirlds.merkledb.files.MemoryIndexDiskKeyValueStore@33dadc6b,hasDiskStoreForHashes=true,keyToPath=com.swirlds.merkledb.files.hashmap.HalfDiskHashMap@473de0d0,pathToKeyValue=com.swirlds.merkledb.files.MemoryIndexDiskKeyValueStore@14aa0f54,snapshotInProgress=false]
node2 7.690s 2025-09-25 10:58:01.499 173 INFO DEMO_INFO <<start-node-2>> VirtualMerkleStateInitializer: max KV pairs = 0
node2 7.692s 2025-09-25 10:58:01.501 174 INFO DEMO_INFO <<start-node-2>> VirtualMerkleStateInitializer: total SC = 0
node2 7.699s 2025-09-25 10:58:01.508 175 INFO DEMO_INFO <<start-node-2>> FCMTransactionPool: Random seed for FCMTransactionPool is -4655588514852919697
node2 7.699s 2025-09-25 10:58:01.508 176 INFO DEMO_INFO <<start-node-2>> FCMTransactionPool: This test will generate sequential FCM payload:
node3 7.706s 2025-09-25 10:58:01.515 105 INFO STARTUP <main> SwirldsPlatform: Starting with roster history:
RosterHistory[ currentRosterRound: 0 ][ no previous roster set ] Current Roster: { "rosterEntries": [{ "weight": "3125000000", "gossipCaCertificate": "MIIDpzCCAg+gAwIBAgIJAK05TS8KZeb1MA0GCSqGSIb3DQEBDAUAMBIxEDAOBgNVBAMTB3Mtbm9kZTEwIBcNMDAwMTAxMDAwMDAwWhgPMjEwMDAxMDEwMDAwMDBaMBIxEDAOBgNVBAMTB3Mtbm9kZTEwggGiMA0GCSqGSIb3DQEBAQUAA4IBjwAwggGKAoIBgQDBoP9dI3K1PRLRK7h90D9eNCfgzuHTyJi70yDEs90XJXlE6jmgf1NE2av83VAhQHLxu8Ehc/55M9Ayx9IQc0zJLSS+IrRM9QwqoG8ZvNdRgNw+je3V/8rAK/mHId+cPnnyDplCyskyi5kWCv6kTULIewFH8/KVZwhe0/hB2+N6ujWixURrxjjGLHA6b2gPoGAb/nxiVOn+L0cWcOzcyiYShxagj0FBWV7AxKx65Ynzfe7eF0gOzBUA+IM10OM5KXJejk53Xz5KpEyGe8htO/bXFlpLdm3UzrYiIhY0oKPYKECAC1s+VAZA6i+MV0nDpqDgxHRRXD8O2arauPhEI6iVT9f05AtzElrs7U95HbpQUuP1sxkaQw+bLdMOQHHMVCgMgw2g0eDdVDAMJD7wjZ+Bs6kDc/EJELb0l1uy2GEnOZMiHkK4K1r4IyZ/ed6QpyIRKfBCNyT5IIpMoVpzRYxVXgjgFdudd8iErKyvSXHThU6nu92c+vSd+FLBFHPpb6ECAwEAATANBgkqhkiG9w0BAQwFAAOCAYEAdga5NYtV48uDCd4vIsmpGWpKuUHtDVDlCvzHc2ij8DxAR6OFp+hIRNEBXkzg1KS5qP8Wba5ptmGoV4f89HemP+AL3Azde+HjpYRtffdfTdQwmMbw7xJg2lKkEo11gDo5+zPZnVbfb3FsZ+IXKji0QshQBfg+ddTkFG3TJG1ttq3ZDw94RxFQivVnkj1p+Ogel/DuBNRWQobFVe5VrmJqbuwwN8AdrPae1dMrkZatF91On5+cpVLGfk96fYUhDohDt6KKQ6DdhvFk5rhd0vsHGMQq2gAW2+Or6ZVsKkHKx8CPINpJVKAdpE0tItI+loMO02jf9oRI/8cThWP1vNAeWnr0D6m275EZf/4qem/DdJ0FJIVou3P7tsq7eSdueDnj5RmcbW/vOBtvlXpD3SqsVRn6sltZ0sk24p+6ZMzopevCZEMf/nL3OzGvSadisXb39H9DgwkNLlefju1QLgHWf0TGfeNHluDgVDhU8+/1/KUGtr2SnZ5EVO1l59FWHALj", "gossipEndpoint": [{ "ipAddressV4": "IjeWHA==", "port": 30124 }, { "ipAddressV4": "CoAAFw==", "port": 30124 }] }, { "nodeId": "1", "weight": "16000000000", "gossipCaCertificate": "MIIDpjCCAg6gAwIBAgIIHWg7e2Q/smQwDQYJKoZIhvcNAQEMBQAwEjEQMA4GA1UEAxMHcy1ub2RlMjAgFw0wMDAxMDEwMDAwMDBaGA8yMTAwMDEwMTAwMDAwMFowEjEQMA4GA1UEAxMHcy1ub2RlMjCCAaIwDQYJKoZIhvcNAQEBBQADggGPADCCAYoCggGBAKr5WsBepS3+y/0/yfBjzMWje7zianEz7sszrNWV3cGu2KUlR7v2+9wp/EtX1+BdcGlTTojgFs5nEBN4lM76Cp6JjFH461yN8GSkIkpe8GZnb1w4KEjZj5UYMbq+qOUI6QmwmgLeO8RHAsS6lCP1AyGFalb2ZVJ09DcYDxCRXeFj4BqvNbtD5r5DTCtpVT4ax3eb3pzNSGsjQUG9zhyp/WcsAmwmzKdMl72tk6qF8tlAWXyzwiCujWHS0Kln0C5pyEjeFNsG299toC4pgT8juxijgseTeIFRnNHmGSeSmXpAkEELlwLKR8HOnqeiS5UXNqdbxNemx/EpJSc5rTB6kzLX24dIuRsgyIIFWx73goOzmaHUolN4xmenifoMYlSNNM07WrsvmjRC5OLc/uGhdWqhZGBCH6AJB8Cmw84QLXVdHE6LiueP1oMd7g++N4X880wJkuh0ebfV3i7etUIn0jLlM50AkRucG9kwZDJ/M4LY7FT2F85R1/o2FaB/537ARQIDAQABMA0GCSqGSIb3DQEBDAUAA4IBgQB5lTkqYw0hEW+BJTFsQ8jEHfIDNRJ0kNbVuibfP+u7kzlJy15lCEi+Qw6E3d8hA1QBX3xJMxNBlrtYPrdG26hh/tOwo5Np/OfxQC5jo0Q7n7hu7aLxZRUB/q7AfdDbOun4Za6rJhT3+EsFocyARWp8bYSk3YILBMkP+2VYDRkgQidzKgKtO5yv21Y9sEgziSprc+dQb/tqn5aQZLWavFwCLwnB3t4r4qwLHkkH00Jw51uOvLeM49/t333V5Caa7wmWzMcE+KSWW0QWFRxeJrodSyjPdmDi4D8lKN5WJHSAU5L2yWIODUyWD/cvsAapTv7xXk9ja/Ssb9DpMQnM1xh0hYaESajNeL1QbGuZgPxAwrw981h7kprR2P2iMGRVGA6u4ezxmhW3s7D+yJ3+Yxs/x2J/sw65Z16mRYXRWYWHQmhgaVQjIviiAkVB6CWZo1kHl/eYaVedQzKlrTpbr3JtmwGwhYEOnrkzsC63h8/AG9gRtIAIGWGqTPWbn2pEm8M=", "gossipEndpoint": [{ "ipAddressV4": "I+CnFQ==", "port": 30125 }, { "ipAddressV4": "CoAAHg==", "port": 30125 }] }, { "nodeId": "2", "weight": "15875000000", "gossipCaCertificate": "MIIDpzCCAg+gAwIBAgIJAJg3GRFp5bT9MA0GCSqGSIb3DQEBDAUAMBIxEDAOBgNVBAMTB3Mtbm9kZTMwIBcNMDAwMTAxMDAwMDAwWhgPMjEwMDAxMDEwMDAwMDBaMBIxEDAOBgNVBAMTB3Mtbm9kZTMwggGiMA0GCSqGSIb3DQEBAQUAA4IBjwAwggGKAoIBgQCl5ut2dCleDmgEneRYpAKa9Pe2qnXzgF+BEIuTfizG2OcPQi/ltv+6HxSrJXtuWNaiX/G4iP7iBzWj2ysaAYwfYj0ezTSMLRqM9hXzVgLtW0LJEF6a8vUXPsJt4GEJkUKiYCCO1MP1NLd3y/3SVJrFhwJSPqKYm2pQNg84WfPDWSkzSneOIO4Z0uWDXgs+vzSNyChWOxVieFQhLjcELtyj6narmLox+Jdo/SxUzPuktuFB3ebNgUqWPkjljgZpl00BTmbRIVHgHfDVulo2PBpXd0VplIDgdPr5zMKdTrKCuDKey8Mft72RkPKMe9LZVZ/21+rXVEh+olvvUCySsP2RkWPUJJD90c8wKo01rZsjAOXscJKQcBYlam5XXO4ZBRYzEdxuivbkPwsOoQ83swCR3alPvwfbg11Va+zXE6sRbUM9LqkYo/M3Hwg8tSIXu8oah6csputanz867dzWwyVJEPzmiXZ6ncVDQO31QlB7RndWCqKTjOQpnpblUMsrE9MCAwEAATANBgkqhkiG9w0BAQwFAAOCAYEAnUA8+kz7L+eSOm/iVvUNYF10PKO2nZtxWWL7R1vwK/2Up765PwqxKb0eSEM4bjgvZq1GuGXs9X/Y7dos42yntXvgeUY+/2JzCnw4J5tzxytZ+IKX6DR67NjDzDzVZQfptjLQrb8E7yzml0uxsqrhNPWl57Bmfe66Kg2lD11jImeeEhExlRggFukoiUWVwRNU21Q1jMUWrg2ZwfP+6fFTgRt0WR+X5zkyYPbvI6/yv7reYGjPDuZTOFhbwG8LUTQxdttDswPjnQ606kMyninL+aNelSdV/UIII7lpr/dTvgQAnrlBaGXvdy6brh3wWEwia0FZFZcKEs6M+jZ3MrFxvlTfUIdI3jRq12L10cCDi2VhORg4JmvlM+Tk6kJeSku30ZLAVo3S7GbTdvkuesOxz3UwnF7yfOA1KYOPvhv1oLxGV5z05glsn1OBKnXMdzsKFbAYYHj81bgBni2WLuIpv3oXlai2uc4y9m8LvWAQ+h/ivyog34Ai3Pvr5ZZOFgjy", "gossipEndpoint": [{ "ipAddressV4": "aJq9lg==", "port": 30126 }, { "ipAddressV4": "CoAAHw==", "port": 30126 }] }, { "nodeId": "3", "weight": "15000000000", "gossipCaCertificate": "MIIDpzCCAg+gAwIBAgIJAN7hww13zBZEMA0GCSqGSIb3DQEBDAUAMBIxEDAOBgNVBAMTB3Mtbm9kZTQwIBcNMDAwMTAxMDAwMDAwWhgPMjEwMDAxMDEwMDAwMDBaMBIxEDAOBgNVBAMTB3Mtbm9kZTQwggGiMA0GCSqGSIb3DQEBAQUAA4IBjwAwggGKAoIBgQDK/bVyv0ZUeJZ4cIOImM+wmqtYjCw4jPAC549WQPPV1vG0lzSpgV+nRKqmWBexhLlKN3bsvrfNCUpKSq8meFyCtdppT1dhUOmEZcoNhLZzqxXb2HYYqRPv82tR+tbh+27WFsBOOqYrYTvr72ECD7qDOuw/Xob6KImaw/b/SIAPecMoYy25fkgYkJSETwd8HUpwssYH/JTLBF8eGjjTTMuu14ARQKeH8BXSs+jjV1+3IItXERS8ryUGDjqc5vC8ZW1kDVQbb91IDxRjqZbFyhuasocCqTAcZuiEgE8Wilwp2g1vbAUnHnvKNfiaEAHoEV6vF4lelaWhOnN2U5tnox/ns6PiDqIbOfs0pmXxjAK0vxc6oZM3TwdRtzo6cSb/AYfQdnmQzkra980kHN12r3f7PK2PzGBuVUPT7fLGA4S3vQDYO4rqcgTc/OLobtqLtdBusOFjZscfIfUW4GVWJUI1j+fwvHacxWLmyZwlQ5Q47UtrtjWpFru7CTn5S477lqMCAwEAATANBgkqhkiG9w0BAQwFAAOCAYEAdW6AWDhT0eOJw+0O6MYngmCgkXfFsgBC/B1plaE596hHo58FHxzCNiLFdvfRj37rxujvqsDAkADWUmOzLzLHYMXu302HzDqAMNY6FZJc32y4ZDsIQpaUOAuiNHAHwFXuPRInVpCqztfJMgw4RhOhcCTEsoIJsqoIN1t4M0pEVAv6x3nJwFKZqSNOZrQ7sOW32FjwWS3kHwRsCTtqdk5n2KxU6wr/fggV3QsSPRMYro8sUfwu93mqggtswwWqfeKlsz5WiaR9aqLnb8z1R6HLvA0bcoPWzjgn8RdP+9we4z06iZ5vdBuNpwBjrCKUELWISyAoekLGGxyS8pPqYiSBRNUoaPITSuUjcCBbJ9EFvm72QgCBesbwF71KPabTPbMPhLmf+uAi+zmeu8ZeVvT6DrX9OHSkIvIEQFry9BrqOT3ce6KBHSO1HpXIetj5Wcd3WHXtz9ulBL9ikWC8eh7/+we51ucmLvFzNKznElhT2Dp+czXUVNEUjp3u/66pyRA4", "gossipEndpoint": [{ "ipAddressV4": "IkPM6Q==", "port": 30127 }, { "ipAddressV4": "CoAAIA==", "port": 30127 }] }] }
node2 7.784s 2025-09-25 10:58:01.593 177 INFO STARTUP <<start-node-2>> SwirldsPlatform: Starting platform 2
node2 7.786s 2025-09-25 10:58:01.595 178 INFO STARTUP <<platform: recycle-bin-cleanup>> RecycleBinImpl: Deleted 0 files from the recycle bin.
node2 7.790s 2025-09-25 10:58:01.599 179 INFO STARTUP <<start-node-2>> CycleFinder: No cyclical back pressure detected in wiring model.
node2 7.791s 2025-09-25 10:58:01.600 180 INFO STARTUP <<start-node-2>> DirectSchedulerChecks: No illegal direct scheduler use detected in the wiring model.
node2 7.792s 2025-09-25 10:58:01.601 181 INFO STARTUP <<start-node-2>> InputWireChecks: All input wires have been bound.
node2 7.793s 2025-09-25 10:58:01.602 182 WARN STARTUP <<start-node-2>> PcesFileTracker: No preconsensus event files available
node2 7.794s 2025-09-25 10:58:01.603 183 INFO STARTUP <<start-node-2>> SwirldsPlatform: replaying preconsensus event stream starting at 0
node2 7.795s 2025-09-25 10:58:01.604 184 INFO STARTUP <<start-node-2>> PcesReplayer: Replayed 0 preconsensus events. These events contained 0 transactions. 0 rounds reached consensus spanning 0.0 nanoseconds of consensus time. The latest round to reach consensus is round 0. Replay took 0.0 nanoseconds.
node2 7.796s 2025-09-25 10:58:01.605 185 INFO DEMO_INFO <<app: appMain 2>> PlatformTestingToolMain: Node 2 starts transactions ........
node2 7.798s 2025-09-25 10:58:01.607 186 INFO PLATFORM_STATUS <platformForkJoinThread-3> DefaultStatusStateMachine: Platform spent 333.0 ms in STARTING_UP. Now in REPLAYING_EVENTS
node2 7.801s 2025-09-25 10:58:01.610 187 INFO STARTUP <platformForkJoinThread-3> PlatformTestingToolMain: Platform Status Change REPLAYING_EVENTS
node2 7.801s 2025-09-25 10:58:01.610 188 INFO PLATFORM_STATUS <platformForkJoinThread-3> DefaultStatusStateMachine: Platform spent 3.0 ms in REPLAYING_EVENTS. Now in OBSERVING
node3 7.801s 2025-09-25 10:58:01.610 106 INFO STARTUP <main> PlatformTestingToolState: Trigger and PreviousSoftwareVersion state received in init function {"trigger":"GENESIS","previousSoftwareVersion":"null"} [com.swirlds.logging.legacy.payload.SoftwareVersionPayload]
node2 7.802s 2025-09-25 10:58:01.611 189 INFO STARTUP <platformForkJoinThread-3> PlatformTestingToolMain: Platform Status Change OBSERVING
node3 7.802s 2025-09-25 10:58:01.611 107 INFO STARTUP <main> PlatformTestingToolState: Set QuorumResult from genesisInit()
node3 7.804s 2025-09-25 10:58:01.613 115 INFO STARTUP <main> MerkleStateRoot: Putting states...
node3 7.804s 2025-09-25 10:58:01.613 116 INFO STARTUP <main> MerkleStateRoot: Put states! Service name: PlatformStateService
node3 7.804s 2025-09-25 10:58:01.613 117 INFO STARTUP <main> MerkleStateRoot: Getting child.. Service name: PlatformStateService / Number of children: 13 / node: 0
node3 7.807s 2025-09-25 10:58:01.616 125 INFO STARTUP <main> MerkleStateRoot: Putting states...
node3 7.807s 2025-09-25 10:58:01.616 126 INFO STARTUP <main> MerkleStateRoot: Put states! Service name: RosterService
node3 7.808s 2025-09-25 10:58:01.617 127 INFO STARTUP <main> MerkleStateRoot: Getting child.. Service name: RosterService / Number of children: 13 / node: 1
node3 7.810s 2025-09-25 10:58:01.619 135 INFO STARTUP <main> MerkleStateRoot: Putting states...
node3 7.810s 2025-09-25 10:58:01.619 136 INFO STARTUP <main> MerkleStateRoot: Put states! Service name: RosterService
node3 7.810s 2025-09-25 10:58:01.619 137 INFO STARTUP <main> MerkleStateRoot: Getting child.. Service name: RosterService / Number of children: 13 / node: 2
node3 7.825s 2025-09-25 10:58:01.634 138 INFO STARTUP <main> StateInitializer: The platform is using the following initial state:
Round: 0 Timestamp: 1970-01-01T00:00:00Z Next consensus number: 0 Legacy running event hash: null Legacy running event mnemonic: null Rounds non-ancient: 0 Creation version: SemanticVersion[major=1, minor=0, patch=0, pre=, build=] Minimum judge hash code: 1 Root hash: 5ee490a06e5e87a9e8eac8f767342769271033e4d04adadf530230b68820098ec32e7f8bc6aadcde3a9e22df179587ef First BR Version: SemanticVersion[major=0, minor=0, patch=0, pre=, build=] Last round before BR: 0 Lowest Judge Gen before BR 0 (root) PlatformTestingToolState / symptom-ugly-slim-april 0 SingletonNode PlatformStateService.PLATFORM_STATE /0 dwarf-staff-copper-this 1 VirtualMap RosterService.ROSTERS /1 step-utility-truth-odor 2 SingletonNode RosterService.ROSTER_STATE /2 anger-shell-eyebrow-boring 3 PayloadCfgSimple /3 potato-pill-swear-author 4 null /4 bid-belt-culture-decorate 5 null /5 bid-belt-culture-decorate 6 null /6 bid-belt-culture-decorate 7 IssLeaf /7 biology-capable-expose-purpose 8 null /8 bid-belt-culture-decorate 9 null /9 bid-belt-culture-decorate 10 null /10 bid-belt-culture-decorate 11 null /11 bid-belt-culture-decorate 12 QuorumResult /12 globe-math-mass-loop
node0 7.843s 2025-09-25 10:58:01.652 140 INFO EVENT_STREAM <main> DefaultConsensusEventStream: EventStreamManager::updateRunningHash: 38b060a751ac96384cd9327eb1b1e36a21fdb71114be07434c0cc7bf63f6e1da274edebfe76f65fbd51ad2f14898b95b
node0 7.848s 2025-09-25 10:58:01.657 141 INFO STARTUP <platformForkJoinThread-2> Shadowgraph: Shadowgraph starting from expiration threshold 1
node0 7.859s 2025-09-25 10:58:01.668 142 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: -Xms32g
node0 7.860s 2025-09-25 10:58:01.669 143 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: -Xmx118g
node0 7.860s 2025-09-25 10:58:01.669 144 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: -XX:+UnlockExperimentalVMOptions
node0 7.860s 2025-09-25 10:58:01.669 145 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: -XX:+UseZGC
node0 7.861s 2025-09-25 10:58:01.670 146 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: -XX:ZAllocationSpikeTolerance=2
node0 7.861s 2025-09-25 10:58:01.670 147 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: -XX:ConcGCThreads=14
node0 7.861s 2025-09-25 10:58:01.670 148 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: -XX:ZMarkStackSpaceLimit=16g
node0 7.861s 2025-09-25 10:58:01.670 149 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: -XX:MaxDirectMemorySize=64g
node0 7.862s 2025-09-25 10:58:01.671 150 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: -XX:MetaspaceSize=100M
node0 7.862s 2025-09-25 10:58:01.671 151 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: -XX:+ZGenerational
node0 7.862s 2025-09-25 10:58:01.671 152 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: -Xlog:gc*:gc.log
node0 7.863s 2025-09-25 10:58:01.672 153 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: --add-opens=java.base/jdk.internal.misc=ALL-UNNAMED
node0 7.863s 2025-09-25 10:58:01.672 154 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: --add-opens=java.base/java.nio=ALL-UNNAMED
node0 7.863s 2025-09-25 10:58:01.672 155 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: -Dio.netty.tryReflectionSetAccessible=true
node0 7.864s 2025-09-25 10:58:01.673 156 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: Parsing JSON FCM-VM-NIReconnect-125-10m.json
node0 7.889s 2025-09-25 10:58:01.698 157 INFO DEMO_INFO <<start-node-0>> PayloadConfig: insertSeq = true
node0 7.890s 2025-09-25 10:58:01.699 158 INFO DEMO_INFO <<start-node-0>> PayloadConfig: appendSig = true
node0 7.891s 2025-09-25 10:58:01.700 159 INFO DEMO_INFO <<start-node-0>> PayloadConfig: variedSize = false
node0 7.892s 2025-09-25 10:58:01.701 160 INFO DEMO_INFO <<start-node-0>> PayloadConfig: payloadByteSize = 100
node0 7.892s 2025-09-25 10:58:01.701 161 INFO DEMO_INFO <<start-node-0>> PayloadConfig: maxByteSize = 100
node0 7.893s 2025-09-25 10:58:01.702 162 INFO DEMO_INFO <<start-node-0>> PayloadConfig: type = TYPE_FCM_VIRTUAL_MIX
node0 7.893s 2025-09-25 10:58:01.702 163 INFO DEMO_INFO <<start-node-0>> PayloadConfig: invalidSigRatio = 0.0
node0 7.895s 2025-09-25 10:58:01.704 164 INFO DEMO_INFO <<start-node-0>> TransactionSubmitter: Goal is TRANS_PER_SECOND_PER_NODE targe 125.0
node0 7.903s 2025-09-25 10:58:01.712 165 INFO DEMO_INFO <<start-node-0>> VirtualMerkleStateInitializer: State = com.swirlds.demo.platform.PlatformTestingToolState@1ebb634d
node0 7.905s 2025-09-25 10:58:01.714 166 INFO DEMO_INFO <<start-node-0>> VirtualMerkleStateInitializer: total accounts = 2400
node0 7.905s 2025-09-25 10:58:01.714 167 INFO DEMO_INFO <<start-node-0>> VirtualMerkleStateInitializer: Creating virtualmap for 2400 accounts.
node0 7.915s 2025-09-25 10:58:01.724 172 INFO DEMO_INFO <<start-node-0>> VirtualMerkleStateInitializer: accounts VM = com.swirlds.virtualmap.VirtualMap@523155ac, DS = MerkleDbDataSource[initialCapacity=1000000,preferDiskBasedIndexes=false,pathToDiskLocationInternalNodes.size=0,pathToDiskLocationLeafNodes.size=0,hashesRamToDiskThreshold=8388608,hashStoreRam.size=0,hashStoreDisk=com.swirlds.merkledb.files.MemoryIndexDiskKeyValueStore@5bb46088,hasDiskStoreForHashes=true,keyToPath=com.swirlds.merkledb.files.hashmap.HalfDiskHashMap@51d10a9f,pathToKeyValue=com.swirlds.merkledb.files.MemoryIndexDiskKeyValueStore@681cf220,snapshotInProgress=false]
node0 7.927s 2025-09-25 10:58:01.736 173 INFO DEMO_INFO <<start-node-0>> VirtualMerkleStateInitializer: max KV pairs = 0
node0 7.928s 2025-09-25 10:58:01.737 174 INFO DEMO_INFO <<start-node-0>> VirtualMerkleStateInitializer: total SC = 0
node0 7.936s 2025-09-25 10:58:01.745 175 INFO DEMO_INFO <<start-node-0>> FCMTransactionPool: Random seed for FCMTransactionPool is 6073385013232117733
node0 7.937s 2025-09-25 10:58:01.746 176 INFO DEMO_INFO <<start-node-0>> FCMTransactionPool: This test will generate sequential FCM payload:
node3 8.022s 2025-09-25 10:58:01.831 140 INFO EVENT_STREAM <main> DefaultConsensusEventStream: EventStreamManager::updateRunningHash: 38b060a751ac96384cd9327eb1b1e36a21fdb71114be07434c0cc7bf63f6e1da274edebfe76f65fbd51ad2f14898b95b
node0 8.024s 2025-09-25 10:58:01.833 177 INFO STARTUP <<start-node-0>> SwirldsPlatform: Starting platform 0
node0 8.025s 2025-09-25 10:58:01.834 178 INFO STARTUP <<platform: recycle-bin-cleanup>> RecycleBinImpl: Deleted 0 files from the recycle bin.
node3 8.026s 2025-09-25 10:58:01.835 141 INFO STARTUP <platformForkJoinThread-2> Shadowgraph: Shadowgraph starting from expiration threshold 1
node0 8.029s 2025-09-25 10:58:01.838 179 INFO STARTUP <<start-node-0>> CycleFinder: No cyclical back pressure detected in wiring model.
node0 8.031s 2025-09-25 10:58:01.840 180 INFO STARTUP <<start-node-0>> DirectSchedulerChecks: No illegal direct scheduler use detected in the wiring model.
node0 8.031s 2025-09-25 10:58:01.840 181 INFO STARTUP <<start-node-0>> InputWireChecks: All input wires have been bound.
node0 8.033s 2025-09-25 10:58:01.842 182 WARN STARTUP <<start-node-0>> PcesFileTracker: No preconsensus event files available
node0 8.034s 2025-09-25 10:58:01.843 183 INFO STARTUP <<start-node-0>> SwirldsPlatform: replaying preconsensus event stream starting at 0
node3 8.034s 2025-09-25 10:58:01.843 142 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: -Xms32g
node3 8.034s 2025-09-25 10:58:01.843 143 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: -Xmx118g
node3 8.034s 2025-09-25 10:58:01.843 144 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: -XX:+UnlockExperimentalVMOptions
node0 8.035s 2025-09-25 10:58:01.844 184 INFO STARTUP <<start-node-0>> PcesReplayer: Replayed 0 preconsensus events. These events contained 0 transactions. 0 rounds reached consensus spanning 0.0 nanoseconds of consensus time. The latest round to reach consensus is round 0. Replay took 0.0 nanoseconds.
node0 8.035s 2025-09-25 10:58:01.844 185 INFO DEMO_INFO <<app: appMain 0>> PlatformTestingToolMain: Node 0 starts transactions ........
node3 8.035s 2025-09-25 10:58:01.844 145 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: -XX:+UseZGC
node3 8.035s 2025-09-25 10:58:01.844 146 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: -XX:ZAllocationSpikeTolerance=2
node3 8.035s 2025-09-25 10:58:01.844 147 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: -XX:ConcGCThreads=14
node3 8.035s 2025-09-25 10:58:01.844 148 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: -XX:ZMarkStackSpaceLimit=16g
node3 8.035s 2025-09-25 10:58:01.844 149 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: -XX:MaxDirectMemorySize=64g
node0 8.036s 2025-09-25 10:58:01.845 186 INFO PLATFORM_STATUS <platformForkJoinThread-4> DefaultStatusStateMachine: Platform spent 375.0 ms in STARTING_UP. Now in REPLAYING_EVENTS
node3 8.036s 2025-09-25 10:58:01.845 150 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: -XX:MetaspaceSize=100M
node3 8.036s 2025-09-25 10:58:01.845 151 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: -XX:+ZGenerational
node3 8.036s 2025-09-25 10:58:01.845 152 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: -Xlog:gc*:gc.log
node3 8.036s 2025-09-25 10:58:01.845 153 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: --add-opens=java.base/jdk.internal.misc=ALL-UNNAMED
node3 8.037s 2025-09-25 10:58:01.846 154 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: --add-opens=java.base/java.nio=ALL-UNNAMED
node3 8.037s 2025-09-25 10:58:01.846 155 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: -Dio.netty.tryReflectionSetAccessible=true
node3 8.037s 2025-09-25 10:58:01.846 156 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: Parsing JSON FCM-VM-NIReconnect-125-10m.json
node0 8.039s 2025-09-25 10:58:01.848 187 INFO STARTUP <platformForkJoinThread-4> PlatformTestingToolMain: Platform Status Change REPLAYING_EVENTS
node0 8.040s 2025-09-25 10:58:01.849 188 INFO PLATFORM_STATUS <platformForkJoinThread-4> DefaultStatusStateMachine: Platform spent 3.0 ms in REPLAYING_EVENTS. Now in OBSERVING
node0 8.040s 2025-09-25 10:58:01.849 189 INFO STARTUP <platformForkJoinThread-4> PlatformTestingToolMain: Platform Status Change OBSERVING
node3 8.058s 2025-09-25 10:58:01.867 157 INFO DEMO_INFO <<start-node-3>> PayloadConfig: insertSeq = true
node3 8.058s 2025-09-25 10:58:01.867 158 INFO DEMO_INFO <<start-node-3>> PayloadConfig: appendSig = true
node3 8.059s 2025-09-25 10:58:01.868 159 INFO DEMO_INFO <<start-node-3>> PayloadConfig: variedSize = false
node3 8.059s 2025-09-25 10:58:01.868 160 INFO DEMO_INFO <<start-node-3>> PayloadConfig: payloadByteSize = 100
node3 8.059s 2025-09-25 10:58:01.868 161 INFO DEMO_INFO <<start-node-3>> PayloadConfig: maxByteSize = 100
node3 8.059s 2025-09-25 10:58:01.868 162 INFO DEMO_INFO <<start-node-3>> PayloadConfig: type = TYPE_FCM_VIRTUAL_MIX
node3 8.060s 2025-09-25 10:58:01.869 163 INFO DEMO_INFO <<start-node-3>> PayloadConfig: invalidSigRatio = 0.0
node3 8.061s 2025-09-25 10:58:01.870 164 INFO DEMO_INFO <<start-node-3>> TransactionSubmitter: Goal is TRANS_PER_SECOND_PER_NODE targe 125.0
node3 8.068s 2025-09-25 10:58:01.877 165 INFO DEMO_INFO <<start-node-3>> VirtualMerkleStateInitializer: State = com.swirlds.demo.platform.PlatformTestingToolState@44a4512a
node3 8.069s 2025-09-25 10:58:01.878 166 INFO DEMO_INFO <<start-node-3>> VirtualMerkleStateInitializer: total accounts = 2400
node3 8.070s 2025-09-25 10:58:01.879 167 INFO DEMO_INFO <<start-node-3>> VirtualMerkleStateInitializer: Creating virtualmap for 2400 accounts.
node3 8.077s 2025-09-25 10:58:01.886 172 INFO DEMO_INFO <<start-node-3>> VirtualMerkleStateInitializer: accounts VM = com.swirlds.virtualmap.VirtualMap@6a76d7d9, DS = MerkleDbDataSource[initialCapacity=1000000,preferDiskBasedIndexes=false,pathToDiskLocationInternalNodes.size=0,pathToDiskLocationLeafNodes.size=0,hashesRamToDiskThreshold=8388608,hashStoreRam.size=0,hashStoreDisk=com.swirlds.merkledb.files.MemoryIndexDiskKeyValueStore@46f885e0,hasDiskStoreForHashes=true,keyToPath=com.swirlds.merkledb.files.hashmap.HalfDiskHashMap@f3819d6,pathToKeyValue=com.swirlds.merkledb.files.MemoryIndexDiskKeyValueStore@8fa7a0b,snapshotInProgress=false]
node3 8.087s 2025-09-25 10:58:01.896 173 INFO DEMO_INFO <<start-node-3>> VirtualMerkleStateInitializer: max KV pairs = 0
node3 8.087s 2025-09-25 10:58:01.896 174 INFO DEMO_INFO <<start-node-3>> VirtualMerkleStateInitializer: total SC = 0
node3 8.092s 2025-09-25 10:58:01.901 175 INFO DEMO_INFO <<start-node-3>> FCMTransactionPool: Random seed for FCMTransactionPool is 5973511517631053944
node3 8.092s 2025-09-25 10:58:01.901 176 INFO DEMO_INFO <<start-node-3>> FCMTransactionPool: This test will generate sequential FCM payload:
node3 8.176s 2025-09-25 10:58:01.985 177 INFO STARTUP <<start-node-3>> SwirldsPlatform: Starting platform 3
node3 8.178s 2025-09-25 10:58:01.987 178 INFO STARTUP <<platform: recycle-bin-cleanup>> RecycleBinImpl: Deleted 0 files from the recycle bin.
node3 8.183s 2025-09-25 10:58:01.992 179 INFO STARTUP <<start-node-3>> CycleFinder: No cyclical back pressure detected in wiring model.
node3 8.184s 2025-09-25 10:58:01.993 180 INFO STARTUP <<start-node-3>> DirectSchedulerChecks: No illegal direct scheduler use detected in the wiring model.
node3 8.184s 2025-09-25 10:58:01.993 181 INFO STARTUP <<start-node-3>> InputWireChecks: All input wires have been bound.
node3 8.186s 2025-09-25 10:58:01.995 182 WARN STARTUP <<start-node-3>> PcesFileTracker: No preconsensus event files available
node3 8.186s 2025-09-25 10:58:01.995 183 INFO STARTUP <<start-node-3>> SwirldsPlatform: replaying preconsensus event stream starting at 0
node3 8.188s 2025-09-25 10:58:01.997 184 INFO STARTUP <<start-node-3>> PcesReplayer: Replayed 0 preconsensus events. These events contained 0 transactions. 0 rounds reached consensus spanning 0.0 nanoseconds of consensus time. The latest round to reach consensus is round 0. Replay took 0.0 nanoseconds.
node3 8.189s 2025-09-25 10:58:01.998 185 INFO DEMO_INFO <<app: appMain 3>> PlatformTestingToolMain: Node 3 starts transactions ........
node3 8.189s 2025-09-25 10:58:01.998 186 INFO PLATFORM_STATUS <platformForkJoinThread-3> DefaultStatusStateMachine: Platform spent 309.0 ms in STARTING_UP. Now in REPLAYING_EVENTS
node3 8.192s 2025-09-25 10:58:02.001 187 INFO STARTUP <platformForkJoinThread-3> PlatformTestingToolMain: Platform Status Change REPLAYING_EVENTS
node3 8.193s 2025-09-25 10:58:02.002 188 INFO PLATFORM_STATUS <platformForkJoinThread-3> DefaultStatusStateMachine: Platform spent 3.0 ms in REPLAYING_EVENTS. Now in OBSERVING
node3 8.194s 2025-09-25 10:58:02.003 189 INFO STARTUP <platformForkJoinThread-3> PlatformTestingToolMain: Platform Status Change OBSERVING
node1 9.133s 2025-09-25 10:58:02.942 190 INFO STARTUP <<platform-core: MetricsThread #0>> LegacyCsvWriter: CsvWriter: Initializing statistics output in CSV format [ csvOutputFolder = '/opt/hgcapp/services-hedera/HapiApp2.0/data/stats', csvFileName = 'PlatformTesting1.csv' ]
node1 9.135s 2025-09-25 10:58:02.944 191 DEBUG STARTUP <<platform-core: MetricsThread #0>> LegacyCsvWriter: CsvWriter: Using the existing metrics folder [ folder = '/opt/hgcapp/services-hedera/HapiApp2.0/data/stats' ]
node2 10.647s 2025-09-25 10:58:04.456 190 INFO STARTUP <<platform-core: MetricsThread #0>> LegacyCsvWriter: CsvWriter: Initializing statistics output in CSV format [ csvOutputFolder = '/opt/hgcapp/services-hedera/HapiApp2.0/data/stats', csvFileName = 'PlatformTesting2.csv' ]
node2 10.650s 2025-09-25 10:58:04.459 191 DEBUG STARTUP <<platform-core: MetricsThread #0>> LegacyCsvWriter: CsvWriter: Using the existing metrics folder [ folder = '/opt/hgcapp/services-hedera/HapiApp2.0/data/stats' ]
node0 10.865s 2025-09-25 10:58:04.674 190 INFO STARTUP <<platform-core: MetricsThread #0>> LegacyCsvWriter: CsvWriter: Initializing statistics output in CSV format [ csvOutputFolder = '/opt/hgcapp/services-hedera/HapiApp2.0/data/stats', csvFileName = 'PlatformTesting0.csv' ]
node0 10.867s 2025-09-25 10:58:04.676 191 DEBUG STARTUP <<platform-core: MetricsThread #0>> LegacyCsvWriter: CsvWriter: Using the existing metrics folder [ folder = '/opt/hgcapp/services-hedera/HapiApp2.0/data/stats' ]
node3 11.041s 2025-09-25 10:58:04.850 190 INFO STARTUP <<platform-core: MetricsThread #0>> LegacyCsvWriter: CsvWriter: Initializing statistics output in CSV format [ csvOutputFolder = '/opt/hgcapp/services-hedera/HapiApp2.0/data/stats', csvFileName = 'PlatformTesting3.csv' ]
node3 11.044s 2025-09-25 10:58:04.853 191 DEBUG STARTUP <<platform-core: MetricsThread #0>> LegacyCsvWriter: CsvWriter: Using the existing metrics folder [ folder = '/opt/hgcapp/services-hedera/HapiApp2.0/data/stats' ]
node1 16.360s 2025-09-25 10:58:10.169 192 INFO PLATFORM_STATUS <platformForkJoinThread-1> DefaultStatusStateMachine: Platform spent 10.1 s in OBSERVING. Now in CHECKING
node1 16.361s 2025-09-25 10:58:10.170 193 INFO STARTUP <platformForkJoinThread-1> PlatformTestingToolMain: Platform Status Change CHECKING
node2 17.892s 2025-09-25 10:58:11.701 192 INFO PLATFORM_STATUS <platformForkJoinThread-4> DefaultStatusStateMachine: Platform spent 10.1 s in OBSERVING. Now in CHECKING
node2 17.893s 2025-09-25 10:58:11.702 193 INFO STARTUP <platformForkJoinThread-4> PlatformTestingToolMain: Platform Status Change CHECKING
node0 18.132s 2025-09-25 10:58:11.941 192 INFO PLATFORM_STATUS <platformForkJoinThread-4> DefaultStatusStateMachine: Platform spent 10.1 s in OBSERVING. Now in CHECKING
node0 18.133s 2025-09-25 10:58:11.942 193 INFO STARTUP <platformForkJoinThread-4> PlatformTestingToolMain: Platform Status Change CHECKING
node3 18.286s 2025-09-25 10:58:12.095 192 INFO PLATFORM_STATUS <platformForkJoinThread-4> DefaultStatusStateMachine: Platform spent 10.1 s in OBSERVING. Now in CHECKING
node3 18.287s 2025-09-25 10:58:12.096 193 INFO STARTUP <platformForkJoinThread-4> PlatformTestingToolMain: Platform Status Change CHECKING
node3 19.411s 2025-09-25 10:58:13.220 194 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: resetting transaction counters
node1 19.496s 2025-09-25 10:58:13.305 195 INFO PLATFORM_STATUS <platformForkJoinThread-7> DefaultStatusStateMachine: Platform spent 3.1 s in CHECKING. Now in ACTIVE
node1 19.496s 2025-09-25 10:58:13.305 194 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: resetting transaction counters
node1 19.497s 2025-09-25 10:58:13.306 196 INFO STARTUP <platformForkJoinThread-7> PlatformTestingToolMain: Platform Status Change ACTIVE
node0 19.506s 2025-09-25 10:58:13.315 194 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: resetting transaction counters
node1 19.579s 2025-09-25 10:58:13.388 197 TRACE STARTUP <<app: appMain 1>> PttTransactionPool: Signed Message { signatureType = 'ED25519', publicKey = '0x44876E0577E0CE94A47AC18902460D3136E0A8D7F9DCB6787158F8B8AB03CC20', privateKey ='0x44876E0577E0CE94A47AC18902460D3136E0A8D7F9DCB6787158F8B8AB03CC20', signature = '0x4FE5180448AFACF5A3CB15D6F3525F8024103D631885467462E81C58F0FF09E6D26D1E50155637AD92DB61B644D802CDC123CBBE7F2F14B5473862ACD9FC7B02',message = '0x12660A1A0801100118E01220E01228E01230E01240DFD09D9FD8F3ECF5273244000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000038014001' }
node2 19.784s 2025-09-25 10:58:13.593 194 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: resetting transaction counters
node3 20.157s 2025-09-25 10:58:13.966 195 INFO PLATFORM_STATUS <platformForkJoinThread-3> DefaultStatusStateMachine: Platform spent 1.9 s in CHECKING. Now in ACTIVE
node3 20.158s 2025-09-25 10:58:13.967 196 INFO STARTUP <platformForkJoinThread-3> PlatformTestingToolMain: Platform Status Change ACTIVE
node3 20.179s 2025-09-25 10:58:13.988 197 TRACE STARTUP <<app: appMain 3>> PttTransactionPool: Signed Message { signatureType = 'ED25519', publicKey = '0x1DCFC865743D8D5CD99EA748CDC3F3E0439067C6DA0998EC67B15C5E26AA5C57', privateKey ='0x1DCFC865743D8D5CD99EA748CDC3F3E0439067C6DA0998EC67B15C5E26AA5C57', signature = '0xF5822BEDD90C64C8D56EE027693012A68F4369F7274E81E2C9D937E329BA9BB1142F4213317BB3A1DA59F6C07E1ECCFB58028199852F993DE9E26119ED15DA08',message = '0x1A6B0A1B08031003180120A18D0628914E30914E409AFAC69DC1BFA4C3F4012A4900000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000D00103' }
node2 20.322s 2025-09-25 10:58:14.131 195 INFO PLATFORM_STATUS <platformForkJoinThread-2> DefaultStatusStateMachine: Platform spent 2.4 s in CHECKING. Now in ACTIVE
node2 20.323s 2025-09-25 10:58:14.132 196 INFO STARTUP <platformForkJoinThread-2> PlatformTestingToolMain: Platform Status Change ACTIVE
node2 20.333s 2025-09-25 10:58:14.142 197 TRACE STARTUP <<app: appMain 2>> PttTransactionPool: Signed Message { signatureType = 'ED25519', publicKey = '0xDAB44B0D3A4CF2F26053A06B289AFA38A365C844B3C6BCBB09FF6BDBD494B396', privateKey ='0xDAB44B0D3A4CF2F26053A06B289AFA38A365C844B3C6BCBB09FF6BDBD494B396', signature = '0x88714C187098182C3B10355013012909BC0850F09C35F0FB6E2DB92AA7CBD39972219A95FB7E68A0381E6D902DD57BDC9C7CA13DEC044AFA64AFB62441AFD607',message = '0x1A6B0A1A08021002180120A18D0628914E30914E4091C0CDCAA590CEDB2B2A4A0000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000D00102' }
node3 20.668s 2025-09-25 10:58:14.477 200 INFO STATE_TO_DISK <<scheduler TransactionHandler>> DefaultSavedStateController: Signed state from round 3 created, will eventually be written to disk, for reason: FIRST_ROUND_AFTER_GENESIS
node0 20.821s 2025-09-25 10:58:14.630 196 INFO PLATFORM_STATUS <platformForkJoinThread-7> DefaultStatusStateMachine: Platform spent 2.7 s in CHECKING. Now in ACTIVE
node0 20.822s 2025-09-25 10:58:14.631 197 INFO STARTUP <platformForkJoinThread-7> PlatformTestingToolMain: Platform Status Change ACTIVE
node0 20.824s 2025-09-25 10:58:14.633 199 INFO STATE_TO_DISK <<scheduler TransactionHandler>> DefaultSavedStateController: Signed state from round 3 created, will eventually be written to disk, for reason: FIRST_ROUND_AFTER_GENESIS
node0 20.860s 2025-09-25 10:58:14.669 208 TRACE STARTUP <<app: appMain 0>> PttTransactionPool: Signed Message { signatureType = 'ED25519', publicKey = '0xBC51CB72436A0C9743986A743D928D53896B565D0EE9C2DA96B7D826E4B8C573', privateKey ='0xBC51CB72436A0C9743986A743D928D53896B565D0EE9C2DA96B7D826E4B8C573', signature = '0x6C305B5514CE6C974341558C83E109D74CA52240036E0DB2BE0FAC0F64E172B4A332E84BD6789FFC9E7BA14C1FAB4161631A429A22F4F6FC9EEF725CA2E77500',message = '0x12660A0A40E5D5CABED8E6E6DD14325600000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000004001' }
node2 20.916s 2025-09-25 10:58:14.725 200 INFO STATE_TO_DISK <<scheduler TransactionHandler>> DefaultSavedStateController: Signed state from round 3 created, will eventually be written to disk, for reason: FIRST_ROUND_AFTER_GENESIS
node1 21.113s 2025-09-25 10:58:14.922 200 INFO STATE_TO_DISK <<scheduler TransactionHandler>> DefaultSavedStateController: Signed state from round 3 created, will eventually be written to disk, for reason: FIRST_ROUND_AFTER_GENESIS
node1 21.157s 2025-09-25 10:58:14.966 209 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Started writing round 3 state to disk. Reason: FIRST_ROUND_AFTER_GENESIS, directory: /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/com.swirlds.demo.platform.PlatformTestingToolMain/1/123/3
node1 21.159s 2025-09-25 10:58:14.968 210 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> MerkleTreeSnapshotWriter: Creating a snapshot on demand in /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/swirlds-tmp/1 for round 3
node0 21.376s 2025-09-25 10:58:15.185 209 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Started writing round 3 state to disk. Reason: FIRST_ROUND_AFTER_GENESIS, directory: /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/com.swirlds.demo.platform.PlatformTestingToolMain/0/123/3
node0 21.379s 2025-09-25 10:58:15.188 210 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> MerkleTreeSnapshotWriter: Creating a snapshot on demand in /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/swirlds-tmp/1 for round 3
node2 21.388s 2025-09-25 10:58:15.197 209 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Started writing round 3 state to disk. Reason: FIRST_ROUND_AFTER_GENESIS, directory: /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/com.swirlds.demo.platform.PlatformTestingToolMain/2/123/3
node2 21.391s 2025-09-25 10:58:15.200 210 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> MerkleTreeSnapshotWriter: Creating a snapshot on demand in /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/swirlds-tmp/1 for round 3
node1 21.411s 2025-09-25 10:58:15.220 259 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> MerkleTreeSnapshotWriter: Successfully created a snapshot on demand in /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/swirlds-tmp/1 for round 3
node1 21.414s 2025-09-25 10:58:15.223 260 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Information for state written to disk:
Round: 3 Timestamp: 2025-09-25T10:58:13.211468Z Next consensus number: 25 Legacy running event hash: 138fe123d8ecb9ae6a1ba17213fd0ecb2a4b5215923c4131595820b0b75d3dfead8cf37f03f803a936e2248fd3df2bb5 Legacy running event mnemonic: impose-sweet-tip-ski Rounds non-ancient: 26 Creation version: SemanticVersion[major=1, minor=0, patch=0, pre=, build=] Minimum judge hash code: 2044907021 Root hash: b72cfe7e888ee2bdd778356c82f8624e6cefd32c602258ce27174c10497e51db6a6711d06480fe20808cd23f9aab5cbe First BR Version: SemanticVersion[major=0, minor=0, patch=0, pre=, build=] Last round before BR: 0 Lowest Judge Gen before BR 0 (root) PlatformTestingToolState / box-core-unusual-position 0 SingletonNode PlatformStateService.PLATFORM_STATE /0 affair-over-identify-select 1 VirtualMap RosterService.ROSTERS /1 step-utility-truth-odor 2 SingletonNode RosterService.ROSTER_STATE /2 anger-shell-eyebrow-boring 3 PayloadCfgSimple /3 potato-pill-swear-author 4 NextSeqConsList /4 sniff-wear-know-arrow 5 FCMFamily /5 coconut-machine-scrap-dish 0 MerkleMap /5/0 fall-topple-alarm-witness 1 MerkleMap /5/1 fall-topple-alarm-witness 6 TransactionCounterList /6 talk-valve-shift-secret 7 IssLeaf /7 biology-capable-expose-purpose 8 NftLedger /8 chicken-butter-size-happy 0 MerkleMap /8/0 fall-topple-alarm-witness 1 null /8/1 bid-belt-culture-decorate 9 VirtualMap accounts /9 moment-congress-mom-belt 10 null /10 bid-belt-culture-decorate 11 null /11 bid-belt-culture-decorate 12 QuorumResult /12 globe-math-mass-loop
node1 21.442s 2025-09-25 10:58:15.251 261 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Found 1 preconsensus file on disk.
File: data/saved/preconsensus-events/1/2025/09/25/2025-09-25T10+58+10.201634750Z_seq0_minr1_maxr501_orgn0.pces
node1 21.443s 2025-09-25 10:58:15.252 262 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Found 1 preconsensus event file meeting specified criteria to copy.
Lower bound: 1 File: data/saved/preconsensus-events/1/2025/09/25/2025-09-25T10+58+10.201634750Z_seq0_minr1_maxr501_orgn0.pces
node1 21.443s 2025-09-25 10:58:15.252 263 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Copying 1 preconsensus event file(s)
node1 21.444s 2025-09-25 10:58:15.253 264 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Finished copying 1 preconsensus event file(s)
node1 21.449s 2025-09-25 10:58:15.258 265 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Finished writing state for round 3 to disk. Reason: FIRST_ROUND_AFTER_GENESIS, directory: /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/com.swirlds.demo.platform.PlatformTestingToolMain/1/123/3 {"round":3,"freezeState":false,"reason":"FIRST_ROUND_AFTER_GENESIS","directory":"file:///opt/hgcapp/services-hedera/HapiApp2.0/data/saved/com.swirlds.demo.platform.PlatformTestingToolMain/1/123/3/"} [com.swirlds.logging.legacy.payload.StateSavedToDiskPayload]
node3 21.455s 2025-09-25 10:58:15.264 209 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Started writing round 3 state to disk. Reason: FIRST_ROUND_AFTER_GENESIS, directory: /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/com.swirlds.demo.platform.PlatformTestingToolMain/3/123/3
node3 21.457s 2025-09-25 10:58:15.266 210 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> MerkleTreeSnapshotWriter: Creating a snapshot on demand in /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/swirlds-tmp/1 for round 3
node0 21.655s 2025-09-25 10:58:15.464 259 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> MerkleTreeSnapshotWriter: Successfully created a snapshot on demand in /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/swirlds-tmp/1 for round 3
node0 21.659s 2025-09-25 10:58:15.468 260 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Information for state written to disk:
Round: 3 Timestamp: 2025-09-25T10:58:13.211468Z Next consensus number: 25 Legacy running event hash: 138fe123d8ecb9ae6a1ba17213fd0ecb2a4b5215923c4131595820b0b75d3dfead8cf37f03f803a936e2248fd3df2bb5 Legacy running event mnemonic: impose-sweet-tip-ski Rounds non-ancient: 26 Creation version: SemanticVersion[major=1, minor=0, patch=0, pre=, build=] Minimum judge hash code: 2044907021 Root hash: b72cfe7e888ee2bdd778356c82f8624e6cefd32c602258ce27174c10497e51db6a6711d06480fe20808cd23f9aab5cbe First BR Version: SemanticVersion[major=0, minor=0, patch=0, pre=, build=] Last round before BR: 0 Lowest Judge Gen before BR 0 (root) PlatformTestingToolState / box-core-unusual-position 0 SingletonNode PlatformStateService.PLATFORM_STATE /0 affair-over-identify-select 1 VirtualMap RosterService.ROSTERS /1 step-utility-truth-odor 2 SingletonNode RosterService.ROSTER_STATE /2 anger-shell-eyebrow-boring 3 PayloadCfgSimple /3 potato-pill-swear-author 4 NextSeqConsList /4 sniff-wear-know-arrow 5 FCMFamily /5 coconut-machine-scrap-dish 0 MerkleMap /5/0 fall-topple-alarm-witness 1 MerkleMap /5/1 fall-topple-alarm-witness 6 TransactionCounterList /6 talk-valve-shift-secret 7 IssLeaf /7 biology-capable-expose-purpose 8 NftLedger /8 chicken-butter-size-happy 0 MerkleMap /8/0 fall-topple-alarm-witness 1 null /8/1 bid-belt-culture-decorate 9 VirtualMap accounts /9 moment-congress-mom-belt 10 null /10 bid-belt-culture-decorate 11 null /11 bid-belt-culture-decorate 12 QuorumResult /12 globe-math-mass-loop
node2 21.672s 2025-09-25 10:58:15.481 259 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> MerkleTreeSnapshotWriter: Successfully created a snapshot on demand in /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/swirlds-tmp/1 for round 3
node2 21.675s 2025-09-25 10:58:15.484 260 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Information for state written to disk:
Round: 3 Timestamp: 2025-09-25T10:58:13.211468Z Next consensus number: 25 Legacy running event hash: 138fe123d8ecb9ae6a1ba17213fd0ecb2a4b5215923c4131595820b0b75d3dfead8cf37f03f803a936e2248fd3df2bb5 Legacy running event mnemonic: impose-sweet-tip-ski Rounds non-ancient: 26 Creation version: SemanticVersion[major=1, minor=0, patch=0, pre=, build=] Minimum judge hash code: 2044907021 Root hash: b72cfe7e888ee2bdd778356c82f8624e6cefd32c602258ce27174c10497e51db6a6711d06480fe20808cd23f9aab5cbe First BR Version: SemanticVersion[major=0, minor=0, patch=0, pre=, build=] Last round before BR: 0 Lowest Judge Gen before BR 0 (root) PlatformTestingToolState / box-core-unusual-position 0 SingletonNode PlatformStateService.PLATFORM_STATE /0 affair-over-identify-select 1 VirtualMap RosterService.ROSTERS /1 step-utility-truth-odor 2 SingletonNode RosterService.ROSTER_STATE /2 anger-shell-eyebrow-boring 3 PayloadCfgSimple /3 potato-pill-swear-author 4 NextSeqConsList /4 sniff-wear-know-arrow 5 FCMFamily /5 coconut-machine-scrap-dish 0 MerkleMap /5/0 fall-topple-alarm-witness 1 MerkleMap /5/1 fall-topple-alarm-witness 6 TransactionCounterList /6 talk-valve-shift-secret 7 IssLeaf /7 biology-capable-expose-purpose 8 NftLedger /8 chicken-butter-size-happy 0 MerkleMap /8/0 fall-topple-alarm-witness 1 null /8/1 bid-belt-culture-decorate 9 VirtualMap accounts /9 moment-congress-mom-belt 10 null /10 bid-belt-culture-decorate 11 null /11 bid-belt-culture-decorate 12 QuorumResult /12 globe-math-mass-loop
node0 21.696s 2025-09-25 10:58:15.505 261 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Found 1 preconsensus file on disk.
File: data/saved/preconsensus-events/0/2025/09/25/2025-09-25T10+58+10.614382749Z_seq0_minr1_maxr501_orgn0.pces
node0 21.697s 2025-09-25 10:58:15.506 262 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Found 1 preconsensus event file meeting specified criteria to copy.
Lower bound: 1 File: data/saved/preconsensus-events/0/2025/09/25/2025-09-25T10+58+10.614382749Z_seq0_minr1_maxr501_orgn0.pces
node0 21.697s 2025-09-25 10:58:15.506 263 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Copying 1 preconsensus event file(s)
node0 21.698s 2025-09-25 10:58:15.507 264 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Finished copying 1 preconsensus event file(s)
node0 21.703s 2025-09-25 10:58:15.512 265 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Finished writing state for round 3 to disk. Reason: FIRST_ROUND_AFTER_GENESIS, directory: /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/com.swirlds.demo.platform.PlatformTestingToolMain/0/123/3 {"round":3,"freezeState":false,"reason":"FIRST_ROUND_AFTER_GENESIS","directory":"file:///opt/hgcapp/services-hedera/HapiApp2.0/data/saved/com.swirlds.demo.platform.PlatformTestingToolMain/0/123/3/"} [com.swirlds.logging.legacy.payload.StateSavedToDiskPayload]
node2 21.708s 2025-09-25 10:58:15.517 261 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Found 1 preconsensus file on disk.
File: data/saved/preconsensus-events/2/2025/09/25/2025-09-25T10+58+10.335157732Z_seq0_minr1_maxr501_orgn0.pces
node2 21.709s 2025-09-25 10:58:15.518 262 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Found 1 preconsensus event file meeting specified criteria to copy.
Lower bound: 1 File: data/saved/preconsensus-events/2/2025/09/25/2025-09-25T10+58+10.335157732Z_seq0_minr1_maxr501_orgn0.pces
node2 21.709s 2025-09-25 10:58:15.518 263 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Copying 1 preconsensus event file(s)
node2 21.711s 2025-09-25 10:58:15.520 264 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Finished copying 1 preconsensus event file(s)
node2 21.715s 2025-09-25 10:58:15.524 265 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Finished writing state for round 3 to disk. Reason: FIRST_ROUND_AFTER_GENESIS, directory: /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/com.swirlds.demo.platform.PlatformTestingToolMain/2/123/3 {"round":3,"freezeState":false,"reason":"FIRST_ROUND_AFTER_GENESIS","directory":"file:///opt/hgcapp/services-hedera/HapiApp2.0/data/saved/com.swirlds.demo.platform.PlatformTestingToolMain/2/123/3/"} [com.swirlds.logging.legacy.payload.StateSavedToDiskPayload]
node3 21.750s 2025-09-25 10:58:15.559 259 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> MerkleTreeSnapshotWriter: Successfully created a snapshot on demand in /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/swirlds-tmp/1 for round 3
node3 21.754s 2025-09-25 10:58:15.563 260 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Information for state written to disk:
Round: 3 Timestamp: 2025-09-25T10:58:13.211468Z Next consensus number: 25 Legacy running event hash: 138fe123d8ecb9ae6a1ba17213fd0ecb2a4b5215923c4131595820b0b75d3dfead8cf37f03f803a936e2248fd3df2bb5 Legacy running event mnemonic: impose-sweet-tip-ski Rounds non-ancient: 26 Creation version: SemanticVersion[major=1, minor=0, patch=0, pre=, build=] Minimum judge hash code: 2044907021 Root hash: b72cfe7e888ee2bdd778356c82f8624e6cefd32c602258ce27174c10497e51db6a6711d06480fe20808cd23f9aab5cbe First BR Version: SemanticVersion[major=0, minor=0, patch=0, pre=, build=] Last round before BR: 0 Lowest Judge Gen before BR 0 (root) PlatformTestingToolState / box-core-unusual-position 0 SingletonNode PlatformStateService.PLATFORM_STATE /0 affair-over-identify-select 1 VirtualMap RosterService.ROSTERS /1 step-utility-truth-odor 2 SingletonNode RosterService.ROSTER_STATE /2 anger-shell-eyebrow-boring 3 PayloadCfgSimple /3 potato-pill-swear-author 4 NextSeqConsList /4 sniff-wear-know-arrow 5 FCMFamily /5 coconut-machine-scrap-dish 0 MerkleMap /5/0 fall-topple-alarm-witness 1 MerkleMap /5/1 fall-topple-alarm-witness 6 TransactionCounterList /6 talk-valve-shift-secret 7 IssLeaf /7 biology-capable-expose-purpose 8 NftLedger /8 chicken-butter-size-happy 0 MerkleMap /8/0 fall-topple-alarm-witness 1 null /8/1 bid-belt-culture-decorate 9 VirtualMap accounts /9 moment-congress-mom-belt 10 null /10 bid-belt-culture-decorate 11 null /11 bid-belt-culture-decorate 12 QuorumResult /12 globe-math-mass-loop
node3 21.790s 2025-09-25 10:58:15.599 261 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Found 1 preconsensus file on disk.
File: data/saved/preconsensus-events/3/2025/09/25/2025-09-25T10+58+10.651513376Z_seq0_minr1_maxr501_orgn0.pces
node3 21.791s 2025-09-25 10:58:15.600 262 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Found 1 preconsensus event file meeting specified criteria to copy.
Lower bound: 1 File: data/saved/preconsensus-events/3/2025/09/25/2025-09-25T10+58+10.651513376Z_seq0_minr1_maxr501_orgn0.pces
node3 21.791s 2025-09-25 10:58:15.600 263 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Copying 1 preconsensus event file(s)
node3 21.792s 2025-09-25 10:58:15.601 264 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Finished copying 1 preconsensus event file(s)
node3 21.797s 2025-09-25 10:58:15.606 265 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Finished writing state for round 3 to disk. Reason: FIRST_ROUND_AFTER_GENESIS, directory: /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/com.swirlds.demo.platform.PlatformTestingToolMain/3/123/3 {"round":3,"freezeState":false,"reason":"FIRST_ROUND_AFTER_GENESIS","directory":"file:///opt/hgcapp/services-hedera/HapiApp2.0/data/saved/com.swirlds.demo.platform.PlatformTestingToolMain/3/123/3/"} [com.swirlds.logging.legacy.payload.StateSavedToDiskPayload]
node1 25.941s 2025-09-25 10:58:19.750 290 INFO DEMO_INFO <<app: appMain 1>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_CREATE transactions
node3 26.691s 2025-09-25 10:58:20.500 290 INFO DEMO_INFO <<app: appMain 3>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_CREATE transactions
node2 26.704s 2025-09-25 10:58:20.513 290 INFO DEMO_INFO <<app: appMain 2>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_CREATE transactions
node0 27.117s 2025-09-25 10:58:20.926 294 INFO DEMO_INFO <<app: appMain 0>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_CREATE transactions
node1 27.507s 2025-09-25 10:58:21.316 303 INFO DEMO_INFO <<app: appMain 1>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_DELETE transactions
node3 28.259s 2025-09-25 10:58:22.068 303 INFO DEMO_INFO <<app: appMain 3>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_DELETE transactions
node2 28.428s 2025-09-25 10:58:22.237 303 INFO DEMO_INFO <<app: appMain 2>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_DELETE transactions
node0 28.593s 2025-09-25 10:58:22.402 307 INFO DEMO_INFO <<app: appMain 0>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_DELETE transactions
node1 59.458s 2025-09-25 10:58:53.267 496 INFO DEMO_INFO <<app: appMain 1>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_UPDATE transactions
node3 1.001m 2025-09-25 10:58:53.895 496 INFO DEMO_INFO <<app: appMain 3>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_UPDATE transactions
node2 1m 1.071s 2025-09-25 10:58:54.880 500 INFO DEMO_INFO <<app: appMain 2>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_UPDATE transactions
node0 1m 1.133s 2025-09-25 10:58:54.942 500 INFO DEMO_INFO <<app: appMain 0>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_UPDATE transactions
node2 1m 23.751s 2025-09-25 10:59:17.560 641 INFO DEMO_INFO <<app: appMain 2>> FCMTransactionPool: Generated enough FCM transaction for type TYPE_FCM_CREATE
node1 1m 23.778s 2025-09-25 10:59:17.587 645 INFO DEMO_INFO <<app: appMain 1>> FCMTransactionPool: Generated enough FCM transaction for type TYPE_FCM_CREATE
node1 1m 23.780s 2025-09-25 10:59:17.589 646 INFO DEMO_INFO <<app: appMain 1>> FCMTransactionPool: Generated enough FCM transaction for type SAVE_EXPECTED_MAP
node1 1m 23.782s 2025-09-25 10:59:17.591 647 INFO DEMO_INFO <<app: appMain 1>> FCMTransactionPool: node1 submits a transaction SAVE_EXPECTED_MAP
node2 1m 23.803s 2025-09-25 10:59:17.612 642 INFO DEMO_INFO <<app: appMain 2>> FCMTransactionPool: Generated enough FCM transaction for type SAVE_EXPECTED_MAP
node2 1m 23.806s 2025-09-25 10:59:17.615 643 INFO DEMO_INFO <<app: appMain 2>> FCMTransactionPool: node2 submits a transaction SAVE_EXPECTED_MAP
node3 1m 24.011s 2025-09-25 10:59:17.820 645 INFO DEMO_INFO <<app: appMain 3>> FCMTransactionPool: Generated enough FCM transaction for type TYPE_FCM_CREATE
node3 1m 24.013s 2025-09-25 10:59:17.822 646 INFO DEMO_INFO <<app: appMain 3>> FCMTransactionPool: Generated enough FCM transaction for type SAVE_EXPECTED_MAP
node3 1m 24.014s 2025-09-25 10:59:17.823 647 INFO DEMO_INFO <<app: appMain 3>> FCMTransactionPool: node3 submits a transaction SAVE_EXPECTED_MAP
node0 1m 24.579s 2025-09-25 10:59:18.388 653 INFO DEMO_INFO <<app: appMain 0>> FCMTransactionPool: Generated enough FCM transaction for type TYPE_FCM_CREATE
node0 1m 24.581s 2025-09-25 10:59:18.390 654 INFO DEMO_INFO <<app: appMain 0>> FCMTransactionPool: Generated enough FCM transaction for type SAVE_EXPECTED_MAP
node0 1m 24.582s 2025-09-25 10:59:18.391 655 INFO DEMO_INFO <<app: appMain 0>> FCMTransactionPool: node0 submits a transaction SAVE_EXPECTED_MAP
node0 1m 25.681s 2025-09-25 10:59:19.490 656 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Received SAVE_EXPECTED_MAP transaction from node 1
node0 1m 25.686s 2025-09-25 10:59:19.495 657 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Received SAVE_EXPECTED_MAP transaction from node 2
node3 1m 25.725s 2025-09-25 10:59:19.534 656 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Received SAVE_EXPECTED_MAP transaction from node 1
node3 1m 25.730s 2025-09-25 10:59:19.539 657 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Received SAVE_EXPECTED_MAP transaction from node 2
node2 1m 25.752s 2025-09-25 10:59:19.561 656 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Received SAVE_EXPECTED_MAP transaction from node 1
node2 1m 25.757s 2025-09-25 10:59:19.566 657 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Received SAVE_EXPECTED_MAP transaction from node 2
node1 1m 25.975s 2025-09-25 10:59:19.784 656 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Received SAVE_EXPECTED_MAP transaction from node 1
node1 1m 25.980s 2025-09-25 10:59:19.789 657 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Received SAVE_EXPECTED_MAP transaction from node 2
node3 1m 26.333s 2025-09-25 10:59:20.142 662 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Received SAVE_EXPECTED_MAP transaction from node 3
node1 1m 26.407s 2025-09-25 10:59:20.216 670 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Received SAVE_EXPECTED_MAP transaction from node 3
node0 1m 26.516s 2025-09-25 10:59:20.325 670 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Received SAVE_EXPECTED_MAP transaction from node 3
node2 1m 26.601s 2025-09-25 10:59:20.410 670 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Received SAVE_EXPECTED_MAP transaction from node 3
node0 1m 27.094s 2025-09-25 10:59:20.903 671 INFO SAVE_EXPECTED_MAP <<scheduler TransactionHandler>> SaveExpectedMapHandler: Serializing ExpectedMap Node0000_ExpectedMap_1758797958988_988368900.json.gz
node3 1m 27.137s 2025-09-25 10:59:20.946 671 INFO SAVE_EXPECTED_MAP <<scheduler TransactionHandler>> SaveExpectedMapHandler: Serializing ExpectedMap Node0003_ExpectedMap_1758797958988_988368900.json.gz
node2 1m 27.164s 2025-09-25 10:59:20.973 671 INFO SAVE_EXPECTED_MAP <<scheduler TransactionHandler>> SaveExpectedMapHandler: Serializing ExpectedMap Node0002_ExpectedMap_1758797958988_988368900.json.gz
node1 1m 27.391s 2025-09-25 10:59:21.200 671 INFO SAVE_EXPECTED_MAP <<scheduler TransactionHandler>> SaveExpectedMapHandler: Serializing ExpectedMap Node0001_ExpectedMap_1758797958988_988368900.json.gz
node0 1m 27.408s 2025-09-25 10:59:21.217 672 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: handling SAVE_EXPECTED_MAP
node3 1m 27.438s 2025-09-25 10:59:21.247 672 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: handling SAVE_EXPECTED_MAP
node2 1m 27.466s 2025-09-25 10:59:21.275 672 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: handling SAVE_EXPECTED_MAP
node1 1m 27.684s 2025-09-25 10:59:21.493 672 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: handling SAVE_EXPECTED_MAP
node1 1m 31.492s 2025-09-25 10:59:25.301 697 INFO DEMO_INFO <<app: appMain 1>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_CREATE transactions
node3 1m 32.231s 2025-09-25 10:59:26.040 701 INFO DEMO_INFO <<app: appMain 3>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_CREATE transactions
node2 1m 32.883s 2025-09-25 10:59:26.692 709 INFO DEMO_INFO <<app: appMain 2>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_CREATE transactions
node1 1m 32.952s 2025-09-25 10:59:26.761 710 INFO DEMO_INFO <<app: appMain 1>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_DELETE transactions
node0 1m 33.815s 2025-09-25 10:59:27.624 713 INFO DEMO_INFO <<app: appMain 0>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_CREATE transactions
node3 1m 33.844s 2025-09-25 10:59:27.653 714 INFO DEMO_INFO <<app: appMain 3>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_DELETE transactions
node2 1m 34.546s 2025-09-25 10:59:28.355 722 INFO DEMO_INFO <<app: appMain 2>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_DELETE transactions
node0 1m 35.328s 2025-09-25 10:59:29.137 722 INFO DEMO_INFO <<app: appMain 0>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_DELETE transactions
node1 2m 5.122s 2025-09-25 10:59:58.931 939 INFO DEMO_INFO <<app: appMain 1>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_UPDATE transactions
node1 2m 5.123s 2025-09-25 10:59:58.932 940 INFO DEMO_INFO <<app: appMain 1>> PttTransactionPool: Generated enough virtual merkle test for sequential mode
node1 2m 5.123s 2025-09-25 10:59:58.932 941 INFO DEMO_INFO <<app: appMain 1>> PlatformTestingToolMain: Stop generating transactions
node1 2m 5.125s 2025-09-25 10:59:58.934 942 INFO DEMO_INFO <<app: appMain 1>> PlatformTestingToolMain: node 1 sent ENTER_VALIDATION Message
node1 2m 5.125s 2025-09-25 10:59:58.934 943 INFO DEMO_INFO <<app: appMain 1>> PlatformTestingToolMain: Node 1 finished generating all transactions.
node3 2m 5.271s 2025-09-25 10:59:59.080 939 INFO DEMO_INFO <<app: appMain 3>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_UPDATE transactions
node3 2m 5.272s 2025-09-25 10:59:59.081 940 INFO DEMO_INFO <<app: appMain 3>> PttTransactionPool: Generated enough virtual merkle test for sequential mode
node3 2m 5.273s 2025-09-25 10:59:59.082 941 INFO DEMO_INFO <<app: appMain 3>> PlatformTestingToolMain: Stop generating transactions
node3 2m 5.275s 2025-09-25 10:59:59.084 942 INFO DEMO_INFO <<app: appMain 3>> PlatformTestingToolMain: node 3 sent ENTER_VALIDATION Message
node3 2m 5.276s 2025-09-25 10:59:59.085 943 INFO DEMO_INFO <<app: appMain 3>> PlatformTestingToolMain: Node 3 finished generating all transactions.
node2 2m 6.440s 2025-09-25 11:00:00.249 943 INFO DEMO_INFO <<app: appMain 2>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_UPDATE transactions
node2 2m 6.491s 2025-09-25 11:00:00.300 944 INFO DEMO_INFO <<app: appMain 2>> PttTransactionPool: Generated enough virtual merkle test for sequential mode
node2 2m 6.492s 2025-09-25 11:00:00.301 945 INFO DEMO_INFO <<app: appMain 2>> PlatformTestingToolMain: Stop generating transactions
node2 2m 6.492s 2025-09-25 11:00:00.301 946 INFO DEMO_INFO <<app: appMain 2>> PlatformTestingToolMain: node 2 sent ENTER_VALIDATION Message
node2 2m 6.492s 2025-09-25 11:00:00.301 947 INFO DEMO_INFO <<app: appMain 2>> PlatformTestingToolMain: Node 2 finished generating all transactions.
node0 2m 6.992s 2025-09-25 11:00:00.801 951 INFO DEMO_INFO <<app: appMain 0>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_UPDATE transactions
node0 2m 7.044s 2025-09-25 11:00:00.853 952 INFO DEMO_INFO <<app: appMain 0>> PttTransactionPool: Generated enough virtual merkle test for sequential mode
node0 2m 7.045s 2025-09-25 11:00:00.854 953 INFO DEMO_INFO <<app: appMain 0>> PlatformTestingToolMain: Stop generating transactions
node0 2m 7.045s 2025-09-25 11:00:00.854 954 INFO DEMO_INFO <<app: appMain 0>> PlatformTestingToolMain: node 0 sent ENTER_VALIDATION Message
node0 2m 7.045s 2025-09-25 11:00:00.854 955 INFO DEMO_INFO <<app: appMain 0>> PlatformTestingToolMain: Node 0 finished generating all transactions.
node3 2m 7.127s 2025-09-25 11:00:00.936 956 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 3, type = ENTER_VALIDATION, consensusTimestamp = 2025-09-25T10:59:59.441782466Z ]
node3 2m 7.128s 2025-09-25 11:00:00.937 957 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 3 apply state ControlAction[timestamp=2025-09-25T10:59:59.441782466Z,type=ENTER_VALIDATION]
node0 2m 7.256s 2025-09-25 11:00:01.065 956 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 3, type = ENTER_VALIDATION, consensusTimestamp = 2025-09-25T10:59:59.441782466Z ]
node0 2m 7.257s 2025-09-25 11:00:01.066 957 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 3 apply state ControlAction[timestamp=2025-09-25T10:59:59.441782466Z,type=ENTER_VALIDATION]
node1 2m 7.303s 2025-09-25 11:00:01.112 956 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 3, type = ENTER_VALIDATION, consensusTimestamp = 2025-09-25T10:59:59.441782466Z ]
node1 2m 7.304s 2025-09-25 11:00:01.113 957 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 3 apply state ControlAction[timestamp=2025-09-25T10:59:59.441782466Z,type=ENTER_VALIDATION]
node2 2m 7.347s 2025-09-25 11:00:01.156 956 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 3, type = ENTER_VALIDATION, consensusTimestamp = 2025-09-25T10:59:59.441782466Z ]
node2 2m 7.347s 2025-09-25 11:00:01.156 957 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 3 apply state ControlAction[timestamp=2025-09-25T10:59:59.441782466Z,type=ENTER_VALIDATION]
node3 2m 7.662s 2025-09-25 11:00:01.471 958 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 1, type = ENTER_VALIDATION, consensusTimestamp = 2025-09-25T10:59:59.758769Z ]
node3 2m 7.662s 2025-09-25 11:00:01.471 959 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 1 apply state ControlAction[timestamp=2025-09-25T10:59:59.758769Z,type=ENTER_VALIDATION]
node3 2m 7.665s 2025-09-25 11:00:01.474 960 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 2, type = ENTER_VALIDATION, consensusTimestamp = 2025-09-25T11:00:00.494384645Z ]
node3 2m 7.665s 2025-09-25 11:00:01.474 961 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 2 apply state ControlAction[timestamp=2025-09-25T11:00:00.494384645Z,type=ENTER_VALIDATION]
node3 2m 7.666s 2025-09-25 11:00:01.475 964 INFO STATE_TO_DISK <<scheduler TransactionHandler>> DefaultSavedStateController: Signed state from round 174 created, will eventually be written to disk, for reason: PERIODIC_SNAPSHOT
node0 2m 7.709s 2025-09-25 11:00:01.518 958 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 1, type = ENTER_VALIDATION, consensusTimestamp = 2025-09-25T10:59:59.758769Z ]
node0 2m 7.710s 2025-09-25 11:00:01.519 959 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 1 apply state ControlAction[timestamp=2025-09-25T10:59:59.758769Z,type=ENTER_VALIDATION]
node0 2m 7.713s 2025-09-25 11:00:01.522 960 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 2, type = ENTER_VALIDATION, consensusTimestamp = 2025-09-25T11:00:00.494384645Z ]
node0 2m 7.713s 2025-09-25 11:00:01.522 961 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 2 apply state ControlAction[timestamp=2025-09-25T11:00:00.494384645Z,type=ENTER_VALIDATION]
node0 2m 7.714s 2025-09-25 11:00:01.523 964 INFO STATE_TO_DISK <<scheduler TransactionHandler>> DefaultSavedStateController: Signed state from round 174 created, will eventually be written to disk, for reason: PERIODIC_SNAPSHOT
node2 2m 7.905s 2025-09-25 11:00:01.714 958 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 1, type = ENTER_VALIDATION, consensusTimestamp = 2025-09-25T10:59:59.758769Z ]
node2 2m 7.906s 2025-09-25 11:00:01.715 959 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 1 apply state ControlAction[timestamp=2025-09-25T10:59:59.758769Z,type=ENTER_VALIDATION]
node2 2m 7.908s 2025-09-25 11:00:01.717 960 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 2, type = ENTER_VALIDATION, consensusTimestamp = 2025-09-25T11:00:00.494384645Z ]
node2 2m 7.909s 2025-09-25 11:00:01.718 961 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 2 apply state ControlAction[timestamp=2025-09-25T11:00:00.494384645Z,type=ENTER_VALIDATION]
node2 2m 7.909s 2025-09-25 11:00:01.718 964 INFO STATE_TO_DISK <<scheduler TransactionHandler>> DefaultSavedStateController: Signed state from round 174 created, will eventually be written to disk, for reason: PERIODIC_SNAPSHOT
node2 2m 7.977s 2025-09-25 11:00:01.786 967 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Started writing round 174 state to disk. Reason: PERIODIC_SNAPSHOT, directory: /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/com.swirlds.demo.platform.PlatformTestingToolMain/2/123/174
node2 2m 7.978s 2025-09-25 11:00:01.787 968 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> MerkleTreeSnapshotWriter: Creating a snapshot on demand in /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/swirlds-tmp/9 for round 174
node3 2m 8.095s 2025-09-25 11:00:01.904 967 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Started writing round 174 state to disk. Reason: PERIODIC_SNAPSHOT, directory: /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/com.swirlds.demo.platform.PlatformTestingToolMain/3/123/174
node3 2m 8.096s 2025-09-25 11:00:01.905 968 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> MerkleTreeSnapshotWriter: Creating a snapshot on demand in /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/swirlds-tmp/9 for round 174
node0 2m 8.236s 2025-09-25 11:00:02.045 967 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Started writing round 174 state to disk. Reason: PERIODIC_SNAPSHOT, directory: /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/com.swirlds.demo.platform.PlatformTestingToolMain/0/123/174
node0 2m 8.237s 2025-09-25 11:00:02.046 968 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> MerkleTreeSnapshotWriter: Creating a snapshot on demand in /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/swirlds-tmp/9 for round 174
node1 2m 8.284s 2025-09-25 11:00:02.093 958 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 1, type = ENTER_VALIDATION, consensusTimestamp = 2025-09-25T10:59:59.758769Z ]
node1 2m 8.284s 2025-09-25 11:00:02.093 959 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 1 apply state ControlAction[timestamp=2025-09-25T10:59:59.758769Z,type=ENTER_VALIDATION]
node1 2m 8.287s 2025-09-25 11:00:02.096 960 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 2, type = ENTER_VALIDATION, consensusTimestamp = 2025-09-25T11:00:00.494384645Z ]
node1 2m 8.287s 2025-09-25 11:00:02.096 961 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 2 apply state ControlAction[timestamp=2025-09-25T11:00:00.494384645Z,type=ENTER_VALIDATION]
node1 2m 8.288s 2025-09-25 11:00:02.097 964 INFO STATE_TO_DISK <<scheduler TransactionHandler>> DefaultSavedStateController: Signed state from round 174 created, will eventually be written to disk, for reason: PERIODIC_SNAPSHOT
node1 2m 8.291s 2025-09-25 11:00:02.100 967 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Started writing round 174 state to disk. Reason: PERIODIC_SNAPSHOT, directory: /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/com.swirlds.demo.platform.PlatformTestingToolMain/1/123/174
node1 2m 8.292s 2025-09-25 11:00:02.101 968 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> MerkleTreeSnapshotWriter: Creating a snapshot on demand in /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/swirlds-tmp/9 for round 174
node2 2m 8.372s 2025-09-25 11:00:02.181 1028 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> MerkleTreeSnapshotWriter: Successfully created a snapshot on demand in /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/swirlds-tmp/9 for round 174
node2 2m 8.375s 2025-09-25 11:00:02.184 1029 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Information for state written to disk:
Round: 174 Timestamp: 2025-09-25T11:00:00.494384645Z Next consensus number: 2772 Legacy running event hash: 551ff2e61f30025ba50faee58cf81664350cd3271fa645140e0fb3f3b505944622828d4c9daf00dca951f380cf7dc06f Legacy running event mnemonic: physical-person-toward-enter Rounds non-ancient: 26 Creation version: SemanticVersion[major=1, minor=0, patch=0, pre=, build=] Minimum judge hash code: 964658043 Root hash: 6aae8d14a5c0361a4f080b5461303a54570fa7e262bb44d65714d3ac8eae5b1c8a48656afafa15d2c3ea211400f44562 First BR Version: SemanticVersion[major=0, minor=0, patch=0, pre=, build=] Last round before BR: 0 Lowest Judge Gen before BR 0 (root) PlatformTestingToolState / fiction-frown-elite-select 0 SingletonNode PlatformStateService.PLATFORM_STATE /0 all-baby-flag-begin 1 VirtualMap RosterService.ROSTERS /1 step-utility-truth-odor 2 SingletonNode RosterService.ROSTER_STATE /2 anger-shell-eyebrow-boring 3 PayloadCfgSimple /3 potato-pill-swear-author 4 NextSeqConsList /4 sniff-wear-know-arrow 5 FCMFamily /5 owner-machine-cabbage-valve 0 MerkleMap /5/0 come-picnic-rich-screen 1 MerkleMap /5/1 fall-topple-alarm-witness 6 TransactionCounterList /6 voyage-casual-ceiling-observe 7 IssLeaf /7 biology-capable-expose-purpose 8 NftLedger /8 chicken-butter-size-happy 0 MerkleMap /8/0 fall-topple-alarm-witness 1 null /8/1 bid-belt-culture-decorate 9 VirtualMap accounts /9 rain-album-lawn-machine 10 null /10 bid-belt-culture-decorate 11 null /11 bid-belt-culture-decorate 12 QuorumResult /12 judge-chase-era-faith
node2 2m 8.382s 2025-09-25 11:00:02.191 1030 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Found 2 preconsensus files on disk.
First file: data/saved/preconsensus-events/2/2025/09/25/2025-09-25T10+58+10.335157732Z_seq0_minr1_maxr137_orgn0.pces Last file: data/saved/preconsensus-events/2/2025/09/25/2025-09-25T10+59+41.807478453Z_seq1_minr109_maxr272_orgn0.pces
node2 2m 8.382s 2025-09-25 11:00:02.191 1031 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Found 1 preconsensus event file meeting specified criteria to copy.
Lower bound: 147 File: data/saved/preconsensus-events/2/2025/09/25/2025-09-25T10+59+41.807478453Z_seq1_minr109_maxr272_orgn0.pces
node2 2m 8.382s 2025-09-25 11:00:02.191 1032 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Copying 1 preconsensus event file(s)
node2 2m 8.385s 2025-09-25 11:00:02.194 1033 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Finished copying 1 preconsensus event file(s)
node2 2m 8.386s 2025-09-25 11:00:02.195 1034 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Finished writing state for round 174 to disk. Reason: PERIODIC_SNAPSHOT, directory: /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/com.swirlds.demo.platform.PlatformTestingToolMain/2/123/174 {"round":174,"freezeState":false,"reason":"PERIODIC_SNAPSHOT","directory":"file:///opt/hgcapp/services-hedera/HapiApp2.0/data/saved/com.swirlds.demo.platform.PlatformTestingToolMain/2/123/174/"} [com.swirlds.logging.legacy.payload.StateSavedToDiskPayload]
node3 2m 8.494s 2025-09-25 11:00:02.303 1028 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> MerkleTreeSnapshotWriter: Successfully created a snapshot on demand in /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/swirlds-tmp/9 for round 174
node3 2m 8.497s 2025-09-25 11:00:02.306 1029 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Information for state written to disk:
Round: 174 Timestamp: 2025-09-25T11:00:00.494384645Z Next consensus number: 2772 Legacy running event hash: 551ff2e61f30025ba50faee58cf81664350cd3271fa645140e0fb3f3b505944622828d4c9daf00dca951f380cf7dc06f Legacy running event mnemonic: physical-person-toward-enter Rounds non-ancient: 26 Creation version: SemanticVersion[major=1, minor=0, patch=0, pre=, build=] Minimum judge hash code: 964658043 Root hash: 6aae8d14a5c0361a4f080b5461303a54570fa7e262bb44d65714d3ac8eae5b1c8a48656afafa15d2c3ea211400f44562 First BR Version: SemanticVersion[major=0, minor=0, patch=0, pre=, build=] Last round before BR: 0 Lowest Judge Gen before BR 0 (root) PlatformTestingToolState / fiction-frown-elite-select 0 SingletonNode PlatformStateService.PLATFORM_STATE /0 all-baby-flag-begin 1 VirtualMap RosterService.ROSTERS /1 step-utility-truth-odor 2 SingletonNode RosterService.ROSTER_STATE /2 anger-shell-eyebrow-boring 3 PayloadCfgSimple /3 potato-pill-swear-author 4 NextSeqConsList /4 sniff-wear-know-arrow 5 FCMFamily /5 owner-machine-cabbage-valve 0 MerkleMap /5/0 come-picnic-rich-screen 1 MerkleMap /5/1 fall-topple-alarm-witness 6 TransactionCounterList /6 voyage-casual-ceiling-observe 7 IssLeaf /7 biology-capable-expose-purpose 8 NftLedger /8 chicken-butter-size-happy 0 MerkleMap /8/0 fall-topple-alarm-witness 1 null /8/1 bid-belt-culture-decorate 9 VirtualMap accounts /9 rain-album-lawn-machine 10 null /10 bid-belt-culture-decorate 11 null /11 bid-belt-culture-decorate 12 QuorumResult /12 judge-chase-era-faith
node3 2m 8.506s 2025-09-25 11:00:02.315 1030 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Found 2 preconsensus files on disk.
First file: data/saved/preconsensus-events/3/2025/09/25/2025-09-25T10+59+41.737212455Z_seq1_minr109_maxr272_orgn0.pces Last file: data/saved/preconsensus-events/3/2025/09/25/2025-09-25T10+58+10.651513376Z_seq0_minr1_maxr137_orgn0.pces
node3 2m 8.506s 2025-09-25 11:00:02.315 1031 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Found 1 preconsensus event file meeting specified criteria to copy.
Lower bound: 147 File: data/saved/preconsensus-events/3/2025/09/25/2025-09-25T10+59+41.737212455Z_seq1_minr109_maxr272_orgn0.pces
node3 2m 8.506s 2025-09-25 11:00:02.315 1032 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Copying 1 preconsensus event file(s)
node3 2m 8.509s 2025-09-25 11:00:02.318 1033 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Finished copying 1 preconsensus event file(s)
node3 2m 8.510s 2025-09-25 11:00:02.319 1034 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Finished writing state for round 174 to disk. Reason: PERIODIC_SNAPSHOT, directory: /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/com.swirlds.demo.platform.PlatformTestingToolMain/3/123/174 {"round":174,"freezeState":false,"reason":"PERIODIC_SNAPSHOT","directory":"file:///opt/hgcapp/services-hedera/HapiApp2.0/data/saved/com.swirlds.demo.platform.PlatformTestingToolMain/3/123/174/"} [com.swirlds.logging.legacy.payload.StateSavedToDiskPayload]
node1 2m 8.657s 2025-09-25 11:00:02.466 1028 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> MerkleTreeSnapshotWriter: Successfully created a snapshot on demand in /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/swirlds-tmp/9 for round 174
node1 2m 8.659s 2025-09-25 11:00:02.468 1029 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Information for state written to disk:
Round: 174 Timestamp: 2025-09-25T11:00:00.494384645Z Next consensus number: 2772 Legacy running event hash: 551ff2e61f30025ba50faee58cf81664350cd3271fa645140e0fb3f3b505944622828d4c9daf00dca951f380cf7dc06f Legacy running event mnemonic: physical-person-toward-enter Rounds non-ancient: 26 Creation version: SemanticVersion[major=1, minor=0, patch=0, pre=, build=] Minimum judge hash code: 964658043 Root hash: 6aae8d14a5c0361a4f080b5461303a54570fa7e262bb44d65714d3ac8eae5b1c8a48656afafa15d2c3ea211400f44562 First BR Version: SemanticVersion[major=0, minor=0, patch=0, pre=, build=] Last round before BR: 0 Lowest Judge Gen before BR 0 (root) PlatformTestingToolState / fiction-frown-elite-select 0 SingletonNode PlatformStateService.PLATFORM_STATE /0 all-baby-flag-begin 1 VirtualMap RosterService.ROSTERS /1 step-utility-truth-odor 2 SingletonNode RosterService.ROSTER_STATE /2 anger-shell-eyebrow-boring 3 PayloadCfgSimple /3 potato-pill-swear-author 4 NextSeqConsList /4 sniff-wear-know-arrow 5 FCMFamily /5 owner-machine-cabbage-valve 0 MerkleMap /5/0 come-picnic-rich-screen 1 MerkleMap /5/1 fall-topple-alarm-witness 6 TransactionCounterList /6 voyage-casual-ceiling-observe 7 IssLeaf /7 biology-capable-expose-purpose 8 NftLedger /8 chicken-butter-size-happy 0 MerkleMap /8/0 fall-topple-alarm-witness 1 null /8/1 bid-belt-culture-decorate 9 VirtualMap accounts /9 rain-album-lawn-machine 10 null /10 bid-belt-culture-decorate 11 null /11 bid-belt-culture-decorate 12 QuorumResult /12 judge-chase-era-faith
node1 2m 8.667s 2025-09-25 11:00:02.476 1030 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Found 2 preconsensus files on disk.
First file: data/saved/preconsensus-events/1/2025/09/25/2025-09-25T10+59+41.784836420Z_seq1_minr109_maxr272_orgn0.pces Last file: data/saved/preconsensus-events/1/2025/09/25/2025-09-25T10+58+10.201634750Z_seq0_minr1_maxr137_orgn0.pces
node1 2m 8.667s 2025-09-25 11:00:02.476 1031 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Found 1 preconsensus event file meeting specified criteria to copy.
Lower bound: 147 File: data/saved/preconsensus-events/1/2025/09/25/2025-09-25T10+59+41.784836420Z_seq1_minr109_maxr272_orgn0.pces
node1 2m 8.667s 2025-09-25 11:00:02.476 1032 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Copying 1 preconsensus event file(s)
node1 2m 8.670s 2025-09-25 11:00:02.479 1033 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Finished copying 1 preconsensus event file(s)
node1 2m 8.671s 2025-09-25 11:00:02.480 1034 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Finished writing state for round 174 to disk. Reason: PERIODIC_SNAPSHOT, directory: /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/com.swirlds.demo.platform.PlatformTestingToolMain/1/123/174 {"round":174,"freezeState":false,"reason":"PERIODIC_SNAPSHOT","directory":"file:///opt/hgcapp/services-hedera/HapiApp2.0/data/saved/com.swirlds.demo.platform.PlatformTestingToolMain/1/123/174/"} [com.swirlds.logging.legacy.payload.StateSavedToDiskPayload]
node0 2m 8.677s 2025-09-25 11:00:02.486 1028 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> MerkleTreeSnapshotWriter: Successfully created a snapshot on demand in /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/swirlds-tmp/9 for round 174
node0 2m 8.680s 2025-09-25 11:00:02.489 1029 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Information for state written to disk:
Round: 174 Timestamp: 2025-09-25T11:00:00.494384645Z Next consensus number: 2772 Legacy running event hash: 551ff2e61f30025ba50faee58cf81664350cd3271fa645140e0fb3f3b505944622828d4c9daf00dca951f380cf7dc06f Legacy running event mnemonic: physical-person-toward-enter Rounds non-ancient: 26 Creation version: SemanticVersion[major=1, minor=0, patch=0, pre=, build=] Minimum judge hash code: 964658043 Root hash: 6aae8d14a5c0361a4f080b5461303a54570fa7e262bb44d65714d3ac8eae5b1c8a48656afafa15d2c3ea211400f44562 First BR Version: SemanticVersion[major=0, minor=0, patch=0, pre=, build=] Last round before BR: 0 Lowest Judge Gen before BR 0 (root) PlatformTestingToolState / fiction-frown-elite-select 0 SingletonNode PlatformStateService.PLATFORM_STATE /0 all-baby-flag-begin 1 VirtualMap RosterService.ROSTERS /1 step-utility-truth-odor 2 SingletonNode RosterService.ROSTER_STATE /2 anger-shell-eyebrow-boring 3 PayloadCfgSimple /3 potato-pill-swear-author 4 NextSeqConsList /4 sniff-wear-know-arrow 5 FCMFamily /5 owner-machine-cabbage-valve 0 MerkleMap /5/0 come-picnic-rich-screen 1 MerkleMap /5/1 fall-topple-alarm-witness 6 TransactionCounterList /6 voyage-casual-ceiling-observe 7 IssLeaf /7 biology-capable-expose-purpose 8 NftLedger /8 chicken-butter-size-happy 0 MerkleMap /8/0 fall-topple-alarm-witness 1 null /8/1 bid-belt-culture-decorate 9 VirtualMap accounts /9 rain-album-lawn-machine 10 null /10 bid-belt-culture-decorate 11 null /11 bid-belt-culture-decorate 12 QuorumResult /12 judge-chase-era-faith
node0 2m 8.691s 2025-09-25 11:00:02.500 1030 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Found 2 preconsensus files on disk.
First file: data/saved/preconsensus-events/0/2025/09/25/2025-09-25T10+59+41.755103935Z_seq1_minr109_maxr272_orgn0.pces Last file: data/saved/preconsensus-events/0/2025/09/25/2025-09-25T10+58+10.614382749Z_seq0_minr1_maxr137_orgn0.pces
node0 2m 8.691s 2025-09-25 11:00:02.500 1031 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Found 1 preconsensus event file meeting specified criteria to copy.
Lower bound: 147 File: data/saved/preconsensus-events/0/2025/09/25/2025-09-25T10+59+41.755103935Z_seq1_minr109_maxr272_orgn0.pces
node0 2m 8.691s 2025-09-25 11:00:02.500 1032 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Copying 1 preconsensus event file(s)
node0 2m 8.694s 2025-09-25 11:00:02.503 1033 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Finished copying 1 preconsensus event file(s)
node0 2m 8.695s 2025-09-25 11:00:02.504 1034 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Finished writing state for round 174 to disk. Reason: PERIODIC_SNAPSHOT, directory: /opt/hgcapp/services-hedera/HapiApp2.0/data/saved/com.swirlds.demo.platform.PlatformTestingToolMain/0/123/174 {"round":174,"freezeState":false,"reason":"PERIODIC_SNAPSHOT","directory":"file:///opt/hgcapp/services-hedera/HapiApp2.0/data/saved/com.swirlds.demo.platform.PlatformTestingToolMain/0/123/174/"} [com.swirlds.logging.legacy.payload.StateSavedToDiskPayload]
node2 2m 8.760s 2025-09-25 11:00:02.569 1035 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 0, type = ENTER_VALIDATION, consensusTimestamp = 2025-09-25T11:00:01.151223985Z ]
node2 2m 8.761s 2025-09-25 11:00:02.570 1036 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 0 apply state ControlAction[timestamp=2025-09-25T11:00:01.151223985Z,type=ENTER_VALIDATION]
node2 2m 8.762s 2025-09-25 11:00:02.571 1037 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolMain: Handling Quorum Transition [ triggeringNodeId = 0, type = ENTER_VALIDATION, consensusTime = 2025-09-25T11:00:01.151223985Z ]
node2 2m 8.763s 2025-09-25 11:00:02.572 1038 INFO DEMO_QUORUM <<PTT: enter-validator 2>> PlatformTestingToolMain: Achieved Quorum on ENTER_VALIDATION transaction [ expectedMapFile = Node0002_ExpectedMap_1758798001151_151223985.json, consensusTime = 2025-09-25T11:00:01.151223985Z ]
node1 2m 8.776s 2025-09-25 11:00:02.585 1035 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 0, type = ENTER_VALIDATION, consensusTimestamp = 2025-09-25T11:00:01.151223985Z ]
node1 2m 8.776s 2025-09-25 11:00:02.585 1036 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 0 apply state ControlAction[timestamp=2025-09-25T11:00:01.151223985Z,type=ENTER_VALIDATION]
node1 2m 8.777s 2025-09-25 11:00:02.586 1037 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolMain: Handling Quorum Transition [ triggeringNodeId = 0, type = ENTER_VALIDATION, consensusTime = 2025-09-25T11:00:01.151223985Z ]
node1 2m 8.779s 2025-09-25 11:00:02.588 1038 INFO DEMO_QUORUM <<PTT: enter-validator 1>> PlatformTestingToolMain: Achieved Quorum on ENTER_VALIDATION transaction [ expectedMapFile = Node0001_ExpectedMap_1758798001151_151223985.json, consensusTime = 2025-09-25T11:00:01.151223985Z ]
node2 2m 8.784s 2025-09-25 11:00:02.593 1039 INFO SAVE_EXPECTED_MAP <<PTT: enter-validator 2>> SaveExpectedMapHandler: Serializing ExpectedMap Node0002_ExpectedMap_1758798001151_151223985.json.gz
node1 2m 8.797s 2025-09-25 11:00:02.606 1039 INFO SAVE_EXPECTED_MAP <<PTT: enter-validator 1>> SaveExpectedMapHandler: Serializing ExpectedMap Node0001_ExpectedMap_1758798001151_151223985.json.gz
node3 2m 9.061s 2025-09-25 11:00:02.870 1035 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 0, type = ENTER_VALIDATION, consensusTimestamp = 2025-09-25T11:00:01.151223985Z ]
node3 2m 9.062s 2025-09-25 11:00:02.871 1036 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 0 apply state ControlAction[timestamp=2025-09-25T11:00:01.151223985Z,type=ENTER_VALIDATION]
node3 2m 9.062s 2025-09-25 11:00:02.871 1037 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolMain: Handling Quorum Transition [ triggeringNodeId = 0, type = ENTER_VALIDATION, consensusTime = 2025-09-25T11:00:01.151223985Z ]
node3 2m 9.064s 2025-09-25 11:00:02.873 1038 INFO DEMO_QUORUM <<PTT: enter-validator 3>> PlatformTestingToolMain: Achieved Quorum on ENTER_VALIDATION transaction [ expectedMapFile = Node0003_ExpectedMap_1758798001151_151223985.json, consensusTime = 2025-09-25T11:00:01.151223985Z ]
node3 2m 9.085s 2025-09-25 11:00:02.894 1039 INFO SAVE_EXPECTED_MAP <<PTT: enter-validator 3>> SaveExpectedMapHandler: Serializing ExpectedMap Node0003_ExpectedMap_1758798001151_151223985.json.gz
node2 2m 9.090s 2025-09-25 11:00:02.899 1040 INFO DEMO_QUORUM <<PTT: enter-validator 2>> PlatformTestingToolMain: Successfully wrote expected map to file [ expectedMapFile = Node0002_ExpectedMap_1758798001151_151223985.json, consensusTime = 2025-09-25T11:00:01.151223985Z ]
node2 2m 9.091s 2025-09-25 11:00:02.900 1041 INFO DEMO_QUORUM <<PTT: enter-validator 2>> PlatformTestingToolMain: Sent EXIT_VALIDATION transaction [ consensusTime = 2025-09-25T11:00:01.151223985Z ]
node1 2m 9.095s 2025-09-25 11:00:02.904 1040 INFO DEMO_QUORUM <<PTT: enter-validator 1>> PlatformTestingToolMain: Successfully wrote expected map to file [ expectedMapFile = Node0001_ExpectedMap_1758798001151_151223985.json, consensusTime = 2025-09-25T11:00:01.151223985Z ]
node1 2m 9.095s 2025-09-25 11:00:02.904 1041 INFO DEMO_QUORUM <<PTT: enter-validator 1>> PlatformTestingToolMain: Sent EXIT_VALIDATION transaction [ consensusTime = 2025-09-25T11:00:01.151223985Z ]
node0 2m 9.113s 2025-09-25 11:00:02.922 1035 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 0, type = ENTER_VALIDATION, consensusTimestamp = 2025-09-25T11:00:01.151223985Z ]
node0 2m 9.113s 2025-09-25 11:00:02.922 1036 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 0 apply state ControlAction[timestamp=2025-09-25T11:00:01.151223985Z,type=ENTER_VALIDATION]
node0 2m 9.114s 2025-09-25 11:00:02.923 1037 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolMain: Handling Quorum Transition [ triggeringNodeId = 0, type = ENTER_VALIDATION, consensusTime = 2025-09-25T11:00:01.151223985Z ]
node0 2m 9.116s 2025-09-25 11:00:02.925 1038 INFO DEMO_QUORUM <<PTT: enter-validator 0>> PlatformTestingToolMain: Achieved Quorum on ENTER_VALIDATION transaction [ expectedMapFile = Node0000_ExpectedMap_1758798001151_151223985.json, consensusTime = 2025-09-25T11:00:01.151223985Z ]
node0 2m 9.144s 2025-09-25 11:00:02.953 1039 INFO SAVE_EXPECTED_MAP <<PTT: enter-validator 0>> SaveExpectedMapHandler: Serializing ExpectedMap Node0000_ExpectedMap_1758798001151_151223985.json.gz
node3 2m 9.385s 2025-09-25 11:00:03.194 1040 INFO DEMO_QUORUM <<PTT: enter-validator 3>> PlatformTestingToolMain: Successfully wrote expected map to file [ expectedMapFile = Node0003_ExpectedMap_1758798001151_151223985.json, consensusTime = 2025-09-25T11:00:01.151223985Z ]
node3 2m 9.385s 2025-09-25 11:00:03.194 1041 INFO DEMO_QUORUM <<PTT: enter-validator 3>> PlatformTestingToolMain: Sent EXIT_VALIDATION transaction [ consensusTime = 2025-09-25T11:00:01.151223985Z ]
node0 2m 9.468s 2025-09-25 11:00:03.277 1040 INFO DEMO_QUORUM <<PTT: enter-validator 0>> PlatformTestingToolMain: Successfully wrote expected map to file [ expectedMapFile = Node0000_ExpectedMap_1758798001151_151223985.json, consensusTime = 2025-09-25T11:00:01.151223985Z ]
node0 2m 9.469s 2025-09-25 11:00:03.278 1041 INFO DEMO_QUORUM <<PTT: enter-validator 0>> PlatformTestingToolMain: Sent EXIT_VALIDATION transaction [ consensusTime = 2025-09-25T11:00:01.151223985Z ]
node3 2m 10.482s 2025-09-25 11:00:04.291 1046 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 2, type = EXIT_VALIDATION, consensusTimestamp = 2025-09-25T11:00:03.324888449Z ]
node3 2m 10.483s 2025-09-25 11:00:04.292 1047 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 2 apply state ControlAction[timestamp=2025-09-25T11:00:03.324888449Z,type=EXIT_VALIDATION]
node0 2m 10.538s 2025-09-25 11:00:04.347 1046 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 2, type = EXIT_VALIDATION, consensusTimestamp = 2025-09-25T11:00:03.324888449Z ]
node0 2m 10.539s 2025-09-25 11:00:04.348 1047 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 2 apply state ControlAction[timestamp=2025-09-25T11:00:03.324888449Z,type=EXIT_VALIDATION]
node2 2m 10.729s 2025-09-25 11:00:04.538 1054 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 2, type = EXIT_VALIDATION, consensusTimestamp = 2025-09-25T11:00:03.324888449Z ]
node2 2m 10.729s 2025-09-25 11:00:04.538 1055 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 2 apply state ControlAction[timestamp=2025-09-25T11:00:03.324888449Z,type=EXIT_VALIDATION]
node0 2m 11.068s 2025-09-25 11:00:04.877 1056 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 1, type = EXIT_VALIDATION, consensusTimestamp = 2025-09-25T11:00:03.324890Z ]
node0 2m 11.069s 2025-09-25 11:00:04.878 1057 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 1 apply state ControlAction[timestamp=2025-09-25T11:00:03.324890Z,type=EXIT_VALIDATION]
node0 2m 11.069s 2025-09-25 11:00:04.878 1058 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 3, type = EXIT_VALIDATION, consensusTimestamp = 2025-09-25T11:00:03.508837Z ]
node0 2m 11.069s 2025-09-25 11:00:04.878 1059 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 3 apply state ControlAction[timestamp=2025-09-25T11:00:03.508837Z,type=EXIT_VALIDATION]
node0 2m 11.069s 2025-09-25 11:00:04.878 1060 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 0, type = EXIT_VALIDATION, consensusTimestamp = 2025-09-25T11:00:03.628900929Z ]
node0 2m 11.069s 2025-09-25 11:00:04.878 1061 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 0 apply state ControlAction[timestamp=2025-09-25T11:00:03.628900929Z,type=EXIT_VALIDATION]
node0 2m 11.069s 2025-09-25 11:00:04.878 1062 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolMain: Handling Quorum Transition [ triggeringNodeId = 0, type = EXIT_VALIDATION, consensusTime = 2025-09-25T11:00:03.628900929Z ]
node0 2m 11.070s 2025-09-25 11:00:04.879 1064 INFO DEMO_QUORUM <<PTT: exit-validator 0>> PlatformTestingToolMain: Achieved Quorum on EXIT_VALIDATION transaction [ consensusTime = 2025-09-25T11:00:03.628900929Z ]
node0 2m 11.073s 2025-09-25 11:00:04.882 1068 INFO DEMO_INFO <<PTT: exit-validator 0>> TransactionSubmitter: Sending Freeze Transaction...
node0 2m 11.073s 2025-09-25 11:00:04.882 1069 INFO DEMO_INFO <<PTT: exit-validator 0>> TransactionSubmitter: Finished Sending Freeze Transaction.
node0 2m 11.073s 2025-09-25 11:00:04.882 1070 INFO DEMO_QUORUM <<PTT: exit-validator 0>> PlatformTestingToolMain: Preparing to terminate the JVM [ sleepAfterTestMs = 90000, consensusTime = 2025-09-25T11:00:03.628900929Z ]
node0 2m 11.074s 2025-09-25 11:00:04.883 1071 INFO DEMO_INFO <<PTT: exit-validator 0>> PlatformTestingToolMain: Test success: Reached quorum on the EXIT_VALIDATION transaction, consensus time = 2025-09-25T11:00:03.628900929Z {} [com.swirlds.logging.legacy.payload.ApplicationFinishedPayload]
node1 2m 11.107s 2025-09-25 11:00:04.916 1054 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 2, type = EXIT_VALIDATION, consensusTimestamp = 2025-09-25T11:00:03.324888449Z ]
node1 2m 11.108s 2025-09-25 11:00:04.917 1055 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 2 apply state ControlAction[timestamp=2025-09-25T11:00:03.324888449Z,type=EXIT_VALIDATION]
node2 2m 11.151s 2025-09-25 11:00:04.960 1056 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 1, type = EXIT_VALIDATION, consensusTimestamp = 2025-09-25T11:00:03.324890Z ]
node2 2m 11.152s 2025-09-25 11:00:04.961 1057 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 1 apply state ControlAction[timestamp=2025-09-25T11:00:03.324890Z,type=EXIT_VALIDATION]
node2 2m 11.152s 2025-09-25 11:00:04.961 1058 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 3, type = EXIT_VALIDATION, consensusTimestamp = 2025-09-25T11:00:03.508837Z ]
node2 2m 11.152s 2025-09-25 11:00:04.961 1059 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 3 apply state ControlAction[timestamp=2025-09-25T11:00:03.508837Z,type=EXIT_VALIDATION]
node2 2m 11.152s 2025-09-25 11:00:04.961 1060 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 0, type = EXIT_VALIDATION, consensusTimestamp = 2025-09-25T11:00:03.628900929Z ]
node2 2m 11.153s 2025-09-25 11:00:04.962 1061 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 0 apply state ControlAction[timestamp=2025-09-25T11:00:03.628900929Z,type=EXIT_VALIDATION]
node2 2m 11.153s 2025-09-25 11:00:04.962 1062 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolMain: Handling Quorum Transition [ triggeringNodeId = 0, type = EXIT_VALIDATION, consensusTime = 2025-09-25T11:00:03.628900929Z ]
node2 2m 11.154s 2025-09-25 11:00:04.963 1064 INFO DEMO_QUORUM <<PTT: exit-validator 2>> PlatformTestingToolMain: Achieved Quorum on EXIT_VALIDATION transaction [ consensusTime = 2025-09-25T11:00:03.628900929Z ]
node2 2m 11.154s 2025-09-25 11:00:04.963 1066 INFO DEMO_QUORUM <<PTT: exit-validator 2>> PlatformTestingToolMain: Preparing to terminate the JVM [ sleepAfterTestMs = 90000, consensusTime = 2025-09-25T11:00:03.628900929Z ]
node2 2m 11.155s 2025-09-25 11:00:04.964 1069 INFO DEMO_INFO <<PTT: exit-validator 2>> PlatformTestingToolMain: Test success: Reached quorum on the EXIT_VALIDATION transaction, consensus time = 2025-09-25T11:00:03.628900929Z {} [com.swirlds.logging.legacy.payload.ApplicationFinishedPayload]
node1 2m 11.156s 2025-09-25 11:00:04.965 1056 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 1, type = EXIT_VALIDATION, consensusTimestamp = 2025-09-25T11:00:03.324890Z ]
node1 2m 11.157s 2025-09-25 11:00:04.966 1057 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 1 apply state ControlAction[timestamp=2025-09-25T11:00:03.324890Z,type=EXIT_VALIDATION]
node1 2m 11.157s 2025-09-25 11:00:04.966 1058 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 3, type = EXIT_VALIDATION, consensusTimestamp = 2025-09-25T11:00:03.508837Z ]
node1 2m 11.157s 2025-09-25 11:00:04.966 1059 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 3 apply state ControlAction[timestamp=2025-09-25T11:00:03.508837Z,type=EXIT_VALIDATION]
node1 2m 11.157s 2025-09-25 11:00:04.966 1060 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 0, type = EXIT_VALIDATION, consensusTimestamp = 2025-09-25T11:00:03.628900929Z ]
node1 2m 11.157s 2025-09-25 11:00:04.966 1061 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 0 apply state ControlAction[timestamp=2025-09-25T11:00:03.628900929Z,type=EXIT_VALIDATION]
node1 2m 11.157s 2025-09-25 11:00:04.966 1062 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolMain: Handling Quorum Transition [ triggeringNodeId = 0, type = EXIT_VALIDATION, consensusTime = 2025-09-25T11:00:03.628900929Z ]
node1 2m 11.158s 2025-09-25 11:00:04.967 1064 INFO DEMO_QUORUM <<PTT: exit-validator 1>> PlatformTestingToolMain: Achieved Quorum on EXIT_VALIDATION transaction [ consensusTime = 2025-09-25T11:00:03.628900929Z ]
node1 2m 11.159s 2025-09-25 11:00:04.968 1066 INFO DEMO_QUORUM <<PTT: exit-validator 1>> PlatformTestingToolMain: Preparing to terminate the JVM [ sleepAfterTestMs = 90000, consensusTime = 2025-09-25T11:00:03.628900929Z ]
node1 2m 11.161s 2025-09-25 11:00:04.970 1069 INFO DEMO_INFO <<PTT: exit-validator 1>> PlatformTestingToolMain: Test success: Reached quorum on the EXIT_VALIDATION transaction, consensus time = 2025-09-25T11:00:03.628900929Z {} [com.swirlds.logging.legacy.payload.ApplicationFinishedPayload]
node3 2m 11.340s 2025-09-25 11:00:05.149 1056 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 1, type = EXIT_VALIDATION, consensusTimestamp = 2025-09-25T11:00:03.324890Z ]
node3 2m 11.341s 2025-09-25 11:00:05.150 1057 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 1 apply state ControlAction[timestamp=2025-09-25T11:00:03.324890Z,type=EXIT_VALIDATION]
node3 2m 11.341s 2025-09-25 11:00:05.150 1058 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 3, type = EXIT_VALIDATION, consensusTimestamp = 2025-09-25T11:00:03.508837Z ]
node3 2m 11.341s 2025-09-25 11:00:05.150 1059 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 3 apply state ControlAction[timestamp=2025-09-25T11:00:03.508837Z,type=EXIT_VALIDATION]
node3 2m 11.341s 2025-09-25 11:00:05.150 1060 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 0, type = EXIT_VALIDATION, consensusTimestamp = 2025-09-25T11:00:03.628900929Z ]
node3 2m 11.341s 2025-09-25 11:00:05.150 1061 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 0 apply state ControlAction[timestamp=2025-09-25T11:00:03.628900929Z,type=EXIT_VALIDATION]
node3 2m 11.341s 2025-09-25 11:00:05.150 1062 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolMain: Handling Quorum Transition [ triggeringNodeId = 0, type = EXIT_VALIDATION, consensusTime = 2025-09-25T11:00:03.628900929Z ]
node3 2m 11.342s 2025-09-25 11:00:05.151 1064 INFO DEMO_QUORUM <<PTT: exit-validator 3>> PlatformTestingToolMain: Achieved Quorum on EXIT_VALIDATION transaction [ consensusTime = 2025-09-25T11:00:03.628900929Z ]
node3 2m 11.343s 2025-09-25 11:00:05.152 1066 INFO DEMO_QUORUM <<PTT: exit-validator 3>> PlatformTestingToolMain: Preparing to terminate the JVM [ sleepAfterTestMs = 90000, consensusTime = 2025-09-25T11:00:03.628900929Z ]
node3 2m 11.345s 2025-09-25 11:00:05.154 1069 INFO DEMO_INFO <<PTT: exit-validator 3>> PlatformTestingToolMain: Test success: Reached quorum on the EXIT_VALIDATION transaction, consensus time = 2025-09-25T11:00:03.628900929Z {} [com.swirlds.logging.legacy.payload.ApplicationFinishedPayload]
node3 2m 12.770s 2025-09-25 11:00:06.579 1078 DEBUG FREEZE <<scheduler TransactionHandler>> FreezeTransactionHandler: Handling FreezeTransaction: startTimeEpochSecond: 1758798064
node0 2m 12.900s 2025-09-25 11:00:06.709 1080 DEBUG FREEZE <<scheduler TransactionHandler>> FreezeTransactionHandler: Handling FreezeTransaction: startTimeEpochSecond: 1758798064
node1 2m 12.950s 2025-09-25 11:00:06.759 1078 DEBUG FREEZE <<scheduler TransactionHandler>> FreezeTransactionHandler: Handling FreezeTransaction: startTimeEpochSecond: 1758798064
node2 2m 12.993s 2025-09-25 11:00:06.802 1078 DEBUG FREEZE <<scheduler TransactionHandler>> FreezeTransactionHandler: Handling FreezeTransaction: startTimeEpochSecond: 1758798064
node2 2m 57.754s 2025-09-25 11:00:51.563 1319 WARN SOCKET_EXCEPTIONS <<platform-core: SyncProtocolWith1 2 to 1>> NetworkUtils: Connection broken: 2 <- 1
java.io.IOException: com.swirlds.common.threading.pool.ParallelExecutionException: Time thrown: 2025-09-25T11:00:51.558685038Z at com.swirlds.platform.gossip.sync.protocol.SyncPeerProtocol.runProtocol(SyncPeerProtocol.java:245) at com.swirlds.platform.network.communication.states.ProtocolNegotiated.transition(ProtocolNegotiated.java:47) at com.swirlds.platform.network.communication.Negotiator.execute(Negotiator.java:79) at com.swirlds.platform.network.communication.ProtocolNegotiatorThread.run(ProtocolNegotiatorThread.java:70) at com.swirlds.common.threading.framework.internal.StoppableThreadImpl.doWork(StoppableThreadImpl.java:599) at com.swirlds.common.threading.framework.internal.StoppableThreadImpl.run(StoppableThreadImpl.java:200) at java.base/java.lang.Thread.run(Thread.java:1583) Caused by: com.swirlds.common.threading.pool.ParallelExecutionException: Time thrown: 2025-09-25T11:00:51.558685038Z at com.swirlds.common.threading.pool.CachedPoolParallelExecutor.doParallel(CachedPoolParallelExecutor.java:159) at com.swirlds.platform.gossip.shadowgraph.ShadowgraphSynchronizer.readWriteParallel(ShadowgraphSynchronizer.java:444) at com.swirlds.platform.gossip.shadowgraph.ShadowgraphSynchronizer.sendAndReceiveEvents(ShadowgraphSynchronizer.java:382) at com.swirlds.platform.gossip.shadowgraph.ShadowgraphSynchronizer.reserveSynchronize(ShadowgraphSynchronizer.java:245) at com.swirlds.platform.gossip.shadowgraph.ShadowgraphSynchronizer.synchronize(ShadowgraphSynchronizer.java:167) at com.swirlds.platform.gossip.sync.protocol.SyncPeerProtocol.runProtocol(SyncPeerProtocol.java:242) ... 6 more Caused by: java.net.SocketTimeoutException: Read timed out at java.base/sun.nio.ch.NioSocketImpl.timedRead(NioSocketImpl.java:278) at java.base/sun.nio.ch.NioSocketImpl.implRead(NioSocketImpl.java:304) at java.base/sun.nio.ch.NioSocketImpl.read(NioSocketImpl.java:346) at java.base/sun.nio.ch.NioSocketImpl$1.read(NioSocketImpl.java:796) at java.base/java.net.Socket$SocketInputStream.read(Socket.java:1099) at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:489) at java.base/sun.security.ssl.SSLSocketInputRecord.readHeader(SSLSocketInputRecord.java:483) at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:70) at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1461) at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:1066) at com.swirlds.common.io.extendable.extensions.AbstractStreamExtension.read(AbstractStreamExtension.java:73) at com.swirlds.common.io.extendable.ExtendableInputStream.read(ExtendableInputStream.java:63) at java.base/java.io.BufferedInputStream.fill(BufferedInputStream.java:291) at java.base/java.io.BufferedInputStream.implRead(BufferedInputStream.java:325) at java.base/java.io.BufferedInputStream.read(BufferedInputStream.java:312) at java.base/java.io.DataInputStream.readUnsignedByte(DataInputStream.java:295) at java.base/java.io.DataInputStream.readByte(DataInputStream.java:275) at org.hiero.base.io.streams.AugmentedDataInputStream.readByte(AugmentedDataInputStream.java:144) at com.swirlds.platform.gossip.shadowgraph.SyncUtils.lambda$readEventsINeed$9(SyncUtils.java:273) at com.swirlds.common.threading.pool.CachedPoolParallelExecutor.doParallel(CachedPoolParallelExecutor.java:157) ... 11 more
node2 2m 57.754s 2025-09-25 11:00:51.563 1320 WARN SOCKET_EXCEPTIONS <<platform-core: SyncProtocolWith0 2 to 0>> NetworkUtils: Connection broken: 2 <- 0
java.io.IOException: com.swirlds.common.threading.pool.ParallelExecutionException: Time thrown: 2025-09-25T11:00:51.560698168Z at com.swirlds.platform.gossip.sync.protocol.SyncPeerProtocol.runProtocol(SyncPeerProtocol.java:245) at com.swirlds.platform.network.communication.states.ProtocolNegotiated.transition(ProtocolNegotiated.java:47) at com.swirlds.platform.network.communication.Negotiator.execute(Negotiator.java:79) at com.swirlds.platform.network.communication.ProtocolNegotiatorThread.run(ProtocolNegotiatorThread.java:70) at com.swirlds.common.threading.framework.internal.StoppableThreadImpl.doWork(StoppableThreadImpl.java:599) at com.swirlds.common.threading.framework.internal.StoppableThreadImpl.run(StoppableThreadImpl.java:200) at java.base/java.lang.Thread.run(Thread.java:1583) Caused by: com.swirlds.common.threading.pool.ParallelExecutionException: Time thrown: 2025-09-25T11:00:51.560698168Z at com.swirlds.common.threading.pool.CachedPoolParallelExecutor.doParallel(CachedPoolParallelExecutor.java:159) at com.swirlds.platform.gossip.shadowgraph.ShadowgraphSynchronizer.readWriteParallel(ShadowgraphSynchronizer.java:444) at com.swirlds.platform.gossip.shadowgraph.ShadowgraphSynchronizer.reserveSynchronize(ShadowgraphSynchronizer.java:199) at com.swirlds.platform.gossip.shadowgraph.ShadowgraphSynchronizer.synchronize(ShadowgraphSynchronizer.java:167) at com.swirlds.platform.gossip.sync.protocol.SyncPeerProtocol.runProtocol(SyncPeerProtocol.java:242) ... 6 more Caused by: java.net.SocketTimeoutException: Read timed out at java.base/sun.nio.ch.NioSocketImpl.timedRead(NioSocketImpl.java:278) at java.base/sun.nio.ch.NioSocketImpl.implRead(NioSocketImpl.java:304) at java.base/sun.nio.ch.NioSocketImpl.read(NioSocketImpl.java:346) at java.base/sun.nio.ch.NioSocketImpl$1.read(NioSocketImpl.java:796) at java.base/java.net.Socket$SocketInputStream.read(Socket.java:1099) at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:489) at java.base/sun.security.ssl.SSLSocketInputRecord.readHeader(SSLSocketInputRecord.java:483) at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:70) at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1461) at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:1066) at com.swirlds.common.io.extendable.extensions.AbstractStreamExtension.read(AbstractStreamExtension.java:73) at com.swirlds.common.io.extendable.ExtendableInputStream.read(ExtendableInputStream.java:63) at java.base/java.io.BufferedInputStream.fill(BufferedInputStream.java:291) at java.base/java.io.BufferedInputStream.read1(BufferedInputStream.java:347) at java.base/java.io.BufferedInputStream.implRead(BufferedInputStream.java:420) at java.base/java.io.BufferedInputStream.read(BufferedInputStream.java:399) at java.base/java.io.DataInputStream.readFully(DataInputStream.java:208) at java.base/java.io.DataInputStream.readLong(DataInputStream.java:407) at org.hiero.base.io.streams.AugmentedDataInputStream.readLong(AugmentedDataInputStream.java:186) at com.swirlds.platform.gossip.shadowgraph.SyncUtils.deserializeEventWindow(SyncUtils.java:592) at com.swirlds.platform.gossip.shadowgraph.SyncUtils.lambda$readTheirTipsAndEventWindow$3(SyncUtils.java:105) at com.swirlds.common.threading.pool.CachedPoolParallelExecutor.doParallel(CachedPoolParallelExecutor.java:157) ... 10 more
node2 2m 57.788s 2025-09-25 11:00:51.597 1321 WARN SOCKET_EXCEPTIONS <<platform-core: SyncProtocolWith3 2 to 3>> NetworkUtils: Connection broken: 2 -> 3
java.net.SocketTimeoutException: Read timed out at java.base/sun.nio.ch.NioSocketImpl.timedRead(NioSocketImpl.java:278) at java.base/sun.nio.ch.NioSocketImpl.implRead(NioSocketImpl.java:304) at java.base/sun.nio.ch.NioSocketImpl.read(NioSocketImpl.java:346) at java.base/sun.nio.ch.NioSocketImpl$1.read(NioSocketImpl.java:796) at java.base/java.net.Socket$SocketInputStream.read(Socket.java:1099) at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:489) at java.base/sun.security.ssl.SSLSocketInputRecord.readHeader(SSLSocketInputRecord.java:483) at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:70) at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1461) at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:1066) at com.swirlds.common.io.extendable.extensions.AbstractStreamExtension.read(AbstractStreamExtension.java:73) at com.swirlds.common.io.extendable.ExtendableInputStream.read(ExtendableInputStream.java:63) at java.base/java.io.BufferedInputStream.fill(BufferedInputStream.java:291) at java.base/java.io.BufferedInputStream.implRead(BufferedInputStream.java:325) at java.base/java.io.BufferedInputStream.read(BufferedInputStream.java:312) at java.base/java.io.FilterInputStream.read(FilterInputStream.java:71) at org.hiero.base.io.streams.AugmentedDataInputStream.read(AugmentedDataInputStream.java:57) at com.swirlds.platform.network.communication.states.SentKeepalive.transition(SentKeepalive.java:44) at com.swirlds.platform.network.communication.Negotiator.execute(Negotiator.java:79) at com.swirlds.platform.network.communication.ProtocolNegotiatorThread.run(ProtocolNegotiatorThread.java:70) at com.swirlds.common.threading.framework.internal.StoppableThreadImpl.doWork(StoppableThreadImpl.java:599) at com.swirlds.common.threading.framework.internal.StoppableThreadImpl.run(StoppableThreadImpl.java:200) at java.base/java.lang.Thread.run(Thread.java:1583)
node0 2m 57.825s 2025-09-25 11:00:51.634 1321 WARN SOCKET_EXCEPTIONS <<platform-core: SyncProtocolWith2 0 to 2>> NetworkUtils: Connection broken: 0 -> 2
java.io.IOException: com.swirlds.common.threading.pool.ParallelExecutionException: Time thrown: 2025-09-25T11:00:51.631334364Z at com.swirlds.platform.gossip.sync.protocol.SyncPeerProtocol.runProtocol(SyncPeerProtocol.java:245) at com.swirlds.platform.network.communication.states.ProtocolNegotiated.transition(ProtocolNegotiated.java:47) at com.swirlds.platform.network.communication.Negotiator.execute(Negotiator.java:79) at com.swirlds.platform.network.communication.ProtocolNegotiatorThread.run(ProtocolNegotiatorThread.java:70) at com.swirlds.common.threading.framework.internal.StoppableThreadImpl.doWork(StoppableThreadImpl.java:599) at com.swirlds.common.threading.framework.internal.StoppableThreadImpl.run(StoppableThreadImpl.java:200) at java.base/java.lang.Thread.run(Thread.java:1583) Caused by: com.swirlds.common.threading.pool.ParallelExecutionException: Time thrown: 2025-09-25T11:00:51.631334364Z at com.swirlds.common.threading.pool.CachedPoolParallelExecutor.doParallel(CachedPoolParallelExecutor.java:159) at com.swirlds.platform.gossip.shadowgraph.ShadowgraphSynchronizer.readWriteParallel(ShadowgraphSynchronizer.java:444) at com.swirlds.platform.gossip.shadowgraph.ShadowgraphSynchronizer.reserveSynchronize(ShadowgraphSynchronizer.java:228) at com.swirlds.platform.gossip.shadowgraph.ShadowgraphSynchronizer.synchronize(ShadowgraphSynchronizer.java:167) at com.swirlds.platform.gossip.sync.protocol.SyncPeerProtocol.runProtocol(SyncPeerProtocol.java:242) ... 6 more Caused by: java.net.SocketTimeoutException: Read timed out at java.base/sun.nio.ch.NioSocketImpl.timedRead(NioSocketImpl.java:278) at java.base/sun.nio.ch.NioSocketImpl.implRead(NioSocketImpl.java:304) at java.base/sun.nio.ch.NioSocketImpl.read(NioSocketImpl.java:346) at java.base/sun.nio.ch.NioSocketImpl$1.read(NioSocketImpl.java:796) at java.base/java.net.Socket$SocketInputStream.read(Socket.java:1099) at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:489) at java.base/sun.security.ssl.SSLSocketInputRecord.readHeader(SSLSocketInputRecord.java:483) at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:70) at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1461) at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:1066) at com.swirlds.common.io.extendable.extensions.AbstractStreamExtension.read(AbstractStreamExtension.java:73) at com.swirlds.common.io.extendable.ExtendableInputStream.read(ExtendableInputStream.java:63) at java.base/java.io.BufferedInputStream.fill(BufferedInputStream.java:291) at java.base/java.io.BufferedInputStream.read1(BufferedInputStream.java:347) at java.base/java.io.BufferedInputStream.implRead(BufferedInputStream.java:420) at java.base/java.io.BufferedInputStream.read(BufferedInputStream.java:399) at java.base/java.io.DataInputStream.readFully(DataInputStream.java:208) at java.base/java.io.DataInputStream.readInt(DataInputStream.java:385) at org.hiero.base.io.streams.AugmentedDataInputStream.readInt(AugmentedDataInputStream.java:179) at org.hiero.base.io.streams.AugmentedDataInputStream.readBooleanList(AugmentedDataInputStream.java:359) at com.swirlds.platform.gossip.shadowgraph.SyncUtils.lambda$readMyTipsTheyHave$7(SyncUtils.java:160) at com.swirlds.common.threading.pool.CachedPoolParallelExecutor.doParallel(CachedPoolParallelExecutor.java:157) ... 10 more
node1 2m 57.826s 2025-09-25 11:00:51.635 1319 WARN SOCKET_EXCEPTIONS <<platform-core: SyncProtocolWith2 1 to 2>> NetworkUtils: Connection broken: 1 -> 2
java.io.IOException: com.swirlds.common.threading.pool.ParallelExecutionException: Time thrown: 2025-09-25T11:00:51.630560765Z at com.swirlds.platform.gossip.sync.protocol.SyncPeerProtocol.runProtocol(SyncPeerProtocol.java:245) at com.swirlds.platform.network.communication.states.ProtocolNegotiated.transition(ProtocolNegotiated.java:47) at com.swirlds.platform.network.communication.Negotiator.execute(Negotiator.java:79) at com.swirlds.platform.network.communication.ProtocolNegotiatorThread.run(ProtocolNegotiatorThread.java:70) at com.swirlds.common.threading.framework.internal.StoppableThreadImpl.doWork(StoppableThreadImpl.java:599) at com.swirlds.common.threading.framework.internal.StoppableThreadImpl.run(StoppableThreadImpl.java:200) at java.base/java.lang.Thread.run(Thread.java:1583) Caused by: com.swirlds.common.threading.pool.ParallelExecutionException: Time thrown: 2025-09-25T11:00:51.630560765Z at com.swirlds.common.threading.pool.CachedPoolParallelExecutor.doParallel(CachedPoolParallelExecutor.java:159) at com.swirlds.platform.gossip.shadowgraph.ShadowgraphSynchronizer.readWriteParallel(ShadowgraphSynchronizer.java:444) at com.swirlds.platform.gossip.shadowgraph.ShadowgraphSynchronizer.sendAndReceiveEvents(ShadowgraphSynchronizer.java:382) at com.swirlds.platform.gossip.shadowgraph.ShadowgraphSynchronizer.reserveSynchronize(ShadowgraphSynchronizer.java:245) at com.swirlds.platform.gossip.shadowgraph.ShadowgraphSynchronizer.synchronize(ShadowgraphSynchronizer.java:167) at com.swirlds.platform.gossip.sync.protocol.SyncPeerProtocol.runProtocol(SyncPeerProtocol.java:242) ... 6 more Caused by: java.net.SocketTimeoutException: Read timed out at java.base/sun.nio.ch.NioSocketImpl.timedRead(NioSocketImpl.java:278) at java.base/sun.nio.ch.NioSocketImpl.implRead(NioSocketImpl.java:304) at java.base/sun.nio.ch.NioSocketImpl.read(NioSocketImpl.java:346) at java.base/sun.nio.ch.NioSocketImpl$1.read(NioSocketImpl.java:796) at java.base/java.net.Socket$SocketInputStream.read(Socket.java:1099) at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:489) at java.base/sun.security.ssl.SSLSocketInputRecord.readHeader(SSLSocketInputRecord.java:483) at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:70) at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1461) at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:1066) at com.swirlds.common.io.extendable.extensions.AbstractStreamExtension.read(AbstractStreamExtension.java:73) at com.swirlds.common.io.extendable.ExtendableInputStream.read(ExtendableInputStream.java:63) at java.base/java.io.BufferedInputStream.fill(BufferedInputStream.java:291) at java.base/java.io.BufferedInputStream.implRead(BufferedInputStream.java:325) at java.base/java.io.BufferedInputStream.read(BufferedInputStream.java:312) at java.base/java.io.DataInputStream.readUnsignedByte(DataInputStream.java:295) at java.base/java.io.DataInputStream.readByte(DataInputStream.java:275) at org.hiero.base.io.streams.AugmentedDataInputStream.readByte(AugmentedDataInputStream.java:144) at com.swirlds.platform.gossip.shadowgraph.SyncUtils.lambda$readEventsINeed$9(SyncUtils.java:273) at com.swirlds.common.threading.pool.CachedPoolParallelExecutor.doParallel(CachedPoolParallelExecutor.java:157) ... 11 more
node3 2m 57.866s 2025-09-25 11:00:51.675 1319 WARN SOCKET_EXCEPTIONS <<platform-core: SyncProtocolWith2 3 to 2>> NetworkUtils: Connection broken: 3 <- 2
java.net.SocketTimeoutException: Read timed out at java.base/sun.nio.ch.NioSocketImpl.timedRead(NioSocketImpl.java:278) at java.base/sun.nio.ch.NioSocketImpl.implRead(NioSocketImpl.java:304) at java.base/sun.nio.ch.NioSocketImpl.read(NioSocketImpl.java:346) at java.base/sun.nio.ch.NioSocketImpl$1.read(NioSocketImpl.java:796) at java.base/java.net.Socket$SocketInputStream.read(Socket.java:1099) at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:489) at java.base/sun.security.ssl.SSLSocketInputRecord.readHeader(SSLSocketInputRecord.java:483) at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:70) at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1461) at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:1066) at com.swirlds.common.io.extendable.extensions.AbstractStreamExtension.read(AbstractStreamExtension.java:73) at com.swirlds.common.io.extendable.ExtendableInputStream.read(ExtendableInputStream.java:63) at java.base/java.io.BufferedInputStream.fill(BufferedInputStream.java:291) at java.base/java.io.BufferedInputStream.implRead(BufferedInputStream.java:325) at java.base/java.io.BufferedInputStream.read(BufferedInputStream.java:312) at java.base/java.io.FilterInputStream.read(FilterInputStream.java:71) at org.hiero.base.io.streams.AugmentedDataInputStream.read(AugmentedDataInputStream.java:57) at com.swirlds.platform.network.communication.states.WaitForAcceptReject.transition(WaitForAcceptReject.java:48) at com.swirlds.platform.network.communication.Negotiator.execute(Negotiator.java:79) at com.swirlds.platform.network.communication.ProtocolNegotiatorThread.run(ProtocolNegotiatorThread.java:70) at com.swirlds.common.threading.framework.internal.StoppableThreadImpl.doWork(StoppableThreadImpl.java:599) at com.swirlds.common.threading.framework.internal.StoppableThreadImpl.run(StoppableThreadImpl.java:200) at java.base/java.lang.Thread.run(Thread.java:1583)
node3 2m 58.094s 2025-09-25 11:00:51.903 1320 WARN SOCKET_EXCEPTIONS <<platform-core: SyncProtocolWith1 3 to 1>> NetworkUtils: Connection broken: 3 <- 1
java.io.IOException: com.swirlds.common.threading.pool.ParallelExecutionException: Time thrown: 2025-09-25T11:00:51.902881625Z at com.swirlds.platform.gossip.sync.protocol.SyncPeerProtocol.runProtocol(SyncPeerProtocol.java:245) at com.swirlds.platform.network.communication.states.ProtocolNegotiated.transition(ProtocolNegotiated.java:47) at com.swirlds.platform.network.communication.Negotiator.execute(Negotiator.java:79) at com.swirlds.platform.network.communication.ProtocolNegotiatorThread.run(ProtocolNegotiatorThread.java:70) at com.swirlds.common.threading.framework.internal.StoppableThreadImpl.doWork(StoppableThreadImpl.java:599) at com.swirlds.common.threading.framework.internal.StoppableThreadImpl.run(StoppableThreadImpl.java:200) at java.base/java.lang.Thread.run(Thread.java:1583) Caused by: com.swirlds.common.threading.pool.ParallelExecutionException: Time thrown: 2025-09-25T11:00:51.902881625Z at com.swirlds.common.threading.pool.CachedPoolParallelExecutor.doParallel(CachedPoolParallelExecutor.java:159) at com.swirlds.platform.gossip.shadowgraph.ShadowgraphSynchronizer.readWriteParallel(ShadowgraphSynchronizer.java:444) at com.swirlds.platform.gossip.shadowgraph.ShadowgraphSynchronizer.reserveSynchronize(ShadowgraphSynchronizer.java:228) at com.swirlds.platform.gossip.shadowgraph.ShadowgraphSynchronizer.synchronize(ShadowgraphSynchronizer.java:167) at com.swirlds.platform.gossip.sync.protocol.SyncPeerProtocol.runProtocol(SyncPeerProtocol.java:242) ... 6 more Caused by: java.net.SocketTimeoutException: Read timed out at java.base/sun.nio.ch.NioSocketImpl.timedRead(NioSocketImpl.java:278) at java.base/sun.nio.ch.NioSocketImpl.implRead(NioSocketImpl.java:304) at java.base/sun.nio.ch.NioSocketImpl.read(NioSocketImpl.java:346) at java.base/sun.nio.ch.NioSocketImpl$1.read(NioSocketImpl.java:796) at java.base/java.net.Socket$SocketInputStream.read(Socket.java:1099) at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:489) at java.base/sun.security.ssl.SSLSocketInputRecord.readHeader(SSLSocketInputRecord.java:483) at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:70) at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1461) at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:1066) at com.swirlds.common.io.extendable.extensions.AbstractStreamExtension.read(AbstractStreamExtension.java:73) at com.swirlds.common.io.extendable.ExtendableInputStream.read(ExtendableInputStream.java:63) at java.base/java.io.BufferedInputStream.fill(BufferedInputStream.java:291) at java.base/java.io.BufferedInputStream.read1(BufferedInputStream.java:347) at java.base/java.io.BufferedInputStream.implRead(BufferedInputStream.java:420) at java.base/java.io.BufferedInputStream.read(BufferedInputStream.java:399) at java.base/java.io.DataInputStream.readFully(DataInputStream.java:208) at java.base/java.io.DataInputStream.readInt(DataInputStream.java:385) at org.hiero.base.io.streams.AugmentedDataInputStream.readInt(AugmentedDataInputStream.java:179) at org.hiero.base.io.streams.AugmentedDataInputStream.readBooleanList(AugmentedDataInputStream.java:359) at com.swirlds.platform.gossip.shadowgraph.SyncUtils.lambda$readMyTipsTheyHave$7(SyncUtils.java:160) at com.swirlds.common.threading.pool.CachedPoolParallelExecutor.doParallel(CachedPoolParallelExecutor.java:157) ... 10 more
node3 2m 58.151s 2025-09-25 11:00:51.960 1321 WARN SOCKET_EXCEPTIONS <<platform-core: SyncProtocolWith0 3 to 0>> NetworkUtils: Connection broken: 3 <- 0
java.net.SocketTimeoutException: Read timed out at java.base/sun.nio.ch.NioSocketImpl.timedRead(NioSocketImpl.java:278) at java.base/sun.nio.ch.NioSocketImpl.implRead(NioSocketImpl.java:304) at java.base/sun.nio.ch.NioSocketImpl.read(NioSocketImpl.java:346) at java.base/sun.nio.ch.NioSocketImpl$1.read(NioSocketImpl.java:796) at java.base/java.net.Socket$SocketInputStream.read(Socket.java:1099) at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:489) at java.base/sun.security.ssl.SSLSocketInputRecord.readHeader(SSLSocketInputRecord.java:483) at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:70) at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1461) at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:1066) at com.swirlds.common.io.extendable.extensions.AbstractStreamExtension.read(AbstractStreamExtension.java:73) at com.swirlds.common.io.extendable.ExtendableInputStream.read(ExtendableInputStream.java:63) at java.base/java.io.BufferedInputStream.fill(BufferedInputStream.java:291) at java.base/java.io.BufferedInputStream.implRead(BufferedInputStream.java:325) at java.base/java.io.BufferedInputStream.read(BufferedInputStream.java:312) at java.base/java.io.FilterInputStream.read(FilterInputStream.java:71) at org.hiero.base.io.streams.AugmentedDataInputStream.read(AugmentedDataInputStream.java:57) at com.swirlds.platform.network.communication.states.SentKeepalive.transition(SentKeepalive.java:44) at com.swirlds.platform.network.communication.Negotiator.execute(Negotiator.java:79) at com.swirlds.platform.network.communication.ProtocolNegotiatorThread.run(ProtocolNegotiatorThread.java:70) at com.swirlds.common.threading.framework.internal.StoppableThreadImpl.doWork(StoppableThreadImpl.java:599) at com.swirlds.common.threading.framework.internal.StoppableThreadImpl.run(StoppableThreadImpl.java:200) at java.base/java.lang.Thread.run(Thread.java:1583)
node1 2m 58.165s 2025-09-25 11:00:51.974 1320 WARN SOCKET_EXCEPTIONS <<platform-core: SyncProtocolWith3 1 to 3>> NetworkUtils: Connection broken: 1 -> 3
java.io.IOException: com.swirlds.common.threading.pool.ParallelExecutionException: Time thrown: 2025-09-25T11:00:51.973645863Z at com.swirlds.platform.gossip.sync.protocol.SyncPeerProtocol.runProtocol(SyncPeerProtocol.java:245) at com.swirlds.platform.network.communication.states.ProtocolNegotiated.transition(ProtocolNegotiated.java:47) at com.swirlds.platform.network.communication.Negotiator.execute(Negotiator.java:79) at com.swirlds.platform.network.communication.ProtocolNegotiatorThread.run(ProtocolNegotiatorThread.java:70) at com.swirlds.common.threading.framework.internal.StoppableThreadImpl.doWork(StoppableThreadImpl.java:599) at com.swirlds.common.threading.framework.internal.StoppableThreadImpl.run(StoppableThreadImpl.java:200) at java.base/java.lang.Thread.run(Thread.java:1583) Caused by: com.swirlds.common.threading.pool.ParallelExecutionException: Time thrown: 2025-09-25T11:00:51.973645863Z at com.swirlds.common.threading.pool.CachedPoolParallelExecutor.doParallel(CachedPoolParallelExecutor.java:159) at com.swirlds.platform.gossip.shadowgraph.ShadowgraphSynchronizer.readWriteParallel(ShadowgraphSynchronizer.java:444) at com.swirlds.platform.gossip.shadowgraph.ShadowgraphSynchronizer.sendAndReceiveEvents(ShadowgraphSynchronizer.java:382) at com.swirlds.platform.gossip.shadowgraph.ShadowgraphSynchronizer.reserveSynchronize(ShadowgraphSynchronizer.java:245) at com.swirlds.platform.gossip.shadowgraph.ShadowgraphSynchronizer.synchronize(ShadowgraphSynchronizer.java:167) at com.swirlds.platform.gossip.sync.protocol.SyncPeerProtocol.runProtocol(SyncPeerProtocol.java:242) ... 6 more Caused by: java.net.SocketTimeoutException: Read timed out at java.base/sun.nio.ch.NioSocketImpl.timedRead(NioSocketImpl.java:278) at java.base/sun.nio.ch.NioSocketImpl.implRead(NioSocketImpl.java:304) at java.base/sun.nio.ch.NioSocketImpl.read(NioSocketImpl.java:346) at java.base/sun.nio.ch.NioSocketImpl$1.read(NioSocketImpl.java:796) at java.base/java.net.Socket$SocketInputStream.read(Socket.java:1099) at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:489) at java.base/sun.security.ssl.SSLSocketInputRecord.readHeader(SSLSocketInputRecord.java:483) at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:70) at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1461) at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:1066) at com.swirlds.common.io.extendable.extensions.AbstractStreamExtension.read(AbstractStreamExtension.java:73) at com.swirlds.common.io.extendable.ExtendableInputStream.read(ExtendableInputStream.java:63) at java.base/java.io.BufferedInputStream.fill(BufferedInputStream.java:291) at java.base/java.io.BufferedInputStream.implRead(BufferedInputStream.java:325) at java.base/java.io.BufferedInputStream.read(BufferedInputStream.java:312) at java.base/java.io.DataInputStream.readUnsignedByte(DataInputStream.java:295) at java.base/java.io.DataInputStream.readByte(DataInputStream.java:275) at org.hiero.base.io.streams.AugmentedDataInputStream.readByte(AugmentedDataInputStream.java:144) at com.swirlds.platform.gossip.shadowgraph.SyncUtils.lambda$readEventsINeed$9(SyncUtils.java:273) at com.swirlds.common.threading.pool.CachedPoolParallelExecutor.doParallel(CachedPoolParallelExecutor.java:157) ... 11 more
node0 2m 58.221s 2025-09-25 11:00:52.030 1322 WARN SOCKET_EXCEPTIONS <<platform-core: SyncProtocolWith3 0 to 3>> NetworkUtils: Connection broken: 0 -> 3
java.net.SocketTimeoutException: Read timed out at java.base/sun.nio.ch.NioSocketImpl.timedRead(NioSocketImpl.java:278) at java.base/sun.nio.ch.NioSocketImpl.implRead(NioSocketImpl.java:304) at java.base/sun.nio.ch.NioSocketImpl.read(NioSocketImpl.java:346) at java.base/sun.nio.ch.NioSocketImpl$1.read(NioSocketImpl.java:796) at java.base/java.net.Socket$SocketInputStream.read(Socket.java:1099) at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:489) at java.base/sun.security.ssl.SSLSocketInputRecord.readHeader(SSLSocketInputRecord.java:483) at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:70) at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1461) at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:1066) at com.swirlds.common.io.extendable.extensions.AbstractStreamExtension.read(AbstractStreamExtension.java:73) at com.swirlds.common.io.extendable.ExtendableInputStream.read(ExtendableInputStream.java:63) at java.base/java.io.BufferedInputStream.fill(BufferedInputStream.java:291) at java.base/java.io.BufferedInputStream.implRead(BufferedInputStream.java:325) at java.base/java.io.BufferedInputStream.read(BufferedInputStream.java:312) at java.base/java.io.FilterInputStream.read(FilterInputStream.java:71) at org.hiero.base.io.streams.AugmentedDataInputStream.read(AugmentedDataInputStream.java:57) at com.swirlds.platform.network.communication.states.WaitForAcceptReject.transition(WaitForAcceptReject.java:48) at com.swirlds.platform.network.communication.Negotiator.execute(Negotiator.java:79) at com.swirlds.platform.network.communication.ProtocolNegotiatorThread.run(ProtocolNegotiatorThread.java:70) at com.swirlds.common.threading.framework.internal.StoppableThreadImpl.doWork(StoppableThreadImpl.java:599) at com.swirlds.common.threading.framework.internal.StoppableThreadImpl.run(StoppableThreadImpl.java:200) at java.base/java.lang.Thread.run(Thread.java:1583)
node3 3m 2.785s 2025-09-25 11:00:56.594 1322 INFO PLATFORM_STATUS <platformForkJoinThread-3> DefaultStatusStateMachine: Platform spent 2.7 m in ACTIVE. Now in CHECKING
node3 3m 2.786s 2025-09-25 11:00:56.595 1323 INFO STARTUP <platformForkJoinThread-3> PlatformTestingToolMain: Platform Status Change CHECKING
node0 3m 2.831s 2025-09-25 11:00:56.640 1323 INFO PLATFORM_STATUS <platformForkJoinThread-5> DefaultStatusStateMachine: Platform spent 2.7 m in ACTIVE. Now in CHECKING
node0 3m 2.832s 2025-09-25 11:00:56.641 1324 INFO STARTUP <platformForkJoinThread-5> PlatformTestingToolMain: Platform Status Change CHECKING
node1 3m 2.860s 2025-09-25 11:00:56.669 1321 INFO PLATFORM_STATUS <platformForkJoinThread-3> DefaultStatusStateMachine: Platform spent 2.7 m in ACTIVE. Now in CHECKING
node1 3m 2.861s 2025-09-25 11:00:56.670 1322 INFO STARTUP <platformForkJoinThread-3> PlatformTestingToolMain: Platform Status Change CHECKING
node2 3m 2.893s 2025-09-25 11:00:56.702 1322 INFO PLATFORM_STATUS <platformForkJoinThread-5> DefaultStatusStateMachine: Platform spent 2.7 m in ACTIVE. Now in CHECKING
node2 3m 2.893s 2025-09-25 11:00:56.702 1323 INFO STARTUP <platformForkJoinThread-5> PlatformTestingToolMain: Platform Status Change CHECKING
node0 3m 41.075s 2025-09-25 11:01:34.884 1325 INFO DEMO_QUORUM <<PTT: exit-validator 0>> PlatformTestingToolMain: Terminating the JVM [ consensusTime = 2025-09-25T11:00:03.628900929Z ]
node0 3m 41.079s 2025-09-25 11:01:34.888 1326 INFO STARTUP <<PTT: exit-validator 0>> SystemExitUtils: System exit requested (NO_ERROR)
thread requesting exit: <PTT: exit-validator 0> com.swirlds.platform.system.SystemExitUtils.exitSystem(SystemExitUtils.java:37) at com.swirlds.platform.system.SystemExitUtils.exitSystem(SystemExitUtils.java:73) at com.swirlds.demo.platform.PlatformTestingToolMain.logSuccessMessageAndFinishTest(PlatformTestingToolMain.java:1206) at com.swirlds.demo.platform.PlatformTestingToolMain.lambda$handleExitValidation$21(PlatformTestingToolMain.java:1153) at java.lang.Thread.run(Thread.java:1583)
node0 3m 41.082s 2025-09-25 11:01:34.891 1327 INFO STARTUP <<PTT: exit-validator 0>> SystemExitUtils: Exiting system {"reason":"NO_ERROR","code":0} [com.swirlds.logging.legacy.payload.SystemExitPayload]
node0 3m 41.084s 2025-09-25 11:01:34.893 1328 INFO STARTUP <<browser: shutdown-hook>> Log4jSetup: JVM is shutting down.
node2 3m 41.156s 2025-09-25 11:01:34.965 1324 INFO DEMO_QUORUM <<PTT: exit-validator 2>> PlatformTestingToolMain: Terminating the JVM [ consensusTime = 2025-09-25T11:00:03.628900929Z ]
node2 3m 41.160s 2025-09-25 11:01:34.969 1325 INFO STARTUP <<PTT: exit-validator 2>> SystemExitUtils: System exit requested (NO_ERROR)
thread requesting exit: <PTT: exit-validator 2> com.swirlds.platform.system.SystemExitUtils.exitSystem(SystemExitUtils.java:37) at com.swirlds.platform.system.SystemExitUtils.exitSystem(SystemExitUtils.java:73) at com.swirlds.demo.platform.PlatformTestingToolMain.logSuccessMessageAndFinishTest(PlatformTestingToolMain.java:1206) at com.swirlds.demo.platform.PlatformTestingToolMain.lambda$handleExitValidation$21(PlatformTestingToolMain.java:1153) at java.lang.Thread.run(Thread.java:1583)
node1 3m 41.162s 2025-09-25 11:01:34.971 1323 INFO DEMO_QUORUM <<PTT: exit-validator 1>> PlatformTestingToolMain: Terminating the JVM [ consensusTime = 2025-09-25T11:00:03.628900929Z ]
node2 3m 41.162s 2025-09-25 11:01:34.971 1326 INFO STARTUP <<PTT: exit-validator 2>> SystemExitUtils: Exiting system {"reason":"NO_ERROR","code":0} [com.swirlds.logging.legacy.payload.SystemExitPayload]
node2 3m 41.163s 2025-09-25 11:01:34.972 1327 INFO STARTUP <<browser: shutdown-hook>> Log4jSetup: JVM is shutting down.
node1 3m 41.164s 2025-09-25 11:01:34.973 1324 INFO STARTUP <<PTT: exit-validator 1>> SystemExitUtils: System exit requested (NO_ERROR)
thread requesting exit: <PTT: exit-validator 1> com.swirlds.platform.system.SystemExitUtils.exitSystem(SystemExitUtils.java:37) at com.swirlds.platform.system.SystemExitUtils.exitSystem(SystemExitUtils.java:73) at com.swirlds.demo.platform.PlatformTestingToolMain.logSuccessMessageAndFinishTest(PlatformTestingToolMain.java:1206) at com.swirlds.demo.platform.PlatformTestingToolMain.lambda$handleExitValidation$21(PlatformTestingToolMain.java:1153) at java.lang.Thread.run(Thread.java:1583)
node1 3m 41.165s 2025-09-25 11:01:34.974 1325 INFO STARTUP <<PTT: exit-validator 1>> SystemExitUtils: Exiting system {"reason":"NO_ERROR","code":0} [com.swirlds.logging.legacy.payload.SystemExitPayload]
node1 3m 41.166s 2025-09-25 11:01:34.975 1326 INFO STARTUP <<browser: shutdown-hook>> Log4jSetup: JVM is shutting down.
node3 3m 41.345s 2025-09-25 11:01:35.154 1324 INFO DEMO_QUORUM <<PTT: exit-validator 3>> PlatformTestingToolMain: Terminating the JVM [ consensusTime = 2025-09-25T11:00:03.628900929Z ]
node3 3m 41.349s 2025-09-25 11:01:35.158 1325 INFO STARTUP <<PTT: exit-validator 3>> SystemExitUtils: System exit requested (NO_ERROR)
thread requesting exit: <PTT: exit-validator 3> com.swirlds.platform.system.SystemExitUtils.exitSystem(SystemExitUtils.java:37) at com.swirlds.platform.system.SystemExitUtils.exitSystem(SystemExitUtils.java:73) at com.swirlds.demo.platform.PlatformTestingToolMain.logSuccessMessageAndFinishTest(PlatformTestingToolMain.java:1206) at com.swirlds.demo.platform.PlatformTestingToolMain.lambda$handleExitValidation$21(PlatformTestingToolMain.java:1153) at java.lang.Thread.run(Thread.java:1583)
node3 3m 41.352s 2025-09-25 11:01:35.161 1326 INFO STARTUP <<PTT: exit-validator 3>> SystemExitUtils: Exiting system {"reason":"NO_ERROR","code":0} [com.swirlds.logging.legacy.payload.SystemExitPayload]
node3 3m 41.353s 2025-09-25 11:01:35.162 1327 INFO STARTUP <<browser: shutdown-hook>> Log4jSetup: JVM is shutting down.