1. Jordan Lee Avatar Jordan Lee
  2. Untitled project
  3. NuBotTrading
  4. Issues

The stop btn sometimes doesn't stop strategy

Issue #581 resolved
Desrever Nu created an issue 
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)

  1. Desrever Nu reporter

    sometimes I also get a popup "

    can't interrupt : java.lang.NullPointerException

    and the session file is not deleted (see #569)

  3. Benjamin Cordes

    could you post the verbose log please? thanks. I'm wondering whether all tasks get shutdown.

  4. Desrever Nu 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
  6. Benjamin Cordes

    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.

  9. Log in to comment
Assignee
–
Type
bug
Priority
critical
Status
resolved
Milestone
0.3.0 - UI
Version
–
Votes
0
Watchers
1
Jira: the preferred issue tracker for Bitbucket. Join the team!