[worky]

В наличии Абиллс, несколько НАСов с ПППОе с кернел мод. НАС пишет такое:

Linux nas05 2.6.32-47-generic-pae #109-Ubuntu SMP Tue May 7 02:19:47 UTC 2013 i686 GNU/Linux

 

И вот на одном из НАСов с 10Ж сетевкой от Интел и дровами ixgbe наблюдаются частые обрывы с USER REQUEST в биллинге. Причем сессии короткие и не факт, что приводят к разрыву соединения. В логах пппд вижу такое:

Jun 10 08:37:17 nas05 pppd[8321]: rcvd [CHAP Response id=0xcd <4da5c03b38f92848135e01c64bfa0d50>, name = "vpnsobaka"]
Jun 10 09:07:43 nas05 pppoe-server[28481]: Session 1563 created for client 90:f6:52:57:ad:01 (10.67.21.27) on vlan90 using Service-Name ''
Jun 10 09:07:43 nas05 pppd[28481]: Plugin /etc/ppp/plugins/rp-pppoe.so loaded.
Jun 10 09:07:43 nas05 pppd[28481]: RP-PPPoE plugin version 3.10 compiled against pppd 2.4.5
Jun 10 09:07:43 nas05 pppd[28481]: Plugin /etc/ppp/plugins/rp-pppoe.so loaded.
Jun 10 09:07:43 nas05 pppd[28481]: RP-PPPoE plugin version 3.10 compiled against pppd 2.4.5
Jun 10 09:07:43 nas05 pppd[28481]: Plugin /usr/lib/pppd/2.4.5/radius.so loaded.
Jun 10 09:07:43 nas05 pppd[28481]: RADIUS plugin initialized.
Jun 10 09:07:43 nas05 pppd[28481]: Plugin /usr/lib/pppd/2.4.5/radattr.so loaded.
Jun 10 09:07:43 nas05 pppd[28481]: RADATTR plugin initialized.
Jun 10 09:07:43 nas05 pppd[28481]: pppd 2.4.5 started by root, uid 0
Jun 10 09:07:43 nas05 pppd[28481]: Connected to 90:f6:52:57:ad:01 via interface vlan90
Jun 10 09:07:43 nas05 pppd[28481]: using channel 178434
Jun 10 09:07:44 nas05 pppd[28481]: Using interface ppp1054
Jun 10 09:07:44 nas05 pppd[28481]: Connect: ppp1054 <--> vlan90
Jun 10 09:07:44 nas05 pppd[28481]: Couldn't increase MTU to 1500
Jun 10 09:07:44 nas05 pppd[28481]: Couldn't increase MRU to 1500
Jun 10 09:07:44 nas05 pppd[28481]: sent [LCP ConfReq id=0x1 <mru 1472> <auth chap MD5> <magic 0xb7d71317>]
Jun 10 09:07:44 nas05 pppd[28481]: rcvd [LCP ConfReq id=0x1 <mru 1480> <magic 0xd947ddea>]
Jun 10 09:07:44 nas05 pppd[28481]: sent [LCP ConfAck id=0x1 <mru 1480> <magic 0xd947ddea>]
Jun 10 09:07:44 nas05 pppd[28481]: rcvd [LCP ConfAck id=0x1 <mru 1472> <auth chap MD5> <magic 0xb7d71317>]
Jun 10 09:07:44 nas05 pppd[28481]: sent [LCP EchoReq id=0x0 magic=0xb7d71317]
Jun 10 09:07:44 nas05 pppd[28481]: sent [CHAP Challenge id=0x55 <2219a18e40df3b8466640ec0144d0e6243251d>, name = "nas05"]
Jun 10 09:07:44 nas05 pppd[28481]: rcvd [LCP EchoRep id=0x0 magic=0xd947ddea]
Jun 10 09:07:44 nas05 pppd[28481]: rcvd [CHAP Response id=0x55 <bd39bdf54165984a07ae252eba21e8c2>, name = "vpn51012"]
Jun 10 09:07:44 nas05 pppd[28481]: RADATTR plugin wrote 7 line(s) to file /var/run/radattr.ppp1054.
Jun 10 09:07:44 nas05 pppd[28481]: sent [CHAP Success id=0x55 ""]
Jun 10 09:07:44 nas05 pppd[28481]: peer from calling number 90:F6:52:57:AD:01 authorized
Jun 10 09:07:44 nas05 pppd[28481]: sent [iPCP ConfReq id=0x1 <addr 10.200.46.1>]
Jun 10 09:07:44 nas05 pppd[28481]: rcvd [iPCP ConfReq id=0x1 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
Jun 10 09:07:44 nas05 pppd[28481]: sent [iPCP ConfNak id=0x1 <addr 10.200.203.149> <ms-dns1 x.x.x.2> <ms-dns2 x.x.x.8>]
Jun 10 09:07:44 nas05 pppd[28481]: rcvd [iPCP ConfAck id=0x1 <addr 10.200.46.1>]
Jun 10 09:07:44 nas05 pppd[28481]: rcvd [iPCP ConfReq id=0x2 <addr 10.200.203.149> <ms-dns1 x.x.x.2> <ms-dns2 x.x.x.8>]
Jun 10 09:07:44 nas05 pppd[28481]: sent [iPCP ConfAck id=0x2 <addr 10.200.203.149> <ms-dns1 x.x.x.2> <ms-dns2 x.x.x.8>]
Jun 10 09:07:44 nas05 pppd[28481]: Cannot determine ethernet address for proxy ARP
Jun 10 09:07:44 nas05 pppd[28481]: local  IP address 10.200.46.1
Jun 10 09:07:44 nas05 pppd[28481]: remote IP address 10.200.203.149
Jun 10 09:07:44 nas05 pppd[28481]: Script /etc/ppp/ip-up started (pid 28513)
Jun 10 09:07:44 nas05 pppd[28481]: Script /etc/ppp/ip-up finished (pid 28513), status = 0x0
Jun 10 09:15:08 nas05 pppd[28481]: rcvd [LCP ConfAck id=0x10 <mru 1472> <auth chap MD5> <magic 0x77a76e4b>] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 5e f5 79 fb 38 04 05 f1 3c 1e 6e ...
Jun 10 09:26:16 nas05 pppd[28481]: rcvd [LCP TermAck id=0x2] 46 61 69 6c 65 64 20 74 6f 20 61 75 74 68 65 6e 74 69 63 61 74 65 20 6f 75 72 73 65 6c 76 65 73 ...
Jun 10 09:26:16 nas05 pppd[28481]: Connect time 18.6 minutes.
Jun 10 09:26:16 nas05 pppd[28481]: Sent 122000620 bytes, received 20413463 bytes.
Jun 10 09:26:16 nas05 pppd[28481]: Script /etc/ppp/ip-down started (pid 7016)
Jun 10 09:26:16 nas05 pppd[28481]: Couldn't increase MTU to 1500
Jun 10 09:26:16 nas05 pppd[28481]: Couldn't increase MRU to 1500
Jun 10 09:26:16 nas05 pppd[28481]: sent [LCP ConfReq id=0x2 <mru 1472> <auth chap MD5> <magic 0xe5108161>]
Jun 10 09:26:16 nas05 pppd[28481]: Script /etc/ppp/ip-down finished (pid 7016), status = 0x0
Jun 10 09:26:16 nas05 pppd[28481]: rcvd [LCP ConfReq id=0x2 <mru 1480> <magic 0xdca7d4ca>]
Jun 10 09:26:16 nas05 pppd[28481]: sent [LCP ConfAck id=0x2 <mru 1480> <magic 0xdca7d4ca>]
Jun 10 09:26:16 nas05 pppd[28481]: rcvd [LCP ConfAck id=0x2 <mru 1472> <auth chap MD5> <magic 0xe5108161>]
Jun 10 09:26:16 nas05 pppd[28481]: sent [CHAP Challenge id=0x2b <aa77e429499185cf266c439661d26455dd>, name = "nas05"]
Jun 10 09:26:16 nas05 pppd[28481]: rcvd [CHAP Response id=0x2b <0211646eb54fdc051994174fd6312129>, name = "vpn51012"]
Jun 10 09:26:16 nas05 pppd[28481]: sent [CHAP Success id=0x2b ""]
Jun 10 09:26:16 nas05 pppd[28481]: peer from calling number 90:F6:52:57:AD:01 authorized
Jun 10 09:26:16 nas05 pppd[28481]: sent [iPCP ConfReq id=0x2 <addr 10.200.46.1>]
Jun 10 09:26:16 nas05 pppd[28481]: rcvd [iPCP ConfReq id=0x3 <addr 10.200.203.149> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
Jun 10 09:26:16 nas05 pppd[28481]: sent [iPCP ConfNak id=0x3 <ms-dns1 x.x.x.2> <ms-dns2 x.x.x.8>]
Jun 10 09:26:16 nas05 pppd[28481]: rcvd [iPCP ConfAck id=0x2 <addr 10.200.46.1>]
Jun 10 09:26:16 nas05 pppd[28481]: rcvd [iPCP ConfReq id=0x4 <addr 10.200.203.149> <ms-dns1 x.x.x.2> <ms-dns2 x.x.x.8>]
Jun 10 09:26:16 nas05 pppd[28481]: sent [iPCP ConfAck id=0x4 <addr 10.200.203.149> <ms-dns1 x.x.x.2> <ms-dns2 x.x.x.8>]
Jun 10 09:26:16 nas05 pppd[28481]: Cannot determine ethernet address for proxy ARP
Jun 10 09:26:16 nas05 pppd[28481]: local  IP address 10.200.46.1
Jun 10 09:26:16 nas05 pppd[28481]: remote IP address 10.200.203.149
Jun 10 09:26:16 nas05 pppd[28481]: Script /etc/ppp/ip-up started (pid 7031)
Jun 10 09:26:16 nas05 pppd[28481]: Script /etc/ppp/ip-up finished (pid 7031), status = 0x0
Jun 10 11:21:42 nas05 pppd[28481]: rcvd [CHAP Response id=0xf5 <e6d9354b7b0465ef0d09e3f57d3009be>, name = "vpnshvecov"] 00 00 00 00 00 00 00 00 00 06 3f 05 0a d1 09 b8 b8 d1 09 df e0 00 00 6f 74 6f 63 6f 6c 00 00 00 ...
Jun 10 12:20:10 nas05 pppd[28481]: rcvd [proto=0xd932] 00 00 68 1b 00 00 68 1b 00 00 00 00 00 00 91 10 5f 1d d5 f0 fa 22 c1 a2 00 44 53 26 60 00 00 00 ...
Jun 10 12:20:10 nas05 pppd[28481]: Unsupported protocol 0xd932 received
Jun 10 12:20:10 nas05 pppd[28481]: sent [LCP ProtRej id=0x3 d9 32 00 00 68 1b 00 00 68 1b 00 00 00 00 00 00 91 10 5f 1d d5 f0 fa 22 c1 a2 00 44 53 26 60 00 ...]
Jun 10 12:20:10 nas05 pppd[28481]: rcvd [proto=0xd932] 00 00 68 1b 00 00 68 1b 00 00 00 00 00 00 91 10 5f 1d d5 f0 fa 22 c1 a2 00 44 53 26 60 00 00 00 ...
Jun 10 12:20:10 nas05 pppd[28481]: Unsupported protocol 0xd932 received
Jun 10 12:20:10 nas05 pppd[28481]: sent [LCP ProtRej id=0x4 d9 32 00 00 68 1b 00 00 68 1b 00 00 00 00 00 00 91 10 5f 1d d5 f0 fa 22 c1 a2 00 44 53 26 60 00 ...]
Jun 10 12:27:43 nas05 pppd[28481]: rcvd [CHAP Response id=0x48 <fb2e5341526b0bf8dc68f956397c1d89>, name = "vpnshago"] 10 80 79 a7 e0 80 4d 6a 20 00 00 00 00 b7 99 20 01 00 00 9d 38 6a b8 3c 44 36 6b 3c 1e 6e ef 01 ...
Jun 10 12:28:38 nas05 pppd[28481]: rcvd [proto=0xfa2e] 52 c1 30 f1 26 c2 ef e8 e8 e1 06 06 00 00 00 02 07 06 00 00 00 01 01 10 76 70 6e 6b 6f 6e 64 72 ...
Jun 10 12:28:38 nas05 pppd[28481]: Unsupported protocol 0xfa2e received
Jun 10 12:28:38 nas05 pppd[28481]: sent [LCP ProtRej id=0x5 fa 2e 52 c1 30 f1 26 c2 ef e8 e8 e1 06 06 00 00 00 02 07 06 00 00 00 01 01 10 76 70 6e 6b 6f 6e ...]
Jun 10 12:28:52 nas05 pppd[28481]: rcvd [LCP ConfAck id=0x1 <mru 1472> <auth chap MD5> <magic 0xe152844d>] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01 01 95 3c 3c 47 32 84 3c 1e 6e ...
Jun 10 12:29:55 nas05 pppd[28481]: rcvd [proto=0xf811] 14 ff 5f 6a c4 ae c3 e1 91 10 45 32 50 ad 00 26 21 a9 41 02 dc cc b1 16 38 44 7f ff ff ff 00 00 ...
Jun 10 12:29:55 nas05 pppd[28481]: Unsupported protocol 0xf811 received
Jun 10 12:29:55 nas05 pppd[28481]: sent [LCP ProtRej id=0x6 f8 11 14 ff 5f 6a c4 ae c3 e1 91 10 45 32 50 ad 00 26 21 a9 41 02 dc cc b1 16 38 44 7f ff ff ff ...]
Jun 10 12:30:41 nas05 pppd[28481]: rcvd [LCP TermAck id=0x6] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ...
Jun 10 12:30:41 nas05 pppd[28481]: Connect time 184.5 minutes.
Jun 10 12:30:41 nas05 pppd[28481]: Sent 2146000933 bytes, received 144716243 bytes.
Jun 10 12:30:41 nas05 pppd[28481]: Script /etc/ppp/ip-down started (pid 5081)
Jun 10 12:30:41 nas05 pppd[28481]: Couldn't increase MTU to 1500
Jun 10 12:30:41 nas05 pppd[28481]: Couldn't increase MRU to 1500
Jun 10 12:30:41 nas05 pppd[28481]: sent [LCP ConfReq id=0x7 <mru 1472> <auth chap MD5> <magic 0xa178f209>]
Jun 10 12:30:41 nas05 pppd[28481]: Script /etc/ppp/ip-down finished (pid 5081), status = 0x0
Jun 10 12:30:41 nas05 pppd[28481]: rcvd [LCP ConfReq id=0x3 <mru 1480> <magic 0xa3b6e33>]
Jun 10 12:30:41 nas05 pppd[28481]: sent [LCP ConfAck id=0x3 <mru 1480> <magic 0xa3b6e33>]
Jun 10 12:30:41 nas05 pppd[28481]: rcvd [LCP ConfAck id=0x7 <mru 1472> <auth chap MD5> <magic 0xa178f209>]
Jun 10 12:30:41 nas05 pppd[28481]: sent [CHAP Challenge id=0x67 <e50e57d527cab22a85a390839da5b599304c7217396f9b>, name = "nas05"]
Jun 10 12:30:41 nas05 pppd[28481]: rcvd [CHAP Response id=0x67 <1ea56be57f407764ea6a81b9488220b6>, name = "vpn51012"]
Jun 10 12:30:41 nas05 pppd[28481]: sent [CHAP Success id=0x67 ""]
Jun 10 12:30:41 nas05 pppd[28481]: peer from calling number 90:F6:52:57:AD:01 authorized
Jun 10 12:30:41 nas05 pppd[28481]: sent [iPCP ConfReq id=0x3 <addr 10.200.46.1>]
Jun 10 12:30:41 nas05 pppd[28481]: rcvd [iPCP ConfReq id=0x5 <addr 10.200.203.149> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
Jun 10 12:30:41 nas05 pppd[28481]: sent [iPCP ConfNak id=0x5 <ms-dns1 x.x.x.2> <ms-dns2 x.x.x.8>]
Jun 10 12:30:41 nas05 pppd[28481]: rcvd [iPCP ConfAck id=0x3 <addr 10.200.46.1>]
Jun 10 12:30:41 nas05 pppd[28481]: rcvd [iPCP ConfReq id=0x6 <addr 10.200.203.149> <ms-dns1 x.x.x.2> <ms-dns2 x.x.x.8>]
Jun 10 12:30:41 nas05 pppd[28481]: sent [iPCP ConfAck id=0x6 <addr 10.200.203.149> <ms-dns1 x.x.x.2> <ms-dns2 x.x.x.8>]
Jun 10 12:30:41 nas05 pppd[28481]: Cannot determine ethernet address for proxy ARP
Jun 10 12:30:41 nas05 pppd[28481]: local  IP address 10.200.46.1
Jun 10 12:30:41 nas05 pppd[28481]: remote IP address 10.200.203.149
Jun 10 12:30:41 nas05 pppd[28481]: Script /etc/ppp/ip-up started (pid 5089)
Jun 10 12:30:41 nas05 pppd[28481]: Script /etc/ppp/ip-up finished (pid 5089), status = 0x0
Jun 10 12:31:58 nas05 pppd[28481]: rcvd [proto=0xc84a] 14 22 86 2f 0c 06 50 0a 7b 99 2c 10 35 31 42 35 38 32 31 41 33 38 38 34 30 30 01 08 76 70 6e 76 ...
Jun 10 12:31:58 nas05 pppd[28481]: Unsupported protocol 0xc84a received
Jun 10 12:31:58 nas05 pppd[28481]: sent [LCP ProtRej id=0x8 c8 4a 14 22 86 2f 0c 06 50 0a 7b 99 2c 10 35 31 42 35 38 32 31 41 33 38 38 34 30 30 01 08 76 70 ...]
Jun 10 12:32:01 nas05 pppd[28481]: rcvd [LCP ConfReq id=0x1 <mru 1480> <magic 0x6e4b1d34>] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 5e f5 79 fd 0c 65 ...
Jun 10 12:32:01 nas05 pppd[28481]: Connect time 1.4 minutes.
Jun 10 12:32:01 nas05 pppd[28481]: Sent 12704989 bytes, received 14348256 bytes.
Jun 10 12:32:01 nas05 pppd[28481]: Script /etc/ppp/ip-down started (pid 5605)
Jun 10 12:32:01 nas05 pppd[28481]: Couldn't increase MTU to 1500
Jun 10 12:32:01 nas05 pppd[28481]: Couldn't increase MRU to 1500
Jun 10 12:32:01 nas05 pppd[28481]: sent [LCP ConfReq id=0x9 <mru 1472> <auth chap MD5> <magic 0x47320252>]
Jun 10 12:32:01 nas05 pppd[28481]: sent [LCP ConfAck id=0x1 <mru 1480> <magic 0x6e4b1d34>]
Jun 10 12:32:01 nas05 pppd[28481]: Script /etc/ppp/ip-down finished (pid 5605), status = 0x0
Jun 10 12:32:01 nas05 pppd[28481]: rcvd [LCP ConfReq id=0x4 <mru 1480> <magic 0xd63729de>]
Jun 10 12:32:01 nas05 pppd[28481]: sent [LCP ConfAck id=0x4 <mru 1480> <magic 0xd63729de>]
Jun 10 12:32:01 nas05 pppd[28481]: rcvd [LCP ConfAck id=0x9 <mru 1472> <auth chap MD5> <magic 0x47320252>]
Jun 10 12:32:01 nas05 pppd[28481]: sent [CHAP Challenge id=0x8 <018f3013b92a8f8a79831b9f6cf99518b9>, name = "nas05"]
Jun 10 12:32:01 nas05 pppd[28481]: rcvd [CHAP Response id=0x8 <10426ba18805508654abd06eb1a5fdc4>, name = "vpn51012"]
Jun 10 12:32:01 nas05 pppd[28481]: sent [CHAP Success id=0x8 ""]
Jun 10 12:32:01 nas05 pppd[28481]: peer from calling number 90:F6:52:57:AD:01 authorized
Jun 10 12:32:01 nas05 pppd[28481]: sent [iPCP ConfReq id=0x4 <addr 10.200.46.1>]
Jun 10 12:32:01 nas05 pppd[28481]: rcvd [iPCP ConfReq id=0x7 <addr 10.200.203.149> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
Jun 10 12:32:01 nas05 pppd[28481]: sent [iPCP ConfNak id=0x7 <ms-dns1 x.x.x.2> <ms-dns2 x.x.x.8>]
Jun 10 12:32:01 nas05 pppd[28481]: rcvd [iPCP ConfAck id=0x4 <addr 10.200.46.1>]
Jun 10 12:32:01 nas05 pppd[28481]: rcvd [iPCP ConfReq id=0x8 <addr 10.200.203.149> <ms-dns1 x.x.x.2> <ms-dns2 x.x.x.8>]
Jun 10 12:32:01 nas05 pppd[28481]: sent [iPCP ConfAck id=0x8 <addr 10.200.203.149> <ms-dns1 x.x.x.2> <ms-dns2 x.x.x.8>]
Jun 10 12:32:01 nas05 pppd[28481]: Cannot determine ethernet address for proxy ARP
Jun 10 12:32:01 nas05 pppd[28481]: local  IP address 10.200.46.1
Jun 10 12:32:01 nas05 pppd[28481]: remote IP address 10.200.203.149
Jun 10 12:32:01 nas05 pppd[28481]: Script /etc/ppp/ip-up started (pid 5616)
Jun 10 12:32:01 nas05 pppd[28481]: Script /etc/ppp/ip-up finished (pid 5616), status = 0x0
Jun 10 12:32:40 nas05 pppd[28481]: rcvd [proto=0xe2cf] 65 01 4d 5f a8 53 7a e3 64 df 2c 10 35 31 42 35 37 35 35 46 33 30 35 39 30 31 01 0b 76 70 6e 62 ...
Jun 10 12:32:40 nas05 pppd[28481]: Unsupported protocol 0xe2cf received
Jun 10 12:32:40 nas05 pppd[28481]: sent [LCP ProtRej id=0xa e2 cf 65 01 4d 5f a8 53 7a e3 64 df 2c 10 35 31 42 35 37 35 35 46 33 30 35 39 30 31 01 0b 76 70 ...]
Jun 10 12:33:22 nas05 pppd[28481]: rcvd [CHAP Response id=0xee <3f492e214ba68a963ae46edc25169896>, name = "vpniverson"] 00 00 00 00 00 00 00 00 00 42 43 05 0a 98 4d d9 62 98 4d f5 5a 20 01 00 00 9d 38 95 3c 00 4f 0f ...
Jun 10 12:39:41 nas05 pppd[28481]: rcvd [proto=0xda79] 16 cb 14 86 cb d9 e3 e8 c9 db 2c 10 35 31 42 35 38 36 34 31 32 46 32 37 30 31 01 0d 76 70 6e 79 ...
Jun 10 12:39:41 nas05 pppd[28481]: Unsupported protocol 0xda79 received
Jun 10 12:39:41 nas05 pppd[28481]: sent [LCP ProtRej id=0xb da 79 16 cb 14 86 cb d9 e3 e8 c9 db 2c 10 35 31 42 35 38 36 34 31 32 46 32 37 30 31 01 0d 76 70 ...]
Jun 10 12:50:30 nas05 pppd[28481]: rcvd [LCP ConfAck id=0x5 <mru 1472> <auth chap MD5> <magic 0xe1e6fd4c>] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 11 fe a6 fc cc 2b 5a 8f 6d 5a 18 65 e9 ...
Jun 10 12:50:43 nas05 pppd[28481]: rcvd [proto=0xf811] b9 97 d5 a7 c2 69 c3 e1 91 10 43 41 8a 07 00 26 64 8a 41 02 bd 23 fb 54 e7 a2 7f ff ff ff 00 00 ...
Jun 10 12:50:43 nas05 pppd[28481]: Unsupported protocol 0xf811 received
Jun 10 12:50:43 nas05 pppd[28481]: sent [LCP ProtRej id=0xc f8 11 b9 97 d5 a7 c2 69 c3 e1 91 10 43 41 8a 07 00 26 64 8a 41 02 bd 23 fb 54 e7 a2 7f ff ff ff ...]
Jun 10 12:51:42 nas05 pppd[28481]: rcvd [LCP ConfReq id=0x6 <mru 1400> <magic 0xca750862>] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ff ff 00 00 f0 00 48 30 ...
Jun 10 12:51:42 nas05 pppd[28481]: Connect time 19.7 minutes.
Jun 10 12:51:42 nas05 pppd[28481]: Sent 436517738 bytes, received 80521238 bytes.
Jun 10 12:51:42 nas05 pppd[28481]: Script /etc/ppp/ip-down started (pid 15315)
Jun 10 12:51:42 nas05 pppd[28481]: Couldn't increase MTU to 1500
Jun 10 12:51:42 nas05 pppd[28481]: Couldn't increase MRU to 1500
Jun 10 12:51:42 nas05 pppd[28481]: sent [LCP ConfReq id=0xd <mru 1472> <auth chap MD5> <magic 0x8c4694c0>]
Jun 10 12:51:42 nas05 pppd[28481]: sent [LCP ConfAck id=0x6 <mru 1400> <magic 0xca750862>]
Jun 10 12:51:42 nas05 pppd[28481]: Script /etc/ppp/ip-down finished (pid 15315), status = 0x0
Jun 10 12:51:42 nas05 pppd[28481]: rcvd [LCP ConfReq id=0x5 <mru 1480> <magic 0x1e060bde>]
Jun 10 12:51:42 nas05 pppd[28481]: sent [LCP ConfAck id=0x5 <mru 1480> <magic 0x1e060bde>]
Jun 10 12:51:42 nas05 pppd[28481]: rcvd [LCP ConfAck id=0xd <mru 1472> <auth chap MD5> <magic 0x8c4694c0>]
Jun 10 12:51:42 nas05 pppd[28481]: sent [CHAP Challenge id=0xe5 <4bc3a3deb63af7638d1d52ef4a37559c>, name = "nas05"]
Jun 10 12:51:42 nas05 pppd[28481]: rcvd [CHAP Response id=0xe5 <1dadcd85e2e6115296a852c45614c3ad>, name = "vpn51012"]
Jun 10 12:51:43 nas05 pppd[28481]: sent [CHAP Success id=0xe5 ""]
Jun 10 12:51:43 nas05 pppd[28481]: peer from calling number 90:F6:52:57:AD:01 authorized
Jun 10 12:51:43 nas05 pppd[28481]: sent [iPCP ConfReq id=0x5 <addr 10.200.46.1>]
Jun 10 12:51:43 nas05 pppd[28481]: rcvd [iPCP ConfReq id=0x9 <addr 10.200.203.149> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
Jun 10 12:51:43 nas05 pppd[28481]: sent [iPCP ConfNak id=0x9 <ms-dns1 x.x.x.2> <ms-dns2 x.x.x.8>]
Jun 10 12:51:43 nas05 pppd[28481]: rcvd [iPCP ConfAck id=0x5 <addr 10.200.46.1>]
Jun 10 12:51:43 nas05 pppd[28481]: rcvd [iPCP ConfReq id=0xa <addr 10.200.203.149> <ms-dns1 x.x.x.2> <ms-dns2 x.x.x.8>]
Jun 10 12:51:43 nas05 pppd[28481]: sent [iPCP ConfAck id=0xa <addr 10.200.203.149> <ms-dns1 x.x.x.2> <ms-dns2 x.x.x.8>]
Jun 10 12:51:43 nas05 pppd[28481]: Cannot determine ethernet address for proxy ARP
Jun 10 12:51:43 nas05 pppd[28481]: local  IP address 10.200.46.1
Jun 10 12:51:43 nas05 pppd[28481]: remote IP address 10.200.203.149
Jun 10 12:51:43 nas05 pppd[28481]: Script /etc/ppp/ip-up started (pid 15322)
Jun 10 12:51:43 nas05 pppd[28481]: Script /etc/ppp/ip-up finished (pid 15322), status = 0x0
Jun 10 12:54:03 nas05 pppd[28481]: rcvd [proto=0xff53] 83 06 c5 74 78 78 a8 67 c3 85 2c 10 35 31 42 35 36 34 31 41 30 44 34 44 30 30 01 0b 76 70 6e 6b ...
Jun 10 12:54:03 nas05 pppd[28481]: Unsupported protocol 0xff53 received
Jun 10 12:54:03 nas05 pppd[28481]: sent [LCP ProtRej id=0xe ff 53 83 06 c5 74 78 78 a8 67 c3 85 2c 10 35 31 42 35 36 34 31 41 30 44 34 44 30 30 01 0b 76 70 ...]
Jun 10 12:55:41 nas05 pppd[28481]: rcvd [proto=0xf811] c9 39 5f 6a d1 82 c3 e1 91 10 1a e1 1a e2 00 1c 96 63 41 00 c6 98 18 40 a9 f8 00 00 00 00 00 00 ...
Jun 10 12:55:41 nas05 pppd[28481]: Unsupported protocol 0xf811 received
Jun 10 12:55:41 nas05 pppd[28481]: sent [LCP ProtRej id=0xf f8 11 c9 39 5f 6a d1 82 c3 e1 91 10 1a e1 1a e2 00 1c 96 63 41 00 c6 98 18 40 a9 f8 00 00 00 00 ...]
Jun 10 12:55:45 nas05 pppd[28481]: rcvd [CHAP Response id=0xac <38943462510badf4da90df66e94a2b31>, name = "vpnandred21"] 00 00 00 00 00 00 00 00 01 01 08 0a 14 d5 65 32 2d 23 12 e7 20 01 00 00 5e f5 79 fd 00 30 1f ce ...
Jun 10 12:55:59 nas05 pppd[28481]: rcvd [LCP ConfReq id=0x3 <mru 1480> <magic 0x1370fae0>] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 0f fe 00 00 ea 83 24 38 ...
Jun 10 12:55:59 nas05 pppd[28481]: Connect time 4.3 minutes.
Jun 10 12:55:59 nas05 pppd[28481]: Sent 71450686 bytes, received 65824530 bytes.
Jun 10 12:55:59 nas05 pppd[28481]: Script /etc/ppp/ip-down started (pid 17380)
Jun 10 12:55:59 nas05 pppd[28481]: Couldn't increase MTU to 1500
Jun 10 12:55:59 nas05 pppd[28481]: Couldn't increase MRU to 1500
Jun 10 12:55:59 nas05 pppd[28481]: sent [LCP ConfReq id=0x10 <mru 1472> <auth chap MD5> <magic 0xffc2b84c>]
Jun 10 12:55:59 nas05 pppd[28481]: sent [LCP ConfAck id=0x3 <mru 1480> <magic 0x1370fae0>]
Jun 10 12:55:59 nas05 pppd[28481]: Script /etc/ppp/ip-down finished (pid 17380), status = 0x0
Jun 10 12:55:59 nas05 pppd[28481]: rcvd [LCP ConfReq id=0x6 <mru 1480> <magic 0x2ccb1dd8>]
Jun 10 12:55:59 nas05 pppd[28481]: sent [LCP ConfAck id=0x6 <mru 1480> <magic 0x2ccb1dd8>]
Jun 10 12:55:59 nas05 pppd[28481]: rcvd [LCP ConfAck id=0x10 <mru 1472> <auth chap MD5> <magic 0xffc2b84c>]
Jun 10 12:55:59 nas05 pppd[28481]: sent [CHAP Challenge id=0xe2 <6a21969cb18465543262077bff84bd6614fb>, name = "nas05"]
Jun 10 12:55:59 nas05 pppd[28481]: rcvd [CHAP Response id=0xe2 <f4267f6010a2b521e1259e7c63fbdc90>, name = "vpn51012"]
Jun 10 12:55:59 nas05 pppd[28481]: sent [CHAP Success id=0xe2 ""]
Jun 10 12:55:59 nas05 pppd[28481]: peer from calling number 90:F6:52:57:AD:01 authorized
Jun 10 12:55:59 nas05 pppd[28481]: sent [iPCP ConfReq id=0x6 <addr 10.200.46.1>]
Jun 10 12:55:59 nas05 pppd[28481]: rcvd [iPCP ConfReq id=0xb <addr 10.200.203.149> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
Jun 10 12:55:59 nas05 pppd[28481]: sent [iPCP ConfNak id=0xb <ms-dns1 x.x.x.2> <ms-dns2 x.x.x.8>]
Jun 10 12:55:59 nas05 pppd[28481]: rcvd [iPCP ConfAck id=0x6 <addr 10.200.46.1>]
Jun 10 12:55:59 nas05 pppd[28481]: rcvd [iPCP ConfReq id=0xc <addr 10.200.203.149> <ms-dns1 x.x.x.2> <ms-dns2 x.x.x.8>]
Jun 10 12:55:59 nas05 pppd[28481]: sent [iPCP ConfAck id=0xc <addr 10.200.203.149> <ms-dns1 x.x.x.2> <ms-dns2 x.x.x.8>]
Jun 10 12:55:59 nas05 pppd[28481]: Cannot determine ethernet address for proxy ARP
Jun 10 12:55:59 nas05 pppd[28481]: local  IP address 10.200.46.1
Jun 10 12:55:59 nas05 pppd[28481]: remote IP address 10.200.203.149
Jun 10 12:55:59 nas05 pppd[28481]: Script /etc/ppp/ip-up started (pid 17388)
Jun 10 12:55:59 nas05 pppd[28481]: Script /etc/ppp/ip-up finished (pid 17388), status = 0x0
Jun 10 12:56:27 nas05 pppd[28481]: rcvd [proto=0xd617] 00 00 66 00 00 00 66 00 00 00 00 00 00 00 93 27 2e b6 56 25 fc 28 00 50 a9 4f fb b5 5e 6b 66 90 ...
Jun 10 12:56:27 nas05 pppd[28481]: Unsupported protocol 0xd617 received
Jun 10 12:56:27 nas05 pppd[28481]: sent [LCP ProtRej id=0x11 d6 17 00 00 66 00 00 00 66 00 00 00 00 00 00 00 93 27 2e b6 56 25 fc 28 00 50 a9 4f fb b5 5e 6b ...]
Jun 10 12:56:30 nas05 pppd[28481]: rcvd [proto=0xf811] f1 3b 6d 7e ff 38 c3 e1 91 10 f2 86 e7 7f 00 3c 05 87 60 00 00 00 00 00 3b 15 20 01 00 00 9d 38 ...
Jun 10 12:56:30 nas05 pppd[28481]: Unsupported protocol 0xf811 received
Jun 10 12:56:30 nas05 pppd[28481]: sent [LCP ProtRej id=0x12 f8 11 f1 3b 6d 7e ff 38 c3 e1 91 10 f2 86 e7 7f 00 3c 05 87 60 00 00 00 00 00 3b 15 20 01 00 00 ...]
Jun 10 12:56:40 nas05 pppd[28481]: rcvd [LCP TermReq id=0x76 "Failed to authenticate ourselves to peer"] 0c 8f 6d 78 00 04 7f 00 00 00 55 00 50 86 b6 bc 08 21 e8 d6 7a 52 b2 50 07 6e 59 4e 19 3f bd 94 ...
Jun 10 12:56:40 nas05 pppd[28481]: LCP terminated by peer (Failed to authenticate ourselves to peer)
Jun 10 12:56:40 nas05 pppd[28481]: Connect time 0.7 minutes.
Jun 10 12:56:40 nas05 pppd[28481]: Sent 4648023 bytes, received 31723540 bytes.
Jun 10 12:56:40 nas05 pppd[28481]: Script /etc/ppp/ip-down started (pid 17820)
Jun 10 12:56:40 nas05 pppd[28481]: Couldn't increase MTU to 1500
Jun 10 12:56:40 nas05 pppd[28481]: Couldn't increase MRU to 1500
Jun 10 12:56:40 nas05 pppd[28481]: sent [LCP TermAck id=0x76]
Jun 10 12:56:40 nas05 pppd[28481]: rcvd [LCP TermAck id=0x2] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 d9 d5 ...
Jun 10 12:56:40 nas05 pppd[28481]: Connection terminated.
Jun 10 12:56:40 nas05 pppd[28481]: Waiting for 1 child processes...
Jun 10 12:56:40 nas05 pppd[28481]:   script /etc/ppp/ip-down, pid 17820
Jun 10 12:56:40 nas05 pppd[28481]: Script /etc/ppp/ip-down finished (pid 17820), status = 0x1
Jun 10 12:56:40 nas05 pppd[28481]: RADATTR plugin removed file /var/run/radattr.ppp1054.
Jun 10 12:56:40 nas05 pppd[28481]: Exit.

То есть несколько раз происходит попытка переконфигурации соединения, а потом обрыв. ПРичем обрыв как бы идет со стороны клиента, но запрос на перекофигурацию идет со стороны сервера, если смотреть со стороны клиента, а со стороны сервера - от клиента. Другие НАСы абсолютно так же настроены (если не считать другие сетевки, бондинг) и таких проблем не наблюдается.

 

В чем может быть причина? ПОчему происходит попытка переконфигурации соединения? Зачем?

[worky]

походу эти фантомные фреймы гнались на пппд из дров или сетевки.

вернулись на старую сетевку - все ок стало.

дрова с сайта интела все пробовали, пару версий ядер перебрали - ничего не помогло.

попробуем другую сетевку

[worky]

другой сетевки пока нет, но прочитали ридми от интела, перекомпили дрова без LRO, отключили все "ускорители" через ethtool и все равно на данный момент проблема есть.

[worky]

выглядит так:

Jul  2 18:00:15 nas05 pppd[24604]: Script /etc/ppp/ip-down started (pid 15525)
Jul  2 18:00:15 nas05 pppd[24604]: sent [LCP ConfReq id=0x12 <mru 1472> <auth chap MD5> <magic 0x34046913>]
Jul  2 18:00:15 nas05 pppd[24604]: sent [LCP ConfAck id=0x1 <mru 1480> <magic 0x1d110bc2>]
Jul  2 18:00:15 nas05 pppd[24604]: Script /etc/ppp/ip-down finished (pid 15525), status = 0x0
Jul  2 18:00:15 nas05 pppd[24604]: rcvd [LCP ConfReq id=0xc <mru 1480> <magic 0xdd9ec734>]
Jul  2 18:00:15 nas05 pppd[24604]: sent [LCP ConfAck id=0xc <mru 1480> <magic 0xdd9ec734>]
Jul  2 18:00:15 nas05 pppd[24604]: rcvd [LCP ConfAck id=0x12 <mru 1472> <auth chap MD5> <magic 0x34046913>]
Jul  2 18:00:15 nas05 pppd[24604]: sent [CHAP Challenge id=0xfc <4bd6ace387dc2d7d687cfaae9f18f161>, name = "nas05"]
Jul  2 18:00:15 nas05 pppd[24604]: rcvd [CHAP Response id=0xfc <6801e87b9c704f90960547edc1576498>, name = "vpn"]
Jul  2 18:00:15 nas05 pppd[24604]: sent [CHAP Success id=0xfc ""]
Jul  2 18:00:15 nas05 pppd[24604]: sent [iPCP ConfReq id=0xc <addr 10.200.46.1>]
Jul  2 18:00:15 nas05 pppd[24604]: rcvd [iPCP ConfReq id=0x17 <addr х.х.х.х> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
Jul  2 18:00:15 nas05 pppd[24604]: sent [iPCP ConfNak id=0x17 <ms-dns1 х.х.х.х> <ms-dns2 х.х.х.х>]
Jul  2 18:00:15 nas05 pppd[24604]: rcvd [iPCP ConfAck id=0xc <addr 10.200.46.1>]
Jul  2 18:00:15 nas05 pppd[24604]: rcvd [iPCP ConfReq id=0x18 <addr х.х.х.х> <ms-dns1 х.х.х.х> <ms-dns2 х.х.х.х>]
Jul  2 18:00:15 nas05 pppd[24604]: sent [iPCP ConfAck id=0x18 <addr х.х.х.х> <ms-dns1 х.х.х.х> <ms-dns2 х.х.х.х>]
Jul  2 18:00:15 nas05 pppd[24604]: Script /etc/ppp/ip-up started (pid 15534)
Jul  2 18:00:15 nas05 pppd[24604]: Script /etc/ppp/ip-up finished (pid 15534), status = 0x0
[b]Jul  2 18:01:07 nas05 pppd[24604]: rcvd [LCP TermReq id=0x2 "Failed to authenticate ourselves to peer"] fb 49 e6 79 00 0d d3 95 01 01 00 00 00 00 44 77 8b 6b 28 c7 00 1c 0f 68 21 00 fe 2c 14 3c ed 7b ...[/b]
Jul  2 18:01:08 nas05 pppd[24604]: Script /etc/ppp/ip-down started (pid 16050)
Jul  2 18:01:08 nas05 pppd[24604]: sent [LCP TermAck id=0x2]
Jul  2 18:01:08 nas05 pppd[24604]: Script /etc/ppp/ip-down finished (pid 16050), status = 0x0
Jul  2 18:01:08 nas05 pppd[24604]: rcvd [LCP ConfReq id=0xd <mru 1480> <magic 0x812e7611>]
Jul  2 18:01:11 nas05 pppd[24604]: RADATTR plugin removed file /var/run/radattr.ppp425.

вот выделенная строка - это вообще из другой сессии. почему она приписывается этой сессии - загадка..........

 

root@nas05:/var/log# ethtool -k eth6

Offload parameters for eth6:

rx-checksumming: on

tx-checksumming: on

scatter-gather: off

tcp-segmentation-offload: off

udp-fragmentation-offload: off

generic-segmentation-offload: off

generic-receive-offload: off

large-receive-offload: off

[worky]

обновление ядра на 3.0 помогло: мусор из логов пппоед пропал, обрывы тоже.