NBU 由6.5.5升级到7.1.0.2后，vault eject报12号错

rand1985

各位高手好！
     我在升级master server ，由NBU6.5.6版本到7.1.0.2版本，除了vault eject作业外，其他都正常完成。vault eject作业报12号错。



对照发现，有以下的变化：
1、NBU原来有 db_ccfs02_daily_vault,omas_daily_vault, 这两个vault 策略没有激活，对应的profile 文件也没有。
对应的profile为：
   ccfs02_daily_vault和 omas_daily_vault
2、6.5版本和7.1版本的 vault的会话日志目录发生了变化，nbu  6.5版本和NBu7.1 版本的会话日志的目录为：
install_path\NetBackup\vault\sessions\vault_name\sidxxx（6.5）
install_path\NetBackup\vault\sessions\vault_name\sidxxx\logs（7.1）
日志的产生，倒都是放在6.5版本原来你的路径下；日志不是放在新的会话目录下。

现在磁带弹出策略一直报12号错误，导致磁带不能正常弹出，每次都要手动弹出。太麻烦了！
请教给位，要保证磁带弹出策略的正常完成，我应该怎样做？？

rand1985

****************
job  detailed  status为：
2012-10-9 20:00:00 - Info nbjm(pid=5584) starting backup job (jobid=357206) for client sccfile02103, policy vault_eject, schedule vault_eject  
2012-10-9 20:00:00 - Info nbjm(pid=5584) requesting NO_STORAGE_UNIT resources from RB for backup job (jobid=357206, request id:{1110CEFF-1AE4-427D-9BF3-39DEA8F84C5D})  
2012-10-9 20:00:00 - requesting resource sccfile02103.NBVAULT.MAXJOBS
2012-10-9 20:00:00 - requesting resource sccfile02103.NBU_POLICY.MAXJOBS.vault_eject
2012-10-9 20:00:00 - granted resource sccfile02103.NBVAULT.MAXJOBS
2012-10-9 20:00:00 - granted resource sccfile02103.NBU_POLICY.MAXJOBS.vault_eject
2012-10-9 20:00:00 - estimated 0 Kbytes needed
2012-10-9 20:00:00 - begin Parent Job
2012-10-9 20:00:00 - begin Vault, Start Notify Script
2012-10-9 20:00:01 - Info RUNCMD(pid=4284) started            
2012-10-9 20:00:01 - Info RUNCMD(pid=4284) exiting with status: 0         
Status 0
2012-10-9 20:00:01 - end Vault, Start Notify Script; elapsed time: 00:00:01
2012-10-9 20:00:01 - begin Vault, Execute Script
2012-10-9 20:00:01 - started process bpbrm (6692)
2012-10-9 20:00:03 - begin Eject/Report
2012-10-9 20:00:03 - connecting
2012-10-9 20:00:03 - connected; connect time: 00:00:00
2012-10-9 20:00:03 - end Eject/Report; elapsed time: 00:00:00
2012-10-9 20:00:03 - end writing
Status 12
2012-10-9 20:00:03 - end Vault, Execute Script; elapsed time: 00:00:02
2012-10-9 20:00:03 - begin Vault, Stop On Error
Status 0
2012-10-9 20:00:03 - end Vault, Stop On Error; elapsed time: 00:00:00
2012-10-9 20:00:03 - begin Vault, End Notify Script
2012-10-9 20:00:04 - Info RUNCMD(pid=2120) started            
2012-10-9 20:00:04 - Info RUNCMD(pid=2120) exiting with status: 0         
Status 0
2012-10-9 20:00:04 - end Vault, End Notify Script; elapsed time: 00:00:01
Status 12
2012-10-9 20:00:04 - end Parent Job; elapsed time: 00:00:04
file open failed(12)

**********************

rand1985

查看install_path\veritas\netbackup\logs\vault\100912.log

**********************
20:00:01.946 [6692.5676] <4> vlteject@main: INITIATING vlteject (VERBOSE: 0 VER: NetBackup 7.1 (Vault) Aug 25 2011 15:32:55 CREATED: 2011082514)
20:00:01.946 [6692.5676] <2> VaultLog::SetLogLevel: Using Log Level = 2
20:00:01.946 [6692.5676] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\vlteject.exe -bt 1349784000 -jobid 357206 -jobgrpid 357206 -masterversion 710000 -eject -report
20:00:01.946 [6692.5676] <4> vlteject@cSigHandler:penWinEvent: Created terminate event:"Global\NetBackup Terminate Event, pid: 6692"
20:00:01.946 [6692.5676] <2> vlteject@setup_nbjm_fd: ExitStatus channel setup
20:00:01.946 [6692.5676] <2> vlteject@setup_nbjm_fd: Sent PIDSTR to nbjm. STRLEN=20 PIDSTR=PID of bpbrm = 6692

20:00:01.962 [6692.5676] <2> vlteject@verify_vault_license: ON=sccfile02103
20:00:01.962 [6692.5676] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6646: 0: fopen() failed: 2 0x00000002
20:00:01.962 [6692.5676] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6647: 0: fopen() failed: C:\Program Files\Veritas\NetBackup\var\host_cache\097\e4406897+bprd,1,0,2,1,0+sccfile02103.txt
20:00:01.962 [6692.5676] <2> vnet_pbxConnect: pbxConnectEx Succeeded
20:00:01.962 [6692.5676] <2> logconnections: BPRD CONNECT FROM 11.11.1.100.3296 TO 11.11.1.100.1556 fd = 1608
20:00:02.118 [6692.5676] <2> vlteject: VERBOSE = 0
20:00:02.133 [6692.5676] <2> Orb::init: initializing ORB Default_CLIENT_Orb with: Unknown -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb Default_CLIENT_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf nul -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(../Orb.cpp:824)
20:00:02.133 [6692.5676] <2> Orb::init: caching EndpointSelectorFactory(../Orb.cpp:839)
20:00:02.149 [6692.5676] <4> vlteject@main: VCPortal init SUCCEEDED
20:00:02.165 [6692.5676] <4> vlteject@main: VCPortal CFG load SUCCEEDED
20:00:02.212 [6692.5676] <2> vnet_pbxConnect: pbxConnectEx Succeeded
20:00:02.274 [6692.5676] <2> do_pbx_service: ../../libvlibs/vnet_connect.c.1776: 0: via PBX: VNETD CONNECT FROM 11.11.1.100.3300 TO 11.11.1.100.1556 fd = 1348
20:00:02.274 [6692.5676] <2> vnet_vnetd_disconnect: ../../libvlibs/vnet_vnetd.c.196: 0: VN_REQUEST_DISCONNECT: 1 0x00000001
20:00:02.274 [6692.5676] <4> get_emm_server_version_init: host sccfile02103 is version 710000
20:00:02.290 [6692.5676] <4> vlteject@main: >>********** VltEject Version: NetBackup 7.1 (Vault) Aug 25 2011 15:32:55 *************<<
20:00:02.290 [6692.5676] <4> vlteject@main: Start Time:                2012.10.09 20:00:02
20:00:02.290 [6692.5676] <2> vlteject@connectToVRTObject: Connecting to vccs server sccfile02103
20:00:02.290 [6692.5676] <2> Orb::init: initializing ORB VaultRuntimePortalORB with: Unknown -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb VaultRuntimePortalORB'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf nul -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(../Orb.cpp:824)
20:00:02.305 [6692.5676] <2> Orb::init: caching EndpointSelectorFactory(../Orb.cpp:839)
20:00:02.305 [6692.5676] <2> vlteject@connectToVRTObject: VaultRuntimePortal init succeeded
20:00:02.993 [6692.5676] <2> vlteject@getAllSessions: Returning 25 number of records
20:00:02.993 [6692.5676] <4> vlteject@VaultJobMonitor::_set_jobd_hostname: Jobd host=sccfile02103
20:00:02.993 [6692.5676] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6646: 0: fopen() failed: 2 0x00000002
20:00:02.993 [6692.5676] <2> file_to_addrinfo: ../../libvlibs/vnet_addrinfo.c.6647: 0: fopen() failed: C:\Program Files\Veritas\NetBackup\var\host_cache\097\e4406897+bpjobd,1,0,2,1,0+sccfile02103.txt
20:00:03.008 [6692.5676] <2> vnet_pbxConnect: pbxConnectEx Succeeded
20:00:03.008 [6692.5676] <2> job_connect: SO_KEEPALIVE set on socket 1276 for client sccfile02103
20:00:03.008 [6692.5676] <2> logconnections: BPJOBD CONNECT FROM 11.11.1.100.3305 TO 11.11.1.100.1556 fd = 1276
20:00:03.008 [6692.5676] <2> job_authenticate_connection: ignoring VxSS authentication check for now...
20:00:03.008 [6692.5676] <2> job_connect: Connected to the host sccfile02103 contype 10 jobid <0> socket <1276>
20:00:03.008 [6692.5676] <2> job_connect: Connected on port 3305
20:00:03.008 [6692.5676] <4> vlteject@VaultJobMonitor::_connect: Successfully connected to Job Daemon at 1349784003
20:00:03.008 [6692.5676] <2> vlteject@main: Connect 0 job 357206
20:00:03.008 [6692.5676] <4> vlteject@spawn_keepalive_thread: Entered with JOBID=357206
20:00:03.008 [6692.5676] <4> vlteject@spawn_keepalive_thread: Spawning thread
20:00:03.008 [6692.5676] <4> vlteject@spawn_keepalive_thread: Thread spawn returned=1
20:00:03.008 [6692.5676] <2> vlteject@init_global_lock(): MaxVltJobs=3
20:00:03.008 [6692.6748] <2> vlteject@jobd_pinger: Entered THD=6748 with JOBID=357206
20:00:03.008 [6692.5676] <2> vlteject@VaultJobMonitor::_send_jobdata: SENT JobDataEx_t to JOBD at 1349784003
20:00:03.008 [6692.6748] <4> vlteject@VaultJobMonitor::_set_jobd_hostname: Jobd host=sccfile02103
20:00:03.008 [6692.5676] <2> vlteject@VaultJobMonitor::_send_jobrundata: SENT JobRunDataEx_t to JOBD at 1349784003
20:00:03.008 [6692.5676] <4> vlteject@veject(): *************************VEJECT STARTS**********************
20:00:03.008 [6692.5676] <2> vlteject@veject(): Received eject request with following parms
20:00:03.008 [6692.5676] <2> vlteject@veject(): Robot Num = null
20:00:03.008 [6692.5676] <2> vlteject@veject(): Vault Name = null
20:00:03.008 [6692.5676] <2> vlteject@veject(): Profile Name = null
20:00:03.008 [6692.5676] <2> vlteject@veject(): Session Id= 0
20:00:03.008 [6692.5676] <2> vlteject@veject(): Printing the structure elements eject parms
preview Requested:0

20:00:03.008 [6692.5676] <2> vlteject@veject(): Extending the scope of existing job id 357206
20:00:03.008 [6692.5676] <2> vlteject@VaultJobMonitor::_send_jobrundata: SENT JobRunDataEx_t to JOBD at 1349784003
20:00:03.008 [6692.5676] <4> vlteject@VaultJobMonitor::_send_operation: Sent Operation=8 op to Job daemon
20:00:03.008 [6692.5676] <2> vlteject@VaultJobMonitor::_find_op_string: Found 8->EJECT_AND_REPORT
20:00:03.008 [6692.5676] <2> vlteject@VaultJobMonitor::_send_try_msg: At 1349784003 sent TRY_MSG: BEGIN_OPERATION 1349784003 EJECT_AND_REPORT

20:00:03.008 [6692.5676] <2> vlteject@VaultJobMonitor::_send_try_msg: At 1349784003 sent TRY_MSG: CONNECT 1349784003

20:00:03.008 [6692.5676] <2> vlteject@VaultJobMonitor::_send_try_msg: At 1349784003 sent TRY_MSG: CONNECTED 1349784003

20:00:03.024 [6692.6748] <2> vnet_pbxConnect: pbxConnectEx Succeeded
20:00:03.024 [6692.6748] <2> job_connect: SO_KEEPALIVE set on socket 1232 for client sccfile02103
20:00:03.024 [6692.6748] <2> logconnections: BPJOBD CONNECT FROM 11.11.1.100.3306 TO 11.11.1.100.1556 fd = 1232
20:00:03.024 [6692.6748] <2> job_authenticate_connection: ignoring VxSS authentication check for now...
20:00:03.024 [6692.6748] <2> job_connect: Connected to the host sccfile02103 contype 10 jobid <0> socket <1232>
20:00:03.024 [6692.6748] <2> job_connect: Connected on port 3306
20:00:03.024 [6692.6748] <4> vlteject@VaultJobMonitor::_connect: Successfully connected to Job Daemon at 1349784003
20:00:03.024 [6692.6748] <2> vlteject@VaultJobMonitor::_send_jobdata: SENT JobDataEx_t to JOBD at 1349784003
20:00:03.024 [6692.5676] <2> vlteject@connectToVRTObject: Connecting to vccs server sccfile02103
20:00:03.024 [6692.5676] <2> Orb::init: initializing ORB VaultRuntimePortalORB with: Unknown -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBDefaultInitRef '' -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb VaultRuntimePortalORB'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf nul -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(../Orb.cpp:824)
20:00:03.024 [6692.5676] <2> Orb::init: caching EndpointSelectorFactory(../Orb.cpp:839)
20:00:03.024 [6692.5676] <2> vlteject@connectToVRTObject: VaultRuntimePortal init succeeded
20:00:03.712 [6692.5676] <2> vlteject@filterSessions(): Entering filterSessions Function [IN Size: 25]
20:00:03.712 [6692.5676] <16> VCCache::getVaultByName: ErrMsg for 322: cannot find vault in vault configuration file
20:00:03.712 [6692.5676] <16> VCCache::getVaultByName: No such Vault : omas
20:00:03.712 [6692.5676] <16> VCCache::getVaultByName: Leaving with SC=322
20:00:03.712 [6692.5676] <16> VCCache::getTripletByName: Leaving with SC=322
20:00:03.712 [6692.5676] <16> VCSnapshotPortal::fetchRVPandGP: Leaving with SC=322
20:00:03.712 [6692.5676] <2> vlteject@filterSessions(): Deleting session element :  Robot=> 0  Vault => omas                       Profile=> omas_daily_vault  SessionId => 961  status => 322
20:00:03.712 [6692.5676] <16> VCCache::getVaultByName: ErrMsg for 322: cannot find vault in vault configuration file
20:00:03.712 [6692.5676] <16> VCCache::getVaultByName: No such Vault : ccfs
20:00:03.712 [6692.5676] <16> VCCache::getVaultByName: Leaving with SC=322
20:00:03.712 [6692.5676] <16> VCCache::getTripletByName: Leaving with SC=322
20:00:03.712 [6692.5676] <16> VCSnapshotPortal::fetchRVPandGP: Leaving with SC=322
20:00:03.712 [6692.5676] <2> vlteject@filterSessions(): Deleting session element :  Robot=> 0  Vault => ccfs                       Profile=> ccfs02_daily_vault  SessionId => 975  status => 322
20:00:03.712 [6692.5676] <2> vlteject@filterSessions(): Retruning... [OUT Size: 23]
20:00:03.712 [6692.5676] <2> vlteject@Consolidate::getSessionsForEject(): Returning 23 number of records
20:00:03.712 [6692.5676] <2> vlteject@RobotsFactory::RobotsFactory(): Robots factory received 23 number of session records
20:00:03.712 [6692.5676] <2> vlteject@RobotsFactory::prepareRobotAndVaultObjects(): Creating Robot object for Robot 0
20:00:03.712 [6692.5676] <2> vlteject@VaultRobot::VaultRobot(): >>********** Vault Robot Starts *************<<
20:00:03.712 [6692.5676] <2> vlteject@VaultRobot::VaultRobot(): rob Type = TLD
20:00:03.712 [6692.5676] <2> vlteject@VaultRobot::VaultRobot(): Lower rob Type = tld
20:00:03.712 [6692.5676] <2> vlteject@VaultScsiRobot::VaultScsiRobot(): >>********** Vault SCSI Robot Starts *************<<
20:00:03.712 [6692.5676] <2> vlteject@RobotsFactory::prepareRobotAndVaultObjects(): Creating Vault Session object for Profile:aic_unicall_daily_vault and Sessionid:979
20:00:03.712 [6692.5676] <2> vlteject@VaultSession::VaultSession: Creating a vault session with 0 aic_unicall aic_unicall_daily_vault 979
20:00:03.712 [6692.5676] <2> vlteject@ReportUtil::checkForValidityOfSession(): For SDIR=C:\Program Files\Veritas\NetBackup\vault\sessions\aic_unicall\sid979\logs
20:00:03.727 [6692.5676] <16> vlteject@VaultSession::VaultSession: cannot open detail.log file in directory C:\Program Files\Veritas\NetBackup\vault\sessions\aic_unicall\sid979\logs
20:00:03.758 [6692.5676] <8> vlteject@VaultSession::VaultSession Destructor called during stack unwinding
20:00:03.758 [6692.5676] <8> vlteject@RobotsFactory::prepareRobotAndVaultObjects() Destructor called during stack unwinding
20:00:03.758 [6692.5676] <8> vlteject@RobotsFactory::RobotsFactory() Destructor called during stack unwinding
20:00:03.758 [6692.5676] <8> vlteject@veject() Destructor called during stack unwinding
20:00:03.758 [6692.5676] <2> vlteject@VaultLockProxy::release_all_locks: UpdJobd=0
20:00:03.758 [6692.5676] <8> vlteject@VaultLockProxy::release_all_locks Destructor called during stack unwinding
20:00:03.758 [6692.5676] <8> vlteject@VaultLockProxy DES Destructor called during stack unwinding
20:00:03.758 [6692.5676] <16> vlteject@main: file open failed
20:00:03.758 [6692.5676] <2> vlteject@bv_vlteject_exit(): In
20:00:03.758 [6692.5676] <2> vlteject@VaultJobMonitor::_send_try_msg: At 1349784003 sent TRY_MSG: END_OPERATION 1349784003

20:00:03.758 [6692.5676] <4> vlteject@bv_vlteject_exit():  Job Complete
20:00:03.758 [6692.5676] <2> vlteject@cancel_keepalive_thread: Entered with JOBID=357206 to cancel ThrGrpId=1
20:00:03.758 [6692.5676] <8> vlteject@cancel_keepalive_thread: Notified keepalive thread. Sleeping for a bit (4 secs)
20:00:06.024 [6692.6748] <8> vlteject@jobd_pinger: Cancel requested. Leaving...

yuanquan44

删除，重新配置下vault？

rand1985

重配vault，工作量 太大！ 而且条件也不允许！
     我只是将10.09当天的sidXX下的日志信息，copy到新建的logs下，再执行vault eject策略就好！