From: leon_ecc (leonz@echinacash.com)
Date: Sun Jan 15 2006 - 02:37:28 EST
Hi friends,
My hardware configuration including:
- SunFire V490
- 3310
- Sol_9 )
- VxVM 4 + Full CVM feature licensed
- SC3.1 + Agent CD
I have some problems with log follow, I think maybe having something
wrong in VxVM configuration.
Pls help me how?
---------------------------------------------------------------------------------------
Rebooting with command: boot
Boot device: disk:a File and args:
SunOS Release 5.9 Version Generic_117171-02 64-bit
Copyright 1983-2003 Sun Microsystems, Inc. All rights reserved.
Use is subject to license terms.
Hardware watchdog enabled
VxVM sysboot INFO V-5-2-3244 Starting restore daemon...
VxVM sysboot INFO V-5-2-3445 starting in boot mode...
NOTICE: VxVM vxdmp V-5-0-34 added disk array OTHER_DISKS, datype =
OTHER_DISKS
configuring IPv4 interfaces: bge0.
Hostname: mt-sun150
Booting as part of a cluster
NOTICE: CMM: Node mt-sun150 (nodeid = 1) with votecount = 1 added.
NOTICE: CMM: Node mt-sun151 (nodeid = 2) with votecount = 1 added.
NOTICE: CMM: Quorum device 1 (/dev/did/rdsk/d2s2) added; votecount = 1,
bitmask of nodes with configured paths = 0x3.
NOTICE: clcomm: Adapter bge3 constructed
NOTICE: clcomm: Path mt-sun150:bge3 - mt-sun151:bge3 being constructed
NOTICE: clcomm: Adapter bge2 constructed
NOTICE: clcomm: Path mt-sun150:bge2 - mt-sun151:bge2 being constructed
NOTICE: CMM: Node mt-sun150: attempting to join cluster.
NOTICE: clcomm: Path mt-sun150:bge3 - mt-sun151:bge3 being initiated
NOTICE: CMM: Node mt-sun151 (nodeid: 2, incarnation #: 1095573589) has
become reachable.
NOTICE: clcomm: Path mt-sun150:bge3 - mt-sun151:bge3 online
NOTICE: CMM: Quorum device /dev/did/rdsk/d2s2: owner set to node 1.
NOTICE: CMM: Cluster has reached quorum.
NOTICE: CMM: Node mt-sun150 (nodeid = 1) is up; new incarnation number =
1095573586.
NOTICE: CMM: Node mt-sun151 (nodeid = 2) is up; new incarnation number =
1095573589.
NOTICE: CMM: Cluster members: mt-sun150 mt-sun151.
NOTICE: CMM: node reconfiguration #1 completed.
NOTICE: CMM: Node mt-sun150: joined cluster.
NOTICE: clcomm: Path mt-sun150:bge2 - mt-sun151:bge2 being initiated
NOTICE: clcomm: Path mt-sun150:bge2 - mt-sun151:bge2 online
ip: joining multicasts failed (18) on clprivnet0 - will use link layer
broadcasts for multicast
VxVM vxvm-startup2 INFO V-5-2-503 VxVM general startup...
The system is coming up. Please wait.
checking ufs filesystems
/dev/rdsk/c3t0d0s6: is clean.
starting rpc services: rpcbind done.
Setting netmask of bge0 to 255.255.0.0
Setting netmask of bge3 to 255.255.255.128
Setting netmask of bge2 to 255.255.255.128
Setting netmask of clprivnet0 to 255.255.255.0
Setting default IPv4 interface for multicast: add net 224.0/4: gateway
mt-sun150
syslog service starting.
obtaining access to all attached disks
Sep 19 13:00:05 Cluster.Framework: stdout: releasing reservations for
scsi-2 disks shared with mt-sun151
Sep 19 13:00:08 mt-sun150 xntpd[463]: couldn't resolve
`clusternode3-priv', giving up on it
Sep 19 13:00:08 mt-sun150 xntpd[463]: couldn't resolve
`clusternode4-priv', giving up on it
Sep 19 13:00:08 mt-sun150 xntpd[463]: couldn't resolve
`clusternode5-priv', giving up on it
Sep 19 13:00:08 mt-sun150 xntpd[463]: couldn't resolve
`clusternode6-priv', giving up on it
Sep 19 13:00:08 mt-sun150 xntpd[463]: couldn't resolve
`clusternode7-priv', giving up on it
Sep 19 13:00:08 mt-sun150 xntpd[463]: couldn't resolve
`clusternode8-priv', giving up on it
Sep 19 13:00:08 mt-sun150 xntpd[463]: couldn't resolve
`clusternode9-priv', giving up on it
Sep 19 13:00:08 mt-sun150 xntpd[463]: couldn't resolve
`clusternode10-priv', giving up on it
Sep 19 13:00:08 mt-sun150 xntpd[463]: couldn't resolve
`clusternode11-priv', giving up on it
Sep 19 13:00:08 mt-sun150 xntpd[463]: couldn't resolve
`clusternode12-priv', giving up on it
Sep 19 13:00:08 mt-sun150 xntpd[463]: couldn't resolve
`clusternode13-priv', giving up on it
Sep 19 13:00:08 mt-sun150 xntpd[463]: couldn't resolve
`clusternode14-priv', giving up on it
Sep 19 13:00:08 mt-sun150 xntpd[463]: couldn't resolve
`clusternode15-priv', giving up on it
Sep 19 13:00:08 mt-sun150 xntpd[463]: couldn't resolve
`clusternode16-priv', giving up on it
Sep 19 13:00:55 mt-sun150 ID[vxclust]: starting start time: 09/19
13:00:55.658: seq # 0
Sep 19 13:00:55 mt-sun150 ID[vxclust]: max nodes defined in the cluster=2
Sep 19 13:00:55 mt-sun150 ID[vxclust]: Sending VOLCVM_INIT_CONFIG
Sep 19 13:00:55 mt-sun150 ID[vxclust]: ending step start time: 09/19
13:00:55.944:
Sep 19 13:00:57 mt-sun150 ID[vxclust]: starting step1 time: 09/19
13:00:57.334: seq # 1
Sep 19 13:00:57 mt-sun150 ID[vxclust:clust_node_update]: Reconf Seqno:1
Sep 19 13:00:57 mt-sun150 ID[vxclust]: members 3 joiners 3 leavers 0
Sep 19 13:00:57 mt-sun150 ID[vxclust]: ending step step1 time: 09/19
13:00:57.336:
Sep 19 13:00:57 mt-sun150 ID[SUNWudlm.udlm]: Unix DLM version (2) and
SUN Unix DLM library version (1): compatible.
volume management starting.
The system is ready.
mt-sun150 console login: Sep 19 13:01:01 mt-sun150 ID[vxclust]: starting
step2 time: 09/19 13:01:01.401: seq # 1
Sep 19 13:01:01 mt-sun150 ID[vxclust:clust_node_update]: Reconf Seqno:1
Sep 19 13:01:01 mt-sun150 ID[vxclust]: members 3 joiners 3 leavers 0
Sep 19 13:01:01 mt-sun150 ID[vxclust]: CVM protocol version:50
Sep 19 13:01:01 mt-sun150 ID[vxclust]: members 3 joiners 3 leavers 0
Sep 19 13:01:01 mt-sun150 ID[vxclust:step2]: Reconf Seqno:1
Sep 19 13:01:01 mt-sun150 ID[vxclust]: Member 0 : mt-sun150
Sep 19 13:01:01 mt-sun150 ID[vxclust]: Member 1 : mt-sun151
Sep 19 13:01:01 mt-sun150 ID[vxclust]: vol_set_membership:Reconf Seqno:1
Sep 19 13:01:01 mt-sun150 ID[vxclust]: 0 mt-sun150
Sep 19 13:01:01 mt-sun150 ID[vxclust]: 1 mt-sun151
Sep 19 13:01:01 mt-sun150 ID[vxclust]: Local Node is a joiner
Sep 19 13:01:01 mt-sun150 ID[vxclust]: Finding coordinator
Sep 19 13:01:01 mt-sun150 ID[vxclust]: coord changed to 172.16.193.1
Sep 19 13:01:01 mt-sun150 ID[vxclust]: coord changed to 172.16.193.2
Sep 19 13:01:01 mt-sun150 ID[vxclust]: vol_set_member:mapno:1
Sep 19 13:01:01 mt-sun150 ID[vxclust]: Coordinator chosen : mt-sun151
Sep 19 13:01:01 mt-sun150 ID[vxclust]: set_nodeid_protocol ....
Sep 19 13:01:01 mt-sun150 ID[vxclust]: set_nodeid_protocol:mapno:1
Sep 19 13:01:01 mt-sun150 ID[vxclust]: set_nodeid_protocol:
name:mt-sun150 id:-1
Sep 19 13:01:01 mt-sun150 ID[vxclust]: Connection established ...
Sep 19 13:01:01 mt-sun150 ID[vxclust]: send_map:mesg size:72
Sep 19 13:01:01 mt-sun150 ID[vxclust]: send_map:master vote :-1
Sep 19 13:01:01 mt-sun150 ID[vxclust]: selected_master:1
Sep 19 13:01:01 mt-sun150 ID[vxclust]: New assignment :0 mt-sun150
Sep 19 13:01:01 mt-sun150 ID[vxclust]: New assignment :1 mt-sun151
Sep 19 13:01:01 mt-sun150 ID[vxclust]: CVM specific ID for local node 0
Sep 19 13:01:01 mt-sun150 ID[vxclust]: Generating bit map for current
membership ......
Sep 19 13:01:01 mt-sun150 ID[vxclust]: CM Node ID : 0
Sep 19 13:01:01 mt-sun150 ID[vxclust]: CM Node ID : 1
Sep 19 13:01:01 mt-sun150 ID[vxclust]: CM Node ID : -1
Sep 19 13:01:01 mt-sun150 ID[vxclust]: vol_set_membership:Reconf Seqno:1
Sep 19 13:01:01 mt-sun150 ID[vxclust]: Joiner 0
Sep 19 13:01:01 mt-sun150 ID[vxclust]: Joiner 1
Sep 19 13:01:01 mt-sun150 ID[vxclust]: vol_set_membership:Reconf Seqno:1
Sep 19 13:01:01 mt-sun150 ID[vxclust]: VOLCVM_CONFIG Successful
Sep 19 13:01:01 mt-sun150 ID[vxclust]: New Master:1
Sep 19 13:01:07 mt-sun150 ID[vxclust]: ending step step2 time: 09/19
13:01:07.460:
Sep 19 13:01:09 mt-sun150 ID[vxclust]: starting step3 time: 09/19
13:01:09.254: seq # 1
mt-sun150 console login: VxVM vxnotifyVxVM ERROR V-5-1-915 Reconnection
to vxconfigdvxnotify ERROR failed: V-5-Configuration daemon is not
accessible1-915
Reconnection to vxconfigd failed: Configuration daemon is not accessible
mt-sun150 console login: Sep 19 13:05:06 mt-sun150 ID[vxclust]: timeout
time: 09/19 13:05:06.249: step step3
Sep 19 13:05:06 mt-sun150 SUNWscucm.ucmm_reconf: cvm requests
reconfiguration in step cmmstep8
Sep 19 13:05:06 mt-sun150 Cluster.OPS.UCMMD: prog <ucmm_reconf> failed
on step <cmmstep8> retcode <205>
Sep 19 13:05:06 mt-sun150 ID[vxclust]: vxconfigd not running err=2
Sep 19 13:05:06 mt-sun150 ID[vxclust]: starting return time: 09/19
13:05:06.624: seq # 1
Sep 19 13:05:06 mt-sun150 ID[vxclust]: failure in caching vxconfigd records
Sep 19 13:05:06 mt-sun150 ID[vxclust]: ending step return time: 09/19
13:05:06.625:
Sep 19 13:05:07 mt-sun150 ID[vxclust]: vxconfigd not running err=2
Sep 19 13:05:07 mt-sun150 ID[vxclust]: starting step1 time: 09/19
13:05:07.340: seq # 2
Sep 19 13:05:07 mt-sun150 ID[vxclust:clust_node_update]: Reconf Seqno:2
Sep 19 13:05:07 mt-sun150 ID[vxclust]: members 3 joiners 3 leavers 0
Sep 19 13:05:13 mt-sun150 ID[vxclust]: vxconfigd not running err=2
Sep 19 13:05:13 mt-sun150 ID[vxclust]: starting step2 time: 09/19
13:05:13.085: seq # 2
Sep 19 13:05:13 mt-sun150 ID[vxclust:clust_node_update]: Reconf Seqno:2
Sep 19 13:05:13 mt-sun150 ID[vxclust]: members 3 joiners 3 leavers 0
Sep 19 13:05:13 mt-sun150 ID[vxclust]: restarting vxconfigd
Sep 19 13:05:13 mt-sun150 ID[vxclust]: priocntl -e -c TS
/usr/sbin/vxconfigd -x 1 -x
mstimestamp -x logfile=/var/tmp/v.out
2>/dev/null&
Sep 19 13:05:13 mt-sun150 ID[vxclust]: CVM protocol version:50
Sep 19 13:05:13 mt-sun150 ID[vxclust]: members 3 joiners 3 leavers 0
and my /var/tmp/v.out content:
-------------------------------------------------------------------------------------------------------------------------------------------------------------
09/19 09:59:22.388: VxVM vxconfigd DEBUG V-5-1-5204
VOLD STARTUP pid=1761
09/19 09:59:22.395: VxVM vxconfigd DEBUG V-5-1-7188 Kernel version 4.0
09/19 09:59:22.395: VxVM vxconfigd DEBUG V-5-1-681 IOCTL KTRANS_ABORT:
failed: errno=22 (Invalid argument)
09/19 09:59:22.395: VxVM vxconfigd DEBUG V-5-1-5737 No command received
09/19 09:59:22.449: VxVM vxconfigd DEBUG V-5-1-5657 mode_set:
oldmode=none newmode=enabled
09/19 09:59:29.054: VxVM vxconfigd DEBUG V-5-1-0
ddl_clean_property_list: Failed to get array_names
09/19 09:59:29.063: VxVM vxconfigd DEBUG V-5-1-681 IOCTL SET_KLICENSE
licenses: basic concat span mirror stripe raid5 ormclustering-full:
return 0(0x0)
09/19 09:59:29.084: VxVM vxconfigd DEBUG V-5-1-5645 MODE: DISABLED
09/19 09:59:29.703: VxVM vxconfigd DEBUG V-5-1-5813 RECOVER: Reonline
all disks
09/19 09:59:30.041: VxVM vxconfigd DEBUG V-5-1-5428 Didnot find a disk
in ddi_fdisk_list with either the same device number or same tag. tag =
c2t9d0s2
09/19 09:59:30.050: VxVM vxconfigd DEBUG V-5-1-5428 Didnot find a disk
in ddi_fdisk_list with either the same device number or same tag. tag =
c2t8d0s2
09/19 09:59:30.062: VxVM vxconfigd DEBUG V-5-1-5428 Didnot find a disk
in ddi_fdisk_list with either the same device number or same tag. tag =
c2t1d0s2
09/19 09:59:30.074: VxVM vxconfigd DEBUG V-5-1-5428 Didnot find a disk
in ddi_fdisk_list with either the same device number or same tag. tag =
c2t0d0s2
09/19 09:59:30.086: VxVM vxconfigd DEBUG V-5-1-5428 Didnot find a disk
in ddi_fdisk_list with either the same device number or same tag. tag =
c1t9d0s2
09/19 09:59:30.098: VxVM vxconfigd DEBUG V-5-1-5428 Didnot find a disk
in ddi_fdisk_list with either the same device number or same tag. tag =
c1t8d0s2
09/19 09:59:30.110: VxVM vxconfigd DEBUG V-5-1-5428 Didnot find a disk
in ddi_fdisk_list with either the same device number or same tag. tag =
c1t1d0s2
09/19 09:59:30.122: VxVM vxconfigd DEBUG V-5-1-5428 Didnot find a disk
in ddi_fdisk_list with either the same device number or same tag. tag =
c1t0d0s2
09/19 09:59:30.141: VxVM vxconfigd DEBUG V-5-1-5481 Found a disk in
ddi_disk_list with the same device number but different tag
09/19 09:59:30.152: VxVM vxconfigd DEBUG V-5-1-5481 Found a disk in
ddi_disk_list with the same device number but different tag
09/19 09:59:30.164: VxVM vxconfigd DEBUG V-5-1-5481 Found a disk in
ddi_disk_list with the same device number but different tag
09/19 09:59:30.176: VxVM vxconfigd DEBUG V-5-1-5481 Found a disk in
ddi_disk_list with the same device number but different tag
09/19 09:59:30.188: VxVM vxconfigd DEBUG V-5-1-5481 Found a disk in
ddi_disk_list with the same device number but different tag
09/19 09:59:30.200: VxVM vxconfigd DEBUG V-5-1-5481 Found a disk in
ddi_disk_list with the same device number but different tag
09/19 09:59:30.212: VxVM vxconfigd DEBUG V-5-1-5481 Found a disk in
ddi_disk_list with the same device number but different tag
09/19 09:59:30.224: VxVM vxconfigd DEBUG V-5-1-5481 Found a disk in
ddi_disk_list with the same device number but different tag
09/19 09:59:30.307: VxVM vxconfigd DEBUG V-5-1-5497 Have BASIC license
09/19 09:59:30.319: VxVM vxconfigd DEBUG V-5-1-5428 Didnot find a disk
in ddi_fdisk_list with either the same device number or same tag. tag =
c2t9d0s2
09/19 09:59:30.331: VxVM vxconfigd DEBUG V-5-1-5428 Didnot find a disk
in ddi_fdisk_list with either the same device number or same tag. tag =
c2t8d0s2
09/19 09:59:30.343: VxVM vxconfigd DEBUG V-5-1-5428 Didnot find a disk
in ddi_fdisk_list with either the same device number or same tag. tag =
c2t1d0s2
09/19 09:59:30.355: VxVM vxconfigd DEBUG V-5-1-5428 Didnot find a disk
in ddi_fdisk_list with either the same device number or same tag. tag =
c2t0d0s2
09/19 09:59:30.367: VxVM vxconfigd DEBUG V-5-1-5428 Didnot find a disk
in ddi_fdisk_list with either the same device number or same tag. tag =
c1t9d0s2
09/19 09:59:30.379: VxVM vxconfigd DEBUG V-5-1-5428 Didnot find a disk
in ddi_fdisk_list with either the same device number or same tag. tag =
c1t8d0s2
09/19 09:59:30.391: VxVM vxconfigd DEBUG V-5-1-5428 Didnot find a disk
in ddi_fdisk_list with either the same device number or same tag. tag =
c1t1d0s2
09/19 09:59:30.403: VxVM vxconfigd DEBUG V-5-1-5428 Didnot find a disk
in ddi_fdisk_list with either the same device number or same tag. tag =
c1t0d0s2
09/19 09:59:30.415: VxVM vxconfigd DEBUG V-5-1-5428 Didnot find a disk
in ddi_fdisk_list with either the same device number or same tag. tag =
c3t0d0s2
09/19 09:59:30.427: VxVM vxconfigd DEBUG V-5-1-5428 Didnot find a disk
in ddi_fdisk_list with either the same device number or same tag. tag =
c3t1d0s2
09/19 09:59:30.446: VxVM vxconfigd DEBUG V-5-1-5481 Found a disk in
ddi_disk_list with the same device number but different tag
09/19 09:59:30.457: VxVM vxconfigd DEBUG V-5-1-5481 Found a disk in
ddi_disk_list with the same device number but different tag
09/19 09:59:30.469: VxVM vxconfigd DEBUG V-5-1-5481 Found a disk in
ddi_disk_list with the same device number but different tag
09/19 09:59:30.481: VxVM vxconfigd DEBUG V-5-1-5481 Found a disk in
ddi_disk_list with the same device number but different tag
09/19 09:59:30.493: VxVM vxconfigd DEBUG V-5-1-5481 Found a disk in
ddi_disk_list with the same device number but different tag
09/19 09:59:30.505: VxVM vxconfigd DEBUG V-5-1-5481 Found a disk in
ddi_disk_list with the same device number but different tag
09/19 09:59:30.517: VxVM vxconfigd DEBUG V-5-1-5481 Found a disk in
ddi_disk_list with the same device number but different tag
09/19 09:59:30.529: VxVM vxconfigd DEBUG V-5-1-5481 Found a disk in
ddi_disk_list with the same device number but different tag
09/19 09:59:30.541: VxVM vxconfigd DEBUG V-5-1-5481 Found a disk in
ddi_disk_list with the same device number but different tag
09/19 09:59:30.553: VxVM vxconfigd DEBUG V-5-1-5481 Found a disk in
ddi_disk_list with the same device number but different tag
09/19 09:59:30.872: VxVM vxconfigd DEBUG V-5-1-5537 importing shared
disk groups
09/19 09:59:30.883: VxVM vxconfigd DEBUG V-5-1-5641 MODE-ENABLE: Setup
all virtual device node directories
09/19 09:59:30.895: VxVM vxconfigd DEBUG V-5-1-5646 MODE: ENABLED
09/19 09:59:30.907: VxVM vxconfigd DEBUG V-5-1-5737 No command received
09/19 09:59:30.926: VxVM vxconfigd DEBUG V-5-1-5704
ncopy_min_copies_per_dg: numdisks = 0
09/19 09:59:30.937: VxVM vxconfigd DEBUG V-5-1-5705
ncopy_min_copies_per_dg: returning = 4 copies.
09/19 09:59:30.949: VxVM vxconfigd DEBUG V-5-1-5704
ncopy_min_copies_per_dg: numdisks = 0
09/19 09:59:30.961: VxVM vxconfigd DEBUG V-5-1-5705
ncopy_min_copies_per_dg: returning = 4 copies.
09/19 09:59:30.973: VxVM vxconfigd DEBUG V-5-1-5371 dg_balance_copies:
config: dg active 0, dg policy 0
09/19 09:59:30.985: VxVM vxconfigd DEBUG V-5-1-5374 dg_balance_copies:
log: dg active 0, dg policy 0
09/19 09:59:31.000: VxVM vxconfigd DEBUG V-5-1-5288 CONNECTION:
client=9386 pid=1947 version=20
09/19 09:59:31.009: VxVM vxconfigd DEBUG V-5-1-900 REQUEST
CLUSTER_SET_ROLE: client 9386, pid 1947, portal DIAG, size 0
09/19 10:09:38.589: VxVM vxconfigd DEBUG V-5-1-5204
_______________________________________________
sunmanagers mailing list
sunmanagers@sunmanagers.org
http://www.sunmanagers.org/mailman/listinfo/sunmanagers
This archive was generated by hypermail 2.1.7 : Wed Apr 09 2008 - 23:38:27 EDT