01-20-2016 11:48 AM
Sorry, veritas n00b here.
I've taken over a well setup veritas install, and have recently been getting error code 191 on a monthly SLP.
1/20/2016 10:14:19 AM - requesting resource LCM_zilac_maxturbo
1/20/2016 10:14:19 AM - granted resource LCM_zilac_maxturbo
1/20/2016 10:14:19 AM - started process RUNCMD (57947)
1/20/2016 10:14:20 AM - begin Duplicate
1/20/2016 10:14:20 AM - requesting resource zilac_maxturbo
1/20/2016 10:14:20 AM - requesting resource @aaaaf
1/20/2016 10:14:20 AM - reserving resource @aaaaf
1/20/2016 10:14:20 AM - ended process 0 (57947)
1/20/2016 10:14:52 AM - Info bpduplicate(pid=57947) window close behavior: Suspend
1/20/2016 10:14:52 AM - reserved resource @aaaaf
1/20/2016 10:14:52 AM - granted resource 060409
1/20/2016 10:14:52 AM - granted resource zilac-Drive1
1/20/2016 10:14:52 AM - granted resource zilac_maxturbo
1/20/2016 10:14:52 AM - granted resource MediaID=@aaaaf;DiskVolume=PureDiskVolume;DiskPool=Long_Term_dp_maxturbo;Path=PureDiskVolume;StorageServer=maxturbo;MediaServer=maxturbo
1/20/2016 10:14:53 AM - Info bptm(pid=184273) start
1/20/2016 10:14:53 AM - started process bptm (184273)
1/20/2016 10:14:54 AM - Info bptm(pid=184273) using 65536 data buffer size
1/20/2016 10:14:54 AM - Info bptm(pid=184273) using 30 data buffers
1/20/2016 10:14:54 AM - Info bptm(pid=184273) start backup
1/20/2016 10:14:54 AM - Info bpdm(pid=184284) started
1/20/2016 10:14:54 AM - started process bpdm (184284)
1/20/2016 10:14:54 AM - Info bptm(pid=184273) backup child process is pid 184286
1/20/2016 10:14:54 AM - Info bpdm(pid=184284) reading backup image
1/20/2016 10:14:54 AM - Info bptm(pid=184273) Waiting for mount of media id 060409 (copy 2) on server maxturbo.
1/20/2016 10:14:54 AM - started process bptm (184273)
1/20/2016 10:14:54 AM - mounting 060409
1/20/2016 10:14:54 AM - Info bptm(pid=184273) INF - Waiting for mount of media id 060409 on server maxturbo for writing.
1/20/2016 10:14:55 AM - Info bpdm(pid=184284) using 30 data buffers
1/20/2016 10:14:55 AM - Info bpdm(pid=184284) spawning a child process
1/20/2016 10:14:55 AM - Info bpbrm(pid=184284) child pid: 184287
1/20/2016 10:14:55 AM - Info bpdm(pid=184284) requesting nbjm for media
1/20/2016 10:14:58 AM - begin reading
1/20/2016 10:15:53 AM - Info bptm(pid=184273) media id 060409 mounted on drive index 1, drivepath /dev/nst25, drivename zilac-Drive1, copy 2
1/20/2016 10:15:54 AM - Info bptm(pid=184273) INF - Waiting for positioning of media id 060409 on server maxturbo for writing.
1/20/2016 10:18:57 AM - end reading; read time: 0:03:59
1/20/2016 10:18:57 AM - Critical bpdm(pid=184284) image open failed: error 2060001: one or more invalid arguments
1/20/2016 10:18:57 AM - Info maxturbo(pid=184284) StorageServer=PureDisk:maxturbo; Report=PDDO Stats for (maxturbo): read: 10240001 KB, CR received: 1071109 KB, CR received over FC: 0 KB, dedup: 0.0%
1/20/2016 10:18:59 AM - Error bptm(pid=184273) media manager terminated by parent process
1/20/2016 10:19:39 AM - Error bpduplicate(pid=57947) host maxturbo backup id dasn-db_1452484803 read failed, media open error (83).
1/20/2016 10:19:39 AM - Error bpduplicate(pid=57947) host maxturbo backupid dasn-db_1452484803 write failed, media manager killed by signal (82).
1/20/2016 10:19:39 AM - Error bpduplicate(pid=57947) Duplicate of backupid dasn-db_1452484803 failed, media manager killed by signal (82).
1/20/2016 10:19:39 AM - Error bpduplicate(pid=57947) Status = no images were successfully processed.
1/20/2016 10:19:39 AM - end Duplicate; elapsed time: 0:05:19
no images were successfully processed (191)
master is netbackup 7.6.1.2 on RHEL
media is 2.6.1.2 on a 5320 appliance.
01-20-2016 12:45 PM
From your log text:
1/20/2016 10:14:54 AM - mounting 060409 ... 1/20/2016 10:15:53 AM - Info bptm(pid=184273) media id 060409 mounted on drive index 1, drivepath /dev/nst25, drivename zilac-Drive1, copy 2 1/20/2016 10:15:54 AM - Info bptm(pid=184273) INF - Waiting for positioning of media id 060409 on server maxturbo for writing. 1/20/2016 10:18:57 AM - end reading; read time: 0:03:59 1/20/2016 10:18:57 AM - Critical bpdm(pid=184284) image open failed: error 2060001: one or more invalid arguments
...the wait for media positioning timed out after 3 minutes 3 seconds - which is suspiciously close to a 180 second wait.
I think you need to look at the bptm log on your media server to determine why the media positioning took so long.
01-21-2016 03:12 AM
I would check the bpdm log too.