abarrau/jeedom-plugins-doc

[iCalendar] La tâche plugin::cron30 n'arrive pas à finir à cause du plugin : iCalendar

Opened this issue · 14 comments

"La tâche plugin::cron30 n'arrive pas à finir à cause du plugin : iCalendar nous vous conseillons de désactiver le plugin et de contacter l'auteur"

revient régulièrement, toutes les demi-heures. Que faire ?

BONJOUR,

Avec si peu d'information c'est un peu dure de vous aider :
Est-il possible de :

  • me communiquer, lorsque le cas se présente, les log : du plugin, cron, cron_execution, http.error, olindoteTools.
  • me préciser :
    - combien avez-vous de calendier ?
    - comment sont-ils configurés (traitement des scénario via le plugin ou en déclenchement d'évènement ?)
    - la version du plugin, de Jeedom, d'apache

Après je pourrais commencer à regarder.
Merci, Cdt,
Aurélien

Bonjour,

Je ne vois pas les fichiers de log dans votre réponse ; j'ai l'impression que vous avez répondu via mail et non via Github.
Merci de m'envoyer au moins iCalendar via Github.

Le CRON30 ne réalise qu'une récupération de calendrier, donc si on arrive a dépasser la minute cela signifie qu'il y a peut-être un problème de connexion et récupération des données au niveau du serveur distant.

L'autre explication pourrait aussi être un plugin en amont, qui prend bcq de tout (j'ai déjà eu ce cas chez d'autres personnes) et qu'au moment où on arrive à iCalendar le temps soit attend.

En l'état difficile d'en dire plus.

Ce serveur hébergé chez vous. Je peux y avoir accès ?
Pour faire un test à mon niveau ?

Merci,
Aurélien

Merci Aurélien pour ce retour,
en effet, j'ai répondu par mail, je ne suis pas un pro de Github. J'y avais joint les fichiers de logs, je les détaille donc ci-après :

(logs du plug-in depuis minuit, aujourd'hui) :

0436|[2023-01-16 00:00:04]WARNING : [|220|2673] formatRangeEvents() ERROR with formated events array ! // STOP
0437|[2023-01-16 00:00:04]WARNING : [|220|2674] formatRangeEvents() ERROR with formated events array ! // STOP
0438|[2023-01-16 00:00:04]WARNING : [|220|2676] formatRangeEvents() ERROR with formated events array ! // STOP
0439|[2023-01-16 09:22:05]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.017)
0440|[2023-01-16 09:23:04]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.021)
0441|[2023-01-16 09:24:04]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.018)
0442|[2023-01-16 09:25:06]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.021)
0443|[2023-01-16 09:26:04]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.021)
0444|[2023-01-16 09:27:05]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.018)
0445|[2023-01-16 09:28:05]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.021)
0446|[2023-01-16 09:29:06]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.019)
0447|[2023-01-16 09:30:04]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.033)
0448|[2023-01-16 09:31:04]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.018)
0449|[2023-01-16 09:32:04]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.018)
0450|[2023-01-16 09:33:04]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.018)
0451|[2023-01-16 09:34:05]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.019)
0452|[2023-01-16 09:35:05]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.021)
0453|[2023-01-16 09:36:05]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.022)
0454|[2023-01-16 09:37:05]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.022)
0455|[2023-01-16 09:38:04]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.023)
0456|[2023-01-16 09:39:05]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.018)
0457|[2023-01-16 09:40:05]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.033)
0458|[2023-01-16 09:41:06]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.02)
0459|[2023-01-16 09:42:05]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.018)
0460|[2023-01-16 09:43:06]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.018)
0461|[2023-01-16 09:44:04]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.018)
0462|[2023-01-16 09:45:05]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.021)
0463|[2023-01-16 09:46:05]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.018)
0464|[2023-01-16 09:47:06]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.019)
0465|[2023-01-16 09:48:04]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.018)
0466|[2023-01-16 09:49:04]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.019)
0467|[2023-01-16 09:50:05]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.026)
0468|[2023-01-16 09:51:06]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.018)
0469|[2023-01-16 09:52:04]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.018)
0470|[2023-01-16 09:53:04]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.02)
0471|[2023-01-16 09:54:05]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.018)
0472|[2023-01-16 09:55:06]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.019)
0473|[2023-01-16 09:56:04]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.018)
0474|[2023-01-16 09:57:05]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.019)
0475|[2023-01-16 09:58:05]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.018)
0476|[2023-01-16 09:59:05]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.021)
0477|[2023-01-16 10:00:06]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.035)
0478|[2023-01-16 10:01:04]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.018)
0479|[2023-01-16 10:02:06]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.019)
0480|[2023-01-16 10:03:04]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.021)
0481|[2023-01-16 10:04:04]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.018)
0482|[2023-01-16 10:05:05]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.024)
0483|[2023-01-16 10:06:05]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.023)
0484|[2023-01-16 10:07:03]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.021)
0485|[2023-01-16 10:08:05]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.019)
0486|[2023-01-16 10:09:05]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.018)
0487|[2023-01-16 10:10:05]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.026)
0488|[2023-01-16 10:11:04]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.022)
0489|[2023-01-16 10:12:05]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.022)
0490|[2023-01-16 10:13:04]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.021)
0491|[2023-01-16 10:14:04]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.018)
0492|[2023-01-16 10:15:06]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.018)
0493|[2023-01-16 10:16:06]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.021)
0494|[2023-01-16 10:17:04]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.021)
0495|[2023-01-16 10:18:04]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.02)
0496|[2023-01-16 10:19:03]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.021)
0497|[2023-01-16 10:20:05]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.026)
0498|[2023-01-16 10:21:05]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.021)
0499|[2023-01-16 10:22:05]INFO : [WIDGET/dashboard|220] toHtml(dashboard) Refresh Widget (3 cal.): OK (0.019)

(logs du http.error)

0000|[Wed Jan 11 22:08:46.210061 2023] [php7:notice] [pid 20281] [client 192.168.1.22:53539] PHP Notice: Undefined offset: 1 in /var/www/html/plugins/sonoffdiy/core/class/sonoffdiy.class.php on line 1408, referer: http://192.168.1.20/index.php?v=d&p=dashboard
0001|[Wed Jan 11 22:08:46.210285 2023] [php7:notice] [pid 20281] [client 192.168.1.22:53539] PHP Notice: Undefined offset: 1 in /var/www/html/plugins/sonoffdiy/core/class/sonoffdiy.class.php on line 1409, referer: http://192.168.1.20/index.php?v=d&p=dashboard
0002|[Wed Jan 11 22:08:46.210358 2023] [php7:notice] [pid 20281] [client 192.168.1.22:53539] PHP Notice: Undefined offset: 1 in /var/www/html/plugins/sonoffdiy/core/class/sonoffdiy.class.php on line 1410, referer: http://192.168.1.20/index.php?v=d&p=dashboard
0003|[Wed Jan 11 22:08:46.210428 2023] [php7:notice] [pid 20281] [client 192.168.1.22:53539] PHP Notice: Undefined offset: 1 in /var/www/html/plugins/sonoffdiy/core/class/sonoffdiy.class.php on line 1411, referer: http://192.168.1.20/index.php?v=d&p=dashboard
0004|[Wed Jan 11 22:08:46.386229 2023] [php7:notice] [pid 20281] [client 192.168.1.22:53539] PHP Notice: Undefined offset: 1 in /var/www/html/plugins/sonoffdiy/core/class/sonoffdiy.class.php on line 1408, referer: http://192.168.1.20/index.php?v=d&p=dashboard
0005|[Wed Jan 11 22:08:46.386377 2023] [php7:notice] [pid 20281] [client 192.168.1.22:53539] PHP Notice: Undefined offset: 1 in /var/www/html/plugins/sonoffdiy/core/class/sonoffdiy.class.php on line 1409, referer: http://192.168.1.20/index.php?v=d&p=dashboard
0006|[Wed Jan 11 22:08:46.386452 2023] [php7:notice] [pid 20281] [client 192.168.1.22:53539] PHP Notice: Undefined offset: 1 in /var/www/html/plugins/sonoffdiy/core/class/sonoffdiy.class.php on line 1410, referer: http://192.168.1.20/index.php?v=d&p=dashboard
0007|[Wed Jan 11 22:08:46.386522 2023] [php7:notice] [pid 20281] [client 192.168.1.22:53539] PHP Notice: Undefined offset: 1 in /var/www/html/plugins/sonoffdiy/core/class/sonoffdiy.class.php on line 1411, referer: http://192.168.1.20/index.php?v=d&p=dashboard
0008|[Wed Jan 11 22:08:46.421640 2023] [php7:notice] [pid 20281] [client 192.168.1.22:53539] PHP Notice: Undefined index: fwVersion in /var/www/html/plugins/sonoffdiy/core/class/sonoffdiy.class.php on line 704, referer: http://192.168.1.20/index.php?v=d&p=dashboard
0009|[12-Jan-2023 08:37:19 Europe/Brussels] PHP Notice: Undefined variable: eqLogic in /var/www/html/plugins/autologin/desktop/php/autologin.php on line 118
0010|[Thu Jan 12 08:38:04.591640 2023] [php7:warn] [pid 3388] [client 192.168.1.22:54401] PHP Warning: Use of undefined constant path - assumed 'path' (this will throw an Error in a future version of PHP) in /var/www/html/plugins/autologin/core/class/autologin.class.php on line 39, referer: http://192.168.1.20/index.php?v=d&m=autologin&p=autologin&id=222
0011|[Thu Jan 12 08:38:04.591806 2023] [php7:warn] [pid 3388] [client 192.168.1.22:54401] PHP Warning: Use of undefined constant path - assumed 'path' (this will throw an Error in a future version of PHP) in /var/www/html/plugins/autologin/core/class/autologin.class.php on line 39, referer: http://192.168.1.20/index.php?v=d&m=autologin&p=autologin&id=222
0012|[Thu Jan 12 08:38:04.591882 2023] [php7:warn] [pid 3388] [client 192.168.1.22:54401] PHP Warning: Use of undefined constant query - assumed 'query' (this will throw an Error in a future version of PHP) in /var/www/html/plugins/autologin/core/class/autologin.class.php on line 39, referer: http://192.168.1.20/index.php?v=d&m=autologin&p=autologin&id=222
0013|[Thu Jan 12 08:38:04.591951 2023] [php7:notice] [pid 3388] [client 192.168.1.22:54401] PHP Notice: Undefined index: query in /var/www/html/plugins/autologin/core/class/autologin.class.php on line 39, referer: http://192.168.1.20/index.php?v=d&m=autologin&p=autologin&id=222
0014|[Sun Jan 15 09:42:07.781801 2023] [core:notice] [pid 628] AH00052: child pid 23581 exit signal Segmentation fault (11)
0015|[Sun Jan 15 09:42:08.785292 2023] [mpm_prefork:notice] [pid 628] AH00169: caught SIGTERM, shutting down
0016|[Sun Jan 15 09:42:27.725297 2023] [mpm_prefork:notice] [pid 626] AH00163: Apache/2.4.38 (Raspbian) configured -- resuming normal operations
0017|[Sun Jan 15 09:42:27.728174 2023] [core:notice] [pid 626] AH00094: Command line: '/usr/sbin/apache2'
0018|[Sun Jan 15 09:42:28.186748 2023] [php7:error] [pid 653] [client 192.168.1.22:53575] PHP Fatal error: Uncaught Error: Class 'ajax' not found in /var/www/html/core/ajax/event.ajax.php:36
0019|Stack trace:
0020|#0 {main}
0021|thrown in /var/www/html/core/ajax/event.ajax.php on line 36, referer: http://192.168.1.20/index.php?v=d&p=reboot
0022|[Mon Jan 16 10:48:32.503721 2023] [php7:error] [pid 5616] [client 127.0.0.1:55218] PHP Fatal error: Uncaught Exception: [MySQL] Error code : 40001 (1213). Deadlock found when trying to get lock; try restarting transaction : REPLACE INTO history
0023|SET cmd_id=:cmd_id,
0024|datetime=:datetime,
0025|value=:value in /var/www/html/core/class/DB.class.php:102
0026|Stack trace:
0027|#0 /var/www/html/core/class/history.class.php(988): DB::Prepare('REPLACE INTO hi...', Array, 0)
0028|#1 /var/www/html/core/class/cmd.class.php(2296): history->save(Object(deconzCmd))
0029|#2 /var/www/html/core/class/cmd.class.php(1782): cmd->addHistoryValue(54.96, '2023-01-16 10:4...')
0030|#3 /var/www/html/core/class/eqLogic.class.php(645): cmd->event(5496, '2023-01-16 10:4...')
0031|#4 /var/www/html/plugins/deconz/core/class/deconz.class.php(794): eqLogic->checkAndUpdateCmd(Object(deconzCmd), 5496, '2023-01-16 10:4...')
0032|#5 /var/www/html/plugins/deconz/core/php/jeeDeconz.php(65): deconz->applyData(Array)
0033|#6 {main}
0034|thrown in /var/www/html/core/class/DB.class.php on line 102
0035|warning: commands will be executed using /bin/sh
0036|job 69469 at Mon Jan 16 11:50:00 2023

Malheureusement, et c'est dû au mécanisme de la 4G et du CGNAT, je ne peux me connecter à mon système depuis l'extérieur, car je n'ai pas d'IP publique. Ce sera donc impossible pour vous de vous connecter à mon Jeedom autrement qui via le LAN. Je sais bien qu'il existe des soutions de VPN mais cela dépasse mes capacités.

Ce que je voulais, par le truchement de votre plug-in, c'est que Jeedom récupère une valeur que j'inscris dans mon calendrier et l'utilise dans un scenario adéquat. Une manière distante d'instruire cette domotique "piégée" localement. Je ne sais pas si c'est un bon choix.

F.V.,
Je ne vois dans la log "http.error", par contre vous avez de pas belles erreurs ... il faudrait s'y pencher dessus ...

Pour ce qui est de la log "iCalendar", vous devez être en mode INFO et non en mode DEBUG.
Passez en mode début et attendez que la CRON30 s'exécute et envoyez moi.
Par contre, il faudra le mettre en pj dans github et non en copier/coller (car ça va être volumineux).

merci, Aurélien

Bonjour Aurélien,

voici joints les logs de la journée d'hier et de la nuit, après avoir passé les logs en INFO, comme demandé, ainsi qu'une copie écran du "centre des messages".

Bonjour,
Je crois qu'il y a eu incompréhension.
J'indiquais qu'il fallait passer en mode DEBUG (car vous aviez dut m'envoyer les log en INFO, car je ne voyais rien d'autre).
Donc il me fait les log en DEBUG, merci.
Aurélien

Aurélien, malheureusement, je dois quitter l'endroit de l'installation aujourd"hiu, aussi je vous enverrai ce que vous demandiez jusqu'à ce moment et après je desactiverai le plug-in pour éviter les erreurs durant mon absence.

Au moment du départ, voici les dernières infos disponibles.

Capture d’écran 2023-01-17 à 15 31 34
iCalendar.txt
http.error.txt

Bjr,
ok merci pour les infos. Par contre, il n'y a strictement rien de log qui m'aide à comprendre ce qui se passe.
Les log s'arrêtent à l'heure de l'appel de la connexion ... il n'y a aucune autre log ensuite ; du coup un peu dure d'en déduire qqch :(
L'erreur affiché au niveau mes messages s'affiche à l'heure du test de connexion ...
La prochaine fois, merci de m'envoyer les copies d'écran de votre paramétrage du calendrier.
Merci,
Aurélien

Bonjour,
Avez-vous avancé sur votre problème ?
Cdt, Aurélien

Bonjour,
Avez-vous avancé sur votre problème ?
Cdt, Aurélien