app.log.2026-07-27

Latest changes applied: initial drive time fix (2026-07-22)
2026-08-06 10:33:43,542 - [12773] - src.utils.log - INFO - ✅ Fetched 0 rows - __SPLIT__
2026-08-06 10:33:43,612 - [12773] - src.utils.log - INFO - 🔍 Fetching data: SELECT * FROM  ServiceCore.WorkOrderAutoGenerationCommissionAdjustment woagca 
        WHERE woagca.WorkOrderAutoGenerationId  in (2740418, 2740419, 2740420);... (Thread: AnyIO worker thread) - __SPLIT__
2026-08-06 10:33:43,975 - [12773] - src.utils.log - INFO - ✅ Fetched 0 rows - __SPLIT__
2026-08-06 10:33:44,049 - [12773] - src.utils.log - INFO - Updating temp tables - __SPLIT__
2026-08-06 10:33:44,123 - [12773] - src.utils.log - INFO - 🔍 Fetching data: SELECT CompanyKey FROM Common.CompanyMaster
     cm WHERE CompanyId = 135;... (Thread: AnyIO worker thread) - __SPLIT__
2026-08-06 10:33:44,487 - [12773] - src.utils.log - INFO - ✅ Fetched 1 rows - __SPLIT__
2026-08-06 10:33:44,556 - [12773] - src.utils.log - INFO - 🔍 Fetching data: SELECT * FROM Common.GenerateNumberConfig gnc 
        WHERE CompanyId = 135 AND EnitityNumberType in ('SubWorkOrder'); ... (Thread: AnyIO worker thread) - __SPLIT__
2026-08-06 10:33:44,964 - [12773] - src.utils.log - INFO - ✅ Fetched 1 rows - __SPLIT__
2026-08-06 10:33:45,047 - [12773] - src.utils.log - INFO - Checking if all rows exist in parent table or not - __SPLIT__
2026-08-06 10:33:45,118 - [12773] - src.utils.log - INFO - 📥 Inserting into ServiceCore.WorkOrderAutoGenerationPublishHistory: 3 rows in batches of 25000 (Thread: AnyIO worker thread) - __SPLIT__
2026-08-06 10:33:45,527 - [12773] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 3) into ServiceCore.WorkOrderAutoGenerationPublishHistory - __SPLIT__
2026-08-06 10:33:46,654 - [12773] - src.utils.log - INFO - ✅ All 3 rows inserted successfully into ServiceCore.WorkOrderAutoGenerationPublishHistory in 1 batches - __SPLIT__
2026-08-06 10:33:46,732 - [12773] - src.utils.log - INFO - 🔍 Fetching data: 
        SELECT 
            WorkOrderAutoGenerationPublishHistoryId,
            WorkOrderNo,
            ServiceSetupId
        FROM ServiceCore.WorkOrderAutoGenerationPublishHistory
        WHERE
    ServiceSetupId = 34489 AND WorkOrderNo IN ('5023494', '1496494', '1131204') AND GenerateWorkOrderHistoryId = 12773;... (Thread: ThreadPoolExecutor-1_0) - __SPLIT__
2026-08-06 10:33:47,127 - [12773] - src.utils.log - INFO - ✅ Fetched 3 rows - __SPLIT__
2026-08-06 10:33:47,197 - [12773] - src.utils.log - INFO - 🏁 Final merged rows: 3 - __SPLIT__
2026-08-06 10:33:47,271 - [12773] - src.utils.log - INFO - Sequential Insertion in work order tables begin - __SPLIT__
2026-08-06 10:33:47,347 - [12773] - src.utils.log - INFO - Checking if all rows exist in parent table or not - __SPLIT__
2026-08-06 10:33:47,417 - [12773] - src.utils.log - INFO - 📥 Inserting into ServiceCore.WorkOrderServices: 3 rows in batches of 25000 (Thread: AnyIO worker thread) - __SPLIT__
2026-08-06 10:33:47,806 - [12773] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 3) into ServiceCore.WorkOrderServices - __SPLIT__
2026-08-06 10:33:49,273 - [12773] - src.utils.log - INFO - ✅ All 3 rows inserted successfully into ServiceCore.WorkOrderServices in 1 batches - __SPLIT__
2026-08-06 10:33:49,345 - [12773] - src.utils.log - INFO - 📥 Empty dataframe of ServiceAuto.WorkOrderAppliedDiscount: - __SPLIT__
2026-08-06 10:33:49,419 - [12773] - src.utils.log - INFO - 📥 Empty dataframe of ServiceCore.WorkOrderSalesPersonCommission: - __SPLIT__
2026-08-06 10:33:49,491 - [12773] - src.utils.log - INFO - 📥 Empty dataframe of ServiceAuto.WorkOrderCommissionAdjustment: - __SPLIT__
2026-08-06 10:33:49,563 - [12773] - src.utils.log - INFO - Checking if all rows exist in parent table or not - __SPLIT__
2026-08-06 10:33:49,634 - [12773] - src.utils.log - INFO - 📥 Inserting into ServiceCore.WorkOrderCrew: 6 rows in batches of 25000 (Thread: AnyIO worker thread) - __SPLIT__
2026-08-06 10:33:50,018 - [12773] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 6) into ServiceCore.WorkOrderCrew - __SPLIT__
2026-08-06 10:33:51,167 - [12773] - src.utils.log - INFO - ✅ All 6 rows inserted successfully into ServiceCore.WorkOrderCrew in 1 batches - __SPLIT__
2026-08-06 10:33:51,288 - [12773] - src.utils.log - INFO - 📥 Empty dataframe of ServiceCore.WorkorderSourceMapping: - __SPLIT__
2026-08-06 10:33:51,355 - [12773] - src.utils.log - INFO - 📥 Empty dataframe of CRM.LeadNote: - __SPLIT__
2026-08-06 10:33:51,423 - [12773] - src.utils.log - INFO - Checking if all rows exist in parent table or not - __SPLIT__
2026-08-06 10:33:51,492 - [12773] - src.utils.log - INFO - 📥 Inserting into ServiceCore.WorkOrderAutoGenerationPublishServices: 3 rows in batches of 25000 (Thread: AnyIO worker thread) - __SPLIT__
2026-08-06 10:33:51,885 - [12773] - src.utils.log - INFO - 📤 Inserting batch 1/1 (0 to 3) into ServiceCore.WorkOrderAutoGenerationPublishServices - __SPLIT__
2026-08-06 10:33:53,787 - [12773] - src.utils.log - INFO - ✅ All 3 rows inserted successfully into ServiceCore.WorkOrderAutoGenerationPublishServices in 1 batches - __SPLIT__
2026-08-06 10:33:53,854 - [12773] - 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 = 34489... (Thread: AnyIO worker thread) - __SPLIT__
2026-08-06 10:33:54,221 - [12773] - src.utils.log - INFO - ✅ Fetched 1 rows - __SPLIT__
2026-08-06 10:33:54,297 - [12773] - src.utils.log - INFO - Updating Next GenerationDate - __SPLIT__
2026-08-06 10:33:54,369 - [12773] - src.utils.log - INFO - 🔍 Fetching data: 
            SELECT ServiceSetupId, MAX(NextWorkOrderGenerationDate) AS MaxNextWorkOrderGeneration
            FROM ServiceCore.WorkOrderSkipMonthAutoGeneration
            WHERE GenerateWorkOrderHistoryId = 12773
            GROUP BY ServiceSetupId;
        ... (Thread: AnyIO worker thread) - __SPLIT__
2026-08-06 10:33:54,759 - [12773] - src.utils.log - INFO - ✅ Fetched 0 rows - __SPLIT__
2026-08-06 10:33:54,833 - [12773] - src.utils.log - INFO - 🔄 Updating next gen date sequentially: inside update_next_workorder_generation - __SPLIT__
2026-08-06 10:33:55,047 - [12773] - src.utils.log - INFO - 🛠 Creating temp table #tbl_20a6e3286a - __SPLIT__
2026-08-06 10:33:55,415 - [12773] - src.utils.log - INFO - 📌 Bulk inserting 1 rows into #tbl_20a6e3286a - __SPLIT__
2026-08-06 10:33:55,776 - [12773] - src.utils.log - INFO - 🔁 Running MERGE update to apply changes - __SPLIT__
2026-08-06 10:33:56,485 - [12773] - src.utils.log - INFO - ✔ SQL reported affected rows: 1 - __SPLIT__
2026-08-06 10:33:56,703 - [12773] - src.utils.log - INFO - 🔍 Verified successfully updated 1 rows via #tbl_20a6e3286a - __SPLIT__
2026-08-06 10:33:56,769 - [12773] - src.utils.log - INFO - 🎉 Successfully updated NextGenerationDate using temp table #tbl_20a6e3286a - __SPLIT__
2026-08-06 10:33:56,838 - [12773] - src.utils.log - INFO - 🗑️ Deleting records: 
                DELETE FROM ServiceCore.WorkOrderSkipMonthAutoGeneration
                WHERE GenerateWorkOrderHistoryId = 12773
               (Thread: AnyIO worker thread) - __SPLIT__
2026-08-06 10:33:57,197 - [12773] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-08-06 10:33:57,319 - [12773] - src.utils.log - INFO - Sequential deletion from temp tables begin - __SPLIT__
2026-08-06 10:33:57,389 - [12773] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.WorkOrderAutoGenerationServices WHERE WorkOrderAutoGenerationId in (2740418, 2740419, 2740420)   (Thread: AnyIO worker thread) - __SPLIT__
2026-08-06 10:33:57,795 - [12773] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-08-06 10:33:57,863 - [12773] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.WorkOrderAutoGenerationCrews WHERE WorkOrderAutoGenerationId in (2740418, 2740419, 2740420)   (Thread: AnyIO worker thread) - __SPLIT__
2026-08-06 10:33:58,255 - [12773] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-08-06 10:33:58,332 - [12773] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.WorkOrderAutoGenerationSalesPersonCommission WHERE WorkOrderAutoGenerationId in (2740418, 2740419, 2740420)   (Thread: AnyIO worker thread) - __SPLIT__
2026-08-06 10:33:58,698 - [12773] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-08-06 10:33:58,766 - [12773] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.WorkOrderAutoGenerationAppliedDiscount WHERE WorkOrderAutoGenerationId in (2740418, 2740419, 2740420)   (Thread: AnyIO worker thread) - __SPLIT__
2026-08-06 10:33:59,173 - [12773] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-08-06 10:33:59,243 - [12773] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.SetupAutoGenerationSourceMapping WHERE WorkOrderAutoGenerationId in (2740418, 2740419, 2740420)   (Thread: AnyIO worker thread) - __SPLIT__
2026-08-06 10:33:59,624 - [12773] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-08-06 10:33:59,696 - [12773] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.SubWorkOrderAutoGeneration WHERE WorkOrderAutoGenerationId in (2740418, 2740419, 2740420)   (Thread: AnyIO worker thread) - __SPLIT__
2026-08-06 10:34:00,066 - [12773] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-08-06 10:34:00,159 - [12773] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.WorkOrderAutoGenerationCommissionAdjustment WHERE WorkOrderAutoGenerationId in (2740418, 2740419, 2740420)   (Thread: AnyIO worker thread) - __SPLIT__
2026-08-06 10:34:00,528 - [12773] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-08-06 10:34:00,597 - [12773] - src.utils.log - INFO - 🗑️ Deleting records: DELETE FROM ServiceCore.WorkOrderAutoGeneration WHERE WorkOrderAutoGenerationId in (2740418, 2740419, 2740420)   (Thread: AnyIO worker thread) - __SPLIT__
2026-08-06 10:34:00,987 - [12773] - src.utils.log - INFO - ✅ Records deleted successfully - __SPLIT__
2026-08-06 10:34:01,065 - [12773] - src.utils.log - INFO - Work order published ids: (2740418, 2740419, 2740420) - __SPLIT__
2026-08-06 10:34:01,147 - [12773] - src.utils.log - INFO - Time took to perform operation in publishing [12773]: 31.81 - __SPLIT__
2026-08-06 10:34:01,224 - [12773] - src.utils.log - INFO - Publish Completed for GenerateWorkOrderHistoryId: [12773] - __SPLIT__
2026-08-06 10:34:01,353 - [12773] - src.utils.log - INFO - 📝 Executing: UPDATE ServiceCore.GenerateWorkOrderHistory SET PublishStatus = 'Completed', 
                        PublishStatusDateTime = '2026-08-06 06:34:01.065' WHERE CompanyId = 135 AND 
                        GenerateWorkOrderHistoryId = 12773;... (Thread: AnyIO worker thread) - __SPLIT__
2026-08-06 10:34:01,969 - [12773] - src.utils.log - INFO - ✅ Command executed successfully - __SPLIT__
2026-08-07 12:03:29,635 - 12781 - src.utils.log - INFO - ParallelProcessing=False GenerateWorkOrderHistoryId=12781 - __SPLIT__
2026-08-07 12:03:29,775 - 12781 - src.utils.log - INFO - Sequential Work order generation algo started for GenerateWorkOrderHistoryId: 12781 - __SPLIT__
2026-08-07 12:03:29,851 - 12781 - src.utils.log - INFO - ✅ Successfully connected to database - __SPLIT__
2026-08-07 12:03:29,929 - 12781 - src.utils.log - INFO - 🔍 Fetching data: SELECT ConnectionStringName FROM Common.CompanyTenantMaster WHERE 
        CompanyKey  = 'Fortive'... (Thread: AnyIO worker thread) - __SPLIT__
2026-08-07 12:03:32,056 - 12781 - src.utils.log - INFO - ✅ Fetched 0 rows - __SPLIT__
2026-08-07 12:03:32,125 - 12781 - src.utils.log - INFO - Connection string not found connecting to common DB - __SPLIT__
2026-08-07 12:03:32,192 - 12781 - src.utils.log - INFO - 📝 Executing: UPDATE ServiceCore.GenerateWorkOrderHistory
                        SET Status = 'InProgress' WHERE GenerateWorkOrderHistoryId = 12781;... (Thread: AnyIO worker thread) - __SPLIT__
2026-08-07 12:03:32,540 - 12781 - src.utils.log - INFO - ✅ Command executed successfully - __SPLIT__
2026-08-07 12:03:32,612 - 12781 - src.utils.log - INFO - 🔍 Fetching data:  
    SELECT sss.RecurringServicePrice, gw.StartDate, gw.EndDate, gw.CreatedBy AS NewCreatedBy, gw.RouteIds, ss.* FROM 
    ServiceCore.GenerateWorkOrderHistory gw JOIN ServiceCore.ServiceSetup ss ON gw.CompanyId = ss.CompanyId AND (
    (ss.NextGenerationDate <= gw.EndDate AND ss.NextGenerationDate >= gw.StartDate) OR ss.NextGenerationDate IS NULL) 
    AND (gw.AccountNumber IS NULL OR ss.AccountNumber = gw.AccountNumber) AND (gw.ServiceSetupId IS NULL OR 
    ss.ServiceSetupId = gw.ServiceSetupId)AND (gw.RouteIds IS NULL OR ss.RouteMasterId IN (SELECT CAST(value AS INT) FROM 
    STRING_SPLIT((SELECT RouteIds FROM ServiceCore.GenerateWorkOrderHistory WHERE GenerateWorkOrderHistoryid = 
    12781), ',' )) OR ss.InitialRouteId IN ( SELECT CAST(value AS INT) FROM STRING_SPLIT((SELECT RouteIds 
    FROM ServiceCore.GenerateWorkOrderHistory WHERE GenerateWorkOrderHistoryid = 12781), ','))) JOIN (SELECT 
    SUM(Price) AS RecurringServicePrice, ServiceSetupId FROM ServiceCore.ServiceSetupServices GROUP BY ServiceSetupId
    ) sss ON sss.ServiceSetupId = ss.ServiceSetupId WHERE gw.GenerateWorkOrderHistoryid = 12781 AND 
    ss.FrequencyScheduleId IS NOT NULL AND ss.IsActive = 1 AND ss.IsDeleted = 0 AND ss.Status <> 'Canceled' 
    AND ((ss.OrderType = gw.OrderType) OR gw.OrderType IS NULL);... (Thread: AnyIO worker thread) - __SPLIT__
2026-08-07 12:03:32,993 - 12781 - src.utils.log - INFO - ✅ Fetched 0 rows - __SPLIT__
2026-08-07 12:03:33,078 - 12781 - src.utils.log - INFO - 🔍 Fetching data: SELECT * from ServiceCore.GenerateWorkOrderHistory gwoh WHERE GenerateWorkOrderHistoryId = 12781;... (Thread: AnyIO worker thread) - __SPLIT__
2026-08-07 12:03:33,433 - 12781 - src.utils.log - INFO - ✅ Fetched 1 rows - __SPLIT__
2026-08-07 12:03:33,507 - 12781 - src.utils.log - INFO - 📝 Executing: UPDATE ServiceCore.GenerateWorkOrderHistory SET
                        Status = 'Failed', WoGenErrorInfo ='No setups found in given range',
                        WorkOrderStatusDateTime = '2026-08-07 08:03:33.507' WHERE  
                        GenerateWorkOrderHistoryId = 12781;... (Thread: AnyIO worker thread) - __SPLIT__
2026-08-07 12:03:33,869 - 12781 - src.utils.log - INFO - ✅ Command executed successfully - __SPLIT__
2026-08-07 12:03:33,941 - 12781 - src.utils.log - INFO - No Setup Found Error:No setups found for GenerateWorkOrderHistoryid: 12781 - __SPLIT__
2026-08-11 12:01:54,467 - 12656 - src.utils.log - INFO - ParallelProcessing=False GenerateWorkOrderHistoryId=12656 - __SPLIT__
2026-08-11 12:01:54,595 - 12656 - src.utils.log - INFO - Sequential Work order generation algo started for GenerateWorkOrderHistoryId: 12656 - __SPLIT__
2026-08-11 12:01:54,659 - 12656 - src.utils.log - INFO - ✅ Successfully connected to database - __SPLIT__
2026-08-11 12:01:54,716 - 12656 - src.utils.log - INFO - 🔍 Fetching data: SELECT ConnectionStringName FROM Common.CompanyTenantMaster WHERE 
        CompanyKey  = 'Fortive'... (Thread: AnyIO worker thread) - __SPLIT__
2026-08-11 12:01:57,080 - 12656 - src.utils.log - INFO - ✅ Fetched 0 rows - __SPLIT__
2026-08-11 12:01:57,142 - 12656 - src.utils.log - INFO - Connection string not found connecting to common DB - __SPLIT__
2026-08-11 12:01:57,204 - 12656 - src.utils.log - INFO - 📝 Executing: UPDATE ServiceCore.GenerateWorkOrderHistory
                        SET Status = 'InProgress' WHERE GenerateWorkOrderHistoryId = 12656;... (Thread: AnyIO worker thread) - __SPLIT__
2026-08-11 12:01:57,575 - 12656 - src.utils.log - INFO - ✅ Command executed successfully - __SPLIT__
2026-08-11 12:01:57,636 - 12656 - src.utils.log - INFO - 🔍 Fetching data:  
    SELECT sss.RecurringServicePrice, gw.StartDate, gw.EndDate, gw.CreatedBy AS NewCreatedBy, gw.RouteIds, ss.* FROM 
    ServiceCore.GenerateWorkOrderHistory gw JOIN ServiceCore.ServiceSetup ss ON gw.CompanyId = ss.CompanyId AND (
    (ss.NextGenerationDate <= gw.EndDate AND ss.NextGenerationDate >= gw.StartDate) OR ss.NextGenerationDate IS NULL) 
    AND (gw.AccountNumber IS NULL OR ss.AccountNumber = gw.AccountNumber) AND (gw.ServiceSetupId IS NULL OR 
    ss.ServiceSetupId = gw.ServiceSetupId)AND (gw.RouteIds IS NULL OR ss.RouteMasterId IN (SELECT CAST(value AS INT) FROM 
    STRING_SPLIT((SELECT RouteIds FROM ServiceCore.GenerateWorkOrderHistory WHERE GenerateWorkOrderHistoryid = 
    12656), ',' )) OR ss.InitialRouteId IN ( SELECT CAST(value AS INT) FROM STRING_SPLIT((SELECT RouteIds 
    FROM ServiceCore.GenerateWorkOrderHistory WHERE GenerateWorkOrderHistoryid = 12656), ','))) JOIN (SELECT 
    SUM(Price) AS RecurringServicePrice, ServiceSetupId FROM ServiceCore.ServiceSetupServices GROUP BY ServiceSetupId
    ) sss ON sss.ServiceSetupId = ss.ServiceSetupId WHERE gw.GenerateWorkOrderHistoryid = 12656 AND 
    ss.FrequencyScheduleId IS NOT NULL AND ss.IsActive = 1 AND ss.IsDeleted = 0 AND ss.Status <> 'Canceled' 
    AND ((ss.OrderType = gw.OrderType) OR gw.OrderType IS NULL);... (Thread: AnyIO worker thread) - __SPLIT__
2026-08-11 12:01:58,033 - 12656 - src.utils.log - INFO - ✅ Fetched 0 rows - __SPLIT__
2026-08-11 12:01:58,088 - 12656 - src.utils.log - INFO - 🔍 Fetching data: SELECT * from ServiceCore.GenerateWorkOrderHistory gwoh WHERE GenerateWorkOrderHistoryId = 12656;... (Thread: AnyIO worker thread) - __SPLIT__
2026-08-11 12:01:58,455 - 12656 - src.utils.log - INFO - ✅ Fetched 1 rows - __SPLIT__
2026-08-11 12:01:58,513 - 12656 - src.utils.log - INFO - 📝 Executing: UPDATE ServiceCore.GenerateWorkOrderHistory SET
                        Status = 'Failed', WoGenErrorInfo ='No setups found in given range',
                        WorkOrderStatusDateTime = '2026-08-11 08:01:58.513' WHERE  
                        GenerateWorkOrderHistoryId = 12656;... (Thread: AnyIO worker thread) - __SPLIT__
2026-08-11 12:01:58,975 - 12656 - src.utils.log - INFO - ✅ Command executed successfully - __SPLIT__
2026-08-11 12:01:59,029 - 12656 - src.utils.log - INFO - No Setup Found Error:No setups found for GenerateWorkOrderHistoryid: 12656 - __SPLIT__