I'm currently investigating some issues with backups taking a long time and I am writing some scripts to report back how long backups take.  A few things have came up and I wondered if anyone could give me some input.

Firstly, the savepnpc logs when it finishes its pre-processing and also when it begins its post-processing; I have yet to see the results of this (from another team!) but I am told the results we are seeing are 16 hours for a backup.

I am analyzing the mminfo output to find duration of backup based on the earliest start time of the savesets (nsavetime) in the backup and the latest completion time (sscomp); this is done by converting sscomp to a UNIX timestamp then subtracting nsavetime from sscomp(timestamp) to give a time in seconds.  This gives a result in the region of 20-25 hours (I have checked this against human readable dates and these are reasonable values based on mminfo output).

I was wondering if anyone has any suggestions as to why we are seeing such a big anomaly here between these two values (I hope to have some further data shortly from the savepnpc logging to shed more light on this!)

The second part of my query is I want to do similar for reporting on clone times; however there is no completion time details.  However after looking at the values mminfo gives out I suspect that I can get this information by taking the clonetime value as the time a clone completes, and the cloneretent with retention time subtracted being the time a clone kicks off.  Anyone have any ideas on this one?