$ Date Time State Comment Pressure Bel Pos Batt Volt Batt Amps CTD Temp CTD Sal Raw Press LPF Press LPF Vel Diff Vel Diff 2 Vel Slope of LPF Press. CTD Sample Period LPS331 Press LPS311 Temp SHT21 Humid SHT21 Temp PID SP PID PV PID Y1 PID Y2 PID I PID v PID u PID uCPS PID h FLBB Topt Tphase Rphase NO3 pH GPS Irid Modem $ 16-Aug-2018 23:45:45.550 UnitTest Reset Controller Status Register Value = 65792 $ 16-Aug-2018 23:45:45.555 UnitTest File Name = \SD\data\2018-8-16T23-45-44 $ 16-Aug-2018 23:45:45.559 UnitTest Volume Total Size: 7939817472 $ 16-Aug-2018 23:45:45.564 UnitTest Volume Free Space: 7921532928 $ 16-Aug-2018 23:45:45.568 UnitTest PlatVel PID K = 352 $ 16-Aug-2018 23:45:45.572 UnitTest PlatVel PID Ti = 50000 $ 16-Aug-2018 23:45:45.576 UnitTest PlatVel PID Td = 44 $ 16-Aug-2018 23:45:45.580 UnitTest PlatVel PID Tf = 10 $ 16-Aug-2018 23:45:45.584 UnitTest PlatVel PID Tt = 100 $ 16-Aug-2018 23:45:45.588 UnitTest PlatVel PID B = 1 $ 16-Aug-2018 23:45:45.592 UnitTest PlatVel PID uLow = -41.666666666666664 $ 16-Aug-2018 23:45:45.596 UnitTest PlatVel PID uHigh = 41.666666666666664 $ 16-Aug-2018 23:45:45.601 UnitTest PlatVel PID h = 3 $ 16-Aug-2018 23:45:45.604 UnitTest Engr Logger Initialized $ 16-Aug-2018 23:46:07.299 UnitTest Starting valve close $ 16-Aug-2018 23:46:16.323 UnitTest Sending Optode Wakeup $ 16-Aug-2018 23:46:20.333 UnitTest Set can pressure resolution to low $ 16-Aug-2018 23:46:20.337 UnitTest Set can pressure active $ 16-Aug-2018 23:46:20.348 UnitTest PTH Message 760.000000 25.966667 48.843903 26.842146 $ 16-Aug-2018 23:46:20.457 UnitTest PTH Message 1012.286865 25.866667 48.843903 26.842146 $ 16-Aug-2018 23:46:20.584 UnitTest Maximum number of profiles = 10 $ 16-Aug-2018 23:46:20.597 UnitTest Mission Timeout = 24:00:00 Mission Start Time: 08/16/2018 23:46:20 $ 16-Aug-2018 23:46:20.602 UnitTest Recovery mode = True $ 16-Aug-2018 23:46:20.622 UnitTest Park Sample Period = 00:10:00 $ 16-Aug-2018 23:46:20.634 UnitTest Descend table 0 Pressure, PV, Park Time, sample list: 100, -0.200, 01:00:00, 7 $ 16-Aug-2018 23:46:20.652 UnitTest Ascend table 0 Pressure, PV, Park Time, sample list: 100, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:20.669 UnitTest Ascend table 1 Pressure, PV, Park Time, sample list: 95, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:20.687 UnitTest Ascend table 2 Pressure, PV, Park Time, sample list: 90, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:20.704 UnitTest Ascend table 3 Pressure, PV, Park Time, sample list: 85, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:20.722 UnitTest Ascend table 4 Pressure, PV, Park Time, sample list: 80, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:20.739 UnitTest Ascend table 5 Pressure, PV, Park Time, sample list: 75, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:20.756 UnitTest Ascend table 6 Pressure, PV, Park Time, sample list: 70, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:20.773 UnitTest Ascend table 7 Pressure, PV, Park Time, sample list: 65, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:20.790 UnitTest Ascend table 8 Pressure, PV, Park Time, sample list: 60, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:20.807 UnitTest Ascend table 9 Pressure, PV, Park Time, sample list: 55, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:20.824 UnitTest Ascend table 10 Pressure, PV, Park Time, sample list: 50, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:20.842 UnitTest Ascend table 11 Pressure, PV, Park Time, sample list: 45, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:20.861 UnitTest Ascend table 12 Pressure, PV, Park Time, sample list: 40, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:20.879 UnitTest Ascend table 13 Pressure, PV, Park Time, sample list: 35, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:20.897 UnitTest Ascend table 14 Pressure, PV, Park Time, sample list: 30, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:20.914 UnitTest Ascend table 15 Pressure, PV, Park Time, sample list: 25, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:20.931 UnitTest Ascend table 16 Pressure, PV, Park Time, sample list: 20, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:20.949 UnitTest Ascend table 17 Pressure, PV, Park Time, sample list: 18, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:20.966 UnitTest Ascend table 18 Pressure, PV, Park Time, sample list: 16, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:20.984 UnitTest Ascend table 19 Pressure, PV, Park Time, sample list: 14, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:21.001 UnitTest Ascend table 20 Pressure, PV, Park Time, sample list: 12, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:21.019 UnitTest Ascend table 21 Pressure, PV, Park Time, sample list: 10, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:21.036 UnitTest Ascend table 22 Pressure, PV, Park Time, sample list: 8, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:21.053 UnitTest Ascend table 23 Pressure, PV, Park Time, sample list: 6, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:21.070 UnitTest Ascend table 24 Pressure, PV, Park Time, sample list: 4, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:21.087 UnitTest Ascend table 25 Pressure, PV, Park Time, sample list: 2, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:21.105 UnitTest Ascend table 26 Pressure, PV, Park Time, sample list: 0, 0.100, 00:00:00, 7 $ 16-Aug-2018 23:46:10.321 UnitTest A3LA Message AT&D0 $ 16-Aug-2018 23:46:10.323 UnitTest A3LA Message OK $ 16-Aug-2018 23:46:11.321 UnitTest A3LA Message AT&K0 $ 16-Aug-2018 23:46:11.323 UnitTest A3LA Message OK $ 16-Aug-2018 23:46:12.324 UnitTest A3LA Message AT+SBDD2 $ 16-Aug-2018 23:46:12.326 UnitTest A3LA Message 0 $ 16-Aug-2018 23:46:12.327 UnitTest A3LA Message $ 16-Aug-2018 23:46:12.329 UnitTest A3LA Message OK $ 16-Aug-2018 23:46:13.325 UnitTest A3LA Message AT+GSN $ 16-Aug-2018 23:46:13.334 UnitTest A3LA Message 300125060561320 $ 16-Aug-2018 23:46:13.335 UnitTest A3LA Message $ 16-Aug-2018 23:46:13.337 UnitTest A3LA Message OK $ 16-Aug-2018 23:46:14.328 UnitTest A3LA Message AT+SBDC $ 16-Aug-2018 23:46:14.330 UnitTest A3LA Message 0 $ 16-Aug-2018 23:46:14.331 UnitTest A3LA Message $ 16-Aug-2018 23:46:14.333 UnitTest A3LA Message OK $ 16-Aug-2018 23:46:21.441 UnitTest Enqueueing File for Transfer: \SD\data\2018-8-10T0-12-57.eng $ 16-Aug-2018 23:46:21.455 UnitTest Enqueueing File for Transfer: \SD\data\2018-8-10T0-30-15.eng $ 16-Aug-2018 23:46:21.470 UnitTest Enqueueing File for Transfer: \SD\data\2018-8-10T0-34-32.eng $ 16-Aug-2018 23:46:21.494 UnitTest Starting Surface Ops $ 16-Aug-2018 23:46:21.498 UnitTest State Entry $ 16-Aug-2018 23:46:23.001 UnitTest Starting valve close $ 16-Aug-2018 23:46:23.004 UnitTest Sending Optode Do Sample $ 16-Aug-2018 23:46:23.014 UnitTest Sending Optode Wakeup $ 16-Aug-2018 23:46:23.607 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:16.335 UnitTest A3LA Message AT+CICCID $ 16-Aug-2018 23:46:16.343 UnitTest A3LA Message 8988169234000597401 $ 16-Aug-2018 23:46:16.344 UnitTest A3LA Message $ 16-Aug-2018 23:46:16.346 UnitTest A3LA Message OK $ 16-Aug-2018 23:46:17.353 UnitTest Optode Message NaN !# $ 16-Aug-2018 23:46:23.725 UnitTest Dialing $ 16-Aug-2018 23:46:23.818 UnitTest IRIDIUM Modem Response: 0 $ 16-Aug-2018 23:46:18.359 UnitTest Optode Message NaN # $ 16-Aug-2018 23:46:23.851 UnitTest PS001 Connect: NO CARRIER received. $ 16-Aug-2018 23:46:23.857 UnitTest Dialing $ 16-Aug-2018 23:46:19.370 UnitTest Optode Message NaN # $ 16-Aug-2018 23:46:23.627 UnitTest A3LA Message AT+CBST=71,0,1 $ 16-Aug-2018 23:46:23.627 UnitTest A3LA Message OK $ 16-Aug-2018 23:46:23.986 UnitTest IRIDIUM Modem Response: 0 $ 16-Aug-2018 23:46:23.996 UnitTest PS001 Connect: NO CARRIER received. $ 16-Aug-2018 23:46:24.002 UnitTest Dialing $ 16-Aug-2018 23:46:24.109 UnitTest IRIDIUM Modem Response: 0 $ 16-Aug-2018 23:46:24.113 UnitTest PS001 Connect: NO CARRIER received. $ 16-Aug-2018 23:46:24.530 UnitTest Sending Escape sequence $ 16-Aug-2018 23:46:24.936 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:24.620 UnitTest Optode Message NaN 4330,1532,250.407,25.033,28.759,28.686,35.800,7.114,650.5,513.4,28.6 $ 16-Aug-2018 23:46:24.623 UnitTest Optode Message NaN # $ 16-Aug-2018 23:46:25.531 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:26.531 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:27.531 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:28.532 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:29.531 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:30.531 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:31.531 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:32.531 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:33.531 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:34.531 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:35.532 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:36.532 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:37.533 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:38.533 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:39.533 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:40.533 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:41.534 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:42.548 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:43.548 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:44.549 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:45.549 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:46.549 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:47.549 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:48.550 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:49.550 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:50.551 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:51.551 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:52.551 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:53.552 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:54.558 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:55.559 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:56.559 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:57.560 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:58.560 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:46:59.561 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:47:00.561 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:47:01.561 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:47:02.561 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:47:03.561 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:47:04.562 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:47:05.563 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:47:06.563 UnitTest Sending ATH0 cmd $ 16-Aug-2018 23:47:06.575 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:47:07.563 UnitTest Surface Ops current action = 3 $ 16-Aug-2018 23:47:06.586 UnitTest A3LA Message ATH0 $ 16-Aug-2018 23:47:07.595 UnitTest iModem invalid response: Looking for IModem-->OK<-- Got-->ATH0 <-- $ 16-Aug-2018 23:47:06.586 UnitTest A3LA Message OK $ 16-Aug-2018 23:47:07.637 UnitTest iModem correct response: Looking for IModem-->OK<-- Got-->OK <-- $ 16-Aug-2018 23:47:08.564 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:09.564 UnitTest Trying GPS get position try num: 0 of 6 $ 16-Aug-2018 23:47:10.082 UnitTest Got Position: lat = 0000.00000 N lon = 00000.00000 E num sats = 0 $ 16-Aug-2018 23:47:10.098 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:10.564 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:11.564 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:12.565 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:13.565 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:14.565 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:15.565 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:16.565 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:17.566 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:18.566 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:19.567 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:20.567 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:21.568 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:22.568 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:23.569 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:24.569 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:25.569 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:26.569 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:27.569 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:28.569 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:29.569 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:30.570 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:31.570 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:32.571 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:33.571 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:34.572 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:35.572 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:36.572 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:37.572 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:38.573 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:39.573 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:40.583 UnitTest Trying GPS get position try num: 1 of 6 $ 16-Aug-2018 23:47:41.102 UnitTest Got Position: lat = 0000.00000 N lon = 00000.00000 E num sats = 0 $ 16-Aug-2018 23:47:41.109 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:41.582 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:42.583 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:43.583 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:44.584 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:45.584 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:46.584 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:47.585 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:48.584 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:49.585 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:50.585 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:51.586 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:52.586 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:53.587 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:54.587 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:55.587 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:56.587 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:57.588 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:58.588 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:47:59.589 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:00.589 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:01.589 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:02.589 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:03.589 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:04.590 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:05.590 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:06.591 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:07.591 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:08.592 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:09.592 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:10.593 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:11.593 UnitTest Trying GPS get position try num: 2 of 6 $ 16-Aug-2018 23:48:12.111 UnitTest Got Position: lat = 0000.00000 N lon = 00000.00000 E num sats = 0 $ 16-Aug-2018 23:48:12.116 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:12.593 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:13.593 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:14.593 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:15.593 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:16.594 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:17.594 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:18.595 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:19.595 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:20.596 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:21.596 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:22.602 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:23.603 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:24.603 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:25.604 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:26.604 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:27.605 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:28.605 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:29.612 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:30.612 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:31.612 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:32.613 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:33.613 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:34.613 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:35.614 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:36.614 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:37.615 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:38.656 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:39.656 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:40.656 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:41.657 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:42.658 UnitTest Trying GPS get position try num: 3 of 6 $ 16-Aug-2018 23:48:43.081 UnitTest Got Position: lat = 0000.00000 N lon = 00000.00000 E num sats = 0 $ 16-Aug-2018 23:48:43.288 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:43.657 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:44.658 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:45.658 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:46.659 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:47.659 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:48.659 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:49.660 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:50.660 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:51.661 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:52.661 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:53.662 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:54.662 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:55.662 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:56.662 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:57.663 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:58.663 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:48:59.664 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:00.664 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:01.664 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:02.665 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:03.665 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:04.666 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:05.666 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:06.667 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:07.667 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:08.668 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:09.668 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:10.668 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:11.668 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:12.669 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:13.669 UnitTest Trying GPS get position try num: 4 of 6 $ 16-Aug-2018 23:49:14.894 UnitTest Got Position: lat = 0000.00000 N lon = 00000.00000 E num sats = 0 $ 16-Aug-2018 23:49:15.112 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:15.217 UnitTest ERROR: State machine cycle time exceeded SMCyclePeriod = 00:00:01.5482115 $ 16-Aug-2018 23:49:15.433 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:16.433 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:17.441 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:18.441 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:19.442 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:20.442 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:21.441 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:22.442 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:23.442 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:24.443 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:25.443 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:26.444 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:27.444 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:28.445 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:29.445 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:30.446 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:31.446 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:32.447 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:33.447 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:34.447 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:35.448 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:36.466 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:37.467 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:38.467 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:39.467 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:40.468 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:41.468 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:42.469 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:43.468 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:44.469 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:45.470 UnitTest Trying GPS get position try num: 5 of 6 $ 16-Aug-2018 23:49:45.889 UnitTest Got Position: lat = 0000.00000 N lon = 00000.00000 E num sats = 0 $ 16-Aug-2018 23:49:46.106 UnitTest Surface Ops current action = 4 $ 16-Aug-2018 23:49:46.470 UnitTest Surface Ops current action = 5 $ 16-Aug-2018 23:49:47.469 UnitTest Surface Ops current action = 6 $ 16-Aug-2018 23:49:48.475 UnitTest 2018-08-16T23:49:48Z,GPS TOF,000339.00,Lat,0000.00000 N,Lon,00000.00000 E,Num Sats,0,NAK SBD,SQ,-1,MO Stat,-,,Mission TO,24:00:00,Park Time,01:00:00,Park P,100,Prof Num,-1 of 10,Max P,0.000,RecMode,True,BBVolts,0.000,Can P,1012.287,Can Hum,48.844,,.., $ 16-Aug-2018 23:49:48.813 UnitTest Surface Ops current action = 7 $ 16-Aug-2018 23:49:49.469 UnitTest Sending SBDWT cmd $ 16-Aug-2018 23:49:49.575 UnitTest Sent SBDWT Command $ 16-Aug-2018 23:49:49.693 UnitTest Surface Ops current action = 7 $ 16-Aug-2018 23:49:49.690 UnitTest A3LA Message AT+SBDWT $ 16-Aug-2018 23:49:50.034 UnitTest iModem invalid response: Looking for IModem-->READY<-- Got-->AT+SBDWT <-- $ 16-Aug-2018 23:49:49.690 UnitTest A3LA Message READY $ 16-Aug-2018 23:49:50.474 UnitTest iModem correct response: Looking for IModem-->READY<-- Got-->READY <-- $ 16-Aug-2018 23:49:50.693 UnitTest ERROR: State machine cycle time exceeded SMCyclePeriod = 00:00:01.2234828 $ 16-Aug-2018 23:49:50.913 UnitTest Surface Ops current action = 8 $ 16-Aug-2018 23:49:51.913 UnitTest Sending loadSBDWT cmd $ 16-Aug-2018 23:49:52.175 UnitTest Loaded SBDWT Buffer with->2018-08-16T23:49:48Z,GPS TOF,000339.00,Lat,0000.00000 N,Lon,00000.00000 E,Num Sats,0,NAK SBD,SQ,-1,MO Stat,-,,Mission TO,24:00:00,Park Time,01:00:00,Park P,100,Prof Num,-1 of 10,Max P,0.000,RecMode,True,BBVolts,0.000,Can P,1012.287,Can Hum,48.844,,..,<- $ 16-Aug-2018 23:49:52.488 UnitTest Surface Ops current action = 8 $ 16-Aug-2018 23:49:52.484 UnitTest A3LA Message 2018-08-16T23:49:48Z,GPS TOF,000339.00,Lat,0000.00000 N,Lon,00000.00000 E,Num Sats,0,NAK SBD,SQ,-1,MO Stat,-,,Mission TO,24:00:00,Park Time,01:00:00,Park P,100,Prof Num,-1 of 10,Max P,0.000,RecMode,True,BBVolts,0.000,Can P,1012.287,Can Hum,48.844,,.., $ 16-Aug-2018 23:49:53.082 UnitTest iModem invalid response: Looking for IModem-->OK<-- Got-->2018-08-16T23:49:48Z,GPS TOF,000339.00,Lat,0000.00000 N,Lon,00000.00000 E,Num Sats,0,NAK SBD,SQ,-1,MO Stat,-,,Mission TO,24:00:00,Park Time,01:00:00,Park P,100,Prof Num,-1 of 10,Max P,0.000,RecMode,True,BBVolts,0.000,Can P,1012.287,Can Hum,48.844,,.., <-- $ 16-Aug-2018 23:49:52.485 UnitTest A3LA Message 0 $ 16-Aug-2018 23:49:53.751 UnitTest iModem invalid response: Looking for IModem-->OK<-- Got-->0 <-- $ 16-Aug-2018 23:49:52.485 UnitTest A3LA Message $ 16-Aug-2018 23:49:54.192 UnitTest iModem invalid response: Looking for IModem-->OK<-- Got--> <-- $ 16-Aug-2018 23:49:52.485 UnitTest A3LA Message OK $ 16-Aug-2018 23:49:54.632 UnitTest iModem correct response: Looking for IModem-->OK<-- Got-->OK <-- $ 16-Aug-2018 23:49:54.839 UnitTest ERROR: State machine cycle time exceeded SMCyclePeriod = 00:00:02.9257514 $ 16-Aug-2018 23:49:55.057 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:49:56.056 UnitTest Sending SBDIX cmd $ 16-Aug-2018 23:49:56.161 UnitTest Sent SBDIX Command $ 16-Aug-2018 23:49:56.277 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:49:57.056 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:49:58.056 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:49:59.057 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:00.057 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:01.065 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:02.065 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:03.065 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:04.066 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:05.066 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:05.171 UnitTest A3LA Message AT+SBDIX $ 16-Aug-2018 23:50:05.403 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:50:05.625 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:50:05.845 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:50:06.066 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:50:06.286 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:50:05.172 UnitTest A3LA Message +SBDIX: 32, 0, 2, 0, 0, 0 $ 16-Aug-2018 23:50:06.731 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:50:06.953 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:50:07.175 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:50:07.399 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:50:07.621 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:50:05.172 UnitTest A3LA Message $ 16-Aug-2018 23:50:08.064 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got--> <-- $ 16-Aug-2018 23:50:08.285 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got--> <-- $ 16-Aug-2018 23:50:08.504 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got--> <-- $ 16-Aug-2018 23:50:08.723 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got--> <-- $ 16-Aug-2018 23:50:08.942 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got--> <-- $ 16-Aug-2018 23:50:05.172 UnitTest A3LA Message OK $ 16-Aug-2018 23:50:09.385 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->OK <-- $ 16-Aug-2018 23:50:09.604 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->OK <-- $ 16-Aug-2018 23:50:09.824 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->OK <-- $ 16-Aug-2018 23:50:10.043 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->OK <-- $ 16-Aug-2018 23:50:10.264 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->OK <-- $ 16-Aug-2018 23:50:10.483 UnitTest ERROR: State machine cycle time exceeded SMCyclePeriod = 00:00:05.4055631 $ 16-Aug-2018 23:50:10.690 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:11.690 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:12.690 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:13.691 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:14.691 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:15.692 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:16.692 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:17.693 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:18.693 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:19.694 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:20.694 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:21.694 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:22.695 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:23.695 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:24.696 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:25.696 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:26.697 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:27.697 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:28.698 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:29.698 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:30.699 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:31.699 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:32.700 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:33.700 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:34.710 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:35.710 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:36.711 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:37.709 UnitTest Sending SBDIX cmd $ 16-Aug-2018 23:50:37.815 UnitTest Sent SBDIX Command $ 16-Aug-2018 23:50:37.931 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:38.710 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:39.710 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:40.711 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:41.711 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:42.711 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:43.712 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:44.712 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:45.713 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:46.713 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:47.713 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:47.047 UnitTest A3LA Message AT+SBDIX $ 16-Aug-2018 23:50:48.050 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:50:48.270 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:50:48.491 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:50:48.711 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:50:48.933 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:50:47.062 UnitTest A3LA Message +SBDIX: 32, 0, 2, 0, 0, 0 $ 16-Aug-2018 23:50:49.377 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:50:49.599 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:50:49.822 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:50:50.044 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:50:50.266 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:50:47.063 UnitTest A3LA Message $ 16-Aug-2018 23:50:50.711 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got--> <-- $ 16-Aug-2018 23:50:50.930 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got--> <-- $ 16-Aug-2018 23:50:51.149 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got--> <-- $ 16-Aug-2018 23:50:51.369 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got--> <-- $ 16-Aug-2018 23:50:51.588 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got--> <-- $ 16-Aug-2018 23:50:47.065 UnitTest A3LA Message OK $ 16-Aug-2018 23:50:52.030 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->OK <-- $ 16-Aug-2018 23:50:52.250 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->OK <-- $ 16-Aug-2018 23:50:52.469 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->OK <-- $ 16-Aug-2018 23:50:52.689 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->OK <-- $ 16-Aug-2018 23:50:52.910 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->OK <-- $ 16-Aug-2018 23:50:53.118 UnitTest ERROR: State machine cycle time exceeded SMCyclePeriod = 00:00:05.4042534 $ 16-Aug-2018 23:50:53.336 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:54.335 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:55.336 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:56.336 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:57.337 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:58.337 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:50:59.337 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:00.338 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:01.338 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:02.339 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:03.339 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:04.340 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:05.340 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:06.341 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:07.341 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:08.342 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:09.342 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:10.343 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:11.343 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:12.343 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:13.344 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:14.344 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:15.345 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:16.345 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:17.346 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:18.347 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:19.345 UnitTest Sending SBDIX cmd $ 16-Aug-2018 23:51:19.451 UnitTest Sent SBDIX Command $ 16-Aug-2018 23:51:19.567 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:20.346 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:21.346 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:22.347 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:23.354 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:24.354 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:25.354 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:24.809 UnitTest A3LA Message AT+SBDIX $ 16-Aug-2018 23:51:25.689 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:51:25.909 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:51:26.131 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:51:26.352 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:51:26.572 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:51:24.824 UnitTest A3LA Message +SBDIX: 32, 0, 2, 0, 0, 0 $ 16-Aug-2018 23:51:27.018 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:51:27.239 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:51:27.461 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:51:27.683 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:51:27.907 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:51:24.825 UnitTest A3LA Message $ 16-Aug-2018 23:51:28.350 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got--> <-- $ 16-Aug-2018 23:51:28.569 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got--> <-- $ 16-Aug-2018 23:51:28.789 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got--> <-- $ 16-Aug-2018 23:51:29.010 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got--> <-- $ 16-Aug-2018 23:51:29.229 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got--> <-- $ 16-Aug-2018 23:51:24.827 UnitTest A3LA Message OK $ 16-Aug-2018 23:51:29.669 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->OK <-- $ 16-Aug-2018 23:51:29.891 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->OK <-- $ 16-Aug-2018 23:51:30.110 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->OK <-- $ 16-Aug-2018 23:51:30.330 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->OK <-- $ 16-Aug-2018 23:51:30.549 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->OK <-- $ 16-Aug-2018 23:51:30.767 UnitTest ERROR: State machine cycle time exceeded SMCyclePeriod = 00:00:05.4134160 $ 16-Aug-2018 23:51:30.987 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:31.976 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:32.984 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:33.984 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:34.985 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:35.985 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:36.985 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:37.986 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:38.986 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:39.987 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:40.987 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:41.988 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:42.988 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:43.989 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:44.989 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:45.990 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:46.990 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:47.991 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:48.991 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:49.991 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:50.992 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:51.992 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:52.993 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:53.993 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:54.994 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:55.994 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:56.995 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:57.995 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:58.996 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:51:59.996 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:00.995 UnitTest Sending SBDIX cmd $ 16-Aug-2018 23:52:01.101 UnitTest Sent SBDIX Command $ 16-Aug-2018 23:52:01.217 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:01.996 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:02.996 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:03.996 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:04.997 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:05.997 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:06.998 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:07.998 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:07.965 UnitTest A3LA Message AT+SBDIX $ 16-Aug-2018 23:52:08.333 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:52:08.555 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:52:08.776 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:52:08.996 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:52:09.216 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:52:07.979 UnitTest A3LA Message +SBDIX: 32, 0, 2, 0, 0, 0 $ 16-Aug-2018 23:52:09.662 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:52:09.883 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:52:10.105 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:52:10.329 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:52:10.551 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:52:07.981 UnitTest A3LA Message $ 16-Aug-2018 23:52:10.994 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got--> <-- $ 16-Aug-2018 23:52:11.213 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got--> <-- $ 16-Aug-2018 23:52:11.434 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got--> <-- $ 16-Aug-2018 23:52:11.653 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got--> <-- $ 16-Aug-2018 23:52:11.873 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got--> <-- $ 16-Aug-2018 23:52:07.983 UnitTest A3LA Message OK $ 16-Aug-2018 23:52:12.315 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->OK <-- $ 16-Aug-2018 23:52:12.534 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->OK <-- $ 16-Aug-2018 23:52:12.754 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->OK <-- $ 16-Aug-2018 23:52:12.973 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->OK <-- $ 16-Aug-2018 23:52:13.193 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->OK <-- $ 16-Aug-2018 23:52:13.402 UnitTest ERROR: State machine cycle time exceeded SMCyclePeriod = 00:00:05.4037938 $ 16-Aug-2018 23:52:13.621 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:14.620 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:15.620 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:16.621 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:17.621 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:18.622 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:19.622 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:20.622 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:21.623 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:22.623 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:23.624 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:24.624 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:25.625 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:26.625 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:27.626 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:28.626 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:29.627 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:30.627 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:31.627 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:32.627 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:33.627 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:34.628 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:35.628 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:36.629 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:37.629 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:38.630 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:39.630 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:40.631 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:41.631 UnitTest IModem waitingForResponse expended all retries $ 16-Aug-2018 23:52:41.846 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:42.631 UnitTest Sending SBDIX cmd $ 16-Aug-2018 23:52:42.736 UnitTest Sent SBDIX Command $ 16-Aug-2018 23:52:42.852 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:43.631 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:44.631 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:45.632 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:46.632 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:47.633 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:48.633 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:49.633 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:50.634 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:51.634 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:52.634 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:51.929 UnitTest A3LA Message AT+SBDIX $ 16-Aug-2018 23:52:52.970 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:52:53.190 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:52:53.410 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:52:53.632 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:52:53.852 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:52:51.944 UnitTest A3LA Message +SBDIX: 32, 0, 2, 0, 0, 0 $ 16-Aug-2018 23:52:54.296 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:52:54.520 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:52:54.742 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:52:54.964 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:52:55.186 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:52:51.945 UnitTest A3LA Message $ 16-Aug-2018 23:52:55.630 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got--> <-- $ 16-Aug-2018 23:52:55.850 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got--> <-- $ 16-Aug-2018 23:52:56.069 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got--> <-- $ 16-Aug-2018 23:52:56.290 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got--> <-- $ 16-Aug-2018 23:52:56.509 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got--> <-- $ 16-Aug-2018 23:52:51.947 UnitTest A3LA Message OK $ 16-Aug-2018 23:52:56.950 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->OK <-- $ 16-Aug-2018 23:52:57.169 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->OK <-- $ 16-Aug-2018 23:52:57.391 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->OK <-- $ 16-Aug-2018 23:52:57.610 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->OK <-- $ 16-Aug-2018 23:52:57.830 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->OK <-- $ 16-Aug-2018 23:52:58.037 UnitTest ERROR: State machine cycle time exceeded SMCyclePeriod = 00:00:05.4026874 $ 16-Aug-2018 23:52:58.257 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:52:59.257 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:00.257 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:01.258 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:02.258 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:03.265 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:04.272 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:05.273 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:06.273 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:07.274 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:08.274 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:09.275 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:10.275 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:11.276 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:12.276 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:13.277 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:14.277 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:15.278 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:16.278 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:17.278 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:18.279 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:19.279 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:20.280 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:21.280 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:22.281 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:23.282 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:24.280 UnitTest Sending SBDIX cmd $ 16-Aug-2018 23:53:24.386 UnitTest Sent SBDIX Command $ 16-Aug-2018 23:53:24.502 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:25.281 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:26.281 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:27.281 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:28.282 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:29.282 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:30.290 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:31.290 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:32.291 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:33.291 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:33.396 UnitTest A3LA Message AT+SBDIX $ 16-Aug-2018 23:53:33.628 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:53:33.848 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:53:34.068 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:53:34.290 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:53:34.511 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:53:33.397 UnitTest A3LA Message +SBDIX: 32, 0, 2, 0, 0, 0 $ 16-Aug-2018 23:53:34.954 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:53:35.178 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:53:35.400 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:53:35.622 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:53:35.844 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:53:33.397 UnitTest A3LA Message $ 16-Aug-2018 23:53:36.288 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got--> <-- $ 16-Aug-2018 23:53:36.508 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got--> <-- $ 16-Aug-2018 23:53:36.727 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got--> <-- $ 16-Aug-2018 23:53:36.948 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got--> <-- $ 16-Aug-2018 23:53:37.168 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got--> <-- $ 16-Aug-2018 23:53:33.397 UnitTest A3LA Message OK $ 16-Aug-2018 23:53:37.608 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->OK <-- $ 16-Aug-2018 23:53:37.828 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->OK <-- $ 16-Aug-2018 23:53:38.049 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->OK <-- $ 16-Aug-2018 23:53:38.268 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->OK <-- $ 16-Aug-2018 23:53:38.488 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->OK <-- $ 16-Aug-2018 23:53:38.695 UnitTest ERROR: State machine cycle time exceeded SMCyclePeriod = 00:00:05.4042521 $ 16-Aug-2018 23:53:38.924 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:39.914 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:40.914 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:41.915 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:42.915 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:43.915 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:44.916 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:45.916 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:46.917 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:47.917 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:48.918 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:49.918 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:50.919 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:51.919 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:52.920 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:53.920 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:54.920 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:55.921 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:56.921 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:57.922 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:58.922 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:53:59.923 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:00.923 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:01.924 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:02.924 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:03.925 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:04.925 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:05.924 UnitTest Sending SBDIX cmd $ 16-Aug-2018 23:54:06.030 UnitTest Sent SBDIX Command $ 16-Aug-2018 23:54:06.146 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:06.925 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:07.925 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:08.925 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:09.926 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:10.926 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:11.927 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:12.927 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:13.928 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:14.928 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:15.033 UnitTest A3LA Message AT+SBDIX $ 16-Aug-2018 23:54:15.265 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:54:15.485 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:54:15.705 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:54:15.937 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:54:16.157 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:54:15.034 UnitTest A3LA Message +SBDIX: 32, 0, 2, 0, 0, 0 $ 16-Aug-2018 23:54:16.601 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:54:16.839 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:54:17.061 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:54:17.283 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:54:17.505 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:54:15.034 UnitTest A3LA Message $ 16-Aug-2018 23:54:17.950 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got--> <-- $ 16-Aug-2018 23:54:18.169 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got--> <-- $ 16-Aug-2018 23:54:18.388 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got--> <-- $ 16-Aug-2018 23:54:18.610 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got--> <-- $ 16-Aug-2018 23:54:18.829 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got--> <-- $ 16-Aug-2018 23:54:15.034 UnitTest A3LA Message OK $ 16-Aug-2018 23:54:19.269 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->OK <-- $ 16-Aug-2018 23:54:19.489 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->OK <-- $ 16-Aug-2018 23:54:19.716 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->OK <-- $ 16-Aug-2018 23:54:19.935 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->OK <-- $ 16-Aug-2018 23:54:20.155 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->OK <-- $ 16-Aug-2018 23:54:20.373 UnitTest ERROR: State machine cycle time exceeded SMCyclePeriod = 00:00:05.4340985 $ 16-Aug-2018 23:54:20.593 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:21.582 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:22.583 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:23.583 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:24.583 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:25.584 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:26.596 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:27.597 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:28.597 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:29.598 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:30.598 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:31.598 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:32.599 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:33.599 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:34.600 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:35.600 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:36.601 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:37.601 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:38.602 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:39.602 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:40.603 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:41.603 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:42.603 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:43.604 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:44.604 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:45.605 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:46.606 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:47.605 UnitTest Sending SBDIX cmd $ 16-Aug-2018 23:54:47.710 UnitTest Sent SBDIX Command $ 16-Aug-2018 23:54:47.826 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:48.605 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:49.605 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:50.606 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:51.606 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:52.613 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:53.621 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:54.621 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:55.622 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:56.622 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:57.622 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:54:56.992 UnitTest A3LA Message AT+SBDIX $ 16-Aug-2018 23:54:57.958 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:54:58.178 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:54:58.400 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:54:58.620 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:54:58.840 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->AT+SBDIX <-- $ 16-Aug-2018 23:54:57.005 UnitTest A3LA Message +SBDIX: 32, 0, 2, 0, 0, 0 $ 16-Aug-2018 23:54:59.286 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:54:59.508 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:54:59.730 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:54:59.952 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:55:00.175 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->+SBDIX: 32, 0, 2, 0, 0, 0 <-- $ 16-Aug-2018 23:54:57.006 UnitTest A3LA Message $ 16-Aug-2018 23:55:00.618 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got--> <-- $ 16-Aug-2018 23:55:00.837 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got--> <-- $ 16-Aug-2018 23:55:01.059 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got--> <-- $ 16-Aug-2018 23:55:01.278 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got--> <-- $ 16-Aug-2018 23:55:01.497 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got--> <-- $ 16-Aug-2018 23:54:57.008 UnitTest A3LA Message OK $ 16-Aug-2018 23:55:01.938 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->OK <-- $ 16-Aug-2018 23:55:02.159 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->OK <-- $ 16-Aug-2018 23:55:02.378 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->OK <-- $ 16-Aug-2018 23:55:02.598 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->OK <-- $ 16-Aug-2018 23:55:02.817 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->OK <-- $ 16-Aug-2018 23:55:03.027 UnitTest ERROR: State machine cycle time exceeded SMCyclePeriod = 00:00:05.4042309 $ 16-Aug-2018 23:55:03.256 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:55:04.245 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:55:05.246 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:55:06.246 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:55:07.246 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:55:08.247 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:55:09.247 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:55:10.248 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:55:11.248 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:55:12.249 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:55:13.249 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:55:14.250 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:55:15.250 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:55:16.251 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:55:17.251 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:55:18.251 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:55:19.252 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:55:20.252 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:55:21.253 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:55:22.253 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:55:23.254 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:55:24.277 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:55:25.278 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:55:26.279 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:55:27.279 UnitTest Surface Ops current action = 9 $ 16-Aug-2018 23:55:28.279 UnitTest IModem waitingForResponse expended all retries $ 16-Aug-2018 23:55:28.495 UnitTest Exiting sendSBDIX sbdiTryNum:8 MOStatus:-1 MTQueued-1 $ 16-Aug-2018 23:55:28.704 UnitTest Surface Ops current action = 11 $ 16-Aug-2018 23:55:29.286 UnitTest Sending FLBB run command