Image of grog
Greg's network disconnects
Greg's diary
Photo index
Greg's home page
Network link stats
Greg's other links
Copyright information
Groogle

This page documents some problems I have had with my Internet connection starting round 23 August 2012. There's more information at that link. The purpose of this page is to document the individual outages and guess at their causes.

Aug 23 11:02:55

Aug 23 11:02:12 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Aug 23 11:02:12 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Aug 23 11:02:12 nerd-gw ppp[59859]: tun0: Phase: deflink: read (0): Got zero bytes
Aug 23 11:02:12 nerd-gw ppp[59859]: tun0: CCP: deflink: State change Stopped --> Closed
Aug 23 11:02:12 nerd-gw ppp[59859]: tun0: CCP: deflink: State change Closed --> Initial

Aug 23 13:34:39

Aug 23 13:33:56 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Aug 23 13:33:56 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Aug 23 13:33:56 nerd-gw ppp[59859]: tun0: Phase: deflink: read (0): Got zero bytes
Aug 23 13:33:56 nerd-gw ppp[59859]: tun0: CCP: deflink: State change Stopped --> Closed
Aug 23 13:33:56 nerd-gw ppp[59859]: tun0: CCP: deflink: State change Closed --> Initial

Aug 23 14:15:36

Aug 23 14:14:53 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Aug 23 14:14:53 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Aug 23 14:14:53 nerd-gw ppp[59859]: tun0: Phase: deflink: read (0): Got zero bytes
Aug 23 14:14:53 nerd-gw ppp[59859]: tun0: CCP: deflink: State change Stopped --> Closed
Aug 23 14:14:53 nerd-gw ppp[59859]: tun0: CCP: deflink: State change Closed --> Initial

Aug 23 15:44:07

Aug 23 15:43:24 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Aug 23 15:43:24 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Aug 23 15:43:24 nerd-gw ppp[59859]: tun0: Phase: deflink: read (0): Got zero bytes
Aug 23 15:43:24 nerd-gw ppp[59859]: tun0: CCP: deflink: State change Stopped --> Closed
Aug 23 15:43:24 nerd-gw ppp[59859]: tun0: CCP: deflink: State change Closed --> Initial

Aug 23 15:45:42

Aug 23 15:44:07 nerd-gw ppp[59859]: tun0: IPCP: myaddr 121.44.19.92 hisaddr = 10.1.0.1
...
Aug 23 15:44:59 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Aug 23 15:44:59 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Aug 23 15:44:59 nerd-gw ppp[59859]: tun0: Phase: deflink: read (0): Got zero bytes
Aug 23 15:44:59 nerd-gw ppp[59859]: tun0: CCP: deflink: State change Stopped --> Closed
Aug 23 15:44:59 nerd-gw ppp[59859]: tun0: CCP: deflink: State change Closed --> Initial

Aug 23 16:23:24

Aug 23 16:22:42 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Aug 23 16:22:42 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Aug 23 16:22:42 nerd-gw ppp[59859]: tun0: Phase: deflink: read (0): Got zero bytes
Aug 23 16:22:42 nerd-gw ppp[59859]: tun0: CCP: deflink: State change Stopped --> Closed
Aug 23 16:22:42 nerd-gw ppp[59859]: tun0: CCP: deflink: State change Closed --> Initial

Aug 24 01:07:35

no kernel messages
Aug 24 01:07:20 nerd-gw ppp[59859]: tun0: LCP: deflink: RecvTerminateReq(4) state = Opened
Aug 24 01:07:20 nerd-gw ppp[59859]: tun0: LCP: deflink: LayerDown
Aug 24 01:07:20 nerd-gw ppp[59859]: tun0: LCP: deflink: SendTerminateAck(4) state = Opened
Aug 24 01:07:20 nerd-gw ppp[59859]: tun0: LCP: deflink: State change Opened --> Stopping
Aug 24 01:07:20 nerd-gw ppp[59859]: tun0: CCP: deflink: State change Stopped --> Closed
Aug 24 01:07:20 nerd-gw ppp[59859]: tun0: CCP: deflink: State change Closed --> Initial

Aug 24 11:00:58

Aug 24 11:00:15 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Aug 24 11:00:15 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Aug 24 11:00:15 nerd-gw ppp[59859]: tun0: Phase: deflink: read (0): Got zero bytes
Aug 24 11:00:15 nerd-gw ppp[59859]: tun0: CCP: deflink: State change Stopped --> Closed
Aug 24 11:00:15 nerd-gw ppp[59859]: tun0: CCP: deflink: State change Closed --> Initial

Aug 24 11:31:22

Aug 24 11:31:22 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Aug 24 11:31:22 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Aug 24 11:31:22 nerd-gw ppp[59859]: tun0: Phase: deflink: read (0): Got zero bytes
Aug 24 11:31:22 nerd-gw ppp[59859]: tun0: CCP: deflink: State change Stopped --> Closed
Aug 24 11:31:22 nerd-gw ppp[59859]: tun0: CCP: deflink: State change Closed --> Initial

Aug 24 12:07:23

no kernel messages
Aug 24 12:07:23 nerd-gw ppp[59859]: tun0: Phase: Signal 15, terminate.
Aug 24 12:07:23 nerd-gw ppp[59859]: tun0: IPCP: deflink: LayerDown: 121.44.40.18

Aug 24 12:17:07

no kernel messages
Aug 24 12:17:07 nerd-gw ppp[87360]: tun0: Phase: Signal 15, terminate.
Aug 24 12:17:07 nerd-gw ppp[87360]: tun0: IPCP: deflink: LayerDown: 118.209.49.7

Aug 25 16:41:44

Aug 25 16:41:44 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Aug 25 16:41:44 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Aug 25 16:41:44 nerd-gw ppp[87396]: tun0: Phase: deflink: read (0): Got zero bytes
Aug 25 16:41:44 nerd-gw ppp[87396]: tun0: CCP: deflink: State change Stopped --> Closed
Aug 25 16:41:44 nerd-gw ppp[87396]: tun0: CCP: deflink: State change Closed --> Initial

Aug 27 23:28:24

no kernel messages
Aug 27 23:28:24 nerd-gw ppp[87396]: tun0: LCP: deflink: RecvTerminateReq(6) state = Opened
Aug 27 23:28:24 nerd-gw ppp[87396]: tun0: LCP: deflink: LayerDown

Aug 29 00:11:40

no kernel messages
Aug 29 00:11:40 nerd-gw ppp[87396]: tun0: LCP: deflink: RecvTerminateReq(11) state = Opened
Aug 29 00:11:40 nerd-gw ppp[87396]: tun0: LCP: deflink: LayerDown
Aug 29 00:12:08 nerd-gw ppp[87396]: tun0: Phase: deflink: opening -> dial
Aug 29 00:12:08 nerd-gw ppp[87396]: tun0: Chat: Send: ATZ^M
Aug 29 00:12:08 nerd-gw ppp[87396]: tun0: Chat: Expect(5): OK
Aug 29 00:12:08 nerd-gw ppp[87396]: tun0: Chat: Received: ^M
Aug 29 00:12:08 nerd-gw ppp[87396]: tun0: Chat: Received: NO CARRIER^M

Aug 29 14:01:26

Aug 29 14:01:26 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Aug 29 14:01:26 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Aug 29 14:01:26 nerd-gw ppp[87396]: tun0: Phase: deflink: read (0): Got zero bytes
Aug 29 14:01:26 nerd-gw ppp[87396]: tun0: CCP: deflink: State change Stopped --> Closed
Aug 29 14:01:26 nerd-gw ppp[87396]: tun0: CCP: deflink: State change Closed --> Initial

This one was a physically removed device.

Aug 30 13:03:05

 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Aug 30 13:03:05 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Aug 30 13:03:05 nerd-gw ppp[87396]: tun0: Phase: deflink: read (0): Got zero bytes
Aug 30 13:03:05 nerd-gw ppp[87396]: tun0: CCP: deflink: State change Stopped --> Closed
Aug 30 13:03:05 nerd-gw ppp[87396]: tun0: CCP: deflink: State change Closed --> Initial

Aug 30 13:03:18 nerd-gw root: Unknown USB device: vendor 0x12d1 product 0x140c bus uhub0
Aug 30 13:03:18 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0
Aug 30 13:03:18 nerd-gw kernel: u3g0: <HUAWEI Technology HUAWEI Mobile, class 0/0, rev 2.00/0.00, addr 2> on usbus0

Aug 31 04:08:32: Optus disconnect

Aug 31 04:08:32 nerd-gw ppp[87396]: tun0: LCP: deflink: RecvTerminateReq(8) state = Opened
Aug 31 04:08:32 nerd-gw ppp[87396]: tun0: LCP: deflink: LayerDown
Aug 31 04:08:32 nerd-gw ppp[87396]: tun0: LCP: deflink: SendTerminateAck(8) state = Opened

Sep 5 15:38:17: Firmware reset

Sep  5 15:38:17 nerd-gw ppp[87396]: tun0: Phase: deflink: read (0): Got zero bytes
Sep  5 15:38:17 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Sep  5 15:38:17 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Sep  5 15:38:17 nerd-gw ppp[87396]: tun0: CCP: deflink: State change Stopped --> Closed
Sep  5 15:38:17 nerd-gw ppp[87396]: tun0: CCP: deflink: State change Closed --> Initial

Sep 5 21:29:40: Firmware reset

Sep  5 21:29:40 nerd-gw fstats: +CGREG  1  81E3  8FC48E8
Sep  5 21:29:51 nerd-gw ppp[87396]: tun0: Phase: deflink: read (0): Got zero bytes
Sep  5 21:29:51 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Sep  5 21:29:51 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Sep  5 21:29:51 nerd-gw ppp[87396]: tun0: CCP: deflink: State change Stopped --> Closed
Sep  5 21:29:51 nerd-gw ppp[87396]: tun0: CCP: deflink: State change Closed --> Initial

Sep 6 11:31:41: Firmware reset

Sep  6 11:31:41 nerd-gw ppp[87396]: tun0: Phase: deflink: read (0): Got zero bytes
Sep  6 11:31:41 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Sep  6 11:31:41 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Sep  6 11:31:41 nerd-gw ppp[87396]: tun0: CCP: deflink: State change Stopped --> Closed
Sep  6 11:31:41 nerd-gw ppp[87396]: tun0: CCP: deflink: State change Closed --> Initial

Sep 7 08:53:55: Firmware reset

Sep  7 08:53:55 nerd-gw fstats: +CGREG  1  81E3  8FC48E8
Sep  7 08:58:18 nerd-gw ppp[87396]: tun0: Phase: deflink: read (0): Got zero bytes
Sep  7 08:58:18 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Sep  7 08:58:18 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Sep  7 08:58:18 nerd-gw ppp[87396]: tun0: CCP: deflink: State change Stopped --> Closed
Sep  7 08:58:18 nerd-gw ppp[87396]: tun0: CCP: deflink: State change Closed --> Initial

Sep 7 19:35:21: Firmware reset

Sep  7 19:35:21 nerd-gw ppp[87396]: tun0: Phase: deflink: read (0): Got zero bytes
Sep  7 19:35:21 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Sep  7 19:35:21 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Sep  7 19:35:21 nerd-gw ppp[87396]: tun0: CCP: deflink: State change Stopped --> Closed
Sep  7 19:35:21 nerd-gw ppp[87396]: tun0: CCP: deflink: State change Closed --> Initial

Sep 8 10:15:30: Firmware reset

Sep  8 10:15:30 nerd-gw ppp[87396]: tun0: Phase: deflink: read (0): Got zero bytes
Sep  8 10:15:30 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Sep  8 10:15:30 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Sep  8 10:15:30 nerd-gw ppp[87396]: tun0: CCP: deflink: State change Stopped --> Closed
Sep  8 10:15:30 nerd-gw ppp[87396]: tun0: CCP: deflink: State change Closed --> Initial

Sep 9 10:36:39: Firmware reset

Sep  9 10:36:39 nerd-gw ppp[87396]: tun0: Phase: deflink: read (0): Got zero bytes
Sep  9 10:36:39 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Sep  9 10:36:39 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Sep  9 10:36:39 nerd-gw ppp[87396]: tun0: CCP: deflink: State change Stopped --> Closed
Sep  9 10:36:39 nerd-gw ppp[87396]: tun0: CCP: deflink: State change Closed --> Initial
...
Sep  9 10:37:21 nerd-gw ppp[87396]: tun0: IPCP: myaddr 121.44.62.201 hisaddr = 10.1.0.1

Sep 12 00:56:40: Remote disconnect

Sep 12 00:56:40 nerd-gw ppp[87396]: tun0: LCP: deflink: RecvTerminateReq(5) state = Opened
Sep 12 00:56:40 nerd-gw ppp[87396]: tun0: LCP: deflink: LayerDown
Sep 12 00:56:40 nerd-gw ppp[87396]: tun0: LCP: deflink: SendTerminateAck(5) state = Opened
Sep 12 00:56:40 nerd-gw ppp[87396]: tun0: LCP: deflink: State change Opened --> Stopping
.... (many retries)
Sep 12 00:58:41 nerd-gw ppp[87396]: tun0: IPCP: myaddr 121.44.70.235 hisaddr = 10.1.0.1

Sep 14 00:59:00: Remote disconnect

Sep 14 00:59:00 nerd-gw ppp[87396]: tun0: LCP: deflink: RecvTerminateReq(13) state = Opened
Sep 14 00:59:00 nerd-gw ppp[87396]: tun0: LCP: deflink: LayerDown
Sep 14 00:59:00 nerd-gw ppp[87396]: tun0: LCP: deflink: SendTerminateAck(13) state = Opened
Sep 14 00:59:00 nerd-gw ppp[87396]: tun0: LCP: deflink: State change Opened --> Stopping
...
Sep 14 10:25:28 nerd-gw ppp[44556]: tun0: IPCP: myaddr 121.44.100.84 hisaddr = 10.1.0.1

This one is interesting because the ppp process didn't recover. It's not clear whose fault this one is, but it required manual intervention and resulted in 9½ hours off the net.

Sep 20 18:55:22: Remote disconnect

Sep 20 18:55:22 nerd-gw ppp[44556]: tun0: LCP: deflink: RecvTerminateReq(5) state = Opened
Sep 20 18:55:22 nerd-gw ppp[44556]: tun0: LCP: deflink: LayerDown
Sep 20 18:55:22 nerd-gw ppp[44556]: tun0: LCP: deflink: SendTerminateAck(5) state = Opened
Sep 20 18:55:22 nerd-gw ppp[44556]: tun0: LCP: deflink: State change Opened --> Stopping
...
Sep 20 18:57:08 nerd-gw ppp[44556]: tun0: IPCP: myaddr 121.44.73.28 hisaddr = 10.1.0.1

Sep 24 14:48:25: Firmware reset

Sep 24 14:48:25 nerd-gw ppp[44556]: tun0: Phase: deflink: read (0): Got zero bytes
Sep 24 14:48:25 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Sep 24 14:48:25 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Sep 24 14:48:25 nerd-gw ppp[44556]: tun0: CCP: deflink: State change Stopped --> Closed
Sep 24 14:48:25 nerd-gw ppp[44556]: tun0: CCP: deflink: State change Closed --> Initial
...
Sep 24 14:49:07 nerd-gw ppp[44556]: tun0: IPCP: myaddr 121.44.68.231 hisaddr = 10.1.0.1

Sep 26 00:54:04: Remote disconnect, longer outage

Sep 26 00:54:04 nerd-gw ppp[44556]: tun0: LCP: deflink: RecvTerminateReq(3) state = Opened
Sep 26 00:54:04 nerd-gw fstats: +CGREG  0
Sep 26 00:54:04 nerd-gw ppp[44556]: tun0: LCP: deflink: LayerDown
Sep 26 00:54:04 nerd-gw ppp[44556]: tun0: LCP: deflink: SendTerminateAck(3) state = Opened
Sep 26 00:54:04 nerd-gw ppp[44556]: tun0: LCP: deflink: State change Opened --> Stopping
...
Sep 26 00:55:21 nerd-gw ppp[44556]: tun0: Phase: deflink: Too many IPCP REQs sent - abandoning negotiation
...
Sep 26 00:55:56 nerd-gw ppp[44556]: tun0: Chat: Expect(40): CONNECT
Sep 26 00:55:56 nerd-gw ppp[44556]: tun0: Chat: Received: AT+CGREG=2^M^M
Sep 26 00:55:56 nerd-gw ppp[44556]: tun0: Chat: Received: OK^M
Sep 26 00:55:56 nerd-gw ppp[44556]: tun0: Chat: Received: ^M
Sep 26 00:55:56 nerd-gw ppp[44556]: tun0: Chat: Received: NO CARRIER^M
...
Sep 26 00:56:35 nerd-gw ppp[44556]: tun0: IPCP: myaddr 121.44.92.128 hisaddr = 10.1.0.1

Oct 4 16:04:04: Firmware reset

Oct  4 15:39:50 nerd-gw fstats: +CGREG  1  81E3  8FC8E66
...
Oct  4 15:43:40 nerd-gw fstats: +CGREG  1  81E3  8FC8E4A
...
Oct  4 15:48:08 nerd-gw fstats: +CGREG  1  81E3  8FC8E52
...
Oct  4 15:56:49 nerd-gw fstats: +CGREG  1  81E3  8FC48E8
Oct  4 16:00:46 nerd-gw fstats: +CGREG  1  81E3  8FC8F2E
Oct  4 16:00:58 nerd-gw fstats: +CGREG  2
Oct  4 16:00:58 nerd-gw fstats: +CGREG  2
Oct  4 16:00:58 nerd-gw fstats: +CGREG  1  81E3  8FC48E8
Oct  4 16:04:04 nerd-gw ppp[1663]: tun0: Phase: deflink: read (0): Got zero bytes
Oct  4 16:04:04 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Oct  4 16:04:04 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Oct  4 16:04:04 nerd-gw ppp[1663]: tun0: CCP: deflink: State change Stopped --> Closed
Oct  4 16:04:04 nerd-gw ppp[1663]: tun0: CCP: deflink: State change Closed --> Initial
...
Oct  4 16:04:46 nerd-gw ppp[1663]: tun0: IPCP: myaddr 121.44.8.66 hisaddr = 10.1.0.1

The frequent cell hopping between 5 cells, along with 12 seconds of no association at all just before the dropout, suggest that this, too, is an Optus problem.

Oct 12 21:33:05: Remote disconnect

Oct 12 18:59:36 nerd-gw fstats: +CGREG  1  81E3  8FC8E66
Oct 12 18:59:56 nerd-gw fstats: +CGREG  2
Oct 12 18:59:56 nerd-gw fstats: +CGREG  2
Oct 12 18:59:56 nerd-gw fstats: +CGREG  1  81E3  8FC48E8
Oct 12 19:02:26 nerd-gw fstats: +CGREG  1  81E3  8FC8E66
...
Oct 12 21:33:05 nerd-gw ppp[1663]: tun0: LCP: deflink: RecvTerminateReq(3) state = Opened
Oct 12 21:33:05 nerd-gw ppp[1663]: tun0: LCP: deflink: LayerDown
Oct 12 21:33:05 nerd-gw ppp[1663]: tun0: LCP: deflink: SendTerminateAck(3) state = Opened
Oct 12 21:33:05 nerd-gw ppp[1663]: tun0: LCP: deflink: State change Opened --> Stopping
Oct 12 21:33:05 nerd-gw ppp[1663]: tun0: CCP: deflink: State change Stopped --> Closed
...
Oct 12 21:33:22 nerd-gw ppp[1663]: tun0: IPCP: myaddr 118.209.65.181 hisaddr = 10.1.0.1

Oct 13 09:06:16: Remote disconnect

Oct 13 09:06:16 nerd-gw ppp[1663]: tun0: LCP: deflink: RecvTerminateReq(7) state = Opened
Oct 13 09:06:16 nerd-gw ppp[1663]: tun0: LCP: deflink: LayerDown
Oct 13 09:06:16 nerd-gw ppp[1663]: tun0: LCP: deflink: SendTerminateAck(7) state = Opened
Oct 13 09:06:16 nerd-gw ppp[1663]: tun0: LCP: deflink: State change Opened --> Stopping
Oct 13 09:06:16 nerd-gw ppp[1663]: tun0: CCP: deflink: State change Stopped --> Closed
...
Oct 13 09:06:34 nerd-gw ppp[1663]: tun0: IPCP: myaddr 118.209.75.163 hisaddr = 10.1.0.1

Oct 13 09:21:16: Remote disconnect

Oct 13 09:21:16 nerd-gw ppp[1663]: tun0: LCP: deflink: RecvTerminateReq(11) state = Opened
Oct 13 09:21:16 nerd-gw ppp[1663]: tun0: LCP: deflink: LayerDown
Oct 13 09:21:16 nerd-gw ppp[1663]: tun0: LCP: deflink: SendTerminateAck(11) state = Opened
Oct 13 09:21:16 nerd-gw ppp[1663]: tun0: LCP: deflink: State change Opened --> Stopping
Oct 13 09:21:16 nerd-gw ppp[1663]: tun0: CCP: deflink: State change Stopped --> Closed
...
Oct 13 09:21:34 nerd-gw ppp[1663]: tun0: IPCP: myaddr 118.209.16.207 hisaddr = 10.1.0.1

Oct 15 09:19:41: Remote disconnect

Oct 15 09:19:41 nerd-gw ppp[1663]: tun0: LCP: deflink: RecvTerminateReq(15) state = Opened
Oct 15 09:19:41 nerd-gw ppp[1663]: tun0: LCP: deflink: LayerDown
Oct 15 09:19:41 nerd-gw ppp[1663]: tun0: LCP: deflink: SendTerminateAck(15) state = Opened
Oct 15 09:19:41 nerd-gw ppp[1663]: tun0: LCP: deflink: State change Opened --> Stopping
Oct 15 09:19:41 nerd-gw ppp[1663]: tun0: CCP: deflink: State change Stopped --> Closed
...
Oct 15 09:20:04 nerd-gw ppp[1663]: tun0: IPCP: myaddr 118.209.125.214 hisaddr = 10.1.0.1

Oct 16 18:13:56: Firmware reset

Oct 16 18:13:56 nerd-gw ppp[1663]: tun0: Phase: deflink: read (0): Got zero bytes
Oct 16 18:13:56 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Oct 16 18:13:56 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Oct 16 18:13:56 nerd-gw ppp[1663]: tun0: CCP: deflink: State change Stopped --> Closed
Oct 16 18:13:56 nerd-gw ppp[1663]: tun0: CCP: deflink: State change Closed --> Initial
...
Oct 16 18:14:38 nerd-gw ppp[1663]: tun0: IPCP: myaddr 118.209.24.71 hisaddr = 10.1.0.1

Oct 16 23:39:10: Remote disconnect

Oct 16 23:39:10 nerd-gw ppp[1663]: tun0: LCP: deflink: RecvTerminateReq(3) state = Opened
Oct 16 23:39:10 nerd-gw ppp[1663]: tun0: LCP: deflink: LayerDown
Oct 16 23:39:10 nerd-gw ppp[1663]: tun0: LCP: deflink: SendTerminateAck(3) state = Opened
Oct 16 23:39:10 nerd-gw ppp[1663]: tun0: LCP: deflink: State change Opened --> Stopping
...
No reconnect

Restarted ppp manually:

Oct 17 09:38:03 nerd-gw ppp[57900]: tun0: IPCP: myaddr 121.44.109.138 hisaddr = 10.1.0.1

Oct 29 16:52:14: Remote disconnect

Oct 29 16:52:14 nerd-gw ppp[1679]: tun0: LCP: deflink: RecvTerminateReq(3) state = Opened
Oct 29 16:52:14 nerd-gw ppp[1679]: tun0: LCP: deflink: LayerDown
Oct 29 16:52:14 nerd-gw ppp[1679]: tun0: LCP: deflink: SendTerminateAck(3) state = Opened
Oct 29 16:52:14 nerd-gw ppp[1679]: tun0: LCP: deflink: State change Opened --> Stopping
...
Oct 29 16:52:32 nerd-gw ppp[1679]: tun0: IPCP: myaddr 118.209.24.124 hisaddr = 10.1.0.1

Nov 1 10:34:53: Firmware reset

Nov  1 10:34:53 nerd-gw ppp[1679]: tun0: Phase: deflink: read (0): Got zero bytes
Nov  1 10:34:53 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Nov  1 10:34:53 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
...
Nov  1 10:35:36 nerd-gw ppp[1679]: tun0: IPCP: myaddr 118.209.90.76 hisaddr = 10.1.0.1

Nov 1 11:30:48: Firmware reset

Nov  1 11:30:48 nerd-gw ppp[1679]: tun0: Phase: deflink: read (0): Got zero bytes
Nov  1 11:30:48 nerd-gw ppp[1679]: tun0: CCP: deflink: State change Stopped --> Closed
Nov  1 11:30:48 nerd-gw ppp[1679]: tun0: CCP: deflink: State change Closed --> Initial
Nov  1 11:30:48 nerd-gw ppp[1679]: tun0: LCP: deflink: LayerDown
...
Nov  1 11:31:30 nerd-gw ppp[1679]: tun0: IPCP: myaddr 118.209.66.167 hisaddr = 10.1.0.1

Nov 1 17:59:33: Firmware reset

Nov  1 17:59:33 nerd-gw fstats: +CGREG  1  81E3  8FC48E8
Nov  1 18:00:30 nerd-gw ppp[1679]: tun0: Phase: deflink: read (0): Got zero bytes
Nov  1 18:00:30 nerd-gw ppp[1679]: tun0: CCP: deflink: State change Stopped --> Closed
Nov  1 18:00:30 nerd-gw ppp[1679]: tun0: CCP: deflink: State change Closed --> Initial
Nov  1 18:00:30 nerd-gw ppp[1679]: tun0: LCP: deflink: LayerDown
...
Nov  1 18:01:12 nerd-gw ppp[1679]: tun0: IPCP: myaddr 121.44.98.155 hisaddr = 10.1.0.1

Connected to GPRS cell

Nov  3 15:21:01 nerd-gw fstats: +CGREG  1  81E3  8FC8F2E
Nov  3 15:21:11 nerd-gw fstats: +CGREG  1  81E3  8FC48E8
Nov  3 15:25:18 nerd-gw fstats: +CGREG  1  81E3  8FC8F2E
Nov  3 15:25:23 nerd-gw fstats: +CGREG  1  81E3  142
1351916924 0.561693 5   # Sat 3 Nov 2012 15:28:44 EST 890.166 ms
1351916993 0.708601 5   # Sat 3 Nov 2012 15:29:53 EST 705.616 ms
1351917130 0 0 hub w3 www.auug.org.au ozlabs.org ftp.netbsd.org         # Sat 3 Nov 2012 15:32:10 EST
1351917205 0 1 hub w3 www.auug.org.au ozlabs.org        # Sat 3 Nov 2012 15:33:25 EST
1351917270 0.0804898 1 w3 www.auug.org.au ozlabs.org ftp.netbsd.org     # Sat 3 Nov 2012 15:34:30 EST 6211.971 ms
1351917339 0 5  # Sat 3 Nov 2012 15:35:39 EST
...
Nov  3 15:38:20 nerd-gw ppp[18653]: tun0: IPCP: myaddr 118.209.86.158 hisaddr = 10.1.0.1

Still connected to GPRS. Popped modem. Connected HSPA:

Nov  3 15:40:04 nerd-gw ppp[18653]: tun0: IPCP: myaddr 121.45.161.181 hisaddr = 10.1.0.1

64 bytes from 203.10.76.45: icmp_seq=266 ttl=54 time=19593.835 ms
64 bytes from 203.10.76.45: icmp_seq=267 ttl=54 time=18622.831 ms
64 bytes from 203.10.76.45: icmp_seq=268 ttl=54 time=17649.802 ms
64 bytes from 203.10.76.45: icmp_seq=269 ttl=54 time=16650.781 ms
64 bytes from 203.10.76.45: icmp_seq=270 ttl=54 time=15667.779 ms
64 bytes from 203.10.76.45: icmp_seq=280 ttl=54 time=5687.755 ms
64 bytes from 203.10.76.45: icmp_seq=281 ttl=54 time=4688.750 ms
64 bytes from 203.10.76.45: icmp_seq=282 ttl=54 time=3695.737 ms
64 bytes from 203.10.76.45: icmp_seq=283 ttl=54 time=2736.686 ms
64 bytes from 203.10.76.45: icmp_seq=284 ttl=54 time=1743.701 ms
64 bytes from 203.10.76.45: icmp_seq=285 ttl=54 time=762.673 ms
64 bytes from 203.10.76.45: icmp_seq=286 ttl=54 time=82.326 ms
64 bytes from 203.10.76.45: icmp_seq=287 ttl=54 time=101.264 ms
64 bytes from 203.10.76.45: icmp_seq=288 ttl=54 time=80.175 ms
64 bytes from 203.10.76.45: icmp_seq=289 ttl=54 time=92.091 ms
64 bytes from 203.10.76.45: icmp_seq=290 ttl=54 time=98.040 ms
64 bytes from 203.10.76.45: icmp_seq=291 ttl=54 time=139.862 ms
64 bytes from 203.10.76.45: icmp_seq=292 ttl=54 time=93.962 ms
64 bytes from 203.10.76.45: icmp_seq=293 ttl=54 time=82.926 ms
...

Nov 8 01:29:56: Remote disconnect

Nov  8 01:29:56 nerd-gw ppp[18653]: tun0: LCP: deflink: RecvTerminateReq(3) state = Opened
Nov  8 01:29:56 nerd-gw ppp[18653]: tun0: LCP: deflink: LayerDown

Unable to reconnect, done manually:

Nov  8 08:11:44 nerd-gw ppp[31273]: tun0: IPCP: myaddr 121.44.109.242 hisaddr = 10.1.0.1

Nov 14 00:55:07: Remote disconnect

Nov 14 00:55:07 nerd-gw ppp[31273]: tun0: LCP: deflink: RecvTerminateReq(11) state = Opened
Nov 14 00:55:07 nerd-gw ppp[31273]: tun0: LCP: deflink: LayerDown
Nov 14 00:55:07 nerd-gw ppp[31273]: tun0: LCP: deflink: SendTerminateAck(11) state = Opened
Nov 14 00:55:07 nerd-gw ppp[31273]: tun0: LCP: deflink: State change Opened --> Stopping
...
Nov 14 00:56:07 nerd-gw ppp[31273]: tun0: Chat: Expect(40): CONNECT
Nov 14 00:56:07 nerd-gw ppp[31273]: tun0: Chat: Received: AT+CGREG=2^M^M
Nov 14 00:56:07 nerd-gw ppp[31273]: tun0: Chat: Received: OK^M
Nov 14 00:56:07 nerd-gw ppp[31273]: tun0: Chat: Received: ^M
Nov 14 00:56:07 nerd-gw ppp[31273]: tun0: Chat: Received: NO CARRIER^M
Nov 14 00:56:07 nerd-gw ppp[31273]: tun0: Chat: Received: ^M
...
Nov 14 00:56:46 nerd-gw ppp[31273]: tun0: IPCP: myaddr 118.209.39.2 hisaddr = 10.1.0.1

Nov 15 16:36:33: Remote disconnect

Nov 15 16:36:33 nerd-gw ppp[31273]: tun0: LCP: deflink: RecvTerminateReq(17) state = Opened
Nov 15 16:36:33 nerd-gw ppp[31273]: tun0: LCP: deflink: LayerDown
Nov 15 16:36:33 nerd-gw ppp[31273]: tun0: LCP: deflink: SendTerminateAck(17) state = Opened
Nov 15 16:36:33 nerd-gw ppp[31273]: tun0: LCP: deflink: State change Opened --> Stopping
...
Nov 15 16:36:51 nerd-gw ppp[31273]: tun0: IPCP: myaddr 118.209.31.139 hisaddr = 10.1.0.1

Nov 19 09:46:10: Remote disconnect

Nov 19 09:46:10 nerd-gw ppp[31273]: tun0: LCP: deflink: RecvTerminateReq(21) state = Opened
Nov 19 09:46:10 nerd-gw ppp[31273]: tun0: LCP: deflink: LayerDown
Nov 19 09:46:10 nerd-gw ppp[31273]: tun0: LCP: deflink: SendTerminateAck(21) state = Opened
Nov 19 09:46:10 nerd-gw ppp[31273]: tun0: LCP: deflink: State change Opened --> Stopping
...
Nov 19 09:46:28 nerd-gw ppp[31273]: tun0: IPCP: myaddr 118.209.45.83 hisaddr = 10.1.0.1

20 Nov 2012 13:08:07: ppp process wedged

Restarting ppp got things going again.

Nov 20 13:13:56 nerd-gw ppp[63956]: tun0: IPCP: myaddr 118.209.41.140 hisaddr = 10.1.0.1

Nov 21 16:20:59: Firmware reset

Nov 21 16:20:59 nerd-gw fstats: +CGREG  1  81E3  8FC48E8
Nov 21 16:22:21 nerd-gw ppp[63956]: tun0: Phase: deflink: read (0): Got zero bytes
Nov 21 16:22:21 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Nov 21 16:22:21 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Nov 21 16:22:21 nerd-gw ppp[63956]: tun0: CCP: deflink: State change Stopped --> Closed
...
Nov 21 16:23:56 nerd-gw ppp[63956]: tun0: IPCP: myaddr 121.44.46.95 hisaddr = 10.1.0.1

Very slow connection, didn't come out of UMTS. Popped modem.

Nov 21 16:27:06 nerd-gw ppp[63956]: tun0: IPCP: myaddr 121.44.30.13 hisaddr = 10.1.0.1

Nov 22 23:57:33: Remote disconnect

Nov 22 23:57:33 nerd-gw ppp[63956]: tun0: LCP: deflink: RecvTerminateReq(3) state = Opened
Nov 22 23:57:33 nerd-gw ppp[63956]: tun0: LCP: deflink: LayerDown
Nov 22 23:57:33 nerd-gw ppp[63956]: tun0: LCP: deflink: SendTerminateAck(3) state = Opened
Nov 22 23:57:33 nerd-gw ppp[63956]: tun0: LCP: deflink: State change Opened --> Stopping
...
Nov 22 23:57:48 nerd-gw ppp[63956]: tun0: IPCP: myaddr 118.209.4.239 hisaddr = 10.1.0.1

Nov 23 09:15:00: Firmware reset

Nov 23 09:15:00 nerd-gw ppp[63956]: tun0: Phase: deflink: read (0): Got zero bytes
Nov 23 09:15:00 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Nov 23 09:15:00 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Nov 23 09:15:00 nerd-gw ppp[63956]: tun0: CCP: deflink: State change Stopped --> Closed
...
Nov 23 09:15:43 nerd-gw ppp[63956]: tun0: IPCP: myaddr 121.44.40.190 hisaddr = 10.1.0.1

Nov 28 17:46:13: Popped modem

Extremely poor connection quality, popped modem.

Nov 28 15:43:13 nerd-gw fstats: +CGREG  1  81E3  8FC8F2E
Nov 28 15:43:15 nerd-gw fstats: +CGREG  1  81E3  8FC48E8
Nov 28 15:43:31 nerd-gw fstats: +CGREG  2
Nov 28 15:43:31 nerd-gw fstats: +CGREG  1  81E3  8FC48E8
Nov 28 15:44:30 nerd-gw fstats: +CGREG  1  81E3  8FC8E4A
Nov 28 15:44:38 nerd-gw fstats: +CGREG  1  81E3  8FC48E8
Nov 28 15:46:08 nerd-gw fstats: +CGREG  1  81E3  8FC8E4A
Nov 28 15:46:48 nerd-gw fstats: +CGREG  1  81E3  8FC48E8
Nov 28 15:47:07 nerd-gw fstats: +CGREG  1  81E3  8FC8E4A
Nov 28 15:47:32 nerd-gw fstats: +CGREG  1  81E3  8FC48E8
Nov 28 16:25:01 nerd-gw fstats: +CGREG  1  81E3  142
Nov 28 16:25:49 nerd-gw fstats: +CGREG  1  F40  8FC48E8
Nov 28 16:26:02 nerd-gw fstats: +CGREG  2
Nov 28 16:26:19 nerd-gw fstats: +CGREG  1  81E3  8FC48E8
Nov 28 16:26:19 nerd-gw fstats: +CGREG  1  81E3  8FC48E8
(yes, two identical messages)
Nov 28 17:46:13 nerd-gw ppp[63956]: tun0: Phase: deflink: read (0): Got zero bytes
Nov 28 17:46:13 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Nov 28 17:46:13 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
...
Nov 28 17:46:55 nerd-gw ppp[63956]: tun0: IPCP: myaddr 121.44.95.87 hisaddr = 10.1.0.1

Nov 28 18:11:57: Remote disconnect

Nov 28 18:11:57 nerd-gw fstats: +CGREG  2
Nov 28 18:11:57 nerd-gw fstats: +CGREG  1  81E3  8FC48E8
Nov 29 01:09:17 nerd-gw ppp[63956]: tun0: LCP: deflink: RecvTerminateReq(3) state = Opened
Nov 29 01:09:17 nerd-gw ppp[63956]: tun0: LCP: deflink: LayerDown
Nov 29 01:09:17 nerd-gw ppp[63956]: tun0: LCP: deflink: SendTerminateAck(3) state = Opened
Nov 29 01:09:17 nerd-gw ppp[63956]: tun0: LCP: deflink: State change Opened --> Stopping
...
Nov 29 01:09:35 nerd-gw ppp[63956]: tun0: IPCP: myaddr 118.209.89.215 hisaddr = 10.1.0.1

Nov 29 09:55:42: Remote disconnect

Nov 29 09:55:42 nerd-gw ppp[63956]: tun0: LCP: deflink: RecvTerminateReq(7) state = Opened
Nov 29 09:55:42 nerd-gw ppp[63956]: tun0: LCP: deflink: LayerDown
Nov 29 09:55:42 nerd-gw ppp[63956]: tun0: LCP: deflink: SendTerminateAck(7) state = Opened
Nov 29 09:55:42 nerd-gw ppp[63956]: tun0: LCP: deflink: State change Opened --> Stopping
...
Nov 29 09:56:00 nerd-gw ppp[63956]: tun0: IPCP: myaddr 118.209.59.55 hisaddr = 10.1.0.1

Dec 3 19:21:43: Loss of connectivity, process manually restarted

Dec  3 18:02:27 nerd-gw fstats: +CGREG  1  81E3  8FC8F2E
Dec  3 18:02:32 nerd-gw fstats: +CGREG  1  81E3  142
Dec  3 18:02:49 nerd-gw fstats: +CGREG  1  F40  8FC48E8
Dec  3 18:02:59 nerd-gw fstats: +CGREG  1  81E3  8FC8F2E
Dec  3 18:03:01 nerd-gw fstats: +CGREG  1  81E3  8FC48E8
Dec  3 18:03:01 nerd-gw fstats: +CGREG  2
Dec  3 18:04:17 nerd-gw fstats: +CGREG  1  81E3  8FC48E8
Dec  3 18:04:18 nerd-gw fstats: +CGREG  1  81E3  8FC48E8
Dec  3 18:04:57 nerd-gw fstats: +CGREG  2
Dec  3 18:04:57 nerd-gw fstats: +CGREG  1  81E3  8FC48E8
...
Dec  3 19:21:43 nerd-gw ppp[1663]: tun0: Phase: Signal 15, terminate.
...
Dec  3 19:22:36 nerd-gw ppp[8147]: tun0: IPCP: myaddr 118.209.5.232 hisaddr = 10.1.0.1
Dec  3 19:40:00 nerd-gw fstats: +CGREG  1  81E3  8FC8F2E

Dec 4 10:12:17: Remote disconnect

Dec  4 10:12:17 nerd-gw ppp[8147]: tun0: LCP: deflink: RecvTerminateReq(7) state = Opened
Dec  4 10:12:17 nerd-gw ppp[8147]: tun0: LCP: deflink: LayerDown
Dec  4 10:12:17 nerd-gw ppp[8147]: tun0: LCP: deflink: SendTerminateAck(7) state = Opened
Dec  4 10:12:17 nerd-gw ppp[8147]: tun0: LCP: deflink: State change Opened --> Stopping
...
Dec  4 10:12:35 nerd-gw ppp[8147]: tun0: IPCP: myaddr 118.209.78.177 hisaddr = 10.1.0.1

Dec 4 16:46:09: Firmware reset

Dec  4 16:46:09 nerd-gw ppp[8147]: tun0: Phase: deflink: read (0): Got zero bytes
Dec  4 16:46:09 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Dec  4 16:46:09 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Dec  4 16:46:09 nerd-gw ppp[8147]: tun0: CCP: deflink: State change Stopped --> Closed
...
Dec  4 16:46:51 nerd-gw ppp[8147]: tun0: IPCP: myaddr 118.209.92.36 hisaddr = 10.1.0.1

Dec 5 11:08:57: Firmware reset

Dec  5 11:08:57 nerd-gw ppp[8147]: tun0: Phase: deflink: read (0): Got zero bytes
Dec  5 11:08:57 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Dec  5 11:08:57 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Dec  5 11:08:57 nerd-gw ppp[8147]: tun0: CCP: deflink: State change Stopped --> Closed
Dec  5 11:08:57 nerd-gw ppp[8147]: tun0: CCP: deflink: State change Closed --> Initial
...
Dec  5 11:09:40 nerd-gw ppp[8147]: tun0: IPCP: myaddr 121.44.87.69 hisaddr = 10.1.0.1

Dec 6 04:24:50: Remote disconnect

Dec  6 04:24:50 nerd-gw ppp[8147]: tun0: LCP: deflink: RecvTerminateReq(3) state = Opened
Dec  6 04:24:50 nerd-gw ppp[8147]: tun0: LCP: deflink: LayerDown
Dec  6 04:24:50 nerd-gw ppp[8147]: tun0: LCP: deflink: SendTerminateAck(3) state = Opened
Dec  6 04:24:50 nerd-gw ppp[8147]: tun0: LCP: deflink: State change Opened --> Stopping
...
Dec  6 04:25:08 nerd-gw ppp[8147]: tun0: IPCP: myaddr 118.209.24.77 hisaddr = 10.1.0.1

Dec 7 16:41:31: Remote disconnect

Dec  7 16:41:31 nerd-gw ppp[8147]: tun0: LCP: deflink: RecvTerminateReq(7) state = Opened
Dec  7 16:41:31 nerd-gw ppp[8147]: tun0: LCP: deflink: LayerDown
Dec  7 16:41:31 nerd-gw ppp[8147]: tun0: LCP: deflink: SendTerminateAck(7) state = Opened
...
Dec  7 16:41:49 nerd-gw ppp[8147]: tun0: IPCP: myaddr 118.209.9.193 hisaddr = 10.1.0.1

Dec 8 16:43:39: Firmware reset

Dec  8 16:43:39 nerd-gw fstats: +CGREG  1  81E3  8FC8F2E
Dec  8 16:44:08 nerd-gw ppp[8147]: tun0: Phase: deflink: read (0): Got zero bytes
Dec  8 16:44:08 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Dec  8 16:44:08 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
...
Dec  8 16:44:51 nerd-gw ppp[8147]: tun0: IPCP: myaddr 121.44.107.78 hisaddr = 10.1.0.1

Dec 16 17:25:58: Firmware reset

Dec 16 17:25:58 nerd-gw ppp[8147]: tun0: Phase: deflink: read (0): Got zero bytes
Dec 16 17:25:58 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Dec 16 17:25:58 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
...
Dec 16 17:26:40 nerd-gw ppp[8147]: tun0: IPCP: myaddr 118.209.17.252 hisaddr = 10.1.0.1

Dec 17 09:27:00: Remote disconnect

Dec 17 09:27:00 nerd-gw ppp[8147]: tun0: LCP: deflink: RecvTerminateReq(3) state = Opened
Dec 17 09:27:00 nerd-gw ppp[8147]: tun0: LCP: deflink: LayerDown
Dec 17 09:27:00 nerd-gw ppp[8147]: tun0: LCP: deflink: SendTerminateAck(3) state = Opened
...
Dec 17 09:27:19 nerd-gw ppp[8147]: tun0: IPCP: myaddr 118.209.67.56 hisaddr = 10.1.0.1

Dec 19 11:34:56: Firmware reset

Dec 19 11:34:56 nerd-gw ppp[8147]: tun0: Phase: deflink: read (0): Got zero bytes
Dec 19 11:34:56 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Dec 19 11:34:56 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
...
Dec 19 11:35:39 nerd-gw ppp[8147]: tun0: IPCP: myaddr 121.44.56.41 hisaddr = 10.1.0.1

Dec 20 10:48:36: Power fail recovery

Dec 20 10:48:36 nerd-gw ppp[1705]: tun0: IPCP: myaddr 121.44.76.157 hisaddr = 10.1.0.1

Dec 24 09:57:04: Power fail recovery

Dec 24 09:57:04 nerd-gw ppp[1673]: tun0: IPCP: myaddr 121.45.161.250 hisaddr = 10.1.0.1

Dec 24 14:54:53: Firware reset

Dec 24 14:54:53 nerd-gw ppp[1673]: tun0: Phase: deflink: read (0): Got zero bytes
Dec 24 14:54:53 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Dec 24 14:54:53 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
...
Dec 24 14:55:36 nerd-gw ppp[1673]: tun0: IPCP: myaddr 118.209.40.165 hisaddr = 10.1.0.1

Dec 28 17:18:11: Firmware reset

Dec 28 17:18:11 nerd-gw ppp[1673]: tun0: Phase: deflink: read (0): Got zero bytes
Dec 28 17:18:11 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Dec 28 17:18:11 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
...
Dec 28 17:18:54 nerd-gw ppp[1673]: tun0: IPCP: myaddr 118.209.110.247 hisaddr = 10.1.0.1

Dec 31 14:12:35: GPRS mode

Dec 31 14:12:35 nerd-gw fstats: +CGREG  1  81E3  8FC8E66
Dec 31 14:12:41 nerd-gw fstats: +CGREG  1  81E3  142
Dec 31 14:14:34 nerd-gw ppp[1673]: tun0: Phase: Signal 15, terminate.
...
Dec 31 14:15:34 nerd-gw ppp[21024]: tun0: IPCP: myaddr 121.44.78.81 hisaddr = 10.1.0.1
Dec 31 14:17:46 nerd-gw fstats: +CGREG  1  F40  8FC48E8
...
64 bytes from 203.10.76.45: icmp_seq=74 ttl=54 time=88170.030 ms
...
Dec 31 14:17:52 nerd-gw fstats: +CGREG  2
Dec 31 14:19:14 nerd-gw fstats: +CGREG  1  81E3  8FC48E8

); /* >>> */ ?>

      <p>
After restarting <i>ppp</i>, it came back still in GPRS mode, but quickly changed to
UMTS/HSPA.
      </p>

      <h3>
Dec 31 15:59:19: GPRS mode
      </h3>

      <?php literaltext (<<< EOS
Dec 31 15:59:19 nerd-gw fstats: +CGREG  1  81E3  8FC48E8
Dec 31 15:59:40 nerd-gw fstats: +CGREG  1  81E3  142
Dec 31 15:59:47 nerd-gw fstats: +CGREG  1  F40  142

Continued until 16:10. Popped modem.

Dec 31 16:15:08 nerd-gw ppp[21024]: tun0: IPCP: myaddr 121.44.70.139 hisaddr = 10.1.0.1

RSSI 4, rose to 7.

Jan 7 14:07:16: Fallback to GPRS

This appears to be due to very poor signal strengths.

1357527997 597366 60 6 4 1300268746 2472774859   20129   13417 # Mon Jan  7 14:06:37 2013
Jan  7 14:07:09 nerd-gw fstats: +CGREG  1  81E3  8FC8E66
Jan  7 14:07:16 nerd-gw fstats: +CGREG  1  81E3  142
1357528057 597426 60 2 17 1300273366 2472778991    4620    4132 # Mon Jan  7 14:07:37 2013
Jan  7 14:30:49 nerd-gw fstats: +CGREG  2
Jan  7 14:31:06 nerd-gw fstats: +CGREG  2
<i>Disconnect antenna, reconnect</i>
Jan  7 14:31:18 nerd-gw fstats: +CGREG  1  81E3  8FC48E8
Jan  7 14:31:31 nerd-gw fstats: +CGREG  2
Jan  7 14:31:33 nerd-gw fstats: +CGREG  2
Jan  7 14:31:33 nerd-gw fstats: +CGREG  1  F40  142
Jan  7 14:32:19 nerd-gw fstats: +CGREG  2
Jan  7 14:32:25 nerd-gw fstats: +CGREG  1  F40  142
<i>Restart ppp</i>
Jan  7 14:32:55 nerd-gw ppp[21024]: tun0: Phase: Signal 15, terminate.
Jan  7 14:32:55 nerd-gw ppp[21024]: tun0: IPCP: deflink: LayerDown: 121.44.70.139
...
Jan  7 14:33:41 nerd-gw ppp[39895]: tun0: IPCP: deflink: LayerUp.
Jan  7 14:33:41 nerd-gw ppp[39895]: tun0: IPCP: myaddr 118.209.41.119 hisaddr = 10.1.0.1
<i>Still GPRS: pop modem</i>
Jan  7 14:34:10 nerd-gw kernel: umass0: at uhub0, port 1, addr 2 (disconnected)
Jan  7 14:34:10 nerd-gw kernel: (cd0:umass-sim0:0:0:0): lost device
Jan  7 14:34:10 nerd-gw kernel: (cd0:umass-sim0:0:0:0): removing device entry
Jan  7 14:34:10 nerd-gw kernel: umass1: at uhub0, port 1, addr 2 (disconnected)
...
Jan  7 14:34:52 nerd-gw ppp[39895]: tun0: IPCP: myaddr 121.44.110.7 hisaddr = 10.1.0.1

Back on UMTS/HSPA, but signal strengths drop as low a -109 dBm (2).

Jan 8 17:22:56: Firmware reset

Jan  8 17:22:56 nerd-gw ppp[39895]: tun0: Phase: deflink: read (0): Got zero bytes
Jan  8 17:22:56 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Jan  8 17:22:56 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Jan  8 17:22:56 nerd-gw ppp[39895]: tun0: CCP: deflink: State change Stopped --> Closed
Jan  8 17:22:56 nerd-gw ppp[39895]: tun0: CCP: deflink: State change Closed --> Initial
Jan  8 17:22:56 nerd-gw ppp[39895]: tun0: LCP: deflink: LayerDown
...
Jan  8 17:23:38 nerd-gw ppp[39895]: tun0: IPCP: myaddr 121.44.49.22 hisaddr = 10.1.0.1

Jan 8 19:20:17: Firmware reset

Jan  8 19:20:17 nerd-gw fstats: +CGREG  1  81E3  8FC48E8
Jan  8 19:20:55 nerd-gw ppp[39895]: tun0: Phase: deflink: read (0): Got zero bytes
Jan  8 19:20:55 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Jan  8 19:20:55 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Jan  8 19:20:55 nerd-gw ppp[39895]: tun0: CCP: deflink: State change Stopped --> Closed
...
Jan  8 19:21:37 nerd-gw ppp[39895]: tun0: IPCP: myaddr 118.209.12.115 hisaddr = 10.1.0.1

Jan 8 20:57: Firmware reset

Jan  8 20:57:01 nerd-gw ppp[39895]: tun0: Phase: deflink: read (0): Got zero bytes
Jan  8 20:57:01 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Jan  8 20:57:01 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Jan  8 20:57:01 nerd-gw ppp[39895]: tun0: CCP: deflink: State change Stopped --> Closed
...
Jan  8 20:57:43 nerd-gw ppp[39895]: tun0: IPCP: myaddr 118.209.46.188 hisaddr = 10.1.0.1

Jan 9 10:02:52: Firmware reset

Jan  9 10:02:52 nerd-gw ppp[39895]: tun0: Phase: deflink: read (0): Got zero bytes
Jan  9 10:02:52 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Jan  9 10:02:52 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Jan  9 10:02:52 nerd-gw ppp[39895]: tun0: CCP: deflink: State change Stopped --> Closed
...
Jan  9 10:03:34 nerd-gw ppp[39895]: tun0: IPCP: myaddr 118.209.121.4 hisaddr = 10.1.0.1

Jan 10 13:17:35: GPRS fallback, modem popped

Jan 10 13:12:44 nerd-gw fstats: +CGREG  1  81E3  8FC48E8
Jan 10 13:17:35 nerd-gw fstats: +CGREG  1  81E3  142
Jan 10 13:28:36 nerd-gw ppp[39895]: tun0: Phase: deflink: read (0): Got zero bytes
Jan 10 13:28:36 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Jan 10 13:28:36 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Jan 10 13:28:36 nerd-gw ppp[39895]: tun0: CCP: deflink: State change Stopped --> Closed
Jan 10 13:28:36 nerd-gw ppp[39895]: tun0: CCP: deflink: State change Closed --> Initial
...
Jan 10 13:29:18 nerd-gw ppp[39895]: tun0: IPCP: myaddr 118.209.80.20 hisaddr = 10.1.0.1

Jan 10 13:49:31: GPRS fallback

Jan 10 13:49:31 nerd-gw fstats: +CGREG  1  81E3  8FC48E8
Jan 10 13:54:20 nerd-gw fstats: +CGREG  1  81E3  142
...
Jan 10 13:58:18 nerd-gw ppp[48082]: tun0: IPCP: myaddr 118.209.96.134 hisaddr = 10.1.0.1
Still GPRS, pop modem
Jan 10 13:59:45 nerd-gw ppp[48082]: tun0: IPCP: myaddr 121.45.162.145 hisaddr = 10.1.0.1

Jan 11 00:27:42: Remote disconnect

Jan 11 00:27:42 nerd-gw ppp[48082]: tun0: LCP: deflink: RecvTerminateReq(3) state = Opened
Jan 11 00:27:42 nerd-gw ppp[48082]: tun0: LCP: deflink: LayerDown
Jan 11 00:27:42 nerd-gw ppp[48082]: tun0: LCP: deflink: SendTerminateAck(3) state = Opened
Jan 11 00:27:42 nerd-gw ppp[48082]: tun0: LCP: deflink: State change Opened --> Stopping
...
Jan 11 00:28:50 nerd-gw ppp[48082]: tun0: IPCP: myaddr 121.44.76.32 hisaddr = 10.1.0.1

Jan 11 2013, 16:01:18: Firmware reset

Jan 11 16:01:18 nerd-gw ppp[48082]: tun0: Phase: deflink: read (0): Got zero bytes
Jan 11 16:01:18 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Jan 11 16:01:18 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
...
Jan 11 16:02:00 nerd-gw ppp[48082]: tun0: IPCP: myaddr 121.44.58.168 hisaddr = 10.1.0.1

Jan 12 2013, 16:23:24: Firmware reset

Jan 12 16:23:24 nerd-gw ppp[48082]: tun0: Phase: deflink: read (0): Got zero bytes
Jan 12 16:23:24 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Jan 12 16:23:24 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Jan 12 16:23:24 nerd-gw ppp[48082]: tun0: CCP: deflink: State change Stopped --> Closed
...
Jan 12 16:24:07 nerd-gw ppp[48082]: tun0: IPCP: myaddr 118.209.103.134 hisaddr = 10.1.0.1

Jan 15 2013, 10:47:52: Remove disconnect

Jan 15 10:47:52 nerd-gw ppp[48082]: tun0: LCP: deflink: RecvTerminateReq(3) state = Opened
Jan 15 10:47:52 nerd-gw ppp[48082]: tun0: LCP: deflink: LayerDown
Jan 15 10:47:52 nerd-gw ppp[48082]: tun0: LCP: deflink: SendTerminateAck(3) state = Opened
Jan 15 10:47:52 nerd-gw ppp[48082]: tun0: LCP: deflink: State change Opened --> Stopping
Jan 15 10:47:52 nerd-gw ppp[48082]: tun0: CCP: deflink: State change Stopped --> Closed
...
Jan 15 10:48:09 nerd-gw ppp[48082]: tun0: IPCP: myaddr 118.209.67.16 hisaddr = 10.1.0.1

15 Jan 2013, 16:43: Really poor connectivity

Jan 15 16:28:07 nerd-gw fstats: Cell found: -> 8fc8e4a a2d729f
Jan 15 16:28:43 nerd-gw fstats: Cell change: 81e3 8fc8e4a (3) -> 81e3 8fc48e8
Jan 15 16:29:02 nerd-gw fstats: RSSI: 3
Jan 15 16:33:35 nerd-gw fstats: Cell lost: 81e3 8fc48e8 (3)
Jan 15 16:33:36 nerd-gw fstats: Cell lost: 0 8fc48e8 (3)
Jan 15 16:33:36 nerd-gw fstats: Cell found: -> 8fc8e4a bd4ac38
Jan 15 16:34:02 nerd-gw fstats: RSSI: 3
Jan 15 16:34:25 nerd-gw ntpd[21264]: kernel time sync status change 2001
Jan 15 16:35:02 nerd-gw fstats: Cell change: 81e3 8fc8e4a (3) -> 81e3 8fc8e66
Jan 15 16:35:02 nerd-gw fstats: RSSI: 3
Jan 15 16:35:04 nerd-gw fstats: Low RSSI: 0
Jan 15 16:35:06 nerd-gw fstats: Low RSSI: 2
Jan 15 16:35:07 nerd-gw fstats: Cell lost: 81e3 8fc8e66 (2)
Jan 15 16:35:07 nerd-gw fstats: Cell lost: 0 8fc8e66 (2)
Jan 15 16:35:07 nerd-gw fstats: Cell found: -> 8fc8e4a bd525fb
Jan 15 16:35:32 nerd-gw fstats: Cell change: 81e3 8fc8e4a (2) -> 81e3 8fc48e8
Jan 15 16:35:36 nerd-gw fstats: RSSI: 5
Jan 15 16:36:31 nerd-gw fstats: Cell lost: 81e3 8fc48e8 (5)
Jan 15 16:36:32 nerd-gw fstats: Cell lost: 0 8fc48e8 (5)
Jan 15 16:36:32 nerd-gw fstats: Cell found: -> 8fc8e4a 0
Jan 15 16:36:36 nerd-gw fstats: RSSI: 5
Jan 15 16:39:21 nerd-gw fstats: Cell change: 81e3 8fc8e4a (5) -> 81e3 8fc48e8
Jan 15 16:39:27 nerd-gw ppp[48082]: tun0: Warning: nat_LayerPull: Problem with IP header length (3893)
Jan 15 16:39:27 nerd-gw ppp[48082]: tun0: Warning: nat_LayerPull: Problem with IP header length
(3893)
<i>Popped modem</i>
<i>GPRS connect</i>
Jan 15 16:44:41 nerd-gw fstats: Cell found: -> 142 0
Jan 15 16:44:49 nerd-gw ppp[48082]: tun0: IPCP: myaddr 121.44.127.146 hisaddr = 10.1.0.1

Much playing around, rebooted machine, checked all connections. Marginally better, probably not due to what I did.

Jan 15 17:04:27 nerd-gw fstats: Cell change: 81e3 8fc48e8 (5) -> 81e3 8fc8e4a
<i>(nothing comes through)</i>
Jan 15 17:04:53 nerd-gw fstats: RSSI: 5
Jan 15 17:05:38 nerd-gw fstats: Cell change: 81e3 8fc8e4a (5) -> 81e3 8fc48e8
<i>(all backed up pings)</i>
Jan 15 17:05:53 nerd-gw fstats: RSSI: 5

Looks like a rogue cell:

ping -i 5 www
PING www.lemis.com (203.10.76.45): 56 data bytes
64 bytes from 203.10.76.45: icmp_seq=0 ttl=55 time=88.779 ms
64 bytes from 203.10.76.45: icmp_seq=1 ttl=55 time=90.475 ms
64 bytes from 203.10.76.45: icmp_seq=2 ttl=55 time=92.566 ms
64 bytes from 203.10.76.45: icmp_seq=3 ttl=55 time=84.671 ms
64 bytes from 203.10.76.45: icmp_seq=4 ttl=55 time=106.756 ms
64 bytes from 203.10.76.45: icmp_seq=5 ttl=55 time=108.852 ms
64 bytes from 203.10.76.45: icmp_seq=6 ttl=55 time=130.933 ms
Jan 15 17:53:43 nerd-gw fstats: Low RSSI: 1
Jan 15 17:53:59 nerd-gw fstats: Cell lost: 81e3 8fc48e8 (4)
Jan 15 17:53:59 nerd-gw fstats: Cell lost: 0 8fc48e8 (4)
Jan 15 17:53:59 nerd-gw fstats: Cell found: -> 8fc8e4a 152ddcd
Jan 15 17:54:45 nerd-gw fstats: RSSI: 4
Jan 15 17:54:57 nerd-gw fstats: Cell change: 81e3 8fc8e4a (4) -> 81e3 8fc48e8
64 bytes from 203.10.76.45: icmp_seq=10 ttl=55 time=59517.631 ms
64 bytes from 203.10.76.45: icmp_seq=11 ttl=55 time=54619.341 ms
64 bytes from 203.10.76.45: icmp_seq=12 ttl=55 time=49707.053 ms
64 bytes from 203.10.76.45: icmp_seq=13 ttl=55 time=44806.813 ms
64 bytes from 203.10.76.45: icmp_seq=14 ttl=55 time=39902.472 ms
64 bytes from 203.10.76.45: icmp_seq=15 ttl=55 time=34996.180 ms
64 bytes from 203.10.76.45: icmp_seq=16 ttl=55 time=30225.827 ms
64 bytes from 203.10.76.45: icmp_seq=17 ttl=55 time=25559.408 ms
64 bytes from 203.10.76.45: icmp_seq=18 ttl=55 time=20836.019 ms
64 bytes from 203.10.76.45: icmp_seq=19 ttl=55 time=16025.678 ms
64 bytes from 203.10.76.45: icmp_seq=20 ttl=55 time=11336.282 ms
64 bytes from 203.10.76.45: icmp_seq=21 ttl=55 time=6848.768 ms
64 bytes from 203.10.76.45: icmp_seq=22 ttl=55 time=1959.468 ms
64 bytes from 203.10.76.45: icmp_seq=23 ttl=55 time=95.635 ms
64 bytes from 203.10.76.45: icmp_seq=24 ttl=55 time=97.729 ms
64 bytes from 203.10.76.45: icmp_seq=25 ttl=55 time=89.836 ms
64 bytes from 203.10.76.45: icmp_seq=26 ttl=55 time=81.938 ms
64 bytes from 203.10.76.45: icmp_seq=27 ttl=55 time=83.044 ms
64 bytes from 203.10.76.45: icmp_seq=28 ttl=55 time=3585.194 ms
Jan 15 17:55:45 nerd-gw fstats: RSSI: 4
64 bytes from 203.10.76.45: icmp_seq=32 ttl=55 time=82.545 ms
64 bytes from 203.10.76.45: icmp_seq=33 ttl=55 time=84.627 ms
64 bytes from 203.10.76.45: icmp_seq=34 ttl=55 time=75.730 ms
64 bytes from 203.10.76.45: icmp_seq=35 ttl=55 time=87.775 ms
64 bytes from 203.10.76.45: icmp_seq=36 ttl=55 time=89.919 ms
64 bytes from 203.10.76.45: icmp_seq=37 ttl=55 time=81.030 ms
64 bytes from 203.10.76.45: icmp_seq=38 ttl=55 time=92.130 ms
64 bytes from 203.10.76.45: icmp_seq=39 ttl=55 time=124.194 ms
64 bytes from 203.10.76.45: icmp_seq=40 ttl=55 time=8714.642 ms
64 bytes from 203.10.76.45: icmp_seq=41 ttl=55 time=3742.397 ms
64 bytes from 203.10.76.45: icmp_seq=42 ttl=55 time=179.464 ms
64 bytes from 203.10.76.45: icmp_seq=43 ttl=55 time=191.562 ms
64 bytes from 203.10.76.45: icmp_seq=44 ttl=55 time=113.694 ms
64 bytes from 203.10.76.45: icmp_seq=45 ttl=55 time=114.798 ms
64 bytes from 203.10.76.45: icmp_seq=46 ttl=55 time=126.882 ms
64 bytes from 203.10.76.45: icmp_seq=47 ttl=55 time=147.979 ms
64 bytes from 203.10.76.45: icmp_seq=48 ttl=55 time=99.103 ms
64 bytes from 203.10.76.45: icmp_seq=49 ttl=55 time=91.196 ms
64 bytes from 203.10.76.45: icmp_seq=50 ttl=55 time=203.239 ms
64 bytes from 203.10.76.45: icmp_seq=51 ttl=55 time=85.386 ms
64 bytes from 203.10.76.45: icmp_seq=52 ttl=55 time=117.482 ms
64 bytes from 203.10.76.45: icmp_seq=53 ttl=55 time=79.601 ms
64 bytes from 203.10.76.45: icmp_seq=54 ttl=55 time=100.723 ms
64 bytes from 203.10.76.45: icmp_seq=55 ttl=55 time=93.779 ms
64 bytes from 203.10.76.45: icmp_seq=56 ttl=55 time=85.891 ms
64 bytes from 203.10.76.45: icmp_seq=57 ttl=55 time=85.998 ms
64 bytes from 203.10.76.45: icmp_seq=58 ttl=55 time=77.095 ms
64 bytes from 203.10.76.45: icmp_seq=59 ttl=55 time=109.178 ms
64 bytes from 203.10.76.45: icmp_seq=60 ttl=55 time=131.260 ms
64 bytes from 203.10.76.45: icmp_seq=61 ttl=55 time=123.361 ms
Jan 15 17:58:17 nerd-gw fstats: Cell change: 81e3 8fc48e8 (4) -> 81e3 8fc8e4a
Jan 15 17:58:41 nerd-gw fstats: Cell change: 81e3 8fc8e4a (4) -> 81e3 8fc48e8
64 bytes from 203.10.76.45: icmp_seq=62 ttl=55 time=23189.105 ms
64 bytes from 203.10.76.45: icmp_seq=63 ttl=55 time=18251.883 ms
64 bytes from 203.10.76.45: icmp_seq=64 ttl=55 time=13268.672 ms
64 bytes from 203.10.76.45: icmp_seq=65 ttl=55 time=8340.379 ms
64 bytes from 203.10.76.45: icmp_seq=66 ttl=55 time=3677.967 ms
64 bytes from 203.10.76.45: icmp_seq=67 ttl=55 time=87.899 ms
Jan 15 17:58:45 nerd-gw fstats: RSSI: 4
64 bytes from 203.10.76.45: icmp_seq=68 ttl=55 time=119.061 ms
64 bytes from 203.10.76.45: icmp_seq=69 ttl=55 time=455.973 ms
64 bytes from 203.10.76.45: icmp_seq=70 ttl=55 time=358.111 ms
64 bytes from 203.10.76.45: icmp_seq=71 ttl=55 time=619.112 ms
64 bytes from 203.10.76.45: icmp_seq=72 ttl=55 time=747.084 ms
64 bytes from 203.10.76.45: icmp_seq=73 ttl=55 time=88.565 ms
Jan 15 17:59:19 nerd-gw fstats: Cell change: 81e3 8fc48e8 (4) -> 81e3 8fc8e4a
Jan 15 17:59:31 nerd-gw fstats: Cell change: 81e3 8fc8e4a (4) -> 81e3 8fc48e8
64 bytes from 203.10.76.45: icmp_seq=74 ttl=55 time=13470.507 ms
64 bytes from 203.10.76.45: icmp_seq=75 ttl=55 time=8523.245 ms
64 bytes from 203.10.76.45: icmp_seq=76 ttl=55 time=3570.015 ms
64 bytes from 203.10.76.45: icmp_seq=77 ttl=55 time=78.959 ms
64 bytes from 203.10.76.45: icmp_seq=78 ttl=55 time=80.102 ms

17 Jan 2013, 02:55:14: Remote disconnect

Jan 17 02:55:14 nerd-gw ppp[1692]: tun0: LCP: deflink: RecvTerminateReq(3) state = Opened
Jan 17 02:55:14 nerd-gw ppp[1692]: tun0: LCP: deflink: LayerDown
Jan 17 02:55:14 nerd-gw ppp[1692]: tun0: LCP: deflink: SendTerminateAck(3) state = Opened
...
Jan 17 02:55:29 nerd-gw ppp[1692]: tun0: IPCP: myaddr 121.44.56.158 hisaddr = 10.1.0.1

17 Jan 2013, 09:25:20: GPRS fallback

Jan 17 09:25:20 nerd-gw fstats: Cell change: 81e3 8fc48e8 (3) -> 81e3 142
Jan 17 09:25:21 nerd-gw fstats: RSSI: 16
...

Finally got back to HSPA at 10:00.

17 Jan 2013, 13:16:28: Firmware reset

Jan 17 13:16:28 nerd-gw ppp[7099]: tun0: Phase: deflink: read (0): Got zero bytes
Jan 17 13:16:28 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Jan 17 13:16:28 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
`... <i>multiple attempts</i>
Jan 17 13:18:03 nerd-gw ppp[7099]: tun0: IPCP: myaddr 118.209.87.60 hisaddr = 10.1.0.1

17 Jan 2013, 18:02:31: Firmware reset

Jan 17 18:02:31 nerd-gw ppp[7099]: tun0: Phase: deflink: read (0): Got zero bytes
Jan 17 18:02:31 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Jan 17 18:02:31 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
...
Jan 17 18:03:14 nerd-gw ppp[7099]: tun0: IPCP: myaddr 118.209.44.144 hisaddr = 10.1.0.1

18 Jan 2013, 13:15:04: Firmware reset

Jan 18 13:15:04 nerd-gw ppp[7099]: tun0: Phase: deflink: read (0): Got zero bytes
Jan 18 13:15:04 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Jan 18 13:15:04 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
...
Jan 18 13:15:47 nerd-gw ppp[7099]: tun0: IPCP: myaddr 121.44.8.182 hisaddr = 10.1.0.1

20 Jan 2013, 16:21:46: Remote disconnect, unable to reconnect

Jan 20 16:21:46 nerd-gw fstats: Cell change: 81e3 8fc48e8 (4) -> 81e3 8fc8e4a
Jan 20 16:22:08 nerd-gw fstats: RSSI: 4
Jan 20 16:22:19 nerd-gw ppp[7099]: tun0: LCP: deflink: RecvTerminateReq(3) state = Opened
Jan 20 16:22:19 nerd-gw ppp[7099]: tun0: LCP: deflink: LayerDown
Jan 20 16:22:19 nerd-gw ppp[7099]: tun0: LCP: deflink: SendTerminateAck(3) state = Opened
...
Jan 20 16:23:37 nerd-gw ppp[7099]: tun0: IPCP: deflink: SendConfigReq(63) state = Req-Sent
Jan 20 16:23:37 nerd-gw ppp[7099]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Jan 20 16:23:40 nerd-gw ppp[7099]: tun0: IPCP: deflink: LayerFinish.
Jan 20 16:23:40 nerd-gw ppp[7099]: tun0: IPCP: Connect time: 19 secs: 0 octets in, 0 octets out
Jan 20 16:23:40 nerd-gw ppp[7099]: tun0: IPCP: 0 packets in, 0 packets out
Jan 20 16:23:40 nerd-gw ppp[7099]: tun0: IPCP:  total 0 bytes/sec, peak 0 bytes/sec on Sun Jan 20 16:23:21 2013
Jan 20 16:23:40 nerd-gw ppp[7099]: tun0: IPCP: deflink: State change Req-Sent --> Stopped
64 bytes from 192.231.203.2: icmp_seq=6762 ttl=249 time=166.718 ms
64 bytes from 192.231.203.2: icmp_seq=6763 ttl=249 time=282.858 ms
Jan 21 12:13:51 nerd-gw fstats: Cell change: 81e3 8fc48e8 (3) -> 81e3 8fc8e4a
64 bytes from 192.231.203.2: icmp_seq=6767 ttl=249 time=40025.503 ms
64 bytes from 192.231.203.2: icmp_seq=6768 ttl=249 time=30235.887 ms
64 bytes from 192.231.203.2: icmp_seq=6769 ttl=249 time=20256.398 ms
Jan 21 12:14:31 nerd-gw fstats: RSSI: 3
Jan 21 12:14:54 nerd-gw fstats: Cell lost: 81e3 8fc8e4a (3)
Jan 21 12:14:54 nerd-gw fstats: Cell found: -> 81e3 8fc8e4a
Jan 21 12:15:31 nerd-gw fstats: RSSI: 3
Jan 21 12:12:09 nerd-gw ntpd[18331]: ntpd 4.2.4p5-a (1)
Jan 21 12:12:41 nerd-gw fstats: Low RSSI: 0
Jan 21 12:12:43 nerd-gw fstats: Low RSSI: 2
Jan 21 12:12:52 nerd-gw fstats: Cell change: 81e3 8fc8e4a (2) -> 81e3 8fc48e8
64 bytes from 192.231.203.2: icmp_seq=6772 ttl=249 time=-96578.083 ms
64 bytes from 192.231.203.2: icmp_seq=6773 ttl=249 time=-105012.094 ms
64 bytes from 192.231.203.2: icmp_seq=6774 ttl=249 time=-114531.037 ms
64 bytes from 192.231.203.2: icmp_seq=6775 ttl=249 time=-124312.735 ms
64 bytes from 192.231.203.2: icmp_seq=6776 ttl=249 time=-134134.389 ms
64 bytes from 192.231.203.2: icmp_seq=6777 ttl=249 time=-144077.922 ms
64 bytes from 192.231.203.2: icmp_seq=6778 ttl=249 time=-153969.505 ms
64 bytes from 192.231.203.2: icmp_seq=6779 ttl=249 time=-163875.072 ms
64 bytes from 192.231.203.2: icmp_seq=6780 ttl=249 time=-173843.587 ms
64 bytes from 192.231.203.2: icmp_seq=6781 ttl=249 time=41665.517 ms
64 bytes from 192.231.203.2: icmp_seq=6782 ttl=249 time=31709.596 ms
64 bytes from 192.231.203.2: icmp_seq=6783 ttl=249 time=21785.547 ms
64 bytes from 192.231.203.2: icmp_seq=6784 ttl=249 time=11875.483 ms
64 bytes from 192.231.203.2: icmp_seq=6785 ttl=249 time=1975.407 ms
64 bytes from 192.231.203.2: icmp_seq=6786 ttl=249 time=5163.471 ms
64 bytes from 192.231.203.2: icmp_seq=6787 ttl=249 time=165.038 ms
64 bytes from 192.231.203.2: icmp_seq=6788 ttl=249 time=229.713 ms
Jan 21 14:44:57 nerd-gw ppp[18179]: tun0: Phase: deflink: read (0): Got zero bytes
Jan 21 14:44:57 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Jan 21 14:44:57 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Jan 21 14:44:57 nerd-gw ppp[18179]: tun0: CCP: deflink: State change Stopped --> Closed
Jan 21 14:44:57 nerd-gw ppp[18179]: tun0: CCP: deflink: State change Closed --> Initial


Jan 21 14:51:07 nerd-gw ppp[18612]: tun0: IPCP: myaddr 118.209.20.147 hisaddr = 10.1.0.1
Cell 142

Jan 21 14:54:39 nerd-gw ppp[18647]: tun0: IPCP: deflink: LayerUp.
Jan 21 14:54:39 nerd-gw ppp[18647]: tun0: IPCP: myaddr 121.44.102.87 hisaddr = 10.1.0.1
Jan 28 17:17:53 nerd-gw ppp[7029]: tun0: Phase: deflink: read (0): Got zero bytes
Jan 28 17:17:53 nerd-gw kernel: ugen0.2: <HUAWEI Technology> at usbus0 (disconnected)
Jan 28 17:17:53 nerd-gw kernel: u3g0: at uhub0, port 1, addr 2 (disconnected)
Jan 28 17:17:53 nerd-gw ppp[7029]: tun0: CCP: deflink: State change Stopped --> Closed
Jan 28 17:17:53 nerd-gw ppp[7029]: tun0: CCP: deflink: State change Closed --> Initial
...
Jan 28 17:19:28 nerd-gw ppp[7029]: tun0: IPCP: myaddr 118.209.127.139 hisaddr = 10.1.0.1
Jan 29 10:39:35 nerd-gw ppp[7029]: tun0: LCP: deflink: RecvTerminateReq(5) state = Opened
Jan 29 10:39:35 nerd-gw ppp[7029]: tun0: LCP: deflink: LayerDown
Jan 29 10:39:35 nerd-gw ppp[7029]: tun0: LCP: deflink: SendTerminateAck(5) state = Opened
...
Jan 29 10:39:56 nerd-gw ppp[7029]: tun0: IPCP: myaddr 118.209.50.62 hisaddr = 10.1.0.1

Greg's home page Greg's diary Greg's photos Copyright

Valid XHTML 1.0!

$Id: disconnects.php,v 1.6 2013/01/28 23:43:43 grog Exp $