2026-08-24 13:09:22.798|0024|Trace| [Npgsql.Connection] Start user action
2026-08-24 13:09:22.798|0024|Debug| [Npgsql.Command] Executing command: SELECT * FROM public.fn_chr__get_processed_cdrs($1)
2026-08-24 13:09:22.804|0005|Trace| [_3CX.BoxesMonitor] 89.VoiceMailBox has been updated: 1/1
2026-08-24 13:09:22.804|0005|Trace| [_3CX.BoxesMonitor] state= for 89/vmail_Rufnummer_89_20260824110814
2026-08-24 13:09:22.805|0005|Trace| [TCXSpeech.TranscriptionService] SPEECH Mail Transcription needed by 89
2026-08-24 13:09:22.816|0024|Trace| [Npgsql.Command] Cleaning up reader
2026-08-24 13:09:22.816|0024| Info| [Npgsql.Command] Command execution completed (duration=18ms): SELECT * FROM public.fn_chr__get_processed_cdrs($1)
2026-08-24 13:09:22.816|0024|Trace| [Npgsql.Connection] End user action
2026-08-24 13:09:22.816|0024|Trace| [CallHistoryReceiver.CallFlowParser] CDR DB post-processing is complete. Number of calls: 1. Number of CDRs: 3. Time taken: 00:00:00.0946088
2026-08-24 13:09:22.817|0024|Trace| [CallHistoryReceiver.CallFlowParser] Processing Recordings and Call History...
2026-08-24 13:09:22.817|0024|Trace| [CallHistoryReceiver.CallFlowParser] Processing CDR: 00000000-01dd-33b8-c99c-0a7800000059
2026-08-24 13:09:22.817|0024|Trace| [CallHistoryReceiver.CallFlowParser] Invoking OnCallEnded event
2026-08-24 13:09:22.817|0024| Info| [_3cxSystemService.MissedCalls] CHReceiver_NewMissedCall called: NewMissedCallArgs: DnOwner=89, PartyCallerId=Rufnummer, PartyName=NAME, PartyDNType=ExternalLine, StartTime=08/24/2026 11:07:44, EndTime=08/24/2026 11:07:44
2026-08-24 13:09:22.819|0024|Trace| [CallHistoryReceiver.CallFlowParser] OnCallEnded event successfully invoked
2026-08-24 13:09:22.821|0024|Trace| [CallHistoryReceiver.CallFlowParser] Processing MyPhoneCallHistory Destination Record: CallDBId={1 }; DnOwner={89 }; PartyDn={10005}; PartyDnType={ExternalLine}; PartyName={NAME}; PartyCallerId={Rufnummer }; StartTime={2026-08-24 11:07:44.042875}; EstablishedTime={ }; EndTime={2026-08-24 11:07:44.096373}; EndStatus={5 }; EndStatusHint={3 }; PartyCRMContact={ }; OwnerCdrParticipantId={00000000-01dd-33b8-c99c-0ac000000074}; PartyCdrParticipantId={00000000-01dd-33b8-c98e-421900000072};
2026-08-24 13:09:22.821|0024|Debug| [Npgsql.Command] Preparing command explicitly:
CALL public.sp_chr__save_myphone_callhistory_record(
:call_id,
:calltype,
:dnowner,
:owner_cdr_participant_id,
:party_dn,
:party_cdr_participant_id,
:party_dntype,
:party_name,
:party_callerid,
:via_role,
:via_dn,
:via_dntype,
:via_dnname,
:via_callerid,
:start_time,
:established_time,
:end_time,
:end_status,
:end_status_hint,
:divert_dn,
:divert_dntype,
:divert_dnname,
:divert_callerid,
:dialed_number,
:party_crm_contact,
:myphone_id
)
2026-08-24 13:09:22.821|0024|Trace| [Npgsql.Connection] Start user action
2026-08-24 13:09:22.824|0005|Trace| [_3CX.BoxesMonitor] Transcription is still pending for 89/vmail_Rufnummer_89_20260824110814: 1
2026-08-24 13:09:22.824|0024|Trace| [_3CX.BoxesMonitor] 3CXTranscription will be used for 89/vmail_Rufnummer_89_20260824110814: 1
2026-08-24 13:09:22.830|0005|Trace| ###TRANSACTION.178 started
2026-08-24 13:09:22.831|0022|Trace| [Npgsql.Connection] End user action
2026-08-24 13:09:22.831|0022| Info| [Npgsql.Command] Prepared command explicitly
2026-08-24 13:09:22.831|0022|Trace| [Npgsql.Connection] Start user action
2026-08-24 13:09:22.831|0022|Debug| [Npgsql.Command] Executing command:
CALL public.sp_chr__save_myphone_callhistory_record(
$1,
$2,
$3,
$4,
$5,
$6,
$7,
$8,
$9,
$10,
$11,
$12,
$13,
$14,
$15,
$16,
$17,
$18,
$19,
$20,
$21,
$22,
$23,
$24,
$25,
$26
)
2026-08-24 13:09:22.840|0005|Trace| +++TRANSACTION.178 updated - elapsed 9.6158
2026-08-24 13:09:22.840|0005|Trace| Finalizing TRANSACTION.178
2026-08-24 13:09:22.840|0005|Trace| ---TRANSACTION.178 finished 9.8847ms
data:9.6154ms
Events:0.197ms
externals:0.0723ms
2026-08-24 13:09:22.843|0022|Trace| [Npgsql.Command] Cleaning up reader
2026-08-24 13:09:22.843|0022| Info| [Npgsql.Command] Command execution completed (duration=12ms):
CALL public.sp_chr__save_myphone_callhistory_record(
$1,
$2,
$3,
$4,
$5,
$6,
$7,
$8,
$9,
$10,
$11,
$12,
$13,
$14,
$15,
$16,
$17,
$18,
$19,
$20,
$21,
$22,
$23,
$24,
$25,
$26
)
2026-08-24 13:09:22.843|0022|Trace| [Npgsql.Connection] End user action
2026-08-24 13:09:22.843|0022|Trace| [CallHistoryReceiver.CallFlowParser] Processing of Recordings and Call History is complete. Time taken: 00:00:00.0267988
2026-08-24 13:09:22.843|0022|Trace| [CallHistoryReceiver.CallFlowParser] Processing CRM contacts...
2026-08-24 13:19:22.825|0023| Info| [_3CX.BoxesMonitor] Transcription timed out for 89/vmail_Rufnummer_89_20260824110814
2026-08-24 13:19:22.833|0023|Trace| [_3CX.BoxesMonitor] state=0 for 89/vmail_Rufnummer_89_20260824110814
2026-08-24 13:19:22.833|0023|Trace| [_3CX.BoxesMonitor] DELIVER 89/vmail_Rufnummer_89_20260824110814 now
2026-08-24 13:19:22.833|0005|Trace| [_3CX.BoxesMonitor] state=0 for 89/vmail_Rufnummer_89_20260824110814
2026-08-24 13:19:22.833|0005|Trace| [_3CX.BoxesMonitor] DELIVER 89/vmail_Rufnummer_89_20260824110814 now
2026-08-24 13:19:22.842|0023|Debug| [_3CX.BoxesMonitor] Schedule delivery of 89/vmail_Rufnummer_89_20260824110814: 1
2026-08-24 13:19:22.842|0023|Trace| [_3CX.BoxesMonitor] state=0 for 89/vmail_Rufnummer_89_20260824110814
2026-08-24 13:19:22.842|0023|Debug| [_3CX.BoxesMonitor] Delivery in progress
2026-08-24 13:19:22.842|0023| Info| [_3CX.BoxesMonitor] Creating email notification for 89
2026-08-24 13:19:22.843|0023|Trace| [_3cxSystemService.OtherTasks.ContactResolver] Resolved contact Rufnummer to '(NAME - NAME)' for user 89 (Company)
2026-08-24 13:19:22.843|0023|Trace| [_3CX.BoxesMonitor] Contact has been resolved
2026-08-24 13:19:22.843|0023| Info| [_3CX.BoxesMonitor] Attaching /var/lib/3cxpbx/Instance1/Data/Ivr/Voicemail/Extensions/89/vmail_Rufnummer_89_20260824110814.wav to email
2026-08-24 13:19:22.843|0023| Info| [_3CX.BoxesMonitor] Sending email notification to Mailadresse
2026-08-24 13:19:22.843|0023| Info| [_3CX.HttpClient] Sending 'POST/2.0' to 'https://mailproxy.3cx.com/SendEmail'
2026-08-24 13:19:23.334|0021| Info| [_3CX.HttpClient] Received '200 OK' after 491.0ms
2026-08-24 13:19:23.335|0026| Info| [_3CX.BoxesMonitor] Email sent to Mailadresse
2026-08-24 13:19:23.335|0026|Debug| [_3CX.BoxesMonitor] Transcription removed: 0
2026-08-24 13:19:23.335|0026|Debug| [_3CX.BoxesMonitor] Delivery removed: 0
2026-08-24 13:19:23.339|0005|Trace| [_3CX.BoxesMonitor] Delivery finished for 89/vmail_Rufnummer_89_20260824110814
2026-08-24 13:19:23.340|0026|Trace| [_3CX.BoxesMonitor] Option AttachmentAndDelete deleting 89/vmail_Rufnummer_89_20260824110814
2026-08-24 13:19:23.342|0005|Trace| [_3CX.BoxesMonitor] 89.VoiceMailBox has been updated: 0/0
2026-08-24 13:19:23.342|0005|Trace| [_3CX.BoxesMonitor] Deleted /var/lib/3cxpbx/Instance1/Data/Ivr/Voicemail/Extensions/89/vmail_Rufnummer_89_20260824110814.wav
2026-08-24 13:19:24.346|0021|Trace| [Npgsql.Connection] Opening connection to /var/run/postgresql:5432/database_single...
2026-08-24 13:19:24.346|0021|Debug| [Npgsql.Connection] Opened connection to /var/run/postgresql:5432/database_single
2026-08-24 13:19:24.346|0021|Trace| [Npgsql.Connection] Start user action
2026-08-24 13:19:24.346|0021|Debug| [Npgsql.Command] Executing command: SELECT id_recording FROM recordings ORDER BY id_recording DESC LIMIT 1
2026-08-24 13:19:24.348|0026| Info| [_3CX.BoxesMonitor] 89.VoiceMailBox UPDATE: 0/0
2026-08-24 13:19:24.348|0029|Trace| [Npgsql.Command] Cleaning up reader
2026-08-24 13:19:24.348|0029| Info| [Npgsql.Command] Command execution completed (duration=2ms): SELECT id_recording FROM recordings ORDER BY id_recording DESC LIMIT 1
2026-08-24 13:19:24.348|0029|Trace| [Npgsql.Connection] End user action
2026-08-24 13:19:24.348|0029|Trace| [Npgsql.Connection] Closing connection to /var/run/postgresql:5432/database_single...
2026-08-24 13:19:24.348|0029|Trace| [Npgsql.Connection] Start user action
2026-08-24 13:19:24.348|0029|Trace| [Npgsql.Connection] End user action