- Mitglied seit
- 6 Apr 2007
- Beiträge
- 34
- Punkte für Reaktionen
- 0
- Punkte
- 0
Hallo Zusammen,
nach einem kleinen Problem in meinem Netzwerk (welches behoben ist) spinnt mein Asteirsk ein wenig.
Das Problem ist, mal kommen Calls ganz normal rein, mal kommen Calls nicht an - wenn die Calls nicht ankommen scheint der A-Teilnehmer (Anrufende) kein Ringing Alertet zu bekommen, weil der Asterisk kein Ringing generiert.
Eventuell habt ihr eine Idee - anbei der Logauszug:
Logfile ohne CAPI Debug:
Logfile mit CAPI Debug:
Der für den "ankommend" zuständige Context:
Mehrere Reboots haben nicht geholfen. Das Problem tritt auch nicht bei jedem Call auf.
Hat irgendjemand eine Idee was ich machen kann? Ich habe die Vermutung, dass der Asterisk einfach viel zu lange für das Dial Command benötigt und deshalb der Switch mit Cause 80 wegen Timeout auslöst.
nach einem kleinen Problem in meinem Netzwerk (welches behoben ist) spinnt mein Asteirsk ein wenig.
Das Problem ist, mal kommen Calls ganz normal rein, mal kommen Calls nicht an - wenn die Calls nicht ankommen scheint der A-Teilnehmer (Anrufende) kein Ringing Alertet zu bekommen, weil der Asterisk kein Ringing generiert.
Eventuell habt ihr eine Idee - anbei der Logauszug:
Logfile ohne CAPI Debug:
Code:
[Jun 21 23:48:38] VERBOSE[2205] chan_capi_utils.c: == ISDN1#02: Incoming call '0xxxxxxxxxxx' -> '995599'
[Jun 21 23:48:38] VERBOSE[8219] pbx.c: -- Executing [995599@ankommend:1] Macro("CAPI/ISDN1#02/995599-6b", "callforwarding,13,0xxxxxxxxxxx") in new stack
[Jun 21 23:48:38] VERBOSE[8219] pbx.c: -- Executing [s@macro-callforwarding:1] Set("CAPI/ISDN1#02/995599-6b", "temp=") in new stack
[Jun 21 23:48:38] VERBOSE[8219] pbx.c: -- Executing [s@macro-callforwarding:2] GotoIf("CAPI/ISDN1#02/995599-6b", "?cfi:nocfi") in new stack
[Jun 21 23:48:38] VERBOSE[8219] pbx.c: -- Goto (macro-callforwarding,s,4)
[Jun 21 23:48:38] VERBOSE[8219] pbx.c: -- Executing [s@macro-callforwarding:4] NoOp("CAPI/ISDN1#02/995599-6b", "") in new stack
[Jun 21 23:48:38] VERBOSE[8219] pbx.c: -- Executing [995599@ankommend:2] Macro("CAPI/ISDN1#02/995599-6b", "callforwarding,14,0xxxxxxxxxxx") in new stack
[Jun 21 23:48:38] VERBOSE[8219] pbx.c: -- Executing [s@macro-callforwarding:1] Set("CAPI/ISDN1#02/995599-6b", "temp=") in new stack
[Jun 21 23:48:38] VERBOSE[8219] pbx.c: -- Executing [s@macro-callforwarding:2] GotoIf("CAPI/ISDN1#02/995599-6b", "?cfi:nocfi") in new stack
[Jun 21 23:48:38] VERBOSE[8219] pbx.c: -- Goto (macro-callforwarding,s,4)
[Jun 21 23:48:38] VERBOSE[8219] pbx.c: -- Executing [s@macro-callforwarding:4] NoOp("CAPI/ISDN1#02/995599-6b", "") in new stack
[Jun 21 23:48:38] VERBOSE[8219] pbx.c: -- Executing [995599@ankommend:3] Macro("CAPI/ISDN1#02/995599-6b", "callforwarding,15,0xxxxxxxxxxx") in new stack
[Jun 21 23:48:38] VERBOSE[8219] pbx.c: -- Executing [s@macro-callforwarding:1] Set("CAPI/ISDN1#02/995599-6b", "temp=") in new stack
[Jun 21 23:48:38] VERBOSE[8219] pbx.c: -- Executing [s@macro-callforwarding:2] GotoIf("CAPI/ISDN1#02/995599-6b", "?cfi:nocfi") in new stack
[Jun 21 23:48:38] VERBOSE[8219] pbx.c: -- Goto (macro-callforwarding,s,4)
[Jun 21 23:48:38] VERBOSE[8219] pbx.c: -- Executing [s@macro-callforwarding:4] NoOp("CAPI/ISDN1#02/995599-6b", "") in new stack
[Jun 21 23:48:38] VERBOSE[8219] pbx.c: -- Executing [995599@ankommend:4] Macro("CAPI/ISDN1#02/995599-6b", "stdcallin,0xxxxxxxxxxx,xxxxxxxxxxx,SIP/13&SIP/14&SIP/15&SIP/16,30") in new stack
[Jun 21 23:48:38] VERBOSE[8219] pbx.c: -- Executing [s@macro-stdcallin:1] System("CAPI/ISDN1#02/995599-6b", "/root/ast_mailnotify.sh 0xxxxxxxxxxx xxxxxxxxxxx 995599 notify") in new stack
[Jun 21 23:48:38] VERBOSE[8219] pbx.c: -- Executing [s@macro-stdcallin:2] GotoIf("CAPI/ISDN1#02/995599-6b", "0?blacklist") in new stack
[Jun 21 23:48:38] VERBOSE[8219] pbx.c: -- Executing [s@macro-stdcallin:3] Set("CAPI/ISDN1#02/995599-6b", "CALLERID(name)=0xxxxxxxxxxx zu 995599") in new stack
[Jun 21 23:48:38] VERBOSE[8219] pbx.c: -- Executing [s@macro-stdcallin:4] Dial("CAPI/ISDN1#02/995599-6b", "SIP/13&SIP/14&SIP/15&SIP/16,30") in new stack
[Jun 21 23:48:38] VERBOSE[8219] netsock2.c: == Using SIP RTP CoS mark 5
[Jun 21 23:48:42] VERBOSE[2205] chan_capi_utils.c: > ISDN1#02: CAPI INFO 0x3490: Normal call clearing
[Jun 21 23:48:42] VERBOSE[2205] chan_capi_utils.c: == ISDN1#01: Incoming call '0xxxxxxxxxxx' -> '995599'
[Jun 21 23:48:42] VERBOSE[8230] pbx.c: -- Executing [995599@ankommend:1] Macro("CAPI/ISDN1#01/995599-6c", "callforwarding,13,0xxxxxxxxxxx") in new stack
[Jun 21 23:48:42] VERBOSE[8230] pbx.c: -- Executing [s@macro-callforwarding:1] Set("CAPI/ISDN1#01/995599-6c", "temp=") in new stack
[Jun 21 23:48:42] VERBOSE[8230] pbx.c: -- Executing [s@macro-callforwarding:2] GotoIf("CAPI/ISDN1#01/995599-6c", "?cfi:nocfi") in new stack
[Jun 21 23:48:42] VERBOSE[8230] pbx.c: -- Goto (macro-callforwarding,s,4)
[Jun 21 23:48:42] VERBOSE[8230] pbx.c: -- Executing [s@macro-callforwarding:4] NoOp("CAPI/ISDN1#01/995599-6c", "") in new stack
[Jun 21 23:48:42] VERBOSE[8230] pbx.c: -- Executing [995599@ankommend:2] Macro("CAPI/ISDN1#01/995599-6c", "callforwarding,14,0xxxxxxxxxxx") in new stack
[Jun 21 23:48:42] VERBOSE[8230] pbx.c: -- Executing [s@macro-callforwarding:1] Set("CAPI/ISDN1#01/995599-6c", "temp=") in new stack
[Jun 21 23:48:42] VERBOSE[8230] pbx.c: -- Executing [s@macro-callforwarding:2] GotoIf("CAPI/ISDN1#01/995599-6c", "?cfi:nocfi") in new stack
[Jun 21 23:48:42] VERBOSE[8230] pbx.c: -- Goto (macro-callforwarding,s,4)
[Jun 21 23:48:42] VERBOSE[8230] pbx.c: -- Executing [s@macro-callforwarding:4] NoOp("CAPI/ISDN1#01/995599-6c", "") in new stack
[Jun 21 23:48:42] VERBOSE[8230] pbx.c: -- Executing [995599@ankommend:3] Macro("CAPI/ISDN1#01/995599-6c", "callforwarding,15,0xxxxxxxxxxx") in new stack
[Jun 21 23:48:42] VERBOSE[8230] pbx.c: -- Executing [s@macro-callforwarding:1] Set("CAPI/ISDN1#01/995599-6c", "temp=") in new stack
[Jun 21 23:48:42] VERBOSE[8230] pbx.c: -- Executing [s@macro-callforwarding:2] GotoIf("CAPI/ISDN1#01/995599-6c", "?cfi:nocfi") in new stack
[Jun 21 23:48:42] VERBOSE[8230] pbx.c: -- Goto (macro-callforwarding,s,4)
[Jun 21 23:48:42] VERBOSE[8230] pbx.c: -- Executing [s@macro-callforwarding:4] NoOp("CAPI/ISDN1#01/995599-6c", "") in new stack
[Jun 21 23:48:42] VERBOSE[8230] pbx.c: -- Executing [995599@ankommend:4] Macro("CAPI/ISDN1#01/995599-6c", "stdcallin,0xxxxxxxxxxx,xxxxxxxxxxx,SIP/13&SIP/14&SIP/15&SIP/16,30") in new stack
[Jun 21 23:48:42] VERBOSE[8230] pbx.c: -- Executing [s@macro-stdcallin:1] System("CAPI/ISDN1#01/995599-6c", "/root/ast_mailnotify.sh 0xxxxxxxxxxx xxxxxxxxxxx 995599 notify") in new stack
[Jun 21 23:48:42] VERBOSE[8230] pbx.c: -- Executing [s@macro-stdcallin:2] GotoIf("CAPI/ISDN1#01/995599-6c", "0?blacklist") in new stack
[Jun 21 23:48:42] VERBOSE[8230] pbx.c: -- Executing [s@macro-stdcallin:3] Set("CAPI/ISDN1#01/995599-6c", "CALLERID(name)=0xxxxxxxxxxx zu 995599") in new stack
[Jun 21 23:48:42] VERBOSE[8230] pbx.c: -- Executing [s@macro-stdcallin:4] Dial("CAPI/ISDN1#01/995599-6c", "SIP/13&SIP/14&SIP/15&SIP/16,30") in new stack
[Jun 21 23:48:42] VERBOSE[8230] netsock2.c: == Using SIP RTP CoS mark 5
[Jun 21 23:48:46] VERBOSE[2205] chan_capi_utils.c: > ISDN1#01: CAPI INFO 0x3490: Normal call clearing
[Jun 21 23:48:54] NOTICE[2181] chan_sip.c: -- Registration for '[email protected]' timed out, trying again (Attempt #412)
[Jun 21 23:48:54] VERBOSE[8230] app_dial.c: -- Called 13
[Jun 21 23:48:54] VERBOSE[8219] app_dial.c: -- Called 13
[Jun 21 23:48:54] VERBOSE[8230] netsock2.c: == Using SIP RTP CoS mark 5
[Jun 21 23:48:54] VERBOSE[8219] netsock2.c: == Using SIP RTP CoS mark 5
[Jun 21 23:48:54] VERBOSE[8230] app_dial.c: -- Called 14
[Jun 21 23:48:54] VERBOSE[8219] app_dial.c: -- Called 14
[Jun 21 23:48:54] VERBOSE[8230] netsock2.c: == Using SIP RTP CoS mark 5
[Jun 21 23:48:54] VERBOSE[8219] netsock2.c: == Using SIP RTP CoS mark 5
[Jun 21 23:48:54] VERBOSE[8230] app_dial.c: -- Called 15
[Jun 21 23:48:54] VERBOSE[8219] app_dial.c: -- Called 15
[Jun 21 23:48:54] VERBOSE[8219] netsock2.c: == Using SIP RTP CoS mark 5
[Jun 21 23:48:54] VERBOSE[8230] netsock2.c: == Using SIP RTP CoS mark 5
[Jun 21 23:48:54] VERBOSE[8219] app_dial.c: -- Called 16
[Jun 21 23:48:54] VERBOSE[8230] app_dial.c: -- Called 16
[Jun 21 23:48:54] VERBOSE[8219] app_macro.c: == Spawn extension (macro-stdcallin, s, 4) exited non-zero on 'CAPI/ISDN1#02/995599-6b' in macro 'stdcallin'
[Jun 21 23:48:54] VERBOSE[8219] pbx.c: == Spawn extension (ankommend, 995599, 4) exited non-zero on 'CAPI/ISDN1#02/995599-6b'
[Jun 21 23:48:54] VERBOSE[8219] chan_capi_utils.c: == ISDN1#02: CAPI Hangingup for PLCI=0xdead0000 in state 4
[Jun 21 23:48:54] VERBOSE[8230] app_macro.c: == Spawn extension (macro-stdcallin, s, 4) exited non-zero on 'CAPI/ISDN1#01/995599-6c' in macro 'stdcallin'
[Jun 21 23:48:54] VERBOSE[8230] pbx.c: == Spawn extension (ankommend, 995599, 4) exited non-zero on 'CAPI/ISDN1#01/995599-6c'
[Jun 21 23:48:54] VERBOSE[8230] chan_capi_utils.c: == ISDN1#01: CAPI Hangingup for PLCI=0xdead0000 in state 4
Logfile mit CAPI Debug:
Code:
[Jun 21 23:43:22] VERBOSE[2205] chan_capi_utils.c: CAPI: ApplId=0x0002 Command=0x02 SubCommand=0x82 MsgNum=0x54c0 NCCI=0x00000101
[Jun 21 23:43:22] VERBOSE[2205] chan_capi_utils.c: CONNECT_IND ID=002 #0x54c0 LEN=0051
Controller/PLCI/NCCI = 0x101
CIPValue = 0x10
CalledPartyNumber = <c1>995599
CallingPartyNumber = <21 83>xxxxxxxxxxx
CalledPartySubaddress = default
CallingPartySubaddress = default
BC = <80 90 a3>
LLC = default
HLC = <91 81>
AdditionalInfo
BChannelinformation = default
Keypadfacility = default
Useruserdata = default
Facilitydataarray = default
SendingComplete = default
[Jun 21 23:43:22] VERBOSE[2205] chan_capi_utils.c: -- CONNECT_IND (PLCI=0x101,DID=995599,CID=xxxxxxxxxxx,CIP=0x10,CONTROLLER=0x1)
[Jun 21 23:43:22] VERBOSE[2205] chan_capi_utils.c: > ISDN1#02: msn='*' DNID='995599' MSN
[Jun 21 23:43:22] VERBOSE[2205] chan_capi_utils.c: == ISDN1#02: setting format alaw - 0x8 (alaw)
[Jun 21 23:43:22] VERBOSE[2205] chan_capi_utils.c: == ISDN1#02: Incoming call '0xxxxxxxxxxx' -> '995599'
[Jun 21 23:43:22] VERBOSE[2205] chan_capi_utils.c: CAPI: ApplId=0x0002 Command=0x08 SubCommand=0x82 MsgNum=0x54c1 NCCI=0x00000101
[Jun 21 23:43:22] VERBOSE[2205] chan_capi_utils.c: INFO_IND ID=002 #0x54c1 LEN=0022
Controller/PLCI/NCCI = 0x101
InfoNumber = 0x70
InfoElement = <c1>995599
[Jun 21 23:43:22] VERBOSE[2205] chan_capi_utils.c: INFO_RESP ID=002 #0x54c1 LEN=0012
Controller/PLCI/NCCI = 0x101
[Jun 21 23:43:22] VERBOSE[2205] chan_capi_utils.c: -- ISDN1#02: info element CALLED PARTY NUMBER
[Jun 21 23:43:22] VERBOSE[2205] chan_capi_utils.c: > ISDN1#02: INFO_IND DID digits not used in this state.
[Jun 21 23:43:22] VERBOSE[2205] chan_capi_utils.c: CAPI: ApplId=0x0002 Command=0x08 SubCommand=0x82 MsgNum=0x54c2 NCCI=0x00000101
[Jun 21 23:43:22] VERBOSE[2205] chan_capi_utils.c: INFO_IND ID=002 #0x54c2 LEN=0015
Controller/PLCI/NCCI = 0x101
InfoNumber = 0xa1
InfoElement = default
[Jun 21 23:43:22] VERBOSE[2205] chan_capi_utils.c: INFO_RESP ID=002 #0x54c2 LEN=0012
Controller/PLCI/NCCI = 0x101
[Jun 21 23:43:22] VERBOSE[2205] chan_capi_utils.c: -- ISDN1#02: info element Sending Complete
[Jun 21 23:43:22] VERBOSE[2205] chan_capi_utils.c: -- ISDN1#02: CAPI/ISDN1#02/995599-69: 995599 matches in context ankommend
[Jun 21 23:43:22] VERBOSE[2158] chan_capi_utils.c: > chan_capi devicestate requested for ISDN1#02/995599 is 'Ringing'
[Jun 21 23:43:22] VERBOSE[2205] chan_capi_utils.c: == Started pbx on channel CAPI/ISDN1#02/995599-69
[Jun 21 23:43:22] VERBOSE[2205] chan_capi_utils.c: CAPI: ApplId=0x0002 Command=0x08 SubCommand=0x82 MsgNum=0x54c3 NCCI=0x00000101
[Jun 21 23:43:22] VERBOSE[8191] pbx.c: -- Executing [995599@ankommend:1] Macro("CAPI/ISDN1#02/995599-69", "callforwarding,13,0xxxxxxxxxxx") in new stack
[Jun 21 23:43:22] VERBOSE[2205] chan_capi_utils.c: INFO_IND ID=002 #0x54c3 LEN=0016
Controller/PLCI/NCCI = 0x101
InfoNumber = 0x18
InfoElement = <89>
[Jun 21 23:43:22] VERBOSE[2205] chan_capi_utils.c: INFO_RESP ID=002 #0x54c3 LEN=0012
Controller/PLCI/NCCI = 0x101
[Jun 21 23:43:22] VERBOSE[2205] chan_capi_utils.c: -- ISDN1#02: info element CHANNEL IDENTIFICATION 89
[Jun 21 23:43:22] VERBOSE[2205] chan_capi_utils.c: CAPI: ApplId=0x0002 Command=0x08 SubCommand=0x82 MsgNum=0x54c4 NCCI=0x00000101
[Jun 21 23:43:22] VERBOSE[2205] chan_capi_utils.c: INFO_IND ID=002 #0x54c4 LEN=0015
Controller/PLCI/NCCI = 0x101
InfoNumber = 0x8005
InfoElement = default
[Jun 21 23:43:22] VERBOSE[2205] chan_capi_utils.c: INFO_RESP ID=002 #0x54c4 LEN=0012
Controller/PLCI/NCCI = 0x101
[Jun 21 23:43:22] VERBOSE[2205] chan_capi_utils.c: -- ISDN1#02: info element SETUP
[Jun 21 23:43:22] VERBOSE[2205] chan_capi_utils.c: > ISDN1#02: IE SETUP / SENDING-COMPLETE already received.
[Jun 21 23:43:22] VERBOSE[8191] pbx.c: -- Executing [s@macro-callforwarding:1] Set("CAPI/ISDN1#02/995599-69", "temp=") in new stack
[Jun 21 23:43:22] VERBOSE[8191] pbx.c: -- Executing [s@macro-callforwarding:2] GotoIf("CAPI/ISDN1#02/995599-69", "?cfi:nocfi") in new stack
[Jun 21 23:43:22] VERBOSE[8191] pbx.c: -- Goto (macro-callforwarding,s,4)
[Jun 21 23:43:22] VERBOSE[8191] pbx.c: -- Executing [s@macro-callforwarding:4] NoOp("CAPI/ISDN1#02/995599-69", "") in new stack
[Jun 21 23:43:22] VERBOSE[8191] pbx.c: -- Executing [995599@ankommend:2] Macro("CAPI/ISDN1#02/995599-69", "callforwarding,14,0xxxxxxxxxxx") in new stack
[Jun 21 23:43:22] VERBOSE[8191] pbx.c: -- Executing [s@macro-callforwarding:1] Set("CAPI/ISDN1#02/995599-69", "temp=") in new stack
[Jun 21 23:43:22] VERBOSE[8191] pbx.c: -- Executing [s@macro-callforwarding:2] GotoIf("CAPI/ISDN1#02/995599-69", "?cfi:nocfi") in new stack
[Jun 21 23:43:22] VERBOSE[8191] pbx.c: -- Goto (macro-callforwarding,s,4)
[Jun 21 23:43:22] VERBOSE[8191] pbx.c: -- Executing [s@macro-callforwarding:4] NoOp("CAPI/ISDN1#02/995599-69", "") in new stack
[Jun 21 23:43:22] VERBOSE[8191] pbx.c: -- Executing [995599@ankommend:3] Macro("CAPI/ISDN1#02/995599-69", "callforwarding,15,0xxxxxxxxxxx") in new stack
[Jun 21 23:43:22] VERBOSE[8191] pbx.c: -- Executing [s@macro-callforwarding:1] Set("CAPI/ISDN1#02/995599-69", "temp=") in new stack
[Jun 21 23:43:22] VERBOSE[8191] pbx.c: -- Executing [s@macro-callforwarding:2] GotoIf("CAPI/ISDN1#02/995599-69", "?cfi:nocfi") in new stack
[Jun 21 23:43:22] VERBOSE[8191] pbx.c: -- Goto (macro-callforwarding,s,4)
[Jun 21 23:43:22] VERBOSE[8191] pbx.c: -- Executing [s@macro-callforwarding:4] NoOp("CAPI/ISDN1#02/995599-69", "") in new stack
[Jun 21 23:43:22] VERBOSE[8191] pbx.c: -- Executing [995599@ankommend:4] Macro("CAPI/ISDN1#02/995599-69", "stdcallin,0xxxxxxxxxxx,xxxxxxxxxxx,SIP/13&SIP/14&SIP/15&SIP/16,30") in new stack
[Jun 21 23:43:22] VERBOSE[8191] pbx.c: -- Executing [s@macro-stdcallin:1] System("CAPI/ISDN1#02/995599-69", "/root/ast_mailnotify.sh 0xxxxxxxxxxx xxxxxxxxxxx 995599 notify") in new stack
[Jun 21 23:43:22] VERBOSE[8191] pbx.c: -- Executing [s@macro-stdcallin:2] GotoIf("CAPI/ISDN1#02/995599-69", "0?blacklist") in new stack
[Jun 21 23:43:22] VERBOSE[8191] pbx.c: -- Executing [s@macro-stdcallin:3] Set("CAPI/ISDN1#02/995599-69", "CALLERID(name)=0xxxxxxxxxxx zu 995599") in new stack
[Jun 21 23:43:22] VERBOSE[8191] pbx.c: -- Executing [s@macro-stdcallin:4] Dial("CAPI/ISDN1#02/995599-69", "SIP/13&SIP/14&SIP/15&SIP/16,30") in new stack
[Jun 21 23:43:22] VERBOSE[8191] netsock2.c: == Using SIP RTP CoS mark 5
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: CAPI: ApplId=0x0002 Command=0x08 SubCommand=0x82 MsgNum=0x54c5 NCCI=0x00000101
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: INFO_IND ID=002 #0x54c5 LEN=0015
Controller/PLCI/NCCI = 0x101
InfoNumber = 0x804d
InfoElement = default
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: INFO_RESP ID=002 #0x54c5 LEN=0012
Controller/PLCI/NCCI = 0x101
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: -- ISDN1#02: info element RELEASE
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: CAPI: ApplId=0x0002 Command=0x04 SubCommand=0x82 MsgNum=0x54c6 NCCI=0x00000101
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: DISCONNECT_IND ID=002 #0x54c6 LEN=0014
Controller/PLCI/NCCI = 0x101
Reason = 0x3490
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: DISCONNECT_RESP ID=002 #0x54c6 LEN=0012
Controller/PLCI/NCCI = 0x101
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: > ISDN1#02: CAPI INFO 0x3490: Normal call clearing
[Jun 21 23:43:26] VERBOSE[2205] frame.c: -- chan_capi queue frame:Control (4) SUBCLASS: Hangup (1) ] [ISDN1#02]
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: CAPI: ApplId=0x0002 Command=0x02 SubCommand=0x82 MsgNum=0x54c7 NCCI=0x00000201
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: CONNECT_IND ID=002 #0x54c7 LEN=0051
Controller/PLCI/NCCI = 0x201
CIPValue = 0x10
CalledPartyNumber = <c1>995599
CallingPartyNumber = <21 83>xxxxxxxxxxx
CalledPartySubaddress = default
CallingPartySubaddress = default
BC = <80 90 a3>
LLC = default
HLC = <91 81>
AdditionalInfo
BChannelinformation = default
Keypadfacility = default
Useruserdata = default
Facilitydataarray = default
SendingComplete = default
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: -- CONNECT_IND (PLCI=0x201,DID=995599,CID=xxxxxxxxxxx,CIP=0x10,CONTROLLER=0x1)
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: > ISDN1#01: msn='*' DNID='995599' MSN
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: == ISDN1#01: setting format alaw - 0x8 (alaw)
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: == ISDN1#01: Incoming call '0xxxxxxxxxxx' -> '995599'
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: CAPI: ApplId=0x0002 Command=0x08 SubCommand=0x82 MsgNum=0x54c8 NCCI=0x00000201
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: INFO_IND ID=002 #0x54c8 LEN=0022
Controller/PLCI/NCCI = 0x201
InfoNumber = 0x70
InfoElement = <c1>995599
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: INFO_RESP ID=002 #0x54c8 LEN=0012
Controller/PLCI/NCCI = 0x201
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: -- ISDN1#01: info element CALLED PARTY NUMBER
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: > ISDN1#01: INFO_IND DID digits not used in this state.
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: CAPI: ApplId=0x0002 Command=0x08 SubCommand=0x82 MsgNum=0x54c9 NCCI=0x00000201
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: INFO_IND ID=002 #0x54c9 LEN=0015
Controller/PLCI/NCCI = 0x201
InfoNumber = 0xa1
InfoElement = default
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: INFO_RESP ID=002 #0x54c9 LEN=0012
Controller/PLCI/NCCI = 0x201
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: -- ISDN1#01: info element Sending Complete
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: -- ISDN1#01: CAPI/ISDN1#01/995599-6a: 995599 matches in context ankommend
[Jun 21 23:43:26] VERBOSE[2158] chan_capi_utils.c: > chan_capi devicestate requested for ISDN1#01/995599 is 'Ringing'
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: == Started pbx on channel CAPI/ISDN1#01/995599-6a
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: CAPI: ApplId=0x0002 Command=0x08 SubCommand=0x82 MsgNum=0x54ca NCCI=0x00000201
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: INFO_IND ID=002 #0x54ca LEN=0016
Controller/PLCI/NCCI = 0x201
InfoNumber = 0x18
InfoElement = <89>
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: INFO_RESP ID=002 #0x54ca LEN=0012
Controller/PLCI/NCCI = 0x201
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: -- ISDN1#01: info element CHANNEL IDENTIFICATION 89
[Jun 21 23:43:26] VERBOSE[8198] pbx.c: -- Executing [995599@ankommend:1] Macro("CAPI/ISDN1#01/995599-6a", "callforwarding,13,0xxxxxxxxxxx") in new stack
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: CAPI: ApplId=0x0002 Command=0x08 SubCommand=0x82 MsgNum=0x54cb NCCI=0x00000201
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: INFO_IND ID=002 #0x54cb LEN=0015
Controller/PLCI/NCCI = 0x201
InfoNumber = 0x8005
InfoElement = default
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: INFO_RESP ID=002 #0x54cb LEN=0012
Controller/PLCI/NCCI = 0x201
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: -- ISDN1#01: info element SETUP
[Jun 21 23:43:26] VERBOSE[2205] chan_capi_utils.c: > ISDN1#01: IE SETUP / SENDING-COMPLETE already received.
[Jun 21 23:43:26] VERBOSE[8198] pbx.c: -- Executing [s@macro-callforwarding:1] Set("CAPI/ISDN1#01/995599-6a", "temp=") in new stack
[Jun 21 23:43:26] VERBOSE[8198] pbx.c: -- Executing [s@macro-callforwarding:2] GotoIf("CAPI/ISDN1#01/995599-6a", "?cfi:nocfi") in new stack
[Jun 21 23:43:26] VERBOSE[8198] pbx.c: -- Goto (macro-callforwarding,s,4)
[Jun 21 23:43:26] VERBOSE[8198] pbx.c: -- Executing [s@macro-callforwarding:4] NoOp("CAPI/ISDN1#01/995599-6a", "") in new stack
[Jun 21 23:43:26] VERBOSE[8198] pbx.c: -- Executing [995599@ankommend:2] Macro("CAPI/ISDN1#01/995599-6a", "callforwarding,14,0xxxxxxxxxxx") in new stack
[Jun 21 23:43:26] VERBOSE[8198] pbx.c: -- Executing [s@macro-callforwarding:1] Set("CAPI/ISDN1#01/995599-6a", "temp=") in new stack
[Jun 21 23:43:26] VERBOSE[8198] pbx.c: -- Executing [s@macro-callforwarding:2] GotoIf("CAPI/ISDN1#01/995599-6a", "?cfi:nocfi") in new stack
[Jun 21 23:43:26] VERBOSE[8198] pbx.c: -- Goto (macro-callforwarding,s,4)
[Jun 21 23:43:26] VERBOSE[8198] pbx.c: -- Executing [s@macro-callforwarding:4] NoOp("CAPI/ISDN1#01/995599-6a", "") in new stack
[Jun 21 23:43:26] VERBOSE[8198] pbx.c: -- Executing [995599@ankommend:3] Macro("CAPI/ISDN1#01/995599-6a", "callforwarding,15,0xxxxxxxxxxx") in new stack
[Jun 21 23:43:26] VERBOSE[8198] pbx.c: -- Executing [s@macro-callforwarding:1] Set("CAPI/ISDN1#01/995599-6a", "temp=") in new stack
[Jun 21 23:43:26] VERBOSE[8198] pbx.c: -- Executing [s@macro-callforwarding:2] GotoIf("CAPI/ISDN1#01/995599-6a", "?cfi:nocfi") in new stack
[Jun 21 23:43:26] VERBOSE[8198] pbx.c: -- Goto (macro-callforwarding,s,4)
[Jun 21 23:43:26] VERBOSE[8198] pbx.c: -- Executing [s@macro-callforwarding:4] NoOp("CAPI/ISDN1#01/995599-6a", "") in new stack
[Jun 21 23:43:26] VERBOSE[8198] pbx.c: -- Executing [995599@ankommend:4] Macro("CAPI/ISDN1#01/995599-6a", "stdcallin,0xxxxxxxxxxx,xxxxxxxxxxx,SIP/13&SIP/14&SIP/15&SIP/16,30") in new stack
[Jun 21 23:43:26] VERBOSE[8198] pbx.c: -- Executing [s@macro-stdcallin:1] System("CAPI/ISDN1#01/995599-6a", "/root/ast_mailnotify.sh 0xxxxxxxxxxx xxxxxxxxxxx 995599 notify") in new stack
[Jun 21 23:43:26] VERBOSE[8198] pbx.c: -- Executing [s@macro-stdcallin:2] GotoIf("CAPI/ISDN1#01/995599-6a", "0?blacklist") in new stack
[Jun 21 23:43:26] VERBOSE[8198] pbx.c: -- Executing [s@macro-stdcallin:3] Set("CAPI/ISDN1#01/995599-6a", "CALLERID(name)=0xxxxxxxxxxx zu 995599") in new stack
[Jun 21 23:43:26] VERBOSE[8198] pbx.c: -- Executing [s@macro-stdcallin:4] Dial("CAPI/ISDN1#01/995599-6a", "SIP/13&SIP/14&SIP/15&SIP/16,30") in new stack
[Jun 21 23:43:26] VERBOSE[8198] netsock2.c: == Using SIP RTP CoS mark 5
[Jun 21 23:43:30] VERBOSE[2205] chan_capi_utils.c: CAPI: ApplId=0x0002 Command=0x08 SubCommand=0x82 MsgNum=0x54cc NCCI=0x00000201
[Jun 21 23:43:30] VERBOSE[2205] chan_capi_utils.c: INFO_IND ID=002 #0x54cc LEN=0015
Controller/PLCI/NCCI = 0x201
InfoNumber = 0x804d
InfoElement = default
[Jun 21 23:43:30] VERBOSE[2205] chan_capi_utils.c: INFO_RESP ID=002 #0x54cc LEN=0012
Controller/PLCI/NCCI = 0x201
[Jun 21 23:43:30] VERBOSE[2205] chan_capi_utils.c: -- ISDN1#01: info element RELEASE
[Jun 21 23:43:30] VERBOSE[2205] chan_capi_utils.c: CAPI: ApplId=0x0002 Command=0x04 SubCommand=0x82 MsgNum=0x54cd NCCI=0x00000201
[Jun 21 23:43:30] VERBOSE[2205] chan_capi_utils.c: DISCONNECT_IND ID=002 #0x54cd LEN=0014
Controller/PLCI/NCCI = 0x201
Reason = 0x3490
[Jun 21 23:43:30] VERBOSE[2205] chan_capi_utils.c: DISCONNECT_RESP ID=002 #0x54cd LEN=0012
Controller/PLCI/NCCI = 0x201
[Jun 21 23:43:30] VERBOSE[2205] chan_capi_utils.c: > ISDN1#01: CAPI INFO 0x3490: Normal call clearing
[Jun 21 23:43:30] VERBOSE[2205] frame.c: -- chan_capi queue frame:Control (4) SUBCLASS: Hangup (1) ] [ISDN1#01]
[Jun 21 23:43:33] NOTICE[2181] chan_sip.c: -- Registration for '[email protected]' timed out, trying again (Attempt #404)
[Jun 21 23:43:33] VERBOSE[8191] app_dial.c: -- Called 13
[Jun 21 23:43:33] VERBOSE[8198] app_dial.c: -- Called 13
[Jun 21 23:43:33] VERBOSE[8191] netsock2.c: == Using SIP RTP CoS mark 5
[Jun 21 23:43:33] VERBOSE[8198] netsock2.c: == Using SIP RTP CoS mark 5
[Jun 21 23:43:33] VERBOSE[8191] app_dial.c: -- Called 14
[Jun 21 23:43:33] VERBOSE[8191] netsock2.c: == Using SIP RTP CoS mark 5
[Jun 21 23:43:33] VERBOSE[8198] app_dial.c: -- Called 14
[Jun 21 23:43:33] VERBOSE[8198] netsock2.c: == Using SIP RTP CoS mark 5
[Jun 21 23:43:33] VERBOSE[8191] app_dial.c: -- Called 15
[Jun 21 23:43:33] VERBOSE[8191] netsock2.c: == Using SIP RTP CoS mark 5
[Jun 21 23:43:33] VERBOSE[8191] app_dial.c: -- Called 16
[Jun 21 23:43:33] VERBOSE[8198] app_dial.c: -- Called 15
[Jun 21 23:43:33] VERBOSE[8198] netsock2.c: == Using SIP RTP CoS mark 5
[Jun 21 23:43:33] VERBOSE[8191] app_macro.c: == Spawn extension (macro-stdcallin, s, 4) exited non-zero on 'CAPI/ISDN1#02/995599-69' in macro 'stdcallin'
[Jun 21 23:43:33] VERBOSE[8191] pbx.c: == Spawn extension (ankommend, 995599, 4) exited non-zero on 'CAPI/ISDN1#02/995599-69'
[Jun 21 23:43:33] VERBOSE[8198] app_dial.c: -- Called 16
[Jun 21 23:43:33] VERBOSE[8191] chan_capi_utils.c: == ISDN1#02: CAPI Hangingup for PLCI=0xdead0000 in state 4
[Jun 21 23:43:33] VERBOSE[8198] app_macro.c: == Spawn extension (macro-stdcallin, s, 4) exited non-zero on 'CAPI/ISDN1#01/995599-6a' in macro 'stdcallin'
[Jun 21 23:43:33] VERBOSE[8198] pbx.c: == Spawn extension (ankommend, 995599, 4) exited non-zero on 'CAPI/ISDN1#01/995599-6a'
[Jun 21 23:43:33] VERBOSE[8191] chan_capi_utils.c: == ISDN1#02: Interface cleanup PLCI=0xdead0000
[Jun 21 23:43:33] VERBOSE[8198] chan_capi_utils.c: == ISDN1#01: CAPI Hangingup for PLCI=0xdead0000 in state 4
[Jun 21 23:43:33] VERBOSE[2158] chan_capi_utils.c: > chan_capi devicestate requested for ISDN1#02/995599 is 'Not in use'
[Jun 21 23:43:33] VERBOSE[8198] chan_capi_utils.c: == ISDN1#01: Interface cleanup PLCI=0xdead0000
[Jun 21 23:43:33] VERBOSE[2158] chan_capi_utils.c: > chan_capi devicestate requested for ISDN1#01/995599 is 'Not in use'
[Jun 21 23:43:33] VERBOSE[2158] chan_capi_utils.c: > chan_capi devicestate requested for ISDN1#02/995599 is 'Not in use'
[Jun 21 23:43:33] VERBOSE[2158] chan_capi_utils.c: > chan_capi devicestate requested for ISDN1#01/995599 is 'Not in use'
Der für den "ankommend" zuständige Context:
Code:
[macro-stdcallin]
; ARG1 = Anrufer | ARG2 = mailadresse | ARG3 = Endgeraete | ARG4 = Voicemaildauer
exten => s,1,System(/root/ast_mailnotify.sh ${ARG1} ${ARG2} ${MACRO_EXTEN} notify)
; SKY
; exten => s,n,GotoIf($["${CALLERID(num)}" = "01802000159"]?blacklist)
; exten => s,n,GotoIf($["${CALLERID(num)}" = "01802001534"]?blacklist)
; exten => s,n,GotoIf($["${CALLERID(num)}" = "01802000158"]?blacklist)
; EMNID
exten => s,n,GotoIf($["${CALLERID(num)}" = "0891247111346"]?blacklist)
; Normal
exten => s,n,Set(CALLERID(name)=${ARG1} zu ${MACRO_EXTEN})
exten => s,n,Dial(${ARG3},${ARG4})
exten => s,n,Macro(tovoicemail,${MACRO_EXTEN})
; exten => s,n(blacklist),Answer
exten => s,n(blacklist),System(/root/ast_mailnotify.sh ${ARG1} ${ARG2} ${MACRO_EXTEN} abweisung)
exten => s,n(blacklist),SetVar(PRI_CAUSE=1)
exten => s,n(blacklist),HangUp(1)
Code:
exten => ${ISDN-SVEN-1},1,Macro(callforwarding,13,${CALLERID(num)})
exten => ${ISDN-SVEN-1},n,Macro(callforwarding,14,${CALLERID(num)})
exten => ${ISDN-SVEN-1},n,Macro(callforwarding,15,${CALLERID(num)})
exten => ${ISDN-SVEN-1},n,Macro(stdcallin,${CALLERID(num)},xxxxxxxxxxxxxxxx,${EG-SIP-SVEN},30)
Mehrere Reboots haben nicht geholfen. Das Problem tritt auch nicht bei jedem Call auf.
Hat irgendjemand eine Idee was ich machen kann? Ich habe die Vermutung, dass der Asterisk einfach viel zu lange für das Dial Command benötigt und deshalb der Switch mit Cause 80 wegen Timeout auslöst.