| Scroll Ignore | ||||||||||||||||||
|---|---|---|---|---|---|---|---|---|---|---|---|---|---|---|---|---|---|---|
| ||||||||||||||||||
|
Libération de ressources lors de la création d'un processus (fork)
Pour exécuter certaines tâches ( Modules, Workers, ... ), le Broker doit créer de nouveaux processus sur le système.
Sur les environnements UNIX et notamment Linux, la procédure pour créer un nouveau processus consiste à dupliquer le processus courant. L'appel système associé se nomme fork, ainsi, cette action est souvent appelée un fork.
Sous Linux, l'opération de fork est rapide, l'allocation effective de la mémoire du nouveau processus se faisant lorsque le nouveau processus veut y accéder.
Python disposant d'un garbage collector, un outil parcourant la mémoire pour identifier les zones inutilisées et la restituer au système, la mémoire héritée du parent finit par être intégralement copiée dans le nouveau processus.
Pour éviter une consommation inutile de ressources, après le fork, on nettoie les données inutiles issues du processus père (mémoire principalement)
Plus concrètement, le Broker va supprimer les données produites par les modules qu'il a instanciés.
Les modules peuvent fournir une méthode pour gérer la libération de leurs ressources.
Dans tous les cas, le log suivant permet de suivre le nettoyage des ressources :
| Code Block | ||||
|---|---|---|---|---|
| ||||
[YYYY-MM-DD HH:MM:SS] INFO: [ MODULE_NAME ] [ CLEAN AFTER FORK ] [ pid:PID] cleanup is starting [YYYY-MM-DD HH:MM:SS] INFO: [ MODULE_NAME ] [ CLEAN AFTER FORK ] [ pid:PID] cleanup done in X.XXXs |
| Code Block | ||||||
|---|---|---|---|---|---|---|
| ||||||
[2021-10-11 06:10:52] INFO : [ WebUI ] [ CLEAN AFTER FORK ] [ pid:3736 ] cleanup is starting [2021-10-11 06:10:52] INFO : [ WebUI ] [ CLEAN AFTER FORK ] [ pid:3736 ] cleanup done in 0.143s |
Une erreur survient pendant le nettoyage des ressources d'un module
Si la méthode de nettoyage d'un module rencontre une erreur, on retrouvera le log qui suit.
Certaines ressources (la mémoire notamment) peuvent ne pas avoir été libérées, mais si le système en a suffisamment de disponibles, Shinken fonctionnera normalement.
| Code Block | ||||
|---|---|---|---|---|
| ||||
[YYYY-MM-DD HH:MM:SS] ERROR : [ MODULE_NAME ] [ CLEAN AFTER FORK ] [ pid:PID] [ MODULE_NAME2 ] On linux system, the forking mechanism (process creation) is fast but create a copy of the father process. So we have to release unnecessary resources inherited from father. The cleaning has been performed but we encountered an error: [YYYY-MM-DD HH:MM:SS] ERROR : [ MODULE_NAME ] [ CLEAN AFTER FORK ] [ pid:PID] [ MODULE_NAME2 ] Cleanup of data from module 〖 MODULE_NAME2 〗 raised error 〖 IOError occurred 〗 [YYYY-MM-DD HH:MM:SS] ERROR : [ MODULE_NAME ] [ CLEAN AFTER FORK ] [ pid:PID] [ MODULE_NAME2 ] Some memory may have not been freed. Shinken will still run if enough memory remains available. [YYYY-MM-DD HH:MM:SS] ERROR : [ MODULE_NAME ] [ CLEAN AFTER FORK ] [ pid:PID] [ MODULE_NAME2 ] You can report this message to support in order to optimize Shinken memory consumption [YYYY-MM-DD HH:MM:SS] ERROR : [ MODULE_NAME ] [ CLEAN AFTER FORK ] [ pid:PID] [ MODULE_NAME2 ] ERROR stack : Traceback (most recent call last): [YYYY-MM-DD HH:MM:SS] ERROR : [ MODULE_NAME ] [ CLEAN AFTER FORK ] [ pid:PID] [ MODULE_NAME2 ] ERREUR PYTHON |
| Code Block | ||||||
|---|---|---|---|---|---|---|
| ||||||
[2021-09-29 15:05:58] ERROR : [ Livestatus ] [ CLEAN AFTER FORK ] [ pid:20404 ] [ sla ] On linux system, the forking mechanism (process creation) is fast but create a copy of the father process. So we have to release unnecessary resources inherited from father. The cleaning has been performed but we encountered an error: [2021-09-29 15:05:58] ERROR : [ Livestatus ] [ CLEAN AFTER FORK ] [ pid:20404 ] [ sla ] Cleanup of data from module 〖 sla 〗 raised error 〖 IOError occurred 〗 [2021-09-29 15:05:58] ERROR : [ Livestatus ] [ CLEAN AFTER FORK ] [ pid:20404 ] [ sla ] Some memory may have not been freed. Shinken will still run if enough memory remains available. [2021-09-29 15:05:58] ERROR : [ Livestatus ] [ CLEAN AFTER FORK ] [ pid:20404 ] [ sla ] You can report this message to support in order to optimize Shinken memory consumption [2021-09-29 15:05:58] ERROR : [ Livestatus ] [ CLEAN AFTER FORK ] [ pid:20404 ] [ sla ] ERROR stack : Traceback (most recent call last): [2021-09-29 15:05:58] ERROR : [ Livestatus ] [ CLEAN AFTER FORK ] [ pid:20404 ] [ sla ] File "/usr/lib/python2.7/site-packages/shinken/modulesmanager.py", line 877, in do_after_fork_cleanup [2021-09-29 15:05:58] ERROR : [ Livestatus ] [ CLEAN AFTER FORK ] [ pid:20404 ] [ sla ] inst_cleanup() [2021-09-29 15:05:58] ERROR : [ Livestatus ] [ CLEAN AFTER FORK ] [ pid:20404 ] [ sla ] File "/var/lib/shinken/modules/sla/sla_module_broker.py", line 93, in after_fork_cleanup [2021-09-29 15:05:58] ERROR : [ Livestatus ] [ CLEAN AFTER FORK ] [ pid:20404 ] [ sla ] raise IOError(u'IOError occurred') [2021-09-29 15:05:58] ERROR : [ Livestatus ] [ CLEAN AFTER FORK ] [ pid:20404 ] [ sla ] IOError: IOError occurred |
Envoi et réception de commande entre plusieurs processus
Les logs suivants permettent de suivre l'envoi et la réception de commande se faisant entre plusieurs processus.
Il existe deux types de communications par commande :
- Communication entre le Broker et un de ses modules
- Communication entre un module et un de ses workers
Envoi d'une commande
Warning
Dans le cas d'un premier timeout d'une commande, ce log s'affichera. La commande sera alors renvoyée une deuxième fois.
| Code Block | ||||
|---|---|---|---|---|
| ||||
[YYYY-MM-DD HH:MM:SS] WARNING WARNING : [ NOMBROKER_DU_BROKERNAME ] [ NOMMODULE_DU_MODULENAME ] [ TheCOMMAND CALL command] call [NOM_DE_LA_COMMANDE] for module NOM_DU_MODULE did timeout[ PID:XXXX ] [ THREAD_NAME ] The command call [ COMMAND_NAME ] for module MODULE_NAME was sent, but the call timed out (TEMPS_TIMEOUTs). WeWill will retry one time. |
| Code Block | ||||||
|---|---|---|---|---|---|---|
| ||||||
[2020-11-17 09:12:11] WARNING : [ broker-master ] [ Livestatus ] [ TheCOMMAND commandCALL call [get] [ PID:29341 ] [ CP Server Thread-46 ] The command call [get_module_info] for module Livestatus was sent but the call didtimed timeoutout (1s). We will retry one time. |
Il peut arriver qu'à l'envoi d'une commande une autre réponse soit reçue, si la précédente commande n'a pas fonctionné par exemple. Dans ce cas, ce log s'affichera. La commande sera alors renvoyée afin de récupérer la bonne réponse.
| Code Block | ||||
|---|---|---|---|---|
| ||||
[YYYY-MM-DD HH:MM:SS] WARNING : [ NOMBROKER_DU_BROKERNAME ] [ NOMMODULE_DU_MODULENAME ] The[ commandCOMMAND call [NOM_DE_LA_COMMANDE] was called but another respond was presentCALL ] [ PID:XXXX ] [ THREAD_NAME ] The command call [ COMMAND_NAME ] was sent but another answer was received. Retrying. |
| Code Block | ||||||
|---|---|---|---|---|---|---|
| ||||||
[2020-11-17 09:12:11] WARNING : [ broker-master ] [ Livestatus ] [ COMMAND CALL ] [ PID:29341 ] [ CP Server Thread-46 ] The command call [get_module_info] was calledsent but another respondanswer was presentreceived. Retrying. |
Error
Si l'envoi de la commande a connu un premier timeout, à son deuxième elle passera en erreur et ne sera pas renvoyée. Ce log sera affiché :
| Code Block | ||||
|---|---|---|---|---|
| ||||
[YYYY-MM-DD HH:MM:SS] ERROR ERROR : [ NOM_DU_BROKERBROKER_NAME ] [ MODULE_NAME ] [ COMMAND CALL ] [ PID:XXXX ] [ NOMTHREAD_DU_MODULENAME ] FailFailed to send command call call [NOM_DE_LA_COMMANDE] for COMMAND_NAME ] for module NOMMODULE_DU_MODULE becauseNAME thebecause module didof timeout (TEMPS_TIMEOUTs). |
| Code Block | ||||||
|---|---|---|---|---|---|---|
| ||||||
[2020-11-17 09:12:11] ERROR : [ broker-master ] [ Livestatus ] [ COMMAND CALL ] [ PID:29341 ] [ CP Server Thread-46 ] FailFailed to send command call [get_module_info] for module Livestatus because theof module did timeout (1s). |
Il peut arriver que la commande échoue à cause d'un problème du côté du module/worker. Dans ce cas ce log sera affiché après l'affichage de la stack.
| Code Block | ||||
|---|---|---|---|---|
| ||||
[YYYY-MM-DD HH:MM:SS] ERROR ERROR : [ NOMBROKER_DU_BROKERNAME ] [ NOM_DU_MODULEMODULE_NAME ] [ COMMAND CALL ] [ PID:XXXX ] [ THREAD_NAME ] FailFailed to send command call call [NOM_DE_LA_COMMANDE] for COMMAND_NAME ] for module NOM_DU_MODULE becauseMODULE_NAME because of an unknown error: ERROR_MESSAGE_D'ERREUR |
| Code Block | ||||||
|---|---|---|---|---|---|---|
| ||||||
[2020-11-17 09:12:11] ERROR : [ broker-master ] [ Livestatus ] Fail[ to COMMAND CALL ] [ PID:29341 ] [ CP Server Thread-46 ] Failed to send command call [get_module_info] for module Livestatus because of an unknown error: 'int' object is not iterable |
Debug
Dans le cas d'une réussite de l'envoi de la commande, jusqu'à sa réception, ce log s'affichera :
| Code Block | ||||
|---|---|---|---|---|
| ||||
[YYYY-MM-DD HH:MM:SS] DEBUG : [ NOMBROKER_DU_BROKERNAME ] [ NOMMODULE_DU_MODULENAME ] The[ commandCOMMAND call [NOM_DE_LA_COMMANDE] was executed CALL ] [ PID:XXXX ] [ THREAD_NAME ] The command call [ COMMAND_NAME ] was executed by the module NOMMODULE_DU_MODULENAME in TEMPSRUNNING_D'EXECUTIONsTIMEs |
| Code Block | ||||||
|---|---|---|---|---|---|---|
| ||||||
[2020-11-17 09:12:11] DEBUG : [ broker-master ] [ Livestatus ] The[ commandCOMMAND call [get_module_infoCALL ] was[ executedPID:29341 by] the[ module CP Server Thread-46 ] The command call [get_module_info] was executed by the module Livestatus in 0.143s |
Réception d'une commande
Warning
Si une commande inconnue est reçue, ce log sera affiché :
| Code Block | ||||
|---|---|---|---|---|
| ||||
[YYYY-MM-DD HH:MM:SS] WARNING : [ NOMBROKER_DU_BROKERNAME ] [ NOMMODULE_DU_MODULENAME ] [PID:PID_DU_PROCESSUS] Received unknown command [NOM_DE_LA_COMMANDE] from father process ! COMMAND CALL ] [ PID:XXXX ] [ THREAD_NAME ] Received unknown command [ COMMAND_NAME ] to execute ! |
| Code Block | |||||
|---|---|---|---|---|---|
| |||||
| |||||
[2020-11-17 09:12:11] WARNING : [ broker-master ] [ Livestatus ] [ COMMAND CALL ] [ PID:256429341 ] [ CP Server Thread-46 ] Received unknown command [get_module_info] fromto father processexecute ! |
Error
Si la commande crash, ce log sera affiché :
| Code Block | ||||
|---|---|---|---|---|
| ||||
[[YYYY-MM-DD HH:MM:SS] ERROR : [ NOM_DU_BROKERBROKER_NAME ] [ MODULE_NAME ] [ COMMAND CALL ] [ NOM_DU_MODULEPID:XXXX ] Our father process did send us the [ THREAD_NAME ] Failed to execute received command [NOM_DE_LA_COMMANDE] that did fail: TRACEBACK COMMAND_NAME ] with error: ERROR |
| Code Block | ||||
|---|---|---|---|---|
| Code Block | ||||
| ||||
| ||||
[2020-11-17 09:12:11] ERROR : [ broker-master ] [ Livestatus ] [ COMMAND CALL ] Our father process did send us the[ PID:29341 ] [ CP Server Thread-46 ] Failed to execute received command [get_module_info] thatwith diderror: fail:Exception [2020-11-17 09:12:11] ERROR : [ broker-master ] [ Livestatus ] Traceback[ (mostCOMMAND recentCALL call] last): [2020-11-17 09:12PID:29341 ] [ CP Server Thread-46 ] Traceback (most recent call last): [2020-11-17 09:12:11] ERROR : [ broker-master ] [ Livestatus ] [ COMMAND CALL ] [ FilePID:29341 ] [ CP Server Thread-46 ] File "C:\dev\workspace\shinken-enterprise\sources\framework\shinken\shinken\basesubprocess.py", line 117, in get_and_execute_command_from_master [2020-11-17 09:12:11] ERROR : [ broker-master ] [ Livestatus ] [ COMMAND CALL result] [ PID:29341 ] [ CP Server Thread-46 ] result = f() [2020-11-17 09:12:11] ERROR : [ broker-master ] [ Livestatus ] [ COMMAND CALL ] [ PID:29341 ] [ CP Server FileThread-46 ] File "C:\dev\workspace\shinken-enterprise\testing\test_command_queue_handler.py", line 43, in fail_command [2020-11-17 09:12:11] ERROR : [ broker-master ] [ Livestatus ] [ COMMAND CALL ] raise Exception [2020-11-17 09:PID:29341 ] [ CP Server Thread-46 ] raise Exception [2020-11-17 09:12:11] ERROR : [ broker-master ] [ Livestatus ] [ COMMAND CALL ] [ PID:29341 ] [ CP Server Thread-46 ] Exception |
Debug
Au moment de la réception de la commande, si cette dernière est exécutable, ce log sera affiché :
| Code Block | ||||
|---|---|---|---|---|
| ||||
[YYYY-MM-DD HH:MM:SS] DEBUG : [ NOM_DU_BROKERBROKER_NAME ] [ MODULE_NAME ] [ NOM_DU_MODULE COMMAND CALL ] [ PID:PID_DU_PROCESSUSXXXX ] [ THREAD_NAME ] Executing command [NOM_DE_LA_COMMANDE COMMAND_NAME ] with param LISTEPARAMETER_DES_PARAMETRESLIST |
| Code Block | ||||||
|---|---|---|---|---|---|---|
| ||||||
[2020-11-17 09:12:11] DEBUG : [ broker-master ] [ Livestatus ] [PID:2564] ExecutingCOMMAND command [get_module_infoCALL ] with param [][ PID:29341 ] [ CP Server Thread-46 ] Executing command [get_module_info] with param [] |
Envoie trop long d'un brok ( Sérialisation ) lors de l'envoi au module ( et ses workers )
Si le délai dépasse le seuil WARNING ( broker__manage_brok__oversized_data_warning_threshold__serialization_time ) le log sera afficher en WARNING.
Sinon si le délai dépasse le seuil ERROR ( broker__manage_brok__oversized_data_error_threshold__serialization_time ) le log sera afficher en ERROR.
( Voir la page Le Broker)
Log avec la taille des éléments variable
| Code Block | ||||
|---|---|---|---|---|
| ||||
[YYYY-MM-DD HH:MM:SS] LOG_LEVEL: [ BROKER_NAME ] [ MODULE_NAME ] [ MANAGE BROKS ] [ OVERSIZED DATA ] [ SIZE ] The brok of type "BROK_TYPE" (item uuid: ITEM_UUID) took too much time to be serialized [X.XXXs] (with size XXXXXXB) and may cause Brok management slow down. Size of potential expensive content: outputs size:XXXXXXB, current perf data size:XXXXXXB, downtimes user content size:XXXXXXB, acknowledgement user content size:XXXXXXB |
| Code Block | ||||||
|---|---|---|---|---|---|---|
| ||||||
[2025-07-23 17:57:54] WARNING: [ broker-master ] [ event-manager-writer ] [ MANAGE BROKS ] [ OVERSIZED DATA ] [ SIZE ] The brok of type "update_service_status" (item uuid: f2f32d781a324394aa9eab104b239b6e-b8c98c745b3411eb97fd080027d2cb3b) took too much time to be serialized [0.004s] (with size 170528B) and may cause Brok management slow down. Size of potential expensive content: outputs size:3518B, current perf data size:301B, downtimes user content size:0B, acknowledgement user content size:0B
[2025-07-23 17:57:54] ERROR: [ broker-master ] [ event-manager-writer ] [ MANAGE BROKS ] [ OVERSIZED DATA ] [ SIZE ] The brok of type "update_service_status" (item uuid: f2f32d781a324394aa9eab104b239b6e-b8c98c745b3411eb97fd080027d2cb3b) took too much time to be serialized [0.300s] (with size 250528B) and may cause Brok management slow down. Size of potential expensive content: outputs size:3518B, current perf data size:301B, downtimes user content size:0B, acknowledgement user content size:0B |
Log avec le nombre des éléments variable
| Code Block | ||||
|---|---|---|---|---|
| ||||
[YYYY-MM-DD HH:MM:SS] LOG_LEVEL: [ BROKER_NAME ] [ MODULE_NAME ] [ MANAGE BROKS ] [ OVERSIZED DATA ] [ DETAILS ] The brok of type "BROK_TYPE" (item uuid: ITEM_UUID) took too much time to be serialized [X.XXXs] (with size XXXXXXB) and may cause Brok management slow down. Detail of potential expensive content: total notifications nb:XXXXXX, incident nb:XXXXXX, parent dependencies (hosts) nb:XXXXXX, source problems nb:XXXXXX, parent dependencies (services) nb:XXXXXX, impacts (services) nb:XXXXXX, impacts (hosts) nb:XXXXXX, downtimes nb:XXXXXX, child dependencies (services) nb:XXXXXX, child dependencies (hosts) nb:XXXXXX |
| Code Block | ||||||
|---|---|---|---|---|---|---|
| ||||||
[2025-07-23 17:57:54] WARNING: [ broker-master ] [ event-manager-writer ] [ MANAGE BROKS ] [ OVERSIZED DATA ] [ DETAILS ] The brok of type "update_service_status" (item uuid: f2f32d781a324394aa9eab104b239b6e-b8c98c745b3411eb97fd080027d2cb3b) took too much time to be serialized [0.004s] (with size 170528B) and may cause Brok management slow down. Detail of potential expensive content: total notifications nb:3226, incident nb:2, parent dependencies (hosts) nb:1, source problems nb:0, parent dependencies (services) nb:0, impacts (services) nb:0, impacts (hosts) nb:0, downtimes nb:0, child dependencies (services) nb:0, child dependencies (hosts) nb:0
[2025-07-23 17:57:54] ERROR: [ broker-master ] [ event-manager-writer ] [ MANAGE BROKS ] [ OVERSIZED DATA ] [ DETAILS ] The brok of type "update_service_status" (item uuid: f2f32d781a324394aa9eab104b239b6e-b8c98c745b3411eb97fd080027d2cb3b) took too much time to be serialized [0.300s] (with size 250528B) and may cause Brok management slow down. Detail of potential expensive content: total notifications nb:3226, incident nb:2, parent dependencies (hosts) nb:1, source problems nb:0, parent dependencies (services) nb:0, impacts (services) nb:0, impacts (hosts) nb:0, downtimes nb:0, child dependencies (services) nb:0, child dependencies (hosts) nb:0 |