problem z ISDN

[kamilf]

Witam,

mam problem z ISDN na UC520. Zachowuje się to tak, że rozmowy wychodzące działają bez problemu, natomiast przychodzące nie działają.

UC520 zastąpił centralę SLICAN, obsługującą 3 terminale ISDN z numerami DDI. Każdy z ISDN obsługuje taką samą numerację DDI, tzn. jeżeli odłączę jeden lub dwa ISDNy to nadal wszystkie numery działają przez jeden ISDN. Na SLICANie to działa, gdy przełączę na port ISDN w UC520 rozmowy przychodzące nie działają.

Logi statusu ISDN:

Kod: Zaznacz cały

UC520#sh isdn status
ISDN BRI0/2/1 interface
        dsl 5, interface ISDN Switchtype = basic-net3
    Layer 1 Status:
        ACTIVE
    Layer 2 Status:
        TEI = 65, Ces = 1, SAPI = 0, State = TEI_ASSIGNED
    Layer 3 Status:
        0 Active Layer 3 Call(s)
    Active dsl 5 CCBs = 0
    The Free Channel Mask:  0x80000003
    Total Allocated ISDN CCBs = 0
UC520#

Konfiguracja portów ISDN na UC520:

Kod: Zaznacz cały

!
interface BRI0/2/0
 no ip address
 isdn switch-type basic-net3
 isdn point-to-point-setup
 isdn incoming-voice voice
 isdn sending-complete
!
interface BRI0/2/1
 no ip address
 isdn switch-type basic-net3
 isdn point-to-point-setup
 isdn incoming-voice voice
 isdn sending-complete
!
!
voice-port 0/2/0
 translation-profile incoming IN
 translation-profile outgoing 1
 compand-type a-law
 cptone PL
!
voice-port 0/2/1
 translation-profile incoming IN
 translation-profile outgoing 1
 compand-type a-law
 cptone PL
!

Dial-peer`y wychodzące:

Kod: Zaznacz cały

!
dial-peer voice 200 pots
 destination-pattern 0.........
 port 0/2/1
 forward-digits 9
!
dial-peer voice 210 pots
 destination-pattern 0.........
 port 0/2/0
 forward-digits 9
!

Translacje:

Kod: Zaznacz cały

!
voice translation-rule 1
 rule 1 /^0\(.*\)/ /\1/
!
voice translation-rule 2
 rule 1 /^.*\(..\)/ /\1/
!

!
voice translation-profile 1
 translate called 1
!
voice translation-profile IN
 translate called 2
!

Próbowałem logować przez debug isdn status - przy wychodzących połączeniach pojawiają się logi, zapala się też dioda B1 przy porcie BRI. Dioda OK pali się bez przerwy. Przy przychodzących logi nie pojawiają się.

Co może być przyczyną? Zgłosiłem problem na infolinię TP, ale twierdzą, że jest dobrze, ponieważ na SLICANie działa. Proszę o pomoc.

Pozdrawiam,

Kamil

[kamilf]

TP przekazała informację, że na tych 3 ISDNach jest skonfigurowana wiązka PBX i nie ma konkretnych DDI przypisanych do ISDNów.

Jak CME może taką wiązkę PBX wspierać z poziomu konfiguracji?

Pozdrawiam,

Kamil

[Kyniu]

Na przykład konfigurujesz dial-peer dla połączeń przychodzących jako DID:

Kod: Zaznacz cały

dial-peer voice 1 pots
 description Incoming calls
 incoming called-number 1212345..
 direct-inward-dial
 port x/x/x
 forward-digits all

a w konfiguracji ephone-dn masz pełny numer:

Kod: Zaznacz cały

ephone-dn  15  dual-line
 number 105 secondary 121234505

Powiedzmy, że teraz ktoś dzwoni na numer 121234505. Router patrzy na "incoming called-number 1212345.." - pasuje - pasuje. To przyjmuje połączenie i przekazuje (forward-digits all) dalej szukając numeru 121234505. A taki numer pasuje do ephone-dn 15 (secondary 121234505). Teoretycznie to "incoming called-number 1212345.." jest nawet zbędne ale po co masz "obrabiać" błędnie wysłane wywołania. Jak called-number nie pasuje do wzorca to do hasioka. A jak ktoś wybierze powiedzmy 121234506 którego nie ma w systemie to połączenie i tak nie zostanie zrealizowane bo router odpowie komunikatem "ISDN Se0/0/0:15 Q931: TX -> DISCONNECT (...) Cause - Unallocated/unassigned number". A Ty przy okazji możesz to wszystko śledzić

[kamilf]

Dzięki, przećwiczę podaną konfigurację.

Czyli nie można obsłużyć DID z wiązki PBX przez konfigurację voice-port zawierającą translation-profile?

Wcześniej, gdy miałem do czynienia ze standardowym ISDN (nie wiązką PBX) tak robiłem i bez problemu działało.

Pozdrawiam,

Kamil

[Kyniu]

Czyli nie można obsłużyć DID z wiązki PBX przez konfigurację voice-port zawierającą translation-profile?

Jeśli potrzebujesz dokonywać translacji to - tak bez głębszego analizowania problemu - nie widzę przeciwwskazań. Tylko zwróć uwagę na "calling number" i "called number" oraz zważ, że musisz je postrzegać "odwrotnie" niż dla połączeń wychodzących.

Wcześniej, gdy miałem do czynienia ze standardowym ISDN (nie wiązką PBX) tak robiłem i bez problemu działało.

Ale tu nie ma wielkiej różnicy, jedyne co Ci operator powiedział, to że możesz się spodziewać połączenia na dowolny numer z przyznanej puli i że przyśle Ci pełny numer a nie tylko dwie lub trzy ostatnie cyfry. Zresztą zrób sobie "debug isdn q931" i zadzwoń do siebie z innego telefonu albo z komórki.

Zresztą masz przykład poniżej.

Przyszło połączenie od operatora

Kod: Zaznacz cały

034385: Apr 24 21:12:36.497 CET: ISDN Se0/0/0:15 Q931: RX <- SETUP pd = 8  callref = 0x0069
        Sending Complete
        Bearer Capability i = 0x8090A3
                Standard = CCITT
                Transfer Capability = Speech
                Transfer Mode = Circuit
                Transfer Rate = 64 kbit/s
        Channel ID i = 0xA98381
                Exclusive, Channel 1
        Calling Party Number i = 0x2183, '6xy6ab6cd' <--- z tego numeru dzwoniłem !!!
                Plan:ISDN, Type:National
        Called Party Number i = 0xA1, '221234567'  <--- na ten numer dzwoniłem !!! (oczywiście tu zmieniony)
                Plan:ISDN, Type:National
        High Layer Compat i = 0x9181
034386: Apr 24 21:12:36.525 CET: ISDN Se0/0/0:15 Q931: TX -> CALL_PROC pd = 8  callref = 0x8069
        Channel ID i = 0xA98381
                Exclusive, Channel 1

Dzwoniący się rozmyślił i zakończył połączenie.

Kod: Zaznacz cały

034388: Apr 24 21:12:38.713 CET: ISDN Se0/0/0:15 Q931: RX <- DISCONNECT pd = 8  callref = 0x0069

Obie strony (router i operator) mówią sobie papa.

Kod: Zaznacz cały

034389: Apr 24 21:12:38.717 CET: ISDN Se0/0/0:15 Q931: TX -> RELEASE pd = 8  callref = 0x8069
034390: Apr 24 21:12:38.781 CET: ISDN Se0/0/0:15 Q931: RX <- RELEASE_COMP pd = 8  callref = 0x0069

[kamilf]

Właśnie w tym problem, że gdy robię "debug isdn all" i dzwonię na dowolny numer z tej wiązki, to nic nie pojawia się w logach. Nie wiem czy powinna się zapalać dioda B1 na VICu, ale nie zapala się. Przy wychodzącym połączeniu zapala się B1. Gdy dzwonię z zewnątrz, od mojego operatora komórkowego dostaję informację, że to połączenie nie może zostać zrealizowane.

Gdybym miał log z "debug isdn all" dla połączeń przychodzących to prawdopodobnie sprawa byłaby wyjaśniona. Natomiast dostaję wynik dla "debug isdn all" tylko dla połączeń wychodzących, które działają bez problemu.

Przełączę na SLICANa i działa to bez problemu.

Jedyna różnica w konfiguracji to brak direct-inward-dial, ale nie mam dial-peer`y przychodzącej, tylko tak jak wyżej napisałem próbuję połączenia przychodzące załatwić poprzez translation-rule odcinającą z czegokolwiek co do mnie trafia, ostatnie dwie cyfry, które stanowią mój numer wewnętrzny zdefiniowoany na ephone-dn.

[Kyniu]

Właśnie w tym problem, że gdy robię "debug isdn all" i dzwonię na dowolny numer z tej wiązki, to nic nie pojawia się w logach.

To plus to:

Kod: Zaznacz cały

UC520#sh isdn status 
ISDN BRI0/2/1 interface 
        dsl 5, interface ISDN Switchtype = basic-net3 
    Layer 1 Status: 
        ACTIVE 
    Layer 2 Status: 
        TEI = 65, Ces = 1, SAPI = 0, State = TEI_ASSIGNED 
    Layer 3 Status: 
        0 Active Layer 3 Call(s) 
    Active dsl 5 CCBs = 0 
    The Free Channel Mask:  0x80000003 
    Total Allocated ISDN CCBs = 0 
UC520# 

Sugerowałoby problem z łączem bo powinno być:

Kod: Zaznacz cały

Layer 1 Status:
        ACTIVE
    Layer 2 Status:
        TEI = 0, Ces = 1, SAPI = 0, State = MULTIPLE_FRAME_ESTABLISHED

Ale z drugiej strony piszesz "Przełączę na SLICANa i działa to bez problemu". Jeszcze jest taka opcja, że to VIC jest źródłem problemu.

Jedyna różnica w konfiguracji to brak direct-inward-dial, ale nie mam dial-peer`y przychodzącej, tylko tak jak wyżej napisałem próbuję połączenia przychodzące załatwić poprzez translation-rule odcinającą z czegokolwiek co do mnie trafia, ostatnie dwie cyfry, które stanowią mój numer wewnętrzny zdefiniowoany na ephone-dn.

Ale jak chcesz odebrać połączenie jak nie masz dial-peer'a? Pozwolę sobie na cytat:

Call Setup Process

• A call is segmented into call legs with a dial peer associated to each call leg. The process for this is listed here:
  
  The POTS call arrives at the originating router/gateway. An inbound POTS dial-peer is matched. (See Note 3 later in this document).
  
  After it associates the incoming call to an inbound POTS dial-peer, the originating router/gateway creates an inbound POTS call leg and assigns it a Call ID (Call Leg 1 in Figure 1).
  
  The originating router/gateway uses the dialed string to match an outbound Voice-Network dial-peer.
  
  After it associates the dialed string to an outbound Voice-Network dial-peer, the originating router/gateway creates an outbound Voice-Network call leg and assigns it a Call ID (Call Leg 2 in Figure 1).
  
  The Voice-Network call requests arrive at the terminating router/gateway. An inbound Voice-Network dial-peer is matched.
  
  After the terminating router/gateway associates the incoming call to an inbound Voice-Network dial peer, the terminating router/gateway creates the inbound Voice-Network call leg and assigns it a Call ID. (Call Leg 3 in in Figure 1)
  
  The terminating router/gateway uses the dialed string to match an outbound POTS dial-peer.
  
  After it associates the incoming call setup to an outbound POTS dial peer, the terminating gateway/router creates an outbound POTS call leg. It assigns it a Call ID, and terminates the call. (Call Leg 4 in Figure 1)

http://www.cisco.com/c/en/us/support/do ... l-leg.html

[kamilf]

Jeżeli chodzi o status ISDN to raczej jest dobrze, ponieważ na innym UC w innej lokalizacji, który pracujące prawidłowo jest identycznie tzn. "State = TEI_ASSIGNED" (nie ma w tym czasie rozmowy na porcie).

Porównując z konfiguracją w innej lokalizacji brakuje mi natomiast:

network-clock-participate wic 1
network-clock-select 1 BRI0/1/0

Czy to może mieć wpływ na działanie tego ISDNa?

Pomijając kwestie dial-peer i translacji - czy mimo wszystko dzwoniąc z komórki na numer DDI z tej wiązki, nie powinno pokazać się cokolwiek w debug isdn all nawet bez konfiguracji dial-peer?

[Kyniu]

Jeżeli chodzi o status ISDN to raczej jest dobrze, ponieważ na innym UC w innej lokalizacji, który pracujące prawidłowo jest identycznie tzn. "State = TEI_ASSIGNED" (nie ma w tym czasie rozmowy na porcie).

Tylko że to oznacza brak wymiany informacji między VIC a switchem ISDN. Natomiast MULTIPLE_FRAME_ESTABLISHED potwierdza, że komunikacja ma miejsce. To może tłumaczyć brak jakichkolwiek informacji przychodzących.

Porównując z konfiguracją w innej lokalizacji brakuje mi natomiast:

network-clock-participate wic 1
network-clock-select 1 BRI0/1/0

Czy to może mieć wpływ na działanie tego ISDNa?

To zależy co rozumiemy przez "wpływ na działanie". W praktyce bez tego, przynajmniej w przypadkach które znam z praktyki, działały rozmowy ale sypały się faksy, a nawet nie tyle sama transmisja co potwierdzenia transmisji. No i rosną liczniki błędów na kontrolerze.

Pomijając kwestie dial-peer i translacji - czy mimo wszystko dzwoniąc z komórki na numer DDI z tej wiązki, nie powinno pokazać się cokolwiek w debug isdn all nawet bez konfiguracji dial-peer?

Powinno. Brak dial-peer'a uniemożliwi zestawienie połączenia ale sama komunikacja z ISDN nie ma związku z istnieniem lub nie dial-peer'a. Aczkolwiek złapałem się kiedyś na tym, że UC500 nie zachowują się do końca identycznie z CUCME na ISR/ISR G2 ale już nie pamiętam o co chodziło. Chyba miało związek z UNITY.

[kamilf]

Spróbuję jutro dodać jeszcze dial-peer przychodzący, ale tak jak stwierdziłeś bez niego także powinny pojawiać się logi z debug isdn all przy próbie połączenia przychodzącego.

Wszystko wygląda na problem z Layer 2/3 ISDNa, ale z drugiej strony wychodzące połączenia działają. Sprawdzę jak wygląda "State =", gdy wykonuję połączenie wychodzące tzn. czy jest "MULTIPLE_FRAME_ESTABLISHED" zamiast "TEI_ASSIGNED".

Nie bardzo mam koncepcję co zrobić z tym dalej. Generalnie na SLICANie działa, więc można założyć, że w TP jest poprawnie lub istnieje niezidentyfikowana niezgodność ich centrali z BRI UC. Może uszkodzony jest port BRI, ale aktualnie nie mam jak tego sprawdzić. TP zaproponowało, że może rozszyć DDI z wiązki PBX na poszczególne terminale ISDN, ale trudno powiedzieć, czy to coś zmieni.

[przemek_z]

Hej,


state=TEI_ASSIGNED indicates that the router has lost connectivity to the switch. This is normal if the Telco (commonly in Europe) deactivates Layers 1 and 2 when there are no active calls. (Dotyczy BRI)

Co pokazuje debug isdn all podczas połączeń wychodzących?

[kamilf]

Gdy nie ma rozmowy jest:

Kod: Zaznacz cały

UC520#show isdn status bri 0/2/1
Global ISDN Switchtype = basic-net3
ISDN BRI0/2/1 interface
        dsl 5, interface ISDN Switchtype = basic-net3
    Layer 1 Status:
        ACTIVE
    Layer 2 Status:
        TEI = 66, Ces = 1, SAPI = 0, State = TEI_ASSIGNED
    Layer 3 Status:
        0 Active Layer 3 Call(s)
    Active dsl 5 CCBs = 0
    The Free Channel Mask:  0x80000003
    Total Allocated ISDN CCBs = 0
UC520#

Gdy mam rozmowe wychodzaca:

Kod: Zaznacz cały

UC520#
normally.show isdn status bri 0/2/1
Global ISDN Switchtype = basic-net3
ISDN BRI0/2/1 interface
        dsl 5, interface ISDN Switchtype = basic-net3
    Layer 1 Status:
        ACTIVE
    Layer 2 Status:
        TEI = 66, Ces = 1, SAPI = 0, State = MULTIPLE_FRAME_ESTABLISHED
    Layer 3 Status:
        0 Active Layer 3 Call(s)
        CCB:callid=8033, sapi=0, ces=1, B-chan=1, calltype=VOICE
    Active dsl 5 CCBs = 1
    The Free Channel Mask:  0x80000002
    Total Allocated ISDN CCBs = 1
UC520#

Wynik "debug isdn q931" przy wychodzacej rozmowie:

Kod: Zaznacz cały

010339: Apr 25 08:12:27.339: ISDN BR0/2/0 Q931: Applying typeplan for sw-type 0x1 is 0x0 0x0, Calling num 88
010340: Apr 25 08:12:27.339: ISDN BR0/2/0 Q931: Sending SETUP  callref = 0x0016 callID = 0x8034 switch = basic-net3 interface = User
010341: Apr 25 08:12:27.339: ISDN BR0/2/0 Q931: TX -> SETUP pd = 8  callref = 0x16
        Bearer Capability i = 0x8090A3
                Standard = CCITT
                Transfer Capability = Speech
                Transfer Mode = Circuit
                Transfer Rate = 64 kbit/s
        Channel ID i = 0x81
                Preferred, B1
        Progress Ind i = 0x8183 - Origination address is non-ISDN
        Calling Party Number i = 0x0080, '88'
                Plan:Unknown, Type:Unknown
        Called Party Number i = 0x80, '694XXXXXX'
                Plan:Unknown, Type:Unknown
        Sending Complete
010342: Apr 25 08:12:27.743: ISDN BR0/2/0 Q931: RX <- CALL_PROC pd = 8  callref = 0x96
        Channel ID i = 0x89
                Exclusive, B1
        Display i = 'Zestawianie'
010343: Apr 25 08:12:35.211: ISDN BR0/2/0 Q931: RX <- ALERTING pd = 8  callref = 0x96
        Display i = 'Wywolywanie'
010344: Apr 25 08:12:58.647: ISDN BR0/2/0 Q931: TX -> DISCONNECT pd = 8  callref = 0x16
        Cause i = 0x8090 - Normal call clearing
010345: Apr 25 08:12:58.807: ISDN BR0/2/0 Q931: RX <- RELEASE pd = 8  callref = 0x96
010346: Apr 25 08:12:58.807: ISDN BR0/2/0 Q931: TX -> RELEASE_COMP pd = 8  callref = 0x16

Aktualnie na testy mam podlaczony jeden z trzech terminali ISDN przypisanych do tej wiazki, ale to raczej nie powinno miec wplywu. Na SLICANie moze byc jeden, dwa lub trzy terminale i zawsze dziala.

Pozdrawiam,

Kamil

[kamilf]

Wynik "debug isdn all" przy rozmowie wychodzacej:

Kod: Zaznacz cały

010382: Apr 25 08:22:48.107: ISDN BR0/2/1 EVENTd: isdn_get_guid: Got Guid 9C73097983C7
010383: Apr 25 08:22:48.111: ISDN BR0/2/1 EVENT: process_bri_call: call id 0x8035, called_number 694XXXXXX, Guid 9C73097983C7 speed 0, call type VOICE, bchan -1 clng_num 88Received pdata len 0x3F data:1C 39 9E 1 0 3 67 74 64 0 0 0 2E 49 41 4D 2C D A 47 43 49 2C 39 63 37 33 30 39 37 39 63 62 38 39 31 31 65 33 38 33 63 37 63 34 61 63 61 65 30 35 61 32 31 35 D A D A 1E 2 81 83
010384: Apr 25 08:22:48.111: ISDN BR0/2/1 EVENTd: process_bri_call: No name in GTD
010385: Apr 25 08:22:48.111: ISDN BR0/2/1 Error: Invalid DSL (0)  EVENTd: isdn_get_display_text_from_gtd: false ret
010386: Apr 25 08:22:48.111: ISDN BR0/2/1 EVENTd: process_bri_call: Name Modified by Calldecode
Name Type 0x0, cs 0x0, ie_val 0x28, pi 0x0, avail 0x0
        fachd len 0x0 data:
        inpdu len 0x0 data:
        name len 0xD data:43 4F 4D 44 52 45 56 20 41 64 6D 69 6E
010387: Apr 25 08:22:48.111: ISDN BR0/2/1 Q931d: Default type/plan 0x0 0x0 sw-type 1 Calling number 88 isdn_map 0 mask 0x3042
010388: Apr 25 08:22:48.111: ISDN BR0/2/1 Q931d: Calling plan/type unspecified in call_decode, so ignored.
010389: Apr 25 08:22:48.111: ISDN BR0/2/1 Q931d: No call_decode, isdn default set; Oct3a 0x0
010390: Apr 25 08:22:48.111: ISDN BR0/2/1 Q931d: Default type/plan 0x0 0x0 sw-type 1 Called number 694XXXXXX isdn_map 0 mask 0x3042
010391: Apr 25 08:22:48.111: ISDN BR0/2/1 Q931d: Called plan/type unspecified in call_decode, so ignored.
010392: Apr 25 08:22:48.111: ISDN BR0/2/1 Q931d: No call_decode, isdn default set
010393: Apr 25 08:22:48.111: ISDN BR0/2/1 CC: CCBRI_Go: source id 0x500, state 0, call id 0x0, event 0x1 ces 0
010394: Apr 25 08:22:48.111: ISDN BR0/2/1 CCd: CCBRI_Go: Host PkgInfo:
010395: Apr 25 08:22:48.111:    0105018035146C050080003838700B000036393434363237323504038090A31801831E028183280D434F4D445245562041646D696EA1--00000CF2
010396: Apr 25 08:22:48.111: ISDN BR0/2/1 CC: CCBRI_Go: call id 0x8035 callref 0x0 state 1 event 0x1 Src->HOST
010397: Apr 25 08:22:48.111: ISDN BR0/2/1 CCd: CCBRI_SetNewChan: ReleaseChan 3 earlier chan 0 exclusive 0
010398: Apr 25 08:22:48.111: ISDN BR0/2/1 CCd: CC_CHAN_GetIdleChanbri: Found idle channel B1
010399: Apr 25 08:22:48.111: ISDN BR0/2/1 TGRM: get_tgrm_avail_state: idb 0x8708D140 bchan 1 service_state 0 call_state 1 false busy 0x0 dsl 5
010400: Apr 25 08:22:48.111: ISDN BR0/2/1 TGRM: update_tgrm_call_status: idb 0x8708D140 bchan 1 availability state 1 call state(prev,new) (0,1), dsl 5
010401: Apr 25 08:22:48.111: ISDN BR0/2/1 TGRM: update_tgrm_call_status: Calling TGRM with tgrm_call_isdn_update: idb 0x8708D140 bchan 1 call state 2 call type 2 call dir 2
010402: Apr 25 08:22:48.115: ISDN BR0/2/1 CCd: ie_in_out_msg: message 0x80 ie = 0 ie_allowed =1
010403: Apr 25 08:22:48.115: ISDN BR0/2/1 CCd: ie_in_out_msg: message 0x80 ie = 3 ie_allowed =1
010404: Apr 25 08:22:48.115: ISDN BR0/2/1 CCd: ie_in_out_msg: message 0x80 ie = 5 ie_allowed =1
010405: Apr 25 08:22:48.115: ISDN BR0/2/1 CCd: ie_in_out_msg: message 0x80 ie = 7 ie_allowed =1
010406: Apr 25 08:22:48.115: ISDN BR0/2/1 CCd: ie_in_out_msg: message 0x80 ie = 8 ie_allowed =1
010407: Apr 25 08:22:48.115: ISDN BR0/2/1 CCd: ie_in_out_msg: message 0x80 ie = 9 ie_allowed =0
010408: Apr 25 08:22:48.115: ISDN BR0/2/1 CCd: ie_in_out_msg: message 0x80 ie = 10 ie_allowed =0
010409: Apr 25 08:22:48.115: ISDN BR0/2/1 CCd: ie_in_out_msg: message 0x80 ie = 12 ie_allowed =1
010410: Apr 25 08:22:48.115: ISDN BR0/2/1 CCd: ie_in_out_msg: message 0x80 ie = 26 ie_allowed =0
010411: Apr 25 08:22:48.115: ISDN BR0/2/1 CCd: ie_in_out_msg: message 0x80 ie = 15 ie_allowed =1
010412: Apr 25 08:22:48.115: ISDN BR0/2/1 CCd: ie_in_out_msg: message 0x80 ie = 16 ie_allowed =1
010413: Apr 25 08:22:48.115: ISDN BR0/2/1 CCd: ie_in_out_msg: message 0x80 ie = 17 ie_allowed =1
010414: Apr 25 08:22:48.115: ISDN BR0/2/1 CCd: ie_in_out_msg: message 0x80 ie = 19 ie_allowed =1
010415: Apr 25 08:22:48.115: ISDN BR0/2/1 CCd: ie_in_out_msg: message 0x80 ie = 21 ie_allowed =0
010416: Apr 25 08:22:48.115: ISDN BR0/2/1 CCd: ie_in_out_msg: message 0x80 ie = 22 ie_allowed =1
010417: Apr 25 08:22:48.115: ISDN BR0/2/1 CCd: ie_in_out_msg: message 0x80 ie = 23 ie_allowed =1
010418: Apr 25 08:22:48.115: ISDN BR0/2/1 CCd: ie_in_out_msg: message 0x80 ie = 24 ie_allowed =1
010419: Apr 25 08:22:48.115: ISDN BR0/2/1 CCd: ie_in_out_msg: message 0x80 ie = 25 ie_allowed =1
010420: Apr 25 08:22:48.115: ISDN BR0/2/1 EVENTd: calltrkr_setup_received: isdn_info=2265507124l, call_id=0x8035 ORIGINATE
010421: Apr 25 08:22:48.115: ISDN BR0/2/1 EVENTd: calltrkr_setup_received: calltracker disabled
010422: Apr 25 08:22:48.115: ISDN BR0/2/1 Q931d: L3_Go: source 0x0400, ces 1, event 0x0080, call id 0x8035, int id 0x0
010423: Apr 25 08:22:48.115: ISDN BR0/2/1 Q931d: L3_Go: event 0x80  cr_len 1 cr 0
010424: Apr 25 08:22:48.115: ISDN BR0/2/1 L3_CODE: L3_GetUser_NLCB: 0x8702DC60:ces=0,st=37,cr=0x0,ind=0
010425: Apr 25 08:22:48.115: ISDN BR0/2/1 L3_CODE: L3_GetUser_NLCB: nlcb 8702DC60 ces 0 state 37 callid 0
010426: Apr 25 08:22:48.115: ISDN BR0/2/1 L3_CODE: L3_GetUser_NLCB: nlcb 0 found for callid 0x8035
010427: Apr 25 08:22:48.115: ISDN BR0/2/1 Q931d: L3_GetNew_NLCB: call_id 0x8035 int_id 0, ces 1
010428: Apr 25 08:22:48.115: ISDN BR0/2/1 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1311), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (180000), pkt (0x0)
010429: Apr 25 08:22:48.115: ISDN BR0/2/1 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x132D), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (300000), pkt (0x0)
010430: Apr 25 08:22:48.115: ISDN BR0/2/1 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1312), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (15000), pkt (0x0)
010431: Apr 25 08:22:48.115: ISDN BR0/2/1 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1313), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (4000), pkt (0x0)
010432: Apr 25 08:22:48.115: ISDN BR0/2/1 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1314), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (30000), pkt (0x0)
010433: Apr 25 08:22:48.115: ISDN BR0/2/1 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1315), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (30000), pkt (0x0)
010434: Apr 25 08:22:48.115: ISDN BR0/2/1 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (pr 25 08:22:48.115: ISDN BR0/2/1 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1319), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (90000), pkt (0x0)
010438: Apr 25 08:22:48.119: ISDN BR0/2/1 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x131B), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (6000), pkt (0x0)0x1316), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (30000),0x131F), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (4000), pkt (0x0) pkt (0x0)
010435: Apr 25 08:22:48.115: ISDN BR0/2/1 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1317), sapi (0x0),
          ces (0x1), cal
010442: Apr 25 08:22:48.119: ISDN BR0/2/1 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1320), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (4000), pkt (0x0)
010443: Apr 25 08:22:48.119: ISDN BR0/2/1 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1322), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (4000), pkt (0x0)
010444: Apr 25 08:22:48.119: ISDN BR0/2/1 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x132C), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (4000), pkt (0x0)l_id (0x0), chan_id (0x0), msecs (180000), pkt (0x0)
010436: Apr 25 08:22:48.115: ISDN BR0/2/1 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1318), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (4000), pkt (0x0)
010437: A
010439: Apr 25 08:22:48.119: ISDN BR0/2/1 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x131A), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (40000), pkt (0x0)
010440: Apr 25 08:22:48.119: ISDN BR0/2/1 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x131C), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (4200), pkt (0x0)
010441: Apr 25 08:22:48.119: ISDN BR0/2/1 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (
010445: Apr 25 08:22:48.119: ISDN BR0/2/1 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1329), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (0), pkt (0x0)
010446: Apr 25 08:22:48.119: ISDN BR0/2/1 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x132A), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (0), pkt (0x0)
010447: Apr 25 08:22:48.119: ISDN BR0/2/1 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1406), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (4000), pkt (0x0)
010448: Apr 25 08:22:48.119: ISDN BR0/2/1 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1404), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (4000), pkt (0x0)
010449: Apr 25 08:22:48.119: ISDN BR0/2/1 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1405), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (4000), pkt (0x0)
010450: Apr 25 08:22:48.119: ISDN BR0/2/1 L3_CODE: L3_GetUser_NLCB: Got NLCB 0x8702D8A0, Ces=0x1, CallId=0x8035, ChanId=0x0, State=26, CallRef=0x0, ind=0x0
010451: Apr 25 08:22:48.119: ISDN BR0/2/1 Q931d: L3_Go: call_id 0x8035 cr 0x0 state 26 event 0x80 ces 1
010452: Apr 25 08:22:48.119: ISDN BR0/2/1 Q931d: L3_ProcessEvent: callref = 0x0000 CC_SETUP_REQ:U98_SetupReq(nlcb)
010453: Apr 25 08:22:48.119: ISDN BR0/2/1 Q931d: L3_EnQueue: Event not yet processed, but queued - state 1A event 80
010454: Apr 25 08:22:48.119: ISDN BR0/2/1 Q931d: L3_MailPkt: Request 0x0200 to callref/callid 0x8035/0000 on CES 1
010455: Apr 25 08:22:48.119: ISDN BR0/2/1 Q931d: srl_send_l3_pak:
        source_id = Q.931 0x0300, dest_id = Q.921 0x0000, prim = DL_EST_REQ 0x0200
        priv_len = 4 int_id = 0x8708D140 datasize = 16
010456: Apr 25 08:22:48.119: ISDN BR0/2/1 Q931d: data =
010457: Apr 25 08:22:48.119:    8708D14000000300020004000001300A--000001E4
010458: Apr 25 08:22:48.119: ISDN BR0/2/1 Q931d: L3_state_change: callref 0x0000 old DL_IDLE_STATE, new ESTABLISH_WAIT
010459: Apr 25 08:22:48.119: ISDN  L2_CODE: isdn_l2_process: QUEUE_EVENT state = 0
010460: Apr 25 08:22:48.119: ISDN BR0/2/1 PACKET: handle_mail: received:
        dest_id = 0, source_id = 768, prim = 200
        priv_ln = 4, int_id = -2029465280, datasize = 16
010461: Apr 25 08:22:48.119: ISDN BR0/2/1 PACKET: L2IF_DispPak: packet - start(E60A2F8), size (16)
010462: Apr 25 08:22:48.119:    00000000008000001B8FAE8142000000--0000029B
010463: Apr 25 08:22:48.119: ISDN BR0/2/1 Q921d: handle_mail: setting pkt to NULL
010464: Apr 25 08:22:48.119: ISDN BR0/2/1 LIF_CODE: LIF_AddTimer: source_id (0x20A), dest_id (0x20A), event (0x1240), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (1000), pkt (0x0)
010465: Apr 25 08:22:48.119: ISDN BR0/2/1 LIF_CODE: LIF_AddTimer: source_id (0x20A), dest_id (0x20A), event (0x1250), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (10000), pkt (0x0)
010466: Apr 25 08:22:48.119: ISDN BR0/2/1 LIF_CODE: LIF_AddTimer: source_id (0x700), dest_id (0x700), event (0x1720), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (2000), pkt (0x0)
010467: Apr 25 08:22:48.119: ISDN BR0/2/1 Q921d: L2_GetDLCB: ces 1
010468: Apr 25 08:22:48.123: ISDN BR0/2/1 PACKET: L2IF_SendprimPkt:
        source_id (0x20A), state (0x1), dest_id (0x700), event (0x621),
        sapi (0x3F), ces (0x0),  call_id (0x0),
        chan_id (0x0), int_id (0x8708D140), free_p (0x1), pkt (0x8705A250), msecs (0x0), priority (0xA)
010469: Apr 25 08:22:48.123: ISDN BR0/2/1 PACKET: L2IF_SendPkt:
        source_id (0x20A), state (0x1),
        dest_id (0x700), event (0x621),
        sapi (0x3F), ces (0x0), call_id (0x0),
        chan_id (0x0), int_id (0x8708D140), free_pkt (0x1),
        pkt (0x866E4FFC), msecs (0x0), priority (0xA)
010470: Apr 25 08:22:48.123: ISDN BR0/2/1 PACKET: L2IF_DispPak: packet - start(E608C78), size (2)
010471: Apr 25 08:22:48.123:    0001--00000001
010472: Apr 25 08:22:48.123: ISDN BR0/2/1 PACKET: L2IF_SendPkt: Sending from L2
        dest_id = 1792, source_id = 522, prim = 621
        priv_ln = 4, int_id = -2029465280
  datastart = E608C68, netstart = E608C78, datasize = 18
010473: Apr 25 08:22:48.123: ISDN BR0/2/1 PACKET: L2IF_DispPak: packet - start(E608C68), size (18)
010474: Apr 25 08:22:48.123:    8708D1400700020A062104003F00300B0001--00000259
010475: Apr 25 08:22:48.123: ISDN BR0/2/1 Q921d: srl_send_l2_pak:
        source_id = Q.921 0x0000, dest_id = ME 0x0700, prim = MDL_ASS_IND 0x0621
        priv_len = 4 int_id = 0x8708D140 datasize = 18
010476: Apr 25 08:22:48.123: ISDN BR0/2/1 Q921d: data =
010477: Apr 25 08:22:48.123:    8708D14007000000062104003F00300B0001--0000024D
010478: Apr 25 08:22:48.123: ISDN BR0/2/1 Q931d: L3IF_rx_L2_pak: received data
010479: Apr 25 08:22:48.123:    0001--00000001
010480: Apr 25 08:22:48.123: ISDN  L2_CODE: isdn_l2_process: TIMER_EVENT
010481: Apr 25 08:22:48.123: ISDN  L2_CODE: isdn_l2_process: state (0x0), event_count (1)
010482: Apr 25 08:22:48.123: ISDN BR0/2/1 MGMNT: LM_MDL_ASS_IND: state 255 ces 1 sapi 0 switch type 1
010483: Apr 25 08:22:48.123: ISDN BR0/2/1 Q931d: srl_send_l3_pak:
        source_id = ME 0x0700, dest_id = Q.921 0x0000, prim = MDL_UI_DATA_REQ 0x0650
        priv_len = 4 int_id = 0x8708D140 datasize = 21
010484: Apr 25 08:22:48.123: ISDN BR0/2/1 Q931d: data =
010485: Apr 25 08:22:48.123:    8708D140000007000650046D3F0030000F593F01FF--00000484
010486: Apr 25 08:22:48.123: ISDN  L2_CODE: isdn_l2_process: QUEUE_EVENT state = 0
010487: Apr 25 08:22:48.123: ISDN BR0/2/1 PACKET: handle_mail: received:
        dest_id = 0, source_id = 1792, prim = 650
        priv_ln = 4, int_id = -2029465280, datasize = 21
010488: Apr 25 08:22:48.123: ISDN BR0/2/1 PACKET: L2IF_DispPak: packet - start(EA97518), size (21)
010489: Apr 25 08:22:48.123:    0F593F01FFC100003F0685760A0101220A010101C9--000004AC
010490: Apr 25 08:22:48.123: ISDN BR0/2/1 PACKET: handle_mail: received data =
010491: Apr 25 08:22:48.123:    0F593F01FF--000001A7
010492: Apr 25 08:22:48.123: ISDN BR0/2/1 Q921d: L2_Go: From ME - event = 0x650
010493: Apr 25 08:22:48.127: ISDN BR0/2/1 Q921d: L2_Go: BROADCAST_TEI = 0x650
010494: Apr 25 08:22:48.127: ISDN BR0/2/1 L2_CODE: L2IF_GetDriverID()
010495: Apr 25 08:22:48.127: ISDN BR0/2/1 PACKET: L2IF_SendprimPkt:
        source_id (0x20A), state (0x0), dest_id (0x200), event (0x140),
        sapi (0x3F), ces (0x0),  call_id (0x0),
        chan_id (0x0), int_id (0x8708D140), free_p (0x1), pkt (0x8705A2D0), msecs (0x0), priority (0xA)
010496: Apr 25 08:22:48.127: ISDN BR0/2/1 PACKET: L2IF_SendPkt:
        source_id (0x20A), state (0x0),
        dest_id (0x200), event (0x140),
        sapi (0x3F), ces (0x0), call_id (0x0),
        chan_id (0x0), int_id (0x8708D140), free_pkt (0x1),
        pkt (0x866E4B40), msecs (0x0), priority (0xA)
010497: Apr 25 08:22:48.127: ISDN BR0/2/1 PACKET: L2IF_DispPak: packet - start(E608B38), size (8)
010498: Apr 25 08:22:48.127:    FCFF030F593F01FF--000003A5
010499: Apr 25 08:22:48.127: ISDN BR0/2/1 PACKET: L2IF_SendPkt: Sending from L2
        dest_id = 0, source_id = 522, prim = 140
        priv_ln = 4, int_id = -2029465280
  datastart = E608B28, netstart = E608B38, datasize = 24
010500: Apr 25 08:22:48.127: ISDN BR0/2/1 PACKET: L2IF_DispPak: packet - start(E608B28), size (24)
010501: Apr 25 08:22:48.127:    8708D1400000020A0140046D3F003000FCFF030F593F01FF--00000672
010502: Apr 25 08:22:48.127: ISDN BR0/2/1 SERROR: L2_Go: at bailout DLCB is NULL
        L2: sapi 63 tei 127 ces 0 ev 0x650
010503: Apr 25 08:22:48.127: ISDN  L2_CODE: isdn_l2_process: TIMER_EVENT
010504: Apr 25 08:22:48.127: ISDN  Q921d: isdn_l2d_srq_process: QUEUE_EVENT
010505: Apr 25 08:22:48.127: ISDN BR0/2/1 PACKET: handle_l2d_srq_mail: received:
        dest_id = 0x0000, source_id = 0x020A, prim = 0x0140
        priv_ln = 4, int_id = 0x8708D140, datasize = 8
        line_status[5] = IF_DOWN
010506: Apr 25 08:22:48.127: ISDN BR0/2/1 **ERROR**: handle_l2d_srq_mail: Layer 1 inactive
010507: Apr 25 08:22:48.127: ISDN BR0/2/1 EVENT: handle_l2d_srq_mail: Activating Layer 1
010508: Apr 25 08:22:48.127: ISDN BR0/2/1 EVENT: isdn_sw_cstate: State = 4, Old State = 4
010509: Apr 25 08:22:48.127: ISDN  Q921d: isdn_l2d_srq_process: event_count 1
010510: Apr 25 08:22:48.127: ISDN  L2_CODE: isdn_l2_process: state (0x0), event_count (1)
010511: Apr 25 08:22:56.127: ISDN BR0/2/1 EVENT: isdn_layer1_failure: Layer 1 not active even after T3 delay
010512: Apr 25 08:22:56.127: ISDN BR0/2/1 EVENT: isdn_sw_cstate: State = 0, Old State = 4
010513: Apr 25 08:22:56.127: ISDN BR0/2/1 PACKET: L2IF_SendPkt:
        source_id (0x200), state (0x0),
        dest_id (0x20A), event (0x171),
        sapi (0x0), ces (0x0), call_id (0x0),
        chan_id (0x0), int_id (0x8708D140), free_pkt (0x0),
        pkt (0x0), msecs (0x0), priority (0xA)
010514: Apr 25 08:22:56.127: ISDN BR0/2/1 PACKET: L2IF_SendPkt: Sending from L2
        dest_id = 0, source_id = 512, prim = 171
        priv_ln = 4, int_id = -2029465280
  datastart = E609DE8, netstart = E609DF8, datasize = 16
010515: Apr 25 08:22:56.127: ISDN BR0/2/1 PACKET: L2IF_DispPak: packet - start(E609DE8), size (16)
010516: Apr 25 08:22:56.127:    8708D14000000200017104B700003000--000002FF
010517: Apr 25 08:22:56.127: ISDN BR0/2/1 Q921d: L2_FreeDLCB
010518: Apr 25 08:22:56.127: ISDN BR0/2/1 SERROR: isdn_l2d_srq_process: T3 Timer expired
010519: Apr 25 08:22:56.127: ISDN  L2_CODE: isdn_l2_process: QUEUE_EVENT state = 0
010520: Apr 25 08:22:56.127: ISDN BR0/2/1 PACKET: handle_mail: received:
        dest_id = 0, source_id = 512, prim = 171
        priv_ln = 4, int_id = -2029465280, datasize = 16
010521: Apr 25 08:22:56.127: ISDN BR0/2/1 PACKET: L2IF_DispPak: packet - start(E609DF8), size (16)
010522: Apr 25 08:22:56.127:    456000289B6900003F06C9EB0A010119--000003EF
010523: Apr 25 08:22:56.127: ISDN  L2_CODE: isdn_l2_process: TIMER_EVENT
010524: Apr 25 08:22:56.127: ISDN  L2_CODE: isdn_l2_process: state (0x0), event_count (1)
010525: Apr 25 08:22:56.127: ISDN BR0/2/1 MGMNT: ME_ShutDown()
010526: Apr 25 08:22:56.127: ISDN BR0/2/1 MGMNT: ME_ShutDown: sending MNL_DEACT_IND
010527: Apr 25 08:22:56.127: ISDN BR0/2/1 L3_CODE: L3_Free_NLCB()
010528: Apr 25 08:22:56.127: ISDN BR0/2/1 CC: CCBRI_Go: source id 0x300, state 0, call id 0x0, event 0x7F0 ces 0
010529: Apr 25 08:22:56.127: ISDN BR0/2/1 TGRM: get_tgrm_avail_state: idb 0x8708D140 bchan 1 service_state 0 call_state 0 false busy 0x0 dsl 5
010530: Apr 25 08:22:56.127: ISDN BR0/2/1 TGRM: update_tgrm_call_status: idb 0x8708D140 bchan 1 availability state 1 call state(prev,new) (1,0), dsl 5
010531: Apr 25 08:22:56.127: ISDN BR0/2/1 TGRM: update_tgrm_call_status: Calling TGRM with tgrm_call_isdn_update: idb 0x8708D140 bchan 1 call state 0 call type 0 call dir 1
010532: Apr 25 08:22:56.127: ISDN  EVENTd: cc_clear_free_list freeing 0x87058AF8
010533: Apr 25 08:22:56.127: ISDN BR0/2/1 EVENT: process_rxstate: ces/callid 1/0x8035 calltype 2 HOST_DISCONNECT_ACK
010534: Apr 25 08:22:56.127: ISDN BR0/2/1 PACKET: process_rxstate:
010535: Apr 25 08:22:56.127:    060501803504080280A9--000001F8
010536: Apr 25 08:22:56.127: ISDN BR0/2/1 EVENTd: process_rxstate: cause=0x29 (41), cause_present=1
010537: Apr 25 08:22:56.127: ISDN BR0/2/1 EVENTd: calltrkr_call_cleared: isdn_info=0x8708E534, call_id=0x8035
010538: Apr 25 08:22:56.131: ISDN BR0/2/1 EVENTd: calltrkr_call_cleared: hndl=0, cause=41, Temporary failure
010539: Apr 25 08:22:56.131: ISDN  CDAPI: cdapi_find_tsm found a GTD message RLC,
PRN,isdn*,,,

:
        end of gtd length is 22
010540: Apr 25 08:22:56.131: ISDN BR0/2/1 EVENT: service_queue_from_physical_layer: Recvd L1 prim ISDN_PH_DEACT_IND state is IF_DOWN
010541: Apr 25 08:22:56.131: ISDN BR0/2/1 EVENTd: service_queue_from_physical_layer: L1 persistent Deactivated
010542: Apr 25 08:22:56.131: ISDN BR0/2/1 PACKET: L2IF_SendPkt:
        source_id (0x700), state (0x0),
        dest_id (0x20A), event (0x1170),
        sapi (0x0), ces (0x1), call_id (0x0),
        chan_id (0x0), int_id (0x8708D140), free_pkt (0x0),
        pkt (0x0), msecs (0x0), priority (0xA)
010543: Apr 25 08:22:56.131: ISDN BR0/2/1 PACKET: L2IF_SendPkt: Sending from L2
        dest_id = 0, source_id = 1792, prim = 1170
        priv_ln = 4, int_id = -2029465280
  datastart = E60A568, netstart = E60A578, datasize = 16
010544: Apr 25 08:22:56.131: ISDN BR0/2/1 PACKET: L2IF_DispPak: packet - start(E60A568), size (16)
010545: Apr 25 08:22:56.131:    8708D14000000700117004A000013200--000002FF
010546: Apr 25 08:22:56.131: ISDN  L2_CODE: isdn_l2_process: QUEUE_EVENT state = 0
010547: Apr 25 08:22:56.131: ISDN BR0/2/1 PACKET: handle_mail: received:
        dest_id = 0, source_id = 1792, prim = 1170
        priv_ln = 4, int_id = -2029465280, datasize = 16
010548: Apr 25 08:22:56.131: ISDN BR0/2/1 PACKET: L2IF_DispPak: packet - start(E60A578), size (16)
010549: Apr 25 08:22:56.131:    456000283FAF00003F06259E0A010121--000002F0
010550: Apr 25 08:22:56.131: ISDN BR0/2/1 Q921d: handle_mail: setting pkt to NULL
010551: Apr 25 08:22:56.131: ISDN BR0/2/1 Q921d: L2_Go: From ME - event = 0x1170
010552: Apr 25 08:22:56.131: ISDN BR0/2/1 SERROR: L2_Go: at bailout DLCB is NULL
        L2: sapi 0 tei 255 ces 1 ev 0x1170
010553: Apr 25 08:22:56.131: ISDN  L2_CODE: isdn_l2_process: TIMER_EVENT
010554: Apr 25 08:22:56.131: ISDN  L2_CODE: isdn_l2_process: state (0x1), event_count (1)
010555: Apr 25 08:22:56.139: ISDN BR0/2/0 EVENTd: isdn_get_guid: Got Guid 9C73097983C7
010556: Apr 25 08:22:56.139: ISDN BR0/2/0 EVENT: process_bri_call: call id 0x8036, called_number 694XXXXXX, Guid 9C73097983C7 speed 0, call type VOICE, bchan -1 clng_num 88Received pdata len 0x3F data:1C 39 9E 1 0 3 67 74 64 0 0 0 2E 49 41 4D 2C D A 47 43 49 2C 39 63 37 33 30 39 37 39 63 62 38 39 31 31 65 33 38 33 63 37 63 34 61 63 61 65 30 35 61 32 31 35 D A D A 1E 2 81 83
010557: Apr 25 08:22:56.139: ISDN BR0/2/0 EVENTd: process_bri_call: No name in GTD
010558: Apr 25 08:22:56.139: ISDN BR0/2/0 Error: Invalid DSL (0)  EVENTd: isdn_get_display_text_from_gtd: false ret
010559: Apr 25 08:22:56.139: ISDN BR0/2/0 EVENTd: process_bri_call: Name Modified by Calldecode
Name Type 0x0, cs 0x0, ie_val 0x28, pi 0x0, avail 0x0
        fachd len 0x0 data:
        inpdu len 0x0 data:
        name len 0xD data:43 4F 4D 44 52 45 56 20 41 64 6D 69 6E
010560: Apr 25 08:22:56.139: ISDN BR0/2/0 Q931d: Default type/plan 0x0 0x0 sw-type 1 Calling number 88 isdn_map 0 mask 0x3042
010561: Apr 25 08:22:56.139: ISDN BR0/2/0 Q931d: Calling plan/type unspecified in call_decode, so ignored.
010562: Apr 25 08:22:56.139: ISDN BR0/2/0 Q931d: No call_decode, isdn default set; Oct3a 0x0
010563: Apr 25 08:22:56.139: ISDN BR0/2/0 Q931d: Default type/plan 0x0 0x0 sw-type 1 Called number 694XXXXXX isdn_map 0 mask 0x3042
010564: Apr 25 08:22:56.139: ISDN BR0/2/0 Q931d: Called plan/type unspecified in call_decode, so ignored.
010565: Apr 25 08:22:56.139: ISDN BR0/2/0 Q931d: No call_decode, isdn default set
010566: Apr 25 08:22:56.139: ISDN BR0/2/0 CC: CCBRI_Go: source id 0x500, state 0, call id 0x0, event 0x1 ces 0
010567: Apr 25 08:22:56.139: ISDN BR0/2/0 CCd: CCBRI_Go: Host PkgInfo:
010568: Apr 25 08:22:56.139:    0104018036146C050080003838700B000036393434363237323504038090A31801831E028183280D434F4D445245562041646D696EA1--00000CF2
010569: Apr 25 08:22:56.139: ISDN BR0/2/0 CC: CCBRI_Go: call id 0x8036 callref 0x0 state 1 event 0x1 Src->HOST
010570: Apr 25 08:22:56.143: ISDN BR0/2/0 CCd: CCBRI_SetNewChan: ReleaseChan 3 earlier chan 0 exclusive 0
010571: Apr 25 08:22:56.143: ISDN BR0/2/0 CCd: CC_CHAN_GetIdleChanbri: Found idle channel B1
010572: Apr 25 08:22:56.143: ISDN BR0/2/0 TGRM: get_tgrm_avail_state: idb 0x8701B488 bchan 1 service_state 0 call_state 1 false busy 0x0 dsl 4
010573: Apr 25 08:22:56.143: ISDN BR0/2/0 TGRM: update_tgrm_call_status: idb 0x8701B488 bchan 1 availability state 1 call state(prev,new) (0,1), dsl 4
010574: Apr 25 08:22:56.143: ISDN BR0/2/0 TGRM: update_tgrm_call_status: Calling TGRM with tgrm_call_isdn_update: idb 0x8701B488 bchan 1 call state 2 call type 2 call dir 2
010575: Apr 25 08:22:56.143: ISDN BR0/2/0 CCd: ie_in_out_msg: message 0x80 ie = 0 ie_allowed =1
010576: Apr 25 08:22:56.143: ISDN BR0/2/0 CCd: ie_in_out_msg: message 0x80 ie = 3 ie_allowed =1
010577: Apr 25 08:22:56.143: ISDN BR0/2/0 CCd: ie_in_out_msg: message 0x80 ie = 5 ie_allowed =1
010578: Apr 25 08:22:56.143: ISDN BR0/2/0 CCd: ie_in_out_msg: message 0x80 ie = 7 ie_allowed =1
010579: Apr 25 08:22:56.143: ISDN BR0/2/0 CCd: ie_in_out_msg: message 0x80 ie = 8 ie_allowed =1
010580: Apr 25 08:22:56.143: ISDN BR0/2/0 CCd: ie_in_out_msg: message 0x80 ie = 9 ie_allowed =0
010581: Apr 25 08:22:56.143: ISDN BR0/2/0 CCd: ie_in_out_msg: message 0x80 ie = 10 ie_allowed =0
010582: Apr 25 08:22:56.143: ISDN BR0/2/0 CCd: ie_in_out_msg: message 0x80 ie = 12 ie_allowed =1
010583: Apr 25 08:22:56.143: ISDN BR0/2/0 CCd: ie_in_out_msg: message 0x80 ie = 26 ie_allowed =0
010584: Apr 25 08:22:56.143: ISDN BR0/2/0 CCd: ie_in_out_msg: message 0x80 ie = 15 ie_allowed =1
010585: Apr 25 08:22:56.143: ISDN BR0/2/0 CCd: ie_in_out_msg: message 0x80 ie = 16 ie_allowed =1
010586: Apr 25 08:22:56.143: ISDN BR0/2/0 CCd: ie_in_out_msg: message 0x80 ie = 17 ie_allowed =1
010587: Apr 25 08:22:56.143: ISDN BR0/2/0 CCd: ie_in_out_msg: message 0x80 ie = 19 ie_allowed =1
010588: Apr 25 08:22:56.143: ISDN BR0/2/0 CCd: ie_in_out_msg: message 0x80 ie = 21 ie_allowed =0
010589: Apr 25 08:22:56.143: ISDN BR0/2/0 CCd: ie_in_out_msg: message 0x80 ie = 22 ie_allowed =1
010590: Apr 25 08:22:56.143: ISDN BR0/2/0 CCd: ie_in_out_msg: message 0x80 ie = 23 ie_allowed =1
010591: Apr 25 08:22:56.143: ISDN BR0/2/0 CCd: ie_in_out_msg: message 0x80 ie = 24 ie_allowed =1
010592: Apr 25 08:22:56.143: ISDN BR0/2/0 CCd: ie_in_out_msg: message 0x80 ie = 25 ie_allowed =1
010593: Apr 25 08:22:56.143: ISDN BR0/2/0 EVENTd: calltrkr_setup_received: isdn_info=2265041020l, call_id=0x8036 ORIGINATE
010594: Apr 25 08:22:56.143: ISDN BR0/2/0 EVENTd: calltrkr_setup_received: calltracker disabled
010595: Apr 25 08:22:56.143: ISDN BR0/2/0 Q931d: L3_Go: source 0x0400, ces 1, event 0x0080, call id 0x8036, int id 0x0
010596: Apr 25 08:22:56.143: ISDN BR0/2/0 Q931d: L3_Go: event 0x80  cr_len 1 cr 0
010597: Apr 25 08:22:56.143: ISDN BR0/2/0 L3_CODE: L3_GetUser_NLCB: 0x8702DDA0:ces=0,st=37,cr=0x0,ind=0
010598: Apr 25 08:22:56.143: ISDN BR0/2/0 L3_CODE: L3_GetUser_NLCB: 0x8702DEE0:ces=0,st=37,cr=0x0,ind=0
010599: Apr 25 08:22:56.143: ISDN BR0/2/0 L3_CODE: L3_GetUser_NLCB: nlcb 8702DDA0 ces 0 state 37 callid 0
010600: Apr 25 08CODE: L3_GetUser_NLCB: nlcb 0 found for callid 0x8036
010602: Apr 25 08:22:56.143: ISDN BR0/2/0 Q931d: L3_GetNew_NLCB: call_id 0x8036 int_id 0, ces 1
010603: Apr 25 08:22:56.143: ISDN BR0/2/0 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1311), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (180000), pkt (0x0):22:56.143: ISDN BR0/2/0 L3_CODE: L3_GetUser_NLCB: nlcb 8702DEE0 ces 0 state 37 callid 0
010601: Apr 25 08:22:5d (0x300), dest_id (0x300), event (0x1315), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (30000), pkt (0x0)
010609: Apr 25 08:22:56.147: ISDN BR0/2/0 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1316), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (30000), pkt (0x0)6.143: ISDN BR0/2/0 L3_
010604: Apr 25 08:22:56.143: ISDN BR0/2/0 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x132D), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (300000), pkt (0x0)
010605: Apr 25 08:22:56.143: ISDN BR0/2/0 LIF_CODE: LIF_AddTi
010610: Apr 25 08:22:56.147: ISDN BR0/2/0 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1317), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (180000), pkt (0x0)
010611: Apr 25 08:22:56.147: ISDN BR0/2/0 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1318), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (4000), pkt (0x0)
010612: Apr 25 08:22:56.147: ISDN BR0/2/0 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1319), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (90000), pkt (0x0)mer: source_id (0x300), dest_id (0x300), event (0x1312), sapi (0x0),
          ces (0x)
010615: Apr 25 08:22:56.147: ISDN BR0/2/0 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x131C), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (4200), pkt (0x0)1), call_id (0x0), chan_id (0x0), msecs (15000), pkt (0x0)
010606: Apr 25 08:22
010616: Apr 25 08:22:56.147: ISDN BR0/2/0 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x131F), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (4000), pkt (0x0)
010617: Apr 25 08:22:56.147: ISDN BR0/2/0 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1320), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (4000), pkt (0x0)
010618: Apr 25 08:22:56.147: ISDN BR0/2/0 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1322), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (4000), pkt (0x0):56.143: ISDN BR0/2/0 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1313), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (4000), pkt (0x0)
010607: Apr 25 08:22:56.143: ISDN BR0/2/0 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1314), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (30000), pkt (0x0)
010608: Apr 25 08:22:56.143: ISDN BR0/2/0 LIF_CODE: LIF_AddTimer: source_i
010613: Apr 25 08:22:56.147: ISDN BR0/2/0 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x131B), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (6000), pkt (0x0)
010614: Apr 25 08:22:56.147: ISDN BR0/2/0 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x131A), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (40000), pkt (0x0
010619: Apr 25 08:22:56.147: ISDN BR0/2/0 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x132C), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (4000), pkt (0x0)
010620: Apr 25 08:22:56.147: ISDN BR0/2/0 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1329), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (0), pkt (0x0)
010621: Apr 25 08:22:56.147: ISDN BR0/2/0 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x132A), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (0), pkt (0x0)
010622: Apr 25 08:22:56.147: ISDN BR0/2/0 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1406), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (4000), pkt (0x0)
010623: Apr 25 08:22:56.147: ISDN BR0/2/0 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1404), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (4000), pkt (0x0)
010624: Apr 25 08:22:56.147: ISDN BR0/2/0 LIF_CODE: LIF_AddTimer: source_id (0x300), dest_id (0x300), event (0x1405), sapi (0x0),
          ces (0x1), call_id (0x0), chan_id (0x0), msecs (4000), pkt (0x0)
010625: Apr 25 08:22:56.147: ISDN BR0/2/0 L3_CODE: L3_GetUser_NLCB: Got NLCB 0x8702D8A0, Ces=0x1, CallId=0x8036, ChanId=0x0, State=26, CallRef=0x0, ind=0x0
010626: Apr 25 08:22:56.147: ISDN BR0/2/0 Q931d: L3_Go: call_id 0x8036 cr 0x0 state 26 event 0x80 ces 1
010627: Apr 25 08:22:56.147: ISDN BR0/2/0 Q931d: L3_ProcessEvent: callref = 0x0000 CC_SETUP_REQ:U98_SetupReq(nlcb)
010628: Apr 25 08:22:56.147: ISDN BR0/2/0 Q931d: L3_EnQueue: Event not yet processed, but queued - state 1A event 80
010629: Apr 25 08:22:56.147: ISDN BR0/2/0 Q931d: L3_MailPkt: Request 0x0200 to callref/callid 0x8036/0000 on CES 1
010630: Apr 25 08:22:56.147: ISDN BR0/2/0 Q931d: srl_send_l3_pak:
        source_id = Q.931 0x0300, dest_id = Q.921 0x0000, prim = DL_EST_REQ 0x0200
        priv_len = 4 int_id = 0x8701B488 datasize = 16
010631: Apr 25 08:22:56.147: ISDN BR0/2/0 Q931d: data =
010632: Apr 25 08:22:56.147:    8701B488000003000200046D00013000--0000026B
010633: Apr 25 08:22:56.147: ISDN BR0/2/0 Q931d: L3_state_change: callref 0x0000 old DL_IDLE_STATE, new ESTABLISH_WAIT
010634: Apr 25 08:22:56.147: ISDN  L2_CODE: isdn_l2_process: QUEUE_EVENT state = 0
010635: Apr 25 08:22:56.147: ISDN BR0/2/0 PACKET: handle_mail: received:
        dest_id = 0, source_id = 768, prim = 200
        priv_ln = 4, int_id = -2029931384, datasize = 16
010636: Apr 25 08:22:56.151: ISDN BR0/2/0 PACKET: L2IF_DispPak: packet - start(EA97018), size (16)
010637: Apr 25 08:22:56.151:    45600028383B00003F062D160A01011D--000001F1
010638: Apr 25 08:22:56.151: ISDN BR0/2/0 Q921d: handle_mail: setting pkt to NULL
010639: Apr 25 08:22:56.151: ISDN BR0/2/0 L2_CODE: L2IF_GetDriverID()
010640: Apr 25 08:22:56.151: ISDN BR0/2/0 PACKET: L2IF_SendprimPkt:
        source_id (0x20A), state (0x4), dest_id (0x200), event (0x140),
        sapi (0x0), ces (0x1),  call_id (0x0),
        chan_id (0x0), int_id (0x8701B488), free_p (0x1), pkt (0x8705A2D0), msecs (0x0), priority (0xA)
010641: Apr 25 08:22:56.151: ISDN BR0/2/0 PACKET: L2IF_SendPkt:
        source_id (0x20A), state (0x4),
        dest_id (0x200), event (0x140),
        sapi (0x0), ces (0x1), call_id (0x0),
        chan_id (0x0), int_id (0x8701B488), free_pkt (0x1),
        pkt (0x87DF8CCC), msecs (0x0), priority (0xA)
010642: Apr 25 08:22:56.151: ISDN BR0/2/0 PACKET: L2IF_DispPak: packet - start(EA73E98), size (3)
010643: Apr 25 08:22:56.151:    00877F--00000106
010644: Apr 25 08:22:56.151: ISDN BR0/2/0 PACKET: L2IF_SendPkt: Sending from L2
        dest_id = 0, source_id = 522, prim = 140
        priv_ln = 4, int_id = -2029931384
  datastart = EA73E88, netstart = EA73E98, datasize = 19
010645: Apr 25 08:22:56.151: ISDN BR0/2/0 PACKET: L2IF_DispPak: packet - start(EA73E88), size (19)
010646: Apr 25 08:22:56.151:    8701B4880000020A0140046C0001300000877F--000003B8
010647: Apr 25 08:22:56.151: ISDN  L2_CODE: isdn_l2_process: TIMER_EVENT
010648: Apr 25 08:22:56.151: ISDN  Q921d: isdn_l2d_srq_process: QUEUE_EVENT
010649: Apr 25 08:22:56.151: ISDN BR0/2/0 PACKET: handle_l2d_srq_mail: received:
        dest_id = 0x0000, source_id = 0x020A, prim = 0x0140
        priv_ln = 4, int_id = 0x8701B488, datasize = 3
        line_status[4] = IF_ACTIVE
010650: Apr 25 08:22:56.151: ISDN BR0/2/0 Q921r: User TX ->
010651: Apr 25 08:22:56.151:    00877F--00000106
010652: Apr 25 08:22:56.151: ISDN  Q921d: isdn_l2d_srq_process: event_count 1
010653: Apr 25 08:22:56.151: ISDN  L2_CODE: isdn_l2_process: state (0x0), event_count (1)
010654: Apr 25 08:22:56.179: %LINK-3-UPDOWN: Interface BRI0/2/1, changed state to down
010655: Apr 25 08:22:56.211: ISDN  Q921d: isdn_from_driver_process: QUEUE_EVENT
010656: Apr 25 08:22:56.211: ISDN BR0/2/0 Q921r: User RX <-
010657: Apr 25 08:22:56.211:    008773--000000FA
010658: Apr 25 08:22:56.211: ISDN BR0/2/0 Q921d: process_rxdata: Frame sent to L2
010659: Apr 25 08:22:56.211: ISDN BR0/2/0 PACKET: L2IF_SendPkt:
        source_id (0x200), state (0x0),
        dest_id (0x20A), event (0x141),
        sapi (0x0), ces (0x0), call_id (0x0),
        chan_id (0x0), int_id (0x8701B488), free_pkt (0x1),
        pkt (0x8B11336C), msecs (0x0), priority (0xA)
010660: Apr 25 08:22:56.211: ISDN BR0/2/0 PACKET: L2IF_DispPak: packet - start(EB0CB14), size (3)
010661: Apr 25 08:22:56.211:    008773--000000FA
010662: Apr 25 08:22:56.211: ISDN BR0/2/0 PACKET: L2IF_SendPkt: Sending from L2
        dest_id = 0, source_id = 512, prim = 141
        priv_ln = 4, int_id = -2029931384
  datastart = EB0CB04, netstart = EB0CB14, datasize = 19
010663: Apr 25 08:22:56.211: ISDN BR0/2/0 PACKET: L2IF_DispPak: packet - start(EB0CB04), size (19)
010664: Apr 25 08:22:56.211:    8701B488000002000141040000003000008773--00000336
010665: Apr 25 08:22:56.211: ISDN  Q921d: isdn_from_driver_process: event_count 1
010666: Apr 25 08:22:56.211: ISDN  L2_CODE: isdn_l2_process: QUEUE_EVENT state = 0
010667: Apr 25 08:22:56.211: ISDN BR0/2/0 PACKET: handle_mail: received:
        dest_id = 0, source_id = 512, prim = 141
        priv_ln = 4, int_id = -2029931384, datasize = 19
010668: Apr 25 08:22:56.211: ISDN BR0/2/0 PACKET: L2IF_DispPak: packet - start(EB0CB14), size (19)
010669: Apr 25 08:22:56.211:    008773ACAC019601280B5779776F6C7977616E--000006FE
010670: Apr 25 08:22:56.211: ISDN BR0/2/0 PACKET: handle_mail: received data =
010671: Apr 25 08:22:56.211:    008773--000000FA
010681: Apr 25 08:22:56.215:    8701B488000000000203046C00013000--0000026A
010682: Apr 25 08:22:56.215: ISDN  L2_CODE: isdn_l2_process: TIMER_EVENT
010683: Apr 25 08:22:56.215: ISDN  L2_CODE: isdn_l2_process: state (0x0), event_count (1)
010684: Apr 25 08:22:56.663: ISDN BR0/2/0 Q931d: L3_Go: source 0x020A, ces 1, event 0x0203, call id 0x0000, int id 0x0
010685: Apr 25 08:22:56.663: ISDN BR0/2/0 Q931d: L3_Go: event 0x203  cr_len 0 cr 0
010686: Apr 25 08:22:56.663: ISDN BR0/2/0 L3_CODE: L3_GetUser_NLCB: 0x8702DDA0:ces=0,st=37,cr=0x0,ind=0
010687: Apr 25 08:22:56.663: ISDN BR0/2/0 L3_CODE: L3_GetUser_NLCB: 0x8702DEE0:ces=0,st=37,cr=0x0,ind=0
010718: Apr 25 08:22:56.667: ISDN BR0/2/0 PACKET: handle_l2d_srq_mail: received:
        dest_id = 0x0000, source_id = 0x020A, prim = 0x0140
        priv_ln = 4, int_id = 0x8701B488, datasize = 39
        line_status[4] = IF_ACTIVE
010719: Apr 25 08:22:56.667: ISDN BR0/2/0 Q921r: User TX ->
010720: Apr 25 08:22:56.667:    008700000801170504038090A31801811E0281836C0400803838700A80363934343632373235A1--000008FC
010721: Apr 25 08:22:56.667: ISDN  Q921d: isdn_l2d_srq_process: event_count 1
010722: Apr 25 08:22:56.667: ISDN  L2_CODE: isdn_l2_process: state (0x0), event_count (1)
010723: Apr 25 08:22:56.855: ISDN  Q921d: isdn_from_driver_process: QUEUE_EVENT
010735: Apr 25 08:22:56.855: ISDN BR0/2/0 PACKET: handle_mail: received:
        dest_id = 0, source_id = 512, prim = 141
        priv_ln = 4, int_id = -2029931384, datasize = 20
010736: Apr 25 08:22:56.855: ISDN BR0/2/0 PACKET: L2IF_DispPak: packet - start(E611234), size (20)
010737: Apr 25 08:22:56.855:    00870102AC01964DAE0189280B5A657374617769--0000066C
010738: Apr 25 08:22:56.855: ISDN BR0/2/0 PACKET: handle_mail: received data =
010739: Apr 25 08:22:56.855:    00870102--0000008A
010740: Apr 25 08:22:56.855: ISDN  L2_CODE: isdn_l2_process: TIMER_EVENT
010741: Apr 25 08:22:56.859: ISDN  L2_CODE: isdn_l2_process: state (0x0), event_count (1)
010804: Apr 25 08:22:57.743: ISDN BR0/2/0 L3_CODE: extract_bchan: extracted channel ie[0-8]
010805: Apr 25 08:22:57.743:    180189A900000000--0000014B
010806: Apr 25 08:23:07.731: ISDN  L2_CODE: isdn_l2_process: TIMER_EVENT
010807: Apr 25 08:23:07.731: ISDN  L2_CODE: l2_timer()
010808: Apr 25 08:23:07.731: ISDN  Q921d: l2_timer: timer (0x89638EB8), timer_type (0x1250)
010809: Apr 25 08:23:07.731: ISDN BR0/2/0 L2_CODE: L2IF_GetDriverID()
010810: Apr 25 08:23:07.731: ISDN BR0/2/0 PACKET: L2IF_SendprimPkt:
        source_id (0x20A), state (0x7), dest_id (0x200), event (0x140),
        sapi (0x0), ces (0x1),  call_id (0x0),
        chan_id (0x0), int_id (0x8701B488), free_p (0x1), pkt (0x8705A2D0), msecs (0x0), priority (0xA)
010811: Apr 25 08:23:07.731: ISDN BR0/2/0 PACKET: L2IF_SendPkt:
        source_id (0x20A), state (0x7),
        dest_id (0x200), event (0x140),
        sapi (0x0), ces (0x1), call_id (0x0),
        chan_id (0x0), int_id (0x8701B488), free_pkt (0x1),
        pkt (0x866E8D88), msecs (0x0), priority (0xA)
010812: Apr 25 08:23:07.731: ISDN BR0/2/0 PACKET: L2IF_DispPak: packet - start(E609CB8), size (4)
010813: Apr 25 08:23:07.731:    00870103--0000008B
010814: Apr 25 08:23:07.731: ISDN BR0/2/0 PACKET: L2IF_SendPkt: Sending from L2
        dest_id = 0, source_id = 522, prim = 140
        priv_ln = 4, int_id = -2029931384
  datastart = E609CA8, netstart = E609CB8, datasize = 20
010815: Apr 25 08:23:07.731: ISDN BR0/2/0 PACKET: L2IF_DispPak: packet - start(E609CA8), size (20)
010816: Apr 25 08:23:07.731:    8701B4880000020A014004000001300B00870103--000002DC
010817: Apr 25 08:23:07.731: ISDN  Q921d: isdn_l2d_srq_process: QUEUE_EVENT
010818: Apr 25 08:23:07.731: ISDN BR0/2/0 PACKET: handle_l2d_srq_mail: received:
        dest_id = 0x0000, source_id = 0x020A, prim = 0x0140
        priv_ln = 4, int_id = 0x8701B488, datasize = 4
        line_status[4] = IF_ACTIVE
010819: Apr 25 08:23:07.731: ISDN BR0/2/0 Q921r: User TX ->
010820: Apr 25 08:23:07.731:    00870103--0000008B
010821: Apr 25 08:23:07.731: ISDN  Q921d: isdn_l2d_srq_process: event_count 1
010822: Apr 25 08:23:07.731: ISDN  L2_CODE: isdn_l2_process: state (0x0), event_count (1)
010823: Apr 25 08:23:07.783: ISDN  Q921d: isdn_from_driver_process: QUEUE_EVENT
010824: Apr 25 08:23:07.783: ISDN BR0/2/0 Q921r: User RX <-
010825: Apr 25 08:23:07.783:    02870103--0000008D
010826: Apr 25 08:23:07.783: ISDN BR0/2/0 Q921d: process_rxdata: Frame sent to L2
010827: Apr 25 08:23:07.783: ISDN BR0/2/0 PACKET: L2IF_SendPkt:
        source_id (0x200), state (0x0),
        dest_id (0x20A), event (0x141),
        sapi (0x0), ces (0x0), call_id (0x0),
        chan_id (0x0), int_id (0x8701B488), free_pkt (0x1),
        pkt (0x8B281C14), msecs (0x0), priority (0xA)
010828: Apr 25 08:23:07.783: ISDN BR0/2/0 PACKET: L2IF_DispPak: packet - start(E611234), size (4)
010829: Apr 25 08:23:07.783:    02870103--0000008D
010830: Apr 25 08:23:07.783: ISDN BR0/2/0 PACKET: L2IF_SendPkt: Sending from L2
        dest_id = 0, source_id = 512, prim = 141
        priv_ln = 4, int_id = -2029931384
  datastart = E611224, netstart = E611234, datasize = 20
010831: Apr 25 08:23:07.783: ISDN BR0/2/0 PACKET: L2IF_DispPak: packet - start(E611224), size (20)
010832: Apr 25 08:23:07.783:    8701B48800000200014104000000300002870103--000002C9
010833: Apr 25 08:23:07.783: ISDN  Q921d: isdn_from_driver_process: event_count 1
010834: Apr 25 08:23:07.783: ISDN  L2_CODE: isdn_l2_process: QUEUE_EVENT state = 0
010835: Apr 25 08:23:07.783: ISDN BR0/2/0 PACKET: handle_mail: received:
        dest_id = 0, source_id = 512, prim = 141
        priv_ln = 4, int_id = -2029931384, datasize = 20
010836: Apr 25 08:23:07.783: ISDN BR0/2/0 PACKET: L2IF_DispPak: packet - start(E611234), size (20)
010837: Apr 25 08:23:07.783:    02870103AE01964DAE0189280B5A657374617769--00000671
010838: Apr 25 08:23:07.783: ISDN BR0/2/0 PACKET: handle_mail: received data =
010839: Apr 25 08:23:07.783:    02870103--0000008D
010840: Apr 25 08:23:07.783: ISDN BR0/2/0 L2_CODE: L2IF_GetDriverID()
010841: Apr 25 08:23:07.783: ISDN BR0/2/0 PACKET: L2IF_SendprimPkt:
        source_id (0x20A), state (0x8), dest_id (0x200), event (0x140),
        sapi (0x0), ces (0x1),  call_id (0x0),
        chan_id (0x0), int_id (0x8701B488), free_p (0x1), pkt (0x8705A250), msecs (0x0), priority (0xA)
010842: Apr 25 08:23:07.783: ISDN BR0/2/0 PACKET: L2IF_SendPkt:
        source_id (0x20A), state (0x8),
        dest_id (0x200), event (0x140),
        sapi (0x0), ces (0x1), call_id (0x0),
        chan_id (0x0), int_id (0x8701B488), free_pkt (0x1),
        pkt (0x866EB368), msecs (0x0), priority (0xA)
010843: Apr 25 08:23:07.783: ISDN BR0/2/0 PACKET: L2IF_DispPak: packet - start(E60A6B8), size (4)
010844: Apr 25 08:23:07.783:    02870103--0000008D
010845: Apr 25 08:23:07.783: ISDN BR0/2/0 PACKET: L2IF_SendPkt: Sending from L2
        dest_id = 0, source_id = 522, prim = 140
        priv_ln = 4, int_id = -2029931384
  datastart = E60A6A8, netstart = E60A6B8, datasize = 20
010846: Apr 25 08:23:07.783: ISDN BR0/2/0 PACKET: L2IF_DispPak: packet - start(E60A6A8), size (20)
010847: Apr 25 08:23:07.783:    8701B4880000020A014004A00001300002870103--00000373
010848: Apr 25 08:23:07.783: ISDN  L2_CODE: isdn_l2_process: TIMER_EVENT
010849: Apr 25 08:23:07.783: ISDN  Q921d: isdn_l2d_srq_process: QUEUE_EVENT
010850: Apr 25 08:23:07.783: ISDN BR0/2/0 PACKET: handle_l2d_srq_mail: received:
        dest_id = 0x0000, source_id = 0x020A, prim = 0x0140
        priv_ln = 4, int_id = 0x8701B488, datasize = 4
        line_status[4] = IF_ACTIVE
010851: Apr 25 08:23:07.783: ISDN BR0/2/0 Q921r: User TX ->
010852: Apr 25 08:23:07.783:    02870103--0000008D
010853: Apr 25 08:23:07.783: ISDN  Q921d: isdn_l2d_srq_process: event_count 1
010854: Apr 25 08:23:07.783: ISDN  L2_CODE: isdn_l2_process: state (0x0), event_count (1)
010855: Apr 25 08:23:07.803: ISDN  Q921d: isdn_from_driver_process: QUEUE_EVENT
010856: Apr 25 08:23:07.803: ISDN BR0/2/0 Q921r: User RX <-
010857: Apr 25 08:23:07.803:    00870103--0000008B
010858: Apr 25 08:23:07.803: ISDN BR0/2/0 Q921d: process_rxdata: Frame sent to L2
010859: Apr 25 08:23:07.803: ISDN BR0/2/0 PACKET: L2IF_SendPkt:
        source_id (0x200), state (0x0),
        dest_id (0x20A), event (0x141),
        sapi (0x0), ces (0x0), call_id (0x0),
        chan_id (0x0), int_id (0x8701B488), free_pkt (0x1),
        pkt (0x8B11336C), msecs (0x0), priority (0xA)
010860: Apr 25 08:23:07.803: ISDN BR0/2/0 PACKET: L2IF_DispPak: packet - start(EB0CB14), size (4)
010861: Apr 25 08:23:07.803:    00870103--0000008B
010862: Apr 25 08:23:07.803: ISDN BR0/2/0 PACKET: L2IF_SendPkt: Sending from L2
        dest_id = 0, source_id = 512, prim = 141
        priv_ln = 4, int_id = -2029931384
  datastart = EB0CB04, netstart = EB0CB14, datasize = 20
010863: Apr 25 08:23:07.803: ISDN BR0/2/0 PACKET: L2IF_DispPak: packet - start(EB0CB04), size (20)
010864: Apr 25 08:23:07.803:    8701B48800000200014104000000300000870103--000002C7
010865: Apr 25 08:23:07.803: ISDN  Q921d: isdn_from_driver_process: event_count 1
010866: Apr 25 08:23:07.803: ISDN  L2_CODE: isdn_l2_process: QUEUE_EVENT state = 0
010867: Apr 25 08:23:07.803: ISDN BR0/2/0 PACKET: handle_mail: received:
        dest_id = 0, source_id = 512, prim = 141
        priv_ln = 4, int_id = -2029931384, datasize = 20
010868: Apr 25 08:23:07.803: ISDN BR0/2/0 PACKET: L2IF_DispPak: packet - start(EB0CB14), size (20)
010869: Apr 25 08:23:07.803:    00870103AC019702180189280B5A657374617769--0000058D
010870: Apr 25 08:23:07.803: ISDN BR0/2/0 PACKET: handle_mail: received data =
010871: Apr 25 08:23:07.803:    00870103--0000008B
010872: Apr 25 08:23:07.803: ISDN  L2_CODE: isdn_l2_process: TIMER_EVENT
010873: Apr 25 08:23:07.803: ISDN  L2_CODE: isdn_l2_process: state (0x0), event_count (1)
010874: Apr 25 08:23:13.375: ISDN  Q921d: isdn_from_driver_process: QUEUE_EVENT
010875: Apr 25 08:23:13.375: ISDN BR0/2/0 Q921r: User RX <-
010876: Apr 25 08:23:13.375:    0287020208019701280B5779776F6C7977616E6965--00000610
010877: Apr 25 08:23:13.375: ISDN BR0/2/0 Q921d: process_rxdata: Frame sent to L2
010878: Apr 25 08:23:13.375: ISDN BR0/2/0 PACKET: L2IF_SendPkt:
        source_id (0x200), state (0x0),
        dest_id (0x20A), event (0x141),
        sapi (0x0), ces (0x0), call_id (0x0),
        chan_id (0x0), int_id (0x8701B488), free_pkt (0x1),
        pkt (0x8B281C14), msecs (0x0), priority (0xA)
010879: Apr 25 08:23:13.375: ISDN BR0/2/0 PACKET: L2IF_DispPak: packet - start(E611234), size (21)
010880: Apr 25 08:23:13.375:    0287020208019701280B5779776F6C7977616E6965--00000610
010881: Apr 25 08:23:13.no deb375: ISDN BR0/2/0 PACKET: L2IF_SendPkt: Sending from L2
        dest_id = 0, source_id = 512, prim = 141
        priv_ln = 4, int_id = -2029931384
  datastart = E611224, netstart = E611234, datasize = 37
010882: Apr 25 08:23:13.375: ISDN BR0/2/0 PACKET: L2IF_DispPak: packet - start(E611224), size (37)
010883: Apr 25 08:23:13.375:    8701B4880000020001410400000030000287020208019701280B5779776F6C7977616E6965--0000084C
010884: Apr 25 08:23:13.375: ISDN  Q921d: isdn_from_driver_process: event_count 1
010885: Apr 25 08:23:13.375: ISDN  L2_CODE: isdn_l2_process: QUEUE_EVENT state = 0
010886: Apr 25 08:23:13.375: ISDN BR0/2/0 PACKET: handle_mail: received:
        dest_id = 0, source_id = 512, prim = 141
        priv_ln = 4, int_id = -2029931384, datasize = 37
010887: Apr 25 08:23:13.375: ISDN BR0/2/0 PACKET: L2IF_DispPak: packet - start(E611234), size (37)
010888: Apr 25 08:23:13.375: ug all
All possible debugging has been turned off
FISK_UC520#     0287020208019701280B5779776F6C7977616E6965AE6965AE000000000000000000000000--0000083A
010889: Apr 25 08:23:13.379: ISDN BR0/2/0 PACKET: handle_mail: received data =
010890: Apr 25 08:23:13.379:    0287020208019701280B5779776F6C7977616E6965--00000610
010891: Apr 25 08:23:13.379: ISDN BR0/2/0 PACKET: L2IF_SendprimPkt:
        source_id (0x20A), state (0x7), dest_id (0x300), event (0x241),
        sapi (0x0), ces (0x1),  call_id (0x0),
        chan_id (0x0), int_id (0x8701B488), free_p (0x1), pkt (0x8705A2D0), msecs (0x0), priority (0xA)
010892: Apr 25 08&a

[Kyniu]

Mnie się wyczerpały pomysły. Chyba że możesz jeszcze zainstalować ten router w innej lokalizacji.

[przemek_z]

Pokaż konfigurację.