Please switch from printing to logging

[jjeff07]

Can you please update this to use logging and not printing? I am automating the sync using Python and all my logging is out putting to JSON but since this is printing I have a mix of log formats now.

[minitriga]

I'm looking into this ;)

[minitriga]

@jjeff07 can you test out my feature branch to see if thats better?

[jjeff07]

Before:

{"levelname": "INFO", "asctime": "2026-02-27 07:41:55,533", "name": "src.infrahub", "message": "Load IP Fabric Data", "thread": 15516, "status": "started", "snapshot_id": "$last"}
Load: Importing data from IPFabricsync
Loading LocationMetro
IPFabricsync: Loading 1/1 tables/inventory/sites
Loading LocationSite
IPFabricsync: Loading 1/1 tables/inventory/sites
Loading ManufacturerVendor
IPFabricsync: Loading 7/7 tables/inventory/summary/vendors
Loading ManufacturerFamily
IPFabricsync: Loading 10/10 tables/inventory/summary/families
Loading ManufacturerPlatform
IPFabricsync: Loading 26/26 tables/inventory/summary/platforms
Loading ManufacturerModel
IPFabricsync: Loading 35/35 tables/inventory/summary/models
Loading InfrastructureDeviceType
IPFabricsync: Loading 80/80 tables/inventory/devices
Loading InfrastructureDevice
IPFabricsync: Loading 80/80 tables/inventory/devices
{"levelname": "INFO", "asctime": "2026-02-27 07:41:59,923", "name": "src.infrahub", "message": "Load IP Fabric Data", "thread": 15516, "status": "completed", "snapshot_id": "$last"}
{"levelname": "INFO", "asctime": "2026-02-27 07:41:59,923", "name": "src.infrahub", "message": "Load Infrahub Data", "thread": 15516, "status": "started"}
Load: Importing data from Infrahub
Loading LocationMetro
Infrahub: Loading all 1 LocationMetro
Loading LocationSite
Infrahub: Loading all 1 LocationSite
Loading ManufacturerVendor
Infrahub: Loading all 7 ManufacturerVendor
Loading ManufacturerFamily
Infrahub: Loading all 10 ManufacturerFamily
Loading ManufacturerPlatform
Infrahub: Loading all 26 ManufacturerPlatform
Loading ManufacturerModel
Infrahub: Loading all 35 ManufacturerModel
Loading InfrastructureDeviceType
Infrahub: Loading all 6 InfrastructureDeviceType
Loading InfrastructureDevice
Infrahub: Loading all 80 InfrastructureDevice
{"levelname": "INFO", "asctime": "2026-02-27 07:42:01,390", "name": "src.infrahub", "message": "Load Infrahub Data", "thread": 15516, "status": "completed"}
{"levelname": "INFO", "asctime": "2026-02-27 07:42:01,390", "name": "src.infrahub", "message": "Calculating Diffs", "thread": 15516, "status": "started"}
Diff: Comparing data from IPFabricsync to Infrahub
{"levelname": "INFO", "asctime": "2026-02-27 07:42:01,390", "name": "diffsync.helpers", "message": "\u001b[2m2026-02-27 12:42.01\u001b[0m [\u001b[32m\u001b[1minfo     \u001b[0m] \u001b[1mBeginning diff calculation    \u001b[0m [\u001b[0m\u001b[1m\u001b[34mdiffsync.helpers\u001b[0m]\u001b[0m \u001b[36mdst\u001b[0m=\u001b[35m<Infrahub>\u001b[0m \u001b[36mflags\u001b[0m=\u001b[35m<DiffSyncFlags.SKIP_UNMATCHED_DST: 4>\u001b[0m \u001b[36msrc\u001b[0m=\u001b[35m<IPFabricsync>\u001b[0m", "thread": 15516}
{"levelname": "INFO", "asctime": "2026-02-27 07:42:01,412", "name": "diffsync.helpers", "message": "\u001b[2m2026-02-27 12:42.01\u001b[0m [\u001b[32m\u001b[1minfo     \u001b[0m] \u001b[1mDiff calculation complete     \u001b[0m [\u001b[0m\u001b[1m\u001b[34mdiffsync.helpers\u001b[0m]\u001b[0m \u001b[36mdst\u001b[0m=\u001b[35m<Infrahub>\u001b[0m \u001b[36mflags\u001b[0m=\u001b[35m<DiffSyncFlags.SKIP_UNMATCHED_DST: 4>\u001b[0m \u001b[36msrc\u001b[0m=\u001b[35m<IPFabricsync>\u001b[0m", "thread": 15516}
{"levelname": "INFO", "asctime": "2026-02-27 07:42:01,412", "name": "src.infrahub", "message": "Calculating Diffs", "thread": 15516, "status": "completed"}
{"levelname": "INFO", "asctime": "2026-02-27 07:42:01,413", "name": "src.api", "message": "Processing snapshot event.", "thread": 15516, "event_db_id": null, "snapshot_id": "$last"}

After:

{"levelname": "INFO", "asctime": "2026-02-27 07:54:04,485", "name": "src.infrahub", "message": "Load IP Fabric Data", "thread": 17832, "status": "started", "snapshot_id": "$last"}
{"levelname": "INFO", "asctime": "2026-02-27 07:54:05,085", "name": "infrahub_sync.adapters.ipfabricsync", "message": "IPFabricsync: Loading 1/1 tables/inventory/sites", "thread": 17832}
{"levelname": "INFO", "asctime": "2026-02-27 07:54:05,620", "name": "infrahub_sync.adapters.ipfabricsync", "message": "IPFabricsync: Loading 1/1 tables/inventory/sites", "thread": 17832}
{"levelname": "INFO", "asctime": "2026-02-27 07:54:06,135", "name": "infrahub_sync.adapters.ipfabricsync", "message": "IPFabricsync: Loading 7/7 tables/inventory/summary/vendors", "thread": 17832}
{"levelname": "INFO", "asctime": "2026-02-27 07:54:06,646", "name": "infrahub_sync.adapters.ipfabricsync", "message": "IPFabricsync: Loading 10/10 tables/inventory/summary/families", "thread": 17832}
{"levelname": "INFO", "asctime": "2026-02-27 07:54:07,170", "name": "infrahub_sync.adapters.ipfabricsync", "message": "IPFabricsync: Loading 26/26 tables/inventory/summary/platforms", "thread": 17832}
{"levelname": "INFO", "asctime": "2026-02-27 07:54:07,685", "name": "infrahub_sync.adapters.ipfabricsync", "message": "IPFabricsync: Loading 35/35 tables/inventory/summary/models", "thread": 17832}
{"levelname": "INFO", "asctime": "2026-02-27 07:54:08,209", "name": "infrahub_sync.adapters.ipfabricsync", "message": "IPFabricsync: Loading 80/80 tables/inventory/devices", "thread": 17832}
{"levelname": "INFO", "asctime": "2026-02-27 07:54:08,727", "name": "infrahub_sync.adapters.ipfabricsync", "message": "IPFabricsync: Loading 80/80 tables/inventory/devices", "thread": 17832}
{"levelname": "INFO", "asctime": "2026-02-27 07:54:08,782", "name": "src.infrahub", "message": "Load IP Fabric Data", "thread": 17832, "status": "completed", "snapshot_id": "$last"}
{"levelname": "INFO", "asctime": "2026-02-27 07:54:08,782", "name": "src.infrahub", "message": "Load Infrahub Data", "thread": 17832, "status": "started"}
{"levelname": "INFO", "asctime": "2026-02-27 07:54:08,809", "name": "infrahub_sync.adapters.infrahub", "message": "Infrahub: Loading all 1 LocationMetro", "thread": 17832}
{"levelname": "INFO", "asctime": "2026-02-27 07:54:08,850", "name": "infrahub_sync.adapters.infrahub", "message": "Infrahub: Loading all 1 LocationSite", "thread": 17832}
{"levelname": "INFO", "asctime": "2026-02-27 07:54:08,877", "name": "infrahub_sync.adapters.infrahub", "message": "Infrahub: Loading all 7 ManufacturerVendor", "thread": 17832}
{"levelname": "INFO", "asctime": "2026-02-27 07:54:08,925", "name": "infrahub_sync.adapters.infrahub", "message": "Infrahub: Loading all 10 ManufacturerFamily", "thread": 17832}
{"levelname": "INFO", "asctime": "2026-02-27 07:54:09,019", "name": "infrahub_sync.adapters.infrahub", "message": "Infrahub: Loading all 26 ManufacturerPlatform", "thread": 17832}
{"levelname": "INFO", "asctime": "2026-02-27 07:54:09,162", "name": "infrahub_sync.adapters.infrahub", "message": "Infrahub: Loading all 35 ManufacturerModel", "thread": 17832}
{"levelname": "INFO", "asctime": "2026-02-27 07:54:09,209", "name": "infrahub_sync.adapters.infrahub", "message": "Infrahub: Loading all 6 InfrastructureDeviceType", "thread": 17832}
{"levelname": "INFO", "asctime": "2026-02-27 07:54:09,953", "name": "infrahub_sync.adapters.infrahub", "message": "Infrahub: Loading all 80 InfrastructureDevice", "thread": 17832}
{"levelname": "INFO", "asctime": "2026-02-27 07:54:09,955", "name": "src.infrahub", "message": "Load Infrahub Data", "thread": 17832, "status": "completed"}
{"levelname": "INFO", "asctime": "2026-02-27 07:54:09,955", "name": "src.infrahub", "message": "Calculating Diffs", "thread": 17832, "status": "started"}
2026-02-27 07:54:09 [debug    ] Diff calculation between these two datasets will involve 332 models dst=<Infrahub> flags=<DiffSyncFlags.SKIP_UNMATCHED_DST: 4> src=<IPFabricsync>
2026-02-27 07:54:09 [info     ] Beginning diff calculation     dst=<Infrahub> flags=<DiffSyncFlags.SKIP_UNMATCHED_DST: 4> src=<IPFabricsync>
2026-02-27 07:54:09 [info     ] Diff calculation complete      dst=<Infrahub> flags=<DiffSyncFlags.SKIP_UNMATCHED_DST: 4> src=<IPFabricsync>
{"levelname": "INFO", "asctime": "2026-02-27 07:54:09,961", "name": "src.infrahub", "message": "Calculating Diffs", "thread": 17832, "status": "completed"}
{"levelname": "INFO", "asctime": "2026-02-27 07:54:09,962", "name": "src.infrahub", "message": "Syncing diffs to Infrahub", "thread": 17832, "status": "Dry run - diffs not synced"}
{"levelname": "INFO", "asctime": "2026-02-27 07:54:09,962", "name": "src.infrahub", "message": "IP Fabric -> Infrahub Sync", "thread": 17832, "status": "completed"}
{"levelname": "INFO", "asctime": "2026-02-27 07:54:09,962", "name": "src.api", "message": "Processing snapshot event.", "thread": 17832, "event_db_id": null, "snapshot_id": "$last"}

Looks Awesome!