Struttura logs del servizio Media Manager Rai

147.7.6.2 Analisi log del job

Senza andare nel dettaglio dovreste far caso a queste cose:
STEP 1
>> service.rai.datamover.service rai datamover (1).zrmteuv331.channel.23.dt.20231007_23_00.log
08/10/2023 01.09.32.3510 [5739044] - ===============================================================================================
08/10/2023 01.09.32.3510 [5739044] - ProcessJob START
Il numero tra parentesi quadre è il numero dell'ID Job
08/10/2023 01.09.32.3510 [5739044] - job data values
08/10/2023 01.09.32.3667 [5739044] - ------------------------------------------------------------
08/10/2023 01.09.32.3667 [5739044] - reference : 5739044-1
il numero che trovate dopo l'ID_Job-numero corrisponde a quale step il servizio sta per eseguire
in questo caso il Primo

08/10/2023 01.09.32.3667 [5739044] - ------------------------------------------------------------
08/10/2023 01.09.32.3667 [5739044] - idMemoryJob : 5739044
08/10/2023 01.09.32.3667 [5739044] - step : 1
08/10/2023 01.09.32.3823 [5739044] - channel : 23
08/10/2023 01.09.32.3823 [5739044] - recDate : 07/10/2023 23:00:00:000
08/10/2023 01.09.32.3823 [5739044] - operation desc : Main file selection
08/10/2023 01.09.32.3823 [5739044] - id_memory_list : 0
08/10/2023 01.09.32.3823 [5739044] - ------------------------------------------------------------ 
....
08/10/2023 01.09.33.3510 [5739044] - ------------------------------------------------------------
08/10/2023 01.09.33.3510 [5739044] - idMemoryListMain : 14683426
08/10/2023 01.09.33.3510 [5739044] - idMemoryListBackup : 14683456
08/10/2023 01.09.33.3667 [5739044] - idMemoryListBackup2 : 0
Questi sono gli id di memory_list su cui il servizio va a lavorare
08/10/2023 01.09.33.3667 [5739044] - selection : 0
08/10/2023 01.09.33.3667 [5739044] - selection desc : keep main file
qui vi dice che ha selezionato il primo / quello che era già Main
08/10/2023 01.09.33.3667 [5739044] - newTypeM : 37
08/10/2023 01.09.33.3823 [5739044] - newTypeB : 38
08/10/2023 01.09.33.3823 [5739044] - newTypeC : 45
08/10/2023 01.09.33.3823 [5739044] - ------------------------------------------------------------
08/10/2023 01.09.33.3823 [5739044] - ------------------------------------------------------------
08/10/2023 01.09.33.3979 [5739044] - idMemoryListMain : 0
08/10/2023 01.09.33.3979 [5739044] - idMemoryListBackup : 0
08/10/2023 01.09.33.3979 [5739044] - idMemoryListBackup2 : 0
Qui non ci sono altre codifiche
08/10/2023 01.09.33.3979 [5739044] - selection : 20
08/10/2023 01.09.33.4135 [5739044] - selection desc : both the main and the backup have errors
08/10/2023 01.09.33.4135 [5739044] - newTypeM : 42
08/10/2023 01.09.33.4135 [5739044] - newTypeB : 43
08/10/2023 01.09.33.4292 [5739044] - newTypeC : 44
08/10/2023 01.09.33.4292 [5739044] - ------------------------------------------------------------ 
...
08/10/2023 01.09.33.6167 [5739044] - ------------------------------------------------------------
08/10/2023 01.09.33.6323 [5739044] - Step: 1 (Main file selection)
08/10/2023 01.09.33.6479 [5739044] - Job completed
08/10/2023 01.09.33.6792 [5739044] - ------------------------------------------------------------ 
Il servizio ha terminato lo step1
....
08/10/2023 01.09.33.9604 [5739044] - ------------------------------------------------------------
08/10/2023 01.09.33.9760 [5739044] - id_memory_job : 5739044
08/10/2023 01.09.34.0073 [5739044] - channel : 23
08/10/2023 01.09.34.0073 [5739044] - rec_date : 07/10/2023 23:00:00:000
08/10/2023 01.09.34.0229 [5739044] - start_time :
08/10/2023 01.09.34.0385 [5739044] - owner :
08/10/2023 01.09.34.0542 [5739044] - status : 0
08/10/2023 01.09.34.0698 [5739044] - message :
08/10/2023 01.09.34.0854 [5739044] - step : 2
08/10/2023 01.09.34.1010 [5739044] - id_memory_list : 0
08/10/2023 01.09.34.1167 [5739044] - ------------------------------------------------------------
08/10/2023 01.09.34.1167 [5739044] - getJobStatus END
Il servizio fa un recap del job e comunica che ha fatto il forward allo step 2
08/10/2023 01.09.34.1479 [5739044] - ------------------------------------------------------------
08/10/2023 01.09.34.1635 [5739044] - job execution time: 1272 msec
08/10/2023 01.09.34.1792 [5739044] - job execution time: 00:00:01:272 
mentre qui è possibile constatare il tempo di esecuzione totale del job

Step2

Recap del job
08/10/2023 01.14.54.9166 [5739060] - ------------------------------------------------------------
08/10/2023 01.14.54.9322 [5739060] - reference : 5739060-2
08/10/2023 01.14.54.9322 [5739060] - ------------------------------------------------------------
08/10/2023 01.14.54.9322 [5739060] - idMemoryJob : 5739060
08/10/2023 01.14.54.9322 [5739060] - step : 2
08/10/2023 01.14.54.9478 [5739060] - channel : 77
08/10/2023 01.14.54.9478 [5739060] - recDate : 07/10/2023 23:05:00:000
08/10/2023 01.14.54.9478 [5739060] - operation desc : Copy file to external Etere system
08/10/2023 01.14.54.9478 [5739060] - id_memory_list : 0
08/10/2023 01.14.54.9478 [5739060] - ------------------------------------------------------------ 
.....
check dei file da copiare in collaudo
08/10/2023 01.14.55.1070 [5739060] - ------------------------------------------------------------
08/10/2023 01.14.55.1070 [5739060] - idMemoryList : 14683510
08/10/2023 01.14.55.1070 [5739060] - channel : 77
08/10/2023 01.14.55.1070 [5739060] - copyType : M
08/10/2023 01.14.55.1070 [5739060] - recDate : 07/10/2023 23:05:00:000
08/10/2023 01.14.55.1226 [5739060] - length : 300000
08/10/2023 01.14.55.1226 [5739060] - CType : 48
08/10/2023 01.14.55.1226 [5739060] - Encoder : 57
08/10/2023 01.14.55.1226 [5739060] - Path1 : 0
08/10/2023 01.14.55.1226 [5739060] - Path2 : 7613
08/10/2023 01.14.55.1382 [5739060] - Path3 : 0
08/10/2023 01.14.55.1382 [5739060] - Path4 : 0
08/10/2023 01.14.55.1382 [5739060] - Path5 : 0
08/10/2023 01.14.55.1382 [5739060] - Path6 : 0
08/10/2023 01.14.55.1382 [5739060] - Filename : MEM077M-20231007Z23050000.mp3
08/10/2023 01.14.55.1539 [5739060] - LastUpdate : 07/10/2023 23:10:16
08/10/2023 01.14.55.1539 [5739060] - Size : 9602066
08/10/2023 01.14.55.1539 [5739060] - ------------------------------------------------------------
08/10/2023 01.14.55.1539 [5739060] - ------------------------------------------------------------
08/10/2023 01.14.55.1539 [5739060] - idMemoryList : 14683490
08/10/2023 01.14.55.1695 [5739060] - channel : 77
08/10/2023 01.14.55.1695 [5739060] - copyType : B
08/10/2023 01.14.55.1695 [5739060] - recDate : 07/10/2023 23:05:00:000
08/10/2023 01.14.55.1695 [5739060] - length : 300000
08/10/2023 01.14.55.1851 [5739060] - CType : 49
08/10/2023 01.14.55.1851 [5739060] - Encoder : 56
08/10/2023 01.14.55.1851 [5739060] - Path1 : 0
08/10/2023 01.14.55.1851 [5739060] - Path2 : 7613
08/10/2023 01.14.55.2007 [5739060] - Path3 : 0
08/10/2023 01.14.55.2007 [5739060] - Path4 : 0
08/10/2023 01.14.55.2007 [5739060] - Path5 : 0
08/10/2023 01.14.55.2164 [5739060] - Path6 : 0
08/10/2023 01.14.55.2164 [5739060] - Filename : MEM077B-20231007Z23050000.mp3
08/10/2023 01.14.55.2164 [5739060] - LastUpdate : 07/10/2023 23:10:14
08/10/2023 01.14.55.2320 [5739060] - Size : 9602066
08/10/2023 01.14.55.2320 [5739060] - ------------------------------------------------------------
...
Copia in collaudo

08/10/2023 01.14.55.3570 [5739060] - ------------------------------------------------------------
08/10/2023 01.14.55.3570 [5739060] - SourceIdPath : 7613
08/10/2023 01.14.55.3726 [5739060] - SourcePathname : \\nasmamto1.ict.corp.rai.it\MAM_ETERE_MO\20231007\
08/10/2023 01.14.55.3726 [5739060] - SourceFilename : MEM077M-20231007Z23050000.mp3
08/10/2023 01.14.55.3726 [5739060] - SourceFullname : \\nasmamto1.ict.corp.rai.it\MAM_ETERE_MO\20231007\MEM077M-20231007Z23050000.mp3
08/10/2023 01.14.55.3726 [5739060] - DestinationMemoryIdPath : 4356
08/10/2023 01.14.55.3882 [5739060] - DestinationIdPath : 2784
08/10/2023 01.14.55.3882 [5739060] - DestinationPathname : \\nasmamtocoll.ict.corp.rai.it\MAM_ETEREcoll_bck\EtereCOLL\20231007\
08/10/2023 01.14.55.3882 [5739060] - DestinationFilename : MEM077M-20231007Z23050000.mp3
08/10/2023 01.14.55.3882 [5739060] - DestinationFullname : \\nasmamtocoll.ict.corp.rai.it\MAM_ETEREcoll_bck\EtereCOLL\20231007\MEM077M-20231007Z23050000.mp3
08/10/2023 01.14.55.4039 [5739060] - ------------------------------------------------------------

...
08/10/2023 01.15.06.8310 [5739060] - ------------------------------------------------------------
08/10/2023 01.15.06.8310 [5739060] - Step: 2 (Copy file to external Etere system)
08/10/2023 01.15.06.8467 [5739060] - Job completed
08/10/2023 01.15.06.8467 [5739060] - ------------------------------------------------------------
....
08/10/2023 01.15.06.9248 [5739060] - ------------------------------------------------------------
08/10/2023 01.15.06.9404 [5739060] - id_memory_job : 5739060
08/10/2023 01.15.06.9404 [5739060] - channel : 77
08/10/2023 01.15.06.9404 [5739060] - rec_date : 07/10/2023 23:05:00:000
08/10/2023 01.15.06.9560 [5739060] - start_time :
08/10/2023 01.15.06.9560 [5739060] - owner :
08/10/2023 01.15.06.9560 [5739060] - status : 0
08/10/2023 01.15.06.9717 [5739060] - message :
08/10/2023 01.15.06.9717 [5739060] - step : 3
08/10/2023 01.15.06.9873 [5739060] - id_memory_list : 0
08/10/2023 01.15.06.9873 [5739060] - ------------------------------------------------------------
08/10/2023 01.15.07.0029 [5739060] - getJobStatus END
08/10/2023 01.15.07.0029 [5739060] - ------------------------------------------------------------
08/10/2023 01.15.07.0029 [5739060] - job execution time: 11919 msec
08/10/2023 01.15.07.0185 [5739060] - job execution time: 00:00:11:919
08/10/2023 01.15.07.0185 [5739060] - result: 0
08/10/2023 01.15.07.0185 [5739060] - alignExternalSystemJob END
08/10/2023 01.15.07.0342 [5739060] - ProcessJob END
08/10/2023 01.15.07.0342 [5739060] - 

FW del job allo status successivo

Step 3
08/10/2023 01.13.02.2215 [5739044] - ------------------------------------------------------------
08/10/2023 01.13.02.2371 [5739044] - reference : 5739044-3
08/10/2023 01.13.02.2371 [5739044] - ------------------------------------------------------------
08/10/2023 01.13.02.2527 [5739044] - idMemoryJob : 5739044
08/10/2023 01.13.02.2527 [5739044] - step : 3
08/10/2023 01.13.02.2683 [5739044] - channel : 23
08/10/2023 01.13.02.2683 [5739044] - recDate : 07/10/2023 23:00:00:000
08/10/2023 01.13.02.2840 [5739044] - operation desc : Copy main file to second path
08/10/2023 01.13.02.2840 [5739044] - id_memory_list : 0
08/10/2023 01.13.02.2996 [5739044] - ------------------------------------------------------------
...
08/10/2023 01.13.02.5652 [5739044] - ------------------------------------------------------------
08/10/2023 01.13.02.5652 [5739044] - idMemoryList : 14683426
08/10/2023 01.13.02.5808 [5739044] - channel : 23
08/10/2023 01.13.02.5965 [5739044] - copyType : M
08/10/2023 01.13.02.5965 [5739044] - recDate : 07/10/2023 23:00:00:000
08/10/2023 01.13.02.5965 [5739044] - length : 300000
08/10/2023 01.13.02.6121 [5739044] - CType : 37
08/10/2023 01.13.02.6121 [5739044] - Encoder : 4
08/10/2023 01.13.02.6277 [5739044] - Path1 : 0
08/10/2023 01.13.02.6433 [5739044] - Path2 : 7613
08/10/2023 01.13.02.6433 [5739044] - Path3 : 0
08/10/2023 01.13.02.6590 [5739044] - Path4 : 0
08/10/2023 01.13.02.6590 [5739044] - Path5 : 0
08/10/2023 01.13.02.6746 [5739044] - Path6 : 0
08/10/2023 01.13.02.6746 [5739044] - Filename : MEM023M-20231007Z23000000.mp4
08/10/2023 01.13.02.6902 [5739044] - LastUpdate : 07/10/2023 23:05:29
08/10/2023 01.13.02.6902 [5739044] - Size : 197121828
08/10/2023 01.13.02.7058 [5739044] - ------------------------------------------------------------
08/10/2023 01.13.02.7058 [5739044] - ------------------------------------------------------------
08/10/2023 01.13.02.7215 [5739044] - idMemoryList : 14683456
08/10/2023 01.13.02.7215 [5739044] - channel : 23
08/10/2023 01.13.02.7371 [5739044] - copyType : B
08/10/2023 01.13.02.7371 [5739044] - recDate : 07/10/2023 23:00:00:000
08/10/2023 01.13.02.7527 [5739044] - length : 300000
08/10/2023 01.13.02.7527 [5739044] - CType : 38
08/10/2023 01.13.02.7683 [5739044] - Encoder : 18
08/10/2023 01.13.02.7683 [5739044] - Path1 : 0
08/10/2023 01.13.02.7840 [5739044] - Path2 : 7613
08/10/2023 01.13.02.7840 [5739044] - Path3 : 0
08/10/2023 01.13.02.7996 [5739044] - Path4 : 0
08/10/2023 01.13.02.7996 [5739044] - Path5 : 0
08/10/2023 01.13.02.8152 [5739044] - Path6 : 0
08/10/2023 01.13.02.8152 [5739044] - Filename : MEM023B-20231007Z23000000.mp4
08/10/2023 01.13.02.8308 [5739044] - LastUpdate : 07/10/2023 23:05:52
08/10/2023 01.13.02.8308 [5739044] - Size : 197120014
08/10/2023 01.13.02.8465 [5739044] - ------------------------------------------------------------ 
il servizio fa un recap dei file presenti per il chunk in esame e poi parte a copiare i file tra le due destinazioni
...
08/10/2023 01.13.02.9253 [5739044] - ------------------------------------------------------------
08/10/2023 01.13.02.9410 [5739044] - SourceIdPath : 7613
08/10/2023 01.13.02.9566 [5739044] - SourcePathname : \\nasmamto1.ict.corp.rai.it\MAM_ETERE_MO\20231007\
08/10/2023 01.13.02.9566 [5739044] - SourceFilename : MEM023M-20231007Z23000000.mp4
08/10/2023 01.13.02.9722 [5739044] - SourceFullname : \\nasmamto1.ict.corp.rai.it\MAM_ETERE_MO\20231007\MEM023M-20231007Z23000000.mp4
08/10/2023 01.13.02.9722 [5739044] - DestinationMemoryIdPath : 2045
08/10/2023 01.13.02.9722 [5739044] - DestinationIdPath : 7612
08/10/2023 01.13.02.9879 [5739044] - DestinationPathname : \\nasmamrm1.ict.corp.rai.it\MAM_ETERE_MO\20231007\
08/10/2023 01.13.03.0035 [5739044] - DestinationFilename : MEM023M-20231007Z23000000.mp4
08/10/2023 01.13.03.0035 [5739044] - DestinationFullname : \\nasmamrm1.ict.corp.rai.it\MAM_ETERE_MO\20231007\MEM023M-20231007Z23000000.mp4
08/10/2023 01.13.03.0347 [5739044] - ------------------------------------------------------------ 
... 
quando ha finito, come già visto sopra
08/10/2023 01.13.29.1391 [5739044] - ------------------------------------------------------------
08/10/2023 01.13.29.2017 [5739044] - Step: 3 (Copy main file to second path)
08/10/2023 01.13.29.2017 [5739044] - Job completed
08/10/2023 01.13.29.2173 [5739044] - ------------------------------------------------------------ 
e fa il FW del del job allo step successivo
08/10/2023 01.13.29.2954 [5739044] - ------------------------------------------------------------
08/10/2023 01.13.29.3110 [5739044] - id_memory_job : 5739044
08/10/2023 01.13.29.3110 [5739044] - channel : 23
08/10/2023 01.13.29.3267 [5739044] - rec_date : 07/10/2023 23:00:00:000
08/10/2023 01.13.29.3267 [5739044] - start_time :
08/10/2023 01.13.29.3267 [5739044] - owner :
08/10/2023 01.13.29.3423 [5739044] - status : 0
08/10/2023 01.13.29.3423 [5739044] - message :
08/10/2023 01.13.29.3423 [5739044] - step : 4
08/10/2023 01.13.29.3579 [5739044] - id_memory_list : 0
08/10/2023 01.13.29.3579 [5739044] - ------------------------------------------------------------
08/10/2023 01.13.29.3735 [5739044] - getJobStatus END
08/10/2023 01.13.29.3735 [5739044] - ------------------------------------------------------------
08/10/2023 01.13.29.3892 [5739044] - job execution time: 26875 msec
08/10/2023 01.13.29.3892 [5739044] - job execution time: 00:00:26:875
08/10/2023 01.13.29.3892 [5739044] - result: 0
08/10/2023 01.13.29.4048 [5739044] - copyMainJob END
08/10/2023 01.13.29.4048 [5739044] - ProcessJob END
08/10/2023 01.13.29.4048 [5739044] - ===============================================================================================
08/10/2023 01.13.29.4204 [5739044] -  


La sintassi presentata è presente anche negli steps successivi con le dovute differenze, ma sostanzialmente sono identici.