Tree Posted April 22, 2009 Report Share Posted April 22, 2009 I have had a few of my scripts, defined to run at times like 11 PM or 7 AM, and at varying intervals (weekly, 2 days, etc.) showing up in the Activities panel as having completed at around 2 AM. It isn't every script; just 4 of them this morning. It isn't a certain kind of script, as I've defined multiple types (Copy, Backup) with various Sources and Media Sets. These are scripts that were not scheduled to run at that time, some not even scheduled to run that day. Yet somehow, they were chosen to run at that time. Any ideas? Quote Link to comment Share on other sites More sharing options...
Mayoff Posted April 22, 2009 Report Share Posted April 22, 2009 Are you sure you didn't have any script still running from the day before that finished at 2am? Is it possible the computer running the engine was sleeping at the scheduled backup time? When you view the logs, does it show the script starting at 2am or finishing at 2? Quote Link to comment Share on other sites More sharing options...
Tree Posted April 22, 2009 Author Report Share Posted April 22, 2009 After studying the logs in a little more detail, this is the picture that develops. I had one script that started at 9:30 PM last night and did not complete until after 2 AM, which was on Execution Unit 1. Three other small backups went ahead and ran when they were scheduled to, between 11:50 PM and midnight, running on Exec. Units 2 and 3. However, the 4 scripts that I mentioned in the initial email did not run until after the "big" one finished, even though they ran on both Exec. Unit 1 and Exec. Unit 2. Not sure why none of them chose to run on Exec. Unit 3, which was free after midnight, nor why those that ran on 2 had to wait around. Another curiosity is that one of these scripts was not even scheduled to be run until 10 PM tonight (it runs weekly), yet it was run anyways. Attached is some greater detail; I've deleted specific computer names and shortened some reports: [color:blue] + Duplicate using ccProjects at 4/21/2009 9:30 PM (Execution unit 1)ccProjects at 4/21/2009 9:30 PM (Execution unit 1)] 4/21/2009 9:30:01 PM: Connected to Server To volume ccProjects1... 4/21/2009 9:30:01 PM: Copying Projects on Server *File "PATH/NAME_DELETED.pdf": can't write, error -2350 (%s)] 4/21/2009 11:46:13 PM: Comparing ccProjects1 4/22/2009 2:02:51 AM: 1 execution errors Completed: 52834 files, 71.3 GB Performance: 544.9 MB/minute (556.0 copy, 534.2 compare) Duration: 04:32:48 (00:04:56 idle/loading/preparing) SMTP: opening addr 74.208.5.2 (MORE TO FOLLOW)[/color] Quote Link to comment Share on other sites More sharing options...
Tree Posted April 22, 2009 Author Report Share Posted April 22, 2009 CONTINUED FROM ABOVE [color:blue]+ Normal backup using EmailBackup at 4/22/2009 2:02 AM (Execution unit 1) To Media Set EmailBackup... 4/22/2009 2:02:54 AM: Copying Mail on [COMPUTER_NAME] 4/22/2009 2:03:13 AM: Snapshot stored, 297 KB 4/22/2009 2:03:13 AM: Comparing Mail... 4/22/2009 2:08:12 AM: Execution completed successfully Total performance: 366.7 MB/minute Total duration: 00:05:14 (00:02:17 idle/loading/preparing)[/color] COMMENT: Scripted to run daily at 11 PM, appears to have waited for Exec. Unit 1 to free up. [color:blue] + Normal backup using ConfigState at 4/22/2009 2:08 AM (Execution unit 2) To Media Set ConfigBackup... 4/22/2009 2:08:13 AM: Copying Retrospect 4/22/2009 2:08:23 AM: Snapshot stored, 80 KB 4/22/2009 2:08:24 AM: Comparing Retrospect 4/22/2009 2:08:25 AM: Execution completed successfully Quote Link to comment Share on other sites More sharing options...
Tree Posted April 22, 2009 Author Report Share Posted April 22, 2009 [color:blue] Completed: 4 files, 71.0 MB Performance: 851.1 MB/minute (472.8 copy, 4255.8 compare) Duration: 00:00:12 (00:00:01 idle/loading/preparing)[/color] COMMENT: Scripted to run weekly, starting 1 AM on 4/22 (next backup=4/29), seems to have waited for Exec. Unit 1 to free up. [color:blue] + Duplicate using ccLibrary at 4/22/2009 2:08 AM (Execution unit 1)ccLibrary at 4/22/2009 2:08 AM (Execution unit 1)] 4/22/2009 2:08:13 AM: Connected to Server 4/22/2009 2:08:13 AM: Connected to [COMPUTER_NAME] To volume ccLibrary1 on [COMPUTER_NAME]... 4/22/2009 2:08:13 AM: Copying Library on Server *File "PATH/NAME_DELETED": can't write, error -1014 ( name already exists)PATH/NAME_DELETED": can't write, error -1014 (%s)]...[/color] COMMENT: (all files gave same error "can't write", changed this morning from Overwrite Older Files to Overwrite Matching Files and re-ran, successful) Scripted to run at 10 PM starting 4/22 (i.e. tonight) on a weekly basis [color:blue] + Normal backup using BackupMRR at 4/22/2009 2:08 AM (Execution unit 2) To Media Set compMRR... 4/22/2009 2:08:26 AM: Copying [COMPUTER_NAME] 4/22/2009 2:08:27 AM: Snapshot stored, 202 KB 4/22/2009 2:08:28 AM: Comparing [COMPUTER_NAME] 4/22/2009 2:08:28 AM: Execution completed successfully Quote Link to comment Share on other sites More sharing options...
Tree Posted April 22, 2009 Author Report Share Posted April 22, 2009 [color:blue] Completed: 1 files, 3 KB Performance: 0.3 MB/minute (0.1 copy, 0.1 compare) Duration: 00:00:02 (00:00:02 idle/loading/preparing)[/color] COMMENT: Scripted to run every 2 days at 11:57 PM, starting 4/21 (i.e. last night); this is a backup of files on the Retrospect Server itself Three other scripts that were scheduled to run just before midnight last night and did, in fact, run at that time (all complete before midnight). Two of these ran on Exec. Unit 2, while one ran on Exec. Unit 3. APOLOGIES for the multiple posts; it kept truncating the full post so I just posted as much as it would accept. Quote Link to comment Share on other sites More sharing options...
Recommended Posts
Join the conversation
You can post now and register later. If you have an account, sign in now to post with your account.