2023-08-24T16:34:08.585Z,1692894848.585 [Supervisor](DEBUG): Initializing supervisor.
2023-08-24T16:34:08.590Z,1692894848.590 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-08-24T16:34:08.590Z,1692894848.590 [SyncHandler](INFO): Protected caller Thread ID is 839
2023-08-24T16:34:08.591Z,1692894848.591 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-08-24T16:34:08.592Z,1692894848.592 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-08-24T16:34:08.592Z,1692894848.592 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 840
2023-08-24T16:34:08.596Z,1692894848.596 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-08-24T16:34:08.614Z,1692894848.614 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-08-24T16:34:08.615Z,1692894848.615 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-08-24T16:34:08.615Z,1692894848.615 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 841
2023-08-24T16:34:08.619Z,1692894848.619 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-08-24T16:34:08.620Z,1692894848.620 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-08-24T16:34:08.621Z,1692894848.621 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 842
2023-08-24T16:34:08.623Z,1692894848.623 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-08-24T16:34:08.624Z,1692894848.624 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-08-24T16:34:08.624Z,1692894848.624 [logger ThreadHandler](INFO): Protected caller Thread ID is 843
2023-08-24T16:34:08.628Z,1692894848.628 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-08-24T16:34:08.628Z,1692894848.628 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-08-24T16:34:08.633Z,1692894848.633 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-08-24T16:34:08.732Z,1692894848.732 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-08-24T16:34:08.734Z,1692894848.734 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-08-24T16:34:09.460Z,1692894849.460 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-08-24T16:34:09.462Z,1692894849.462 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-08-24T16:34:09.685Z,1692894849.685 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-08-24T16:34:09.687Z,1692894849.687 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-08-24T16:34:09.766Z,1692894849.766 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-08-24T16:34:09.898Z,1692894849.898 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-08-24T16:34:09.900Z,1692894849.900 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-08-24T16:34:09.983Z,1692894849.983 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-08-24T16:34:10.091Z,1692894850.091 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-08-24T16:34:10.092Z,1692894850.092 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-08-24T16:34:10.483Z,1692894850.483 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-08-24T16:34:10.484Z,1692894850.484 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-08-24T16:34:10.979Z,1692894850.979 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-08-24T16:34:10.979Z,1692894850.979 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-08-24T16:34:11.198Z,1692894851.198 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-08-24T16:34:11.199Z,1692894851.199 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-08-24T16:34:11.394Z,1692894851.394 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-08-24T16:34:11.394Z,1692894851.394 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-08-24T16:34:11.920Z,1692894851.920 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-08-24T16:34:12.379Z,1692894852.379 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-08-24T16:34:12.812Z,1692894852.812 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-08-24T16:34:12.814Z,1692894852.814 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-08-24T16:34:13.040Z,1692894853.040 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-08-24T16:34:13.040Z,1692894853.040 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-08-24T16:34:13.432Z,1692894853.432 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-08-24T16:34:13.433Z,1692894853.433 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-08-24T16:34:13.788Z,1692894853.788 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-08-24T16:34:13.790Z,1692894853.790 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2023-08-24T16:34:13.793Z,1692894853.793 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2023-08-24T16:34:13.878Z,1692894853.878 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2023-08-24T16:34:14.075Z,1692894854.075 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2023-08-24T16:34:14.180Z,1692894854.180 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2023-08-24T16:34:14.267Z,1692894854.267 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2023-08-24T16:34:14.365Z,1692894854.365 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2023-08-24T16:34:14.481Z,1692894854.481 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2023-08-24T16:34:14.756Z,1692894854.756 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-08-24T16:34:14.756Z,1692894854.756 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2023-08-24T16:34:14.851Z,1692894854.851 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2023-08-24T16:34:14.951Z,1692894854.951 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2023-08-24T16:34:15.093Z,1692894855.093 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2023-08-24T16:34:15.194Z,1692894855.194 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2023-08-24T16:34:15.200Z,1692894855.200 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-08-24T16:34:15.333Z,1692894855.333 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-08-24T16:34:15.333Z,1692894855.333 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-08-24T16:34:15.404Z,1692894855.404 [VerticalControl](DEBUG): Construct VerticalControl.
2023-08-24T16:34:15.466Z,1692894855.466 [VerticalControl] Loaded
2023-08-24T16:34:15.466Z,1692894855.466 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-08-24T16:34:15.469Z,1692894855.469 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-08-24T16:34:15.512Z,1692894855.512 [HorizontalControl] Loaded
2023-08-24T16:34:15.512Z,1692894855.512 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-08-24T16:34:15.514Z,1692894855.514 [SpeedControl](DEBUG): Construct SpeedControl.
2023-08-24T16:34:15.518Z,1692894855.518 [SpeedControl] Loaded
2023-08-24T16:34:15.518Z,1692894855.518 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-08-24T16:34:15.520Z,1692894855.520 [LoopControl](DEBUG): Construct LoopControl.
2023-08-24T16:34:15.521Z,1692894855.521 [LoopControl] Loaded
2023-08-24T16:34:15.521Z,1692894855.521 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-08-24T16:34:15.522Z,1692894855.522 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-08-24T16:34:15.522Z,1692894855.522 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-08-24T16:34:15.594Z,1692894855.594 [DepthRateCalculator] Loaded
2023-08-24T16:34:15.594Z,1692894855.594 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-08-24T16:34:15.599Z,1692894855.599 [PitchRateCalculator] Loaded
2023-08-24T16:34:15.599Z,1692894855.599 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-08-24T16:34:15.610Z,1692894855.610 [SpeedCalculator] Loaded
2023-08-24T16:34:15.610Z,1692894855.610 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-08-24T16:34:15.614Z,1692894855.614 [YawRateCalculator] Loaded
2023-08-24T16:34:15.615Z,1692894855.615 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-08-24T16:34:15.633Z,1692894855.633 [ElevatorOffsetCalculator] Loaded
2023-08-24T16:34:15.634Z,1692894855.634 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-08-24T16:34:15.634Z,1692894855.634 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-08-24T16:34:15.635Z,1692894855.635 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-08-24T16:34:15.679Z,1692894855.679 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-08-24T16:34:15.680Z,1692894855.680 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-08-24T16:34:15.764Z,1692894855.764 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-08-24T16:34:15.765Z,1692894855.765 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-08-24T16:34:16.146Z,1692894856.146 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-08-24T16:34:16.147Z,1692894856.147 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-08-24T16:34:16.338Z,1692894856.338 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-08-24T16:34:16.339Z,1692894856.339 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-08-24T16:34:17.125Z,1692894857.125 [AHRS_M2] Loaded
2023-08-24T16:34:17.125Z,1692894857.125 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-08-24T16:34:17.375Z,1692894857.375 [BackseatComponent] Loaded
2023-08-24T16:34:17.375Z,1692894857.375 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-08-24T16:34:17.377Z,1692894857.377 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4098F4E0
2023-08-24T16:34:17.377Z,1692894857.377 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 925
2023-08-24T16:34:17.380Z,1692894857.380 [LcmUniversalReporter] Loaded
2023-08-24T16:34:17.380Z,1692894857.380 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-08-24T16:34:18.671Z,1692894858.671 [BPC1] Loaded
2023-08-24T16:34:18.671Z,1692894858.671 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-08-24T16:34:18.813Z,1692894858.813 [DataOverHttps] Loaded
2023-08-24T16:34:18.813Z,1692894858.813 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-08-24T16:34:18.814Z,1692894858.814 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409BF4E0
2023-08-24T16:34:18.815Z,1692894858.815 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 926
2023-08-24T16:34:18.836Z,1692894858.836 [Depth_Keller] Loaded
2023-08-24T16:34:18.836Z,1692894858.836 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-08-24T16:34:18.841Z,1692894858.841 [DropWeight] Loaded
2023-08-24T16:34:18.841Z,1692894858.841 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-08-24T16:34:18.906Z,1692894858.906 [NAL9602] Loaded
2023-08-24T16:34:18.906Z,1692894858.906 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-08-24T16:34:18.936Z,1692894858.936 [Onboard] Loaded
2023-08-24T16:34:18.936Z,1692894858.936 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-08-24T16:34:18.938Z,1692894858.938 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409EF4E0
2023-08-24T16:34:18.938Z,1692894858.938 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 927
2023-08-24T16:34:18.944Z,1692894858.944 [PowerOnly](INFO): Adding load control power supply at /dev/loadB1
2023-08-24T16:34:18.956Z,1692894858.956 [PowerOnly] Loaded
2023-08-24T16:34:18.956Z,1692894858.956 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2023-08-24T16:34:18.968Z,1692894858.968 [Power24vConverter] Loaded
2023-08-24T16:34:18.968Z,1692894858.968 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-08-24T16:34:18.981Z,1692894858.981 [Radio_Surface] Loaded
2023-08-24T16:34:18.982Z,1692894858.982 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-08-24T16:34:18.983Z,1692894858.983 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A1F4E0
2023-08-24T16:34:18.983Z,1692894858.983 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 928
2023-08-24T16:34:19.029Z,1692894859.029 [RDI_Pathfinder] Loaded
2023-08-24T16:34:19.029Z,1692894859.029 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2023-08-24T16:34:19.124Z,1692894859.124 [DAT] Loaded
2023-08-24T16:34:19.124Z,1692894859.124 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2023-08-24T16:34:19.125Z,1692894859.125 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A4F4E0
2023-08-24T16:34:19.126Z,1692894859.126 [DAT ThreadHandler](INFO): Protected caller Thread ID is 929
2023-08-24T16:34:19.126Z,1692894859.126 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-08-24T16:34:19.127Z,1692894859.127 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-08-24T16:34:19.267Z,1692894859.267 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-08-24T16:34:19.267Z,1692894859.267 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-08-24T16:34:19.282Z,1692894859.282 [NavChart] Loaded
2023-08-24T16:34:19.282Z,1692894859.282 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-08-24T16:34:19.288Z,1692894859.288 [UniversalFixResidualReporter] Loaded
2023-08-24T16:34:19.288Z,1692894859.288 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-08-24T16:34:19.289Z,1692894859.289 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-08-24T16:34:19.291Z,1692894859.291 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-08-24T16:34:19.469Z,1692894859.469 [SBIT](DEBUG): Construct Startup Built In Test.
2023-08-24T16:34:19.478Z,1692894859.478 [SBIT] Loaded
2023-08-24T16:34:19.479Z,1692894859.479 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-08-24T16:34:19.482Z,1692894859.482 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-08-24T16:34:19.494Z,1692894859.494 [IBIT] Loaded
2023-08-24T16:34:19.495Z,1692894859.495 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-08-24T16:34:19.501Z,1692894859.501 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-08-24T16:34:19.608Z,1692894859.608 [CBIT] Loaded
2023-08-24T16:34:19.609Z,1692894859.609 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-08-24T16:34:19.609Z,1692894859.609 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-08-24T16:34:19.610Z,1692894859.610 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-08-24T16:34:19.773Z,1692894859.773 [BuoyancyServo] Loaded
2023-08-24T16:34:19.774Z,1692894859.774 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-08-24T16:34:19.795Z,1692894859.795 [ElevatorServo] Loaded
2023-08-24T16:34:19.795Z,1692894859.795 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-08-24T16:34:19.815Z,1692894859.815 [MassServo] Loaded
2023-08-24T16:34:19.815Z,1692894859.815 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-08-24T16:34:19.834Z,1692894859.834 [RudderServo] Loaded
2023-08-24T16:34:19.835Z,1692894859.835 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-08-24T16:34:19.853Z,1692894859.853 [ThrusterServo] Loaded
2023-08-24T16:34:19.853Z,1692894859.853 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-08-24T16:34:19.854Z,1692894859.854 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-08-24T16:34:19.854Z,1692894859.854 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-08-24T16:34:19.870Z,1692894859.870 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-08-24T16:34:19.871Z,1692894859.871 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-08-24T16:34:20.101Z,1692894860.101 [CTD_Seabird] Loaded
2023-08-24T16:34:20.101Z,1692894860.101 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-08-24T16:34:20.102Z,1692894860.102 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B9A4E0
2023-08-24T16:34:20.103Z,1692894860.103 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 930
2023-08-24T16:34:20.123Z,1692894860.123 [PAR_Licor] Loaded
2023-08-24T16:34:20.124Z,1692894860.124 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-08-24T16:34:20.166Z,1692894860.166 [WetLabsBB2FL] Loaded
2023-08-24T16:34:20.166Z,1692894860.166 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-08-24T16:34:20.167Z,1692894860.167 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BCA4E0
2023-08-24T16:34:20.168Z,1692894860.168 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 931
2023-08-24T16:34:20.188Z,1692894860.188 [WetLabsUBAT] Loaded
2023-08-24T16:34:20.188Z,1692894860.188 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2023-08-24T16:34:20.190Z,1692894860.190 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40BFA4E0
2023-08-24T16:34:20.190Z,1692894860.190 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 932
2023-08-24T16:34:20.190Z,1692894860.190 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-08-24T16:34:20.197Z,1692894860.197 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-08-24T16:34:20.200Z,1692894860.200 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-08-24T16:34:20.211Z,1692894860.211 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-08-24T16:34:20.212Z,1692894860.212 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C2A4E0
2023-08-24T16:34:20.212Z,1692894860.212 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 933
2023-08-24T16:34:20.217Z,1692894860.217 [Supervisor](INFO): Main Thread ID is 830
2023-08-24T16:34:20.217Z,1692894860.217 [Supervisor](DEBUG): Running supervisor.
2023-08-24T16:34:20.218Z,1692894860.218 [CommandExec ThreadHandler](INFO): Handler Thread ID is 934
2023-08-24T16:34:20.218Z,1692894860.218 [CommandExec](INFO): Initializing the command executive.
2023-08-24T16:34:20.220Z,1692894860.220 [CommandLine ThreadHandler](INFO): Handler Thread ID is 935
2023-08-24T16:34:20.222Z,1692894860.222 [controlThread ThreadHandler](INFO): Handler Thread ID is 936
2023-08-24T16:34:20.222Z,1692894860.222 [controlThread](DEBUG): Initializing ControlThread
2023-08-24T16:34:20.223Z,1692894860.223 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-08-24T16:34:20.225Z,1692894860.225 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-08-24T16:34:20.226Z,1692894860.226 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-08-24T16:34:20.226Z,1692894860.226 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-08-24T16:34:20.227Z,1692894860.227 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-08-24T16:34:20.227Z,1692894860.227 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-08-24T16:34:20.227Z,1692894860.227 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-08-24T16:34:20.228Z,1692894860.228 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-08-24T16:34:20.228Z,1692894860.228 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-08-24T16:34:20.236Z,1692894860.236 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-08-24T16:34:20.236Z,1692894860.236 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-08-24T16:34:20.236Z,1692894860.236 [SBIT](INFO): Initialize SBIT Component.
2023-08-24T16:34:20.237Z,1692894860.237 [SBIT](IMPORTANT): git: 2023-08-02-8-ga2b2183f7
2023-08-24T16:34:20.237Z,1692894860.237 [SBIT](INFO): git hash: a2b2183f71e07b707bfeaaf571c74c3561721119
2023-08-24T16:34:20.237Z,1692894860.237 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-08-24T16:34:20.239Z,1692894860.239 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-08-24T16:34:20.240Z,1692894860.240 [SBIT](INFO): Beginning SBIT in 49.000000 seconds.
2023-08-24T16:34:20.240Z,1692894860.240 [IBIT](INFO): Initialize IBIT Component.
2023-08-24T16:34:20.241Z,1692894860.241 [CBIT](DEBUG): Initialize CBIT Component.
2023-08-24T16:34:20.242Z,1692894860.242 [logger ThreadHandler](INFO): Handler Thread ID is 937
2023-08-24T16:34:20.253Z,1692894860.253 [CBIT](DEBUG): Initialized mux pins.
2023-08-24T16:34:20.253Z,1692894860.253 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2023-08-24T16:34:20.253Z,1692894860.253 [CBIT](DEBUG): Initializing the watchdog timer.
2023-08-24T16:34:20.261Z,1692894860.261 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 938
2023-08-24T16:34:20.273Z,1692894860.273 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 939
2023-08-24T16:34:20.274Z,1692894860.274 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-08-24T16:34:20.277Z,1692894860.277 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-08-24T16:34:20.278Z,1692894860.278 [CBIT](DEBUG): Initializing heartbeat.
2023-08-24T16:34:20.285Z,1692894860.285 [Onboard ThreadHandler](INFO): Handler Thread ID is 940
2023-08-24T16:34:20.303Z,1692894860.303 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 941
2023-08-24T16:34:20.322Z,1692894860.322 [DAT ThreadHandler](INFO): Handler Thread ID is 942
2023-08-24T16:34:20.323Z,1692894860.323 [DAT](INFO): Powering up
2023-08-24T16:34:20.323Z,1692894860.323 [DAT](DEBUG): Initializing DAT.
2023-08-24T16:34:20.326Z,1692894860.326 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 944
2023-08-24T16:34:20.330Z,1692894860.330 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-08-24T16:34:20.334Z,1692894860.334 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 946
2023-08-24T16:34:20.338Z,1692894860.338 [WetLabsBB2FL](INFO): Powering up
2023-08-24T16:34:20.339Z,1692894860.339 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 948
2023-08-24T16:34:20.342Z,1692894860.342 [WetLabsUBAT](INFO): Powering up
2023-08-24T16:34:20.343Z,1692894860.343 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 950
2023-08-24T16:34:20.349Z,1692894860.349 [CBIT](DEBUG): Deactivating GF circuits.
2023-08-24T16:34:20.349Z,1692894860.349 [CBIT](DEBUG): Deactivating emergency mode.
2023-08-24T16:34:20.352Z,1692894860.352 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-08-24T16:34:20.352Z,1692894860.352 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-08-24T16:34:20.352Z,1692894860.352 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-08-24T16:34:20.352Z,1692894860.352 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-08-24T16:34:20.353Z,1692894860.353 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-08-24T16:34:20.353Z,1692894860.353 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-08-24T16:34:20.353Z,1692894860.353 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-08-24T16:34:20.353Z,1692894860.353 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-08-24T16:34:20.389Z,1692894860.389 [CBIT](DEBUG): Backplane powered.
2023-08-24T16:34:20.394Z,1692894860.394 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-08-24T16:34:20.434Z,1692894860.434 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-08-24T16:34:20.466Z,1692894860.466 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2023-08-24T16:34:20.466Z,1692894860.466 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-08-24T16:34:20.547Z,1692894860.547 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-08-24T16:34:20.549Z,1692894860.549 [Default:A.Wait](DEBUG): Construct Wait.
2023-08-24T16:34:20.567Z,1692894860.567 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-08-24T16:34:20.590Z,1692894860.590 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-08-24T16:34:20.593Z,1692894860.593 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-08-24T16:34:20.619Z,1692894860.619 [Default:E.Execute](DEBUG): Construct Execute.
2023-08-24T16:34:20.635Z,1692894860.635 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2023-08-24T16:34:20.639Z,1692894860.639 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,RDI_Pathfinder,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-08-24T16:34:20.677Z,1692894860.677 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-08-24T16:34:20.725Z,1692894860.725 [Radio_Surface](INFO): Powering up
2023-08-24T16:34:20.891Z,1692894860.891 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993016 m
2023-08-24T16:34:20.892Z,1692894860.892 [PowerOnly](INFO): Powering up loadControl
2023-08-24T16:34:20.910Z,1692894860.910 [Power24vConverter](INFO): Powering up.
2023-08-24T16:34:20.911Z,1692894860.911 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2023-08-24T16:34:20.919Z,1692894860.919 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-08-24T16:34:20.958Z,1692894860.958 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-08-24T16:34:20.965Z,1692894860.965 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-08-24T16:34:20.966Z,1692894860.966 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-08-24T16:34:20.973Z,1692894860.973 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-08-24T16:34:20.974Z,1692894860.974 [MassServo](DEBUG): Initializing EZServoServo.
2023-08-24T16:34:20.981Z,1692894860.981 [MassServo](DEBUG): Initializing MassServo.
2023-08-24T16:34:20.982Z,1692894860.982 [RudderServo](DEBUG): Initializing EZServoServo.
2023-08-24T16:34:20.989Z,1692894860.989 [RudderServo](DEBUG): Initializing RudderServo.
2023-08-24T16:34:20.990Z,1692894860.990 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-08-24T16:34:20.997Z,1692894860.997 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-08-24T16:34:21.113Z,1692894861.113 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-08-24T16:34:21.113Z,1692894861.113 [DropWeight] Hardware Fault, FailCount= 1
2023-08-24T16:34:21.113Z,1692894861.114 [DropWeight](ERROR): Hardware Fault
2023-08-24T16:34:21.144Z,1692894861.144 [CommandExec](FAULT): Scheduling is paused
2023-08-24T16:34:21.144Z,1692894861.144 [CBIT](INFO): Critical error at 20230824T163421
2023-08-24T16:34:21.145Z,1692894861.145 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-08-24T16:34:21.165Z,1692894861.165 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-08-24T16:34:21.165Z,1692894861.165 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-08-24T16:34:21.475Z,1692894861.475 [CBIT](INFO): Critical error at 20230824T163421
2023-08-24T16:34:22.949Z,1692894862.949 [WetLabsBB2FL](INFO): Powering down
2023-08-24T16:34:29.669Z,1692894869.669 [WetLabsUBAT](INFO): Powering down
2023-08-24T16:34:33.181Z,1692894873.181 [DAT](INFO): DAT read: 

2023-08-24T16:34:33.183Z,1692894873.183 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-08-24T16:34:34.946Z,1692894874.946 [DAT](INFO): DAT read: MF Frequency Band

2023-08-24T16:34:34.947Z,1692894874.947 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-08-24T16:34:34.948Z,1692894874.948 [DAT](INFO): DAT read: Aug 23 2023 22:41:19 

2023-08-24T16:34:35.954Z,1692894875.954 [DAT](INFO): DAT read: Features enabled [Bearing]

2023-08-24T16:34:35.955Z,1692894875.955 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-08-24T16:34:35.956Z,1692894875.956 [DAT](INFO): commRate: 800
2023-08-24T16:34:38.021Z,1692894878.021 [DAT](INFO): entering command mode
2023-08-24T16:34:38.222Z,1692894878.222 [DAT](INFO): DAT read: 

2023-08-24T16:34:38.222Z,1692894878.222 [DAT](INFO): DAT read: user:1>

2023-08-24T16:34:38.223Z,1692894878.223 [DAT](INFO): setting verbose to 3
2023-08-24T16:34:38.475Z,1692894878.475 [DAT](INFO): DAT read: user:1>

2023-08-24T16:34:38.483Z,1692894878.483 [DAT](INFO): DAT read: Verbose         | 3           

2023-08-24T16:34:38.484Z,1692894878.484 [DAT](INFO): set verbose to 3
2023-08-24T16:34:38.485Z,1692894878.485 [DAT](INFO): setting DatVerbose to 27440
2023-08-24T16:34:38.726Z,1692894878.726 [DAT](INFO): DAT read: user:2>

2023-08-24T16:34:38.727Z,1692894878.727 [DAT](INFO): DAT read: DatVerbose      | 27440       

2023-08-24T16:34:38.727Z,1692894878.727 [DAT](INFO): set DatVerbose to 27440
2023-08-24T16:34:38.727Z,1692894878.727 [DAT](INFO): setting transmit power to 8
2023-08-24T16:34:38.978Z,1692894878.978 [DAT](INFO): DAT read: user:3>

2023-08-24T16:34:38.979Z,1692894878.979 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2023-08-24T16:34:38.979Z,1692894878.979 [DAT](INFO): set transmit power to 8
2023-08-24T16:34:38.979Z,1692894878.979 [DAT](INFO): setting local address to 10
2023-08-24T16:34:39.230Z,1692894879.230 [DAT](INFO): DAT read: user:4>

2023-08-24T16:34:39.231Z,1692894879.231 [DAT](INFO): DAT read: LocalAddr       | 10          

2023-08-24T16:34:39.231Z,1692894879.231 [DAT](INFO): set local address to 10
2023-08-24T16:34:39.232Z,1692894879.232 [DAT](INFO): Setting time to: 16:34:39 And date to:8/24/2023
2023-08-24T16:34:39.483Z,1692894879.483 [DAT](INFO): DAT read: user:5>

2023-08-24T16:34:39.487Z,1692894879.487 [DAT](INFO): DAT read: Thu Aug 24, 2023  16:34:39  

2023-08-24T16:34:39.488Z,1692894879.488 [DAT](INFO): Local DAT time set to Thu Aug 24, 2023  16:34:39  

2023-08-24T16:34:48.947Z,1692894888.947 [NAL9602](INFO): Powering up NAL9602
2023-08-24T16:34:59.842Z,1692894899.842 [NAL9602](INFO): NAL9602 initialized
2023-08-24T16:35:10.043Z,1692894910.043 [SBIT](IMPORTANT): Beginning Startup BIT
2023-08-24T16:35:10.052Z,1692894910.052 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-24T16:35:18.059Z,1692894918.059 [NAL9602](INFO): SBD MO Status=2, MOMSN=30494, MT Status=2, MTMSN=0
2023-08-24T16:35:18.059Z,1692894918.059 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-24T16:35:21.358Z,1692894921.358 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.010738
CHAN A1 (24V): 0.067770
CHAN A2 (12V): 0.008496
CHAN A3 (5V): 0.004202
CHAN B0 (3.3V): 0.005358
CHAN B1 (3.15aV): 0.004057
CHAN B2 (3.15bV): 0.004057
CHAN B3 (GND): 0.001797
OPEN: -0.000700
Full Scale: +/- 1 mA
2023-08-24T16:36:02.884Z,1692894962.884 [NAL9602](INFO): SBD MO Status=2, MOMSN=30494, MT Status=2, MTMSN=0
2023-08-24T16:36:02.884Z,1692894962.884 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-24T16:36:04.128Z,1692894964.128 [SBIT](IMPORTANT): SBIT PASSED
2023-08-24T16:36:04.128Z,1692894964.128 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2023-08-24T16:36:04.495Z,1692894964.495 [MissionManager](IMPORTANT): Started mission Startup
2023-08-24T16:36:04.495Z,1692894964.495 [Startup] Running Loop=1
2023-08-24T16:36:04.495Z,1692894964.495 [Startup](DEBUG): Aggregate::initialize Startup
2023-08-24T16:36:04.495Z,1692894964.495 [Startup:A.GoToSurface] Running Loop=1
2023-08-24T16:36:04.495Z,1692894964.495 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-08-24T16:36:04.496Z,1692894964.496 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-08-24T16:36:04.496Z,1692894964.496 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-08-24T16:36:04.497Z,1692894964.497 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-08-24T16:36:04.497Z,1692894964.497 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-08-24T16:36:04.498Z,1692894964.498 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-08-24T16:36:04.499Z,1692894964.499 [Startup:StartupSatComms] Running Loop=1
2023-08-24T16:36:04.499Z,1692894964.499 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-08-24T16:36:04.499Z,1692894964.499 [Startup:StartupSatComms:A] Running Loop=1
2023-08-24T16:36:04.916Z,1692894964.916 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-08-24T16:36:15.820Z,1692894975.820 [NAL9602](INFO): SBD MO Status=2, MOMSN=30494, MT Status=2, MTMSN=0
2023-08-24T16:36:15.820Z,1692894975.820 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-24T16:37:04.729Z,1692895024.729 [Startup:StartupSatComms:A](INFO): Timed out from 2023-08-24T16:36:04.5Z
2023-08-24T16:37:04.729Z,1692895024.729 [Startup:StartupSatComms:A] Stopped
2023-08-24T16:37:04.729Z,1692895024.729 [Startup:StartupSatComms:B] Running Loop=1
2023-08-24T16:37:05.096Z,1692895025.096 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-08-24T16:37:20.473Z,1692895040.473 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-08-24T16:37:20.473Z,1692895040.473 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-08-24T16:37:20.492Z,1692895040.492 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-08-24T16:37:20.927Z,1692895040.927 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-08-24T16:37:20.927Z,1692895040.927 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-08-24T16:37:55.760Z,1692895075.760 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004456
2023-08-24T16:38:00.852Z,1692895080.852 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD +8.17,        +9.46,        +4.87,   9.62,  0.00
2023-08-24T16:38:03.398Z,1692895083.398 [DataOverHttps](INFO): Sending 240 bytes from file Logs/20230823T221934/Courier0000.lzma
2023-08-24T16:38:04.399Z,1692895084.399 [DataOverHttps](INFO): Moved sent file to Logs/20230823T221934/Courier0000.lzma.bak
2023-08-24T16:38:04.399Z,1692895084.399 [DataOverHttps](INFO): SBD MOMSN=18589942
2023-08-24T16:38:04.912Z,1692895084.912 [Startup:StartupSatComms:B](INFO): Timed out from 2023-08-24T16:37:04.7Z
2023-08-24T16:38:04.912Z,1692895084.912 [Startup:StartupSatComms:B] Stopped
2023-08-24T16:38:04.912Z,1692895084.912 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-08-24T16:38:04.912Z,1692895084.912 [Startup:StartupSatComms] Stopped
2023-08-24T16:38:04.912Z,1692895084.912 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-08-24T16:38:04.913Z,1692895084.913 [Startup](INFO): Completed Startup
2023-08-24T16:38:04.913Z,1692895084.913 [MissionManager](INFO): Startup is completed.
2023-08-24T16:38:04.913Z,1692895084.913 [MissionManager](INFO): Uninitializing Mission Startup
2023-08-24T16:38:04.914Z,1692895084.914 [Startup] Stopped
2023-08-24T16:38:04.914Z,1692895084.914 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-08-24T16:38:04.914Z,1692895084.914 [Startup:A.GoToSurface] Stopped
2023-08-24T16:38:04.914Z,1692895084.914 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-08-24T16:38:05.290Z,1692895085.290 [MissionManager](IMPORTANT): Started mission Default
2023-08-24T16:38:05.290Z,1692895085.290 [Default] Running Loop=1
2023-08-24T16:38:05.290Z,1692895085.290 [Default](DEBUG): Aggregate::initialize Default
2023-08-24T16:38:05.290Z,1692895085.290 [Default:B.GoToSurface] Running Loop=1
2023-08-24T16:38:05.290Z,1692895085.290 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-08-24T16:38:05.290Z,1692895085.290 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-08-24T16:38:05.291Z,1692895085.291 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-08-24T16:38:05.291Z,1692895085.291 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-08-24T16:38:05.291Z,1692895085.291 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-08-24T16:38:05.292Z,1692895085.292 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-08-24T16:38:05.292Z,1692895085.292 [Default:A.Wait] Running Loop=1
2023-08-24T16:38:05.292Z,1692895085.292 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-08-24T16:38:18.624Z,1692895098.624 [Default:A.Wait](INFO): Done Waiting.
2023-08-24T16:38:18.624Z,1692895098.624 [Default:A.Wait] Stopped
2023-08-24T16:38:18.624Z,1692895098.624 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-08-24T16:38:19.100Z,1692895099.100 [Default:CheckIn] Running Loop=1
2023-08-24T16:38:19.101Z,1692895099.101 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-24T16:38:19.101Z,1692895099.101 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-24T16:38:19.462Z,1692895099.462 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-08-24T16:39:50.739Z,1692895190.739 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS,    +6,    +9, 
2023-08-24T16:40:03.247Z,1692895203.247 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-08-24T16:40:09.309Z,1692895209.309 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164011.00,A,3648.16633,N,12147.28578,W,0.078,0.00,240823,,,A*76
2023-08-24T16:40:09.312Z,1692895209.312 [NAL9602](INFO): GPS fix at 20230824T164011: (36.802772, -121.788096)
2023-08-24T16:40:09.347Z,1692895209.347 [Default:CheckIn:Read_GPS] Stopped
2023-08-24T16:40:09.347Z,1692895209.347 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-24T16:40:09.832Z,1692895209.832 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-08-24T16:40:23.652Z,1692895223.652 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230823T221934/Courier0004.lzma
2023-08-24T16:40:24.655Z,1692895224.655 [DataOverHttps](INFO): Moved sent file to Logs/20230823T221934/Courier0004.lzma.bak
2023-08-24T16:40:24.655Z,1692895224.655 [DataOverHttps](INFO): SBD MOMSN=18589947
2023-08-24T16:40:41.362Z,1692895241.362 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-24T16:40:43.337Z,1692895243.337 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20230824T162806/Courier0000.lzma
2023-08-24T16:40:44.334Z,1692895244.334 [DataOverHttps](INFO): Moved sent file to Logs/20230824T162806/Courier0000.lzma.bak
2023-08-24T16:40:44.334Z,1692895244.334 [DataOverHttps](INFO): SBD MOMSN=18589949
2023-08-24T16:41:01.324Z,1692895261.324 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230824T162806/Courier0004.lzma
2023-08-24T16:41:02.327Z,1692895262.327 [DataOverHttps](INFO): Moved sent file to Logs/20230824T162806/Courier0004.lzma.bak
2023-08-24T16:41:02.327Z,1692895262.327 [DataOverHttps](INFO): SBD MOMSN=18589955
2023-08-24T16:41:19.773Z,1692895279.773 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20230824T163408/Courier0000.lzma
2023-08-24T16:41:20.779Z,1692895280.779 [DataOverHttps](INFO): Moved sent file to Logs/20230824T163408/Courier0000.lzma.bak
2023-08-24T16:41:20.779Z,1692895280.779 [DataOverHttps](INFO): SBD MOMSN=18589957
2023-08-24T16:41:29.639Z,1692895289.639 [DataOverHttps](IMPORTANT): SBD MTMSN=20230824T164131
2023-08-24T16:41:37.494Z,1692895297.494 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20230824T163408/Courier0004.lzma
2023-08-24T16:41:37.497Z,1692895297.497 [DataOverHttps](INFO): Received command: load Maintenance/tank_ballast_and_trim.tl;set ballast_and_trim.Depth1 12 m;set ballast_and_trim.SettleTime 120 min;run
2023-08-24T16:41:37.600Z,1692895297.600 [CommandExec](IMPORTANT): got command load ./Missions/Maintenance/tank_ballast_and_trim.tl
2023-08-24T16:41:37.600Z,1692895297.600 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/tank_ballast_and_trim.tl
2023-08-24T16:41:37.601Z,1692895297.601 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Maintenance/tank_ballast_and_trim.tx
2023-08-24T16:41:37.701Z,1692895297.701 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 120.000000 min
2023-08-24T16:41:37.704Z,1692895297.704 [MissionManager](INFO): DefineArg ballast_and_trim.SkipComms = 0 bool
2023-08-24T16:41:37.707Z,1692895297.707 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 7.000000 m
2023-08-24T16:41:37.709Z,1692895297.709 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m
2023-08-24T16:41:37.712Z,1692895297.712 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 0.100000 m
2023-08-24T16:41:37.715Z,1692895297.715 [MissionManager](INFO): DefineArg ballast_and_trim.SurfaceThreshold = 2.500000 m
2023-08-24T16:41:37.718Z,1692895297.718 [MissionManager](INFO): DefineArg ballast_and_trim.MaxWaitOnSurface = 20.000000 min
2023-08-24T16:41:37.721Z,1692895297.721 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min
2023-08-24T16:41:37.723Z,1692895297.723 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min
2023-08-24T16:41:37.726Z,1692895297.726 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min
2023-08-24T16:41:37.729Z,1692895297.729 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm
2023-08-24T16:41:37.732Z,1692895297.732 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc
2023-08-24T16:41:37.735Z,1692895297.735 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900002 %
2023-08-24T16:41:37.737Z,1692895297.737 [MissionManager](INFO): DefineArg ballast_and_trim.WaitToFloat = 5.000000 min
2023-08-24T16:41:37.738Z,1692895297.738 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.tl
2023-08-24T16:41:37.738Z,1692895297.738 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/Science.tx
2023-08-24T16:41:38.248Z,1692895298.248 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool
2023-08-24T16:41:38.251Z,1692895298.251 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min
2023-08-24T16:41:38.254Z,1692895298.254 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool
2023-08-24T16:41:38.257Z,1692895298.257 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestSaltPeakReportActive = 0 bool
2023-08-24T16:41:38.259Z,1692895298.259 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestOilPeakReportActive = 0 bool
2023-08-24T16:41:38.310Z,1692895298.310 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool
2023-08-24T16:41:38.313Z,1692895298.313 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DetectTrough = 0 bool
2023-08-24T16:41:38.325Z,1692895298.325 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count
2023-08-24T16:41:38.336Z,1692895298.336 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count
2023-08-24T16:41:38.343Z,1692895298.343 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 %
2023-08-24T16:41:38.358Z,1692895298.358 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool
2023-08-24T16:41:38.360Z,1692895298.360 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool
2023-08-24T16:41:38.367Z,1692895298.367 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool
2023-08-24T16:41:38.376Z,1692895298.376 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectSalinityActive = 0 bool
2023-08-24T16:41:38.387Z,1692895298.387 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2023-08-24T16:41:38.395Z,1692895298.395 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0 bool
2023-08-24T16:41:38.399Z,1692895298.399 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0 bool
2023-08-24T16:41:38.410Z,1692895298.410 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 1 bool
2023-08-24T16:41:38.418Z,1692895298.418 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 1 bool
2023-08-24T16:41:38.425Z,1692895298.425 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool
2023-08-24T16:41:38.429Z,1692895298.429 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 1 bool
2023-08-24T16:41:38.436Z,1692895298.436 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count
2023-08-24T16:41:38.439Z,1692895298.439 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count
2023-08-24T16:41:38.458Z,1692895298.458 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakShallowBound = nan m
2023-08-24T16:41:38.461Z,1692895298.461 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDeepBound = nan m
2023-08-24T16:41:38.468Z,1692895298.468 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2023-08-24T16:41:38.475Z,1692895298.475 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlThreshTriggerActive = 0 bool
2023-08-24T16:41:38.482Z,1692895298.482 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlLowerThresh = nan ug/l
2023-08-24T16:41:38.487Z,1692895298.487 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlUpperThresh = nan ug/l
2023-08-24T16:41:38.490Z,1692895298.490 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesThresh = 5.000000 count
2023-08-24T16:41:38.492Z,1692895298.492 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = nan ug/l
2023-08-24T16:41:38.520Z,1692895298.520 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = nan m
2023-08-24T16:41:38.531Z,1692895298.531 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = nan degC
2023-08-24T16:41:38.542Z,1692895298.542 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg
2023-08-24T16:41:38.547Z,1692895298.547 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg
2023-08-24T16:41:38.554Z,1692895298.554 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = nan umol/l
2023-08-24T16:41:38.565Z,1692895298.565 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = nan m
2023-08-24T16:41:38.568Z,1692895298.568 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg
2023-08-24T16:41:38.579Z,1692895298.579 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg
2023-08-24T16:41:38.590Z,1692895298.590 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = nan kg/m3
2023-08-24T16:41:38.601Z,1692895298.601 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = nan m
2023-08-24T16:41:38.604Z,1692895298.604 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg
2023-08-24T16:41:38.620Z,1692895298.620 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg
2023-08-24T16:41:38.627Z,1692895298.627 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = nan ppb
2023-08-24T16:41:38.646Z,1692895298.646 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = nan m
2023-08-24T16:41:38.649Z,1692895298.649 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMTemperature = nan degC
2023-08-24T16:41:38.656Z,1692895298.656 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg
2023-08-24T16:41:38.663Z,1692895298.663 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg
2023-08-24T16:41:38.734Z,1692895298.734 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchFDOMDistance = nan m
2023-08-24T16:41:38.757Z,1692895298.757 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSalt = nan psu
2023-08-24T16:41:38.764Z,1692895298.764 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltDepth = nan m
2023-08-24T16:41:38.775Z,1692895298.775 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltTemperature = nan degC
2023-08-24T16:41:38.782Z,1692895298.782 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLatitude = nan arcdeg
2023-08-24T16:41:38.798Z,1692895298.798 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLongitude = nan arcdeg
2023-08-24T16:41:38.801Z,1692895298.801 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = nan ug/l
2023-08-24T16:41:38.824Z,1692895298.824 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = nan m
2023-08-24T16:41:38.836Z,1692895298.836 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg
2023-08-24T16:41:38.843Z,1692895298.843 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg
2023-08-24T16:41:38.866Z,1692895298.866 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m
2023-08-24T16:41:38.877Z,1692895298.877 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSalt = nan psu
2023-08-24T16:41:38.880Z,1692895298.880 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDepth = nan m
2023-08-24T16:41:38.895Z,1692895298.895 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLatitude = nan arcdeg
2023-08-24T16:41:38.902Z,1692895298.902 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLongitude = nan arcdeg
2023-08-24T16:41:38.914Z,1692895298.914 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDistance = nan m
2023-08-24T16:41:38.925Z,1692895298.925 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOil = nan psu
2023-08-24T16:41:38.928Z,1692895298.928 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDepth = nan m
2023-08-24T16:41:38.935Z,1692895298.935 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLatitude = nan arcdeg
2023-08-24T16:41:38.954Z,1692895298.954 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLongitude = nan arcdeg
2023-08-24T16:41:38.961Z,1692895298.961 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDistance = nan m
2023-08-24T16:41:38.964Z,1692895298.964 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.TriggeredOnChl = 0 bool
2023-08-24T16:41:38.991Z,1692895298.991 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.ChlTriggering = nan ug/l
2023-08-24T16:41:39.022Z,1692895299.022 [DataOverHttps](INFO): Moved sent file to Logs/20230824T163408/Courier0004.lzma.bak
2023-08-24T16:41:39.022Z,1692895299.022 [DataOverHttps](INFO): SBD MOMSN=18589963
2023-08-24T16:41:39.119Z,1692895299.119 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-08-24T16:41:39.218Z,1692895299.218 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-08-24T16:41:39.254Z,1692895299.254 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-08-24T16:41:39.273Z,1692895299.273 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-08-24T16:41:39.300Z,1692895299.300 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-08-24T16:41:39.330Z,1692895299.330 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-08-24T16:41:39.355Z,1692895299.355 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-08-24T16:41:39.385Z,1692895299.385 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-08-24T16:41:39.411Z,1692895299.411 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Construct ValueDetect.
2023-08-24T16:41:39.422Z,1692895299.422 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2023-08-24T16:41:39.423Z,1692895299.423 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BackseatDriver.tx
2023-08-24T16:41:39.448Z,1692895299.448 [MissionManager](INFO): DefineArg ballast_and_trim:BackseatDriver.EnableBackseat = 0 bool
2023-08-24T16:41:39.450Z,1692895299.450 [ballast_and_trim:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2023-08-24T16:41:39.458Z,1692895299.458 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BallastAndTrim.tl
2023-08-24T16:41:39.459Z,1692895299.459 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BallastAndTrim.tx
2023-08-24T16:41:39.831Z,1692895299.831 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepth = 25.000000 m
2023-08-24T16:41:39.846Z,1692895299.846 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s
2023-08-24T16:41:39.849Z,1692895299.849 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s
2023-08-24T16:41:39.856Z,1692895299.856 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg
2023-08-24T16:41:39.859Z,1692895299.859 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min
2023-08-24T16:41:39.870Z,1692895299.870 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min
2023-08-24T16:41:39.910Z,1692895299.910 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = 10.000000 min
2023-08-24T16:41:39.913Z,1692895299.913 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = 45.000000 min
2023-08-24T16:41:39.930Z,1692895299.930 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = 10.000000 min
2023-08-24T16:41:39.936Z,1692895299.936 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2023-08-24T16:41:39.940Z,1692895299.940 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2023-08-24T16:41:39.951Z,1692895299.951 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = 99.900002 %
2023-08-24T16:41:39.963Z,1692895299.963 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2023-08-24T16:41:39.967Z,1692895299.967 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = 0.250000 mm
2023-08-24T16:41:40.002Z,1692895300.002 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct.
2023-08-24T16:41:40.021Z,1692895300.021 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy.
2023-08-24T16:41:40.032Z,1692895300.032 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct.
2023-08-24T16:41:40.044Z,1692895300.044 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait.
2023-08-24T16:41:40.056Z,1692895300.056 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct.
2023-08-24T16:41:40.064Z,1692895300.064 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct.
2023-08-24T16:41:40.092Z,1692895300.092 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2023-08-24T16:41:40.107Z,1692895300.107 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct.
2023-08-24T16:41:40.111Z,1692895300.111 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct.
2023-08-24T16:41:40.162Z,1692895300.162 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait.
2023-08-24T16:41:40.207Z,1692895300.207 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](DEBUG): Construct BallastAndTrim.
2023-08-24T16:41:40.243Z,1692895300.243 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband
2023-08-24T16:41:40.245Z,1692895300.245 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = reader:ballast_and_trim.MaxWaitOnSurface
2023-08-24T16:41:40.247Z,1692895300.247 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime
2023-08-24T16:41:40.249Z,1692895300.249 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout
2023-08-24T16:41:40.270Z,1692895300.270 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime
2023-08-24T16:41:40.272Z,1692895300.272 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound
2023-08-24T16:41:40.273Z,1692895300.273 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound
2023-08-24T16:41:40.275Z,1692895300.275 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence
2023-08-24T16:41:40.347Z,1692895300.347 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy.
2023-08-24T16:41:40.359Z,1692895300.359 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Construct Wait.
2023-08-24T16:41:40.434Z,1692895300.434 [MissionManager](DEBUG): 
mission ballast_and_trim {
  arguments {
    MissionTimeout = 120 minute
      """
      Maximum duration of mission
      """

    SkipComms = false
      """
      Skip communications at start of mission.
      """

    Depth1 = 7 meter

    Depth2 = NaN meter

    DepthDeadband = 0.1 meter
      """
      How much vertical drift from the specified depth is allowed durnig the
      mission.
      """

    SurfaceThreshold = 2.5 meter
      """
      Surface threshold depth.
      """

    MaxWaitOnSurface = 20 minute
      """
      Maximum wait time for the vehicle to go under the surface (i.e., reach
      the surface threshold 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 duration=MissionTimeout

  insert Insert/Science.tl

  insert Insert/BackseatDriver.tl

  aggregate RunBallastAndTrim {
    run in sequence

    insert Insert/BallastAndTrim.tl {
      redefineArg InsertDepthDeadband = DepthDeadband
      redefineArg InsertApproachDepthTimeout = MaxWaitOnSurface
      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 = Depth1

      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-08-24T16:41:40.434Z,1692895300.434 [CommandExec](IMPORTANT): Loaded ./Missions/Maintenance/tank_ballast_and_trim.tl
2023-08-24T16:41:50.239Z,1692895310.239 [CommandExec](IMPORTANT): got command set ballast_and_trim.Depth1 12 meter
2023-08-24T16:41:50.240Z,1692895310.240 [CommandExec](IMPORTANT): got command set ballast_and_trim.SettleTime 120 minute
2023-08-24T16:41:50.241Z,1692895310.241 [CommandExec](IMPORTANT): got command run
2023-08-24T16:41:50.244Z,1692895310.244 [CommandExec](IMPORTANT): Running 
2023-08-24T16:41:50.438Z,1692895310.438 [Default] Stopped
2023-08-24T16:41:50.438Z,1692895310.438 [Default](DEBUG): Aggregate::uninitialize Default
2023-08-24T16:41:50.438Z,1692895310.438 [Default:B.GoToSurface] Stopped
2023-08-24T16:41:50.438Z,1692895310.438 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-08-24T16:41:50.438Z,1692895310.438 [Default:CheckIn] Stopped
2023-08-24T16:41:50.438Z,1692895310.438 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-24T16:41:50.439Z,1692895310.439 [Default:CheckIn:Read_Iridium] Stopped
2023-08-24T16:41:50.439Z,1692895310.439 [MissionManager](IMPORTANT): Started mission ballast_and_trim
2023-08-24T16:41:50.439Z,1692895310.439 [ballast_and_trim] Running Loop=1
2023-08-24T16:41:50.439Z,1692895310.439 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim
2023-08-24T16:41:50.439Z,1692895310.439 [ballast_and_trim:Science] Running Loop=1
2023-08-24T16:41:50.439Z,1692895310.439 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science
2023-08-24T16:41:50.439Z,1692895310.439 [ballast_and_trim:Science:A] Running Loop=1
2023-08-24T16:41:50.439Z,1692895310.439 [ballast_and_trim:Science:B] Running Loop=1
2023-08-24T16:41:50.439Z,1692895310.439 [ballast_and_trim:Science:C] Running Loop=1
2023-08-24T16:41:50.440Z,1692895310.440 [ballast_and_trim:Science:D] Running Loop=1
2023-08-24T16:41:50.440Z,1692895310.440 [ballast_and_trim:Science:E] Running Loop=1
2023-08-24T16:41:50.440Z,1692895310.440 [ballast_and_trim:Science:F] Running Loop=1
2023-08-24T16:41:50.440Z,1692895310.440 [ballast_and_trim:Science:Read_Oil] Running Loop=1
2023-08-24T16:41:50.440Z,1692895310.440 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1
2023-08-24T16:41:50.440Z,1692895310.440 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl
2023-08-24T16:41:50.440Z,1692895310.440 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2023-08-24T16:41:50.440Z,1692895310.440 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-08-24T16:41:50.442Z,1692895310.442 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1
2023-08-24T16:41:50.443Z,1692895310.443 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport
2023-08-24T16:41:50.443Z,1692895310.443 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-08-24T16:41:50.443Z,1692895310.443 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-08-24T16:41:50.443Z,1692895310.443 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1
2023-08-24T16:41:50.443Z,1692895310.443 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3
2023-08-24T16:41:50.443Z,1692895310.443 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2023-08-24T16:41:50.443Z,1692895310.443 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-08-24T16:41:50.446Z,1692895310.446 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1
2023-08-24T16:41:50.446Z,1692895310.446 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil
2023-08-24T16:41:50.446Z,1692895310.446 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2023-08-24T16:41:50.446Z,1692895310.446 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-08-24T16:41:50.448Z,1692895310.448 [ballast_and_trim:Science:HighestOilPeakReport] Running Loop=1
2023-08-24T16:41:50.448Z,1692895310.448 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestOilPeakReport
2023-08-24T16:41:50.448Z,1692895310.448 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-08-24T16:41:50.448Z,1692895310.448 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-08-24T16:41:50.448Z,1692895310.448 [ballast_and_trim:Science:PeakDetectFDOM] Running Loop=1
2023-08-24T16:41:50.448Z,1692895310.448 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectFDOM
2023-08-24T16:41:50.449Z,1692895310.449 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2023-08-24T16:41:50.449Z,1692895310.449 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-08-24T16:41:50.450Z,1692895310.450 [ballast_and_trim:Science:PeakDetectSalinity] Running Loop=1
2023-08-24T16:41:50.450Z,1692895310.450 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectSalinity
2023-08-24T16:41:50.451Z,1692895310.451 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2023-08-24T16:41:50.451Z,1692895310.451 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-08-24T16:41:50.452Z,1692895310.452 [ballast_and_trim:Science:HighestSaltPeakReport] Running Loop=1
2023-08-24T16:41:50.452Z,1692895310.452 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestSaltPeakReport
2023-08-24T16:41:50.453Z,1692895310.453 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-08-24T16:41:50.454Z,1692895310.454 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-08-24T16:41:50.454Z,1692895310.454 [ballast_and_trim:Science:ChlThreshTrigger] Running Loop=1
2023-08-24T16:41:50.454Z,1692895310.454 [ballast_and_trim:Science:ChlThreshTrigger](DEBUG): Aggregate::initialize ballast_and_trim:Science:ChlThreshTrigger
2023-08-24T16:41:50.454Z,1692895310.454 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect] Running Loop=1
2023-08-24T16:41:50.454Z,1692895310.454 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](INFO): Initialize.
2023-08-24T16:41:50.455Z,1692895310.455 [ballast_and_trim:Science:OceanCurrent] Running Loop=1
2023-08-24T16:41:50.455Z,1692895310.455 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::initialize ballast_and_trim:Science:OceanCurrent
2023-08-24T16:41:50.455Z,1692895310.455 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2023-08-24T16:41:50.455Z,1692895310.455 [ballast_and_trim:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2023-08-24T16:41:50.455Z,1692895310.455 [ballast_and_trim:BackseatDriver] Running Loop=1
2023-08-24T16:41:50.455Z,1692895310.455 [ballast_and_trim:BackseatDriver](DEBUG): Aggregate::initialize ballast_and_trim:BackseatDriver
2023-08-24T16:41:50.455Z,1692895310.455 [ballast_and_trim:BackseatDriver:A.BackseatDriver] Running Loop=1
2023-08-24T16:41:50.455Z,1692895310.455 [ballast_and_trim:RunBallastAndTrim] Running Loop=1
2023-08-24T16:41:50.456Z,1692895310.456 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim
2023-08-24T16:41:50.456Z,1692895310.456 [ballast_and_trim:RunBallastAndTrim:Depth1] Running Loop=1
2023-08-24T16:41:50.456Z,1692895310.456 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1
2023-08-24T16:41:50.456Z,1692895310.456 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Running Loop=1
2023-08-24T16:41:50.457Z,1692895310.457 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Stopped
2023-08-24T16:41:50.457Z,1692895310.457 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Running Loop=1
2023-08-24T16:41:50.473Z,1692895310.473 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2023-08-24T16:41:50.473Z,1692895310.473 [ballast_and_trim:BackseatDriver] Running Loop=1
2023-08-24T16:41:50.474Z,1692895310.474 [ballast_and_trim:Science] Running Loop=1
2023-08-24T16:41:50.474Z,1692895310.474 [ballast_and_trim:Science:ChlThreshTrigger] Stopped
2023-08-24T16:41:50.475Z,1692895310.475 [ballast_and_trim:Science:ChlThreshTrigger](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:ChlThreshTrigger
2023-08-24T16:41:50.475Z,1692895310.475 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect] Stopped
2023-08-24T16:41:50.475Z,1692895310.475 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Uninitialize.
2023-08-24T16:41:50.475Z,1692895310.475 [ballast_and_trim:Science:HighestSaltPeakReport] Stopped
2023-08-24T16:41:50.475Z,1692895310.475 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestSaltPeakReport
2023-08-24T16:41:50.475Z,1692895310.475 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2023-08-24T16:41:50.475Z,1692895310.475 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-08-24T16:41:50.475Z,1692895310.475 [ballast_and_trim:Science:PeakDetectSalinity] Stopped
2023-08-24T16:41:50.475Z,1692895310.475 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectSalinity
2023-08-24T16:41:50.475Z,1692895310.475 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2023-08-24T16:41:50.475Z,1692895310.475 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-08-24T16:41:50.475Z,1692895310.475 [ballast_and_trim:Science:PeakDetectFDOM] Stopped
2023-08-24T16:41:50.476Z,1692895310.476 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectFDOM
2023-08-24T16:41:50.476Z,1692895310.476 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2023-08-24T16:41:50.476Z,1692895310.476 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-08-24T16:41:50.476Z,1692895310.476 [ballast_and_trim:Science:HighestOilPeakReport] Stopped
2023-08-24T16:41:50.476Z,1692895310.476 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestOilPeakReport
2023-08-24T16:41:50.476Z,1692895310.476 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2023-08-24T16:41:50.476Z,1692895310.476 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-08-24T16:41:50.476Z,1692895310.476 [ballast_and_trim:Science:PeakDetectOil] Stopped
2023-08-24T16:41:50.476Z,1692895310.476 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil
2023-08-24T16:41:50.476Z,1692895310.476 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2023-08-24T16:41:50.476Z,1692895310.476 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-08-24T16:41:50.476Z,1692895310.476 [ballast_and_trim:Science:PeakDetectNO3] Stopped
2023-08-24T16:41:50.477Z,1692895310.477 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3
2023-08-24T16:41:50.477Z,1692895310.477 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2023-08-24T16:41:50.477Z,1692895310.477 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-08-24T16:41:50.481Z,1692895310.481 [ballast_and_trim:Science:HighestChlPeakReport] Stopped
2023-08-24T16:41:50.481Z,1692895310.481 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport
2023-08-24T16:41:50.481Z,1692895310.481 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2023-08-24T16:41:50.481Z,1692895310.481 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-08-24T16:41:50.481Z,1692895310.481 [ballast_and_trim:Science:PeakDetectChl] Stopped
2023-08-24T16:41:50.481Z,1692895310.481 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl
2023-08-24T16:41:50.481Z,1692895310.481 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2023-08-24T16:41:50.482Z,1692895310.482 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-08-24T16:41:50.482Z,1692895310.482 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2023-08-24T16:41:50.484Z,1692895310.484 [ballast_and_trim:Science:Read_Oil] Stopped
2023-08-24T16:41:50.484Z,1692895310.484 [ballast_and_trim:Science:F] Running Loop=1
2023-08-24T16:41:50.484Z,1692895310.484 [ballast_and_trim:Science:F](DEBUG): Initialize ReadDataComponent to sense WetLabsUBAT.average_bioluminescence
2023-08-24T16:41:50.484Z,1692895310.484 [ballast_and_trim:Science:E] Running Loop=1
2023-08-24T16:41:50.485Z,1692895310.485 [ballast_and_trim:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2023-08-24T16:41:50.486Z,1692895310.486 [ballast_and_trim:Science:D] Stopped
2023-08-24T16:41:50.486Z,1692895310.486 [ballast_and_trim:Science:C] Running Loop=1
2023-08-24T16:41:50.486Z,1692895310.486 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2023-08-24T16:41:50.487Z,1692895310.487 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2023-08-24T16:41:50.487Z,1692895310.487 [ballast_and_trim:Science:B] Running Loop=1
2023-08-24T16:41:50.488Z,1692895310.488 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2023-08-24T16:41:50.488Z,1692895310.488 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2023-08-24T16:41:50.488Z,1692895310.488 [ballast_and_trim:Science:A] Stopped
2023-08-24T16:41:50.611Z,1692895310.611 [WetLabsBB2FL](INFO): Powering up
2023-08-24T16:41:50.814Z,1692895310.814 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Running Loop=1
2023-08-24T16:41:50.814Z,1692895310.814 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2023-08-24T16:41:50.815Z,1692895310.815 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2023-08-24T16:41:50.815Z,1692895310.815 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2023-08-24T16:41:50.815Z,1692895310.815 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2023-08-24T16:41:50.815Z,1692895310.815 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2023-08-24T16:41:50.815Z,1692895310.815 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2023-08-24T16:41:50.815Z,1692895310.815 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1
2023-08-24T16:41:50.817Z,1692895310.817 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F](INFO): Going to target depth. Speed set to 0.000000 m/s 
2023-08-24T16:41:50.817Z,1692895310.817 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Stopped
2023-08-24T16:41:50.817Z,1692895310.817 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Running Loop=1
2023-08-24T16:41:50.817Z,1692895310.817 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2023-08-24T16:41:50.817Z,1692895310.817 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2023-08-24T16:41:50.817Z,1692895310.817 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1
2023-08-24T16:41:50.818Z,1692895310.818 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2023-08-24T16:41:50.818Z,1692895310.818 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1
2023-08-24T16:41:50.818Z,1692895310.818 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize.
2023-08-24T16:41:50.818Z,1692895310.818 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1
2023-08-24T16:41:50.818Z,1692895310.818 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2023-08-24T16:41:50.818Z,1692895310.818 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1
2023-08-24T16:41:50.818Z,1692895310.818 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize.
2023-08-24T16:41:50.818Z,1692895310.818 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1
2023-08-24T16:41:50.819Z,1692895310.819 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2023-08-24T16:41:50.819Z,1692895310.819 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1
2023-08-24T16:41:50.819Z,1692895310.819 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2023-08-24T16:41:50.819Z,1692895310.819 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2023-08-24T16:41:50.819Z,1692895310.819 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2023-08-24T16:41:50.819Z,1692895310.819 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2023-08-24T16:41:50.820Z,1692895310.820 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2023-08-24T16:41:50.986Z,1692895310.986 [WetLabsUBAT](INFO): Powering up
2023-08-24T16:41:51.231Z,1692895311.231 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A](INFO): Waiting for 0.000000 min while pumping down to neutral.
2023-08-24T16:41:51.231Z,1692895311.231 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped
2023-08-24T16:41:51.231Z,1692895311.231 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Running Loop=1
2023-08-24T16:41:51.231Z,1692895311.231 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Initialize Wait Component.
2023-08-24T16:41:51.232Z,1692895311.232 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped
2023-08-24T16:41:51.232Z,1692895311.232 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2023-08-24T16:41:51.232Z,1692895311.232 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped
2023-08-24T16:41:51.232Z,1692895311.232 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped
2023-08-24T16:41:51.232Z,1692895311.232 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2023-08-24T16:41:51.232Z,1692895311.232 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped
2023-08-24T16:41:51.232Z,1692895311.232 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2023-08-24T16:41:51.655Z,1692895311.655 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](INFO): Done Waiting.
2023-08-24T16:41:51.655Z,1692895311.655 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Stopped
2023-08-24T16:41:51.655Z,1692895311.655 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Uninitialize Wait Component.
2023-08-24T16:41:51.655Z,1692895311.655 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2023-08-24T16:41:51.655Z,1692895311.655 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped
2023-08-24T16:41:51.655Z,1692895311.655 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2023-08-24T16:41:51.656Z,1692895311.656 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1
2023-08-24T16:41:51.656Z,1692895311.656 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2023-08-24T16:41:51.656Z,1692895311.656 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2023-08-24T16:41:51.656Z,1692895311.656 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize.
2023-08-24T16:41:51.656Z,1692895311.656 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1
2023-08-24T16:41:52.030Z,1692895312.030 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2023-08-24T16:41:52.031Z,1692895312.031 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 12.000000 m 
2023-08-24T16:41:52.031Z,1692895312.031 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped
2023-08-24T16:41:52.031Z,1692895312.031 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1
2023-08-24T16:41:52.031Z,1692895312.031 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize.
2023-08-24T16:41:54.282Z,1692895314.282 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2023-08-24T16:42:01.179Z,1692895321.179 [WetLabsUBAT](FAULT): UBAT flow rate is below the specified threshold of 0.05 l/s.
2023-08-24T16:42:01.184Z,1692895321.184 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230824T163408/Courier0007.lzma
2023-08-24T16:42:01.611Z,1692895321.611 [DataOverHttps](INFO): Moved sent file to Logs/20230824T163408/Courier0007.lzma.bak
2023-08-24T16:42:01.612Z,1692895321.612 [DataOverHttps](INFO): SBD MOMSN=18589967
2023-08-24T16:42:27.992Z,1692895347.992 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-08-24T16:42:28.000Z,1692895348.000 [BPC1](INFO): Received data from all battery sticks.
2023-08-24T16:43:18.894Z,1692895398.894 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,23082408441951,35.0, +21.7,   0.0,1526.:RA,  0.00,   0.00,  99.34,  99.34,  97.79
2023-08-24T16:44:24.618Z,1692895464.618 [DataOverHttps](IMPORTANT): SBD MTMSN=20230824T164426
2023-08-24T16:44:37.780Z,1692895477.780 [DataOverHttps](INFO): Received command: configSet dropweight.loadAtStartup 0 bool persist;restart app
2023-08-24T16:44:38.111Z,1692895478.111 [CommandExec](IMPORTANT): got command configSet DropWeight.loadAtStartup 0 bool persist
2023-08-24T16:44:38.112Z,1692895478.112 [CommandExec](IMPORTANT): configSet DropWeight.loadAtStartup requires a restart to take effect.
2023-08-24T16:44:38.113Z,1692895478.113 [CommandExec](FAULT): Not allowing to overwrite existing Data/persisted.cfg after unclean shutdown.
2023-08-24T16:44:38.157Z,1692895478.157 [CommandExec](IMPORTANT): got command restart application
2023-08-24T16:44:39.161Z,1692895479.161 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2023-08-24T16:44:39.161Z,1692895479.161 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-24T16:44:39.162Z,1692895479.162 [CommandExec](INFO): Uninitializing the command executive.
2023-08-24T16:44:39.162Z,1692895479.162 [CommandExec](INFO): Uninitializing the command scheduler.
2023-08-24T16:44:39.162Z,1692895479.162 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:39.317Z,1692895479.317 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-08-24T16:44:39.317Z,1692895479.317 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-08-24T16:44:39.318Z,1692895479.318 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:39.318Z,1692895479.318 [NavChartDb](INFO): Join timeout helper Thread ID is 1001
2023-08-24T16:44:39.399Z,1692895479.399 [MissionManager](INFO): MissionManager is completed.
2023-08-24T16:44:39.399Z,1692895479.399 [MissionManager](INFO): Uninitializing Mission ballast_and_trim
2023-08-24T16:44:39.399Z,1692895479.399 [ballast_and_trim] Stopped
2023-08-24T16:44:39.399Z,1692895479.399 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim
2023-08-24T16:44:39.400Z,1692895479.400 [ballast_and_trim:Science] Stopped
2023-08-24T16:44:39.400Z,1692895479.400 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science
2023-08-24T16:44:39.400Z,1692895479.400 [ballast_and_trim:Science:B] Stopped
2023-08-24T16:44:39.400Z,1692895479.400 [ballast_and_trim:Science:C] Stopped
2023-08-24T16:44:39.400Z,1692895479.400 [ballast_and_trim:Science:E] Stopped
2023-08-24T16:44:39.400Z,1692895479.400 [ballast_and_trim:Science:F] Stopped
2023-08-24T16:44:39.400Z,1692895479.400 [ballast_and_trim:Science:OceanCurrent] Stopped
2023-08-24T16:44:39.400Z,1692895479.400 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:OceanCurrent
2023-08-24T16:44:39.400Z,1692895479.400 [ballast_and_trim:Science:OceanCurrent:A.] Stopped
2023-08-24T16:44:39.400Z,1692895479.400 [ballast_and_trim:BackseatDriver] Stopped
2023-08-24T16:44:39.400Z,1692895479.400 [ballast_and_trim:BackseatDriver](DEBUG): Aggregate::uninitialize ballast_and_trim:BackseatDriver
2023-08-24T16:44:39.400Z,1692895479.400 [ballast_and_trim:BackseatDriver:A.BackseatDriver] Stopped
2023-08-24T16:44:39.400Z,1692895479.400 [ballast_and_trim:RunBallastAndTrim] Stopped
2023-08-24T16:44:39.401Z,1692895479.401 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim
2023-08-24T16:44:39.401Z,1692895479.401 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Stopped
2023-08-24T16:44:39.401Z,1692895479.401 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2023-08-24T16:44:39.401Z,1692895479.401 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Stopped
2023-08-24T16:44:39.401Z,1692895479.401 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Stopped
2023-08-24T16:44:39.401Z,1692895479.401 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Stopped
2023-08-24T16:44:39.401Z,1692895479.401 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Stopped
2023-08-24T16:44:39.401Z,1692895479.401 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Stopped
2023-08-24T16:44:39.401Z,1692895479.401 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Stopped
2023-08-24T16:44:39.402Z,1692895479.402 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2023-08-24T16:44:39.402Z,1692895479.402 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Stopped
2023-08-24T16:44:39.402Z,1692895479.402 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped
2023-08-24T16:44:39.402Z,1692895479.402 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2023-08-24T16:44:39.402Z,1692895479.402 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped
2023-08-24T16:44:39.402Z,1692895479.402 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize.
2023-08-24T16:44:39.402Z,1692895479.402 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped
2023-08-24T16:44:39.402Z,1692895479.402 [ballast_and_trim:RunBallastAndTrim:Depth1] Stopped
2023-08-24T16:44:39.402Z,1692895479.402 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1
2023-08-24T16:44:39.402Z,1692895479.402 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Stopped
2023-08-24T16:44:39.402Z,1692895479.402 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2023-08-24T16:44:39.543Z,1692895479.543 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-24T16:44:39.545Z,1692895479.545 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:39.565Z,1692895479.565 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2023-08-24T16:44:39.566Z,1692895479.566 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:39.566Z,1692895479.566 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 1002
2023-08-24T16:44:39.654Z,1692895479.654 [WetLabsBB2FL](INFO): Powering down
2023-08-24T16:44:39.775Z,1692895479.775 [MissionManager](IMPORTANT): Started mission Default
2023-08-24T16:44:39.776Z,1692895479.776 [Default] Running Loop=1
2023-08-24T16:44:39.776Z,1692895479.776 [Default](DEBUG): Aggregate::initialize Default
2023-08-24T16:44:39.776Z,1692895479.776 [Default:B.GoToSurface] Running Loop=1
2023-08-24T16:44:39.776Z,1692895479.776 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-08-24T16:44:39.776Z,1692895479.776 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-08-24T16:44:39.776Z,1692895479.776 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-08-24T16:44:39.777Z,1692895479.777 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-08-24T16:44:39.778Z,1692895479.778 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-08-24T16:44:39.778Z,1692895479.778 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-08-24T16:44:39.779Z,1692895479.779 [Default:A.Wait] Running Loop=1
2023-08-24T16:44:39.779Z,1692895479.779 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-08-24T16:44:39.855Z,1692895479.855 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-24T16:44:39.949Z,1692895479.949 [WetLabsUBAT](INFO): Powering down
2023-08-24T16:44:39.950Z,1692895479.950 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:39.972Z,1692895479.972 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-08-24T16:44:39.972Z,1692895479.972 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:39.972Z,1692895479.972 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1005
2023-08-24T16:44:40.061Z,1692895480.061 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-24T16:44:40.062Z,1692895480.062 [WetLabsBB2FL](INFO): Powering down
2023-08-24T16:44:40.062Z,1692895480.062 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:40.080Z,1692895480.080 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-08-24T16:44:40.080Z,1692895480.080 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:40.080Z,1692895480.080 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1006
2023-08-24T16:44:41.014Z,1692895481.014 [CTD_Seabird](INFO): Powering down
2023-08-24T16:44:41.145Z,1692895481.145 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-24T16:44:41.145Z,1692895481.145 [CTD_Seabird](INFO): Powering down
2023-08-24T16:44:41.157Z,1692895481.157 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:41.173Z,1692895481.173 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2023-08-24T16:44:41.173Z,1692895481.173 [DAT ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:41.173Z,1692895481.173 [DAT](INFO): Join timeout helper Thread ID is 1007
2023-08-24T16:44:41.249Z,1692895481.249 [DAT](INFO): Powering down
2023-08-24T16:44:41.322Z,1692895481.322 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-24T16:44:41.322Z,1692895481.322 [DAT](INFO): Powering down
2023-08-24T16:44:41.323Z,1692895481.323 [DAT ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:41.337Z,1692895481.337 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-08-24T16:44:41.337Z,1692895481.337 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:41.369Z,1692895481.369 [Radio_Surface](INFO): Join timeout helper Thread ID is 1008
2023-08-24T16:44:41.609Z,1692895481.609 [Radio_Surface](INFO): Powering down
2023-08-24T16:44:41.610Z,1692895481.610 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-24T16:44:41.610Z,1692895481.610 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:41.622Z,1692895481.622 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-08-24T16:44:41.622Z,1692895481.622 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:41.622Z,1692895481.622 [Onboard](INFO): Join timeout helper Thread ID is 1009
2023-08-24T16:44:41.785Z,1692895481.785 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-08-24T16:44:45.809Z,1692895485.809 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-24T16:44:45.809Z,1692895485.809 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:45.822Z,1692895485.822 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-08-24T16:44:45.822Z,1692895485.822 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:45.822Z,1692895485.822 [DataOverHttps](INFO): Join timeout helper Thread ID is 1010
2023-08-24T16:44:46.805Z,1692895486.805 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-24T16:44:46.805Z,1692895486.805 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:46.810Z,1692895486.810 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-08-24T16:44:46.810Z,1692895486.810 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:46.810Z,1692895486.810 [BackseatComponent](INFO): Join timeout helper Thread ID is 1011
2023-08-24T16:44:46.894Z,1692895486.894 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-24T16:44:46.895Z,1692895486.895 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:46.911Z,1692895486.911 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-08-24T16:44:46.911Z,1692895486.911 [logger ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:46.912Z,1692895486.912 [logger](INFO): Join timeout helper Thread ID is 1012
2023-08-24T16:44:46.918Z,1692895486.918 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-24T16:44:46.919Z,1692895486.919 [logger ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:46.931Z,1692895486.931 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-08-24T16:44:46.931Z,1692895486.931 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:46.932Z,1692895486.932 [CommandLine](INFO): Join timeout helper Thread ID is 1013
2023-08-24T16:44:46.965Z,1692895486.965 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-24T16:44:46.965Z,1692895486.965 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:46.970Z,1692895486.970 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-08-24T16:44:46.970Z,1692895486.970 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:46.970Z,1692895486.970 [CommandExec](INFO): Join timeout helper Thread ID is 1014
2023-08-24T16:44:46.971Z,1692895486.971 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-08-24T16:44:46.972Z,1692895486.972 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:46.972Z,1692895486.972 [controlThread](INFO): Join timeout helper Thread ID is 1015
2023-08-24T16:44:47.001Z,1692895487.001 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-24T16:44:47.001Z,1692895487.001 [controlThread](DEBUG): Uninitializing ControlThread
2023-08-24T16:44:47.002Z,1692895487.002 [AHRS_M2](INFO): Powering down
2023-08-24T16:44:47.074Z,1692895487.074 [NAL9602](INFO): Powering down
2023-08-24T16:44:47.145Z,1692895487.145 [RDI_Pathfinder](INFO): Powering down
2023-08-24T16:44:47.147Z,1692895487.147 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-08-24T16:44:47.147Z,1692895487.147 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-08-24T16:44:47.148Z,1692895487.148 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-08-24T16:44:47.148Z,1692895487.148 [MissionManager](INFO): Uninitializing Mission Default
2023-08-24T16:44:47.149Z,1692895487.149 [Default] Stopped
2023-08-24T16:44:47.149Z,1692895487.149 [Default](DEBUG): Aggregate::uninitialize Default
2023-08-24T16:44:47.149Z,1692895487.149 [Default:A.Wait] Stopped
2023-08-24T16:44:47.149Z,1692895487.149 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-08-24T16:44:47.149Z,1692895487.149 [Default:B.GoToSurface] Stopped
2023-08-24T16:44:47.149Z,1692895487.149 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-08-24T16:44:47.152Z,1692895487.152 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-08-24T16:44:47.152Z,1692895487.152 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-08-24T16:44:47.153Z,1692895487.153 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-08-24T16:44:47.153Z,1692895487.153 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-08-24T16:44:47.153Z,1692895487.153 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-08-24T16:44:47.154Z,1692895487.154 [BuoyancyServo](INFO): Powering down
2023-08-24T16:44:47.285Z,1692895487.285 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-08-24T16:44:47.285Z,1692895487.285 [ElevatorServo](INFO): Powering down
2023-08-24T16:44:47.286Z,1692895487.286 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-08-24T16:44:47.286Z,1692895487.286 [MassServo](INFO): Powering down
2023-08-24T16:44:47.287Z,1692895487.287 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-08-24T16:44:47.287Z,1692895487.287 [RudderServo](INFO): Powering down
2023-08-24T16:44:47.288Z,1692895487.288 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2023-08-24T16:44:47.288Z,1692895487.288 [ThrusterServo](INFO): Powering down
2023-08-24T16:44:47.289Z,1692895487.289 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-08-24T16:44:47.290Z,1692895487.290 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-08-24T16:44:47.290Z,1692895487.290 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-08-24T16:44:47.290Z,1692895487.290 [CBIT](DEBUG): Powering off loads.
2023-08-24T16:44:47.301Z,1692895487.301 [CBIT](DEBUG): Disabling WDT.
2023-08-24T16:44:47.313Z,1692895487.313 [CBIT](DEBUG): Opening all GF detection circuits.
2023-08-24T16:44:47.314Z,1692895487.314 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:47.372Z,1692895487.372 [DAT ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:47.380Z,1692895487.380 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:47.383Z,1692895487.383 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:47.389Z,1692895487.389 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:47.434Z,1692895487.434 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:47.492Z,1692895487.492 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:47.494Z,1692895487.494 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:47.498Z,1692895487.498 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:47.507Z,1692895487.507 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-08-24T16:44:47.581Z,1692895487.581 [logger ThreadHandler](INFO): Thread cancelled.