2021-12-02T18:29:32.641Z,1638469772.641 [Supervisor](DEBUG): Initializing supervisor.
2021-12-02T18:29:32.646Z,1638469772.646 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-12-02T18:29:32.646Z,1638469772.646 [SyncHandler](INFO): Protected caller Thread ID is 4141
2021-12-02T18:29:32.647Z,1638469772.647 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-12-02T18:29:32.648Z,1638469772.648 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-12-02T18:29:32.648Z,1638469772.648 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4142
2021-12-02T18:29:32.652Z,1638469772.652 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-12-02T18:29:32.670Z,1638469772.670 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2021-12-02T18:29:32.671Z,1638469772.671 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-12-02T18:29:32.671Z,1638469772.671 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4143
2021-12-02T18:29:32.676Z,1638469772.676 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-12-02T18:29:32.677Z,1638469772.677 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-12-02T18:29:32.677Z,1638469772.677 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4144
2021-12-02T18:29:32.680Z,1638469772.680 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-12-02T18:29:32.680Z,1638469772.680 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2021-12-02T18:29:32.681Z,1638469772.681 [logger ThreadHandler](INFO): Protected caller Thread ID is 4145
2021-12-02T18:29:32.685Z,1638469772.685 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-12-02T18:29:32.685Z,1638469772.685 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-12-02T18:29:32.687Z,1638469772.687 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-12-02T18:29:32.785Z,1638469772.785 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-12-02T18:29:32.785Z,1638469772.785 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-12-02T18:29:33.377Z,1638469773.377 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-12-02T18:29:33.378Z,1638469773.378 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-12-02T18:29:33.589Z,1638469773.589 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-12-02T18:29:33.589Z,1638469773.589 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-12-02T18:29:33.670Z,1638469773.670 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-12-02T18:29:33.795Z,1638469773.795 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-12-02T18:29:33.796Z,1638469773.796 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-12-02T18:29:33.878Z,1638469773.878 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-12-02T18:29:33.986Z,1638469773.986 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-12-02T18:29:33.986Z,1638469773.986 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-12-02T18:29:34.339Z,1638469774.339 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-12-02T18:29:34.340Z,1638469774.340 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-12-02T18:29:34.864Z,1638469774.864 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-12-02T18:29:34.864Z,1638469774.864 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-12-02T18:29:35.145Z,1638469775.145 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-12-02T18:29:35.146Z,1638469775.146 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-12-02T18:29:35.331Z,1638469775.331 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-12-02T18:29:35.331Z,1638469775.331 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-12-02T18:29:35.779Z,1638469775.779 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-12-02T18:29:35.779Z,1638469775.779 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-12-02T18:29:36.015Z,1638469776.015 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-12-02T18:29:36.015Z,1638469776.015 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-12-02T18:29:36.217Z,1638469776.217 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-12-02T18:29:36.218Z,1638469776.218 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-12-02T18:29:36.567Z,1638469776.567 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-12-02T18:29:36.568Z,1638469776.568 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-12-02T18:29:36.903Z,1638469776.903 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-12-02T18:29:36.905Z,1638469776.905 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2021-12-02T18:29:36.906Z,1638469776.906 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2021-12-02T18:29:36.992Z,1638469776.992 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2021-12-02T18:29:37.144Z,1638469777.144 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2021-12-02T18:29:37.253Z,1638469777.253 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2021-12-02T18:29:37.340Z,1638469777.340 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2021-12-02T18:29:37.436Z,1638469777.436 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2021-12-02T18:29:37.621Z,1638469777.621 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2021-12-02T18:29:37.878Z,1638469777.878 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-12-02T18:29:37.879Z,1638469777.879 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2021-12-02T18:29:37.974Z,1638469777.974 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2021-12-02T18:29:38.073Z,1638469778.073 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2021-12-02T18:29:38.212Z,1638469778.212 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2021-12-02T18:29:38.314Z,1638469778.314 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2021-12-02T18:29:38.314Z,1638469778.314 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-12-02T18:29:38.325Z,1638469778.325 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-12-02T18:29:38.418Z,1638469778.418 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-12-02T18:29:38.419Z,1638469778.419 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-12-02T18:29:38.471Z,1638469778.471 [VerticalControl](DEBUG): Construct VerticalControl.
2021-12-02T18:29:38.532Z,1638469778.532 [VerticalControl] Loaded
2021-12-02T18:29:38.532Z,1638469778.532 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-12-02T18:29:38.535Z,1638469778.535 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-12-02T18:29:38.573Z,1638469778.573 [HorizontalControl] Loaded
2021-12-02T18:29:38.574Z,1638469778.574 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-12-02T18:29:38.576Z,1638469778.576 [SpeedControl](DEBUG): Construct SpeedControl.
2021-12-02T18:29:38.579Z,1638469778.579 [SpeedControl] Loaded
2021-12-02T18:29:38.579Z,1638469778.579 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-12-02T18:29:38.582Z,1638469778.582 [LoopControl](DEBUG): Construct LoopControl.
2021-12-02T18:29:38.582Z,1638469778.582 [LoopControl] Loaded
2021-12-02T18:29:38.583Z,1638469778.583 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-12-02T18:29:38.583Z,1638469778.583 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-12-02T18:29:38.584Z,1638469778.584 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-12-02T18:29:38.625Z,1638469778.625 [DepthRateCalculator] Loaded
2021-12-02T18:29:38.625Z,1638469778.625 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-12-02T18:29:38.630Z,1638469778.630 [PitchRateCalculator] Loaded
2021-12-02T18:29:38.630Z,1638469778.630 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-12-02T18:29:38.640Z,1638469778.640 [SpeedCalculator] Loaded
2021-12-02T18:29:38.640Z,1638469778.640 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-12-02T18:29:38.654Z,1638469778.654 [TempGradientCalculator] Loaded
2021-12-02T18:29:38.654Z,1638469778.654 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-12-02T18:29:38.658Z,1638469778.658 [YawRateCalculator] Loaded
2021-12-02T18:29:38.658Z,1638469778.658 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-12-02T18:29:38.675Z,1638469778.675 [ElevatorOffsetCalculator] Loaded
2021-12-02T18:29:38.676Z,1638469778.676 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-12-02T18:29:38.677Z,1638469778.677 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-12-02T18:29:38.677Z,1638469778.677 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-12-02T18:29:38.704Z,1638469778.704 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-12-02T18:29:38.705Z,1638469778.705 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-12-02T18:29:38.761Z,1638469778.761 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-12-02T18:29:38.762Z,1638469778.762 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-12-02T18:29:39.076Z,1638469779.076 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-12-02T18:29:39.077Z,1638469779.077 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-12-02T18:29:39.178Z,1638469779.178 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-12-02T18:29:39.178Z,1638469779.178 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-12-02T18:29:39.576Z,1638469779.576 [AHRS_M2] Loaded
2021-12-02T18:29:39.577Z,1638469779.577 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-12-02T18:29:39.606Z,1638469779.606 [BackseatComponent] Loaded
2021-12-02T18:29:39.606Z,1638469779.606 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2021-12-02T18:29:39.607Z,1638469779.607 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409474E0
2021-12-02T18:29:39.608Z,1638469779.608 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 4227
2021-12-02T18:29:39.610Z,1638469779.610 [LcmUniversalReporter] Loaded
2021-12-02T18:29:39.611Z,1638469779.611 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2021-12-02T18:29:40.384Z,1638469780.384 [BPC1] Loaded
2021-12-02T18:29:40.384Z,1638469780.384 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-12-02T18:29:40.454Z,1638469780.454 [DataOverHttps] Loaded
2021-12-02T18:29:40.455Z,1638469780.455 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-12-02T18:29:40.456Z,1638469780.456 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409774E0
2021-12-02T18:29:40.456Z,1638469780.456 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4228
2021-12-02T18:29:40.475Z,1638469780.475 [Depth_Keller] Loaded
2021-12-02T18:29:40.476Z,1638469780.476 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-12-02T18:29:40.480Z,1638469780.480 [DropWeight] Loaded
2021-12-02T18:29:40.481Z,1638469780.481 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2021-12-02T18:29:40.539Z,1638469780.539 [NAL9602] Loaded
2021-12-02T18:29:40.539Z,1638469780.539 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-12-02T18:29:40.599Z,1638469780.599 [Onboard] Loaded
2021-12-02T18:29:40.600Z,1638469780.600 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-12-02T18:29:40.601Z,1638469780.601 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409A74E0
2021-12-02T18:29:40.601Z,1638469780.601 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4229
2021-12-02T18:29:40.613Z,1638469780.613 [Power24vConverter] Loaded
2021-12-02T18:29:40.614Z,1638469780.614 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2021-12-02T18:29:40.637Z,1638469780.637 [Radio_Surface] Loaded
2021-12-02T18:29:40.637Z,1638469780.637 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-12-02T18:29:40.638Z,1638469780.638 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409D74E0
2021-12-02T18:29:40.639Z,1638469780.639 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4230
2021-12-02T18:29:40.681Z,1638469780.681 [RDI_Pathfinder] Loaded
2021-12-02T18:29:40.682Z,1638469780.682 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2021-12-02T18:29:40.817Z,1638469780.817 [DAT] Loaded
2021-12-02T18:29:40.817Z,1638469780.817 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2021-12-02T18:29:40.818Z,1638469780.818 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-12-02T18:29:40.818Z,1638469780.818 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-12-02T18:29:40.956Z,1638469780.956 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-12-02T18:29:40.956Z,1638469780.956 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-12-02T18:29:40.969Z,1638469780.969 [NavChart] Loaded
2021-12-02T18:29:40.970Z,1638469780.970 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-12-02T18:29:40.975Z,1638469780.975 [UniversalFixResidualReporter] Loaded
2021-12-02T18:29:40.976Z,1638469780.976 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-12-02T18:29:40.976Z,1638469780.976 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-12-02T18:29:40.977Z,1638469780.977 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-12-02T18:29:41.308Z,1638469781.308 [SBIT](DEBUG): Construct Startup Built In Test.
2021-12-02T18:29:41.318Z,1638469781.318 [SBIT] Loaded
2021-12-02T18:29:41.318Z,1638469781.318 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-12-02T18:29:41.321Z,1638469781.321 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-12-02T18:29:41.334Z,1638469781.334 [IBIT] Loaded
2021-12-02T18:29:41.334Z,1638469781.334 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-12-02T18:29:41.340Z,1638469781.340 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-12-02T18:29:41.577Z,1638469781.577 [CBIT] Loaded
2021-12-02T18:29:41.577Z,1638469781.577 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-12-02T18:29:41.578Z,1638469781.578 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-12-02T18:29:41.578Z,1638469781.578 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-12-02T18:29:41.715Z,1638469781.715 [BuoyancyServo] Loaded
2021-12-02T18:29:41.715Z,1638469781.715 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-12-02T18:29:41.735Z,1638469781.735 [ElevatorServo] Loaded
2021-12-02T18:29:41.735Z,1638469781.735 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-12-02T18:29:41.754Z,1638469781.754 [MassServo] Loaded
2021-12-02T18:29:41.754Z,1638469781.754 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-12-02T18:29:41.772Z,1638469781.772 [RudderServo] Loaded
2021-12-02T18:29:41.773Z,1638469781.773 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-12-02T18:29:41.791Z,1638469781.791 [ThrusterServo] Loaded
2021-12-02T18:29:41.791Z,1638469781.791 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-12-02T18:29:41.792Z,1638469781.792 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-12-02T18:29:41.792Z,1638469781.792 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-12-02T18:29:41.803Z,1638469781.803 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-12-02T18:29:41.803Z,1638469781.803 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-12-02T18:29:41.972Z,1638469781.972 [CTD_Seabird] Loaded
2021-12-02T18:29:41.973Z,1638469781.973 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-12-02T18:29:41.974Z,1638469781.974 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B104E0
2021-12-02T18:29:41.974Z,1638469781.974 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4231
2021-12-02T18:29:41.994Z,1638469781.994 [PAR_Licor] Loaded
2021-12-02T18:29:41.994Z,1638469781.994 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-12-02T18:29:42.026Z,1638469782.026 [WetLabsBB2FL] Loaded
2021-12-02T18:29:42.026Z,1638469782.026 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-12-02T18:29:42.027Z,1638469782.027 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B404E0
2021-12-02T18:29:42.027Z,1638469782.027 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4232
2021-12-02T18:29:42.047Z,1638469782.047 [WetLabsUBAT] Loaded
2021-12-02T18:29:42.048Z,1638469782.048 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2021-12-02T18:29:42.049Z,1638469782.049 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40B704E0
2021-12-02T18:29:42.049Z,1638469782.049 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 4233
2021-12-02T18:29:42.050Z,1638469782.050 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-12-02T18:29:42.056Z,1638469782.056 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-12-02T18:29:42.059Z,1638469782.059 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-12-02T18:29:42.069Z,1638469782.069 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-12-02T18:29:42.070Z,1638469782.070 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BA04E0
2021-12-02T18:29:42.071Z,1638469782.071 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4234
2021-12-02T18:29:42.075Z,1638469782.075 [Supervisor](INFO): Main Thread ID is 3967
2021-12-02T18:29:42.076Z,1638469782.076 [Supervisor](DEBUG): Running supervisor.
2021-12-02T18:29:42.076Z,1638469782.076 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4235
2021-12-02T18:29:42.077Z,1638469782.077 [CommandExec](INFO): Initializing the command executive.
2021-12-02T18:29:42.078Z,1638469782.078 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4236
2021-12-02T18:29:42.081Z,1638469782.081 [controlThread ThreadHandler](INFO): Handler Thread ID is 4237
2021-12-02T18:29:42.082Z,1638469782.082 [controlThread](DEBUG): Initializing ControlThread
2021-12-02T18:29:42.083Z,1638469782.083 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-12-02T18:29:42.085Z,1638469782.085 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-12-02T18:29:42.086Z,1638469782.086 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-12-02T18:29:42.086Z,1638469782.086 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-12-02T18:29:42.086Z,1638469782.086 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-12-02T18:29:42.087Z,1638469782.087 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-12-02T18:29:42.087Z,1638469782.087 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-12-02T18:29:42.088Z,1638469782.088 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-12-02T18:29:42.088Z,1638469782.088 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-12-02T18:29:42.089Z,1638469782.089 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-12-02T18:29:42.095Z,1638469782.095 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-12-02T18:29:42.095Z,1638469782.095 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-12-02T18:29:42.096Z,1638469782.096 [SBIT](INFO): Initialize SBIT Component.
2021-12-02T18:29:42.096Z,1638469782.096 [SBIT](IMPORTANT): git: 2021-11-29
2021-12-02T18:29:42.096Z,1638469782.096 [SBIT](INFO): git hash: f462e1af5d4560b0531ff1e41adede0f51e7b106
2021-12-02T18:29:42.097Z,1638469782.097 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-12-02T18:29:42.098Z,1638469782.098 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2021-12-02T18:29:42.099Z,1638469782.099 [SBIT](INFO): Beginning SBIT in 55.000000 seconds.
2021-12-02T18:29:42.100Z,1638469782.100 [IBIT](INFO): Initialize IBIT Component.
2021-12-02T18:29:42.101Z,1638469782.101 [CBIT](DEBUG): Initialize CBIT Component.
2021-12-02T18:29:42.102Z,1638469782.102 [logger ThreadHandler](INFO): Handler Thread ID is 4238
2021-12-02T18:29:42.112Z,1638469782.112 [CBIT](DEBUG): Initialized mux pins.
2021-12-02T18:29:42.112Z,1638469782.112 [CBIT](DEBUG): Initializing the watchdog timer.
2021-12-02T18:29:42.121Z,1638469782.121 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 4239
2021-12-02T18:29:42.133Z,1638469782.133 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4240
2021-12-02T18:29:42.134Z,1638469782.134 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-12-02T18:29:42.136Z,1638469782.136 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-12-02T18:29:42.137Z,1638469782.137 [CBIT](DEBUG): Initializing heartbeat.
2021-12-02T18:29:42.145Z,1638469782.145 [Onboard ThreadHandler](INFO): Handler Thread ID is 4241
2021-12-02T18:29:42.162Z,1638469782.162 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4242
2021-12-02T18:29:42.193Z,1638469782.193 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4243
2021-12-02T18:29:42.193Z,1638469782.193 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-12-02T18:29:42.198Z,1638469782.198 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4245
2021-12-02T18:29:42.201Z,1638469782.201 [WetLabsBB2FL](INFO): Powering up
2021-12-02T18:29:42.202Z,1638469782.202 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 4247
2021-12-02T18:29:42.205Z,1638469782.205 [WetLabsUBAT](INFO): Powering up
2021-12-02T18:29:42.206Z,1638469782.206 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4249
2021-12-02T18:29:42.209Z,1638469782.209 [CBIT](DEBUG): Deactivating GF circuits.
2021-12-02T18:29:42.209Z,1638469782.209 [CBIT](DEBUG): Deactivating emergency mode.
2021-12-02T18:29:42.210Z,1638469782.210 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-12-02T18:29:42.210Z,1638469782.210 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-12-02T18:29:42.210Z,1638469782.210 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-12-02T18:29:42.211Z,1638469782.211 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-12-02T18:29:42.211Z,1638469782.211 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-12-02T18:29:42.211Z,1638469782.211 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-12-02T18:29:42.211Z,1638469782.211 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-12-02T18:29:42.211Z,1638469782.211 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-12-02T18:29:42.211Z,1638469782.211 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-12-02T18:29:42.212Z,1638469782.212 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-12-02T18:29:42.212Z,1638469782.212 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-12-02T18:29:42.212Z,1638469782.212 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-12-02T18:29:42.212Z,1638469782.212 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-12-02T18:29:42.212Z,1638469782.212 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-12-02T18:29:42.213Z,1638469782.213 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-12-02T18:29:42.213Z,1638469782.213 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-12-02T18:29:42.244Z,1638469782.244 [CBIT](DEBUG): Backplane powered.
2021-12-02T18:29:42.249Z,1638469782.249 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-12-02T18:29:42.278Z,1638469782.278 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-12-02T18:29:42.307Z,1638469782.307 [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-02T18:29:42.307Z,1638469782.307 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-12-02T18:29:42.401Z,1638469782.401 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-12-02T18:29:42.402Z,1638469782.402 [Default:A.Wait](DEBUG): Construct Wait.
2021-12-02T18:29:42.412Z,1638469782.412 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-12-02T18:29:42.440Z,1638469782.440 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-12-02T18:29:42.446Z,1638469782.446 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-12-02T18:29:42.476Z,1638469782.476 [Default:E.Execute](DEBUG): Construct Execute.
2021-12-02T18:29:42.493Z,1638469782.493 [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-02T18:29:42.498Z,1638469782.497 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,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,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2021-12-02T18:29:42.520Z,1638469782.520 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-12-02T18:29:42.568Z,1638469782.568 [Radio_Surface](INFO): Powering up
2021-12-02T18:29:42.609Z,1638469782.609 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2021-12-02T18:29:42.621Z,1638469782.621 [Power24vConverter](INFO): Powering up.
2021-12-02T18:29:42.637Z,1638469782.637 [DAT](INFO): Powering up
2021-12-02T18:29:42.638Z,1638469782.638 [DAT](DEBUG): Initializing DAT.
2021-12-02T18:29:42.689Z,1638469782.689 [DepthRateCalculator](ERROR): Depth measurement is not active
2021-12-02T18:29:42.730Z,1638469782.730 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-12-02T18:29:42.737Z,1638469782.737 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-12-02T18:29:42.738Z,1638469782.738 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-12-02T18:29:42.753Z,1638469782.753 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-12-02T18:29:42.754Z,1638469782.754 [MassServo](DEBUG): Initializing EZServoServo.
2021-12-02T18:29:42.761Z,1638469782.761 [MassServo](DEBUG): Initializing MassServo.
2021-12-02T18:29:42.762Z,1638469782.762 [RudderServo](DEBUG): Initializing EZServoServo.
2021-12-02T18:29:42.769Z,1638469782.769 [RudderServo](DEBUG): Initializing RudderServo.
2021-12-02T18:29:42.769Z,1638469782.769 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-12-02T18:29:42.777Z,1638469782.777 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-12-02T18:29:43.652Z,1638469783.652 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2021-12-02T18:29:43.653Z,1638469783.653 [RudderServo](FAULT): Rudder failed to initialize
2021-12-02T18:29:43.653Z,1638469783.653 [RudderServo] Communications Fault, FailCount= 1
2021-12-02T18:29:43.653Z,1638469783.653 [RudderServo](ERROR): Communications Fault
2021-12-02T18:29:43.753Z,1638469783.753 [CBIT](ERROR): Communications Fault in component: RudderServo
2021-12-02T18:29:43.941Z,1638469783.941 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-12-02T18:29:43.942Z,1638469783.942 [RudderServo](INFO): Powering down
2021-12-02T18:29:44.627Z,1638469784.627 [RudderServo](DEBUG): Initializing EZServoServo.
2021-12-02T18:29:44.745Z,1638469784.745 [RudderServo](DEBUG): Initializing RudderServo.
2021-12-02T18:29:44.750Z,1638469784.750 [CBIT](INFO): Clearing failed state for component RudderServo
2021-12-02T18:29:44.750Z,1638469784.750 [RudderServo] No Fault, FailCount= 1
2021-12-02T18:29:44.812Z,1638469784.812 [WetLabsBB2FL](INFO): Powering down
2021-12-02T18:29:51.464Z,1638469791.464 [WetLabsUBAT](INFO): Powering down
2021-12-02T18:29:56.706Z,1638469796.706 [DAT](INFO): commRate: 800
2021-12-02T18:29:58.719Z,1638469798.719 [DAT](INFO): entering command mode
2021-12-02T18:29:59.175Z,1638469799.175 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:29:59.523Z,1638469799.523 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:29:59.927Z,1638469799.927 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:00.392Z,1638469800.392 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:00.735Z,1638469800.735 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:01.201Z,1638469801.201 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:01.543Z,1638469801.543 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:01.947Z,1638469801.947 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:02.379Z,1638469802.379 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:02.754Z,1638469802.754 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:03.239Z,1638469803.239 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:03.563Z,1638469803.563 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:03.966Z,1638469803.966 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:04.447Z,1638469804.447 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:04.775Z,1638469804.775 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:05.204Z,1638469805.204 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:05.611Z,1638469805.611 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:05.987Z,1638469805.987 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:06.430Z,1638469806.430 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:06.795Z,1638469806.795 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:07.299Z,1638469807.299 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:07.602Z,1638469807.602 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:08.007Z,1638469808.007 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:08.461Z,1638469808.461 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:08.818Z,1638469808.818 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:09.320Z,1638469809.320 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:09.627Z,1638469809.627 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:10.027Z,1638469810.027 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:10.475Z,1638469810.475 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:10.834Z,1638469810.834 [NAL9602](INFO): Powering up NAL9602
2021-12-02T18:30:10.836Z,1638469810.836 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:11.256Z,1638469811.256 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:11.671Z,1638469811.671 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:12.047Z,1638469812.047 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:12.491Z,1638469812.491 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:12.855Z,1638469812.855 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:13.287Z,1638469813.287 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:13.703Z,1638469813.703 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:14.067Z,1638469814.067 [DAT](DEBUG): checking for command mode acknowledgment
2021-12-02T18:30:14.067Z,1638469814.067 [DAT](FAULT): failed to enter command mode
2021-12-02T18:30:14.491Z,1638469814.491 [DAT](INFO): entering command mode
2021-12-02T18:30:14.880Z,1638469814.880 [DAT](INFO): setting verbose to 3
2021-12-02T18:30:15.293Z,1638469815.293 [DAT](INFO): set verbose to 3
2021-12-02T18:30:15.293Z,1638469815.293 [DAT](INFO): setting DatVerbose to 27440
2021-12-02T18:30:15.733Z,1638469815.733 [DAT](INFO): set DatVerbose to 27440
2021-12-02T18:30:15.733Z,1638469815.733 [DAT](INFO): setting transmit power to 8
2021-12-02T18:30:16.088Z,1638469816.088 [DAT](INFO): set transmit power to 8
2021-12-02T18:30:16.088Z,1638469816.088 [DAT](INFO): setting local address to 9
2021-12-02T18:30:16.524Z,1638469816.524 [DAT](INFO): set local address to 9
2021-12-02T18:30:21.742Z,1638469821.742 [NAL9602](INFO): NAL9602 initialized
2021-12-02T18:30:37.525Z,1638469837.525 [SBIT](IMPORTANT): Beginning Startup BIT
2021-12-02T18:30:37.550Z,1638469837.550 [CBIT](IMPORTANT): Beginning ground fault scan
2021-12-02T18:30:48.479Z,1638469848.479 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.010761
CHAN A1 (24V): -0.000479
CHAN A2 (12V): -0.007108
CHAN A3 (5V): -0.001716
CHAN B0 (3.3V): 0.000367
CHAN B1 (3.15aV): 0.000355
CHAN B2 (3.15bV): -0.000716
CHAN B3 (GND): 0.001915
OPEN: 0.005058
Full Scale: +/- 1 mA
2021-12-02T18:31:31.681Z,1638469891.681 [SBIT](IMPORTANT): SBIT PASSED
2021-12-02T18:31:31.682Z,1638469891.682 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-12-02T18:31:31.683Z,1638469891.683 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2021-12-02T18:31:31.683Z,1638469891.683 [SBIT](IMPORTANT): CBIT.gf24Offset=143 microampere;

2021-12-02T18:31:31.683Z,1638469891.683 [SBIT](IMPORTANT): DAT.baud=115200 bit_per_second;

2021-12-02T18:31:31.683Z,1638469891.683 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;

2021-12-02T18:31:31.683Z,1638469891.683 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;

2021-12-02T18:31:31.683Z,1638469891.683 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=24 ampere_hour;

2021-12-02T18:31:31.683Z,1638469891.683 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=13 volt;

2021-12-02T18:31:31.684Z,1638469891.684 [SBIT](IMPORTANT): Power24vConverter.simulateHardware=0 bool;

2021-12-02T18:31:31.684Z,1638469891.684 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=1 bool;

2021-12-02T18:31:31.684Z,1638469891.684 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=231.913961 cubic_centimeter;

2021-12-02T18:31:31.684Z,1638469891.684 [SBIT](IMPORTANT): VerticalControl.massDefault=8.903467 millimeter;

2021-12-02T18:31:32.055Z,1638469892.055 [MissionManager](IMPORTANT): Started mission Startup
2021-12-02T18:31:32.056Z,1638469892.056 [Startup] Running Loop=1
2021-12-02T18:31:32.056Z,1638469892.056 [Startup](DEBUG): Aggregate::initialize Startup
2021-12-02T18:31:32.056Z,1638469892.056 [Startup:A.GoToSurface] Running Loop=1
2021-12-02T18:31:32.056Z,1638469892.056 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-12-02T18:31:32.073Z,1638469892.073 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-12-02T18:31:32.073Z,1638469892.073 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-12-02T18:31:32.074Z,1638469892.074 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-12-02T18:31:32.074Z,1638469892.074 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-12-02T18:31:32.074Z,1638469892.074 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-12-02T18:31:32.076Z,1638469892.076 [Startup:StartupSatComms] Running Loop=1
2021-12-02T18:31:32.076Z,1638469892.076 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-12-02T18:31:32.076Z,1638469892.076 [Startup:StartupSatComms:A] Running Loop=1
2021-12-02T18:31:32.517Z,1638469892.517 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-12-02T18:32:16.893Z,1638469936.893 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2021-12-02T18:32:32.320Z,1638469952.320 [Startup:StartupSatComms:A](INFO): Timed out from 2021-12-02T18:31:32.1Z
2021-12-02T18:32:32.320Z,1638469952.320 [Startup:StartupSatComms:A] Stopped
2021-12-02T18:32:32.324Z,1638469952.324 [Startup:StartupSatComms:B] Running Loop=1
2021-12-02T18:32:32.676Z,1638469952.676 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-12-02T18:32:42.360Z,1638469962.360 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-12-02T18:32:42.360Z,1638469962.360 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-02T18:32:42.371Z,1638469962.371 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-02T18:32:42.781Z,1638469962.781 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-02T18:32:42.781Z,1638469962.781 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-12-02T18:33:05.558Z,1638469985.558 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004417
2021-12-02T18:33:16.440Z,1638469996.440 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20211202T181829/Courier0007.lzma
2021-12-02T18:33:17.442Z,1638469997.442 [DataOverHttps](INFO): Moved sent file to Logs/20211202T181829/Courier0007.lzma.bak
2021-12-02T18:33:17.442Z,1638469997.442 [DataOverHttps](INFO): SBD MOMSN=16323150
2021-12-02T18:33:25.285Z,1638470005.285 [CommandExec](IMPORTANT): got command failComponent
2021-12-02T18:33:25.285Z,1638470005.285 [CommandExec](IMPORTANT): Failed components:
2021-12-02T18:33:25.286Z,1638470005.286 [CommandExec](IMPORTANT): No failed Components.
2021-12-02T18:33:32.563Z,1638470012.563 [Startup:StartupSatComms:B](INFO): Timed out from 2021-12-02T18:32:32.3Z
2021-12-02T18:33:32.563Z,1638470012.563 [Startup:StartupSatComms:B] Stopped
2021-12-02T18:33:32.563Z,1638470012.563 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-12-02T18:33:32.564Z,1638470012.564 [Startup:StartupSatComms] Stopped
2021-12-02T18:33:32.564Z,1638470012.564 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-12-02T18:33:32.569Z,1638470012.569 [Startup](INFO): Completed Startup
2021-12-02T18:33:32.569Z,1638470012.569 [MissionManager](INFO): Startup is completed.
2021-12-02T18:33:32.570Z,1638470012.570 [MissionManager](INFO): Uninitializing Mission Startup
2021-12-02T18:33:32.570Z,1638470012.570 [Startup] Stopped
2021-12-02T18:33:32.570Z,1638470012.570 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-12-02T18:33:32.570Z,1638470012.570 [Startup:A.GoToSurface] Stopped
2021-12-02T18:33:32.570Z,1638470012.570 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-12-02T18:33:32.935Z,1638470012.935 [MissionManager](IMPORTANT): Started mission Default
2021-12-02T18:33:32.935Z,1638470012.935 [Default] Running Loop=1
2021-12-02T18:33:32.935Z,1638470012.935 [Default](DEBUG): Aggregate::initialize Default
2021-12-02T18:33:32.936Z,1638470012.936 [Default:B.GoToSurface] Running Loop=1
2021-12-02T18:33:32.936Z,1638470012.936 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-12-02T18:33:32.940Z,1638470012.940 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-12-02T18:33:32.941Z,1638470012.941 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-12-02T18:33:32.941Z,1638470012.941 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-12-02T18:33:32.941Z,1638470012.941 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-12-02T18:33:32.942Z,1638470012.942 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-12-02T18:33:32.942Z,1638470012.942 [Default:A.Wait] Running Loop=1
2021-12-02T18:33:32.942Z,1638470012.942 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-12-02T18:33:34.096Z,1638470014.096 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20211202T182932/Courier0000.lzma
2021-12-02T18:33:35.098Z,1638470015.098 [DataOverHttps](INFO): Moved sent file to Logs/20211202T182932/Courier0000.lzma.bak
2021-12-02T18:33:35.098Z,1638470015.098 [DataOverHttps](INFO): SBD MOMSN=16323153
2021-12-02T18:33:46.296Z,1638470026.296 [Default:A.Wait](INFO): Done Waiting.
2021-12-02T18:33:46.296Z,1638470026.296 [Default:A.Wait] Stopped
2021-12-02T18:33:46.296Z,1638470026.296 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-12-02T18:33:46.714Z,1638470026.714 [Default:CheckIn] Running Loop=1
2021-12-02T18:33:46.714Z,1638470026.714 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-12-02T18:33:46.714Z,1638470026.714 [Default:CheckIn:Read_GPS] Running Loop=1
2021-12-02T18:33:47.104Z,1638470027.104 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-12-02T18:34:23.222Z,1638470063.222 [CommandExec](IMPORTANT): got command failComponent
2021-12-02T18:34:23.222Z,1638470063.222 [CommandExec](IMPORTANT): Failed components:
2021-12-02T18:34:23.223Z,1638470063.223 [CommandExec](IMPORTANT): No failed Components.
2021-12-02T18:34:39.395Z,1638470079.395 [CommandExec](IMPORTANT): got command report touch platform_buoyancy_position
2021-12-02T18:34:55.562Z,1638470095.562 [DataOverHttps](IMPORTANT): SBD MTMSN=20211202T183454
2021-12-02T18:35:03.141Z,1638470103.141 [DataOverHttps](INFO): Received command: load RegressionTests/IgnitionTests/testDepthVBS.xml;set buoy_test_vbs.MissionTimeout 25 min;set buoy_test_vbs.DepthCmd 7.0 m;run
2021-12-02T18:35:03.224Z,1638470103.224 [CommandExec](IMPORTANT): got command load ./Missions/RegressionTests/IgnitionTests/testDepthVBS.xml
2021-12-02T18:35:03.225Z,1638470103.225 [MissionManager](INFO): Loading Mission: ./Missions/RegressionTests/IgnitionTests/testDepthVBS.xml
2021-12-02T18:35:03.272Z,1638470103.272 [MissionManager](INFO): DefineArg buoy_test_vbs.MissionTimeout = 90.000000 min
2021-12-02T18:35:03.275Z,1638470103.275 [MissionManager](INFO): DefineArg buoy_test_vbs.DepthCmd = 10.000000 m
2021-12-02T18:35:03.279Z,1638470103.279 [MissionManager](INFO): DefineArg buoy_test_vbs.DepthDeadband = 0.100000 m
2021-12-02T18:35:03.282Z,1638470103.282 [MissionManager](INFO): DefineArg buoy_test_vbs.Speed = 0.000000 m/s
2021-12-02T18:35:03.286Z,1638470103.286 [buoy_test_vbs:HoldPitch:A.SetSpeed](DEBUG): Construct.
2021-12-02T18:35:03.289Z,1638470103.289 [buoy_test_vbs:HoldPitch:B.Point](DEBUG): Construct.
2021-12-02T18:35:03.297Z,1638470103.297 [buoy_test_vbs:HoldPitch:C.Mass](DEBUG): Construct.
2021-12-02T18:35:03.341Z,1638470103.341 [buoy_test_vbs:HoldPitch:D.Pitch](DEBUG): Construct.
2021-12-02T18:35:03.347Z,1638470103.347 [buoy_test_vbs:HoldPitch:E.Wait](DEBUG): Construct Wait.
2021-12-02T18:35:03.359Z,1638470103.359 [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="buoy_test_vbs">

    <!-- The following arguments are input arguments -->

    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission
    </Description><Units:minute/><Value>90</Value></DefineArg>

    <DefineArg Name="DepthCmd"><Units:meter/><Value>10.0</Value></DefineArg>

    <DefineArg Name="DepthDeadband"><Units:meter/><Value>0.1</Value></DefineArg>

    <DefineArg Name="Speed"><Units:meter_per_second/><Value>0</Value></DefineArg>


    <!-- The timeout -->

    <Timeout Duration="MissionTimeout"/>

    <!-- And now the mission -->

    <Assign><Parallel/><Control:VerticalControl.depthDeadband/><Arg Name="DepthDeadband"/></Assign>


    <Aggregate Id="HoldPitch">

        <Sequence/>

        <Guidance:SetSpeed>
            <Parallel/>
            <Setting><Guidance:SetSpeed.speed/><Arg Name="Speed"/></Setting>
        </Guidance:SetSpeed>

        <Guidance:Point>
            <Parallel/>
            <Setting><Guidance:Point.rudderAngle/><Units:degree/><Value>0</Value></Setting>
        </Guidance:Point>

        <Guidance:Mass>
            <Parallel/>
            <Setting><Guidance:Mass.position/><Control:VerticalControl.massDefault/></Setting>
        </Guidance:Mass>

        <Guidance:Pitch>
            <Parallel/>
            <Setting><Guidance:Pitch.depth/><Arg Name="DepthCmd"/></Setting>
            <Setting><Guidance:Pitch.elevatorAngle/><Units:degree/><Value>0</Value></Setting>
        </Guidance:Pitch>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Arg Name="MissionTimeout"/></Setting>
        </Guidance:Wait>

    </Aggregate>

</Mission>


2021-12-02T18:35:03.359Z,1638470103.359 [CommandExec](IMPORTANT): Loaded ./Missions/RegressionTests/IgnitionTests/testDepthVBS.xml
2021-12-02T18:35:07.854Z,1638470107.854 [CommandExec](IMPORTANT): got command set buoy_test_vbs.MissionTimeout 25.000000 minute
2021-12-02T18:35:07.857Z,1638470107.857 [CommandExec](IMPORTANT): got command set buoy_test_vbs.DepthCmd 7.000000 meter
2021-12-02T18:35:07.858Z,1638470107.858 [CommandExec](IMPORTANT): got command run
2021-12-02T18:35:07.864Z,1638470107.864 [CommandExec](IMPORTANT): Running 
2021-12-02T18:35:07.985Z,1638470107.985 [Default] Stopped
2021-12-02T18:35:07.985Z,1638470107.985 [Default](DEBUG): Aggregate::uninitialize Default
2021-12-02T18:35:07.986Z,1638470107.986 [Default:B.GoToSurface] Stopped
2021-12-02T18:35:07.986Z,1638470107.986 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-12-02T18:35:07.986Z,1638470107.986 [Default:CheckIn] Stopped
2021-12-02T18:35:07.986Z,1638470107.986 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-12-02T18:35:07.986Z,1638470107.986 [Default:CheckIn:Read_GPS] Stopped
2021-12-02T18:35:07.986Z,1638470107.986 [MissionManager](IMPORTANT): Started mission buoy_test_vbs
2021-12-02T18:35:07.986Z,1638470107.986 [buoy_test_vbs] Running Loop=1
2021-12-02T18:35:07.987Z,1638470107.987 [buoy_test_vbs](DEBUG): Aggregate::initialize buoy_test_vbs
2021-12-02T18:35:07.987Z,1638470107.987 [buoy_test_vbs:A] Running Loop=1
2021-12-02T18:35:07.987Z,1638470107.987 [buoy_test_vbs:HoldPitch] Running Loop=1
2021-12-02T18:35:07.987Z,1638470107.987 [buoy_test_vbs:HoldPitch](DEBUG): Aggregate::initialize buoy_test_vbs:HoldPitch
2021-12-02T18:35:07.987Z,1638470107.987 [buoy_test_vbs:HoldPitch:A.SetSpeed] Running Loop=1
2021-12-02T18:35:07.987Z,1638470107.987 [buoy_test_vbs:HoldPitch:A.SetSpeed](DEBUG): Initialize.
2021-12-02T18:35:07.987Z,1638470107.987 [buoy_test_vbs:HoldPitch:B.Point] Running Loop=1
2021-12-02T18:35:07.987Z,1638470107.987 [buoy_test_vbs:HoldPitch:B.Point](DEBUG): Initialize.
2021-12-02T18:35:07.988Z,1638470107.988 [buoy_test_vbs:HoldPitch:C.Mass] Running Loop=1
2021-12-02T18:35:07.988Z,1638470107.988 [buoy_test_vbs:HoldPitch:C.Mass](DEBUG): Initialize.
2021-12-02T18:35:07.988Z,1638470107.988 [buoy_test_vbs:HoldPitch:D.Pitch] Running Loop=1
2021-12-02T18:35:07.988Z,1638470107.988 [buoy_test_vbs:HoldPitch:D.Pitch](DEBUG): Initialize.
2021-12-02T18:35:07.989Z,1638470107.989 [buoy_test_vbs:HoldPitch:E.Wait] Running Loop=1
2021-12-02T18:35:07.989Z,1638470107.989 [buoy_test_vbs:HoldPitch:E.Wait](DEBUG): Initialize Wait Component.
2021-12-02T18:35:07.989Z,1638470107.989 [buoy_test_vbs:HoldPitch:D.Pitch] Running Loop=1
2021-12-02T18:35:07.990Z,1638470107.990 [buoy_test_vbs:HoldPitch:C.Mass] Running Loop=1
2021-12-02T18:35:07.991Z,1638470107.991 [buoy_test_vbs:HoldPitch:B.Point] Running Loop=1
2021-12-02T18:35:07.991Z,1638470107.991 [buoy_test_vbs:HoldPitch:A.SetSpeed] Running Loop=1
2021-12-02T18:35:07.991Z,1638470107.991 [buoy_test_vbs:A] Running Loop=1
2021-12-02T18:35:13.271Z,1638470113.271 [Reporter](INFO): platform_buoyancy_position 961.560791 cc
2021-12-02T18:35:13.619Z,1638470113.619 [Reporter](INFO): platform_buoyancy_position 962.232300 cc
2021-12-02T18:35:14.079Z,1638470114.079 [Reporter](INFO): platform_buoyancy_position 959.680542 cc
2021-12-02T18:35:14.400Z,1638470114.400 [Reporter](INFO): platform_buoyancy_position 949.473755 cc
2021-12-02T18:35:14.819Z,1638470114.819 [Reporter](INFO): platform_buoyancy_position 947.324951 cc
2021-12-02T18:35:15.235Z,1638470115.235 [Reporter](INFO): platform_buoyancy_position 943.430298 cc
2021-12-02T18:35:15.652Z,1638470115.652 [Reporter](INFO): platform_buoyancy_position 938.998413 cc
2021-12-02T18:35:16.075Z,1638470116.075 [Reporter](INFO): platform_buoyancy_position 934.566528 cc
2021-12-02T18:35:16.459Z,1638470116.459 [Reporter](INFO): platform_buoyancy_position 930.134644 cc
2021-12-02T18:35:16.843Z,1638470116.843 [Reporter](INFO): platform_buoyancy_position 925.031128 cc
2021-12-02T18:35:17.259Z,1638470117.259 [Reporter](INFO): platform_buoyancy_position 919.524902 cc
2021-12-02T18:35:17.635Z,1638470117.635 [Reporter](INFO): platform_buoyancy_position 913.750000 cc
2021-12-02T18:35:18.186Z,1638470118.186 [Reporter](INFO): platform_buoyancy_position 906.632080 cc
2021-12-02T18:35:18.523Z,1638470118.523 [Reporter](INFO): platform_buoyancy_position 902.334473 cc
2021-12-02T18:35:18.899Z,1638470118.899 [Reporter](INFO): platform_buoyancy_position 897.768311 cc
2021-12-02T18:35:19.309Z,1638470119.309 [Reporter](INFO): platform_buoyancy_position 891.456177 cc
2021-12-02T18:35:19.671Z,1638470119.671 [Reporter](INFO): platform_buoyancy_position 887.158569 cc
2021-12-02T18:35:20.135Z,1638470120.135 [Reporter](INFO): platform_buoyancy_position 881.517944 cc
2021-12-02T18:35:20.515Z,1638470120.515 [Reporter](INFO): platform_buoyancy_position 876.548828 cc
2021-12-02T18:35:20.875Z,1638470120.875 [Reporter](INFO): platform_buoyancy_position 872.116943 cc
2021-12-02T18:35:21.295Z,1638470121.295 [Reporter](INFO): platform_buoyancy_position 866.073486 cc
2021-12-02T18:35:21.674Z,1638470121.674 [Reporter](INFO): platform_buoyancy_position 861.372925 cc
2021-12-02T18:35:22.115Z,1638470122.115 [Reporter](INFO): platform_buoyancy_position 856.269531 cc
2021-12-02T18:35:22.555Z,1638470122.555 [Reporter](INFO): platform_buoyancy_position 850.629028 cc
2021-12-02T18:35:22.911Z,1638470122.911 [Reporter](INFO): platform_buoyancy_position 846.331421 cc
2021-12-02T18:35:23.334Z,1638470123.334 [Reporter](INFO): platform_buoyancy_position 839.885010 cc
2021-12-02T18:35:23.696Z,1638470123.696 [Reporter](INFO): platform_buoyancy_position 835.587402 cc
2021-12-02T18:35:24.126Z,1638470124.126 [Reporter](INFO): platform_buoyancy_position 829.275269 cc
2021-12-02T18:35:24.547Z,1638470124.547 [Reporter](INFO): platform_buoyancy_position 824.574829 cc
2021-12-02T18:35:24.854Z,1638470124.854 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-12-02T18:35:24.921Z,1638470124.921 [Reporter](INFO): platform_buoyancy_position 818.531250 cc
2021-12-02T18:35:25.338Z,1638470125.338 [Reporter](INFO): platform_buoyancy_position 814.099365 cc
2021-12-02T18:35:25.753Z,1638470125.753 [Reporter](INFO): platform_buoyancy_position 807.787292 cc
2021-12-02T18:35:26.180Z,1638470126.180 [Reporter](INFO): platform_buoyancy_position 803.221069 cc
2021-12-02T18:35:26.573Z,1638470126.573 [Reporter](INFO): platform_buoyancy_position 797.043274 cc
2021-12-02T18:35:26.943Z,1638470126.943 [Reporter](INFO): platform_buoyancy_position 792.611389 cc
2021-12-02T18:35:27.367Z,1638470127.367 [Reporter](INFO): platform_buoyancy_position 786.970764 cc
2021-12-02T18:35:27.739Z,1638470127.739 [Reporter](INFO): platform_buoyancy_position 782.001648 cc
2021-12-02T18:35:28.165Z,1638470128.165 [Reporter](INFO): platform_buoyancy_position 777.569763 cc
2021-12-02T18:35:28.583Z,1638470128.583 [Reporter](INFO): platform_buoyancy_position 771.123352 cc
2021-12-02T18:35:28.988Z,1638470128.988 [Reporter](INFO): platform_buoyancy_position 766.825745 cc
2021-12-02T18:35:29.405Z,1638470129.405 [Reporter](INFO): platform_buoyancy_position 760.513672 cc
2021-12-02T18:35:29.777Z,1638470129.777 [Reporter](INFO): platform_buoyancy_position 756.081787 cc
2021-12-02T18:35:30.158Z,1638470130.158 [Reporter](INFO): platform_buoyancy_position 751.649841 cc
2021-12-02T18:35:30.611Z,1638470130.611 [Reporter](INFO): platform_buoyancy_position 745.472046 cc
2021-12-02T18:35:30.983Z,1638470130.983 [Reporter](INFO): platform_buoyancy_position 739.965759 cc
2021-12-02T18:35:31.402Z,1638470131.402 [Reporter](INFO): platform_buoyancy_position 734.593750 cc
2021-12-02T18:35:31.919Z,1638470131.919 [Reporter](INFO): platform_buoyancy_position 728.147339 cc
2021-12-02T18:35:32.266Z,1638470132.266 [Reporter](INFO): platform_buoyancy_position 723.581177 cc
2021-12-02T18:35:32.653Z,1638470132.653 [Reporter](INFO): platform_buoyancy_position 717.537659 cc
2021-12-02T18:35:33.022Z,1638470133.022 [Reporter](INFO): platform_buoyancy_position 713.105774 cc
2021-12-02T18:35:33.438Z,1638470133.438 [Reporter](INFO): platform_buoyancy_position 706.659363 cc
2021-12-02T18:35:33.799Z,1638470133.799 [Reporter](INFO): platform_buoyancy_position 702.496094 cc
2021-12-02T18:35:34.215Z,1638470134.215 [Reporter](INFO): platform_buoyancy_position 698.064148 cc
2021-12-02T18:35:34.672Z,1638470134.672 [Reporter](INFO): platform_buoyancy_position 691.483459 cc
2021-12-02T18:35:35.051Z,1638470135.051 [Reporter](INFO): platform_buoyancy_position 685.305664 cc
2021-12-02T18:35:35.483Z,1638470135.483 [Reporter](INFO): platform_buoyancy_position 681.008057 cc
2021-12-02T18:35:35.841Z,1638470135.841 [Reporter](INFO): platform_buoyancy_position 675.904663 cc
2021-12-02T18:35:36.251Z,1638470136.251 [Reporter](INFO): platform_buoyancy_position 670.129761 cc
2021-12-02T18:35:36.706Z,1638470136.706 [Reporter](INFO): platform_buoyancy_position 663.817688 cc
2021-12-02T18:35:37.065Z,1638470137.065 [Reporter](INFO): platform_buoyancy_position 659.520081 cc
2021-12-02T18:35:37.547Z,1638470137.547 [Reporter](INFO): platform_buoyancy_position 653.073669 cc
2021-12-02T18:35:37.870Z,1638470137.870 [Reporter](INFO): platform_buoyancy_position 648.776062 cc
2021-12-02T18:35:38.274Z,1638470138.274 [Reporter](INFO): platform_buoyancy_position 644.344177 cc
2021-12-02T18:35:38.683Z,1638470138.683 [Reporter](INFO): platform_buoyancy_position 638.032043 cc
2021-12-02T18:35:39.063Z,1638470139.063 [Reporter](INFO): platform_buoyancy_position 633.600159 cc
2021-12-02T18:35:39.475Z,1638470139.475 [Reporter](INFO): platform_buoyancy_position 629.302551 cc
2021-12-02T18:35:39.870Z,1638470139.870 [Reporter](INFO): platform_buoyancy_position 622.856140 cc
2021-12-02T18:35:40.267Z,1638470140.267 [Reporter](INFO): platform_buoyancy_position 618.558533 cc
2021-12-02T18:35:40.719Z,1638470140.719 [Reporter](INFO): platform_buoyancy_position 612.112183 cc
2021-12-02T18:35:41.091Z,1638470141.091 [Reporter](INFO): platform_buoyancy_position 607.680237 cc
2021-12-02T18:35:41.519Z,1638470141.519 [Reporter](INFO): platform_buoyancy_position 601.368164 cc
2021-12-02T18:35:41.878Z,1638470141.878 [Reporter](INFO): platform_buoyancy_position 597.070557 cc
2021-12-02T18:35:42.291Z,1638470142.291 [Reporter](INFO): platform_buoyancy_position 590.758484 cc
2021-12-02T18:35:42.714Z,1638470142.714 [Reporter](INFO): platform_buoyancy_position 586.192261 cc
2021-12-02T18:35:43.079Z,1638470143.079 [Reporter](INFO): platform_buoyancy_position 580.685974 cc
2021-12-02T18:35:43.498Z,1638470143.498 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-12-02T18:35:43.498Z,1638470143.498 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-02T18:35:43.538Z,1638470143.538 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-02T18:35:43.539Z,1638470143.539 [Reporter](INFO): platform_buoyancy_position 575.448242 cc
2021-12-02T18:35:43.914Z,1638470143.914 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-02T18:35:43.914Z,1638470143.914 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-12-02T18:35:43.916Z,1638470143.916 [Reporter](INFO): platform_buoyancy_position 569.270447 cc
2021-12-02T18:35:44.338Z,1638470144.338 [Reporter](INFO): platform_buoyancy_position 564.972839 cc
2021-12-02T18:35:44.739Z,1638470144.739 [Reporter](INFO): platform_buoyancy_position 558.660767 cc
2021-12-02T18:35:45.234Z,1638470145.234 [Reporter](INFO): platform_buoyancy_position 552.080078 cc
2021-12-02T18:35:45.603Z,1638470145.603 [Reporter](INFO): platform_buoyancy_position 547.782471 cc
2021-12-02T18:35:45.955Z,1638470145.955 [Reporter](INFO): platform_buoyancy_position 543.350525 cc
2021-12-02T18:35:46.363Z,1638470146.363 [Reporter](INFO): platform_buoyancy_position 537.172729 cc
2021-12-02T18:35:46.779Z,1638470146.779 [Reporter](INFO): platform_buoyancy_position 532.606567 cc
2021-12-02T18:35:47.147Z,1638470147.147 [Reporter](INFO): platform_buoyancy_position 528.174683 cc
2021-12-02T18:35:47.611Z,1638470147.611 [Reporter](INFO): platform_buoyancy_position 521.862549 cc
2021-12-02T18:35:47.931Z,1638470147.931 [Reporter](INFO): platform_buoyancy_position 517.699280 cc
2021-12-02T18:35:48.350Z,1638470148.350 [Reporter](INFO): platform_buoyancy_position 511.252838 cc
2021-12-02T18:35:48.782Z,1638470148.782 [Reporter](INFO): platform_buoyancy_position 506.820923 cc
2021-12-02T18:35:49.146Z,1638470149.146 [Reporter](INFO): platform_buoyancy_position 502.523376 cc
2021-12-02T18:35:49.615Z,1638470149.615 [Reporter](INFO): platform_buoyancy_position 496.211243 cc
2021-12-02T18:35:49.975Z,1638470149.975 [Reporter](INFO): platform_buoyancy_position 489.764862 cc
2021-12-02T18:35:50.382Z,1638470150.382 [Reporter](INFO): platform_buoyancy_position 485.332947 cc
2021-12-02T18:35:50.798Z,1638470150.798 [Reporter](INFO): platform_buoyancy_position 480.766724 cc
2021-12-02T18:35:51.167Z,1638470151.167 [Reporter](INFO): platform_buoyancy_position 474.723267 cc
2021-12-02T18:35:51.646Z,1638470151.646 [Reporter](INFO): platform_buoyancy_position 468.276855 cc
2021-12-02T18:35:51.995Z,1638470151.995 [Reporter](INFO): platform_buoyancy_position 463.844971 cc
2021-12-02T18:35:52.411Z,1638470152.411 [Reporter](INFO): platform_buoyancy_position 459.413025 cc
2021-12-02T18:35:52.835Z,1638470152.835 [Reporter](INFO): platform_buoyancy_position 453.100952 cc
2021-12-02T18:35:53.203Z,1638470153.203 [Reporter](INFO): platform_buoyancy_position 448.669067 cc
2021-12-02T18:35:53.656Z,1638470153.656 [Reporter](INFO): platform_buoyancy_position 442.491272 cc
2021-12-02T18:35:54.031Z,1638470154.031 [Reporter](INFO): platform_buoyancy_position 437.925049 cc
2021-12-02T18:35:54.403Z,1638470154.403 [Reporter](INFO): platform_buoyancy_position 433.761719 cc
2021-12-02T18:35:54.842Z,1638470154.842 [Reporter](INFO): platform_buoyancy_position 425.435120 cc
2021-12-02T18:35:55.227Z,1638470155.227 [Reporter](INFO): platform_buoyancy_position 421.003235 cc
2021-12-02T18:35:55.679Z,1638470155.679 [Reporter](INFO): platform_buoyancy_position 416.571350 cc
2021-12-02T18:35:56.069Z,1638470156.069 [Reporter](INFO): platform_buoyancy_position 410.259216 cc
2021-12-02T18:35:56.431Z,1638470156.431 [Reporter](INFO): platform_buoyancy_position 405.693054 cc
2021-12-02T18:35:56.847Z,1638470156.847 [Reporter](INFO): platform_buoyancy_position 399.515259 cc
2021-12-02T18:35:57.251Z,1638470157.251 [Reporter](INFO): platform_buoyancy_position 395.083374 cc
2021-12-02T18:35:57.702Z,1638470157.702 [Reporter](INFO): platform_buoyancy_position 388.905518 cc
2021-12-02T18:35:58.203Z,1638470158.203 [Reporter](INFO): platform_buoyancy_position 382.190552 cc
2021-12-02T18:35:58.695Z,1638470158.695 [Reporter](INFO): platform_buoyancy_position 375.744141 cc
2021-12-02T18:35:58.975Z,1638470158.975 [Reporter](INFO): platform_buoyancy_position 371.580872 cc
2021-12-02T18:35:59.319Z,1638470159.319 [Reporter](INFO): platform_buoyancy_position 367.417542 cc
2021-12-02T18:35:59.735Z,1638470159.735 [Reporter](INFO): platform_buoyancy_position 362.985657 cc
2021-12-02T18:36:00.122Z,1638470160.122 [Reporter](INFO): platform_buoyancy_position 358.553711 cc
2021-12-02T18:36:00.488Z,1638470160.488 [Reporter](INFO): platform_buoyancy_position 352.241638 cc
2021-12-02T18:36:00.943Z,1638470160.943 [Reporter](INFO): platform_buoyancy_position 347.675415 cc
2021-12-02T18:36:01.275Z,1638470161.275 [Reporter](INFO): platform_buoyancy_position 343.512146 cc
2021-12-02T18:36:01.759Z,1638470161.759 [Reporter](INFO): platform_buoyancy_position 336.931458 cc
2021-12-02T18:36:02.154Z,1638470162.154 [Reporter](INFO): platform_buoyancy_position 330.753662 cc
2021-12-02T18:36:02.543Z,1638470162.543 [Reporter](INFO): platform_buoyancy_position 326.456055 cc
2021-12-02T18:36:02.948Z,1638470162.948 [Reporter](INFO): platform_buoyancy_position 320.009644 cc
2021-12-02T18:36:03.290Z,1638470163.290 [Reporter](INFO): platform_buoyancy_position 315.712036 cc
2021-12-02T18:36:03.749Z,1638470163.749 [Reporter](INFO): platform_buoyancy_position 309.265625 cc
2021-12-02T18:36:04.162Z,1638470164.162 [Reporter](INFO): platform_buoyancy_position 304.833740 cc
2021-12-02T18:36:04.518Z,1638470164.518 [Reporter](INFO): platform_buoyancy_position 300.401825 cc
2021-12-02T18:36:04.977Z,1638470164.977 [Reporter](INFO): platform_buoyancy_position 294.089722 cc
2021-12-02T18:36:05.335Z,1638470165.335 [Reporter](INFO): platform_buoyancy_position 289.657837 cc
2021-12-02T18:36:05.748Z,1638470165.748 [Reporter](INFO): platform_buoyancy_position 283.480042 cc
2021-12-02T18:36:06.191Z,1638470166.191 [Reporter](INFO): platform_buoyancy_position 277.302246 cc
2021-12-02T18:36:06.534Z,1638470166.534 [Reporter](INFO): platform_buoyancy_position 272.736023 cc
2021-12-02T18:36:06.987Z,1638470166.987 [Reporter](INFO): platform_buoyancy_position 268.035522 cc
2021-12-02T18:36:07.380Z,1638470167.380 [Reporter](INFO): platform_buoyancy_position 261.992035 cc
2021-12-02T18:36:07.835Z,1638470167.835 [Reporter](INFO): platform_buoyancy_position 255.545624 cc
2021-12-02T18:36:08.215Z,1638470168.215 [Reporter](INFO): platform_buoyancy_position 251.113724 cc
2021-12-02T18:36:08.570Z,1638470168.570 [Reporter](INFO): platform_buoyancy_position 246.950424 cc
2021-12-02T18:36:08.967Z,1638470168.967 [Reporter](INFO): platform_buoyancy_position 240.504028 cc
2021-12-02T18:36:09.387Z,1638470169.387 [Reporter](INFO): platform_buoyancy_position 236.072144 cc
2021-12-02T18:36:09.824Z,1638470169.824 [Reporter](INFO): platform_buoyancy_position 230.028625 cc
2021-12-02T18:36:10.211Z,1638470170.211 [Reporter](INFO): platform_buoyancy_position 225.462433 cc
2021-12-02T18:36:10.607Z,1638470170.607 [Reporter](INFO): platform_buoyancy_position 219.284637 cc
2021-12-02T18:36:11.036Z,1638470171.036 [Reporter](INFO): platform_buoyancy_position 214.584137 cc
2021-12-02T18:36:11.403Z,1638470171.403 [Reporter](INFO): platform_buoyancy_position 210.286530 cc
2021-12-02T18:36:12.135Z,1638470172.135 [Reporter](INFO): platform_buoyancy_position 199.542542 cc
2021-12-02T18:36:12.372Z,1638470172.372 [Reporter](INFO): platform_buoyancy_position 195.513519 cc
2021-12-02T18:36:12.693Z,1638470172.693 [Reporter](INFO): platform_buoyancy_position 193.096130 cc
2021-12-02T18:36:13.102Z,1638470173.102 [Reporter](INFO): platform_buoyancy_position 186.649719 cc
2021-12-02T18:36:13.467Z,1638470173.467 [Reporter](INFO): platform_buoyancy_position 182.217834 cc
2021-12-02T18:36:13.881Z,1638470173.881 [Reporter](INFO): platform_buoyancy_position 177.785919 cc
2021-12-02T18:36:14.267Z,1638470174.267 [Reporter](INFO): platform_buoyancy_position 171.876740 cc
2021-12-02T18:36:14.635Z,1638470174.635 [Reporter](INFO): platform_buoyancy_position 167.444824 cc
2021-12-02T18:36:15.059Z,1638470175.059 [Reporter](INFO): platform_buoyancy_position 160.864136 cc
2021-12-02T18:36:15.435Z,1638470175.435 [Reporter](INFO): platform_buoyancy_position 156.163635 cc
2021-12-02T18:36:15.850Z,1638470175.850 [Reporter](INFO): platform_buoyancy_position 152.134613 cc
2021-12-02T18:36:16.270Z,1638470176.270 [Reporter](INFO): platform_buoyancy_position 145.822540 cc
2021-12-02T18:36:16.675Z,1638470176.675 [Reporter](INFO): platform_buoyancy_position 139.510437 cc
2021-12-02T18:36:17.092Z,1638470177.092 [Reporter](INFO): platform_buoyancy_position 134.407013 cc
2021-12-02T18:36:17.467Z,1638470177.467 [Reporter](INFO): platform_buoyancy_position 128.766418 cc
2021-12-02T18:36:17.851Z,1638470177.851 [Reporter](INFO): platform_buoyancy_position 124.468819 cc
2021-12-02T18:36:18.303Z,1638470178.303 [Reporter](INFO): platform_buoyancy_position 118.022430 cc
2021-12-02T18:36:18.683Z,1638470178.683 [Reporter](INFO): platform_buoyancy_position 113.724823 cc
2021-12-02T18:36:19.095Z,1638470179.095 [Reporter](INFO): platform_buoyancy_position 107.278412 cc
2021-12-02T18:36:19.511Z,1638470179.511 [Reporter](INFO): platform_buoyancy_position 102.846527 cc
2021-12-02T18:36:19.912Z,1638470179.912 [Reporter](INFO): platform_buoyancy_position 98.548920 cc
2021-12-02T18:36:20.334Z,1638470180.334 [Reporter](INFO): platform_buoyancy_position 92.236816 cc
2021-12-02T18:36:20.706Z,1638470180.706 [Reporter](INFO): platform_buoyancy_position 87.670624 cc
2021-12-02T18:36:21.123Z,1638470181.123 [Reporter](INFO): platform_buoyancy_position 81.492828 cc
2021-12-02T18:36:21.530Z,1638470181.530 [Reporter](INFO): platform_buoyancy_position 77.195221 cc
2021-12-02T18:37:12.861Z,1638470232.861 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-02T18:37:15.873Z,1638470235.873 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.246918
2021-12-02T18:37:19.222Z,1638470239.222 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2021-12-02T18:37:19.245Z,1638470239.245 [BPC1](INFO): Received data from all battery sticks.
2021-12-02T18:37:48.010Z,1638470268.010 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-02T18:38:23.157Z,1638470303.157 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-02T18:38:38.885Z,1638470318.885 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003107
2021-12-02T18:38:44.486Z,1638470324.486 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2021-12-02T18:38:44.486Z,1638470324.486 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-02T18:38:44.500Z,1638470324.500 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-02T18:38:44.910Z,1638470324.910 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-02T18:38:44.910Z,1638470324.910 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2021-12-02T18:40:09.965Z,1638470409.965 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-02T18:40:11.973Z,1638470411.973 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.238640
2021-12-02T18:40:22.626Z,1638470422.626 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-12-02T18:40:22.626Z,1638470422.626 [NAL9602] Data Fault, FailCount= 1
2021-12-02T18:40:22.626Z,1638470422.626 [NAL9602](ERROR): Data Fault
2021-12-02T18:40:22.779Z,1638470422.779 [CBIT](ERROR): Data Fault in component: NAL9602
2021-12-02T18:40:23.034Z,1638470423.034 [NAL9602](INFO): Powering down
2021-12-02T18:40:23.907Z,1638470423.907 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-02T18:40:23.907Z,1638470423.907 [NAL9602] No Fault, FailCount= 1
2021-12-02T18:40:25.665Z,1638470425.665 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003160
2021-12-02T18:40:53.334Z,1638470453.334 [NAL9602](INFO): Powering up NAL9602
2021-12-02T18:41:04.238Z,1638470464.238 [NAL9602](INFO): NAL9602 initialized
2021-12-02T18:41:25.906Z,1638470485.906 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.244503
2021-12-02T18:41:45.490Z,1638470505.490 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2021-12-02T18:41:45.490Z,1638470505.490 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-02T18:41:45.503Z,1638470505.503 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-02T18:41:45.888Z,1638470505.888 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-02T18:41:45.905Z,1638470505.905 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2021-12-02T18:42:11.757Z,1638470531.757 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003098
2021-12-02T18:43:06.977Z,1638470586.977 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-02T18:43:11.997Z,1638470591.997 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.243210
2021-12-02T18:44:46.594Z,1638470686.594 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2021-12-02T18:44:46.594Z,1638470686.594 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-02T18:44:46.607Z,1638470686.607 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-02T18:44:46.959Z,1638470686.959 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-02T18:44:46.960Z,1638470686.960 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2021-12-02T18:44:52.953Z,1638470692.953 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-02T18:45:28.093Z,1638470728.093 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-02T18:46:03.233Z,1638470763.233 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-02T18:46:38.373Z,1638470798.373 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-02T18:47:13.521Z,1638470833.521 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-02T18:47:47.584Z,1638470867.584 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2021-12-02T18:47:47.584Z,1638470867.584 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-02T18:47:47.597Z,1638470867.597 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-02T18:47:47.962Z,1638470867.962 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-02T18:47:47.962Z,1638470867.962 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2021-12-02T18:47:48.661Z,1638470868.661 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-02T18:48:23.825Z,1638470903.825 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-02T18:48:58.965Z,1638470938.965 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-02T18:49:34.125Z,1638470974.125 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-02T18:50:09.282Z,1638471009.282 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-02T18:50:44.909Z,1638471044.909 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2021-12-02T18:50:48.550Z,1638471048.550 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2021-12-02T18:50:48.550Z,1638471048.550 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-02T18:50:48.579Z,1638471048.579 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-02T18:50:48.951Z,1638471048.951 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-02T18:50:48.952Z,1638471048.952 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2021-12-02T18:51:06.686Z,1638471066.686 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-12-02T18:51:06.686Z,1638471066.686 [NAL9602] Data Fault, FailCount= 2
2021-12-02T18:51:06.686Z,1638471066.686 [NAL9602](ERROR): Data Fault
2021-12-02T18:51:06.783Z,1638471066.783 [CBIT](ERROR): Data Fault in component: NAL9602
2021-12-02T18:51:07.090Z,1638471067.090 [NAL9602](INFO): Powering down
2021-12-02T18:51:07.942Z,1638471067.942 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-02T18:51:07.942Z,1638471067.942 [NAL9602] No Fault, FailCount= 2
2021-12-02T18:51:20.057Z,1638471080.057 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-02T18:51:37.390Z,1638471097.390 [NAL9602](INFO): Powering up NAL9602
2021-12-02T18:51:44.713Z,1638471104.713 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003114
2021-12-02T18:51:48.306Z,1638471108.306 [NAL9602](INFO): NAL9602 initialized
2021-12-02T18:53:06.825Z,1638471186.825 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-02T18:53:11.845Z,1638471191.845 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.251492
2021-12-02T18:53:49.594Z,1638471229.594 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2021-12-02T18:53:49.594Z,1638471229.594 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-02T18:53:49.619Z,1638471229.619 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-02T18:53:50.024Z,1638471230.024 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-02T18:53:50.024Z,1638471230.024 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2021-12-02T18:54:17.121Z,1638471257.121 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-02T18:54:52.261Z,1638471292.261 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-02T18:55:27.409Z,1638471327.409 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-02T18:56:02.565Z,1638471362.565 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-02T18:56:16.145Z,1638471376.145 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003057
2021-12-02T18:56:50.563Z,1638471410.563 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2021-12-02T18:56:50.563Z,1638471410.563 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-02T18:56:50.605Z,1638471410.605 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-02T18:56:51.017Z,1638471411.017 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-02T18:56:51.017Z,1638471411.017 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2021-12-02T18:57:13.373Z,1638471433.373 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-02T18:57:16.385Z,1638471436.385 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.243206
2021-12-02T18:57:48.541Z,1638471468.541 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-02T18:58:23.681Z,1638471503.681 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-02T18:58:57.357Z,1638471537.357 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003394
2021-12-02T18:59:51.633Z,1638471591.633 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2021-12-02T18:59:51.633Z,1638471591.633 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-02T18:59:51.663Z,1638471591.663 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-02T18:59:51.974Z,1638471591.974 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-02T18:59:51.974Z,1638471591.974 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2021-12-02T19:00:08.177Z,1638471608.177 [buoy_test_vbs](INFO): Timed out from 2021-12-02T18:35:07.0Z
2021-12-02T19:00:08.178Z,1638471608.178 [MissionManager](INFO): buoy_test_vbs is completed.
2021-12-02T19:00:08.178Z,1638471608.178 [MissionManager](INFO): Uninitializing Mission buoy_test_vbs
2021-12-02T19:00:08.178Z,1638471608.178 [buoy_test_vbs] Stopped
2021-12-02T19:00:08.178Z,1638471608.178 [buoy_test_vbs](DEBUG): Aggregate::uninitialize buoy_test_vbs
2021-12-02T19:00:08.178Z,1638471608.178 [buoy_test_vbs:A] Stopped
2021-12-02T19:00:08.178Z,1638471608.178 [buoy_test_vbs:HoldPitch] Stopped
2021-12-02T19:00:08.178Z,1638471608.178 [buoy_test_vbs:HoldPitch](DEBUG): Aggregate::uninitialize buoy_test_vbs:HoldPitch
2021-12-02T19:00:08.178Z,1638471608.178 [buoy_test_vbs:HoldPitch:A.SetSpeed] Stopped
2021-12-02T19:00:08.178Z,1638471608.178 [buoy_test_vbs:HoldPitch:A.SetSpeed](DEBUG): Uninitialize.
2021-12-02T19:00:08.179Z,1638471608.179 [buoy_test_vbs:HoldPitch:B.Point] Stopped
2021-12-02T19:00:08.179Z,1638471608.179 [buoy_test_vbs:HoldPitch:C.Mass] Stopped
2021-12-02T19:00:08.179Z,1638471608.179 [buoy_test_vbs:HoldPitch:C.Mass](DEBUG): Uninitialize.
2021-12-02T19:00:08.179Z,1638471608.179 [buoy_test_vbs:HoldPitch:D.Pitch] Stopped
2021-12-02T19:00:08.179Z,1638471608.179 [buoy_test_vbs:HoldPitch:E.Wait] Stopped
2021-12-02T19:00:08.179Z,1638471608.179 [buoy_test_vbs:HoldPitch:E.Wait](DEBUG): Uninitialize Wait Component.
2021-12-02T19:00:08.528Z,1638471608.528 [MissionManager](IMPORTANT): Started mission Default
2021-12-02T19:00:08.528Z,1638471608.528 [Default] Running Loop=1
2021-12-02T19:00:08.528Z,1638471608.528 [Default](DEBUG): Aggregate::initialize Default
2021-12-02T19:00:08.529Z,1638471608.529 [Default:B.GoToSurface] Running Loop=1
2021-12-02T19:00:08.529Z,1638471608.529 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-12-02T19:00:08.529Z,1638471608.529 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-12-02T19:00:08.529Z,1638471608.529 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-12-02T19:00:08.530Z,1638471608.530 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-12-02T19:00:08.530Z,1638471608.530 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-12-02T19:00:08.530Z,1638471608.530 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-12-02T19:00:08.531Z,1638471608.531 [Default:A.Wait] Running Loop=1
2021-12-02T19:00:08.531Z,1638471608.531 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-12-02T19:00:09.752Z,1638471609.752 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -20.00 mm (1 active estimators).
2021-12-02T19:00:10.260Z,1638471610.260 [Reporter](INFO): platform_buoyancy_position 64.436707 cc
2021-12-02T19:00:10.778Z,1638471610.778 [Reporter](INFO): platform_buoyancy_position 64.302429 cc
2021-12-02T19:00:11.144Z,1638471611.144 [Reporter](INFO): platform_buoyancy_position 65.108215 cc
2021-12-02T19:00:11.574Z,1638471611.574 [Reporter](INFO): platform_buoyancy_position 66.316925 cc
2021-12-02T19:00:11.941Z,1638471611.941 [Reporter](INFO): platform_buoyancy_position 72.897614 cc
2021-12-02T19:00:12.257Z,1638471612.257 [Reporter](INFO): platform_buoyancy_position 77.060913 cc
2021-12-02T19:00:12.706Z,1638471612.706 [Reporter](INFO): platform_buoyancy_position 83.507324 cc
2021-12-02T19:00:13.149Z,1638471613.149 [Reporter](INFO): platform_buoyancy_position 88.073517 cc
2021-12-02T19:00:13.530Z,1638471613.530 [Reporter](INFO): platform_buoyancy_position 94.385620 cc
2021-12-02T19:00:14.065Z,1638471614.065 [Reporter](INFO): platform_buoyancy_position 100.697723 cc
2021-12-02T19:00:14.325Z,1638471614.325 [Reporter](INFO): platform_buoyancy_position 105.263916 cc
2021-12-02T19:00:14.686Z,1638471614.686 [Reporter](INFO): platform_buoyancy_position 109.427216 cc
2021-12-02T19:00:15.117Z,1638471615.117 [Reporter](INFO): platform_buoyancy_position 115.739319 cc
2021-12-02T19:00:15.497Z,1638471615.497 [Reporter](INFO): platform_buoyancy_position 120.305511 cc
2021-12-02T19:00:15.953Z,1638471615.953 [Reporter](INFO): platform_buoyancy_position 126.483337 cc
2021-12-02T19:00:16.361Z,1638471616.361 [Reporter](INFO): platform_buoyancy_position 132.929718 cc
2021-12-02T19:00:16.725Z,1638471616.725 [Reporter](INFO): platform_buoyancy_position 137.361633 cc
2021-12-02T19:00:17.142Z,1638471617.142 [Reporter](INFO): platform_buoyancy_position 141.793518 cc
2021-12-02T19:00:17.509Z,1638471617.509 [Reporter](INFO): platform_buoyancy_position 148.105621 cc
2021-12-02T19:00:17.978Z,1638471617.978 [Reporter](INFO): platform_buoyancy_position 154.552032 cc
2021-12-02T19:00:18.345Z,1638471618.345 [Reporter](INFO): platform_buoyancy_position 158.715332 cc
2021-12-02T19:00:18.744Z,1638471618.744 [Reporter](INFO): platform_buoyancy_position 163.147217 cc
2021-12-02T19:00:19.229Z,1638471619.229 [Reporter](INFO): platform_buoyancy_position 169.593628 cc
2021-12-02T19:00:19.578Z,1638471619.578 [Reporter](INFO): platform_buoyancy_position 173.756927 cc
2021-12-02T19:00:20.069Z,1638471620.069 [Reporter](INFO): platform_buoyancy_position 180.337616 cc
2021-12-02T19:00:20.493Z,1638471620.493 [Reporter](INFO): platform_buoyancy_position 184.635223 cc
2021-12-02T19:00:20.738Z,1638471620.738 [Reporter](INFO): platform_buoyancy_position 188.932831 cc
2021-12-02T19:00:21.161Z,1638471621.161 [Reporter](INFO): platform_buoyancy_position 195.110626 cc
2021-12-02T19:00:21.558Z,1638471621.558 [Reporter](INFO): platform_buoyancy_position 199.676819 cc
2021-12-02T19:00:21.895Z,1638471621.895 [Default:A.Wait](INFO): Done Waiting.
2021-12-02T19:00:21.895Z,1638471621.895 [Default:A.Wait] Stopped
2021-12-02T19:00:21.895Z,1638471621.895 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-12-02T19:00:22.106Z,1638471622.106 [Reporter](INFO): platform_buoyancy_position 206.257538 cc
2021-12-02T19:00:22.434Z,1638471622.434 [Reporter](INFO): platform_buoyancy_position 211.898132 cc
2021-12-02T19:00:22.833Z,1638471622.833 [Reporter](INFO): platform_buoyancy_position 216.867218 cc
2021-12-02T19:00:23.286Z,1638471623.286 [Reporter](INFO): platform_buoyancy_position 221.433441 cc
2021-12-02T19:00:23.614Z,1638471623.614 [Reporter](INFO): platform_buoyancy_position 227.611237 cc
2021-12-02T19:00:24.042Z,1638471624.042 [Reporter](INFO): platform_buoyancy_position 231.908844 cc
2021-12-02T19:00:24.450Z,1638471624.450 [Reporter](INFO): platform_buoyancy_position 238.355225 cc
2021-12-02T19:00:24.861Z,1638471624.861 [Reporter](INFO): platform_buoyancy_position 242.518524 cc
2021-12-02T19:00:25.250Z,1638471625.250 [Reporter](INFO): platform_buoyancy_position 248.562042 cc
2021-12-02T19:00:25.682Z,1638471625.682 [Reporter](INFO): platform_buoyancy_position 253.262558 cc
2021-12-02T19:00:26.097Z,1638471626.097 [Reporter](INFO): platform_buoyancy_position 259.708923 cc
2021-12-02T19:00:26.319Z,1638471626.319 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range.
2021-12-02T19:00:26.490Z,1638471626.490 [Reporter](INFO): platform_buoyancy_position 264.006531 cc
2021-12-02T19:00:26.838Z,1638471626.838 [Reporter](INFO): platform_buoyancy_position 268.438446 cc
2021-12-02T19:00:27.361Z,1638471627.361 [Reporter](INFO): platform_buoyancy_position 274.884827 cc
2021-12-02T19:00:27.610Z,1638471627.610 [Reporter](INFO): platform_buoyancy_position 279.182434 cc
2021-12-02T19:00:28.041Z,1638471628.041 [Reporter](INFO): platform_buoyancy_position 285.494537 cc
2021-12-02T19:00:28.457Z,1638471628.457 [Reporter](INFO): platform_buoyancy_position 289.926422 cc
2021-12-02T19:00:28.934Z,1638471628.934 [Reporter](INFO): platform_buoyancy_position 296.238525 cc
2021-12-02T19:00:29.310Z,1638471629.310 [Reporter](INFO): platform_buoyancy_position 301.476227 cc
2021-12-02T19:00:29.705Z,1638471629.705 [Reporter](INFO): platform_buoyancy_position 306.848236 cc
2021-12-02T19:00:30.114Z,1638471630.114 [Reporter](INFO): platform_buoyancy_position 312.623108 cc
2021-12-02T19:00:30.514Z,1638471630.514 [Reporter](INFO): platform_buoyancy_position 317.726562 cc
2021-12-02T19:00:30.974Z,1638471630.974 [Reporter](INFO): platform_buoyancy_position 322.024109 cc
2021-12-02T19:00:31.282Z,1638471631.282 [Reporter](INFO): platform_buoyancy_position 327.396118 cc
2021-12-02T19:00:31.569Z,1638471631.569 [RDI_Pathfinder](ERROR): DVL BIT error. See manual. Result code: 61
2021-12-02T19:00:31.686Z,1638471631.686 [Reporter](INFO): platform_buoyancy_position 332.902466 cc
2021-12-02T19:00:32.083Z,1638471632.083 [Reporter](INFO): platform_buoyancy_position 337.334351 cc
2021-12-02T19:00:32.500Z,1638471632.500 [Reporter](INFO): platform_buoyancy_position 343.512146 cc
2021-12-02T19:00:32.929Z,1638471632.929 [Reporter](INFO): platform_buoyancy_position 347.944031 cc
2021-12-02T19:00:33.394Z,1638471633.394 [Reporter](INFO): platform_buoyancy_position 354.390442 cc
2021-12-02T19:00:33.841Z,1638471633.841 [Reporter](INFO): platform_buoyancy_position 358.553711 cc
2021-12-02T19:00:34.077Z,1638471634.077 [Reporter](INFO): platform_buoyancy_position 364.060059 cc
2021-12-02T19:00:34.501Z,1638471634.501 [Reporter](INFO): platform_buoyancy_position 369.432068 cc
2021-12-02T19:00:34.913Z,1638471634.913 [Reporter](INFO): platform_buoyancy_position 374.266846 cc
2021-12-02T19:00:35.377Z,1638471635.377 [Reporter](INFO): platform_buoyancy_position 381.519043 cc
2021-12-02T19:00:35.749Z,1638471635.749 [Reporter](INFO): platform_buoyancy_position 386.488159 cc
2021-12-02T19:00:36.133Z,1638471636.133 [Reporter](INFO): platform_buoyancy_position 390.785767 cc
2021-12-02T19:00:36.622Z,1638471636.622 [Reporter](INFO): platform_buoyancy_position 396.157715 cc
2021-12-02T19:00:36.902Z,1638471636.902 [Reporter](INFO): platform_buoyancy_position 401.529724 cc
2021-12-02T19:00:37.349Z,1638471637.349 [Reporter](INFO): platform_buoyancy_position 408.110474 cc
2021-12-02T19:00:37.786Z,1638471637.786 [Reporter](INFO): platform_buoyancy_position 412.408020 cc
2021-12-02T19:00:38.133Z,1638471638.133 [Reporter](INFO): platform_buoyancy_position 416.571350 cc
2021-12-02T19:00:38.609Z,1638471638.609 [Reporter](INFO): platform_buoyancy_position 423.152039 cc
2021-12-02T19:00:38.973Z,1638471638.973 [Reporter](INFO): platform_buoyancy_position 429.598450 cc
2021-12-02T19:00:39.253Z,1638471639.253 [Default:CheckIn] Running Loop=1
2021-12-02T19:00:39.253Z,1638471639.253 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-12-02T19:00:39.253Z,1638471639.253 [Default:CheckIn:Read_GPS] Running Loop=1
2021-12-02T19:00:39.530Z,1638471639.530 [Reporter](INFO): platform_buoyancy_position 433.896057 cc
2021-12-02T19:00:39.863Z,1638471639.863 [Reporter](INFO): platform_buoyancy_position 438.059326 cc
2021-12-02T19:00:40.148Z,1638471640.148 [Reporter](INFO): platform_buoyancy_position 444.371460 cc
2021-12-02T19:00:40.560Z,1638471640.560 [Reporter](INFO): platform_buoyancy_position 448.803345 cc
2021-12-02T19:00:41.014Z,1638471641.014 [Reporter](INFO): platform_buoyancy_position 455.249756 cc
2021-12-02T19:00:41.407Z,1638471641.407 [Reporter](INFO): platform_buoyancy_position 459.815918 cc
2021-12-02T19:00:41.762Z,1638471641.762 [Reporter](INFO): platform_buoyancy_position 465.993774 cc
2021-12-02T19:00:42.172Z,1638471642.172 [Reporter](INFO): platform_buoyancy_position 470.291321 cc
2021-12-02T19:00:42.554Z,1638471642.554 [Reporter](INFO): platform_buoyancy_position 474.857544 cc
2021-12-02T19:00:43.010Z,1638471643.010 [Reporter](INFO): platform_buoyancy_position 481.035339 cc
2021-12-02T19:00:43.468Z,1638471643.468 [Reporter](INFO): platform_buoyancy_position 487.481750 cc
2021-12-02T19:00:43.903Z,1638471643.903 [Reporter](INFO): platform_buoyancy_position 491.913635 cc
2021-12-02T19:00:44.174Z,1638471644.174 [Reporter](INFO): platform_buoyancy_position 496.211243 cc
2021-12-02T19:00:44.566Z,1638471644.566 [Reporter](INFO): platform_buoyancy_position 500.643097 cc
2021-12-02T19:00:44.958Z,1638471644.958 [Reporter](INFO): platform_buoyancy_position 506.820923 cc
2021-12-02T19:00:45.386Z,1638471645.386 [Reporter](INFO): platform_buoyancy_position 513.401672 cc
2021-12-02T19:00:45.762Z,1638471645.762 [Reporter](INFO): platform_buoyancy_position 517.699280 cc
2021-12-02T19:00:46.144Z,1638471646.144 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-02T19:00:46.178Z,1638471646.178 [Reporter](INFO): platform_buoyancy_position 524.145630 cc
2021-12-02T19:00:46.594Z,1638471646.594 [Reporter](INFO): platform_buoyancy_position 528.443237 cc
2021-12-02T19:00:47.006Z,1638471647.006 [Reporter](INFO): platform_buoyancy_position 534.755371 cc
2021-12-02T19:00:47.426Z,1638471647.426 [Reporter](INFO): platform_buoyancy_position 539.187256 cc
2021-12-02T19:00:47.786Z,1638471647.786 [Reporter](INFO): platform_buoyancy_position 543.753479 cc
2021-12-02T19:00:48.154Z,1638471648.154 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.248413
2021-12-02T19:00:48.247Z,1638471648.247 [Reporter](INFO): platform_buoyancy_position 549.931274 cc
2021-12-02T19:00:48.628Z,1638471648.628 [Reporter](INFO): platform_buoyancy_position 555.168945 cc
2021-12-02T19:00:48.998Z,1638471648.998 [Reporter](INFO): platform_buoyancy_position 560.540955 cc
2021-12-02T19:00:49.459Z,1638471649.459 [Reporter](INFO): platform_buoyancy_position 566.987366 cc
2021-12-02T19:00:49.818Z,1638471649.818 [Reporter](INFO): platform_buoyancy_position 571.284973 cc
2021-12-02T19:00:50.262Z,1638471650.262 [Reporter](INFO): platform_buoyancy_position 577.597046 cc
2021-12-02T19:00:50.655Z,1638471650.655 [Reporter](INFO): platform_buoyancy_position 582.028931 cc
2021-12-02T19:00:51.006Z,1638471651.006 [Reporter](INFO): platform_buoyancy_position 586.326538 cc
2021-12-02T19:00:51.462Z,1638471651.462 [Reporter](INFO): platform_buoyancy_position 592.907288 cc
2021-12-02T19:00:51.847Z,1638471651.847 [Reporter](INFO): platform_buoyancy_position 597.204834 cc
2021-12-02T19:00:52.294Z,1638471652.294 [Reporter](INFO): platform_buoyancy_position 603.516968 cc
2021-12-02T19:00:52.690Z,1638471652.690 [Reporter](INFO): platform_buoyancy_position 608.351746 cc
2021-12-02T19:00:53.054Z,1638471653.054 [Reporter](INFO): platform_buoyancy_position 614.260986 cc
2021-12-02T19:00:53.470Z,1638471653.470 [Reporter](INFO): platform_buoyancy_position 618.692871 cc
2021-12-02T19:00:53.846Z,1638471653.846 [Reporter](INFO): platform_buoyancy_position 625.004944 cc
2021-12-02T19:00:54.290Z,1638471654.290 [Reporter](INFO): platform_buoyancy_position 629.436890 cc
2021-12-02T19:00:54.678Z,1638471654.678 [Reporter](INFO): platform_buoyancy_position 633.734436 cc
2021-12-02T19:00:55.062Z,1638471655.062 [Reporter](INFO): platform_buoyancy_position 640.180847 cc
2021-12-02T19:00:55.498Z,1638471655.498 [Reporter](INFO): platform_buoyancy_position 646.492981 cc
2021-12-02T19:00:55.898Z,1638471655.898 [Reporter](INFO): platform_buoyancy_position 650.924866 cc
2021-12-02T19:00:56.342Z,1638471656.342 [Reporter](INFO): platform_buoyancy_position 657.236938 cc
2021-12-02T19:00:56.694Z,1638471656.694 [Reporter](INFO): platform_buoyancy_position 661.534546 cc
2021-12-02T19:00:57.094Z,1638471657.094 [Reporter](INFO): platform_buoyancy_position 665.966492 cc
2021-12-02T19:00:57.535Z,1638471657.535 [Reporter](INFO): platform_buoyancy_position 672.412842 cc
2021-12-02T19:00:57.886Z,1638471657.886 [Reporter](INFO): platform_buoyancy_position 676.710449 cc
2021-12-02T19:00:58.346Z,1638471658.346 [Reporter](INFO): platform_buoyancy_position 683.022583 cc
2021-12-02T19:00:58.727Z,1638471658.727 [Reporter](INFO): platform_buoyancy_position 687.723083 cc
2021-12-02T19:00:59.126Z,1638471659.126 [Reporter](INFO): platform_buoyancy_position 693.900879 cc
2021-12-02T19:00:59.522Z,1638471659.522 [Reporter](INFO): platform_buoyancy_position 698.198486 cc
2021-12-02T19:00:59.906Z,1638471659.906 [Reporter](INFO): platform_buoyancy_position 704.376282 cc
2021-12-02T19:01:00.339Z,1638471660.339 [Reporter](INFO): platform_buoyancy_position 708.942444 cc
2021-12-02T19:01:00.750Z,1638471660.750 [Reporter](INFO): platform_buoyancy_position 715.254578 cc
2021-12-02T19:01:01.118Z,1638471661.118 [Reporter](INFO): platform_buoyancy_position 719.686462 cc
2021-12-02T19:01:01.570Z,1638471661.570 [Reporter](INFO): platform_buoyancy_position 725.998596 cc
2021-12-02T19:01:01.950Z,1638471661.950 [Reporter](INFO): platform_buoyancy_position 730.430481 cc
2021-12-02T19:01:02.374Z,1638471662.374 [Reporter](INFO): platform_buoyancy_position 735.399597 cc
2021-12-02T19:01:02.814Z,1638471662.814 [Reporter](INFO): platform_buoyancy_position 741.174500 cc
2021-12-02T19:01:03.134Z,1638471663.134 [Reporter](INFO): platform_buoyancy_position 745.337769 cc
2021-12-02T19:01:03.570Z,1638471663.570 [Reporter](INFO): platform_buoyancy_position 751.918457 cc
2021-12-02T19:01:03.946Z,1638471663.946 [Reporter](INFO): platform_buoyancy_position 756.216064 cc
2021-12-02T19:01:04.397Z,1638471664.397 [Reporter](INFO): platform_buoyancy_position 762.528198 cc
2021-12-02T19:01:04.818Z,1638471664.818 [Reporter](INFO): platform_buoyancy_position 766.960083 cc
2021-12-02T19:01:05.186Z,1638471665.186 [Reporter](INFO): platform_buoyancy_position 771.526245 cc
2021-12-02T19:01:05.602Z,1638471665.602 [Reporter](INFO): platform_buoyancy_position 777.569763 cc
2021-12-02T19:01:05.971Z,1638471665.971 [Reporter](INFO): platform_buoyancy_position 782.001648 cc
2021-12-02T19:01:06.384Z,1638471666.384 [Reporter](INFO): platform_buoyancy_position 788.448059 cc
2021-12-02T19:01:06.802Z,1638471666.802 [Reporter](INFO): platform_buoyancy_position 793.282898 cc
2021-12-02T19:01:07.170Z,1638471667.170 [Reporter](INFO): platform_buoyancy_position 799.057800 cc
2021-12-02T19:01:07.634Z,1638471667.634 [Reporter](INFO): platform_buoyancy_position 805.504150 cc
2021-12-02T19:01:08.002Z,1638471668.002 [Reporter](INFO): platform_buoyancy_position 809.801758 cc
2021-12-02T19:01:08.431Z,1638471668.431 [Reporter](INFO): platform_buoyancy_position 814.367981 cc
2021-12-02T19:01:08.938Z,1638471668.938 [Reporter](INFO): platform_buoyancy_position 820.680054 cc
2021-12-02T19:01:09.174Z,1638471669.174 [Reporter](INFO): platform_buoyancy_position 824.977661 cc
2021-12-02T19:01:09.615Z,1638471669.615 [Reporter](INFO): platform_buoyancy_position 831.424072 cc
2021-12-02T19:01:10.001Z,1638471670.001 [Reporter](INFO): platform_buoyancy_position 835.721680 cc
2021-12-02T19:01:10.425Z,1638471670.425 [Reporter](INFO): platform_buoyancy_position 842.033813 cc
2021-12-02T19:01:10.843Z,1638471670.843 [Reporter](INFO): platform_buoyancy_position 848.211548 cc
2021-12-02T19:01:11.215Z,1638471671.215 [Reporter](INFO): platform_buoyancy_position 852.912109 cc
2021-12-02T19:01:11.635Z,1638471671.635 [Reporter](INFO): platform_buoyancy_position 857.209717 cc
2021-12-02T19:01:11.994Z,1638471671.994 [Reporter](INFO): platform_buoyancy_position 861.641602 cc
2021-12-02T19:01:12.418Z,1638471672.418 [Reporter](INFO): platform_buoyancy_position 867.953735 cc
2021-12-02T19:01:12.831Z,1638471672.831 [Reporter](INFO): platform_buoyancy_position 872.519897 cc
2021-12-02T19:01:13.237Z,1638471673.237 [Reporter](INFO): platform_buoyancy_position 878.563354 cc
2021-12-02T19:01:13.669Z,1638471673.669 [Reporter](INFO): platform_buoyancy_position 882.995239 cc
2021-12-02T19:01:14.035Z,1638471674.035 [Reporter](INFO): platform_buoyancy_position 889.307373 cc
2021-12-02T19:01:14.446Z,1638471674.446 [Reporter](INFO): platform_buoyancy_position 893.739258 cc
2021-12-02T19:01:14.862Z,1638471674.862 [Reporter](INFO): platform_buoyancy_position 900.185669 cc
2021-12-02T19:01:15.227Z,1638471675.227 [Reporter](INFO): platform_buoyancy_position 906.497803 cc
2021-12-02T19:01:15.659Z,1638471675.659 [Reporter](INFO): platform_buoyancy_position 910.929688 cc
2021-12-02T19:01:16.059Z,1638471676.059 [Reporter](INFO): platform_buoyancy_position 915.227295 cc
2021-12-02T19:01:16.476Z,1638471676.476 [Reporter](INFO): platform_buoyancy_position 921.673706 cc
2021-12-02T19:01:16.914Z,1638471676.914 [Reporter](INFO): platform_buoyancy_position 925.837036 cc
2021-12-02T19:01:17.263Z,1638471677.263 [Reporter](INFO): platform_buoyancy_position 930.403198 cc
2021-12-02T19:01:17.684Z,1638471677.684 [Reporter](INFO): platform_buoyancy_position 936.715332 cc
2021-12-02T19:01:18.051Z,1638471678.051 [Reporter](INFO): platform_buoyancy_position 941.012939 cc
2021-12-02T19:01:18.499Z,1638471678.499 [Reporter](INFO): platform_buoyancy_position 945.310425 cc
2021-12-02T19:01:21.289Z,1638471681.289 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-12-02T19:01:34.005Z,1638471694.005 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003765
2021-12-02T19:01:49.518Z,1638471709.518 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-12-02T19:01:49.519Z,1638471709.519 [NAL9602] Data Fault, FailCount= 3
2021-12-02T19:01:49.519Z,1638471709.519 [NAL9602](ERROR): Data Fault
2021-12-02T19:01:49.571Z,1638471709.571 [CBIT](ERROR): Data Fault in component: NAL9602
2021-12-02T19:01:49.922Z,1638471709.922 [NAL9602](INFO): Powering down
2021-12-02T19:01:50.745Z,1638471710.745 [CBIT](INFO): Clearing failed state for component NAL9602
2021-12-02T19:01:50.746Z,1638471710.746 [NAL9602] No Fault, FailCount= 3
2021-12-02T19:02:20.267Z,1638471740.267 [NAL9602](INFO): Powering up NAL9602
2021-12-02T19:02:31.181Z,1638471751.181 [NAL9602](INFO): NAL9602 initialized
2021-12-02T19:02:52.614Z,1638471772.614 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2021-12-02T19:02:52.614Z,1638471772.614 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-02T19:02:52.639Z,1638471772.639 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-02T19:02:53.043Z,1638471773.043 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-02T19:02:53.043Z,1638471773.043 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2021-12-02T19:05:39.483Z,1638471939.483 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-12-02T19:00:39.3Z
2021-12-02T19:05:39.483Z,1638471939.483 [Default:CheckIn:Read_GPS] Stopped
2021-12-02T19:05:39.483Z,1638471939.483 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-12-02T19:05:39.925Z,1638471939.925 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-12-02T19:05:42.953Z,1638471942.953 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20211202T182932/Courier0004.lzma
2021-12-02T19:05:43.538Z,1638471943.538 [DataOverHttps](INFO): Moved sent file to Logs/20211202T182932/Courier0004.lzma.bak
2021-12-02T19:05:43.538Z,1638471943.538 [DataOverHttps](INFO): SBD MOMSN=16323286
2021-12-02T19:05:53.477Z,1638471953.477 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2021-12-02T19:05:53.477Z,1638471953.477 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-12-02T19:05:53.509Z,1638471953.509 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-12-02T19:05:53.829Z,1638471953.829 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-12-02T19:05:53.830Z,1638471953.829 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2021-12-02T19:06:06.905Z,1638471966.905 [DataOverHttps](INFO): Sending 383 bytes from file Logs/20211202T181829/Express0008.lzma
2021-12-02T19:06:07.906Z,1638471967.906 [DataOverHttps](INFO): Moved sent file to Logs/20211202T181829/Express0008.lzma.bak
2021-12-02T19:06:07.906Z,1638471967.906 [DataOverHttps](INFO): SBD MOMSN=16323291
2021-12-02T19:06:24.878Z,1638471984.878 [DataOverHttps](INFO): Sending 956 bytes from file Logs/20211202T182932/Express0001.lzma
2021-12-02T19:06:25.879Z,1638471985.879 [DataOverHttps](INFO): Moved sent file to Logs/20211202T182932/Express0001.lzma.bak
2021-12-02T19:06:25.879Z,1638471985.879 [DataOverHttps](INFO): SBD MOMSN=16323307
2021-12-02T19:06:42.206Z,1638472002.206 [DataOverHttps](INFO): Sending 556 bytes from file Logs/20211202T182932/Express0005.lzma
2021-12-02T19:06:43.206Z,1638472003.206 [DataOverHttps](INFO): Moved sent file to Logs/20211202T182932/Express0005.lzma.bak
2021-12-02T19:06:43.206Z,1638472003.206 [DataOverHttps](INFO): SBD MOMSN=16323331
2021-12-02T19:06:59.292Z,1638472019.292 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20211202T182932/Express0008.lzma
2021-12-02T19:07:00.294Z,1638472020.294 [DataOverHttps](INFO): Moved sent file to Logs/20211202T182932/Express0008.lzma.bak
2021-12-02T19:07:00.294Z,1638472020.294 [DataOverHttps](INFO): SBD MOMSN=16323354
2021-12-02T19:07:01.771Z,1638472021.771 [Default:CheckIn:Read_Iridium] Stopped
2021-12-02T19:07:01.771Z,1638472021.771 [Default:CheckIn:C.Wait] Running Loop=1
2021-12-02T19:07:01.771Z,1638472021.771 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-12-02T19:07:08.954Z,1638472028.954 [DataOverHttps](IMPORTANT): SBD MTMSN=20211202T190707
2021-12-02T19:07:17.088Z,1638472037.088 [DataOverHttps](INFO): Received command: restart logs