[bisq-network/bisq] 2nd SEPA trade in a row always times out (#2301)

Sylo44 notifications at github.com
Mon Jan 21 09:56:02 UTC 2019


http://prntscr.com/m9zia5

This loading screen takes for ever, before the timeout error:

Here is another clean .log from start to finish

#################################################################
Connected to node: jhgcy2won7xnslrb.onion:8000
Received 459 instances
Filter: 1
MailboxStoragePayload: 169
Alert: 1
Arbitrator: 2
OfferPayload: 286
################################################################# 
Jan.-21 10:51:23.976 [JavaFX Application Thread] INFO  b.n.p.p.g.RequestDataHandler: Start processing 0 items. 
Jan.-21 10:51:24.183 [JavaFX Application Thread] INFO  b.d.m.o.t.TakeOfferDataModel: Start requestTxFee: txFeeFromFeeService=3200 
Jan.-21 10:51:24.207 [JavaFX Application Thread] INFO  b.c.t.TaskRunner: Run task: SendOfferAvailabilityRequest 
Jan.-21 10:51:24.208 [JavaFX Application Thread] INFO  b.c.o.a.t.SendOfferAvailabilityRequest: Send OfferAvailabilityRequest with offerId xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093 and uid bdea3bf6-8052-48a1-9ab0-178f13de3045 to peer 2b4mxjclbcs6eqvv.onion:9999 
Jan.-21 10:51:24.498 [JavaFX Application Thread] INFO  b.d.m.o.t.TakeOfferDataModel: Completed requestTxFee: txFeeFromFeeService=3200 
Jan.-21 10:51:27.543 [JavaFX Application Thread] INFO  b.n.p.p.PeerManager: Num of latestLivePeers=9 
Jan.-21 10:51:32.766 [JavaFX Application Thread] INFO  b.c.o.a.t.SendOfferAvailabilityRequest: OfferAvailabilityRequest arrived at peer: offerId=xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093; uid=bdea3bf6-8052-48a1-9ab0-178f13de3045 
Jan.-21 10:51:33.931 [JavaFX Application Thread] INFO  b.c.o.a.OfferAvailabilityProtocol: Received handleOfferAvailabilityResponse from 2b4mxjclbcs6eqvv.onion:9999 with offerId xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093 and uid 0dee1bae-fb47-47c8-bf50-a2186c488d45 
Jan.-21 10:51:33.933 [JavaFX Application Thread] INFO  b.c.t.TaskRunner: Run task: ProcessOfferAvailabilityResponse 
Jan.-21 10:51:33.935 [JavaFX Application Thread] INFO  b.c.o.a.OfferAvailabilityProtocol: Send AckMessage for OfferAvailabilityResponse to peer 2b4mxjclbcs6eqvv.onion:9999 with offerId xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093 and sourceUid 0dee1bae-fb47-47c8-bf50-a2186c488d45 
Jan.-21 10:51:33.937 [JavaFX Application Thread] INFO  b.n.p.p.PeerManager: Num of latestLivePeers=11 
Jan.-21 10:51:33.969 [JavaFX Application Thread] INFO  b.c.o.a.OfferAvailabilityProtocol: AckMessage for OfferAvailabilityResponse arrived at makersNodeAddress 2b4mxjclbcs6eqvv.onion:9999. offerId=xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093, sourceUid=0dee1bae-fb47-47c8-bf50-a2186c488d45 
Jan.-21 10:51:34.158 [JavaFX Application Thread] INFO  b.c.o.OpenOfferManager: Received AckMessage for OfferAvailabilityRequest with offerId xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093 and uid bdea3bf6-8052-48a1-9ab0-178f13de3045 
Jan.-21 10:51:35.519 [JavaFX Application Thread] INFO  b.n.p.p.PeerManager: We have 14 connections open. Our limit is 12 
Jan.-21 10:51:35.519 [JavaFX Application Thread] INFO  b.n.p.p.PeerManager: We have too many connections open. Lets try first to remove the inbound connections of type PEER. 
Jan.-21 10:51:35.520 [JavaFX Application Thread] INFO  b.n.p.p.PeerManager: checkMaxConnections: Num candidates for shut down=0. We close oldest connection: Connection{peerAddress=Optional[f6oryfrhhj4tol27.onion:9999], peerType=PEER, uid='3b841623-736d-4449-bbbf-9a0482bf5e1c'} 
Jan.-21 10:51:35.528 [InputHandler-f6oryfrhhj4tol27.onion:9999] INFO  b.n.p.n.Connection$SharedModel: SocketException (expected if connection lost). closeConnectionReason=CloseConnectionReason{sendCloseMessage=false, isIntended=false} SOCKET_CLOSED; connection=Connection{peerAddress=Optional[f6oryfrhhj4tol27.onion:9999], peerType=PEER, uid='3b841623-736d-4449-bbbf-9a0482bf5e1c'} 
Jan.-21 10:51:35.528 [InputHandler-rm7b56wbrcczpjvl.onion:8000] INFO  b.n.p.n.Connection$SharedModel: SocketException (expected if connection lost). closeConnectionReason=CloseConnectionReason{sendCloseMessage=false, isIntended=false} RESET; connection=Connection{peerAddress=Optional[rm7b56wbrcczpjvl.onion:8000], peerType=SEED_NODE, uid='795dc862-5e42-4abf-853a-26a888ef779e'} 
Jan.-21 10:51:35.733 [JavaFX Application Thread] INFO  b.n.p.p.PeerManager: onDisconnect called: nodeAddress=Optional[rm7b56wbrcczpjvl.onion:8000], closeConnectionReason=CloseConnectionReason{sendCloseMessage=true, isIntended=true} TOO_MANY_SEED_NODES_CONNECTED 
Jan.-21 10:51:35.737 [JavaFX Application Thread] INFO  b.n.p.p.p.PeerExchangeManager: onDisconnect closeConnectionReason=CloseConnectionReason{sendCloseMessage=true, isIntended=true} TOO_MANY_SEED_NODES_CONNECTED, nodeAddressOpt=Optional[rm7b56wbrcczpjvl.onion:8000] 
Jan.-21 10:51:35.754 [JavaFX Application Thread] INFO  b.n.p.p.PeerManager: onDisconnect called: nodeAddress=Optional[f6oryfrhhj4tol27.onion:9999], closeConnectionReason=CloseConnectionReason{sendCloseMessage=true, isIntended=true} TOO_MANY_CONNECTIONS_OPEN 
Jan.-21 10:51:35.756 [JavaFX Application Thread] INFO  b.n.p.p.p.PeerExchangeManager: onDisconnect closeConnectionReason=CloseConnectionReason{sendCloseMessage=true, isIntended=true} TOO_MANY_CONNECTIONS_OPEN, nodeAddressOpt=Optional[f6oryfrhhj4tol27.onion:9999] 
Jan.-21 10:51:35.928 [JavaFX Application Thread] INFO  b.n.p.p.PeerManager: We have 12 connections open. Our limit is 12 
Jan.-21 10:51:42.801 [JavaFX Application Thread] INFO  o.b.w.WalletFiles: Saving wallet; last seen block is height 559442, date 2019-01-21T09:50:04Z, hash 0000000000000000000cce3e0438cce741cf67855e0899d1cccfdfae2faff396 
Jan.-21 10:51:42.978 [JavaFX Application Thread] INFO  o.b.w.WalletFiles: Save completed in 176.1 ms 
Jan.-21 10:51:43.079 [JavaFX Application Thread] INFO  o.b.w.WalletFiles: Saving wallet; last seen block is height 559442, date 2019-01-21T09:50:04Z, hash 0000000000000000000cce3e0438cce741cf67855e0899d1cccfdfae2faff396 
Jan.-21 10:51:43.277 [JavaFX Application Thread] INFO  o.b.w.WalletFiles: Save completed in 199.1 ms 
Jan.-21 10:51:43.284 [JavaFX Application Thread] INFO  b.c.b.w.TradeWalletService: estimateBtcTradingFeeTxSize 
Jan.-21 10:51:43.285 [JavaFX Application Thread] INFO  o.b.w.Wallet: Completing send tx with 2 outputs totalling 0.0105137 BTC and a fee of 0.00 BTC/kB 
Jan.-21 10:51:43.287 [JavaFX Application Thread] INFO  o.b.w.Wallet:   with 0.0789092 BTC change 
Jan.-21 10:51:43.309 [JavaFX Application Thread] INFO  o.b.w.Wallet:   with a fee of 0.00012307 BTC/kB, 0.000032 BTC for 260 bytes 
Jan.-21 10:51:43.311 [JavaFX Application Thread] INFO  o.b.w.Wallet:   completed:   3c876ab84e42d953d25eb2c0aba6cd4e22e9768566ae4374427981c11d5cd8c7
     in   PUSHDATA(72)[3045022100cc7797b7f4f229f1f2907921d471a39f4d89a2e8d13dfc201a9968c6a4de5a37022066836152e50ef108b314b4f41a2d50a3d158189d250bed4904ac746d3f680ea401] PUSHDATA(33)[029f15b0ae97198500de2f1fd5ad887bfed685716ac4ce4526977270de46321a12] 0.0894549 BTC (8945490)
          outpoint:419b748587512dfa295f7bd8ee94347016f5a7be8760b79ffa11d16a7d327321:2 hash160:c7aba5ea9cfa2c81255fa6485cfbcdcf2f642ebc
     out  DUP HASH160 PUSHDATA(20)[f6876dfc59041e46bdaece49428676dd9e5b3226] EQUALVERIFY CHECKSIG 0.0004497 BTC (44970) ScriptPubKey: 76a914f6876dfc59041e46bdaece49428676dd9e5b322688ac Address:1PUXU1MQ82JC3Hx1NN5tZs3BaTAJVg72MC 
     out  DUP HASH160 PUSHDATA(20)[5363b762cbbd616e948cd6fdabeb3fcddbe68435] EQUALVERIFY CHECKSIG 0.010064 BTC (1006400) ScriptPubKey: 76a9145363b762cbbd616e948cd6fdabeb3fcddbe6843588ac Address:18bvYLTXs7aSuYwsK1RApmCgPEu9uLAuUT 
     out  DUP HASH160 PUSHDATA(20)[fe43016368f33def86672e0ecaa9c0a18528dddf] EQUALVERIFY CHECKSIG 0.0789092 BTC (7890920) ScriptPubKey: 76a914fe43016368f33def86672e0ecaa9c0a18528dddf88ac Address:1QBQuLv1W5bRcuL6vTUZ8tSj8WmvWjAWDi 
     fee  0.000032 BTC for 260 bytes (12 Satoshi/Byte)
     prps USER_PAYMENT
 
Jan.-21 10:51:43.313 [JavaFX Application Thread] INFO  b.d.m.o.t.TakeOfferDataModel: Fee estimation completed:
txFee based on estimated size of 320 bytes. Average tx size = 320 bytes. Actual tx size = 260 bytes. TxFee is 0.000032 BTC (10 sat/byte) 
Jan.-21 10:51:44.507 [BlockingClient network thread for vlf5i3grro3wux24.onion:8333] INFO  o.b.c.PeerGroup: [vlf5i3grro3wux24.onion]:8333: Peer died      (7 connected, 1 pending, 9 max) 
Jan.-21 10:51:44.625 [PeerGroup Thread] INFO  o.b.c.PeerGroup: Waiting 885 msec before next connect attempt to [z33nukt7ngik3cpe.onion]:8333 
Jan.-21 10:51:45.670 [PeerGroup Thread] INFO  o.b.c.PeerGroup: Attempting connection to [i3a5xtzfm4xwtybd.onion]:8333     (7 connected, 2 pending, 9 max) 
Jan.-21 10:51:49.225 [BlockingClient network thread for i3a5xtzfm4xwtybd.onion:8333] INFO  o.b.core.Peer: Announcing to i3a5xtzfm4xwtybd.onion:8333 as: /bitcoinj:0.14.4.11/Bisq:0.9.3/ 
Jan.-21 10:51:49.632 [BlockingClient network thread for i3a5xtzfm4xwtybd.onion:8333] INFO  o.b.core.Peer: [i3a5xtzfm4xwtybd.onion]:8333: Got version=70015, subVer='/Satoshi:0.17.0/', services=0x1037, time=2019-01-21 10:51:49, blocks=559442 
Jan.-21 10:51:49.633 [BlockingClient network thread for i3a5xtzfm4xwtybd.onion:8333] INFO  o.b.c.PeerGroup: [i3a5xtzfm4xwtybd.onion]:8333: New peer      (8 connected, 1 pending, 9 max) 
Jan.-21 10:51:52.904 [JavaFX Application Thread] INFO  b.c.p.p.PriceFeedService: request from provider http://62nvujg5iou3vu3i.onion/ 66.84 sec. after last request 
Jan.-21 10:51:54.177 [JavaFX Application Thread] INFO  b.c.p.p.PriceFeedService: Received new MarketPrice(currencyCode=EUR, price=3115.57, timestampSec=1548064371711, isExternallyProvidedPrice=true) from provider http://44mgyoe2b6oqiytt.onion/ after 1.272 sec. 
Jan.-21 10:52:07.186 [JavaFX Application Thread] INFO  b.c.t.TaskRunner: Run task: SendOfferAvailabilityRequest 
Jan.-21 10:52:07.187 [JavaFX Application Thread] INFO  b.c.o.a.t.SendOfferAvailabilityRequest: Send OfferAvailabilityRequest with offerId xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093 and uid a7bdc4d6-0a7b-4a63-be83-47e11725ab94 to peer 2b4mxjclbcs6eqvv.onion:9999 
Jan.-21 10:52:07.269 [JavaFX Application Thread] INFO  b.c.o.a.t.SendOfferAvailabilityRequest: OfferAvailabilityRequest arrived at peer: offerId=xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093; uid=a7bdc4d6-0a7b-4a63-be83-47e11725ab94 
Jan.-21 10:52:08.307 [JavaFX Application Thread] INFO  b.c.o.a.OfferAvailabilityProtocol: Received handleOfferAvailabilityResponse from 2b4mxjclbcs6eqvv.onion:9999 with offerId xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093 and uid 931611fb-43e3-4030-9231-43d6ae1e577d 
Jan.-21 10:52:08.308 [JavaFX Application Thread] INFO  b.c.t.TaskRunner: Run task: ProcessOfferAvailabilityResponse 
Jan.-21 10:52:08.309 [JavaFX Application Thread] INFO  b.c.o.a.OfferAvailabilityProtocol: Send AckMessage for OfferAvailabilityResponse to peer 2b4mxjclbcs6eqvv.onion:9999 with offerId xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093 and sourceUid 931611fb-43e3-4030-9231-43d6ae1e577d 
Jan.-21 10:52:08.318 [JavaFX Application Thread] INFO  b.c.n.a.TradeEvents: We got a new trade. id=xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093 
Jan.-21 10:52:08.327 [JavaFX Application Thread] INFO  b.c.t.TaskRunner: Run task: TakerSelectMediator 
Jan.-21 10:52:08.331 [JavaFX Application Thread] INFO  b.c.t.TaskRunner: Run task: TakerVerifyMakerAccount 
Jan.-21 10:52:08.331 [JavaFX Application Thread] INFO  b.c.t.TaskRunner: Run task: TakerVerifyMakerFeePayment 
Jan.-21 10:52:08.332 [JavaFX Application Thread] INFO  b.c.t.TaskRunner: Run task: CreateTakerFeeTx 
Jan.-21 10:52:08.558 [JavaFX Application Thread] INFO  b.c.b.w.TradeWalletService: reservedFundsForOffer 0.010064 
Jan.-21 10:52:08.559 [JavaFX Application Thread] INFO  b.c.b.w.TradeWalletService: tradingFee 0.0004497 
Jan.-21 10:52:08.559 [JavaFX Application Thread] INFO  b.c.b.w.TradeWalletService: txFee 0.000032 
Jan.-21 10:52:08.560 [JavaFX Application Thread] INFO  o.b.w.Wallet: Completing send tx with 2 outputs totalling 0.0105137 BTC and a fee of 0.00 BTC/kB 
Jan.-21 10:52:08.561 [JavaFX Application Thread] INFO  o.b.w.Wallet:   with 0.0789092 BTC change 
Jan.-21 10:52:08.565 [JavaFX Application Thread] INFO  o.b.w.Wallet:   with a fee of 0.00012307 BTC/kB, 0.000032 BTC for 260 bytes 
Jan.-21 10:52:08.565 [JavaFX Application Thread] INFO  o.b.w.Wallet:   completed:   3c876ab84e42d953d25eb2c0aba6cd4e22e9768566ae4374427981c11d5cd8c7
     in   PUSHDATA(72)[3045022100cc7797b7f4f229f1f2907921d471a39f4d89a2e8d13dfc201a9968c6a4de5a37022066836152e50ef108b314b4f41a2d50a3d158189d250bed4904ac746d3f680ea401] PUSHDATA(33)[029f15b0ae97198500de2f1fd5ad887bfed685716ac4ce4526977270de46321a12] 0.0894549 BTC (8945490)
          outpoint:419b748587512dfa295f7bd8ee94347016f5a7be8760b79ffa11d16a7d327321:2 hash160:c7aba5ea9cfa2c81255fa6485cfbcdcf2f642ebc
     out  DUP HASH160 PUSHDATA(20)[f6876dfc59041e46bdaece49428676dd9e5b3226] EQUALVERIFY CHECKSIG 0.0004497 BTC (44970) ScriptPubKey: 76a914f6876dfc59041e46bdaece49428676dd9e5b322688ac Address:1PUXU1MQ82JC3Hx1NN5tZs3BaTAJVg72MC 
     out  DUP HASH160 PUSHDATA(20)[5363b762cbbd616e948cd6fdabeb3fcddbe68435] EQUALVERIFY CHECKSIG 0.010064 BTC (1006400) ScriptPubKey: 76a9145363b762cbbd616e948cd6fdabeb3fcddbe6843588ac Address:18bvYLTXs7aSuYwsK1RApmCgPEu9uLAuUT 
     out  DUP HASH160 PUSHDATA(20)[fe43016368f33def86672e0ecaa9c0a18528dddf] EQUALVERIFY CHECKSIG 0.0789092 BTC (7890920) ScriptPubKey: 76a914fe43016368f33def86672e0ecaa9c0a18528dddf88ac Address:1QBQuLv1W5bRcuL6vTUZ8tSj8WmvWjAWDi 
     fee  0.000032 BTC for 260 bytes (12 Satoshi/Byte)
     prps USER_PAYMENT
 
Jan.-21 10:52:08.567 [JavaFX Application Thread] INFO  b.c.b.w.WalletService: 
tradingFeeTx:
  3c876ab84e42d953d25eb2c0aba6cd4e22e9768566ae4374427981c11d5cd8c7
     in   PUSHDATA(72)[3045022100cc7797b7f4f229f1f2907921d471a39f4d89a2e8d13dfc201a9968c6a4de5a37022066836152e50ef108b314b4f41a2d50a3d158189d250bed4904ac746d3f680ea401] PUSHDATA(33)[029f15b0ae97198500de2f1fd5ad887bfed685716ac4ce4526977270de46321a12] 0.0894549 BTC (8945490)
          outpoint:419b748587512dfa295f7bd8ee94347016f5a7be8760b79ffa11d16a7d327321:2 hash160:c7aba5ea9cfa2c81255fa6485cfbcdcf2f642ebc
     out  DUP HASH160 PUSHDATA(20)[f6876dfc59041e46bdaece49428676dd9e5b3226] EQUALVERIFY CHECKSIG 0.0004497 BTC (44970) ScriptPubKey: 76a914f6876dfc59041e46bdaece49428676dd9e5b322688ac Address:1PUXU1MQ82JC3Hx1NN5tZs3BaTAJVg72MC 
     out  DUP HASH160 PUSHDATA(20)[5363b762cbbd616e948cd6fdabeb3fcddbe68435] EQUALVERIFY CHECKSIG 0.010064 BTC (1006400) ScriptPubKey: 76a9145363b762cbbd616e948cd6fdabeb3fcddbe6843588ac Address:18bvYLTXs7aSuYwsK1RApmCgPEu9uLAuUT 
     out  DUP HASH160 PUSHDATA(20)[fe43016368f33def86672e0ecaa9c0a18528dddf] EQUALVERIFY CHECKSIG 0.0789092 BTC (7890920) ScriptPubKey: 76a914fe43016368f33def86672e0ecaa9c0a18528dddf88ac Address:1QBQuLv1W5bRcuL6vTUZ8tSj8WmvWjAWDi 
     fee  0.000032 BTC for 260 bytes (12 Satoshi/Byte)
     prps USER_PAYMENT
 
Jan.-21 10:52:08.570 [JavaFX Application Thread] INFO  o.b.c.TransactionBroadcast: Waiting for 4 peers required for broadcast, we have 8 ... 
Jan.-21 10:52:08.571 [JavaFX Application Thread] INFO  o.b.c.TransactionBroadcast: broadcastTransaction: We have 8 peers, adding 3c876ab84e42d953d25eb2c0aba6cd4e22e9768566ae4374427981c11d5cd8c7 to the memory pool 
Jan.-21 10:52:08.572 [JavaFX Application Thread] INFO  o.b.c.TransactionBroadcast: Sending to 4 peers, will wait for 2, sending to: [c6ac4jdfyeiakex2.onion]:8333,[sjyzmwwu6diiit3r.onion]:8333,[3r44ddzjitznyahw.onion]:8333,[i3a5xtzfm4xwtybd.onion]:8333 
Jan.-21 10:52:08.583 [JavaFX Application Thread] INFO  b.c.o.a.OfferAvailabilityProtocol: AckMessage for OfferAvailabilityResponse arrived at makersNodeAddress 2b4mxjclbcs6eqvv.onion:9999. offerId=xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093, sourceUid=931611fb-43e3-4030-9231-43d6ae1e577d 
Jan.-21 10:52:08.594 [JavaFX Application Thread] INFO  b.c.o.OpenOfferManager: Received AckMessage for OfferAvailabilityRequest with offerId xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093 and uid a7bdc4d6-0a7b-4a63-be83-47e11725ab94 
Jan.-21 10:52:12.629 [BlockingClient network thread for fz6nsij6jiyuwlsc.onion:8333] INFO  o.b.core.Peer: [fz6nsij6jiyuwlsc.onion]:8333: Downloading dependencies of 3c876ab84e42d953d25eb2c0aba6cd4e22e9768566ae4374427981c11d5cd8c7 
Jan.-21 10:52:13.029 [BlockingClient network thread for fz6nsij6jiyuwlsc.onion:8333] INFO  o.b.core.Peer: [fz6nsij6jiyuwlsc.onion]:8333: Bottomed out dep tree at 419b748587512dfa295f7bd8ee94347016f5a7be8760b79ffa11d16a7d327321 
Jan.-21 10:52:13.031 [BlockingClient network thread for fz6nsij6jiyuwlsc.onion:8333] INFO  o.b.core.Peer: [fz6nsij6jiyuwlsc.onion]:8333: Dependency download complete! 
Jan.-21 10:52:13.036 [BlockingClient network thread for fz6nsij6jiyuwlsc.onion:8333] INFO  o.b.w.Wallet: Received a pending transaction 3c876ab84e42d953d25eb2c0aba6cd4e22e9768566ae4374427981c11d5cd8c7 that spends 0.0894549 BTC from our own wallet, and sends us 0.0889732 BTC 
Jan.-21 10:52:13.037 [BlockingClient network thread for fz6nsij6jiyuwlsc.onion:8333] INFO  o.b.w.Wallet: commitTx of 3c876ab84e42d953d25eb2c0aba6cd4e22e9768566ae4374427981c11d5cd8c7 
Jan.-21 10:52:13.037 [BlockingClient network thread for fz6nsij6jiyuwlsc.onion:8333] INFO  o.b.w.Wallet:   marked 419b748587512dfa295f7bd8ee94347016f5a7be8760b79ffa11d16a7d327321:2 as spent by 3c876ab84e42d953d25eb2c0aba6cd4e22e9768566ae4374427981c11d5cd8c7 
Jan.-21 10:52:13.038 [BlockingClient network thread for fz6nsij6jiyuwlsc.onion:8333] INFO  o.b.w.Wallet:   419b748587512dfa295f7bd8ee94347016f5a7be8760b79ffa11d16a7d327321 prevtx <-unspent ->spent 
Jan.-21 10:52:13.038 [BlockingClient network thread for fz6nsij6jiyuwlsc.onion:8333] INFO  o.b.w.Wallet: ->pending: 3c876ab84e42d953d25eb2c0aba6cd4e22e9768566ae4374427981c11d5cd8c7 
Jan.-21 10:52:13.039 [BlockingClient network thread for fz6nsij6jiyuwlsc.onion:8333] INFO  o.b.w.Wallet: Estimated balance is now: 0.0992497 BTC 
Jan.-21 10:52:13.048 [BlockingClient network thread for fz6nsij6jiyuwlsc.onion:8333] INFO  o.b.w.WalletFiles: Saving wallet; last seen block is height 559442, date 2019-01-21T09:50:04Z, hash 0000000000000000000cce3e0438cce741cf67855e0899d1cccfdfae2faff396 
Jan.-21 10:52:13.340 [BlockingClient network thread for fz6nsij6jiyuwlsc.onion:8333] INFO  o.b.w.WalletFiles: Save completed in 291.0 ms 
Jan.-21 10:52:32.591 [BlockingClient network thread for r3dsojfhwcm7x7p6.onion:8333] WARN  o.b.n.BlockingClient: Error trying to open/read from connection: r3dsojfhwcm7x7p6.onion:8333: SOCKS: TTL expired 
Jan.-21 10:52:32.592 [BlockingClient network thread for r3dsojfhwcm7x7p6.onion:8333] INFO  o.b.c.PeerGroup: [r3dsojfhwcm7x7p6.onion]:8333: Peer died      (8 connected, 0 pending, 9 max) 
Jan.-21 10:52:32.594 [PeerGroup Thread] INFO  o.b.c.PeerGroup: Waiting 1000 msec before next connect attempt to [z33nukt7ngik3cpe.onion]:8333 
Jan.-21 10:52:33.121 [AbstractTimeoutHandler timeouts] INFO  o.b.c.PeerSocketHandler: [r3dsojfhwcm7x7p6.onion]:8333: Timed out 
Jan.-21 10:52:33.125 [AbstractTimeoutHandler timeouts] INFO  o.b.c.PeerGroup: [r3dsojfhwcm7x7p6.onion]:8333: Peer died      (8 connected, 0 pending, 9 max) 
Jan.-21 10:52:33.125 [AbstractTimeoutHandler timeouts] INFO  o.b.c.PeerSocketHandler: [vlf5i3grro3wux24.onion]:8333: Timed out 
Jan.-21 10:52:33.128 [PeerGroup Thread] INFO  o.b.c.PeerGroup: Waiting 1497 msec before next connect attempt to [sslnjjhnmwllysv4.onion]:8333 
Jan.-21 10:52:33.665 [PeerGroup Thread] INFO  o.b.c.PeerGroup: Waiting 960 msec before next connect attempt to [z33nukt7ngik3cpe.onion]:8333 
Jan.-21 10:52:34.729 [PeerGroup Thread] INFO  o.b.c.PeerGroup: Attempting connection to [sslnjjhnmwllysv4.onion]:8333     (8 connected, 1 pending, 9 max) 
Jan.-21 10:52:39.051 [BlockingClient network thread for sslnjjhnmwllysv4.onion:8333] INFO  o.b.core.Peer: Announcing to sslnjjhnmwllysv4.onion:8333 as: /bitcoinj:0.14.4.11/Bisq:0.9.3/ 
Jan.-21 10:52:39.390 [BlockingClient network thread for sslnjjhnmwllysv4.onion:8333] INFO  o.b.core.Peer: [sslnjjhnmwllysv4.onion]:8333: Got version=70015, subVer='/Satoshi:0.17.0.1/', services=0x1037, time=2019-01-21 10:52:29, blocks=559442 
Jan.-21 10:52:39.391 [BlockingClient network thread for sslnjjhnmwllysv4.onion:8333] INFO  o.b.c.PeerGroup: [sslnjjhnmwllysv4.onion]:8333: New peer      (9 connected, 0 pending, 9 max) 
Jan.-21 10:52:39.459 [JavaFX Application Thread] WARN  b.c.b.w.TxBroadcaster: Broadcast of tx 3c876ab84e42d953d25eb2c0aba6cd4e22e9768566ae4374427981c11d5cd8c7 not completed after 30 sec. 
Jan.-21 10:52:40.092 [JavaFX Application Thread] WARN  b.c.b.w.TxBroadcaster: TxBroadcaster.onTimeout called: TxBroadcastTimeoutException{
     localTx=  3c876ab84e42d953d25eb2c0aba6cd4e22e9768566ae4374427981c11d5cd8c7
     in   PUSHDATA(72)[3045022100cc7797b7f4f229f1f2907921d471a39f4d89a2e8d13dfc201a9968c6a4de5a37022066836152e50ef108b314b4f41a2d50a3d158189d250bed4904ac746d3f680ea401] PUSHDATA(33)[029f15b0ae97198500de2f1fd5ad887bfed685716ac4ce4526977270de46321a12] 0.0894549 BTC (8945490)
          outpoint:419b748587512dfa295f7bd8ee94347016f5a7be8760b79ffa11d16a7d327321:2 hash160:c7aba5ea9cfa2c81255fa6485cfbcdcf2f642ebc
     out  DUP HASH160 PUSHDATA(20)[f6876dfc59041e46bdaece49428676dd9e5b3226] EQUALVERIFY CHECKSIG 0.0004497 BTC (44970) ScriptPubKey: 76a914f6876dfc59041e46bdaece49428676dd9e5b322688ac Address:1PUXU1MQ82JC3Hx1NN5tZs3BaTAJVg72MC 
     out  DUP HASH160 PUSHDATA(20)[5363b762cbbd616e948cd6fdabeb3fcddbe68435] EQUALVERIFY CHECKSIG 0.010064 BTC (1006400) ScriptPubKey: 76a9145363b762cbbd616e948cd6fdabeb3fcddbe6843588ac Address:18bvYLTXs7aSuYwsK1RApmCgPEu9uLAuUT 
     out  DUP HASH160 PUSHDATA(20)[fe43016368f33def86672e0ecaa9c0a18528dddf] EQUALVERIFY CHECKSIG 0.0789092 BTC (7890920) ScriptPubKey: 76a914fe43016368f33def86672e0ecaa9c0a18528dddf88ac Address:1QBQuLv1W5bRcuL6vTUZ8tSj8WmvWjAWDi 
     fee  0.000032 BTC for 260 bytes (12 Satoshi/Byte)
     prps USER_PAYMENT
,
     delay=30
} TxBroadcastException{
     txId='null'
} bisq.core.btc.exceptions.TxBroadcastTimeoutException: The transaction was not broadcasted in 30seconds. txId=3c876ab84e42d953d25eb2c0aba6cd4e22e9768566ae4374427981c11d5cd8c7 
We optimistically assume that the tx broadcast succeeds later and call onSuccess on the callback handler. This behaviour carries less potential problems than if we would trigger a failure (e.g. which would cause a failed create offer attempt of failed take offer attempt).
We have no guarantee how long it will take to get the information that sufficiently many BTC nodes have reported back to BitcoinJ that the tx is in their mempool.
In normal situations that's very fast but in some cases it can take minutes (mostly related to Tor connection issues). So if we just go on in the application logic and treat it as successful and the tx will be broadcast successfully later all is fine.
If it will fail to get broadcast, it will lead to a failure state, the same as if we would trigger a failure due the timeout.So we can assume that this behaviour will lead to less problems as otherwise.
Long term we should implement better monitoring for Tor and the provided Bitcoin nodes to find out why those delays happen and add some rollback behaviour to the app state in case the tx will never get broadcast. 
Jan.-21 10:52:40.093 [JavaFX Application Thread] INFO  b.c.b.w.BtcWalletService: swap addressEntry with address 1CPckrV9wmr7seXYSMn8H4aC9fQxQ6MgsK and offerId xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093 from context OFFER_FUNDING to available 
Jan.-21 10:52:40.094 [JavaFX Application Thread] INFO  b.c.trade.Trade: Set new state at BuyerAsTakerTrade (id=xpraf): TAKER_PUBLISHED_TAKER_FEE_TX 
Jan.-21 10:52:40.098 [JavaFX Application Thread] INFO  b.c.t.TaskRunner: Run task: BuyerAsTakerCreatesDepositTxInputs 
Jan.-21 10:52:40.196 [JavaFX Application Thread] INFO  o.b.w.Wallet: Completing send tx with 1 outputs totalling 0.010032 BTC and a fee of 0.00 BTC/kB 
Jan.-21 10:52:40.206 [JavaFX Application Thread] INFO  o.b.w.Wallet:   with a fee of 0.00016666 BTC/kB, 0.000032 BTC for 192 bytes 
Jan.-21 10:52:40.208 [JavaFX Application Thread] INFO  o.b.w.Wallet:   completed:   f3954a37bbd1d65e7fa119ddc98459be55f6f27260a63c08f705a35a9ab31291
     in   PUSHDATA(72)[30450221009788ad06faa22b0dbda2ec754a4499c0bca73ad552fd6c9be07c4c3be4437db202205cb8e40926adc23b0702bef3a9c6644dfa5db43f5f7ac4db01d62566537bd1a501] PUSHDATA(33)[031c701527f5f90cef907079e472a85d2b497c75a129bd688307c0f28e0cbcf64d] 0.010064 BTC (1006400)
          outpoint:3c876ab84e42d953d25eb2c0aba6cd4e22e9768566ae4374427981c11d5cd8c7:1 hash160:5363b762cbbd616e948cd6fdabeb3fcddbe68435
     out  DUP HASH160 PUSHDATA(20)[74673c99c3ce54c700cf7960d0acdb5ce8c0ab77] EQUALVERIFY CHECKSIG 0.010032 BTC (1003200) ScriptPubKey: 76a91474673c99c3ce54c700cf7960d0acdb5ce8c0ab7788ac Address:1BcV4GQ45knNoGFxAVDiHiG9FPvqGG4fgh 
     fee  0.000032 BTC for 192 bytes (16 Satoshi/Byte)
     prps USER_PAYMENT
 
Jan.-21 10:52:40.211 [JavaFX Application Thread] INFO  b.c.t.TaskRunner: Run task: TakerSendPayDepositRequest 
Jan.-21 10:52:40.687 [JavaFX Application Thread] INFO  b.c.t.p.t.t.TakerSendPayDepositRequest: Send PayDepositRequest with offerId xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093 and uid b853139b-9bb7-4a47-80b1-6dfe13fddd0f to peer 2b4mxjclbcs6eqvv.onion:9999 
Jan.-21 10:52:40.942 [JavaFX Application Thread] INFO  b.c.t.p.t.t.TakerSendPayDepositRequest: PayDepositRequest arrived at peer: offerId=xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093; uid=b853139b-9bb7-4a47-80b1-6dfe13fddd0f 
Jan.-21 10:52:42.258 [JavaFX Application Thread] WARN  b.c.t.p.TradeProtocol: Received AckMessage with error state for PayDepositRequest from 2b4mxjclbcs6eqvv.onion:9999 with tradeId xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093 and errorMessage=An error occurred at task: SellerAsMakerCreatesAndSignsDepositTx
Exception message: org.bitcoinj.core.InsufficientMoneyException: Insufficient money,  missing 0.1529 BTC 
Jan.-21 10:52:49.099 [JavaFX Application Thread] WARN  b.c.trade.Trade: depositTx is null 
Jan.-21 10:52:49.100 [JavaFX Application Thread] WARN  b.c.trade.Trade: depositTx is null 
Jan.-21 10:52:56.452 [JavaFX Application Thread] INFO  b.c.p.p.PriceFeedService: request from provider http://62nvujg5iou3vu3i.onion/ 63.547 sec. after last request 
Jan.-21 10:52:57.538 [JavaFX Application Thread] INFO  b.c.p.p.PriceFeedService: Received new MarketPrice(currencyCode=EUR, price=3116.13, timestampSec=1548064461791, isExternallyProvidedPrice=true) from provider http://44mgyoe2b6oqiytt.onion/ after 1.085 sec. 
Jan.-21 10:53:03.298 [NetworkNode:SendMessage-to-5a2y236i5ayudz5e.onion:9999] INFO  b.n.p.n.NetworkNode: onFailure at sendMessage: peersNodeAddress=5a2y236i5ayudz5e.onion:9999
	message=GetPeersRequest(senderNodeAddress=diq3yvb7rorhywb2.onion:9999, nonce=801233317, reportedPeers=[], supportedCapabilities=[0, 1, 2, 7]) 
Jan.-21 10:53:03.298 [NetworkNode:SendMessage-to-tsnociq3wxf34sxq.onion:9999] INFO  b.n.p.n.NetworkNode: onFailure at sendMessage: peersNodeAddress=tsnociq3wxf34sxq.onion:9999
	message=GetPeersRequest(senderNodeAddress=diq3yvb7rorhywb2.onion:9999, nonce=-1433932350, reportedPeers=[], supportedCapabilities=[0, 1, 2, 7]) 
Jan.-21 10:53:03.304 [NetworkNode:SendMessage-to-g2vwsz7r65ohh5xc.onion:9999] INFO  b.n.p.n.NetworkNode: onFailure at sendMessage: peersNodeAddress=g2vwsz7r65ohh5xc.onion:9999
	message=GetPeersRequest(senderNodeAddress=diq3yvb7rorhywb2.onion:9999, nonce=-861297348, reportedPeers=[], supportedCapabilities=[0, 1, 2, 7]) 
Jan.-21 10:53:48.399 [JavaFX Application Thread] WARN  b.c.trade.Trade: depositTx is null 
Jan.-21 10:53:48.400 [JavaFX Application Thread] WARN  b.c.trade.Trade: depositTx is null 
Jan.-21 10:54:00.126 [JavaFX Application Thread] INFO  b.c.p.p.PriceFeedService: request from provider http://62nvujg5iou3vu3i.onion/ 63.673 sec. after last request 
Jan.-21 10:54:01.612 [JavaFX Application Thread] INFO  b.c.p.p.PriceFeedService: Received new MarketPrice(currencyCode=EUR, price=3115.37, timestampSec=1548064552081, isExternallyProvidedPrice=true) from provider http://44mgyoe2b6oqiytt.onion/ after 1.485 sec. 
Jan.-21 10:54:08.432 [JavaFX Application Thread] ERROR b.c.t.p.TradeProtocol: Timeout reached. TradeID=xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093, state=TAKER_PUBLISHED_TAKER_FEE_TX 
Jan.-21 10:54:08.688 [JavaFX Application Thread] WARN  b.c.t.p.TradeProtocol: cleanupTradableOnFault tradeState=TAKER_PUBLISHED_TAKER_FEE_TX 
Jan.-21 10:54:08.694 [JavaFX Application Thread] WARN  b.c.t.TradeManager: We found an outdated addressEntry for trade ETQ6AG-0f186df8-9b6e-4dd4-8754-1134d050b810-080 
Jan.-21 10:54:08.695 [JavaFX Application Thread] WARN  b.c.t.TradeManager: We found an outdated addressEntry for trade JOCVFB-548c3eac-c490-4cfc-bc68-376ce5f69105-093 
Jan.-21 10:54:08.695 [JavaFX Application Thread] WARN  b.c.t.TradeManager: We found an outdated addressEntry for trade xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093 
Jan.-21 10:54:08.696 [JavaFX Application Thread] INFO  b.c.b.w.BtcWalletService: swap addressEntry with address 1CPckrV9wmr7seXYSMn8H4aC9fQxQ6MgsK and offerId xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093 from context MULTI_SIG to available 
Jan.-21 10:54:08.697 [JavaFX Application Thread] WARN  b.c.t.TradeManager: We found an outdated addressEntry for trade xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093 




-- 
You are receiving this because you are subscribed to this thread.
Reply to this email directly or view it on GitHub:
https://github.com/bisq-network/bisq/issues/2301#issuecomment-456013542
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.bisq.network/pipermail/bisq-github/attachments/20190121/60331845/attachment-0001.html>


More information about the bisq-github mailing list