Hi all,
started with a FS PBX. Inbound calls work fine, internal to. But somehow I can't make an outbound call, phone says "forbidden". Tried it on different phones.
This is the log:
Anyone an idea what I'm doing wrong?
started with a FS PBX. Inbound calls work fine, internal to. But somehow I can't make an outbound call, phone says "forbidden". Tried it on different phones.
This is the log:
Code:
1 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) State DESTROY going to sleep
2 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [DEBUG] switch_core_state_machine.c:175 sofia/internal/12@pbx.wapenvanvelden.nl:5060 Standard DESTROY
3 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [DEBUG] mod_sofia.c:388 sofia/internal/12@pbx.wapenvanvelden.nl:5060 SOFIA DESTROY
4 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) State DESTROY
5 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [DEBUG] switch_core_state_machine.c:735 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) Running State Change CS_DESTROY (Cur 0 Tot 122)
6 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [NOTICE] switch_core_session.c:1770 Close Channel sofia/internal/12@pbx.wapenvanvelden.nl:5060 [CS_DESTROY]
7 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [NOTICE] switch_core_session.c:1766 Session 121 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) Ended
8 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [DEBUG] switch_core_session.c:1748 Session 121 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) Locked, Waiting on external entities
9 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [DEBUG] switch_core_state_machine.c:607 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) State Change CS_REPORTING -> CS_DESTROY
10 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) State REPORTING going to sleep
11 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [DEBUG] switch_core_state_machine.c:168 sofia/internal/12@pbx.wapenvanvelden.nl:5060 Standard REPORTING, cause: NORMAL_CLEARING
12 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) State REPORTING
13 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) Running State Change CS_REPORTING (Cur 1 Tot 122)
14 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [DEBUG] switch_core_state_machine.c:616 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) State Change CS_HANGUP -> CS_REPORTING
15 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) State HANGUP going to sleep
16 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [DEBUG] switch_core_state_machine.c:59 sofia/internal/12@pbx.wapenvanvelden.nl:5060 Standard HANGUP, cause: NORMAL_CLEARING
17 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [DEBUG] mod_sofia.c:622 Responding to INVITE with: 403
18 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [DEBUG] mod_sofia.c:477 Channel sofia/internal/12@pbx.wapenvanvelden.nl:5060 hanging up, cause: NORMAL_CLEARING
19 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [DEBUG] mod_sofia.c:471 sofia/internal/12@pbx.wapenvanvelden.nl:5060 Overriding SIP cause 480 with 403 from the other leg
20 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) State HANGUP
21 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [DEBUG] switch_core_state_machine.c:844 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) Callstate Change RINGING -> HANGUP
22 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) Running State Change CS_HANGUP (Cur 1 Tot 122)
23 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) State EXECUTE going to sleep
24 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [NOTICE] switch_core_state_machine.c:384 Hangup sofia/internal/12@pbx.wapenvanvelden.nl:5060 [CS_EXECUTE] [NORMAL_CLEARING]
25 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [NOTICE] switch_core_state_machine.c:382 sofia/internal/12@pbx.wapenvanvelden.nl:5060 has executed the last dialplan instruction, hanging up.
26 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:35.089807 99.00% [DEBUG] switch_core_state_machine.c:745 (sofia/external/0646011828) State DESTROY going to sleep
27 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:35.089807 99.00% [DEBUG] switch_core_state_machine.c:175 sofia/external/0646011828 Standard DESTROY
28 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:35.089807 99.00% [DEBUG] mod_sofia.c:388 sofia/external/0646011828 SOFIA DESTROY
29 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:35.089807 99.00% [DEBUG] switch_core_state_machine.c:745 (sofia/external/0646011828) State DESTROY
30 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [DEBUG] switch_channel.c:4946 Continue on fail [1,2,3,6,18,21,27,28,31,34,38,41,42,44,58,88,111,403,501,602,607,809]: Cause: CALL_REJECTED
31 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [INFO] mod_dptools.c:3637 Originate Failed. Cause: CALL_REJECTED
32 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:35.089807 99.00% [DEBUG] switch_core_state_machine.c:735 (sofia/external/0646011828) Running State Change CS_DESTROY (Cur 1 Tot 122)
33 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:35.089807 99.00% [NOTICE] switch_core_session.c:1770 Close Channel sofia/external/0646011828 [CS_DESTROY]
34 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:35.089807 99.00% [NOTICE] switch_core_session.c:1766 Session 122 (sofia/external/0646011828) Ended
35 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:35.089807 99.00% [DEBUG] switch_ivr_originate.c:4056 Originate Resulted in Error Cause: 21 [CALL_REJECTED]
36 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:35.069803 99.00% [DEBUG] switch_core_session.c:1748 Session 122 (sofia/external/0646011828) Locked, Waiting on external entities
37 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:35.069803 99.00% [DEBUG] switch_core_state_machine.c:607 (sofia/external/0646011828) State Change CS_REPORTING -> CS_DESTROY
38 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:35.069803 99.00% [DEBUG] switch_core_state_machine.c:932 (sofia/external/0646011828) State REPORTING going to sleep
39 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:35.069803 99.00% [DEBUG] switch_core_state_machine.c:168 sofia/external/0646011828 Standard REPORTING, cause: CALL_REJECTED
40 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:35.069803 99.00% [DEBUG] switch_core_state_machine.c:932 (sofia/external/0646011828) State REPORTING
41 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:35.069803 99.00% [DEBUG] switch_core_state_machine.c:581 (sofia/external/0646011828) Running State Change CS_REPORTING (Cur 2 Tot 122)
42 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:35.069803 99.00% [DEBUG] switch_core_state_machine.c:616 (sofia/external/0646011828) State Change CS_HANGUP -> CS_REPORTING
43 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:35.069803 99.00% [DEBUG] switch_core_state_machine.c:846 (sofia/external/0646011828) State HANGUP going to sleep
44 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:35.069803 99.00% [DEBUG] switch_core_state_machine.c:59 sofia/external/0646011828 Standard HANGUP, cause: CALL_REJECTED
45 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:35.069803 99.00% [DEBUG] mod_sofia.c:477 Channel sofia/external/0646011828 hanging up, cause: CALL_REJECTED
46 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:35.069803 99.00% [DEBUG] switch_core_state_machine.c:846 (sofia/external/0646011828) State HANGUP
47 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:35.069803 99.00% [DEBUG] switch_core_state_machine.c:844 (sofia/external/0646011828) Callstate Change DOWN -> HANGUP
48 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:35.069803 99.00% [DEBUG] switch_core_state_machine.c:581 (sofia/external/0646011828) Running State Change CS_HANGUP (Cur 2 Tot 122)
49 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:35.069803 99.00% [NOTICE] sofia.c:8742 Hangup sofia/external/0646011828 [CS_CONSUME_MEDIA] [CALL_REJECTED]
50 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:35.069803 99.00% [DEBUG] sofia.c:7493 Channel sofia/external/0646011828 entering state [terminated][403]
51 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:34.989907 99.00% [DEBUG] sofia.c:7493 Channel sofia/external/0646011828 entering state [calling][0]
52 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:34.969784 99.00% [DEBUG] sofia.c:7493 Channel sofia/external/0646011828 entering state [calling][0]
53 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_core_state_machine.c:659 (sofia/external/0646011828) State CONSUME_MEDIA going to sleep
54 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_core_state_machine.c:659 (sofia/external/0646011828) State CONSUME_MEDIA
55 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_core_state_machine.c:581 (sofia/external/0646011828) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 122)
56 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_core_state_machine.c:640 (sofia/external/0646011828) State ROUTING going to sleep
57 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_ivr_originate.c:67 (sofia/external/0646011828) State Change CS_ROUTING -> CS_CONSUME_MEDIA
58 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:34.969784 99.00% [DEBUG] mod_sofia.c:158 sofia/external/0646011828 SOFIA ROUTING
59 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_core_state_machine.c:640 (sofia/external/0646011828) State ROUTING
60 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_core_state_machine.c:581 (sofia/external/0646011828) Running State Change CS_ROUTING (Cur 2 Tot 122)
61 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_core_state_machine.c:624 (sofia/external/0646011828) State INIT going to sleep
62 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_core_state_machine.c:48 (sofia/external/0646011828) State Change CS_INIT -> CS_ROUTING
63 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_core_state_machine.c:40 sofia/external/0646011828 Standard INIT
64 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73
65 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 a=sendrecv
66 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 a=ptime:20
67 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 a=silenceSupp:off - - - -
68 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 a=fmtp:101 0-15
69 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 a=rtpmap:101 telephone-event/8000
70 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 a=rtpmap:9 G722/8000
71 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 a=rtpmap:8 PCMA/8000
72 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 a=rtpmap:0 PCMU/8000
73 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 m=audio 24380 RTP/AVP 0 8 9 101
74 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 t=0 0
75 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 c=IN IP4 82.192.72.238
76 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 s=FreeSWITCH
77 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 o=FreeSWITCH 1767514034 1767514035 IN IP4 82.192.72.238
78 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 v=0
79 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 Local SDP:
80 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:34.969784 99.00% [DEBUG] sofia_glue.c:1659 sofia/external/0646011828 sending invite version: 1.10.12-release git b4fddb8 2025-12-12 20:28:25Z 64bit
81 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:34.969784 99.00% [INFO] sofia_glue.c:1656 sofia/external/0646011828 sending invite call-id: (null)
82 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:34.969784 99.00% [DEBUG] mod_sofia.c:97 sofia/external/0646011828 SOFIA INIT
83 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_core_state_machine.c:624 (sofia/external/0646011828) State INIT
84 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_core_state_machine.c:581 (sofia/external/0646011828) Running State Change CS_INIT (Cur 2 Tot 122)
85 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:34.969784 99.00% [DEBUG] mod_sofia.c:5131 (sofia/external/0646011828) State Change CS_NEW -> CS_INIT
86 d6afc79f-13c1-4c3b-aaf4-b5b08524fa73 2026-01-04 14:53:34.969784 99.00% [NOTICE] switch_channel.c:1143 New Channel sofia/external/0646011828 [d6afc79f-13c1-4c3b-aaf4-b5b08524fa73]
87 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_ivr_originate.c:2301 Parsing global variables
88 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_channel.c:1289 sofia/internal/12@pbx.wapenvanvelden.nl:5060 EXPORTING[export_vars] [origination_callee_id_name]=[0646011828] to event
89 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_channel.c:1289 sofia/internal/12@pbx.wapenvanvelden.nl:5060 EXPORTING[export_vars] [call_direction]=[outbound] to event
90 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_channel.c:1289 sofia/internal/12@pbx.wapenvanvelden.nl:5060 EXPORTING[export_vars] [call_direction]=[outbound] to event
91 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_channel.c:1289 sofia/internal/12@pbx.wapenvanvelden.nl:5060 EXPORTING[export_vars] [domain_uuid]=[37156ebb-d6db-4064-959c-71e075a921c3] to event
92 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_channel.c:1289 sofia/internal/12@pbx.wapenvanvelden.nl:5060 EXPORTING[export_vars] [domain_name]=[pbx.wapenvanvelden.nl] to event
93 f867e341-5741-49c1-ab8c-f4f6ce320c31 EXECUTE [depth=0] sofia/internal/12@pbx.wapenvanvelden.nl:5060 bridge(sofia/gateway/fdd0f945-fad6-4375-b758-54b6579d674a/0646011828)
94 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] mod_dptools.c:1671 SET sofia/internal/12@pbx.wapenvanvelden.nl:5060 [continue_on_fail]=[1,2,3,6,18,21,27,28,31,34,38,41,42,44,58,88,111,403,501,602,607,809]
95 f867e341-5741-49c1-ab8c-f4f6ce320c31 EXECUTE [depth=0] sofia/internal/12@pbx.wapenvanvelden.nl:5060 set(continue_on_fail=1,2,3,6,18,21,27,28,31,34,38,41,42,44,58,88,111,403,501,602,607,809)
96 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] mod_dptools.c:1671 SET sofia/internal/12@pbx.wapenvanvelden.nl:5060 [callee_id_number]=[0646011828]
97 f867e341-5741-49c1-ab8c-f4f6ce320c31 EXECUTE [depth=0] sofia/internal/12@pbx.wapenvanvelden.nl:5060 set(callee_id_number=0646011828)
98 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] mod_dptools.c:1671 SET sofia/internal/12@pbx.wapenvanvelden.nl:5060 [ignore_display_updates]=[true]
99 f867e341-5741-49c1-ab8c-f4f6ce320c31 EXECUTE [depth=0] sofia/internal/12@pbx.wapenvanvelden.nl:5060 set(ignore_display_updates=true)
100 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] mod_dptools.c:1671 SET sofia/internal/12@pbx.wapenvanvelden.nl:5060 [inherit_codec]=[true]
101 f867e341-5741-49c1-ab8c-f4f6ce320c31 EXECUTE [depth=0] sofia/internal/12@pbx.wapenvanvelden.nl:5060 set(inherit_codec=true)
102 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] mod_dptools.c:1671 SET sofia/internal/12@pbx.wapenvanvelden.nl:5060 [effective_caller_id_number]=[773690170]
103 f867e341-5741-49c1-ab8c-f4f6ce320c31 EXECUTE [depth=0] sofia/internal/12@pbx.wapenvanvelden.nl:5060 set(effective_caller_id_number=773690170)
104 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] mod_dptools.c:1671 SET sofia/internal/12@pbx.wapenvanvelden.nl:5060 [effective_caller_id_name]=[Het Wapen van Velden]
105 f867e341-5741-49c1-ab8c-f4f6ce320c31 EXECUTE [depth=0] sofia/internal/12@pbx.wapenvanvelden.nl:5060 set(effective_caller_id_name=Het Wapen van Velden)
106 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] mod_dptools.c:1671 SET sofia/internal/12@pbx.wapenvanvelden.nl:5060 [hangup_after_bridge]=[true]
107 f867e341-5741-49c1-ab8c-f4f6ce320c31 EXECUTE [depth=0] sofia/internal/12@pbx.wapenvanvelden.nl:5060 set(hangup_after_bridge=true)
108 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] mod_dptools.c:1816 UNSET [call_timeout]
109 f867e341-5741-49c1-ab8c-f4f6ce320c31 EXECUTE [depth=0] sofia/internal/12@pbx.wapenvanvelden.nl:5060 unset(call_timeout)
110 f867e341-5741-49c1-ab8c-f4f6ce320c31 EXECUTE [depth=0] sofia/internal/12@pbx.wapenvanvelden.nl:5060 hash(insert/pbx.wapenvanvelden.nl-last_dial/12/0646011828)
111 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_cpp.cpp:1210 sofia/internal/12@pbx.wapenvanvelden.nl:5060 destroy/unlink session from object
112 f867e341-5741-49c1-ab8c-f4f6ce320c31 EXECUTE [depth=0] sofia/internal/12@pbx.wapenvanvelden.nl:5060 lua(check_ext_suspension.lua)
113 2026-01-04 14:53:34.969784 99.00% [NOTICE] switch_cpp.cpp:1466 [check-suspension] No result found for domain_uuid: 37156ebb-d6db-4064-959c-71e075a921c3f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_cpp.cpp:1210 sofia/internal/12@pbx.wapenvanvelden.nl:5060 destroy/unlink session from object
114 2026-01-04 14:53:34.969784 99.00% [NOTICE] switch_cpp.cpp:1466 [check-suspension] No result found for domain_uuid: 37156ebb-d6db-4064-959c-71e075a921c3f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_cpp.cpp:1210 sofia/internal/12@pbx.wapenvanvelden.nl:5060 destroy/unlink session from object
115 f867e341-5741-49c1-ab8c-f4f6ce320c31 EXECUTE [depth=0] sofia/internal/12@pbx.wapenvanvelden.nl:5060 lua(check_billing_suspension.lua)
116 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_channel.c:1335 EXPORT (export_vars) [origination_callee_id_name]=[0646011828]
117 f867e341-5741-49c1-ab8c-f4f6ce320c31 EXECUTE [depth=0] sofia/internal/12@pbx.wapenvanvelden.nl:5060 export(origination_callee_id_name=0646011828)
118 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] mod_dptools.c:1671 SET sofia/internal/12@pbx.wapenvanvelden.nl:5060 [RFC2822_DATE]=[Sun, 04 Jan 2026 14:53:34 +0000]
119 f867e341-5741-49c1-ab8c-f4f6ce320c31 EXECUTE [depth=0] sofia/internal/12@pbx.wapenvanvelden.nl:5060 set(RFC2822_DATE=Sun, 04 Jan 2026 14:53:34 +0000)
120 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] mod_dptools.c:1671 SET sofia/internal/12@pbx.wapenvanvelden.nl:5060 [caller_id_number]=[12]
121 f867e341-5741-49c1-ab8c-f4f6ce320c31 EXECUTE [depth=0] sofia/internal/12@pbx.wapenvanvelden.nl:5060 set(caller_id_number=12)
122 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_core_state_machine.c:323 sofia/internal/12@pbx.wapenvanvelden.nl:5060 Standard EXECUTE
123 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] mod_sofia.c:213 sofia/internal/12@pbx.wapenvanvelden.nl:5060 SOFIA EXECUTE
124 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) State EXECUTE
125 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) Running State Change CS_EXECUTE (Cur 1 Tot 121)
126 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) State ROUTING going to sleep
127 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_core_state_machine.c:281 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) State Change CS_ROUTING -> CS_EXECUTE
128 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Action bridge(sofia/gateway/fdd0f945-fad6-4375-b758-54b6579d674a/0646011828)
129 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Action set(continue_on_fail=1,2,3,6,18,21,27,28,31,34,38,41,42,44,58,88,111,403,501,602,607,809)
130 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Action set(callee_id_number=0646011828)
131 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Action set(ignore_display_updates=true)
132 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Action set(inherit_codec=true)
133 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Action set(effective_caller_id_number=${outbound_caller_id_number})
134 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Action set(effective_caller_id_name=${outbound_caller_id_name})
135 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Action set(hangup_after_bridge=true)
136 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Action unset(call_timeout)
137 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_channel.c:1335 EXPORT (export_vars) [call_direction]=[outbound]
138 f867e341-5741-49c1-ab8c-f4f6ce320c31 EXECUTE [depth=0] sofia/internal/12@pbx.wapenvanvelden.nl:5060 export(call_direction=outbound)
139 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Action export(call_direction=outbound) INLINE
140 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (PASS) [Cheapconnect.10d] destination_number(0646011828) =~ /^(\d{8,11})$/ break=on-false
141 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (PASS) [Cheapconnect.10d] ${user_exists}(false) =~ /false/ break=on-false
142 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 parsing [pbx.wapenvanvelden.nl->Cheapconnect.10d] continue=false
143 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [OUTBOUND_CALLER_ID] ${call_direction}(outbound) =~ /local/ break=on-false
144 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [OUTBOUND_CALLER_ID] ${outbound_caller_id_name}(Het Wapen van Velden) =~ /^$/ break=never
145 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [OUTBOUND_CALLER_ID] ${emergency_caller_id_number}(773690170) =~ /^$/ break=never
146 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [OUTBOUND_CALLER_ID] ${emergency_caller_id_name}(Het Wapen van Velden) =~ /^$/ break=never
147 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 parsing [pbx.wapenvanvelden.nl->OUTBOUND_CALLER_ID] continue=true
148 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [user_hold_music] ${user_exists}(false) =~ /true/ break=on-false
149 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 parsing [pbx.wapenvanvelden.nl->user_hold_music] continue=true
150 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [speed_dial] destination_number(0646011828) =~ /^\*0(.*)$/ break=on-false
151 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 parsing [pbx.wapenvanvelden.nl->speed_dial] continue=false
152 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
153 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (PASS) [redial] () =~ // break=never
154 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [redial] destination_number(0646011828) =~ /^(redial|\*870)$/ break=on-true
155 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 parsing [pbx.wapenvanvelden.nl->redial] continue=true
156 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [rtp_has_crypto] ${rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_SHA1_80|AES_CM_192_HMAC_SHA1_80|AES_CM_128_HMAC_SHA1_80|AES_CM_256_HMAC_SHA1_32|AES_CM_192_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_32|AES_CM_128_NULL_AUTH)$/ break=on-false
157 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 parsing [pbx.wapenvanvelden.nl->rtp_has_crypto] continue=true
158 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
159 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
160 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [user_record] ${call_direction}(outbound) =~ /^local$/ break=never
161 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
162 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
163 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (PASS) [user_record] ${call_direction}(outbound) =~ /^outbound$/ break=never
164 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
165 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
166 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [user_record] ${call_direction}(outbound) =~ /^inbound$/ break=never
167 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
168 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
169 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
170 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] mod_dptools.c:1671 SET sofia/internal/12@pbx.wapenvanvelden.nl:5060 [from_user_record]=[UNDEF]
171 f867e341-5741-49c1-ab8c-f4f6ce320c31 EXECUTE [depth=0] sofia/internal/12@pbx.wapenvanvelden.nl:5060 set(from_user_record=)
172 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Action set(from_user_record=${user_data ${sip_from_user}@${sip_from_host} var user_record}) INLINE
173 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
174 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
175 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [user_record] ${call_direction}(outbound) =~ /^local$/ break=never
176 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
177 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
178 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (PASS) [user_record] ${call_direction}(outbound) =~ /^outbound$/ break=never
179 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
180 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
181 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [user_record] ${call_direction}(outbound) =~ /^inbound$/ break=never
182 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
183 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
184 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
185 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 parsing [pbx.wapenvanvelden.nl->user_record] continue=true
186 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [call-direction] ${call_direction}(outbound) =~ /^$/ break=never
187 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 parsing [pbx.wapenvanvelden.nl->call-direction] continue=true
188 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [is_loopback] ${is_follow_me_loopback}() =~ /true/ break=on-false
189 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 parsing [pbx.wapenvanvelden.nl->is_loopback] continue=true
190 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [clear_sip_auto_answer] ${click_to_call}() =~ /true/ break=on-false
191 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 parsing [pbx.wapenvanvelden.nl->clear_sip_auto_answer] continue=true
192 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] switch_channel.c:1335 EXPORT (export_vars) [call_direction]=[outbound]
193 f867e341-5741-49c1-ab8c-f4f6ce320c31 EXECUTE [depth=0] sofia/internal/12@pbx.wapenvanvelden.nl:5060 export(call_direction=outbound)
194 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Action export(call_direction=outbound) INLINE
195 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (PASS) [call_direction-outbound] destination_number(0646011828) =~ /^(\d{8,11})$/ break=on-false
196 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (PASS) [call_direction-outbound] ${call_direction}() =~ /^$/ break=on-false
197 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (PASS) [call_direction-outbound] ${user_exists}(false) =~ /false/ break=on-false
198 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 parsing [pbx.wapenvanvelden.nl->call_direction-outbound] continue=true
199 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Action lua(check_ext_suspension.lua)
200 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Action lua(check_billing_suspension.lua)
201 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (PASS) [CHECK_BILLING_SUSPENSION] destination_number(0646011828) =~ /^(?!933\.?$|911\.?$)/ break=on-false
202 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 parsing [pbx.wapenvanvelden.nl->CHECK_BILLING_SUSPENSION] continue=true
203 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] mod_dptools.c:1671 SET sofia/internal/12@pbx.wapenvanvelden.nl:5060 [operator]=[1000]
204 f867e341-5741-49c1-ab8c-f4f6ce320c31 EXECUTE [depth=0] sofia/internal/12@pbx.wapenvanvelden.nl:5060 set(operator=1000)
205 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Action set(operator=1000) INLINE
206 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Action export(origination_callee_id_name=${caller_destination})
207 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Absolute Condition [domain-variables]
208 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 parsing [pbx.wapenvanvelden.nl->domain-variables] continue=true
209 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] mod_dptools.c:1671 SET sofia/internal/12@pbx.wapenvanvelden.nl:5060 [default_emergency_caller_id_number]=[UNDEF]
210 f867e341-5741-49c1-ab8c-f4f6ce320c31 EXECUTE [depth=0] sofia/internal/12@pbx.wapenvanvelden.nl:5060 set(default_emergency_caller_id_number=)
211 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Action set(default_emergency_caller_id_number=) INLINE
212 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.969784 99.00% [DEBUG] mod_dptools.c:1671 SET sofia/internal/12@pbx.wapenvanvelden.nl:5060 [default_outbound_caller_id_number]=[UNDEF]
213 f867e341-5741-49c1-ab8c-f4f6ce320c31 EXECUTE [depth=0] sofia/internal/12@pbx.wapenvanvelden.nl:5060 set(default_outbound_caller_id_number=)
214 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Action set(default_outbound_caller_id_number=) INLINE
215 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Absolute Condition [DEFAULT_CALLER_ID]
216 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 parsing [pbx.wapenvanvelden.nl->DEFAULT_CALLER_ID] continue=true
217 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.949787 99.00% [DEBUG] mod_dptools.c:1671 SET sofia/internal/12@pbx.wapenvanvelden.nl:5060 [timezone]=[Europe/Amsterdam]
218 f867e341-5741-49c1-ab8c-f4f6ce320c31 EXECUTE [depth=0] sofia/internal/12@pbx.wapenvanvelden.nl:5060 set(timezone=Europe/Amsterdam)
219 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Action set(timezone=Europe/Amsterdam) INLINE
220 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
221 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (PASS) [global-variables] () =~ // break=on-false
222 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 parsing [pbx.wapenvanvelden.nl->global-variables] continue=true
223 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Action set(caller_id_number=${caller_id_number})
224 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.949787 99.00% [DEBUG] mod_dptools.c:1671 SET sofia/internal/12@pbx.wapenvanvelden.nl:5060 [caller_id_name]=[12]
225 f867e341-5741-49c1-ab8c-f4f6ce320c31 EXECUTE [depth=0] sofia/internal/12@pbx.wapenvanvelden.nl:5060 set(caller_id_name=12)
226 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Action set(caller_id_name=${caller_id_name}) INLINE
227 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.949787 99.00% [DEBUG] mod_dptools.c:1671 SET sofia/internal/12@pbx.wapenvanvelden.nl:5060 [caller_destination]=[0646011828]
228 f867e341-5741-49c1-ab8c-f4f6ce320c31 EXECUTE [depth=0] sofia/internal/12@pbx.wapenvanvelden.nl:5060 set(caller_destination=0646011828)
229 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Action set(caller_destination=${destination_number}) INLINE
230 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (PASS) [caller-details] ${caller_destination}() =~ /^$/ break=never
231 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 parsing [pbx.wapenvanvelden.nl->caller-details] continue=true
232 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
233 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.949787 99.00% [DEBUG] mod_dptools.c:1671 SET sofia/internal/12@pbx.wapenvanvelden.nl:5060 [from_user_exists]=[true]
234 f867e341-5741-49c1-ab8c-f4f6ce320c31 EXECUTE [depth=0] sofia/internal/12@pbx.wapenvanvelden.nl:5060 set(from_user_exists=true)
235 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
236 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.949787 99.00% [DEBUG] mod_dptools.c:1671 SET sofia/internal/12@pbx.wapenvanvelden.nl:5060 [user_exists]=[false]
237 f867e341-5741-49c1-ab8c-f4f6ce320c31 EXECUTE [depth=0] sofia/internal/12@pbx.wapenvanvelden.nl:5060 set(user_exists=false)
238 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
239 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (PASS) [user_exists] () =~ // break=on-false
240 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 Regex (FAIL) [user_exists] ${loopback_leg}() =~ /^B$/ break=never
241 f867e341-5741-49c1-ab8c-f4f6ce320c31 Dialplan: sofia/internal/12@pbx.wapenvanvelden.nl:5060 parsing [pbx.wapenvanvelden.nl->user_exists] continue=true
242 2026-01-04 14:53:34.949787 99.00% [NOTICE] switch_cpp.cpp:1466 [xml_handler] single key:dialplan:pbx.wapenvanvelden.nl
243 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.929874 99.00% [INFO] mod_dialplan_xml.c:648 Processing 12 <12>->0646011828 in context pbx.wapenvanvelden.nl
244 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.929874 99.00% [DEBUG] switch_core_state_machine.c:230 sofia/internal/12@pbx.wapenvanvelden.nl:5060 Standard ROUTING
245 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.929874 99.00% [DEBUG] mod_sofia.c:158 sofia/internal/12@pbx.wapenvanvelden.nl:5060 SOFIA ROUTING
246 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.929874 99.00% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) State ROUTING
247 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.929874 99.00% [DEBUG] switch_channel.c:2400 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) Callstate Change DOWN -> RINGING
248 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.929874 99.00% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) Running State Change CS_ROUTING (Cur 1 Tot 121)
249 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.929874 99.00% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) State INIT going to sleep
250 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.929874 99.00% [DEBUG] switch_core_state_machine.c:48 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) State Change CS_INIT -> CS_ROUTING
251 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.929874 99.00% [DEBUG] switch_core_state_machine.c:40 sofia/internal/12@pbx.wapenvanvelden.nl:5060 Standard INIT
252 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.929874 99.00% [DEBUG] mod_sofia.c:97 sofia/internal/12@pbx.wapenvanvelden.nl:5060 SOFIA INIT
253 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.929874 99.00% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) State INIT
254 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.929874 99.00% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) Running State Change CS_INIT (Cur 1 Tot 121)
255 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.929874 99.00% [DEBUG] sofia.c:7906 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) State Change CS_NEW -> CS_INIT
256 f867e341-5741-49c1-ab8c-f4f6ce320c31
257 f867e341-5741-49c1-ab8c-f4f6ce320c31 a=ptime:20
258 f867e341-5741-49c1-ab8c-f4f6ce320c31 a=fmtp:101 0-15
259 f867e341-5741-49c1-ab8c-f4f6ce320c31 a=rtpmap:101 telephone-event/8000
260 f867e341-5741-49c1-ab8c-f4f6ce320c31 a=rtpmap:9 G722/8000
261 f867e341-5741-49c1-ab8c-f4f6ce320c31 a=fmtp:18 annexb=no
262 f867e341-5741-49c1-ab8c-f4f6ce320c31 a=rtpmap:18 G729/8000
263 f867e341-5741-49c1-ab8c-f4f6ce320c31 a=rtpmap:8 PCMA/8000
264 f867e341-5741-49c1-ab8c-f4f6ce320c31 a=rtpmap:0 PCMU/8000
265 f867e341-5741-49c1-ab8c-f4f6ce320c31 m=audio 12592 RTP/AVP 0 8 18 9 101
266 f867e341-5741-49c1-ab8c-f4f6ce320c31 t=0 0
267 f867e341-5741-49c1-ab8c-f4f6ce320c31 c=IN IP4 192.168.178.136
268 f867e341-5741-49c1-ab8c-f4f6ce320c31 s=SDP data
269 f867e341-5741-49c1-ab8c-f4f6ce320c31 o=- 20027 20027 IN IP4 192.168.178.136
270 f867e341-5741-49c1-ab8c-f4f6ce320c31 v=0
271 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.929874 99.00% [DEBUG] sofia.c:7503 Remote SDP:
272 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.929874 99.00% [DEBUG] sofia.c:7493 Channel sofia/internal/12@pbx.wapenvanvelden.nl:5060 entering state [received][100]
273 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.929874 99.00% [DEBUG] sofia.c:11681 Setting NAT mode based on via received
274 2026-01-04 14:53:34.929874 99.00% [DEBUG] sofia.c:10560 verifying acl "providers" for ip/port 31.184.91.244:0.
275 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.929874 99.00% [INFO] sofia.c:10466 sofia/internal/12@pbx.wapenvanvelden.nl:5060 receiving invite from 31.184.91.244:61571 version: 1.10.12-release git b4fddb8 2025-12-12 20:28:25Z 64bit call-id: 0_1653910602@192.168.178.136
276 2026-01-04 14:53:34.929874 99.00% [DEBUG] sofia.c:2532 Re-attaching to session f867e341-5741-49c1-ab8c-f4f6ce320c31
277 2026-01-04 14:53:34.909823 99.00% [DEBUG] sofia.c:2419 detaching session f867e341-5741-49c1-ab8c-f4f6ce320c31
278 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.909823 99.00% [DEBUG] switch_core_state_machine.c:600 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) State NEW
279 2026-01-04 14:53:34.909823 99.00% [DEBUG] sofia.c:10560 verifying acl "providers" for ip/port 31.184.91.244:0.
280 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.909823 99.00% [INFO] sofia.c:10466 sofia/internal/12@pbx.wapenvanvelden.nl:5060 receiving invite from 31.184.91.244:61571 version: 1.10.12-release git b4fddb8 2025-12-12 20:28:25Z 64bit call-id: 0_1653910602@192.168.178.136
281 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.909823 99.00% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/12@pbx.wapenvanvelden.nl:5060) Running State Change CS_NEW (Cur 1 Tot 121)
282 f867e341-5741-49c1-ab8c-f4f6ce320c31 2026-01-04 14:53:34.909823 99.00% [NOTICE] switch_channel.c:1143 New Channel sofia/internal/12@pbx.wapenvanvelden.nl:5060 [f867e341-5741-49c1-ab8c-f4f6ce320c31]Anyone an idea what I'm doing wrong?
