2026-05-28 09:31:38,954 - [56366] - src.utils.log - INFO - 📥 Inserting into ServiceCore.WorkOrderAutoGenerationPublishHistory: 2 rows in batches of 25000 (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:38,998 - [56366] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 2) into ServiceCore.WorkOrderAutoGenerationPublishHistory - __SPLIT__
2026-05-28 09:31:39,112 - [56366] - src.utils.log - INFO - ✅ All 2 rows inserted successfully into ServiceCore.WorkOrderAutoGenerationPublishHistory in 1 batches - __SPLIT__
2026-05-28 09:31:39,113 - [56366] - src.utils.log - INFO - 🔍 Fetching data:
SELECT
WorkOrderAutoGenerationPublishHistoryId,
WorkOrderNo,
ServiceSetupId
FROM ServiceCore.WorkOrderAutoGenerationPublishHistory
WHERE
ServiceSetupId = 106056 AND WorkOrderNo IN ('6467084', '8336764') AND GenerateWorkOrderHistoryId = 56366;... (Thread: ThreadPoolExecutor-40_0) - __SPLIT__
2026-05-28 09:31:39,172 - [56366] - src.utils.log - INFO - ✅ Fetched 2 rows - __SPLIT__
2026-05-28 09:31:39,172 - [56366] - src.utils.log - INFO - 🏁 Final merged rows: 2 - __SPLIT__
2026-05-28 09:31:39,176 - [56366] - src.utils.log - INFO - Sequential Insertion in work order tables begin - __SPLIT__
2026-05-28 09:31:39,176 - [56366] - src.utils.log - INFO - Checking if all rows exist in parent table or not - __SPLIT__
2026-05-28 09:31:39,177 - [56366] - src.utils.log - INFO - 📥 Inserting into ServiceCore.WorkOrderServices: 2 rows in batches of 25000 (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:39,207 - [56366] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 2) into ServiceCore.WorkOrderServices - __SPLIT__
2026-05-28 09:31:39,303 - [56366] - src.utils.log - INFO - ✅ All 2 rows inserted successfully into ServiceCore.WorkOrderServices in 1 batches - __SPLIT__
2026-05-28 09:31:39,304 - [56366] - src.utils.log - INFO - 📥 Empty dataframe of ServiceAuto.WorkOrderAppliedDiscount: - __SPLIT__
2026-05-28 09:31:39,304 - [56366] - src.utils.log - INFO - 📥 Empty dataframe of ServiceCore.WorkOrderSalesPersonCommission: - __SPLIT__
2026-05-28 09:31:39,304 - [56366] - src.utils.log - INFO - 📥 Empty dataframe of ServiceAuto.WorkOrderCommissionAdjustment: - __SPLIT__
2026-05-28 09:31:39,304 - [56366] - src.utils.log - INFO - Checking if all rows exist in parent table or not - __SPLIT__
2026-05-28 09:31:39,304 - [56366] - src.utils.log - INFO - 📥 Inserting into ServiceCore.WorkOrderCrew: 4 rows in batches of 25000 (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:39,333 - [56366] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 4) into ServiceCore.WorkOrderCrew - __SPLIT__
2026-05-28 09:31:39,429 - [56366] - src.utils.log - INFO - ✅ All 4 rows inserted successfully into ServiceCore.WorkOrderCrew in 1 batches - __SPLIT__
2026-05-28 09:31:39,429 - [56366] - src.utils.log - INFO - 📥 Empty dataframe of ServiceCore.WorkorderSourceMapping: - __SPLIT__
2026-05-28 09:31:39,430 - [56366] - src.utils.log - INFO - 📥 Empty dataframe of CRM.LeadNote: - __SPLIT__
2026-05-28 09:31:39,430 - [56366] - src.utils.log - INFO - Checking if all rows exist in parent table or not - __SPLIT__
2026-05-28 09:31:39,430 - [56366] - src.utils.log - INFO - 📥 Inserting into ServiceCore.WorkOrderAutoGenerationPublishServices: 2 rows in batches of 25000 (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:39,461 - [56366] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 2) into ServiceCore.WorkOrderAutoGenerationPublishServices - __SPLIT__
2026-05-28 09:31:39,554 - [56366] - src.utils.log - INFO - ✅ All 2 rows inserted successfully into ServiceCore.WorkOrderAutoGenerationPublishServices in 1 batches - __SPLIT__
2026-05-28 09:31:39,554 - [56366] - src.utils.log - INFO - 🔍 Fetching data: SELECT ss.ServiceSetupId as SetupId, ss.FrequencyScheduleId as setup_frequency_schedule_id, ss.AccountNumber, ss.CompanyId,
ss.BillingLocationId, ss.ServiceLocationId, ss.TaxSysName, ss.TermId, ss.TaxPercent, ss.PoNumber, ss.IsTaxExempt,
ss.DisplayServiceAsSeparateLineItem, ss.GenerateInvoice, ss.IsGenerateInvoiceforInitalSetup,
ss.IsGenerateInvoiceforInitalSetupBilling, ss.IsBilling, t.NetDueIn, ptm.PaymentType, ssb.*
FROM ServiceCore.ServiceSetup ss LEFT JOIN ServiceCore.ServiceSetupBilling ssb
ON ss.ServiceSetupId = ssb.ServiceSetupId LEFT JOIN Billing.PaymentTypeMaster ptm ON
ptm.PaymentTypeMasterId = ssb.PaymentTypeMasterId left join Billing.Term t on ss.TermId = t.TermId
WHERE ss.ServiceSetupId = 106056... (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:39,580 - [56366] - src.utils.log - INFO - ✅ Fetched 1 rows - __SPLIT__
2026-05-28 09:31:39,586 - [56366] - src.utils.log - INFO - Size of Transaction: 2 - __SPLIT__
2026-05-28 09:31:39,586 - [56366] - src.utils.log - INFO - 📥 Inserting into Billing.Transaction (skip triggers): 2 rows in batches of 25000 (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:39,628 - [56366] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 2) into Billing.Transaction (triggers skipped) - __SPLIT__
2026-05-28 09:31:39,718 - [56366] - src.utils.log - INFO - ✅ All 2 rows inserted successfully into Billing.Transaction in 1 batches (triggers skipped) - __SPLIT__
2026-05-28 09:31:39,730 - [56366] - src.utils.log - INFO - 🔄 Triggers re-enabled for session - __SPLIT__
2026-05-28 09:31:39,730 - [56366] - src.utils.log - INFO - 🔍 Fetching data: SELECT TransactionId, ModifiedBy, TransactionDate as InvoiceDate from Billing.[Transaction] t WHERE t.ModifiedBy in (12345678910, 12345678911) and t.CompanyId = 146 AND
t.TransactionType ='Invoice' AND t.CreatedDate = '2026-05-28 05:31:39.581';... (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:39,825 - [56366] - src.utils.log - INFO - ✅ Fetched 2 rows - __SPLIT__
2026-05-28 09:31:39,828 - [56366] - src.utils.log - INFO - 🔍 Fetching data: SELECT * from ServiceCore.ServiceSetupSubWorkOrder ssswo WHERE
ssswo.ServiceSetupId = 106056;... (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:39,864 - [56366] - src.utils.log - INFO - ✅ Fetched 0 rows - __SPLIT__
2026-05-28 09:31:39,872 - [56366] - src.utils.log - INFO - 📝 Executing:
UPDATE Billing.[Transaction]
SET ModifiedBy = NULL
WHERE ModifiedBy IN (12345678910,12345678911)
AND CreatedDate = '2026-05-28 05:31:39.581';
... (Thread: ThreadPoolExecutor-41_0) - __SPLIT__
2026-05-28 09:31:40,067 - [56366] - src.utils.log - INFO - ✅ Command executed successfully - __SPLIT__
2026-05-28 09:31:40,072 - [56366] - src.utils.log - INFO - Checking if all rows exist in parent table or not - __SPLIT__
2026-05-28 09:31:40,072 - [56366] - src.utils.log - INFO - 📥 Inserting into Billing.Invoice: 2 rows in batches of 25000 (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:40,104 - [56366] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 2) into Billing.Invoice - __SPLIT__
2026-05-28 09:31:40,198 - [56366] - src.utils.log - INFO - ✅ All 2 rows inserted successfully into Billing.Invoice in 1 batches - __SPLIT__
2026-05-28 09:31:40,198 - [56366] - src.utils.log - INFO - 🔍 Fetching data: SELECT InvoiceId, WorkOrderNo FROM Billing.Invoice i WHERE i.WorkOrderNo in ('56338', '56337')... (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:40,275 - [56366] - src.utils.log - INFO - ✅ Fetched 4 rows - __SPLIT__
2026-05-28 09:31:40,286 - [56366] - src.utils.log - INFO - 🔍 Fetching data: SELECT sss.ServiceSetupId, sss.NonStdSoldServiceId as SoldServiceNonStandardId, sss.Qty as Quantity,
sss.Price as Rate, sss.IsTaxable, sm.Name as NonStandardServiceName, sm.Description , sm.ServiceMasterId as ServiceId
FROM ServiceCore.ServiceSetupServices sss join ServiceCore.ServiceMaster sm on sm.ServiceMasterId = sss.ServiceId
where ServiceSetupId = 106056 and sss.IsDeleted =0;... (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:40,314 - [56366] - src.utils.log - INFO - ✅ Fetched 1 rows - __SPLIT__
2026-05-28 09:31:40,314 - [56366] - src.utils.log - INFO - 🔍 Fetching data: SELECT sss.ServiceSetupId, sss.NonStdSoldServiceId as SoldServiceNonStandardId, sss.Qty as Quantity,
sss.Price as Rate, sss.IsTaxable, sm.Name as NonStandardServiceName, sm.Description , sm.ServiceMasterId as ServiceId
FROM ServiceCore.ServiceSetupInitialServices sss join ServiceCore.ServiceMaster sm on sm.ServiceMasterId = sss.ServiceId
where ServiceSetupId = 106056 and sss.IsDeleted =0;... (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:40,338 - [56366] - src.utils.log - INFO - ✅ Fetched 1 rows - __SPLIT__
2026-05-28 09:31:40,339 - [56366] - src.utils.log - INFO - 🔍 Fetching data: SELECT sss.ServiceSetupId, sss.NonStdSoldServiceId as SoldServiceNonStandardId, sss.Qty as Quantity,
sss.Price as Rate, sss.IsTaxable, sm.servicename as NonStandardServiceName, sm.Description , sss.ServiceId
FROM ServiceCore.ServiceSetupServices sss join ServiceCore.SoldServiceNonStandard sm on sm.SoldServiceNonStandardId = sss.NonStdSoldServiceId
where ServiceSetupId = 106056 and sss.IsDeleted =0; ... (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:40,363 - [56366] - src.utils.log - INFO - ✅ Fetched 0 rows - __SPLIT__
2026-05-28 09:31:40,364 - [56366] - src.utils.log - INFO - 🔍 Fetching data: SELECT sss.ServiceSetupId, sss.NonStdSoldServiceId as SoldServiceNonStandardId, sss.Qty as Quantity,
sss.Price as Rate, sss.IsTaxable, sm.servicename as NonStandardServiceName, sm.Description , sss.ServiceId
FROM ServiceCore.ServiceSetupInitialServices sss join ServiceCore.SoldServiceNonStandard sm on sm.SoldServiceNonStandardId = sss.NonStdSoldServiceId
where ServiceSetupId = 106056 and sss.IsDeleted =0; ... (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:40,388 - [56366] - src.utils.log - INFO - ✅ Fetched 0 rows - __SPLIT__
2026-05-28 09:31:40,400 - [56366] - src.utils.log - INFO - 🔍 Fetching data: SELECT * from ServiceCore.ServiceSetupSubWorkOrder ssswo WHERE
ssswo.ServiceSetupId = 106056;... (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:40,426 - [56366] - src.utils.log - INFO - ✅ Fetched 0 rows - __SPLIT__
2026-05-28 09:31:40,430 - [56366] - src.utils.log - INFO - 🔍 Fetching data: SELECT IsInitialSetupDiscount as IsInitial, DiscountSetupId,
AppliedDiscountAmt as AppliedDiscountAmount,IsActive ,ServiceSetupId from
ServiceCore.ServiceSetupAppliedDiscount ssad WHERE ssad.ServiceSetupId = 106056;... (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:40,455 - [56366] - src.utils.log - INFO - ✅ Fetched 0 rows - __SPLIT__
2026-05-28 09:31:40,458 - [56366] - src.utils.log - INFO - 🔍 Fetching data: SELECT IsInitialSetupDiscount as IsInitial, DiscountSetupId,IsActive,DiscountAmount,
IsDiscountPercent,DiscountPercent,DiscountDescription , ServiceSetupId from
ServiceCore.ServiceSetupAppliedDiscount ssad WHERE ssad.ServiceSetupId = 106056;... (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:40,483 - [56366] - src.utils.log - INFO - ✅ Fetched 0 rows - __SPLIT__
2026-05-28 09:31:40,483 - [56366] - src.utils.log - INFO - Sequential Insertion in invoice tables begin - __SPLIT__
2026-05-28 09:31:40,483 - [56366] - src.utils.log - INFO - 📥 Empty dataframe of Billing.AccountDiscountSetup: - __SPLIT__
2026-05-28 09:31:40,483 - [56366] - src.utils.log - INFO - Checking if all rows exist in parent table or not - __SPLIT__
2026-05-28 09:31:40,483 - [56366] - src.utils.log - INFO - 📥 Inserting into Billing.InvoiceCharges: 4 rows in batches of 25000 (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:40,514 - [56366] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 4) into Billing.InvoiceCharges - __SPLIT__
2026-05-28 09:31:40,606 - [56366] - src.utils.log - INFO - ✅ All 4 rows inserted successfully into Billing.InvoiceCharges in 1 batches - __SPLIT__
2026-05-28 09:31:40,606 - [56366] - src.utils.log - INFO - 📥 Empty dataframe of Billing.DiscountApplied: - __SPLIT__
2026-05-28 09:31:40,606 - [56366] - src.utils.log - INFO - Invoice Publish Done - __SPLIT__
2026-05-28 09:31:40,607 - [56366] - src.utils.log - INFO - Updating Next GenerationDate - __SPLIT__
2026-05-28 09:31:40,607 - [56366] - src.utils.log - INFO - 🔍 Fetching data:
SELECT ServiceSetupId, MAX(NextWorkOrderGenerationDate) AS MaxNextWorkOrderGeneration
FROM ServiceCore.WorkOrderSkipMonthAutoGeneration
WHERE GenerateWorkOrderHistoryId = 56366
GROUP BY ServiceSetupId;
... (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:40,631 - [56366] - src.utils.log - INFO - ✅ Fetched 0 rows - __SPLIT__
2026-05-28 09:31:40,636 - [56366] - src.utils.log - INFO - 🔄 Updating next gen date sequentially: inside update_next_workorder_generation - __SPLIT__
2026-05-28 09:31:40,648 - [56366] - src.utils.log - INFO - 🛠 Creating temp table #tbl_303526bf2d - __SPLIT__
2026-05-28 09:31:40,675 - [56366] - src.utils.log - INFO - 📌 Bulk inserting 1 rows into #tbl_303526bf2d - __SPLIT__
2026-05-28 09:31:40,700 - [56366] - src.utils.log - INFO - 🔁 Running MERGE update to apply changes - __SPLIT__
2026-05-28 09:31:40,718 - [56366] - src.utils.log - INFO - ✔ SQL reported affected rows: 1 - __SPLIT__
2026-05-28 09:31:40,734 - [56366] - src.utils.log - INFO - 🔍 Verified successfully updated 1 rows via #tbl_303526bf2d - __SPLIT__
2026-05-28 09:31:40,734 - [56366] - src.utils.log - INFO - 🎉 Successfully updated NextGenerationDate using temp table #tbl_303526bf2d - __SPLIT__
2026-05-28 09:31:40,734 - [56366] - src.utils.log - INFO - 🗑️ Deleting records:
DELETE FROM ServiceCore.WorkOrderSkipMonthAutoGeneration
WHERE GenerateWorkOrderHistoryId = 56366
(Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:40,759 - [56366] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-05-28 09:31:40,759 - [56366] - src.utils.log - INFO - Sequential deletion from temp tables begin - __SPLIT__
2026-05-28 09:31:40,759 - [56366] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.WorkOrderAutoGenerationServices WHERE WorkOrderAutoGenerationId in (577571, 577572) (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:40,846 - [56366] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-05-28 09:31:40,846 - [56366] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.WorkOrderAutoGenerationCrews WHERE WorkOrderAutoGenerationId in (577571, 577572) (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:40,916 - [56366] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-05-28 09:31:40,916 - [56366] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.WorkOrderAutoGenerationSalesPersonCommission WHERE WorkOrderAutoGenerationId in (577571, 577572) (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:40,945 - [56366] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-05-28 09:31:40,945 - [56366] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.WorkOrderAutoGenerationAppliedDiscount WHERE WorkOrderAutoGenerationId in (577571, 577572) (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:40,990 - [56366] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-05-28 09:31:40,990 - [56366] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.SetupAutoGenerationSourceMapping WHERE WorkOrderAutoGenerationId in (577571, 577572) (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:41,023 - [56366] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-05-28 09:31:41,023 - [56366] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.SubWorkOrderAutoGeneration WHERE WorkOrderAutoGenerationId in (577571, 577572) (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:41,086 - [56366] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-05-28 09:31:41,087 - [56366] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.WorkOrderAutoGenerationCommissionAdjustment WHERE WorkOrderAutoGenerationId in (577571, 577572) (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:41,113 - [56366] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-05-28 09:31:41,113 - [56366] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.WorkOrderAutoGeneration WHERE WorkOrderAutoGenerationId in (577571, 577572) (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:41,238 - [56366] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-05-28 09:31:41,238 - [56366] - src.utils.log - INFO - Work order published ids: (577571, 577572) - __SPLIT__
2026-05-28 09:31:41,238 - [56366] - src.utils.log - INFO - Time took to perform operation in publishing [56366]: 3.42 - __SPLIT__
2026-05-28 09:31:41,238 - [56366] - src.utils.log - INFO - Publish Completed for GenerateWorkOrderHistoryId: [56366] - __SPLIT__
2026-05-28 09:31:41,238 - [56366] - src.utils.log - INFO - 📝 Executing: UPDATE ServiceCore.GenerateWorkOrderHistory SET PublishStatus = 'Completed',
PublishStatusDateTime = '2026-05-28 05:31:41.238' WHERE CompanyId = 146 AND
GenerateWorkOrderHistoryId = 56366;... (Thread: AnyIO worker thread) - __SPLIT__
2026-05-28 09:31:41,263 - [56366] - src.utils.log - INFO - ✅ Command executed successfully - __SPLIT__