[
https://issues.apache.org/jira/browse/MESOS-9045?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=17862647#comment-17862647
]
Benjamin Mahler commented on MESOS-9045:
----------------------------------------
Very different case, but also a segfault:
{noformat}
[----------] 2 tests from LogZooKeeperTest
I0703 02:50:24.968773 185149 zookeeper.cpp:82] Using Java classpath:
-Djava.class.path=/tmp/SRC/build/mesos-1.12.0/_build/sub/3rdparty/zookeeper-3.4.8/zookeeper-3.4.8.jar:/tmp/SRC/build/mesos-1.12.0/_build/sub/3rdparty/zookeeper-3.4.8/lib/log4j-1.2.16.jar:/tmp/SRC/build/mesos-1.12.0/_build/sub/3rdparty/zookeeper-3.4.8/lib/jline-0.9.94.jar:/tmp/SRC/build/mesos-1.12.0/_build/sub/3rdparty/zookeeper-3.4.8/lib/slf4j-log4j12-1.6.1.jar:/tmp/SRC/build/mesos-1.12.0/_build/sub/3rdparty/zookeeper-3.4.8/lib/netty-3.7.0.Final.jar:/tmp/SRC/build/mesos-1.12.0/_build/sub/3rdparty/zookeeper-3.4.8/lib/slf4j-api-1.6.1.jar
[ RUN ] LogZooKeeperTest.WriteRead
I0703 02:50:25.058761 185149 jvm.cpp:590] Looking up method
<init>(Ljava/lang/String;)V
I0703 02:50:25.059170 185149 jvm.cpp:590] Looking up method deleteOnExit()V
I0703 02:50:25.060112 185149 jvm.cpp:590] Looking up method
<init>(Ljava/io/File;Ljava/io/File;)V
log4j:WARN No appenders could be found for logger
(org.apache.zookeeper.server.persistence.FileTxnSnapLog).
log4j:WARN Please initialize the log4j system properly.
log4j:WARN See http://logging.apache.org/log4j/1.2/faq.html#noconfig for more
info.
I0703 02:50:25.206512 185149 jvm.cpp:590] Looking up method <init>()V
I0703 02:50:25.207417 185149 jvm.cpp:590] Looking up method
<init>(Lorg/apache/zookeeper/server/persistence/FileTxnSnapLog;Lorg/apache/zookeeper/server/ZooKeeperServer$DataTreeBuilder;)V
*** Aborted at 1719975025 (unix time) try "date -d @1719975025" if you are
using GNU date ***
PC: @ 0x7f5edf914ccd OopStorage::Block::release_entries()
*** SIGSEGV (@0x238) received by PID 185149 (TID 0x7f5f5a15cb40) from PID 568;
stack trace: ***
@ 0x7f5edf923929 os::Linux::chained_handler()
@ 0x7f5edf92963b JVM_handle_linux_signal
@ 0x7f5edf91c1dc signalHandler()
@ 0x7f5f5b7af420 (unknown)
@ 0x7f5edf914ccd OopStorage::Block::release_entries()
@ 0x7f5edf914f26 OopStorage::release()
@ 0x7f5edf617b21 jni_DeleteGlobalRef
@ 0x7f5f6aeffaf2 JNIEnv_::DeleteGlobalRef()
@ 0x7f5f6aefdc3a Jvm::deleteGlobalRef()
@ 0x55f0ed05a2ea Jvm::Object::~Object()
@ 0x55f0ed05f110
org::apache::zookeeper::server::ZooKeeperServer::DataTreeBuilder::~DataTreeBuilder()
@ 0x55f0ed061a14
org::apache::zookeeper::server::ZooKeeperServer::BasicDataTreeBuilder::~BasicDataTreeBuilder()
@ 0x55f0ed05da2f
mesos::internal::tests::ZooKeeperTestServer::ZooKeeperTestServer()
@ 0x55f0eba05ef6 mesos::internal::tests::ZooKeeperTest::ZooKeeperTest()
@ 0x55f0eba0823f
mesos::internal::tests::LogZooKeeperTest::LogZooKeeperTest()
@ 0x55f0eba08350
mesos::internal::tests::LogZooKeeperTest_WriteRead_Test::LogZooKeeperTest_WriteRead_Test()
@ 0x55f0eba73252 testing::internal::TestFactoryImpl<>::CreateTest()
@ 0x55f0ed0a42bc
testing::internal::HandleSehExceptionsInMethodIfSupported<>()
@ 0x55f0ed09d88d
testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x55f0ed079ac5 testing::TestInfo::Run()
@ 0x55f0ed07a1cd testing::TestCase::Run()
@ 0x55f0ed081567 testing::internal::UnitTestImpl::RunAllTests()
@ 0x55f0ed0a54ea
testing::internal::HandleSehExceptionsInMethodIfSupported<>()
@ 0x55f0ed09e3f3
testing::internal::HandleExceptionsInMethodIfSupported<>()
@ 0x55f0ed08007f testing::UnitTest::Run()
@ 0x55f0eba90e05 RUN_ALL_TESTS()
@ 0x55f0eba907cc main
@ 0x7f5f5b5cd083 __libc_start_main
@ 0x55f0eaad675e _start
{noformat}
> LogZooKeeperTest.WriteRead can segfault
> ---------------------------------------
>
> Key: MESOS-9045
> URL: https://issues.apache.org/jira/browse/MESOS-9045
> Project: Mesos
> Issue Type: Bug
> Affects Versions: 1.5.1
> Environment: macOS
> Reporter: Jan Schlicht
> Priority: Major
> Labels: flaky-test, segfault
>
> The following segfault occured when testing the {{1.5.x}} branch (SHA
> {{64341865d}}) on macOS:
> {noformat}
> [ RUN ] LogZooKeeperTest.WriteRead
> I0702 00:49:46.259831 2560127808 jvm.cpp:590] Looking up method
> <init>(Ljava/lang/String;)V
> I0702 00:49:46.260002 2560127808 jvm.cpp:590] Looking up method
> deleteOnExit()V
> I0702 00:49:46.260550 2560127808 jvm.cpp:590] Looking up method
> <init>(Ljava/io/File;Ljava/io/File;)V
> log4j:WARN No appenders could be found for logger
> (org.apache.zookeeper.server.persistence.FileTxnSnapLog).
> log4j:WARN Please initialize the log4j system properly.
> log4j:WARN See http://logging.apache.org/log4j/1.2/faq.html#noconfig for more
> info.
> I0702 00:49:46.305560 2560127808 jvm.cpp:590] Looking up method <init>()V
> I0702 00:49:46.306149 2560127808 jvm.cpp:590] Looking up method
> <init>(Lorg/apache/zookeeper/server/persistence/FileTxnSnapLog;Lorg/apache/zookeeper/server/ZooKeeperServer$DataTreeBuilder;)V
> I0702 00:49:46.333307 2560127808 jvm.cpp:590] Looking up method <init>()V
> I0702 00:49:46.343977 2560127808 jvm.cpp:590] Looking up method <init>(I)V
> I0702 00:49:46.344200 2560127808 jvm.cpp:590] Looking up method
> configure(Ljava/net/InetSocketAddress;I)V
> I0702 00:49:46.357642 2560127808 jvm.cpp:590] Looking up method
> startup(Lorg/apache/zookeeper/server/ZooKeeperServer;)V
> I0702 00:49:46.437831 2560127808 jvm.cpp:590] Looking up method
> getClientPort()I
> I0702 00:49:46.437893 2560127808 zookeeper_test_server.cpp:156] Started
> ZooKeeperTestServer on port 54057
> I0702 00:49:46.438153 2560127808 log_tests.cpp:2468] Using temporary
> directory
> '/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/LogZooKeeperTest_WriteRead_AKZArL'
> I0702 00:49:46.440680 2560127808 leveldb.cpp:174] Opened db in 2.415822ms
> I0702 00:49:46.441301 2560127808 leveldb.cpp:181] Compacted db in 584251ns
> I0702 00:49:46.441349 2560127808 leveldb.cpp:196] Created db iterator in
> 20482ns
> I0702 00:49:46.441380 2560127808 leveldb.cpp:202] Seeked to beginning of db
> in 14577ns
> I0702 00:49:46.441407 2560127808 leveldb.cpp:277] Iterated through 0 keys in
> the db in 16622ns
> I0702 00:49:46.441447 2560127808 replica.cpp:795] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0702 00:49:46.441737 207974400 leveldb.cpp:310] Persisting metadata (8
> bytes) to leveldb took 157037ns
> I0702 00:49:46.441764 207974400 replica.cpp:322] Persisted replica status to
> VOTING
> I0702 00:49:46.443361 2560127808 leveldb.cpp:174] Opened db in 1.305425ms
> I0702 00:49:46.443821 2560127808 leveldb.cpp:181] Compacted db in 448477ns
> I0702 00:49:46.443871 2560127808 leveldb.cpp:196] Created db iterator in
> 12681ns
> I0702 00:49:46.443889 2560127808 leveldb.cpp:202] Seeked to beginning of db
> in 13291ns
> I0702 00:49:46.443914 2560127808 leveldb.cpp:277] Iterated through 0 keys in
> the db in 14460ns
> I0702 00:49:46.443944 2560127808 replica.cpp:795] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> I0702 00:49:46.444277 206901248 leveldb.cpp:310] Persisting metadata (8
> bytes) to leveldb took 234740ns
> I0702 00:49:46.444317 206901248 replica.cpp:322] Persisted replica status to
> VOTING
> I0702 00:49:46.445854 2560127808 leveldb.cpp:174] Opened db in 1.253613ms
> I0702 00:49:46.446967 2560127808 leveldb.cpp:181] Compacted db in 1.096521ms
> I0702 00:49:46.447022 2560127808 leveldb.cpp:196] Created db iterator in
> 14312ns
> I0702 00:49:46.447048 2560127808 leveldb.cpp:202] Seeked to beginning of db
> in 16620ns
> I0702 00:49:46.447077 2560127808 leveldb.cpp:277] Iterated through 1 keys in
> the db in 21267ns
> I0702 00:49:46.447113 2560127808 replica.cpp:795] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> 2018-07-02 00:49:46,447:85946(0x70000c6da000):ZOO_INFO@log_env@753: Client
> environment:zookeeper.version=zookeeper C client 3.4.8
> 2018-07-02 00:49:46,447:85946(0x70000c6da000):ZOO_INFO@log_env@757: Client
> environment:host.name=Jenkinss-Mac-mini.local
> 2018-07-02 00:49:46,447:85946(0x70000c657000):ZOO_INFO@log_env@753: Client
> environment:zookeeper.version=zookeeper C client 3.4.8
> 2018-07-02 00:49:46,447:85946(0x70000c657000):ZOO_INFO@log_env@757: Client
> environment:host.name=Jenkinss-Mac-mini.local
> 2018-07-02 00:49:46,447:85946(0x70000c6da000):ZOO_INFO@log_env@764: Client
> environment:os.name=Darwin
> 2018-07-02 00:49:46,447:85946(0x70000c6da000):ZOO_INFO@log_env@765: Client
> environment:os.arch=17.4.0
> 2018-07-02 00:49:46,447:85946(0x70000c657000):ZOO_INFO@log_env@764: Client
> environment:os.name=Darwin
> I0702 00:49:46.447453 206901248 log.cpp:108] Attempting to join replica to
> ZooKeeper group
> 2018-07-02 00:49:46,447:85946(0x70000c6da000):ZOO_INFO@log_env@766: Client
> environment:os.version=Darwin Kernel Version 17.4.0: Sun Dec 17 09:19:54 PST
> 2017; root:xnu-4570.41.2~1/RELEASE_X86_64
> 2018-07-02 00:49:46,447:85946(0x70000c657000):ZOO_INFO@log_env@765: Client
> environment:os.arch=17.4.0
> 2018-07-02 00:49:46,447:85946(0x70000c6da000):ZOO_INFO@log_env@774: Client
> environment:user.name=jenkins
> 2018-07-02 00:49:46,447:85946(0x70000c657000):ZOO_INFO@log_env@766: Client
> environment:os.version=Darwin Kernel Version 17.4.0: Sun Dec 17 09:19:54 PST
> 2017; root:xnu-4570.41.2~1/RELEASE_X86_64
> 2018-07-02 00:49:46,447:85946(0x70000c6da000):ZOO_INFO@log_env@782: Client
> environment:user.home=/Users/jenkins
> 2018-07-02 00:49:46,447:85946(0x70000c657000):ZOO_INFO@log_env@774: Client
> environment:user.name=jenkins
> 2018-07-02 00:49:46,447:85946(0x70000c6da000):ZOO_INFO@log_env@794: Client
> environment:user.dir=/private/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/LogZooKeeperTest_WriteRead_AKZArL
> 2018-07-02 00:49:46,447:85946(0x70000c657000):ZOO_INFO@log_env@782: Client
> environment:user.home=/Users/jenkins
> I0702 00:49:46.447716 207437824 recover.cpp:437] Starting replica recovery
> 2018-07-02 00:49:46,447:85946(0x70000c6da000):ZOO_INFO@zookeeper_init@827:
> Initiating client connection, host=127.0.0.1:54057 sessionTimeout=10000
> watcher=0x10ff76570 sessionId=0 sessionPasswd=<null> context=0x7f9b5cbf6380
> flags=0
> 2018-07-02 00:49:46,447:85946(0x70000c657000):ZOO_INFO@log_env@794: Client
> environment:user.dir=/private/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/LogZooKeeperTest_WriteRead_AKZArL
> I0702 00:49:46.447913 210657280 recover.cpp:468] Replica is in VOTING status
> 2018-07-02 00:49:46,447:85946(0x70000c657000):ZOO_INFO@zookeeper_init@827:
> Initiating client connection, host=127.0.0.1:54057 sessionTimeout=10000
> watcher=0x10ff76570 sessionId=0 sessionPasswd=<null> context=0x7f9b5cbf6ad0
> flags=0
> I0702 00:49:46.448031 210657280 recover.cpp:447] Recover process terminated
> 2018-07-02 00:49:46,448:85946(0x70000ddae000):ZOO_INFO@check_events@1764:
> initiated connection to server [127.0.0.1:54057]
> 2018-07-02 00:49:46,448:85946(0x70000dd2b000):ZOO_INFO@check_events@1764:
> initiated connection to server [127.0.0.1:54057]
> I0702 00:49:46.449156 2560127808 leveldb.cpp:174] Opened db in 1.729251ms
> I0702 00:49:46.450155 2560127808 leveldb.cpp:181] Compacted db in 975056ns
> I0702 00:49:46.450191 2560127808 leveldb.cpp:196] Created db iterator in
> 15151ns
> I0702 00:49:46.450220 2560127808 leveldb.cpp:202] Seeked to beginning of db
> in 23952ns
> I0702 00:49:46.450266 2560127808 leveldb.cpp:277] Iterated through 1 keys in
> the db in 33355ns
> I0702 00:49:46.450306 2560127808 replica.cpp:795] Replica recovered with log
> positions 0 -> 0 with 1 holes and 0 unlearned
> 2018-07-02 00:49:46,450:85946(0x70000c551000):ZOO_INFO@log_env@753: Client
> environment:zookeeper.version=zookeeper C client 3.4.8
> 2018-07-02 00:49:46,450:85946(0x70000c551000):ZOO_INFO@log_env@757: Client
> environment:host.name=Jenkinss-Mac-mini.local
> 2018-07-02 00:49:46,450:85946(0x70000c551000):ZOO_INFO@log_env@764: Client
> environment:os.name=Darwin
> 2018-07-02 00:49:46,450:85946(0x70000c551000):ZOO_INFO@log_env@765: Client
> environment:os.arch=17.4.0
> 2018-07-02 00:49:46,450:85946(0x70000c551000):ZOO_INFO@log_env@766: Client
> environment:os.version=Darwin Kernel Version 17.4.0: Sun Dec 17 09:19:54 PST
> 2017; root:xnu-4570.41.2~1/RELEASE_X86_64
> 2018-07-02 00:49:46,450:85946(0x70000c551000):ZOO_INFO@log_env@774: Client
> environment:user.name=jenkins
> 2018-07-02 00:49:46,450:85946(0x70000c551000):ZOO_INFO@log_env@782: Client
> environment:user.home=/Users/jenkins
> 2018-07-02 00:49:46,450:85946(0x70000c551000):ZOO_INFO@log_env@794: Client
> environment:user.dir=/private/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/LogZooKeeperTest_WriteRead_AKZArL
> 2018-07-02 00:49:46,450:85946(0x70000c551000):ZOO_INFO@zookeeper_init@827:
> Initiating client connection, host=127.0.0.1:54057 sessionTimeout=10000
> watcher=0x10ff76570 sessionId=0 sessionPasswd=<null> context=0x7f9b5d1d5b30
> flags=0
> I0702 00:49:46.450652 209047552 log.cpp:108] Attempting to join replica to
> ZooKeeper group
> 2018-07-02 00:49:46,450:85946(0x70000c7e0000):ZOO_INFO@log_env@753: Client
> environment:zookeeper.version=zookeeper C client 3.4.8
> 2018-07-02 00:49:46,450:85946(0x70000c7e0000):ZOO_INFO@log_env@757: Client
> environment:host.name=Jenkinss-Mac-mini.local
> 2018-07-02 00:49:46,450:85946(0x70000c7e0000):ZOO_INFO@log_env@764: Client
> environment:os.name=Darwin
> 2018-07-02 00:49:46,450:85946(0x70000c7e0000):ZOO_INFO@log_env@765: Client
> environment:os.arch=17.4.0
> 2018-07-02 00:49:46,450:85946(0x70000c7e0000):ZOO_INFO@log_env@766: Client
> environment:os.version=Darwin Kernel Version 17.4.0: Sun Dec 17 09:19:54 PST
> 2017; root:xnu-4570.41.2~1/RELEASE_X86_64
> 2018-07-02 00:49:46,450:85946(0x70000c7e0000):ZOO_INFO@log_env@774: Client
> environment:user.name=jenkins
> 2018-07-02 00:49:46,450:85946(0x70000c7e0000):ZOO_INFO@log_env@782: Client
> environment:user.home=/Users/jenkins
> 2018-07-02 00:49:46,450:85946(0x70000c7e0000):ZOO_INFO@log_env@794: Client
> environment:user.dir=/private/var/folders/6w/rw03zh013y38ys6cyn8qppf80000gn/T/LogZooKeeperTest_WriteRead_AKZArL
> 2018-07-02 00:49:46,450:85946(0x70000c7e0000):ZOO_INFO@zookeeper_init@827:
> Initiating client connection, host=127.0.0.1:54057 sessionTimeout=10000
> watcher=0x10ff76570 sessionId=0 sessionPasswd=<null> context=0x7f9b5d173360
> flags=0
> I0702 00:49:46.450794 207437824 recover.cpp:437] Starting replica recovery
> I0702 00:49:46.450980 209047552 recover.cpp:468] Replica is in VOTING status
> I0702 00:49:46.451055 209047552 recover.cpp:447] Recover process terminated
> I0702 00:49:46.451346 207437824 log.cpp:554] Attempting to start the writer
> 2018-07-02 00:49:46,451:85946(0x70000df37000):ZOO_INFO@check_events@1764:
> initiated connection to server [127.0.0.1:54057]
> 2018-07-02 00:49:46,451:85946(0x70000e03d000):ZOO_INFO@check_events@1764:
> initiated connection to server [127.0.0.1:54057]
> 2018-07-02 00:49:46,519:85946(0x70000ddae000):ZOO_INFO@check_events@1811:
> session establishment complete on server [127.0.0.1:54057],
> sessionId=0x16459f80efb0000, negotiated timeout=10000
> I0702 00:49:46.520006 208510976 group.cpp:341] Group process
> (zookeeper-group(2)@10.0.49.4:53277) connected to ZooKeeper
> I0702 00:49:46.520083 208510976 group.cpp:831] Syncing group operations:
> queue size (joins, cancels, datas) = (1, 0, 0)
> I0702 00:49:46.520105 208510976 group.cpp:419] Trying to create path '/log'
> in ZooKeeper
> 2018-07-02 00:49:46,520:85946(0x70000dd2b000):ZOO_INFO@check_events@1811:
> session establishment complete on server [127.0.0.1:54057],
> sessionId=0x16459f80efb0001, negotiated timeout=10000
> 2018-07-02 00:49:46,520:85946(0x70000df37000):ZOO_INFO@check_events@1811:
> session establishment complete on server [127.0.0.1:54057],
> sessionId=0x16459f80efb0002, negotiated timeout=10000
> I0702 00:49:46.520737 209584128 group.cpp:341] Group process
> (zookeeper-group(1)@10.0.49.4:53277) connected to ZooKeeper
> I0702 00:49:46.520766 209584128 group.cpp:831] Syncing group operations:
> queue size (joins, cancels, datas) = (0, 0, 0)
> I0702 00:49:46.520781 209584128 group.cpp:419] Trying to create path '/log'
> in ZooKeeper
> 2018-07-02 00:49:46,520:85946(0x70000e03d000):ZOO_INFO@check_events@1811:
> session establishment complete on server [127.0.0.1:54057],
> sessionId=0x16459f80efb0003, negotiated timeout=10000
> I0702 00:49:46.521023 207974400 group.cpp:341] Group process
> (zookeeper-group(3)@10.0.49.4:53277) connected to ZooKeeper
> I0702 00:49:46.521049 207974400 group.cpp:831] Syncing group operations:
> queue size (joins, cancels, datas) = (0, 0, 0)
> I0702 00:49:46.521062 207974400 group.cpp:419] Trying to create path '/log'
> in ZooKeeper
> I0702 00:49:46.521158 209047552 group.cpp:341] Group process
> (zookeeper-group(4)@10.0.49.4:53277) connected to ZooKeeper
> I0702 00:49:46.521184 209047552 group.cpp:831] Syncing group operations:
> queue size (joins, cancels, datas) = (1, 0, 0)
> I0702 00:49:46.521198 209047552 group.cpp:419] Trying to create path '/log'
> in ZooKeeper
> I0702 00:49:46.543576 210120704 network.hpp:436] ZooKeeper group memberships
> changed
> I0702 00:49:46.543627 206901248 network.hpp:436] ZooKeeper group memberships
> changed
> I0702 00:49:46.543758 210657280 group.cpp:700] Trying to get
> '/log/0000000000' in ZooKeeper
> I0702 00:49:46.543805 207437824 group.cpp:700] Trying to get
> '/log/0000000000' in ZooKeeper
> I0702 00:49:46.545581 207437824 group.cpp:700] Trying to get
> '/log/0000000001' in ZooKeeper
> I0702 00:49:46.545801 210657280 group.cpp:700] Trying to get
> '/log/0000000001' in ZooKeeper
> I0702 00:49:46.546525 206901248 network.hpp:484] ZooKeeper group PIDs: {
> log-replica(155)@10.0.49.4:53277, log-replica(156)@10.0.49.4:53277 }
> I0702 00:49:46.546659 209584128 network.hpp:484] ZooKeeper group PIDs: {
> log-replica(155)@10.0.49.4:53277, log-replica(156)@10.0.49.4:53277 }
> I0702 00:49:46.546860 208510976 replica.cpp:497] Replica received implicit
> promise request from __req_res__(1002)@10.0.49.4:53277 with proposal 1
> I0702 00:49:46.546870 209047552 replica.cpp:497] Replica received implicit
> promise request from __req_res__(1003)@10.0.49.4:53277 with proposal 1
> I0702 00:49:46.547183 209047552 leveldb.cpp:310] Persisting metadata (8
> bytes) to leveldb took 297811ns
> I0702 00:49:46.547192 208510976 leveldb.cpp:310] Persisting metadata (8
> bytes) to leveldb took 307351ns
> I0702 00:49:46.547211 209047552 replica.cpp:344] Persisted promised to 1
> I0702 00:49:46.547217 208510976 replica.cpp:344] Persisted promised to 1
> I0702 00:49:46.547399 210657280 coordinator.cpp:238] Coordinator attempting
> to fill missing positions
> I0702 00:49:46.547942 209047552 replica.cpp:391] Replica received explicit
> promise request from __req_res__(1004)@10.0.49.4:53277 for position 0 with
> proposal 2
> I0702 00:49:46.547948 206901248 replica.cpp:391] Replica received explicit
> promise request from __req_res__(1005)@10.0.49.4:53277 for position 0 with
> proposal 2
> I0702 00:49:46.548156 209047552 leveldb.cpp:347] Persisting action (8 bytes)
> to leveldb took 189841ns
> I0702 00:49:46.548157 206901248 leveldb.cpp:347] Persisting action (8 bytes)
> to leveldb took 188406ns
> I0702 00:49:46.548193 209047552 replica.cpp:712] Persisted action NOP at
> position 0
> I0702 00:49:46.548197 206901248 replica.cpp:712] Persisted action NOP at
> position 0
> I0702 00:49:46.548602 208510976 replica.cpp:541] Replica received write
> request for position 0 from __req_res__(1006)@10.0.49.4:53277
> I0702 00:49:46.548624 210120704 replica.cpp:541] Replica received write
> request for position 0 from __req_res__(1007)@10.0.49.4:53277
> I0702 00:49:46.548645 208510976 leveldb.cpp:460] Reading position from
> leveldb took 27414ns
> I0702 00:49:46.548668 210120704 leveldb.cpp:460] Reading position from
> leveldb took 28518ns
> I0702 00:49:46.548866 208510976 leveldb.cpp:347] Persisting action (14 bytes)
> to leveldb took 200010ns
> I0702 00:49:46.548897 208510976 replica.cpp:712] Persisted action NOP at
> position 0
> I0702 00:49:46.548909 210120704 leveldb.cpp:347] Persisting action (14 bytes)
> to leveldb took 220008ns
> I0702 00:49:46.548936 210120704 replica.cpp:712] Persisted action NOP at
> position 0
> I0702 00:49:46.549126 210120704 replica.cpp:695] Replica received learned
> notice for position 0 from log-network(50)@10.0.49.4:53277
> I0702 00:49:46.549125 208510976 replica.cpp:695] Replica received learned
> notice for position 0 from log-network(50)@10.0.49.4:53277
> I0702 00:49:46.549291 210120704 leveldb.cpp:347] Persisting action (16 bytes)
> to leveldb took 148012ns
> I0702 00:49:46.549293 208510976 leveldb.cpp:347] Persisting action (16 bytes)
> to leveldb took 118005ns
> I0702 00:49:46.549314 210120704 replica.cpp:712] Persisted action NOP at
> position 0
> I0702 00:49:46.549320 208510976 replica.cpp:712] Persisted action NOP at
> position 0
> I0702 00:49:46.549448 209047552 log.cpp:570] Writer started with ending
> position 0
> I0702 00:49:46.549610 207974400 log.cpp:578] Attempting to append 11 bytes to
> the log
> I0702 00:49:46.549646 206901248 coordinator.cpp:348] Coordinator attempting
> to write APPEND action at position 1
> I0702 00:49:46.549824 209047552 replica.cpp:541] Replica received write
> request for position 1 from __req_res__(1008)@10.0.49.4:53277
> I0702 00:49:46.549840 208510976 replica.cpp:541] Replica received write
> request for position 1 from __req_res__(1009)@10.0.49.4:53277
> I0702 00:49:46.550011 208510976 leveldb.cpp:347] Persisting action (27 bytes)
> to leveldb took 159855ns
> I0702 00:49:46.550034 208510976 replica.cpp:712] Persisted action APPEND at
> position 1
> I0702 00:49:46.550019 209047552 leveldb.cpp:347] Persisting action (27 bytes)
> to leveldb took 179891ns
> I0702 00:49:46.550070 209047552 replica.cpp:712] Persisted action APPEND at
> position 1
> I0702 00:49:46.550231 208510976 replica.cpp:695] Replica received learned
> notice for position 1 from log-network(50)@10.0.49.4:53277
> I0702 00:49:46.550233 209047552 replica.cpp:695] Replica received learned
> notice for position 1 from log-network(50)@10.0.49.4:53277
> I0702 00:49:46.550401 208510976 leveldb.cpp:347] Persisting action (29 bytes)
> to leveldb took 155963ns
> I0702 00:49:46.550426 208510976 replica.cpp:712] Persisted action APPEND at
> position 1
> I0702 00:49:46.550417 209047552 leveldb.cpp:347] Persisting action (29 bytes)
> to leveldb took 161088ns
> I0702 00:49:46.550447 209047552 replica.cpp:712] Persisted action APPEND at
> position 1
> I0702 00:49:46.550748 210120704 leveldb.cpp:460] Reading position from
> leveldb took 46136ns
> 2018-07-02 00:49:46,551:85946(0x70000c657000):ZOO_INFO@zookeeper_close@2562:
> Closing zookeeper sessionId=0x16459f80efb0003 to [127.0.0.1:54057]
> 2018-07-02 00:49:46,551:85946(0x70000c657000):ZOO_INFO@zookeeper_close@2562:
> Closing zookeeper sessionId=0x16459f80efb0002 to [127.0.0.1:54057]
> 2018-07-02 00:49:46,552:85946(0x7fff98987340):ZOO_INFO@zookeeper_close@2562:
> Closing zookeeper sessionId=0x16459f80efb0000 to [127.0.0.1:54057]
> 2018-07-02 00:49:46,552:85946(0x7fff98987340):ZOO_INFO@zookeeper_close@2562:
> Closing zookeeper sessionId=0x16459f80efb0001 to [127.0.0.1:54057]
> F0702 00:49:46.554960 206901248 semaphore.hpp:53] Check failed: 0 ==
> semaphore_wait(semaphore) (0 vs. 14)
> *** Check failure stack trace: ***
> I0702 00:49:46.555055 2560127808 zookeeper_test_server.cpp:116] Shutting down
> ZooKeeperTestServer on port 54057
> I0702 00:49:46.555161 2560127808 jvm.cpp:590] Looking up method shutdown()V
> @ 0x11150657a google::LogMessage::Fail()
> @ 0x11150422c google::LogMessage::SendToLog()
> @ 0x111504fb9 google::LogMessage::Flush()
> @ 0x11150e239 google::LogMessageFatal::~LogMessageFatal()
> @ 0x111506a65 google::LogMessageFatal::~LogMessageFatal()
> @ 0x1112c262d KernelSemaphore::wait()
> @ 0x1112c2449 DecomissionableKernelSemaphore::wait()
> @ 0x111262939 process::RunQueue::wait()
> @ 0x111262808 process::ProcessManager::dequeue()
> @ 0x11135a99c
> process::ProcessManager::init_threads()::$_5::operator()()
> @ 0x11135a606
> _ZNSt3__114__thread_proxyINS_5tupleIJNS_10unique_ptrINS_15__thread_structENS_14default_deleteIS3_EEEEZN7process14ProcessManager12init_threadsEvE3$_5EEEEEPvSB_
> @ 0x7fff5fbbd6c1 _pthread_body
> @ 0x7fff5fbbd56d _pthread_start
> @ 0x7fff5fbbcc5d thread_start
> {noformat}
--
This message was sent by Atlassian Jira
(v8.20.10#820010)
