2023-03-20T21:47:05.228Z,1679348825.228 [Supervisor](DEBUG): Initializing supervisor.
2023-03-20T21:47:05.232Z,1679348825.232 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-03-20T21:47:05.233Z,1679348825.233 [SyncHandler](INFO): Protected caller Thread ID is 835
2023-03-20T21:47:05.233Z,1679348825.233 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-03-20T21:47:05.234Z,1679348825.234 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-03-20T21:47:05.234Z,1679348825.234 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 836
2023-03-20T21:47:05.238Z,1679348825.238 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-03-20T21:47:05.255Z,1679348825.255 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-03-20T21:47:05.256Z,1679348825.256 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-03-20T21:47:05.257Z,1679348825.257 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 837
2023-03-20T21:47:05.261Z,1679348825.261 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-03-20T21:47:05.262Z,1679348825.262 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-03-20T21:47:05.262Z,1679348825.262 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 838
2023-03-20T21:47:05.264Z,1679348825.264 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-03-20T21:47:05.265Z,1679348825.265 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-03-20T21:47:05.266Z,1679348825.266 [logger ThreadHandler](INFO): Protected caller Thread ID is 839
2023-03-20T21:47:05.269Z,1679348825.269 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-03-20T21:47:05.269Z,1679348825.269 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-03-20T21:47:05.273Z,1679348825.273 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-03-20T21:47:05.653Z,1679348825.653 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-03-20T21:47:05.656Z,1679348825.656 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-03-20T21:47:06.340Z,1679348826.340 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-03-20T21:47:06.340Z,1679348826.340 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-03-20T21:47:06.447Z,1679348826.447 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-03-20T21:47:06.449Z,1679348826.449 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-03-20T21:47:06.926Z,1679348826.926 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-03-20T21:47:06.928Z,1679348826.928 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-03-20T21:47:07.024Z,1679348827.024 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-03-20T21:47:07.024Z,1679348827.024 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-03-20T21:47:07.106Z,1679348827.106 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-03-20T21:47:07.497Z,1679348827.497 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-03-20T21:47:07.497Z,1679348827.497 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-03-20T21:47:07.712Z,1679348827.712 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-03-20T21:47:07.713Z,1679348827.713 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-03-20T21:47:07.846Z,1679348827.846 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-03-20T21:47:07.847Z,1679348827.847 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-03-20T21:47:08.076Z,1679348828.076 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-03-20T21:47:08.077Z,1679348828.077 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-03-20T21:47:08.930Z,1679348828.930 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-03-20T21:47:08.933Z,1679348828.933 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-03-20T21:47:09.298Z,1679348829.298 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-03-20T21:47:09.300Z,1679348829.300 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-03-20T21:47:09.379Z,1679348829.379 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-03-20T21:47:09.568Z,1679348829.568 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-03-20T21:47:09.570Z,1679348829.570 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-03-20T21:47:09.785Z,1679348829.785 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-03-20T21:47:09.786Z,1679348829.786 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-03-20T21:47:10.073Z,1679348830.073 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-03-20T21:47:10.074Z,1679348830.074 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2023-03-20T21:47:10.079Z,1679348830.079 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2023-03-20T21:47:10.187Z,1679348830.187 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2023-03-20T21:47:10.331Z,1679348830.331 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2023-03-20T21:47:10.417Z,1679348830.417 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2023-03-20T21:47:10.499Z,1679348830.499 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2023-03-20T21:47:10.611Z,1679348830.611 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2023-03-20T21:47:10.782Z,1679348830.782 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2023-03-20T21:47:11.040Z,1679348831.040 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-03-20T21:47:11.041Z,1679348831.041 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2023-03-20T21:47:11.171Z,1679348831.171 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2023-03-20T21:47:11.262Z,1679348831.262 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2023-03-20T21:47:11.366Z,1679348831.366 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2023-03-20T21:47:11.466Z,1679348831.466 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-03-20T21:47:11.482Z,1679348831.482 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-03-20T21:47:11.606Z,1679348831.606 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-03-20T21:47:11.608Z,1679348831.608 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-03-20T21:47:11.623Z,1679348831.623 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-03-20T21:47:11.624Z,1679348831.624 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-03-20T21:47:11.694Z,1679348831.694 [DepthRateCalculator] Loaded
2023-03-20T21:47:11.695Z,1679348831.695 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-03-20T21:47:11.700Z,1679348831.700 [PitchRateCalculator] Loaded
2023-03-20T21:47:11.700Z,1679348831.700 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-03-20T21:47:11.710Z,1679348831.710 [SpeedCalculator] Loaded
2023-03-20T21:47:11.711Z,1679348831.711 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-03-20T21:47:11.715Z,1679348831.715 [YawRateCalculator] Loaded
2023-03-20T21:47:11.716Z,1679348831.716 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-03-20T21:47:11.737Z,1679348831.737 [ElevatorOffsetCalculator] Loaded
2023-03-20T21:47:11.737Z,1679348831.737 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-03-20T21:47:11.737Z,1679348831.737 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-03-20T21:47:11.739Z,1679348831.739 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-03-20T21:47:11.806Z,1679348831.806 [VerticalControl](DEBUG): Construct VerticalControl.
2023-03-20T21:47:11.861Z,1679348831.861 [VerticalControl] Loaded
2023-03-20T21:47:11.861Z,1679348831.861 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-03-20T21:47:11.864Z,1679348831.864 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-03-20T21:47:11.903Z,1679348831.903 [HorizontalControl] Loaded
2023-03-20T21:47:11.904Z,1679348831.904 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-03-20T21:47:11.906Z,1679348831.906 [SpeedControl](DEBUG): Construct SpeedControl.
2023-03-20T21:47:11.909Z,1679348831.909 [SpeedControl] Loaded
2023-03-20T21:47:11.909Z,1679348831.909 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-03-20T21:47:11.912Z,1679348831.912 [LoopControl](DEBUG): Construct LoopControl.
2023-03-20T21:47:11.912Z,1679348831.912 [LoopControl] Loaded
2023-03-20T21:47:11.912Z,1679348831.912 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-03-20T21:47:11.913Z,1679348831.913 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-03-20T21:47:11.913Z,1679348831.913 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-03-20T21:47:12.048Z,1679348832.048 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-03-20T21:47:12.048Z,1679348832.048 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-03-20T21:47:12.062Z,1679348832.062 [NavChart] Loaded
2023-03-20T21:47:12.062Z,1679348832.062 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-03-20T21:47:12.067Z,1679348832.067 [UniversalFixResidualReporter] Loaded
2023-03-20T21:47:12.068Z,1679348832.068 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-03-20T21:47:12.068Z,1679348832.068 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-03-20T21:47:12.070Z,1679348832.070 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-03-20T21:47:12.249Z,1679348832.249 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-03-20T21:47:12.250Z,1679348832.250 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-03-20T21:47:13.178Z,1679348833.178 [AHRS_M2] Loaded
2023-03-20T21:47:13.178Z,1679348833.178 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-03-20T21:47:13.430Z,1679348833.430 [BackseatComponent] Loaded
2023-03-20T21:47:13.430Z,1679348833.430 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-03-20T21:47:13.431Z,1679348833.431 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408FD4E0
2023-03-20T21:47:13.431Z,1679348833.431 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 921
2023-03-20T21:47:13.434Z,1679348833.434 [LcmUniversalReporter] Loaded
2023-03-20T21:47:13.434Z,1679348833.434 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-03-20T21:47:13.764Z,1679348833.764 [DataOverHttps] Loaded
2023-03-20T21:47:13.764Z,1679348833.764 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-03-20T21:47:13.765Z,1679348833.765 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4092D4E0
2023-03-20T21:47:13.766Z,1679348833.766 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 922
2023-03-20T21:47:13.786Z,1679348833.786 [Depth_Keller] Loaded
2023-03-20T21:47:13.787Z,1679348833.787 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-03-20T21:47:13.791Z,1679348833.791 [DropWeight] Loaded
2023-03-20T21:47:13.792Z,1679348833.792 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-03-20T21:47:13.828Z,1679348833.828 [DVL_micro] Loaded
2023-03-20T21:47:13.829Z,1679348833.829 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2023-03-20T21:47:13.891Z,1679348833.891 [NAL9602] Loaded
2023-03-20T21:47:13.891Z,1679348833.891 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-03-20T21:47:13.921Z,1679348833.921 [Onboard] Loaded
2023-03-20T21:47:13.922Z,1679348833.922 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-03-20T21:47:13.923Z,1679348833.923 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4095D4E0
2023-03-20T21:47:13.923Z,1679348833.923 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 923
2023-03-20T21:47:13.935Z,1679348833.935 [Power24vConverter] Loaded
2023-03-20T21:47:13.935Z,1679348833.935 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-03-20T21:47:13.948Z,1679348833.948 [Radio_Surface] Loaded
2023-03-20T21:47:13.948Z,1679348833.948 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-03-20T21:47:13.949Z,1679348833.949 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4098D4E0
2023-03-20T21:47:13.949Z,1679348833.949 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 924
2023-03-20T21:47:14.040Z,1679348834.040 [DAT] Loaded
2023-03-20T21:47:14.041Z,1679348834.041 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2023-03-20T21:47:14.042Z,1679348834.042 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409BD4E0
2023-03-20T21:47:14.042Z,1679348834.042 [DAT ThreadHandler](INFO): Protected caller Thread ID is 925
2023-03-20T21:47:14.043Z,1679348834.043 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-03-20T21:47:14.043Z,1679348834.043 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-03-20T21:47:14.623Z,1679348834.623 [BuoyancyServo] Loaded
2023-03-20T21:47:14.626Z,1679348834.626 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-03-20T21:47:14.646Z,1679348834.646 [ElevatorServo] Loaded
2023-03-20T21:47:14.646Z,1679348834.646 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-03-20T21:47:14.666Z,1679348834.666 [MassServo] Loaded
2023-03-20T21:47:14.666Z,1679348834.666 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-03-20T21:47:14.684Z,1679348834.684 [RudderServo] Loaded
2023-03-20T21:47:14.684Z,1679348834.684 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-03-20T21:47:14.698Z,1679348834.698 [ThrusterHE] Loaded
2023-03-20T21:47:14.699Z,1679348834.699 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-03-20T21:47:14.699Z,1679348834.699 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-03-20T21:47:14.700Z,1679348834.700 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-03-20T21:47:14.812Z,1679348834.812 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-03-20T21:47:14.813Z,1679348834.813 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-03-20T21:47:14.856Z,1679348834.856 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-03-20T21:47:14.856Z,1679348834.856 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-03-20T21:47:15.211Z,1679348835.211 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-03-20T21:47:15.212Z,1679348835.212 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-03-20T21:47:15.428Z,1679348835.428 [CTD_Seabird] Loaded
2023-03-20T21:47:15.428Z,1679348835.428 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-03-20T21:47:15.429Z,1679348835.429 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B474E0
2023-03-20T21:47:15.430Z,1679348835.430 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 926
2023-03-20T21:47:15.462Z,1679348835.462 [ESPComponent] Loaded
2023-03-20T21:47:15.463Z,1679348835.463 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-03-20T21:47:15.482Z,1679348835.482 [PAR_Licor] Loaded
2023-03-20T21:47:15.482Z,1679348835.482 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-03-20T21:47:15.513Z,1679348835.513 [WetLabsBB2FL] Loaded
2023-03-20T21:47:15.513Z,1679348835.513 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-03-20T21:47:15.514Z,1679348835.514 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B774E0
2023-03-20T21:47:15.514Z,1679348835.514 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 927
2023-03-20T21:47:15.515Z,1679348835.515 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-03-20T21:47:15.517Z,1679348835.517 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-03-20T21:47:15.683Z,1679348835.683 [SBIT](DEBUG): Construct Startup Built In Test.
2023-03-20T21:47:15.692Z,1679348835.692 [SBIT] Loaded
2023-03-20T21:47:15.693Z,1679348835.693 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-03-20T21:47:15.695Z,1679348835.695 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-03-20T21:47:15.708Z,1679348835.708 [IBIT] Loaded
2023-03-20T21:47:15.709Z,1679348835.709 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-03-20T21:47:15.715Z,1679348835.715 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-03-20T21:47:15.942Z,1679348835.942 [CBIT] Loaded
2023-03-20T21:47:15.942Z,1679348835.942 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-03-20T21:47:15.943Z,1679348835.943 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-03-20T21:47:15.949Z,1679348835.949 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-03-20T21:47:15.952Z,1679348835.952 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-03-20T21:47:15.963Z,1679348835.963 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-03-20T21:47:15.964Z,1679348835.964 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BE54E0
2023-03-20T21:47:15.964Z,1679348835.964 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 928
2023-03-20T21:47:15.968Z,1679348835.968 [Supervisor](INFO): Main Thread ID is 831
2023-03-20T21:47:15.969Z,1679348835.969 [Supervisor](DEBUG): Running supervisor.
2023-03-20T21:47:15.969Z,1679348835.969 [CommandExec ThreadHandler](INFO): Handler Thread ID is 929
2023-03-20T21:47:15.970Z,1679348835.970 [CommandExec](INFO): Initializing the command executive.
2023-03-20T21:47:15.971Z,1679348835.971 [CommandLine ThreadHandler](INFO): Handler Thread ID is 930
2023-03-20T21:47:15.973Z,1679348835.973 [controlThread ThreadHandler](INFO): Handler Thread ID is 931
2023-03-20T21:47:15.974Z,1679348835.974 [controlThread](DEBUG): Initializing ControlThread
2023-03-20T21:47:15.974Z,1679348835.974 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-03-20T21:47:15.975Z,1679348835.975 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-03-20T21:47:15.975Z,1679348835.975 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-03-20T21:47:15.976Z,1679348835.976 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-03-20T21:47:15.976Z,1679348835.976 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-03-20T21:47:15.977Z,1679348835.977 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-03-20T21:47:15.978Z,1679348835.978 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-03-20T21:47:15.979Z,1679348835.979 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-03-20T21:47:15.979Z,1679348835.979 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-03-20T21:47:15.980Z,1679348835.980 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-03-20T21:47:15.981Z,1679348835.981 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-03-20T21:47:15.988Z,1679348835.988 [SBIT](INFO): Initialize SBIT Component.
2023-03-20T21:47:15.989Z,1679348835.989 [SBIT](IMPORTANT): git: 2023-02-15A-18-g952c73059
2023-03-20T21:47:15.989Z,1679348835.989 [SBIT](INFO): git hash: 952c7305905b924e3dfe6a20bfba2406582094af
2023-03-20T21:47:15.989Z,1679348835.989 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-03-20T21:47:15.990Z,1679348835.990 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Sep 24 11:12:27 PDT 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-03-20T21:47:15.991Z,1679348835.991 [SBIT](INFO): Beginning SBIT in 22.000000 seconds.
2023-03-20T21:47:15.992Z,1679348835.992 [IBIT](INFO): Initialize IBIT Component.
2023-03-20T21:47:15.993Z,1679348835.993 [CBIT](DEBUG): Initialize CBIT Component.
2023-03-20T21:47:15.994Z,1679348835.994 [logger ThreadHandler](INFO): Handler Thread ID is 932
2023-03-20T21:47:16.004Z,1679348836.004 [CBIT](DEBUG): Initialized mux pins.
2023-03-20T21:47:16.004Z,1679348836.004 [CBIT](DEBUG): Initializing the watchdog timer.
2023-03-20T21:47:16.012Z,1679348836.012 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 933
2023-03-20T21:47:16.024Z,1679348836.024 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 934
2023-03-20T21:47:16.025Z,1679348836.025 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-03-20T21:47:16.028Z,1679348836.028 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-03-20T21:47:16.028Z,1679348836.028 [CBIT](DEBUG): Initializing heartbeat.
2023-03-20T21:47:16.036Z,1679348836.036 [Onboard ThreadHandler](INFO): Handler Thread ID is 935
2023-03-20T21:47:16.054Z,1679348836.054 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 936
2023-03-20T21:47:16.076Z,1679348836.076 [DAT ThreadHandler](INFO): Handler Thread ID is 937
2023-03-20T21:47:16.077Z,1679348836.077 [DAT](INFO): Powering up
2023-03-20T21:47:16.077Z,1679348836.077 [DAT](DEBUG): Initializing DAT.
2023-03-20T21:47:16.081Z,1679348836.081 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 939
2023-03-20T21:47:16.082Z,1679348836.082 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-03-20T21:47:16.085Z,1679348836.085 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 941
2023-03-20T21:47:16.088Z,1679348836.088 [WetLabsBB2FL](INFO): Powering up
2023-03-20T21:47:16.090Z,1679348836.090 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 943
2023-03-20T21:47:16.098Z,1679348836.098 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-03-20T21:47:16.098Z,1679348836.098 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-03-20T21:47:16.098Z,1679348836.098 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-03-20T21:47:16.098Z,1679348836.098 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-03-20T21:47:16.099Z,1679348836.099 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-03-20T21:47:16.099Z,1679348836.099 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-03-20T21:47:16.099Z,1679348836.099 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-03-20T21:47:16.099Z,1679348836.099 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-03-20T21:47:16.100Z,1679348836.100 [CBIT](DEBUG): Deactivating GF circuits.
2023-03-20T21:47:16.100Z,1679348836.100 [CBIT](DEBUG): Deactivating emergency mode.
2023-03-20T21:47:16.136Z,1679348836.136 [CBIT](DEBUG): Backplane powered.
2023-03-20T21:47:16.137Z,1679348836.137 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-03-20T21:47:16.184Z,1679348836.184 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-03-20T21:47:16.196Z,1679348836.196 [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-03-20T21:47:16.224Z,1679348836.224 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-03-20T21:47:16.305Z,1679348836.305 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-03-20T21:47:16.306Z,1679348836.306 [Default:A.Wait](DEBUG): Construct Wait.
2023-03-20T21:47:16.308Z,1679348836.308 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-03-20T21:47:16.345Z,1679348836.345 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-03-20T21:47:16.348Z,1679348836.348 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-03-20T21:47:16.360Z,1679348836.360 [Default:E.Execute](DEBUG): Construct Execute.
2023-03-20T21:47:16.363Z,1679348836.363 [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-03-20T21:47:16.372Z,1679348836.372 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-03-20T21:47:16.381Z,1679348836.381 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-03-20T21:47:16.445Z,1679348836.445 [Depth_Keller](ERROR): Pressure reading out of range: 1830.099487 decibar
2023-03-20T21:47:16.453Z,1679348836.453 [Power24vConverter](INFO): Powering up.
2023-03-20T21:47:16.460Z,1679348836.460 [Radio_Surface](INFO): Powering up
2023-03-20T21:47:16.463Z,1679348836.463 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-03-20T21:47:16.542Z,1679348836.542 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-03-20T21:47:16.548Z,1679348836.548 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-03-20T21:47:16.549Z,1679348836.549 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-03-20T21:47:16.556Z,1679348836.556 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-03-20T21:47:16.557Z,1679348836.557 [MassServo](DEBUG): Initializing EZServoServo.
2023-03-20T21:47:16.564Z,1679348836.564 [MassServo](DEBUG): Initializing MassServo.
2023-03-20T21:47:16.565Z,1679348836.565 [RudderServo](DEBUG): Initializing EZServoServo.
2023-03-20T21:47:16.572Z,1679348836.572 [RudderServo](DEBUG): Initializing RudderServo.
2023-03-20T21:47:16.573Z,1679348836.573 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-03-20T21:47:16.581Z,1679348836.581 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-03-20T21:47:16.830Z,1679348836.830 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-03-20T21:47:16.830Z,1679348836.830 [DropWeight] Hardware Fault, FailCount= 1
2023-03-20T21:47:16.830Z,1679348836.830 [DropWeight](ERROR): Hardware Fault
2023-03-20T21:47:16.862Z,1679348836.862 [CommandExec](FAULT): Scheduling is paused
2023-03-20T21:47:16.863Z,1679348836.863 [CBIT](INFO): Critical error at 20230320T214716
2023-03-20T21:47:16.863Z,1679348836.863 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-03-20T21:47:16.874Z,1679348836.874 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-03-20T21:47:16.874Z,1679348836.874 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-03-20T21:47:17.544Z,1679348837.544 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2023-03-20T21:47:17.544Z,1679348837.544 [RudderServo](FAULT): Rudder failed to initialize
2023-03-20T21:47:17.544Z,1679348837.544 [RudderServo] Communications Fault, FailCount= 1
2023-03-20T21:47:17.544Z,1679348837.544 [RudderServo](ERROR): Communications Fault
2023-03-20T21:47:17.546Z,1679348837.546 [CBIT](INFO): Critical error at 20230320T214716
2023-03-20T21:47:17.549Z,1679348837.549 [CBIT](ERROR): Communications Fault in component: RudderServo
2023-03-20T21:47:17.739Z,1679348837.739 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-03-20T21:47:17.739Z,1679348837.739 [RudderServo](INFO): Powering down
2023-03-20T21:47:18.420Z,1679348838.420 [RudderServo](DEBUG): Initializing EZServoServo.
2023-03-20T21:47:18.541Z,1679348838.541 [RudderServo](DEBUG): Initializing RudderServo.
2023-03-20T21:47:18.545Z,1679348838.545 [CBIT](INFO): Clearing failed state for component RudderServo
2023-03-20T21:47:18.545Z,1679348838.545 [RudderServo] No Fault, FailCount= 1
2023-03-20T21:47:18.700Z,1679348838.700 [WetLabsBB2FL](INFO): Powering down
2023-03-20T21:47:22.185Z,1679348842.185 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-03-20T21:47:23.113Z,1679348843.113 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-03-20T21:47:26.232Z,1679348846.232 [CBIT](CRITICAL): Environmental Failure. Press:14.726233 PSI. Humidity:36%. Temp:24 C. ABORTING MISSION
2023-03-20T21:47:26.638Z,1679348846.638 [CBIT](INFO): Critical error at 20230320T214726
2023-03-20T21:47:28.172Z,1679348848.172 [DAT](INFO): DAT read: 

2023-03-20T21:47:28.174Z,1679348848.174 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-03-20T21:47:29.937Z,1679348849.937 [DAT](INFO): DAT read: MF Frequency Band

2023-03-20T21:47:29.938Z,1679348849.938 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-03-20T21:47:29.938Z,1679348849.938 [DAT](INFO): DAT read: Mar 20 20:18 

2023-03-20T21:47:30.945Z,1679348850.945 [DAT](INFO): DAT read: Features enabled [Bearing]

2023-03-20T21:47:30.946Z,1679348850.946 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-03-20T21:47:30.947Z,1679348850.947 [DAT](INFO): commRate: 800
2023-03-20T21:47:33.024Z,1679348853.024 [DAT](INFO): entering command mode
2023-03-20T21:47:33.024Z,1679348853.024 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:33.212Z,1679348853.212 [DAT](INFO): DAT read: 

2023-03-20T21:47:33.213Z,1679348853.213 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:33.213Z,1679348853.213 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:33.464Z,1679348853.464 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:33.465Z,1679348853.465 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:33.716Z,1679348853.716 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:33.716Z,1679348853.716 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:33.968Z,1679348853.968 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:33.968Z,1679348853.968 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:34.237Z,1679348854.237 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:34.237Z,1679348854.237 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:34.488Z,1679348854.488 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:34.488Z,1679348854.488 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:34.741Z,1679348854.741 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:34.741Z,1679348854.741 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:34.992Z,1679348854.992 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:34.993Z,1679348854.993 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:35.244Z,1679348855.244 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:35.245Z,1679348855.245 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:35.496Z,1679348855.496 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:35.497Z,1679348855.497 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:35.752Z,1679348855.752 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:35.752Z,1679348855.752 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:36.000Z,1679348856.000 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:36.000Z,1679348856.000 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:36.252Z,1679348856.252 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:36.253Z,1679348856.253 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:36.504Z,1679348856.504 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:36.505Z,1679348856.505 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:36.756Z,1679348856.756 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:36.756Z,1679348856.756 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:37.008Z,1679348857.008 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:37.009Z,1679348857.009 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:37.260Z,1679348857.260 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:37.260Z,1679348857.260 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:37.512Z,1679348857.512 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:37.513Z,1679348857.513 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:37.764Z,1679348857.764 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:37.765Z,1679348857.765 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:38.016Z,1679348858.016 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:38.016Z,1679348858.016 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:38.269Z,1679348858.269 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:38.269Z,1679348858.269 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:38.520Z,1679348858.520 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:38.520Z,1679348858.520 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:38.661Z,1679348858.661 [SBIT](IMPORTANT): Beginning Startup BIT
2023-03-20T21:47:38.666Z,1679348858.666 [CBIT](IMPORTANT): Beginning ground fault scan
2023-03-20T21:47:38.772Z,1679348858.772 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:38.772Z,1679348858.772 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:39.024Z,1679348859.024 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:39.024Z,1679348859.024 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:39.276Z,1679348859.276 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:39.276Z,1679348859.276 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:39.528Z,1679348859.528 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:39.529Z,1679348859.529 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:39.780Z,1679348859.780 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:39.780Z,1679348859.780 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:40.032Z,1679348860.032 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:40.032Z,1679348860.032 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:40.284Z,1679348860.284 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:40.285Z,1679348860.285 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:40.536Z,1679348860.536 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:40.537Z,1679348860.537 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:40.788Z,1679348860.788 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:40.789Z,1679348860.789 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:41.040Z,1679348861.040 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:41.040Z,1679348861.040 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:41.293Z,1679348861.293 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:41.293Z,1679348861.293 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:41.544Z,1679348861.544 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:41.545Z,1679348861.545 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:41.796Z,1679348861.796 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:41.797Z,1679348861.797 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:42.048Z,1679348862.048 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:42.049Z,1679348862.049 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:42.302Z,1679348862.302 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:42.303Z,1679348862.303 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:42.552Z,1679348862.552 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:42.553Z,1679348862.553 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:42.804Z,1679348862.804 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:42.805Z,1679348862.805 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:43.056Z,1679348863.056 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:43.057Z,1679348863.057 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:43.308Z,1679348863.308 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:43.309Z,1679348863.309 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:43.560Z,1679348863.560 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:43.561Z,1679348863.561 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:43.812Z,1679348863.812 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:43.812Z,1679348863.812 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:44.066Z,1679348864.066 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:44.067Z,1679348864.067 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:44.279Z,1679348864.279 [NAL9602](INFO): Powering up NAL9602
2023-03-20T21:47:44.316Z,1679348864.316 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:44.316Z,1679348864.316 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:44.568Z,1679348864.568 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:44.568Z,1679348864.568 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:44.820Z,1679348864.820 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:44.821Z,1679348864.821 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:45.072Z,1679348865.072 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:45.072Z,1679348865.072 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:45.324Z,1679348865.324 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:45.325Z,1679348865.325 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:45.576Z,1679348865.576 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:45.577Z,1679348865.577 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:45.828Z,1679348865.828 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:45.828Z,1679348865.828 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:46.082Z,1679348866.082 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:46.082Z,1679348866.082 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:46.332Z,1679348866.332 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:46.332Z,1679348866.332 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:46.584Z,1679348866.584 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:46.584Z,1679348866.584 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:46.836Z,1679348866.836 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:46.836Z,1679348866.836 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:47.088Z,1679348867.088 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:47.088Z,1679348867.088 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:47.340Z,1679348867.340 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:47.340Z,1679348867.340 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:47.592Z,1679348867.592 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:47.593Z,1679348867.593 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:47.844Z,1679348867.844 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:47.844Z,1679348867.844 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:48.096Z,1679348868.096 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:48.097Z,1679348868.097 [DAT](FAULT): failed to enter command mode
2023-03-20T21:47:48.412Z,1679348868.412 [DAT](INFO): entering command mode
2023-03-20T21:47:48.412Z,1679348868.412 [DAT](DEBUG): checking for command mode acknowledgment
2023-03-20T21:47:48.605Z,1679348868.605 [DAT](INFO): DAT read: 

2023-03-20T21:47:48.605Z,1679348868.605 [DAT](INFO): DAT read: Command '+++' not found

2023-03-20T21:47:48.606Z,1679348868.606 [DAT](INFO): DAT read: Error

2023-03-20T21:47:48.606Z,1679348868.606 [DAT](INFO): setting verbose to 3
2023-03-20T21:47:48.857Z,1679348868.857 [DAT](INFO): DAT read: user:2>

2023-03-20T21:47:48.858Z,1679348868.858 [DAT](INFO): DAT read: Verbose         | 3           

2023-03-20T21:47:48.858Z,1679348868.858 [DAT](INFO): set verbose to 3
2023-03-20T21:47:48.858Z,1679348868.858 [DAT](INFO): setting DatVerbose to 27440
2023-03-20T21:47:49.108Z,1679348869.108 [DAT](INFO): DAT read: user:3>

2023-03-20T21:47:49.109Z,1679348869.109 [DAT](INFO): DAT read: DatVerbose      | 27440       

2023-03-20T21:47:49.110Z,1679348869.110 [DAT](INFO): set DatVerbose to 27440
2023-03-20T21:47:49.110Z,1679348869.110 [DAT](INFO): setting transmit power to 8
2023-03-20T21:47:49.360Z,1679348869.360 [DAT](INFO): DAT read: user:4>

2023-03-20T21:47:49.361Z,1679348869.361 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2023-03-20T21:47:49.362Z,1679348869.362 [DAT](INFO): set transmit power to 8
2023-03-20T21:47:49.362Z,1679348869.362 [DAT](INFO): setting local address to 8
2023-03-20T21:47:49.612Z,1679348869.612 [DAT](INFO): DAT read: user:5>

2023-03-20T21:47:49.613Z,1679348869.613 [DAT](INFO): DAT read: LocalAddr       | 8           

2023-03-20T21:47:49.614Z,1679348869.614 [DAT](INFO): set local address to 8
2023-03-20T21:47:49.615Z,1679348869.615 [DAT](INFO): Setting time to: 21:47:49 And date to:3/20/2023
2023-03-20T21:47:49.865Z,1679348869.865 [DAT](INFO): DAT read: user:6>

2023-03-20T21:47:49.865Z,1679348869.865 [DAT](INFO): DAT read: Mon Mar 20, 2023  21:47:49  

2023-03-20T21:47:49.866Z,1679348869.866 [DAT](INFO): Local DAT time set to Mon Mar 20, 2023  21:47:49  

2023-03-20T21:47:49.977Z,1679348869.977 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.018291
CHAN A1 (24V): -0.002320
CHAN A2 (12V): -0.000423
CHAN A3 (5V): -0.000529
CHAN B0 (3.3V): 0.000502
CHAN B1 (3.15aV): 0.000113
CHAN B2 (3.15bV): 0.000721
CHAN B3 (GND): 0.001229
OPEN: -0.002789
Full Scale: +/- 1 mA
2023-03-20T21:47:51.990Z,1679348871.990 [SBIT](ERROR): Could not read massPosReader_.
2023-03-20T21:47:55.175Z,1679348875.175 [NAL9602](INFO): NAL9602 initialized
2023-03-20T21:47:59.622Z,1679348879.622 [DVL_micro](ERROR): only read 3 of 4 data items
2023-03-20T21:47:59.622Z,1679348879.622 [DVL_micro](ERROR): Failed to parse:
:BI,+0088,+00743,+00000,I
2023-03-20T21:48:32.774Z,1679348912.774 [SBIT](CRITICAL): SBIT FAILED
2023-03-20T21:48:32.774Z,1679348912.774 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-03-20T21:48:32.775Z,1679348912.775 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=35 count;

2023-03-20T21:48:32.775Z,1679348912.775 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool;

2023-03-20T21:48:32.777Z,1679348912.777 [SBIT](IMPORTANT): CTD_Seabird.minSalinityBound=0 practical_salinity_unit;

2023-03-20T21:48:32.777Z,1679348912.777 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum;

2023-03-20T21:48:32.778Z,1679348912.778 [SBIT](IMPORTANT): Depth_Keller.offset=3.9 decibar;

2023-03-20T21:48:32.778Z,1679348912.778 [SBIT](IMPORTANT): Express mostRecent CBIT.ampHoursUsed;

2023-03-20T21:48:32.778Z,1679348912.778 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2023-03-20T21:48:32.778Z,1679348912.778 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=547.568399 cubic_centimeter;

2023-03-20T21:48:32.778Z,1679348912.778 [SBIT](IMPORTANT): VerticalControl.massDefault=0.503160 millimeter;

2023-03-20T21:48:32.778Z,1679348912.778 [SBIT](IMPORTANT): VerticalControl.massPositionLimitAft=-28 millimeter;

2023-03-20T21:48:32.778Z,1679348912.778 [SBIT](IMPORTANT): VerticalControl.massPositionLimitFwd=32 millimeter;

2023-03-20T21:48:32.778Z,1679348912.778 [SBIT](IMPORTANT): VerticalControl.surfaceThreshold=1.5 meter;

2023-03-20T21:48:32.782Z,1679348912.782 [CBIT](INFO): Critical error at 20230320T214832
2023-03-20T21:48:33.171Z,1679348913.171 [MissionManager](IMPORTANT): Started mission Startup
2023-03-20T21:48:33.172Z,1679348913.172 [Startup] Running Loop=1
2023-03-20T21:48:33.172Z,1679348913.172 [Startup](DEBUG): Aggregate::initialize Startup
2023-03-20T21:48:33.172Z,1679348913.172 [Startup:A.GoToSurface] Running Loop=1
2023-03-20T21:48:33.172Z,1679348913.172 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-03-20T21:48:33.173Z,1679348913.173 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-03-20T21:48:33.173Z,1679348913.173 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-03-20T21:48:33.174Z,1679348913.174 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-03-20T21:48:33.174Z,1679348913.174 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-03-20T21:48:33.174Z,1679348913.174 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-03-20T21:48:33.176Z,1679348913.176 [Startup:StartupSatComms] Running Loop=1
2023-03-20T21:48:33.176Z,1679348913.176 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-03-20T21:48:33.176Z,1679348913.176 [Startup:StartupSatComms:A] Running Loop=1
2023-03-20T21:48:33.562Z,1679348913.562 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-03-20T21:49:33.378Z,1679348973.378 [Startup:StartupSatComms:A](INFO): Timed out from 2023-03-20T21:48:33.2Z
2023-03-20T21:49:33.378Z,1679348973.378 [Startup:StartupSatComms:A] Stopped
2023-03-20T21:49:33.378Z,1679348973.378 [Startup:StartupSatComms:B] Running Loop=1
2023-03-20T21:49:33.803Z,1679348973.803 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-03-20T21:49:41.427Z,1679348981.427 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-03-20T21:49:41.427Z,1679348981.427 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35,0000.0,10
2023-03-20T21:49:48.019Z,1679348988.019 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004951
2023-03-20T21:49:51.002Z,1679348991.002 [DataOverHttps](INFO): Sending 116 bytes from file Logs/20230320T190150/Courier0016.lzma
2023-03-20T21:49:52.003Z,1679348992.003 [DataOverHttps](INFO): Moved sent file to Logs/20230320T190150/Courier0016.lzma.bak
2023-03-20T21:49:52.003Z,1679348992.003 [DataOverHttps](INFO): SBD MOMSN=17534160
2023-03-20T21:50:09.533Z,1679349009.533 [DataOverHttps](INFO): Sending 339 bytes from file Logs/20230320T214705/Courier0000.lzma
2023-03-20T21:50:10.534Z,1679349010.534 [DataOverHttps](INFO): Moved sent file to Logs/20230320T214705/Courier0000.lzma.bak
2023-03-20T21:50:10.535Z,1679349010.535 [DataOverHttps](INFO): SBD MOMSN=17534164
2023-03-20T21:50:16.289Z,1679349016.289 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-03-20T21:50:16.289Z,1679349016.289 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-20T21:50:16.299Z,1679349016.299 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-20T21:50:16.707Z,1679349016.707 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-20T21:50:16.708Z,1679349016.708 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-03-20T21:50:28.878Z,1679349028.878 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230320T190150/Express0017.lzma
2023-03-20T21:50:29.879Z,1679349029.879 [DataOverHttps](INFO): Moved sent file to Logs/20230320T190150/Express0017.lzma.bak
2023-03-20T21:50:29.879Z,1679349029.879 [DataOverHttps](INFO): SBD MOMSN=17534171
2023-03-20T21:50:33.700Z,1679349033.700 [Startup:StartupSatComms:B](INFO): Timed out from 2023-03-20T21:49:33.4Z
2023-03-20T21:50:33.700Z,1679349033.700 [Startup:StartupSatComms:B] Stopped
2023-03-20T21:50:33.700Z,1679349033.700 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-03-20T21:50:33.700Z,1679349033.700 [Startup:StartupSatComms] Stopped
2023-03-20T21:50:33.705Z,1679349033.705 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-03-20T21:50:33.706Z,1679349033.706 [Startup](INFO): Completed Startup
2023-03-20T21:50:33.706Z,1679349033.706 [MissionManager](INFO): Startup is completed.
2023-03-20T21:50:33.707Z,1679349033.707 [MissionManager](INFO): Uninitializing Mission Startup
2023-03-20T21:50:33.707Z,1679349033.707 [Startup] Stopped
2023-03-20T21:50:33.707Z,1679349033.707 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-03-20T21:50:33.707Z,1679349033.707 [Startup:A.GoToSurface] Stopped
2023-03-20T21:50:33.707Z,1679349033.707 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-03-20T21:50:34.074Z,1679349034.074 [MissionManager](IMPORTANT): Started mission Default
2023-03-20T21:50:34.074Z,1679349034.074 [Default] Running Loop=1
2023-03-20T21:50:34.074Z,1679349034.074 [Default](DEBUG): Aggregate::initialize Default
2023-03-20T21:50:34.074Z,1679349034.074 [Default:B.GoToSurface] Running Loop=1
2023-03-20T21:50:34.074Z,1679349034.074 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-03-20T21:50:34.075Z,1679349034.075 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-03-20T21:50:34.075Z,1679349034.075 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-03-20T21:50:34.075Z,1679349034.075 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-03-20T21:50:34.076Z,1679349034.076 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-03-20T21:50:34.076Z,1679349034.076 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-03-20T21:50:34.076Z,1679349034.076 [Default:A.Wait] Running Loop=1
2023-03-20T21:50:34.076Z,1679349034.076 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-03-20T21:50:43.350Z,1679349043.350 [DAT](INFO): DAT read: user:7>Lowpower

2023-03-20T21:50:43.350Z,1679349043.350 [DAT](DEBUG): Re-entering command mode due to deviceResponse_: user:7>Lowpower

2023-03-20T21:50:47.403Z,1679349047.403 [Default:A.Wait](INFO): Done Waiting.
2023-03-20T21:50:47.403Z,1679349047.403 [Default:A.Wait] Stopped
2023-03-20T21:50:47.403Z,1679349047.403 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-03-20T21:50:47.802Z,1679349047.802 [Default:CheckIn] Running Loop=1
2023-03-20T21:50:47.802Z,1679349047.802 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-20T21:50:47.803Z,1679349047.803 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-20T21:50:48.203Z,1679349048.203 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-03-20T21:51:16.991Z,1679349076.991 [DataOverHttps](INFO): Sending 1014 bytes from file Logs/20230320T214705/Express0001.lzma
2023-03-20T21:51:17.987Z,1679349077.987 [DataOverHttps](INFO): Moved sent file to Logs/20230320T214705/Express0001.lzma.bak
2023-03-20T21:51:17.987Z,1679349077.987 [DataOverHttps](INFO): SBD MOMSN=17534174
2023-03-20T21:52:58.700Z,1679349178.700 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-03-20T21:53:17.278Z,1679349197.278 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-03-20T21:53:17.278Z,1679349197.278 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-20T21:53:17.308Z,1679349197.308 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-20T21:53:17.691Z,1679349197.691 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-20T21:53:17.691Z,1679349197.691 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-03-20T21:54:30.408Z,1679349270.408 [DVL_micro](ERROR): only read 3 of 4 data items
2023-03-20T21:54:30.408Z,1679349270.408 [DVL_micro](ERROR): Failed to parse:
:RD,+99999.99,+9999.99,+9999.99
2023-03-20T21:55:47.994Z,1679349347.994 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-03-20T21:50:47.8Z
2023-03-20T21:55:47.994Z,1679349347.994 [Default:CheckIn:Read_GPS] Stopped
2023-03-20T21:55:47.994Z,1679349347.994 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-20T21:55:48.390Z,1679349348.390 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-03-20T21:55:56.468Z,1679349356.468 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230320T214705/Courier0004.lzma
2023-03-20T21:55:57.470Z,1679349357.470 [DataOverHttps](INFO): Moved sent file to Logs/20230320T214705/Courier0004.lzma.bak
2023-03-20T21:55:57.471Z,1679349357.471 [DataOverHttps](INFO): SBD MOMSN=17534199
2023-03-20T21:55:59.324Z,1679349359.324 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-03-20T21:55:59.324Z,1679349359.324 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+21.4,009.0,000
2023-03-20T21:56:15.984Z,1679349375.984 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20230320T214705/Express0005.lzma
2023-03-20T21:56:16.987Z,1679349376.987 [DataOverHttps](INFO): Moved sent file to Logs/20230320T214705/Express0005.lzma.bak
2023-03-20T21:56:16.987Z,1679349376.987 [DataOverHttps](INFO): SBD MOMSN=17534201
2023-03-20T21:56:18.341Z,1679349378.341 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-03-20T21:56:18.341Z,1679349378.341 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-20T21:56:18.365Z,1679349378.365 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-20T21:56:18.736Z,1679349378.736 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-20T21:56:18.736Z,1679349378.736 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-03-20T21:56:21.169Z,1679349381.169 [Default:CheckIn:Read_Iridium] Stopped
2023-03-20T21:56:21.170Z,1679349381.170 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-20T21:56:21.170Z,1679349381.170 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-20T21:56:45.205Z,1679349405.205 [CommandExec](IMPORTANT): got command failComponent
2023-03-20T21:56:45.206Z,1679349405.206 [CommandExec](IMPORTANT): Failed components:
2023-03-20T21:56:45.206Z,1679349405.206 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2023-03-20T21:56:55.473Z,1679349415.473 [DVL_micro](ERROR): Failed to parse:
:WI,00094,+01445,+00000,A
2023-03-20T21:57:56.492Z,1679349476.492 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-03-20T21:57:56.492Z,1679349476.492 [NAL9602] Data Fault, FailCount= 1
2023-03-20T21:57:56.492Z,1679349476.492 [NAL9602](ERROR): Data Fault
2023-03-20T21:57:56.510Z,1679349476.510 [CBIT](ERROR): Data Fault in component: NAL9602
2023-03-20T21:57:56.884Z,1679349476.884 [NAL9602](INFO): Powering down
2023-03-20T21:57:57.742Z,1679349477.742 [CBIT](INFO): Clearing failed state for component NAL9602
2023-03-20T21:57:57.742Z,1679349477.742 [NAL9602] No Fault, FailCount= 1
2023-03-20T21:58:00.663Z,1679349480.663 [CommandExec](IMPORTANT): got command report touch NAL9602.sigQuality
2023-03-20T21:58:06.797Z,1679349486.797 [CommandExec](IMPORTANT): got command ibit
2023-03-20T21:58:06.998Z,1679349486.998 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-03-20T21:58:06.998Z,1679349486.998 [IBIT](IMPORTANT): Beginning control surface checks.
2023-03-20T21:58:07.002Z,1679349487.002 [CBIT](IMPORTANT): Beginning ground fault scan
2023-03-20T21:58:17.922Z,1679349497.922 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.020422
CHAN A1 (24V): -0.002609
CHAN A2 (12V): -0.000380
CHAN A3 (5V): 0.002056
CHAN B0 (3.3V): 0.000646
CHAN B1 (3.15aV): -0.000249
CHAN B2 (3.15bV): -0.000059
CHAN B3 (GND): 0.000967
OPEN: 0.004952
Full Scale: +/- 1 mA
2023-03-20T21:58:27.189Z,1679349507.189 [NAL9602](INFO): Powering up NAL9602
2023-03-20T21:58:38.086Z,1679349518.086 [NAL9602](INFO): NAL9602 initialized
2023-03-20T21:59:19.301Z,1679349559.301 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-03-20T21:59:19.301Z,1679349559.301 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-20T21:59:19.334Z,1679349559.334 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-20T21:59:19.746Z,1679349559.746 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-20T21:59:19.746Z,1679349559.746 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-03-20T21:59:58.116Z,1679349598.116 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2023-03-20T21:59:58.528Z,1679349598.528 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2023-03-20T21:59:58.897Z,1679349598.897 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-03-20T21:59:58.898Z,1679349598.898 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-03-20T21:59:58.898Z,1679349598.898 [IBIT](ERROR): Pressure failed. Onboard reading:14.635256 PSI
2023-03-20T21:59:58.898Z,1679349598.898 [IBIT](IMPORTANT): Humidity:35.832012 %
2023-03-20T21:59:59.305Z,1679349599.305 [IBIT](IMPORTANT): Vehicle Pitch:0.315499 degrees
2023-03-20T21:59:59.305Z,1679349599.305 [IBIT](IMPORTANT): Vehicle  Roll:-0.370467 degrees
2023-03-20T21:59:59.306Z,1679349599.306 [IBIT](IMPORTANT): Vehicle  Heading:21.873095 degrees
2023-03-20T21:59:59.732Z,1679349599.732 [IBIT](IMPORTANT): surfaceThreshold: 1.500000 m
2023-03-20T21:59:59.733Z,1679349599.733 [IBIT](IMPORTANT): buoyancyNeutral: 547.568420 cc
2023-03-20T21:59:59.733Z,1679349599.733 [IBIT](IMPORTANT): massDefault: 0.050316 cm
2023-03-20T21:59:59.733Z,1679349599.733 [IBIT](IMPORTANT): stopDepth: 265.000000 m
2023-03-20T21:59:59.734Z,1679349599.734 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2023-03-20T21:59:59.734Z,1679349599.734 [IBIT](IMPORTANT): IBIT FAILED
2023-03-20T22:00:14.497Z,1679349614.497 [CommandExec](IMPORTANT): got command report
2023-03-20T22:00:14.498Z,1679349614.498 [Reporter](INFO): NAL9602.sigQuality reporting when touched
2023-03-20T22:00:54.069Z,1679349654.069 [CommandExec](IMPORTANT): got command failComponent
2023-03-20T22:00:54.070Z,1679349654.070 [CommandExec](IMPORTANT): Failed components:
2023-03-20T22:00:54.070Z,1679349654.070 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2023-03-20T22:00:56.149Z,1679349656.149 [CommandExec](IMPORTANT): got command ibit
2023-03-20T22:00:56.269Z,1679349656.269 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-03-20T22:00:56.269Z,1679349656.269 [IBIT](IMPORTANT): Beginning control surface checks.
2023-03-20T22:00:56.289Z,1679349656.289 [CBIT](IMPORTANT): Beginning ground fault scan
2023-03-20T22:01:07.605Z,1679349667.605 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005224
CHAN A1 (24V): -0.002345
CHAN A2 (12V): -0.000092
CHAN A3 (5V): 0.003850
CHAN B0 (3.3V): 0.000819
CHAN B1 (3.15aV): 0.001152
CHAN B2 (3.15bV): 0.000772
CHAN B3 (GND): 0.001335
OPEN: 0.004721
Full Scale: +/- 1 mA
2023-03-20T22:01:50.840Z,1679349710.840 [DVL_micro](ERROR): Failed to parse:
:WI,+01436,+00299,+01194,+00000,A
2023-03-20T22:01:54.894Z,1679349714.894 [CommandExec](IMPORTANT): got command strobe off
2023-03-20T22:01:54.894Z,1679349714.894 [CommandExec](IMPORTANT): Deactivating strobe
2023-03-20T22:02:02.518Z,1679349722.518 [CommandExec](IMPORTANT): got command 
2023-03-20T22:02:02.518Z,1679349722.518 [CommandExec](FAULT): Incomplete syntax. Try: help
2023-03-20T22:02:03.461Z,1679349723.461 [CommandExec](IMPORTANT): got command failComponent
2023-03-20T22:02:03.461Z,1679349723.461 [CommandExec](IMPORTANT): Failed components:
2023-03-20T22:02:03.462Z,1679349723.462 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2023-03-20T22:02:05.230Z,1679349725.230 [CommandExec](IMPORTANT): got command report
2023-03-20T22:02:05.230Z,1679349725.230 [Reporter](INFO): NAL9602.sigQuality reporting when touched
2023-03-20T22:02:13.582Z,1679349733.582 [CommandExec](IMPORTANT): got command get NAL9602.simulateHardware
2023-03-20T22:02:13.583Z,1679349733.583 [CommandExec](IMPORTANT): NAL9602.simulateHardware 0 bool
2023-03-20T22:02:17.102Z,1679349737.102 [CBIT](INFO): Clearing failed state for component DropWeight
2023-03-20T22:02:17.102Z,1679349737.102 [DropWeight] No Fault, FailCount= 1
2023-03-20T22:02:20.324Z,1679349740.324 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-03-20T22:02:20.324Z,1679349740.324 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-20T22:02:20.334Z,1679349740.334 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-20T22:02:20.585Z,1679349740.585 [CommandExec](IMPORTANT): got command get NAL9602.loadAtStartup
2023-03-20T22:02:20.585Z,1679349740.585 [CommandExec](IMPORTANT): NAL9602.loadAtStartup 1 bool
2023-03-20T22:02:20.750Z,1679349740.750 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-20T22:02:20.750Z,1679349740.750 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-03-20T22:02:29.014Z,1679349749.014 [CommandExec](IMPORTANT): got command report
2023-03-20T22:02:29.014Z,1679349749.014 [Reporter](INFO): NAL9602.sigQuality reporting when touched
2023-03-20T22:02:31.722Z,1679349751.722 [CommandExec](IMPORTANT): got command report 
2023-03-20T22:02:31.722Z,1679349751.722 [Reporter](INFO): NAL9602.sigQuality reporting when touched
2023-03-20T22:02:40.052Z,1679349760.052 [CommandExec](IMPORTANT): got command get NAL9602.sigQuality
2023-03-20T22:02:40.053Z,1679349760.053 [CommandExec](IMPORTANT): NAL9602.sigQuality 0 count
2023-03-20T22:02:46.108Z,1679349766.108 [CommandExec](IMPORTANT): got command show stack
2023-03-20T22:02:46.108Z,1679349766.108 [CommandExec](IMPORTANT): Behavior Stack:

2023-03-20T22:02:46.108Z,1679349766.108 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface
2023-03-20T22:02:46.109Z,1679349766.109 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:C.Wait
2023-03-20T22:02:47.387Z,1679349767.387 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2023-03-20T22:02:47.791Z,1679349767.791 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2023-03-20T22:02:48.247Z,1679349768.247 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-03-20T22:02:48.248Z,1679349768.248 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-03-20T22:02:48.248Z,1679349768.248 [IBIT](ERROR): Pressure failed. Onboard reading:14.688294 PSI
2023-03-20T22:02:48.248Z,1679349768.248 [IBIT](IMPORTANT): Humidity:36.021244 %
2023-03-20T22:02:48.612Z,1679349768.612 [IBIT](IMPORTANT): Vehicle Pitch:0.316444 degrees
2023-03-20T22:02:48.612Z,1679349768.612 [IBIT](IMPORTANT): Vehicle  Roll:-0.372062 degrees
2023-03-20T22:02:48.612Z,1679349768.612 [IBIT](IMPORTANT): Vehicle  Heading:21.944866 degrees
2023-03-20T22:02:49.040Z,1679349769.040 [IBIT](IMPORTANT): surfaceThreshold: 1.500000 m
2023-03-20T22:02:49.040Z,1679349769.040 [IBIT](IMPORTANT): buoyancyNeutral: 547.568420 cc
2023-03-20T22:02:49.041Z,1679349769.041 [IBIT](IMPORTANT): massDefault: 0.050316 cm
2023-03-20T22:02:49.041Z,1679349769.041 [IBIT](IMPORTANT): stopDepth: 265.000000 m
2023-03-20T22:02:49.041Z,1679349769.041 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2023-03-20T22:02:49.041Z,1679349769.041 [IBIT](IMPORTANT): IBIT FAILED
2023-03-20T22:02:49.419Z,1679349769.419 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-03-20T22:02:49.419Z,1679349769.419 [Default:CheckIn:C.Wait] Stopped
2023-03-20T22:02:49.419Z,1679349769.419 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-20T22:02:49.419Z,1679349769.419 [Default:CheckIn:D] Running Loop=1
2023-03-20T22:02:49.834Z,1679349769.834 [Default:CheckIn:D] Stopped
2023-03-20T22:02:49.834Z,1679349769.834 [Default:CheckIn:E] Running Loop=1
2023-03-20T22:02:50.218Z,1679349770.218 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.262666 min 
2023-03-20T22:02:50.218Z,1679349770.218 [Default:CheckIn:E] Stopped
2023-03-20T22:02:50.218Z,1679349770.218 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-03-20T22:02:50.218Z,1679349770.218 [Default:CheckIn] Stopped
2023-03-20T22:02:50.218Z,1679349770.218 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-20T22:02:50.218Z,1679349770.218 [Default:CheckIn](INFO): Running loop #2
2023-03-20T22:02:50.219Z,1679349770.219 [Default:CheckIn] Running Loop=2
2023-03-20T22:02:50.219Z,1679349770.219 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-20T22:02:50.219Z,1679349770.219 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-20T22:04:00.545Z,1679349840.545 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-03-20T22:04:00.545Z,1679349840.545 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+0.0,1489.0,000
2023-03-20T22:05:21.326Z,1679349921.326 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2023-03-20T22:05:21.326Z,1679349921.326 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-20T22:05:21.337Z,1679349921.337 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-20T22:05:21.749Z,1679349921.749 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-20T22:05:21.749Z,1679349921.749 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2023-03-20T22:05:28.818Z,1679349928.818 [CommandExec](IMPORTANT): got command show variable par
2023-03-20T22:05:28.895Z,1679349928.895 [CommandExec](IMPORTANT): PAR_Licor.loadControl (none)
2023-03-20T22:05:28.896Z,1679349928.896 [CommandExec](IMPORTANT): PAR_Licor.ad (none)
2023-03-20T22:05:28.896Z,1679349928.896 [CommandExec](IMPORTANT): PAR_Licor.adRes (bit)
2023-03-20T22:05:28.896Z,1679349928.896 [CommandExec](IMPORTANT): PAR_Licor.adTimeout (millisecond)
2023-03-20T22:05:28.897Z,1679349928.897 [CommandExec](IMPORTANT): PAR_Licor.adVref (volt)
2023-03-20T22:05:28.902Z,1679349928.902 [CommandExec](IMPORTANT): PAR_Licor.loadAtStartup (bool)
2023-03-20T22:05:28.902Z,1679349928.902 [CommandExec](IMPORTANT): PAR_Licor.simulateHardware (bool)
2023-03-20T22:05:28.903Z,1679349928.903 [CommandExec](IMPORTANT): PAR_Licor.adcCal (microampere_per_count)
2023-03-20T22:05:28.903Z,1679349928.903 [CommandExec](IMPORTANT): PAR_Licor.darkCount (count)
2023-03-20T22:05:28.903Z,1679349928.903 [CommandExec](IMPORTANT): PAR_Licor.maxBound (micromole_per_second_per_square_meter)
2023-03-20T22:05:28.903Z,1679349928.903 [CommandExec](IMPORTANT): PAR_Licor.maxValidPitch (degree)
2023-03-20T22:05:28.904Z,1679349928.904 [CommandExec](IMPORTANT): PAR_Licor.minBound (micromole_per_second_per_square_meter)
2023-03-20T22:05:28.904Z,1679349928.904 [CommandExec](IMPORTANT): PAR_Licor.minValidPitch (degree)
2023-03-20T22:05:28.905Z,1679349928.905 [CommandExec](IMPORTANT): PAR_Licor.multiplier (micromole_per_second_per_square_meter_per_microampere)
2023-03-20T22:05:28.905Z,1679349928.905 [CommandExec](IMPORTANT): PAR_Licor.parCal (none)
2023-03-20T22:05:28.905Z,1679349928.905 [CommandExec](IMPORTANT): PAR_Licor.serial (none)
2023-03-20T22:05:28.957Z,1679349928.957 [CommandExec](IMPORTANT): PAR_Licor.enableBroadcast (bool)
2023-03-20T22:05:28.958Z,1679349928.958 [CommandExec](IMPORTANT): PAR_Licor.component_voltage (volt)
2023-03-20T22:05:28.958Z,1679349928.958 [CommandExec](IMPORTANT): PAR_Licor.component_avgVoltage (volt)
2023-03-20T22:05:28.958Z,1679349928.958 [CommandExec](IMPORTANT): PAR_Licor.component_current (milliampere)
2023-03-20T22:05:28.959Z,1679349928.959 [CommandExec](IMPORTANT): PAR_Licor.component_avgCurrent (milliampere)
2023-03-20T22:05:28.959Z,1679349928.959 [CommandExec](IMPORTANT): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water (micromole_per_second_per_square_meter)
2023-03-20T22:05:28.959Z,1679349928.959 [CommandExec](IMPORTANT): PAR_Licor.adcCount (count)
2023-03-20T22:05:47.868Z,1679349947.868 [CommandExec](IMPORTANT): got command report touch PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water
2023-03-20T22:05:48.007Z,1679349948.007 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.270598 umol/s/m2
2023-03-20T22:05:48.410Z,1679349948.410 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.292879 umol/s/m2
2023-03-20T22:05:48.822Z,1679349948.822 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.304877 umol/s/m2
2023-03-20T22:05:49.209Z,1679349949.209 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.296308 umol/s/m2
2023-03-20T22:05:49.651Z,1679349949.651 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.313448 umol/s/m2
2023-03-20T22:05:50.066Z,1679349950.066 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.284310 umol/s/m2
2023-03-20T22:05:50.441Z,1679349950.441 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.304877 umol/s/m2
2023-03-20T22:05:50.851Z,1679349950.851 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.292879 umol/s/m2
2023-03-20T22:05:51.233Z,1679349951.233 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.323732 umol/s/m2
2023-03-20T22:05:51.645Z,1679349951.645 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.294594 umol/s/m2
2023-03-20T22:05:52.048Z,1679349952.048 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.296308 umol/s/m2
2023-03-20T22:05:52.483Z,1679349952.483 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.306592 umol/s/m2
2023-03-20T22:05:52.880Z,1679349952.880 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.311732 umol/s/m2
2023-03-20T22:05:53.266Z,1679349953.266 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.298022 umol/s/m2
2023-03-20T22:05:53.681Z,1679349953.681 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.260314 umol/s/m2
2023-03-20T22:05:54.074Z,1679349954.074 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.316875 umol/s/m2
2023-03-20T22:05:54.461Z,1679349954.461 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.272312 umol/s/m2
2023-03-20T22:05:54.879Z,1679349954.879 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.274026 umol/s/m2
2023-03-20T22:05:55.278Z,1679349955.278 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.234605 umol/s/m2
2023-03-20T22:05:55.698Z,1679349955.698 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.260314 umol/s/m2
2023-03-20T22:05:56.108Z,1679349956.108 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 13.379328 umol/s/m2
2023-03-20T22:05:56.521Z,1679349956.521 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.020568 umol/s/m2
2023-03-20T22:05:56.890Z,1679349956.890 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.005142 umol/s/m2
2023-03-20T22:05:57.308Z,1679349957.308 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.011998 umol/s/m2
2023-03-20T22:05:57.720Z,1679349957.720 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.003428 umol/s/m2
2023-03-20T22:05:58.131Z,1679349958.131 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.027424 umol/s/m2
2023-03-20T22:05:58.525Z,1679349958.525 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.003428 umol/s/m2
2023-03-20T22:05:58.918Z,1679349958.918 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.003428 umol/s/m2
2023-03-20T22:05:59.329Z,1679349959.329 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.003428 umol/s/m2
2023-03-20T22:05:59.750Z,1679349959.750 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.046277 umol/s/m2
2023-03-20T22:06:00.169Z,1679349960.169 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.229464 umol/s/m2
2023-03-20T22:06:00.545Z,1679349960.545 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.251745 umol/s/m2
2023-03-20T22:06:00.954Z,1679349960.954 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.260314 umol/s/m2
2023-03-20T22:06:01.374Z,1679349961.374 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.296308 umol/s/m2
2023-03-20T22:06:01.773Z,1679349961.773 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.275740 umol/s/m2
2023-03-20T22:06:02.169Z,1679349962.169 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.277453 umol/s/m2
2023-03-20T22:06:02.590Z,1679349962.590 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.265456 umol/s/m2
2023-03-20T22:06:02.992Z,1679349962.992 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.291166 umol/s/m2
2023-03-20T22:06:03.399Z,1679349963.399 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.296308 umol/s/m2
2023-03-20T22:06:03.781Z,1679349963.781 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.298022 umol/s/m2
2023-03-20T22:06:04.175Z,1679349964.175 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.289453 umol/s/m2
2023-03-20T22:06:04.565Z,1679349964.565 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.262029 umol/s/m2
2023-03-20T22:06:04.978Z,1679349964.978 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.282596 umol/s/m2
2023-03-20T22:06:05.378Z,1679349965.378 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.299735 umol/s/m2
2023-03-20T22:06:05.796Z,1679349965.796 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.316875 umol/s/m2
2023-03-20T22:06:06.203Z,1679349966.203 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.308305 umol/s/m2
2023-03-20T22:06:06.619Z,1679349966.619 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.287738 umol/s/m2
2023-03-20T22:06:06.994Z,1679349966.994 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.284310 umol/s/m2
2023-03-20T22:06:07.399Z,1679349967.399 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.267171 umol/s/m2
2023-03-20T22:06:07.835Z,1679349967.835 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.304877 umol/s/m2
2023-03-20T22:06:08.202Z,1679349968.202 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.291166 umol/s/m2
2023-03-20T22:06:08.606Z,1679349968.606 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.298022 umol/s/m2
2023-03-20T22:06:08.898Z,1679349968.898 [CommandExec](IMPORTANT): got command report clear
2023-03-20T22:06:23.634Z,1679349983.634 [CommandExec](IMPORTANT): got command get NAL9602.sigQuality
2023-03-20T22:06:23.635Z,1679349983.635 [CommandExec](IMPORTANT): NAL9602.sigQuality 0 count
2023-03-20T22:06:51.005Z,1679350011.005 [DVL_micro](ERROR): DVL BIT error. See manual. Result code: 1309
2023-03-20T22:06:51.007Z,1679350011.007 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,000.00
2023-03-20T22:07:50.433Z,1679350070.433 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-03-20T22:02:50.2Z
2023-03-20T22:07:50.433Z,1679350070.433 [Default:CheckIn:Read_GPS] Stopped
2023-03-20T22:07:50.433Z,1679350070.433 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-03-20T22:07:52.668Z,1679350072.668 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230320T214705/Courier0007.lzma
2023-03-20T22:07:53.674Z,1679350073.674 [DataOverHttps](INFO): Moved sent file to Logs/20230320T214705/Courier0007.lzma.bak
2023-03-20T22:07:53.675Z,1679350073.675 [DataOverHttps](INFO): SBD MOMSN=17534208
2023-03-20T22:08:11.299Z,1679350091.299 [DataOverHttps](INFO): Sending 1726 bytes from file Logs/20230320T214705/Express0008.lzma
2023-03-20T22:08:12.298Z,1679350092.298 [DataOverHttps](INFO): Moved sent file to Logs/20230320T214705/Express0008.lzma.bak
2023-03-20T22:08:12.299Z,1679350092.299 [DataOverHttps](INFO): SBD MOMSN=17534210
2023-03-20T22:08:18.319Z,1679350098.319 [Default:CheckIn:Read_Iridium] Stopped
2023-03-20T22:08:18.319Z,1679350098.319 [Default:CheckIn:C.Wait] Running Loop=1
2023-03-20T22:08:18.319Z,1679350098.319 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-03-20T22:08:22.372Z,1679350102.372 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2023-03-20T22:08:22.372Z,1679350102.372 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-20T22:08:22.382Z,1679350102.382 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-20T22:08:22.787Z,1679350102.787 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-20T22:08:22.787Z,1679350102.787 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2023-03-20T22:08:40.935Z,1679350120.935 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-03-20T22:08:40.935Z,1679350120.935 [NAL9602] Data Fault, FailCount= 2
2023-03-20T22:08:40.935Z,1679350120.935 [NAL9602](ERROR): Data Fault
2023-03-20T22:08:40.965Z,1679350120.965 [CBIT](ERROR): Data Fault in component: NAL9602
2023-03-20T22:08:41.335Z,1679350121.335 [NAL9602](INFO): Powering down
2023-03-20T22:08:42.168Z,1679350122.168 [CBIT](INFO): Clearing failed state for component NAL9602
2023-03-20T22:08:42.168Z,1679350122.168 [NAL9602] No Fault, FailCount= 2
2023-03-20T22:08:55.486Z,1679350135.486 [DVL_micro](ERROR): only read 3 of 4 data items
2023-03-20T22:08:55.486Z,1679350135.486 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999..99
2023-03-20T22:09:11.635Z,1679350151.635 [NAL9602](INFO): Powering up NAL9602
2023-03-20T22:09:22.547Z,1679350162.547 [NAL9602](INFO): NAL9602 initialized
2023-03-20T22:09:23.374Z,1679350163.374 [DVL_micro](ERROR): Failed to parse:
:R99,+9999.99,+9999.99,+9999.99
2023-03-20T22:11:23.350Z,1679350283.350 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2023-03-20T22:11:23.350Z,1679350283.350 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-20T22:11:23.360Z,1679350283.360 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-20T22:11:23.771Z,1679350283.771 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-20T22:11:23.771Z,1679350283.771 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2023-03-20T22:11:32.486Z,1679350292.486 [DataOverHttps](IMPORTANT): SBD MTMSN=20230320T221131
2023-03-20T22:11:39.785Z,1679350299.785 [DataOverHttps](INFO): Received command: run Maintenance/run_backseat_on_surface.xml
2023-03-20T22:11:39.862Z,1679350299.862 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/run_backseat_on_surface.xml
2023-03-20T22:11:39.862Z,1679350299.862 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/run_backseat_on_surface.xml
2023-03-20T22:11:39.920Z,1679350299.920 [MissionManager](INFO): DefineArg run_backseat_on_surface.ElapsedSinceMissionStarted = 0.000000 min
2023-03-20T22:11:39.921Z,1679350299.921 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.xml
2023-03-20T22:11:40.199Z,1679350300.199 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectChlActive = 0 bool
2023-03-20T22:11:40.202Z,1679350300.202 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.TimeWindowPeakReport = nan min
2023-03-20T22:11:40.204Z,1679350300.204 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestChlPeakReportActive = 0 bool
2023-03-20T22:11:40.207Z,1679350300.207 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestSaltPeakReportActive = 0 bool
2023-03-20T22:11:40.209Z,1679350300.209 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestOilPeakReportActive = 0 bool
2023-03-20T22:11:40.212Z,1679350300.212 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PatchTracking = 0 bool
2023-03-20T22:11:40.214Z,1679350300.214 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.DetectTrough = 0 bool
2023-03-20T22:11:40.217Z,1679350300.217 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.FilterWidthHorizontal = 3.000000 count
2023-03-20T22:11:40.220Z,1679350300.220 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.NumProfilesSlidingwindow = 100.000000 count
2023-03-20T22:11:40.222Z,1679350300.222 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.OffPeakFractionHorizontal = 80.000000 %
2023-03-20T22:11:40.225Z,1679350300.225 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectNO3Active = 0 bool
2023-03-20T22:11:40.227Z,1679350300.227 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectOilActive = 0 bool
2023-03-20T22:11:40.230Z,1679350300.230 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectFDOMActive = 0 bool
2023-03-20T22:11:40.232Z,1679350300.232 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectSalinityActive = 0 bool
2023-03-20T22:11:40.235Z,1679350300.235 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2023-03-20T22:11:40.239Z,1679350300.239 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledAanderaaO2 = 0 bool
2023-03-20T22:11:40.242Z,1679350300.242 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledNeilBrown = 0 bool
2023-03-20T22:11:40.290Z,1679350300.290 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledSeabird = 1 bool
2023-03-20T22:11:40.308Z,1679350300.308 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsBB2FL = 1 bool
2023-03-20T22:11:40.338Z,1679350300.338 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool
2023-03-20T22:11:40.349Z,1679350300.349 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsUBAT = 0 bool
2023-03-20T22:11:40.352Z,1679350300.352 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.LowPassWindowLength = 20.000000 count
2023-03-20T22:11:40.364Z,1679350300.364 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.MedianFilterLen = 5.000000 count
2023-03-20T22:11:40.385Z,1679350300.385 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakShallowBound = nan m
2023-03-20T22:11:40.388Z,1679350300.388 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDeepBound = nan m
2023-03-20T22:11:40.407Z,1679350300.407 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2023-03-20T22:11:40.424Z,1679350300.424 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChl = 0.000000 ug/l
2023-03-20T22:11:40.427Z,1679350300.427 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlDepth = 0.000000 m
2023-03-20T22:11:40.442Z,1679350300.442 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlTemperature = 0.000000 degC
2023-03-20T22:11:40.446Z,1679350300.446 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlLatitude = nan arcdeg
2023-03-20T22:11:40.460Z,1679350300.460 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlLongitude = nan arcdeg
2023-03-20T22:11:40.463Z,1679350300.463 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3 = 0.000000 umol/l
2023-03-20T22:11:40.468Z,1679350300.468 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Depth = 0.000000 m
2023-03-20T22:11:40.479Z,1679350300.479 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Latitude = nan arcdeg
2023-03-20T22:11:40.483Z,1679350300.483 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Longitude = nan arcdeg
2023-03-20T22:11:40.494Z,1679350300.494 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOil = 0.000000 kg/m3
2023-03-20T22:11:40.501Z,1679350300.501 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilDepth = 0.000000 m
2023-03-20T22:11:40.504Z,1679350300.504 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilLatitude = nan arcdeg
2023-03-20T22:11:40.519Z,1679350300.519 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilLongitude = nan arcdeg
2023-03-20T22:11:40.530Z,1679350300.530 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOM = 0.000000 ppb
2023-03-20T22:11:40.538Z,1679350300.538 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMDepth = 0.000000 m
2023-03-20T22:11:40.546Z,1679350300.546 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMTemperature = nan degC
2023-03-20T22:11:40.562Z,1679350300.562 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMLatitude = nan arcdeg
2023-03-20T22:11:40.569Z,1679350300.569 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMLongitude = nan arcdeg
2023-03-20T22:11:40.575Z,1679350300.575 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchFDOMDistance = nan m
2023-03-20T22:11:40.578Z,1679350300.578 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSalt = 0.000000 psu
2023-03-20T22:11:40.584Z,1679350300.584 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltDepth = 0.000000 m
2023-03-20T22:11:40.596Z,1679350300.596 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltTemperature = 0.000000 degC
2023-03-20T22:11:40.603Z,1679350300.603 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltLatitude = nan arcdeg
2023-03-20T22:11:40.610Z,1679350300.610 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltLongitude = nan arcdeg
2023-03-20T22:11:40.614Z,1679350300.614 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChl = 0.000000 ug/l
2023-03-20T22:11:40.629Z,1679350300.629 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlDepth = 0.000000 m
2023-03-20T22:11:40.632Z,1679350300.632 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlLatitude = nan arcdeg
2023-03-20T22:11:40.649Z,1679350300.649 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlLongitude = nan arcdeg
2023-03-20T22:11:40.652Z,1679350300.652 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlDistance = nan m
2023-03-20T22:11:40.656Z,1679350300.656 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSalt = 0.000000 psu
2023-03-20T22:11:40.663Z,1679350300.663 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltDepth = 0.000000 m
2023-03-20T22:11:40.668Z,1679350300.668 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltLatitude = nan arcdeg
2023-03-20T22:11:40.679Z,1679350300.679 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltLongitude = nan arcdeg
2023-03-20T22:11:40.682Z,1679350300.682 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltDistance = nan m
2023-03-20T22:11:40.692Z,1679350300.692 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOil = 0.000000 psu
2023-03-20T22:11:40.737Z,1679350300.737 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilDepth = 0.000000 m
2023-03-20T22:11:40.740Z,1679350300.740 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilLatitude = nan arcdeg
2023-03-20T22:11:40.756Z,1679350300.756 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilLongitude = nan arcdeg
2023-03-20T22:11:40.771Z,1679350300.771 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilDistance = nan m
2023-03-20T22:11:40.878Z,1679350300.878 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-03-20T22:11:40.929Z,1679350300.929 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-03-20T22:11:40.967Z,1679350300.967 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-03-20T22:11:40.990Z,1679350300.990 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-03-20T22:11:41.017Z,1679350301.017 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-03-20T22:11:41.046Z,1679350301.046 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-03-20T22:11:41.078Z,1679350301.078 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-03-20T22:11:41.096Z,1679350301.096 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-03-20T22:11:41.164Z,1679350301.164 [run_backseat_on_surface:B.BackseatDriver](DEBUG): Construct BackseatDriver.
2023-03-20T22:11:41.198Z,1679350301.198 [run_backseat_on_surface:C.GoToSurface](DEBUG): Construct GoToSurface.
2023-03-20T22:11:41.210Z,1679350301.210 [run_backseat_on_surface:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-03-20T22:11:41.212Z,1679350301.212 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-03-20T22:11:41.242Z,1679350301.242 [run_backseat_on_surface:F.Execute](DEBUG): Construct Execute.
2023-03-20T22:11:41.286Z,1679350301.286 [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="run_backseat_on_surface">

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

    <!-- Power the science payload. -->

    <Insert Filename="Insert/Science.xml" Id="Science"></Insert>

    <!-- Power the Backseat payload. -->

    <Guidance:BackseatDriver>
        <Parallel/>
    </Guidance:BackseatDriver>

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2023-03-20T22:11:41.293Z,1679350301.293 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/run_backseat_on_surface.xml
2023-03-20T22:11:41.542Z,1679350301.542 [Default] Stopped
2023-03-20T22:11:41.542Z,1679350301.542 [Default](DEBUG): Aggregate::uninitialize Default
2023-03-20T22:11:41.542Z,1679350301.542 [Default:B.GoToSurface] Stopped
2023-03-20T22:11:41.542Z,1679350301.542 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-03-20T22:11:41.542Z,1679350301.542 [Default:CheckIn] Stopped
2023-03-20T22:11:41.542Z,1679350301.542 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-20T22:11:41.542Z,1679350301.542 [Default:CheckIn:C.Wait] Stopped
2023-03-20T22:11:41.542Z,1679350301.542 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-03-20T22:11:41.542Z,1679350301.542 [MissionManager](IMPORTANT): Started mission run_backseat_on_surface
2023-03-20T22:11:41.543Z,1679350301.543 [run_backseat_on_surface] Running Loop=1
2023-03-20T22:11:41.543Z,1679350301.543 [run_backseat_on_surface](DEBUG): Aggregate::initialize run_backseat_on_surface
2023-03-20T22:11:41.543Z,1679350301.543 [run_backseat_on_surface:Science] Running Loop=1
2023-03-20T22:11:41.544Z,1679350301.544 [run_backseat_on_surface:Science](DEBUG): Aggregate::initialize run_backseat_on_surface:Science
2023-03-20T22:11:41.544Z,1679350301.544 [run_backseat_on_surface:Science:A] Running Loop=1
2023-03-20T22:11:41.544Z,1679350301.544 [run_backseat_on_surface:Science:B] Running Loop=1
2023-03-20T22:11:41.544Z,1679350301.544 [run_backseat_on_surface:Science:C] Running Loop=1
2023-03-20T22:11:41.544Z,1679350301.544 [run_backseat_on_surface:Science:D] Running Loop=1
2023-03-20T22:11:41.544Z,1679350301.544 [run_backseat_on_surface:Science:E] Running Loop=1
2023-03-20T22:11:41.544Z,1679350301.544 [run_backseat_on_surface:Science:F] Running Loop=1
2023-03-20T22:11:41.544Z,1679350301.544 [run_backseat_on_surface:Science:Read_Oil] Running Loop=1
2023-03-20T22:11:41.544Z,1679350301.544 [run_backseat_on_surface:Science:PeakDetectChl] Running Loop=1
2023-03-20T22:11:41.544Z,1679350301.544 [run_backseat_on_surface:Science:PeakDetectChl](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectChl
2023-03-20T22:11:41.544Z,1679350301.544 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2023-03-20T22:11:41.544Z,1679350301.544 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-03-20T22:11:41.550Z,1679350301.550 [run_backseat_on_surface:Science:HighestChlPeakReport] Running Loop=1
2023-03-20T22:11:41.551Z,1679350301.551 [run_backseat_on_surface:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestChlPeakReport
2023-03-20T22:11:41.551Z,1679350301.551 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-03-20T22:11:41.551Z,1679350301.551 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-03-20T22:11:41.552Z,1679350301.552 [run_backseat_on_surface:Science:PeakDetectNO3] Running Loop=1
2023-03-20T22:11:41.552Z,1679350301.552 [run_backseat_on_surface:Science:PeakDetectNO3](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectNO3
2023-03-20T22:11:41.552Z,1679350301.552 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2023-03-20T22:11:41.552Z,1679350301.552 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-03-20T22:11:41.556Z,1679350301.556 [run_backseat_on_surface:Science:PeakDetectOil] Running Loop=1
2023-03-20T22:11:41.556Z,1679350301.556 [run_backseat_on_surface:Science:PeakDetectOil](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectOil
2023-03-20T22:11:41.556Z,1679350301.556 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2023-03-20T22:11:41.565Z,1679350301.565 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-03-20T22:11:41.567Z,1679350301.567 [run_backseat_on_surface:Science:HighestOilPeakReport] Running Loop=1
2023-03-20T22:11:41.567Z,1679350301.567 [run_backseat_on_surface:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestOilPeakReport
2023-03-20T22:11:41.567Z,1679350301.567 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-03-20T22:11:41.567Z,1679350301.567 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-03-20T22:11:41.568Z,1679350301.568 [run_backseat_on_surface:Science:PeakDetectFDOM] Running Loop=1
2023-03-20T22:11:41.568Z,1679350301.568 [run_backseat_on_surface:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectFDOM
2023-03-20T22:11:41.568Z,1679350301.568 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2023-03-20T22:11:41.568Z,1679350301.568 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-03-20T22:11:41.574Z,1679350301.574 [run_backseat_on_surface:Science:PeakDetectSalinity] Running Loop=1
2023-03-20T22:11:41.574Z,1679350301.574 [run_backseat_on_surface:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectSalinity
2023-03-20T22:11:41.575Z,1679350301.575 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2023-03-20T22:11:41.575Z,1679350301.575 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-03-20T22:11:41.576Z,1679350301.576 [run_backseat_on_surface:Science:HighestSaltPeakReport] Running Loop=1
2023-03-20T22:11:41.576Z,1679350301.576 [run_backseat_on_surface:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestSaltPeakReport
2023-03-20T22:11:41.576Z,1679350301.576 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-03-20T22:11:41.581Z,1679350301.581 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-03-20T22:11:41.581Z,1679350301.581 [run_backseat_on_surface:Science:OceanCurrent] Running Loop=1
2023-03-20T22:11:41.581Z,1679350301.581 [run_backseat_on_surface:Science:OceanCurrent](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:OceanCurrent
2023-03-20T22:11:41.581Z,1679350301.581 [run_backseat_on_surface:Science:OceanCurrent:A.] Running Loop=1
2023-03-20T22:11:41.582Z,1679350301.582 [run_backseat_on_surface:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2023-03-20T22:11:41.582Z,1679350301.582 [run_backseat_on_surface:B.BackseatDriver] Running Loop=1
2023-03-20T22:11:41.582Z,1679350301.582 [run_backseat_on_surface:B.BackseatDriver](DEBUG): Initialize.
2023-03-20T22:11:41.686Z,1679350301.686 [run_backseat_on_surface:C.GoToSurface] Running Loop=1
2023-03-20T22:11:41.686Z,1679350301.686 [run_backseat_on_surface:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-03-20T22:11:41.687Z,1679350301.687 [run_backseat_on_surface:C.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-03-20T22:11:41.687Z,1679350301.687 [run_backseat_on_surface:C.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-03-20T22:11:41.687Z,1679350301.687 [run_backseat_on_surface:C.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-03-20T22:11:41.688Z,1679350301.688 [run_backseat_on_surface:C.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-03-20T22:11:41.688Z,1679350301.688 [run_backseat_on_surface:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-03-20T22:11:41.693Z,1679350301.693 [run_backseat_on_surface:CheckIn] Running Loop=1
2023-03-20T22:11:41.693Z,1679350301.693 [run_backseat_on_surface:CheckIn](DEBUG): Aggregate::initialize run_backseat_on_surface:CheckIn
2023-03-20T22:11:41.693Z,1679350301.693 [run_backseat_on_surface:CheckIn:Read_GPS] Running Loop=1
2023-03-20T22:11:41.693Z,1679350301.693 [run_backseat_on_surface:B.BackseatDriver] Running Loop=1
2023-03-20T22:11:41.697Z,1679350301.697 [run_backseat_on_surface:Science] Running Loop=1
2023-03-20T22:11:41.698Z,1679350301.698 [run_backseat_on_surface:Science:HighestSaltPeakReport] Stopped
2023-03-20T22:11:41.698Z,1679350301.698 [run_backseat_on_surface:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestSaltPeakReport
2023-03-20T22:11:41.698Z,1679350301.698 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2023-03-20T22:11:41.698Z,1679350301.698 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-03-20T22:11:41.698Z,1679350301.698 [run_backseat_on_surface:Science:PeakDetectSalinity] Stopped
2023-03-20T22:11:41.698Z,1679350301.698 [run_backseat_on_surface:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectSalinity
2023-03-20T22:11:41.698Z,1679350301.698 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2023-03-20T22:11:41.699Z,1679350301.699 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-03-20T22:11:41.699Z,1679350301.699 [run_backseat_on_surface:Science:PeakDetectFDOM] Stopped
2023-03-20T22:11:41.699Z,1679350301.699 [run_backseat_on_surface:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectFDOM
2023-03-20T22:11:41.699Z,1679350301.699 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2023-03-20T22:11:41.699Z,1679350301.699 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-03-20T22:11:41.699Z,1679350301.699 [run_backseat_on_surface:Science:HighestOilPeakReport] Stopped
2023-03-20T22:11:41.699Z,1679350301.699 [run_backseat_on_surface:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestOilPeakReport
2023-03-20T22:11:41.699Z,1679350301.699 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2023-03-20T22:11:41.699Z,1679350301.699 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-03-20T22:11:41.699Z,1679350301.699 [run_backseat_on_surface:Science:PeakDetectOil] Stopped
2023-03-20T22:11:41.699Z,1679350301.699 [run_backseat_on_surface:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectOil
2023-03-20T22:11:41.699Z,1679350301.699 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2023-03-20T22:11:41.699Z,1679350301.699 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-03-20T22:11:41.700Z,1679350301.700 [run_backseat_on_surface:Science:PeakDetectNO3] Stopped
2023-03-20T22:11:41.700Z,1679350301.700 [run_backseat_on_surface:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectNO3
2023-03-20T22:11:41.700Z,1679350301.700 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2023-03-20T22:11:41.700Z,1679350301.700 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-03-20T22:11:41.700Z,1679350301.700 [run_backseat_on_surface:Science:HighestChlPeakReport] Stopped
2023-03-20T22:11:41.700Z,1679350301.700 [run_backseat_on_surface:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestChlPeakReport
2023-03-20T22:11:41.700Z,1679350301.700 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2023-03-20T22:11:41.700Z,1679350301.700 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-03-20T22:11:41.700Z,1679350301.700 [run_backseat_on_surface:Science:PeakDetectChl] Stopped
2023-03-20T22:11:41.700Z,1679350301.700 [run_backseat_on_surface:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectChl
2023-03-20T22:11:41.700Z,1679350301.700 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2023-03-20T22:11:41.704Z,1679350301.704 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-03-20T22:11:41.704Z,1679350301.704 [run_backseat_on_surface:Science:OceanCurrent:A.] Running Loop=1
2023-03-20T22:11:41.717Z,1679350301.717 [run_backseat_on_surface:Science:Read_Oil] Stopped
2023-03-20T22:11:41.717Z,1679350301.717 [run_backseat_on_surface:Science:F] Stopped
2023-03-20T22:11:41.717Z,1679350301.717 [run_backseat_on_surface:Science:E] Running Loop=1
2023-03-20T22:11:41.718Z,1679350301.718 [run_backseat_on_surface:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2023-03-20T22:11:41.718Z,1679350301.718 [run_backseat_on_surface:Science:D] Stopped
2023-03-20T22:11:41.718Z,1679350301.718 [run_backseat_on_surface:Science:C] Running Loop=1
2023-03-20T22:11:41.719Z,1679350301.719 [run_backseat_on_surface:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2023-03-20T22:11:41.719Z,1679350301.719 [run_backseat_on_surface:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2023-03-20T22:11:41.719Z,1679350301.719 [run_backseat_on_surface:Science:B] Running Loop=1
2023-03-20T22:11:41.720Z,1679350301.720 [run_backseat_on_surface:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2023-03-20T22:11:41.725Z,1679350301.725 [run_backseat_on_surface:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2023-03-20T22:11:41.725Z,1679350301.725 [run_backseat_on_surface:Science:A] Stopped
2023-03-20T22:11:41.757Z,1679350301.757 [BackseatComponent](INFO): Powering up
2023-03-20T22:11:41.758Z,1679350301.758 [BackseatComponent](INFO): Subscribing to LCM channels.
2023-03-20T22:11:41.946Z,1679350301.946 [run_backseat_on_surface:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-03-20T22:11:42.058Z,1679350302.058 [WetLabsBB2FL](INFO): Powering up
2023-03-20T22:11:44.827Z,1679350304.827 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2023-03-20T22:12:41.361Z,1679350361.361 [CommandExec](IMPORTANT): got command stop
2023-03-20T22:12:41.362Z,1679350361.362 [CommandExec](IMPORTANT): Scheduling is paused
2023-03-20T22:12:41.362Z,1679350361.362 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2023-03-20T22:12:41.738Z,1679350361.738 [MissionManager](INFO): MissionManager is completed.
2023-03-20T22:12:41.738Z,1679350361.738 [MissionManager](INFO): Uninitializing Mission run_backseat_on_surface
2023-03-20T22:12:41.738Z,1679350361.738 [run_backseat_on_surface] Stopped
2023-03-20T22:12:41.738Z,1679350361.738 [run_backseat_on_surface](DEBUG): Aggregate::uninitialize run_backseat_on_surface
2023-03-20T22:12:41.738Z,1679350361.738 [run_backseat_on_surface:Science] Stopped
2023-03-20T22:12:41.738Z,1679350361.738 [run_backseat_on_surface:Science](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science
2023-03-20T22:12:41.739Z,1679350361.739 [run_backseat_on_surface:Science:B] Stopped
2023-03-20T22:12:41.739Z,1679350361.739 [run_backseat_on_surface:Science:C] Stopped
2023-03-20T22:12:41.739Z,1679350361.739 [run_backseat_on_surface:Science:E] Stopped
2023-03-20T22:12:41.739Z,1679350361.739 [run_backseat_on_surface:Science:OceanCurrent] Stopped
2023-03-20T22:12:41.739Z,1679350361.739 [run_backseat_on_surface:Science:OceanCurrent](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:OceanCurrent
2023-03-20T22:12:41.739Z,1679350361.739 [run_backseat_on_surface:Science:OceanCurrent:A.] Stopped
2023-03-20T22:12:41.739Z,1679350361.739 [run_backseat_on_surface:B.BackseatDriver] Stopped
2023-03-20T22:12:41.739Z,1679350361.739 [run_backseat_on_surface:B.BackseatDriver](DEBUG): Uninitialize.
2023-03-20T22:12:41.739Z,1679350361.739 [run_backseat_on_surface:C.GoToSurface] Stopped
2023-03-20T22:12:41.739Z,1679350361.739 [run_backseat_on_surface:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-03-20T22:12:41.739Z,1679350361.739 [run_backseat_on_surface:CheckIn] Stopped
2023-03-20T22:12:41.739Z,1679350361.739 [run_backseat_on_surface:CheckIn](DEBUG): Aggregate::uninitialize run_backseat_on_surface:CheckIn
2023-03-20T22:12:41.740Z,1679350361.740 [run_backseat_on_surface:CheckIn:Read_GPS] Stopped
2023-03-20T22:12:42.073Z,1679350362.073 [BackseatComponent](INFO): Sent LCM shutdown request.
2023-03-20T22:12:42.161Z,1679350362.161 [MissionManager](IMPORTANT): Started mission Default
2023-03-20T22:12:42.161Z,1679350362.161 [Default] Running Loop=1
2023-03-20T22:12:42.161Z,1679350362.161 [Default](DEBUG): Aggregate::initialize Default
2023-03-20T22:12:42.161Z,1679350362.161 [Default:B.GoToSurface] Running Loop=1
2023-03-20T22:12:42.161Z,1679350362.161 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-03-20T22:12:42.162Z,1679350362.162 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-03-20T22:12:42.162Z,1679350362.162 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-03-20T22:12:42.162Z,1679350362.162 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-03-20T22:12:42.163Z,1679350362.163 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-03-20T22:12:42.163Z,1679350362.163 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-03-20T22:12:42.164Z,1679350362.164 [Default:A.Wait] Running Loop=1
2023-03-20T22:12:42.164Z,1679350362.164 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-03-20T22:12:42.181Z,1679350362.181 [WetLabsBB2FL](INFO): Powering down
2023-03-20T22:12:55.483Z,1679350375.483 [Default:A.Wait](INFO): Done Waiting.
2023-03-20T22:12:55.484Z,1679350375.484 [Default:A.Wait] Stopped
2023-03-20T22:12:55.484Z,1679350375.484 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-03-20T22:12:55.894Z,1679350375.894 [Default:CheckIn] Running Loop=1
2023-03-20T22:12:55.894Z,1679350375.894 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-03-20T22:12:55.895Z,1679350375.895 [Default:CheckIn:Read_GPS] Running Loop=1
2023-03-20T22:12:57.169Z,1679350377.169 [BackseatComponent](INFO): Powering down and starting shutdown timer (15.00 sec).
2023-03-20T22:12:57.170Z,1679350377.170 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2023-03-20T22:12:57.170Z,1679350377.170 [LcmUniversalReporter](INFO): Deactivating messaging.
2023-03-20T22:14:01.741Z,1679350441.741 [CBIT](FAULT): WATER ALARM STERN.
2023-03-20T22:14:01.741Z,1679350441.741 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1
2023-03-20T22:14:24.366Z,1679350464.366 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2023-03-20T22:14:24.366Z,1679350464.366 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-03-20T22:14:24.405Z,1679350464.405 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-03-20T22:14:24.783Z,1679350464.783 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-03-20T22:14:24.783Z,1679350464.783 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2023-03-20T22:16:01.312Z,1679350561.312 [CBIT](FAULT): WATER ALARM STERN.
2023-03-20T22:16:01.313Z,1679350561.313 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1
2023-03-20T22:16:01.718Z,1679350561.718 [CBIT](FAULT): WATER ALARM STERN.
2023-03-20T22:16:01.719Z,1679350561.719 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 2
2023-03-20T22:16:02.135Z,1679350562.135 [CBIT](FAULT): WATER ALARM STERN.
2023-03-20T22:16:02.136Z,1679350562.136 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 3
2023-03-20T22:16:02.546Z,1679350562.546 [CBIT](FAULT): WATER ALARM STERN.
2023-03-20T22:16:02.561Z,1679350562.561 [CBIT](CRITICAL): WATER DETECTED IN PRESSURE HULL. BURNWIRE ACTIVATED
2023-03-20T22:16:02.562Z,1679350562.562 [CBIT](IMPORTANT): Powered off ESP with command: ! echo 0 > /dev/loadA6
2023-03-20T22:16:02.578Z,1679350562.578 [CommandExec](IMPORTANT): got command ! echo 0 > /dev/loadA6
2023-03-20T22:16:02.968Z,1679350562.968 [CommandExec](FAULT): Scheduling is paused
2023-03-20T22:16:02.968Z,1679350562.968 [CBIT](INFO): Critical error at 20230320T221602
2023-03-20T22:16:03.714Z,1679350563.714 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-03-20T22:16:03.714Z,1679350563.714 [DropWeight] Hardware Fault, FailCount= 1
2023-03-20T22:16:03.714Z,1679350563.714 [DropWeight](ERROR): Hardware Fault
2023-03-20T22:16:03.759Z,1679350563.759 [CBIT](INFO): Critical error at 20230320T221603
2023-03-20T22:16:03.761Z,1679350563.761 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-03-20T22:16:03.762Z,1679350563.762 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-03-20T22:16:04.162Z,1679350564.162 [CBIT](INFO): Critical error at 20230320T221603
2023-03-20T22:16:56.243Z,1679350616.243 [DVL_micro](ERROR): Failed to parse:253,-02593,+00220,+00000,I
2023-03-20T22:16:56.610Z,1679350616.610 [CommandExec](IMPORTANT): got command restart system
2023-03-20T22:16:58.706Z,1679350618.706 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-20T22:16:58.707Z,1679350618.707 [CommandExec](INFO): Uninitializing the command executive.
2023-03-20T22:16:58.708Z,1679350618.708 [CommandExec](INFO): Uninitializing the command scheduler.
2023-03-20T22:16:58.709Z,1679350618.709 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:16:58.769Z,1679350618.769 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-03-20T22:16:58.769Z,1679350618.769 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-03-20T22:16:58.769Z,1679350618.769 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:16:58.770Z,1679350618.770 [NavChartDb](INFO): Join timeout helper Thread ID is 1174
2023-03-20T22:16:58.781Z,1679350618.781 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-20T22:16:58.781Z,1679350618.781 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:16:58.794Z,1679350618.794 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-03-20T22:16:58.794Z,1679350618.794 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:16:58.795Z,1679350618.795 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1175
2023-03-20T22:16:59.206Z,1679350619.206 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-20T22:16:59.206Z,1679350619.206 [WetLabsBB2FL](INFO): Powering down
2023-03-20T22:16:59.207Z,1679350619.207 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:16:59.213Z,1679350619.213 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-03-20T22:16:59.213Z,1679350619.213 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:16:59.213Z,1679350619.213 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1176
2023-03-20T22:17:00.117Z,1679350620.117 [CTD_Seabird](INFO): Powering down
2023-03-20T22:17:00.129Z,1679350620.129 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-20T22:17:00.129Z,1679350620.129 [CTD_Seabird](INFO): Powering down
2023-03-20T22:17:00.141Z,1679350620.141 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:17:00.161Z,1679350620.161 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2023-03-20T22:17:00.161Z,1679350620.161 [DAT ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:17:00.161Z,1679350620.161 [DAT](INFO): Join timeout helper Thread ID is 1177
2023-03-20T22:17:00.317Z,1679350620.317 [DAT](INFO): Powering down
2023-03-20T22:17:00.394Z,1679350620.394 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-20T22:17:00.394Z,1679350620.394 [DAT](INFO): Powering down
2023-03-20T22:17:00.395Z,1679350620.395 [DAT ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:17:00.409Z,1679350620.409 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-03-20T22:17:00.409Z,1679350620.409 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:17:00.410Z,1679350620.410 [Radio_Surface](INFO): Join timeout helper Thread ID is 1178
2023-03-20T22:17:00.813Z,1679350620.813 [Radio_Surface](INFO): Powering down
2023-03-20T22:17:00.814Z,1679350620.814 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-20T22:17:00.814Z,1679350620.814 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:17:00.826Z,1679350620.826 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-03-20T22:17:00.827Z,1679350620.827 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:17:00.827Z,1679350620.827 [Onboard](INFO): Join timeout helper Thread ID is 1179
2023-03-20T22:17:01.017Z,1679350621.017 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-03-20T22:17:01.141Z,1679350621.141 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-20T22:17:01.141Z,1679350621.141 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:17:01.156Z,1679350621.156 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-03-20T22:17:01.156Z,1679350621.156 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:17:01.157Z,1679350621.157 [DataOverHttps](INFO): Join timeout helper Thread ID is 1180
2023-03-20T22:17:02.021Z,1679350622.021 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-20T22:17:02.022Z,1679350622.022 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:17:02.033Z,1679350622.033 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-03-20T22:17:02.034Z,1679350622.034 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:17:02.034Z,1679350622.034 [BackseatComponent](INFO): Join timeout helper Thread ID is 1181
2023-03-20T22:17:02.229Z,1679350622.229 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-20T22:17:02.230Z,1679350622.230 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:17:02.242Z,1679350622.242 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-03-20T22:17:02.242Z,1679350622.242 [logger ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:17:02.242Z,1679350622.242 [logger](INFO): Join timeout helper Thread ID is 1182
2023-03-20T22:17:02.281Z,1679350622.281 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-20T22:17:02.281Z,1679350622.281 [logger ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:17:02.282Z,1679350622.282 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-03-20T22:17:02.283Z,1679350622.283 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:17:02.283Z,1679350622.283 [CommandLine](INFO): Join timeout helper Thread ID is 1183
2023-03-20T22:17:02.337Z,1679350622.337 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-20T22:17:02.337Z,1679350622.337 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:17:02.341Z,1679350622.341 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-03-20T22:17:02.342Z,1679350622.342 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:17:02.342Z,1679350622.342 [CommandExec](INFO): Join timeout helper Thread ID is 1184
2023-03-20T22:17:02.343Z,1679350622.343 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-03-20T22:17:02.343Z,1679350622.343 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:17:02.344Z,1679350622.344 [controlThread](INFO): Join timeout helper Thread ID is 1185
2023-03-20T22:17:02.685Z,1679350622.685 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-03-20T22:17:02.685Z,1679350622.685 [controlThread](DEBUG): Uninitializing ControlThread
2023-03-20T22:17:02.685Z,1679350622.685 [AHRS_M2](INFO): Powering down
2023-03-20T22:17:02.829Z,1679350622.829 [DVL_micro](INFO): Powering down
2023-03-20T22:17:02.830Z,1679350622.830 [NAL9602](INFO): Powering down
2023-03-20T22:17:02.833Z,1679350622.833 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-03-20T22:17:02.834Z,1679350622.834 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-03-20T22:17:02.834Z,1679350622.834 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-03-20T22:17:02.835Z,1679350622.835 [MissionManager](INFO): Uninitializing Mission Default
2023-03-20T22:17:02.835Z,1679350622.835 [Default] Stopped
2023-03-20T22:17:02.835Z,1679350622.835 [Default](DEBUG): Aggregate::uninitialize Default
2023-03-20T22:17:02.835Z,1679350622.835 [Default:B.GoToSurface] Stopped
2023-03-20T22:17:02.835Z,1679350622.835 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-03-20T22:17:02.835Z,1679350622.835 [Default:CheckIn] Stopped
2023-03-20T22:17:02.835Z,1679350622.835 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-03-20T22:17:02.835Z,1679350622.835 [Default:CheckIn:Read_GPS] Stopped
2023-03-20T22:17:02.838Z,1679350622.838 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-03-20T22:17:02.838Z,1679350622.838 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-03-20T22:17:02.839Z,1679350622.839 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-03-20T22:17:02.839Z,1679350622.839 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-03-20T22:17:02.839Z,1679350622.839 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-03-20T22:17:02.839Z,1679350622.839 [BuoyancyServo](INFO): Powering down
2023-03-20T22:17:02.853Z,1679350622.853 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-03-20T22:17:02.853Z,1679350622.853 [ElevatorServo](INFO): Powering down
2023-03-20T22:17:02.854Z,1679350622.854 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-03-20T22:17:02.854Z,1679350622.854 [MassServo](INFO): Powering down
2023-03-20T22:17:02.855Z,1679350622.855 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-03-20T22:17:02.855Z,1679350622.855 [RudderServo](INFO): Powering down
2023-03-20T22:17:02.856Z,1679350622.856 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-03-20T22:17:02.856Z,1679350622.856 [ThrusterHE](INFO): Powering down
2023-03-20T22:17:02.857Z,1679350622.857 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-03-20T22:17:02.857Z,1679350622.857 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-03-20T22:17:02.858Z,1679350622.858 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-03-20T22:17:02.858Z,1679350622.858 [CBIT](DEBUG): Powering off loads.
2023-03-20T22:17:02.869Z,1679350622.869 [CBIT](DEBUG): Disabling WDT.
2023-03-20T22:17:02.884Z,1679350622.884 [CBIT](DEBUG): Opening all GF detection circuits.
2023-03-20T22:17:02.885Z,1679350622.885 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:17:02.949Z,1679350622.949 [DAT ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:17:02.954Z,1679350622.954 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:17:02.956Z,1679350622.956 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:17:02.964Z,1679350622.964 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:17:02.970Z,1679350622.970 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:17:03.025Z,1679350623.025 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:17:03.030Z,1679350623.030 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:17:03.054Z,1679350623.054 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-03-20T22:17:03.114Z,1679350623.114 [logger ThreadHandler](INFO): Thread cancelled.