timestored / pulseui

Real-time interactive database applications
https://www.timestored.com/pulse/
Other
54 stars 6 forks source link

Check all major actions are logged at info level #215

Open ryanhamilton opened 6 months ago

ryanhamilton commented 6 months ago

user-ro user-bar would like to easily grep to see any failures to save in the logs.

Major user actions that change state should be logged. e.g.

ryanhamilton commented 6 months ago

Converted most user actions to log 1-4 lines:

20:05:18.473 [default-nioEventLoopGroup-1-2] INFO  c.s.webby.ConferenceController - getPresubscribeKey u-admin
20:05:18.485 [default-nioEventLoopGroup-1-6] INFO  c.s.webby.WebSocketServer - (admin) Joined [d8b444cb-441f-406b-a303-37d1fa8eb68e]
20:05:18.485 [default-nioEventLoopGroup-1-6] INFO  c.s.webby.WebSocketServer - Starting DashboardEngine for user u-admin
20:05:18.493 [default-nioEventLoopGroup-1-2] INFO  c.s.webby.DashboardController - get d-93 0 u-admin
20:05:18.493 [default-nioEventLoopGroup-1-2] INFO  c.s.webby.DashboardController - get latest d-93 u-admin
20:05:23.703 [default-nioEventLoopGroup-1-2] INFO  c.s.webby.DashboardController - upsert d-93 u-admin
20:05:23.705 [default-nioEventLoopGroup-1-2] INFO  c.s.webby.DashboardController - upsertDB d-93 TAQ
20:05:52.710 [default-nioEventLoopGroup-1-6] INFO  c.s.webby.WebSocketServer - [d8b444cb-441f-406b-a303-37d1fa8eb68e] Disconnected.
20:05:52.710 [default-nioEventLoopGroup-1-6] INFO  c.sqldashboards.dashy.QueryEngine2 - shutDown
20:06:00.852 [default-nioEventLoopGroup-1-2] INFO  c.s.webby.DbServerController - test u-admin s-KDB:localhost:5000
20:06:03.798 [default-nioEventLoopGroup-1-2] INFO  c.s.webby.DbServerController - update u-admin s-KDB:localhost:5000
20:06:03.802 [default-nioEventLoopGroup-1-2] INFO  c.s.shared.ConnectionManager - updateServer(KDB:localhost:5000 -> ServerConfig [name=KDB:localhost:5000, username=, host=localhost, port=5000, database=, jdbcType=KDB])
20:06:03.802 [default-nioEventLoopGroup-1-2] INFO  c.s.shared.ConnectionManager - updated server: ServerConfig [name=KDB:localhost:5000, username=, host=localhost, port=5000, database=, jdbcType=KDB]
20:06:10.223 [default-nioEventLoopGroup-1-2] INFO  c.sqldashboards.webby.TeamController - setUserTeamRole u-admin setting 1 to ADMIN
20:06:14.066 [default-nioEventLoopGroup-1-2] INFO  c.s.webby.ConferenceController - getPresubscribeKey u-admin
20:06:14.068 [default-nioEventLoopGroup-1-3] INFO  c.s.webby.DashboardController - get d-3 0 u-admin
20:06:14.068 [default-nioEventLoopGroup-1-3] INFO  c.s.webby.DashboardController - get latest d-3 u-admin
20:06:14.080 [default-nioEventLoopGroup-1-8] INFO  c.s.webby.WebSocketServer - (admin) Joined [c105eb2a-373e-444b-8956-5701a32d8ed0]
20:06:14.080 [default-nioEventLoopGroup-1-8] INFO  c.s.webby.WebSocketServer - Starting DashboardEngine for user u-admin
20:06:18.185 [default-nioEventLoopGroup-1-3] INFO  c.s.webby.DashboardController - upsert d-3 u-admin
20:06:18.187 [default-nioEventLoopGroup-1-3] INFO  c.s.webby.DashboardController - upsertDB d-3 FX Dash
20:06:30.192 [default-nioEventLoopGroup-1-3] INFO  c.s.webby.ConferenceController - getPresubscribeKey u-admin
20:06:30.193 [default-nioEventLoopGroup-1-2] INFO  c.s.webby.DashboardController - get d-3 0 u-admin
20:06:30.194 [default-nioEventLoopGroup-1-2] INFO  c.s.webby.DashboardController - get latest d-3 u-admin
20:06:30.220 [default-nioEventLoopGroup-1-9] INFO  c.s.webby.WebSocketServer - (admin) Joined [40085293-09c9-42ce-be22-7376f4eef6ac]
20:06:30.220 [default-nioEventLoopGroup-1-9] INFO  c.s.webby.WebSocketServer - Starting DashboardEngine for user u-admin
20:06:33.261 [default-nioEventLoopGroup-1-3] INFO  c.s.webby.DashboardController - upsert d-3 u-admin
20:06:33.262 [default-nioEventLoopGroup-1-3] INFO  c.s.webby.DashboardController - upsertDB d-3 FX Dash
20:06:37.237 [default-nioEventLoopGroup-1-9] INFO  c.s.webby.WebSocketServer - [40085293-09c9-42ce-be22-7376f4eef6ac] Disconnected.
20:06:37.237 [default-nioEventLoopGroup-1-9] INFO  c.sqldashboards.dashy.QueryEngine2 - shutDown
20:06:38.220 [default-nioEventLoopGroup-1-3] INFO  c.s.webby.DashboardController - upsert d-3 u-admin
20:06:38.221 [default-nioEventLoopGroup-1-3] INFO  c.s.webby.DashboardController - upsertDB d-3 FX Dash
20:06:38.222 [default-nioEventLoopGroup-1-3] WARN  c.s.webby.DashboardController - upsert FAILED 3 u-admin
ryanhamilton commented 6 months ago

Improved startup log:

.20:38:03.397 [main] INFO  com.sqldashboards.webby.Application - Database path: ./pulsedb2
...20:38:04.783 [main] INFO  com.zaxxer.hikari.HikariDataSource - HikariPool-1 - Starting...
20:38:05.126 [main] INFO  com.zaxxer.hikari.HikariDataSource - HikariPool-1 - Start completed.
20:38:05.194 [main] INFO  i.m.flyway.AbstractFlywayMigration - Running migrations for database with qualifier [default]
20:38:05.330 [main] INFO  o.f.core.internal.command.DbValidate - Successfully validated 0 migrations (execution time 00:00.017s)
20:38:05.330 [main] WARN  o.f.core.internal.command.DbValidate - No migrations found. Are your locations set up correctly?
20:38:05.349 [main] INFO  o.f.core.internal.command.DbMigrate - Current version of schema "PUBLIC": << Empty Schema >>
20:38:05.353 [main] INFO  o.f.core.internal.command.DbMigrate - Schema "PUBLIC" is up to date. No migration necessary.
...20:38:06.672 [main] INFO  io.micronaut.runtime.Micronaut - Startup completed in 3216ms. Server Running: http://localhost:8080
.
                         ##                                               
                     ##########                                           
                    ############                                          
                   (((###########                                         
                 ,(((((((#########                                        
                ((((((((((((#******                                       
               (((((((((((((********,                                     
              ///(((((((((***********/                                    
             ///////((((( ********/////                (((((((((########  
            ///////////(   .**//////////             /((((((((((((########
           ////////////      ////////////           //(((((((((((((((#####
  ,*********//////////        ////////////         //////(((((((((((((((# 
 **************//////          ////////////       //////////((            
********************            ////////((((/    ////////////             
 *****************               ////(((((((((  **//////////              
     ........*.                   *((((((((((((*******/////               
                                    ((((((((((((*********/                
                                     ((((((((((((********                 
                                      ((((((((#####(***.                  
                                       ((((###########                    
                                        #############                     
                                         (##########                      
                                           #######                        

Pulse License: FREE - 3 users expires 2025-05-06
Pulse 2.24:  http://ryan-box-1:8080

See https://www.timestored.com/pulse/help/release-changes for what's new

20:38:06.695 [main] INFO  com.sqldashboards.webby.Application - Configuration(demo=false, demo_rundb=true, license_text=null, root_url=null, master_api_key=null, auth_url=null, auth_proxy_enabled=false, username_header_name=X-WEBAUTH-USER, auto_sign_up=true, smtp_enabled=false, smtp_starttls=true, smtp_host=null, smtp_port=null, smtp_user=null, smtp_password=null, smtp_from_address=null, smtp_from_name=null, sassy=false)
20:38:06.758 [pool-4-thread-1] INFO  c.t.babeldb.BabelDBJdbcDriver - fetch:https://testnet.binancefuture.com/fapi/v1/ticker/price
20:38:08.366 [pool-4-thread-1] INFO  c.t.babeldb.BabelDBJdbcDriver - fetch:https://api.kucoin.com/api/v2/symbols
20:38:24.187 [default-nioEventLoopGroup-1-2] INFO  c.s.webby.ConferenceController - getPresubscribeKey u-admin
20:38:24.204 [default-nioEventLoopGroup-1-4] INFO  c.s.webby.DashboardController - get d-3 0 u-admin
20:38:24.204 [default-nioEventLoopGroup-1-4] INFO  c.s.webby.DashboardController - get latest d-3 u-admin
20:38:24.276 [default-nioEventLoopGroup-1-5] INFO  c.s.webby.WebSocketServer - (admin) Joined [4a80eff7-54d7-43a7-8b92-acb318224c30]
20:38:24.277 [default-nioEventLoopGroup-1-5] INFO  c.s.webby.WebSocketServer - Starting DashboardEngine for user u-admin
20:38:25.786 [default-nioEventLoopGroup-1-5] INFO  c.s.webby.WebSocketServer - [4a80eff7-54d7-43a7-8b92-acb318224c30] Disconnected.
20:38:25.786 [default-nioEventLoopGroup-1-5] INFO  c.sqldashboards.dashy.QueryEngine2 - shutDown
20:38:28.475 [default-nioEventLoopGroup-1-4] INFO  c.s.webby.ConferenceController - getPresubscribeKey u-admin
20:38:28.475 [default-nioEventLoopGroup-1-2] INFO  c.s.webby.DashboardController - get d-3 0 u-admin
20:38:28.475 [default-nioEventLoopGroup-1-2] INFO  c.s.webby.DashboardController - get latest d-3 u-admin
20:38:28.492 [default-nioEventLoopGroup-1-6] INFO  c.s.webby.WebSocketServer - (admin) Joined [93c9b981-010c-441e-8b55-58c432d5b177]
20:38:28.492 [default-nioEventLoopGroup-1-6] INFO  c.s.webby.WebSocketServer - Starting DashboardEngine for user u-admin
20:38:30.741 [default-nioEventLoopGroup-1-4] INFO  c.s.webby.DashboardController - upsert d-3 u-admin
20:38:30.745 [default-nioEventLoopGroup-1-4] INFO  c.s.webby.DashboardController - upsertDB d-3 FX Dash
20:38:34.422 [default-nioEventLoopGroup-1-4] INFO  c.s.webby.DashboardController - upsert d-3 u-admin
20:38:34.425 [default-nioEventLoopGroup-1-4] INFO  c.s.webby.DashboardController - upsertDB d-3 FX Dash
20:38:39.552 [default-nioEventLoopGroup-1-6] INFO  c.s.webby.WebSocketServer - [93c9b981-010c-441e-8b55-58c432d5b177] Disconnected.
20:38:39.553 [default-nioEventLoopGroup-1-6] INFO  c.sqldashboards.dashy.QueryEngine2 - shutDown
20:38:41.218 [default-nioEventLoopGroup-1-4] INFO  c.sqldashboards.webby.UserController - getPrivate u-admin getting u-admin
20:38:48.521 [default-nioEventLoopGroup-1-4] INFO  c.s.webby.DbServerController - test u-admin s-FRED
20:38:57.859 [default-nioEventLoopGroup-1-4] INFO  c.s.webby.DashboardController - delete d-98 u-admin
20:39:02.669 [default-nioEventLoopGroup-1-2] INFO  c.sqldashboards.webby.UserController - get u-admin getting u-qweqwe
20:39:04.393 [default-nioEventLoopGroup-1-4] INFO  c.sqldashboards.webby.UserController - getPrivate u-admin getting u-qweqwe
20:39:05.368 [default-nioEventLoopGroup-1-4] INFO  c.sqldashboards.webby.UserController - get u-admin getting u-qweqwe
20:39:16.159 [default-nioEventLoopGroup-1-4] INFO  c.sqldashboards.webby.UserController - save u-admin saving u-qweq
20:39:22.879 [default-nioEventLoopGroup-1-4] INFO  c.sqldashboards.webby.UserController - delete u-6