public StartRecording ( string name ) : ReplicationStatisticsRecorderScope | ||
name | string | |
return | ReplicationStatisticsRecorderScope |
private bool? ReplicateDocuments(ReplicationStrategy destination, SourceReplicationInformationWithBatchInformation destinationsReplicationInformationForSource, ReplicationStatisticsRecorder.ReplicationStatisticsRecorderScope recorder, out int replicatedDocuments) { replicatedDocuments = 0; JsonDocumentsToReplicate documentsToReplicate = null; var sp = Stopwatch.StartNew(); IDisposable removeBatch = null; var prefetchingBehavior = prefetchingBehaviors.GetOrAdd(destination.ConnectionStringOptions.Url, x => docDb.Prefetcher.CreatePrefetchingBehavior(PrefetchingUser.Replicator, autoTuner, string.Format("Replication for URL: {0}" ,destination.ConnectionStringOptions.DefaultDatabase) )); prefetchingBehavior.AdditionalInfo = string.Format("For destination: {0}. Last replicated etag: {1}", destination.ConnectionStringOptions.Url, destinationsReplicationInformationForSource.LastDocumentEtag); try { using (var scope = recorder.StartRecording("Get")) { documentsToReplicate = GetJsonDocuments(destinationsReplicationInformationForSource, destination, prefetchingBehavior, scope); if (documentsToReplicate.Documents == null || documentsToReplicate.Documents.Length == 0) { if (documentsToReplicate.LastEtag != destinationsReplicationInformationForSource.LastDocumentEtag) { // we don't notify remote server about updates to system docs, see: RavenDB-715 if (documentsToReplicate.CountOfFilteredDocumentsWhichAreSystemDocuments == 0 || documentsToReplicate.CountOfFilteredDocumentsWhichAreSystemDocuments > SystemDocsLimitForRemoteEtagUpdate || documentsToReplicate.CountOfFilteredDocumentsWhichOriginFromDestination > DestinationDocsLimitForRemoteEtagUpdate) // see RavenDB-1555 { using (scope.StartRecording("Notify")) { SetLastReplicatedEtagForServer(destination, lastDocEtag: documentsToReplicate.LastEtag); scope.Record(new RavenJObject { { "LastDocEtag", documentsToReplicate.LastEtag.ToString() } }); } } } RecordLastEtagChecked(destination.ConnectionStringOptions.Url, documentsToReplicate.LastEtag); return null; } } // if the db is idling in all respect except sending out replication, let us keep it that way. docDb.WorkContext.UpdateFoundWork(); removeBatch = prefetchingBehavior.UpdateCurrentlyUsedBatches(documentsToReplicate.LoadedDocs); using (var scope = recorder.StartRecording("Send")) { string lastError; if (TryReplicationDocuments(destination, documentsToReplicate.Documents, out lastError) == false) // failed to replicate, start error handling strategy { if (IsFirstFailure(destination.ConnectionStringOptions.Url)) { log.Info( "This is the first failure for {0}, assuming transient failure and trying again", destination); if (TryReplicationDocuments(destination, documentsToReplicate.Documents, out lastError)) // success on second fail { RecordSuccess(destination.ConnectionStringOptions.Url, documentsToReplicate.LastEtag, documentsToReplicate.LastLastModified); return true; } } // if we had an error sending to this endpoint, it might be because we are sending too much data, or because // the request timed out. This will let us know that the next time we try, we'll use just the initial doc counts // and we'll be much more conservative with increasing the sizes prefetchingBehavior.OutOfMemoryExceptionHappened(); scope.RecordError(lastError); RecordFailure(destination.ConnectionStringOptions.Url, lastError); return false; } } } finally { if (documentsToReplicate != null && documentsToReplicate.LoadedDocs != null) { prefetchingBehavior.UpdateAutoThrottler(documentsToReplicate.LoadedDocs, sp.Elapsed); replicatedDocuments = documentsToReplicate.LoadedDocs.Count; } if (removeBatch != null) removeBatch.Dispose(); } RecordSuccess(destination.ConnectionStringOptions.Url, documentsToReplicate.LastEtag, documentsToReplicate.LastLastModified); return true; }
private bool? ReplicateAttachments(ReplicationStrategy destination, SourceReplicationInformationWithBatchInformation destinationsReplicationInformationForSource, ReplicationStatisticsRecorder.ReplicationStatisticsRecorderScope recorder) { Tuple<RavenJArray, Etag> tuple; RavenJArray attachments; using (var scope = recorder.StartRecording("Get")) { tuple = GetAttachments(destinationsReplicationInformationForSource, destination, scope); attachments = tuple.Item1; if (attachments == null || attachments.Length == 0) { if (tuple.Item2 != destinationsReplicationInformationForSource.LastAttachmentEtag) { SetLastReplicatedEtagForServer(destination, lastAttachmentEtag: tuple.Item2); } return null; } } using (var scope = recorder.StartRecording("Send")) { string lastError; if (TryReplicationAttachments(destination, attachments, out lastError) == false) // failed to replicate, start error handling strategy { if (IsFirstFailure(destination.ConnectionStringOptions.Url)) { log.Info("This is the first failure for {0}, assuming transient failure and trying again", destination); if (TryReplicationAttachments(destination, attachments, out lastError)) // success on second fail { RecordSuccess(destination.ConnectionStringOptions.Url, lastReplicatedEtag: tuple.Item2, forDocuments:false); return true; } } scope.RecordError(lastError); RecordFailure(destination.ConnectionStringOptions.Url, lastError); return false; } } RecordSuccess(destination.ConnectionStringOptions.Url, lastReplicatedEtag: tuple.Item2, forDocuments: false); return true; }
private bool ReplicateTo(ReplicationStrategy destination) { try { if (docDb.Disposed) return false; using (docDb.DisableAllTriggersForCurrentThread()) using (var stats = new ReplicationStatisticsRecorder(destination, destinationStats)) { SourceReplicationInformationWithBatchInformation destinationsReplicationInformationForSource; using (var scope = stats.StartRecording("Destination")) { try { destinationsReplicationInformationForSource = GetLastReplicatedEtagFrom(destination); if (destinationsReplicationInformationForSource == null) { destinationsReplicationInformationForSource = GetLastReplicatedEtagFrom(destination); if (destinationsReplicationInformationForSource == null) { log.Error("Failed to replicate documents to destination {0}, because was not able to receive last Etag", destination.ConnectionStringOptions.Url); return false; } } scope.Record(RavenJObject.FromObject(destinationsReplicationInformationForSource)); if (destinationsReplicationInformationForSource.LastDocumentEtag == Etag.InvalidEtag && destinationsReplicationInformationForSource.LastAttachmentEtag == Etag.InvalidEtag) { DateTime lastSent; // todo: move lastModifiedDate after the condition var lastModifiedDate = destinationsReplicationInformationForSource.LastModified.HasValue ? destinationsReplicationInformationForSource.LastModified.Value.ToLocalTime() : DateTime.MinValue; if (destinationAlertSent.TryGetValue(destination.ConnectionStringOptions.Url, out lastSent) && (SystemTime.UtcNow - lastSent).TotalMinutes < 1) { // todo: remove this log line log.Debug(string.Format(@"Destination server is forbidding replication due to a possibility of having multiple instances with same DatabaseId replicating to it. After 10 minutes from '{2}' another instance will start replicating. Destination Url: {0}. DatabaseId: {1}. Current source: {3}. Stored source on destination: {4}.", destination.ConnectionStringOptions.Url, docDb.TransactionalStorage.Id, lastModifiedDate, docDb.ServerUrl, destinationsReplicationInformationForSource.Source)); return false; } docDb.AddAlert(new Alert { AlertLevel = AlertLevel.Error, CreatedAt = SystemTime.UtcNow, Message = string.Format(@"Destination server is forbidding replication due to a possibility of having multiple instances with same DatabaseId replicating to it. After 10 minutes from '{2}' another instance will start replicating. Destination Url: {0}. DatabaseId: {1}. Current source: {3}. Stored source on destination: {4}.", destination.ConnectionStringOptions.Url, docDb.TransactionalStorage.Id, lastModifiedDate, docDb.ServerUrl, destinationsReplicationInformationForSource.Source), Title = string.Format("Replication error. Multiple databases replicating at the same time with same DatabaseId ('{0}') detected.", docDb.TransactionalStorage.Id), UniqueKey = "Replication to " + destination.ConnectionStringOptions.Url + " errored. Wrong DatabaseId: " + docDb.TransactionalStorage.Id }); destinationAlertSent.AddOrUpdate(destination.ConnectionStringOptions.Url, SystemTime.UtcNow, (_, __) => SystemTime.UtcNow); return false; } } catch (Exception e) { scope.RecordError(e); log.WarnException("Failed to replicate to: " + destination, e); return false; } } bool? replicated = null; int replicatedDocuments; using (var scope = stats.StartRecording("Documents")) { switch (ReplicateDocuments(destination, destinationsReplicationInformationForSource, scope, out replicatedDocuments)) { case true: replicated = true; break; case false: return false; } } using (var scope = stats.StartRecording("Attachments")) { switch (ReplicateAttachments(destination, destinationsReplicationInformationForSource, scope)) { case true: replicated = true; break; case false: return false; } } var elapsedMicroseconds = (long)(stats.ElapsedTime.Ticks * SystemTime.MicroSecPerTick); docDb.WorkContext.MetricsCounters.GetReplicationDurationHistogram(destination).Update(elapsedMicroseconds); UpdateReplicationPerformance(destination, stats.Started, stats.ElapsedTime, replicatedDocuments); return replicated ?? false; } } finally { var holder = activeReplicationTasks.GetOrAdd(destination.ConnectionStringOptions.Url, s => new SemaphoreSlim(0, 1)); holder.Release(); } }
private bool? ReplicateDocuments(ReplicationStrategy destination, SourceReplicationInformation destinationsReplicationInformationForSource, ReplicationStatisticsRecorder.ReplicationStatisticsRecorderScope recorder) { JsonDocumentsToReplicate documentsToReplicate; using (var scope = recorder.StartRecording("Get")) { documentsToReplicate = GetJsonDocuments(destinationsReplicationInformationForSource, destination, scope); if (documentsToReplicate.Documents == null || documentsToReplicate.Documents.Length == 0) { if (documentsToReplicate.LastEtag != destinationsReplicationInformationForSource.LastDocumentEtag) { // we don't notify remote server about updates to system docs, see: RavenDB-715 if (documentsToReplicate.CountOfFilteredDocumentsWhichAreSystemDocuments == 0 || documentsToReplicate.CountOfFilteredDocumentsWhichAreSystemDocuments > SystemDocsLimitForRemoteEtagUpdate || documentsToReplicate.CountOfFilteredDocumentsWhichOriginFromDestination > DestinationDocsLimitForRemoteEtagUpdate) // see RavenDB-1555 { using (scope.StartRecording("Notify")) { SetLastReplicatedEtagForServer(destination, lastDocEtag: documentsToReplicate.LastEtag); scope.Record(new RavenJObject { { "LastDocEtag", documentsToReplicate.LastEtag.ToString() } }); } } } RecordLastEtagChecked(destination.ConnectionStringOptions.Url, documentsToReplicate.LastEtag); return null; } } using (var scope = recorder.StartRecording("Send")) { string lastError; if (TryReplicationDocuments(destination, documentsToReplicate.Documents, out lastError) == false) // failed to replicate, start error handling strategy { if (IsFirstFailure(destination.ConnectionStringOptions.Url)) { log.Info( "This is the first failure for {0}, assuming transient failure and trying again", destination); if (TryReplicationDocuments(destination, documentsToReplicate.Documents, out lastError)) // success on second fail { RecordSuccess(destination.ConnectionStringOptions.Url, documentsToReplicate.LastEtag, documentsToReplicate.LastLastModified); return true; } } scope.RecordError(lastError); RecordFailure(destination.ConnectionStringOptions.Url, lastError); return false; } } RecordSuccess(destination.ConnectionStringOptions.Url, documentsToReplicate.LastEtag, documentsToReplicate.LastLastModified); return true; }
private bool ReplicateTo(ReplicationStrategy destination) { try { if (docDb.Disposed) return false; using (docDb.DisableAllTriggersForCurrentThread()) using (var stats = new ReplicationStatisticsRecorder(destination, destinationStats)) { SourceReplicationInformation destinationsReplicationInformationForSource; using (var scope = stats.StartRecording("Destination")) { try { destinationsReplicationInformationForSource = GetLastReplicatedEtagFrom(destination); if (destinationsReplicationInformationForSource == null) return false; scope.Record(RavenJObject.FromObject(destinationsReplicationInformationForSource)); } catch (Exception e) { scope.RecordError(e); log.WarnException("Failed to replicate to: " + destination, e); return false; } } bool? replicated = null; using (var scope = stats.StartRecording("Documents")) { switch (ReplicateDocuments(destination, destinationsReplicationInformationForSource, scope)) { case true: replicated = true; break; case false: return false; } } using (var scope = stats.StartRecording("Attachments")) { switch (ReplicateAttachments(destination, destinationsReplicationInformationForSource, scope)) { case true: replicated = true; break; case false: return false; } } docDb.WorkContext.MetricsCounters.GetReplicationDurationMetric(destination).Mark((long)stats.ElapsedTime.TotalMilliseconds); docDb.WorkContext.MetricsCounters.GetReplicationDurationHistogram(destination).Update((long)stats.ElapsedTime.TotalMilliseconds); return replicated ?? false; } } finally { var holder = activeReplicationTasks.GetOrAdd(destination.ConnectionStringOptions.Url, s => new SemaphoreSlim(0,1)); holder.Release(); } }
private bool ReplicateTo(ReplicationStrategy destination) { try { if (docDb.Disposed) return false; using (docDb.DisableAllTriggersForCurrentThread()) using (var stats = new ReplicationStatisticsRecorder(destination, destinationStats)) { SourceReplicationInformation destinationsReplicationInformationForSource; using (var scope = stats.StartRecording("Destination")) { try { destinationsReplicationInformationForSource = GetLastReplicatedEtagFrom(destination); if (destinationsReplicationInformationForSource == null) return false; scope.Record(RavenJObject.FromObject(destinationsReplicationInformationForSource)); } catch (Exception e) { scope.RecordError(e); log.WarnException("Failed to replicate to: " + destination, e); return false; } } bool? replicated = null; using (var scope = stats.StartRecording("Documents")) { switch (ReplicateDocuments(destination, destinationsReplicationInformationForSource, scope)) { case true: replicated = true; break; case false: return false; } } using (var scope = stats.StartRecording("Attachments")) { switch (ReplicateAttachments(destination, destinationsReplicationInformationForSource, scope)) { case true: replicated = true; break; case false: return false; } } return replicated ?? false; } } finally { var holder = activeReplicationTasks.GetOrAdd(destination.ConnectionStringOptions.Url, new IntHolder()); Thread.VolatileWrite(ref holder.Value, 0); } }