[ https://issues.apache.org/jira/browse/BEAM-8333?focusedWorklogId=325419&page=com.atlassian.jira.plugin.system.issuetabpanels:worklog-tabpanel#worklog-325419 ]
ASF GitHub Bot logged work on BEAM-8333: ---------------------------------------- Author: ASF GitHub Bot Created on: 09/Oct/19 01:22 Start Date: 09/Oct/19 01:22 Worklog Time Spent: 10m Work Description: pabloem commented on pull request #9746: [BEAM-8333] Adding lull logging for SDK harness URL: https://github.com/apache/beam/pull/9746#discussion_r332796401 ########## File path: sdks/python/apache_beam/runners/worker/sdk_worker.py ########## @@ -403,10 +412,35 @@ def process_bundle_split(self, request, instruction_id): instruction_id=instruction_id, error='Instruction not running: %s' % instruction_id) + def _log_lull_in_bundle_processor(self, processor): + state_sampler = processor.state_sampler + sampler_info = state_sampler.get_info() + if (sampler_info + and sampler_info.time_since_transition + and sampler_info.time_since_transition > self.log_lull_timeout_ns): + step_name = sampler_info.state_name.step_name + state_name = sampler_info.state_name.name + state_lull_log = ( + 'There has been a processing lull of over %.2f seconds in state %s' + % (sampler_info.time_since_transition / 1e9, state_name)) + step_name_log = (' in step %s ' % step_name) if step_name else '' + + exec_thread = getattr(sampler_info, 'tracked_thread', None) + if exec_thread is not None: + thread_frame = sys._current_frames().get(exec_thread.ident) # pylint: disable=protected-access + stack_trace = '\n'.join( + traceback.format_stack(thread_frame)) if thread_frame else '' + else: + stack_trace = '-NOT AVAILABLE-' + + logging.warning( + '%s%s. Traceback:\n%s', state_lull_log, step_name_log, stack_trace) + def process_bundle_progress(self, request, instruction_id): # It is an error to get progress for a not-in-flight bundle. - processor = self.bundle_processor_cache.lookup( - request.instruction_id) + processor = self.bundle_processor_cache.lookup(request.instruction_id) + if processor: + self._log_lull_in_bundle_processor(processor) Review comment: I have not benchmarked this, but a similar operation is done on the python prod worker, and it has not been a problem. Furthermore, a progress update involves going through all of the user and system metrics, and building updates for them. This should be equivalent to adding one more metric, as speaking to the state sampler is lockless. ---------------------------------------------------------------- This is an automated message from the Apache Git Service. To respond to the message, please log on to GitHub and use the URL above to go to the specific comment. For queries about this service, please contact Infrastructure at: us...@infra.apache.org Issue Time Tracking ------------------- Worklog Id: (was: 325419) Time Spent: 1h 40m (was: 1.5h) > Python SDK Worker should log lulls with progress-reporting thread > ----------------------------------------------------------------- > > Key: BEAM-8333 > URL: https://issues.apache.org/jira/browse/BEAM-8333 > Project: Beam > Issue Type: Improvement > Components: sdk-py-harness > Reporter: Pablo Estrada > Assignee: Pablo Estrada > Priority: Major > Time Spent: 1h 40m > Remaining Estimate: 0h > -- This message was sent by Atlassian Jira (v8.3.4#803005)