logging.warning does not appear to be working

[cmccully]

It appears warnings.warn warnings are showing up correctly. Checking the highlighted frame in my tests showed that WCSERR=4 so https://github.com/LCOGT/banzai/blob/main/banzai/photometry.py#L300 should have printed a log message but it does not exist.

[cmccully]

Noticed this error during logging. Might be related:
2024-08-16 14:06:55.183 WARNING: log: --- Logging error ---
2024-08-16 14:06:55.686 WARNING: log: Traceback (most recent call last):
2024-08-16 14:06:55.687 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/logging/init.py", line 1100, in emit
msg = self.format(record)
2024-08-16 14:06:55.687 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/logging/init.py", line 943, in format
return fmt.format(record)
2024-08-16 14:06:55.687 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/lcogt_logging/lcogt_formatter.py", line 45, in format
json.dumps(tags)
2024-08-16 14:06:55.687 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/json/init.py", line 231, in dumps
return _default_encoder.encode(obj)
2024-08-16 14:06:55.687 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/json/encoder.py", line 199, in encode
chunks = self.iterencode(o, _one_shot=True)
2024-08-16 14:06:55.687 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/json/encoder.py", line 257, in iterencode
return _iterencode(o, 0)
2024-08-16 14:06:55.687 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/json/encoder.py", line 179, in default
raise TypeError(f'Object of type {o.class.name} '
2024-08-16 14:06:55.687 WARNING: log: TypeError: Object of type datetime is not JSON serializable
2024-08-16 14:06:55.687 WARNING: log: Call stack:
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/bin/celery", line 8, in
sys.exit(main())
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/celery/main.py", line 15, in main
sys.exit(_main())
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/celery/bin/celery.py", line 236, in main
return celery(auto_envvar_prefix="CELERY")
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/click/core.py", line 1157, in call
return self.main(*args, **kwargs)
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/click/core.py", line 1078, in main
rv = self.invoke(ctx)
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/click/core.py", line 1688, in invoke
return _process_result(sub_ctx.command.invoke(sub_ctx))
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/click/core.py", line 1434, in invoke
return ctx.invoke(self.callback, **ctx.params)
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/click/core.py", line 783, in invoke
return __callback(*args, **kwargs)
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/click/decorators.py", line 33, in new_func
return f(get_current_context(), *args, **kwargs)
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/celery/bin/base.py", line 135, in caller
return f(ctx, *args, **kwargs)
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/celery/bin/worker.py", line 356, in worker
worker.start()
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/celery/worker/worker.py", line 202, in start
self.blueprint.start(self)
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/celery/bootsteps.py", line 116, in start
step.start(parent)
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/celery/bootsteps.py", line 365, in start
return self.obj.start()
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/celery/concurrency/base.py", line 130, in start
self.on_start()
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/celery/concurrency/prefork.py", line 109, in on_start
P = self._pool = Pool(processes=self.limit,
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/celery/concurrency/asynpool.py", line 464, in init
super().init(processes, *args, **kwargs)
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/billiard/pool.py", line 1045, in init
self._create_worker_process(i)
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/celery/concurrency/asynpool.py", line 482, in _create_worker_process
return super()._create_worker_process(i)
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/billiard/pool.py", line 1157, in _create_worker_process
w.start()
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/billiard/process.py", line 120, in start
self._popen = self._Popen(self)
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/billiard/context.py", line 331, in _Popen
return Popen(process_obj)
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/billiard/popen_fork.py", line 22, in init
self._launch(process_obj)
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/billiard/popen_fork.py", line 77, in _launch
code = process_obj._bootstrap()
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/billiard/process.py", line 323, in _bootstrap
self.run()
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/billiard/process.py", line 110, in run
self._target(*self._args, **self._kwargs)
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/billiard/pool.py", line 291, in call
sys.exit(self.workloop(pid=pid))
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/billiard/pool.py", line 361, in workloop
result = (True, prepare_result(fun(*args, **kwargs)))
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/celery/app/trace.py", line 651, in fast_trace_task
R, I, T, Rstr = tasks[task].trace(
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/celery/app/trace.py", line 453, in trace_task
R = retval = fun(*args, **kwargs)
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/celery/app/trace.py", line 736, in protected_call
return self.run(*args, **kwargs)
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/banzai/celery.py", line 166, in stack_calibrations
calibrations.make_master_calibrations(instrument, frame_type, min_date, max_date, runtime_context)
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/banzai/calibrations.py", line 193, in make_master_calibrations
stage_utils.run_pipeline_stages(calibration_frames_info, runtime_context, calibration_maker=True)
2024-08-16 14:06:55.689 WARNING: log: File "/home/archive/envs/banzai/lib/python3.10/site-packages/banzai/utils/stage_utils.py", line 60, in run_pipeline_stages
logger.error('Reduction stopped', extra_tags={'filename': image_paths})
2024-08-16 14:06:55.689 WARNING: log: Message: 'Reduction stopped'
Arguments: ()
2024-08-16 14:06:55.689 INFO: calibrations: Finished | {"processName": "ForkPoolWorker-1"}
2024-08-16 14:06:55.689 INFO: trace: Task celery.stack_calibrations[c8ea36d7-c105-46b8-ab0b-c7f3a51cca09] succeeded in 14.61367720097769s: None