ADSM-L

Re: anr9999 Message on 5.1.5.1

2002-11-13 02:22:43
Subject: Re: anr9999 Message on 5.1.5.1
From: Rainer Tammer <tsm AT SPG.SCHULERGROUP DOT COM>
To: ADSM-L AT VM.MARIST DOT EDU
Date: Wed, 13 Nov 2002 08:18:52 +0100
Hello,
the same error is in 4.2.3.0. I have an open PMR for this problem.
If you turn on context reporting you will get an detailed error message.

We experienced this error on our test system.

--------- PMR ---------------- PMR ----------------
PMR 08763
TSM: Error in actlog after upgrade

Status Open
Contact Name Rainer Tammer
Last Updated 02/11/11
Severity 3
Created 02/11/02
Branch 070
Country/Region 724
Customer Number nnnnnnn
APAR Number _______

PMR Contents:


 +AIXSERV               -           -L217/ENPSER-P3S3-02/11/02-15:54--CE
*** RS/6000 SUPPORT LINE ENTITLED R1S2S3L4
*** Electronic creation by customer tammer
*** Please contact customer by  PHONE
***
***
Problem Details
 E-Mail: rainer.tammer AT spg.schulergroup DOT com
.
Abstract:
 TSM: Error in actlog after upgrade
.
Operating System:  AIX V4.3
Product Group:  ADSM (Adstar Distributed Storage Manager)
.
Environment:
TSM 4.2.3.0 Server on AIX 4.3.3.0 ML10
Windows NT Client 4.2.3.1 Windows NT 4.0 SP6a

.
Problem:
Hello,
please have a look ath the following error:

Steps:
1. Upgrade TSM Server on AIX from 4.2.2.8 to 4.2.3.0
2. Backup with 4.2.2.0 Client on NT
2. Deinstall TSM 4.2.2.0 Client  on NT
3. Install 4.2.3.1 Client on NT
4. Backup System Object
5. See the error in the act log:

11/02/02 13:52:16 ANR1142I Moving data for collocation cluster 5 of  16 on 
volume 014.
11/02/02 14:00:20 ANE4952I (Session: 135, Node: TAMMER-PPP) Total  number of 
objects inspected: 9
11/02/02 14:00:20 ANE4954I (Session: 135, Node: TAMMER-PPP) Total  number of 
objects backed up: 9
11/02/02 14:00:20 ANE4957I (Session: 135, Node: TAMMER-PPP) Total  number of 
objects deleted: 0
11/02/02 14:00:20 ANE4970I (Session: 135, Node: TAMMER-PPP) Total  number of 
objects expired: 0
11/02/02 14:01:05 ANE4955I (Session: 135, Node: TAMMER-PPP) Total  number of 
objects restored: 6
11/02/02 14:01:08 ANR9999D smnode.c(18972): ThreadId<40> Session exiting has no 
affinityId cluster
11/02/02 14:01:08 (40) Context report
11/02/02 14:01:09 (40) Thread AcceptorThread (20) is a parent thread related 
to: 40
11/02/02 14:01:09 (20) Generating TM Context Report: (struct=tmTxnDesc) 
(slots=256)
11/02/02 14:01:09 (20) *** no transactions found ***
11/02/02 14:01:09 (20) Generating Database Transaction Table Context:
11/02/02 14:01:09 (20) Tsn=0:3893866 --> Valid=1, inRollback=0, endNTA=0, 
State=2, Index=2, LatchCount=0, SavePoint=0,  TotLogRecs=0, TotLogBytes=0,
UndoLogRecs=0, UndoLogBytes=0,  LogReserve=0, PageReserve=0, Elapsed=11 (secs), 
MinLsn=0.0.0,  MaxLsn=0.0.0, LastLsn=0.0.0, UndoNextLsn=0.0.0, logWriter=False,
backupTxn=False
11/02/02 14:01:09 (20) Open objects:
11/02/02 14:01:09 (20) name ->Filespaces<- (sp=0)
11/02/02 14:01:09 (20) *** no transactions found ***
11/02/02 14:01:09 (20) Generating SM Context Report:
11/02/02 14:01:09 (20) *** no sessions found ***
11/02/02 14:01:09 (20) Generating AS Vol Context Report:
11/02/02 14:01:09 (20) No mounted (or mount in progress) volumes.
11/02/02 14:01:09 (20) Generating ssSession Context Report:
11/02/02 14:01:09 (20) No storage service sessions active.
11/02/02 14:01:09 (20) Generating ssOpenSeg Context Report:
11/02/02 14:01:09 (20) No storage service segments found.
11/02/02 14:01:09 (20) Generating BF Copy Control Context Report:
11/02/02 14:01:09 (20) No global copy control blocks.
11/02/02 14:01:09
11/02/02 14:01:09 (40) SessionThread : ANR9999D calling thread
11/02/02 14:01:09 (40) Generating TM Context Report: (struct=tmTxnDesc) 
(slots=256)
11/02/02 14:01:09 (40) slot -> 106:
11/02/02 14:01:09 (40) Tsn=0:3893866, Resurrected=False, InFlight=True, 
Distributed=False
11/02/02 14:01:09 (40) Participants=1, summaryVote=ReadOnly
11/02/02 14:01:09 Participant DB: voteReceived=False, ackReceived=False
11/02/02 14:01:09 (40) Generating Database Transaction Table Context:
11/02/02 14:01:09 (40) *** no transactions found ***
11/02/02 14:01:09 (40) Generating SM Context Report:
11/02/02 14:01:09 (40) Session 135: Type=Node, Id=TAMMER-PPP
11/02/02 14:01:09 (40) Platform=WinNT, NodeId=9, Owner=
11/02/02 14:01:09 (40) SessType=4, Index=1, TermReason=0
11/02/02 14:01:09 (40) RecvWaitTime=0.000 (samples=0)
11/02/02 14:01:09 (40) Backup Objects ( bytes ) Inserted: 0 ( 0.0 )
11/02/02 14:01:09 (40) Backup Objects ( bytes ) Restored: 7 (0.1613143 )
11/02/02 14:01:09 (40) Archive Objects ( bytes ) Inserted: 0 ( 0.0 )
11/02/02 14:01:09 (40) Archive Objects ( bytes ) Retrieved: 0 ( 0.0 )
11/02/02 14:01:09 (40) Last Verb ( SignOff ), Last Verb State ( Recv )
11/02/02 14:01:09 (40) Generating AS Vol Context Report:
11/02/02 14:01:09 (40) No mounted (or mount in progress) volumes.
11/02/02 14:01:09 (40) Generating ssSession Context Report:
11/02/02 14:01:09 (40) No storage service sessions active.
11/02/02 14:01:09 (40) Generating ssOpenSeg Context Report:
11/02/02 14:01:09 (40) No storage service segments found.
11/02/02 14:01:09 (40) Generating BF Copy Control Context Report:
11/02/02 14:01:09 (40) No global copy control blocks.
11/02/02 14:01:09
11/02/02 14:01:09 (40) End Context report

Bye
  Rainer Tammer
 +CCMS, EUROPE          -           -L200/-------P3S3-02/11/04-07:28--AI



 +CCMS, EUROPE          -           -L200/-------P3S3-02/11/04-07:28--AL
 -PAPPE, CORNELIA    EET-5698TSMAX  -L217/-------P3S3-02/11/04-07:28--AL
 -PAPPE, CORNELIA    EET-5698TSMAX  -L217/-------P3S3-02/11/04-07:29--AL
 -PAPPE, CORNELIA    EET-5698TSMAX  -L217/ENPSER-P3S3-02/11/04-07:29--CR
 S5> SERVICE GIVEN= 99  SG/99/
ccms entitled
 -MUELLER, MICHAEL   CSG-5698TSMAX  -L25X/CFETSM-P3S3-02/11/04-08:39--CT
 -MUELLER, MICHAEL   CSG-5698ISMAX  -L25X/CFETSM-P3S3-02/11/04-08:52--CR
 S5> SERVICE GIVEN= 99  SG/99/
requested actlog, dsmerror.log and q node f=d,
error occurs with backup of system objects
 +AIXSERV               -           -L203/ETR724-P3S3-02/11/04-19:53-SCE
***  Electronic update by customer
***
Additional Information:
Hello,
sent to ftp.emea.ibm.com as 08763.070.724.schuler.zip
Bye
  Rainer Tammer
 -ROBOCAD            ROB-5698ISMAX  -L225/-------P3S3-02/11/04-19:54--AT
Material received from FTP Server and stored in ECuRep (4226 bytes):
/:/proj/PMR/0/8/08763,070,724/08763.070.724.schuler.zip
 -ROBOCAD            ROB-5698ISMAX  -L203/CEADM -P3S3-02/11/04-19:54-SCG
 -ROBOCAD            ROB-5698ISMAX  -L203/CEADM -P3S3-02/11/04-19:54-S2D
 -ROBO TIVWEB        ROB-5698ISMAX  -L203/ETR724-P3S3-02/11/04-20:03-SCR
 S5> SERVICE GIVEN= 99  SG/99/
 -REUSCHER, MICHAELA SDK-5698ISMAX  -L203/SLINK -P3S3-02/11/05-07:27-SCR
 S5> SERVICE GIVEN= 99  SG/99/
pmr was on queue slink, 203 -> requeue to last queue, pls. hdl



 -MUELLER, MICHAEL   CSG-5698ISMAX  -L25X/CFETSM-P3S3-02/11/05-08:17-S2D
 +AIXSERV               -           -L203/ETR724-P3S3-02/11/08-07:23-SCE
***  Electronic update by customer
***
Additional Information:
Hello,
any news?

Bye
  Rainer Tammer
 -ROBO TIVWEB        ROB-5698ISMAX  -L203/ETR724-P3S3-02/11/08-07:28-SCR
 S5> SERVICE GIVEN= 99  SG/99/
 -REUSCHER, MICHAELA SDK-5698ISMAX  -L203/SLINK -P3S3-02/11/08-07:31-SCR
 S5> SERVICE GIVEN= 99  SG/99/
pls. use next queue field  -> pmr was on queue slink,203 -> cr to
queue cfetsm,25x
 -ZEZULKA, HANS-D.   CSG-5698ISMSV  -L25X/CFETSM-P3S3-02/11/11-09:52-SCC
 S5> SERVICE GIVEN= 99  SG/99/
close sec
 -ZEZULKA, HANS-D.   CSG-5698ISMCL  -L25X/-------P3S3-02/11/11-09:53--AL
 -MARTENS, HOLGER    AIX-5698ISMCL  -L25X/CFETSM-P3S3-02/11/11-12:44--CT
 -MARTENS, HOLGER    AIX-5698TSMAX  -L25X/CFETSM-P3S3-02/11/11-13:19--CR
 S5> SERVICE GIVEN= 99  SG/99/
to bo.
picked this one up as I can recreate the problem in my environment.
 -MARTENS, HOLGER    AIX-5698TSMAX  -L25X/CBETSM-P3S3-02/11/11-13:19--CT
 -MARTENS, HOLGER    AIX-5698TSMAX  -L25X/-------P3S3-02/11/11-13:19--AT
Informed Mr. Tammer that I can recreate the problem in my environment.
I'll pass this one over to c/t for further handling / APAR opening...



 -MARTENS, HOLGER    AIX-5698TSMAX  -L25X/CBETSM-P3S3-02/11/11-13:20--CR
 S5> SERVICE GIVEN= 99  SG/99/
 -MARTENS, HOLGER    AIX-5698TSMAX  -L25X/CBWTSM-P3S3-02/11/11-13:21--CT
 NO CONTACT IS REQUIRED

 -MARTENS, HOLGER    AIX-5698TSMAX  -L25X/-------P3S3-02/11/11-13:36--AT
Hi there,
  I can recreate the problem reported by cus in my environment as well.
For recreation I used:
TSM AIX Server 4.2.3.0 running on AIX 4.3.3 ML09
TSM Windows Client 5.1.5.0, English, running on
  Windows 2000 Professional, English
.
TSM Client settings I assume to be related to this issue:
  RESOURCEUTIL <default>
  PASSWORDACCESS GENERATE
.
TSM Server settings I assume to be related to this problem:
   MAXNUMP 999
.
Problem recreation:
(1) Open the TSM Client GUI to ARCHIVE a subdirectory containing
    some files.
    In my system it resulted into 3 client sessions opened with the
    TSM Server. Session IDs 1159, 1160, 1161.
(2) If the ARCHIVE operation ended successfully (ARCHIVE COMPLETE window
    pops up), press OK to acknowledge / close the ARCHIVE COMPLETE
    window.
(3) In the GUI go back to the screen where you can select to RETRIEVE
    data. DO NOT CLOSE THE GUI|||
    --> One client node session will end now. In my env it was ID 1161.
(4) Use the GUI to RETRIEVE some data now.
    In my test I retrieved other data than archived during this test
    to a different location, not preserving the directory structure.
    --> the retrieve finished without any problem.
(5) If the retrieve finished go ahead and close the GUI completely
    ending all client sessions.
    --> the result is
    "ANR9999D smnode.c(18972): ThreadId<29> Session exiting has no
     affinityId cluster"
    After the ANR9999D occured session ID 1160 closed.
    Session ID 1161 timed out later due to IDLETIMEOUT reached.
.
As this problem is recreatable please go ahead and open an APAR to
address this issue.
>From the source it looks like a "statistical" / messaging issue.
.
I uploaded the compressed activity log as pmr08763.actlog.Z to shasta.
By the way...the dsmerror.log does NOT contain any entry at time of
ANR9999D occurance.
Thanks and regards...Holger.
 -MARTENS, HOLGER    AIX-5698TSMAX  -L25X/CBWTSM-P3S3-02/11/11-13:43--CR
 S5> SERVICE GIVEN= 99  SG/99/

--------- PMR ---------------- PMR ----------------

Bye
  Rainer Tammer

On Tue, 12 Nov 2002 10:08:20 -0600, Brenda Collins wrote:

>Good morning!
>
>Has anyone seen this message before?
>
>11/12/02 01:04:48 AM  ANR9999D smnode.c(19810): ThreadId<17> Session
>exiting has
>                       no affinityId cluster
>
>
>
>We are running on Solaris 8, TSM 5.1.5.1 - I have called it into support
>but seeing as I have to wait for a response, I thought I would check with
>the listserv.
>
>Any help would be greatly appreciated!
>
>Thanks,
>Brenda
>

<Prev in Thread] Current Thread [Next in Thread>