X-Git-Url: https://git.immae.eu/?a=blobdiff_plain;f=test.py;h=0f1f13030b2539a91d56d665368e225ca6a70fb0;hb=3d0247f944d7510943dfaa64eeb0e15a43b6c989;hp=9fd058a0ad6d59c80798466d4cbf4ddace1ab6eb;hpb=c31df868c655612b8387a25111e69882f0fe6344;p=perso%2FImmae%2FProjets%2FCryptomonnaies%2FCryptoportfolio%2FTrader.git diff --git a/test.py b/test.py index 9fd058a..0f1f130 100644 --- a/test.py +++ b/test.py @@ -27,6 +27,8 @@ class WebMockTestCase(unittest.TestCase): self.wm.start() self.patchers = [ + mock.patch.multiple(portfolio.ReportStore, + logs=[], verbose_print=True), mock.patch.multiple(portfolio.BalanceStore, all={},), mock.patch.multiple(portfolio.TradeStore, @@ -63,7 +65,8 @@ class PortfolioTest(WebMockTestCase): self.wm.get(portfolio.Portfolio.URL, text=self.json_response) - def test_get_cryptoportfolio(self): + @mock.patch("portfolio.ReportStore") + def test_get_cryptoportfolio(self, report_store): self.wm.get(portfolio.Portfolio.URL, [ {"text":'{ "foo": "bar" }', "status_code": 200}, {"text": "System Error", "status_code": 500}, @@ -74,17 +77,28 @@ class PortfolioTest(WebMockTestCase): self.assertEqual("bar", portfolio.Portfolio.data["foo"]) self.assertTrue(self.wm.called) self.assertEqual(1, self.wm.call_count) + report_store.log_error.assert_not_called() + report_store.log_http_request.assert_called_once() + report_store.log_http_request.reset_mock() portfolio.Portfolio.get_cryptoportfolio() self.assertIsNone(portfolio.Portfolio.data) self.assertEqual(2, self.wm.call_count) + report_store.log_error.assert_not_called() + report_store.log_http_request.assert_called_once() + report_store.log_http_request.reset_mock() + portfolio.Portfolio.data = "Foo" portfolio.Portfolio.get_cryptoportfolio() self.assertEqual("Foo", portfolio.Portfolio.data) self.assertEqual(3, self.wm.call_count) + report_store.log_error.assert_called_once_with("get_cryptoportfolio", + exception=mock.ANY) + report_store.log_http_request.assert_not_called() - def test_parse_cryptoportfolio(self): + @mock.patch("portfolio.ReportStore") + def test_parse_cryptoportfolio(self, report_store): portfolio.Portfolio.parse_cryptoportfolio() self.assertListEqual( @@ -121,15 +135,22 @@ class PortfolioTest(WebMockTestCase): self.assertDictEqual(expected, liquidities["medium"][date]) self.assertEqual(portfolio.datetime(2018, 1, 15), portfolio.Portfolio.last_date) + report_store.log_http_request.assert_called_once_with("GET", + portfolio.Portfolio.URL, None, mock.ANY, mock.ANY) + report_store.log_http_request.reset_mock() + # It doesn't refetch the data when available portfolio.Portfolio.parse_cryptoportfolio() + report_store.log_http_request.assert_not_called() self.assertEqual(1, self.wm.call_count) portfolio.Portfolio.parse_cryptoportfolio(refetch=True) self.assertEqual(2, self.wm.call_count) + report_store.log_http_request.assert_called_once() - def test_repartition(self): + @mock.patch("portfolio.ReportStore") + def test_repartition(self, report_store): expected_medium = { 'BTC': (D("1.1102e-16"), "long"), 'USDT': (D("0.1"), "long"), @@ -163,6 +184,8 @@ class PortfolioTest(WebMockTestCase): portfolio.Portfolio.repartition(refetch=True) self.assertEqual(2, self.wm.call_count) + report_store.log_http_request.assert_called() + self.assertEqual(2, report_store.log_http_request.call_count) @mock.patch.object(portfolio.time, "sleep") @mock.patch.object(portfolio.Portfolio, "repartition") @@ -434,6 +457,17 @@ class AmountTest(WebMockTestCase): amount2.linked_to = amount3 self.assertEqual("Amount(32.00000000 BTX -> Amount(12000.00000000 USDT -> Amount(0.10000000 BTC)))", repr(amount1)) + def test_as_json(self): + amount = portfolio.Amount("BTX", 32) + self.assertEqual({"currency": "BTX", "value": D("32")}, amount.as_json()) + + amount = portfolio.Amount("BTX", "1E-10") + self.assertEqual({"currency": "BTX", "value": D("0")}, amount.as_json()) + + amount = portfolio.Amount("BTX", "1E-5") + self.assertEqual({"currency": "BTX", "value": D("0.00001")}, amount.as_json()) + self.assertEqual("0.00001", str(amount.as_json()["value"])) + @unittest.skipUnless("unit" in limits, "Unit skipped") class BalanceTest(WebMockTestCase): def test_values(self): @@ -497,6 +531,18 @@ class BalanceTest(WebMockTestCase): "margin_borrowed": 1, "exchange_free": 2, "exchange_total": 2}) self.assertEqual("Balance(BTX Exch: [✔2.00000000 BTX] Margin: [borrowed 1.00000000 BTX] Total: [0.00000000 BTX])", repr(balance)) + def test_as_json(self): + balance = portfolio.Balance("BTX", { "exchange_free": 2, "exchange_total": 2 }) + as_json = balance.as_json() + self.assertEqual(set(portfolio.Balance.base_keys), set(as_json.keys())) + self.assertEqual(D(0), as_json["total"]) + self.assertEqual(D(2), as_json["exchange_total"]) + self.assertEqual(D(2), as_json["exchange_free"]) + self.assertEqual(D(0), as_json["exchange_used"]) + self.assertEqual(D(0), as_json["margin_total"]) + self.assertEqual(D(0), as_json["margin_free"]) + self.assertEqual(D(0), as_json["margin_borrowed"]) + @unittest.skipUnless("unit" in limits, "Unit skipped") class HelperTest(WebMockTestCase): def test_get_ticker(self): @@ -575,7 +621,9 @@ class HelperTest(WebMockTestCase): @mock.patch.object(portfolio.Portfolio, "repartition") @mock.patch.object(helper, "get_ticker") @mock.patch.object(portfolio.TradeStore, "compute_trades") - def test_prepare_trades(self, compute_trades, get_ticker, repartition): + @mock.patch("store.ReportStore") + @mock.patch("helper.ReportStore") + def test_prepare_trades(self, report_store_h, report_store, compute_trades, get_ticker, repartition): repartition.return_value = { "XEM": (D("0.75"), "long"), "BTC": (D("0.25"), "long"), @@ -614,11 +662,15 @@ class HelperTest(WebMockTestCase): self.assertEqual(D("0.01"), call[0][0]["XVG"].value) self.assertEqual(D("0.2525"), call[0][1]["BTC"].value) self.assertEqual(D("0.7575"), call[0][1]["XEM"].value) + report_store_h.log_stage.assert_called_once_with("prepare_trades") + report_store.log_balances.assert_called_once_with(market) @mock.patch.object(portfolio.Portfolio, "repartition") @mock.patch.object(helper, "get_ticker") @mock.patch.object(portfolio.TradeStore, "compute_trades") - def test_update_trades(self, compute_trades, get_ticker, repartition): + @mock.patch("store.ReportStore") + @mock.patch("helper.ReportStore") + def test_update_trades(self, report_store_h, report_store, compute_trades, get_ticker, repartition): repartition.return_value = { "XEM": (D("0.75"), "long"), "BTC": (D("0.25"), "long"), @@ -657,11 +709,15 @@ class HelperTest(WebMockTestCase): self.assertEqual(D("0.01"), call[0][0]["XVG"].value) self.assertEqual(D("0.2525"), call[0][1]["BTC"].value) self.assertEqual(D("0.7575"), call[0][1]["XEM"].value) + report_store_h.log_stage.assert_called_once_with("update_trades") + report_store.log_balances.assert_called_once_with(market) @mock.patch.object(portfolio.Portfolio, "repartition") @mock.patch.object(helper, "get_ticker") @mock.patch.object(portfolio.TradeStore, "compute_trades") - def test_prepare_trades_to_sell_all(self, compute_trades, get_ticker, repartition): + @mock.patch("store.ReportStore") + @mock.patch("helper.ReportStore") + def test_prepare_trades_to_sell_all(self, report_store_h, report_store, compute_trades, get_ticker, repartition): def _get_ticker(c1, c2, market): if c1 == "USDT" and c2 == "BTC": return { "average": D("0.0001") } @@ -694,16 +750,17 @@ class HelperTest(WebMockTestCase): self.assertEqual(1, call[0][0]["USDT"].value) self.assertEqual(D("0.01"), call[0][0]["XVG"].value) self.assertEqual(D("1.01"), call[0][1]["BTC"].value) + report_store_h.log_stage.assert_called_once_with("prepare_trades_to_sell_all") + report_store.log_balances.assert_called_once_with(market) @mock.patch.object(portfolio.time, "sleep") @mock.patch.object(portfolio.TradeStore, "all_orders") def test_follow_orders(self, all_orders, time_mock): - for verbose, debug, sleep in [ - (True, False, None), (False, False, None), - (True, True, None), (True, False, 12), - (True, True, 12)]: - with self.subTest(sleep=sleep, debug=debug, verbose=verbose), \ - mock.patch('sys.stdout', new_callable=StringIO) as stdout_mock: + for debug, sleep in [ + (False, None), (True, None), + (False, 12), (True, 12)]: + with self.subTest(sleep=sleep, debug=debug), \ + mock.patch("helper.ReportStore") as report_store: portfolio.TradeStore.debug = debug order_mock1 = mock.Mock() order_mock2 = mock.Mock() @@ -729,7 +786,7 @@ class HelperTest(WebMockTestCase): order_mock2.trade = mock.Mock() order_mock3.trade = mock.Mock() - helper.follow_orders(verbose=verbose, sleep=sleep) + helper.follow_orders(sleep=sleep) order_mock1.trade.update_order.assert_any_call(order_mock1, 1) order_mock1.trade.update_order.assert_any_call(order_mock1, 2) @@ -743,25 +800,43 @@ class HelperTest(WebMockTestCase): order_mock3.trade.update_order.assert_any_call(order_mock3, 2) self.assertEqual(1, order_mock3.trade.update_order.call_count) self.assertEqual(2, order_mock3.get_status.call_count) + report_store.log_stage.assert_called() + calls = [ + mock.call("follow_orders_begin"), + mock.call("follow_orders_tick_1"), + mock.call("follow_orders_tick_2"), + mock.call("follow_orders_tick_3"), + mock.call("follow_orders_end"), + ] + report_store.log_stage.assert_has_calls(calls) + report_store.log_orders.assert_called() + self.assertEqual(3, report_store.log_orders.call_count) + calls = [ + mock.call([order_mock1, order_mock2], tick=1), + mock.call([order_mock1, order_mock3], tick=2), + mock.call([order_mock1, order_mock3], tick=3), + ] + report_store.log_orders.assert_has_calls(calls) + calls = [ + mock.call(order_mock1, 3, finished=True), + mock.call(order_mock3, 3, finished=True), + ] + report_store.log_order.assert_has_calls(calls) if sleep is None: if debug: + report_store.log_debug_action.assert_called_with("Set follow_orders tick to 7s") time_mock.assert_called_with(7) else: time_mock.assert_called_with(30) else: time_mock.assert_called_with(sleep) - if verbose: - self.assertNotEqual("", stdout_mock.getvalue()) - else: - self.assertEqual("", stdout_mock.getvalue()) - @mock.patch.object(portfolio.BalanceStore, "fetch_balances") def test_move_balance(self, fetch_balances): for debug in [True, False]: with self.subTest(debug=debug),\ - mock.patch('sys.stdout', new_callable=StringIO) as stdout_mock: + mock.patch("helper.ReportStore") as report_store: value_from = portfolio.Amount("BTC", "1.0") value_from.linked_to = portfolio.Amount("ETH", "10.0") value_to = portfolio.Amount("BTC", "10.0") @@ -788,8 +863,11 @@ class HelperTest(WebMockTestCase): helper.move_balances(market, debug=debug) fetch_balances.assert_called_with(market) + report_store.log_move_balances.assert_called_once() + if debug: - self.assertRegex(stdout_mock.getvalue(), "market.transfer_balance") + report_store.log_debug_action.assert_called() + self.assertEqual(3, report_store.log_debug_action.call_count) else: market.transfer_balance.assert_any_call("BTC", 3, "exchange", "margin") market.transfer_balance.assert_any_call("USDT", 50, "margin", "exchange") @@ -797,9 +875,8 @@ class HelperTest(WebMockTestCase): @mock.patch.object(helper, "prepare_trades") @mock.patch.object(portfolio.TradeStore, "prepare_orders") - @mock.patch.object(portfolio.TradeStore, "print_all_with_order") - @mock.patch('sys.stdout', new_callable=StringIO) - def test_print_orders(self, stdout_mock, print_all_with_order, prepare_orders, prepare_trades): + @mock.patch.object(portfolio.ReportStore, "log_stage") + def test_print_orders(self, log_stage, prepare_orders, prepare_trades): market = mock.Mock() portfolio.BalanceStore.all = { "BTC": portfolio.Balance("BTC", { @@ -817,13 +894,12 @@ class HelperTest(WebMockTestCase): prepare_trades.assert_called_with(market, base_currency="BTC", compute_value="average", debug=True) prepare_orders.assert_called_with(compute_value="average") - print_all_with_order.assert_called() - self.assertRegex(stdout_mock.getvalue(), "Balance") + log_stage.assert_called_with("print_orders") @mock.patch.object(portfolio.BalanceStore, "fetch_balances") @mock.patch.object(portfolio.BalanceStore, "in_currency") - @mock.patch('sys.stdout', new_callable=StringIO) - def test_print_balances(self, stdout_mock, in_currency, fetch_balances): + @mock.patch.object(helper.ReportStore, "print_log") + def test_print_balances(self, print_log, in_currency, fetch_balances): market = mock.Mock() portfolio.BalanceStore.all = { "BTC": portfolio.Balance("BTC", { @@ -843,18 +919,18 @@ class HelperTest(WebMockTestCase): } helper.print_balances(market) fetch_balances.assert_called_with(market) - self.assertRegex(stdout_mock.getvalue(), "Balance") - self.assertRegex(stdout_mock.getvalue(), "0.95000000 BTC") + print_log.assert_has_calls([ + mock.call("total:"), + mock.call(portfolio.Amount("BTC", "0.95")), + ]) @mock.patch.object(helper, "prepare_trades") @mock.patch.object(helper, "follow_orders") @mock.patch.object(portfolio.TradeStore, "prepare_orders") - @mock.patch.object(portfolio.TradeStore, "print_all_with_order") @mock.patch.object(portfolio.TradeStore, "run_orders") - @mock.patch('sys.stdout', new_callable=StringIO) - def test_process_sell_needed__1_sell(self, stdout_mock, run_orders, - print_all_with_order, prepare_orders, follow_orders, - prepare_trades): + @mock.patch.object(portfolio.ReportStore, "log_stage") + def test_process_sell_needed__1_sell(self, log_stage, run_orders, + prepare_orders, follow_orders, prepare_trades): market = mock.Mock() portfolio.BalanceStore.all = { "BTC": portfolio.Balance("BTC", { @@ -873,20 +949,18 @@ class HelperTest(WebMockTestCase): liquidity="medium", debug=False) prepare_orders.assert_called_with(compute_value="average", only="dispose") - print_all_with_order.assert_called() run_orders.assert_called() follow_orders.assert_called() - self.assertRegex(stdout_mock.getvalue(), "Balance") + log_stage.assert_called_with("process_sell_needed__1_sell_end") @mock.patch.object(helper, "update_trades") @mock.patch.object(helper, "follow_orders") @mock.patch.object(helper, "move_balances") @mock.patch.object(portfolio.TradeStore, "prepare_orders") - @mock.patch.object(portfolio.TradeStore, "print_all_with_order") @mock.patch.object(portfolio.TradeStore, "run_orders") - @mock.patch('sys.stdout', new_callable=StringIO) - def test_process_sell_needed__2_buy(self, stdout_mock, run_orders, - print_all_with_order, prepare_orders, move_balances, + @mock.patch.object(portfolio.ReportStore, "log_stage") + def test_process_sell_needed__2_buy(self, log_stage, run_orders, + prepare_orders, move_balances, follow_orders, update_trades): market = mock.Mock() portfolio.BalanceStore.all = { @@ -906,21 +980,18 @@ class HelperTest(WebMockTestCase): debug=False, liquidity="medium", only="acquire") prepare_orders.assert_called_with(compute_value="average", only="acquire") - print_all_with_order.assert_called() move_balances.assert_called_with(market, debug=False) run_orders.assert_called() follow_orders.assert_called() - self.assertRegex(stdout_mock.getvalue(), "Balance") + log_stage.assert_called_with("process_sell_needed__2_buy_end") @mock.patch.object(helper, "prepare_trades_to_sell_all") @mock.patch.object(helper, "follow_orders") @mock.patch.object(portfolio.TradeStore, "prepare_orders") - @mock.patch.object(portfolio.TradeStore, "print_all_with_order") @mock.patch.object(portfolio.TradeStore, "run_orders") - @mock.patch('sys.stdout', new_callable=StringIO) - def test_process_sell_all__1_sell(self, stdout_mock, run_orders, - print_all_with_order, prepare_orders, follow_orders, - prepare_trades_to_sell_all): + @mock.patch.object(portfolio.ReportStore, "log_stage") + def test_process_sell_all__1_sell(self, log_stage, run_orders, + prepare_orders, follow_orders, prepare_trades_to_sell_all): market = mock.Mock() portfolio.BalanceStore.all = { "BTC": portfolio.Balance("BTC", { @@ -938,21 +1009,19 @@ class HelperTest(WebMockTestCase): prepare_trades_to_sell_all.assert_called_with(market, base_currency="BTC", debug=False) prepare_orders.assert_called_with(compute_value="average") - print_all_with_order.assert_called() run_orders.assert_called() follow_orders.assert_called() - self.assertRegex(stdout_mock.getvalue(), "Balance") + log_stage.assert_called_with("process_sell_all__1_all_sell_end") @mock.patch.object(helper, "prepare_trades") @mock.patch.object(helper, "follow_orders") @mock.patch.object(helper, "move_balances") @mock.patch.object(portfolio.TradeStore, "prepare_orders") - @mock.patch.object(portfolio.TradeStore, "print_all_with_order") @mock.patch.object(portfolio.TradeStore, "run_orders") - @mock.patch('sys.stdout', new_callable=StringIO) - def test_process_sell_all__2_all_buy(self, stdout_mock, run_orders, - print_all_with_order, prepare_orders, move_balances, - follow_orders, prepare_trades): + @mock.patch.object(portfolio.ReportStore, "log_stage") + def test_process_sell_all__2_all_buy(self, log_stage, run_orders, + prepare_orders, move_balances, follow_orders, + prepare_trades): market = mock.Mock() portfolio.BalanceStore.all = { "BTC": portfolio.Balance("BTC", { @@ -970,18 +1039,18 @@ class HelperTest(WebMockTestCase): prepare_trades.assert_called_with(market, base_currency="BTC", liquidity="medium", debug=False) prepare_orders.assert_called_with(compute_value="average") - print_all_with_order.assert_called() move_balances.assert_called_with(market, debug=False) run_orders.assert_called() follow_orders.assert_called() - self.assertRegex(stdout_mock.getvalue(), "Balance") + log_stage.assert_called_with("process_sell_all__2_all_buy_end") @unittest.skipUnless("unit" in limits, "Unit skipped") class TradeStoreTest(WebMockTestCase): @mock.patch.object(portfolio.BalanceStore, "currencies") - @mock.patch.object(portfolio.TradeStore, "add_trade_if_matching") - def test_compute_trades(self, add_trade_if_matching, currencies): + @mock.patch.object(portfolio.TradeStore, "trade_if_matching") + @mock.patch.object(portfolio.ReportStore, "log_trades") + def test_compute_trades(self, log_trades, trade_if_matching, currencies): currencies.return_value = ["XMR", "DASH", "XVG", "BTC", "ETH"] values_in_base = { @@ -996,96 +1065,89 @@ class TradeStoreTest(WebMockTestCase): "BTC": portfolio.Amount("BTC", D("0.4")), "ETH": portfolio.Amount("BTC", D("0.3")), } + side_effect = [ + (True, 1), + (False, 2), + (False, 3), + (True, 4), + (True, 5) + ] + trade_if_matching.side_effect = side_effect portfolio.TradeStore.compute_trades(values_in_base, new_repartition, only="only", market="market") - self.assertEqual(5, add_trade_if_matching.call_count) - add_trade_if_matching.assert_any_call( - portfolio.Amount("BTC", D("0.9")), - portfolio.Amount("BTC", 0), - "XMR", only="only", market="market" - ) - add_trade_if_matching.assert_any_call( - portfolio.Amount("BTC", D("0.4")), - portfolio.Amount("BTC", D("0.5")), - "DASH", only="only", market="market" - ) - add_trade_if_matching.assert_any_call( - portfolio.Amount("BTC", D("-0.5")), - portfolio.Amount("BTC", D("0")), - "XVG", only="only", market="market" - ) - add_trade_if_matching.assert_any_call( - portfolio.Amount("BTC", D("0")), - portfolio.Amount("BTC", D("0.1")), - "XVG", only="only", market="market" - ) - add_trade_if_matching.assert_any_call( - portfolio.Amount("BTC", D("0")), - portfolio.Amount("BTC", D("0.3")), - "ETH", only="only", market="market" - ) + self.assertEqual(5, trade_if_matching.call_count) + self.assertEqual(3, len(portfolio.TradeStore.all)) + self.assertEqual([1, 4, 5], portfolio.TradeStore.all) + log_trades.assert_called_with(side_effect, "only", False) - def test_add_trade_if_matching(self): - result = portfolio.TradeStore.add_trade_if_matching( + def test_trade_if_matching(self): + result = portfolio.TradeStore.trade_if_matching( portfolio.Amount("BTC", D("0")), portfolio.Amount("BTC", D("0.3")), "ETH", only="nope", market="market" ) - self.assertEqual(0, len(portfolio.TradeStore.all)) - self.assertEqual(False, result) + self.assertEqual(False, result[0]) + self.assertIsInstance(result[1], portfolio.Trade) portfolio.TradeStore.all = [] - result = portfolio.TradeStore.add_trade_if_matching( + result = portfolio.TradeStore.trade_if_matching( portfolio.Amount("BTC", D("0")), portfolio.Amount("BTC", D("0.3")), "ETH", only=None, market="market" ) - self.assertEqual(1, len(portfolio.TradeStore.all)) - self.assertEqual(True, result) + self.assertEqual(True, result[0]) portfolio.TradeStore.all = [] - result = portfolio.TradeStore.add_trade_if_matching( + result = portfolio.TradeStore.trade_if_matching( portfolio.Amount("BTC", D("0")), portfolio.Amount("BTC", D("0.3")), "ETH", only="acquire", market="market" ) - self.assertEqual(1, len(portfolio.TradeStore.all)) - self.assertEqual(True, result) + self.assertEqual(True, result[0]) portfolio.TradeStore.all = [] - result = portfolio.TradeStore.add_trade_if_matching( + result = portfolio.TradeStore.trade_if_matching( portfolio.Amount("BTC", D("0")), portfolio.Amount("BTC", D("0.3")), "ETH", only="dispose", market="market" ) - self.assertEqual(0, len(portfolio.TradeStore.all)) - self.assertEqual(False, result) + self.assertEqual(False, result[0]) - def test_prepare_orders(self): + @mock.patch.object(portfolio.ReportStore, "log_orders") + def test_prepare_orders(self, log_orders): trade_mock1 = mock.Mock() trade_mock2 = mock.Mock() + trade_mock1.prepare_order.return_value = 1 + trade_mock2.prepare_order.return_value = 2 + portfolio.TradeStore.all.append(trade_mock1) portfolio.TradeStore.all.append(trade_mock2) portfolio.TradeStore.prepare_orders() trade_mock1.prepare_order.assert_called_with(compute_value="default") trade_mock2.prepare_order.assert_called_with(compute_value="default") + log_orders.assert_called_once_with([1, 2], None, "default") + + log_orders.reset_mock() portfolio.TradeStore.prepare_orders(compute_value="bla") trade_mock1.prepare_order.assert_called_with(compute_value="bla") trade_mock2.prepare_order.assert_called_with(compute_value="bla") + log_orders.assert_called_once_with([1, 2], None, "bla") trade_mock1.prepare_order.reset_mock() trade_mock2.prepare_order.reset_mock() + log_orders.reset_mock() trade_mock1.action = "foo" trade_mock2.action = "bar" portfolio.TradeStore.prepare_orders(only="bar") trade_mock1.prepare_order.assert_not_called() trade_mock2.prepare_order.assert_called_with(compute_value="default") + log_orders.assert_called_once_with([2], "bar", "default") def test_print_all_with_order(self): trade_mock1 = mock.Mock() @@ -1099,8 +1161,10 @@ class TradeStoreTest(WebMockTestCase): trade_mock2.print_with_order.assert_called() trade_mock3.print_with_order.assert_called() + @mock.patch.object(portfolio.ReportStore, "log_stage") + @mock.patch.object(portfolio.ReportStore, "log_orders") @mock.patch.object(portfolio.TradeStore, "all_orders") - def test_run_orders(self, all_orders): + def test_run_orders(self, all_orders, log_orders, log_stage): order_mock1 = mock.Mock() order_mock2 = mock.Mock() order_mock3 = mock.Mock() @@ -1112,6 +1176,10 @@ class TradeStoreTest(WebMockTestCase): order_mock2.run.assert_called() order_mock3.run.assert_called() + log_stage.assert_called_with("run_orders") + log_orders.assert_called_with([order_mock1, order_mock2, + order_mock3]) + def test_all_orders(self): trade_mock1 = mock.Mock() trade_mock2 = mock.Mock() @@ -1150,6 +1218,7 @@ class TradeStoreTest(WebMockTestCase): order_mock2.get_status.assert_called() order_mock3.get_status.assert_called() + @unittest.skipUnless("unit" in limits, "Unit skipped") class BalanceStoreTest(WebMockTestCase): def setUp(self): @@ -1184,7 +1253,8 @@ class BalanceStoreTest(WebMockTestCase): } @mock.patch.object(helper, "get_ticker") - def test_in_currency(self, get_ticker): + @mock.patch("portfolio.ReportStore.log_tickers") + def test_in_currency(self, log_tickers, get_ticker): portfolio.BalanceStore.all = { "BTC": portfolio.Balance("BTC", { "total": "0.65", @@ -1208,16 +1278,26 @@ class BalanceStoreTest(WebMockTestCase): self.assertEqual("BTC", amounts["ETH"].currency) self.assertEqual(D("0.65"), amounts["BTC"].value) self.assertEqual(D("0.30"), amounts["ETH"].value) + log_tickers.assert_called_once_with(market, amounts, "BTC", + "average", "total") + log_tickers.reset_mock() amounts = portfolio.BalanceStore.in_currency("BTC", market, compute_value="bid") self.assertEqual(D("0.65"), amounts["BTC"].value) self.assertEqual(D("0.27"), amounts["ETH"].value) + log_tickers.assert_called_once_with(market, amounts, "BTC", + "bid", "total") + log_tickers.reset_mock() amounts = portfolio.BalanceStore.in_currency("BTC", market, compute_value="bid", type="exchange_used") self.assertEqual(D("0.30"), amounts["BTC"].value) self.assertEqual(0, amounts["ETH"].value) + log_tickers.assert_called_once_with(market, amounts, "BTC", + "bid", "exchange_used") + log_tickers.reset_mock() - def test_fetch_balances(self): + @mock.patch.object(portfolio.ReportStore, "log_balances") + def test_fetch_balances(self, log_balances): market = mock.Mock() market.fetch_all_balances.return_value = self.fetch_balance @@ -1231,20 +1311,24 @@ class BalanceStoreTest(WebMockTestCase): portfolio.BalanceStore.fetch_balances(market) self.assertEqual(0, portfolio.BalanceStore.all["ETC"].total) self.assertListEqual(["USDT", "XVG", "XMR", "ETC"], list(portfolio.BalanceStore.currencies())) + log_balances.assert_called_with(market) @mock.patch.object(portfolio.Portfolio, "repartition") - def test_dispatch_assets(self, repartition): + @mock.patch.object(portfolio.ReportStore, "log_balances") + @mock.patch("store.ReportStore.log_dispatch") + def test_dispatch_assets(self, log_dispatch, log_balances, repartition): market = mock.Mock() market.fetch_all_balances.return_value = self.fetch_balance portfolio.BalanceStore.fetch_balances(market) self.assertNotIn("XEM", portfolio.BalanceStore.currencies()) - repartition.return_value = { + repartition_hash = { "XEM": (D("0.75"), "long"), "BTC": (D("0.26"), "long"), "DASH": (D("0.10"), "short"), } + repartition.return_value = repartition_hash amounts = portfolio.BalanceStore.dispatch_assets(portfolio.Amount("BTC", "11.1")) repartition.assert_called_with(liquidity="medium") @@ -1252,6 +1336,9 @@ class BalanceStoreTest(WebMockTestCase): self.assertEqual(D("2.6"), amounts["BTC"].value) self.assertEqual(D("7.5"), amounts["XEM"].value) self.assertEqual(D("-1.0"), amounts["DASH"].value) + log_balances.assert_called_with(market) + log_dispatch.assert_called_once_with(portfolio.Amount("BTC", + "11.1"), amounts, "medium", repartition_hash) def test_currencies(self): portfolio.BalanceStore.all = { @@ -1268,6 +1355,23 @@ class BalanceStoreTest(WebMockTestCase): } self.assertListEqual(["BTC", "ETH"], list(portfolio.BalanceStore.currencies())) + def test_as_json(self): + balance_mock1 = mock.Mock() + balance_mock1.as_json.return_value = 1 + + balance_mock2 = mock.Mock() + balance_mock2.as_json.return_value = 2 + + portfolio.BalanceStore.all = { + "BTC": balance_mock1, + "ETH": balance_mock2, + } + + as_json = portfolio.BalanceStore.as_json() + self.assertEqual(1, as_json["BTC"]) + self.assertEqual(2, as_json["ETH"]) + + @unittest.skipUnless("unit" in limits, "Unit skipped") class ComputationTest(WebMockTestCase): def test_compute_value(self): @@ -1426,7 +1530,8 @@ class TradeTest(WebMockTestCase): Order.assert_not_called() get_ticker.return_value = { "inverted": False } - with self.subTest(desc="Already filled"), mock.patch('sys.stdout', new_callable=StringIO) as stdout_mock: + with self.subTest(desc="Already filled"),\ + mock.patch("portfolio.ReportStore") as report_store: filled_amount.return_value = portfolio.Amount("FOO", "100") compute_value.return_value = D("0.125") @@ -1441,7 +1546,7 @@ class TradeTest(WebMockTestCase): filled_amount.assert_called_with(in_base_currency=False) compute_value.assert_called_with(get_ticker.return_value, "sell", compute_value="default") self.assertEqual(0, len(trade.orders)) - self.assertRegex(stdout_mock.getvalue(), "Less to do than already filled: ") + report_store.log_error.assert_called_with("prepare_order", message=mock.ANY) Order.assert_not_called() with self.subTest(action="dispose", inverted=False): @@ -1554,77 +1659,110 @@ class TradeTest(WebMockTestCase): prepare_order.return_value = new_order_mock for i in [0, 1, 3, 4, 6]: - with self.subTest(tick=i), mock.patch('sys.stdout', new_callable=StringIO) as stdout_mock: + with self.subTest(tick=i),\ + mock.patch.object(portfolio.ReportStore, "log_order") as log_order: trade.update_order(order_mock, i) order_mock.cancel.assert_not_called() new_order_mock.run.assert_not_called() - self.assertRegex(stdout_mock.getvalue(), "tick {}, waiting".format(i)) + log_order.assert_called_once_with(order_mock, i, + update="waiting", compute_value=None, new_order=None) order_mock.reset_mock() new_order_mock.reset_mock() trade.orders = [] - with mock.patch('sys.stdout', new_callable=StringIO) as stdout_mock: + with mock.patch.object(portfolio.ReportStore, "log_order") as log_order: trade.update_order(order_mock, 2) order_mock.cancel.assert_called() new_order_mock.run.assert_called() prepare_order.assert_called() - self.assertRegex(stdout_mock.getvalue(), "tick 2, cancelling and adjusting") + log_order.assert_called() + self.assertEqual(2, log_order.call_count) + calls = [ + mock.call(order_mock, 2, update="adjusting", + compute_value='lambda x, y: (x[y] + x["average"]) / 2', + new_order=new_order_mock), + mock.call(order_mock, 2, new_order=new_order_mock), + ] + log_order.assert_has_calls(calls) order_mock.reset_mock() new_order_mock.reset_mock() trade.orders = [] - with mock.patch('sys.stdout', new_callable=StringIO) as stdout_mock: + with mock.patch.object(portfolio.ReportStore, "log_order") as log_order: trade.update_order(order_mock, 5) order_mock.cancel.assert_called() new_order_mock.run.assert_called() prepare_order.assert_called() - self.assertRegex(stdout_mock.getvalue(), "tick 5, cancelling and adjusting") + self.assertEqual(2, log_order.call_count) + log_order.assert_called() + calls = [ + mock.call(order_mock, 5, update="adjusting", + compute_value='lambda x, y: (x[y]*2 + x["average"]) / 3', + new_order=new_order_mock), + mock.call(order_mock, 5, new_order=new_order_mock), + ] + log_order.assert_has_calls(calls) order_mock.reset_mock() new_order_mock.reset_mock() trade.orders = [] - with mock.patch('sys.stdout', new_callable=StringIO) as stdout_mock: + with mock.patch.object(portfolio.ReportStore, "log_order") as log_order: trade.update_order(order_mock, 7) order_mock.cancel.assert_called() new_order_mock.run.assert_called() prepare_order.assert_called_with(compute_value="default") - self.assertRegex(stdout_mock.getvalue(), "tick 7, fallbacking to market value") - self.assertRegex(stdout_mock.getvalue(), "tick 7, market value, cancelling and adjusting to") + log_order.assert_called() + self.assertEqual(2, log_order.call_count) + calls = [ + mock.call(order_mock, 7, update="market_fallback", + compute_value='default', + new_order=new_order_mock), + mock.call(order_mock, 7, new_order=new_order_mock), + ] + log_order.assert_has_calls(calls) order_mock.reset_mock() new_order_mock.reset_mock() trade.orders = [] for i in [10, 13, 16]: - with self.subTest(tick=i), mock.patch('sys.stdout', new_callable=StringIO) as stdout_mock: + with self.subTest(tick=i), mock.patch.object(portfolio.ReportStore, "log_order") as log_order: trade.update_order(order_mock, i) order_mock.cancel.assert_called() new_order_mock.run.assert_called() prepare_order.assert_called_with(compute_value="default") - self.assertNotRegex(stdout_mock.getvalue(), "tick {}, fallbacking to market value".format(i)) - self.assertRegex(stdout_mock.getvalue(), "tick {}, market value, cancelling and adjusting to".format(i)) + log_order.assert_called() + self.assertEqual(2, log_order.call_count) + calls = [ + mock.call(order_mock, i, update="market_adjust", + compute_value='default', + new_order=new_order_mock), + mock.call(order_mock, i, new_order=new_order_mock), + ] + log_order.assert_has_calls(calls) order_mock.reset_mock() new_order_mock.reset_mock() trade.orders = [] for i in [8, 9, 11, 12]: - with self.subTest(tick=i), mock.patch('sys.stdout', new_callable=StringIO) as stdout_mock: + with self.subTest(tick=i), mock.patch.object(portfolio.ReportStore, "log_order") as log_order: trade.update_order(order_mock, i) order_mock.cancel.assert_not_called() new_order_mock.run.assert_not_called() - self.assertEqual("", stdout_mock.getvalue()) + log_order.assert_called_once_with(order_mock, i, update="waiting", + compute_value=None, new_order=None) order_mock.reset_mock() new_order_mock.reset_mock() trade.orders = [] - @mock.patch('sys.stdout', new_callable=StringIO) - def test_print_with_order(self, mock_stdout): + @mock.patch.object(portfolio.ReportStore, "print_log") + def test_print_with_order(self, print_log): value_from = portfolio.Amount("BTC", "0.5") value_from.linked_to = portfolio.Amount("ETH", "10.0") value_to = portfolio.Amount("BTC", "1.0") @@ -1651,12 +1789,13 @@ class TradeTest(WebMockTestCase): trade.print_with_order() - out = mock_stdout.getvalue().split("\n") - self.assertEqual("Trade(0.50000000 BTC [10.00000000 ETH] -> 1.00000000 BTC in ETH, acquire)", out[0]) - self.assertEqual("\tMock 1", out[1]) - self.assertEqual("\tMock 2", out[2]) - self.assertEqual("\t\tMouvement 1", out[3]) - self.assertEqual("\t\tMouvement 2", out[4]) + print_log.assert_called() + calls = print_log.mock_calls + self.assertEqual("Trade(0.50000000 BTC [10.00000000 ETH] -> 1.00000000 BTC in ETH, acquire)", str(calls[0][1][0])) + self.assertEqual("\tMock 1", str(calls[1][1][0])) + self.assertEqual("\tMock 2", str(calls[2][1][0])) + self.assertEqual("\t\tMouvement 1", str(calls[3][1][0])) + self.assertEqual("\t\tMouvement 2", str(calls[4][1][0])) def test__repr(self): value_from = portfolio.Amount("BTC", "0.5") @@ -1666,6 +1805,19 @@ class TradeTest(WebMockTestCase): self.assertEqual("Trade(0.50000000 BTC [10.00000000 ETH] -> 1.00000000 BTC in ETH, acquire)", str(trade)) + def test_as_json(self): + value_from = portfolio.Amount("BTC", "0.5") + value_from.linked_to = portfolio.Amount("ETH", "10.0") + value_to = portfolio.Amount("BTC", "1.0") + trade = portfolio.Trade(value_from, value_to, "ETH") + + as_json = trade.as_json() + self.assertEqual("acquire", as_json["action"]) + self.assertEqual(D("0.5"), as_json["from"]) + self.assertEqual(D("1.0"), as_json["to"]) + self.assertEqual("ETH", as_json["currency"]) + self.assertEqual("BTC", as_json["base_currency"]) + @unittest.skipUnless("unit" in limits, "Unit skipped") class OrderTest(WebMockTestCase): def test_values(self): @@ -1693,6 +1845,27 @@ class OrderTest(WebMockTestCase): close_if_possible=True) self.assertEqual("Order(buy long 10.00000000 ETH at 0.1 BTC [pending] ✂)", repr(order)) + def test_as_json(self): + order = portfolio.Order("buy", portfolio.Amount("ETH", 10), + D("0.1"), "BTC", "long", "market", "trade") + mouvement_mock1 = mock.Mock() + mouvement_mock1.as_json.return_value = 1 + mouvement_mock2 = mock.Mock() + mouvement_mock2.as_json.return_value = 2 + + order.mouvements = [mouvement_mock1, mouvement_mock2] + as_json = order.as_json() + self.assertEqual("buy", as_json["action"]) + self.assertEqual("long", as_json["trade_type"]) + self.assertEqual(10, as_json["amount"]) + self.assertEqual("ETH", as_json["currency"]) + self.assertEqual("BTC", as_json["base_currency"]) + self.assertEqual(D("0.1"), as_json["rate"]) + self.assertEqual("pending", as_json["status"]) + self.assertEqual(False, as_json["close_if_possible"]) + self.assertIsNone(as_json["id"]) + self.assertEqual([1, 2], as_json["mouvements"]) + def test_account(self): order = portfolio.Order("buy", portfolio.Amount("ETH", 10), D("0.1"), "BTC", "long", "market", "trade") @@ -1728,7 +1901,8 @@ class OrderTest(WebMockTestCase): self.assertTrue(order.finished) @mock.patch.object(portfolio.Order, "fetch") - def test_cancel(self, fetch): + @mock.patch("portfolio.ReportStore") + def test_cancel(self, report_store, fetch): market = mock.Mock() portfolio.TradeStore.debug = True order = portfolio.Order("buy", portfolio.Amount("ETH", 10), @@ -1737,6 +1911,8 @@ class OrderTest(WebMockTestCase): order.cancel() market.cancel_order.assert_not_called() + report_store.log_debug_action.assert_called_once() + report_store.log_debug_action.reset_mock() self.assertEqual("canceled", order.status) portfolio.TradeStore.debug = False @@ -1748,6 +1924,7 @@ class OrderTest(WebMockTestCase): order.cancel() market.cancel_order.assert_called_with(42) fetch.assert_called_once() + report_store.log_debug_action.assert_not_called() def test_dust_amount_remaining(self): order = portfolio.Order("buy", portfolio.Amount("ETH", 10), @@ -1824,7 +2001,8 @@ class OrderTest(WebMockTestCase): order.fetch_mouvements() self.assertEqual(0, len(order.mouvements)) - def test_mark_finished_order(self): + @mock.patch("portfolio.ReportStore") + def test_mark_finished_order(self, report_store): market = mock.Mock() order = portfolio.Order("buy", portfolio.Amount("ETH", 10), D("0.1"), "BTC", "short", market, "trade", @@ -1870,9 +2048,11 @@ class OrderTest(WebMockTestCase): order.mark_finished_order() market.close_margin_position.assert_not_called() + report_store.log_debug_action.assert_called_once() @mock.patch.object(portfolio.Order, "fetch_mouvements") - def test_fetch(self, fetch_mouvements): + @mock.patch("portfolio.ReportStore") + def test_fetch(self, report_store, fetch_mouvements): time = self.time.time() with mock.patch.object(portfolio.time, "time") as time_mock: market = mock.Mock() @@ -1883,10 +2063,14 @@ class OrderTest(WebMockTestCase): portfolio.TradeStore.debug = True order.fetch() time_mock.assert_not_called() + report_store.log_debug_action.assert_called_once() + report_store.log_debug_action.reset_mock() order.fetch(force=True) time_mock.assert_not_called() market.fetch_order.assert_not_called() fetch_mouvements.assert_not_called() + report_store.log_debug_action.assert_called_once() + report_store.log_debug_action.reset_mock() self.assertIsNone(order.fetch_cache_timestamp) with self.subTest(debug=False): @@ -1924,16 +2108,19 @@ class OrderTest(WebMockTestCase): order.fetch() market.fetch_order.assert_called_once() fetch_mouvements.assert_called_once() + report_store.log_debug_action.assert_not_called() @mock.patch.object(portfolio.Order, "fetch") @mock.patch.object(portfolio.Order, "mark_finished_order") - def test_get_status(self, mark_finished_order, fetch): + @mock.patch("portfolio.ReportStore") + def test_get_status(self, report_store, mark_finished_order, fetch): with self.subTest(debug=True): portfolio.TradeStore.debug = True order = portfolio.Order("buy", portfolio.Amount("ETH", 10), D("0.1"), "BTC", "long", "market", "trade") self.assertEqual("pending", order.get_status()) fetch.assert_not_called() + report_store.log_debug_action.assert_called_once() with self.subTest(debug=False, finished=False): portfolio.TradeStore.debug = False @@ -1968,13 +2155,13 @@ class OrderTest(WebMockTestCase): market.order_precision.return_value = 4 with self.subTest(debug=True),\ - mock.patch('sys.stdout', new_callable=StringIO) as stdout_mock: + mock.patch('portfolio.ReportStore') as report_store: portfolio.TradeStore.debug = True order = portfolio.Order("buy", portfolio.Amount("ETH", 10), D("0.1"), "BTC", "long", market, "trade") order.run() market.create_order.assert_not_called() - self.assertEqual("market.create_order('ETH/BTC', 'limit', 'buy', 10.0000, price=0.1, account=exchange)\n", stdout_mock.getvalue()) + report_store.log_debug_action.assert_called_with("market.create_order('ETH/BTC', 'limit', 'buy', 10.0000, price=0.1, account=exchange)") self.assertEqual("open", order.status) self.assertEqual(1, len(order.results)) self.assertEqual(-1, order.id) @@ -1992,7 +2179,7 @@ class OrderTest(WebMockTestCase): market.create_order.reset_mock() with self.subTest(exception=True),\ - mock.patch('sys.stdout', new_callable=StringIO) as stdout_mock: + mock.patch('portfolio.ReportStore') as report_store: order = portfolio.Order("buy", portfolio.Amount("ETH", 10), D("0.1"), "BTC", "long", market, "trade") market.create_order.side_effect = Exception("bouh") @@ -2000,8 +2187,7 @@ class OrderTest(WebMockTestCase): market.create_order.assert_called_once() self.assertEqual(0, len(order.results)) self.assertEqual("error", order.status) - self.assertRegex(stdout_mock.getvalue(), "error when running market.create_order") - self.assertRegex(stdout_mock.getvalue(), "Exception: bouh") + report_store.log_error.assert_called_once() market.create_order.reset_mock() with self.subTest(dust_amount_exception=True),\ @@ -2050,6 +2236,7 @@ class MouvementTest(WebMockTestCase): "amount": "10", "total": "1" }) self.assertEqual("Mouvement(2017-12-30 12:00:12 ; buy 10.00000000 ETH (1.00000000 BTC) fee: 0.1500%)", repr(mouvement)) + mouvement = portfolio.Mouvement("ETH", "BTC", { "tradeID": 42, "type": "buy", "date": "garbage", "rate": "0.1", @@ -2057,10 +2244,401 @@ class MouvementTest(WebMockTestCase): }) self.assertEqual("Mouvement(No date ; buy 10.00000000 ETH (1.00000000 BTC))", repr(mouvement)) + def test_as_json(self): + mouvement = portfolio.Mouvement("ETH", "BTC", { + "tradeID": 42, "type": "buy", "fee": "0.0015", + "date": "2017-12-30 12:00:12", "rate": "0.1", + "amount": "10", "total": "1" + }) + as_json = mouvement.as_json() + + self.assertEqual(D("0.0015"), as_json["fee_rate"]) + self.assertEqual(portfolio.datetime(2017, 12, 30, 12, 0, 12), as_json["date"]) + self.assertEqual("buy", as_json["action"]) + self.assertEqual(D("10"), as_json["total"]) + self.assertEqual(D("1"), as_json["total_in_base"]) + self.assertEqual("BTC", as_json["base_currency"]) + self.assertEqual("ETH", as_json["currency"]) + +@unittest.skipUnless("unit" in limits, "Unit skipped") +class ReportStoreTest(WebMockTestCase): + def test_add_log(self): + portfolio.ReportStore.add_log({"foo": "bar"}) + + self.assertEqual({"foo": "bar", "date": mock.ANY}, portfolio.ReportStore.logs[0]) + + def test_set_verbose(self): + with self.subTest(verbose=True): + portfolio.ReportStore.set_verbose(True) + self.assertTrue(portfolio.ReportStore.verbose_print) + + with self.subTest(verbose=False): + portfolio.ReportStore.set_verbose(False) + self.assertFalse(portfolio.ReportStore.verbose_print) + + def test_print_log(self): + with self.subTest(verbose=True),\ + mock.patch('sys.stdout', new_callable=StringIO) as stdout_mock: + portfolio.ReportStore.set_verbose(True) + portfolio.ReportStore.print_log("Coucou") + portfolio.ReportStore.print_log(portfolio.Amount("BTC", 1)) + self.assertEqual(stdout_mock.getvalue(), "Coucou\n1.00000000 BTC\n") + + with self.subTest(verbose=False),\ + mock.patch('sys.stdout', new_callable=StringIO) as stdout_mock: + portfolio.ReportStore.set_verbose(False) + portfolio.ReportStore.print_log("Coucou") + portfolio.ReportStore.print_log(portfolio.Amount("BTC", 1)) + self.assertEqual(stdout_mock.getvalue(), "") + + def test_to_json(self): + portfolio.ReportStore.logs.append({"foo": "bar"}) + self.assertEqual('[{"foo": "bar"}]', portfolio.ReportStore.to_json()) + portfolio.ReportStore.logs.append({"date": portfolio.datetime(2018, 2, 24)}) + self.assertEqual('[{"foo": "bar"}, {"date": "2018-02-24T00:00:00"}]', portfolio.ReportStore.to_json()) + portfolio.ReportStore.logs.append({"amount": portfolio.Amount("BTC", 1)}) + with self.assertRaises(TypeError): + portfolio.ReportStore.to_json() + + @mock.patch.object(portfolio.ReportStore, "print_log") + @mock.patch.object(portfolio.ReportStore, "add_log") + def test_log_stage(self, add_log, print_log): + portfolio.ReportStore.log_stage("foo") + print_log.assert_has_calls([ + mock.call("-----------"), + mock.call("[Stage] foo"), + ]) + add_log.assert_called_once_with({'type': 'stage', 'stage': 'foo'}) + + @mock.patch.object(portfolio.ReportStore, "print_log") + @mock.patch.object(portfolio.ReportStore, "add_log") + @mock.patch("store.BalanceStore") + def test_log_balances(self, balance_store, add_log, print_log): + balance_store.as_json.return_value = "json" + balance_store.all = { "FOO": "bar", "BAR": "baz" } + + portfolio.ReportStore.log_balances("market") + print_log.assert_has_calls([ + mock.call("[Balance]"), + mock.call("\tbar"), + mock.call("\tbaz"), + ]) + add_log.assert_called_once_with({'type': 'balance', 'balances': 'json'}) + + @mock.patch.object(portfolio.ReportStore, "print_log") + @mock.patch.object(portfolio.ReportStore, "add_log") + def test_log_tickers(self, add_log, print_log): + market = mock.Mock() + amounts = { + "BTC": portfolio.Amount("BTC", 10), + "ETH": portfolio.Amount("BTC", D("0.3")) + } + amounts["ETH"].rate = D("0.1") + + portfolio.ReportStore.log_tickers(market, amounts, "BTC", "default", "total") + print_log.assert_not_called() + add_log.assert_called_once_with({ + 'type': 'tickers', + 'compute_value': 'default', + 'balance_type': 'total', + 'currency': 'BTC', + 'balances': { + 'BTC': D('10'), + 'ETH': D('0.3') + }, + 'rates': { + 'BTC': None, + 'ETH': D('0.1') + }, + 'total': D('10.3') + }) + + @mock.patch.object(portfolio.ReportStore, "print_log") + @mock.patch.object(portfolio.ReportStore, "add_log") + def test_log_dispatch(self, add_log, print_log): + amount = portfolio.Amount("BTC", "10.3") + amounts = { + "BTC": portfolio.Amount("BTC", 10), + "ETH": portfolio.Amount("BTC", D("0.3")) + } + portfolio.ReportStore.log_dispatch(amount, amounts, "medium", "repartition") + print_log.assert_not_called() + add_log.assert_called_once_with({ + 'type': 'dispatch', + 'liquidity': 'medium', + 'repartition_ratio': 'repartition', + 'total_amount': { + 'currency': 'BTC', + 'value': D('10.3') + }, + 'repartition': { + 'BTC': D('10'), + 'ETH': D('0.3') + } + }) + + @mock.patch.object(portfolio.ReportStore, "print_log") + @mock.patch.object(portfolio.ReportStore, "add_log") + def test_log_trades(self, add_log, print_log): + trade_mock1 = mock.Mock() + trade_mock2 = mock.Mock() + trade_mock1.as_json.return_value = { "trade": "1" } + trade_mock2.as_json.return_value = { "trade": "2" } + + matching_and_trades = [ + (True, trade_mock1), + (False, trade_mock2), + ] + portfolio.ReportStore.log_trades(matching_and_trades, "only", "debug") + + print_log.assert_not_called() + add_log.assert_called_with({ + 'type': 'trades', + 'only': 'only', + 'debug': 'debug', + 'trades': [ + {'trade': '1', 'skipped': False}, + {'trade': '2', 'skipped': True} + ] + }) + + @mock.patch.object(portfolio.ReportStore, "print_log") + @mock.patch.object(portfolio.ReportStore, "add_log") + @mock.patch.object(portfolio.TradeStore, "print_all_with_order") + def test_log_orders(self, print_all_with_order, add_log, print_log): + order_mock1 = mock.Mock() + order_mock2 = mock.Mock() + + order_mock1.as_json.return_value = "order1" + order_mock2.as_json.return_value = "order2" + + orders = [order_mock1, order_mock2] + + portfolio.ReportStore.log_orders(orders, tick="tick", + only="only", compute_value="compute_value") + + print_log.assert_called_once_with("[Orders]") + print_all_with_order.assert_called_once_with(ind="\t") + + add_log.assert_called_with({ + 'type': 'orders', + 'only': 'only', + 'compute_value': 'compute_value', + 'tick': 'tick', + 'orders': ['order1', 'order2'] + }) + + @mock.patch.object(portfolio.ReportStore, "print_log") + @mock.patch.object(portfolio.ReportStore, "add_log") + def test_log_order(self, add_log, print_log): + order_mock = mock.Mock() + order_mock.as_json.return_value = "order" + new_order_mock = mock.Mock() + new_order_mock.as_json.return_value = "new_order" + order_mock.__repr__ = mock.Mock() + order_mock.__repr__.return_value = "Order Mock" + new_order_mock.__repr__ = mock.Mock() + new_order_mock.__repr__.return_value = "New order Mock" + + with self.subTest(finished=True): + portfolio.ReportStore.log_order(order_mock, 1, finished=True) + print_log.assert_called_once_with("[Order] Finished Order Mock") + add_log.assert_called_once_with({ + 'type': 'order', + 'tick': 1, + 'update': None, + 'order': 'order', + 'compute_value': None, + 'new_order': None + }) + + add_log.reset_mock() + print_log.reset_mock() + + with self.subTest(update="waiting"): + portfolio.ReportStore.log_order(order_mock, 1, update="waiting") + print_log.assert_called_once_with("[Order] Order Mock, tick 1, waiting") + add_log.assert_called_once_with({ + 'type': 'order', + 'tick': 1, + 'update': 'waiting', + 'order': 'order', + 'compute_value': None, + 'new_order': None + }) + + add_log.reset_mock() + print_log.reset_mock() + with self.subTest(update="adjusting"): + portfolio.ReportStore.log_order(order_mock, 3, + update="adjusting", new_order=new_order_mock, + compute_value="default") + print_log.assert_called_once_with("[Order] Order Mock, tick 3, cancelling and adjusting to New order Mock") + add_log.assert_called_once_with({ + 'type': 'order', + 'tick': 3, + 'update': 'adjusting', + 'order': 'order', + 'compute_value': "default", + 'new_order': 'new_order' + }) + + add_log.reset_mock() + print_log.reset_mock() + with self.subTest(update="market_fallback"): + portfolio.ReportStore.log_order(order_mock, 7, + update="market_fallback", new_order=new_order_mock) + print_log.assert_called_once_with("[Order] Order Mock, tick 7, fallbacking to market value") + add_log.assert_called_once_with({ + 'type': 'order', + 'tick': 7, + 'update': 'market_fallback', + 'order': 'order', + 'compute_value': None, + 'new_order': 'new_order' + }) + + add_log.reset_mock() + print_log.reset_mock() + with self.subTest(update="market_adjusting"): + portfolio.ReportStore.log_order(order_mock, 17, + update="market_adjust", new_order=new_order_mock) + print_log.assert_called_once_with("[Order] Order Mock, tick 17, market value, cancelling and adjusting to New order Mock") + add_log.assert_called_once_with({ + 'type': 'order', + 'tick': 17, + 'update': 'market_adjust', + 'order': 'order', + 'compute_value': None, + 'new_order': 'new_order' + }) + + @mock.patch.object(portfolio.ReportStore, "print_log") + @mock.patch.object(portfolio.ReportStore, "add_log") + def test_log_move_balances(self, add_log, print_log): + needed = { + "BTC": portfolio.Amount("BTC", 10), + "USDT": 1 + } + moving = { + "BTC": portfolio.Amount("BTC", 3), + "USDT": -2 + } + portfolio.ReportStore.log_move_balances(needed, moving, True) + print_log.assert_not_called() + add_log.assert_called_once_with({ + 'type': 'move_balances', + 'debug': True, + 'needed': { + 'BTC': D('10'), + 'USDT': 1 + }, + 'moving': { + 'BTC': D('3'), + 'USDT': -2 + } + }) + + @mock.patch.object(portfolio.ReportStore, "print_log") + @mock.patch.object(portfolio.ReportStore, "add_log") + def test_log_http_request(self, add_log, print_log): + response = mock.Mock() + response.status_code = 200 + response.text = "Hey" + + portfolio.ReportStore.log_http_request("method", "url", "body", + "headers", response) + print_log.assert_not_called() + add_log.assert_called_once_with({ + 'type': 'http_request', + 'method': 'method', + 'url': 'url', + 'body': 'body', + 'headers': 'headers', + 'status': 200, + 'response': 'Hey' + }) + + @mock.patch.object(portfolio.ReportStore, "print_log") + @mock.patch.object(portfolio.ReportStore, "add_log") + def test_log_error(self, add_log, print_log): + with self.subTest(message=None, exception=None): + portfolio.ReportStore.log_error("action") + print_log.assert_called_once_with("[Error] action") + add_log.assert_called_once_with({ + 'type': 'error', + 'action': 'action', + 'exception_class': None, + 'exception_message': None, + 'message': None + }) + + print_log.reset_mock() + add_log.reset_mock() + with self.subTest(message="Hey", exception=None): + portfolio.ReportStore.log_error("action", message="Hey") + print_log.assert_has_calls([ + mock.call("[Error] action"), + mock.call("\tHey") + ]) + add_log.assert_called_once_with({ + 'type': 'error', + 'action': 'action', + 'exception_class': None, + 'exception_message': None, + 'message': "Hey" + }) + + print_log.reset_mock() + add_log.reset_mock() + with self.subTest(message=None, exception=Exception("bouh")): + portfolio.ReportStore.log_error("action", exception=Exception("bouh")) + print_log.assert_has_calls([ + mock.call("[Error] action"), + mock.call("\tException: bouh") + ]) + add_log.assert_called_once_with({ + 'type': 'error', + 'action': 'action', + 'exception_class': "Exception", + 'exception_message': "bouh", + 'message': None + }) + + print_log.reset_mock() + add_log.reset_mock() + with self.subTest(message="Hey", exception=Exception("bouh")): + portfolio.ReportStore.log_error("action", message="Hey", exception=Exception("bouh")) + print_log.assert_has_calls([ + mock.call("[Error] action"), + mock.call("\tException: bouh"), + mock.call("\tHey") + ]) + add_log.assert_called_once_with({ + 'type': 'error', + 'action': 'action', + 'exception_class': "Exception", + 'exception_message': "bouh", + 'message': "Hey" + }) + + @mock.patch.object(portfolio.ReportStore, "print_log") + @mock.patch.object(portfolio.ReportStore, "add_log") + def test_log_debug_action(self, add_log, print_log): + portfolio.ReportStore.log_debug_action("Hey") + + print_log.assert_called_once_with("[Debug] Hey") + add_log.assert_called_once_with({ + 'type': 'debug_action', + 'action': 'Hey' + }) + @unittest.skipUnless("acceptance" in limits, "Acceptance skipped") class AcceptanceTest(WebMockTestCase): @unittest.expectedFailure def test_success_sell_only_necessary(self): + # FIXME: catch stdout + portfolio.ReportStore.verbose_print = False fetch_balance = { "ETH": { "exchange_free": D("1.0"),