app.log.2026-07-20

Latest changes applied: initial drive time fix (2026-07-22)
2026-07-29 06:37:28,319 - [12755] - src.utils.log - INFO - Checking if all rows exist in parent table or not - __SPLIT__
2026-07-29 06:37:28,387 - [12755] - src.utils.log - INFO - 📥 Inserting into ServiceCore.WorkOrderAutoGenerationPublishHistory: 1 rows in batches of 25000 (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:28,790 - [12755] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 1) into ServiceCore.WorkOrderAutoGenerationPublishHistory - __SPLIT__
2026-07-29 06:37:29,913 - [12755] - src.utils.log - INFO - ✅ All 1 rows inserted successfully into ServiceCore.WorkOrderAutoGenerationPublishHistory in 1 batches - __SPLIT__
2026-07-29 06:37:29,981 - [12755] - src.utils.log - INFO - 🔍 Fetching data: 
            SELECT 
                WorkOrderAutoGenerationPublishHistoryId, 
                WorkOrderNo, 
                ServiceSetupId 
            FROM ServiceCore.WorkOrderAutoGenerationPublishHistory 
            WHERE ServiceSetupId = 34487
              AND WorkOrderNo = '1807900'
              AND GenerateWorkOrderHistoryId = 12755;
        ... (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:30,397 - [12755] - src.utils.log - INFO - ✅ Fetched 1 rows - __SPLIT__
2026-07-29 06:37:30,479 - [12755] - src.utils.log - INFO - Sequential Insertion in work order tables begin - __SPLIT__
2026-07-29 06:37:30,553 - [12755] - src.utils.log - INFO - Checking if all rows exist in parent table or not - __SPLIT__
2026-07-29 06:37:30,624 - [12755] - src.utils.log - INFO - 📥 Inserting into ServiceCore.WorkOrderServices: 1 rows in batches of 25000 (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:31,011 - [12755] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 1) into ServiceCore.WorkOrderServices - __SPLIT__
2026-07-29 06:37:32,851 - [12755] - src.utils.log - INFO - ✅ All 1 rows inserted successfully into ServiceCore.WorkOrderServices in 1 batches - __SPLIT__
2026-07-29 06:37:32,924 - [12755] - src.utils.log - INFO - 📥 Empty dataframe of ServiceAuto.WorkOrderAppliedDiscount: - __SPLIT__
2026-07-29 06:37:32,993 - [12755] - src.utils.log - INFO - 📥 Empty dataframe of ServiceCore.WorkOrderSalesPersonCommission: - __SPLIT__
2026-07-29 06:37:33,059 - [12755] - src.utils.log - INFO - 📥 Empty dataframe of ServiceAuto.WorkOrderCommissionAdjustment: - __SPLIT__
2026-07-29 06:37:33,126 - [12755] - src.utils.log - INFO - Checking if all rows exist in parent table or not - __SPLIT__
2026-07-29 06:37:33,198 - [12755] - src.utils.log - INFO - 📥 Inserting into ServiceCore.WorkOrderCrew: 1 rows in batches of 25000 (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:33,583 - [12755] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 1) into ServiceCore.WorkOrderCrew - __SPLIT__
2026-07-29 06:37:35,317 - [12755] - src.utils.log - INFO - ✅ All 1 rows inserted successfully into ServiceCore.WorkOrderCrew in 1 batches - __SPLIT__
2026-07-29 06:37:35,434 - [12755] - src.utils.log - INFO - 📥 Empty dataframe of ServiceCore.WorkorderSourceMapping: - __SPLIT__
2026-07-29 06:37:35,495 - [12755] - src.utils.log - INFO - 📥 Empty dataframe of CRM.LeadNote: - __SPLIT__
2026-07-29 06:37:35,559 - [12755] - src.utils.log - INFO - Checking if all rows exist in parent table or not - __SPLIT__
2026-07-29 06:37:35,627 - [12755] - src.utils.log - INFO - 📥 Inserting into ServiceCore.WorkOrderAutoGenerationPublishServices: 1 rows in batches of 25000 (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:36,014 - [12755] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 1) into ServiceCore.WorkOrderAutoGenerationPublishServices - __SPLIT__
2026-07-29 06:37:37,819 - [12755] - src.utils.log - INFO - ✅ All 1 rows inserted successfully into ServiceCore.WorkOrderAutoGenerationPublishServices in 1 batches - __SPLIT__
2026-07-29 06:37:37,890 - [12755] - 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 = 34487... (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:38,248 - [12755] - src.utils.log - INFO - ✅ Fetched 1 rows - __SPLIT__
2026-07-29 06:37:38,313 - [12755] - src.utils.log - INFO - Size of Transaction: 1 - __SPLIT__
2026-07-29 06:37:38,372 - [12755] - src.utils.log - INFO - 📥 Inserting into Billing.Transaction (skip triggers): 1 rows in batches of 25000 (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:38,900 - [12755] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 1) into Billing.Transaction (triggers skipped) - __SPLIT__
2026-07-29 06:37:41,801 - [12755] - src.utils.log - INFO - ✅ All 1 rows inserted successfully into Billing.Transaction in 1 batches (triggers skipped) - __SPLIT__
2026-07-29 06:37:42,020 - [12755] - src.utils.log - INFO - 🔄 Triggers re-enabled for session - __SPLIT__
2026-07-29 06:37:42,082 - [12755] - src.utils.log - INFO - 🔍 Fetching data: SELECT TransactionId, ModifiedBy, TransactionDate as InvoiceDate from Billing.[Transaction] t WHERE t.ModifiedBy = 12345678910 and t.CompanyId = 135 AND
         t.TransactionType ='Invoice' AND t.CreatedDate = '2026-07-29 02:37:38.308';... (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:42,481 - [12755] - src.utils.log - INFO - ✅ Fetched 1 rows - __SPLIT__
2026-07-29 06:37:42,548 - [12755] - src.utils.log - INFO - 🔍 Fetching data: SELECT * from ServiceCore.ServiceSetupSubWorkOrder ssswo WHERE 
     ssswo.ServiceSetupId  = 34487;... (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:42,906 - [12755] - src.utils.log - INFO - ✅ Fetched 0 rows - __SPLIT__
2026-07-29 06:37:42,978 - [12755] - src.utils.log - INFO - 📝 Executing: 
                    UPDATE Billing.[Transaction]
                    SET ModifiedBy = NULL
                    WHERE ModifiedBy = 12345678910
                    AND CreatedDate = '2026-07-29 02:37:38.308';
                ... (Thread: ThreadPoolExecutor-1_0) - __SPLIT__
2026-07-29 06:37:43,457 - [12755] - src.utils.log - INFO - ✅ Command executed successfully - __SPLIT__
2026-07-29 06:37:43,527 - [12755] - src.utils.log - INFO - Checking if all rows exist in parent table or not - __SPLIT__
2026-07-29 06:37:43,643 - [12755] - src.utils.log - INFO - 📥 Inserting into Billing.Invoice: 1 rows in batches of 25000 (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:44,030 - [12755] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 1) into Billing.Invoice - __SPLIT__
2026-07-29 06:37:46,680 - [12755] - src.utils.log - INFO - ✅ All 1 rows inserted successfully into Billing.Invoice in 1 batches - __SPLIT__
2026-07-29 06:37:46,746 - [12755] - src.utils.log - INFO - 🔍 Fetching data: SELECT InvoiceId, WorkOrderNo FROM Billing.Invoice i WHERE i.WorkOrderNo = '169608R'... (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:47,985 - [12755] - src.utils.log - INFO - ✅ Fetched 1 rows - __SPLIT__
2026-07-29 06:37:48,069 - [12755] - 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 = 34487 and sss.IsDeleted =0;... (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:48,453 - [12755] - src.utils.log - INFO - ✅ Fetched 1 rows - __SPLIT__
2026-07-29 06:37:48,530 - [12755] - 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 = 34487 and sss.IsDeleted =0;... (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:48,894 - [12755] - src.utils.log - INFO - ✅ Fetched 1 rows - __SPLIT__
2026-07-29 06:37:48,976 - [12755] - 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 = 34487  and sss.IsDeleted =0;  ... (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:49,352 - [12755] - src.utils.log - INFO - ✅ Fetched 0 rows - __SPLIT__
2026-07-29 06:37:49,429 - [12755] - 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 = 34487  and sss.IsDeleted =0;  ... (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:49,800 - [12755] - src.utils.log - INFO - ✅ Fetched 0 rows - __SPLIT__
2026-07-29 06:37:49,909 - [12755] - src.utils.log - INFO - 🔍 Fetching data: SELECT * from ServiceCore.ServiceSetupSubWorkOrder ssswo WHERE 
     ssswo.ServiceSetupId  = 34487;... (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:50,277 - [12755] - src.utils.log - INFO - ✅ Fetched 0 rows - __SPLIT__
2026-07-29 06:37:50,357 - [12755] - src.utils.log - INFO - 🔍 Fetching data: SELECT IsInitialSetupDiscount as IsInitial, DiscountSetupId, 
        AppliedDiscountAmt as AppliedDiscountAmount,IsActive ,ServiceSetupId  from 
        ServiceCore.ServiceSetupAppliedDiscount ssad WHERE ssad.ServiceSetupId = 34487;... (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:50,722 - [12755] - src.utils.log - INFO - ✅ Fetched 0 rows - __SPLIT__
2026-07-29 06:37:50,812 - [12755] - src.utils.log - INFO - 🔍 Fetching data: SELECT IsInitialSetupDiscount as IsInitial, DiscountSetupId,IsActive,DiscountAmount,
        IsDiscountPercent,DiscountPercent,DiscountDescription , ServiceSetupId from 
        ServiceCore.ServiceSetupAppliedDiscount ssad WHERE ssad.ServiceSetupId = 34487;... (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:51,174 - [12755] - src.utils.log - INFO - ✅ Fetched 0 rows - __SPLIT__
2026-07-29 06:37:51,253 - [12755] - src.utils.log - INFO - Sequential Insertion in invoice tables begin - __SPLIT__
2026-07-29 06:37:51,371 - [12755] - src.utils.log - INFO - 📥 Empty dataframe of Billing.AccountDiscountSetup: - __SPLIT__
2026-07-29 06:37:51,437 - [12755] - src.utils.log - INFO - Checking if all rows exist in parent table or not - __SPLIT__
2026-07-29 06:37:51,493 - [12755] - src.utils.log - INFO - 📥 Inserting into Billing.InvoiceCharges: 1 rows in batches of 25000 (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:51,876 - [12755] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 1) into Billing.InvoiceCharges - __SPLIT__
2026-07-29 06:37:53,264 - [12755] - src.utils.log - INFO - ✅ All 1 rows inserted successfully into Billing.InvoiceCharges in 1 batches - __SPLIT__
2026-07-29 06:37:53,329 - [12755] - src.utils.log - INFO - 📥 Empty dataframe of Billing.DiscountApplied: - __SPLIT__
2026-07-29 06:37:53,395 - [12755] - src.utils.log - INFO - Invoice Publish Done - __SPLIT__
2026-07-29 06:37:53,457 - [12755] - src.utils.log - INFO - Updating Next GenerationDate - __SPLIT__
2026-07-29 06:37:53,517 - [12755] - src.utils.log - INFO - 🔍 Fetching data: 
            SELECT ServiceSetupId, MAX(NextWorkOrderGenerationDate) AS MaxNextWorkOrderGeneration
            FROM ServiceCore.WorkOrderSkipMonthAutoGeneration
            WHERE GenerateWorkOrderHistoryId = 12755
            GROUP BY ServiceSetupId;
        ... (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:54,142 - [12755] - src.utils.log - INFO - ✅ Fetched 0 rows - __SPLIT__
2026-07-29 06:37:54,222 - [12755] - src.utils.log - INFO - 🔄 Updating next gen date sequentially: inside update_next_workorder_generation - __SPLIT__
2026-07-29 06:37:54,434 - [12755] - src.utils.log - INFO - 🛠 Creating temp table #tbl_125eb60902 - __SPLIT__
2026-07-29 06:37:54,796 - [12755] - src.utils.log - INFO - 📌 Bulk inserting 1 rows into #tbl_125eb60902 - __SPLIT__
2026-07-29 06:37:55,144 - [12755] - src.utils.log - INFO - 🔁 Running MERGE update to apply changes - __SPLIT__
2026-07-29 06:37:55,569 - [12755] - src.utils.log - INFO - ✔ SQL reported affected rows: 1 - __SPLIT__
2026-07-29 06:37:55,777 - [12755] - src.utils.log - INFO - 🔍 Verified successfully updated 1 rows via #tbl_125eb60902 - __SPLIT__
2026-07-29 06:37:55,839 - [12755] - src.utils.log - INFO - 🎉 Successfully updated NextGenerationDate using temp table #tbl_125eb60902 - __SPLIT__
2026-07-29 06:37:55,911 - [12755] - src.utils.log - INFO - 🗑️ Deleting records: 
                DELETE FROM ServiceCore.WorkOrderSkipMonthAutoGeneration
                WHERE GenerateWorkOrderHistoryId = 12755
               (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:56,260 - [12755] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-07-29 06:37:56,320 - [12755] - src.utils.log - INFO - Sequential deletion from temp tables begin - __SPLIT__
2026-07-29 06:37:56,433 - [12755] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.WorkOrderAutoGenerationServices WHERE WorkOrderAutoGenerationId = 2739779   (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:56,857 - [12755] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-07-29 06:37:56,924 - [12755] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.WorkOrderAutoGenerationCrews WHERE WorkOrderAutoGenerationId = 2739779   (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:57,413 - [12755] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-07-29 06:37:57,470 - [12755] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.WorkOrderAutoGenerationSalesPersonCommission WHERE WorkOrderAutoGenerationId = 2739779   (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:57,831 - [12755] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-07-29 06:37:57,888 - [12755] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.WorkOrderAutoGenerationAppliedDiscount WHERE WorkOrderAutoGenerationId = 2739779   (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:58,286 - [12755] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-07-29 06:37:58,349 - [12755] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.SetupAutoGenerationSourceMapping WHERE WorkOrderAutoGenerationId = 2739779   (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:58,721 - [12755] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-07-29 06:37:58,789 - [12755] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.SubWorkOrderAutoGeneration WHERE WorkOrderAutoGenerationId = 2739779   (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:59,169 - [12755] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-07-29 06:37:59,230 - [12755] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.WorkOrderAutoGenerationCommissionAdjustment WHERE WorkOrderAutoGenerationId = 2739779   (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:37:59,589 - [12755] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-07-29 06:37:59,649 - [12755] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.WorkOrderAutoGeneration WHERE WorkOrderAutoGenerationId = 2739779   (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:38:00,060 - [12755] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-07-29 06:38:00,128 - [12755] - src.utils.log - INFO - Work order published ids: (2739779,) - __SPLIT__
2026-07-29 06:38:00,193 - [12755] - src.utils.log - INFO - Time took to perform operation in publishing [12755]: 51.26 - __SPLIT__
2026-07-29 06:38:00,247 - [12755] - src.utils.log - INFO - Publish Completed for GenerateWorkOrderHistoryId: [12755] - __SPLIT__
2026-07-29 06:38:00,313 - [12755] - src.utils.log - INFO - 📝 Executing: UPDATE ServiceCore.GenerateWorkOrderHistory SET PublishStatus = 'Completed', 
                        PublishStatusDateTime = '2026-07-29 02:38:00.128' WHERE CompanyId = 135 AND 
                        GenerateWorkOrderHistoryId = 12755;... (Thread: AnyIO worker thread) - __SPLIT__
2026-07-29 06:38:00,779 - [12755] - src.utils.log - INFO - ✅ Command executed successfully - __SPLIT__