Fix logging ms

Author: fugerit79

fj-core/src/main/java/org/fugerit/java/core/db/dao/DAOHelper.java

@@ -28,6 +28,7 @@
 import java.util.List;
 
 import org.fugerit.java.core.log.LogObject;
+import org.fugerit.java.core.util.checkpoint.CheckpointUtils;
 import org.slf4j.Logger;
 
 public class DAOHelper {
@@ -40,7 +41,8 @@ public static void setAll( String queryId, PreparedStatement ps, FieldList field
 			np++;
 			int param = (k+1);
 			Field f = fields.getField(k);
-			log.debug( "queryId:'{}', setAll() Setting param (ng1) : '{}'", queryId, "n. "+param+", value: "+String.valueOf( f ) );
+			long startTime = System.currentTimeMillis();
+			log.debug( "queryId:'{}', setAll() Setting param (ng1) : '{}'", queryId, "n. "+param+", value: "+String.valueOf( f )+" (set time : '"+CheckpointUtils.formatTimeDiffMillis( startTime, System.currentTimeMillis() )+"')" );
 			f.setField(ps, param);
 			k++;
 		}    	

fj-core/src/main/java/org/fugerit/java/core/db/daogen/BasicDAOHelper.java

@@ -94,13 +94,16 @@ public void loadAllHelper( List<T> l, String query, FieldList fields, RSExtracto
 			int i=0;
 			try ( PreparedStatement ps = conn.prepareStatement( query ) ) {
 				DAOHelper.setAll( queryId, ps, fields , log );
+				long executeStart = System.currentTimeMillis();
 				try ( ResultSet rs = ps.executeQuery() ) {
-					log.debug("queryId:'{}', loadAll query execute end time : '{}'", queryId, CheckpointUtils.formatTimeDiff(startTime, System.currentTimeMillis()) );
+					long executeEnd = System.currentTimeMillis();
+					log.debug("queryId:'{}', loadAll query execute end time : '{}'", queryId, CheckpointUtils.formatTimeDiffMillis(executeStart, executeEnd ) );
 					while (rs.next()) {
 						l.add( re.extractNext( rs ) );
 						i++;
 					}
-					log.debug("queryId:'{}', loadAll query result end time : '{}'", queryId, CheckpointUtils.formatTimeDiff(startTime, System.currentTimeMillis()) );
+					log.debug("queryId:'{}', loadAll query result set end time : '{}'", queryId, CheckpointUtils.formatTimeDiffMillis( executeEnd, System.currentTimeMillis()) );
+					log.debug("queryId:'{}', loadAll query total end time : '{}'", queryId, CheckpointUtils.formatTimeDiffMillis( startTime, System.currentTimeMillis()) );
 				}
 			} catch (SQLException e) {
 				throw (new DAOException( e.getMessage()+"[query:"+query+",queryId:"+queryId+",record:"+i+"]", e ));
@@ -123,8 +126,10 @@ public int update( QueryHelper queryHelper ) throws DAOException {
 			Connection conn = this.daoContext.getConnection();
 			try ( PreparedStatement ps = conn.prepareStatement( query ) ) {
 				DAOHelper.setAll( queryId, ps, fields , log );
+				long executeStart = System.currentTimeMillis();
 				res = ps.executeUpdate();
-				log.debug("queryId:'{}', update query execute end time : '{}'", queryId, CheckpointUtils.formatTimeDiff(startTime, System.currentTimeMillis()) );
+				log.debug("queryId:'{}', update query execute end time : '{}'", queryId, CheckpointUtils.formatTimeDiffMillis(executeStart, System.currentTimeMillis()) );
+				log.debug("queryId:'{}', update total time : '{}'", queryId, CheckpointUtils.formatTimeDiffMillis(startTime, System.currentTimeMillis()) );
 			} catch (SQLException e) {
 				throw (new DAOException( e.getMessage()+"[query:"+query+",queryId:"+queryId+"]", e ));
 			}

fj-core/src/main/java/org/fugerit/java/core/util/checkpoint/CheckpointUtils.java

@@ -10,4 +10,8 @@ public static String formatTimeDiff( long start, long end ) {
 		return formatTimeDiff( end-start );
 	}
 
+	public static String formatTimeDiffMillis( long start, long end ) {
+		return String.valueOf(end-start)+" ms";
+	}
+	
 }