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 U2FsdGVkX19zkW5bUty25v/xSikgGdJRBwO50B/e0Sg= 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 the command controllers cellular wwan0 pdp 1 show network-data-connection on DUT0 and check whether the 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 = 37.12.6.45 IPv4 mask = 255.255.255.252 IPv4 gateway = 37.12.6.46 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 the 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=184 ms --- 8.8.8.8 ping statistics --- 1 packets transmitted, 1 received, 0% packet loss, time 0ms rtt min/avg/max/mdev = 184.139/184.139/184.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 the command system journal clear on DUT0.
Step 5: Run the command system journal show | cat on DUT0 and check whether the 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
Jul 25 01:58:55.357647 osdx systemd-journald[463157]: Runtime Journal (/run/log/journal/e45843c7472642b2abfe20cd3db6d993) is 8.0M, max 80.5M, 72.5M free. Jul 25 01:58:55.364841 osdx systemd-journald[463157]: Received client request to rotate journal, rotating. Jul 25 01:58:55.365031 osdx systemd-journald[463157]: Vacuuming done, freed 0B of archived journals from /run/log/journal/e45843c7472642b2abfe20cd3db6d993. Jul 25 01:58:55.449200 osdx OSDxCLI[1141108]: User 'admin' executed a new command: 'system journal clear'. Jul 25 01:59:04.295370 osdx wwan-accessibility-control[1157156]: [Core/wwan0/1] Warning: Previous packet pending - ID: 43044, Sequence: 0 Jul 25 01:59:04.295390 osdx wwan-accessibility-control[1157156]: [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 the command system journal clear on 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 the 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 the command system journal show | cat on DUT0 and check whether the 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
Jul 25 01:59:37.768621 osdx systemd-journald[463157]: Runtime Journal (/run/log/journal/e45843c7472642b2abfe20cd3db6d993) is 8.0M, max 80.5M, 72.5M free. Jul 25 01:59:37.776254 osdx systemd-journald[463157]: Received client request to rotate journal, rotating. Jul 25 01:59:37.776438 osdx systemd-journald[463157]: Vacuuming done, freed 0B of archived journals from /run/log/journal/e45843c7472642b2abfe20cd3db6d993. Jul 25 01:59:37.861256 osdx OSDxCLI[1141108]: User 'admin' executed a new command: 'system journal clear'. Jul 25 01:59:38.002166 osdx OSDxCLI[1141108]: User 'admin' entered the configuration menu. Jul 25 01:59:38.108903 osdx OSDxCLI[1141108]: User 'admin' added a new cfg line: 'set interfaces cellular cell0 traffic control in PLOSS'. Jul 25 01:59:38.202582 osdx OSDxCLI[1141108]: User 'admin' added a new cfg line: 'set traffic control PLOSS type network-emulator packet-loss 100'. Jul 25 01:59:38.313491 osdx OSDxCLI[1141108]: User 'admin' added a new cfg line: 'show changes'. Jul 25 01:59:38.463305 osdx ubnt-cfgd[1157251]: inactive Jul 25 01:59:38.598604 osdx INFO[1157262]: FRR daemons did not change Jul 25 01:59:38.687772 osdx (udev-worker)[1157277]: ifb.cell0: Could not set Alias=, MACAddress=/MACAddressPolicy=, TransmitQueues=, ReceiveQueues=, TransmitQueueLength=, MTUBytes=, GenericSegmentOffloadMaxBytes= or GenericSegmentOffloadMaxSegments=, ignoring: Operation not supported Jul 25 01:59:38.687877 osdx (udev-worker)[1157277]: Network interface NamePolicy= disabled on kernel command line. Jul 25 01:59:38.745099 osdx cfgd[2059]: [1141108]Completed change to active configuration Jul 25 01:59:38.766355 osdx systemd[1]: osdx-ledsd.service: Sent signal SIGUSR2 to main process 2724 (osdx-ledsd) on client request. Jul 25 01:59:38.766386 osdx osdx-ledsd[2724]: Config changed, checking config paths Jul 25 01:59:38.770271 osdx OSDxCLI[1141108]: User 'admin' committed the configuration. Jul 25 01:59:38.819446 osdx OSDxCLI[1141108]: User 'admin' left the configuration menu. Jul 25 01:59:40.022023 osdx OSDxCLI[1141108]: User 'admin' executed a new command: 'ping 8.8.8.8 count 1 size 56 timeout 1'. Jul 25 01:59:54.296347 osdx wwan-accessibility-control[1157156]: [Core/wwan0/1] Warning: Previous packet pending - ID: 43044, Sequence: 5 Jul 25 01:59:54.296369 osdx wwan-accessibility-control[1157156]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 1 - interval reduced to 10 seconds Jul 25 02:00:00.363224 osdx OSDxCLI[1141108]: User 'admin' executed a new command: 'system journal show | cat'. Jul 25 02:00:01.703214 osdx OSDxCLI[1141108]: User 'admin' executed a new command: 'system journal show | cat'. Jul 25 02:00:03.051231 osdx OSDxCLI[1141108]: User 'admin' executed a new command: 'system journal show | cat'. Jul 25 02:00:04.296629 osdx wwan-accessibility-control[1157156]: [Core/wwan0/1] Warning: Previous packet pending - ID: 43044, Sequence: 6 Jul 25 02:00:04.296651 osdx wwan-accessibility-control[1157156]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 2 - interval reduced to 5 seconds Jul 25 02:00:04.419203 osdx OSDxCLI[1141108]: User 'admin' executed a new command: 'system journal show | cat'. Jul 25 02:00:05.815221 osdx OSDxCLI[1141108]: User 'admin' executed a new command: 'system journal show | cat'. Jul 25 02:00:07.219227 osdx OSDxCLI[1141108]: User 'admin' executed a new command: 'system journal show | cat'. Jul 25 02:00:08.663203 osdx OSDxCLI[1141108]: User 'admin' executed a new command: 'system journal show | cat'. Jul 25 02:00:09.296904 osdx wwan-accessibility-control[1157156]: [Core/wwan0/1] Warning: Previous packet pending - ID: 43044, Sequence: 7 Jul 25 02:00:09.296921 osdx wwan-accessibility-control[1157156]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 3 - interval reduced to 5 seconds Jul 25 02:00:10.119220 osdx OSDxCLI[1141108]: User 'admin' executed a new command: 'system journal show | cat'. Jul 25 02:00:11.615222 osdx OSDxCLI[1141108]: User 'admin' executed a new command: 'system journal show | cat'. Jul 25 02:00:13.103214 osdx OSDxCLI[1141108]: User 'admin' executed a new command: 'system journal show | cat'. Jul 25 02:00:14.297180 osdx wwan-accessibility-control[1157156]: [Core/wwan0/1] Warning: Previous packet pending - ID: 43044, Sequence: 8 Jul 25 02:00:14.297204 osdx wwan-accessibility-control[1157156]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 4 - interval reduced to 5 seconds Jul 25 02:00:14.309750 osdx WARNING[1157374]: [wwan0/1] Requesting reconnection... Jul 25 02:00:14.319164 osdx wwan-cli-socket[1157376]: [Device/wwan0] Info: wwan-cli-socket reconnect 60000 wwan0 Jul 25 02:00:14.319729 osdx wwan-module[1141093]: [Device/wwan0] Info: Reconnecting device... Jul 25 02:00:14.319746 osdx wwan-module[1141093]: [Device/wwan0] Warning: [PDP#1] Requesting reconnection... Jul 25 02:00:14.319810 osdx wwan-module[1141093]: [Device/wwan0] Info: WDS[0]: Stopping network connection... Jul 25 02:00:14.416350 osdx wwan-module[1141093]: [Device/wwan0] Info: WDS[0]: Network connection was stopped! Jul 25 02:00:14.422029 osdx kernel: net _wwan0: link_state 0x1 -> 0x0 Jul 25 02:00:14.425581 osdx wwan-module[1141093]: [Device/wwan0] Info: [PDP#1] Checking registration state... Jul 25 02:00:14.455180 osdx wwan-accessibility-control[1157156]: [Core/wwan0/1] Info: Execution stopped Jul 25 02:00:14.455483 osdx systemd[1]: Stopping wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control... Jul 25 02:00:14.458350 osdx systemd[1]: wwan-accessibility-control@wwan0pdp1.service: Deactivated successfully. Jul 25 02:00:14.458889 osdx systemd[1]: Stopped wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control. Jul 25 02:00:14.475333 osdx INFO[1157381]: Stop DHCP client for cell0 Jul 25 02:00:14.485141 osdx dhclient[1157387]: Killed old client process Jul 25 02:00:14.544507 osdx wwan-module[1141093]: [Device/wwan0] Info: [PDP#1] Device registered and PS attached successfully Jul 25 02:00:14.544531 osdx wwan-module[1141093]: [Device/wwan0] Info: [PDP#1] Establishing network connection... Jul 25 02:00:14.583177 osdx OSDxCLI[1141108]: User 'admin' executed a new command: 'system journal show | cat'. Jul 25 02:00:14.602305 osdx dhclient[1157387]: DHCPRELEASE of 37.12.6.45 on cell0 to 37.12.6.46 port 67 Jul 25 02:00:14.739139 osdx wwan-module[1141093]: [Device/wwan0] Info: [PDP#1] Network connection was successfully started: PDH=[3597800272] Jul 25 02:00:14.865893 osdx kernel: net _wwan0: link_state 0x0 -> 0x1 Jul 25 02:00:15.137908 osdx kernel: cell0: (slave _wwan0_1): Releasing backup interface Jul 25 02:00:15.361908 osdx kernel: cell0: (slave _wwan0_1): The slave device specified does not support setting the MAC address Jul 25 02:00:15.362125 osdx kernel: cell0: (slave _wwan0_1): making interface the new active one Jul 25 02:00:15.365938 osdx kernel: cell0: (slave _wwan0_1): Enslaving as an active interface with an up link Jul 25 02:00:15.381905 osdx kernel: 8021q: adding VLAN 0 to HW filter on device cell0 Jul 25 02:00:15.383483 osdx INFO[1157437]: Start DHCP client for cell0
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 U2FsdGVkX18QkXLoXU2WhVZd4zkxlQZ2Y/aeDQPMxx0= 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 the command controllers cellular wwan0 pdp 1 show network-data-connection on DUT0 and check whether the 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 = 2.143.51.137 IPv4 mask = 255.255.255.252 IPv4 gateway = 2.143.51.138 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 the 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=156 ms --- 8.8.8.8 ping statistics --- 1 packets transmitted, 1 received, 0% packet loss, time 0ms rtt min/avg/max/mdev = 155.551/155.551/155.551/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 the command system journal clear on DUT0.
Step 5: Run the command system journal show | cat on DUT0 and check whether the 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
Jul 24 15:47:55.627995 osdx systemd-journald[2513]: Runtime Journal (/run/log/journal/c0352365b7ce4e14bf0921be79232a66) is 8.2M, max 69.4M, 61.2M free. Jul 24 15:47:55.629410 osdx systemd-journald[2513]: Received client request to rotate journal, rotating. Jul 24 15:47:55.629462 osdx systemd-journald[2513]: Vacuuming done, freed 0B of archived journals from /run/log/journal/c0352365b7ce4e14bf0921be79232a66. Jul 24 15:47:55.702184 osdx OSDxCLI[1118300]: User 'admin' executed a new command: 'system journal clear'. Jul 24 15:48:05.202289 osdx wwan-accessibility-control[1123908]: [Core/wwan0/1] Warning: Previous packet pending - ID: 9796, Sequence: 0 Jul 24 15:48:05.202297 osdx wwan-accessibility-control[1123908]: [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 the command system journal clear on 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 the 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 the command system journal show | cat on DUT0 and check whether the 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
Jul 24 15:48:37.947397 osdx systemd-journald[2513]: Runtime Journal (/run/log/journal/c0352365b7ce4e14bf0921be79232a66) is 8.0M, max 69.4M, 61.4M free. Jul 24 15:48:37.949044 osdx systemd-journald[2513]: Received client request to rotate journal, rotating. Jul 24 15:48:37.949119 osdx systemd-journald[2513]: Vacuuming done, freed 0B of archived journals from /run/log/journal/c0352365b7ce4e14bf0921be79232a66. Jul 24 15:48:38.006164 osdx OSDxCLI[1118300]: User 'admin' executed a new command: 'system journal clear'. Jul 24 15:48:38.167658 osdx OSDxCLI[1118300]: User 'admin' entered the configuration menu. Jul 24 15:48:38.293224 osdx OSDxCLI[1118300]: User 'admin' added a new cfg line: 'set interfaces cellular cell0 traffic control in PLOSS'. Jul 24 15:48:38.372023 osdx OSDxCLI[1118300]: User 'admin' added a new cfg line: 'set traffic control PLOSS type network-emulator packet-loss 100'. Jul 24 15:48:38.476150 osdx OSDxCLI[1118300]: User 'admin' added a new cfg line: 'show changes'. Jul 24 15:48:38.616647 osdx ubnt-cfgd[1123997]: inactive Jul 24 15:48:38.665329 osdx INFO[1124008]: FRR daemons did not change Jul 24 15:48:38.687426 osdx (udev-worker)[1124021]: ifb.cell0: Could not set Alias=, MACAddress=/MACAddressPolicy=, TransmitQueues=, ReceiveQueues=, TransmitQueueLength=, MTUBytes=, GenericSegmentOffloadMaxBytes= or GenericSegmentOffloadMaxSegments=, ignoring: Operation not supported Jul 24 15:48:38.687455 osdx (udev-worker)[1124021]: Network interface NamePolicy= disabled on kernel command line. Jul 24 15:48:38.703532 osdx cfgd[2109]: [1118300]Completed change to active configuration Jul 24 15:48:38.704239 osdx OSDxCLI[1118300]: User 'admin' committed the configuration. Jul 24 15:48:38.746855 osdx OSDxCLI[1118300]: User 'admin' left the configuration menu. Jul 24 15:48:39.906407 osdx OSDxCLI[1118300]: User 'admin' executed a new command: 'ping 8.8.8.8 count 1 size 56 timeout 1'. Jul 24 15:48:55.202896 osdx wwan-accessibility-control[1123908]: [Core/wwan0/1] Warning: Previous packet pending - ID: 9796, Sequence: 5 Jul 24 15:48:55.202904 osdx wwan-accessibility-control[1123908]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 1 - interval reduced to 10 seconds Jul 24 15:49:00.201582 osdx OSDxCLI[1118300]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 15:49:01.497509 osdx OSDxCLI[1118300]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 15:49:02.817511 osdx OSDxCLI[1118300]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 15:49:04.125574 osdx OSDxCLI[1118300]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 15:49:05.203050 osdx wwan-accessibility-control[1123908]: [Core/wwan0/1] Warning: Previous packet pending - ID: 9796, Sequence: 6 Jul 24 15:49:05.203057 osdx wwan-accessibility-control[1123908]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 2 - interval reduced to 5 seconds Jul 24 15:49:05.501564 osdx OSDxCLI[1118300]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 15:49:06.877584 osdx OSDxCLI[1118300]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 15:49:08.265604 osdx OSDxCLI[1118300]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 15:49:09.661574 osdx OSDxCLI[1118300]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 15:49:10.203300 osdx wwan-accessibility-control[1123908]: [Core/wwan0/1] Warning: Previous packet pending - ID: 9796, Sequence: 7 Jul 24 15:49:10.203308 osdx wwan-accessibility-control[1123908]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 3 - interval reduced to 5 seconds Jul 24 15:49:11.085591 osdx OSDxCLI[1118300]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 15:49:12.529582 osdx OSDxCLI[1118300]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 15:49:13.965588 osdx OSDxCLI[1118300]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 15:49:15.203457 osdx wwan-accessibility-control[1123908]: [Core/wwan0/1] Warning: Previous packet pending - ID: 9796, Sequence: 8 Jul 24 15:49:15.203464 osdx wwan-accessibility-control[1123908]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 4 - interval reduced to 5 seconds Jul 24 15:49:15.207032 osdx WARNING[1124117]: [wwan0/1] Requesting reconnection... Jul 24 15:49:15.209788 osdx wwan-cli-socket[1124119]: [Device/wwan0] Info: wwan-cli-socket reconnect 60000 wwan0 Jul 24 15:49:15.210047 osdx wwan-module[3948]: [Device/wwan0] Info: Reconnecting device... Jul 24 15:49:15.210053 osdx wwan-module[3948]: [Device/wwan0] Warning: [PDP#1] Requesting reconnection... Jul 24 15:49:15.210150 osdx wwan-module[3948]: [Device/wwan0] Info: WDS[0]: Stopping network connection... Jul 24 15:49:15.332174 osdx wwan-module[3948]: [Device/wwan0] Info: WDS[0]: Network connection was stopped! Jul 24 15:49:15.337052 osdx kernel: net _wwan0: link_state 0x1 -> 0x0 Jul 24 15:49:15.337879 osdx wwan-module[3948]: [Device/wwan0] Info: [PDP#1] Checking registration state... Jul 24 15:49:15.343009 osdx systemd[1]: Stopping wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control... Jul 24 15:49:15.343012 osdx wwan-accessibility-control[1123908]: [Core/wwan0/1] Info: Execution stopped Jul 24 15:49:15.343732 osdx systemd[1]: wwan-accessibility-control@wwan0pdp1.service: Deactivated successfully. Jul 24 15:49:15.343891 osdx systemd[1]: Stopped wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control. Jul 24 15:49:15.347717 osdx INFO[1124123]: Stop DHCP client for cell0 Jul 24 15:49:15.350840 osdx dhclient[1124130]: Killed old client process Jul 24 15:49:15.395966 osdx wwan-module[3948]: [Device/wwan0] Info: [PDP#1] Device registered and PS attached successfully Jul 24 15:49:15.395974 osdx wwan-module[3948]: [Device/wwan0] Info: [PDP#1] Establishing network connection... Jul 24 15:49:15.421606 osdx OSDxCLI[1118300]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 15:49:15.469279 osdx dhclient[1124130]: DHCPRELEASE of 2.143.51.137 on cell0 to 2.143.51.138 port 67 Jul 24 15:49:15.617075 osdx kernel: cell0: (slave _wwan0_1): Releasing backup interface Jul 24 15:49:15.621001 osdx wwan-module[3948]: [Device/wwan0] Info: [PDP#1] Network connection was successfully started: PDH=[3798752320] Jul 24 15:49:15.685088 osdx kernel: net _wwan0: link_state 0x0 -> 0x1 Jul 24 15:49:15.701071 osdx kernel: cell0: (slave _wwan0_1): The slave device specified does not support setting the MAC address Jul 24 15:49:15.701134 osdx kernel: cell0: (slave _wwan0_1): making interface the new active one Jul 24 15:49:15.701151 osdx kernel: cell0: (slave _wwan0_1): Enslaving as an active interface with an up link Jul 24 15:49:15.705068 osdx kernel: 8021q: adding VLAN 0 to HW filter on device cell0 Jul 24 15:49:15.705183 osdx INFO[1124186]: Start DHCP client for cell0 Jul 24 15:49:15.885369 osdx dhclient[1124199]: DHCPDISCOVER on cell0 to 255.255.255.255 port 67 interval 8 Jul 24 15:49:15.888638 osdx dhclient[1124199]: DHCPOFFER of 176.82.182.168 from 176.82.182.169 Jul 24 15:49:15.888666 osdx dhclient[1124199]: DHCPREQUEST for 176.82.182.168 on cell0 to 255.255.255.255 port 67 Jul 24 15:49:15.890590 osdx dhclient[1124199]: DHCPACK of 176.82.182.168 from 176.82.182.169 Jul 24 15:49:15.937564 osdx systemd[1]: Started wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control. Jul 24 15:49:15.941657 osdx wwan-accessibility-control[1124211]: [Core/wwan0/1] Info: Starting '/osdx/sbin/wwan-accessibility-control'... Jul 24 15:49:16.003978 osdx dhclient[1124199]: bound to 176.82.182.168 -- renewal in 3196 seconds.
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 U2FsdGVkX19wUmLwsnlhxQF2FQROuqCjTl8cWqaNd7Y= 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 the command controllers cellular wwan0 pdp 1 show network-data-connection on DUT0 and check whether the 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 = 2.143.61.45 IPv4 mask = 255.255.255.252 IPv4 gateway = 2.143.61.46 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 the 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=146 ms --- 8.8.8.8 ping statistics --- 1 packets transmitted, 1 received, 0% packet loss, time 0ms rtt min/avg/max/mdev = 146.211/146.211/146.211/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 the command system journal clear on DUT0.
Step 5: Run the command system journal show | cat on DUT0 and check whether the 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
Jul 24 08:40:09.006899 osdx systemd-journald[4472]: Runtime Journal (/run/log/journal/e45843c7472642b2abfe20cd3db6d993) is 8.0M, max 80.3M, 72.3M free. Jul 24 08:40:09.011634 osdx systemd-journald[4472]: Received client request to rotate journal, rotating. Jul 24 08:40:09.011740 osdx systemd-journald[4472]: Vacuuming done, freed 0B of archived journals from /run/log/journal/e45843c7472642b2abfe20cd3db6d993. Jul 24 08:40:09.088733 osdx OSDxCLI[76798]: User 'admin' executed a new command: 'system journal clear'. Jul 24 08:40:18.086076 osdx wwan-accessibility-control[77700]: [Core/wwan0/1] Warning: Previous packet pending - ID: 12164, Sequence: 0 Jul 24 08:40:18.086095 osdx wwan-accessibility-control[77700]: [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 the command system journal clear on 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 the 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 the command system journal show | cat on DUT0 and check whether the 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
Jul 24 08:40:51.418113 osdx systemd-journald[4472]: Runtime Journal (/run/log/journal/e45843c7472642b2abfe20cd3db6d993) is 8.0M, max 80.3M, 72.3M free. Jul 24 08:40:51.423028 osdx systemd-journald[4472]: Received client request to rotate journal, rotating. Jul 24 08:40:51.423136 osdx systemd-journald[4472]: Vacuuming done, freed 0B of archived journals from /run/log/journal/e45843c7472642b2abfe20cd3db6d993. Jul 24 08:40:51.496690 osdx OSDxCLI[76798]: User 'admin' executed a new command: 'system journal clear'. Jul 24 08:40:51.647240 osdx OSDxCLI[76798]: User 'admin' entered the configuration menu. Jul 24 08:40:51.771073 osdx OSDxCLI[76798]: User 'admin' added a new cfg line: 'set interfaces cellular cell0 traffic control in PLOSS'. Jul 24 08:40:51.873850 osdx OSDxCLI[76798]: User 'admin' added a new cfg line: 'set traffic control PLOSS type network-emulator packet-loss 100'. Jul 24 08:40:51.987313 osdx OSDxCLI[76798]: User 'admin' added a new cfg line: 'show changes'. Jul 24 08:40:52.140462 osdx ubnt-cfgd[77787]: inactive Jul 24 08:40:52.243719 osdx INFO[77798]: FRR daemons did not change Jul 24 08:40:52.324350 osdx (udev-worker)[300]: ifb.cell0: Could not set Alias=, MACAddress=/MACAddressPolicy=, TransmitQueues=, ReceiveQueues=, TransmitQueueLength=, MTUBytes=, GenericSegmentOffloadMaxBytes= or GenericSegmentOffloadMaxSegments=, ignoring: Operation not supported Jul 24 08:40:52.366322 osdx kernel: Mirror/redirect action on Jul 24 08:40:52.404907 osdx cfgd[4127]: [76798]Completed change to active configuration Jul 24 08:40:52.419666 osdx systemd[1]: osdx-ledsd.service: Sent signal SIGUSR2 to main process 4951 (osdx-ledsd) on client request. Jul 24 08:40:52.419696 osdx osdx-ledsd[4951]: Config changed, checking config paths Jul 24 08:40:52.422187 osdx OSDxCLI[76798]: User 'admin' committed the configuration. Jul 24 08:40:52.466326 osdx OSDxCLI[76798]: User 'admin' left the configuration menu. Jul 24 08:40:53.669201 osdx OSDxCLI[76798]: User 'admin' executed a new command: 'ping 8.8.8.8 count 1 size 56 timeout 1'. Jul 24 08:41:08.086742 osdx wwan-accessibility-control[77700]: [Core/wwan0/1] Warning: Previous packet pending - ID: 12164, Sequence: 5 Jul 24 08:41:08.086757 osdx wwan-accessibility-control[77700]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 1 - interval reduced to 10 seconds Jul 24 08:41:14.015261 osdx OSDxCLI[76798]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 08:41:15.387231 osdx OSDxCLI[76798]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 08:41:16.755223 osdx OSDxCLI[76798]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 08:41:18.086926 osdx wwan-accessibility-control[77700]: [Core/wwan0/1] Warning: Previous packet pending - ID: 12164, Sequence: 6 Jul 24 08:41:18.086940 osdx wwan-accessibility-control[77700]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 2 - interval reduced to 5 seconds Jul 24 08:41:18.131248 osdx OSDxCLI[76798]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 08:41:19.535224 osdx OSDxCLI[76798]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 08:41:20.971231 osdx OSDxCLI[76798]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 08:41:22.415245 osdx OSDxCLI[76798]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 08:41:23.087111 osdx wwan-accessibility-control[77700]: [Core/wwan0/1] Warning: Previous packet pending - ID: 12164, Sequence: 7 Jul 24 08:41:23.087126 osdx wwan-accessibility-control[77700]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 3 - interval reduced to 5 seconds Jul 24 08:41:23.891225 osdx OSDxCLI[76798]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 08:41:25.351222 osdx OSDxCLI[76798]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 08:41:26.819223 osdx OSDxCLI[76798]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 08:41:28.087299 osdx wwan-accessibility-control[77700]: [Core/wwan0/1] Warning: Previous packet pending - ID: 12164, Sequence: 8 Jul 24 08:41:28.087314 osdx wwan-accessibility-control[77700]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 4 - interval reduced to 5 seconds Jul 24 08:41:28.097603 osdx WARNING[77908]: [wwan0/1] Requesting reconnection... Jul 24 08:41:28.104189 osdx wwan-cli-socket[77910]: [Device/wwan0] Info: wwan-cli-socket reconnect 60000 wwan0 Jul 24 08:41:28.104573 osdx wwan-module[4990]: [Device/wwan0] Info: Reconnecting device... Jul 24 08:41:28.104586 osdx wwan-module[4990]: [Device/wwan0] Warning: [PDP#1] Requesting reconnection... Jul 24 08:41:28.104637 osdx wwan-module[4990]: [Device/wwan0] Info: WDS[0]: Stopping network connection... Jul 24 08:41:28.210026 osdx wwan-module[4990]: [Device/wwan0] Info: WDS[0]: Network connection was stopped! Jul 24 08:41:28.214323 osdx kernel: net _wwan0: link_state 0x1 -> 0x0 Jul 24 08:41:28.220148 osdx wwan-module[4990]: [Device/wwan0] Info: [PDP#1] Checking registration state... Jul 24 08:41:28.238478 osdx wwan-accessibility-control[77700]: [Core/wwan0/1] Info: Execution stopped Jul 24 08:41:28.238685 osdx systemd[1]: Stopping wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control... Jul 24 08:41:28.240464 osdx systemd[1]: wwan-accessibility-control@wwan0pdp1.service: Deactivated successfully. Jul 24 08:41:28.240828 osdx systemd[1]: Stopped wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control. Jul 24 08:41:28.250879 osdx INFO[77914]: Stop DHCP client for cell0 Jul 24 08:41:28.258023 osdx dhclient[77921]: Killed old client process Jul 24 08:41:28.273947 osdx wwan-module[4990]: [Device/wwan0] Info: [PDP#1] Device registered and PS attached successfully Jul 24 08:41:28.273962 osdx wwan-module[4990]: [Device/wwan0] Info: [PDP#1] Establishing network connection... Jul 24 08:41:28.315254 osdx OSDxCLI[76798]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 08:41:28.374500 osdx dhclient[77921]: DHCPRELEASE of 2.143.61.45 on cell0 to 2.143.61.46 port 67 Jul 24 08:41:28.500080 osdx wwan-module[4990]: [Device/wwan0] Info: [PDP#1] Network connection was successfully started: PDH=[3799647760] Jul 24 08:41:28.562312 osdx kernel: net _wwan0: link_state 0x0 -> 0x1 Jul 24 08:41:28.710320 osdx kernel: cell0: (slave _wwan0_1): Releasing backup interface Jul 24 08:41:28.890316 osdx kernel: cell0: (slave _wwan0_1): The slave device specified does not support setting the MAC address Jul 24 08:41:28.890451 osdx kernel: cell0: (slave _wwan0_1): making interface the new active one Jul 24 08:41:28.890509 osdx kernel: cell0: (slave _wwan0_1): Enslaving as an active interface with an up link Jul 24 08:41:28.902303 osdx kernel: 8021q: adding VLAN 0 to HW filter on device cell0 Jul 24 08:41:28.904117 osdx INFO[77969]: Start DHCP client for cell0 Jul 24 08:41:29.238600 osdx dhclient[77989]: DHCPDISCOVER on cell0 to 255.255.255.255 port 67 interval 5 Jul 24 08:41:29.241327 osdx dhclient[77989]: DHCPOFFER of 37.12.51.222 from 37.12.51.221 Jul 24 08:41:29.241377 osdx dhclient[77989]: DHCPREQUEST for 37.12.51.222 on cell0 to 255.255.255.255 port 67 Jul 24 08:41:29.243540 osdx dhclient[77989]: DHCPACK of 37.12.51.222 from 37.12.51.221 Jul 24 08:41:29.283702 osdx systemd[1]: Started wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control. Jul 24 08:41:29.296587 osdx wwan-accessibility-control[78001]: [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 U2FsdGVkX19fluRXt8sv0n446/UfFQj32UhiVmRktos= 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 the command controllers cellular wwan0 pdp 1 show network-data-connection on DUT0 and check whether the 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.82.222 IPv4 mask = 255.255.255.252 IPv4 gateway = 81.47.82.221 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 the 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=186 ms --- 8.8.8.8 ping statistics --- 1 packets transmitted, 1 received, 0% packet loss, time 0ms rtt min/avg/max/mdev = 185.832/185.832/185.832/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 the command system journal clear on DUT0.
Step 5: Run the command system journal show | cat on DUT0 and check whether the 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
Jul 24 20:38:43.000080 osdx systemd-journald[2152]: Runtime Journal (/run/log/journal/e45843c7472642b2abfe20cd3db6d993) is 8.0M, max 80.5M, 72.5M free. Jul 24 20:38:43.006965 osdx systemd-journald[2152]: Received client request to rotate journal, rotating. Jul 24 20:38:43.007146 osdx systemd-journald[2152]: Vacuuming done, freed 0B of archived journals from /run/log/journal/e45843c7472642b2abfe20cd3db6d993. Jul 24 20:38:43.092098 osdx OSDxCLI[916144]: User 'admin' executed a new command: 'system journal clear'. Jul 24 20:38:51.962764 osdx wwan-accessibility-control[922983]: [Core/wwan0/1] Warning: Previous packet pending - ID: 5479, Sequence: 0 Jul 24 20:38:51.962783 osdx wwan-accessibility-control[922983]: [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 the command system journal clear on 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 the 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 the command system journal show | cat on DUT0 and check whether the 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
Jul 24 20:39:25.406775 osdx systemd-journald[2152]: Runtime Journal (/run/log/journal/e45843c7472642b2abfe20cd3db6d993) is 8.0M, max 80.5M, 72.5M free. Jul 24 20:39:25.414011 osdx systemd-journald[2152]: Received client request to rotate journal, rotating. Jul 24 20:39:25.414191 osdx systemd-journald[2152]: Vacuuming done, freed 0B of archived journals from /run/log/journal/e45843c7472642b2abfe20cd3db6d993. Jul 24 20:39:25.496031 osdx OSDxCLI[916144]: User 'admin' executed a new command: 'system journal clear'. Jul 24 20:39:25.648817 osdx OSDxCLI[916144]: User 'admin' entered the configuration menu. Jul 24 20:39:25.750457 osdx OSDxCLI[916144]: User 'admin' added a new cfg line: 'set interfaces cellular cell0 traffic control in PLOSS'. Jul 24 20:39:25.845397 osdx OSDxCLI[916144]: User 'admin' added a new cfg line: 'set traffic control PLOSS type network-emulator packet-loss 100'. Jul 24 20:39:25.963628 osdx OSDxCLI[916144]: User 'admin' added a new cfg line: 'show changes'. Jul 24 20:39:26.121291 osdx ubnt-cfgd[923077]: inactive Jul 24 20:39:26.255922 osdx INFO[923088]: FRR daemons did not change Jul 24 20:39:26.329071 osdx (udev-worker)[923101]: ifb.cell0: Could not set Alias=, MACAddress=/MACAddressPolicy=, TransmitQueues=, ReceiveQueues=, TransmitQueueLength=, MTUBytes=, GenericSegmentOffloadMaxBytes= or GenericSegmentOffloadMaxSegments=, ignoring: Operation not supported Jul 24 20:39:26.329186 osdx (udev-worker)[923101]: Network interface NamePolicy= disabled on kernel command line. Jul 24 20:39:26.400705 osdx cfgd[1770]: [916144]Completed change to active configuration Jul 24 20:39:26.422024 osdx systemd[1]: osdx-ledsd.service: Sent signal SIGUSR2 to main process 2816 (osdx-ledsd) on client request. Jul 24 20:39:26.422059 osdx osdx-ledsd[2816]: Config changed, checking config paths Jul 24 20:39:26.425942 osdx OSDxCLI[916144]: User 'admin' committed the configuration. Jul 24 20:39:26.494259 osdx OSDxCLI[916144]: User 'admin' left the configuration menu. Jul 24 20:39:27.680890 osdx OSDxCLI[916144]: User 'admin' executed a new command: 'ping 8.8.8.8 count 1 size 56 timeout 1'. Jul 24 20:39:41.963753 osdx wwan-accessibility-control[922983]: [Core/wwan0/1] Warning: Previous packet pending - ID: 5479, Sequence: 5 Jul 24 20:39:41.963776 osdx wwan-accessibility-control[922983]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 1 - interval reduced to 10 seconds Jul 24 20:39:48.022067 osdx OSDxCLI[916144]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 20:39:49.386080 osdx OSDxCLI[916144]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 20:39:50.738081 osdx OSDxCLI[916144]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 20:39:51.964041 osdx wwan-accessibility-control[922983]: [Core/wwan0/1] Warning: Previous packet pending - ID: 5479, Sequence: 6 Jul 24 20:39:51.964061 osdx wwan-accessibility-control[922983]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 2 - interval reduced to 5 seconds Jul 24 20:39:52.102032 osdx OSDxCLI[916144]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 20:39:53.510078 osdx OSDxCLI[916144]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 20:39:54.926074 osdx OSDxCLI[916144]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 20:39:56.366080 osdx OSDxCLI[916144]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 20:39:56.964301 osdx wwan-accessibility-control[922983]: [Core/wwan0/1] Warning: Previous packet pending - ID: 5479, Sequence: 7 Jul 24 20:39:56.964319 osdx wwan-accessibility-control[922983]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 3 - interval reduced to 5 seconds Jul 24 20:39:57.818084 osdx OSDxCLI[916144]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 20:39:59.290070 osdx OSDxCLI[916144]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 20:40:00.774112 osdx OSDxCLI[916144]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 20:40:01.964566 osdx wwan-accessibility-control[922983]: [Core/wwan0/1] Warning: Previous packet pending - ID: 5479, Sequence: 8 Jul 24 20:40:01.964585 osdx wwan-accessibility-control[922983]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 4 - interval reduced to 5 seconds Jul 24 20:40:01.976568 osdx WARNING[923198]: [wwan0/1] Requesting reconnection... Jul 24 20:40:01.985685 osdx wwan-cli-socket[923200]: [Device/wwan0] Info: wwan-cli-socket reconnect 60000 wwan0 Jul 24 20:40:01.986234 osdx wwan-module[2851]: [Device/wwan0] Info: Reconnecting device... Jul 24 20:40:01.986251 osdx wwan-module[2851]: [Device/wwan0] Warning: [PDP#1] Requesting reconnection... Jul 24 20:40:01.986323 osdx wwan-module[2851]: [Device/wwan0] Info: WDS[0]: Stopping network connection... Jul 24 20:40:02.093127 osdx wwan-module[2851]: [Device/wwan0] Info: WDS[0]: Network connection was stopped! Jul 24 20:40:02.096785 osdx kernel: net _wwan0: link_state 0x1 -> 0x0 Jul 24 20:40:02.100187 osdx wwan-module[2851]: [Device/wwan0] Info: [PDP#1] Checking registration state... Jul 24 20:40:02.102828 osdx wwan-module[2851]: [Device/wwan0] Info: [PDP#1] Device registered and PS attached successfully Jul 24 20:40:02.102855 osdx wwan-module[2851]: [Device/wwan0] Info: [PDP#1] Establishing network connection... Jul 24 20:40:02.131176 osdx wwan-accessibility-control[922983]: [Core/wwan0/1] Info: Execution stopped Jul 24 20:40:02.131699 osdx systemd[1]: Stopping wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control... Jul 24 20:40:02.134644 osdx systemd[1]: wwan-accessibility-control@wwan0pdp1.service: Deactivated successfully. Jul 24 20:40:02.135175 osdx systemd[1]: Stopped wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control. Jul 24 20:40:02.151575 osdx INFO[923206]: Stop DHCP client for cell0 Jul 24 20:40:02.161231 osdx dhclient[923211]: Killed old client process Jul 24 20:40:02.269985 osdx OSDxCLI[916144]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 20:40:02.285143 osdx dhclient[923211]: DHCPRELEASE of 81.47.82.222 on cell0 to 81.47.82.221 port 67 Jul 24 20:40:02.331313 osdx wwan-module[2851]: [Device/wwan0] Info: [PDP#1] Network connection was successfully started: PDH=[3799243360] Jul 24 20:40:02.336766 osdx kernel: net _wwan0: link_state 0x0 -> 0x1 Jul 24 20:40:02.808832 osdx kernel: cell0: (slave _wwan0_1): Releasing backup interface Jul 24 20:40:03.036761 osdx kernel: cell0: (slave _wwan0_1): making interface the new active one Jul 24 20:40:03.036953 osdx kernel: cell0: (slave _wwan0_1): Enslaving as an active interface with an up link Jul 24 20:40:03.052767 osdx kernel: 8021q: adding VLAN 0 to HW filter on device cell0 Jul 24 20:40:03.062467 osdx INFO[923261]: Start DHCP client for cell0
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+uultS54+L2wX21Bqtu4m7NxbXxzLfrz4= 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 the command controllers cellular wwan0 pdp 1 show network-data-connection on DUT0 and check whether the 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 = 37.12.17.35 IPv4 mask = 255.255.255.248 IPv4 gateway = 37.12.17.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 the 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=32.9 ms --- 8.8.8.8 ping statistics --- 1 packets transmitted, 1 received, 0% packet loss, time 0ms rtt min/avg/max/mdev = 32.929/32.929/32.929/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 the command system journal clear on DUT0.
Step 5: Run the command system journal show | cat on DUT0 and check whether the 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
Jul 24 07:42:16.130787 osdx systemd-journald[2981]: Runtime Journal (/run/log/journal/e45843c7472642b2abfe20cd3db6d993) is 8.0M, max 80.5M, 72.5M free. Jul 24 07:42:16.138076 osdx systemd-journald[2981]: Received client request to rotate journal, rotating. Jul 24 07:42:16.138263 osdx systemd-journald[2981]: Vacuuming done, freed 0B of archived journals from /run/log/journal/e45843c7472642b2abfe20cd3db6d993. Jul 24 07:42:16.221462 osdx OSDxCLI[15033]: User 'admin' executed a new command: 'system journal clear'. Jul 24 07:42:25.182661 osdx wwan-accessibility-control[21909]: [Core/wwan0/1] Warning: Previous packet pending - ID: 21909, Sequence: 0 Jul 24 07:42:25.182683 osdx wwan-accessibility-control[21909]: [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 the command system journal clear on 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 the 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 the command system journal show | cat on DUT0 and check whether the 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
Jul 24 07:42:58.545978 osdx systemd-journald[2981]: Runtime Journal (/run/log/journal/e45843c7472642b2abfe20cd3db6d993) is 8.0M, max 80.5M, 72.5M free. Jul 24 07:42:58.553137 osdx systemd-journald[2981]: Received client request to rotate journal, rotating. Jul 24 07:42:58.553323 osdx systemd-journald[2981]: Vacuuming done, freed 0B of archived journals from /run/log/journal/e45843c7472642b2abfe20cd3db6d993. Jul 24 07:42:58.637592 osdx OSDxCLI[15033]: User 'admin' executed a new command: 'system journal clear'. Jul 24 07:42:58.786620 osdx OSDxCLI[15033]: User 'admin' entered the configuration menu. Jul 24 07:42:58.900404 osdx OSDxCLI[15033]: User 'admin' added a new cfg line: 'set interfaces cellular cell0 traffic control in PLOSS'. Jul 24 07:42:59.007036 osdx OSDxCLI[15033]: User 'admin' added a new cfg line: 'set traffic control PLOSS type network-emulator packet-loss 100'. Jul 24 07:42:59.124839 osdx OSDxCLI[15033]: User 'admin' added a new cfg line: 'show changes'. Jul 24 07:42:59.279305 osdx ubnt-cfgd[22003]: inactive Jul 24 07:42:59.458782 osdx INFO[22014]: FRR daemons did not change Jul 24 07:42:59.569340 osdx (udev-worker)[22028]: ifb.cell0: Could not set Alias=, MACAddress=/MACAddressPolicy=, TransmitQueues=, ReceiveQueues=, TransmitQueueLength=, MTUBytes=, GenericSegmentOffloadMaxBytes= or GenericSegmentOffloadMaxSegments=, ignoring: Operation not supported Jul 24 07:42:59.569443 osdx (udev-worker)[22028]: Network interface NamePolicy= disabled on kernel command line. Jul 24 07:42:59.630084 osdx kernel: Mirror/redirect action on Jul 24 07:42:59.654077 osdx kernel: netem: version 1.3 Jul 24 07:42:59.705727 osdx cfgd[2615]: [15033]Completed change to active configuration Jul 24 07:42:59.727371 osdx systemd[1]: osdx-ledsd.service: Sent signal SIGUSR2 to main process 3514 (osdx-ledsd) on client request. Jul 24 07:42:59.727405 osdx osdx-ledsd[3514]: Config changed, checking config paths Jul 24 07:42:59.731493 osdx OSDxCLI[15033]: User 'admin' committed the configuration. Jul 24 07:42:59.843788 osdx OSDxCLI[15033]: User 'admin' left the configuration menu. Jul 24 07:43:01.070336 osdx OSDxCLI[15033]: User 'admin' executed a new command: 'ping 8.8.8.8 count 1 size 56 timeout 1'. Jul 24 07:43:15.183617 osdx wwan-accessibility-control[21909]: [Core/wwan0/1] Warning: Previous packet pending - ID: 21909, Sequence: 5 Jul 24 07:43:15.183639 osdx wwan-accessibility-control[21909]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 1 - interval reduced to 10 seconds Jul 24 07:43:21.415433 osdx OSDxCLI[15033]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 07:43:22.779423 osdx OSDxCLI[15033]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 07:43:24.331406 osdx OSDxCLI[15033]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 07:43:25.183869 osdx wwan-accessibility-control[21909]: [Core/wwan0/1] Warning: Previous packet pending - ID: 21909, Sequence: 6 Jul 24 07:43:25.183889 osdx wwan-accessibility-control[21909]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 2 - interval reduced to 5 seconds Jul 24 07:43:25.751386 osdx OSDxCLI[15033]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 07:43:27.167444 osdx OSDxCLI[15033]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 07:43:28.587365 osdx OSDxCLI[15033]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 07:43:30.011458 osdx OSDxCLI[15033]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 07:43:30.184129 osdx wwan-accessibility-control[21909]: [Core/wwan0/1] Warning: Previous packet pending - ID: 21909, Sequence: 7 Jul 24 07:43:30.184148 osdx wwan-accessibility-control[21909]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 3 - interval reduced to 5 seconds Jul 24 07:43:31.475447 osdx OSDxCLI[15033]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 07:43:33.499438 osdx OSDxCLI[15033]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 07:43:34.995416 osdx OSDxCLI[15033]: User 'admin' executed a new command: 'system journal show | cat'. Jul 24 07:43:35.184391 osdx wwan-accessibility-control[21909]: [Core/wwan0/1] Warning: Previous packet pending - ID: 21909, Sequence: 8 Jul 24 07:43:35.184409 osdx wwan-accessibility-control[21909]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 4 - interval reduced to 5 seconds Jul 24 07:43:35.197170 osdx WARNING[22124]: [wwan0/1] Requesting reconnection... Jul 24 07:43:35.206713 osdx wwan-cli-socket[22126]: [Device/wwan0] Info: wwan-cli-socket reconnect 60000 wwan0 Jul 24 07:43:35.207298 osdx wwan-module[3550]: [Device/wwan0] Info: Reconnecting device... Jul 24 07:43:35.207316 osdx wwan-module[3550]: [Device/wwan0] Warning: [PDP#1] Requesting reconnection... Jul 24 07:43:35.207390 osdx wwan-module[3550]: [Device/wwan0] Info: WDS[0]: Stopping network connection... Jul 24 07:43:35.294952 osdx wwan-module[3550]: [Device/wwan0] Info: WDS[0]: Network connection was stopped! Jul 24 07:43:35.302098 osdx kernel: net _wwan0: link_state 0x1 -> 0x0 Jul 24 07:43:35.306722 osdx wwan-module[3550]: [Device/wwan0] Info: [PDP#1] Checking registration state... Jul 24 07:43:35.333960 osdx wwan-accessibility-control[21909]: [Core/wwan0/1] Info: Execution stopped Jul 24 07:43:35.334370 osdx systemd[1]: Stopping wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control... Jul 24 07:43:35.337196 osdx systemd[1]: wwan-accessibility-control@wwan0pdp1.service: Deactivated successfully. Jul 24 07:43:35.337747 osdx systemd[1]: Stopped wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control. Jul 24 07:43:35.354585 osdx INFO[22130]: Stop DHCP client for cell0 Jul 24 07:43:35.358936 osdx wwan-module[3550]: [Device/wwan0] Info: [PDP#1] Device registered and PS attached successfully Jul 24 07:43:35.358961 osdx wwan-module[3550]: [Device/wwan0] Info: [PDP#1] Establishing network connection... Jul 24 07:43:35.364754 osdx dhclient[22137]: Killed old client process Jul 24 07:43:35.486339 osdx dhclient[22137]: DHCPRELEASE of 37.12.17.35 on cell0 to 37.12.17.36 port 67 Jul 24 07:43:35.553144 osdx wwan-module[3550]: [Device/wwan0] Info: [PDP#1] Network connection was successfully started: PDH=[3799222432] Jul 24 07:43:35.618076 osdx kernel: net _wwan0: link_state 0x0 -> 0x1 Jul 24 07:43:36.022103 osdx kernel: cell0: (slave _wwan0_1): Releasing backup interface Jul 24 07:43:36.258861 osdx kernel: cell0: (slave _wwan0_1): The slave device specified does not support setting the MAC address Jul 24 07:43:36.259071 osdx kernel: cell0: (slave _wwan0_1): making interface the new active one Jul 24 07:43:36.262202 osdx kernel: cell0: (slave _wwan0_1): Enslaving as an active interface with an up link Jul 24 07:43:36.278080 osdx kernel: 8021q: adding VLAN 0 to HW filter on device cell0 Jul 24 07:43:36.282839 osdx INFO[22187]: Start DHCP client for cell0