db2数据库备份问题

shiner

大侠们帮我分析一下，我的DB2数据库在线全量备份时，为什么 db2diag.log 日志中总会有 Error的错误信息
备份脚本：

1. #!/bin/sh
   
2. #采用的是在线全量备份
   
3. 
   
4. #加载DB2环境变量
   
5. . /home/db2inst1/sqllib/db2profile
   
6. 
   
7. #数据库文件备份路径
   
8. bakpath=/amfe/amfedb_bak
   
9. 
   
10. #获取上一日期(YYYYMMDD)
    
11. deldate=$(date +"%Y%m%d" -d yesterday)
    
12. 
    
13. #备份日志路径及名称
    
14. logDir=$bakpath
    
15. logFile=db2backup_history.log
    
16. 
    
17. #记录备份日志文件
    
18. echo "" | tee -a ${logDir}/${logFile}
    
19. echo "" | tee -a ${logDir}/${logFile}
    
20. echo "==============================[ `date +\"%Y-%m-%d %T\"` ]==============================" | tee -a ${logDir}/${logFile}
    
21. 
    
22. #开始备份DB2数据库
    
23. echo "begin backup,date: "$(date +"%Y%m%d %T") | tee -a ${logDir}/${logFile}
    
24. db2 backup db AMFEDB online to $bakpath compress include logs
    
25. if [ $? != 0 ]; then       
    
26.         echo "backup failed !" | tee -a ${logDir}/${logFile}
    
27. else
    
28.         #备份成功删除上次全量备份文件
    
29.         echo "backup success !" | tee -a ${logDir}/${logFile}
    
30.         rm -f ${bakpath}/AMFEDB*${deldate}*
    
31.         echo "delete old backup file success !" | tee -a ${logDir}/${logFile}
    
32.        
    
33.         #备份成功删除归档文件
    
34.         filetimestamp=`ls -t ${bakpath}/AMFEDB* | head -1 | awk -F"." '{print $6}'`
    
35.         echo "filetimestamp:" $filetimestamp | tee -a ${logDir}/${logFile}
    
36.         db2 connect to amfedb
    
37.         db2 prune history $filetimestamp and delete
    
38.         echo "delete archivelog success !" | tee -a ${logDir}/${logFile}
    
39.         db2 connect reset
    
40.        
    
41.         #备份成功后把备份文件上次至AMFE备份服务
    
42.         filename=`ls -t ${bakpath}/AMFEDB* | head -1`
    
43.         sftp db2inst1@SFTPHOSTS <<EOF
    
44.         cd  ${bakpath}
    
45.         lcd ${bakpath}
    
46.         put $filename
    
47.         quit
    
48. EOF
    
49. 
    
50. fi
    

复制代码

db2diag.log 具体报错信息如下：

1. 2015-12-22-01.30.03.028361+480 E8019776E491        LEVEL: Info
   
2. PID     : 4041                 TID  : 140514032609024PROC : db2sysc
   
3. INSTANCE: db2inst1             NODE : 000          DB   : AMFEDB
   
4. APPHDL  : 0-1147               APPID: *LOCAL.db2inst1.151221173003
   
5. AUTHID  : DB2INST1
   
6. EDUID   : 28                   EDUNAME: db2agent (AMFEDB)
   
7. FUNCTION: DB2 UDB, database utilities, sqluxGetDegreeParallelism, probe:507
   
8. DATA #1 : <preformatted>
   
9. Autonomic backup/restore - using parallelism = 8.
   
10. 
    
11. 2015-12-22-01.30.03.038382+480 E8020268E517        LEVEL: Info
    
12. PID     : 4041                 TID  : 140514032609024PROC : db2sysc
    
13. INSTANCE: db2inst1             NODE : 000          DB   : AMFEDB
    
14. APPHDL  : 0-1147               APPID: *LOCAL.db2inst1.151221173003
    
15. AUTHID  : DB2INST1
    
16. EDUID   : 28                   EDUNAME: db2agent (AMFEDB)
    
17. FUNCTION: DB2 UDB, database utilities, sqluxGetAvailableHeapPages, probe:618
    
18. DATA #1 : <preformatted>
    
19. Autonomic BAR - heap consumption.
    
20. Targetting (50%) - 13088 of 26176 pages.
    
21. 
    
22. 2015-12-22-01.30.03.038468+480 E8020786E505        LEVEL: Info
    
23. PID     : 4041                 TID  : 140514032609024PROC : db2sysc
    
24. INSTANCE: db2inst1             NODE : 000          DB   : AMFEDB
    
25. APPHDL  : 0-1147               APPID: *LOCAL.db2inst1.151221173003
    
26. AUTHID  : DB2INST1
    
27. EDUID   : 28                   EDUNAME: db2agent (AMFEDB)
    
28. FUNCTION: DB2 UDB, database utilities, sqlubTuneBuffers, probe:869
    
29. DATA #1 : <preformatted>
    
30. Autonomic backup - tuning enabled.
    
31. Using buffer size = 512, number = 16.
    
32. 
    
33. 2015-12-22-01.30.03.063404+480 E8021292E452        LEVEL: Info
    
34. PID     : 4041                 TID  : 140514032609024PROC : db2sysc
    
35. INSTANCE: db2inst1             NODE : 000          DB   : AMFEDB
    
36. APPHDL  : 0-1147               APPID: *LOCAL.db2inst1.151221173003
    
37. AUTHID  : DB2INST1
    
38. EDUID   : 28                   EDUNAME: db2agent (AMFEDB)
    
39. FUNCTION: DB2 UDB, database utilities, sqlubSetupJobControl, probe:1533
    
40. MESSAGE : Starting an online db backup.
    
41. 
    
42. 2015-12-22-01.30.06.190350+480 E8021745E426        LEVEL: Info
    
43. PID     : 4041                 TID  : 140514032609024PROC : db2sysc
    
44. INSTANCE: db2inst1             NODE : 000          DB   : AMFEDB
    
45. APPHDL  : 0-1147               APPID: *LOCAL.db2inst1.151221173003
    
46. AUTHID  : DB2INST1
    
47. EDUID   : 28                   EDUNAME: db2agent (AMFEDB)
    
48. FUNCTION: DB2 UDB, database utilities, sqlubcka, probe:838
    
49. MESSAGE : Backup complete.
    
50. 
    
51. 2015-12-22-01.30.06.369583+480 E8022172E520        LEVEL: Info
    
52. PID     : 4041                 TID  : 140514024220416PROC : db2sysc
    
53. INSTANCE: db2inst1             NODE : 000          DB   : AMFEDB
    
54. APPHDL  : 0-1148               APPID: *LOCAL.db2inst1.151221173006
    
55. AUTHID  : DB2INST1
    
56. EDUID   : 30                   EDUNAME: db2agent (AMFEDB)
    
57. FUNCTION: DB2 UDB, database utilities, sqluhDeletionReport, probe:381
    
58. MESSAGE : ADM8506I  Successfully deleted the following database logs "327 -
    
59.           357" in log chain "0".
    
60. 
    
61. 2015-12-22-01.30.06.480547+480 I8022693E365        LEVEL: Warning
    
62. PID     : 4041                 TID  : 140513583818496PROC : db2sysc
    
63. INSTANCE: db2inst1             NODE : 000
    
64. EDUID   : 140                  EDUNAME: db2logmgr (AMFEDB)
    
65. FUNCTION: DB2 UDB, data protection services, sqlpgArchiveLogFile, probe:3108
    
66. MESSAGE : Started archive for log file S0000357.LOG.
    
67. 
    
68. 2015-12-22-01.30.06.480662+480 I8023059E448        LEVEL: Error
    
69. PID     : 4041                 TID  : 140513583818496PROC : db2sysc
    
70. INSTANCE: db2inst1             NODE : 000
    
71. EDUID   : 140                  EDUNAME: db2logmgr (AMFEDB)
    
72. FUNCTION: DB2 UDB, data protection services, sqlpgArchiveLogDisk, probe:2500
    
73. RETCODE : ZRC=0x860F000A=-2045837302=SQLO_FNEX "File not found."
    
74.           DIA8411C A file "/amfe/dblog/S0000357.LOG" could not be found.
    
75. 
    
76. 2015-12-22-01.30.06.480740+480 E8023508E473        LEVEL: Warning
    
77. PID     : 4041                 TID  : 140513583818496PROC : db2sysc
    
78. INSTANCE: db2inst1             NODE : 000
    
79. EDUID   : 140                  EDUNAME: db2logmgr (AMFEDB)
    
80. FUNCTION: DB2 UDB, data protection services, sqlpgArchiveLogFile, probe:3150
    
81. MESSAGE : ADM1848W  Failed to archive log file "S0000357.LOG" to
    
82.           "/amfe/dbarchlog/db2inst1/AMFEDB/NODE0000/C0000000/" from
    
83.           "/amfe/dblog/".
    
84. 
    
85. 2015-12-22-01.30.06.480857+480 E8023982E562        LEVEL: Error
    
86. PID     : 4041                 TID  : 140513583818496PROC : db2sysc
    
87. INSTANCE: db2inst1             NODE : 000
    
88. EDUID   : 140                  EDUNAME: db2logmgr (AMFEDB)
    
89. FUNCTION: DB2 UDB, data protection services, sqlpgArchiveLogFile, probe:3160
    
90. MESSAGE : ZRC=0x860F000A=-2045837302=SQLO_FNEX "File not found."
    
91.           DIA8411C A file "" could not be found.
    
92. DATA #1 : <preformatted>
    
93. Failed to archive log file S0000357.LOG to /amfe/dbarchlog/db2inst1/AMFEDB/NODE0000/C0000000/ from /amfe/dblog/.
    
94. 
    
95. 2015-12-22-01.30.06.480922+480 I8024545E381        LEVEL: Error
    
96. PID     : 4041                 TID  : 140513583818496PROC : db2sysc
    
97. INSTANCE: db2inst1             NODE : 000
    
98. EDUID   : 140                  EDUNAME: db2logmgr (AMFEDB)
    
99. FUNCTION: DB2 UDB, data protection services, sqlpgArchivePendingLogs, probe:1500
    
100. MESSAGE : Log archive failed with rc -2045837302 for LOGARCHMETH1.
     
101. 
     
102. 2015-12-22-01.30.27.480090+480 I8024927E365        LEVEL: Warning
     
103. PID     : 4041                 TID  : 140513583818496PROC : db2sysc
     
104. INSTANCE: db2inst1             NODE : 000
     
105. EDUID   : 140                  EDUNAME: db2logmgr (AMFEDB)
     
106. FUNCTION: DB2 UDB, data protection services, sqlpgArchiveLogFile, probe:3108
     
107. MESSAGE : Started archive for log file S0000357.LOG.
     
108. 
     
109. 2015-12-22-01.30.27.480205+480 I8025293E448        LEVEL: Error
     
110. PID     : 4041                 TID  : 140513583818496PROC : db2sysc
     
111. INSTANCE: db2inst1             NODE : 000
     
112. EDUID   : 140                  EDUNAME: db2logmgr (AMFEDB)
     
113. FUNCTION: DB2 UDB, data protection services, sqlpgArchiveLogDisk, probe:2500
     
114. RETCODE : ZRC=0x860F000A=-2045837302=SQLO_FNEX "File not found."
     
115.           DIA8411C A file "/amfe/dblog/S0000357.LOG" could not be found.
     
116. 
     
117. 2015-12-22-01.30.27.480284+480 E8025742E473        LEVEL: Warning
     
118. PID     : 4041                 TID  : 140513583818496PROC : db2sysc
     
119. INSTANCE: db2inst1             NODE : 000
     
120. EDUID   : 140                  EDUNAME: db2logmgr (AMFEDB)
     
121. FUNCTION: DB2 UDB, data protection services, sqlpgArchiveLogFile, probe:3150
     
122. MESSAGE : ADM1848W  Failed to archive log file "S0000357.LOG" to
     
123.           "/amfe/dbarchlog/db2inst1/AMFEDB/NODE0000/C0000000/" from
     
124.           "/amfe/dblog/".
     
125. 
     
126. 2015-12-22-01.30.27.480390+480 E8026216E562        LEVEL: Error
     
127. PID     : 4041                 TID  : 140513583818496PROC : db2sysc
     
128. INSTANCE: db2inst1             NODE : 000
     
129. EDUID   : 140                  EDUNAME: db2logmgr (AMFEDB)
     
130. FUNCTION: DB2 UDB, data protection services, sqlpgArchiveLogFile, probe:3160
     
131. MESSAGE : ZRC=0x860F000A=-2045837302=SQLO_FNEX "File not found."
     
132.           DIA8411C A file "" could not be found.
     
133. DATA #1 : <preformatted>
     
134. Failed to archive log file S0000357.LOG to /amfe/dbarchlog/db2inst1/AMFEDB/NODE0000/C0000000/ from /amfe/dblog/.
     
135. 
     
136. 2015-12-22-01.30.27.480439+480 I8026779E510        LEVEL: Warning
     
137. PID     : 4041                 TID  : 140513583818496PROC : db2sysc
     
138. INSTANCE: db2inst1             NODE : 000
     
139. EDUID   : 140                  EDUNAME: db2logmgr (AMFEDB)
     
140. FUNCTION: DB2 UDB, data protection services, sqlpgRetryFailedArchive, probe:4760
     
141. MESSAGE : Unable to find log file 357 for LOGARCHMETH1 using method 1 and
     
142.           target /amfe/dbarchlog/db2inst1/AMFEDB/NODE0000/C0000000/.  Assume
     
143.           log was archived and continue.
     

复制代码

shiner

有知道怎么回事的亲么？ 我这个脚本在测试环境是没问题的，一上生产就报错，头疼！
而且我看大家推荐的清除归档日志的方式就是这个方式。

大梦

从日志文件看备份是成功了，问题应该是出在db2 prune history 与LOGARCHMETH1相斥上！你的测试环境与生产环境LOGARCHMETH1应该不一样吧！
建议你把$filetimestamp这个时间再缩小一天看看！就是清昨天之前的日志！

shiner

回复 3# 大梦

大梦兄，我生产和测试环境的DB2环境变量都看了
db2 get db cfg 全部列出来比较了一下
就有两个地方不一样：
测试：Degree of parallelism       (DFT_DEGREE) = ANY
生产：Degree of parallelism       (DFT_DEGREE) = 1

测试：First active log file            = S0005955.LOG
生产：First active log file            = S0000518.LOG


LOGARCHMETH1 的值都是 DISK:/amfe/dbarchlog/

   

shiner

感觉好像是 S0000357.LOG 这个日志还没有归档完成，就已经被 db2 prune history 列入需要删除的日志列表里。

大梦

两边的参数上应该没问题！
从db2diag.log里面看应该是prune与archive log操作冲突了，找不到357号日志，之前被剪掉了！
你可以把prune的时间放再改早点或是根据当前的日志号减10，用prune logfile去做看看，避免冲途。

shiner

回复 6# 大梦
谢谢大梦兄弟，我试试看，这个为什么会出现冲突呢，DB2机制出了问题？