- changed status to resolved
The stop btn sometimes doesn't stop strategy
Issue #581
resolved
11:22:33.950 [qtp1060608637-27 - //localhost:4567/startstop] INFO - bot start/stop >> stop [com.nubits.nubot.webui.BotController:72]
11:22:33.951 [qtp1060608637-27 - //localhost:4567/startstop] INFO - try interrupt bot [com.nubits.nubot.webui.BotController:116]
11:22:33.951 [qtp1060608637-27 - //localhost:4567/startstop] INFO - Bot shutting down sequence started. [com.nubits.nubot.bot.NuBotBase:285]
11:22:33.952 [qtp1060608637-27 - //localhost:4567/startstop] INFO - after 0 hours 0 minutes 9 seconds uptime on bitcoincoid [nbt_btc] [com.nubits.nubot.bot.NuBotBase:294]
11:22:34.148 [qtp1060608637-21 - //localhost:4567/info] ERROR - ApiError [5 : Nonce must be greater than 1429694553710. You provided 1429694553568.] [c.n.n.s.OrderManager:251]
11:22:34.484 [qtp1060608637-27 - //localhost:4567/startstop] INFO - try to shutdown all tasks [com.nubits.nubot.bot.NuBotBase:302]
11:22:34.484 [qtp1060608637-27 - //localhost:4567/startstop] INFO - Stopping all BotTasks. [com.nubits.nubot.tasks.TaskManager:141]
11:22:34.485 [qtp1060608637-27 - //localhost:4567/startstop] INFO - BotTasks stopped. [com.nubits.nubot.tasks.TaskManager:155]
11:22:34.486 [qtp1060608637-27 - //localhost:4567/startstop] INFO - Clearing out active orders ... [com.nubits.nubot.bot.NuBotBase:313]
11:22:35.049 [qtp1060608637-27 - //localhost:4567/startstop] INFO - Order clear request successful [com.nubits.nubot.bot.NuBotBase:320]
11:22:35.049 [qtp1060608637-27 - //localhost:4567/startstop] INFO - Logs of this session saved in logs/session_1429694394197 [com.nubits.nubot.bot.NuBotBase:353]
11:22:35.049 [qtp1060608637-27 - //localhost:4567/startstop] INFO - ** end of the session ** [com.nubits.nubot.bot.NuBotBase:357]
11:22:35.142 [Strategy Crypto Task] INFO - initOrders SELL, price 0.00439769 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:252]
11:22:35.426 [Strategy Crypto Task] WARN - Submiting limit order : SELL side order : SELL 2.5 NBT @ 0.00439769 BTC [c.n.n.s.S.StrategySecondaryPegUtils:176]
11:22:35.426 [Strategy Crypto Task] INFO - executeSellsideOrder : nbtbtc 2.5 0.00439769 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:161]
11:22:35.857 [Strategy Crypto Task] INFO - Strategy - Order success: SELL Response = 45528 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:190]
11:22:35.867 [qtp1060608637-21 - //localhost:4567/info] ERROR - ApiError [5 : Nonce must be greater than 1429694555426. You provided 1429694555038.] [c.n.n.s.BalanceManager:27]
11:22:42.148 [Strategy Crypto Task] ERROR - Error while reading the balance the second time ApiError [5 : Nonce must be greater than 1429694561093. You provided 1429694560858.] [c.n.n.s.S.StrategySecondaryPegUtils:371]
11:22:42.148 [Strategy Crypto Task] INFO - initOrders BUY, price 0.00418317 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:252]
11:22:42.407 [Strategy Crypto Task] WARN - Submiting limit order : BUY side order : BUY 2.5 NBT @ 0.00418317 BTC [c.n.n.s.S.StrategySecondaryPegUtils:176]
11:22:42.407 [Strategy Crypto Task] INFO - executeBuysideOrder : nbtbtc 2.5 0.00418317 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:147]
11:22:42.433 [qtp1060608637-27 - //localhost:4567/info] ERROR - ApiError [5 : Nonce must be greater than 1429694562149. You provided 1429694561361.] [c.n.n.s.OrderManager:251]
11:22:42.878 [Strategy Crypto Task] INFO - Strategy - Order success: BUY Response = 45529 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:190]
11:22:48.145 [Strategy Crypto Task] WARN - Submiting limit order : BUY side order : BUY 1.8388 NBT @ 0.00418317 BTC [c.n.n.s.S.StrategySecondaryPegUtils:176]
11:22:48.145 [Strategy Crypto Task] INFO - executeBuysideOrder : nbtbtc 1.83877299 0.00418317 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:147]
11:22:48.616 [Strategy Crypto Task] INFO - Strategy - Order success: BUY Response = 45530 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:190]
11:22:48.616 [Strategy Crypto Task] INFO - Initial walls placed [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:137]
Comments (8)
-
reporter -
reporter - changed status to open
-
could you post the verbose log please? thanks. I'm wondering whether all tasks get shutdown.
-
reporter Here is with verbosity (different session, same problem)
here is the startup sequence
11:36:10.427 [main] INFO - defined session path logs/session_1429695370422 [com.nubits.nubot.launch.MainLaunch:61] 11:36:10.508 [main] INFO - Running NuBot with GUI [com.nubits.nubot.launch.MainLaunch:71] 11:36:10.510 [main] DEBUG - parsing options from config/myconfig/bitcoincoid.json [SessionLOG:44] 11:36:10.554 [main] WARN - You are using the "spread" != 0 , which is not reccomented by Nu developers for purposes different from testing. [c.n.n.o.ParseOptions:332] 11:36:10.554 [main] DEBUG - Skipping validation of configuration file. [com.nubits.nubot.options.ParseOptions:342] 11:36:10.617 [main] DEBUG - loading opt: {"nudIp":"127.0.0.1","submitLiquidity":false,"apiKey":"VGUYLP7B-LFILPWLD-S0N9Q0ED-TLZCTIWY-TVTAME71","distributeLiquidity":false,"nubitAddress":"bSBh4Kag9f1kSdsLhVRnnd1TuDwBMs4yPq","mainFeed":"btce","rpcUser":"desrever","mailRecipient":"desrever.nu@gmail.com","maxBuyVolume":5.0,"maxSellVolume":5.0,"nudPort":9091,"backupFeeds":["coinbase","blockchain"],"priceIncrement":0.5,"rpcPass":"c140c140","apiSecret":"1c81ae7b748905b50a4b50fc03603464a8c3091f576065586b11d78a5c64403ec89482fbbfaef289","hipchat":true,"pair":"nbt_btc","mailnotifications":"SEVERE","verbose":true,"spread":5.0,"emergencyTimeout":60,"multipleCustodians":false,"txFee":0.2,"exchangeName":"bitcoincoid","executeOrders":true,"wallchangeThreshold":0.1,"dualSide":true,"keepProceeds":0.0} [com.nubits.nubot.launch.MainLaunch:49] 11:36:10.619 [main] INFO - start ui server. configfile config/myconfig/bitcoincoid.json [com.nubits.nubot.webui.UiServer:31] 11:36:10.620 [main] INFO - launching on http://localhost:4567 [com.nubits.nubot.webui.UiServer:36] 11:36:10.704 [main] DEBUG - creates RouteMatcher [spark.route.RouteMatcherFactory:38] 11:36:10.708 [main] DEBUG - Adds route: post, /configreset, spark.SparkBase$1@525f1e4e [spark.route.SimpleRouteMatcher:135] 11:36:10.710 [main] DEBUG - Adds route: get, /configfile, spark.SparkBase$1@52aa2946 [spark.route.SimpleRouteMatcher:135] 11:36:10.712 [main] DEBUG - Adds route: get, /config, spark.SparkBase$1@67e2d983 [spark.route.SimpleRouteMatcher:135] 11:36:10.714 [main] DEBUG - Adds route: post, /config, spark.SparkBase$1@3bbc39f8 [spark.route.SimpleRouteMatcher:135] 11:36:10.718 [main] DEBUG - Adds route: get, /logdump, spark.SparkBase$1@568bf312 [spark.route.SimpleRouteMatcher:135] 11:36:10.720 [main] DEBUG - Adds route: get, /info, spark.SparkBase$1@589b3632 [spark.route.SimpleRouteMatcher:135] 11:36:10.734 [main] DEBUG - Adds route: get, /, spark.TemplateViewRouteImpl$1@40e6dfe1 [spark.route.SimpleRouteMatcher:135] 11:36:10.748 [main] DEBUG - Adds route: get, /configui, spark.TemplateViewRouteImpl$1@123f1134 [spark.route.SimpleRouteMatcher:135] 11:36:10.750 [main] DEBUG - Adds route: get, /docu, spark.TemplateViewRouteImpl$1@5b0abc94 [spark.route.SimpleRouteMatcher:135] 11:36:10.753 [main] DEBUG - Adds route: get, /disclaimer, spark.TemplateViewRouteImpl$1@25b485ba [spark.route.SimpleRouteMatcher:135] 11:36:10.755 [main] DEBUG - Adds route: get, /opstatus, spark.SparkBase$1@214b199c [spark.route.SimpleRouteMatcher:135] 11:36:10.757 [main] DEBUG - Adds route: post, /startstop, spark.SparkBase$1@2893de87 [spark.route.SimpleRouteMatcher:135] 11:36:10.757 [main] DEBUG - Opening the system default browser : [com.nubits.nubot.webui.UiServer:64] 11:36:10.978 [Thread-0] INFO - == Spark has ignited ... [spark.webserver.SparkServer:120] 11:36:10.979 [Thread-0] INFO - >> Listening on 0.0.0.0:4567 [spark.webserver.SparkServer:121] 11:36:16.417 [qtp911507603-21 - //localhost:4567/startstop] INFO - bot start/stop >> start [com.nubits.nubot.webui.BotController:72] 11:36:16.419 [qtp911507603-21 - //localhost:4567/startstop] INFO - testing if global options are valid [com.nubits.nubot.webui.BotController:79] 11:36:16.432 [qtp911507603-21 - //localhost:4567/startstop] INFO - trying to start bot [com.nubits.nubot.webui.BotController:90] 11:36:16.433 [qtp911507603-21 - //localhost:4567/startstop] INFO - adding shutdown hook [SessionLOG:86] 11:36:16.495 [qtp911507603-21 - //localhost:4567/startstop] INFO - *** session *** starting at 20150422 11:36:16 [com.nubits.nubot.launch.MainLaunch:92] 11:36:16.497 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - execute bot depending on defined strategy [com.nubits.nubot.launch.MainLaunch:136] 11:36:16.497 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - creating secondary bot object [com.nubits.nubot.launch.MainLaunch:140] 11:36:16.511 [qtp911507603-21 - //localhost:4567/startstop] INFO - Setting up NuBot version : develop:branch-develop [com.nubits.nubot.bot.NuBotBase:200] 11:36:16.512 [qtp911507603-21 - //localhost:4567/startstop] WARN - Trades will be executed [c.n.n.b.NuBotBase:205] 11:36:16.513 [qtp911507603-21 - //localhost:4567/startstop] INFO - Session ID = develop:branch-develop|1429695376513|bbb1db [com.nubits.nubot.bot.NuBotBase:73] 11:36:16.514 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logback used 'file:/Users/advanced/Desktop/nubot/config/logging/logback.xml' as the configuration file. [com.nubits.nubot.bot.NuBotBase:97] 11:36:16.516 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[ROOT] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.516 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[SessionLOG] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.516 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.517 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.517 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.517 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.bot] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.517 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.bot.Global] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.518 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.bot.NuBotBase] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.518 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.exchanges] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.518 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.exchanges.Exchange] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.518 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.exchanges.ExchangeFacade] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.518 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.launch] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.519 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.launch.CLIOptions] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.519 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.launch.MainLaunch] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.519 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.models] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.519 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.models.ApiError] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.520 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.models.Currency] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.520 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.options] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.520 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.options.NuBotOptions] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.520 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.options.ParseOptions] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.520 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.pricefeeds] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.521 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.pricefeeds.feedservices] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.521 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.pricefeeds.feedservices.BitcoinaveragePriceFeed] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.521 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.pricefeeds.feedservices.BitfinexPriceFeed] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.521 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.pricefeeds.feedservices.BitstampEURPriceFeed] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.521 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.pricefeeds.feedservices.BitstampPriceFeed] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.522 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.pricefeeds.feedservices.BlockchainPriceFeed] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.522 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.pricefeeds.feedservices.BtcePriceFeed] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.522 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.pricefeeds.feedservices.BterPriceFeed] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.523 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.pricefeeds.feedservices.CcedkPriceFeed] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.523 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.pricefeeds.feedservices.CoinbasePriceFeed] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.523 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.pricefeeds.feedservices.ExchangeratelabPriceFeed] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.523 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.pricefeeds.feedservices.GoogleUnofficialPriceFeed] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.523 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.pricefeeds.feedservices.OpenexchangeratesPriceFeed] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.524 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.pricefeeds.feedservices.YahooPriceFeed] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.524 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.strategy] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.524 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.strategy.Secondary] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.524 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.strategy.Secondary.NuBotSecondary] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.524 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.trading] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.525 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.trading.keys] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.525 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.trading.keys.ApiKeys] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.525 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.trading.wrappers] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.525 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.trading.wrappers.BterWrapper] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.525 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.utils] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.526 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.utils.CSVtools] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.526 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.utils.FilesystemUtils] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.526 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.utils.Utils] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.526 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.utils.VersionInfo] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.526 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.webui] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.527 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.webui.BotController] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.527 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.webui.ConfigController] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.527 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.webui.LogController] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.527 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[com.nubits.nubot.webui.UiServer] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.527 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.528 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.528 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.528 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.http] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.528 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.http.HttpFields] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.528 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.http.HttpGenerator] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.529 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.http.HttpParser] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.529 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.http.MimeTypes] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.529 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.http.PreEncodedHttpField] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.529 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.io] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.529 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.io.AbstractConnection] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.530 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.io.AbstractEndPoint] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.530 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.io.ChannelEndPoint] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.530 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.io.FillInterest] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.530 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.io.IdleTimeout] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.531 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.io.SelectChannelEndPoint] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.531 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.io.SelectorManager] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.531 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.io.WriteFlusher] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.531 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.server] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.531 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.server.CookieCutter] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.532 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.server.HttpChannel] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.532 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.server.HttpChannelOverHttp] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.532 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.server.HttpChannelState] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.532 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.server.HttpConnection] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.532 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.server.HttpInput] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.533 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.server.HttpInputOverHTTP] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.533 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.server.HttpOutput] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.533 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.server.Request] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.533 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.server.Response] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.534 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.server.Server] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.534 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.server.ServerConnector] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.534 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.server.handler] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.535 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.server.handler.AbstractHandler] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.535 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.server.handler.ContextHandler] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.535 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.server.handler.ErrorHandler] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.535 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.server.handler.ResourceHandler] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.536 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.server.session] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.536 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.server.session.AbstractSessionIdManager] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.536 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.util] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.536 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.util.SharedBlockingCallback] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.537 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.util.StringUtil] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.537 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.util.TypeUtil] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.537 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.util.URIUtil] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.538 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.util.Utf8Appendable] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.538 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.util.component] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.538 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.util.component.AbstractLifeCycle] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.539 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.util.component.ContainerLifeCycle] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.539 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.util.log] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.539 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.util.resource] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.539 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.util.resource.PathResource] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.540 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.util.resource.Resource] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.540 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.util.thread] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.540 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[org.eclipse.jetty.util.thread.QueuedThreadPool] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.541 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[spark] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.541 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[spark.Request] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.541 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[spark.Response] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.542 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[spark.Spark] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.542 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[spark.route] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.542 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[spark.route.RouteMatcherFactory] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.542 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[spark.route.SimpleRouteMatcher] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.543 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[spark.webserver] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.543 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[spark.webserver.JettyHandler] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.543 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[spark.webserver.MatcherFilter] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.544 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Logger[spark.webserver.SparkServer] [com.nubits.nubot.bot.NuBotBase:107] 11:36:16.544 [qtp911507603-21 - //localhost:4567/startstop] INFO - Set up SSL certificates [com.nubits.nubot.bot.NuBotBase:112] 11:36:16.545 [qtp911507603-21 - //localhost:4567/startstop] INFO - installKeystore. trustall: false [com.nubits.nubot.utils.Utils:391] 11:36:16.546 [qtp911507603-21 - //localhost:4567/startstop] INFO - Reading keystorefile from : /Users/advanced/Desktop/nubot/res/ssl/nubot_keystore.jks [com.nubits.nubot.utils.Utils:406] 11:36:16.547 [qtp911507603-21 - //localhost:4567/startstop] INFO - setup Exchange object [com.nubits.nubot.bot.NuBotBase:123] 11:36:16.547 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Wrap the keys into a new ApiKeys object [com.nubits.nubot.bot.NuBotBase:125] 11:36:16.547 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Create e ExchangeLiveData object to accommodate liveData from the exchange [com.nubits.nubot.bot.NuBotBase:130] 11:36:16.548 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - get exchange interface for bitcoincoid [com.nubits.nubot.exchanges.ExchangeFacade:140] 11:36:16.550 [qtp911507603-21 - //localhost:4567/startstop] INFO - Swapped pair mode : false [com.nubits.nubot.bot.NuBotBase:159] 11:36:16.552 [qtp911507603-21 - //localhost:4567/startstop] WARN - The bot detected a 0 TX fee : forcing a priceOffset of 0.1% [if required] [c.n.n.b.NuBotBase:177] 11:36:16.552 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Create a TaskManager [com.nubits.nubot.bot.NuBotBase:215] 11:36:16.557 [qtp911507603-21 - //localhost:4567/startstop] INFO - setting up tasks [com.nubits.nubot.tasks.TaskManager:91] 11:36:16.559 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - checkConnectionTask : com.nubits.nubot.tasks.BotTask@619e1af [com.nubits.nubot.tasks.TaskManager:96] 11:36:16.560 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - sendLiquidityTask : com.nubits.nubot.tasks.BotTask@1acc85b3 [com.nubits.nubot.tasks.TaskManager:101] 11:36:16.562 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - strategyFiatTask : com.nubits.nubot.tasks.BotTask@4691cdcd [com.nubits.nubot.tasks.TaskManager:107] 11:36:16.564 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - secondaryPegTask : com.nubits.nubot.tasks.BotTask@70c4229f [com.nubits.nubot.tasks.TaskManager:112] 11:36:16.567 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - priceTriggerTask : com.nubits.nubot.tasks.BotTask@3999a1b1 [com.nubits.nubot.tasks.TaskManager:127] 11:36:16.568 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - Starting task : Check connection with exchange [com.nubits.nubot.bot.NuBotBase:224] 11:36:16.568 [qtp911507603-21 - //localhost:4567/startstop] INFO - BotTask [checkConnection] will start in 1s, and run it every 127s [com.nubits.nubot.tasks.BotTask:63] 11:36:16.569 [qtp911507603-21 - //localhost:4567/startstop] INFO - Waiting a for the connectionThreads to detect connection [com.nubits.nubot.bot.NuBotBase:228] 11:36:17.570 [checkConnection] DEBUG - Executing class com.nubits.nubot.tasks.CheckConnectionTask [com.nubits.nubot.tasks.CheckConnectionTask:39] 11:36:17.992 [checkConnection] DEBUG - Checking connection to https://vip.bitcoin.co.id/tapi - Connected : true [com.nubits.nubot.tasks.CheckConnectionTask:43] 11:36:20.233 [qtp911507603-21 - //localhost:4567/startstop] INFO - BotTask [sendLiquidity] will start in 40s, and run it every 130s [com.nubits.nubot.tasks.BotTask:63] 11:36:20.234 [qtp911507603-21 - //localhost:4567/startstop] INFO - Start trading Strategy specific for nbtbtc [com.nubits.nubot.bot.NuBotBase:257] 11:36:20.237 [qtp911507603-21 - //localhost:4567/startstop] INFO - Options loaded : {"nudIp":"127.0.0.1","submitLiquidity":false,"apiKey":"VGUYLP7B-LFILPWLD-S0N9Q0ED-TLZCTIWY-TVTAME71","distributeLiquidity":false,"nubitAddress":"bSBh4Kag9f1kSdsLhVRnnd1TuDwBMs4yPq","mainFeed":"btce","rpcUser":"desrever","mailRecipient":"desrever.nu@gmail.com","maxBuyVolume":5.0,"maxSellVolume":5.0,"nudPort":9091,"backupFeeds":["coinbase","blockchain"],"priceIncrement":0.5,"rpcPass":"c140c140","apiSecret":"1c81ae7b748905b50a4b50fc03603464a8c3091f576065586b11d78a5c64403ec89482fbbfaef289","hipchat":true,"pair":"nbt_btc","mailnotifications":"SEVERE","verbose":true,"spread":5.0,"emergencyTimeout":60,"multipleCustodians":false,"txFee":0.2,"exchangeName":"bitcoincoid","executeOrders":true,"wallchangeThreshold":0.1,"dualSide":true,"keepProceeds":0.0} [com.nubits.nubot.bot.NuBotBase:259] 11:36:20.244 [qtp911507603-21 - //localhost:4567/startstop] INFO - Configuring NuBot for Dual-Side strategy [com.nubits.nubot.strategy.Secondary.NuBotSecondary:51] 11:36:20.246 [qtp911507603-21 - //localhost:4567/startstop] INFO - multiple custodians: false [com.nubits.nubot.strategy.Secondary.NuBotSecondary:127] 11:36:20.246 [qtp911507603-21 - //localhost:4567/startstop] INFO - NuBot will not try to sync with other bots via remote NTP : 'multiple-custodians' is set to false [com.nubits.nubot.strategy.Secondary.NuBotSecondary:134] 11:36:20.246 [qtp911507603-21 - //localhost:4567/startstop] INFO - BotTask [priceTriggerTask] will start in 0s, and run it every 61s [com.nubits.nubot.tasks.BotTask:63] 11:36:20.247 [priceTriggerTask] DEBUG - Executing class com.nubits.nubot.tasks.PriceMonitorTriggerTask [com.nubits.nubot.tasks.PriceMonitorTriggerTask:118] 11:36:20.247 [qtp911507603-21 - //localhost:4567/startstop] DEBUG - notify online A new <strong>dual-side</strong> bot just came online on bitcoincoid pair (nbt_btc) [com.nubits.nubot.bot.NuBotBase:279] 11:36:20.247 [priceTriggerTask] INFO - running PriceMonitorTrigger for first time [com.nubits.nubot.tasks.PriceMonitorTriggerTask:121] 11:36:20.249 [priceTriggerTask] DEBUG - Executing task : PriceMonitorTriggerTask [com.nubits.nubot.tasks.PriceMonitorTriggerTask:138] 11:36:20.249 [priceTriggerTask] DEBUG - fetch last prices [com.nubits.nubot.pricefeeds.PriceFeedManager:62] 11:36:21.106 [priceTriggerTask] DEBUG - Obtained price : 233.146 from BtcePriceFeed [com.nubits.nubot.pricefeeds.PriceFeedManager:73] 11:36:21.266 [qtp911507603-21 - //localhost:4567/startstop] INFO - start bot success? true [com.nubits.nubot.webui.BotController:103] 11:36:21.747 [priceTriggerTask] DEBUG - Obtained price : 236.18 from CoinbasePriceFeed [com.nubits.nubot.pricefeeds.PriceFeedManager:73] 11:36:21.899 [qtp911507603-29 - //localhost:4567/info] DEBUG - activeorders BUY 0 [com.nubits.nubot.strategy.OrderManager:267] 11:36:21.900 [qtp911507603-29 - //localhost:4567/info] DEBUG - buy orders: 0 [com.nubits.nubot.strategy.OrderManager:283] 11:36:21.923 [priceTriggerTask] DEBUG - Obtained price : 235.19 from BlockchainPriceFeed [com.nubits.nubot.pricefeeds.PriceFeedManager:73] 11:36:21.924 [priceTriggerTask] DEBUG - CheckLastPrice received values from remote feeds. [com.nubits.nubot.tasks.PriceMonitorTriggerTask:242] 11:36:21.924 [priceTriggerTask] INFO - Price Updated. btce:1 BTC = 233.146 USD [com.nubits.nubot.tasks.PriceMonitorTriggerTask:439] 11:36:21.925 [priceTriggerTask] DEBUG - halfspread 2.5 [com.nubits.nubot.tasks.PriceMonitorTriggerTask:180] 11:36:21.925 [priceTriggerTask] DEBUG - offset 0.025 [com.nubits.nubot.tasks.PriceMonitorTriggerTask:181] 11:36:21.925 [priceTriggerTask] INFO - Computing USD prices with spread 5.0% : sell @ 1.025 buy @ 0.975 [com.nubits.nubot.tasks.PriceMonitorTriggerTask:190] 11:36:21.926 [priceTriggerTask] INFO - Converted price (using 1 BTC = 233.146 USD) : sell @ 0.00439639 BTC; buy @ 0.00418193 BTC [com.nubits.nubot.tasks.PriceMonitorTriggerTask:213] 11:36:21.927 [priceTriggerTask] INFO - setBuyPricePEG : 0.00418193 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegTask:199] 11:36:21.927 [priceTriggerTask] INFO - set setSellPricePEG : 0.00439639 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegTask:190] 11:36:21.927 [priceTriggerTask] INFO - Started BotTask Strategy Crypto Task [com.nubits.nubot.tasks.BotTask:57] 11:36:21.927 [Strategy Crypto Task] DEBUG - Executing task on bitcoincoid: StrategySecondaryPegTask. DualSide : true [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegTask:60] 11:36:21.927 [Strategy Crypto Task] INFO - Initializing strategy [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegTask:119] 11:36:21.927 [Strategy Crypto Task] INFO - setting up ordermanager [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegTask:120] 11:36:22.462 [qtp911507603-29 - //localhost:4567/info] ERROR - ApiError [5 : Nonce must be greater than 1429695381928. You provided 1429695381900.] [c.n.n.s.OrderManager:251] 11:36:22.463 [qtp911507603-29 - //localhost:4567/info] DEBUG - activeorders SELL 0 [com.nubits.nubot.strategy.OrderManager:267] 11:36:22.463 [qtp911507603-29 - //localhost:4567/info] DEBUG - sell orders: 0 [com.nubits.nubot.strategy.OrderManager:284] 11:36:23.027 [Strategy Crypto Task] DEBUG - activeorders SELL 0 [com.nubits.nubot.strategy.OrderManager:267] 11:36:23.606 [Strategy Crypto Task] DEBUG - activeorders BUY 0 [com.nubits.nubot.strategy.OrderManager:267] 11:36:23.607 [Strategy Crypto Task] DEBUG - reInitiateOrders . firstTime=true [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:45] 11:36:23.814 [qtp911507603-29 - //localhost:4567/info] ERROR - ApiError [5 : Nonce must be greater than 1429695383028. You provided 1429695382463.] [c.n.n.s.OrderManager:251] 11:36:23.814 [qtp911507603-29 - //localhost:4567/info] DEBUG - activeorders BUY 0 [com.nubits.nubot.strategy.OrderManager:267] 11:36:24.321 [Strategy Crypto Task] DEBUG - Frozen funds already in balance (not proceeds) updated : 0.0 Currency{fiat=false, code=BTC, extendedName=Bitcoin} [com.nubits.nubot.utils.FrozenBalancesManager:173] 11:36:24.322 [Strategy Crypto Task] DEBUG - init sell orders. price: 0.00439639 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:126] 11:36:24.322 [Strategy Crypto Task] INFO - initOrders SELL, price 0.00439639 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:252] 11:36:24.477 [qtp911507603-29 - //localhost:4567/info] DEBUG - activeorders SELL 0 [com.nubits.nubot.strategy.OrderManager:267] 11:36:24.478 [qtp911507603-29 - //localhost:4567/info] DEBUG - buys: 0 [com.nubits.nubot.webui.LogController:68] 11:36:24.478 [qtp911507603-29 - //localhost:4567/info] DEBUG - sells: 0 [com.nubits.nubot.webui.LogController:69] 11:36:24.593 [Strategy Crypto Task] DEBUG - balance 9.53076606 NBT maxBuy 5.0. maxSell 5.0 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:288] 11:36:24.594 [Strategy Crypto Task] DEBUG - amount1: 4.76538303 . balance 9.53076606 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:295] 11:36:24.594 [Strategy Crypto Task] WARN - Submiting limit order : SELL side order : SELL 2.5 NBT @ 0.00439639 BTC [c.n.n.s.S.StrategySecondaryPegUtils:176] 11:36:24.595 [Strategy Crypto Task] INFO - executeSellsideOrder : nbtbtc 2.5 0.00439639 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:161] 11:36:25.005 [qtp911507603-29 - //localhost:4567/info] ERROR - ApiError [5 : Nonce must be greater than 1429695384595. You provided 1429695384478.] [c.n.n.s.BalanceManager:27] 11:36:25.134 [Strategy Crypto Task] INFO - Strategy - Order success: SELL Response = 45548 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:190]
and here is when I pressed STOP
11:36:25.389 [qtp911507603-28 - //localhost:4567/startstop] INFO - bot start/stop >> stop [com.nubits.nubot.webui.BotController:72] 11:36:25.390 [qtp911507603-28 - //localhost:4567/startstop] INFO - try interrupt bot [com.nubits.nubot.webui.BotController:116] 11:36:25.390 [qtp911507603-28 - //localhost:4567/startstop] INFO - Bot shutting down sequence started. [com.nubits.nubot.bot.NuBotBase:285] 11:36:25.391 [qtp911507603-28 - //localhost:4567/startstop] INFO - after 0 hours 0 minutes 8 seconds uptime on bitcoincoid [nbt_btc] [com.nubits.nubot.bot.NuBotBase:294] 11:36:25.916 [qtp911507603-28 - //localhost:4567/startstop] INFO - try to shutdown all tasks [com.nubits.nubot.bot.NuBotBase:302] 11:36:25.917 [qtp911507603-28 - //localhost:4567/startstop] INFO - Stopping all BotTasks. [com.nubits.nubot.tasks.TaskManager:141] 11:36:25.917 [qtp911507603-28 - //localhost:4567/startstop] DEBUG - Shutting down checkConnection [com.nubits.nubot.tasks.TaskManager:147] 11:36:25.917 [qtp911507603-28 - //localhost:4567/startstop] DEBUG - Shutting down sendLiquidity [com.nubits.nubot.tasks.TaskManager:147] 11:36:25.917 [qtp911507603-28 - //localhost:4567/startstop] DEBUG - Shutting down Strategy Fiat Task [com.nubits.nubot.tasks.TaskManager:147] 11:36:25.918 [qtp911507603-28 - //localhost:4567/startstop] DEBUG - Shutting down Strategy Crypto Task [com.nubits.nubot.tasks.TaskManager:147] 11:36:25.918 [qtp911507603-28 - //localhost:4567/startstop] DEBUG - Shutting down priceTriggerTask [com.nubits.nubot.tasks.TaskManager:147] 11:36:25.918 [qtp911507603-28 - //localhost:4567/startstop] INFO - BotTasks stopped. [com.nubits.nubot.tasks.TaskManager:155] 11:36:25.919 [qtp911507603-28 - //localhost:4567/startstop] INFO - Clearing out active orders ... [com.nubits.nubot.bot.NuBotBase:313] 11:36:27.926 [qtp911507603-22 - //localhost:4567/info] ERROR - ApiError [5 : Nonce must be greater than 1429695386426. You provided 1429695386142.] [c.n.n.s.OrderManager:251] 11:36:27.926 [qtp911507603-22 - //localhost:4567/info] DEBUG - activeorders BUY 0 [com.nubits.nubot.strategy.OrderManager:267] 11:36:27.926 [qtp911507603-22 - //localhost:4567/info] DEBUG - buy orders: 0 [com.nubits.nubot.strategy.OrderManager:283] 11:36:27.995 [qtp911507603-28 - //localhost:4567/startstop] INFO - Order clear request successful [com.nubits.nubot.bot.NuBotBase:320] 11:36:27.995 [qtp911507603-28 - //localhost:4567/startstop] INFO - Logs of this session saved in logs/session_1429695370422 [com.nubits.nubot.bot.NuBotBase:353] 11:36:27.996 [qtp911507603-28 - //localhost:4567/startstop] INFO - ** end of the session ** [com.nubits.nubot.bot.NuBotBase:357] 11:36:28.188 [qtp911507603-22 - //localhost:4567/info] DEBUG - activeorders SELL 0 [com.nubits.nubot.strategy.OrderManager:267] 11:36:28.188 [qtp911507603-22 - //localhost:4567/info] DEBUG - sell orders: 0 [com.nubits.nubot.strategy.OrderManager:284] 11:36:28.625 [qtp911507603-22 - //localhost:4567/info] DEBUG - activeorders BUY 0 [com.nubits.nubot.strategy.OrderManager:267] 11:36:28.896 [qtp911507603-22 - //localhost:4567/info] DEBUG - activeorders SELL 0 [com.nubits.nubot.strategy.OrderManager:267] 11:36:28.896 [qtp911507603-22 - //localhost:4567/info] DEBUG - buys: 0 [com.nubits.nubot.webui.LogController:68] 11:36:28.896 [qtp911507603-22 - //localhost:4567/info] DEBUG - sells: 0 [com.nubits.nubot.webui.LogController:69] 11:36:30.101 [qtp911507603-23 - //localhost:4567/info] DEBUG - activeorders BUY 0 [com.nubits.nubot.strategy.OrderManager:267] 11:36:30.101 [qtp911507603-23 - //localhost:4567/info] DEBUG - buy orders: 0 [com.nubits.nubot.strategy.OrderManager:283] 11:36:30.408 [Strategy Crypto Task] WARN - Submiting limit order : SELL side order : SELL 2.5 NBT @ 0.00439639 BTC [c.n.n.s.S.StrategySecondaryPegUtils:176] 11:36:30.408 [Strategy Crypto Task] INFO - executeSellsideOrder : nbtbtc 2.5 0.00439639 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:161] 11:36:30.685 [qtp911507603-23 - //localhost:4567/info] ERROR - ApiError [5 : Nonce must be greater than 1429695390135. You provided 1429695390101.] [c.n.n.s.OrderManager:251] 11:36:30.686 [qtp911507603-23 - //localhost:4567/info] DEBUG - activeorders SELL 0 [com.nubits.nubot.strategy.OrderManager:267] 11:36:30.686 [qtp911507603-23 - //localhost:4567/info] DEBUG - sell orders: 0 [com.nubits.nubot.strategy.OrderManager:284] 11:36:30.950 [qtp911507603-23 - //localhost:4567/info] DEBUG - activeorders BUY 0 [com.nubits.nubot.strategy.OrderManager:267] 11:36:31.146 [Strategy Crypto Task] INFO - Strategy - Order success: SELL Response = 45549 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:190] 11:36:31.146 [Strategy Crypto Task] DEBUG - init buy orders. price: 0.00418193 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:131] 11:36:31.146 [Strategy Crypto Task] INFO - initOrders BUY, price 0.00418193 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:252] 11:36:31.223 [qtp911507603-23 - //localhost:4567/info] DEBUG - activeorders SELL 1 [com.nubits.nubot.strategy.OrderManager:267] 11:36:31.223 [qtp911507603-23 - //localhost:4567/info] DEBUG - buys: 0 [com.nubits.nubot.webui.LogController:68] 11:36:31.223 [qtp911507603-23 - //localhost:4567/info] DEBUG - sells: 1 [com.nubits.nubot.webui.LogController:69] 11:36:31.223 [qtp911507603-23 - //localhost:4567/info] DEBUG - order: Order{id=45549, insertedDate=Wed Apr 22 11:36:32 CEST 2015, executedDate=null, type=SELL, pair=nbtbtc, amount=2.5 NBT , price=0.004396 BTC , amountPlusFee=null, completed=true} [com.nubits.nubot.webui.LogController:74] 11:36:31.413 [Strategy Crypto Task] DEBUG - balance 0.02201121 BTC maxBuy 5.0. maxSell 5.0 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:288] 11:36:31.414 [Strategy Crypto Task] DEBUG - amount1: 0.0110056 . balance 0.02201121 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:295] 11:36:31.414 [Strategy Crypto Task] DEBUG - buy: => amount 2.63170354 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:307] 11:36:31.415 [Strategy Crypto Task] WARN - Submiting limit order : BUY side order : BUY 2.5 NBT @ 0.00418193 BTC [c.n.n.s.S.StrategySecondaryPegUtils:176] 11:36:31.416 [Strategy Crypto Task] INFO - executeBuysideOrder : nbtbtc 2.5 0.00418193 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:147] 11:36:31.874 [Strategy Crypto Task] INFO - Strategy - Order success: BUY Response = 45550 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:190] 11:36:32.034 [qtp911507603-72 - //localhost:4567/info] DEBUG - activeorders BUY 1 [com.nubits.nubot.strategy.OrderManager:267] 11:36:32.034 [qtp911507603-72 - //localhost:4567/info] DEBUG - buy orders: 1 [com.nubits.nubot.strategy.OrderManager:283] 11:36:32.307 [qtp911507603-72 - //localhost:4567/info] DEBUG - activeorders SELL 1 [com.nubits.nubot.strategy.OrderManager:267] 11:36:32.307 [qtp911507603-72 - //localhost:4567/info] DEBUG - sell orders: 1 [com.nubits.nubot.strategy.OrderManager:284] 11:36:33.616 [qtp911507603-72 - //localhost:4567/info] DEBUG - activeorders BUY 1 [com.nubits.nubot.strategy.OrderManager:267] 11:36:33.890 [qtp911507603-72 - //localhost:4567/info] DEBUG - activeorders SELL 1 [com.nubits.nubot.strategy.OrderManager:267] 11:36:33.891 [qtp911507603-72 - //localhost:4567/info] DEBUG - buys: 1 [com.nubits.nubot.webui.LogController:68] 11:36:33.891 [qtp911507603-72 - //localhost:4567/info] DEBUG - sells: 1 [com.nubits.nubot.webui.LogController:69] 11:36:33.891 [qtp911507603-72 - //localhost:4567/info] DEBUG - order: Order{id=45549, insertedDate=Wed Apr 22 11:36:32 CEST 2015, executedDate=null, type=SELL, pair=nbtbtc, amount=2.5 NBT , price=0.004396 BTC , amountPlusFee=null, completed=true} [com.nubits.nubot.webui.LogController:74] 11:36:33.891 [qtp911507603-72 - //localhost:4567/info] DEBUG - order: Order{id=45550, insertedDate=Wed Apr 22 11:36:32 CEST 2015, executedDate=null, type=BUY, pair=nbtbtc, amount=2.49995935 NBT , price=0.004182 BTC , amountPlusFee=null, completed=false} [com.nubits.nubot.webui.LogController:74] 11:36:34.696 [qtp911507603-29 - //localhost:4567/info] DEBUG - activeorders BUY 1 [com.nubits.nubot.strategy.OrderManager:267] 11:36:34.697 [qtp911507603-29 - //localhost:4567/info] DEBUG - buy orders: 1 [com.nubits.nubot.strategy.OrderManager:283] 11:36:34.978 [qtp911507603-29 - //localhost:4567/info] DEBUG - activeorders SELL 1 [com.nubits.nubot.strategy.OrderManager:267] 11:36:34.978 [qtp911507603-29 - //localhost:4567/info] DEBUG - sell orders: 1 [com.nubits.nubot.strategy.OrderManager:284] 11:36:36.614 [qtp911507603-29 - //localhost:4567/info] DEBUG - activeorders BUY 1 [com.nubits.nubot.strategy.OrderManager:267] 11:36:36.881 [qtp911507603-29 - //localhost:4567/info] DEBUG - activeorders SELL 1 [com.nubits.nubot.strategy.OrderManager:267] 11:36:36.882 [qtp911507603-29 - //localhost:4567/info] DEBUG - buys: 1 [com.nubits.nubot.webui.LogController:68] 11:36:36.882 [qtp911507603-29 - //localhost:4567/info] DEBUG - sells: 1 [com.nubits.nubot.webui.LogController:69] 11:36:36.882 [qtp911507603-29 - //localhost:4567/info] DEBUG - order: Order{id=45549, insertedDate=Wed Apr 22 11:36:32 CEST 2015, executedDate=null, type=SELL, pair=nbtbtc, amount=2.5 NBT , price=0.004396 BTC , amountPlusFee=null, completed=true} [com.nubits.nubot.webui.LogController:74] 11:36:36.882 [qtp911507603-29 - //localhost:4567/info] DEBUG - order: Order{id=45550, insertedDate=Wed Apr 22 11:36:32 CEST 2015, executedDate=null, type=BUY, pair=nbtbtc, amount=2.49995935 NBT , price=0.004182 BTC , amountPlusFee=null, completed=false} [com.nubits.nubot.webui.LogController:74] 11:36:37.131 [Strategy Crypto Task] WARN - Submiting limit order : BUY side order : BUY 1.8403 NBT @ 0.00418193 BTC [c.n.n.s.S.StrategySecondaryPegUtils:176] 11:36:37.131 [Strategy Crypto Task] INFO - executeBuysideOrder : nbtbtc 1.8403321 0.00418193 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:147] 11:36:37.581 [Strategy Crypto Task] INFO - Strategy - Order success: BUY Response = 45551 [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:190] 11:36:37.582 [Strategy Crypto Task] INFO - Initial walls placed [com.nubits.nubot.strategy.Secondary.StrategySecondaryPegUtils:137] 11:36:37.940 [qtp911507603-29 - //localhost:4567/info] ERROR - ApiError [5 : Nonce must be greater than 1429695397132. You provided 1429695396882.] [c.n.n.s.BalanceManager:27] 11:36:38.804 [qtp911507603-28 - //localhost:4567/info] DEBUG - activeorders BUY 2 [com.nubits.nubot.strategy.OrderManager:267] 11:36:38.804 [qtp911507603-28 - //localhost:4567/info] DEBUG - buy orders: 2 [com.nubits.nubot.strategy.OrderManager:283] 11:36:39.065 [qtp911507603-28 - //localhost:4567/info] DEBUG - activeorders SELL 1 [com.nubits.nubot.strategy.OrderManager:267] 11:36:39.065 [qtp911507603-28 - //localhost:4567/info] DEBUG - sell orders: 1 [com.nubits.nubot.strategy.OrderManager:284] 11:36:39.785 [qtp911507603-28 - //localhost:4567/info] DEBUG - activeorders BUY 2 [com.nubits.nubot.strategy.OrderManager:267] 11:36:40.077 [qtp911507603-28 - //localhost:4567/info] DEBUG - activeorders SELL 1 [com.nubits.nubot.strategy.OrderManager:267] 11:36:40.077 [qtp911507603-28 - //localhost:4567/info] DEBUG - buys: 2 [com.nubits.nubot.webui.LogController:68] 11:36:40.077 [qtp911507603-28 - //localhost:4567/info] DEBUG - sells: 1 [com.nubits.nubot.webui.LogController:69] 11:36:40.077 [qtp911507603-28 - //localhost:4567/info] DEBUG - order: Order{id=45549, insertedDate=Wed Apr 22 11:36:32 CEST 2015, executedDate=null, type=SELL, pair=nbtbtc, amount=2.5 NBT , price=0.004396 BTC , amountPlusFee=null, completed=true} [com.nubits.nubot.webui.LogController:74] 11:36:40.077 [qtp911507603-28 - //localhost:4567/info] DEBUG - order: Order{id=45550, insertedDate=Wed Apr 22 11:36:32 CEST 2015, executedDate=null, type=BUY, pair=nbtbtc, amount=2.49995935 NBT , price=0.004182 BTC , amountPlusFee=null, completed=false} [com.nubits.nubot.webui.LogController:74] 11:36:40.078 [qtp911507603-28 - //localhost:4567/info] DEBUG - order: Order{id=45551, insertedDate=Wed Apr 22 11:36:38 CEST 2015, executedDate=null, type=BUY, pair=nbtbtc, amount=1.84030129 NBT , price=0.004182 BTC , amountPlusFee=null, completed=false} [com.nubits.nubot.webui.LogController:74] 11:36:41.605 [qtp911507603-72 - //localhost:4567/info] DEBUG - activeorders BUY 2 [com.nubits.nubot.strategy.OrderManager:267] 11:36:41.605 [qtp911507603-72 - //localhost:4567/info] DEBUG - buy orders: 2 [com.nubits.nubot.strategy.OrderManager:283] 11:36:42.167 [qtp911507603-72 - //localhost:4567/info] DEBUG - activeorders SELL 1 [com.nubits.nubot.strategy.OrderManager:267] 11:36:42.168 [qtp911507603-72 - //localhost:4567/info] DEBUG - sell orders: 1 [com.nubits.nubot.strategy.OrderManager:284] 11:36:42.420 [qtp911507603-72 - //localhost:4567/info] DEBUG - activeorders BUY 2 [com.nubits.nubot.strategy.OrderManager:267] 11:36:42.698 [qtp911507603-72 - //localhost:4567/info] DEBUG - activeorders SELL 1 [com.nubits.nubot.strategy.OrderManager:267] 11:36:42.698 [qtp911507603-72 - //localhost:4567/info] DEBUG - buys: 2 [com.nubits.nubot.webui.LogController:68] 11:36:42.698 [qtp911507603-72 - //localhost:4567/info] DEBUG - sells: 1 [com.nubits.nubot.webui.LogController:69] 11:36:42.698 [qtp911507603-72 - //localhost:4567/info] DEBUG - order: Order{id=45549, insertedDate=Wed Apr 22 11:36:32 CEST 2015, executedDate=null, type=SELL, pair=nbtbtc, amount=2.5 NBT , price=0.004396 BTC , amountPlusFee=null, completed=true} [com.nubits.nubot.webui.LogController:74] 11:36:42.699 [qtp911507603-72 - //localhost:4567/info] DEBUG - order: Order{id=45550, insertedDate=Wed Apr 22 11:36:32 CEST 2015, executedDate=null, type=BUY, pair=nbtbtc, amount=2.49995935 NBT , price=0.004182 BTC , amountPlusFee=null, completed=false} [com.nubits.nubot.webui.LogController:74] 11:36:42.699 [qtp911507603-72 - //localhost:4567/info] DEBUG - order: Order{id=45551, insertedDate=Wed Apr 22 11:36:38 CEST 2015, executedDate=null, type=BUY, pair=nbtbtc, amount=1.84030129 NBT , price=0.004182 BTC , amountPlusFee=null, completed=false} [com.nubits.nubot.webui.LogController:74] 11:36:44.009 [Thread-14] INFO - Bot shutting down sequence started. [com.nubits.nubot.bot.NuBotBase:285] 11:36:44.010 [Thread-14] INFO - after 0 hours 0 minutes 27 seconds uptime on bitcoincoid [nbt_btc] [com.nubits.nubot.bot.NuBotBase:294] 11:36:44.184 [qtp911507603-23 - //localhost:4567/info] DEBUG - activeorders BUY 2 [com.nubits.nubot.strategy.OrderManager:267] 11:36:44.184 [qtp911507603-23 - //localhost:4567/info] DEBUG - buy orders: 2 [com.nubits.nubot.strategy.OrderManager:283] 11:36:44.448 [qtp911507603-23 - //localhost:4567/info] DEBUG - activeorders SELL 1 [com.nubits.nubot.strategy.OrderManager:267] 11:36:44.448 [qtp911507603-23 - //localhost:4567/info] DEBUG - sell orders: 1 [com.nubits.nubot.strategy.OrderManager:284] 11:36:44.705 [qtp911507603-23 - //localhost:4567/info] DEBUG - activeorders BUY 2 [com.nubits.nubot.strategy.OrderManager:267] 11:36:45.002 [qtp911507603-23 - //localhost:4567/info] DEBUG - activeorders SELL 1 [com.nubits.nubot.strategy.OrderManager:267] 11:36:45.003 [qtp911507603-23 - //localhost:4567/info] DEBUG - buys: 2 [com.nubits.nubot.webui.LogController:68] 11:36:45.003 [qtp911507603-23 - //localhost:4567/info] DEBUG - sells: 1 [com.nubits.nubot.webui.LogController:69] 11:36:45.003 [qtp911507603-23 - //localhost:4567/info] DEBUG - order: Order{id=45549, insertedDate=Wed Apr 22 11:36:32 CEST 2015, executedDate=null, type=SELL, pair=nbtbtc, amount=2.5 NBT , price=0.004396 BTC , amountPlusFee=null, completed=true} [com.nubits.nubot.webui.LogController:74] 11:36:45.003 [qtp911507603-23 - //localhost:4567/info] DEBUG - order: Order{id=45550, insertedDate=Wed Apr 22 11:36:32 CEST 2015, executedDate=null, type=BUY, pair=nbtbtc, amount=2.49995935 NBT , price=0.004182 BTC , amountPlusFee=null, completed=false} [com.nubits.nubot.webui.LogController:74] 11:36:45.004 [qtp911507603-23 - //localhost:4567/info] DEBUG - order: Order{id=45551, insertedDate=Wed Apr 22 11:36:38 CEST 2015, executedDate=null, type=BUY, pair=nbtbtc, amount=1.84030129 NBT , price=0.004182 BTC
-
reporter Issue
#573was marked as a duplicate of this issue. -
a solution could be: testing the flag if sessionRunning in strategy tasks. not very nice but should work. A better solution is to modify the shutdown procedure: see if all tasks are stopped and then finally shutdown.
-
reporter - changed status to resolved
Interruption disseminated
-
reporter - changed milestone to 0.3.0 - UI
- Log in to comment
sometimes I also get a popup "
and the session file is not deleted (see
#569)