2020-01-10T21:49:52.919Z,1578692992.919 [Supervisor](DEBUG): Initializing supervisor.
2020-01-10T21:49:52.922Z,1578692992.922 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-01-10T21:49:52.923Z,1578692992.923 [SyncHandler](INFO): Protected caller Thread ID is 3768
2020-01-10T21:49:52.923Z,1578692992.923 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-01-10T21:49:52.924Z,1578692992.924 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-01-10T21:49:52.925Z,1578692992.925 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3769
2020-01-10T21:49:52.928Z,1578692992.928 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-01-10T21:49:52.940Z,1578692992.940 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-01-10T21:49:52.941Z,1578692992.941 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-01-10T21:49:52.941Z,1578692992.941 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3770
2020-01-10T21:49:52.942Z,1578692992.942 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-01-10T21:49:52.943Z,1578692992.943 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-01-10T21:49:52.944Z,1578692992.944 [logger ThreadHandler](INFO): Protected caller Thread ID is 3771
2020-01-10T21:49:52.946Z,1578692992.946 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-01-10T21:49:52.946Z,1578692992.946 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-01-10T21:49:52.948Z,1578692992.948 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-01-10T21:49:53.414Z,1578692993.414 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-01-10T21:49:53.414Z,1578692993.414 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-01-10T21:49:53.509Z,1578692993.509 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-01-10T21:49:53.510Z,1578692993.510 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-01-10T21:49:53.821Z,1578692993.821 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-01-10T21:49:53.822Z,1578692993.822 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-01-10T21:49:53.957Z,1578692993.957 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-01-10T21:49:53.957Z,1578692993.957 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-01-10T21:49:54.140Z,1578692994.140 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-01-10T21:49:54.141Z,1578692994.141 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-01-10T21:49:54.580Z,1578692994.580 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-01-10T21:49:54.581Z,1578692994.581 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-01-10T21:49:54.782Z,1578692994.782 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-01-10T21:49:54.783Z,1578692994.783 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-01-10T21:49:54.923Z,1578692994.923 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-01-10T21:49:54.923Z,1578692994.923 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-01-10T21:49:55.103Z,1578692995.103 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-01-10T21:49:55.104Z,1578692995.104 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-01-10T21:49:55.197Z,1578692995.197 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-01-10T21:49:55.198Z,1578692995.198 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-01-10T21:49:55.489Z,1578692995.489 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-01-10T21:49:55.489Z,1578692995.489 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-01-10T21:49:55.567Z,1578692995.567 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-01-10T21:49:55.667Z,1578692995.667 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-01-10T21:49:55.667Z,1578692995.667 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-01-10T21:49:56.293Z,1578692996.293 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-01-10T21:49:56.294Z,1578692996.294 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-01-10T21:49:56.697Z,1578692996.697 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-01-10T21:49:56.699Z,1578692996.699 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-01-10T21:49:56.699Z,1578692996.699 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-01-10T21:49:56.897Z,1578692996.897 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-01-10T21:49:56.992Z,1578692996.992 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-01-10T21:49:57.087Z,1578692997.087 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-01-10T21:49:57.314Z,1578692997.314 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-01-10T21:49:57.315Z,1578692997.315 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-01-10T21:49:57.397Z,1578692997.397 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-01-10T21:49:57.486Z,1578692997.486 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-01-10T21:49:57.580Z,1578692997.580 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-01-10T21:49:57.660Z,1578692997.660 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-01-10T21:49:57.764Z,1578692997.764 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-01-10T21:49:58.008Z,1578692998.008 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-01-10T21:49:58.196Z,1578692998.196 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2020-01-10T21:49:58.197Z,1578692998.197 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-01-10T21:49:58.218Z,1578692998.218 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-01-10T21:49:58.730Z,1578692998.730 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-01-10T21:49:58.736Z,1578692998.736 [AHRS_M2](INFO): created writer for : platform_orientation
2020-01-10T21:49:58.737Z,1578692998.737 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-01-10T21:49:58.777Z,1578692998.777 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-01-10T21:49:58.778Z,1578692998.778 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-01-10T21:49:58.784Z,1578692998.784 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-01-10T21:49:58.784Z,1578692998.784 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-01-10T21:49:58.826Z,1578692998.826 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-01-10T21:49:58.999Z,1578692998.999 [AHRS_M2] Loaded
2020-01-10T21:49:58.999Z,1578692998.999 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-01-10T21:49:59.078Z,1578692999.078 [DataOverHttps] Loaded
2020-01-10T21:49:59.079Z,1578692999.079 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-01-10T21:49:59.080Z,1578692999.080 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4076F4E0
2020-01-10T21:49:59.080Z,1578692999.080 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3850
2020-01-10T21:49:59.105Z,1578692999.105 [DDM] Loaded
2020-01-10T21:49:59.106Z,1578692999.106 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-01-10T21:49:59.119Z,1578692999.119 [Depth_Keller] Loaded
2020-01-10T21:49:59.119Z,1578692999.119 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-01-10T21:49:59.124Z,1578692999.124 [DropWeight] Loaded
2020-01-10T21:49:59.125Z,1578692999.125 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-01-10T21:49:59.188Z,1578692999.188 [DUSBL_Hydroid] Loaded
2020-01-10T21:49:59.189Z,1578692999.189 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-01-10T21:49:59.237Z,1578692999.237 [Micromodem] Loaded
2020-01-10T21:49:59.237Z,1578692999.237 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-01-10T21:49:59.339Z,1578692999.339 [NAL9602] Loaded
2020-01-10T21:49:59.339Z,1578692999.339 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-01-10T21:49:59.378Z,1578692999.378 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2020-01-10T21:49:59.379Z,1578692999.379 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2020-01-10T21:49:59.379Z,1578692999.379 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2020-01-10T21:49:59.380Z,1578692999.380 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2020-01-10T21:49:59.381Z,1578692999.381 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2020-01-10T21:49:59.381Z,1578692999.381 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2020-01-10T21:49:59.382Z,1578692999.382 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2020-01-10T21:49:59.382Z,1578692999.382 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2020-01-10T21:49:59.382Z,1578692999.382 [Onboard] Loaded
2020-01-10T21:49:59.383Z,1578692999.383 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-01-10T21:49:59.389Z,1578692999.389 [PowerOnly] Loaded
2020-01-10T21:49:59.389Z,1578692999.389 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-01-10T21:49:59.396Z,1578692999.396 [Radio_Surface] Loaded
2020-01-10T21:49:59.396Z,1578692999.396 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-01-10T21:49:59.397Z,1578692999.397 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4079F4E0
2020-01-10T21:49:59.397Z,1578692999.397 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3851
2020-01-10T21:49:59.443Z,1578692999.443 [RDI_Pathfinder] Loaded
2020-01-10T21:49:59.443Z,1578692999.443 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-01-10T21:50:01.007Z,1578693001.007 [BPC1] Loaded
2020-01-10T21:50:01.007Z,1578693001.007 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-01-10T21:50:01.007Z,1578693001.007 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-01-10T21:50:01.008Z,1578693001.008 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-01-10T21:50:01.021Z,1578693001.021 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-01-10T21:50:01.022Z,1578693001.022 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-01-10T21:50:01.130Z,1578693001.130 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-01-10T21:50:01.130Z,1578693001.130 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-01-10T21:50:01.151Z,1578693001.151 [NavChart] Loaded
2020-01-10T21:50:01.151Z,1578693001.151 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-01-10T21:50:01.155Z,1578693001.155 [UniversalFixResidualReporter] Loaded
2020-01-10T21:50:01.156Z,1578693001.156 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-01-10T21:50:01.156Z,1578693001.156 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-01-10T21:50:01.157Z,1578693001.157 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-01-10T21:50:01.267Z,1578693001.267 [BuoyancyServo] Loaded
2020-01-10T21:50:01.267Z,1578693001.267 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-01-10T21:50:01.283Z,1578693001.283 [ElevatorServo] Loaded
2020-01-10T21:50:01.283Z,1578693001.283 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-01-10T21:50:01.298Z,1578693001.298 [MassServo] Loaded
2020-01-10T21:50:01.298Z,1578693001.298 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-01-10T21:50:01.314Z,1578693001.314 [RudderServo] Loaded
2020-01-10T21:50:01.314Z,1578693001.314 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-01-10T21:50:01.329Z,1578693001.329 [ThrusterServo] Loaded
2020-01-10T21:50:01.329Z,1578693001.329 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-01-10T21:50:01.329Z,1578693001.329 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-01-10T21:50:01.330Z,1578693001.330 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-01-10T21:50:01.596Z,1578693001.596 [CTD_NeilBrown] Loaded
2020-01-10T21:50:01.596Z,1578693001.596 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2020-01-10T21:50:01.597Z,1578693001.597 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408F34E0
2020-01-10T21:50:01.598Z,1578693001.598 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 3852
2020-01-10T21:50:01.612Z,1578693001.612 [PAR_Licor] Loaded
2020-01-10T21:50:01.613Z,1578693001.613 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-01-10T21:50:01.658Z,1578693001.658 [WetLabsSeaOWL_UV_A] Loaded
2020-01-10T21:50:01.658Z,1578693001.658 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-01-10T21:50:01.659Z,1578693001.659 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409234E0
2020-01-10T21:50:01.659Z,1578693001.659 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 3853
2020-01-10T21:50:01.660Z,1578693001.660 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-01-10T21:50:01.661Z,1578693001.661 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-01-10T21:50:01.982Z,1578693001.982 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-01-10T21:50:01.983Z,1578693001.983 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-01-10T21:50:02.024Z,1578693002.024 [DepthRateCalculator] Loaded
2020-01-10T21:50:02.024Z,1578693002.024 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-01-10T21:50:02.030Z,1578693002.030 [PitchRateCalculator] Loaded
2020-01-10T21:50:02.030Z,1578693002.030 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-01-10T21:50:02.043Z,1578693002.043 [SpeedCalculator] Loaded
2020-01-10T21:50:02.043Z,1578693002.043 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-01-10T21:50:02.064Z,1578693002.064 [TempGradientCalculator] Loaded
2020-01-10T21:50:02.065Z,1578693002.065 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-01-10T21:50:02.071Z,1578693002.071 [YawRateCalculator] Loaded
2020-01-10T21:50:02.071Z,1578693002.071 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-01-10T21:50:02.112Z,1578693002.112 [ElevatorOffsetCalculator] Loaded
2020-01-10T21:50:02.113Z,1578693002.113 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-01-10T21:50:02.113Z,1578693002.113 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-01-10T21:50:02.114Z,1578693002.114 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-01-10T21:50:02.254Z,1578693002.254 [SBIT](DEBUG): Construct Startup Built In Test.
2020-01-10T21:50:02.277Z,1578693002.277 [SBIT] Loaded
2020-01-10T21:50:02.277Z,1578693002.277 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-01-10T21:50:02.278Z,1578693002.278 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-01-10T21:50:02.290Z,1578693002.290 [IBIT] Loaded
2020-01-10T21:50:02.290Z,1578693002.290 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-01-10T21:50:02.294Z,1578693002.294 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-01-10T21:50:02.436Z,1578693002.436 [CBIT] Loaded
2020-01-10T21:50:02.436Z,1578693002.436 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-01-10T21:50:02.436Z,1578693002.436 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-01-10T21:50:02.437Z,1578693002.437 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-01-10T21:50:02.523Z,1578693002.523 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-01-10T21:50:02.523Z,1578693002.523 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-01-10T21:50:02.623Z,1578693002.623 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-01-10T21:50:02.624Z,1578693002.624 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-01-10T21:50:02.673Z,1578693002.673 [VerticalControl](DEBUG): Construct VerticalControl.
2020-01-10T21:50:02.758Z,1578693002.758 [VerticalControl] Loaded
2020-01-10T21:50:02.759Z,1578693002.759 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-01-10T21:50:02.759Z,1578693002.759 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-01-10T21:50:02.832Z,1578693002.832 [HorizontalControl] Loaded
2020-01-10T21:50:02.832Z,1578693002.832 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-01-10T21:50:02.833Z,1578693002.833 [SpeedControl](DEBUG): Construct SpeedControl.
2020-01-10T21:50:02.834Z,1578693002.834 [SpeedControl] Loaded
2020-01-10T21:50:02.835Z,1578693002.835 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-01-10T21:50:02.835Z,1578693002.835 [LoopControl](DEBUG): Construct LoopControl.
2020-01-10T21:50:02.836Z,1578693002.836 [LoopControl] Loaded
2020-01-10T21:50:02.836Z,1578693002.836 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-01-10T21:50:02.837Z,1578693002.837 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-01-10T21:50:02.837Z,1578693002.837 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-01-10T21:50:02.870Z,1578693002.870 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-01-10T21:50:02.874Z,1578693002.874 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-01-10T21:50:02.875Z,1578693002.875 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-01-10T21:50:02.881Z,1578693002.881 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-01-10T21:50:02.882Z,1578693002.882 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B024E0
2020-01-10T21:50:02.883Z,1578693002.883 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3854
2020-01-10T21:50:02.887Z,1578693002.887 [Supervisor](INFO): Main Thread ID is 3767
2020-01-10T21:50:02.887Z,1578693002.887 [Supervisor](DEBUG): Running supervisor.
2020-01-10T21:50:02.888Z,1578693002.888 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3855
2020-01-10T21:50:02.890Z,1578693002.890 [controlThread ThreadHandler](INFO): Handler Thread ID is 3856
2020-01-10T21:50:02.891Z,1578693002.891 [controlThread](DEBUG): Initializing ControlThread
2020-01-10T21:50:02.896Z,1578693002.896 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-01-10T21:50:02.896Z,1578693002.896 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-01-10T21:50:02.901Z,1578693002.901 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-01-10T21:50:02.901Z,1578693002.901 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-01-10T21:50:02.902Z,1578693002.902 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-01-10T21:50:02.902Z,1578693002.902 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-01-10T21:50:02.902Z,1578693002.902 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-01-10T21:50:02.903Z,1578693002.903 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-01-10T21:50:02.903Z,1578693002.903 [SBIT](INFO): Initialize SBIT Component.
2020-01-10T21:50:02.904Z,1578693002.904 [SBIT](IMPORTANT): git: 2019-12-03-17-g4812c94
2020-01-10T21:50:02.904Z,1578693002.904 [SBIT](INFO): git hash: 4812c9429720da7730f61311b178b42396aebb36
2020-01-10T21:50:02.904Z,1578693002.904 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-01-10T21:50:02.905Z,1578693002.905 [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-10T21:50:02.907Z,1578693002.907 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-01-10T21:50:02.907Z,1578693002.907 [IBIT](INFO): Initialize IBIT Component.
2020-01-10T21:50:02.908Z,1578693002.908 [CBIT](DEBUG): Initialize CBIT Component.
2020-01-10T21:50:02.909Z,1578693002.909 [logger ThreadHandler](INFO): Handler Thread ID is 3857
2020-01-10T21:50:02.921Z,1578693002.921 [CBIT](DEBUG): Initialized mux pins.
2020-01-10T21:50:02.921Z,1578693002.921 [CBIT](DEBUG): Initializing the watchdog timer.
2020-01-10T21:50:02.929Z,1578693002.929 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3858
2020-01-10T21:50:02.930Z,1578693002.930 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-01-10T21:50:02.941Z,1578693002.941 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3859
2020-01-10T21:50:02.945Z,1578693002.945 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-01-10T21:50:02.945Z,1578693002.945 [CBIT](DEBUG): Initializing heartbeat.
2020-01-10T21:50:02.953Z,1578693002.953 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 3860
2020-01-10T21:50:02.954Z,1578693002.954 [CTD_NeilBrown](INFO): Powering down
2020-01-10T21:50:02.981Z,1578693002.981 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 3861
2020-01-10T21:50:02.982Z,1578693002.982 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-10T21:50:03.009Z,1578693003.009 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3862
2020-01-10T21:50:03.012Z,1578693003.012 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-01-10T21:50:03.013Z,1578693003.013 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-01-10T21:50:03.013Z,1578693003.013 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-01-10T21:50:03.013Z,1578693003.013 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-01-10T21:50:03.014Z,1578693003.014 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-01-10T21:50:03.014Z,1578693003.014 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-01-10T21:50:03.014Z,1578693003.014 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-01-10T21:50:03.014Z,1578693003.014 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-01-10T21:50:03.014Z,1578693003.014 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-01-10T21:50:03.014Z,1578693003.014 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-01-10T21:50:03.015Z,1578693003.015 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-01-10T21:50:03.015Z,1578693003.015 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-01-10T21:50:03.015Z,1578693003.015 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-01-10T21:50:03.015Z,1578693003.015 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-01-10T21:50:03.015Z,1578693003.015 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-01-10T21:50:03.016Z,1578693003.016 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-01-10T21:50:03.017Z,1578693003.017 [CBIT](DEBUG): Deactivating GF circuits.
2020-01-10T21:50:03.017Z,1578693003.017 [CBIT](DEBUG): Deactivating emergency mode.
2020-01-10T21:50:03.053Z,1578693003.053 [CBIT](DEBUG): Backplane powered.
2020-01-10T21:50:03.053Z,1578693003.053 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-01-10T21:50:03.055Z,1578693003.055 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-01-10T21:50:03.055Z,1578693003.055 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-01-10T21:50:03.056Z,1578693003.056 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-01-10T21:50:03.056Z,1578693003.056 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-01-10T21:50:03.067Z,1578693003.067 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-10T21:50:03.080Z,1578693003.080 [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-10T21:50:03.080Z,1578693003.080 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-01-10T21:50:03.167Z,1578693003.167 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-01-10T21:50:03.168Z,1578693003.168 [Default:A.Wait](DEBUG): Construct Wait.
2020-01-10T21:50:03.169Z,1578693003.169 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-10T21:50:03.209Z,1578693003.209 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-01-10T21:50:03.211Z,1578693003.211 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-01-10T21:50:03.233Z,1578693003.233 [Default:E.Execute](DEBUG): Construct Execute.
2020-01-10T21:50:03.236Z,1578693003.236 [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-10T21:50:03.241Z,1578693003.241 [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-10T21:50:03.262Z,1578693003.262 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-10T21:50:03.306Z,1578693003.306 [DDM](INFO): Powering up
2020-01-10T21:50:03.307Z,1578693003.307 [DDM](DEBUG): Initializing DDM.
2020-01-10T21:50:03.332Z,1578693003.332 [DUSBL_Hydroid](INFO): Powering up
2020-01-10T21:50:03.332Z,1578693003.332 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-10T21:50:03.345Z,1578693003.345 [Radio_Surface](INFO): Powering up
2020-01-10T21:50:03.526Z,1578693003.526 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-01-10T21:50:03.533Z,1578693003.533 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-01-10T21:50:03.534Z,1578693003.534 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-01-10T21:50:03.541Z,1578693003.541 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-01-10T21:50:03.542Z,1578693003.542 [MassServo](DEBUG): Initializing EZServoServo.
2020-01-10T21:50:03.549Z,1578693003.549 [MassServo](DEBUG): Initializing MassServo.
2020-01-10T21:50:03.550Z,1578693003.550 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-10T21:50:03.557Z,1578693003.557 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-10T21:50:03.558Z,1578693003.558 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-01-10T21:50:03.566Z,1578693003.566 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-01-10T21:50:03.758Z,1578693003.758 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-01-10T21:50:03.758Z,1578693003.758 [DropWeight] Hardware Fault, FailCount= 1
2020-01-10T21:50:03.758Z,1578693003.758 [DropWeight](ERROR): Hardware Fault
2020-01-10T21:50:03.760Z,1578693003.760 [Micromodem](INFO): Powering up
2020-01-10T21:50:03.760Z,1578693003.760 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-10T21:50:03.856Z,1578693003.856 [CommandLine](FAULT): Scheduling is paused
2020-01-10T21:50:03.857Z,1578693003.857 [CBIT](INFO): Critical error at 20200110T215003
2020-01-10T21:50:03.857Z,1578693003.857 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-01-10T21:50:03.859Z,1578693003.859 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-01-10T21:50:03.860Z,1578693003.860 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-01-10T21:50:04.445Z,1578693004.445 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-01-10T21:50:04.445Z,1578693004.445 [RudderServo](FAULT): Rudder failed to initialize
2020-01-10T21:50:04.445Z,1578693004.445 [RudderServo] Communications Fault, FailCount= 1
2020-01-10T21:50:04.445Z,1578693004.445 [RudderServo](ERROR): Communications Fault
2020-01-10T21:50:04.554Z,1578693004.554 [CBIT](INFO): Critical error at 20200110T215003
2020-01-10T21:50:04.557Z,1578693004.557 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-01-10T21:50:04.758Z,1578693004.758 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-10T21:50:04.758Z,1578693004.758 [RudderServo](INFO): Powering down
2020-01-10T21:50:05.431Z,1578693005.431 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-10T21:50:05.550Z,1578693005.550 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-10T21:50:05.554Z,1578693005.554 [CBIT](INFO): Clearing failed state for component RudderServo
2020-01-10T21:50:05.554Z,1578693005.554 [RudderServo] No Fault, FailCount= 1
2020-01-10T21:50:08.645Z,1578693008.645 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-10T21:50:09.048Z,1578693009.048 [Micromodem](INFO): Nmea in: $CATMG,2020-01-10T21:50:08.609261Z,RTC,RTC*54

2020-01-10T21:50:09.048Z,1578693009.048 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-10T21:50:08.609261Z,RTC,RTC*54

2020-01-10T21:50:09.527Z,1578693009.527 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-10T21:50:09.527Z,1578693009.527 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-10T21:50:09.935Z,1578693009.935 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-01-10T21:50:09.935Z,1578693009.935 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-01-10T21:50:10.339Z,1578693010.339 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-01-10T21:50:10.339Z,1578693010.339 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-01-10T21:50:10.743Z,1578693010.743 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-01-10T21:50:10.743Z,1578693010.743 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-10T21:50:11.147Z,1578693011.147 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-10T21:50:11.147Z,1578693011.147 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-10T21:50:11.547Z,1578693011.547 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-10T21:50:11.547Z,1578693011.547 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-10T21:50:11.963Z,1578693011.963 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-10T21:50:11.963Z,1578693011.963 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-10T21:50:12.331Z,1578693012.331 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-10T21:50:12.332Z,1578693012.332 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,10,21,50,13*40

2020-01-10T21:50:12.743Z,1578693012.743 [Micromodem](INFO): Nmea in: $CACLK,2020,1,10,21,50,13*72

2020-01-10T21:50:13.135Z,1578693013.135 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-10T21:50:14Z*75

2020-01-10T21:50:13.145Z,1578693013.145 [Micromodem](INFO): Nmea in: $CATMG,2020-01-10T21:50:14.027564Z,USER_CMD,RTC*10

2020-01-10T21:50:13.950Z,1578693013.950 [DDM](INFO): Dynamic Docking Module:Y
2020-01-10T21:50:17.187Z,1578693017.187 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-10T21:50:18.057Z,1578693018.057 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-10T21:50:18.057Z,1578693018.057 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-10T21:50:18.494Z,1578693018.494 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004464
2020-01-10T21:50:21.218Z,1578693021.218 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-01-10T21:50:29.695Z,1578693029.695 [NAL9602](INFO): Powering up NAL9602
2020-01-10T21:50:34.575Z,1578693034.575 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-10T21:50:34.575Z,1578693034.575 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20011015032539,35.0, -0.1,   0.0,1448.RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-10T21:50:40.611Z,1578693040.611 [NAL9602](INFO): NAL9602 initialized
2020-01-10T21:50:42.336Z,1578693042.336 [SBIT](IMPORTANT): Beginning Startup BIT
2020-01-10T21:50:42.365Z,1578693042.365 [CBIT](IMPORTANT): Beginning ground fault scan
2020-01-10T21:50:44.984Z,1578693044.984 [Micromodem](INFO): Nmea in: $CACYC,1,12,1,1,0,2*6B

2020-01-10T21:50:44.984Z,1578693044.984 [Micromodem](INFO): Have incoming data
2020-01-10T21:50:45.055Z,1578693045.055 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,1,858A1AB878DB70DBE89704525FDC95B5C5FE9F4147C09B083E4A8CE30F9EA23DB7C2EC1A3F0C8416B2D6BA469C63028C955B1EDAD054116D0BA2C541AD2A74E9*5A

2020-01-10T21:50:45.056Z,1578693045.056 [Micromodem](ERROR): Could not determine end of data in CARXD:$CARXD,12,1,0,1,858A1AB878DB70DBE89704525FDC95B5C5FE9F4147C09B083E4A8CE30F9EA23DB7C2EC1A3F0C8416B2D6BA469C63028C955B1EDAD054116D0BA2C541AD2A74E9*5A

2020-01-10T21:50:45.408Z,1578693045.408 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,2,4EEE1310BA042F91D0BD31A76379D458F2E8C122*52

2020-01-10T21:50:45.446Z,1578693045.446 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110215042.195346,06,1944,32,0127,0150,59,02,02,00,04,1,012,001,0,3,2,0,150,12.8,7.00,00,-4.00,-01,-0.02,39,10000,4000*7A

2020-01-10T21:50:53.573Z,1578693053.573 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.013032
CHAN A1 (24V): -0.028985
CHAN A2 (12V): -0.007297
CHAN A3 (5V): -0.002130
CHAN B0 (3.3V): 0.000026
CHAN B1 (3.15aV): 0.000013
CHAN B2 (3.15bV): 0.000186
CHAN B3 (GND): 0.002328
OPEN: 0.004974
Full Scale Calc: 4.765 mA, -1.589 mA
2020-01-10T21:51:07.692Z,1578693067.692 [Micromodem](INFO): Nmea in: $CACYC,1,12,1,1,0,2*6B

2020-01-10T21:51:07.692Z,1578693067.692 [Micromodem](INFO): Have incoming data
2020-01-10T21:51:07.699Z,1578693067.699 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,1,858A1AB878DB70DBE89704525FDC95B5C5FE9F4147C09B083E4A8CE30F9EA23DB7C2EC1A3F0C8416B2D6BA469C63028C955B1EDAD054116D0BA2C541AD2A74E9*5A

2020-01-10T21:51:08.104Z,1578693068.104 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,2,4EEE1310BA042F91D0BD31A76379D458F2E8C122*52

2020-01-10T21:51:08.106Z,1578693068.106 [Micromodem](INFO): Received command:failc;stop;configSet list;get platform_orientation degree;stop;failc
2020-01-10T21:51:08.743Z,1578693068.743 [CommandLine](IMPORTANT): got command failComponent
2020-01-10T21:51:08.743Z,1578693068.743 [CommandLine](IMPORTANT): Failed components:
2020-01-10T21:51:08.743Z,1578693068.743 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2020-01-10T21:51:08.743Z,1578693068.743 [CommandLine](IMPORTANT): got command stop
2020-01-10T21:51:08.744Z,1578693068.744 [CommandLine](IMPORTANT): Scheduling is paused
2020-01-10T21:51:08.744Z,1578693068.744 [CommandLine](IMPORTANT): got command configSet list
2020-01-10T21:51:08.744Z,1578693068.744 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-01-10T21:51:08.745Z,1578693068.745 [CommandLine](IMPORTANT): DDM.loadAtStartup=1 bool;

2020-01-10T21:51:08.745Z,1578693068.745 [CommandLine](IMPORTANT): DDM.verbosity=3 count;

2020-01-10T21:51:08.745Z,1578693068.745 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=75 count;

2020-01-10T21:51:08.745Z,1578693068.745 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout=40 millisecond;

2020-01-10T21:51:08.745Z,1578693068.745 [CommandLine](IMPORTANT): Express linearApproximation DDM.DDMMode 1.000000 count;

2020-01-10T21:51:08.745Z,1578693068.745 [CommandLine](IMPORTANT): Express all DDM.cablePresent;

2020-01-10T21:51:08.746Z,1578693068.746 [CommandLine](IMPORTANT): Express linearApproximation DDM.latchState 1.000000 count;

2020-01-10T21:51:08.746Z,1578693068.746 [CommandLine](IMPORTANT): Express linearApproximation DDM.whiskerState 1.000000 count;

2020-01-10T21:51:08.746Z,1578693068.746 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.acoustic_contact_range 1.000000 meter;

2020-01-10T21:51:08.746Z,1578693068.746 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 2.000000 degree;

2020-01-10T21:51:08.746Z,1578693068.746 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.height_above_sea_floor 2.000000 meter;

2020-01-10T21:51:08.746Z,1578693068.746 [CommandLine](IMPORTANT): Express linearApproximation platform_orientation 5.000000 degree;

2020-01-10T21:51:08.746Z,1578693068.746 [CommandLine](IMPORTANT): Express linearApproximation platform_x_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-10T21:51:08.746Z,1578693068.746 [CommandLine](IMPORTANT): Express linearApproximation platform_y_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-10T21:51:08.746Z,1578693068.746 [CommandLine](IMPORTANT): HorizontalControl.kiHeading=0.002 reciprocal_second;

2020-01-10T21:51:08.747Z,1578693068.747 [CommandLine](IMPORTANT): HorizontalControl.kpHeading=0.8 none;

2020-01-10T21:51:08.747Z,1578693068.747 [CommandLine](IMPORTANT): HorizontalControl.rudDeadband=0.05 degree;

2020-01-10T21:51:08.747Z,1578693068.747 [CommandLine](IMPORTANT): Micromodem.destinationAddress=12 enum;

2020-01-10T21:51:08.747Z,1578693068.747 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold=0 meter;

2020-01-10T21:51:08.747Z,1578693068.747 [CommandLine](IMPORTANT): PowerOnly.sampleTime=90 second;

2020-01-10T21:51:08.747Z,1578693068.747 [CommandLine](IMPORTANT): RudderServo.deviationAngle=0.0001 degree;

2020-01-10T21:51:08.747Z,1578693068.747 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter;

2020-01-10T21:51:08.747Z,1578693068.747 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter;

2020-01-10T21:51:08.748Z,1578693068.748 [CommandLine](IMPORTANT): got command get platform_orientation degree
2020-01-10T21:51:08.749Z,1578693068.749 [CommandLine](IMPORTANT): platform_orientation 72.493435 arcdeg
2020-01-10T21:51:08.749Z,1578693068.749 [CommandLine](IMPORTANT): got command stop
2020-01-10T21:51:08.749Z,1578693068.749 [CommandLine](IMPORTANT): got command failComponent
2020-01-10T21:51:08.749Z,1578693068.749 [CommandLine](IMPORTANT): Failed components:
2020-01-10T21:51:08.749Z,1578693068.749 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2020-01-10T21:51:08.783Z,1578693068.783 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110215105.247724,06,1533,31,0104,0150,29,02,02,00,02,1,012,001,0,3,2,0,150,12.5,8.00,00,-5.00,-01,-0.02,34,10000,4000*75

2020-01-10T21:51:35.814Z,1578693095.814 [SBIT](FAULT): Rudder: EXPECTED:0.000000 ACTUAL:-0.004399
2020-01-10T21:51:35.814Z,1578693095.814 [SBIT](FAULT): Control surface position failure.
2020-01-10T21:51:36.212Z,1578693096.212 [SBIT](CRITICAL): SBIT FAILED
2020-01-10T21:51:36.242Z,1578693096.242 [CommandLine](FAULT): Scheduling is paused
2020-01-10T21:51:36.243Z,1578693096.243 [CBIT](INFO): Critical error at 20200110T215136
2020-01-10T21:51:36.305Z,1578693096.305 [CommandLine](IMPORTANT): got command configSet list
2020-01-10T21:51:36.305Z,1578693096.305 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-01-10T21:51:36.306Z,1578693096.306 [CommandLine](IMPORTANT): DDM.loadAtStartup=1 bool;

2020-01-10T21:51:36.306Z,1578693096.306 [CommandLine](IMPORTANT): DDM.verbosity=3 count;

2020-01-10T21:51:36.306Z,1578693096.306 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=75 count;

2020-01-10T21:51:36.306Z,1578693096.306 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout=40 millisecond;

2020-01-10T21:51:36.307Z,1578693096.307 [CommandLine](IMPORTANT): Express linearApproximation DDM.DDMMode 1.000000 count;

2020-01-10T21:51:36.307Z,1578693096.307 [CommandLine](IMPORTANT): Express all DDM.cablePresent;

2020-01-10T21:51:36.307Z,1578693096.307 [CommandLine](IMPORTANT): Express linearApproximation DDM.latchState 1.000000 count;

2020-01-10T21:51:36.307Z,1578693096.307 [CommandLine](IMPORTANT): Express linearApproximation DDM.whiskerState 1.000000 count;

2020-01-10T21:51:36.307Z,1578693096.307 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.acoustic_contact_range 1.000000 meter;

2020-01-10T21:51:36.308Z,1578693096.308 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 2.000000 degree;

2020-01-10T21:51:36.308Z,1578693096.308 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.height_above_sea_floor 2.000000 meter;

2020-01-10T21:51:36.308Z,1578693096.308 [CommandLine](IMPORTANT): Express linearApproximation platform_orientation 5.000000 degree;

2020-01-10T21:51:36.308Z,1578693096.308 [CommandLine](IMPORTANT): Express linearApproximation platform_x_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-10T21:51:36.309Z,1578693096.309 [CommandLine](IMPORTANT): Express linearApproximation platform_y_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-10T21:51:36.309Z,1578693096.309 [CommandLine](IMPORTANT): HorizontalControl.kiHeading=0.002 reciprocal_second;

2020-01-10T21:51:36.309Z,1578693096.309 [CommandLine](IMPORTANT): HorizontalControl.kpHeading=0.8 none;

2020-01-10T21:51:36.310Z,1578693096.310 [CommandLine](IMPORTANT): HorizontalControl.rudDeadband=0.05 degree;

2020-01-10T21:51:36.310Z,1578693096.310 [CommandLine](IMPORTANT): Micromodem.destinationAddress=12 enum;

2020-01-10T21:51:36.311Z,1578693096.311 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold=0 meter;

2020-01-10T21:51:36.311Z,1578693096.311 [CommandLine](IMPORTANT): PowerOnly.sampleTime=90 second;

2020-01-10T21:51:36.311Z,1578693096.311 [CommandLine](IMPORTANT): RudderServo.deviationAngle=0.0001 degree;

2020-01-10T21:51:36.311Z,1578693096.311 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter;

2020-01-10T21:51:36.312Z,1578693096.312 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter;

2020-01-10T21:51:36.566Z,1578693096.566 [MissionManager](IMPORTANT): Started mission Startup
2020-01-10T21:51:36.566Z,1578693096.566 [Startup] Running Loop=1
2020-01-10T21:51:36.566Z,1578693096.566 [Startup](DEBUG): Aggregate::initialize Startup
2020-01-10T21:51:36.566Z,1578693096.566 [Startup:A.GoToSurface] Running Loop=1
2020-01-10T21:51:36.566Z,1578693096.566 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-10T21:51:36.567Z,1578693096.567 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-10T21:51:36.567Z,1578693096.567 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-10T21:51:36.568Z,1578693096.568 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-10T21:51:36.568Z,1578693096.568 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-10T21:51:36.568Z,1578693096.568 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-10T21:51:36.575Z,1578693096.575 [Startup:StartupSatComms] Running Loop=1
2020-01-10T21:51:36.575Z,1578693096.575 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-01-10T21:51:36.575Z,1578693096.575 [Startup:StartupSatComms:A] Running Loop=1
2020-01-10T21:51:37.047Z,1578693097.047 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-01-10T21:51:54.692Z,1578693114.692 [Micromodem](INFO): Nmea in: $CACYC,1,12,1,1,0,1*68

2020-01-10T21:51:54.692Z,1578693114.692 [Micromodem](INFO): Have incoming data
2020-01-10T21:51:54.695Z,1578693114.695 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,1,3D90B8D172D0E11A94BB9A0D6EC0434A2AB488F6*51

2020-01-10T21:51:54.696Z,1578693114.696 [Micromodem](INFO): Received command:stop
2020-01-10T21:51:54.763Z,1578693114.763 [CommandLine](IMPORTANT): got command stop
2020-01-10T21:51:54.763Z,1578693114.763 [CommandLine](IMPORTANT): Scheduling is paused
2020-01-10T21:51:55.098Z,1578693115.098 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110215153.746077,06,1834,32,0126,0150,59,02,02,00,03,1,012,001,0,3,1,0,150,5.2,8.00,00,-5.00,-01,-0.02,45,10000,4000*48

2020-01-10T21:52:36.761Z,1578693156.761 [Startup:StartupSatComms:A](INFO): Timed out from 2020-01-10T21:51:36.6Z
2020-01-10T21:52:36.761Z,1578693156.761 [Startup:StartupSatComms:A] Stopped
2020-01-10T21:52:36.761Z,1578693156.761 [Startup:StartupSatComms:B] Running Loop=1
2020-01-10T21:52:37.149Z,1578693157.149 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-10T21:52:38.763Z,1578693158.763 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 75ED185E000000005D00000400900000000000000000151D80030C5669BF31555EAE1409A708CE2489EC102F971AAFC35C3D87A14EFD88D7E667BF45C1E3A334
2020-01-10T21:52:38.764Z,1578693158.764 [Micromodem](INFO): Outgoing frame #2, 64 bytes: DD6C8876D2B9382E2430F9BF3872E30A849C87CDF09BFDFCA0A5CD9E9928A729E573A91C13B399CEB8DFC3189A530F9EE28311F836351A91A0C29B6BD0DD3247
2020-01-10T21:52:38.764Z,1578693158.764 [Micromodem](INFO): Outgoing frame #3, 15 bytes: 3CDDAE0596C02AC38AC6547D950000
2020-01-10T21:52:38.764Z,1578693158.764 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-10T21:52:38.946Z,1578693158.946 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-10T21:52:39.327Z,1578693159.327 [Micromodem](INFO): Nmea in: $CADRQ,215239,1,12,0,64,1*7A

2020-01-10T21:52:39.328Z,1578693159.328 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,75ED185E000000005D00000400900000000000000000151D80030C5669BF31555EAE1409A708CE2489EC102F971AAFC35C3D87A14EFD88D7E667BF45C1E3A334*4B

2020-01-10T21:52:39.720Z,1578693159.720 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T21:52:40.132Z,1578693160.132 [Micromodem](INFO): Nmea in: $CADRQ,215240,1,12,0,64,2*77

2020-01-10T21:52:40.132Z,1578693160.132 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,DD6C8876D2B9382E2430F9BF3872E30A849C87CDF09BFDFCA0A5CD9E9928A729E573A91C13B399CEB8DFC3189A530F9EE28311F836351A91A0C29B6BD0DD3247*3A

2020-01-10T21:52:40.559Z,1578693160.559 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T21:52:40.943Z,1578693160.943 [Micromodem](INFO): Nmea in: $CADRQ,215241,1,12,0,64,3*77

2020-01-10T21:52:40.950Z,1578693160.950 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,3CDDAE0596C02AC38AC6547D950000*37

2020-01-10T21:52:41.335Z,1578693161.335 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,15*4D

2020-01-10T21:52:41.738Z,1578693161.738 [Micromodem](INFO): Nmea in: $CATXP,143*44

2020-01-10T21:52:45.391Z,1578693165.391 [Micromodem](INFO): Nmea in: $CATXF,143*52

2020-01-10T21:52:46.601Z,1578693166.601 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,215241.974226,3,0,200,4000,10000,1,1,12,1,3,3,3,143*7B

2020-01-10T21:52:47.014Z,1578693167.014 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-10T21:52:47.399Z,1578693167.399 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-10T21:52:47.673Z,1578693167.673 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20200110T213237/Courier0000.lzma
2020-01-10T21:52:47.811Z,1578693167.811 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-10T21:52:47.812Z,1578693167.812 [Micromodem](INFO): Sent 135 bytes from file Logs/20200110T213237/Courier0000.lzma.parts
2020-01-10T21:52:47.812Z,1578693167.812 [Micromodem](INFO): Packets left to send: 0
2020-01-10T21:52:48.215Z,1578693168.215 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110215246.479348,06,532,27,0113,0150,59,02,02,00,02,1,012,001,0,4,1,0,150,25.0,7.00,15,-4.00,-01,-0.02,24,10000,4000*4C

2020-01-10T21:52:49.303Z,1578693169.303 [Micromodem](INFO): Outgoing frame #1, 64 bytes: AAEE185E000000005D00000400900000000000000000151D80030C5669BF31555EAE1409A708CE2489EC102F971AAFC35C3D87A14EFD88D7E667BF45C1E3A334
2020-01-10T21:52:49.303Z,1578693169.303 [Micromodem](INFO): Outgoing frame #2, 64 bytes: DF672D90DA03735CD40E7F2A097D0DAD7F53C75C667D1973AFBABBFD7B81ECF3BC2DDFAA468A79B650C7FE81997189A7C553CA43AD4B4D6D173718E86FA92D2D
2020-01-10T21:52:49.304Z,1578693169.304 [Micromodem](INFO): Outgoing frame #3, 16 bytes: FDC819A0484822098B94A4039EE78000
2020-01-10T21:52:49.304Z,1578693169.304 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-10T21:52:49.683Z,1578693169.683 [DataOverHttps](FAULT): Server acknowledged file Logs/20200110T213237/Courier0000.lzma, notLogs/20200110T213746/Courier0000.lzma
2020-01-10T21:52:49.683Z,1578693169.683 [DataOverHttps](INFO): SBD MOMSN=12190882
2020-01-10T21:52:50.307Z,1578693170.307 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-10T21:52:50.707Z,1578693170.707 [Micromodem](INFO): Nmea in: $CADRQ,215250,1,12,0,64,1*75

2020-01-10T21:52:50.707Z,1578693170.707 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,AAEE185E000000005D00000400900000000000000000151D80030C5669BF31555EAE1409A708CE2489EC102F971AAFC35C3D87A14EFD88D7E667BF45C1E3A334*48

2020-01-10T21:52:51.117Z,1578693171.117 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T21:52:51.515Z,1578693171.515 [Micromodem](INFO): Nmea in: $CADRQ,215251,1,12,0,64,2*77

2020-01-10T21:52:51.516Z,1578693171.516 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,DF672D90DA03735CD40E7F2A097D0DAD7F53C75C667D1973AFBABBFD7B81ECF3BC2DDFAA468A79B650C7FE81997189A7C553CA43AD4B4D6D173718E86FA92D2D*48

2020-01-10T21:52:51.919Z,1578693171.919 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T21:52:52.327Z,1578693172.327 [Micromodem](INFO): Nmea in: $CADRQ,215252,1,12,0,64,3*75

2020-01-10T21:52:52.328Z,1578693172.328 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,FDC819A0484822098B94A4039EE78000*45

2020-01-10T21:52:52.727Z,1578693172.727 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,16*4E

2020-01-10T21:52:53.130Z,1578693173.130 [Micromodem](INFO): Nmea in: $CATXP,144*43

2020-01-10T21:52:56.771Z,1578693176.771 [Micromodem](INFO): Nmea in: $CATXF,144*55

2020-01-10T21:52:57.178Z,1578693177.178 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,215253.355690,3,0,200,4000,10000,1,1,12,1,3,3,3,144*7F

2020-01-10T21:52:58.387Z,1578693178.387 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-10T21:52:58.791Z,1578693178.791 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-10T21:52:59.191Z,1578693179.191 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-10T21:52:59.192Z,1578693179.192 [Micromodem](INFO): Sent 136 bytes from file Logs/20200110T213746/Courier0000.lzma.parts
2020-01-10T21:52:59.192Z,1578693179.192 [Micromodem](INFO): Packets left to send: 0
2020-01-10T21:52:59.606Z,1578693179.606 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110215257.860361,06,468,26,0106,0150,89,02,02,00,03,1,012,001,0,4,1,0,150,19.1,6.00,13,-3.00,-01,-0.06,31,10000,4000*4A

2020-01-10T21:53:00.585Z,1578693180.585 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 8FEF185E000001005D00000400360100000000000000151D80030C5669BF31555EAE1409A708CE2489EC102F971AAFC35C3D87A14EFD88D7E667BF45C1E3A334
2020-01-10T21:53:00.586Z,1578693180.586 [Micromodem](INFO): Outgoing frame #2, 64 bytes: DF0AAC01C45F28C0A027E012CB284E1E881A5C02D66F426FC7B15215DA9E6671CE8BA1197FBEF7E0B7D8605803F339671AB7259BBC7333FCF9A72136DF0F0C26
2020-01-10T21:53:00.586Z,1578693180.586 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 155C3F7A46529F38ABB69B2E6BCDC21064E0EA6C85BC75177CEE7398B277D01D1C6A7272D9DB1B30FBFDCEABA79C9ACA450E085358BA630E38FA5A71670CEF0D
2020-01-10T21:53:00.586Z,1578693180.586 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-10T21:53:01.535Z,1578693181.535 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-10T21:53:01.935Z,1578693181.935 [Micromodem](INFO): Nmea in: $CADRQ,215301,1,12,0,64,1*70

2020-01-10T21:53:01.935Z,1578693181.935 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,8FEF185E000001005D00000400360100000000000000151D80030C5669BF31555EAE1409A708CE2489EC102F971AAFC35C3D87A14EFD88D7E667BF45C1E3A334*39

2020-01-10T21:53:02.416Z,1578693182.416 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T21:53:02.744Z,1578693182.744 [Micromodem](INFO): Nmea in: $CADRQ,215302,1,12,0,64,2*70

2020-01-10T21:53:02.744Z,1578693182.744 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,DF0AAC01C45F28C0A027E012CB284E1E881A5C02D66F426FC7B15215DA9E6671CE8BA1197FBEF7E0B7D8605803F339671AB7259BBC7333FCF9A72136DF0F0C26*3D

2020-01-10T21:53:02.915Z,1578693182.915 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-01-10T21:53:02.915Z,1578693182.915 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-10T21:53:03.030Z,1578693183.030 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-10T21:53:03.151Z,1578693183.151 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T21:53:03.371Z,1578693183.371 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-10T21:53:03.371Z,1578693183.371 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-01-10T21:53:03.551Z,1578693183.551 [Micromodem](INFO): Nmea in: $CADRQ,215303,1,12,0,64,3*70

2020-01-10T21:53:03.551Z,1578693183.551 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,155C3F7A46529F38ABB69B2E6BCDC21064E0EA6C85BC75177CEE7398B277D01D1C6A7272D9DB1B30FBFDCEABA79C9ACA450E085358BA630E38FA5A71670CEF0D*39

2020-01-10T21:53:03.955Z,1578693183.955 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T21:53:04.359Z,1578693184.359 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-10T21:53:08.022Z,1578693188.022 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-10T21:53:08.177Z,1578693188.177 [DataOverHttps](INFO): Sending 259 bytes from file Logs/20200110T214135/Courier0000.lzma
2020-01-10T21:53:08.400Z,1578693188.400 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,215304.623803,3,0,200,4000,10000,1,1,12,1,3,3,3,192*77

2020-01-10T21:53:09.611Z,1578693189.611 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-10T21:53:10.017Z,1578693190.017 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-10T21:53:10.182Z,1578693190.182 [DataOverHttps](INFO): Moved sent file to Logs/20200110T214135/Courier0000.lzma.bak
2020-01-10T21:53:10.182Z,1578693190.182 [DataOverHttps](INFO): SBD MOMSN=12190887
2020-01-10T21:53:10.419Z,1578693190.419 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-10T21:53:10.420Z,1578693190.420 [Micromodem](INFO): Sent 184 bytes from file Logs/20200110T214135/Courier0000.lzma.parts
2020-01-10T21:53:10.420Z,1578693190.420 [Micromodem](INFO): Packets left to send: 1
2020-01-10T21:53:10.832Z,1578693190.832 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110215309.178965,06,497,26,0102,0150,89,02,02,00,03,1,012,001,0,4,1,0,150,23.5,5.00,12,-2.00,-01,-0.06,28,10000,4000*4C

2020-01-10T21:53:11.687Z,1578693191.687 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 80F1185E000000005D00000400BE0000000000000000151D80030C5669BF31555EAE1409A708CE2489EC102F971AAFC35C3D87A14EFD88D7E667BF45C1E3A334
2020-01-10T21:53:11.687Z,1578693191.687 [Micromodem](INFO): Outgoing frame #2, 64 bytes: DF52633A4768EC430441D46D67F91E30A96C5B03F7E39947E8DA768D838F1A856682D75325361D6CE4711DEB67632E3D48075F20B8770C78AD55505F3A4DD956
2020-01-10T21:53:11.688Z,1578693191.688 [Micromodem](INFO): Outgoing frame #3, 38 bytes: 3730591B205112D4D47374819E3E66AA1271005B3D9F816C855C0E919A29E6E2E939B569E600
2020-01-10T21:53:11.688Z,1578693191.688 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-10T21:53:12.691Z,1578693192.691 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-10T21:53:13.099Z,1578693193.099 [Micromodem](INFO): Nmea in: $CADRQ,215312,1,12,0,64,1*72

2020-01-10T21:53:13.099Z,1578693193.099 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,80F1185E000000005D00000400BE0000000000000000151D80030C5669BF31555EAE1409A708CE2489EC102F971AAFC35C3D87A14EFD88D7E667BF45C1E3A334*39

2020-01-10T21:53:13.499Z,1578693193.499 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T21:53:13.935Z,1578693193.935 [Micromodem](INFO): Nmea in: $CADRQ,215314,1,12,0,64,2*77

2020-01-10T21:53:13.935Z,1578693193.935 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,DF52633A4768EC430441D46D67F91E30A96C5B03F7E39947E8DA768D838F1A856682D75325361D6CE4711DEB67632E3D48075F20B8770C78AD55505F3A4DD956*40

2020-01-10T21:53:14.315Z,1578693194.315 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T21:53:14.711Z,1578693194.711 [Micromodem](INFO): Nmea in: $CADRQ,215314,1,12,0,64,3*76

2020-01-10T21:53:14.711Z,1578693194.711 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,3730591B205112D4D47374819E3E66AA1271005B3D9F816C855C0E919A29E6E2E939B569E600*38

2020-01-10T21:53:15.127Z,1578693195.127 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,38*42

2020-01-10T21:53:15.518Z,1578693195.518 [Micromodem](INFO): Nmea in: $CATXP,166*43

2020-01-10T21:53:19.155Z,1578693199.155 [Micromodem](INFO): Nmea in: $CATXF,166*55

2020-01-10T21:53:19.564Z,1578693199.564 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,215315.765253,3,0,200,4000,10000,1,1,12,1,3,3,3,166*70

2020-01-10T21:53:20.775Z,1578693200.775 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-10T21:53:21.179Z,1578693201.179 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-10T21:53:21.586Z,1578693201.586 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-10T21:53:21.587Z,1578693201.587 [Micromodem](INFO): Sent 158 bytes from file Logs/20200110T214952/Courier0000.lzma.parts
2020-01-10T21:53:21.587Z,1578693201.587 [Micromodem](INFO): Packets left to send: 0
2020-01-10T21:53:21.986Z,1578693201.986 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110215320.295764,06,527,27,0112,0150,59,02,02,00,03,1,012,001,0,4,1,0,150,22.8,7.00,16,-4.00,-01,-0.05,27,10000,4000*4F

2020-01-10T21:53:22.890Z,1578693202.890 [Startup:StartupSatComms:B] Stopped
2020-01-10T21:53:22.890Z,1578693202.890 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-01-10T21:53:22.891Z,1578693202.891 [Startup:StartupSatComms] Stopped
2020-01-10T21:53:22.891Z,1578693202.891 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-01-10T21:53:22.891Z,1578693202.891 [Startup](INFO): Completed Startup
2020-01-10T21:53:22.892Z,1578693202.892 [MissionManager](INFO): Startup is completed.
2020-01-10T21:53:22.892Z,1578693202.892 [MissionManager](INFO): Uninitializing Mission Startup
2020-01-10T21:53:22.892Z,1578693202.892 [Startup] Stopped
2020-01-10T21:53:22.892Z,1578693202.892 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-01-10T21:53:22.892Z,1578693202.892 [Startup:A.GoToSurface] Stopped
2020-01-10T21:53:22.892Z,1578693202.892 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-10T21:53:23.077Z,1578693203.077 [MissionManager](IMPORTANT): Started mission Default
2020-01-10T21:53:23.078Z,1578693203.078 [Default] Running Loop=1
2020-01-10T21:53:23.078Z,1578693203.078 [Default](DEBUG): Aggregate::initialize Default
2020-01-10T21:53:23.079Z,1578693203.079 [Default:B.GoToSurface] Running Loop=1
2020-01-10T21:53:23.079Z,1578693203.079 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-10T21:53:23.079Z,1578693203.079 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-10T21:53:23.079Z,1578693203.079 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-10T21:53:23.080Z,1578693203.080 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-10T21:53:23.080Z,1578693203.080 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-10T21:53:23.080Z,1578693203.080 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-10T21:53:23.089Z,1578693203.089 [Default:A.Wait] Running Loop=1
2020-01-10T21:53:23.089Z,1578693203.089 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-10T21:53:24.257Z,1578693204.257 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-01-10T21:53:24.257Z,1578693204.257 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-01-10T21:53:24.257Z,1578693204.257 [RDI_Pathfinder](ERROR): Communications Fault
2020-01-10T21:53:24.257Z,1578693204.257 [RDI_Pathfinder](ERROR): Failed to parse:
2020-01-10T21:53:24.288Z,1578693204.288 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-01-10T21:53:24.625Z,1578693204.625 [RDI_Pathfinder](INFO): Powering down
2020-01-10T21:53:25.430Z,1578693205.430 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-01-10T21:53:25.430Z,1578693205.430 [RDI_Pathfinder] No Fault, FailCount= 1
2020-01-10T21:53:29.432Z,1578693209.432 [DataOverHttps](INFO): Sending 818 bytes from file Logs/20200109T222223/Express0001.lzma
2020-01-10T21:53:31.434Z,1578693211.434 [DataOverHttps](INFO): Moved sent file to Logs/20200109T222223/Express0001.lzma.bak
2020-01-10T21:53:31.434Z,1578693211.434 [DataOverHttps](INFO): SBD MOMSN=12190896
2020-01-10T21:53:36.308Z,1578693216.308 [Default:A.Wait](INFO): Done Waiting.
2020-01-10T21:53:36.308Z,1578693216.308 [Default:A.Wait] Stopped
2020-01-10T21:53:36.308Z,1578693216.308 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-10T21:53:36.691Z,1578693216.691 [Default:CheckIn] Running Loop=1
2020-01-10T21:53:36.691Z,1578693216.691 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-10T21:53:36.691Z,1578693216.691 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-10T21:53:37.211Z,1578693217.211 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-01-10T21:53:38.710Z,1578693218.710 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-10T21:54:06.699Z,1578693246.699 [Micromodem](INFO): Nmea in: $CACYC,1,12,1,1,0,1*68

2020-01-10T21:54:06.700Z,1578693246.700 [Micromodem](INFO): Have incoming data
2020-01-10T21:54:06.703Z,1578693246.703 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,1,3D90B8D172D0E11A94BB9A0D6EC0434A2AB488F6*51

2020-01-10T21:54:06.704Z,1578693246.704 [Micromodem](INFO): Received command:stop
2020-01-10T21:54:06.747Z,1578693246.747 [CommandLine](IMPORTANT): got command stop
2020-01-10T21:54:07.110Z,1578693247.110 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110215405.541907,06,2065,33,0148,0150,59,02,02,00,03,1,012,001,0,3,1,0,150,20.3,7.00,00,-4.00,-01,-0.03,34,10000,4000*7C

2020-01-10T21:55:19.040Z,1578693319.040 [RDI_Pathfinder](ERROR): Failed to parse:768,-32768,-32768,V
2020-01-10T21:55:44.063Z,1578693344.063 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-01-10T21:56:03.979Z,1578693363.979 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-01-10T21:56:03.979Z,1578693363.979 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-10T21:56:04.013Z,1578693364.013 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-10T21:56:04.325Z,1578693364.325 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-10T21:56:04.325Z,1578693364.325 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-01-10T21:56:35.897Z,1578693395.897 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-01-10T21:56:35.897Z,1578693395.897 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-01-10T21:56:35.897Z,1578693395.897 [RDI_Pathfinder](ERROR): Communications Fault
2020-01-10T21:56:35.897Z,1578693395.897 [RDI_Pathfinder](ERROR): Failed to parse:
2020-01-10T21:56:35.930Z,1578693395.930 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-01-10T21:56:36.293Z,1578693396.293 [RDI_Pathfinder](INFO): Powering down
2020-01-10T21:56:37.099Z,1578693397.099 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-01-10T21:56:37.100Z,1578693397.100 [RDI_Pathfinder] No Fault, FailCount= 1
2020-01-10T21:56:50.346Z,1578693410.346 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-10T21:57:31.160Z,1578693451.160 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-01-10T21:57:31.163Z,1578693451.163 [BPC1](INFO): Received data from all battery sticks.
2020-01-10T21:58:02.666Z,1578693482.666 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-10T21:58:02.666Z,1578693482.666 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20011015105367,.1,   0.0,1448.9,  0
2020-01-10T21:58:37.037Z,1578693517.037 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-10T21:53:36.7Z
2020-01-10T21:58:37.037Z,1578693517.037 [Default:CheckIn:Read_GPS] Stopped
2020-01-10T21:58:37.037Z,1578693517.037 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-10T21:58:37.430Z,1578693517.430 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-10T21:58:38.742Z,1578693518.742 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 80F1185E040001005D00000400540100000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A
2020-01-10T21:58:38.743Z,1578693518.743 [Micromodem](INFO): Outgoing frame #2, 64 bytes: EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C42BBF445E54CD5F36943A3BDD9E89309E9017DB79DD009A333A47CF42F3BE4E4AD4301D597F
2020-01-10T21:58:38.743Z,1578693518.743 [Micromodem](INFO): Outgoing frame #3, 64 bytes: FFA3EC7FA24353C087A3A1B3BD161F92F69707865251F3B93C9772C25CD67B52231335EF84B8E92D5FFA309E6B04FFDD6F183B3523039885D91E471320960191
2020-01-10T21:58:38.744Z,1578693518.744 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-10T21:58:38.911Z,1578693518.911 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-10T21:58:39.283Z,1578693519.283 [Micromodem](INFO): Nmea in: $CADRQ,215839,1,12,0,64,1*70

2020-01-10T21:58:39.283Z,1578693519.283 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,80F1185E040001005D00000400540100000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A*4B

2020-01-10T21:58:39.687Z,1578693519.687 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T21:58:40.107Z,1578693520.107 [Micromodem](INFO): Nmea in: $CADRQ,215840,1,12,0,64,2*7D

2020-01-10T21:58:40.107Z,1578693520.107 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C42BBF445E54CD5F36943A3BDD9E89309E9017DB79DD009A333A47CF42F3BE4E4AD4301D597F*3D

2020-01-10T21:58:40.495Z,1578693520.495 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T21:58:40.907Z,1578693520.907 [Micromodem](INFO): Nmea in: $CADRQ,215841,1,12,0,64,3*7D

2020-01-10T21:58:40.907Z,1578693520.907 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,FFA3EC7FA24353C087A3A1B3BD161F92F69707865251F3B93C9772C25CD67B52231335EF84B8E92D5FFA309E6B04FFDD6F183B3523039885D91E471320960191*45

2020-01-10T21:58:41.302Z,1578693521.302 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T21:58:41.703Z,1578693521.703 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-10T21:58:44.849Z,1578693524.849 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20200110T214952/Courier0004.lzma
2020-01-10T21:58:45.339Z,1578693525.339 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-10T21:58:45.752Z,1578693525.752 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,215841.983383,3,0,200,4000,10000,1,1,12,1,3,3,3,192*7B

2020-01-10T21:58:46.626Z,1578693526.626 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-10T21:58:46.854Z,1578693526.854 [DataOverHttps](INFO): Moved sent file to Logs/20200110T214952/Courier0004.lzma.bak
2020-01-10T21:58:46.854Z,1578693526.854 [DataOverHttps](INFO): SBD MOMSN=12190913
2020-01-10T21:58:46.959Z,1578693526.959 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-10T21:58:47.368Z,1578693527.368 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-10T21:58:47.369Z,1578693527.369 [Micromodem](INFO): Sent 184 bytes from file Logs/20200110T214952/Courier0004.lzma.parts
2020-01-10T21:58:47.369Z,1578693527.369 [Micromodem](INFO): Packets left to send: 1
2020-01-10T21:58:47.892Z,1578693527.892 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110215846.536478,06,396,25,0105,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,21.8,5.00,11,-2.00,-01,-0.08,26,10000,4000*40

2020-01-10T21:58:49.425Z,1578693529.425 [Default:CheckIn:Read_Iridium] Stopped
2020-01-10T21:58:49.425Z,1578693529.425 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-10T21:58:49.425Z,1578693529.425 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-10T21:58:50.322Z,1578693530.322 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-01-10T21:58:50.322Z,1578693530.322 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0    +0.00,        +0.00,   0.00,120.00
2020-01-10T21:59:04.848Z,1578693544.848 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-01-10T21:59:04.848Z,1578693544.848 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-10T21:59:04.858Z,1578693544.858 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-10T21:59:05.265Z,1578693545.265 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-10T21:59:05.265Z,1578693545.265 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-01-10T21:59:33.080Z,1578693573.080 [DataOverHttps](INFO): Sending 775 bytes from file Logs/20200109T222730/Express0001.lzma
2020-01-10T21:59:35.082Z,1578693575.082 [DataOverHttps](INFO): Moved sent file to Logs/20200109T222730/Express0001.lzma.bak
2020-01-10T21:59:35.082Z,1578693575.082 [DataOverHttps](INFO): SBD MOMSN=12190915
2020-01-10T22:00:11.498Z,1578693611.498 [RDI_Pathfinder](ERROR): Failed to parse:
:   +0.00,        +0.00,        +0.00,   0.00,200.00
2020-01-10T22:00:27.630Z,1578693627.630 [Micromodem](INFO): Nmea in: $CACYC,1,12,1,1,0,1*68

2020-01-10T22:00:27.630Z,1578693627.630 [Micromodem](INFO): Have incoming data
2020-01-10T22:00:27.633Z,1578693627.633 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,1,3D90B8D172D0E11A94BB9A0D6EC0434A2AB488F6*51

2020-01-10T22:00:27.634Z,1578693627.634 [Micromodem](INFO): Received command:stop
2020-01-10T22:00:27.725Z,1578693627.725 [CommandLine](IMPORTANT): got command stop
2020-01-10T22:00:28.034Z,1578693628.034 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110220026.369113,06,1805,32,0126,0150,59,02,02,00,04,1,012,001,0,3,1,0,150,27.0,8.00,00,-5.00,-01,-0.02,24,10000,4000*77

2020-01-10T22:00:32.488Z,1578693632.488 [Micromodem](INFO): Nmea in: $CACYC,1,12,1,1,0,1*68

2020-01-10T22:00:32.488Z,1578693632.488 [Micromodem](INFO): Have incoming data
2020-01-10T22:00:32.493Z,1578693632.493 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,1,EA49DA6859B6D6FDD7866B1D923D9D8A099CA094*52

2020-01-10T22:00:32.494Z,1578693632.494 [Micromodem](INFO): Received command:failc
2020-01-10T22:00:32.580Z,1578693632.580 [CommandLine](IMPORTANT): got command failComponent
2020-01-10T22:00:32.580Z,1578693632.580 [CommandLine](IMPORTANT): Failed components:
2020-01-10T22:00:32.580Z,1578693632.580 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2020-01-10T22:00:32.888Z,1578693632.888 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110220031.474955,06,1781,32,0124,0150,59,02,02,00,03,1,012,001,0,3,1,0,150,26.9,8.00,00,-5.00,-01,-0.02,24,10000,4000*7E

2020-01-10T22:00:41.767Z,1578693641.767 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-01-10T22:00:41.767Z,1578693641.767 [NAL9602] Data Fault, FailCount= 1
2020-01-10T22:00:41.767Z,1578693641.767 [NAL9602](ERROR): Data Fault
2020-01-10T22:00:41.852Z,1578693641.852 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-10T22:00:42.167Z,1578693642.167 [NAL9602](INFO): Powering down
2020-01-10T22:00:43.021Z,1578693643.021 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-10T22:00:43.021Z,1578693643.021 [NAL9602] No Fault, FailCount= 1
2020-01-10T22:01:12.469Z,1578693672.469 [NAL9602](INFO): Powering up NAL9602
2020-01-10T22:01:23.379Z,1578693683.379 [NAL9602](INFO): NAL9602 initialized
2020-01-10T22:02:05.834Z,1578693725.834 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-01-10T22:02:05.834Z,1578693725.834 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-10T22:02:05.860Z,1578693725.860 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-10T22:02:06.266Z,1578693726.266 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-10T22:02:06.266Z,1578693726.266 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-01-10T22:02:58.894Z,1578693778.894 [CommandLine](IMPORTANT): got command quit
2020-01-10T22:02:59.901Z,1578693779.901 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T22:02:59.901Z,1578693779.901 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-10T22:02:59.917Z,1578693779.917 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-01-10T22:02:59.917Z,1578693779.917 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-10T22:02:59.918Z,1578693779.918 [CommandLine](INFO): Join timeout helper Thread ID is 3899
2020-01-10T22:02:59.921Z,1578693779.921 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-01-10T22:02:59.921Z,1578693779.921 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-10T22:02:59.921Z,1578693779.921 [NavChartDb](INFO): Join timeout helper Thread ID is 3900
2020-01-10T22:03:00.313Z,1578693780.313 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T22:03:00.313Z,1578693780.313 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-10T22:03:00.336Z,1578693780.336 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-01-10T22:03:00.336Z,1578693780.336 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-10T22:03:00.336Z,1578693780.336 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 3901
2020-01-10T22:03:00.477Z,1578693780.477 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T22:03:00.477Z,1578693780.477 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-10T22:03:00.478Z,1578693780.478 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-10T22:03:00.493Z,1578693780.493 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2020-01-10T22:03:00.493Z,1578693780.493 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-10T22:03:00.493Z,1578693780.493 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 3902
2020-01-10T22:03:00.525Z,1578693780.525 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T22:03:00.525Z,1578693780.525 [CTD_NeilBrown](INFO): Powering down
2020-01-10T22:03:00.537Z,1578693780.537 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-10T22:03:00.553Z,1578693780.553 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-01-10T22:03:00.553Z,1578693780.553 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-10T22:03:00.553Z,1578693780.553 [Radio_Surface](INFO): Join timeout helper Thread ID is 3903
2020-01-10T22:03:00.861Z,1578693780.861 [Radio_Surface](INFO): Powering down
2020-01-10T22:03:00.862Z,1578693780.862 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T22:03:00.862Z,1578693780.862 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-10T22:03:00.878Z,1578693780.878 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-01-10T22:03:00.878Z,1578693780.878 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-10T22:03:00.880Z,1578693780.880 [DataOverHttps](INFO): Join timeout helper Thread ID is 3904
2020-01-10T22:03:01.349Z,1578693781.349 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T22:03:01.350Z,1578693781.350 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-10T22:03:01.365Z,1578693781.365 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-01-10T22:03:01.366Z,1578693781.366 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-10T22:03:01.366Z,1578693781.366 [logger](INFO): Join timeout helper Thread ID is 3905
2020-01-10T22:03:01.381Z,1578693781.381 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T22:03:01.381Z,1578693781.381 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-10T22:03:01.386Z,1578693781.386 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-01-10T22:03:01.386Z,1578693781.386 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-10T22:03:01.386Z,1578693781.386 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-01-10T22:03:01.386Z,1578693781.386 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-10T22:03:01.386Z,1578693781.386 [controlThread](INFO): Join timeout helper Thread ID is 3906
2020-01-10T22:03:01.549Z,1578693781.549 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T22:03:01.549Z,1578693781.549 [controlThread](DEBUG): Uninitializing ControlThread
2020-01-10T22:03:01.550Z,1578693781.550 [AHRS_M2](INFO): Powering down
2020-01-10T22:03:01.621Z,1578693781.621 [DDM](INFO): Powering down
2020-01-10T22:03:01.713Z,1578693781.713 [DUSBL_Hydroid](INFO): Powering down
2020-01-10T22:03:01.805Z,1578693781.805 [Micromodem](INFO): Powering down
2020-01-10T22:03:01.901Z,1578693781.901 [NAL9602](INFO): Powering down
2020-01-10T22:03:01.973Z,1578693781.973 [RDI_Pathfinder](INFO): Powering down
2020-01-10T22:03:01.974Z,1578693781.974 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-01-10T22:03:01.975Z,1578693781.975 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-01-10T22:03:01.976Z,1578693781.976 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-01-10T22:03:01.976Z,1578693781.976 [MissionManager](INFO): Uninitializing Mission Default
2020-01-10T22:03:01.976Z,1578693781.976 [Default] Stopped
2020-01-10T22:03:01.976Z,1578693781.976 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-10T22:03:01.976Z,1578693781.976 [Default:B.GoToSurface] Stopped
2020-01-10T22:03:01.977Z,1578693781.977 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-10T22:03:01.977Z,1578693781.977 [Default:CheckIn] Stopped
2020-01-10T22:03:01.977Z,1578693781.977 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-10T22:03:01.977Z,1578693781.977 [Default:CheckIn:C.Wait] Stopped
2020-01-10T22:03:01.977Z,1578693781.977 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-10T22:03:01.979Z,1578693781.979 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-01-10T22:03:01.980Z,1578693781.980 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-01-10T22:03:01.980Z,1578693781.980 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-01-10T22:03:01.980Z,1578693781.980 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-01-10T22:03:01.980Z,1578693781.980 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-01-10T22:03:01.981Z,1578693781.981 [BuoyancyServo](INFO): Powering down
2020-01-10T22:03:01.993Z,1578693781.993 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-01-10T22:03:01.993Z,1578693781.993 [ElevatorServo](INFO): Powering down
2020-01-10T22:03:01.994Z,1578693781.994 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-01-10T22:03:01.994Z,1578693781.994 [MassServo](INFO): Powering down
2020-01-10T22:03:01.995Z,1578693781.995 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-10T22:03:01.995Z,1578693781.995 [RudderServo](INFO): Powering down
2020-01-10T22:03:01.995Z,1578693781.995 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-01-10T22:03:01.996Z,1578693781.996 [ThrusterServo](INFO): Powering down
2020-01-10T22:03:01.996Z,1578693781.996 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-01-10T22:03:01.997Z,1578693781.997 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-01-10T22:03:01.997Z,1578693781.997 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-01-10T22:03:01.997Z,1578693781.997 [CBIT](DEBUG): Powering off loads.
2020-01-10T22:03:02.009Z,1578693782.009 [CBIT](DEBUG): Disabling WDT.
2020-01-10T22:03:02.021Z,1578693782.021 [CBIT](DEBUG): Opening all GF detection circuits.
2020-01-10T22:03:02.022Z,1578693782.022 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-10T22:03:02.070Z,1578693782.070 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-10T22:03:02.080Z,1578693782.080 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-10T22:03:02.123Z,1578693782.123 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-10T22:03:02.126Z,1578693782.126 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-10T22:03:02.185Z,1578693782.185 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-10T22:03:02.245Z,1578693782.245 [logger ThreadHandler](INFO): Thread cancelled.