Some times doesn't send Custom Event

What is the problem?

Can anyone explain me with this log why sometimes octoprint doens't send me the custom event MetadataAnalysisFinished?

Systeminfo Bundle

  INFO - Starting analysis of local:BLM_MiniSaberToothSabers.gcode
2023-11-27 09:43:12,045 - octoprint.plugins.webhooks - INFO - event: FileAdded
2023-11-27 09:43:12,046 - octoprint.plugins.webhooks - INFO - payload: {'storage': 'local', 'path': 'BLM_MiniSaberToothSabers.gcode', 'name': 'BLM_MiniSaberToothSabers.gcode', 'type': ['machinecode', 'gcode'], 'operation': 'add'}
2023-11-27 09:43:12,046 - octoprint.plugins.webhooks - INFO - event: FileAdded
2023-11-27 09:43:12,046 - octoprint.plugins.webhooks - INFO - payload: {'storage': 'local', 'path': 'BLM_MiniSaberToothSabers.gcode', 'name': 'BLM_MiniSaberToothSabers.gcode', 'type': ['machinecode', 'gcode'], 'operation': 'add'}
2023-11-27 09:43:12,052 - octoprint.plugins.webhooks - INFO - event: UpdatedFiles
2023-11-27 09:43:12,054 - octoprint.plugins.webhooks - INFO - payload: {'type': 'printables'}
2023-11-27 09:43:12,058 - octoprint.plugins.webhooks - INFO - event: UpdatedFiles
2023-11-27 09:43:12,059 - octoprint.plugins.webhooks - INFO - payload: {'type': 'printables'}
2023-11-27 09:43:12,064 - octoprint.plugins.webhooks - INFO - event: MetadataAnalysisStarted
2023-11-27 09:43:12,066 - octoprint.plugins.webhooks - INFO - payload: {'name': 'BLM_MiniSaberToothSabers.gcode', 'path': 'BLM_MiniSaberToothSabers.gcode', 'origin': 'local', 'type': 'gcode'}
2023-11-27 09:43:12,067 - octoprint.plugins.webhooks - INFO - event: MetadataAnalysisStarted
2023-11-27 09:43:12,069 - octoprint.plugins.webhooks - INFO - payload: {'name': 'BLM_MiniSaberToothSabers.gcode', 'path': 'BLM_MiniSaberToothSabers.gcode', 'origin': 'local', 'type': 'gcode'}
2023-11-27 09:43:12,073 - octoprint.plugins.SlicerSettingsParser - INFO - Analyzing file: BLM_MiniSaberToothSabers.gcode
2023-11-27 09:43:12,893 - octoprint.plugins.SlicerSettingsParser - INFO - Saved slicer settings metadata for file: BLM_MiniSaberToothSabers.gcode
2023-11-27 09:43:12,895 - octoprint.plugins.webhooks - INFO - event: Upload
2023-11-27 09:43:12,895 - octoprint.plugins.webhooks - INFO - payload: {'name': 'BLM_MiniSaberToothSabers.gcode', 'path': 'BLM_MiniSaberToothSabers.gcode', 'target': 'local', 'select': True, 'print': False, 'effective_select': False, 'effective_print': False}
2023-11-27 09:43:12,895 - octoprint.plugins.webhooks - INFO - event: Upload
2023-11-27 09:43:12,895 - octoprint.plugins.webhooks - INFO - payload: {'name': 'BLM_MiniSaberToothSabers.gcode', 'path': 'BLM_MiniSaberToothSabers.gcode', 'target': 'local', 'select': True, 'print': False, 'effective_select': False, 'effective_print': False}
2023-11-27 09:43:12,899 - octoprint.plugins.webhooks - INFO - Skipping event 'MetadataAnalysisFinished' (last event 942 seconds ago - cooldown of 1800 seconds not elapsed)
2023-11-27 09:43:12,902 - octoprint.plugins.webhooks - INFO - Skipping event 'MetadataAnalysisFinished' (last event 942 seconds ago - cooldown of 1800 seconds not elapsed)
2023-11-27 09:43:13,436 - octoprint.plugins.webhooks - INFO - Skipping event 'MetadataAnalysisFinished' (last event 943 seconds ago - cooldown of 1800 seconds not elapsed)
2023-11-27 09:43:13,436 - octoprint.plugins.webhooks - INFO - Skipping event 'MetadataAnalysisFinished' (last event 943 seconds ago - cooldown of 1800 seconds not elapsed)
2023-11-27 09:43:13,480 - octoprint.filemanager.analysis - INFO - Analysis of entry local:BLM_MiniSaberToothSabers.gcode finished, needed 1.48s
2023-11-27 09:43:13,503 - octoprint.plugins.webhooks - INFO - Skipping event 'MetadataAnalysisFinished' (last event 943 seconds ago - cooldown of 1800 seconds not elapsed)
2023-11-27 09:43:13,503 - octoprint.plugins.webhooks - INFO - Skipping event 'MetadataAnalysisFinished' (last event 943 seconds ago - cooldown of 1800 seconds not elapsed)
2023-11-27 09:51:47,450 - octoprint.server.heartbeat - INFO - Server heartbeat <3

OctoPrint is sending the event, and the webhooks plugin is seeing this. But it decides not to do anything because it wants to wait at least 1800 seconds since it last did something, but it has only been 942 seconds so far.

1800 seconds could be something that's configurable in the plugin.

1 Like

This topic was automatically closed 90 days after the last reply. New replies are no longer allowed.