Skip to content

Instantly share code, notes, and snippets.

@phinett

phinett/replicationerror.log

Created July 30, 2014 15:08
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save phinett/9957d06c1d15fbc3aaf3 to your computer and use it in GitHub Desktop.
Save phinett/9957d06c1d15fbc3aaf3 to your computer and use it in GitHub Desktop.
Download ZIP
ravendb log file
Raw
replicationerror.log
2014-07-30 16:06:37.1202,Raven.Database.Indexing.WorkContext,Debug,,Incremented work counter to 76257 because: WORK BY IndexingExecuter,
2014-07-30 16:06:37.1202,Raven.Database.Storage.Voron.StorageActions.DocumentsStorageActions,Debug,housemixes,Document with key='Raven/Replication/Destinations' was not found,
2014-07-30 16:06:37.1202,Raven.Database.Storage.Voron.StorageActions.DocumentsStorageActions,Debug,housemixes,Document with key='Raven/Replication/Destinations' was not found,
2014-07-30 16:06:37.1202,Raven.Database.Indexing.WorkContext,Debug,housemixes,"No work was found, workerWorkCounter: 76257, for: ReplicationTask, will wait for additional work",
2014-07-30 16:06:37.1202,Raven.Database.Server.WebApi.RequestManager,Debug,housemixes,"Request #516,118: POST - 3,371 ms - housemixes - 500 - http://46.28.51.84:9090/databases/housemixes/replication/replicateDocs?from=http:%2F%2Fmp443887:8081%2Fdatabases%2Fhousemixes&dbid=91e12dde-b14c-41e2-819b-3486932e42f1",
2014-07-30 16:06:37.1202,Raven.Database.Indexing.WorkContext,Debug,,"No work was found, workerWorkCounter: 76257, for: ReducingExecuter, will wait for additional work",
2014-07-30 16:06:39.2453,Raven.Database.Server.WebApi.RequestManager,Debug,housemixes,"Request #516,119: GET - 451 ms - housemixes - 200 - http://localhost:9090/databases/housemixes/stats?_=1406631997861",
2014-07-30 16:06:39.9174,Raven.Database.Storage.Voron.StorageActions.DocumentsStorageActions,Debug,,DocumentByKey() by key ='Raven/Databases/housemixes',
2014-07-30 16:06:39.9654,Raven.Database.Storage.Voron.StorageActions.DocumentsStorageActions,Debug,housemixes,DocumentByKey() by key ='Raven/Replication/Sources/http://mp443887:8081/databases/housemixes',
2014-07-30 16:06:39.9654,Raven.Database.Server.Controllers.RavenBaseApiController,Debug,housemixes,Got replication last etag request from http://mp443887:8081/databases/housemixes: [Local: 01000000-0000-0675-0000-000006149686 Remote: 01000000-0000-0677-0000-0000009F17DD],
2014-07-30 16:06:39.9654,Raven.Database.Server.WebApi.RequestManager,Debug,housemixes,"Request #516,120: GET - 0 ms - housemixes - 200 - http://46.28.51.84:9090/databases/housemixes/replication/lastEtag?from=http:%2F%2Fmp443887:8081%2Fdatabases%2Fhousemixes&currentEtag=01000000-0000-0677-0000-0000009F17DD&dbid=91e12dde-b14c-41e2-819b-3486932e42f1",
2014-07-30 16:06:40.3874,Raven.Database.Storage.Voron.StorageActions.DocumentsStorageActions,Debug,,DocumentByKey() by key ='Raven/Databases/housemixes',
2014-07-30 16:06:40.8714,Raven.Database.Server.WebApi.RequestManager,Debug,housemixes,"Request #516,121: POST - 429 ms - housemixes - 500 - http://46.28.51.84:9090/databases/housemixes/replication/replicateDocs?from=http:%2F%2Fmp443887:8081%2Fdatabases%2Fhousemixes&dbid=91e12dde-b14c-41e2-819b-3486932e42f1",
2014-07-30 16:06:43.6026,Raven.Database.Storage.Voron.StorageActions.DocumentsStorageActions,Debug,,DocumentByKey() by key ='Raven/Databases/housemixes',
2014-07-30 16:06:43.6606,Raven.Database.Storage.Voron.StorageActions.DocumentsStorageActions,Debug,housemixes,DocumentByKey() by key ='Raven/Replication/Sources/http://mp443887:8081/databases/housemixes',
2014-07-30 16:06:43.6606,Raven.Database.Server.Controllers.RavenBaseApiController,Debug,housemixes,Got replication last etag request from http://mp443887:8081/databases/housemixes: [Local: 01000000-0000-0675-0000-000006149686 Remote: 01000000-0000-0677-0000-0000009F17E0],
2014-07-30 16:06:43.6606,Raven.Database.Server.WebApi.RequestManager,Debug,housemixes,"Request #516,122: GET - 0 ms - housemixes - 200 - http://46.28.51.84:9090/databases/housemixes/replication/lastEtag?from=http:%2F%2Fmp443887:8081%2Fdatabases%2Fhousemixes&currentEtag=01000000-0000-0677-0000-0000009F17E0&dbid=91e12dde-b14c-41e2-819b-3486932e42f1",
2014-07-30 16:06:44.1006,Raven.Database.Storage.Voron.StorageActions.DocumentsStorageActions,Debug,,DocumentByKey() by key ='Raven/Databases/housemixes',
2014-07-30 16:06:44.1866,Raven.Database.Server.WebApi.RequestManager,Debug,housemixes,"Request #516,123: POST - 26 ms - housemixes - 500 - http://46.28.51.84:9090/databases/housemixes/replication/replicateDocs?from=http:%2F%2Fmp443887:8081%2Fdatabases%2Fhousemixes&dbid=91e12dde-b14c-41e2-819b-3486932e42f1",
2014-07-30 16:06:45.4137,Raven.Database.Storage.Voron.StorageActions.DocumentsStorageActions,Debug,,DocumentByKey() by key ='Raven/Databases/housemixes',
2014-07-30 16:06:45.5077,Raven.Database.Storage.Voron.StorageActions.DocumentsStorageActions,Debug,housemixes,DocumentByKey() by key ='Raven/Replication/Sources/http://mp443887:8081/databases/housemixes',
2014-07-30 16:06:45.5077,Raven.Database.Server.Controllers.RavenBaseApiController,Debug,housemixes,Got replication last etag request from http://mp443887:8081/databases/housemixes: [Local: 01000000-0000-0675-0000-000006149686 Remote: 01000000-0000-0677-0000-0000009F17E2],
2014-07-30 16:06:45.5077,Raven.Database.Server.WebApi.RequestManager,Debug,housemixes,"Request #516,124: GET - 0 ms - housemixes - 200 - http://46.28.51.84:9090/databases/housemixes/replication/lastEtag?from=http:%2F%2Fmp443887:8081%2Fdatabases%2Fhousemixes&currentEtag=01000000-0000-0677-0000-0000009F17E2&dbid=91e12dde-b14c-41e2-819b-3486932e42f1",
2014-07-30 16:06:46.0157,Raven.Database.Storage.Voron.StorageActions.DocumentsStorageActions,Debug,,DocumentByKey() by key ='Raven/Databases/housemixes',
2014-07-30 16:06:46.1347,Raven.Database.Server.WebApi.RequestManager,Debug,housemixes,"Request #516,125: POST - 34 ms - housemixes - 500 - http://46.28.51.84:9090/databases/housemixes/replication/replicateDocs?from=http:%2F%2Fmp443887:8081%2Fdatabases%2Fhousemixes&dbid=91e12dde-b14c-41e2-819b-3486932e42f1",
2014-07-30 16:06:48.3588,Raven.Database.Storage.Voron.StorageActions.DocumentsStorageActions,Debug,,DocumentByKey() by key ='Raven/Databases/housemixes',
2014-07-30 16:06:48.4548,Raven.Database.Storage.Voron.StorageActions.DocumentsStorageActions,Debug,housemixes,DocumentByKey() by key ='Raven/Replication/Sources/http://mp443887:8081/databases/housemixes',
2014-07-30 16:06:48.4548,Raven.Database.Server.Controllers.RavenBaseApiController,Debug,housemixes,Got replication last etag request from http://mp443887:8081/databases/housemixes: [Local: 01000000-0000-0675-0000-000006149686 Remote: 01000000-0000-0677-0000-0000009F17E8],
2014-07-30 16:06:48.4548,Raven.Database.Server.WebApi.RequestManager,Debug,housemixes,"Request #516,126: GET - 0 ms - housemixes - 200 - http://46.28.51.84:9090/databases/housemixes/replication/lastEtag?from=http:%2F%2Fmp443887:8081%2Fdatabases%2Fhousemixes&currentEtag=01000000-0000-0677-0000-0000009F17E8&dbid=91e12dde-b14c-41e2-819b-3486932e42f1",
2014-07-30 16:06:49.3579,Raven.Database.Storage.Voron.StorageActions.DocumentsStorageActions,Debug,,DocumentByKey() by key ='Raven/Databases/housemixes',
2014-07-30 16:06:49.7999,Raven.Database.Server.WebApi.RequestManager,Debug,housemixes,"Request #516,127: POST - 361 ms - housemixes - 500 - http://46.28.51.84:9090/databases/housemixes/replication/replicateDocs?from=http:%2F%2Fmp443887:8081%2Fdatabases%2Fhousemixes&dbid=91e12dde-b14c-41e2-819b-3486932e42f1",
2014-07-30 16:06:50.2289,Raven.Database.Storage.Voron.StorageActions.DocumentsStorageActions,Debug,,DocumentByKey() by key ='Raven/Databases/housemixes',
2014-07-30 16:06:50.3549,Raven.Database.Storage.Voron.StorageActions.DocumentsStorageActions,Debug,housemixes,DocumentByKey() by key ='Raven/Replication/Sources/http://mp443887:8081/databases/housemixes',
2014-07-30 16:06:50.3549,Raven.Database.Server.Controllers.RavenBaseApiController,Debug,housemixes,Got replication last etag request from http://mp443887:8081/databases/housemixes: [Local: 01000000-0000-0675-0000-000006149686 Remote: 01000000-0000-0677-0000-0000009F17F2],
2014-07-30 16:06:50.3549,Raven.Database.Server.WebApi.RequestManager,Debug,housemixes,"Request #516,128: GET - 0 ms - housemixes - 200 - http://46.28.51.84:9090/databases/housemixes/replication/lastEtag?from=http:%2F%2Fmp443887:8081%2Fdatabases%2Fhousemixes&currentEtag=01000000-0000-0677-0000-0000009F17F2&dbid=91e12dde-b14c-41e2-819b-3486932e42f1",
2014-07-30 16:06:51.9750,Raven.Database.Storage.Voron.StorageActions.DocumentsStorageActions,Debug,,DocumentByKey() by key ='accounts-559119',
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,,Found a total of 1 documents that requires indexing since etag: 01000000-0000-0002-0000-0000002218C5: (accounts-559119),
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,,"After read triggers executed, 1 documents remained",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,housemixes,"All documents have been filtered for 12, no indexing will be performed, updating to 01000000-0000-0002-0000-0000002218C6, 07/24/2014 18:07:12",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,housemixes,"Going to index 1 documents in IndexId: 15, LastIndexedEtag: 01000000-0000-0002-0000-0000002218C5: (accounts-559119)",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,housemixes,"All documents have been filtered for 19, no indexing will be performed, updating to 01000000-0000-0002-0000-0000002218C6, 07/24/2014 18:07:12",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,housemixes,"All documents have been filtered for 20, no indexing will be performed, updating to 01000000-0000-0002-0000-0000002218C6, 07/24/2014 18:07:12",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,housemixes,"All documents have been filtered for 13, no indexing will be performed, updating to 01000000-0000-0002-0000-0000002218C6, 07/24/2014 18:07:12",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,housemixes,"All documents have been filtered for 16, no indexing will be performed, updating to 01000000-0000-0002-0000-0000002218C6, 07/24/2014 18:07:12",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,housemixes,"All documents have been filtered for 14, no indexing will be performed, updating to 01000000-0000-0002-0000-0000002218C6, 07/24/2014 18:07:12",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,housemixes,"All documents have been filtered for 17, no indexing will be performed, updating to 01000000-0000-0002-0000-0000002218C6, 07/24/2014 18:07:12",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,housemixes,"Going to index 1 documents in IndexId: 22, LastIndexedEtag: 01000000-0000-0002-0000-0000002218C5: (accounts-559119)",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,housemixes,"Going to index 1 documents in IndexId: 23, LastIndexedEtag: 01000000-0000-0002-0000-0000002218C5: (accounts-559119)",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,housemixes,"All documents have been filtered for 25, no indexing will be performed, updating to 01000000-0000-0002-0000-0000002218C6, 07/24/2014 18:07:12",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,housemixes,"All documents have been filtered for 31, no indexing will be performed, updating to 01000000-0000-0002-0000-0000002218C6, 07/24/2014 18:07:12",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,housemixes,"Going to index 1 documents in IndexId: 21, LastIndexedEtag: 01000000-0000-0002-0000-0000002218C5: (accounts-559119)",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,housemixes,"All documents have been filtered for 24, no indexing will be performed, updating to 01000000-0000-0002-0000-0000002218C6, 07/24/2014 18:07:12",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,housemixes,"Going to index 1 documents in IndexId: 3, LastIndexedEtag: 01000000-0000-0002-0000-0000002218C5: (accounts-559119)",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,housemixes,"All documents have been filtered for 30, no indexing will be performed, updating to 01000000-0000-0002-0000-0000002218C6, 07/24/2014 18:07:12",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,housemixes,"All documents have been filtered for 33, no indexing will be performed, updating to 01000000-0000-0002-0000-0000002218C6, 07/24/2014 18:07:12",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,housemixes,"All documents have been filtered for 32, no indexing will be performed, updating to 01000000-0000-0002-0000-0000002218C6, 07/24/2014 18:07:12",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,housemixes,"All documents have been filtered for 9, no indexing will be performed, updating to 01000000-0000-0002-0000-0000002218C6, 07/24/2014 18:07:12",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,housemixes,"All documents have been filtered for 6, no indexing will be performed, updating to 01000000-0000-0002-0000-0000002218C6, 07/24/2014 18:07:12",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,housemixes,"All documents have been filtered for 4, no indexing will be performed, updating to 01000000-0000-0002-0000-0000002218C6, 07/24/2014 18:07:12",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,housemixes,"Going to index 1 documents in IndexId: 5, LastIndexedEtag: 01000000-0000-0002-0000-0000002218C5: (accounts-559119)",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,,Indexing 1 documents for index: Image/Index. (accounts-559119),
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,,Indexing 1 documents for index: Raven/ConflictDocuments. (accounts-559119),
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,,Indexing 1 documents for index: Raven/DocumentsByEntityName. (accounts-559119),
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,,Indexing 1 documents for index: Raven/DocumentsByExpirationDate. (accounts-559119),
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,,Indexing 1 documents for index: AccountSearch/Index. (accounts-559119),
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,,Indexing 1 documents for index: Account/Index. (accounts-559119),
2014-07-30 16:06:51.9750,Raven.Database.Indexing.Index.Indexing,Debug,,Scheduled to touch documents: ,
2014-07-30 16:06:51.9750,Raven.Database.Indexing.Index.Indexing,Debug,,"Indexing on accounts-559119 result in index 22 gave document: __document_id IS: accounts-559119
Tag I-: Accounts
LastModified I-: 2014-07-24T18:07:12.1321535Z
",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.Index.Indexing,Debug,,"Indexing on accounts-559119 result in index 5 gave document: __document_id IS: accounts-559119
Query_IsArray I-: true
Query I-: cherryto4
Query I-: stefan
AdminQuery_IsArray I-: true
AdminQuery I-: cherryto4
AdminQuery I-: stefan
AdminQuery I-: cherryto4@abv.bg
AccountType I-: MusicFan
AccountStatus I-: Active
DateJoined I-: 2013-11-25T11:50:43.7437283Z
SignupStatus I-: Verified
",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.Index.Indexing,Debug,,Scheduled to touch documents: ,
2014-07-30 16:06:51.9750,Raven.Database.Indexing.Index.Indexing,Debug,,"Indexing on accounts-559119 result in index 3 gave document: __document_id IS: accounts-559119
Id I-: accounts-559119
ArtistName I-: cherryto4
Fullname I-: stefan
EMail I-: cherryto4@abv.bg
AccountStatus I-: Active
AccountType I-: MusicFan
NhibernateAccountId I-: 0
NhibernateAccountId_Range I-: 0
OptInToNewsletter I-: true
DateJoined I-: 2013-11-25T11:50:43.7437283Z
",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.Index.Indexing,Debug,,Scheduled to touch documents: ,
2014-07-30 16:06:51.9750,Raven.Database.Indexing.Index.Indexing,Debug,,Indexed 0 documents for 21,
2014-07-30 16:06:51.9750,Raven.Database.Indexing.Index.Indexing,Debug,,"Indexing on accounts-559119 result in index 15 gave document: __document_id IS: accounts-559119
DocumentId IS: accounts-559119
DocumentType IS: Account
ArtistName IS: cherryto4
Filename IS: EMPTY_STRING
Status IS: Inactive
Location IS: NotSet
UploadedToCloud IS: false
",
2014-07-30 16:06:51.9750,Raven.Database.Indexing.Index.Indexing,Debug,,Scheduled to touch documents: ,
2014-07-30 16:06:51.9750,Raven.Database.Indexing.Index.Indexing,Debug,,Scheduled to touch documents: ,
2014-07-30 16:06:51.9750,Raven.Database.Indexing.Index.Indexing,Debug,,Scheduled to touch documents: ,
2014-07-30 16:06:51.9750,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,,"After indexing 1 documents, the new last etag for is: 01000000-0000-0002-0000-0000002218C6 for 21",
2014-07-30 16:06:51.9930,Raven.Database.Indexing.Index.Indexing,Debug,,Indexed 0 documents for 23,
2014-07-30 16:06:51.9930,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,,"After indexing 1 documents, the new last etag for is: 01000000-0000-0002-0000-0000002218C6 for 23",
2014-07-30 16:06:51.9930,Raven.Database.Indexing.Index.Indexing,Debug,,Indexed 1 documents for 5,
2014-07-30 16:06:51.9930,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,,"After indexing 1 documents, the new last etag for is: 01000000-0000-0002-0000-0000002218C6 for 5",
2014-07-30 16:06:51.9930,Raven.Database.Indexing.Index.Indexing,Debug,,Indexed 1 documents for 3,
2014-07-30 16:06:51.9930,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,,"After indexing 1 documents, the new last etag for is: 01000000-0000-0002-0000-0000002218C6 for 3",
2014-07-30 16:06:51.9930,Raven.Database.Indexing.Index.Indexing,Debug,,Indexed 1 documents for 15,
2014-07-30 16:06:51.9930,Raven.Database.Indexing.AbstractIndexingExecuter,Debug,,"After indexing 1 documents, the new last etag for is: 01000000-0000-0002-0000-0000002218C6 for 15",
2014-07-30 16:06:52.0080,Raven.Database.Server.WebApi.RequestManager,Debug,housemixes,"Request #516,129: GET - 12 ms - housemixes - 200 - http://localhost:9090/databases/housemixes/stats?_=1406631997862",
2014-07-30 16:06:52.4181,Raven.Database.Storage.Voron.StorageActions.DocumentsStorageActions,Debug,,DocumentByKey() by key ='Raven/Databases/housemixes',
2014-07-30 16:06:52.8671,Raven.Database.Server.WebApi.RequestManager,Debug,housemixes,"Request #516,130: POST - 26 ms - housemixes - 500 - http://46.28.51.84:9090/databases/housemixes/replication/replicateDocs?from=http:%2F%2Fmp443887:8081%2Fdatabases%2Fhousemixes&dbid=91e12dde-b14c-41e2-819b-3486932e42f1",
2014-07-30 16:06:53.1561,Raven.Database.Storage.Voron.StorageActions.DocumentsStorageActions,Debug,,DocumentByKey() by key ='Raven/Databases/housemixes',
2014-07-30 16:06:53.2091,Raven.Database.Storage.Voron.StorageActions.DocumentsStorageActions,Debug,housemixes,DocumentByKey() by key ='Raven/Replication/Sources/http://mp443887:8081/databases/housemixes',
2014-07-30 16:06:53.2091,Raven.Database.Server.Controllers.RavenBaseApiController,Debug,housemixes,Got replication last etag request from http://mp443887:8081/databases/housemixes: [Local: 01000000-0000-0675-0000-000006149686 Remote: 01000000-0000-0677-0000-0000009F17F7],
2014-07-30 16:06:53.2091,Raven.Database.Server.WebApi.RequestManager,Debug,housemixes,"Request #516,131: GET - 0 ms - housemixes - 200 - http://46.28.51.84:9090/databases/housemixes/replication/lastEtag?from=http:%2F%2Fmp443887:8081%2Fdatabases%2Fhousemixes&currentEtag=01000000-0000-0677-0000-0000009F17F7&dbid=91e12dde-b14c-41e2-819b-3486932e42f1",
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment