Unsolved
1 Rookie
•
1 Message
0
4328
April 21st, 2021 12:00
retrying mmd reservation request!!!
Hello Community,
i have the problem to clone my save sets. Sometimes it works. But usally i have to retry it. The log said always:
21.04.2021 17:59:55 Retry reservation after it was rejected. Retry counter [4]
21.04.2021 17:59:55 Sending cancel broker request 532
21.04.2021 17:59:55 Request 532 was cancelled.
21.04.2021 18:00:05 retrying mmd reservation request!!!
21.04.2021 18:00:15 Retry reservation after it was rejected. Retry counter [3]
21.04.2021 18:00:15 Sending cancel broker request 533
21.04.2021 18:00:15 Request 533 was cancelled.
21.04.2021 18:00:25 retrying mmd reservation request!!!
21.04.2021 18:00:35 Retry reservation after it was rejected. Retry counter [2]
21.04.2021 18:00:35 Sending cancel broker request 534
21.04.2021 18:00:35 Request 534 was cancelled.
21.04.2021 18:00:45 retrying mmd reservation request!!!
21.04.2021 18:00:55 Retry reservation after it was rejected. Retry counter [1]
21.04.2021 18:00:55 Sending cancel broker request 535
21.04.2021 18:00:55 Request 535 was cancelled.
21.04.2021 18:01:05 retrying mmd reservation request!!!
21.04.2021 18:01:15 Retry reservation after it was rejected. Retry counter [0]
21.04.2021 18:01:15 Sending cancel broker request 536
21.04.2021 18:01:15 Request 536 was cancelled.
21.04.2021 18:01:25 retrying mmd reservation request!!!
21.04.2021 18:01:35 Sending cancel broker request 537
21.04.2021 18:01:35 Request 537 was cancelled.
21.04.2021 18:01:45 retrying mmd reservation request!!!
21.04.2021 18:01:55 Sending cancel broker request 538
21.04.2021 18:01:55 Request 538 was cancelled.
21.04.2021 18:02:05 retrying mmd reservation request!!!
21.04.2021 18:02:15 Sending cancel broker request 539
21.04.2021 18:02:15 Request 539 was cancelled.
21.04.2021 18:02:25 retrying mmd reservation request!!!
21.04.2021 18:02:35 Sending cancel broker request 540
21.04.2021 18:02:35 Request 540 was cancelled.
21.04.2021 18:02:45 retrying mmd reservation request!!!
21.04.2021 18:02:55 Sending cancel broker request 541
21.04.2021 18:02:55 Request 541 was cancelled.
21.04.2021 18:03:05 retrying mmd reservation request!!!
21.04.2021 18:03:15 Sending cancel broker request 542
21.04.2021 18:03:15 Request 542 was cancelled.
21.04.2021 18:03:25 retrying mmd reservation request!!!
And i dont undestand what it means.
here the ful log:
07.04.2021 03:24:20 Step (1 of 16): NSRCLONE_INIT_PHASE_ONE: nsrclone has been started on host 'networker.cloudi.de', with command 'C:\Program Files\EMC NetWorker\nsr\bin\nsrclone.exe -a *policy name=CLone -a *policy workflow name=Clone Tresor VM -a *policy action name=clone -s networker -b Kopie-Tresor -J networker -y 1 Years -w 1 Months -o -F -S '.
07.04.2021 03:24:20 04/07/21 03:24:20.378616 ============== nsrcloneconfig parameters ===============================
07.04.2021 03:24:20 04/07/21 03:24:20.378616 max_threads_per_client(0) is default value.
07.04.2021 03:24:20 04/07/21 03:24:20.378616 max_savesets_per_thread(0) is default value.
07.04.2021 03:24:20 04/07/21 03:24:20.378616 max_client_threads(0) is default value.
07.04.2021 03:24:20 04/07/21 03:24:20.378616 mmd_reservation_timeout(0) is default value.
07.04.2021 03:24:20 04/07/21 03:24:20.378616 disable_vsr(0) is default value.
07.04.2021 03:24:20 04/07/21 03:24:20.378616 concurrent_clone_enabled(1) is default value.
07.04.2021 03:24:20 04/07/21 03:24:20.378616 ams_enabled(0) is default value.
07.04.2021 03:24:20 04/07/21 03:24:20.378616 ams_slice_size_factor(31) is default value.
07.04.2021 03:24:20 04/07/21 03:24:20.378616 ams_preferred_slice_count(0) is default value.
07.04.2021 03:24:20 04/07/21 03:24:20.378616 ams_min_concurrent_slice_count(1) is default value.
07.04.2021 03:24:20 04/07/21 03:24:20.378616 ams_max_concurrent_slice_count(60) is default value.
07.04.2021 03:24:20 04/07/21 03:24:20.378616 ams_force_multithreaded_slicecopies(0) is default value.
07.04.2021 03:24:20 04/07/21 03:24:20.378616 co_use_ip_if(0) is default value.
07.04.2021 03:24:20 04/07/21 03:24:20.378616 ========================================================================
07.04.2021 03:24:20 Step (2 of 16): NSRCLONE_INIT_PHASE_TWO: Obtaining nsrclone options.
07.04.2021 03:24:20 Step (3 of 16): NSRCLONE_INIT_PHASE_THREE: Performing Recover Pipe to Save disabled cloning.
07.04.2021 03:24:20 Action clone 'clone' has initialized as 'clone job' with job id 2151054
07.04.2021 03:24:20 Step (4 of 16): NSRCLONE_INIT_PHASE_FOUR: Validating the command line arguments.
07.04.2021 03:24:21 Obtaining all available input work items.
07.04.2021 03:24:21 Step (5 of 16): NSRCLONE_PROCESSING_PHASE_ONE: Submitting all save sets for Recover Pipe to Save processing.
07.04.2021 03:24:21 RPS is not enabled but VM savesets are detected, will use RPS framework for cloning of these savesets
07.04.2021 03:24:21 Step (6 of 16): NSRCLONE_PROCESSING_PHASE_TWO: Obtaining the save set ID and clone ID for the respective save sets.
07.04.2021 03:24:21 107783615, 124560830,
07.04.2021 03:24:21 141337888, 158115083,
07.04.2021 03:24:21 174892235, 191669388,
07.04.2021 03:24:21 208446384, 225223590,
07.04.2021 03:24:21 23897986, 242000785,
07.04.2021 03:24:21 258777980, 275554976,
07.04.2021 03:24:21 292332023, 309109164,
07.04.2021 03:24:21 325886380, 40675109,
07.04.2021 03:24:21 4201425267, 4218202461,
07.04.2021 03:24:21 4234979627, 4251756761,
07.04.2021 03:24:21 4268533944, 4285311078,
07.04.2021 03:24:21 57452175, 7120791,
07.04.2021 03:24:21 74229267, 91006461;
07.04.2021 03:24:21 Step (7 of 16): NSRCLONE_PROCESSING_PHASE_THREE: Found the recover storage node 'networker.cloudi.de' for source volume '5564328'.
07.04.2021 03:24:21 107783615/1617733054, 124560830/1617733054,
07.04.2021 03:24:21 141337888/1617732896, 158115083/1617732875,
07.04.2021 03:24:21 174892235/1617732811, 191669388/1617732748,
07.04.2021 03:24:21 208446384/1617732528, 225223590/1617732518,
07.04.2021 03:24:21 23897986/1617733506, 242000785/1617732497,
07.04.2021 03:24:21 258777980/1617732476, 275554976/1617732256,
07.04.2021 03:24:21 292332023/1617732087, 309109164/1617732012,
07.04.2021 03:24:21 325886380/1617732012, 40675109/1617733413,
07.04.2021 03:24:21 4201425267/1617734003, 4218202461/1617733981,
07.04.2021 03:24:21 4234979627/1617733931, 4251756761/1617733849,
07.04.2021 03:24:21 4268533944/1617733816, 4285311078/1617733734,
07.04.2021 03:24:21 57452175/1617733263, 7120791/1617733527,
07.04.2021 03:24:21 74229267/1617733139, 91006461/1617733117;
07.04.2021 03:24:27 Retry reservation after it was rejected. Retry counter [4]
07.04.2021 03:24:27 Sending cancel broker request 67
07.04.2021 03:24:27 Request 67 was cancelled.
07.04.2021 03:24:37 retrying mmd reservation request!!!
07.04.2021 03:34:48 Sending cancel broker request 68
07.04.2021 03:34:48 Request 68 was cancelled.
07.04.2021 03:34:48 retrying mmd reservation request!!!
07.04.2021 03:44:58 Sending cancel broker request 69
07.04.2021 03:44:58 Request 69 was cancelled.
07.04.2021 03:44:58 retrying mmd reservation request!!!
07.04.2021 03:55:19 Sending cancel broker request 70
....
....
07.04.2021 09:10:51 retrying mmd reservation request!!!
07.04.2021 09:11:01 Sending cancel broker request 310
07.04.2021 09:11:01 Request 310 was cancelled.
07.04.2021 09:11:11 retrying mmd reservation request!!!
07.04.2021 09:11:21 Sending cancel broker request 311
07.04.2021 09:11:21 Request 311 was cancelled.
07.04.2021 09:11:31 retrying mmd reservation request!!!
07.04.2021 09:11:41 Sending cancel broker request 312
07.04.2021 09:11:41 Request 312 was cancelled.
07.04.2021 09:11:51 retrying mmd reservation request!!!
07.04.2021 09:12:01 Sending cancel broker request 313
07.04.2021 09:12:01 Request 313 was cancelled.
07.04.2021 09:12:11 retrying mmd reservation request!!!
07.04.2021 09:12:21 Sending cancel broker request 314
07.04.2021 09:12:21 Request 314 was cancelled.
07.04.2021 09:12:31 retrying mmd reservation request!!!
07.04.2021 09:12:41 Sending cancel broker request 315
07.04.2021 09:12:41 Request 315 was cancelled.
07.04.2021 09:12:51 retrying mmd reservation request!!!
07.04.2021 09:13:02 Sending cancel broker request 316
07.04.2021 09:13:02 Request 316 was cancelled.
07.04.2021 09:13:12 retrying mmd reservation request!!!
07.04.2021 09:13:22 No more retries for reservation
07.04.2021 09:13:22 Quitting MMD reservation due to request status = REQ_REJECTED!!!
07.04.2021 09:13:22 Sending cancel broker request 317
07.04.2021 09:13:22 Request 317 was cancelled.
07.04.2021 09:13:22 NSRCLONE being aborted since broker request for mmd reservation failed with nonretryable error.
07.04.2021 09:13:22 Request 286 was cancelled.
07.04.2021 09:13:22 Failed to get mmd reservation with err: MMD reservation failed. .
07.04.2021 09:13:22 MMD reservation failed.
07.04.2021 09:13:23 No valid input savesets - check CLONE SKIPPED SAVESETS!
07.04.2021 09:13:23 RPS CLONE RESULTS \n [ORIGINAL REQUESTED SAVESETS]
07.04.2021 09:13:23 107783615, 124560830,
07.04.2021 09:13:23 141337888, 158115083,
07.04.2021 09:13:23 174892235, 191669388,
07.04.2021 09:13:23 208446384, 225223590,
07.04.2021 09:13:23 23897986, 242000785,
07.04.2021 09:13:23 258777980, 275554976,
07.04.2021 09:13:23 292332023, 309109164,
07.04.2021 09:13:23 325886380, 40675109,
07.04.2021 09:13:23 4201425267, 4218202461,
07.04.2021 09:13:23 4234979627, 4251756761,
07.04.2021 09:13:23 4268533944, 4285311078,
07.04.2021 09:13:23 57452175, 7120791,
07.04.2021 09:13:23 74229267, 91006461;
07.04.2021 09:13:23 [CLONE SKIPPED SAVESETS]
07.04.2021 09:13:23 107783615/1617733054, 124560830/1617733054,
07.04.2021 09:13:23 141337888/1617732896, 158115083/1617732875,
07.04.2021 09:13:23 174892235/1617732811, 191669388/1617732748,
07.04.2021 09:13:23 208446384/1617732528, 225223590/1617732518,
07.04.2021 09:13:23 23897986/1617733506, 242000785/1617732497,
07.04.2021 09:13:23 258777980/1617732476, 275554976/1617732256,
07.04.2021 09:13:23 292332023/1617732087, 309109164/1617732012,
07.04.2021 09:13:23 325886380/1617732012, 40675109/1617733413,
07.04.2021 09:13:23 4201425267/1617734003, 4218202461/1617733981,
07.04.2021 09:13:23 4234979627/1617733931, 4251756761/1617733849,
07.04.2021 09:13:23 4268533944/1617733816, 4285311078/1617733734,
07.04.2021 09:13:23 57452175/1617733263, 7120791/1617733527,
07.04.2021 09:13:23 74229267/1617733139, 91006461/1617733117;
07.04.2021 09:13:28 The nsrclone verification detected some failures.
07.04.2021 09:13:28 04/07/21 09:13:28.296513 Save set list count: 26, successful count: 0, failed count: 26
07.04.2021 09:13:28 04/07/21 09:13:28.296513 Total save set list: : 91006461, 74229267, 7120791, 57452175, 4285311078, 4268533944, 4251756761, 4234979627, 4218202461, 4201425267, 40675109, 325886380, 309109164, 292332023, 275554976, 258777980, 242000785, 23897986, 225223590, 208446384, 191669388, 174892235, 158115083, 141337888, 124560830, 107783615
07.04.2021 09:13:28 04/07/21 09:13:28.296513 Failed save set list: : 91006461, 74229267, 7120791, 57452175, 4285311078, 4268533944, 4251756761, 4234979627, 4218202461, 4201425267, 40675109, 325886380, 309109164, 292332023, 275554976, 258777980, 242000785, 23897986, 225223590, 208446384, 191669388, 174892235, 158115083, 141337888, 124560830, 107783615
07.04.2021 09:13:28 04/07/21 09:13:28.296513 Cloned save set list::
07.04.2021 09:13:28 Action clone 'clone' with job id 2151054 is exiting with status 'failed', exit code 1
07.04.2021 09:13:28 NSRCLONE failed for one or more savesets.
0 events found

