Maintain control of your Microsoft 365 data
Post Reply
rhys.hammond
Veeam Software
Posts: 75
Liked: 16 times
Joined: Apr 07, 2013 10:36 pm
Full Name: Rhys Hammond
Location: Brisbane , Australia
Contact:

The operation has timed out - backup failed

Post by rhys.hammond »

Installation,configuration, add organization and create job went without a hitch.
Selected all mailboxes, started the job and it fails with a time-out.
https://drive.google.com/open?id=0B13iJ ... jlXY29yeDQ

Just created a new job and manually selected 1 mailbox and it appears to be progressing further.
Awesome!

Here is the log information from the time it was failing when selecting all mailboxes.

Code: Select all

29/09/2016 1:05:05 PM   23 (10848) Error: Item change synchronization failed
29/09/2016 1:05:05 PM   23 (10848) Stack:
29/09/2016 1:05:05 PM   23 (10848)    at Veeam.Ews.ExItemSyncer.GetChanges(String folderId, String syncState, Int32 maxItems, IReadOnlyDictionary`2 requestProps)
   at Veeam.Archiver.Source.SyncPage.GetChanges(String folderId, Byte[] cookieIn, Byte[]& cookieOut)
   at Veeam.Archiver.Source.ItemProcessor.ProcessFolder(Byte[] id, ExFolder folder, Byte[] currentState)
   at Veeam.Archiver.Source.ItemProcessor.<>c__DisplayClass5_0.<Process>b__0()
   at Veeam.Archiver.Source.FolderRetry.<>c__DisplayClass9_0`1.<Process>b__0()
   at Veeam.Archiver.Source.FolderRetry.Process(Action action)
29/09/2016 1:05:05 PM   23 (10848) Error: The operation has timed out
29/09/2016 1:05:05 PM   23 (10848) Stack:
29/09/2016 1:05:05 PM   23 (10848)    at System.Web.Services.Protocols.WebClientProtocol.GetWebResponse(WebRequest request)
   at System.Web.Services.Protocols.HttpWebClientProtocol.GetWebResponse(WebRequest request)
   at System.Web.Services.Protocols.SoapHttpClientProtocol.Invoke(String methodName, Object[] parameters)
   at EWServices.ExchangeServiceBinding.SyncFolderItems(SyncFolderItemsType SyncFolderItems1)
   at Veeam.Ews.ExItemSyncer.GetChanges(String folderId, String syncState, Int32 maxItems, IReadOnlyDictionary`2 requestProps)
29/09/2016 1:05:05 PM   23 (10848) Warning: Exchange Web Services request failed
Veeam Certified Architect | Author of http://rhyshammond.com | Veeam Vanguard | vExpert
rhys.hammond
Veeam Software
Posts: 75
Liked: 16 times
Joined: Apr 07, 2013 10:36 pm
Full Name: Rhys Hammond
Location: Brisbane , Australia
Contact:

Re: The operation has timed out - backup failed

Post by rhys.hammond »

Looks like the second job configured to only backup one mailbox failed after 21 minutes with error 'item change synchronisation failed'.
Logs are below

Code: Select all

29/09/2016 2:50:43 PM   21 (3704) Error: Item change synchronization failed
29/09/2016 2:50:43 PM   21 (3704) Stack:
29/09/2016 2:50:43 PM   21 (3704)    at Veeam.Ews.ExItemSyncer.GetChanges(String folderId, String syncState, Int32 maxItems, IReadOnlyDictionary`2 requestProps)
   at Veeam.Archiver.Source.SyncPage.GetChanges(String folderId, Byte[] cookieIn, Byte[]& cookieOut)
   at Veeam.Archiver.Source.ItemProcessor.ProcessFolder(Byte[] id, ExFolder folder, Byte[] currentState)
   at Veeam.Archiver.Source.ItemProcessor.<>c__DisplayClass5_0.<Process>b__0()
   at Veeam.Archiver.Source.FolderRetry.<>c__DisplayClass9_0`1.<Process>b__0()
   at Veeam.Archiver.Source.FolderRetry.Process(Action action)
29/09/2016 2:50:43 PM   21 (3704) Error: The operation has timed out
29/09/2016 2:50:43 PM   21 (3704) Stack:
29/09/2016 2:50:43 PM   21 (3704)    at System.Net.HttpWebRequest.GetRequestStream(TransportContext& context)
   at System.Net.HttpWebRequest.GetRequestStream()
   at System.Web.Services.Protocols.SoapHttpClientProtocol.Invoke(String methodName, Object[] parameters)
   at EWServices.ExchangeServiceBinding.SyncFolderItems(SyncFolderItemsType SyncFolderItems1)
   at Veeam.Ews.ExItemSyncer.GetChanges(String folderId, String syncState, Int32 maxItems, IReadOnlyDictionary`2 requestProps)
29/09/2016 2:50:43 PM   21 (3704) Warning: Exchange Web Services request failed
29/09/2016 2:50:43 PM   21 (3704) Retry count: 1
29/09/2016 2:50:43 PM   23 (10848)   Sync time: 4.8791069
29/09/2016 2:50:43 PM   23 (10848)   Changed items: 64, deleted items: 0
29/09/2016 2:50:43 PM   23 (10848)   Retrieving items data...
Veeam Certified Architect | Author of http://rhyshammond.com | Veeam Vanguard | vExpert
foggy
Veeam Software
Posts: 21139
Liked: 2141 times
Joined: Jul 11, 2011 10:22 am
Full Name: Alexander Fogelson
Contact:

Re: The operation has timed out - backup failed

Post by foggy »

Hi Rhys, thanks for reporting. More logs are required for closer investigation and I believe our engineers have requested them via PM.
rhys.hammond
Veeam Software
Posts: 75
Liked: 16 times
Joined: Apr 07, 2013 10:36 pm
Full Name: Rhys Hammond
Location: Brisbane , Australia
Contact:

Re: The operation has timed out - backup failed

Post by rhys.hammond »

Hey Foggy,

Uploading now
Veeam Certified Architect | Author of http://rhyshammond.com | Veeam Vanguard | vExpert
Kostya
Veeam Software
Posts: 104
Liked: 28 times
Joined: Jun 18, 2012 9:38 am
Full Name: Kostya Yasyuk
Contact:

Re: The operation has timed out - backup failed

Post by Kostya »

Experimental fix has been provided via PM.
Yo_Was_Here
Novice
Posts: 5
Liked: never
Joined: Oct 25, 2016 7:44 pm
Contact:

Re: The operation has timed out - backup failed

Post by Yo_Was_Here »

Hello, I think we are having the same error. How do you get this logs?
I only have:

Code: Select all

24/10/2016 08:30:52 a.m.    1 (1512) Server: WIN-DKFME4V0C9E (CPUs: 4)
24/10/2016 08:30:52 a.m.    1 (1512) OS: Microsoft Windows NT 6.2.9200.0 (x64)
24/10/2016 08:30:52 a.m.    1 (1512) UTC Date & Time: 24/10/2016 11:30:52 a.m.
24/10/2016 08:30:52 a.m.    1 (1512) Timezone: (UTC-03:00) City of Buenos Aires
24/10/2016 08:30:52 a.m.    1 (1512) Daylight: Argentina Standard Time
24/10/2016 08:30:52 a.m.    1 (1512) Account: WIN-DKFME4V0C9E\Administrator
24/10/2016 08:30:52 a.m.    1 (1512) Log level: Default
24/10/2016 08:30:52 a.m.    1 (1512) ================================================================================
24/10/2016 08:30:52 a.m.    1 (1512) Veeam.Archiver.Shell.exe: Version=9.5.0.657
24/10/2016 08:30:52 a.m.    1 (1512) Command line parameters:
25/10/2016 04:49:15 p.m.    1 (1512) Starting job: iortega...
25/10/2016 04:51:54 p.m.    1 (1512) Closing shell...
25/10/2016 04:51:54 p.m.    1 (1512) Shell closed
And the job finished with "time out"
foggy
Veeam Software
Posts: 21139
Liked: 2141 times
Joined: Jul 11, 2011 10:22 am
Full Name: Alexander Fogelson
Contact:

Re: The operation has timed out - backup failed

Post by foggy »

This is a console log, the actual error should be in the service log (Veeam.Archiver.Service_<date>.log).
Yo_Was_Here
Novice
Posts: 5
Liked: never
Joined: Oct 25, 2016 7:44 pm
Contact:

Re: The operation has timed out - backup failed

Post by Yo_Was_Here »

Hello Foggy, and thanks for your help. Sorry for my delay, I was expecting a warning email.
This is the log for a new created job.

Code: Select all

31/10/2016 05:07:47 p.m.   15 (2352) Starting job: Maria...
31/10/2016 05:07:47 p.m.   15 (2352)   Retry settings: count 3, delay 00:10:00
31/10/2016 05:07:47 p.m.   15 (2352)   Mailbox folders excluded from backup:
31/10/2016 05:07:47 p.m.   15 (2352)     Deleted Items
31/10/2016 05:07:47 p.m.   15 (2352)     Drafts
31/10/2016 05:07:47 p.m.   15 (2352)     Junk E-Mail
31/10/2016 05:07:47 p.m.   15 (2352)     Outbox
31/10/2016 05:07:47 p.m.   15 (2352)     Sync Issues
31/10/2016 05:07:47 p.m.   15 (2352) Scheduling job: Maria. Next run at: 01/11/2016 07:00:00 a.m. (UTC: 01/11/2016 10:00:00 a.m.)...
31/10/2016 05:07:47 p.m.   15 (2352) Job started: Maria
31/10/2016 05:07:47 p.m.   12 (1816) Activating job: Maria...
31/10/2016 05:07:47 p.m.   18 (2976) Connecting to organization...
31/10/2016 05:07:51 p.m.   18 (2976) Resolving mailboxes...
31/10/2016 05:07:51 p.m.   18 (2976) Resolving mailbox (ID: 79f05b88-311d-48e6-a973-ec06688f20c3)...
31/10/2016 05:07:52 p.m.   18 (2976)   Found: Marisa Tormakh, SMTP: maria@crownergy.com
31/10/2016 05:07:52 p.m.   18 (2976) Closing remote connection...
31/10/2016 05:07:52 p.m.   18 (2976) Adding 1 mailboxes in processing...
31/10/2016 05:07:52 p.m.   18 (2976) Processing mailbox: maria@crownergy.com...
31/10/2016 05:07:52 p.m.   18 (2976) Connecting to Exchange Web Services...
31/10/2016 05:07:52 p.m.   18 (2976)   Host: outlook.office365.com
31/10/2016 05:09:52 p.m.   18 (2976) Error: The operation has timed out
31/10/2016 05:09:52 p.m.   18 (2976) Stack:
31/10/2016 05:09:52 p.m.   18 (2976)    at System.Net.HttpWebRequest.GetRequestStream(TransportContext& context)
   at System.Net.HttpWebRequest.GetRequestStream()
   at System.Web.Services.Protocols.SoapHttpClientProtocol.Invoke(String methodName, Object[] parameters)
   at EWServices.ExchangeServiceBinding.GetFolder(GetFolderType GetFolder1)
   at Veeam.Ews.ExMailbox.GetFolder(BaseFolderIdType id, IReadOnlyDictionary`2 requestProps)
   at Veeam.Ews.ExMailbox.GetMsgRootFolder(IReadOnlyDictionary`2 requestProps)
   at Veeam.Archiver.Source.MailboxActivator.SyncHierarchy(IMailboxContext bind, CancellationToken cancel)
   at Veeam.Archiver.Source.MailboxActivator.DoWork(CancellationToken cancel)
   at Veeam.Archiver.Source.WorkAction.WorkWrap(CancellationToken cancel)
31/10/2016 05:09:52 p.m.   18 (2976) Job processing failed
31/10/2016 05:09:53 p.m.   18 (2976) Job Maria failed, remaining retry count: 3
31/10/2016 05:09:53 p.m.   18 (2976)   Delaying next try to 31/10/2016 05:19:53 p.m. (UTC: 31/10/2016 08:19:53 p.m.)...
It's like it can't connect to Exchange Web Services. Any idea?
Yo_Was_Here
Novice
Posts: 5
Liked: never
Joined: Oct 25, 2016 7:44 pm
Contact:

Re: The operation has timed out - backup failed

Post by Yo_Was_Here »

Hello again,
When it retry the job, it shows the same error as rhys.hammond:

Code: Select all

31/10/2016 05:20:54 p.m.   13 (1848) Processing mailbox: maria@crownergy.com...
31/10/2016 05:20:54 p.m.   13 (1848) Syncing folder items: Impuesto Sellos TDF...
31/10/2016 05:20:54 p.m.   17 (1864)   Sync time: 2,6200881
31/10/2016 05:20:54 p.m.   17 (1864)   Changed items: 38, deleted items: 0
31/10/2016 05:20:54 p.m.   17 (1864)   Retrieving items data...
31/10/2016 05:21:20 p.m.   16 (1100)     Receive time: 27,3872404
31/10/2016 05:21:20 p.m.   16 (1100)   Saving received items...
31/10/2016 05:21:21 p.m.   16 (1100)     Saving time: 0,6814609
31/10/2016 05:21:21 p.m.   16 (1100)   Total data: 7986816
31/10/2016 05:21:21 p.m.   16 (1100)     Receive rate: 291625 B/s
31/10/2016 05:21:21 p.m.   16 (1100)     Saving rate : 11720138 B/s
31/10/2016 05:21:21 p.m.   16 (1100) Processing mailbox: maria@crownergy.com...
31/10/2016 05:21:21 p.m.   16 (1100) Syncing folder items: Informes sectoriales...
31/10/2016 05:22:54 p.m.   13 (1848) Error: Item change synchronization failed
31/10/2016 05:22:54 p.m.   13 (1848) Stack:
31/10/2016 05:22:54 p.m.   13 (1848)    at Veeam.Ews.ExItemSyncer.GetChanges(String folderId, String syncState, Int32 maxItems, IReadOnlyDictionary`2 requestProps)
   at Veeam.Archiver.Source.SyncPage.GetChanges(String folderId, Byte[] cookieIn, Byte[]& cookieOut)
   at Veeam.Archiver.Source.ItemProcessor.ProcessFolder(Byte[] id, ExFolder folder, Byte[] currentState)
   at Veeam.Archiver.Source.ItemProcessor.<>c__DisplayClass5_0.<Process>b__0()
   at Veeam.Archiver.Source.FolderRetry.<>c__DisplayClass9_0`1.<Process>b__0()
   at Veeam.Archiver.Source.FolderRetry.Process(Action action)
31/10/2016 05:22:54 p.m.   13 (1848) Error: The operation has timed out
31/10/2016 05:22:54 p.m.   13 (1848) Stack:
31/10/2016 05:22:54 p.m.   13 (1848)    at System.Net.HttpWebRequest.GetRequestStream(TransportContext& context)
   at System.Net.HttpWebRequest.GetRequestStream()
   at System.Web.Services.Protocols.SoapHttpClientProtocol.Invoke(String methodName, Object[] parameters)
   at EWServices.ExchangeServiceBinding.SyncFolderItems(SyncFolderItemsType SyncFolderItems1)
   at Veeam.Ews.ExItemSyncer.GetChanges(String folderId, String syncState, Int32 maxItems, IReadOnlyDictionary`2 requestProps)
31/10/2016 05:22:54 p.m.   13 (1848) Warning: Exchange Web Services request failed
31/10/2016 05:22:54 p.m.   13 (1848) Retry count: 1
31/10/2016 05:23:21 p.m.   16 (1100) Error: Item change synchronization failed
31/10/2016 05:23:21 p.m.   16 (1100) Stack:
31/10/2016 05:23:21 p.m.   16 (1100)    at Veeam.Ews.ExItemSyncer.GetChanges(String folderId, String syncState, Int32 maxItems, IReadOnlyDictionary`2 requestProps)
   at Veeam.Archiver.Source.SyncPage.GetChanges(String folderId, Byte[] cookieIn, Byte[]& cookieOut)
   at Veeam.Archiver.Source.ItemProcessor.ProcessFolder(Byte[] id, ExFolder folder, Byte[] currentState)
   at Veeam.Archiver.Source.ItemProcessor.<>c__DisplayClass5_0.<Process>b__0()
   at Veeam.Archiver.Source.FolderRetry.<>c__DisplayClass9_0`1.<Process>b__0()
   at Veeam.Archiver.Source.FolderRetry.Process(Action action)
31/10/2016 05:23:21 p.m.   16 (1100) Error: The operation has timed out
Mike Resseler
Product Manager
Posts: 8191
Liked: 1322 times
Joined: Feb 08, 2013 3:08 pm
Full Name: Mike Resseler
Location: Belgium
Contact:

Re: The operation has timed out - backup failed

Post by Mike Resseler »

Hi,

Did you implement the fix that is provided here: [BETA1] Veeam Backup for Microsoft Office 365?

Thanks
Mike
Yo_Was_Here
Novice
Posts: 5
Liked: never
Joined: Oct 25, 2016 7:44 pm
Contact:

Re: The operation has timed out - backup failed

Post by Yo_Was_Here »

Trying...
Yo_Was_Here
Novice
Posts: 5
Liked: never
Joined: Oct 25, 2016 7:44 pm
Contact:

Re: The operation has timed out - backup failed

Post by Yo_Was_Here »

More or less the same:

Code: Select all

01/11/2016 12:18:26 p.m.   26 (2212) Job started: maria
01/11/2016 12:18:26 p.m.   12 (1752) Activating job: maria...
01/11/2016 12:18:26 p.m.   15 (1712) Connecting to organization...
01/11/2016 12:24:32 p.m.   18 (2824) Error: Item change synchronization failed
01/11/2016 12:24:32 p.m.   18 (2824) Stack:
01/11/2016 12:24:32 p.m.   18 (2824)    at Veeam.Ews.ExItemSyncer.GetChanges(String folderId, String syncState, Int32 maxItems, IReadOnlyDictionary`2 requestProps)
   at Veeam.Archiver.Source.SyncPage.GetChanges(String folderId, Byte[] cookieIn, Byte[]& cookieOut)
   at Veeam.Archiver.Source.ItemProcessor.ProcessFolder(Byte[] id, ExFolder folder, Byte[] currentState)
   at Veeam.Archiver.Source.ItemProcessor.<>c__DisplayClass5_0.<Process>b__0()
   at Veeam.Archiver.Source.FolderRetry.<>c__DisplayClass9_0`1.<Process>b__0()
   at Veeam.Archiver.Source.FolderRetry.Process(Action action)
01/11/2016 12:24:32 p.m.   18 (2824) Error: The operation has timed out
01/11/2016 12:24:32 p.m.   18 (2824) Stack:
01/11/2016 12:24:32 p.m.   18 (2824)    at System.Net.HttpWebRequest.GetRequestStream(TransportContext& context)
   at System.Net.HttpWebRequest.GetRequestStream()
   at System.Web.Services.Protocols.SoapHttpClientProtocol.Invoke(String methodName, Object[] parameters)
   at EWServices.ExchangeServiceBinding.SyncFolderItems(SyncFolderItemsType SyncFolderItems1)
   at Veeam.Ews.ExItemSyncer.GetChanges(String folderId, String syncState, Int32 maxItems, IReadOnlyDictionary`2 requestProps)
01/11/2016 12:24:32 p.m.   18 (2824) Warning: Exchange Web Services request failed
01/11/2016 12:24:32 p.m.   18 (2824) Retry count: 1
01/11/2016 12:24:32 p.m.   18 (2824) Processing stopped
01/11/2016 12:24:32 p.m.   15 (1712) Resolving mailboxes...
01/11/2016 12:24:32 p.m.   15 (1712) Resolving mailbox (ID: 79f05b88-311d-48e6-a973-ec06688f20c3)...
01/11/2016 12:24:34 p.m.   15 (1712)   Found: Marisa Tormakh, SMTP: maria@crownergy.com
01/11/2016 12:24:34 p.m.   15 (1712) Closing remote connection...
01/11/2016 12:24:34 p.m.   15 (1712) Adding 1 mailboxes in processing...
02/11/2016 11:27:12 a.m.   15 (1712) Processing mailbox: maria@crownergy.com...
02/11/2016 11:27:12 a.m.   15 (1712) Connecting to Exchange Web Services...
02/11/2016 11:27:12 a.m.   15 (1712)   Host: outlook.office365.com
02/11/2016 11:27:16 a.m.   15 (1712) Requesting folder hierarchy changes...
02/11/2016 11:27:17 a.m.   15 (1712)   No folder hierarchy changes
02/11/2016 11:27:17 a.m.   15 (1712) Preparing folders for items processing...
02/11/2016 11:27:22 a.m.   15 (1712) Getting subfolders of folder: Principio del almacén de información
02/11/2016 11:27:23 a.m.   15 (1712)   Folder: Archivos
02/11/2016 11:27:23 a.m.   15 (1712)     Prop: 0x3001001F = Archivos
02/11/2016 11:27:23 a.m.   15 (1712)     Prop: 0x3004001F = 
02/11/2016 11:27:23 a.m.   15 (1712)     Prop: 0x0FFF0102 = AAAAAJ65bkI0MHtGleiZyeOK7esBAKlR9YtJHYpNtxbWKSzjxsgAAMHs6OEAAA==
02/11/2016 11:27:23 a.m.   15 (1712)     Prop: 0x0E090102 = AAAAAJ65bkI0MHtGleiZyeOK7esBAKlR9YtJHYpNtxbWKSzjxsgAAAAAAQgAAA==
02/11/2016 11:27:23 a.m.   15 (1712)   Folder: Bandeja de entrada
02/11/2016 11:27:23 a.m.   15 (1712)     Prop: 0x3001001F = Bandeja de entrada
02/11/2016 11:27:23 a.m.   15 (1712)     Prop: 0x3004001F = 
02/11/2016 11:27:23 a.m.   15 (1712)     Prop: 0x0FFF0102 = AAAAAJ65bkI0MHtGleiZyeOK7esBAKlR9YtJHYpNtxbWKSzjxsgAAAAAAQwAAA==
02/11/2016 11:27:23 a.m.   15 (1712)     Prop: 0x0E090102 = AAAAAJ65bkI0MHtGleiZyeOK7esBAKlR9YtJHYpNtxbWKSzjxsgAAAAAAQgAAA==
02/11/2016 11:27:23 a.m.   15 (1712)   Folder: Bandeja de salida
02/11/2016 11:27:23 a.m.   15 (1712)     Prop: 0x3001001F = Bandeja de salida
02/11/2016 11:27:23 a.m.   15 (1712)     Prop: 0x3004001F = 
02/11/2016 11:27:23 a.m.   15 (1712)     Prop: 0x0FFF0102 = AAAAAJ65bkI0MHtGleiZyeOK7esBAKlR9YtJHYpNtxbWKSzjxsgAAAAAAQsAAA==
02/11/2016 11:27:23 a.m.   15 (1712)     Prop: 0x0E090102 = AAAAAJ65bkI0MHtGleiZyeOK7esBAKlR9YtJHYpNtxbWKSzjxsgAAAAAAQgAAA==
02/11/2016 11:27:23 a.m.   15 (1712)   Folder: Borradores
.
.
.
02/11/2016 11:28:11 a.m.   15 (1712)   41 folders found
02/11/2016 11:28:11 a.m.   15 (1712) Processing mailbox: maria@crownergy.com...
02/11/2016 11:28:11 a.m.   18 (2824) Processing mailbox: maria@crownergy.com...
02/11/2016 11:28:11 a.m.   16 (2944) Processing mailbox: maria@crownergy.com...
02/11/2016 11:28:11 a.m.   18 (2824) Connecting to Exchange Web Services...
02/11/2016 11:28:11 a.m.   18 (2824)   Host: outlook.office365.com
02/11/2016 11:28:11 a.m.   17 (3048) Processing mailbox: maria@crownergy.com...
02/11/2016 11:28:11 a.m.   17 (3048) Connecting to Exchange Web Services...
02/11/2016 11:28:11 a.m.   17 (3048)   Host: outlook.office365.com
02/11/2016 11:28:11 a.m.   15 (1712) Syncing folder items: Principio del almacén de información...
02/11/2016 11:28:11 a.m.   18 (2824) Syncing folder items: Archivos...
02/11/2016 11:28:11 a.m.   16 (2944) Connecting to Exchange Web Services...
02/11/2016 11:28:11 a.m.   16 (2944)   Host: outlook.office365.com
02/11/2016 11:28:11 a.m.   17 (3048) Syncing folder items: 15 Q2...
02/11/2016 11:28:11 a.m.   16 (2944) Syncing folder items: Bandeja de entrada...
02/11/2016 11:28:13 a.m.   15 (1712)   Sync time: 1,1459714
02/11/2016 11:28:13 a.m.   15 (1712)   No changes
02/11/2016 11:28:13 a.m.   15 (1712) Processing mailbox: maria@crownergy.com...
02/11/2016 11:28:13 a.m.   15 (1712) Syncing folder items: Auditoría...
02/11/2016 11:28:15 a.m.   16 (2944)   Sync time: 3,0747975
02/11/2016 11:28:15 a.m.   16 (2944)   Changed items: 100, deleted items: 0
02/11/2016 11:28:15 a.m.   16 (2944)   Retrieving items data...
02/11/2016 11:28:15 a.m.   18 (2824)   Sync time: 3,4913984
02/11/2016 11:28:15 a.m.   18 (2824)   Changed items: 100, deleted items: 0
02/11/2016 11:28:15 a.m.   18 (2824)   Retrieving items data...
02/11/2016 11:28:15 a.m.   17 (3048)   Sync time: 3,9658783
02/11/2016 11:28:15 a.m.   17 (3048)   No changes
02/11/2016 11:28:15 a.m.   17 (3048) Processing mailbox: maria@crownergy.com...
02/11/2016 11:28:15 a.m.   17 (3048) Syncing folder items: Clippings...
02/11/2016 11:28:16 a.m.   15 (1712)   Sync time: 3,1040702
02/11/2016 11:28:16 a.m.   15 (1712)   No changes
02/11/2016 11:28:16 a.m.   15 (1712) Processing mailbox: maria@crownergy.com...
02/11/2016 11:28:16 a.m.   15 (1712) Syncing folder items: Impuesto Sellos TDF...
02/11/2016 11:28:32 a.m.   18 (2824)     Receive time: 17,1514046
02/11/2016 11:28:32 a.m.   18 (2824)   Saving received items...
02/11/2016 11:28:33 a.m.   18 (2824)     Saving time: 0,7606312
02/11/2016 11:28:33 a.m.   18 (2824)   Total data: 4370128
02/11/2016 11:28:33 a.m.   18 (2824)     Receive rate: 254797 B/s
02/11/2016 11:28:33 a.m.   18 (2824)     Saving rate : 5745396 B/s
02/11/2016 11:28:33 a.m.   18 (2824) Processing mailbox: maria@crownergy.com...
02/11/2016 11:28:33 a.m.   18 (2824) Syncing folder items: Informes sectoriales...
02/11/2016 11:28:33 a.m.   17 (3048)   Sync time: 17,7082048
02/11/2016 11:28:33 a.m.   17 (3048)   Changed items: 38, deleted items: 0
02/11/2016 11:28:33 a.m.   17 (3048)   Retrieving items data...
02/11/2016 11:28:34 a.m.   18 (2824)   Sync time: 1,2903923
02/11/2016 11:28:34 a.m.   18 (2824)   No changes
02/11/2016 11:28:34 a.m.   18 (2824) Processing mailbox: maria@crownergy.com...
02/11/2016 11:28:34 a.m.   18 (2824) Syncing folder items: Operaciones...
02/11/2016 11:33:15 a.m.   16 (2944) Error: Async batch export failed with timeout
02/11/2016 11:33:15 a.m.   16 (2944) Stack:
02/11/2016 11:33:15 a.m.   16 (2944)    at Veeam.Ews.ExServicesExtension.WaitRequests(WaitHandle[] asyncs, CancellationToken cancel, TimeSpan timeout)
   at Veeam.Ews.ExServicesExtension.BatchExport(ExServices ews, IEnumerable`1 ids, Int32 splitCount, TimeSpan timeout, CancellationToken cancel)
   at Veeam.Archiver.Source.SyncPage.ProcessData(IFolderRepository repository, IReadOnlyList`1 page)
   at Veeam.Archiver.Source.SyncPage.SaveChanges(IFolderRepository repository)
   at Veeam.Archiver.Source.ItemProcessor.ProcessFolder(Byte[] id, ExFolder folder, Byte[] currentState)
   at Veeam.Archiver.Source.ItemProcessor.<>c__DisplayClass5_0.<Process>b__0()
   at Veeam.Archiver.Source.FolderRetry.<>c__DisplayClass9_0`1.<Process>b__0()
   at Veeam.Archiver.Source.FolderRetry.Process(Action action)
02/11/2016 11:33:15 a.m.   16 (2944) Warning: Exchange Web Services request failed
02/11/2016 11:33:15 a.m.   16 (2944) Retry count: 1
02/11/2016 11:33:33 a.m.   17 (3048) Error: Async batch export failed with timeout
02/11/2016 11:33:33 a.m.   17 (3048) Stack:
02/11/2016 11:33:33 a.m.   17 (3048)    at Veeam.Ews.ExServicesExtension.WaitRequests(WaitHandle[] asyncs, CancellationToken cancel, TimeSpan timeout)
   at Veeam.Ews.ExServicesExtension.BatchExport(ExServices ews, IEnumerable`1 ids, Int32 splitCount, TimeSpan timeout, CancellationToken cancel)
   at Veeam.Archiver.Source.SyncPage.ProcessData(IFolderRepository repository, IReadOnlyList`1 page)
   at Veeam.Archiver.Source.SyncPage.SaveChanges(IFolderRepository repository)
   at Veeam.Archiver.Source.ItemProcessor.ProcessFolder(Byte[] id, ExFolder folder, Byte[] currentState)
   at Veeam.Archiver.Source.ItemProcessor.<>c__DisplayClass5_0.<Process>b__0()
   at Veeam.Archiver.Source.FolderRetry.<>c__DisplayClass9_0`1.<Process>b__0()
   at Veeam.Archiver.Source.FolderRetry.Process(Action action)
02/11/2016 11:33:33 a.m.   17 (3048) Warning: Exchange Web Services request failed
02/11/2016 11:33:33 a.m.   17 (3048) Retry count: 1
02/11/2016 11:36:29 a.m.   15 (1712)   Sync time: 493,4351145
02/11/2016 11:36:29 a.m.   15 (1712)   No changes
02/11/2016 11:36:29 a.m.   15 (1712) Processing mailbox: maria@crownergy.com...
02/11/2016 11:36:29 a.m.   15 (1712) Syncing folder items: Sistemas...
02/11/2016 11:36:30 a.m.   16 (2944)   Sync time: 185,9426744
02/11/2016 11:36:30 a.m.   16 (2944)   Changed items: 100, deleted items: 0
02/11/2016 11:36:30 a.m.   16 (2944)   Retrieving items data...
02/11/2016 11:36:32 a.m.   15 (1712)   Sync time: 2,519676
02/11/2016 11:36:32 a.m.   15 (1712)   No changes
02/11/2016 11:36:32 a.m.   15 (1712) Processing mailbox: maria@crownergy.com...
02/11/2016 11:36:32 a.m.   15 (1712) Syncing folder items: Calendario...
02/11/2016 11:38:34 a.m.   18 (2824) Error: Item change synchronization failed
02/11/2016 11:38:34 a.m.   18 (2824) Stack:
02/11/2016 11:38:34 a.m.   18 (2824)    at Veeam.Ews.ExItemSyncer.GetChanges(String folderId, String syncState, Int32 maxItems, IReadOnlyDictionary`2 requestProps)
   at Veeam.Archiver.Source.SyncPage.GetChanges(String folderId, Byte[] cookieIn, Byte[]& cookieOut)
   at Veeam.Archiver.Source.ItemProcessor.ProcessFolder(Byte[] id, ExFolder folder, Byte[] currentState)
   at Veeam.Archiver.Source.ItemProcessor.<>c__DisplayClass5_0.<Process>b__0()
   at Veeam.Archiver.Source.FolderRetry.<>c__DisplayClass9_0`1.<Process>b__0()
   at Veeam.Archiver.Source.FolderRetry.Process(Action action)
02/11/2016 11:38:34 a.m.   18 (2824) Error: The operation has timed out
02/11/2016 11:38:34 a.m.   18 (2824) Stack:
02/11/2016 11:38:34 a.m.   18 (2824)    at System.Net.HttpWebRequest.GetRequestStream(TransportContext& context)
   at System.Net.HttpWebRequest.GetRequestStream()
   at System.Web.Services.Protocols.SoapHttpClientProtocol.Invoke(String methodName, Object[] parameters)
   at EWServices.ExchangeServiceBinding.SyncFolderItems(SyncFolderItemsType SyncFolderItems1)
   at Veeam.Ews.ExItemSyncer.GetChanges(String folderId, String syncState, Int32 maxItems, IReadOnlyDictionary`2 requestProps)
02/11/2016 11:38:34 a.m.   18 (2824) Warning: Exchange Web Services request failed
02/11/2016 11:38:34 a.m.   18 (2824) Retry count: 1
02/11/2016 11:41:31 a.m.   16 (2944) Error: Async batch export failed with timeout
02/11/2016 11:41:31 a.m.   16 (2944) Stack:
02/11/2016 11:41:31 a.m.   16 (2944)    at Veeam.Ews.ExServicesExtension.WaitRequests(WaitHandle[] asyncs, CancellationToken cancel, TimeSpan timeout)
   at Veeam.Ews.ExServicesExtension.BatchExport(ExServices ews, IEnumerable`1 ids, Int32 splitCount, TimeSpan timeout, CancellationToken cancel)
   at Veeam.Archiver.Source.SyncPage.ProcessData(IFolderRepository repository, IReadOnlyList`1 page)
   at Veeam.Archiver.Source.SyncPage.SaveChanges(IFolderRepository repository)
   at Veeam.Archiver.Source.ItemProcessor.ProcessFolder(Byte[] id, ExFolder folder, Byte[] currentState)
   at Veeam.Archiver.Source.ItemProcessor.<>c__DisplayClass5_0.<Process>b__0()
   at Veeam.Archiver.Source.FolderRetry.<>c__DisplayClass9_0`1.<Process>b__0()
   at Veeam.Archiver.Source.FolderRetry.Process(Action action)
02/11/2016 11:41:31 a.m.   16 (2944) Warning: Exchange Web Services request failed
02/11/2016 11:41:31 a.m.   16 (2944) Retry count: 2
Mike Resseler
Product Manager
Posts: 8191
Liked: 1322 times
Joined: Feb 08, 2013 3:08 pm
Full Name: Mike Resseler
Location: Belgium
Contact:

Re: The operation has timed out - backup failed

Post by Mike Resseler »

Hey,

I need to consult with the R&D teams. We are working on a next version (not GA yet but improved one over the one you have today) and I hope to have that available as soon as possible.
In the meantime, I will share the log with our teams for investigation and I hope we can get this solved so you can continue to test as soon as possible.

Sorry for now :-(

Mike
Mike Resseler
Product Manager
Posts: 8191
Liked: 1322 times
Joined: Feb 08, 2013 3:08 pm
Full Name: Mike Resseler
Location: Belgium
Contact:

Re: The operation has timed out - backup failed

Post by Mike Resseler »

PS: speaking of the devil... It is live: veeam-backup-for-microsoft-office-365-f ... 38574.html
Post Reply

Who is online

Users browsing this forum: No registered users and 7 guests