ADSM-L

scheduler problem

2003-07-03 08:55:05
Subject: scheduler problem
From: Schoenleitner Thomas <thomas.schoenleitner AT SGKK.SOZVERS DOT AT>
To: ADSM-L AT VM.MARIST DOT EDU
Date: Thu, 3 Jul 2003 14:54:22 +0200
hi TSMers!

i have a scheduler problem
normaly all works fine but sometimes it doesn't.
i am using two perl scripts (startet by the client scheduler) do to some admin 
work.
the firs (checkout) does not start and the second starts at the wrong time and 
i don't know why!
why starts the checkin script at 8:40? 

my configuration:
        server tsm 4.2.3.4 (w2ksp3)
        client-scheduler 4.2.3.0 


tsm: TSM1>q ev * tsm* begind=-2 endd=+1

Scheduled Start          Actual Start             Schedule Name     Node Name   
      Status   
--------------------     --------------------     -------------     
-------------     ---------
07/01/03   08:30:00      07/01/03   08:30:10      TSM_CHECKOUT      TSM1        
      Completed
07/01/03   15:01:00      07/01/03   15:01:11      TSM_CHECKIN       TSM1        
      Completed
07/02/03   08:30:00      07/02/03   08:30:05      TSM_CHECKOUT      TSM1        
      Completed
07/02/03   15:01:00      07/02/03   15:01:06      TSM_CHECKIN       TSM1        
      Completed
07/03/03   08:30:00                               TSM_CHECKOUT      TSM1        
      Missed   
07/03/03   15:01:00      07/03/03   08:40:20      TSM_CHECKIN       TSM1        
      Completed
07/04/03   08:30:00                               TSM_CHECKOUT      TSM1        
      Future   
07/04/03   15:01:00                               TSM_CHECKIN       TSM1        
      Future   

tsm: TSM1>

dsmsched.log: (no entrys in dsmerror.log)

02.07.2003 19:25:35 --- SCHEDULEREC QUERY BEGIN
02.07.2003 19:25:35 --- SCHEDULEREC QUERY END
02.07.2003 19:25:35 Next operation scheduled:
02.07.2003 19:25:35 ------------------------------------------------------------
02.07.2003 19:25:35 Schedule Name:         TSM_CHECKOUT
02.07.2003 19:25:35 Action:                Command
02.07.2003 19:25:35 Objects:               d:\tsm\skripts\tsm_checkout.pl
02.07.2003 19:25:35 Options:               
02.07.2003 19:25:35 Server Window Start:   08:30:00 on 03.07.2003
02.07.2003 19:25:35 ------------------------------------------------------------
02.07.2003 19:25:35 Scheduler has been stopped.

03.07.2003 08:40:20 Scheduler has been started by Dsmcad.
03.07.2003 08:40:20 Querying server for next scheduled event.
03.07.2003 08:40:20 Node Name: TSM1
03.07.2003 08:40:20 Session established with server TSM1: Windows
03.07.2003 08:40:20   Server Version 4, Release 2, Level 3.4
03.07.2003 08:40:20   Data compression forced on by the server
03.07.2003 08:40:20   Server date/time: 03.07.2003 08:40:20  Last access: 
03.07.2003 08:40:20

03.07.2003 08:40:20 --- SCHEDULEREC QUERY BEGIN
03.07.2003 08:40:20 --- SCHEDULEREC QUERY END
03.07.2003 08:40:20 Next operation scheduled:
03.07.2003 08:40:20 ------------------------------------------------------------
03.07.2003 08:40:20 Schedule Name:         TSM_CHECKIN
03.07.2003 08:40:20 Action:                Command
03.07.2003 08:40:20 Objects:               d:\tsm\skripts\tsm_checkin.pl
03.07.2003 08:40:20 Options:               
03.07.2003 08:40:20 Server Window Start:   15:01:00 on 03.07.2003
03.07.2003 08:40:20 ------------------------------------------------------------
03.07.2003 08:40:20 
Executing scheduled command now.
03.07.2003 08:40:20 
Executing Operating System command or script:
   d:\tsm\skripts\tsm_checkin.pl
03.07.2003 08:40:27 Finished command.  Return code is:
   0
03.07.2003 08:40:27 Scheduled event 'TSM_CHECKIN' completed successfully.
03.07.2003 08:40:27 Sending results for scheduled event 'TSM_CHECKIN'.
03.07.2003 08:40:27 Results sent to server for scheduled event 'TSM_CHECKIN'.

03.07.2003 08:40:27 ANS1483I Schedule log pruning started.
03.07.2003 08:40:27 Schedule Log Prune: 2245 lines processed.  0 lines pruned.
03.07.2003 08:40:27 ANS1484I Schedule log pruning finished successfully.
03.07.2003 08:40:27 Querying server for next scheduled event.
03.07.2003 08:40:27 Node Name: TSM1
03.07.2003 08:40:27 Session established with server TSM1: Windows
03.07.2003 08:40:27   Server Version 4, Release 2, Level 3.4
03.07.2003 08:40:27   Data compression forced on by the server
03.07.2003 08:40:27   Server date/time: 03.07.2003 08:40:27  Last access: 
03.07.2003 08:40:20

03.07.2003 08:40:27 --- SCHEDULEREC QUERY BEGIN
03.07.2003 08:40:27 --- SCHEDULEREC QUERY END
03.07.2003 08:40:27 Next operation scheduled:
03.07.2003 08:40:27 ------------------------------------------------------------
03.07.2003 08:40:27 Schedule Name:         DAILY_INCR
03.07.2003 08:40:27 Action:                Incremental
03.07.2003 08:40:27 Objects:               
03.07.2003 08:40:27 Options:               
03.07.2003 08:40:27 Server Window Start:   19:00:00 on 03.07.2003
03.07.2003 08:40:27 ------------------------------------------------------------
03.07.2003 08:40:27 Scheduler has been stopped.


activity-log server: 

tsm: TSM1>q act begint=08:28 endt=08:45

Date/Time                Message                                                
   
--------------------     
----------------------------------------------------------
07/03/03   08:30:01      ANR2561I Schedule prompter contacting TSM1 (session 
2135) 
                          to start a scheduled operation.                       
   
07/03/03   08:30:10      ANR0406I Session 2136 started for node TSM1 (WinNT)    
   
                          (Tcp/Ip 10.117.20.1(1031)).                           
   
07/03/03   08:30:10      ANR0403I Session 2136 ended for node TSM1 (WinNT).     
   
07/03/03   08:30:32      ANR2561I Schedule prompter contacting TSM1 (session 
2137) 
                          to start a scheduled operation.                       
   
07/03/03   08:31:03      ANR2561I Schedule prompter contacting TSM1 (session 
2138) 
                          to start a scheduled operation.                       
   
07/03/03   08:31:34      ANR2561I Schedule prompter contacting TSM1 (session 
2139) 
                          to start a scheduled operation.                       
   
07/03/03   08:32:01      ANR2578W Schedule TSM_CHECKOUT in domain STANDARD for 
node
                          TSM1 has missed its scheduled start up window.        
   
07/03/03   08:40:20      ANR0406I Session 2140 started for node TSM1 (WinNT)    
   
                          (Tcp/Ip 10.117.20.1(1036)).                           
   
07/03/03   08:40:20      ANR0403I Session 2140 ended for node TSM1 (WinNT).     
   
07/03/03   08:40:20      ANR0406I Session 2141 started for node TSM1 (WinNT)    
   
                          (Tcp/Ip 10.117.20.1(1037)).                           
   
07/03/03   08:40:21      ANR0407I Session 2142 started for administrator 
OPERATOR  
                          (WinNT) (Tcp/Ip 10.117.20.1(1038)).                   
   
07/03/03   08:40:21      ANR2017I Administrator OPERATOR issued command: UPDATE 
   
                          STGPOOL backup1 recl=100                              
   
07/03/03   08:40:21      ANR2202I Storage pool BACKUP1 updated.                 
   
07/03/03   08:40:21      ANR0405I Session 2142 ended for administrator OPERATOR 
   
                          (WinNT).                                              
   
07/03/03   08:40:21      ANR0407I Session 2143 started for administrator 
OPERATOR  
                          (WinNT) (Tcp/Ip 10.117.20.1(1039)).                   
   
07/03/03   08:40:21      ANR2017I Administrator OPERATOR issued command: UPDATE 
   
                          STGPOOL copy1 recl=100                                
   
07/03/03   08:40:21      ANR2202I Storage pool COPY1 updated.                   
   
07/03/03   08:40:21      ANR0405I Session 2143 ended for administrator OPERATOR 
   
                          (WinNT).                                              
   
07/03/03   08:40:21      ANR0407I Session 2144 started for administrator 
OPERATOR  
                          (WinNT) (Tcp/Ip 10.117.20.1(1040)).                   
   
07/03/03   08:40:21      ANR2017I Administrator OPERATOR issued command: QUERY 
REQ 
07/03/03   08:40:21      ANR8346I QUERY REQUEST: No requests are outstanding.   
   
07/03/03   08:40:21      ANR2017I Administrator OPERATOR issued command: 
ROLLBACK  
07/03/03   08:40:21      ANR0405I Session 2144 ended for administrator OPERATOR 
   
                          (WinNT).                                              
   
07/03/03   08:40:21      ANR0407I Session 2145 started for administrator 
OPERATOR  
                          (WinNT) (Tcp/Ip 10.117.20.1(1041)).                   
   
07/03/03   08:40:21      ANR2017I Administrator OPERATOR issued command: 
CHECKIN   
                          libv lib3583 search=bulk status=scr checkl=b          
   
07/03/03   08:40:21      ANR0984I Process 331 for CHECKIN LIBVOLUME started in 
the 
                          BACKGROUND at 08:40:21.                               
   
07/03/03   08:40:21      ANR8422I CHECKIN LIBVOLUME: Operation for library 
LIB3583 
                          started as process 331.                               
   
07/03/03   08:40:21      ANR0405I Session 2145 ended for administrator OPERATOR 
   
                          (WinNT).                                              
   
07/03/03   08:40:21      ANR0407I Session 2146 started for administrator 
OPERATOR  
                          (WinNT) (Tcp/Ip 10.117.20.1(1042)).                   
   
07/03/03   08:40:21      ANR2017I Administrator OPERATOR issued command: QUERY 
REQ 
07/03/03   08:40:21      ANR8346I QUERY REQUEST: No requests are outstanding.   
   
07/03/03   08:40:21      ANR2017I Administrator OPERATOR issued command: 
ROLLBACK  
07/03/03   08:40:21      ANR0405I Session 2146 ended for administrator OPERATOR 
   
                          (WinNT).                                              
   
07/03/03   08:40:22      ANR8373I 022: Fill the bulk entry/exit port of library 
   
                          LIB3583 with all LTO volumes to be processed within 
60   
                          minute(s); issue 'REPLY' along with the request ID 
when  
                          ready.                                                
   
07/03/03   08:40:26      ANR0407I Session 2147 started for administrator 
OPERATOR  
                          (WinNT) (Tcp/Ip 10.117.20.1(1043)).                   
   
07/03/03   08:40:26      ANR2017I Administrator OPERATOR issued command: QUERY 
REQ 
07/03/03   08:40:26      ANR8352I Requests outstanding:                         
   
07/03/03   08:40:26      ANR8373I 022: Fill the bulk entry/exit port of library 
   
                          LIB3583 with all LTO volumes to be processed within 
60   
                          minute(s); issue 'REPLY' along with the request ID 
when  
                          ready.                                                
   
07/03/03   08:40:26      ANR0405I Session 2147 ended for administrator OPERATOR 
   
                          (WinNT).                                              
   
07/03/03   08:40:27      ANR0407I Session 2148 started for administrator 
OPERATOR  
                          (WinNT) (Tcp/Ip 10.117.20.1(1044)).                   
   
07/03/03   08:40:27      ANR2017I Administrator OPERATOR issued command: REPLY 
22  
07/03/03   08:40:27      ANR8499I Command accepted.                             
   
07/03/03   08:40:27      ANR0405I Session 2148 ended for administrator OPERATOR 
   
                          (WinNT).                                              
   
07/03/03   08:40:27      ANR2507I Schedule TSM_CHECKIN for domain STANDARD 
started 
                          at 07/03/2003 15:01:00 for node TSM1 completed        
   
                          successfully at 07/03/2003 08:40:27.                  
   
07/03/03   08:40:27      ANR0403I Session 2141 ended for node TSM1 (WinNT).     
   
07/03/03   08:40:27      ANR0406I Session 2149 started for node TSM1 (WinNT)    
   
                          (Tcp/Ip 10.117.20.1(1045)).                           
   
07/03/03   08:40:27      ANR0403I Session 2149 ended for node TSM1 (WinNT).     
   
07/03/03   08:41:00      ANR8787W Unable to read the barcode label(s) in 
library   
                          LIB3583.                                              
   
07/03/03   08:41:00      ANR9756I No volumes were found.                        
   
07/03/03   08:41:01      ANR8431I CHECKIN LIBVOLUME process completed for 
library  
                          LIB3583; 0 volume(s) found.                           
   
07/03/03   08:41:01      ANR0985I Process 331 for CHECKIN LIBVOLUME running in 
the 
                          BACKGROUND completed with completion state SUCCESS at 
   
                          08:41:01.                                             
   

tsm: TSM1>q sched * tsm* f=d

            Policy Domain Name: STANDARD
                 Schedule Name: TSM_CHECKIN
                   Description: Admin Checkin
                        Action: Command
                       Options: 
                       Objects: d:\tsm\skripts\tsm_checkin.pl
                      Priority: 1
               Start Date/Time: 06/30/03   15:01:00
                      Duration: 5 Minute(s)
                        Period: 1 Day(s)
                   Day of Week: Weekday
                    Expiration: 
Last Update by (administrator): ADMIN
         Last Update Date/Time: 06/30/03   10:12:31
              Managing profile: 

            Policy Domain Name: STANDARD
                 Schedule Name: TSM_CHECKOUT
                   Description: Admin Checkout
                        Action: Command
                       Options: 
                       Objects: d:\tsm\skripts\tsm_checkout.pl
                      Priority: 1
               Start Date/Time: 06/30/03   08:30:00
                      Duration: 2 Minute(s)
                        Period: 1 Day(s)
                   Day of Week: Weekday
                    Expiration: 
Last Update by (administrator): ADMIN
         Last Update Date/Time: 06/30/03   10:12:59
              Managing profile: 


tsm: TSM1>


i hope somebody can help!
thanks

thomas

----------------------------------------------------
Dipl.-Ing. (FH) Thomas Schönleitner
Salzburger Gebietskrankenkasse
EDV/System
Adr.:   Faberstraße 19-23
        A-5024 Salzburg
Tel.:   +43 (0)662 8889-510
Fax.:   +43 (0)662 8889-515
Mail:   mailto:thomas.schoenleitner AT sgkk.sozvers DOT at
        mailto:ts AT sgkk DOT at  
Web :   http://www.sgkk.at/ 
---------------------------------------------------- 
 
 

<Prev in Thread] Current Thread [Next in Thread>
  • scheduler problem, Schoenleitner Thomas <=