Newsgroups: bit.listserv.banyan-l
Date:         Tue, 25 Aug 1992 11:10:44 PST
From:         Gerry Przybyszewski <Gerry_Przybyszewski@CCM.HF.INTEL.COM>
Subject:      Weird BANYAN Backups problem!!!!!!!
X-To:         BANYAN-L@vm1.cc.uakron.edu, BANYAN-L@akronvm.BITNET

To whoever may be able to help.

I have a Banyan server (486 33MHz 12MB) running vines 4.11(5).

The problem is that although tape backups are scheduled for 22:00 they are not
kicked off until 24:00 and even then not performed.

I have stayed and watched at 22:00 and nothing happens.

Before the obvious is suggested the console screens I have been at are the
Service monitor and the main menu screen with both failing to allow backups.
Manual backups work fine.  They are full system backups.

Here is the relevant section from the offending servers system log :

=============================================================
       Server name         : EIS SW CCMAIL1
       Serial number       : 2500225
       Software Revision   : 4.11 (5)
       Machine Type        : V386
       Log report started  : Tue Aug 25 15:19:01 BST 1992
       Events logged after : 8/20/92,00:00
       Events logged before: 8/22/92,01:00
       Services selected   : SYSTEM
=============================================================
=============================================================
       System Logs
=============================================================
07:19:04 [0] 1000 "Server Log" 0 Thu Aug 20 07:19:04 1992
09:19:04 [1] Server operator using backup/restore.  Services are running.
09:21:29 [1] Server operator using backup/restore.  Services are running.
00:01:09 [1] Scheduled backup time detected.
00:01:09 [1] Scheduled backup being started.  Services are running.
00:01:09 [1] Scheduled backup returned.
00:06:22 [1] Scheduled backup time detected.
00:06:22 [1] Scheduled backup being started.  Services are running.
00:06:22 [1] Scheduled backup returned.
00:11:35 [1] Scheduled backup time detected.
00:11:35 [1] Scheduled backup being started.  Services are running.
00:11:36 [1] Scheduled backup returned.
00:16:48 [1] Scheduled backup time detected.
00:16:48 [1] Scheduled backup being started.  Services are running.
00:16:48 [1] Scheduled backup returned.
00:22:01 [1] Scheduled backup time detected.
00:22:01 [1] Scheduled backup being started.  Services are running.
00:22:01 [1] Scheduled backup returned.
00:27:14 [1] Scheduled backup time detected.
00:27:14 [1] Scheduled backup being started.  Services are running.
00:27:14 [1] Scheduled backup returned.
00:32:27 [1] Scheduled backup time detected.
00:32:27 [1] Scheduled backup being started.  Services are running.
00:32:28 [1] Scheduled backup returned.
00:37:40 [1] Scheduled backup time detected.
00:37:40 [1] Scheduled backup being started.  Services are running.
00:37:40 [1] Scheduled backup returned.
00:42:53 [1] Scheduled backup time detected.
00:42:53 [1] Scheduled backup being started.  Services are running.
00:42:53 [1] Scheduled backup returned.
00:48:06 [1] Scheduled backup time detected.
00:48:06 [1] Scheduled backup being started.  Services are running.
00:48:06 [1] Scheduled backup returned.
00:53:19 [1] Scheduled backup time detected.
00:53:19 [1] Scheduled backup being started.  Services are running.
00:53:19 [1] Scheduled backup returned.
00:58:32 [1] Scheduled backup time detected.
00:58:32 [1] Scheduled backup being started.  Services are running.
00:58:33 [1] Scheduled backup returned.
01:00:45 [1] Scheduled backup time detected.
01:00:45 [1] Scheduled backup being started.  Services are running.
01:00:46 [1] Scheduled backup returned.
01:05:58 [1] Scheduled backup time detected.
01:05:58 [1] Scheduled backup being started.  Services are running.
01:05:59 [1] Scheduled backup returned.
01:11:11 [1] Scheduled backup time detected.
01:11:11 [1] Scheduled backup being started.  Services are running.
01:11:12 [1] Scheduled backup returned.
01:16:24 [1] Scheduled backup time detected.
01:16:24 [1] Scheduled backup being started.  Services are running.
01:16:24 [1] Scheduled backup returned.
01:21:37 [1] Scheduled backup time detected.
01:21:37 [1] Scheduled backup being started.  Services are running.
01:21:37 [1] Scheduled backup returned.
01:26:50 [1] Scheduled backup time detected.
01:26:50 [1] Scheduled backup being started.  Services are running.
01:26:51 [1] Scheduled backup returned.
01:32:03 [1] Scheduled backup time detected.
01:32:03 [1] Scheduled backup being started.  Services are running.
01:32:04 [1] Scheduled backup returned.
01:37:16 [1] Scheduled backup time detected.
01:37:16 [1] Scheduled backup being started.  Services are running.
01:37:17 [1] Scheduled backup returned.
01:42:29 [1] Scheduled backup time detected.
01:42:29 [1] Scheduled backup being started.  Services are running.
01:42:29 [1] Scheduled backup returned.
01:47:43 [1] Scheduled backup time detected.
01:47:43 [1] Scheduled backup being started.  Services are running.
01:47:44 [1] Scheduled backup returned.
01:52:56 [1] Scheduled backup time detected.
01:52:56 [1] Scheduled backup being started.  Services are running.
01:52:56 [1] Scheduled backup returned.
01:58:09 [1] Scheduled backup time detected.
01:58:09 [1] Scheduled backup being started.  Services are running.
01:58:09 [1] Scheduled backup returned.
=============================================================
       Watchdog Log
=============================================================

*** Thu Aug 20 22:06:22 1992

22:06:22 Scheduled backup signaled
22:11:35 Scheduled backup signaled
22:16:48 Scheduled backup signaled
22:22:01 Scheduled backup signaled
22:27:14 Scheduled backup signaled
22:32:27 Scheduled backup signaled
22:37:40 Scheduled backup signaled
22:42:53 Scheduled backup signaled
22:48:06 Scheduled backup signaled
22:53:19 Scheduled backup signaled
22:58:32 Scheduled backup signaled
23:00:45 Scheduled backup signaled
23:05:58 Scheduled backup signaled
23:11:11 Scheduled backup signaled
23:16:24 Scheduled backup signaled
23:21:37 Scheduled backup signaled
23:26:50 Scheduled backup signaled
23:32:03 Scheduled backup signaled
23:37:16 Scheduled backup signaled
23:42:29 Scheduled backup signaled
23:47:43 Scheduled backup signaled
23:52:56 Scheduled backup signaled
23:58:09 Scheduled backup signaled
=============================================================
       Server Patch History
=============================================================
This server was fresh installed with 4.11 (0) on Fri Aug 14 09:45:24 GMT 1992
Fri Aug 14 14:02:33 BST 1992
Vines Maintenance Release 4.11 (5) Applied
Fri Aug 14 14:58:24 BST 1992
4.11 Site-Specific Patch 4.11-AJ-1
Fri Aug 14 15:00:03 BST 1992
4.11 Site-Specific Patch 4.11-AK-1
Fri Aug 14 15:00:37 BST 1992
4.11(5) Site Specific Patch 4.11-BF-1
Fri Aug 14 15:01:28 BST 1992
4.11(5) Site-Specific Patch 411-BR-1
Fri Aug 14 15:02:41 BST 1992
4.11(5) Site Specific Patch 4.11-CP-1
Fri Aug 14 13:10:18 BST 1992
5.00(1) Site-Specific Patch 5.00-AC-1
Fri Aug 14 13:11:13 BST 1992
VINES 4.11(5) Site-Spefic Patch BE-1

Why does the backup kickoff at 00:00 instead of the scheduled time of 22:00?
Why do those 3 messages appear at 5 minute intervals for just under 2 hours and
then stop?
Why does the watchdog log disagree with the scheduled backup times?

Here is an extract from a server that backs up well.

=============================================================
       Server name         : OISM SW 034
       Serial number       : 2901405
       Software Revision   : 4.11 (5)
       Machine Type        : V386
       Log report started  : Tue Aug 25 17:33:08 BST 1992
       Events logged after : 8/20/92,00:00
       Events logged before: 8/22/92,01:00
       Services selected   : SYSTEM
=============================================================
=============================================================
       System Logs
=============================================================
22:01:07 [0] 1000 "Server Log" 0 Thu Aug 20 22:01:07 1992
22:01:07 [1] Scheduled backup time detected.
22:01:07 [1] Scheduled backup being started.  Services are running.
23:35:38 [1] Scheduled backup returned.
22:01:12 [0] 1000 "Server Log" 0 Fri Aug 21 22:01:12 1992
22:01:12 [1] Scheduled backup time detected.
22:01:12 [1] Scheduled backup being started.  Services are running.
23:41:49 [1] Scheduled backup returned.
=============================================================
       Watchdog Log
=============================================================
*** Tue Aug  4 20:09:18 1992

20:09:18 Watchdog process started.
22:00:34 Scheduled backup signaled

*** Wed Aug  5 22:00:47 1992

22:00:47 Scheduled backup signaled

*** Thu Aug  6 22:00:46 1992

22:00:46 Scheduled backup signaled

*** Fri Aug  7 22:00:42 1992

22:00:42 Scheduled backup signaled

*** Mon Aug 10 22:00:52 1992

22:00:52 Scheduled backup signaled

*** Tue Aug 11 22:00:48 1992

22:00:48 Scheduled backup signaled

*** Wed Aug 12 22:03:31 1992

22:03:31 Scheduled backup signaled

*** Thu Aug 13 22:01:00 1992

22:01:00 Scheduled backup signaled

*** Fri Aug 14 22:00:57 1992

22:00:57 Scheduled backup signaled

*** Mon Aug 17 22:01:07 1992

22:01:07 Scheduled backup signaled

*** Tue Aug 18 22:01:02 1992

22:01:02 Scheduled backup signaled

*** Wed Aug 19 22:00:46 1992

22:00:46 Scheduled backup signaled

*** Thu Aug 20 22:01:07 1992

22:01:07 Scheduled backup signaled

*** Fri Aug 21 22:01:12 1992

22:01:12 Scheduled backup signaled

*** Mon Aug 24 22:01:03 1992

22:01:03 Scheduled backup signaled
=============================================================
       Server Patch History
=============================================================
This server was upgraded to 4.11 (0) on Fri Nov  1 23:19:55 GMT 1991
Tue Aug  4 20:00:28 BST 1992
Vines Maintenance Release 4.11 (5) Applied
Tue Aug  4 20:14:33 BST 1992
4.11 Site-Specific Patch 4.11-AK-1
Tue Aug  4 20:17:02 BST 1992
4.11(5) Site-Specific Patch 411-AR-1


Any help/insight appreciated.

Gerry

Internet : Gerry_Przybyszewski@CCM.HF.INTEL.COM
Comments: Gated by NETNEWS@AUVM.AMERICAN.EDU