Hi guys,
I'm experiencing random drops in 4G connectivity without successful restore of the connection. The connection drops happen randomly once a month, beside the connection is rocket stable. Maybe the connection drop is triggered by an issue on the carrier side, however, I'd expect that pfSense successfully reconnects afterwards. It seems that it tries without success.
Unfortunately I'm not a wireless expert :( As soon as the device is restarted, the modem connects fine again.
My setup is as follows: PC Engines APU2 with Sierra MC7710 on German Telekom network in a remote location (good coverage).
Please find the ppp log below. If there is anything else required, I'm happy to supply.
Thank you for any input!
Mar 4 05:54:56 pvob ppp: [wan_link0] LCP: no reply to 1 echo request(s)
Mar 4 05:55:06 pvob ppp: [wan_link0] LCP: no reply to 2 echo request(s)
Mar 4 05:55:16 pvob ppp: [wan_link0] LCP: no reply to 3 echo request(s)
Mar 4 05:55:26 pvob ppp: [wan_link0] LCP: no reply to 4 echo request(s)
Mar 4 05:55:36 pvob ppp: [wan_link0] LCP: no reply to 5 echo request(s)
Mar 4 05:55:36 pvob ppp: [wan_link0] LCP: peer not responding to echo requests
Mar 4 05:55:36 pvob ppp: [wan_link0] LCP: state change Opened --> Stopping
Mar 4 05:55:36 pvob ppp: [wan_link0] Link: Leave bundle "wan"
Mar 4 05:55:36 pvob ppp: [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Mar 4 05:55:36 pvob ppp: [wan] IPCP: Close event
Mar 4 05:55:36 pvob ppp: [wan] IPCP: state change Opened --> Closing
Mar 4 05:55:36 pvob ppp: [wan] IPCP: SendTerminateReq #22
Mar 4 05:55:36 pvob ppp: [wan] IPCP: LayerDown
Mar 4 05:55:36 pvob check_reload_status: Rewriting resolv.conf
Mar 4 05:55:36 pvob ppp: [wan] IFACE: Down event
Mar 4 05:55:36 pvob ppp: [wan] IFACE: Rename interface ppp0 to ppp0
Mar 4 05:55:36 pvob ppp: [wan] IPV6CP: Close event
Mar 4 05:55:36 pvob ppp: [wan] IPV6CP: state change Stopped --> Closed
Mar 4 05:55:36 pvob ppp: [wan] IPCP: Down event
Mar 4 05:55:36 pvob ppp: [wan] IPCP: LayerFinish
Mar 4 05:55:36 pvob ppp: [wan] Bundle: No NCPs left. Closing links...
Mar 4 05:55:36 pvob ppp: [wan] IPCP: state change Closing --> Initial
Mar 4 05:55:36 pvob ppp: [wan] IPV6CP: Down event
Mar 4 05:55:36 pvob ppp: [wan] IPV6CP: state change Closed --> Initial
Mar 4 05:55:36 pvob ppp: [wan_link0] LCP: SendTerminateReq #8
Mar 4 05:55:36 pvob ppp: [wan_link0] LCP: LayerDown
Mar 4 05:55:38 pvob ppp: [wan_link0] LCP: SendTerminateReq #9
Mar 4 05:55:40 pvob ppp: [wan_link0] LCP: state change Stopping --> Stopped
Mar 4 05:55:40 pvob ppp: [wan_link0] LCP: LayerFinish
Mar 4 05:55:40 pvob ppp: [wan_link0] Link: DOWN event
Mar 4 05:55:40 pvob ppp: [wan_link0] LCP: Down event
Mar 4 05:55:40 pvob ppp: [wan_link0] LCP: state change Stopped --> Starting
Mar 4 05:55:40 pvob ppp: [wan_link0] LCP: LayerStart
Mar 4 05:55:40 pvob ppp: [wan_link0] Link: reconnection attempt 1 in 4 seconds
Mar 4 05:55:44 pvob ppp: [wan_link0] Link: reconnection attempt 1
Mar 4 05:55:44 pvob ppp: [wan_link0] CHAT: Detected Hayes compatible modem.
Mar 4 05:55:47 pvob ppp: [wan_link0] CHAT: The modem is not responding to "AT&F&C1&D2E0S0=0M1" at ModemCmd: label.
Mar 4 05:55:47 pvob ppp: [wan_link0] MODEM: chat script failed
Mar 4 05:55:47 pvob ppp: [wan_link0] Link: DOWN event
Mar 4 05:55:47 pvob ppp: [wan_link0] LCP: Down event
Mar 4 05:55:47 pvob ppp: [wan_link0] Link: reconnection attempt 2 in 2 seconds
Mar 4 05:55:49 pvob ppp: [wan_link0] Link: reconnection attempt 2
Mar 4 05:56:03 pvob ppp: [wan_link0] CHAT: The modem is not responding to "AT" at ModemCmd: label.
Mar 4 05:56:03 pvob ppp: [wan_link0] MODEM: chat script failed
Mar 4 05:56:03 pvob ppp: [wan_link0] Link: DOWN event
Mar 4 05:56:03 pvob ppp: [wan_link0] LCP: Down event
Mar 4 05:56:03 pvob ppp: [wan_link0] Link: reconnection attempt 3 in 3 seconds
Mar 4 05:56:06 pvob ppp: [wan_link0] Link: reconnection attempt 3
Mar 4 05:56:20 pvob ppp: [wan_link0] CHAT: The modem is not responding to "AT" at ModemCmd: label.
Mar 4 05:56:20 pvob ppp: [wan_link0] MODEM: chat script failed
Mar 4 05:56:20 pvob ppp: [wan_link0] Link: DOWN event
Mar 4 05:56:20 pvob ppp: [wan_link0] LCP: Down event
Mar 4 05:56:20 pvob ppp: [wan_link0] Link: reconnection attempt 4 in 2 seconds
Mar 4 05:56:22 pvob ppp: [wan_link0] Link: reconnection attempt 4
Mar 4 05:56:23 pvob ppp: [wan_link0] CHAT: +CGDCONT=1,"IP","Internet.t-d1.de"
Mar 4 05:56:23 pvob ppp: [wan_link0] CHAT: Detected Hayes compatible modem.
Mar 4 05:56:23 pvob ppp: [wan_link0] CHAT: Dialing server at *99#...
Mar 4 05:56:23 pvob ppp: [wan_link0] CHAT: ATDT*99#
Mar 4 05:56:23 pvob ppp: [wan_link0] CHAT: Connected at 100000000.
Mar 4 05:56:23 pvob ppp: [wan_link0] MODEM: chat script succeeded
Mar 4 05:56:23 pvob ppp: [wan_link0] Link: UP event
Mar 4 05:56:23 pvob ppp: [wan_link0] LCP: Up event
Mar 4 05:56:23 pvob ppp: [wan_link0] LCP: state change Starting --> Req-Sent
Mar 4 05:56:23 pvob ppp: [wan_link0] LCP: SendConfigReq #10
Mar 4 05:56:23 pvob ppp: [wan_link0] ACFCOMP
Mar 4 05:56:23 pvob ppp: [wan_link0] PROTOCOMP
Mar 4 05:56:23 pvob ppp: [wan_link0] ACCMAP 0x000a0000
Mar 4 05:56:23 pvob ppp: [wan_link0] MRU 1500
Mar 4 05:56:23 pvob ppp: [wan_link0] MAGICNUM 0x8f0c9bf1
Mar 4 05:56:23 pvob ppp: [wan_link0] LCP: rec'd Configure Request #11 (Req-Sent)
Mar 4 05:56:23 pvob ppp: [wan_link0] ACCMAP 0x00000000
Mar 4 05:56:23 pvob ppp: [wan_link0] AUTHPROTO CHAP MD5
Mar 4 05:56:23 pvob ppp: [wan_link0] MAGICNUM 0x4f1e7b2c
Mar 4 05:56:23 pvob ppp: [wan_link0] PROTOCOMP
Mar 4 05:56:23 pvob ppp: [wan_link0] ACFCOMP
Mar 4 05:56:23 pvob ppp: [wan_link0] LCP: SendConfigAck #11
Mar 4 05:56:23 pvob ppp: [wan_link0] ACCMAP 0x00000000
Mar 4 05:56:23 pvob ppp: [wan_link0] AUTHPROTO CHAP MD5
Mar 4 05:56:23 pvob ppp: [wan_link0] MAGICNUM 0x4f1e7b2c
Mar 4 05:56:23 pvob ppp: [wan_link0] PROTOCOMP
Mar 4 05:56:23 pvob ppp: [wan_link0] ACFCOMP
Mar 4 05:56:23 pvob ppp: [wan_link0] LCP: state change Req-Sent --> Ack-Sent
Mar 4 05:56:23 pvob ppp: [wan_link0] LCP: rec'd Configure Ack #10 (Ack-Sent)
Mar 4 05:56:23 pvob ppp: [wan_link0] ACFCOMP
Mar 4 05:56:23 pvob ppp: [wan_link0] PROTOCOMP
Mar 4 05:56:23 pvob ppp: [wan_link0] ACCMAP 0x000a0000
Mar 4 05:56:23 pvob ppp: [wan_link0] MRU 1500
Mar 4 05:56:23 pvob ppp: [wan_link0] MAGICNUM 0x8f0c9bf1
Mar 4 05:56:23 pvob ppp: [wan_link0] LCP: state change Ack-Sent --> Opened
Mar 4 05:56:23 pvob ppp: [wan_link0] LCP: auth: peer wants CHAP, I want nothing
Mar 4 05:56:23 pvob ppp: [wan_link0] LCP: LayerUp
Mar 4 05:56:23 pvob ppp: [wan_link0] LCP: rec'd Discard Request #12 (Opened)
Mar 4 05:56:23 pvob ppp: [wan_link0] CHAP: rec'd CHALLENGE #1 len: 35
Mar 4 05:56:23 pvob ppp: [wan_link0] Name: "UMTS_CHAP_SRVR"
Mar 4 05:56:23 pvob ppp: [wan_link0] CHAP: Using authname "t-mobile"
Mar 4 05:56:23 pvob ppp: [wan_link0] CHAP: sending RESPONSE #1 len: 29
Mar 4 05:56:23 pvob ppp: [wan_link0] CHAP: rec'd SUCCESS #1 len: 4
Mar 4 05:56:23 pvob ppp: [wan_link0] LCP: authorization successful
Mar 4 05:56:23 pvob ppp: [wan_link0] Link: Matched action 'bundle "wan" ""'
Mar 4 05:56:23 pvob ppp: [wan_link0] Link: Join bundle "wan"
Mar 4 05:56:23 pvob ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 100000000 bps
Mar 4 05:56:23 pvob ppp: [wan] IPCP: Open event
Mar 4 05:56:23 pvob ppp: [wan] IPCP: state change Initial --> Starting
Mar 4 05:56:23 pvob ppp: [wan] IPCP: LayerStart
Mar 4 05:56:23 pvob ppp: [wan] IPV6CP: Open event
Mar 4 05:56:23 pvob ppp: [wan] IPV6CP: state change Initial --> Starting
Mar 4 05:56:23 pvob ppp: [wan] IPV6CP: LayerStart
Mar 4 05:56:23 pvob ppp: [wan] IPCP: Up event
Mar 4 05:56:23 pvob ppp: [wan] IPCP: state change Starting --> Req-Sent
Mar 4 05:56:23 pvob ppp: [wan] IPCP: SendConfigReq #23
Mar 4 05:56:23 pvob ppp: [wan] IPADDR 0.0.0.0
Mar 4 05:56:23 pvob ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Mar 4 05:56:23 pvob ppp: [wan] IPV6CP: Up event
Mar 4 05:56:23 pvob ppp: [wan] IPV6CP: state change Starting --> Req-Sent
Mar 4 05:56:23 pvob ppp: [wan] IPV6CP: SendConfigReq #31
Mar 4 05:56:24 pvob ppp: [wan] IPCP: rec'd Configure Nak #23 (Req-Sent)
Mar 4 05:56:24 pvob ppp: [wan] IPCP: SendConfigReq #24
Mar 4 05:56:24 pvob ppp: [wan] IPADDR 0.0.0.0
Mar 4 05:56:24 pvob ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Mar 4 05:56:25 pvob ppp: [wan] IPV6CP: SendConfigReq #32
Mar 4 05:56:25 pvob ppp: [wan] IPCP: rec'd Configure Nak #24 (Req-Sent)
Mar 4 05:56:25 pvob ppp: [wan] IPCP: SendConfigReq #25
Mar 4 05:56:25 pvob ppp: [wan] IPADDR 0.0.0.0
Mar 4 05:56:25 pvob ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Mar 4 05:56:26 pvob ppp: [wan] IPCP: rec'd Configure Nak #25 (Req-Sent)
Mar 4 05:56:26 pvob ppp: [wan] IPCP: SendConfigReq #26
Mar 4 05:56:26 pvob ppp: [wan] IPADDR 0.0.0.0
Mar 4 05:56:26 pvob ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Mar 4 05:56:27 pvob ppp: [wan] IPV6CP: SendConfigReq #33
Mar 4 05:56:27 pvob ppp: [wan] IPCP: rec'd Configure Nak #26 (Req-Sent)
Mar 4 05:56:27 pvob ppp: [wan] IPCP: SendConfigReq #27
Mar 4 05:56:27 pvob ppp: [wan] IPADDR 0.0.0.0
Mar 4 05:56:27 pvob ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Mar 4 05:56:27 pvob ppp: [wan] IPCP: SendConfigReq #27
Mar 4 05:56:27 pvob ppp: [wan] IPADDR 0.0.0.0
Mar 4 05:56:27 pvob ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Mar 4 05:56:28 pvob ppp: [wan] IPCP: rec'd Configure Nak #27 (Req-Sent)
Mar 4 05:56:28 pvob ppp: [wan] IPCP: SendConfigReq #28
Mar 4 05:56:28 pvob ppp: [wan] IPADDR 0.0.0.0
Mar 4 05:56:28 pvob ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Mar 4 05:56:29 pvob ppp: [wan] IPV6CP: SendConfigReq #34
Mar 4 05:56:29 pvob ppp: [wan] IPCP: rec'd Configure Nak #28 (Req-Sent)
Mar 4 05:56:29 pvob ppp: [wan] IPCP: SendConfigReq #29
Mar 4 05:56:29 pvob ppp: [wan] IPADDR 0.0.0.0
Mar 4 05:56:29 pvob ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Mar 4 05:56:30 pvob ppp: [wan] IPCP: rec'd Configure Nak #29 (Req-Sent)
Mar 4 05:56:30 pvob ppp: [wan] IPCP: SendConfigReq #30
Mar 4 05:56:30 pvob ppp: [wan] IPADDR 0.0.0.0
Mar 4 05:56:30 pvob ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Mar 4 05:56:31 pvob ppp: [wan] IPV6CP: SendConfigReq #35
Mar 4 05:56:31 pvob ppp: [wan] IPCP: rec'd Configure Nak #30 (Req-Sent)
Mar 4 05:56:31 pvob ppp: [wan] IPCP: SendConfigReq #31
Mar 4 05:56:31 pvob ppp: [wan] IPADDR 0.0.0.0
Mar 4 05:56:31 pvob ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Mar 4 05:56:32 pvob ppp: [wan] IPCP: rec'd Configure Nak #31 (Req-Sent)
Mar 4 05:56:32 pvob ppp: [wan] IPCP: SendConfigReq #32
Mar 4 05:56:32 pvob ppp: [wan] IPADDR 0.0.0.0
Mar 4 05:56:32 pvob ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Mar 4 05:56:33 pvob ppp: [wan] IPV6CP: SendConfigReq #36
Mar 4 05:56:33 pvob ppp: [wan] IPCP: rec'd Configure Nak #32 (Req-Sent)
Mar 4 05:56:33 pvob ppp: [wan] IPCP: not converging
Mar 4 05:56:33 pvob ppp: [wan] IPCP: parameter negotiation failed
Mar 4 05:56:33 pvob ppp: [wan] IPCP: state change Req-Sent --> Stopped
Mar 4 05:56:33 pvob ppp: [wan] IPCP: LayerFinish
Mar 4 05:56:35 pvob ppp: [wan] IPV6CP: SendConfigReq #37
Mar 4 05:56:37 pvob ppp: [wan] IPV6CP: SendConfigReq #38
Mar 4 05:56:39 pvob ppp: [wan] IPV6CP: SendConfigReq #39
Mar 4 05:56:41 pvob ppp: [wan] IPV6CP: SendConfigReq #40
Mar 4 05:56:43 pvob ppp: [wan] IPV6CP: parameter negotiation failed
Mar 4 05:56:43 pvob ppp: [wan] IPV6CP: state change Req-Sent --> Stopped
Mar 4 05:56:43 pvob ppp: [wan] IPV6CP: LayerFinish
Mar 4 05:56:43 pvob ppp: [wan] Bundle: No NCPs left. Closing links...
Mar 4 05:56:43 pvob ppp: [wan] Bundle: closing link "wan_link0"...
Mar 4 05:56:43 pvob ppp: [wan_link0] Link: CLOSE event
Mar 4 05:56:43 pvob ppp: [wan_link0] LCP: Close event
Mar 4 05:56:43 pvob ppp: [wan_link0] LCP: state change Opened --> Closing
Mar 4 05:56:43 pvob ppp: [wan_link0] Link: Leave bundle "wan"
Mar 4 05:56:43 pvob ppp: [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Mar 4 05:56:43 pvob ppp: [wan] IPCP: Close event
Mar 4 05:56:43 pvob ppp: [wan] IPCP: state change Stopped --> Closed
Mar 4 05:56:43 pvob ppp: [wan] IPV6CP: Close event
Mar 4 05:56:43 pvob ppp: [wan] IPV6CP: state change Stopped --> Closed
Mar 4 05:56:43 pvob ppp: [wan] IPCP: Down event
Mar 4 05:56:43 pvob ppp: [wan] IPCP: state change Closed --> Initial
Mar 4 05:56:43 pvob ppp: [wan] IPV6CP: Down event
Mar 4 05:56:43 pvob ppp: [wan] IPV6CP: state change Closed --> Initial
Mar 4 05:56:43 pvob ppp: [wan_link0] LCP: SendTerminateReq #11
Mar 4 05:56:43 pvob ppp: [wan_link0] LCP: LayerDown
Mar 4 05:56:43 pvob ppp: [wan_link0] LCP: rec'd Terminate Ack #11 (Closing)
Mar 4 05:56:43 pvob ppp: [wan_link0] LCP: state change Closing --> Closed
Mar 4 05:56:43 pvob ppp: [wan_link0] LCP: LayerFinish
Mar 4 05:56:43 pvob ppp: [wan_link0] Link: DOWN event
Mar 4 05:56:43 pvob ppp: [wan_link0] LCP: Down event
Mar 4 05:56:43 pvob ppp: [wan_link0] LCP: state change Closed --> Initial
Mar 5 01:01:01 pvob php-cgi: rc.dyndns.update: Dynamic DNS (xxx.xxx.xxx) There was an error trying to determine the public IP for interface - wan (ppp0 ).
Mar 5 06:46:41 pvob syslogd: kernel boot file is /boot/kernel/kernel