Networker

[Networker] probe unexpectedly exited

2005-01-26 16:35:55
Subject: [Networker] probe unexpectedly exited
From: Jim Thorsley <thor AT CSD.UWO DOT CA>
To: NETWORKER AT LISTSERV.TEMPLE DOT EDU
Date: Wed, 26 Jan 2005 16:30:25 -0500
Hi. I have a client which fails on backup. The client is a Windows XP machine
without SP2 installed. The server is a Solaris 8 machine running Networker
7.0. When backup runs it returns an error "probe unexpectedly exited" and
then "nsrexec: authtype nsrexec". I've done some tests based on what I've read
in this news group and a tech note at Legato's site. Here's what I found:

1) The client can ping the server and the server can ping the client using
   both short and fully qualified names.

2) rpcinfo reports no errors on either system.

3) there are 4 other clients in the group with similar configurations
   (i.e. XP with no SP2) which work fine. In fact there is another machine
   with XP and SP2 installed which works fine as well (after allowing
   nsrexecd through the firewall).

4) The log files says:

===============start========================================================
giles# grep mirror daemon.log
  ...earlier stuff deleted...
01/25/05 02:00:35 savegrp: mirror.admin.csd.uwo.ca:probe unexpectedly exited.
* mirror.admin.csd.uwo.ca:L:\ nsrexec: authtype nsrexec
01/25/05 02:00:35 savegrp: mirror.admin.csd.uwo.ca:probe will retry 1 more
time(s)
01/25/05 03:48:16 savegrp: mirror.admin.csd.uwo.ca:probe unexpectedly exited.
* mirror.admin.csd.uwo.ca:L:\ 1 retry attempted
* mirror.admin.csd.uwo.ca:L:\ nsrexec: authtype nsrexec
01/25/05 03:48:16 savegrp: mirror.admin.csd.uwo.ca:probe will retry 0 more
time(s)
01/25/05 03:55:16 nsrd: giles.csd.uwo.ca:index:mirror.admin.csd.uwo.ca
saving to pool 'Default' (CJX962)
01/25/05 03:55:16 nsrd: giles.csd.uwo.ca:index:mirror.admin.csd.uwo.ca done
saving to pool 'Default' (CJX962)
01/25/05 04:10:58 nsrd: savegroup alert: Admin completed, total 14 client(s), 0
Hostname(s) Unresolved, 3 Failed, 11 Succeeded. (light.admin.csd.uwo.ca,
mirror.admin.csd.uwo.ca, quartz.admin.csd.uwo.ca Failed)
01/26/05 02:00:28 savegrp: mirror.admin.csd.uwo.ca:probe unexpectedly exited.
* mirror.admin.csd.uwo.ca:L:\ nsrexec: authtype nsrexec
01/26/05 02:00:28 savegrp: mirror.admin.csd.uwo.ca:probe will retry 1 more
time(s)
01/26/05 03:25:24 savegrp: mirror.admin.csd.uwo.ca:probe unexpectedly exited.
* mirror.admin.csd.uwo.ca:L:\ 1 retry attempted
* mirror.admin.csd.uwo.ca:L:\ nsrexec: authtype nsrexec
01/26/05 03:25:24 savegrp: mirror.admin.csd.uwo.ca:probe will retry 0 more
time(s)
01/26/05 03:26:49 nsrd: giles.csd.uwo.ca:index:mirror.admin.csd.uwo.ca
saving to pool 'Default' (CJX836)
01/26/05 03:26:49 nsrd: giles.csd.uwo.ca:index:mirror.admin.csd.uwo.ca done
saving to pool 'Default' (CJX836)
01/26/05 03:48:00 nsrd: savegroup alert: Admin completed, total 14 client(s), 0
Hostname(s) Unresolved, 2 Failed, 12 Succeeded. (mirror.admin.csd.uwo.ca,
quartz.admin.csd.uwo.ca Failed)
===============end==========================================================

5) Someone suggested typing "savegrp -pv -c client group" on the server so
   I tried that. The results where:

===============start========================================================
giles# savegrp -pv -c mirror.admin.csd.uwo.ca Admin
mirror.admin.csd.uwo.ca:L:\               level=incr
01/26/05 11:42:54 savegrp: Run up to 4 clients in parallel
01/26/05 11:42:54 savegrp: mirror.admin.csd.uwo.ca:probe
startedsavefs -s giles.csd.uwo.ca -c mirror.admin.csd.uwo.ca -g Admin -p -l
full -R -v
-F L:\
01/26/05 11:43:06 savegrp: mirror.admin.csd.uwo.ca:probe unexpectedly exited.
* mirror.admin.csd.uwo.ca:L:\ rcmd mirror.admin.csd.uwo.ca, user root:
`savefs -s giles.csd.uwo.ca -c mirror.admin.csd.uwo.ca -g Admin -p -l full
-R -v -F "L:\\"'
* mirror.admin.csd.uwo.ca:L:\ nsrexec: authtype nsrexec
--- Probe Summary ---

mirror.admin.csd.uwo.ca:L:\        level=full, dn=-1, mx=0, vers=unknown, p=1
mirror.admin.csd.uwo.ca:L:\ level=full, pool=Admin, save as of Wed Jan 26
11:43:06 GMT-0500 AM 2
mirror.admin.csd.uwo.ca:index      level=9, dn=-1, mx=0, vers=unknown, p=1
mirror.admin.csd.uwo.ca:index level=9, pool=Admin incr, save as of Tue Jan
4 08:08:31 GMT-0500 AM 2

giles# ping mirror.admin.csd.uwo.ca
mirror.admin.csd.uwo.ca is alive
giles# host mirror.admin.csd.uwo.ca
mirror.admin.csd.uwo.ca has address 129.100.23.47
===============end==========================================================

6) I also tried "savegrp -D9 -pv -c client group" and got:
===============start========================================================
Script started on Wed 26 Jan 2005 11:56:23 AM EST
giles# savegrp -D9 -pv -c mirror.admin.csd.uwo.ca Admin
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp:
 Calling clnttcp_create function
01/26/05 11:56:56 savegrp:
 Calling clnttcp_create function
01/26/05 11:56:56 savegrp: socket binded to port 18045
01/26/05 11:56:56 savegrp: socket binded to port 20906
01/26/05 11:56:56 savegrp: lgto_auth for `nsrd' succeeded
01/26/05 11:56:56 savegrp: RPC Authentication: Client successfully
authenticated using AUTH_LGTO
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp:
 Calling clnttcp_create function
01/26/05 11:56:56 savegrp:
 Calling clnttcp_create function
01/26/05 11:56:56 savegrp: socket binded to port 20741
01/26/05 11:56:56 savegrp: socket binded to port 20611
01/26/05 11:56:56 savegrp: lgto_auth for `nsrd' succeeded
01/26/05 11:56:56 savegrp: RPC Authentication: Client successfully
authenticated using AUTH_LGTO
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:56:56 savegrp: lg_fstat(): Calling fstat64().
mirror.admin.csd.uwo.ca:L:\               level=incr
01/26/05 11:56:56 savegrp: Run up to 4 clients in parallel
01/26/05 11:56:56 savegrp: mirror.admin.csd.uwo.ca:probe
started01/26/05 11:56:56 savegrp: current token = savefs
savefs -s giles.csd.uwo.ca -c mirror.admin.csd.uwo.ca -g Admin -p -l full -R
-v -F L:\
01/26/05 11:56:56 savegrp: lg_open(): Calling open64().
01/26/05 11:56:56 savegrp: nsrexec started, pid 16587
01/26/05 11:57:03 savegrp: waitchild reaped, pid 16587
01/26/05 11:57:03 savegrp: mirror.admin.csd.uwo.ca:probe unexpectedly exited.
* mirror.admin.csd.uwo.ca:L:\ rcmd mirror.admin.csd.uwo.ca, user root:
`savefs -s giles.csd.uwo.ca -c mirror.admin.csd.uwo.ca -g Admin -p -l full
-R -v -F "L:\\"'
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 savegrp: using
`/usr/sbin/nsr/nsrexec' as execpath for `nsrexec'
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: current token = savefs
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: current token = -s
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: current token =
giles.csd.uwo.ca
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: current token = -c
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: current token =
mirror.admin.csd.uwo.ca
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: current token = -g
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: current token = Admin
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: current token = -p
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: current token = -l
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: current token = full
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: current token = -R
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: current token = -v
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: current token = -F
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: current token = L:\
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: lg_fstat(): Calling
fstat64().
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: lg_fstat(): Calling
fstat64().
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: lg_fstat(): Calling
fstat64().
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: lg_fstat(): Calling
fstat64().
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec:
* mirror.admin.csd.uwo.ca:L:\ clu_init_lc(): ENTRY...
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec:
* mirror.admin.csd.uwo.ca:L:\ get_lc_fspath_vhost_map(): ENTRY ...
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: No access to file:
/usr/sbin/lcmap ...
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec:
* mirror.admin.csd.uwo.ca:L:\ clu_init_lc(): Can't build fspath_vhost_map...
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec:
* mirror.admin.csd.uwo.ca:L:\ dump_map_lc(): ENTRY ...
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec:
Lc_use_local_vhost_list = FALSE
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec:
* mirror.admin.csd.uwo.ca:L:\ dump_map_lc(): EXIT ...
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec:
* mirror.admin.csd.uwo.ca:L:\ clu_is_cluster_host_lc(): ENTRY ...
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: Cannot find the Sun
Cluster haget utility.01/26/05 11:56:56 nsrexec: Use $HA_UTIL to point to
the location of the High Availability utilities.
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec:
* mirror.admin.csd.uwo.ca:L:\  Calling clnttcp_create function
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec:
* mirror.admin.csd.uwo.ca:L:\  Calling clnttcp_create function
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: socket binded to
port 19513
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: socket binded to
port 27871
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: RPC Authentication:
Client successfully authenticated using AUTH_UNIX
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec:
* mirror.admin.csd.uwo.ca:L:\  Calling clnttcp_create function
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: socket binded to
port 28419
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: socket binded to
port 24701
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: RPC Authentication:
Clent successfully authenticated using AUTH_UNIX
* mirror.admin.csd.uwo.ca:L:\ 01/26/05 11:56:56 nsrexec: socket binded to
port 9846
* mirror.admin.csd.uwo.ca:L:\ nsrexec: authtype nsrexec
--- Probe Summary ---

01/26/05 11:57:03 savegrp:
 Calling clnttcp_create function
01/26/05 11:57:03 savegrp:
 Calling clnttcp_create function
01/26/05 11:57:03 savegrp: socket binded to port 11465
01/26/05 11:57:03 savegrp: socket binded to port 28521
01/26/05 11:57:03 savegrp: lgto_auth: redirected to giles.csd.uwo.ca prog
390103 vers 2
01/26/05 11:57:03 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:57:03 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:57:03 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:57:03 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:57:03 savegrp:
 Calling clnttcp_create function
01/26/05 11:57:03 savegrp:
 Calling clnttcp_create function
01/26/05 11:57:03 savegrp: socket binded to port 29811
01/26/05 11:57:03 savegrp: socket binded to port 15093
01/26/05 11:57:03 savegrp: lgto_auth for `nsrmmdbd' succeeded
01/26/05 11:57:03 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:57:03 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:57:03 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:57:03 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:57:03 savegrp:
 Calling clnttcp_create function
01/26/05 11:57:03 savegrp:
 Calling clnttcp_create function
01/26/05 11:57:03 savegrp: socket binded to port 12548
01/26/05 11:57:03 savegrp: socket binded to port 16947
01/26/05 11:57:03 savegrp: RPC Authentication: Client successfully
authenticated using AUTH_LGTO
mirror.admin.csd.uwo.ca:L:\        level=full, dn=-1, mx=0, vers=unknown, p=1
01/26/05 11:57:03 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:57:03 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:57:03 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:57:03 savegrp: lg_fstat(): Calling fstat64().
01/26/05 11:57:03 savegrp:
 Calling clnttcp_create function
01/26/05 11:57:03 savegrp:
 Calling clnttcp_create function
01/26/05 11:57:03 savegrp: socket binded to port 21542
01/26/05 11:57:03 savegrp: socket binded to port 16882
01/26/05 11:57:03 savegrp: lgto_auth for `nsrd' succeeded
01/26/05 11:57:03 savegrp: RPC Authentication: Client successfully
authenticated using AUTH_LGTO
mirror.admin.csd.uwo.ca:L:\ level=full, pool=Admin, save as of Wed Jan 26
11:57:03 GMT-0500 AM 2
mirror.admin.csd.uwo.ca:index      level=9, dn=-1, mx=0, vers=unknown, p=1
mirror.admin.csd.uwo.ca:index level=9, pool=Admin incr, save as of Tue Jan
4 08:08:31 GMT-0500 AM 2

01/26/05 11:57:03 savegrp: lg_open(): Calling open64().
01/26/05 11:57:03 savegrp: lg_stat(): Calling stat64().
giles#
===============end==========================================================

I guess my question is: what does this error message mean?
and how can I fix it? and is there any other way to debug this thing?

-Jim

--
Note: To sign off this list, send a "signoff networker" command via email
to listserv AT listserv.temple DOT edu or visit the list's Web site at
http://listserv.temple.edu/archives/networker.html where you can
also view and post messages to the list. Questions regarding this list
should be sent to stan AT temple DOT edu
=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=*=

<Prev in Thread] Current Thread [Next in Thread>