openvidu-server: recording small fixes (duplicate recording start notification, do not init automatic stop on session closed by API REST, loggin improved)

openvidu-server/src/main/java/io/openvidu/server/kurento/core/KurentoSessionManager.java

@@ -169,6 +169,10 @@ public class KurentoSessionManager extends SessionManager {
 		sessionEventsHandler.onParticipantLeft(participant, sessionId, remainingParticipants, transactionId, null,
 				reason);
 
+		if (!"sessionClosedByServer".equals(reason)) {
+			// If session is closed by a call to "DELETE /api/sessions" do NOT stop the
+			// recording. Will be stopped after in method
+			// "SessionManager.closeSessionAndEmptyCollections"
 			if (remainingParticipants.isEmpty()) {
 				if (openviduConfig.isRecordingModuleEnabled()
 						&& MediaMode.ROUTED.equals(session.getSessionProperties().mediaMode())
@@ -177,7 +181,8 @@ public class KurentoSessionManager extends SessionManager {
 										&& this.recordingManager.sessionIsBeingRecordedOnlyAudio(sessionId)))) {
 					// Start countdown to stop recording if INDIVIDUAL mode or COMPOSED audio-only
 					// (will be aborted if a Publisher starts before timeout)
-				log.info("Last participant left. Starting {} seconds countdown for stopping recording of session {}",
+					log.info(
+							"Last participant left. Starting {} seconds countdown for stopping recording of session {}",
 							this.openviduConfig.getOpenviduRecordingAutostopTimeout(), sessionId);
 					recordingManager.initAutomaticRecordingStopThread(session);
 				} else {
@@ -196,16 +201,18 @@ public class KurentoSessionManager extends SessionManager {
 					// RecordingMode.ALWAYS
 					log.info("Last participant left. Stopping recording for session {}", sessionId);
 					recordingManager.stopRecording(session, null, reason);
-				evictParticipant(session.getParticipantByPublicId(ProtocolElements.RECORDER_PARTICIPANT_PUBLICID), null,
+					evictParticipant(session.getParticipantByPublicId(ProtocolElements.RECORDER_PARTICIPANT_PUBLICID),
-						null, "EVICT_RECORDER");
+							null, null, "EVICT_RECORDER");
 				} else if (RecordingMode.MANUAL.equals(session.getSessionProperties().recordingMode())) {
 					// Start countdown to stop recording if RecordingMode.MANUAL (will be aborted if
 					// a Publisher starts before timeout)
-				log.info("Last participant left. Starting {} seconds countdown for stopping recording of session {}",
+					log.info(
+							"Last participant left. Starting {} seconds countdown for stopping recording of session {}",
 							this.openviduConfig.getOpenviduRecordingAutostopTimeout(), sessionId);
 					recordingManager.initAutomaticRecordingStopThread(session);
 				}
 			}
+		}
 
 		// Finally close websocket session if required
 		if (closeWebSocket) {

openvidu-server/src/main/java/io/openvidu/server/recording/service/ComposedRecordingService.java

@@ -93,8 +93,8 @@ public class ComposedRecordingService extends RecordingService {
 			recording = this.startRecordingAudioOnly(session, recording, properties);
 		}
 
-		// Update collections and return recording
+		this.updateRecordingManagerCollections(session, recording);
-		this.updateCollectionsAndSendNotifCauseRecordingStarted(session, recording);
+
 		return recording;
 	}
 
@@ -132,6 +132,10 @@ public class ComposedRecordingService extends RecordingService {
 
 	private Recording startRecordingWithVideo(Session session, Recording recording, RecordingProperties properties)
 			throws OpenViduException {
+
+		log.info("Starting composed ({}) recording {} of session {}",
+				properties.hasAudio() ? "video + audio" : "audio-only", recording.getId(), recording.getSessionId());
+
 		List<String> envs = new ArrayList<>();
 
 		String layoutUrl = this.getLayoutUrl(recording, this.getShortSessionId(session));
@@ -173,6 +177,9 @@ public class ComposedRecordingService extends RecordingService {
 	private Recording startRecordingAudioOnly(Session session, Recording recording, RecordingProperties properties)
 			throws OpenViduException {
 
+		log.info("Starting composed (audio-only) recording {} of session {}", recording.getId(),
+				recording.getSessionId());
+
 		CompositeWrapper compositeWrapper = new CompositeWrapper((KurentoSession) session,
 				"file://" + this.openviduConfig.getOpenViduRecordingPath() + recording.getId() + "/" + properties.name()
 						+ ".webm");
@@ -191,12 +198,17 @@ public class ComposedRecordingService extends RecordingService {
 		}
 
 		this.generateRecordingMetadataFile(recording);
+		this.sendRecordingStartedNotification(session, recording);
 
 		return recording;
 	}
 
 	private Recording stopRecordingWithVideo(Session session, Recording recording, String reason) {
 
+		log.info("Stopping composed ({}) recording {} of session {}. Reason: {}",
+				recording.hasAudio() ? "video + audio" : "audio-only", recording.getId(), recording.getSessionId(),
+				RecordingManager.finalReason(reason));
+
 		String containerId = this.sessionsContainers.remove(recording.getSessionId());
 		this.cleanRecordingMaps(recording);
 
@@ -218,13 +230,14 @@ public class ComposedRecordingService extends RecordingService {
 				log.warn("Session closed while starting recording container");
 				boolean containerClosed = false;
 				String containerIdAux;
-				int timeOut = 0;
+				int i = 0;
-				while (!containerClosed && (timeOut < 30)) {
+				final int timeout = 30;
+				while (!containerClosed && (i < timeout)) {
 					containerIdAux = this.sessionsContainers.remove(session.getSessionId());
 					if (containerIdAux == null) {
 						try {
 							log.warn("Waiting for container to be launched...");
-							timeOut++;
+							i++;
 							Thread.sleep(500);
 						} catch (InterruptedException e) {
 							e.printStackTrace();
@@ -244,6 +257,10 @@ public class ComposedRecordingService extends RecordingService {
 						}
 					}
 				}
+				if (i == timeout) {
+					log.error("Container did not launched in {} seconds", timeout / 2);
+					return;
+				}
 			}).start();
 
 		} else {
@@ -309,6 +326,10 @@ public class ComposedRecordingService extends RecordingService {
 	}
 
 	private Recording stopRecordingAudioOnly(Session session, Recording recording, String reason) {
+
+		log.info("Stopping composed (audio-only) recording {} of session {}. Reason: {}", recording.getId(),
+				recording.getSessionId(), reason);
+
 		String sessionId;
 		if (session == null) {
 			log.warn(

openvidu-server/src/main/java/io/openvidu/server/recording/service/RecordingService.java

@@ -83,11 +83,17 @@ public abstract class RecordingService {
 	 * Changes recording from starting to started, updates global recording
 	 * collections and sends RPC response to clients
 	 */
-	protected void updateCollectionsAndSendNotifCauseRecordingStarted(Session session, Recording recording) {
+	protected void updateRecordingManagerCollections(Session session, Recording recording) {
 		this.recordingManager.sessionHandler.setRecordingStarted(session.getSessionId(), recording);
 		this.recordingManager.sessionsRecordings.put(session.getSessionId(), recording);
 		this.recordingManager.startingRecordings.remove(recording.getId());
 		this.recordingManager.startedRecordings.put(recording.getId(), recording);
+	}
+
+	/**
+	 * Sends RPC response for recording started event
+	 */
+	protected void sendRecordingStartedNotification(Session session, Recording recording) {
 		this.recordingManager.getSessionEventsHandler().sendRecordingStartedNotification(session, recording);
 	}
 
@@ -126,6 +132,7 @@ public abstract class RecordingService {
 	}
 
 	protected OpenViduException failStartRecording(Session session, Recording recording, String errorMessage) {
+		log.error("Recording start failed for session {}: {}", session.getSessionId(), errorMessage);
 		recording.setStatus(io.openvidu.java.client.Recording.Status.failed);
 		this.recordingManager.startingRecordings.remove(recording.getId());
 		this.stopRecording(session, recording, null);

openvidu-server/src/main/java/io/openvidu/server/recording/service/SingleStreamRecordingService.java

@@ -79,6 +79,10 @@ public class SingleStreamRecordingService extends RecordingService {
 		properties = updatePropertiesAndRecordingId.properties;
 		String recordingId = updatePropertiesAndRecordingId.recordingId;
 
+		log.info("Starting individual ({}) recording {} of session {}",
+				properties.hasVideo() ? (properties.hasAudio() ? "video+audio" : "video-only") : "audioOnly",
+				recordingId, session.getSessionId());
+
 		Recording recording = new Recording(session.getSessionId(), recordingId, properties);
 		this.recordingManager.startingRecordings.put(recording.getId(), recording);
 
@@ -120,7 +124,8 @@ public class SingleStreamRecordingService extends RecordingService {
 		}
 
 		this.generateRecordingMetadataFile(recording);
-		this.updateCollectionsAndSendNotifCauseRecordingStarted(session, recording);
+		this.updateRecordingManagerCollections(session, recording);
+		this.sendRecordingStartedNotification(session, recording);
 
 		return recording;
 	}
@@ -128,6 +133,10 @@ public class SingleStreamRecordingService extends RecordingService {
 	@Override
 	public Recording stopRecording(Session session, Recording recording, String reason) {
 
+		log.info("Stopping individual ({}) recording {} of session {}. Reason: {}",
+				recording.hasVideo() ? (recording.hasAudio() ? "video+audio" : "video-only") : "audioOnly",
+				recording.getId(), recording.getSessionId(), reason);
+
 		final int numberOfActiveRecorders = recorders.get(recording.getSessionId()).size();
 		final CountDownLatch stoppedCountDown = new CountDownLatch(numberOfActiveRecorders);