NBU报86错误？？日志中报40

Question

更换磁带机后
每次备份catalog时Netbackup 频繁报状态码86

bpdbjobs
208065 Catalog Backup   Done     86                                                           27910
208066         Backup   Done      0     informix_set3 Default-Application-Backup      11323
208067 Catalog Backup   Done     86                                                             13068
208068         Backup   Done      0     informix_set3 Default-Application-Backup    27766
208069 Catalog Backup   Done     86                                                               29546
208070         Backup   Done      0     informix_set2         informix_set2_full       1130
208071         Backup   Done      0     informix_set2 Default-Application-Backup      1226
208072         Backup   Done      0     informix_set2 Default-Application-Backup     2217
208073         Backup   Done      0     informix_set2 Default-Application-Backup     2437
208074         Backup   Done      0     informix_set2 Default-Application-Backup        2617
208075         Backup   Done      0     informix_set3 Default-Application-Backup      17030
208076         Backup   Done      0     informix_set1 Default-Application-Backup      19419
208077         Backup   Done      0     informix_set3 Default-Application-Backup       4083
208078         Backup   Done      0     informix_set2 Default-Application-Backup    18422
208079         Backup   Done      0 informix_etc_set2          informix_etc_set2    18669
208080 Catalog Backup   Done      0                                                                 20006
208081         Backup   Done      0     informix_set3 Default-Application-Backup ty_set3           22734
208082 Catalog Backup   Done      0                                                        24541
208083         Backup   Done      0     informix_set3 Default-Application-Backup ty_set3    10911
208084 Catalog Backup   Done     86                                                                12680
208085         Backup   Done      0     informix_set3 Default-Application-Backup ty_set3         28455
208086 Catalog Backup   Done     86                                                                313
208087         Backup   Done      0     informix_set3 Default-Application-Backup      16319
208088 Catalog Backup   Done     86                                             
208089         Backup   Done      0     informix_set3 Default-Application-Backup      4100
208090 Catalog Backup   Done     86                                                              5841
208091         Backup   Done      0     informix_set3 Default-Application-Backup  19072
208092         Backup   Done      0     informix_set4 Default-Application-Backup      20732
208093 Catalog Backup   Done      0                                                            22514
208094         Backup   Done      0     informix_set3 Default-Application-Backup      28693
208095 Catalog Backup   Done      0                                                              680
208096         Backup   Done     86     informix_set3 Default-Application-Backup        4851
208097         Backup   Done     86     informix_set3 Default-Application-Backup       6978
208098         Backup   Done     86     informix_set3 Default-Application-Backup   9816
208099         Backup   Done     86     informix_set3 Default-Application-Backup  11954
208100         Backup   Done    150     informix_set3 Default-Application-Backup    17303
208101         Backup   Done     50     informix_set3 Default-Application-Backup   22535
208102         Backup   Done      0     informix_set3 Default-Application-Backup    23126
208103         Backup   Done      0     informix_set3 Default-Application-Backup    23863
208104         Backup   Done      0     informix_set3 Default-Application-Backup     24117
208105         Backup   Done      0     informix_set3 Default-Application-Backup    25497
208106         Backup   Done      0     informix_set3 Default-Application-Backup  26791
208107 Catalog Backup   Done     86                                               28367
208108         Backup Active            informix_set3 Default-Application-Backup       1386

bpbkar 日志
08:07:40.723 [1225] <4> bpbkar: INF - bpbkar exit normal
08:07:40.723 [1225] <4> bpbkar: INF - EXIT STATUS 0: the requested operation was successfully completed
08:07:40.723 [1225] <4> bpbkar: INF - setenv FINISHED=1
09:26:31.126 [28883] <4> bpbkar main: real locales <zh_CN.hp15CN zh_CN.hp15CN C zh_CN.hp15CN zh_CN.hp15CN zh_CN.hp15CN>
09:26:31.126 [28883] <4> bpbkar main: standardized locales - lc_messages <zh> lc_ctype <zh> lc_time <zh> lc_collate <zh> lc_numeric <zh>
09:26:31.127 [28883] <2> logparams: bpbkar -IEL -nfsok -dt 0 -ru root -nocont -no_security /usr/openv/netbackup/db
09:26:31.131 [28883] <4> bpbkar: INF - setenv KEYWORD=NONE
09:26:31.131 [28883] <4> bpbkar: INF - setenv STREAM_PID=28883
09:26:31.131 [28883] <4> bpbkar: INF - setenv STREAM_NUMBER=0
09:26:31.131 [28883] <4> bpbkar: INF - setenv STREAM_COUNT=0
09:26:31.131 [28883] <4> bpbkar: INF - setenv STREAMS=0
09:26:31.131 [28883] <4> bpbkar: INF - setenv BPSTART_TIMEOUT=0
09:26:31.131 [28883] <4> bpbkar: INF - setenv BPEND_TIMEOUT=0
09:26:31.131 [28883] <4> bpbkar: INF - setenv RESTARTED=0
09:26:31.131 [28883] <4> bpbkar: INF - Estimate:-1 -1
09:26:31.132 [28883] <4> bpbkar: INF - Processing /usr/openv/netbackup/db
09:26:31.158 [28883] <4> bpbkar: INF - VxFS filesystem is /usr for /usr/openv/netbackup/db
09:26:31.167 [28883] <4> bpbkar: INF - VX_UNBUFFERED failed on /usr/openv/netbackup/db/jobs/bpjobd.db. Errno = 251: 功能不可用
09:26:31.167 [28883] <4> bpbkar: INF - VX_DIRECT failed on /usr/openv/netbackup/db/jobs/bpjobd.db. Errno = 251: 功能不可用
09:28:36.737 [28883] <16> bpbkar: ERR - bpbkar killed by SIGPIPE
09:28:36.737 [28883] <16> bpbkar: ERR - bpbkar FATAL exit status = 40: network connection broken
09:28:36.737 [28883] <4> bpbkar: INF - EXIT STATUS 40: network connection broken
09:28:36.738 [28883] <4> bpbkar: INF - setenv FINISHED=0

bptm日志
09:25:01.444 [28354] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
09:25:01.444 [28354] <2> bptm: EXITING with status 0 <----------
09:25:02.080 [28363] <2> bptm: INITIATING (VERBOSE = 0): -U
09:25:02.081 [28363] <2> bptm: EXITING with status 0 <----------
09:25:02.870 [28383] <2> bptm: INITIATING (VERBOSE = 0): -load -jobid 208107 -ev M122L2 -den 14 -sync
09:25:02.871 [28383] <2> logconnections: BPJOBD CONNECT FROM 10.128.0.9.51917 TO 10.128.0.9.13723
09:25:02.874 [28383] <2> job_authenticate_connection: ignoring VxSS authentication check for now...
09:25:02.875 [28383] <2> job_connect: Connected to the host ty_set4 contype 10 jobid <208107> socket <7>
09:25:02.875 [28383] <2> job_connect: Connected on port 51917
09:25:02.875 [28383] <2> set_job_details: Done
09:25:02.896 [28383] <2> LOCAL CLASS_ATT_DEFS: Product ID = 6
09:25:02.914 [28383] <2> db_byid: search for media id M122L2
09:25:02.914 [28383] <2> add_to_vmhost_list: added ty_set4 to vmhost list
09:25:03.057 [28383] <2> vmdb_query_byID_getpool: server returned:  1 M122L2 ------ 14 XEM122L2 -------- 8 0 ty_set4 00_000_TLD - 10 0 0 0 0 root root 1 NetBackup - 1254274426 1254274471 1254276059 1255045455 0 0 414 0 1 - 0 0 50 0 0 0 0 0 - 0 0 0 0 0 0 0 0 0 0 0 - 0 0 0 0 0 0 0 0 0 0 0 0 - Added by Media Manager

09:25:03.057 [28383] <2> vmdb_query_byID_getpool: server returned:  1 21 NetBackup ANYHOST 0 -2 the NetBackup pool

09:25:03.058 [28383] <2> mount_open_media: Waiting for mount of media id M122L2 (copy 1) on server ty_set4.
09:25:03.058 [28383] <2> set_job_details: Done
09:25:03.058 [28383] <2> set_job_details: Done
09:26:26.177 [28383] <2> io_open: SCSI RESERVE
09:26:26.244 [28383] <2> io_open: file /tmp/sync_M122L2 successfully opened (mode 0)
09:26:26.244 [28383] <2> load_media: media id M122L2 mounted on drive index 1
09:26:26.244 [28383] <2> io_read_media_header: drive index 1, reading media header, buflen = 65536, buff = 0x400d0450, copy 1
09:26:26.245 [28383] <2> io_ioctl: command (5)MTREW 1 from (bptm.c.739 on drive index 1
09:26:26.421 [28383] <2> io_read_media_header: block read is not a NetBackup or Backup Exec media header, len = 1024, media id M122L2, drive index 1, data is NetBackup database backup
09:26:26.421 [28383] <2> io_ioctl: command (5)MTREW 1 from (tmisc.c.2593) on drive index 1
09:26:29.524 [28383] <2> io_close: closing /tmp/sync_M122L2, from tmisc.c.2894
09:26:29.534 [28383] <2> bptm: EXITING with status 0 <----------
09:28:00.719 [29331] <2> bptm: INITIATING (VERBOSE = 0): -unload -jobid 208107 -ev M122L2 -den 14 -sync
09:28:00.721 [29331] <2> logconnections: BPJOBD CONNECT FROM 10.128.0.9.52429 TO 10.128.0.9.13723
09:28:00.724 [29331] <2> job_authenticate_connection: ignoring VxSS authentication check for now...
09:28:00.724 [29331] <2> job_connect: Connected to the host ty_set4 contype 10 jobid <208107> socket <8>
09:28:00.725 [29331] <2> job_connect: Connected on port 52429
09:28:00.725 [29331] <2> set_job_details: Done
09:28:00.725 [29331] <2> db_byid: search for media id M122L2
09:28:00.725 [29331] <2> db_byid: search for media id
09:28:00.725 [29331] <2> tpunmount: tpunmount'ing /tmp/sync_M122L2
09:28:00.744 [29331] <2> TpUnmountWrapper: SCSI RELEASE
09:28:00.804 [29331] <2> bptm: EXITING with status 0 <----------
09:29:37.008 [29941] <2> bptm: INITIATING (VERBOSE = 0): -count -cmd -rt 8 -rn 0 -stunit ty_set4-hcart2-robot-tld-0 -den 14 -mt 2 -masterversion 510000
09:29:37.009 [29941] <2> bptm: NUM UP 2 2 0 0 2 0 HPUltrium2-SCSI0 HPUltrium2-SCSI1 a-HPUltrium2-SCSI0 a-HPUltrium2-SCSI1
09:29:37.009 [29941] <2> bptm: EXITING with status 0 <----------
09:29:38.559 [29959] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
09:29:38.559 [29959] <2> bptm: EXITING with status 0 <----------
09:33:49.301 [1367] <2> bptm: INITIATING (VERBOSE = 0): -count -cmd -rt 8 -rn 0 -stunit ty_set4-hcart2-robot-tld-0 -den 14 -mt 2 -masterversion 510000
09:33:49.302 [1367] <2> bptm: NUM UP 2 2 0 0 2 0 HPUltrium2-SCSI0 HPUltrium2-SCSI1 a-HPUltrium2-SCSI0 a-HPUltrium2-SCSI1
09:33:49.302 [1367] <2> bptm: EXITING with status 0 <----------
09:33:50.823 [1385] <2> bptm: INITIATING (VERBOSE = 0): -U
09:33:50.823 [1385] <2> bptm: EXITING with status 0 <----------
09:38:52.784 [3065] <2> bptm: INITIATING (VERBOSE = 0): -count -cmd -rt 8 -rn 0 -stunit ty_set4-hcart2-robot-tld-0 -den 14 -mt 2 -masterversion 510000
09:38:52.784 [3065] <2> bptm: NUM UP 2 2 0 0 2 0 HPUltrium2-SCSI0 HPUltrium2-SCSI1 a-HPUltrium2-SCSI0 a-HPUltrium2-SCSI1
09:38:52.784 [3065] <2> bptm: EXITING with status 0 <----------
09:39:03.084 [3205] <2> bptm: INITIATING (VERBOSE = 0): -mlist -cmd -raw
09:39:03.085 [3205] <2> bptm: EXITING with status 0 <----------
09:39:36.540 [3568] <2> bptm: INITIATING (VERBOSE = 0): -count -cmd -rt 8 -rn 0 -stunit ty_set4-hcart2-robot-tld-0 -den 14 -mt 2 -masterversion 510000
09:39:36.541 [3568] <2> bptm: NUM UP 2 2 0 0 2 0 HPUltrium2-SCSI0 HPUltrium2-SCSI1 b-HPUltrium2-SCSI0 a-HPUltrium2-SCSI1
09:39:36.541 [3568] <2> bptm: EXITING with status 0 <----------
09:39:38.099 [3576] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired
09:39:38.100 [3576] <2> bptm: EXITING with status 0 <----------

Answer 1

在加日志
04:10:45 [16709] <2> bpbkar write_eot: INF - bpbkar waited 200 times for empty buffer, delayed 205 times
这里是说备份等待清空buffer，但没有成功，
04:10:45 [16709] <2> bpbkar main: INF - Total Size:55896231
04:10:45 [16709] <4> bpbkar Exit: INF - bpbkar exit normal
04:10:45 [16709] <4> bpbkar Exit: INF - EXIT STATUS 0: the requested operation was successfully completed
已是放弃备份。

2009-11-21 04:08:14 16679  16677 /informix/bin/onbar_d -b -w -L 0
2009-11-21 04:08:14 16679  16677 Working with veritas-netbackup as generic storage manager.
2009-11-21 04:08:20 16679  16677 Begin level 0 backup rootdbs.
2009-11-21 04:08:20 16679  16677 Successfully connected to Storage Manager.
2009-11-21 04:08:20 16679  16677 Process 16679 received signal 13. Process will exit after cleanup.
2009-11-21 04:13:24 16679  16677 XBSA Error: (BSAEndData) A system error occurred. Aborting XBSA session.
2009-11-21 04:13:34 16679  16677 /informix/bin/onbar_d complete, returning 141 (0x8d)

2009-11-21 04:18:18 17198  17196 /informix/bin/onbar_d -b -w -L 0
2009-11-21 04:18:18 17198  17196 Working with veritas-netbackup as generic storage manager.
2009-11-21 04:18:22 17198  17196 Begin level 0 backup rootdbs.
2009-11-21 04:18:22 17198  17196 Successfully connected to Storage Manager.
2009-11-21 04:18:22 17198  17196 Process 17198 received signal 13. Process will exit after cleanup.
2009-11-21 04:22:25 17198  17196 XBSA Error: (BSAEndData) A system error occurred. Aborting XBSA session.
2009-11-21 04:22:36 17198  17196 /informix/bin/onbar_d complete, returning 141 (0x8d)

这里报了个buffer问题，请问怎么修改这个参数。

Answer 2

没看出个所以然

Answer 3

看到我的问题，帮忙回个

Answer 4

bpbkar FATAL exit status = 40: network connection broken

00:07:44 [6439] <4> bpbkar PrintFile: /app/crcigc/gjjs/bin/.DBLOADER_210_XTVMPSSG.lock
00:07:44 [6439] <4> bpbkar PrintFile: /app/crcigc/gjjs/bin/.DBLOADER_200_XTVMPSSG.lock
00:07:44 [6439] <4> bpbkar PrintFile: /app/crcigc/gjjs/bin/.control_sh.ini
00:07:44 [6439] <4> bpbkar process_file: INF - VX_UNBUFFERED failed on /app/crcigc/gjjs/bin/nohup.out. Errno = 251: Function is not available
00:07:44 [6439] <4> bpbkar process_file: INF - VX_DIRECT failed on /app/crcigc/gjjs/bin/nohup.out. Errno = 251: Function is not available
00:12:04 [6439] <16> bpbkar shm_addr: ERR - bpbkar exiting because backup is aborting
00:12:04 [6439] <16> bpbkar Exit: ERR - bpbkar FATAL exit status = 40: network connection broken
00:12:04 [6439] <4> bpbkar Exit: INF - EXIT STATUS 40: network connection broken
00:14:19 [6742] <2> logparams: bpbkar -r 2678400 -ru root -dt 605171 -to 0 -clnt jifei -cl

23:15:06 [11482] <4> bpbkar PrintFile: /app/crcigc/gjjs/log/bj_tfx/bj_tfx_16827_20091121002527.log
23:15:06 [11482] <4> bpbkar PrintFile: /app/crcigc/gjjs/log/bj_tfx/bj_tfx_16827_20091122002505.log
23:15:06 [11482] <4> bpbkar PrintFile: /app/crcigc/gjjs/log/bj_ver/
23:19:26 [11482] <16> bpbkar shm_addr: ERR - bpbkar exiting because backup is aborting
23:19:26 [11482] <16> bpbkar Exit: ERR - bpbkar FATAL exit status = 40: network connection broken
23:19:26 [11482] <4> bpbkar Exit: INF - EXIT STATUS 40: network connection broken
23:21:37 [11784] <2> logparams: bpbkar -r 2678400 -ru root -dt 167934 -to 0 -clnt jifei -class jifei_FS -sched inc-file -st CINC -bpstart_to 300 -bpend_to 300 -read_to 7200 -use_otm -fso -b jifei_1258903294 -kl 28 -shm
23:21:37 [11784] <2> mount build_mount_list: INF - Processing (vxfs) /dev/vg00/lvol3 on /

Answer 5

问题依旧?

Answer 6

scan
我用tpconfig都重新配置过一遍了。

Answer 7

如果不是HP-UX平台，你就再扫一遍设备。

Answer 8

是的，只是备份catalog就报86.很频繁啊。

昨天开始备份其他的数据也开始报86了

Answer 9

什么平台？
只是catalog备份报86,还是所有作业？