2023-11-08T23:13:32.783Z,1699485212.783 [Supervisor](DEBUG): Initializing supervisor.
2023-11-08T23:13:32.831Z,1699485212.831 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-11-08T23:13:32.832Z,1699485212.832 [SyncHandler](INFO): Protected caller Thread ID is 7062
2023-11-08T23:13:32.832Z,1699485212.832 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-11-08T23:13:32.833Z,1699485212.833 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-11-08T23:13:32.834Z,1699485212.834 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7063
2023-11-08T23:13:32.838Z,1699485212.838 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-11-08T23:13:32.856Z,1699485212.856 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-11-08T23:13:32.857Z,1699485212.857 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-11-08T23:13:32.857Z,1699485212.857 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 7064
2023-11-08T23:13:32.861Z,1699485212.861 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-11-08T23:13:32.862Z,1699485212.862 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-11-08T23:13:32.863Z,1699485212.863 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7065
2023-11-08T23:13:32.865Z,1699485212.865 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-11-08T23:13:32.866Z,1699485212.866 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-11-08T23:13:32.866Z,1699485212.866 [logger ThreadHandler](INFO): Protected caller Thread ID is 7066
2023-11-08T23:13:32.870Z,1699485212.870 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-11-08T23:13:32.870Z,1699485212.870 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-11-08T23:13:32.876Z,1699485212.876 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-11-08T23:13:33.271Z,1699485213.271 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-11-08T23:13:33.273Z,1699485213.273 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-11-08T23:13:33.358Z,1699485213.358 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-11-08T23:13:33.846Z,1699485213.846 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-11-08T23:13:33.848Z,1699485213.848 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-11-08T23:13:34.183Z,1699485214.183 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-11-08T23:13:34.185Z,1699485214.185 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-11-08T23:13:34.285Z,1699485214.285 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-11-08T23:13:34.285Z,1699485214.285 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-11-08T23:13:34.692Z,1699485214.692 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-11-08T23:13:34.694Z,1699485214.694 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-11-08T23:13:34.918Z,1699485214.918 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-11-08T23:13:34.918Z,1699485214.918 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-11-08T23:13:35.453Z,1699485215.453 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-11-08T23:13:35.454Z,1699485215.454 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-11-08T23:13:35.594Z,1699485215.594 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-11-08T23:13:35.596Z,1699485215.596 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-11-08T23:13:35.679Z,1699485215.679 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-11-08T23:13:36.485Z,1699485216.485 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-11-08T23:13:36.486Z,1699485216.486 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-11-08T23:13:36.868Z,1699485216.868 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-11-08T23:13:36.869Z,1699485216.869 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-11-08T23:13:37.064Z,1699485217.064 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-11-08T23:13:37.065Z,1699485217.065 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-11-08T23:13:37.179Z,1699485217.179 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-11-08T23:13:37.181Z,1699485217.181 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-11-08T23:13:37.414Z,1699485217.414 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-11-08T23:13:37.416Z,1699485217.416 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-11-08T23:13:37.668Z,1699485217.668 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-11-08T23:13:37.670Z,1699485217.670 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2023-11-08T23:13:37.674Z,1699485217.674 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2023-11-08T23:13:38.104Z,1699485218.104 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2023-11-08T23:13:38.395Z,1699485218.395 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2023-11-08T23:13:38.691Z,1699485218.691 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2023-11-08T23:13:38.779Z,1699485218.779 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2023-11-08T23:13:38.887Z,1699485218.887 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2023-11-08T23:13:38.994Z,1699485218.994 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2023-11-08T23:13:39.113Z,1699485219.113 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2023-11-08T23:13:39.192Z,1699485219.192 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2023-11-08T23:13:39.348Z,1699485219.348 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2023-11-08T23:13:39.494Z,1699485219.494 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2023-11-08T23:13:39.609Z,1699485219.609 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2023-11-08T23:13:39.879Z,1699485219.879 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-11-08T23:13:39.879Z,1699485219.879 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-11-08T23:13:39.894Z,1699485219.894 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-11-08T23:13:39.961Z,1699485219.961 [VerticalControl](DEBUG): Construct VerticalControl.
2023-11-08T23:13:40.023Z,1699485220.023 [VerticalControl] Loaded
2023-11-08T23:13:40.023Z,1699485220.023 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-11-08T23:13:40.026Z,1699485220.026 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-11-08T23:13:40.068Z,1699485220.068 [HorizontalControl] Loaded
2023-11-08T23:13:40.068Z,1699485220.068 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-11-08T23:13:40.070Z,1699485220.070 [SpeedControl](DEBUG): Construct SpeedControl.
2023-11-08T23:13:40.074Z,1699485220.074 [SpeedControl] Loaded
2023-11-08T23:13:40.074Z,1699485220.074 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-11-08T23:13:40.077Z,1699485220.077 [LoopControl](DEBUG): Construct LoopControl.
2023-11-08T23:13:40.077Z,1699485220.077 [LoopControl] Loaded
2023-11-08T23:13:40.077Z,1699485220.077 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-11-08T23:13:40.078Z,1699485220.078 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-11-08T23:13:40.078Z,1699485220.078 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-11-08T23:13:40.094Z,1699485220.094 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-11-08T23:13:40.094Z,1699485220.094 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-11-08T23:13:40.269Z,1699485220.269 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-11-08T23:13:40.270Z,1699485220.270 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-11-08T23:13:40.431Z,1699485220.431 [BuoyancyServo] Loaded
2023-11-08T23:13:40.431Z,1699485220.431 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-11-08T23:13:40.452Z,1699485220.452 [ElevatorServo] Loaded
2023-11-08T23:13:40.452Z,1699485220.452 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-11-08T23:13:40.471Z,1699485220.471 [MassServo] Loaded
2023-11-08T23:13:40.472Z,1699485220.472 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-11-08T23:13:40.493Z,1699485220.493 [RudderServo] Loaded
2023-11-08T23:13:40.493Z,1699485220.493 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-11-08T23:13:40.508Z,1699485220.508 [ThrusterHE] Loaded
2023-11-08T23:13:40.508Z,1699485220.508 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-11-08T23:13:40.508Z,1699485220.508 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-11-08T23:13:40.509Z,1699485220.509 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-11-08T23:13:40.652Z,1699485220.652 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-11-08T23:13:40.652Z,1699485220.652 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-11-08T23:13:40.666Z,1699485220.666 [NavChart] Loaded
2023-11-08T23:13:40.666Z,1699485220.666 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-11-08T23:13:40.673Z,1699485220.673 [UniversalFixResidualReporter] Loaded
2023-11-08T23:13:40.674Z,1699485220.674 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-11-08T23:13:40.674Z,1699485220.674 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-11-08T23:13:40.675Z,1699485220.675 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-11-08T23:13:40.756Z,1699485220.756 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-11-08T23:13:40.756Z,1699485220.756 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-11-08T23:13:41.545Z,1699485221.545 [AHRS_M2] Loaded
2023-11-08T23:13:41.545Z,1699485221.545 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-11-08T23:13:42.373Z,1699485222.373 [BPC1] Loaded
2023-11-08T23:13:42.374Z,1699485222.374 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-11-08T23:13:42.680Z,1699485222.680 [DataOverHttps] Loaded
2023-11-08T23:13:42.681Z,1699485222.681 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-11-08T23:13:42.683Z,1699485222.683 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090E4E0
2023-11-08T23:13:42.683Z,1699485222.683 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7151
2023-11-08T23:13:42.706Z,1699485222.706 [Depth_Keller] Loaded
2023-11-08T23:13:42.706Z,1699485222.706 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-11-08T23:13:42.711Z,1699485222.711 [DropWeight] Loaded
2023-11-08T23:13:42.711Z,1699485222.711 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-11-08T23:13:42.775Z,1699485222.775 [NAL9602] Loaded
2023-11-08T23:13:42.775Z,1699485222.775 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-11-08T23:13:42.809Z,1699485222.809 [Onboard] Loaded
2023-11-08T23:13:42.809Z,1699485222.809 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-11-08T23:13:42.810Z,1699485222.810 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093E4E0
2023-11-08T23:13:42.810Z,1699485222.810 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7152
2023-11-08T23:13:42.824Z,1699485222.824 [Power24vConverter] Loaded
2023-11-08T23:13:42.824Z,1699485222.824 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-11-08T23:13:42.838Z,1699485222.838 [Radio_Surface] Loaded
2023-11-08T23:13:42.838Z,1699485222.838 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-11-08T23:13:42.839Z,1699485222.839 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096E4E0
2023-11-08T23:13:42.839Z,1699485222.839 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7153
2023-11-08T23:13:42.841Z,1699485222.841 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-11-08T23:13:42.841Z,1699485222.841 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-11-08T23:13:43.063Z,1699485223.063 [DepthRateCalculator] Loaded
2023-11-08T23:13:43.063Z,1699485223.063 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-11-08T23:13:43.069Z,1699485223.069 [PitchRateCalculator] Loaded
2023-11-08T23:13:43.069Z,1699485223.069 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-11-08T23:13:43.078Z,1699485223.078 [SpeedCalculator] Loaded
2023-11-08T23:13:43.078Z,1699485223.078 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-11-08T23:13:43.106Z,1699485223.106 [YawRateCalculator] Loaded
2023-11-08T23:13:43.107Z,1699485223.107 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-11-08T23:13:43.220Z,1699485223.220 [ElevatorOffsetCalculator] Loaded
2023-11-08T23:13:43.220Z,1699485223.220 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-11-08T23:13:43.221Z,1699485223.221 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-11-08T23:13:43.221Z,1699485223.221 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-11-08T23:13:43.671Z,1699485223.671 [CANONSampler] Loaded
2023-11-08T23:13:43.671Z,1699485223.671 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2023-11-08T23:13:43.734Z,1699485223.734 [CTD_Seabird] Loaded
2023-11-08T23:13:43.734Z,1699485223.734 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-11-08T23:13:43.736Z,1699485223.736 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A294E0
2023-11-08T23:13:43.737Z,1699485223.737 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7154
2023-11-08T23:13:43.768Z,1699485223.768 [PAR_Licor] Loaded
2023-11-08T23:13:43.768Z,1699485223.768 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-11-08T23:13:43.809Z,1699485223.809 [WetLabsBB2FL] Loaded
2023-11-08T23:13:43.810Z,1699485223.810 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-11-08T23:13:43.811Z,1699485223.811 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A594E0
2023-11-08T23:13:43.811Z,1699485223.811 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7155
2023-11-08T23:13:43.812Z,1699485223.812 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-11-08T23:13:43.812Z,1699485223.812 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-11-08T23:13:43.856Z,1699485223.856 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-11-08T23:13:43.856Z,1699485223.856 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-11-08T23:13:44.208Z,1699485224.208 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-11-08T23:13:44.209Z,1699485224.209 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-11-08T23:13:44.375Z,1699485224.375 [SBIT](DEBUG): Construct Startup Built In Test.
2023-11-08T23:13:44.384Z,1699485224.384 [SBIT] Loaded
2023-11-08T23:13:44.385Z,1699485224.385 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-11-08T23:13:44.388Z,1699485224.388 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-11-08T23:13:44.401Z,1699485224.401 [IBIT] Loaded
2023-11-08T23:13:44.401Z,1699485224.401 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-11-08T23:13:44.407Z,1699485224.407 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-11-08T23:13:44.516Z,1699485224.516 [CBIT] Loaded
2023-11-08T23:13:44.516Z,1699485224.516 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-11-08T23:13:44.517Z,1699485224.517 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-11-08T23:13:44.517Z,1699485224.517 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-11-08T23:13:44.642Z,1699485224.642 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-11-08T23:13:44.648Z,1699485224.648 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-11-08T23:13:44.651Z,1699485224.651 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-11-08T23:13:44.662Z,1699485224.662 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-11-08T23:13:44.663Z,1699485224.663 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B944E0
2023-11-08T23:13:44.664Z,1699485224.664 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7156
2023-11-08T23:13:44.668Z,1699485224.668 [Supervisor](INFO): Main Thread ID is 7061
2023-11-08T23:13:44.668Z,1699485224.668 [Supervisor](DEBUG): Running supervisor.
2023-11-08T23:13:44.669Z,1699485224.669 [CommandExec ThreadHandler](INFO): Handler Thread ID is 7157
2023-11-08T23:13:44.669Z,1699485224.669 [CommandExec](INFO): Initializing the command executive.
2023-11-08T23:13:44.674Z,1699485224.674 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7158
2023-11-08T23:13:44.676Z,1699485224.676 [controlThread ThreadHandler](INFO): Handler Thread ID is 7159
2023-11-08T23:13:44.676Z,1699485224.676 [controlThread](DEBUG): Initializing ControlThread
2023-11-08T23:13:44.677Z,1699485224.677 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-11-08T23:13:44.679Z,1699485224.679 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-11-08T23:13:44.680Z,1699485224.680 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-11-08T23:13:44.680Z,1699485224.680 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-11-08T23:13:44.682Z,1699485224.682 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-11-08T23:13:44.682Z,1699485224.682 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-11-08T23:13:44.686Z,1699485224.686 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-11-08T23:13:44.686Z,1699485224.686 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-11-08T23:13:44.687Z,1699485224.687 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-11-08T23:13:44.687Z,1699485224.687 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-11-08T23:13:44.687Z,1699485224.687 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-11-08T23:13:44.693Z,1699485224.693 [SBIT](INFO): Initialize SBIT Component.
2023-11-08T23:13:44.693Z,1699485224.693 [SBIT](IMPORTANT): git: 2023-10-12
2023-11-08T23:13:44.693Z,1699485224.693 [SBIT](INFO): git hash: f38038725fad7dfee583a1d6cb07fccd712c5d54
2023-11-08T23:13:44.694Z,1699485224.694 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-11-08T23:13:44.695Z,1699485224.695 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-11-08T23:13:44.696Z,1699485224.696 [SBIT](INFO): Beginning SBIT in 64.000000 seconds.
2023-11-08T23:13:44.697Z,1699485224.697 [IBIT](INFO): Initialize IBIT Component.
2023-11-08T23:13:44.697Z,1699485224.697 [CBIT](DEBUG): Initialize CBIT Component.
2023-11-08T23:13:44.698Z,1699485224.698 [logger ThreadHandler](INFO): Handler Thread ID is 7160
2023-11-08T23:13:44.708Z,1699485224.708 [CBIT](DEBUG): Initialized mux pins.
2023-11-08T23:13:44.708Z,1699485224.708 [CBIT](DEBUG): Initializing the watchdog timer.
2023-11-08T23:13:44.717Z,1699485224.717 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7161
2023-11-08T23:13:44.718Z,1699485224.718 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-11-08T23:13:44.729Z,1699485224.729 [Onboard ThreadHandler](INFO): Handler Thread ID is 7162
2023-11-08T23:13:44.733Z,1699485224.733 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-11-08T23:13:44.733Z,1699485224.733 [CBIT](DEBUG): Initializing heartbeat.
2023-11-08T23:13:44.750Z,1699485224.750 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7163
2023-11-08T23:13:44.761Z,1699485224.761 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7164
2023-11-08T23:13:44.761Z,1699485224.761 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-11-08T23:13:44.766Z,1699485224.766 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7166
2023-11-08T23:13:44.769Z,1699485224.769 [WetLabsBB2FL](INFO): Powering up
2023-11-08T23:13:44.770Z,1699485224.770 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7168
2023-11-08T23:13:44.778Z,1699485224.778 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-11-08T23:13:44.779Z,1699485224.779 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-11-08T23:13:44.779Z,1699485224.779 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-11-08T23:13:44.779Z,1699485224.779 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-11-08T23:13:44.779Z,1699485224.779 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-11-08T23:13:44.779Z,1699485224.779 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-11-08T23:13:44.779Z,1699485224.779 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-11-08T23:13:44.780Z,1699485224.780 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-11-08T23:13:44.804Z,1699485224.804 [CBIT](DEBUG): Deactivating GF circuits.
2023-11-08T23:13:44.804Z,1699485224.804 [CBIT](DEBUG): Deactivating emergency mode.
2023-11-08T23:13:44.844Z,1699485224.844 [CBIT](DEBUG): Backplane powered.
2023-11-08T23:13:44.845Z,1699485224.845 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-11-08T23:13:44.873Z,1699485224.873 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-11-08T23:13:44.901Z,1699485224.901 [MissionManager](DEBUG):
2023-11-08T23:13:44.902Z,1699485224.902 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-11-08T23:13:45.031Z,1699485225.031 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-11-08T23:13:45.033Z,1699485225.033 [Default:A.Wait](DEBUG): Construct Wait.
2023-11-08T23:13:45.035Z,1699485225.035 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-11-08T23:13:45.083Z,1699485225.083 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-11-08T23:13:45.093Z,1699485225.093 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-11-08T23:13:45.099Z,1699485225.099 [Default:E.Execute](DEBUG): Construct Execute.
2023-11-08T23:13:45.119Z,1699485225.119 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2023-11-08T23:13:45.123Z,1699485225.123 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-11-08T23:13:45.146Z,1699485225.146 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-11-08T23:13:45.152Z,1699485225.152 [Radio_Surface](INFO): Powering up
2023-11-08T23:13:45.255Z,1699485225.255 [Power24vConverter](INFO): Powering up.
2023-11-08T23:13:45.260Z,1699485225.260 [CANONSampler](INFO): Powering down
2023-11-08T23:13:45.295Z,1699485225.295 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-11-08T23:13:45.301Z,1699485225.301 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-11-08T23:13:45.302Z,1699485225.302 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-11-08T23:13:45.309Z,1699485225.309 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-11-08T23:13:45.309Z,1699485225.309 [MassServo](DEBUG): Initializing EZServoServo.
2023-11-08T23:13:45.317Z,1699485225.317 [MassServo](DEBUG): Initializing MassServo.
2023-11-08T23:13:45.317Z,1699485225.317 [RudderServo](DEBUG): Initializing EZServoServo.
2023-11-08T23:13:45.325Z,1699485225.325 [RudderServo](DEBUG): Initializing RudderServo.
2023-11-08T23:13:45.325Z,1699485225.325 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-11-08T23:13:45.333Z,1699485225.333 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-11-08T23:13:47.380Z,1699485227.380 [WetLabsBB2FL](INFO): Powering down
2023-11-08T23:13:50.897Z,1699485230.897 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-11-08T23:13:58.191Z,1699485238.191 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005129
2023-11-08T23:14:13.010Z,1699485253.010 [NAL9602](INFO): Powering up NAL9602
2023-11-08T23:14:23.919Z,1699485263.919 [NAL9602](INFO): NAL9602 initialized
2023-11-08T23:14:49.424Z,1699485289.424 [SBIT](IMPORTANT): Beginning Startup BIT
2023-11-08T23:14:49.433Z,1699485289.433 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-08T23:15:00.299Z,1699485300.299 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-11-08T23:15:00.706Z,1699485300.706 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-08T23:15:11.690Z,1699485311.690 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-11-08T23:15:12.101Z,1699485312.101 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-08T23:15:13.730Z,1699485313.730 [CommandExec](IMPORTANT): got command strobe off
2023-11-08T23:15:13.730Z,1699485313.730 [CommandExec](IMPORTANT): Deactivating strobe
2023-11-08T23:15:22.979Z,1699485322.979 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-11-08T23:15:22.979Z,1699485322.979 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2023-11-08T23:15:43.533Z,1699485343.533 [SBIT](IMPORTANT): SBIT PASSED
2023-11-08T23:15:43.533Z,1699485343.533 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-11-08T23:15:43.534Z,1699485343.534 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;
2023-11-08T23:15:43.534Z,1699485343.534 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count;
2023-11-08T23:15:43.534Z,1699485343.534 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;
2023-11-08T23:15:43.534Z,1699485343.534 [SBIT](IMPORTANT): CBIT.runElevOffsetCalc=1 bool;
2023-11-08T23:15:43.534Z,1699485343.534 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2023-11-08T23:15:43.534Z,1699485343.534 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool;
2023-11-08T23:15:43.535Z,1699485343.535 [SBIT](IMPORTANT): ThrusterServo.currLimit=50 percent;
2023-11-08T23:15:43.535Z,1699485343.535 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=234.708189 cubic_centimeter;
2023-11-08T23:15:43.535Z,1699485343.535 [SBIT](IMPORTANT): VerticalControl.massDefault=-11.012119 millimeter;
2023-11-08T23:15:43.937Z,1699485343.937 [MissionManager](IMPORTANT): Started mission Startup
2023-11-08T23:15:43.938Z,1699485343.938 [Startup] Running Loop=1
2023-11-08T23:15:43.938Z,1699485343.938 [Startup](DEBUG): Aggregate::initialize Startup
2023-11-08T23:15:43.938Z,1699485343.938 [Startup:A.GoToSurface] Running Loop=1
2023-11-08T23:15:43.938Z,1699485343.938 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-11-08T23:15:43.939Z,1699485343.939 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-11-08T23:15:43.939Z,1699485343.939 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-11-08T23:15:43.939Z,1699485343.939 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-11-08T23:15:43.940Z,1699485343.940 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-11-08T23:15:43.941Z,1699485343.941 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-11-08T23:15:43.942Z,1699485343.942 [Startup:StartupSatComms] Running Loop=1
2023-11-08T23:15:43.942Z,1699485343.942 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-11-08T23:15:43.942Z,1699485343.942 [Startup:StartupSatComms:A] Running Loop=1
2023-11-08T23:15:44.335Z,1699485344.335 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-11-08T23:16:22.689Z,1699485382.689 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-08T23:16:36.425Z,1699485396.425 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-08T23:16:36.809Z,1699485396.809 [CommandExec](IMPORTANT): got command gfscan
2023-11-08T23:16:36.884Z,1699485396.884 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-08T23:16:44.143Z,1699485404.143 [Startup:StartupSatComms:A](INFO): Timed out from 2023-11-08T23:15:43.9Z
2023-11-08T23:16:44.143Z,1699485404.143 [Startup:StartupSatComms:A] Stopped
2023-11-08T23:16:44.143Z,1699485404.143 [Startup:StartupSatComms:B] Running Loop=1
2023-11-08T23:16:44.539Z,1699485404.539 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-11-08T23:16:44.937Z,1699485404.937 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-11-08T23:16:44.937Z,1699485404.937 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-08T23:16:44.947Z,1699485404.947 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-08T23:16:45.347Z,1699485405.347 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-08T23:16:45.347Z,1699485405.347 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-11-08T23:16:47.813Z,1699485407.813 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.020823
CHAN A1 (24V): 0.000630
CHAN A2 (12V): -0.002479
CHAN A3 (5V): -0.001515
CHAN B0 (3.3V): 0.000243
CHAN B1 (3.15aV): -0.000841
CHAN B2 (3.15bV): -0.000034
CHAN B3 (GND): 0.000305
OPEN: -0.000250
Full Scale: +/- 1 mA
2023-11-08T23:16:50.565Z,1699485410.565 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-08T23:16:51.013Z,1699485411.013 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231018T181931/Courier0069.lzma
2023-11-08T23:16:52.014Z,1699485412.014 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Courier0069.lzma.bak
2023-11-08T23:16:52.014Z,1699485412.014 [DataOverHttps](INFO): SBD MOMSN=19052821
2023-11-08T23:17:04.705Z,1699485424.705 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-08T23:17:07.488Z,1699485427.488 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20231108T231332/Courier0000.lzma
2023-11-08T23:17:08.490Z,1699485428.490 [DataOverHttps](INFO): Moved sent file to Logs/20231108T231332/Courier0000.lzma.bak
2023-11-08T23:17:08.490Z,1699485428.490 [DataOverHttps](INFO): SBD MOMSN=19052823
2023-11-08T23:17:18.042Z,1699485438.042 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-08T23:17:23.977Z,1699485443.977 [DataOverHttps](INFO): Sending 328 bytes from file Logs/20231018T181931/Express0070.lzma
2023-11-08T23:17:24.978Z,1699485444.978 [DataOverHttps](INFO): Moved sent file to Logs/20231018T181931/Express0070.lzma.bak
2023-11-08T23:17:24.979Z,1699485444.979 [DataOverHttps](INFO): SBD MOMSN=19052826
2023-11-08T23:17:32.177Z,1699485452.177 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-08T23:17:40.545Z,1699485460.545 [DataOverHttps](INFO): Sending 815 bytes from file Logs/20231108T231332/Express0001.lzma
2023-11-08T23:17:41.546Z,1699485461.546 [DataOverHttps](INFO): Moved sent file to Logs/20231108T231332/Express0001.lzma.bak
2023-11-08T23:17:41.546Z,1699485461.546 [DataOverHttps](INFO): SBD MOMSN=19052839
2023-11-08T23:17:42.702Z,1699485462.702 [Startup:StartupSatComms:B] Stopped
2023-11-08T23:17:42.703Z,1699485462.703 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-11-08T23:17:42.703Z,1699485462.703 [Startup:StartupSatComms] Stopped
2023-11-08T23:17:42.703Z,1699485462.703 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-11-08T23:17:42.703Z,1699485462.703 [Startup](INFO): Completed Startup
2023-11-08T23:17:42.704Z,1699485462.704 [MissionManager](INFO): Startup is completed.
2023-11-08T23:17:42.704Z,1699485462.704 [MissionManager](INFO): Uninitializing Mission Startup
2023-11-08T23:17:42.704Z,1699485462.704 [Startup] Stopped
2023-11-08T23:17:42.704Z,1699485462.704 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-11-08T23:17:42.704Z,1699485462.704 [Startup:A.GoToSurface] Stopped
2023-11-08T23:17:42.704Z,1699485462.704 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-11-08T23:17:43.102Z,1699485463.102 [MissionManager](IMPORTANT): Started mission Default
2023-11-08T23:17:43.103Z,1699485463.103 [Default] Running Loop=1
2023-11-08T23:17:43.103Z,1699485463.103 [Default](DEBUG): Aggregate::initialize Default
2023-11-08T23:17:43.103Z,1699485463.103 [Default:B.GoToSurface] Running Loop=1
2023-11-08T23:17:43.103Z,1699485463.103 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-11-08T23:17:43.103Z,1699485463.103 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-11-08T23:17:43.104Z,1699485463.104 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-11-08T23:17:43.104Z,1699485463.104 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-11-08T23:17:43.104Z,1699485463.104 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-11-08T23:17:43.105Z,1699485463.105 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-11-08T23:17:43.105Z,1699485463.105 [Default:A.Wait] Running Loop=1
2023-11-08T23:17:43.105Z,1699485463.105 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-11-08T23:17:45.913Z,1699485465.913 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-08T23:17:56.459Z,1699485476.459 [Default:A.Wait](INFO): Done Waiting.
2023-11-08T23:17:56.459Z,1699485476.459 [Default:A.Wait] Stopped
2023-11-08T23:17:56.459Z,1699485476.459 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-11-08T23:17:56.849Z,1699485476.849 [Default:CheckIn] Running Loop=1
2023-11-08T23:17:56.849Z,1699485476.849 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-08T23:17:56.849Z,1699485476.849 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-08T23:17:57.271Z,1699485477.271 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-11-08T23:18:00.053Z,1699485480.053 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-08T23:18:14.193Z,1699485494.193 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-08T23:18:27.930Z,1699485507.930 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-08T23:18:50.410Z,1699485530.410 [DataOverHttps](IMPORTANT): SBD MTMSN=20231108T231849
2023-11-08T23:18:57.716Z,1699485537.716 [DataOverHttps](INFO): Received command: gfscan
2023-11-08T23:18:57.718Z,1699485537.718 [CommandExec](IMPORTANT): got command gfscan
2023-11-08T23:18:57.852Z,1699485537.852 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-08T23:19:08.827Z,1699485548.827 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.020191
CHAN A1 (24V): 0.000707
CHAN A2 (12V): -0.002273
CHAN A3 (5V): -0.001644
CHAN B0 (3.3V): -0.000180
CHAN B1 (3.15aV): 0.000037
CHAN B2 (3.15bV): 0.000030
CHAN B3 (GND): 0.000323
OPEN: -0.000435
Full Scale: +/- 1 mA
2023-11-08T23:19:27.326Z,1699485567.326 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-11-08T23:19:45.916Z,1699485585.916 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-11-08T23:19:45.916Z,1699485585.916 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-08T23:19:45.957Z,1699485585.957 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-08T23:19:46.330Z,1699485586.330 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-08T23:19:46.330Z,1699485586.330 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-11-08T23:19:48.323Z,1699485588.323 [CommandExec](IMPORTANT): got command burn off
2023-11-08T23:19:48.323Z,1699485588.323 [CommandExec](IMPORTANT): Deactivating dropweight wire
2023-11-08T23:19:54.669Z,1699485594.669 [CommandExec](IMPORTANT): got command failComponent
2023-11-08T23:19:54.669Z,1699485594.669 [CommandExec](IMPORTANT): Failed components:
2023-11-08T23:19:54.669Z,1699485594.669 [CommandExec](IMPORTANT): No failed Components.
2023-11-08T23:21:08.325Z,1699485668.325 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 5.
2023-11-08T23:21:08.328Z,1699485668.328 [BPC1](INFO): Received data from all battery sticks.
2023-11-08T23:22:46.922Z,1699485766.922 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-11-08T23:22:46.923Z,1699485766.923 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-08T23:22:46.933Z,1699485766.933 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-08T23:22:47.322Z,1699485767.322 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-08T23:22:47.322Z,1699485767.322 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-11-08T23:22:57.019Z,1699485777.019 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-11-08T23:17:56.8Z
2023-11-08T23:22:57.019Z,1699485777.019 [Default:CheckIn:Read_GPS] Stopped
2023-11-08T23:22:57.019Z,1699485777.019 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-08T23:22:57.434Z,1699485777.434 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-11-08T23:22:59.487Z,1699485779.487 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20231108T231332/Courier0004.lzma
2023-11-08T23:23:00.258Z,1699485780.258 [DataOverHttps](INFO): Moved sent file to Logs/20231108T231332/Courier0004.lzma.bak
2023-11-08T23:23:00.258Z,1699485780.258 [DataOverHttps](INFO): SBD MOMSN=19052877
2023-11-08T23:23:39.565Z,1699485819.565 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-08T23:23:46.357Z,1699485826.357 [Power24vConverter](INFO): Powering down.
2023-11-08T23:24:25.927Z,1699485865.927 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-11-08T23:24:25.927Z,1699485865.927 [NAL9602] Data Fault, FailCount= 1
2023-11-08T23:24:25.927Z,1699485865.927 [NAL9602](ERROR): Data Fault
2023-11-08T23:24:25.943Z,1699485865.943 [CBIT](ERROR): Data Fault in component: NAL9602
2023-11-08T23:24:26.338Z,1699485866.338 [NAL9602](INFO): Powering down
2023-11-08T23:24:27.175Z,1699485867.175 [CBIT](INFO): Clearing failed state for component NAL9602
2023-11-08T23:24:27.175Z,1699485867.175 [NAL9602] No Fault, FailCount= 1
2023-11-08T23:24:41.849Z,1699485881.849 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-08T23:24:56.638Z,1699485896.638 [NAL9602](INFO): Powering up NAL9602
2023-11-08T23:25:07.546Z,1699485907.546 [NAL9602](INFO): NAL9602 initialized
2023-11-08T23:25:12.973Z,1699485912.973 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-08T23:25:44.097Z,1699485944.097 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-08T23:25:47.956Z,1699485947.956 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-11-08T23:25:47.956Z,1699485947.956 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-08T23:25:47.966Z,1699485947.966 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-08T23:25:48.372Z,1699485948.372 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-08T23:25:48.372Z,1699485948.372 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-11-08T23:26:09.213Z,1699485969.213 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.770325
2023-11-08T23:26:40.336Z,1699486000.336 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-08T23:27:11.504Z,1699486031.504 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-08T23:27:42.628Z,1699486062.628 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-08T23:28:13.757Z,1699486093.757 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-08T23:28:44.901Z,1699486124.901 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-08T23:28:48.958Z,1699486128.958 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-11-08T23:28:48.958Z,1699486128.958 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-08T23:28:48.969Z,1699486128.969 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-08T23:28:49.382Z,1699486129.382 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-08T23:28:49.382Z,1699486129.382 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-11-08T23:29:16.025Z,1699486156.025 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-08T23:29:47.149Z,1699486187.149 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-08T23:30:18.289Z,1699486218.289 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-08T23:30:49.413Z,1699486249.413 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-08T23:31:20.537Z,1699486280.537 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-08T23:31:49.970Z,1699486309.970 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2023-11-08T23:31:49.970Z,1699486309.970 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-08T23:31:49.000Z,1699486310.000 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-08T23:31:50.364Z,1699486310.364 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-08T23:31:50.364Z,1699486310.364 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2023-11-08T23:31:51.660Z,1699486311.660 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-08T23:32:22.793Z,1699486342.793 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-08T23:32:53.937Z,1699486373.937 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-08T23:33:25.066Z,1699486405.066 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-08T23:33:56.196Z,1699486436.196 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-08T23:34:27.321Z,1699486467.321 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-08T23:34:50.943Z,1699486490.943 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2023-11-08T23:34:50.943Z,1699486490.943 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-08T23:34:50.953Z,1699486490.953 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-08T23:34:51.441Z,1699486491.441 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-08T23:34:51.441Z,1699486491.441 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2023-11-08T23:34:58.444Z,1699486498.444 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-08T23:35:09.103Z,1699486509.103 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-11-08T23:35:09.103Z,1699486509.103 [NAL9602] Data Fault, FailCount= 2
2023-11-08T23:35:09.103Z,1699486509.103 [NAL9602](ERROR): Data Fault
2023-11-08T23:35:09.127Z,1699486509.127 [CBIT](ERROR): Data Fault in component: NAL9602
2023-11-08T23:35:09.506Z,1699486509.506 [NAL9602](INFO): Powering down
2023-11-08T23:35:10.331Z,1699486510.331 [CBIT](INFO): Clearing failed state for component NAL9602
2023-11-08T23:35:10.331Z,1699486510.331 [NAL9602] No Fault, FailCount= 2
2023-11-08T23:35:29.568Z,1699486529.568 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-08T23:35:39.804Z,1699486539.804 [NAL9602](INFO): Powering up NAL9602
2023-11-08T23:35:50.714Z,1699486550.714 [NAL9602](INFO): NAL9602 initialized
2023-11-08T23:36:00.725Z,1699486560.725 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-08T23:36:44.805Z,1699486604.805 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003427
2023-11-08T23:36:51.550Z,1699486611.550 [NAL9602](INFO): SBD MO Status=2, MOMSN=45459, MT Status=2, MTMSN=0
2023-11-08T23:36:51.551Z,1699486611.551 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-11-08T23:36:54.345Z,1699486614.345 [DataOverHttps](INFO): Sending 554 bytes from file Logs/20231108T231332/Express0005.lzma
2023-11-08T23:36:55.346Z,1699486615.346 [DataOverHttps](INFO): Moved sent file to Logs/20231108T231332/Express0005.lzma.bak
2023-11-08T23:36:55.346Z,1699486615.346 [DataOverHttps](INFO): SBD MOMSN=19052879
2023-11-08T23:37:11.888Z,1699486631.888 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231108T231332/Express0008.lzma
2023-11-08T23:37:12.890Z,1699486632.890 [DataOverHttps](INFO): Moved sent file to Logs/20231108T231332/Express0008.lzma.bak
2023-11-08T23:37:12.890Z,1699486632.890 [DataOverHttps](INFO): SBD MOMSN=19052893
2023-11-08T23:37:14.178Z,1699486634.178 [Default:CheckIn:Read_Iridium] Stopped
2023-11-08T23:37:14.179Z,1699486634.179 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-08T23:37:14.179Z,1699486634.179 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-08T23:37:51.774Z,1699486671.774 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2023-11-08T23:37:51.774Z,1699486671.774 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-08T23:37:51.809Z,1699486671.809 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-08T23:37:52.180Z,1699486672.180 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-08T23:37:52.180Z,1699486672.180 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2023-11-08T23:40:36.733Z,1699486836.733 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234035.00,A,3648.16274,N,12147.28544,W,0.136,359.54,081123,,,A*7D
2023-11-08T23:40:36.736Z,1699486836.736 [NAL9602](INFO): GPS fix at 20231108T234035: (36.802712, -121.788091)
2023-11-08T23:41:09.529Z,1699486869.529 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-08T23:42:14.993Z,1699486934.993 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-08T23:42:14.993Z,1699486934.993 [Default:CheckIn:C.Wait] Stopped
2023-11-08T23:42:14.993Z,1699486934.993 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-08T23:42:14.993Z,1699486934.993 [Default:CheckIn:D] Running Loop=1
2023-11-08T23:42:15.393Z,1699486935.393 [Default:CheckIn:D] Stopped
2023-11-08T23:42:15.393Z,1699486935.393 [Default:CheckIn:E] Running Loop=1
2023-11-08T23:42:15.792Z,1699486935.792 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.538167 min
2023-11-08T23:42:15.792Z,1699486935.792 [Default:CheckIn:E] Stopped
2023-11-08T23:42:15.792Z,1699486935.792 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-08T23:42:15.792Z,1699486935.792 [Default:CheckIn] Stopped
2023-11-08T23:42:15.808Z,1699486935.808 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-08T23:42:15.808Z,1699486935.808 [Default:CheckIn](INFO): Running loop #2
2023-11-08T23:42:15.809Z,1699486935.809 [Default:CheckIn] Running Loop=2
2023-11-08T23:42:15.809Z,1699486935.809 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-08T23:42:15.809Z,1699486935.809 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-08T23:42:17.803Z,1699486937.803 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234216.00,A,3648.16111,N,12147.28580,W,0.641,269.70,081123,,,A*75
2023-11-08T23:42:17.805Z,1699486937.805 [NAL9602](INFO): GPS fix at 20231108T234216: (36.802685, -121.788097)
2023-11-08T23:42:17.817Z,1699486937.817 [Default:CheckIn:Read_GPS] Stopped
2023-11-08T23:42:17.817Z,1699486937.817 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-08T23:42:27.692Z,1699486947.692 [DataOverHttps](INFO): Sending 221 bytes from file Logs/20231108T231332/Courier0010.lzma
2023-11-08T23:42:28.695Z,1699486948.695 [DataOverHttps](INFO): Moved sent file to Logs/20231108T231332/Courier0010.lzma.bak
2023-11-08T23:42:28.695Z,1699486948.695 [DataOverHttps](INFO): SBD MOMSN=19052900
2023-11-08T23:42:44.349Z,1699486964.349 [DataOverHttps](INFO): Sending 330 bytes from file Logs/20231108T231332/Express0011.lzma
2023-11-08T23:42:45.350Z,1699486965.350 [DataOverHttps](INFO): Moved sent file to Logs/20231108T231332/Express0011.lzma.bak
2023-11-08T23:42:45.350Z,1699486965.350 [DataOverHttps](INFO): SBD MOMSN=19052903
2023-11-08T23:42:46.650Z,1699486966.650 [Default:CheckIn:Read_Iridium] Stopped
2023-11-08T23:42:46.650Z,1699486966.650 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-08T23:42:46.650Z,1699486966.650 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-08T23:47:34.031Z,1699487254.031 [NAL9602](INFO): SBD MO Status=2, MOMSN=45459, MT Status=2, MTMSN=0
2023-11-08T23:47:34.031Z,1699487254.031 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-11-08T23:47:34.428Z,1699487254.428 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-11-08T23:47:47.371Z,1699487267.371 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-08T23:47:47.371Z,1699487267.371 [Default:CheckIn:C.Wait] Stopped
2023-11-08T23:47:47.371Z,1699487267.371 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-08T23:47:47.371Z,1699487267.371 [Default:CheckIn:D] Running Loop=1
2023-11-08T23:47:47.772Z,1699487267.772 [Default:CheckIn:D] Stopped
2023-11-08T23:47:47.772Z,1699487267.772 [Default:CheckIn:E] Running Loop=1
2023-11-08T23:47:48.190Z,1699487268.190 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.077826 min
2023-11-08T23:47:48.190Z,1699487268.190 [Default:CheckIn:E] Stopped
2023-11-08T23:47:48.190Z,1699487268.190 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-08T23:47:48.190Z,1699487268.190 [Default:CheckIn] Stopped
2023-11-08T23:47:48.190Z,1699487268.190 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-08T23:47:48.190Z,1699487268.190 [Default:CheckIn](INFO): Running loop #3
2023-11-08T23:47:48.191Z,1699487268.191 [Default:CheckIn] Running Loop=3
2023-11-08T23:47:48.191Z,1699487268.191 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-08T23:47:48.191Z,1699487268.191 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-08T23:47:50.191Z,1699487270.191 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234749.00,A,3648.16297,N,12147.28457,W,0.097,269.70,081123,,,A*71
2023-11-08T23:47:50.193Z,1699487270.193 [NAL9602](INFO): GPS fix at 20231108T234749: (36.802716, -121.788076)
2023-11-08T23:47:50.203Z,1699487270.203 [Default:CheckIn:Read_GPS] Stopped
2023-11-08T23:47:50.203Z,1699487270.203 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-08T23:47:57.352Z,1699487277.352 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231108T231332/Courier0013.lzma
2023-11-08T23:47:58.354Z,1699487278.354 [DataOverHttps](INFO): Moved sent file to Logs/20231108T231332/Courier0013.lzma.bak
2023-11-08T23:47:58.354Z,1699487278.354 [DataOverHttps](INFO): SBD MOMSN=19052927
2023-11-08T23:48:14.017Z,1699487294.017 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20231108T231332/Express0014.lzma
2023-11-08T23:48:15.018Z,1699487295.018 [DataOverHttps](INFO): Moved sent file to Logs/20231108T231332/Express0014.lzma.bak
2023-11-08T23:48:15.019Z,1699487295.019 [DataOverHttps](INFO): SBD MOMSN=19052930
2023-11-08T23:48:16.465Z,1699487296.465 [Default:CheckIn:Read_Iridium] Stopped
2023-11-08T23:48:16.465Z,1699487296.465 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-08T23:48:16.465Z,1699487296.465 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-08T23:48:22.565Z,1699487302.565 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-08T23:53:16.903Z,1699487596.903 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-08T23:53:16.903Z,1699487596.903 [Default:CheckIn:C.Wait] Stopped
2023-11-08T23:53:16.903Z,1699487596.903 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-08T23:53:16.903Z,1699487596.903 [Default:CheckIn:D] Running Loop=1
2023-11-08T23:53:17.317Z,1699487597.317 [Default:CheckIn:D] Stopped
2023-11-08T23:53:17.317Z,1699487597.317 [Default:CheckIn:E] Running Loop=1
2023-11-08T23:53:17.710Z,1699487597.710 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.570243 min
2023-11-08T23:53:17.710Z,1699487597.710 [Default:CheckIn:E] Stopped
2023-11-08T23:53:17.710Z,1699487597.710 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-08T23:53:17.710Z,1699487597.710 [Default:CheckIn] Stopped
2023-11-08T23:53:17.710Z,1699487597.710 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-08T23:53:17.710Z,1699487597.710 [Default:CheckIn](INFO): Running loop #4
2023-11-08T23:53:17.711Z,1699487597.711 [Default:CheckIn] Running Loop=4
2023-11-08T23:53:17.711Z,1699487597.711 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-08T23:53:17.711Z,1699487597.711 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-08T23:53:19.723Z,1699487599.723 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235319.00,A,3648.16474,N,12147.28058,W,0.583,0.00,081123,,,A*7B
2023-11-08T23:53:19.725Z,1699487599.725 [NAL9602](INFO): GPS fix at 20231108T235319: (36.802746, -121.788010)
2023-11-08T23:53:19.766Z,1699487599.766 [Default:CheckIn:Read_GPS] Stopped
2023-11-08T23:53:19.766Z,1699487599.766 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-08T23:53:26.520Z,1699487606.520 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231108T231332/Courier0016.lzma
2023-11-08T23:53:27.522Z,1699487607.522 [DataOverHttps](INFO): Moved sent file to Logs/20231108T231332/Courier0016.lzma.bak
2023-11-08T23:53:27.523Z,1699487607.523 [DataOverHttps](IMPORTANT): SBD MOMSN=19052971, MTMSN=20231108T235326
2023-11-08T23:53:34.824Z,1699487614.824 [DataOverHttps](INFO): Received command: run Maintenance/tank_ballast_and_trim.tl
2023-11-08T23:53:34.940Z,1699487614.940 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/tank_ballast_and_trim.tl
2023-11-08T23:53:34.941Z,1699487614.941 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/tank_ballast_and_trim.tl
2023-11-08T23:53:34.941Z,1699487614.941 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Maintenance/tank_ballast_and_trim.tx
2023-11-08T23:53:35.016Z,1699487615.016 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 120.000000 min
2023-11-08T23:53:35.019Z,1699487615.019 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 7.000000 m
2023-11-08T23:53:35.022Z,1699487615.022 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m
2023-11-08T23:53:35.024Z,1699487615.024 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 0.100000 m
2023-11-08T23:53:35.027Z,1699487615.027 [MissionManager](INFO): DefineArg ballast_and_trim.SurfaceThreshold = 2.500000 m
2023-11-08T23:53:35.030Z,1699487615.030 [MissionManager](INFO): DefineArg ballast_and_trim.TargetDepthTimeout = 20.000000 min
2023-11-08T23:53:35.033Z,1699487615.033 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min
2023-11-08T23:53:35.035Z,1699487615.035 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min
2023-11-08T23:53:35.038Z,1699487615.038 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min
2023-11-08T23:53:35.041Z,1699487615.041 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm
2023-11-08T23:53:35.044Z,1699487615.044 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc
2023-11-08T23:53:35.047Z,1699487615.047 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900002 %
2023-11-08T23:53:35.050Z,1699487615.050 [MissionManager](INFO): DefineArg ballast_and_trim.WaitToFloat = 5.000000 min
2023-11-08T23:53:35.050Z,1699487615.050 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.tl
2023-11-08T23:53:35.050Z,1699487615.050 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/Science.tx
2023-11-08T23:53:35.566Z,1699487615.566 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool
2023-11-08T23:53:35.569Z,1699487615.569 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min
2023-11-08T23:53:35.571Z,1699487615.571 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool
2023-11-08T23:53:35.574Z,1699487615.574 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestSaltPeakReportActive = 0 bool
2023-11-08T23:53:35.576Z,1699487615.576 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestOilPeakReportActive = 0 bool
2023-11-08T23:53:35.579Z,1699487615.579 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool
2023-11-08T23:53:35.581Z,1699487615.581 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DetectTrough = 0 bool
2023-11-08T23:53:35.584Z,1699487615.584 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count
2023-11-08T23:53:35.587Z,1699487615.587 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count
2023-11-08T23:53:35.590Z,1699487615.590 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 %
2023-11-08T23:53:35.629Z,1699487615.629 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool
2023-11-08T23:53:35.631Z,1699487615.631 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool
2023-11-08T23:53:35.634Z,1699487615.634 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool
2023-11-08T23:53:35.636Z,1699487615.636 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectSalinityActive = 0 bool
2023-11-08T23:53:35.639Z,1699487615.639 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2023-11-08T23:53:35.663Z,1699487615.663 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0 bool
2023-11-08T23:53:35.667Z,1699487615.667 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0 bool
2023-11-08T23:53:35.670Z,1699487615.670 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 1 bool
2023-11-08T23:53:35.685Z,1699487615.685 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 1 bool
2023-11-08T23:53:35.689Z,1699487615.689 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool
2023-11-08T23:53:35.692Z,1699487615.692 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 0 bool
2023-11-08T23:53:35.695Z,1699487615.695 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count
2023-11-08T23:53:35.702Z,1699487615.702 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count
2023-11-08T23:53:35.705Z,1699487615.705 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakShallowBound = nan m
2023-11-08T23:53:35.708Z,1699487615.708 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDeepBound = nan m
2023-11-08T23:53:35.711Z,1699487615.711 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2023-11-08T23:53:35.742Z,1699487615.742 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlThreshTriggerActive = 0 bool
2023-11-08T23:53:35.746Z,1699487615.746 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlLowerThresh = nan ug/l
2023-11-08T23:53:35.749Z,1699487615.749 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlUpperThresh = nan ug/l
2023-11-08T23:53:35.751Z,1699487615.751 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesThresh = 5.000000 count
2023-11-08T23:53:35.754Z,1699487615.754 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = nan ug/l
2023-11-08T23:53:35.777Z,1699487615.777 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = nan m
2023-11-08T23:53:35.780Z,1699487615.780 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = nan degC
2023-11-08T23:53:35.783Z,1699487615.783 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg
2023-11-08T23:53:35.802Z,1699487615.802 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg
2023-11-08T23:53:35.805Z,1699487615.805 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = nan umol/l
2023-11-08T23:53:35.808Z,1699487615.808 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = nan m
2023-11-08T23:53:35.811Z,1699487615.811 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg
2023-11-08T23:53:35.850Z,1699487615.850 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg
2023-11-08T23:53:35.853Z,1699487615.853 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = nan kg/m3
2023-11-08T23:53:35.855Z,1699487615.855 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = nan m
2023-11-08T23:53:35.903Z,1699487615.903 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg
2023-11-08T23:53:35.920Z,1699487615.920 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg
2023-11-08T23:53:35.923Z,1699487615.923 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = nan ppb
2023-11-08T23:53:35.942Z,1699487615.942 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = nan m
2023-11-08T23:53:35.945Z,1699487615.945 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMTemperature = nan degC
2023-11-08T23:53:35.948Z,1699487615.948 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg
2023-11-08T23:53:35.983Z,1699487615.983 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg
2023-11-08T23:53:35.986Z,1699487615.986 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchFDOMDistance = nan m
2023-11-08T23:53:35.997Z,1699487615.997 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSalt = nan psu
2023-11-08T23:53:35.000Z,1699487616.000 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltDepth = nan m
2023-11-08T23:53:36.003Z,1699487616.003 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltTemperature = nan degC
2023-11-08T23:53:36.042Z,1699487616.042 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLatitude = nan arcdeg
2023-11-08T23:53:36.049Z,1699487616.049 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLongitude = nan arcdeg
2023-11-08T23:53:36.061Z,1699487616.061 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = nan ug/l
2023-11-08T23:53:36.064Z,1699487616.064 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = nan m
2023-11-08T23:53:36.086Z,1699487616.086 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg
2023-11-08T23:53:36.105Z,1699487616.105 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg
2023-11-08T23:53:36.118Z,1699487616.118 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m
2023-11-08T23:53:36.125Z,1699487616.125 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSalt = nan psu
2023-11-08T23:53:36.145Z,1699487616.145 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDepth = nan m
2023-11-08T23:53:36.147Z,1699487616.147 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLatitude = nan arcdeg
2023-11-08T23:53:36.170Z,1699487616.170 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLongitude = nan arcdeg
2023-11-08T23:53:36.174Z,1699487616.174 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDistance = nan m
2023-11-08T23:53:36.185Z,1699487616.185 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOil = nan psu
2023-11-08T23:53:36.196Z,1699487616.196 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDepth = nan m
2023-11-08T23:53:36.207Z,1699487616.207 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLatitude = nan arcdeg
2023-11-08T23:53:36.210Z,1699487616.210 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLongitude = nan arcdeg
2023-11-08T23:53:36.218Z,1699487616.218 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDistance = nan m
2023-11-08T23:53:36.234Z,1699487616.234 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.TriggeredOnChl = 0 bool
2023-11-08T23:53:36.249Z,1699487616.249 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.ChlTriggering = nan ug/l
2023-11-08T23:53:36.395Z,1699487616.395 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-11-08T23:53:36.419Z,1699487616.419 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-11-08T23:53:36.449Z,1699487616.449 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-11-08T23:53:36.468Z,1699487616.468 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-11-08T23:53:36.495Z,1699487616.495 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-11-08T23:53:36.520Z,1699487616.520 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-11-08T23:53:36.546Z,1699487616.546 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-11-08T23:53:36.570Z,1699487616.570 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-11-08T23:53:36.595Z,1699487616.595 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Construct ValueDetect.
2023-11-08T23:53:36.614Z,1699487616.614 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2023-11-08T23:53:36.615Z,1699487616.615 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BackseatDriver.tx
2023-11-08T23:53:36.641Z,1699487616.641 [MissionManager](INFO): DefineArg ballast_and_trim:BackseatDriver.EnableBackseat = 0 bool
2023-11-08T23:53:37.190Z,1699487617.190 [ballast_and_trim:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2023-11-08T23:53:37.216Z,1699487617.216 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BallastAndTrim.tl
2023-11-08T23:53:37.216Z,1699487617.216 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BallastAndTrim.tx
2023-11-08T23:53:37.462Z,1699487617.462 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepth = 25.000000 m
2023-11-08T23:53:37.465Z,1699487617.465 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s
2023-11-08T23:53:37.468Z,1699487617.468 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s
2023-11-08T23:53:37.503Z,1699487617.503 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg
2023-11-08T23:53:37.510Z,1699487617.510 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min
2023-11-08T23:53:37.533Z,1699487617.533 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min
2023-11-08T23:53:37.548Z,1699487617.548 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = 10.000000 min
2023-11-08T23:53:37.551Z,1699487617.551 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = 45.000000 min
2023-11-08T23:53:37.567Z,1699487617.567 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = 10.000000 min
2023-11-08T23:53:37.589Z,1699487617.589 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2023-11-08T23:53:37.601Z,1699487617.601 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2023-11-08T23:53:37.604Z,1699487617.604 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = 99.900002 %
2023-11-08T23:53:37.615Z,1699487617.615 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2023-11-08T23:53:37.626Z,1699487617.626 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = 0.250000 mm
2023-11-08T23:53:37.674Z,1699487617.674 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct.
2023-11-08T23:53:37.708Z,1699487617.708 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy.
2023-11-08T23:53:37.712Z,1699487617.712 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct.
2023-11-08T23:53:37.746Z,1699487617.746 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait.
2023-11-08T23:53:37.758Z,1699487617.758 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct.
2023-11-08T23:53:37.769Z,1699487617.769 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct.
2023-11-08T23:53:37.797Z,1699487617.797 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2023-11-08T23:53:37.805Z,1699487617.805 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct.
2023-11-08T23:53:37.808Z,1699487617.808 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct.
2023-11-08T23:53:37.827Z,1699487617.827 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait.
2023-11-08T23:53:37.841Z,1699487617.841 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Execute](DEBUG): Construct Execute.
2023-11-08T23:53:37.852Z,1699487617.852 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](DEBUG): Construct BallastAndTrim.
2023-11-08T23:53:37.928Z,1699487617.928 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband
2023-11-08T23:53:37.930Z,1699487617.930 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = reader:ballast_and_trim.TargetDepthTimeout
2023-11-08T23:53:37.932Z,1699487617.932 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime
2023-11-08T23:53:37.943Z,1699487617.943 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout
2023-11-08T23:53:37.945Z,1699487617.945 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime
2023-11-08T23:53:37.946Z,1699487617.946 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound
2023-11-08T23:53:37.948Z,1699487617.948 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound
2023-11-08T23:53:37.950Z,1699487617.950 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence
2023-11-08T23:53:37.973Z,1699487617.973 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy.
2023-11-08T23:53:37.993Z,1699487617.993 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Construct Wait.
2023-11-08T23:53:38.033Z,1699487618.033 [MissionManager](DEBUG):
mission ballast_and_trim {
arguments {
MissionTimeout = 120 minute
"""
Maximum duration of mission.
"""
Depth1 = 7 meter
"""
Target depth for ballast and trim.
"""
Depth2 = NaN meter
"""
Target depth to run a second ballast and trim, skipped if NaN.
"""
DepthDeadband = 0.1 meter
"""
How much vertical drift from the specified depth is allowed during the
mission.
"""
SurfaceThreshold = 2.5 meter
"""
Surface threshold depth, relevant only to the ascent.
"""
TargetDepthTimeout = 20 minute
"""
Maximum wait time for the vehicle to reach the target ballast and trim
depth.
"""
SettleTime = 10 minute
"""
How long to wait after reaching target depth before starting ballast and
trim.
"""
EstimationTimeout = 45 minute
"""
Timeout for ballast and trim estimation.
"""
MinEstimationTime = 10 minute
"""
Minimum time to run ballast and trim estimation before determining
convergence (must be shorter than timeout).
"""
MassEstimationErrorBound = 0.25 millimeter
"""
Desired range of the estimated sample mean from the true mean statistic
(smaller values take longer to converge).
"""
BuoyEstimationErrorBound = 15 cubic_centimeter
"""
Desired range of the estimated sample mean from the true mean statistic
(smaller values take longer to converge).
"""
EstimationConfidence = 99.9 percent
"""
Confidence level that the estimator has reached the desired error range
(larger values take longer to converge).
"""
WaitToFloat = 5 minute
"""
Timeout for reaching surface threshold on float ascent, mission will end
when reached.
"""
}
timeout duration=MissionTimeout
insert Insert/Science.tl
insert Insert/BackseatDriver.tl
aggregate RunBallastAndTrim {
run in sequence
insert Insert/BallastAndTrim.tl {
redefineArg InsertDepthDeadband = DepthDeadband
redefineArg InsertApproachDepthTimeout = TargetDepthTimeout
redefineArg InsertSettleTime = SettleTime
redefineArg EstTimeout = EstimationTimeout
redefineArg MinEstTime = MinEstimationTime
redefineArg MassEstErrorBound = MassEstimationErrorBound
redefineArg BuoyEstErrorBound = BuoyEstimationErrorBound
redefineArg EstConfidence = EstimationConfidence
}
aggregate Depth1 {
run in sequence
break if ( isNaN ( Depth1 ) )
assign in sequence BallastAndTrim:InsertDepth = Depth1
call refId="BallastAndTrim"
}
aggregate Depth2 {
run in sequence
break if ( isNaN ( Depth2 ) )
assign in sequence BallastAndTrim:InsertDepth = Depth2
call refId="BallastAndTrim"
}
}
aggregate Float_Up {
"""
Float to surface instead of driving.
"""
run in sequence
break if (
Universal:depth < Control:VerticalControl.surfaceThreshold
)
behavior Guidance:Buoyancy {
run in parallel
set position = Control:VerticalControl.buoyancyDefault
}
behavior Guidance:Wait {
run in sequence
set duration = WaitToFloat
}
}
}
2023-11-08T23:53:38.036Z,1699487618.036 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/tank_ballast_and_trim.tl
2023-11-08T23:53:38.355Z,1699487618.355 [Default] Stopped
2023-11-08T23:53:38.356Z,1699487618.356 [Default](DEBUG): Aggregate::uninitialize Default
2023-11-08T23:53:38.356Z,1699487618.356 [Default:B.GoToSurface] Stopped
2023-11-08T23:53:38.356Z,1699487618.356 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-11-08T23:53:38.356Z,1699487618.356 [Default:CheckIn] Stopped
2023-11-08T23:53:38.356Z,1699487618.356 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-08T23:53:38.356Z,1699487618.356 [Default:CheckIn:Read_Iridium] Stopped
2023-11-08T23:53:38.356Z,1699487618.356 [MissionManager](IMPORTANT): Started mission ballast_and_trim
2023-11-08T23:53:38.356Z,1699487618.356 [ballast_and_trim] Running Loop=1
2023-11-08T23:53:38.357Z,1699487618.357 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim
2023-11-08T23:53:38.357Z,1699487618.357 [ballast_and_trim:Science] Running Loop=1
2023-11-08T23:53:38.357Z,1699487618.357 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science
2023-11-08T23:53:38.357Z,1699487618.357 [ballast_and_trim:Science:A] Running Loop=1
2023-11-08T23:53:38.357Z,1699487618.357 [ballast_and_trim:Science:B] Running Loop=1
2023-11-08T23:53:38.357Z,1699487618.357 [ballast_and_trim:Science:C] Running Loop=1
2023-11-08T23:53:38.357Z,1699487618.357 [ballast_and_trim:Science:D] Running Loop=1
2023-11-08T23:53:38.357Z,1699487618.357 [ballast_and_trim:Science:E] Running Loop=1
2023-11-08T23:53:38.357Z,1699487618.357 [ballast_and_trim:Science:F] Running Loop=1
2023-11-08T23:53:38.357Z,1699487618.357 [ballast_and_trim:Science:Read_Oil] Running Loop=1
2023-11-08T23:53:38.357Z,1699487618.357 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1
2023-11-08T23:53:38.357Z,1699487618.357 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl
2023-11-08T23:53:38.358Z,1699487618.358 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2023-11-08T23:53:38.358Z,1699487618.358 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-11-08T23:53:38.360Z,1699487618.360 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1
2023-11-08T23:53:38.360Z,1699487618.360 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport
2023-11-08T23:53:38.360Z,1699487618.360 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-11-08T23:53:38.372Z,1699487618.372 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-11-08T23:53:38.373Z,1699487618.373 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1
2023-11-08T23:53:38.373Z,1699487618.373 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3
2023-11-08T23:53:38.373Z,1699487618.373 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2023-11-08T23:53:38.373Z,1699487618.373 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-11-08T23:53:38.375Z,1699487618.375 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1
2023-11-08T23:53:38.375Z,1699487618.375 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil
2023-11-08T23:53:38.375Z,1699487618.375 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2023-11-08T23:53:38.375Z,1699487618.375 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-11-08T23:53:38.377Z,1699487618.377 [ballast_and_trim:Science:HighestOilPeakReport] Running Loop=1
2023-11-08T23:53:38.377Z,1699487618.377 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestOilPeakReport
2023-11-08T23:53:38.377Z,1699487618.377 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-11-08T23:53:38.377Z,1699487618.377 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-11-08T23:53:38.378Z,1699487618.378 [ballast_and_trim:Science:PeakDetectFDOM] Running Loop=1
2023-11-08T23:53:38.378Z,1699487618.378 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectFDOM
2023-11-08T23:53:38.378Z,1699487618.378 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2023-11-08T23:53:38.378Z,1699487618.378 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-11-08T23:53:38.380Z,1699487618.380 [ballast_and_trim:Science:PeakDetectSalinity] Running Loop=1
2023-11-08T23:53:38.380Z,1699487618.380 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectSalinity
2023-11-08T23:53:38.380Z,1699487618.380 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2023-11-08T23:53:38.380Z,1699487618.380 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-11-08T23:53:38.381Z,1699487618.381 [ballast_and_trim:Science:HighestSaltPeakReport] Running Loop=1
2023-11-08T23:53:38.382Z,1699487618.382 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestSaltPeakReport
2023-11-08T23:53:38.382Z,1699487618.382 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-11-08T23:53:38.382Z,1699487618.382 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-11-08T23:53:38.383Z,1699487618.383 [ballast_and_trim:Science:ChlThreshTrigger] Running Loop=1
2023-11-08T23:53:38.383Z,1699487618.383 [ballast_and_trim:Science:ChlThreshTrigger](DEBUG): Aggregate::initialize ballast_and_trim:Science:ChlThreshTrigger
2023-11-08T23:53:38.383Z,1699487618.383 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect] Running Loop=1
2023-11-08T23:53:38.383Z,1699487618.383 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](INFO): Initialize.
2023-11-08T23:53:38.383Z,1699487618.383 [ballast_and_trim:Science:OceanCurrent] Running Loop=1
2023-11-08T23:53:38.383Z,1699487618.383 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::initialize ballast_and_trim:Science:OceanCurrent
2023-11-08T23:53:38.383Z,1699487618.383 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2023-11-08T23:53:38.384Z,1699487618.384 [ballast_and_trim:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2023-11-08T23:53:38.384Z,1699487618.384 [ballast_and_trim:BackseatDriver] Running Loop=1
2023-11-08T23:53:38.384Z,1699487618.384 [ballast_and_trim:BackseatDriver](DEBUG): Aggregate::initialize ballast_and_trim:BackseatDriver
2023-11-08T23:53:38.396Z,1699487618.396 [ballast_and_trim:BackseatDriver:A.BackseatDriver] Running Loop=1
2023-11-08T23:53:38.396Z,1699487618.396 [ballast_and_trim:RunBallastAndTrim] Running Loop=1
2023-11-08T23:53:38.396Z,1699487618.396 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim
2023-11-08T23:53:38.397Z,1699487618.397 [ballast_and_trim:RunBallastAndTrim:Depth1] Running Loop=1
2023-11-08T23:53:38.397Z,1699487618.397 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1
2023-11-08T23:53:38.397Z,1699487618.397 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Running Loop=1
2023-11-08T23:53:38.397Z,1699487618.397 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Stopped
2023-11-08T23:53:38.397Z,1699487618.397 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Running Loop=1
2023-11-08T23:53:38.398Z,1699487618.398 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2023-11-08T23:53:38.398Z,1699487618.398 [ballast_and_trim:BackseatDriver] Running Loop=1
2023-11-08T23:53:38.398Z,1699487618.398 [ballast_and_trim:Science] Running Loop=1
2023-11-08T23:53:38.399Z,1699487618.399 [ballast_and_trim:Science:ChlThreshTrigger] Stopped
2023-11-08T23:53:38.399Z,1699487618.399 [ballast_and_trim:Science:ChlThreshTrigger](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:ChlThreshTrigger
2023-11-08T23:53:38.399Z,1699487618.399 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect] Stopped
2023-11-08T23:53:38.399Z,1699487618.399 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Uninitialize.
2023-11-08T23:53:38.399Z,1699487618.399 [ballast_and_trim:Science:HighestSaltPeakReport] Stopped
2023-11-08T23:53:38.400Z,1699487618.400 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestSaltPeakReport
2023-11-08T23:53:38.400Z,1699487618.400 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2023-11-08T23:53:38.400Z,1699487618.400 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-11-08T23:53:38.400Z,1699487618.400 [ballast_and_trim:Science:PeakDetectSalinity] Stopped
2023-11-08T23:53:38.400Z,1699487618.400 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectSalinity
2023-11-08T23:53:38.400Z,1699487618.400 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2023-11-08T23:53:38.400Z,1699487618.400 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-11-08T23:53:38.400Z,1699487618.400 [ballast_and_trim:Science:PeakDetectFDOM] Stopped
2023-11-08T23:53:38.400Z,1699487618.400 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectFDOM
2023-11-08T23:53:38.400Z,1699487618.400 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2023-11-08T23:53:38.400Z,1699487618.400 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-11-08T23:53:38.400Z,1699487618.400 [ballast_and_trim:Science:HighestOilPeakReport] Stopped
2023-11-08T23:53:38.401Z,1699487618.401 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestOilPeakReport
2023-11-08T23:53:38.401Z,1699487618.401 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2023-11-08T23:53:38.401Z,1699487618.401 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-11-08T23:53:38.401Z,1699487618.401 [ballast_and_trim:Science:PeakDetectOil] Stopped
2023-11-08T23:53:38.401Z,1699487618.401 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil
2023-11-08T23:53:38.401Z,1699487618.401 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2023-11-08T23:53:38.401Z,1699487618.401 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-11-08T23:53:38.401Z,1699487618.401 [ballast_and_trim:Science:PeakDetectNO3] Stopped
2023-11-08T23:53:38.401Z,1699487618.401 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3
2023-11-08T23:53:38.401Z,1699487618.401 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2023-11-08T23:53:38.401Z,1699487618.401 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-11-08T23:53:38.401Z,1699487618.401 [ballast_and_trim:Science:HighestChlPeakReport] Stopped
2023-11-08T23:53:38.401Z,1699487618.401 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport
2023-11-08T23:53:38.402Z,1699487618.402 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2023-11-08T23:53:38.402Z,1699487618.402 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-11-08T23:53:38.402Z,1699487618.402 [ballast_and_trim:Science:PeakDetectChl] Stopped
2023-11-08T23:53:38.402Z,1699487618.402 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl
2023-11-08T23:53:38.402Z,1699487618.402 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2023-11-08T23:53:38.402Z,1699487618.402 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-11-08T23:53:38.402Z,1699487618.402 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2023-11-08T23:53:38.402Z,1699487618.402 [ballast_and_trim:Science:Read_Oil] Stopped
2023-11-08T23:53:38.402Z,1699487618.402 [ballast_and_trim:Science:F] Stopped
2023-11-08T23:53:38.402Z,1699487618.402 [ballast_and_trim:Science:E] Running Loop=1
2023-11-08T23:53:38.403Z,1699487618.403 [ballast_and_trim:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2023-11-08T23:53:38.403Z,1699487618.403 [ballast_and_trim:Science:D] Stopped
2023-11-08T23:53:38.403Z,1699487618.403 [ballast_and_trim:Science:C] Running Loop=1
2023-11-08T23:53:38.404Z,1699487618.404 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2023-11-08T23:53:38.405Z,1699487618.405 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2023-11-08T23:53:38.405Z,1699487618.405 [ballast_and_trim:Science:B] Running Loop=1
2023-11-08T23:53:38.405Z,1699487618.405 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2023-11-08T23:53:38.406Z,1699487618.406 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2023-11-08T23:53:38.406Z,1699487618.406 [ballast_and_trim:Science:A] Stopped
2023-11-08T23:53:38.722Z,1699487618.722 [WetLabsBB2FL](INFO): Powering up
2023-11-08T23:53:38.735Z,1699487618.735 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Running Loop=1
2023-11-08T23:53:38.735Z,1699487618.735 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2023-11-08T23:53:38.735Z,1699487618.735 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2023-11-08T23:53:38.735Z,1699487618.735 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2023-11-08T23:53:38.735Z,1699487618.735 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2023-11-08T23:53:38.735Z,1699487618.735 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2023-11-08T23:53:38.735Z,1699487618.735 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2023-11-08T23:53:38.735Z,1699487618.735 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1
2023-11-08T23:53:38.741Z,1699487618.741 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F](INFO): Going to target depth. Speed set to 0.000000 m/s
2023-11-08T23:53:38.741Z,1699487618.741 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Stopped
2023-11-08T23:53:38.742Z,1699487618.742 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Running Loop=1
2023-11-08T23:53:38.742Z,1699487618.742 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2023-11-08T23:53:38.742Z,1699487618.742 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2023-11-08T23:53:38.742Z,1699487618.742 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1
2023-11-08T23:53:38.742Z,1699487618.742 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2023-11-08T23:53:38.742Z,1699487618.742 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1
2023-11-08T23:53:38.742Z,1699487618.742 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize.
2023-11-08T23:53:38.742Z,1699487618.742 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1
2023-11-08T23:53:38.742Z,1699487618.742 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2023-11-08T23:53:38.742Z,1699487618.742 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1
2023-11-08T23:53:38.743Z,1699487618.743 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize.
2023-11-08T23:53:38.743Z,1699487618.743 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1
2023-11-08T23:53:38.743Z,1699487618.743 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2023-11-08T23:53:38.743Z,1699487618.743 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1
2023-11-08T23:53:38.743Z,1699487618.743 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2023-11-08T23:53:38.743Z,1699487618.743 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2023-11-08T23:53:38.744Z,1699487618.744 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2023-11-08T23:53:38.744Z,1699487618.744 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2023-11-08T23:53:38.748Z,1699487618.748 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2023-11-08T23:53:39.154Z,1699487619.154 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A](INFO): Waiting for 0.000000 min while pumping down to neutral.
2023-11-08T23:53:39.155Z,1699487619.155 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped
2023-11-08T23:53:39.155Z,1699487619.155 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Running Loop=1
2023-11-08T23:53:39.155Z,1699487619.155 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Initialize Wait Component.
2023-11-08T23:53:39.155Z,1699487619.155 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped
2023-11-08T23:53:39.155Z,1699487619.155 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2023-11-08T23:53:39.155Z,1699487619.155 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped
2023-11-08T23:53:39.155Z,1699487619.155 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped
2023-11-08T23:53:39.155Z,1699487619.155 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2023-11-08T23:53:39.155Z,1699487619.155 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped
2023-11-08T23:53:39.155Z,1699487619.155 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2023-11-08T23:53:39.527Z,1699487619.527 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](INFO): Done Waiting.
2023-11-08T23:53:39.528Z,1699487619.528 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Stopped
2023-11-08T23:53:39.528Z,1699487619.528 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Uninitialize Wait Component.
2023-11-08T23:53:39.528Z,1699487619.528 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2023-11-08T23:53:39.528Z,1699487619.528 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped
2023-11-08T23:53:39.528Z,1699487619.528 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2023-11-08T23:53:39.528Z,1699487619.528 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1
2023-11-08T23:53:39.528Z,1699487619.528 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2023-11-08T23:53:39.528Z,1699487619.528 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2023-11-08T23:53:39.529Z,1699487619.529 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize.
2023-11-08T23:53:39.529Z,1699487619.529 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1
2023-11-08T23:53:39.945Z,1699487619.945 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2023-11-08T23:53:39.946Z,1699487619.946 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 7.000000 m
2023-11-08T23:53:39.946Z,1699487619.946 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped
2023-11-08T23:53:39.946Z,1699487619.946 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1
2023-11-08T23:53:39.946Z,1699487619.946 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize.
2023-11-08T23:53:42.166Z,1699487622.166 [CTD_Seabird](ERROR): Failed to parse device response:
2023-11-08T23:53:44.175Z,1699487624.175 [CTD_Seabird](ERROR): Salinity reading out of range: 25.670034 psu
2023-11-08T23:53:44.177Z,1699487624.177 [CTD_Seabird](INFO): some bad data, not updating bins
2023-11-08T23:53:44.812Z,1699487624.812 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231108T231332/Express0017.lzma
2023-11-08T23:53:45.814Z,1699487625.814 [DataOverHttps](INFO): Moved sent file to Logs/20231108T231332/Express0017.lzma.bak
2023-11-08T23:53:45.814Z,1699487625.814 [DataOverHttps](INFO): SBD MOMSN=19052976
2023-11-08T23:55:48.765Z,1699487748.765 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-08T23:56:19.889Z,1699487779.889 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-08T23:56:44.788Z,1699487804.788 [Radio_Surface](INFO): Powering down
2023-11-08T23:56:51.017Z,1699487811.017 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-08T23:56:52.021Z,1699487812.021 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-11-08T23:57:57.281Z,1699487877.281 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.727127
2023-11-08T23:58:22.397Z,1699487902.397 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-11-08T23:58:28.044Z,1699487908.044 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped
2023-11-08T23:58:28.045Z,1699487908.045 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2023-11-08T23:58:28.045Z,1699487908.045 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped
2023-11-08T23:58:28.045Z,1699487908.045 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2023-11-08T23:58:28.045Z,1699487908.045 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped
2023-11-08T23:58:28.045Z,1699487908.045 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize.
2023-11-08T23:58:28.045Z,1699487908.045 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2023-11-08T23:58:28.045Z,1699487908.045 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Stopped
2023-11-08T23:58:28.046Z,1699487908.046 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2023-11-08T23:58:28.046Z,1699487908.046 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Stopped
2023-11-08T23:58:28.046Z,1699487908.046 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1
2023-11-08T23:58:28.046Z,1699487908.046 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2023-11-08T23:58:28.046Z,1699487908.046 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2023-11-08T23:58:28.046Z,1699487908.046 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Initialize.
2023-11-08T23:58:28.046Z,1699487908.046 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2023-11-08T23:58:28.046Z,1699487908.046 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Initialize.
2023-11-08T23:58:28.046Z,1699487908.046 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Running Loop=1
2023-11-08T23:58:28.453Z,1699487908.453 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C](INFO): Waiting for the vehicle to settle. Depth = 7.058485 m
2023-11-08T23:58:28.453Z,1699487908.453 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Stopped
2023-11-08T23:58:28.453Z,1699487908.453 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Running Loop=1
2023-11-08T23:58:28.453Z,1699487908.453 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Initialize Wait Component.
2023-11-08T23:58:28.453Z,1699487908.453 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2023-11-08T23:58:28.454Z,1699487908.454 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2023-11-08T23:58:53.080Z,1699487933.080 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-09T00:08:29.241Z,1699488509.241 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](INFO): Done Waiting.
2023-11-09T00:08:29.241Z,1699488509.241 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Stopped
2023-11-09T00:08:29.241Z,1699488509.241 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Uninitialize Wait Component.
2023-11-09T00:08:29.241Z,1699488509.241 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Execute] Running Loop=1
2023-11-09T00:08:29.605Z,1699488509.605 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Execute](INFO): Executing command gfscan
2023-11-09T00:08:29.606Z,1699488509.606 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Execute] Stopped
2023-11-09T00:08:29.606Z,1699488509.606 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F] Running Loop=1
2023-11-09T00:08:29.621Z,1699488509.621 [CommandExec](IMPORTANT): got command gfscan
2023-11-09T00:08:29.678Z,1699488509.678 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-09T00:08:29.922Z,1699488509.922 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-11-09T00:08:29.924Z,1699488509.924 [CTD_Seabird](INFO): some bad data, not updating bins
2023-11-09T00:08:30.013Z,1699488510.013 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F](INFO): Running ballast and trim. Depth = 6.834921 m
2023-11-09T00:08:30.013Z,1699488510.013 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F] Stopped
2023-11-09T00:08:30.014Z,1699488510.014 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.] Running Loop=1
2023-11-09T00:08:30.014Z,1699488510.014 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](INFO): Initializing BallastAndTrim.
2023-11-09T00:08:30.926Z,1699488510.926 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-11-09T00:08:30.927Z,1699488510.927 [CTD_Seabird](INFO): some bad data, not updating bins
2023-11-09T00:08:31.930Z,1699488511.930 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-11-09T00:08:31.931Z,1699488511.931 [CTD_Seabird](INFO): some bad data, not updating bins
2023-11-09T00:08:32.476Z,1699488512.476 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-11-09T00:08:32.934Z,1699488512.934 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-11-09T00:08:32.935Z,1699488512.935 [CTD_Seabird](INFO): some bad data, not updating bins
2023-11-09T00:08:33.938Z,1699488513.938 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-11-09T00:08:33.939Z,1699488513.939 [CTD_Seabird](INFO): some bad data, not updating bins
2023-11-09T00:08:34.946Z,1699488514.946 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-11-09T00:08:34.948Z,1699488514.948 [CTD_Seabird](INFO): some bad data, not updating bins
2023-11-09T00:08:35.946Z,1699488515.946 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-11-09T00:08:35.947Z,1699488515.947 [CTD_Seabird](INFO): some bad data, not updating bins
2023-11-09T00:08:36.950Z,1699488516.950 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-11-09T00:08:36.951Z,1699488516.951 [CTD_Seabird](INFO): some bad data, not updating bins
2023-11-09T00:08:37.954Z,1699488517.954 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-11-09T00:08:37.955Z,1699488517.955 [CTD_Seabird](INFO): some bad data, not updating bins
2023-11-09T00:08:38.958Z,1699488518.958 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-11-09T00:08:38.959Z,1699488518.959 [CTD_Seabird](INFO): some bad data, not updating bins
2023-11-09T00:08:39.962Z,1699488519.962 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-11-09T00:08:39.963Z,1699488519.963 [CTD_Seabird](INFO): some bad data, not updating bins
2023-11-09T00:08:40.581Z,1699488520.581 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002124
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.001907
CHAN A3 (5V): -0.001851
CHAN B0 (3.3V): -0.000042
CHAN B1 (3.15aV): -0.000199
CHAN B2 (3.15bV): 0.000207
CHAN B3 (GND): 0.000365
OPEN: -0.000370
Full Scale: +/- 1 mA
2023-11-09T00:23:45.463Z,1699489425.463 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-09T00:23:59.602Z,1699489439.602 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-09T00:24:13.743Z,1699489453.743 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-09T00:24:27.883Z,1699489467.883 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-09T00:24:42.023Z,1699489482.023 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-09T00:24:55.767Z,1699489495.767 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-09T00:25:09.504Z,1699489509.504 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-09T00:25:23.238Z,1699489523.238 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-09T00:25:36.977Z,1699489536.977 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-09T00:25:50.307Z,1699489550.307 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-11-09T00:28:31.520Z,1699489711.520 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 5.
2023-11-09T00:28:31.523Z,1699489711.523 [BPC1](INFO): Received data from all battery sticks.
2023-11-09T00:28:37.207Z,1699489717.207 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): BallastAndTrim completed. Estimation time: 20.11 minutes.
2023-11-09T00:28:37.208Z,1699489717.208 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): Estimated buoyancyNeutral: 211.377075 +/- 11.734060 cc (conf. level 99.90%, sigma: 19.659240 cc).
2023-11-09T00:28:37.209Z,1699489717.209 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): Estimated massDefault: -8.477228 +/- 0.249957 mm (conf. level 99.90%, sigma: 0.418778 mm).
2023-11-09T00:28:37.210Z,1699489717.210 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): Changing persisted config values to: 211.377075 cc buoyancy neutral and -8.477228 mm mass default
2023-11-09T00:28:37.213Z,1699489717.213 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-11-09T00:28:37.214Z,1699489717.214 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): AMEcho.loadAtStartup=0 bool;
2023-11-09T00:28:37.214Z,1699489717.214 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count;
2023-11-09T00:28:37.214Z,1699489717.214 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): CANONSampler.loadAtStartup=1 bool;
2023-11-09T00:28:37.214Z,1699489717.214 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): CBIT.runElevOffsetCalc=1 bool;
2023-11-09T00:28:37.215Z,1699489717.215 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): DAT.loadAtStartup=0 bool;
2023-11-09T00:28:37.215Z,1699489717.215 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool;
2023-11-09T00:28:37.215Z,1699489717.215 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): ThrusterServo.currLimit=50 percent;
2023-11-09T00:28:37.215Z,1699489717.215 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): VerticalControl.buoyancyNeutral=211.377075 cubic_centimeter;
2023-11-09T00:28:37.215Z,1699489717.215 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): VerticalControl.massDefault=-8.477228 millimeter;
2023-11-09T00:28:37.215Z,1699489717.215 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.] Stopped
2023-11-09T00:28:37.215Z,1699489717.215 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](INFO): Uninitializing BallastAndTrim.
2023-11-09T00:28:37.217Z,1699489717.217 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2023-11-09T00:28:37.217Z,1699489717.217 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped
2023-11-09T00:28:37.217Z,1699489717.217 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2023-11-09T00:28:37.217Z,1699489717.217 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Stopped
2023-11-09T00:28:37.217Z,1699489717.217 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Uninitialize.
2023-11-09T00:28:37.217Z,1699489717.217 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Stopped
2023-11-09T00:28:37.218Z,1699489717.218 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2023-11-09T00:28:37.218Z,1699489717.218 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Stopped
2023-11-09T00:28:37.218Z,1699489717.218 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2023-11-09T00:28:37.218Z,1699489717.218 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Stopped
2023-11-09T00:28:37.218Z,1699489717.218 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Stopped
2023-11-09T00:28:37.218Z,1699489717.218 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Stopped
2023-11-09T00:28:37.218Z,1699489717.218 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Stopped
2023-11-09T00:28:37.218Z,1699489717.218 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Stopped
2023-11-09T00:28:37.597Z,1699489717.597 [ballast_and_trim:RunBallastAndTrim:Depth1:B](INFO): Completed ballast_and_trim:RunBallastAndTrim:Depth1:B
2023-11-09T00:28:37.597Z,1699489717.597 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Stopped
2023-11-09T00:28:37.597Z,1699489717.597 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2023-11-09T00:28:37.597Z,1699489717.597 [ballast_and_trim:RunBallastAndTrim:Depth1](INFO): Completed ballast_and_trim:RunBallastAndTrim:Depth1
2023-11-09T00:28:37.597Z,1699489717.597 [ballast_and_trim:RunBallastAndTrim:Depth1] Stopped
2023-11-09T00:28:37.597Z,1699489717.597 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1
2023-11-09T00:28:37.598Z,1699489717.598 [ballast_and_trim:RunBallastAndTrim:Depth2] Running Loop=1
2023-11-09T00:28:37.598Z,1699489717.598 [ballast_and_trim:RunBallastAndTrim:Depth2](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth2
2023-11-09T00:28:37.598Z,1699489717.598 [ballast_and_trim:RunBallastAndTrim:Depth2:A] Running Loop=1
2023-11-09T00:28:37.997Z,1699489717.997 [ballast_and_trim:RunBallastAndTrim:Depth2] Stopped
2023-11-09T00:28:37.997Z,1699489717.997 [ballast_and_trim:RunBallastAndTrim:Depth2](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth2
2023-11-09T00:28:37.997Z,1699489717.997 [ballast_and_trim:RunBallastAndTrim:Depth2:A] Stopped
2023-11-09T00:28:37.998Z,1699489717.998 [ballast_and_trim:RunBallastAndTrim](INFO): Completed ballast_and_trim:RunBallastAndTrim
2023-11-09T00:28:37.998Z,1699489717.998 [ballast_and_trim:RunBallastAndTrim] Stopped
2023-11-09T00:28:37.998Z,1699489717.998 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim
2023-11-09T00:28:37.999Z,1699489717.999 [ballast_and_trim:Float_Up] Running Loop=1
2023-11-09T00:28:37.999Z,1699489717.999 [ballast_and_trim:Float_Up](DEBUG): Aggregate::initialize ballast_and_trim:Float_Up
2023-11-09T00:28:37.999Z,1699489717.999 [ballast_and_trim:Float_Up:A.Buoyancy] Running Loop=1
2023-11-09T00:28:37.999Z,1699489717.999 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2023-11-09T00:28:37.999Z,1699489717.999 [ballast_and_trim:Float_Up:B.Wait] Running Loop=1
2023-11-09T00:28:37.999Z,1699489717.999 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Initialize Wait Component.
2023-11-09T00:28:38.415Z,1699489718.415 [ballast_and_trim:Float_Up:A.Buoyancy] Running Loop=1
2023-11-09T00:30:50.905Z,1699489850.905 [ballast_and_trim:Float_Up] Stopped
2023-11-09T00:30:50.905Z,1699489850.905 [ballast_and_trim:Float_Up](DEBUG): Aggregate::uninitialize ballast_and_trim:Float_Up
2023-11-09T00:30:50.905Z,1699489850.905 [ballast_and_trim:Float_Up:A.Buoyancy] Stopped
2023-11-09T00:30:50.905Z,1699489850.905 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2023-11-09T00:30:50.905Z,1699489850.905 [ballast_and_trim:Float_Up:B.Wait] Stopped
2023-11-09T00:30:50.905Z,1699489850.905 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Uninitialize Wait Component.
2023-11-09T00:30:50.907Z,1699489850.907 [ballast_and_trim](INFO): Completed ballast_and_trim
2023-11-09T00:30:50.907Z,1699489850.907 [MissionManager](INFO): ballast_and_trim is completed.
2023-11-09T00:30:50.907Z,1699489850.907 [MissionManager](INFO): Uninitializing Mission ballast_and_trim
2023-11-09T00:30:50.907Z,1699489850.907 [ballast_and_trim] Stopped
2023-11-09T00:30:50.907Z,1699489850.907 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim
2023-11-09T00:30:50.907Z,1699489850.907 [ballast_and_trim:Science] Stopped
2023-11-09T00:30:50.908Z,1699489850.908 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science
2023-11-09T00:30:50.908Z,1699489850.908 [ballast_and_trim:Science:B] Stopped
2023-11-09T00:30:50.908Z,1699489850.908 [ballast_and_trim:Science:C] Stopped
2023-11-09T00:30:50.908Z,1699489850.908 [ballast_and_trim:Science:E] Stopped
2023-11-09T00:30:50.908Z,1699489850.908 [ballast_and_trim:Science:OceanCurrent] Stopped
2023-11-09T00:30:50.908Z,1699489850.908 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:OceanCurrent
2023-11-09T00:30:50.908Z,1699489850.908 [ballast_and_trim:Science:OceanCurrent:A.] Stopped
2023-11-09T00:30:50.908Z,1699489850.908 [ballast_and_trim:BackseatDriver] Stopped
2023-11-09T00:30:50.908Z,1699489850.908 [ballast_and_trim:BackseatDriver](DEBUG): Aggregate::uninitialize ballast_and_trim:BackseatDriver
2023-11-09T00:30:50.908Z,1699489850.908 [ballast_and_trim:BackseatDriver:A.BackseatDriver] Stopped
2023-11-09T00:30:51.164Z,1699489851.164 [WetLabsBB2FL](INFO): Powering down
2023-11-09T00:30:51.319Z,1699489851.319 [MissionManager](IMPORTANT): Started mission Default
2023-11-09T00:30:51.320Z,1699489851.320 [Default] Running Loop=1
2023-11-09T00:30:51.320Z,1699489851.320 [Default](DEBUG): Aggregate::initialize Default
2023-11-09T00:30:51.320Z,1699489851.320 [Default:B.GoToSurface] Running Loop=1
2023-11-09T00:30:51.320Z,1699489851.320 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-11-09T00:30:51.320Z,1699489851.320 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-11-09T00:30:51.321Z,1699489851.321 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-11-09T00:30:51.321Z,1699489851.321 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-11-09T00:30:51.321Z,1699489851.321 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-11-09T00:30:51.322Z,1699489851.322 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-11-09T00:30:51.322Z,1699489851.322 [Default:A.Wait] Running Loop=1
2023-11-09T00:30:51.322Z,1699489851.322 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-11-09T00:30:51.504Z,1699489851.504 [Radio_Surface](INFO): Powering up
2023-11-09T00:30:57.545Z,1699489857.545 [DataOverHttps](INFO): Radio surface powered ON.
2023-11-09T00:31:04.647Z,1699489864.647 [Default:A.Wait](INFO): Done Waiting.
2023-11-09T00:31:04.647Z,1699489864.647 [Default:A.Wait] Stopped
2023-11-09T00:31:04.647Z,1699489864.647 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-11-09T00:31:05.048Z,1699489865.048 [Default:CheckIn] Running Loop=1
2023-11-09T00:31:05.048Z,1699489865.048 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-09T00:31:05.048Z,1699489865.048 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-09T00:31:27.673Z,1699489887.673 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-09T00:31:37.766Z,1699489897.766 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-11-09T00:31:37.840Z,1699489897.840 [NAL9602](ERROR): received:
+CSQ:0
OK459, 2, 0, 0, 0
OK
2023-11-09T00:31:58.797Z,1699489918.797 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-09T00:32:29.929Z,1699489949.929 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-09T00:33:01.105Z,1699489981.105 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-09T00:33:32.228Z,1699490012.228 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-09T00:34:03.356Z,1699490043.356 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-09T00:34:21.921Z,1699490061.921 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003232
2023-11-09T00:36:05.255Z,1699490165.255 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-11-09T00:31:05.0Z
2023-11-09T00:36:05.255Z,1699490165.255 [Default:CheckIn:Read_GPS] Stopped
2023-11-09T00:36:05.256Z,1699490165.256 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-09T00:36:09.278Z,1699490169.278 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-11-09T00:36:12.548Z,1699490172.548 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231108T231332/Courier0019.lzma
2023-11-09T00:36:13.550Z,1699490173.550 [DataOverHttps](INFO): Moved sent file to Logs/20231108T231332/Courier0019.lzma.bak
2023-11-09T00:36:13.550Z,1699490173.550 [DataOverHttps](INFO): SBD MOMSN=19053108
2023-11-09T00:36:33.292Z,1699490193.292 [DataOverHttps](INFO): Sending 51 bytes from file Logs/20231108T231332/Courier0022.lzma
2023-11-09T00:36:34.294Z,1699490194.294 [DataOverHttps](INFO): Moved sent file to Logs/20231108T231332/Courier0022.lzma.bak
2023-11-09T00:36:34.294Z,1699490194.294 [DataOverHttps](INFO): SBD MOMSN=19053112
2023-11-09T00:36:40.044Z,1699490200.044 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-09T00:37:05.417Z,1699490225.417 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-09T00:37:30.180Z,1699490250.180 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20231108T231332/Express0020.lzma
2023-11-09T00:37:31.182Z,1699490251.182 [DataOverHttps](INFO): Moved sent file to Logs/20231108T231332/Express0020.lzma.bak
2023-11-09T00:37:31.182Z,1699490251.182 [DataOverHttps](INFO): SBD MOMSN=19053118
2023-11-09T00:37:47.119Z,1699490267.119 [DataOverHttps](INFO): Sending 1329 bytes from file Logs/20231108T231332/Express0023.lzma
2023-11-09T00:37:48.118Z,1699490268.118 [DataOverHttps](INFO): Moved sent file to Logs/20231108T231332/Express0023.lzma.bak
2023-11-09T00:37:48.118Z,1699490268.118 [DataOverHttps](INFO): SBD MOMSN=19053124
2023-11-09T00:37:49.220Z,1699490269.220 [Default:CheckIn:Read_Iridium] Stopped
2023-11-09T00:37:49.221Z,1699490269.221 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-09T00:37:49.221Z,1699490269.221 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-09T00:42:49.835Z,1699490569.835 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-09T00:42:49.835Z,1699490569.835 [Default:CheckIn:C.Wait] Stopped
2023-11-09T00:42:49.835Z,1699490569.835 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-09T00:42:49.835Z,1699490569.835 [Default:CheckIn:D] Running Loop=1
2023-11-09T00:42:50.239Z,1699490570.239 [Default:CheckIn:D] Stopped
2023-11-09T00:42:50.239Z,1699490570.239 [Default:CheckIn:E] Running Loop=1
2023-11-09T00:42:50.651Z,1699490570.651 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.981977 min
2023-11-09T00:42:50.651Z,1699490570.651 [Default:CheckIn:E] Stopped
2023-11-09T00:42:50.652Z,1699490570.652 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-09T00:42:50.652Z,1699490570.652 [Default:CheckIn] Stopped
2023-11-09T00:42:50.652Z,1699490570.652 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-09T00:42:50.652Z,1699490570.652 [Default:CheckIn](INFO): Running loop #2
2023-11-09T00:42:50.652Z,1699490570.652 [Default:CheckIn] Running Loop=2
2023-11-09T00:42:50.652Z,1699490570.652 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-09T00:42:50.652Z,1699490570.652 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-09T00:42:52.249Z,1699490572.249 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-11-09T00:42:52.249Z,1699490572.249 [NAL9602] Data Fault, FailCount= 3
2023-11-09T00:42:52.249Z,1699490572.249 [NAL9602](ERROR): Data Fault
2023-11-09T00:42:52.275Z,1699490572.275 [CBIT](ERROR): Data Fault in component: NAL9602
2023-11-09T00:42:52.653Z,1699490572.653 [NAL9602](INFO): Powering down
2023-11-09T00:42:53.487Z,1699490573.487 [CBIT](INFO): Clearing failed state for component NAL9602
2023-11-09T00:42:53.487Z,1699490573.487 [NAL9602] No Fault, FailCount= 3
2023-11-09T00:43:22.953Z,1699490602.953 [NAL9602](INFO): Powering up NAL9602
2023-11-09T00:43:33.866Z,1699490613.866 [NAL9602](INFO): NAL9602 initialized
2023-11-09T00:46:45.783Z,1699490805.783 [NAL9602](INFO): SBD MO Status=0, MOMSN=45459, MT Status=0, MTMSN=0
2023-11-09T00:46:45.783Z,1699490805.783 [NAL9602](INFO): No messages in MT queue
2023-11-09T00:46:46.993Z,1699490806.993 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004645.00,A,3648.16283,N,12147.27875,W,0.078,0.00,091123,,,A*71
2023-11-09T00:46:46.998Z,1699490806.998 [NAL9602](INFO): GPS fix at 20231109T004645: (36.802714, -121.787979)
2023-11-09T00:46:47.013Z,1699490807.013 [Default:CheckIn:Read_GPS] Stopped
2023-11-09T00:46:47.013Z,1699490807.013 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-09T00:46:57.452Z,1699490817.452 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231108T231332/Courier0025.lzma
2023-11-09T00:46:58.454Z,1699490818.454 [DataOverHttps](INFO): Moved sent file to Logs/20231108T231332/Courier0025.lzma.bak
2023-11-09T00:46:58.454Z,1699490818.454 [DataOverHttps](INFO): SBD MOMSN=19053190
2023-11-09T00:47:14.116Z,1699490834.116 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20231108T231332/Express0026.lzma
2023-11-09T00:47:15.118Z,1699490835.118 [DataOverHttps](INFO): Moved sent file to Logs/20231108T231332/Express0026.lzma.bak
2023-11-09T00:47:15.118Z,1699490835.118 [DataOverHttps](INFO): SBD MOMSN=19053193
2023-11-09T00:47:16.230Z,1699490836.230 [Default:CheckIn:Read_Iridium] Stopped
2023-11-09T00:47:16.230Z,1699490836.230 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-09T00:47:16.230Z,1699490836.230 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-09T00:47:19.429Z,1699490839.429 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-09T00:48:27.082Z,1699490907.082 [DataOverHttps](IMPORTANT): SBD MTMSN=20231109T004826
2023-11-09T00:48:34.432Z,1699490914.432 [DataOverHttps](INFO): Received command: Sonardyne_Nano.loadAtStartup=1 bool persist
2023-11-09T00:48:35.781Z,1699490915.781 [CommandExec](IMPORTANT): got command send
2023-11-09T00:48:35.782Z,1699490915.782 [CommandExec](FAULT): Incomplete syntax. Try: help send
2023-11-09T00:52:16.869Z,1699491136.869 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-09T00:52:16.869Z,1699491136.869 [Default:CheckIn:C.Wait] Stopped
2023-11-09T00:52:16.869Z,1699491136.869 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-09T00:52:16.869Z,1699491136.869 [Default:CheckIn:D] Running Loop=1
2023-11-09T00:52:17.268Z,1699491137.268 [Default:CheckIn:D] Stopped
2023-11-09T00:52:17.268Z,1699491137.268 [Default:CheckIn:E] Running Loop=1
2023-11-09T00:52:17.663Z,1699491137.663 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.432469 min
2023-11-09T00:52:17.663Z,1699491137.663 [Default:CheckIn:E] Stopped
2023-11-09T00:52:17.663Z,1699491137.663 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-09T00:52:17.663Z,1699491137.663 [Default:CheckIn] Stopped
2023-11-09T00:52:17.663Z,1699491137.663 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-09T00:52:17.663Z,1699491137.663 [Default:CheckIn](INFO): Running loop #3
2023-11-09T00:52:17.663Z,1699491137.663 [Default:CheckIn] Running Loop=3
2023-11-09T00:52:17.663Z,1699491137.663 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-09T00:52:17.664Z,1699491137.664 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-09T00:52:19.676Z,1699491139.676 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,005218.00,A,3648.16777,N,12147.28508,W,0.408,129.92,091123,,,A*78
2023-11-09T00:52:19.679Z,1699491139.679 [NAL9602](INFO): GPS fix at 20231109T005218: (36.802796, -121.788085)
2023-11-09T00:52:19.697Z,1699491139.697 [Default:CheckIn:Read_GPS] Stopped
2023-11-09T00:52:19.697Z,1699491139.697 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-09T00:52:34.088Z,1699491154.088 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231108T231332/Courier0028.lzma
2023-11-09T00:52:35.090Z,1699491155.090 [DataOverHttps](INFO): Moved sent file to Logs/20231108T231332/Courier0028.lzma.bak
2023-11-09T00:52:35.090Z,1699491155.090 [DataOverHttps](INFO): SBD MOMSN=19053202
2023-11-09T00:52:53.332Z,1699491173.332 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20231108T231332/Express0029.lzma
2023-11-09T00:52:54.334Z,1699491174.334 [DataOverHttps](INFO): Moved sent file to Logs/20231108T231332/Express0029.lzma.bak
2023-11-09T00:52:54.334Z,1699491174.334 [DataOverHttps](INFO): SBD MOMSN=19053205
2023-11-09T00:53:10.788Z,1699491190.788 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20231108T231332/Express0032.lzma
2023-11-09T00:53:11.790Z,1699491191.790 [DataOverHttps](INFO): Moved sent file to Logs/20231108T231332/Express0032.lzma.bak
2023-11-09T00:53:11.790Z,1699491191.790 [DataOverHttps](INFO): SBD MOMSN=19053211
2023-11-09T00:53:13.045Z,1699491193.045 [Default:CheckIn:Read_Iridium] Stopped
2023-11-09T00:53:13.046Z,1699491193.046 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-09T00:53:13.046Z,1699491193.046 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-09T00:54:14.039Z,1699491254.039 [NAL9602](INFO): SBD MO Status=2, MOMSN=45460, MT Status=2, MTMSN=0
2023-11-09T00:54:14.039Z,1699491254.039 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-11-09T00:54:36.707Z,1699491276.707 [NAL9602](INFO): SBD MO Status=2, MOMSN=45460, MT Status=2, MTMSN=0
2023-11-09T00:54:36.707Z,1699491276.707 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-11-09T00:55:02.575Z,1699491302.575 [NAL9602](INFO): SBD MO Status=2, MOMSN=45460, MT Status=2, MTMSN=0
2023-11-09T00:55:02.575Z,1699491302.575 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-11-09T00:55:33.283Z,1699491333.283 [NAL9602](INFO): SBD MO Status=2, MOMSN=45460, MT Status=2, MTMSN=0
2023-11-09T00:55:33.283Z,1699491333.283 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-11-09T00:56:37.980Z,1699491397.980 [NAL9602](INFO): SBD MO Status=2, MOMSN=45460, MT Status=2, MTMSN=0
2023-11-09T00:56:37.981Z,1699491397.981 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-11-09T00:56:56.506Z,1699491416.506 [DataOverHttps](IMPORTANT): SBD MTMSN=20231109T005655
2023-11-09T00:57:03.996Z,1699491423.996 [DataOverHttps](INFO): Received command: configset sonardyne_nano.loadatstartup 1 bool persist
2023-11-09T00:57:04.392Z,1699491424.392 [CommandExec](IMPORTANT): got command configSet Sonardyne_Nano.loadAtStartup 1 bool persist
2023-11-09T00:57:04.399Z,1699491424.399 [CommandExec](IMPORTANT): configSet Sonardyne_Nano.loadAtStartup requires a restart to take effect.
2023-11-09T00:57:22.481Z,1699491442.481 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-11-09T00:57:53.191Z,1699491473.191 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-09T00:58:13.803Z,1699491493.803 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-09T00:58:13.803Z,1699491493.803 [Default:CheckIn:C.Wait] Stopped
2023-11-09T00:58:13.803Z,1699491493.803 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-09T00:58:13.803Z,1699491493.803 [Default:CheckIn:D] Running Loop=1
2023-11-09T00:58:14.202Z,1699491494.202 [Default:CheckIn:D] Stopped
2023-11-09T00:58:14.202Z,1699491494.202 [Default:CheckIn:E] Running Loop=1
2023-11-09T00:58:14.611Z,1699491494.611 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.381374 min
2023-11-09T00:58:14.612Z,1699491494.612 [Default:CheckIn:E] Stopped
2023-11-09T00:58:14.612Z,1699491494.612 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-09T00:58:14.612Z,1699491494.612 [Default:CheckIn] Stopped
2023-11-09T00:58:14.612Z,1699491494.612 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-09T00:58:14.612Z,1699491494.612 [Default:CheckIn](INFO): Running loop #4
2023-11-09T00:58:14.612Z,1699491494.612 [Default:CheckIn] Running Loop=4
2023-11-09T00:58:14.612Z,1699491494.612 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-09T00:58:14.613Z,1699491494.613 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-09T00:58:16.623Z,1699491496.623 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,005815.00,A,3648.16814,N,12147.28137,W,0.505,129.92,091123,,,A*71
2023-11-09T00:58:16.634Z,1699491496.634 [NAL9602](INFO): GPS fix at 20231109T005815: (36.802802, -121.788023)
2023-11-09T00:58:16.645Z,1699491496.645 [Default:CheckIn:Read_GPS] Stopped
2023-11-09T00:58:16.645Z,1699491496.645 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-09T00:58:27.352Z,1699491507.352 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231108T231332/Courier0034.lzma
2023-11-09T00:58:28.354Z,1699491508.354 [DataOverHttps](INFO): Moved sent file to Logs/20231108T231332/Courier0034.lzma.bak
2023-11-09T00:58:28.354Z,1699491508.354 [DataOverHttps](INFO): SBD MOMSN=19053215
2023-11-09T00:58:44.361Z,1699491524.361 [DataOverHttps](INFO): Sending 332 bytes from file Logs/20231108T231332/Express0035.lzma
2023-11-09T00:58:45.362Z,1699491525.362 [DataOverHttps](INFO): Moved sent file to Logs/20231108T231332/Express0035.lzma.bak
2023-11-09T00:58:45.362Z,1699491525.362 [DataOverHttps](INFO): SBD MOMSN=19053218
2023-11-09T00:58:47.452Z,1699491527.452 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-11-09T00:58:47.528Z,1699491527.528 [NAL9602](ERROR): received:
+CSQ:0
OK460, 2, 0, 0, 0
OK
2023-11-09T00:59:04.172Z,1699491544.172 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20231108T231332/Express0038.lzma
2023-11-09T00:59:05.174Z,1699491545.174 [DataOverHttps](INFO): Moved sent file to Logs/20231108T231332/Express0038.lzma.bak
2023-11-09T00:59:05.174Z,1699491545.174 [DataOverHttps](INFO): SBD MOMSN=19053225
2023-11-09T00:59:06.489Z,1699491546.489 [Default:CheckIn:Read_Iridium] Stopped
2023-11-09T00:59:06.489Z,1699491546.489 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-09T00:59:06.489Z,1699491546.489 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-09T01:03:21.819Z,1699491801.819 [NAL9602](INFO): SBD MO Status=2, MOMSN=45460, MT Status=2, MTMSN=0
2023-11-09T01:03:21.819Z,1699491801.819 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-11-09T01:03:22.221Z,1699491802.221 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-11-09T01:03:52.937Z,1699491832.937 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-09T01:04:07.074Z,1699491847.074 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-09T01:04:07.074Z,1699491847.074 [Default:CheckIn:C.Wait] Stopped
2023-11-09T01:04:07.074Z,1699491847.074 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-09T01:04:07.074Z,1699491847.074 [Default:CheckIn:D] Running Loop=1
2023-11-09T01:04:07.483Z,1699491847.483 [Default:CheckIn:D] Stopped
2023-11-09T01:04:07.483Z,1699491847.483 [Default:CheckIn:E] Running Loop=1
2023-11-09T01:04:07.891Z,1699491847.891 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.269385 min
2023-11-09T01:04:07.892Z,1699491847.892 [Default:CheckIn:E] Stopped
2023-11-09T01:04:07.892Z,1699491847.892 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-09T01:04:07.892Z,1699491847.892 [Default:CheckIn] Stopped
2023-11-09T01:04:07.892Z,1699491847.892 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-09T01:04:07.892Z,1699491847.892 [Default:CheckIn](INFO): Running loop #5
2023-11-09T01:04:07.892Z,1699491847.892 [Default:CheckIn] Running Loop=5
2023-11-09T01:04:07.892Z,1699491847.892 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-09T01:04:07.893Z,1699491847.893 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-09T01:04:09.905Z,1699491849.905 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,010409.00,A,3648.16670,N,12147.28203,W,0.350,0.00,091123,,,A*7B
2023-11-09T01:04:09.907Z,1699491849.907 [NAL9602](INFO): GPS fix at 20231109T010409: (36.802778, -121.788034)
2023-11-09T01:04:09.918Z,1699491849.918 [Default:CheckIn:Read_GPS] Stopped
2023-11-09T01:04:09.918Z,1699491849.918 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-09T01:04:17.616Z,1699491857.616 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231108T231332/Courier0040.lzma
2023-11-09T01:04:18.618Z,1699491858.618 [DataOverHttps](INFO): Moved sent file to Logs/20231108T231332/Courier0040.lzma.bak
2023-11-09T01:04:18.618Z,1699491858.618 [DataOverHttps](INFO): SBD MOMSN=19053227
2023-11-09T01:04:36.936Z,1699491876.936 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20231108T231332/Express0041.lzma
2023-11-09T01:04:37.938Z,1699491877.938 [DataOverHttps](INFO): Moved sent file to Logs/20231108T231332/Express0041.lzma.bak
2023-11-09T01:04:37.938Z,1699491877.938 [DataOverHttps](INFO): SBD MOMSN=19053230
2023-11-09T01:04:39.454Z,1699491879.454 [Default:CheckIn:Read_Iridium] Stopped
2023-11-09T01:04:39.454Z,1699491879.454 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-09T01:04:39.454Z,1699491879.454 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-09T01:05:29.127Z,1699491929.127 [NAL9602](INFO): SBD MO Status=2, MOMSN=45460, MT Status=2, MTMSN=0
2023-11-09T01:05:29.127Z,1699491929.127 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-11-09T01:05:50.935Z,1699491950.935 [NAL9602](INFO): SBD MO Status=2, MOMSN=45460, MT Status=2, MTMSN=0
2023-11-09T01:05:50.935Z,1699491950.935 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-11-09T01:06:40.437Z,1699492000.437 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-09T01:07:11.561Z,1699492031.561 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-09T01:07:42.684Z,1699492062.684 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-09T01:08:13.808Z,1699492093.808 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-09T01:08:44.937Z,1699492124.937 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-09T01:08:47.949Z,1699492127.949 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.721832
2023-11-09T01:09:12.529Z,1699492152.529 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-11-09T01:09:23.725Z,1699492163.725 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003708
2023-11-09T01:09:40.033Z,1699492180.033 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-09T01:09:40.033Z,1699492180.033 [Default:CheckIn:C.Wait] Stopped
2023-11-09T01:09:40.033Z,1699492180.033 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-09T01:09:40.033Z,1699492180.033 [Default:CheckIn:D] Running Loop=1
2023-11-09T01:09:40.414Z,1699492180.414 [Default:CheckIn:D] Stopped
2023-11-09T01:09:40.414Z,1699492180.414 [Default:CheckIn:E] Running Loop=1
2023-11-09T01:09:40.823Z,1699492180.823 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.818241 min
2023-11-09T01:09:40.823Z,1699492180.823 [Default:CheckIn:E] Stopped
2023-11-09T01:09:40.823Z,1699492180.823 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-09T01:09:40.824Z,1699492180.824 [Default:CheckIn] Stopped
2023-11-09T01:09:40.824Z,1699492180.824 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-09T01:09:40.824Z,1699492180.824 [Default:CheckIn](INFO): Running loop #6
2023-11-09T01:09:40.824Z,1699492180.824 [Default:CheckIn] Running Loop=6
2023-11-09T01:09:40.824Z,1699492180.824 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-09T01:09:40.824Z,1699492180.824 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-09T01:13:50.893Z,1699492430.893 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2023-11-09T01:13:50.893Z,1699492430.893 [DropWeight] Hardware Fault, FailCount= 1
2023-11-09T01:13:50.893Z,1699492430.893 [DropWeight](ERROR): Hardware Fault
2023-11-09T01:13:50.981Z,1699492430.981 [CommandExec](FAULT): Scheduling is paused
2023-11-09T01:13:50.981Z,1699492430.981 [CBIT](INFO): Critical error at 20231109T011350
2023-11-09T01:13:50.981Z,1699492430.981 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-11-09T01:13:50.984Z,1699492430.984 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-11-09T01:13:50.996Z,1699492430.996 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-11-09T01:13:51.321Z,1699492431.321 [CBIT](INFO): Critical error at 20231109T011350
2023-11-09T01:13:58.929Z,1699492438.929 [CommandExec](IMPORTANT): got command quit
2023-11-09T01:13:59.932Z,1699492439.932 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-09T01:13:59.933Z,1699492439.933 [CommandExec](INFO): Uninitializing the command executive.
2023-11-09T01:13:59.933Z,1699492439.933 [CommandExec](INFO): Uninitializing the command scheduler.
2023-11-09T01:13:59.933Z,1699492439.933 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-11-09T01:14:00.076Z,1699492440.076 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2023-11-09T01:14:00.076Z,1699492440.076 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-11-09T01:14:00.076Z,1699492440.076 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-11-09T01:14:00.077Z,1699492440.077 [NavChartDb](INFO): Join timeout helper Thread ID is 7692
2023-11-09T01:14:00.409Z,1699492440.409 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-09T01:14:00.409Z,1699492440.409 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-11-09T01:14:00.420Z,1699492440.420 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-11-09T01:14:00.420Z,1699492440.420 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-11-09T01:14:00.421Z,1699492440.421 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7693
2023-11-09T01:14:00.465Z,1699492440.465 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-09T01:14:00.465Z,1699492440.465 [WetLabsBB2FL](INFO): Powering down
2023-11-09T01:14:00.466Z,1699492440.466 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-11-09T01:14:00.484Z,1699492440.484 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-11-09T01:14:00.484Z,1699492440.484 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-11-09T01:14:00.485Z,1699492440.485 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7694
2023-11-09T01:14:00.729Z,1699492440.729 [CTD_Seabird](INFO): Powering down
2023-11-09T01:14:00.740Z,1699492440.740 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-09T01:14:00.741Z,1699492440.741 [CTD_Seabird](INFO): Powering down
2023-11-09T01:14:00.752Z,1699492440.752 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-11-09T01:14:00.768Z,1699492440.768 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-11-09T01:14:00.768Z,1699492440.768 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-11-09T01:14:00.769Z,1699492440.769 [Radio_Surface](INFO): Join timeout helper Thread ID is 7695
2023-11-09T01:14:00.788Z,1699492440.788 [Radio_Surface](INFO): Powering down
2023-11-09T01:14:00.789Z,1699492440.789 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-09T01:14:00.790Z,1699492440.790 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-11-09T01:14:00.792Z,1699492440.792 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-11-09T01:14:00.793Z,1699492440.793 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-11-09T01:14:00.793Z,1699492440.793 [Onboard](INFO): Join timeout helper Thread ID is 7696
2023-11-09T01:14:00.953Z,1699492440.953 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-09T01:14:00.953Z,1699492440.953 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-11-09T01:14:00.965Z,1699492440.965 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-11-09T01:14:00.965Z,1699492440.965 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-11-09T01:14:00.966Z,1699492440.966 [DataOverHttps](INFO): Join timeout helper Thread ID is 7697
2023-11-09T01:14:01.285Z,1699492441.285 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-09T01:14:01.285Z,1699492441.285 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-11-09T01:14:01.305Z,1699492441.305 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-11-09T01:14:01.306Z,1699492441.306 [logger ThreadHandler](INFO): Thread cancelled.
2023-11-09T01:14:01.306Z,1699492441.306 [logger](INFO): Join timeout helper Thread ID is 7698
2023-11-09T01:14:01.331Z,1699492441.331 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-09T01:14:01.331Z,1699492441.331 [logger ThreadHandler](INFO): Thread cancelled.
2023-11-09T01:14:01.349Z,1699492441.349 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-11-09T01:14:01.349Z,1699492441.349 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-11-09T01:14:01.349Z,1699492441.349 [CommandLine](INFO): Join timeout helper Thread ID is 7699
2023-11-09T01:14:01.421Z,1699492441.421 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-09T01:14:01.421Z,1699492441.421 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-11-09T01:14:01.433Z,1699492441.433 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-11-09T01:14:01.433Z,1699492441.433 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-11-09T01:14:01.434Z,1699492441.434 [CommandExec](INFO): Join timeout helper Thread ID is 7700
2023-11-09T01:14:01.439Z,1699492441.439 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-11-09T01:14:01.439Z,1699492441.439 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-11-09T01:14:01.440Z,1699492441.440 [controlThread](INFO): Join timeout helper Thread ID is 7701
2023-11-09T01:14:01.780Z,1699492441.780 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-09T01:14:01.781Z,1699492441.781 [controlThread](DEBUG): Uninitializing ControlThread
2023-11-09T01:14:01.781Z,1699492441.781 [AHRS_M2](INFO): Powering down
2023-11-09T01:14:01.853Z,1699492441.853 [NAL9602](INFO): Powering down
2023-11-09T01:14:01.856Z,1699492441.856 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-11-09T01:14:01.857Z,1699492441.857 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-11-09T01:14:01.857Z,1699492441.857 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-11-09T01:14:01.858Z,1699492441.858 [MissionManager](INFO): Uninitializing Mission Default
2023-11-09T01:14:01.858Z,1699492441.858 [Default] Stopped
2023-11-09T01:14:01.858Z,1699492441.858 [Default](DEBUG): Aggregate::uninitialize Default
2023-11-09T01:14:01.858Z,1699492441.858 [Default:B.GoToSurface] Stopped
2023-11-09T01:14:01.858Z,1699492441.858 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-11-09T01:14:01.858Z,1699492441.858 [Default:CheckIn] Stopped
2023-11-09T01:14:01.858Z,1699492441.858 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-09T01:14:01.858Z,1699492441.858 [Default:CheckIn:Read_GPS] Stopped
2023-11-09T01:14:01.861Z,1699492441.861 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-11-09T01:14:01.861Z,1699492441.861 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-11-09T01:14:01.862Z,1699492441.862 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-11-09T01:14:01.862Z,1699492441.862 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-11-09T01:14:01.862Z,1699492441.862 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-11-09T01:14:01.863Z,1699492441.863 [BuoyancyServo](INFO): Powering down
2023-11-09T01:14:01.876Z,1699492441.876 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-11-09T01:14:01.877Z,1699492441.877 [ElevatorServo](INFO): Powering down
2023-11-09T01:14:01.877Z,1699492441.877 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-11-09T01:14:01.878Z,1699492441.878 [MassServo](INFO): Powering down
2023-11-09T01:14:01.878Z,1699492441.878 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-11-09T01:14:01.878Z,1699492441.878 [RudderServo](INFO): Powering down
2023-11-09T01:14:01.879Z,1699492441.879 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-11-09T01:14:01.879Z,1699492441.879 [ThrusterHE](INFO): Powering down
2023-11-09T01:14:01.880Z,1699492441.880 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-11-09T01:14:01.881Z,1699492441.881 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-11-09T01:14:01.881Z,1699492441.881 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-11-09T01:14:01.881Z,1699492441.881 [CBIT](DEBUG): Powering off loads.
2023-11-09T01:14:01.892Z,1699492441.892 [CBIT](DEBUG): Disabling WDT.
2023-11-09T01:14:01.904Z,1699492441.904 [CBIT](DEBUG): Opening all GF detection circuits.
2023-11-09T01:14:01.905Z,1699492441.905 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-11-09T01:14:01.959Z,1699492441.959 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-11-09T01:14:01.962Z,1699492441.962 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-11-09T01:14:01.969Z,1699492441.969 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-11-09T01:14:02.044Z,1699492442.044 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-11-09T01:14:02.049Z,1699492442.049 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-11-09T01:14:02.090Z,1699492442.090 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-11-09T01:14:02.168Z,1699492442.168 [logger ThreadHandler](INFO): Thread cancelled.