Re: Volume start fails on recent git

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

 



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@xxxxxxx> 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@xxxxxxxxxx
https://lists.nongnu.org/mailman/listinfo/gluster-devel


[Index of Archives]     [Gluster Users]     [Ceph Users]     [Linux ARM Kernel]     [Linux ARM]     [Linux Omap]     [Fedora ARM]     [IETF Annouce]     [Security]     [Bugtraq]     [Linux]     [Linux OMAP]     [Linux MIPS]     [eCos]     [Asterisk Internet PBX]     [Linux API]

  Powered by Linux