SME and VSS issue while backup

[harpreetsingh]

Hello,

I am using windows 2003 x64 running MS exchange 2007 SP3

and NetApp snapmanager 6.0.2 along with snap drive 6.3.1.

I am getting lot of backup failure errors:

[09:09:27.411]  ------------ Creating the snapshot -----------------

[09:09:27.411]  Starting asynchronous PrepareForBackup. Please wait...

[09:09:28.411]  Asynchronous PrepareForBackup finished.

[09:09:28.458]  Status after PrepareForBackup (9 writers)

[09:09:28.458]  Status for writer System Writer: STABLE(0x00000000)

[09:09:28.458]  Status for writer MSDEWriter: STABLE(0x00000000)

[09:09:28.458]  Status for writer Registry Writer: STABLE(0x00000000)

[09:09:28.458]  Status for writer Event Log Writer: STABLE(0x00000000)

[09:09:28.458]  Status for writer COM+ REGDB Writer: STABLE(0x00000000)

[09:09:28.458]  Status for writer Cluster Service Writer: STABLE(0x00000000)

[09:09:28.458]  Status for writer Microsoft Exchange Writer: STABLE(0x00000000)

[09:09:28.458]  Status for writer WMI Writer: STABLE(0x00000000)

[09:09:28.458]  Status for writer IIS Metabase Writer: STABLE(0x00000000)

[09:09:28.458]  Starting asynchronous DoSnapshotSet. Please wait...

[09:09:29.458]  Operation pending, please wait...(1)

[09:09:34.458]  Operation pending, please wait...(2)

[09:09:39.457]  Operation pending, please wait...(3)

[09:09:44.457]  Operation pending, please wait...(4)

[09:09:49.457]  Operation pending, please wait...(5)

[09:09:54.457]  Operation pending, please wait...(6)

[09:09:59.456]  Operation pending, please wait...(7)

[09:10:04.456]  Operation pending, please wait...(8)

[09:10:09.456]  Operation pending, please wait...(9)

[09:10:14.456]  Operation pending, please wait...(10)

[09:10:19.455]  Operation pending, please wait...(11)

[09:10:24.455]  Operation pending, please wait...(12)

[09:10:29.455]  Operation pending, please wait...(13)

[09:10:34.455]  Operation pending, please wait...(14)

[09:10:39.454]  Operation pending, please wait...(15)

[09:10:44.454]  Operation pending, please wait...(16)

[09:10:49.454]  Operation pending, please wait...(17)

[09:10:54.454]  Operation pending, please wait...(18)

[09:10:59.453]  Operation pending, please wait...(19)

[09:11:04.453]  Operation pending, please wait...(20)

[09:11:09.453]  Operation pending, please wait...(21)

[09:11:14.453]  Operation pending, please wait...(22)

[09:11:19.452]  Operation pending, please wait...(23)

[09:11:24.452]  Operation pending, please wait...(24)

[09:11:29.452]  Operation pending, please wait...(25)

[09:11:34.452]  Operation pending, please wait...(26)

[09:11:39.451]  Operation pending, please wait...(27)

[09:11:44.451]  Operation pending, please wait...(28)

[09:11:49.451]  Operation pending, please wait...(29)

[09:11:54.450]  Operation pending, please wait...(30)

[09:11:59.450]  Operation pending, please wait...(31)

[09:12:04.450]  Operation pending, please wait...(32)

[09:12:09.450]  Operation pending, please wait...(33)

[09:12:14.449]  Operation pending, please wait...(34)

[09:12:19.449]  Operation pending, please wait...(35)

[09:12:24.449]  Operation pending, please wait...(36)

[09:12:29.449]  Operation pending, please wait...(37)

[09:12:34.448]  Operation pending, please wait...(38)

[09:12:39.448]  Operation pending, please wait...(39)

[09:12:44.448]  Operation pending, please wait...(40)

[09:12:49.448]  Operation pending, please wait...(41)

[09:12:54.447]  Operation pending, please wait...(42)

[09:12:59.447]  Operation pending, please wait...(43)

[09:13:04.447]  Operation pending, please wait...(44)

[09:13:09.447]  Operation pending, please wait...(45)

[09:13:14.446]  Operation pending, please wait...(46)

[09:13:19.446]  Operation pending, please wait...(47)

[09:13:24.446]  Operation pending, please wait...(48)

[09:13:29.446]  Operation pending, please wait...(49)

[09:13:34.445]  Operation pending, please wait...(50)

[09:13:39.445]  Operation pending, please wait...(51)

[09:13:44.445]  Operation pending, please wait...(52)

[09:13:49.445]  Operation pending, please wait...(53)

[09:13:54.444]  Operation pending, please wait...(54)

[09:13:59.444]  Operation pending, please wait...(55)

[09:14:04.444]  Operation pending, please wait...(56)

[09:14:09.444]  Operation pending, please wait...(57)

[09:14:14.443]  Operation pending, please wait...(58)

[09:14:19.443]  Operation pending, please wait...(59)

[09:14:24.443]  Operation pending, please wait...(60)

[09:14:29.443]  Operation pending, please wait...(61)

[09:14:34.442]  Operation pending, please wait...(62)

[09:14:39.442]  Operation pending, please wait...(63)

[09:14:44.442]  Operation pending, please wait...(64)

[09:14:49.442]  Operation pending, please wait...(65)

[09:14:54.441]  Operation pending, please wait...(66)

[09:14:59.441]  Operation pending, please wait...(67)

[09:15:04.441]  Operation pending, please wait...(68)

[09:15:09.440]  Operation pending, please wait...(69)

[09:15:14.440]  Operation pending, please wait...(70)

[09:15:19.440]  Operation pending, please wait...(71)

[09:15:24.440]  Operation pending, please wait...(72)

[09:15:29.439]  Operation pending, please wait...(73)

[09:15:34.439]  Operation pending, please wait...(74)

[09:15:39.439]  Operation pending, please wait...(75)

[09:15:44.439]  Operation pending, please wait...(76)

[09:15:49.438]  Operation pending, please wait...(77)

[09:15:54.438]  Operation pending, please wait...(78)

[09:15:59.438]  Operation pending, please wait...(79)

[09:16:04.438]  Operation pending, please wait...(80)

[09:16:09.437]  Operation pending, please wait...(81)

[09:16:14.437]  Operation pending, please wait...(82)

[09:16:19.437]  Operation pending, please wait...(83)

[09:16:24.437]  Operation pending, please wait...(84)

[09:16:29.436]  Operation pending, please wait...(85)

[09:16:34.436]  Operation pending, please wait...(86)

[09:16:39.436]  Operation pending, please wait...(87)

[09:16:44.436]  Operation pending, please wait...(88)

[09:16:49.435]  Operation pending, please wait...(89)

[09:16:54.435]  Operation pending, please wait...(90)

[09:16:59.435]  Operation pending, please wait...(91)

[09:17:04.435]  Operation pending, please wait...(92)

[09:17:09.434]  Operation pending, please wait...(93)

[09:17:14.434]  Operation pending, please wait...(94)

[09:17:19.434]  Operation pending, please wait...(95)

[09:17:24.434]  Operation pending, please wait...(96)

[09:17:29.433]  Operation pending, please wait...(97)

[09:17:34.433]  Operation pending, please wait...(98)

[09:17:39.433]  Operation pending, please wait...(99)

[09:17:44.433]  Operation pending, please wait...(100)

[09:17:49.432]  Operation pending, please wait...(101)

[09:17:54.432]  Operation pending, please wait...(102)

[09:17:59.432]  Operation pending, please wait...(103)

[09:18:04.432]  Operation pending, please wait...(104)

[09:18:09.431]  Operation pending, please wait...(105)

[09:18:14.431]  Operation pending, please wait...(106)

[09:18:19.431]  Operation pending, please wait...(107)

[09:18:24.431]  Operation pending, please wait...(108)

[09:18:29.430]  Operation pending, please wait...(109)

[09:18:34.430]  Operation pending, please wait...(110)

[09:18:39.430]  Operation pending, please wait...(111)

[09:18:44.429]  Operation pending, please wait...(112)

[09:18:49.429]  Operation pending, please wait...(113)

[09:18:54.429]  Operation pending, please wait...(114)

[09:18:59.429]  Operation pending, please wait...(115)

[09:19:04.428]  Operation pending, please wait...(116)

[09:19:09.428]  Operation pending, please wait...(117)

[09:19:14.428]  Operation pending, please wait...(118)

[09:19:19.428]  Operation pending, please wait...(119)

[09:19:24.427]  Operation pending, please wait...(120)

[09:19:29.427]  Operation pending, please wait...(121)

[09:19:29.427]  Asynchronous call DoSnapshotSet timed out!

[09:19:29.427]  The call for DoSnapshotSet was cancelled.

[09:19:29.427]  Failed to perform VSS snapshot, aborting...

[09:19:29.505]  Backup is aborted.

[09:19:29.505]  ----------------------------------------------------

[09:19:29.505]  SnapManager will retry VSS backup within [5] minutes, please wait...

[09:19:59.504]  Please wait...

[09:20:29.502]  SnapManager will retry VSS backup within [4] minutes, please wait...

[09:20:59.501]  Please wait...

[09:21:29.499]  SnapManager will retry VSS backup within [3] minutes, please wait...

[09:21:59.498]  Please wait...

[09:22:29.496]  SnapManager will retry VSS backup within [2] minutes, please wait...

[09:22:59.495]  Please wait...

[09:23:29.493]  SnapManager will retry VSS backup within [1] minutes, please wait...

[09:23:59.491]  Please wait...

[09:24:29.490]  -------------- Retrying VSS backup -----------------

09:24:30.302]  ----------------- Initializing ---------------------

[09:24:30.302]  Creating backup components object...

[09:24:30.302]  Backup components object created.

[09:24:30.302]  Initializing the backup components object for backup...

[09:24:30.318]  Setting up backup context...

[09:24:30.427]  Setting up database backup type: Full Backup...

[09:24:30.427]  Backup components object initialized for backup operations.

[09:24:30.427]  ---------- Gathering writer metadata ---------------

[09:24:30.427]  Starting asynchronous GatherWriterMetadata. Please wait...

[09:24:31.427]  Operation pending, please wait...(1)

[09:24:36.427]  Asynchronous GatherWriterMetadata finished.

[09:24:36.427]  Getting the list of Writer Metadata Documents provided to VSS...

[09:24:36.427]  Number of writers that responded: 9.

[09:24:36.427]  Starting snapshot set...

[09:24:41.427]  Snapshot set startup in progress...(1)

[09:24:46.427]  Snapshot set startup in progress...(2)

[09:24:51.426]  Snapshot set startup in progress...(3)

[09:24:56.426]  Snapshot set startup in progress...(4)

[09:25:01.426]  Snapshot set startup in progress...(5)

[09:25:06.426]  Snapshot set startup in progress...(6)

[09:25:11.425]  Snapshot set startup in progress...(7)

[09:25:16.425]  Snapshot set startup in progress...(8)

[09:25:21.425]  Snapshot set startup in progress...(9)

[09:25:26.425]  Snapshot set startup in progress...(10)

[09:25:31.424]  Snapshot set startup in progress...(11)

[09:25:36.424]  Snapshot set startup in progress...(12)

[09:25:37.424]  Error in calling VSS API: Error code = 0x80042316

Error description: VSS_E_SNAPSHOT_SET_IN_PROGRESS

[09:25:37.424]  ----------------------------------------------------

[09:25:37.424]  SnapManager will retry VSS backup within [5] minutes, please wait...

[09:26:07.422]  Please wait...

[09:26:37.421]  SnapManager will retry VSS backup within [4] minutes, please wait...

[09:27:07.419]  Please wait...

[09:27:37.418]  SnapManager will retry VSS backup within [3] minutes, please wait...

[09:28:07.416]  Please wait...

[09:28:37.415]  SnapManager will retry VSS backup within [2] minutes, please wait...

[09:29:07.413]  Please wait...

[09:29:37.412]  SnapManager will retry VSS backup within [1] minutes, please wait...

[09:30:07.410]  Please wait...

I am doing backup of 36 + 36 logs and database luns. I have opened case with support team since last 1 week and still have not heared word from them.

I have 9 VSS writers available with Ok status and snapmanger is using MS exchange writer for its operation.

Any insight will to this issue will be much Appriciated.

[GRAEMEOGDEN]

What state are the VSS writers currently in?

vssadmin list writers

Slightly different error to the one you're seeing, but this bug recommends only backing up 10 SGs at a time.

http://support.netapp.com/NOW/cgi-bin/bol?Type=Detail&Display=380995

Also see: https://kb.netapp.com/support/index?page=content&id=1010785

I've also seen vss errors resolved by re-installed SnapDrive.

Cheers,

Graeme

[harpreetsingh]

Hello,

we have 7 exchange server in our envorinonment,  Each exchange server has 36 Exchange database.Only i am facing the issue only on the single exchange server, and I have created the schedules on using the 6 backup schedules with 6DB in each backup scheduled, still i am getting the same Error. Also as part of troubleshooting i have reinstalled the SD two times. the current status of all the writers are Stable.

[harpreetsingh]

hello,

i have re-runned the backups and got with following error messages

[12:50:31.564]  Creating backup components object...
[12:50:31.564]  Backup components object created.

[12:50:31.580]  Initializing the backup components object for backup...
[12:50:31.580]  Setting up backup context...
[12:50:31.783]  Setting up database backup type: Full Backup...

[12:50:31.783]  Backup components object initialized for backup operations.

[12:50:31.783]  ---------- Gathering writer metadata ---------------

[12:50:31.783]  Starting asynchronous GatherWriterMetadata. Please wait...
[12:50:32.783]  Operation pending, please wait...(1)
[12:50:37.783]  Operation pending, please wait...(2)
[12:50:42.783]  Operation pending, please wait...(3)
[12:50:47.783]  Operation pending, please wait...(4)
[12:50:50.783]  Asynchronous GatherWriterMetadata finished.
[12:50:50.783]  Getting the list of Writer Metadata Documents provided to VSS...

[12:50:50.783]  Number of writers that responded: 10.
[12:50:50.783]  Starting snapshot set...

[12:50:55.783]  Snapshot set startup in progress...(1)
[12:51:00.782]  Snapshot set startup in progress...(2)
[12:51:05.782]  Snapshot set startup in progress...(3)
[12:51:10.782]  Snapshot set startup in progress...(4)
[12:51:15.782]  Snapshot set startup in progress...(5)
[12:51:20.782]  Snapshot set startup in progress...(6)
[12:51:25.782]  Snapshot set startup in progress...(7)
[12:51:30.781]  Snapshot set startup in progress...(8)
[12:51:35.781]  Snapshot set startup in progress...(9)
[12:51:40.781]  Snapshot set startup in progress...(10)
[12:51:45.781]  Snapshot set startup in progress...(11)
[12:51:50.780]  Snapshot set startup in progress...(12)

[12:51:51.780]  Error in calling VSS API: Error code = 0x80042316
Error description: VSS_E_SNAPSHOT_SET_IN_PROGRESS

[12:45:29.648]  Failed to perform VSS snapshot, aborting...
[12:45:29.711]  Backup is aborted.

[12:45:29.711]  ----------------------------------------------------
[12:45:29.711]  SnapManager will retry VSS backup within [5] minutes, please wait...
[12:45:59.711]  Please wait...

[12:50:31.564]  ----------------- Initializing ---------------------

[12:50:31.564]  Creating backup components object...
[12:50:31.564]  Backup components object created.

[12:50:31.580]  Initializing the backup components object for backup...
[12:50:31.580]  Setting up backup context...
[12:50:31.783]  Setting up database backup type: Full Backup...

[12:50:31.783]  Backup components object initialized for backup operations.

[12:50:31.783]  ---------- Gathering writer metadata ---------------

[12:50:31.783]  Starting asynchronous GatherWriterMetadata. Please wait...
[12:50:32.783]  Operation pending, please wait...(1)
[12:50:37.783]  Operation pending, please wait...(2)
[12:50:42.783]  Operation pending, please wait...(3)
[12:50:47.783]  Operation pending, please wait...(4)
[12:50:50.783]  Asynchronous GatherWriterMetadata finished.
[12:50:50.783]  Getting the list of Writer Metadata Documents provided to VSS...

[12:50:50.783]  Number of writers that responded: 10.
[12:50:50.783]  Starting snapshot set...

[12:50:55.783]  Snapshot set startup in progress...(1)
[12:51:00.782]  Snapshot set startup in progress...(2)
[12:51:05.782]  Snapshot set startup in progress...(3)
[12:51:10.782]  Snapshot set startup in progress...(4)
[12:51:15.782]  Snapshot set startup in progress...(5)
[12:51:20.782]  Snapshot set startup in progress...(6)
[12:51:25.782]  Snapshot set startup in progress...(7)
[12:51:30.781]  Snapshot set startup in progress...(8)
[12:51:35.781]  Snapshot set startup in progress...(9)
[12:51:40.781]  Snapshot set startup in progress...(10)
[12:51:45.781]  Snapshot set startup in progress...(11)
[12:51:50.780]  Snapshot set startup in progress...(12)

[12:51:51.780]  Error in calling VSS API: Error code = 0x80042316
Error description: VSS_E_SNAPSHOT_SET_IN_PROGRESS

[12:51:51.780]  ----------------------------------------------------
[12:51:51.780]  SnapManager will retry VSS backup within [5] minutes, please wait...

[13:26:41.941]  ------------ Creating the snapshot -----------------

[13:26:41.941]  Starting asynchronous PrepareForBackup. Please wait...

[13:26:42.941]  Asynchronous PrepareForBackup finished.

[13:26:43.003]  Status after PrepareForBackup (10 writers)
[13:26:43.003]  Status for writer System Writer: STABLE(0x00000000)
[13:26:43.003]  Status for writer Microsoft Exchange Writer: STABLE(0x00000000)
[13:26:43.003]  Status for writer Cluster Service Writer: STABLE(0x00000000)
[13:26:43.003]  Status for writer MSDEWriter: STABLE(0x00000000)
[13:26:43.003]  Status for writer COM+ REGDB Writer: STABLE(0x00000000)
[13:26:43.003]  Status for writer WMI Writer: STABLE(0x00000000)
[13:26:43.003]  Status for writer Registry Writer: STABLE(0x00000000)
[13:26:43.003]  Status for writer Event Log Writer: STABLE(0x00000000)
[13:26:43.003]  Status for writer Removable Storage Manager: STABLE(0x00000000)
[13:26:43.003]  Status for writer IIS Metabase Writer: STABLE(0x00000000)

[13:26:43.003]  Starting asynchronous DoSnapshotSet. Please wait...

[13:26:44.019]  Operation pending, please wait...(1)
[13:26:49.018]  Operation pending, please wait...(2)
[13:26:54.018]  Operation pending, please wait...(3)
[13:26:59.018]  Operation pending, please wait...(4)
[13:27:04.018]  Operation pending, please wait...(5)
[13:27:09.017]  Operation pending, please wait...(6)
[13:27:14.017]  Operation pending, please wait...(7)
[13:27:19.017]  Operation pending, please wait...(8)
[13:27:24.017]  Operation pending, please wait...(9)
[13:27:29.016]  Operation pending, please wait...(10)
[13:27:34.016]  Operation pending, please wait...(11)
[13:27:39.016]  Operation pending, please wait...(12)
[13:27:44.016]  Operation pending, please wait...(13)
[13:27:49.015]  Operation pending, please wait...(14)
[13:27:54.015]  Operation pending, please wait...(15)
[13:27:59.015]  Operation pending, please wait...(16)
[13:28:04.015]  Operation pending, please wait...(17)
[13:28:09.014]  Operation pending, please wait...(18)
[13:28:14.014]  Operation pending, please wait...(19)
[13:28:19.014]  Operation pending, please wait...(20)
[13:28:24.014]  Operation pending, please wait...(21)
[13:28:29.013]  Operation pending, please wait...(22)
[13:28:34.013]  Operation pending, please wait...(23)
[13:28:39.013]  Operation pending, please wait...(24)
[13:28:44.013]  Operation pending, please wait...(25)
[13:28:49.012]  Operation pending, please wait...(26)
[13:28:54.012]  Operation pending, please wait...(27)
[13:28:59.012]  Operation pending, please wait...(28)
[13:29:04.012]  Operation pending, please wait...(29)
[13:29:09.011]  Operation pending, please wait...(30)
[13:29:14.011]  Operation pending, please wait...(31)
[13:29:19.011]  Operation pending, please wait...(32)
[13:29:24.010]  Operation pending, please wait...(33)
[13:29:29.010]  Operation pending, please wait...(34)
[13:29:34.010]  Operation pending, please wait...(35)
[13:29:39.010]  Operation pending, please wait...(36)
[13:29:44.009]  Operation pending, please wait...(37)
[13:29:49.009]  Operation pending, please wait...(38)
[13:29:54.009]  Operation pending, please wait...(39)
[13:29:59.009]  Operation pending, please wait...(40)
[13:30:04.008]  Operation pending, please wait...(41)
[13:30:09.008]  Operation pending, please wait...(42)
[13:30:14.008]  Operation pending, please wait...(43)
[13:30:19.008]  Operation pending, please wait...(44)
[13:30:24.007]  Operation pending, please wait...(45)
[13:30:29.007]  Operation pending, please wait...(46)
[13:30:34.007]  Operation pending, please wait...(47)
[13:30:39.007]  Operation pending, please wait...(48)
[13:30:44.006]  Operation pending, please wait...(49)
[13:30:49.006]  Operation pending, please wait...(50)
[13:30:54.006]  Operation pending, please wait...(51)
[13:30:59.006]  Operation pending, please wait...(52)
[13:31:04.005]  Operation pending, please wait...(53)
[13:31:09.005]  Operation pending, please wait...(54)
[13:31:14.005]  Operation pending, please wait...(55)
[13:31:19.005]  Operation pending, please wait...(56)
[13:31:24.004]  Operation pending, please wait...(57)
[13:31:29.004]  Operation pending, please wait...(58)
[13:31:34.004]  Operation pending, please wait...(59)
[13:31:39.004]  Operation pending, please wait...(60)
[13:31:44.003]  Operation pending, please wait...(61)
[13:31:49.003]  Operation pending, please wait...(62)
[13:31:54.003]  Operation pending, please wait...(63)
[13:31:59.003]  Operation pending, please wait...(64)
[13:32:04.002]  Operation pending, please wait...(65)
[13:32:09.002]  Operation pending, please wait...(66)
[13:32:14.002]  Operation pending, please wait...(67)
[13:32:19.002]  Operation pending, please wait...(68)
[13:32:24.001]  Operation pending, please wait...(69)
[13:32:29.001]  Operation pending, please wait...(70)
[13:32:34.001]  Operation pending, please wait...(71)
[13:32:39.001]  Operation pending, please wait...(72)
[13:32:44.000]  Operation pending, please wait...(73)
[13:32:49.000]  Operation pending, please wait...(74)
[13:32:54.000]  Operation pending, please wait...(75)
[13:32:58.999]  Operation pending, please wait...(76)
[13:33:03.999]  Operation pending, please wait...(77)
[13:33:08.999]  Operation pending, please wait...(78)
[13:33:13.999]  Operation pending, please wait...(79)
[13:33:18.998]  Operation pending, please wait...(80)
[13:33:23.998]  Operation pending, please wait...(81)
[13:33:28.998]  Operation pending, please wait...(82)
[13:33:33.998]  Operation pending, please wait...(83)
[13:33:38.997]  Operation pending, please wait...(84)
[13:33:43.997]  Operation pending, please wait...(85)
[13:33:48.997]  Operation pending, please wait...(86)
[13:33:53.997]  Operation pending, please wait...(87)
[13:33:58.996]  Operation pending, please wait...(88)
[13:34:03.996]  Operation pending, please wait...(89)
[13:34:08.996]  Operation pending, please wait...(90)
[13:34:13.996]  Operation pending, please wait...(91)
[13:34:18.995]  Operation pending, please wait...(92)
[13:34:23.995]  Operation pending, please wait...(93)
[13:34:28.995]  Operation pending, please wait...(94)
[13:34:33.995]  Operation pending, please wait...(95)
[13:34:38.994]  Operation pending, please wait...(96)
[13:34:43.994]  Operation pending, please wait...(97)
[13:34:48.994]  Operation pending, please wait...(98)
[13:34:53.994]  Operation pending, please wait...(99)
[13:34:58.993]  Operation pending, please wait...(100)
[13:35:03.993]  Operation pending, please wait...(101)
[13:35:08.993]  Operation pending, please wait...(102)
[13:35:13.993]  Operation pending, please wait...(103)
[13:35:18.992]  Operation pending, please wait...(104)
[13:35:23.992]  Operation pending, please wait...(105)
[13:35:28.992]  Operation pending, please wait...(106)
[13:35:33.992]  Operation pending, please wait...(107)
[13:35:38.991]  Operation pending, please wait...(108)
[13:35:43.991]  Operation pending, please wait...(109)
[13:35:48.991]  Operation pending, please wait...(110)
[13:35:53.991]  Operation pending, please wait...(111)
[13:35:58.990]  Operation pending, please wait...(112)
[13:36:03.990]  Operation pending, please wait...(113)
[13:36:08.990]  Operation pending, please wait...(114)
[13:36:13.989]  Operation pending, please wait...(115)
[13:36:18.989]  Operation pending, please wait...(116)
[13:36:23.989]  Operation pending, please wait...(117)
[13:36:28.989]  Operation pending, please wait...(118)
[13:36:33.988]  Operation pending, please wait...(119)
[13:36:38.988]  Operation pending, please wait...(120)
[13:36:43.988]  Operation pending, please wait...(121)
[13:36:43.988]  Asynchronous call DoSnapshotSet timed out!
[13:36:43.988]  The call for DoSnapshotSet was cancelled.

[13:36:43.988]  Failed to perform VSS snapshot, aborting...
[13:36:44.050]  Backup is aborted.

[13:36:44.050]  CLEANUP FAILED BACKUP

[14:28:15.220]  Rename SnapInfo folder from and to:
[14:28:15.220]  Q:\safex1mail4_sg4l\SME_SnapInfo\EXCH__SAFEX1MAIL4\SG__EU - ITA Storage Group - 04\12-04-2012_23.04.09__Daily
[14:28:15.220]  Q:\safex1mail4_sg4l\SME_SnapInfo\EXCH__SAFEX1MAIL4\SG__EU - ITA Storage Group - 04\SAFEX1MAIL4__recent
[14:28:15.220]  Rename succesfully completed.

[14:28:15.220]  Error Code: 0xc00413be
VSS asynchronous call timed out.

[14:29:58.715]  Error code: 0xc00413be, VSS asynchronous call timed out.

[14:29:58.715]  *****BACKUP DETAIL SUMMARY*****

[14:29:58.715]  Backup group set #1:

[14:29:58.715]  Backup SG/DB [EU - ITA Storage Group - 23] Error: VSS asynchronous call timed out.

[14:29:58.715]  Backup SG/DB [EU - ITA Storage Group - 24] Error: VSS asynchronous call timed out.

[niyaz]

Hello Harpreet,

Is there any other applications leveraging VSS running on the problematic server?

Regards,

Niyaz

[harpreetsingh]

Hello Niyaz,

No other application uses the VSS, this server is purely exchange server with SME and SD installed with Exchange 2007 on windows 2003 x64

Regards,

Harpreet

[niyaz]

Harpreet,

Could you please try restarting "Volume Shadow Copy" service.

Regards,

Niyaz

[harpreetsingh]

Hello Niyaz,

Volume Shadow copy server is manual type in the server.

howeveri have restarted the service couple of times before each backup. But the backup is againg failed.

[qzhang]

Have you checked Exchange related event logs (such as MSExchangeIS, type Exchange VSS Writer) and SDW related event logs? Normally those event logs could tell you what the issue is.

Thanks,

-Qing

[harpreetsingh]

hello Qing

i am unable to relate the issue with the event logs, can u specify the which events are most generated and i need to monotor,

since i am unable t perforn the ful clean backups.

[qzhang]

You will need to check the error and warning event log entries in application event log during the period of the backup. For example, in your original post, check the error and warning events from 09:09:28.458 to 09:19:29.427.

[09:09:28.458]  Starting asynchronous DoSnapshotSet. Please wait...

...

[09:19:29.427]  Asynchronous call DoSnapshotSet timed out!

We are not monitoring events there, but check the events after the backup failed to figure out the root cause of the problem. The event you are looking for MAY look like this:

Log Name:      Application

Source:        VSS

Date:          2/7/2012 6:43:18 PM

Event ID:      8194

Task Category: None

Level:         Error

Keywords:      Classic

User:          N/A

Computer:      test.test.lab.test.com

Description:

Volume Shadow Copy Service error: Unexpected error querying for the IVssWriterCallback interface.  hr = 0x80070005. This is often caused by incorrect security settings in either the writer or requestor process.

Operation:

   Gathering Writer Data

[harpreetsingh]

following vss error are generated during the backup failure,

Event Type: Error
Event Source: VSS
Event Category: None
Event ID: 12298
Date:  12/10/2012
Time:  8:34:42 PM
User:  N/A
Computer: SAFEX1NODE7
Description:
Volume Shadow Copy Service error: The I/O writes cannot be held during the shadow copy creation period on volume Q:\Safex1mail4_sg22l\. The volume index in the shadow copy set is 0. Error details: Open[0x00000000], Flush[0x00000000], Release[0x00000000], OnRun[0x8000ffff].

For more information, see Help and Support Center at http://go.microsoft.com/fwlink/events.asp.
Data:
0000: 2d 20 43 6f 64 65 3a 20   - Code:
0008: 43 4f 52 4c 4f 56 4c 43   CORLOVLC
0010: 30 30 30 30 30 39 36 34   00000964
0018: 2d 20 43 61 6c 6c 3a 20   - Call:
0020: 43 4f 52 4c 4f 56 4c 43   CORLOVLC
0028: 30 30 30 30 30 38 36 36   00000866
0030: 2d 20 50 49 44 3a 20 20   - PID: 
0038: 30 30 30 31 33 35 30 30   00013500
0040: 2d 20 54 49 44 3a 20 20   - TID: 
0048: 30 30 30 31 32 31 30 34   00012104
0050: 2d 20 43 4d 44 3a 20 20   - CMD: 
0058: 43 3a 5c 57 49 4e 44 4f   C:\WINDO
0060: 57 53 5c 53 79 73 74 65   WS\Syste
0068: 6d 33 32 5c 76 73 73 76   m32\vssv
0070: 63 2e 65 78 65 20 20 20   c.exe  
0078: 2d 20 55 73 65 72 3a 20   - User:
0080: 4e 54 20 41 55 54 48 4f   NT AUTHO
0088: 52 49 54 59 5c 53 59 53   RITY\SYS
0090: 54 45 4d 20 20 20 20 20   TEM    
0098: 2d 20 53 69 64 3a 20 20   - Sid: 
00a0: 53 2d 31 2d 35 2d 31 38   S-1-5-18

Event Type: Error
Event Source: VSS
Event Category: None
Event ID: 8193
Date:  12/10/2012
Time:  9:45:32 PM
User:  N/A
Computer: SAFEX1NODE7
Description:
Volume Shadow Copy Service error: Unexpected error calling routine IVssCoordCallback::SetContent.  hr = 0x80042301.

For more information, see Help and Support Center at http://go.microsoft.com/fwlink/events.asp.
Data:
0000: 2d 20 43 6f 64 65 3a 20   - Code:
0008: 43 4f 52 48 57 50 57 43   CORHWPWC
0010: 30 30 30 30 30 38 38 33   00000883
0018: 2d 20 43 61 6c 6c 3a 20   - Call:
0020: 43 4f 52 48 57 50 57 43   CORHWPWC
0028: 30 30 30 30 30 37 30 35   00000705
0030: 2d 20 50 49 44 3a 20 20   - PID: 
0038: 30 30 30 30 39 35 35 36   00009556
0040: 2d 20 54 49 44 3a 20 20   - TID: 
0048: 30 30 30 31 32 37 33 36   00012736
0050: 2d 20 43 4d 44 3a 20 20   - CMD: 
0058: 43 3a 5c 57 49 4e 44 4f   C:\WINDO
0060: 57 53 5c 53 79 73 74 65   WS\Syste
0068: 6d 33 32 5c 76 73 73 76   m32\vssv
0070: 63 2e 65 78 65 20 20 20   c.exe  
0078: 2d 20 55 73 65 72 3a 20   - User:
0080: 4e 54 20 41 55 54 48 4f   NT AUTHO
0088: 52 49 54 59 5c 53 59 53   RITY\SYS
0090: 54 45 4d 20 20 20 20 20   TEM    
0098: 2d 20 53 69 64 3a 20 20   - Sid: 
00a0: 53 2d 31 2d 35 2d 31 38   S-1-5-18