Status: Closed (View Workflow)
Affects Version/s: 5.2.0.CR1
Fix Version/s: 5.2.0.Final
Git Pull Request:
Using a clustered cache with 2 nodes, where the cache in each node is configured identically with replication, eviction and (non-shared) file cache store. (See attached configuration.)
The first (coordinator) process in the cluster is started and populated with 293 entries. Then the first process continually adds a few entries every 5 seconds. After a short delay, the second process is started, at which point it joins the cluster and starts the state-transfer process; logging shows in the first process that all 293 entries are transferred to the new cluster member, and the second log shows that they are all received. The second process then attempts to look for a specific entry that was created during initial population in the first process. This fails to find the existing entry.
By enabling trace logging and "IDE breakpoint output messages" around state transfer, it's visible that from the 293 keys, only 218 are placed into the cache, the others being lost.
(This problem was originally discovered when clustering ModeShape, which behaves roughly in the manner described above. The initial entries that are populated upon initialization are content created when a new repository is started. The second process looks for this content, and if it finds the content it knows not to create all of this initial content. However, if it doesn't find it, it thinks the repository has not yet been initialized and that it should create the initial content. The problem described by this bug then manifests itself in ModeShape through dozens of exceptions because the repository has been corrupted. See
MODE-1745 for details on this problem. ModeShape's corresponding known issue for this issue, ISPN-2712, is MODE-1754.)
The eviction is configured like this:
The attached log file is from the second process (the "receiver" node) and it contains the following key points:
- line 40 - the total number of keys & entries to be transferred = 293
- line 1352 and from there onwards 1358 / 1364 / i + 6 - the data container's size stops growing at 218, while the other entries are being sent. This means that in effect, they are ignored.
- line 1797 - the loop from org.infinispan.statetransfer.StateConsumerImpl#doApplyState finishes
Disabling eviction fixes the problem and all 293 nodes are placed in Node2's cache.
(I initially marked this as CRITICAL priority, though it is a blocker for our use of Infinispan 5.2.)