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/ktXJkKKNGexan2rMBCV7n6Zb8AevvclU= 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 = 37.12.69.136 IPv4 mask = 255.255.255.240 IPv4 gateway = 37.12.69.137 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=24.4 ms --- 8.8.8.8 ping statistics --- 1 packets transmitted, 1 received, 0% packet loss, time 0ms rtt min/avg/max/mdev = 24.369/24.369/24.369/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
May 04 23:23:26.439415 osdx systemd-journald[105063]: Runtime Journal (/run/log/journal/e690253e028a4db0b28f57fc268485ec) is 8.0M, max 80.5M, 72.5M free. May 04 23:23:26.446475 osdx systemd-journald[105063]: Received client request to rotate journal, rotating. May 04 23:23:26.446655 osdx systemd-journald[105063]: Vacuuming done, freed 0B of archived journals from /run/log/journal/e690253e028a4db0b28f57fc268485ec. May 04 23:23:26.528159 osdx OSDxCLI[1073719]: User 'admin' executed a new command: 'system journal clear'. May 04 23:23:33.861957 osdx wwan-accessibility-control[1084080]: [Core/wwan0/1] Warning: Previous packet pending - ID: 35504, Sequence: 0 May 04 23:23:33.861980 osdx wwan-accessibility-control[1084080]: [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
May 04 23:24:08.832049 osdx systemd-journald[105063]: Runtime Journal (/run/log/journal/e690253e028a4db0b28f57fc268485ec) is 8.0M, max 80.5M, 72.5M free. May 04 23:24:08.839540 osdx systemd-journald[105063]: Received client request to rotate journal, rotating. May 04 23:24:08.839725 osdx systemd-journald[105063]: Vacuuming done, freed 0B of archived journals from /run/log/journal/e690253e028a4db0b28f57fc268485ec. May 04 23:24:08.924138 osdx OSDxCLI[1073719]: User 'admin' executed a new command: 'system journal clear'. May 04 23:24:09.051871 osdx OSDxCLI[1073719]: User 'admin' entered the configuration menu. May 04 23:24:09.149965 osdx OSDxCLI[1073719]: User 'admin' added a new cfg line: 'set interfaces cellular cell0 traffic control in PLOSS'. May 04 23:24:09.241122 osdx OSDxCLI[1073719]: User 'admin' added a new cfg line: 'set traffic control PLOSS type network-emulator packet-loss 100'. May 04 23:24:09.355370 osdx OSDxCLI[1073719]: User 'admin' added a new cfg line: 'show changes'. May 04 23:24:09.505359 osdx ubnt-cfgd[1084179]: inactive May 04 23:24:09.631323 osdx INFO[1084189]: FRR daemons did not change May 04 23:24:09.735391 osdx (udev-worker)[1084209]: ifb.cell0: Could not set Alias=, MACAddress=/MACAddressPolicy=, TransmitQueues=, ReceiveQueues=, TransmitQueueLength=, MTUBytes=, GenericSegmentOffloadMaxBytes= or GenericSegmentOffloadMaxSegments=, ignoring: Operation not supported May 04 23:24:09.735489 osdx (udev-worker)[1084209]: Network interface NamePolicy= disabled on kernel command line. May 04 23:24:09.750345 osdx cfgd[1773]: [1073719]Completed change to active configuration May 04 23:24:09.779333 osdx systemd[1]: osdx-ledsd.service: Sent signal SIGUSR2 to main process 2439 (osdx-ledsd) on client request. May 04 23:24:09.779392 osdx osdx-ledsd[2439]: Config changed, checking config paths May 04 23:24:09.783953 osdx OSDxCLI[1073719]: User 'admin' committed the configuration. May 04 23:24:09.814398 osdx OSDxCLI[1073719]: User 'admin' left the configuration menu. May 04 23:24:11.028922 osdx OSDxCLI[1073719]: User 'admin' executed a new command: 'ping 8.8.8.8 count 1 size 56 timeout 1'. May 04 23:24:23.863039 osdx wwan-accessibility-control[1084080]: [Core/wwan0/1] Warning: Previous packet pending - ID: 35504, Sequence: 5 May 04 23:24:23.863057 osdx wwan-accessibility-control[1084080]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 1 - interval reduced to 10 seconds May 04 23:24:31.374133 osdx OSDxCLI[1073719]: User 'admin' executed a new command: 'system journal show | cat'. May 04 23:24:32.710135 osdx OSDxCLI[1073719]: User 'admin' executed a new command: 'system journal show | cat'. May 04 23:24:33.863323 osdx wwan-accessibility-control[1084080]: [Core/wwan0/1] Warning: Previous packet pending - ID: 35504, Sequence: 6 May 04 23:24:33.863344 osdx wwan-accessibility-control[1084080]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 2 - interval reduced to 5 seconds May 04 23:24:34.070097 osdx OSDxCLI[1073719]: User 'admin' executed a new command: 'system journal show | cat'. May 04 23:24:35.458144 osdx OSDxCLI[1073719]: User 'admin' executed a new command: 'system journal show | cat'. May 04 23:24:36.862148 osdx OSDxCLI[1073719]: User 'admin' executed a new command: 'system journal show | cat'. May 04 23:24:38.278152 osdx OSDxCLI[1073719]: User 'admin' executed a new command: 'system journal show | cat'. May 04 23:24:38.863595 osdx wwan-accessibility-control[1084080]: [Core/wwan0/1] Warning: Previous packet pending - ID: 35504, Sequence: 7 May 04 23:24:38.863612 osdx wwan-accessibility-control[1084080]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 3 - interval reduced to 5 seconds May 04 23:24:39.726164 osdx OSDxCLI[1073719]: User 'admin' executed a new command: 'system journal show | cat'. May 04 23:24:41.182143 osdx OSDxCLI[1073719]: User 'admin' executed a new command: 'system journal show | cat'. May 04 23:24:42.650112 osdx OSDxCLI[1073719]: User 'admin' executed a new command: 'system journal show | cat'. May 04 23:24:43.863870 osdx wwan-accessibility-control[1084080]: [Core/wwan0/1] Warning: Previous packet pending - ID: 35504, Sequence: 8 May 04 23:24:43.863892 osdx wwan-accessibility-control[1084080]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 4 - interval reduced to 5 seconds May 04 23:24:43.876169 osdx WARNING[1084312]: [wwan0/1] Requesting reconnection... May 04 23:24:43.885423 osdx wwan-cli-socket[1084314]: [Device/wwan0] Info: wwan-cli-socket reconnect 60000 wwan0 May 04 23:24:43.885996 osdx wwan-module[1073704]: [Device/wwan0] Info: Reconnecting device... May 04 23:24:43.886013 osdx wwan-module[1073704]: [Device/wwan0] Warning: [PDP#1] Requesting reconnection... May 04 23:24:43.886078 osdx wwan-module[1073704]: [Device/wwan0] Info: WDS[0]: Stopping network connection... May 04 23:24:43.997609 osdx wwan-module[1073704]: [Device/wwan0] Info: WDS[0]: Network connection was stopped! May 04 23:24:44.004825 osdx kernel: net _wwan0: link_state 0x1 -> 0x0 May 04 23:24:44.007422 osdx wwan-module[1073704]: [Device/wwan0] Info: Checking registration state... May 04 23:24:44.036211 osdx wwan-accessibility-control[1084080]: [Core/wwan0/1] Info: Execution stopped May 04 23:24:44.037000 osdx systemd[1]: Stopping wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control... May 04 23:24:44.039857 osdx systemd[1]: wwan-accessibility-control@wwan0pdp1.service: Deactivated successfully. May 04 23:24:44.040398 osdx systemd[1]: Stopped wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control. May 04 23:24:44.056702 osdx INFO[1084319]: Stop DHCP client for cell0 May 04 23:24:44.066895 osdx dhclient[1084325]: Killed old client process May 04 23:24:44.125511 osdx wwan-module[1073704]: [Device/wwan0] Info: Device registered and PS attached successfully May 04 23:24:44.125528 osdx wwan-module[1073704]: [Device/wwan0] Info: [PDP#1] Establishing network connection... May 04 23:24:44.130148 osdx OSDxCLI[1073719]: User 'admin' executed a new command: 'system journal show | cat'. May 04 23:24:44.189075 osdx dhclient[1084325]: DHCPRELEASE of 37.12.69.136 on cell0 to 37.12.69.137 port 67 May 04 23:24:44.384238 osdx wwan-module[1073704]: [Device/wwan0] Info: [PDP#1] Network connection was successfully started: PDH=[2557747600] May 04 23:24:44.512831 osdx kernel: net _wwan0: link_state 0x0 -> 0x1 May 04 23:24:44.712829 osdx kernel: cell0: (slave _wwan0_1): Releasing backup interface May 04 23:24:44.940813 osdx kernel: cell0: (slave _wwan0_1): The slave device specified does not support setting the MAC address May 04 23:24:44.944817 osdx kernel: cell0: (slave _wwan0_1): making interface the new active one May 04 23:24:44.945013 osdx kernel: cell0: (slave _wwan0_1): Enslaving as an active interface with an up link May 04 23:24:44.960812 osdx kernel: 8021q: adding VLAN 0 to HW filter on device cell0 May 04 23:24:44.963828 osdx INFO[1084375]: 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+bPhqs6vKO95FZm+MWhM+AfY5KRIAdiF4= 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 = 88.31.110.138 IPv4 mask = 255.255.255.252 IPv4 gateway = 88.31.110.137 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=150 ms --- 8.8.8.8 ping statistics --- 1 packets transmitted, 1 received, 0% packet loss, time 0ms rtt min/avg/max/mdev = 149.860/149.860/149.860/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
May 04 15:25:21.054505 osdx systemd-journald[600215]: Runtime Journal (/run/log/journal/177bde4b449c4bb0bc32ef34b489934d) is 8.0M, max 69.4M, 61.4M free. May 04 15:25:21.056082 osdx systemd-journald[600215]: Received client request to rotate journal, rotating. May 04 15:25:21.056139 osdx systemd-journald[600215]: Vacuuming done, freed 0B of archived journals from /run/log/journal/177bde4b449c4bb0bc32ef34b489934d. May 04 15:25:21.109166 osdx OSDxCLI[975808]: User 'admin' executed a new command: 'system journal clear'. May 04 15:25:24.892862 osdx wwan-accessibility-control[982851]: [Core/wwan0/1] Warning: Previous packet pending - ID: 65347, Sequence: 0 May 04 15:25:24.892870 osdx wwan-accessibility-control[982851]: [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
May 04 15:26:03.366273 osdx systemd-journald[600215]: Runtime Journal (/run/log/journal/177bde4b449c4bb0bc32ef34b489934d) is 8.0M, max 69.4M, 61.4M free. May 04 15:26:03.368088 osdx systemd-journald[600215]: Received client request to rotate journal, rotating. May 04 15:26:03.368164 osdx systemd-journald[600215]: Vacuuming done, freed 0B of archived journals from /run/log/journal/177bde4b449c4bb0bc32ef34b489934d. May 04 15:26:03.421138 osdx OSDxCLI[975808]: User 'admin' executed a new command: 'system journal clear'. May 04 15:26:03.586752 osdx OSDxCLI[975808]: User 'admin' entered the configuration menu. May 04 15:26:03.651904 osdx OSDxCLI[975808]: User 'admin' added a new cfg line: 'set interfaces cellular cell0 traffic control in PLOSS'. May 04 15:26:03.726927 osdx OSDxCLI[975808]: User 'admin' added a new cfg line: 'set traffic control PLOSS type network-emulator packet-loss 100'. May 04 15:26:03.819090 osdx OSDxCLI[975808]: User 'admin' added a new cfg line: 'show changes'. May 04 15:26:03.903636 osdx ubnt-cfgd[982952]: inactive May 04 15:26:03.945086 osdx INFO[982962]: FRR daemons did not change May 04 15:26:03.970463 osdx (udev-worker)[982980]: ifb.cell0: Could not set Alias=, MACAddress=/MACAddressPolicy=, TransmitQueues=, ReceiveQueues=, TransmitQueueLength=, MTUBytes=, GenericSegmentOffloadMaxBytes= or GenericSegmentOffloadMaxSegments=, ignoring: Operation not supported May 04 15:26:03.970492 osdx (udev-worker)[982980]: Network interface NamePolicy= disabled on kernel command line. May 04 15:26:03.977531 osdx cfgd[2006]: [975808]Completed change to active configuration May 04 15:26:03.980667 osdx OSDxCLI[975808]: User 'admin' committed the configuration. May 04 15:26:04.009697 osdx OSDxCLI[975808]: User 'admin' left the configuration menu. May 04 15:26:05.149337 osdx OSDxCLI[975808]: User 'admin' executed a new command: 'ping 8.8.8.8 count 1 size 56 timeout 1'. May 04 15:26:14.893475 osdx wwan-accessibility-control[982851]: [Core/wwan0/1] Warning: Previous packet pending - ID: 65347, Sequence: 5 May 04 15:26:14.893481 osdx wwan-accessibility-control[982851]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 1 - interval reduced to 10 seconds May 04 15:26:24.893632 osdx wwan-accessibility-control[982851]: [Core/wwan0/1] Warning: Previous packet pending - ID: 65347, Sequence: 6 May 04 15:26:24.893638 osdx wwan-accessibility-control[982851]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 2 - interval reduced to 5 seconds May 04 15:26:25.448511 osdx OSDxCLI[975808]: User 'admin' executed a new command: 'system journal show | cat'. May 04 15:26:26.796506 osdx OSDxCLI[975808]: User 'admin' executed a new command: 'system journal show | cat'. May 04 15:26:28.116502 osdx OSDxCLI[975808]: User 'admin' executed a new command: 'system journal show | cat'. May 04 15:26:29.456525 osdx OSDxCLI[975808]: User 'admin' executed a new command: 'system journal show | cat'. May 04 15:26:29.893861 osdx wwan-accessibility-control[982851]: [Core/wwan0/1] Warning: Previous packet pending - ID: 65347, Sequence: 7 May 04 15:26:29.893866 osdx wwan-accessibility-control[982851]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 3 - interval reduced to 5 seconds May 04 15:26:30.860511 osdx OSDxCLI[975808]: User 'admin' executed a new command: 'system journal show | cat'. May 04 15:26:32.240509 osdx OSDxCLI[975808]: User 'admin' executed a new command: 'system journal show | cat'. May 04 15:26:33.640501 osdx OSDxCLI[975808]: User 'admin' executed a new command: 'system journal show | cat'. May 04 15:26:34.893995 osdx wwan-accessibility-control[982851]: [Core/wwan0/1] Warning: Previous packet pending - ID: 65347, Sequence: 8 May 04 15:26:34.894002 osdx wwan-accessibility-control[982851]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 4 - interval reduced to 5 seconds May 04 15:26:34.897509 osdx WARNING[983070]: [wwan0/1] Requesting reconnection... May 04 15:26:34.900300 osdx wwan-cli-socket[983072]: [Device/wwan0] Info: wwan-cli-socket reconnect 60000 wwan0 May 04 15:26:34.900536 osdx wwan-module[3790]: [Device/wwan0] Info: Reconnecting device... May 04 15:26:34.900542 osdx wwan-module[3790]: [Device/wwan0] Warning: [PDP#1] Requesting reconnection... May 04 15:26:34.900570 osdx wwan-module[3790]: [Device/wwan0] Info: WDS[0]: Stopping network connection... May 04 15:26:35.039609 osdx wwan-module[3790]: [Device/wwan0] Info: WDS[0]: Network connection was stopped! May 04 15:26:35.044089 osdx kernel: net _wwan0: link_state 0x1 -> 0x0 May 04 15:26:35.045156 osdx wwan-module[3790]: [Device/wwan0] Info: Checking registration state... May 04 15:26:35.050221 osdx systemd[1]: Stopping wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control... May 04 15:26:35.050265 osdx wwan-accessibility-control[982851]: [Core/wwan0/1] Info: Execution stopped May 04 15:26:35.050936 osdx systemd[1]: wwan-accessibility-control@wwan0pdp1.service: Deactivated successfully. May 04 15:26:35.051100 osdx systemd[1]: Stopped wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control. May 04 15:26:35.052527 osdx OSDxCLI[975808]: User 'admin' executed a new command: 'system journal show | cat'. May 04 15:26:35.054911 osdx INFO[983076]: Stop DHCP client for cell0 May 04 15:26:35.058020 osdx dhclient[983083]: Killed old client process May 04 15:26:35.103560 osdx wwan-module[3790]: [Device/wwan0] Info: Device registered and PS attached successfully May 04 15:26:35.103567 osdx wwan-module[3790]: [Device/wwan0] Info: [PDP#1] Establishing network connection... May 04 15:26:35.180217 osdx dhclient[983083]: DHCPRELEASE of 88.31.110.138 on cell0 to 88.31.110.137 port 67 May 04 15:26:35.328087 osdx kernel: cell0: (slave _wwan0_1): Releasing backup interface May 04 15:26:35.328467 osdx wwan-module[3790]: [Device/wwan0] Info: [PDP#1] Network connection was successfully started: PDH=[3799353584] May 04 15:26:35.392092 osdx kernel: net _wwan0: link_state 0x0 -> 0x1 May 04 15:26:35.408088 osdx kernel: cell0: (slave _wwan0_1): The slave device specified does not support setting the MAC address May 04 15:26:35.408154 osdx kernel: cell0: (slave _wwan0_1): making interface the new active one May 04 15:26:35.408171 osdx kernel: cell0: (slave _wwan0_1): Enslaving as an active interface with an up link May 04 15:26:35.412095 osdx kernel: 8021q: adding VLAN 0 to HW filter on device cell0 May 04 15:26:35.412848 osdx INFO[983138]: Start DHCP client for cell0 May 04 15:26:35.600277 osdx dhclient[983151]: DHCPDISCOVER on cell0 to 255.255.255.255 port 67 interval 6 May 04 15:26:35.602768 osdx dhclient[983151]: DHCPOFFER of 5.205.213.206 from 5.205.213.205 May 04 15:26:35.602797 osdx dhclient[983151]: DHCPREQUEST for 5.205.213.206 on cell0 to 255.255.255.255 port 67 May 04 15:26:35.604620 osdx dhclient[983151]: DHCPACK of 5.205.213.206 from 5.205.213.205 May 04 15:26:35.636570 osdx systemd[1]: Started wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control. May 04 15:26:35.640510 osdx wwan-accessibility-control[983163]: [Core/wwan0/1] Info: Starting '/osdx/sbin/wwan-accessibility-control'... May 04 15:26:35.716038 osdx dhclient[983151]: bound to 5.205.213.206 -- renewal in 3460 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 U2FsdGVkX19/qFkILJ5d7GjjnS2rR8WqFFpg2WYuxBE= 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 = 37.13.151.116 IPv4 mask = 255.255.255.248 IPv4 gateway = 37.13.151.117 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=30.4 ms --- 8.8.8.8 ping statistics --- 1 packets transmitted, 1 received, 0% packet loss, time 0ms rtt min/avg/max/mdev = 30.432/30.432/30.432/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
May 04 16:44:45.898802 osdx systemd-journald[66891]: Runtime Journal (/run/log/journal/e690253e028a4db0b28f57fc268485ec) is 8.2M, max 80.3M, 72.0M free. May 04 16:44:45.903838 osdx systemd-journald[66891]: Received client request to rotate journal, rotating. May 04 16:44:45.903953 osdx systemd-journald[66891]: Vacuuming done, freed 0B of archived journals from /run/log/journal/e690253e028a4db0b28f57fc268485ec. May 04 16:44:45.988285 osdx OSDxCLI[265579]: User 'admin' executed a new command: 'system journal clear'. May 04 16:44:50.532715 osdx wwan-accessibility-control[280939]: [Core/wwan0/1] Warning: Previous packet pending - ID: 18795, Sequence: 0 May 04 16:44:50.532730 osdx wwan-accessibility-control[280939]: [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
May 04 16:45:28.325697 osdx systemd-journald[66891]: Runtime Journal (/run/log/journal/e690253e028a4db0b28f57fc268485ec) is 8.0M, max 80.3M, 72.3M free. May 04 16:45:28.330754 osdx systemd-journald[66891]: Received client request to rotate journal, rotating. May 04 16:45:28.330864 osdx systemd-journald[66891]: Vacuuming done, freed 0B of archived journals from /run/log/journal/e690253e028a4db0b28f57fc268485ec. May 04 16:45:28.404287 osdx OSDxCLI[265579]: User 'admin' executed a new command: 'system journal clear'. May 04 16:45:28.558910 osdx OSDxCLI[265579]: User 'admin' entered the configuration menu. May 04 16:45:28.677941 osdx OSDxCLI[265579]: User 'admin' added a new cfg line: 'set interfaces cellular cell0 traffic control in PLOSS'. May 04 16:45:28.782249 osdx OSDxCLI[265579]: User 'admin' added a new cfg line: 'set traffic control PLOSS type network-emulator packet-loss 100'. May 04 16:45:28.914978 osdx OSDxCLI[265579]: User 'admin' added a new cfg line: 'show changes'. May 04 16:45:29.091366 osdx ubnt-cfgd[281040]: inactive May 04 16:45:29.187408 osdx INFO[281050]: FRR daemons did not change May 04 16:45:29.279658 osdx (udev-worker)[295]: ifb.cell0: Could not set Alias=, MACAddress=/MACAddressPolicy=, TransmitQueues=, ReceiveQueues=, TransmitQueueLength=, MTUBytes=, GenericSegmentOffloadMaxBytes= or GenericSegmentOffloadMaxSegments=, ignoring: Operation not supported May 04 16:45:29.325934 osdx kernel: Mirror/redirect action on May 04 16:45:29.336865 osdx cfgd[3855]: [265579]Completed change to active configuration May 04 16:45:29.357196 osdx systemd[1]: osdx-ledsd.service: Sent signal SIGUSR2 to main process 4650 (osdx-ledsd) on client request. May 04 16:45:29.357224 osdx osdx-ledsd[4650]: Config changed, checking config paths May 04 16:45:29.359859 osdx OSDxCLI[265579]: User 'admin' committed the configuration. May 04 16:45:29.393958 osdx OSDxCLI[265579]: User 'admin' left the configuration menu. May 04 16:45:30.592840 osdx OSDxCLI[265579]: User 'admin' executed a new command: 'ping 8.8.8.8 count 1 size 56 timeout 1'. May 04 16:45:40.533494 osdx wwan-accessibility-control[280939]: [Core/wwan0/1] Warning: Previous packet pending - ID: 18795, Sequence: 5 May 04 16:45:40.533508 osdx wwan-accessibility-control[280939]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 1 - interval reduced to 10 seconds May 04 16:45:50.533702 osdx wwan-accessibility-control[280939]: [Core/wwan0/1] Warning: Previous packet pending - ID: 18795, Sequence: 6 May 04 16:45:50.533716 osdx wwan-accessibility-control[280939]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 2 - interval reduced to 5 seconds May 04 16:45:50.978850 osdx OSDxCLI[265579]: User 'admin' executed a new command: 'system journal show | cat'. May 04 16:45:52.350833 osdx OSDxCLI[265579]: User 'admin' executed a new command: 'system journal show | cat'. May 04 16:45:53.738841 osdx OSDxCLI[265579]: User 'admin' executed a new command: 'system journal show | cat'. May 04 16:45:55.138865 osdx OSDxCLI[265579]: User 'admin' executed a new command: 'system journal show | cat'. May 04 16:45:55.533913 osdx wwan-accessibility-control[280939]: [Core/wwan0/1] Warning: Previous packet pending - ID: 18795, Sequence: 7 May 04 16:45:55.533928 osdx wwan-accessibility-control[280939]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 3 - interval reduced to 5 seconds May 04 16:45:56.578835 osdx OSDxCLI[265579]: User 'admin' executed a new command: 'system journal show | cat'. May 04 16:45:58.026855 osdx OSDxCLI[265579]: User 'admin' executed a new command: 'system journal show | cat'. May 04 16:45:59.474846 osdx OSDxCLI[265579]: User 'admin' executed a new command: 'system journal show | cat'. May 04 16:46:00.534117 osdx wwan-accessibility-control[280939]: [Core/wwan0/1] Warning: Previous packet pending - ID: 18795, Sequence: 8 May 04 16:46:00.534132 osdx wwan-accessibility-control[280939]: [Core/wwan0/1] Warning: Connection in failure - consecutive failures: 4 - interval reduced to 5 seconds May 04 16:46:00.544408 osdx WARNING[281157]: [wwan0/1] Requesting reconnection... May 04 16:46:00.550986 osdx wwan-cli-socket[281159]: [Device/wwan0] Info: wwan-cli-socket reconnect 60000 wwan0 May 04 16:46:00.551389 osdx wwan-module[4684]: [Device/wwan0] Info: Reconnecting device... May 04 16:46:00.551401 osdx wwan-module[4684]: [Device/wwan0] Warning: [PDP#1] Requesting reconnection... May 04 16:46:00.551454 osdx wwan-module[4684]: [Device/wwan0] Info: WDS[0]: Stopping network connection... May 04 16:46:00.679773 osdx wwan-module[4684]: [Device/wwan0] Info: WDS[0]: Network connection was stopped! May 04 16:46:00.685923 osdx kernel: net _wwan0: link_state 0x1 -> 0x0 May 04 16:46:00.688160 osdx wwan-module[4684]: [Device/wwan0] Info: Checking registration state... May 04 16:46:00.708828 osdx wwan-accessibility-control[280939]: [Core/wwan0/1] Info: Execution stopped May 04 16:46:00.708970 osdx systemd[1]: Stopping wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control... May 04 16:46:00.711038 osdx systemd[1]: wwan-accessibility-control@wwan0pdp1.service: Deactivated successfully. May 04 16:46:00.711425 osdx systemd[1]: Stopped wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control. May 04 16:46:00.721342 osdx INFO[281168]: Stop DHCP client for cell0 May 04 16:46:00.728303 osdx dhclient[281175]: Killed old client process May 04 16:46:00.743660 osdx wwan-module[4684]: [Device/wwan0] Info: Device registered and PS attached successfully May 04 16:46:00.743674 osdx wwan-module[4684]: [Device/wwan0] Info: [PDP#1] Establishing network connection... May 04 16:46:00.850087 osdx dhclient[281175]: DHCPRELEASE of 37.13.151.116 on cell0 to 37.13.151.117 port 67 May 04 16:46:00.976259 osdx wwan-module[4684]: [Device/wwan0] Info: [PDP#1] Network connection was successfully started: PDH=[3799337680] May 04 16:46:01.026988 osdx OSDxCLI[265579]: User 'admin' executed a new command: 'system journal show | cat'. May 04 16:46:01.033907 osdx kernel: net _wwan0: link_state 0x0 -> 0x1 May 04 16:46:01.182008 osdx kernel: cell0: (slave _wwan0_1): Releasing backup interface May 04 16:46:01.361929 osdx kernel: cell0: (slave _wwan0_1): The slave device specified does not support setting the MAC address May 04 16:46:01.362053 osdx kernel: cell0: (slave _wwan0_1): making interface the new active one May 04 16:46:01.362096 osdx kernel: cell0: (slave _wwan0_1): Enslaving as an active interface with an up link May 04 16:46:01.373909 osdx kernel: 8021q: adding VLAN 0 to HW filter on device cell0 May 04 16:46:01.375869 osdx INFO[281222]: Start DHCP client for cell0 May 04 16:46:01.722294 osdx dhclient[281242]: DHCPDISCOVER on cell0 to 255.255.255.255 port 67 interval 3 May 04 16:46:01.725043 osdx dhclient[281242]: DHCPOFFER of 5.205.232.69 from 5.205.232.70 May 04 16:46:01.725101 osdx dhclient[281242]: DHCPREQUEST for 5.205.232.69 on cell0 to 255.255.255.255 port 67 May 04 16:46:01.727236 osdx dhclient[281242]: DHCPACK of 5.205.232.69 from 5.205.232.70 May 04 16:46:01.775861 osdx systemd[1]: Started wwan-accessibility-control@wwan0pdp1.service - WWAN Accessibility Control. May 04 16:46:01.795163 osdx wwan-accessibility-control[281256]: [Core/wwan0/1] Info: Starting '/osdx/sbin/wwan-accessibility-control'... May 04 16:46:02.047638 osdx dhclient[281242]: bound to 5.205.232.69 -- renewal in 2922 seconds.