[Gluster-users] glusterfs 4.1.6 error in starting glusterd service
Atin Mukherjee
amukherj at redhat.com
Wed Jan 16 11:04:18 UTC 2019
This is a case of partial write of a transaction and as the host ran out of
space for the root partition where all the glusterd related configurations
are persisted, the transaction couldn't be written and hence the new
(replaced) brick's information wasn't persisted in the configuration. The
workaround for this is to copy the content of
/var/lib/glusterd/vols/gfs-tst/ from one of the nodes in the trusted
storage pool to the node where glusterd service fails to come up and post
that restarting the glusterd service should be able to make peer status
reporting all nodes healthy and connected.
On Wed, Jan 16, 2019 at 3:49 PM Amudhan P <amudhan83 at gmail.com> wrote:
> Hi,
>
> In short, when I started glusterd service I am getting following error msg
> in the glusterd.log file in one server.
> what needs to be done?
>
> error logged in glusterd.log
>
> [2019-01-15 17:50:13.956053] I [MSGID: 100030] [glusterfsd.c:2741:main]
> 0-/usr/local/sbin/glusterd: Started running /usr/local/sbin/glusterd
> version 4.1.6 (args: /usr/local/sbin/glusterd -p /var/run/glusterd.pid)
> [2019-01-15 17:50:13.960131] I [MSGID: 106478] [glusterd.c:1423:init]
> 0-management: Maximum allowed open file descriptors set to 65536
> [2019-01-15 17:50:13.960193] I [MSGID: 106479] [glusterd.c:1481:init]
> 0-management: Using /var/lib/glusterd as working directory
> [2019-01-15 17:50:13.960212] I [MSGID: 106479] [glusterd.c:1486:init]
> 0-management: Using /var/run/gluster as pid file working directory
> [2019-01-15 17:50:13.964437] W [MSGID: 103071]
> [rdma.c:4629:__gf_rdma_ctx_create] 0-rpc-transport/rdma: rdma_cm event
> channel creation failed [No such device]
> [2019-01-15 17:50:13.964474] W [MSGID: 103055] [rdma.c:4938:init]
> 0-rdma.management: Failed to initialize IB Device
> [2019-01-15 17:50:13.964491] W [rpc-transport.c:351:rpc_transport_load]
> 0-rpc-transport: 'rdma' initialization failed
> [2019-01-15 17:50:13.964560] W [rpcsvc.c:1781:rpcsvc_create_listener]
> 0-rpc-service: cannot create listener, initing the transport failed
> [2019-01-15 17:50:13.964579] E [MSGID: 106244] [glusterd.c:1764:init]
> 0-management: creation of 1 listeners failed, continuing with succeeded
> transport
> [2019-01-15 17:50:14.967681] I [MSGID: 106513]
> [glusterd-store.c:2240:glusterd_restore_op_version] 0-glusterd: retrieved
> op-version: 40100
> [2019-01-15 17:50:14.973931] I [MSGID: 106544]
> [glusterd.c:158:glusterd_uuid_init] 0-management: retrieved UUID:
> d6bf51a7-c296-492f-8dac-e81efa9dd22d
> [2019-01-15 17:50:15.046620] E [MSGID: 101032]
> [store.c:441:gf_store_handle_retrieve] 0-: Path corresponding to
> /var/lib/glusterd/vols/gfs-tst/bricks/IP.3:-media-disk3-brick3. [No such
> file or directory]
> [2019-01-15 17:50:15.046685] E [MSGID: 106201]
> [glusterd-store.c:3384:glusterd_store_retrieve_volumes] 0-management:
> Unable to restore volume: gfs-tst
> [2019-01-15 17:50:15.046718] E [MSGID: 101019] [xlator.c:720:xlator_init]
> 0-management: Initialization of volume 'management' failed, review your
> volfile again
> [2019-01-15 17:50:15.046732] E [MSGID: 101066]
> [graph.c:367:glusterfs_graph_init] 0-management: initializing translator
> failed
> [2019-01-15 17:50:15.046741] E [MSGID: 101176]
> [graph.c:738:glusterfs_graph_activate] 0-graph: init failed
> [2019-01-15 17:50:15.047171] W [glusterfsd.c:1514:cleanup_and_exit]
> (-->/usr/local/sbin/glusterd(glusterfs_volumes
>
>
>
> In long, I am trying to simulate a situation. where volume stoped
> abnormally and
> entire cluster restarted with some missing disks.
>
> My test cluster is set up with 3 nodes and each has four disks, I have
> setup a volume with disperse 4+2.
> In Node-3 2 disks have failed, to replace I have shutdown all system
>
> below are the steps done.
>
> 1. umount from client machine
> 2. shutdown all system by running `shutdown -h now` command ( without
> stopping volume and stop service)
> 3. replace faulty disk in Node-3
> 4. powered ON all system
> 5. format replaced drives, and mount all drives
> 6. start glusterd service in all node (success)
> 7. Now running `voulume status` command from node-3
> output : [2019-01-15 16:52:17.718422] : v status : FAILED : Staging
> failed on 0083ec0c-40bf-472a-a128-458924e56c96. Please check log file for
> details.
> 8. running `voulume start gfs-tst` command from node-3
> output : [2019-01-15 16:53:19.410252] : v start gfs-tst : FAILED : Volume
> gfs-tst already started
>
> 9. running `gluster v status` in other node. showing all brick available
> but 'self-heal daemon' not running
> @gfstst-node2:~$ sudo gluster v status
> Status of volume: gfs-tst
> Gluster process TCP Port RDMA Port Online
> Pid
>
> ------------------------------------------------------------------------------
> Brick IP.2:/media/disk1/brick1 49152 0 Y 1517
> Brick IP.4:/media/disk1/brick1 49152 0 Y 1668
> Brick IP.2:/media/disk2/brick2 49153 0 Y 1522
> Brick IP.4:/media/disk2/brick2 49153 0 Y 1678
> Brick IP.2:/media/disk3/brick3 49154 0 Y 1527
> Brick IP.4:/media/disk3/brick3 49154 0 Y 1677
> Brick IP.2:/media/disk4/brick4 49155 0 Y 1541
> Brick IP.4:/media/disk4/brick4 49155 0 Y 1683
> Self-heal Daemon on localhost N/A N/A Y
> 2662
> Self-heal Daemon on IP.4 N/A N/A Y 2786
>
> 10. in the above output 'volume already started'. so, running
> `reset-brick` command
> v reset-brick gfs-tst IP.3:/media/disk3/brick3 IP.3:/media/disk3/brick3
> commit force
>
> output : [2019-01-15 16:57:37.916942] : v reset-brick gfs-tst
> IP.3:/media/disk3/brick3 IP.3:/media/disk3/brick3 commit force : FAILED :
> /media/disk3/brick3 is already part of a volume
>
> 11. reset-brick command was not working, so, tried stopping volume and
> start with force command
> output : [2019-01-15 17:01:04.570794] : v start gfs-tst force : FAILED :
> Pre-validation failed on localhost. Please check log file for details
>
> 12. now stopped service in all node and tried starting again. except
> node-3 other nodes service started successfully without any issues.
>
> in node-3 receiving following message.
>
> sudo service glusterd start
> * Starting glusterd service glusterd
>
> [fail]
> /usr/local/sbin/glusterd: option requires an argument -- 'f'
> Try `glusterd --help' or `glusterd --usage' for more information.
>
> 13. checking glusterd log file found that OS drive was running out of space
> output : [2019-01-15 16:51:37.210792] W [MSGID: 101012]
> [store.c:372:gf_store_save_value] 0-management: fflush failed. [No space
> left on device]
> [2019-01-15 16:51:37.210874] E [MSGID: 106190]
> [glusterd-store.c:1058:glusterd_volume_exclude_options_write] 0-management:
> Unable to write volume values for gfs-tst
>
> 14. cleared some space in OS drive but still, service is not running.
> below is the error logged in glusterd.log
>
> [2019-01-15 17:50:13.956053] I [MSGID: 100030] [glusterfsd.c:2741:main]
> 0-/usr/local/sbin/glusterd: Started running /usr/local/sbin/glusterd
> version 4.1.6 (args: /usr/local/sbin/glusterd -p /var/run/glusterd.pid)
> [2019-01-15 17:50:13.960131] I [MSGID: 106478] [glusterd.c:1423:init]
> 0-management: Maximum allowed open file descriptors set to 65536
> [2019-01-15 17:50:13.960193] I [MSGID: 106479] [glusterd.c:1481:init]
> 0-management: Using /var/lib/glusterd as working directory
> [2019-01-15 17:50:13.960212] I [MSGID: 106479] [glusterd.c:1486:init]
> 0-management: Using /var/run/gluster as pid file working directory
> [2019-01-15 17:50:13.964437] W [MSGID: 103071]
> [rdma.c:4629:__gf_rdma_ctx_create] 0-rpc-transport/rdma: rdma_cm event
> channel creation failed [No such device]
> [2019-01-15 17:50:13.964474] W [MSGID: 103055] [rdma.c:4938:init]
> 0-rdma.management: Failed to initialize IB Device
> [2019-01-15 17:50:13.964491] W [rpc-transport.c:351:rpc_transport_load]
> 0-rpc-transport: 'rdma' initialization failed
> [2019-01-15 17:50:13.964560] W [rpcsvc.c:1781:rpcsvc_create_listener]
> 0-rpc-service: cannot create listener, initing the transport failed
> [2019-01-15 17:50:13.964579] E [MSGID: 106244] [glusterd.c:1764:init]
> 0-management: creation of 1 listeners failed, continuing with succeeded
> transport
> [2019-01-15 17:50:14.967681] I [MSGID: 106513]
> [glusterd-store.c:2240:glusterd_restore_op_version] 0-glusterd: retrieved
> op-version: 40100
> [2019-01-15 17:50:14.973931] I [MSGID: 106544]
> [glusterd.c:158:glusterd_uuid_init] 0-management: retrieved UUID:
> d6bf51a7-c296-492f-8dac-e81efa9dd22d
> [2019-01-15 17:50:15.046620] E [MSGID: 101032]
> [store.c:441:gf_store_handle_retrieve] 0-: Path corresponding to
> /var/lib/glusterd/vols/gfs-tst/bricks/IP.3:-media-disk3-brick3. [No such
> file or directory]
> [2019-01-15 17:50:15.046685] E [MSGID: 106201]
> [glusterd-store.c:3384:glusterd_store_retrieve_volumes] 0-management:
> Unable to restore volume: gfs-tst
> [2019-01-15 17:50:15.046718] E [MSGID: 101019] [xlator.c:720:xlator_init]
> 0-management: Initialization of volume 'management' failed, review your
> volfile again
> [2019-01-15 17:50:15.046732] E [MSGID: 101066]
> [graph.c:367:glusterfs_graph_init] 0-management: initializing translator
> failed
> [2019-01-15 17:50:15.046741] E [MSGID: 101176]
> [graph.c:738:glusterfs_graph_activate] 0-graph: init failed
> [2019-01-15 17:50:15.047171] W [glusterfsd.c:1514:cleanup_and_exit]
> (-->/usr/local/sbin/glusterd(glusterfs_volumes_init+0xc2) [0x409f52]
> -->/usr/local/sbin/glusterd(glusterfs_process_volfp+0x151) [0x409e41]
> -->/usr/local/sbin/glusterd(cleanup_and_exit+0x5f) [0x40942f] ) 0-:
> received signum (-1), shutting down
>
>
> 15. In other node running `volume status' still shows bricks node3 is live
> but 'peer status' showing node-3 disconnected
>
> @gfstst-node2:~$ sudo gluster v status
> Status of volume: gfs-tst
> Gluster process TCP Port RDMA Port Online
> Pid
>
> ------------------------------------------------------------------------------
> Brick IP.2:/media/disk1/brick1 49152 0 Y 1517
> Brick IP.4:/media/disk1/brick1 49152 0 Y 1668
> Brick IP.2:/media/disk2/brick2 49153 0 Y 1522
> Brick IP.4:/media/disk2/brick2 49153 0 Y 1678
> Brick IP.2:/media/disk3/brick3 49154 0 Y 1527
> Brick IP.4:/media/disk3/brick3 49154 0 Y 1677
> Brick IP.2:/media/disk4/brick4 49155 0 Y 1541
> Brick IP.4:/media/disk4/brick4 49155 0 Y 1683
> Self-heal Daemon on localhost N/A N/A Y 2662
> Self-heal Daemon on IP.4 N/A N/A Y 2786
>
> Task Status of Volume gfs-tst
>
> ------------------------------------------------------------------------------
> There are no active volume tasks
>
>
> root at gfstst-node2:~$ sudo gluster pool list
> UUID Hostname State
> d6bf51a7-c296-492f-8dac-e81efa9dd22d IP.3 Disconnected
> c1cbb58e-3ceb-4637-9ba3-3d28ef20b143 IP.4 Connected
> 0083ec0c-40bf-472a-a128-458924e56c96 localhost Connected
>
> root at gfstst-node2:~$ sudo gluster peer status
> Number of Peers: 2
>
> Hostname: IP.3
> Uuid: d6bf51a7-c296-492f-8dac-e81efa9dd22d
> State: Peer in Cluster (Disconnected)
>
> Hostname: IP.4
> Uuid: c1cbb58e-3ceb-4637-9ba3-3d28ef20b143
> State: Peer in Cluster (Connected)
>
>
> regards
> Amudhan
> _______________________________________________
> Gluster-users mailing list
> Gluster-users at gluster.org
> https://lists.gluster.org/mailman/listinfo/gluster-users
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.gluster.org/pipermail/gluster-users/attachments/20190116/cca68b64/attachment.html>
More information about the Gluster-users
mailing list