2023-09-06T22:53:52.151Z,1694040832.151 [Supervisor](DEBUG): Initializing supervisor.
2023-09-06T22:53:52.215Z,1694040832.215 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-09-06T22:53:52.215Z,1694040832.215 [SyncHandler](INFO): Protected caller Thread ID is 5864
2023-09-06T22:53:52.216Z,1694040832.216 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-09-06T22:53:52.217Z,1694040832.217 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-09-06T22:53:52.217Z,1694040832.217 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5865
2023-09-06T22:53:52.222Z,1694040832.222 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-09-06T22:53:52.239Z,1694040832.239 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-09-06T22:53:52.240Z,1694040832.240 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-09-06T22:53:52.240Z,1694040832.240 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 5866
2023-09-06T22:53:52.244Z,1694040832.244 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-09-06T22:53:52.245Z,1694040832.245 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-09-06T22:53:52.246Z,1694040832.246 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5867
2023-09-06T22:53:52.248Z,1694040832.248 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-09-06T22:53:52.249Z,1694040832.249 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-09-06T22:53:52.249Z,1694040832.249 [logger ThreadHandler](INFO): Protected caller Thread ID is 5868
2023-09-06T22:53:52.253Z,1694040832.253 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-09-06T22:53:52.253Z,1694040832.253 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-09-06T22:53:52.257Z,1694040832.257 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-09-06T22:53:52.652Z,1694040832.652 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-09-06T22:53:52.654Z,1694040832.654 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-09-06T22:53:52.739Z,1694040832.739 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-09-06T22:53:53.193Z,1694040833.193 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-09-06T22:53:53.195Z,1694040833.195 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-09-06T22:53:53.596Z,1694040833.596 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-09-06T22:53:53.598Z,1694040833.598 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-09-06T22:53:53.695Z,1694040833.695 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-09-06T22:53:53.697Z,1694040833.697 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-09-06T22:53:54.074Z,1694040834.074 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-09-06T22:53:54.076Z,1694040834.076 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-09-06T22:53:54.285Z,1694040834.285 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-09-06T22:53:54.285Z,1694040834.285 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-09-06T22:53:54.790Z,1694040834.790 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-09-06T22:53:54.791Z,1694040834.791 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-09-06T22:53:54.924Z,1694040834.924 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-09-06T22:53:54.926Z,1694040834.926 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-09-06T22:53:55.007Z,1694040835.007 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-09-06T22:53:55.757Z,1694040835.757 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-09-06T22:53:55.758Z,1694040835.758 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-09-06T22:53:56.402Z,1694040836.402 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-09-06T22:53:56.403Z,1694040836.403 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-09-06T22:53:56.844Z,1694040836.844 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-09-06T22:53:56.845Z,1694040836.845 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-09-06T22:53:56.956Z,1694040836.956 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-09-06T22:53:56.956Z,1694040836.956 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-09-06T22:53:57.181Z,1694040837.181 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-09-06T22:53:57.183Z,1694040837.183 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-09-06T22:53:57.420Z,1694040837.420 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-09-06T22:53:57.422Z,1694040837.422 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2023-09-06T22:53:57.426Z,1694040837.426 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2023-09-06T22:53:57.520Z,1694040837.520 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2023-09-06T22:53:57.608Z,1694040837.608 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2023-09-06T22:53:57.711Z,1694040837.711 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2023-09-06T22:53:57.798Z,1694040837.798 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2023-09-06T22:53:57.903Z,1694040837.903 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2023-09-06T22:53:58.008Z,1694040838.008 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2023-09-06T22:53:58.123Z,1694040838.123 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2023-09-06T22:53:58.201Z,1694040838.201 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2023-09-06T22:53:58.348Z,1694040838.348 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2023-09-06T22:53:58.487Z,1694040838.487 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2023-09-06T22:53:58.597Z,1694040838.597 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2023-09-06T22:53:58.860Z,1694040838.860 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-09-06T22:53:58.861Z,1694040838.861 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2023-09-06T22:53:58.862Z,1694040838.862 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-09-06T22:53:58.879Z,1694040838.879 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-09-06T22:53:58.944Z,1694040838.944 [VerticalControl](DEBUG): Construct VerticalControl.
2023-09-06T22:53:59.003Z,1694040839.003 [VerticalControl] Loaded
2023-09-06T22:53:59.003Z,1694040839.003 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-09-06T22:53:59.006Z,1694040839.006 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-09-06T22:53:59.046Z,1694040839.046 [HorizontalControl] Loaded
2023-09-06T22:53:59.046Z,1694040839.046 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-09-06T22:53:59.048Z,1694040839.048 [SpeedControl](DEBUG): Construct SpeedControl.
2023-09-06T22:53:59.051Z,1694040839.051 [SpeedControl] Loaded
2023-09-06T22:53:59.052Z,1694040839.052 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-09-06T22:53:59.054Z,1694040839.054 [LoopControl](DEBUG): Construct LoopControl.
2023-09-06T22:53:59.055Z,1694040839.055 [LoopControl] Loaded
2023-09-06T22:53:59.055Z,1694040839.055 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-09-06T22:53:59.055Z,1694040839.055 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-09-06T22:53:59.057Z,1694040839.057 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-09-06T22:53:59.072Z,1694040839.072 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-09-06T22:53:59.073Z,1694040839.073 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-09-06T22:53:59.257Z,1694040839.257 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-09-06T22:53:59.258Z,1694040839.258 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-09-06T22:53:59.414Z,1694040839.414 [BuoyancyServo] Loaded
2023-09-06T22:53:59.414Z,1694040839.414 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-09-06T22:53:59.433Z,1694040839.433 [ElevatorServo] Loaded
2023-09-06T22:53:59.434Z,1694040839.434 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-09-06T22:53:59.454Z,1694040839.454 [MassServo] Loaded
2023-09-06T22:53:59.454Z,1694040839.454 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-09-06T22:53:59.472Z,1694040839.472 [RudderServo] Loaded
2023-09-06T22:53:59.472Z,1694040839.472 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-09-06T22:53:59.486Z,1694040839.486 [ThrusterHE] Loaded
2023-09-06T22:53:59.487Z,1694040839.487 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-09-06T22:53:59.487Z,1694040839.487 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-09-06T22:53:59.488Z,1694040839.488 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-09-06T22:53:59.624Z,1694040839.624 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-09-06T22:53:59.624Z,1694040839.624 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-09-06T22:53:59.638Z,1694040839.638 [NavChart] Loaded
2023-09-06T22:53:59.639Z,1694040839.639 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-09-06T22:53:59.645Z,1694040839.645 [UniversalFixResidualReporter] Loaded
2023-09-06T22:53:59.646Z,1694040839.646 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-09-06T22:53:59.646Z,1694040839.646 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-09-06T22:53:59.647Z,1694040839.647 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-09-06T22:53:59.727Z,1694040839.727 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-09-06T22:53:59.727Z,1694040839.727 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-09-06T22:54:00.443Z,1694040840.443 [AHRS_M2] Loaded
2023-09-06T22:54:00.444Z,1694040840.444 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-09-06T22:54:00.695Z,1694040840.695 [BackseatComponent] Loaded
2023-09-06T22:54:00.695Z,1694040840.695 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-09-06T22:54:00.715Z,1694040840.715 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4090C4E0
2023-09-06T22:54:00.715Z,1694040840.715 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 5954
2023-09-06T22:54:00.718Z,1694040840.718 [LcmUniversalReporter] Loaded
2023-09-06T22:54:00.718Z,1694040840.718 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-09-06T22:54:01.638Z,1694040841.638 [BPC1] Loaded
2023-09-06T22:54:01.638Z,1694040841.638 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-09-06T22:54:02.068Z,1694040842.068 [DataOverHttps] Loaded
2023-09-06T22:54:02.068Z,1694040842.068 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-09-06T22:54:02.077Z,1694040842.077 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4093C4E0
2023-09-06T22:54:02.085Z,1694040842.085 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5955
2023-09-06T22:54:02.106Z,1694040842.106 [Depth_Keller] Loaded
2023-09-06T22:54:02.106Z,1694040842.106 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-09-06T22:54:02.111Z,1694040842.111 [DropWeight] Loaded
2023-09-06T22:54:02.112Z,1694040842.112 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-09-06T22:54:02.188Z,1694040842.188 [NAL9602] Loaded
2023-09-06T22:54:02.188Z,1694040842.188 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-09-06T22:54:02.302Z,1694040842.302 [Onboard] Loaded
2023-09-06T22:54:02.302Z,1694040842.302 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-09-06T22:54:02.310Z,1694040842.310 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4096C4E0
2023-09-06T22:54:02.310Z,1694040842.310 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 5956
2023-09-06T22:54:02.463Z,1694040842.463 [Power24vConverter] Loaded
2023-09-06T22:54:02.463Z,1694040842.463 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-09-06T22:54:02.555Z,1694040842.555 [Radio_Surface] Loaded
2023-09-06T22:54:02.555Z,1694040842.555 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-09-06T22:54:02.556Z,1694040842.556 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4099C4E0
2023-09-06T22:54:02.556Z,1694040842.556 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5957
2023-09-06T22:54:02.558Z,1694040842.558 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-09-06T22:54:02.558Z,1694040842.558 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-09-06T22:54:02.695Z,1694040842.695 [DepthRateCalculator] Loaded
2023-09-06T22:54:02.696Z,1694040842.696 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-09-06T22:54:02.700Z,1694040842.700 [PitchRateCalculator] Loaded
2023-09-06T22:54:02.701Z,1694040842.701 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-09-06T22:54:02.709Z,1694040842.709 [SpeedCalculator] Loaded
2023-09-06T22:54:02.710Z,1694040842.710 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-09-06T22:54:02.714Z,1694040842.714 [YawRateCalculator] Loaded
2023-09-06T22:54:02.714Z,1694040842.714 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-09-06T22:54:02.732Z,1694040842.732 [ElevatorOffsetCalculator] Loaded
2023-09-06T22:54:02.733Z,1694040842.733 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-09-06T22:54:02.733Z,1694040842.733 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-09-06T22:54:02.734Z,1694040842.734 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-09-06T22:54:02.913Z,1694040842.913 [CANONSampler] Loaded
2023-09-06T22:54:02.913Z,1694040842.913 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2023-09-06T22:54:02.973Z,1694040842.973 [CTD_Seabird] Loaded
2023-09-06T22:54:02.973Z,1694040842.973 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-09-06T22:54:02.977Z,1694040842.977 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A574E0
2023-09-06T22:54:02.977Z,1694040842.977 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5958
2023-09-06T22:54:02.998Z,1694040842.998 [PAR_Licor] Loaded
2023-09-06T22:54:02.999Z,1694040842.999 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-09-06T22:54:03.038Z,1694040843.038 [WetLabsBB2FL] Loaded
2023-09-06T22:54:03.038Z,1694040843.038 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-09-06T22:54:03.039Z,1694040843.039 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A874E0
2023-09-06T22:54:03.039Z,1694040843.039 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5959
2023-09-06T22:54:03.040Z,1694040843.040 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-09-06T22:54:03.041Z,1694040843.041 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-09-06T22:54:03.082Z,1694040843.082 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-09-06T22:54:03.083Z,1694040843.083 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-09-06T22:54:03.431Z,1694040843.431 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-09-06T22:54:03.432Z,1694040843.432 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-09-06T22:54:03.596Z,1694040843.596 [SBIT](DEBUG): Construct Startup Built In Test.
2023-09-06T22:54:03.605Z,1694040843.605 [SBIT] Loaded
2023-09-06T22:54:03.605Z,1694040843.605 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-09-06T22:54:03.608Z,1694040843.608 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-09-06T22:54:03.622Z,1694040843.622 [IBIT] Loaded
2023-09-06T22:54:03.622Z,1694040843.622 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-09-06T22:54:03.628Z,1694040843.628 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-09-06T22:54:03.731Z,1694040843.731 [CBIT] Loaded
2023-09-06T22:54:03.731Z,1694040843.731 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-09-06T22:54:03.731Z,1694040843.731 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-09-06T22:54:03.732Z,1694040843.732 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-09-06T22:54:03.852Z,1694040843.852 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-09-06T22:54:03.859Z,1694040843.859 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-09-06T22:54:03.861Z,1694040843.861 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-09-06T22:54:03.873Z,1694040843.873 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-09-06T22:54:03.875Z,1694040843.875 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BC84E0
2023-09-06T22:54:03.875Z,1694040843.875 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5960
2023-09-06T22:54:03.880Z,1694040843.880 [Supervisor](INFO): Main Thread ID is 5863
2023-09-06T22:54:03.880Z,1694040843.880 [Supervisor](DEBUG): Running supervisor.
2023-09-06T22:54:03.880Z,1694040843.880 [CommandExec ThreadHandler](INFO): Handler Thread ID is 5961
2023-09-06T22:54:03.881Z,1694040843.881 [CommandExec](INFO): Initializing the command executive.
2023-09-06T22:54:03.882Z,1694040843.882 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5962
2023-09-06T22:54:03.885Z,1694040843.885 [controlThread ThreadHandler](INFO): Handler Thread ID is 5963
2023-09-06T22:54:03.885Z,1694040843.885 [controlThread](DEBUG): Initializing ControlThread
2023-09-06T22:54:03.886Z,1694040843.886 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-09-06T22:54:03.888Z,1694040843.888 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-09-06T22:54:03.888Z,1694040843.888 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-09-06T22:54:03.889Z,1694040843.889 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-09-06T22:54:03.891Z,1694040843.891 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-09-06T22:54:03.891Z,1694040843.891 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-09-06T22:54:03.895Z,1694040843.895 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-09-06T22:54:03.896Z,1694040843.896 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-09-06T22:54:03.896Z,1694040843.896 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-09-06T22:54:03.896Z,1694040843.896 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-09-06T22:54:03.897Z,1694040843.897 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-09-06T22:54:03.902Z,1694040843.902 [SBIT](INFO): Initialize SBIT Component.
2023-09-06T22:54:03.902Z,1694040843.902 [SBIT](IMPORTANT): git: 2023-07-13-16-gac1c2d9c8
2023-09-06T22:54:03.902Z,1694040843.902 [SBIT](INFO): git hash: ac1c2d9c86739cc59064b1e8d69712a613ceedbd
2023-09-06T22:54:03.903Z,1694040843.903 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-09-06T22:54:03.904Z,1694040843.904 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-09-06T22:54:03.905Z,1694040843.905 [SBIT](INFO): Beginning SBIT in 65.000000 seconds.
2023-09-06T22:54:03.905Z,1694040843.905 [IBIT](INFO): Initialize IBIT Component.
2023-09-06T22:54:03.906Z,1694040843.906 [CBIT](DEBUG): Initialize CBIT Component.
2023-09-06T22:54:03.907Z,1694040843.907 [logger ThreadHandler](INFO): Handler Thread ID is 5964
2023-09-06T22:54:03.917Z,1694040843.917 [CBIT](DEBUG): Initialized mux pins.
2023-09-06T22:54:03.917Z,1694040843.917 [CBIT](DEBUG): Initializing the watchdog timer.
2023-09-06T22:54:03.926Z,1694040843.926 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 5965
2023-09-06T22:54:03.938Z,1694040843.938 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5966
2023-09-06T22:54:03.939Z,1694040843.939 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-09-06T22:54:03.941Z,1694040843.941 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-09-06T22:54:03.942Z,1694040843.942 [CBIT](DEBUG): Initializing heartbeat.
2023-09-06T22:54:03.950Z,1694040843.950 [Onboard ThreadHandler](INFO): Handler Thread ID is 5967
2023-09-06T22:54:03.967Z,1694040843.967 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5968
2023-09-06T22:54:03.990Z,1694040843.990 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5969
2023-09-06T22:54:03.990Z,1694040843.990 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-09-06T22:54:03.995Z,1694040843.995 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5971
2023-09-06T22:54:03.998Z,1694040843.998 [WetLabsBB2FL](INFO): Powering up
2023-09-06T22:54:03.999Z,1694040843.999 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5973
2023-09-06T22:54:04.006Z,1694040844.006 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-09-06T22:54:04.007Z,1694040844.007 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-09-06T22:54:04.007Z,1694040844.007 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-09-06T22:54:04.007Z,1694040844.007 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-09-06T22:54:04.007Z,1694040844.007 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-09-06T22:54:04.007Z,1694040844.007 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-09-06T22:54:04.008Z,1694040844.008 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-09-06T22:54:04.008Z,1694040844.008 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-09-06T22:54:04.013Z,1694040844.013 [CBIT](DEBUG): Deactivating GF circuits.
2023-09-06T22:54:04.013Z,1694040844.013 [CBIT](DEBUG): Deactivating emergency mode.
2023-09-06T22:54:04.053Z,1694040844.053 [CBIT](DEBUG): Backplane powered.
2023-09-06T22:54:04.054Z,1694040844.054 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-09-06T22:54:04.088Z,1694040844.088 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-06T22:54:04.132Z,1694040844.132 [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-09-06T22:54:04.133Z,1694040844.133 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-09-06T22:54:04.249Z,1694040844.249 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-09-06T22:54:04.263Z,1694040844.263 [Default:A.Wait](DEBUG): Construct Wait.
2023-09-06T22:54:04.265Z,1694040844.265 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-06T22:54:04.303Z,1694040844.303 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-09-06T22:54:04.306Z,1694040844.306 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-09-06T22:54:04.328Z,1694040844.328 [Default:E.Execute](DEBUG): Construct Execute.
2023-09-06T22:54:04.331Z,1694040844.331 [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-09-06T22:54:04.338Z,1694040844.338 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-09-06T22:54:04.357Z,1694040844.357 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-09-06T22:54:04.378Z,1694040844.378 [Radio_Surface](INFO): Powering up
2023-09-06T22:54:04.920Z,1694040844.920 [Power24vConverter](INFO): Powering up.
2023-09-06T22:54:04.922Z,1694040844.922 [CANONSampler](INFO): Powering down
2023-09-06T22:54:04.975Z,1694040844.975 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-09-06T22:54:04.982Z,1694040844.982 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-09-06T22:54:04.983Z,1694040844.983 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-09-06T22:54:04.990Z,1694040844.990 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-09-06T22:54:04.991Z,1694040844.991 [MassServo](DEBUG): Initializing EZServoServo.
2023-09-06T22:54:04.998Z,1694040844.998 [MassServo](DEBUG): Initializing MassServo.
2023-09-06T22:54:04.998Z,1694040844.998 [RudderServo](DEBUG): Initializing EZServoServo.
2023-09-06T22:54:05.006Z,1694040845.006 [RudderServo](DEBUG): Initializing RudderServo.
2023-09-06T22:54:05.006Z,1694040845.006 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-09-06T22:54:05.014Z,1694040845.014 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-09-06T22:54:05.227Z,1694040845.227 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-09-06T22:54:05.227Z,1694040845.227 [DropWeight] Hardware Fault, FailCount= 1
2023-09-06T22:54:05.227Z,1694040845.227 [DropWeight](ERROR): Hardware Fault
2023-09-06T22:54:05.256Z,1694040845.256 [CommandExec](FAULT): Scheduling is paused
2023-09-06T22:54:05.257Z,1694040845.257 [CBIT](INFO): Critical error at 20230906T225405
2023-09-06T22:54:05.257Z,1694040845.257 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-09-06T22:54:05.268Z,1694040845.268 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-09-06T22:54:05.268Z,1694040845.268 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-09-06T22:54:05.454Z,1694040845.454 [CBIT](INFO): Critical error at 20230906T225405
2023-09-06T22:54:06.609Z,1694040846.609 [WetLabsBB2FL](INFO): Powering down
2023-09-06T22:54:10.434Z,1694040850.434 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-09-06T22:54:18.196Z,1694040858.196 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004726
2023-09-06T22:54:32.511Z,1694040872.511 [NAL9602](INFO): Powering up NAL9602
2023-09-06T22:54:37.582Z,1694040877.582 [CommandExec](IMPORTANT): got command failComponent
2023-09-06T22:54:37.582Z,1694040877.582 [CommandExec](IMPORTANT): Failed components:
2023-09-06T22:54:37.583Z,1694040877.583 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2023-09-06T22:54:43.415Z,1694040883.415 [NAL9602](INFO): NAL9602 initialized
2023-09-06T22:54:45.722Z,1694040885.722 [CommandExec](IMPORTANT): got command gfscan
2023-09-06T22:54:45.918Z,1694040885.918 [CBIT](IMPORTANT): Beginning ground fault scan
2023-09-06T22:54:56.847Z,1694040896.847 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-09-06T22:54:57.258Z,1694040897.258 [CBIT](IMPORTANT): Beginning ground fault scan
2023-09-06T22:55:08.181Z,1694040908.181 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002784
CHAN A1 (24V): 0.000442
CHAN A2 (12V): -0.002229
CHAN A3 (5V): -0.001522
CHAN B0 (3.3V): -0.000346
CHAN B1 (3.15aV): -0.000295
CHAN B2 (3.15bV): -0.000139
CHAN B3 (GND): -0.000386
OPEN: -0.000244
Full Scale: +/- 1 mA
2023-09-06T22:55:09.351Z,1694040909.351 [SBIT](IMPORTANT): Beginning Startup BIT
2023-09-06T22:55:09.355Z,1694040909.355 [CBIT](IMPORTANT): Beginning ground fault scan
2023-09-06T22:55:20.604Z,1694040920.604 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-09-06T22:55:21.012Z,1694040921.012 [CBIT](IMPORTANT): Beginning ground fault scan
2023-09-06T22:55:32.010Z,1694040932.010 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-09-06T22:55:32.398Z,1694040932.398 [CBIT](IMPORTANT): Beginning ground fault scan
2023-09-06T22:55:43.278Z,1694040943.278 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-09-06T22:55:43.279Z,1694040943.279 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2023-09-06T22:55:49.363Z,1694040949.363 [SBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.799875
2023-09-06T22:55:49.363Z,1694040949.363 [SBIT](FAULT): Control surface position failure.
2023-09-06T22:56:03.429Z,1694040963.429 [SBIT](CRITICAL): SBIT FAILED
2023-09-06T22:56:03.430Z,1694040963.430 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-09-06T22:56:03.430Z,1694040963.430 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2023-09-06T22:56:03.431Z,1694040963.431 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count;

2023-09-06T22:56:03.431Z,1694040963.431 [SBIT](IMPORTANT): BackseatComponent.loadAtStartup=1 bool;

2023-09-06T22:56:03.431Z,1694040963.431 [SBIT](IMPORTANT): BackseatComponent.simulateHardware=1 bool;

2023-09-06T22:56:03.431Z,1694040963.431 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2023-09-06T22:56:03.431Z,1694040963.431 [SBIT](IMPORTANT): CBIT.runElevOffsetCalc=1 bool;

2023-09-06T22:56:03.431Z,1694040963.431 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2023-09-06T22:56:03.431Z,1694040963.431 [SBIT](IMPORTANT): RudderServo.offsetAngle=0 degree;

2023-09-06T22:56:03.431Z,1694040963.431 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool;

2023-09-06T22:56:03.432Z,1694040963.432 [SBIT](IMPORTANT): ThrusterServo.currLimit=50 percent;

2023-09-06T22:56:03.432Z,1694040963.432 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=241.098459 cubic_centimeter;

2023-09-06T22:56:03.432Z,1694040963.432 [SBIT](IMPORTANT): VerticalControl.massDefault=-11.361481 millimeter;

2023-09-06T22:56:03.434Z,1694040963.434 [CBIT](INFO): Critical error at 20230906T225603
2023-09-06T22:56:03.828Z,1694040963.828 [MissionManager](IMPORTANT): Started mission Startup
2023-09-06T22:56:03.829Z,1694040963.829 [Startup] Running Loop=1
2023-09-06T22:56:03.829Z,1694040963.829 [Startup](DEBUG): Aggregate::initialize Startup
2023-09-06T22:56:03.829Z,1694040963.829 [Startup:A.GoToSurface] Running Loop=1
2023-09-06T22:56:03.829Z,1694040963.829 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-06T22:56:03.854Z,1694040963.854 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-06T22:56:03.854Z,1694040963.854 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-06T22:56:03.855Z,1694040963.855 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-06T22:56:03.855Z,1694040963.855 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-06T22:56:03.855Z,1694040963.855 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-06T22:56:03.861Z,1694040963.861 [Startup:StartupSatComms] Running Loop=1
2023-09-06T22:56:03.861Z,1694040963.861 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-09-06T22:56:03.862Z,1694040963.862 [Startup:StartupSatComms:A] Running Loop=1
2023-09-06T22:56:04.230Z,1694040964.230 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-09-06T22:56:39.359Z,1694040999.359 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-06T22:56:52.690Z,1694041012.690 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-06T22:57:04.017Z,1694041024.017 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-09-06T22:57:04.017Z,1694041024.017 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-06T22:57:04.019Z,1694041024.019 [Startup:StartupSatComms:A](INFO): Timed out from 2023-09-06T22:56:03.9Z
2023-09-06T22:57:04.019Z,1694041024.019 [Startup:StartupSatComms:A] Stopped
2023-09-06T22:57:04.019Z,1694041024.019 [Startup:StartupSatComms:B] Running Loop=1
2023-09-06T22:57:04.028Z,1694041024.028 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-06T22:57:04.431Z,1694041024.431 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-09-06T22:57:04.440Z,1694041024.440 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-06T22:57:04.440Z,1694041024.440 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-09-06T22:57:06.426Z,1694041026.426 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-06T22:57:10.690Z,1694041030.690 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230802T170310/Courier0063.lzma
2023-09-06T22:57:11.691Z,1694041031.691 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Courier0063.lzma.bak
2023-09-06T22:57:11.692Z,1694041031.692 [DataOverHttps](INFO): SBD MOMSN=18624781
2023-09-06T22:57:20.167Z,1694041040.167 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-06T22:57:27.654Z,1694041047.654 [DataOverHttps](INFO): Sending 238 bytes from file Logs/20230810T194631/Courier0000.lzma
2023-09-06T22:57:29.659Z,1694041049.659 [DataOverHttps](INFO): Moved sent file to Logs/20230810T194631/Courier0000.lzma.bak
2023-09-06T22:57:29.659Z,1694041049.659 [DataOverHttps](INFO): SBD MOMSN=18624783
2023-09-06T22:57:33.898Z,1694041053.898 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-06T22:57:45.217Z,1694041065.217 [DataOverHttps](INFO): Sending 262 bytes from file Logs/20230906T225352/Courier0000.lzma
2023-09-06T22:57:46.219Z,1694041066.219 [DataOverHttps](INFO): Moved sent file to Logs/20230906T225352/Courier0000.lzma.bak
2023-09-06T22:57:46.219Z,1694041066.219 [DataOverHttps](INFO): SBD MOMSN=18624788
2023-09-06T22:57:47.634Z,1694041067.634 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-06T22:58:01.682Z,1694041081.682 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20230802T170310/Express0064.lzma
2023-09-06T22:58:01.775Z,1694041081.775 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-06T22:58:02.683Z,1694041082.683 [DataOverHttps](INFO): Moved sent file to Logs/20230802T170310/Express0064.lzma.bak
2023-09-06T22:58:02.684Z,1694041082.684 [DataOverHttps](INFO): SBD MOMSN=18624798
2023-09-06T22:58:04.223Z,1694041084.223 [Startup:StartupSatComms:B](INFO): Timed out from 2023-09-06T22:57:04.0Z
2023-09-06T22:58:04.224Z,1694041084.224 [Startup:StartupSatComms:B] Stopped
2023-09-06T22:58:04.224Z,1694041084.224 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-09-06T22:58:04.224Z,1694041084.224 [Startup:StartupSatComms] Stopped
2023-09-06T22:58:04.224Z,1694041084.224 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-09-06T22:58:04.225Z,1694041084.225 [Startup](INFO): Completed Startup
2023-09-06T22:58:04.225Z,1694041084.225 [MissionManager](INFO): Startup is completed.
2023-09-06T22:58:04.225Z,1694041084.225 [MissionManager](INFO): Uninitializing Mission Startup
2023-09-06T22:58:04.225Z,1694041084.225 [Startup] Stopped
2023-09-06T22:58:04.225Z,1694041084.225 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-09-06T22:58:04.225Z,1694041084.225 [Startup:A.GoToSurface] Stopped
2023-09-06T22:58:04.225Z,1694041084.225 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-06T22:58:04.632Z,1694041084.632 [MissionManager](IMPORTANT): Started mission Default
2023-09-06T22:58:04.632Z,1694041084.632 [Default] Running Loop=1
2023-09-06T22:58:04.633Z,1694041084.633 [Default](DEBUG): Aggregate::initialize Default
2023-09-06T22:58:04.633Z,1694041084.633 [Default:B.GoToSurface] Running Loop=1
2023-09-06T22:58:04.633Z,1694041084.633 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-06T22:58:04.633Z,1694041084.633 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-06T22:58:04.634Z,1694041084.634 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-06T22:58:04.634Z,1694041084.634 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-06T22:58:04.634Z,1694041084.634 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-06T22:58:04.634Z,1694041084.634 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-06T22:58:04.635Z,1694041084.635 [Default:A.Wait] Running Loop=1
2023-09-06T22:58:04.635Z,1694041084.635 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-09-06T22:58:15.914Z,1694041095.914 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-06T22:58:17.952Z,1694041097.952 [Default:A.Wait](INFO): Done Waiting.
2023-09-06T22:58:17.952Z,1694041097.952 [Default:A.Wait] Stopped
2023-09-06T22:58:17.952Z,1694041097.952 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-09-06T22:58:18.356Z,1694041098.356 [Default:CheckIn] Running Loop=1
2023-09-06T22:58:18.356Z,1694041098.356 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-06T22:58:18.356Z,1694041098.356 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-06T22:58:18.774Z,1694041098.774 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-09-06T22:58:22.375Z,1694041102.375 [CommandExec](IMPORTANT): got command failComponent
2023-09-06T22:58:22.375Z,1694041102.375 [CommandExec](IMPORTANT): Failed components:
2023-09-06T22:58:22.375Z,1694041102.375 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2023-09-06T22:58:25.919Z,1694041105.919 [CommandExec](IMPORTANT): got command help failComponent
2023-09-06T22:58:30.054Z,1694041110.054 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-06T22:58:40.719Z,1694041120.719 [DataOverHttps](INFO): Sending 413 bytes from file Logs/20230810T194631/Express0001.lzma
2023-09-06T22:58:41.017Z,1694041121.017 [CommandExec](IMPORTANT): got command failComponent none DropWeight
2023-09-06T22:58:41.018Z,1694041121.018 [DropWeight] No Fault, FailCount= 1
2023-09-06T22:58:41.018Z,1694041121.018 [CommandExec](IMPORTANT): DropWeight failureMode is No Fault
2023-09-06T22:58:41.719Z,1694041121.719 [DataOverHttps](INFO): Moved sent file to Logs/20230810T194631/Express0001.lzma.bak
2023-09-06T22:58:41.719Z,1694041121.719 [DataOverHttps](INFO): SBD MOMSN=18624813
2023-09-06T22:58:43.790Z,1694041123.790 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-06T22:58:45.390Z,1694041125.390 [CommandExec](IMPORTANT): got command failComponent
2023-09-06T22:58:45.390Z,1694041125.390 [CommandExec](IMPORTANT): Failed components:
2023-09-06T22:58:45.390Z,1694041125.390 [CommandExec](IMPORTANT): No failed Components.
2023-09-06T22:58:54.763Z,1694041134.763 [CommandExec](IMPORTANT): got command restart application
2023-09-06T22:58:55.765Z,1694041135.765 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-06T22:58:55.766Z,1694041135.766 [CommandExec](INFO): Uninitializing the command executive.
2023-09-06T22:58:55.766Z,1694041135.766 [CommandExec](INFO): Uninitializing the command scheduler.
2023-09-06T22:58:55.766Z,1694041135.766 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:58:55.853Z,1694041135.853 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-09-06T22:58:55.853Z,1694041135.853 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-09-06T22:58:55.854Z,1694041135.854 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:58:55.854Z,1694041135.854 [NavChartDb](INFO): Join timeout helper Thread ID is 5986
2023-09-06T22:58:56.046Z,1694041136.046 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-06T22:58:56.046Z,1694041136.046 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:58:56.061Z,1694041136.061 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-09-06T22:58:56.062Z,1694041136.062 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:58:56.062Z,1694041136.062 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5987
2023-09-06T22:58:56.454Z,1694041136.454 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-06T22:58:56.454Z,1694041136.454 [WetLabsBB2FL](INFO): Powering down
2023-09-06T22:58:56.454Z,1694041136.454 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:58:56.469Z,1694041136.469 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-09-06T22:58:56.469Z,1694041136.469 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:58:56.470Z,1694041136.470 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5988
2023-09-06T22:58:57.198Z,1694041137.198 [CTD_Seabird](INFO): Powering down
2023-09-06T22:58:57.215Z,1694041137.215 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-06T22:58:57.216Z,1694041137.216 [CTD_Seabird](INFO): Powering down
2023-09-06T22:58:57.230Z,1694041137.230 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:58:57.237Z,1694041137.237 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-09-06T22:58:57.238Z,1694041137.238 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:58:57.238Z,1694041137.238 [Radio_Surface](INFO): Join timeout helper Thread ID is 5989
2023-09-06T22:58:57.302Z,1694041137.302 [Radio_Surface](INFO): Powering down
2023-09-06T22:58:57.302Z,1694041137.302 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-06T22:58:57.303Z,1694041137.303 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:58:57.317Z,1694041137.317 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-09-06T22:58:57.318Z,1694041137.318 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:58:57.318Z,1694041137.318 [Onboard](INFO): Join timeout helper Thread ID is 5990
2023-09-06T22:58:57.782Z,1694041137.782 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-09-06T22:58:58.742Z,1694041138.742 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-09-06T22:58:59.202Z,1694041139.202 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-06T22:58:59.202Z,1694041139.202 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:58:59.206Z,1694041139.206 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-09-06T22:58:59.207Z,1694041139.207 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:58:59.207Z,1694041139.207 [DataOverHttps](INFO): Join timeout helper Thread ID is 5991
2023-09-06T22:58:59.789Z,1694041139.789 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-06T22:58:59.790Z,1694041139.790 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:58:59.794Z,1694041139.794 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-09-06T22:58:59.794Z,1694041139.794 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:58:59.795Z,1694041139.795 [BackseatComponent](INFO): Join timeout helper Thread ID is 5992
2023-09-06T22:58:59.942Z,1694041139.942 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-06T22:58:59.942Z,1694041139.942 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:58:59.962Z,1694041139.962 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-09-06T22:58:59.962Z,1694041139.962 [logger ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:58:59.963Z,1694041139.963 [logger](INFO): Join timeout helper Thread ID is 5993
2023-09-06T22:58:59.974Z,1694041139.974 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-06T22:58:59.974Z,1694041139.974 [logger ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:58:59.986Z,1694041139.986 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-09-06T22:58:59.986Z,1694041139.986 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:58:59.987Z,1694041139.987 [CommandLine](INFO): Join timeout helper Thread ID is 5994
2023-09-06T22:59:00.050Z,1694041140.050 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-06T22:59:00.050Z,1694041140.050 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:59:00.066Z,1694041140.066 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-09-06T22:59:00.066Z,1694041140.066 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:59:00.067Z,1694041140.067 [CommandExec](INFO): Join timeout helper Thread ID is 5995
2023-09-06T22:59:00.068Z,1694041140.068 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-09-06T22:59:00.068Z,1694041140.068 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:59:00.068Z,1694041140.068 [controlThread](INFO): Join timeout helper Thread ID is 5996
2023-09-06T22:59:00.349Z,1694041140.349 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-06T22:59:00.350Z,1694041140.350 [controlThread](DEBUG): Uninitializing ControlThread
2023-09-06T22:59:00.350Z,1694041140.350 [AHRS_M2](INFO): Powering down
2023-09-06T22:59:00.423Z,1694041140.423 [NAL9602](INFO): Powering down
2023-09-06T22:59:00.425Z,1694041140.425 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-09-06T22:59:00.426Z,1694041140.426 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-09-06T22:59:00.427Z,1694041140.427 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-09-06T22:59:00.427Z,1694041140.427 [MissionManager](INFO): Uninitializing Mission Default
2023-09-06T22:59:00.427Z,1694041140.427 [Default] Stopped
2023-09-06T22:59:00.427Z,1694041140.427 [Default](DEBUG): Aggregate::uninitialize Default
2023-09-06T22:59:00.427Z,1694041140.427 [Default:B.GoToSurface] Stopped
2023-09-06T22:59:00.427Z,1694041140.427 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-06T22:59:00.428Z,1694041140.428 [Default:CheckIn] Stopped
2023-09-06T22:59:00.428Z,1694041140.428 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-06T22:59:00.428Z,1694041140.428 [Default:CheckIn:Read_GPS] Stopped
2023-09-06T22:59:00.431Z,1694041140.431 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-09-06T22:59:00.431Z,1694041140.431 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-09-06T22:59:00.431Z,1694041140.431 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-09-06T22:59:00.431Z,1694041140.431 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-09-06T22:59:00.432Z,1694041140.432 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-09-06T22:59:00.432Z,1694041140.432 [BuoyancyServo](INFO): Powering down
2023-09-06T22:59:00.446Z,1694041140.446 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-09-06T22:59:00.446Z,1694041140.446 [ElevatorServo](INFO): Powering down
2023-09-06T22:59:00.447Z,1694041140.447 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-09-06T22:59:00.447Z,1694041140.447 [MassServo](INFO): Powering down
2023-09-06T22:59:00.448Z,1694041140.448 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-09-06T22:59:00.448Z,1694041140.448 [RudderServo](INFO): Powering down
2023-09-06T22:59:00.448Z,1694041140.448 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-09-06T22:59:00.449Z,1694041140.449 [ThrusterHE](INFO): Powering down
2023-09-06T22:59:00.450Z,1694041140.450 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-09-06T22:59:00.450Z,1694041140.450 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-09-06T22:59:00.450Z,1694041140.450 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-09-06T22:59:00.450Z,1694041140.450 [CBIT](DEBUG): Powering off loads.
2023-09-06T22:59:00.461Z,1694041140.461 [CBIT](DEBUG): Disabling WDT.
2023-09-06T22:59:00.473Z,1694041140.473 [CBIT](DEBUG): Opening all GF detection circuits.
2023-09-06T22:59:00.474Z,1694041140.474 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:59:00.526Z,1694041140.526 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:59:00.529Z,1694041140.529 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:59:00.534Z,1694041140.534 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:59:00.578Z,1694041140.578 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:59:00.611Z,1694041140.611 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:59:00.615Z,1694041140.615 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:59:00.660Z,1694041140.660 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-09-06T22:59:00.722Z,1694041140.722 [logger ThreadHandler](INFO): Thread cancelled.