ADSM-L

Restore backupset take very long

2002-08-13 07:43:45
Subject: Restore backupset take very long
From: Demaerel Miguel <miguel.demaerel AT BRUSSELS.SEMA.SLB DOT COM>
To: ADSM-L AT VM.MARIST DOT EDU
Date: Tue, 13 Aug 2002 13:39:57 +0200
Hoi

TSM server: 4.2.2.7 (AIX 5.1)
TSM client: 4.2.1.7 (Solaris 8)


I try to restore some files from a backupset (size 110 GB on 2 tapes)

tsm> restore backupset SOVI01_2002-08-06.130729665
/data11/oradata/PSVB/admin/files/lcnv/*20020417.dat.gz /opt/restore.
No problem so far....

But this takes very long

Result of restore:
Restore function invoked.

Session established with server ADSM: AIX-RS/6000
  Server Version 4, Release 2, Level 2.7
  Server date/time: 13-08-2002 11:46:50  Last access: 13-08-2002 11:22:06

ANS1247I Waiting for files from the server...
Restoring          46,592 /data11/oradata/PSVB/admin/files/lcnv -->
/opt/restore/lcnv [Done]
Restoring       5,673,249
/data11/oradata/PSVB/admin/files/lcnv/euro_stap2A_TZS_CREDIT_DEBET_EURO_1_20
020417.
Restoring       7,556,400
/data11/oradata/PSVB/admin/files/lcnv/euro_stap2A_TZS_CREDIT_DEBET_EURO_3_20
020417.
Restoring      11,659,832
/data11/oradata/PSVB/admin/files/lcnv/euro_stap2A_TZS_CREDIT_DEBET_EURO_4_20
020417.
Restoring      50,129,976
/data11/oradata/PSVB/admin/files/lcnv/euro_stap2B_TZS_CREDIT_DEBET_EURO_1_20
020417.
Restoring      31,002,144
/data11/oradata/PSVB/admin/files/lcnv/euro_stap2B_TZS_CREDIT_DEBET_EURO_2_20
020417.
Restoring      32,800,605
/data11/oradata/PSVB/admin/files/lcnv/euro_stap2B_TZS_CREDIT_DEBET_EURO_3_20
020417.
Restoring      38,666,328
/data11/oradata/PSVB/admin/files/lcnv/euro_stap2B_TZS_CREDIT_DEBET_EURO_4_20
020417.

Restore processing finished.

Total number of objects restored:         8
Total number of objects failed:           0
Total number of bytes transferred:   169.53 MB
Data transfer time:                   10.80 sec
Network data transfer rate:        16,063.53 KB/sec
Aggregate data transfer rate:         27.44 KB/sec
Elapsed processing time:           01:45:25
tsm>

There isn't action on the TSM server or on the client.
Top output.
last pid:  1655;  load averages:  0.02,  0.01,  0.02
13:04:09
52 processes:  51 sleeping, 1 on cpu
CPU states: 99.9% idle,  0.0% user,  0.1% kernel,  0.0% iowait,  0.0% swap
Memory: 1024M real, 657M free, 106M swap in use, 1156M swap free

   PID USERNAME THR PRI NICE  SIZE   RES STATE    TIME    CPU COMMAND
  1655 root       1  50    0 2536K 1632K cpu/3    0:00  0.25% top
   224 root      12  58    0 2688K 1400K sleep   15:07  0.00% mibiisa
   216 root       1  59    0   27M   26M sleep    3:58  0.00% Xsun
  1482 root       6  59    0 9032K 6376K sleep    0:11  0.00% dsmc
 23409 root       1  59    0 6872K 2192K sleep    0:09  0.00% sdtperfmeter
 23405 root       8  59    0 8808K 4240K sleep    0:05  0.00% dtwm
   155 root       1  48    0 1952K 1072K sleep    0:04  0.00% cron
 23398 root       6  49    0 8168K 4712K sleep    0:04  0.00% dtsession
 23408 root       1  48    0   10M 3608K sleep    0:03  0.00% dtfile
 23407 root       1  49    0 7640K 3496K sleep    0:01  0.00% dtterm
   136 root       5  58    0 4040K 2640K sleep    0:01  0.00% automountd
   146 root      10  58    0 3200K 1584K sleep    0:01  0.00% syslogd
 23417 root       1  59    0 1800K  664K sleep    0:01  0.00% rpc.rstatd
 23420 root       1   2    0 1032K  256K sleep    0:00  0.00% sh
 23347 root       1  10    0 2304K  440K sleep    0:00  0.00% fbconsole

Restore from archive:
tsm> retrieve /data11/oradata/PSVB/admin/files/lcnv/*20020417.dat.gz
/opt/restore/
Retrieve function invoked.

Retrieving          46,592 /data11/oradata/PSVB/admin/files/lcnv -->
/opt/restore/lcnv [Done]
 ** Interrupted **
ANS1114I Waiting for mount of offline media.
Retrieving       7,556,400
/data11/oradata/PSVB/admin/files/lcnv/euro_stap2A_TZS_CREDIT_DEBET_EURO_3_20
020417
Retrieving      11,659,832
/data11/oradata/PSVB/admin/files/lcnv/euro_stap2A_TZS_CREDIT_DEBET_EURO_4_20
020417
Retrieving      31,002,144
/data11/oradata/PSVB/admin/files/lcnv/euro_stap2B_TZS_CREDIT_DEBET_EURO_2_20
020417
Retrieving      32,800,605
/data11/oradata/PSVB/admin/files/lcnv/euro_stap2B_TZS_CREDIT_DEBET_EURO_3_20
020417
Retrieving      38,666,328
/data11/oradata/PSVB/admin/files/lcnv/euro_stap2B_TZS_CREDIT_DEBET_EURO_4_20
020417
Retrieving       5,673,249
/data11/oradata/PSVB/admin/files/lcnv/euro_stap2A_TZS_CREDIT_DEBET_EURO_1_20
020417
Retrieving      50,129,976
/data11/oradata/PSVB/admin/files/lcnv/euro_stap2B_TZS_CREDIT_DEBET_EURO_1_20
020417

Retrieve processing finished.

Total number of objects retrieved:        8
Total number of objects failed:           0
Total number of bytes transferred:   169.28 MB
Data transfer time:                    9.76 sec
Network data transfer rate:        17,750.91 KB/sec
Aggregate data transfer rate:      1,751.29 KB/sec
Elapsed processing time:           00:01:38
tsm>

What can be the reason? What a difference in time?
Restore backupset 01:45
Retrieve 00:02

Thanks in advance.

Greetings Miguel

                                      /////
                                     (o o)
==============oOO==(_)==OOo==============
                            Miguel Demaerel
                            System Engineer
                             Schlumberger
          Raketstraat 98, B-1130 Brussels, Belgium
                        Tel +32 (0)2 724 93 17
                       Fax +32 (0)2 724 92 92
                 Email Miguel.Demaerel AT SLB DOT COM
=========================================

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