nondeterministic exec

gistfile1.txt

2023-06-02T18:38:59.290999Z  INFO penumbra_storage::storage: opening rocksdb path="/var/folders/j7/mbymjl4j16bfslngd1r6gt4h0000gn/T/.tmp8XGPZg/storage.db"
2023-06-02T18:38:59.321478Z DEBUG jmt: key=b"block_height" key_hash=KeyHash("c4b4e25a8824650a238d3451f754dcda1551dec8bec1a96abe23d2ff8b454db8")
2023-06-02T18:38:59.321520Z DEBUG jmt: key=b"chain/epoch_by_height/0" key_hash=KeyHash("ac0564683e6400e242f342e2b31a882a1195ac1aab399be415baef324ea87845")
2023-06-02T18:38:59.333391Z  INFO penumbra_dex::component::tests: penumbra_id=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm
2023-06-02T18:38:59.333471Z  INFO penumbra_dex::component::tests: test_usd_id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda
2023-06-02T18:38:59.333613Z DEBUG put_position{id=plpid17uuhzt0544hck7x8fsv4fg8qtfwcm49897ahqudyq0745kwwct4qyyy72y}: penumbra_dex::component::position_manager: position=Position { state: Opened, reserves: Reserves { r1: 110000000, r2: 0 }, phi: TradingFunction { component: BareTradingFunction { fee: 0, p: 1000000, q: 110000000 }, pair: TradingPair { asset_1: passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, asset_2: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm } }, nonce: "8c4a9aeb9d0d52c64fba372039c3178e471ef062727c08607c6104f1dd6a9809" }
2023-06-02T18:38:59.333685Z DEBUG put_position{id=plpid17uuhzt0544hck7x8fsv4fg8qtfwcm49897ahqudyq0745kwwct4qyyy72y}: penumbra_dex::component::position_manager: deindexing position
2023-06-02T18:38:59.333795Z DEBUG put_position{id=plpid17uuhzt0544hck7x8fsv4fg8qtfwcm49897ahqudyq0745kwwct4qyyy72y}: penumbra_dex::component::position_manager: indexing position for 2=>1 trades
2023-06-02T18:38:59.333899Z DEBUG put_position{id=plpid1dtdrslaufgwtesf05n3lrfegggavm4u7wvld23mfy4q3y4753scs37g07y}: penumbra_dex::component::position_manager: position=Position { state: Opened, reserves: Reserves { r1: 100000000, r2: 0 }, phi: TradingFunction { component: BareTradingFunction { fee: 0, p: 1000000, q: 100000000 }, pair: TradingPair { asset_1: passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, asset_2: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm } }, nonce: "d4ecdce2bbe8bbc5119a4f1f2861dd34093dba9ffe9d50bc4d1e74ae878ca86f" }
2023-06-02T18:38:59.333957Z DEBUG put_position{id=plpid1dtdrslaufgwtesf05n3lrfegggavm4u7wvld23mfy4q3y4753scs37g07y}: penumbra_dex::component::position_manager: deindexing position
2023-06-02T18:38:59.334024Z DEBUG put_position{id=plpid1dtdrslaufgwtesf05n3lrfegggavm4u7wvld23mfy4q3y4753scs37g07y}: penumbra_dex::component::position_manager: indexing position for 2=>1 trades
2023-06-02T18:38:59.334129Z DEBUG put_position{id=plpid1p9jfypqd00ajp2d0hn6cc9dfvcjrvgg8vegv0kj0ltsudwlw4jfq54ltfa}: penumbra_dex::component::position_manager: position=Position { state: Opened, reserves: Reserves { r1: 0, r2: 10000000 }, phi: TradingFunction { component: BareTradingFunction { fee: 0, p: 1000000, q: 100000000 }, pair: TradingPair { asset_1: passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, asset_2: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm } }, nonce: "21429b9a6e1a05ec6effcec79e5d9530f0031dce005144ebd488601b83463126" }
2023-06-02T18:38:59.334191Z DEBUG put_position{id=plpid1p9jfypqd00ajp2d0hn6cc9dfvcjrvgg8vegv0kj0ltsudwlw4jfq54ltfa}: penumbra_dex::component::position_manager: deindexing position
2023-06-02T18:38:59.334266Z DEBUG put_position{id=plpid1p9jfypqd00ajp2d0hn6cc9dfvcjrvgg8vegv0kj0ltsudwlw4jfq54ltfa}: penumbra_dex::component::position_manager: indexing position for 1=>2 trades
2023-06-02T18:38:59.334331Z  INFO penumbra_dex::component::tests: we posted the positions
2023-06-02T18:38:59.334337Z  INFO penumbra_dex::component::tests: we are triggering the arbitrage logic
2023-06-02T18:38:59.334363Z DEBUG arbitrage: penumbra_dex::component::arb: beginning arb search arb_token=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm fixed_candidates=[passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm, passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda]
2023-06-02T18:38:59.334431Z DEBUG arbitrage:route_and_fill: penumbra_dex::component::router::route_and_fill: starting route_and_fill delta_1=18446744073709551615 asset_1=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm asset_2=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm
2023-06-02T18:38:59.334485Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: searching for path src=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm dst=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm max_hops=5
2023-06-02T18:38:59.334572Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}: penumbra_dex::component::router::path: beginning path
2023-06-02T18:38:59.334606Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: relaxing active paths active_paths_len=1
2023-06-02T18:38:59.334684Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}: penumbra_dex::component::router::path_search: relaxing path degree=2 candidates=[passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm]
2023-06-02T18:38:59.334960Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:extend_to{new_end=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}: penumbra_dex::component::position_manager: deindexing position
2023-06-02T18:38:59.335035Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:extend_to{new_end=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}: penumbra_dex::component::router::path: extended path path_price=0.00909090909090909 hop_price=0.00909090909090909 id=plpid17uuhzt0544hck7x8fsv4fg8qtfwcm49897ahqudyq0745kwwct4qyyy72y
2023-06-02T18:38:59.335099Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}: penumbra_dex::component::router::path_cache: inserting new path
2023-06-02T18:38:59.335143Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:extend_to{new_end=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}: penumbra_dex::component::router::path: no best position, failing to extend path
2023-06-02T18:38:59.335188Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: finished relaxing all active paths i=0
2023-06-02T18:38:59.335207Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: relaxing active paths active_paths_len=1
2023-06-02T18:38:59.335236Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}: penumbra_dex::component::router::path_search: relaxing path degree=2 candidates=[passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm]
2023-06-02T18:38:59.335359Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}:extend_to{new_end=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}: penumbra_dex::component::router::path: no best position, failing to extend path
2023-06-02T18:38:59.335435Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}:extend_to{new_end=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}: penumbra_dex::component::position_manager: deindexing position
2023-06-02T18:38:59.335509Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}:extend_to{new_end=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}: penumbra_dex::component::router::path: extended path path_price=0.9090909090909091 hop_price=100 id=plpid1p9jfypqd00ajp2d0hn6cc9dfvcjrvgg8vegv0kj0ltsudwlw4jfq54ltfa
2023-06-02T18:38:59.335570Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}:via{id=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}: penumbra_dex::component::router::path_cache: updating path new_price=0.9090909090909091 old_price=1
2023-06-02T18:38:59.335610Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: finished relaxing all active paths i=1
2023-06-02T18:38:59.335625Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: relaxing active paths active_paths_len=1
2023-06-02T18:38:59.335653Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}:via{id=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}: penumbra_dex::component::router::path_search: relaxing path degree=2 candidates=[passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm]
2023-06-02T18:38:59.335822Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}:via{id=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:extend_to{new_end=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}: penumbra_dex::component::position_manager: deindexing position
2023-06-02T18:38:59.335921Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}:via{id=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:extend_to{new_end=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}: penumbra_dex::component::router::path: extended path path_price=0.00909090909090909 hop_price=0.01 id=plpid1dtdrslaufgwtesf05n3lrfegggavm4u7wvld23mfy4q3y4753scs37g07y
2023-06-02T18:38:59.336001Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}:via{id=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}: penumbra_dex::component::router::path_cache: updating path new_price=0.00909090909090909 old_price=0.00909090909090909
2023-06-02T18:38:59.336046Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}:via{id=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:extend_to{new_end=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}: penumbra_dex::component::router::path: no best position, failing to extend path
2023-06-02T18:38:59.336093Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: finished relaxing all active paths i=2
2023-06-02T18:38:59.336111Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: relaxing active paths active_paths_len=1
2023-06-02T18:38:59.336142Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}:via{id=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}: penumbra_dex::component::router::path_search: relaxing path degree=2 candidates=[passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm]
2023-06-02T18:38:59.336280Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}:via{id=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}:extend_to{new_end=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}: penumbra_dex::component::router::path: no best position, failing to extend path
2023-06-02T18:38:59.336329Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}:via{id=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}:extend_to{new_end=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}: penumbra_dex::component::router::path: no best position, failing to extend path
2023-06-02T18:38:59.336373Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: finished relaxing all active paths i=3
2023-06-02T18:38:59.336386Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: relaxing active paths active_paths_len=0
2023-06-02T18:38:59.336397Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: finished relaxing all active paths i=4
2023-06-02T18:38:59.336418Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: found path price=0.9090909090909091 spill_price=1 src=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm nodes=[passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm]
2023-06-02T18:38:59.336495Z DEBUG arbitrage:route_and_fill: penumbra_dex::component::router::route_and_fill: found path, starting to fill up to spill price path=[passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm] delta_1=18446744073709551615
2023-06-02T18:38:59.336547Z DEBUG arbitrage:route_and_fill:fill_route: penumbra_dex::component::router::fill_route: input=18446744073709551615 route=[passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm, passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm] spill_price=Some(U128x128 { integral: 1, fractional: 0 })
2023-06-02T18:38:59.336808Z DEBUG arbitrage:route_and_fill:fill_route: penumbra_dex::component::router::fill_route: assembled initial frontier frontier=Frontier { pairs: [DirectedTradingPair { start: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm, end: passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda }, DirectedTradingPair { start: passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, end: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm }], positions: [Position { state: Opened, reserves: Reserves { r1: 110000000, r2: 0 }, phi: TradingFunction { component: BareTradingFunction { fee: 0, p: 1000000, q: 110000000 }, pair: TradingPair { asset_1: passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, asset_2: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm } }, nonce: "8c4a9aeb9d0d52c64fba372039c3178e471ef062727c08607c6104f1dd6a9809" }, Position { state: Opened, reserves: Reserves { r1: 0, r2: 10000000 }, phi: TradingFunction { component: BareTradingFunction { fee: 0, p: 1000000, q: 100000000 }, pair: TradingPair { asset_1: passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, asset_2: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm } }, nonce: "21429b9a6e1a05ec6effcec79e5d9530f0031dce005144ebd488601b83463126" }], position_ids: {plpid1p9jfypqd00ajp2d0hn6cc9dfvcjrvgg8vegv0kj0ltsudwlw4jfq54ltfa, plpid17uuhzt0544hck7x8fsv4fg8qtfwcm49897ahqudyq0745kwwct4qyyy72y}, trace: [], .. }
2023-06-02T18:38:59.336980Z DEBUG arbitrage:route_and_fill:fill_route:sense_capacity_constraint{input=18446744073709551615}: penumbra_dex::component::router::fill_route: sensing frontier capacity with test amount
2023-06-02T18:38:59.337029Z DEBUG arbitrage:route_and_fill:fill_route:sense_capacity_constraint{input=18446744073709551615}: penumbra_dex::component::router::fill_route: could not completely fill input amount, marking as constraining i=0 current_input=18446744073709551615 unfilled=18446744073708551615 output=110000000 old_reserves=Reserves { r1: 110000000, r2: 0 } new_reserves=Reserves { r1: 0, r2: 1000000 }
2023-06-02T18:38:59.337074Z DEBUG arbitrage:route_and_fill:fill_route:sense_capacity_constraint{input=18446744073709551615}: penumbra_dex::component::router::fill_route: completely filled  i=1 current_input=110000000 unfilled=0 output=1100000 old_reserves=Reserves { r1: 0, r2: 10000000 } new_reserves=Reserves { r1: 110000000, r2: 8900000 }
2023-06-02T18:38:59.337104Z DEBUG arbitrage:route_and_fill:fill_route: penumbra_dex::component::router::fill_route: attempting to completely consume reserves of constraining position constraining_index=0 exactly_consumed_reserves=110000000
2023-06-02T18:38:59.337126Z DEBUG arbitrage:route_and_fill:fill_route:fill_backward{start_index=0 output=110000000}: penumbra_dex::component::router::fill_route: filling backward along frontier
2023-06-02T18:38:59.337156Z DEBUG arbitrage:route_and_fill:fill_route:fill_backward{start_index=0 output=110000000}:fill_output: penumbra_dex::lp::trading_function: computed reverse fill reserves=Reserves { r1: 0, r2: 110000000 } lambda_2=110000000 fillable_delta_1=1000000 fillable_delta_1_exact=1000000 new_reserves=Reserves { r1: 1000000, r2: 0 }
2023-06-02T18:38:59.337181Z DEBUG arbitrage:route_and_fill:fill_route:fill_backward{start_index=0 output=110000000}: penumbra_dex::component::router::fill_route: found previous input for current value i=0 current_value=110000000 prev_input=1000000 old_reserves=Reserves { r1: 110000000, r2: 0 } new_reserves=Reserves { r1: 0, r2: 1000000 }
2023-06-02T18:38:59.337208Z DEBUG arbitrage:route_and_fill:fill_route:fill_forward{start_index=1 input=110000000}: penumbra_dex::component::router::fill_route: filling forward along frontier
2023-06-02T18:38:59.337248Z DEBUG arbitrage:route_and_fill:fill_route: penumbra_dex::component::router::fill_route: completed fill iteration, updating frontier current_input=1000000 current_output=1100000 input=18446744073708551615 output=1100000
2023-06-02T18:38:59.337289Z DEBUG arbitrage:route_and_fill:fill_route:replace_position{index=0}: penumbra_dex::component::router::fill_route: replacing position replaced_position_id=plpid17uuhzt0544hck7x8fsv4fg8qtfwcm49897ahqudyq0745kwwct4qyyy72y
2023-06-02T18:38:59.337363Z DEBUG arbitrage:route_and_fill:fill_route:replace_position{index=0}:put_position{id=plpid17uuhzt0544hck7x8fsv4fg8qtfwcm49897ahqudyq0745kwwct4qyyy72y}: penumbra_dex::component::position_manager: position=Position { state: Opened, reserves: Reserves { r1: 0, r2: 1000000 }, phi: TradingFunction { component: BareTradingFunction { fee: 0, p: 1000000, q: 110000000 }, pair: TradingPair { asset_1: passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, asset_2: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm } }, nonce: "8c4a9aeb9d0d52c64fba372039c3178e471ef062727c08607c6104f1dd6a9809" }
2023-06-02T18:38:59.337426Z DEBUG arbitrage:route_and_fill:fill_route:replace_position{index=0}:put_position{id=plpid17uuhzt0544hck7x8fsv4fg8qtfwcm49897ahqudyq0745kwwct4qyyy72y}: penumbra_dex::component::position_manager: deindexing position
2023-06-02T18:38:59.337512Z DEBUG arbitrage:route_and_fill:fill_route:replace_position{index=0}:put_position{id=plpid17uuhzt0544hck7x8fsv4fg8qtfwcm49897ahqudyq0745kwwct4qyyy72y}: penumbra_dex::component::position_manager: indexing position for 1=>2 trades
2023-06-02T18:38:59.337587Z DEBUG arbitrage:route_and_fill:fill_route:replace_position{index=0}: penumbra_dex::component::router::fill_route: replacing constraining position in frontier next_position_id=plpid1dtdrslaufgwtesf05n3lrfegggavm4u7wvld23mfy4q3y4753scs37g07y next_position=Position { state: Opened, reserves: Reserves { r1: 100000000, r2: 0 }, phi: TradingFunction { component: BareTradingFunction { fee: 0, p: 1000000, q: 100000000 }, pair: TradingPair { asset_1: passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, asset_2: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm } }, nonce: "d4ecdce2bbe8bbc5119a4f1f2861dd34093dba9ffe9d50bc4d1e74ae878ca86f" }
2023-06-02T18:38:59.337659Z DEBUG arbitrage:route_and_fill:fill_route:sense_capacity_constraint{input=18446744073708551615}: penumbra_dex::component::router::fill_route: sensing frontier capacity with test amount
2023-06-02T18:38:59.337697Z DEBUG arbitrage:route_and_fill:fill_route:sense_capacity_constraint{input=18446744073708551615}: penumbra_dex::component::router::fill_route: could not completely fill input amount, marking as constraining i=0 current_input=18446744073708551615 unfilled=18446744073707551615 output=100000000 old_reserves=Reserves { r1: 100000000, r2: 0 } new_reserves=Reserves { r1: 0, r2: 1000000 }
2023-06-02T18:38:59.337730Z DEBUG arbitrage:route_and_fill:fill_route:sense_capacity_constraint{input=18446744073708551615}: penumbra_dex::component::router::fill_route: completely filled  i=1 current_input=100000000 unfilled=0 output=1000000 old_reserves=Reserves { r1: 110000000, r2: 8900000 } new_reserves=Reserves { r1: 210000000, r2: 7900000 }
2023-06-02T18:38:59.337769Z DEBUG arbitrage:route_and_fill:fill_route: penumbra_dex::component::router::fill_route: attempting to completely consume reserves of constraining position constraining_index=0 exactly_consumed_reserves=100000000
2023-06-02T18:38:59.337828Z DEBUG arbitrage:route_and_fill:fill_route:fill_backward{start_index=0 output=100000000}: penumbra_dex::component::router::fill_route: filling backward along frontier
2023-06-02T18:38:59.337920Z DEBUG arbitrage:route_and_fill:fill_route:fill_backward{start_index=0 output=100000000}:fill_output: penumbra_dex::lp::trading_function: computed reverse fill reserves=Reserves { r1: 0, r2: 100000000 } lambda_2=100000000 fillable_delta_1=1000000 fillable_delta_1_exact=1000000 new_reserves=Reserves { r1: 1000000, r2: 0 }
2023-06-02T18:38:59.337953Z DEBUG arbitrage:route_and_fill:fill_route:fill_backward{start_index=0 output=100000000}: penumbra_dex::component::router::fill_route: found previous input for current value i=0 current_value=100000000 prev_input=1000000 old_reserves=Reserves { r1: 100000000, r2: 0 } new_reserves=Reserves { r1: 0, r2: 1000000 }
2023-06-02T18:38:59.337985Z DEBUG arbitrage:route_and_fill:fill_route:fill_forward{start_index=1 input=100000000}: penumbra_dex::component::router::fill_route: filling forward along frontier
2023-06-02T18:38:59.338026Z DEBUG arbitrage:route_and_fill:fill_route: penumbra_dex::component::router::fill_route: completed fill iteration, updating frontier current_input=1000000 current_output=1000000 input=18446744073707551615 output=2100000
2023-06-02T18:38:59.338088Z DEBUG arbitrage:route_and_fill:fill_route:replace_position{index=0}: penumbra_dex::component::router::fill_route: replacing position replaced_position_id=plpid1dtdrslaufgwtesf05n3lrfegggavm4u7wvld23mfy4q3y4753scs37g07y
2023-06-02T18:38:59.338167Z DEBUG arbitrage:route_and_fill:fill_route:replace_position{index=0}:put_position{id=plpid1dtdrslaufgwtesf05n3lrfegggavm4u7wvld23mfy4q3y4753scs37g07y}: penumbra_dex::component::position_manager: position=Position { state: Opened, reserves: Reserves { r1: 0, r2: 1000000 }, phi: TradingFunction { component: BareTradingFunction { fee: 0, p: 1000000, q: 100000000 }, pair: TradingPair { asset_1: passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, asset_2: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm } }, nonce: "d4ecdce2bbe8bbc5119a4f1f2861dd34093dba9ffe9d50bc4d1e74ae878ca86f" }
2023-06-02T18:38:59.338233Z DEBUG arbitrage:route_and_fill:fill_route:replace_position{index=0}:put_position{id=plpid1dtdrslaufgwtesf05n3lrfegggavm4u7wvld23mfy4q3y4753scs37g07y}: penumbra_dex::component::position_manager: deindexing position
2023-06-02T18:38:59.338315Z DEBUG arbitrage:route_and_fill:fill_route:replace_position{index=0}:put_position{id=plpid1dtdrslaufgwtesf05n3lrfegggavm4u7wvld23mfy4q3y4753scs37g07y}: penumbra_dex::component::position_manager: indexing position for 1=>2 trades
2023-06-02T18:38:59.338379Z DEBUG arbitrage:route_and_fill:fill_route:replace_position{index=0}: penumbra_dex::component::router::fill_route: no more positions available for pair pair=DirectedTradingPair { start: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm, end: passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda }
2023-06-02T18:38:59.338424Z DEBUG arbitrage:route_and_fill:fill_route: penumbra_dex::component::router::fill_route: ran out of positions, breaking loop
2023-06-02T18:38:59.338475Z DEBUG arbitrage:route_and_fill:fill_route:put_position{id=plpid1dtdrslaufgwtesf05n3lrfegggavm4u7wvld23mfy4q3y4753scs37g07y}: penumbra_dex::component::position_manager: position=Position { state: Opened, reserves: Reserves { r1: 0, r2: 1000000 }, phi: TradingFunction { component: BareTradingFunction { fee: 0, p: 1000000, q: 100000000 }, pair: TradingPair { asset_1: passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, asset_2: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm } }, nonce: "d4ecdce2bbe8bbc5119a4f1f2861dd34093dba9ffe9d50bc4d1e74ae878ca86f" }
2023-06-02T18:38:59.338531Z DEBUG arbitrage:route_and_fill:fill_route:put_position{id=plpid1dtdrslaufgwtesf05n3lrfegggavm4u7wvld23mfy4q3y4753scs37g07y}: penumbra_dex::component::position_manager: deindexing position
2023-06-02T18:38:59.338598Z DEBUG arbitrage:route_and_fill:fill_route:put_position{id=plpid1dtdrslaufgwtesf05n3lrfegggavm4u7wvld23mfy4q3y4753scs37g07y}: penumbra_dex::component::position_manager: indexing position for 1=>2 trades
2023-06-02T18:38:59.338674Z DEBUG arbitrage:route_and_fill:fill_route:put_position{id=plpid1p9jfypqd00ajp2d0hn6cc9dfvcjrvgg8vegv0kj0ltsudwlw4jfq54ltfa}: penumbra_dex::component::position_manager: position=Position { state: Opened, reserves: Reserves { r1: 210000000, r2: 7900000 }, phi: TradingFunction { component: BareTradingFunction { fee: 0, p: 1000000, q: 100000000 }, pair: TradingPair { asset_1: passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, asset_2: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm } }, nonce: "21429b9a6e1a05ec6effcec79e5d9530f0031dce005144ebd488601b83463126" }
2023-06-02T18:38:59.338729Z DEBUG arbitrage:route_and_fill:fill_route:put_position{id=plpid1p9jfypqd00ajp2d0hn6cc9dfvcjrvgg8vegv0kj0ltsudwlw4jfq54ltfa}: penumbra_dex::component::position_manager: deindexing position
2023-06-02T18:38:59.338796Z DEBUG arbitrage:route_and_fill:fill_route:put_position{id=plpid1p9jfypqd00ajp2d0hn6cc9dfvcjrvgg8vegv0kj0ltsudwlw4jfq54ltfa}: penumbra_dex::component::position_manager: indexing position for 1=>2 trades
2023-06-02T18:38:59.338824Z DEBUG arbitrage:route_and_fill:fill_route:put_position{id=plpid1p9jfypqd00ajp2d0hn6cc9dfvcjrvgg8vegv0kj0ltsudwlw4jfq54ltfa}: penumbra_dex::component::position_manager: indexing position for 2=>1 trades
2023-06-02T18:38:59.338877Z DEBUG arbitrage:route_and_fill:fill_route: penumbra_dex::component::router::fill_route: returning swap execution of filled route swap_execution=SwapExecution { traces: [[Value { amount: 1000000, asset_id: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm }, Value { amount: 110000000, asset_id: passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda }, Value { amount: 1100000, asset_id: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm }], [Value { amount: 1000000, asset_id: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm }, Value { amount: 100000000, asset_id: passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda }, Value { amount: 1000000, asset_id: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm }]], input: Value { amount: 2000000, asset_id: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm }, output: Value { amount: 2100000, asset_id: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm } }
2023-06-02T18:38:59.339036Z DEBUG arbitrage:route_and_fill: penumbra_dex::component::router::route_and_fill: filled along best path lambda_2=2100000 unfilled_1=18446744073707551615
2023-06-02T18:38:59.339058Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: searching for path src=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm dst=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm max_hops=5
2023-06-02T18:38:59.339118Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}: penumbra_dex::component::router::path: beginning path
2023-06-02T18:38:59.339137Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: relaxing active paths active_paths_len=1
2023-06-02T18:38:59.339167Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}: penumbra_dex::component::router::path_search: relaxing path degree=2 candidates=[passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm]
2023-06-02T18:38:59.339373Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:extend_to{new_end=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}: penumbra_dex::component::position_manager: deindexing position
2023-06-02T18:38:59.339447Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:extend_to{new_end=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}: penumbra_dex::component::router::path: extended path path_price=0.01 hop_price=0.01 id=plpid1p9jfypqd00ajp2d0hn6cc9dfvcjrvgg8vegv0kj0ltsudwlw4jfq54ltfa
2023-06-02T18:38:59.339510Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}: penumbra_dex::component::router::path_cache: inserting new path
2023-06-02T18:38:59.339545Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:extend_to{new_end=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}: penumbra_dex::component::router::path: no best position, failing to extend path
2023-06-02T18:38:59.339585Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: finished relaxing all active paths i=0
2023-06-02T18:38:59.339600Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: relaxing active paths active_paths_len=1
2023-06-02T18:38:59.339630Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}: penumbra_dex::component::router::path_search: relaxing path degree=2 candidates=[passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm]
2023-06-02T18:38:59.339753Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}:extend_to{new_end=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}: penumbra_dex::component::router::path: no best position, failing to extend path
2023-06-02T18:38:59.339835Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}:extend_to{new_end=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}: penumbra_dex::component::position_manager: deindexing position
2023-06-02T18:38:59.339911Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}:extend_to{new_end=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}: penumbra_dex::component::router::path: extended path path_price=1 hop_price=100 id=plpid1dtdrslaufgwtesf05n3lrfegggavm4u7wvld23mfy4q3y4753scs37g07y
2023-06-02T18:38:59.339970Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}:via{id=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}: penumbra_dex::component::router::path_cache: updating path new_price=1 old_price=1
2023-06-02T18:38:59.340010Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: finished relaxing all active paths i=1
2023-06-02T18:38:59.340025Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: relaxing active paths active_paths_len=1
2023-06-02T18:38:59.340053Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}:via{id=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}: penumbra_dex::component::router::path_search: relaxing path degree=2 candidates=[passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm]
2023-06-02T18:38:59.340186Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}:via{id=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:extend_to{new_end=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}: penumbra_dex::component::router::path: no best position, failing to extend path
2023-06-02T18:38:59.340223Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}:via{id=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:extend_to{new_end=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}: penumbra_dex::component::router::path: no best position, failing to extend path
2023-06-02T18:38:59.340262Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: finished relaxing all active paths i=2
2023-06-02T18:38:59.340275Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: relaxing active paths active_paths_len=0
2023-06-02T18:38:59.340286Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: finished relaxing all active paths i=3
2023-06-02T18:38:59.340296Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: relaxing active paths active_paths_len=0
2023-06-02T18:38:59.340340Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: finished relaxing all active paths i=4
2023-06-02T18:38:59.340361Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: found path price=1 spill_price=1 src=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm nodes=[passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm]
2023-06-02T18:38:59.340427Z DEBUG arbitrage:route_and_fill: penumbra_dex::component::router::route_and_fill: found path, starting to fill up to spill price path=[passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm] delta_1=18446744073707551615
2023-06-02T18:38:59.340471Z DEBUG arbitrage:route_and_fill:fill_route: penumbra_dex::component::router::fill_route: input=18446744073707551615 route=[passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm, passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm] spill_price=Some(U128x128 { integral: 1, fractional: 0 })
2023-06-02T18:38:59.340637Z DEBUG arbitrage:route_and_fill:fill_route: penumbra_dex::component::router::fill_route: assembled initial frontier frontier=Frontier { pairs: [DirectedTradingPair { start: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm, end: passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda }, DirectedTradingPair { start: passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, end: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm }], positions: [Position { state: Opened, reserves: Reserves { r1: 210000000, r2: 7900000 }, phi: TradingFunction { component: BareTradingFunction { fee: 0, p: 1000000, q: 100000000 }, pair: TradingPair { asset_1: passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, asset_2: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm } }, nonce: "21429b9a6e1a05ec6effcec79e5d9530f0031dce005144ebd488601b83463126" }, Position { state: Opened, reserves: Reserves { r1: 0, r2: 1000000 }, phi: TradingFunction { component: BareTradingFunction { fee: 0, p: 1000000, q: 100000000 }, pair: TradingPair { asset_1: passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, asset_2: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm } }, nonce: "d4ecdce2bbe8bbc5119a4f1f2861dd34093dba9ffe9d50bc4d1e74ae878ca86f" }], position_ids: {plpid1p9jfypqd00ajp2d0hn6cc9dfvcjrvgg8vegv0kj0ltsudwlw4jfq54ltfa, plpid1dtdrslaufgwtesf05n3lrfegggavm4u7wvld23mfy4q3y4753scs37g07y}, trace: [], .. }
2023-06-02T18:38:59.340837Z DEBUG arbitrage:route_and_fill:fill_route:sense_capacity_constraint{input=18446744073707551615}: penumbra_dex::component::router::fill_route: sensing frontier capacity with test amount
2023-06-02T18:38:59.340876Z DEBUG arbitrage:route_and_fill:fill_route:sense_capacity_constraint{input=18446744073707551615}: penumbra_dex::component::router::fill_route: could not completely fill input amount, marking as constraining i=0 current_input=18446744073707551615 unfilled=18446744073705451615 output=210000000 old_reserves=Reserves { r1: 210000000, r2: 7900000 } new_reserves=Reserves { r1: 0, r2: 10000000 }
2023-06-02T18:38:59.340935Z DEBUG arbitrage:route_and_fill:fill_route:sense_capacity_constraint{input=18446744073707551615}: penumbra_dex::component::router::fill_route: could not completely fill input amount, marking as constraining i=1 current_input=210000000 unfilled=110000000 output=1000000 old_reserves=Reserves { r1: 0, r2: 1000000 } new_reserves=Reserves { r1: 100000000, r2: 0 }
2023-06-02T18:38:59.340959Z DEBUG arbitrage:route_and_fill:fill_route: penumbra_dex::component::router::fill_route: attempting to completely consume reserves of constraining position constraining_index=1 exactly_consumed_reserves=1000000
2023-06-02T18:38:59.340976Z DEBUG arbitrage:route_and_fill:fill_route:fill_backward{start_index=1 output=1000000}: penumbra_dex::component::router::fill_route: filling backward along frontier
2023-06-02T18:38:59.341004Z DEBUG arbitrage:route_and_fill:fill_route:fill_backward{start_index=1 output=1000000}:fill_output: penumbra_dex::lp::trading_function: computed reverse fill reserves=Reserves { r1: 0, r2: 1000000 } lambda_2=1000000 fillable_delta_1=100000000 fillable_delta_1_exact=100000000 new_reserves=Reserves { r1: 100000000, r2: 0 }
2023-06-02T18:38:59.341027Z DEBUG arbitrage:route_and_fill:fill_route:fill_backward{start_index=1 output=1000000}: penumbra_dex::component::router::fill_route: found previous input for current value i=1 current_value=1000000 prev_input=100000000 old_reserves=Reserves { r1: 0, r2: 1000000 } new_reserves=Reserves { r1: 100000000, r2: 0 }
2023-06-02T18:38:59.341059Z DEBUG arbitrage:route_and_fill:fill_route:fill_backward{start_index=1 output=1000000}:fill_output: penumbra_dex::lp::trading_function: computed reverse fill reserves=Reserves { r1: 7900000, r2: 210000000 } lambda_2=100000000 fillable_delta_1=1000000 fillable_delta_1_exact=1000000 new_reserves=Reserves { r1: 8900000, r2: 110000000 }
2023-06-02T18:38:59.341128Z DEBUG arbitrage:route_and_fill:fill_route:fill_backward{start_index=1 output=1000000}: penumbra_dex::component::router::fill_route: found previous input for current value i=0 current_value=100000000 prev_input=1000000 old_reserves=Reserves { r1: 210000000, r2: 7900000 } new_reserves=Reserves { r1: 110000000, r2: 8900000 }
2023-06-02T18:38:59.341154Z DEBUG arbitrage:route_and_fill:fill_route:fill_forward{start_index=2 input=1000000}: penumbra_dex::component::router::fill_route: filling forward along frontier
2023-06-02T18:38:59.341176Z DEBUG arbitrage:route_and_fill:fill_route: penumbra_dex::component::router::fill_route: completed fill iteration, updating frontier current_input=1000000 current_output=1000000 input=18446744073706551615 output=1000000
2023-06-02T18:38:59.341230Z DEBUG arbitrage:route_and_fill:fill_route:replace_position{index=1}: penumbra_dex::component::router::fill_route: replacing position replaced_position_id=plpid1dtdrslaufgwtesf05n3lrfegggavm4u7wvld23mfy4q3y4753scs37g07y
2023-06-02T18:38:59.341301Z DEBUG arbitrage:route_and_fill:fill_route:replace_position{index=1}:put_position{id=plpid1dtdrslaufgwtesf05n3lrfegggavm4u7wvld23mfy4q3y4753scs37g07y}: penumbra_dex::component::position_manager: position=Position { state: Opened, reserves: Reserves { r1: 100000000, r2: 0 }, phi: TradingFunction { component: BareTradingFunction { fee: 0, p: 1000000, q: 100000000 }, pair: TradingPair { asset_1: passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, asset_2: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm } }, nonce: "d4ecdce2bbe8bbc5119a4f1f2861dd34093dba9ffe9d50bc4d1e74ae878ca86f" }
2023-06-02T18:38:59.341362Z DEBUG arbitrage:route_and_fill:fill_route:replace_position{index=1}:put_position{id=plpid1dtdrslaufgwtesf05n3lrfegggavm4u7wvld23mfy4q3y4753scs37g07y}: penumbra_dex::component::position_manager: deindexing position
2023-06-02T18:38:59.341447Z DEBUG arbitrage:route_and_fill:fill_route:replace_position{index=1}:put_position{id=plpid1dtdrslaufgwtesf05n3lrfegggavm4u7wvld23mfy4q3y4753scs37g07y}: penumbra_dex::component::position_manager: indexing position for 2=>1 trades
2023-06-02T18:38:59.341517Z DEBUG arbitrage:route_and_fill:fill_route:replace_position{index=1}: penumbra_dex::component::router::fill_route: replacing constraining position in frontier next_position_id=plpid17uuhzt0544hck7x8fsv4fg8qtfwcm49897ahqudyq0745kwwct4qyyy72y next_position=Position { state: Opened, reserves: Reserves { r1: 0, r2: 1000000 }, phi: TradingFunction { component: BareTradingFunction { fee: 0, p: 1000000, q: 110000000 }, pair: TradingPair { asset_1: passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, asset_2: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm } }, nonce: "8c4a9aeb9d0d52c64fba372039c3178e471ef062727c08607c6104f1dd6a9809" }
2023-06-02T18:38:59.341585Z DEBUG arbitrage:route_and_fill:fill_route:sense_capacity_constraint{input=18446744073706551615}: penumbra_dex::component::router::fill_route: sensing frontier capacity with test amount
2023-06-02T18:38:59.341622Z DEBUG arbitrage:route_and_fill:fill_route:sense_capacity_constraint{input=18446744073706551615}: penumbra_dex::component::router::fill_route: could not completely fill input amount, marking as constraining i=0 current_input=18446744073706551615 unfilled=18446744073705451615 output=110000000 old_reserves=Reserves { r1: 110000000, r2: 8900000 } new_reserves=Reserves { r1: 0, r2: 10000000 }
2023-06-02T18:38:59.341653Z DEBUG arbitrage:route_and_fill:fill_route:sense_capacity_constraint{input=18446744073706551615}: penumbra_dex::component::router::fill_route: completely filled  i=1 current_input=110000000 unfilled=0 output=1000000 old_reserves=Reserves { r1: 0, r2: 1000000 } new_reserves=Reserves { r1: 110000000, r2: 0 }
2023-06-02T18:38:59.341676Z DEBUG arbitrage:route_and_fill:fill_route: penumbra_dex::component::router::fill_route: attempting to completely consume reserves of constraining position constraining_index=0 exactly_consumed_reserves=110000000
2023-06-02T18:38:59.341693Z DEBUG arbitrage:route_and_fill:fill_route:fill_backward{start_index=0 output=110000000}: penumbra_dex::component::router::fill_route: filling backward along frontier
2023-06-02T18:38:59.341718Z DEBUG arbitrage:route_and_fill:fill_route:fill_backward{start_index=0 output=110000000}:fill_output: penumbra_dex::lp::trading_function: computed reverse fill reserves=Reserves { r1: 8900000, r2: 110000000 } lambda_2=110000000 fillable_delta_1=1100000 fillable_delta_1_exact=1100000 new_reserves=Reserves { r1: 10000000, r2: 0 }
2023-06-02T18:38:59.341740Z DEBUG arbitrage:route_and_fill:fill_route:fill_backward{start_index=0 output=110000000}: penumbra_dex::component::router::fill_route: found previous input for current value i=0 current_value=110000000 prev_input=1100000 old_reserves=Reserves { r1: 110000000, r2: 8900000 } new_reserves=Reserves { r1: 0, r2: 10000000 }
2023-06-02T18:38:59.341766Z DEBUG arbitrage:route_and_fill:fill_route:fill_forward{start_index=1 input=110000000}: penumbra_dex::component::router::fill_route: filling forward along frontier
2023-06-02T18:38:59.341805Z DEBUG arbitrage:route_and_fill:fill_route: penumbra_dex::component::router::fill_route: exceeded spill price, breaking loop spill_price=Some("1") actual_price=Ok("1.1")
2023-06-02T18:38:59.341863Z DEBUG arbitrage:route_and_fill:fill_route:put_position{id=plpid1p9jfypqd00ajp2d0hn6cc9dfvcjrvgg8vegv0kj0ltsudwlw4jfq54ltfa}: penumbra_dex::component::position_manager: position=Position { state: Opened, reserves: Reserves { r1: 110000000, r2: 8900000 }, phi: TradingFunction { component: BareTradingFunction { fee: 0, p: 1000000, q: 100000000 }, pair: TradingPair { asset_1: passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, asset_2: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm } }, nonce: "21429b9a6e1a05ec6effcec79e5d9530f0031dce005144ebd488601b83463126" }
2023-06-02T18:38:59.341920Z DEBUG arbitrage:route_and_fill:fill_route:put_position{id=plpid1p9jfypqd00ajp2d0hn6cc9dfvcjrvgg8vegv0kj0ltsudwlw4jfq54ltfa}: penumbra_dex::component::position_manager: deindexing position
2023-06-02T18:38:59.341989Z DEBUG arbitrage:route_and_fill:fill_route:put_position{id=plpid1p9jfypqd00ajp2d0hn6cc9dfvcjrvgg8vegv0kj0ltsudwlw4jfq54ltfa}: penumbra_dex::component::position_manager: indexing position for 1=>2 trades
2023-06-02T18:38:59.342017Z DEBUG arbitrage:route_and_fill:fill_route:put_position{id=plpid1p9jfypqd00ajp2d0hn6cc9dfvcjrvgg8vegv0kj0ltsudwlw4jfq54ltfa}: penumbra_dex::component::position_manager: indexing position for 2=>1 trades
2023-06-02T18:38:59.342095Z DEBUG arbitrage:route_and_fill:fill_route:put_position{id=plpid17uuhzt0544hck7x8fsv4fg8qtfwcm49897ahqudyq0745kwwct4qyyy72y}: penumbra_dex::component::position_manager: position=Position { state: Opened, reserves: Reserves { r1: 0, r2: 1000000 }, phi: TradingFunction { component: BareTradingFunction { fee: 0, p: 1000000, q: 110000000 }, pair: TradingPair { asset_1: passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, asset_2: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm } }, nonce: "8c4a9aeb9d0d52c64fba372039c3178e471ef062727c08607c6104f1dd6a9809" }
2023-06-02T18:38:59.342153Z DEBUG arbitrage:route_and_fill:fill_route:put_position{id=plpid17uuhzt0544hck7x8fsv4fg8qtfwcm49897ahqudyq0745kwwct4qyyy72y}: penumbra_dex::component::position_manager: deindexing position
2023-06-02T18:38:59.342239Z DEBUG arbitrage:route_and_fill:fill_route:put_position{id=plpid17uuhzt0544hck7x8fsv4fg8qtfwcm49897ahqudyq0745kwwct4qyyy72y}: penumbra_dex::component::position_manager: indexing position for 1=>2 trades
2023-06-02T18:38:59.342292Z DEBUG arbitrage:route_and_fill:fill_route: penumbra_dex::component::router::fill_route: returning swap execution of filled route swap_execution=SwapExecution { traces: [[Value { amount: 1000000, asset_id: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm }, Value { amount: 100000000, asset_id: passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda }, Value { amount: 1000000, asset_id: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm }]], input: Value { amount: 1000000, asset_id: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm }, output: Value { amount: 1000000, asset_id: passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm } }
2023-06-02T18:38:59.342399Z DEBUG arbitrage:route_and_fill: penumbra_dex::component::router::route_and_fill: filled along best path lambda_2=1000000 unfilled_1=18446744073706551615
2023-06-02T18:38:59.342418Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: searching for path src=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm dst=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm max_hops=5
2023-06-02T18:38:59.342481Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}: penumbra_dex::component::router::path: beginning path
2023-06-02T18:38:59.342501Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: relaxing active paths active_paths_len=1
2023-06-02T18:38:59.342534Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}: penumbra_dex::component::router::path_search: relaxing path degree=2 candidates=[passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm]
2023-06-02T18:38:59.342716Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:extend_to{new_end=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}: penumbra_dex::component::position_manager: deindexing position
2023-06-02T18:38:59.342791Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:extend_to{new_end=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}: penumbra_dex::component::router::path: extended path path_price=0.01 hop_price=0.01 id=plpid1p9jfypqd00ajp2d0hn6cc9dfvcjrvgg8vegv0kj0ltsudwlw4jfq54ltfa
2023-06-02T18:38:59.342856Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}: penumbra_dex::component::router::path_cache: inserting new path
2023-06-02T18:38:59.342889Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:extend_to{new_end=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}: penumbra_dex::component::router::path: no best position, failing to extend path
2023-06-02T18:38:59.342927Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: finished relaxing all active paths i=0
2023-06-02T18:38:59.342942Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: relaxing active paths active_paths_len=1
2023-06-02T18:38:59.342971Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}: penumbra_dex::component::router::path_search: relaxing path degree=2 candidates=[passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda, passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm]
2023-06-02T18:38:59.343104Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}:extend_to{new_end=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}: penumbra_dex::component::router::path: no best position, failing to extend path
2023-06-02T18:38:59.343186Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}:extend_to{new_end=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}: penumbra_dex::component::position_manager: deindexing position
2023-06-02T18:38:59.343259Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}:path{start=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}:via{id=passet14h46dmcyy6fl5vyz7xx933n8l7jy202ahrxg92kad6yfladsvcyqczcwda}:extend_to{new_end=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm}: penumbra_dex::component::router::path: extended path path_price=1.1 hop_price=110 id=plpid17uuhzt0544hck7x8fsv4fg8qtfwcm49897ahqudyq0745kwwct4qyyy72y
2023-06-02T18:38:59.343340Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: finished relaxing all active paths i=1
2023-06-02T18:38:59.343354Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: relaxing active paths active_paths_len=0
2023-06-02T18:38:59.343365Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: finished relaxing all active paths i=2
2023-06-02T18:38:59.343376Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: relaxing active paths active_paths_len=0
2023-06-02T18:38:59.343387Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: finished relaxing all active paths i=3
2023-06-02T18:38:59.343398Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: relaxing active paths active_paths_len=0
2023-06-02T18:38:59.343409Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: finished relaxing all active paths i=4
2023-06-02T18:38:59.343424Z DEBUG arbitrage:route_and_fill:path_search{max_hops=5}: penumbra_dex::component::router::path_search: found path price=1 spill_price=0 src=passet1984fctenw8m2fpl8a9wzguzp7j34d7vravryuhft808nyt9fdggqxmanqm nodes=[]
2023-06-02T18:38:59.343463Z DEBUG arbitrage:route_and_fill: penumbra_dex::component::router::route_and_fill: empty path found, exiting route_and_fill
2023-06-02T18:38:59.343476Z DEBUG arbitrage: penumbra_dex::component::arb: successfully arbitraged positions, burning profit arb_profit=100000
2023-06-02T18:38:59.343558Z DEBUG arbitrage: jmt: key=b"block_height" key_hash=KeyHash("c4b4e25a8824650a238d3451f754dcda1551dec8bec1a96abe23d2ff8b454db8")
2023-06-02T18:38:59.343714Z  INFO penumbra_dex::component::tests: the arbitrage logic has concluded!
2023-06-02T18:38:59.343728Z  INFO penumbra_dex::component::tests: fetching the `ArbExecution`