2021-12-15T16:39:04.822Z,1639586344.822 [Supervisor](DEBUG): Initializing supervisor.
2021-12-15T16:39:04.826Z,1639586344.826 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-12-15T16:39:04.827Z,1639586344.827 [SyncHandler](INFO): Protected caller Thread ID is 835
2021-12-15T16:39:04.827Z,1639586344.827 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-12-15T16:39:04.828Z,1639586344.828 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-12-15T16:39:04.828Z,1639586344.828 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 836
2021-12-15T16:39:04.833Z,1639586344.833 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-12-15T16:39:04.851Z,1639586344.851 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2021-12-15T16:39:04.852Z,1639586344.852 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-12-15T16:39:04.852Z,1639586344.852 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 837
2021-12-15T16:39:04.856Z,1639586344.856 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-12-15T16:39:04.857Z,1639586344.857 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-12-15T16:39:04.858Z,1639586344.858 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 838
2021-12-15T16:39:04.860Z,1639586344.860 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-12-15T16:39:04.861Z,1639586344.861 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2021-12-15T16:39:04.862Z,1639586344.862 [logger ThreadHandler](INFO): Protected caller Thread ID is 839
2021-12-15T16:39:04.865Z,1639586344.865 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-12-15T16:39:04.866Z,1639586344.866 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-12-15T16:39:04.870Z,1639586344.870 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-12-15T16:39:05.309Z,1639586345.309 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-12-15T16:39:05.309Z,1639586345.309 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-12-15T16:39:05.456Z,1639586345.456 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-12-15T16:39:05.458Z,1639586345.458 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-12-15T16:39:05.540Z,1639586345.540 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-12-15T16:39:05.761Z,1639586345.761 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-12-15T16:39:05.762Z,1639586345.762 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-12-15T16:39:05.845Z,1639586345.845 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-12-15T16:39:05.942Z,1639586345.942 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-12-15T16:39:05.942Z,1639586345.942 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-12-15T16:39:06.296Z,1639586346.296 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-12-15T16:39:06.296Z,1639586346.296 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-12-15T16:39:06.423Z,1639586346.423 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-12-15T16:39:06.425Z,1639586346.425 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-12-15T16:39:06.952Z,1639586346.952 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-12-15T16:39:06.953Z,1639586346.953 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-12-15T16:39:07.144Z,1639586347.144 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-12-15T16:39:07.145Z,1639586347.145 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-12-15T16:39:07.520Z,1639586347.520 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-12-15T16:39:07.522Z,1639586347.522 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-12-15T16:39:07.765Z,1639586347.765 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-12-15T16:39:08.083Z,1639586348.083 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-12-15T16:39:08.500Z,1639586348.500 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-12-15T16:39:08.502Z,1639586348.502 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-12-15T16:39:09.174Z,1639586349.174 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-12-15T16:39:09.176Z,1639586349.176 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-12-15T16:39:09.515Z,1639586349.515 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-12-15T16:39:09.516Z,1639586349.516 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-12-15T16:39:09.740Z,1639586349.740 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-12-15T16:39:09.742Z,1639586349.742 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2021-12-15T16:39:09.746Z,1639586349.746 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2021-12-15T16:39:09.942Z,1639586349.942 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2021-12-15T16:39:10.025Z,1639586350.025 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2021-12-15T16:39:10.162Z,1639586350.162 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2021-12-15T16:39:10.249Z,1639586350.249 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2021-12-15T16:39:10.512Z,1639586350.512 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-12-15T16:39:10.512Z,1639586350.512 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2021-12-15T16:39:10.617Z,1639586350.617 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2021-12-15T16:39:10.729Z,1639586350.729 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2021-12-15T16:39:10.824Z,1639586350.824 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2021-12-15T16:39:10.968Z,1639586350.968 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2021-12-15T16:39:11.067Z,1639586351.067 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2021-12-15T16:39:11.160Z,1639586351.160 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-12-15T16:39:11.179Z,1639586351.179 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-12-15T16:39:11.196Z,1639586351.196 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-12-15T16:39:11.197Z,1639586351.197 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-12-15T16:39:11.318Z,1639586351.318 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-12-15T16:39:11.319Z,1639586351.319 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-12-15T16:39:11.391Z,1639586351.391 [VerticalControl](DEBUG): Construct VerticalControl.
2021-12-15T16:39:11.454Z,1639586351.454 [VerticalControl] Loaded
2021-12-15T16:39:11.454Z,1639586351.454 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-12-15T16:39:11.457Z,1639586351.457 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-12-15T16:39:11.495Z,1639586351.495 [HorizontalControl] Loaded
2021-12-15T16:39:11.496Z,1639586351.496 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-12-15T16:39:11.498Z,1639586351.498 [SpeedControl](DEBUG): Construct SpeedControl.
2021-12-15T16:39:11.501Z,1639586351.501 [SpeedControl] Loaded
2021-12-15T16:39:11.502Z,1639586351.502 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-12-15T16:39:11.504Z,1639586351.504 [LoopControl](DEBUG): Construct LoopControl.
2021-12-15T16:39:11.505Z,1639586351.505 [LoopControl] Loaded
2021-12-15T16:39:11.505Z,1639586351.505 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-12-15T16:39:11.505Z,1639586351.505 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-12-15T16:39:11.506Z,1639586351.506 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-12-15T16:39:11.688Z,1639586351.688 [BuoyancyServo] Loaded
2021-12-15T16:39:11.689Z,1639586351.689 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-12-15T16:39:11.708Z,1639586351.708 [ElevatorServo] Loaded
2021-12-15T16:39:11.708Z,1639586351.708 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-12-15T16:39:11.726Z,1639586351.726 [MassServo] Loaded
2021-12-15T16:39:11.726Z,1639586351.726 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-12-15T16:39:11.744Z,1639586351.744 [RudderServo] Loaded
2021-12-15T16:39:11.745Z,1639586351.745 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-12-15T16:39:11.759Z,1639586351.759 [ThrusterHE] Loaded
2021-12-15T16:39:11.760Z,1639586351.760 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2021-12-15T16:39:11.760Z,1639586351.760 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-12-15T16:39:11.761Z,1639586351.761 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-12-15T16:39:11.822Z,1639586351.822 [DepthRateCalculator] Loaded
2021-12-15T16:39:11.822Z,1639586351.822 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-12-15T16:39:11.827Z,1639586351.827 [PitchRateCalculator] Loaded
2021-12-15T16:39:11.827Z,1639586351.827 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-12-15T16:39:11.837Z,1639586351.837 [SpeedCalculator] Loaded
2021-12-15T16:39:11.837Z,1639586351.837 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-12-15T16:39:11.851Z,1639586351.851 [TempGradientCalculator] Loaded
2021-12-15T16:39:11.851Z,1639586351.851 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-12-15T16:39:11.856Z,1639586351.856 [YawRateCalculator] Loaded
2021-12-15T16:39:11.856Z,1639586351.856 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-12-15T16:39:11.874Z,1639586351.874 [ElevatorOffsetCalculator] Loaded
2021-12-15T16:39:11.874Z,1639586351.874 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-12-15T16:39:11.874Z,1639586351.874 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-12-15T16:39:11.875Z,1639586351.875 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-12-15T16:39:11.974Z,1639586351.974 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-12-15T16:39:11.974Z,1639586351.974 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-12-15T16:39:11.987Z,1639586351.987 [NavChart] Loaded
2021-12-15T16:39:11.988Z,1639586351.988 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-12-15T16:39:11.994Z,1639586351.994 [UniversalFixResidualReporter] Loaded
2021-12-15T16:39:11.994Z,1639586351.994 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-12-15T16:39:11.994Z,1639586351.994 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-12-15T16:39:11.995Z,1639586351.995 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-12-15T16:39:12.690Z,1639586352.690 [AHRS_M2] Loaded
2021-12-15T16:39:12.691Z,1639586352.691 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-12-15T16:39:12.938Z,1639586352.938 [BackseatComponent] Loaded
2021-12-15T16:39:12.938Z,1639586352.938 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2021-12-15T16:39:12.939Z,1639586352.939 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408A64E0
2021-12-15T16:39:12.940Z,1639586352.940 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 921
2021-12-15T16:39:12.943Z,1639586352.943 [LcmUniversalReporter] Loaded
2021-12-15T16:39:12.943Z,1639586352.943 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2021-12-15T16:39:14.420Z,1639586354.420 [BPC1] Loaded
2021-12-15T16:39:14.420Z,1639586354.420 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-12-15T16:39:14.569Z,1639586354.569 [DataOverHttps] Loaded
2021-12-15T16:39:14.569Z,1639586354.569 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-12-15T16:39:14.570Z,1639586354.570 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408D64E0
2021-12-15T16:39:14.570Z,1639586354.570 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 922
2021-12-15T16:39:14.590Z,1639586354.590 [Depth_Keller] Loaded
2021-12-15T16:39:14.591Z,1639586354.591 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-12-15T16:39:14.595Z,1639586354.595 [DropWeight] Loaded
2021-12-15T16:39:14.596Z,1639586354.596 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2021-12-15T16:39:14.655Z,1639586354.655 [NAL9602] Loaded
2021-12-15T16:39:14.656Z,1639586354.656 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-12-15T16:39:14.685Z,1639586354.685 [Onboard] Loaded
2021-12-15T16:39:14.685Z,1639586354.685 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-12-15T16:39:14.686Z,1639586354.686 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409064E0
2021-12-15T16:39:14.687Z,1639586354.687 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 923
2021-12-15T16:39:14.700Z,1639586354.700 [Power24vConverter] Loaded
2021-12-15T16:39:14.700Z,1639586354.700 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2021-12-15T16:39:14.713Z,1639586354.713 [Radio_Surface] Loaded
2021-12-15T16:39:14.713Z,1639586354.713 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-12-15T16:39:14.715Z,1639586354.715 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409364E0
2021-12-15T16:39:14.715Z,1639586354.715 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 924
2021-12-15T16:39:14.797Z,1639586354.797 [DAT] Loaded
2021-12-15T16:39:14.797Z,1639586354.797 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2021-12-15T16:39:14.798Z,1639586354.798 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-12-15T16:39:14.798Z,1639586354.798 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-12-15T16:39:14.882Z,1639586354.882 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-12-15T16:39:14.883Z,1639586354.883 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-12-15T16:39:15.017Z,1639586355.017 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-12-15T16:39:15.019Z,1639586355.019 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-12-15T16:39:15.062Z,1639586355.062 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-12-15T16:39:15.062Z,1639586355.062 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-12-15T16:39:15.288Z,1639586355.288 [CTD_Seabird] Loaded
2021-12-15T16:39:15.288Z,1639586355.288 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-12-15T16:39:15.289Z,1639586355.289 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A564E0
2021-12-15T16:39:15.290Z,1639586355.290 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 925
2021-12-15T16:39:15.310Z,1639586355.310 [PAR_Licor] Loaded
2021-12-15T16:39:15.310Z,1639586355.310 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-12-15T16:39:15.342Z,1639586355.342 [WetLabsBB2FL] Loaded
2021-12-15T16:39:15.342Z,1639586355.342 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-12-15T16:39:15.343Z,1639586355.343 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A864E0
2021-12-15T16:39:15.344Z,1639586355.344 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 926
2021-12-15T16:39:15.345Z,1639586355.345 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-12-15T16:39:15.345Z,1639586355.345 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-12-15T16:39:15.729Z,1639586355.729 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-12-15T16:39:15.731Z,1639586355.731 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-12-15T16:39:15.906Z,1639586355.906 [SBIT](DEBUG): Construct Startup Built In Test.
2021-12-15T16:39:15.916Z,1639586355.916 [SBIT] Loaded
2021-12-15T16:39:15.916Z,1639586355.916 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-12-15T16:39:15.919Z,1639586355.919 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-12-15T16:39:15.932Z,1639586355.932 [IBIT] Loaded
2021-12-15T16:39:15.932Z,1639586355.932 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-12-15T16:39:15.938Z,1639586355.938 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-12-15T16:39:16.041Z,1639586356.041 [CBIT] Loaded
2021-12-15T16:39:16.041Z,1639586356.041 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-12-15T16:39:16.041Z,1639586356.041 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-12-15T16:39:16.048Z,1639586356.048 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-12-15T16:39:16.050Z,1639586356.050 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-12-15T16:39:16.061Z,1639586356.061 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-12-15T16:39:16.062Z,1639586356.062 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B6D4E0
2021-12-15T16:39:16.062Z,1639586356.062 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 927
2021-12-15T16:39:16.068Z,1639586356.068 [Supervisor](INFO): Main Thread ID is 831
2021-12-15T16:39:16.068Z,1639586356.068 [Supervisor](DEBUG): Running supervisor.
2021-12-15T16:39:16.069Z,1639586356.069 [CommandExec ThreadHandler](INFO): Handler Thread ID is 928
2021-12-15T16:39:16.070Z,1639586356.070 [CommandExec](INFO): Initializing the command executive.
2021-12-15T16:39:16.071Z,1639586356.071 [CommandLine ThreadHandler](INFO): Handler Thread ID is 929
2021-12-15T16:39:16.073Z,1639586356.073 [controlThread ThreadHandler](INFO): Handler Thread ID is 930
2021-12-15T16:39:16.074Z,1639586356.074 [controlThread](DEBUG): Initializing ControlThread
2021-12-15T16:39:16.074Z,1639586356.074 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-12-15T16:39:16.077Z,1639586356.077 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-12-15T16:39:16.078Z,1639586356.078 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-12-15T16:39:16.078Z,1639586356.078 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-12-15T16:39:16.080Z,1639586356.080 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-12-15T16:39:16.080Z,1639586356.080 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-12-15T16:39:16.080Z,1639586356.080 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-12-15T16:39:16.081Z,1639586356.081 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-12-15T16:39:16.081Z,1639586356.081 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-12-15T16:39:16.082Z,1639586356.082 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-12-15T16:39:16.083Z,1639586356.083 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-12-15T16:39:16.083Z,1639586356.083 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-12-15T16:39:16.092Z,1639586356.092 [SBIT](INFO): Initialize SBIT Component.
2021-12-15T16:39:16.093Z,1639586356.093 [SBIT](IMPORTANT): git: 2021-11-09
2021-12-15T16:39:16.093Z,1639586356.093 [SBIT](INFO): git hash: 235ad51f9df6aba2b8b5ed692903eef5eaec5ce6
2021-12-15T16:39:16.093Z,1639586356.093 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-12-15T16:39:16.094Z,1639586356.094 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2021-12-15T16:39:16.096Z,1639586356.096 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2021-12-15T16:39:16.096Z,1639586356.096 [IBIT](INFO): Initialize IBIT Component.
2021-12-15T16:39:16.097Z,1639586356.097 [CBIT](DEBUG): Initialize CBIT Component.
2021-12-15T16:39:16.098Z,1639586356.098 [logger ThreadHandler](INFO): Handler Thread ID is 931
2021-12-15T16:39:16.112Z,1639586356.112 [CBIT](DEBUG): Initialized mux pins.
2021-12-15T16:39:16.112Z,1639586356.112 [CBIT](DEBUG): Initializing the watchdog timer.
2021-12-15T16:39:16.116Z,1639586356.116 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 932
2021-12-15T16:39:16.117Z,1639586356.117 [BackseatComponent](INFO): Powering up
2021-12-15T16:39:16.118Z,1639586356.118 [BackseatComponent](INFO): Subscribing to LCM channels.
2021-12-15T16:39:16.140Z,1639586356.140 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-12-15T16:39:16.141Z,1639586356.141 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 934
2021-12-15T16:39:16.142Z,1639586356.142 [CBIT](FAULT): Found backtrace file:
1639528842.858545 [CBIT]: Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x1a1810]
./bin/LRAUV(_ZN7PCaller10PThreadRunEPv+0xc8) [0x1a125c]


2021-12-15T16:39:16.143Z,1639586356.143 [CBIT](FAULT): Renaming backtrace file to Data/backtrace1
2021-12-15T16:39:16.144Z,1639586356.144 [CBIT](DEBUG): Initializing heartbeat.
2021-12-15T16:39:16.145Z,1639586356.145 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-12-15T16:39:16.196Z,1639586356.196 [Onboard ThreadHandler](INFO): Handler Thread ID is 935
2021-12-15T16:39:16.204Z,1639586356.204 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 936
2021-12-15T16:39:16.216Z,1639586356.216 [CBIT](DEBUG): Deactivating GF circuits.
2021-12-15T16:39:16.216Z,1639586356.216 [CBIT](DEBUG): Deactivating emergency mode.
2021-12-15T16:39:16.233Z,1639586356.233 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 937
2021-12-15T16:39:16.234Z,1639586356.234 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-12-15T16:39:16.238Z,1639586356.238 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 939
2021-12-15T16:39:16.240Z,1639586356.240 [WetLabsBB2FL](INFO): Powering up
2021-12-15T16:39:16.241Z,1639586356.241 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 941
2021-12-15T16:39:16.248Z,1639586356.248 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-12-15T16:39:16.248Z,1639586356.248 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-12-15T16:39:16.248Z,1639586356.248 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-12-15T16:39:16.249Z,1639586356.249 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-12-15T16:39:16.249Z,1639586356.249 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-12-15T16:39:16.249Z,1639586356.249 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-12-15T16:39:16.249Z,1639586356.249 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-12-15T16:39:16.249Z,1639586356.249 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-12-15T16:39:16.249Z,1639586356.249 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-12-15T16:39:16.250Z,1639586356.250 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-12-15T16:39:16.250Z,1639586356.250 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-12-15T16:39:16.250Z,1639586356.250 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-12-15T16:39:16.250Z,1639586356.250 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-12-15T16:39:16.250Z,1639586356.250 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-12-15T16:39:16.250Z,1639586356.250 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-12-15T16:39:16.251Z,1639586356.251 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-12-15T16:39:16.252Z,1639586356.252 [CBIT](DEBUG): Backplane powered.
2021-12-15T16:39:16.253Z,1639586356.253 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-12-15T16:39:16.310Z,1639586356.310 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-12-15T16:39:16.326Z,1639586356.326 [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>


2021-12-15T16:39:16.327Z,1639586356.327 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-12-15T16:39:16.432Z,1639586356.432 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-12-15T16:39:16.433Z,1639586356.433 [Default:A.Wait](DEBUG): Construct Wait.
2021-12-15T16:39:16.435Z,1639586356.435 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-12-15T16:39:16.463Z,1639586356.463 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-12-15T16:39:16.482Z,1639586356.482 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-12-15T16:39:16.487Z,1639586356.487 [Default:E.Execute](DEBUG): Construct Execute.
2021-12-15T16:39:16.522Z,1639586356.522 [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>


2021-12-15T16:39:16.527Z,1639586356.527 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,DAT,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2021-12-15T16:39:16.540Z,1639586356.540 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-12-15T16:39:16.608Z,1639586356.608 [Radio_Surface](INFO): Powering up
2021-12-15T16:39:16.694Z,1639586356.694 [Power24vConverter](INFO): Powering up.
2021-12-15T16:39:16.695Z,1639586356.695 [DAT](INFO): Powering up
2021-12-15T16:39:16.695Z,1639586356.695 [DAT](DEBUG): Initializing DAT.
2021-12-15T16:39:16.765Z,1639586356.765 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-12-15T16:39:16.772Z,1639586356.772 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-12-15T16:39:16.774Z,1639586356.774 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-12-15T16:39:16.780Z,1639586356.780 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-12-15T16:39:16.781Z,1639586356.781 [MassServo](DEBUG): Initializing EZServoServo.
2021-12-15T16:39:16.788Z,1639586356.788 [MassServo](DEBUG): Initializing MassServo.
2021-12-15T16:39:16.789Z,1639586356.789 [RudderServo](DEBUG): Initializing EZServoServo.
2021-12-15T16:39:16.796Z,1639586356.796 [RudderServo](DEBUG): Initializing RudderServo.
2021-12-15T16:39:16.797Z,1639586356.797 [ThrusterHE](DEBUG): Initializing EZServoServo.
2021-12-15T16:39:16.804Z,1639586356.804 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2021-12-15T16:39:17.664Z,1639586357.664 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2021-12-15T16:39:17.664Z,1639586357.664 [RudderServo](FAULT): Rudder failed to initialize
2021-12-15T16:39:17.664Z,1639586357.664 [RudderServo] Communications Fault, FailCount= 1
2021-12-15T16:39:17.664Z,1639586357.664 [RudderServo](ERROR): Communications Fault
2021-12-15T16:39:17.668Z,1639586357.668 [CBIT](ERROR): Communications Fault in component: RudderServo
2021-12-15T16:39:17.929Z,1639586357.929 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-12-15T16:39:17.929Z,1639586357.929 [RudderServo](INFO): Powering down
2021-12-15T16:39:18.348Z,1639586358.348 [WetLabsBB2FL](INFO): Powering down
2021-12-15T16:39:18.577Z,1639586358.577 [RudderServo](DEBUG): Initializing EZServoServo.
2021-12-15T16:39:18.697Z,1639586358.697 [RudderServo](DEBUG): Initializing RudderServo.
2021-12-15T16:39:18.701Z,1639586358.701 [CBIT](INFO): Clearing failed state for component RudderServo
2021-12-15T16:39:18.701Z,1639586358.701 [RudderServo] No Fault, FailCount= 1
2021-12-15T16:39:39.666Z,1639586379.666 [SBIT](IMPORTANT): Beginning Startup BIT
2021-12-15T16:39:39.670Z,1639586379.670 [CBIT](IMPORTANT): Beginning ground fault scan
2021-12-15T16:39:44.822Z,1639586384.822 [NAL9602](INFO): Powering up NAL9602
2021-12-15T16:39:50.598Z,1639586390.598 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005182
CHAN A1 (24V): -0.008890
CHAN A2 (12V): -0.004048
CHAN A3 (5V): -0.002452
CHAN B0 (3.3V): -0.000619
CHAN B1 (3.15aV): -0.001699
CHAN B2 (3.15bV): -0.001308
CHAN B3 (GND): -0.000142
OPEN: 0.004396
Full Scale: +/- 1 mA
2021-12-15T16:39:55.730Z,1639586395.730 [NAL9602](INFO): NAL9602 initialized
2021-12-15T16:40:12.571Z,1639586412.571 [BackseatComponent](IMPORTANT): IBASE917-01: Running bioacoustics backseat app. Logging to: 20211215T164006
2021-12-15T16:40:15.279Z,1639586415.279 [BackseatComponent](INFO): Created universal data reader for depth with code: 6
2021-12-15T16:40:15.279Z,1639586415.279 [BackseatComponent](INFO): Requesting data from depth (6). Requested size is: 1
2021-12-15T16:40:15.282Z,1639586415.282 [BackseatComponent](INFO): Created universal data reader for latitude with code: 23
2021-12-15T16:40:15.282Z,1639586415.282 [BackseatComponent](INFO): Requesting data from latitude (23). Requested size is: 2
2021-12-15T16:40:15.285Z,1639586415.285 [BackseatComponent](INFO): Created universal data reader for longitude with code: 26
2021-12-15T16:40:15.285Z,1639586415.285 [BackseatComponent](INFO): Requesting data from longitude (26). Requested size is: 3
2021-12-15T16:40:15.287Z,1639586415.287 [BackseatComponent](INFO): Created universal data reader for platform_orientation with code: 52
2021-12-15T16:40:15.288Z,1639586415.288 [BackseatComponent](INFO): Requesting data from platform_orientation (52). Requested size is: 4
2021-12-15T16:40:15.290Z,1639586415.290 [BackseatComponent](INFO): Created universal data reader for platform_pitch_angle with code: 54
2021-12-15T16:40:15.290Z,1639586415.290 [BackseatComponent](INFO): Requesting data from platform_pitch_angle (54). Requested size is: 5
2021-12-15T16:40:15.294Z,1639586415.294 [BackseatComponent](INFO): Created universal data reader for platform_roll_angle with code: 59
2021-12-15T16:40:15.294Z,1639586415.294 [BackseatComponent](INFO): Requesting data from platform_roll_angle (59). Requested size is: 6
2021-12-15T16:40:15.297Z,1639586415.297 [BackseatComponent](INFO): Created universal data reader for platform_x_velocity_wrt_sea_water with code: 70
2021-12-15T16:40:15.297Z,1639586415.297 [BackseatComponent](INFO): Requesting data from platform_x_velocity_wrt_sea_water (70). Requested size is: 7
2021-12-15T16:40:16.739Z,1639586416.739 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T16:40:16.739Z,1639586416.739 [DAT] Communications Fault, FailCount= 1
2021-12-15T16:40:16.739Z,1639586416.739 [DAT](ERROR): Communications Fault
2021-12-15T16:40:16.837Z,1639586416.837 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T16:40:17.167Z,1639586417.167 [DAT](INFO): Powering down
2021-12-15T16:40:18.032Z,1639586418.032 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T16:40:18.032Z,1639586418.032 [DAT] No Fault, FailCount= 1
2021-12-15T16:40:20.396Z,1639586420.396 [DAT](INFO): Powering up
2021-12-15T16:40:20.397Z,1639586420.397 [DAT](DEBUG): Initializing DAT.
2021-12-15T16:40:33.445Z,1639586433.445 [SBIT](IMPORTANT): SBIT PASSED
2021-12-15T16:40:33.445Z,1639586433.445 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-12-15T16:40:33.446Z,1639586433.446 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=32 count;

2021-12-15T16:40:33.446Z,1639586433.446 [SBIT](IMPORTANT): BPC1.batterySamplingInterval=1 hour;

2021-12-15T16:40:33.446Z,1639586433.446 [SBIT](IMPORTANT): BackseatComponent.alwaysOn=1 bool;

2021-12-15T16:40:33.446Z,1639586433.446 [SBIT](IMPORTANT): BackseatComponent.loadAtStartup=1 bool;

2021-12-15T16:40:33.447Z,1639586433.447 [SBIT](IMPORTANT): CBIT.gf24Offset=-18.106 microampere;

2021-12-15T16:40:33.447Z,1639586433.447 [SBIT](IMPORTANT): CBIT.stopDepth=295 meter;

2021-12-15T16:40:33.447Z,1639586433.447 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2021-12-15T16:40:33.447Z,1639586433.447 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree;

2021-12-15T16:40:33.447Z,1639586433.447 [SBIT](IMPORTANT): HorizontalControl.rudDeadband=0.001 degree;

2021-12-15T16:40:33.447Z,1639586433.447 [SBIT](IMPORTANT): PAR_Licor.parCal=1.38207 none;

2021-12-15T16:40:33.447Z,1639586433.447 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=474.287270 cubic_centimeter;

2021-12-15T16:40:33.447Z,1639586433.447 [SBIT](IMPORTANT): VerticalControl.elevDeadband=0.001 degree;

2021-12-15T16:40:33.448Z,1639586433.448 [SBIT](IMPORTANT): VerticalControl.massDefault=-0.758621 millimeter;

2021-12-15T16:40:33.734Z,1639586433.734 [MissionManager](IMPORTANT): Started mission Startup
2021-12-15T16:40:33.735Z,1639586433.735 [Startup] Running Loop=1
2021-12-15T16:40:33.735Z,1639586433.735 [Startup](DEBUG): Aggregate::initialize Startup
2021-12-15T16:40:33.735Z,1639586433.735 [Startup:A.GoToSurface] Running Loop=1
2021-12-15T16:40:33.735Z,1639586433.735 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-12-15T16:40:33.748Z,1639586433.748 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-12-15T16:40:33.748Z,1639586433.748 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-12-15T16:40:33.749Z,1639586433.749 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-12-15T16:40:33.749Z,1639586433.749 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-12-15T16:40:33.750Z,1639586433.750 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-12-15T16:40:33.751Z,1639586433.751 [Startup:StartupSatComms] Running Loop=1
2021-12-15T16:40:33.751Z,1639586433.751 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-12-15T16:40:33.751Z,1639586433.751 [Startup:StartupSatComms:A] Running Loop=1
2021-12-15T16:40:34.147Z,1639586434.147 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-12-15T16:41:13.304Z,1639586473.304 [BPC1](INFO): Calculating totals. Valid battery stick count: 28. Valid reserve battery stick count: 3.
2021-12-15T16:41:13.308Z,1639586473.308 [BPC1](INFO): Received data from all battery sticks.
2021-12-15T16:41:20.575Z,1639586480.575 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T16:41:20.575Z,1639586480.575 [DAT] Communications Fault, FailCount= 2
2021-12-15T16:41:20.575Z,1639586480.575 [DAT](ERROR): Communications Fault
2021-12-15T16:41:20.677Z,1639586480.677 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T16:41:20.992Z,1639586480.992 [DAT](INFO): Powering down
2021-12-15T16:41:21.889Z,1639586481.889 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T16:41:21.889Z,1639586481.889 [DAT] No Fault, FailCount= 2
2021-12-15T16:41:24.210Z,1639586484.210 [DAT](INFO): Powering up
2021-12-15T16:41:24.211Z,1639586484.211 [DAT](DEBUG): Initializing DAT.
2021-12-15T16:41:33.914Z,1639586493.914 [Startup:StartupSatComms:A](INFO): Timed out from 2021-12-15T16:40:33.8Z
2021-12-15T16:41:33.914Z,1639586493.914 [Startup:StartupSatComms:A] Stopped
2021-12-15T16:41:33.914Z,1639586493.914 [Startup:StartupSatComms:B] Running Loop=1
2021-12-15T16:41:34.325Z,1639586494.325 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-12-15T16:41:52.318Z,1639586512.318 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005077
2021-12-15T16:42:00.102Z,1639586520.102 [DataOverHttps](INFO): Sending 193 bytes from file Logs/20211215T163904/Courier0000.lzma
2021-12-15T16:42:01.105Z,1639586521.105 [DataOverHttps](INFO): Moved sent file to Logs/20211215T163904/Courier0000.lzma.bak
2021-12-15T16:42:01.105Z,1639586521.105 [DataOverHttps](INFO): SBD MOMSN=16391218
2021-12-15T16:42:16.335Z,1639586536.335 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-12-15T16:42:16.335Z,1639586536.335 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-15T16:42:16.418Z,1639586536.418 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-15T16:42:16.823Z,1639586536.823 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-15T16:42:16.823Z,1639586536.823 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-12-15T16:42:17.247Z,1639586537.247 [DataOverHttps](INFO): Sending 1293 bytes from file Logs/20211215T163904/Express0001.lzma
2021-12-15T16:42:18.247Z,1639586538.247 [DataOverHttps](INFO): Moved sent file to Logs/20211215T163904/Express0001.lzma.bak
2021-12-15T16:42:18.247Z,1639586538.247 [DataOverHttps](INFO): SBD MOMSN=16391221
2021-12-15T16:42:19.596Z,1639586539.596 [Startup:StartupSatComms:B] Stopped
2021-12-15T16:42:19.596Z,1639586539.596 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-12-15T16:42:19.596Z,1639586539.596 [Startup:StartupSatComms] Stopped
2021-12-15T16:42:19.596Z,1639586539.596 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-12-15T16:42:19.598Z,1639586539.598 [Startup](INFO): Completed Startup
2021-12-15T16:42:19.598Z,1639586539.598 [MissionManager](INFO): Startup is completed.
2021-12-15T16:42:19.598Z,1639586539.598 [MissionManager](INFO): Uninitializing Mission Startup
2021-12-15T16:42:19.598Z,1639586539.598 [Startup] Stopped
2021-12-15T16:42:19.598Z,1639586539.598 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-12-15T16:42:19.599Z,1639586539.599 [Startup:A.GoToSurface] Stopped
2021-12-15T16:42:19.599Z,1639586539.599 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-12-15T16:42:19.992Z,1639586539.992 [MissionManager](IMPORTANT): Started mission Default
2021-12-15T16:42:19.992Z,1639586539.992 [Default] Running Loop=1
2021-12-15T16:42:19.992Z,1639586539.992 [Default](DEBUG): Aggregate::initialize Default
2021-12-15T16:42:19.992Z,1639586539.992 [Default:B.GoToSurface] Running Loop=1
2021-12-15T16:42:19.992Z,1639586539.992 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-12-15T16:42:19.993Z,1639586539.993 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-12-15T16:42:19.993Z,1639586539.993 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-12-15T16:42:19.993Z,1639586539.993 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-12-15T16:42:19.994Z,1639586539.994 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-12-15T16:42:19.994Z,1639586539.994 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-12-15T16:42:19.994Z,1639586539.994 [Default:A.Wait] Running Loop=1
2021-12-15T16:42:19.995Z,1639586539.995 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-12-15T16:42:24.426Z,1639586544.426 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T16:42:24.426Z,1639586544.426 [DAT] Communications Fault, FailCount= 3
2021-12-15T16:42:24.426Z,1639586544.426 [DAT](ERROR): Communications Fault
2021-12-15T16:42:24.565Z,1639586544.565 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T16:42:24.822Z,1639586544.822 [DAT](INFO): Powering down
2021-12-15T16:42:25.226Z,1639586545.226 [DAT](FAULT): LCB fault:  Software Overcurrent.
2021-12-15T16:42:25.226Z,1639586545.226 [DAT] Hardware Fault, FailCount= 3
2021-12-15T16:42:25.226Z,1639586545.226 [DAT](ERROR): Hardware Fault
2021-12-15T16:42:25.733Z,1639586545.733 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T16:42:25.733Z,1639586545.733 [DAT] No Fault, FailCount= 3
2021-12-15T16:42:28.046Z,1639586548.046 [DAT](INFO): Powering up
2021-12-15T16:42:28.046Z,1639586548.046 [DAT](DEBUG): Initializing DAT.
2021-12-15T16:42:33.308Z,1639586553.308 [Default:A.Wait](INFO): Done Waiting.
2021-12-15T16:42:33.308Z,1639586553.308 [Default:A.Wait] Stopped
2021-12-15T16:42:33.309Z,1639586553.309 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-12-15T16:42:33.704Z,1639586553.704 [Default:CheckIn] Running Loop=1
2021-12-15T16:42:33.705Z,1639586553.705 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-12-15T16:42:33.705Z,1639586553.705 [Default:CheckIn:Read_GPS] Running Loop=1
2021-12-15T16:42:34.108Z,1639586554.108 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-12-15T16:43:28.250Z,1639586608.250 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T16:43:28.250Z,1639586608.250 [DAT] Communications Fault, FailCount= 4
2021-12-15T16:43:28.250Z,1639586608.250 [DAT](ERROR): Communications Fault
2021-12-15T16:43:28.349Z,1639586608.349 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T16:43:28.654Z,1639586608.654 [DAT](INFO): Powering down
2021-12-15T16:43:29.532Z,1639586609.532 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T16:43:29.532Z,1639586609.532 [DAT] No Fault, FailCount= 4
2021-12-15T16:43:31.878Z,1639586611.878 [DAT](INFO): Powering up
2021-12-15T16:43:31.878Z,1639586611.878 [DAT](DEBUG): Initializing DAT.
2021-12-15T16:44:27.568Z,1639586667.568 [CommandExec](IMPORTANT): got command burn on
2021-12-15T16:44:27.568Z,1639586667.568 [CommandExec](IMPORTANT): Activating dropweight wire
2021-12-15T16:44:32.068Z,1639586672.068 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T16:44:32.069Z,1639586672.069 [DAT] Communications Fault, FailCount= 5
2021-12-15T16:44:32.069Z,1639586672.069 [DAT](ERROR): Communications Fault
2021-12-15T16:44:32.174Z,1639586672.174 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T16:44:32.476Z,1639586672.476 [DAT](INFO): Powering down
2021-12-15T16:44:33.373Z,1639586673.373 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T16:44:33.374Z,1639586673.374 [DAT] No Fault, FailCount= 5
2021-12-15T16:44:35.722Z,1639586675.722 [DAT](INFO): Powering up
2021-12-15T16:44:35.722Z,1639586675.722 [DAT](DEBUG): Initializing DAT.
2021-12-15T16:44:59.544Z,1639586699.544 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-12-15T16:45:01.362Z,1639586701.362 [CommandExec](IMPORTANT): got command burn off
2021-12-15T16:45:01.362Z,1639586701.362 [CommandExec](IMPORTANT): Deactivating dropweight wire
2021-12-15T16:45:17.354Z,1639586717.354 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-12-15T16:45:17.354Z,1639586717.354 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-15T16:45:17.367Z,1639586717.367 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-15T16:45:17.740Z,1639586717.740 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-15T16:45:17.741Z,1639586717.741 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-12-15T16:45:19.202Z,1639586719.202 [BackseatComponent](FAULT): IBASE917-01: Simrad log size (0B) did not increase within specified timeout.
2021-12-15T16:45:19.202Z,1639586719.202 [BackseatComponent] Data Fault, FailCount= 1
2021-12-15T16:45:19.202Z,1639586719.202 [BackseatComponent](ERROR): Data Fault
2021-12-15T16:45:19.387Z,1639586719.387 [CBIT](ERROR): Data Fault in component: BackseatComponent
2021-12-15T16:45:19.608Z,1639586719.608 [BackseatComponent](INFO): Sent LCM shutdown request.
2021-12-15T16:45:34.961Z,1639586734.961 [BackseatComponent](INFO): Powering down
2021-12-15T16:45:35.365Z,1639586735.365 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2021-12-15T16:45:35.366Z,1639586735.366 [LcmUniversalReporter](INFO): Deactivating messaging.
2021-12-15T16:45:35.769Z,1639586735.769 [BackseatComponent](INFO): Powering up
2021-12-15T16:45:35.770Z,1639586735.770 [BackseatComponent](INFO): Subscribing to LCM channels.
2021-12-15T16:45:35.904Z,1639586735.904 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T16:45:35.904Z,1639586735.904 [DAT] Communications Fault, FailCount= 6
2021-12-15T16:45:35.904Z,1639586735.904 [DAT](ERROR): Communications Fault
2021-12-15T16:45:35.922Z,1639586735.922 [CBIT](INFO): Clearing failed state for component BackseatComponent
2021-12-15T16:45:35.922Z,1639586735.922 [BackseatComponent] No Fault, FailCount= 0
2021-12-15T16:45:35.922Z,1639586735.922 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T16:45:36.308Z,1639586736.308 [DAT](INFO): Powering down
2021-12-15T16:45:37.129Z,1639586737.129 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T16:45:37.129Z,1639586737.129 [DAT] No Fault, FailCount= 6
2021-12-15T16:45:39.541Z,1639586739.541 [DAT](INFO): Powering up
2021-12-15T16:45:39.542Z,1639586739.542 [DAT](DEBUG): Initializing DAT.
2021-12-15T16:46:35.095Z,1639586795.095 [BackseatComponent](IMPORTANT): IBASE917-01: Running bioacoustics backseat app. Logging to: 20211215T164628
2021-12-15T16:46:39.735Z,1639586799.735 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T16:46:39.736Z,1639586799.736 [DAT] Communications Fault, FailCount= 7
2021-12-15T16:46:39.736Z,1639586799.736 [DAT](ERROR): Communications Fault
2021-12-15T16:46:39.753Z,1639586799.753 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T16:46:40.154Z,1639586800.154 [DAT](INFO): Powering down
2021-12-15T16:46:40.986Z,1639586800.986 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T16:46:40.986Z,1639586800.986 [DAT] No Fault, FailCount= 7
2021-12-15T16:46:43.376Z,1639586803.376 [DAT](INFO): Powering up
2021-12-15T16:46:43.376Z,1639586803.376 [DAT](DEBUG): Initializing DAT.
2021-12-15T16:47:33.904Z,1639586853.904 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-12-15T16:42:33.7Z
2021-12-15T16:47:33.904Z,1639586853.904 [Default:CheckIn:Read_GPS] Stopped
2021-12-15T16:47:33.904Z,1639586853.904 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-12-15T16:47:34.304Z,1639586854.304 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-12-15T16:47:41.008Z,1639586861.008 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20211215T163904/Courier0004.lzma
2021-12-15T16:47:42.027Z,1639586862.027 [DataOverHttps](INFO): Moved sent file to Logs/20211215T163904/Courier0004.lzma.bak
2021-12-15T16:47:42.027Z,1639586862.027 [DataOverHttps](INFO): SBD MOMSN=16391257
2021-12-15T16:47:43.584Z,1639586863.584 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T16:47:43.584Z,1639586863.584 [DAT] Communications Fault, FailCount= 8
2021-12-15T16:47:43.584Z,1639586863.584 [DAT](ERROR): Communications Fault
2021-12-15T16:47:43.602Z,1639586863.602 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T16:47:43.603Z,1639586863.603 [CBIT](FAULT): Communications Fault in component: DAT
2021-12-15T16:47:43.982Z,1639586863.982 [DAT](INFO): Powering down
2021-12-15T16:47:57.766Z,1639586877.766 [DataOverHttps](INFO): Sending 522 bytes from file Logs/20211215T163904/Express0005.lzma
2021-12-15T16:47:58.767Z,1639586878.767 [DataOverHttps](INFO): Moved sent file to Logs/20211215T163904/Express0005.lzma.bak
2021-12-15T16:47:58.767Z,1639586878.767 [DataOverHttps](INFO): SBD MOMSN=16391260
2021-12-15T16:48:00.143Z,1639586880.143 [Default:CheckIn:Read_Iridium] Stopped
2021-12-15T16:48:00.143Z,1639586880.143 [Default:CheckIn:C.Wait] Running Loop=1
2021-12-15T16:48:00.143Z,1639586880.143 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-12-15T16:48:18.318Z,1639586898.318 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2021-12-15T16:48:18.318Z,1639586898.318 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-15T16:48:18.330Z,1639586898.330 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-15T16:48:18.737Z,1639586898.737 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-15T16:48:18.737Z,1639586898.737 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2021-12-15T16:49:57.291Z,1639586997.291 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-12-15T16:49:57.291Z,1639586997.291 [NAL9602] Data Fault, FailCount= 1
2021-12-15T16:49:57.291Z,1639586997.291 [NAL9602](ERROR): Data Fault
2021-12-15T16:49:57.309Z,1639586997.309 [CBIT](ERROR): Data Fault in component: NAL9602
2021-12-15T16:49:57.699Z,1639586997.699 [NAL9602](INFO): Powering down
2021-12-15T16:49:58.540Z,1639586998.540 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T16:49:58.540Z,1639586998.540 [NAL9602] No Fault, FailCount= 1
2021-12-15T16:50:18.301Z,1639587018.301 [Power24vConverter](INFO): Powering down.
2021-12-15T16:50:27.997Z,1639587027.997 [NAL9602](INFO): Powering up NAL9602
2021-12-15T16:50:48.599Z,1639587048.599 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T16:50:48.599Z,1639587048.599 [NAL9602] Communications Fault, FailCount= 2
2021-12-15T16:50:48.599Z,1639587048.599 [NAL9602](ERROR): Communications Fault
2021-12-15T16:50:48.646Z,1639587048.646 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T16:50:49.003Z,1639587049.003 [NAL9602](INFO): Powering down
2021-12-15T16:50:49.831Z,1639587049.831 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T16:50:49.831Z,1639587049.831 [NAL9602] No Fault, FailCount= 2
2021-12-15T16:51:19.298Z,1639587079.298 [NAL9602](INFO): Powering up NAL9602
2021-12-15T16:51:19.305Z,1639587079.305 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2021-12-15T16:51:19.305Z,1639587079.305 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-15T16:51:19.316Z,1639587079.316 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-15T16:51:19.733Z,1639587079.733 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-15T16:51:19.733Z,1639587079.733 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2021-12-15T16:51:39.909Z,1639587099.909 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T16:51:39.909Z,1639587099.909 [NAL9602] Communications Fault, FailCount= 3
2021-12-15T16:51:39.909Z,1639587099.909 [NAL9602](ERROR): Communications Fault
2021-12-15T16:51:39.928Z,1639587099.928 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T16:51:40.310Z,1639587100.310 [NAL9602](INFO): Powering down
2021-12-15T16:51:41.135Z,1639587101.135 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T16:51:41.136Z,1639587101.136 [NAL9602] No Fault, FailCount= 3
2021-12-15T16:52:10.615Z,1639587130.615 [NAL9602](INFO): Powering up NAL9602
2021-12-15T16:52:22.684Z,1639587142.684 [BackseatComponent](IMPORTANT): IBASE917-01: Running bioacoustics backseat app. Logging to: 20211215T165216
2021-12-15T16:52:31.219Z,1639587151.219 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T16:52:31.219Z,1639587151.219 [NAL9602] Communications Fault, FailCount= 4
2021-12-15T16:52:31.219Z,1639587151.219 [NAL9602](ERROR): Communications Fault
2021-12-15T16:52:31.260Z,1639587151.260 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T16:52:31.619Z,1639587151.619 [NAL9602](INFO): Powering down
2021-12-15T16:52:32.457Z,1639587152.457 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T16:52:32.458Z,1639587152.458 [NAL9602] No Fault, FailCount= 4
2021-12-15T16:52:43.758Z,1639587163.758 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T16:52:43.758Z,1639587163.758 [DAT] No Fault, FailCount= 8
2021-12-15T16:52:44.147Z,1639587164.147 [DAT](INFO): Powering up
2021-12-15T16:52:44.148Z,1639587164.148 [DAT](DEBUG): Initializing DAT.
2021-12-15T16:52:44.555Z,1639587164.555 [Power24vConverter](INFO): Powering up.
2021-12-15T16:53:00.747Z,1639587180.747 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-12-15T16:53:00.747Z,1639587180.747 [Default:CheckIn:C.Wait] Stopped
2021-12-15T16:53:00.747Z,1639587180.747 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-12-15T16:53:00.748Z,1639587180.748 [Default:CheckIn:D] Running Loop=1
2021-12-15T16:53:01.159Z,1639587181.159 [Default:CheckIn:D] Stopped
2021-12-15T16:53:01.159Z,1639587181.159 [Default:CheckIn:E] Running Loop=1
2021-12-15T16:53:01.552Z,1639587181.552 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.686121 min 
2021-12-15T16:53:01.552Z,1639587181.552 [Default:CheckIn:E] Stopped
2021-12-15T16:53:01.552Z,1639587181.552 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-12-15T16:53:01.552Z,1639587181.552 [Default:CheckIn] Stopped
2021-12-15T16:53:01.552Z,1639587181.552 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-12-15T16:53:01.552Z,1639587181.552 [Default:CheckIn](INFO): Running loop #2
2021-12-15T16:53:01.552Z,1639587181.552 [Default:CheckIn] Running Loop=2
2021-12-15T16:53:01.553Z,1639587181.553 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-12-15T16:53:01.553Z,1639587181.553 [Default:CheckIn:Read_GPS] Running Loop=1
2021-12-15T16:53:01.951Z,1639587181.951 [NAL9602](INFO): Powering up NAL9602
2021-12-15T16:53:22.563Z,1639587202.563 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T16:53:22.563Z,1639587202.563 [NAL9602] Communications Fault, FailCount= 5
2021-12-15T16:53:22.563Z,1639587202.563 [NAL9602](ERROR): Communications Fault
2021-12-15T16:53:22.647Z,1639587202.647 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T16:53:22.647Z,1639587202.647 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2021-12-15T16:53:22.954Z,1639587202.954 [NAL9602](INFO): Powering down
2021-12-15T16:53:23.017Z,1639587203.017 [CommandExec](FAULT): Scheduling is paused
2021-12-15T16:53:23.018Z,1639587203.018 [CBIT](INFO): Critical error at 20211215T165322
2021-12-15T16:53:23.018Z,1639587203.018 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2021-12-15T16:53:44.371Z,1639587224.371 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T16:53:44.371Z,1639587224.371 [DAT] Communications Fault, FailCount= 1
2021-12-15T16:53:44.371Z,1639587224.371 [DAT](ERROR): Communications Fault
2021-12-15T16:53:44.389Z,1639587224.389 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T16:53:44.779Z,1639587224.779 [DAT](INFO): Powering down
2021-12-15T16:53:45.621Z,1639587225.621 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T16:53:45.621Z,1639587225.621 [DAT] No Fault, FailCount= 1
2021-12-15T16:53:48.007Z,1639587228.007 [DAT](INFO): Powering up
2021-12-15T16:53:48.007Z,1639587228.007 [DAT](DEBUG): Initializing DAT.
2021-12-15T16:54:09.734Z,1639587249.734 [CommandExec](IMPORTANT): got command get platform_orientation
2021-12-15T16:54:09.735Z,1639587249.735 [CommandExec](IMPORTANT): platform_orientation 2.228316 rad
2021-12-15T16:54:18.457Z,1639587258.457 [CommandExec](IMPORTANT): got command get platform_orientation degree
2021-12-15T16:54:18.457Z,1639587258.457 [CommandExec](IMPORTANT): platform_orientation 127.709333 arcdeg
2021-12-15T16:54:20.342Z,1639587260.342 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2021-12-15T16:54:20.342Z,1639587260.342 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-15T16:54:20.353Z,1639587260.353 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-15T16:54:20.763Z,1639587260.763 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-15T16:54:20.763Z,1639587260.763 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2021-12-15T16:54:44.924Z,1639587284.924 [CommandExec](IMPORTANT): got command get platform_orientation degree
2021-12-15T16:54:44.924Z,1639587284.924 [CommandExec](IMPORTANT): platform_orientation 159.559680 arcdeg
2021-12-15T16:54:48.199Z,1639587288.199 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T16:54:48.199Z,1639587288.199 [DAT] Communications Fault, FailCount= 2
2021-12-15T16:54:48.199Z,1639587288.199 [DAT](ERROR): Communications Fault
2021-12-15T16:54:48.271Z,1639587288.271 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T16:54:48.607Z,1639587288.607 [DAT](INFO): Powering down
2021-12-15T16:54:49.011Z,1639587289.011 [DAT](FAULT): LCB fault:  Software Overcurrent.
2021-12-15T16:54:49.011Z,1639587289.011 [DAT] Hardware Fault, FailCount= 2
2021-12-15T16:54:49.011Z,1639587289.011 [DAT](ERROR): Hardware Fault
2021-12-15T16:54:49.435Z,1639587289.435 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T16:54:49.435Z,1639587289.435 [DAT] No Fault, FailCount= 2
2021-12-15T16:54:51.843Z,1639587291.843 [DAT](INFO): Powering up
2021-12-15T16:54:51.843Z,1639587291.843 [DAT](DEBUG): Initializing DAT.
2021-12-15T16:55:22.969Z,1639587322.969 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T16:55:22.969Z,1639587322.969 [NAL9602] No Fault, FailCount= 5
2021-12-15T16:55:23.351Z,1639587323.351 [NAL9602](INFO): Powering up NAL9602
2021-12-15T16:55:43.959Z,1639587343.959 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T16:55:43.959Z,1639587343.959 [NAL9602] Communications Fault, FailCount= 1
2021-12-15T16:55:43.959Z,1639587343.959 [NAL9602](ERROR): Communications Fault
2021-12-15T16:55:44.028Z,1639587344.028 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T16:55:44.354Z,1639587344.354 [NAL9602](INFO): Powering down
2021-12-15T16:55:45.219Z,1639587345.219 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T16:55:45.219Z,1639587345.219 [NAL9602] No Fault, FailCount= 1
2021-12-15T16:55:52.035Z,1639587352.035 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T16:55:52.035Z,1639587352.035 [DAT] Communications Fault, FailCount= 3
2021-12-15T16:55:52.035Z,1639587352.035 [DAT](ERROR): Communications Fault
2021-12-15T16:55:52.053Z,1639587352.053 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T16:55:52.442Z,1639587352.442 [DAT](INFO): Powering down
2021-12-15T16:55:53.258Z,1639587353.258 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T16:55:53.258Z,1639587353.258 [DAT] No Fault, FailCount= 3
2021-12-15T16:55:55.673Z,1639587355.673 [DAT](INFO): Powering up
2021-12-15T16:55:55.674Z,1639587355.674 [DAT](DEBUG): Initializing DAT.
2021-12-15T16:56:14.659Z,1639587374.659 [NAL9602](INFO): Powering up NAL9602
2021-12-15T16:56:35.259Z,1639587395.259 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T16:56:35.259Z,1639587395.259 [NAL9602] Communications Fault, FailCount= 2
2021-12-15T16:56:35.259Z,1639587395.259 [NAL9602](ERROR): Communications Fault
2021-12-15T16:56:35.286Z,1639587395.286 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T16:56:35.663Z,1639587395.663 [NAL9602](INFO): Powering down
2021-12-15T16:56:36.497Z,1639587396.497 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T16:56:36.497Z,1639587396.497 [NAL9602] No Fault, FailCount= 2
2021-12-15T16:56:55.871Z,1639587415.871 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T16:56:55.871Z,1639587415.871 [DAT] Communications Fault, FailCount= 4
2021-12-15T16:56:55.871Z,1639587415.871 [DAT](ERROR): Communications Fault
2021-12-15T16:56:55.948Z,1639587415.948 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T16:56:56.267Z,1639587416.267 [DAT](INFO): Powering down
2021-12-15T16:56:57.145Z,1639587417.145 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T16:56:57.145Z,1639587417.145 [DAT] No Fault, FailCount= 4
2021-12-15T16:56:59.503Z,1639587419.503 [DAT](INFO): Powering up
2021-12-15T16:56:59.503Z,1639587419.503 [DAT](DEBUG): Initializing DAT.
2021-12-15T16:57:05.967Z,1639587425.967 [NAL9602](INFO): Powering up NAL9602
2021-12-15T16:57:21.326Z,1639587441.326 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2021-12-15T16:57:21.326Z,1639587441.326 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-15T16:57:21.366Z,1639587441.366 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-15T16:57:21.740Z,1639587441.740 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-15T16:57:21.740Z,1639587441.740 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2021-12-15T16:57:26.571Z,1639587446.571 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T16:57:26.571Z,1639587446.571 [NAL9602] Communications Fault, FailCount= 3
2021-12-15T16:57:26.571Z,1639587446.571 [NAL9602](ERROR): Communications Fault
2021-12-15T16:57:26.610Z,1639587446.610 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T16:57:26.975Z,1639587446.975 [NAL9602](INFO): Powering down
2021-12-15T16:57:27.809Z,1639587447.809 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T16:57:27.809Z,1639587447.809 [NAL9602] No Fault, FailCount= 3
2021-12-15T16:57:29.482Z,1639587449.482 [BackseatComponent](FAULT): IBASE917-01: Simrad log size (8.55 MB) did not increase within specified timeout.
2021-12-15T16:57:29.482Z,1639587449.482 [BackseatComponent] Data Fault, FailCount= 1
2021-12-15T16:57:29.482Z,1639587449.482 [BackseatComponent](ERROR): Data Fault
2021-12-15T16:57:29.841Z,1639587449.841 [CBIT](ERROR): Data Fault in component: BackseatComponent
2021-12-15T16:57:29.886Z,1639587449.886 [BackseatComponent](INFO): Sent LCM shutdown request.
2021-12-15T16:57:45.245Z,1639587465.245 [BackseatComponent](INFO): Powering down
2021-12-15T16:57:45.649Z,1639587465.649 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2021-12-15T16:57:45.649Z,1639587465.649 [LcmUniversalReporter](INFO): Deactivating messaging.
2021-12-15T16:57:46.053Z,1639587466.053 [BackseatComponent](INFO): Powering up
2021-12-15T16:57:46.054Z,1639587466.054 [BackseatComponent](INFO): Subscribing to LCM channels.
2021-12-15T16:57:46.391Z,1639587466.391 [CBIT](INFO): Clearing failed state for component BackseatComponent
2021-12-15T16:57:46.391Z,1639587466.391 [BackseatComponent] No Fault, FailCount= 0
2021-12-15T16:57:57.269Z,1639587477.269 [NAL9602](INFO): Powering up NAL9602
2021-12-15T16:57:59.696Z,1639587479.696 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T16:57:59.696Z,1639587479.696 [DAT] Communications Fault, FailCount= 5
2021-12-15T16:57:59.696Z,1639587479.696 [DAT](ERROR): Communications Fault
2021-12-15T16:57:59.714Z,1639587479.714 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T16:58:00.103Z,1639587480.103 [DAT](INFO): Powering down
2021-12-15T16:58:00.922Z,1639587480.922 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T16:58:00.922Z,1639587480.922 [DAT] No Fault, FailCount= 5
2021-12-15T16:58:01.731Z,1639587481.731 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-12-15T16:53:01.6Z
2021-12-15T16:58:01.731Z,1639587481.731 [Default:CheckIn:Read_GPS] Stopped
2021-12-15T16:58:01.731Z,1639587481.731 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-12-15T16:58:03.342Z,1639587483.342 [DAT](INFO): Powering up
2021-12-15T16:58:03.342Z,1639587483.342 [DAT](DEBUG): Initializing DAT.
2021-12-15T16:58:08.505Z,1639587488.505 [DataOverHttps](INFO): Sending 113 bytes from file Logs/20211215T163904/Courier0007.lzma
2021-12-15T16:58:09.507Z,1639587489.507 [DataOverHttps](INFO): Moved sent file to Logs/20211215T163904/Courier0007.lzma.bak
2021-12-15T16:58:09.507Z,1639587489.507 [DataOverHttps](INFO): SBD MOMSN=16391311
2021-12-15T16:58:17.873Z,1639587497.873 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T16:58:17.874Z,1639587497.874 [NAL9602] Communications Fault, FailCount= 4
2021-12-15T16:58:17.874Z,1639587497.874 [NAL9602](ERROR): Communications Fault
2021-12-15T16:58:17.892Z,1639587497.892 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T16:58:18.284Z,1639587498.284 [NAL9602](INFO): Powering down
2021-12-15T16:58:19.108Z,1639587499.108 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T16:58:19.108Z,1639587499.108 [NAL9602] No Fault, FailCount= 4
2021-12-15T16:58:24.897Z,1639587504.897 [DataOverHttps](INFO): Sending 583 bytes from file Logs/20211215T163904/Express0008.lzma
2021-12-15T16:58:25.899Z,1639587505.899 [DataOverHttps](INFO): Moved sent file to Logs/20211215T163904/Express0008.lzma.bak
2021-12-15T16:58:25.899Z,1639587505.899 [DataOverHttps](INFO): SBD MOMSN=16391314
2021-12-15T16:58:27.178Z,1639587507.178 [Default:CheckIn:Read_Iridium] Stopped
2021-12-15T16:58:27.178Z,1639587507.178 [Default:CheckIn:C.Wait] Running Loop=1
2021-12-15T16:58:27.178Z,1639587507.178 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-12-15T16:58:47.674Z,1639587527.674 [BackseatComponent](IMPORTANT): IBASE917-01: Running bioacoustics backseat app. Logging to: 20211215T165841
2021-12-15T16:58:48.583Z,1639587528.583 [NAL9602](INFO): Powering up NAL9602
2021-12-15T16:59:03.532Z,1639587543.532 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T16:59:03.532Z,1639587543.532 [DAT] Communications Fault, FailCount= 6
2021-12-15T16:59:03.532Z,1639587543.532 [DAT](ERROR): Communications Fault
2021-12-15T16:59:03.575Z,1639587543.575 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T16:59:03.936Z,1639587543.936 [DAT](INFO): Powering down
2021-12-15T16:59:04.783Z,1639587544.783 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T16:59:04.783Z,1639587544.783 [DAT] No Fault, FailCount= 6
2021-12-15T16:59:07.163Z,1639587547.163 [DAT](INFO): Powering up
2021-12-15T16:59:07.163Z,1639587547.163 [DAT](DEBUG): Initializing DAT.
2021-12-15T16:59:09.187Z,1639587549.187 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T16:59:09.187Z,1639587549.187 [NAL9602] Communications Fault, FailCount= 5
2021-12-15T16:59:09.187Z,1639587549.187 [NAL9602](ERROR): Communications Fault
2021-12-15T16:59:09.207Z,1639587549.207 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T16:59:09.207Z,1639587549.207 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2021-12-15T16:59:09.595Z,1639587549.595 [NAL9602](INFO): Powering down
2021-12-15T16:59:09.626Z,1639587549.626 [CBIT](INFO): Critical error at 20211215T165909
2021-12-15T17:00:07.366Z,1639587607.366 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:00:07.367Z,1639587607.367 [DAT] Communications Fault, FailCount= 7
2021-12-15T17:00:07.367Z,1639587607.367 [DAT](ERROR): Communications Fault
2021-12-15T17:00:07.385Z,1639587607.385 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:00:07.763Z,1639587607.763 [DAT](INFO): Powering down
2021-12-15T17:00:08.588Z,1639587608.588 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:00:08.589Z,1639587608.589 [DAT] No Fault, FailCount= 7
2021-12-15T17:00:10.999Z,1639587610.999 [DAT](INFO): Powering up
2021-12-15T17:00:10.999Z,1639587610.999 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:00:22.320Z,1639587622.320 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2021-12-15T17:00:22.320Z,1639587622.320 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-15T17:00:22.331Z,1639587622.331 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-15T17:00:22.746Z,1639587622.746 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-15T17:00:22.746Z,1639587622.746 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2021-12-15T17:01:09.193Z,1639587669.193 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:01:09.193Z,1639587669.193 [NAL9602] No Fault, FailCount= 5
2021-12-15T17:01:09.575Z,1639587669.575 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:01:11.202Z,1639587671.202 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:01:11.202Z,1639587671.202 [DAT] Communications Fault, FailCount= 8
2021-12-15T17:01:11.202Z,1639587671.202 [DAT](ERROR): Communications Fault
2021-12-15T17:01:11.220Z,1639587671.220 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:01:11.220Z,1639587671.220 [CBIT](FAULT): Communications Fault in component: DAT
2021-12-15T17:01:11.599Z,1639587671.599 [DAT](INFO): Powering down
2021-12-15T17:01:30.183Z,1639587690.183 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:01:30.183Z,1639587690.183 [NAL9602] Communications Fault, FailCount= 1
2021-12-15T17:01:30.183Z,1639587690.183 [NAL9602](ERROR): Communications Fault
2021-12-15T17:01:30.230Z,1639587690.230 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:01:30.587Z,1639587690.587 [NAL9602](INFO): Powering down
2021-12-15T17:01:31.422Z,1639587691.422 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:01:31.422Z,1639587691.422 [NAL9602] No Fault, FailCount= 1
2021-12-15T17:02:00.895Z,1639587720.895 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:02:21.491Z,1639587741.491 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:02:21.491Z,1639587741.491 [NAL9602] Communications Fault, FailCount= 2
2021-12-15T17:02:21.491Z,1639587741.491 [NAL9602](ERROR): Communications Fault
2021-12-15T17:02:21.537Z,1639587741.537 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:02:21.889Z,1639587741.889 [NAL9602](INFO): Powering down
2021-12-15T17:02:22.735Z,1639587742.735 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:02:22.735Z,1639587742.735 [NAL9602] No Fault, FailCount= 2
2021-12-15T17:02:52.191Z,1639587772.191 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:03:12.799Z,1639587792.799 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:03:12.799Z,1639587792.799 [NAL9602] Communications Fault, FailCount= 3
2021-12-15T17:03:12.799Z,1639587792.799 [NAL9602](ERROR): Communications Fault
2021-12-15T17:03:12.842Z,1639587792.842 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:03:13.198Z,1639587793.198 [NAL9602](INFO): Powering down
2021-12-15T17:03:14.074Z,1639587794.074 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:03:14.074Z,1639587794.074 [NAL9602] No Fault, FailCount= 3
2021-12-15T17:03:23.303Z,1639587803.303 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2021-12-15T17:03:23.303Z,1639587803.303 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-15T17:03:23.333Z,1639587803.333 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-15T17:03:23.723Z,1639587803.723 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-15T17:03:23.723Z,1639587803.723 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2021-12-15T17:03:27.754Z,1639587807.754 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-12-15T17:03:27.754Z,1639587807.754 [Default:CheckIn:C.Wait] Stopped
2021-12-15T17:03:27.754Z,1639587807.754 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-12-15T17:03:27.754Z,1639587807.754 [Default:CheckIn:D] Running Loop=1
2021-12-15T17:03:28.160Z,1639587808.160 [Default:CheckIn:D] Stopped
2021-12-15T17:03:28.160Z,1639587808.160 [Default:CheckIn:E] Running Loop=1
2021-12-15T17:03:28.576Z,1639587808.576 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.136137 min 
2021-12-15T17:03:28.576Z,1639587808.576 [Default:CheckIn:E] Stopped
2021-12-15T17:03:28.576Z,1639587808.576 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-12-15T17:03:28.577Z,1639587808.577 [Default:CheckIn] Stopped
2021-12-15T17:03:28.577Z,1639587808.577 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-12-15T17:03:28.578Z,1639587808.578 [Default:CheckIn](INFO): Running loop #3
2021-12-15T17:03:28.578Z,1639587808.578 [Default:CheckIn] Running Loop=3
2021-12-15T17:03:28.578Z,1639587808.578 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-12-15T17:03:28.578Z,1639587808.578 [Default:CheckIn:Read_GPS] Running Loop=1
2021-12-15T17:03:43.499Z,1639587823.499 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:03:45.923Z,1639587825.923 [Power24vConverter](INFO): Powering down.
2021-12-15T17:04:04.111Z,1639587844.111 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:04:04.112Z,1639587844.112 [NAL9602] Communications Fault, FailCount= 4
2021-12-15T17:04:04.112Z,1639587844.112 [NAL9602](ERROR): Communications Fault
2021-12-15T17:04:04.129Z,1639587844.129 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:04:04.507Z,1639587844.507 [NAL9602](INFO): Powering down
2021-12-15T17:04:05.335Z,1639587845.335 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:04:05.335Z,1639587845.335 [NAL9602] No Fault, FailCount= 4
2021-12-15T17:04:34.806Z,1639587874.806 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:04:55.415Z,1639587895.415 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:04:55.415Z,1639587895.415 [NAL9602] Communications Fault, FailCount= 5
2021-12-15T17:04:55.415Z,1639587895.415 [NAL9602](ERROR): Communications Fault
2021-12-15T17:04:55.448Z,1639587895.448 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:04:55.448Z,1639587895.448 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2021-12-15T17:04:55.819Z,1639587895.819 [NAL9602](INFO): Powering down
2021-12-15T17:04:55.859Z,1639587895.859 [CBIT](INFO): Critical error at 20211215T170455
2021-12-15T17:06:11.426Z,1639587971.426 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:06:11.426Z,1639587971.426 [DAT] No Fault, FailCount= 8
2021-12-15T17:06:11.773Z,1639587971.773 [DAT](INFO): Powering up
2021-12-15T17:06:11.774Z,1639587971.774 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:06:12.170Z,1639587972.170 [Power24vConverter](INFO): Powering up.
2021-12-15T17:06:24.301Z,1639587984.301 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2021-12-15T17:06:24.302Z,1639587984.302 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-15T17:06:24.313Z,1639587984.313 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-15T17:06:24.717Z,1639587984.717 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-15T17:06:24.717Z,1639587984.717 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2021-12-15T17:06:55.434Z,1639588015.434 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:06:55.434Z,1639588015.434 [NAL9602] No Fault, FailCount= 5
2021-12-15T17:06:55.807Z,1639588015.807 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:07:11.972Z,1639588031.972 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:07:11.972Z,1639588031.972 [DAT] Communications Fault, FailCount= 1
2021-12-15T17:07:11.972Z,1639588031.972 [DAT](ERROR): Communications Fault
2021-12-15T17:07:11.991Z,1639588031.991 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:07:12.368Z,1639588032.368 [DAT](INFO): Powering down
2021-12-15T17:07:13.228Z,1639588033.228 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:07:13.228Z,1639588033.228 [DAT] No Fault, FailCount= 1
2021-12-15T17:07:15.609Z,1639588035.609 [DAT](INFO): Powering up
2021-12-15T17:07:15.609Z,1639588035.609 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:07:16.408Z,1639588036.408 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:07:16.408Z,1639588036.408 [NAL9602] Communications Fault, FailCount= 1
2021-12-15T17:07:16.408Z,1639588036.408 [NAL9602](ERROR): Communications Fault
2021-12-15T17:07:16.428Z,1639588036.428 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:07:16.811Z,1639588036.811 [NAL9602](INFO): Powering down
2021-12-15T17:07:17.654Z,1639588037.654 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:07:17.654Z,1639588037.654 [NAL9602] No Fault, FailCount= 1
2021-12-15T17:07:30.921Z,1639588050.921 [BackseatComponent](FAULT): Failed to receive device response within the specified timeout.
2021-12-15T17:07:30.921Z,1639588050.921 [BackseatComponent] Communications Fault, FailCount= 1
2021-12-15T17:07:30.921Z,1639588050.921 [BackseatComponent](ERROR): Communications Fault
2021-12-15T17:07:31.001Z,1639588051.001 [CBIT](ERROR): Communications Fault in component: BackseatComponent
2021-12-15T17:07:31.326Z,1639588051.326 [BackseatComponent](INFO): Sent LCM shutdown request.
2021-12-15T17:07:46.678Z,1639588066.678 [BackseatComponent](INFO): Powering down
2021-12-15T17:07:47.081Z,1639588067.081 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2021-12-15T17:07:47.081Z,1639588067.081 [LcmUniversalReporter](INFO): Deactivating messaging.
2021-12-15T17:07:47.119Z,1639588067.119 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:07:47.485Z,1639588067.485 [BackseatComponent](INFO): Powering up
2021-12-15T17:07:47.486Z,1639588067.486 [BackseatComponent](INFO): Subscribing to LCM channels.
2021-12-15T17:07:47.548Z,1639588067.548 [CBIT](INFO): Clearing failed state for component BackseatComponent
2021-12-15T17:07:47.548Z,1639588067.548 [BackseatComponent] No Fault, FailCount= 1
2021-12-15T17:08:07.715Z,1639588087.715 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:08:07.715Z,1639588087.715 [NAL9602] Communications Fault, FailCount= 2
2021-12-15T17:08:07.715Z,1639588087.715 [NAL9602](ERROR): Communications Fault
2021-12-15T17:08:07.770Z,1639588087.770 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:08:08.122Z,1639588088.122 [NAL9602](INFO): Powering down
2021-12-15T17:08:08.950Z,1639588088.950 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:08:08.950Z,1639588088.950 [NAL9602] No Fault, FailCount= 2
2021-12-15T17:08:15.806Z,1639588095.806 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:08:15.806Z,1639588095.806 [DAT] Communications Fault, FailCount= 2
2021-12-15T17:08:15.806Z,1639588095.806 [DAT](ERROR): Communications Fault
2021-12-15T17:08:15.824Z,1639588095.824 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:08:16.203Z,1639588096.203 [DAT](INFO): Powering down
2021-12-15T17:08:17.025Z,1639588097.025 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:08:17.025Z,1639588097.025 [DAT] No Fault, FailCount= 2
2021-12-15T17:08:19.435Z,1639588099.435 [DAT](INFO): Powering up
2021-12-15T17:08:19.435Z,1639588099.435 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:08:28.735Z,1639588108.735 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-12-15T17:03:28.6Z
2021-12-15T17:08:28.735Z,1639588108.735 [Default:CheckIn:Read_GPS] Stopped
2021-12-15T17:08:28.736Z,1639588108.736 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-12-15T17:08:35.337Z,1639588115.337 [DataOverHttps](INFO): Sending 96 bytes from file Logs/20211215T163904/Courier0010.lzma
2021-12-15T17:08:35.341Z,1639588115.341 [BackseatComponent](IMPORTANT): IBASE917-01: Running bioacoustics backseat app. Logging to: 20211215T170834
2021-12-15T17:08:36.339Z,1639588116.339 [DataOverHttps](INFO): Moved sent file to Logs/20211215T163904/Courier0010.lzma.bak
2021-12-15T17:08:36.339Z,1639588116.339 [DataOverHttps](INFO): SBD MOMSN=16391375
2021-12-15T17:08:38.423Z,1639588118.423 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:08:52.102Z,1639588132.102 [DataOverHttps](INFO): Sending 349 bytes from file Logs/20211215T163904/Express0011.lzma
2021-12-15T17:08:53.103Z,1639588133.103 [DataOverHttps](INFO): Moved sent file to Logs/20211215T163904/Express0011.lzma.bak
2021-12-15T17:08:53.103Z,1639588133.103 [DataOverHttps](INFO): SBD MOMSN=16391379
2021-12-15T17:08:54.196Z,1639588134.196 [Default:CheckIn:Read_Iridium] Stopped
2021-12-15T17:08:54.196Z,1639588134.196 [Default:CheckIn:C.Wait] Running Loop=1
2021-12-15T17:08:54.196Z,1639588134.196 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-12-15T17:08:55.018Z,1639588135.018 [BackseatComponent](FAULT): LCB fault:  Software Overcurrent.
2021-12-15T17:08:55.018Z,1639588135.018 [BackseatComponent] Hardware Fault, FailCount= 1
2021-12-15T17:08:55.018Z,1639588135.018 [BackseatComponent](ERROR): Hardware Fault
2021-12-15T17:08:55.019Z,1639588135.019 [BackseatComponent](INFO): Sent LCM shutdown request.
2021-12-15T17:08:55.040Z,1639588135.040 [CBIT](ERROR): Hardware Fault in component: BackseatComponent
2021-12-15T17:08:59.031Z,1639588139.031 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:08:59.031Z,1639588139.031 [NAL9602] Communications Fault, FailCount= 3
2021-12-15T17:08:59.031Z,1639588139.031 [NAL9602](ERROR): Communications Fault
2021-12-15T17:08:59.091Z,1639588139.091 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:08:59.431Z,1639588139.431 [NAL9602](INFO): Powering down
2021-12-15T17:09:00.250Z,1639588140.250 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:09:00.250Z,1639588140.250 [NAL9602] No Fault, FailCount= 3
2021-12-15T17:09:10.401Z,1639588150.401 [BackseatComponent](INFO): Powering down
2021-12-15T17:09:10.805Z,1639588150.805 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2021-12-15T17:09:10.805Z,1639588150.805 [LcmUniversalReporter](INFO): Deactivating messaging.
2021-12-15T17:09:11.209Z,1639588151.209 [BackseatComponent](INFO): Powering up
2021-12-15T17:09:11.210Z,1639588151.210 [BackseatComponent](INFO): Subscribing to LCM channels.
2021-12-15T17:09:11.582Z,1639588151.582 [CBIT](INFO): Clearing failed state for component BackseatComponent
2021-12-15T17:09:11.582Z,1639588151.582 [BackseatComponent] No Fault, FailCount= 0
2021-12-15T17:09:19.634Z,1639588159.634 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:09:19.634Z,1639588159.634 [DAT] Communications Fault, FailCount= 3
2021-12-15T17:09:19.634Z,1639588159.634 [DAT](ERROR): Communications Fault
2021-12-15T17:09:19.652Z,1639588159.652 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:09:20.035Z,1639588160.035 [DAT](INFO): Powering down
2021-12-15T17:09:20.854Z,1639588160.854 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:09:20.854Z,1639588160.854 [DAT] No Fault, FailCount= 3
2021-12-15T17:09:23.261Z,1639588163.261 [DAT](INFO): Powering up
2021-12-15T17:09:23.261Z,1639588163.261 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:09:25.294Z,1639588165.294 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2021-12-15T17:09:25.294Z,1639588165.294 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-15T17:09:25.305Z,1639588165.305 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-15T17:09:25.715Z,1639588165.715 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-15T17:09:25.715Z,1639588165.715 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2021-12-15T17:09:29.731Z,1639588169.731 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:09:50.335Z,1639588190.335 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:09:50.335Z,1639588190.335 [NAL9602] Communications Fault, FailCount= 4
2021-12-15T17:09:50.335Z,1639588190.335 [NAL9602](ERROR): Communications Fault
2021-12-15T17:09:50.354Z,1639588190.354 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:09:50.739Z,1639588190.739 [NAL9602](INFO): Powering down
2021-12-15T17:09:51.561Z,1639588191.561 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:09:51.561Z,1639588191.561 [NAL9602] No Fault, FailCount= 4
2021-12-15T17:10:02.275Z,1639588202.275 [BackseatComponent](IMPORTANT): IBASE917-01: Running bioacoustics backseat app. Logging to: 20211215T171000
2021-12-15T17:10:21.035Z,1639588221.035 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:10:23.463Z,1639588223.463 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:10:23.463Z,1639588223.463 [DAT] Communications Fault, FailCount= 4
2021-12-15T17:10:23.463Z,1639588223.463 [DAT](ERROR): Communications Fault
2021-12-15T17:10:23.480Z,1639588223.480 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:10:23.867Z,1639588223.867 [DAT](INFO): Powering down
2021-12-15T17:10:24.719Z,1639588224.719 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:10:24.719Z,1639588224.719 [DAT] No Fault, FailCount= 4
2021-12-15T17:10:27.099Z,1639588227.099 [DAT](INFO): Powering up
2021-12-15T17:10:27.099Z,1639588227.099 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:10:41.643Z,1639588241.643 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:10:41.643Z,1639588241.643 [NAL9602] Communications Fault, FailCount= 5
2021-12-15T17:10:41.643Z,1639588241.643 [NAL9602](ERROR): Communications Fault
2021-12-15T17:10:41.682Z,1639588241.682 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:10:41.683Z,1639588241.683 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2021-12-15T17:10:42.046Z,1639588242.046 [NAL9602](INFO): Powering down
2021-12-15T17:10:42.063Z,1639588242.063 [CBIT](INFO): Critical error at 20211215T171041
2021-12-15T17:10:56.834Z,1639588256.834 [BackseatComponent](FAULT): LCB fault:  Software Overcurrent.
2021-12-15T17:10:56.834Z,1639588256.834 [BackseatComponent] Hardware Fault, FailCount= 1
2021-12-15T17:10:56.834Z,1639588256.834 [BackseatComponent](ERROR): Hardware Fault
2021-12-15T17:10:56.835Z,1639588256.835 [BackseatComponent](INFO): Sent LCM shutdown request.
2021-12-15T17:10:57.007Z,1639588257.007 [CBIT](ERROR): Hardware Fault in component: BackseatComponent
2021-12-15T17:11:12.189Z,1639588272.189 [BackseatComponent](INFO): Powering down
2021-12-15T17:11:12.593Z,1639588272.593 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2021-12-15T17:11:12.593Z,1639588272.593 [LcmUniversalReporter](INFO): Deactivating messaging.
2021-12-15T17:11:12.997Z,1639588272.997 [BackseatComponent](INFO): Powering up
2021-12-15T17:11:12.998Z,1639588272.998 [BackseatComponent](INFO): Subscribing to LCM channels.
2021-12-15T17:11:13.177Z,1639588273.177 [CBIT](INFO): Clearing failed state for component BackseatComponent
2021-12-15T17:11:13.177Z,1639588273.177 [BackseatComponent] No Fault, FailCount= 0
2021-12-15T17:11:27.295Z,1639588287.295 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:11:27.295Z,1639588287.295 [DAT] Communications Fault, FailCount= 5
2021-12-15T17:11:27.295Z,1639588287.295 [DAT](ERROR): Communications Fault
2021-12-15T17:11:27.333Z,1639588287.333 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:11:27.699Z,1639588287.699 [DAT](INFO): Powering down
2021-12-15T17:11:28.522Z,1639588288.522 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:11:28.522Z,1639588288.522 [DAT] No Fault, FailCount= 5
2021-12-15T17:11:30.926Z,1639588290.926 [DAT](INFO): Powering up
2021-12-15T17:11:30.926Z,1639588290.926 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:12:04.424Z,1639588324.424 [BackseatComponent](IMPORTANT): IBASE917-01: Running bioacoustics backseat app. Logging to: 20211215T171203
2021-12-15T17:12:26.287Z,1639588346.287 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2021-12-15T17:12:26.287Z,1639588346.287 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-15T17:12:26.318Z,1639588346.318 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-15T17:12:26.700Z,1639588346.700 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-15T17:12:26.700Z,1639588346.700 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2021-12-15T17:12:31.127Z,1639588351.127 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:12:31.127Z,1639588351.127 [DAT] Communications Fault, FailCount= 6
2021-12-15T17:12:31.127Z,1639588351.127 [DAT](ERROR): Communications Fault
2021-12-15T17:12:31.208Z,1639588351.208 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:12:31.526Z,1639588351.526 [DAT](INFO): Powering down
2021-12-15T17:12:32.362Z,1639588352.362 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:12:32.362Z,1639588352.362 [DAT] No Fault, FailCount= 6
2021-12-15T17:12:34.763Z,1639588354.763 [DAT](INFO): Powering up
2021-12-15T17:12:34.763Z,1639588354.763 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:12:41.645Z,1639588361.645 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:12:41.645Z,1639588361.645 [NAL9602] No Fault, FailCount= 5
2021-12-15T17:12:42.038Z,1639588362.038 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:13:02.639Z,1639588382.639 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:13:02.639Z,1639588382.639 [NAL9602] Communications Fault, FailCount= 1
2021-12-15T17:13:02.639Z,1639588382.639 [NAL9602](ERROR): Communications Fault
2021-12-15T17:13:02.657Z,1639588382.657 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:13:03.042Z,1639588383.042 [NAL9602](INFO): Powering down
2021-12-15T17:13:03.864Z,1639588383.864 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:13:03.864Z,1639588383.864 [NAL9602] No Fault, FailCount= 1
2021-12-15T17:13:33.346Z,1639588413.346 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:13:34.956Z,1639588414.956 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:13:34.956Z,1639588414.956 [DAT] Communications Fault, FailCount= 7
2021-12-15T17:13:34.956Z,1639588414.956 [DAT](ERROR): Communications Fault
2021-12-15T17:13:34.977Z,1639588414.977 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:13:35.359Z,1639588415.359 [DAT](INFO): Powering down
2021-12-15T17:13:36.188Z,1639588416.188 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:13:36.188Z,1639588416.188 [DAT] No Fault, FailCount= 7
2021-12-15T17:13:38.595Z,1639588418.595 [DAT](INFO): Powering up
2021-12-15T17:13:38.595Z,1639588418.595 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:13:53.949Z,1639588433.949 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:13:53.950Z,1639588433.950 [NAL9602] Communications Fault, FailCount= 2
2021-12-15T17:13:53.950Z,1639588433.950 [NAL9602](ERROR): Communications Fault
2021-12-15T17:13:53.992Z,1639588433.992 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:13:54.351Z,1639588434.351 [NAL9602](INFO): Powering down
2021-12-15T17:13:54.772Z,1639588434.772 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-12-15T17:13:54.773Z,1639588434.773 [Default:CheckIn:C.Wait] Stopped
2021-12-15T17:13:54.774Z,1639588434.774 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-12-15T17:13:54.774Z,1639588434.774 [Default:CheckIn:D] Running Loop=1
2021-12-15T17:13:55.183Z,1639588435.183 [Default:CheckIn:D] Stopped
2021-12-15T17:13:55.183Z,1639588435.183 [Default:CheckIn:E] Running Loop=1
2021-12-15T17:13:55.194Z,1639588435.194 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:13:55.194Z,1639588435.194 [NAL9602] No Fault, FailCount= 2
2021-12-15T17:13:55.571Z,1639588435.571 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.586515 min 
2021-12-15T17:13:55.571Z,1639588435.571 [Default:CheckIn:E] Stopped
2021-12-15T17:13:55.572Z,1639588435.572 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-12-15T17:13:55.572Z,1639588435.572 [Default:CheckIn] Stopped
2021-12-15T17:13:55.572Z,1639588435.572 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-12-15T17:13:55.572Z,1639588435.572 [Default:CheckIn](INFO): Running loop #4
2021-12-15T17:13:55.572Z,1639588435.572 [Default:CheckIn] Running Loop=4
2021-12-15T17:13:55.572Z,1639588435.572 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-12-15T17:13:55.572Z,1639588435.572 [Default:CheckIn:Read_GPS] Running Loop=1
2021-12-15T17:14:24.645Z,1639588464.645 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:14:38.788Z,1639588478.788 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:14:38.789Z,1639588478.789 [DAT] Communications Fault, FailCount= 8
2021-12-15T17:14:38.789Z,1639588478.789 [DAT](ERROR): Communications Fault
2021-12-15T17:14:38.806Z,1639588478.806 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:14:38.807Z,1639588478.807 [CBIT](FAULT): Communications Fault in component: DAT
2021-12-15T17:14:39.191Z,1639588479.191 [DAT](INFO): Powering down
2021-12-15T17:14:45.255Z,1639588485.255 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:14:45.255Z,1639588485.255 [NAL9602] Communications Fault, FailCount= 3
2021-12-15T17:14:45.255Z,1639588485.255 [NAL9602](ERROR): Communications Fault
2021-12-15T17:14:45.273Z,1639588485.273 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:14:45.663Z,1639588485.663 [NAL9602](INFO): Powering down
2021-12-15T17:14:46.504Z,1639588486.504 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:14:46.504Z,1639588486.504 [NAL9602] No Fault, FailCount= 3
2021-12-15T17:15:15.959Z,1639588515.959 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:15:27.290Z,1639588527.290 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2021-12-15T17:15:27.290Z,1639588527.290 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-15T17:15:27.337Z,1639588527.337 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-15T17:15:27.708Z,1639588527.708 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-15T17:15:27.708Z,1639588527.708 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2021-12-15T17:15:36.563Z,1639588536.563 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:15:36.563Z,1639588536.563 [NAL9602] Communications Fault, FailCount= 4
2021-12-15T17:15:36.563Z,1639588536.563 [NAL9602](ERROR): Communications Fault
2021-12-15T17:15:36.613Z,1639588536.613 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:15:36.967Z,1639588536.967 [NAL9602](INFO): Powering down
2021-12-15T17:15:37.798Z,1639588537.798 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:15:37.798Z,1639588537.798 [NAL9602] No Fault, FailCount= 4
2021-12-15T17:16:07.267Z,1639588567.267 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:16:27.871Z,1639588587.871 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:16:27.871Z,1639588587.871 [NAL9602] Communications Fault, FailCount= 5
2021-12-15T17:16:27.871Z,1639588587.871 [NAL9602](ERROR): Communications Fault
2021-12-15T17:16:27.890Z,1639588587.890 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:16:27.890Z,1639588587.890 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2021-12-15T17:16:28.271Z,1639588588.271 [NAL9602](INFO): Powering down
2021-12-15T17:16:28.338Z,1639588588.338 [CBIT](INFO): Critical error at 20211215T171627
2021-12-15T17:17:13.518Z,1639588633.518 [Power24vConverter](INFO): Powering down.
2021-12-15T17:18:27.874Z,1639588707.874 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:18:27.874Z,1639588707.874 [NAL9602] No Fault, FailCount= 5
2021-12-15T17:18:28.263Z,1639588708.263 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:18:28.286Z,1639588708.286 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2021-12-15T17:18:28.287Z,1639588708.287 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-15T17:18:28.314Z,1639588708.314 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-15T17:18:28.703Z,1639588708.703 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-15T17:18:28.703Z,1639588708.703 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2021-12-15T17:18:48.871Z,1639588728.871 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:18:48.871Z,1639588728.871 [NAL9602] Communications Fault, FailCount= 1
2021-12-15T17:18:48.871Z,1639588728.871 [NAL9602](ERROR): Communications Fault
2021-12-15T17:18:48.890Z,1639588728.890 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:18:49.267Z,1639588729.267 [NAL9602](INFO): Powering down
2021-12-15T17:18:50.092Z,1639588730.092 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:18:50.093Z,1639588730.093 [NAL9602] No Fault, FailCount= 1
2021-12-15T17:18:55.757Z,1639588735.757 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-12-15T17:13:55.6Z
2021-12-15T17:18:55.757Z,1639588735.757 [Default:CheckIn:Read_GPS] Stopped
2021-12-15T17:18:55.757Z,1639588735.757 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-12-15T17:19:03.445Z,1639588743.445 [DataOverHttps](INFO): Sending 96 bytes from file Logs/20211215T163904/Courier0013.lzma
2021-12-15T17:19:04.447Z,1639588744.447 [DataOverHttps](INFO): Moved sent file to Logs/20211215T163904/Courier0013.lzma.bak
2021-12-15T17:19:04.447Z,1639588744.447 [DataOverHttps](INFO): SBD MOMSN=16391396
2021-12-15T17:19:19.250Z,1639588759.250 [DataOverHttps](INFO): Sending 374 bytes from file Logs/20211215T163904/Express0014.lzma
2021-12-15T17:19:19.566Z,1639588759.566 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:19:20.249Z,1639588760.249 [DataOverHttps](INFO): Moved sent file to Logs/20211215T163904/Express0014.lzma.bak
2021-12-15T17:19:20.249Z,1639588760.249 [DataOverHttps](INFO): SBD MOMSN=16391400
2021-12-15T17:19:21.599Z,1639588761.599 [Default:CheckIn:Read_Iridium] Stopped
2021-12-15T17:19:21.599Z,1639588761.599 [Default:CheckIn:C.Wait] Running Loop=1
2021-12-15T17:19:21.599Z,1639588761.599 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-12-15T17:19:38.991Z,1639588778.991 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:19:38.991Z,1639588778.991 [DAT] No Fault, FailCount= 8
2021-12-15T17:19:39.363Z,1639588779.363 [DAT](INFO): Powering up
2021-12-15T17:19:39.364Z,1639588779.364 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:19:39.771Z,1639588779.771 [Power24vConverter](INFO): Powering up.
2021-12-15T17:19:40.175Z,1639588780.175 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:19:40.175Z,1639588780.175 [NAL9602] Communications Fault, FailCount= 2
2021-12-15T17:19:40.175Z,1639588780.175 [NAL9602](ERROR): Communications Fault
2021-12-15T17:19:40.194Z,1639588780.194 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:19:40.585Z,1639588780.585 [NAL9602](INFO): Powering down
2021-12-15T17:19:41.404Z,1639588781.404 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:19:41.404Z,1639588781.404 [NAL9602] No Fault, FailCount= 2
2021-12-15T17:20:10.881Z,1639588810.881 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:20:31.483Z,1639588831.483 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:20:31.483Z,1639588831.483 [NAL9602] Communications Fault, FailCount= 3
2021-12-15T17:20:31.483Z,1639588831.483 [NAL9602](ERROR): Communications Fault
2021-12-15T17:20:31.524Z,1639588831.524 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:20:31.883Z,1639588831.883 [NAL9602](INFO): Powering down
2021-12-15T17:20:32.717Z,1639588832.717 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:20:32.717Z,1639588832.717 [NAL9602] No Fault, FailCount= 3
2021-12-15T17:20:34.422Z,1639588834.422 [BackseatComponent](FAULT): LCB fault:  Software Overcurrent.
2021-12-15T17:20:34.422Z,1639588834.422 [BackseatComponent] Hardware Fault, FailCount= 1
2021-12-15T17:20:34.422Z,1639588834.422 [BackseatComponent](ERROR): Hardware Fault
2021-12-15T17:20:34.423Z,1639588834.423 [BackseatComponent](INFO): Sent LCM shutdown request.
2021-12-15T17:20:34.754Z,1639588834.754 [CBIT](ERROR): Hardware Fault in component: BackseatComponent
2021-12-15T17:20:39.563Z,1639588839.563 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:20:39.563Z,1639588839.563 [DAT] Communications Fault, FailCount= 1
2021-12-15T17:20:39.563Z,1639588839.563 [DAT](ERROR): Communications Fault
2021-12-15T17:20:39.601Z,1639588839.601 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:20:39.967Z,1639588839.967 [DAT](INFO): Powering down
2021-12-15T17:20:40.789Z,1639588840.789 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:20:40.789Z,1639588840.789 [DAT] No Fault, FailCount= 1
2021-12-15T17:20:43.193Z,1639588843.193 [DAT](INFO): Powering up
2021-12-15T17:20:43.193Z,1639588843.193 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:20:49.773Z,1639588849.773 [BackseatComponent](INFO): Powering down
2021-12-15T17:20:50.177Z,1639588850.177 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2021-12-15T17:20:50.177Z,1639588850.177 [LcmUniversalReporter](INFO): Deactivating messaging.
2021-12-15T17:20:50.581Z,1639588850.581 [BackseatComponent](INFO): Powering up
2021-12-15T17:20:50.582Z,1639588850.582 [BackseatComponent](INFO): Subscribing to LCM channels.
2021-12-15T17:20:50.890Z,1639588850.890 [CBIT](INFO): Clearing failed state for component BackseatComponent
2021-12-15T17:20:50.890Z,1639588850.890 [BackseatComponent] No Fault, FailCount= 0
2021-12-15T17:21:02.187Z,1639588862.187 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:21:22.791Z,1639588882.791 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:21:22.791Z,1639588882.791 [NAL9602] Communications Fault, FailCount= 4
2021-12-15T17:21:22.791Z,1639588882.791 [NAL9602](ERROR): Communications Fault
2021-12-15T17:21:22.833Z,1639588882.833 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:21:23.194Z,1639588883.194 [NAL9602](INFO): Powering down
2021-12-15T17:21:24.022Z,1639588884.022 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:21:24.022Z,1639588884.022 [NAL9602] No Fault, FailCount= 4
2021-12-15T17:21:29.256Z,1639588889.256 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2021-12-15T17:21:29.256Z,1639588889.256 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-15T17:21:29.290Z,1639588889.290 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-15T17:21:29.692Z,1639588889.692 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-15T17:21:29.692Z,1639588889.692 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2021-12-15T17:21:43.391Z,1639588903.391 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:21:43.391Z,1639588903.391 [DAT] Communications Fault, FailCount= 2
2021-12-15T17:21:43.391Z,1639588903.391 [DAT](ERROR): Communications Fault
2021-12-15T17:21:43.418Z,1639588903.418 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:21:43.802Z,1639588903.802 [DAT](INFO): Powering down
2021-12-15T17:21:44.624Z,1639588904.624 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:21:44.624Z,1639588904.624 [DAT] No Fault, FailCount= 2
2021-12-15T17:21:47.027Z,1639588907.027 [DAT](INFO): Powering up
2021-12-15T17:21:47.027Z,1639588907.027 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:21:53.492Z,1639588913.492 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:22:14.093Z,1639588934.093 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:22:14.093Z,1639588934.093 [NAL9602] Communications Fault, FailCount= 5
2021-12-15T17:22:14.093Z,1639588934.093 [NAL9602](ERROR): Communications Fault
2021-12-15T17:22:14.121Z,1639588934.121 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:22:14.121Z,1639588934.121 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2021-12-15T17:22:14.499Z,1639588934.499 [NAL9602](INFO): Powering down
2021-12-15T17:22:14.516Z,1639588934.516 [CBIT](INFO): Critical error at 20211215T172214
2021-12-15T17:22:47.227Z,1639588967.227 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:22:47.227Z,1639588967.227 [DAT] Communications Fault, FailCount= 3
2021-12-15T17:22:47.228Z,1639588967.228 [DAT](ERROR): Communications Fault
2021-12-15T17:22:47.246Z,1639588967.246 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:22:47.631Z,1639588967.631 [DAT](INFO): Powering down
2021-12-15T17:22:48.037Z,1639588968.037 [DAT](FAULT): LCB fault:  Software Overcurrent.
2021-12-15T17:22:48.037Z,1639588968.037 [DAT] Hardware Fault, FailCount= 3
2021-12-15T17:22:48.037Z,1639588968.037 [DAT](ERROR): Hardware Fault
2021-12-15T17:22:48.471Z,1639588968.471 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:22:48.471Z,1639588968.471 [DAT] No Fault, FailCount= 3
2021-12-15T17:22:50.863Z,1639588970.863 [DAT](INFO): Powering up
2021-12-15T17:22:50.863Z,1639588970.863 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:23:51.107Z,1639589031.107 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:23:51.107Z,1639589031.107 [DAT] Communications Fault, FailCount= 4
2021-12-15T17:23:51.107Z,1639589031.107 [DAT](ERROR): Communications Fault
2021-12-15T17:23:51.125Z,1639589031.125 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:23:51.503Z,1639589031.503 [DAT](INFO): Powering down
2021-12-15T17:23:52.326Z,1639589032.326 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:23:52.326Z,1639589032.326 [DAT] No Fault, FailCount= 4
2021-12-15T17:23:54.745Z,1639589034.745 [DAT](INFO): Powering up
2021-12-15T17:23:54.746Z,1639589034.746 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:24:14.142Z,1639589054.142 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:24:14.142Z,1639589054.142 [NAL9602] No Fault, FailCount= 5
2021-12-15T17:24:14.535Z,1639589054.535 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:24:22.219Z,1639589062.219 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-12-15T17:24:22.219Z,1639589062.219 [Default:CheckIn:C.Wait] Stopped
2021-12-15T17:24:22.219Z,1639589062.219 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-12-15T17:24:22.220Z,1639589062.220 [Default:CheckIn:D] Running Loop=1
2021-12-15T17:24:22.626Z,1639589062.626 [Default:CheckIn:D] Stopped
2021-12-15T17:24:22.626Z,1639589062.626 [Default:CheckIn:E] Running Loop=1
2021-12-15T17:24:23.033Z,1639589063.033 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.043896 min 
2021-12-15T17:24:23.033Z,1639589063.033 [Default:CheckIn:E] Stopped
2021-12-15T17:24:23.033Z,1639589063.033 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-12-15T17:24:23.034Z,1639589063.034 [Default:CheckIn] Stopped
2021-12-15T17:24:23.034Z,1639589063.034 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-12-15T17:24:23.034Z,1639589063.034 [Default:CheckIn](INFO): Running loop #5
2021-12-15T17:24:23.034Z,1639589063.034 [Default:CheckIn] Running Loop=5
2021-12-15T17:24:23.034Z,1639589063.034 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-12-15T17:24:23.034Z,1639589063.034 [Default:CheckIn:Read_GPS] Running Loop=1
2021-12-15T17:24:30.312Z,1639589070.312 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2021-12-15T17:24:30.312Z,1639589070.312 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-15T17:24:30.324Z,1639589070.324 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-15T17:24:30.712Z,1639589070.712 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-15T17:24:30.712Z,1639589070.712 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2021-12-15T17:24:35.135Z,1639589075.135 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:24:35.135Z,1639589075.135 [NAL9602] Communications Fault, FailCount= 1
2021-12-15T17:24:35.135Z,1639589075.135 [NAL9602](ERROR): Communications Fault
2021-12-15T17:24:35.207Z,1639589075.207 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:24:35.539Z,1639589075.539 [NAL9602](INFO): Powering down
2021-12-15T17:24:36.397Z,1639589076.397 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:24:36.397Z,1639589076.397 [NAL9602] No Fault, FailCount= 1
2021-12-15T17:24:54.936Z,1639589094.936 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:24:54.936Z,1639589094.936 [DAT] Communications Fault, FailCount= 5
2021-12-15T17:24:54.936Z,1639589094.936 [DAT](ERROR): Communications Fault
2021-12-15T17:24:54.954Z,1639589094.954 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:24:55.333Z,1639589095.333 [DAT](INFO): Powering down
2021-12-15T17:24:56.163Z,1639589096.163 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:24:56.164Z,1639589096.164 [DAT] No Fault, FailCount= 5
2021-12-15T17:24:58.573Z,1639589098.573 [DAT](INFO): Powering up
2021-12-15T17:24:58.573Z,1639589098.573 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:25:05.839Z,1639589105.839 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:25:26.443Z,1639589126.443 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:25:26.443Z,1639589126.443 [NAL9602] Communications Fault, FailCount= 2
2021-12-15T17:25:26.443Z,1639589126.443 [NAL9602](ERROR): Communications Fault
2021-12-15T17:25:26.463Z,1639589126.463 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:25:26.854Z,1639589126.854 [NAL9602](INFO): Powering down
2021-12-15T17:25:27.677Z,1639589127.677 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:25:27.677Z,1639589127.677 [NAL9602] No Fault, FailCount= 2
2021-12-15T17:25:57.147Z,1639589157.147 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:25:58.779Z,1639589158.779 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:25:58.779Z,1639589158.779 [DAT] Communications Fault, FailCount= 6
2021-12-15T17:25:58.779Z,1639589158.779 [DAT](ERROR): Communications Fault
2021-12-15T17:25:58.796Z,1639589158.796 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:25:59.167Z,1639589159.167 [DAT](INFO): Powering down
2021-12-15T17:25:59.996Z,1639589159.996 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:25:59.996Z,1639589159.996 [DAT] No Fault, FailCount= 6
2021-12-15T17:26:02.407Z,1639589162.407 [DAT](INFO): Powering up
2021-12-15T17:26:02.407Z,1639589162.407 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:26:12.453Z,1639589172.453 [BackseatComponent](FAULT): Failed to receive device response within the specified timeout.
2021-12-15T17:26:12.453Z,1639589172.453 [BackseatComponent] Communications Fault, FailCount= 1
2021-12-15T17:26:12.453Z,1639589172.453 [BackseatComponent](ERROR): Communications Fault
2021-12-15T17:26:12.530Z,1639589172.530 [CBIT](ERROR): Communications Fault in component: BackseatComponent
2021-12-15T17:26:12.858Z,1639589172.858 [BackseatComponent](INFO): Sent LCM shutdown request.
2021-12-15T17:26:17.763Z,1639589177.763 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:26:17.763Z,1639589177.763 [NAL9602] Communications Fault, FailCount= 3
2021-12-15T17:26:17.763Z,1639589177.763 [NAL9602](ERROR): Communications Fault
2021-12-15T17:26:17.810Z,1639589177.810 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:26:18.156Z,1639589178.156 [NAL9602](INFO): Powering down
2021-12-15T17:26:18.998Z,1639589178.998 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:26:18.998Z,1639589178.998 [NAL9602] No Fault, FailCount= 3
2021-12-15T17:26:28.209Z,1639589188.209 [BackseatComponent](INFO): Powering down
2021-12-15T17:26:28.614Z,1639589188.614 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2021-12-15T17:26:28.614Z,1639589188.614 [LcmUniversalReporter](INFO): Deactivating messaging.
2021-12-15T17:26:29.017Z,1639589189.017 [BackseatComponent](INFO): Powering up
2021-12-15T17:26:29.018Z,1639589189.018 [BackseatComponent](INFO): Subscribing to LCM channels.
2021-12-15T17:26:29.112Z,1639589189.112 [CBIT](INFO): Clearing failed state for component BackseatComponent
2021-12-15T17:26:29.113Z,1639589189.113 [BackseatComponent] No Fault, FailCount= 1
2021-12-15T17:26:48.469Z,1639589208.469 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:27:02.604Z,1639589222.604 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:27:02.604Z,1639589222.604 [DAT] Communications Fault, FailCount= 7
2021-12-15T17:27:02.604Z,1639589222.604 [DAT](ERROR): Communications Fault
2021-12-15T17:27:02.654Z,1639589222.654 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:27:03.007Z,1639589223.007 [DAT](INFO): Powering down
2021-12-15T17:27:03.853Z,1639589223.853 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:27:03.853Z,1639589223.853 [DAT] No Fault, FailCount= 7
2021-12-15T17:27:06.240Z,1639589226.240 [DAT](INFO): Powering up
2021-12-15T17:27:06.240Z,1639589226.240 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:27:09.067Z,1639589229.067 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:27:09.067Z,1639589229.067 [NAL9602] Communications Fault, FailCount= 4
2021-12-15T17:27:09.067Z,1639589229.067 [NAL9602](ERROR): Communications Fault
2021-12-15T17:27:09.109Z,1639589229.109 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:27:09.471Z,1639589229.471 [NAL9602](INFO): Powering down
2021-12-15T17:27:10.301Z,1639589230.301 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:27:10.301Z,1639589230.301 [NAL9602] No Fault, FailCount= 4
2021-12-15T17:27:19.305Z,1639589239.305 [BackseatComponent](IMPORTANT): IBASE917-01: Running bioacoustics backseat app. Logging to: 20211215T172718
2021-12-15T17:27:31.294Z,1639589251.294 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2021-12-15T17:27:31.294Z,1639589251.294 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-15T17:27:31.305Z,1639589251.305 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-15T17:27:31.712Z,1639589251.712 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-15T17:27:31.713Z,1639589251.713 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2021-12-15T17:27:33.646Z,1639589253.646 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239143
2021-12-15T17:27:39.775Z,1639589259.775 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:28:00.371Z,1639589280.371 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:28:00.371Z,1639589280.371 [NAL9602] Communications Fault, FailCount= 5
2021-12-15T17:28:00.371Z,1639589280.371 [NAL9602](ERROR): Communications Fault
2021-12-15T17:28:00.422Z,1639589280.422 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:28:00.423Z,1639589280.423 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2021-12-15T17:28:00.778Z,1639589280.778 [NAL9602](INFO): Powering down
2021-12-15T17:28:00.822Z,1639589280.822 [CBIT](INFO): Critical error at 20211215T172800
2021-12-15T17:28:06.435Z,1639589286.435 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:28:06.435Z,1639589286.435 [DAT] Communications Fault, FailCount= 8
2021-12-15T17:28:06.435Z,1639589286.435 [DAT](ERROR): Communications Fault
2021-12-15T17:28:06.454Z,1639589286.454 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:28:06.454Z,1639589286.454 [CBIT](FAULT): Communications Fault in component: DAT
2021-12-15T17:28:06.779Z,1639589286.779 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-15T17:28:06.835Z,1639589286.835 [DAT](INFO): Powering down
2021-12-15T17:28:41.937Z,1639589321.937 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-15T17:28:54.170Z,1639589334.170 [BackseatComponent](FAULT): LCB fault:  Software Overcurrent.
2021-12-15T17:28:54.170Z,1639589334.170 [BackseatComponent] Hardware Fault, FailCount= 1
2021-12-15T17:28:54.170Z,1639589334.170 [BackseatComponent](ERROR): Hardware Fault
2021-12-15T17:28:54.171Z,1639589334.171 [BackseatComponent](INFO): Sent LCM shutdown request.
2021-12-15T17:28:54.528Z,1639589334.528 [CBIT](ERROR): Hardware Fault in component: BackseatComponent
2021-12-15T17:29:09.535Z,1639589349.535 [BackseatComponent](INFO): Powering down
2021-12-15T17:29:09.937Z,1639589349.937 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2021-12-15T17:29:09.937Z,1639589349.937 [LcmUniversalReporter](INFO): Deactivating messaging.
2021-12-15T17:29:10.341Z,1639589350.341 [BackseatComponent](INFO): Powering up
2021-12-15T17:29:10.342Z,1639589350.342 [BackseatComponent](INFO): Subscribing to LCM channels.
2021-12-15T17:29:10.704Z,1639589350.704 [CBIT](INFO): Clearing failed state for component BackseatComponent
2021-12-15T17:29:10.705Z,1639589350.705 [BackseatComponent] No Fault, FailCount= 0
2021-12-15T17:29:17.078Z,1639589357.078 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-15T17:29:23.205Z,1639589363.205 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-12-15T17:24:23.0Z
2021-12-15T17:29:23.205Z,1639589363.205 [Default:CheckIn:Read_GPS] Stopped
2021-12-15T17:29:23.205Z,1639589363.205 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-12-15T17:29:52.217Z,1639589392.217 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-15T17:30:00.384Z,1639589400.384 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:30:00.384Z,1639589400.384 [NAL9602] No Fault, FailCount= 5
2021-12-15T17:30:00.783Z,1639589400.783 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:30:00.957Z,1639589400.957 [BackseatComponent](IMPORTANT): IBASE917-01: Running bioacoustics backseat app. Logging to: 20211215T172959
2021-12-15T17:30:21.375Z,1639589421.375 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:30:21.375Z,1639589421.375 [NAL9602] Communications Fault, FailCount= 1
2021-12-15T17:30:21.375Z,1639589421.375 [NAL9602](ERROR): Communications Fault
2021-12-15T17:30:21.421Z,1639589421.421 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:30:21.779Z,1639589421.779 [NAL9602](INFO): Powering down
2021-12-15T17:30:22.618Z,1639589422.618 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:30:22.618Z,1639589422.618 [NAL9602] No Fault, FailCount= 1
2021-12-15T17:30:27.357Z,1639589427.357 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-15T17:30:32.304Z,1639589432.304 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2021-12-15T17:30:32.304Z,1639589432.304 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-15T17:30:32.319Z,1639589432.319 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-15T17:30:32.727Z,1639589432.727 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-15T17:30:32.727Z,1639589432.727 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2021-12-15T17:30:41.171Z,1639589441.171 [Power24vConverter](INFO): Powering down.
2021-12-15T17:30:52.083Z,1639589452.083 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:31:02.498Z,1639589462.498 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-15T17:31:12.687Z,1639589472.687 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:31:12.687Z,1639589472.687 [NAL9602] Communications Fault, FailCount= 2
2021-12-15T17:31:12.687Z,1639589472.687 [NAL9602](ERROR): Communications Fault
2021-12-15T17:31:12.730Z,1639589472.730 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:31:13.087Z,1639589473.087 [NAL9602](INFO): Powering down
2021-12-15T17:31:13.932Z,1639589473.932 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:31:13.933Z,1639589473.933 [NAL9602] No Fault, FailCount= 2
2021-12-15T17:31:37.653Z,1639589497.653 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-15T17:31:43.391Z,1639589503.391 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:32:03.995Z,1639589523.995 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:32:03.995Z,1639589523.995 [NAL9602] Communications Fault, FailCount= 3
2021-12-15T17:32:03.995Z,1639589523.995 [NAL9602](ERROR): Communications Fault
2021-12-15T17:32:04.013Z,1639589524.013 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:32:04.398Z,1639589524.398 [NAL9602](INFO): Powering down
2021-12-15T17:32:05.239Z,1639589525.239 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:32:05.239Z,1639589525.239 [NAL9602] No Fault, FailCount= 3
2021-12-15T17:32:12.809Z,1639589532.809 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-15T17:32:34.699Z,1639589554.699 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:32:47.949Z,1639589567.949 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-15T17:32:55.303Z,1639589575.303 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:32:55.303Z,1639589575.303 [NAL9602] Communications Fault, FailCount= 4
2021-12-15T17:32:55.303Z,1639589575.303 [NAL9602](ERROR): Communications Fault
2021-12-15T17:32:55.346Z,1639589575.346 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:32:55.707Z,1639589575.707 [NAL9602](INFO): Powering down
2021-12-15T17:32:56.546Z,1639589576.546 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:32:56.546Z,1639589576.546 [NAL9602] No Fault, FailCount= 4
2021-12-15T17:33:06.647Z,1639589586.647 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:33:06.647Z,1639589586.647 [DAT] No Fault, FailCount= 8
2021-12-15T17:33:07.019Z,1639589587.019 [DAT](INFO): Powering up
2021-12-15T17:33:07.019Z,1639589587.019 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:33:07.423Z,1639589587.423 [Power24vConverter](INFO): Powering up.
2021-12-15T17:33:23.101Z,1639589603.101 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-15T17:33:26.015Z,1639589606.015 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:33:33.290Z,1639589613.290 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2021-12-15T17:33:33.290Z,1639589613.290 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-15T17:33:33.302Z,1639589613.302 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-15T17:33:33.709Z,1639589613.709 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-15T17:33:33.709Z,1639589613.709 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2021-12-15T17:33:46.611Z,1639589626.611 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:33:46.611Z,1639589626.611 [NAL9602] Communications Fault, FailCount= 5
2021-12-15T17:33:46.611Z,1639589626.611 [NAL9602](ERROR): Communications Fault
2021-12-15T17:33:46.630Z,1639589626.630 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:33:46.630Z,1639589626.630 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2021-12-15T17:33:47.019Z,1639589627.019 [NAL9602](INFO): Powering down
2021-12-15T17:33:47.036Z,1639589627.036 [CBIT](INFO): Critical error at 20211215T173346
2021-12-15T17:33:58.241Z,1639589638.241 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-15T17:34:07.209Z,1639589647.209 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:34:07.209Z,1639589647.209 [DAT] Communications Fault, FailCount= 1
2021-12-15T17:34:07.209Z,1639589647.209 [DAT](ERROR): Communications Fault
2021-12-15T17:34:07.255Z,1639589647.255 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:34:07.613Z,1639589647.613 [DAT](INFO): Powering down
2021-12-15T17:34:08.448Z,1639589648.448 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:34:08.449Z,1639589648.449 [DAT] No Fault, FailCount= 1
2021-12-15T17:34:10.851Z,1639589650.851 [DAT](INFO): Powering up
2021-12-15T17:34:10.851Z,1639589650.851 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:34:33.389Z,1639589673.389 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-15T17:35:08.530Z,1639589708.530 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-15T17:35:11.048Z,1639589711.048 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:35:11.048Z,1639589711.048 [DAT] Communications Fault, FailCount= 2
2021-12-15T17:35:11.048Z,1639589711.048 [DAT](ERROR): Communications Fault
2021-12-15T17:35:11.066Z,1639589711.066 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:35:11.455Z,1639589711.455 [DAT](INFO): Powering down
2021-12-15T17:35:12.296Z,1639589712.296 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:35:12.296Z,1639589712.296 [DAT] No Fault, FailCount= 2
2021-12-15T17:35:14.683Z,1639589714.683 [DAT](INFO): Powering up
2021-12-15T17:35:14.683Z,1639589714.683 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:35:43.686Z,1639589743.686 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-15T17:35:46.631Z,1639589746.631 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:35:46.631Z,1639589746.631 [NAL9602] No Fault, FailCount= 5
2021-12-15T17:35:47.003Z,1639589747.003 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:36:07.607Z,1639589767.607 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:36:07.607Z,1639589767.607 [NAL9602] Communications Fault, FailCount= 1
2021-12-15T17:36:07.607Z,1639589767.607 [NAL9602](ERROR): Communications Fault
2021-12-15T17:36:07.626Z,1639589767.626 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:36:08.011Z,1639589768.011 [NAL9602](INFO): Powering down
2021-12-15T17:36:08.849Z,1639589768.849 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:36:08.849Z,1639589768.849 [NAL9602] No Fault, FailCount= 1
2021-12-15T17:36:14.880Z,1639589774.880 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:36:14.880Z,1639589774.880 [DAT] Communications Fault, FailCount= 3
2021-12-15T17:36:14.880Z,1639589774.880 [DAT](ERROR): Communications Fault
2021-12-15T17:36:14.898Z,1639589774.898 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:36:15.295Z,1639589775.295 [DAT](INFO): Powering down
2021-12-15T17:36:16.135Z,1639589776.135 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:36:16.135Z,1639589776.135 [DAT] No Fault, FailCount= 3
2021-12-15T17:36:18.515Z,1639589778.515 [DAT](INFO): Powering up
2021-12-15T17:36:18.515Z,1639589778.515 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:36:18.825Z,1639589778.825 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-15T17:36:34.297Z,1639589794.297 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2021-12-15T17:36:34.297Z,1639589794.297 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-15T17:36:34.336Z,1639589794.336 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-15T17:36:34.698Z,1639589794.698 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-15T17:36:34.698Z,1639589794.698 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2021-12-15T17:36:38.318Z,1639589798.318 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:36:53.977Z,1639589813.977 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-15T17:36:58.915Z,1639589818.915 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:36:58.915Z,1639589818.915 [NAL9602] Communications Fault, FailCount= 2
2021-12-15T17:36:58.915Z,1639589818.915 [NAL9602](ERROR): Communications Fault
2021-12-15T17:36:58.934Z,1639589818.934 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:36:59.319Z,1639589819.319 [NAL9602](INFO): Powering down
2021-12-15T17:37:00.164Z,1639589820.164 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:37:00.165Z,1639589820.165 [NAL9602] No Fault, FailCount= 2
2021-12-15T17:37:18.711Z,1639589838.711 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:37:18.711Z,1639589838.711 [DAT] Communications Fault, FailCount= 4
2021-12-15T17:37:18.711Z,1639589838.711 [DAT](ERROR): Communications Fault
2021-12-15T17:37:18.751Z,1639589838.751 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:37:19.111Z,1639589839.111 [DAT](INFO): Powering down
2021-12-15T17:37:19.948Z,1639589839.948 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:37:19.948Z,1639589839.948 [DAT] No Fault, FailCount= 4
2021-12-15T17:37:22.347Z,1639589842.347 [DAT](INFO): Powering up
2021-12-15T17:37:22.347Z,1639589842.347 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:37:29.117Z,1639589849.117 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-15T17:37:29.621Z,1639589849.621 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:37:50.223Z,1639589870.223 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:37:50.223Z,1639589870.223 [NAL9602] Communications Fault, FailCount= 3
2021-12-15T17:37:50.223Z,1639589870.223 [NAL9602](ERROR): Communications Fault
2021-12-15T17:37:50.242Z,1639589870.242 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:37:50.623Z,1639589870.623 [NAL9602](INFO): Powering down
2021-12-15T17:37:51.467Z,1639589871.467 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:37:51.467Z,1639589871.467 [NAL9602] No Fault, FailCount= 3
2021-12-15T17:38:04.269Z,1639589884.269 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-15T17:38:20.927Z,1639589900.927 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:38:22.544Z,1639589902.544 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:38:22.544Z,1639589902.544 [DAT] Communications Fault, FailCount= 5
2021-12-15T17:38:22.544Z,1639589902.544 [DAT](ERROR): Communications Fault
2021-12-15T17:38:22.562Z,1639589902.562 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:38:22.953Z,1639589902.953 [DAT](INFO): Powering down
2021-12-15T17:38:23.772Z,1639589903.772 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:38:23.772Z,1639589903.772 [DAT] No Fault, FailCount= 5
2021-12-15T17:38:26.179Z,1639589906.179 [DAT](INFO): Powering up
2021-12-15T17:38:26.179Z,1639589906.179 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:38:39.410Z,1639589919.410 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-15T17:38:41.527Z,1639589921.527 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:38:41.527Z,1639589921.527 [NAL9602] Communications Fault, FailCount= 4
2021-12-15T17:38:41.527Z,1639589921.527 [NAL9602](ERROR): Communications Fault
2021-12-15T17:38:41.582Z,1639589921.582 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:38:41.935Z,1639589921.935 [NAL9602](INFO): Powering down
2021-12-15T17:38:42.758Z,1639589922.758 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:38:42.758Z,1639589922.758 [NAL9602] No Fault, FailCount= 4
2021-12-15T17:39:12.235Z,1639589952.235 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:39:14.550Z,1639589954.550 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-15T17:39:26.380Z,1639589966.380 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:39:26.380Z,1639589966.380 [DAT] Communications Fault, FailCount= 6
2021-12-15T17:39:26.381Z,1639589966.381 [DAT](ERROR): Communications Fault
2021-12-15T17:39:26.398Z,1639589966.398 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:39:26.776Z,1639589966.776 [DAT](INFO): Powering down
2021-12-15T17:39:27.599Z,1639589967.599 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:39:27.599Z,1639589967.599 [DAT] No Fault, FailCount= 6
2021-12-15T17:39:30.012Z,1639589970.012 [DAT](INFO): Powering up
2021-12-15T17:39:30.012Z,1639589970.012 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:39:32.839Z,1639589972.839 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:39:32.839Z,1639589972.839 [NAL9602] Communications Fault, FailCount= 5
2021-12-15T17:39:32.839Z,1639589972.839 [NAL9602](ERROR): Communications Fault
2021-12-15T17:39:32.860Z,1639589972.860 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:39:32.861Z,1639589972.861 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2021-12-15T17:39:33.247Z,1639589973.247 [NAL9602](INFO): Powering down
2021-12-15T17:39:33.284Z,1639589973.284 [CBIT](INFO): Critical error at 20211215T173932
2021-12-15T17:39:35.269Z,1639589975.269 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2021-12-15T17:39:35.269Z,1639589975.269 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-15T17:39:35.308Z,1639589975.308 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-15T17:39:35.685Z,1639589975.685 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-15T17:39:35.685Z,1639589975.685 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2021-12-15T17:40:02.118Z,1639590002.118 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003337
2021-12-15T17:40:09.493Z,1639590009.493 [DataOverHttps](INFO): Sending 96 bytes from file Logs/20211215T163904/Courier0016.lzma
2021-12-15T17:40:10.495Z,1639590010.495 [DataOverHttps](INFO): Moved sent file to Logs/20211215T163904/Courier0016.lzma.bak
2021-12-15T17:40:10.495Z,1639590010.495 [DataOverHttps](INFO): SBD MOMSN=16391430
2021-12-15T17:40:29.193Z,1639590029.193 [DataOverHttps](INFO): Sending 455 bytes from file Logs/20211215T163904/Express0017.lzma
2021-12-15T17:40:30.195Z,1639590030.195 [DataOverHttps](INFO): Moved sent file to Logs/20211215T163904/Express0017.lzma.bak
2021-12-15T17:40:30.195Z,1639590030.195 [DataOverHttps](INFO): SBD MOMSN=16391434
2021-12-15T17:40:30.247Z,1639590030.247 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:40:30.247Z,1639590030.247 [DAT] Communications Fault, FailCount= 7
2021-12-15T17:40:30.247Z,1639590030.247 [DAT](ERROR): Communications Fault
2021-12-15T17:40:30.302Z,1639590030.302 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:40:30.651Z,1639590030.651 [DAT](INFO): Powering down
2021-12-15T17:40:31.470Z,1639590031.470 [Default:CheckIn:Read_Iridium] Stopped
2021-12-15T17:40:31.470Z,1639590031.470 [Default:CheckIn:C.Wait] Running Loop=1
2021-12-15T17:40:31.470Z,1639590031.470 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-12-15T17:40:31.481Z,1639590031.481 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:40:31.481Z,1639590031.481 [DAT] No Fault, FailCount= 7
2021-12-15T17:40:33.879Z,1639590033.879 [DAT](INFO): Powering up
2021-12-15T17:40:33.879Z,1639590033.879 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:41:32.907Z,1639590092.907 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:41:32.907Z,1639590092.907 [NAL9602] No Fault, FailCount= 5
2021-12-15T17:41:33.264Z,1639590093.264 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:41:34.075Z,1639590094.075 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:41:34.075Z,1639590094.075 [DAT] Communications Fault, FailCount= 8
2021-12-15T17:41:34.075Z,1639590094.075 [DAT](ERROR): Communications Fault
2021-12-15T17:41:34.113Z,1639590094.113 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:41:34.114Z,1639590094.114 [CBIT](FAULT): Communications Fault in component: DAT
2021-12-15T17:41:34.479Z,1639590094.479 [DAT](INFO): Powering down
2021-12-15T17:41:53.869Z,1639590113.869 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:41:53.869Z,1639590113.869 [NAL9602] Communications Fault, FailCount= 1
2021-12-15T17:41:53.877Z,1639590113.877 [NAL9602](ERROR): Communications Fault
2021-12-15T17:41:53.914Z,1639590113.914 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:41:54.279Z,1639590114.279 [NAL9602](INFO): Powering down
2021-12-15T17:41:55.100Z,1639590115.100 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:41:55.101Z,1639590115.101 [NAL9602] No Fault, FailCount= 1
2021-12-15T17:42:24.642Z,1639590144.642 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:42:36.346Z,1639590156.346 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21
2021-12-15T17:42:36.346Z,1639590156.346 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-15T17:42:36.385Z,1639590156.385 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-15T17:42:36.760Z,1639590156.760 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-15T17:42:36.760Z,1639590156.760 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21
2021-12-15T17:42:45.231Z,1639590165.231 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:42:45.231Z,1639590165.231 [NAL9602] Communications Fault, FailCount= 2
2021-12-15T17:42:45.231Z,1639590165.231 [NAL9602](ERROR): Communications Fault
2021-12-15T17:42:45.250Z,1639590165.250 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:42:45.629Z,1639590165.629 [NAL9602](INFO): Powering down
2021-12-15T17:42:46.459Z,1639590166.459 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:42:46.459Z,1639590166.459 [NAL9602] No Fault, FailCount= 2
2021-12-15T17:43:15.948Z,1639590195.948 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:43:36.537Z,1639590216.537 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:43:36.537Z,1639590216.537 [NAL9602] Communications Fault, FailCount= 3
2021-12-15T17:43:36.537Z,1639590216.537 [NAL9602](ERROR): Communications Fault
2021-12-15T17:43:36.576Z,1639590216.576 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:43:36.935Z,1639590216.935 [NAL9602](INFO): Powering down
2021-12-15T17:43:37.790Z,1639590217.790 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:43:37.790Z,1639590217.790 [NAL9602] No Fault, FailCount= 3
2021-12-15T17:43:43.030Z,1639590223.030 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-12-15T17:43:56.335Z,1639590236.335 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-12-15T17:43:56.337Z,1639590236.337 [BPC1](INFO): Calculating totals. Valid battery stick count: 27. Valid reserve battery stick count: 3.
2021-12-15T17:43:56.340Z,1639590236.340 [BPC1](INFO): Received data from all battery sticks.
2021-12-15T17:44:07.235Z,1639590247.235 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:44:08.455Z,1639590248.455 [Power24vConverter](INFO): Powering down.
2021-12-15T17:44:27.839Z,1639590267.839 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:44:27.839Z,1639590267.839 [NAL9602] Communications Fault, FailCount= 4
2021-12-15T17:44:27.839Z,1639590267.839 [NAL9602](ERROR): Communications Fault
2021-12-15T17:44:27.868Z,1639590267.868 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:44:28.243Z,1639590268.243 [NAL9602](INFO): Powering down
2021-12-15T17:44:29.095Z,1639590269.095 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:44:29.095Z,1639590269.095 [NAL9602] No Fault, FailCount= 4
2021-12-15T17:44:58.551Z,1639590298.551 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:45:19.149Z,1639590319.149 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:45:19.149Z,1639590319.149 [NAL9602] Communications Fault, FailCount= 5
2021-12-15T17:45:19.149Z,1639590319.149 [NAL9602](ERROR): Communications Fault
2021-12-15T17:45:19.210Z,1639590319.210 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:45:19.211Z,1639590319.211 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2021-12-15T17:45:19.557Z,1639590319.557 [NAL9602](INFO): Powering down
2021-12-15T17:45:19.577Z,1639590319.577 [CBIT](INFO): Critical error at 20211215T174519
2021-12-15T17:45:32.086Z,1639590332.086 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-12-15T17:45:32.086Z,1639590332.086 [Default:CheckIn:C.Wait] Stopped
2021-12-15T17:45:32.086Z,1639590332.086 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-12-15T17:45:32.086Z,1639590332.086 [Default:CheckIn:D] Running Loop=1
2021-12-15T17:45:32.486Z,1639590332.486 [Default:CheckIn:D] Stopped
2021-12-15T17:45:32.486Z,1639590332.486 [Default:CheckIn:E] Running Loop=1
2021-12-15T17:45:32.898Z,1639590332.898 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.208240 min 
2021-12-15T17:45:32.898Z,1639590332.898 [Default:CheckIn:E] Stopped
2021-12-15T17:45:32.898Z,1639590332.898 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-12-15T17:45:32.898Z,1639590332.898 [Default:CheckIn] Stopped
2021-12-15T17:45:32.898Z,1639590332.898 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-12-15T17:45:32.899Z,1639590332.899 [Default:CheckIn](INFO): Running loop #6
2021-12-15T17:45:32.899Z,1639590332.899 [Default:CheckIn] Running Loop=6
2021-12-15T17:45:32.899Z,1639590332.899 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-12-15T17:45:32.899Z,1639590332.899 [Default:CheckIn:Read_GPS] Running Loop=1
2021-12-15T17:45:37.339Z,1639590337.339 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22
2021-12-15T17:45:37.339Z,1639590337.339 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-15T17:45:37.350Z,1639590337.350 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-15T17:45:37.755Z,1639590337.755 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-15T17:45:37.755Z,1639590337.755 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22
2021-12-15T17:46:34.428Z,1639590394.428 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:46:34.428Z,1639590394.428 [DAT] No Fault, FailCount= 8
2021-12-15T17:46:34.739Z,1639590394.739 [DAT](INFO): Powering up
2021-12-15T17:46:34.739Z,1639590394.739 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:46:35.151Z,1639590395.151 [Power24vConverter](INFO): Powering up.
2021-12-15T17:47:19.248Z,1639590439.248 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:47:19.248Z,1639590439.248 [NAL9602] No Fault, FailCount= 5
2021-12-15T17:47:19.627Z,1639590439.627 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:47:34.980Z,1639590454.980 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:47:34.980Z,1639590454.980 [DAT] Communications Fault, FailCount= 1
2021-12-15T17:47:34.980Z,1639590454.980 [DAT](ERROR): Communications Fault
2021-12-15T17:47:34.998Z,1639590454.998 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:47:35.392Z,1639590455.392 [DAT](INFO): Powering down
2021-12-15T17:47:36.226Z,1639590456.226 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:47:36.226Z,1639590456.226 [DAT] No Fault, FailCount= 1
2021-12-15T17:47:38.616Z,1639590458.616 [DAT](INFO): Powering up
2021-12-15T17:47:38.616Z,1639590458.616 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:47:40.227Z,1639590460.227 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:47:40.227Z,1639590460.227 [NAL9602] Communications Fault, FailCount= 1
2021-12-15T17:47:40.227Z,1639590460.227 [NAL9602](ERROR): Communications Fault
2021-12-15T17:47:40.289Z,1639590460.289 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:47:40.638Z,1639590460.638 [NAL9602](INFO): Powering down
2021-12-15T17:47:41.488Z,1639590461.488 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:47:41.489Z,1639590461.489 [NAL9602] No Fault, FailCount= 1
2021-12-15T17:48:10.935Z,1639590490.935 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:48:31.535Z,1639590511.535 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:48:31.535Z,1639590511.535 [NAL9602] Communications Fault, FailCount= 2
2021-12-15T17:48:31.535Z,1639590511.535 [NAL9602](ERROR): Communications Fault
2021-12-15T17:48:31.564Z,1639590511.564 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:48:31.939Z,1639590511.939 [NAL9602](INFO): Powering down
2021-12-15T17:48:32.763Z,1639590512.763 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:48:32.763Z,1639590512.763 [NAL9602] No Fault, FailCount= 2
2021-12-15T17:48:38.416Z,1639590518.416 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 23
2021-12-15T17:48:38.417Z,1639590518.417 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-15T17:48:38.428Z,1639590518.428 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-15T17:48:38.811Z,1639590518.811 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:48:38.811Z,1639590518.811 [DAT] Communications Fault, FailCount= 2
2021-12-15T17:48:38.811Z,1639590518.811 [DAT](ERROR): Communications Fault
2021-12-15T17:48:38.847Z,1639590518.847 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-15T17:48:38.847Z,1639590518.847 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 23
2021-12-15T17:48:38.848Z,1639590518.848 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:48:39.215Z,1639590519.215 [DAT](INFO): Powering down
2021-12-15T17:48:40.037Z,1639590520.037 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:48:40.037Z,1639590520.037 [DAT] No Fault, FailCount= 2
2021-12-15T17:48:42.447Z,1639590522.447 [DAT](INFO): Powering up
2021-12-15T17:48:42.447Z,1639590522.447 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:49:02.243Z,1639590542.243 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:49:22.847Z,1639590562.847 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:49:22.847Z,1639590562.847 [NAL9602] Communications Fault, FailCount= 3
2021-12-15T17:49:22.847Z,1639590562.847 [NAL9602](ERROR): Communications Fault
2021-12-15T17:49:22.889Z,1639590562.889 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:49:23.250Z,1639590563.250 [NAL9602](INFO): Powering down
2021-12-15T17:49:24.086Z,1639590564.086 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:49:24.086Z,1639590564.086 [NAL9602] No Fault, FailCount= 3
2021-12-15T17:49:42.643Z,1639590582.643 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:49:42.643Z,1639590582.643 [DAT] Communications Fault, FailCount= 3
2021-12-15T17:49:42.643Z,1639590582.643 [DAT](ERROR): Communications Fault
2021-12-15T17:49:42.686Z,1639590582.686 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:49:43.047Z,1639590583.047 [DAT](INFO): Powering down
2021-12-15T17:49:43.879Z,1639590583.879 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:49:43.879Z,1639590583.879 [DAT] No Fault, FailCount= 3
2021-12-15T17:49:46.279Z,1639590586.279 [DAT](INFO): Powering up
2021-12-15T17:49:46.279Z,1639590586.279 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:49:53.559Z,1639590593.559 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:50:14.163Z,1639590614.163 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:50:14.163Z,1639590614.163 [NAL9602] Communications Fault, FailCount= 4
2021-12-15T17:50:14.163Z,1639590614.163 [NAL9602](ERROR): Communications Fault
2021-12-15T17:50:14.202Z,1639590614.202 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:50:14.563Z,1639590614.563 [NAL9602](INFO): Powering down
2021-12-15T17:50:15.402Z,1639590615.402 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:50:15.402Z,1639590615.402 [NAL9602] No Fault, FailCount= 4
2021-12-15T17:50:33.155Z,1639590633.155 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-12-15T17:45:32.9Z
2021-12-15T17:50:33.155Z,1639590633.155 [Default:CheckIn:Read_GPS] Stopped
2021-12-15T17:50:33.155Z,1639590633.155 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-12-15T17:50:40.901Z,1639590640.901 [DataOverHttps](INFO): Sending 113 bytes from file Logs/20211215T163904/Courier0019.lzma
2021-12-15T17:50:41.903Z,1639590641.903 [DataOverHttps](INFO): Moved sent file to Logs/20211215T163904/Courier0019.lzma.bak
2021-12-15T17:50:41.903Z,1639590641.903 [DataOverHttps](INFO): SBD MOMSN=16391457
2021-12-15T17:50:44.951Z,1639590644.951 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:50:46.539Z,1639590646.539 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:50:46.539Z,1639590646.539 [DAT] Communications Fault, FailCount= 4
2021-12-15T17:50:46.540Z,1639590646.540 [DAT](ERROR): Communications Fault
2021-12-15T17:50:46.621Z,1639590646.621 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:50:46.939Z,1639590646.939 [DAT](INFO): Powering down
2021-12-15T17:50:47.786Z,1639590647.786 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:50:47.786Z,1639590647.786 [DAT] No Fault, FailCount= 4
2021-12-15T17:50:50.175Z,1639590650.175 [DAT](INFO): Powering up
2021-12-15T17:50:50.175Z,1639590650.175 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:50:57.829Z,1639590657.829 [DataOverHttps](INFO): Sending 359 bytes from file Logs/20211215T163904/Express0020.lzma
2021-12-15T17:50:58.833Z,1639590658.833 [DataOverHttps](INFO): Moved sent file to Logs/20211215T163904/Express0020.lzma.bak
2021-12-15T17:50:58.833Z,1639590658.833 [DataOverHttps](INFO): SBD MOMSN=16391462
2021-12-15T17:50:59.880Z,1639590659.880 [Default:CheckIn:Read_Iridium] Stopped
2021-12-15T17:50:59.880Z,1639590659.880 [Default:CheckIn:C.Wait] Running Loop=1
2021-12-15T17:50:59.880Z,1639590659.880 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-12-15T17:51:05.559Z,1639590665.559 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:51:05.559Z,1639590665.559 [NAL9602] Communications Fault, FailCount= 5
2021-12-15T17:51:05.559Z,1639590665.559 [NAL9602](ERROR): Communications Fault
2021-12-15T17:51:05.681Z,1639590665.681 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:51:05.697Z,1639590665.697 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2021-12-15T17:51:05.971Z,1639590665.971 [NAL9602](INFO): Powering down
2021-12-15T17:51:06.066Z,1639590666.066 [CBIT](INFO): Critical error at 20211215T175105
2021-12-15T17:51:39.530Z,1639590699.530 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 24
2021-12-15T17:51:39.530Z,1639590699.530 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-15T17:51:39.553Z,1639590699.553 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-15T17:51:39.958Z,1639590699.958 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-15T17:51:39.958Z,1639590699.958 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 24
2021-12-15T17:51:50.407Z,1639590710.407 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:51:50.408Z,1639590710.408 [DAT] Communications Fault, FailCount= 5
2021-12-15T17:51:50.408Z,1639590710.408 [DAT](ERROR): Communications Fault
2021-12-15T17:51:50.438Z,1639590710.438 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:51:50.811Z,1639590710.811 [DAT](INFO): Powering down
2021-12-15T17:51:51.636Z,1639590711.636 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:51:51.636Z,1639590711.636 [DAT] No Fault, FailCount= 5
2021-12-15T17:51:54.039Z,1639590714.039 [DAT](INFO): Powering up
2021-12-15T17:51:54.039Z,1639590714.039 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:52:54.242Z,1639590774.242 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2021-12-15T17:52:54.242Z,1639590774.242 [DAT] Communications Fault, FailCount= 6
2021-12-15T17:52:54.242Z,1639590774.242 [DAT](ERROR): Communications Fault
2021-12-15T17:52:54.260Z,1639590774.260 [CBIT](ERROR): Communications Fault in component: DAT
2021-12-15T17:52:54.643Z,1639590774.643 [DAT](INFO): Powering down
2021-12-15T17:52:55.475Z,1639590775.475 [CBIT](INFO): Clearing failed state for component DAT
2021-12-15T17:52:55.475Z,1639590775.475 [DAT] No Fault, FailCount= 6
2021-12-15T17:52:57.875Z,1639590777.875 [DAT](INFO): Powering up
2021-12-15T17:52:57.875Z,1639590777.875 [DAT](DEBUG): Initializing DAT.
2021-12-15T17:53:05.594Z,1639590785.594 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:53:05.594Z,1639590785.594 [NAL9602] No Fault, FailCount= 5
2021-12-15T17:53:05.958Z,1639590785.958 [NAL9602](INFO): Powering up NAL9602
2021-12-15T17:53:26.559Z,1639590806.559 [NAL9602](ERROR): NAL9602 initialization error.
2021-12-15T17:53:26.559Z,1639590806.559 [NAL9602] Communications Fault, FailCount= 1
2021-12-15T17:53:26.559Z,1639590806.559 [NAL9602](ERROR): Communications Fault
2021-12-15T17:53:26.625Z,1639590806.625 [CBIT](ERROR): Communications Fault in component: NAL9602
2021-12-15T17:53:26.974Z,1639590806.974 [NAL9602](INFO): Powering down
2021-12-15T17:53:27.808Z,1639590807.808 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-15T17:53:27.808Z,1639590807.808 [NAL9602] No Fault, FailCount= 1
2021-12-15T17:53:28.754Z,1639590808.754 [DataOverHttps](IMPORTANT): SBD MTMSN=20211215T175327
2021-12-15T17:53:36.149Z,1639590816.149 [DataOverHttps](INFO): Received command: restart sys
2021-12-15T17:53:36.191Z,1639590816.191 [CommandExec](IMPORTANT): got command restart system
2021-12-15T17:53:38.249Z,1639590818.249 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2021-12-15T17:53:38.249Z,1639590818.249 [CommandExec](INFO): Uninitializing the command executive.
2021-12-15T17:53:38.250Z,1639590818.250 [CommandExec](INFO): Uninitializing the command scheduler.
2021-12-15T17:53:38.250Z,1639590818.250 [CommandExec ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:38.361Z,1639590818.361 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-12-15T17:53:38.361Z,1639590818.361 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-12-15T17:53:38.361Z,1639590818.361 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:38.362Z,1639590818.362 [NavChartDb](INFO): Join timeout helper Thread ID is 1155
2021-12-15T17:53:38.401Z,1639590818.401 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-12-15T17:53:38.402Z,1639590818.402 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:38.421Z,1639590818.421 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2021-12-15T17:53:38.421Z,1639590818.421 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:38.422Z,1639590818.422 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1156
2021-12-15T17:53:38.610Z,1639590818.610 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2021-12-15T17:53:38.611Z,1639590818.611 [WetLabsBB2FL](INFO): Powering down
2021-12-15T17:53:38.612Z,1639590818.612 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:38.621Z,1639590818.621 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2021-12-15T17:53:38.621Z,1639590818.621 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:38.622Z,1639590818.622 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1157
2021-12-15T17:53:39.393Z,1639590819.393 [CTD_Seabird](INFO): Powering down
2021-12-15T17:53:39.406Z,1639590819.406 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2021-12-15T17:53:39.406Z,1639590819.406 [CTD_Seabird](INFO): Powering down
2021-12-15T17:53:39.421Z,1639590819.421 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:39.429Z,1639590819.429 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-12-15T17:53:39.429Z,1639590819.429 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:39.430Z,1639590819.430 [Radio_Surface](INFO): Join timeout helper Thread ID is 1158
2021-12-15T17:53:39.577Z,1639590819.577 [Radio_Surface](INFO): Powering down
2021-12-15T17:53:39.578Z,1639590819.578 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-12-15T17:53:39.578Z,1639590819.578 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:39.585Z,1639590819.585 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-12-15T17:53:39.585Z,1639590819.585 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:39.586Z,1639590819.586 [Onboard](INFO): Join timeout helper Thread ID is 1159
2021-12-15T17:53:40.133Z,1639590820.133 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-12-15T17:53:40.134Z,1639590820.134 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:40.158Z,1639590820.158 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2021-12-15T17:53:40.158Z,1639590820.158 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:40.159Z,1639590820.159 [DataOverHttps](INFO): Join timeout helper Thread ID is 1160
2021-12-15T17:53:40.161Z,1639590820.161 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2021-12-15T17:53:40.162Z,1639590820.162 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:40.178Z,1639590820.178 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2021-12-15T17:53:40.178Z,1639590820.178 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:40.178Z,1639590820.178 [BackseatComponent](INFO): Join timeout helper Thread ID is 1161
2021-12-15T17:53:40.274Z,1639590820.274 [BackseatComponent](INFO): Sent LCM shutdown request.
2021-12-15T17:53:40.274Z,1639590820.274 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2021-12-15T17:53:40.275Z,1639590820.275 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:40.278Z,1639590820.278 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-12-15T17:53:40.278Z,1639590820.278 [logger ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:40.278Z,1639590820.278 [logger](INFO): Join timeout helper Thread ID is 1162
2021-12-15T17:53:40.305Z,1639590820.305 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-12-15T17:53:40.305Z,1639590820.305 [logger ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:40.318Z,1639590820.318 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-12-15T17:53:40.318Z,1639590820.318 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:40.318Z,1639590820.318 [CommandLine](INFO): Join timeout helper Thread ID is 1163
2021-12-15T17:53:40.393Z,1639590820.393 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-12-15T17:53:40.394Z,1639590820.394 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:40.402Z,1639590820.402 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2021-12-15T17:53:40.402Z,1639590820.402 [CommandExec ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:40.402Z,1639590820.402 [CommandExec](INFO): Join timeout helper Thread ID is 1164
2021-12-15T17:53:40.403Z,1639590820.403 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-12-15T17:53:40.403Z,1639590820.403 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:40.404Z,1639590820.404 [controlThread](INFO): Join timeout helper Thread ID is 1165
2021-12-15T17:53:40.685Z,1639590820.685 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-12-15T17:53:40.686Z,1639590820.686 [controlThread](DEBUG): Uninitializing ControlThread
2021-12-15T17:53:40.686Z,1639590820.686 [AHRS_M2](INFO): Powering down
2021-12-15T17:53:40.758Z,1639590820.758 [NAL9602](INFO): Powering down
2021-12-15T17:53:40.759Z,1639590820.759 [DAT](INFO): Powering down
2021-12-15T17:53:40.878Z,1639590820.878 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-12-15T17:53:40.879Z,1639590820.879 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-12-15T17:53:40.880Z,1639590820.880 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-12-15T17:53:40.880Z,1639590820.880 [MissionManager](INFO): Uninitializing Mission Default
2021-12-15T17:53:40.880Z,1639590820.880 [Default] Stopped
2021-12-15T17:53:40.880Z,1639590820.880 [Default](DEBUG): Aggregate::uninitialize Default
2021-12-15T17:53:40.880Z,1639590820.880 [Default:B.GoToSurface] Stopped
2021-12-15T17:53:40.881Z,1639590820.881 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-12-15T17:53:40.881Z,1639590820.881 [Default:CheckIn] Stopped
2021-12-15T17:53:40.881Z,1639590820.881 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-12-15T17:53:40.881Z,1639590820.881 [Default:CheckIn:C.Wait] Stopped
2021-12-15T17:53:40.881Z,1639590820.881 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-12-15T17:53:40.884Z,1639590820.884 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-12-15T17:53:40.885Z,1639590820.885 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-12-15T17:53:40.885Z,1639590820.885 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-12-15T17:53:40.885Z,1639590820.885 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-12-15T17:53:40.886Z,1639590820.886 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-12-15T17:53:40.886Z,1639590820.886 [BuoyancyServo](INFO): Powering down
2021-12-15T17:53:40.897Z,1639590820.897 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-12-15T17:53:40.897Z,1639590820.897 [ElevatorServo](INFO): Powering down
2021-12-15T17:53:40.898Z,1639590820.898 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-12-15T17:53:40.898Z,1639590820.898 [MassServo](INFO): Powering down
2021-12-15T17:53:40.899Z,1639590820.899 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-12-15T17:53:40.900Z,1639590820.899 [RudderServo](INFO): Powering down
2021-12-15T17:53:40.900Z,1639590820.900 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2021-12-15T17:53:40.901Z,1639590820.901 [ThrusterHE](INFO): Powering down
2021-12-15T17:53:40.902Z,1639590820.902 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-12-15T17:53:40.902Z,1639590820.902 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-12-15T17:53:40.902Z,1639590820.902 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-12-15T17:53:40.903Z,1639590820.903 [CBIT](DEBUG): Powering off loads.
2021-12-15T17:53:40.917Z,1639590820.917 [CBIT](DEBUG): Disabling WDT.
2021-12-15T17:53:40.929Z,1639590820.929 [CBIT](DEBUG): Opening all GF detection circuits.
2021-12-15T17:53:40.930Z,1639590820.930 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:40.998Z,1639590820.998 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:41.001Z,1639590821.001 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:41.008Z,1639590821.008 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:41.057Z,1639590821.057 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:41.092Z,1639590821.092 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:41.096Z,1639590821.096 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:41.130Z,1639590821.130 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-12-15T17:53:41.196Z,1639590821.196 [logger ThreadHandler](INFO): Thread cancelled.