Unsolved
This post is more than 5 years old
1 Rookie
•
21 Posts
0
3857
April 17th, 2007 01:00
When the backupserver is restarted the AutoLoader is disabled
When I restarted the backup server for some Windows patches I started getting these error messages. Thought it was the patches so I uninstalled them again without any change. Apparently something else has gone wrong. It's not often the backup server is restarted.
If I wait for the retry attempts to finish, and Networker disables the autoloader I can enable the autoloader, and all the backup runs fine.
After testing I found that I can either wait for the retry attempts and enable the autoloader, or restart the services before networker disables the autoloader. Both actions makes networker run without problem, and the autoloader is not disabled.
Running Networker 7.3.2 Jumbo Update 1 build 386 on Windows Server 2003 Std SP2 x86. All Windows patches is installed to date. The server is a PowerEdge 860. The jukebox (autoloader) is a Compaq SSL2020 connected to the server by a SCSI Card, Adaptec 39320A Ultra320 SCSI.
See log below, this is the log after restart of backup server. I have stripped the first 9 retry attempts. First seven rows repeats 10 times.
~09:22 Networker is started. It tries 10 times and logs the information below.
09:36:25 the jukebox (autoloader) is disabled.
09:43:42 I manually enable the jukebox from NMC.
09:45:00 a schedule job is started.
daemon.log (stripped):
04/12/07 09:36:22 nsrlcpd #1: Creating Jukebox `SSL2020'
04/12/07 09:36:24 nsrlcpd #1: Configuring Jukebox `SSL2020'
04/12/07 09:36:24 nsrmmgd: lcpd 1 at host valas02.rottneros.com reported error 'Jukebox `SSL2020', command operation `CONFIG', command ID 2 is not connected.
' for the command `3'.
04/12/07 09:36:24 nsrmmgd: Configuration of jukebox `SSL2020' failed with the following error: 'Jukebox `SSL2020', command operation `CONFIG', command ID 2 is not connected.
'.
04/12/07 09:36:24 nsrmmgd: Jukebox 'SSL2020' is unavailable. The config command failed on nsrlcpd 1 for host `valas02'. Retrying..
04/12/07 09:36:24 nsrmmgd: mgd_process_admin_response: failed to process response for command 3 from lcpd 1 at host valas02.rottneros.com with error 'Jukebox `SSL2020', command operation `CONFIG', command ID 2 is not connected.
'.
04/12/07 09:36:24 nsrmmgd: nsrlcpd response processing failed with error 'Jukebox `SSL2020', command operation `CONFIG', command ID 2 is not connected.
'.
04/12/07 09:36:25 nsrmmgd: Jukebox 'SSL2020' is being disabled because configuration of nsrlcpd 1 at host `valas02' failed more than 10 times.
04/12/07 09:36:25 nsrmmgd: Disabling jukebox `SSL2020' due to a configuration problem. Correct the problem and re-enable the jukebox resource.
04/12/07 09:36:25 nsrd: Jukebox SSL2020 is now disabled.
04/12/07 09:36:25 nsrlcpd #1: Jukebox `SSL2020' is exiting. The jukebox is no longer managed by nsrlcpd.
04/12/07 09:43:42 nsrd: Jukebox SSL2020 is now enabled.
04/12/07 09:43:44 nsrmmgd: Started nsrlcpd on host `valas02' with command line options '-s valas02.rottneros.com -N 1 -n 1 '.
04/12/07 09:43:44 nsrlcpd #1: Starting program `nsrlcpd #1', with process ID 2768, on HOST valas02.rottneros.com.
04/12/07 09:43:48 nsrlcpd #1: Creating Jukebox `SSL2020'
04/12/07 09:43:50 nsrlcpd #1: Configuring Jukebox `SSL2020'
04/12/07 09:43:53 nsrd: [Jukebox `SSL2020', operation 1]. Initiated operation `operation: "Load"; operation device: "\\.\Tape0"; operation slots: "10"; write enabled: "Yes"; '.
04/12/07 09:43:53 nsrmmgd: Jukebox `SSL2020' is unavailable. Operation `initialize elements status' is in progress.
04/12/07 09:43:53 nsrd: [Jukebox `SSL2020', operation # 1]. Jukebox `SSL2020' is unavailable. Operation `initialize elements status' is in progress.
04/12/07 09:43:53 nsrd: [Jukebox `SSL2020', operation # 1]. Finished with status: retryable
04/12/07 09:43:53 nsrmmgd: Jukebox 'SSL2020' is not in a valid state for nsrlcpd update.
04/12/07 09:45:00 nsrd: savegroup info: starting valas06 (with 1 client(s))
04/12/07 09:45:06 nsrd: Operation 2 started : Load volume `BQQ848'.
.
04/12/07 09:45:06 nsrd: media waiting event: Waiting for 1 writable volumes to backup pool 'Vallvik' tape(s) on valas02.rottneros.com
04/12/07 09:45:08 nsrmmd #2: Start nsrmmd #2, with PID 3120, at HOST valas02.rottneros.com
04/12/07 09:45:25 nsrd: \\.\Tape0 Verify label operation in progress
04/12/07 09:45:43 nsrd: \\.\Tape0 Mount operation in progress
04/12/07 09:45:52 nsrd: media info: Jukebox `SSL2020' Hardware status of jukebox 'SSL2020' is 'ready'
04/12/07 09:46:41 nsrd: media event cleared: Waiting for 1 writable volumes to backup pool 'Vallvik' tape(s) on valas02.rottneros.com
04/12/07 09:46:41 nsrd: valas06:D:\MSSQL7\BACKUP saving to pool 'Vallvik' (BQQ848)
04/12/07 09:46:45 nsrd: [Jukebox `SSL2020', operation # 2]. Finished with status: succeeded
pools supported: Vallvik;
04/12/07 09:46:49 nsrd: valas06:D:\MSSQL7\BACKUP done saving to pool 'Vallvik' (BQQ848) 523 KB
04/12/07 09:46:50 nsrd: valas02.rottneros.com:index:valas06 saving to pool 'Vallvik' (BQQ848)
04/12/07 09:46:52 nsrd: valas02.rottneros.com:index:valas06 done saving to pool 'Vallvik' (BQQ848) 468 KB
04/12/07 09:46:52 nsrd: savegroup notice: valas06 completed, Total 1 client(s), 1 Succeeded. Please see group completion details for more information.
If I wait for the retry attempts to finish, and Networker disables the autoloader I can enable the autoloader, and all the backup runs fine.
After testing I found that I can either wait for the retry attempts and enable the autoloader, or restart the services before networker disables the autoloader. Both actions makes networker run without problem, and the autoloader is not disabled.
Running Networker 7.3.2 Jumbo Update 1 build 386 on Windows Server 2003 Std SP2 x86. All Windows patches is installed to date. The server is a PowerEdge 860. The jukebox (autoloader) is a Compaq SSL2020 connected to the server by a SCSI Card, Adaptec 39320A Ultra320 SCSI.
See log below, this is the log after restart of backup server. I have stripped the first 9 retry attempts. First seven rows repeats 10 times.
~09:22 Networker is started. It tries 10 times and logs the information below.
09:36:25 the jukebox (autoloader) is disabled.
09:43:42 I manually enable the jukebox from NMC.
09:45:00 a schedule job is started.
daemon.log (stripped):
04/12/07 09:36:22 nsrlcpd #1: Creating Jukebox `SSL2020'
04/12/07 09:36:24 nsrlcpd #1: Configuring Jukebox `SSL2020'
04/12/07 09:36:24 nsrmmgd: lcpd 1 at host valas02.rottneros.com reported error 'Jukebox `SSL2020', command operation `CONFIG', command ID 2 is not connected.
' for the command `3'.
04/12/07 09:36:24 nsrmmgd: Configuration of jukebox `SSL2020' failed with the following error: 'Jukebox `SSL2020', command operation `CONFIG', command ID 2 is not connected.
'.
04/12/07 09:36:24 nsrmmgd: Jukebox 'SSL2020' is unavailable. The config command failed on nsrlcpd 1 for host `valas02'. Retrying..
04/12/07 09:36:24 nsrmmgd: mgd_process_admin_response: failed to process response for command 3 from lcpd 1 at host valas02.rottneros.com with error 'Jukebox `SSL2020', command operation `CONFIG', command ID 2 is not connected.
'.
04/12/07 09:36:24 nsrmmgd: nsrlcpd response processing failed with error 'Jukebox `SSL2020', command operation `CONFIG', command ID 2 is not connected.
'.
04/12/07 09:36:25 nsrmmgd: Jukebox 'SSL2020' is being disabled because configuration of nsrlcpd 1 at host `valas02' failed more than 10 times.
04/12/07 09:36:25 nsrmmgd: Disabling jukebox `SSL2020' due to a configuration problem. Correct the problem and re-enable the jukebox resource.
04/12/07 09:36:25 nsrd: Jukebox SSL2020 is now disabled.
04/12/07 09:36:25 nsrlcpd #1: Jukebox `SSL2020' is exiting. The jukebox is no longer managed by nsrlcpd.
04/12/07 09:43:42 nsrd: Jukebox SSL2020 is now enabled.
04/12/07 09:43:44 nsrmmgd: Started nsrlcpd on host `valas02' with command line options '-s valas02.rottneros.com -N 1 -n 1 '.
04/12/07 09:43:44 nsrlcpd #1: Starting program `nsrlcpd #1', with process ID 2768, on HOST valas02.rottneros.com.
04/12/07 09:43:48 nsrlcpd #1: Creating Jukebox `SSL2020'
04/12/07 09:43:50 nsrlcpd #1: Configuring Jukebox `SSL2020'
04/12/07 09:43:53 nsrd: [Jukebox `SSL2020', operation 1]. Initiated operation `operation: "Load"; operation device: "\\.\Tape0"; operation slots: "10"; write enabled: "Yes"; '.
04/12/07 09:43:53 nsrmmgd: Jukebox `SSL2020' is unavailable. Operation `initialize elements status' is in progress.
04/12/07 09:43:53 nsrd: [Jukebox `SSL2020', operation # 1]. Jukebox `SSL2020' is unavailable. Operation `initialize elements status' is in progress.
04/12/07 09:43:53 nsrd: [Jukebox `SSL2020', operation # 1]. Finished with status: retryable
04/12/07 09:43:53 nsrmmgd: Jukebox 'SSL2020' is not in a valid state for nsrlcpd update.
04/12/07 09:45:00 nsrd: savegroup info: starting valas06 (with 1 client(s))
04/12/07 09:45:06 nsrd: Operation 2 started : Load volume `BQQ848'.
.
04/12/07 09:45:06 nsrd: media waiting event: Waiting for 1 writable volumes to backup pool 'Vallvik' tape(s) on valas02.rottneros.com
04/12/07 09:45:08 nsrmmd #2: Start nsrmmd #2, with PID 3120, at HOST valas02.rottneros.com
04/12/07 09:45:25 nsrd: \\.\Tape0 Verify label operation in progress
04/12/07 09:45:43 nsrd: \\.\Tape0 Mount operation in progress
04/12/07 09:45:52 nsrd: media info: Jukebox `SSL2020' Hardware status of jukebox 'SSL2020' is 'ready'
04/12/07 09:46:41 nsrd: media event cleared: Waiting for 1 writable volumes to backup pool 'Vallvik' tape(s) on valas02.rottneros.com
04/12/07 09:46:41 nsrd: valas06:D:\MSSQL7\BACKUP saving to pool 'Vallvik' (BQQ848)
04/12/07 09:46:45 nsrd: [Jukebox `SSL2020', operation # 2]. Finished with status: succeeded
pools supported: Vallvik;
04/12/07 09:46:49 nsrd: valas06:D:\MSSQL7\BACKUP done saving to pool 'Vallvik' (BQQ848) 523 KB
04/12/07 09:46:50 nsrd: valas02.rottneros.com:index:valas06 saving to pool 'Vallvik' (BQQ848)
04/12/07 09:46:52 nsrd: valas02.rottneros.com:index:valas06 done saving to pool 'Vallvik' (BQQ848) 468 KB
04/12/07 09:46:52 nsrd: savegroup notice: valas06 completed, Total 1 client(s), 1 Succeeded. Please see group completion details for more information.
0 events found
No Events found!
ble1
4 Operator
•
14.4K Posts
0
April 17th, 2007 03:00
b) disable auto-configuration if enabled
c) delete jukebox
d) delete devices
e) stop nw
f) delete everything inside C:\Pro...\nsr\tmp
g) start nw
h) make sure RSM is disabled
i) run jbconfig
j) run nsrjb -IIv for your library
If it does not work you should contact your support.
ble1
4 Operator
•
14.4K Posts
0
April 18th, 2007 05:00
vallviksbrukab
1 Rookie
•
21 Posts
0
April 18th, 2007 05:00
I did as you wrote, no change. I have contacted support, no solution so far.
Tested further. If nsrd service is set to manually so it won't autostart at startup, and then manually start the service after logon no error messages appear. I feel like it's a service or device that doesn't finishes starting before nsrd starts, when the nsrd service is set to autostart.
Any ideas are appreciated.