CONTAP-824188: Effiency metadata grows after enabling storage efficiency for a DP volume
Issue
- The command "volume efficiency show" reports "op-status" as "Active" on a DP volume for a very long time without making any progress but only showing "status" stuck at "Fingerprint is being recovered from volume (0% complete)"
- The efficiency log (logs/sis from SPI interface) shows a VERIFY operation being triggered from an incoming snapmirror operation ("sis start vault") very short after efficiency was enabled on the affected DP volume:
Sat Feb 21 19:27:08 GMT 2026 [Vserver UUID: 9379110f-...] /vol/affected_volume [sid: 0] Enable
Sat Feb 21 19:30:11 GMT 2026 [Vserver UUID: 9379110f-...] /vol/affected_volume [sid: 1771702211] Begin (sis start vault)
Sat Feb 21 19:30:11 GMT 2026 [Vserver UUID: 9379110f-...] /vol/affected_volume [sid: 1771702211] Verify - The efficiency operation then loops on every incoming snapmirror update, never able to complete the "Generating aggr FPDB from volume FPDB" stage:
Sat Feb 21 00:30:04 GMT 2026 [Vserver UUID: 439ca6bc-ac3d-11ee-96d7-d039ea39693c] /vol/affected_volume [sid: 0] Info (Dedupe operation is pausing)
Sat Feb 21 00:30:05 GMT 2026 [Vserver UUID: 439ca6bc-ac3d-11ee-96d7-d039ea39693c] /vol/affected_volume [sid: 1769885118] Stats (blks gathered 0,finger prints sorted 0,dups found 0,new dups found 0,blks deduped 0,finger prints checked 0,finger prints deleted 0)
Sat Feb 21 00:30:05 GMT 2026 [Vserver UUID: 439ca6bc-ac3d-11ee-96d7-d039ea39693c] /vol/affected_volume [sid: 0] Error (Operation was stopped )
Sat Feb 21 00:30:19 GMT 2026 [Vserver UUID: 439ca6bc-ac3d-11ee-96d7-d039ea39693c] /vol/affected_volume [sid: 0] Info (sis start vault)
Sat Feb 21 00:30:19 GMT 2026 [Vserver UUID: 439ca6bc-ac3d-11ee-96d7-d039ea39693c] /vol/affected_volume [sid: 1769885118] Sis Restart (Restarting from Sorting stage )
Sat Feb 21 00:30:19 GMT 2026 [Vserver UUID: 439ca6bc-ac3d-11ee-96d7-d039ea39693c] /vol/affected_volume [sid: 1769885118] Begin (sis start vault)
Sat Feb 21 00:30:19 GMT 2026 [Vserver UUID: 439ca6bc-ac3d-11ee-96d7-d039ea39693c] /vol/affected_volume [sid: 0] Info (Generating aggr FPDB from volume FPDB)
...
Sat Feb 21 00:45:08 GMT 2026 [Vserver UUID: 9379110f-b1b2-11ee-96d7-d039ea39693c] /vol/affected_volume [sid: 0] Info (sis stop vault)
Sat Feb 21 00:45:08 GMT 2026 [Vserver UUID: 9379110f-b1b2-11ee-96d7-d039ea39693c] /vol/affected_volume [sid: 0] Info (Dedupe operation is pausing)
Sat Feb 21 00:45:08 GMT 2026 [Vserver UUID: 9379110f-b1b2-11ee-96d7-d039ea39693c] /vol/affected_volume [sid: 1769887822] Stats (blks gathered 0,finger prints sorted 0,dups found 0,new dups found 0,blks deduped 0,finger prints checked 0,finger prints deleted 0)
Sat Feb 21 00:45:08 GMT 2026 [Vserver UUID: 9379110f-b1b2-11ee-96d7-d039ea39693c] /vol/affected_volume [sid: 0] Error (Operation was stopped )
Sat Feb 21 00:45:24 GMT 2026 [Vserver UUID: 9379110f-b1b2-11ee-96d7-d039ea39693c] /vol/affected_volume [sid: 0] Info (sis start vault)
Sat Feb 21 00:45:24 GMT 2026 [Vserver UUID: 9379110f-b1b2-11ee-96d7-d039ea39693c] /vol/affected_volume [sid: 1769887822] Sis Restart (Restarting from Sorting stage )
Sat Feb 21 00:45:24 GMT 2026 [Vserver UUID: 9379110f-b1b2-11ee-96d7-d039ea39693c] /vol/affected_volume [sid: 1769887822] Begin (sis start vault)
Sat Feb 21 00:45:24 GMT 2026 [Vserver UUID: 9379110f-b1b2-11ee-96d7-d039ea39693c] /vol/affected_volume [sid: 0] Info (Generating aggr FPDB from volume FPDB)
<looping potentially forever, unless the operation would be able to complete before the next snapmirror update>
- Over time, the "Deduplication Metadata" from command "volume show-footprint" for the affected volume keeps on growing unexpectedly high and can even consume most of the volume aggregate footprint. In this example already 61% of the volume footprint is consumed by Deduplication metadata:
::> volume show-footprint -volume affected_volume
Deduplication Metadata 77.3TB 61%
Temporary Deduplication 77.3TB 61%
Cross Volume Deduplication 4.00KB 0%
