Hi, I checked the 3CXSystemService.log file for Call_Duration
There's about 1000 matches, always says the same. Is there any other file I can check for more CRM logs?
[{"code":"INVALID_DATA","details":{"api_name":"Call_Duration","json_path":"$.data[0].Call_Duration"},"message":"Please provide a valid call start time and duration","status":"error"}]}
Here is the rest of the logs if it helps, i have removed personal info with xxxx
2025/07/05 12:41:53.501|0026|Trac| [Npgsql.Connection] Closing connection to /var/run/postgresql:5432/database_single...
2025/07/05 12:41:53.501|0026|Trac| [Npgsql.Connection] Start user action
2025/07/05 12:41:53.501|0026|Trac| [Npgsql.Connection] End user action
2025/07/05 12:41:53.501|0026|Debg| [Npgsql.Connection] Closed connection to /var/run/postgresql:5432/database_single
2025/07/05 12:41:53.501|0026|Trac| [Npgsql.Connection] Opening connection to /var/run/postgresql:5432/database_single...
2025/07/05 12:41:53.501|0026|Debg| [Npgsql.Connection] Opened connection to /var/run/postgresql:5432/database_single
2025/07/05 12:41:53.501|0026|Trac| [Npgsql.Connection] Start user action
2025/07/05 12:41:53.501|0029|Info| [Integration.Crm.CallProcessingService] CRM: about to report 1 calls at maxConcurrency 16
2025/07/05 12:41:53.501|0026|Debg| [Npgsql.Command] Executing command: update cdrcrmcontact set journaled=true where cdr_participant_id=ANY($1)
2025/07/05 12:41:53.502|0029|Debg| [Integration.Crm.CallProcessingService] CRM: Triggering Call Journaling with values - CallType='Outbound' - PhoneNumber='XXXXXXXX' - ContactName='xxxxxx' - AgentExtension='100' - AgentFirstName='xxxx' - AgentLastName='xxxx' - AgentEmail='pxxxxx.au' - ContactRawData='72526000001015050|Contacts' - StartTime='07/05/2025 02:41:37' - EndTime='07/05/2025 02:41:53' - EstablishedTime='07/05/2025 02:41:42'
2025/07/05 12:41:53.502|0029|Debg| [Integration.Crm.Engine.ResultVariableScenarioProcessor] CRM: Processing scenario 'ReportCall'.
2025/07/05 12:41:53.502|0029|Debg| [Integration.Crm.Engine.ScenarioAuthHttpClientProvider] CRM: All tokens are up to date
2025/07/05 12:41:53.502|0026|Trac| [Npgsql.Command] Cleaning up reader
2025/07/05 12:41:53.502|0026|Info| [Npgsql.Command] Command execution completed (duration=4ms): SELECT * FROM public.fn_chr__postprocess_cdrs()
2025/07/05 12:41:53.502|0026|Trac| [Npgsql.Connection] End user action
2025/07/05 12:41:53.502|0026|Trac| [Npgsql.Connection] Start user action
2025/07/05 12:41:53.502|0026|Debg| [Npgsql.Connection] Rolling back transaction
2025/07/05 12:41:53.502|0029|Info| [_3CX.HttpClient] Sending 'GET/1.1' to '
https://www.zohoapis.com.au/crm/v2.1/users/search?criteria=(email:equals:prxxxxx.au)'
2025/07/05 12:41:53.502|0036|Debg| [Npgsql.Transaction] Rolled back transaction
2025/07/05 12:41:53.502|0036|Trac| [Npgsql.Connection] End user action
2025/07/05 12:41:53.502|0036|Trac| [Npgsql.Connection] Closing connection to /var/run/postgresql:5432/database_single...
2025/07/05 12:41:53.502|0036|Trac| [Npgsql.Connection] Start user action
2025/07/05 12:41:53.502|0036|Trac| [Npgsql.Connection] End user action
2025/07/05 12:41:53.502|0036|Debg| [Npgsql.Connection] Closed connection to /var/run/postgresql:5432/database_single
2025/07/05 12:41:53.502|0029|Trac| [Npgsql.Command] Cleaning up reader
2025/07/05 12:41:53.502|0029|Info| [Npgsql.Command] Command execution completed (duration=0ms): update cdrcrmcontact set journaled=true where cdr_participant_id=ANY($1)
2025/07/05 12:41:53.502|0029|Trac| [Npgsql.Connection] End user action
2025/07/05 12:41:53.502|0029|Trac| [Npgsql.Connection] Closing connection to /var/run/postgresql:5432/database_single...
2025/07/05 12:41:53.503|0029|Trac| [Npgsql.Connection] Start user action
2025/07/05 12:41:53.503|0029|Trac| [Npgsql.Connection] End user action
2025/07/05 12:41:53.503|0029|Debg| [Npgsql.Connection] Closed connection to /var/run/postgresql:5432/database_single
2025/07/05 12:41:53.552|0036|Info| [_3CX.HttpClient] Received '204 NoContent' after 50.1ms
2025/07/05 12:41:53.552|0036|Debg| [Integration.Crm.Engine.ResultVariableScenarioProcessor] CRM: The response is: '********'
2025/07/05 12:41:53.553|0036|Debg| [Integration.Crm.Engine.ResultVariableScenarioProcessor] CRM: Continue with empty matching.
2025/07/05 12:41:53.553|0036|Debg| [Integration.Crm.Engine.ResultVariableScenarioProcessor] CRM: Processing scenario 'CreateCallActivity'.
2025/07/05 12:41:53.553|0036|Debg| [Integration.Crm.Engine.ScenarioAuthHttpClientProvider] CRM: All tokens are up to date
2025/07/05 12:41:53.553|0036|Debg| [Integration.Crm.Engine.ResultVariableScenarioProcessor] CRM: Performing POST request to '
https://www.zohoapis.com.au' with message '********'.
2025/07/05 12:41:53.554|0036|Info| [_3CX.HttpClient] Sending 'POST/1.1' to '
https://www.zohoapis.com.au/crm/v2.1/Calls'
2025/07/05 12:41:53.624|0036|Info| [_3CX.HttpClient] Received '400 BadRequest' after 70.6ms
2025/07/05 12:41:53.626|0036|Erro| [Integration.Crm.Engine.CrmProcessor] CRM: Exception during call reporting
System.Exception: Server returned a non successful status code - HTTPStatusCode=BadRequest - Reason= - Content={"data":[{"code":"INVALID_DATA","details":{"api_name":"Call_Duration","json_path":"$.data[0].Call_Duration"},"message":"Please provide a valid call start time and duration","status":"error"}]}
at Integration.Crm.Engine.ScenarioProcessorBase.ProcessRequest(Boolean authScenario, ScenarioRequest request, IValueManager customManager, HttpClient client, Boolean retry, CancellationToken token)
at Integration.Crm.Engine.ScenarioProcessorBase.ProcessScenario(ProtocolScenario scenario, IValueManager customManager, SqlQueryParameter[] sqlQueryParameter, CancellationToken token)
at Integration.Crm.Engine.ScenarioProcessorBase.ProcessMatching(ProtocolScenario scenario, MatchingPath matching, ResponseMatcher responseMatcher, IValueManager customManager, SqlQueryParameter[] sqlQueryParameter, HashSet`1 distinctMatchings, CancellationToken token)
at Integration.Crm.Engine.ScenarioProcessorBase.ProcessFoundMatchings(ProtocolScenario scenario, IValueManager customManager, SqlQueryParameter[] sqlQueryParameter, List`1 matchings, ResponseMatcher responseMatcher, CancellationToken token)
at Integration.Crm.Engine.ScenarioProcessorBase.ProcessScenario(ProtocolScenario scenario, IValueManager customManager, SqlQueryParameter[] sqlQueryParameter, CancellationToken token)
at Integration.Crm.Engine.ScenarioProcessorBase.Execute(ProtocolScenario scenario, SqlQueryParameter[] sqlQueryParameter, CancellationToken token)
at Integration.Crm.Engine.CrmProcessor.ExecuteScenarioByName(String scenarioName, IEnumerable`1 parameters, Boolean useNoAuthProvider, CancellationToken token)
at Integration.Crm.Engine.CrmProcessor.ExecuteScenarioWithRetries(String scenarioId, Dictionary`2 parameters, CancellationToken token)
at Integration.Crm.Engine.CrmProcessor.ReportCall(CallEndedEvent callEndedEvent, CancellationToken token)
2025/07/05 12:41:57.735|0030|Debg| [_3cxSystemService.PublicIpUpdater] TryGetLocalIpListOsDependent
2025/07/05 12:41:57.737|0030|Debg| [_3cxSystemService.PublicIpUpdater] TryGetLocalIpListOsDependent. Result = [{"Description":"enp45s0 (enp45s0)","HasDefaultGateway":true,"IsIpv6":false,"IpAddressStr":"192.168.100.100"}]
2025/07/05 12:41:58.611|0030|Debg| [_3CX.BoxesMonitor] WaitingEvent for updates: 60000