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?<div><br></div><div>Avati</div><div><br><br><div class="gmail_quote">On Wed, Sep 26, 2012 at 1:06 AM, Jan Engelhardt <span dir="ltr"><<a href="mailto:jengelh@inai.de" target="_blank">jengelh@inai.de</a>></span> wrote:<br>
<blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><br>
<br>
With glusterfs v3.3.0qa39-457-g5ad96fb ("master" branch), starting a<br>
volume fails. The start command however works with v3.3.1qa3<br>
("release-3.3" branch).<br>
<br>
<br>
# gluster volume create d0 replica 2 transport tcp \<br>
mozart:/sync/.gluster-store bach:/sync/.gluster-store<br>
<br>
09:35 mozart:/tmp/glu # gluster volume info<br>
<br>
Volume Name: d0<br>
Type: Replicate<br>
Volume ID: 09386acc-7149-4c9c-b8f2-e6ed4104435b<br>
Status: Created<br>
Number of Bricks: 1 x 2 = 2<br>
Transport-type: tcp<br>
Bricks:<br>
Brick1: mozart:/sync/.gluster-store<br>
Brick2: bach:/sync/.gluster-store<br>
09:35 mozart:/tmp/glu # gluster volume status<br>
Volume d0 is not started<br>
<br>
09:35 mozart:/tmp/glu # gluster volume start d0<br>
volume start: d0: failed<br>
09:35 mozart:/tmp/glu # tail /var/log/messages<br>
Sep 26 09:35:50 mozart GlusterFS[19401]: [2012-09-26 07:35:50.067710] C<br>
[glusterd-op-sm.c:1923:glusterd_op_build_payload] 0-management: volname<br>
is not present in operation ctx<br>
<br>
When glusterd runs with --debug, on `volume start`, it prints:<br>
<br>
[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<br>
[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<br>
[2012-09-26 07:49:41.234191] I [glusterd-handler.c:440:glusterd_op_txn_begin] 0-management: Acquired local lock<br>
[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'<br>
[2012-09-26 07:49:41.234214] D [glusterd-handler.c:458:glusterd_op_txn_begin] 0-management: Returning 0<br>
[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'<br>
[2012-09-26 07:49:41.234297] D [glusterd-rpc-ops.c:1629:glusterd_cluster_lock] 0-glusterd: Returning 0<br>
[2012-09-26 07:49:41.234311] D [glusterd-op-sm.c:1618:glusterd_op_ac_send_lock] 0-: Returning with 0<br>
[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'<br>
[2012-09-26 07:49:41.234332] D [glusterd-utils.c:4767:glusterd_sm_tr_log_transition_add] 0-: returning 0<br>
[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<br>
[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<br>
[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'<br>
[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'<br>
[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'<br>
[2012-09-26 07:49:41.235353] D [glusterd-op-sm.c:1767:glusterd_op_ac_rcvd_lock_acc] 0-: Returning 0<br>
[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'<br>
[2012-09-26 07:49:41.235372] D [glusterd-utils.c:4767:glusterd_sm_tr_log_transition_add] 0-: returning 0<br>
[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'<br>
[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<br>
[2012-09-26 07:49:41.235466] E [glusterd-op-sm.c:1968:glusterd_op_ac_send_stage_op] 0-management: Building payload failed<br>
[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'<br>
[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<br>
[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'<br>
[2012-09-26 07:49:41.235508] D [glusterd-op-sm.c:134:glusterd_op_sm_inject_all_acc] 0-: Returning 0<br>
[2012-09-26 07:49:41.235516] D [glusterd-op-sm.c:2021:glusterd_op_ac_send_stage_op] 0-: Returning with 0<br>
[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'<br>
[2012-09-26 07:49:41.235535] D [glusterd-utils.c:4767:glusterd_sm_tr_log_transition_add] 0-: returning 0<br>
[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'<br>
[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'<br>
[2012-09-26 07:49:41.235561] D [glusterd-op-sm.c:2425:glusterd_op_ac_stage_op_failed] 0-: Returning 0<br>
[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'<br>
[2012-09-26 07:49:41.235579] D [glusterd-utils.c:4767:glusterd_sm_tr_log_transition_add] 0-: returning 0<br>
[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'<br>
[2012-09-26 07:49:41.235601] D [glusterd-op-sm.c:1577:glusterd_op_ac_none] 0-: Returning with 0<br>
[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'<br>
[2012-09-26 07:49:41.235609] D [glusterd-utils.c:4767:glusterd_sm_tr_log_transition_add] 0-: returning 0<br>
[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'<br>
[2012-09-26 07:49:41.235635] D [glusterd-rpc-ops.c:1663:glusterd_cluster_unlock] 0-glusterd: Returning 0<br>
[2012-09-26 07:49:41.235668] D [glusterd-op-sm.c:1665:glusterd_op_ac_send_unlock] 0-: Returning with 0<br>
[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'<br>
[2012-09-26 07:49:41.235685] D [glusterd-utils.c:4767:glusterd_sm_tr_log_transition_add] 0-: returning 0<br>
[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<br>
[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<br>
[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'<br>
[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'<br>
[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'<br>
[2012-09-26 07:49:41.236569] D [glusterd-op-sm.c:2562:glusterd_op_ac_rcvd_unlock_acc] 0-: Returning 0<br>
[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'<br>
[2012-09-26 07:49:41.236588] D [glusterd-utils.c:4767:glusterd_sm_tr_log_transition_add] 0-: returning 0<br>
[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'<br>
[2012-09-26 07:49:41.236619] I [glusterd-op-sm.c:2630:glusterd_op_txn_complete] 0-glusterd: Cleared local lock<br>
[2012-09-26 07:49:41.236632] E [glusterd-utils.c:5732:glusterd_to_cli] 0-glusterd: Failed to get command string<br>
[2012-09-26 07:49:41.236685] D [glusterd-rpc-ops.c:180:glusterd_op_send_cli_response] 0-: Returning 0<br>
[2012-09-26 07:49:41.236698] D [glusterd-op-sm.c:2648:glusterd_op_txn_complete] 0-glusterd: Returning 0<br>
[2012-09-26 07:49:41.236709] D [glusterd-op-sm.c:2661:glusterd_op_ac_unlocked_all] 0-: Returning 0<br>
volume start: d0: failed<br>
[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'<br>
[2012-09-26 07:49:41.236829] D [glusterd-utils.c:4767:glusterd_sm_tr_log_transition_add] 0-: returning 0<br>
[2012-09-26 07:49:41.237278] D [socket.c:373:__socket_rwv] 0-socket.management: EOF on socket<br>
[2012-09-26 07:49:41.237320] W [socket.c:399:__socket_rwv] 0-socket.management: readv failed (No data available)<br>
[2012-09-26 07:49:41.237339] D [socket.c:2104:socket_event_handler] 0-transport: disconnecting now<br>
<br>
_______________________________________________<br>
Gluster-devel mailing list<br>
<a href="mailto:Gluster-devel@nongnu.org">Gluster-devel@nongnu.org</a><br>
<a href="https://lists.nongnu.org/mailman/listinfo/gluster-devel" target="_blank">https://lists.nongnu.org/mailman/listinfo/gluster-devel</a><br>
</blockquote></div><br></div>