<!DOCTYPE html PUBLIC "-//W3C//DTD XHTML 1.0 Strict//EN" "http://www.w3.org/TR/xhtml1/DTD/xhtml1-strict.dtd">
<html xmlns="http://www.w3.org/1999/xhtml">
<head>
<meta http-equiv="Content-Type" content="text/html; charset=utf-8" />
<meta name="viewport" content="width=device-width, initial-scale=1.0, maximum-scale=1.0" /> <base href="https://hibernate.atlassian.net" />
<title>Message Title</title>
</head>
<body class="jira" style="color: #333; font-family: Arial, sans-serif; font-size: 14px; line-height: 1.429">
<table id="background-table" cellpadding="0" cellspacing="0" width="100%" style="border-collapse: collapse; mso-table-lspace: 0pt; mso-table-rspace: 0pt; background-color: #f5f5f5; border-collapse: collapse; mso-table-lspace: 0pt; mso-table-rspace: 0pt">
<!-- header here -->
<tr>
<td id="header-pattern-container" style="padding: 0px; border-collapse: collapse; padding: 10px 20px">
<table id="header-pattern" cellspacing="0" cellpadding="0" border="0" style="border-collapse: collapse; mso-table-lspace: 0pt; mso-table-rspace: 0pt">
<tr>
<td id="header-avatar-image-container" valign="top" style="padding: 0px; border-collapse: collapse; vertical-align: top; width: 32px; padding-right: 8px"> <img id="header-avatar-image" class="image_fix" src="https://secure.gravatar.com/avatar/12df4da7e3351be801bc16b66caf8038?d=mm&s=48" height="32" width="32" border="0" style="border-radius: 3px; vertical-align: top" />
</td>
<td id="header-text-container" valign="middle" style="padding: 0px; border-collapse: collapse; vertical-align: middle; font-family: Arial, sans-serif; font-size: 14px; line-height: 20px; mso-line-height-rule: exactly; mso-text-raise: 1px"> <a class="user-hover" rel="hardy.ferentschik" id="email_hardy.ferentschik" href="https://hibernate.atlassian.net/secure/ViewProfile.jspa?name=hardy.ferentschik" style="color:#6c797f;; color: #3b73af; text-decoration: none">Hardy Ferentschik</a> <strong>edited a comment</strong> on <a href="https://hibernate.atlassian.net/browse/HSEARCH-1558" style="color: #3b73af; text-decoration: none"><img src="cid:jira-generated-image-static-task-99b5e76d-2791-4755-8a46-d29d1be1a4a4" height="16" width="16" border="0" align="absmiddle" alt="Task" /> HSEARCH-1558</a>
</td>
</tr>
</table>
</td>
</tr>
<tr>
<td id="email-content-container" style="padding: 0px; border-collapse: collapse; padding: 0 20px">
<table id="email-content-table" cellspacing="0" cellpadding="0" border="0" width="100%" style="border-collapse: collapse; mso-table-lspace: 0pt; mso-table-rspace: 0pt; border-spacing: 0; border-collapse: separate">
<tr>
<!-- there needs to be content in the cell for it to render in some clients -->
<td class="email-content-rounded-top mobile-expand" style="padding: 0px; border-collapse: collapse; color: #fff; padding: 0 15px 0 16px; height: 15px; background-color: #fff; border-left: 1px solid #ccc; border-top: 1px solid #ccc; border-right: 1px solid #ccc; border-bottom: 0; border-top-right-radius: 5px; border-top-left-radius: 5px; height: 10px; line-height: 10px; padding: 0 15px 0 16px; mso-line-height-rule: exactly">
</td>
</tr>
<tr>
<td class="email-content-main mobile-expand " style="padding: 0px; border-collapse: collapse; border-left: 1px solid #ccc; border-right: 1px solid #ccc; border-top: 0; border-bottom: 0; padding: 0 15px 0 16px; background-color: #fff">
<table class="page-title-pattern" cellspacing="0" cellpadding="0" border="0" width="100%" style="border-collapse: collapse; mso-table-lspace: 0pt; mso-table-rspace: 0pt">
<tr>
<td style="vertical-align: top;; padding: 0px; border-collapse: collapse; padding-right: 5px; font-size: 20px; line-height: 30px; mso-line-height-rule: exactly" class="page-title-pattern-header-container"> <span class="page-title-pattern-header" style="font-family: Arial, sans-serif; padding: 0; font-size: 20px; line-height: 30px; mso-text-raise: 2px; mso-line-height-rule: exactly; vertical-align: middle"> <a href="https://hibernate.atlassian.net/browse/HSEARCH-1558" style="color: #3b73af; text-decoration: none">Re: Upgrade JGroups to 3.5.0.Beta1</a> </span>
</td>
</tr>
</table>
</td>
</tr>
<tr>
<td id="text-paragraph-pattern-top" class="email-content-main mobile-expand comment-top-special-margin comment-top-pattern" style="padding: 0px; border-collapse: collapse; border-left: 1px solid #ccc; border-right: 1px solid #ccc; border-top: 0; border-bottom: 0; padding: 0 15px 0 16px; background-color: #fff; border-bottom: none; padding-bottom: 0">
<table class="text-paragraph-pattern" cellspacing="0" cellpadding="0" border="0" width="100%" style="border-collapse: collapse; mso-table-lspace: 0pt; mso-table-rspace: 0pt; font-family: Arial, sans-serif; font-size: 14px; line-height: 20px; mso-line-height-rule: exactly; mso-text-raise: 2px">
<tr>
<td class="text-paragraph-pattern-container mobile-resize-text " style="padding: 0px; border-collapse: collapse; padding: 0 0 10px 0; padding-top: 10px"> <span class="diffcontext">So here is the log with trace enabled for the test itself an JGroups:<br />{noformat}<br />/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/bin/java -Djava.net.preferIPv4Stack=true -Djgroups.bind_addr=127.0.0.1 -Dhibernate.service.allow_crawling=false -Didea.launcher.port=7536 "-Didea.launcher.bin.path=/Applications/Development/IDE/IntelliJ IDEA 13.app/bin" -Dfile.encoding=UTF-8 -classpath "/Applications/Development/IDE/IntelliJ IDEA 13.app/lib/idea_rt.jar:/Applications/Development/IDE/IntelliJ IDEA 13.app/plugins/junit/lib/junit-rt.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/lib/ant-javafx.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/lib/dt.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/lib/javafx-doclet.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/lib/javafx-mx.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/lib/jconsole.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/lib/sa-jdi.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/lib/tools.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/jre/lib/charsets.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/jre/lib/deploy.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/jre/lib/htmlconverter.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/jre/lib/javaws.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/jre/lib/jce.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/jre/lib/jfr.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/jre/lib/jfxrt.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/jre/lib/JObjC.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/jre/lib/jsse.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/jre/lib/management-agent.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/jre/lib/plugin.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/jre/lib/resources.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/jre/lib/rt.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/jre/lib/ext/dnsns.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/jre/lib/ext/localedata.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/jre/lib/ext/sunec.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/jre/lib/ext/sunjce_provider.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/jre/lib/ext/sunpkcs11.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_45.jdk/Contents/Home/jre/lib/ext/zipfs.jar:/Users/hardy/work/hibernate/git/search/backends/jgroups/target/test-classes:/Users/hardy/work/hibernate/git/search/backends/jgroups/target/classes:/Users/hardy/work/hibernate/git/search/engine/target/classes:/Users/hardy/tmp/m2/org/hibernate/common/hibernate-commons-annotations/4.0.4.Final/hibernate-commons-annotations-4.0.4.Final.jar:/Users/hardy/tmp/m2/org/jboss/logging/jboss-logging/3.1.4.GA/jboss-logging-3.1.4.GA.jar:/Users/hardy/tmp/m2/org/apache/lucene/lucene-core/4.6.1/lucene-core-4.6.1.jar:/Users/hardy/tmp/m2/org/apache/lucene/lucene-facet/4.6.1/lucene-facet-4.6.1.jar:/Users/hardy/tmp/m2/org/apache/lucene/lucene-analyzers-common/4.6.1/lucene-analyzers-common-4.6.1.jar:/Users/hardy/tmp/m2/org/apache/lucene/lucene-queries/4.6.1/lucene-queries-4.6.1.jar:/Users/hardy/tmp/m2/org/jgroups/jgroups/3.5.0.Beta1/jgroups-3.5.0.Beta1.jar:/Users/hardy/work/hibernate/git/search/serialization/avro/target/classes:/Users/hardy/tmp/m2/org/apache/avro/avro/1.7.6/avro-1.7.6.jar:/Users/hardy/tmp/m2/org/codehaus/jackson/jackson-core-asl/1.9.13/jackson-core-asl-1.9.13.jar:/Users/hardy/tmp/m2/org/codehaus/jackson/jackson-mapper-asl/1.9.13/jackson-mapper-asl-1.9.13.jar:/Users/hardy/tmp/m2/com/thoughtworks/paranamer/paranamer/2.3/paranamer-2.3.jar:/Users/hardy/tmp/m2/org/apache/commons/commons-compress/1.5/commons-compress-1.5.jar:/Users/hardy/tmp/m2/org/slf4j/slf4j-api/1.6.1/slf4j-api-1.6.1.jar:/Users/hardy/tmp/m2/org/apache/lucene/lucene-queryparser/4.6.1/lucene-queryparser-4.6.1.jar:/Users/hardy/tmp/m2/org/apache/lucene/lucene-sandbox/4.6.1/lucene-sandbox-4.6.1.jar:/Users/hardy/tmp/m2/jakarta-regexp/jakarta-regexp/1.4/jakarta-regexp-1.4.jar:/Users/hardy/tmp/m2/junit/junit/4.8.2/junit-4.8.2.jar:/Users/hardy/tmp/m2/log4j/log4j/1.2.16/log4j-1.2.16.jar:/Users/hardy/tmp/m2/org/hibernate/hibernate-testing/4.3.4.Final/hibernate-testing-4.3.4.Final.jar:/Users/hardy/tmp/m2/org/jboss/logging/jboss-logging-annotations/1.2.0.Beta1/jboss-logging-annotations-1.2.0.Beta1.jar:/Users/hardy/tmp/m2/org/hibernate/hibernate-core/4.3.4.Final/hibernate-core-4.3.4.Final.jar:/Users/hardy/tmp/m2/org/jboss/spec/javax/transaction/jboss-transaction-api_1.2_spec/1.0.0.Final/jboss-transaction-api_1.2_spec-1.0.0.Final.jar:/Users/hardy/tmp/m2/dom4j/dom4j/1.6.1/dom4j-1.6.1.jar:/Users/hardy/tmp/m2/xml-apis/xml-apis/1.3.03/xml-apis-1.3.03.jar:/Users/hardy/tmp/m2/org/hibernate/javax/persistence/hibernate-jpa-2.1-api/1.0.0.Final/hibernate-jpa-2.1-api-1.0.0.Final.jar:/Users/hardy/tmp/m2/org/javassist/javassist/3.18.1-GA/javassist-3.18.1-GA.jar:/Users/hardy/tmp/m2/antlr/antlr/2.7.7/antlr-2.7.7.jar:/Users/hardy/tmp/m2/org/jboss/jandex/1.1.0.Final/jandex-1.1.0.Final.jar:/Users/hardy/tmp/m2/org/jboss/byteman/byteman/2.1.4/byteman-2.1.4.jar:/Users/hardy/tmp/m2/org/jboss/byteman/byteman-install/2.1.4/byteman-install-2.1.4.jar:/Users/hardy/tmp/m2/org/jboss/byteman/byteman-bmunit/2.1.4/byteman-bmunit-2.1.4.jar:/Users/hardy/tmp/m2/org/jboss/byteman/byteman-submit/2.1.4/byteman-submit-2.1.4.jar:/Users/hardy/work/hibernate/git/search/orm/target/classes:/Users/hardy/work/hibernate/git/search/orm/target/test-classes:/Users/hardy/work/hibernate/git/search/engine/target/test-classes:/Users/hardy/tmp/m2/com/h2database/h2/1.3.175/h2-1.3.175.jar" com.intellij.rt.execution.application.AppMain com.intellij.rt.execution.junit.JUnitStarter -ideVersion5 org.hibernate.search.backend.jgroups.impl.SyncJGroupsBackendTest,testSynchSerialisationWithTimeout<br />22:24:28,410 (main) INFO Version:43 - HSEARCH000034: Hibernate Search 5.0.0-SNAPSHOT<br />22:24:28,458 (main) DEBUG ConfigContext:263 - Setting Lucene compatibility to Version LUCENE_CURRENT<br />22:24:28,596 (main) DEBUG DispatchMessageSender:115 - HSEARCH200008: Starting JGroups ChannelProvider<br />22:24:28,599 (main) DEBUG DispatchMessageSender:199 - HSEARCH200014: Starting JGroups channel using configuration 'jgroups-testing-udp.xml'<br />22:24:29,201 (main) DEBUG Configurator:820 - set property UDP.mcast_group_addr to default value /228.8.8.8<br />22:24:29,202 (main) DEBUG Configurator:820 - set property UDP.diagnostics_addr to default value /224.0.75.75<br />22:24:29,640 (main) DEBUG GMS:398 - Sarmakand-2-19291: found state transfer protocol STATE_TRANSFER, setting install_view_locally_first to true<br />22:24:29,641 (main) TRACE STABLE:455 - Sarmakand-2-19291: stable task started<br />22:24:29,643 (main) DEBUG UDP:320 - sockets will use interface 127.0.0.1<br />22:24:29,647 (main) DEBUG UDP:387 - socket information:<br />mcast_addr=228.8.8.8:45588, bind_addr=/127.0.0.1, ttl=0<br />sock: bound to 127.0.0.1:56943, receive buffer size=20000, send buffer size=20000<br />mcast_sock: bound to 127.0.0.1:45588, send buffer size=20000, receive buffer size=20000<br />22:24:29,649 (main) TRACE UDP:215 - joined /224.0.75.75:7500 on bridge100<br />22:24:29,649 (main) TRACE UDP:215 - joined /224.0.75.75:7500 on en1<br />22:24:29,649 (main) TRACE UDP:215 - joined /224.0.75.75:7500 on lo0<br /><br />-------------------------------------------------------------------<br />GMS: address=Sarmakand-2-19291, cluster=Hibernate Search Cluster, physical address=127.0.0.1:56943<br />-------------------------------------------------------------------<br />22:24:29,660 (main) TRACE UDP:1420 - Sarmakand-2-19291: sending msg to null, src=Sarmakand-2-19291, headers are PING: [PING: type=GET_MBRS_REQ, cluster=Hibernate Search Cluster], UDP: [cluster_name=Hibernate Search Cluster]<br />22:24:29,661 (main) TRACE UDP:1450 - Sarmakand-2-19291: looping back message [dst: <null>, src: Sarmakand-2-19291 (2 headers), size=51 bytes, flags=OOB|DONT_BUNDLE|INTERNAL]<br />22:24:29,662 (INT-1,Hibernate Search Cluster,Sarmakand-2-19291) TRACE UDP:1507 - Sarmakand-2-19291: received [dst: <null>, src: Sarmakand-2-19291 (2 headers), size=51 bytes, flags=OOB|DONT_BUNDLE|INTERNAL], headers are PING: [PING: type=GET_MBRS_REQ, cluster=Hibernate Search Cluster], UDP: [cluster_name=Hibernate Search Cluster]<br />22:24:29,665 (TransferQueueBundler,Hibernate Search Cluster,Sarmakand-2-19291) TRACE UDP:2358 - Sarmakand-2-19291: sending 1 msgs (140 bytes (0.22 of max_bundle_size) to 1 dests(s): [Hibernate Search Cluster]<br />22:24:29,667 (INT-1,Hibernate Search Cluster,Sarmakand-2-19291) TRACE PING:671 - Sarmakand-2-19291: received GET_MBRS_REQ from Sarmakand-2-19291, sending response Sarmakand-2-19291, view_id=, is_server=false, is_coord=false, logical_name=Sarmakand-2-19291, physical_addrs=(1) 127.0.0.1:56943<br />22:24:29,667 (TransferQueueBundler,Hibernate Search Cluster,Sarmakand-2-19291) ERROR UDP:2405 - JGRP000029: Sarmakand-2-19291: failed sending message to cluster (140 bytes): java.io.IOException: Can't assign requested address, headers: PING: [PING: type=GET_MBRS_REQ, cluster=Hibernate Search Cluster], UDP: [cluster_name=Hibernate Search Cluster]<br />22:24:29,668 (INT-1,Hibernate Search Cluster,Sarmakand-2-19291) TRACE UDP:1420 - Sarmakand-2-19291: sending msg to Sarmakand-2-19291, src=Sarmakand-2-19291, headers are PING: [PING: type=GET_MBRS_RSP], UDP: [cluster_name=Hibernate Search Cluster]<br />22:24:29,669 (INT-1,Hibernate Search Cluster,Sarmakand-2-19291) TRACE UDP:1450 - Sarmakand-2-19291: looping back message [dst: Sarmakand-2-19291, src: Sarmakand-2-19291 (2 headers), size=51 bytes, flags=OOB|DONT_BUNDLE|INTERNAL]<br />22:24:29,671 (INT-2,Hibernate Search Cluster,Sarmakand-2-19291) TRACE UDP:1507 - Sarmakand-2-19291: received [dst: Sarmakand-2-19291, src: Sarmakand-2-19291 (2 headers), size=51 bytes, flags=OOB|DONT_BUNDLE|INTERNAL], headers are PING: [PING: type=GET_MBRS_RSP], UDP: [cluster_name=Hibernate Search Cluster]<br />22:24:29,672 (INT-2,Hibernate Search Cluster,Sarmakand-2-19291) TRACE PING:485 - Sarmakand-2-19291: received GET_MBRS_RSP from Sarmakand-2-19291: Sarmakand-2-19291, view_id=, is_server=false, is_coord=false, logical_name=Sarmakand-2-19291, physical_addrs=(1) 127.0.0.1:56943<br />22:24:29,865 (main) TRACE PING:554 - Sarmakand-2-19291: discovery took 209 ms: responses: 1 total (0 servers (0 coord), 1 clients)<br />22:24:29,866 (main) TRACE GMS:88 - Sarmakand-2-19291: no initial members discovered: creating cluster as first member<br />22:24:29,870 (main) DEBUG NAKACK2:1302 - <br />[Sarmakand-2-19291 setDigest()]<br />existing digest: []<br />new digest: Sarmakand-2-19291: [0 (0)]<br />resulting digest: Sarmakand-2-19291: [0 (0)]<br />22:24:29,871 (main) DEBUG GMS:690 - Sarmakand-2-19291: installing view [Sarmakand-2-19291|0] (1) [Sarmakand-2-19291]<br />22:24:29,872 (main) DEBUG STABLE:202 - resuming message garbage collection<br />22:24:29,873 (main) DEBUG FD_SOCK:338 - Sarmakand-2-19291: VIEW_CHANGE received: [Sarmakand-2-19291]<br />22:24:29,876 (main) TRACE STABLE:415 - Sarmakand-2-19291: reset digest to Sarmakand-2-19291: [-1]<br />22:24:29,877 (main) TRACE UFC:519 - new membership: [Sarmakand-2-19291]<br />22:24:29,878 (main) TRACE MFC:519 - new membership: [Sarmakand-2-19291]<br />22:24:29,879 (main) DEBUG FLUSH:640 - Sarmakand-2-19291: installing view [Sarmakand-2-19291|0] (1) [Sarmakand-2-19291]<br />22:24:29,880 (main) DEBUG FLUSH:656 - Sarmakand-2-19291: received STOP_FLUSH, unblocking FLUSH.down() and sending UNBLOCK up<br />22:24:29,884 (main) TRACE STABLE:415 - Sarmakand-2-19291: reset digest to Sarmakand-2-19291: [-1]<br />22:24:29,884 (main) DEBUG STABLE:202 - resuming message garbage collection<br />22:24:29,885 (main) DEBUG GMS:307 - created cluster (first member). My view is [Sarmakand-2-19291|0], impl is org.jgroups.protocols.pbcast.CoordGmsImpl<br />22:24:29,886 (main) INFO DispatchMessageSender:262 - HSEARCH200020: Connected to cluster [ Hibernate Search Cluster ]. The local Address is Sarmakand-2-19291<br />22:24:29,937 (main) DEBUG DispatchMessageSender:154 - HSEARCH200011: Using JGroups channel having configuration '<config><br /> <UDP bind_addr="127.0.0.1" <br /> ignore_dont_bundle="true" <br /> oob_thread_pool_keep_alive_time="5000" <br /> timer_keep_alive_time="3000" <br /> external_port="0" <br /> oob_thread_pool_enabled="true" <br /> max_bundle_size="64000" <br /> diagnostics_ttl="8" <br /> physical_addr_max_fetch_attempts="3" <br /> internal_thread_pool_min_threads="2" <br /> receive_on_all_interfaces="false" <br /> mcast_port="45588" <br /> thread_pool_min_threads="2" <br /> thread_pool_keep_alive_time="5000" <br /> internal_thread_pool_queue_enabled="true" <br /> enable_diagnostics="true" <br /> thread_pool_max_threads="8" <br /> internal_thread_pool_queue_max_size="500" <br /> ucast_send_buf_size="20000" <br /> ip_ttl="0" <br /> oob_thread_pool_queue_max_size="100" <br /> enable_bundling="true" <br /> internal_thread_pool_rejection_policy="discard" <br /> thread_pool_queue_enabled="true" <br /> suppress_time_different_cluster_warnings="60000" <br /> diagnostics_port="7500" <br /> timer_rejection_policy="abort" <br /> oob_thread_pool_max_threads="8" <br /> wheel_size="200" <br /> disable_loopback="false" <br /> logical_addr_cache_max_size="500" <br /> ip_mcast="true" <br /> internal_thread_pool_enabled="true" <br /> tick_time="50" <br /> logical_addr_cache_expiration="120000" <br /> thread_pool_rejection_policy="discard" <br /> internal_thread_pool_keep_alive_time="30000" <br /> suppress_time_different_version_warnings="60000" <br /> enable_batching="true" <br /> oob_thread_pool_min_threads="1" <br /> who_has_cache_timeout="2000" <br /> port_range="50" <br /> internal_thread_pool_max_threads="4" <br /> stats="true" <br /> mcast_send_buf_size="20000" <br /> id="21" <br /> diagnostics_addr="224.0.75.75" <br /> mcast_recv_buf_size="20000" <br /> bind_port="0" tos="8" <br /> oob_thread_pool_rejection_policy="discard" <br /> loopback="true" <br /> oob_thread_pool_queue_enabled="false" <br /> name="UDP" <br /> enable_unicast_bundling="true" <br /> time_service_interval="500" <br /> log_discard_msgs_version="true" <br /> thread_pool_enabled="true" <br /> thread_naming_pattern="cl" <br /> ucast_recv_buf_size="20000" <br /> timer_max_threads="4" <br /> timer_queue_max_size="500" <br /> discard_incompatible_packets="true" <br /> ergonomics="true" <br /> bundler_capacity="20000" <br /> loopback_separate_thread="true" <br /> max_bundle_timeout="30" <br /> mcast_group_addr="228.8.8.8" <br /> bind_interface_str="" <br /> loopback_copy="false" <br /> timer_min_threads="2" <br /> log_discard_msgs="true" <br /> bundler_type="transfer-queue" <br /> thread_pool_queue_max_size="10000" <br /> timer_type="new3" /><br /> <PING id="6" <br /> always_send_physical_addr_with_discovery_request="false" <br /> return_entire_cache="false" <br /> num_initial_members="2" <br /> break_on_coord_rsp="true" <br /> use_disk_cache="false" <br /> force_sending_discovery_rsps="true" <br /> stagger_timeout="0" <br /> stats="true" <br /> name="PING" <br /> ergonomics="true" <br /> timeout="200" <br /> num_initial_srv_members="0" /><br /> <MERGE2 id="0" stats="true" <br /> merge_fast="true" <br /> name="MERGE2" <br /> inconsistent_view_threshold="1" <br /> min_interval="10000" <br /> ergonomics="true" <br /> merge_fast_delay="1000" <br /> max_interval="30000" /><br /> <FD_SOCK bind_addr="127.0.0.1" <br /> external_port="0" <br /> port_range="50" <br /> stats="true" <br /> suspect_msg_interval="5000" <br /> client_bind_port="0" <br /> ergonomics="true" <br /> num_tries="3" id="3" <br /> get_cache_timeout="1000" <br /> sock_conn_timeout="1000" <br /> bind_interface_str="" <br /> name="FD_SOCK" <br /> keep_alive="true" <br /> start_port="0" /><br /> <FD_ALL id="29" interval="8000" <br /> stats="true" <br /> name="FD_ALL" <br /> use_time_service="true" <br /> ergonomics="true" <br /> msg_counts_as_heartbeat="false" <br /> timeout="40000" <br /> timeout_check_interval="2000" /><br /> <VERIFY_SUSPECT id="13" <br /> bind_addr="127.0.0.1" <br /> bind_interface_str="" <br /> stats="true" <br /> name="VERIFY_SUSPECT" <br /> num_msgs="1" <br /> ergonomics="true" <br /> use_mcast_rsps="false" <br /> use_icmp="false" <br /> timeout="1500" /><br /> <BARRIER id="0" <br /> max_close_time="60000" <br /> stats="true" <br /> name="BARRIER" <br /> ergonomics="true" <br /> flush_timeout="5000" /><br /> <NAKACK2 <br /> use_mcast_xmit_req="false" <br /> use_mcast_xmit="false" <br /> suppress_time_non_member_warnings="60000" <br /> max_msg_batch_size="500" <br /> xmit_from_random_member="false" <br /> stats="true" <br /> xmit_table_max_compaction_time="30000" <br /> log_not_found_msgs="true" <br /> ergonomics="true" <br /> discard_delivered_msgs="true" <br /> print_stability_history_on_failed_xmit="false" <br /> id="57" <br /> become_server_queue_size="50" <br /> max_rebroadcast_timeout="2000" <br /> xmit_table_msgs_per_row="2000" <br /> xmit_table_num_rows="100" <br /> name="NAKACK2" <br /> log_discard_msgs="true" <br /> xmit_table_resize_factor="1.2" <br /> xmit_interval="500" /><br /> <UNICAST3 <br /> max_retransmit_time="60000" <br /> max_msg_batch_size="500" <br /> stats="true" <br /> xmit_table_max_compaction_time="60000" <br /> ack_batches_immediately="true" <br /> log_not_found_msgs="true" <br /> ergonomics="true" <br /> conn_close_timeout="60000" <br /> id="64" <br /> conn_expiry_timeout="0" <br /> xmit_table_msgs_per_row="2000" <br /> xmit_table_num_rows="100" <br /> name="UNICAST3" <br /> ack_threshold="5" <br /> xmit_table_resize_factor="1.2" <br /> xmit_interval="500" /><br /> <STABLE id="16" <br /> desired_avg_gossip="50000" <br /> max_bytes="4000000" <br /> stats="true" <br /> cap="0.1" <br /> name="STABLE" <br /> send_stable_msgs_to_coord_only="true" <br /> ergonomics="true" <br /> stability_delay="0" /><br /> <GMS print_local_addr="true" <br /> stats="true" <br /> max_bundling_time="50" <br /> log_collect_msgs="true" <br /> resume_task_timeout="20000" <br /> log_view_warnings="true" <br /> num_prev_views="10" <br /> ergonomics="true" <br /> use_flush_if_present="true" <br /> print_physical_addrs="true" <br /> merge_timeout="5000" <br /> id="14" <br /> membership_change_policy="org.jgroups.protocols.pbcast.GMS$DefaultMembershipPolicy@4132b71e" <br /> num_prev_mbrs="50" <br /> leave_timeout="1000" <br /> use_delta_views="true" <br /> install_view_locally_first="true" <br /> view_bundling="true" <br /> name="GMS" <br /> join_timeout="3000" <br /> handle_concurrent_startup="true" <br /> view_ack_collection_timeout="2000" <br /> max_join_attempts="0" /><br /> <UFC id="45" <br /> max_block_time="5000" <br /> max_credits="2000000" <br /> stats="true" <br /> ignore_synchronous_response="false" <br /> min_credits="800000" <br /> name="UFC" <br /> min_threshold="0.4" <br /> ergonomics="true" /><br /> <MFC id="44" <br /> max_block_time="5000" <br /> max_credits="2000000" <br /> stats="true" <br /> ignore_synchronous_response="false" <br /> min_credits="800000" <br /> name="MFC" <br /> min_threshold="0.4" <br /> ergonomics="true" /><br /> <FRAG2 id="5" frag_size="60000" <br /> stats="true" <br /> name="FRAG2" <br /> ergonomics="true" /><br /> <RSVP id="55" stats="true" <br /> name="RSVP" <br /> resend_interval="2000" <br /> throw_exception_on_timeout="true" <br /> ergonomics="true" <br /> ack_on_delivery="true" <br /> timeout="10000" /><br /> <STATE_TRANSFER id="17" <br /> stats="true" <br /> name="STATE_TRANSFER" <br /> ergonomics="true" /><br /> <FLUSH id="31" stats="true" <br /> start_flush_timeout="2000" <br /> name="FLUSH" <br /> enable_reconciliation="true" <br /> bypass="false" <br /> retry_timeout="2000" <br /> end_flush_timeout="2000" <br /> ergonomics="true" <br /> timeout="8000" /><br /></config>'<br />22:24:29,975 (main) DEBUG JGroupsBackendQueueProcessor:128 - HSEARCH200022: JGroups backend configured for index 'drinks' using block_for_ack 'true'<br />22:24:29,986 (main) DEBUG WorkspaceFactory:47 - Starting workspace for index drinks using an exclusive index strategy<br />22:24:30,284 (main) DEBUG DocumentBuilderIndexedEntity:184 - Field selection in projections is set to true for entity org.hibernate.search.backend.jgroups.impl.SyncJGroupsBackendTest$Drink.<br />22:24:30,285 (main) DEBUG JGroupsBackendQueueProcessor:128 - HSEARCH200022: JGroups backend configured for index 'stars' using block_for_ack 'true'<br />22:24:30,285 (main) DEBUG WorkspaceFactory:47 - Starting workspace for index stars using an exclusive index strategy<br />22:24:30,287 (main) DEBUG DocumentBuilderIndexedEntity:184 - Field selection in projections is set to true for entity org.hibernate.search.backend.jgroups.impl.SyncJGroupsBackendTest$Star.<br />22:24:30,288 (main) DEBUG JGroupsBackendQueueProcessor:128 - HSEARCH200022: JGroups backend configured for index 'dvds' using block_for_ack 'true'<br />22:24:30,288 (main) DEBUG WorkspaceFactory:47 - Starting workspace for index dvds using an exclusive index strategy<br />22:24:30,290 (main) DEBUG DocumentBuilderIndexedEntity:184 - Field selection in projections is set to true for entity org.hibernate.search.backend.jgroups.impl.SyncJGroupsBackendTest$Dvd.<br />22:24:30,290 (main) DEBUG JGroupsBackendQueueProcessor:128 - HSEARCH200022: JGroups backend configured for index 'books' using block_for_ack 'true'<br />22:24:30,291 (main) DEBUG WorkspaceFactory:47 - Starting workspace for index books using an exclusive index strategy<br />22:24:30,292 (main) DEBUG DocumentBuilderIndexedEntity:184 - Field selection in projections is set to true for entity org.hibernate.search.backend.jgroups.impl.SyncJGroupsBackendTest$Book.<br />22:24:30,306 (main) DEBUG ConfigContext:263 - Setting Lucene compatibility to Version LUCENE_CURRENT<br />22:24:30,309 (main) DEBUG DispatchMessageSender:115 - HSEARCH200008: Starting JGroups ChannelProvider<br />22:24:30,309 (main) DEBUG DispatchMessageSender:199 - HSEARCH200014: Starting JGroups channel using configuration 'jgroups-testing-udp.xml'<br />22:24:30,408 (main) DEBUG Configurator:820 - set property UDP.mcast_group_addr to default value /228.8.8.8<br />22:24:30,409 (main) DEBUG Configurator:820 - set property UDP.diagnostics_addr to default value /224.0.75.75<br />22:24:30,449 (main) DEBUG GMS:398 - Sarmakand-2-55210: found state transfer protocol STATE_TRANSFER, setting install_view_locally_first to true<br />22:24:30,450 (main) TRACE STABLE:455 - Sarmakand-2-55210: stable task started<br />22:24:30,451 (main) DEBUG UDP:320 - sockets will use interface 127.0.0.1<br />22:24:30,452 (main) DEBUG UDP:387 - socket information:<br />mcast_addr=228.8.8.8:45588, bind_addr=/127.0.0.1, ttl=0<br />sock: bound to 127.0.0.1:60943, receive buffer size=20000, send buffer size=20000<br />mcast_sock: bound to 127.0.0.1:45588, send buffer size=20000, receive buffer size=20000<br />22:24:30,453 (main) TRACE UDP:215 - joined /224.0.75.75:7500 on bridge100<br />22:24:30,453 (main) TRACE UDP:215 - joined /224.0.75.75:7500 on en1<br />22:24:30,454 (main) TRACE UDP:215 - joined /224.0.75.75:7500 on lo0<br /><br />-------------------------------------------------------------------<br />GMS: address=Sarmakand-2-55210, cluster=Hibernate Search Cluster, physical address=127.0.0.1:60943<br />-------------------------------------------------------------------<br />22:24:30,456 (main) TRACE UDP:1420 - Sarmakand-2-55210: sending msg to null, src=Sarmakand-2-55210, headers are PING: [PING: type=GET_MBRS_REQ, cluster=Hibernate Search Cluster], UDP: [cluster_name=Hibernate Search Cluster]<br />22:24:30,457 (main) TRACE UDP:1450 - Sarmakand-2-55210: looping back message [dst: <null>, src: Sarmakand-2-55210 (2 headers), size=51 bytes, flags=OOB|DONT_BUNDLE|INTERNAL]<br />22:24:30,458 (INT-1,Hibernate Search Cluster,Sarmakand-2-55210) TRACE UDP:1507 - Sarmakand-2-55210: received [dst: <null>, src: Sarmakand-2-55210 (2 headers), size=51 bytes, flags=OOB|DONT_BUNDLE|INTERNAL], headers are PING: [PING: type=GET_MBRS_REQ, cluster=Hibernate Search Cluster], UDP: [cluster_name=Hibernate Search Cluster]<br />22:24:30,458 (TransferQueueBundler,Hibernate Search Cluster,Sarmakand-2-55210) TRACE UDP:2358 - Sarmakand-2-55210: sending 1 msgs (140 bytes (0.22 of max_bundle_size) to 1 dests(s): [Hibernate Search Cluster]<br />22:24:30,459 (INT-1,Hibernate Search Cluster,Sarmakand-2-55210) TRACE PING:671 - Sarmakand-2-55210: received GET_MBRS_REQ from Sarmakand-2-55210, sending response Sarmakand-2-55210, view_id=, is_server=false, is_coord=false, logical_name=Sarmakand-2-55210, physical_addrs=(1) 127.0.0.1:60943<br />22:24:30,459 (TransferQueueBundler,Hibernate Search Cluster,Sarmakand-2-55210) ERROR UDP:2405 - JGRP000029: Sarmakand-2-55210: failed sending message to cluster (140 bytes): java.io.IOException: Can't assign requested address, headers: PING: [PING: type=GET_MBRS_REQ, cluster=Hibernate Search Cluster], UDP: [cluster_name=Hibernate Search Cluster]<br />22:24:30,459 (INT-1,Hibernate Search Cluster,Sarmakand-2-55210) TRACE UDP:1420 - Sarmakand-2-55210: sending msg to Sarmakand-2-55210, src=Sarmakand-2-55210, headers are PING: [PING: type=GET_MBRS_RSP], UDP: [cluster_name=Hibernate Search Cluster]<br />22:24:30,460 (INT-1,Hibernate Search Cluster,Sarmakand-2-55210) TRACE UDP:1450 - Sarmakand-2-55210: looping back message [dst: Sarmakand-2-55210, src: Sarmakand-2-55210 (2 headers), size=51 bytes, flags=OOB|DONT_BUNDLE|INTERNAL]<br />22:24:30,461 (INT-2,Hibernate Search Cluster,Sarmakand-2-55210) TRACE UDP:1507 - Sarmakand-2-55210: received [dst: Sarmakand-2-55210, src: Sarmakand-2-55210 (2 headers), size=51 bytes, flags=OOB|DONT_BUNDLE|INTERNAL], headers are PING: [PING: type=GET_MBRS_RSP], UDP: [cluster_name=Hibernate Search Cluster]<br />22:24:30,461 (INT-2,Hibernate Search Cluster,Sarmakand-2-55210) TRACE PING:485 - Sarmakand-2-55210: received GET_MBRS_RSP from Sarmakand-2-55210: Sarmakand-2-55210, view_id=, is_server=false, is_coord=false, logical_name=Sarmakand-2-55210, physical_addrs=(1) 127.0.0.1:60943<br />22:24:30,659 (main) TRACE PING:554 - Sarmakand-2-55210: discovery took 203 ms: responses: 1 total (0 servers (0 coord), 1 clients)<br />22:24:30,659 (main) TRACE GMS:88 - Sarmakand-2-55210: no initial members discovered: creating cluster as first member<br />22:24:30,660 (main) DEBUG NAKACK2:1302 - <br />[Sarmakand-2-55210 setDigest()]<br />existing digest: []<br />new digest: Sarmakand-2-55210: [0 (0)]<br />resulting digest: Sarmakand-2-55210: [0 (0)]<br />22:24:30,660 (main) DEBUG GMS:690 - Sarmakand-2-55210: installing view [Sarmakand-2-55210|0] (1) [Sarmakand-2-55210]<br />22:24:30,661 (main) DEBUG STABLE:202 - resuming message garbage collection<br />22:24:30,661 (main) DEBUG FD_SOCK:338 - Sarmakand-2-55210: VIEW_CHANGE received: [Sarmakand-2-55210]<br />22:24:30,662 (main) TRACE STABLE:415 - Sarmakand-2-55210: reset digest to Sarmakand-2-55210: [-1]<br />22:24:30,662 (main) TRACE UFC:519 - new membership: [Sarmakand-2-55210]<br />22:24:30,662 (main) TRACE MFC:519 - new membership: [Sarmakand-2-55210]<br />22:24:30,662 (main) DEBUG FLUSH:640 - Sarmakand-2-55210: installing view [Sarmakand-2-55210|0] (1) [Sarmakand-2-55210]<br />22:24:30,663 (main) DEBUG FLUSH:656 - Sarmakand-2-55210: received STOP_FLUSH, unblocking FLUSH.down() and sending UNBLOCK up<br />22:24:30,663 (main) TRACE STABLE:415 - Sarmakand-2-55210: reset digest to Sarmakand-2-55210: [-1]<br />22:24:30,663 (main) DEBUG STABLE:202 - resuming message garbage collection<br />22:24:30,664 (main) DEBUG GMS:307 - created cluster (first member). My view is [Sarmakand-2-55210|0], impl is org.jgroups.protocols.pbcast.CoordGmsImpl<br />22:24:30,664 (main) INFO DispatchMessageSender:262 - HSEARCH200020: Connected to cluster [ Hibernate Search Cluster ]. The local Address is Sarmakand-2-55210<br />22:24:30,704 (main) DEBUG DispatchMessageSender:154 - HSEARCH200011: Using JGroups channel having configuration '<config><br /> <UDP bind_addr="127.0.0.1" <br /> ignore_dont_bundle="true" <br /> oob_thread_pool_keep_alive_time="5000" <br /> timer_keep_alive_time="3000" <br /> external_port="0" <br /> oob_thread_pool_enabled="true" <br /> max_bundle_size="64000" <br /> diagnostics_ttl="8" <br /> physical_addr_max_fetch_attempts="3" <br /> internal_thread_pool_min_threads="2" <br /> receive_on_all_interfaces="false" <br /> mcast_port="45588" <br /> thread_pool_min_threads="2" <br /> thread_pool_keep_alive_time="5000" <br /> internal_thread_pool_queue_enabled="true" <br /> enable_diagnostics="true" <br /> thread_pool_max_threads="8" <br /> internal_thread_pool_queue_max_size="500" <br /> ucast_send_buf_size="20000" <br /> ip_ttl="0" <br /> oob_thread_pool_queue_max_size="100" <br /> enable_bundling="true" <br /> internal_thread_pool_rejection_policy="discard" <br /> thread_pool_queue_enabled="true" <br /> suppress_time_different_cluster_warnings="60000" <br /> diagnostics_port="7500" <br /> timer_rejection_policy="abort" <br /> oob_thread_pool_max_threads="8" <br /> wheel_size="200" <br /> disable_loopback="false" <br /> logical_addr_cache_max_size="500" <br /> ip_mcast="true" <br /> internal_thread_pool_enabled="true" <br /> tick_time="50" <br /> logical_addr_cache_expiration="120000" <br /> thread_pool_rejection_policy="discard" <br /> internal_thread_pool_keep_alive_time="30000" <br /> suppress_time_different_version_warnings="60000" <br /> enable_batching="true" <br /> oob_thread_pool_min_threads="1" <br /> who_has_cache_timeout="2000" <br /> port_range="50" <br /> internal_thread_pool_max_threads="4" <br /> stats="true" <br /> mcast_send_buf_size="20000" <br /> id="21" <br /> diagnostics_addr="224.0.75.75" <br /> mcast_recv_buf_size="20000" <br /> bind_port="0" tos="8" <br /> oob_thread_pool_rejection_policy="discard" <br /> loopback="true" <br /> oob_thread_pool_queue_enabled="false" <br /> name="UDP" <br /> enable_unicast_bundling="true" <br /> time_service_interval="500" <br /> log_discard_msgs_version="true" <br /> thread_pool_enabled="true" <br /> thread_naming_pattern="cl" <br /> ucast_recv_buf_size="20000" <br /> timer_max_threads="4" <br /> timer_queue_max_size="500" <br /> discard_incompatible_packets="true" <br /> ergonomics="true" <br /> bundler_capacity="20000" <br /> loopback_separate_thread="true" <br /> max_bundle_timeout="30" <br /> mcast_group_addr="228.8.8.8" <br /> bind_interface_str="" <br /> loopback_copy="false" <br /> timer_min_threads="2" <br /> log_discard_msgs="true" <br /> bundler_type="transfer-queue" <br /> thread_pool_queue_max_size="10000" <br /> timer_type="new3" /><br /> <PING id="6" <br /> always_send_physical_addr_with_discovery_request="false" <br /> return_entire_cache="false" <br /> num_initial_members="2" <br /> break_on_coord_rsp="true" <br /> use_disk_cache="false" <br /> force_sending_discovery_rsps="true" <br /> stagger_timeout="0" <br /> stats="true" <br /> name="PING" <br /> ergonomics="true" <br /> timeout="200" <br /> num_initial_srv_members="0" /><br /> <MERGE2 id="0" stats="true" <br /> merge_fast="true" <br /> name="MERGE2" <br /> inconsistent_view_threshold="1" <br /> min_interval="10000" <br /> ergonomics="true" <br /> merge_fast_delay="1000" <br /> max_interval="30000" /><br /> <FD_SOCK bind_addr="127.0.0.1" <br /> external_port="0" <br /> port_range="50" <br /> stats="true" <br /> suspect_msg_interval="5000" <br /> client_bind_port="0" <br /> ergonomics="true" <br /> num_tries="3" id="3" <br /> get_cache_timeout="1000" <br /> sock_conn_timeout="1000" <br /> bind_interface_str="" <br /> name="FD_SOCK" <br /> keep_alive="true" <br /> start_port="0" /><br /> <FD_ALL id="29" interval="8000" <br /> stats="true" <br /> name="FD_ALL" <br /> use_time_service="true" <br /> ergonomics="true" <br /> msg_counts_as_heartbeat="false" <br /> timeout="40000" <br /> timeout_check_interval="2000" /><br /> <VERIFY_SUSPECT id="13" <br /> bind_addr="127.0.0.1" <br /> bind_interface_str="" <br /> stats="true" <br /> name="VERIFY_SUSPECT" <br /> num_msgs="1" <br /> ergonomics="true" <br /> use_mcast_rsps="false" <br /> use_icmp="false" <br /> timeout="1500" /><br /> <BARRIER id="0" <br /> max_close_time="60000" <br /> stats="true" <br /> name="BARRIER" <br /> ergonomics="true" <br /> flush_timeout="5000" /><br /> <NAKACK2 <br /> use_mcast_xmit_req="false" <br /> use_mcast_xmit="false" <br /> suppress_time_non_member_warnings="60000" <br /> max_msg_batch_size="500" <br /> xmit_from_random_member="false" <br /> stats="true" <br /> xmit_table_max_compaction_time="30000" <br /> log_not_found_msgs="true" <br /> ergonomics="true" <br /> discard_delivered_msgs="true" <br /> print_stability_history_on_failed_xmit="false" <br /> id="57" <br /> become_server_queue_size="50" <br /> max_rebroadcast_timeout="2000" <br /> xmit_table_msgs_per_row="2000" <br /> xmit_table_num_rows="100" <br /> name="NAKACK2" <br /> log_discard_msgs="true" <br /> xmit_table_resize_factor="1.2" <br /> xmit_interval="500" /><br /> <UNICAST3 <br /> max_retransmit_time="60000" <br /> max_msg_batch_size="500" <br /> stats="true" <br /> xmit_table_max_compaction_time="60000" <br /> ack_batches_immediately="true" <br /> log_not_found_msgs="true" <br /> ergonomics="true" <br /> conn_close_timeout="60000" <br /> id="64" <br /> conn_expiry_timeout="0" <br /> xmit_table_msgs_per_row="2000" <br /> xmit_table_num_rows="100" <br /> name="UNICAST3" <br /> ack_threshold="5" <br /> xmit_table_resize_factor="1.2" <br /> xmit_interval="500" /><br /> <STABLE id="16" <br /> desired_avg_gossip="50000" <br /> max_bytes="4000000" <br /> stats="true" <br /> cap="0.1" <br /> name="STABLE" <br /> send_stable_msgs_to_coord_only="true" <br /> ergonomics="true" <br /> stability_delay="0" /><br /> <GMS print_local_addr="true" <br /> stats="true" <br /> max_bundling_time="50" <br /> log_collect_msgs="true" <br /> resume_task_timeout="20000" <br /> log_view_warnings="true" <br /> num_prev_views="10" <br /> ergonomics="true" <br /> use_flush_if_present="true" <br /> print_physical_addrs="true" <br /> merge_timeout="5000" <br /> id="14" <br /> membership_change_policy="org.jgroups.protocols.pbcast.GMS$DefaultMembershipPolicy@6debbf97" <br /> num_prev_mbrs="50" <br /> leave_timeout="1000" <br /> use_delta_views="true" <br /> install_view_locally_first="true" <br /> view_bundling="true" <br /> name="GMS" <br /> join_timeout="3000" <br /> handle_concurrent_startup="true" <br /> view_ack_collection_timeout="2000" <br /> max_join_attempts="0" /><br /> <UFC id="45" <br /> max_block_time="5000" <br /> max_credits="2000000" <br /> stats="true" <br /> ignore_synchronous_response="false" <br /> min_credits="800000" <br /> name="UFC" <br /> min_threshold="0.4" <br /> ergonomics="true" /><br /> <MFC id="44" <br /> max_block_time="5000" <br /> max_credits="2000000" <br /> stats="true" <br /> ignore_synchronous_response="false" <br /> min_credits="800000" <br /> name="MFC" <br /> min_threshold="0.4" <br /> ergonomics="true" /><br /> <FRAG2 id="5" frag_size="60000" <br /> stats="true" <br /> name="FRAG2" <br /> ergonomics="true" /><br /> <RSVP id="55" stats="true" <br /> name="RSVP" <br /> resend_interval="2000" <br /> throw_exception_on_timeout="true" <br /> ergonomics="true" <br /> ack_on_delivery="true" <br /> timeout="10000" /><br /> <STATE_TRANSFER id="17" <br /> stats="true" <br /> name="STATE_TRANSFER" <br /> ergonomics="true" /><br /> <FLUSH id="31" stats="true" <br /> start_flush_timeout="2000" <br /> name="FLUSH" <br /> enable_reconciliation="true" <br /> bypass="false" <br /> retry_timeout="2000" <br /> end_flush_timeout="2000" <br /> ergonomics="true" <br /> timeout="8000" /><br /></config>'<br />22:24:30,717 (main) DEBUG JGroupsBackendQueueProcessor:128 - HSEARCH200022: JGroups backend configured for index 'drinks' using block_for_ack 'true'<br />22:24:30,718 (main) DEBUG WorkspaceFactory:47 - Starting workspace for index drinks using an exclusive index strategy<br />22:24:30,723 (main) DEBUG DocumentBuilderIndexedEntity:184 - Field selection in projections is set to true for entity org.hibernate.search.backend.jgroups.impl.SyncJGroupsBackendTest$Drink.<br />22:24:30,724 (main) DEBUG JGroupsBackendQueueProcessor:128 - HSEARCH200022: JGroups backend configured for index 'stars' using block_for_ack 'false'<br />22:24:30,725 (main) DEBUG WorkspaceFactory:47 - Starting workspace for index stars using an exclusive index strategy<br />22:24:30,727 (main) DEBUG DocumentBuilderIndexedEntity:184 - Field selection in projections is set to true for entity org.hibernate.search.backend.jgroups.impl.SyncJGroupsBackendTest$Star.<br />22:24:30,729 (main) DEBUG JGroupsBackendQueueProcessor:128 - HSEARCH200022: JGroups backend configured for index 'dvds' using block_for_ack 'true'<br />22:24:30,731 (main) WARN BlackHoleBackendQueueProcessor:57 - HSEARCH000001: initialized "blackhole" backend. Index changes will be prepared but discarded!<br />22:24:30,734 (main) DEBUG DocumentBuilderIndexedEntity:184 - Field selection in projections is set to true for entity org.hibernate.search.backend.jgroups.impl.SyncJGroupsBackendTest$Dvd.<br />22:24:30,738 (main) DEBUG JGroupsBackendQueueProcessor:128 - HSEARCH200022: JGroups backend configured for index 'books' using block_for_ack 'false'<br />22:24:30,738 (main) DEBUG WorkspaceFactory:47 - Starting workspace for index books using an exclusive index strategy<br />22:24:30,740 (main) DEBUG DocumentBuilderIndexedEntity:184 - Field selection in projections is set to true for entity org.hibernate.search.backend.jgroups.impl.SyncJGroupsBackendTest$Book.<br />22:24:30,743 (main) TRACE SyncJGroupsBackendTest:116 - [PRESEND] Timestamp: 1395177870743037000<br />22:24:30,767 (main) TRACE JGroupsBackendQueueTask:69 - Preparing 1 Lucene works to be sent to master node.<br />22:24:30,768 (main) TRACE JGroupsBackendQueueTask:80 - Filtering: optimized Lucene works are not going to be sent to master node. There is 1 Lucene works after filtering.<br />22:24:30,774 (main) DEBUG AvroSerializationProvider:107 - HSEARCH000079: Serialization protocol version 1.0 initialized<br />22:24:31,088 (main) INFO DirectoryBasedIndexManager:163 - HSEARCH000168: Serialization service Avro SerializationProvider v1.0 being used for index 'dvds'<br />22:24:31,106 (main) TRACE MessageDispatcher:329 - real_dests=[]<br />22:24:31,107 (main) TRACE MessageDispatcher:333 - destination list is empty, won't send message<br />22:24:31,109 (main) TRACE JGroupsBackendQueueTask:98 - Lucene works have been sent from slave Sarmakand-2-55210 to master node.<br />22:24:31,109 (main) TRACE SyncJGroupsBackendTest:123 - [POSTSEND] Timestamp: 1395177871109375000 Diff: 366 ms.<br />22:24:31,110 (main) DEBUG AbstractWorkspaceImpl:107 - HSEARCH000108: Shutting down backend for IndexManager 'drinks'<br />22:24:31,110 (main) DEBUG AbstractWorkspaceImpl:107 - HSEARCH000108: Shutting down backend for IndexManager 'stars'<br />22:24:31,110 (main) DEBUG AbstractWorkspaceImpl:107 - HSEARCH000108: Shutting down backend for IndexManager 'books'<br />22:24:31,111 (main) INFO DispatchMessageSender:253 - HSEARCH200017: Disconnecting and closing JGroups Channel to cluster 'Hibernate Search Cluster'<br />22:24:31,114 (main) DEBUG UDP:556 - multicast socket closed<br />22:24:31,114 (multicast receiver,Hibernate Search Cluster,Sarmakand-2-55210) DEBUG UDP:688 - receiver socket is closed, exception=java.net.SocketException: Socket closed<br />22:24:31,114 (multicast receiver,Hibernate Search Cluster,Sarmakand-2-55210) DEBUG UDP:698 - multicast receiver thread terminated<br />22:24:31,115 (unicast receiver,Hibernate Search Cluster,Sarmakand-2-55210) DEBUG UDP:688 - receiver socket is closed, exception=java.net.SocketException: Socket closed<br />22:24:31,115 (unicast receiver,Hibernate Search Cluster,Sarmakand-2-55210) DEBUG UDP:698 - unicast receiver thread terminated<br />22:24:31,115 (main) DEBUG UDP:250 - closing sockets and stopping threads<br />22:24:31,418 (main) INFO BlackHoleBackendQueueProcessor:63 - HSEARCH000002: closed "blackhole" backend.<br />22:24:31,419 (main) DEBUG AbstractWorkspaceImpl:107 - HSEARCH000108: Shutting down backend for IndexManager 'drinks'<br />22:24:31,420 (main) DEBUG AbstractWorkspaceImpl:107 - HSEARCH000108: Shutting down backend for IndexManager 'stars'<br />22:24:31,420 (main) DEBUG AbstractWorkspaceImpl:107 - HSEARCH000108: Shutting down backend for IndexManager 'books'<br />22:24:31,421 (main) INFO DispatchMessageSender:253 - HSEARCH200017: Disconnecting and closing JGroups Channel to cluster 'Hibernate Search Cluster'<br />22:24:31,422 (main) DEBUG UDP:556 - multicast socket closed<br />22:24:31,423 (multicast receiver,Hibernate Search Cluster,Sarmakand-2-19291) DEBUG UDP:688 - receiver socket is closed, exception=java.net.SocketException: Socket closed<br />22:24:31,423 (multicast receiver,Hibernate Search Cluster,Sarmakand-2-19291) DEBUG UDP:698 - multicast receiver thread terminated<br />22:24:31,424 (unicast receiver,Hibernate Search Cluster,Sarmakand-2-19291) DEBUG UDP:688 - receiver socket is closed, exception=java.net.SocketException: Socket closed<br />22:24:31,424 (unicast receiver,Hibernate Search Cluster,Sarmakand-2-19291) DEBUG UDP:698 - unicast receiver thread terminated<br />22:24:31,424 (main) DEBUG UDP:250 - closing sockets and stopping threads<br />22:24:31,728 (main) DEBUG AbstractWorkspaceImpl:107 - HSEARCH000108: Shutting down backend for IndexManager 'dvds'<br /><br />junit.framework.AssertionFailedError: The backend didn't receive any message: something wrong with the test setup of network configuration<br /> at org.hibernate.search.backend.jgroups.impl.SyncJGroupsBackendTest.testSynchSerialisationWithTimeout(SyncJGroupsBackendTest.java:135)<br /> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)<br /> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)<br /> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)<br /> at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)<br /> at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)<br /> at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)<br /> at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)<br /> at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:43)<br /> at org.junit.rules.ExternalResource$1.evaluate(ExternalResource.java:43)<br /> at org.junit.runners.BlockJUnit4ClassRunner.runNotIgnored(BlockJUnit4ClassRunner.java:79)<br /> at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:71)<br /> at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:49)<br /> at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193)<br /> at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52)<br /> at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191)<br /> at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42)<br /> at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184)<br /> at org.junit.runners.ParentRunner.run(ParentRunner.java:236)<br /> at org.junit.runner.JUnitCore.run(JUnitCore.java:157)<br /> at com.intellij.junit4.JUnit4IdeaTestRunner.startRunnerWithArgs(JUnit4IdeaTestRunner.java:74)<br /> at com.intellij.rt.execution.junit.JUnitStarter.prepareStreamsAndStart(JUnitStarter.java:202)<br /> at com.intellij.rt.execution.junit.JUnitStarter.main(JUnitStarter.java:65)<br /> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)<br /> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)<br /> at com.intellij.rt.execution.application.AppMain.main(AppMain.java:120)<br />{noformat}<br /></span> <span class="diffaddedchars" style="background-color:#ddfade;">Personally, I am having a hard time making sense of this. I set breakpoints into backend and there is really no message received/processed there. I tried to change the xml config file against the default one, makes no difference. <br /></span>
</td>
</tr>
</table>
</td>
</tr>
<tr>
<td class="email-content-main mobile-expand " style="padding: 0px; border-collapse: collapse; border-left: 1px solid #ccc; border-right: 1px solid #ccc; border-top: 0; border-bottom: 0; padding: 0 15px 0 16px; background-color: #fff">
<table id="actions-pattern" cellspacing="0" cellpadding="0" border="0" width="100%" style="border-collapse: collapse; mso-table-lspace: 0pt; mso-table-rspace: 0pt; font-family: Arial, sans-serif; font-size: 14px; line-height: 20px; mso-line-height-rule: exactly; mso-text-raise: 1px">
<tr>
<td id="actions-pattern-container" valign="middle" style="padding: 0px; border-collapse: collapse; padding: 10px 0 10px 24px; vertical-align: middle; padding-left: 0">
<table align="left" style="border-collapse: collapse; mso-table-lspace: 0pt; mso-table-rspace: 0pt">
<tr>
<td class="actions-pattern-action-icon-container" style="padding: 0px; border-collapse: collapse; font-family: Arial, sans-serif; font-size: 14px; line-height: 20px; mso-line-height-rule: exactly; mso-text-raise: 0px; vertical-align: middle"> <a href="https://hibernate.atlassian.net/browse/HSEARCH-1558#add-comment" target="_blank" title="Add Comment" style="color: #3b73af; text-decoration: none"> <img class="actions-pattern-action-icon-image" src="cid:jira-generated-image-static-comment-icon-8ce3b504-d823-47fc-922c-458a902a1574" alt="Add Comment" title="Add Comment" height="16" width="16" border="0" style="vertical-align: middle" /> </a>
</td>
<td class="actions-pattern-action-text-container" style="padding: 0px; border-collapse: collapse; font-family: Arial, sans-serif; font-size: 14px; line-height: 20px; mso-line-height-rule: exactly; mso-text-raise: 4px; padding-left: 5px"> <a href="https://hibernate.atlassian.net/browse/HSEARCH-1558#add-comment" target="_blank" title="Add Comment" style="color: #3b73af; text-decoration: none">Add Comment</a>
</td>
</tr>
</table>
</td>
</tr>
</table>
</td>
</tr>
<!-- there needs to be content in the cell for it to render in some clients -->
<tr>
<td class="email-content-rounded-bottom mobile-expand" style="padding: 0px; border-collapse: collapse; color: #fff; padding: 0 15px 0 16px; height: 5px; line-height: 5px; background-color: #fff; border-top: 0; border-left: 1px solid #ccc; border-bottom: 1px solid #ccc; border-right: 1px solid #ccc; border-bottom-right-radius: 5px; border-bottom-left-radius: 5px; mso-line-height-rule: exactly">
</td>
</tr>
</table>
</td>
</tr>
<tr>
<td id="footer-pattern" style="padding: 0px; border-collapse: collapse; padding: 12px 20px">
<table id="footer-pattern-container" cellspacing="0" cellpadding="0" border="0" style="border-collapse: collapse; mso-table-lspace: 0pt; mso-table-rspace: 0pt">
<tr>
<td id="footer-pattern-text" class="mobile-resize-text" width="100%" style="padding: 0px; border-collapse: collapse; color: #999; font-size: 12px; line-height: 18px; font-family: Arial, sans-serif; mso-line-height-rule: exactly; mso-text-raise: 2px">
This message was sent by Atlassian JIRA <span id="footer-build-information">(v6.2-OD-10-004-WN#6253-<span title="0f638781a9eb90c00e546dda3da3aa1c24cb2738" data-commit-id="0f638781a9eb90c00e546dda3da3aa1c24cb2738}">sha1:0f63878</span>)</span>
</td>
<td id="footer-pattern-logo-desktop-container" valign="top" style="padding: 0px; border-collapse: collapse; padding-left: 20px; vertical-align: top">
<table style="border-collapse: collapse; mso-table-lspace: 0pt; mso-table-rspace: 0pt">
<tr>
<td id="footer-pattern-logo-desktop-padding" style="padding: 0px; border-collapse: collapse; padding-top: 3px"> <img id="footer-pattern-logo-desktop" src="cid:jira-generated-image-static-footer-desktop-logo-2d3da96f-3482-4473-a1f5-ccebaefdc114" alt="Atlassian logo" title="Atlassian logo" width="169" height="36" class="image_fix" />
</td>
</tr>
</table>
</td>
</tr>
</table>
</td>
</tr>
</table>
</body>
</html>