$ 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 $ 21-Aug-2018 23:07:12.572 UnitTest Reset Controller Status Register Value = 65792 $ 21-Aug-2018 23:07:12.576 UnitTest File Name = \SD\data\2018-8-21T23-7-11 $ 21-Aug-2018 23:07:12.580 UnitTest Volume Total Size: 7939817472 $ 21-Aug-2018 23:07:12.585 UnitTest Volume Free Space: 7920156672 $ 21-Aug-2018 23:07:12.589 UnitTest PlatVel PID K = 352 $ 21-Aug-2018 23:07:12.593 UnitTest PlatVel PID Ti = 50000 $ 21-Aug-2018 23:07:12.597 UnitTest PlatVel PID Td = 44 $ 21-Aug-2018 23:07:12.601 UnitTest PlatVel PID Tf = 10 $ 21-Aug-2018 23:07:12.605 UnitTest PlatVel PID Tt = 100 $ 21-Aug-2018 23:07:12.609 UnitTest PlatVel PID B = 1 $ 21-Aug-2018 23:07:12.613 UnitTest PlatVel PID uLow = -41.666666666666664 $ 21-Aug-2018 23:07:12.618 UnitTest PlatVel PID uHigh = 41.666666666666664 $ 21-Aug-2018 23:07:12.622 UnitTest PlatVel PID h = 3 $ 21-Aug-2018 23:07:12.625 UnitTest Engr Logger Initialized $ 21-Aug-2018 23:07:40.695 UnitTest Starting valve close $ 21-Aug-2018 23:07:49.716 UnitTest Sending Optode Wakeup $ 21-Aug-2018 23:07:53.732 UnitTest Set can pressure resolution to low $ 21-Aug-2018 23:07:53.744 UnitTest Set can pressure active $ 21-Aug-2018 23:07:53.747 UnitTest PTH Message 742.060059 21.525000 39.055389 22.616458 $ 21-Aug-2018 23:07:53.868 UnitTest PTH Message 742.060059 21.616667 39.055389 22.627183 $ 21-Aug-2018 23:07:53.983 UnitTest Sending FLBB stop command $ 21-Aug-2018 23:07:55.487 UnitTest Sending FLBB pkt command: $pkt 1 $ 21-Aug-2018 23:07:56.500 UnitTest Sending FLBB ave command: $ave 20 $ 21-Aug-2018 23:07:56.518 UnitTest Maximum number of profiles = 10 $ 21-Aug-2018 23:07:56.531 UnitTest Mission Timeout = 24:00:00 Mission Start Time: 08/21/2018 23:07:56 $ 21-Aug-2018 23:07:56.548 UnitTest Recovery mode = True $ 21-Aug-2018 23:07:56.552 UnitTest Park Sample Period = 00:10:00 $ 21-Aug-2018 23:07:56.570 UnitTest Descend table 0 Pressure, PV, Park Time, sample list: 100, -0.200, 01:00:00, 7 $ 21-Aug-2018 23:07:56.587 UnitTest Ascend table 0 Pressure, PV, Park Time, sample list: 100, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:56.605 UnitTest Ascend table 1 Pressure, PV, Park Time, sample list: 95, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:56.622 UnitTest Ascend table 2 Pressure, PV, Park Time, sample list: 90, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:56.639 UnitTest Ascend table 3 Pressure, PV, Park Time, sample list: 85, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:56.656 UnitTest Ascend table 4 Pressure, PV, Park Time, sample list: 80, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:56.673 UnitTest Ascend table 5 Pressure, PV, Park Time, sample list: 75, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:56.691 UnitTest Ascend table 6 Pressure, PV, Park Time, sample list: 70, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:56.708 UnitTest Ascend table 7 Pressure, PV, Park Time, sample list: 65, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:56.725 UnitTest Ascend table 8 Pressure, PV, Park Time, sample list: 60, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:56.742 UnitTest Ascend table 9 Pressure, PV, Park Time, sample list: 55, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:56.759 UnitTest Ascend table 10 Pressure, PV, Park Time, sample list: 50, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:56.777 UnitTest Ascend table 11 Pressure, PV, Park Time, sample list: 45, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:56.794 UnitTest Ascend table 12 Pressure, PV, Park Time, sample list: 40, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:56.812 UnitTest Ascend table 13 Pressure, PV, Park Time, sample list: 35, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:56.829 UnitTest Ascend table 14 Pressure, PV, Park Time, sample list: 30, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:56.846 UnitTest Ascend table 15 Pressure, PV, Park Time, sample list: 25, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:56.864 UnitTest Ascend table 16 Pressure, PV, Park Time, sample list: 20, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:56.881 UnitTest Ascend table 17 Pressure, PV, Park Time, sample list: 18, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:56.899 UnitTest Ascend table 18 Pressure, PV, Park Time, sample list: 16, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:56.916 UnitTest Ascend table 19 Pressure, PV, Park Time, sample list: 14, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:56.933 UnitTest Ascend table 20 Pressure, PV, Park Time, sample list: 12, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:56.950 UnitTest Ascend table 21 Pressure, PV, Park Time, sample list: 10, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:56.968 UnitTest Ascend table 22 Pressure, PV, Park Time, sample list: 8, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:56.985 UnitTest Ascend table 23 Pressure, PV, Park Time, sample list: 6, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:57.002 UnitTest Ascend table 24 Pressure, PV, Park Time, sample list: 4, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:57.019 UnitTest Ascend table 25 Pressure, PV, Park Time, sample list: 2, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:57.036 UnitTest Ascend table 26 Pressure, PV, Park Time, sample list: 0, 0.100, 00:00:00, 7 $ 21-Aug-2018 23:07:43.717 UnitTest A3LA Message AT&D0 $ 21-Aug-2018 23:07:43.719 UnitTest A3LA Message OK $ 21-Aug-2018 23:07:44.719 UnitTest A3LA Message AT&K0 $ 21-Aug-2018 23:07:44.721 UnitTest A3LA Message OK $ 21-Aug-2018 23:07:45.722 UnitTest A3LA Message AT+SBDD2 $ 21-Aug-2018 23:07:45.724 UnitTest A3LA Message 0 $ 21-Aug-2018 23:07:45.725 UnitTest A3LA Message $ 21-Aug-2018 23:07:45.727 UnitTest A3LA Message OK $ 21-Aug-2018 23:07:46.721 UnitTest A3LA Message AT+GSN $ 21-Aug-2018 23:07:46.730 UnitTest A3LA Message 300125060561320 $ 21-Aug-2018 23:07:46.731 UnitTest A3LA Message $ 21-Aug-2018 23:07:46.733 UnitTest A3LA Message OK $ 21-Aug-2018 23:07:48.329 UnitTest A3LA Message AT+SBDC $ 21-Aug-2018 23:07:48.330 UnitTest A3LA Message 0 $ 21-Aug-2018 23:07:48.331 UnitTest A3LA Message $ 21-Aug-2018 23:07:48.333 UnitTest A3LA Message OK $ 21-Aug-2018 23:07:57.364 UnitTest Enqueueing File for Transfer: \SD\data\2018-8-10T0-12-57.eng $ 21-Aug-2018 23:07:57.379 UnitTest Enqueueing File for Transfer: \SD\data\2018-8-10T0-30-15.eng $ 21-Aug-2018 23:07:57.385 UnitTest Enqueueing File for Transfer: \SD\data\2018-8-10T0-34-32.eng $ 21-Aug-2018 23:07:57.399 UnitTest Starting Surface Ops $ 21-Aug-2018 23:07:57.412 UnitTest State Entry $ 21-Aug-2018 23:07:58.001 UnitTest Starting valve close $ 21-Aug-2018 23:07:58.004 UnitTest Sending Optode Do Sample $ 21-Aug-2018 23:07:58.014 UnitTest Sending Optode Wakeup $ 21-Aug-2018 23:07:58.574 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:07:49.726 UnitTest A3LA Message AT+CICCID $ 21-Aug-2018 23:07:49.735 UnitTest A3LA Message 8988169234000597401 $ 21-Aug-2018 23:07:49.736 UnitTest A3LA Message $ 21-Aug-2018 23:07:49.739 UnitTest A3LA Message OK $ 21-Aug-2018 23:07:58.668 UnitTest [RUDICS] Dialing Attempt: 1 $ 21-Aug-2018 23:07:58.756 UnitTest IRIDIUM Modem Response: 0 $ 21-Aug-2018 23:07:58.762 UnitTest PS001 Connect: NO CARRIER received. $ 21-Aug-2018 23:07:50.757 UnitTest Optode Message NaN # $ 21-Aug-2018 23:07:58.802 UnitTest [RUDICS] Dialing Attempt: 2 $ 21-Aug-2018 23:07:51.763 UnitTest Optode Message NaN # $ 21-Aug-2018 23:07:52.771 UnitTest Optode Message NaN # $ 21-Aug-2018 23:07:58.897 UnitTest IRIDIUM Modem Response: 0 $ 21-Aug-2018 23:07:58.901 UnitTest PS001 Connect: NO CARRIER received. $ 21-Aug-2018 23:07:58.587 UnitTest A3LA Message AT+CBST=71,0,1 $ 21-Aug-2018 23:07:58.930 UnitTest [RUDICS] Dialing Attempt: 3 $ 21-Aug-2018 23:07:58.587 UnitTest A3LA Message OK $ 21-Aug-2018 23:07:59.043 UnitTest IRIDIUM Modem Response: 0 $ 21-Aug-2018 23:07:59.047 UnitTest PS001 Connect: NO CARRIER received. $ 21-Aug-2018 23:07:59.065 UnitTest [RUDICS] Failed to Login, CODE -2 $ 21-Aug-2018 23:07:59.071 UnitTest [RUDICS] Thread Complete, Disconnecting $ 21-Aug-2018 23:07:59.531 UnitTest Sending Escape sequence $ 21-Aug-2018 23:07:59.936 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:07:59.625 UnitTest Optode Message NaN 4330,1532,262.901,21.480,29.644,29.580,36.696,7.116,655.0,527.8,143.4 $ 21-Aug-2018 23:07:59.629 UnitTest Optode Message NaN # $ 21-Aug-2018 23:08:00.531 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:01.531 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:02.532 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:03.532 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:04.531 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:05.532 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:06.531 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:07.531 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:08.530 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:09.530 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:10.531 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:11.531 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:12.531 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:13.531 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:14.532 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:15.532 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:16.532 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:17.533 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:18.532 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:19.532 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:20.532 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:21.531 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:22.532 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:23.531 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:24.531 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:25.531 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:26.531 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:27.530 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:28.531 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:29.531 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:30.531 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:31.531 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:32.532 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:33.532 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:34.533 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:35.534 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:36.534 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:37.535 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:38.542 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:39.541 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:40.541 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:41.541 UnitTest Sending ATH0 cmd $ 21-Aug-2018 23:08:41.646 UnitTest Surface Ops current action = 3 $ 21-Aug-2018 23:08:41.759 UnitTest A3LA Message ATH0 $ 21-Aug-2018 23:08:41.992 UnitTest iModem invalid response: Looking for IModem-->OK<-- Got-->ATH0 <-- $ 21-Aug-2018 23:08:41.760 UnitTest A3LA Message OK $ 21-Aug-2018 23:08:42.431 UnitTest iModem correct response: Looking for IModem-->OK<-- Got-->OK <-- $ 21-Aug-2018 23:08:42.639 UnitTest ERROR: State machine cycle time exceeded SMCyclePeriod = 00:00:01.0980739 $ 21-Aug-2018 23:08:42.857 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:08:43.857 UnitTest Trying GPS get position try num: 0 of 6 $ 21-Aug-2018 23:08:44.883 UnitTest Got Position: lat = 0000.00000 N lon = 00000.00000 E num sats = 0 $ 21-Aug-2018 23:08:45.090 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:08:45.213 UnitTest ERROR: State machine cycle time exceeded SMCyclePeriod = 00:00:01.3500880 $ 21-Aug-2018 23:08:45.420 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:08:46.421 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:08:47.421 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:08:48.422 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:08:49.429 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:08:50.437 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:08:51.444 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:08:52.451 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:08:53.459 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:08:54.466 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:08:55.466 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:08:56.474 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:08:57.481 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:08:58.488 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:08:59.496 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:00.503 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:01.503 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:02.502 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:03.502 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:04.501 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:05.501 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:06.500 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:07.501 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:08.501 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:09.502 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:10.502 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:11.503 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:12.503 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:13.504 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:14.504 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:15.505 UnitTest Trying GPS get position try num: 1 of 6 $ 21-Aug-2018 23:09:15.919 UnitTest Got Position: lat = 0000.00000 N lon = 00000.00000 E num sats = 0 $ 21-Aug-2018 23:09:16.128 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:16.512 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:17.511 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:18.512 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:19.512 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:20.513 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:21.513 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:22.520 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:23.520 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:24.521 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:25.521 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:26.522 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:27.522 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:28.523 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:29.523 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:30.524 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:31.524 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:32.525 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:33.525 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:34.526 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:35.526 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:36.526 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:37.526 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:38.527 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:39.527 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:40.528 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:41.528 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:42.535 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:43.543 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:44.543 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:45.551 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:46.558 UnitTest Trying GPS get position try num: 2 of 6 $ 21-Aug-2018 23:09:46.975 UnitTest Got Position: lat = 0000.00000 N lon = 00000.00000 E num sats = 0 $ 21-Aug-2018 23:09:47.192 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:47.558 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:48.557 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:49.558 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:50.558 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:51.559 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:52.559 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:53.566 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:54.574 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:55.581 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:56.581 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:57.588 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:58.595 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:09:59.596 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:00.603 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:01.610 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:02.618 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:03.617 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:04.625 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:05.632 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:06.632 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:07.633 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:08.640 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:09.647 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:10.648 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:11.655 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:12.663 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:13.670 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:14.669 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:15.669 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:16.668 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:17.668 UnitTest Trying GPS get position try num: 3 of 6 $ 21-Aug-2018 23:10:18.897 UnitTest Got Position: lat = 0000.00000 N lon = 00000.00000 E num sats = 0 $ 21-Aug-2018 23:10:19.114 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:19.220 UnitTest ERROR: State machine cycle time exceeded SMCyclePeriod = 00:00:01.5519611 $ 21-Aug-2018 23:10:19.445 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:20.435 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:21.435 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:22.435 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:23.435 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:24.436 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:25.436 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:26.437 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:27.437 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:28.438 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:29.438 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:30.439 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:31.439 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:32.440 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:33.447 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:34.454 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:35.462 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:36.461 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:37.468 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:38.468 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:39.467 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:40.467 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:41.466 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:42.466 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:43.466 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:44.467 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:45.467 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:46.468 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:47.468 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:48.469 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:49.470 UnitTest Trying GPS get position try num: 4 of 6 $ 21-Aug-2018 23:10:49.889 UnitTest Got Position: lat = 0000.00000 N lon = 00000.00000 E num sats = 0 $ 21-Aug-2018 23:10:50.108 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:50.469 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:51.470 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:52.470 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:53.471 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:54.471 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:55.472 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:56.473 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:57.473 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:58.480 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:10:59.488 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:11:00.495 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:11:01.502 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:11:02.510 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:11:03.510 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:11:04.517 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:11:05.525 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:11:06.532 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:11:07.539 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:11:08.547 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:11:09.554 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:11:10.555 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:11:11.554 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:11:12.554 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:11:13.553 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:11:14.554 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:11:15.554 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:11:16.555 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:11:17.554 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:11:18.555 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:11:19.555 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:11:20.556 UnitTest Trying GPS get position try num: 5 of 6 $ 21-Aug-2018 23:11:21.583 UnitTest Got Position: lat = 0000.00000 N lon = 00000.00000 E num sats = 0 $ 21-Aug-2018 23:11:21.791 UnitTest Surface Ops current action = 4 $ 21-Aug-2018 23:11:21.913 UnitTest ERROR: State machine cycle time exceeded SMCyclePeriod = 00:00:01.3506948 $ 21-Aug-2018 23:11:22.133 UnitTest Surface Ops current action = 5 $ 21-Aug-2018 23:11:23.123 UnitTest Surface Ops current action = 6 $ 21-Aug-2018 23:11:24.130 UnitTest 2018-08-21T23:11:24Z,GPS TOF,231142.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,742.060,Can Hum,39.055,,.., $ 21-Aug-2018 23:11:24.440 UnitTest Surface Ops current action = 7 $ 21-Aug-2018 23:11:25.124 UnitTest Sending SBDWT cmd $ 21-Aug-2018 23:11:25.229 UnitTest Sent SBDWT Command $ 21-Aug-2018 23:11:25.351 UnitTest Surface Ops current action = 7 $ 21-Aug-2018 23:11:25.344 UnitTest A3LA Message AT+SBDWT $ 21-Aug-2018 23:11:25.686 UnitTest iModem invalid response: Looking for IModem-->READY<-- Got-->AT+SBDWT <-- $ 21-Aug-2018 23:11:25.345 UnitTest A3LA Message READY $ 21-Aug-2018 23:11:26.129 UnitTest iModem correct response: Looking for IModem-->READY<-- Got-->READY <-- $ 21-Aug-2018 23:11:26.337 UnitTest ERROR: State machine cycle time exceeded SMCyclePeriod = 00:00:01.2129380 $ 21-Aug-2018 23:11:26.555 UnitTest Surface Ops current action = 8 $ 21-Aug-2018 23:11:27.554 UnitTest Sending loadSBDWT cmd $ 21-Aug-2018 23:11:27.666 UnitTest Loaded SBDWT Buffer with->2018-08-21T23:11:24Z,GPS TOF,231142.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,742.060,Can Hum,39.055,,..,<- $ 21-Aug-2018 23:11:28.129 UnitTest Surface Ops current action = 8 $ 21-Aug-2018 23:11:28.125 UnitTest A3LA Message 2018-08-21T23:11:24Z,GPS TOF,231142.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,742.060,Can Hum,39.055,,.., $ 21-Aug-2018 23:11:28.724 UnitTest iModem invalid response: Looking for IModem-->OK<-- Got-->2018-08-21T23:11:24Z,GPS TOF,231142.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,742.060,Can Hum,39.055,,.., <-- $ 21-Aug-2018 23:11:28.125 UnitTest A3LA Message 0 $ 21-Aug-2018 23:11:29.394 UnitTest iModem invalid response: Looking for IModem-->OK<-- Got-->0 <-- $ 21-Aug-2018 23:11:28.126 UnitTest A3LA Message $ 21-Aug-2018 23:11:29.833 UnitTest iModem invalid response: Looking for IModem-->OK<-- Got--> <-- $ 21-Aug-2018 23:11:28.126 UnitTest A3LA Message OK $ 21-Aug-2018 23:11:30.272 UnitTest iModem correct response: Looking for IModem-->OK<-- Got-->OK <-- $ 21-Aug-2018 23:11:30.492 UnitTest ERROR: State machine cycle time exceeded SMCyclePeriod = 00:00:02.9272988 $ 21-Aug-2018 23:11:30.710 UnitTest Surface Ops current action = 9 $ 21-Aug-2018 23:11:31.700 UnitTest Sending SBDIX cmd $ 21-Aug-2018 23:11:31.805 UnitTest Sent SBDIX Command $ 21-Aug-2018 23:11:31.927 UnitTest Surface Ops current action = 9 $ 21-Aug-2018 23:11:32.700 UnitTest Surface Ops current action = 9 $ 21-Aug-2018 23:11:33.701 UnitTest Surface Ops current action = 9 $ 21-Aug-2018 23:11:34.707 UnitTest Surface Ops current action = 9 $ 21-Aug-2018 23:11:35.708 UnitTest Surface Ops current action = 9 $ 21-Aug-2018 23:11:36.708 UnitTest Surface Ops current action = 9 $ 21-Aug-2018 23:11:37.709 UnitTest Surface Ops current action = 9 $ 21-Aug-2018 23:11:38.709 UnitTest Surface Ops current action = 9 $ 21-Aug-2018 23:11:39.710 UnitTest Surface Ops current action = 9 $ 21-Aug-2018 23:11:40.710 UnitTest Surface Ops current action = 9 $ 21-Aug-2018 23:11:41.711 UnitTest Surface Ops current action = 9 $ 21-Aug-2018 23:11:42.711 UnitTest Surface Ops current action = 9 $ 21-Aug-2018 23:11:43.712 UnitTest Surface Ops current action = 9 $ 21-Aug-2018 23:11:43.459 UnitTest A3LA Message AT+SBDIX $ 21-Aug-2018 23:11:44.047 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->AT+SBDIX <-- $ 21-Aug-2018 23:11:44.267 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->AT+SBDIX <-- $ 21-Aug-2018 23:11:44.489 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->AT+SBDIX <-- $ 21-Aug-2018 23:11:44.709 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->AT+SBDIX <-- $ 21-Aug-2018 23:11:44.930 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->AT+SBDIX <-- $ 21-Aug-2018 23:11:43.473 UnitTest A3LA Message +SBDIX: 18, 0, 2, 0, 0, 0 $ 21-Aug-2018 23:11:45.375 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->+SBDIX: 18, 0, 2, 0, 0, 0 <-- $ 21-Aug-2018 23:11:45.597 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->+SBDIX: 18, 0, 2, 0, 0, 0 <-- $ 21-Aug-2018 23:11:45.819 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->+SBDIX: 18, 0, 2, 0, 0, 0 <-- $ 21-Aug-2018 23:11:46.041 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->+SBDIX: 18, 0, 2, 0, 0, 0 <-- $ 21-Aug-2018 23:11:46.265 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->+SBDIX: 18, 0, 2, 0, 0, 0 <-- $ 21-Aug-2018 23:11:43.474 UnitTest A3LA Message $ 21-Aug-2018 23:11:46.708 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got--> <-- $ 21-Aug-2018 23:11:46.927 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got--> <-- $ 21-Aug-2018 23:11:47.089 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got--> <-- $ 21-Aug-2018 23:11:47.106 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got--> <-- $ 21-Aug-2018 23:11:47.123 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got--> <-- $ 21-Aug-2018 23:11:43.476 UnitTest A3LA Message OK $ 21-Aug-2018 23:11:47.159 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 0,<-- Got-->OK <-- $ 21-Aug-2018 23:11:47.181 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 1,<-- Got-->OK <-- $ 21-Aug-2018 23:11:47.200 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 2,<-- Got-->OK <-- $ 21-Aug-2018 23:11:47.217 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 3,<-- Got-->OK <-- $ 21-Aug-2018 23:11:47.234 UnitTest iModem invalid response: Looking for IModem-->+SBDIX: 4,<-- Got-->OK <-- $ 21-Aug-2018 23:11:47.251 UnitTest ERROR: State machine cycle time exceeded SMCyclePeriod = 00:00:03.5281592 $ 21-Aug-2018 23:11:47.264 UnitTest Surface Ops current action = 9