Jeszcze jedno pytanie.
Czy w logu route nie powinien się znaleźć wpis dotyczący interface lo (Local Loopback) z IP 127.0.0.1 ? Coś w stylu
# the route to the loop back device
127.0.0.1 * 255.0.0.0 U 3584 0 483 lo
A co daje przełącznik -n w route?
Nie jesteś zalogowany. Proszę się zalogować lub zarejestrować.
eko.one.pl → Posty przez jkdobro
Jeszcze jedno pytanie.
Czy w logu route nie powinien się znaleźć wpis dotyczący interface lo (Local Loopback) z IP 127.0.0.1 ? Coś w stylu
# the route to the loop back device
127.0.0.1 * 255.0.0.0 U 3584 0 483 lo
A co daje przełącznik -n w route?
OK. Ale dopiero wieczorem, kiedy wrócę z pracy ![]()
Pozdrawiam Jarek
Porównując logi z błędnego (automatycznego) odpalenia pppd i paprawnego (ręcznego) wygląda na to, że w modemie są jakieś śmieci.
Automatyczne (błędne) ładowanie pppd:
Jan 1 00:01:15 (none) kern.notice pppd[717]: pppd 2.4.3 started by root, uid 0
Jan 1 00:01:15 (none) kern.debug pppd[717]: using channel 1
Jan 1 00:01:15 (none) kern.info pppd[717]: Using interface ppp0
Jan 1 00:01:15 (none) kern.notice pppd[717]: Connect: ppp0 <--> /dev/pts/1
Jan 1 00:01:15 (none) kern.debug pppd[717]: rcvd [LCP EchoReq id=0x14 magic=0x4792590d]
Jan 1 00:01:15 (none) kern.debug pppd[717]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0xae5834a0> <pcomp> <accomp>]
Jan 1 00:01:16 (none) kern.debug pppd[717]: rcvd [LCP ConfReq id=0x15 <auth chap MD5> <magic 0x2557e3b3>]
Jan 1 00:01:16 (none) kern.debug pppd[717]: sent [LCP ConfAck id=0x15 <auth chap MD5> <magic 0x2557e3b3>]
Jan 1 00:01:16 (none) kern.debug pppd[717]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0xae5834a0> <pcomp> <accomp>]
Jan 1 00:01:16 (none) kern.debug pppd[717]: sent [LCP EchoReq id=0x0 magic=0xae5834a0]
Jan 1 00:01:16 (none) kern.debug pppd[717]: rcvd [LCP TermReq id=0x16]
Jan 1 00:01:16 (none) kern.info pppd[717]: LCP terminated by peer
Jan 1 00:01:16 (none) kern.debug pppd[717]: sent [LCP TermAck id=0x16]I prawidłowe (ręczne) ładowanie pppd:
Jan 1 01:02:20 (none) kern.notice pppd[1013]: pppd 2.4.3 started by root, uid 0
Jan 1 01:02:20 (none) kern.debug pppd[1013]: using channel 7
Jan 1 01:02:20 (none) kern.info pppd[1013]: Using interface ppp0
Jan 1 01:02:20 (none) kern.notice pppd[1013]: Connect: ppp0 <--> /dev/pts/1
Jan 1 01:02:21 (none) kern.debug pppd[1013]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x25c393f2> <pcomp> <accomp>]
Jan 1 01:02:23 (none) kern.debug pppd[1013]: rcvd [LCP ConfReq id=0x70 <auth chap MD5> <magic 0x283ca3da>]
Jan 1 01:02:23 (none) kern.debug pppd[1013]: sent [LCP ConfAck id=0x70 <auth chap MD5> <magic 0x283ca3da>]
Jan 1 01:02:24 (none) kern.debug pppd[1013]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x25c393f2> <pcomp> <accomp>]
Jan 1 01:02:24 (none) kern.debug pppd[1013]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x25c393f2> <pcomp> <accomp>]
Jan 1 01:02:24 (none) kern.debug pppd[1013]: sent [LCP EchoReq id=0x0 magic=0x25c393f2]
Jan 1 01:02:24 (none) kern.debug pppd[1013]: rcvd [CHAP Challenge id=0xa9 <76bba1f1c76eccf907bde857d5b91dae6c2282e6c752319185cea699967a>, name = "rze_ru1"]sekwencja przesyłania danych z LCP rozpoczyna się w pierwszym (auto) od:
Jan 1 00:01:15 (none) kern.debug pppd[717]: rcvd [LCP EchoReq id=0x14 magic=0x4792590d]
jak widać '...rcvd [LCP EchoReq...' mówi że jest to odbierane Echo z modemu. Potem dopiero następuje wysyłanie danych do modemu i odbieranie.
W logu z rozruchu ręcznego (działającego) sekwencja z LCP rozpoczyna się od wysłania '...sent [LCP ConfReq..." danych i dopiero potem odebrania.
Podsumowując w logu błednym (auto) sekwencje LCP zaczynają się od odczytu danych (echo), a dopiero kolejne są podobne jak w logu prawidłowym (ręcznym).
Myślę że trzeba będzie zrobić, tak jak pisałeś, zastosować sztuczkę:
pppd call neostrada-pppoa
sleep 1
killall pppd
pppd call neostrada-pppoa
Chyba, że udało by się wyczyścić bufor modemu, albo zresetować. Ale jak?
Witaj
Połączenie roszło. Pomogło (route del default gw 192.168.2.1) przed pppd. Pinguje różne ip. DNS też prawidłowo działa. Z kompa też wygląda, że jest wszystko OK. Właśnie piszę wiadoność na neo z WL500gp. ![]()
Został tylko problem pierwszego (automatycznego) odpalenia pppd. Znów jest ten sam problem z LPC
Jan 1 00:01:16 (none) kern.debug pppd[713]: Discarded non-LCP packet when LCP not open
Co ciekawe w pliku z logami od pppd nic na ten temat nie ma. Porównując z logami, które są po poprawnym załadowaniu pppd, wygląda jak by modem miał już coś wcześniej wysłane i wysłanie danych do niego zwraca jakieś błędy.
Jak by to sprawdzić?
Widać wszystko co wartościowe rodzi się w bólach.....
Więc tak. Moduły ładują się prawidłowo. Firmware też. Jest synchro modemu.
Kiedy startuje pppd (dałem sllep 15 pomiędzy ładowaniem firmware i pppd) wywala jakiś błąd. Coś z LCP
Jan 1 00:00:59 (none) user.info : Info ADSL line is up (2496 kbit/s down | 320 kbit/s up)
# tu przerwa 15 sekund (dla pewności)
Jan 1 00:01:14 (none) kern.notice pppd[712]: pppd 2.4.3 started by root, uid 0
Jan 1 00:01:15 (none) kern.debug pppd[712]: using channel 1
Jan 1 00:01:15 (none) kern.info pppd[712]: Using interface ppp0
Jan 1 00:01:15 (none) kern.notice pppd[712]: Connect: ppp0 <--> /dev/pts/1
Jan 1 00:01:15 (none) kern.debug pppd[712]: rcvd [LCP EchoReq id=0x67 magic=0x581210e4]
Jan 1 00:01:15 (none) kern.debug pppd[712]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0xa6ce3082> <pcomp> <accomp>]
Jan 1 00:01:15 (none) kern.debug pppd[712]: rcvd [LCP ConfReq id=0x68 <auth chap MD5> <magic 0x599f6116>]
Jan 1 00:01:15 (none) kern.debug pppd[712]: sent [LCP ConfAck id=0x68 <auth chap MD5> <magic 0x599f6116>]
Jan 1 00:01:15 (none) kern.debug pppd[712]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0xa6ce3082> <pcomp> <accomp>]
Jan 1 00:01:15 (none) kern.debug pppd[712]: sent [LCP EchoReq id=0x0 magic=0xa6ce3082]
Jan 1 00:01:15 (none) kern.debug pppd[712]: rcvd [LCP TermReq id=0x69]
Jan 1 00:01:15 (none) kern.info pppd[712]: LCP terminated by peer
Jan 1 00:01:15 (none) kern.debug pppd[712]: sent [LCP TermAck id=0x69]
Jan 1 01:01:18 (none) kern.notice pppd[712]: Connection terminated.
Jan 1 01:01:18 (none) kern.notice pppd[712]: Modem hangupMuszę porównać z logami kiedy ładuje sie prawidłowo, może coś namierzę.
Skoro tak się działo to:
Zabiłem pppd, dnsmasq i zacząłem ładować z palca. pppd poszedł cacy, a po nim S60dnsmasq - tek OK.
Log wygląda tak:
Jan 1 01:05:00 (none) kern.notice pppd[1393]: pppd 2.4.3 started by root, uid 0
Jan 1 01:05:00 (none) kern.debug pppd[1393]: using channel 26
Jan 1 01:05:00 (none) kern.info pppd[1393]: Using interface ppp0
Jan 1 01:05:00 (none) kern.notice pppd[1393]: Connect: ppp0 <--> /dev/pts/1
Jan 1 01:05:01 (none) kern.debug pppd[1393]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x3b58430> <pcomp> <accomp>]
Jan 1 01:05:05 (none) kern.debug pppd[1393]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x3b58430> <pcomp> <accomp>]
Jan 1 01:05:05 (none) kern.debug pppd[1393]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x3b58430> <pcomp> <accomp>]
Jan 1 01:05:06 (none) kern.debug pppd[1393]: rcvd [LCP ConfReq id=0xeb <auth chap MD5> <magic 0x53586aa6>]
Jan 1 01:05:06 (none) kern.debug pppd[1393]: sent [LCP ConfAck id=0xeb <auth chap MD5> <magic 0x53586aa6>]
Jan 1 01:05:06 (none) kern.debug pppd[1393]: sent [LCP EchoReq id=0x0 magic=0x3b58430]
Jan 1 01:05:06 (none) kern.debug pppd[1393]: rcvd [CHAP Challenge id=0xd7 <59b09c574fa553a273d73117f9925c0fca7ead9dbd2cae25b70ea8fd452c>, name = "rze_ru1"]
Jan 1 01:05:06 (none) kern.debug pppd[1393]: sent [CHAP Response id=0xd7 <bc68525c39f9d8add22f584c748fbc85>, name = "XXXXXXX@neostrada.pl"]
Jan 1 01:05:06 (none) kern.debug pppd[1393]: rcvd [LCP EchoRep id=0x0 magic=0x53586aa6]
Jan 1 01:05:06 (none) kern.debug pppd[1393]: rcvd [CHAP Success id=0xd7 ""]
Jan 1 01:05:06 (none) kern.info pppd[1393]: CHAP authentication succeeded
Jan 1 01:05:06 (none) kern.notice pppd[1393]: CHAP authentication succeeded
Jan 1 01:05:06 (none) kern.debug pppd[1393]: sent [CCP ConfReq id=0x1 <mppe -H -M -S -L -D +C>]
Jan 1 01:05:06 (none) kern.debug pppd[1393]: sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 0.0.0.0>]
Jan 1 01:05:06 (none) kern.debug pppd[1393]: rcvd [LCP ProtRej id=0xec 80 fd 01 01 00 0a 12 06 00 00 00 01]
Jan 1 01:05:06 (none) kern.debug pppd[1393]: rcvd [IPCP ConfRej id=0x1 <compress VJ 0f 01>]
Jan 1 01:05:06 (none) kern.debug pppd[1393]: sent [IPCP ConfReq id=0x2 <addr 0.0.0.0>]
Jan 1 01:05:06 (none) kern.debug pppd[1393]: rcvd [IPCP ConfNak id=0x2 <addr 83.11.151.34>]
Jan 1 01:05:06 (none) kern.debug pppd[1393]: sent [IPCP ConfReq id=0x3 <addr 83.11.151.34>]
Jan 1 01:05:06 (none) kern.debug pppd[1393]: rcvd [IPCP ConfAck id=0x3 <addr 83.11.151.34>]
Jan 1 01:05:06 (none) kern.debug pppd[1393]: rcvd [IPCP ConfReq id=0x95 <addr 213.25.2.228>]
Jan 1 01:05:06 (none) kern.debug pppd[1393]: sent [IPCP ConfAck id=0x95 <addr 213.25.2.228>]
Jan 1 01:05:06 (none) kern.err pppd[1393]: not replacing default route to br0 [192.168.2.1]
Jan 1 01:05:06 (none) kern.notice pppd[1393]: local IP address 83.11.151.34
Jan 1 01:05:06 (none) kern.notice pppd[1393]: remote IP address 213.25.2.228
Jan 1 01:05:06 (none) kern.debug pppd[1393]: Script /etc/ppp/ip-up started (pid 1409)
Jan 1 01:05:06 (none) kern.debug pppd[1393]: Script /etc/ppp/ip-up finished (pid 1409), status = 0x1
Jan 1 01:05:42 (none) kern.info dnsmasq[1495]: started, version 2.35 cachesize 150
Jan 1 01:05:42 (none) kern.info dnsmasq[1495]: compile time options: IPv6 GNU-getopt ISC-leasefile no-DBus no-I18N
Jan 1 01:05:42 (none) kern.info dnsmasq[1495]: DHCP, IP range 192.168.2.100 -- 192.168.2.249, lease time 12h
Jan 1 01:05:42 (none) kern.info dnsmasq[1495]: using local addresses only for domain lan
Jan 1 01:05:42 (none) kern.info dnsmasq[1495]: reading /tmp/resolv.conf.auto
Jan 1 01:05:42 (none) kern.info dnsmasq[1495]: using nameserver 217.98.63.164#53
Jan 1 01:05:42 (none) kern.info dnsmasq[1495]: using nameserver 194.204.152.34#53
Jan 1 01:05:42 (none) kern.info dnsmasq[1495]: using local addresses only for domain lan
Jan 1 01:05:42 (none) kern.info dnsmasq[1495]: read /etc/hosts - 1 addresses
Jan 1 01:05:42 (none) kern.info dnsmasq[1495]: read /etc/ethers - 0 addressesW tym logu nie podoba mi się:
Jan 1 01:05:06 (none) kern.err pppd[1393]: not replacing default route to br0 [192.168.2.1]
Jak go zmusić żeby zmienił zdanie?
i jeszcze
Jan 1 01:05:42 (none) kern.info dnsmasq[1495]: using local addresses only for domain lan
Jan 1 01:05:42 (none) kern.info dnsmasq[1495]: reading /tmp/resolv.conf.auto
Jan 1 01:05:42 (none) kern.info dnsmasq[1495]: using nameserver 217.98.63.164#53
Jan 1 01:05:42 (none) kern.info dnsmasq[1495]: using nameserver 194.204.152.34#53
Jan 1 01:05:42 (none) kern.info dnsmasq[1495]: using local addresses only for domain lan
mowa, że adres tylko dla LAN. Czy tak ma być?
Masz jakiś pomysł?
EDIT:
Zapomniałem.........
Pingi i route takie jak wczoraj. Czyli źle ustawione DNS, czy maże nie spięte ppp0 z br0?
Mam już wszystko poustawiane.
Moduł ppp_synctty już się ładuje. Skrypt odpalał się wcześniej niż były ładowane 2 inne potrzebne moduły: slhc i ppp_generic
Nazwę skryptu zmieniłem na S51neostrada. Chciałem, żeby połączenie z netem zestawiało się przed uruchomieniem S52ez-ipupdate i S60dnsmasq. Powinne chyba już mieć dostęp do netu.
Teraz czekam tylko na test. Muszę poczekać do 10, bo pozbawię netu paru sąsiadów
. Cóż trzeba się dzielić z bliźnimi ![]()
Zaraz po teście zdam relację co się udało osiągnąć
Pozdrawiam Jarek
Skrypty są uruchamiane jako /etc/init.d/S* czy wszystko co ma S na początku i w kolejnosci alfabetycznej (czyli w tym przypadku tak jak liczby lecą)
Moduły - tak samo.
Jak dobrze rozumiem, to moduły (/etc/modules.d) odpalają się przed skryptami (/etc/init.d). Czy tak?
EDIT:
Jeszcze jedno pytanie.
Jeśli wykonują się skrypty np. S40..... i S50......., to S50 czeka na zakończenie S40?
Jeśli tak, to co się stanie gdy S40 zawiśnie (np. zapętli się)?
3. route pokazuje coś takiego:
Kernel IP routing table
Destination Gateway Genmask Flags Metric Ref Use Iface
213.25.2.228 * 255.255.255.255 UH 0 0 0 ppp0
192.168.2.0 * 255.255.255.0 U 0 0 0 br0
.
. tutaj następuje długie oczekiwanie (kilkadziesiąt sekund)
.
default 192.168.2.1 0.0.0.0 UG 0 0 0 br04. nslookup nie odpowiada
A to raczej oznacza, ze coś nie tak z dns'ami jest. jak masz ustawione resolv.conf dnsmasq?
zrobiłeś restart dnsmaq po podniesieniu ppp0?
W resolv.conf są wpisane 2 adresy DNS. Używane są teraz do ustawienia DNS dla LAN.
Po podniesieniu ppp0 nie robiłem restartu dnsmaq. Czy tak?
killall dnsmaq
dnsmasq -l /tmp/dhcp.leases -K -F lan,192.168.2.100,1 # (pokazuje w procesach, że tak jest odpalany) albo może odpalić SXXdnsmasq?
A może lepiej było by odpalić skrypt SXXneostrada przed SXXdnsmasq? Nie trze by było restartować dnsmasq.
Tak wygląda kolejność odpalanych procesów:
PID Uid VmSize Stat Command
1 root 356 S init
2 root SW [keventd]
3 root RWN [ksoftirqd_CPU0]
4 root SW [kswapd]
5 root SW [bdflush]
6 root SW [kupdated]
9 root SW [mtdblockd]
69 root SWN [jffs2_gcd_mtd4]
92 root 388 S /bin/sh /etc/init.d/rcS
93 root 344 S logger -s -p 6 -t
96 root 356 S init
98 root 336 S klogd
122 root 340 S /sbin/syslogd -C 16 -m 0
271 root SW [khubd]
381 root SW [usb-storage-0]
382 root SW [scsi_eh_0]
551 root 324 S wifi up
596 root 412 S /usr/sbin/nas -P /var/run/nas.lan.pid -l br0 -H 34954
603 root 392 S /usr/sbin/dropbear
611 root 380 S httpd -p 80 -h /www -r OpenWrt
787 nobody 404 S dnsmasq -l /tmp/dhcp.leases -K -F lan,192.168.2.100,1
793 root 272 S ntpclient -i 86400 -h pool.ntp.org
797 root 364 S /bin/sh /etc/init.d/S90neostrada start
799 root 52 S /usr/local/modem_run -v 1 -m -f /usr/local/KQD6_3.012
800 root 100 S /usr/local/modem_run -v 1 -m -f /usr/local/KQD6_3.012
801 root 100 S /usr/local/modem_run -v 1 -m -f /usr/local/KQD6_3.012
802 root 588 S /usr/sbin/dropbear
803 root 468 S -ash
806 root 328 R psDlaczego modem_run ma aż 3 procesy?
Tak przy okazji.
W jakiej kolejności odpalają się skrypty z /etc/modules.d i /etc/init.d ?
Pierwsza próba taka sobie.....
1. Nie chce mi załadować ze skryptu modułu
# insmod ppp_synctty
I w konsekwencji nie odpala pppd. Dopiero (i tylko tak) ręczne załadowanie i odpalenie pppd nawiązuje połączenie.
2. ping odpowiada tylko z adresów 80.11.155.143 i 213.25.2.228. No i oczywiście 192.168.2.6 (laptop)
3. route pokazuje coś takiego:
Kernel IP routing table
Destination Gateway Genmask Flags Metric Ref Use Iface
213.25.2.228 * 255.255.255.255 UH 0 0 0 ppp0
192.168.2.0 * 255.255.255.0 U 0 0 0 br0
.
. tutaj następuje długie oczekiwanie (kilkadziesiąt sekund)
.
default 192.168.2.1 0.0.0.0 UG 0 0 0 br0
4. nslookup nie odpowiada
5. Skrypt S90neostrada wygląda następująco
#!/bin/sh
#insmod atm # wsadziłem do: /etc/modules.d/90-pppoa
#insmod n_hdlc # wsadziłem do: /etc/modules.d/90-pppoa
#insmod ppp_synctty # również wsadzałem do 90-pppoa, ale też się nie odpalił
# tu dobrze by było sprawdzić czy jest już zamontowany. Nie wiem jak :(
mount -t usbdevfs usbdevfs /proc/bus/usb
# jak sprawdzić czy modem jest podłšczony do USB. Inaczej wywali się modem_run
/usr/local/modem_run -v 1 -m -f /usr/local/KQD6_3.012
# jak sprawdzić czy modem się zsynchronizował. Dobrze by było żeby pppd odpalać tylka przy zsynchronizowanym modemie.
insmod ppp_synctty # próbowałem też i tak, ale to samo. Nie odpala się.
sleep 1
pppd call neostrada-pppoa6. Zmiana
wan_device=eth0 # na: ppp0
wan_ifname=vlan1 # na: ppp0
nic nie daje. I tak, i tak, efekt jest ten sam.
Co o tym sądzisz?
wan_device=eth0 # tu zmiana na: ppp0
wan_vport=4 # ????
wan_proto=none # ????
wan_dhcp_num= # ????
wan_ifnames=vlan1 # zmiana na: ppp0
wandevs=et0
wan_ifname=vlan1 # ????
wan_hostname=OpenWrt
wan_dhcp_start=
wan_dhcp_lease=lan_gateway=192.168.2.1 # zostaje
lan_ifnames=vlan0 eth2 # czy tu nie powinienem dopisać: ppp0
lan_proto=static # zostaje
lan_ipaddr=192.168.2.3 # zmiana na: 192.168.2.1
Sprawdź proszę, czy dobrze myślę. Tam gdzie są ???? nie jestem pewien.
Witaj Cezary,
Wreszcie mogę się zająć routerkiem.
Mam małe ograniczenia z testami. Mianowicie teraz jestem podpięty do netu przez inny router i kiedy przystępuję do testów muszę się rozłączyć z netem, wszystko przepięć, przetestować..... i jak nie działa wszystko spowrotem poprzepinać. Dlatego chciałem się trochę przygotować do uruchomienia połączenia z netem na WL500gp.
Teraz mam ustawione wszystko następująco:
- router z neo (ten obecny) 192.168.2.1 i dla innych kompów to jest brama
- WL500gp 192.168.2.3 (adr. statyczny) - włączony tylko LAN. WAN i WiFi mam wyłączone
- komp z którego konfiguruję WL500gp 192.168.2.6 (adr. statyczny)
Na czas próby WL500gp ustawię jako 192.168.2.1 (oczywiście wyłączając obecny router) - reszta zostanie.
Mam już napisany skrypt rozruchowy do speedtoucha.
Co mam zrobić, tak krok po kroku, żeby sprawnie ustawić połączenie z netem na WL500gp?
Jakich narzędzi używać do debagowania i co sprawdzać?
Jarek
Chyba nie rozwali, bo gdzieś widziałem właśnie taką konfigurację. Z tym, że skrypt, który odpala automatycznie ppp0 przewiduje tylko obsługę pppoe i trzeba by było go zmodyfikować dla pppoa.
Jasne
bez HOWTO by się nie obeszło!
Jeszcze kilka drobiazgów. Ponieważ chciałem żeby wszystko było w konwencji X-Wrt (może zrobie panel www do PPPoA), potrzebuję kilka wskazówek.
1. W jakim katalogu umieścić pliki dla speedtoucha? Czy /usr/spdtch330 jest dobrym pomysłem?
2. Jak spiąć ppp0 z br0? Czy trzeba coś utawiać w zmiennych (nvram)?
3. Jak przeprowadzić test (co i w jakiej kolejności) poprawnego połączenia z internetem i współpracy (router) z innymi komputerami?
Jarek
No i udało się.......
DZIAŁA!!!!!!
Syslog wygląda następująco:
Jan 1 01:01:07 (none) kern.warn kernel: HDLC line discipline: version $Revision: 3.7 $, maxframe=4096
Jan 1 01:01:07 (none) kern.info kernel: N_HDLC line discipline registered.
Jan 1 01:03:00 (none) kern.warn kernel: usb_control/bulk_msg: timeout
Jan 1 01:03:00 (none) kern.warn kernel: usbdevfs: USBDEVFS_BULK failed dev 2 ep 0x85 len 512 ret -145
Jan 1 01:04:23 (none) kern.notice pppd[865]: pppd 2.4.3 started by root, uid 0
Jan 1 01:04:24 (none) kern.debug pppd[865]: using channel 1
Jan 1 01:04:24 (none) kern.info pppd[865]: Using interface ppp0
Jan 1 01:04:24 (none) kern.notice pppd[865]: Connect: ppp0 <--> /dev/pts/1
Jan 1 01:04:25 (none) kern.debug pppd[865]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0xd6625cb8> <pcomp> <accomp>]
Jan 1 01:04:26 (none) kern.debug pppd[865]: rcvd [LCP ConfReq id=0x2b <auth chap MD5> <magic 0x19d3b591>]
Jan 1 01:04:26 (none) kern.debug pppd[865]: sent [LCP ConfAck id=0x2b <auth chap MD5> <magic 0x19d3b591>]
Jan 1 01:04:28 (none) kern.debug pppd[865]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0xd6625cb8> <pcomp> <accomp>]
Jan 1 01:04:28 (none) kern.debug pppd[865]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0xd6625cb8> <pcomp> <accomp>]
Jan 1 01:04:28 (none) kern.debug pppd[865]: sent [LCP EchoReq id=0x0 magic=0xd6625cb8]
Jan 1 01:04:28 (none) kern.debug pppd[865]: rcvd [CHAP Challenge id=0x3 <ef5484b89d49fc3fc252a2e5ba24d5b6f1e25f4074c416b794>, name = "rze_ru1"]
Jan 1 01:04:28 (none) kern.debug pppd[865]: sent [CHAP Response id=0x3 <294cb5f8e572747b8b4ef6e393656f6b>, name = "XXXXXXX@neostrada.pl"]
Jan 1 01:04:28 (none) kern.debug pppd[865]: rcvd [LCP EchoRep id=0x0 magic=0x19d3b591]
Jan 1 01:04:28 (none) kern.debug pppd[865]: rcvd [CHAP Success id=0x3 ""]
Jan 1 01:04:28 (none) kern.info pppd[865]: CHAP authentication succeeded
Jan 1 01:04:28 (none) kern.notice pppd[865]: CHAP authentication succeeded
Jan 1 01:04:28 (none) kern.debug pppd[865]: sent [CCP ConfReq id=0x1 <mppe -H -M -S -L -D +C>]
Jan 1 01:04:28 (none) kern.debug pppd[865]: sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 0.0.0.0>]
Jan 1 01:04:28 (none) kern.debug pppd[865]: rcvd [LCP ProtRej id=0x2c 80 fd 01 01 00 0a 12 06 00 00 00 01]
Jan 1 01:04:28 (none) kern.debug pppd[865]: rcvd [IPCP ConfRej id=0x1 <compress VJ 0f 01>]
Jan 1 01:04:28 (none) kern.debug pppd[865]: sent [IPCP ConfReq id=0x2 <addr 0.0.0.0>]
Jan 1 01:04:28 (none) kern.debug pppd[865]: rcvd [IPCP ConfNak id=0x2 <addr 83.11.196.207>]
Jan 1 01:04:28 (none) kern.debug pppd[865]: sent [IPCP ConfReq id=0x3 <addr 83.11.196.207>]
Jan 1 01:04:28 (none) kern.debug pppd[865]: rcvd [IPCP ConfAck id=0x3 <addr 83.11.196.207>]
Jan 1 01:04:28 (none) kern.debug pppd[865]: rcvd [IPCP ConfReq id=0x27 <addr 213.25.2.228>]
Jan 1 01:04:28 (none) kern.debug pppd[865]: sent [IPCP ConfAck id=0x27 <addr 213.25.2.228>]
Jan 1 01:04:28 (none) kern.err pppd[865]: not replacing default route to br0 [192.168.2.1]
Jan 1 01:04:28 (none) kern.notice pppd[865]: local IP address 83.11.196.207
Jan 1 01:04:28 (none) kern.notice pppd[865]: remote IP address 213.25.2.228
Jan 1 01:04:28 (none) kern.debug pppd[865]: Script /etc/ppp/ip-up started (pid 880)
Jan 1 01:04:28 (none) kern.debug pppd[865]: Script /etc/ppp/ip-up finished (pid 880), status = 0x1A ifconfig melduje:
br0 Link encap:Ethernet HWaddr 00:17:31:DC:06:2B
inet addr:192.168.2.3 Bcast:192.168.2.255 Mask:255.255.255.0
UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1
RX packets:544 errors:0 dropped:0 overruns:0 frame:0
TX packets:436 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:0
RX bytes:37533 (36.6 KiB) TX bytes:85065 (83.0 KiB)
eth0 Link encap:Ethernet HWaddr 00:17:31:DC:06:2B
UP BROADCAST RUNNING PROMISC MULTICAST MTU:1500 Metric:1
RX packets:544 errors:0 dropped:0 overruns:0 frame:0
TX packets:440 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:1000
RX bytes:49451 (48.2 KiB) TX bytes:93780 (91.5 KiB)
Interrupt:4
eth2 Link encap:Ethernet HWaddr 00:17:31:DC:06:2B
UP BROADCAST RUNNING ALLMULTI MULTICAST MTU:1500 Metric:1
RX packets:0 errors:0 dropped:0 overruns:0 frame:0
TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:1000
RX bytes:0 (0.0 B) TX bytes:0 (0.0 B)
Interrupt:2 Base address:0x2000
lo Link encap:Local Loopback
inet addr:127.0.0.1 Mask:255.0.0.0
UP LOOPBACK RUNNING MTU:16436 Metric:1
RX packets:0 errors:0 dropped:0 overruns:0 frame:0
TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:0
RX bytes:0 (0.0 B) TX bytes:0 (0.0 B)
ppp0 Link encap:Point-to-Point Protocol
inet addr:83.11.196.207 P-t-P:213.25.2.228 Mask:255.255.255.255
UP POINTOPOINT RUNNING NOARP MULTICAST MTU:1500 Metric:1
RX packets:8 errors:0 dropped:0 overruns:0 frame:0
TX packets:5 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:3
RX bytes:264 (264.0 B) TX bytes:56 (56.0 B)
vlan0 Link encap:Ethernet HWaddr 00:17:31:DC:06:2B
UP BROADCAST RUNNING ALLMULTI MULTICAST MTU:1500 Metric:1
RX packets:544 errors:0 dropped:0 overruns:0 frame:0
TX packets:436 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:0
RX bytes:39709 (38.7 KiB) TX bytes:86809 (84.7 KiB)Bardzo Ci dziękuję za pomoc. Jestem zobowiącany......
![]()
Zostaje tylko jeszcze podłączenie ppp0 do br0......... i tu będę cię prosił o pomoc. Chciałbym to wszystko skonfigurować i wsadzić do sktyptów.
Na razie BARDZO DZIĘKUJĘ i pozdrawiam Jarek
Nooooo........ teraz wywaliło tasiemce. Przeglądnołem Syslog i PPPoAlog. Chyba więcej znaczących informacji jest w tym pierwszym. Jest tego dużo.
Jan 15 23:36:37 (none) kern.warn kernel: HDLC line discipline: version $Revision: 3.7 $, maxframe=4096
Jan 15 23:36:37 (none) kern.info kernel: N_HDLC line discipline registered.
Jan 15 23:38:40 (none) kern.info kernel: hub.c: new USB device 01:03.0-2, assigned address 2
Jan 15 23:38:40 (none) kern.warn kernel: usb.c: USB device 2 (vend/prod 0x6b9/0x4061) is not claimed by any active driver.
Jan 15 23:38:47 (none) kern.warn kernel: usb_control/bulk_msg: timeout
Jan 15 23:38:47 (none) kern.warn kernel: usbdevfs: USBDEVFS_BULK failed dev 2 ep 0x85 len 512 ret -145
Jan 15 23:39:49 (none) kern.notice pppd[866]: pppd 2.4.3 started by root, uid 0
Jan 15 23:39:49 (none) kern.debug pppd[866]: using channel 1
Jan 15 23:39:49 (none) kern.info pppd[866]: Using interface ppp0
Jan 15 23:39:49 (none) kern.notice pppd[866]: Connect: ppp0 <--> /dev/pts/1
Jan 15 23:39:49 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 0a c0 21 09 ec ...
Jan 15 23:39:49 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:39:49 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 32 00 21 45 00 ...
Jan 15 23:39:49 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:39:49 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 32 00 21 45 00 ...
Jan 15 23:39:49 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:39:49 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 32 00 21 45 00 ...
Jan 15 23:39:49 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:39:49 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 32 00 21 45 00 ...
Jan 15 23:39:49 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:39:49 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 0a c0 21 09 ed ...
Jan 15 23:39:49 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:39:50 (none) kern.debug pppd[866]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x607f9cb3> <pcomp> <accomp>]
Jan 15 23:39:53 (none) kern.debug pppd[866]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x607f9cb3> <pcomp> <accomp>]
Jan 15 23:39:54 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 32 00 21 45 00 ...
Jan 15 23:39:54 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:39:56 (none) kern.debug pppd[866]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x607f9cb3> <pcomp> <accomp>]
Jan 15 23:39:59 (none) kern.debug pppd[866]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x607f9cb3> <pcomp> <accomp>]
Jan 15 23:40:00 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 35 00 21 45 00 ...
Jan 15 23:40:00 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:40:02 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 35 00 21 45 00 ...
Jan 15 23:40:02 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:40:02 (none) kern.debug pppd[866]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x607f9cb3> <pcomp> <accomp>]
Jan 15 23:40:03 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 35 00 21 45 00 ...
Jan 15 23:40:03 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:40:03 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 35 00 21 45 00 ...
Jan 15 23:40:03 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:40:04 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 35 00 21 45 00 ...
Jan 15 23:40:04 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:40:04 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 35 00 21 45 00 ...
Jan 15 23:40:04 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:40:05 (none) kern.debug pppd[866]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x607f9cb3> <pcomp> <accomp>]
Jan 15 23:40:05 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 35 00 21 45 00 ...
Jan 15 23:40:05 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:40:07 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 35 00 21 45 00 ...
Jan 15 23:40:07 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:40:07 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 35 00 21 45 00 ...
Jan 15 23:40:07 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:40:08 (none) kern.debug pppd[866]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x607f9cb3> <pcomp> <accomp>]
Jan 15 23:40:09 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 35 00 21 45 00 ...
Jan 15 23:40:09 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:40:09 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 35 00 21 45 00 ...
Jan 15 23:40:09 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:40:11 (none) kern.debug pppd[866]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x607f9cb3> <pcomp> <accomp>]
Jan 15 23:40:11 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 35 00 21 45 00 ...
Jan 15 23:40:11 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:40:11 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 35 00 21 45 00 ...
Jan 15 23:40:11 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:40:12 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 35 00 21 45 00 ...
Jan 15 23:40:12 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:40:13 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 42 00 21 45 00 ...
Jan 15 23:40:13 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:40:13 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 35 00 21 45 00 ...
Jan 15 23:40:13 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:40:14 (none) kern.debug pppd[866]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x607f9cb3> <pcomp> <accomp>]
Jan 15 23:40:14 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 35 00 21 45 00 ...
Jan 15 23:40:14 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:40:15 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 35 00 21 45 00 ...
Jan 15 23:40:15 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:40:16 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 42 00 21 45 00 ...
Jan 15 23:40:16 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:40:16 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 0a c0 21 09 ee ...
Jan 15 23:40:16 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:40:16 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 35 00 21 45 00 ...
Jan 15 23:40:16 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:40:17 (none) kern.debug pppd[866]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x607f9cb3> <pcomp> <accomp>]
Jan 15 23:40:17 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 35 00 21 45 00 ...
Jan 15 23:40:17 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:40:18 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 35 00 21 45 00 ...
Jan 15 23:40:18 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:40:20 (none) kern.debug pppd[866]: rcvd [proto=0xaaaa] 03 00 80 c2 00 07 00 00 00 e0 95 50 d1 72 00 90 1a a0 43 56 88 64 11 00 16 d5 00 35 00 21 45 00 ...
Jan 15 23:40:20 (none) kern.debug pppd[866]: Discarded non-LCP packet when LCP not open
Jan 15 23:40:20 (none) kern.warn pppd[866]: LCP: timeout sending Config-Requests
Jan 15 23:40:20 (none) kern.notice pppd[866]: Connection terminated.
Jan 15 23:40:20 (none) kern.notice pppd[866]: Modem hangup
Jan 15 23:40:23 (none) kern.debug pppd[866]: using channel 2
Jan 15 23:40:23 (none) kern.info pppd[866]: Using interface ppp0
Jan 15 23:40:23 (none) kern.notice pppd[866]: Connect: ppp0 <--> /dev/pts/2
Jan 15 23:40:23 (none) kern.debug pppd[866]: Script /usr/sptch/bin/cezary/pppoa3 -v 2 -e 1 -c -m 1 -vpi 0 -vci 35 finished (pid 893), status = 0xff
Jan 15 23:40:23 (none) kern.notice pppd[866]: Modem hangup
Jan 15 23:40:23 (none) kern.notice pppd[866]: Connection terminated.
.
.
. itdMowa o LCP że coś nie tak. Tylko co?
Rzuć okiem na to.
Teraz jest następny błąd
New log started for PPPoA3 version 1.3.1
---------------------------------------------
Info >1024< pppoa3 version 1.3.1 started by root (uid 0)
Info >1024< Vpi set to 0
Info >1024< Vci set to 35
Info >1024< Verbosity level set to 2
Info >1024< Schedule policy set to 0
Info >1024< Modem ID set to 1
Info >1024< Using Sync HDLC mode
Info >1024< Using PPPoA mode
Info >1024< Control pipe disabled
Info >1024< Previous instance cleaning enabled
Info >1024< Log filename set to /var/log/pppoa3-modem1.log
[Mon Jan 15 22:49:37 2007] Info >1024< Control thread ready
[Mon Jan 15 22:49:37 2007] Debug >1024< Pty descriptors : fdin=0, fdout=1
[Mon Jan 15 22:49:37 2007] Debug >1024< N_HDLC line set up
[Mon Jan 15 22:49:37 2007] Info >1024< Modem found!
[Mon Jan 15 22:49:37 2007] Error >1024< pusb_claim_interface 1 failedCoś nie tak z USB, ale nie kumam o co chodzi i gdzie szukać........
Chyba znalazłem. W /var/log zapakował plik pppoa3-modem1.log tam są informacje. Ale dlaczego tam? Nie ważne. Znalazł sie winowajca. Poniżej zawartość pppoa3-modem1.log:
New log started for PPPoA3 version 1.3.1
---------------------------------------------
Info >1024< pppoa3 version 1.3.1 started by root (uid 0)
Info >1024< Vpi set to 0
Info >1024< Vci set to 35
Info >1024< Verbosity level set to 2
Info >1024< Schedule policy set to 0
Info >1024< Modem ID set to 1
Info >1024< Using Sync HDLC mode
Info >1024< Using PPPoA mode
Info >1024< Control pipe disabled
Info >1024< Previous instance cleaning enabled
Info >1024< Log filename set to /var/log/pppoa3-modem1.log
[Sat Jan 1 02:38:45 2000] Info >1024< Control thread ready
[Sat Jan 1 02:38:45 2000] Debug >1024< Pty descriptors : fdin=0, fdout=1
[Sat Jan 1 02:38:45 2000] Error >1024< Error loading N_HDLCNie załadowany był moduł n_hdlc.o
Zaraz skoryguję......
odpaliłem teraz
# pppd call neostrada-pppoa
bez modemu. Zachowuje się tak samo jak z modemem. Dziwne. Nie informuje o żadnych błędach. Może nie rozpoznaje modemu? pppoa3 ma przełącznik -m i w helpie pisze że to jest ID modemu. U mnie jest ustawione na 1. Może ID modemu jest inne. Jak można sprawdzić ID modemu?
/etc/ppp/options w tej chili u mnie nie istnieje. Był ale dużo wcześniej zmieniłem go na options.old
Nie próbowałem.
# pppoa3 -h
Chyba działa, bo z -h wyświetlił dostępne opcje.
Faktycznie z -v 2 powinien dawać dużo informacji. Tak przynajmniej mówi help.
W manualu do pppd na http://www.digipedia.pl/man/pppd.8.html wyczytałem, że:
Komunikaty są przesyłane do demona sysloga przy użyciu zabudowy LOG_DAEMON. (Może to być przesłonięte przez rekompilowanie pppd z makrem LOG_PPP przedefiniowanym na pożądaną zabudowę.) Aby zobaczyć komunikaty o błędach i debuggowe, musisz wyedytować plik /etc/syslog.conf tak, aby przekierowywał komunikaty na określone urządzenie wyjściowe lub plik.
ale nie mam pliku /etc/syslog.conf
Syslog do pliku zadziałał. Niestety przy próbie uruchomienia pppd nic więcej oprócz tego co było wcześniej się nie znajduje. Dokładnie te same wpisy. Zero dodatkowej informacji. ![]()
Wydaje mi się, że w efekcie tego powinien sie stworzyć plik /tmp/msg.txt z zawartym logiem.
Niestety nic się nie tworzy. Może coś z parametrami nie tak?
Po zmianie wywołania na: pppoa3 -e 1 -v 2 -m 1 -c -vpi 0 -vci 35
w logu nic się nie zmieniło. Jest tak samo jak przed zmianą. Może on zapisuje swój log do jakiegoś innego pliku?
A jak powinny być zapisane moj_login@neostrada.pl i XyXy_haslo w chap-secrets, pap-secrets i meostrada-pppoa? W "" czy bez?
Spotkałem się z różnymi zapisami i nie jestem pewien czy mam dobrze. Ja NIE używam "".
Witam po dłuższej przerwie..... Trochę mało czasowy jestem ostatnio, ale udało się zrobić kilka rzeczy:
Robiłem zgodnie z opisem na http://pl.docs.pld-linux.org/siec_dsl_neo_thompson.html
Zainstalowałem moduły: atm, atm_synctty.
Podmontowałem system plików: mount -t usbdevfs usbdevfs /proc/bus/usb
I teraz są 2 drogi w zależności od kernela. Obie przećwiczyłem i jest podobny efekt. Opiszę tą dla kernela 2.4.22+.
Zainstalowałem moduł: speedtch (od Sebastiana)
Załadowałem firmware do modemu. Modem przyjął soft i zsynchronizował się.
Info modem_run version 1.3.1 started by root uid 0
Info Found SpeedTouch USB modem
Info Modem revision: 0200
Info Best offset 36 with probability 100%
Info Best offset 983 with probability 100%
Info Firmware info (CRC:0xd80bf9f7, Size:991, Checked: Yes, Alcatel/Thomson Boot block (old))
Info Best offset 1027 with probability 100%
Info Best offset 762642 with probability 100%
Info Firmware info (CRC:0x78039fed, Size:762650, Checked: Yes, 3.0.6 - MacOSX - Win32)
Info BLOCK1 : 991 bytes uploaded : OK
Info BLOCK2 : 511 bytes downloaded : OK
Info BLOCK3 : 762650 bytes uploaded : OK
Info BLOCK4 : 511 bytes downloaded : OKInfo Found kernel mode driver
Info Modem reference : 3566067A......
Info [monitoring report] ADSL link went up
Info ADSL synchronization has been obtained
Info ADSL line is up (2496 kbit/s down | 320 kbit/s up)Następnie uruchomiłem moduł: pppoatm
I odpaliłem ppp: pppd call meostrada-pppoa
# cat /etc/ppp/peers/neostrada-pppoa
debug
kdebug 1
noauth
usepeerdns
noipdefault
defaultroute
pty "/usr/sptch/bin/cezary/pppoa3 -e 1 -v 1 -m 1 -c -vpi 0 -vci 35"
sync
user moj_login@neostrada.pl
noaccomp
nopcomp
noccp
holdoff 4
persist
maxfail 25Oczywiście chap-secrets i pap-secrets są na swoim miejscu w /etc/ppp
# Secrets for authentication using CHAP
# client server secret IP addresses
moj_login@neostrada.pl * XyXy_haslo *Tu jedno małe pytanie. Czy moj_login@neostrada.pl i XyXy_haslo mają być zamknięte w "" ? Ja mam bez "".
No i teraz czas na efekty. Log wygląda następująco:
Jan 1 00:00:10 (none) kern.info kernel: usb.c: registered new driver usbdevfs
Jan 1 00:00:10 (none) kern.info kernel: usb.c: registered new driver hub
Jan 1 00:00:11 (none) kern.info kernel: usb.c: registered new driver acm
Jan 1 00:00:11 (none) kern.info kernel: acm.c: v0.21:USB Abstract Control Model driver for USB modems and ISDN adapters
Jan 1 00:00:11 (none) kern.info kernel: SCSI subsystem driver Revision: 1.00
Jan 1 00:00:11 (none) kern.info kernel: Initializing USB Mass Storage driver...
Jan 1 00:00:11 (none) kern.info kernel: usb.c: registered new driver usb-storage
Jan 1 00:00:11 (none) kern.info kernel: USB Mass Storage support registered.
Jan 1 00:00:11 (none) kern.info kernel: uhci.c: USB Universal Host Controller Interface driver v1.1
Jan 1 00:00:11 (none) kern.warn kernel: PCI: Enabling device 01:03.0 (0000 -> 0001)
Jan 1 00:00:11 (none) kern.info kernel: uhci.c: USB UHCI at I/O 0x100, IRQ 2
Jan 1 00:00:11 (none) kern.info kernel: usb.c: new USB bus registered, assigned bus number 1
Jan 1 00:00:11 (none) kern.info kernel: hub.c: USB hub found
Jan 1 00:00:11 (none) kern.info kernel: hub.c: 2 ports detected
Jan 1 00:00:11 (none) kern.warn kernel: PCI: Enabling device 01:03.1 (0000 -> 0001)
Jan 1 00:00:11 (none) kern.info kernel: uhci.c: USB UHCI at I/O 0x120, IRQ 2
Jan 1 00:00:11 (none) kern.info kernel: usb.c: new USB bus registered, assigned bus number 2
Jan 1 00:00:11 (none) kern.info kernel: hub.c: USB hub found
Jan 1 00:00:11 (none) kern.info kernel: hub.c: 2 ports detected
Jan 1 00:00:11 (none) kern.warn kernel: PCI: Enabling device 01:03.2 (0000 -> 0002)
Jan 1 00:00:11 (none) kern.info kernel: ehci_hcd 01:03.2: PCI device 1106:3104
Jan 1 00:00:11 (none) kern.info kernel: ehci_hcd 01:03.2: irq 2, pci mem c01a5000
Jan 1 00:00:11 (none) kern.info kernel: usb.c: new USB bus registered, assigned bus number 3
Jan 1 00:00:11 (none) kern.info kernel: EHCI: Enabling VIA 6212 workarounds
Jan 1 00:00:11 (none) kern.info kernel: ehci_hcd 01:03.2: USB 2.0 enabled, EHCI 1.00, driver 2003-Dec-29/2.4
Jan 1 00:00:12 (none) kern.info kernel: hub.c: USB hub found
Jan 1 00:00:12 (none) kern.info kernel: hub.c: 4 ports detected
Jan 1 00:00:12 (none) kern.info kernel: device eth0 entered promiscuous mode
Jan 1 00:00:12 (none) kern.err kernel: hub.c: connect-debounce failed, port 1 disabled
Jan 1 00:00:13 (none) kern.info kernel: hub.c: new USB device 01:03.2-1, assigned address 2
Jan 1 00:00:13 (none) kern.info kernel: scsi0 : SCSI emulation for USB Mass Storage devices
Jan 1 00:00:13 (none) kern.warn kernel: Vendor: KINGMAX Model: USB2.0 Flashdisk Rev: 2.00
Jan 1 00:00:13 (none) kern.warn kernel: Type: Direct-Access ANSI SCSI revision: 02
Jan 1 00:00:13 (none) kern.warn kernel: Attached scsi removable disk sda at scsi0, channel 0, id 0, lun 0
Jan 1 00:00:13 (none) kern.warn kernel: SCSI device sda: 512000 512-byte hdwr sectors (262 MB)
Jan 1 00:00:13 (none) kern.warn kernel: sda: Write Protect is off
Jan 1 00:00:13 (none) kern.info kernel: Partition check:
Jan 1 00:00:13 (none) kern.info kernel: p1
Jan 1 00:00:13 (none) kern.debug kernel: WARNING: USB Mass Storage data integrity not assured
Jan 1 00:00:13 (none) kern.debug kernel: USB Mass Storage device found at 2
Jan 1 00:00:15 (none) kern.info kernel: b44: eth0: Link is up at 100 Mbps, full duplex.
Jan 1 00:00:15 (none) kern.info kernel: b44: eth0: Flow control is off for TX and off for RX.
Jan 1 00:00:15 (none) kern.debug kernel: vlan0: add 01:00:5e:00:00:01 mcast address to master interface
Jan 1 00:00:15 (none) kern.info kernel: vlan0: dev_set_promiscuity(master, 1)
Jan 1 00:00:15 (none) kern.info kernel: vlan0: dev_set_allmulti(master, 1)
Jan 1 00:00:15 (none) kern.info kernel: device eth2 entered promiscuous mode
Jan 1 00:00:15 (none) kern.info kernel: eth2: attempt to add interface with same source address.
Jan 1 00:00:15 (none) kern.info kernel: br0: port 2(eth2) entering learning state
Jan 1 00:00:15 (none) kern.info kernel: br0: port 1(vlan0) entering learning state
Jan 1 00:00:15 (none) kern.info kernel: br0: port 2(eth2) entering forwarding state
Jan 1 00:00:15 (none) kern.info kernel: br0: topology change detected, propagating
Jan 1 00:00:15 (none) kern.info kernel: br0: port 1(vlan0) entering forwarding state
Jan 1 00:00:15 (none) kern.info kernel: br0: topology change detected, propagating
Jan 1 00:00:18 (none) kern.info dropbear[583]: Running in background
Jan 1 00:00:19 (none) kern.info kernel: IPP2P v0.8.1_rc1 loading
Jan 1 00:00:20 (none) user.info : ez-ipupdate is disabled
Jan 1 00:00:21 (none) kern.info dnsmasq[767]: started, version 2.35 cachesize 150
Jan 1 00:00:21 (none) kern.info dnsmasq[767]: compile time options: IPv6 GNU-getopt ISC-leasefile no-DBus no-I18N
Jan 1 00:00:21 (none) kern.info dnsmasq[767]: DHCP, IP range 192.168.2.100 -- 192.168.2.249, lease time 12h
Jan 1 00:00:21 (none) kern.info dnsmasq[767]: using local addresses only for domain lan
Jan 1 00:00:21 (none) kern.info dnsmasq[767]: reading /tmp/resolv.conf.auto
Jan 1 00:00:21 (none) kern.info dnsmasq[767]: using nameserver 217.98.63.164#53
Jan 1 00:00:21 (none) kern.info dnsmasq[767]: using nameserver 194.204.152.34#53
Jan 1 00:00:21 (none) kern.info dnsmasq[767]: using local addresses only for domain lan
Jan 1 00:00:21 (none) kern.info dnsmasq[767]: read /etc/hosts - 1 addresses
Jan 1 01:00:21 (none) kern.info dnsmasq[767]: read /etc/ethers - 0 addresses
Jan 1 01:00:22 (none) user.info : crond is disabled[: 0: unknown operand
Jan 1 01:00:22 (none) kern.info kernel: CSLIP: code copyright 1989 Regents of the University of California
Jan 1 01:00:22 (none) kern.info kernel: PPP generic driver version 2.4.2
Jan 1 01:00:22 (none) kern.info kernel: MPPE/MPPC encryption/compression module registered
Jan 1 01:00:23 (none) user.info : To enable miniupnpd set option enable = '1' in /etc/config/upnpd.
Jan 1 01:00:55 (none) kern.info kernel: usb.c: registered new driver speedtch
Jan 1 01:01:22 (none) user.info : pool.ntp.org: Unknown host
Jan 1 01:01:32 (none) kern.info kernel: hub.c: new USB device 01:03.0-2, assigned address 2
# tu następuje wczytanie firmware
Jan 1 01:02:13 (none) kern.warn kernel: usb_control/bulk_msg: timeout
Jan 1 01:02:13 (none) kern.warn kernel: usbdevfs: USBDEVFS_BULK failed dev 2 ep 0x85 len 512 ret -145
Jan 1 01:03:53 (none) kern.notice pppd[866]: pppd 2.4.3 started by root, uid 0
Jan 1 01:03:53 (none) kern.debug pppd[866]: using channel 1
Jan 1 01:03:53 (none) kern.info pppd[866]: Using interface ppp0
Jan 1 01:03:53 (none) kern.notice pppd[866]: Connect: ppp0 <--> /dev/pts/1
Jan 1 01:03:53 (none) kern.debug pppd[866]: Script /usr/sptch/bin/cezary/pppoa3 -e 1 -v 1 -m 1 -c -vpi 0 -vci 35 finished (pid 867), status = 0xff
Jan 1 01:03:53 (none) kern.notice pppd[866]: Modem hangup
Jan 1 01:03:53 (none) kern.notice pppd[866]: Connection terminated.
Jan 1 01:03:57 (none) kern.debug pppd[866]: using channel 2
Jan 1 01:03:57 (none) kern.info pppd[866]: Using interface ppp0
Jan 1 01:03:57 (none) kern.notice pppd[866]: Connect: ppp0 <--> /dev/pts/2
Jan 1 01:03:57 (none) kern.debug pppd[866]: Script /usr/sptch/bin/cezary/pppoa3 -e 1 -v 1 -m 1 -c -vpi 0 -vci 35 finished (pid 887), status = 0xff
Jan 1 01:03:57 (none) kern.notice pppd[866]: Modem hangup
Jan 1 01:03:57 (none) kern.notice pppd[866]: Connection terminated.
.
. itd, itd
.
Jan 1 01:05:32 (none) kern.debug pppd[866]: using channel 25
Jan 1 01:05:32 (none) kern.info pppd[866]: Using interface ppp0
Jan 1 01:05:32 (none) kern.notice pppd[866]: Connect: ppp0 <--> /dev/pts/25
Jan 1 01:05:32 (none) kern.debug pppd[866]: Script /usr/sptch/bin/cezary/pppoa3 -e 1 -v 1 -m 1 -c -vpi 0 -vci 35 finished (pid 1357), status = 0xff
Jan 1 01:05:32 (none) kern.notice pppd[866]: Modem hangup
Jan 1 01:05:32 (none) kern.notice pppd[866]: Connection terminated.
Jan 1 01:05:32 (none) kern.info pppd[866]: Exit..... i nic więcej ![]()
Podsumowując:
1. Instalowanie i rozpoznawanie USB - OK
2. Wczytywanie firmware do modemu i synchronizacja - OK
3. Odpalenie ppp - KICHA
Co jest nie tak? Kombinowałem na wszystkie sposoby. Już brakuje mi pomysłów. Jak można dokładniej dowiedzieć się co się dzieje po odpaleniu pppd?
Pozdrawiam Jarek
eko.one.pl → Posty przez jkdobro
Forum oparte o PunBB, wspierane przez Informer Technologies, Inc