NDMPcopy creates unexpected duplicate files "rst_was"
Applies to
- ONATP 9
- NDMP
- NDMPcopy
- NDMP incremental
Issue
- NDMPcopy full dump - level 0 is running without issues
- NDMPcopy incremental is creating duplicate files for some files
- Example:
rst Wed Sep 13 10:46:29 CEST 2023 /SVM/vol1/ Start (Level 0, NDMP:12345)
rst Wed Sep 13 10:46:29 CEST 2023 /SVM/vol1/ Options (b=0, r, y, H, TCP recv buffer size = 33580, TCP send buffer size = 33580)
rst Wed Sep 13 10:46:29 CEST 2023 /SVM/vol1/ Tape_open (ndmp)
rst Wed Sep 13 10:46:29 CEST 2023 /SVM/vol1/ Log_msg (Number of constituents = 4)
rst Wed Sep 13 10:53:01 CEST 2023 /SVM/vol1/ Phase_change (Dirs)
rst Wed Sep 13 10:53:02 CEST 2023 /SVM/vol1/ Warning (Unable to unlink the file . :Undefined error: 0)
rst Wed Sep 13 10:53:02 CEST 2023 /SVM/vol1/ Warning (Cannot create directory ./.copy_offload: File exists)
rst Wed Sep 13 10:53:59 CEST 2023 /SVM/vol1/ Warning (Original ./dir/dir1/dir2/file1.txt on filesystem was replaced by an entry with the same name. Moving original entry to ./dir/dir1/dir2/rst_was_file1.txt
rst Wed Sep 13 10:53:59 CEST 2023 /SVM/vol1/ Warning (Original ./dir/dir1/dir2/file2.txt on filesystem was replaced by an entry with the same name. Moving original entry to ./dir/dir1/dir2/rst_was_file2.txt
rst Wed Sep 13 10:55:03 CEST 2023 /SVM/vol1/ Warning (Original ./dir/dir1/dir3/file3.txt on filesystem was replaced by an entry with the same name. Moving original entry to ./dir/dir1/dir3/rst_was_file3.txt
rst Wed Sep 13 10:55:07 CEST 2023 /SVM/vol1/ Warning (Original ./dir/dir1/dir3/file4.txt on filesystem was replaced by an entry with the same name. Moving original entry to ./dir/dir1/dir3/rst_was_file4.txt
rst Wed Sep 13 10:55:08 CEST 2023 /SVM/vol1/ Warning (Original ./dir/dir1/dir3/file5.txt on filesystem was replaced by an entry with the same name. Moving original entry to ./dir/dir1/dir3/rst_was_file5.txt
rst Wed Sep 13 10:55:10 CEST 2023 /SVM/vol1/ Warning (Original ./dir/dir1/dir3/file6.txt on filesystem was replaced by an entry with the same name. Moving original entry to ./dir/dir1/dir3/rst_was_file6.txt
rst Wed Sep 13 10:55:11 CEST 2023 /SVM/vol1/ Warning (Original ./dir/dir1/dir3/file7.txt on filesystem was replaced by an entry with the same name. Moving original entry to ./dir/dir1/dir3/rst_was_file7.txt
rst Wed Sep 13 10:55:22 CEST 2023 /SVM/vol1/ Warning (Original ./dir/dir1/dir3/file8.txt on filesystem was replaced by an entry with the same name. Moving original entry to ./dir/dir1/dir3/rst_was_file8.txt
rst Wed Sep 13 10:55:28 CEST 2023 /SVM/vol1/ Warning (Original ./dir/dir1/dir3/file9.txt on filesystem was replaced by an entry with the same name. Moving original entry to ./dir/dir1/dir3/rst_was_file9.txt
rst Wed Sep 13 10:55:28 CEST 2023 /SVM/vol1/ Warning (Original ./dir/dir1/dir4/file9.txt on filesystem was replaced by an entry with the same name. Moving original entry to ./dir/dir1/dir4/rst_was_file9.txt
rst Wed Sep 13 10:55:31 CEST 2023 /SVM/vol1/ Warning (Original ./dir/dir1/dir5/file9.txt on filesystem was replaced by an entry with the same name. Moving original entry to ./dir/dir1/dir5/rst_was_file9.txt
rst Wed Sep 13 10:55:34 CEST 2023 /SVM/vol1/ Warning (Original ./dir/dir1/dir6/file9.txt on filesystem was replaced by an entry with the same name. Moving original entry to ./dir/dir1/dir6/rst_was_file9.txt
rst Wed Sep 13 10:55:41 CEST 2023 /SVM/vol1/ Warning (Original ./dir/dir1/dir7/file9.txt on filesystem was replaced by an entry with the same name. Moving original entry to ./dir/dir1/dir7/rst_was_file9.txt
rst Wed Sep 13 10:56:08 CEST 2023 /SVM/vol1/ Warning (Original ./dir/dir1/dir8/file9.txt on filesystem was replaced by an entry with the same name. Moving original entry to ./dir/dir1/dir8/rst_was_file9.txt
rst Wed Sep 13 10:57:06 CEST 2023 /SVM/vol1/ Phase_change (Files)
rst Wed Sep 13 10:59:59 CEST 2023 /SVM/vol1/ Warning (File ./.copy_offload/.tokens not created on the destination.)
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (Total P2 threads (8))
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (P2 thread 0: time (sec) (192), dirs processed (1188))
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (P2 thread 1: time (sec) (192), dirs processed (1470))
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (P2 thread 2: time (sec) (192), dirs processed (1196))
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (P2 thread 3: time (sec) (192), dirs processed (1280))
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (P2 thread 4: time (sec) (192), dirs processed (1306))
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (P2 thread 5: time (sec) (192), dirs processed (1139))
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (P2 thread 6: time (sec) (192), dirs processed (1285))
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (P2 thread 7: time (sec) (192), dirs processed (1241))
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (VM clrimap : wait_time (sec) (1), lookup_time (sec) (2))
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (VM dumpmap : wait_time (sec) (7), lookup_time (sec) (19))
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (VM inomap : wait_time (sec) (11), lookup_time (sec) (94))
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (VM inomap : Cache hits (88884459), Cache misses (1405664))
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (VM basemap : wait_time (sec) (14), lookup_time (sec) (51))
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (VM filemap : wait_time (sec) (17), lookup_time (sec) (40))
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (VM dirmap : wait_time (sec) (0), lookup_time (sec) (4))
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (VM inotab : wait_time (sec) (0), lookup_time (sec) (1))
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (Phase 1 percent spent in reading from media: 12%)
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (Phase 1 total time (sec): 392)
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (Phase 2 total time (sec): 244)
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (Phase 2 time (sec) spent in purgatory deletion: 47)
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (Phase 3 percent spent in reading from media: 77%)
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (Phase 3 total time (sec): 1077)
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (Phase 3 total number of master node files: 12401)
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (Phase 3 total time(sec) to write master files: 545)
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (Phase 3 total number of remote node files: 12236)
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (Phase 3 total time(sec) to write remote files: 336)
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (Phase 3 total number of synchronous writes of max bufs: 0)
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (Phase 3 total number of asynchronous writes of max bufs: 7135)
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (Phase 3 total number of synchronous writes of bufs: 0)
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (Phase 3 total number of asynchronous writes of bufs: 207760)
rst Wed Sep 13 11:15:03 CEST 2023 /SVM/vol1/ Log_msg (Phase 3 total number of synchronous writes of unaligned bufs: 7198)
rst Wed Sep 13 11:15:04 CEST 2023 /SVM/vol1/ Tape_close (ndmp)
rst Wed Sep 13 11:15:04 CEST 2023 /SVM/vol1/ End (28572 files, 223479 MB processed, 5 MB skipped, 1715 secs, 130 MBps)