[Gluster-devel] Volume start fails on recent git

Wed Sep 26 20:13:11 UTC 2012

Sent the patch http://review.gluster.org/4001

I am extremely sorry if someone has already raised a bug for this. I could not find the bug so raised the new bug
https://bugzilla.redhat.com/show_bug.cgi?id=860833

Pranith

----- Original Message -----
From: "Anand Avati" <anand.avati at gmail.com>
To: "Jan Engelhardt" <jengelh at inai.de>
Cc: gluster-devel at nongnu.org
Sent: Wednesday, September 26, 2012 9:23:57 PM
Subject: Re: [Gluster-devel] Volume start fails on recent git

I checked just now and the same test seems to work fine for me. Have you verified if both glusterds were on the same commit id? 

Avati 

On Wed, Sep 26, 2012 at 1:06 AM, Jan Engelhardt < jengelh at inai.de > wrote: 

With glusterfs v3.3.0qa39-457-g5ad96fb ("master" branch), starting a 
volume fails. The start command however works with v3.3.1qa3 
("release-3.3" branch). 

# gluster volume create d0 replica 2 transport tcp \ 
mozart:/sync/.gluster-store bach:/sync/.gluster-store 

09:35 mozart:/tmp/glu # gluster volume info 

Volume Name: d0 
Type: Replicate 
Volume ID: 09386acc-7149-4c9c-b8f2-e6ed4104435b 
Status: Created 
Number of Bricks: 1 x 2 = 2 
Transport-type: tcp 
Bricks: 
Brick1: mozart:/sync/.gluster-store 
Brick2: bach:/sync/.gluster-store 
09:35 mozart:/tmp/glu # gluster volume status 
Volume d0 is not started 

09:35 mozart:/tmp/glu # gluster volume start d0 
volume start: d0: failed 
09:35 mozart:/tmp/glu # tail /var/log/messages 
Sep 26 09:35:50 mozart GlusterFS[19401]: [2012-09-26 07:35:50.067710] C 
[glusterd-op-sm.c:1923:glusterd_op_build_payload] 0-management: volname 
is not present in operation ctx 

When glusterd runs with --debug, on `volume start`, it prints: 

[2012-09-26 07:49:41.234138] I [glusterd-volume-ops.c:261:glusterd_handle_cli_start_volume] 0-management: Received start vol req for volume d0 
[2012-09-26 07:49:41.234179] I [glusterd-utils.c:274:glusterd_lock] 0-glusterd: Cluster lock held by 93820285-fa3b-4f9e-8510-93da28df5bfd 
[2012-09-26 07:49:41.234191] I [glusterd-handler.c:440:glusterd_op_txn_begin] 0-management: Acquired local lock 
[2012-09-26 07:49:41.234203] D [glusterd-op-sm.c:4521:glusterd_op_sm_inject_event] 0-glusterd: Enqueue event: 'GD_OP_EVENT_START_LOCK' 
[2012-09-26 07:49:41.234214] D [glusterd-handler.c:458:glusterd_op_txn_begin] 0-management: Returning 0 
[2012-09-26 07:49:41.234224] D [glusterd-op-sm.c:4593:glusterd_op_sm] 0-: Dequeued event of type: 'GD_OP_EVENT_START_LOCK' 
[2012-09-26 07:49:41.234297] D [glusterd-rpc-ops.c:1629:glusterd_cluster_lock] 0-glusterd: Returning 0 
[2012-09-26 07:49:41.234311] D [glusterd-op-sm.c:1618:glusterd_op_ac_send_lock] 0-: Returning with 0 
[2012-09-26 07:49:41.234320] D [glusterd-utils.c:4765:glusterd_sm_tr_log_transition_add] 0-glusterd: Transitioning from 'Default' to 'Lock sent' due to event 'GD_OP_EVENT_START_LOCK' 
[2012-09-26 07:49:41.234332] D [glusterd-utils.c:4767:glusterd_sm_tr_log_transition_add] 0-: returning 0 
[2012-09-26 07:49:41.235284] I [glusterd-rpc-ops.c:537:glusterd_cluster_lock_cbk] 0-glusterd: Received ACC from uuid: 4ea16432-3d76-40e0-b1d2-a4676c60a2b4 
[2012-09-26 07:49:41.235310] D [glusterd-utils.c:4125:glusterd_friend_find_by_uuid] 0-glusterd: Friend found... state: Peer in Cluster 
[2012-09-26 07:49:41.235323] D [glusterd-op-sm.c:4521:glusterd_op_sm_inject_event] 0-glusterd: Enqueue event: 'GD_OP_EVENT_RCVD_ACC' 
[2012-09-26 07:49:41.235334] D [glusterd-op-sm.c:4593:glusterd_op_sm] 0-: Dequeued event of type: 'GD_OP_EVENT_RCVD_ACC' 
[2012-09-26 07:49:41.235343] D [glusterd-op-sm.c:4521:glusterd_op_sm_inject_event] 0-glusterd: Enqueue event: 'GD_OP_EVENT_ALL_ACC' 
[2012-09-26 07:49:41.235353] D [glusterd-op-sm.c:1767:glusterd_op_ac_rcvd_lock_acc] 0-: Returning 0 
[2012-09-26 07:49:41.235362] D [glusterd-utils.c:4765:glusterd_sm_tr_log_transition_add] 0-glusterd: Transitioning from 'Lock sent' to 'Lock sent' due to event 'GD_OP_EVENT_RCVD_ACC' 
[2012-09-26 07:49:41.235372] D [glusterd-utils.c:4767:glusterd_sm_tr_log_transition_add] 0-: returning 0 
[2012-09-26 07:49:41.235381] D [glusterd-op-sm.c:4593:glusterd_op_sm] 0-: Dequeued event of type: 'GD_OP_EVENT_ALL_ACC' 
[2012-09-26 07:49:41.235391] C [glusterd-op-sm.c:1923:glusterd_op_build_payload] 0-management: volname is not present in operation ctx 
[2012-09-26 07:49:41.235466] E [glusterd-op-sm.c:1968:glusterd_op_ac_send_stage_op] 0-management: Building payload failed 
[2012-09-26 07:49:41.235479] D [glusterd-op-sm.c:4521:glusterd_op_sm_inject_event] 0-glusterd: Enqueue event: 'GD_OP_EVENT_RCVD_RJT' 
[2012-09-26 07:49:41.235489] I [glusterd-op-sm.c:2016:glusterd_op_ac_send_stage_op] 0-glusterd: Sent op req to 0 peers 
[2012-09-26 07:49:41.235499] D [glusterd-op-sm.c:4521:glusterd_op_sm_inject_event] 0-glusterd: Enqueue event: 'GD_OP_EVENT_ALL_ACC' 
[2012-09-26 07:49:41.235508] D [glusterd-op-sm.c:134:glusterd_op_sm_inject_all_acc] 0-: Returning 0 
[2012-09-26 07:49:41.235516] D [glusterd-op-sm.c:2021:glusterd_op_ac_send_stage_op] 0-: Returning with 0 
[2012-09-26 07:49:41.235525] D [glusterd-utils.c:4765:glusterd_sm_tr_log_transition_add] 0-glusterd: Transitioning from 'Lock sent' to 'Stage op sent' due to event 'GD_OP_EVENT_ALL_ACC' 
[2012-09-26 07:49:41.235535] D [glusterd-utils.c:4767:glusterd_sm_tr_log_transition_add] 0-: returning 0 
[2012-09-26 07:49:41.235543] D [glusterd-op-sm.c:4593:glusterd_op_sm] 0-: Dequeued event of type: 'GD_OP_EVENT_RCVD_RJT' 
[2012-09-26 07:49:41.235552] D [glusterd-op-sm.c:4521:glusterd_op_sm_inject_event] 0-glusterd: Enqueue event: 'GD_OP_EVENT_ALL_ACK' 
[2012-09-26 07:49:41.235561] D [glusterd-op-sm.c:2425:glusterd_op_ac_stage_op_failed] 0-: Returning 0 
[2012-09-26 07:49:41.235570] D [glusterd-utils.c:4765:glusterd_sm_tr_log_transition_add] 0-glusterd: Transitioning from 'Stage op sent' to 'Stage op failed' due to event 'GD_OP_EVENT_RCVD_RJT' 
[2012-09-26 07:49:41.235579] D [glusterd-utils.c:4767:glusterd_sm_tr_log_transition_add] 0-: returning 0 
[2012-09-26 07:49:41.235588] D [glusterd-op-sm.c:4593:glusterd_op_sm] 0-: Dequeued event of type: 'GD_OP_EVENT_ALL_ACC' 
[2012-09-26 07:49:41.235601] D [glusterd-op-sm.c:1577:glusterd_op_ac_none] 0-: Returning with 0 
[2012-09-26 07:49:41.235605] D [glusterd-utils.c:4765:glusterd_sm_tr_log_transition_add] 0-glusterd: Transitioning from 'Stage op failed' to 'Stage op failed' due to event 'GD_OP_EVENT_ALL_ACC' 
[2012-09-26 07:49:41.235609] D [glusterd-utils.c:4767:glusterd_sm_tr_log_transition_add] 0-: returning 0 
[2012-09-26 07:49:41.235613] D [glusterd-op-sm.c:4593:glusterd_op_sm] 0-: Dequeued event of type: 'GD_OP_EVENT_ALL_ACK' 
[2012-09-26 07:49:41.235635] D [glusterd-rpc-ops.c:1663:glusterd_cluster_unlock] 0-glusterd: Returning 0 
[2012-09-26 07:49:41.235668] D [glusterd-op-sm.c:1665:glusterd_op_ac_send_unlock] 0-: Returning with 0 
[2012-09-26 07:49:41.235673] D [glusterd-utils.c:4765:glusterd_sm_tr_log_transition_add] 0-glusterd: Transitioning from 'Stage op failed' to 'Unlock sent' due to event 'GD_OP_EVENT_ALL_ACK' 
[2012-09-26 07:49:41.235685] D [glusterd-utils.c:4767:glusterd_sm_tr_log_transition_add] 0-: returning 0 
[2012-09-26 07:49:41.236502] I [glusterd-rpc-ops.c:596:glusterd_cluster_unlock_cbk] 0-glusterd: Received ACC from uuid: 4ea16432-3d76-40e0-b1d2-a4676c60a2b4 
[2012-09-26 07:49:41.236528] D [glusterd-utils.c:4125:glusterd_friend_find_by_uuid] 0-glusterd: Friend found... state: Peer in Cluster 
[2012-09-26 07:49:41.236540] D [glusterd-op-sm.c:4521:glusterd_op_sm_inject_event] 0-glusterd: Enqueue event: 'GD_OP_EVENT_RCVD_ACC' 
[2012-09-26 07:49:41.236551] D [glusterd-op-sm.c:4593:glusterd_op_sm] 0-: Dequeued event of type: 'GD_OP_EVENT_RCVD_ACC' 
[2012-09-26 07:49:41.236560] D [glusterd-op-sm.c:4521:glusterd_op_sm_inject_event] 0-glusterd: Enqueue event: 'GD_OP_EVENT_ALL_ACC' 
[2012-09-26 07:49:41.236569] D [glusterd-op-sm.c:2562:glusterd_op_ac_rcvd_unlock_acc] 0-: Returning 0 
[2012-09-26 07:49:41.236578] D [glusterd-utils.c:4765:glusterd_sm_tr_log_transition_add] 0-glusterd: Transitioning from 'Unlock sent' to 'Unlock sent' due to event 'GD_OP_EVENT_RCVD_ACC' 
[2012-09-26 07:49:41.236588] D [glusterd-utils.c:4767:glusterd_sm_tr_log_transition_add] 0-: returning 0 
[2012-09-26 07:49:41.236597] D [glusterd-op-sm.c:4593:glusterd_op_sm] 0-: Dequeued event of type: 'GD_OP_EVENT_ALL_ACC' 
[2012-09-26 07:49:41.236619] I [glusterd-op-sm.c:2630:glusterd_op_txn_complete] 0-glusterd: Cleared local lock 
[2012-09-26 07:49:41.236632] E [glusterd-utils.c:5732:glusterd_to_cli] 0-glusterd: Failed to get command string 
[2012-09-26 07:49:41.236685] D [glusterd-rpc-ops.c:180:glusterd_op_send_cli_response] 0-: Returning 0 
[2012-09-26 07:49:41.236698] D [glusterd-op-sm.c:2648:glusterd_op_txn_complete] 0-glusterd: Returning 0 
[2012-09-26 07:49:41.236709] D [glusterd-op-sm.c:2661:glusterd_op_ac_unlocked_all] 0-: Returning 0 
volume start: d0: failed 
[2012-09-26 07:49:41.236717] D [glusterd-utils.c:4765:glusterd_sm_tr_log_transition_add] 0-glusterd: Transitioning from 'Unlock sent' to 'Default' due to event 'GD_OP_EVENT_ALL_ACC' 
[2012-09-26 07:49:41.236829] D [glusterd-utils.c:4767:glusterd_sm_tr_log_transition_add] 0-: returning 0 
[2012-09-26 07:49:41.237278] D [socket.c:373:__socket_rwv] 0-socket.management: EOF on socket 
[2012-09-26 07:49:41.237320] W [socket.c:399:__socket_rwv] 0-socket.management: readv failed (No data available) 
[2012-09-26 07:49:41.237339] D [socket.c:2104:socket_event_handler] 0-transport: disconnecting now 

_______________________________________________ 
Gluster-devel mailing list 
Gluster-devel at nongnu.org 
https://lists.nongnu.org/mailman/listinfo/gluster-devel 

_______________________________________________
Gluster-devel mailing list
Gluster-devel at nongnu.org
https://lists.nongnu.org/mailman/listinfo/gluster-devel