Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

2nd SEPA trade in a row always times out #2301

Closed
Sylo44 opened this issue Jan 21, 2019 · 9 comments
Closed

2nd SEPA trade in a row always times out #2301

Sylo44 opened this issue Jan 21, 2019 · 9 comments
Labels
a:bug is:critical https://bisq.wiki/Critical_bug is:priority PR or issue marked with this label is up for compensation

Comments

@Sylo44
Copy link

Sylo44 commented Jan 21, 2019

Everytime I want to buy BTC with SEPA from several offers I always get an error for the 2nd trade.

The first trade works just fine. I get all the details mark order as paid and everything is ok.
But when I immediatly try to buy more BTC with SEPA, I just get to the screen where it is loading and it always finishes with htis error message:

https://prnt.sc/m9z4mj

And here is the log of it:

Jan.-21 10:18:13.700 [JavaFX Application Thread] INFO b.d.m.o.t.TakeOfferDataModel: Fee estimation completed:
txFee based on estimated size of 702 bytes. Average tx size = 511 bytes. Actual tx size = 703 bytes. TxFee is 0.0000511 BTC (10 sat/byte)
Jan.-21 10:18:13.859 [JavaFX Application Thread] INFO b.c.o.a.OfferAvailabilityProtocol: Send AckMessage for OfferAvailabilityResponse to peer 2b4mxjclbcs6eqvv.onion:9999 with offerId CEALMQ-9eda1b40-c24c-42c8-be95-66471be2bb9f-093 and sourceUid fa75399d-ed1a-4a81-bf41-a97fecafe43a
Jan.-21 10:18:13.863 [JavaFX Application Thread] INFO b.n.p.p.PeerManager: Num of latestLivePeers=3
Jan.-21 10:18:14.006 [JavaFX Application Thread] INFO b.c.o.OpenOfferManager: Received AckMessage for OfferAvailabilityRequest with offerId CEALMQ-9eda1b40-c24c-42c8-be95-66471be2bb9f-093 and uid 0d93b274-8460-45b9-ab6f-15e9efa4591a
Jan.-21 10:18:14.149 [JavaFX Application Thread] INFO b.c.o.a.OfferAvailabilityProtocol: AckMessage for OfferAvailabilityResponse arrived at makersNodeAddress 2b4mxjclbcs6eqvv.onion:9999. offerId=CEALMQ-9eda1b40-c24c-42c8-be95-66471be2bb9f-093, sourceUid=fa75399d-ed1a-4a81-bf41-a97fecafe43a
Jan.-21 10:18:21.871 [JavaFX Application Thread] INFO b.n.p.p.PeerManager: We have 13 connections open. Our limit is 12
Jan.-21 10:18:21.873 [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:18:21.875 [JavaFX Application Thread] INFO b.n.p.p.PeerManager: checkMaxConnections: Num candidates for shut down=2. We close oldest connection: Connection{peerAddress=Optional[zxnxtdzcx2wti2tl.onion:9999], peerType=PEER, uid='b938d409-fea8-4bf9-a99b-9fa7f7f04c11'}
Jan.-21 10:18:21.877 [InputHandler-723ljisnynbtdohi.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[723ljisnynbtdohi.onion:8000], peerType=SEED_NODE, uid='30655662-9a5e-4406-9fc8-1cdf3a577903'}
Jan.-21 10:18:21.898 [InputHandler-zxnxtdzcx2wti2tl.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[zxnxtdzcx2wti2tl.onion:9999], peerType=PEER, uid='b938d409-fea8-4bf9-a99b-9fa7f7f04c11'}
Jan.-21 10:18:22.083 [JavaFX Application Thread] INFO b.n.p.p.PeerManager: onDisconnect called: nodeAddress=Optional[723ljisnynbtdohi.onion:8000], closeConnectionReason=CloseConnectionReason{sendCloseMessage=true, isIntended=true} TOO_MANY_SEED_NODES_CONNECTED
Jan.-21 10:18:22.084 [JavaFX Application Thread] INFO b.n.p.p.p.PeerExchangeManager: onDisconnect closeConnectionReason=CloseConnectionReason{sendCloseMessage=true, isIntended=true} TOO_MANY_SEED_NODES_CONNECTED, nodeAddressOpt=Optional[723ljisnynbtdohi.onion:8000]
Jan.-21 10:18:22.106 [JavaFX Application Thread] INFO b.n.p.p.PeerManager: onDisconnect called: nodeAddress=Optional[zxnxtdzcx2wti2tl.onion:9999], closeConnectionReason=CloseConnectionReason{sendCloseMessage=true, isIntended=true} TOO_MANY_CONNECTIONS_OPEN
Jan.-21 10:18:22.106 [JavaFX Application Thread] INFO b.n.p.p.p.PeerExchangeManager: onDisconnect closeConnectionReason=CloseConnectionReason{sendCloseMessage=true, isIntended=true} TOO_MANY_CONNECTIONS_OPEN, nodeAddressOpt=Optional[zxnxtdzcx2wti2tl.onion:9999]
Jan.-21 10:18:22.222 [JavaFX Application Thread] INFO b.n.p.p.PeerManager: We have 11 connections open. Our limit is 12
Jan.-21 10:18:27.624 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: SendOfferAvailabilityRequest
Jan.-21 10:18:27.625 [JavaFX Application Thread] INFO b.c.o.a.t.SendOfferAvailabilityRequest: Send OfferAvailabilityRequest with offerId CEALMQ-9eda1b40-c24c-42c8-be95-66471be2bb9f-093 and uid c3b11f85-4990-4686-9c99-0bddc182073a to peer 2b4mxjclbcs6eqvv.onion:9999
Jan.-21 10:18:27.637 [JavaFX Application Thread] INFO b.c.o.a.t.SendOfferAvailabilityRequest: OfferAvailabilityRequest arrived at peer: offerId=CEALMQ-9eda1b40-c24c-42c8-be95-66471be2bb9f-093; uid=c3b11f85-4990-4686-9c99-0bddc182073a
Jan.-21 10:18:28.272 [JavaFX Application Thread] INFO b.c.o.a.OfferAvailabilityProtocol: Received handleOfferAvailabilityResponse from 2b4mxjclbcs6eqvv.onion:9999 with offerId CEALMQ-9eda1b40-c24c-42c8-be95-66471be2bb9f-093 and uid 6af8ca79-4eb2-41ca-b817-ae462007a185
Jan.-21 10:18:28.272 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: ProcessOfferAvailabilityResponse
Jan.-21 10:18:28.273 [JavaFX Application Thread] INFO b.c.o.a.OfferAvailabilityProtocol: Send AckMessage for OfferAvailabilityResponse to peer 2b4mxjclbcs6eqvv.onion:9999 with offerId CEALMQ-9eda1b40-c24c-42c8-be95-66471be2bb9f-093 and sourceUid 6af8ca79-4eb2-41ca-b817-ae462007a185
Jan.-21 10:18:28.275 [JavaFX Application Thread] INFO b.n.p.p.PeerManager: Num of latestLivePeers=10
Jan.-21 10:18:28.288 [JavaFX Application Thread] INFO b.c.n.a.TradeEvents: We got a new trade. id=CEALMQ-9eda1b40-c24c-42c8-be95-66471be2bb9f-093
Jan.-21 10:18:28.300 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: TakerSelectMediator
Jan.-21 10:18:28.304 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: TakerVerifyMakerAccount
Jan.-21 10:18:28.305 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: TakerVerifyMakerFeePayment
Jan.-21 10:18:28.305 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: CreateTakerFeeTx
Jan.-21 10:18:28.387 [JavaFX Application Thread] INFO b.c.b.w.TradeWalletService: reservedFundsForOffer 0.0101022
Jan.-21 10:18:28.387 [JavaFX Application Thread] INFO b.c.b.w.TradeWalletService: tradingFee 0.0005997
Jan.-21 10:18:28.388 [JavaFX Application Thread] INFO b.c.b.w.TradeWalletService: txFee 0.0000511
Jan.-21 10:18:28.389 [JavaFX Application Thread] INFO o.b.w.Wallet: Completing send tx with 2 outputs totalling 0.0107019 BTC and a fee of 0.00 BTC/kB
Jan.-21 10:18:28.390 [JavaFX Application Thread] INFO o.b.w.Wallet: with 0.0006982 BTC change
Jan.-21 10:18:28.429 [JavaFX Application Thread] INFO o.b.w.Wallet: with a fee of 0.00007268 BTC/kB, 0.0000511 BTC for 703 bytes
Jan.-21 10:18:28.429 [JavaFX Application Thread] INFO o.b.w.Wallet: completed: 86d38f8078d52998c9e6343a227e50381190feafe59502b470d126c2de86b23b
in PUSHDATA(71)[3044022003f37ce01e854615bc333d422ea844aaf62c6ddd5ecfdf679f90c239ab58e056022068082f29f17a11edf1a913205fc6bcc69860b53bb93e8420ed07dd3a79edbcb901] PUSHDATA(33)[028d1eb5aad149983c405d6270a7ec27ca7aa960a6e3f060ff372a6e8dcecc6c15] 0.0032985 BTC (329850)
outpoint:77f0dfce9c8715f4be1b65d4b438e1f3ed763f05adc268c7404b24b02194b22d:1 hash160:c49fa2510353e5095939bca3aa07ac9a1d510113
in PUSHDATA(72)[3045022100b200e8466f496ff9c11b1e4294f9e1ef2cd1009592cffc8c80fc201e47216bb6022053fb99b950924b5e799250439c529ab522a4e7aaac7a6560b9b37a2d8f4d062601] PUSHDATA(33)[023963bdd636947012026aedbad7bd49d6ba2c0a233ff0288ae1e42f990a6ebd70] 0.003 BTC (300000)
outpoint:34c288cdf4116e933e95125b4c63d543574a49d61ce169ae675e41229f19ac35:1 hash160:d956ce196147f427d89d45678d583679ef2eca87
in PUSHDATA(72)[3045022100d6eda73792ce95b6d376fc65c7548989665d78e23be4ec48759cb1b021dc0f770220423c367feac0345cb4261924a1c17c5bb3617f4df3f12a7b663f96b0af4aea9001] PUSHDATA(33)[03de4f43f1190f2ce7b361ea291456be3cb4f475e3171a6badcacd28225a71701a] 0.003 BTC (300000)
outpoint:98a33e23a55f9bf2b98400c64f0d9fc070a42e7739bc96f771bea093baa5ab53:1 hash160:9d34aefb2942c4c25cbba7b5400ab4ea1452012a
in PUSHDATA(72)[3045022100b9fb4e3f33039084a85b9271e385ede2f9d0c0d95bfe0d80d8bfa89f9dd8dde6022059b3a07b5b35921c3e7ef89febec3a12808ace7729a8c8c516b0b9adfecb6bb801] PUSHDATA(33)[033ce8af6bc4b5c91010bf4beeb07afba947c8f8ae0af424221e2d0c0866d00961] 0.0021527 BTC (215270)
outpoint:def83a0086cf5544318fa0c0ddcb05069b077777c6eb81594463adc374fc4894:2 hash160:0c7ddebea237e1cc25f4a7b182a7ce2ceca1e15a
out DUP HASH160 PUSHDATA(20)[f6876dfc59041e46bdaece49428676dd9e5b3226] EQUALVERIFY CHECKSIG 0.0005997 BTC (59970) ScriptPubKey: 76a914f6876dfc59041e46bdaece49428676dd9e5b322688ac Address:1PUXU1MQ82JC3Hx1NN5tZs3BaTAJVg72MC
out DUP HASH160 PUSHDATA(20)[271537d2f8114e9c1ab616bb384f949eab50fcab] EQUALVERIFY CHECKSIG 0.0101022 BTC (1010220) ScriptPubKey: 76a914271537d2f8114e9c1ab616bb384f949eab50fcab88ac Address:14ZemjpSeZQBoYNNzAQ3PCGX378Wmsxfqb
out DUP HASH160 PUSHDATA(20)[b05ef5c072aaf91f5953fbd01a4e22b5e012af5b] EQUALVERIFY CHECKSIG 0.0006982 BTC (69820) ScriptPubKey: 76a914b05ef5c072aaf91f5953fbd01a4e22b5e012af5b88ac Address:1H5ZghPdvL9jrRRDhemyc4FENLUYx1Wd6q
fee 0.0000511 BTC for 703 bytes (7 Satoshi/Byte)
prps USER_PAYMENT

Jan.-21 10:18:28.431 [JavaFX Application Thread] INFO b.c.b.w.WalletService:
tradingFeeTx:
86d38f8078d52998c9e6343a227e50381190feafe59502b470d126c2de86b23b
in PUSHDATA(71)[3044022003f37ce01e854615bc333d422ea844aaf62c6ddd5ecfdf679f90c239ab58e056022068082f29f17a11edf1a913205fc6bcc69860b53bb93e8420ed07dd3a79edbcb901] PUSHDATA(33)[028d1eb5aad149983c405d6270a7ec27ca7aa960a6e3f060ff372a6e8dcecc6c15] 0.0032985 BTC (329850)
outpoint:77f0dfce9c8715f4be1b65d4b438e1f3ed763f05adc268c7404b24b02194b22d:1 hash160:c49fa2510353e5095939bca3aa07ac9a1d510113
in PUSHDATA(72)[3045022100b200e8466f496ff9c11b1e4294f9e1ef2cd1009592cffc8c80fc201e47216bb6022053fb99b950924b5e799250439c529ab522a4e7aaac7a6560b9b37a2d8f4d062601] PUSHDATA(33)[023963bdd636947012026aedbad7bd49d6ba2c0a233ff0288ae1e42f990a6ebd70] 0.003 BTC (300000)
outpoint:34c288cdf4116e933e95125b4c63d543574a49d61ce169ae675e41229f19ac35:1 hash160:d956ce196147f427d89d45678d583679ef2eca87
in PUSHDATA(72)[3045022100d6eda73792ce95b6d376fc65c7548989665d78e23be4ec48759cb1b021dc0f770220423c367feac0345cb4261924a1c17c5bb3617f4df3f12a7b663f96b0af4aea9001] PUSHDATA(33)[03de4f43f1190f2ce7b361ea291456be3cb4f475e3171a6badcacd28225a71701a] 0.003 BTC (300000)
outpoint:98a33e23a55f9bf2b98400c64f0d9fc070a42e7739bc96f771bea093baa5ab53:1 hash160:9d34aefb2942c4c25cbba7b5400ab4ea1452012a
in PUSHDATA(72)[3045022100b9fb4e3f33039084a85b9271e385ede2f9d0c0d95bfe0d80d8bfa89f9dd8dde6022059b3a07b5b35921c3e7ef89febec3a12808ace7729a8c8c516b0b9adfecb6bb801] PUSHDATA(33)[033ce8af6bc4b5c91010bf4beeb07afba947c8f8ae0af424221e2d0c0866d00961] 0.0021527 BTC (215270)
outpoint:def83a0086cf5544318fa0c0ddcb05069b077777c6eb81594463adc374fc4894:2 hash160:0c7ddebea237e1cc25f4a7b182a7ce2ceca1e15a
out DUP HASH160 PUSHDATA(20)[f6876dfc59041e46bdaece49428676dd9e5b3226] EQUALVERIFY CHECKSIG 0.0005997 BTC (59970) ScriptPubKey: 76a914f6876dfc59041e46bdaece49428676dd9e5b322688ac Address:1PUXU1MQ82JC3Hx1NN5tZs3BaTAJVg72MC
out DUP HASH160 PUSHDATA(20)[271537d2f8114e9c1ab616bb384f949eab50fcab] EQUALVERIFY CHECKSIG 0.0101022 BTC (1010220) ScriptPubKey: 76a914271537d2f8114e9c1ab616bb384f949eab50fcab88ac Address:14ZemjpSeZQBoYNNzAQ3PCGX378Wmsxfqb
out DUP HASH160 PUSHDATA(20)[b05ef5c072aaf91f5953fbd01a4e22b5e012af5b] EQUALVERIFY CHECKSIG 0.0006982 BTC (69820) ScriptPubKey: 76a914b05ef5c072aaf91f5953fbd01a4e22b5e012af5b88ac Address:1H5ZghPdvL9jrRRDhemyc4FENLUYx1Wd6q
fee 0.0000511 BTC for 703 bytes (7 Satoshi/Byte)
prps USER_PAYMENT

Jan.-21 10:18:28.438 [JavaFX Application Thread] INFO o.b.c.TransactionBroadcast: Waiting for 4 peers required for broadcast, we have 9 ...
Jan.-21 10:18:28.443 [JavaFX Application Thread] INFO o.b.c.TransactionBroadcast: broadcastTransaction: We have 9 peers, adding 86d38f8078d52998c9e6343a227e50381190feafe59502b470d126c2de86b23b to the memory pool
Jan.-21 10:18:28.443 [JavaFX Application Thread] INFO o.b.c.TransactionBroadcast: Sending to 5 peers, will wait for 2, sending to: [3xucqntxp5ddoaz5.onion]:8333,[4jyh6llqj264oggs.onion]:8333,[z33nukt7ngik3cpe.onion]:8333,[mxdtrjhe2yfsx3pg.onion]:8333,[lgkvbvro67jomosw.onion]:8333
Jan.-21 10:18:28.461 [JavaFX Application Thread] INFO b.c.o.a.OfferAvailabilityProtocol: AckMessage for OfferAvailabilityResponse arrived at makersNodeAddress 2b4mxjclbcs6eqvv.onion:9999. offerId=CEALMQ-9eda1b40-c24c-42c8-be95-66471be2bb9f-093, sourceUid=6af8ca79-4eb2-41ca-b817-ae462007a185
Jan.-21 10:18:28.473 [JavaFX Application Thread] INFO b.c.o.OpenOfferManager: Received AckMessage for OfferAvailabilityRequest with offerId CEALMQ-9eda1b40-c24c-42c8-be95-66471be2bb9f-093 and uid c3b11f85-4990-4686-9c99-0bddc182073a
Jan.-21 10:18:31.360 [BlockingClient network thread for i3a5xtzfm4xwtybd.onion:8333] INFO o.b.core.Peer: [i3a5xtzfm4xwtybd.onion]:8333: Downloading dependencies of 86d38f8078d52998c9e6343a227e50381190feafe59502b470d126c2de86b23b
Jan.-21 10:18:31.361 [BlockingClient network thread for i3a5xtzfm4xwtybd.onion:8333] INFO o.b.core.Peer: [i3a5xtzfm4xwtybd.onion]:8333: Requesting 4 transactions for depth 1 dep resolution
Jan.-21 10:18:31.901 [BlockingClient network thread for i3a5xtzfm4xwtybd.onion:8333] INFO o.b.core.Peer: [i3a5xtzfm4xwtybd.onion]:8333: Bottomed out dep tree at 77f0dfce9c8715f4be1b65d4b438e1f3ed763f05adc268c7404b24b02194b22d
Jan.-21 10:18:31.902 [BlockingClient network thread for i3a5xtzfm4xwtybd.onion:8333] INFO o.b.core.Peer: [i3a5xtzfm4xwtybd.onion]:8333: Bottomed out dep tree at 34c288cdf4116e933e95125b4c63d543574a49d61ce169ae675e41229f19ac35
Jan.-21 10:18:31.915 [BlockingClient network thread for i3a5xtzfm4xwtybd.onion:8333] INFO o.b.core.Peer: [i3a5xtzfm4xwtybd.onion]:8333: Bottomed out dep tree at 98a33e23a55f9bf2b98400c64f0d9fc070a42e7739bc96f771bea093baa5ab53
Jan.-21 10:18:31.915 [BlockingClient network thread for i3a5xtzfm4xwtybd.onion:8333] INFO o.b.core.Peer: [i3a5xtzfm4xwtybd.onion]:8333: Bottomed out dep tree at def83a0086cf5544318fa0c0ddcb05069b077777c6eb81594463adc374fc4894
Jan.-21 10:18:31.915 [BlockingClient network thread for i3a5xtzfm4xwtybd.onion:8333] INFO o.b.core.Peer: [i3a5xtzfm4xwtybd.onion]:8333: Dependency download complete!
Jan.-21 10:18:31.916 [BlockingClient network thread for i3a5xtzfm4xwtybd.onion:8333] INFO o.b.w.Wallet: Received a pending transaction 86d38f8078d52998c9e6343a227e50381190feafe59502b470d126c2de86b23b that spends 0.0114512 BTC from our own wallet, and sends us 0.0108004 BTC
Jan.-21 10:18:31.916 [BlockingClient network thread for i3a5xtzfm4xwtybd.onion:8333] INFO o.b.w.Wallet: commitTx of 86d38f8078d52998c9e6343a227e50381190feafe59502b470d126c2de86b23b
Jan.-21 10:18:31.917 [BlockingClient network thread for i3a5xtzfm4xwtybd.onion:8333] INFO o.b.w.Wallet: marked 77f0dfce9c8715f4be1b65d4b438e1f3ed763f05adc268c7404b24b02194b22d:1 as spent by 86d38f8078d52998c9e6343a227e50381190feafe59502b470d126c2de86b23b
Jan.-21 10:18:31.917 [BlockingClient network thread for i3a5xtzfm4xwtybd.onion:8333] INFO o.b.w.Wallet: 77f0dfce9c8715f4be1b65d4b438e1f3ed763f05adc268c7404b24b02194b22d prevtx <-unspent ->spent
Jan.-21 10:18:31.917 [BlockingClient network thread for i3a5xtzfm4xwtybd.onion:8333] INFO o.b.w.Wallet: marked 34c288cdf4116e933e95125b4c63d543574a49d61ce169ae675e41229f19ac35:1 as spent by 86d38f8078d52998c9e6343a227e50381190feafe59502b470d126c2de86b23b
Jan.-21 10:18:31.918 [BlockingClient network thread for i3a5xtzfm4xwtybd.onion:8333] INFO o.b.w.Wallet: 34c288cdf4116e933e95125b4c63d543574a49d61ce169ae675e41229f19ac35 prevtx <-unspent ->spent
Jan.-21 10:18:31.918 [BlockingClient network thread for i3a5xtzfm4xwtybd.onion:8333] INFO o.b.w.Wallet: marked 98a33e23a55f9bf2b98400c64f0d9fc070a42e7739bc96f771bea093baa5ab53:1 as spent by 86d38f8078d52998c9e6343a227e50381190feafe59502b470d126c2de86b23b
Jan.-21 10:18:31.918 [BlockingClient network thread for i3a5xtzfm4xwtybd.onion:8333] INFO o.b.w.Wallet: 98a33e23a55f9bf2b98400c64f0d9fc070a42e7739bc96f771bea093baa5ab53 prevtx <-unspent ->spent
Jan.-21 10:18:31.918 [BlockingClient network thread for i3a5xtzfm4xwtybd.onion:8333] INFO o.b.w.Wallet: marked def83a0086cf5544318fa0c0ddcb05069b077777c6eb81594463adc374fc4894:2 as spent by 86d38f8078d52998c9e6343a227e50381190feafe59502b470d126c2de86b23b
Jan.-21 10:18:31.918 [BlockingClient network thread for i3a5xtzfm4xwtybd.onion:8333] INFO o.b.w.Wallet: def83a0086cf5544318fa0c0ddcb05069b077777c6eb81594463adc374fc4894 prevtx <-unspent ->spent
Jan.-21 10:18:31.919 [BlockingClient network thread for i3a5xtzfm4xwtybd.onion:8333] INFO o.b.w.Wallet: ->pending: 86d38f8078d52998c9e6343a227e50381190feafe59502b470d126c2de86b23b
Jan.-21 10:18:31.920 [BlockingClient network thread for i3a5xtzfm4xwtybd.onion:8333] INFO o.b.w.Wallet: Estimated balance is now: 0.1108004 BTC
Jan.-21 10:18:31.924 [BlockingClient network thread for i3a5xtzfm4xwtybd.onion:8333] INFO o.b.w.WalletFiles: Saving wallet; last seen block is height 559435, date 2019-01-21T09:15:31Z, hash 00000000000000000005c501eec6a6d03c5578879a6d276ddff3fac5bab22d06
Jan.-21 10:18:32.216 [BlockingClient network thread for i3a5xtzfm4xwtybd.onion:8333] INFO o.b.w.WalletFiles: Save completed in 293.6 ms
Jan.-21 10:18:38.249 [JavaFX Application Thread] WARN b.c.trade.Trade: depositTx is null
Jan.-21 10:18:38.250 [JavaFX Application Thread] WARN b.c.trade.Trade: depositTx is null
Jan.-21 10:18:59.367 [JavaFX Application Thread] WARN b.c.b.w.TxBroadcaster: Broadcast of tx 86d38f8078d52998c9e6343a227e50381190feafe59502b470d126c2de86b23b not completed after 30 sec.
Jan.-21 10:18:59.384 [JavaFX Application Thread] WARN b.c.b.w.TxBroadcaster: TxBroadcaster.onTimeout called: TxBroadcastTimeoutException{
localTx= 86d38f8078d52998c9e6343a227e50381190feafe59502b470d126c2de86b23b
in PUSHDATA(71)[3044022003f37ce01e854615bc333d422ea844aaf62c6ddd5ecfdf679f90c239ab58e056022068082f29f17a11edf1a913205fc6bcc69860b53bb93e8420ed07dd3a79edbcb901] PUSHDATA(33)[028d1eb5aad149983c405d6270a7ec27ca7aa960a6e3f060ff372a6e8dcecc6c15] 0.0032985 BTC (329850)
outpoint:77f0dfce9c8715f4be1b65d4b438e1f3ed763f05adc268c7404b24b02194b22d:1 hash160:c49fa2510353e5095939bca3aa07ac9a1d510113
in PUSHDATA(72)[3045022100b200e8466f496ff9c11b1e4294f9e1ef2cd1009592cffc8c80fc201e47216bb6022053fb99b950924b5e799250439c529ab522a4e7aaac7a6560b9b37a2d8f4d062601] PUSHDATA(33)[023963bdd636947012026aedbad7bd49d6ba2c0a233ff0288ae1e42f990a6ebd70] 0.003 BTC (300000)
outpoint:34c288cdf4116e933e95125b4c63d543574a49d61ce169ae675e41229f19ac35:1 hash160:d956ce196147f427d89d45678d583679ef2eca87
in PUSHDATA(72)[3045022100d6eda73792ce95b6d376fc65c7548989665d78e23be4ec48759cb1b021dc0f770220423c367feac0345cb4261924a1c17c5bb3617f4df3f12a7b663f96b0af4aea9001] PUSHDATA(33)[03de4f43f1190f2ce7b361ea291456be3cb4f475e3171a6badcacd28225a71701a] 0.003 BTC (300000)
outpoint:98a33e23a55f9bf2b98400c64f0d9fc070a42e7739bc96f771bea093baa5ab53:1 hash160:9d34aefb2942c4c25cbba7b5400ab4ea1452012a
in PUSHDATA(72)[3045022100b9fb4e3f33039084a85b9271e385ede2f9d0c0d95bfe0d80d8bfa89f9dd8dde6022059b3a07b5b35921c3e7ef89febec3a12808ace7729a8c8c516b0b9adfecb6bb801] PUSHDATA(33)[033ce8af6bc4b5c91010bf4beeb07afba947c8f8ae0af424221e2d0c0866d00961] 0.0021527 BTC (215270)
outpoint:def83a0086cf5544318fa0c0ddcb05069b077777c6eb81594463adc374fc4894:2 hash160:0c7ddebea237e1cc25f4a7b182a7ce2ceca1e15a
out DUP HASH160 PUSHDATA(20)[f6876dfc59041e46bdaece49428676dd9e5b3226] EQUALVERIFY CHECKSIG 0.0005997 BTC (59970) ScriptPubKey: 76a914f6876dfc59041e46bdaece49428676dd9e5b322688ac Address:1PUXU1MQ82JC3Hx1NN5tZs3BaTAJVg72MC
out DUP HASH160 PUSHDATA(20)[271537d2f8114e9c1ab616bb384f949eab50fcab] EQUALVERIFY CHECKSIG 0.0101022 BTC (1010220) ScriptPubKey: 76a914271537d2f8114e9c1ab616bb384f949eab50fcab88ac Address:14ZemjpSeZQBoYNNzAQ3PCGX378Wmsxfqb
out DUP HASH160 PUSHDATA(20)[b05ef5c072aaf91f5953fbd01a4e22b5e012af5b] EQUALVERIFY CHECKSIG 0.0006982 BTC (69820) ScriptPubKey: 76a914b05ef5c072aaf91f5953fbd01a4e22b5e012af5b88ac Address:1H5ZghPdvL9jrRRDhemyc4FENLUYx1Wd6q
fee 0.0000511 BTC for 703 bytes (7 Satoshi/Byte)
prps USER_PAYMENT
,
delay=30
} TxBroadcastException{
txId='null'
} bisq.core.btc.exceptions.TxBroadcastTimeoutException: The transaction was not broadcasted in 30seconds. txId=86d38f8078d52998c9e6343a227e50381190feafe59502b470d126c2de86b23b
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:18:59.385 [JavaFX Application Thread] INFO b.c.b.w.BtcWalletService: swap addressEntry with address 1DTS7oKisygSN314vxnGHpYHBXiZEtKzsb and offerId CEALMQ-9eda1b40-c24c-42c8-be95-66471be2bb9f-093 from context OFFER_FUNDING to available
Jan.-21 10:18:59.388 [JavaFX Application Thread] INFO b.c.trade.Trade: Set new state at BuyerAsTakerTrade (id=CEALMQ): TAKER_PUBLISHED_TAKER_FEE_TX
Jan.-21 10:18:59.391 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: BuyerAsTakerCreatesDepositTxInputs
Jan.-21 10:18:59.429 [JavaFX Application Thread] INFO o.b.w.Wallet: Completing send tx with 1 outputs totalling 0.0100511 BTC and a fee of 0.00 BTC/kB
Jan.-21 10:18:59.436 [JavaFX Application Thread] INFO o.b.w.Wallet: with a fee of 0.00026614 BTC/kB, 0.0000511 BTC for 192 bytes
Jan.-21 10:18:59.436 [JavaFX Application Thread] INFO o.b.w.Wallet: completed: b7a254402a5ac41185b2c618eecae2df36442e8285d839feb2d288f626ab1e33
in PUSHDATA(72)[3045022100805e7ea5b7be54edcea288b8a770ea4387021c0f290dd50afe3be9dd997cf76002204d310192ce68e77e53ae7eeaf24e3b1ffcc19a371e5d0be7a52e2fc58a4a480201] PUSHDATA(33)[0316b4f6f43e41498fbb655b45d9a1d3a6167fdc727b533c6da90a95f75411a0d4] 0.0101022 BTC (1010220)
outpoint:86d38f8078d52998c9e6343a227e50381190feafe59502b470d126c2de86b23b:1 hash160:271537d2f8114e9c1ab616bb384f949eab50fcab
out DUP HASH160 PUSHDATA(20)[d7b7e0321d6be8ab05ae1528ca04c01463de5cab] EQUALVERIFY CHECKSIG 0.0100511 BTC (1005110) ScriptPubKey: 76a914d7b7e0321d6be8ab05ae1528ca04c01463de5cab88ac Address:1LfcZA2bmevhjjo49gVDp28zH6uwr3Vp5L
fee 0.0000511 BTC for 192 bytes (26 Satoshi/Byte)
prps USER_PAYMENT

Jan.-21 10:18:59.438 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: TakerSendPayDepositRequest
Jan.-21 10:18:59.497 [JavaFX Application Thread] INFO o.b.w.WalletFiles: Saving wallet; last seen block is height 559435, date 2019-01-21T09:15:31Z, hash 00000000000000000005c501eec6a6d03c5578879a6d276ddff3fac5bab22d06
Jan.-21 10:18:59.761 [JavaFX Application Thread] INFO o.b.w.WalletFiles: Save completed in 265.9 ms
Jan.-21 10:18:59.764 [JavaFX Application Thread] INFO b.c.t.p.t.t.TakerSendPayDepositRequest: Send PayDepositRequest with offerId CEALMQ-9eda1b40-c24c-42c8-be95-66471be2bb9f-093 and uid 587e023d-a50b-487a-a2e9-e03d6c113bd2 to peer 2b4mxjclbcs6eqvv.onion:9999
Jan.-21 10:18:59.783 [JavaFX Application Thread] INFO b.c.t.p.t.t.TakerSendPayDepositRequest: PayDepositRequest arrived at peer: offerId=CEALMQ-9eda1b40-c24c-42c8-be95-66471be2bb9f-093; uid=587e023d-a50b-487a-a2e9-e03d6c113bd2
Jan.-21 10:19:00.740 [JavaFX Application Thread] INFO b.c.t.p.BuyerAsTakerProtocol: Received PublishDepositTxRequest from 2b4mxjclbcs6eqvv.onion:9999 with tradeId CEALMQ-9eda1b40-c24c-42c8-be95-66471be2bb9f-093 and uid 6f8df8b9-3822-4d7c-8a3a-263469a56640
Jan.-21 10:19:00.745 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: TakerProcessPublishDepositTxRequest
Jan.-21 10:19:00.745 [JavaFX Application Thread] INFO b.c.trade.Trade: Set new state at BuyerAsTakerTrade (id=CEALMQ): TAKER_RECEIVED_PUBLISH_DEPOSIT_TX_REQUEST
Jan.-21 10:19:00.746 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: CheckIfPeerIsBanned
Jan.-21 10:19:00.747 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: TakerVerifyMakerAccount
Jan.-21 10:19:00.747 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: VerifyPeersAccountAgeWitness
Jan.-21 10:19:00.750 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: TakerVerifyMakerFeePayment
Jan.-21 10:19:00.750 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: TakerVerifyAndSignContract
Jan.-21 10:19:00.803 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: BuyerAsTakerSignAndPublishDepositTx
Jan.-21 10:19:00.822 [JavaFX Application Thread] INFO b.c.b.w.WalletService:
depositTx:
d66ded385ff192ee5aae0e6644096d9a60bca1df997037559609739a477daa01
in PUSHDATA(71)[304402206b9f1171a0c949231f63aa63035edefb3ae72ba3b8a80bb0f7aca3318eb78d4b0220016b287c90f8108a283c1d02b2ee14f147c02a8df84f2791183f511c69ffa8de01] PUSHDATA(33)[0316b4f6f43e41498fbb655b45d9a1d3a6167fdc727b533c6da90a95f75411a0d4] 0.0101022 BTC (1010220)
outpoint:86d38f8078d52998c9e6343a227e50381190feafe59502b470d126c2de86b23b:1 hash160:271537d2f8114e9c1ab616bb384f949eab50fcab
in PUSHDATA(71)[3044022034d4270fa64ea3ffd3d503c570df5ad66df5cd2de19ce78addc94dad6c88f893022045273a3c8c0939a37c516687b5ec51e33e9a2993dbddb6b1223b12f4b5c455c301] PUSHDATA(33)[0357ab22adab2145f37f8692285e6cc03b94f5078094ab092577f7ec946f52049a] 0.2029 BTC (20290000)
outpoint:6ebadd99d94dd42fd52f2ada5dd39498587bb7417874d4be4d6270a4f2e9b7b0:1 hash160:e669da20b8421abbd030c9d3706b86e4dc1c5b21
out HASH160 PUSHDATA(20)[44d9d24acb78a65874e28df1e372c5e194dfc98d] EQUAL 0.2129511 BTC (21295110) ScriptPubKey: a91444d9d24acb78a65874e28df1e372c5e194dfc98d87 Address:37y4p64p3MAa8vgM6swW5cbn8zJmpFCBF6
out RETURN PUSHDATA(32)[4309593008cfd75b7655e77a573fda677390b73d0465109d1888fee0af865318] 0.00 BTC (0) ScriptPubKey: 6a204309593008cfd75b7655e77a573fda677390b73d0465109d1888fee0af865318 Address:[exception: Cannot cast this script to a pay-to-address type]
fee 0.0000511 BTC for 379 bytes (13 Satoshi/Byte)
prps UNKNOWN

Jan.-21 10:19:00.826 [JavaFX Application Thread] INFO o.b.c.PeerGroup: Transaction source unknown, setting to SELF: d66ded385ff192ee5aae0e6644096d9a60bca1df997037559609739a477daa01
Jan.-21 10:19:00.827 [JavaFX Application Thread] INFO o.b.c.TransactionBroadcast: Waiting for 4 peers required for broadcast, we have 9 ...
Jan.-21 10:19:00.827 [JavaFX Application Thread] INFO o.b.c.TransactionBroadcast: broadcastTransaction: We have 9 peers, adding d66ded385ff192ee5aae0e6644096d9a60bca1df997037559609739a477daa01 to the memory pool
Jan.-21 10:19:00.827 [JavaFX Application Thread] INFO o.b.c.TransactionBroadcast: Sending to 5 peers, will wait for 2, sending to: [z33nukt7ngik3cpe.onion]:8333,[i3a5xtzfm4xwtybd.onion]:8333,[3r44ddzjitznyahw.onion]:8333,[lgkvbvro67jomosw.onion]:8333,[4jyh6llqj264oggs.onion]:8333
Jan.-21 10:19:00.849 [JavaFX Application Thread] INFO b.c.t.p.TradeProtocol: Received AckMessage for PayDepositRequest from 2b4mxjclbcs6eqvv.onion:9999 with tradeId CEALMQ-9eda1b40-c24c-42c8-be95-66471be2bb9f-093 and uid 587e023d-a50b-487a-a2e9-e03d6c113bd2
Jan.-21 10:19:02.917 [JavaFX Application Thread] INFO o.b.c.TransactionBroadcast: broadcastTransaction: SEEN_PEERS: TX d66ded385ff192ee5aae0e6644096d9a60bca1df997037559609739a477daa01 seen by 1 peers
Jan.-21 10:19:05.578 [JavaFX Application Thread] INFO o.b.c.TransactionBroadcast: broadcastTransaction: SEEN_PEERS: TX d66ded385ff192ee5aae0e6644096d9a60bca1df997037559609739a477daa01 seen by 2 peers
Jan.-21 10:19:05.579 [JavaFX Application Thread] INFO o.b.c.TransactionBroadcast: broadcastTransaction: d66ded385ff192ee5aae0e6644096d9a60bca1df997037559609739a477daa01 complete
Jan.-21 10:19:05.581 [JavaFX Application Thread] INFO o.b.w.Wallet: Received a pending transaction d66ded385ff192ee5aae0e6644096d9a60bca1df997037559609739a477daa01 that spends 0.0101022 BTC from our own wallet, and sends us 0.00 BTC
Jan.-21 10:19:05.581 [JavaFX Application Thread] INFO o.b.w.Wallet: commitTx of d66ded385ff192ee5aae0e6644096d9a60bca1df997037559609739a477daa01
Jan.-21 10:19:05.582 [JavaFX Application Thread] INFO o.b.w.Wallet: marked 86d38f8078d52998c9e6343a227e50381190feafe59502b470d126c2de86b23b:1 as spent by d66ded385ff192ee5aae0e6644096d9a60bca1df997037559609739a477daa01
Jan.-21 10:19:05.583 [JavaFX Application Thread] INFO o.b.w.Wallet: ->pending: d66ded385ff192ee5aae0e6644096d9a60bca1df997037559609739a477daa01
Jan.-21 10:19:05.584 [JavaFX Application Thread] INFO o.b.w.Wallet: Estimated balance is now: 0.1006982 BTC
Jan.-21 10:19:05.587 [JavaFX Application Thread] INFO o.b.w.WalletFiles: Saving wallet; last seen block is height 559435, date 2019-01-21T09:15:31Z, hash 00000000000000000005c501eec6a6d03c5578879a6d276ddff3fac5bab22d06
Jan.-21 10:19:05.728 [JavaFX Application Thread] INFO o.b.w.WalletFiles: Save completed in 141.6 ms
Jan.-21 10:19:05.996 [JavaFX Application Thread] INFO b.c.trade.Trade: Set new state at BuyerAsTakerTrade (id=CEALMQ): TAKER_PUBLISHED_DEPOSIT_TX
Jan.-21 10:19:06.181 [JavaFX Application Thread] INFO b.c.b.w.BtcWalletService: swap addressEntry with address 14ZemjpSeZQBoYNNzAQ3PCGX378Wmsxfqb and offerId CEALMQ-9eda1b40-c24c-42c8-be95-66471be2bb9f-093 from context RESERVED_FOR_TRADE to available
Jan.-21 10:19:06.182 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: TakerSendDepositTxPublishedMessage
Jan.-21 10:19:06.182 [JavaFX Application Thread] INFO b.c.trade.Trade: Set new state at BuyerAsTakerTrade (id=CEALMQ): TAKER_SENT_DEPOSIT_TX_PUBLISHED_MSG
Jan.-21 10:19:06.184 [JavaFX Application Thread] INFO b.c.t.p.t.t.TakerSendDepositTxPublishedMessage: Send DepositTxPublishedMessage to peer 2b4mxjclbcs6eqvv.onion:9999. tradeId=CEALMQ-9eda1b40-c24c-42c8-be95-66471be2bb9f-093, uid=8a72ad0b-7316-4391-a6c0-29ca0f8ba46c
Jan.-21 10:19:06.238 [JavaFX Application Thread] INFO b.c.t.p.t.t.TakerSendDepositTxPublishedMessage: DepositTxPublishedMessage arrived at peer 2b4mxjclbcs6eqvv.onion:9999. tradeId=CEALMQ-9eda1b40-c24c-42c8-be95-66471be2bb9f-093, uid=8a72ad0b-7316-4391-a6c0-29ca0f8ba46c
Jan.-21 10:19:06.239 [JavaFX Application Thread] INFO b.c.trade.Trade: Set new state at BuyerAsTakerTrade (id=CEALMQ): TAKER_SAW_ARRIVED_DEPOSIT_TX_PUBLISHED_MSG
Jan.-21 10:19:06.239 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: PublishTradeStatistics
Jan.-21 10:19:06.502 [JavaFX Application Thread] INFO b.c.t.p.TradeProtocol: Send AckMessage for PublishDepositTxRequest to peer 2b4mxjclbcs6eqvv.onion:9999. tradeId=CEALMQ-9eda1b40-c24c-42c8-be95-66471be2bb9f-093, sourceUid=6f8df8b9-3822-4d7c-8a3a-263469a56640
Jan.-21 10:19:06.522 [JavaFX Application Thread] INFO b.c.t.p.TradeProtocol: AckMessage for PublishDepositTxRequest arrived at peer 2b4mxjclbcs6eqvv.onion:9999. tradeId=CEALMQ-9eda1b40-c24c-42c8-be95-66471be2bb9f-093, sourceUid=6f8df8b9-3822-4d7c-8a3a-263469a56640
Jan.-21 10:19:07.589 [JavaFX Application Thread] INFO b.c.t.p.TradeProtocol: Received AckMessage for DepositTxPublishedMessage from 2b4mxjclbcs6eqvv.onion:9999 with tradeId CEALMQ-9eda1b40-c24c-42c8-be95-66471be2bb9f-093 and uid 8a72ad0b-7316-4391-a6c0-29ca0f8ba46c
Jan.-21 10:19:11.447 [JavaFX Application Thread] INFO b.c.b.w.BtcWalletService: resetAddressEntriesForOpenOffer offerId=CEALMQ-9eda1b40-c24c-42c8-be95-66471be2bb9f-093
Jan.-21 10:19:12.036 [JavaFX Application Thread] INFO b.c.p.p.PriceFeedService: request from provider http://44mgyoe2b6oqiytt.onion/ 62.395 sec. after last request
Jan.-21 10:19:13.065 [JavaFX Application Thread] INFO b.c.p.p.PriceFeedService: Received new MarketPrice(currencyCode=EUR, price=3117.38, timestampSec=1548062298393, isExternallyProvidedPrice=true) from provider http://ceaanhbvluug4we6.onion/ after 1.028 sec.
Jan.-21 10:20:12.000 [JavaFX Application Thread] INFO b.c.p.p.PriceFeedService: request from provider http://44mgyoe2b6oqiytt.onion/ 59.963 sec. after last request
Jan.-21 10:20:12.940 [JavaFX Application Thread] INFO b.c.p.p.PriceFeedService: Received new MarketPrice(currencyCode=EUR, price=3117.42, timestampSec=1548062387375, isExternallyProvidedPrice=true) from provider http://ceaanhbvluug4we6.onion/ after 0.94 sec.
Jan.-21 10:21:13.486 [JavaFX Application Thread] INFO b.c.p.p.PriceFeedService: request from provider http://44mgyoe2b6oqiytt.onion/ 61.486 sec. after last request
Jan.-21 10:21:14.606 [JavaFX Application Thread] INFO b.c.p.p.PriceFeedService: Received new MarketPrice(currencyCode=EUR, price=3117.42, timestampSec=1548062387375, isExternallyProvidedPrice=true) from provider http://ceaanhbvluug4we6.onion/ after 1.119 sec.
Jan.-21 10:21:29.525 [JavaFX Application Thread] INFO b.d.app.BisqApp: System load: Memory (MB)): 370 / No. of threads: 82
Jan.-21 10:21:52.632 [BlockingClient network thread for z33nukt7ngik3cpe.onion:8333] INFO o.b.w.Wallet: Received tx for -0.0006508 BTC: 86d38f8078d52998c9e6343a227e50381190feafe59502b470d126c2de86b23b [0] in block 0000000000000000001a35cce3141ca66ef4825338eb242795e481e85608f9e8
Jan.-21 10:21:52.633 [BlockingClient network thread for z33nukt7ngik3cpe.onion:8333] INFO o.b.w.Wallet: <-pending
Jan.-21 10:21:52.634 [BlockingClient network thread for z33nukt7ngik3cpe.onion:8333] INFO o.b.w.Wallet: Connected pending tx input d66ded385ff192ee5aae0e6644096d9a60bca1df997037559609739a477daa01:0
Jan.-21 10:21:52.634 [BlockingClient network thread for z33nukt7ngik3cpe.onion:8333] INFO o.b.w.Wallet: Removed from UNSPENTS: TxOut of 0.0101022 BTC to 14ZemjpSeZQBoYNNzAQ3PCGX378Wmsxfqb script:DUP HASH160 PUSHDATA(20)[271537d2f8114e9c1ab616bb384f949eab50fcab] EQUALVERIFY CHECKSIG
Jan.-21 10:21:52.634 [BlockingClient network thread for z33nukt7ngik3cpe.onion:8333] INFO o.b.w.Wallet: tx 86d38f8078d52998c9e6343a227e50381190feafe59502b470d126c2de86b23b ->unspent
Jan.-21 10:21:52.636 [BlockingClient network thread for z33nukt7ngik3cpe.onion:8333] INFO o.b.w.Wallet: Balance is now: 0.1006982 BTC
Jan.-21 10:21:52.642 [BlockingClient network thread for z33nukt7ngik3cpe.onion:8333] INFO o.b.w.Wallet: Received tx for -0.0101022 BTC: d66ded385ff192ee5aae0e6644096d9a60bca1df997037559609739a477daa01 [1] in block 0000000000000000001a35cce3141ca66ef4825338eb242795e481e85608f9e8
Jan.-21 10:21:52.643 [BlockingClient network thread for z33nukt7ngik3cpe.onion:8333] INFO o.b.w.Wallet: <-pending
Jan.-21 10:21:52.643 [BlockingClient network thread for z33nukt7ngik3cpe.onion:8333] INFO o.b.w.Wallet: tx d66ded385ff192ee5aae0e6644096d9a60bca1df997037559609739a477daa01 ->spent
Jan.-21 10:21:52.646 [BlockingClient network thread for z33nukt7ngik3cpe.onion:8333] INFO o.b.w.Wallet: Balance is now: 0.1006982 BTC
Jan.-21 10:21:52.652 [BlockingClient network thread for z33nukt7ngik3cpe.onion:8333] INFO o.b.w.WalletFiles: Saving wallet; last seen block is height 559436, date 2019-01-21T09:21:13Z, hash 0000000000000000001a35cce3141ca66ef4825338eb242795e481e85608f9e8
Jan.-21 10:21:52.677 [JavaFX Application Thread] INFO b.c.trade.Trade: Set new state at BuyerAsTakerTrade (id=CEALMQ): DEPOSIT_CONFIRMED_IN_BLOCK_CHAIN
Jan.-21 10:21:52.886 [BlockingClient network thread for z33nukt7ngik3cpe.onion:8333] INFO o.b.w.WalletFiles: Save completed in 215.7 ms
Jan.-21 10:21:52.974 [JavaFX Application Thread] INFO b.c.n.MobileNotificationService: Send message: 'Die Kaution-Transaktion für den Handel mit ID CEALMQ wurde bestätigt. Bitte öffnen Sie Ihre Bisq Anwendung und starten die Zahlung.'
Jan.-21 10:21:52.974 [JavaFX Application Thread] INFO b.c.n.MobileNotificationService: sendMessage message=MobileMessage(sentDate=1548062512974, txId=CEALMQ, title=Handelsstatus verändert, message=Die Kaution-Transaktion für den Handel mit ID CEALMQ wurde bestätigt. Bitte öffnen Sie Ihre Bisq Anwendung und starten die Zahlung., mobileMessageType=TRADE, type=TRADE, actionRequired=, version=1)
Jan.-21 10:21:52.992 [JavaFX Application Thread] INFO b.c.n.MobileNotificationService: json {"sentDate":1548062512974,"txId":"CEALMQ","title":"Handelsstatus verändert","message":"Die Kaution-Transaktion für den Handel mit ID CEALMQ wurde bestätigt. Bitte öffnen Sie Ihre Bisq Anwendung und starten die Zahlung.","type":"TRADE","actionRequired":"","version":1}
Jan.-21 10:21:52.997 [JavaFX Application Thread] INFO b.c.n.MobileNotificationService: key = /GJRf3a/QIqlvPTQdnIhDr4qu3t88E0q
Jan.-21 10:21:52.997 [JavaFX Application Thread] INFO b.c.n.MobileNotificationService: iv = 30ae18dd57624458
Jan.-21 10:21:52.997 [JavaFX Application Thread] INFO b.c.n.MobileNotificationService: encryptedJson = Bws6i59yxO9Xq4nEHl1zfW5voS6qiAubsB9SGslzh/T6EdQSG58MNk27lITRN6ZdnxrK9ODIUUl5RWXfEz8oiaCr7mFjVnCoRJZ47/vyXT+kE0UF3vksKRLmhfQUoKMVcchxEIvdR1zvSrvhvwO1usJsXvjKaCSM7Q2RSlB7cA3Ipa6BBhzKYQCuOnrNHhsHdLbDu4DpjMWrzm0p9FJ5qx7lv2xF3UzGbOLbDzt3a9C53SDo2Mtn9UITSvNuwkO7nB59MhMIBeiJtTGiUyEjWp6+Xz/TdA+Uq4GKwc3Jey5wvX44OSG4dL/RDG87FKa/Ve0Aoa5TKPq/r/RAZ8+8oFJcobN8dq2wOTHDuNwATzU=
Jan.-21 10:21:53.017 [JavaFX Application Thread] INFO b.c.n.MobileNotificationService: Send: token=ehHrPqjRjqY:APA91bFiV3FbonYwFdLIcKF9MEvMSWT2dd_tND3Q4wdyPPmS8KhwtAEdL5wEAj6PjT1bYm9sWZOFzR0Uy0FjxNfcfcJhf1nDcH7N-pvaQWIKm_XJ7ekG3eCW9sYYGe5nlDty8Ybff48g
Jan.-21 10:21:53.017 [JavaFX Application Thread] INFO b.c.n.MobileNotificationService: Send: msg=BisqMessageAndroid|30ae18dd57624458|Bws6i59yxO9Xq4nEHl1zfW5voS6qiAubsB9SGslzh/T6EdQSG58MNk27lITRN6ZdnxrK9ODIUUl5RWXfEz8oiaCr7mFjVnCoRJZ47/vyXT+kE0UF3vksKRLmhfQUoKMVcchxEIvdR1zvSrvhvwO1usJsXvjKaCSM7Q2RSlB7cA3Ipa6BBhzKYQCuOnrNHhsHdLbDu4DpjMWrzm0p9FJ5qx7lv2xF3UzGbOLbDzt3a9C53SDo2Mtn9UITSvNuwkO7nB59MhMIBeiJtTGiUyEjWp6+Xz/TdA+Uq4GKwc3Jey5wvX44OSG4dL/RDG87FKa/Ve0Aoa5TKPq/r/RAZ8+8oFJcobN8dq2wOTHDuNwATzU=
Jan.-21 10:21:53.017 [JavaFX Application Thread] INFO b.c.n.MobileNotificationService: Send: isAndroid=true
useSound=true
tokenAsHex=6568487250716a526a71593a4150413931624669563346626f6e597746644c49634b46394d45764d5357543264645f744e4433513477647950506d53384b6877744145644c357745416a36506a543162596d3973575a4f467a5230557930466a784e666366634a6866316e446348374e2d7076615157494b6d5f584a37656b4733654357397359594765356e6c4474793859626666343867
msgAsHex=426973714d657373616765416e64726f69647c333061653138646435373632343435387c4277733669353979784f395871346e45486c317a665735766f533671694175627342395347736c7a682f5436456451534735384d4e6b32376c4954524e365a646e78724b394f444955556c3552575866457a386f69614372376d466a566e436f524a5a34372f767958542b6b4530554633766b734b524c6d686651556f4b4d56636368784549766452317a765372766876774f3175734a7358766a4b6143534d37513252536c4237634133497061364242687a4b595143754f6e724e48687348644c6244753444706a4d57727a6d307039464a357178376c7632784633557a47624f4c62447a7433613943353353446f324d746e3955495453764e75776b4f376e4235394d684d494265694a745447695579456a5770362b587a2f5464412b557134474b7763334a65793577765834344f534734644c2f5244473837464b612f566530416f6135544b50712f722f52415a382b386f464a636f624e38647132774f544844754e7741547a553d
Jan.-21 10:21:56.388 [sendMobileNotification-42697...] INFO b.c.n.MobileNotificationService: sendMobileNotification result: Error: com.google.firebase.messaging.FirebaseMessagingException: Requested entity was not found.
Jan.-21 10:21:56.392 [JavaFX Application Thread] ERROR b.c.n.MobileNotificationService: sendMessage failed. throwable=java.lang.IllegalArgumentException: Result was not 'success'. result=Error: com.google.firebase.messaging.FirebaseMessagingException: Requested entity was not found.
Jan.-21 10:21:57.934 [Wallet autosave thread] INFO o.b.w.WalletFiles: Background saving wallet; last seen block is height 559436, date 2019-01-21T09:21:13Z, hash 0000000000000000001a35cce3141ca66ef4825338eb242795e481e85608f9e8
Jan.-21 10:21:58.160 [Wallet autosave thread] INFO o.b.w.WalletFiles: Background saving wallet; last seen block is height 559436, date 2019-01-21T09:21:13Z, hash 0000000000000000001a35cce3141ca66ef4825338eb242795e481e85608f9e8
Jan.-21 10:21:58.417 [Wallet autosave thread] INFO o.b.w.WalletFiles: Save completed in 254.8 ms
Jan.-21 10:21:58.507 [Wallet autosave thread] INFO o.b.w.WalletFiles: Save completed in 572.7 ms
Jan.-21 10:22:15.064 [JavaFX Application Thread] INFO b.c.p.p.PriceFeedService: request from provider http://44mgyoe2b6oqiytt.onion/ 61.577 sec. after last request
Jan.-21 10:22:15.995 [JavaFX Application Thread] INFO b.c.p.p.PriceFeedService: Received new MarketPrice(currencyCode=EUR, price=3118.55, timestampSec=1548062480754, isExternallyProvidedPrice=true) from provider http://ceaanhbvluug4we6.onion/ after 0.93 sec.
Jan.-21 10:22:39.450 [JavaFX Application Thread] INFO b.c.p.f.FeeService: BTC tx fee: txFeePerByte=10
Jan.-21 10:23:17.998 [JavaFX Application Thread] INFO b.c.p.p.PriceFeedService: request from provider http://44mgyoe2b6oqiytt.onion/ 62.933 sec. after last request
Jan.-21 10:23:19.302 [JavaFX Application Thread] INFO b.c.p.p.PriceFeedService: Received new MarketPrice(currencyCode=EUR, price=3119.24, timestampSec=1548062567325, isExternallyProvidedPrice=true) from provider http://ceaanhbvluug4we6.onion/ after 1.303 sec.
Jan.-21 10:23:23.912 [Wallet autosave thread] INFO o.b.w.WalletFiles: Background saving wallet; last seen block is height 559437, date 2019-01-21T09:23:22Z, hash 0000000000000000001b439c83fb75695f86138f568bfc3f3a8d7bb4be7488a0
Jan.-21 10:23:23.913 [Wallet autosave thread] INFO o.b.w.WalletFiles: Background saving wallet; last seen block is height 559437, date 2019-01-21T09:23:22Z, hash 0000000000000000001b439c83fb75695f86138f568bfc3f3a8d7bb4be7488a0
Jan.-21 10:23:24.114 [Wallet autosave thread] INFO o.b.w.WalletFiles: Save completed in 182.2 ms
Jan.-21 10:23:24.238 [Wallet autosave thread] INFO o.b.w.WalletFiles: Save completed in 296.8 ms
Jan.-21 10:24:21.086 [JavaFX Application Thread] INFO b.c.p.p.PriceFeedService: request from provider http://44mgyoe2b6oqiytt.onion/ 63.087 sec. after last request
Jan.-21 10:24:22.148 [JavaFX Application Thread] INFO b.c.p.p.PriceFeedService: Received new MarketPrice(currencyCode=EUR, price=3119.18, timestampSec=1548062657243, isExternallyProvidedPrice=true) from provider http://ceaanhbvluug4we6.onion/ after 1.061 sec.
Jan.-21 10:24:24.971 [JavaFX Application Thread] INFO b.c.trade.Trade: Set new state at BuyerAsTakerTrade (id=CEALMQ): BUYER_CONFIRMED_IN_UI_FIAT_PAYMENT_INITIATED
Jan.-21 10:24:24.979 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: CheckIfPeerIsBanned
Jan.-21 10:24:24.980 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: TakerVerifyMakerAccount
Jan.-21 10:24:24.980 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: TakerVerifyMakerFeePayment
Jan.-21 10:24:24.980 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: BuyerAsMakerSignPayoutTx
Jan.-21 10:24:24.983 [JavaFX Application Thread] INFO b.c.b.w.TradeWalletService: buyerPubKey HEX=0221700e78a77d683ba7244230b4619a228e1d6eba7bf218858834204af76af6b6
Jan.-21 10:24:24.983 [JavaFX Application Thread] INFO b.c.b.w.TradeWalletService: sellerPubKey HEX=03b802642296ff55c231aec5b1ae4bfd2d094d9433483c79c887ff60ea12b9edb5
Jan.-21 10:24:24.984 [JavaFX Application Thread] INFO b.c.b.w.TradeWalletService: arbitratorPubKey HEX=03b32b908cff09a0057b1bd6cecd2da0e8c03b88cb861b284d41792a695ff3ab24
Jan.-21 10:24:24.985 [JavaFX Application Thread] INFO b.c.b.w.WalletService:
prepared payoutTx:
721861110c8b9d39f1506500096a6fc654bfb66900c7b212bc6fca6d5be029c8
in 0.2129511 BTC (21295110)
outpoint:d66ded385ff192ee5aae0e6644096d9a60bca1df997037559609739a477daa01:0 hash160:44d9d24acb78a65874e28df1e372c5e194dfc98d
out DUP HASH160 PUSHDATA(20)[ec40b51e9961d7b3bb7516095c3589f6346a53b1] EQUALVERIFY CHECKSIG 0.2099 BTC (20990000) ScriptPubKey: 76a914ec40b51e9961d7b3bb7516095c3589f6346a53b188ac Address:1NYBzEDDioWMBuKpHiMqGK3Qth16bwqC78
out DUP HASH160 PUSHDATA(20)[4dc35c5b61eaf7f4133727f8877eaf5410718124] EQUALVERIFY CHECKSIG 0.003 BTC (300000) ScriptPubKey: 76a9144dc35c5b61eaf7f4133727f8877eaf541071812488ac Address:186B4gAFwwxJp79pjnk355YrPYTntUPng3
fee 0.0000511 BTC for 119 bytes (42 Satoshi/Byte)
prps UNKNOWN

Jan.-21 10:24:24.986 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: BuyerSendCounterCurrencyTransferStartedMessage
Jan.-21 10:24:24.987 [JavaFX Application Thread] INFO b.c.t.p.t.b.BuyerSendCounterCurrencyTransferStartedMessage: Send CounterCurrencyTransferStartedMessage to peer 2b4mxjclbcs6eqvv.onion:9999. tradeId=CEALMQ-9eda1b40-c24c-42c8-be95-66471be2bb9f-093, uid=06f1fcf8-33de-49ce-8e8e-aadbfd5ede33
Jan.-21 10:24:24.987 [JavaFX Application Thread] INFO b.c.trade.Trade: Set new state at BuyerAsTakerTrade (id=CEALMQ): BUYER_SENT_FIAT_PAYMENT_INITIATED_MSG
Jan.-21 10:24:25.034 [JavaFX Application Thread] INFO b.c.t.p.t.b.BuyerSendCounterCurrencyTransferStartedMessage: CounterCurrencyTransferStartedMessage arrived at peer 2b4mxjclbcs6eqvv.onion:9999. tradeId=CEALMQ-9eda1b40-c24c-42c8-be95-66471be2bb9f-093, uid=06f1fcf8-33de-49ce-8e8e-aadbfd5ede33
Jan.-21 10:24:25.034 [JavaFX Application Thread] INFO b.c.trade.Trade: Set new state at BuyerAsTakerTrade (id=CEALMQ): BUYER_SAW_ARRIVED_FIAT_PAYMENT_INITIATED_MSG
Jan.-21 10:24:25.053 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: BuyerSetupPayoutTxListener
Jan.-21 10:24:26.228 [JavaFX Application Thread] INFO b.c.t.p.TradeProtocol: Received AckMessage for CounterCurrencyTransferStartedMessage from 2b4mxjclbcs6eqvv.onion:9999 with tradeId CEALMQ-9eda1b40-c24c-42c8-be95-66471be2bb9f-093 and uid 06f1fcf8-33de-49ce-8e8e-aadbfd5ede33
Jan.-21 10:24:35.326 [JavaFX Application Thread] INFO b.n.p.p.PeerManager: We have 12 connections open. Our limit is 12
Jan.-21 10:24:41.610 [JavaFX Application Thread] INFO o.b.w.WalletFiles: Saving wallet; last seen block is height 559437, date 2019-01-21T09:23:22Z, hash 0000000000000000001b439c83fb75695f86138f568bfc3f3a8d7bb4be7488a0
Jan.-21 10:24:41.776 [JavaFX Application Thread] INFO o.b.w.WalletFiles: Save completed in 165.9 ms
Jan.-21 10:24:41.778 [JavaFX Application Thread] INFO b.d.m.o.t.TakeOfferDataModel: Start requestTxFee: txFeeFromFeeService=3200
Jan.-21 10:24:41.781 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: SendOfferAvailabilityRequest
Jan.-21 10:24:41.782 [JavaFX Application Thread] INFO b.c.o.a.t.SendOfferAvailabilityRequest: Send OfferAvailabilityRequest with offerId xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093 and uid 8cc2f0b0-6351-4c58-9cf5-ca1c564dd146 to peer 2b4mxjclbcs6eqvv.onion:9999
Jan.-21 10:24:41.945 [JavaFX Application Thread] INFO b.c.o.a.t.SendOfferAvailabilityRequest: OfferAvailabilityRequest arrived at peer: offerId=xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093; uid=8cc2f0b0-6351-4c58-9cf5-ca1c564dd146
Jan.-21 10:24:42.679 [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 841d49da-d0ea-40f3-b007-421d1680dfca
Jan.-21 10:24:42.680 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: ProcessOfferAvailabilityResponse
Jan.-21 10:24:42.680 [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 841d49da-d0ea-40f3-b007-421d1680dfca
Jan.-21 10:24:42.681 [JavaFX Application Thread] INFO b.n.p.p.PeerManager: Num of latestLivePeers=11
Jan.-21 10:24:42.688 [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=841d49da-d0ea-40f3-b007-421d1680dfca
Jan.-21 10:24:42.993 [JavaFX Application Thread] INFO b.c.p.f.FeeService: BTC tx fee: txFeePerByte=10
Jan.-21 10:24:42.994 [JavaFX Application Thread] INFO b.d.m.o.t.TakeOfferDataModel: Completed requestTxFee: txFeeFromFeeService=3200
Jan.-21 10:24:43.055 [JavaFX Application Thread] INFO b.c.o.OpenOfferManager: Received AckMessage for OfferAvailabilityRequest with offerId xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093 and uid 8cc2f0b0-6351-4c58-9cf5-ca1c564dd146
Jan.-21 10:24:59.892 [Wallet autosave thread] INFO o.b.w.WalletFiles: Background saving wallet; last seen block is height 559438, date 2019-01-21T09:24:48Z, hash 0000000000000000001ebc6c7eaa809fa73544c0ff14f002b1b31682ed78c8a5
Jan.-21 10:24:59.892 [Wallet autosave thread] INFO o.b.w.WalletFiles: Background saving wallet; last seen block is height 559438, date 2019-01-21T09:24:48Z, hash 0000000000000000001ebc6c7eaa809fa73544c0ff14f002b1b31682ed78c8a5
Jan.-21 10:25:00.058 [Wallet autosave thread] INFO o.b.w.WalletFiles: Save completed in 167.1 ms
Jan.-21 10:25:00.208 [Wallet autosave thread] INFO o.b.w.WalletFiles: Save completed in 317.4 ms
Jan.-21 10:25:22.942 [JavaFX Application Thread] INFO b.c.p.p.PriceFeedService: request from provider http://44mgyoe2b6oqiytt.onion/ 61.855 sec. after last request
Jan.-21 10:25:23.924 [JavaFX Application Thread] INFO b.c.p.p.PriceFeedService: Received new MarketPrice(currencyCode=EUR, price=3119.18, timestampSec=1548062657243, isExternallyProvidedPrice=true) from provider http://ceaanhbvluug4we6.onion/ after 0.981 sec.
Jan.-21 10:25:26.574 [JavaFX Application Thread] INFO o.b.w.WalletFiles: Saving wallet; last seen block is height 559438, date 2019-01-21T09:24:48Z, hash 0000000000000000001ebc6c7eaa809fa73544c0ff14f002b1b31682ed78c8a5
Jan.-21 10:25:26.722 [JavaFX Application Thread] INFO o.b.w.WalletFiles: Save completed in 148.4 ms
Jan.-21 10:25:26.789 [JavaFX Application Thread] INFO o.b.w.WalletFiles: Saving wallet; last seen block is height 559438, date 2019-01-21T09:24:48Z, hash 0000000000000000001ebc6c7eaa809fa73544c0ff14f002b1b31682ed78c8a5
Jan.-21 10:25:27.067 [JavaFX Application Thread] INFO o.b.w.WalletFiles: Save completed in 276.6 ms
Jan.-21 10:25:27.068 [JavaFX Application Thread] INFO b.c.b.w.TradeWalletService: estimateBtcTradingFeeTxSize
Jan.-21 10:25:27.068 [JavaFX Application Thread] INFO o.b.w.Wallet: Completing send tx with 2 outputs totalling 0.0105131 BTC and a fee of 0.00 BTC/kB
Jan.-21 10:25:27.070 [JavaFX Application Thread] INFO o.b.w.Wallet: with 0.0894549 BTC change
Jan.-21 10:25:27.074 [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:25:27.075 [JavaFX Application Thread] INFO o.b.w.Wallet: completed: 419b748587512dfa295f7bd8ee94347016f5a7be8760b79ffa11d16a7d327321
in PUSHDATA(72)[3045022100f9217f89d9f43d1871878da9385f8d91c0dbf4acb5d84bffbf1deb6f82b29e48022032184e2280bbfbe1a200f301201681235a2d1f5fd80ad94c6afc459b42e2facb01] PUSHDATA(33)[0331f0c6f6e01fa1d4db05d1612e6c6871772959001a8d9cbab23c94c915a6539c] 0.10 BTC (10000000)
outpoint:0096a920298ea8a88652a968f17aa4e0ef081fb4a17397c967ab14ed3eba23f2:1 hash160:a49b8306ab55c2034700d8c947bc1d7600a9d896
out DUP HASH160 PUSHDATA(20)[f6876dfc59041e46bdaece49428676dd9e5b3226] EQUALVERIFY CHECKSIG 0.0004491 BTC (44910) ScriptPubKey: 76a914f6876dfc59041e46bdaece49428676dd9e5b322688ac Address:1PUXU1MQ82JC3Hx1NN5tZs3BaTAJVg72MC
out DUP HASH160 PUSHDATA(20)[8d62da77410f73872b6203dfbc474f74c9d97c43] EQUALVERIFY CHECKSIG 0.010064 BTC (1006400) ScriptPubKey: 76a9148d62da77410f73872b6203dfbc474f74c9d97c4388ac Address:1DtahW62dYttS914fo9jYiWX4X3a3EEbP5
out DUP HASH160 PUSHDATA(20)[c7aba5ea9cfa2c81255fa6485cfbcdcf2f642ebc] EQUALVERIFY CHECKSIG 0.0894549 BTC (8945490) ScriptPubKey: 76a914c7aba5ea9cfa2c81255fa6485cfbcdcf2f642ebc88ac Address:1KCm6Vf9kgqnxSUVS6GoiGHwLaHRxmcfhP
fee 0.000032 BTC for 260 bytes (12 Satoshi/Byte)
prps USER_PAYMENT

Jan.-21 10:25:27.075 [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:25:42.886 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: SendOfferAvailabilityRequest
Jan.-21 10:25:42.887 [JavaFX Application Thread] INFO b.c.o.a.t.SendOfferAvailabilityRequest: Send OfferAvailabilityRequest with offerId xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093 and uid b16e5134-8af1-4cdf-b2e9-b9cf394f3e4d to peer 2b4mxjclbcs6eqvv.onion:9999
Jan.-21 10:25:42.891 [JavaFX Application Thread] INFO b.c.o.a.t.SendOfferAvailabilityRequest: OfferAvailabilityRequest arrived at peer: offerId=xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093; uid=b16e5134-8af1-4cdf-b2e9-b9cf394f3e4d
Jan.-21 10:25:43.999 [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 f1b673d4-22d8-4dbf-a37b-91d59ca25fb9
Jan.-21 10:25:44.000 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: ProcessOfferAvailabilityResponse
Jan.-21 10:25:44.000 [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 f1b673d4-22d8-4dbf-a37b-91d59ca25fb9
Jan.-21 10:25:44.006 [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:25:44.008 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: TakerSelectMediator
Jan.-21 10:25:44.008 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: TakerVerifyMakerAccount
Jan.-21 10:25:44.008 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: TakerVerifyMakerFeePayment
Jan.-21 10:25:44.008 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: CreateTakerFeeTx
Jan.-21 10:25:44.076 [JavaFX Application Thread] INFO b.c.b.w.TradeWalletService: reservedFundsForOffer 0.010064
Jan.-21 10:25:44.076 [JavaFX Application Thread] INFO b.c.b.w.TradeWalletService: tradingFee 0.0004491
Jan.-21 10:25:44.076 [JavaFX Application Thread] INFO b.c.b.w.TradeWalletService: txFee 0.000032
Jan.-21 10:25:44.077 [JavaFX Application Thread] INFO o.b.w.Wallet: Completing send tx with 2 outputs totalling 0.0105131 BTC and a fee of 0.00 BTC/kB
Jan.-21 10:25:44.078 [JavaFX Application Thread] INFO o.b.w.Wallet: with 0.0894549 BTC change
Jan.-21 10:25:44.081 [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:25:44.081 [JavaFX Application Thread] INFO o.b.w.Wallet: completed: 419b748587512dfa295f7bd8ee94347016f5a7be8760b79ffa11d16a7d327321
in PUSHDATA(72)[3045022100f9217f89d9f43d1871878da9385f8d91c0dbf4acb5d84bffbf1deb6f82b29e48022032184e2280bbfbe1a200f301201681235a2d1f5fd80ad94c6afc459b42e2facb01] PUSHDATA(33)[0331f0c6f6e01fa1d4db05d1612e6c6871772959001a8d9cbab23c94c915a6539c] 0.10 BTC (10000000)
outpoint:0096a920298ea8a88652a968f17aa4e0ef081fb4a17397c967ab14ed3eba23f2:1 hash160:a49b8306ab55c2034700d8c947bc1d7600a9d896
out DUP HASH160 PUSHDATA(20)[f6876dfc59041e46bdaece49428676dd9e5b3226] EQUALVERIFY CHECKSIG 0.0004491 BTC (44910) ScriptPubKey: 76a914f6876dfc59041e46bdaece49428676dd9e5b322688ac Address:1PUXU1MQ82JC3Hx1NN5tZs3BaTAJVg72MC
out DUP HASH160 PUSHDATA(20)[8d62da77410f73872b6203dfbc474f74c9d97c43] EQUALVERIFY CHECKSIG 0.010064 BTC (1006400) ScriptPubKey: 76a9148d62da77410f73872b6203dfbc474f74c9d97c4388ac Address:1DtahW62dYttS914fo9jYiWX4X3a3EEbP5
out DUP HASH160 PUSHDATA(20)[c7aba5ea9cfa2c81255fa6485cfbcdcf2f642ebc] EQUALVERIFY CHECKSIG 0.0894549 BTC (8945490) ScriptPubKey: 76a914c7aba5ea9cfa2c81255fa6485cfbcdcf2f642ebc88ac Address:1KCm6Vf9kgqnxSUVS6GoiGHwLaHRxmcfhP
fee 0.000032 BTC for 260 bytes (12 Satoshi/Byte)
prps USER_PAYMENT

Jan.-21 10:25:44.082 [JavaFX Application Thread] INFO b.c.b.w.WalletService:
tradingFeeTx:
419b748587512dfa295f7bd8ee94347016f5a7be8760b79ffa11d16a7d327321
in PUSHDATA(72)[3045022100f9217f89d9f43d1871878da9385f8d91c0dbf4acb5d84bffbf1deb6f82b29e48022032184e2280bbfbe1a200f301201681235a2d1f5fd80ad94c6afc459b42e2facb01] PUSHDATA(33)[0331f0c6f6e01fa1d4db05d1612e6c6871772959001a8d9cbab23c94c915a6539c] 0.10 BTC (10000000)
outpoint:0096a920298ea8a88652a968f17aa4e0ef081fb4a17397c967ab14ed3eba23f2:1 hash160:a49b8306ab55c2034700d8c947bc1d7600a9d896
out DUP HASH160 PUSHDATA(20)[f6876dfc59041e46bdaece49428676dd9e5b3226] EQUALVERIFY CHECKSIG 0.0004491 BTC (44910) ScriptPubKey: 76a914f6876dfc59041e46bdaece49428676dd9e5b322688ac Address:1PUXU1MQ82JC3Hx1NN5tZs3BaTAJVg72MC
out DUP HASH160 PUSHDATA(20)[8d62da77410f73872b6203dfbc474f74c9d97c43] EQUALVERIFY CHECKSIG 0.010064 BTC (1006400) ScriptPubKey: 76a9148d62da77410f73872b6203dfbc474f74c9d97c4388ac Address:1DtahW62dYttS914fo9jYiWX4X3a3EEbP5
out DUP HASH160 PUSHDATA(20)[c7aba5ea9cfa2c81255fa6485cfbcdcf2f642ebc] EQUALVERIFY CHECKSIG 0.0894549 BTC (8945490) ScriptPubKey: 76a914c7aba5ea9cfa2c81255fa6485cfbcdcf2f642ebc88ac Address:1KCm6Vf9kgqnxSUVS6GoiGHwLaHRxmcfhP
fee 0.000032 BTC for 260 bytes (12 Satoshi/Byte)
prps USER_PAYMENT

Jan.-21 10:25:44.083 [JavaFX Application Thread] INFO o.b.c.TransactionBroadcast: Waiting for 4 peers required for broadcast, we have 9 ...
Jan.-21 10:25:44.083 [JavaFX Application Thread] INFO o.b.c.TransactionBroadcast: broadcastTransaction: We have 9 peers, adding 419b748587512dfa295f7bd8ee94347016f5a7be8760b79ffa11d16a7d327321 to the memory pool
Jan.-21 10:25:44.083 [JavaFX Application Thread] INFO o.b.c.TransactionBroadcast: Sending to 5 peers, will wait for 2, sending to: [3xucqntxp5ddoaz5.onion]:8333,[4jyh6llqj264oggs.onion]:8333,[3r44ddzjitznyahw.onion]:8333,[i3a5xtzfm4xwtybd.onion]:8333,[z33nukt7ngik3cpe.onion]:8333
Jan.-21 10:25:44.089 [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=f1b673d4-22d8-4dbf-a37b-91d59ca25fb9
Jan.-21 10:25:44.103 [JavaFX Application Thread] INFO b.c.o.OpenOfferManager: Received AckMessage for OfferAvailabilityRequest with offerId xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093 and uid b16e5134-8af1-4cdf-b2e9-b9cf394f3e4d
Jan.-21 10:25:47.911 [BlockingClient network thread for mxdtrjhe2yfsx3pg.onion:8333] INFO o.b.core.Peer: [mxdtrjhe2yfsx3pg.onion]:8333: Downloading dependencies of 419b748587512dfa295f7bd8ee94347016f5a7be8760b79ffa11d16a7d327321
Jan.-21 10:25:48.562 [BlockingClient network thread for mxdtrjhe2yfsx3pg.onion:8333] INFO o.b.core.Peer: [mxdtrjhe2yfsx3pg.onion]:8333: Bottomed out dep tree at 0096a920298ea8a88652a968f17aa4e0ef081fb4a17397c967ab14ed3eba23f2
Jan.-21 10:25:48.562 [BlockingClient network thread for mxdtrjhe2yfsx3pg.onion:8333] INFO o.b.core.Peer: [mxdtrjhe2yfsx3pg.onion]:8333: Dependency download complete!
Jan.-21 10:25:48.564 [BlockingClient network thread for mxdtrjhe2yfsx3pg.onion:8333] INFO o.b.w.Wallet: Received a pending transaction 419b748587512dfa295f7bd8ee94347016f5a7be8760b79ffa11d16a7d327321 that spends 0.10 BTC from our own wallet, and sends us 0.0995189 BTC
Jan.-21 10:25:48.564 [BlockingClient network thread for mxdtrjhe2yfsx3pg.onion:8333] INFO o.b.w.Wallet: commitTx of 419b748587512dfa295f7bd8ee94347016f5a7be8760b79ffa11d16a7d327321
Jan.-21 10:25:48.565 [BlockingClient network thread for mxdtrjhe2yfsx3pg.onion:8333] INFO o.b.w.Wallet: marked 0096a920298ea8a88652a968f17aa4e0ef081fb4a17397c967ab14ed3eba23f2:1 as spent by 419b748587512dfa295f7bd8ee94347016f5a7be8760b79ffa11d16a7d327321
Jan.-21 10:25:48.566 [BlockingClient network thread for mxdtrjhe2yfsx3pg.onion:8333] INFO o.b.w.Wallet: 0096a920298ea8a88652a968f17aa4e0ef081fb4a17397c967ab14ed3eba23f2 prevtx <-unspent ->spent
Jan.-21 10:25:48.567 [BlockingClient network thread for mxdtrjhe2yfsx3pg.onion:8333] INFO o.b.w.Wallet: ->pending: 419b748587512dfa295f7bd8ee94347016f5a7be8760b79ffa11d16a7d327321
Jan.-21 10:25:48.567 [BlockingClient network thread for mxdtrjhe2yfsx3pg.onion:8333] INFO o.b.w.Wallet: Estimated balance is now: 0.1002171 BTC
Jan.-21 10:25:48.570 [BlockingClient network thread for mxdtrjhe2yfsx3pg.onion:8333] INFO o.b.w.WalletFiles: Saving wallet; last seen block is height 559438, date 2019-01-21T09:24:48Z, hash 0000000000000000001ebc6c7eaa809fa73544c0ff14f002b1b31682ed78c8a5
Jan.-21 10:25:48.725 [BlockingClient network thread for mxdtrjhe2yfsx3pg.onion:8333] INFO o.b.w.WalletFiles: Save completed in 154.6 ms
Jan.-21 10:26:14.077 [JavaFX Application Thread] WARN b.c.b.w.TxBroadcaster: Broadcast of tx 419b748587512dfa295f7bd8ee94347016f5a7be8760b79ffa11d16a7d327321 not completed after 30 sec.
Jan.-21 10:26:14.079 [JavaFX Application Thread] WARN b.c.b.w.TxBroadcaster: TxBroadcaster.onTimeout called: TxBroadcastTimeoutException{
localTx= 419b748587512dfa295f7bd8ee94347016f5a7be8760b79ffa11d16a7d327321
in PUSHDATA(72)[3045022100f9217f89d9f43d1871878da9385f8d91c0dbf4acb5d84bffbf1deb6f82b29e48022032184e2280bbfbe1a200f301201681235a2d1f5fd80ad94c6afc459b42e2facb01] PUSHDATA(33)[0331f0c6f6e01fa1d4db05d1612e6c6871772959001a8d9cbab23c94c915a6539c] 0.10 BTC (10000000)
outpoint:0096a920298ea8a88652a968f17aa4e0ef081fb4a17397c967ab14ed3eba23f2:1 hash160:a49b8306ab55c2034700d8c947bc1d7600a9d896
out DUP HASH160 PUSHDATA(20)[f6876dfc59041e46bdaece49428676dd9e5b3226] EQUALVERIFY CHECKSIG 0.0004491 BTC (44910) ScriptPubKey: 76a914f6876dfc59041e46bdaece49428676dd9e5b322688ac Address:1PUXU1MQ82JC3Hx1NN5tZs3BaTAJVg72MC
out DUP HASH160 PUSHDATA(20)[8d62da77410f73872b6203dfbc474f74c9d97c43] EQUALVERIFY CHECKSIG 0.010064 BTC (1006400) ScriptPubKey: 76a9148d62da77410f73872b6203dfbc474f74c9d97c4388ac Address:1DtahW62dYttS914fo9jYiWX4X3a3EEbP5
out DUP HASH160 PUSHDATA(20)[c7aba5ea9cfa2c81255fa6485cfbcdcf2f642ebc] EQUALVERIFY CHECKSIG 0.0894549 BTC (8945490) ScriptPubKey: 76a914c7aba5ea9cfa2c81255fa6485cfbcdcf2f642ebc88ac Address:1KCm6Vf9kgqnxSUVS6GoiGHwLaHRxmcfhP
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=419b748587512dfa295f7bd8ee94347016f5a7be8760b79ffa11d16a7d327321
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:26:14.079 [JavaFX Application Thread] INFO b.c.b.w.BtcWalletService: swap addressEntry with address 19yda7MBWJFzBQUumnJBJCgoRvGLNJyA61 and offerId xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093 from context OFFER_FUNDING to available
Jan.-21 10:26:14.080 [JavaFX Application Thread] INFO b.c.trade.Trade: Set new state at BuyerAsTakerTrade (id=xpraf): TAKER_PUBLISHED_TAKER_FEE_TX
Jan.-21 10:26:14.081 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: BuyerAsTakerCreatesDepositTxInputs
Jan.-21 10:26:14.114 [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:26:14.120 [JavaFX Application Thread] INFO o.b.w.Wallet: with a fee of 0.00016753 BTC/kB, 0.000032 BTC for 191 bytes
Jan.-21 10:26:14.120 [JavaFX Application Thread] INFO o.b.w.Wallet: completed: 88b13fc024c9d9c0223c6a89fc7ccd9e720b3ef67644531d23a9ea75ef3a3200
in PUSHDATA(71)[304402204aa5a14b2e955a3d62f98c749724eb9d05faef97bebee50f9ecd38133b7350e0022075f8890e3818623321d56f2ac31f926165a2f5490e2ca728e3421905dab33f9201] PUSHDATA(33)[0393d5dd6b7641f7eeab40a2474900919086d803d8282d40f55f5f76662e3b13e0] 0.010064 BTC (1006400)
outpoint:419b748587512dfa295f7bd8ee94347016f5a7be8760b79ffa11d16a7d327321:1 hash160:8d62da77410f73872b6203dfbc474f74c9d97c43
out DUP HASH160 PUSHDATA(20)[af6fbe967466e0f22ee4371457d4f94e5bc15a1e] EQUALVERIFY CHECKSIG 0.010032 BTC (1003200) ScriptPubKey: 76a914af6fbe967466e0f22ee4371457d4f94e5bc15a1e88ac Address:1Gzd7iMz8YTFQ57XJaUGWsKAgWoottuZGq
fee 0.000032 BTC for 191 bytes (16 Satoshi/Byte)
prps USER_PAYMENT

Jan.-21 10:26:14.120 [JavaFX Application Thread] INFO b.c.t.TaskRunner: Run task: TakerSendPayDepositRequest
Jan.-21 10:26:14.190 [JavaFX Application Thread] INFO o.b.w.WalletFiles: Saving wallet; last seen block is height 559438, date 2019-01-21T09:24:48Z, hash 0000000000000000001ebc6c7eaa809fa73544c0ff14f002b1b31682ed78c8a5
Jan.-21 10:26:14.504 [JavaFX Application Thread] INFO o.b.w.WalletFiles: Save completed in 313.7 ms
Jan.-21 10:26:14.505 [JavaFX Application Thread] INFO b.c.t.p.t.t.TakerSendPayDepositRequest: Send PayDepositRequest with offerId xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093 and uid 208bfa68-d8b0-4a8a-956e-0b9782ffe203 to peer 2b4mxjclbcs6eqvv.onion:9999
Jan.-21 10:26:14.525 [JavaFX Application Thread] INFO b.c.t.p.t.t.TakerSendPayDepositRequest: PayDepositRequest arrived at peer: offerId=xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093; uid=208bfa68-d8b0-4a8a-956e-0b9782ffe203
Jan.-21 10:26:15.456 [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.1527 BTC
Jan.-21 10:26:22.909 [JavaFX Application Thread] INFO b.c.p.p.PriceFeedService: request from provider http://44mgyoe2b6oqiytt.onion/ 59.966 sec. after last request
Jan.-21 10:26:24.031 [JavaFX Application Thread] INFO b.c.p.p.PriceFeedService: Received new MarketPrice(currencyCode=EUR, price=3119.94, timestampSec=1548062747246, isExternallyProvidedPrice=true) from provider http://ceaanhbvluug4we6.onion/ after 1.122 sec.
Jan.-21 10:26:39.210 [JavaFX Application Thread] WARN b.c.trade.Trade: depositTx is null
Jan.-21 10:26:39.211 [JavaFX Application Thread] WARN b.c.trade.Trade: depositTx is null
Jan.-21 10:27:24.869 [JavaFX Application Thread] INFO b.c.p.p.PriceFeedService: request from provider http://44mgyoe2b6oqiytt.onion/ 61.96 sec. after last request
Jan.-21 10:27:27.655 [JavaFX Application Thread] INFO b.c.p.p.PriceFeedService: Received new MarketPrice(currencyCode=EUR, price=3120.63, timestampSec=1548062837241, isExternallyProvidedPrice=true) from provider http://ceaanhbvluug4we6.onion/ after 2.786 sec.
Jan.-21 10:27:39.016 [JavaFX Application Thread] WARN b.c.trade.Trade: depositTx is null
Jan.-21 10:27:39.017 [JavaFX Application Thread] WARN b.c.trade.Trade: depositTx is null
Jan.-21 10:27:40.299 [JavaFX Application Thread] INFO b.c.p.f.FeeService: BTC tx fee: txFeePerByte=10
Jan.-21 10:27:44.639 [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:27:44.664 [JavaFX Application Thread] WARN b.c.t.p.TradeProtocol: cleanupTradableOnFault tradeState=TAKER_PUBLISHED_TAKER_FEE_TX
Jan.-21 10:27:44.668 [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:27:44.669 [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:27:44.669 [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:27:44.669 [JavaFX Application Thread] INFO b.c.b.w.BtcWalletService: swap addressEntry with address 19yda7MBWJFzBQUumnJBJCgoRvGLNJyA61 and offerId xpraf-d3c6cab7-c7d1-4243-9d25-64de87a2ce37-093 from context MULTI_SIG to available
Jan.-21 10:27:44.670 [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:28:24.958 [JavaFX Application Thread] INFO b.c.p.p.PriceFeedService: request from provider http://44mgyoe2b6oqiytt.onion/ 60.089 sec. after last request
Jan.-21 10:28:26.125 [JavaFX Application Thread] INFO b.c.p.p.PriceFeedService: Received new MarketPrice(currencyCode=EUR, price=3120.63, timestampSec=1548062837241, isExternallyProvidedPrice=true) from provider http://ceaanhbvluug4we6.onion/ after 1.167 sec.
Jan.-21 10:29:27.047 [JavaFX Application Thread] INFO b.c.p.p.PriceFeedService: request from provider http://44mgyoe2b6oqiytt.onion/ 62.089 sec. after last request
Jan.-21 10:29:28.067 [JavaFX Application Thread] INFO b.c.p.p.PriceFeedService: Received new MarketPrice(currencyCode=EUR, price=3121.79, timestampSec=1548062927245, isExternallyProvidedPrice=true) from provider http://ceaanhbvluug4we6.onion/ after 1.019 sec.
Jan.-21 10:29:32.876 [JavaFX Application Thread] INFO b.n.p.p.PeerManager: We have 13 connections open. Our limit is 12
Jan.-21 10:29:32.876 [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:29:32.876 [JavaFX Application Thread] INFO b.n.p.p.PeerManager: checkMaxConnections: Num candidates for shut down=3. We close oldest connection: Connection{peerAddress=Optional[ypa3nyh6hq2mtgbl.onion:9999], peerType=PEER, uid='b9f81615-bba8-4844-b25e-27d586412cd2'}
Jan.-21 10:29:32.878 [InputHandler-3f3cu2yw7u457ztq.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[3f3cu2yw7u457ztq.onion:8000], peerType=SEED_NODE, uid='2d57e7dc-7019-488e-9755-022cc8d78d0e'}
Jan.-21 10:29:32.879 [InputHandler-ypa3nyh6hq2mtgbl.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[ypa3nyh6hq2mtgbl.onion:9999], peerType=PEER, uid='b9f81615-bba8-4844-b25e-27d586412cd2'}
Jan.-21 10:29:33.080 [JavaFX Application Thread] INFO b.n.p.p.PeerManager: onDisconnect called: nodeAddress=Optional[3f3cu2yw7u457ztq.onion:8000], closeConnectionReason=CloseConnectionReason{sendCloseMessage=true, isIntended=true} TOO_MANY_SEED_NODES_CONNECTED
Jan.-21 10:29:33.272 [JavaFX Application Thread] INFO b.n.p.p.p.PeerExchangeManager: onDisconnect closeConnectionReason=CloseConnectionReason{sendCloseMessage=true, isIntended=true} TOO_MANY_SEED_NODES_CONNECTED, nodeAddressOpt=Optional[3f3cu2yw7u457ztq.onion:8000]
Jan.-21 10:29:33.350 [JavaFX Application Thread] INFO b.n.p.p.PeerManager: onDisconnect called: nodeAddress=Optional[ypa3nyh6hq2mtgbl.onion:9999], closeConnectionReason=CloseConnectionReason{sendCloseMessage=true, isIntended=true} TOO_MANY_CONNECTIONS_OPEN
Jan.-21 10:29:33.351 [JavaFX Application Thread] INFO b.n.p.p.p.PeerExchangeManager: onDisconnect closeConnectionReason=CloseConnectionReason{sendCloseMessage=true, isIntended=true} TOO_MANY_CONNECTIONS_OPEN, nodeAddressOpt=Optional[ypa3nyh6hq2mtgbl.onion:9999]
Jan.-21 10:29:33.468 [JavaFX Application Thread] INFO b.n.p.p.PeerManager: We have 11 connections open. Our limit is 12

@Sylo44
Copy link
Author

Sylo44 commented Jan 21, 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

@ManfredKarrer
Copy link
Contributor

It can be that the peer is not well connected (e.g. if he has not updated to 0.9 and is in standby mode). if you have that issue with other traders as well you can consider to switch to a new data directly. See https://bisq.community/t/how-to-switch-to-a-new-data-directory/6753/

@Sylo44
Copy link
Author

Sylo44 commented Jan 21, 2019

The first order worked flawlessly and it was from the same peer. Not even 5 minutes apart.

@ManfredKarrer
Copy link
Contributor

Yes if the peer is online it should be all ok, but if his OS is in standby and gets veru low network resources then such issues happen, that the offer is still online but taking the offer times out as the app is not responding fast enough. At least that what I found on OSX with appNap. Not sure about other OS. WIth 0.9 we fixed that by avoiding standby mode, but that is also only verified on OSX, not sure about other OS. So it can be that the peer has an older version or other OS where there is still an issue with standby. Or it is some other issue (network instable, bug on peers side...). Or he has blocked your onion address, but I think that would cause another error message...

@Sylo44
Copy link
Author

Sylo44 commented Jan 22, 2019

But wouldnt that be strange considering that the first order worked without any problems and flawlessly? Then not even 5 minutes later second order from same peer times out. I have already more than 5 orders with this peer, so I think its safe to assume that he probably didn't block me out of the blue. Why should he block me in between 5 minutes of order #1 and order #2. Also the argument about the network ressources seem thin as there was no problem in the first place with order #1 and then 5 minutes later the OS would stop allocating the ressources to the app?

I'm not very techy person but intuitively this chain of events seem highly improbable to me. If order #1 and #2 were two different peers I might give it a higher probability of it beeing realistic.

@ghost
Copy link

ghost commented Jan 22, 2019

In the last 48 hours, my network monitoring recorded very few network connection issues.
(It's however purely from my side).

@ripcurlx
Copy link
Contributor

ripcurlx commented Jan 22, 2019

@Sylo44 I looked at your error logs and saw that it fails always because the taker somehow had insuffient money to create the transaction.

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

@ManfredKarrer Do you have an idea how someone can publish an offer without having sufficient money locked up for a trade to happen?

@ripcurlx ripcurlx added the a:bug label Jan 22, 2019
@Sylo44
Copy link
Author

Sylo44 commented Jan 22, 2019

thank you @ripcurlx for digging into it. Didn't know it was even a possibility. hope this incident helps to fix this issue

@ripcurlx
Copy link
Contributor

This is a similar issue as in #2293.

@ripcurlx ripcurlx added the is:priority PR or issue marked with this label is up for compensation label Feb 18, 2020
@ripcurlx ripcurlx added the is:critical https://bisq.wiki/Critical_bug label Feb 18, 2020
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
a:bug is:critical https://bisq.wiki/Critical_bug is:priority PR or issue marked with this label is up for compensation
Projects
None yet
Development

No branches or pull requests

4 participants