/*
 * Copyright (c) 2017 Data and Web Science Group, University of Mannheim, Germany (http://dws.informatik.uni-mannheim.de/)
 *
 * Licensed under the Apache License, Version 2.0 (the "License"); you may not use this file except in compliance with the License.
 * You may obtain a copy of the License at
 *
 * http://www.apache.org/licenses/LICENSE-2.0
 *
 * Unless required by applicable law or agreed to in writing, software distributed under the License is distributed on an "AS IS" BASIS, WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
 * See the License for the specific language governing permissions and limitations under the License.
 */

package de.uni_mannheim.informatik.dws.winter.utils.parallel;

import java.util.Map.Entry;
import java.util.concurrent.ThreadPoolExecutor;

import org.apache.commons.lang.time.DurationFormatUtils;
import org.apache.logging.log4j.Logger;

import de.uni_mannheim.informatik.dws.winter.utils.WinterLogManager;

/**
 * 
 * Progress reporter that can run in its own thread.
 * 
 * @author Oliver Lehmberg (oli@dwslab.de)
 *
 */
public class RunnableProgressReporter implements Runnable {

	private ThreadPoolExecutor pool;
	private Thread thread;
	private Task userTask;
	private boolean stop;
	private String message;
	private boolean reportIfStuck = true;

	private static final Logger logger = WinterLogManager.getLogger("progress");

	public Task getUserTask() {
		return userTask;
	}

	public void setUserTask(Task userTask) {
		this.userTask = userTask;
	}

	public String getMessage() {
		return message;
	}

	public void setMessage(String message) {
		this.message = message;
	}

	public ThreadPoolExecutor getPool() {
		return pool;
	}

	public void setPool(ThreadPoolExecutor pool) {
		this.pool = pool;
	}

	public void setReportIfStuck(boolean reportIfStuck) {
		this.reportIfStuck = reportIfStuck;
	}

	public boolean getReportIfStuck() {
		return reportIfStuck;
	}

	long start = 0;
	long tasks = 0;
	long done = 0;
	int stuckIterations = 0;;
	long last = 0;
	long lastTime = 0;

	public void run() {
		try {
			initialise();
			while (!stop) {
				Thread.sleep(10000);

				if (!stop) {
					print();
				}
			}
		} catch (Exception e) {
			e.printStackTrace();
		}
	}

	public void initialise() {
		start = System.currentTimeMillis();
		tasks = pool.getTaskCount();
		done = pool.getCompletedTaskCount();
		stuckIterations = 0;
		;
		last = 0;
		lastTime = System.currentTimeMillis();
	}

	public void print() {

		if (System.currentTimeMillis() - lastTime >= 10000) {

			tasks = pool.getTaskCount();
			done = pool.getCompletedTaskCount();

			long soFar = System.currentTimeMillis() - start;
			long pauseTime = System.currentTimeMillis() - lastTime;
			long left = (long) (((float) soFar / done) * (tasks - done));
			float itemsPerSecAvg = (float) done / (float) (soFar / 1000.0f);
			float itemsPerSecNow = (float) (done - last) / (pauseTime / 1000.0f);

			if ((((float) soFar) / done) == Float.POSITIVE_INFINITY) {
				left = -1;
			}
			String ttl = DurationFormatUtils.formatDuration(soFar, "HH:mm:ss.S");
			String remaining = DurationFormatUtils.formatDuration(left, "HH:mm:ss.S");

			String usrMsg = message == null ? "" : message + ": ";
			logger.error(String.format(
					"%s%,d of %,d tasks completed after %s (%d/%d active threads). Avg: %.2f items/s, Current: %.2f items/s, %s left.",
					usrMsg, done, tasks, ttl, pool.getActiveCount(), pool.getPoolSize(), itemsPerSecAvg, itemsPerSecNow,
					remaining));

			if (userTask != null)
				userTask.execute();

			if (done == last) {
				stuckIterations++;
			} else {
				last = done;
				stuckIterations = 0;
			}

			if (stuckIterations >= 3 && reportIfStuck) {
				logger.error("ThreadPool seems to be stuck!");
				int threadCnt = 0;
				int parkedCnt = 0;
				Entry<Thread, StackTraceElement[]> main = null;
				for (Entry<Thread, StackTraceElement[]> e : Thread.getAllStackTraces().entrySet()) {
					if (e.getKey().getName().contains("Parallel")) {
						threadCnt++;

						if (e.getValue()[0].toString().startsWith("sun.misc.Unsafe.park")) {
							parkedCnt++;
						} else {
							logger.error(e.getKey().getName());
							for (StackTraceElement elem : e.getValue()) {
								logger.error("\t" + elem.toString());
							}
						}

					}

					if (e.getKey().getName().equals("main")) {
						main = e;
					}
				}

				logger.error(String.format("%s %d Parallel.X threads (%d parked) --- %d total",
						pool.isTerminated() ? "[pool terminated]" : "", threadCnt, parkedCnt,
						Thread.getAllStackTraces().size()));

				if (main != null) {
					logger.error(main.getKey().getName());
					for (StackTraceElement elem : main.getValue()) {
						logger.error("\t" + elem.toString());
					}
				}
			}

			lastTime = System.currentTimeMillis();

		}
	}

	public void start() {
		stop = false;
		thread = new Thread(this);
		thread.start();
	}

	public void stop() {
		stop = true;
	}
}

@@ -3356,37 +3356,36 @@
 : %22;%0D%0A%09%09%09logger.
-error
+info
 (String.format(%0D
@@ -3861,21 +3861,21 @@
 %09logger.
-error
+trace
 (%22Thread
@@ -4297,21 +4297,21 @@
 %09logger.
-error
+trace
 (e.getKe
@@ -4388,37 +4388,37 @@
 %0A%09%09%09%09%09%09%09%09logger.
-error
+trace
 (%22%5Ct%22 + elem.toS
@@ -4553,21 +4553,21 @@
 %09logger.
-error
+trace
 (String.
@@ -4787,21 +4787,21 @@
 %09logger.
-error
+trace
 (main.ge

change log level from error to info / trace