Problem with labelin

[vlatko666]

Hi I am new in Tivoli and having this problem.

TSM 5.5 is install on IBM Blade Server HS21 withWindows 2003 32bit. Have 2 dual port HBA, one of HBA is zoned with TS3200 via 2 san switches.
TSM is going to make backup on tape ts3200. I configure the library, 2 drives and paths. When I start labelling of volumes with vollist parameter Tivoli start with labelling process, he label one or two tapes and then stops. The process is still running but not taking any tapes for labelling, and change Drive State to UNLOADED. When I connect to tape from web doesn’t show any errors, drives are empty and everything look ok.
I try labelling via command line and administration central and in booth case showing the same problem.

command
label libvolume TS3200 search=yes labels=vollist checkin=scratch overwrite=yes vollist=vol001,vol002,vol004,vol005,vol006,vol007

q drive ts3200 f=d

Library Name: TS3200
Drive Name: DRV01
Device Type: LTO
On-Line: Yes
Read Formats: ULTRIUM4C,ULTRIUM4,ULTRIUM3C,ULT-
RIUM3,ULTRIUM2C,ULTRIUM2
Write Formats: ULTRIUM4C,ULTRIUM4,ULTRIUM3C,ULT-
RIUM3
Element: 256
Drive State: UNLOADED
Volume Name:
Allocated to: TIV-01
WWN: 2001000E111087CD
Serial Number: 1310045994
Last Update by (administrator): ADMIN
Last Update Date/Time: 07/23/2009 15:06:09
Cleaning Frequency (Gigabytes/ASNEEDED/NONE): NONE

Library Name: TS3200
Drive Name: DRV02
Device Type: LTO
On-Line: Yes
Read Formats: ULTRIUM4C,ULTRIUM4,ULTRIUM3C,ULT-
RIUM3,ULTRIUM2C,ULTRIUM2
Write Formats: ULTRIUM4C,ULTRIUM4,ULTRIUM3C,ULT-
RIUM3
Element: 257
Drive State: EMPTY
Volume Name:
Allocated to:
WWN: 2007000E111087CD
Serial Number: 1310046053
Last Update by (administrator): ADMIN
Last Update Date/Time: 07/23/2009 15:05:35
Cleaning Frequency (Gigabytes/ASNEEDED/NONE): NONE

[BBB]

Look in the tsm activity log with "q act". Its not going to fail these operations silently, there will be something in there that will help diagnose.

[vlatko666]

He are some act log.


2009-07-23 13:51:50 ANR2017I Administrator ADMIN issued command: select volume_name,cleanings_left from libvolumes where library_name='LIBR' and status='Cleaner'
2009-07-23 13:51:50 ANR2034E SELECT: No match found using this criteria.
2009-07-23 13:51:48 ANR0407I Session 51 started for administrator ADMIN (DSMAPI) (Tcp/Ip tiv-01..com(2605)).
2009-07-23 13:51:48 ANR2017I Administrator ADMIN issued command: SHOW TIME
2009-07-23 13:51:48 ANR2017I Administrator ADMIN issued command: QUERY LIBRARY LIBR F=D
2009-07-23 13:51:48 ANR2017I Administrator ADMIN issued command: select mover_name from datamovers
2009-07-23 13:51:48 ANR2034E SELECT: No match found using this criteria.
2009-07-23 13:51:48 ANR2017I Administrator ADMIN issued command: select server_name from servers
2009-07-23 13:51:48 ANR2034E SELECT: No match found using this criteria.
2009-07-23 13:51:48 ANR2017I Administrator ADMIN issued command: select mover_name from datamovers
2009-07-23 13:51:48 ANR2034E SELECT: No match found using this criteria.
2009-07-23 13:51:48 ANR2017I Administrator ADMIN issued command: select server_name from servers
2009-07-23 13:51:48 ANR2034E SELECT: No match found using this criteria.
2009-07-23 13:51:48 ANR2017I Administrator ADMIN issued command: QUERY DRIVE LIBR F=D
2009-07-23 13:51:48 ANR2017I Administrator ADMIN issued command: select count(source_name) as COUNT from paths where DESTINATION_NAME='DRV01' and LIBRARY_NAME='LIBR'
2009-07-23 13:51:48 ANR2017I Administrator ADMIN issued command: select count(source_name) as COUNT from paths where DESTINATION_NAME='DRV02' and LIBRARY_NAME='LIBR'
2009-07-23 13:51:48 ANR2017I Administrator ADMIN issued command: select * from paths where library_name='LIBR' AND DESTINATION_TYPE='DRIVE'
2009-07-23 13:51:48 ANR2017I Administrator ADMIN issued command: select drive_name from drives where library_name='LIBR'
2009-07-23 13:51:48 ANR2017I Administrator ADMIN issued command: select * from paths where destination_name='LIBR' AND DESTINATION_TYPE='LIBRARY'
2009-07-23 13:51:44 ANR2017I Administrator ADMIN issued command: SET LICENSEAUDITPERIOD 1
2009-07-23 13:51:44 ANR2814I SET LICENSEAUDITPERIOD: License audit period changed to 1 days.
2009-07-23 13:51:44 ANR0405I Session 50 ended for administrator ADMIN (DSMAPI).
2009-07-23 13:51:37 ANR0407I Session 50 started for administrator ADMIN (DSMAPI) (Tcp/Ip tiv-01.com(2604)).
2009-07-23 13:51:37 ANR2017I Administrator ADMIN issued command: SHOW TIME
2009-07-23 13:51:37 ANR2017I Administrator ADMIN issued command: QUERY STATUS
2009-07-23 13:51:37 ANR2017I Administrator ADMIN issued command: QUERY OPTION
2009-07-23 13:51:37 ANR2017I Administrator ADMIN issued command: QUERY SHREDSTATUS F=D
2009-07-23 13:51:17 ANR0407I Session 49 started for administrator ADMIN_CENTER (DSMAPI) (Tcp/Ip prho-tiv-01.rbko-kosovo.com(2599)).
2009-07-23 13:51:17 ANR2017I Administrator ADMIN_CENTER issued command: QUERY DB f=d
2009-07-23 13:51:17 ANR2017I Administrator ADMIN_CENTER issued command: QUERY SPACETRIGGER db f=d
2009-07-23 13:51:17 ANR2034E QUERY SPACETRIGGER: No match found using this criteria.
2009-07-23 13:51:17 ANR2017I Administrator ADMIN_CENTER issued command: QUERY LOG f=d
2009-07-23 13:51:17 ANR2017I Administrator ADMIN_CENTER issued command: QUERY LIBRARY f=d
2009-07-23 13:51:17 ANR2017I Administrator ADMIN_CENTER issued command: QUERY DRIVE f=d
2009-07-23 13:51:17 ANR2017I Administrator ADMIN_CENTER issued command: QUERY PATH f=d
2009-07-23 13:51:17 ANR2017I Administrator ADMIN_CENTER issued command: QUERY EVENT * * begintime=now-24:00 endtime=now enddate=today f=d
2009-07-23 13:51:17 ANR2034E QUERY EVENT: No match found using this criteria.
2009-07-23 13:51:17 ANR2017I Administrator ADMIN_CENTER issued command: QUERY EVENT * type=admin begintime=now-24:00 endtime=now enddate=today f=d
2009-07-23 13:51:17 ANR2034E QUERY EVENT: No match found using this criteria.
2009-07-23 13:51:17 ANR2017I Administrator ADMIN_CENTER issued command: select * from actlog where originator='SERVER' and (SEVERITY='E' or SEVERITY='S' or SEVERITY='D') and DATE_TIME > TIMESTAMP(CURRENT_TIMESTAMP - interval '24' hour)
2009-07-23 13:51:17 ANR2017I Administrator ADMIN_CENTER issued command: select colname, typename from syscat.columns where tabname='ACTLOG'
2009-07-23 13:51:17 ANR2017I Administrator ADMIN_CENTER issued command: select * from actlog where originator='SERVER' and SEVERITY='W' and DATE_TIME > TIMESTAMP(CURRENT_TIMESTAMP - interval '24' hour)
2009-07-23 13:51:17 ANR2017I Administrator ADMIN_CENTER issued command: select colname, typename from syscat.columns where tabname='ACTLOG'
2009-07-23 13:51:17 ANR0405I Session 49 ended for administrator ADMIN_CENTER (DSMAPI).
2009-07-23 13:49:54 ANR2017I Administrator ADMIN issued command: QUERY PROCESS
2009-07-23 13:47:20 ANR8810I Volume VOL002 has been labeled in library LIBR.
2009-07-23 13:45:59 ANR8810I Volume VOL001 has been labeled in library LIBR.
2009-07-23 13:45:31 ANR2017I Administrator ADMIN issued command: QUERY PROCESS
2009-07-23 13:45:15 ANR2017I Administrator ADMIN issued command: LABEL LIBVOLUME libr search=yes labels=vollist checkin=scr overwrite=yes waittime=0 vollist=vol001,vol002,vol004,vol005,vol007,vol019, vol020,vol021,vol022,vol026,vol030,vol031,vol045

[moon-buddy]

Are the tapes in the I/O slot or in the library slots? If the tapes are in the I/O slots, change to search=bulk and run the label command again.

[vlatko666]

tapes are on the library slots.

The problem is when one tape is labelled i can see that volume like scratch tape on TSM but when second tape is labelled say
Volume VOL002 has been labeled in library LIBR.
But this tape i can not see like scratch tape its not added like scratch and then tape drive state is UNLOADED, and not changing to ready.

Library Name: TS3200
Drive Name: DRV01
Device Type: LTO
On-Line: Yes
Read Formats: ULTRIUM4C,ULTRIUM4,ULTRIUM3C,ULT-
RIUM3,ULTRIUM2C,ULTRIUM2
Write Formats: ULTRIUM4C,ULTRIUM4,ULTRIUM3C,ULT-
RIUM3
Element: 256
Drive State: UNLOADED
Volume Name:
Allocated to: PRHO-TIV-01
WWN: 2001000E111087CD
Serial Number: 1310045994
Last Update by (administrator): ADMIN
Last Update Date/Time: 07/23/2009 15:06:09
Cleaning Frequency (Gigabytes/ASNEEDED/NONE): NONE


This is drive status

Drive 1 Status At 15:22:31 Library Time
Status Ready
Cartridge In Drive None
Drive Error Code No Error
Drive Activity Ready
Port A Status Login complete
Port Name 2002000E111087CD
Speed 4 Gb/sec
Topology L-Port
FC-AL Loop ID 4
Encryption Status
Encryption method Disabled
Key path Default by method
BOP policy Disabled
Density reporting Other

Drive 2 Status At 15:22:31 Library Time
Status Ready
Cartridge In Drive None
Drive Error Code No Error
Drive Activity Ready
Port A Status Login complete
Port Name 2008000E111087CD
Speed 4 Gb/sec
Topology L-Port
FC-AL Loop ID 5
Encryption Status
Encryption method Disabled
Key path Default by method
BOP policy Disabled
Density reporting Other

[Eldoraan]

state of unloaded means the drive has ejected the volume, but the library hasn't picked it up and moved it back to home slot. Are you seeing library errors or perhaps a tape is stuck in drive - in which case you'll need it manually ejected?

[vlatko666]

Library doesn’t show any errors, tape is back to home slot. You can see the drive status on previous post, there is no errors. When I connect to the tape from web it’s showing that all tapes are on home slots.

[Mita201]

Vlatko,

Can you explain your configuration: "one of HBA is zoned with TS3200 via 2 san switches" with more details? Are two switches two separate fabrics?
Do you see one tape over one HBA port and the other one trough other HBA port on your server? If so, I suggest you to move both tapes to one fabric, and zone two tapes with one HBA port on server. You will probably have to rediscover devices, redefine library etc.

[vlatko666]

Hi Mita. I have 2 DualPort HBA, First HBA is zoned with Storage, Second HBA is zoned with one TS3200 trough separate switch, First Port on First Switch and Second Port on Second Switch. Tape TS3200 have 2 ports and they are connected same as HBA, each port on separate switch. Next what I was to do is to connect on same switch, I hope this will help me.
Mita I like to call you in the office for advice, i was on December on TM50 course.

Pozdrav.

[vlatko666]

Thanks to Mita201 I solved the problem. Seems that RSM service was making the problem from other client who are zoned with TSM.