ADSM-L

Strange behavior

2000-12-14 11:42:33
Subject: Strange behavior
From: "Gill, Geoffrey L." <GEOFFREY.L.GILL AT SAIC DOT COM>
Date: Thu, 14 Dec 2000 08:39:26 -0800
I have 13 servers acting very strangely all of a sudden. The schedule has
many more servers in it, 42 total. None have been moved or upgraded and they
are not on the same switch. They all just happen to be the last 13 computers
that ADSM goes out to start on it's schedule each night. They range in TSM
level from 3.1.07 to 3.7.2.16. The DSM error log shows no strange entries
but the dsmsched log and the ADSM log looks as if someone had stopped and
started the schedule service 17 times after the computer completed it's
schedule. Here is a copy of what I see in the ADSM log, this happens for
each of the clients:

12/13/00 23:59:39     ANR2561I Schedule prompter contacting Client1
                       (session 568) to start a scheduled operation.
12/14/00 00:01:00     ANR2561I Schedule prompter contacting Client1
                       (session 634) to start a scheduled operation.
12/14/00 00:01:48     ANR2561I Schedule prompter contacting Client1
                       (session 648) to start a scheduled operation.
12/14/00 00:02:32     ANR2561I Schedule prompter contacting Client1
                       (session 663) to start a scheduled operation.
12/14/00 00:03:17     ANR2561I Schedule prompter contacting Client1
                       (session 677) to start a scheduled operation.
12/14/00 00:04:01     ANR2561I Schedule prompter contacting Client1
                       (session 691) to start a scheduled operation.
12/14/00 00:04:45     ANR2561I Schedule prompter contacting Client1
                       (session 711) to start a scheduled operation.
12/14/00 00:05:28     ANR2561I Schedule prompter contacting Client1
                       (session 725) to start a scheduled operation.
12/14/00 00:06:11     ANR2561I Schedule prompter contacting Client1
                       (session 741) to start a scheduled operation.
12/14/00 00:06:54     ANR2561I Schedule prompter contacting Client1
                       (session 755) to start a scheduled operation.
12/14/00 00:07:38     ANR2561I Schedule prompter contacting Client1
                       (session 772) to start a scheduled operation.
12/14/00 00:08:21     ANR2561I Schedule prompter contacting Client1
                       (session 789) to start a scheduled operation.
12/14/00 00:09:04     ANR2561I Schedule prompter contacting Client1
                       (session 809) to start a scheduled operation.
12/14/00 00:09:47     ANR2561I Schedule prompter contacting Client1
                       (session 826) to start a scheduled operation.
12/14/00 00:10:30     ANR2561I Schedule prompter contacting Client1
                       (session 840) to start a scheduled operation.
12/14/00 00:11:13     ANR2561I Schedule prompter contacting Client1
                       (session 856) to start a scheduled operation.
12/14/00 00:11:56     ANR2561I Schedule prompter contacting Client1
                       (session 872) to start a scheduled operation.

12/14/00 00:12:35     ANR8214E Session open with xxx.xxx.xxx.xxx failed due
to
                       connection refusal.

12/14/00 00:12:35     ANR2716E Schedule prompter was not able to contact
client
                       Client1 using type 1 (xxx.xxx.xxx.xxx 1501).

12/14/00 00:15:29     ANR0406I Session 891 started for node Client1 (WinNT)
                       (Tcp/Ip xxx.xxx.xxx.xxx(4213)).
12/14/00 00:18:03     ANR0406I Session 902 started for node Client1 (WinNT)
                       (Tcp/Ip xxx.xxx.xxx.xxx(4267)).

12/14/00 00:27:31     ANR0403I Session 902 ended for node Client1 (WinNT).
12/14/00 00:27:32     ANE4952I (Session: 891, Node: Client1)  Total number
                       of objects inspected:   11,271

12/14/00 00:27:32     ANE4954I (Session: 891, Node: Client1)  Total number
                       of objects backed up:       25

12/14/00 00:27:32     ANE4958I (Session: 891, Node: Client1)  Total number
                       of objects updated:          0

12/14/00 00:27:32     ANE4960I (Session: 891, Node: Client1)  Total number
                       of objects rebound:          0

12/14/00 00:27:32     ANE4957I (Session: 891, Node: Client1)  Total number
                       of objects deleted:          0

12/14/00 00:27:32     ANE4970I (Session: 891, Node: Client1)  Total number
                       of objects expired:          0

12/14/00 00:27:32     ANE4959I (Session: 891, Node: Client1)  Total number
                       of objects failed:          24

12/14/00 00:27:32     ANE4961I (Session: 891, Node: Client1)  Total number
                       of bytes transferred:     2.93 MB

12/14/00 00:27:32     ANE4963I (Session: 891, Node: Client1)  Data transfer
                       time:                    0.21 sec

12/14/00 00:27:32     ANE4966I (Session: 891, Node: Client1)  Network data
                       transfer rate:        13,742.83 KB/sec

12/14/00 00:27:32     ANE4967I (Session: 891, Node: Client1)  Aggregate
                       data transfer rate:          4.16 KB/sec

12/14/00 00:27:32     ANE4968I (Session: 891, Node: Client1)  Objects
                       compressed by:                   73%

12/14/00 00:27:32     ANE4964I (Session: 891, Node: Client1)  Elapsed
                       processing time:            00:12:03

12/14/00 00:27:32     ANR0403I Session 891 ended for node Client1 (WinNT).
12/14/00 00:27:33     ANR0406I Session 983 started for node Client1 (WinNT)
                       (Tcp/Ip 139.121.174.49(4463)).

12/14/00 00:27:33     ANR0403I Session 983 ended for node Client1 (WinNT).
12/14/00 00:27:33     ANR0406I Session 984 started for node Client1 (WinNT)
                       (Tcp/Ip 139.121.174.49(4465)).

12/14/00 00:27:33     ANR0403I Session 984 ended for node Client1 (WinNT).
12/14/00 00:27:33     ANR0406I Session 985 started for node Client1 (WinNT)
                       (Tcp/Ip 139.121.174.49(4466)).

12/14/00 00:27:33     ANR0403I Session 985 ended for node Client1 (WinNT).
12/14/00 00:27:33     ANR0406I Session 986 started for node Client1 (WinNT)
                       (Tcp/Ip 139.121.174.49(4467)).

12/14/00 00:27:34     ANR0403I Session 986 ended for node Client1 (WinNT).
12/14/00 00:27:34     ANR0406I Session 987 started for node Client1 (WinNT)
                       (Tcp/Ip 139.121.174.49(4468)).

12/14/00 00:27:34     ANR0403I Session 987 ended for node Client1 (WinNT).
12/14/00 00:27:34     ANR0406I Session 988 started for node Client1 (WinNT)
                       (Tcp/Ip 139.121.174.49(4469)).

12/14/00 00:27:34     ANR0403I Session 988 ended for node Client1 (WinNT).
12/14/00 00:27:34     ANR0406I Session 989 started for node Client1 (WinNT)
                       (Tcp/Ip 139.121.174.49(4470)).

12/14/00 00:27:34     ANR0403I Session 989 ended for node Client1 (WinNT).
12/14/00 00:27:34     ANR0406I Session 990 started for node Client1 (WinNT)
                       (Tcp/Ip 139.121.174.49(4471)).

12/14/00 00:27:34     ANR0403I Session 990 ended for node Client1 (WinNT).
12/14/00 00:27:34     ANR0406I Session 991 started for node Client1 (WinNT)
                       (Tcp/Ip 139.121.174.49(4472)).

12/14/00 00:27:35     ANR0403I Session 991 ended for node Client1 (WinNT).
12/14/00 00:27:35     ANR0406I Session 992 started for node Client1 (WinNT)
                       (Tcp/Ip 139.121.174.49(4473)).

12/14/00 00:27:35     ANR0403I Session 992 ended for node Client1 (WinNT).
12/14/00 00:27:35     ANR0406I Session 993 started for node Client1 (WinNT)
                       (Tcp/Ip 139.121.174.49(4474)).

12/14/00 00:27:35     ANR0403I Session 993 ended for node Client1 (WinNT).
12/14/00 00:27:35     ANR0406I Session 994 started for node Client1 (WinNT)
                       (Tcp/Ip 139.121.174.49(4475)).

12/14/00 00:27:35     ANR0403I Session 994 ended for node Client1 (WinNT).
12/14/00 00:27:35     ANR0406I Session 995 started for node Client1 (WinNT)
                       (Tcp/Ip 139.121.174.49(4476)).

12/14/00 00:27:36     ANR0403I Session 995 ended for node Client1 (WinNT).
12/14/00 00:27:36     ANR0406I Session 996 started for node Client1 (WinNT)
                       (Tcp/Ip 139.121.174.49(4477)).

12/14/00 00:27:36     ANR0403I Session 996 ended for node Client1 (WinNT).
12/14/00 00:27:36     ANR0406I Session 997 started for node Client1 (WinNT)
                       (Tcp/Ip 139.121.174.49(4478)).

12/14/00 00:27:36     ANR0403I Session 997 ended for node Client1 (WinNT).
12/14/00 00:27:36     ANR0406I Session 998 started for node Client1 (WinNT)
                       (Tcp/Ip 139.121.174.49(4479)).

12/14/00 00:27:37     ANR0403I Session 998 ended for node Client1 (WinNT).
12/14/00 00:27:37     ANR0406I Session 999 started for node Client1 (WinNT)
                       (Tcp/Ip 139.121.174.49(4480)).

12/14/00 00:27:37     ANR0403I Session 999 ended for node Client1 (WinNT).

Has anyone ever seen this before?

ADSM 3.1.2.55 on AIX 4.3.3

Thanks,

Geoff Gill
NT Systems Support Engineer
SAIC
Computer Systems Group
E-Mail:   gillg AT saic DOT com
Phone:  (858) 826-4062
Pager:   (888) 997-9614
<Prev in Thread] Current Thread [Next in Thread>