Problem
VMware Backup fails with error status 25
Error Message
Activity Monitor Job Detailed Status shows:
28/07/2012 2:12:09 PM - estimated 51551248 Kbytes needed
28/07/2012 2:12:09 PM - begin Parent Job
28/07/2012 2:12:09 PM - begin Application Resolver, Start Notify Script
28/07/2012 2:12:09 PM - Info RUNCMD(pid=5428) started
28/07/2012 2:12:09 PM - Info RUNCMD(pid=5428) exiting with status: 0
Status 0
28/07/2012 2:12:09 PM - end Application Resolver, Start Notify Script; elapsed time: 00:00:00
28/07/2012 2:12:09 PM - begin Application Resolver, Step By Condition
Status 0
28/07/2012 2:12:09 PM - end Application Resolver, Step By Condition; elapsed time: 00:00:00
28/07/2012 2:12:09 PM - begin Application Resolver, Resolver Discovery
Status 25
28/07/2012 2:14:21 PM - end Application Resolver, Resolver Discovery; elapsed time: 00:02:12
28/07/2012 2:14:21 PM - begin Application Resolver, Stop On Error
Status 0
28/07/2012 2:14:21 PM - end Application Resolver, Stop On Error; elapsed time: 00:00:00
28/07/2012 2:14:21 PM - begin Application Resolver, End Notify Script
28/07/2012 2:14:21 PM - Info RUNCMD(pid=3928) started
28/07/2012 2:14:21 PM - Info RUNCMD(pid=3928) exiting with status: 0
Status 0
28/07/2012 2:14:21 PM - end Application Resolver, End Notify Script; elapsed time: 00:00:00
Status 25
28/07/2012 2:14:21 PM - end Parent Job; elapsed time: 00:02:12
cannot connect on socket(25)
Cause
Checking bpcd log on the VM backup host:
07:58:54.209 [3060.3776] <2> bpcd main: VERBOSE = 5
07:58:54.209 [3060.3776] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpcd.exe -standalone
07:58:54.209 [3060.3776] <2> ReadKeyfile: keyfile C:\Program Files\Veritas\NetBackup\bin\keyfile.dat does not exist
07:58:54.209 [3060.3776] <2> process_requests: offset to GMT -36000
07:58:54.209 [3060.3776] <2> logconnections: BPCD ACCEPT FROM 10.1.1.1.53664 TO 10.1.1.16.1556 fd = 364
07:58:54.209 [3060.3776] <2> init_resilient_cache: [vnet_nbrntd.c:869] Initialize resilient cache. 0 0x0
07:58:54.209 [3060.3776] <2> process_requests: setup_sockopts complete
07:58:54.209 [3060.3776] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
07:58:54.209 [3060.3776] <2> vnet_cached_getnameinfo: [vnet_addrinfo.c:1895] found via getnameinfo OUR_HOST=mybackup-host IPSTR=10.1.1.1
07:58:54.209 [3060.3776] <2> bpcd peer_hostname: Connection from host mybackup-host (10.1.1.1) port 53664
07:58:54.209 [3060.3776] <2> bpcd valid_server: comparing master-host and mybackup-host
07:58:54.209 [3060.3776] <2> vnet_pcache_init_table: [vnet_private.c:235] starting cache size 200 0xc8
07:58:54.209 [3060.3776] <2> bpcd valid_server: comparing media-host and mybackup-host
07:58:54.209 [3060.3776] <4> bpcd valid_server: mybackup-host is not a master server
07:58:54.209 [3060.3776] <16> bpcd valid_server: mybackup-host is not a media server either
07:58:54.209 [3060.3776] <2> process_requests: output socket port number = 1
...
07:58:54.272 [3060.3776] <2> process_requests: <---- NetBackup 7.5 0 ------------initiated
07:58:54.272 [3060.3776] <2> process_requests: VERBOSE = 5
07:58:54.272 [3060.3776] <2> bpcd exit_bpcd: exit status 46 ----------->exiting
07:58:54.272 [3060.3776] <4> bpcd exit_bpcd: FTL - BPCD EXIT STATUS 46
07:58:54.287 [3060.3776] <16> process_requests: Server access denied
Solution
Add mybackup-host to the VM backup host's server list should allow the backup to start:
Applies To
Masterserver: master-host
Media Server: media-host
VM Backup Host: mybackup-host