ADSM-L

"backup db"-performance

1996-12-11 11:15:09
Subject: "backup db"-performance
From: Peter Duempert <c0034003 AT RZIBM214.RZ.TU-BS DOT DE>
Date: Wed, 11 Dec 1996 17:15:09 +0100
Hi ADSM'ers,

1. my problem "backup db takes ages" has vanished with the installation
of adsmserv.2.1.0.11, i.e. new device-files had been created and the
performance of a "backup db" onto 8mm EXB-10i has got it's old speed.
We suffered for  one week from this poor performance.
2. The following o/p from a "Q VOL SRVTB*" + "Q VOL SRVTC*" may give
   some hint that something was changed with the 8mm DAT o/p-device.

Volume Name                  Storage         Device         Estimated     %=
Util      Volume=20
                             Pool Name       Class Name      Capacity      =
          Status=20
                                                                 (MB)      =
        =20
------------------------     -----------     ----------     ---------     -=
----     --------
----     --------
SRVTB0                       EXB-10E         EXB-10E          4,944.0      =
SRVTB0                       EXB-10E         EXB-10E          4,944.0      =
 0.5     Filling=20
SRVTB1                       EXB-10E         EXB-10E          4,271.6      =
58.1       Full =20
SRVTB2                       EXB-10E         EXB-10E          4,944.0      =
 0.6     Filling=20
SRVTB3                       EXB-10E         EXB-10E          4,944.0 =09  =
  0.4 Filling=20
SRVTB4                       EXB-10E         EXB-10E          4,944.0      =
 0.8     Filling=20
SRVTB5                       EXB-10E         EXB-10E          4,096.8      =
56.8       Full =20
SRVTB6                       EXB-10E         EXB-10E          4,319.5      =
58.9       Full =20
SRVTB7                       EXB-10E         EXB-10E          4,103.6      =
71.4       Full =20
SRVTB8                       EXB-10E         EXB-10E          4,944.0      =
39.5     Filling=20
SRVTB9                       EXB-10E         EXB-10E          1,033.0      =
80.6       Full =20

Volume Name                  Storage         Device         Estimated     %=
Util      Volume=20
                             Pool Name       Class Name      Capacity      =
          Status=20
                                                                 (MB)      =
        =20
------------------------     -----------     ----------     ---------     -=
----     --------
----     --------
SRVTC0                       EXB-10E         EXB-10E            980.9      =
SRVTC0                       EXB-10E         EXB-10E            980.9      =
84.7       Full =20
SRVTC1                       EXB-10E         EXB-10E            977.1      =
78.8       Full =20
SRVTC2                       EXB-10E         EXB-10E              0.0      =
 0.0      Empty =20
SRVTC3                       EXB-10E         EXB-10E              0.0      =
 0.0      Empty =20
SRVTC4                       EXB-10E         EXB-10E              0.0      =
 0.0      Empty =20


i.e. the change of the "Estimated Capacity" within this one week period
from the nominal 4GB downto ca. 1GB. Is it something with "blocksize"-s ?


3. May be others have experienced a similar behaviour, I'd like to ask
for an explanation of the following "backup db"-performance:

Example 1:   VERY SLOW speed onto 8mm EXB-10i-drive
             with adsmserv 2.1.0.9
=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=
=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=
=3D

Date/Time                Message                                           =
       =20
--------------------     --------------------------------------------------=
--------
--------
12/10/96   15:31:27      ANR2280I Full database backup started as process 3=
12/10/96   15:31:27      ANR2280I Full database backup started as process 3=
=2E      =20
12/10/96   15:32:44      ANR8337I 8MM volume BDBTUE mounted in drive SCREXT=
       =20
                          (/dev/mt1).                                      =
       =20
12/10/96   15:32:44      ANR1360I Output volume BDBTUE opened (sequence num=
ber 1).=20
12/10/96   15:33:48      ANR4554I Backed up 32 of 428924 database pages.   =
       =20
12/10/96   15:34:21      ANR4554I Backed up 176 of 428924 database pages.  =
       =20
12/10/96   15:34:53      ANR4554I Backed up 320 of 428924 database pages.  =
       =20
12/10/96   15:35:26      ANR4554I Backed up 464 of 428924 database pages.  =
       =20
=2E..
12/10/96   16:06:37      ANR4554I Backed up 8800 of 428924 database pages. =
       =20
12/10/96   16:07:09      ANR4554I Backed up 8944 of 428924 database pages. =
       =20
12/10/96   16:07:40      ANR4554I Backed up 9072 of 428924 database pages. =
       =20
12/10/96   16:07:58      ANR4555I Database backup process 3 canceled.      =
       =20
12/10/96   16:08:08      ANR1361I Output volume BDBTUE closed.             =
       =20
12/10/96   16:08:59      ANR8336I Verifying label of 8MM volume BDBTUE in d=
rive   =20
                          SCREXT (/dev/mt1).                         =20
            =20
I cancelled the backup, because it would take ca. 28 hours to complete


Example 2: FAST speed onto 3490 via S370/Adapter-card
           with adsmserv 2.1.0.9
=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=
=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=
=3D=3D=3D=20

12/10/96   16:09:11      ANR2280I Full database backup started as process 4=
=2E      =20
12/10/96   16:09:13      ANR8326I 001: Mount CARTRIDGE volume DB0000 R/W in=
 drive =20
                          UNIT1021 (/dev/cart1) of library 3490 within 60 m=
inutes.=20
12/10/96   16:10:14      ANR8326I 001: Mount CARTRIDGE volume DB0000 R/W in=
 drive =20
                          UNIT1021 (/dev/cart1) of library 3490 within 59 m=
inutes.=20
12/10/96   16:10:29      ANR8468I 8MM volume BDBTUE dismounted from drive S=
CREXT  =20
                          (/dev/mt1) in library EXB-10I-S.                 =
       =20
12/10/96   16:10:31      ANR8335I 001: Verifying label of CARTRIDGE volume =
DB0000 =20
                          in drive UNIT1021 (/dev/cart1).                  =
       =20
12/10/96   16:10:33      ANR8328I 001: CARTRIDGE volume DB0000 mounted in d=
rive   =20
                          UNIT1021 (/dev/cart1).                           =
       =20
12/10/96   16:10:35      ANR1360I Output volume DB0000 opened (sequence num=
ber 1).=20
12/10/96   16:11:05      ANR4554I Backed up 6704 of 428924 database pages. =
       =20
12/10/96   16:11:35      ANR4554I Backed up 13760 of 428924 database pages.=
       =20
12/10/96   16:12:06      ANR4554I Backed up 21520 of 428924 database pages.=
       =20
12/10/96   16:12:36      ANR4554I Backed up 26864 of 428924 database pages.=
       =20
12/10/96   16:13:06      ANR4554I Backed up 35984 of 428924 database pages.=
       =20
12/10/96   16:13:36      ANR4554I Backed up 43424 of 428924 database pages.=
       =20
12/10/96   16:14:05      ANR8341I End-of-volume reached for CARTRIDGE volum=
e      =20


I cancelled the "backup db" because it should only show the  speedup !!!

   =20
Example 3: Normal speed after the above mentioned adsm.2.1.0.11-install
           onto 8mm EXB-10i
=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=
=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=
=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D  =20

Date/Time                Message                                           =
       =20
--------------------     --------------------------------------------------=
--------
--------
12/11/96   14:22:32      ANR2280I Full database backup started as process 3=
12/11/96   14:22:32      ANR2280I Full database backup started as process 3=
=2E      =20
12/11/96   14:23:43      ANR8337I 8MM volume BDBWED mounted in drive SCREXT=
       =20
                          (/dev/mt1).                                      =
       =20
12/11/96   14:23:43      ANR1360I Output volume BDBWED opened (sequence num=
ber 1).=20
12/11/96   14:24:38      ANR4554I Backed up 32 of 429969 database pages.   =
       =20
12/11/96   14:25:08      ANR4554I Backed up 5104 of 429969 database pages. =
       =20
12/11/96   14:25:38      ANR4554I Backed up 9760 of 429969 database pages. =
       =20
12/11/96   14:26:08      ANR4554I Backed up 14560 of 429969 database pages.=
       =20
12/11/96   14:26:38      ANR4554I Backed up 19472 of 429969 database pages.=
       =20
=2E..
12/11/96   14:37:10      ANR4554I Backed up 102688 of 429969 database pages=
=2E      =20
=2E..
12/11/96   14:48:43      ANR4554I Backed up 201696 of 429969 database pages=
=2E      =20
=2E..
12/11/96   15:01:45      ANR4554I Backed up 299088 of 429969 database pages=
=2E      =20
=2E..
12/11/96   15:14:17      ANR4554I Backed up 399760 of 429969 database pages=
=2E      =20
=2E..

12/11/96   15:17:18      ANR4554I Backed up 425984 of 429969 database pages=
=2E      =20
12/11/96   15:18:56      ANR1361I Output volume BDBWED closed.             =
       =20
12/11/96   15:19:17      ANR4550I Full database backup (process 3) complete=
, 429969
                          pages copied.                                    =
       =20
12/11/96   15:19:41      ANR8336I Verifying label of 8MM volume BDBWED in d=
rive   =20
                          SCREXT (/dev/mt1).                               =
       =20
12/11/96   15:21:30      ANR8468I 8MM volume BDBWED dismounted from drive S=
CREXT  =20
                          (/dev/mt1) in library EXB-10I-S.                 =
       =20


Q1: Why this behaviour ?=20

        EX-1: 15:31:27 - 16:07:40, i.e. ca. 36 mins for 9072 pages
              --> ca. 1700 mins ( ca. 28 hours !!) for 428924 pages
              ( extrapolated )
        EX-2: 3 mins for 43424 pages --> ca. 30 mins for 428924 pages
=09      ( extrapolated )  =20
        EX-3: 14:22:32 - 15:21:30, i.e. ca. 59 mins for 429969 pages,
              i.e. the "normal" behaviour we saw until recently ( and now
              again ) =20
        - The real astonishing facts are the comparison of EX-1 and EX-3.
        - Comparison of EX-2 and EX-3 is obvious due to the different
          speeds of the o/p devices involved.

Q2: Any means for influencing this behaviour ( apart from a new install=20
    for adsmserv ) ?

--=20
MfG / Ciao         - - - - - - - - - - - - - - - - - - - - - - - - - - - -
MfG / Ciao         - - - - - - - - - - - - - - - - - - - - - - - - - - - -
Peter D=FCmpert                                   Email: [email protected]=
e
Rechenzentrum der Technischen Universit=E4t       Fax  : ++49/531/391-5549
D 38092 Braunschweig                            Tel  : ++49/531/391-5535
=20
=20
<Prev in Thread] Current Thread [Next in Thread>
  • "backup db"-performance, Peter Duempert <=