2018-06-22T12:09:16.7548486Z, 68, Information, Server, Raven.Server.RavenServerStartup, GET /topology??name=data-prod - 200 - 0 ms 2018-06-22T12:09:17.5756768Z, 68, Information, data-prod, Raven.Server.Documents.Indexes.Persistence.Lucene.IndexReadOperation, Issuing query on index CompanyFullIndex for: [My Query] 2018-06-22T12:09:23.0724800Z, 14, Information, Server, Raven.Server.RavenServerStartup, GET /databases/data-prod/configuration/client? - 200 - 0 ms 2018-06-22T12:10:27.4814165Z, 14, Information, Server, Raven.Server.RavenServerStartup, GET /topology??name=data-prod - 200 - 0 ms 2018-06-22T12:12:46.3253810Z, 25, Information, data-prod, Raven.Server.Documents.TransactionOperationsMerger, Merged 1 operations in 00:00:00.0000097 with 0 operations remaining. Status: HasMore 2018-06-22T12:12:46.3254238Z, 25, Information, data-prod, Raven.Server.Documents.TransactionOperationsMerger, BeginAsyncCommit on 768297 with 0 additional operations pending 2018-06-22T12:12:46.3254545Z, 25, Information, data-prod, Raven.Server.Documents.TransactionOperationsMerger, Merged 0 operations in 00:00:00.0000008 (async) with 0 operations remaining. Status: CompletedAll 2018-06-22T12:12:46.3254661Z, 25, Information, data-prod, Raven.Server.Documents.TransactionOperationsMerger, EndAsyncCommit on 768297 2018-06-22T12:12:46.3256913Z, 20, Information, data-subset, Raven.Server.Documents.TransactionOperationsMerger, Merged 1 operations in 00:00:00.0000149 with 0 operations remaining. Status: HasMore 2018-06-22T12:12:46.3257283Z, 20, Information, data-subset, Raven.Server.Documents.TransactionOperationsMerger, BeginAsyncCommit on 28114 with 0 additional operations pending 2018-06-22T12:12:46.3257418Z, 20, Information, data-subset, Raven.Server.Documents.TransactionOperationsMerger, Merged 0 operations in 00:00:00.0000004 (async) with 0 operations remaining. Status: CompletedAll 2018-06-22T12:12:46.3257485Z, 20, Information, data-subset, Raven.Server.Documents.TransactionOperationsMerger, EndAsyncCommit on 28114 2018-06-22T12:15:00.1331732Z, 3, Information, Configuration, Voron.Impl.Journal.WriteAheadJournal, Preparing to write tx 290 to journal with 2 pages (16 kb) in 00:00:00.0000281 with 20 kb compressed. 2018-06-22T12:15:00.1380160Z, 3, Information, Configuration, Voron.Impl.Journal.WriteAheadJournal, Writing 20 kb to journal 0000000000000000055 took 00:00:00.0047868 2018-06-22T12:15:00.1381254Z, 5, Information, Configuration, Voron.Impl.Journal.WriteAheadJournal, Flushed 2 pages to E:\Raven\Databases\data-subset\Configuration\Raven.voron with 16 kb in 00:00:00.0000189 2018-06-22T12:15:00.1382008Z, 5, Information, Configuration, Voron.Impl.Journal.WriteAheadJournal, Updated journal state under write tx lock after waiting for 00:00:00.0000456 2018-06-22T12:15:00.1599130Z, 4, Information, data-subset, Raven.Server.Documents.PeriodicBackup.PeriodicBackupRunner, Creating an incremental backup 2018-06-22T12:15:00.6096929Z, 4, Information, data-subset, Raven.Server.NotificationCenter.NotificationsStorage, Deleting notification 'OperationChanged/60'. 2018-06-22T12:15:00.6117949Z, 4, Information, Configuration, Voron.Impl.Journal.WriteAheadJournal, Preparing to write tx 291 to journal with 7 pages (56 kb) in 00:00:00.0001599 with 60 kb compressed. 2018-06-22T12:15:00.6322135Z, 4, Information, Configuration, Voron.Impl.Journal.WriteAheadJournal, New journal file created 0000000000000000056 2018-06-22T12:15:00.6371496Z, 4, Information, Configuration, Voron.Impl.Journal.WriteAheadJournal, Writing 60 kb to journal 0000000000000000056 took 00:00:00.0048921 2018-06-22T12:15:00.6376347Z, 4, Information, data-subset, Raven.Server.Documents.PeriodicBackup.PeriodicBackupRunner, Successfully created an incremental backup in 477 ms 2018-06-22T12:15:00.6558767Z, 4, Information, System, Voron.Impl.Journal.WriteAheadJournal, Preparing to write tx 386258 to journal with 5 pages (40 kb) in 00:00:00.0000398 with 4 kb compressed. 2018-06-22T12:15:00.6564037Z, 4, Information, System, Voron.Impl.Journal.WriteAheadJournal, Writing 4 kb to journal 0000000000000003044 took 00:00:00.0005027 2018-06-22T12:15:00.6908007Z, 4, Information, System, Voron.Impl.Journal.WriteAheadJournal, Flushed 9 pages to D:\Raven\System\Raven.voron with 72 kb in 00:00:00.0000616 2018-06-22T12:15:00.6930921Z, 13, Information, System, Voron.Impl.Journal.WriteAheadJournal, Preparing to write tx 386259 to journal with 3 pages (24 kb) in 00:00:00.0000206 with 4 kb compressed. 2018-06-22T12:15:00.6935344Z, 13, Information, System, Voron.Impl.Journal.WriteAheadJournal, Writing 4 kb to journal 0000000000000003044 took 00:00:00.0004109 2018-06-22T12:15:00.6935725Z, 13, Information, System, Voron.Impl.Journal.WriteAheadJournal, Updated journal state under write tx lock after waiting for 00:00:00.0027479 2018-06-22T12:15:00.6944328Z, 13, Information, System, Voron.Impl.Journal.WriteAheadJournal, Preparing to write tx 386260 to journal with 6 pages (48 kb) in 00:00:00.0000220 with 4 kb compressed. 2018-06-22T12:15:00.6957862Z, 5, Information, data-subset, Raven.Server.Documents.PeriodicBackup.PeriodicBackupRunner, Periodic backup status with task id 2956 was updated 2018-06-22T12:15:00.7001427Z, 13, Information, System, Voron.Impl.Journal.WriteAheadJournal, Writing 4 kb to journal 0000000000000003044 took 00:00:00.0056921 2018-06-22T12:15:00.7006319Z, 5, Information, data-subset, Raven.Server.Documents.PeriodicBackup.PeriodicBackupRunner, Next full backup is in 714.988324698333 minutes 2018-06-22T12:15:00.7024033Z, 4, Information, data-subset, Raven.Server.NotificationCenter.NotificationsStorage, Saving notification 'OperationChanged/61'. 2018-06-22T12:15:00.7167736Z, 4, Information, Configuration, Voron.Impl.Journal.WriteAheadJournal, Preparing to write tx 292 to journal with 7 pages (56 kb) in 00:00:00.0000247 with 60 kb compressed. 2018-06-22T12:15:00.7217727Z, 4, Information, Configuration, Voron.Impl.Journal.WriteAheadJournal, Writing 60 kb to journal 0000000000000000056 took 00:00:00.0049759 2018-06-22T12:15:27.5012092Z, 60, Information, Server, Raven.Server.RavenServerStartup, GET /topology??name=data-prod - 200 - 0 ms 2018-06-22T12:15:30.7304919Z, 34, Information, System, Voron.Impl.Journal.WriteAheadJournal, Flushed 7 pages to D:\Raven\System\Raven.voron with 56 kb in 00:00:00.0000283 2018-06-22T12:15:30.7306758Z, 34, Information, System, Voron.Impl.Journal.WriteAheadJournal, Updated journal state under write tx lock after waiting for 00:00:00.0001522 2018-06-22T12:15:34.4806652Z, 5, Information, Configuration, Voron.Impl.Journal.WriteAheadJournal, Flushed 7 pages to E:\Raven\Databases\data-subset\Configuration\Raven.voron with 56 kb in 00:00:00.0000633 2018-06-22T12:15:34.4808982Z, 5, Information, Configuration, Voron.Impl.Journal.WriteAheadJournal, Updated journal state under write tx lock after waiting for 00:00:00.0001241 2018-06-22T12:15:49.4806400Z, 11, Information, Global Flusher, Voron.GlobalFlushingBehavior, Starting desired sync with 7 items to sync after 14,999 ms with no activity 2018-06-22T12:15:49.4836156Z, 45, Information, System, Voron.Impl.Journal.WriteAheadJournal, Sync of 16,384 kb file with 128 kb dirty in 00:00:00.0013574 2018-06-22T12:15:49.5076546Z, 3, Information, Configuration, Voron.Impl.Journal.WriteAheadJournal, Sync of 8,192 kb file with 72 kb dirty in 00:00:00.0120924 2018-06-22T12:15:58.1067319Z, 10, Information, Server, Raven.Server.RavenServerStartup, POST /databases/data-prod/streams/queries? - 200 - 1,590,934 ms 2018-06-22T12:17:46.3265623Z, 25, Information, data-prod, Raven.Server.Documents.TransactionOperationsMerger, Merged 1 operations in 00:00:00.0000136 with 0 operations remaining. Status: HasMore 2018-06-22T12:17:46.3265877Z, 25, Information, data-prod, Raven.Server.Documents.TransactionOperationsMerger, BeginAsyncCommit on 768297 with 0 additional operations pending 2018-06-22T12:17:46.3266020Z, 25, Information, data-prod, Raven.Server.Documents.TransactionOperationsMerger, Merged 0 operations in 00:00:00.0000005 (async) with 0 operations remaining. Status: CompletedAll 2018-06-22T12:17:46.3266107Z, 25, Information, data-prod, Raven.Server.Documents.TransactionOperationsMerger, EndAsyncCommit on 768297 2018-06-22T12:17:46.3265675Z, 20, Information, data-subset, Raven.Server.Documents.TransactionOperationsMerger, Merged 1 operations in 00:00:00.0000867 with 0 operations remaining. Status: HasMore 2018-06-22T12:17:46.3265881Z, 20, Information, data-subset, Raven.Server.Documents.TransactionOperationsMerger, BeginAsyncCommit on 28114 with 0 additional operations pending 2018-06-22T12:17:46.3274668Z, 34, Information, data-subset, Voron.Impl.Journal.WriteAheadJournal, Preparing to write tx 28114 to journal with 2 pages (16 kb) in 00:00:00.0000370 with 20 kb compressed. 2018-06-22T12:17:46.3351405Z, 34, Information, data-subset, Voron.Impl.Journal.WriteAheadJournal, Writing 20 kb to journal 0000000000000000065 took 00:00:00.0076547 2018-06-22T12:17:46.3356777Z, 20, Information, data-subset, Raven.Server.Documents.TransactionOperationsMerger, Merged 0 operations in 00:00:00.0083836 (async) with 0 operations remaining. Status: CompletedAll 2018-06-22T12:17:46.3357077Z, 20, Information, data-subset, Raven.Server.Documents.TransactionOperationsMerger, EndAsyncCommit on 28114 2018-06-22T12:17:46.3366820Z, 3, Information, data-subset, Voron.Impl.Journal.WriteAheadJournal, Flushed 2 pages to E:\Raven\Databases\data-subset\Raven.voron with 16 kb in 00:00:00.0000160 2018-06-22T12:17:46.3367888Z, 3, Information, data-subset, Voron.Impl.Journal.WriteAheadJournal, Updated journal state under write tx lock after waiting for 00:00:00.0000889 2018-06-22T12:18:01.3263259Z, 11, Information, Global Flusher, Voron.GlobalFlushingBehavior, Starting desired sync with 2 items to sync after 15,000 ms with no activity 2018-06-22T12:18:01.5083040Z, 3, Information, data-subset, Voron.Impl.Journal.WriteAheadJournal, Sync of 3,145,728 kb file with 16 kb dirty in 00:00:00.0101256 2018-06-22T12:18:16.3422252Z, 11, Information, Global Flusher, Voron.GlobalFlushingBehavior, Starting desired sync with 1 items to sync after 15,000 ms with no activity 2018-06-22T12:19:17.4424892Z, 60, Information, Server, Raven.Server.RavenServerStartup, GET /topology??name=data-prod - 200 - 0 ms 2018-06-22T12:20:27.5170192Z, 69, Information, Server, Raven.Server.RavenServerStartup, GET /topology??name=data-prod - 200 - 0 ms