Node ID






Columns











Log Level






Log Marker











Class












































node3 0.000ns 2025-10-15 12:07:42.231 1 INFO STARTUP <main> StaticPlatformBuilder:
////////////////////// // Node is Starting // //////////////////////
node3 88.000ms 2025-10-15 12:07:42.319 2 DEBUG STARTUP <main> StaticPlatformBuilder: main() started {} [com.swirlds.logging.legacy.payload.NodeStartPayload]
node3 106.000ms 2025-10-15 12:07:42.337 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.
node3 107.000ms 2025-10-15 12:07:42.338 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 158.000ms 2025-10-15 12:07:42.389 1 INFO STARTUP <main> StaticPlatformBuilder:
////////////////////// // Node is Starting // //////////////////////
node3 229.000ms 2025-10-15 12:07:42.460 5 INFO STARTUP <main> Browser: The following nodes [3] are set to run locally
node3 236.000ms 2025-10-15 12:07:42.467 6 INFO STARTUP <main> PlatformTestingToolMain: Registering PlatformTestingToolState with ConstructableRegistry
node2 247.000ms 2025-10-15 12:07:42.478 2 DEBUG STARTUP <main> StaticPlatformBuilder: main() started {} [com.swirlds.logging.legacy.payload.NodeStartPayload]
node2 264.000ms 2025-10-15 12:07:42.495 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 264.000ms 2025-10-15 12:07:42.495 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.
node3 267.000ms 2025-10-15 12:07:42.498 7 INFO DEMO_INFO <main> ExpectedFCMFamily: Set ExpectedMap initial capacity to be: 4000
node3 268.000ms 2025-10-15 12:07:42.499 8 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountList initial capacity to be: 1000
node3 269.000ms 2025-10-15 12:07:42.500 9 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountSelfEntitiesList initial capacity to be: 1000
node3 269.000ms 2025-10-15 12:07:42.500 10 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqList initial capacity to be: 1000
node3 270.000ms 2025-10-15 12:07:42.501 11 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqSelfEntitiesList initial capacity to be: 1000
node3 270.000ms 2025-10-15 12:07:42.501 12 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountList initial capacity to be: 1000
node3 270.000ms 2025-10-15 12:07:42.501 13 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountSelfEntitiesList initial capacity to be: 1000
node3 271.000ms 2025-10-15 12:07:42.502 14 INFO DEMO_INFO <main> ExpectedFCMFamily: Set ExpectedMap initial capacity to be: 4000
node3 272.000ms 2025-10-15 12:07:42.503 15 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountList initial capacity to be: 1000
node3 272.000ms 2025-10-15 12:07:42.503 16 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountSelfEntitiesList initial capacity to be: 1000
node3 273.000ms 2025-10-15 12:07:42.504 17 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqList initial capacity to be: 1000
node3 273.000ms 2025-10-15 12:07:42.504 18 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqSelfEntitiesList initial capacity to be: 1000
node3 273.000ms 2025-10-15 12:07:42.504 19 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountList initial capacity to be: 1000
node3 274.000ms 2025-10-15 12:07:42.505 20 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountSelfEntitiesList initial capacity to be: 1000
node3 275.000ms 2025-10-15 12:07:42.506 21 INFO STARTUP <main> PlatformTestingToolMain: PlatformTestingToolState is registered with ConstructableRegistry: -4571139351850460298
node2 382.000ms 2025-10-15 12:07:42.613 5 INFO STARTUP <main> Browser: The following nodes [2] are set to run locally
node2 389.000ms 2025-10-15 12:07:42.620 6 INFO STARTUP <main> PlatformTestingToolMain: Registering PlatformTestingToolState with ConstructableRegistry
node2 418.000ms 2025-10-15 12:07:42.649 7 INFO DEMO_INFO <main> ExpectedFCMFamily: Set ExpectedMap initial capacity to be: 4000
node2 420.000ms 2025-10-15 12:07:42.651 8 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountList initial capacity to be: 1000
node2 420.000ms 2025-10-15 12:07:42.651 9 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountSelfEntitiesList initial capacity to be: 1000
node2 421.000ms 2025-10-15 12:07:42.652 10 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqList initial capacity to be: 1000
node2 421.000ms 2025-10-15 12:07:42.652 11 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqSelfEntitiesList initial capacity to be: 1000
node2 422.000ms 2025-10-15 12:07:42.653 12 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountList initial capacity to be: 1000
node2 422.000ms 2025-10-15 12:07:42.653 13 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountSelfEntitiesList initial capacity to be: 1000
node2 423.000ms 2025-10-15 12:07:42.654 14 INFO DEMO_INFO <main> ExpectedFCMFamily: Set ExpectedMap initial capacity to be: 4000
node2 423.000ms 2025-10-15 12:07:42.654 15 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountList initial capacity to be: 1000
node2 424.000ms 2025-10-15 12:07:42.655 16 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountSelfEntitiesList initial capacity to be: 1000
node2 424.000ms 2025-10-15 12:07:42.655 17 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqList initial capacity to be: 1000
node2 424.000ms 2025-10-15 12:07:42.655 18 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqSelfEntitiesList initial capacity to be: 1000
node2 425.000ms 2025-10-15 12:07:42.656 19 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountList initial capacity to be: 1000
node2 425.000ms 2025-10-15 12:07:42.656 20 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountSelfEntitiesList initial capacity to be: 1000
node2 426.000ms 2025-10-15 12:07:42.657 21 INFO STARTUP <main> PlatformTestingToolMain: PlatformTestingToolState is registered with ConstructableRegistry: -4571139351850460298
node3 692.000ms 2025-10-15 12:07:42.923 24 DEBUG STARTUP <main> BootstrapUtils: Scanning the classpath for RuntimeConstructable classes
node2 831.000ms 2025-10-15 12:07:43.062 24 DEBUG STARTUP <main> BootstrapUtils: Scanning the classpath for RuntimeConstructable classes
node0 1.169s 2025-10-15 12:07:43.400 1 INFO STARTUP <main> StaticPlatformBuilder:
////////////////////// // Node is Starting // //////////////////////
node0 1.270s 2025-10-15 12:07:43.501 2 DEBUG STARTUP <main> StaticPlatformBuilder: main() started {} [com.swirlds.logging.legacy.payload.NodeStartPayload]
node0 1.287s 2025-10-15 12:07:43.518 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.288s 2025-10-15 12:07:43.519 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.412s 2025-10-15 12:07:43.643 5 INFO STARTUP <main> Browser: The following nodes [0] are set to run locally
node0 1.419s 2025-10-15 12:07:43.650 6 INFO STARTUP <main> PlatformTestingToolMain: Registering PlatformTestingToolState with ConstructableRegistry
node0 1.448s 2025-10-15 12:07:43.679 7 INFO DEMO_INFO <main> ExpectedFCMFamily: Set ExpectedMap initial capacity to be: 4000
node0 1.450s 2025-10-15 12:07:43.681 8 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountList initial capacity to be: 1000
node0 1.450s 2025-10-15 12:07:43.681 9 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountSelfEntitiesList initial capacity to be: 1000
node0 1.451s 2025-10-15 12:07:43.682 10 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqList initial capacity to be: 1000
node0 1.452s 2025-10-15 12:07:43.683 11 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqSelfEntitiesList initial capacity to be: 1000
node0 1.452s 2025-10-15 12:07:43.683 12 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountList initial capacity to be: 1000
node0 1.452s 2025-10-15 12:07:43.683 13 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountSelfEntitiesList initial capacity to be: 1000
node0 1.453s 2025-10-15 12:07:43.684 14 INFO DEMO_INFO <main> ExpectedFCMFamily: Set ExpectedMap initial capacity to be: 4000
node0 1.454s 2025-10-15 12:07:43.685 15 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountList initial capacity to be: 1000
node0 1.454s 2025-10-15 12:07:43.685 16 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountSelfEntitiesList initial capacity to be: 1000
node0 1.454s 2025-10-15 12:07:43.685 17 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqList initial capacity to be: 1000
node0 1.455s 2025-10-15 12:07:43.686 18 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqSelfEntitiesList initial capacity to be: 1000
node0 1.455s 2025-10-15 12:07:43.686 19 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountList initial capacity to be: 1000
node0 1.456s 2025-10-15 12:07:43.687 20 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountSelfEntitiesList initial capacity to be: 1000
node0 1.456s 2025-10-15 12:07:43.687 21 INFO STARTUP <main> PlatformTestingToolMain: PlatformTestingToolState is registered with ConstructableRegistry: -4571139351850460298
node3 1.599s 2025-10-15 12:07:43.830 25 DEBUG STARTUP <main> BootstrapUtils: Done with registerConstructables, time taken 907ms
node3 1.606s 2025-10-15 12:07:43.837 26 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 1.606s 2025-10-15 12:07:43.837 27 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 1.652s 2025-10-15 12:07:43.883 28 INFO STARTUP <main> PrometheusEndpoint: PrometheusEndpoint: Starting server listing on port: 9999
node3 1.716s 2025-10-15 12:07:43.947 29 WARN STARTUP <main> CryptoStatic: There are no keys on disk, Adhoc keys will be generated, but this is incompatible with DAB.
node3 1.716s 2025-10-15 12:07:43.947 30 DEBUG STARTUP <main> CryptoStatic: Started generating keys
node2 1.793s 2025-10-15 12:07:44.024 25 DEBUG STARTUP <main> BootstrapUtils: Done with registerConstructables, time taken 961ms
node2 1.799s 2025-10-15 12:07:44.030 26 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.799s 2025-10-15 12:07:44.030 27 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.835s 2025-10-15 12:07:44.066 28 INFO STARTUP <main> PrometheusEndpoint: PrometheusEndpoint: Starting server listing on port: 9999
node0 1.871s 2025-10-15 12:07:44.102 24 DEBUG STARTUP <main> BootstrapUtils: Scanning the classpath for RuntimeConstructable classes
node2 1.915s 2025-10-15 12:07:44.146 29 WARN STARTUP <main> CryptoStatic: There are no keys on disk, Adhoc keys will be generated, but this is incompatible with DAB.
node2 1.916s 2025-10-15 12:07:44.147 30 DEBUG STARTUP <main> CryptoStatic: Started generating keys
node1 2.239s 2025-10-15 12:07:44.470 1 INFO STARTUP <main> StaticPlatformBuilder:
////////////////////// // Node is Starting // //////////////////////
node1 2.347s 2025-10-15 12:07:44.578 2 DEBUG STARTUP <main> StaticPlatformBuilder: main() started {} [com.swirlds.logging.legacy.payload.NodeStartPayload]
node1 2.367s 2025-10-15 12:07:44.598 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 2.369s 2025-10-15 12:07:44.600 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 2.517s 2025-10-15 12:07:44.748 5 INFO STARTUP <main> Browser: The following nodes [1] are set to run locally
node1 2.526s 2025-10-15 12:07:44.757 6 INFO STARTUP <main> PlatformTestingToolMain: Registering PlatformTestingToolState with ConstructableRegistry
node1 2.562s 2025-10-15 12:07:44.793 7 INFO DEMO_INFO <main> ExpectedFCMFamily: Set ExpectedMap initial capacity to be: 4000
node1 2.564s 2025-10-15 12:07:44.795 8 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountList initial capacity to be: 1000
node1 2.565s 2025-10-15 12:07:44.796 9 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountSelfEntitiesList initial capacity to be: 1000
node1 2.566s 2025-10-15 12:07:44.797 10 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqList initial capacity to be: 1000
node1 2.566s 2025-10-15 12:07:44.797 11 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqSelfEntitiesList initial capacity to be: 1000
node1 2.567s 2025-10-15 12:07:44.798 12 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountList initial capacity to be: 1000
node1 2.567s 2025-10-15 12:07:44.798 13 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountSelfEntitiesList initial capacity to be: 1000
node1 2.568s 2025-10-15 12:07:44.799 14 INFO DEMO_INFO <main> ExpectedFCMFamily: Set ExpectedMap initial capacity to be: 4000
node1 2.568s 2025-10-15 12:07:44.799 15 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountList initial capacity to be: 1000
node1 2.569s 2025-10-15 12:07:44.800 16 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountSelfEntitiesList initial capacity to be: 1000
node1 2.569s 2025-10-15 12:07:44.800 17 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqList initial capacity to be: 1000
node1 2.570s 2025-10-15 12:07:44.801 18 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqSelfEntitiesList initial capacity to be: 1000
node1 2.570s 2025-10-15 12:07:44.801 19 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountList initial capacity to be: 1000
node1 2.571s 2025-10-15 12:07:44.802 20 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountSelfEntitiesList initial capacity to be: 1000
node1 2.572s 2025-10-15 12:07:44.803 21 INFO STARTUP <main> PlatformTestingToolMain: PlatformTestingToolState is registered with ConstructableRegistry: -4571139351850460298
node0 2.952s 2025-10-15 12:07:45.183 25 DEBUG STARTUP <main> BootstrapUtils: Done with registerConstructables, time taken 1081ms
node0 2.960s 2025-10-15 12:07:45.191 26 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 2.960s 2025-10-15 12:07:45.191 27 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.002s 2025-10-15 12:07:45.233 28 INFO STARTUP <main> PrometheusEndpoint: PrometheusEndpoint: Starting server listing on port: 9999
node1 3.042s 2025-10-15 12:07:45.273 24 DEBUG STARTUP <main> BootstrapUtils: Scanning the classpath for RuntimeConstructable classes
node0 3.072s 2025-10-15 12:07:45.303 29 WARN STARTUP <main> CryptoStatic: There are no keys on disk, Adhoc keys will be generated, but this is incompatible with DAB.
node0 3.073s 2025-10-15 12:07:45.304 30 DEBUG STARTUP <main> CryptoStatic: Started generating keys
node3 3.714s 2025-10-15 12:07:45.945 31 DEBUG STARTUP <main> CryptoStatic: Done generating keys
node3 3.791s 2025-10-15 12:07:46.022 34 INFO STARTUP <main> StartupStateUtils: No saved states were found on disk.
node3 3.792s 2025-10-15 12:07:46.023 35 INFO DEMO_INFO <main> ExpectedFCMFamily: Set ExpectedMap initial capacity to be: 4000
node3 3.792s 2025-10-15 12:07:46.023 36 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountList initial capacity to be: 1000
node3 3.792s 2025-10-15 12:07:46.023 37 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountSelfEntitiesList initial capacity to be: 1000
node3 3.793s 2025-10-15 12:07:46.024 38 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqList initial capacity to be: 1000
node3 3.793s 2025-10-15 12:07:46.024 39 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqSelfEntitiesList initial capacity to be: 1000
node3 3.793s 2025-10-15 12:07:46.024 40 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountList initial capacity to be: 1000
node3 3.793s 2025-10-15 12:07:46.024 41 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountSelfEntitiesList initial capacity to be: 1000
node2 3.907s 2025-10-15 12:07:46.138 31 DEBUG STARTUP <main> CryptoStatic: Done generating keys
node2 3.998s 2025-10-15 12:07:46.229 34 INFO STARTUP <main> StartupStateUtils: No saved states were found on disk.
node2 3.999s 2025-10-15 12:07:46.230 35 INFO DEMO_INFO <main> ExpectedFCMFamily: Set ExpectedMap initial capacity to be: 4000
node2 3.999s 2025-10-15 12:07:46.230 36 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountList initial capacity to be: 1000
node2 3.999s 2025-10-15 12:07:46.230 37 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountSelfEntitiesList initial capacity to be: 1000
node2 4.000s 2025-10-15 12:07:46.231 38 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqList initial capacity to be: 1000
node2 4.000s 2025-10-15 12:07:46.231 39 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqSelfEntitiesList initial capacity to be: 1000
node2 4.000s 2025-10-15 12:07:46.231 40 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountList initial capacity to be: 1000
node2 4.001s 2025-10-15 12:07:46.232 41 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountSelfEntitiesList initial capacity to be: 1000
node1 4.213s 2025-10-15 12:07:46.444 25 DEBUG STARTUP <main> BootstrapUtils: Done with registerConstructables, time taken 1169ms
node1 4.220s 2025-10-15 12:07:46.451 26 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 4.221s 2025-10-15 12:07:46.452 27 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 4.262s 2025-10-15 12:07:46.493 28 INFO STARTUP <main> PrometheusEndpoint: PrometheusEndpoint: Starting server listing on port: 9999
node1 4.326s 2025-10-15 12:07:46.557 29 WARN STARTUP <main> CryptoStatic: There are no keys on disk, Adhoc keys will be generated, but this is incompatible with DAB.
node1 4.326s 2025-10-15 12:07:46.557 30 DEBUG STARTUP <main> CryptoStatic: Started generating keys
node3 4.587s 2025-10-15 12:07:46.818 51 INFO STARTUP <main> BootstrapUtils: Not upgrading software, current software is version SemanticVersion[major=1, minor=0, patch=0, pre=, build=].
node3 4.592s 2025-10-15 12:07:46.823 52 INFO STARTUP <main> AddressBookInitializer: Starting from genesis: using the config address book.
node2 4.810s 2025-10-15 12:07:47.041 51 INFO STARTUP <main> BootstrapUtils: Not upgrading software, current software is version SemanticVersion[major=1, minor=0, patch=0, pre=, build=].
node2 4.816s 2025-10-15 12:07:47.047 52 INFO STARTUP <main> AddressBookInitializer: Starting from genesis: using the config address book.
node0 5.105s 2025-10-15 12:07:47.336 31 DEBUG STARTUP <main> CryptoStatic: Done generating keys
node0 5.197s 2025-10-15 12:07:47.428 34 INFO STARTUP <main> StartupStateUtils: No saved states were found on disk.
node0 5.198s 2025-10-15 12:07:47.429 35 INFO DEMO_INFO <main> ExpectedFCMFamily: Set ExpectedMap initial capacity to be: 4000
node0 5.198s 2025-10-15 12:07:47.429 36 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountList initial capacity to be: 1000
node0 5.199s 2025-10-15 12:07:47.430 37 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountSelfEntitiesList initial capacity to be: 1000
node0 5.199s 2025-10-15 12:07:47.430 38 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqList initial capacity to be: 1000
node0 5.199s 2025-10-15 12:07:47.430 39 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqSelfEntitiesList initial capacity to be: 1000
node0 5.199s 2025-10-15 12:07:47.430 40 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountList initial capacity to be: 1000
node0 5.200s 2025-10-15 12:07:47.431 41 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountSelfEntitiesList initial capacity to be: 1000
node3 5.703s 2025-10-15 12:07:47.934 53 INFO STARTUP <main> OSHealthChecker:
PASSED - Clock Source Speed Check Report[callsPerSec=26220648] PASSED - Entropy Check Report[success=true, entropySource=Strong Instance, elapsedNanos=160820, randomLong=-5360805186068448869, exception=null] PASSED - Entropy Check Report[success=true, entropySource=NativePRNGBlocking:SUN, elapsedNanos=12200, randomLong=-3728571184619959775, exception=null] PASSED - File System Check Report[code=SUCCESS, readNanos=995230, data=35, exception=null] OS Health Check Report - Complete (took 1020 ms)
node3 5.732s 2025-10-15 12:07:47.963 54 DEBUG STARTUP <main> BootstrapUtils: jvmPauseDetectorThread started
node3 5.739s 2025-10-15 12:07:47.970 55 INFO STARTUP <main> StandardScratchpad: Scratchpad platform.iss contents:
LAST_ISS_ROUND null
node3 5.744s 2025-10-15 12:07:47.975 56 INFO STARTUP <main> PlatformBuilder: Default platform pool parallelism: 8
node3 5.826s 2025-10-15 12:07:48.057 57 INFO STARTUP <main> SwirldsPlatform: Starting with roster history:
RosterHistory[ currentRosterRound: 0 ][ no previous roster set ] Current Roster: { "rosterEntries": [{ "weight": "16125000000", "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": "IqwG/Q==", "port": 30124 }, { "ipAddressV4": "CoAAHQ==", "port": 30124 }] }, { "nodeId": "1", "weight": "14625000000", "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": "Ih20Yg==", "port": 30125 }, { "ipAddressV4": "CoAADw==", "port": 30125 }] }, { "nodeId": "2", "weight": "2500000000", "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": "I+FSFA==", "port": 30126 }, { "ipAddressV4": "CoAAGg==", "port": 30126 }] }, { "nodeId": "3", "weight": "16750000000", "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": "iHBQuA==", "port": 30127 }, { "ipAddressV4": "CoAAGw==", "port": 30127 }] }] }
node3 5.924s 2025-10-15 12:07:48.155 58 INFO STARTUP <main> PlatformTestingToolState: Trigger and PreviousSoftwareVersion state received in init function {"trigger":"GENESIS","previousSoftwareVersion":"null"} [com.swirlds.logging.legacy.payload.SoftwareVersionPayload]
node3 5.925s 2025-10-15 12:07:48.156 59 INFO STARTUP <main> PlatformTestingToolState: Set QuorumResult from genesisInit()
node2 5.940s 2025-10-15 12:07:48.171 53 INFO STARTUP <main> OSHealthChecker:
PASSED - Clock Source Speed Check Report[callsPerSec=26356685] PASSED - Entropy Check Report[success=true, entropySource=Strong Instance, elapsedNanos=217150, randomLong=3293405640565767027, exception=null] PASSED - Entropy Check Report[success=true, entropySource=NativePRNGBlocking:SUN, elapsedNanos=7320, randomLong=-4604902795700482611, exception=null] PASSED - File System Check Report[code=SUCCESS, readNanos=1191241, data=35, exception=null] OS Health Check Report - Complete (took 1021 ms)
node3 5.940s 2025-10-15 12:07:48.171 60 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: db5ed32abf4b39df92f5abbca1b9ff02002b11eaf0f41595ace43577dbd4bae7bfb92fbb39fff6a9e13c9ebf117b9e54 (root) PlatformTestingToolState / nephew-onion-birth-desk 0 SingletonNode PlatformStateService.PLATFORM_STATE /0 method-topple-elite-gate 1 VirtualMap RosterService.ROSTERS /1 alpha-fiction-denial-ensure 2 SingletonNode RosterService.ROSTER_STATE /2 dismiss-west-wonder-outdoor 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.969s 2025-10-15 12:07:48.200 54 DEBUG STARTUP <main> BootstrapUtils: jvmPauseDetectorThread started
node2 5.978s 2025-10-15 12:07:48.209 55 INFO STARTUP <main> StandardScratchpad: Scratchpad platform.iss contents:
LAST_ISS_ROUND null
node2 5.984s 2025-10-15 12:07:48.215 56 INFO STARTUP <main> PlatformBuilder: Default platform pool parallelism: 8
node0 6.019s 2025-10-15 12:07:48.250 51 INFO STARTUP <main> BootstrapUtils: Not upgrading software, current software is version SemanticVersion[major=1, minor=0, patch=0, pre=, build=].
node0 6.025s 2025-10-15 12:07:48.256 52 INFO STARTUP <main> AddressBookInitializer: Starting from genesis: using the config address book.
node2 6.067s 2025-10-15 12:07:48.298 57 INFO STARTUP <main> SwirldsPlatform: Starting with roster history:
RosterHistory[ currentRosterRound: 0 ][ no previous roster set ] Current Roster: { "rosterEntries": [{ "weight": "16125000000", "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": "IqwG/Q==", "port": 30124 }, { "ipAddressV4": "CoAAHQ==", "port": 30124 }] }, { "nodeId": "1", "weight": "14625000000", "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": "Ih20Yg==", "port": 30125 }, { "ipAddressV4": "CoAADw==", "port": 30125 }] }, { "nodeId": "2", "weight": "2500000000", "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": "I+FSFA==", "port": 30126 }, { "ipAddressV4": "CoAAGg==", "port": 30126 }] }, { "nodeId": "3", "weight": "16750000000", "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": "iHBQuA==", "port": 30127 }, { "ipAddressV4": "CoAAGw==", "port": 30127 }] }] }
node3 6.135s 2025-10-15 12:07:48.366 62 INFO EVENT_STREAM <main> DefaultConsensusEventStream: EventStreamManager::updateRunningHash: 38b060a751ac96384cd9327eb1b1e36a21fdb71114be07434c0cc7bf63f6e1da274edebfe76f65fbd51ad2f14898b95b
node3 6.139s 2025-10-15 12:07:48.370 63 INFO STARTUP <platformForkJoinThread-1> Shadowgraph: Shadowgraph starting from expiration threshold 1
node3 6.148s 2025-10-15 12:07:48.379 64 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: -Xms32g
node3 6.149s 2025-10-15 12:07:48.380 65 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: -Xmx118g
node3 6.150s 2025-10-15 12:07:48.381 66 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: -XX:+UnlockExperimentalVMOptions
node3 6.150s 2025-10-15 12:07:48.381 67 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: -XX:+UseZGC
node3 6.150s 2025-10-15 12:07:48.381 68 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: -XX:ZAllocationSpikeTolerance=2
node3 6.151s 2025-10-15 12:07:48.382 69 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: -XX:ConcGCThreads=14
node3 6.151s 2025-10-15 12:07:48.382 70 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: -XX:ZMarkStackSpaceLimit=16g
node3 6.151s 2025-10-15 12:07:48.382 71 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: -XX:MaxDirectMemorySize=64g
node3 6.152s 2025-10-15 12:07:48.383 72 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: -XX:MetaspaceSize=100M
node3 6.152s 2025-10-15 12:07:48.383 73 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: -XX:+ZGenerational
node3 6.152s 2025-10-15 12:07:48.383 74 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: -Xlog:gc*:gc.log
node3 6.153s 2025-10-15 12:07:48.384 75 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: --add-opens=java.base/jdk.internal.misc=ALL-UNNAMED
node3 6.153s 2025-10-15 12:07:48.384 76 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: --add-opens=java.base/java.nio=ALL-UNNAMED
node3 6.154s 2025-10-15 12:07:48.385 77 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: JVM arg: -Dio.netty.tryReflectionSetAccessible=true
node3 6.154s 2025-10-15 12:07:48.385 78 INFO STARTUP <<start-node-3>> PlatformTestingToolMain: Parsing JSON FCM-VM-NIReconnect-125-10m.json
node2 6.163s 2025-10-15 12:07:48.394 58 INFO STARTUP <main> PlatformTestingToolState: Trigger and PreviousSoftwareVersion state received in init function {"trigger":"GENESIS","previousSoftwareVersion":"null"} [com.swirlds.logging.legacy.payload.SoftwareVersionPayload]
node2 6.164s 2025-10-15 12:07:48.395 59 INFO STARTUP <main> PlatformTestingToolState: Set QuorumResult from genesisInit()
node3 6.176s 2025-10-15 12:07:48.407 79 INFO DEMO_INFO <<start-node-3>> PayloadConfig: insertSeq = true
node3 6.177s 2025-10-15 12:07:48.408 80 INFO DEMO_INFO <<start-node-3>> PayloadConfig: appendSig = true
node3 6.177s 2025-10-15 12:07:48.408 81 INFO DEMO_INFO <<start-node-3>> PayloadConfig: variedSize = false
node3 6.178s 2025-10-15 12:07:48.409 82 INFO DEMO_INFO <<start-node-3>> PayloadConfig: payloadByteSize = 100
node3 6.178s 2025-10-15 12:07:48.409 83 INFO DEMO_INFO <<start-node-3>> PayloadConfig: maxByteSize = 100
node2 6.179s 2025-10-15 12:07:48.410 60 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: db5ed32abf4b39df92f5abbca1b9ff02002b11eaf0f41595ace43577dbd4bae7bfb92fbb39fff6a9e13c9ebf117b9e54 (root) PlatformTestingToolState / nephew-onion-birth-desk 0 SingletonNode PlatformStateService.PLATFORM_STATE /0 method-topple-elite-gate 1 VirtualMap RosterService.ROSTERS /1 alpha-fiction-denial-ensure 2 SingletonNode RosterService.ROSTER_STATE /2 dismiss-west-wonder-outdoor 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 6.179s 2025-10-15 12:07:48.410 84 INFO DEMO_INFO <<start-node-3>> PayloadConfig: type = TYPE_FCM_VIRTUAL_MIX
node3 6.179s 2025-10-15 12:07:48.410 85 INFO DEMO_INFO <<start-node-3>> PayloadConfig: invalidSigRatio = 0.0
node3 6.181s 2025-10-15 12:07:48.412 86 INFO DEMO_INFO <<start-node-3>> TransactionSubmitter: Goal is TRANS_PER_SECOND_PER_NODE targe 125.0
node3 6.186s 2025-10-15 12:07:48.417 87 INFO DEMO_INFO <<start-node-3>> VirtualMerkleStateInitializer: State = com.swirlds.demo.platform.PlatformTestingToolState@5efc8dd0
node3 6.187s 2025-10-15 12:07:48.418 88 INFO DEMO_INFO <<start-node-3>> VirtualMerkleStateInitializer: total accounts = 2400
node3 6.188s 2025-10-15 12:07:48.419 89 INFO DEMO_INFO <<start-node-3>> VirtualMerkleStateInitializer: Creating virtualmap for 2400 accounts.
node3 6.196s 2025-10-15 12:07:48.427 94 INFO DEMO_INFO <<start-node-3>> VirtualMerkleStateInitializer: accounts VM = com.swirlds.virtualmap.VirtualMap@53967915, DS = MerkleDbDataSource[initialCapacity=1000000,preferDiskBasedIndexes=false,pathToDiskLocationInternalNodes.size=0,pathToDiskLocationLeafNodes.size=0,hashesRamToDiskThreshold=8388608,hashStoreRam.size=0,hashStoreDisk=com.swirlds.merkledb.files.MemoryIndexDiskKeyValueStore@a431ccd,hasDiskStoreForHashes=true,keyToPath=com.swirlds.merkledb.files.hashmap.HalfDiskHashMap@36bf0625,pathToKeyValue=com.swirlds.merkledb.files.MemoryIndexDiskKeyValueStore@5cc9ad2e,snapshotInProgress=false]
node3 6.204s 2025-10-15 12:07:48.435 95 INFO DEMO_INFO <<start-node-3>> VirtualMerkleStateInitializer: max KV pairs = 0
node3 6.204s 2025-10-15 12:07:48.435 96 INFO DEMO_INFO <<start-node-3>> VirtualMerkleStateInitializer: total SC = 0
node3 6.209s 2025-10-15 12:07:48.440 97 INFO DEMO_INFO <<start-node-3>> FCMTransactionPool: Random seed for FCMTransactionPool is 6071279828736460271
node3 6.209s 2025-10-15 12:07:48.440 98 INFO DEMO_INFO <<start-node-3>> FCMTransactionPool: This test will generate sequential FCM payload:
node3 6.289s 2025-10-15 12:07:48.520 99 INFO STARTUP <<start-node-3>> SwirldsPlatform: Starting platform 3
node3 6.291s 2025-10-15 12:07:48.522 100 INFO STARTUP <<platform: recycle-bin-cleanup>> RecycleBinImpl: Deleted 0 files from the recycle bin.
node3 6.294s 2025-10-15 12:07:48.525 101 INFO STARTUP <<start-node-3>> CycleFinder: No cyclical back pressure detected in wiring model.
node3 6.295s 2025-10-15 12:07:48.526 102 INFO STARTUP <<start-node-3>> DirectSchedulerChecks: No illegal direct scheduler use detected in the wiring model.
node3 6.296s 2025-10-15 12:07:48.527 103 INFO STARTUP <<start-node-3>> InputWireChecks: All input wires have been bound.
node3 6.298s 2025-10-15 12:07:48.529 104 WARN STARTUP <<start-node-3>> PcesFileTracker: No preconsensus event files available
node3 6.299s 2025-10-15 12:07:48.530 105 INFO STARTUP <<start-node-3>> SwirldsPlatform: replaying preconsensus event stream starting at 0
node3 6.300s 2025-10-15 12:07:48.531 106 INFO STARTUP <<start-node-3>> PcesReplayer: Replayed 0 preconsensus events with max birth round -1. 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 6.301s 2025-10-15 12:07:48.532 107 INFO DEMO_INFO <<app: appMain 3>> PlatformTestingToolMain: Node 3 starts transactions ........
node3 6.301s 2025-10-15 12:07:48.532 108 INFO PLATFORM_STATUS <platformForkJoinThread-4> DefaultStatusStateMachine: Platform spent 308.0 ms in STARTING_UP. Now in REPLAYING_EVENTS
node3 6.305s 2025-10-15 12:07:48.536 109 INFO STARTUP <platformForkJoinThread-4> PlatformTestingToolMain: Platform Status Change REPLAYING_EVENTS
node3 6.306s 2025-10-15 12:07:48.537 110 INFO PLATFORM_STATUS <platformForkJoinThread-4> DefaultStatusStateMachine: Platform spent 3.0 ms in REPLAYING_EVENTS. Now in OBSERVING
node3 6.306s 2025-10-15 12:07:48.537 111 INFO STARTUP <platformForkJoinThread-4> PlatformTestingToolMain: Platform Status Change OBSERVING
node1 6.342s 2025-10-15 12:07:48.573 31 DEBUG STARTUP <main> CryptoStatic: Done generating keys
node2 6.393s 2025-10-15 12:07:48.624 62 INFO EVENT_STREAM <main> DefaultConsensusEventStream: EventStreamManager::updateRunningHash: 38b060a751ac96384cd9327eb1b1e36a21fdb71114be07434c0cc7bf63f6e1da274edebfe76f65fbd51ad2f14898b95b
node2 6.399s 2025-10-15 12:07:48.630 63 INFO STARTUP <platformForkJoinThread-2> Shadowgraph: Shadowgraph starting from expiration threshold 1
node2 6.409s 2025-10-15 12:07:48.640 64 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: -Xms32g
node2 6.410s 2025-10-15 12:07:48.641 65 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: -Xmx118g
node2 6.410s 2025-10-15 12:07:48.641 66 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: -XX:+UnlockExperimentalVMOptions
node2 6.411s 2025-10-15 12:07:48.642 67 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: -XX:+UseZGC
node2 6.411s 2025-10-15 12:07:48.642 68 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: -XX:ZAllocationSpikeTolerance=2
node2 6.411s 2025-10-15 12:07:48.642 69 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: -XX:ConcGCThreads=14
node2 6.411s 2025-10-15 12:07:48.642 70 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: -XX:ZMarkStackSpaceLimit=16g
node2 6.412s 2025-10-15 12:07:48.643 71 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: -XX:MaxDirectMemorySize=64g
node2 6.412s 2025-10-15 12:07:48.643 72 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: -XX:MetaspaceSize=100M
node2 6.412s 2025-10-15 12:07:48.643 73 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: -XX:+ZGenerational
node2 6.413s 2025-10-15 12:07:48.644 74 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: -Xlog:gc*:gc.log
node2 6.413s 2025-10-15 12:07:48.644 75 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: --add-opens=java.base/jdk.internal.misc=ALL-UNNAMED
node2 6.413s 2025-10-15 12:07:48.644 76 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: --add-opens=java.base/java.nio=ALL-UNNAMED
node2 6.413s 2025-10-15 12:07:48.644 77 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: JVM arg: -Dio.netty.tryReflectionSetAccessible=true
node2 6.413s 2025-10-15 12:07:48.644 78 INFO STARTUP <<start-node-2>> PlatformTestingToolMain: Parsing JSON FCM-VM-NIReconnect-125-10m.json
node2 6.429s 2025-10-15 12:07:48.660 79 INFO DEMO_INFO <<start-node-2>> PayloadConfig: insertSeq = true
node1 6.430s 2025-10-15 12:07:48.661 34 INFO STARTUP <main> StartupStateUtils: No saved states were found on disk.
node2 6.430s 2025-10-15 12:07:48.661 80 INFO DEMO_INFO <<start-node-2>> PayloadConfig: appendSig = true
node2 6.430s 2025-10-15 12:07:48.661 81 INFO DEMO_INFO <<start-node-2>> PayloadConfig: variedSize = false
node1 6.431s 2025-10-15 12:07:48.662 35 INFO DEMO_INFO <main> ExpectedFCMFamily: Set ExpectedMap initial capacity to be: 4000
node1 6.431s 2025-10-15 12:07:48.662 36 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountList initial capacity to be: 1000
node2 6.431s 2025-10-15 12:07:48.662 82 INFO DEMO_INFO <<start-node-2>> PayloadConfig: payloadByteSize = 100
node2 6.431s 2025-10-15 12:07:48.662 83 INFO DEMO_INFO <<start-node-2>> PayloadConfig: maxByteSize = 100
node1 6.432s 2025-10-15 12:07:48.663 37 INFO DEMO_INFO <main> ExpectedFCMFamily: Set accountSelfEntitiesList initial capacity to be: 1000
node1 6.432s 2025-10-15 12:07:48.663 38 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqList initial capacity to be: 1000
node1 6.432s 2025-10-15 12:07:48.663 39 INFO DEMO_INFO <main> ExpectedFCMFamily: Set fcqSelfEntitiesList initial capacity to be: 1000
node2 6.432s 2025-10-15 12:07:48.663 84 INFO DEMO_INFO <<start-node-2>> PayloadConfig: type = TYPE_FCM_VIRTUAL_MIX
node2 6.432s 2025-10-15 12:07:48.663 85 INFO DEMO_INFO <<start-node-2>> PayloadConfig: invalidSigRatio = 0.0
node1 6.433s 2025-10-15 12:07:48.664 40 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountList initial capacity to be: 1000
node1 6.433s 2025-10-15 12:07:48.664 41 INFO DEMO_INFO <main> ExpectedFCMFamily: Set virtual-merkle-accountSelfEntitiesList initial capacity to be: 1000
node2 6.434s 2025-10-15 12:07:48.665 86 INFO DEMO_INFO <<start-node-2>> TransactionSubmitter: Goal is TRANS_PER_SECOND_PER_NODE targe 125.0
node2 6.438s 2025-10-15 12:07:48.669 87 INFO DEMO_INFO <<start-node-2>> VirtualMerkleStateInitializer: State = com.swirlds.demo.platform.PlatformTestingToolState@17dbc940
node2 6.440s 2025-10-15 12:07:48.671 88 INFO DEMO_INFO <<start-node-2>> VirtualMerkleStateInitializer: total accounts = 2400
node2 6.440s 2025-10-15 12:07:48.671 89 INFO DEMO_INFO <<start-node-2>> VirtualMerkleStateInitializer: Creating virtualmap for 2400 accounts.
node2 6.447s 2025-10-15 12:07:48.678 94 INFO DEMO_INFO <<start-node-2>> VirtualMerkleStateInitializer: accounts VM = com.swirlds.virtualmap.VirtualMap@42d4deab, DS = MerkleDbDataSource[initialCapacity=1000000,preferDiskBasedIndexes=false,pathToDiskLocationInternalNodes.size=0,pathToDiskLocationLeafNodes.size=0,hashesRamToDiskThreshold=8388608,hashStoreRam.size=0,hashStoreDisk=com.swirlds.merkledb.files.MemoryIndexDiskKeyValueStore@1e9e5c8d,hasDiskStoreForHashes=true,keyToPath=com.swirlds.merkledb.files.hashmap.HalfDiskHashMap@4391beab,pathToKeyValue=com.swirlds.merkledb.files.MemoryIndexDiskKeyValueStore@3b64b14c,snapshotInProgress=false]
node2 6.455s 2025-10-15 12:07:48.686 95 INFO DEMO_INFO <<start-node-2>> VirtualMerkleStateInitializer: max KV pairs = 0
node2 6.456s 2025-10-15 12:07:48.687 96 INFO DEMO_INFO <<start-node-2>> VirtualMerkleStateInitializer: total SC = 0
node2 6.461s 2025-10-15 12:07:48.692 97 INFO DEMO_INFO <<start-node-2>> FCMTransactionPool: Random seed for FCMTransactionPool is 2251652063817816366
node2 6.461s 2025-10-15 12:07:48.692 98 INFO DEMO_INFO <<start-node-2>> FCMTransactionPool: This test will generate sequential FCM payload:
node2 6.541s 2025-10-15 12:07:48.772 99 INFO STARTUP <<start-node-2>> SwirldsPlatform: Starting platform 2
node2 6.543s 2025-10-15 12:07:48.774 100 INFO STARTUP <<platform: recycle-bin-cleanup>> RecycleBinImpl: Deleted 0 files from the recycle bin.
node2 6.547s 2025-10-15 12:07:48.778 101 INFO STARTUP <<start-node-2>> CycleFinder: No cyclical back pressure detected in wiring model.
node2 6.548s 2025-10-15 12:07:48.779 102 INFO STARTUP <<start-node-2>> DirectSchedulerChecks: No illegal direct scheduler use detected in the wiring model.
node2 6.549s 2025-10-15 12:07:48.780 103 INFO STARTUP <<start-node-2>> InputWireChecks: All input wires have been bound.
node2 6.551s 2025-10-15 12:07:48.782 104 WARN STARTUP <<start-node-2>> PcesFileTracker: No preconsensus event files available
node2 6.551s 2025-10-15 12:07:48.782 105 INFO STARTUP <<start-node-2>> SwirldsPlatform: replaying preconsensus event stream starting at 0
node2 6.553s 2025-10-15 12:07:48.784 106 INFO STARTUP <<start-node-2>> PcesReplayer: Replayed 0 preconsensus events with max birth round -1. 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 6.553s 2025-10-15 12:07:48.784 107 INFO DEMO_INFO <<app: appMain 2>> PlatformTestingToolMain: Node 2 starts transactions ........
node2 6.554s 2025-10-15 12:07:48.785 108 INFO PLATFORM_STATUS <platformForkJoinThread-3> DefaultStatusStateMachine: Platform spent 322.0 ms in STARTING_UP. Now in REPLAYING_EVENTS
node2 6.557s 2025-10-15 12:07:48.788 109 INFO STARTUP <platformForkJoinThread-3> PlatformTestingToolMain: Platform Status Change REPLAYING_EVENTS
node2 6.558s 2025-10-15 12:07:48.789 110 INFO PLATFORM_STATUS <platformForkJoinThread-3> DefaultStatusStateMachine: Platform spent 3.0 ms in REPLAYING_EVENTS. Now in OBSERVING
node2 6.559s 2025-10-15 12:07:48.790 111 INFO STARTUP <platformForkJoinThread-3> PlatformTestingToolMain: Platform Status Change OBSERVING
node0 7.144s 2025-10-15 12:07:49.375 53 INFO STARTUP <main> OSHealthChecker:
PASSED - Clock Source Speed Check Report[callsPerSec=26258978] PASSED - Entropy Check Report[success=true, entropySource=Strong Instance, elapsedNanos=246821, randomLong=-3004833919708190996, exception=null] PASSED - Entropy Check Report[success=true, entropySource=NativePRNGBlocking:SUN, elapsedNanos=9510, randomLong=2796488098594577016, exception=null] PASSED - File System Check Report[code=SUCCESS, readNanos=968371, data=35, exception=null] OS Health Check Report - Complete (took 1023 ms)
node0 7.177s 2025-10-15 12:07:49.408 54 DEBUG STARTUP <main> BootstrapUtils: jvmPauseDetectorThread started
node0 7.185s 2025-10-15 12:07:49.416 55 INFO STARTUP <main> StandardScratchpad: Scratchpad platform.iss contents:
LAST_ISS_ROUND null
node0 7.190s 2025-10-15 12:07:49.421 56 INFO STARTUP <main> PlatformBuilder: Default platform pool parallelism: 8
node0 7.275s 2025-10-15 12:07:49.506 57 INFO STARTUP <main> SwirldsPlatform: Starting with roster history:
RosterHistory[ currentRosterRound: 0 ][ no previous roster set ] Current Roster: { "rosterEntries": [{ "weight": "16125000000", "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": "IqwG/Q==", "port": 30124 }, { "ipAddressV4": "CoAAHQ==", "port": 30124 }] }, { "nodeId": "1", "weight": "14625000000", "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": "Ih20Yg==", "port": 30125 }, { "ipAddressV4": "CoAADw==", "port": 30125 }] }, { "nodeId": "2", "weight": "2500000000", "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": "I+FSFA==", "port": 30126 }, { "ipAddressV4": "CoAAGg==", "port": 30126 }] }, { "nodeId": "3", "weight": "16750000000", "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": "iHBQuA==", "port": 30127 }, { "ipAddressV4": "CoAAGw==", "port": 30127 }] }] }
node1 7.302s 2025-10-15 12:07:49.533 51 INFO STARTUP <main> BootstrapUtils: Not upgrading software, current software is version SemanticVersion[major=1, minor=0, patch=0, pre=, build=].
node1 7.308s 2025-10-15 12:07:49.539 52 INFO STARTUP <main> AddressBookInitializer: Starting from genesis: using the config address book.
node0 7.377s 2025-10-15 12:07:49.608 58 INFO STARTUP <main> PlatformTestingToolState: Trigger and PreviousSoftwareVersion state received in init function {"trigger":"GENESIS","previousSoftwareVersion":"null"} [com.swirlds.logging.legacy.payload.SoftwareVersionPayload]
node0 7.378s 2025-10-15 12:07:49.609 59 INFO STARTUP <main> PlatformTestingToolState: Set QuorumResult from genesisInit()
node0 7.394s 2025-10-15 12:07:49.625 60 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: db5ed32abf4b39df92f5abbca1b9ff02002b11eaf0f41595ace43577dbd4bae7bfb92fbb39fff6a9e13c9ebf117b9e54 (root) PlatformTestingToolState / nephew-onion-birth-desk 0 SingletonNode PlatformStateService.PLATFORM_STATE /0 method-topple-elite-gate 1 VirtualMap RosterService.ROSTERS /1 alpha-fiction-denial-ensure 2 SingletonNode RosterService.ROSTER_STATE /2 dismiss-west-wonder-outdoor 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.619s 2025-10-15 12:07:49.850 62 INFO EVENT_STREAM <main> DefaultConsensusEventStream: EventStreamManager::updateRunningHash: 38b060a751ac96384cd9327eb1b1e36a21fdb71114be07434c0cc7bf63f6e1da274edebfe76f65fbd51ad2f14898b95b
node0 7.624s 2025-10-15 12:07:49.855 63 INFO STARTUP <platformForkJoinThread-2> Shadowgraph: Shadowgraph starting from expiration threshold 1
node0 7.635s 2025-10-15 12:07:49.866 64 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: -Xms32g
node0 7.635s 2025-10-15 12:07:49.866 65 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: -Xmx118g
node0 7.636s 2025-10-15 12:07:49.867 66 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: -XX:+UnlockExperimentalVMOptions
node0 7.636s 2025-10-15 12:07:49.867 67 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: -XX:+UseZGC
node0 7.636s 2025-10-15 12:07:49.867 68 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: -XX:ZAllocationSpikeTolerance=2
node0 7.637s 2025-10-15 12:07:49.868 69 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: -XX:ConcGCThreads=14
node0 7.637s 2025-10-15 12:07:49.868 70 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: -XX:ZMarkStackSpaceLimit=16g
node0 7.637s 2025-10-15 12:07:49.868 71 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: -XX:MaxDirectMemorySize=64g
node0 7.638s 2025-10-15 12:07:49.869 72 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: -XX:MetaspaceSize=100M
node0 7.638s 2025-10-15 12:07:49.869 73 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: -XX:+ZGenerational
node0 7.638s 2025-10-15 12:07:49.869 74 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: -Xlog:gc*:gc.log
node0 7.638s 2025-10-15 12:07:49.869 75 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: --add-opens=java.base/jdk.internal.misc=ALL-UNNAMED
node0 7.639s 2025-10-15 12:07:49.870 76 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: --add-opens=java.base/java.nio=ALL-UNNAMED
node0 7.639s 2025-10-15 12:07:49.870 77 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: JVM arg: -Dio.netty.tryReflectionSetAccessible=true
node0 7.639s 2025-10-15 12:07:49.870 78 INFO STARTUP <<start-node-0>> PlatformTestingToolMain: Parsing JSON FCM-VM-NIReconnect-125-10m.json
node0 7.656s 2025-10-15 12:07:49.887 79 INFO DEMO_INFO <<start-node-0>> PayloadConfig: insertSeq = true
node0 7.657s 2025-10-15 12:07:49.888 80 INFO DEMO_INFO <<start-node-0>> PayloadConfig: appendSig = true
node0 7.657s 2025-10-15 12:07:49.888 81 INFO DEMO_INFO <<start-node-0>> PayloadConfig: variedSize = false
node0 7.657s 2025-10-15 12:07:49.888 82 INFO DEMO_INFO <<start-node-0>> PayloadConfig: payloadByteSize = 100
node0 7.658s 2025-10-15 12:07:49.889 83 INFO DEMO_INFO <<start-node-0>> PayloadConfig: maxByteSize = 100
node0 7.658s 2025-10-15 12:07:49.889 84 INFO DEMO_INFO <<start-node-0>> PayloadConfig: type = TYPE_FCM_VIRTUAL_MIX
node0 7.659s 2025-10-15 12:07:49.890 85 INFO DEMO_INFO <<start-node-0>> PayloadConfig: invalidSigRatio = 0.0
node0 7.660s 2025-10-15 12:07:49.891 86 INFO DEMO_INFO <<start-node-0>> TransactionSubmitter: Goal is TRANS_PER_SECOND_PER_NODE targe 125.0
node0 7.665s 2025-10-15 12:07:49.896 87 INFO DEMO_INFO <<start-node-0>> VirtualMerkleStateInitializer: State = com.swirlds.demo.platform.PlatformTestingToolState@37b992b5
node0 7.667s 2025-10-15 12:07:49.898 88 INFO DEMO_INFO <<start-node-0>> VirtualMerkleStateInitializer: total accounts = 2400
node0 7.667s 2025-10-15 12:07:49.898 89 INFO DEMO_INFO <<start-node-0>> VirtualMerkleStateInitializer: Creating virtualmap for 2400 accounts.
node0 7.674s 2025-10-15 12:07:49.905 94 INFO DEMO_INFO <<start-node-0>> VirtualMerkleStateInitializer: accounts VM = com.swirlds.virtualmap.VirtualMap@7789dc0e, DS = MerkleDbDataSource[initialCapacity=1000000,preferDiskBasedIndexes=false,pathToDiskLocationInternalNodes.size=0,pathToDiskLocationLeafNodes.size=0,hashesRamToDiskThreshold=8388608,hashStoreRam.size=0,hashStoreDisk=com.swirlds.merkledb.files.MemoryIndexDiskKeyValueStore@22fc744f,hasDiskStoreForHashes=true,keyToPath=com.swirlds.merkledb.files.hashmap.HalfDiskHashMap@2ad8f7b8,pathToKeyValue=com.swirlds.merkledb.files.MemoryIndexDiskKeyValueStore@2d9a1d3d,snapshotInProgress=false]
node0 7.682s 2025-10-15 12:07:49.913 95 INFO DEMO_INFO <<start-node-0>> VirtualMerkleStateInitializer: max KV pairs = 0
node0 7.683s 2025-10-15 12:07:49.914 96 INFO DEMO_INFO <<start-node-0>> VirtualMerkleStateInitializer: total SC = 0
node0 7.688s 2025-10-15 12:07:49.919 97 INFO DEMO_INFO <<start-node-0>> FCMTransactionPool: Random seed for FCMTransactionPool is -6698488724181311233
node0 7.688s 2025-10-15 12:07:49.919 98 INFO DEMO_INFO <<start-node-0>> FCMTransactionPool: This test will generate sequential FCM payload:
node0 7.771s 2025-10-15 12:07:50.002 99 INFO STARTUP <<start-node-0>> SwirldsPlatform: Starting platform 0
node0 7.772s 2025-10-15 12:07:50.003 100 INFO STARTUP <<platform: recycle-bin-cleanup>> RecycleBinImpl: Deleted 0 files from the recycle bin.
node0 7.777s 2025-10-15 12:07:50.008 101 INFO STARTUP <<start-node-0>> CycleFinder: No cyclical back pressure detected in wiring model.
node0 7.778s 2025-10-15 12:07:50.009 102 INFO STARTUP <<start-node-0>> DirectSchedulerChecks: No illegal direct scheduler use detected in the wiring model.
node0 7.778s 2025-10-15 12:07:50.009 103 INFO STARTUP <<start-node-0>> InputWireChecks: All input wires have been bound.
node0 7.781s 2025-10-15 12:07:50.012 104 WARN STARTUP <<start-node-0>> PcesFileTracker: No preconsensus event files available
node0 7.781s 2025-10-15 12:07:50.012 105 INFO STARTUP <<start-node-0>> SwirldsPlatform: replaying preconsensus event stream starting at 0
node0 7.782s 2025-10-15 12:07:50.013 106 INFO STARTUP <<start-node-0>> PcesReplayer: Replayed 0 preconsensus events with max birth round -1. 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 7.783s 2025-10-15 12:07:50.014 107 INFO DEMO_INFO <<app: appMain 0>> PlatformTestingToolMain: Node 0 starts transactions ........
node0 7.784s 2025-10-15 12:07:50.015 108 INFO PLATFORM_STATUS <platformForkJoinThread-3> DefaultStatusStateMachine: Platform spent 336.0 ms in STARTING_UP. Now in REPLAYING_EVENTS
node0 7.788s 2025-10-15 12:07:50.019 109 INFO STARTUP <platformForkJoinThread-3> PlatformTestingToolMain: Platform Status Change REPLAYING_EVENTS
node0 7.789s 2025-10-15 12:07:50.020 110 INFO PLATFORM_STATUS <platformForkJoinThread-3> DefaultStatusStateMachine: Platform spent 4.0 ms in REPLAYING_EVENTS. Now in OBSERVING
node0 7.789s 2025-10-15 12:07:50.020 111 INFO STARTUP <platformForkJoinThread-3> PlatformTestingToolMain: Platform Status Change OBSERVING
node1 8.429s 2025-10-15 12:07:50.660 53 INFO STARTUP <main> OSHealthChecker:
PASSED - Clock Source Speed Check Report[callsPerSec=26173324] PASSED - Entropy Check Report[success=true, entropySource=Strong Instance, elapsedNanos=359760, randomLong=6593436242795559762, exception=null] PASSED - Entropy Check Report[success=true, entropySource=NativePRNGBlocking:SUN, elapsedNanos=10070, randomLong=-6213970481928039270, exception=null] PASSED - File System Check Report[code=SUCCESS, readNanos=1597201, data=35, exception=null] OS Health Check Report - Complete (took 1022 ms)
node1 8.462s 2025-10-15 12:07:50.693 54 DEBUG STARTUP <main> BootstrapUtils: jvmPauseDetectorThread started
node1 8.471s 2025-10-15 12:07:50.702 55 INFO STARTUP <main> StandardScratchpad: Scratchpad platform.iss contents:
LAST_ISS_ROUND null
node1 8.477s 2025-10-15 12:07:50.708 56 INFO STARTUP <main> PlatformBuilder: Default platform pool parallelism: 8
node1 8.570s 2025-10-15 12:07:50.801 57 INFO STARTUP <main> SwirldsPlatform: Starting with roster history:
RosterHistory[ currentRosterRound: 0 ][ no previous roster set ] Current Roster: { "rosterEntries": [{ "weight": "16125000000", "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": "IqwG/Q==", "port": 30124 }, { "ipAddressV4": "CoAAHQ==", "port": 30124 }] }, { "nodeId": "1", "weight": "14625000000", "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": "Ih20Yg==", "port": 30125 }, { "ipAddressV4": "CoAADw==", "port": 30125 }] }, { "nodeId": "2", "weight": "2500000000", "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": "I+FSFA==", "port": 30126 }, { "ipAddressV4": "CoAAGg==", "port": 30126 }] }, { "nodeId": "3", "weight": "16750000000", "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": "iHBQuA==", "port": 30127 }, { "ipAddressV4": "CoAAGw==", "port": 30127 }] }] }
node1 8.682s 2025-10-15 12:07:50.913 58 INFO STARTUP <main> PlatformTestingToolState: Trigger and PreviousSoftwareVersion state received in init function {"trigger":"GENESIS","previousSoftwareVersion":"null"} [com.swirlds.logging.legacy.payload.SoftwareVersionPayload]
node1 8.683s 2025-10-15 12:07:50.914 59 INFO STARTUP <main> PlatformTestingToolState: Set QuorumResult from genesisInit()
node1 8.701s 2025-10-15 12:07:50.932 60 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: db5ed32abf4b39df92f5abbca1b9ff02002b11eaf0f41595ace43577dbd4bae7bfb92fbb39fff6a9e13c9ebf117b9e54 (root) PlatformTestingToolState / nephew-onion-birth-desk 0 SingletonNode PlatformStateService.PLATFORM_STATE /0 method-topple-elite-gate 1 VirtualMap RosterService.ROSTERS /1 alpha-fiction-denial-ensure 2 SingletonNode RosterService.ROSTER_STATE /2 dismiss-west-wonder-outdoor 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
node1 8.941s 2025-10-15 12:07:51.172 62 INFO EVENT_STREAM <main> DefaultConsensusEventStream: EventStreamManager::updateRunningHash: 38b060a751ac96384cd9327eb1b1e36a21fdb71114be07434c0cc7bf63f6e1da274edebfe76f65fbd51ad2f14898b95b
node1 8.946s 2025-10-15 12:07:51.177 63 INFO STARTUP <platformForkJoinThread-2> Shadowgraph: Shadowgraph starting from expiration threshold 1
node1 8.958s 2025-10-15 12:07:51.189 64 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: -Xms32g
node1 8.959s 2025-10-15 12:07:51.190 65 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: -Xmx118g
node1 8.960s 2025-10-15 12:07:51.191 66 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: -XX:+UnlockExperimentalVMOptions
node1 8.960s 2025-10-15 12:07:51.191 67 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: -XX:+UseZGC
node1 8.961s 2025-10-15 12:07:51.192 68 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: -XX:ZAllocationSpikeTolerance=2
node1 8.961s 2025-10-15 12:07:51.192 69 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: -XX:ConcGCThreads=14
node1 8.962s 2025-10-15 12:07:51.193 70 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: -XX:ZMarkStackSpaceLimit=16g
node1 8.962s 2025-10-15 12:07:51.193 71 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: -XX:MaxDirectMemorySize=64g
node1 8.962s 2025-10-15 12:07:51.193 72 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: -XX:MetaspaceSize=100M
node1 8.963s 2025-10-15 12:07:51.194 73 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: -XX:+ZGenerational
node1 8.963s 2025-10-15 12:07:51.194 74 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: -Xlog:gc*:gc.log
node1 8.964s 2025-10-15 12:07:51.195 75 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: --add-opens=java.base/jdk.internal.misc=ALL-UNNAMED
node1 8.964s 2025-10-15 12:07:51.195 76 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: --add-opens=java.base/java.nio=ALL-UNNAMED
node1 8.965s 2025-10-15 12:07:51.196 77 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: JVM arg: -Dio.netty.tryReflectionSetAccessible=true
node1 8.965s 2025-10-15 12:07:51.196 78 INFO STARTUP <<start-node-1>> PlatformTestingToolMain: Parsing JSON FCM-VM-NIReconnect-125-10m.json
node1 8.991s 2025-10-15 12:07:51.222 79 INFO DEMO_INFO <<start-node-1>> PayloadConfig: insertSeq = true
node1 8.992s 2025-10-15 12:07:51.223 80 INFO DEMO_INFO <<start-node-1>> PayloadConfig: appendSig = true
node1 8.993s 2025-10-15 12:07:51.224 81 INFO DEMO_INFO <<start-node-1>> PayloadConfig: variedSize = false
node1 8.993s 2025-10-15 12:07:51.224 82 INFO DEMO_INFO <<start-node-1>> PayloadConfig: payloadByteSize = 100
node1 8.994s 2025-10-15 12:07:51.225 83 INFO DEMO_INFO <<start-node-1>> PayloadConfig: maxByteSize = 100
node1 8.995s 2025-10-15 12:07:51.226 84 INFO DEMO_INFO <<start-node-1>> PayloadConfig: type = TYPE_FCM_VIRTUAL_MIX
node1 8.995s 2025-10-15 12:07:51.226 85 INFO DEMO_INFO <<start-node-1>> PayloadConfig: invalidSigRatio = 0.0
node1 8.997s 2025-10-15 12:07:51.228 86 INFO DEMO_INFO <<start-node-1>> TransactionSubmitter: Goal is TRANS_PER_SECOND_PER_NODE targe 125.0
node1 9.004s 2025-10-15 12:07:51.235 87 INFO DEMO_INFO <<start-node-1>> VirtualMerkleStateInitializer: State = com.swirlds.demo.platform.PlatformTestingToolState@7694609b
node1 9.006s 2025-10-15 12:07:51.237 88 INFO DEMO_INFO <<start-node-1>> VirtualMerkleStateInitializer: total accounts = 2400
node1 9.007s 2025-10-15 12:07:51.238 89 INFO DEMO_INFO <<start-node-1>> VirtualMerkleStateInitializer: Creating virtualmap for 2400 accounts.
node1 9.018s 2025-10-15 12:07:51.249 94 INFO DEMO_INFO <<start-node-1>> VirtualMerkleStateInitializer: accounts VM = com.swirlds.virtualmap.VirtualMap@76389e4e, DS = MerkleDbDataSource[initialCapacity=1000000,preferDiskBasedIndexes=false,pathToDiskLocationInternalNodes.size=0,pathToDiskLocationLeafNodes.size=0,hashesRamToDiskThreshold=8388608,hashStoreRam.size=0,hashStoreDisk=com.swirlds.merkledb.files.MemoryIndexDiskKeyValueStore@55710d9a,hasDiskStoreForHashes=true,keyToPath=com.swirlds.merkledb.files.hashmap.HalfDiskHashMap@8ca0482,pathToKeyValue=com.swirlds.merkledb.files.MemoryIndexDiskKeyValueStore@4aaf6397,snapshotInProgress=false]
node1 9.031s 2025-10-15 12:07:51.262 95 INFO DEMO_INFO <<start-node-1>> VirtualMerkleStateInitializer: max KV pairs = 0
node1 9.032s 2025-10-15 12:07:51.263 96 INFO DEMO_INFO <<start-node-1>> VirtualMerkleStateInitializer: total SC = 0
node1 9.039s 2025-10-15 12:07:51.270 97 INFO DEMO_INFO <<start-node-1>> FCMTransactionPool: Random seed for FCMTransactionPool is -9119159344323713840
node1 9.040s 2025-10-15 12:07:51.271 98 INFO DEMO_INFO <<start-node-1>> FCMTransactionPool: This test will generate sequential FCM payload:
node1 9.139s 2025-10-15 12:07:51.370 99 INFO STARTUP <<start-node-1>> SwirldsPlatform: Starting platform 1
node1 9.145s 2025-10-15 12:07:51.376 100 INFO STARTUP <<platform: recycle-bin-cleanup>> RecycleBinImpl: Deleted 0 files from the recycle bin.
node1 9.151s 2025-10-15 12:07:51.382 101 INFO STARTUP <<start-node-1>> CycleFinder: No cyclical back pressure detected in wiring model.
node1 9.153s 2025-10-15 12:07:51.384 102 INFO STARTUP <<start-node-1>> DirectSchedulerChecks: No illegal direct scheduler use detected in the wiring model.
node1 9.154s 2025-10-15 12:07:51.385 103 INFO STARTUP <<start-node-1>> InputWireChecks: All input wires have been bound.
node1 9.157s 2025-10-15 12:07:51.388 104 WARN STARTUP <<start-node-1>> PcesFileTracker: No preconsensus event files available
node3 9.157s 2025-10-15 12:07:51.388 112 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' ]
node1 9.158s 2025-10-15 12:07:51.389 105 INFO STARTUP <<start-node-1>> SwirldsPlatform: replaying preconsensus event stream starting at 0
node1 9.160s 2025-10-15 12:07:51.391 106 INFO STARTUP <<start-node-1>> PcesReplayer: Replayed 0 preconsensus events with max birth round -1. 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 9.160s 2025-10-15 12:07:51.391 113 DEBUG STARTUP <<platform-core: MetricsThread #0>> LegacyCsvWriter: CsvWriter: Using the existing metrics folder [ folder = '/opt/hgcapp/services-hedera/HapiApp2.0/data/stats' ]
node1 9.162s 2025-10-15 12:07:51.393 107 INFO DEMO_INFO <<app: appMain 1>> PlatformTestingToolMain: Node 1 starts transactions ........
node1 9.164s 2025-10-15 12:07:51.395 108 INFO PLATFORM_STATUS <platformForkJoinThread-6> DefaultStatusStateMachine: Platform spent 401.0 ms in STARTING_UP. Now in REPLAYING_EVENTS
node1 9.169s 2025-10-15 12:07:51.400 109 INFO STARTUP <platformForkJoinThread-6> PlatformTestingToolMain: Platform Status Change REPLAYING_EVENTS
node1 9.170s 2025-10-15 12:07:51.401 110 INFO PLATFORM_STATUS <platformForkJoinThread-6> DefaultStatusStateMachine: Platform spent 5.0 ms in REPLAYING_EVENTS. Now in OBSERVING
node1 9.171s 2025-10-15 12:07:51.402 111 INFO STARTUP <platformForkJoinThread-6> PlatformTestingToolMain: Platform Status Change OBSERVING
node2 9.416s 2025-10-15 12:07:51.647 112 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 9.418s 2025-10-15 12:07:51.649 113 DEBUG STARTUP <<platform-core: MetricsThread #0>> LegacyCsvWriter: CsvWriter: Using the existing metrics folder [ folder = '/opt/hgcapp/services-hedera/HapiApp2.0/data/stats' ]
node0 10.650s 2025-10-15 12:07:52.881 112 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.653s 2025-10-15 12:07:52.884 113 DEBUG STARTUP <<platform-core: MetricsThread #0>> LegacyCsvWriter: CsvWriter: Using the existing metrics folder [ folder = '/opt/hgcapp/services-hedera/HapiApp2.0/data/stats' ]
node1 11.967s 2025-10-15 12:07:54.198 112 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 11.970s 2025-10-15 12:07:54.201 113 DEBUG STARTUP <<platform-core: MetricsThread #0>> LegacyCsvWriter: CsvWriter: Using the existing metrics folder [ folder = '/opt/hgcapp/services-hedera/HapiApp2.0/data/stats' ]
node3 16.398s 2025-10-15 12:07:58.629 114 INFO PLATFORM_STATUS <platformForkJoinThread-6> DefaultStatusStateMachine: Platform spent 10.1 s in OBSERVING. Now in CHECKING
node3 16.399s 2025-10-15 12:07:58.630 115 INFO STARTUP <platformForkJoinThread-6> PlatformTestingToolMain: Platform Status Change CHECKING
node2 16.650s 2025-10-15 12:07:58.881 114 INFO PLATFORM_STATUS <platformForkJoinThread-6> DefaultStatusStateMachine: Platform spent 10.1 s in OBSERVING. Now in CHECKING
node2 16.651s 2025-10-15 12:07:58.882 115 INFO STARTUP <platformForkJoinThread-6> PlatformTestingToolMain: Platform Status Change CHECKING
node0 17.880s 2025-10-15 12:08:00.111 114 INFO PLATFORM_STATUS <platformForkJoinThread-5> DefaultStatusStateMachine: Platform spent 10.1 s in OBSERVING. Now in CHECKING
node0 17.882s 2025-10-15 12:08:00.113 115 INFO STARTUP <platformForkJoinThread-5> PlatformTestingToolMain: Platform Status Change CHECKING
node1 19.257s 2025-10-15 12:08:01.488 114 INFO PLATFORM_STATUS <platformForkJoinThread-5> DefaultStatusStateMachine: Platform spent 10.1 s in OBSERVING. Now in CHECKING
node1 19.259s 2025-10-15 12:08:01.490 115 INFO STARTUP <platformForkJoinThread-5> PlatformTestingToolMain: Platform Status Change CHECKING
node3 19.333s 2025-10-15 12:08:01.564 116 INFO PLATFORM_STATUS <platformForkJoinThread-4> DefaultStatusStateMachine: Platform spent 2.9 s in CHECKING. Now in ACTIVE
node3 19.333s 2025-10-15 12:08:01.564 117 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: resetting transaction counters
node3 19.334s 2025-10-15 12:08:01.565 118 INFO STARTUP <platformForkJoinThread-4> PlatformTestingToolMain: Platform Status Change ACTIVE
node3 19.417s 2025-10-15 12:08:01.648 119 TRACE STARTUP <<app: appMain 3>> PttTransactionPool: Signed Message { signatureType = 'ED25519', publicKey = '0x515991590B50388D611EA2752BE0E7A8C1843746584128BB876D37A2DC4689DB', privateKey ='0x515991590B50388D611EA2752BE0E7A8C1843746584128BB876D37A2DC4689DB', signature = '0x885971B599582EE19B628E13896EB80852924AA368F01FCC956BA3CA67458FB76D6F486D62CC753980BC99965BD24A9AD8A068D150709A902E10B53166E05F0E',message = '0x12660A1A0803100318A03820A03828A03830A03840FD96F599D8F1F8DF3F3244000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000038034001' }
node0 19.670s 2025-10-15 12:08:01.901 116 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: resetting transaction counters
node1 19.744s 2025-10-15 12:08:01.975 116 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: resetting transaction counters
node2 19.755s 2025-10-15 12:08:01.986 116 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: resetting transaction counters
node0 21.093s 2025-10-15 12:08:03.324 118 INFO PLATFORM_STATUS <platformForkJoinThread-6> DefaultStatusStateMachine: Platform spent 3.2 s in CHECKING. Now in ACTIVE
node0 21.095s 2025-10-15 12:08:03.326 119 INFO STARTUP <platformForkJoinThread-6> PlatformTestingToolMain: Platform Status Change ACTIVE
node0 21.096s 2025-10-15 12:08:03.327 121 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
node3 21.112s 2025-10-15 12:08:03.343 122 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 21.117s 2025-10-15 12:08:03.348 136 TRACE STARTUP <<app: appMain 0>> PttTransactionPool: Signed Message { signatureType = 'ED25519', publicKey = '0x94C568123B45F9FF0ABB156FCB47DEF542FA1FDAF4388310FD5D43ABEF873E16', privateKey ='0x94C568123B45F9FF0ABB156FCB47DEF542FA1FDAF4388310FD5D43ABEF873E16', signature = '0x36D47D29ACD7282BDC995B629B8870C1CE73880062E43773956B1A4D89A9AF48A22CA0DA618C16343A8CFAC920747EC97C88BE67989234D34754A8308D070E0E',message = '0x12660A0A40C38B9FABD2B9908D6D325600000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000004001' }
node2 21.172s 2025-10-15 12:08:03.403 117 INFO PLATFORM_STATUS <platformForkJoinThread-1> DefaultStatusStateMachine: Platform spent 4.5 s in CHECKING. Now in ACTIVE
node2 21.172s 2025-10-15 12:08:03.403 119 INFO STARTUP <platformForkJoinThread-1> PlatformTestingToolMain: Platform Status Change ACTIVE
node2 21.174s 2025-10-15 12:08:03.405 121 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
node2 21.189s 2025-10-15 12:08:03.420 136 TRACE STARTUP <<app: appMain 2>> PttTransactionPool: Signed Message { signatureType = 'ED25519', publicKey = '0xE638A961E227E951C6F39415FF61DA4131F380F2DDE8EBF32CDF393FD7C1F4C1', privateKey ='0xE638A961E227E951C6F39415FF61DA4131F380F2DDE8EBF32CDF393FD7C1F4C1', signature = '0x0CF273505EF2D44504C5B627245258309C968F35BDC62A4A810DCFFAFED5D19320D44C083F4529A361DFFFE0DA4E94E3F2697B5301DE0330F40E617821640205',message = '0x1A6B0A1B08021002180120A18D0628914E30914E40DEC5EBACB09D9CBB8A012A4900000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000D00102' }
node1 21.421s 2025-10-15 12:08:03.652 119 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.484s 2025-10-15 12:08:03.715 134 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.488s 2025-10-15 12:08:03.719 135 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
node3 21.709s 2025-10-15 12:08:03.940 137 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.711s 2025-10-15 12:08:03.942 138 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.804s 2025-10-15 12:08:04.035 192 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.808s 2025-10-15 12:08:04.039 193 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Information for state written to disk:
Round: 3 Timestamp: 2025-10-15T12:08:01.966422Z Next consensus number: 15 Legacy running event hash: cf5d68cedb4ac6e8852c5a7b6601f5580a899694a46a86e31a38635435cc2446c73c46e956a2dc40116f52a2543d27be Legacy running event mnemonic: behave-border-nothing-cart Rounds non-ancient: 26 Creation version: SemanticVersion[major=1, minor=0, patch=0, pre=, build=] Minimum judge hash code: 2044907021 Root hash: 9698852aa96a3dbc3995f6ff3d872638c3263ecae76b3a87202865e5fb532b3799f35fabff2ec75ff74113be42974439 (root) PlatformTestingToolState / code-food-spot-resource 0 SingletonNode PlatformStateService.PLATFORM_STATE /0 main-night-service-winner 1 VirtualMap RosterService.ROSTERS /1 alpha-fiction-denial-ensure 2 SingletonNode RosterService.ROSTER_STATE /2 dismiss-west-wonder-outdoor 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 absent-boy-buddy-wear 10 null /10 bid-belt-culture-decorate 11 null /11 bid-belt-culture-decorate 12 QuorumResult /12 globe-math-mass-loop
node2 21.844s 2025-10-15 12:08:04.075 137 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.845s 2025-10-15 12:08:04.076 138 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.851s 2025-10-15 12:08:04.082 194 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Found 1 preconsensus file on disk.
File: data/saved/preconsensus-events/1/2025/10/15/2025-10-15T12+07+58.853773758Z_seq0_minr1_maxr501_orgn0.pces
node1 21.852s 2025-10-15 12:08:04.083 195 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/10/15/2025-10-15T12+07+58.853773758Z_seq0_minr1_maxr501_orgn0.pces
node1 21.852s 2025-10-15 12:08:04.083 196 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Copying 1 preconsensus event file(s)
node1 21.854s 2025-10-15 12:08:04.085 197 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Finished copying 1 preconsensus event file(s)
node1 21.861s 2025-10-15 12:08:04.092 198 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]
node0 21.913s 2025-10-15 12:08:04.144 137 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.915s 2025-10-15 12:08:04.146 138 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
node3 21.978s 2025-10-15 12:08:04.209 195 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.981s 2025-10-15 12:08:04.212 196 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Information for state written to disk:
Round: 3 Timestamp: 2025-10-15T12:08:01.966422Z Next consensus number: 15 Legacy running event hash: cf5d68cedb4ac6e8852c5a7b6601f5580a899694a46a86e31a38635435cc2446c73c46e956a2dc40116f52a2543d27be Legacy running event mnemonic: behave-border-nothing-cart Rounds non-ancient: 26 Creation version: SemanticVersion[major=1, minor=0, patch=0, pre=, build=] Minimum judge hash code: 2044907021 Root hash: 9698852aa96a3dbc3995f6ff3d872638c3263ecae76b3a87202865e5fb532b3799f35fabff2ec75ff74113be42974439 (root) PlatformTestingToolState / code-food-spot-resource 0 SingletonNode PlatformStateService.PLATFORM_STATE /0 main-night-service-winner 1 VirtualMap RosterService.ROSTERS /1 alpha-fiction-denial-ensure 2 SingletonNode RosterService.ROSTER_STATE /2 dismiss-west-wonder-outdoor 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 absent-boy-buddy-wear 10 null /10 bid-belt-culture-decorate 11 null /11 bid-belt-culture-decorate 12 QuorumResult /12 globe-math-mass-loop
node1 21.998s 2025-10-15 12:08:04.229 199 INFO PLATFORM_STATUS <platformForkJoinThread-8> DefaultStatusStateMachine: Platform spent 2.7 s in CHECKING. Now in ACTIVE
node1 21.999s 2025-10-15 12:08:04.230 200 INFO STARTUP <platformForkJoinThread-8> PlatformTestingToolMain: Platform Status Change ACTIVE
node3 22.010s 2025-10-15 12:08:04.241 197 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Found 1 preconsensus file on disk.
File: data/saved/preconsensus-events/3/2025/10/15/2025-10-15T12+07+58.667006954Z_seq0_minr1_maxr501_orgn0.pces
node3 22.011s 2025-10-15 12:08:04.242 198 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/10/15/2025-10-15T12+07+58.667006954Z_seq0_minr1_maxr501_orgn0.pces
node3 22.011s 2025-10-15 12:08:04.242 199 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Copying 1 preconsensus event file(s)
node3 22.012s 2025-10-15 12:08:04.243 200 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Finished copying 1 preconsensus event file(s)
node3 22.017s 2025-10-15 12:08:04.248 201 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 22.043s 2025-10-15 12:08:04.274 201 TRACE STARTUP <<app: appMain 1>> PttTransactionPool: Signed Message { signatureType = 'ED25519', publicKey = '0x97CEB7249D429F1D1441B3D824B9602ABE7C2C7469048185662BF9B93BFDDBE2', privateKey ='0x97CEB7249D429F1D1441B3D824B9602ABE7C2C7469048185662BF9B93BFDDBE2', signature = '0x658400F508509A7835A7647147C5E479A8D01CEA7FB3E2FF30B425ADCE6272FEF6E464531560FB246C1C2596493691E19990302E3ABFA19C148E344B526FE90E',message = '0x1A6B0A1D0801100118A23820C2C50628B2860130B2860140DEBEB7D1FF81C1835B2A470000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000D00101' }
node2 22.103s 2025-10-15 12:08:04.334 195 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 22.106s 2025-10-15 12:08:04.337 196 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Information for state written to disk:
Round: 3 Timestamp: 2025-10-15T12:08:01.966422Z Next consensus number: 15 Legacy running event hash: cf5d68cedb4ac6e8852c5a7b6601f5580a899694a46a86e31a38635435cc2446c73c46e956a2dc40116f52a2543d27be Legacy running event mnemonic: behave-border-nothing-cart Rounds non-ancient: 26 Creation version: SemanticVersion[major=1, minor=0, patch=0, pre=, build=] Minimum judge hash code: 2044907021 Root hash: 9698852aa96a3dbc3995f6ff3d872638c3263ecae76b3a87202865e5fb532b3799f35fabff2ec75ff74113be42974439 (root) PlatformTestingToolState / code-food-spot-resource 0 SingletonNode PlatformStateService.PLATFORM_STATE /0 main-night-service-winner 1 VirtualMap RosterService.ROSTERS /1 alpha-fiction-denial-ensure 2 SingletonNode RosterService.ROSTER_STATE /2 dismiss-west-wonder-outdoor 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 absent-boy-buddy-wear 10 null /10 bid-belt-culture-decorate 11 null /11 bid-belt-culture-decorate 12 QuorumResult /12 globe-math-mass-loop
node2 22.138s 2025-10-15 12:08:04.369 197 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Found 1 preconsensus file on disk.
File: data/saved/preconsensus-events/2/2025/10/15/2025-10-15T12+07+58.873166638Z_seq0_minr1_maxr501_orgn0.pces
node2 22.139s 2025-10-15 12:08:04.370 198 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/10/15/2025-10-15T12+07+58.873166638Z_seq0_minr1_maxr501_orgn0.pces
node2 22.139s 2025-10-15 12:08:04.370 199 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Copying 1 preconsensus event file(s)
node2 22.141s 2025-10-15 12:08:04.372 200 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Finished copying 1 preconsensus event file(s)
node2 22.145s 2025-10-15 12:08:04.376 201 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]
node0 22.215s 2025-10-15 12:08:04.446 195 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 22.219s 2025-10-15 12:08:04.450 196 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> SignedStateFileWriter: Information for state written to disk:
Round: 3 Timestamp: 2025-10-15T12:08:01.966422Z Next consensus number: 15 Legacy running event hash: cf5d68cedb4ac6e8852c5a7b6601f5580a899694a46a86e31a38635435cc2446c73c46e956a2dc40116f52a2543d27be Legacy running event mnemonic: behave-border-nothing-cart Rounds non-ancient: 26 Creation version: SemanticVersion[major=1, minor=0, patch=0, pre=, build=] Minimum judge hash code: 2044907021 Root hash: 9698852aa96a3dbc3995f6ff3d872638c3263ecae76b3a87202865e5fb532b3799f35fabff2ec75ff74113be42974439 (root) PlatformTestingToolState / code-food-spot-resource 0 SingletonNode PlatformStateService.PLATFORM_STATE /0 main-night-service-winner 1 VirtualMap RosterService.ROSTERS /1 alpha-fiction-denial-ensure 2 SingletonNode RosterService.ROSTER_STATE /2 dismiss-west-wonder-outdoor 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 absent-boy-buddy-wear 10 null /10 bid-belt-culture-decorate 11 null /11 bid-belt-culture-decorate 12 QuorumResult /12 globe-math-mass-loop
node0 22.263s 2025-10-15 12:08:04.494 197 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Found 1 preconsensus file on disk.
File: data/saved/preconsensus-events/0/2025/10/15/2025-10-15T12+07+58.789800508Z_seq0_minr1_maxr501_orgn0.pces
node0 22.264s 2025-10-15 12:08:04.495 198 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/10/15/2025-10-15T12+07+58.789800508Z_seq0_minr1_maxr501_orgn0.pces
node0 22.264s 2025-10-15 12:08:04.495 199 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Copying 1 preconsensus event file(s)
node0 22.265s 2025-10-15 12:08:04.496 200 INFO STATE_TO_DISK <<scheduler StateSnapshotManager>> BestEffortPcesFileCopy: Finished copying 1 preconsensus event file(s)
node0 22.272s 2025-10-15 12:08:04.503 201 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]
node3 25.634s 2025-10-15 12:08:07.865 222 INFO DEMO_INFO <<app: appMain 3>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_CREATE transactions
node3 27.252s 2025-10-15 12:08:09.483 243 INFO DEMO_INFO <<app: appMain 3>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_DELETE transactions
node0 27.332s 2025-10-15 12:08:09.563 242 INFO DEMO_INFO <<app: appMain 0>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_CREATE transactions
node2 27.488s 2025-10-15 12:08:09.719 242 INFO DEMO_INFO <<app: appMain 2>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_CREATE transactions
node1 28.469s 2025-10-15 12:08:10.700 242 INFO DEMO_INFO <<app: appMain 1>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_CREATE transactions
node0 28.649s 2025-10-15 12:08:10.880 247 INFO DEMO_INFO <<app: appMain 0>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_DELETE transactions
node2 29.005s 2025-10-15 12:08:11.236 263 INFO DEMO_INFO <<app: appMain 2>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_DELETE transactions
node1 30.092s 2025-10-15 12:08:12.323 263 INFO DEMO_INFO <<app: appMain 1>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_DELETE transactions
node3 59.413s 2025-10-15 12:08:41.644 528 INFO DEMO_INFO <<app: appMain 3>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_UPDATE transactions
node0 1.006m 2025-10-15 12:08:42.572 544 INFO DEMO_INFO <<app: appMain 0>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_UPDATE transactions
node2 1m 1.602s 2025-10-15 12:08:43.833 564 INFO DEMO_INFO <<app: appMain 2>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_UPDATE transactions
node1 1m 1.761s 2025-10-15 12:08:43.992 564 INFO DEMO_INFO <<app: appMain 1>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_UPDATE transactions
node3 1m 22.831s 2025-10-15 12:09:05.062 733 INFO DEMO_INFO <<app: appMain 3>> FCMTransactionPool: Generated enough FCM transaction for type TYPE_FCM_CREATE
node3 1m 22.832s 2025-10-15 12:09:05.063 734 INFO DEMO_INFO <<app: appMain 3>> FCMTransactionPool: Generated enough FCM transaction for type SAVE_EXPECTED_MAP
node3 1m 22.834s 2025-10-15 12:09:05.065 735 INFO DEMO_INFO <<app: appMain 3>> FCMTransactionPool: node3 submits a transaction SAVE_EXPECTED_MAP
node2 1m 24.584s 2025-10-15 12:09:06.815 745 INFO DEMO_INFO <<app: appMain 2>> FCMTransactionPool: Generated enough FCM transaction for type TYPE_FCM_CREATE
node2 1m 24.587s 2025-10-15 12:09:06.818 746 INFO DEMO_INFO <<app: appMain 2>> FCMTransactionPool: Generated enough FCM transaction for type SAVE_EXPECTED_MAP
node2 1m 24.589s 2025-10-15 12:09:06.820 747 INFO DEMO_INFO <<app: appMain 2>> FCMTransactionPool: node2 submits a transaction SAVE_EXPECTED_MAP
node0 1m 24.642s 2025-10-15 12:09:06.873 749 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Received SAVE_EXPECTED_MAP transaction from node 3
node1 1m 24.722s 2025-10-15 12:09:06.953 745 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Received SAVE_EXPECTED_MAP transaction from node 3
node2 1m 24.772s 2025-10-15 12:09:07.003 748 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Received SAVE_EXPECTED_MAP transaction from node 3
node3 1m 24.849s 2025-10-15 12:09:07.080 752 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Received SAVE_EXPECTED_MAP transaction from node 3
node0 1m 25.340s 2025-10-15 12:09:07.571 770 INFO DEMO_INFO <<app: appMain 0>> FCMTransactionPool: Generated enough FCM transaction for type TYPE_FCM_CREATE
node0 1m 25.342s 2025-10-15 12:09:07.573 771 INFO DEMO_INFO <<app: appMain 0>> FCMTransactionPool: Generated enough FCM transaction for type SAVE_EXPECTED_MAP
node0 1m 25.342s 2025-10-15 12:09:07.573 772 INFO DEMO_INFO <<app: appMain 0>> FCMTransactionPool: node0 submits a transaction SAVE_EXPECTED_MAP
node2 1m 26.687s 2025-10-15 12:09:08.918 769 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Received SAVE_EXPECTED_MAP transaction from node 2
node3 1m 26.786s 2025-10-15 12:09:09.017 773 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Received SAVE_EXPECTED_MAP transaction from node 2
node0 1m 26.889s 2025-10-15 12:09:09.120 773 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Received SAVE_EXPECTED_MAP transaction from node 2
node1 1m 26.960s 2025-10-15 12:09:09.191 766 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Received SAVE_EXPECTED_MAP transaction from node 2
node1 1m 26.989s 2025-10-15 12:09:09.220 771 INFO DEMO_INFO <<app: appMain 1>> FCMTransactionPool: Generated enough FCM transaction for type TYPE_FCM_CREATE
node1 1m 26.989s 2025-10-15 12:09:09.220 772 INFO DEMO_INFO <<app: appMain 1>> FCMTransactionPool: Generated enough FCM transaction for type SAVE_EXPECTED_MAP
node1 1m 26.990s 2025-10-15 12:09:09.221 773 INFO DEMO_INFO <<app: appMain 1>> FCMTransactionPool: node1 submits a transaction SAVE_EXPECTED_MAP
node3 1m 27.281s 2025-10-15 12:09:09.512 778 INFO SAVE_EXPECTED_MAP <<scheduler TransactionHandler>> SaveExpectedMapHandler: Serializing ExpectedMap Node0003_ExpectedMap_1760530148060_60870000.json.gz
node0 1m 27.337s 2025-10-15 12:09:09.568 794 INFO SAVE_EXPECTED_MAP <<scheduler TransactionHandler>> SaveExpectedMapHandler: Serializing ExpectedMap Node0000_ExpectedMap_1760530148060_60870000.json.gz
node1 1m 27.557s 2025-10-15 12:09:09.788 790 INFO SAVE_EXPECTED_MAP <<scheduler TransactionHandler>> SaveExpectedMapHandler: Serializing ExpectedMap Node0001_ExpectedMap_1760530148060_60870000.json.gz
node3 1m 27.571s 2025-10-15 12:09:09.802 795 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: handling SAVE_EXPECTED_MAP
node2 1m 27.588s 2025-10-15 12:09:09.819 790 INFO SAVE_EXPECTED_MAP <<scheduler TransactionHandler>> SaveExpectedMapHandler: Serializing ExpectedMap Node0002_ExpectedMap_1760530148060_60870000.json.gz
node0 1m 27.632s 2025-10-15 12:09:09.863 795 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: handling SAVE_EXPECTED_MAP
node1 1m 27.887s 2025-10-15 12:09:10.118 791 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: handling SAVE_EXPECTED_MAP
node2 1m 27.890s 2025-10-15 12:09:10.121 791 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: handling SAVE_EXPECTED_MAP
node0 1m 28.600s 2025-10-15 12:09:10.831 796 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Received SAVE_EXPECTED_MAP transaction from node 1
node2 1m 28.670s 2025-10-15 12:09:10.901 792 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Received SAVE_EXPECTED_MAP transaction from node 1
node1 1m 28.798s 2025-10-15 12:09:11.029 792 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Received SAVE_EXPECTED_MAP transaction from node 1
node3 1m 29.061s 2025-10-15 12:09:11.292 796 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Received SAVE_EXPECTED_MAP transaction from node 1
node3 1m 31.909s 2025-10-15 12:09:14.140 821 INFO DEMO_INFO <<app: appMain 3>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_CREATE transactions
node1 1m 32.648s 2025-10-15 12:09:14.879 833 INFO DEMO_INFO <<app: appMain 1>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_CREATE transactions
node0 1m 33.114s 2025-10-15 12:09:15.345 841 INFO DEMO_INFO <<app: appMain 0>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_CREATE transactions
node3 1m 33.471s 2025-10-15 12:09:15.702 842 INFO DEMO_INFO <<app: appMain 3>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_DELETE transactions
node2 1m 34.027s 2025-10-15 12:09:16.258 853 INFO DEMO_INFO <<app: appMain 2>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_CREATE transactions
node1 1m 34.113s 2025-10-15 12:09:16.344 854 INFO DEMO_INFO <<app: appMain 1>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_DELETE transactions
node0 1m 34.728s 2025-10-15 12:09:16.959 858 INFO DEMO_INFO <<app: appMain 0>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_DELETE transactions
node2 1m 35.541s 2025-10-15 12:09:17.772 874 INFO DEMO_INFO <<app: appMain 2>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_DELETE transactions
node3 2m 5.151s 2025-10-15 12:09:47.382 1179 INFO DEMO_INFO <<app: appMain 3>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_UPDATE transactions
node3 2m 5.202s 2025-10-15 12:09:47.433 1180 INFO DEMO_INFO <<app: appMain 3>> PttTransactionPool: Generated enough virtual merkle test for sequential mode
node3 2m 5.203s 2025-10-15 12:09:47.434 1181 INFO DEMO_INFO <<app: appMain 3>> PlatformTestingToolMain: Stop generating transactions
node3 2m 5.205s 2025-10-15 12:09:47.436 1182 INFO DEMO_INFO <<app: appMain 3>> PlatformTestingToolMain: node 3 sent ENTER_VALIDATION Message
node3 2m 5.207s 2025-10-15 12:09:47.438 1183 INFO DEMO_INFO <<app: appMain 3>> PlatformTestingToolMain: Node 3 finished generating all transactions.
node0 2m 5.826s 2025-10-15 12:09:48.057 1183 INFO DEMO_INFO <<app: appMain 0>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_UPDATE transactions
node0 2m 5.828s 2025-10-15 12:09:48.059 1184 INFO DEMO_INFO <<app: appMain 0>> PttTransactionPool: Generated enough virtual merkle test for sequential mode
node0 2m 5.828s 2025-10-15 12:09:48.059 1185 INFO DEMO_INFO <<app: appMain 0>> PlatformTestingToolMain: Stop generating transactions
node0 2m 5.828s 2025-10-15 12:09:48.059 1186 INFO DEMO_INFO <<app: appMain 0>> PlatformTestingToolMain: node 0 sent ENTER_VALIDATION Message
node0 2m 5.831s 2025-10-15 12:09:48.062 1187 INFO DEMO_INFO <<app: appMain 0>> PlatformTestingToolMain: Node 0 finished generating all transactions.
node1 2m 6.342s 2025-10-15 12:09:48.573 1195 INFO DEMO_INFO <<app: appMain 1>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_UPDATE transactions
node1 2m 6.395s 2025-10-15 12:09:48.626 1196 INFO DEMO_INFO <<app: appMain 1>> PttTransactionPool: Generated enough virtual merkle test for sequential mode
node1 2m 6.396s 2025-10-15 12:09:48.627 1197 INFO DEMO_INFO <<app: appMain 1>> PlatformTestingToolMain: Stop generating transactions
node1 2m 6.396s 2025-10-15 12:09:48.627 1198 INFO DEMO_INFO <<app: appMain 1>> PlatformTestingToolMain: node 1 sent ENTER_VALIDATION Message
node1 2m 6.400s 2025-10-15 12:09:48.631 1199 INFO DEMO_INFO <<app: appMain 1>> PlatformTestingToolMain: Node 1 finished generating all transactions.
node0 2m 7.468s 2025-10-15 12:09:49.699 1204 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 3, type = ENTER_VALIDATION, consensusTimestamp = 2025-10-15T12:09:47.880207661Z ]
node0 2m 7.469s 2025-10-15 12:09:49.700 1205 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 3 apply state ControlAction[timestamp=2025-10-15T12:09:47.880207661Z,type=ENTER_VALIDATION]
node0 2m 7.472s 2025-10-15 12:09:49.703 1206 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 0, type = ENTER_VALIDATION, consensusTimestamp = 2025-10-15T12:09:48.430377Z ]
node0 2m 7.472s 2025-10-15 12:09:49.703 1207 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 0 apply state ControlAction[timestamp=2025-10-15T12:09:48.430377Z,type=ENTER_VALIDATION]
node1 2m 7.506s 2025-10-15 12:09:49.737 1200 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 3, type = ENTER_VALIDATION, consensusTimestamp = 2025-10-15T12:09:47.880207661Z ]
node1 2m 7.507s 2025-10-15 12:09:49.738 1201 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 3 apply state ControlAction[timestamp=2025-10-15T12:09:47.880207661Z,type=ENTER_VALIDATION]
node1 2m 7.510s 2025-10-15 12:09:49.741 1202 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 0, type = ENTER_VALIDATION, consensusTimestamp = 2025-10-15T12:09:48.430377Z ]
node1 2m 7.510s 2025-10-15 12:09:49.741 1203 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 0 apply state ControlAction[timestamp=2025-10-15T12:09:48.430377Z,type=ENTER_VALIDATION]
node2 2m 7.605s 2025-10-15 12:09:49.836 1195 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 3, type = ENTER_VALIDATION, consensusTimestamp = 2025-10-15T12:09:47.880207661Z ]
node2 2m 7.606s 2025-10-15 12:09:49.837 1196 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 3 apply state ControlAction[timestamp=2025-10-15T12:09:47.880207661Z,type=ENTER_VALIDATION]
node2 2m 7.609s 2025-10-15 12:09:49.840 1197 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 0, type = ENTER_VALIDATION, consensusTimestamp = 2025-10-15T12:09:48.430377Z ]
node2 2m 7.609s 2025-10-15 12:09:49.840 1198 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 0 apply state ControlAction[timestamp=2025-10-15T12:09:48.430377Z,type=ENTER_VALIDATION]
node2 2m 7.684s 2025-10-15 12:09:49.915 1203 INFO DEMO_INFO <<app: appMain 2>> SequentialTransactionRequestSupplier: Finished generating TYPE_VIRTUAL_MERKLE_UPDATE transactions
node2 2m 7.685s 2025-10-15 12:09:49.916 1204 INFO DEMO_INFO <<app: appMain 2>> PttTransactionPool: Generated enough virtual merkle test for sequential mode
node2 2m 7.685s 2025-10-15 12:09:49.916 1205 INFO DEMO_INFO <<app: appMain 2>> PlatformTestingToolMain: Stop generating transactions
node2 2m 7.686s 2025-10-15 12:09:49.917 1206 INFO DEMO_INFO <<app: appMain 2>> PlatformTestingToolMain: node 2 sent ENTER_VALIDATION Message
node2 2m 7.686s 2025-10-15 12:09:49.917 1207 INFO DEMO_INFO <<app: appMain 2>> PlatformTestingToolMain: Node 2 finished generating all transactions.
node3 2m 7.703s 2025-10-15 12:09:49.934 1204 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 3, type = ENTER_VALIDATION, consensusTimestamp = 2025-10-15T12:09:47.880207661Z ]
node3 2m 7.703s 2025-10-15 12:09:49.934 1205 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 3 apply state ControlAction[timestamp=2025-10-15T12:09:47.880207661Z,type=ENTER_VALIDATION]
node3 2m 7.706s 2025-10-15 12:09:49.937 1206 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 0, type = ENTER_VALIDATION, consensusTimestamp = 2025-10-15T12:09:48.430377Z ]
node3 2m 7.707s 2025-10-15 12:09:49.938 1207 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 0 apply state ControlAction[timestamp=2025-10-15T12:09:48.430377Z,type=ENTER_VALIDATION]
node1 2m 8.124s 2025-10-15 12:09:50.355 1208 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 1, type = ENTER_VALIDATION, consensusTimestamp = 2025-10-15T12:09:49.070130Z ]
node1 2m 8.124s 2025-10-15 12:09:50.355 1209 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 1 apply state ControlAction[timestamp=2025-10-15T12:09:49.070130Z,type=ENTER_VALIDATION]
node2 2m 8.256s 2025-10-15 12:09:50.487 1208 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 1, type = ENTER_VALIDATION, consensusTimestamp = 2025-10-15T12:09:49.070130Z ]
node2 2m 8.256s 2025-10-15 12:09:50.487 1209 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 1 apply state ControlAction[timestamp=2025-10-15T12:09:49.070130Z,type=ENTER_VALIDATION]
node3 2m 8.393s 2025-10-15 12:09:50.624 1228 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 1, type = ENTER_VALIDATION, consensusTimestamp = 2025-10-15T12:09:49.070130Z ]
node3 2m 8.394s 2025-10-15 12:09:50.625 1229 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 1 apply state ControlAction[timestamp=2025-10-15T12:09:49.070130Z,type=ENTER_VALIDATION]
node0 2m 8.409s 2025-10-15 12:09:50.640 1228 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 1, type = ENTER_VALIDATION, consensusTimestamp = 2025-10-15T12:09:49.070130Z ]
node0 2m 8.409s 2025-10-15 12:09:50.640 1229 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 1 apply state ControlAction[timestamp=2025-10-15T12:09:49.070130Z,type=ENTER_VALIDATION]
node2 2m 9.678s 2025-10-15 12:09:51.909 1226 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 2, type = ENTER_VALIDATION, consensusTimestamp = 2025-10-15T12:09:50.330081244Z ]
node2 2m 9.678s 2025-10-15 12:09:51.909 1227 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 2 apply state ControlAction[timestamp=2025-10-15T12:09:50.330081244Z,type=ENTER_VALIDATION]
node2 2m 9.679s 2025-10-15 12:09:51.910 1228 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolMain: Handling Quorum Transition [ triggeringNodeId = 2, type = ENTER_VALIDATION, consensusTime = 2025-10-15T12:09:50.330081244Z ]
node2 2m 9.680s 2025-10-15 12:09:51.911 1230 INFO DEMO_QUORUM <<PTT: enter-validator 2>> PlatformTestingToolMain: Achieved Quorum on ENTER_VALIDATION transaction [ expectedMapFile = Node0002_ExpectedMap_1760530190330_330081244.json, consensusTime = 2025-10-15T12:09:50.330081244Z ]
node2 2m 9.702s 2025-10-15 12:09:51.933 1234 INFO SAVE_EXPECTED_MAP <<PTT: enter-validator 2>> SaveExpectedMapHandler: Serializing ExpectedMap Node0002_ExpectedMap_1760530190330_330081244.json.gz
node3 2m 9.803s 2025-10-15 12:09:52.034 1230 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 2, type = ENTER_VALIDATION, consensusTimestamp = 2025-10-15T12:09:50.330081244Z ]
node1 2m 9.804s 2025-10-15 12:09:52.035 1226 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 2, type = ENTER_VALIDATION, consensusTimestamp = 2025-10-15T12:09:50.330081244Z ]
node1 2m 9.804s 2025-10-15 12:09:52.035 1227 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 2 apply state ControlAction[timestamp=2025-10-15T12:09:50.330081244Z,type=ENTER_VALIDATION]
node3 2m 9.804s 2025-10-15 12:09:52.035 1231 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 2 apply state ControlAction[timestamp=2025-10-15T12:09:50.330081244Z,type=ENTER_VALIDATION]
node1 2m 9.805s 2025-10-15 12:09:52.036 1228 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolMain: Handling Quorum Transition [ triggeringNodeId = 2, type = ENTER_VALIDATION, consensusTime = 2025-10-15T12:09:50.330081244Z ]
node3 2m 9.805s 2025-10-15 12:09:52.036 1232 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolMain: Handling Quorum Transition [ triggeringNodeId = 2, type = ENTER_VALIDATION, consensusTime = 2025-10-15T12:09:50.330081244Z ]
node1 2m 9.807s 2025-10-15 12:09:52.038 1230 INFO DEMO_QUORUM <<PTT: enter-validator 1>> PlatformTestingToolMain: Achieved Quorum on ENTER_VALIDATION transaction [ expectedMapFile = Node0001_ExpectedMap_1760530190330_330081244.json, consensusTime = 2025-10-15T12:09:50.330081244Z ]
node3 2m 9.808s 2025-10-15 12:09:52.039 1234 INFO DEMO_QUORUM <<PTT: enter-validator 3>> PlatformTestingToolMain: Achieved Quorum on ENTER_VALIDATION transaction [ expectedMapFile = Node0003_ExpectedMap_1760530190330_330081244.json, consensusTime = 2025-10-15T12:09:50.330081244Z ]
node0 2m 9.827s 2025-10-15 12:09:52.058 1230 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 2, type = ENTER_VALIDATION, consensusTimestamp = 2025-10-15T12:09:50.330081244Z ]
node0 2m 9.828s 2025-10-15 12:09:52.059 1231 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 2 apply state ControlAction[timestamp=2025-10-15T12:09:50.330081244Z,type=ENTER_VALIDATION]
node0 2m 9.828s 2025-10-15 12:09:52.059 1232 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolMain: Handling Quorum Transition [ triggeringNodeId = 2, type = ENTER_VALIDATION, consensusTime = 2025-10-15T12:09:50.330081244Z ]
node1 2m 9.829s 2025-10-15 12:09:52.060 1234 INFO SAVE_EXPECTED_MAP <<PTT: enter-validator 1>> SaveExpectedMapHandler: Serializing ExpectedMap Node0001_ExpectedMap_1760530190330_330081244.json.gz
node3 2m 9.829s 2025-10-15 12:09:52.060 1238 INFO SAVE_EXPECTED_MAP <<PTT: enter-validator 3>> SaveExpectedMapHandler: Serializing ExpectedMap Node0003_ExpectedMap_1760530190330_330081244.json.gz
node0 2m 9.830s 2025-10-15 12:09:52.061 1234 INFO DEMO_QUORUM <<PTT: enter-validator 0>> PlatformTestingToolMain: Achieved Quorum on ENTER_VALIDATION transaction [ expectedMapFile = Node0000_ExpectedMap_1760530190330_330081244.json, consensusTime = 2025-10-15T12:09:50.330081244Z ]
node0 2m 9.851s 2025-10-15 12:09:52.082 1238 INFO SAVE_EXPECTED_MAP <<PTT: enter-validator 0>> SaveExpectedMapHandler: Serializing ExpectedMap Node0000_ExpectedMap_1760530190330_330081244.json.gz
node2 2m 10.007s 2025-10-15 12:09:52.238 1235 INFO DEMO_QUORUM <<PTT: enter-validator 2>> PlatformTestingToolMain: Successfully wrote expected map to file [ expectedMapFile = Node0002_ExpectedMap_1760530190330_330081244.json, consensusTime = 2025-10-15T12:09:50.330081244Z ]
node2 2m 10.007s 2025-10-15 12:09:52.238 1236 INFO DEMO_QUORUM <<PTT: enter-validator 2>> PlatformTestingToolMain: Sent EXIT_VALIDATION transaction [ consensusTime = 2025-10-15T12:09:50.330081244Z ]
node3 2m 10.131s 2025-10-15 12:09:52.362 1239 INFO DEMO_QUORUM <<PTT: enter-validator 3>> PlatformTestingToolMain: Successfully wrote expected map to file [ expectedMapFile = Node0003_ExpectedMap_1760530190330_330081244.json, consensusTime = 2025-10-15T12:09:50.330081244Z ]
node3 2m 10.132s 2025-10-15 12:09:52.363 1240 INFO DEMO_QUORUM <<PTT: enter-validator 3>> PlatformTestingToolMain: Sent EXIT_VALIDATION transaction [ consensusTime = 2025-10-15T12:09:50.330081244Z ]
node1 2m 10.143s 2025-10-15 12:09:52.374 1235 INFO DEMO_QUORUM <<PTT: enter-validator 1>> PlatformTestingToolMain: Successfully wrote expected map to file [ expectedMapFile = Node0001_ExpectedMap_1760530190330_330081244.json, consensusTime = 2025-10-15T12:09:50.330081244Z ]
node1 2m 10.144s 2025-10-15 12:09:52.375 1236 INFO DEMO_QUORUM <<PTT: enter-validator 1>> PlatformTestingToolMain: Sent EXIT_VALIDATION transaction [ consensusTime = 2025-10-15T12:09:50.330081244Z ]
node0 2m 10.151s 2025-10-15 12:09:52.382 1239 INFO DEMO_QUORUM <<PTT: enter-validator 0>> PlatformTestingToolMain: Successfully wrote expected map to file [ expectedMapFile = Node0000_ExpectedMap_1760530190330_330081244.json, consensusTime = 2025-10-15T12:09:50.330081244Z ]
node0 2m 10.151s 2025-10-15 12:09:52.382 1240 INFO DEMO_QUORUM <<PTT: enter-validator 0>> PlatformTestingToolMain: Sent EXIT_VALIDATION transaction [ consensusTime = 2025-10-15T12:09:50.330081244Z ]
node3 2m 12.784s 2025-10-15 12:09:55.015 1261 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 2, type = EXIT_VALIDATION, consensusTimestamp = 2025-10-15T12:09:52.660135Z ]
node3 2m 12.784s 2025-10-15 12:09:55.015 1262 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 2 apply state ControlAction[timestamp=2025-10-15T12:09:52.660135Z,type=EXIT_VALIDATION]
node3 2m 12.784s 2025-10-15 12:09:55.015 1263 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 0, type = EXIT_VALIDATION, consensusTimestamp = 2025-10-15T12:09:52.808915254Z ]
node3 2m 12.785s 2025-10-15 12:09:55.016 1264 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 0 apply state ControlAction[timestamp=2025-10-15T12:09:52.808915254Z,type=EXIT_VALIDATION]
node3 2m 12.785s 2025-10-15 12:09:55.016 1265 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 3, type = EXIT_VALIDATION, consensusTimestamp = 2025-10-15T12:09:52.810418401Z ]
node3 2m 12.785s 2025-10-15 12:09:55.016 1266 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 3 apply state ControlAction[timestamp=2025-10-15T12:09:52.810418401Z,type=EXIT_VALIDATION]
node3 2m 12.785s 2025-10-15 12:09:55.016 1267 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 1, type = EXIT_VALIDATION, consensusTimestamp = 2025-10-15T12:09:52.810420Z ]
node3 2m 12.785s 2025-10-15 12:09:55.016 1268 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 1 apply state ControlAction[timestamp=2025-10-15T12:09:52.810420Z,type=EXIT_VALIDATION]
node3 2m 12.785s 2025-10-15 12:09:55.016 1269 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolMain: Handling Quorum Transition [ triggeringNodeId = 1, type = EXIT_VALIDATION, consensusTime = 2025-10-15T12:09:52.810420Z ]
node3 2m 12.786s 2025-10-15 12:09:55.017 1270 INFO DEMO_QUORUM <<PTT: exit-validator 3>> PlatformTestingToolMain: Achieved Quorum on EXIT_VALIDATION transaction [ consensusTime = 2025-10-15T12:09:52.810420Z ]
node3 2m 12.787s 2025-10-15 12:09:55.018 1271 INFO DEMO_QUORUM <<PTT: exit-validator 3>> PlatformTestingToolMain: Preparing to terminate the JVM [ sleepAfterTestMs = 90000, consensusTime = 2025-10-15T12:09:52.810420Z ]
node3 2m 12.790s 2025-10-15 12:09:55.021 1272 INFO DEMO_INFO <<PTT: exit-validator 3>> PlatformTestingToolMain: Test success: Reached quorum on the EXIT_VALIDATION transaction, consensus time = 2025-10-15T12:09:52.810420Z {} [com.swirlds.logging.legacy.payload.ApplicationFinishedPayload]
node1 2m 12.852s 2025-10-15 12:09:55.083 1273 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 2, type = EXIT_VALIDATION, consensusTimestamp = 2025-10-15T12:09:52.660135Z ]
node1 2m 12.853s 2025-10-15 12:09:55.084 1274 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 2 apply state ControlAction[timestamp=2025-10-15T12:09:52.660135Z,type=EXIT_VALIDATION]
node1 2m 12.853s 2025-10-15 12:09:55.084 1275 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 0, type = EXIT_VALIDATION, consensusTimestamp = 2025-10-15T12:09:52.808915254Z ]
node1 2m 12.854s 2025-10-15 12:09:55.085 1276 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 0 apply state ControlAction[timestamp=2025-10-15T12:09:52.808915254Z,type=EXIT_VALIDATION]
node1 2m 12.854s 2025-10-15 12:09:55.085 1277 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 3, type = EXIT_VALIDATION, consensusTimestamp = 2025-10-15T12:09:52.810418401Z ]
node1 2m 12.854s 2025-10-15 12:09:55.085 1278 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 3 apply state ControlAction[timestamp=2025-10-15T12:09:52.810418401Z,type=EXIT_VALIDATION]
node1 2m 12.854s 2025-10-15 12:09:55.085 1279 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 1, type = EXIT_VALIDATION, consensusTimestamp = 2025-10-15T12:09:52.810420Z ]
node1 2m 12.854s 2025-10-15 12:09:55.085 1280 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 1 apply state ControlAction[timestamp=2025-10-15T12:09:52.810420Z,type=EXIT_VALIDATION]
node1 2m 12.854s 2025-10-15 12:09:55.085 1281 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolMain: Handling Quorum Transition [ triggeringNodeId = 1, type = EXIT_VALIDATION, consensusTime = 2025-10-15T12:09:52.810420Z ]
node1 2m 12.856s 2025-10-15 12:09:55.087 1282 INFO DEMO_QUORUM <<PTT: exit-validator 1>> PlatformTestingToolMain: Achieved Quorum on EXIT_VALIDATION transaction [ consensusTime = 2025-10-15T12:09:52.810420Z ]
node1 2m 12.856s 2025-10-15 12:09:55.087 1283 INFO DEMO_QUORUM <<PTT: exit-validator 1>> PlatformTestingToolMain: Preparing to terminate the JVM [ sleepAfterTestMs = 90000, consensusTime = 2025-10-15T12:09:52.810420Z ]
node1 2m 12.858s 2025-10-15 12:09:55.089 1284 INFO DEMO_INFO <<PTT: exit-validator 1>> PlatformTestingToolMain: Test success: Reached quorum on the EXIT_VALIDATION transaction, consensus time = 2025-10-15T12:09:52.810420Z {} [com.swirlds.logging.legacy.payload.ApplicationFinishedPayload]
node2 2m 12.970s 2025-10-15 12:09:55.201 1273 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 2, type = EXIT_VALIDATION, consensusTimestamp = 2025-10-15T12:09:52.660135Z ]
node2 2m 12.971s 2025-10-15 12:09:55.202 1274 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 2 apply state ControlAction[timestamp=2025-10-15T12:09:52.660135Z,type=EXIT_VALIDATION]
node2 2m 12.971s 2025-10-15 12:09:55.202 1275 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 0, type = EXIT_VALIDATION, consensusTimestamp = 2025-10-15T12:09:52.808915254Z ]
node2 2m 12.971s 2025-10-15 12:09:55.202 1276 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 0 apply state ControlAction[timestamp=2025-10-15T12:09:52.808915254Z,type=EXIT_VALIDATION]
node2 2m 12.971s 2025-10-15 12:09:55.202 1277 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 3, type = EXIT_VALIDATION, consensusTimestamp = 2025-10-15T12:09:52.810418401Z ]
node2 2m 12.971s 2025-10-15 12:09:55.202 1278 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 3 apply state ControlAction[timestamp=2025-10-15T12:09:52.810418401Z,type=EXIT_VALIDATION]
node2 2m 12.971s 2025-10-15 12:09:55.202 1279 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 1, type = EXIT_VALIDATION, consensusTimestamp = 2025-10-15T12:09:52.810420Z ]
node2 2m 12.972s 2025-10-15 12:09:55.203 1280 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 1 apply state ControlAction[timestamp=2025-10-15T12:09:52.810420Z,type=EXIT_VALIDATION]
node2 2m 12.972s 2025-10-15 12:09:55.203 1281 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolMain: Handling Quorum Transition [ triggeringNodeId = 1, type = EXIT_VALIDATION, consensusTime = 2025-10-15T12:09:52.810420Z ]
node2 2m 12.972s 2025-10-15 12:09:55.203 1282 INFO DEMO_QUORUM <<PTT: exit-validator 2>> PlatformTestingToolMain: Achieved Quorum on EXIT_VALIDATION transaction [ consensusTime = 2025-10-15T12:09:52.810420Z ]
node2 2m 12.973s 2025-10-15 12:09:55.204 1283 INFO DEMO_QUORUM <<PTT: exit-validator 2>> PlatformTestingToolMain: Preparing to terminate the JVM [ sleepAfterTestMs = 90000, consensusTime = 2025-10-15T12:09:52.810420Z ]
node2 2m 12.975s 2025-10-15 12:09:55.206 1284 INFO DEMO_INFO <<PTT: exit-validator 2>> PlatformTestingToolMain: Test success: Reached quorum on the EXIT_VALIDATION transaction, consensus time = 2025-10-15T12:09:52.810420Z {} [com.swirlds.logging.legacy.payload.ApplicationFinishedPayload]
node0 2m 13.038s 2025-10-15 12:09:55.269 1277 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 2, type = EXIT_VALIDATION, consensusTimestamp = 2025-10-15T12:09:52.660135Z ]
node0 2m 13.038s 2025-10-15 12:09:55.269 1278 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 2 apply state ControlAction[timestamp=2025-10-15T12:09:52.660135Z,type=EXIT_VALIDATION]
node0 2m 13.039s 2025-10-15 12:09:55.270 1279 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 0, type = EXIT_VALIDATION, consensusTimestamp = 2025-10-15T12:09:52.808915254Z ]
node0 2m 13.039s 2025-10-15 12:09:55.270 1280 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 0 apply state ControlAction[timestamp=2025-10-15T12:09:52.808915254Z,type=EXIT_VALIDATION]
node0 2m 13.039s 2025-10-15 12:09:55.270 1281 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 3, type = EXIT_VALIDATION, consensusTimestamp = 2025-10-15T12:09:52.810418401Z ]
node0 2m 13.039s 2025-10-15 12:09:55.270 1282 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 3 apply state ControlAction[timestamp=2025-10-15T12:09:52.810418401Z,type=EXIT_VALIDATION]
node0 2m 13.039s 2025-10-15 12:09:55.270 1283 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolState: Handling Control Transaction [ originatingNodeId = 1, type = EXIT_VALIDATION, consensusTimestamp = 2025-10-15T12:09:52.810420Z ]
node0 2m 13.039s 2025-10-15 12:09:55.270 1284 INFO DEMO_INFO <<scheduler TransactionHandler>> QuorumTriggeredAction: Node 1 apply state ControlAction[timestamp=2025-10-15T12:09:52.810420Z,type=EXIT_VALIDATION]
node0 2m 13.040s 2025-10-15 12:09:55.271 1285 INFO DEMO_INFO <<scheduler TransactionHandler>> PlatformTestingToolMain: Handling Quorum Transition [ triggeringNodeId = 1, type = EXIT_VALIDATION, consensusTime = 2025-10-15T12:09:52.810420Z ]
node0 2m 13.040s 2025-10-15 12:09:55.271 1286 INFO DEMO_QUORUM <<PTT: exit-validator 0>> PlatformTestingToolMain: Achieved Quorum on EXIT_VALIDATION transaction [ consensusTime = 2025-10-15T12:09:52.810420Z ]
node0 2m 13.042s 2025-10-15 12:09:55.273 1287 INFO DEMO_INFO <<PTT: exit-validator 0>> TransactionSubmitter: Sending Freeze Transaction...
node0 2m 13.042s 2025-10-15 12:09:55.273 1288 INFO DEMO_INFO <<PTT: exit-validator 0>> TransactionSubmitter: Finished Sending Freeze Transaction.
node0 2m 13.042s 2025-10-15 12:09:55.273 1289 INFO DEMO_QUORUM <<PTT: exit-validator 0>> PlatformTestingToolMain: Preparing to terminate the JVM [ sleepAfterTestMs = 90000, consensusTime = 2025-10-15T12:09:52.810420Z ]
node0 2m 13.044s 2025-10-15 12:09:55.275 1290 INFO DEMO_INFO <<PTT: exit-validator 0>> PlatformTestingToolMain: Test success: Reached quorum on the EXIT_VALIDATION transaction, consensus time = 2025-10-15T12:09:52.810420Z {} [com.swirlds.logging.legacy.payload.ApplicationFinishedPayload]
node1 2m 15.123s 2025-10-15 12:09:57.354 1289 DEBUG FREEZE <<scheduler TransactionHandler>> FreezeTransactionHandler: Handling FreezeTransaction: startTimeEpochSecond: 1760530255
node3 2m 15.242s 2025-10-15 12:09:57.473 1309 DEBUG FREEZE <<scheduler TransactionHandler>> FreezeTransactionHandler: Handling FreezeTransaction: startTimeEpochSecond: 1760530255
node0 2m 15.314s 2025-10-15 12:09:57.545 1311 DEBUG FREEZE <<scheduler TransactionHandler>> FreezeTransactionHandler: Handling FreezeTransaction: startTimeEpochSecond: 1760530255
node2 2m 15.323s 2025-10-15 12:09:57.554 1305 DEBUG FREEZE <<scheduler TransactionHandler>> FreezeTransactionHandler: Handling FreezeTransaction: startTimeEpochSecond: 1760530255
node2 2m 57.884s 2025-10-15 12:10:40.115 1647 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.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.heartbeats.HeartbeatPeerProtocol.initiateHeartbeat(HeartbeatPeerProtocol.java:112) at com.swirlds.platform.heartbeats.HeartbeatPeerProtocol.runProtocol(HeartbeatPeerProtocol.java:156) 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)
node2 2m 57.884s 2025-10-15 12:10:40.115 1646 WARN SOCKET_EXCEPTIONS <<platform-core: SyncProtocolWith0 2 to 0>> NetworkUtils: Connection broken: 2 <- 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.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.heartbeats.HeartbeatPeerProtocol.acknowledgeHeartbeat(HeartbeatPeerProtocol.java:136) at com.swirlds.platform.heartbeats.HeartbeatPeerProtocol.runProtocol(HeartbeatPeerProtocol.java:157) 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)
node0 2m 57.953s 2025-10-15 12:10:40.184 1656 WARN SOCKET_EXCEPTIONS <<platform-core: SyncProtocolWith2 0 to 2>> NetworkUtils: Connection broken: 0 -> 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.SentInitiate.transition(SentInitiate.java:73) 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 57.953s 2025-10-15 12:10:40.184 1654 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.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.heartbeats.HeartbeatPeerProtocol.acknowledgeHeartbeat(HeartbeatPeerProtocol.java:136) at com.swirlds.platform.heartbeats.HeartbeatPeerProtocol.runProtocol(HeartbeatPeerProtocol.java:157) 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)
node2 2m 57.981s 2025-10-15 12:10:40.212 1648 WARN SOCKET_EXCEPTIONS <<platform-core: SyncProtocolWith1 2 to 1>> NetworkUtils: Connection broken: 2 <- 1
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.055s 2025-10-15 12:10:40.286 1650 WARN SOCKET_EXCEPTIONS <<platform-core: SyncProtocolWith2 1 to 2>> NetworkUtils: Connection broken: 1 -> 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.266s 2025-10-15 12:10:40.497 1655 WARN SOCKET_EXCEPTIONS <<platform-core: SyncProtocolWith1 3 to 1>> NetworkUtils: Connection broken: 3 <- 1
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.heartbeats.HeartbeatPeerProtocol.acknowledgeHeartbeat(HeartbeatPeerProtocol.java:136) at com.swirlds.platform.heartbeats.HeartbeatPeerProtocol.runProtocol(HeartbeatPeerProtocol.java:157) 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)
node3 2m 58.303s 2025-10-15 12:10:40.534 1656 WARN SOCKET_EXCEPTIONS <<platform-core: SyncProtocolWith0 3 to 0>> NetworkUtils: Connection broken: 3 <- 0
java.io.IOException: com.swirlds.common.threading.pool.ParallelExecutionException: Time thrown: 2025-10-15T12:10:40.533837630Z at com.swirlds.platform.gossip.sync.protocol.SyncPeerProtocol.runProtocol(SyncPeerProtocol.java:246) 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-10-15T12:10:40.533837630Z at com.swirlds.common.threading.pool.CachedPoolParallelExecutor.doParallel(CachedPoolParallelExecutor.java:145) at com.swirlds.platform.gossip.shadowgraph.ShadowgraphSynchronizer.readWriteParallel(ShadowgraphSynchronizer.java:279) at com.swirlds.platform.gossip.shadowgraph.ShadowgraphSynchronizer.sendAndReceiveEvents(ShadowgraphSynchronizer.java:217) at com.swirlds.platform.gossip.shadowgraph.ShadowgraphSynchronizer.reserveSynchronize(ShadowgraphSynchronizer.java:184) at com.swirlds.platform.gossip.shadowgraph.ShadowgraphSynchronizer.synchronize(ShadowgraphSynchronizer.java:105) at com.swirlds.platform.gossip.sync.protocol.SyncPeerProtocol.runProtocol(SyncPeerProtocol.java:243) ... 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:272) at com.swirlds.common.threading.pool.CachedPoolParallelExecutor.doParallel(CachedPoolParallelExecutor.java:143) ... 11 more
node1 2m 58.337s 2025-10-15 12:10:40.568 1651 WARN SOCKET_EXCEPTIONS <<platform-core: SyncProtocolWith3 1 to 3>> NetworkUtils: Connection broken: 1 -> 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.SentInitiate.transition(SentInitiate.java:73) 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 58.373s 2025-10-15 12:10:40.604 1657 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.SentInitiate.transition(SentInitiate.java:73) 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)
node2 3m 2.749s 2025-10-15 12:10:44.980 1649 INFO PLATFORM_STATUS <platformForkJoinThread-6> DefaultStatusStateMachine: Platform spent 2.7 m in ACTIVE. Now in CHECKING
node2 3m 2.750s 2025-10-15 12:10:44.981 1650 INFO STARTUP <platformForkJoinThread-6> PlatformTestingToolMain: Platform Status Change CHECKING
node3 3m 3.198s 2025-10-15 12:10:45.429 1657 INFO PLATFORM_STATUS <platformForkJoinThread-4> DefaultStatusStateMachine: Platform spent 2.7 m in ACTIVE. Now in CHECKING
node3 3m 3.199s 2025-10-15 12:10:45.430 1658 INFO STARTUP <platformForkJoinThread-4> PlatformTestingToolMain: Platform Status Change CHECKING
node0 3m 3.380s 2025-10-15 12:10:45.611 1658 INFO PLATFORM_STATUS <platformForkJoinThread-1> DefaultStatusStateMachine: Platform spent 2.7 m in ACTIVE. Now in CHECKING
node0 3m 3.381s 2025-10-15 12:10:45.612 1659 INFO STARTUP <platformForkJoinThread-1> PlatformTestingToolMain: Platform Status Change CHECKING
node1 3m 3.855s 2025-10-15 12:10:46.086 1652 INFO PLATFORM_STATUS <platformForkJoinThread-1> DefaultStatusStateMachine: Platform spent 2.7 m in ACTIVE. Now in CHECKING
node1 3m 3.856s 2025-10-15 12:10:46.087 1653 INFO STARTUP <platformForkJoinThread-1> PlatformTestingToolMain: Platform Status Change CHECKING
node3 3m 42.790s 2025-10-15 12:11:25.021 1659 INFO DEMO_QUORUM <<PTT: exit-validator 3>> PlatformTestingToolMain: Terminating the JVM [ consensusTime = 2025-10-15T12:09:52.810420Z ]
node3 3m 42.792s 2025-10-15 12:11:25.023 1660 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:1217) at com.swirlds.demo.platform.PlatformTestingToolMain.lambda$handleExitValidation$21(PlatformTestingToolMain.java:1164) at java.lang.Thread.run(Thread.java:1583)
node3 3m 42.793s 2025-10-15 12:11:25.024 1661 INFO STARTUP <<PTT: exit-validator 3>> SystemExitUtils: Exiting system {"reason":"NO_ERROR","code":0} [com.swirlds.logging.legacy.payload.SystemExitPayload]
node3 3m 42.794s 2025-10-15 12:11:25.025 1662 INFO STARTUP <<browser: shutdown-hook>> Log4jSetup: JVM is shutting down.
node1 3m 42.859s 2025-10-15 12:11:25.090 1654 INFO DEMO_QUORUM <<PTT: exit-validator 1>> PlatformTestingToolMain: Terminating the JVM [ consensusTime = 2025-10-15T12:09:52.810420Z ]
node1 3m 42.863s 2025-10-15 12:11:25.094 1655 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:1217) at com.swirlds.demo.platform.PlatformTestingToolMain.lambda$handleExitValidation$21(PlatformTestingToolMain.java:1164) at java.lang.Thread.run(Thread.java:1583)
node1 3m 42.866s 2025-10-15 12:11:25.097 1656 INFO STARTUP <<PTT: exit-validator 1>> SystemExitUtils: Exiting system {"reason":"NO_ERROR","code":0} [com.swirlds.logging.legacy.payload.SystemExitPayload]
node1 3m 42.868s 2025-10-15 12:11:25.099 1657 INFO STARTUP <<browser: shutdown-hook>> Log4jSetup: JVM is shutting down.
node2 3m 42.975s 2025-10-15 12:11:25.206 1651 INFO DEMO_QUORUM <<PTT: exit-validator 2>> PlatformTestingToolMain: Terminating the JVM [ consensusTime = 2025-10-15T12:09:52.810420Z ]
node2 3m 42.979s 2025-10-15 12:11:25.210 1652 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:1217) at com.swirlds.demo.platform.PlatformTestingToolMain.lambda$handleExitValidation$21(PlatformTestingToolMain.java:1164) at java.lang.Thread.run(Thread.java:1583)
node2 3m 42.981s 2025-10-15 12:11:25.212 1653 INFO STARTUP <<PTT: exit-validator 2>> SystemExitUtils: Exiting system {"reason":"NO_ERROR","code":0} [com.swirlds.logging.legacy.payload.SystemExitPayload]
node2 3m 42.982s 2025-10-15 12:11:25.213 1654 INFO STARTUP <<browser: shutdown-hook>> Log4jSetup: JVM is shutting down.
node0 3m 43.045s 2025-10-15 12:11:25.276 1660 INFO DEMO_QUORUM <<PTT: exit-validator 0>> PlatformTestingToolMain: Terminating the JVM [ consensusTime = 2025-10-15T12:09:52.810420Z ]
node0 3m 43.048s 2025-10-15 12:11:25.279 1661 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:1217) at com.swirlds.demo.platform.PlatformTestingToolMain.lambda$handleExitValidation$21(PlatformTestingToolMain.java:1164) at java.lang.Thread.run(Thread.java:1583)
node0 3m 43.049s 2025-10-15 12:11:25.280 1662 INFO STARTUP <<PTT: exit-validator 0>> SystemExitUtils: Exiting system {"reason":"NO_ERROR","code":0} [com.swirlds.logging.legacy.payload.SystemExitPayload]
node0 3m 43.050s 2025-10-15 12:11:25.281 1663 INFO STARTUP <<browser: shutdown-hook>> Log4jSetup: JVM is shutting down.