Closed vinniefalco closed 10 years ago
(gdb) bt
from /usr/lib/libstdc++.so.6
include_nonmatching_leaf=true, partialOk=false) at ripple_SHAMap.cpp:97
type=@0x7fffff1ef184) at ripple_SHAMap.cpp:472
at ripple_SHAMap.cpp:463
uHash=@0x7fffff1ef240) at Ledger.cpp:1247
uHash=@0x7fffff1ef940) at LedgerEntrySet.cpp:987
uHash=@0x7fffff1ef940, uEnd=@0x7fffff1ef920) at LedgerEntrySet.cpp:1006
bOpenLedger=true, bPassive=false, bSell=true, uBookBase=@0x7fffff1f2ed0,
uTakerAccountID=@0x8088b081c, sleTakerAccount=@0x7fffff1f2f80,
saTakerPays=@0x7fffff1f2e30, saTakerGets=@0x7fffff1f2e80,
saTakerPaid=@0x7fffff1f2de0, saTakerGot=@0x7fffff1f2d90, bUnfunded=@0x7fffff1f2f7f)
at OfferCreateTransactor.cpp:141
at OfferCreateTransactor.cpp:523
txn=@0x804df3220, params=17, didApply=@0x7fffff1f8fff) at TransactionEngine.cpp:99
txn=@0x804b16ee0, params=17, didApply=@0x7fffff1f8fff) at LedgerMaster.cpp:165
trans=@0x7fffff1f99e0, bAdmin=false, callback=@0x7fffff1f99c0) at NetworkOPs.cpp:345
transaction=@0x7fffff1f9b00, bAdmin=false) at NetworkOPs.h:136
peer=@0x7fffff1f9b60) at ripple_Peer.cpp:1077
a=@0x7fffff1f9bd0) at bind.hpp:457
at bind_template.hpp:32
at function_template.hpp:153
a0=@0x7fffff1f9cc0) at function_template.hpp:760
---Type
this=0x8048951b8, p=0x804871b60) at mem_fn_template.hpp:49
a=@0x7fffff1f9f5f) at bind.hpp:253
at bind_template.hpp:20
this=0x804895100) at thread.hpp:61
Cannot access memory at address 0x7fffff1fa000
(gdb) bt
from /usr/lib/libstdc++.so.6
hash=@0x8051f0600) at ripple_SHAMap.cpp:234
at ripple_SHAMap.cpp:195
at ripple_SHAMap.cpp:549
at Ledger.cpp:1131
letType=ltDIR_NODE, index=@0x7fffffff4ae0) at LedgerEntrySet.cpp:95
uNodeDir=@0x7fffffff4b80, uRootIndex=@0x7fffffff4ae0, uLedgerIndex=@0x7fffffff4b00,
fDescriber=@0x7fffffff7b60) at LedgerEntrySet.cpp:635
at OfferCreateTransactor.cpp:623
txn=@0x80481e620, params=tapRETRY, didApply=@0x7fffffffaa57)
at TransactionEngine.cpp:99
engine=@0x7fffffffb2e0, txn=@0x7fffffffb3f0, ledger=@0x7fffffffbe00,
openLedger=false, retryAssured=true) at ripple_LedgerConsensus.cpp:1059
set=@0x7fffffffdb00, applyLedger=@0x7fffffffbe00, checkLedger=@0x7fffffffbe00,
failedTransactions=@0x7fffffffbc60, openLgr=false) at ripple_LedgerConsensus.cpp:1106
at ripple_LedgerConsensus.cpp:1205
u=@0x7fffffffdaf0, b1=@0x7fffffffdb00, b2=@0x7fffffffd980) at mem_fn_template.hpp:271
(this=0x7fffffffdae0, u=@0x7fffffffdaf0, a1=@0x7fffffffdb00, a2=@0x7fffffffd980)
at mem_fn_template.hpp:286
f=@0x7fffffffdae0, a=@0x7fffffffd9df) at bind.hpp:392
this=0x7fffffffdae0) at bind_template.hpp:20
d, boost::_mfi::mf2<void, LedgerConsensus, boost::shared_ptr
at completion_handler.hpp:67
this=0x809830c40, owner=@0x80481e380, ec=@0x7fffffffddf0, bytes_transferred=0)
at task_io_service_operation.hpp:37
this=0x80481e380, lock=@0x7fffffffdd30, this_thread=@0x7fffffffdd80,
private_op_queue=@0x7fffffffdd60, ec=@0x7fffffffddf0) at task_io_service.ipp:366
ec=@0x7fffffffddf0) at task_io_service.ipp:146
at io_service.ipp:59
at ripple_Application.cpp:606
We rebuilt the latest dev branch on FreeBSD. Ran it with the existing database and config. It hung almost immediately.
ripple# ./rippled --net 2013-Jun-26 23:29:20 WRN Launching child 1 Loading: "/root/NewCoin/public/rippled.cfg" 2013-Jun-26 23:29:20 ripple_JobQueue:NFO Auto-tuning to 6 validation/transaction/proposal threads 2013-Jun-26 23:29:20 FTL Unable to open logfile "log/debug.log" 2013-Jun-26 23:29:20 ripple_Application:NFO SQLite used for nodes 2013-Jun-26 23:29:20 ripple_Application:NFO Root master seed: snoPBrXtMeMyMHUVTgbuqAfg1SUTb 2013-Jun-26 23:29:20 ripple_Application:NFO Root account: rHb9CJAWyB4rj91VRWn96DkukG4bwdtyTh 2013-Jun-26 23:29:20 LedgerMaster:NFO PushLedger: AB868A6CFEEC779C2FF845C0AF00A642259986AF40C01976A7F842B6918936C7 2013-Jun-26 23:29:20 LedgerMaster:NFO StashAccepted: 2D81EC3F8AB517E3148872F7155122187F85FF3A995963A504FE597A43E791AC NodeIdentity: n9LprKw7B9H3Yf8bwZvkQsgPKTsUwBAFXkdQ5wGrHqdd9TQGtawR 2013-Jun-26 23:29:20 ripple_UniqueNodeList:NFO Bootstrapping UNL: loading from 'validators.txt'. 2013-Jun-26 23:29:20 ripple_UniqueNodeList:NFO Node Public: n9KPnVLn7ewVzHvn218DcEYsnWLzKerTDwhpofhk4Ym1RUq4TeGw RIP1 2013-Jun-26 23:29:20 ripple_UniqueNodeList:NFO Node Public: n9LFzWuhKNvXStHAuemfRKFVECLApowncMAM5chSCL9R5ECHGN4V RIP2 2013-Jun-26 23:29:20 ripple_UniqueNodeList:NFO Node Public: n94rSdgTyBNGvYg8pZXGuNt59Y5bGAZGxbxyvjDaqD9ceRAgD85P RIP3 2013-Jun-26 23:29:20 ripple_UniqueNodeList:NFO Node Public: n9LeQeDcLDMZKjx1TZtrXoLBLo5q1bR1sUQrWG7tEADFU6R27UBp RIP4
ripple# gdb rippled -p 22718 ... [Switching to Thread 804a0a580 (LWP 100424/rippled)] 0x0000000802a8c39c in __error () from /lib/libthr.so.3
(gdb) thread apply all where
Thread 10 (Thread 804a041c0 (LWP 100243/rippled)):
fail_ok=false) at ripple_SqliteDatabase.cpp:97
at ripple_Database.h:49
Thread 9 (Thread 804a8d540 (LWP 100094/rippled)):
at condition_variable.hpp:55
at mem_fn_template.hpp:49
Error accessing memory address 0x7fffffbff000: Bad address.
Thread 8 (Thread 804a8d380 (LWP 100129/rippled)):
sql=0x804c177a8 "DELETE FROM AccountTransactions WHERE LedgerSeq = 2;", fail_ok=false)
at ripple_SqliteDatabase.cpp:99
---Type
at ripple_Database.h:49
this=0x804c13bb0, u=@0x804c13bc0, b1=@0x7fffff9fdcc0, b2=@0x7fffff9fdb14) at mem_fn_template.hpp:271
this=0x804c13bb0, u=@0x804c13bc0, a1=@0x7fffff9fdcc0, a2=false) at mem_fn_template.hpp:286
f=@0x804c13bb0, a=@0x7fffff9fdbd0) at bind.hpp:392
a1=@0x7fffff9fdcc0) at bind_template.hpp:32
at function_template.hpp:760
at mem_fn_template.hpp:49
Error accessing memory address 0x7fffff9fe000: Bad address.
Thread 7 (Thread 804a8d1c0 (LWP 100142/rippled)):
at condition_variable.hpp:55
at mem_fn_template.hpp:49
Error accessing memory address 0x7fffff7fd000: Bad address.
---Type
at condition_variable.hpp:55
at mem_fn_template.hpp:49
Error accessing memory address 0x7fffff5fc000: Bad address.
Thread 5 (Thread 804a0ac80 (LWP 100375/rippled)):
p=0x804a708a8, a1=@0x7fffff3facc0) at mem_fn_template.hpp:165
a=@0x7fffff3fabd0) at bind.hpp:313
at bind_template.hpp:32
---Type
at function_template.hpp:760
at mem_fn_template.hpp:49
Error accessing memory address 0x7fffff3fb000: Bad address.
Thread 4 (Thread 804a0aac0 (LWP 100395/rippled)):
at condition_variable.hpp:55
at mem_fn_template.hpp:49
Error accessing memory address 0x7fffff1fa000: Bad address.
Thread 3 (Thread 804a0a900 (LWP 100402/rippled)):
at thread_data.hpp:138
at mem_fn_template.hpp:49
---Type
Error accessing memory address 0x7ffffeff9000: Bad address.
Thread 2 (Thread 804a0a740 (LWP 100406/rippled)):
at clone_allocator.hpp:74
this=0x804ab1570, i={iter_ = {_M_current = 0x804ad5400}}) at reversible_ptr_container.hpp:250
this=0x804ab1570, first={iter_ = {_M_current = 0x804ad5400}}, last={iter_ = {_M_current = 0x804ad5480}})
at reversible_ptr_container.hpp:257
this=0x804ab1570) at reversible_ptr_container.hpp:218
at make_shared.hpp:56
at make_shared.hpp:86
at ripple_TaggedCache.h:240
---Type
at mem_fn_template.hpp:49
a=@0x7ffffedf7ab0) at bind.hpp:253
at bind_template.hpp:47
at bind_handler.hpp:46
function=
{handler_ = {f_ = {f_ = {__pfn = 0x139, __delta = 0}}, l_ = {boost::_bi::storage1<boost::_bi::value<Application* >> = {a1_ = {t_ = 0x804a70000}}, <No data fields>}}, arg1_ = {m_val = 0, m_cat = 0x802317810}})
at handler_invoke_hook.hpp:64
(function=@0x7ffffedf7c40, context=@0x7ffffedf7c40) at handler_invoke_helpers.hpp:39
at wait_handler.hpp:68
owner=@0x804a1e380, ec=@0x7ffffedf7ea0, bytes_transferred=0) at task_io_service_operation.hpp:37
this_thread=@0x7ffffedf7e30, private_op_queue=@0x7ffffedf7e10, ec=@0x7ffffedf7ea0) at task_io_service.ipp:366
at task_io_service.ipp:146
Error accessing memory address 0x7ffffedf8000: Bad address.
Thread 1 (Thread 804a0a580 (LWP 100424/rippled)):
this_thread=@0x7ffffebf6e90, private_op_queue=@0x7ffffebf6e70, ec=@0x7ffffebf6f00) at task_io_service.ipp:377
---Type
at task_io_service.ipp:146
this=0x804a3d5a8) at resolver_service_base.ipp:32
Error accessing memory address 0x7ffffebf7000: Bad address.
The last one, that only happens on startup, should be fixed with https://github.com/ripple/rippled/commit/e7da485373e538874f1e7bf0d8ba9d5a2cb947f4 Currently pushed to develop. Should make it to master in a day or so.
Latest develop build. New gdb executable
stalled on startup
2013-Jun-27 02:12:24 NetworkOPs:NFO Net LCL 360485AFE76C4307BE979C1585689492986FCB88CFEB3EEE4F10A725D9E68FCA 2013-Jun-27 02:12:24 NetworkOPs:NFO Acquiring consensus ledger 360485AFE76C4307BE979C1585689492986FCB88CFEB3EEE4F10A725D9E68FCA 2013-Jun-27 02:12:24 NetworkOPs:NFO Consensus time for ledger 8 2013-Jun-27 02:12:24 NetworkOPs:NFO LCL is 3DD5E5C7E75861850E5C42B0836C0E6C991C96712A5844B29E5A31A8D62EFE80 2013-Jun-27 02:12:24 ripple_LedgerConsensus:NFO Entering consensus process, watching 2013-Jun-27 02:12:24 ripple_LedgerConsensus:WRN Need consensus ledger 360485AFE76C4307BE979C1585689492986FCB88CFEB3EEE4F10A725D9E68FCA 2013-Jun-27 02:12:24 ripple_LedgerConsensus:NFO Entering consensus with: 3DD5E5C7E75861850E5C42B0836C0E6C991C96712A5844B29E5A31A8D62EFE80 2013-Jun-27 02:12:24 ripple_LedgerConsensus:NFO Correct LCL is: 360485AFE76C4307BE979C1585689492986FCB88CFEB3EEE4F10A725D9E68FCA 2013-Jun-27 02:12:24 ripple_LedgerConsensus:NFO Have the consensus ledger 360485AFE76C4307BE979C1585689492986FCB88CFEB3EEE4F10A725D9E68FCA 2013-Jun-27 02:12:24 ripple_TaggedCache:NFO AcceptedLedger is growing fast 6 of 4 aging at 40 of 60 2013-Jun-27 02:12:33 ripple_LoadManager:WRN Server stalled for 10 seconds. 2013-Jun-27 02:12:34 ripple_TaggedCache:NFO AcceptedLedger is growing fast 6 of 4 aging at 40 of 60 2013-Jun-27 02:12:43 ripple_LoadManager:WRN Server stalled for 20 seconds. 2013-Jun-27 02:12:44 ripple_TaggedCache:NFO AcceptedLedger is growing fast 6 of 4 aging at 40 of 60 2013-Jun-27 02:12:53 ripple_LoadManager:WRN Server stalled for 30 seconds. 2013-Jun-27 02:13:03 ripple_LoadManager:WRN Server stalled for 40 seconds. 2013-Jun-27 02:13:13 ripple_LoadManager:WRN Server stalled for 50 seconds.
(gdb) bt
block=true, ops=...) at /usr/local/include/boost/asio/detail/impl/kqueue_reactor.ipp:393
this=0x804e40580, lock=..., this_thread=..., private_op_queue=..., ec=...)
at /usr/local/include/boost/asio/detail/impl/task_io_service.ipp:350
ec=...) at /usr/local/include/boost/asio/detail/impl/task_io_service.ipp:146
at /usr/local/include/boost/asio/impl/io_service.ipp:59
at Subtrees/websocket/src/endpoint.hpp:53
this=0x804fdee10, e=..., num_threads=0) at Subtrees/websocket/src/roles/server.hpp:347
e=..., num_threads=1) at Subtrees/websocket/src/roles/server.hpp:266
at ./src/cpp/ripple/WSDoor.cpp:51
p=0x804a737f0) at /usr/local/include/boost/bind/mem_fn_template.hpp:49
at /usr/local/include/boost/bind/bind.hpp:253
at /usr/local/include/boost/bind/bind_template.hpp:20
at /usr/local/include/boost/thread/detail/thread.hpp:61
Cannot access memory at address 0x7ffffe7f5000 (gdb)
2013-Jun-27 02:22:24 ripple_LoadManager:WRN Server stalled for 210 seconds. 2013-Jun-27 02:22:34 ripple_LoadManager:WRN Server stalled for 220 seconds. 2013-Jun-27 02:22:40 ripple_TaggedCache:NFO HashedObjectStore is growing fast 8589 of 8192 aging at 28 of 30 2013-Jun-27 02:22:44 ripple_LoadManager:WRN Server stalled for 230 seconds. 2013-Jun-27 02:22:50 ripple_TaggedCache:NFO HashedObjectStore is growing fast 8856 of 8192 aging at 27 of 30 2013-Jun-27 02:22:54 ripple_LoadManager:WRN Server stalled for 240 seconds. 2013-Jun-27 02:23:00 ripple_TaggedCache:NFO HashedObjectStore is growing fast 8695 of 8192 aging at 28 of 30 2013-Jun-27 02:23:04 ripple_LoadManager:WRN Server stalled for 250 seconds. 2013-Jun-27 02:23:14 ripple_LoadManager:WRN Server stalled for 260 seconds. 2013-Jun-27 02:23:24 ripple_LoadManager:WRN Server stalled for 270 seconds. 2013-Jun-27 02:23:34 ripple_LoadManager:WRN Server stalled for 280 seconds. 2013-Jun-27 02:23:44 ripple_LoadManager:WRN Server stalled for 290 seconds. 2013-Jun-27 02:23:54 ripple_LoadManager:WRN Server stalled for 300 seconds. 2013-Jun-27 02:24:04 ripple_LoadManager:WRN Server stalled for 310 seconds. 2013-Jun-27 02:24:10 ripple_TaggedCache:NFO HashedObjectStore is growing fast 13747 of 8192 aging at 17 of 30 2013-Jun-27 02:24:14 ripple_LoadManager:WRN Server stalled for 320 seconds. 2013-Jun-27 02:24:20 ripple_TaggedCache:NFO HashedObjectStore is growing fast 13317 of 8192 aging at 18 of 30 2013-Jun-27 02:24:24 ripple_LoadManager:WRN Server stalled for 330 seconds. 2013-Jun-27 02:24:24 ripple_LoadMonitor:WRN Job: timerEntry ExecutionTime: 736912 2013-Jun-27 02:24:24 ripple_LoadMonitor:WRN Job: gotLedgerData ExecutionTime: 738731 2013-Jun-27 02:24:28 ripple_LoadMonitor:WRN Job: gotLedgerData ExecutionTime: 723737 2013-Jun-27 02:24:28 ripple_LoadMonitor:WRN Job: PeerImp::ledgerdata ExecutionTime: 723700 2013-Jun-27 02:24:28 ripple_Peer:NFO Peer: Connect: Error: system: Operation timed out: system:60 2013-Jun-27 02:24:28 ripple_Peer:NFO Peer: Connect: Error: system: Operation timed out: system:60 2013-Jun-27 02:24:28 ripple_Peer:NFO Peer: Connect: Outbound: 0000000804A9B400: 176.31.181.172 51235 2013-Jun-27 02:24:28 ripple_LedgerConsensus:NFO Have the consensus ledger 360485AFE76C4307BE979C1585689492986FCB88CFEB3EEE4F10A725D9E68FCA 2013-Jun-27 02:24:28 LedgerTiming:WRN CLC::shouldClose range Trans=no, Prop: 4/0, Secs: 738000 (last:2017) 2013-Jun-27 02:24:28 ripple_LedgerConsensus:NFO initial position 0000000000000000000000000000000000000000000000000000000000000000 2013-Jun-27 02:24:28 ripple_Validations:WRN Received stale validation now=425607408, close=425606702 2013-Jun-27 02:24:28 ripple_Validations:WRN Received stale validation now=425607408, close=425606702 2013-Jun-27 02:24:28 ripple_Validations:WRN Received stale validation now=425607408, close=425606702 2013-Jun-27 02:24:28 ripple_Validations:WRN Received stale validation now=425607408, close=425606702 2013-Jun-27 02:24:28 ripple_Validations:WRN Received stale validation now=425607408, close=425606711 2013-Jun-27 02:24:28 ripple_Validations:WRN Received stale validation now=425607408, close=425606711 2013-Jun-27 02:24:28 ripple_Validations:WRN Received stale validation now=425607408, close=425606711 2013-Jun-27 02:24:28 ripple_Validations:WRN Received stale validation now=425607408, close=425606711 2013-Jun-27 02:24:28 TransactionEngine:NFO applyTransaction: terResult=terNO_ACCOUNT : -96 : The source account does not exist. 2013-Jun-27 02:24:28 NetworkOPs:NFO TransactionResult: terNO_ACCOUNT: The source account does not exist. 2013-Jun-27 02:24:28 ripple_Validations:WRN Received stale validation now=425607408, close=425606732 2013-Jun-27 02:24:28 ripple_Validations:WRN Received stale validation now=425607408, close=425606732 2013-Jun-27 02:24:28 ripple_Validations:WRN Received stale validation now=425607408, close=425606732 2013-Jun-27 02:24:28 ripple_Validations:WRN Received stale validation now=425607408, close=425606732 2013-Jun-27 02:24:28 TransactionEngine:NFO applyTransaction: terResult=terNO_ACCOUNT : -96 : The source account does not exist. 2013-Jun-27 02:24:28 NetworkOPs:NFO TransactionResult: terNO_ACCOUNT: The source account does not exist. 2013-Jun-27 02:24:28 ripple_Peer:NFO Connect peer: success. 2013-Jun-27 02:24:28 ripple_Validations:WRN Received stale validation now=425607408, close=425606738 2013-Jun-27 02:24:28 ripple_Validations:WRN Received stale validation now=425607408, close=425606738 2013-Jun-27 02:24:28 TransactionEngine:NFO applyTransaction: terResult=terNO_ACCOUNT : -96 : The source account does not exist. 2013-Jun-27 02:24:28 NetworkOPs:NFO TransactionResult: terNO_ACCOUNT: The source account does not exist. 2013-Jun-27 02:24:28 ripple_Validations:WRN Received stale validation now=425607408, close=425606738 2013-Jun-27 02:24:28 ripple_Validations:WRN Received stale validation now=425607408, close=425606739 2013-Jun-27 02:24:28 ripple_Peer:NFO Peer: Write: Error: 0000000804A9C400: bytes=0: system: Broken pipe: system:32 2013-Jun-27 02:24:28 ripple_Validations:WRN Received stale validation now=425607408, close=425606744 2013-Jun-27 02:24:28 ripple_Validations:WRN Received stale validation now=425607408, close=425606744 2013-Jun-27 02:24:28 ripple_Validations:WRN Received stale validation now=425607408, close=425606745 2013-Jun-27 02:24:28 ripple_Validations:WRN Received stale validation now=425607408, close=425606745 2013-Jun-27 02:24:28 TransactionEngine:NFO applyTransaction: terResult=terNO_ACCOUNT : -96 : The source account does not exist. 2013-Jun-27 02:24:28 NetworkOPs:NFO TransactionResult: terNO_ACCOUNT: The source account does not exist. 2013-Jun-27 02:24:28 TransactionEngine:NFO applyTransaction: terResult=terNO_ACCOUNT : -96 : The source account does not exist. 2013-Jun-27 02:24:28 NetworkOPs:NFO TransactionResult: terNO_ACCOUNT: The source account does not exist. 2013-Jun-27 02:24:28 ripple_LoadManager:NFO {
(gdb) continue Continuing. [New Thread 804a0a040 (LWP 100439)] [Thread 804a0a040 (LWP 100439) exited] [Thread 804a09e80 (LWP 100439) exited] [Thread 804a09cc0 (LWP 100439) exited] [Thread 804a09b00 (LWP 100439) exited] [Thread 804a0a3c0 (LWP 100439) exited] [Thread 804a0a040 (LWP 100439) exited] [Thread 804a09e80 (LWP 100439) exited] [Thread 804a09cc0 (LWP 100439) exited] [Thread 804a09b00 (LWP 100439) exited] [Thread 804a0a3c0 (LWP 100439) exited] [Thread 804a0a040 (LWP 100439) exited] [Thread 804a09e80 (LWP 100439) exited] [Thread 804a09cc0 (LWP 100439) exited] [Thread 804a09b00 (LWP 100439) exited] [Thread 804a0a3c0 (LWP 100439) exited] [Thread 804a0a040 (LWP 100439) exited] [Thread 804a09e80 (LWP 100439) exited] [Thread 804a09cc0 (LWP 100439) exited] [Thread 804a09b00 (LWP 100439) exited] [Thread 804a0a3c0 (LWP 100439) exited] [Thread 804a0a040 (LWP 100439) exited] [Thread 804a09e80 (LWP 100439) exited] [Thread 804a09cc0 (LWP 100439) exited] [Thread 804a09b00 (LWP 100439) exited] [Thread 804a0a3c0 (LWP 100439) exited] [Thread 804a0a040 (LWP 100439) exited] [Thread 804a09e80 (LWP 100439) exited] [Thread 804a09cc0 (LWP 100439) exited] [Thread 804a09b00 (LWP 100439) exited] [Thread 804a0a3c0 (LWP 100439) exited] [Thread 804a0a040 (LWP 100439) exited]
Program received signal SIGABRT, Aborted. [Switching to Thread 804a0aac0 (LWP 100375)] 0x0000000802be8a4c in thr_kill () from /lib/libc.so.7 (gdb)
2013-Jun-27 02:28:00 ripple_LedgerConsensus:NFO initial position 0000000000000000000000000000000000000000000000000000000000000000 2013-Jun-27 02:28:02 ripple_LedgerConsensus:NFO Proposers:4 nw:70 thrV:3 thrC:3 2013-Jun-27 02:28:02 LedgerTiming:NFO normal consensus 2013-Jun-27 02:28:02 ripple_LedgerConsensus:NFO Converge cutoff (4 participants) 2013-Jun-27 02:28:02 ripple_LedgerConsensus:NFO CNF newLCL A002496D48136B5693F76B1FE79627BE30C02E6E3DF8512D149403F9A8F6BC69 2013-Jun-27 02:28:02 LedgerMaster:NFO StashAccepted: A002496D48136B5693F76B1FE79627BE30C02E6E3DF8512D149403F9A8F6BC69 2013-Jun-27 02:28:02 LedgerMaster:NFO Advancing accepted ledger to 1146728 with >= 2 validations 2013-Jun-27 02:28:04 NFO STATE->full 2013-Jun-27 02:28:04 NetworkOPs:NFO Consensus time for ledger 1146729 2013-Jun-27 02:28:04 NetworkOPs:NFO LCL is A002496D48136B5693F76B1FE79627BE30C02E6E3DF8512D149403F9A8F6BC69 2013-Jun-27 02:28:04 ripple_LedgerConsensus:NFO Entering consensus process, watching 2013-Jun-27 02:28:10 ripple_TaggedCache:NFO AcceptedLedger is growing fast 6 of 4 aging at 40 of 60 2013-Jun-27 02:28:19 OfferCreateTransactor:NFO OfferCreate: take against book: 4627DFFCFF8B5A265EDBD8AE8C14A52325DBFEDAF4F5C32E0000000000000000 for 140143840/XRP -> 2.768/USD/rvYAfWj5gh67oV6fW32ZzP3Aw4Eubs59B 2013-Jun-27 02:28:19 OfferCreateTransactor:NFO OfferCreate: offer not fully consumed: saTakerPays=2.768/USD/rvYAfWj5gh67oV6fW32ZzP3Aw4Eubs59B saTakerGets=140143840/XRP 2013-Jun-27 02:28:19 OfferCreateTransactor:NFO OfferCreate: adding to book: DFA3B6DDAB58C7E8E5D944E736DA4B7046C30E4F460FD9DE0000000000000000 : USD/rvYAfWj5gh67oV6fW32ZzP3Aw4Eubs59B -> XRP/rrrrrrrrrrrrrrrrrrrrrhoLvTp terminate called after throwing an instance of 'SHAMapMissingNode' what(): SHAMapMissingNode
(gdb) bt
from /usr/lib/libstdc++.so.6
at ./src/cpp/ripple/ripple_SHAMap.cpp:236
at ./src/cpp/ripple/ripple_SHAMap.cpp:197
at ./src/cpp/ripple/ripple_SHAMap.cpp:551
at ./src/cpp/ripple/Ledger.cpp:1134
index=...) at ./src/cpp/ripple/ripple_LedgerEntrySet.cpp:99
this=0x804a70118, uNodeDir=@0x7fffff1f2b30: 0, uRootIndex=..., uLedgerIndex=...,
fDescriber=...) at ./src/cpp/ripple/ripple_LedgerEntrySet.cpp:639
at ./src/cpp/ripple/OfferCreateTransactor.cpp:659
at ./src/cpp/ripple/Transactor.cpp:251
params=(tapNO_CHECK_SIGN | tapOPEN_LEDGER), didApply=@0x7fffff1f8fff: false)
at ./src/cpp/ripple/TransactionEngine.cpp:102
params=(tapNO_CHECK_SIGN | tapOPEN_LEDGER), didApply=@0x7fffff1f8fff: false)
at ./src/cpp/ripple/LedgerMaster.cpp:170
trans=..., bAdmin=false, bFailHard=false, callback=...)
at ./src/cpp/ripple/NetworkOPs.cpp:350
bAdmin=false, bFailHard=false) at ./src/cpp/ripple/NetworkOPs.h:142
at ./src/cpp/ripple/ripple_Peer.cpp:1081
f=@0x805404820: 0xfcb260 <checkTransaction(Job&, int, boost::shared_ptr<SerializedTransaction>&, boost::weak_ptr<Peer>&)>, a=...) at /usr/local/include/boost/bind/bind.hpp:457
at /usr/local/include/boost/bind/bind_template.hpp:32
(function_obj_ptr=..., a0=...)
at /usr/local/include/boost/function/function_template.hpp:153
---Type
at modules/ripple_core/functional/ripple_Job.cpp:41
at modules/ripple_core/functional/ripple_JobQueue.cpp:268
p=0x804a70b48) at /usr/local/include/boost/bind/mem_fn_template.hpp:49
at /usr/local/include/boost/bind/bind.hpp:253
at /usr/local/include/boost/bind/bind_template.hpp:20
this=0x804a94200) at /usr/local/include/boost/thread/detail/thread.hpp:61
(gdb)
(gdb) thread apply all where
Thread 11 (Thread 804a041c0 (LWP 100442)):
at /usr/local/include/boost/thread/pthread/recursive_mutex.hpp:133
this=0x7fffffffa230) at /usr/local/include/boost/thread/locks.hpp:412
this=0x7fffffffa230, m_=...) at /usr/local/include/boost/thread/locks.hpp:290
at ./src/cpp/ripple/ripple_InboundLedger.cpp:171
at ./src/cpp/ripple/ripple_InboundLedgers.cpp:74
MasterLockHolder=...) at ./src/cpp/ripple/ripple_Peer.cpp:2072
at ./src/cpp/ripple/ripple_Peer.cpp:833
at ./src/cpp/ripple/ripple_Peer.cpp:631
u=..., b1=...) at /usr/local/include/boost/bind/mem_fn_template.hpp:156
at /usr/local/include/boost/bind/mem_fn_template.hpp:171
this=0x7fffffffd340, f=..., a=...) at /usr/local/include/boost/bind/bind.hpp:313
this=0x7fffffffd330, a1=..., a2=@0x7fffffffd360: 585)
at /usr/local/include/boost/bind/bind_template.hpp:102
at /usr/local/include/boost/asio/handler_invoke_hook.hpp:64
at /usr/local/include/boost/asio/detail/handler_invoke_helpers.hpp:39
---Type
at /usr/local/include/boost/asio/detail/handler_invoke_helpers.hpp:39
impl=@0x7fffffffd998: 0x8056d3330, handler=...)
at /usr/local/include/boost/asio/detail/impl/strand_service.hpp:66
at /usr/local/include/boost/asio/strand.hpp:150
arg2=@0x7fffffffd988: 585) at /usr/local/include/boost/asio/detail/wrapped_handler.hpp:79
bytes_transferred=585, start=0) at /usr/local/include/boost/asio/impl/read.hpp:274
bytes_transferred=@0x7fffffffd7b0: 585)
at /usr/local/include/boost/asio/ssl/detail/read_op.hpp:59
this=0x7fffffffd930, ec=..., bytes_transferred=0, start=0)
at /usr/local/include/boost/asio/ssl/detail/io.hpp:260
rvice::strand, boost::_bi::bind_t<void, boost::_mfi::mf1<void, PeerImp, boost::system::error_code const&>, boost::_bi::list2boost::_bi::value<boost::sharedptr
at /usr/local/include/boost/asio/detail/wrapped_handler.hpp:171
at /usr/local/include/boost/asio/handler_invoke_hook.hpp:64
at /usr/local/include/boost/asio/detail/handler_invoke_helpers.hpp:39
:io_op<boost::asio::basic_stream_socket<boost::asio::ip::tcp, boost::asio::stream_socket_service
at /usr/local/include/boost/asio/detail/handler_invoke_helpers.hpp:39
at /usr/local/include/boost/asio/detail/completion_handler.hpp:67
&>, boost::_bi::list2boost::_bi::value<boost::sharedptr
at /usr/local/include/boost/asio/strand.hpp:150
at /usr/local/include/boost/asio/detail/wrapped_handler.hpp:204
context=...) at /usr/local/include/boost/asio/detail/handler_invoke_helpers.hpp:39
nd, boost::_bi::bind_t<void, boost::_mfi::mf1<void, PeerImp, boost::system::error_code const&>, boost::_bi::list2boost::_bi::value<boost::sharedptr
at /usr/local/include/boost/asio/detail/handler_invoke_helpers.hpp:39
at /usr/local/include/boost/asio/ssl/detail/io.hpp:305
at /usr/local/include/boost/asio/detail/handler_invoke_helpers.hpp:39
ffers_1, boost::asio::ssl::detail::io_op<boost::asio::basic_stream_socket<boost::asio::ip::tcp, boost::asio::stream_socket_service
this=0x804a6d800, owner=..., ec=..., bytes_transferred=0)
at /usr/local/include/boost/asio/detail/task_io_service_operation.hpp:37
this=0x804a1e300, lock=..., this_thread=..., private_op_queue=..., ec=...)
at /usr/local/include/boost/asio/detail/impl/task_io_service.ipp:366
ec=...) at /usr/local/include/boost/asio/detail/impl/task_io_service.ipp:146
at /usr/local/include/boost/asio/impl/io_service.ipp:59
at ./src/cpp/ripple/ripple_Application.cpp:652
Thread 10 (Thread 804a8d540 (LWP 100129)):
at /usr/local/include/boost/thread/pthread/recursive_mutex.hpp:133
this=0x7fffffbfabc0) at /usr/local/include/boost/thread/locks.hpp:412
this=0x7fffffbfabc0, m_=...) at /usr/local/include/boost/thread/locks.hpp:290
at ./src/cpp/ripple/ripple_HashedObjectStore.cpp:443
at ./src/cpp/ripple/ripple_HashedObjectStore.h:42
at ./src/cpp/ripple/ripple_SHAMap.cpp:827
at ./src/cpp/ripple/ripple_SHAMap.cpp:249
filter=0x7fffffbfbcc0) at ./src/cpp/ripple/ripple_SHAMap.cpp:264
hashes=..., max=238, filter=0x7fffffbfbcc0) at ./src/cpp/ripple/ripple_SHAMapSync.cpp:55
at ./src/cpp/ripple/ripple_InboundLedger.cpp:466
packet_ptr=..., wPeer=...) at ./src/cpp/ripple/ripple_InboundLedgers.cpp:179
p=0x804a703e0, a1=..., a2=..., a3=..., a4=...)
at /usr/local/include/boost/bind/mem_fn_template.hpp:506
ob&, uint256, boost::shared_ptrprotocol::TMLedgerData, boost::weak_ptr
at /usr/local/include/boost/bind/bind_template.hpp:32
function_obj_ptr=..., a0=...)
at /usr/local/include/boost/function/function_template.hpp:153
a0=...) at /usr/local/include/boost/function/function_template.hpp:760
at modules/ripple_core/functional/ripple_Job.cpp:41
at modules/ripple_core/functional/ripple_JobQueue.cpp:268
p=0x804a70b48) at /usr/local/include/boost/bind/mem_fn_template.hpp:49
at /usr/local/include/boost/bind/bind.hpp:253
at /usr/local/include/boost/bind/bind_template.hpp:20
this=0x804a6c700) at /usr/local/include/boost/thread/detail/thread.hpp:61
Thread 9 (Thread 804a8d380 (LWP 100142)):
at /usr/local/include/boost/thread/pthread/recursive_mutex.hpp:133
this=0x7fffff9fd980) at /usr/local/include/boost/thread/locks.hpp:412
this=0x7fffff9fd980, m_=...) at /usr/local/include/boost/thread/locks.hpp:290
at ./src/cpp/ripple/ripple_PeerSet.cpp:47
at ./src/cpp/ripple/ripple_PeerSet.cpp:91
f=@0x7fffff9fdcd8: 0x104f200 <PeerSet::TimerJobEntry(Job&, boost::shared_ptr<PeerSet>)>,
a=...) at /usr/local/include/boost/bind/bind.hpp:313
---Type
a0=...) at /usr/local/include/boost/function/function_template.hpp:153
a0=...) at /usr/local/include/boost/function/function_template.hpp:760
at modules/ripple_core/functional/ripple_Job.cpp:41
at modules/ripple_core/functional/ripple_JobQueue.cpp:268
p=0x804a70b48) at /usr/local/include/boost/bind/mem_fn_template.hpp:49
at /usr/local/include/boost/bind/bind.hpp:253
at /usr/local/include/boost/bind/bind_template.hpp:20
this=0x804a94600) at /usr/local/include/boost/thread/detail/thread.hpp:61
Thread 8 (Thread 804a8d1c0 (LWP 100235)):
at /usr/local/include/boost/thread/pthread/condition_variable.hpp:55
at modules/ripple_core/functional/ripple_JobQueue.cpp:246
p=0x804a70b48) at /usr/local/include/boost/bind/mem_fn_template.hpp:49
at /usr/local/include/boost/bind/bind.hpp:253
at /usr/local/include/boost/bind/bind_template.hpp:20
this=0x804a94500) at /usr/local/include/boost/thread/detail/thread.hpp:61
Thread 7 (Thread 804a0ae40 (LWP 100243)):
at /usr/local/include/boost/thread/pthread/recursive_mutex.hpp:133
this=0x7fffff5f7bc0) at /usr/local/include/boost/thread/locks.hpp:412
this=0x7fffff5f7bc0, m_=...) at /usr/local/include/boost/thread/locks.hpp:290
---Type
at ./src/cpp/ripple/ripple_HashedObjectStore.h:42
at ./src/cpp/ripple/ripple_SHAMap.cpp:827
at ./src/cpp/ripple/ripple_SHAMap.cpp:249
filter=0x7fffff5f8cc0) at ./src/cpp/ripple/ripple_SHAMap.cpp:264
hashes=..., max=239, filter=0x7fffff5f8cc0) at ./src/cpp/ripple/ripple_SHAMapSync.cpp:55
at ./src/cpp/ripple/ripple_InboundLedger.cpp:466
packet_ptr=..., wPeer=...) at ./src/cpp/ripple/ripple_InboundLedgers.cpp:134
p=0x804a703e0, a1=..., a2=..., a3=..., a4=...)
at /usr/local/include/boost/bind/mem_fn_template.hpp:506
at /usr/local/include/boost/bind/bind_template.hpp:32
function_obj_ptr=..., a0=...)
at /usr/local/include/boost/function/function_template.hpp:153
a0=...) at /usr/local/include/boost/function/function_template.hpp:760
at modules/ripple_core/functional/ripple_Job.cpp:41
at modules/ripple_core/functional/ripple_JobQueue.cpp:268
p=0x804a70b48) at /usr/local/include/boost/bind/mem_fn_template.hpp:49
at /usr/local/include/boost/bind/bind.hpp:253
at /usr/local/include/boost/bind/bind_template.hpp:20
this=0x804a94400) at /usr/local/include/boost/thread/detail/thread.hpp:61
---Type
Thread 6 (Thread 804a0ac80 (LWP 100326)):
this=0x1479398 <HashedObjectStore::retrieveSQLite(uint256 const&)::pSt>)
at ./src/cpp/ripple/ripple_SqliteDatabase.cpp:397
at ./src/cpp/ripple/ripple_HashedObjectStore.cpp:449
at ./src/cpp/ripple/ripple_HashedObjectStore.h:42
at ./src/cpp/ripple/ripple_SHAMap.cpp:827
at ./src/cpp/ripple/ripple_SHAMap.cpp:249
filter=0x7fffff3f7cc0) at ./src/cpp/ripple/ripple_SHAMap.cpp:264
hashes=..., max=238, filter=0x7fffff3f7cc0) at ./src/cpp/ripple/ripple_SHAMapSync.cpp:55
at ./src/cpp/ripple/ripple_InboundLedger.cpp:466
packet_ptr=..., wPeer=...) at ./src/cpp/ripple/ripple_InboundLedgers.cpp:179
p=0x804a703e0, a1=..., a2=..., a3=..., a4=...)
at /usr/local/include/boost/bind/mem_fn_template.hpp:506
at /usr/local/include/boost/bind/bind_template.hpp:32
function_obj_ptr=..., a0=...)
at /usr/local/include/boost/function/function_template.hpp:153
---Type
a0=...) at /usr/local/include/boost/function/function_template.hpp:760
at modules/ripple_core/functional/ripple_Job.cpp:41
at modules/ripple_core/functional/ripple_JobQueue.cpp:268
p=0x804a70b48) at /usr/local/include/boost/bind/mem_fn_template.hpp:49
at /usr/local/include/boost/bind/bind.hpp:253
at /usr/local/include/boost/bind/bind_template.hpp:20
this=0x804a94300) at /usr/local/include/boost/thread/detail/thread.hpp:61
Thread 5 (Thread 804a0aac0 (LWP 100375)):
at /usr/local/include/boost/thread/pthread/recursive_mutex.hpp:133
this=0x7fffff1f9980) at /usr/local/include/boost/thread/locks.hpp:412
this=0x7fffff1f9980, m_=...) at /usr/local/include/boost/thread/locks.hpp:290
at ./src/cpp/ripple/ripple_PeerSet.cpp:47
at ./src/cpp/ripple/ripple_PeerSet.cpp:91
f=@0x7fffff1f9cd8: 0x104f200 <PeerSet::TimerJobEntry(Job&, boost::shared_ptr<PeerSet>)>,
a=...) at /usr/local/include/boost/bind/bind.hpp:313
a0=...) at /usr/local/include/boost/function/function_template.hpp:153
a0=...) at /usr/local/include/boost/function/function_template.hpp:760
at modules/ripple_core/functional/ripple_Job.cpp:41
at modules/ripple_core/functional/ripple_JobQueue.cpp:268
p=0x804a70b48) at /usr/local/include/boost/bind/mem_fn_template.hpp:49
at /usr/local/include/boost/bind/bind.hpp:253
---Type
at /usr/local/include/boost/bind/bind_template.hpp:20
this=0x804a94200) at /usr/local/include/boost/thread/detail/thread.hpp:61
Thread 4 (Thread 804a0a900 (LWP 100395)):
from /usr/local/lib/libboost_thread.so
rel_time=...) at /usr/local/include/boost/thread/pthread/thread_data.hpp:138
at ./src/cpp/ripple/ripple_LoadManager.cpp:406
p=0x804a80d00) at /usr/local/include/boost/bind/mem_fn_template.hpp:49
at /usr/local/include/boost/bind/bind.hpp:253
at /usr/local/include/boost/bind/bind_template.hpp:20
this=0x804a94100) at /usr/local/include/boost/thread/detail/thread.hpp:61
Thread 3 (Thread 804a0a740 (LWP 100401)):
block=true, ops=...) at /usr/local/include/boost/asio/detail/impl/kqueue_reactor.ipp:393
this=0x804a1e380, lock=..., this_thread=..., private_op_queue=..., ec=...)
at /usr/local/include/boost/asio/detail/impl/task_io_service.ipp:350
ec=...) at /usr/local/include/boost/asio/detail/impl/task_io_service.ipp:146
at /usr/local/include/boost/asio/impl/io_service.ipp:59
f=@0x804a6ffb8: 0xfaba40 <runAux(boost::asio::io_service&)>, a=...)
at /usr/local/include/boost/bind/bind.hpp:253
this=0x804a6ffb8) at /usr/local/include/boost/bind/bind_template.hpp:20
:run (this=0x804a6ff00) at /usr/local/include/boost/thread/detail/thread.hpp:61
Thread 2 (Thread 804a0a580 (LWP 100427)):
at /usr/local/include/boost/asio/detail/posix_event.hpp:80
this=0x804a1e400, lock=..., this_thread=..., private_op_queue=..., ec=...)
at /usr/local/include/boost/asio/detail/impl/task_io_service.ipp:377
ec=...) at /usr/local/include/boost/asio/detail/impl/task_io_service.ipp:146
at /usr/local/include/boost/asio/impl/io_service.ipp:59
at /usr/local/include/boost/asio/detail/impl/resolver_service_base.ipp:32
at /usr/local/include/boost/asio/detail/posix_thread.hpp:82
arg=0x804a3d5a0) at /usr/local/include/boost/asio/detail/impl/posix_thread.ipp:64
Thread 1 (Thread 804a0a200 (LWP 100438)):
block=true, ops=...) at /usr/local/include/boost/asio/detail/impl/kqueue_reactor.ipp:393
this=0x804e40580, lock=..., this_thread=..., private_op_queue=..., ec=...)
at /usr/local/include/boost/asio/detail/impl/task_io_service.ipp:350
ec=...) at /usr/local/include/boost/asio/detail/impl/task_io_service.ipp:146
at /usr/local/include/boost/asio/impl/io_service.ipp:59
at Subtrees/websocket/src/endpoint.hpp:53
this=0x804fdee10, e=..., num_threads=0) at Subtrees/websocket/src/roles/server.hpp:347
e=..., num_threads=1) at Subtrees/websocket/src/roles/server.hpp:266
at ./src/cpp/ripple/WSDoor.cpp:51
p=0x804a737f0) at /usr/local/include/boost/bind/mem_fn_template.hpp:49
at /usr/local/include/boost/bind/bind.hpp:253
---Type
at /usr/local/include/boost/thread/detail/thread.hpp:61
Cannot access memory at address 0x7ffffe7f5000 (gdb)
I experienced the "terminate called after throwing an instance of 'ripple::SHAMapMissingNode'" with the same backtrace in first comment with latest develop branch.
@alexdupre What really puzzles me is this -- this bug seems to have something to do with corruption of the current ledger or a node in the current ledger not being in the local database. You would expect a bug like that to cause random failures in all kinds of code paths that access the current ledger. But we always see this very same stack trace -- always the OfferCreateTransactor calling LES::getNextLedgerIndex calling SHAMap::getStack.
If this happens again, please give me the last two or three lines from your debug log file.
I still have the log file. Here are the log lines for the last 5 seconds before crashing:
2013-Jun-29 08:02:50 ripple_LedgerConsensus:NFO initial position 0000000000000000000000000000000000000000000000000000000000000000 2013-Jun-29 08:02:52 ripple_LedgerConsensus:NFO Proposers:4 nw:70 thrV:3 thrC:3 2013-Jun-29 08:02:52 ripple_LedgerConsensus:DBG CCTime: seq1164496: 425808170 has 4, 3 required 2013-Jun-29 08:02:52 ripple_LedgerConsensus:DBG Close time consensus reached: 425808170 2013-Jun-29 08:02:52 ripple_LedgerConsensus:DBG Checking for TX consensus: agree=4, disagree=0 2013-Jun-29 08:02:52 LedgerTiming:NFO normal consensus 2013-Jun-29 08:02:52 ripple_LedgerConsensus:NFO Converge cutoff (4 participants) 2013-Jun-29 08:02:52 ripple_LedgerConsensus:DBG Report: Prop=no val=no corLCL=yes fail=no 2013-Jun-29 08:02:52 ripple_LedgerConsensus:DBG Report: Prev = 584242561C93BBD385666145F269E010D1823D029AFD6649E4222331E73B1EC6:1164495 2013-Jun-29 08:02:52 ripple_LedgerConsensus:DBG Report: TxSt = 0000000000000000000000000000000000000000000000000000000000000000, close 425808170 2013-Jun-29 08:02:52 ripple_LedgerConsensus:DBG Applying consensus set transactions to the last closed ledger 2013-Jun-29 08:02:52 ripple_LedgerConsensus:DBG Pass: 0 Txns: 0 retriable 2013-Jun-29 08:02:52 ripple_LedgerConsensus:DBG Pass: 0 finished 0 changes 2013-Jun-29 08:02:52 ripple_LedgerConsensus:DBG Pass: 1 Txns: 0 final 2013-Jun-29 08:02:52 ripple_LedgerConsensus:DBG Pass: 1 finished 0 changes 2013-Jun-29 08:02:52 ripple_LedgerConsensus:DBG Report: NewL = 441EE344F4707F34D7A489F9C2C5E429DD27BBDF651BCED9E80E89EEB9A3D0F7:1164496 2013-Jun-29 08:02:52 ripple_LedgerConsensus:NFO CNF newLCL 441EE344F4707F34D7A489F9C2C5E429DD27BBDF651BCED9E80E89EEB9A3D0F7 2013-Jun-29 08:02:52 ripple_LedgerConsensus:DBG Applying transactions from current open ledger 2013-Jun-29 08:02:52 ripple_LedgerConsensus:DBG Pass: 0 Txns: 0 retriable 2013-Jun-29 08:02:52 ripple_LedgerConsensus:DBG Pass: 0 finished 0 changes 2013-Jun-29 08:02:52 ripple_LedgerConsensus:DBG Pass: 1 Txns: 0 final 2013-Jun-29 08:02:52 ripple_LedgerConsensus:DBG Pass: 1 finished 0 changes 2013-Jun-29 08:02:52 LedgerMaster:NFO StashAccepted: 441EE344F4707F34D7A489F9C2C5E429DD27BBDF651BCED9E80E89EEB9A3D0F7 2013-Jun-29 08:02:52 NetworkOPs:DBG recvValidation 441EE344F4707F34D7A489F9C2C5E429DD27BBDF651BCED9E80E89EEB9A3D0F7 from 23.23.201.55 2013-Jun-29 08:02:52 ripple_Validations:DBG Val for 441EE344F4707F34D7A489F9C2C5E429DD27BBDF651BCED9E80E89EEB9A3D0F7 from n9LeQeDcLDMZKjx1TZtrXoLBLo5q1bR1sUQrWG7tEADFU6R27UBp added trusted/ current 2013-Jun-29 08:02:52 LedgerMaster:DBG no prior missing ledger, not resuming 2013-Jun-29 08:02:52 NetworkOPs:DBG recvValidation 441EE344F4707F34D7A489F9C2C5E429DD27BBDF651BCED9E80E89EEB9A3D0F7 from 23.23.201.55 2013-Jun-29 08:02:52 ripple_Validations:DBG Val for 441EE344F4707F34D7A489F9C2C5E429DD27BBDF651BCED9E80E89EEB9A3D0F7 from n9KPnVLn7ewVzHvn218DcEYsnWLzKerTDwhpofhk4Ym1RUq4TeGw added trusted/ current 2013-Jun-29 08:02:52 LedgerMaster:NFO Advancing accepted ledger to 1164496 with >= 2 validations 2013-Jun-29 08:02:52 LedgerMaster:DBG Publishing ledger 1164496 2013-Jun-29 08:02:52 LedgerMaster:DBG Ledger 1164496 accepted :441EE344F4707F34D7A489F9C2C5E429DD27BBDF651BCED9E80E89EEB9A3D0F7 2013-Jun-29 08:02:52 LedgerMaster:DBG Ledger 1157719 is needed 2013-Jun-29 08:02:53 ripple_Peer:DBG Got new transaction from peer 2013-Jun-29 08:02:53 NetworkOPs:DBG recvValidation 441EE344F4707F34D7A489F9C2C5E429DD27BBDF651BCED9E80E89EEB9A3D0F7 from 23.23.201.55 2013-Jun-29 08:02:53 ripple_Validations:DBG Val for 441EE344F4707F34D7A489F9C2C5E429DD27BBDF651BCED9E80E89EEB9A3D0F7 from n9LFzWuhKNvXStHAuemfRKFVECLApowncMAM5chSCL9R5ECHGN4V added trusted/ current 2013-Jun-29 08:02:53 NetworkOPs:DBG recvValidation 441EE344F4707F34D7A489F9C2C5E429DD27BBDF651BCED9E80E89EEB9A3D0F7 from 23.23.201.55 2013-Jun-29 08:02:53 ripple_Validations:DBG Val for 441EE344F4707F34D7A489F9C2C5E429DD27BBDF651BCED9E80E89EEB9A3D0F7 from n94rSdgTyBNGvYg8pZXGuNt59Y5bGAZGxbxyvjDaqD9ceRAgD85P added trusted/current 2013-Jun-29 08:02:53 OfferCreateTransactor:NFO OfferCreate: take against book: DFA3B6DDAB58C7E8E5D944E736DA4B7046C30E4F460FD9DE0000000000000000 for 7.57/USD/rvYAfWj5gh67oV6fW32ZzP3Aw4Eubs59B -> 514003000/XRP 2013-Jun-29 08:02:53 OfferCreateTransactor:DBG OfferCreate: takeOffers: BEFORE saTakerGets=7.57/USD/rvYAfWj5gh67oV6fW32ZzP3Aw4Eubs59B 2013-Jun-29 08:02:53 OfferCreateTransactor:DBG takeOffers: bSell: 1: against book: DFA3B6DDAB58C7E8E5D944E736DA4B7046C30E4F460FD9DE0000000000000000 2013-Jun-29 08:02:53 OfferCreateTransactor:DBG takeOffers: possible counter offer found: uTipQuality=5549954497759356847 uTipIndex=DFA3B6DDAB58C7E8E5D944E736DA4B7046C30E4F460FD9DE4D056635F68727AF 2013-Jun-29 08:02:53 OfferCreateTransactor:DBG takeOffers: done: dir=1 uTakeQuality=5549907494970524709 < uTipQuality=5549954497759356847 saTakerRate=0.00000001472754050073637/1/1 < saTipRate=0.00000001519756838905775/1/1 bPassive=0 2013-Jun-29 08:02:53 OfferCreateTransactor:DBG takeOffers: tesSUCCESS 2013-Jun-29 08:02:53 OfferCreateTransactor:DBG takeOffers< tesSUCCESS 2013-Jun-29 08:02:53 OfferCreateTransactor:DBG OfferCreate: takeOffers=0 2013-Jun-29 08:02:53 OfferCreateTransactor:DBG OfferCreate: takeOffers: saPaid=0/USD/rvYAfWj5gh67oV6fW32ZzP3Aw4Eubs59B 2013-Jun-29 08:02:53 OfferCreateTransactor:DBG OfferCreate: takeOffers: saGot=0/XRP 2013-Jun-29 08:02:53 OfferCreateTransactor:DBG OfferCreate: takeOffers: AFTER saTakerPays=514003000/XRP 2013-Jun-29 08:02:53 OfferCreateTransactor:DBG OfferCreate: takeOffers: AFTER saTakerGets=7.57/USD/rvYAfWj5gh67oV6fW32ZzP3Aw4Eubs59B 2013-Jun-29 08:02:53 OfferCreateTransactor:DBG OfferCreate: takeOffers: saTakerPays=514003000/XRP 2013-Jun-29 08:02:53 OfferCreateTransactor:DBG OfferCreate: takeOffers: saTakerGets=7.57/USD/rvYAfWj5gh67oV6fW32ZzP3Aw4Eubs59B 2013-Jun-29 08:02:53 OfferCreateTransactor:DBG OfferCreate: takeOffers: mTxnAccountID=rNAAy9xnjuU6McAjVFtMyFbDNKzTXQ9wbV 2013-Jun-29 08:02:53 OfferCreateTransactor:DBG OfferCreate: takeOffers: FUNDS=7.579526387225548/USD/rvYAfWj5gh67oV6fW32ZzP3Aw4Eubs59B 2013-Jun-29 08:02:53 OfferCreateTransactor:NFO OfferCreate: offer not fully consumed: saTakerPays=514003000/XRP saTakerGets=7.57/USD/rvYAfWj5gh67oV6fW32ZzP3Aw4Eubs59B 2013-Jun-29 08:02:53 OfferCreateTransactor:NFO OfferCreate: adding to book: 4627DFFCFF8B5A265EDBD8AE8C14A52325DBFEDAF4F5C32E0000000000000000 : XRP/rrrrrrrrrrrrrrrrrrrrrhoLvTp -> USD/rvYAfWj5gh67oV6fW32ZzP3Aw4Eubs59B 2013-Jun-29 08:02:53 OfferCreateTransactor:DBG OfferCreate: sfAccount=rNAAy9xnjuU6McAjVFtMyFbDNKzTXQ9wbV 2013-Jun-29 08:02:53 OfferCreateTransactor:DBG OfferCreate: uPaysIssuerID=rrrrrrrrrrrrrrrrrrrrrhoLvTp 2013-Jun-29 08:02:53 OfferCreateTransactor:DBG OfferCreate: uGetsIssuerID=rvYAfWj5gh67oV6fW32ZzP3Aw4Eubs59B 2013-Jun-29 08:02:53 OfferCreateTransactor:DBG OfferCreate: uPaysCurrency=XRP 2013-Jun-29 08:02:53 OfferCreateTransactor:DBG OfferCreate: uGetsCurrency=USD 2013-Jun-29 08:02:53 OfferCreateTransactor:NFO OfferCreate: final terResult=tesSUCCESS sleOffer= { "Account" : "rNAAy9xnjuU6McAjVFtMyFbDNKzTXQ9wbV", "BookDirectory" : "4627DFFCFF8B5A265EDBD8AE8C14A52325DBFEDAF4F5C32E5C181F7812066000", "BookNode" : "0000000000000000", "Flags" : 131072, "LedgerEntryType" : "Offer", "OwnerNode" : "0000000000000000", "PreviousTxnID" : "0000000000000000000000000000000000000000000000000000000000000000", "PreviousTxnLgrSeq" : 0, "Sequence" : 1285, "TakerGets" : { "currency" : "USD", "issuer" : "rvYAfWj5gh67oV6fW32ZzP3Aw4Eubs59B", "value" : "7.57" }, "TakerPays" : "514003000", "index" : "029EF22BD3034839A9676C0F8F35C87203BBE895E5979B2C3D4B23053CB2623A" }
2013-Jun-29 08:02:53 TransactionEngine:NFO applyTransaction: terResult=tesSUCCESS : 0 : The transaction was applied. 2013-Jun-29 08:02:53 TransactionEngine:NFO applyTransaction: taaCREATE: { 029EF22BD3034839A9676C0F8F35C87203BBE895E5979B2C3D4B23053CB2623A, {, , Offer, 131072, rNAAy9xnjuU6McAjVFtMyFbDNKzTX Q9wbV, 1285, 514003000, 7.57, 4627DFFCFF8B5A265EDBD8AE8C14A52325DBFEDAF4F5C32E5C181F7812066000, 0, 0, CC0FF27788BAAC227F9043DF2D6617C945DE601F7B010894421D86DF5251F11A, 1164497, } } 2013-Jun-29 08:02:53 TransactionEngine:NFO applyTransaction: taaMODIFY: { 0A016E805D09CD4BBC2E142BF047DB6EDB9F8ED2E713DEA2996E50BFE7EC4DBC, {, , DirectoryNode, 0, rNAAy9xnjuU6McAjVFtMyFbDNK zTXQ9wbV, , , , , , , 0A016E805D09CD4BBC2E142BF047DB6EDB9F8ED2E713DEA2996E50BFE7EC4DBC, , } } 2013-Jun-29 08:02:53 TransactionEngine:NFO applyTransaction: taaCREATE: { 4627DFFCFF8B5A265EDBD8AE8C14A52325DBFEDAF4F5C32E5C181F7812066000, {, , DirectoryNode, 0, , 000000000000000000000000 0000000000000000, 0000000000000000000000000000000000000000, 0000000000000000000000005553440000000000, 0A20B3C85F482532A9578DBB3950B85CA06594D1, 6636088651489370112, , 4627DFFCFF8B5A265EDBD8 AE8C14A52325DBFEDAF4F5C32E5C181F7812066000, , } } 2013-Jun-29 08:02:53 TransactionEngine:NFO applyTransaction: taaMODIFY: { 9F249BAF1A390202DBB788ACB584784BB4AD944BA7A35310F380AC3153AE6197, {, , AccountRoot, 0, rNAAy9xnjuU6McAjVFtMyFbDNKzT XQ9wbV, 1286, 13322306639, 11, CC0FF27788BAAC227F9043DF2D6617C945DE601F7B010894421D86DF5251F11A, 1164497, , , , , , , } } 2013-Jun-29 08:02:53 NetworkOPs:NFO Transaction is now included in open ledger 2013-Jun-29 08:02:53 ripple_LoadMonitor:NFO Job: LedgerMaster::asyncAccept ExecutionTime: 635 2013-Jun-29 08:02:54 NetworkOPs:DBG L: 441EE344F4707F34D7A489F9C2C5E429DD27BBDF651BCED9E80E89EEB9A3D0F7 t=4, n=2 2013-Jun-29 08:02:54 NetworkOPs:NFO Consensus time for ledger 1164497 2013-Jun-29 08:02:54 NetworkOPs:NFO LCL is 441EE344F4707F34D7A489F9C2C5E429DD27BBDF651BCED9E80E89EEB9A3D0F7 2013-Jun-29 08:02:54 ripple_LedgerConsensus:DBG Creating consensus object 2013-Jun-29 08:02:54 ripple_LedgerConsensus:NFO Entering consensus process, watching 2013-Jun-29 08:02:54 NetworkOPs:DBG Initiating consensus engine 2013-Jun-29 08:02:54 ripple_LedgerConsensus:NFO initial position F8140EEFD39CAA4DE488EE1AC5296076C39FE76A13D4B1DAE118B79F98834246 2013-Jun-29 08:02:54 ripple_LoadMonitor:NFO Job: LedgerMaster::asyncAccept ExecutionTime: 630 2013-Jun-29 08:02:55 ripple_Peer:DBG Got new transaction from peer 2013-Jun-29 08:02:55 OfferCreateTransactor:NFO OfferCreate: take against book: FAE6F85BC792DE5D6E89D97862792CE1D01F47A3DD18F0E20000000000000000 for 500000000/XRP -> 48.45/CNY/razqQKzJRdB4UxFPWf5NEpEG3WMkmwgcXA 2013-Jun-29 08:02:55 OfferCreateTransactor:DBG OfferCreate: takeOffers: BEFORE saTakerGets=500000000/XRP 2013-Jun-29 08:02:55 OfferCreateTransactor:DBG takeOffers: bSell: 1: against book: FAE6F85BC792DE5D6E89D97862792CE1D01F47A3DD18F0E20000000000000000 2013-Jun-29 08:02:55 ripple_TaggedCache:NFO LedgerCache is growing fast 298 of 32 aging at 3 of 30 2
I got a different backtrace, now in OfferCancelTransactor:
Thread 8 (Thread 804809000 (LWP 100256)):
bAdmin=false, bFailHard=false, callback=...) at ./src/cpp/ripple/NetworkOPs.cpp:337
at /usr/local/lib/gcc46/include/c++/tr1/functional:1153
Last log lines:
2013-Jul-05 09:40:17 OfferCancelTransactor:DBG OfferCancel: uAccountSequenceNext=147628 uOfferSequence=147147 2013-Jul-05 09:40:17 OfferCancelTransactor:WRN OfferCancel: uOfferSequence=147147 2013-Jul-05 09:40:17 TransactionEngine:NFO applyTransaction: terResult=tesSUCCESS : 0 : The transaction was applied. 2013-Jul-05 09:40:17 TransactionEngine:NFO applyTransaction: taaMODIFY: { 5B990836B96EE489F45FB62755479EAF0D80F17ECA627A07F1493ECD26634484, {, , DirectoryNode, 0, rLs4G81Z45GbNZgHXWHawuDogzWxAFCKW3, , , , , , , 135F93C06E0A230DBCF843DD10 E5DCE302C1EB2EF6E13DFF194CA9C8DB73232A, 442, 441} } 2013-Jul-05 09:40:17 TransactionEngine:NFO applyTransaction: taaMODIFY: { A0083FBE1409241D31E598D48915E59AD782690D816C5C13104E9D8BBF77BE95, {, , AccountRoot, 0, rLs4G81Z45GbNZgHXWHawuDogzWxAFCKW3, 147628, 209314575424, 113, B100B8619A652 6415C777C27ED71CCB949BDF3C87AF9E4B349C7AD3377FA681E, 1219003, , , , , , , } } 2013-Jul-05 09:40:17 TransactionEngine:NFO applyTransaction: taaDELETE: { D780BB63C22A1AF3EA6131BEDC53CB87512D4137AFB39A0B30B1E2F24142501A, {, , Offer, 0, rLs4G81Z45GbNZgHXWHawuDogzWxAFCKW3, 147147, 154.14138, 0.459, FE4D53B02BC5D46DE095 166E0667A0F3797F8A782F8A203B570BEE43DE15B000, 0, 441, BF856833F6ADF5813ED202FA224E4F812ED23FBC18F9D4ABADE584393F5801AF, 1214132, } } 2013-Jul-05 09:40:17 TransactionEngine:NFO applyTransaction: taaMODIFY: { DB7B89154788F6713C0A882A04141327C1641345F5D2F34337056ACB6A483556, {, , DirectoryNode, 0, rLs4G81Z45GbNZgHXWHawuDogzWxAFCKW3, , , , , , , 135F93C06E0A230DBCF843DD10 E5DCE302C1EB2EF6E13DFF194CA9C8DB73232A, 443, 441} } 2013-Jul-05 09:40:17 TransactionEngine:NFO applyTransaction: taaDELETE: { FE4D53B02BC5D46DE095166E0667A0F3797F8A782F8A203B570BEE43DE15B000, {, , DirectoryNode, 0, , 000000000000000000000000494C530000000000, 92D705968936C419CE614BF264B5EE B1CEA47FF4, 0000000000000000000000004254430000000000, 92D705968936C419CE614BF264B5EEB1CEA47FF4, 6272368881299730432, , FE4D53B02BC5D46DE095166E0667A0F3797F8A782F8A203B570BEE43DE15B000, , } } 2013-Jul-05 09:40:17 NetworkOPs:NFO Transaction is now included in open ledger 2013-Jul-05 09:40:17 OfferCancelTransactor:DBG OfferCancel: uAccountSequenceNext=99 uOfferSequence=93 2013-Jul-05 09:40:17 OfferCancelTransactor:WRN OfferCancel: uOfferSequence=93
Interesting. The basic failure is the same -- a node in the open ledger wasn't found in any of the caches or in the database. Needless to say, a server should always have available, at worst in the HashedObjectStore, every node in its own open ledger. Do we only see this on FreeBSD? It's really hard to imagine how this could be an OS-specific bug.
I have a crazy theory -- does this only happen early in the server's run time? Or can the server be stable for some time and then this happens?
I'm quite sure it's not FreeBSD specific...yesterday Vinnie was affected by this issue, I doubt he was running the server on FreeBSD, since the reported message was "getNodePointer() threw SHAMapMissingNode with a call stack that has no try".
Another different backtrace for the same assertion:
Thread 8 (Thread 804809000 (LWP 100308)):
bAdmin=false, bFailHard=false, callback=...) at ./src/cpp/ripple/NetworkOPs.cpp:337
at /usr/local/lib/gcc46/include/c++/tr1/functional:1153
Another one..just to confirm that is not "always the OfferCreateTransactor calling LES::getNextLedgerIndex calling SHAMap::getStack":
Thread 8 (Thread 804809000 (LWP 100325)):
at ./src/cpp/ripple/ripple_LedgerEntrySet.cpp:1438
at ./src/cpp/ripple/ripple_LedgerEntrySet.cpp:1567
sleTakerAccount=..., saTakerPays=..., saTakerGets=..., saTakerPaid=..., saTakerGot=..., bUnfunded=@0x7fffff5f430f: false) at ./src/cpp/ripple/OfferCreateTransactor.cpp:316
didApply=@0x7fffff5fa23f: false) at ./src/cpp/ripple/TransactionEngine.cpp:102
at ./src/cpp/ripple/LedgerMaster.cpp:170
this=0x8053d9bb0, __args=...) at /usr/local/lib/gcc46/include/c++/tr1/functional:1153
at /usr/local/lib/gcc46/include/c++/tr1/functional:1207
__functor=..., __args#0=...) at /usr/local/lib/gcc46/include/c++/tr1/functional:1684
at /usr/local/lib/gcc46/include/c++/tr1/functional:2040
...
Take a look at this stack trace, from one of our client servers running Linux:
at ./src/cpp/ripple/ripple_SHAMap.cpp:236
at ./src/cpp/ripple/ripple_SHAMapDelta.cpp:141
at ./src/cpp/ripple/ripple_LedgerConsensus.cpp:290
at ./src/cpp/ripple/ripple_LedgerConsensus.cpp:350
at ./src/cpp/ripple/NetworkOPs.cpp:1068
So it looks like this is a bug that causes nodes to somehow disappear or the server to think a map is complete when it's missing nodes. This particular one is interested because it occurred in proposed transaction sets. This rules out the ledger logic (these maps aren't part of a ledger) or database corruption (these maps aren't stored in the database).
Bug still present in 0.12.0 release:
Thread 16 (Thread 804807400 (LWP 100079)):
at modules/ripple_app/tx/TransactionEngine.cpp:168
at modules/ripple_app/consensus/ripple_LedgerConsensus.cpp:1069
at modules/ripple_app/consensus/ripple_LedgerConsensus.cpp:1116
...
How long had the server been running when this happened? What platform? What database back end?
This is strange. We're applying transactions to a ledger because a consensus round completed. The transaction requires deleting a node from the state SHAMap tree. When we go to find other nodes that have a common parent to this node's parent, and we encounter a missing node. Of course, we're not supposed to use a ledger for the consensus process unless we have every node in it in the local database.
This could be caused by something bizarre like a hardware problem or compiler bug, but that seems unlikely. Also possible is a bug in the database back end. If it was a logic error in the SHAMap code, we'd expect lots of servers to crash at once, so I don't think it's that, but logic errors in the node caching or ledger acceptance code could do it.
Well, this is the full history, yesterday I had two SHAMapMissingNode exceptions:
Related to node caching, both HyperLevelDB and MDB use BatchWriter to write NodeObjects asynchronously. Could it happen that the TaggedCache already purged the NodeObject while the backend has not persisted it yet?
No. The TaggedCache can't purge an object while the BatchWriter holds a reference to it, and the BatchWriter won't discard its reference until the write is complete.
For the records, I got another one, with 0.12.0 release running for ~3 days starting from a clean HyperLevelDB, so we can exclude db corruption at shutdown.
at modules/ripple_app/tx/TransactionEngine.cpp:102
at modules/ripple_app/consensus/ripple_LedgerConsensus.cpp:1069
at modules/ripple_app/consensus/ripple_LedgerConsensus.cpp:1116
Can we close this?
Yes, but I don't seem to have the close button anymore.
yes, you can close it, thanks!
ÔÚ 2013-12-19 03:28:02£¬"Vinnie Falco" notifications@github.com дµÀ£º
Can we close this?
¡ª Reply to this email directly or view it on GitHub.
During execution, the server experiences a crash. There is a core dump, followed by the Sustain process restarting the server.