C:\Projects\Agidens.Infra.PlcLogicTester\Agidens.Infra.PlcLogicTester.EventStore\start>..\EventStore\EventStore.ClusterNode.exe   -disable-http-caching --ext-tcp-heartbeat-timeout=20000  --run-projections=all --db ..\..\Agidens.Infra.PlcLogicTester.API\bin\x64\Debug\Data\EventStore\db --log ..\..\Agidens.Infra.PlcLogicTester.API\bin\x64\Debug\Data\EventStore\logs
[21348,01,12:58:42.824]
ES VERSION:               4.0.3.3 (oss-v4.0.3-hotfix1/357b56a59af2e733782acd4eba4c625c6bcf28db, Mon, 4 Dec 2017 08:06:24 +0000)
[21348,01,12:58:42.838] OS:                       Windows (Microsoft Windows NT 6.2.9200.0)
[21348,01,12:58:42.838] RUNTIME:                  .NET 4.0.30319.42000 (64-bit)
[21348,01,12:58:42.838] GC:                       3 GENERATIONS
[21348,01,12:58:42.838] LOGS:                     C:\Projects\Agidens.Infra.PlcLogicTester\Agidens.Infra.PlcLogicTester.API\bin\x64\Debug\Data\EventStore\logs
[21348,01,12:58:42.844] MODIFIED OPTIONS:

        DISABLE HTTP CACHING:     true (Command Line)
        EXT TCP HEARTBEAT TIMEOUT: 20000 (Command Line)
        RUN PROJECTIONS:          all (Command Line)
        DB:                       ..\..\Agidens.Infra.PlcLogicTester.API\bin\x64\Debug\Data\EventStore\db (Command Line)
        LOG:                      ..\..\Agidens.Infra.PlcLogicTester.API\bin\x64\Debug\Data\EventStore\logs (Command Line)

DEFAULT OPTIONS:

        HELP:                     False (<DEFAULT>)
        VERSION:                  False (<DEFAULT>)
        CONFIG:                   <empty> (<DEFAULT>)
        DEFINES:                  <empty> (<DEFAULT>)
        WHAT IF:                  False (<DEFAULT>)
        START STANDARD PROJECTIONS: False (<DEFAULT>)
        MONO MIN THREADPOOL SIZE: 10 (<DEFAULT>)
        INT IP:                   127.0.0.1 (<DEFAULT>)
        EXT IP:                   127.0.0.1 (<DEFAULT>)
        INT HTTP PORT:            2112 (<DEFAULT>)
        EXT HTTP PORT:            2113 (<DEFAULT>)
        INT TCP PORT:             1112 (<DEFAULT>)
        INT SECURE TCP PORT:      0 (<DEFAULT>)
        EXT TCP PORT:             1113 (<DEFAULT>)
        EXT SECURE TCP PORT ADVERTISE AS: 0 (<DEFAULT>)
        EXT SECURE TCP PORT:      0 (<DEFAULT>)
        EXT IP ADVERTISE AS:      <empty> (<DEFAULT>)
        EXT TCP PORT ADVERTISE AS: 0 (<DEFAULT>)
        EXT HTTP PORT ADVERTISE AS: 0 (<DEFAULT>)
        INT IP ADVERTISE AS:      <empty> (<DEFAULT>)
        INT SECURE TCP PORT ADVERTISE AS: 0 (<DEFAULT>)
        INT TCP PORT ADVERTISE AS: 0 (<DEFAULT>)
        INT HTTP PORT ADVERTISE AS: 0 (<DEFAULT>)
        INT TCP HEARTBEAT TIMEOUT: 700 (<DEFAULT>)
        INT TCP HEARTBEAT INTERVAL: 700 (<DEFAULT>)
        EXT TCP HEARTBEAT INTERVAL: 2000 (<DEFAULT>)
        GOSSIP ON SINGLE NODE:    False (<DEFAULT>)
        CONNECTION PENDING SEND BYTES THRESHOLD: 10485760 (<DEFAULT>)
        FORCE:                    False (<DEFAULT>)
        CLUSTER SIZE:             1 (<DEFAULT>)
        NODE PRIORITY:            0 (<DEFAULT>)
        MIN FLUSH DELAY MS:       2 (<DEFAULT>)
        COMMIT COUNT:             -1 (<DEFAULT>)
        PREPARE COUNT:            -1 (<DEFAULT>)
        ADMIN ON EXT:             True (<DEFAULT>)
        STATS ON EXT:             True (<DEFAULT>)
        GOSSIP ON EXT:            True (<DEFAULT>)
        DISABLE SCAVENGE MERGING: False (<DEFAULT>)
        SCAVENGE HISTORY MAX AGE: 30 (<DEFAULT>)
        DISCOVER VIA DNS:         True (<DEFAULT>)
        CLUSTER DNS:              fake.dns (<DEFAULT>)
        CLUSTER GOSSIP PORT:      30777 (<DEFAULT>)
        GOSSIP SEED:              <empty> (<DEFAULT>)
        STATS PERIOD SEC:         30 (<DEFAULT>)
        CACHED CHUNKS:            -1 (<DEFAULT>)
        READER THREADS COUNT:     4 (<DEFAULT>)
        CHUNKS CACHE SIZE:        536871424 (<DEFAULT>)
        MAX MEM TABLE SIZE:       1000000 (<DEFAULT>)
        HASH COLLISION READ LIMIT: 100 (<DEFAULT>)
        INDEX:                    <empty> (<DEFAULT>)
        MEM DB:                   False (<DEFAULT>)
        SKIP DB VERIFY:           False (<DEFAULT>)
        WRITE THROUGH:            False (<DEFAULT>)
        UNBUFFERED:               False (<DEFAULT>)
        PROJECTION THREADS:       3 (<DEFAULT>)
        WORKER THREADS:           5 (<DEFAULT>)
        INT HTTP PREFIXES:        <empty> (<DEFAULT>)
        EXT HTTP PREFIXES:        <empty> (<DEFAULT>)
        ENABLE TRUSTED AUTH:      False (<DEFAULT>)
        ADD INTERFACE PREFIXES:   True (<DEFAULT>)
        CERTIFICATE STORE LOCATION: <empty> (<DEFAULT>)
        CERTIFICATE STORE NAME:   <empty> (<DEFAULT>)
        CERTIFICATE SUBJECT NAME: <empty> (<DEFAULT>)
        CERTIFICATE THUMBPRINT:   <empty> (<DEFAULT>)
        CERTIFICATE FILE:         <empty> (<DEFAULT>)
        CERTIFICATE PASSWORD:     <empty> (<DEFAULT>)
        USE INTERNAL SSL:         False (<DEFAULT>)
        DISABLE INSECURE TCP:     False (<DEFAULT>)
        SSL TARGET HOST:          n/a (<DEFAULT>)
        SSL VALIDATE SERVER:      True (<DEFAULT>)
        AUTHENTICATION TYPE:      internal (<DEFAULT>)
        AUTHENTICATION CONFIG:    <empty> (<DEFAULT>)
        PREPARE TIMEOUT MS:       2000 (<DEFAULT>)
        COMMIT TIMEOUT MS:        2000 (<DEFAULT>)
        UNSAFE DISABLE FLUSH TO DISK: False (<DEFAULT>)
        BETTER ORDERING:          False (<DEFAULT>)
        UNSAFE IGNORE HARD DELETE: False (<DEFAULT>)
        INDEX CACHE DEPTH:        16 (<DEFAULT>)
        GOSSIP INTERVAL MS:       1000 (<DEFAULT>)
        GOSSIP ALLOWED DIFFERENCE MS: 60000 (<DEFAULT>)
        GOSSIP TIMEOUT MS:        500 (<DEFAULT>)
        ENABLE HISTOGRAMS:        False (<DEFAULT>)
        LOG HTTP REQUESTS:        False (<DEFAULT>)
        ALWAYS KEEP SCAVENGED:    False (<DEFAULT>)
        SKIP INDEX SCAN ON READS: False (<DEFAULT>)

[21348,01,12:58:42.844] Quorum size set to 1
[21348,01,12:58:42.859] Cannot find plugins path: C:\Projects\Agidens.Infra.PlcLogicTester\Agidens.Infra.PlcLogicTester.EventStore\EventStore\plugins
[21348,01,12:58:42.993] INSTANCE ID:              109b12e2-5abf-4ca8-bfb0-b09dc80f9fc2
[21348,01,12:58:42.993] DATABASE:                 ..\..\Agidens.Infra.PlcLogicTester.API\bin\x64\Debug\Data\EventStore\db
[21348,01,12:58:42.993] WRITER CHECKPOINT:        4558085 (0x458D05)
[21348,01,12:58:42.993] CHASER CHECKPOINT:        4558085 (0x458D05)
[21348,01,12:58:42.993] EPOCH CHECKPOINT:         4345646 (0x424F2E)
[21348,01,12:58:42.993] TRUNCATE CHECKPOINT:      -1 (0xFFFFFFFFFFFFFFFF)
[21348,01,12:58:43.111] MessageHierarchy initialization took 00:00:00.0752518.
[21348,01,12:58:43.201] Opened ongoing ..\..\Agidens.Infra.PlcLogicTester.API\bin\x64\Debug\Data\EventStore\db\chunk-000000.000000 as version 3
[21348,01,12:58:43.217] CACHED TFChunk #0-0 (chunk-000000.000000) in 00:00:00.0050077.
[21348,01,12:58:43.535] Starting MiniWeb for /web/es/js/projections ==> C:\Projects\Agidens.Infra.PlcLogicTester\Agidens.Infra.PlcLogicTester.EventStore\EventStore\projections
[21348,01,12:58:43.535] Starting MiniWeb for /web/es/js/projections/v8/Prelude ==> C:\Projects\Agidens.Infra.PlcLogicTester\Agidens.Infra.PlcLogicTester.EventStore\EventStore\Prelude
[21348,01,12:58:43.535] Binding MiniWeb to /web/es/js/projections/{*remaining_path}
[21348,01,12:58:43.535] Binding MiniWeb to /web/es/js/projections/v8/Prelude/{*remaining_path}
[21348,01,12:58:43.551] Starting MiniWeb for /web ==> C:\Projects\Agidens.Infra.PlcLogicTester\Agidens.Infra.PlcLogicTester.EventStore\EventStore\clusternode-web
[21348,01,12:58:43.551] Binding MiniWeb to /web/{*remaining_path}
[21348,15,12:58:43.567] ========== [127.0.0.1:2112] SYSTEM INIT...
[21348,15,12:58:43.586] Starting Normal TCP listening on TCP endpoint: 127.0.0.1:1113.
[21348,17,12:58:43.603] TableIndex initialization...
[21348,17,12:58:43.616] ReadIndex building...
[21348,15,12:58:43.626] Starting HTTP server on [http://127.0.0.1:2113/,http://localhost:2113/]...
[21348,15,12:58:43.626] HTTP server is up and listening on [http://127.0.0.1:2113/,http://localhost:2113/]
[21348,15,12:58:43.640] SLOW QUEUE MSG [MainQueue]: SystemInit - 73ms. Q: 0/4.
[21348,15,12:58:43.640] ========== [127.0.0.1:2112] Service 'StorageReader' initialized.
[21348,15,12:58:43.640] ========== [127.0.0.1:2112] Service 'StorageWriter' initialized.
[21348,17,12:58:43.670] ReadIndex rebuilding done: total processed 647 records, time elapsed: 00:00:00.0540398.
[21348,15,12:58:43.670] ========== [127.0.0.1:2112] Service 'StorageChaser' initialized.
[21348,15,12:58:43.686] ========== [127.0.0.1:2112] SYSTEM START...
[21348,15,12:58:43.686] ========== [127.0.0.1:2112] IS UNKNOWN...
[21348,11,12:58:43.711] Subscriptions received state change to Unknown stopping listening.
[21348,15,12:58:43.724] ELECTIONS: STARTING ELECTIONS.
[21348,15,12:58:43.724] ELECTIONS: (V=0) SHIFT TO LEADER ELECTION.
[21348,15,12:58:43.724] ELECTIONS: (V=0) VIEWCHANGE FROM [127.0.0.1:2112, {109b12e2-5abf-4ca8-bfb0-b09dc80f9fc2}].
[21348,15,12:58:43.724] ELECTIONS: (V=0) MAJORITY OF VIEWCHANGE.
[21348,15,12:58:43.724] ELECTIONS: (V=0) SHIFT TO PREPARE PHASE.
[21348,15,12:58:43.724] ELECTIONS: (V=0) PREPARE_OK FROM [127.0.0.1:2112,{109b12e2-5abf-4ca8-bfb0-b09dc80f9fc2}](L=4557481,W=4558085,C=4558085,E2@4345646:{bf5de0de-2770-410d-bd52-54562207b01d}).
[21348,15,12:58:43.732] ELECTIONS: (V=0) SHIFT TO REG_LEADER.
[21348,15,12:58:43.732] ELECTIONS: (V=0) SENDING PROPOSAL CANDIDATE: [127.0.0.1:2112,{109b12e2-5abf-4ca8-bfb0-b09dc80f9fc2}](L=4557481,W=4558085,C=4558085,E2@4345646:{bf5de0de-2770-410d-bd52-54562207b01d}), ME: [127.0.0.1:2112,{109b12e2-5abf-4ca8-bfb0-b09dc80f9fc2}](L=4557481,W=4558085,C=4558085,E2@4345646:{bf5de0de-2770-410d-bd52-54562207b01d}).
[21348,15,12:58:43.732] ELECTIONS: (V=0) ACCEPT FROM [127.0.0.1:2112,{109b12e2-5abf-4ca8-bfb0-b09dc80f9fc2}] M=[127.0.0.1:2112,{109b12e2-5abf-4ca8-bfb0-b09dc80f9fc2}]).
[21348,15,12:58:43.732] ELECTIONS: (V=0) DONE. ELECTED MASTER = [127.0.0.1:2112,{109b12e2-5abf-4ca8-bfb0-b09dc80f9fc2}](L=4557481,W=4558085,C=4558085,E2@4345646:{bf5de0de-2770-410d-bd52-54562207b01d}). ME=[127.0.0.1:2112,{109b12e2-5abf-4ca8-bfb0-b09dc80f9fc2}](L=4557481,W=4558085,C=4558085,E2@4345646:{bf5de0de-2770-410d-bd52-54562207b01d}).
[21348,15,12:58:43.761] SLOW BUS MSG [MainBus]: StartElections - 54ms. Handler: ElectionsService.
[21348,15,12:58:43.761] SLOW QUEUE MSG [MainQueue]: StartElections - 70ms. Q: 0/4.
[21348,15,12:58:43.763] ========== [127.0.0.1:2112] PRE-MASTER STATE, WAITING FOR CHASER TO CATCH UP...
[21348,11,12:58:43.763] Subscriptions received state change to PreMaster stopping listening.
[21348,15,12:58:43.763] ========== [127.0.0.1:2112] IS MASTER... SPARTA!
[21348,05,12:58:43.763] Subscriptions Became Master so now handling subscriptions
[21348,13,12:58:43.842] === Writing E3@4558085:{88dd9759-7d3d-46b1-89cb-862be6a0efea} (previous epoch at 4345646).
[21348,13,12:58:43.842] === Update Last Epoch E3@4558085:{88dd9759-7d3d-46b1-89cb-862be6a0efea} (previous epoch at 4345646).
[21348,18,12:58:43.857] PROJECTIONS: Starting Projections Core Coordinator. (Node State : Master)
[21348,15,12:58:43.880] Subscription file-$TabularView-FB_HEFDEUR_SM_HAPPYF.xlsb-$0::FileProcessor: read no checksum.
[21348,15,12:58:43.880] strtfrom = 0
[21348,15,12:58:43.880] Subscription file-$TabularView-FB_HEFDEUR_SM_HAPPYF.xlsb-$0::TestModels: read no checksum.
[21348,15,12:58:43.880] strtfrom = 0
[21348,15,12:58:43.880] Subscription file-$TabularView-FB_HEFDEUR_SM_HAPPYF.xlsb-$0::UIModels: read no checksum.
[21348,15,12:58:43.880] strtfrom = 0
[21348,15,12:58:43.880] Subscription installatie-$8423e8de-8399-fb33-f5fe-29205cc395bb::UIModels: read no checksum.
[21348,15,12:58:43.880] strtfrom = 0
[21348,15,12:58:43.880] Subscription file::UIModels: read no checksum.
[21348,15,12:58:43.880] strtfrom = 0
[21348,15,12:58:43.880] Subscription installatie::UIModels: read no checksum.
[21348,15,12:58:43.880] strtfrom = 0
[21348,15,12:58:43.880] Subscription testRun::UIModels: read no checksum.
[21348,15,12:58:43.880] strtfrom = 0
[21348,15,12:58:43.880] Subscription file::TestModels: read no checksum.
[21348,15,12:58:43.880] strtfrom = 0
[21348,15,12:58:43.880] Subscription testRun::TestProcessor: read no checksum.
[21348,15,12:58:43.880] strtfrom = 0
[21348,15,12:58:43.880] Subscription file::FileProcessor: read no checksum.
[21348,15,12:58:43.880] strtfrom = 0
[21348,15,12:58:43.880] Subscription testinstalatie::testreaderbegin: read no checksum.
[21348,15,12:58:43.880] strtfrom = 0
[21348,15,12:58:43.880] Subscription testSequence::UIModels: read no checksum.
[21348,15,12:58:43.880] strtfrom = 0
[21348,15,12:58:43.880] Subscription TestStream2::testreaderend: read no checksum.
[21348,15,12:58:43.880] strtfrom = -1
[21348,18,12:58:43.905] PROJECTIONS: Starting Projections Manager. (Node State : Master)
[21348,18,12:58:43.905] SLOW QUEUE MSG [Projections Master]: SystemCoreReady - 63ms. Q: 0/2.
[21348,21,12:58:43.921] PROJECTIONS: Starting Projection Core Reader (reads from $projections-$2634d850b2fd4a51bedb7ff0f7c6825a)
[21348,19,12:58:43.921] PROJECTIONS: Starting Projection Core Reader (reads from $projections-$4a54eb63b5f847bd876e9ccc2b025cda)
[21348,20,12:58:43.921] PROJECTIONS: Starting Projection Core Reader (reads from $projections-$9b12d405f91a46698c408c17404bbb8b)
[21348,20,12:58:43.921] SLOW BUS MSG [bus]: StartCore - 67ms. Handler: ProjectionCoreServiceCommandReader.
[21348,21,12:58:43.921] SLOW BUS MSG [bus]: StartCore - 68ms. Handler: ProjectionCoreServiceCommandReader.
[21348,19,12:58:43.921] SLOW BUS MSG [bus]: StartCore - 67ms. Handler: ProjectionCoreServiceCommandReader.
[21348,18,12:58:43.935] PROJECTIONS: Starting Projection Manager Response Reader (reads from $projections-$master)
[21348,18,12:58:43.953] SLOW BUS MSG [manager input bus]: Starting - 48ms. Handler: ProjectionManagerResponseReader.
[21348,21,12:58:43.968] PROJECTIONS: Resetting Master Writer
[21348,20,12:58:43.968] PROJECTIONS: Resetting Master Writer
[21348,21,12:58:43.968] SLOW QUEUE MSG [Projection Core #2]: StartCore - 102ms. Q: 2/5.
[21348,20,12:58:43.968] SLOW QUEUE MSG [Projection Core #1]: StartCore - 102ms. Q: 2/5.
[21348,19,12:58:43.968] PROJECTIONS: Resetting Master Writer
[21348,19,12:58:43.968] SLOW QUEUE MSG [Projection Core #0]: StartCore - 102ms. Q: 2/5.
[21348,20,12:58:43.990] PROJECTIONS: Finished Starting Projection Core Reader (reads from $projections-$9b12d405f91a46698c408c17404bbb8b)
[21348,19,12:58:43.990] PROJECTIONS: Finished Starting Projection Core Reader (reads from $projections-$4a54eb63b5f847bd876e9ccc2b025cda)
[21348,21,12:58:43.990] PROJECTIONS: Finished Starting Projection Core Reader (reads from $projections-$2634d850b2fd4a51bedb7ff0f7c6825a)
[21348,18,12:58:43.990] PROJECTIONS: Resetting Worker Writer
[21348,18,12:58:43.990] SLOW QUEUE MSG [Projections Master]: Starting - 87ms. Q: 0/3.
[21348,20,12:58:43.990] PROJECTIONS: Starting read $projections-$control-bf5de0de-2770-410d-bd52-54562207b01d
[21348,19,12:58:43.990] PROJECTIONS: Starting read $projections-$control-bf5de0de-2770-410d-bd52-54562207b01d
[21348,21,12:58:43.990] PROJECTIONS: Starting read $projections-$control-bf5de0de-2770-410d-bd52-54562207b01d
[21348,18,12:58:44.003] PROJECTIONS: Finished Starting Projection Manager Response Reader (reads from $projections-$master)
[21348,18,12:58:44.016] PROJECTIONS: Response received: 208@$response-reader-starting
[21348,18,12:58:44.016] PROJECTIONS: Response received: 209@$projection-worker-started
[21348,18,12:58:44.016] PROJECTIONS: Response received: 210@$projection-worker-started
[21348,18,12:58:44.016] PROJECTIONS: Response received: 211@$projection-worker-started
[21348,18,12:58:44.016] PROJECTIONS: Reading Existing Projections from $projections-$all
[21348,18,12:58:44.029] PROJECTIONS: Found the following projections. $streams, $stream_by_category, $by_category, $by_event_type, installatie, file, testSequence, testRun
[21348,18,12:58:44.029] Adding projection 2f385723-112d-42c3-afe5-5b2e2439b776@$streams to list
[21348,18,12:58:44.029] Adding projection 797366d4-de1b-4ebd-94e7-007feb7813c8@$stream_by_category to list
[21348,18,12:58:44.029] Adding projection 77b21277-f91e-4841-9952-0f7cde36625d@$by_category to list
[21348,18,12:58:44.029] Adding projection 7f26f13c-0b04-43d7-aab1-5259f3c3196a@$by_event_type to list
[21348,18,12:58:44.029] Adding projection ec35bead-9332-4e69-bf35-a3417986ab80@installatie to list
[21348,18,12:58:44.029] Adding projection 0ea7ad9e-6671-49bf-97ab-4ca94762f8bd@file to list
[21348,18,12:58:44.029] Adding projection c6da5db9-99f2-41ed-a42b-225d39a11618@testSequence to list
[21348,18,12:58:44.029] Adding projection 06e9b1a5-b79e-43da-b441-dc1e178ce642@testRun to list
[21348,15,12:58:44.029] ========== [127.0.0.1:2112] Sub System 'Projections' initialized.
[21348,18,12:58:44.088] PROJECTIONS: Scheduling the writing of $create-prepared to $projections-$4a54eb63-b5f8-47bd-876e-9ccc2b025cda. Current status of Writer: Busy: False
[21348,18,12:58:44.106] PROJECTIONS: Scheduling the writing of $create-and-prepare to $projections-$2634d850-b2fd-4a51-bedb-7ff0f7c6825a. Current status of Writer: Busy: False
[21348,18,12:58:44.113] PROJECTIONS: Scheduling the writing of $create-prepared to $projections-$9b12d405-f91a-4669-8c40-8c17404bbb8b. Current status of Writer: Busy: False
[21348,18,12:58:44.113] PROJECTIONS: Scheduling the writing of $create-prepared to $projections-$4a54eb63-b5f8-47bd-876e-9ccc2b025cda. Current status of Writer: Busy: True
[21348,18,12:58:44.113] PROJECTIONS: Scheduling the writing of $create-and-prepare to $projections-$9b12d405-f91a-4669-8c40-8c17404bbb8b. Current status of Writer: Busy: True
[21348,18,12:58:44.113] PROJECTIONS: Scheduling the writing of $create-and-prepare to $projections-$2634d850-b2fd-4a51-bedb-7ff0f7c6825a. Current status of Writer: Busy: True
[21348,18,12:58:44.113] PROJECTIONS: Scheduling the writing of $create-and-prepare to $projections-$4a54eb63-b5f8-47bd-876e-9ccc2b025cda. Current status of Writer: Busy: True
[21348,18,12:58:44.113] PROJECTIONS: Scheduling the writing of $create-and-prepare to $projections-$9b12d405-f91a-4669-8c40-8c17404bbb8b. Current status of Writer: Busy: True
[21348,18,12:58:44.113] PROJECTIONS: Finished writing events to $projections-$4a54eb63b5f847bd876e9ccc2b025cda: $create-prepared
[21348,18,12:58:44.113] PROJECTIONS: Finished writing events to $projections-$2634d850b2fd4a51bedb7ff0f7c6825a: $create-and-prepare
[21348,18,12:58:44.113] PROJECTIONS: Finished writing events to $projections-$9b12d405f91a46698c408c17404bbb8b: $create-prepared
[21348,19,12:58:44.113] PROJECTIONS: Command received: 0@$create-prepared
[21348,20,12:58:44.113] PROJECTIONS: Command received: 0@$create-prepared
[21348,21,12:58:44.113] PROJECTIONS: Command received: 0@$create-and-prepare
[21348,18,12:58:44.125] PROJECTIONS: Finished writing events to $projections-$4a54eb63b5f847bd876e9ccc2b025cda: $create-prepared
[21348,18,12:58:44.125] PROJECTIONS: Finished writing events to $projections-$4a54eb63b5f847bd876e9ccc2b025cda: $create-and-prepare
[21348,18,12:58:44.125] PROJECTIONS: Finished writing events to $projections-$2634d850b2fd4a51bedb7ff0f7c6825a: $create-and-prepare
[21348,18,12:58:44.125] PROJECTIONS: Finished writing events to $projections-$9b12d405f91a46698c408c17404bbb8b: $create-and-prepare
[21348,18,12:58:44.125] PROJECTIONS: Finished writing events to $projections-$9b12d405f91a46698c408c17404bbb8b: $create-and-prepare
[21348,20,12:58:44.187] PROJECTIONS: Scheduling the writing of $prepared to $projections-$master. Current status of Writer: Busy: True
[21348,19,12:58:44.187] PROJECTIONS: Scheduling the writing of $prepared to $projections-$master. Current status of Writer: Busy: True
[21348,21,12:58:44.187] PROJECTIONS: Scheduling the writing of $prepared to $projections-$master. Current status of Writer: Busy: True
[21348,20,12:58:44.187] PROJECTIONS: Command received: 1@$create-and-prepare
[21348,21,12:58:44.187] PROJECTIONS: Command received: 1@$create-and-prepare
[21348,19,12:58:44.187] PROJECTIONS: Command received: 1@$create-prepared
[21348,20,12:58:44.187] PROJECTIONS: Command received: 2@$create-and-prepare
[21348,19,12:58:44.187] PROJECTIONS: Command received: 2@$create-and-prepare
[21348,20,12:58:44.301] SLOW BUS MSG [bus]: CreateAndPrepare - 111ms. Handler: ProjectionCoreService.
[21348,20,12:58:44.301] SLOW QUEUE MSG [Projection Core #1]: CreateAndPrepare - 111ms. Q: 5/11.
[21348,21,12:58:44.301] SLOW BUS MSG [bus]: CreateAndPrepare - 110ms. Handler: ProjectionCoreService.
[21348,21,12:58:44.301] SLOW QUEUE MSG [Projection Core #2]: CreateAndPrepare - 110ms. Q: 4/13.
[21348,19,12:58:44.301] SLOW BUS MSG [bus]: CreateAndPrepare - 110ms. Handler: ProjectionCoreService.
[21348,21,12:58:44.301] PROJECTIONS: Scheduling the writing of $prepared to $projections-$master. Current status of Writer: Busy: True
[21348,19,12:58:44.301] SLOW QUEUE MSG [Projection Core #0]: CreateAndPrepare - 110ms. Q: 7/15.
[21348,19,12:58:44.301] PROJECTIONS: Scheduling the writing of $prepared to $projections-$master. Current status of Writer: Busy: True
[21348,19,12:58:44.301] PROJECTIONS: Scheduling the writing of $prepared to $projections-$master. Current status of Writer: Busy: True
[21348,21,12:58:44.312] PROJECTIONS: Finished writing events to $projections-$master: $prepared
[21348,18,12:58:44.312] PROJECTIONS: Response received: 215@$prepared
[21348,21,12:58:44.312] PROJECTIONS: Finished writing events to $projections-$master: $prepared
[21348,18,12:58:44.312] PROJECTIONS: Response received: 217@$prepared
[21348,19,12:58:44.312] PROJECTIONS: Finished writing events to $projections-$master: $prepared
[21348,19,12:58:44.312] PROJECTIONS: Finished writing events to $projections-$master: $prepared
[21348,19,12:58:44.312] PROJECTIONS: Finished writing events to $projections-$master: $prepared
[21348,18,12:58:44.312] PROJECTIONS: Scheduling the writing of $start to $projections-$2634d850-b2fd-4a51-bedb-7ff0f7c6825a. Current status of Writer: Busy: False
[21348,18,12:58:44.312] PROJECTIONS: Scheduling the writing of $start to $projections-$2634d850-b2fd-4a51-bedb-7ff0f7c6825a. Current status of Writer: Busy: True
[21348,18,12:58:44.312] PROJECTIONS: Response received: 218@$prepared
[21348,18,12:58:44.312] PROJECTIONS: Response received: 220@$prepared
[21348,18,12:58:44.312] PROJECTIONS: Response received: 222@$prepared
[21348,18,12:58:44.312] PROJECTIONS: Scheduling the writing of $load-stopped to $projections-$4a54eb63-b5f8-47bd-876e-9ccc2b025cda. Current status of Writer: Busy: False
[21348,21,12:58:44.312] PROJECTIONS: Command received: 2@$start
[21348,18,12:58:44.312] PROJECTIONS: Scheduling the writing of $load-stopped to $projections-$4a54eb63-b5f8-47bd-876e-9ccc2b025cda. Current status of Writer: Busy: True
[21348,18,12:58:44.312] PROJECTIONS: Scheduling the writing of $start to $projections-$4a54eb63-b5f8-47bd-876e-9ccc2b025cda. Current status of Writer: Busy: True
[21348,18,12:58:44.312] PROJECTIONS: Finished writing events to $projections-$2634d850b2fd4a51bedb7ff0f7c6825a: $start
[21348,18,12:58:44.312] PROJECTIONS: Finished writing events to $projections-$4a54eb63b5f847bd876e9ccc2b025cda: $load-stopped
[21348,19,12:58:44.312] PROJECTIONS: Command received: 3@$load-stopped
[21348,18,12:58:44.312] PROJECTIONS: Finished writing events to $projections-$2634d850b2fd4a51bedb7ff0f7c6825a: $start
[21348,18,12:58:44.327] PROJECTIONS: Finished writing events to $projections-$4a54eb63b5f847bd876e9ccc2b025cda: $load-stopped
[21348,18,12:58:44.327] PROJECTIONS: Finished writing events to $projections-$4a54eb63b5f847bd876e9ccc2b025cda: $start
[21348,19,12:58:44.349] PROJECTIONS: Scheduling the writing of $stopped to $projections-$master. Current status of Writer: Busy: True
[21348,21,12:58:44.349] PROJECTIONS: Command received: 3@$start
[21348,19,12:58:44.349] PROJECTIONS: Command received: 4@$load-stopped
[21348,19,12:58:44.349] PROJECTIONS: Command received: 5@$start
[21348,19,12:58:44.349] PROJECTIONS: Scheduling the writing of $stopped to $projections-$master. Current status of Writer: Busy: True
[21348,18,12:58:44.349] PROJECTIONS: Response received: 227@$stopped
[21348,21,12:58:44.370] PROJECTIONS: Scheduling the writing of $started to $projections-$master. Current status of Writer: Busy: True
[21348,19,12:58:44.374] PROJECTIONS: Finished writing events to $projections-$master: $stopped
[21348,19,12:58:44.374] PROJECTIONS: Scheduling the writing of $started to $projections-$master. Current status of Writer: Busy: True
[21348,18,12:58:44.374] PROJECTIONS: Response received: 233@$stopped
[21348,20,12:58:44.374] SLOW BUS MSG [bus]: CreateAndPrepare - 83ms. Handler: ProjectionCoreService.
[21348,20,12:58:44.374] SLOW QUEUE MSG [Projection Core #1]: CreateAndPrepare - 83ms. Q: 8/12.
[21348,20,12:58:44.374] PROJECTIONS: Scheduling the writing of $prepared to $projections-$master. Current status of Writer: Busy: True
[21348,20,12:58:44.374] PROJECTIONS: Scheduling the writing of $prepared to $projections-$master. Current status of Writer: Busy: True
[21348,20,12:58:44.389] PROJECTIONS: Finished writing events to $projections-$master: $prepared
[21348,20,12:58:44.389] PROJECTIONS: Finished writing events to $projections-$master: $prepared
[21348,18,12:58:44.389] PROJECTIONS: Response received: 236@$prepared
[21348,18,12:58:44.389] PROJECTIONS: Response received: 238@$prepared
[21348,18,12:58:44.389] PROJECTIONS: Scheduling the writing of $load-stopped to $projections-$9b12d405-f91a-4669-8c40-8c17404bbb8b. Current status of Writer: Busy: False
[21348,18,12:58:44.389] PROJECTIONS: Scheduling the writing of $start to $projections-$9b12d405-f91a-4669-8c40-8c17404bbb8b. Current status of Writer: Busy: True
[21348,20,12:58:44.389] PROJECTIONS: Finished writing events to $projections-$master: $prepared
[21348,18,12:58:44.389] PROJECTIONS: Response received: 240@$prepared
[21348,18,12:58:44.389] PROJECTIONS: Scheduling the writing of $start to $projections-$9b12d405-f91a-4669-8c40-8c17404bbb8b. Current status of Writer: Busy: True
[21348,18,12:58:44.389] PROJECTIONS: Finished writing events to $projections-$9b12d405f91a46698c408c17404bbb8b: $load-stopped
[21348,20,12:58:44.389] PROJECTIONS: Command received: 3@$load-stopped
[21348,20,12:58:44.389] PROJECTIONS: Scheduling the writing of $stopped to $projections-$master. Current status of Writer: Busy: True
[21348,18,12:58:44.389] PROJECTIONS: Finished writing events to $projections-$9b12d405f91a46698c408c17404bbb8b: $start
[21348,18,12:58:44.389] PROJECTIONS: Finished writing events to $projections-$9b12d405f91a46698c408c17404bbb8b: $start
[21348,20,12:58:44.389] PROJECTIONS: Command received: 4@$start
[21348,20,12:58:44.389] PROJECTIONS: Command received: 5@$start
[21348,20,12:58:44.389] PROJECTIONS: Scheduling the writing of $started to $projections-$master. Current status of Writer: Busy: True
[21348,20,12:58:44.389] PROJECTIONS: Scheduling the writing of $started to $projections-$master. Current status of Writer: Busy: True
[21348,19,12:58:44.405] PROJECTIONS: Finished writing events to $projections-$master: $stopped
[21348,19,12:58:44.405] PROJECTIONS: Finished writing events to $projections-$master: $started
[21348,18,12:58:44.405] PROJECTIONS: Response received: 242@$started
[21348,18,12:58:44.405] PROJECTIONS: Response received: 245@$started
[21348,18,12:58:44.430] PROJECTIONS: Response received: 250@$stopped
[21348,18,12:58:44.430] PROJECTIONS: Response received: 256@$started
[21348,18,12:58:44.430] PROJECTIONS: Response received: 259@$started
[21348,20,12:58:44.430] PROJECTIONS: Finished writing events to $projections-$master: $stopped
[21348,20,12:58:44.430] PROJECTIONS: Finished writing events to $projections-$master: $started
[21348,20,12:58:44.430] PROJECTIONS: Finished writing events to $projections-$master: $started
[21348,21,12:58:44.448] PROJECTIONS: Finished writing events to $projections-$master: $started
[21348,21,12:58:44.460] PROJECTIONS: Scheduling the writing of $started to $projections-$master. Current status of Writer: Busy: False
[21348,18,12:58:44.460] PROJECTIONS: Response received: 262@$started
[21348,21,12:58:44.460] PROJECTIONS: Finished writing events to $projections-$master: $started
[21348,06,12:58:45.050] SLOW QUEUE MSG [MonitoringQueue]: SystemInit - 1464ms. Q: 0/5.
[21348,06,12:58:45.071] Created stats stream '$stats-127.0.0.1:2113', code = WrongExpectedVersion
[21348,18,12:58:52.195] Enabling '$by_category' projection
Segments count: 1, buffers count: 512, should be when full: 512
[21348,04,12:58:52.478] External TCP connection accepted: [Normal, 127.0.0.1:54479, L127.0.0.1:1113, {75fe7371-9d1c-4dc0-a447-8ef826adf929}].
[21348,04,12:58:52.747] Connection 'external-normal' ({75fe7371-9d1c-4dc0-a447-8ef826adf929}) identified by client. Client connection name: 'ES-e7d8b425-47d9-4cbb-9afe-979a633f1ea7', Client version: V2.
[21348,09,12:58:53.352] create subscription testRun::TestProcessor
[21348,05,12:59:03.076] update subscription testRun::TestProcessor
[21348,15,12:59:03.076] Subscription testRun::TestProcessor: read no checksum.
[21348,15,12:59:03.076] strtfrom = 0
[21348,05,12:59:03.076] Saving Configuration.
[21348,12,12:59:03.120] New connection to persistent subscription TestProcessor.
[21348,16,12:59:03.136] create subscription installatie::UIModels
[21348,23,12:59:05.113] update subscription installatie::UIModels
[21348,23,12:59:05.113] Saving Configuration.
[21348,15,12:59:05.113] Subscription installatie::UIModels: read no checksum.
[21348,15,12:59:05.113] strtfrom = 0
[21348,11,12:59:05.113] New connection to persistent subscription UIModels.
[21348,10,12:59:05.113] create subscription file::UIModels
[21348,08,12:59:07.463] update subscription file::UIModels
[21348,08,12:59:07.463] Saving Configuration.
[21348,15,12:59:07.463] Subscription file::UIModels: read no checksum.
[21348,15,12:59:07.463] strtfrom = 0
[21348,05,12:59:07.466] New connection to persistent subscription UIModels.
[21348,06,12:59:07.466] create subscription testRun::UIModels
[21348,12,12:59:08.861] update subscription testRun::UIModels
[21348,12,12:59:08.861] Saving Configuration.
[21348,15,12:59:08.861] Subscription testRun::UIModels: read no checksum.
[21348,15,12:59:08.861] strtfrom = 0
[21348,12,12:59:08.861] New connection to persistent subscription UIModels.
[21348,06,12:59:08.861] create subscription testSequence::UIModels
[21348,05,12:59:09.442] update subscription testSequence::UIModels
[21348,05,12:59:09.442] Saving Configuration.
[21348,15,12:59:09.442] Subscription testSequence::UIModels: read no checksum.
[21348,15,12:59:09.442] strtfrom = 0
[21348,07,12:59:09.442] New connection to persistent subscription UIModels.
[21348,05,12:59:09.453] create subscription file::TestModels
[21348,16,12:59:10.020] update subscription file::TestModels
[21348,16,12:59:10.020] Saving Configuration.
[21348,15,12:59:10.020] Subscription file::TestModels: read no checksum.
[21348,15,12:59:10.020] strtfrom = 0
[21348,09,12:59:10.020] New connection to persistent subscription TestModels.
[21348,23,12:59:10.020] create subscription file::FileProcessor
[21348,06,12:59:10.748] update subscription file::FileProcessor
[21348,06,12:59:10.748] Saving Configuration.
[21348,15,12:59:10.748] Subscription file::FileProcessor: read no checksum.
[21348,15,12:59:10.748] strtfrom = 0
[21348,05,12:59:10.748] New connection to persistent subscription FileProcessor.
[21348,26,12:59:20.263] External TCP connection accepted: [Normal, 127.0.0.1:54526, L127.0.0.1:1113, {8d07f292-bbff-4082-afa1-0908f77b1c98}].
[21348,26,12:59:20.263] Connection 'external-normal' ({8d07f292-bbff-4082-afa1-0908f77b1c98}) identified by client. Client connection name: 'ES-eec89d4d-fc43-40be-bad9-13c828d56c73', Client version: V2.
[21348,24,12:59:20.380] ES TcpConnection closed [12:59:20.363: N127.0.0.1:54526, L127.0.0.1:1113, {8d07f292-bbff-4082-afa1-0908f77b1c98}]:Received bytes: 526, Sent bytes: 62
[21348,24,12:59:20.380] ES TcpConnection closed [12:59:20.380: N127.0.0.1:54526, L127.0.0.1:1113, {8d07f292-bbff-4082-afa1-0908f77b1c98}]:Send calls: 2, callbacks: 2
[21348,24,12:59:20.380] ES TcpConnection closed [12:59:20.380: N127.0.0.1:54526, L127.0.0.1:1113, {8d07f292-bbff-4082-afa1-0908f77b1c98}]:Receive calls: 3, callbacks: 3
[21348,24,12:59:20.380] ES TcpConnection closed [12:59:20.380: N127.0.0.1:54526, L127.0.0.1:1113, {8d07f292-bbff-4082-afa1-0908f77b1c98}]:Close reason: [Success] Socket closed
[21348,24,12:59:20.380] Connection 'external-normal:ES-eec89d4d-fc43-40be-bad9-13c828d56c73' [127.0.0.1:54526, {8d07f292-bbff-4082-afa1-0908f77b1c98}] closed: Success.
[21348,16,12:59:20.380] Lost connection from 127.0.0.1:54526