> -----Urspr�ngliche Nachricht-----
> Von: Richard Leopold [SMTP:[EMAIL PROTECTED]]
> Gesendet am: Montag, 15. M�rz 1999 13:21
> Cc: [EMAIL PROTECTED]; Engemann, Michael; Karsten Keil
> Betreff: E002F - D-Kanal-Trace
>
> MOIn,
>
> heute ist mir ein E002F (remote hangup) ins Netz gegangen !
> UND die Leitung blieb wieder h�ngen.
>
> Im folgenden findet ihr das messages-Log und den isdnctrl0-Trace.
>
> Soweit ich es deuten kann, wird _kein_ DISCONNECT vom Provider
> gesendet.
>
> Trotzdem m�chte ich euch bitten, ebenfalls die Logs zu interpretieren.
>
> Was mich besonders interessiert ist:
> - Mon Mar 15 11:19:59 1999 04:51.12 Card 1 PH_DEACT ph_active 5
> - Mon Mar 15 11:19:59 1999 04:51.33 Card 1 PH_ACT ph_active 4
> im isdnctl0.
>
> Gru� - Richard
> -----------------------------------------------------------------------
> #/var/log/messages:
> Mar 15 11:19:30 next kernel: ippp1: dialing 0 01801000555...
> Mar 15 11:19:30 next isdnlog: Mar 15 11:19:30 * tei 115 calling -1und1-
> with IGATE.WLP RING (Data)
> Mar 15 11:19:31 next isdnlog: Mar 15 11:19:31 * tei 115 calling -1und1-
> with IGATE.WLP CHANNEL: BRI, B1 gefordert
> Mar 15 11:19:33 next isdnlog: Mar 15 11:19:33 tei 115 calling -1und1- with
> IGATE.WLP Time:Mon Mar 15 11:20:00 1999
> Mar 15 11:19:33 next isdnlog: Mar 15 11:19:33 tei 115 calling -1und1- with
> IGATE.WLP CONNECT (Data)
> Mar 15 11:19:33 next kernel: isdn_net: ippp1 connected
> Mar 15 11:19:33 next kernel: isdn_net: chargetime of ippp1 now 3026548
> Mar 15 11:19:33 next isdnlog: Mar 15 11:19:33 tei 115 calling -1und1- with
> IGATE.WLP CHARGEINT ippp1 still 90 - City, Vormittag
> Mar 15 11:19:33 next isdnlog: Mar 15 11:19:33 tei 115 calling -1und1- with
> IGATE.WLP HUPTIMEOUT ippp1 still 30
> Mar 15 11:19:33 next isdnlog: Mar 15 11:19:33 tei 115 calling -1und1- with
> IGATE.WLP CHARGEMAX remaining=DM 18,72
> Mar 15 11:19:33 next isdnlog: Mar 15 11:19:33 tei 115 calling -1und1- with
> IGATE.WLP 1.EH DM 0,12
> Mar 15 11:19:33 next ipppd[120]: Local number: 912081, Remote number:
> 01801000555, Type: outgoing
> Mar 15 11:19:33 next ipppd[120]: PHASE_WAIT -> PHASE_ESTABLISHED, ifunit:
> 1, linkunit: 0, fd: 6
> Mar 15 11:19:36 next ipppd[120]: Remote message:
> Mar 15 11:19:36 next ipppd[120]: bundle, he: 0 we: 0
> Mar 15 11:19:36 next ipppd[120]: local IP address 62.144.239.163
> Mar 15 11:19:36 next ipppd[120]: remote IP address 62.144.240.58
> Mar 15 11:19:38 next set_mask: FIREWALL AND MASQUERADING IS UP ...
----------------------------------------------------------------------------
> Mar 15 11:19:59 next kernel: isdn: HiSax,ch0 cause: E002F
---------------------11:19:59------------ hier tritt die
Kernelmeldung auf, aber (siehe unten)
> Mar 15 11:19:59 next kernel: ippp1: remote hangup
> Mar 15 11:19:59 next kernel: ippp1: Chargesum is 0
> Mar 15 11:19:59 next ipppd[120]: Modem hangup
> Mar 15 11:19:59 next ipppd[120]: Connection terminated.
> Mar 15 11:19:59 next ipppd[120]: taking down PHASE_DEAD link 0, linkunit:
> 0
> Mar 15 11:19:59 next ipppd[120]: link 0 closed , linkunit: 0
> Mar 15 11:19:59 next ipppd[120]: reinit_unit: 0
> Mar 15 11:19:59 next ipppd[120]: Connect[0]: /dev/ippp1, fd: 6
> Mar 15 11:19:59 next kernel: OPEN: 62.144.239.163 -> 192.41.19.180 TCP,
> port: 1560 -> 110
> Mar 15 11:19:59 next kernel: ippp1: dialing 0 01801000555...
> Mar 15 11:19:59 next E002F-DEFENCE: reb00ting system in 300 seconds
> Mar 15 11:20:02 next del_mask: FIREWALL AND MASQUERADING IS DOWN ...
> Mar 15 11:20:02 next kernel: isdn_net: local hangup ippp1
> Mar 15 11:20:02 next kernel: ippp1: Chargesum is 0
> Mar 15 11:20:04 next kernel: ip_rewrite_addrs(): shifting saddr from
> 62.144.239.163 to 192.168.15.3 (state 4)
> Mar 15 11:20:04 next kernel: OPEN: 192.168.15.3 -> 192.41.19.180 TCP,
> port: 1560 -> 110
> Mar 15 11:20:04 next kernel: ippp1: dialing 0 01801000555...
> Mar 15 11:20:12 next kernel: isdn_net: local hangup ippp1
> Mar 15 11:20:12 next kernel: ippp1: Chargesum is 0
> Mar 15 11:20:20 next kernel: OPEN: 192.168.15.3 -> 192.41.19.180 TCP,
> port: 1560 -> 110
> Mar 15 11:20:20 next kernel: ippp1: dialing 0 01801000555...
> Mar 15 11:20:27 next kernel: isdn_net: local hangup ippp1
> Mar 15 11:20:27 next kernel: ippp1: Chargesum is 0
> Mar 15 11:20:41 next kernel: OPEN: 192.168.15.3 -> 192.41.19.180 TCP,
> port: 1560 -> 110
> Mar 15 11:20:41 next kernel: ippp1: dialing 0 01801000555...
> Mar 15 11:20:49 next kernel: isdn_net: local hangup ippp1
> Mar 15 11:20:49 next kernel: ippp1: Chargesum is 0
> Mar 15 11:21:03 next isdnlog: Mar 15 11:21:03 tei 115 calling -1und1- with
> IGATE.WLP CHARGEMAX remaining=DM 18,60
> Mar 15 11:21:03 next isdnlog: Mar 15 11:21:03 tei 115 calling -1und1- with
> IGATE.WLP 2.EH DM 0,24 ( 0:01:30)
> Mar 15 11:21:23 next kernel: OPEN: 192.168.15.3 -> 192.41.19.180 TCP,
> port: 1560 -> 110
> Mar 15 11:21:23 next kernel: ippp1: dialing 0 01801000555...
> Mar 15 11:21:31 next kernel: isdn_net: local hangup ippp1
> Mar 15 11:21:31 next kernel: ippp1: Chargesum is 0
> Mar 15 11:22:33 next isdnlog: Mar 15 11:22:33 tei 115 calling -1und1- with
> IGATE.WLP CHARGEMAX remaining=DM 18,48
> Mar 15 11:22:33 next isdnlog: Mar 15 11:22:33 tei 115 calling -1und1- with
> IGATE.WLP 3.EH DM 0,36 ( 0:03:00)
> Mar 15 11:22:47 next kernel: OPEN: 192.168.15.3 -> 192.41.19.180 TCP,
> port: 1560 -> 110
> Mar 15 11:22:47 next kernel: ippp1: dialing 0 01801000555...
> Mar 15 11:22:55 next kernel: isdn_net: local hangup ippp1
> Mar 15 11:22:55 next kernel: ippp1: Chargesum is 0
> Mar 15 11:24:03 next isdnlog: Mar 15 11:24:03 tei 115 calling -1und1- with
> IGATE.WLP CHARGEMAX remaining=DM 18,36
> Mar 15 11:24:03 next isdnlog: Mar 15 11:24:03 tei 115 calling -1und1- with
> IGATE.WLP 4.EH DM 0,48 ( 0:04:30)
> Mar 15 11:24:47 next kernel: OPEN: 192.168.15.3 -> 192.41.19.180 TCP,
> port: 1560 -> 110
> Mar 15 11:24:47 next kernel: ippp1: dialing 0 01801000555...
> Mar 15 11:24:55 next kernel: isdn_net: local hangup ippp1
> Mar 15 11:24:55 next kernel: ippp1: Chargesum is 0
> Mar 15 11:27:51 next syslogd 1.3-0: restart.
> --------------------------------------------------------------------------
> ---------------------
> #/tmp/isdnctrl0:
> Mon Mar 15 11:05:02 1999 89:54.98 Card 1 PH_DEACT ph_active 5
> Mon Mar 15 11:19:30 1999 04:22.88 Card 1 PH_ACT ph_active 0
> Mon Mar 15 11:19:30 1999 HEX: 00 E7 00 00 08 01 45 05 A1 04 02 88 90 6C
> 08 01 80 39 31 32 30 38 31 70 0C 81 30 31 38 30 31 30 30 30 35 35 35
> Mon Mar 15 11:19:30 1999 Q.931 frame user->network tei 115
> Mon Mar 15 11:19:30 1999 callref 69 caller size 33 message type SETUP
> Mon Mar 15 11:19:30 1999 Sending complete
> Mon Mar 15 11:19:30 1999 Bearer capability
> Mon Mar 15 11:19:30 1999 octet 3 10001000
> Mon Mar 15 11:19:30 1999 octet 4 10010000
> Mon Mar 15 11:19:30 1999 octet 7 01101100
> Mon Mar 15 11:19:30 1999 Calling party number
> Mon Mar 15 11:19:30 1999 octet 3 00000001
> Mon Mar 15 11:19:30 1999 octet 3a 10000000
> Mon Mar 15 11:19:30 1999 number digits 912081
> Mon Mar 15 11:19:30 1999 Called party number
> Mon Mar 15 11:19:30 1999 octet 3 10000001
> Mon Mar 15 11:19:30 1999 number digits 01801000555
> Mon Mar 15 11:19:30 1999
> Mon Mar 15 11:19:31 1999 HEX: 02 E7 00 02 08 01 C5 02 18 01 89
> Mon Mar 15 11:19:31 1999 Q.931 frame network->user tei 115
> Mon Mar 15 11:19:31 1999 callref 69 called size 7 message type CALL
> PROCEEDING
> Mon Mar 15 11:19:31 1999 Channel identification
> Mon Mar 15 11:19:31 1999 octet 3 10001001
> Mon Mar 15 11:19:31 1999
> Mon Mar 15 11:19:33 1999 HEX: 02 E7 02 02 08 01 C5 01
> Mon Mar 15 11:19:33 1999 Q.931 frame network->user tei 115
> Mon Mar 15 11:19:33 1999 callref 69 called size 4 message type ALERTING
> Mon Mar 15 11:19:33 1999
> Mon Mar 15 11:19:33 1999 HEX: 02 E7 04 02 08 01 C5 07 29 05 63 03 0F 0B
> 14
> Mon Mar 15 11:19:33 1999 Q.931 frame network->user tei 115
> Mon Mar 15 11:19:33 1999 callref 69 called size 11 message type CONNECT
> Mon Mar 15 11:19:33 1999 Date/Time
> Mon Mar 15 11:19:33 1999 octet 3 01100011
> Mon Mar 15 11:19:33 1999 octet 3a 00000011
> Mon Mar 15 11:19:33 1999 octet 3b 00001111
> Mon Mar 15 11:19:33 1999 octet 3c 00001011
> Mon Mar 15 11:19:33 1999 octet 3d 00010100
> Mon Mar 15 11:19:33 1999
> Mon Mar 15 11:19:33 1999 HEX: 02 E7 06 02 08 01 C5 62 1C 18 91 A1 15 02
> 01 FE 02 01 22 30 0D A1 05 30 03 02 01 01 82 01 00 83 01 00
> Mon Mar 15 11:19:33 1999 Q.931 frame network->user tei 115
> Mon Mar 15 11:19:33 1999 callref 69 called size 30 message type FACILITY
> Mon Mar 15 11:19:33 1999 Facility
> Mon Mar 15 11:19:33 1999 octet 3 10010001
> Mon Mar 15 11:19:33 1999 octet 4 10100001
> Mon Mar 15 11:19:33 1999 octet 5 21
> Mon Mar 15 11:19:33 1999 contents 2 1 fe 2 1 22 30 d a1 5 30 3
> 2 1 1 82 1 0 83 1 0
> Mon Mar 15 11:19:33 1999
>
>
+---------------------------------------------------------------------------
------------------------------------------+
Es kommt um 11:19:33 die erste Geb�hreneinheit.
Um 11:19:59 (zum Zeitpunkt der Kernelmeldung) kommt auf dem D-Kanal
KEIN Disconnect von der Vermittlungsstelle, geschweige den von der
ISDN-Karte. Der Kernel meldet zwar Cause E002F, aber es bleibt auf dem
D-Kanal ohne folgen, weil es sich nicht wirklich um einen Fehler im
Protokoll handelt. Die Verbindung ist auch einwandfrei. Die ISDN-Karte oder
i4l verschluckt sich intern an irgendetwas und gibt diese Meldung im LOG aus
ohne weitere Aktionen in Richtung der Vermittlungsstelle zu veranlassen.
Hinzu kommt noch, da� laut ETS 300 102-1, Anhang I, Tabelle I-2 beim
Auftreten des Fehlergrundes E002F (Cause 47) keine weiteren Prozeduren
vorgesehen sind. So bleibt die Verbindung bestehen.
+---------------------------------------------------------------------------
------------------------------------------+
> Mon Mar 15 11:19:59 1999 04:51.12 Card 1 PH_DEACT ph_active 5
> Mon Mar 15 11:19:59 1999 04:51.33 Card 1 PH_ACT ph_active 4
> Mon Mar 15 11:20:02 1999 04:54.51 Card 1 PH_DEACT ph_active 5
> Mon Mar 15 11:20:04 1999 04:56.28 Card 1 PH_ACT ph_active 4
> Mon Mar 15 11:20:12 1999 05:04.08 Card 1 PH_DEACT ph_active 5
> Mon Mar 15 11:20:20 1999 05:12.12 Card 1 PH_ACT ph_active 4
> Mon Mar 15 11:20:27 1999 05:19.92 Card 1 PH_DEACT ph_active 5
> Mon Mar 15 11:20:41 1999 05:33.24 Card 1 PH_ACT ph_active 4
> Mon Mar 15 11:20:49 1999 05:41.04 Card 1 PH_DEACT ph_active 5
> Mon Mar 15 11:21:03 1999 HEX: 02 E7 00 00 08 01 C5 62 1C 18 91 A1 15 02
> 01 FF 02 01 22 30 0D A1 05 30 03 02 01 02 82 01 00 83 01 00
> Mon Mar 15 11:21:03 1999 Q.931 frame network->user tei 115
> Mon Mar 15 11:21:03 1999 callref 69 called size 30 message type FACILITY
> Mon Mar 15 11:21:03 1999 Facility
> Mon Mar 15 11:21:03 1999 octet 3 10010001
> Mon Mar 15 11:21:03 1999 octet 4 10100001
> Mon Mar 15 11:21:03 1999 octet 5 21
> Mon Mar 15 11:21:03 1999 contents 2 1 ff 2 1 22 30 d a1 5 30 3
> 2 1 2 82 1 0 83 1 0
> Mon Mar 15 11:21:03 1999
> Mon Mar 15 11:21:23 1999 06:15.48 Card 1 PH_ACT ph_active 4
> Mon Mar 15 11:21:31 1999 06:23.28 Card 1 PH_DEACT ph_active 5
> Mon Mar 15 11:22:33 1999 HEX: 02 E7 02 00 08 01 C5 62 1C 18 91 A1 15 02
> 01 01 02 01 22 30 0D A1 05 30 03 02 01 03 82 01 00 83 01 00
> Mon Mar 15 11:22:33 1999 Q.931 frame network->user tei 115
> Mon Mar 15 11:22:33 1999 callref 69 called size 30 message type FACILITY
> Mon Mar 15 11:22:33 1999 Facility
> Mon Mar 15 11:22:33 1999 octet 3 10010001
> Mon Mar 15 11:22:33 1999 octet 4 10100001
> Mon Mar 15 11:22:33 1999 octet 5 21
> Mon Mar 15 11:22:33 1999 contents 2 1 1 2 1 22 30 d a1 5 30 3
> 2 1 3 82 1 0 83 1 0
> Mon Mar 15 11:22:33 1999
> Mon Mar 15 11:22:47 1999 07:39.96 Card 1 PH_ACT ph_active 4
> Mon Mar 15 11:22:55 1999 07:47.76 Card 1 PH_DEACT ph_active 5
> Mon Mar 15 11:24:03 1999 HEX: 02 E7 04 00 08 01 C5 62 1C 18 91 A1 15 02
> 01 02 02 01 22 30 0D A1 05 30 03 02 01 04 82 01 00 83 01 00
> Mon Mar 15 11:24:03 1999 Q.931 frame network->user tei 115
> Mon Mar 15 11:24:03 1999 callref 69 called size 30 message type FACILITY
> Mon Mar 15 11:24:03 1999 Facility
> Mon Mar 15 11:24:03 1999 octet 3 10010001
> Mon Mar 15 11:24:03 1999 octet 4 10100001
> Mon Mar 15 11:24:03 1999 octet 5 21
> Mon Mar 15 11:24:03 1999 contents 2 1 2 2 1 22 30 d a1 5 30 3
> 2 1 4 82 1 0 83 1 0
> Mon Mar 15 11:24:03 1999
> Mon Mar 15 11:24:47 1999 09:39.96 Card 1 PH_ACT ph_active 4
> Mon Mar 15 11:24:55 1999 09:47.76 Card 1 PH_DEACT ph_active 5
> Mon Mar 15 11:27:47 1999 02:16.49 Card 1 IRQ 5 count 0
> Mon Mar 15 11:27:47 1999 02:16.49 Card 1 HSCX B ISTA 0
> Mon Mar 15 11:27:47 1999 02:16.49 Card 1 HSCX A ISTA 0
> Mon Mar 15 11:27:47 1999 02:16.49 Card 1 HSCX B STAR 48
> Mon Mar 15 11:27:47 1999 02:16.49 Card 1 HSCX A STAR 48
> Mon Mar 15 11:27:47 1999 02:16.49 Card 1 ISAC STAR 48
> Mon Mar 15 11:27:47 1999 02:16.49 Card 1 ISAC MODE 0
> Mon Mar 15 11:27:47 1999 02:16.49 Card 1 ISAC ADF2 0
> Mon Mar 15 11:27:47 1999 02:16.49 Card 1 ISAC ISTA 0
> Mon Mar 15 11:27:47 1999 02:16.49 Card 1 IRQ 5 count 2
> Mon Mar 15 11:27:47 1999 debugging flags card 1 set to 4
> --------------------------------------------------------------------------
> ---------------------------
>
>
>
>
>
Fazit:
Die Verbindung wird nicht getrennt, weil weder die ISDN-Karte noch
die Vermittlungsstelle einen Disconnect senden. Es besteht ja auch kein
Grund dazu, da der eigentliche Verbindungsaufbau erfolgreich war und keine
Fehler aufgetreten sind. Nur i4l, bzw der Kernel meldet einen "imagin�ren"
Fehler und verh�lt sich so, als ob die Verbindung aus der Ferne getrennt
wurde.
ABER: Sie wurde nicht getrennt.
I4l m�sste jetzt eigentlich einen Disconnect zur VSt senden um die
Verbindung auch physikalisch zu trennen.
Also: kein Fehler im D-Kanal, sondern h�chstwarscheinlich in der
Software.
mfg Michael Engemann
-
To unsubscribe from this list please send a mail to [EMAIL PROTECTED] with
'unsubscribe suse-isdn' in its body.
