first_pass

gistfile1.txt

2023-06-02T14:14:43.552116Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}: searching for path src=pen dst=pen max_hops=5
2023-06-02T14:14:43.552636Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}: beginning path
2023-06-02T14:14:43.552722Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}: relaxing active paths active_paths_len=1
2023-06-02T14:14:43.552804Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}: relaxing path degree=7 candidates=[gm, test_btc, test_usd, test_osmo, test_atom, pen, gn]
2023-06-02T14:14:43.553487Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:extend_to{new_end=test_osmo}: no best position, failing to extend path
2023-06-02T14:14:43.553620Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:extend_to{new_end=test_atom}: no best position, failing to extend path
2023-06-02T14:14:43.553757Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:extend_to{new_end=test_btc}: no best position, failing to extend path
2023-06-02T14:14:43.554004Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:extend_to{new_end=gm}: no best position, failing to extend path
2023-06-02T14:14:43.555736Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:extend_to{new_end=pen}: no best position, failing to extend path
2023-06-02T14:14:43.555847Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:extend_to{new_end=test_usd}: deindexing position
2023-06-02T14:14:43.555917Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:extend_to{new_end=test_usd}: extended path path_price=0.9090909090909091 hop_price=0.9090909090909091 id=plpid1vkrrjqh249g4emy8l76pvcmpepadf7445jamzug44ud2npysvtpsgtlwxx
2023-06-02T14:14:43.556036Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}: inserting new path
2023-06-02T14:14:43.556461Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:extend_to{new_end=gn}: no best position, failing to extend path
2023-06-02T14:14:43.557393Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}: finished relaxing all active paths i=0
2023-06-02T14:14:43.557665Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}: relaxing active paths active_paths_len=1
2023-06-02T14:14:43.557761Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}: relaxing path degree=7 candidates=[gm, test_btc, test_usd, test_osmo, test_atom, pen, gn]
2023-06-02T14:14:43.558557Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:extend_to{new_end=gm}: no best position, failing to extend path
2023-06-02T14:14:43.559028Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:extend_to{new_end=test_osmo}: no best position, failing to extend path
2023-06-02T14:14:43.559110Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:extend_to{new_end=pen}: deindexing position
2023-06-02T14:14:43.559131Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:extend_to{new_end=pen}: extended path path_price=1 hop_price=1.1 id=plpid1qdf8l3x4e9vzwfvuuprnll8yc6pljhkjy5864r0qk9kcugaq50lqhka606
2023-06-02T14:14:43.559187Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=pen}: updating path new_price=1 old_price=1
2023-06-02T14:14:43.559251Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:extend_to{new_end=gn}: no best position, failing to extend path
2023-06-02T14:14:43.559878Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:extend_to{new_end=test_usd}: no best position, failing to extend path
2023-06-02T14:14:43.560200Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:extend_to{new_end=test_atom}: key=b"dex/position/plpid1q795wxsyr8qy3kjchmvkn9qsua333kwnf6c8ye453t2nce5yejcskg0yqt" key_hash=KeyHash("9f50d09e684d4cfbf924b5484dd702cd333a57eb272f67dfcfab26c619b3c9f4")
2023-06-02T14:14:43.560964Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:extend_to{new_end=test_btc}: key=b"dex/position/plpid1xpyfkc6es6svyajseffscluepe24r8m9cwrv5mu40hp5ga758khs8hky73" key_hash=KeyHash("80986090078de97c861338d21e6c42919c0903c2cd0b93a04eb2187339a1a03e")
2023-06-02T14:14:43.561080Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:extend_to{new_end=test_atom}: deindexing position
2023-06-02T14:14:43.561137Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:extend_to{new_end=test_atom}: extended path path_price=9.542699724517906 hop_price=10.496969696969696 id=plpid1q795wxsyr8qy3kjchmvkn9qsua333kwnf6c8ye453t2nce5yejcskg0yqt
2023-06-02T14:14:43.561167Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=test_atom}: inserting new path
2023-06-02T14:14:43.561529Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:extend_to{new_end=test_btc}: deindexing position
2023-06-02T14:14:43.561549Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:extend_to{new_end=test_btc}: extended path path_price=246.57359044995408 hop_price=271.23094949494947 id=plpid1xpyfkc6es6svyajseffscluepe24r8m9cwrv5mu40hp5ga758khs8hky73
2023-06-02T14:14:43.561693Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=test_btc}: inserting new path
2023-06-02T14:14:43.561727Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}: finished relaxing all active paths i=1
2023-06-02T14:14:43.561737Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}: relaxing active paths active_paths_len=3
2023-06-02T14:14:43.561780Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=pen}: relaxing path degree=7 candidates=[gm, test_btc, test_usd, test_osmo, test_atom, pen, gn]
2023-06-02T14:14:43.562008Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=test_atom}: relaxing path degree=7 candidates=[gm, test_btc, test_usd, test_osmo, test_atom, pen, gn]
2023-06-02T14:14:43.562114Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=test_btc}: relaxing path degree=7 candidates=[gm, test_btc, test_usd, test_osmo, test_atom, pen, gn]
2023-06-02T14:14:43.562865Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=test_atom}:extend_to{new_end=gn}: no best position, failing to extend path
2023-06-02T14:14:43.563206Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=test_btc}:extend_to{new_end=gn}: no best position, failing to extend path
2023-06-02T14:14:43.563241Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=test_atom}:extend_to{new_end=gm}: no best position, failing to extend path
2023-06-02T14:14:43.563287Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=test_atom}:extend_to{new_end=test_btc}: key=b"dex/position/plpid1egreudqrqr36nkat5fk39ajel0n3z6apkftr5xucn5rtmgxvqeeq9efxv4" key_hash=KeyHash("25cd51fd6d63096e697f23cb5dc5f2d12e6a9c859ef2e510f3f8a08bb04c0ca3")
2023-06-02T14:14:43.563482Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=pen}:extend_to{new_end=test_osmo}: no best position, failing to extend path
2023-06-02T14:14:43.563648Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=pen}:extend_to{new_end=test_usd}: deindexing position
2023-06-02T14:14:43.563677Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=pen}:extend_to{new_end=test_usd}: extended path path_price=1.0101010101010102 hop_price=1.0101010101010102 id=plpid1f65rs3acdtl8vz7n4vqnckcyun2s235s3n4aep00gv606zzfujesjs35vt
2023-06-02T14:14:43.563903Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=pen}:extend_to{new_end=test_btc}: no best position, failing to extend path
2023-06-02T14:14:43.564059Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=test_atom}:extend_to{new_end=test_btc}: deindexing position
2023-06-02T14:14:43.564090Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=test_atom}:extend_to{new_end=test_btc}: extended path path_price=249.71737385560021 hop_price=26.168419950803372 id=plpid1egreudqrqr36nkat5fk39ajel0n3z6apkftr5xucn5rtmgxvqeeq9efxv4
2023-06-02T14:14:43.564368Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=test_atom}:extend_to{new_end=test_osmo}: no best position, failing to extend path
2023-06-02T14:14:43.564900Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=test_atom}:extend_to{new_end=test_atom}: no best position, failing to extend path
2023-06-02T14:14:43.564937Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=test_atom}:extend_to{new_end=test_usd}: no best position, failing to extend path
2023-06-02T14:14:43.565005Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=pen}:extend_to{new_end=test_atom}: no best position, failing to extend path
2023-06-02T14:14:43.565019Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=test_atom}:extend_to{new_end=pen}: no best position, failing to extend path
2023-06-02T14:14:43.565162Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=test_btc}:extend_to{new_end=test_osmo}: no best position, failing to extend path
2023-06-02T14:14:43.565410Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=test_btc}:extend_to{new_end=test_btc}: no best position, failing to extend path
2023-06-02T14:14:43.565494Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=test_btc}:extend_to{new_end=test_usd}: no best position, failing to extend path
2023-06-02T14:14:43.565740Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=pen}:extend_to{new_end=gm}: no best position, failing to extend path
2023-06-02T14:14:43.565775Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=test_btc}:extend_to{new_end=gm}: no best position, failing to extend path
2023-06-02T14:14:43.566400Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=test_btc}:extend_to{new_end=pen}: no best position, failing to extend path
2023-06-02T14:14:43.566434Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=pen}:extend_to{new_end=pen}: no best position, failing to extend path
2023-06-02T14:14:43.566449Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=pen}:extend_to{new_end=gn}: no best position, failing to extend path
2023-06-02T14:14:43.566475Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=test_btc}:extend_to{new_end=test_atom}: key=b"dex/position/plpid1egreudqrqr36nkat5fk39ajel0n3z6apkftr5xucn5rtmgxvqeeq9efxv4" key_hash=KeyHash("25cd51fd6d63096e697f23cb5dc5f2d12e6a9c859ef2e510f3f8a08bb04c0ca3")
2023-06-02T14:14:43.566604Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=test_btc}:extend_to{new_end=test_atom}: deindexing position
2023-06-02T14:14:43.566704Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}:path{start=pen}:via{id=test_usd}:via{id=test_btc}:extend_to{new_end=test_atom}: extended path path_price=9.613879385220432 hop_price=0.03898989898989899 id=plpid1egreudqrqr36nkat5fk39ajel0n3z6apkftr5xucn5rtmgxvqeeq9efxv4
2023-06-02T14:14:43.566814Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}: finished relaxing all active paths i=2
2023-06-02T14:14:43.566892Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}: relaxing active paths active_paths_len=0
2023-06-02T14:14:43.566925Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}: finished relaxing all active paths i=3
2023-06-02T14:14:43.566995Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}: relaxing active paths active_paths_len=0
2023-06-02T14:14:43.567030Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}: finished relaxing all active paths i=4
2023-06-02T14:14:43.567096Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}: found path price=1 spill_price=1 src=pen nodes=[test_usd, pen]
2023-06-02T14:14:43.567184Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill: found path, starting to fill up to spill price path=[test_usd, pen] delta_1=18446744072650523539
2023-06-02T14:14:43.567357Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route: input=18446744072650523539 route=[pen, test_usd, pen] spill_price=Some(U128x128 { integral: 1, fractional: 0 })
2023-06-02T14:14:43.567763Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route: assembled initial frontier frontier=Frontier { pairs: [DirectedTradingPair { start: pen, end: test_usd }, DirectedTradingPair { start: test_usd, end: pen }], positions: [Position { state: Opened, reserves: Reserves { r1: 500000000, r2: 44686822576 }, phi: TradingFunction { component: BareTradingFunction { fee: 0, p: 1000000, q: 1100000 }, pair: TradingPair { asset_1: test_usd, asset_2: pen } }, nonce: "2c58155f59985ae9027ab195a6d18547a40fd7fbeff129fda51322d03de53613" }, Position { state: Opened, reserves: Reserves { r1: 0, r2: 50160 }, phi: TradingFunction { component: BareTradingFunction { fee: 0, p: 1000000, q: 1100000 }, pair: TradingPair { asset_1: test_usd, asset_2: pen } }, nonce: "7cc3fc6166037683cb57cbf0c60a9085cc5a272ffa8d6ed7d673b61a0cfc7d84" }], position_ids: {plpid1qdf8l3x4e9vzwfvuuprnll8yc6pljhkjy5864r0qk9kcugaq50lqhka606, plpid1vkrrjqh249g4emy8l76pvcmpepadf7445jamzug44ud2npysvtpsgtlwxx}, trace: [], .. }
2023-06-02T14:14:43.567875Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:sense_capacity_constraint{input=18446744072650523539}: sensing frontier capacity with test amount
2023-06-02T14:14:43.567900Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:sense_capacity_constraint{input=18446744072650523539}: could not completely fill input amount, marking as constraining i=0 current_input=18446744072650523539 unfilled=18446744072195978084 output=500000000 old_reserves=Reserves { r1: 500000000, r2: 44686822576 } new_reserves=Reserves { r1: 0, r2: 45141368031 }
2023-06-02T14:14:43.568287Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:sense_capacity_constraint{input=18446744072650523539}: could not completely fill input amount, marking as constraining i=1 current_input=500000000 unfilled=499944824 output=50160 old_reserves=Reserves { r1: 0, r2: 50160 } new_reserves=Reserves { r1: 55176, r2: 0 }
2023-06-02T14:14:43.568300Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route: attempting to completely consume reserves of constraining position constraining_index=1 exactly_consumed_reserves=50160
2023-06-02T14:14:43.568311Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:fill_backward{start_index=1 output=50160}: filling backward along frontier
2023-06-02T14:14:43.568320Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:fill_backward{start_index=1 output=50160}:fill_output: computed reverse fill reserves=Reserves { r1: 0, r2: 50160 } lambda_2=50160 fillable_delta_1=55176 fillable_delta_1_exact=55176 new_reserves=Reserves { r1: 55176, r2: 0 }
2023-06-02T14:14:43.568338Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:fill_backward{start_index=1 output=50160}: found previous input for current value i=1 current_value=50160 prev_input=55176 old_reserves=Reserves { r1: 0, r2: 50160 } new_reserves=Reserves { r1: 55176, r2: 0 }
2023-06-02T14:14:43.568348Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:fill_backward{start_index=1 output=50160}:fill_output: computed reverse fill reserves=Reserves { r1: 44686822576, r2: 500000000 } lambda_2=55176 fillable_delta_1=50160 fillable_delta_1_exact=50160 new_reserves=Reserves { r1: 44686872736, r2: 499944824 }
2023-06-02T14:14:43.568730Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:fill_backward{start_index=1 output=50160}: found previous input for current value i=0 current_value=55176 prev_input=50160 old_reserves=Reserves { r1: 500000000, r2: 44686822576 } new_reserves=Reserves { r1: 499944824, r2: 44686872736 }
2023-06-02T14:14:43.568792Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:fill_forward{start_index=2 input=50160}: filling forward along frontier
2023-06-02T14:14:43.568958Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route: completed fill iteration, updating frontier current_input=50160 current_output=50160 input=18446744072650473379 output=50160
2023-06-02T14:14:43.569027Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:replace_position{index=1}: replacing position replaced_position_id=plpid1qdf8l3x4e9vzwfvuuprnll8yc6pljhkjy5864r0qk9kcugaq50lqhka606
2023-06-02T14:14:43.569183Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:replace_position{index=1}:put_position{id=plpid1qdf8l3x4e9vzwfvuuprnll8yc6pljhkjy5864r0qk9kcugaq50lqhka606}: position=Position { state: Opened, reserves: Reserves { r1: 55176, r2: 0 }, phi: TradingFunction { component: BareTradingFunction { fee: 0, p: 1000000, q: 1100000 }, pair: TradingPair { asset_1: test_usd, asset_2: pen } }, nonce: "7cc3fc6166037683cb57cbf0c60a9085cc5a272ffa8d6ed7d673b61a0cfc7d84" }
2023-06-02T14:14:43.569414Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:replace_position{index=1}:put_position{id=plpid1qdf8l3x4e9vzwfvuuprnll8yc6pljhkjy5864r0qk9kcugaq50lqhka606}: deindexing position
2023-06-02T14:14:43.569487Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:replace_position{index=1}:put_position{id=plpid1qdf8l3x4e9vzwfvuuprnll8yc6pljhkjy5864r0qk9kcugaq50lqhka606}: indexing position for 2=>1 trades
2023-06-02T14:14:43.569543Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:replace_position{index=1}: skipping position already in frontier position_id=plpid1vkrrjqh249g4emy8l76pvcmpepadf7445jamzug44ud2npysvtpsgtlwxx
2023-06-02T14:14:43.569672Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:replace_position{index=1}: replacing constraining position in frontier next_position_id=plpid10u82mfg8p59ykaw6m878ypgdtwjvr33cctfp95cy0ra34pes2pzqsejh4r next_position=Position { state: Opened, reserves: Reserves { r1: 0, r2: 39106463939 }, phi: TradingFunction { component: BareTradingFunction { fee: 0, p: 1000000, q: 1210000 }, pair: TradingPair { asset_1: test_usd, asset_2: pen } }, nonce: "ad660aecb168983a29f6484d7517124b199bfb7ebd2709d711872eb2abc5544b" }
2023-06-02T14:14:43.569748Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:sense_capacity_constraint{input=18446744072650473379}: sensing frontier capacity with test amount
2023-06-02T14:14:43.569801Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:sense_capacity_constraint{input=18446744072650473379}: could not completely fill input amount, marking as constraining i=0 current_input=18446744072650473379 unfilled=18446744072195978084 output=499944824 old_reserves=Reserves { r1: 499944824, r2: 44686872736 } new_reserves=Reserves { r1: 0, r2: 45141368031 }
2023-06-02T14:14:43.569874Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:sense_capacity_constraint{input=18446744072650473379}: completely filled  i=1 current_input=499944824 unfilled=0 output=413177540 old_reserves=Reserves { r1: 0, r2: 39106463939 } new_reserves=Reserves { r1: 499944824, r2: 38693286399 }
2023-06-02T14:14:43.569951Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route: attempting to completely consume reserves of constraining position constraining_index=0 exactly_consumed_reserves=499944824
2023-06-02T14:14:43.569998Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:fill_backward{start_index=0 output=499944824}: filling backward along frontier
2023-06-02T14:14:43.570082Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:fill_backward{start_index=0 output=499944824}:fill_output: computed reverse fill reserves=Reserves { r1: 44686872736, r2: 499944824 } lambda_2=499944824 fillable_delta_1=454495294.54545456 fillable_delta_1_exact=454495295 new_reserves=Reserves { r1: 45141368031, r2: 0 }
2023-06-02T14:14:43.570147Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:fill_backward{start_index=0 output=499944824}: found previous input for current value i=0 current_value=499944824 prev_input=454495295 old_reserves=Reserves { r1: 499944824, r2: 44686872736 } new_reserves=Reserves { r1: 0, r2: 45141368031 }
2023-06-02T14:14:43.570186Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:fill_forward{start_index=1 input=499944824}: filling forward along frontier
2023-06-02T14:14:43.570238Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route: exceeded spill price, breaking loop spill_price=Some("1") actual_price=Ok("1.1000000024202672")
2023-06-02T14:14:43.570315Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:put_position{id=plpid1vkrrjqh249g4emy8l76pvcmpepadf7445jamzug44ud2npysvtpsgtlwxx}: position=Position { state: Opened, reserves: Reserves { r1: 499944824, r2: 44686872736 }, phi: TradingFunction { component: BareTradingFunction { fee: 0, p: 1000000, q: 1100000 }, pair: TradingPair { asset_1: test_usd, asset_2: pen } }, nonce: "2c58155f59985ae9027ab195a6d18547a40fd7fbeff129fda51322d03de53613" }
2023-06-02T14:14:43.570375Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:put_position{id=plpid1vkrrjqh249g4emy8l76pvcmpepadf7445jamzug44ud2npysvtpsgtlwxx}: deindexing position
2023-06-02T14:14:43.570447Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:put_position{id=plpid1vkrrjqh249g4emy8l76pvcmpepadf7445jamzug44ud2npysvtpsgtlwxx}: indexing position for 1=>2 trades
2023-06-02T14:14:43.570506Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:put_position{id=plpid1vkrrjqh249g4emy8l76pvcmpepadf7445jamzug44ud2npysvtpsgtlwxx}: indexing position for 2=>1 trades
2023-06-02T14:14:43.570556Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:put_position{id=plpid10u82mfg8p59ykaw6m878ypgdtwjvr33cctfp95cy0ra34pes2pzqsejh4r}: position=Position { state: Opened, reserves: Reserves { r1: 0, r2: 39106463939 }, phi: TradingFunction { component: BareTradingFunction { fee: 0, p: 1000000, q: 1210000 }, pair: TradingPair { asset_1: test_usd, asset_2: pen } }, nonce: "ad660aecb168983a29f6484d7517124b199bfb7ebd2709d711872eb2abc5544b" }
2023-06-02T14:14:43.570657Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:put_position{id=plpid10u82mfg8p59ykaw6m878ypgdtwjvr33cctfp95cy0ra34pes2pzqsejh4r}: deindexing position
2023-06-02T14:14:43.570715Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route:put_position{id=plpid10u82mfg8p59ykaw6m878ypgdtwjvr33cctfp95cy0ra34pes2pzqsejh4r}: indexing position for 1=>2 trades
2023-06-02T14:14:43.570988Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:fill_route: returning swap execution of filled route swap_execution=SwapExecution { traces: [[Value { amount: 50160, asset_id: pen }, Value { amount: 55176, asset_id: test_usd }, Value { amount: 50160, asset_id: pen }]], input: Value { amount: 50160, asset_id: pen }, output: Value { amount: 50160, asset_id: pen } }
2023-06-02T14:14:43.571078Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill: filled along best path lambda_2=50160 unfilled_1=18446744072650473379
2023-06-02T14:14:43.571129Z DEBUG abci:EndBlock{height=3424}:dex:arbitrage:route_and_fill:path_search{max_hops=5}: searching for path src=pen dst=pen max_hops=5