[CURATOR-405] Connection loss not leading to ConnectionState.LOST and causing logs to fill

• Type: Bug
• Status: Resolved
• Priority: Major
• Resolution: Fixed
• Affects Version/s: 3.3.0
• Fix Version/s: 4.1.0
• Component/s: Framework
• Assignee: Jordan Zimmerman
• Reporter: Jordan Zimmerman
• Created: 02/May/17 19:31
• Updated: 09/Dec/18 22:06
• Resolved: 09/Dec/18 22:06

Description

It's unclear how it got into this state, but a client started endlessly logging "Session timeout has elapsed while SUSPENDED". Curator 3.0 is supposed to go to LOST and inject a session expiration but it's not working in this case.

There are millions of these:

[2017-05-01 18:29:34,636][WARN ][org.apache.curator.framework.state.ConnectionStateManager] Session timeout has elapsed while SUSPENDED. Injecting a session expiration. Elapsed ms: 250368206. Adjusted session timeout ms: 15000 {}
[2017-05-01 18:29:34,637][INFO ][XXXXX] Resolved connection string from [http://localhost:2180/zookeeper/clients/ensemble/connection-string?namespace=/v1] to [localhost:22191,localhost:22194,localhost:22192,localhost:22193/v1] with local namespace [/v1] {}
[2017-05-01 18:29:34,637][INFO ][org.apache.zookeeper.ZooKeeperTestable] injectSessionExpiration() called {}
[2017-05-01 18:29:34,637][WARN ][org.apache.curator.framework.state.ConnectionStateManager] Session timeout has elapsed while SUSPENDED. Injecting a session expiration. Elapsed ms: 250368207. Adjusted session timeout ms: 15000 {}
[2017-05-01 18:29:34,637][INFO ][XXXXX] Resolved connection string from [http://localhost:2180/zookeeper/clients/ensemble/connection-string?namespace=/v1] to [localhost:22191,localhost:22194,localhost:22192,localhost:22193/v1] with local namespace [/v1] {}
[2017-05-01 18:29:34,637][INFO ][org.apache.zookeeper.ZooKeeperTestable] injectSessionExpiration() called {}
[2017-05-01 18:29:34,637][WARN ][org.apache.curator.framework.state.ConnectionStateManager] Session timeout has elapsed while SUSPENDED. Injecting a session expiration. Elapsed ms: 250368207. Adjusted session timeout ms: 15000 {}
[2017-05-01 18:29:34,637][INFO ][XXXXX] Resolved connection string from [http://localhost:2180/zookeeper/clients/ensemble/connection-string?namespace=/v1] to [localhost:22191,localhost:22194,localhost:22192,localhost:22193/v1] with local namespace [/v1] {}
[2017-05-01 18:29:34,637][INFO ][org.apache.zookeeper.ZooKeeperTestable] injectSessionExpiration() called {}
[2017-05-01 18:29:34,637][WARN ][org.apache.curator.framework.state.ConnectionStateManager] Session timeout has elapsed while SUSPENDED. Injecting a session expiration. Elapsed ms: 250368207. Adjusted session timeout ms: 15000 {}
[2017-05-01 18:29:34,637][INFO ][XXXXX] Resolved connection string from [http://localhost:2180/zookeeper/clients/ensemble/connection-string?namespace=/v1] to [localhost:22191,localhost:22194,localhost:22192,localhost:22193/v1] with local namespace [/v1] {}
[2017-05-01 18:29:34,637][INFO ][org.apache.zookeeper.ZooKeeperTestable] injectSessionExpiration() called {}
[2017-05-01 18:29:34,637][WARN ][org.apache.curator.framework.state.ConnectionStateManager] Session timeout has elapsed while SUSPENDED. Injecting a session expiration. Elapsed ms: 250368207. Adjusted session timeout ms: 15000 {}
[2017-05-01 18:29:34,638][INFO ][XXXXX] Resolved connection string from [http://localhost:2180/zookeeper/clients/ensemble/connection-string?namespace=/v1] to [localhost:22191,localhost:22194,localhost:22192,localhost:22193/v1] with local namespace [/v1] {}
[2017-05-01 18:29:34,638][INFO ][org.apache.zookeeper.ZooKeeperTestable] injectSessionExpiration() called {}
[2017-05-01 18:29:34,638][WARN ][org.apache.curator.framework.state.ConnectionStateManager] Session timeout has elapsed while SUSPENDED. Injecting a session expiration. Elapsed ms: 250368208. Adjusted session timeout ms: 15000 {}

Issue Links

• links to: GitHub Pull Request #292