app.log.2026-04-13

Latest changes applied: fixed table name (2026-04-28)
2026-04-22 20:52:17,129 - [24593] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 10) into ServiceCore.WorkOrderServices - __SPLIT__
2026-04-22 20:52:17,952 - [24593] - src.utils.log - INFO - ✅ All 10 rows inserted successfully into ServiceCore.WorkOrderServices in 1 batches - __SPLIT__
2026-04-22 20:52:18,629 - [24593] - src.utils.log - INFO - Checking if all rows exist in parent table or not - __SPLIT__
2026-04-22 20:52:19,589 - [24593] - src.utils.log - INFO - 📥 Inserting into ServiceAuto.WorkOrderAppliedDiscount: 1 rows in batches of 25000 (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:52:20,321 - [24593] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 1) into ServiceAuto.WorkOrderAppliedDiscount - __SPLIT__
2026-04-22 20:52:21,130 - [24593] - src.utils.log - INFO - ✅ All 1 rows inserted successfully into ServiceAuto.WorkOrderAppliedDiscount in 1 batches - __SPLIT__
2026-04-22 20:52:21,794 - [24593] - src.utils.log - INFO - 📥 Empty dataframe of ServiceCore.WorkOrderSalesPersonCommission: - __SPLIT__
2026-04-22 20:52:22,459 - [24593] - src.utils.log - INFO - 📥 Empty dataframe of ServiceAuto.WorkOrderCommissionAdjustment: - __SPLIT__
2026-04-22 20:52:23,124 - [24593] - src.utils.log - INFO - Checking if all rows exist in parent table or not - __SPLIT__
2026-04-22 20:52:23,784 - [24593] - src.utils.log - INFO - 📥 Inserting into ServiceCore.WorkOrderCrew: 8 rows in batches of 25000 (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:52:24,521 - [24593] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 8) into ServiceCore.WorkOrderCrew - __SPLIT__
2026-04-22 20:52:25,302 - [24593] - src.utils.log - INFO - ✅ All 8 rows inserted successfully into ServiceCore.WorkOrderCrew in 1 batches - __SPLIT__
2026-04-22 20:52:25,970 - [24593] - src.utils.log - INFO - 📥 Empty dataframe of ServiceCore.WorkorderSourceMapping: - __SPLIT__
2026-04-22 20:52:26,642 - [24593] - src.utils.log - INFO - 📥 Empty dataframe of CRM.LeadNote: - __SPLIT__
2026-04-22 20:52:27,302 - [24593] - src.utils.log - INFO - Checking if all rows exist in parent table or not - __SPLIT__
2026-04-22 20:52:27,966 - [24593] - src.utils.log - INFO - 📥 Inserting into ServiceCore.WorkOrderAutoGenerationPublishServices: 10 rows in batches of 25000 (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:52:28,681 - [24593] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 10) into ServiceCore.WorkOrderAutoGenerationPublishServices - __SPLIT__
2026-04-22 20:52:29,489 - [24593] - src.utils.log - INFO - ✅ All 10 rows inserted successfully into ServiceCore.WorkOrderAutoGenerationPublishServices in 1 batches - __SPLIT__
2026-04-22 20:52:30,147 - [24593] - 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 in (65095, 66489, 65096, 65097, 66527, 25870, 32360, 32732)... (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:52:30,839 - [24593] - src.utils.log - INFO - ✅ Fetched 8 rows - __SPLIT__
2026-04-22 20:52:31,506 - [24593] - src.utils.log - INFO - Size of Transaction: 6 - __SPLIT__
2026-04-22 20:52:32,174 - [24593] - src.utils.log - INFO - 📥 Inserting into Billing.Transaction (skip triggers): 6 rows in batches of 25000 (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:52:32,904 - [24593] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 6) into Billing.Transaction (triggers skipped) - __SPLIT__
2026-04-22 20:52:34,007 - [24593] - src.utils.log - INFO - ✅ All 6 rows inserted successfully into Billing.Transaction in 1 batches (triggers skipped) - __SPLIT__
2026-04-22 20:52:34,692 - [24593] - src.utils.log - INFO - 🔄 Triggers re-enabled for session - __SPLIT__
2026-04-22 20:52:35,359 - [24593] - src.utils.log - INFO - 🔍 Fetching data: SELECT TransactionId, ModifiedBy, TransactionDate as InvoiceDate from Billing.[Transaction] t WHERE t.ModifiedBy in (12345678910, 12345678911, 12345678912, 12345678913, 12345678914, 12345678915)  and t.CompanyId = 133 AND 
        t.TransactionType ='Invoice' AND t.CreatedDate = '2026-04-22 16:52:31.502';... (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:52:36,835 - [24593] - src.utils.log - INFO - ✅ Fetched 6 rows - __SPLIT__
2026-04-22 20:52:37,510 - [24593] - src.utils.log - INFO - 🔍 Fetching data: SELECT * from ServiceCore.ServiceSetupSubWorkOrder ssswo WHERE 
     ssswo.ServiceSetupId  in (25870, 32360, 32732, 65095, 65096, 65097, 66489, 66527);... (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:52:38,316 - [24593] - src.utils.log - INFO - ✅ Fetched 0 rows - __SPLIT__
2026-04-22 20:52:38,996 - [24593] - src.utils.log - INFO - 📝 Executing: 
                    UPDATE Billing.[Transaction]
                    SET ModifiedBy = NULL
                    WHERE ModifiedBy IN (12345678910,12345678911,12345678912,12345678913,12345678914,12345678915)
                    AND CreatedDate = '2026-04-22 16:52:31.502';
                ... (Thread: ThreadPoolExecutor-29_0) - __SPLIT__
2026-04-22 20:52:40,099 - [24593] - src.utils.log - INFO - ✅ Command executed successfully - __SPLIT__
2026-04-22 20:52:40,777 - [24593] - src.utils.log - INFO - Checking if all rows exist in parent table or not - __SPLIT__
2026-04-22 20:52:41,455 - [24593] - src.utils.log - INFO - 📥 Inserting into Billing.Invoice: 6 rows in batches of 25000 (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:52:42,189 - [24593] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 6) into Billing.Invoice - __SPLIT__
2026-04-22 20:52:42,991 - [24593] - src.utils.log - INFO - ✅ All 6 rows inserted successfully into Billing.Invoice in 1 batches - __SPLIT__
2026-04-22 20:52:43,673 - [24593] - src.utils.log - INFO - 🔍 Fetching data: SELECT InvoiceId, WorkOrderNo FROM Billing.Invoice i WHERE i.WorkOrderNo in ('478757', '478758', '478759', '478755', '478753', '478756')... (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:52:44,786 - [24593] - src.utils.log - INFO - ✅ Fetched 6 rows - __SPLIT__
2026-04-22 20:52:45,469 - [24593] - 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 in (66489, 65097, 66527, 25870, 32360, 32732) and sss.IsDeleted =0;... (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:52:46,185 - [24593] - src.utils.log - INFO - ✅ Fetched 8 rows - __SPLIT__
2026-04-22 20:52:46,855 - [24593] - 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 in (66489, 65097, 66527, 25870, 32360, 32732) and sss.IsDeleted =0;... (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:52:47,564 - [24593] - src.utils.log - INFO - ✅ Fetched 2 rows - __SPLIT__
2026-04-22 20:52:48,235 - [24593] - 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 in (66489, 65097, 66527, 25870, 32360, 32732)  and sss.IsDeleted =0;  ... (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:52:48,936 - [24593] - src.utils.log - INFO - ✅ Fetched 0 rows - __SPLIT__
2026-04-22 20:52:49,912 - [24593] - 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 in (66489, 65097, 66527, 25870, 32360, 32732)  and sss.IsDeleted =0;  ... (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:52:50,624 - [24593] - src.utils.log - INFO - ✅ Fetched 0 rows - __SPLIT__
2026-04-22 20:52:51,312 - [24593] - src.utils.log - INFO - 🔍 Fetching data: SELECT * from ServiceCore.ServiceSetupSubWorkOrder ssswo WHERE 
     ssswo.ServiceSetupId  in (66489, 65097, 66527, 25870, 32360, 32732);... (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:52:52,018 - [24593] - src.utils.log - INFO - ✅ Fetched 0 rows - __SPLIT__
2026-04-22 20:52:52,701 - [24593] - src.utils.log - INFO - 🔍 Fetching data: SELECT IsInitialSetupDiscount as IsInitial, DiscountSetupId, 
        AppliedDiscountAmt as AppliedDiscountAmount,IsActive ,ServiceSetupId  from 
        ServiceCore.ServiceSetupAppliedDiscount ssad WHERE ssad.ServiceSetupId in (66489, 65097, 66527, 25870, 32360, 32732);... (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:52:53,415 - [24593] - src.utils.log - INFO - ✅ Fetched 1 rows - __SPLIT__
2026-04-22 20:52:54,110 - [24593] - src.utils.log - INFO - 🔍 Fetching data: SELECT IsInitialSetupDiscount as IsInitial, DiscountSetupId,IsActive,DiscountAmount,
        IsDiscountPercent,DiscountPercent,DiscountDescription, ServiceSetupId from 
        ServiceCore.ServiceSetupAppliedDiscount ssad WHERE ssad.ServiceSetupId  in (66489, 65097, 66527, 25870, 32360, 32732);... (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:52:54,829 - [24593] - src.utils.log - INFO - ✅ Fetched 1 rows - __SPLIT__
2026-04-22 20:52:55,516 - [24593] - src.utils.log - INFO - Sequential Insertion in invoice tables begin - __SPLIT__
2026-04-22 20:52:56,196 - [24593] - src.utils.log - INFO - Checking if all rows exist in parent table or not - __SPLIT__
2026-04-22 20:52:56,871 - [24593] - src.utils.log - INFO - 📥 Inserting into Billing.AccountDiscountSetup: 1 rows in batches of 25000 (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:52:57,606 - [24593] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 1) into Billing.AccountDiscountSetup - __SPLIT__
2026-04-22 20:52:58,394 - [24593] - src.utils.log - INFO - ✅ All 1 rows inserted successfully into Billing.AccountDiscountSetup in 1 batches - __SPLIT__
2026-04-22 20:52:59,071 - [24593] - src.utils.log - INFO - Checking if all rows exist in parent table or not - __SPLIT__
2026-04-22 20:52:59,777 - [24593] - src.utils.log - INFO - 📥 Inserting into Billing.InvoiceCharges: 8 rows in batches of 25000 (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:53:00,508 - [24593] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 8) into Billing.InvoiceCharges - __SPLIT__
2026-04-22 20:53:01,351 - [24593] - src.utils.log - INFO - ✅ All 8 rows inserted successfully into Billing.InvoiceCharges in 1 batches - __SPLIT__
2026-04-22 20:53:02,060 - [24593] - src.utils.log - INFO - Checking if all rows exist in parent table or not - __SPLIT__
2026-04-22 20:53:02,736 - [24593] - src.utils.log - INFO - 📥 Inserting into Billing.DiscountApplied: 1 rows in batches of 25000 (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:53:03,464 - [24593] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 1) into Billing.DiscountApplied - __SPLIT__
2026-04-22 20:53:04,552 - [24593] - src.utils.log - INFO - ✅ All 1 rows inserted successfully into Billing.DiscountApplied in 1 batches - __SPLIT__
2026-04-22 20:53:05,253 - [24593] - src.utils.log - INFO - Invoice Publish Done - __SPLIT__
2026-04-22 20:53:05,925 - [24593] - src.utils.log - INFO - Updating Next GenerationDate - __SPLIT__
2026-04-22 20:53:06,595 - [24593] - src.utils.log - INFO - 🔍 Fetching data: 
            SELECT ServiceSetupId, MAX(NextWorkOrderGenerationDate) AS MaxNextWorkOrderGeneration
            FROM ServiceCore.WorkOrderSkipMonthAutoGeneration
            WHERE GenerateWorkOrderHistoryId = 24593
            GROUP BY ServiceSetupId;
        ... (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:53:07,304 - [24593] - src.utils.log - INFO - ✅ Fetched 0 rows - __SPLIT__
2026-04-22 20:53:07,980 - [24593] - src.utils.log - INFO - 🔄 Updating next gen date sequentially: inside update_next_workorder_generation - __SPLIT__
2026-04-22 20:53:08,675 - [24593] - src.utils.log - INFO - 🛠 Creating temp table #tbl_bb5d836b00 - __SPLIT__
2026-04-22 20:53:09,372 - [24593] - src.utils.log - INFO - 📌 Bulk inserting 8 rows into #tbl_bb5d836b00 - __SPLIT__
2026-04-22 20:53:10,165 - [24593] - src.utils.log - INFO - 🔁 Running MERGE update to apply changes - __SPLIT__
2026-04-22 20:53:10,862 - [24593] - src.utils.log - INFO - ✔ SQL reported affected rows: 8 - __SPLIT__
2026-04-22 20:53:11,557 - [24593] - src.utils.log - INFO - 🔍 Verified successfully updated 8 rows via #tbl_bb5d836b00 - __SPLIT__
2026-04-22 20:53:12,247 - [24593] - src.utils.log - INFO - 🎉 Successfully updated NextGenerationDate using temp table #tbl_bb5d836b00 - __SPLIT__
2026-04-22 20:53:12,915 - [24593] - src.utils.log - INFO - 🗑️ Deleting records: 
                DELETE FROM ServiceCore.WorkOrderSkipMonthAutoGeneration
                WHERE GenerateWorkOrderHistoryId = 24593
               (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:53:13,620 - [24593] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-04-22 20:53:14,296 - [24593] - src.utils.log - INFO - Sequential deletion from temp tables begin - __SPLIT__
2026-04-22 20:53:14,970 - [24593] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.WorkOrderAutoGenerationServices WHERE WorkOrderAutoGenerationId in (643622, 643623, 643624, 643625, 643626, 643627, 643628, 643629)   (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:53:15,704 - [24593] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-04-22 20:53:16,382 - [24593] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.WorkOrderAutoGenerationCrews WHERE WorkOrderAutoGenerationId in (643622, 643623, 643624, 643625, 643626, 643627, 643628, 643629)   (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:53:17,093 - [24593] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-04-22 20:53:17,769 - [24593] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.WorkOrderAutoGenerationSalesPersonCommission WHERE WorkOrderAutoGenerationId in (643622, 643623, 643624, 643625, 643626, 643627, 643628, 643629)   (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:53:18,743 - [24593] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-04-22 20:53:19,410 - [24593] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.WorkOrderAutoGenerationAppliedDiscount WHERE WorkOrderAutoGenerationId in (643622, 643623, 643624, 643625, 643626, 643627, 643628, 643629)   (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:53:20,167 - [24593] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-04-22 20:53:20,833 - [24593] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.SetupAutoGenerationSourceMapping WHERE WorkOrderAutoGenerationId in (643622, 643623, 643624, 643625, 643626, 643627, 643628, 643629)   (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:53:21,548 - [24593] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-04-22 20:53:22,214 - [24593] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.SubWorkOrderAutoGeneration WHERE WorkOrderAutoGenerationId in (643622, 643623, 643624, 643625, 643626, 643627, 643628, 643629)   (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:53:22,907 - [24593] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-04-22 20:53:23,567 - [24593] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.WorkOrderAutoGenerationCommissionAdjustment WHERE WorkOrderAutoGenerationId in (643622, 643623, 643624, 643625, 643626, 643627, 643628, 643629)   (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:53:24,246 - [24593] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-04-22 20:53:24,919 - [24593] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.WorkOrderAutoGeneration WHERE WorkOrderAutoGenerationId in (643622, 643623, 643624, 643625, 643626, 643627, 643628, 643629)   (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:53:25,652 - [24593] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-04-22 20:53:26,315 - [24593] - src.utils.log - INFO - Work order published ids: (643622, 643623, 643624, 643625, 643626, 643627, 643628, 643629) - __SPLIT__
2026-04-22 20:53:26,976 - [24593] - src.utils.log - INFO - Time took to perform operation in publishing [24593]: 122.98 - __SPLIT__
2026-04-22 20:53:27,629 - [24593] - src.utils.log - INFO - Publish Completed for GenerateWorkOrderHistoryId: [24593] - __SPLIT__
2026-04-22 20:53:28,290 - [24593] - src.utils.log - INFO - 📝 Executing: UPDATE ServiceCore.GenerateWorkOrderHistory SET PublishStatus = 'Completed', 
                        PublishStatusDateTime = '2026-04-22 16:53:26.315' WHERE CompanyId = 133 AND 
                        GenerateWorkOrderHistoryId = 24593;... (Thread: AnyIO worker thread) - __SPLIT__
2026-04-22 20:53:28,984 - [24593] - src.utils.log - INFO - ✅ Command executed successfully - __SPLIT__