Scheduled deduplication job stops at saving_sharing phase

Last updated

Apr 30, 2024
Save as PDF
Share
1. Share
2. Tweet
3. Share

Views:: 432

Visibility:: Public

Votes:: 0

Category:: fas-systems

Specialty:: core

Last Updated:: 4/30/2024, 8:53:13 PM

Applies to

ONTAP 9.3
ONTAP 9.4

Issue

Scheduled deduplication job stops at saving_sharing phase.
It finally causes change log full.

Example:

SIS -STATUS-L.xml

Checkpoint Stage: Saving_sharing

SIS log

Fri Nov 27 00:00:00 2020 [Vserver UUID: de5d877b-eb36-xxxx-xxxx-xxxxxxxx] /vol/vol1 [sid: 0] Info (sis start schedule)

Fri Nov 27 00:00:00 2020 [Vserver UUID: de5d877b-eb36-xxxx-xxxx-xxxxxxxx] /vol/vol1 [sid: 1606402800] Begin (schedule)

Fri Nov 27 00:00:00 2020 [Vserver UUID: de5d877b-eb36-xxxx-xxxx-xxxxxxxx] /vol/vol1 [sid: 1606402800] Sort (44048338 fp entries)

Fri Nov 27 00:01:51 2020 [Vserver UUID: de5d877b-eb36-xxxx-xxxx-xxxxxxxx] /vol/vol1 [sid: 1606402800] Dedup Pass1 (1777260 dup entries)

Fri Nov 27 00:01:55 2020 [Vserver UUID: de5d877b-eb36-xxxx-xxxx-xxxxxxxx] /vol/vol1 [sid: 1606402800] Dedup Pass2 (366008 dup entries)

Fri Nov 27 00:01:55 2020 [Vserver UUID: de5d877b-eb36-xxxx-xxxx-xxxxxxxx] /vol/vol1 [sid: 1606402800] Sharing (0 return status)

Fri Nov 27 00:02:19 2020 [Vserver UUID: de5d877b-eb36-xxxx-xxxx-xxxxxxxx] /vol/vol1 [sid: 1606402939] Begin (sis auto check)

Fri Nov 27 00:02:19 2020 [Vserver UUID: de5d877b-eb36-xxxx-xxxx-xxxxxxxx] /vol/vol1 [sid: 1606402939] Verify

Fri Nov 27 00:04:31 2020 [Vserver UUID: de5d877b-eb36-xxxx-xxxx-xxxxxxxx] /vol/vol1 [sid: 1606402939] Merge (39729004 stale entries)

Fri Nov 27 00:05:21 2020 [Vserver UUID: de5d877b-eb36-xxxx-xxxx-xxxxxxxx] /vol/vol1 [sid: 1606402939] Sis Verify Phase End

Fri Nov 27 00:05:21 2020 [Vserver UUID: de5d877b-eb36-xxxx-xxxx-xxxxxxxx] /vol/vol1 [sid: 1606402939] Stats (blks gathered 0,finger prints sorted 135854173,dups found 1777260,new dups found 366008,blks deduped 1845128,finger prints checked 134906749,finger prints deleted 39729004)

Fri Nov 27 00:05:21 2020 [Vserver UUID: de5d877b-eb36-xxxx-xxxx-xxxxxxxx] /vol/vol1 [sid: 1606402939] End (176193352 KB)

Sat Nov 28 00:00:00 2020 [Vserver UUID: de5d877b-eb36- xxxx-xxxx-xxxxxxxx] /vol/vol1 [sid: 0] Info (sis start schedule)

Sat Nov 28 00:00:00 2020 [Vserver UUID: de5d877b-eb36- xxxx-xxxx-xxxxxxxx] /vol/vol1 [sid: 1606489200] Begin (schedule)

Sat Nov 28 00:00:00 2020 [Vserver UUID: de5d877b-eb36- xxxx-xxxx-xxxxxxxx] /vol/vol1 [sid: 1606489200] Sort (120963811 fp entries)

Sat Nov 28 00:03:41 2020 [Vserver UUID: de5d877b-eb36- xxxx-xxxx-xxxxxxxx] /vol/vol1 [sid: 1606489200] Dedup Pass1 (10670398 dup entries)

Sat Nov 28 00:03:58 2020 [Vserver UUID: de5d877b-eb36- xxxx-xxxx-xxxxxxxx] /vol/vol1 [sid: 1606489200] Dedup Pass2 (14344642 dup entries)

Sat Nov 28 00:04:19 2020 [Vserver UUID: de5d877b-eb36- xxxx-xxxx-xxxxxxxx] /vol/vol1 [sid: 1606489200] Sharing (0 return status)

<<< It stops here

Sun Nov 29 00:00:00 2020 [Vserver UUID: de5d877b-eb36- xxxx-xxxx-xxxxxxxx] /vol/vol1 [sid: 0] Info (sis start schedule)

Mon Nov 30 00:00:00 2020 [Vserver UUID: de5d877b-eb36- xxxx-xxxx-xxxxxxxx] /vol/vol1 [sid: 0] Info (sis start schedule)

Tue Dec 1 00:00:00 2020 [Vserver UUID: de5d877b-eb36- xxxx-xxxx-xxxxxxxx] /vol/vol1 [sid: 0] Info (sis start schedule)

...

Wed Jul 14 00:00:00 2021 [Vserver UUID: de5d877b-eb36- xxxx-xxxx-xxxxxxxx] /vol/vol1 [sid: 0] Info (sis start schedule)

EMS log

[?] Wed Jul 14 10:48:00 [controller-01: dense_split_worker: sis.changelog.full:error]: SIS change logging metafile for volume vol1@vserver:de5d877b-eb36-xxxx-xxxx-xxxxxxxx is full.