2020-01-09T19:17:34.596Z,1578597454.596 [Supervisor](DEBUG): Initializing supervisor.
2020-01-09T19:17:34.598Z,1578597454.598 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-01-09T19:17:34.599Z,1578597454.599 [SyncHandler](INFO): Protected caller Thread ID is 807
2020-01-09T19:17:34.599Z,1578597454.599 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-01-09T19:17:34.600Z,1578597454.600 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-01-09T19:17:34.601Z,1578597454.601 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 808
2020-01-09T19:17:34.603Z,1578597454.603 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-01-09T19:17:34.614Z,1578597454.614 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-01-09T19:17:34.615Z,1578597454.615 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-01-09T19:17:34.616Z,1578597454.616 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 809
2020-01-09T19:17:34.617Z,1578597454.617 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-01-09T19:17:34.618Z,1578597454.618 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-01-09T19:17:34.618Z,1578597454.618 [logger ThreadHandler](INFO): Protected caller Thread ID is 810
2020-01-09T19:17:34.620Z,1578597454.620 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-01-09T19:17:34.620Z,1578597454.620 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-01-09T19:17:34.624Z,1578597454.624 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-01-09T19:17:35.058Z,1578597455.058 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-01-09T19:17:35.060Z,1578597455.060 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-01-09T19:17:35.156Z,1578597455.156 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-01-09T19:17:35.158Z,1578597455.158 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-01-09T19:17:35.481Z,1578597455.481 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-01-09T19:17:35.482Z,1578597455.482 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-01-09T19:17:35.619Z,1578597455.619 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-01-09T19:17:35.621Z,1578597455.621 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-01-09T19:17:35.809Z,1578597455.809 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-01-09T19:17:35.811Z,1578597455.811 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-01-09T19:17:36.268Z,1578597456.268 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-01-09T19:17:36.270Z,1578597456.270 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-01-09T19:17:36.476Z,1578597456.476 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-01-09T19:17:36.478Z,1578597456.478 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-01-09T19:17:36.622Z,1578597456.622 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-01-09T19:17:36.624Z,1578597456.624 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-01-09T19:17:36.812Z,1578597456.812 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-01-09T19:17:36.812Z,1578597456.812 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-01-09T19:17:36.908Z,1578597456.908 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-01-09T19:17:36.909Z,1578597456.909 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-01-09T19:17:37.208Z,1578597457.208 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-01-09T19:17:37.210Z,1578597457.210 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-01-09T19:17:37.290Z,1578597457.290 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-01-09T19:17:37.392Z,1578597457.392 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-01-09T19:17:37.393Z,1578597457.393 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-01-09T19:17:38.043Z,1578597458.043 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-01-09T19:17:38.044Z,1578597458.044 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-01-09T19:17:38.851Z,1578597458.851 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-01-09T19:17:38.853Z,1578597458.853 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-01-09T19:17:38.855Z,1578597458.855 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-01-09T19:17:39.061Z,1578597459.061 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-01-09T19:17:39.159Z,1578597459.159 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-01-09T19:17:39.257Z,1578597459.257 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-01-09T19:17:39.482Z,1578597459.482 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-01-09T19:17:39.483Z,1578597459.483 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-01-09T19:17:39.567Z,1578597459.567 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-01-09T19:17:39.660Z,1578597459.660 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-01-09T19:17:39.757Z,1578597459.757 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-01-09T19:17:39.839Z,1578597459.839 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-01-09T19:17:39.946Z,1578597459.946 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-01-09T19:17:40.128Z,1578597460.128 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-01-09T19:17:40.257Z,1578597460.257 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-01-09T19:17:40.285Z,1578597460.285 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-01-09T19:17:40.824Z,1578597460.824 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-01-09T19:17:40.829Z,1578597460.829 [AHRS_M2](INFO): created writer for : platform_orientation
2020-01-09T19:17:40.831Z,1578597460.831 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-01-09T19:17:40.836Z,1578597460.836 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-01-09T19:17:40.837Z,1578597460.837 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-01-09T19:17:40.842Z,1578597460.842 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-01-09T19:17:40.843Z,1578597460.843 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-01-09T19:17:40.848Z,1578597460.848 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-01-09T19:17:41.025Z,1578597461.025 [AHRS_M2] Loaded
2020-01-09T19:17:41.025Z,1578597461.025 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-01-09T19:17:41.170Z,1578597461.170 [DataOverHttps] Loaded
2020-01-09T19:17:41.171Z,1578597461.171 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-01-09T19:17:41.172Z,1578597461.172 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4076F4E0
2020-01-09T19:17:41.172Z,1578597461.172 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 889
2020-01-09T19:17:41.196Z,1578597461.196 [DDM] Loaded
2020-01-09T19:17:41.196Z,1578597461.196 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-01-09T19:17:41.209Z,1578597461.209 [Depth_Keller] Loaded
2020-01-09T19:17:41.209Z,1578597461.209 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-01-09T19:17:41.214Z,1578597461.214 [DropWeight] Loaded
2020-01-09T19:17:41.214Z,1578597461.214 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-01-09T19:17:41.275Z,1578597461.275 [DUSBL_Hydroid] Loaded
2020-01-09T19:17:41.275Z,1578597461.275 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-01-09T19:17:41.321Z,1578597461.321 [Micromodem] Loaded
2020-01-09T19:17:41.321Z,1578597461.321 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-01-09T19:17:41.417Z,1578597461.417 [NAL9602] Loaded
2020-01-09T19:17:41.417Z,1578597461.417 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-01-09T19:17:41.454Z,1578597461.454 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2020-01-09T19:17:41.455Z,1578597461.455 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2020-01-09T19:17:41.455Z,1578597461.455 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2020-01-09T19:17:41.456Z,1578597461.456 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2020-01-09T19:17:41.456Z,1578597461.456 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2020-01-09T19:17:41.457Z,1578597461.457 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2020-01-09T19:17:41.457Z,1578597461.457 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2020-01-09T19:17:41.458Z,1578597461.458 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2020-01-09T19:17:41.458Z,1578597461.458 [Onboard] Loaded
2020-01-09T19:17:41.458Z,1578597461.458 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-01-09T19:17:41.464Z,1578597461.464 [PowerOnly] Loaded
2020-01-09T19:17:41.464Z,1578597461.464 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-01-09T19:17:41.471Z,1578597461.471 [Radio_Surface] Loaded
2020-01-09T19:17:41.471Z,1578597461.471 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-01-09T19:17:41.472Z,1578597461.472 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4079F4E0
2020-01-09T19:17:41.472Z,1578597461.472 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 890
2020-01-09T19:17:41.515Z,1578597461.515 [RDI_Pathfinder] Loaded
2020-01-09T19:17:41.516Z,1578597461.516 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-01-09T19:17:42.994Z,1578597462.994 [BPC1] Loaded
2020-01-09T19:17:42.994Z,1578597462.994 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-01-09T19:17:42.994Z,1578597462.994 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-01-09T19:17:42.995Z,1578597462.995 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-01-09T19:17:43.019Z,1578597463.019 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-01-09T19:17:43.021Z,1578597463.021 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-01-09T19:17:43.140Z,1578597463.140 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-01-09T19:17:43.140Z,1578597463.140 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-01-09T19:17:43.160Z,1578597463.160 [NavChart] Loaded
2020-01-09T19:17:43.160Z,1578597463.160 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-01-09T19:17:43.164Z,1578597463.164 [UniversalFixResidualReporter] Loaded
2020-01-09T19:17:43.164Z,1578597463.164 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-01-09T19:17:43.165Z,1578597463.165 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-01-09T19:17:43.165Z,1578597463.165 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-01-09T19:17:43.551Z,1578597463.551 [BuoyancyServo] Loaded
2020-01-09T19:17:43.551Z,1578597463.551 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-01-09T19:17:43.567Z,1578597463.567 [ElevatorServo] Loaded
2020-01-09T19:17:43.567Z,1578597463.567 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-01-09T19:17:43.582Z,1578597463.582 [MassServo] Loaded
2020-01-09T19:17:43.582Z,1578597463.582 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-01-09T19:17:43.597Z,1578597463.597 [RudderServo] Loaded
2020-01-09T19:17:43.597Z,1578597463.597 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-01-09T19:17:43.650Z,1578597463.650 [ThrusterServo] Loaded
2020-01-09T19:17:43.650Z,1578597463.650 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-01-09T19:17:43.650Z,1578597463.650 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-01-09T19:17:43.748Z,1578597463.748 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-01-09T19:17:44.124Z,1578597464.124 [CTD_NeilBrown] Loaded
2020-01-09T19:17:44.125Z,1578597464.125 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2020-01-09T19:17:44.126Z,1578597464.126 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408F34E0
2020-01-09T19:17:44.126Z,1578597464.126 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 892
2020-01-09T19:17:44.140Z,1578597464.140 [PAR_Licor] Loaded
2020-01-09T19:17:44.140Z,1578597464.140 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-01-09T19:17:44.183Z,1578597464.183 [WetLabsSeaOWL_UV_A] Loaded
2020-01-09T19:17:44.184Z,1578597464.184 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-01-09T19:17:44.185Z,1578597464.185 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409234E0
2020-01-09T19:17:44.185Z,1578597464.185 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 893
2020-01-09T19:17:44.186Z,1578597464.186 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-01-09T19:17:44.188Z,1578597464.188 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-01-09T19:17:44.541Z,1578597464.541 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-01-09T19:17:44.542Z,1578597464.542 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-01-09T19:17:44.595Z,1578597464.595 [DepthRateCalculator] Loaded
2020-01-09T19:17:44.596Z,1578597464.596 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-01-09T19:17:44.601Z,1578597464.601 [PitchRateCalculator] Loaded
2020-01-09T19:17:44.602Z,1578597464.602 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-01-09T19:17:44.614Z,1578597464.614 [SpeedCalculator] Loaded
2020-01-09T19:17:44.614Z,1578597464.614 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-01-09T19:17:44.634Z,1578597464.634 [TempGradientCalculator] Loaded
2020-01-09T19:17:44.635Z,1578597464.635 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-01-09T19:17:44.640Z,1578597464.640 [YawRateCalculator] Loaded
2020-01-09T19:17:44.640Z,1578597464.640 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-01-09T19:17:44.680Z,1578597464.680 [ElevatorOffsetCalculator] Loaded
2020-01-09T19:17:44.680Z,1578597464.680 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-01-09T19:17:44.681Z,1578597464.681 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-01-09T19:17:44.681Z,1578597464.681 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-01-09T19:17:44.839Z,1578597464.839 [SBIT](DEBUG): Construct Startup Built In Test.
2020-01-09T19:17:44.860Z,1578597464.860 [SBIT] Loaded
2020-01-09T19:17:44.861Z,1578597464.861 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-01-09T19:17:44.861Z,1578597464.861 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-01-09T19:17:44.874Z,1578597464.874 [IBIT] Loaded
2020-01-09T19:17:44.874Z,1578597464.874 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-01-09T19:17:44.877Z,1578597464.877 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-01-09T19:17:45.015Z,1578597465.015 [CBIT] Loaded
2020-01-09T19:17:45.016Z,1578597465.016 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-01-09T19:17:45.016Z,1578597465.016 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-01-09T19:17:45.018Z,1578597465.018 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-01-09T19:17:45.141Z,1578597465.141 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-01-09T19:17:45.142Z,1578597465.142 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-01-09T19:17:45.263Z,1578597465.263 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-01-09T19:17:45.264Z,1578597465.264 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-01-09T19:17:45.327Z,1578597465.327 [VerticalControl](DEBUG): Construct VerticalControl.
2020-01-09T19:17:45.409Z,1578597465.409 [VerticalControl] Loaded
2020-01-09T19:17:45.409Z,1578597465.409 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-01-09T19:17:45.410Z,1578597465.410 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-01-09T19:17:45.479Z,1578597465.479 [HorizontalControl] Loaded
2020-01-09T19:17:45.479Z,1578597465.479 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-01-09T19:17:45.480Z,1578597465.480 [SpeedControl](DEBUG): Construct SpeedControl.
2020-01-09T19:17:45.482Z,1578597465.482 [SpeedControl] Loaded
2020-01-09T19:17:45.482Z,1578597465.482 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-01-09T19:17:45.482Z,1578597465.482 [LoopControl](DEBUG): Construct LoopControl.
2020-01-09T19:17:45.483Z,1578597465.483 [LoopControl] Loaded
2020-01-09T19:17:45.483Z,1578597465.483 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-01-09T19:17:45.484Z,1578597465.484 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-01-09T19:17:45.484Z,1578597465.484 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-01-09T19:17:45.532Z,1578597465.532 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-01-09T19:17:45.536Z,1578597465.536 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-01-09T19:17:45.537Z,1578597465.537 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-01-09T19:17:45.543Z,1578597465.543 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-01-09T19:17:45.544Z,1578597465.544 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B024E0
2020-01-09T19:17:45.545Z,1578597465.545 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 894
2020-01-09T19:17:45.549Z,1578597465.549 [Supervisor](INFO): Main Thread ID is 803
2020-01-09T19:17:45.549Z,1578597465.549 [Supervisor](DEBUG): Running supervisor.
2020-01-09T19:17:45.550Z,1578597465.550 [CommandLine ThreadHandler](INFO): Handler Thread ID is 895
2020-01-09T19:17:45.552Z,1578597465.552 [controlThread ThreadHandler](INFO): Handler Thread ID is 896
2020-01-09T19:17:45.553Z,1578597465.553 [controlThread](DEBUG): Initializing ControlThread
2020-01-09T19:17:45.560Z,1578597465.560 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-01-09T19:17:45.560Z,1578597465.560 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-01-09T19:17:45.565Z,1578597465.565 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-01-09T19:17:45.565Z,1578597465.565 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-01-09T19:17:45.566Z,1578597465.566 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-01-09T19:17:45.566Z,1578597465.566 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-01-09T19:17:45.567Z,1578597465.567 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-01-09T19:17:45.567Z,1578597465.567 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-01-09T19:17:45.568Z,1578597465.568 [SBIT](INFO): Initialize SBIT Component.
2020-01-09T19:17:45.568Z,1578597465.568 [SBIT](IMPORTANT): git: 2019-12-03-17-g4812c94
2020-01-09T19:17:45.568Z,1578597465.568 [SBIT](INFO): git hash: 4812c9429720da7730f61311b178b42396aebb36
2020-01-09T19:17:45.569Z,1578597465.569 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-01-09T19:17:45.570Z,1578597465.570 [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-09T19:17:45.571Z,1578597465.571 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-01-09T19:17:45.572Z,1578597465.572 [IBIT](INFO): Initialize IBIT Component.
2020-01-09T19:17:45.573Z,1578597465.573 [CBIT](DEBUG): Initialize CBIT Component.
2020-01-09T19:17:45.574Z,1578597465.574 [logger ThreadHandler](INFO): Handler Thread ID is 897
2020-01-09T19:17:45.585Z,1578597465.585 [CBIT](DEBUG): Initialized mux pins.
2020-01-09T19:17:45.585Z,1578597465.585 [CBIT](DEBUG): Initializing the watchdog timer.
2020-01-09T19:17:45.593Z,1578597465.593 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 898
2020-01-09T19:17:45.594Z,1578597465.594 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-01-09T19:17:45.605Z,1578597465.605 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 899
2020-01-09T19:17:45.609Z,1578597465.609 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-01-09T19:17:45.609Z,1578597465.609 [CBIT](DEBUG): Initializing heartbeat.
2020-01-09T19:17:45.617Z,1578597465.617 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 900
2020-01-09T19:17:45.618Z,1578597465.618 [CTD_NeilBrown](INFO): Powering down
2020-01-09T19:17:45.645Z,1578597465.645 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 901
2020-01-09T19:17:45.646Z,1578597465.646 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-09T19:17:45.673Z,1578597465.673 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 902
2020-01-09T19:17:45.681Z,1578597465.681 [CBIT](DEBUG): Deactivating GF circuits.
2020-01-09T19:17:45.681Z,1578597465.681 [CBIT](DEBUG): Deactivating emergency mode.
2020-01-09T19:17:45.683Z,1578597465.683 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-01-09T19:17:45.683Z,1578597465.683 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-01-09T19:17:45.683Z,1578597465.683 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-01-09T19:17:45.683Z,1578597465.683 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-01-09T19:17:45.684Z,1578597465.684 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-01-09T19:17:45.684Z,1578597465.684 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-01-09T19:17:45.684Z,1578597465.684 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-01-09T19:17:45.684Z,1578597465.684 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-01-09T19:17:45.684Z,1578597465.684 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-01-09T19:17:45.684Z,1578597465.684 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-01-09T19:17:45.685Z,1578597465.685 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-01-09T19:17:45.685Z,1578597465.685 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-01-09T19:17:45.685Z,1578597465.685 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-01-09T19:17:45.685Z,1578597465.685 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-01-09T19:17:45.686Z,1578597465.686 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-01-09T19:17:45.686Z,1578597465.686 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-01-09T19:17:45.717Z,1578597465.717 [CBIT](DEBUG): Backplane powered.
2020-01-09T19:17:45.717Z,1578597465.717 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-01-09T19:17:45.719Z,1578597465.719 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-01-09T19:17:45.719Z,1578597465.719 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-01-09T19:17:45.720Z,1578597465.720 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-01-09T19:17:45.721Z,1578597465.721 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-01-09T19:17:45.746Z,1578597465.746 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-09T19:17:45.786Z,1578597465.786 [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-09T19:17:45.787Z,1578597465.787 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-01-09T19:17:45.850Z,1578597465.850 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-01-09T19:17:45.851Z,1578597465.851 [Default:A.Wait](DEBUG): Construct Wait.
2020-01-09T19:17:45.853Z,1578597465.853 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-09T19:17:45.901Z,1578597465.901 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-01-09T19:17:45.919Z,1578597465.919 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-01-09T19:17:45.924Z,1578597465.924 [Default:E.Execute](DEBUG): Construct Execute.
2020-01-09T19:17:45.944Z,1578597465.944 [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-09T19:17:45.949Z,1578597465.949 [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-09T19:17:45.961Z,1578597465.961 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-09T19:17:46.009Z,1578597466.009 [Radio_Surface](INFO): Powering up
2020-01-09T19:17:46.047Z,1578597466.047 [DDM](INFO): Powering up
2020-01-09T19:17:46.047Z,1578597466.047 [DDM](DEBUG): Initializing DDM.
2020-01-09T19:17:46.095Z,1578597466.095 [DUSBL_Hydroid](INFO): Powering up
2020-01-09T19:17:46.096Z,1578597466.096 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-09T19:17:46.342Z,1578597466.342 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-01-09T19:17:46.349Z,1578597466.349 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-01-09T19:17:46.350Z,1578597466.350 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-01-09T19:17:46.357Z,1578597466.357 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-01-09T19:17:46.358Z,1578597466.358 [MassServo](DEBUG): Initializing EZServoServo.
2020-01-09T19:17:46.365Z,1578597466.365 [MassServo](DEBUG): Initializing MassServo.
2020-01-09T19:17:46.366Z,1578597466.366 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-09T19:17:46.373Z,1578597466.373 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-09T19:17:46.374Z,1578597466.374 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-01-09T19:17:46.381Z,1578597466.381 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-01-09T19:17:46.538Z,1578597466.538 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-01-09T19:17:46.538Z,1578597466.538 [DropWeight] Hardware Fault, FailCount= 1
2020-01-09T19:17:46.538Z,1578597466.538 [DropWeight](ERROR): Hardware Fault
2020-01-09T19:17:46.540Z,1578597466.540 [Micromodem](INFO): Powering up
2020-01-09T19:17:46.540Z,1578597466.540 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-09T19:17:46.622Z,1578597466.622 [CommandLine](FAULT): Scheduling is paused
2020-01-09T19:17:46.623Z,1578597466.623 [CBIT](INFO): Critical error at 20200109T191746
2020-01-09T19:17:46.623Z,1578597466.623 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-01-09T19:17:46.626Z,1578597466.626 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-01-09T19:17:46.626Z,1578597466.626 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-01-09T19:17:46.872Z,1578597466.872 [CBIT](INFO): Critical error at 20200109T191746
2020-01-09T19:17:51.341Z,1578597471.341 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-09T19:17:51.739Z,1578597471.739 [Micromodem](INFO): Nmea in: $CATMG,2020-01-09T19:22:46.493663Z,RTC,RTC*5F

2020-01-09T19:17:51.740Z,1578597471.740 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-09T19:22:46.493663Z,RTC,RTC*5F

2020-01-09T19:17:52.155Z,1578597472.155 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-09T19:17:52.156Z,1578597472.156 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-09T19:17:52.559Z,1578597472.559 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-01-09T19:17:52.559Z,1578597472.559 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-01-09T19:17:52.967Z,1578597472.967 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-01-09T19:17:52.967Z,1578597472.967 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-01-09T19:17:53.371Z,1578597473.371 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-01-09T19:17:53.371Z,1578597473.371 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-09T19:17:53.775Z,1578597473.775 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-09T19:17:53.775Z,1578597473.775 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-09T19:17:54.175Z,1578597474.175 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-09T19:17:54.175Z,1578597474.175 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-09T19:17:54.997Z,1578597474.997 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-09T19:17:54.998Z,1578597474.998 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-09T19:17:55.191Z,1578597475.191 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-09T19:17:55.192Z,1578597475.192 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,09,19,17,56*41

2020-01-09T19:17:55.559Z,1578597475.559 [Micromodem](INFO): Nmea in: $CACLK,2020,1,9,19,17,56*43

2020-01-09T19:17:56.378Z,1578597476.378 [Micromodem](INFO): Nmea in: $CATMG,2020-01-09T19:17:57.000090Z,USER_CMD,RTC*1C

2020-01-09T19:17:56.390Z,1578597476.390 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-09T19:17:57Z*72

2020-01-09T19:17:56.766Z,1578597476.766 [DDM](INFO): Dynamic Docking Module:
2020-01-09T19:17:56.879Z,1578597476.879 [Micromodem](INFO): Nmea in: $CATMG,2020-01-09T19:17:57.044923Z,USER_CMD,RTC*1D

2020-01-09T19:17:59.632Z,1578597479.632 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-09T19:18:04.050Z,1578597484.050 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-01-09T19:18:11.719Z,1578597491.719 [NAL9602](INFO): Powering up NAL9602
2020-01-09T19:18:15.792Z,1578597495.792 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-01-09T19:18:22.627Z,1578597502.627 [NAL9602](INFO): NAL9602 initialized
2020-01-09T19:18:25.087Z,1578597505.087 [SBIT](IMPORTANT): Beginning Startup BIT
2020-01-09T19:18:25.095Z,1578597505.095 [CBIT](IMPORTANT): Beginning ground fault scan
2020-01-09T19:18:36.272Z,1578597516.272 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.012899
CHAN A1 (24V): -0.028220
CHAN A2 (12V): -0.007268
CHAN A3 (5V): -0.002201
CHAN B0 (3.3V): 0.000014
CHAN B1 (3.15aV): -0.000190
CHAN B2 (3.15bV): -0.000135
CHAN B3 (GND): 0.002209
OPEN: 0.005230
Full Scale Calc: 4.765 mA, -1.589 mA
2020-01-09T19:18:36.910Z,1578597516.910 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2020-01-09T19:18:36.910Z,1578597516.910 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768-32768,V
2020-01-09T19:18:38.203Z,1578597518.203 [SBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:15.008821
2020-01-09T19:19:18.774Z,1578597558.774 [SBIT](CRITICAL): SBIT FAILED
2020-01-09T19:19:18.824Z,1578597558.824 [CommandLine](IMPORTANT): got command configSet list
2020-01-09T19:19:18.824Z,1578597558.824 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-01-09T19:19:18.825Z,1578597558.825 [CommandLine](IMPORTANT): Courier linearApproximation depth 5.000000 meter;

2020-01-09T19:19:18.826Z,1578597558.826 [CommandLine](IMPORTANT): Courier linearApproximation latitude 0.000500 angular_degree;

2020-01-09T19:19:18.826Z,1578597558.826 [CommandLine](IMPORTANT): Courier linearApproximation longitude 0.000500 angular_degree;

2020-01-09T19:19:18.826Z,1578597558.826 [CommandLine](IMPORTANT): DDM.loadAtStartup=1 bool;

2020-01-09T19:19:18.826Z,1578597558.826 [CommandLine](IMPORTANT): DDM.verbosity=3 count;

2020-01-09T19:19:18.826Z,1578597558.826 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=75 count;

2020-01-09T19:19:18.826Z,1578597558.826 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout=40 millisecond;

2020-01-09T19:19:18.826Z,1578597558.826 [CommandLine](IMPORTANT): Express linearApproximation DDM.DDMMode 1.000000 count;

2020-01-09T19:19:18.826Z,1578597558.826 [CommandLine](IMPORTANT): Express all DDM.cablePresent;

2020-01-09T19:19:18.826Z,1578597558.826 [CommandLine](IMPORTANT): Express linearApproximation DDM.latchState 1.000000 count;

2020-01-09T19:19:18.827Z,1578597558.827 [CommandLine](IMPORTANT): Express linearApproximation DDM.whiskerState 1.000000 count;

2020-01-09T19:19:18.827Z,1578597558.827 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.acoustic_contact_range 1.000000 meter;

2020-01-09T19:19:18.827Z,1578597558.827 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 2.000000 degree;

2020-01-09T19:19:18.827Z,1578597558.827 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.height_above_sea_floor 2.000000 meter;

2020-01-09T19:19:18.827Z,1578597558.827 [CommandLine](IMPORTANT): Express linearApproximation platform_orientation 5.000000 degree;

2020-01-09T19:19:18.827Z,1578597558.827 [CommandLine](IMPORTANT): Express linearApproximation platform_x_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-09T19:19:18.827Z,1578597558.827 [CommandLine](IMPORTANT): Express linearApproximation platform_y_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-09T19:19:18.827Z,1578597558.827 [CommandLine](IMPORTANT): HorizontalControl.kiHeading=0.002 reciprocal_second;

2020-01-09T19:19:18.827Z,1578597558.827 [CommandLine](IMPORTANT): HorizontalControl.kpHeading=0.8 none;

2020-01-09T19:19:18.828Z,1578597558.828 [CommandLine](IMPORTANT): HorizontalControl.rudDeadband=0.05 degree;

2020-01-09T19:19:18.828Z,1578597558.828 [CommandLine](IMPORTANT): Micromodem.destinationAddress=12 enum;

2020-01-09T19:19:18.828Z,1578597558.828 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold=0 meter;

2020-01-09T19:19:18.828Z,1578597558.828 [CommandLine](IMPORTANT): PowerOnly.sampleTime=90 second;

2020-01-09T19:19:18.828Z,1578597558.828 [CommandLine](IMPORTANT): RudderServo.deviationAngle=0.0001 degree;

2020-01-09T19:19:18.828Z,1578597558.828 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter;

2020-01-09T19:19:18.828Z,1578597558.828 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter;

2020-01-09T19:19:18.840Z,1578597558.840 [CBIT](INFO): Critical error at 20200109T191918
2020-01-09T19:19:19.176Z,1578597559.176 [MissionManager](IMPORTANT): Started mission Startup
2020-01-09T19:19:19.176Z,1578597559.176 [Startup] Running Loop=1
2020-01-09T19:19:19.176Z,1578597559.176 [Startup](DEBUG): Aggregate::initialize Startup
2020-01-09T19:19:19.177Z,1578597559.177 [Startup:A.GoToSurface] Running Loop=1
2020-01-09T19:19:19.177Z,1578597559.177 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-09T19:19:19.178Z,1578597559.178 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-09T19:19:19.178Z,1578597559.178 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-09T19:19:19.178Z,1578597559.178 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-09T19:19:19.179Z,1578597559.179 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-09T19:19:19.179Z,1578597559.179 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-09T19:19:19.180Z,1578597559.180 [Startup:StartupSatComms] Running Loop=1
2020-01-09T19:19:19.180Z,1578597559.180 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-01-09T19:19:19.181Z,1578597559.181 [Startup:StartupSatComms:A] Running Loop=1
2020-01-09T19:19:19.599Z,1578597559.599 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-01-09T19:19:28.060Z,1578597568.060 [RDI_Pathfinder](ERROR): Failed to parse:.00,   0.00,   0.00,   0.00,   0.00
2020-01-09T19:20:15.906Z,1578597615.906 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004640
2020-01-09T19:20:19.395Z,1578597619.395 [Startup:StartupSatComms:A](INFO): Timed out from 2020-01-09T19:19:19.2Z
2020-01-09T19:20:19.395Z,1578597619.395 [Startup:StartupSatComms:A] Stopped
2020-01-09T19:20:19.395Z,1578597619.395 [Startup:StartupSatComms:B] Running Loop=1
2020-01-09T19:20:19.771Z,1578597619.771 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-09T19:20:21.232Z,1578597621.232 [Micromodem](INFO): Outgoing frame #1, 55 bytes: 1C7C175E040000005D00000400200000000000000000151D80030C5669C08C34EE4BB8125EB91BD0AAE594CB9FC195C81D8C4ABDDAF5E0
2020-01-09T19:20:21.232Z,1578597621.232 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,1*6B

2020-01-09T19:20:22.243Z,1578597622.243 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,1*69

2020-01-09T19:20:22.283Z,1578597622.283 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-09T19:20:22.647Z,1578597622.647 [Micromodem](INFO): Nmea in: $CADRQ,192022,1,12,0,64,1*7E

2020-01-09T19:20:22.647Z,1578597622.647 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,1C7C175E040000005D00000400200000000000000000151D80030C5669C08C34EE4BB8125EB91BD0AAE594CB9FC195C81D8C4ABDDAF5E0*39

2020-01-09T19:20:23.047Z,1578597623.047 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,55*49

2020-01-09T19:20:23.463Z,1578597623.463 [Micromodem](INFO): Nmea in: $CATXP,55*72

2020-01-09T19:20:23.483Z,1578597623.483 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-09T19:20:24.663Z,1578597624.663 [Micromodem](INFO): Nmea in: $CATXF,55*64

2020-01-09T19:20:25.072Z,1578597625.072 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,192023.736894,3,0,200,4000,10000,1,1,12,1,1,1,3,55*44

2020-01-09T19:20:25.875Z,1578597625.875 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T19:20:25.876Z,1578597625.876 [Micromodem](INFO): Sent 47 bytes from file Logs/20200109T191644/Courier0004.lzma.parts
2020-01-09T19:20:25.876Z,1578597625.876 [Micromodem](INFO): Packets left to send: 0
2020-01-09T19:20:26.282Z,1578597626.282 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109192025.747893,06,748,28,0109,0150,89,02,02,00,03,1,012,001,0,4,1,0,150,20.0,6.00,13,-3.00,-01,-0.05,32,10000,4000*4E

2020-01-09T19:20:27.240Z,1578597627.240 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 4E7C175E000001005D000004005B0100000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6
2020-01-09T19:20:27.241Z,1578597627.241 [Micromodem](INFO): Outgoing frame #2, 64 bytes: AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE366FBE
2020-01-09T19:20:27.242Z,1578597627.242 [Micromodem](INFO): Outgoing frame #3, 64 bytes: B82DD336D0CDE1726E8D1FB7B10DE1314636F83CC18AE739188B373E90B9BB7833DA23B4D28AB88AD1B44DF3548AA029DBAC3588AF9FA0E70E523E4EB11B5B5B
2020-01-09T19:20:27.242Z,1578597627.242 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-09T19:20:28.076Z,1578597628.076 [DataOverHttps](INFO): Sending 255 bytes from file Logs/20200109T191734/Courier0000.lzma
2020-01-09T19:20:28.215Z,1578597628.215 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-09T19:20:28.622Z,1578597628.622 [Micromodem](INFO): Nmea in: $CADRQ,192028,1,12,0,64,1*74

2020-01-09T19:20:28.623Z,1578597628.623 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,4E7C175E000001005D000004005B0100000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6*48

2020-01-09T19:20:29.023Z,1578597629.023 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T19:20:29.427Z,1578597629.427 [Micromodem](INFO): Nmea in: $CADRQ,192029,1,12,0,64,2*76

2020-01-09T19:20:29.427Z,1578597629.427 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE366FBE*4A

2020-01-09T19:20:29.511Z,1578597629.511 [DataOverHttps](INFO): Moved sent file to Logs/20200109T191734/Courier0000.lzma.bak
2020-01-09T19:20:29.512Z,1578597629.512 [DataOverHttps](INFO): SBD MOMSN=12188100
2020-01-09T19:20:29.834Z,1578597629.834 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T19:20:30.231Z,1578597630.231 [Micromodem](INFO): Nmea in: $CADRQ,192030,1,12,0,64,3*7F

2020-01-09T19:20:30.231Z,1578597630.231 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,B82DD336D0CDE1726E8D1FB7B10DE1314636F83CC18AE739188B373E90B9BB7833DA23B4D28AB88AD1B44DF3548AA029DBAC3588AF9FA0E70E523E4EB11B5B5B*3B

2020-01-09T19:20:30.639Z,1578597630.639 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T19:20:31.053Z,1578597631.053 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-09T19:20:34.675Z,1578597634.675 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-09T19:20:35.080Z,1578597635.080 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,192031.356723,3,0,200,4000,10000,1,1,12,1,3,3,3,192*7C

2020-01-09T19:20:35.904Z,1578597635.904 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T19:20:36.299Z,1578597636.299 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T19:20:36.703Z,1578597636.703 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-09T19:20:36.704Z,1578597636.704 [Micromodem](INFO): Sent 184 bytes from file Logs/20200109T191734/Courier0000.lzma.parts
2020-01-09T19:20:36.704Z,1578597636.704 [Micromodem](INFO): Packets left to send: 1
2020-01-09T19:20:37.102Z,1578597637.102 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109192035.909569,06,242,23,0100,0150,59,02,02,00,04,1,012,001,0,4,1,0,150,26.2,6.00,16,-3.00,-01,-0.05,23,10000,4000*45

2020-01-09T19:20:38.031Z,1578597638.031 [Startup:StartupSatComms:B] Stopped
2020-01-09T19:20:38.032Z,1578597638.032 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-01-09T19:20:38.032Z,1578597638.032 [Startup:StartupSatComms] Stopped
2020-01-09T19:20:38.032Z,1578597638.032 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-01-09T19:20:38.032Z,1578597638.032 [Startup](INFO): Completed Startup
2020-01-09T19:20:38.033Z,1578597638.033 [MissionManager](INFO): Startup is completed.
2020-01-09T19:20:38.033Z,1578597638.033 [MissionManager](INFO): Uninitializing Mission Startup
2020-01-09T19:20:38.033Z,1578597638.033 [Startup] Stopped
2020-01-09T19:20:38.033Z,1578597638.033 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-01-09T19:20:38.033Z,1578597638.033 [Startup:A.GoToSurface] Stopped
2020-01-09T19:20:38.033Z,1578597638.033 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-09T19:20:38.174Z,1578597638.174 [MissionManager](IMPORTANT): Started mission Default
2020-01-09T19:20:38.174Z,1578597638.174 [Default] Running Loop=1
2020-01-09T19:20:38.174Z,1578597638.174 [Default](DEBUG): Aggregate::initialize Default
2020-01-09T19:20:38.174Z,1578597638.174 [Default:B.GoToSurface] Running Loop=1
2020-01-09T19:20:38.174Z,1578597638.174 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-09T19:20:38.175Z,1578597638.175 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-09T19:20:38.175Z,1578597638.175 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-09T19:20:38.175Z,1578597638.175 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-09T19:20:38.175Z,1578597638.175 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-09T19:20:38.176Z,1578597638.176 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-09T19:20:38.176Z,1578597638.176 [Default:A.Wait] Running Loop=1
2020-01-09T19:20:38.176Z,1578597638.176 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-09T19:20:45.876Z,1578597645.876 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-01-09T19:20:45.876Z,1578597645.876 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-09T19:20:45.892Z,1578597645.892 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-09T19:20:46.225Z,1578597646.225 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-09T19:20:46.225Z,1578597646.225 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-01-09T19:20:48.502Z,1578597648.502 [DataOverHttps](INFO): Sending 124 bytes from file Logs/20200109T005301/Express0005.lzma
2020-01-09T19:20:50.506Z,1578597650.506 [DataOverHttps](INFO): Moved sent file to Logs/20200109T005301/Express0005.lzma.bak
2020-01-09T19:20:50.507Z,1578597650.507 [DataOverHttps](INFO): SBD MOMSN=12188110
2020-01-09T19:20:51.571Z,1578597651.571 [Default:A.Wait](INFO): Done Waiting.
2020-01-09T19:20:51.571Z,1578597651.571 [Default:A.Wait] Stopped
2020-01-09T19:20:51.571Z,1578597651.571 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-09T19:20:51.925Z,1578597651.925 [Default:CheckIn] Running Loop=1
2020-01-09T19:20:51.925Z,1578597651.925 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-09T19:20:51.926Z,1578597651.926 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-09T19:20:52.355Z,1578597652.355 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-01-09T19:22:20.695Z,1578597740.695 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2020-01-09T19:22:39.718Z,1578597759.718 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-09T19:22:39.718Z,1578597759.718 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00, :BI,-32768,-32768,-32768,-32768,V
2020-01-09T19:23:26.151Z,1578597806.151 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-01-09T19:23:46.810Z,1578597826.810 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-01-09T19:23:46.810Z,1578597826.810 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-09T19:23:46.819Z,1578597826.819 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-09T19:23:47.223Z,1578597827.223 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-09T19:23:47.223Z,1578597827.223 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-01-09T19:24:19.533Z,1578597859.533 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-01-09T19:24:19.536Z,1578597859.536 [BPC1](INFO): Received data from all battery sticks.
2020-01-09T19:25:20.073Z,1578597920.073 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192520.00,A,3648.15418,N,12147.30531,W,0.758,85.19,090120,,,A*41
2020-01-09T19:25:20.076Z,1578597920.076 [NAL9602](INFO): GPS fix at 20200109T192520: (36.802570, -121.788422)
2020-01-09T19:25:20.104Z,1578597920.104 [Default:CheckIn:Read_GPS] Stopped
2020-01-09T19:25:20.137Z,1578597920.137 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-09T19:25:20.550Z,1578597920.550 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-09T19:25:21.855Z,1578597921.855 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 4E7C175E040001005D00000400640400000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A
2020-01-09T19:25:21.855Z,1578597921.855 [Micromodem](INFO): Outgoing frame #2, 64 bytes: EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C42BBF445E54CD5F36943A3BDD9E89309E9017DB79DD009A333A47CF42F3BE4E4AD4301D597F
2020-01-09T19:25:21.856Z,1578597921.856 [Micromodem](INFO): Outgoing frame #3, 64 bytes: FFA3EC7FA24353C087A3A1B3BD161F92F69707865251F3B93C9772C25CD67B52231335EF84B8E92D5FFA3505415BCA8B6F49DB1D0DD4D95B0AEBEF6F0AF82124
2020-01-09T19:25:21.856Z,1578597921.856 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-09T19:25:22.012Z,1578597922.012 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-09T19:25:22.403Z,1578597922.403 [Micromodem](INFO): Nmea in: $CADRQ,192522,1,12,0,64,1*7B

2020-01-09T19:25:22.403Z,1578597922.403 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,4E7C175E040001005D00000400640400000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A*38

2020-01-09T19:25:22.810Z,1578597922.810 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T19:25:23.214Z,1578597923.214 [Micromodem](INFO): Nmea in: $CADRQ,192523,1,12,0,64,2*79

2020-01-09T19:25:23.215Z,1578597923.215 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C42BBF445E54CD5F36943A3BDD9E89309E9017DB79DD009A333A47CF42F3BE4E4AD4301D597F*3D

2020-01-09T19:25:23.614Z,1578597923.614 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T19:25:24.018Z,1578597924.018 [Micromodem](INFO): Nmea in: $CADRQ,192524,1,12,0,64,3*7F

2020-01-09T19:25:24.019Z,1578597924.019 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,FFA3EC7FA24353C087A3A1B3BD161F92F69707865251F3B93C9772C25CD67B52231335EF84B8E92D5FFA3505415BCA8B6F49DB1D0DD4D95B0AEBEF6F0AF82124*40

2020-01-09T19:25:24.422Z,1578597924.422 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T19:25:24.826Z,1578597924.826 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-09T19:25:28.467Z,1578597928.467 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-09T19:25:28.868Z,1578597928.868 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,192525.144693,3,0,200,4000,10000,1,1,12,1,3,3,3,192*77

2020-01-09T19:25:30.087Z,1578597930.087 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T19:25:30.483Z,1578597930.483 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T19:25:30.896Z,1578597930.896 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-09T19:25:30.897Z,1578597930.897 [Micromodem](INFO): Sent 184 bytes from file Logs/20200109T191734/Courier0004.lzma.parts
2020-01-09T19:25:30.897Z,1578597930.897 [Micromodem](INFO): Packets left to send: 1
2020-01-09T19:25:31.315Z,1578597931.315 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109192529.700252,06,282,24,0100,0150,89,02,02,00,03,1,012,001,0,4,1,0,150,24.8,5.00,11,-2.00,-01,-0.05,27,10000,4000*4D

2020-01-09T19:25:31.394Z,1578597931.394 [DataOverHttps](INFO): Sending 356 bytes from file Logs/20200109T191734/Courier0004.lzma
2020-01-09T19:25:31.842Z,1578597931.842 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 4E7C175E040000009634E43A0572BBE93E5AC659EDEE9D13950B4C07B5D22B86F016B782E16CBDE60360C9517CE46AEDF0C42290FA9627CA7C5529C3DD8135C3
2020-01-09T19:25:31.842Z,1578597931.842 [Micromodem](INFO): Outgoing frame #2, 64 bytes: B9B673EE69750C27DBABB5F5A573A250DB21C0E979010CF9EC6AEE893B6BD4E5517C67BB1EA56595962F9DA36A1854F1E76CE04D14D21A5FF9C84991D0E5472C
2020-01-09T19:25:31.843Z,1578597931.843 [Micromodem](INFO): Outgoing frame #3, 52 bytes: 40DD836BC6E814225BE2002FE6AF1E03384776F6F8619FC84236F5FD0A632003540B0C6638BDD981009613F7F984E9233FA4B300
2020-01-09T19:25:31.843Z,1578597931.843 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-09T19:25:32.779Z,1578597932.779 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-09T19:25:33.190Z,1578597933.190 [Micromodem](INFO): Nmea in: $CADRQ,192532,1,12,0,64,1*7A

2020-01-09T19:25:33.191Z,1578597933.191 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,4E7C175E040000009634E43A0572BBE93E5AC659EDEE9D13950B4C07B5D22B86F016B782E16CBDE60360C9517CE46AEDF0C42290FA9627CA7C5529C3DD8135C3*3A

2020-01-09T19:25:33.398Z,1578597933.398 [DataOverHttps](INFO): Moved sent file to Logs/20200109T191734/Courier0004.lzma.bak
2020-01-09T19:25:33.398Z,1578597933.398 [DataOverHttps](INFO): SBD MOMSN=12188117
2020-01-09T19:25:33.587Z,1578597933.587 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T19:25:33.991Z,1578597933.991 [Micromodem](INFO): Nmea in: $CADRQ,192534,1,12,0,64,2*7F

2020-01-09T19:25:33.991Z,1578597933.991 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,B9B673EE69750C27DBABB5F5A573A250DB21C0E979010CF9EC6AEE893B6BD4E5517C67BB1EA56595962F9DA36A1854F1E76CE04D14D21A5FF9C84991D0E5472C*47

2020-01-09T19:25:34.439Z,1578597934.439 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T19:25:34.799Z,1578597934.799 [Micromodem](INFO): Nmea in: $CADRQ,192535,1,12,0,64,3*7F

2020-01-09T19:25:34.799Z,1578597934.799 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,40DD836BC6E814225BE2002FE6AF1E03384776F6F8619FC84236F5FD0A632003540B0C6638BDD981009613F7F984E9233FA4B300*33

2020-01-09T19:25:35.206Z,1578597935.206 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,52*4E

2020-01-09T19:25:35.606Z,1578597935.606 [Micromodem](INFO): Nmea in: $CATXP,180*4B

2020-01-09T19:25:39.242Z,1578597939.242 [Micromodem](INFO): Nmea in: $CATXF,180*5D

2020-01-09T19:25:39.651Z,1578597939.651 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,192535.914599,3,0,200,4000,10000,1,1,12,1,3,3,3,180*71

2020-01-09T19:25:40.487Z,1578597940.487 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T19:25:40.863Z,1578597940.863 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T19:25:41.271Z,1578597941.271 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-09T19:25:41.271Z,1578597941.271 [Micromodem](INFO): Sent 172 bytes from file Logs/20200109T191734/Courier0004.lzma.parts
2020-01-09T19:25:41.271Z,1578597941.271 [Micromodem](INFO): Packets left to send: 0
2020-01-09T19:25:41.674Z,1578597941.674 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109192540.458336,06,852,29,0126,0150,89,02,02,00,03,1,012,001,0,4,1,0,150,25.7,7.00,17,-4.00,-01,-0.03,27,10000,4000*4B

2020-01-09T19:25:43.161Z,1578597943.161 [Default:CheckIn:Read_Iridium] Stopped
2020-01-09T19:25:43.161Z,1578597943.161 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-09T19:25:43.161Z,1578597943.161 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-09T19:25:52.098Z,1578597952.098 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-09T19:25:54.108Z,1578597954.108 [DataOverHttps](INFO): Sending 1373 bytes from file Logs/20200109T010332/Express0001.lzma
2020-01-09T19:25:56.106Z,1578597956.106 [DataOverHttps](INFO): Moved sent file to Logs/20200109T010332/Express0001.lzma.bak
2020-01-09T19:25:56.106Z,1578597956.106 [DataOverHttps](INFO): SBD MOMSN=12188120
2020-01-09T19:29:34.355Z,1578598174.355 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-09T19:29:34.356Z,1578598174.356 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010912422472,31,   0.0,1448.9,  0
2020-01-09T19:30:12.328Z,1578598212.328 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-09T19:30:12.328Z,1578598212.328 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010912430, -0.1,   0.0,1448.9,  0
2020-01-09T19:30:43.426Z,1578598243.426 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-09T19:30:43.426Z,1578598243.426 [Default:CheckIn:C.Wait] Stopped
2020-01-09T19:30:43.427Z,1578598243.427 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-09T19:30:43.427Z,1578598243.427 [Default:CheckIn:D] Running Loop=1
2020-01-09T19:30:43.833Z,1578598243.833 [Default:CheckIn:D] Stopped
2020-01-09T19:30:43.833Z,1578598243.833 [Default:CheckIn:E] Running Loop=1
2020-01-09T19:30:44.218Z,1578598244.218 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.094316 min 
2020-01-09T19:30:44.218Z,1578598244.218 [Default:CheckIn:E] Stopped
2020-01-09T19:30:44.219Z,1578598244.219 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-09T19:30:44.219Z,1578598244.219 [Default:CheckIn] Stopped
2020-01-09T19:30:44.219Z,1578598244.219 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-09T19:30:44.219Z,1578598244.219 [Default:CheckIn](INFO): Running loop #2
2020-01-09T19:30:44.219Z,1578598244.219 [Default:CheckIn] Running Loop=2
2020-01-09T19:30:44.219Z,1578598244.219 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-09T19:30:44.219Z,1578598244.219 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-09T19:30:48.687Z,1578598248.687 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-09T19:30:48.689Z,1578598248.689 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010912433872,35.0, -0.1, 48.9,  0
2020-01-09T19:31:13.710Z,1578598273.710 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2020-01-09T19:31:16.919Z,1578598276.919 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-01-09T19:31:17.001Z,1578598277.001 [NAL9602](FAULT): received: 
+CSQ:0

OK
2020-01-09T19:31:17.001Z,1578598277.001 [NAL9602] Data Fault, FailCount= 1
2020-01-09T19:31:17.001Z,1578598277.001 [NAL9602](ERROR): Data Fault
2020-01-09T19:31:17.101Z,1578598277.101 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-09T19:31:17.323Z,1578598277.323 [NAL9602](INFO): Powering down
2020-01-09T19:31:18.185Z,1578598278.185 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-09T19:31:18.185Z,1578598278.185 [NAL9602] No Fault, FailCount= 1
2020-01-09T19:31:47.627Z,1578598307.627 [NAL9602](INFO): Powering up NAL9602
2020-01-09T19:31:58.525Z,1578598318.525 [NAL9602](INFO): NAL9602 initialized
2020-01-09T19:32:05.002Z,1578598325.002 [Micromodem](INFO): Nmea in: $CACYC,1,12,1,1,0,1*68

2020-01-09T19:32:05.002Z,1578598325.002 [Micromodem](INFO): Have incoming data
2020-01-09T19:32:05.005Z,1578598325.005 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,1,3D90B8D172D0E11A94BB9A0D6EC0434A2AB488F6*51

2020-01-09T19:32:05.006Z,1578598325.006 [Micromodem](INFO): Deserialize. FrameCount:1
2020-01-09T19:32:05.006Z,1578598325.006 [Micromodem](INFO): Outer loop. Size:20
2020-01-09T19:32:05.006Z,1578598325.006 [Micromodem](INFO): Inner loop. C:61
2020-01-09T19:32:05.006Z,1578598325.006 [Micromodem](INFO): Inner loop. C:144
2020-01-09T19:32:05.006Z,1578598325.006 [Micromodem](INFO): Inner loop. C:184
2020-01-09T19:32:05.006Z,1578598325.006 [Micromodem](INFO): Inner loop. C:209
2020-01-09T19:32:05.007Z,1578598325.007 [Micromodem](INFO): Inner loop. C:114
2020-01-09T19:32:05.007Z,1578598325.007 [Micromodem](INFO): Inner loop. C:208
2020-01-09T19:32:05.007Z,1578598325.007 [Micromodem](INFO): Inner loop. C:225
2020-01-09T19:32:05.007Z,1578598325.007 [Micromodem](INFO): Inner loop. C:26
2020-01-09T19:32:05.007Z,1578598325.007 [Micromodem](INFO): Inner loop. C:148
2020-01-09T19:32:05.007Z,1578598325.007 [Micromodem](INFO): Inner loop. C:187
2020-01-09T19:32:05.008Z,1578598325.008 [Micromodem](INFO): Inner loop. C:154
2020-01-09T19:32:05.008Z,1578598325.008 [Micromodem](INFO): Inner loop. C:13
2020-01-09T19:32:05.008Z,1578598325.008 [Micromodem](INFO): Inner loop. C:110
2020-01-09T19:32:05.008Z,1578598325.008 [Micromodem](INFO): Inner loop. C:192
2020-01-09T19:32:05.008Z,1578598325.008 [Micromodem](INFO): Inner loop. C:67
2020-01-09T19:32:05.009Z,1578598325.009 [Micromodem](INFO): Inner loop. C:74
2020-01-09T19:32:05.009Z,1578598325.009 [Micromodem](INFO): Inner loop. C:42
2020-01-09T19:32:05.010Z,1578598325.010 [Micromodem](INFO): Inner loop. C:180
2020-01-09T19:32:05.010Z,1578598325.010 [Micromodem](INFO): Inner loop. C:136
2020-01-09T19:32:05.010Z,1578598325.010 [Micromodem](INFO): Inner loop. C:246
2020-01-09T19:32:05.011Z,1578598325.011 [Micromodem](INFO): Received command:stop
2020-01-09T19:32:05.012Z,1578598325.012 [Micromodem](CRITICAL): 1
2020-01-09T19:32:05.014Z,1578598325.014 [Micromodem](CRITICAL): Failed to parse uplink message:3D90B8D172D0E11A94BB9A0D6EC0434A2AB488F6
2020-01-09T19:32:05.085Z,1578598325.085 [CommandLine](IMPORTANT): got command stop
2020-01-09T19:32:05.085Z,1578598325.085 [CommandLine](IMPORTANT): Scheduling is paused
2020-01-09T19:32:05.110Z,1578598325.110 [CommandLine](FAULT): Scheduling is paused
2020-01-09T19:32:05.111Z,1578598325.111 [CBIT](INFO): Critical error at 20200109T193205
2020-01-09T19:32:05.416Z,1578598325.416 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109193203.990321,06,1961,32,0164,0150,59,02,02,00,04,1,012,001,0,3,1,0,150,4.9,8.00,00,-5.00,-01,-0.02,44,10000,4000*45

2020-01-09T19:32:46.761Z,1578598366.761 [CBIT](INFO): Clearing failed state for component DropWeight
2020-01-09T19:32:46.762Z,1578598366.762 [DropWeight] No Fault, FailCount= 1
2020-01-09T19:33:13.708Z,1578598393.708 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-09T19:33:13.708Z,1578598393.708 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010912460372,35.0, -0.11448.9,  0
2020-01-09T19:35:44.402Z,1578598544.402 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-09T19:30:44.2Z
2020-01-09T19:35:44.402Z,1578598544.402 [Default:CheckIn:Read_GPS] Stopped
2020-01-09T19:35:44.402Z,1578598544.402 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-09T19:35:45.738Z,1578598545.738 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 4E7C175E070000005D00000400AD0000000000000000151D80030C5669BF31555EAE1409A708CE2489B190EA4D9D68DCD8D7AAA11AD9F0ED77F7C27FB6E9EF60
2020-01-09T19:35:45.738Z,1578598545.738 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 1DC81E3031BC4C83F1D91466A0F571B4944A911A3861FAF36604308093177C3A6693AAC0748045563A5E4BF603054134A60B733ED0F38870DAED3A9311E5D803
2020-01-09T19:35:45.739Z,1578598545.739 [Micromodem](INFO): Outgoing frame #3, 55 bytes: A750355B5B3E5920CCC619557A3A8D88D69AE98AAF088DBB0804383C880C43669B9DA46DBEC9EEBEA3BDE97E05CF8A629F9D8B5280C900
2020-01-09T19:35:45.739Z,1578598545.739 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-09T19:35:46.315Z,1578598546.315 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-09T19:35:46.719Z,1578598546.719 [Micromodem](INFO): Nmea in: $CADRQ,193546,1,12,0,64,1*78

2020-01-09T19:35:46.719Z,1578598546.719 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,4E7C175E070000005D00000400AD0000000000000000151D80030C5669BF31555EAE1409A708CE2489B190EA4D9D68DCD8D7AAA11AD9F0ED77F7C27FB6E9EF60*48

2020-01-09T19:35:47.115Z,1578598547.115 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T19:35:47.523Z,1578598547.523 [Micromodem](INFO): Nmea in: $CADRQ,193547,1,12,0,64,2*7A

2020-01-09T19:35:47.523Z,1578598547.523 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,1DC81E3031BC4C83F1D91466A0F571B4944A911A3861FAF36604308093177C3A6693AAC0748045563A5E4BF603054134A60B733ED0F38870DAED3A9311E5D803*39

2020-01-09T19:35:47.923Z,1578598547.923 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T19:35:48.327Z,1578598548.327 [Micromodem](INFO): Nmea in: $CADRQ,193548,1,12,0,64,3*74

2020-01-09T19:35:48.327Z,1578598548.327 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,A750355B5B3E5920CCC619557A3A8D88D69AE98AAF088DBB0804383C880C43669B9DA46DBEC9EEBEA3BDE97E05CF8A629F9D8B5280C900*4D

2020-01-09T19:35:48.731Z,1578598548.731 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,55*49

2020-01-09T19:35:48.732Z,1578598548.732 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-01-09T19:35:49.138Z,1578598549.138 [Micromodem](INFO): Nmea in: $CATXP,183*48

2020-01-09T19:35:52.781Z,1578598552.781 [Micromodem](INFO): Nmea in: $CATXF,183*5E

2020-01-09T19:35:52.808Z,1578598552.808 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20200109T191734/Courier0007.lzma
2020-01-09T19:35:53.180Z,1578598553.180 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,193549.446837,3,0,200,4000,10000,1,1,12,1,3,3,3,183*7B

2020-01-09T19:35:53.995Z,1578598553.995 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T19:35:54.391Z,1578598554.391 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T19:35:54.795Z,1578598554.795 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-09T19:35:54.795Z,1578598554.795 [Micromodem](INFO): Sent 175 bytes from file Logs/20200109T191734/Courier0007.lzma.parts
2020-01-09T19:35:54.796Z,1578598554.796 [Micromodem](INFO): Packets left to send: 0
2020-01-09T19:35:55.198Z,1578598555.198 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109193553.994121,06,556,27,0091,0150,89,02,02,00,03,1,012,001,0,4,1,0,150,-2.0,6.00,19,-3.00,-01,-0.05,49,10000,4000*52

2020-01-09T19:35:56.179Z,1578598556.179 [Default:CheckIn:Read_Iridium] Stopped
2020-01-09T19:35:56.179Z,1578598556.179 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-09T19:35:56.179Z,1578598556.179 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-09T19:35:56.399Z,1578598556.399 [DataOverHttps](FAULT): Server acknowledged file Logs/20200109T191734/Courier0007.lzma, notLogs/20200109T010332/Express0005.lzma
2020-01-09T19:35:56.399Z,1578598556.399 [DataOverHttps](INFO): SBD MOMSN=12188169
2020-01-09T19:36:19.258Z,1578598579.258 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-09T19:36:39.150Z,1578598599.150 [DataOverHttps](INFO): Sending 239 bytes from file Logs/20200109T010332/Express0005.lzma
2020-01-09T19:36:41.154Z,1578598601.154 [DataOverHttps](INFO): Moved sent file to Logs/20200109T010332/Express0005.lzma.bak
2020-01-09T19:36:41.154Z,1578598601.154 [DataOverHttps](INFO): SBD MOMSN=12188175
2020-01-09T19:38:42.696Z,1578598722.696 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-09T19:38:42.696Z,1578598722.696 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010912513272,35.0, -0.,1448.9,  0
2020-01-09T19:38:58.054Z,1578598738.054 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-09T19:38:58.054Z,1578598738.054 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200109125.0, -0.1,   0.0,1448.9,  0
2020-01-09T19:39:18.694Z,1578598758.694 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-09T19:39:18.694Z,1578598758.694 [RDI_Pathfinder](ERROR): Failed to parse:
:TS

2020-01-09T19:40:56.445Z,1578598856.445 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-09T19:40:56.445Z,1578598856.445 [Default:CheckIn:C.Wait] Stopped
2020-01-09T19:40:56.445Z,1578598856.445 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-09T19:40:56.445Z,1578598856.445 [Default:CheckIn:D] Running Loop=1
2020-01-09T19:40:56.841Z,1578598856.841 [Default:CheckIn:D] Stopped
2020-01-09T19:40:56.842Z,1578598856.842 [Default:CheckIn:E] Running Loop=1
2020-01-09T19:40:57.235Z,1578598857.235 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.311125 min 
2020-01-09T19:40:57.236Z,1578598857.236 [Default:CheckIn:E] Stopped
2020-01-09T19:40:57.236Z,1578598857.236 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-09T19:40:57.236Z,1578598857.236 [Default:CheckIn] Stopped
2020-01-09T19:40:57.236Z,1578598857.236 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-09T19:40:57.236Z,1578598857.236 [Default:CheckIn](INFO): Running loop #3
2020-01-09T19:40:57.236Z,1578598857.236 [Default:CheckIn] Running Loop=3
2020-01-09T19:40:57.236Z,1578598857.236 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-09T19:40:57.236Z,1578598857.236 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-09T19:40:58.820Z,1578598858.820 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-01-09T19:40:58.820Z,1578598858.820 [NAL9602] Data Fault, FailCount= 2
2020-01-09T19:40:58.820Z,1578598858.820 [NAL9602](ERROR): Data Fault
2020-01-09T19:40:58.899Z,1578598858.899 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-09T19:40:59.223Z,1578598859.223 [NAL9602](INFO): Powering down
2020-01-09T19:41:00.077Z,1578598860.077 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-09T19:41:00.077Z,1578598860.077 [NAL9602] No Fault, FailCount= 2
2020-01-09T19:41:29.523Z,1578598889.523 [NAL9602](INFO): Powering up NAL9602
2020-01-09T19:41:40.431Z,1578598900.431 [NAL9602](INFO): NAL9602 initialized
2020-01-09T19:43:22.676Z,1578599002.676 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-09T19:43:22.676Z,1578599002.676 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200109125612 -0.1,   0.0,1448.9,  0
2020-01-09T19:45:57.426Z,1578599157.426 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-09T19:40:57.2Z
2020-01-09T19:45:57.426Z,1578599157.426 [Default:CheckIn:Read_GPS] Stopped
2020-01-09T19:45:57.426Z,1578599157.426 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-09T19:45:58.994Z,1578599158.994 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 4E7C175E0A0000005D00000400320000000000000000151D80030C5669C08C34EE459C2AE7BF881F1AEAD58265D372C09EEC5444DFFFC36294CA89D82F906899
2020-01-09T19:45:58.995Z,1578599158.995 [Micromodem](INFO): Outgoing frame #2, 6 bytes: 180C116A9100
2020-01-09T19:45:58.995Z,1578599158.995 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,2*68

2020-01-09T19:45:59.156Z,1578599159.156 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,2*6A

2020-01-09T19:45:59.535Z,1578599159.535 [Micromodem](INFO): Nmea in: $CADRQ,194600,1,12,0,64,1*7E

2020-01-09T19:45:59.535Z,1578599159.535 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,4E7C175E0A0000005D00000400320000000000000000151D80030C5669C08C34EE459C2AE7BF881F1AEAD58265D372C09EEC5444DFFFC36294CA89D82F906899*3F

2020-01-09T19:45:59.939Z,1578599159.939 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T19:46:00.351Z,1578599160.351 [Micromodem](INFO): Nmea in: $CADRQ,194600,1,12,0,64,2*7D

2020-01-09T19:46:00.351Z,1578599160.351 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,180C116A9100*4E

2020-01-09T19:46:00.747Z,1578599160.747 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,6*7F

2020-01-09T19:46:01.150Z,1578599161.150 [Micromodem](INFO): Nmea in: $CATXP,70*75

2020-01-09T19:46:03.574Z,1578599163.574 [Micromodem](INFO): Nmea in: $CATXF,70*63

2020-01-09T19:46:03.976Z,1578599163.976 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,194601.401294,3,0,200,4000,10000,1,1,12,1,2,2,3,70*4E

2020-01-09T19:46:04.795Z,1578599164.795 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T19:46:04.860Z,1578599164.860 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20200109T191734/Courier0010.lzma
2020-01-09T19:46:05.187Z,1578599165.187 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T19:46:05.188Z,1578599165.188 [Micromodem](INFO): Sent 62 bytes from file Logs/20200109T191734/Courier0010.lzma.parts
2020-01-09T19:46:05.188Z,1578599165.188 [Micromodem](INFO): Packets left to send: 0
2020-01-09T19:46:05.598Z,1578599165.598 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109194604.629043,06,753,28,0103,0150,89,02,02,00,03,1,012,001,0,4,1,0,150,25.7,6.00,14,-3.00,-01,-0.04,27,10000,4000*41

2020-01-09T19:46:06.576Z,1578599166.576 [Default:CheckIn:Read_Iridium] Stopped
2020-01-09T19:46:06.577Z,1578599166.577 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-09T19:46:06.577Z,1578599166.577 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-09T19:46:07.390Z,1578599167.390 [DataOverHttps](FAULT): Server acknowledged file Logs/20200109T191734/Courier0010.lzma, notLogs/20200109T010332/Express0008.lzma
2020-01-09T19:46:07.391Z,1578599167.391 [DataOverHttps](INFO): SBD MOMSN=12188185
2020-01-09T19:46:12.335Z,1578599172.335 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-09T19:46:12.335Z,1578599172.335 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-09T19:46:31.689Z,1578599191.689 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-09T19:46:31.689Z,1578599191.689 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,2001091259217-0.1,   0.0,1448.9,  0
2020-01-09T19:46:33.342Z,1578599193.342 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-09T19:46:33.342Z,1578599193.342 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.00 +0.00,   0.00,1713.87
2020-01-09T19:46:43.795Z,1578599203.795 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-01-09T19:46:52.295Z,1578599212.295 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20200109T010332/Express0008.lzma
2020-01-09T19:46:54.290Z,1578599214.290 [DataOverHttps](INFO): Moved sent file to Logs/20200109T010332/Express0008.lzma.bak
2020-01-09T19:46:54.291Z,1578599214.291 [DataOverHttps](INFO): SBD MOMSN=12188187
2020-01-09T19:47:14.495Z,1578599234.495 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-09T19:51:06.856Z,1578599466.856 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-09T19:51:06.856Z,1578599466.856 [Default:CheckIn:C.Wait] Stopped
2020-01-09T19:51:06.856Z,1578599466.856 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-09T19:51:06.856Z,1578599466.856 [Default:CheckIn:D] Running Loop=1
2020-01-09T19:51:07.227Z,1578599467.227 [Default:CheckIn:D] Stopped
2020-01-09T19:51:07.227Z,1578599467.227 [Default:CheckIn:E] Running Loop=1
2020-01-09T19:51:07.673Z,1578599467.673 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.484222 min 
2020-01-09T19:51:07.673Z,1578599467.673 [Default:CheckIn:E] Stopped
2020-01-09T19:51:07.674Z,1578599467.674 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-09T19:51:07.674Z,1578599467.674 [Default:CheckIn] Stopped
2020-01-09T19:51:07.674Z,1578599467.674 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-09T19:51:07.674Z,1578599467.674 [Default:CheckIn](INFO): Running loop #4
2020-01-09T19:51:07.674Z,1578599467.674 [Default:CheckIn] Running Loop=4
2020-01-09T19:51:07.675Z,1578599467.675 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-09T19:51:07.675Z,1578599467.675 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-09T19:51:13.328Z,1578599473.328 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-09T19:51:13.328Z,1578599473.328 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010913040372,35.0, 00
2020-01-09T19:51:40.334Z,1578599500.334 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-01-09T19:51:40.413Z,1578599500.413 [NAL9602](FAULT): received: 
+CSQ:0

OK
2020-01-09T19:51:40.413Z,1578599500.413 [NAL9602] Data Fault, FailCount= 3
2020-01-09T19:51:40.413Z,1578599500.413 [NAL9602](ERROR): Data Fault
2020-01-09T19:51:40.456Z,1578599500.456 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-09T19:51:40.736Z,1578599500.736 [NAL9602](INFO): Powering down
2020-01-09T19:51:41.594Z,1578599501.594 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-09T19:51:41.594Z,1578599501.594 [NAL9602] No Fault, FailCount= 3
2020-01-09T19:52:11.031Z,1578599531.031 [NAL9602](INFO): Powering up NAL9602
2020-01-09T19:52:21.943Z,1578599541.943 [NAL9602](INFO): NAL9602 initialized
2020-01-09T19:52:22.751Z,1578599542.751 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-01-09T19:52:22.751Z,1578599542.751 [NAL9602] Data Fault, FailCount= 4
2020-01-09T19:52:22.751Z,1578599542.751 [NAL9602](ERROR): Data Fault
2020-01-09T19:52:22.784Z,1578599542.784 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-09T19:52:23.161Z,1578599543.161 [NAL9602](INFO): Powering down
2020-01-09T19:52:24.025Z,1578599544.025 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-09T19:52:24.025Z,1578599544.025 [NAL9602] No Fault, FailCount= 4
2020-01-09T19:52:53.463Z,1578599573.463 [NAL9602](INFO): Powering up NAL9602
2020-01-09T19:53:04.377Z,1578599584.377 [NAL9602](INFO): NAL9602 initialized
2020-01-09T19:56:07.824Z,1578599767.824 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-09T19:51:07.7Z
2020-01-09T19:56:07.824Z,1578599767.824 [Default:CheckIn:Read_GPS] Stopped
2020-01-09T19:56:07.824Z,1578599767.824 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-09T19:56:09.272Z,1578599769.272 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 4E7C175E0D0000005D00000400320000000000000000151D80030C5669C08C34EE73FCBACB57A89CDE08C26F94470E333C769928E0FAF0CEDE583A0E76B11991
2020-01-09T19:56:09.273Z,1578599769.273 [Micromodem](INFO): Outgoing frame #2, 6 bytes: CBEC95F66000
2020-01-09T19:56:09.274Z,1578599769.274 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,2*68

2020-01-09T19:56:09.507Z,1578599769.507 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,2*6A

2020-01-09T19:56:09.863Z,1578599769.863 [Micromodem](INFO): Nmea in: $CADRQ,195610,1,12,0,64,1*7E

2020-01-09T19:56:09.863Z,1578599769.863 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,4E7C175E0D0000005D00000400320000000000000000151D80030C5669C08C34EE73FCBACB57A89CDE08C26F94470E333C769928E0FAF0CEDE583A0E76B11991*43

2020-01-09T19:56:10.261Z,1578599770.261 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T19:56:10.668Z,1578599770.668 [Micromodem](INFO): Nmea in: $CADRQ,195610,1,12,0,64,2*7D

2020-01-09T19:56:10.668Z,1578599770.668 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,CBEC95F66000*36

2020-01-09T19:56:11.063Z,1578599771.063 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,6*7F

2020-01-09T19:56:11.467Z,1578599771.467 [Micromodem](INFO): Nmea in: $CATXP,70*75

2020-01-09T19:56:11.468Z,1578599771.468 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-01-09T19:56:13.887Z,1578599773.887 [Micromodem](INFO): Nmea in: $CATXF,70*63

2020-01-09T19:56:14.298Z,1578599774.298 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,195611.717747,3,0,200,4000,10000,1,1,12,1,2,2,3,70*41

2020-01-09T19:56:15.099Z,1578599775.099 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T19:56:15.507Z,1578599775.507 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T19:56:15.508Z,1578599775.508 [Micromodem](INFO): Sent 62 bytes from file Logs/20200109T191734/Courier0013.lzma.parts
2020-01-09T19:56:15.508Z,1578599775.508 [Micromodem](INFO): Packets left to send: 0
2020-01-09T19:56:15.909Z,1578599775.909 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109195614.943636,06,612,27,0095,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,25.2,7.00,17,-4.00,-01,-0.07,23,10000,4000*49

2020-01-09T19:56:16.852Z,1578599776.852 [Default:CheckIn:Read_Iridium] Stopped
2020-01-09T19:56:16.852Z,1578599776.852 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-09T19:56:16.852Z,1578599776.852 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-09T19:56:17.584Z,1578599777.584 [DataOverHttps](INFO): Sending 104 bytes from file Logs/20200109T010332/Express0011.lzma
2020-01-09T19:56:19.610Z,1578599779.610 [DataOverHttps](INFO): Moved sent file to Logs/20200109T010332/Express0011.lzma.bak
2020-01-09T19:56:19.610Z,1578599779.610 [DataOverHttps](INFO): SBD MOMSN=12188198
2020-01-09T19:56:29.465Z,1578599789.465 [RDI_Pathfinder](ERROR): Failed to parse:
    +0.00,        +0.00,        +0.00,   0.00,2308.77
2020-01-09T19:56:41.958Z,1578599801.958 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-09T19:57:28.028Z,1578599848.028 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-09T19:57:28.028Z,1578599848.028 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-09T19:57:32.484Z,1578599852.484 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-09T19:59:49.028Z,1578599989.028 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-09T19:59:49.028Z,1578599989.028 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-09T20:01:17.111Z,1578600077.111 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-09T20:01:17.111Z,1578600077.111 [Default:CheckIn:C.Wait] Stopped
2020-01-09T20:01:17.111Z,1578600077.111 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-09T20:01:17.111Z,1578600077.111 [Default:CheckIn:D] Running Loop=1
2020-01-09T20:01:17.517Z,1578600077.517 [Default:CheckIn:D] Stopped
2020-01-09T20:01:17.517Z,1578600077.517 [Default:CheckIn:E] Running Loop=1
2020-01-09T20:01:17.938Z,1578600077.938 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.655717 min 
2020-01-09T20:01:17.938Z,1578600077.938 [Default:CheckIn:E] Stopped
2020-01-09T20:01:17.938Z,1578600077.938 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-09T20:01:17.938Z,1578600077.938 [Default:CheckIn] Stopped
2020-01-09T20:01:17.938Z,1578600077.938 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-09T20:01:17.939Z,1578600077.939 [Default:CheckIn](INFO): Running loop #5
2020-01-09T20:01:17.939Z,1578600077.939 [Default:CheckIn] Running Loop=5
2020-01-09T20:01:17.939Z,1578600077.939 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-09T20:01:17.939Z,1578600077.939 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-09T20:01:50.615Z,1578600110.615 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-01-09T20:01:50.689Z,1578600110.689 [NAL9602](FAULT): received: 
+CSQ:0

OK
2020-01-09T20:01:50.689Z,1578600110.689 [NAL9602] Data Fault, FailCount= 5
2020-01-09T20:01:50.689Z,1578600110.689 [NAL9602](ERROR): Data Fault
2020-01-09T20:01:50.732Z,1578600110.732 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-09T20:01:50.732Z,1578600110.732 [CBIT](CRITICAL): Data Fault in component: NAL9602
2020-01-09T20:01:51.023Z,1578600111.023 [NAL9602](INFO): Powering down
2020-01-09T20:01:51.070Z,1578600111.070 [CBIT](INFO): Critical error at 20200109T200150
2020-01-09T20:02:47.986Z,1578600167.986 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-01-09T20:02:47.986Z,1578600167.986 [DropWeight] Hardware Fault, FailCount= 1
2020-01-09T20:02:47.986Z,1578600167.986 [DropWeight](ERROR): Hardware Fault
2020-01-09T20:02:48.101Z,1578600168.101 [CBIT](INFO): Critical error at 20200109T200247
2020-01-09T20:02:48.104Z,1578600168.104 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-01-09T20:02:48.104Z,1578600168.104 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-01-09T20:02:48.443Z,1578600168.443 [CBIT](INFO): Critical error at 20200109T200248
2020-01-09T20:03:51.053Z,1578600231.053 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-09T20:03:51.054Z,1578600231.054 [NAL9602] No Fault, FailCount= 5
2020-01-09T20:03:51.415Z,1578600231.415 [NAL9602](INFO): Powering up NAL9602
2020-01-09T20:04:02.331Z,1578600242.331 [NAL9602](INFO): NAL9602 initialized
2020-01-09T20:04:03.138Z,1578600243.138 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-01-09T20:04:03.138Z,1578600243.138 [NAL9602] Data Fault, FailCount= 1
2020-01-09T20:04:03.138Z,1578600243.138 [NAL9602](ERROR): Data Fault
2020-01-09T20:04:03.197Z,1578600243.197 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-09T20:04:03.535Z,1578600243.535 [NAL9602](INFO): Powering down
2020-01-09T20:04:04.403Z,1578600244.403 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-09T20:04:04.403Z,1578600244.403 [NAL9602] No Fault, FailCount= 1
2020-01-09T20:04:33.839Z,1578600273.839 [NAL9602](INFO): Powering up NAL9602
2020-01-09T20:04:44.747Z,1578600284.747 [NAL9602](INFO): NAL9602 initialized
2020-01-09T20:05:36.864Z,1578600336.864 [NAL9602](INFO): SBD MO Status=2, MOMSN=10151, MT Status=2, MTMSN=0
2020-01-09T20:05:36.864Z,1578600336.864 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-09T20:06:18.102Z,1578600378.102 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-09T20:01:17.9Z
2020-01-09T20:06:18.102Z,1578600378.102 [Default:CheckIn:Read_GPS] Stopped
2020-01-09T20:06:18.102Z,1578600378.102 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-09T20:06:19.664Z,1578600379.664 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 4E7C175E100000005D00000400AC0000000000000000151D80030C5669C074ACE9145BC9266A43C331F06FB3E7BA3AEC8E5E645033D89F8DAF37BB5F7B94E853
2020-01-09T20:06:19.664Z,1578600379.664 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 2E479F5C86EA69D552D0DB45E8A131CBF3E2A39A24C3EB9C6B6E9340EA35A29E15B36AEA259C5B29AE652FD880C12B855E60C980DF916499FF5F855583501C33
2020-01-09T20:06:19.664Z,1578600379.664 [Micromodem](INFO): Outgoing frame #3, 40 bytes: D118F399C512A1682E454AE4525014C99413A1F1CE7930418498A16237B415EC2199DF277C652400
2020-01-09T20:06:19.669Z,1578600379.669 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-09T20:06:19.863Z,1578600379.863 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-09T20:06:20.246Z,1578600380.246 [Micromodem](INFO): Nmea in: $CADRQ,200620,1,12,0,64,1*72

2020-01-09T20:06:20.247Z,1578600380.247 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,4E7C175E100000005D00000400AC0000000000000000151D80030C5669C074ACE9145BC9266A43C331F06FB3E7BA3AEC8E5E645033D89F8DAF37BB5F7B94E853*3F

2020-01-09T20:06:20.648Z,1578600380.648 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T20:06:21.046Z,1578600381.046 [Micromodem](INFO): Nmea in: $CADRQ,200621,1,12,0,64,2*70

2020-01-09T20:06:21.047Z,1578600381.047 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,2E479F5C86EA69D552D0DB45E8A131CBF3E2A39A24C3EB9C6B6E9340EA35A29E15B36AEA259C5B29AE652FD880C12B855E60C980DF916499FF5F855583501C33*3E

2020-01-09T20:06:21.446Z,1578600381.446 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T20:06:21.851Z,1578600381.851 [Micromodem](INFO): Nmea in: $CADRQ,200622,1,12,0,64,3*72

2020-01-09T20:06:21.851Z,1578600381.851 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,D118F399C512A1682E454AE4525014C99413A1F1CE7930418498A16237B415EC2199DF277C652400*3C

2020-01-09T20:06:21.852Z,1578600381.852 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-01-09T20:06:22.253Z,1578600382.253 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,40*4D

2020-01-09T20:06:22.654Z,1578600382.654 [Micromodem](INFO): Nmea in: $CATXP,168*4D

2020-01-09T20:06:26.300Z,1578600386.300 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20200109T191734/Courier0016.lzma
2020-01-09T20:06:26.304Z,1578600386.304 [Micromodem](INFO): Nmea in: $CATXF,168*5B

2020-01-09T20:06:26.695Z,1578600386.695 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,200622.952579,3,0,200,4000,10000,1,1,12,1,3,3,3,168*76

2020-01-09T20:06:27.917Z,1578600387.917 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T20:06:28.306Z,1578600388.306 [DataOverHttps](INFO): Moved sent file to Logs/20200109T191734/Courier0016.lzma.bak
2020-01-09T20:06:28.307Z,1578600388.307 [DataOverHttps](INFO): SBD MOMSN=12188210
2020-01-09T20:06:28.333Z,1578600388.333 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T20:06:28.719Z,1578600388.719 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-09T20:06:28.719Z,1578600388.719 [Micromodem](INFO): Sent 160 bytes from file Logs/20200109T191734/Courier0016.lzma.parts
2020-01-09T20:06:28.720Z,1578600388.720 [Micromodem](INFO): Packets left to send: 0
2020-01-09T20:06:29.126Z,1578600389.126 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109200627.480308,06,607,27,0096,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,22.8,6.00,14,-3.00,-01,-0.04,24,10000,4000*47

2020-01-09T20:06:30.011Z,1578600390.011 [Default:CheckIn:Read_Iridium] Stopped
2020-01-09T20:06:30.012Z,1578600390.012 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-09T20:06:30.012Z,1578600390.012 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-09T20:06:52.313Z,1578600412.313 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-09T20:07:12.792Z,1578600432.792 [DataOverHttps](INFO): Sending 1424 bytes from file Logs/20200109T185556/Express0001.lzma
2020-01-09T20:07:14.794Z,1578600434.794 [DataOverHttps](INFO): Moved sent file to Logs/20200109T185556/Express0001.lzma.bak
2020-01-09T20:07:14.794Z,1578600434.794 [DataOverHttps](INFO): SBD MOMSN=12188217
2020-01-09T20:08:10.694Z,1578600490.694 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2020-01-09T20:09:15.731Z,1578600555.731 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-09T20:09:15.731Z,1578600555.731 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010913220572,35.0, -0.,1448.9,  0
2020-01-09T20:11:30.307Z,1578600690.307 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-09T20:11:30.307Z,1578600690.307 [Default:CheckIn:C.Wait] Stopped
2020-01-09T20:11:30.307Z,1578600690.307 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-09T20:11:30.307Z,1578600690.307 [Default:CheckIn:D] Running Loop=1
2020-01-09T20:11:30.694Z,1578600690.694 [Default:CheckIn:D] Stopped
2020-01-09T20:11:30.694Z,1578600690.694 [Default:CheckIn:E] Running Loop=1
2020-01-09T20:11:31.100Z,1578600691.100 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.875326 min 
2020-01-09T20:11:31.100Z,1578600691.100 [Default:CheckIn:E] Stopped
2020-01-09T20:11:31.100Z,1578600691.100 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-09T20:11:31.100Z,1578600691.100 [Default:CheckIn] Stopped
2020-01-09T20:11:31.101Z,1578600691.101 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-09T20:11:31.101Z,1578600691.101 [Default:CheckIn](INFO): Running loop #6
2020-01-09T20:11:31.101Z,1578600691.101 [Default:CheckIn] Running Loop=6
2020-01-09T20:11:31.101Z,1578600691.101 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-09T20:11:31.101Z,1578600691.101 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-09T20:12:03.787Z,1578600723.787 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-01-09T20:12:03.869Z,1578600723.869 [NAL9602](FAULT): received: 
+CSQ:0

OK151, 2, 0, 0, 0

OK
2020-01-09T20:12:03.869Z,1578600723.869 [NAL9602] Data Fault, FailCount= 2
2020-01-09T20:12:03.869Z,1578600723.869 [NAL9602](ERROR): Data Fault
2020-01-09T20:12:03.922Z,1578600723.922 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-09T20:12:04.190Z,1578600724.190 [NAL9602](INFO): Powering down
2020-01-09T20:12:05.033Z,1578600725.033 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-09T20:12:05.033Z,1578600725.033 [NAL9602] No Fault, FailCount= 2
2020-01-09T20:12:34.483Z,1578600754.483 [NAL9602](INFO): Powering up NAL9602
2020-01-09T20:12:45.395Z,1578600765.395 [NAL9602](INFO): NAL9602 initialized
2020-01-09T20:13:07.212Z,1578600787.212 [NAL9602](INFO): SBD MO Status=2, MOMSN=10151, MT Status=2, MTMSN=0
2020-01-09T20:13:07.212Z,1578600787.212 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-09T20:13:26.200Z,1578600806.200 [NAL9602](INFO): SBD MO Status=2, MOMSN=10151, MT Status=2, MTMSN=0
2020-01-09T20:13:26.200Z,1578600806.200 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-09T20:13:32.307Z,1578600812.307 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-09T20:13:32.307Z,1578600812.307 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-3268,-32768,V
2020-01-09T20:14:00.948Z,1578600840.948 [NAL9602](INFO): SBD MO Status=2, MOMSN=10151, MT Status=2, MTMSN=0
2020-01-09T20:14:00.948Z,1578600840.948 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-09T20:14:33.315Z,1578600873.315 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-3:BD,        +0.00,        +0.00,        +0.00,   0.00,3393.50
2020-01-09T20:15:13.704Z,1578600913.704 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-09T20:15:13.704Z,1578600913.704 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-09T20:16:31.260Z,1578600991.260 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-09T20:11:31.1Z
2020-01-09T20:16:31.260Z,1578600991.260 [Default:CheckIn:Read_GPS] Stopped
2020-01-09T20:16:31.260Z,1578600991.260 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-09T20:16:31.667Z,1578600991.667 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-09T20:16:32.945Z,1578600992.945 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 4E7C175E130000005D00000400320000000000000000151D80030C5669C08C34EE604A22DDDA1E307C22A28A07608084E22F5CBBD2DD4E75D5F1AAF662CFBE04
2020-01-09T20:16:32.945Z,1578600992.945 [Micromodem](INFO): Outgoing frame #2, 6 bytes: FEDA0754C200
2020-01-09T20:16:32.946Z,1578600992.946 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,2*68

2020-01-09T20:16:33.118Z,1578600993.118 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,2*6A

2020-01-09T20:16:33.490Z,1578600993.490 [Micromodem](INFO): Nmea in: $CADRQ,201633,1,12,0,64,1*71

2020-01-09T20:16:33.491Z,1578600993.491 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,4E7C175E130000005D00000400320000000000000000151D80030C5669C08C34EE604A22DDDA1E307C22A28A07608084E22F5CBBD2DD4E75D5F1AAF662CFBE04*3A

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

2020-01-09T20:16:34.328Z,1578600994.328 [Micromodem](INFO): Nmea in: $CADRQ,201634,1,12,0,64,2*75

2020-01-09T20:16:34.328Z,1578600994.328 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,FEDA0754C200*3A

2020-01-09T20:16:34.703Z,1578600994.703 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,6*7F

2020-01-09T20:16:35.102Z,1578600995.102 [Micromodem](INFO): Nmea in: $CATXP,70*75

2020-01-09T20:16:35.511Z,1578600995.511 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-01-09T20:16:37.530Z,1578600997.530 [Micromodem](INFO): Nmea in: $CATXF,70*63

2020-01-09T20:16:37.936Z,1578600997.936 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,201635.380917,3,0,200,4000,10000,1,1,12,1,2,2,3,70*48

2020-01-09T20:16:39.144Z,1578600999.144 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20200109T191734/Courier0019.lzma
2020-01-09T20:16:39.148Z,1578600999.148 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

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

2020-01-09T20:16:39.551Z,1578600999.551 [Micromodem](INFO): Sent 62 bytes from file Logs/20200109T191734/Courier0019.lzma.parts
2020-01-09T20:16:39.552Z,1578600999.552 [Micromodem](INFO): Packets left to send: 0
2020-01-09T20:16:39.958Z,1578600999.958 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109201638.606358,06,431,26,0092,0150,89,02,02,00,03,1,012,001,0,4,1,0,150,23.0,8.00,19,-5.00,-01,-0.04,29,10000,4000*4F

2020-01-09T20:16:40.868Z,1578601000.868 [Default:CheckIn:Read_Iridium] Stopped
2020-01-09T20:16:40.868Z,1578601000.868 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-09T20:16:40.868Z,1578601000.868 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-09T20:16:41.651Z,1578601001.651 [DataOverHttps](FAULT): Server acknowledged file Logs/20200109T191734/Courier0019.lzma, notLogs/20200109T185556/Express0005.lzma
2020-01-09T20:16:41.651Z,1578601001.651 [DataOverHttps](INFO): SBD MOMSN=12188265
2020-01-09T20:17:05.946Z,1578601025.946 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-09T20:17:25.194Z,1578601045.194 [DataOverHttps](INFO): Sending 284 bytes from file Logs/20200109T185556/Express0005.lzma
2020-01-09T20:17:27.198Z,1578601047.198 [DataOverHttps](INFO): Moved sent file to Logs/20200109T185556/Express0005.lzma.bak
2020-01-09T20:17:27.198Z,1578601047.198 [DataOverHttps](INFO): SBD MOMSN=12188269
2020-01-09T20:17:48.011Z,1578601068.011 [CBIT](INFO): Clearing failed state for component DropWeight
2020-01-09T20:17:48.011Z,1578601068.011 [DropWeight] No Fault, FailCount= 1
2020-01-09T20:19:57.685Z,1578601197.685 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2020-01-09T20:21:16.850Z,1578601276.850 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-09T20:21:16.850Z,1578601276.850 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010913340672,35.0, -0.1,   0.0,1448:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-09T20:21:41.091Z,1578601301.091 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-09T20:21:41.091Z,1578601301.091 [Default:CheckIn:C.Wait] Stopped
2020-01-09T20:21:41.091Z,1578601301.091 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-09T20:21:41.091Z,1578601301.091 [Default:CheckIn:D] Running Loop=1
2020-01-09T20:21:41.513Z,1578601301.513 [Default:CheckIn:D] Stopped
2020-01-09T20:21:41.514Z,1578601301.514 [Default:CheckIn:E] Running Loop=1
2020-01-09T20:21:41.915Z,1578601301.915 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.055660 min 
2020-01-09T20:21:41.915Z,1578601301.915 [Default:CheckIn:E] Stopped
2020-01-09T20:21:41.915Z,1578601301.915 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-09T20:21:41.915Z,1578601301.915 [Default:CheckIn] Stopped
2020-01-09T20:21:41.915Z,1578601301.915 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-09T20:21:41.915Z,1578601301.915 [Default:CheckIn](INFO): Running loop #7
2020-01-09T20:21:41.915Z,1578601301.915 [Default:CheckIn] Running Loop=7
2020-01-09T20:21:41.915Z,1578601301.915 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-09T20:21:41.915Z,1578601301.915 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-09T20:21:43.495Z,1578601303.495 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-01-09T20:21:43.495Z,1578601303.495 [NAL9602] Data Fault, FailCount= 3
2020-01-09T20:21:43.495Z,1578601303.495 [NAL9602](ERROR): Data Fault
2020-01-09T20:21:43.581Z,1578601303.581 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-09T20:21:43.899Z,1578601303.899 [NAL9602](INFO): Powering down
2020-01-09T20:21:44.756Z,1578601304.756 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-09T20:21:44.756Z,1578601304.756 [NAL9602] No Fault, FailCount= 3
2020-01-09T20:22:14.199Z,1578601334.199 [NAL9602](INFO): Powering up NAL9602
2020-01-09T20:22:25.107Z,1578601345.107 [NAL9602](INFO): NAL9602 initialized
2020-01-09T20:24:20.308Z,1578601460.308 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-09T20:24:20.308Z,1578601460.308 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-09T20:24:25.526Z,1578601465.526 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010913371572,35.0, -0.1,   0.0,1448.9,  0
2020-01-09T20:24:45.372Z,1578601485.372 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-09T20:24:45.372Z,1578601485.372 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,

2020-01-09T20:26:42.121Z,1578601602.121 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-09T20:21:41.9Z
2020-01-09T20:26:42.121Z,1578601602.121 [Default:CheckIn:Read_GPS] Stopped
2020-01-09T20:26:42.121Z,1578601602.121 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-09T20:26:43.781Z,1578601603.781 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 4E7C175E160000005D00000400320000000000000000151D80030C5669C08C34EE7CAC7D3CD2C5887899D2E650A42F9E5A3175BA055B9289F0C9A9FAB432BE53
2020-01-09T20:26:43.784Z,1578601603.784 [Micromodem](INFO): Outgoing frame #2, 6 bytes: 907523A70A00
2020-01-09T20:26:43.785Z,1578601603.785 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,2*68

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

2020-01-09T20:26:44.335Z,1578601604.335 [Micromodem](INFO): Nmea in: $CADRQ,202644,1,12,0,64,1*72

2020-01-09T20:26:44.335Z,1578601604.335 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,4E7C175E160000005D00000400320000000000000000151D80030C5669C08C34EE7CAC7D3CD2C5887899D2E650A42F9E5A3175BA055B9289F0C9A9FAB432BE53*4C

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

2020-01-09T20:26:45.143Z,1578601605.143 [Micromodem](INFO): Nmea in: $CADRQ,202645,1,12,0,64,2*70

2020-01-09T20:26:45.143Z,1578601605.143 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,907523A70A00*46

2020-01-09T20:26:45.547Z,1578601605.547 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,6*7F

2020-01-09T20:26:45.946Z,1578601605.946 [Micromodem](INFO): Nmea in: $CATXP,70*75

2020-01-09T20:26:48.375Z,1578601608.375 [Micromodem](INFO): Nmea in: $CATXF,70*63

2020-01-09T20:26:48.780Z,1578601608.780 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,202646.201432,3,0,200,4000,10000,1,1,12,1,2,2,3,70*4D

2020-01-09T20:26:49.583Z,1578601609.583 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T20:26:49.988Z,1578601609.988 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T20:26:49.989Z,1578601609.989 [Micromodem](INFO): Sent 62 bytes from file Logs/20200109T191734/Courier0022.lzma.parts
2020-01-09T20:26:49.990Z,1578601609.990 [Micromodem](INFO): Packets left to send: 0
2020-01-09T20:26:50.428Z,1578601610.428 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109202649.424670,06,556,27,0110,0150,29,02,02,00,04,1,012,001,0,4,1,0,150,22.6,6.00,13,-3.00,-01,-0.06,20,10000,4000*4E

2020-01-09T20:26:51.495Z,1578601611.495 [Default:CheckIn:Read_Iridium] Stopped
2020-01-09T20:26:51.495Z,1578601611.495 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-09T20:26:51.495Z,1578601611.495 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-09T20:26:52.010Z,1578601612.010 [DataOverHttps](INFO): Sending 324 bytes from file Logs/20200109T185556/Express0008.lzma
2020-01-09T20:26:54.018Z,1578601614.018 [DataOverHttps](INFO): Moved sent file to Logs/20200109T185556/Express0008.lzma.bak
2020-01-09T20:26:54.018Z,1578601614.018 [DataOverHttps](INFO): SBD MOMSN=12188280
2020-01-09T20:27:28.721Z,1578601648.721 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-01-09T20:27:59.441Z,1578601679.441 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-09T20:31:48.133Z,1578601908.133 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-01-09T20:31:48.135Z,1578601908.135 [BPC1](INFO): Received data from all battery sticks.
2020-01-09T20:31:51.776Z,1578601911.776 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-09T20:31:51.776Z,1578601911.776 [Default:CheckIn:C.Wait] Stopped
2020-01-09T20:31:51.776Z,1578601911.776 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-09T20:31:51.776Z,1578601911.776 [Default:CheckIn:D] Running Loop=1
2020-01-09T20:31:52.193Z,1578601912.193 [Default:CheckIn:D] Stopped
2020-01-09T20:31:52.193Z,1578601912.193 [Default:CheckIn:E] Running Loop=1
2020-01-09T20:31:52.605Z,1578601912.605 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.233651 min 
2020-01-09T20:31:52.605Z,1578601912.605 [Default:CheckIn:E] Stopped
2020-01-09T20:31:52.605Z,1578601912.605 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-09T20:31:52.605Z,1578601912.605 [Default:CheckIn] Stopped
2020-01-09T20:31:52.605Z,1578601912.605 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-09T20:31:52.606Z,1578601912.606 [Default:CheckIn](INFO): Running loop #8
2020-01-09T20:31:52.606Z,1578601912.606 [Default:CheckIn] Running Loop=8
2020-01-09T20:31:52.606Z,1578601912.606 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-09T20:31:52.606Z,1578601912.606 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-09T20:31:54.581Z,1578601914.581 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203154.00,A,3648.16519,N,12147.27719,W,0.194,241.69,090120,,,A*7B
2020-01-09T20:31:54.584Z,1578601914.584 [NAL9602](INFO): GPS fix at 20200109T203154: (36.802753, -121.787953)
2020-01-09T20:31:54.637Z,1578601914.637 [Default:CheckIn:Read_GPS] Stopped
2020-01-09T20:31:54.638Z,1578601914.638 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-09T20:31:56.396Z,1578601916.396 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 4E7C175E190000005D00000400790000000000000000151D80030C5669C08C34EE786C5980601FC01F64A84791216AF9353BB00B0472672583323A8F26957432
2020-01-09T20:31:56.396Z,1578601916.396 [Micromodem](INFO): Outgoing frame #2, 45 bytes: B5BE0E69FEF73F0DF8024853D15FD9CAD7E971D92253FA70F33D6997C4A0305315DD89B5A536E6BB725581A000
2020-01-09T20:31:56.396Z,1578601916.396 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,2*68

2020-01-09T20:31:56.602Z,1578601916.602 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,2*6A

2020-01-09T20:31:56.947Z,1578601916.947 [Micromodem](INFO): Nmea in: $CADRQ,203157,1,12,0,64,1*76

2020-01-09T20:31:56.947Z,1578601916.947 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,4E7C175E190000005D00000400790000000000000000151D80030C5669C08C34EE786C5980601FC01F64A84791216AF9353BB00B0472672583323A8F26957432*4D

2020-01-09T20:31:57.347Z,1578601917.347 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-09T20:31:57.766Z,1578601917.766 [Micromodem](INFO): Nmea in: $CADRQ,203158,1,12,0,64,2*7A

2020-01-09T20:31:57.767Z,1578601917.767 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,B5BE0E69FEF73F0DF8024853D15FD9CAD7E971D92253FA70F33D6997C4A0305315DD89B5A536E6BB725581A000*42

2020-01-09T20:31:58.160Z,1578601918.160 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,45*48

2020-01-09T20:31:58.565Z,1578601918.565 [Micromodem](INFO): Nmea in: $CATXP,109*4A

2020-01-09T20:32:00.995Z,1578601920.995 [Micromodem](INFO): Nmea in: $CATXF,109*5C

2020-01-09T20:32:02.197Z,1578601922.197 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,203158.860586,3,0,200,4000,10000,1,1,12,1,2,2,3,109*78

2020-01-09T20:32:02.340Z,1578601922.340 [DataOverHttps](INFO): Sending 101 bytes from file Logs/20200109T191734/Courier0025.lzma
2020-01-09T20:32:02.600Z,1578601922.600 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T20:32:03.007Z,1578601923.007 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T20:32:03.008Z,1578601923.008 [Micromodem](INFO): Sent 101 bytes from file Logs/20200109T191734/Courier0025.lzma.parts
2020-01-09T20:32:03.008Z,1578601923.008 [Micromodem](INFO): Packets left to send: 0
2020-01-09T20:32:03.412Z,1578601923.412 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109203202.128225,06,779,28,0115,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,24.4,7.00,15,-4.00,-01,-0.05,23,10000,4000*48

2020-01-09T20:32:04.447Z,1578601924.447 [Default:CheckIn:Read_Iridium] Stopped
2020-01-09T20:32:04.447Z,1578601924.447 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-09T20:32:04.447Z,1578601924.447 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-09T20:32:04.883Z,1578601924.883 [DataOverHttps](FAULT): Server acknowledged file Logs/20200109T191734/Courier0025.lzma, notLogs/20200109T185556/Express0011.lzma
2020-01-09T20:32:04.883Z,1578601924.883 [DataOverHttps](INFO): SBD MOMSN=12188286
2020-01-09T20:32:25.108Z,1578601945.108 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-01-09T20:32:25.181Z,1578601945.181 [NAL9602](FAULT): received: 
+CSQ:0

OK151, 2, 0, 0, 0

OK
2020-01-09T20:32:25.181Z,1578601945.181 [NAL9602] Data Fault, FailCount= 4
2020-01-09T20:32:25.181Z,1578601945.181 [NAL9602](ERROR): Data Fault
2020-01-09T20:32:25.241Z,1578601945.241 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-09T20:32:25.521Z,1578601945.521 [NAL9602](INFO): Powering down
2020-01-09T20:32:26.371Z,1578601946.371 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-09T20:32:26.371Z,1578601946.371 [NAL9602] No Fault, FailCount= 4
2020-01-09T20:32:48.676Z,1578601968.676 [DataOverHttps](INFO): Sending 124 bytes from file Logs/20200109T185556/Express0011.lzma
2020-01-09T20:32:50.682Z,1578601970.682 [DataOverHttps](INFO): Moved sent file to Logs/20200109T185556/Express0011.lzma.bak
2020-01-09T20:32:50.682Z,1578601970.682 [DataOverHttps](INFO): SBD MOMSN=12188289
2020-01-09T20:32:55.815Z,1578601975.815 [NAL9602](INFO): Powering up NAL9602
2020-01-09T20:33:06.727Z,1578601986.727 [NAL9602](INFO): NAL9602 initialized
2020-01-09T20:33:37.839Z,1578602017.839 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-09T20:37:04.701Z,1578602224.701 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-09T20:37:04.701Z,1578602224.701 [Default:CheckIn:C.Wait] Stopped
2020-01-09T20:37:04.701Z,1578602224.701 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-09T20:37:04.701Z,1578602224.701 [Default:CheckIn:D] Running Loop=1
2020-01-09T20:37:05.119Z,1578602225.119 [Default:CheckIn:D] Stopped
2020-01-09T20:37:05.119Z,1578602225.119 [Default:CheckIn:E] Running Loop=1
2020-01-09T20:37:05.532Z,1578602225.532 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.449080 min 
2020-01-09T20:37:05.532Z,1578602225.532 [Default:CheckIn:E] Stopped
2020-01-09T20:37:05.532Z,1578602225.532 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-09T20:37:05.532Z,1578602225.532 [Default:CheckIn] Stopped
2020-01-09T20:37:05.533Z,1578602225.533 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-09T20:37:05.533Z,1578602225.533 [Default:CheckIn](INFO): Running loop #9
2020-01-09T20:37:05.533Z,1578602225.533 [Default:CheckIn] Running Loop=9
2020-01-09T20:37:05.533Z,1578602225.533 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-09T20:37:05.533Z,1578602225.533 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-09T20:37:09.122Z,1578602229.122 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-01-09T20:37:22.498Z,1578602242.498 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-09T20:37:22.498Z,1578602242.498 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010913501172,35.0,61.01
2020-01-09T20:40:33.656Z,1578602433.656 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-09T20:40:33.656Z,1578602433.656 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010913532372,35.0, -0.1,   0.0,1448.9A,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-09T20:42:05.800Z,1578602525.800 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-09T20:37:05.5Z
2020-01-09T20:42:05.800Z,1578602525.800 [Default:CheckIn:Read_GPS] Stopped
2020-01-09T20:42:05.800Z,1578602525.800 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-09T20:42:07.363Z,1578602527.363 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 4E7C175E1C0000005D00000400320000000000000000151D80030C5669C08C34EE676E401D8D393518349B3070B41D177654D4B23FB459DBA4FB72A3980CC906
2020-01-09T20:42:07.363Z,1578602527.363 [Micromodem](INFO): Outgoing frame #2, 6 bytes: 0A07EB012600
2020-01-09T20:42:07.363Z,1578602527.363 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,2*68

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

2020-01-09T20:42:07.919Z,1578602527.919 [Micromodem](INFO): Nmea in: $CADRQ,204208,1,12,0,64,1*78

2020-01-09T20:42:07.919Z,1578602527.919 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,4E7C175E1C0000005D00000400320000000000000000151D80030C5669C08C34EE676E401D8D393518349B3070B41D177654D4B23FB459DBA4FB72A3980CC906*32

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

2020-01-09T20:42:08.723Z,1578602528.723 [Micromodem](INFO): Nmea in: $CADRQ,204208,1,12,0,64,2*7B

2020-01-09T20:42:08.723Z,1578602528.723 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,0A07EB012600*3F

2020-01-09T20:42:09.127Z,1578602529.127 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,6*7F

2020-01-09T20:42:09.534Z,1578602529.534 [Micromodem](INFO): Nmea in: $CATXP,70*75

2020-01-09T20:42:11.955Z,1578602531.955 [Micromodem](INFO): Nmea in: $CATXF,70*63

2020-01-09T20:42:12.360Z,1578602532.360 [Micromodem](INFO): Nmea in: $CAXST,6,20200109,204209.776434,3,0,200,4000,10000,1,1,12,1,2,2,3,70*47

2020-01-09T20:42:13.175Z,1578602533.175 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-09T20:42:13.288Z,1578602533.288 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20200109T191734/Courier0028.lzma
2020-01-09T20:42:13.571Z,1578602533.571 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-09T20:42:13.572Z,1578602533.572 [Micromodem](INFO): Sent 62 bytes from file Logs/20200109T191734/Courier0028.lzma.parts
2020-01-09T20:42:13.572Z,1578602533.572 [Micromodem](INFO): Packets left to send: 0
2020-01-09T20:42:13.982Z,1578602533.982 [Micromodem](INFO): Nmea in: $CACST,6,0,20200109204213.001302,06,553,27,0102,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,-0.4,6.00,13,-3.00,-01,-0.07,45,10000,4000*5B

2020-01-09T20:42:14.759Z,1578602534.759 [Default:CheckIn:Read_Iridium] Stopped
2020-01-09T20:42:14.759Z,1578602534.759 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-09T20:42:14.759Z,1578602534.759 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-09T20:42:15.679Z,1578602535.679 [DataOverHttps](FAULT): Server acknowledged file Logs/20200109T191734/Courier0028.lzma, notLogs/20200109T191644/Express0001.lzma
2020-01-09T20:42:15.679Z,1578602535.679 [DataOverHttps](INFO): SBD MOMSN=12188301
2020-01-09T20:42:39.871Z,1578602559.871 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-09T20:42:58.616Z,1578602578.616 [DataOverHttps](INFO): Sending 1450 bytes from file Logs/20200109T191644/Express0001.lzma
2020-01-09T20:43:00.610Z,1578602580.610 [DataOverHttps](INFO): Moved sent file to Logs/20200109T191644/Express0001.lzma.bak
2020-01-09T20:43:00.610Z,1578602580.610 [DataOverHttps](INFO): SBD MOMSN=12188303
2020-01-09T20:44:16.475Z,1578602656.475 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-09T20:44:16.475Z,1578602656.475 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.00 +0.00,   0.00,5175.81
2020-01-09T20:45:05.363Z,1578602705.363 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-09T20:45:05.363Z,1578602705.363 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-3278,V
2020-01-09T20:45:20.311Z,1578602720.311 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-09T20:45:20.311Z,1578602720.311 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.0  +0.00,   0.00,5239.77
2020-01-09T20:46:16.844Z,1578602776.844 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-09T20:46:16.844Z,1578602776.844 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010913590672,35.0, -0.1,   0.0,1448.RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-09T20:46:21.838Z,1578602781.838 [CommandLine](IMPORTANT): got command quit
2020-01-09T20:46:22.845Z,1578602782.845 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T20:46:22.845Z,1578602782.845 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-09T20:46:22.909Z,1578602782.909 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-01-09T20:46:22.909Z,1578602782.909 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-09T20:46:22.910Z,1578602782.910 [CommandLine](INFO): Join timeout helper Thread ID is 1041
2020-01-09T20:46:22.929Z,1578602782.929 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-01-09T20:46:22.929Z,1578602782.929 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-09T20:46:22.929Z,1578602782.929 [NavChartDb](INFO): Join timeout helper Thread ID is 1042
2020-01-09T20:46:22.965Z,1578602782.965 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T20:46:22.965Z,1578602782.965 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-09T20:46:22.981Z,1578602782.981 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-01-09T20:46:22.981Z,1578602782.981 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-09T20:46:22.981Z,1578602782.981 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1043
2020-01-09T20:46:22.997Z,1578602782.997 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T20:46:22.997Z,1578602782.997 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-09T20:46:22.999Z,1578602782.999 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-09T20:46:23.002Z,1578602783.002 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2020-01-09T20:46:23.002Z,1578602783.002 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-09T20:46:23.004Z,1578602783.004 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1044
2020-01-09T20:46:23.085Z,1578602783.085 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T20:46:23.085Z,1578602783.085 [CTD_NeilBrown](INFO): Powering down
2020-01-09T20:46:23.097Z,1578602783.097 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-09T20:46:23.101Z,1578602783.101 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-01-09T20:46:23.101Z,1578602783.101 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-09T20:46:23.101Z,1578602783.101 [Radio_Surface](INFO): Join timeout helper Thread ID is 1045
2020-01-09T20:46:23.209Z,1578602783.209 [Radio_Surface](INFO): Powering down
2020-01-09T20:46:23.210Z,1578602783.210 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T20:46:23.210Z,1578602783.210 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-09T20:46:23.222Z,1578602783.222 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-01-09T20:46:23.222Z,1578602783.222 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-09T20:46:23.223Z,1578602783.223 [DataOverHttps](INFO): Join timeout helper Thread ID is 1046
2020-01-09T20:46:23.402Z,1578602783.402 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T20:46:23.403Z,1578602783.403 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-09T20:46:23.409Z,1578602783.409 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-01-09T20:46:23.410Z,1578602783.410 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-09T20:46:23.410Z,1578602783.410 [logger](INFO): Join timeout helper Thread ID is 1047
2020-01-09T20:46:23.445Z,1578602783.445 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T20:46:23.445Z,1578602783.445 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-09T20:46:23.449Z,1578602783.449 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-01-09T20:46:23.450Z,1578602783.450 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-09T20:46:23.450Z,1578602783.450 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-01-09T20:46:23.450Z,1578602783.450 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-09T20:46:23.450Z,1578602783.450 [controlThread](INFO): Join timeout helper Thread ID is 1048
2020-01-09T20:46:23.665Z,1578602783.665 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-09T20:46:23.665Z,1578602783.665 [controlThread](DEBUG): Uninitializing ControlThread
2020-01-09T20:46:23.666Z,1578602783.666 [AHRS_M2](INFO): Powering down
2020-01-09T20:46:23.737Z,1578602783.737 [DDM](INFO): Powering down
2020-01-09T20:46:23.830Z,1578602783.830 [DUSBL_Hydroid](INFO): Powering down
2020-01-09T20:46:23.921Z,1578602783.921 [Micromodem](INFO): Powering down
2020-01-09T20:46:24.017Z,1578602784.017 [NAL9602](INFO): Powering down
2020-01-09T20:46:24.089Z,1578602784.089 [RDI_Pathfinder](INFO): Powering down
2020-01-09T20:46:24.090Z,1578602784.090 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-01-09T20:46:24.091Z,1578602784.091 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-01-09T20:46:24.092Z,1578602784.092 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-01-09T20:46:24.092Z,1578602784.092 [MissionManager](INFO): Uninitializing Mission Default
2020-01-09T20:46:24.092Z,1578602784.092 [Default] Stopped
2020-01-09T20:46:24.092Z,1578602784.092 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-09T20:46:24.093Z,1578602784.093 [Default:B.GoToSurface] Stopped
2020-01-09T20:46:24.093Z,1578602784.093 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-09T20:46:24.093Z,1578602784.093 [Default:CheckIn] Stopped
2020-01-09T20:46:24.093Z,1578602784.093 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-09T20:46:24.093Z,1578602784.093 [Default:CheckIn:C.Wait] Stopped
2020-01-09T20:46:24.093Z,1578602784.093 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-09T20:46:24.095Z,1578602784.095 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-01-09T20:46:24.096Z,1578602784.096 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-01-09T20:46:24.096Z,1578602784.096 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-01-09T20:46:24.096Z,1578602784.096 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-01-09T20:46:24.096Z,1578602784.096 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-01-09T20:46:24.097Z,1578602784.097 [BuoyancyServo](INFO): Powering down
2020-01-09T20:46:24.109Z,1578602784.109 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-01-09T20:46:24.109Z,1578602784.109 [ElevatorServo](INFO): Powering down
2020-01-09T20:46:24.110Z,1578602784.110 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-01-09T20:46:24.110Z,1578602784.110 [MassServo](INFO): Powering down
2020-01-09T20:46:24.111Z,1578602784.111 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-09T20:46:24.111Z,1578602784.111 [RudderServo](INFO): Powering down
2020-01-09T20:46:24.112Z,1578602784.112 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-01-09T20:46:24.112Z,1578602784.112 [ThrusterServo](INFO): Powering down
2020-01-09T20:46:24.112Z,1578602784.112 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-01-09T20:46:24.113Z,1578602784.113 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-01-09T20:46:24.113Z,1578602784.113 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-01-09T20:46:24.113Z,1578602784.113 [CBIT](DEBUG): Powering off loads.
2020-01-09T20:46:24.125Z,1578602784.125 [CBIT](DEBUG): Disabling WDT.
2020-01-09T20:46:24.137Z,1578602784.137 [CBIT](DEBUG): Opening all GF detection circuits.
2020-01-09T20:46:24.138Z,1578602784.138 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-09T20:46:24.189Z,1578602784.189 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-09T20:46:24.200Z,1578602784.200 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-09T20:46:24.243Z,1578602784.243 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-09T20:46:24.246Z,1578602784.246 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-09T20:46:24.305Z,1578602784.305 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-09T20:46:24.377Z,1578602784.377 [logger ThreadHandler](INFO): Thread cancelled.