2019-08-29T22:09:28.937Z,1567116568.937 [Supervisor](DEBUG): Initializing supervisor.
2019-08-29T22:09:28.949Z,1567116568.949 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-08-29T22:09:28.949Z,1567116568.949 [SyncHandler](INFO): Protected caller Thread ID is 806
2019-08-29T22:09:28.950Z,1567116568.950 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-08-29T22:09:28.961Z,1567116568.961 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-08-29T22:09:28.961Z,1567116568.961 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 807
2019-08-29T22:09:28.964Z,1567116568.964 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-08-29T22:09:28.986Z,1567116568.986 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-08-29T22:09:28.997Z,1567116568.997 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-08-29T22:09:28.997Z,1567116568.997 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 808
2019-08-29T22:09:28.998Z,1567116568.998 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-08-29T22:09:29.009Z,1567116569.009 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-08-29T22:09:29.009Z,1567116569.009 [logger ThreadHandler](INFO): Protected caller Thread ID is 809
2019-08-29T22:09:29.011Z,1567116569.011 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-08-29T22:09:29.012Z,1567116569.012 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-08-29T22:09:29.025Z,1567116569.025 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-08-29T22:09:29.724Z,1567116569.724 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-08-29T22:09:29.726Z,1567116569.726 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-08-29T22:09:29.824Z,1567116569.824 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-08-29T22:09:29.825Z,1567116569.825 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-08-29T22:09:30.145Z,1567116570.145 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-08-29T22:09:30.147Z,1567116570.147 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-08-29T22:09:30.287Z,1567116570.287 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-08-29T22:09:30.289Z,1567116570.289 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-08-29T22:09:30.481Z,1567116570.481 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-08-29T22:09:30.483Z,1567116570.483 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-08-29T22:09:30.955Z,1567116570.955 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-08-29T22:09:30.956Z,1567116570.956 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-08-29T22:09:31.163Z,1567116571.163 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-08-29T22:09:31.165Z,1567116571.165 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-08-29T22:09:31.310Z,1567116571.310 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-08-29T22:09:31.312Z,1567116571.312 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-08-29T22:09:31.511Z,1567116571.511 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-08-29T22:09:31.512Z,1567116571.512 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-08-29T22:09:32.177Z,1567116572.177 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-08-29T22:09:32.178Z,1567116572.178 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-08-29T22:09:32.479Z,1567116572.479 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-08-29T22:09:32.480Z,1567116572.480 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-08-29T22:09:32.562Z,1567116572.562 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-08-29T22:09:32.665Z,1567116572.665 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-08-29T22:09:32.666Z,1567116572.666 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-08-29T22:09:33.261Z,1567116573.261 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-08-29T22:09:33.262Z,1567116573.262 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-08-29T22:09:33.662Z,1567116573.662 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-08-29T22:09:33.664Z,1567116573.664 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-08-29T22:09:33.666Z,1567116573.666 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-08-29T22:09:33.879Z,1567116573.879 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-08-29T22:09:33.981Z,1567116573.981 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-08-29T22:09:34.081Z,1567116574.081 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-08-29T22:09:34.309Z,1567116574.309 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-08-29T22:09:34.310Z,1567116574.310 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-08-29T22:09:34.395Z,1567116574.395 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-08-29T22:09:34.488Z,1567116574.488 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-08-29T22:09:34.585Z,1567116574.585 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-08-29T22:09:34.668Z,1567116574.668 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-08-29T22:09:34.778Z,1567116574.778 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-08-29T22:09:34.963Z,1567116574.963 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-08-29T22:09:35.095Z,1567116575.095 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2019-08-29T22:09:35.100Z,1567116575.100 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-08-29T22:09:35.873Z,1567116575.873 [AHRS_M2] Loaded
2019-08-29T22:09:35.873Z,1567116575.873 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-08-29T22:09:36.018Z,1567116576.018 [DataOverHttps] Loaded
2019-08-29T22:09:36.018Z,1567116576.018 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-08-29T22:09:36.019Z,1567116576.019 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407664E0
2019-08-29T22:09:36.020Z,1567116576.020 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 889
2019-08-29T22:09:36.033Z,1567116576.033 [Depth_Keller] Loaded
2019-08-29T22:09:36.034Z,1567116576.034 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-08-29T22:09:36.038Z,1567116576.038 [DropWeight] Loaded
2019-08-29T22:09:36.039Z,1567116576.039 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-08-29T22:09:36.090Z,1567116576.090 [Micromodem] Loaded
2019-08-29T22:09:36.091Z,1567116576.091 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-08-29T22:09:36.189Z,1567116576.189 [NAL9602] Loaded
2019-08-29T22:09:36.189Z,1567116576.189 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-08-29T22:09:36.205Z,1567116576.205 [Onboard] Loaded
2019-08-29T22:09:36.206Z,1567116576.206 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-08-29T22:09:36.211Z,1567116576.211 [PowerOnly] Loaded
2019-08-29T22:09:36.212Z,1567116576.212 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2019-08-29T22:09:36.218Z,1567116576.218 [Radio_Surface] Loaded
2019-08-29T22:09:36.219Z,1567116576.219 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-08-29T22:09:36.219Z,1567116576.219 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407964E0
2019-08-29T22:09:36.220Z,1567116576.220 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 890
2019-08-29T22:09:36.264Z,1567116576.264 [RDI_Pathfinder] Loaded
2019-08-29T22:09:36.265Z,1567116576.265 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-08-29T22:09:38.023Z,1567116578.023 [BPC1] Loaded
2019-08-29T22:09:38.023Z,1567116578.023 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-08-29T22:09:38.024Z,1567116578.024 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-08-29T22:09:38.024Z,1567116578.024 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-08-29T22:09:38.048Z,1567116578.048 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-08-29T22:09:38.049Z,1567116578.049 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-08-29T22:09:38.172Z,1567116578.172 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-08-29T22:09:38.173Z,1567116578.173 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-08-29T22:09:38.193Z,1567116578.193 [NavChart] Loaded
2019-08-29T22:09:38.193Z,1567116578.193 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-08-29T22:09:38.197Z,1567116578.197 [UniversalFixResidualReporter] Loaded
2019-08-29T22:09:38.198Z,1567116578.198 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-08-29T22:09:38.198Z,1567116578.198 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-08-29T22:09:38.199Z,1567116578.199 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-08-29T22:09:38.331Z,1567116578.331 [BuoyancyServo] Loaded
2019-08-29T22:09:38.332Z,1567116578.332 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-08-29T22:09:38.347Z,1567116578.347 [ElevatorServo] Loaded
2019-08-29T22:09:38.347Z,1567116578.347 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-08-29T22:09:38.362Z,1567116578.362 [MassServo] Loaded
2019-08-29T22:09:38.363Z,1567116578.363 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-08-29T22:09:38.378Z,1567116578.378 [RudderServo] Loaded
2019-08-29T22:09:38.378Z,1567116578.378 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-08-29T22:09:38.393Z,1567116578.393 [ThrusterServo] Loaded
2019-08-29T22:09:38.393Z,1567116578.393 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-08-29T22:09:38.394Z,1567116578.394 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-08-29T22:09:38.395Z,1567116578.395 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-08-29T22:09:38.652Z,1567116578.652 [Aanderaa_O2] Loaded
2019-08-29T22:09:38.652Z,1567116578.652 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-08-29T22:09:38.736Z,1567116578.736 [CTD_NeilBrown] Loaded
2019-08-29T22:09:38.736Z,1567116578.736 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-08-29T22:09:38.737Z,1567116578.737 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408E14E0
2019-08-29T22:09:38.737Z,1567116578.737 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 891
2019-08-29T22:09:38.752Z,1567116578.752 [PAR_Licor] Loaded
2019-08-29T22:09:38.752Z,1567116578.752 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-08-29T22:09:38.797Z,1567116578.797 [WetLabsSeaOWL_UV_A] Loaded
2019-08-29T22:09:38.798Z,1567116578.798 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-08-29T22:09:38.799Z,1567116578.799 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409114E0
2019-08-29T22:09:38.799Z,1567116578.799 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 892
2019-08-29T22:09:38.800Z,1567116578.800 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-08-29T22:09:38.800Z,1567116578.800 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-08-29T22:09:39.144Z,1567116579.144 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-08-29T22:09:39.144Z,1567116579.144 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-08-29T22:09:39.200Z,1567116579.200 [DepthRateCalculator] Loaded
2019-08-29T22:09:39.201Z,1567116579.201 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-08-29T22:09:39.207Z,1567116579.207 [PitchRateCalculator] Loaded
2019-08-29T22:09:39.207Z,1567116579.207 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-08-29T22:09:39.219Z,1567116579.219 [SpeedCalculator] Loaded
2019-08-29T22:09:39.220Z,1567116579.220 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-08-29T22:09:39.241Z,1567116579.241 [TempGradientCalculator] Loaded
2019-08-29T22:09:39.241Z,1567116579.241 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-08-29T22:09:39.247Z,1567116579.247 [YawRateCalculator] Loaded
2019-08-29T22:09:39.247Z,1567116579.247 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-08-29T22:09:39.288Z,1567116579.288 [ElevatorOffsetCalculator] Loaded
2019-08-29T22:09:39.289Z,1567116579.289 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-08-29T22:09:39.289Z,1567116579.289 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-08-29T22:09:39.290Z,1567116579.290 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-08-29T22:09:39.451Z,1567116579.451 [SBIT](DEBUG): Construct Startup Built In Test.
2019-08-29T22:09:39.473Z,1567116579.473 [SBIT] Loaded
2019-08-29T22:09:39.474Z,1567116579.474 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-08-29T22:09:39.474Z,1567116579.474 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-08-29T22:09:39.486Z,1567116579.486 [IBIT] Loaded
2019-08-29T22:09:39.486Z,1567116579.486 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-08-29T22:09:39.490Z,1567116579.490 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-08-29T22:09:39.631Z,1567116579.631 [CBIT] Loaded
2019-08-29T22:09:39.631Z,1567116579.631 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-08-29T22:09:39.631Z,1567116579.631 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-08-29T22:09:39.632Z,1567116579.632 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-08-29T22:09:39.757Z,1567116579.757 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-08-29T22:09:39.758Z,1567116579.758 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-08-29T22:09:39.884Z,1567116579.884 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-08-29T22:09:39.885Z,1567116579.885 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-08-29T22:09:39.973Z,1567116579.973 [VerticalControl](DEBUG): Construct VerticalControl.
2019-08-29T22:09:40.058Z,1567116580.058 [VerticalControl] Loaded
2019-08-29T22:09:40.058Z,1567116580.058 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-08-29T22:09:40.059Z,1567116580.059 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-08-29T22:09:40.117Z,1567116580.117 [HorizontalControl] Loaded
2019-08-29T22:09:40.117Z,1567116580.117 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-08-29T22:09:40.118Z,1567116580.118 [SpeedControl](DEBUG): Construct SpeedControl.
2019-08-29T22:09:40.120Z,1567116580.120 [SpeedControl] Loaded
2019-08-29T22:09:40.120Z,1567116580.120 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-08-29T22:09:40.121Z,1567116580.121 [LoopControl](DEBUG): Construct LoopControl.
2019-08-29T22:09:40.121Z,1567116580.121 [LoopControl] Loaded
2019-08-29T22:09:40.122Z,1567116580.122 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-08-29T22:09:40.122Z,1567116580.122 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-08-29T22:09:40.123Z,1567116580.123 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-08-29T22:09:40.170Z,1567116580.170 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-08-29T22:09:40.174Z,1567116580.174 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-08-29T22:09:40.174Z,1567116580.174 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-08-29T22:09:40.181Z,1567116580.181 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-08-29T22:09:40.182Z,1567116580.182 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AED4E0
2019-08-29T22:09:40.183Z,1567116580.183 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 893
2019-08-29T22:09:40.187Z,1567116580.187 [Supervisor](INFO): Main Thread ID is 802
2019-08-29T22:09:40.188Z,1567116580.188 [Supervisor](DEBUG): Running supervisor.
2019-08-29T22:09:40.188Z,1567116580.188 [CommandLine ThreadHandler](INFO): Handler Thread ID is 894
2019-08-29T22:09:40.190Z,1567116580.190 [controlThread ThreadHandler](INFO): Handler Thread ID is 895
2019-08-29T22:09:40.191Z,1567116580.191 [controlThread](DEBUG): Initializing ControlThread
2019-08-29T22:09:40.196Z,1567116580.196 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-08-29T22:09:40.196Z,1567116580.196 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-08-29T22:09:40.201Z,1567116580.201 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-08-29T22:09:40.201Z,1567116580.201 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-08-29T22:09:40.202Z,1567116580.202 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-08-29T22:09:40.202Z,1567116580.202 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-08-29T22:09:40.202Z,1567116580.202 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-08-29T22:09:40.203Z,1567116580.203 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-08-29T22:09:40.203Z,1567116580.203 [SBIT](INFO): Initialize SBIT Component.
2019-08-29T22:09:40.204Z,1567116580.204 [SBIT](IMPORTANT): git: 2019-08-24A
2019-08-29T22:09:40.204Z,1567116580.204 [SBIT](INFO): git hash: 1cd70827f152bddb570e5fcd117087690803f3ad
2019-08-29T22:09:40.204Z,1567116580.204 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-08-29T22:09:40.205Z,1567116580.205 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #2 PREEMPT Wed May 15 08:34:03 PDT 2019
2019-08-29T22:09:40.207Z,1567116580.207 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-08-29T22:09:40.207Z,1567116580.207 [IBIT](INFO): Initialize IBIT Component.
2019-08-29T22:09:40.208Z,1567116580.208 [CBIT](DEBUG): Initialize CBIT Component.
2019-08-29T22:09:40.209Z,1567116580.209 [logger ThreadHandler](INFO): Handler Thread ID is 896
2019-08-29T22:09:40.221Z,1567116580.221 [CBIT](DEBUG): Initialized mux pins.
2019-08-29T22:09:40.221Z,1567116580.221 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2019-08-29T22:09:40.221Z,1567116580.221 [CBIT](DEBUG): Initializing the watchdog timer.
2019-08-29T22:09:40.229Z,1567116580.229 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 897
2019-08-29T22:09:40.230Z,1567116580.230 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-08-29T22:09:40.241Z,1567116580.241 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 898
2019-08-29T22:09:40.245Z,1567116580.245 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2019-08-29T22:09:40.245Z,1567116580.245 [CBIT](DEBUG): Initializing heartbeat.
2019-08-29T22:09:40.253Z,1567116580.253 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 899
2019-08-29T22:09:40.254Z,1567116580.254 [CTD_NeilBrown](INFO): Powering down
2019-08-29T22:09:40.282Z,1567116580.282 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 900
2019-08-29T22:09:40.282Z,1567116580.282 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-08-29T22:09:40.309Z,1567116580.309 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 901
2019-08-29T22:09:40.317Z,1567116580.317 [CBIT](DEBUG): Deactivating GF circuits.
2019-08-29T22:09:40.317Z,1567116580.317 [CBIT](DEBUG): Deactivating emergency mode.
2019-08-29T22:09:40.321Z,1567116580.321 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-08-29T22:09:40.322Z,1567116580.322 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-08-29T22:09:40.322Z,1567116580.322 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-08-29T22:09:40.322Z,1567116580.322 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-08-29T22:09:40.322Z,1567116580.322 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-08-29T22:09:40.322Z,1567116580.322 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-08-29T22:09:40.322Z,1567116580.322 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-08-29T22:09:40.323Z,1567116580.323 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-08-29T22:09:40.323Z,1567116580.323 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-08-29T22:09:40.323Z,1567116580.323 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-08-29T22:09:40.323Z,1567116580.323 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-08-29T22:09:40.323Z,1567116580.323 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-08-29T22:09:40.324Z,1567116580.324 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-08-29T22:09:40.324Z,1567116580.324 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-08-29T22:09:40.324Z,1567116580.324 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-08-29T22:09:40.324Z,1567116580.324 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-08-29T22:09:40.353Z,1567116580.353 [CBIT](DEBUG): Backplane powered.
2019-08-29T22:09:40.353Z,1567116580.353 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-08-29T22:09:40.355Z,1567116580.355 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-08-29T22:09:40.355Z,1567116580.355 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-08-29T22:09:40.356Z,1567116580.356 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-08-29T22:09:40.357Z,1567116580.357 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-08-29T22:09:40.392Z,1567116580.392 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-08-29T22:09:40.414Z,1567116580.414 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2019-08-29T22:09:40.415Z,1567116580.415 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-08-29T22:09:40.491Z,1567116580.491 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-08-29T22:09:40.492Z,1567116580.492 [Default:A.Wait](DEBUG): Construct Wait.
2019-08-29T22:09:40.494Z,1567116580.494 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-08-29T22:09:40.504Z,1567116580.504 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-08-29T22:09:40.523Z,1567116580.523 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-08-29T22:09:40.547Z,1567116580.547 [Default:E.Execute](DEBUG): Construct Execute.
2019-08-29T22:09:40.551Z,1567116580.551 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2019-08-29T22:09:40.569Z,1567116580.569 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,Aanderaa_O2,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-08-29T22:09:40.575Z,1567116580.575 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-08-29T22:09:40.645Z,1567116580.645 [Radio_Surface](INFO): Powering up
2019-08-29T22:09:40.862Z,1567116580.862 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-08-29T22:09:40.869Z,1567116580.869 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-08-29T22:09:40.870Z,1567116580.870 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-08-29T22:09:40.877Z,1567116580.877 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-08-29T22:09:40.878Z,1567116580.878 [MassServo](DEBUG): Initializing EZServoServo.
2019-08-29T22:09:40.885Z,1567116580.885 [MassServo](DEBUG): Initializing MassServo.
2019-08-29T22:09:40.886Z,1567116580.886 [RudderServo](DEBUG): Initializing EZServoServo.
2019-08-29T22:09:40.893Z,1567116580.893 [RudderServo](DEBUG): Initializing RudderServo.
2019-08-29T22:09:40.894Z,1567116580.894 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-08-29T22:09:40.901Z,1567116580.901 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-08-29T22:09:40.903Z,1567116580.903 [CommandLine](FAULT): Scheduling is paused
2019-08-29T22:09:40.903Z,1567116580.903 [CBIT](INFO): Critical error at 20190829T220940
2019-08-29T22:09:40.904Z,1567116580.904 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-08-29T22:09:40.000Z,1567116581.000 [Micromodem](INFO): Powering up
2019-08-29T22:09:41.000Z,1567116581.000 [Micromodem](DEBUG): Initializing Micromodem.
2019-08-29T22:09:41.773Z,1567116581.773 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-08-29T22:09:41.773Z,1567116581.773 [RudderServo](FAULT): Rudder failed to initialize
2019-08-29T22:09:41.773Z,1567116581.773 [RudderServo] Communications Fault, FailCount= 1
2019-08-29T22:09:41.773Z,1567116581.773 [RudderServo](ERROR): Communications Fault
2019-08-29T22:09:41.888Z,1567116581.888 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-08-29T22:09:42.080Z,1567116582.080 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-08-29T22:09:42.081Z,1567116582.081 [RudderServo](INFO): Powering down
2019-08-29T22:09:42.767Z,1567116582.767 [RudderServo](DEBUG): Initializing EZServoServo.
2019-08-29T22:09:42.886Z,1567116582.886 [RudderServo](DEBUG): Initializing RudderServo.
2019-08-29T22:09:42.890Z,1567116582.890 [CBIT](INFO): Clearing failed state for component RudderServo
2019-08-29T22:09:42.890Z,1567116582.890 [RudderServo] No Fault, FailCount= 1
2019-08-29T22:09:44.125Z,1567116584.125 [Aanderaa_O2](INFO): Powering down
2019-08-29T22:09:45.975Z,1567116585.975 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2019-08-29T22:09:52.039Z,1567116592.039 [Micromodem](INFO): Nmea in: $CATMG,2019-08-29T22:09:27.164558Z,RTC,RTC*5E

2019-08-29T22:09:52.039Z,1567116592.039 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2019-08-29T22:09:27.164558Z,RTC,RTC*5E

2019-08-29T22:09:54.109Z,1567116594.109 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-08-29T22:09:57.306Z,1567116597.306 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2019-08-29T22:09:57.699Z,1567116597.699 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2019-08-29T22:09:57.699Z,1567116597.699 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2019-08-29T22:09:58.099Z,1567116598.099 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2019-08-29T22:09:58.099Z,1567116598.099 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-08-29T22:09:58.507Z,1567116598.507 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2019-08-29T22:09:58.507Z,1567116598.507 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2019-08-29T22:09:58.911Z,1567116598.911 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2019-08-29T22:09:58.911Z,1567116598.911 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2019-08-29T22:09:59.314Z,1567116599.314 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2019-08-29T22:09:59.315Z,1567116599.315 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2019-08-29T22:09:59.731Z,1567116599.731 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2019-08-29T22:09:59.731Z,1567116599.731 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2019-08-29T22:10:00.119Z,1567116600.119 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2019-08-29T22:10:00.119Z,1567116600.119 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2019-08-29T22:10:00.523Z,1567116600.523 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2019-08-29T22:10:00.523Z,1567116600.523 [Micromodem](INFO): Nmea out: $CCCLK,2019,08,29,22,10,01*4D

2019-08-29T22:10:01.735Z,1567116601.735 [Micromodem](INFO): Nmea in: $CACLK,2019,8,29,22,10,1*4F

2019-08-29T22:10:01.736Z,1567116601.736 [Micromodem](INFO): Nmea in: $CATMS,0,2019-08-29T22:10:02Z*7C

2019-08-29T22:10:01.738Z,1567116601.738 [Micromodem](INFO): Nmea in: $CATMG,2019-08-29T22:10:02.028722Z,USER_CMD,RTC*16

2019-08-29T22:10:06.583Z,1567116606.583 [NAL9602](INFO): Powering up NAL9602
2019-08-29T22:10:08.690Z,1567116608.690 [SBIT](IMPORTANT): Beginning Startup BIT
2019-08-29T22:10:08.694Z,1567116608.694 [CBIT](IMPORTANT): Beginning ground fault scan
2019-08-29T22:10:17.491Z,1567116617.491 [NAL9602](INFO): NAL9602 initialized
2019-08-29T22:10:18.313Z,1567116618.313 [NAL9602](DEBUG): Fix Requested
2019-08-29T22:10:19.645Z,1567116619.645 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.026468
CHAN A1 (24V): -0.024424
CHAN A2 (12V): -0.005725
CHAN A3 (5V): -0.002452
CHAN B0 (3.3V): 0.000122
CHAN B1 (3.15aV): -0.000267
CHAN B2 (3.15bV): 0.000263
CHAN B3 (GND): 0.000418
OPEN: 0.008944
Full Scale Calc: 4.765 mA, -1.589 mA
2019-08-29T22:10:22.035Z,1567116622.035 [SBIT](FAULT): Mass: EXPECTED:0.008600 ACTUAL:0.004240
2019-08-29T22:10:35.680Z,1567116635.680 [NAL9602](INFO): SBD MO Status=0, MOMSN=8926, MT Status=0, MTMSN=0
2019-08-29T22:10:35.680Z,1567116635.680 [NAL9602](INFO): No messages in MT queue
2019-08-29T22:10:36.497Z,1567116636.497 [NAL9602](DEBUG): Fix Requested
2019-08-29T22:10:39.325Z,1567116639.325 [NAL9602](DEBUG): Fix Requested
2019-08-29T22:10:42.561Z,1567116642.561 [NAL9602](DEBUG): Fix Requested
2019-08-29T22:10:45.385Z,1567116645.385 [NAL9602](DEBUG): Fix Requested
2019-08-29T22:10:48.617Z,1567116648.617 [NAL9602](DEBUG): Fix Requested
2019-08-29T22:10:51.537Z,1567116651.537 [NAL9602](DEBUG): Fix Requested
2019-08-29T22:10:54.769Z,1567116654.769 [NAL9602](DEBUG): Fix Requested
2019-08-29T22:10:57.597Z,1567116657.597 [NAL9602](DEBUG): Fix Requested
2019-08-29T22:11:00.425Z,1567116660.425 [NAL9602](DEBUG): Fix Requested
2019-08-29T22:11:02.884Z,1567116662.884 [SBIT](CRITICAL): SBIT FAILED
2019-08-29T22:11:02.904Z,1567116662.904 [CBIT](INFO): Critical error at 20190829T221102
2019-08-29T22:11:02.934Z,1567116662.934 [CommandLine](IMPORTANT): got command configSet list
2019-08-29T22:11:02.934Z,1567116662.934 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-08-29T22:11:02.938Z,1567116662.938 [CommandLine](IMPORTANT): Courier linearApproximation WetLabsSeaOWL_UV_A.OutputFDOM 100.000000 count;

2019-08-29T22:11:02.938Z,1567116662.938 [CommandLine](IMPORTANT): Courier none depth;

2019-08-29T22:11:02.938Z,1567116662.938 [CommandLine](IMPORTANT): Courier linearApproximation latitude 0.000500 angular_degree;

2019-08-29T22:11:02.938Z,1567116662.938 [CommandLine](IMPORTANT): Courier linearApproximation longitude 0.000500 angular_degree;

2019-08-29T22:11:02.938Z,1567116662.938 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.height_above_sea_floor 2.000000 meter;

2019-08-29T22:11:02.938Z,1567116662.938 [CommandLine](IMPORTANT): Express linearApproximation WetLabsSeaOWL_UV_A.OutputFDOM 50.000000 count;

2019-08-29T22:11:02.938Z,1567116662.938 [CommandLine](IMPORTANT): Express linearApproximation WetLabsSeaOWL_UV_A.concentration_of_chromophoric_dissolved_organic_matter_in_sea_water 0.050000 part_per_billion;

2019-08-29T22:11:02.938Z,1567116662.938 [CommandLine](IMPORTANT): Express linearApproximation WetLabsSeaOWL_UV_A.mass_concentration_of_petroleum_hydrocarbons_in_sea_water 0.000020 kilogram_per_cubic_meter;

2019-08-29T22:11:02.939Z,1567116662.939 [CommandLine](IMPORTANT): Express linearApproximation concentration_of_chromophoric_dissolved_organic_matter_in_sea_water 0.100000 part_per_billion;

2019-08-29T22:11:02.939Z,1567116662.939 [CommandLine](IMPORTANT): Express none mass_concentration_of_oxygen_in_sea_water;

2019-08-29T22:11:02.939Z,1567116662.939 [CommandLine](IMPORTANT): Express none sea_water_salinity;

2019-08-29T22:11:02.939Z,1567116662.939 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold=2 meter;

2019-08-29T22:11:02.939Z,1567116662.939 [CommandLine](IMPORTANT): NAL9602.fastGPSFix=1 bool;

2019-08-29T22:11:02.939Z,1567116662.939 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=278 cubic_centimeter;

2019-08-29T22:11:02.939Z,1567116662.939 [CommandLine](IMPORTANT): VerticalControl.massDefault=25 millimeter;

2019-08-29T22:11:03.273Z,1567116663.273 [MissionManager](IMPORTANT): Started mission Startup
2019-08-29T22:11:03.273Z,1567116663.273 [Startup] Running Loop=1
2019-08-29T22:11:03.273Z,1567116663.273 [Startup](DEBUG): Aggregate::initialize Startup
2019-08-29T22:11:03.273Z,1567116663.273 [Startup:A.GoToSurface] Running Loop=1
2019-08-29T22:11:03.273Z,1567116663.273 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-08-29T22:11:03.274Z,1567116663.274 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-08-29T22:11:03.274Z,1567116663.274 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-08-29T22:11:03.275Z,1567116663.275 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-08-29T22:11:03.275Z,1567116663.275 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-08-29T22:11:03.275Z,1567116663.275 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-08-29T22:11:03.277Z,1567116663.277 [Startup:StartupSatComms] Running Loop=1
2019-08-29T22:11:03.277Z,1567116663.277 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-08-29T22:11:03.277Z,1567116663.277 [Startup:StartupSatComms:A] Running Loop=1
2019-08-29T22:11:03.657Z,1567116663.657 [NAL9602](DEBUG): Fix Requested
2019-08-29T22:11:03.723Z,1567116663.723 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-08-29T22:11:06.889Z,1567116666.889 [NAL9602](DEBUG): Fix Requested
2019-08-29T22:11:09.317Z,1567116669.317 [NAL9602](DEBUG): Fix Requested
2019-08-29T22:11:12.545Z,1567116672.545 [NAL9602](DEBUG): Fix Requested
2019-08-29T22:11:15.369Z,1567116675.369 [NAL9602](DEBUG): Fix Requested
2019-08-29T22:11:18.605Z,1567116678.605 [NAL9602](DEBUG): Fix Requested
2019-08-29T22:11:21.429Z,1567116681.429 [NAL9602](DEBUG): Fix Requested
2019-08-29T22:11:24.661Z,1567116684.661 [NAL9602](DEBUG): Fix Requested
2019-08-29T22:11:27.505Z,1567116687.505 [NAL9602](DEBUG): Fix Requested
2019-08-29T22:11:30.725Z,1567116690.725 [NAL9602](DEBUG): Fix Requested
2019-08-29T22:11:33.553Z,1567116693.553 [NAL9602](DEBUG): Fix Requested
2019-08-29T22:11:36.377Z,1567116696.377 [NAL9602](DEBUG): Fix Requested
2019-08-29T22:11:39.613Z,1567116699.613 [NAL9602](DEBUG): Fix Requested
2019-08-29T22:11:42.441Z,1567116702.441 [NAL9602](DEBUG): Fix Requested
2019-08-29T22:11:42.833Z,1567116702.833 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221100.00,A,3423.36720,N,11950.60116,W,0.039,0.00,290819,,,A*77
2019-08-29T22:11:42.836Z,1567116702.836 [NAL9602](INFO): GPS fix at 20190829T221100: (34.389453, -119.843353)
2019-08-29T22:11:42.882Z,1567116702.882 [Startup:StartupSatComms:A] Stopped
2019-08-29T22:11:42.882Z,1567116702.882 [Startup:StartupSatComms:B] Running Loop=1
2019-08-29T22:11:43.311Z,1567116703.311 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-08-29T22:11:45.607Z,1567116705.607 [Micromodem](INFO): Outgoing frame #1, 60 bytes: 26EE675DA80000005D00000400260000000000000000151D80030C5669C010DBC54863B619DE605C2D92D86E4BE6F93129D54E899452DF46ACA9EB00
2019-08-29T22:11:45.608Z,1567116705.608 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:11:45.840Z,1567116705.840 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:11:46.198Z,1567116706.198 [Micromodem](INFO): Nmea in: $CADRQ,221146,1,9,0,64,1*4C

2019-08-29T22:11:46.199Z,1567116706.199 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,26EE675DA80000005D00000400260000000000000000151D80030C5669C010DBC54863B619DE605C2D92D86E4BE6F93129D54E899452DF46ACA9EB00*79

2019-08-29T22:11:46.602Z,1567116706.602 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,60*75

2019-08-29T22:11:47.010Z,1567116707.010 [Micromodem](INFO): Nmea in: $CATXP,60*74

2019-08-29T22:11:48.218Z,1567116708.218 [Micromodem](INFO): Nmea in: $CATXF,60*62

2019-08-29T22:11:48.627Z,1567116708.627 [Micromodem](INFO): Nmea in: $CAXST,6,20190829,221147.181604,3,0,200,4000,10000,1,1,9,1,1,1,3,60*7C

2019-08-29T22:11:51.857Z,1567116711.857 [Micromodem](INFO): Nmea in: $CAACK,9,1,1,1*43

2019-08-29T22:11:51.858Z,1567116711.858 [Micromodem](INFO): Sent 52 bytes from file Logs/20190829T152422/Courier0168.lzma.parts
2019-08-29T22:11:51.858Z,1567116711.858 [Micromodem](INFO): Packets left to send: 0
2019-08-29T22:11:52.270Z,1567116712.270 [Micromodem](INFO): Nmea in: $CACST,6,0,20190829221151.466179,06,13834,41,0314,0150,07,00,00,00,01,1,009,001,0,4,1,0,150,14.1,4.00,13,-1.00,-01,-0.71,48,10000,4000*4A

2019-08-29T22:11:52.925Z,1567116712.925 [Micromodem](INFO): Using rate 1 for 26 bytes.
2019-08-29T22:11:52.925Z,1567116712.925 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:11:52.925Z,1567116712.925 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:11:53.838Z,1567116713.838 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:11:54.246Z,1567116714.246 [Micromodem](INFO): Nmea in: $CADRQ,221153,1,9,0,64,1*48

2019-08-29T22:11:54.247Z,1567116714.247 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:11:54.906Z,1567116714.906 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:11:54.907Z,1567116714.907 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:11:54.907Z,1567116714.907 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:11:55.353Z,1567116715.353 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:11:55.698Z,1567116715.698 [Micromodem](INFO): Nmea in: $CADRQ,221155,1,9,0,64,1*4E

2019-08-29T22:11:55.699Z,1567116715.699 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:11:56.434Z,1567116716.434 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:11:56.434Z,1567116716.434 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:11:56.435Z,1567116716.435 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:11:56.610Z,1567116716.610 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:11:56.957Z,1567116716.957 [Micromodem](INFO): Nmea in: $CADRQ,221157,1,9,0,64,1*4C

2019-08-29T22:11:56.959Z,1567116716.959 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:11:58.397Z,1567116718.397 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:11:58.397Z,1567116718.397 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:11:58.398Z,1567116718.398 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:11:58.551Z,1567116718.551 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:11:58.930Z,1567116718.930 [Micromodem](INFO): Nmea in: $CADRQ,221159,1,9,0,64,1*42

2019-08-29T22:11:58.931Z,1567116718.931 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:11:59.596Z,1567116719.596 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:11:59.596Z,1567116719.596 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:11:59.597Z,1567116719.597 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:11:59.730Z,1567116719.730 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:00.083Z,1567116720.083 [Micromodem](INFO): Nmea in: $CADRQ,221200,1,9,0,64,1*4D

2019-08-29T22:12:00.083Z,1567116720.083 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:00.755Z,1567116720.755 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:00.755Z,1567116720.755 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:00.756Z,1567116720.756 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:00.878Z,1567116720.878 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:01.238Z,1567116721.238 [Micromodem](INFO): Nmea in: $CADRQ,221201,1,9,0,64,1*4C

2019-08-29T22:12:01.239Z,1567116721.239 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:02.278Z,1567116722.278 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:02.278Z,1567116722.278 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:02.279Z,1567116722.279 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:02.430Z,1567116722.430 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:02.794Z,1567116722.794 [Micromodem](INFO): Nmea in: $CADRQ,221203,1,9,0,64,1*4E

2019-08-29T22:12:02.795Z,1567116722.795 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:03.468Z,1567116723.468 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:03.469Z,1567116723.469 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:03.469Z,1567116723.469 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:03.609Z,1567116723.609 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:03.959Z,1567116723.959 [Micromodem](INFO): Nmea in: $CADRQ,221204,1,9,0,64,1*49

2019-08-29T22:12:03.960Z,1567116723.960 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:04.639Z,1567116724.639 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:04.640Z,1567116724.640 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:04.640Z,1567116724.640 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:04.798Z,1567116724.798 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:05.146Z,1567116725.146 [Micromodem](INFO): Nmea in: $CADRQ,221205,1,9,0,64,1*48

2019-08-29T22:12:05.147Z,1567116725.147 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:05.894Z,1567116725.894 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:05.894Z,1567116725.894 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:05.895Z,1567116725.895 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:06.080Z,1567116726.080 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:06.470Z,1567116726.470 [Micromodem](INFO): Nmea in: $CADRQ,221206,1,9,0,64,1*4B

2019-08-29T22:12:06.471Z,1567116726.471 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:07.248Z,1567116727.248 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:07.248Z,1567116727.248 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:07.253Z,1567116727.253 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:07.306Z,1567116727.306 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-08-29T22:12:07.414Z,1567116727.414 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:07.767Z,1567116727.767 [Micromodem](INFO): Nmea in: $CADRQ,221208,1,9,0,64,1*45

2019-08-29T22:12:07.767Z,1567116727.767 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:08.817Z,1567116728.817 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:08.817Z,1567116728.817 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:08.818Z,1567116728.818 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:09.001Z,1567116729.001 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:09.343Z,1567116729.343 [Micromodem](INFO): Nmea in: $CADRQ,221209,1,9,0,64,1*44

2019-08-29T22:12:09.343Z,1567116729.343 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:09.981Z,1567116729.981 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:09.982Z,1567116729.982 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:09.982Z,1567116729.982 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:10.254Z,1567116730.254 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:10.626Z,1567116730.626 [Micromodem](INFO): Nmea in: $CADRQ,221210,1,9,0,64,1*4C

2019-08-29T22:12:10.627Z,1567116730.627 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:11.328Z,1567116731.328 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:11.329Z,1567116731.329 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:11.329Z,1567116731.329 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:11.501Z,1567116731.501 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:11.858Z,1567116731.858 [Micromodem](INFO): Nmea in: $CADRQ,221212,1,9,0,64,1*4E

2019-08-29T22:12:11.859Z,1567116731.859 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:12.557Z,1567116732.557 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:12.558Z,1567116732.558 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:12.558Z,1567116732.558 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:12.742Z,1567116732.742 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:13.091Z,1567116733.091 [Micromodem](INFO): Nmea in: $CADRQ,221213,1,9,0,64,1*4F

2019-08-29T22:12:13.091Z,1567116733.091 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:13.775Z,1567116733.775 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:13.775Z,1567116733.775 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:13.776Z,1567116733.776 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:13.918Z,1567116733.918 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:14.298Z,1567116734.298 [Micromodem](INFO): Nmea in: $CADRQ,221214,1,9,0,64,1*48

2019-08-29T22:12:14.299Z,1567116734.299 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:16.219Z,1567116736.219 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:16.219Z,1567116736.219 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:16.219Z,1567116736.219 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:16.223Z,1567116736.223 [NAL9602](INFO): Not Powering down - fast GPS
2019-08-29T22:12:16.386Z,1567116736.386 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:16.738Z,1567116736.738 [Micromodem](INFO): Nmea in: $CADRQ,221217,1,9,0,64,1*4B

2019-08-29T22:12:16.739Z,1567116736.739 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:17.405Z,1567116737.405 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:17.405Z,1567116737.405 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:17.405Z,1567116737.405 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:17.538Z,1567116737.538 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:17.890Z,1567116737.890 [Micromodem](INFO): Nmea in: $CADRQ,221218,1,9,0,64,1*44

2019-08-29T22:12:17.891Z,1567116737.891 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:18.558Z,1567116738.558 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:18.559Z,1567116738.559 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:18.559Z,1567116738.559 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:18.676Z,1567116738.676 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:19.039Z,1567116739.039 [Micromodem](INFO): Nmea in: $CADRQ,221219,1,9,0,64,1*45

2019-08-29T22:12:19.039Z,1567116739.039 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:19.679Z,1567116739.679 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:19.679Z,1567116739.679 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:19.680Z,1567116739.680 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:19.818Z,1567116739.818 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:20.162Z,1567116740.162 [Micromodem](INFO): Nmea in: $CADRQ,221220,1,9,0,64,1*4F

2019-08-29T22:12:20.163Z,1567116740.163 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:20.846Z,1567116740.846 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:20.847Z,1567116740.847 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:20.847Z,1567116740.847 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:20.990Z,1567116740.990 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:21.342Z,1567116741.342 [Micromodem](INFO): Nmea in: $CADRQ,221221,1,9,0,64,1*4E

2019-08-29T22:12:21.343Z,1567116741.343 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:21.987Z,1567116741.987 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:21.988Z,1567116741.988 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:21.988Z,1567116741.988 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:22.118Z,1567116742.118 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:22.476Z,1567116742.476 [Micromodem](INFO): Nmea in: $CADRQ,221222,1,9,0,64,1*4D

2019-08-29T22:12:22.477Z,1567116742.477 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:23.120Z,1567116743.120 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:23.120Z,1567116743.120 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:23.121Z,1567116743.121 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:23.254Z,1567116743.254 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:23.598Z,1567116743.598 [Micromodem](INFO): Nmea in: $CADRQ,221224,1,9,0,64,1*4B

2019-08-29T22:12:23.599Z,1567116743.599 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:24.343Z,1567116744.343 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:24.343Z,1567116744.343 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:24.343Z,1567116744.343 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:24.506Z,1567116744.506 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:24.850Z,1567116744.850 [Micromodem](INFO): Nmea in: $CADRQ,221225,1,9,0,64,1*4A

2019-08-29T22:12:24.851Z,1567116744.851 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:25.562Z,1567116745.562 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:25.562Z,1567116745.562 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:25.563Z,1567116745.563 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:25.779Z,1567116745.779 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:26.178Z,1567116746.178 [Micromodem](INFO): Nmea in: $CADRQ,221226,1,9,0,64,1*49

2019-08-29T22:12:26.179Z,1567116746.179 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:26.809Z,1567116746.809 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:26.809Z,1567116746.809 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:26.810Z,1567116746.810 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:26.975Z,1567116746.975 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:27.334Z,1567116747.334 [Micromodem](INFO): Nmea in: $CADRQ,221227,1,9,0,64,1*48

2019-08-29T22:12:27.335Z,1567116747.335 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:27.989Z,1567116747.989 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:27.991Z,1567116747.991 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:27.991Z,1567116747.991 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:28.151Z,1567116748.151 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:28.490Z,1567116748.490 [Micromodem](INFO): Nmea in: $CADRQ,221228,1,9,0,64,1*47

2019-08-29T22:12:28.491Z,1567116748.491 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:29.168Z,1567116749.168 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:29.168Z,1567116749.168 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:29.169Z,1567116749.169 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:29.308Z,1567116749.308 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:29.650Z,1567116749.650 [Micromodem](INFO): Nmea in: $CADRQ,221230,1,9,0,64,1*4E

2019-08-29T22:12:29.651Z,1567116749.651 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:31.054Z,1567116751.054 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:31.054Z,1567116751.054 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:31.055Z,1567116751.055 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:31.174Z,1567116751.174 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:31.532Z,1567116751.532 [Micromodem](INFO): Nmea in: $CADRQ,221231,1,9,0,64,1*4F

2019-08-29T22:12:31.533Z,1567116751.533 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:32.153Z,1567116752.153 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:32.154Z,1567116752.154 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:32.154Z,1567116752.154 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:32.284Z,1567116752.284 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:32.630Z,1567116752.630 [Micromodem](INFO): Nmea in: $CADRQ,221233,1,9,0,64,1*4D

2019-08-29T22:12:32.631Z,1567116752.631 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:33.262Z,1567116753.262 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:33.262Z,1567116753.262 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:33.263Z,1567116753.263 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:33.446Z,1567116753.446 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:33.793Z,1567116753.793 [Micromodem](INFO): Nmea in: $CADRQ,221234,1,9,0,64,1*4A

2019-08-29T22:12:33.794Z,1567116753.794 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:34.801Z,1567116754.801 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:34.802Z,1567116754.802 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:34.802Z,1567116754.802 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:34.827Z,1567116754.827 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2019-08-29T22:12:34.914Z,1567116754.914 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:35.306Z,1567116755.306 [Micromodem](INFO): Nmea in: $CADRQ,221235,1,9,0,64,1*4B

2019-08-29T22:12:35.307Z,1567116755.307 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:35.946Z,1567116755.946 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:35.947Z,1567116755.947 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:35.947Z,1567116755.947 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:36.110Z,1567116756.110 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:36.466Z,1567116756.466 [Micromodem](INFO): Nmea in: $CADRQ,221236,1,9,0,64,1*48

2019-08-29T22:12:36.467Z,1567116756.467 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:37.162Z,1567116757.162 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:37.163Z,1567116757.163 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:37.163Z,1567116757.163 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:37.298Z,1567116757.298 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:37.641Z,1567116757.641 [Micromodem](INFO): Nmea in: $CADRQ,221238,1,9,0,64,1*46

2019-08-29T22:12:37.642Z,1567116757.642 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:38.299Z,1567116758.299 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:38.299Z,1567116758.299 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:38.300Z,1567116758.300 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:38.431Z,1567116758.431 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:38.783Z,1567116758.783 [Micromodem](INFO): Nmea in: $CADRQ,221239,1,9,0,64,1*47

2019-08-29T22:12:38.783Z,1567116758.783 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:39.437Z,1567116759.437 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:39.438Z,1567116759.438 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:39.438Z,1567116759.438 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:39.722Z,1567116759.722 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:40.075Z,1567116760.075 [Micromodem](INFO): Nmea in: $CADRQ,221240,1,9,0,64,1*49

2019-08-29T22:12:40.076Z,1567116760.076 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:40.706Z,1567116760.706 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:40.707Z,1567116760.707 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:40.707Z,1567116760.707 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:40.903Z,1567116760.903 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:41.259Z,1567116761.259 [Micromodem](INFO): Nmea in: $CADRQ,221241,1,9,0,64,1*48

2019-08-29T22:12:41.259Z,1567116761.259 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:41.949Z,1567116761.949 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:41.949Z,1567116761.949 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:41.950Z,1567116761.950 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:42.094Z,1567116762.094 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:42.442Z,1567116762.442 [Micromodem](INFO): Nmea in: $CADRQ,221242,1,9,0,64,1*4B

2019-08-29T22:12:42.443Z,1567116762.443 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:43.084Z,1567116763.084 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:12:43.084Z,1567116763.084 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:12:43.084Z,1567116763.084 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:12:43.133Z,1567116763.133 [Startup:StartupSatComms:B](INFO): Timed out from 2019-08-29T22:11:42.9Z
2019-08-29T22:12:43.133Z,1567116763.133 [Startup:StartupSatComms:B] Stopped
2019-08-29T22:12:43.133Z,1567116763.133 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-08-29T22:12:43.133Z,1567116763.133 [Startup:StartupSatComms] Stopped
2019-08-29T22:12:43.134Z,1567116763.134 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-08-29T22:12:43.134Z,1567116763.134 [Startup](INFO): Completed Startup
2019-08-29T22:12:43.134Z,1567116763.134 [MissionManager](INFO): Startup is completed.
2019-08-29T22:12:43.135Z,1567116763.135 [MissionManager](INFO): Uninitializing Mission Startup
2019-08-29T22:12:43.135Z,1567116763.135 [Startup] Stopped
2019-08-29T22:12:43.135Z,1567116763.135 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-08-29T22:12:43.135Z,1567116763.135 [Startup:A.GoToSurface] Stopped
2019-08-29T22:12:43.135Z,1567116763.135 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-08-29T22:12:43.226Z,1567116763.226 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:12:43.228Z,1567116763.227 [Micromodem](INFO): Nmea in: $CADRQ,221243,1,9,0,64,1*4A

2019-08-29T22:12:43.228Z,1567116763.228 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:12:43.261Z,1567116763.261 [MissionManager](IMPORTANT): Started mission Default
2019-08-29T22:12:43.261Z,1567116763.261 [Default] Running Loop=1
2019-08-29T22:12:43.262Z,1567116763.262 [Default](DEBUG): Aggregate::initialize Default
2019-08-29T22:12:43.262Z,1567116763.262 [Default:B.GoToSurface] Running Loop=1
2019-08-29T22:12:43.262Z,1567116763.262 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-08-29T22:12:43.262Z,1567116763.262 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-08-29T22:12:43.262Z,1567116763.262 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-08-29T22:12:43.263Z,1567116763.263 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-08-29T22:12:43.263Z,1567116763.263 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-08-29T22:12:43.263Z,1567116763.263 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-08-29T22:12:43.263Z,1567116763.263 [Default:A.Wait] Running Loop=1
2019-08-29T22:12:43.263Z,1567116763.263 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-08-29T22:12:56.580Z,1567116776.580 [Default:A.Wait](INFO): Done Waiting.
2019-08-29T22:12:56.580Z,1567116776.580 [Default:A.Wait] Stopped
2019-08-29T22:12:56.580Z,1567116776.580 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-08-29T22:12:57.013Z,1567116777.013 [Default:CheckIn] Running Loop=1
2019-08-29T22:12:57.013Z,1567116777.013 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-29T22:12:57.013Z,1567116777.013 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-29T22:12:57.395Z,1567116777.395 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-08-29T22:12:58.577Z,1567116778.577 [NAL9602](DEBUG): Fix Requested
2019-08-29T22:12:58.978Z,1567116778.978 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221216.00,A,3423.36819,N,11950.59338,W,0.350,67.01,290819,,,A*4E
2019-08-29T22:12:58.984Z,1567116778.984 [NAL9602](INFO): GPS fix at 20190829T221216: (34.389470, -119.843223)
2019-08-29T22:12:59.022Z,1567116779.022 [Default:CheckIn:Read_GPS] Stopped
2019-08-29T22:12:59.022Z,1567116779.022 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-29T22:12:59.461Z,1567116779.461 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-08-29T22:13:00.607Z,1567116780.607 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:13:00.608Z,1567116780.608 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:13:00.608Z,1567116780.608 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:13:00.794Z,1567116780.794 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:13:01.174Z,1567116781.174 [Micromodem](INFO): Nmea in: $CADRQ,221301,1,9,0,64,1*4D

2019-08-29T22:13:01.175Z,1567116781.175 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:13:01.865Z,1567116781.865 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:13:01.866Z,1567116781.866 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:13:01.866Z,1567116781.866 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:13:02.014Z,1567116782.014 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:13:02.357Z,1567116782.357 [Micromodem](INFO): Nmea in: $CADRQ,221302,1,9,0,64,1*4E

2019-08-29T22:13:02.358Z,1567116782.358 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:13:04.610Z,1567116784.610 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:13:04.610Z,1567116784.610 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:13:04.610Z,1567116784.610 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:13:04.745Z,1567116784.745 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:13:05.143Z,1567116785.143 [Micromodem](INFO): Nmea in: $CADRQ,221305,1,9,0,64,1*49

2019-08-29T22:13:05.143Z,1567116785.143 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:13:06.257Z,1567116786.257 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:13:06.258Z,1567116786.258 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:13:06.258Z,1567116786.258 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:13:06.486Z,1567116786.486 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:13:06.833Z,1567116786.833 [Micromodem](INFO): Nmea in: $CADRQ,221307,1,9,0,64,1*4B

2019-08-29T22:13:06.833Z,1567116786.833 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:13:07.503Z,1567116787.503 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:13:07.504Z,1567116787.504 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:13:07.504Z,1567116787.504 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:13:07.842Z,1567116787.842 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:13:08.198Z,1567116788.198 [Micromodem](INFO): Nmea in: $CADRQ,221308,1,9,0,64,1*44

2019-08-29T22:13:08.199Z,1567116788.199 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:13:09.462Z,1567116789.462 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:13:09.463Z,1567116789.463 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:13:09.463Z,1567116789.463 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:13:09.690Z,1567116789.690 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:13:10.069Z,1567116790.069 [Micromodem](INFO): Nmea in: $CADRQ,221310,1,9,0,64,1*4D

2019-08-29T22:13:10.069Z,1567116790.069 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:13:11.433Z,1567116791.433 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:13:11.434Z,1567116791.434 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:13:11.434Z,1567116791.434 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:13:11.637Z,1567116791.637 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:13:12.014Z,1567116792.014 [Micromodem](INFO): Nmea in: $CADRQ,221312,1,9,0,64,1*4F

2019-08-29T22:13:12.015Z,1567116792.015 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:13:13.154Z,1567116793.154 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:13:13.154Z,1567116793.154 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:13:13.155Z,1567116793.155 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:13:13.278Z,1567116793.278 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:13:13.678Z,1567116793.678 [Micromodem](INFO): Nmea in: $CADRQ,221314,1,9,0,64,1*49

2019-08-29T22:13:13.679Z,1567116793.679 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:13:14.234Z,1567116794.234 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004487
2019-08-29T22:13:14.383Z,1567116794.383 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:13:14.384Z,1567116794.384 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:13:14.384Z,1567116794.384 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:13:14.396Z,1567116794.396 [NAL9602](INFO): SBD MO Status=0, MOMSN=8927, MT Status=0, MTMSN=0
2019-08-29T22:13:14.396Z,1567116794.396 [NAL9602](INFO): No messages in MT queue
2019-08-29T22:13:14.530Z,1567116794.530 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:13:14.906Z,1567116794.906 [Micromodem](INFO): Nmea in: $CADRQ,221315,1,9,0,64,1*48

2019-08-29T22:13:14.907Z,1567116794.907 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:13:15.948Z,1567116795.948 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:13:15.948Z,1567116795.948 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:13:15.949Z,1567116795.949 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:13:16.093Z,1567116796.093 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:13:16.482Z,1567116796.482 [Micromodem](INFO): Nmea in: $CADRQ,221316,1,9,0,64,1*4B

2019-08-29T22:13:16.483Z,1567116796.483 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:13:17.906Z,1567116797.906 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:13:17.907Z,1567116797.907 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:13:17.907Z,1567116797.907 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:13:18.125Z,1567116798.125 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:13:18.490Z,1567116798.490 [Micromodem](INFO): Nmea in: $CADRQ,221318,1,9,0,64,1*45

2019-08-29T22:13:18.491Z,1567116798.491 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:13:19.787Z,1567116799.787 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:13:19.787Z,1567116799.787 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:13:19.788Z,1567116799.788 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:13:19.816Z,1567116799.816 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2019-08-29T22:13:19.939Z,1567116799.939 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:13:20.278Z,1567116800.278 [Micromodem](INFO): Nmea in: $CADRQ,221320,1,9,0,64,1*4E

2019-08-29T22:13:20.279Z,1567116800.279 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:13:22.095Z,1567116802.095 [Micromodem](INFO): Using rate 0 for 26 bytes.
2019-08-29T22:13:22.096Z,1567116802.096 [Micromodem](INFO): Outgoing frame #1, 26 bytes: 26EE675DAB0000005D0000040000000000000000000000000000
2019-08-29T22:13:22.096Z,1567116802.096 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,1*51

2019-08-29T22:13:22.111Z,1567116802.111 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190829T152422/Courier0171.lzma
2019-08-29T22:13:22.321Z,1567116802.321 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,1*53

2019-08-29T22:13:22.678Z,1567116802.678 [Micromodem](INFO): Nmea in: $CADRQ,221322,1,9,0,64,1*4C

2019-08-29T22:13:22.679Z,1567116802.679 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32
2019-08-29T22:13:22.915Z,1567116802.915 [DataOverHttps](INFO): Moved sent file to Logs/20190829T152422/Courier0171.lzma.bak
2019-08-29T22:13:22.915Z,1567116802.915 [DataOverHttps](INFO): SBD MOMSN=11714361
2019-08-29T22:13:23.469Z,1567116803.469 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 184D685D000001005D00000400540200000000000000151D80030C5669BF31555EAE1409A708CE248A69041B345E92F8B20B7420A5EA58D066A72552190D94DE
2019-08-29T22:13:23.469Z,1567116803.469 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 3340D4F7314800B3CEEB35DDAB86D41960BEDF8972ED848965EC9D0D2EEC25B8F4924AB5CB89B42E39E26EB2C9B6F7300996FEA801D8D79DD6C2131CCC5C9819
2019-08-29T22:13:23.470Z,1567116803.470 [Micromodem](INFO): Outgoing frame #3, 64 bytes: B96481E02D08AFEB4E8375355F57D15F843E216C5AF6A6B6022E25671BA0F8CD19E9A20349DD41175E989FD0DA5C8A024AB2982110EB5801B66F8CACE8A929D2
2019-08-29T22:13:23.470Z,1567116803.470 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,3*53

2019-08-29T22:13:23.804Z,1567116803.804 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,3*51

2019-08-29T22:13:24.198Z,1567116804.198 [Micromodem](INFO): Nmea in: $CADRQ,221324,1,9,0,64,1*4A

2019-08-29T22:13:24.199Z,1567116804.199 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,184D685D000001005D00000400540200000000000000151D80030C5669BF31555EAE1409A708CE248A69041B345E92F8B20B7420A5EA58D066A72552190D94DE*0A

2019-08-29T22:13:24.602Z,1567116804.602 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71

2019-08-29T22:13:25.018Z,1567116805.018 [Micromodem](INFO): Nmea in: $CADRQ,221325,1,9,0,64,2*48

2019-08-29T22:13:25.019Z,1567116805.019 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,3340D4F7314800B3CEEB35DDAB86D41960BEDF8972ED848965EC9D0D2EEC25B8F4924AB5CB89B42E39E26EB2C9B6F7300996FEA801D8D79DD6C2131CCC5C9819*09

2019-08-29T22:13:25.414Z,1567116805.414 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71

2019-08-29T22:13:25.818Z,1567116805.818 [Micromodem](INFO): Nmea in: $CADRQ,221325,1,9,0,64,3*49

2019-08-29T22:13:25.819Z,1567116805.819 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,B96481E02D08AFEB4E8375355F57D15F843E216C5AF6A6B6022E25671BA0F8CD19E9A20349DD41175E989FD0DA5C8A024AB2982110EB5801B66F8CACE8A929D2*70

2019-08-29T22:13:26.218Z,1567116806.218 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71

2019-08-29T22:13:26.622Z,1567116806.622 [Micromodem](INFO): Nmea in: $CATXP,192*48

2019-08-29T22:13:30.266Z,1567116810.266 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2019-08-29T22:13:30.668Z,1567116810.668 [Micromodem](INFO): Nmea in: $CAXST,6,20190829,221326.834176,3,0,200,4000,10000,1,1,9,1,3,3,3,192*40

2019-08-29T22:13:35.107Z,1567116815.107 [Micromodem](INFO): Nmea in: $CAACK,9,1,1,1*43

2019-08-29T22:13:35.511Z,1567116815.511 [Micromodem](INFO): Nmea in: $CAACK,9,1,2,1*40

2019-08-29T22:13:35.921Z,1567116815.921 [Micromodem](INFO): Nmea in: $CAACK,9,1,3,1*41

2019-08-29T22:13:35.923Z,1567116815.923 [Micromodem](INFO): Sent 184 bytes from file Logs/20190829T220928/Courier0000.lzma.parts
2019-08-29T22:13:35.923Z,1567116815.923 [Micromodem](INFO): Packets left to send: 1
2019-08-29T22:13:36.073Z,1567116816.073 [DataOverHttps](INFO): Sending 298 bytes from file Logs/20190829T220928/Courier0000.lzma
2019-08-29T22:13:36.322Z,1567116816.322 [Micromodem](INFO): Nmea in: $CACST,6,0,20190829221334.803609,06,10434,40,0251,0150,07,00,00,00,02,1,009,001,0,4,1,0,150,13.4,3.00,10,0.00,-01,-0.05,48,10000,4000*60

2019-08-29T22:13:36.878Z,1567116816.878 [DataOverHttps](INFO): Moved sent file to Logs/20190829T220928/Courier0000.lzma.bak
2019-08-29T22:13:36.878Z,1567116816.878 [DataOverHttps](INFO): SBD MOMSN=11714363
2019-08-29T22:13:37.775Z,1567116817.775 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 184D685D040000005D00000400370000000000000000151D80030C5669C08C35ED3E027E1AA734E2A8D4A7C29846DEEC766E58EB7DAB30979DB27B6FE4E21EB4
2019-08-29T22:13:37.776Z,1567116817.776 [Micromodem](INFO): Outgoing frame #2, 5 bytes: D27DAB6800
2019-08-29T22:13:37.776Z,1567116817.776 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,2*52

2019-08-29T22:13:38.620Z,1567116818.620 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,2*50

2019-08-29T22:13:39.036Z,1567116819.036 [Micromodem](INFO): Nmea in: $CADRQ,221338,1,9,0,64,1*47

2019-08-29T22:13:39.037Z,1567116819.037 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,184D685D040000005D00000400370000000000000000151D80030C5669C08C35ED3E027E1AA734E2A8D4A7C29846DEEC766E58EB7DAB30979DB27B6FE4E21EB4*7B

2019-08-29T22:13:39.422Z,1567116819.422 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71

2019-08-29T22:13:39.826Z,1567116819.826 [Micromodem](INFO): Nmea in: $CADRQ,221339,1,9,0,64,2*45

2019-08-29T22:13:39.827Z,1567116819.827 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,D27DAB6800*79

2019-08-29T22:13:40.230Z,1567116820.230 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,5*46

2019-08-29T22:13:40.634Z,1567116820.634 [Micromodem](INFO): Nmea in: $CATXP,69*7D

2019-08-29T22:13:43.058Z,1567116823.058 [Micromodem](INFO): Nmea in: $CATXF,69*6B

2019-08-29T22:13:43.463Z,1567116823.463 [Micromodem](INFO): Nmea in: $CAXST,6,20190829,221340.760429,3,0,200,4000,10000,1,1,9,1,2,2,3,69*74

2019-08-29T22:13:44.674Z,1567116824.674 [NAL9602](INFO): Not Powering down - fast GPS
2019-08-29T22:13:47.099Z,1567116827.099 [Micromodem](INFO): Nmea in: $CAACK,9,1,1,1*43

2019-08-29T22:13:47.503Z,1567116827.503 [Micromodem](INFO): Nmea in: $CAACK,9,1,2,1*40

2019-08-29T22:13:47.503Z,1567116827.503 [Micromodem](INFO): Sent 61 bytes from file Logs/20190829T220928/Courier0004.lzma.parts
2019-08-29T22:13:47.503Z,1567116827.503 [Micromodem](INFO): Packets left to send: 0
2019-08-29T22:13:47.909Z,1567116827.909 [Micromodem](INFO): Nmea in: $CACST,6,0,20190829221346.868609,06,12006,40,0299,0150,07,00,00,00,02,1,009,001,0,4,1,0,150,10.4,3.00,09,0.00,-01,-0.12,49,10000,4000*67

2019-08-29T22:13:49.314Z,1567116829.314 [Default:CheckIn:Read_Iridium] Stopped
2019-08-29T22:13:49.314Z,1567116829.314 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-29T22:13:49.314Z,1567116829.314 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-29T22:13:50.319Z,1567116830.319 [DataOverHttps](IMPORTANT): Could not stat file Logs/20190829T220928/Courier0004.lzma
2019-08-29T22:13:50.319Z,1567116830.319 [DataOverHttps](CRITICAL): Could not open file Logs/20190829T220928/Courier0004.lzma
2019-08-29T22:13:50.621Z,1567116830.621 [CBIT](INFO): Critical error at 20190829T221350
2019-08-29T22:13:56.476Z,1567116836.476 [DataOverHttps](IMPORTANT): Could not stat file Logs/20190829T220928/Courier0004.lzma
2019-08-29T22:13:56.477Z,1567116836.477 [DataOverHttps](CRITICAL): Could not open file Logs/20190829T220928/Courier0004.lzma
2019-08-29T22:13:56.675Z,1567116836.675 [CBIT](INFO): Critical error at 20190829T221356
2019-08-29T22:14:31.779Z,1567116871.779 [DataOverHttps](IMPORTANT): Could not stat file Logs/20190829T220928/Courier0004.lzma
2019-08-29T22:14:31.779Z,1567116871.779 [DataOverHttps](CRITICAL): Could not open file Logs/20190829T220928/Courier0004.lzma
2019-08-29T22:14:31.927Z,1567116871.927 [CBIT](INFO): Critical error at 20190829T221431
2019-08-29T22:14:32.586Z,1567116872.586 [DataOverHttps](IMPORTANT): SBD MTMSN=20190829T221350
2019-08-29T22:14:38.267Z,1567116878.267 [DataOverHttps](IMPORTANT): Could not stat file Logs/20190829T220928/Courier0004.lzma
2019-08-29T22:14:38.267Z,1567116878.267 [DataOverHttps](CRITICAL): Could not open file Logs/20190829T220928/Courier0004.lzma
2019-08-29T22:14:38.269Z,1567116878.269 [DataOverHttps](INFO): Received command:restart sys
2019-08-29T22:14:38.325Z,1567116878.325 [CommandLine](IMPORTANT): got command restart system
2019-08-29T22:14:38.371Z,1567116878.371 [CBIT](INFO): Critical error at 20190829T221438
2019-08-29T22:14:40.385Z,1567116880.385 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-29T22:14:40.385Z,1567116880.385 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-08-29T22:14:40.513Z,1567116880.513 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-08-29T22:14:40.513Z,1567116880.513 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-08-29T22:14:40.513Z,1567116880.513 [CommandLine](INFO): Join timeout helper Thread ID is 930
2019-08-29T22:14:40.514Z,1567116880.514 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-08-29T22:14:40.514Z,1567116880.514 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-08-29T22:14:40.515Z,1567116880.515 [NavChartDb](INFO): Join timeout helper Thread ID is 931
2019-08-29T22:14:40.801Z,1567116880.801 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-29T22:14:40.801Z,1567116880.801 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-08-29T22:14:40.817Z,1567116880.817 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-08-29T22:14:40.818Z,1567116880.818 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-08-29T22:14:40.819Z,1567116880.819 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 932
2019-08-29T22:14:40.925Z,1567116880.925 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-29T22:14:40.925Z,1567116880.925 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-08-29T22:14:40.926Z,1567116880.926 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-08-29T22:14:40.937Z,1567116880.937 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-08-29T22:14:40.937Z,1567116880.937 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-08-29T22:14:40.937Z,1567116880.937 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 933
2019-08-29T22:14:41.117Z,1567116881.117 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-29T22:14:41.117Z,1567116881.117 [CTD_NeilBrown](INFO): Powering down
2019-08-29T22:14:41.129Z,1567116881.129 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-08-29T22:14:41.137Z,1567116881.137 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-08-29T22:14:41.137Z,1567116881.137 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-08-29T22:14:41.138Z,1567116881.138 [Radio_Surface](INFO): Join timeout helper Thread ID is 934
2019-08-29T22:14:41.301Z,1567116881.301 [Radio_Surface](INFO): Powering down
2019-08-29T22:14:41.302Z,1567116881.302 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-29T22:14:41.302Z,1567116881.302 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-08-29T22:14:41.322Z,1567116881.322 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-08-29T22:14:41.322Z,1567116881.322 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-08-29T22:14:41.322Z,1567116881.322 [DataOverHttps](INFO): Join timeout helper Thread ID is 935
2019-08-29T22:14:41.497Z,1567116881.497 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-29T22:14:41.497Z,1567116881.497 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-08-29T22:14:41.509Z,1567116881.509 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-08-29T22:14:41.509Z,1567116881.509 [logger ThreadHandler](INFO): Thread cancelled.
2019-08-29T22:14:41.509Z,1567116881.509 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-29T22:14:41.509Z,1567116881.509 [logger ThreadHandler](INFO): Thread cancelled.
2019-08-29T22:14:41.510Z,1567116881.510 [logger](INFO): Join timeout helper Thread ID is 936
2019-08-29T22:14:41.511Z,1567116881.511 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-08-29T22:14:41.511Z,1567116881.511 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-08-29T22:14:41.511Z,1567116881.511 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-08-29T22:14:41.511Z,1567116881.511 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-08-29T22:14:41.512Z,1567116881.512 [controlThread](INFO): Join timeout helper Thread ID is 937
2019-08-29T22:14:41.545Z,1567116881.545 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-29T22:14:41.545Z,1567116881.545 [controlThread](DEBUG): Uninitializing ControlThread
2019-08-29T22:14:41.545Z,1567116881.545 [AHRS_M2](INFO): Powering down
2019-08-29T22:14:41.617Z,1567116881.617 [Micromodem](INFO): Powering down
2019-08-29T22:14:41.713Z,1567116881.713 [NAL9602](INFO): Powering down
2019-08-29T22:14:41.785Z,1567116881.785 [RDI_Pathfinder](INFO): Powering down
2019-08-29T22:14:41.787Z,1567116881.787 [Aanderaa_O2](INFO): Powering down
2019-08-29T22:14:41.788Z,1567116881.788 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-08-29T22:14:41.789Z,1567116881.789 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-08-29T22:14:41.790Z,1567116881.790 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-08-29T22:14:41.790Z,1567116881.790 [MissionManager](INFO): Uninitializing Mission Default
2019-08-29T22:14:41.790Z,1567116881.790 [Default] Stopped
2019-08-29T22:14:41.790Z,1567116881.790 [Default](DEBUG): Aggregate::uninitialize Default
2019-08-29T22:14:41.790Z,1567116881.790 [Default:B.GoToSurface] Stopped
2019-08-29T22:14:41.790Z,1567116881.790 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-08-29T22:14:41.791Z,1567116881.791 [Default:CheckIn] Stopped
2019-08-29T22:14:41.791Z,1567116881.791 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-29T22:14:41.791Z,1567116881.791 [Default:CheckIn:C.Wait] Stopped
2019-08-29T22:14:41.791Z,1567116881.791 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-29T22:14:41.793Z,1567116881.793 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-08-29T22:14:41.794Z,1567116881.794 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-08-29T22:14:41.794Z,1567116881.794 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-08-29T22:14:41.794Z,1567116881.794 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-08-29T22:14:41.794Z,1567116881.794 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-08-29T22:14:41.795Z,1567116881.795 [BuoyancyServo](INFO): Powering down
2019-08-29T22:14:41.809Z,1567116881.809 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-08-29T22:14:41.809Z,1567116881.809 [ElevatorServo](INFO): Powering down
2019-08-29T22:14:41.810Z,1567116881.810 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-08-29T22:14:41.810Z,1567116881.810 [MassServo](INFO): Powering down
2019-08-29T22:14:41.811Z,1567116881.811 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-08-29T22:14:41.811Z,1567116881.811 [RudderServo](INFO): Powering down
2019-08-29T22:14:41.812Z,1567116881.812 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-08-29T22:14:41.812Z,1567116881.812 [ThrusterServo](INFO): Powering down
2019-08-29T22:14:41.813Z,1567116881.813 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-08-29T22:14:41.813Z,1567116881.813 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-08-29T22:14:41.814Z,1567116881.814 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-08-29T22:14:41.814Z,1567116881.814 [CBIT](DEBUG): Powering off loads.
2019-08-29T22:14:41.825Z,1567116881.825 [CBIT](DEBUG): Disabling WDT.
2019-08-29T22:14:41.837Z,1567116881.837 [CBIT](DEBUG): Opening all GF detection circuits.
2019-08-29T22:14:41.837Z,1567116881.837 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-08-29T22:14:41.889Z,1567116881.889 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-08-29T22:14:41.898Z,1567116881.898 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-08-29T22:14:41.943Z,1567116881.943 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-08-29T22:14:41.946Z,1567116881.946 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-08-29T22:14:42.009Z,1567116882.009 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-08-29T22:14:42.082Z,1567116882.082 [logger ThreadHandler](INFO): Thread cancelled.