[2021/12/03 21:06:22.865545, 10, pid=2219011, effective(0, 0), real(0, 0)] ../../source3/lib/util_event.c:100(event_add_idle)
  event_add_idle: idle_evt(print_queue_housekeeping) 0x55b2ee14b120
[2021/12/03 21:06:22.865593,  5, pid=2219011, effective(0, 0), real(0, 0)] ../../source3/lib/messages.c:723(messaging_register)
  Registering messaging pointer for type 33 - private_data=0x55b2ee11c890
[2021/12/03 21:06:22.865608,  5, pid=2219011, effective(0, 0), real(0, 0)] ../../source3/lib/messages.c:723(messaging_register)
  Registering messaging pointer for type 517 - private_data=(nil)
[2021/12/03 21:06:22.865620,  5, pid=2219011, effective(0, 0), real(0, 0)] ../../source3/lib/messages.c:771(messaging_deregister)
  Deregistering messaging pointer for type 515 - private_data=(nil)
[2021/12/03 21:06:22.865633,  5, pid=2219011, effective(0, 0), real(0, 0)] ../../source3/lib/messages.c:723(messaging_register)
  Registering messaging pointer for type 515 - private_data=(nil)
[2021/12/03 21:06:22.865655,  3, pid=2219011, effective(0, 0), real(0, 0)] ../../source3/printing/pcap.c:140(pcap_cache_reload)
  reloading printcap cache
[2021/12/03 21:06:22.865680,  5, pid=2219011, effective(0, 0), real(0, 0)] ../../source3/printing/print_cups.c:469(cups_pcap_load_async)
  cups_pcap_load_async: asynchronously loading cups printers
[2021/12/03 21:06:22.866961, 10, pid=2219011, effective(0, 0), real(0, 0)] ../../source3/printing/print_cups.c:485(cups_pcap_load_async)
  cups_pcap_load_async: child pid = 2219013
[2021/12/03 21:06:22.867036, 10, pid=2219011, effective(0, 0), real(0, 0)] ../../source3/printing/print_cups.c:606(cups_cache_reload)
  cups_cache_reload: async read on fd 13
[2021/12/03 21:06:22.867078,  3, pid=2219011, effective(0, 0), real(0, 0)] ../../source3/printing/pcap.c:194(pcap_cache_reload)
  reload status: ok
[2021/12/03 21:06:22.867105,  5, pid=2219011, effective(0, 0), real(0, 0)] ../../source3/printing/queue_process.c:419(start_background_queue)
  start_background_queue: background LPQ thread waiting for messages
[2021/12/03 21:06:22.867412, 10, pid=2219013, effective(0, 0), real(0, 0)] ../../lib/messaging/messages_dgm_ref.c:163(msg_dgm_ref_destructor)
  msg_dgm_ref_destructor: refs=(nil)
[2021/12/03 21:06:22.867595, 10, pid=2219013, effective(0, 0), real(0, 0)] ../../lib/messaging/messages_dgm_ref.c:80(messaging_dgm_ref)
  messaging_dgm_ref: messaging_dgm_init returned Success
[2021/12/03 21:06:22.867640, 10, pid=2219013, effective(0, 0), real(0, 0)] ../../lib/messaging/messages_dgm_ref.c:109(messaging_dgm_ref)
  messaging_dgm_ref: unique = 4253961467458087800
[2021/12/03 21:06:22.867660,  2, pid=2219013, effective(0, 0), real(0, 0)] ../../source3/lib/tallocmsg.c:84(register_msg_pool_usage)
  Registered MSG_REQ_POOL_USAGE
[2021/12/03 21:06:22.867682,  5, pid=2219013, effective(0, 0), real(0, 0)] ../../source3/printing/print_cups.c:338(cups_cache_reload_async)
  reloading cups printcap cache
[2021/12/03 21:06:22.868085, 10, pid=2219013, effective(0, 0), real(0, 0)] ../../source3/printing/print_cups.c:138(cups_connect)
  connecting to cups server localhost:631
[2021/12/03 21:06:46.963182,  5, pid=2219011, effective(0, 0), real(0, 0)] ../../source3/printing/print_cups.c:532(cups_async_callback)
  cups_async_callback: callback received for printer data. fd = 13
[2021/12/03 21:06:46.963744,  0, pid=2219011, effective(0, 0), real(0, 0)] ../../source3/printing/print_cups.c:537(cups_async_callback)
  failed to recv pcap blob
[2021/12/03 21:06:46.963827, 10, pid=2219013, effective(0, 0), real(0, 0)] ../../source3/printing/print_cups.c:191(send_pcap_blob)
  successfully sent blob of len 124144
[2021/12/03 21:06:46.964191, 10, pid=2219013, effective(0, 0), real(0, 0)] ../../lib/messaging/messages_dgm_ref.c:163(msg_dgm_ref_destructor)
  msg_dgm_ref_destructor: refs=(nil)
[2021/12/03 21:06:46.970805,  6, pid=2219011, effective(0, 0), real(0, 0)] ../../source3/printing/queue_process.c:265(bq_sig_chld_handler)
  Bq child process 2219013 terminated with 0