Check Accessibility Control
This scenario shows how to configure a cellular interface, enabling the accessibility control mechanism that triggers reconnections when network connectivity is lost.
Test Reconnection By Accessibility Control
Description
In DUT0, the cell0 cellular interface is
configured with a cellular profile for which
accessibility control is enabled. Once connectivity
with Google’s DNS public server has been verified, a
situation where 100% of the packets received by the
network are lost is emulated to verify that the
accessibility control mechanism triggers a reconnection.
Scenario
Step 1: Set the following configuration in DUT0 :
set cellular logging level info set cellular profile CELPROFILE accessibility-control ping address 8.8.8.8 set cellular profile CELPROFILE accessibility-control ping interval 10 set cellular profile CELPROFILE apn movistar.es set controllers cellular wwan0 encrypted-pin U2FsdGVkX1/12w90DPXUdUEhduRCKnoEACMQCCM1+KE= set controllers cellular wwan0 pdp 1 profile CELPROFILE set interfaces cellular cell0 address dhcp set interfaces cellular cell0 phy wwan0 pdp 1 set system login user admin authentication encrypted-password '$6$GSjsCj8gHLv$/VcqU6FLi6CT2Oxn0MJQ2C2tqnRDrYKNF8HIYWJp68nvXvPdFccDsT04.WtigUONbKYrgKg8d6rEs8PjljMkH0'
Step 2: Run command controllers cellular wwan0 pdp 1 show network-data-connection at DUT0 and check if output matches the following regular expressions:
Connection status\s* = connectedShow output
Connection status = connected Traffic channel status = active Max. TX channel rate (bps) = 50000000 Max. RX channel rate (bps) = 300000000 IPv4 address = 81.47.79.35 IPv4 mask = 255.255.255.248 IPv4 gateway = 81.47.79.36 IPv4 primary DNS = 80.58.61.248 IPv4 secondary DNS = 80.58.61.249
Note
Once the cellular interface is configured, it can be verified that there is network connectivity by pinging Google's DNS public server.
Step 3: Ping IP address 8.8.8.8 from DUT0:
admin@DUT0$ ping 8.8.8.8 count 1 size 56 timeout 1Show output
PING 8.8.8.8 (8.8.8.8) 56(84) bytes of data. 64 bytes from 8.8.8.8: icmp_seq=1 ttl=116 time=40.7 ms --- 8.8.8.8 ping statistics --- 1 packets transmitted, 1 received, 0% packet loss, time 0ms rtt min/avg/max/mdev = 40.737/40.737/40.737/0.000 ms
Note
The accessibility control is a mechanism where pings are sent at an interval, and to the IP address, configured by the user. The mechanism then waits for a response within this interval. If no response is received before it times out, a second ping is sent and a response is expected within 10 seconds. If there is still no response, two more pings are sent at 5-second intervals. Finally, if there is still no response, the device reconnects to the network.
Note
As a result, you can verify that no reconnection has been recorded in the system logs if you wait long enough.
Step 4: Run command system journal clear at DUT0.
Step 5: Run command system journal show | cat at DUT0 and check if output does not contain the following tokens:
[Device/wwan0] Warning: [PDP#1] Requesting reconnection... [Device/wwan0] Info: WDS[0]: Stopping network connection... [Device/wwan0] Info: [PDP#1] Establishing network connection...Show output
Feb 19 07:44:57.918773 osdx systemd-journald[2142]: Runtime Journal (/run/log/journal/96696b25b9cf4bceb576f9ffeda6fd5d) is 4.5M, max 35.3M, 30.8M free. Feb 19 07:44:57.923192 osdx systemd-journald[2142]: Received client request to rotate journal, rotating. Feb 19 07:44:57.923378 osdx systemd-journald[2142]: Vacuuming done, freed 0B of archived journals from /run/log/journal/96696b25b9cf4bceb576f9ffeda6fd5d. Feb 19 07:44:58.019277 osdx OSDxCLI[13300]: User 'admin' executed a new command: 'system journal clear'. Feb 19 07:45:03.078928 osdx wwan-accessibility-control[14974]: [Core/wwan0/1] Warning: Previous packet pending - ID: 14974, Sequence: 0 Feb 19 07:45:03.078948 osdx wwan-accessibility-control[14974]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 1 - interval reduced to 10 seconds
Note
However, when traffic control is applied, it is possible to prove the 'ping' command is failing because ICMP packets are being dropped.
Step 6: Run command system journal clear at DUT0.
Step 7: Modify the following configuration lines in DUT0 :
set interfaces cellular cell0 traffic control in PLOSS set traffic control PLOSS type network-emulator packet-loss 100
Step 8: Expect a failure in the following command:
Ping IP address 8.8.8.8 from DUT0:
admin@DUT0$ ping 8.8.8.8 count 1 size 56 timeout 1Show output
PING 8.8.8.8 (8.8.8.8) 56(84) bytes of data. --- 8.8.8.8 ping statistics --- 1 packets transmitted, 0 received, 100% packet loss, time 0ms
Note
You can verify that a reconnection has been recorded in the system logs if you wait long enough.
Step 9: Run command system journal show | cat at DUT0 and check if output contains the following tokens:
[Device/wwan0] Warning: [PDP#1] Requesting reconnection... [Device/wwan0] Info: WDS[0]: Stopping network connection... [Device/wwan0] Info: [PDP#1] Establishing network connection...Show output
Feb 19 07:45:40.342154 osdx systemd-journald[2142]: Runtime Journal (/run/log/journal/96696b25b9cf4bceb576f9ffeda6fd5d) is 4.4M, max 35.3M, 30.9M free. Feb 19 07:45:40.346788 osdx systemd-journald[2142]: Received client request to rotate journal, rotating. Feb 19 07:45:40.346973 osdx systemd-journald[2142]: Vacuuming done, freed 0B of archived journals from /run/log/journal/96696b25b9cf4bceb576f9ffeda6fd5d. Feb 19 07:45:40.431380 osdx OSDxCLI[13300]: User 'admin' executed a new command: 'system journal clear'. Feb 19 07:45:40.598962 osdx OSDxCLI[13300]: User 'admin' entered the configuration menu. Feb 19 07:45:40.742761 osdx OSDxCLI[13300]: User 'admin' added a new cfg line: 'set interfaces cellular cell0 traffic control in PLOSS'. Feb 19 07:45:40.863729 osdx OSDxCLI[13300]: User 'admin' added a new cfg line: 'set traffic control PLOSS type network-emulator packet-loss 100'. Feb 19 07:45:40.998171 osdx OSDxCLI[13300]: User 'admin' added a new cfg line: 'show changes'. Feb 19 07:45:41.159710 osdx ubnt-cfgd[15069]: inactive Feb 19 07:45:41.304528 osdx INFO[15079]: FRR daemons did not change Feb 19 07:45:41.464975 osdx (udev-worker)[15098]: ifb.cell0: Could not set Alias=, MACAddress=/MACAddressPolicy=, TransmitQueues=, ReceiveQueues=, TransmitQueueLength=, MTUBytes=, GenericSegmentOffloadMaxBytes= or GenericSegmentOffloadMaxSegments=, ignoring: Operation not supported Feb 19 07:45:41.465064 osdx (udev-worker)[15098]: Network interface NamePolicy= disabled on kernel command line. Feb 19 07:45:41.528091 osdx kernel: Mirror/redirect action on Feb 19 07:45:41.535991 osdx kernel: netem: version 1.3 Feb 19 07:45:41.550824 osdx cfgd[1799]: [13300]Completed change to active configuration Feb 19 07:45:41.578511 osdx systemd[1]: osdx-ledsd.service: Sent signal SIGUSR2 to main process 2463 (osdx-ledsd) on client request. Feb 19 07:45:41.578542 osdx osdx-ledsd[2463]: Config changed, checking config paths Feb 19 07:45:41.582346 osdx OSDxCLI[13300]: User 'admin' committed the configuration. Feb 19 07:45:41.621444 osdx OSDxCLI[13300]: User 'admin' left the configuration menu. Feb 19 07:45:42.852122 osdx OSDxCLI[13300]: User 'admin' executed a new command: 'ping 8.8.8.8 count 1 size 56 timeout 1'. Feb 19 07:45:53.079964 osdx wwan-accessibility-control[14974]: [Core/wwan0/1] Warning: Previous packet pending - ID: 14974, Sequence: 5 Feb 19 07:45:53.079983 osdx wwan-accessibility-control[14974]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 1 - interval reduced to 10 seconds Feb 19 07:46:02.929452 osdx systemd[1]: Starting systemd-tmpfiles-clean.service - Cleanup of Temporary Directories... Feb 19 07:46:02.986104 osdx systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully. Feb 19 07:46:02.986617 osdx systemd[1]: Finished systemd-tmpfiles-clean.service - Cleanup of Temporary Directories. Feb 19 07:46:02.991379 osdx systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dclean.service.mount: Deactivated successfully. Feb 19 07:46:03.080232 osdx wwan-accessibility-control[14974]: [Core/wwan0/1] Warning: Previous packet pending - ID: 14974, Sequence: 6 Feb 19 07:46:03.080250 osdx wwan-accessibility-control[14974]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 2 - interval reduced to 5 seconds Feb 19 07:46:03.205245 osdx OSDxCLI[13300]: User 'admin' executed a new command: 'system journal show | cat'. Feb 19 07:46:04.625305 osdx OSDxCLI[13300]: User 'admin' executed a new command: 'system journal show | cat'. Feb 19 07:46:06.053287 osdx OSDxCLI[13300]: User 'admin' executed a new command: 'system journal show | cat'. Feb 19 07:46:07.485289 osdx OSDxCLI[13300]: User 'admin' executed a new command: 'system journal show | cat'. Feb 19 07:46:08.080494 osdx wwan-accessibility-control[14974]: [Core/wwan0/1] Warning: Previous packet pending - ID: 14974, Sequence: 7 Feb 19 07:46:08.080513 osdx wwan-accessibility-control[14974]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 3 - interval reduced to 5 seconds Feb 19 07:46:08.989301 osdx OSDxCLI[13300]: User 'admin' executed a new command: 'system journal show | cat'. Feb 19 07:46:10.485257 osdx OSDxCLI[13300]: User 'admin' executed a new command: 'system journal show | cat'. Feb 19 07:46:11.985254 osdx OSDxCLI[13300]: User 'admin' executed a new command: 'system journal show | cat'. Feb 19 07:46:13.080774 osdx wwan-accessibility-control[14974]: [Core/wwan0/1] Warning: Previous packet pending - ID: 14974, Sequence: 8 Feb 19 07:46:13.080796 osdx wwan-accessibility-control[14974]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 4 - interval reduced to 5 seconds Feb 19 07:46:13.092599 osdx WARNING[15194]: [wwan0/1] Requesting reconnection... Feb 19 07:46:13.102398 osdx wwan-cli-socket[15196]: [Device/wwan0] Info: wwan-cli-socket reconnect 60000 wwan0 Feb 19 07:46:13.103976 osdx wwan-module[13285]: [Device/wwan0] Info: Reconnecting device... Feb 19 07:46:13.103994 osdx wwan-module[13285]: [Device/wwan0] Warning: [PDP#1] Requesting reconnection... Feb 19 07:46:13.104061 osdx wwan-module[13285]: [Device/wwan0] Info: WDS[0]: Stopping network connection... Feb 19 07:46:13.212388 osdx wwan-module[13285]: [Device/wwan0] Info: WDS[0]: Network connection was stopped! Feb 19 07:46:13.219984 osdx kernel: net _wwan0: link_state 0x1 -> 0x0 Feb 19 07:46:13.220299 osdx wwan-module[13285]: [Device/wwan0] Info: Checking registration state... Feb 19 07:46:13.250298 osdx wwan-accessibility-control[14974]: [Core/wwan0/1] Info: Execution stopped Feb 19 07:46:13.250737 osdx systemd[1]: Stopping wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control... Feb 19 07:46:13.253618 osdx systemd[1]: wwan-accessibility-control@wwan0pdp1.service: Deactivated successfully. Feb 19 07:46:13.254132 osdx systemd[1]: Stopped wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control. Feb 19 07:46:13.270183 osdx INFO[15206]: Stop DHCP client for cell0 Feb 19 07:46:13.279689 osdx dhclient[15211]: Killed old client process Feb 19 07:46:13.340398 osdx wwan-module[13285]: [Device/wwan0] Info: Device registered and PS attached successfully Feb 19 07:46:13.340417 osdx wwan-module[13285]: [Device/wwan0] Info: [PDP#1] Establishing network connection... Feb 19 07:46:13.396279 osdx dhclient[15211]: DHCPRELEASE of 81.47.79.35 on cell0 to 81.47.79.36 port 67 Feb 19 07:46:13.567600 osdx wwan-module[13285]: [Device/wwan0] Info: [PDP#1] Network connection was successfully started: PDH=[1181970000] Feb 19 07:46:13.585468 osdx OSDxCLI[13300]: User 'admin' executed a new command: 'system journal show | cat'. Feb 19 07:46:13.696033 osdx kernel: net _wwan0: link_state 0x0 -> 0x1 Feb 19 07:46:13.911996 osdx kernel: cell0: (slave _wwan0_1): Releasing backup interface Feb 19 07:46:14.011991 osdx kernel: cell0: (slave _wwan0_1): The slave device specified does not support setting the MAC address Feb 19 07:46:14.015997 osdx kernel: cell0: (slave _wwan0_1): making interface the new active one Feb 19 07:46:14.016203 osdx kernel: cell0: (slave _wwan0_1): Enslaving as an active interface with an up link Feb 19 07:46:14.031989 osdx kernel: 8021q: adding VLAN 0 to HW filter on device cell0 Feb 19 07:46:14.035033 osdx INFO[15257]: Start DHCP client for cell0 Feb 19 07:46:14.528399 osdx dhclient[15270]: DHCPDISCOVER on cell0 to 255.255.255.255 port 67 interval 4 Feb 19 07:46:14.533677 osdx dhclient[15270]: DHCPOFFER of 88.31.104.66 from 88.31.104.65 Feb 19 07:46:14.533756 osdx dhclient[15270]: DHCPREQUEST for 88.31.104.66 on cell0 to 255.255.255.255 port 67 Feb 19 07:46:14.535626 osdx dhclient[15270]: DHCPACK of 88.31.104.66 from 88.31.104.65 Feb 19 07:46:14.581622 osdx systemd[1]: Started wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control. Feb 19 07:46:14.597956 osdx wwan-accessibility-control[15282]: [Core/wwan0/1] Info: Starting '/osdx/sbin/wwan-accessibility-control'...
Step 1: Set the following configuration in DUT0 :
set cellular logging level info set cellular profile CELPROFILE accessibility-control ping address 8.8.8.8 set cellular profile CELPROFILE accessibility-control ping interval 10 set cellular profile CELPROFILE apn movistar.es set controllers cellular wwan0 sim select socket_1 set controllers cellular wwan0 sim socket socket_1 encrypted-pin U2FsdGVkX1/FcmRU35CJni76hqgqBUsypP/ZPqdXM1w= set controllers cellular wwan0 sim socket socket_1 pdp 1 profile CELPROFILE set interfaces cellular cell0 address dhcp set interfaces cellular cell0 phy wwan0 pdp 1 set system login user admin authentication encrypted-password '$6$GSjsCj8gHLv$/VcqU6FLi6CT2Oxn0MJQ2C2tqnRDrYKNF8HIYWJp68nvXvPdFccDsT04.WtigUONbKYrgKg8d6rEs8PjljMkH0'
Step 2: Run command controllers cellular wwan0 pdp 1 show network-data-connection at DUT0 and check if output matches the following regular expressions:
Connection status\s* = connectedShow output
Connection status = connected Traffic channel status = active Max. TX channel rate (bps) = 0 Max. RX channel rate (bps) = 0 IPv4 address = 5.205.241.62 IPv4 mask = 255.255.255.252 IPv4 gateway = 5.205.241.61 IPv4 primary DNS = 80.58.61.248 IPv4 secondary DNS = 80.58.61.249
Note
Once the cellular interface is configured, it can be verified that there is network connectivity by pinging Google's DNS public server.
Step 3: Ping IP address 8.8.8.8 from DUT0:
admin@DUT0$ ping 8.8.8.8 count 1 size 56 timeout 1Show output
PING 8.8.8.8 (8.8.8.8) 56(84) bytes of data. 64 bytes from 8.8.8.8: icmp_seq=1 ttl=117 time=26.5 ms --- 8.8.8.8 ping statistics --- 1 packets transmitted, 1 received, 0% packet loss, time 0ms rtt min/avg/max/mdev = 26.545/26.545/26.545/0.000 ms
Note
The accessibility control is a mechanism where pings are sent at an interval, and to the IP address, configured by the user. The mechanism then waits for a response within this interval. If no response is received before it times out, a second ping is sent and a response is expected within 10 seconds. If there is still no response, two more pings are sent at 5-second intervals. Finally, if there is still no response, the device reconnects to the network.
Note
As a result, you can verify that no reconnection has been recorded in the system logs if you wait long enough.
Step 4: Run command system journal clear at DUT0.
Step 5: Run command system journal show | cat at DUT0 and check if output does not contain the following tokens:
[Device/wwan0] Warning: [PDP#1] Requesting reconnection... [Device/wwan0] Info: WDS[0]: Stopping network connection... [Device/wwan0] Info: [PDP#1] Establishing network connection...Show output
Feb 19 07:24:13.626914 osdx systemd-journald[2359]: Runtime Journal (/run/log/journal/ded6b92ac85248ef9d0ddefe199dc428) is 8.0M, max 69.5M, 61.5M free. Feb 19 07:24:13.628345 osdx systemd-journald[2359]: Received client request to rotate journal, rotating. Feb 19 07:24:13.628404 osdx systemd-journald[2359]: Vacuuming done, freed 0B of archived journals from /run/log/journal/ded6b92ac85248ef9d0ddefe199dc428. Feb 19 07:24:13.688164 osdx OSDxCLI[13414]: User 'admin' executed a new command: 'system journal clear'. Feb 19 07:24:18.078816 osdx wwan-accessibility-control[14233]: [Core/wwan0/1] Warning: Previous packet pending - ID: 14233, Sequence: 0 Feb 19 07:24:18.078822 osdx wwan-accessibility-control[14233]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 1 - interval reduced to 10 seconds
Note
However, when traffic control is applied, it is possible to prove the 'ping' command is failing because ICMP packets are being dropped.
Step 6: Run command system journal clear at DUT0.
Step 7: Modify the following configuration lines in DUT0 :
set interfaces cellular cell0 traffic control in PLOSS set traffic control PLOSS type network-emulator packet-loss 100
Step 8: Expect a failure in the following command:
Ping IP address 8.8.8.8 from DUT0:
admin@DUT0$ ping 8.8.8.8 count 1 size 56 timeout 1Show output
PING 8.8.8.8 (8.8.8.8) 56(84) bytes of data. --- 8.8.8.8 ping statistics --- 1 packets transmitted, 0 received, 100% packet loss, time 0ms
Note
You can verify that a reconnection has been recorded in the system logs if you wait long enough.
Step 9: Run command system journal show | cat at DUT0 and check if output contains the following tokens:
[Device/wwan0] Warning: [PDP#1] Requesting reconnection... [Device/wwan0] Info: WDS[0]: Stopping network connection... [Device/wwan0] Info: [PDP#1] Establishing network connection...Show output
Feb 19 07:24:56.046033 osdx systemd-journald[2359]: Runtime Journal (/run/log/journal/ded6b92ac85248ef9d0ddefe199dc428) is 8.0M, max 69.5M, 61.5M free. Feb 19 07:24:56.047453 osdx systemd-journald[2359]: Received client request to rotate journal, rotating. Feb 19 07:24:56.047512 osdx systemd-journald[2359]: Vacuuming done, freed 0B of archived journals from /run/log/journal/ded6b92ac85248ef9d0ddefe199dc428. Feb 19 07:24:56.108237 osdx OSDxCLI[13414]: User 'admin' executed a new command: 'system journal clear'. Feb 19 07:24:56.257823 osdx OSDxCLI[13414]: User 'admin' entered the configuration menu. Feb 19 07:24:56.519718 osdx OSDxCLI[13414]: User 'admin' added a new cfg line: 'set interfaces cellular cell0 traffic control in PLOSS'. Feb 19 07:24:56.621920 osdx OSDxCLI[13414]: User 'admin' added a new cfg line: 'set traffic control PLOSS type network-emulator packet-loss 100'. Feb 19 07:24:56.746967 osdx OSDxCLI[13414]: User 'admin' added a new cfg line: 'show changes'. Feb 19 07:24:56.927049 osdx ubnt-cfgd[14321]: inactive Feb 19 07:24:56.968740 osdx INFO[14331]: FRR daemons did not change Feb 19 07:24:57.003728 osdx (udev-worker)[14352]: ifb.cell0: Could not set Alias=, MACAddress=/MACAddressPolicy=, TransmitQueues=, ReceiveQueues=, TransmitQueueLength=, MTUBytes=, GenericSegmentOffloadMaxBytes= or GenericSegmentOffloadMaxSegments=, ignoring: Operation not supported Feb 19 07:24:57.003758 osdx (udev-worker)[14352]: Network interface NamePolicy= disabled on kernel command line. Feb 19 07:24:57.023145 osdx kernel: u32 classifier Feb 19 07:24:57.023209 osdx kernel: Performance counters on Feb 19 07:24:57.023227 osdx kernel: input device check on Feb 19 07:24:57.023240 osdx kernel: Actions configured Feb 19 07:24:57.027143 osdx kernel: Mirror/redirect action on Feb 19 07:24:57.031143 osdx kernel: netem: version 1.3 Feb 19 07:24:57.033974 osdx cfgd[2003]: [13414]Completed change to active configuration Feb 19 07:24:57.036943 osdx OSDxCLI[13414]: User 'admin' committed the configuration. Feb 19 07:24:57.136842 osdx OSDxCLI[13414]: User 'admin' left the configuration menu. Feb 19 07:24:58.268445 osdx OSDxCLI[13414]: User 'admin' executed a new command: 'ping 8.8.8.8 count 1 size 56 timeout 1'. Feb 19 07:25:08.079416 osdx wwan-accessibility-control[14233]: [Core/wwan0/1] Warning: Previous packet pending - ID: 14233, Sequence: 5 Feb 19 07:25:08.079422 osdx wwan-accessibility-control[14233]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 1 - interval reduced to 10 seconds Feb 19 07:25:18.079578 osdx wwan-accessibility-control[14233]: [Core/wwan0/1] Warning: Previous packet pending - ID: 14233, Sequence: 6 Feb 19 07:25:18.079584 osdx wwan-accessibility-control[14233]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 2 - interval reduced to 5 seconds Feb 19 07:25:18.647554 osdx OSDxCLI[13414]: User 'admin' executed a new command: 'system journal show | cat'. Feb 19 07:25:20.011555 osdx OSDxCLI[13414]: User 'admin' executed a new command: 'system journal show | cat'. Feb 19 07:25:21.395555 osdx OSDxCLI[13414]: User 'admin' executed a new command: 'system journal show | cat'. Feb 19 07:25:22.811551 osdx OSDxCLI[13414]: User 'admin' executed a new command: 'system journal show | cat'. Feb 19 07:25:23.079739 osdx wwan-accessibility-control[14233]: [Core/wwan0/1] Warning: Previous packet pending - ID: 14233, Sequence: 7 Feb 19 07:25:23.079744 osdx wwan-accessibility-control[14233]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 3 - interval reduced to 5 seconds Feb 19 07:25:24.355548 osdx OSDxCLI[13414]: User 'admin' executed a new command: 'system journal show | cat'. Feb 19 07:25:25.867552 osdx OSDxCLI[13414]: User 'admin' executed a new command: 'system journal show | cat'. Feb 19 07:25:27.307551 osdx OSDxCLI[13414]: User 'admin' executed a new command: 'system journal show | cat'. Feb 19 07:25:28.079920 osdx wwan-accessibility-control[14233]: [Core/wwan0/1] Warning: Previous packet pending - ID: 14233, Sequence: 8 Feb 19 07:25:28.079926 osdx wwan-accessibility-control[14233]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 4 - interval reduced to 5 seconds Feb 19 07:25:28.083163 osdx WARNING[14445]: [wwan0/1] Requesting reconnection... Feb 19 07:25:28.085904 osdx wwan-cli-socket[14447]: [Device/wwan0] Info: wwan-cli-socket reconnect 60000 wwan0 Feb 19 07:25:28.086167 osdx wwan-module[3765]: [Device/wwan0] Info: Reconnecting device... Feb 19 07:25:28.086173 osdx wwan-module[3765]: [Device/wwan0] Warning: [PDP#1] Requesting reconnection... Feb 19 07:25:28.086235 osdx wwan-module[3765]: [Device/wwan0] Info: WDS[0]: Stopping network connection... Feb 19 07:25:28.198783 osdx wwan-module[3765]: [Device/wwan0] Info: WDS[0]: Network connection was stopped! Feb 19 07:25:28.203151 osdx kernel: net _wwan0: link_state 0x1 -> 0x0 Feb 19 07:25:28.204420 osdx wwan-module[3765]: [Device/wwan0] Info: Checking registration state... Feb 19 07:25:28.208921 osdx wwan-accessibility-control[14233]: [Core/wwan0/1] Info: Execution stopped Feb 19 07:25:28.208970 osdx systemd[1]: Stopping wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control... Feb 19 07:25:28.209653 osdx systemd[1]: wwan-accessibility-control@wwan0pdp1.service: Deactivated successfully. Feb 19 07:25:28.209795 osdx systemd[1]: Stopped wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control. Feb 19 07:25:28.213452 osdx INFO[14451]: Stop DHCP client for cell0 Feb 19 07:25:28.216404 osdx dhclient[14458]: Killed old client process Feb 19 07:25:28.262786 osdx wwan-module[3765]: [Device/wwan0] Info: Device registered and PS attached successfully Feb 19 07:25:28.262793 osdx wwan-module[3765]: [Device/wwan0] Info: [PDP#1] Establishing network connection... Feb 19 07:25:28.343278 osdx dhclient[14458]: DHCPRELEASE of 5.205.241.62 on cell0 to 5.205.241.61 port 67 Feb 19 07:25:28.456074 osdx wwan-module[3765]: [Device/wwan0] Info: [PDP#1] Network connection was successfully started: PDH=[3799489632] Feb 19 07:25:28.491145 osdx kernel: cell0: (slave _wwan0_1): Releasing backup interface Feb 19 07:25:28.519144 osdx kernel: net _wwan0: link_state 0x0 -> 0x1
Step 1: Set the following configuration in DUT0 :
set cellular logging level info set cellular profile CELPROFILE accessibility-control ping address 8.8.8.8 set cellular profile CELPROFILE accessibility-control ping interval 10 set cellular profile CELPROFILE apn movistar.es set controllers cellular wwan0 sim select socket_1 set controllers cellular wwan0 sim socket socket_1 encrypted-pin U2FsdGVkX1/obThqltvIWPC0rnJ1dN17/s+kScf5Lrw= set controllers cellular wwan0 sim socket socket_1 pdp 1 profile CELPROFILE set interfaces cellular cell0 address dhcp set interfaces cellular cell0 phy wwan0 pdp 1 set system login user admin authentication encrypted-password '$6$GSjsCj8gHLv$/VcqU6FLi6CT2Oxn0MJQ2C2tqnRDrYKNF8HIYWJp68nvXvPdFccDsT04.WtigUONbKYrgKg8d6rEs8PjljMkH0'
Step 2: Run command controllers cellular wwan0 pdp 1 show network-data-connection at DUT0 and check if output matches the following regular expressions:
Connection status\s* = connectedShow output
Connection status = connected Traffic channel status = active Max. TX channel rate (bps) = 0 Max. RX channel rate (bps) = 0 IPv4 address = 81.47.85.105 IPv4 mask = 255.255.255.252 IPv4 gateway = 81.47.85.106 IPv4 primary DNS = 80.58.61.248 IPv4 secondary DNS = 80.58.61.249
Note
Once the cellular interface is configured, it can be verified that there is network connectivity by pinging Google's DNS public server.
Step 3: Ping IP address 8.8.8.8 from DUT0:
admin@DUT0$ ping 8.8.8.8 count 1 size 56 timeout 1Show output
PING 8.8.8.8 (8.8.8.8) 56(84) bytes of data. 64 bytes from 8.8.8.8: icmp_seq=1 ttl=118 time=44.1 ms --- 8.8.8.8 ping statistics --- 1 packets transmitted, 1 received, 0% packet loss, time 0ms rtt min/avg/max/mdev = 44.139/44.139/44.139/0.000 ms
Note
The accessibility control is a mechanism where pings are sent at an interval, and to the IP address, configured by the user. The mechanism then waits for a response within this interval. If no response is received before it times out, a second ping is sent and a response is expected within 10 seconds. If there is still no response, two more pings are sent at 5-second intervals. Finally, if there is still no response, the device reconnects to the network.
Note
As a result, you can verify that no reconnection has been recorded in the system logs if you wait long enough.
Step 4: Run command system journal clear at DUT0.
Step 5: Run command system journal show | cat at DUT0 and check if output does not contain the following tokens:
[Device/wwan0] Warning: [PDP#1] Requesting reconnection... [Device/wwan0] Info: WDS[0]: Stopping network connection... [Device/wwan0] Info: [PDP#1] Establishing network connection...Show output
Feb 19 07:30:13.095894 osdx systemd-journald[4218]: Runtime Journal (/run/log/journal/96696b25b9cf4bceb576f9ffeda6fd5d) is 8.0M, max 80.3M, 72.3M free. Feb 19 07:30:13.100547 osdx systemd-journald[4218]: Received client request to rotate journal, rotating. Feb 19 07:30:13.100654 osdx systemd-journald[4218]: Vacuuming done, freed 0B of archived journals from /run/log/journal/96696b25b9cf4bceb576f9ffeda6fd5d. Feb 19 07:30:13.174236 osdx OSDxCLI[12904]: User 'admin' executed a new command: 'system journal clear'. Feb 19 07:30:17.409495 osdx wwan-accessibility-control[13880]: [Core/wwan0/1] Warning: Previous packet pending - ID: 13880, Sequence: 0 Feb 19 07:30:17.409511 osdx wwan-accessibility-control[13880]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 1 - interval reduced to 10 seconds
Note
However, when traffic control is applied, it is possible to prove the 'ping' command is failing because ICMP packets are being dropped.
Step 6: Run command system journal clear at DUT0.
Step 7: Modify the following configuration lines in DUT0 :
set interfaces cellular cell0 traffic control in PLOSS set traffic control PLOSS type network-emulator packet-loss 100
Step 8: Expect a failure in the following command:
Ping IP address 8.8.8.8 from DUT0:
admin@DUT0$ ping 8.8.8.8 count 1 size 56 timeout 1Show output
PING 8.8.8.8 (8.8.8.8) 56(84) bytes of data. --- 8.8.8.8 ping statistics --- 1 packets transmitted, 0 received, 100% packet loss, time 0ms
Note
You can verify that a reconnection has been recorded in the system logs if you wait long enough.
Step 9: Run command system journal show | cat at DUT0 and check if output contains the following tokens:
[Device/wwan0] Warning: [PDP#1] Requesting reconnection... [Device/wwan0] Info: WDS[0]: Stopping network connection... [Device/wwan0] Info: [PDP#1] Establishing network connection...Show output
Feb 19 07:30:55.502949 osdx systemd-journald[4218]: Runtime Journal (/run/log/journal/96696b25b9cf4bceb576f9ffeda6fd5d) is 8.0M, max 80.3M, 72.3M free. Feb 19 07:30:55.507682 osdx systemd-journald[4218]: Received client request to rotate journal, rotating. Feb 19 07:30:55.507808 osdx systemd-journald[4218]: Vacuuming done, freed 0B of archived journals from /run/log/journal/96696b25b9cf4bceb576f9ffeda6fd5d. Feb 19 07:30:55.578256 osdx OSDxCLI[12904]: User 'admin' executed a new command: 'system journal clear'. Feb 19 07:30:55.732600 osdx OSDxCLI[12904]: User 'admin' entered the configuration menu. Feb 19 07:30:55.844777 osdx OSDxCLI[12904]: User 'admin' added a new cfg line: 'set interfaces cellular cell0 traffic control in PLOSS'. Feb 19 07:30:55.988192 osdx OSDxCLI[12904]: User 'admin' added a new cfg line: 'set traffic control PLOSS type network-emulator packet-loss 100'. Feb 19 07:30:56.105081 osdx OSDxCLI[12904]: User 'admin' added a new cfg line: 'show changes'. Feb 19 07:30:56.281958 osdx ubnt-cfgd[13970]: inactive Feb 19 07:30:56.397425 osdx INFO[13980]: FRR daemons did not change Feb 19 07:30:56.485507 osdx (udev-worker)[290]: ifb.cell0: Could not set Alias=, MACAddress=/MACAddressPolicy=, TransmitQueues=, ReceiveQueues=, TransmitQueueLength=, MTUBytes=, GenericSegmentOffloadMaxBytes= or GenericSegmentOffloadMaxSegments=, ignoring: Operation not supported Feb 19 07:30:56.527760 osdx kernel: Mirror/redirect action on Feb 19 07:30:56.535765 osdx kernel: netem: version 1.3 Feb 19 07:30:56.546895 osdx cfgd[3872]: [12904]Completed change to active configuration Feb 19 07:30:56.566439 osdx systemd[1]: osdx-ledsd.service: Sent signal SIGUSR2 to main process 4684 (osdx-ledsd) on client request. Feb 19 07:30:56.566467 osdx osdx-ledsd[4684]: Config changed, checking config paths Feb 19 07:30:56.569029 osdx OSDxCLI[12904]: User 'admin' committed the configuration. Feb 19 07:30:56.603836 osdx OSDxCLI[12904]: User 'admin' left the configuration menu. Feb 19 07:30:57.838667 osdx OSDxCLI[12904]: User 'admin' executed a new command: 'ping 8.8.8.8 count 1 size 56 timeout 1'. Feb 19 07:31:07.410276 osdx wwan-accessibility-control[13880]: [Core/wwan0/1] Warning: Previous packet pending - ID: 13880, Sequence: 5 Feb 19 07:31:07.410291 osdx wwan-accessibility-control[13880]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 1 - interval reduced to 10 seconds Feb 19 07:31:17.410470 osdx wwan-accessibility-control[13880]: [Core/wwan0/1] Warning: Previous packet pending - ID: 13880, Sequence: 6 Feb 19 07:31:17.410486 osdx wwan-accessibility-control[13880]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 2 - interval reduced to 5 seconds Feb 19 07:31:18.208684 osdx OSDxCLI[12904]: User 'admin' executed a new command: 'system journal show | cat'. Feb 19 07:31:19.640693 osdx OSDxCLI[12904]: User 'admin' executed a new command: 'system journal show | cat'. Feb 19 07:31:21.032697 osdx OSDxCLI[12904]: User 'admin' executed a new command: 'system journal show | cat'. Feb 19 07:31:22.410650 osdx wwan-accessibility-control[13880]: [Core/wwan0/1] Warning: Previous packet pending - ID: 13880, Sequence: 7 Feb 19 07:31:22.410664 osdx wwan-accessibility-control[13880]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 3 - interval reduced to 5 seconds Feb 19 07:31:22.436688 osdx OSDxCLI[12904]: User 'admin' executed a new command: 'system journal show | cat'. Feb 19 07:31:23.892706 osdx OSDxCLI[12904]: User 'admin' executed a new command: 'system journal show | cat'. Feb 19 07:31:25.328695 osdx OSDxCLI[12904]: User 'admin' executed a new command: 'system journal show | cat'. Feb 19 07:31:26.772710 osdx OSDxCLI[12904]: User 'admin' executed a new command: 'system journal show | cat'. Feb 19 07:31:27.410836 osdx wwan-accessibility-control[13880]: [Core/wwan0/1] Warning: Previous packet pending - ID: 13880, Sequence: 8 Feb 19 07:31:27.410850 osdx wwan-accessibility-control[13880]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 4 - interval reduced to 5 seconds Feb 19 07:31:27.420390 osdx WARNING[14091]: [wwan0/1] Requesting reconnection... Feb 19 07:31:27.426988 osdx wwan-cli-socket[14093]: [Device/wwan0] Info: wwan-cli-socket reconnect 60000 wwan0 Feb 19 07:31:27.427378 osdx wwan-module[4716]: [Device/wwan0] Info: Reconnecting device... Feb 19 07:31:27.427390 osdx wwan-module[4716]: [Device/wwan0] Warning: [PDP#1] Requesting reconnection... Feb 19 07:31:27.427442 osdx wwan-module[4716]: [Device/wwan0] Info: WDS[0]: Stopping network connection... Feb 19 07:31:27.525570 osdx wwan-module[4716]: [Device/wwan0] Info: WDS[0]: Network connection was stopped! Feb 19 07:31:27.531768 osdx kernel: net _wwan0: link_state 0x1 -> 0x0 Feb 19 07:31:27.533921 osdx wwan-module[4716]: [Device/wwan0] Info: Checking registration state... Feb 19 07:31:27.553198 osdx wwan-accessibility-control[13880]: [Core/wwan0/1] Info: Execution stopped Feb 19 07:31:27.553382 osdx systemd[1]: Stopping wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control... Feb 19 07:31:27.555371 osdx systemd[1]: wwan-accessibility-control@wwan0pdp1.service: Deactivated successfully. Feb 19 07:31:27.555780 osdx systemd[1]: Stopped wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control. Feb 19 07:31:27.565818 osdx INFO[14097]: Stop DHCP client for cell0 Feb 19 07:31:27.572643 osdx dhclient[14104]: Killed old client process Feb 19 07:31:27.589549 osdx wwan-module[4716]: [Device/wwan0] Info: Device registered and PS attached successfully Feb 19 07:31:27.589563 osdx wwan-module[4716]: [Device/wwan0] Info: [PDP#1] Establishing network connection... Feb 19 07:31:27.687945 osdx dhclient[14104]: DHCPRELEASE of 81.47.85.105 on cell0 to 81.47.85.106 port 67 Feb 19 07:31:27.815603 osdx wwan-module[4716]: [Device/wwan0] Info: [PDP#1] Network connection was successfully started: PDH=[3799777728] Feb 19 07:31:27.879760 osdx kernel: net _wwan0: link_state 0x0 -> 0x1