On 11/28/2013 2:33 AM, Johan Hovold wrote:
On Wed, Nov 27, 2013 at 11:51:29AM -0500, Alan Stern wrote:
On Wed, 27 Nov 2013, Johan Hovold wrote:
I am attaching the output that I am getting in the syslog. Note that I have
two usb modems connected to that router and that's how I am able to debug
it.
2-1 is an external USB2.0 hub, 2-1.2 is the ZTE modem, and 2-1.1 is a Huawei
CDMA modem, which is working fine. The problem happens both with and without
a hub.
The transmissions are failing with -ENOENT (-2), and the "clear tt
buffer" are related to the hub.
Can you get a log from when not using the hub?
Could you try reproducing this on v3.12?
Alan, what do you think of this?
The -ENOENT means that the transfers were cancelled. Perhaps because
of a timeout or perhaps for some other reason.
The "clear tt buffer" lines are merely side effects of the cancelled
transfers. Running the test without the external hub ought to
eliminate them. It would simplify the test results, so it's worth
doing.
Also, it might be worthwhile to collect a usbmon trace during the test.
The output might be meaningful to somebody who understands how this
driver is supposed to work.
Dmitry, first you could try reproducing this with (dynamic) debugging
enabled, for example, echo the following to
/sys/kernel/debug/dynamic_debug/control:
module usbserial +p
func usb_serial_generic_read_bulk_callback -p
func usb_serial_generic_submit_read_urb -p
func usb_serial_debug_data -p
module zte_ev +p
The -ENOENT are likely from an ordinary close of the device. Wasn't
thinking clearly yesterday. :)
Thanks,
Johan
Hi Johan,
I was able to merge 3.12.1 with Broadcom code over the weekend, so I am
running the latest kernel now, but the problem is still there.
I did the test as you suggested without any diagnostic patches and with
dynamic debugging enabled and I am attaching the output.
I don't really see anything suspicious in the traces, which makes me
believe that one of those hardcoded commands in zte_ev is enabling some
sort of watchdog in the modem that drops the ppp connection after a
while. Communication with the modem doesn't really break because pppd is
able to reestablish the connection after it drops. If you think you need
a usbmon trace please let me know.
Regards, Dmitry
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.640000] tty ttyUSB0: serial_open
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.640000] zte_ev ttyUSB0: result = 0
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.640000] zte_ev ttyUSB0: result = 7
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.640000] zte_ev ttyUSB0: zte_ev_usb_serial_open - length = 7, data = 60 4d 2f 00 00 00 08
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.640000] zte_ev ttyUSB0: result = 7
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.640000] zte_ev ttyUSB0: zte_ev_usb_serial_open - length = 7, data = 80 25 00 00 00 00 08
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.640000] zte_ev ttyUSB0: result = 0
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.640000] zte_ev ttyUSB0: result = 7
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.640000] zte_ev ttyUSB0: zte_ev_usb_serial_open - length = 7, data = 60 4d 2f 00 00 00 08
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.640000] zte_ev ttyUSB0: result = 7
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.640000] zte_ev ttyUSB0: zte_ev_usb_serial_open - length = 7, data = 80 25 00 00 00 00 08
Dec 2 19:37:54 iqvpn local2.info chat[3712]: abort on (BUSY)
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.670000] tty ttyUSB0: serial_ioctl - cmd 0x540d
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.670000] tty ttyUSB0: serial_ioctl - cmd 0x540d
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.670000] tty ttyUSB0: serial_ioctl - cmd 0x540d
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.670000] tty ttyUSB0: serial_ioctl - cmd 0x540d
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.670000] tty ttyUSB0: serial_ioctl - cmd 0x540d
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.670000] tty ttyUSB0: serial_ioctl - cmd 0x540e
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.670000] tty ttyUSB0: serial_set_termios
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.670000] tty ttyUSB0: serial_ioctl - cmd 0x540d
Dec 2 19:37:54 iqvpn local2.info chat[3712]: abort on (NO CARRIER)
Dec 2 19:37:54 iqvpn local2.info chat[3712]: abort on (VOICE)
Dec 2 19:37:54 iqvpn local2.info chat[3712]: abort on (NO DIALTONE)
Dec 2 19:37:54 iqvpn local2.info chat[3712]: abort on (NO DIAL TONE)
Dec 2 19:37:54 iqvpn local2.info chat[3712]: abort on (NO ANSWER)
Dec 2 19:37:54 iqvpn local2.info chat[3712]: abort on (DELAYED)
Dec 2 19:37:54 iqvpn local2.info chat[3712]: abort on (+CME ERROR)
Dec 2 19:37:54 iqvpn local2.info chat[3712]: abort on (ERROR)
Dec 2 19:37:54 iqvpn local2.info chat[3712]: timeout set to 1 seconds
Dec 2 19:37:54 iqvpn local2.info chat[3712]: send (AT^M)
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.710000] tty ttyUSB0: serial_write - 1 byte(s)
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.730000] tty ttyUSB0: serial_write - 1 byte(s)
Dec 2 19:37:54 iqvpn local2.info chat[3712]: expect (OK)
Dec 2 19:37:54 iqvpn local2.info chat[3712]: AT^M^M
Dec 2 19:37:54 iqvpn local2.info chat[3712]: OK
Dec 2 19:37:54 iqvpn local2.info chat[3712]: -- got it
Dec 2 19:37:54 iqvpn local2.info chat[3712]: send (ATD#777^M)
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.750000] tty ttyUSB0: serial_write - 1 byte(s)
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.780000] tty ttyUSB0: serial_write - 1 byte(s)
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.800000] tty ttyUSB0: serial_write - 1 byte(s)
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.820000] tty ttyUSB0: serial_write - 1 byte(s)
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.840000] tty ttyUSB0: serial_write - 1 byte(s)
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.860000] tty ttyUSB0: serial_write - 1 byte(s)
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.880000] tty ttyUSB0: serial_write - 1 byte(s)
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.900000] tty ttyUSB0: serial_write - 1 byte(s)
Dec 2 19:37:54 iqvpn local2.info chat[3712]: timeout set to 30 seconds
Dec 2 19:37:54 iqvpn local2.info chat[3712]: expect (CONNECT)
Dec 2 19:37:54 iqvpn local2.info chat[3712]: ^M
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.920000] tty ttyUSB0: serial_write - 1 byte(s)
Dec 2 19:37:54 iqvpn local2.info chat[3712]: ATD#777^M^M
Dec 2 19:37:54 iqvpn local2.info chat[3712]: CONNECT
Dec 2 19:37:54 iqvpn local2.info chat[3712]: -- got it
Dec 2 19:37:54 iqvpn local2.info chat[3712]: send (^M)
Dec 2 19:37:54 iqvpn daemon.info pppd[2156]: Serial connection established.
Dec 2 19:37:54 iqvpn daemon.info pppd[2156]: Using interface ppp1
Dec 2 19:37:54 iqvpn daemon.notice pppd[2156]: Connect: ppp1 <--> /dev/gsmmodem-wan1
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.950000] tty ttyUSB0: serial_write - 1 byte(s)
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.950000] tty ttyUSB0: serial_ioctl - cmd 0x540e
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.950000] tty ttyUSB0: serial_set_termios
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.950000] tty ttyUSB0: serial_ioctl - cmd 0x540d
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.950000] tty ttyUSB0: serial_tiocmset
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.950000] tty ttyUSB0: serial_ioctl - cmd 0x540d
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.950000] tty ttyUSB0: serial_ioctl - cmd 0x5410
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.950000] tty ttyUSB0: serial_chars_in_buffer
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.950000] zte_ev ttyUSB0: usb_serial_generic_chars_in_buffer - returns 0
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.950000] tty ttyUSB0: serial_wait_until_sent
Dec 2 19:37:54 iqvpn kern.debug kernel: [ 949.950000] tty ttyUSB0: serial_set_termios
Dec 2 19:37:55 iqvpn kern.debug kernel: [ 950.970000] tty ttyUSB0: serial_write - 46 byte(s)
Dec 2 19:37:55 iqvpn kern.debug kernel: [ 951.100000] tty ttyUSB0: serial_write - 55 byte(s)
Dec 2 19:37:55 iqvpn kern.debug kernel: [ 951.100000] tty ttyUSB0: serial_write - 13 byte(s)
Dec 2 19:37:55 iqvpn kern.debug kernel: [ 951.210000] tty ttyUSB0: serial_write - 29 byte(s)
Dec 2 19:37:55 iqvpn daemon.info pppd[2156]: CHAP authentication succeeded
Dec 2 19:37:55 iqvpn daemon.notice pppd[2156]: CHAP authentication succeeded
Dec 2 19:37:55 iqvpn kern.debug kernel: [ 951.330000] tty ttyUSB0: serial_write - 20 byte(s)
Dec 2 19:37:55 iqvpn kern.debug kernel: [ 951.330000] tty ttyUSB0: serial_write - 27 byte(s)
Dec 2 19:37:55 iqvpn kern.debug kernel: [ 951.330000] tty ttyUSB0: serial_write - 15 byte(s)
Dec 2 19:37:55 iqvpn kern.debug kernel: [ 951.450000] tty ttyUSB0: serial_write - 27 byte(s)
Dec 2 19:37:55 iqvpn daemon.err pppd[2156]: Failed to create /etc/ppp/resolv.conf: Read-only file system
Dec 2 19:37:55 iqvpn daemon.notice pppd[2156]: local IP address 10.182.145.86
Dec 2 19:37:55 iqvpn daemon.notice pppd[2156]: remote IP address 192.168.50.12
Dec 2 19:37:55 iqvpn daemon.notice pppd[2156]: primary DNS address 213.55.96.148
Dec 2 19:37:55 iqvpn daemon.notice pppd[2156]: secondary DNS address 213.55.96.166
Dec 2 19:37:55 iqvpn daemon.info dnsmasq[733]: reading /etc/resolv.conf
Dec 2 19:37:55 iqvpn daemon.info dnsmasq[733]: using nameserver 8.8.4.4#53
Dec 2 19:37:55 iqvpn daemon.info dnsmasq[733]: using nameserver 8.8.8.8#53
Dec 2 19:37:55 iqvpn daemon.info dnsmasq[733]: using nameserver 8.8.8.8#53
Dec 2 19:37:55 iqvpn daemon.info dnsmasq[733]: using nameserver 213.55.96.148#53
Dec 2 19:37:56 iqvpn kern.debug kernel: [ 951.650000] tty ttyUSB0: serial_write - 38 byte(s)
Dec 2 19:37:57 iqvpn kern.debug kernel: [ 952.680000] tty ttyUSB0: serial_write - 63 byte(s)
Dec 2 19:37:57 iqvpn kern.debug kernel: [ 952.820000] tty ttyUSB0: serial_write - 80 byte(s)
Dec 2 19:37:57 iqvpn kern.debug kernel: [ 952.890000] tty ttyUSB0: serial_write - 90 byte(s)
Dec 2 19:37:57 iqvpn kern.debug kernel: [ 953.490000] tty ttyUSB0: serial_write - 94 byte(s)
Dec 2 19:37:58 iqvpn kern.debug kernel: [ 954.040000] tty ttyUSB0: serial_write - 102 byte(s)
Dec 2 19:37:58 iqvpn kern.debug kernel: [ 954.140000] tty ttyUSB0: serial_write - 180 byte(s)
Dec 2 19:38:01 iqvpn kern.debug kernel: [ 956.740000] tty ttyUSB0: serial_write - 39 byte(s)
Dec 2 19:38:02 iqvpn kern.debug kernel: [ 957.720000] tty ttyUSB0: serial_write - 94 byte(s)
Dec 2 19:38:04 iqvpn kern.debug kernel: [ 960.100000] tty ttyUSB0: serial_write - 192 byte(s)
Dec 2 19:38:05 iqvpn kern.debug kernel: [ 961.150000] tty ttyUSB0: serial_write - 14 byte(s)
Dec 2 19:38:05 iqvpn kern.debug kernel: [ 961.300000] tty ttyUSB0: serial_write - 148 byte(s)
Dec 2 19:38:08 iqvpn kern.debug kernel: [ 964.380000] tty ttyUSB0: serial_write - 111 byte(s)
Dec 2 19:38:09 iqvpn kern.debug kernel: [ 964.880000] tty ttyUSB0: serial_write - 102 byte(s)
Dec 2 19:38:09 iqvpn kern.debug kernel: [ 964.960000] tty ttyUSB0: serial_write - 475 byte(s)
Dec 2 19:38:09 iqvpn kern.debug kernel: [ 965.510000] tty ttyUSB0: serial_write - 102 byte(s)
Dec 2 19:38:11 iqvpn kern.debug kernel: [ 966.750000] tty ttyUSB0: serial_write - 40 byte(s)
Dec 2 19:38:15 iqvpn kern.debug kernel: [ 970.720000] tty ttyUSB0: serial_write - 92 byte(s)
Dec 2 19:38:15 iqvpn kern.debug kernel: [ 971.160000] tty ttyUSB0: serial_write - 13 byte(s)
Dec 2 19:38:20 iqvpn kern.debug kernel: [ 976.280000] tty ttyUSB0: serial_write - 147 byte(s)
Dec 2 19:38:21 iqvpn kern.debug kernel: [ 976.770000] tty ttyUSB0: serial_write - 38 byte(s)
Dec 2 19:38:21 iqvpn kern.debug kernel: [ 976.910000] tty ttyUSB0: serial_write - 136 byte(s)
Dec 2 19:38:23 iqvpn kern.debug kernel: [ 978.880000] tty ttyUSB0: serial_write - 136 byte(s)
Dec 2 19:38:24 iqvpn kern.debug kernel: [ 979.860000] tty ttyUSB0: serial_write - 135 byte(s)
Dec 2 19:38:25 iqvpn kern.debug kernel: [ 980.930000] tty ttyUSB0: serial_write - 136 byte(s)
Dec 2 19:38:25 iqvpn kern.debug kernel: [ 981.180000] tty ttyUSB0: serial_write - 13 byte(s)
Dec 2 19:38:25 iqvpn kern.debug kernel: [ 981.330000] tty ttyUSB0: serial_write - 91 byte(s)
Dec 2 19:38:26 iqvpn kern.debug kernel: [ 981.870000] tty ttyUSB0: serial_write - 135 byte(s)
Dec 2 19:38:28 iqvpn kern.debug kernel: [ 984.380000] tty ttyUSB0: serial_write - 111 byte(s)
Dec 2 19:38:29 iqvpn kern.debug kernel: [ 984.880000] tty ttyUSB0: serial_write - 103 byte(s)
Dec 2 19:38:29 iqvpn kern.debug kernel: [ 984.960000] tty ttyUSB0: serial_write - 476 byte(s)
Dec 2 19:38:29 iqvpn kern.debug kernel: [ 985.510000] tty ttyUSB0: serial_write - 102 byte(s)
Dec 2 19:38:31 iqvpn kern.debug kernel: [ 986.700000] tty ttyUSB0: serial_write - 39 byte(s)
Dec 2 19:38:32 iqvpn kern.debug kernel: [ 987.740000] tty ttyUSB0: serial_write - 238 byte(s)
Dec 2 19:38:32 iqvpn kern.debug kernel: [ 987.830000] tty ttyUSB0: serial_write - 94 byte(s)
Dec 2 19:38:32 iqvpn kern.debug kernel: [ 988.350000] tty ttyUSB0: serial_write - 148 byte(s)
Dec 2 19:38:33 iqvpn kern.debug kernel: [ 988.860000] tty ttyUSB0: serial_write - 131 byte(s)
Dec 2 19:38:33 iqvpn kern.debug kernel: [ 989.370000] tty ttyUSB0: serial_write - 169 byte(s)
Dec 2 19:38:34 iqvpn kern.debug kernel: [ 989.850000] tty ttyUSB0: serial_write - 123 byte(s)
Dec 2 19:38:34 iqvpn kern.debug kernel: [ 990.380000] tty ttyUSB0: serial_write - 109 byte(s)
Dec 2 19:38:35 iqvpn kern.debug kernel: [ 990.860000] tty ttyUSB0: serial_write - 138 byte(s)
Dec 2 19:38:35 iqvpn kern.debug kernel: [ 991.200000] tty ttyUSB0: serial_write - 13 byte(s)
Dec 2 19:38:35 iqvpn kern.debug kernel: [ 991.350000] tty ttyUSB0: serial_write - 94 byte(s)
Dec 2 19:38:36 iqvpn kern.debug kernel: [ 991.850000] tty ttyUSB0: serial_write - 381 byte(s)
Dec 2 19:38:36 iqvpn kern.debug kernel: [ 991.950000] tty ttyUSB0: serial_write - 91 byte(s)
Dec 2 19:38:36 iqvpn kern.debug kernel: [ 992.280000] tty ttyUSB0: serial_write - 146 byte(s)
Dec 2 19:38:36 iqvpn kern.debug kernel: [ 992.390000] tty ttyUSB0: serial_write - 171 byte(s)
Dec 2 19:38:37 iqvpn kern.debug kernel: [ 992.790000] tty ttyUSB0: serial_write - 78 byte(s)
Dec 2 19:38:37 iqvpn kern.debug kernel: [ 992.950000] tty ttyUSB0: serial_write - 128 byte(s)
Dec 2 19:38:37 iqvpn kern.debug kernel: [ 993.460000] tty ttyUSB0: serial_write - 138 byte(s)
Dec 2 19:38:38 iqvpn kern.debug kernel: [ 994.000000] tty ttyUSB0: serial_write - 110 byte(s)
Dec 2 19:38:38 iqvpn kern.debug kernel: [ 994.530000] tty ttyUSB0: serial_write - 140 byte(s)
Dec 2 19:38:39 iqvpn kern.debug kernel: [ 995.060000] tty ttyUSB0: serial_write - 94 byte(s)
Dec 2 19:38:40 iqvpn kern.debug kernel: [ 995.630000] tty ttyUSB0: serial_write - 481 byte(s)
Dec 2 19:38:40 iqvpn kern.debug kernel: [ 996.190000] tty ttyUSB0: serial_write - 171 byte(s)
Dec 2 19:38:41 iqvpn kern.debug kernel: [ 996.720000] tty ttyUSB0: serial_write - 147 byte(s)
Dec 2 19:38:41 iqvpn kern.debug kernel: [ 996.760000] tty ttyUSB0: serial_write - 38 byte(s)
Dec 2 19:38:43 iqvpn kern.debug kernel: [ 998.760000] tty ttyUSB0: serial_write - 39 byte(s)
Dec 2 19:38:45 iqvpn kern.debug kernel: [ 1001.230000] tty ttyUSB0: serial_write - 14 byte(s)
Dec 2 19:38:46 iqvpn kern.debug kernel: [ 1002.560000] tty ttyUSB0: serial_write - 92 byte(s)
Dec 2 19:38:48 iqvpn kern.debug kernel: [ 1004.370000] tty ttyUSB0: serial_write - 111 byte(s)
Dec 2 19:38:49 iqvpn kern.debug kernel: [ 1004.860000] tty ttyUSB0: serial_write - 102 byte(s)
Dec 2 19:38:49 iqvpn kern.debug kernel: [ 1004.950000] tty ttyUSB0: serial_write - 478 byte(s)
Dec 2 19:38:49 iqvpn kern.debug kernel: [ 1005.500000] tty ttyUSB0: serial_write - 102 byte(s)
Dec 2 19:38:51 iqvpn kern.debug kernel: [ 1006.740000] tty ttyUSB0: serial_write - 39 byte(s)
Dec 2 19:38:51 iqvpn kern.debug kernel: [ 1007.570000] tty ttyUSB0: serial_write - 146 byte(s)
Dec 2 19:38:53 iqvpn kern.debug kernel: [ 1008.740000] tty ttyUSB0: serial_write - 39 byte(s)
Dec 2 19:38:55 iqvpn kern.debug kernel: [ 1011.250000] tty ttyUSB0: serial_write - 14 byte(s)
Dec 2 19:38:57 iqvpn kern.debug kernel: [ 1013.280000] tty ttyUSB0: serial_write - 92 byte(s)
Dec 2 19:38:57 iqvpn kern.debug kernel: [ 1013.560000] tty ttyUSB0: serial_write - 94 byte(s)
Dec 2 19:38:58 iqvpn kern.debug kernel: [ 1014.080000] tty ttyUSB0: serial_write - 102 byte(s)
Dec 2 19:38:58 iqvpn kern.debug kernel: [ 1014.460000] tty ttyUSB0: serial_write - 179 byte(s)
Dec 2 19:39:00 iqvpn kern.debug kernel: [ 1016.580000] tty ttyUSB0: serial_write - 148 byte(s)
Dec 2 19:39:01 iqvpn kern.debug kernel: [ 1016.780000] tty ttyUSB0: serial_write - 38 byte(s)
Dec 2 19:39:03 iqvpn kern.debug kernel: [ 1018.780000] tty ttyUSB0: serial_write - 39 byte(s)
Dec 2 19:39:04 iqvpn kern.debug kernel: [ 1020.380000] tty ttyUSB0: serial_write - 188 byte(s)
Dec 2 19:39:05 iqvpn kern.debug kernel: [ 1021.260000] tty ttyUSB0: serial_write - 13 byte(s)
Dec 2 19:39:05 iqvpn kern.debug kernel: [ 1021.610000] tty ttyUSB0: serial_write - 78 byte(s)
Dec 2 19:39:07 iqvpn kern.debug kernel: [ 1022.630000] tty ttyUSB0: serial_write - 147 byte(s)
Dec 2 19:39:08 iqvpn kern.debug kernel: [ 1024.540000] tty ttyUSB0: serial_write - 111 byte(s)
Dec 2 19:39:09 iqvpn kern.debug kernel: [ 1025.400000] tty ttyUSB0: serial_write - 102 byte(s)
Dec 2 19:39:09 iqvpn kern.debug kernel: [ 1025.480000] tty ttyUSB0: serial_write - 476 byte(s)
Dec 2 19:39:11 iqvpn kern.debug kernel: [ 1026.870000] tty ttyUSB0: serial_write - 39 byte(s)
Dec 2 19:39:11 iqvpn kern.debug kernel: [ 1027.200000] tty ttyUSB0: serial_write - 387 byte(s)
Dec 2 19:39:12 iqvpn kern.debug kernel: [ 1028.000000] tty ttyUSB0: serial_write - 119 byte(s)
Dec 2 19:39:12 iqvpn kern.debug kernel: [ 1028.550000] tty ttyUSB0: serial_write - 102 byte(s)
Dec 2 19:39:13 iqvpn kern.debug kernel: [ 1028.870000] tty ttyUSB0: serial_write - 38 byte(s)
Dec 2 19:39:15 iqvpn kern.debug kernel: [ 1031.100000] tty ttyUSB0: serial_write - 93 byte(s)
Dec 2 19:39:15 iqvpn kern.debug kernel: [ 1031.290000] tty ttyUSB0: serial_write - 13 byte(s)
Dec 2 19:39:20 iqvpn kern.debug kernel: [ 1036.210000] tty ttyUSB0: serial_write - 136 byte(s)
Dec 2 19:39:21 iqvpn kern.debug kernel: [ 1036.850000] tty ttyUSB0: serial_write - 38 byte(s)
Dec 2 19:39:21 iqvpn kern.debug kernel: [ 1037.600000] tty ttyUSB0: serial_write - 135 byte(s)
Dec 2 19:39:22 iqvpn kern.debug kernel: [ 1037.920000] tty ttyUSB0: serial_write - 146 byte(s)
Dec 2 19:39:22 iqvpn kern.debug kernel: [ 1038.340000] tty ttyUSB0: serial_write - 135 byte(s)
Dec 2 19:39:23 iqvpn kern.debug kernel: [ 1038.670000] tty ttyUSB0: serial_write - 223 byte(s)
Dec 2 19:39:23 iqvpn kern.debug kernel: [ 1038.850000] tty ttyUSB0: serial_write - 38 byte(s)
Dec 2 19:39:23 iqvpn kern.debug kernel: [ 1039.280000] tty ttyUSB0: serial_write - 135 byte(s)
Dec 2 19:39:23 iqvpn kern.debug kernel: [ 1039.340000] tty ttyUSB0: serial_write - 104 byte(s)
Dec 2 19:39:24 iqvpn kern.debug kernel: [ 1040.480000] tty ttyUSB0: serial_write - 136 byte(s)
Dec 2 19:39:25 iqvpn kern.debug kernel: [ 1041.310000] tty ttyUSB0: serial_write - 13 byte(s)
Dec 2 19:39:26 iqvpn kern.debug kernel: [ 1041.920000] tty ttyUSB0: serial_write - 92 byte(s)
Dec 2 19:39:28 iqvpn kern.debug kernel: [ 1044.430000] tty ttyUSB0: serial_write - 111 byte(s)
Dec 2 19:39:29 iqvpn kern.debug kernel: [ 1045.200000] tty ttyUSB0: serial_write - 102 byte(s)
Dec 2 19:39:29 iqvpn kern.debug kernel: [ 1045.280000] tty ttyUSB0: serial_write - 476 byte(s)
Dec 2 19:39:30 iqvpn kern.debug kernel: [ 1045.910000] tty ttyUSB0: serial_write - 102 byte(s)
Dec 2 19:39:31 iqvpn kern.debug kernel: [ 1046.860000] tty ttyUSB0: serial_write - 38 byte(s)
Dec 2 19:39:33 iqvpn kern.debug kernel: [ 1048.860000] tty ttyUSB0: serial_write - 39 byte(s)
Dec 2 19:39:35 iqvpn daemon.info pppd[2156]: No response to 3 echo-requests
Dec 2 19:39:35 iqvpn daemon.notice pppd[2156]: Serial link appears to be disconnected.
Dec 2 19:39:35 iqvpn daemon.info pppd[2156]: Connect time 1.7 minutes.
Dec 2 19:39:35 iqvpn daemon.info pppd[2156]: Sent 12813 bytes, received 53534 bytes.
Dec 2 19:39:35 iqvpn daemon.info dnsmasq[733]: reading /etc/resolv.conf
Dec 2 19:39:35 iqvpn daemon.info dnsmasq[733]: using nameserver 8.8.4.4#53
Dec 2 19:39:35 iqvpn daemon.info dnsmasq[733]: using nameserver 8.8.8.8#53
Dec 2 19:39:35 iqvpn daemon.info dnsmasq[733]: using nameserver 8.8.8.8#53
Dec 2 19:39:35 iqvpn daemon.info dnsmasq[733]: using nameserver 213.55.96.148#53
Dec 2 19:39:35 iqvpn daemon.info dnsmasq[733]: using nameserver 213.55.96.148#53
Dec 2 19:39:35 iqvpn kern.debug kernel: [ 1051.320000] tty ttyUSB0: serial_write - 36 byte(s)
Dec 2 19:39:38 iqvpn kern.debug kernel: [ 1054.350000] tty ttyUSB0: serial_write - 36 byte(s)
Dec 2 19:39:41 iqvpn daemon.notice pppd[2156]: Connection terminated.
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.360000] tty ttyUSB0: serial_ioctl - cmd 0x5407
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.360000] tty ttyUSB0: serial_chars_in_buffer
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.360000] zte_ev ttyUSB0: usb_serial_generic_chars_in_buffer - returns 0
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.360000] tty ttyUSB0: serial_wait_until_sent
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.360000] tty ttyUSB0: serial_tiocmset
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.360000] tty ttyUSB0: serial_ioctl - cmd 0x540d
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.360000] tty ttyUSB0: serial_ioctl - cmd 0x5410
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.360000] tty ttyUSB0: serial_chars_in_buffer
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.360000] zte_ev ttyUSB0: usb_serial_generic_chars_in_buffer - returns 0
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.360000] tty ttyUSB0: serial_wait_until_sent
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.360000] tty ttyUSB0: serial_set_termios
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.370000] tty ttyUSB0: serial_ioctl - cmd 0x540d
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.370000] tty ttyUSB0: serial_ioctl - cmd 0x540d
Dec 2 19:39:41 iqvpn local2.info chat[4025]: abort on (BUSY)
Dec 2 19:39:41 iqvpn local2.info chat[4025]: abort on (ERROR)
Dec 2 19:39:41 iqvpn local2.info chat[4025]: abort on (NO DIALTONE)
Dec 2 19:39:41 iqvpn local2.info chat[4025]: timeout set to 10 seconds
Dec 2 19:39:41 iqvpn local2.info chat[4025]: send (+++)
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.380000] tty ttyUSB0: serial_ioctl - cmd 0x540d
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.380000] tty ttyUSB0: serial_ioctl - cmd 0x540d
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.380000] tty ttyUSB0: serial_ioctl - cmd 0x540d
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.380000] tty ttyUSB0: serial_ioctl - cmd 0x540e
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.380000] tty ttyUSB0: serial_set_termios
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.380000] tty ttyUSB0: serial_ioctl - cmd 0x540d
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.400000] tty ttyUSB0: serial_write - 1 byte(s)
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.420000] tty ttyUSB0: serial_write - 1 byte(s)
Dec 2 19:39:41 iqvpn local2.info chat[4025]: send (ATH^M)
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.440000] tty ttyUSB0: serial_write - 1 byte(s)
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.460000] tty ttyUSB0: serial_write - 1 byte(s)
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.480000] tty ttyUSB0: serial_write - 1 byte(s)
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.500000] tty ttyUSB0: serial_write - 1 byte(s)
Dec 2 19:39:41 iqvpn daemon.info pppd[2156]: Serial link disconnected.
Dec 2 19:39:41 iqvpn daemon.notice pppd[2156]: Modem hangup
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.520000] tty ttyUSB0: serial_write - 1 byte(s)
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.520000] tty ttyUSB0: serial_ioctl - cmd 0x540e
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.520000] tty ttyUSB0: serial_set_termios
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.520000] tty ttyUSB0: serial_ioctl - cmd 0x540d
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.520000] tty ttyUSB0: serial_ioctl - cmd 0x540e
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.520000] tty ttyUSB0: serial_set_termios
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.520000] tty ttyUSB0: serial_ioctl - cmd 0x540d
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.520000] tty ttyUSB0: serial_close
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.520000] tty ttyUSB0: serial_chars_in_buffer
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.520000] zte_ev ttyUSB0: usb_serial_generic_chars_in_buffer - returns 0
Dec 2 19:39:41 iqvpn kern.debug kernel: [ 1057.520000] tty ttyUSB0: serial_wait_until_sent
