Bug 1083089

Summary: Client logs are filled with gf_ports_reserved warnings for port 54321
Product: [Community] GlusterFS Reporter: vpshastry <vshastry>
Component: coreAssignee: Niels de Vos <ndevos>
Status: CLOSED CURRENTRELEASE QA Contact:
Severity: medium Docs Contact:
Priority: medium    
Version: mainlineCC: bugs, mkeir, rhinduja, rhs-bugs, rwheeler, smohan, vbellur
Target Milestone: ---   
Target Release: ---   
Hardware: x86_64   
OS: Linux   
Whiteboard:
Fixed In Version: glusterfs-4.1.3 (or later) Doc Type: Bug Fix
Doc Text:
Story Points: ---
Clone Of: 971276 Environment:
Last Closed: 2018-08-29 03:35:01 UTC Type: Bug
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:
Embargoed:
Bug Depends On: 971276    
Bug Blocks: 1050219    

Description vpshastry 2014-04-01 13:06:02 UTC
+++ This bug was initially created as a clone of Bug #971276 +++

Description of problem:
=======================

Client logs are filled with following messages

[2013-06-06 06:25:49.595443] W [common-utils.c:2405:gf_ports_reserved] 0-glusterfs-socket: 54321 is not a valid port identifier


neither bricks nor glusterd has the port number 54321 so why the logs reports the port number 54321 as not a valid port identifier.

none of the config files in /var/lib/glusterd contains the port number 54321

local port ranges from
# cat /proc/sys/net/ipv4/ip_local_port_range 
32768	61000



Actual Result:
==============

[2013-06-06 07:37:47.238208] W [socket.c:515:__socket_rwv] 0-bb-rep-client-1: readv on 10.70.36.111:24007 failed (No data available)
[2013-06-06 07:37:47.241734] W [common-utils.c:2405:gf_ports_reserved] 0-glusterfs-socket: 54321 is not a valid port identifier
[2013-06-06 07:37:47.242168] E [socket.c:2158:socket_connect_finish] 0-bb-rep-client-1: connection to 10.70.36.111:49152 failed (Connection refused)
[2013-06-06 07:37:47.242205] W [socket.c:515:__socket_rwv] 0-bb-rep-client-1: readv on 10.70.36.111:49152 failed (No data available)
[2013-06-06 07:37:51.240290] W [common-utils.c:2405:gf_ports_reserved] 0-glusterfs-socket: 54321 is not a valid port identifier
[2013-06-06 07:37:51.242247] I [rpc-clnt.c:1648:rpc_clnt_reconfig] 0-bb-rep-client-1: changing port to 49152 (from 0)
[2013-06-06 07:37:51.242316] W [socket.c:515:__socket_rwv] 0-bb-rep-client-1: readv on 10.70.36.111:24007 failed (No data available)
[2013-06-06 07:37:51.245841] W [common-utils.c:2405:gf_ports_reserved] 0-glusterfs-socket: 54321 is not a valid port identifier
[2013-06-06 07:37:51.246319] E [socket.c:2158:socket_connect_finish] 0-bb-rep-client-1: connection to 10.70.36.111:49152 failed (Connection refused)
[2013-06-06 07:37:51.246355] W [socket.c:515:__socket_rwv] 0-bb-rep-client-1: readv on 10.70.36.111:49152 failed (No data available)
[2013-06-06 07:37:55.244333] W [common-utils.c:2405:gf_ports_reserved] 0-glusterfs-socket: 54321 is not a valid port identifier
[2013-06-06 07:37:55.246161] I [rpc-clnt.c:1648:rpc_clnt_reconfig] 0-bb-rep-client-1: changing port to 49152 (from 0)
[2013-06-06 07:37:55.246237] W [socket.c:515:__socket_rwv] 0-bb-rep-client-1: readv on 10.70.36.111:24007 failed (No data available)
[2013-06-06 07:37:55.249768] W [common-utils.c:2405:gf_ports_reserved] 0-glusterfs-socket: 54321 is not a valid port identifier
[2013-06-06 07:37:55.250332] E [socket.c:2158:socket_connect_finish] 0-bb-rep-client-1: connection to 10.70.36.111:49152 failed (Connection refused)
[2013-06-06 07:37:55.250367] W [socket.c:515:__socket_rwv] 0-bb-rep-client-1: readv on 10.70.36.111:49152 failed (No data available)
[2013-06-06 07:37:59.248485] W [common-utils.c:2405:gf_ports_reserved] 0-glusterfs-socket: 54321 is not a valid port identifier
[2013-06-06 07:37:59.250128] I [rpc-clnt.c:1648:rpc_clnt_reconfig] 0-bb-rep-client-1: changing port to 49152 (from 0)
[2013-06-06 07:37:59.250185] W [socket.c:515:__socket_rwv] 0-bb-rep-client-1: readv on 10.70.36.111:24007 failed (No data available)
[2013-06-06 07:37:59.253694] W [common-utils.c:2405:gf_ports_reserved] 0-glusterfs-socket: 54321 is not a valid port identifier
[2013-06-06 07:37:59.254087] E [socket.c:2158:socket_connect_finish] 0-bb-rep-client-1: connection to 10.70.36.111:49152 failed (Connection refused)
[2013-06-06 07:37:59.254116] W [socket.c:515:__socket_rwv] 0-bb-rep-client-1: readv on 10.70.36.111:49152 failed (No data available)
[2013-06-06 07:38:03.252549] W [common-utils.c:2405:gf_ports_reserved] 0-glusterfs-socket: 54321 is not a valid port identifier
[2013-06-06 07:38:03.254203] I [rpc-clnt.c:1648:rpc_clnt_reconfig] 0-bb-rep-client-1: changing port to 49152 (from 0)
[2013-06-06 07:38:03.254266] W [socket.c:515:__socket_rwv] 0-bb-rep-client-1: readv on 10.70.36.111:24007 failed (No data available)
[2013-06-06 07:38:03.257780] W [common-utils.c:2405:gf_ports_reserved] 0-glusterfs-socket: 54321 is not a valid port identifier
[2013-06-06 07:38:03.258153] E [socket.c:2158:socket_connect_finish] 0-bb-rep-client-1: connection to 10.70.36.111:49152 failed (Connection refused)
[2013-06-06 07:38:03.258181] W [socket.c:515:__socket_rwv] 0-bb-rep-client-1: readv on 10.70.36.111:49152 failed (No data available)
[2013-06-06 07:38:07.256660] W [common-utils.c:2405:gf_ports_reserved] 0-glusterfs-socket: 54321 is not a valid port identifier
[2013-06-06 07:38:07.258620] I [rpc-clnt.c:1648:rpc_clnt_reconfig] 0-bb-rep-client-1: changing port to 49152 (from 0)
[2013-06-06 07:38:07.258686] W [socket.c:515:__socket_rwv] 0-bb-rep-client-1: readv on 10.70.36.111:24007 failed (No data available)
[2013-06-06 07:38:07.262185] W [common-utils.c:2405:gf_ports_reserved] 0-glusterfs-socket: 54321 is not a valid port identifier
[2013-06-06 07:38:07.262600] E [socket.c:2158:socket_connect_finish] 0-bb-rep-client-1: connection to 10.70.36.111:49152 failed (Connection refused)
[2013-06-06 07:38:07.262630] W [socket.c:515:__socket_rwv] 0-bb-rep-client-1: readv on 10.70.36.111:49152 failed (No data available)
[2013-06-06 07:38:11.260734] W [common-utils.c:2405:gf_ports_reserved] 0-glusterfs-socket: 54321 is not a valid port identifier
[2013-06-06 07:38:11.262632] I [rpc-clnt.c:1648:rpc_clnt_reconfig] 0-bb-rep-client-1: changing port to 49152 (from 0)
[2013-06-06 07:38:11.262691] W [socket.c:515:__socket_rwv] 0-bb-rep-client-1: readv on 10.70.36.111:24007 failed (No data available)
[2013-06-06 07:38:11.266189] W [common-utils.c:2405:gf_ports_reserved] 0-glusterfs-socket: 54321 is not a valid port identifier
[2013-06-06 07:38:11.266637] E [socket.c:2158:socket_connect_finish] 0-bb-rep-client-1: connection to 10.70.36.111:49152 failed (Connection refused)
[2013-06-06 07:38:11.266696] W [socket.c:515:__socket_rwv] 0-bb-rep-client-1: readv on 10.70.36.111:49152 failed (No data available)
[2013-06-06 07:38:15.264754] W [common-utils.c:2405:gf_ports_reserved] 0-glusterfs-socket: 54321 is not a valid port identifier
[2013-06-06 07:38:15.266450] I [rpc-clnt.c:1648:rpc_clnt_reconfig] 0-bb-rep-client-1: changing port to 49152 (from 0)
[2013-06-06 07:38:15.266511] W [socket.c:515:__socket_rwv] 0-bb-rep-client-1: readv on 10.70.36.111:24007 failed (No data available)

Comment 1 Anand Avati 2014-04-01 13:07:28 UTC
REVIEW: http://review.gluster.org/7376 (common-utils: changed the port holder to int32_t) posted (#1) for review on master by Varun Shastry (vshastry)

Comment 4 Anand Avati 2014-08-23 09:39:18 UTC
REVIEW: http://review.gluster.org/7376 (common-utils: changed the port holder to uint16_t) posted (#2) for review on master by Niels de Vos (ndevos)

Comment 5 Anand Avati 2015-06-01 09:47:04 UTC
REVIEW: http://review.gluster.org/7376 (common-utils: changed the port holder to uint16_t) posted (#3) for review on master by Niels de Vos (ndevos)

Comment 6 Mike McCune 2016-03-28 23:11:51 UTC
This bug was accidentally moved from POST to MODIFIED via an error in automation, please see mmccune with any questions

Comment 7 Amar Tumballi 2018-08-29 03:35:01 UTC
This update is done in bulk based on the state of the patch and the time since last activity. If the issue is still seen, please reopen the bug.