Effizienzsteigerung von FileWriter als Logging

[garstig]

Hallo liebe community!

Ich habe ein etwas größeres Programm geschrieben das 19 (derzeit) Objekte verwaltet (Files sortieren und verschieben, Kontrollmechanismen zum Filtern von Dateien) Im Leerlauf, also wenn keine Dateien für die einzelnen Objekte zum einsortieren sind, braucht das Programm ohne Logging Funktion max. 1 Min zum terminieren. Mit der Logging funktion, wo in den verschiedenen Klassen an verschiedenen Stellen, die statische write Funktion meiner LoggerKlasse aufgerufen wird, braucht das Programm im Leerlauf an die 6 Minuten.
Jetzt habe ich mir gedacht, ich sammle alle Logs eines Objektes in der LoggerKlasse und wenn ein Objekt abgearbeitet wurde, sage ich einfach flush und close (Objekte werden nacheinander abgearbeitet). Naja um mein Problem näher beschreiben zu können poste ich einfach mal die paar Zeilen Code die meine Logger-Klasse ausmachen.

public class MsgLog {
     protected static String defaultLogFile = "msglog.txt";
    
         public static void write(String s){
         try {
			write(defaultLogFile, s);
		} catch (IOException e) {
			// TODO Auto-generated catch block
			e.printStackTrace();
		}
     }
    
         public static void write(String f, String s) throws IOException {
         TimeZone tz = TimeZone.getTimeZone("CET");
         Date now = new Date();
         DateFormat df = new SimpleDateFormat ("dd.MM.yyyy HH:mm:ss ");
         df.setTimeZone(tz);
         String currentTime = df.format(now);
        
         FileWriter aWriter = new FileWriter(f, true);
         aWriter.write(currentTime + " " + s + "\n");
         aWriter.flush();
         aWriter.close();
     }
 }

Wie ihr seht, habe ich zu jeder Logmessage die an den Logger geschickt wird einen sekundengenauen Zeitstempel. Das würde beim Bündeln so abhanden kommen. Wie kann ich das am gescheitesten machen? Würde das überhaupt etwas ausmachen? Ich würde gerne wissen, wo beim FileWriter soviel Zeitaufwand aufkommt.

Danke jedenfalls fürs lesen, noch mehr Dank wenn ihr darüber nachdenkt und mir hilfreiche Gedankenanstöße gebt!

 

[Marco13]

Ein BufferedWriter könnte schonmal ein bißchen helfen, aber ich bin nicht sicher, ob ich das richtig verstanden habe. Bist du sicher, dass das eigentliche Rausschreiben das teure ist, und nicht z.B. das bauen der Strings? Grundsätzlich könnte man GANZ GROB sowas machen, dass man alle Log-Messages (so, wie sie jetzt rausgeschrieben werden - also mit Zeitstempel) in einer Liste sammelt, und irgendwann am Stück rausschreibt, aber ob das der Weisheit letzter Schluss ist....!?

 

[Lumaraf]

Ich würde sagen das Problem ist das für jeden einzelnen Logeintrag die Datei geöffnet werden muß. Die Logdatei nur einmal zu öffnen sollte könnte da schon einen guten Geschwindigkeitsgewinn geben. Dann solltest du am besten dem System auch die Chance geben die Daten etwas zu puffern indem du nicht nach jedem Eintrag ein flush aufrufst. Das braucht auch seine Zeit.

 

[garstig]

Ja, danke für eure Anregungen, es war genau das was ich mir auch schon grob vorgestellt habe. So sieht jetzt der Code aus,

public class MsgLog {
	protected static String defaultLogFile = "msglog.txt";
	static String logbundle = "";
	
	public static void write(String s){
		TimeZone tz = TimeZone.getTimeZone("CET"); // or PST, MID, etc ...
		Date now = new Date();
		DateFormat df = new SimpleDateFormat ("dd.MM.yyyy HH:mm:ss ");
		df.setTimeZone(tz);
		String currentTime = df.format(now);
		
		logbundle = logbundle + currentTime + " " + s + "\n";
	}

	public static void write(String f, String s) throws IOException {

		FileWriter aWriter = new FileWriter(defaultLogFile, true);
		aWriter.write(s);
		aWriter.flush();
		aWriter.close();
	}

	public static void flush(){
		try {
			write(defaultLogFile, logbundle);
		} catch (IOException e) {
			// TODO Auto-generated catch block
			e.printStackTrace();
		}
	}

}

´

Die Methode flush() wird im Code zweimal erwähnt, einmal gegen Ende des Codes und ein weiteres Mal in der Schleife der einzelnen Objekte, damit nichts verloren geht. Ich habe allerdings noch eine Frage hierzu: die Methode flush() wird in der Main Methode aufgerufen, wenn jetzt in einer der anderen Klassen eine Exception vorkommt, die in den Log schreibt oder generell wenn im Stream noch Strings sind die nicht "geflushed" wurden und eine Exception vorkommt, gehen dann diese Strings verloren? Wie kann ich dem vorbeugen? Würde ein finally in der MainMethode reichen oder sollte ich jetzt wieder in jedem Exception handling flush() aufrufen?

 

[KrokoDiehl]

Klingt nach einem typischen Fall für finally, weil das immer ausgeführt wird.

Eines noch: Für String-Arbeiten würde ich den StringBuilder nehmen, weil String+String durchaus ineffizient sein kann. Deine Globale

logbundle

würde ich also zum StringBuilder machen. Wer weiß, vielliecht gewinnst du noch ein paar Millisekunden