2020-01-09T00:02:30.379Z,1578528150.379 [Supervisor](DEBUG): Initializing supervisor.
2020-01-09T00:02:30.382Z,1578528150.382 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-01-09T00:02:30.383Z,1578528150.383 [SyncHandler](INFO): Protected caller Thread ID is 1436
2020-01-09T00:02:30.383Z,1578528150.383 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-01-09T00:02:30.384Z,1578528150.384 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-01-09T00:02:30.385Z,1578528150.385 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1437
2020-01-09T00:02:30.389Z,1578528150.389 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-01-09T00:02:30.401Z,1578528150.401 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-01-09T00:02:30.402Z,1578528150.402 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-01-09T00:02:30.402Z,1578528150.402 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1438
2020-01-09T00:02:30.403Z,1578528150.403 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-01-09T00:02:30.404Z,1578528150.404 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-01-09T00:02:30.404Z,1578528150.404 [logger ThreadHandler](INFO): Protected caller Thread ID is 1439
2020-01-09T00:02:30.406Z,1578528150.406 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-01-09T00:02:30.407Z,1578528150.407 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-01-09T00:02:30.408Z,1578528150.408 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-01-09T00:02:30.827Z,1578528150.827 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-01-09T00:02:30.827Z,1578528150.827 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-01-09T00:02:30.921Z,1578528150.921 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-01-09T00:02:30.922Z,1578528150.922 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-01-09T00:02:31.234Z,1578528151.234 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-01-09T00:02:31.234Z,1578528151.234 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-01-09T00:02:31.379Z,1578528151.379 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-01-09T00:02:31.380Z,1578528151.380 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-01-09T00:02:31.677Z,1578528151.677 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-01-09T00:02:31.677Z,1578528151.677 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-01-09T00:02:32.110Z,1578528152.110 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-01-09T00:02:32.111Z,1578528152.111 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-01-09T00:02:32.311Z,1578528152.311 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-01-09T00:02:32.311Z,1578528152.311 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-01-09T00:02:32.451Z,1578528152.451 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-01-09T00:02:32.452Z,1578528152.452 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-01-09T00:02:32.633Z,1578528152.633 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-01-09T00:02:32.634Z,1578528152.634 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-01-09T00:02:32.727Z,1578528152.727 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-01-09T00:02:32.727Z,1578528152.727 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-01-09T00:02:33.016Z,1578528153.016 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-01-09T00:02:33.016Z,1578528153.016 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-01-09T00:02:33.095Z,1578528153.095 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-01-09T00:02:33.195Z,1578528153.195 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-01-09T00:02:33.196Z,1578528153.196 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-01-09T00:02:33.812Z,1578528153.812 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-01-09T00:02:33.812Z,1578528153.812 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-01-09T00:02:34.210Z,1578528154.210 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-01-09T00:02:34.212Z,1578528154.212 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-01-09T00:02:34.213Z,1578528154.213 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-01-09T00:02:34.410Z,1578528154.410 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-01-09T00:02:34.506Z,1578528154.506 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-01-09T00:02:34.600Z,1578528154.600 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-01-09T00:02:34.824Z,1578528154.824 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-01-09T00:02:34.825Z,1578528154.825 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-01-09T00:02:34.907Z,1578528154.907 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-01-09T00:02:34.998Z,1578528154.998 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-01-09T00:02:35.092Z,1578528155.092 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-01-09T00:02:35.173Z,1578528155.173 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-01-09T00:02:35.277Z,1578528155.277 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-01-09T00:02:35.452Z,1578528155.452 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-01-09T00:02:35.578Z,1578528155.578 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2020-01-09T00:02:35.579Z,1578528155.579 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-01-09T00:02:35.602Z,1578528155.602 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-01-09T00:02:35.920Z,1578528155.920 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-01-09T00:02:35.926Z,1578528155.926 [AHRS_M2](INFO): created writer for : platform_orientation
2020-01-09T00:02:35.928Z,1578528155.928 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-01-09T00:02:35.933Z,1578528155.933 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-01-09T00:02:35.933Z,1578528155.933 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-01-09T00:02:35.939Z,1578528155.939 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-01-09T00:02:35.939Z,1578528155.939 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-01-09T00:02:35.944Z,1578528155.944 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-01-09T00:02:36.015Z,1578528156.015 [AHRS_M2] Loaded
2020-01-09T00:02:36.015Z,1578528156.015 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-01-09T00:02:36.098Z,1578528156.098 [DataOverHttps] Loaded
2020-01-09T00:02:36.098Z,1578528156.098 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-01-09T00:02:36.099Z,1578528156.099 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407684E0
2020-01-09T00:02:36.099Z,1578528156.099 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1518
2020-01-09T00:02:36.123Z,1578528156.123 [DDM] Loaded
2020-01-09T00:02:36.123Z,1578528156.123 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-01-09T00:02:36.136Z,1578528156.136 [Depth_Keller] Loaded
2020-01-09T00:02:36.137Z,1578528156.137 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-01-09T00:02:36.141Z,1578528156.141 [DropWeight] Loaded
2020-01-09T00:02:36.142Z,1578528156.142 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-01-09T00:02:36.202Z,1578528156.202 [DUSBL_Hydroid] Loaded
2020-01-09T00:02:36.203Z,1578528156.203 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-01-09T00:02:36.250Z,1578528156.250 [Micromodem] Loaded
2020-01-09T00:02:36.250Z,1578528156.250 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-01-09T00:02:36.347Z,1578528156.347 [NAL9602] Loaded
2020-01-09T00:02:36.347Z,1578528156.347 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-01-09T00:02:36.384Z,1578528156.384 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2020-01-09T00:02:36.384Z,1578528156.384 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2020-01-09T00:02:36.385Z,1578528156.385 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2020-01-09T00:02:36.385Z,1578528156.385 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2020-01-09T00:02:36.386Z,1578528156.386 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2020-01-09T00:02:36.387Z,1578528156.387 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2020-01-09T00:02:36.387Z,1578528156.387 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2020-01-09T00:02:36.387Z,1578528156.387 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2020-01-09T00:02:36.388Z,1578528156.388 [Onboard] Loaded
2020-01-09T00:02:36.388Z,1578528156.388 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-01-09T00:02:36.394Z,1578528156.394 [PowerOnly] Loaded
2020-01-09T00:02:36.394Z,1578528156.394 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-01-09T00:02:36.400Z,1578528156.400 [Radio_Surface] Loaded
2020-01-09T00:02:36.401Z,1578528156.401 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-01-09T00:02:36.402Z,1578528156.402 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407984E0
2020-01-09T00:02:36.402Z,1578528156.402 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1519
2020-01-09T00:02:36.446Z,1578528156.446 [RDI_Pathfinder] Loaded
2020-01-09T00:02:36.447Z,1578528156.447 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-01-09T00:02:38.309Z,1578528158.309 [BPC1] Loaded
2020-01-09T00:02:38.310Z,1578528158.310 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-01-09T00:02:38.310Z,1578528158.310 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-01-09T00:02:38.311Z,1578528158.311 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-01-09T00:02:38.324Z,1578528158.324 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-01-09T00:02:38.324Z,1578528158.324 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-01-09T00:02:38.428Z,1578528158.428 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-01-09T00:02:38.428Z,1578528158.428 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-01-09T00:02:38.448Z,1578528158.448 [NavChart] Loaded
2020-01-09T00:02:38.448Z,1578528158.448 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-01-09T00:02:38.452Z,1578528158.452 [UniversalFixResidualReporter] Loaded
2020-01-09T00:02:38.452Z,1578528158.452 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-01-09T00:02:38.453Z,1578528158.453 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-01-09T00:02:38.453Z,1578528158.453 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-01-09T00:02:38.558Z,1578528158.558 [BuoyancyServo] Loaded
2020-01-09T00:02:38.559Z,1578528158.559 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-01-09T00:02:38.574Z,1578528158.574 [ElevatorServo] Loaded
2020-01-09T00:02:38.574Z,1578528158.574 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-01-09T00:02:38.588Z,1578528158.588 [MassServo] Loaded
2020-01-09T00:02:38.589Z,1578528158.589 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-01-09T00:02:38.604Z,1578528158.604 [RudderServo] Loaded
2020-01-09T00:02:38.604Z,1578528158.604 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-01-09T00:02:38.618Z,1578528158.618 [ThrusterServo] Loaded
2020-01-09T00:02:38.619Z,1578528158.619 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-01-09T00:02:38.619Z,1578528158.619 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-01-09T00:02:38.620Z,1578528158.620 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-01-09T00:02:38.874Z,1578528158.874 [CTD_NeilBrown] Loaded
2020-01-09T00:02:38.874Z,1578528158.874 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2020-01-09T00:02:38.875Z,1578528158.875 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408EC4E0
2020-01-09T00:02:38.876Z,1578528158.876 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1520
2020-01-09T00:02:38.890Z,1578528158.890 [PAR_Licor] Loaded
2020-01-09T00:02:38.890Z,1578528158.890 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-01-09T00:02:38.933Z,1578528158.933 [WetLabsSeaOWL_UV_A] Loaded
2020-01-09T00:02:38.934Z,1578528158.934 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-01-09T00:02:38.935Z,1578528158.935 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 4091C4E0
2020-01-09T00:02:38.935Z,1578528158.935 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1521
2020-01-09T00:02:38.935Z,1578528158.935 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-01-09T00:02:38.936Z,1578528158.936 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-01-09T00:02:39.242Z,1578528159.242 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-01-09T00:02:39.243Z,1578528159.243 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-01-09T00:02:39.282Z,1578528159.282 [DepthRateCalculator] Loaded
2020-01-09T00:02:39.282Z,1578528159.282 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-01-09T00:02:39.288Z,1578528159.288 [PitchRateCalculator] Loaded
2020-01-09T00:02:39.288Z,1578528159.288 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-01-09T00:02:39.301Z,1578528159.301 [SpeedCalculator] Loaded
2020-01-09T00:02:39.301Z,1578528159.301 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-01-09T00:02:39.322Z,1578528159.322 [TempGradientCalculator] Loaded
2020-01-09T00:02:39.322Z,1578528159.322 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-01-09T00:02:39.327Z,1578528159.327 [YawRateCalculator] Loaded
2020-01-09T00:02:39.328Z,1578528159.328 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-01-09T00:02:39.367Z,1578528159.367 [ElevatorOffsetCalculator] Loaded
2020-01-09T00:02:39.368Z,1578528159.368 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-01-09T00:02:39.368Z,1578528159.368 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-01-09T00:02:39.369Z,1578528159.369 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-01-09T00:02:39.502Z,1578528159.502 [SBIT](DEBUG): Construct Startup Built In Test.
2020-01-09T00:02:39.523Z,1578528159.523 [SBIT] Loaded
2020-01-09T00:02:39.524Z,1578528159.524 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-01-09T00:02:39.524Z,1578528159.524 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-01-09T00:02:39.537Z,1578528159.537 [IBIT] Loaded
2020-01-09T00:02:39.537Z,1578528159.537 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-01-09T00:02:39.540Z,1578528159.540 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-01-09T00:02:39.678Z,1578528159.678 [CBIT] Loaded
2020-01-09T00:02:39.678Z,1578528159.678 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-01-09T00:02:39.679Z,1578528159.679 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-01-09T00:02:39.679Z,1578528159.679 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-01-09T00:02:39.760Z,1578528159.760 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-01-09T00:02:39.760Z,1578528159.760 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-01-09T00:02:39.855Z,1578528159.855 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-01-09T00:02:39.856Z,1578528159.856 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-01-09T00:02:39.903Z,1578528159.903 [VerticalControl](DEBUG): Construct VerticalControl.
2020-01-09T00:02:39.986Z,1578528159.986 [VerticalControl] Loaded
2020-01-09T00:02:39.987Z,1578528159.987 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-01-09T00:02:39.988Z,1578528159.988 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-01-09T00:02:40.057Z,1578528160.057 [HorizontalControl] Loaded
2020-01-09T00:02:40.057Z,1578528160.057 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-01-09T00:02:40.058Z,1578528160.058 [SpeedControl](DEBUG): Construct SpeedControl.
2020-01-09T00:02:40.060Z,1578528160.060 [SpeedControl] Loaded
2020-01-09T00:02:40.060Z,1578528160.060 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-01-09T00:02:40.060Z,1578528160.060 [LoopControl](DEBUG): Construct LoopControl.
2020-01-09T00:02:40.061Z,1578528160.061 [LoopControl] Loaded
2020-01-09T00:02:40.061Z,1578528160.061 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-01-09T00:02:40.062Z,1578528160.062 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-01-09T00:02:40.062Z,1578528160.062 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-01-09T00:02:40.094Z,1578528160.094 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-01-09T00:02:40.098Z,1578528160.098 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-01-09T00:02:40.099Z,1578528160.099 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-01-09T00:02:40.105Z,1578528160.105 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-01-09T00:02:40.106Z,1578528160.106 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AFB4E0
2020-01-09T00:02:40.107Z,1578528160.107 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1522
2020-01-09T00:02:40.111Z,1578528160.111 [Supervisor](INFO): Main Thread ID is 9800
2020-01-09T00:02:40.112Z,1578528160.112 [Supervisor](DEBUG): Running supervisor.
2020-01-09T00:02:40.112Z,1578528160.112 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1523
2020-01-09T00:02:40.115Z,1578528160.115 [controlThread ThreadHandler](INFO): Handler Thread ID is 1524
2020-01-09T00:02:40.115Z,1578528160.115 [controlThread](DEBUG): Initializing ControlThread
2020-01-09T00:02:40.124Z,1578528160.124 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-01-09T00:02:40.124Z,1578528160.124 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-01-09T00:02:40.129Z,1578528160.129 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-01-09T00:02:40.129Z,1578528160.129 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-01-09T00:02:40.130Z,1578528160.130 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-01-09T00:02:40.130Z,1578528160.130 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-01-09T00:02:40.130Z,1578528160.130 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-01-09T00:02:40.131Z,1578528160.131 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-01-09T00:02:40.131Z,1578528160.131 [SBIT](INFO): Initialize SBIT Component.
2020-01-09T00:02:40.132Z,1578528160.132 [SBIT](IMPORTANT): git: 2019-12-03-17-g4812c94
2020-01-09T00:02:40.132Z,1578528160.132 [SBIT](INFO): git hash: 4812c9429720da7730f61311b178b42396aebb36
2020-01-09T00:02:40.132Z,1578528160.132 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-01-09T00:02:40.134Z,1578528160.134 [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
2020-01-09T00:02:40.135Z,1578528160.135 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-01-09T00:02:40.135Z,1578528160.135 [IBIT](INFO): Initialize IBIT Component.
2020-01-09T00:02:40.136Z,1578528160.136 [CBIT](DEBUG): Initialize CBIT Component.
2020-01-09T00:02:40.137Z,1578528160.137 [logger ThreadHandler](INFO): Handler Thread ID is 1525
2020-01-09T00:02:40.149Z,1578528160.149 [CBIT](DEBUG): Initialized mux pins.
2020-01-09T00:02:40.149Z,1578528160.149 [CBIT](DEBUG): Initializing the watchdog timer.
2020-01-09T00:02:40.157Z,1578528160.157 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1526
2020-01-09T00:02:40.158Z,1578528160.158 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-01-09T00:02:40.169Z,1578528160.169 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1527
2020-01-09T00:02:40.173Z,1578528160.173 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2020-01-09T00:02:40.173Z,1578528160.173 [CBIT](DEBUG): Initializing heartbeat.
2020-01-09T00:02:40.181Z,1578528160.181 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1528
2020-01-09T00:02:40.182Z,1578528160.182 [CTD_NeilBrown](INFO): Powering down
2020-01-09T00:02:40.209Z,1578528160.209 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1529
2020-01-09T00:02:40.210Z,1578528160.210 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-09T00:02:40.237Z,1578528160.237 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1530
2020-01-09T00:02:40.240Z,1578528160.240 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-01-09T00:02:40.240Z,1578528160.240 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-01-09T00:02:40.241Z,1578528160.241 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-01-09T00:02:40.241Z,1578528160.241 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-01-09T00:02:40.241Z,1578528160.241 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-01-09T00:02:40.242Z,1578528160.242 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-01-09T00:02:40.242Z,1578528160.242 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-01-09T00:02:40.242Z,1578528160.242 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-01-09T00:02:40.242Z,1578528160.242 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-01-09T00:02:40.242Z,1578528160.242 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-01-09T00:02:40.243Z,1578528160.243 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-01-09T00:02:40.243Z,1578528160.243 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-01-09T00:02:40.243Z,1578528160.243 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-01-09T00:02:40.243Z,1578528160.243 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-01-09T00:02:40.243Z,1578528160.243 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-01-09T00:02:40.243Z,1578528160.243 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-01-09T00:02:40.245Z,1578528160.245 [CBIT](DEBUG): Deactivating GF circuits.
2020-01-09T00:02:40.245Z,1578528160.245 [CBIT](DEBUG): Deactivating emergency mode.
2020-01-09T00:02:40.281Z,1578528160.281 [CBIT](DEBUG): Backplane powered.
2020-01-09T00:02:40.281Z,1578528160.281 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-01-09T00:02:40.283Z,1578528160.283 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-01-09T00:02:40.283Z,1578528160.283 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-01-09T00:02:40.284Z,1578528160.284 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-01-09T00:02:40.285Z,1578528160.285 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-01-09T00:02:40.296Z,1578528160.296 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-09T00:02:40.315Z,1578528160.315 [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>


2020-01-09T00:02:40.315Z,1578528160.315 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-01-09T00:02:40.397Z,1578528160.397 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-01-09T00:02:40.398Z,1578528160.398 [Default:A.Wait](DEBUG): Construct Wait.
2020-01-09T00:02:40.400Z,1578528160.400 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-09T00:02:40.432Z,1578528160.432 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-01-09T00:02:40.459Z,1578528160.459 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-01-09T00:02:40.495Z,1578528160.495 [Default:E.Execute](DEBUG): Construct Execute.
2020-01-09T00:02:40.498Z,1578528160.498 [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>


2020-01-09T00:02:40.503Z,1578528160.503 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,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,
2020-01-09T00:02:40.521Z,1578528160.521 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-09T00:02:40.562Z,1578528160.562 [DDM](INFO): Powering up
2020-01-09T00:02:40.563Z,1578528160.563 [DDM](DEBUG): Initializing DDM.
2020-01-09T00:02:40.573Z,1578528160.573 [Radio_Surface](INFO): Powering up
2020-01-09T00:02:40.590Z,1578528160.590 [Depth_Keller](ERROR): Pressure reading out of range: 957.962097 decibar
2020-01-09T00:02:40.591Z,1578528160.591 [DUSBL_Hydroid](INFO): Powering up
2020-01-09T00:02:40.591Z,1578528160.591 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-09T00:02:40.773Z,1578528160.773 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-01-09T00:02:40.799Z,1578528160.799 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-01-09T00:02:40.805Z,1578528160.805 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-01-09T00:02:40.806Z,1578528160.806 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-01-09T00:02:40.813Z,1578528160.813 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-01-09T00:02:40.814Z,1578528160.814 [MassServo](DEBUG): Initializing EZServoServo.
2020-01-09T00:02:40.821Z,1578528160.821 [MassServo](DEBUG): Initializing MassServo.
2020-01-09T00:02:40.822Z,1578528160.822 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-09T00:02:40.829Z,1578528160.829 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-09T00:02:40.830Z,1578528160.830 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-01-09T00:02:40.837Z,1578528160.837 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-01-09T00:02:40.839Z,1578528160.839 [CommandLine](FAULT): Scheduling is paused
2020-01-09T00:02:40.840Z,1578528160.840 [CBIT](INFO): Critical error at 20200109T000240
2020-01-09T00:02:40.840Z,1578528160.840 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-01-09T00:02:41.039Z,1578528161.039 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-01-09T00:02:41.039Z,1578528161.039 [DropWeight] Hardware Fault, FailCount= 1
2020-01-09T00:02:41.039Z,1578528161.039 [DropWeight](ERROR): Hardware Fault
2020-01-09T00:02:41.041Z,1578528161.041 [Micromodem](INFO): Powering up
2020-01-09T00:02:41.041Z,1578528161.041 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-09T00:02:41.106Z,1578528161.106 [CBIT](INFO): Critical error at 20200109T000241
2020-01-09T00:02:41.108Z,1578528161.108 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-01-09T00:02:41.109Z,1578528161.109 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-01-09T00:02:41.677Z,1578528161.677 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-01-09T00:02:41.677Z,1578528161.677 [RudderServo](FAULT): Rudder failed to initialize
2020-01-09T00:02:41.677Z,1578528161.677 [RudderServo] Communications Fault, FailCount= 1
2020-01-09T00:02:41.677Z,1578528161.677 [RudderServo](ERROR): Communications Fault
2020-01-09T00:02:41.786Z,1578528161.786 [CBIT](INFO): Critical error at 20200109T000241
2020-01-09T00:02:41.789Z,1578528161.789 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-01-09T00:02:41.987Z,1578528161.987 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-09T00:02:41.988Z,1578528161.988 [RudderServo](INFO): Powering down
2020-01-09T00:02:42.672Z,1578528162.672 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-09T00:02:42.794Z,1578528162.794 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-09T00:02:42.798Z,1578528162.798 [CBIT](INFO): Clearing failed state for component RudderServo
2020-01-09T00:02:42.798Z,1578528162.798 [RudderServo] No Fault, FailCount= 1
2020-01-09T00:02:45.877Z,1578528165.877 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-09T00:02:46.279Z,1578528166.279 [Micromodem](INFO): Nmea in: $CATMG,2020-01-09T00:02:46.378744Z,RTC,RTC*53

2020-01-09T00:02:46.280Z,1578528166.280 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-09T00:02:46.378744Z,RTC,RTC*53

2020-01-09T00:02:46.687Z,1578528166.687 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-09T00:02:46.687Z,1578528166.687 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-09T00:02:47.091Z,1578528167.091 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-01-09T00:02:47.091Z,1578528167.091 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-01-09T00:02:47.491Z,1578528167.491 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-01-09T00:02:47.491Z,1578528167.491 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-01-09T00:02:47.899Z,1578528167.899 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-01-09T00:02:47.899Z,1578528167.899 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-09T00:02:48.303Z,1578528168.303 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-09T00:02:48.303Z,1578528168.303 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-09T00:02:48.703Z,1578528168.703 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-09T00:02:48.703Z,1578528168.703 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-09T00:02:49.125Z,1578528169.125 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-09T00:02:49.125Z,1578528169.125 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-09T00:02:49.491Z,1578528169.491 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-09T00:02:49.492Z,1578528169.492 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,09,00,02,50*4B

2020-01-09T00:02:49.921Z,1578528169.921 [Micromodem](INFO): Nmea in: $CACLK,2020,1,9,0,2,50*49

2020-01-09T00:02:49.923Z,1578528169.923 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-09T00:02:51Z*78

2020-01-09T00:02:49.924Z,1578528169.924 [Micromodem](INFO): Nmea in: $CATMG,2020-01-09T00:02:51.027037Z,USER_CMD,RTC*1E

2020-01-09T00:02:51.106Z,1578528171.106 [DDM](INFO): Dynamic Docking Module:!C REMUS Capture Rev 1.1
2020-01-09T00:02:53.948Z,1578528173.948 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-09T00:02:58.774Z,1578528178.774 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-01-09T00:03:06.451Z,1578528186.451 [NAL9602](INFO): Powering up NAL9602
2020-01-09T00:03:17.355Z,1578528197.355 [NAL9602](INFO): NAL9602 initialized
2020-01-09T00:03:19.899Z,1578528199.899 [SBIT](IMPORTANT): Beginning Startup BIT
2020-01-09T00:03:19.911Z,1578528199.911 [CBIT](IMPORTANT): Beginning ground fault scan
2020-01-09T00:03:20.593Z,1578528200.593 [Micromodem](INFO): Nmea in: $CACYC,1,12,1,1,0,1*68

2020-01-09T00:03:20.593Z,1578528200.593 [Micromodem](INFO): Have incoming data
2020-01-09T00:03:20.597Z,1578528200.597 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,1,3D90B8D172D0E11A94BB9A0D6EC0434A2AB488F6*51

2020-01-09T00:03:20.598Z,1578528200.598 [Micromodem](INFO): Data:3D90B8D172D0E11A94BB9A0D6EC0434A2AB488F6*51

2020-01-09T00:03:20.599Z,1578528200.599 [Micromodem](INFO): InRXSBD:14
2020-01-09T00:03:20.599Z,1578528200.599 [Micromodem](INFO): Data Is:3D90B8D172D0E11A94BB9A0D6EC0434A2AB488F6*51

2020-01-09T00:03:20.599Z,1578528200.599 [Micromodem](INFO): InTryRXSBD:14
2020-01-09T00:03:20.600Z,1578528200.600 [Micromodem](INFO): Data Is:3D90B8D172D0E11A94BB9A0D6EC0434A2AB488F6*51

2020-01-09T00:03:20.602Z,1578528200.602 [Micromodem](INFO): Received command:V¡
Iw@Ø0áó,
2020-01-09T00:03:20.641Z,1578528200.641 [Micromodem](CRITICAL): 1
2020-01-09T00:03:20.642Z,1578528200.642 [Micromodem](INFO): FrameFormat:%128s
2020-01-09T00:03:20.643Z,1578528200.643 [Micromodem](INFO): Frame:1
2020-01-09T00:03:20.644Z,1578528200.644 [Micromodem](INFO): HexData:51
2020-01-09T00:03:20.653Z,1578528200.653 [Micromodem](INFO): IncomingPacketHex:51
2020-01-09T00:03:20.653Z,1578528200.653 [Micromodem](INFO): binSize:0
2020-01-09T00:03:20.653Z,1578528200.653 [Micromodem](INFO): InRXSBD:0
2020-01-09T00:03:20.653Z,1578528200.653 [Micromodem](INFO): Data Is:
2020-01-09T00:03:20.654Z,1578528200.654 [Micromodem](INFO): InTryRXSBD:0
2020-01-09T00:03:20.654Z,1578528200.654 [Micromodem](INFO): Data Is:
2020-01-09T00:03:20.654Z,1578528200.654 [Micromodem](CRITICAL): Packet size is not N*16+4: 0x0
2020-01-09T00:03:20.654Z,1578528200.654 [Micromodem](CRITICAL): Failed to parse uplink message:
2020-01-09T00:03:20.677Z,1578528200.677 [CommandLine](IMPORTANT): got command ibit
2020-01-09T00:03:20.794Z,1578528200.794 [IBIT](FAULT): Cannot run IBIT while SBIT is in progress.
2020-01-09T00:03:20.796Z,1578528200.796 [CBIT](INFO): Critical error at 20200109T000320
2020-01-09T00:03:21.002Z,1578528201.002 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109000320.085926,06,1470,31,0128,0150,59,02,02,00,02,1,012,001,0,3,1,0,150,26.9,8.00,00,-5.00,-01,-0.02,23,10000,4000*7F

2020-01-09T00:03:21.163Z,1578528201.163 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2020-01-09T00:03:21.164Z,1578528201.164 [IBIT](IMPORTANT): buoyancyNeutral: 105.000000 cc
2020-01-09T00:03:21.164Z,1578528201.164 [IBIT](IMPORTANT): massDefault: -0.500000 cm
2020-01-09T00:03:21.164Z,1578528201.164 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2020-01-09T00:03:21.164Z,1578528201.164 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2020-01-09T00:03:21.169Z,1578528201.169 [IBIT](IMPORTANT): IBIT FAILED
2020-01-09T00:03:30.615Z,1578528210.615 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.012744
CHAN A1 (24V): -0.029897
CHAN A2 (12V): -0.006873
CHAN A3 (5V): -0.002231
CHAN B0 (3.3V): 0.000185
CHAN B1 (3.15aV): 0.000172
CHAN B2 (3.15bV): 0.000273
CHAN B3 (GND): 0.002095
OPEN: 0.005185
Full Scale Calc: 4.765 mA, -1.589 mA
2020-01-09T00:03:34.961Z,1578528214.961 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2020-01-09T00:03:59.767Z,1578528239.767 [SBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-15.008821
2020-01-09T00:03:59.767Z,1578528239.767 [SBIT](FAULT): Control surface position failure.
2020-01-09T00:04:13.983Z,1578528253.983 [SBIT](CRITICAL): SBIT FAILED
2020-01-09T00:04:14.014Z,1578528254.014 [CBIT](INFO): Critical error at 20200109T000413
2020-01-09T00:04:14.026Z,1578528254.026 [CommandLine](IMPORTANT): got command configSet list
2020-01-09T00:04:14.026Z,1578528254.026 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-01-09T00:04:14.027Z,1578528254.027 [CommandLine](IMPORTANT): Courier linearApproximation depth 5.000000 meter;

2020-01-09T00:04:14.027Z,1578528254.027 [CommandLine](IMPORTANT): Courier linearApproximation latitude 0.000500 angular_degree;

2020-01-09T00:04:14.027Z,1578528254.027 [CommandLine](IMPORTANT): Courier linearApproximation longitude 0.000500 angular_degree;

2020-01-09T00:04:14.027Z,1578528254.027 [CommandLine](IMPORTANT): DDM.loadAtStartup=1 bool;

2020-01-09T00:04:14.027Z,1578528254.027 [CommandLine](IMPORTANT): DDM.verbosity=3 count;

2020-01-09T00:04:14.028Z,1578528254.028 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=75 count;

2020-01-09T00:04:14.028Z,1578528254.028 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout=40 millisecond;

2020-01-09T00:04:14.028Z,1578528254.028 [CommandLine](IMPORTANT): Express linearApproximation DDM.DDMMode 1.000000 count;

2020-01-09T00:04:14.028Z,1578528254.028 [CommandLine](IMPORTANT): Express all DDM.cablePresent;

2020-01-09T00:04:14.028Z,1578528254.028 [CommandLine](IMPORTANT): Express linearApproximation DDM.latchState 1.000000 count;

2020-01-09T00:04:14.028Z,1578528254.028 [CommandLine](IMPORTANT): Express linearApproximation DDM.whiskerState 1.000000 count;

2020-01-09T00:04:14.028Z,1578528254.028 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.acoustic_contact_range 1.000000 meter;

2020-01-09T00:04:14.028Z,1578528254.028 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 2.000000 degree;

2020-01-09T00:04:14.028Z,1578528254.028 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.height_above_sea_floor 2.000000 meter;

2020-01-09T00:04:14.029Z,1578528254.029 [CommandLine](IMPORTANT): Express linearApproximation platform_orientation 5.000000 degree;

2020-01-09T00:04:14.029Z,1578528254.029 [CommandLine](IMPORTANT): Express linearApproximation platform_x_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-09T00:04:14.029Z,1578528254.029 [CommandLine](IMPORTANT): Express linearApproximation platform_y_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-09T00:04:14.029Z,1578528254.029 [CommandLine](IMPORTANT): HorizontalControl.kiHeading=0.002 reciprocal_second;

2020-01-09T00:04:14.029Z,1578528254.029 [CommandLine](IMPORTANT): HorizontalControl.kpHeading=0.8 none;

2020-01-09T00:04:14.029Z,1578528254.029 [CommandLine](IMPORTANT): HorizontalControl.rudDeadband=0.05 degree;

2020-01-09T00:04:14.029Z,1578528254.029 [CommandLine](IMPORTANT): Micromodem.destinationAddress=12 enum;

2020-01-09T00:04:14.030Z,1578528254.030 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold=0 meter;

2020-01-09T00:04:14.030Z,1578528254.030 [CommandLine](IMPORTANT): PowerOnly.sampleTime=90 second;

2020-01-09T00:04:14.030Z,1578528254.030 [CommandLine](IMPORTANT): RudderServo.deviationAngle=0.0001 degree;

2020-01-09T00:04:14.030Z,1578528254.030 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter;

2020-01-09T00:04:14.030Z,1578528254.030 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter;

2020-01-09T00:04:14.477Z,1578528254.477 [MissionManager](IMPORTANT): Started mission Startup
2020-01-09T00:04:14.477Z,1578528254.477 [Startup] Running Loop=1
2020-01-09T00:04:14.477Z,1578528254.477 [Startup](DEBUG): Aggregate::initialize Startup
2020-01-09T00:04:14.477Z,1578528254.477 [Startup:A.GoToSurface] Running Loop=1
2020-01-09T00:04:14.477Z,1578528254.477 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-09T00:04:14.478Z,1578528254.478 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-09T00:04:14.478Z,1578528254.478 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-09T00:04:14.479Z,1578528254.479 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-09T00:04:14.479Z,1578528254.479 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-09T00:04:14.479Z,1578528254.479 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-09T00:04:14.481Z,1578528254.481 [Startup:StartupSatComms] Running Loop=1
2020-01-09T00:04:14.481Z,1578528254.481 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-01-09T00:04:14.481Z,1578528254.481 [Startup:StartupSatComms:A] Running Loop=1
2020-01-09T00:04:14.796Z,1578528254.796 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-01-09T00:04:31.299Z,1578528271.299 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-09T00:04:31.299Z,1578528271.299 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010817170379,35.   0.0,1448.9,  0
2020-01-09T00:05:09.443Z,1578528309.443 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004987
2020-01-09T00:05:14.538Z,1578528314.538 [Startup:StartupSatComms:A](INFO): Timed out from 2020-01-09T00:04:14.5Z
2020-01-09T00:05:14.538Z,1578528314.538 [Startup:StartupSatComms:A] Stopped
2020-01-09T00:05:14.538Z,1578528314.538 [Startup:StartupSatComms:B] Running Loop=1
2020-01-09T00:05:14.953Z,1578528314.953 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-09T00:05:16.132Z,1578528316.132 [Micromodem](INFO): Outgoing frame #1, 47 bytes: B66C165E040000005D00000400140000000000000000151D80030C5669C08C34EE5150A22D62212AABA55A2043A000
2020-01-09T00:05:16.132Z,1578528316.132 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,1*6B

2020-01-09T00:05:16.335Z,1578528316.335 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,1*69

2020-01-09T00:05:16.731Z,1578528316.731 [Micromodem](INFO): Nmea in: $CADRQ,000517,1,12,0,64,1*77

2020-01-09T00:05:16.732Z,1578528316.732 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,B66C165E040000005D00000400140000000000000000151D80030C5669C08C34EE5150A22D62212AABA55A2043A000*44

2020-01-09T00:05:17.123Z,1578528317.123 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,47*4A

2020-01-09T00:05:17.531Z,1578528317.531 [Micromodem](INFO): Nmea in: $CATXP,47*71

2020-01-09T00:05:18.758Z,1578528318.758 [Micromodem](INFO): Nmea in: $CATXF,47*67

2020-01-09T00:05:19.148Z,1578528319.148 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,000518.280981,3,0,200,4000,10000,1,1,12,1,1,1,3,47*4D

2020-01-09T00:05:19.963Z,1578528319.963 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T00:05:19.964Z,1578528319.964 [Micromodem](INFO): Sent 39 bytes from file Logs/20200108T235846/Courier0004.lzma.parts
2020-01-09T00:05:19.964Z,1578528319.964 [Micromodem](INFO): Packets left to send: 0
2020-01-09T00:05:20.362Z,1578528320.362 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109000520.280178,06,663,28,0102,0150,89,02,02,00,04,1,012,001,0,4,1,0,150,24.7,8.00,18,-5.00,-01,-0.06,26,10000,4000*44

2020-01-09T00:05:21.375Z,1578528321.375 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 966D165E000002005D00000400080200000000000000151D80030C5669BF31555EAE1409A708CE248A694289805E92F8B20B7420A5EA58D0BD60900C230653E8
2020-01-09T00:05:21.375Z,1578528321.375 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 9F032E0695D5FAA7D7B32B601E1895D873ACD16A6883936F15D99AA938B83B32CAA4AB40CEA4396C1576EE7BE20247D94BC9CEA01F0F72F38D8127943EB54130
2020-01-09T00:05:21.376Z,1578528321.376 [Micromodem](INFO): Outgoing frame #3, 64 bytes: EECA092A7D8DDE47833D8666B70E217831C6E33EB0545249C654E7F2E529F3D5F02941E0E78F57C6CE9011C9FA888174D9DE3C59FE345E08FA281DA28A9F58AF
2020-01-09T00:05:21.376Z,1578528321.376 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-09T00:05:22.463Z,1578528322.463 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-09T00:05:22.472Z,1578528322.472 [DataOverHttps](INFO): Sending 377 bytes from file Logs/20200109T000230/Courier0000.lzma
2020-01-09T00:05:22.865Z,1578528322.865 [Micromodem](INFO): Nmea in: $CADRQ,000522,1,12,0,64,1*71

2020-01-09T00:05:22.866Z,1578528322.866 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,966D165E000002005D00000400080200000000000000151D80030C5669BF31555EAE1409A708CE248A694289805E92F8B20B7420A5EA58D0BD60900C230653E8*49

2020-01-09T00:05:23.271Z,1578528323.271 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T00:05:23.675Z,1578528323.675 [Micromodem](INFO): Nmea in: $CADRQ,000524,1,12,0,64,2*74

2020-01-09T00:05:23.675Z,1578528323.675 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,9F032E0695D5FAA7D7B32B601E1895D873ACD16A6883936F15D99AA938B83B32CAA4AB40CEA4396C1576EE7BE20247D94BC9CEA01F0F72F38D8127943EB54130*47

2020-01-09T00:05:23.814Z,1578528323.814 [DataOverHttps](INFO): Moved sent file to Logs/20200109T000230/Courier0000.lzma.bak
2020-01-09T00:05:23.814Z,1578528323.814 [DataOverHttps](INFO): SBD MOMSN=12187544
2020-01-09T00:05:24.071Z,1578528324.071 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T00:05:24.471Z,1578528324.471 [Micromodem](INFO): Nmea in: $CADRQ,000525,1,12,0,64,3*74

2020-01-09T00:05:24.471Z,1578528324.471 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,EECA092A7D8DDE47833D8666B70E217831C6E33EB0545249C654E7F2E529F3D5F02941E0E78F57C6CE9011C9FA888174D9DE3C59FE345E08FA281DA28A9F58AF*36

2020-01-09T00:05:24.887Z,1578528324.887 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T00:05:25.282Z,1578528325.282 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-09T00:05:28.915Z,1578528328.915 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-09T00:05:29.316Z,1578528329.316 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,000526.062950,3,0,200,4000,10000,1,1,12,1,3,3,3,192*7B

2020-01-09T00:05:40.269Z,1578528340.269 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-01-09T00:05:40.269Z,1578528340.269 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-09T00:05:40.287Z,1578528340.287 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-09T00:05:40.705Z,1578528340.705 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-09T00:05:40.705Z,1578528340.705 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-01-09T00:05:42.368Z,1578528342.368 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20200108T233151/Express0008.lzma
2020-01-09T00:05:44.374Z,1578528344.374 [DataOverHttps](INFO): Moved sent file to Logs/20200108T233151/Express0008.lzma.bak
2020-01-09T00:05:44.374Z,1578528344.374 [DataOverHttps](INFO): SBD MOMSN=12187554
2020-01-09T00:06:02.814Z,1578528362.814 [DataOverHttps](INFO): Sending 1415 bytes from file Logs/20200108T233844/Express0001.lzma
2020-01-09T00:06:04.818Z,1578528364.818 [DataOverHttps](INFO): Moved sent file to Logs/20200108T233844/Express0001.lzma.bak
2020-01-09T00:06:04.818Z,1578528364.818 [DataOverHttps](INFO): SBD MOMSN=12187557
2020-01-09T00:06:14.664Z,1578528374.664 [Startup:StartupSatComms:B](INFO): Timed out from 2020-01-09T00:05:14.5Z
2020-01-09T00:06:14.664Z,1578528374.664 [Startup:StartupSatComms:B] Stopped
2020-01-09T00:06:14.664Z,1578528374.664 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-01-09T00:06:14.664Z,1578528374.664 [Startup:StartupSatComms] Stopped
2020-01-09T00:06:14.665Z,1578528374.665 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-01-09T00:06:14.665Z,1578528374.665 [Startup](INFO): Completed Startup
2020-01-09T00:06:14.666Z,1578528374.666 [MissionManager](INFO): Startup is completed.
2020-01-09T00:06:14.666Z,1578528374.666 [MissionManager](INFO): Uninitializing Mission Startup
2020-01-09T00:06:14.666Z,1578528374.666 [Startup] Stopped
2020-01-09T00:06:14.666Z,1578528374.666 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-01-09T00:06:14.666Z,1578528374.666 [Startup:A.GoToSurface] Stopped
2020-01-09T00:06:14.666Z,1578528374.666 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-09T00:06:15.023Z,1578528375.023 [MissionManager](IMPORTANT): Started mission Default
2020-01-09T00:06:15.023Z,1578528375.023 [Default] Running Loop=1
2020-01-09T00:06:15.023Z,1578528375.023 [Default](DEBUG): Aggregate::initialize Default
2020-01-09T00:06:15.024Z,1578528375.024 [Default:B.GoToSurface] Running Loop=1
2020-01-09T00:06:15.024Z,1578528375.024 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-09T00:06:15.024Z,1578528375.024 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-09T00:06:15.024Z,1578528375.024 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-09T00:06:15.029Z,1578528375.029 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-09T00:06:15.029Z,1578528375.029 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-09T00:06:15.029Z,1578528375.029 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-09T00:06:15.030Z,1578528375.030 [Default:A.Wait] Running Loop=1
2020-01-09T00:06:15.030Z,1578528375.030 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-09T00:06:23.098Z,1578528383.098 [DataOverHttps](INFO): Sending 321 bytes from file Logs/20200108T233844/Express0005.lzma
2020-01-09T00:06:25.091Z,1578528385.091 [DataOverHttps](INFO): Moved sent file to Logs/20200108T233844/Express0005.lzma.bak
2020-01-09T00:06:25.091Z,1578528385.091 [DataOverHttps](INFO): SBD MOMSN=12187598
2020-01-09T00:06:28.370Z,1578528388.370 [Default:A.Wait](INFO): Done Waiting.
2020-01-09T00:06:28.370Z,1578528388.370 [Default:A.Wait] Stopped
2020-01-09T00:06:28.371Z,1578528388.371 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-09T00:06:28.782Z,1578528388.782 [Default:CheckIn] Running Loop=1
2020-01-09T00:06:28.783Z,1578528388.783 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-09T00:06:28.783Z,1578528388.783 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-09T00:06:29.162Z,1578528389.162 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-01-09T00:07:28.114Z,1578528448.114 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-09T00:07:28.114Z,1578528448.114 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-09T00:08:21.019Z,1578528501.019 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-01-09T00:08:41.278Z,1578528521.278 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-01-09T00:08:41.278Z,1578528521.278 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-09T00:08:41.292Z,1578528521.292 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-09T00:08:41.702Z,1578528521.702 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-09T00:08:41.702Z,1578528521.702 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-01-09T00:09:47.112Z,1578528587.112 [RDI_Pathfinder](ERROR): only read 0 of 4 data items
2020-01-09T00:09:47.112Z,1578528587.112 [RDI_Pathfinder](ERROR): Failed to parse:
:RA   0.00,   0.00,   0.00,   0.00
2020-01-09T00:10:23.453Z,1578528623.453 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-09T00:10:23.453Z,1578528623.453 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010817225579,35.0, -.0,1448.9,  0
2020-01-09T00:10:49.311Z,1578528649.311 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-01-09T00:10:49.314Z,1578528649.314 [BPC1](INFO): Received data from all battery sticks.
2020-01-09T00:11:28.918Z,1578528688.918 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-09T00:06:28.8Z
2020-01-09T00:11:28.918Z,1578528688.918 [Default:CheckIn:Read_GPS] Stopped
2020-01-09T00:11:28.918Z,1578528688.918 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-09T00:11:29.327Z,1578528689.327 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-09T00:11:29.739Z,1578528689.739 [Micromodem](ERROR): Buffer fill timout failure.
2020-01-09T00:11:30.586Z,1578528690.586 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 966D165E040001005D00000400600100000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A
2020-01-09T00:11:30.586Z,1578528690.586 [Micromodem](INFO): Outgoing frame #2, 64 bytes: EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C42BBF445E54CD5F36943A3BDD9E89309E9017DB79DD009A333A47CF42F3BE4E4AD4301D597F
2020-01-09T00:11:30.587Z,1578528690.587 [Micromodem](INFO): Outgoing frame #3, 64 bytes: FFA3EC7FA24353C087A3A1B3BD161F92F69707865251F3B93C9772C25CD67B52231335EF84B8E92D5FFA309E6A3CA3D46AE7242463728D9B1C38F918EB56FF45
2020-01-09T00:11:30.587Z,1578528690.587 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-09T00:11:31.093Z,1578528691.093 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-09T00:11:31.495Z,1578528691.495 [Micromodem](INFO): Nmea in: $CADRQ,001132,1,12,0,64,1*75

2020-01-09T00:11:31.495Z,1578528691.495 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,966D165E040001005D00000400600100000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A*40

2020-01-09T00:11:31.899Z,1578528691.899 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T00:11:32.303Z,1578528692.303 [Micromodem](INFO): Nmea in: $CADRQ,001133,1,12,0,64,2*77

2020-01-09T00:11:32.304Z,1578528692.304 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C42BBF445E54CD5F36943A3BDD9E89309E9017DB79DD009A333A47CF42F3BE4E4AD4301D597F*3D

2020-01-09T00:11:32.723Z,1578528692.723 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T00:11:33.119Z,1578528693.119 [Micromodem](INFO): Nmea in: $CADRQ,001133,1,12,0,64,3*76

2020-01-09T00:11:33.119Z,1578528693.119 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,FFA3EC7FA24353C087A3A1B3BD161F92F69707865251F3B93C9772C25CD67B52231335EF84B8E92D5FFA309E6A3CA3D46AE7242463728D9B1C38F918EB56FF45*34

2020-01-09T00:11:33.519Z,1578528693.519 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T00:11:33.933Z,1578528693.933 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-09T00:11:36.493Z,1578528696.493 [DataOverHttps](INFO): Sending 200 bytes from file Logs/20200109T000230/Courier0004.lzma
2020-01-09T00:11:37.555Z,1578528697.555 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-09T00:11:37.972Z,1578528697.972 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,001134.712926,3,0,200,4000,10000,1,1,12,1,3,3,3,192*7C

2020-01-09T00:11:38.498Z,1578528698.498 [DataOverHttps](INFO): Moved sent file to Logs/20200109T000230/Courier0004.lzma.bak
2020-01-09T00:11:38.498Z,1578528698.498 [DataOverHttps](INFO): SBD MOMSN=12187602
2020-01-09T00:11:38.786Z,1578528698.786 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T00:11:39.172Z,1578528699.172 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T00:11:39.575Z,1578528699.575 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-09T00:11:39.576Z,1578528699.576 [Micromodem](INFO): Sent 184 bytes from file Logs/20200109T000230/Courier0004.lzma.parts
2020-01-09T00:11:39.579Z,1578528699.579 [Micromodem](INFO): Packets left to send: 1
2020-01-09T00:11:39.990Z,1578528699.990 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109001139.264061,06,712,28,0133,0150,89,02,02,00,03,1,012,001,0,4,1,0,150,25.0,7.00,19,-4.00,-01,-0.06,27,10000,4000*40

2020-01-09T00:11:40.990Z,1578528700.990 [Default:CheckIn:Read_Iridium] Stopped
2020-01-09T00:11:40.990Z,1578528700.990 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-09T00:11:40.990Z,1578528700.990 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-09T00:11:42.296Z,1578528702.296 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-01-09T00:11:42.296Z,1578528702.296 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-09T00:11:42.317Z,1578528702.317 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-09T00:11:42.694Z,1578528702.694 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-09T00:11:42.694Z,1578528702.694 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-01-09T00:12:23.372Z,1578528743.372 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20200108T233844/Express0008.lzma
2020-01-09T00:12:25.378Z,1578528745.378 [DataOverHttps](INFO): Moved sent file to Logs/20200108T233844/Express0008.lzma.bak
2020-01-09T00:12:25.378Z,1578528745.378 [DataOverHttps](INFO): SBD MOMSN=12187604
2020-01-09T00:12:26.744Z,1578528746.744 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-09T00:12:26.745Z,1578528746.745 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-3276,-32768,V
2020-01-09T00:13:14.771Z,1578528794.771 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001258.00,A,3648.16484,N,12147.28406,W,0.097,0.00,090120,,,A*79
2020-01-09T00:13:14.774Z,1578528794.774 [NAL9602](INFO): GPS fix at 20200109T001258: (36.802747, -121.788068)
2020-01-09T00:13:22.459Z,1578528802.459 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-09T00:13:22.460Z,1578528802.460 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010817255479,35.0, -0.1,   0.,  0
2020-01-09T00:13:47.085Z,1578528827.085 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-09T00:14:29.124Z,1578528869.124 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-09T00:14:29.124Z,1578528869.124 [RDI_Pathfinder](ERROR): Failed to parse:
:RA, 8,-32768,-32768,-32768,V
2020-01-09T00:16:41.255Z,1578529001.255 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-09T00:16:41.255Z,1578529001.255 [Default:CheckIn:C.Wait] Stopped
2020-01-09T00:16:41.256Z,1578529001.256 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-09T00:16:41.256Z,1578529001.256 [Default:CheckIn:D] Running Loop=1
2020-01-09T00:16:41.643Z,1578529001.643 [Default:CheckIn:D] Stopped
2020-01-09T00:16:41.643Z,1578529001.643 [Default:CheckIn:E] Running Loop=1
2020-01-09T00:16:42.048Z,1578529002.048 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.443655 min 
2020-01-09T00:16:42.048Z,1578529002.048 [Default:CheckIn:E] Stopped
2020-01-09T00:16:42.048Z,1578529002.048 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-09T00:16:42.048Z,1578529002.048 [Default:CheckIn] Stopped
2020-01-09T00:16:42.048Z,1578529002.048 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-09T00:16:42.049Z,1578529002.049 [Default:CheckIn](INFO): Running loop #2
2020-01-09T00:16:42.049Z,1578529002.049 [Default:CheckIn] Running Loop=2
2020-01-09T00:16:42.049Z,1578529002.049 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-09T00:16:42.049Z,1578529002.049 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-09T00:16:44.055Z,1578529004.055 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001627.00,A,3648.16606,N,12147.28016,W,0.739,22.28,090120,,,A*41
2020-01-09T00:16:44.057Z,1578529004.057 [NAL9602](INFO): GPS fix at 20200109T001627: (36.802768, -121.788003)
2020-01-09T00:16:44.092Z,1578529004.092 [Default:CheckIn:Read_GPS] Stopped
2020-01-09T00:16:44.092Z,1578529004.092 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-09T00:16:45.839Z,1578529005.839 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 966D165E070001005D00000400360300000000000000151D80030C5669BF3148E2FA22188B6A3173E6960F887FF8A99E669AC91D474B0DB47F940EC581E0D567
2020-01-09T00:16:45.840Z,1578529005.840 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 266CC0E6F1A517638CB0C0B8BE372A0B00F6D61E05F93094AA77DEFC753FE61F094712E1114D8E0AD7B6106F0F7B016211E5CA62E332602D1D41ACBF7CDB6A31
2020-01-09T00:16:45.840Z,1578529005.840 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 6612EED8A8416BB29191C1CCB1D608CF1AC7D5382E0295D324E3FB9C1C2302F2C3889792149C6D6DF406A218E47F75290FD65E23824F901508F051383D0A8ED3
2020-01-09T00:16:45.841Z,1578529005.841 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-09T00:16:46.023Z,1578529006.023 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-09T00:16:46.374Z,1578529006.374 [Micromodem](INFO): Nmea in: $CADRQ,001647,1,12,0,64,1*70

2020-01-09T00:16:46.375Z,1578529006.375 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,966D165E070001005D00000400360300000000000000151D80030C5669BF3148E2FA22188B6A3173E6960F887FF8A99E669AC91D474B0DB47F940EC581E0D567*3B

2020-01-09T00:16:46.777Z,1578529006.777 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T00:16:47.183Z,1578529007.183 [Micromodem](INFO): Nmea in: $CADRQ,001647,1,12,0,64,2*73

2020-01-09T00:16:47.183Z,1578529007.183 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,266CC0E6F1A517638CB0C0B8BE372A0B00F6D61E05F93094AA77DEFC753FE61F094712E1114D8E0AD7B6106F0F7B016211E5CA62E332602D1D41ACBF7CDB6A31*3C

2020-01-09T00:16:47.588Z,1578529007.588 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T00:16:47.987Z,1578529007.987 [Micromodem](INFO): Nmea in: $CADRQ,001648,1,12,0,64,3*7D

2020-01-09T00:16:47.987Z,1578529007.987 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,6612EED8A8416BB29191C1CCB1D608CF1AC7D5382E0295D324E3FB9C1C2302F2C3889792149C6D6DF406A218E47F75290FD65E23824F901508F051383D0A8ED3*39

2020-01-09T00:16:48.398Z,1578529008.398 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T00:16:48.802Z,1578529008.802 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-09T00:16:52.009Z,1578529012.009 [DataOverHttps](INFO): Sending 288 bytes from file Logs/20200109T000230/Courier0007.lzma
2020-01-09T00:16:52.432Z,1578529012.432 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-09T00:16:52.840Z,1578529012.840 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,001649.579086,3,0,200,4000,10000,1,1,12,1,3,3,3,192*7D

2020-01-09T00:16:54.014Z,1578529014.014 [DataOverHttps](INFO): Moved sent file to Logs/20200109T000230/Courier0007.lzma.bak
2020-01-09T00:16:54.014Z,1578529014.014 [DataOverHttps](INFO): SBD MOMSN=12187607
2020-01-09T00:16:54.051Z,1578529014.051 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T00:16:54.455Z,1578529014.455 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T00:16:54.862Z,1578529014.862 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-09T00:16:54.863Z,1578529014.863 [Micromodem](INFO): Sent 184 bytes from file Logs/20200109T000230/Courier0007.lzma.parts
2020-01-09T00:16:54.863Z,1578529014.863 [Micromodem](INFO): Packets left to send: 1
2020-01-09T00:16:55.274Z,1578529015.274 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109001654.134189,06,644,28,0110,0150,89,02,02,00,04,1,012,001,0,4,1,0,150,25.8,5.00,14,-2.00,-01,-0.03,26,10000,4000*4C

2020-01-09T00:16:56.241Z,1578529016.241 [Default:CheckIn:Read_Iridium] Stopped
2020-01-09T00:16:56.241Z,1578529016.241 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-09T00:16:56.241Z,1578529016.241 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-09T00:17:14.855Z,1578529034.855 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-01-09T00:17:14.937Z,1578529034.937 [NAL9602](FAULT): received: 
+CSQ:0

OK
2020-01-09T00:17:14.937Z,1578529034.937 [NAL9602] Data Fault, FailCount= 1
2020-01-09T00:17:14.937Z,1578529034.937 [NAL9602](ERROR): Data Fault
2020-01-09T00:17:15.002Z,1578529035.002 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-09T00:17:15.254Z,1578529035.254 [NAL9602](INFO): Powering down
2020-01-09T00:17:16.113Z,1578529036.113 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-09T00:17:16.113Z,1578529036.113 [NAL9602] No Fault, FailCount= 1
2020-01-09T00:17:38.760Z,1578529058.760 [DataOverHttps](INFO): Sending 159 bytes from file Logs/20200108T233844/Express0011.lzma
2020-01-09T00:17:40.766Z,1578529060.766 [DataOverHttps](INFO): Moved sent file to Logs/20200108T233844/Express0011.lzma.bak
2020-01-09T00:17:40.766Z,1578529060.766 [DataOverHttps](INFO): SBD MOMSN=12187610
2020-01-09T00:17:41.177Z,1578529061.177 [CBIT](INFO): Clearing failed state for component DropWeight
2020-01-09T00:17:41.177Z,1578529061.177 [DropWeight] No Fault, FailCount= 1
2020-01-09T00:17:45.559Z,1578529065.559 [NAL9602](INFO): Powering up NAL9602
2020-01-09T00:17:56.467Z,1578529076.467 [NAL9602](INFO): NAL9602 initialized
2020-01-09T00:18:27.619Z,1578529107.619 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-09T00:21:56.470Z,1578529316.470 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-09T00:21:56.470Z,1578529316.470 [Default:CheckIn:C.Wait] Stopped
2020-01-09T00:21:56.470Z,1578529316.470 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-09T00:21:56.470Z,1578529316.470 [Default:CheckIn:D] Running Loop=1
2020-01-09T00:21:56.887Z,1578529316.887 [Default:CheckIn:D] Stopped
2020-01-09T00:21:56.887Z,1578529316.887 [Default:CheckIn:E] Running Loop=1
2020-01-09T00:21:57.283Z,1578529317.283 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 15.697729 min 
2020-01-09T00:21:57.283Z,1578529317.283 [Default:CheckIn:E] Stopped
2020-01-09T00:21:57.283Z,1578529317.283 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-09T00:21:57.283Z,1578529317.283 [Default:CheckIn] Stopped
2020-01-09T00:21:57.283Z,1578529317.283 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-09T00:21:57.284Z,1578529317.284 [Default:CheckIn](INFO): Running loop #3
2020-01-09T00:21:57.284Z,1578529317.284 [Default:CheckIn] Running Loop=3
2020-01-09T00:21:57.284Z,1578529317.284 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-09T00:21:57.284Z,1578529317.284 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-09T00:22:00.908Z,1578529320.908 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-01-09T00:24:11.018Z,1578529451.018 [RDI_Pathfinder](ERROR): Failed to parse:     +0.00 +0.00,        +0.00,   0.00,1275.93
2020-01-09T00:26:57.455Z,1578529617.455 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-09T00:21:57.3Z
2020-01-09T00:26:57.455Z,1578529617.455 [Default:CheckIn:Read_GPS] Stopped
2020-01-09T00:26:57.455Z,1578529617.455 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-09T00:26:58.692Z,1578529618.692 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 966D165E0A0000005D00000400320000000000000000151D80030C5669C08C34EE6F35D0F07D15172119BA72B627E6A055BD12CBFED8A10E93D88C26A005F851
2020-01-09T00:26:58.694Z,1578529618.694 [Micromodem](INFO): Outgoing frame #2, 5 bytes: 9A1BCF2200
2020-01-09T00:26:58.695Z,1578529618.695 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,2*68

2020-01-09T00:26:58.891Z,1578529618.891 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,2*6A

2020-01-09T00:26:59.227Z,1578529619.227 [Micromodem](INFO): Nmea in: $CADRQ,002700,1,12,0,64,1*71

2020-01-09T00:26:59.227Z,1578529619.227 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,966D165E0A0000005D00000400320000000000000000151D80030C5669C08C34EE6F35D0F07D15172119BA72B627E6A055BD12CBFED8A10E93D88C26A005F851*4C

2020-01-09T00:26:59.635Z,1578529619.635 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T00:27:00.044Z,1578529620.044 [Micromodem](INFO): Nmea in: $CADRQ,002700,1,12,0,64,2*72

2020-01-09T00:27:00.045Z,1578529620.045 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,9A1BCF2200*45

2020-01-09T00:27:00.443Z,1578529620.443 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,5*7C

2020-01-09T00:27:00.866Z,1578529620.866 [Micromodem](INFO): Nmea in: $CATXP,69*7D

2020-01-09T00:27:03.266Z,1578529623.266 [Micromodem](INFO): Nmea in: $CATXF,69*6B

2020-01-09T00:27:03.672Z,1578529623.672 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,002701.563771,3,0,200,4000,10000,1,1,12,1,2,2,3,69*42

2020-01-09T00:27:04.479Z,1578529624.479 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T00:27:04.883Z,1578529624.883 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T00:27:04.884Z,1578529624.884 [Micromodem](INFO): Sent 61 bytes from file Logs/20200109T000230/Courier0010.lzma.parts
2020-01-09T00:27:04.884Z,1578529624.884 [Micromodem](INFO): Packets left to send: 0
2020-01-09T00:27:05.290Z,1578529625.290 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109002704.788800,06,581,27,0096,0150,89,02,02,00,03,1,012,001,0,4,1,0,150,25.2,6.00,13,-3.00,-01,-0.06,27,10000,4000*44

2020-01-09T00:27:06.294Z,1578529626.294 [Default:CheckIn:Read_Iridium] Stopped
2020-01-09T00:27:06.294Z,1578529626.294 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-09T00:27:06.294Z,1578529626.294 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-09T00:27:06.592Z,1578529626.592 [DataOverHttps](INFO): Sending 1403 bytes from file Logs/20200108T235316/Express0001.lzma
2020-01-09T00:27:08.606Z,1578529628.606 [DataOverHttps](INFO): Moved sent file to Logs/20200108T235316/Express0001.lzma.bak
2020-01-09T00:27:08.606Z,1578529628.606 [DataOverHttps](INFO): SBD MOMSN=12187614
2020-01-09T00:27:28.962Z,1578529648.962 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-09T00:28:43.760Z,1578529723.760 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-01-09T00:32:04.122Z,1578529924.122 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-09T00:32:04.122Z,1578529924.122 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010817443679,35.0, -0.1,   0.0,1448:RA,  0.00,   0.00,   0.00,   0.00
2020-01-09T00:32:06.549Z,1578529926.549 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-09T00:32:06.549Z,1578529926.549 [Default:CheckIn:C.Wait] Stopped
2020-01-09T00:32:06.549Z,1578529926.549 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-09T00:32:06.549Z,1578529926.549 [Default:CheckIn:D] Running Loop=1
2020-01-09T00:32:06.960Z,1578529926.960 [Default:CheckIn:D] Stopped
2020-01-09T00:32:06.960Z,1578529926.960 [Default:CheckIn:E] Running Loop=1
2020-01-09T00:32:07.359Z,1578529927.359 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.865615 min 
2020-01-09T00:32:07.359Z,1578529927.359 [Default:CheckIn:E] Stopped
2020-01-09T00:32:07.359Z,1578529927.359 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-09T00:32:07.359Z,1578529927.359 [Default:CheckIn] Stopped
2020-01-09T00:32:07.359Z,1578529927.359 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-09T00:32:07.359Z,1578529927.359 [Default:CheckIn](INFO): Running loop #4
2020-01-09T00:32:07.359Z,1578529927.359 [Default:CheckIn] Running Loop=4
2020-01-09T00:32:07.360Z,1578529927.360 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-09T00:32:07.360Z,1578529927.360 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-09T00:32:08.943Z,1578529928.943 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-01-09T00:32:08.943Z,1578529928.943 [NAL9602] Data Fault, FailCount= 1
2020-01-09T00:32:08.943Z,1578529928.943 [NAL9602](ERROR): Data Fault
2020-01-09T00:32:09.018Z,1578529929.018 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-09T00:32:09.347Z,1578529929.347 [NAL9602](INFO): Powering down
2020-01-09T00:32:10.197Z,1578529930.197 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-09T00:32:10.197Z,1578529930.197 [NAL9602] No Fault, FailCount= 1
2020-01-09T00:32:39.647Z,1578529959.647 [NAL9602](INFO): Powering up NAL9602
2020-01-09T00:32:50.558Z,1578529970.558 [NAL9602](INFO): NAL9602 initialized
2020-01-09T00:34:24.280Z,1578530064.280 [NAL9602](INFO): SBD MO Status=0, MOMSN=10146, MT Status=0, MTMSN=0
2020-01-09T00:34:24.280Z,1578530064.280 [NAL9602](INFO): No messages in MT queue
2020-01-09T00:37:07.571Z,1578530227.571 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-09T00:32:07.4Z
2020-01-09T00:37:07.571Z,1578530227.571 [Default:CheckIn:Read_GPS] Stopped
2020-01-09T00:37:07.571Z,1578530227.571 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-09T00:37:08.879Z,1578530228.879 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 966D165E0D0000005D00000400320000000000000000151D80030C5669C08C34EE63C5ED75E9F62FB2B62531DF59B66E25E6E2FA143C6955FE37D2F9E4F02E59
2020-01-09T00:37:08.880Z,1578530228.880 [Micromodem](INFO): Outgoing frame #2, 6 bytes: 7D5734363800
2020-01-09T00:37:08.880Z,1578530228.880 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,2*68

2020-01-09T00:37:09.113Z,1578530229.113 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,2*6A

2020-01-09T00:37:09.475Z,1578530229.475 [Micromodem](INFO): Nmea in: $CADRQ,003710,1,12,0,64,1*71

2020-01-09T00:37:09.475Z,1578530229.475 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,966D165E0D0000005D00000400320000000000000000151D80030C5669C08C34EE63C5ED75E9F62FB2B62531DF59B66E25E6E2FA143C6955FE37D2F9E4F02E59*47

2020-01-09T00:37:09.890Z,1578530229.890 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T00:37:10.283Z,1578530230.283 [Micromodem](INFO): Nmea in: $CADRQ,003711,1,12,0,64,2*73

2020-01-09T00:37:10.283Z,1578530230.283 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,7D5734363800*33

2020-01-09T00:37:10.699Z,1578530230.699 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,6*7F

2020-01-09T00:37:11.098Z,1578530231.098 [Micromodem](INFO): Nmea in: $CATXP,70*75

2020-01-09T00:37:13.514Z,1578530233.514 [Micromodem](INFO): Nmea in: $CATXF,70*63

2020-01-09T00:37:13.945Z,1578530233.945 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,003711.802196,3,0,200,4000,10000,1,1,12,1,2,2,3,70*4F

2020-01-09T00:37:14.751Z,1578530234.751 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T00:37:15.131Z,1578530235.131 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T00:37:15.132Z,1578530235.132 [Micromodem](INFO): Sent 62 bytes from file Logs/20200109T000230/Courier0013.lzma.parts
2020-01-09T00:37:15.132Z,1578530235.132 [Micromodem](INFO): Packets left to send: 0
2020-01-09T00:37:15.542Z,1578530235.542 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109003715.027185,06,565,27,0095,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,24.1,7.00,16,-4.00,-01,-0.07,24,10000,4000*44

2020-01-09T00:37:16.342Z,1578530236.342 [Default:CheckIn:Read_Iridium] Stopped
2020-01-09T00:37:16.343Z,1578530236.343 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-09T00:37:16.343Z,1578530236.343 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-09T00:37:17.341Z,1578530237.341 [DataOverHttps](INFO): Sending 127 bytes from file Logs/20200108T235316/Express0005.lzma
2020-01-09T00:37:19.352Z,1578530239.352 [DataOverHttps](INFO): Moved sent file to Logs/20200108T235316/Express0005.lzma.bak
2020-01-09T00:37:19.352Z,1578530239.352 [DataOverHttps](INFO): SBD MOMSN=12187657
2020-01-09T00:37:40.206Z,1578530260.206 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-09T00:38:08.107Z,1578530288.107 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-09T00:38:08.107Z,1578530288.107 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010817504079,35.0, -0.1,   0.0,1448.RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-09T00:42:16.574Z,1578530536.574 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-09T00:42:16.574Z,1578530536.574 [Default:CheckIn:C.Wait] Stopped
2020-01-09T00:42:16.574Z,1578530536.574 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-09T00:42:16.574Z,1578530536.574 [Default:CheckIn:D] Running Loop=1
2020-01-09T00:42:16.998Z,1578530536.998 [Default:CheckIn:D] Stopped
2020-01-09T00:42:16.998Z,1578530536.998 [Default:CheckIn:E] Running Loop=1
2020-01-09T00:42:17.400Z,1578530537.400 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.032902 min 
2020-01-09T00:42:17.400Z,1578530537.400 [Default:CheckIn:E] Stopped
2020-01-09T00:42:17.401Z,1578530537.401 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-09T00:42:17.401Z,1578530537.401 [Default:CheckIn] Stopped
2020-01-09T00:42:17.401Z,1578530537.401 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-09T00:42:17.401Z,1578530537.401 [Default:CheckIn](INFO): Running loop #5
2020-01-09T00:42:17.401Z,1578530537.401 [Default:CheckIn] Running Loop=5
2020-01-09T00:42:17.401Z,1578530537.401 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-09T00:42:17.401Z,1578530537.401 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-09T00:42:19.386Z,1578530539.386 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004202.00,A,3648.19020,N,12147.29060,W,2.974,4.80,090120,,,A*79
2020-01-09T00:42:19.388Z,1578530539.388 [NAL9602](INFO): GPS fix at 20200109T004202: (36.803170, -121.788177)
2020-01-09T00:42:19.429Z,1578530539.429 [Default:CheckIn:Read_GPS] Stopped
2020-01-09T00:42:19.429Z,1578530539.429 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-09T00:42:20.639Z,1578530540.639 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 966D165E100000005D00000400610000000000000000151D80030C5669C08C34EE4D008B489FF90AE8BBA1F35F13CF2C1F830F8A61213060607187CBE51B4B30
2020-01-09T00:42:20.640Z,1578530540.640 [Micromodem](INFO): Outgoing frame #2, 28 bytes: 9A85148F8B2D49499B22379F1E6BB7BC085C31AA02E49ECDDBA48200
2020-01-09T00:42:20.640Z,1578530540.640 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,2*68

2020-01-09T00:42:20.791Z,1578530540.791 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,2*6A

2020-01-09T00:42:21.151Z,1578530541.151 [Micromodem](INFO): Nmea in: $CADRQ,004222,1,12,0,64,1*72

2020-01-09T00:42:21.151Z,1578530541.151 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,966D165E100000005D00000400610000000000000000151D80030C5669C08C34EE4D008B489FF90AE8BBA1F35F13CF2C1F830F8A61213060607187CBE51B4B30*38

2020-01-09T00:42:21.561Z,1578530541.561 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T00:42:21.955Z,1578530541.955 [Micromodem](INFO): Nmea in: $CADRQ,004222,1,12,0,64,2*71

2020-01-09T00:42:21.955Z,1578530541.955 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,9A85148F8B2D49499B22379F1E6BB7BC085C31AA02E49ECDDBA48200*30

2020-01-09T00:42:22.363Z,1578530542.363 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,28*43

2020-01-09T00:42:22.766Z,1578530542.766 [Micromodem](INFO): Nmea in: $CATXP,92*79

2020-01-09T00:42:25.187Z,1578530545.187 [Micromodem](INFO): Nmea in: $CATXF,92*6F

2020-01-09T00:42:26.401Z,1578530546.401 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,004223.500394,3,0,200,4000,10000,1,1,12,1,2,2,3,92*4F

2020-01-09T00:42:26.819Z,1578530546.819 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T00:42:27.214Z,1578530547.214 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T00:42:27.214Z,1578530547.214 [Micromodem](INFO): Sent 84 bytes from file Logs/20200109T000230/Courier0016.lzma.parts
2020-01-09T00:42:27.214Z,1578530547.214 [Micromodem](INFO): Packets left to send: 0
2020-01-09T00:42:27.613Z,1578530547.613 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109004226.745415,06,254,24,0132,0150,89,02,02,00,03,1,012,001,0,4,1,0,150,21.8,6.00,13,-3.00,-01,-0.02,30,10000,4000*41

2020-01-09T00:42:29.309Z,1578530549.309 [Default:CheckIn:Read_Iridium] Stopped
2020-01-09T00:42:29.309Z,1578530549.309 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-09T00:42:29.309Z,1578530549.309 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-09T00:42:30.124Z,1578530550.124 [DataOverHttps](INFO): Sending 1385 bytes from file Logs/20200108T235846/Express0001.lzma
2020-01-09T00:42:32.198Z,1578530552.198 [DataOverHttps](INFO): Moved sent file to Logs/20200108T235846/Express0001.lzma.bak
2020-01-09T00:42:32.198Z,1578530552.198 [DataOverHttps](INFO): SBD MOMSN=12187663
2020-01-09T00:42:42.679Z,1578530562.679 [NAL9602](INFO): SBD MO Status=2, MOMSN=10147, MT Status=2, MTMSN=0
2020-01-09T00:42:42.679Z,1578530562.679 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-09T00:43:38.424Z,1578530618.424 [NAL9602](INFO): SBD MO Status=0, MOMSN=10147, MT Status=0, MTMSN=0
2020-01-09T00:43:38.439Z,1578530618.439 [NAL9602](INFO): No messages in MT queue
2020-01-09T00:44:09.127Z,1578530649.127 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-09T00:47:02.295Z,1578530822.295 [CommandLine](IMPORTANT): got command restart application
2020-01-09T00:47:03.301Z,1578530823.301 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T00:47:03.301Z,1578530823.301 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-09T00:47:03.325Z,1578530823.325 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-01-09T00:47:03.325Z,1578530823.325 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-09T00:47:03.326Z,1578530823.326 [CommandLine](INFO): Join timeout helper Thread ID is 1596
2020-01-09T00:47:03.333Z,1578530823.333 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-01-09T00:47:03.333Z,1578530823.333 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-09T00:47:03.334Z,1578530823.334 [NavChartDb](INFO): Join timeout helper Thread ID is 1597
2020-01-09T00:47:03.633Z,1578530823.633 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T00:47:03.633Z,1578530823.633 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-09T00:47:03.653Z,1578530823.653 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-01-09T00:47:03.653Z,1578530823.653 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-09T00:47:03.653Z,1578530823.653 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1598
2020-01-09T00:47:03.658Z,1578530823.658 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T00:47:03.658Z,1578530823.658 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-09T00:47:03.659Z,1578530823.659 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-09T00:47:03.675Z,1578530823.675 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2020-01-09T00:47:03.675Z,1578530823.675 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-09T00:47:03.676Z,1578530823.676 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1599
2020-01-09T00:47:03.873Z,1578530823.873 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T00:47:03.873Z,1578530823.873 [CTD_NeilBrown](INFO): Powering down
2020-01-09T00:47:03.885Z,1578530823.885 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-09T00:47:03.901Z,1578530823.901 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-01-09T00:47:03.901Z,1578530823.901 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-09T00:47:03.901Z,1578530823.901 [Radio_Surface](INFO): Join timeout helper Thread ID is 1600
2020-01-09T00:47:03.949Z,1578530823.949 [Radio_Surface](INFO): Powering down
2020-01-09T00:47:03.953Z,1578530823.953 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T00:47:03.954Z,1578530823.954 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-09T00:47:03.962Z,1578530823.962 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-01-09T00:47:03.962Z,1578530823.962 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-09T00:47:03.962Z,1578530823.962 [DataOverHttps](INFO): Join timeout helper Thread ID is 1601
2020-01-09T00:47:04.337Z,1578530824.337 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T00:47:04.337Z,1578530824.337 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-09T00:47:04.355Z,1578530824.355 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-01-09T00:47:04.355Z,1578530824.355 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-09T00:47:04.355Z,1578530824.355 [logger](INFO): Join timeout helper Thread ID is 1602
2020-01-09T00:47:04.397Z,1578530824.397 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T00:47:04.397Z,1578530824.397 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-09T00:47:04.413Z,1578530824.413 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-01-09T00:47:04.414Z,1578530824.414 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-09T00:47:04.414Z,1578530824.414 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-01-09T00:47:04.414Z,1578530824.414 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-09T00:47:04.414Z,1578530824.414 [controlThread](INFO): Join timeout helper Thread ID is 1603
2020-01-09T00:47:04.445Z,1578530824.445 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T00:47:04.445Z,1578530824.445 [controlThread](DEBUG): Uninitializing ControlThread
2020-01-09T00:47:04.446Z,1578530824.446 [AHRS_M2](INFO): Powering down
2020-01-09T00:47:04.517Z,1578530824.517 [DDM](INFO): Powering down
2020-01-09T00:47:04.609Z,1578530824.609 [DUSBL_Hydroid](INFO): Powering down
2020-01-09T00:47:04.702Z,1578530824.702 [Micromodem](INFO): Powering down
2020-01-09T00:47:04.797Z,1578530824.797 [NAL9602](INFO): Powering down
2020-01-09T00:47:04.869Z,1578530824.869 [RDI_Pathfinder](INFO): Powering down
2020-01-09T00:47:04.870Z,1578530824.870 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-01-09T00:47:04.871Z,1578530824.871 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-01-09T00:47:04.872Z,1578530824.872 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-01-09T00:47:04.872Z,1578530824.872 [MissionManager](INFO): Uninitializing Mission Default
2020-01-09T00:47:04.872Z,1578530824.872 [Default] Stopped
2020-01-09T00:47:04.872Z,1578530824.872 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-09T00:47:04.872Z,1578530824.872 [Default:B.GoToSurface] Stopped
2020-01-09T00:47:04.873Z,1578530824.873 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-09T00:47:04.873Z,1578530824.873 [Default:CheckIn] Stopped
2020-01-09T00:47:04.873Z,1578530824.873 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-09T00:47:04.873Z,1578530824.873 [Default:CheckIn:C.Wait] Stopped
2020-01-09T00:47:04.873Z,1578530824.873 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-09T00:47:04.875Z,1578530824.875 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-01-09T00:47:04.876Z,1578530824.876 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-01-09T00:47:04.876Z,1578530824.876 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-01-09T00:47:04.876Z,1578530824.876 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-01-09T00:47:04.876Z,1578530824.876 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-01-09T00:47:04.876Z,1578530824.876 [BuoyancyServo](INFO): Powering down
2020-01-09T00:47:04.889Z,1578530824.889 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-01-09T00:47:04.889Z,1578530824.889 [ElevatorServo](INFO): Powering down
2020-01-09T00:47:04.890Z,1578530824.890 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-01-09T00:47:04.890Z,1578530824.890 [MassServo](INFO): Powering down
2020-01-09T00:47:04.891Z,1578530824.891 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-09T00:47:04.891Z,1578530824.891 [RudderServo](INFO): Powering down
2020-01-09T00:47:04.891Z,1578530824.891 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-01-09T00:47:04.891Z,1578530824.891 [ThrusterServo](INFO): Powering down
2020-01-09T00:47:04.892Z,1578530824.892 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-01-09T00:47:04.893Z,1578530824.893 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-01-09T00:47:04.893Z,1578530824.893 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-01-09T00:47:04.893Z,1578530824.893 [CBIT](DEBUG): Powering off loads.
2020-01-09T00:47:04.905Z,1578530824.905 [CBIT](DEBUG): Disabling WDT.
2020-01-09T00:47:04.917Z,1578530824.917 [CBIT](DEBUG): Opening all GF detection circuits.
2020-01-09T00:47:04.918Z,1578530824.918 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-09T00:47:04.972Z,1578530824.972 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-09T00:47:04.983Z,1578530824.983 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-09T00:47:05.030Z,1578530825.030 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-09T00:47:05.033Z,1578530825.033 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-09T00:47:05.092Z,1578530825.092 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-09T00:47:05.165Z,1578530825.165 [logger ThreadHandler](INFO): Thread cancelled.