2022-09-21T22:42:29.858Z,1663800149.858 [Supervisor](DEBUG): Initializing supervisor.
2022-09-21T22:42:29.863Z,1663800149.863 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-09-21T22:42:29.864Z,1663800149.864 [SyncHandler](INFO): Protected caller Thread ID is 1951
2022-09-21T22:42:29.864Z,1663800149.864 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-09-21T22:42:29.866Z,1663800149.866 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-09-21T22:42:29.866Z,1663800149.866 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1952
2022-09-21T22:42:29.871Z,1663800149.871 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-09-21T22:42:29.889Z,1663800149.889 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-09-21T22:42:29.890Z,1663800149.890 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-09-21T22:42:29.890Z,1663800149.890 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1953
2022-09-21T22:42:29.895Z,1663800149.895 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-09-21T22:42:29.896Z,1663800149.896 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-09-21T22:42:29.896Z,1663800149.896 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1954
2022-09-21T22:42:29.898Z,1663800149.898 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-09-21T22:42:29.899Z,1663800149.899 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-09-21T22:42:29.900Z,1663800149.900 [logger ThreadHandler](INFO): Protected caller Thread ID is 1955
2022-09-21T22:42:29.903Z,1663800149.903 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-09-21T22:42:29.904Z,1663800149.904 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-09-21T22:42:29.905Z,1663800149.905 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-09-21T22:42:30.004Z,1663800150.004 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-09-21T22:42:30.004Z,1663800150.004 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-09-21T22:42:30.703Z,1663800150.703 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-09-21T22:42:30.703Z,1663800150.703 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-09-21T22:42:30.941Z,1663800150.941 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-09-21T22:42:30.941Z,1663800150.941 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-09-21T22:42:31.021Z,1663800151.021 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-09-21T22:42:31.150Z,1663800151.150 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-09-21T22:42:31.150Z,1663800151.150 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-09-21T22:42:31.232Z,1663800151.232 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-09-21T22:42:31.344Z,1663800151.344 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-09-21T22:42:31.345Z,1663800151.345 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-09-21T22:42:31.741Z,1663800151.741 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-09-21T22:42:31.741Z,1663800151.741 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-09-21T22:42:32.344Z,1663800152.344 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-09-21T22:42:32.345Z,1663800152.345 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-09-21T22:42:32.575Z,1663800152.575 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-09-21T22:42:32.575Z,1663800152.575 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-09-21T22:42:32.779Z,1663800152.779 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-09-21T22:42:32.779Z,1663800152.779 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-09-21T22:42:33.760Z,1663800153.760 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-09-21T22:42:33.761Z,1663800153.761 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-09-21T22:42:34.167Z,1663800154.167 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-09-21T22:42:34.168Z,1663800154.168 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-09-21T22:42:34.400Z,1663800154.400 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-09-21T22:42:34.400Z,1663800154.400 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-09-21T22:42:34.792Z,1663800154.792 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-09-21T22:42:34.793Z,1663800154.793 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-09-21T22:42:35.155Z,1663800155.155 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-09-21T22:42:35.157Z,1663800155.157 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2022-09-21T22:42:35.158Z,1663800155.158 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2022-09-21T22:42:35.243Z,1663800155.243 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2022-09-21T22:42:35.408Z,1663800155.408 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2022-09-21T22:42:35.522Z,1663800155.522 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2022-09-21T22:42:35.610Z,1663800155.610 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2022-09-21T22:42:35.707Z,1663800155.707 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2022-09-21T22:42:35.902Z,1663800155.902 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2022-09-21T22:42:36.178Z,1663800156.178 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-09-21T22:42:36.178Z,1663800156.178 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2022-09-21T22:42:36.275Z,1663800156.275 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2022-09-21T22:42:36.377Z,1663800156.377 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2022-09-21T22:42:36.526Z,1663800156.526 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2022-09-21T22:42:36.630Z,1663800156.630 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2022-09-21T22:42:36.631Z,1663800156.631 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-09-21T22:42:36.647Z,1663800156.647 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-09-21T22:42:36.750Z,1663800156.750 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-09-21T22:42:36.751Z,1663800156.751 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-09-21T22:42:36.806Z,1663800156.806 [VerticalControl](DEBUG): Construct VerticalControl.
2022-09-21T22:42:36.869Z,1663800156.869 [VerticalControl] Loaded
2022-09-21T22:42:36.870Z,1663800156.870 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-09-21T22:42:36.872Z,1663800156.872 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-09-21T22:42:36.914Z,1663800156.914 [HorizontalControl] Loaded
2022-09-21T22:42:36.914Z,1663800156.914 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-09-21T22:42:36.916Z,1663800156.916 [SpeedControl](DEBUG): Construct SpeedControl.
2022-09-21T22:42:36.920Z,1663800156.920 [SpeedControl] Loaded
2022-09-21T22:42:36.920Z,1663800156.920 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-09-21T22:42:36.923Z,1663800156.923 [LoopControl](DEBUG): Construct LoopControl.
2022-09-21T22:42:36.923Z,1663800156.923 [LoopControl] Loaded
2022-09-21T22:42:36.924Z,1663800156.924 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-09-21T22:42:36.924Z,1663800156.924 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-09-21T22:42:36.924Z,1663800156.924 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-09-21T22:42:36.978Z,1663800156.978 [DepthRateCalculator] Loaded
2022-09-21T22:42:36.979Z,1663800156.978 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-09-21T22:42:36.983Z,1663800156.983 [PitchRateCalculator] Loaded
2022-09-21T22:42:36.984Z,1663800156.984 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-09-21T22:42:36.995Z,1663800156.995 [SpeedCalculator] Loaded
2022-09-21T22:42:36.995Z,1663800156.995 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-09-21T22:42:36.999Z,1663800156.999 [YawRateCalculator] Loaded
2022-09-21T22:42:36.000Z,1663800157.000 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-09-21T22:42:37.018Z,1663800157.018 [ElevatorOffsetCalculator] Loaded
2022-09-21T22:42:37.018Z,1663800157.018 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-09-21T22:42:37.019Z,1663800157.019 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-09-21T22:42:37.019Z,1663800157.019 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-09-21T22:42:37.047Z,1663800157.047 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-09-21T22:42:37.048Z,1663800157.048 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-09-21T22:42:37.108Z,1663800157.108 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-09-21T22:42:37.108Z,1663800157.108 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-09-21T22:42:37.442Z,1663800157.442 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-09-21T22:42:37.442Z,1663800157.442 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-09-21T22:42:37.556Z,1663800157.556 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-09-21T22:42:37.556Z,1663800157.556 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-09-21T22:42:37.995Z,1663800157.995 [AHRS_M2] Loaded
2022-09-21T22:42:37.996Z,1663800157.996 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-09-21T22:42:38.032Z,1663800158.032 [BackseatComponent] Loaded
2022-09-21T22:42:38.032Z,1663800158.032 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-09-21T22:42:38.033Z,1663800158.033 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4096D4E0
2022-09-21T22:42:38.033Z,1663800158.033 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 2037
2022-09-21T22:42:38.036Z,1663800158.036 [LcmUniversalReporter] Loaded
2022-09-21T22:42:38.036Z,1663800158.036 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-09-21T22:42:38.878Z,1663800158.878 [BPC1] Loaded
2022-09-21T22:42:38.878Z,1663800158.878 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-09-21T22:42:38.953Z,1663800158.953 [DataOverHttps] Loaded
2022-09-21T22:42:38.953Z,1663800158.953 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-09-21T22:42:38.954Z,1663800158.954 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4099D4E0
2022-09-21T22:42:38.954Z,1663800158.954 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2038
2022-09-21T22:42:38.975Z,1663800158.975 [Depth_Keller] Loaded
2022-09-21T22:42:38.976Z,1663800158.976 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-09-21T22:42:38.981Z,1663800158.981 [DropWeight] Loaded
2022-09-21T22:42:38.981Z,1663800158.981 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-09-21T22:42:39.044Z,1663800159.044 [NAL9602] Loaded
2022-09-21T22:42:39.044Z,1663800159.044 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-09-21T22:42:39.074Z,1663800159.074 [Onboard] Loaded
2022-09-21T22:42:39.074Z,1663800159.074 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-09-21T22:42:39.075Z,1663800159.075 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409CD4E0
2022-09-21T22:42:39.075Z,1663800159.075 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2039
2022-09-21T22:42:39.087Z,1663800159.087 [Power24vConverter] Loaded
2022-09-21T22:42:39.088Z,1663800159.088 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-09-21T22:42:39.101Z,1663800159.101 [Radio_Surface] Loaded
2022-09-21T22:42:39.101Z,1663800159.101 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-09-21T22:42:39.102Z,1663800159.102 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409FD4E0
2022-09-21T22:42:39.102Z,1663800159.102 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2040
2022-09-21T22:42:39.148Z,1663800159.148 [RDI_Pathfinder] Loaded
2022-09-21T22:42:39.148Z,1663800159.148 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2022-09-21T22:42:39.237Z,1663800159.237 [DAT] Loaded
2022-09-21T22:42:39.237Z,1663800159.237 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2022-09-21T22:42:39.238Z,1663800159.238 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A2D4E0
2022-09-21T22:42:39.238Z,1663800159.238 [DAT ThreadHandler](INFO): Protected caller Thread ID is 2041
2022-09-21T22:42:39.239Z,1663800159.239 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-09-21T22:42:39.239Z,1663800159.239 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-09-21T22:42:39.686Z,1663800159.686 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-09-21T22:42:39.686Z,1663800159.686 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-09-21T22:42:39.700Z,1663800159.700 [NavChart] Loaded
2022-09-21T22:42:39.700Z,1663800159.700 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-09-21T22:42:39.706Z,1663800159.706 [UniversalFixResidualReporter] Loaded
2022-09-21T22:42:39.707Z,1663800159.707 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-09-21T22:42:39.707Z,1663800159.707 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-09-21T22:42:39.708Z,1663800159.708 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-09-21T22:42:40.097Z,1663800160.097 [SBIT](DEBUG): Construct Startup Built In Test.
2022-09-21T22:42:40.108Z,1663800160.108 [SBIT] Loaded
2022-09-21T22:42:40.108Z,1663800160.108 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-09-21T22:42:40.111Z,1663800160.111 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-09-21T22:42:40.125Z,1663800160.125 [IBIT] Loaded
2022-09-21T22:42:40.125Z,1663800160.125 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-09-21T22:42:40.131Z,1663800160.131 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-09-21T22:42:40.446Z,1663800160.446 [CBIT] Loaded
2022-09-21T22:42:40.446Z,1663800160.446 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-09-21T22:42:40.447Z,1663800160.447 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-09-21T22:42:40.447Z,1663800160.447 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-09-21T22:42:40.803Z,1663800160.803 [BuoyancyServo] Loaded
2022-09-21T22:42:40.803Z,1663800160.803 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-09-21T22:42:40.825Z,1663800160.825 [ElevatorServo] Loaded
2022-09-21T22:42:40.825Z,1663800160.825 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-09-21T22:42:40.845Z,1663800160.845 [MassServo] Loaded
2022-09-21T22:42:40.845Z,1663800160.845 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-09-21T22:42:40.864Z,1663800160.864 [RudderServo] Loaded
2022-09-21T22:42:40.865Z,1663800160.865 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-09-21T22:42:40.883Z,1663800160.883 [ThrusterServo] Loaded
2022-09-21T22:42:40.884Z,1663800160.884 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2022-09-21T22:42:40.884Z,1663800160.884 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-09-21T22:42:40.885Z,1663800160.885 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-09-21T22:42:40.896Z,1663800160.896 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-09-21T22:42:40.896Z,1663800160.896 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-09-21T22:42:41.076Z,1663800161.076 [CTD_Seabird] Loaded
2022-09-21T22:42:41.077Z,1663800161.077 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-09-21T22:42:41.078Z,1663800161.078 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B774E0
2022-09-21T22:42:41.078Z,1663800161.078 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2042
2022-09-21T22:42:41.099Z,1663800161.099 [PAR_Licor] Loaded
2022-09-21T22:42:41.099Z,1663800161.099 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-09-21T22:42:41.133Z,1663800161.133 [WetLabsBB2FL] Loaded
2022-09-21T22:42:41.133Z,1663800161.133 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-09-21T22:42:41.134Z,1663800161.134 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BA74E0
2022-09-21T22:42:41.135Z,1663800161.135 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2043
2022-09-21T22:42:41.156Z,1663800161.156 [WetLabsUBAT] Loaded
2022-09-21T22:42:41.156Z,1663800161.156 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2022-09-21T22:42:41.157Z,1663800161.157 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40BD74E0
2022-09-21T22:42:41.158Z,1663800161.158 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 2044
2022-09-21T22:42:41.158Z,1663800161.158 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-09-21T22:42:41.165Z,1663800161.165 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-09-21T22:42:41.167Z,1663800161.167 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-09-21T22:42:41.178Z,1663800161.178 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-09-21T22:42:41.179Z,1663800161.179 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0
2022-09-21T22:42:41.180Z,1663800161.180 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2045
2022-09-21T22:42:41.184Z,1663800161.184 [Supervisor](INFO): Main Thread ID is 830
2022-09-21T22:42:41.184Z,1663800161.184 [Supervisor](DEBUG): Running supervisor.
2022-09-21T22:42:41.185Z,1663800161.185 [CommandExec ThreadHandler](INFO): Handler Thread ID is 2046
2022-09-21T22:42:41.185Z,1663800161.185 [CommandExec](INFO): Initializing the command executive.
2022-09-21T22:42:41.187Z,1663800161.187 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2047
2022-09-21T22:42:41.189Z,1663800161.189 [controlThread ThreadHandler](INFO): Handler Thread ID is 2048
2022-09-21T22:42:41.189Z,1663800161.189 [controlThread](DEBUG): Initializing ControlThread
2022-09-21T22:42:41.190Z,1663800161.190 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-09-21T22:42:41.192Z,1663800161.192 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-09-21T22:42:41.193Z,1663800161.193 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-09-21T22:42:41.193Z,1663800161.193 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-09-21T22:42:41.193Z,1663800161.193 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-09-21T22:42:41.194Z,1663800161.194 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-09-21T22:42:41.194Z,1663800161.194 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-09-21T22:42:41.195Z,1663800161.195 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-09-21T22:42:41.195Z,1663800161.195 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-09-21T22:42:41.199Z,1663800161.199 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-09-21T22:42:41.200Z,1663800161.200 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-09-21T22:42:41.200Z,1663800161.200 [SBIT](INFO): Initialize SBIT Component.
2022-09-21T22:42:41.200Z,1663800161.200 [SBIT](IMPORTANT): git: 2022-06-07-3-g3731f8b3f
2022-09-21T22:42:41.201Z,1663800161.201 [SBIT](INFO): git hash: 3731f8b3f5f07755356c125fa6a04ab4ea762347
2022-09-21T22:42:41.201Z,1663800161.201 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-09-21T22:42:41.202Z,1663800161.202 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2022-09-21T22:42:41.203Z,1663800161.203 [SBIT](INFO): Beginning SBIT in 50.000000 seconds.
2022-09-21T22:42:41.204Z,1663800161.204 [IBIT](INFO): Initialize IBIT Component.
2022-09-21T22:42:41.204Z,1663800161.204 [CBIT](DEBUG): Initialize CBIT Component.
2022-09-21T22:42:41.206Z,1663800161.206 [logger ThreadHandler](INFO): Handler Thread ID is 2049
2022-09-21T22:42:41.217Z,1663800161.217 [CBIT](DEBUG): Initialized mux pins.
2022-09-21T22:42:41.217Z,1663800161.217 [CBIT](DEBUG): Initializing the watchdog timer.
2022-09-21T22:42:41.225Z,1663800161.225 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 2050
2022-09-21T22:42:41.237Z,1663800161.237 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2051
2022-09-21T22:42:41.238Z,1663800161.238 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-09-21T22:42:41.241Z,1663800161.241 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-09-21T22:42:41.241Z,1663800161.241 [CBIT](DEBUG): Initializing heartbeat.
2022-09-21T22:42:41.249Z,1663800161.249 [Onboard ThreadHandler](INFO): Handler Thread ID is 2052
2022-09-21T22:42:41.267Z,1663800161.267 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2053
2022-09-21T22:42:41.285Z,1663800161.285 [DAT ThreadHandler](INFO): Handler Thread ID is 2054
2022-09-21T22:42:41.286Z,1663800161.286 [DAT](INFO): Powering up
2022-09-21T22:42:41.286Z,1663800161.286 [DAT](DEBUG): Initializing DAT.
2022-09-21T22:42:41.291Z,1663800161.291 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2056
2022-09-21T22:42:41.291Z,1663800161.291 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-09-21T22:42:41.294Z,1663800161.294 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2058
2022-09-21T22:42:41.297Z,1663800161.297 [WetLabsBB2FL](INFO): Powering up
2022-09-21T22:42:41.298Z,1663800161.298 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 2060
2022-09-21T22:42:41.302Z,1663800161.302 [WetLabsUBAT](INFO): Powering up
2022-09-21T22:42:41.303Z,1663800161.303 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2062
2022-09-21T22:42:41.306Z,1663800161.306 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-09-21T22:42:41.306Z,1663800161.306 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-09-21T22:42:41.306Z,1663800161.306 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-09-21T22:42:41.306Z,1663800161.306 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-09-21T22:42:41.307Z,1663800161.307 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-09-21T22:42:41.307Z,1663800161.307 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-09-21T22:42:41.307Z,1663800161.307 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-09-21T22:42:41.307Z,1663800161.307 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-09-21T22:42:41.313Z,1663800161.313 [CBIT](DEBUG): Deactivating GF circuits.
2022-09-21T22:42:41.313Z,1663800161.313 [CBIT](DEBUG): Deactivating emergency mode.
2022-09-21T22:42:41.349Z,1663800161.349 [CBIT](DEBUG): Backplane powered.
2022-09-21T22:42:41.354Z,1663800161.354 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-09-21T22:42:41.366Z,1663800161.366 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-09-21T22:42:41.399Z,1663800161.399 [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>


2022-09-21T22:42:41.399Z,1663800161.399 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-09-21T22:42:41.476Z,1663800161.476 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-09-21T22:42:41.478Z,1663800161.478 [Default:A.Wait](DEBUG): Construct Wait.
2022-09-21T22:42:41.480Z,1663800161.480 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-09-21T22:42:41.532Z,1663800161.532 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-09-21T22:42:41.534Z,1663800161.534 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-09-21T22:42:41.548Z,1663800161.548 [Default:E.Execute](DEBUG): Construct Execute.
2022-09-21T22:42:41.552Z,1663800161.552 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2022-09-21T22:42:41.573Z,1663800161.573 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-09-21T22:42:41.589Z,1663800161.589 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-09-21T22:42:41.663Z,1663800161.663 [Power24vConverter](INFO): Powering up.
2022-09-21T22:42:41.664Z,1663800161.664 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2022-09-21T22:42:41.673Z,1663800161.673 [Radio_Surface](INFO): Powering up
2022-09-21T22:42:41.746Z,1663800161.746 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-09-21T22:42:41.757Z,1663800161.757 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-09-21T22:42:41.758Z,1663800161.758 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-09-21T22:42:41.765Z,1663800161.765 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-09-21T22:42:41.766Z,1663800161.766 [MassServo](DEBUG): Initializing EZServoServo.
2022-09-21T22:42:41.773Z,1663800161.773 [MassServo](DEBUG): Initializing MassServo.
2022-09-21T22:42:41.774Z,1663800161.774 [RudderServo](DEBUG): Initializing EZServoServo.
2022-09-21T22:42:41.785Z,1663800161.785 [RudderServo](DEBUG): Initializing RudderServo.
2022-09-21T22:42:41.786Z,1663800161.786 [ThrusterServo](DEBUG): Initializing EZServoServo.
2022-09-21T22:42:41.793Z,1663800161.793 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2022-09-21T22:42:42.006Z,1663800162.006 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-09-21T22:42:42.006Z,1663800162.006 [DropWeight] Hardware Fault, FailCount= 1
2022-09-21T22:42:42.006Z,1663800162.006 [DropWeight](ERROR): Hardware Fault
2022-09-21T22:42:42.024Z,1663800162.024 [CommandExec](FAULT): Scheduling is paused
2022-09-21T22:42:42.033Z,1663800162.033 [CBIT](INFO): Critical error at 20220921T224242
2022-09-21T22:42:42.033Z,1663800162.033 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2022-09-21T22:42:42.035Z,1663800162.035 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-09-21T22:42:42.036Z,1663800162.036 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-09-21T22:42:42.733Z,1663800162.733 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-09-21T22:42:42.733Z,1663800162.733 [RudderServo](FAULT): Rudder failed to initialize
2022-09-21T22:42:42.733Z,1663800162.733 [RudderServo] Communications Fault, FailCount= 1
2022-09-21T22:42:42.733Z,1663800162.733 [RudderServo](ERROR): Communications Fault
2022-09-21T22:42:42.831Z,1663800162.831 [CBIT](INFO): Critical error at 20220921T224242
2022-09-21T22:42:42.834Z,1663800162.834 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-09-21T22:42:42.998Z,1663800162.998 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-09-21T22:42:42.998Z,1663800162.998 [RudderServo](INFO): Powering down
2022-09-21T22:42:43.669Z,1663800163.669 [RudderServo](DEBUG): Initializing EZServoServo.
2022-09-21T22:42:43.790Z,1663800163.790 [RudderServo](DEBUG): Initializing RudderServo.
2022-09-21T22:42:43.795Z,1663800163.795 [CBIT](INFO): Clearing failed state for component RudderServo
2022-09-21T22:42:43.795Z,1663800163.795 [RudderServo] No Fault, FailCount= 1
2022-09-21T22:42:43.909Z,1663800163.909 [WetLabsBB2FL](INFO): Powering down
2022-09-21T22:42:50.561Z,1663800170.561 [WetLabsUBAT](INFO): Powering down
2022-09-21T22:42:53.130Z,1663800173.130 [DAT](INFO): DAT read: 

2022-09-21T22:42:53.131Z,1663800173.131 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2022-09-21T22:42:54.894Z,1663800174.894 [DAT](INFO): DAT read: MF Frequency Band

2022-09-21T22:42:54.895Z,1663800174.895 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2022-09-21T22:42:54.896Z,1663800174.896 [DAT](INFO): DAT read: Sep 21 2022 22:42:43 

2022-09-21T22:42:55.902Z,1663800175.902 [DAT](INFO): DAT read: Features enabled [Bearing]

2022-09-21T22:42:55.903Z,1663800175.903 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2022-09-21T22:42:55.904Z,1663800175.904 [DAT](INFO): commRate: 800
2022-09-21T22:42:57.969Z,1663800177.969 [DAT](INFO): entering command mode
2022-09-21T22:42:58.170Z,1663800178.170 [DAT](INFO): DAT read: 

2022-09-21T22:42:58.170Z,1663800178.170 [DAT](INFO): DAT read: user:1>

2022-09-21T22:42:58.170Z,1663800178.170 [DAT](INFO): setting verbose to 3
2022-09-21T22:42:58.424Z,1663800178.424 [DAT](INFO): DAT read: user:1>

2022-09-21T22:42:58.425Z,1663800178.425 [DAT](INFO): DAT read: Verbose         | 3           

2022-09-21T22:42:58.425Z,1663800178.425 [DAT](INFO): set verbose to 3
2022-09-21T22:42:58.426Z,1663800178.426 [DAT](INFO): setting DatVerbose to 27440
2022-09-21T22:42:58.674Z,1663800178.674 [DAT](INFO): DAT read: user:2>

2022-09-21T22:42:58.675Z,1663800178.675 [DAT](INFO): DAT read: DatVerbose      | 27440       

2022-09-21T22:42:58.675Z,1663800178.675 [DAT](INFO): set DatVerbose to 27440
2022-09-21T22:42:58.676Z,1663800178.676 [DAT](INFO): setting transmit power to 8
2022-09-21T22:42:58.926Z,1663800178.926 [DAT](INFO): DAT read: user:3>

2022-09-21T22:42:58.927Z,1663800178.927 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2022-09-21T22:42:58.927Z,1663800178.927 [DAT](INFO): set transmit power to 8
2022-09-21T22:42:58.927Z,1663800178.927 [DAT](INFO): setting local address to 9
2022-09-21T22:42:59.179Z,1663800179.179 [DAT](INFO): DAT read: user:4>

2022-09-21T22:42:59.181Z,1663800179.181 [DAT](INFO): DAT read: LocalAddr       | 9           

2022-09-21T22:42:59.182Z,1663800179.182 [DAT](INFO): set local address to 9
2022-09-21T22:43:09.114Z,1663800189.114 [NAL9602](INFO): Powering up NAL9602
2022-09-21T22:43:20.034Z,1663800200.034 [NAL9602](INFO): NAL9602 initialized
2022-09-21T22:43:31.764Z,1663800211.764 [SBIT](IMPORTANT): Beginning Startup BIT
2022-09-21T22:43:31.769Z,1663800211.769 [CBIT](IMPORTANT): Beginning ground fault scan
2022-09-21T22:43:42.750Z,1663800222.750 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007445
CHAN A1 (24V): 0.001027
CHAN A2 (12V): -0.006671
CHAN A3 (5V): -0.002976
CHAN B0 (3.3V): -0.000428
CHAN B1 (3.15aV): -0.000797
CHAN B2 (3.15bV): -0.000175
CHAN B3 (GND): 0.001916
OPEN: 0.005562
Full Scale: +/- 1 mA
2022-09-21T22:44:25.531Z,1663800265.531 [SBIT](IMPORTANT): SBIT PASSED
2022-09-21T22:44:25.531Z,1663800265.531 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-09-21T22:44:25.532Z,1663800265.532 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2022-09-21T22:44:25.532Z,1663800265.532 [SBIT](IMPORTANT): CBIT.gf24Offset=143 microampere;

2022-09-21T22:44:25.532Z,1663800265.532 [SBIT](IMPORTANT): CBIT.gfScanTimeout=2 hour;

2022-09-21T22:44:25.532Z,1663800265.532 [SBIT](IMPORTANT): DAT.baud=115200 bit_per_second;

2022-09-21T22:44:25.532Z,1663800265.532 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;

2022-09-21T22:44:25.532Z,1663800265.532 [SBIT](IMPORTANT): DAT.surfaceThreshold=0 meter;

2022-09-21T22:44:25.537Z,1663800265.537 [SBIT](IMPORTANT): DAT.verbosity=2 count;

2022-09-21T22:44:25.537Z,1663800265.537 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;

2022-09-21T22:44:25.537Z,1663800265.537 [SBIT](IMPORTANT): Express none _.adinos;

2022-09-21T22:44:25.537Z,1663800265.537 [SBIT](IMPORTANT): Express none _.aother;

2022-09-21T22:44:25.537Z,1663800265.537 [SBIT](IMPORTANT): Express linearApproximation _.dinoflagellates 10000000000.000000 none;

2022-09-21T22:44:25.538Z,1663800265.538 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=24 ampere_hour;

2022-09-21T22:44:25.538Z,1663800265.538 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=13 volt;

2022-09-21T22:44:25.538Z,1663800265.538 [SBIT](IMPORTANT): Power24vConverter.simulateHardware=0 bool;

2022-09-21T22:44:25.538Z,1663800265.538 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=1 bool;

2022-09-21T22:44:25.538Z,1663800265.538 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=209.634716 cubic_centimeter;

2022-09-21T22:44:25.538Z,1663800265.538 [SBIT](IMPORTANT): VerticalControl.massDefault=7.723761 millimeter;

2022-09-21T22:44:25.903Z,1663800265.903 [MissionManager](IMPORTANT): Started mission Startup
2022-09-21T22:44:25.903Z,1663800265.903 [Startup] Running Loop=1
2022-09-21T22:44:25.903Z,1663800265.903 [Startup](DEBUG): Aggregate::initialize Startup
2022-09-21T22:44:25.904Z,1663800265.904 [Startup:A.GoToSurface] Running Loop=1
2022-09-21T22:44:25.904Z,1663800265.904 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-09-21T22:44:25.904Z,1663800265.904 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-09-21T22:44:25.905Z,1663800265.905 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-09-21T22:44:25.905Z,1663800265.905 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-09-21T22:44:25.906Z,1663800265.906 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-09-21T22:44:25.906Z,1663800265.906 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-09-21T22:44:25.907Z,1663800265.907 [Startup:StartupSatComms] Running Loop=1
2022-09-21T22:44:25.907Z,1663800265.907 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-09-21T22:44:25.907Z,1663800265.907 [Startup:StartupSatComms:A] Running Loop=1
2022-09-21T22:44:26.311Z,1663800266.311 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-09-21T22:44:43.229Z,1663800283.229 [CommandExec](IMPORTANT): got command failComponent
2022-09-21T22:44:43.230Z,1663800283.230 [CommandExec](IMPORTANT): Failed components:
2022-09-21T22:44:43.230Z,1663800283.230 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2022-09-21T22:44:56.582Z,1663800296.582 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2022-09-21T22:45:09.316Z,1663800309.316 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004613
2022-09-21T22:45:26.107Z,1663800326.107 [Startup:StartupSatComms:A](INFO): Timed out from 2022-09-21T22:44:25.9Z
2022-09-21T22:45:26.107Z,1663800326.107 [Startup:StartupSatComms:A] Stopped
2022-09-21T22:45:26.107Z,1663800326.107 [Startup:StartupSatComms:B] Running Loop=1
2022-09-21T22:45:26.514Z,1663800326.514 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-09-21T22:45:41.483Z,1663800341.483 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-09-21T22:45:41.483Z,1663800341.483 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-09-21T22:45:41.517Z,1663800341.517 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-09-21T22:45:41.875Z,1663800341.875 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-09-21T22:45:41.875Z,1663800341.875 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-09-21T22:46:26.282Z,1663800386.282 [Startup:StartupSatComms:B](INFO): Timed out from 2022-09-21T22:45:26.1Z
2022-09-21T22:46:26.283Z,1663800386.283 [Startup:StartupSatComms:B] Stopped
2022-09-21T22:46:26.283Z,1663800386.283 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-09-21T22:46:26.283Z,1663800386.283 [Startup:StartupSatComms] Stopped
2022-09-21T22:46:26.283Z,1663800386.283 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-09-21T22:46:26.284Z,1663800386.284 [Startup](INFO): Completed Startup
2022-09-21T22:46:26.284Z,1663800386.284 [MissionManager](INFO): Startup is completed.
2022-09-21T22:46:26.284Z,1663800386.284 [MissionManager](INFO): Uninitializing Mission Startup
2022-09-21T22:46:26.284Z,1663800386.284 [Startup] Stopped
2022-09-21T22:46:26.284Z,1663800386.284 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-09-21T22:46:26.284Z,1663800386.284 [Startup:A.GoToSurface] Stopped
2022-09-21T22:46:26.284Z,1663800386.284 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-09-21T22:46:26.690Z,1663800386.690 [MissionManager](IMPORTANT): Started mission Default
2022-09-21T22:46:26.690Z,1663800386.690 [Default] Running Loop=1
2022-09-21T22:46:26.690Z,1663800386.690 [Default](DEBUG): Aggregate::initialize Default
2022-09-21T22:46:26.691Z,1663800386.691 [Default:B.GoToSurface] Running Loop=1
2022-09-21T22:46:26.691Z,1663800386.691 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-09-21T22:46:26.691Z,1663800386.691 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-09-21T22:46:26.691Z,1663800386.691 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-09-21T22:46:26.691Z,1663800386.691 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-09-21T22:46:26.692Z,1663800386.692 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-09-21T22:46:26.692Z,1663800386.692 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-09-21T22:46:26.692Z,1663800386.692 [Default:A.Wait] Running Loop=1
2022-09-21T22:46:26.692Z,1663800386.692 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-09-21T22:46:40.039Z,1663800400.039 [Default:A.Wait](INFO): Done Waiting.
2022-09-21T22:46:40.040Z,1663800400.040 [Default:A.Wait] Stopped
2022-09-21T22:46:40.040Z,1663800400.040 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-09-21T22:46:40.448Z,1663800400.448 [Default:CheckIn] Running Loop=1
2022-09-21T22:46:40.448Z,1663800400.448 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-21T22:46:40.448Z,1663800400.448 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-21T22:46:40.827Z,1663800400.827 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-09-21T22:48:23.030Z,1663800503.030 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-09-21T22:48:42.450Z,1663800522.450 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-09-21T22:48:42.450Z,1663800522.450 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-09-21T22:48:42.461Z,1663800522.461 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-09-21T22:48:42.864Z,1663800522.864 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-09-21T22:48:42.864Z,1663800522.864 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-09-21T22:49:15.982Z,1663800555.982 [CommandExec](IMPORTANT): got command restart system
2022-09-21T22:49:18.037Z,1663800558.037 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-21T22:49:18.037Z,1663800558.037 [CommandExec](INFO): Uninitializing the command executive.
2022-09-21T22:49:18.037Z,1663800558.037 [CommandExec](INFO): Uninitializing the command scheduler.
2022-09-21T22:49:18.038Z,1663800558.038 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:18.085Z,1663800558.085 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-09-21T22:49:18.085Z,1663800558.085 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-09-21T22:49:18.085Z,1663800558.085 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:18.086Z,1663800558.086 [NavChartDb](INFO): Join timeout helper Thread ID is 2082
2022-09-21T22:49:18.301Z,1663800558.301 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-21T22:49:18.301Z,1663800558.301 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:18.309Z,1663800558.309 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2022-09-21T22:49:18.309Z,1663800558.309 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:18.309Z,1663800558.309 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 2083
2022-09-21T22:49:18.313Z,1663800558.313 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-21T22:49:18.314Z,1663800558.314 [WetLabsUBAT](INFO): Powering down
2022-09-21T22:49:18.314Z,1663800558.314 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:18.329Z,1663800558.329 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-09-21T22:49:18.329Z,1663800558.329 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:18.329Z,1663800558.329 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2084
2022-09-21T22:49:18.653Z,1663800558.653 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-21T22:49:18.653Z,1663800558.653 [WetLabsBB2FL](INFO): Powering down
2022-09-21T22:49:18.654Z,1663800558.654 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:18.678Z,1663800558.678 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-09-21T22:49:18.678Z,1663800558.678 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:18.678Z,1663800558.678 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2085
2022-09-21T22:49:18.953Z,1663800558.953 [CTD_Seabird](INFO): Powering down
2022-09-21T22:49:18.965Z,1663800558.965 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-21T22:49:18.965Z,1663800558.965 [CTD_Seabird](INFO): Powering down
2022-09-21T22:49:18.977Z,1663800558.977 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:18.985Z,1663800558.985 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2022-09-21T22:49:18.985Z,1663800558.985 [DAT ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:18.985Z,1663800558.985 [DAT](INFO): Join timeout helper Thread ID is 2086
2022-09-21T22:49:19.177Z,1663800559.177 [DAT](INFO): Powering down
2022-09-21T22:49:19.250Z,1663800559.250 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-21T22:49:19.250Z,1663800559.250 [DAT](INFO): Powering down
2022-09-21T22:49:19.251Z,1663800559.251 [DAT ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:19.270Z,1663800559.270 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-09-21T22:49:19.270Z,1663800559.270 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:19.270Z,1663800559.270 [Radio_Surface](INFO): Join timeout helper Thread ID is 2087
2022-09-21T22:49:19.309Z,1663800559.309 [Radio_Surface](INFO): Powering down
2022-09-21T22:49:19.310Z,1663800559.310 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-21T22:49:19.311Z,1663800559.311 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:19.322Z,1663800559.322 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-09-21T22:49:19.322Z,1663800559.322 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:19.322Z,1663800559.322 [Onboard](INFO): Join timeout helper Thread ID is 2088
2022-09-21T22:49:19.665Z,1663800559.665 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-09-21T22:49:21.581Z,1663800561.581 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-21T22:49:21.581Z,1663800561.581 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:21.586Z,1663800561.586 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-09-21T22:49:21.586Z,1663800561.586 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:21.586Z,1663800561.586 [DataOverHttps](INFO): Join timeout helper Thread ID is 2089
2022-09-21T22:49:21.673Z,1663800561.673 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-21T22:49:21.673Z,1663800561.673 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:21.686Z,1663800561.686 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-09-21T22:49:21.686Z,1663800561.686 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:21.686Z,1663800561.686 [BackseatComponent](INFO): Join timeout helper Thread ID is 2090
2022-09-21T22:49:21.761Z,1663800561.761 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-21T22:49:21.761Z,1663800561.761 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:21.774Z,1663800561.774 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-09-21T22:49:21.774Z,1663800561.774 [logger ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:21.774Z,1663800561.774 [logger](INFO): Join timeout helper Thread ID is 2091
2022-09-21T22:49:21.809Z,1663800561.809 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-21T22:49:21.809Z,1663800561.809 [logger ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:21.814Z,1663800561.814 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-09-21T22:49:21.814Z,1663800561.814 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:21.814Z,1663800561.814 [CommandLine](INFO): Join timeout helper Thread ID is 2092
2022-09-21T22:49:21.817Z,1663800561.817 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-21T22:49:21.817Z,1663800561.817 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:21.833Z,1663800561.833 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-09-21T22:49:21.834Z,1663800561.834 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:21.834Z,1663800561.834 [CommandExec](INFO): Join timeout helper Thread ID is 2093
2022-09-21T22:49:21.835Z,1663800561.835 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-09-21T22:49:21.835Z,1663800561.835 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:21.836Z,1663800561.836 [controlThread](INFO): Join timeout helper Thread ID is 2094
2022-09-21T22:49:21.997Z,1663800561.997 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-21T22:49:21.997Z,1663800561.997 [controlThread](DEBUG): Uninitializing ControlThread
2022-09-21T22:49:21.998Z,1663800561.998 [AHRS_M2](INFO): Powering down
2022-09-21T22:49:22.070Z,1663800562.070 [NAL9602](INFO): Powering down
2022-09-21T22:49:22.141Z,1663800562.141 [RDI_Pathfinder](INFO): Powering down
2022-09-21T22:49:22.142Z,1663800562.142 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-09-21T22:49:22.143Z,1663800562.143 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-09-21T22:49:22.144Z,1663800562.144 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-09-21T22:49:22.144Z,1663800562.144 [MissionManager](INFO): Uninitializing Mission Default
2022-09-21T22:49:22.144Z,1663800562.144 [Default] Stopped
2022-09-21T22:49:22.144Z,1663800562.144 [Default](DEBUG): Aggregate::uninitialize Default
2022-09-21T22:49:22.144Z,1663800562.144 [Default:B.GoToSurface] Stopped
2022-09-21T22:49:22.145Z,1663800562.145 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-09-21T22:49:22.145Z,1663800562.145 [Default:CheckIn] Stopped
2022-09-21T22:49:22.145Z,1663800562.145 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-21T22:49:22.145Z,1663800562.145 [Default:CheckIn:Read_GPS] Stopped
2022-09-21T22:49:22.148Z,1663800562.148 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-09-21T22:49:22.148Z,1663800562.148 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-09-21T22:49:22.149Z,1663800562.149 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-09-21T22:49:22.149Z,1663800562.149 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-09-21T22:49:22.149Z,1663800562.149 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-09-21T22:49:22.149Z,1663800562.149 [BuoyancyServo](INFO): Powering down
2022-09-21T22:49:22.161Z,1663800562.161 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-09-21T22:49:22.161Z,1663800562.161 [ElevatorServo](INFO): Powering down
2022-09-21T22:49:22.162Z,1663800562.162 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-09-21T22:49:22.162Z,1663800562.162 [MassServo](INFO): Powering down
2022-09-21T22:49:22.163Z,1663800562.163 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-09-21T22:49:22.163Z,1663800562.163 [RudderServo](INFO): Powering down
2022-09-21T22:49:22.164Z,1663800562.164 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2022-09-21T22:49:22.164Z,1663800562.164 [ThrusterServo](INFO): Powering down
2022-09-21T22:49:22.166Z,1663800562.166 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-09-21T22:49:22.166Z,1663800562.166 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-09-21T22:49:22.166Z,1663800562.166 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-09-21T22:49:22.166Z,1663800562.166 [CBIT](DEBUG): Powering off loads.
2022-09-21T22:49:22.177Z,1663800562.177 [CBIT](DEBUG): Disabling WDT.
2022-09-21T22:49:22.189Z,1663800562.189 [CBIT](DEBUG): Opening all GF detection circuits.
2022-09-21T22:49:22.190Z,1663800562.190 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:22.239Z,1663800562.239 [DAT ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:22.247Z,1663800562.247 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:22.249Z,1663800562.249 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:22.255Z,1663800562.255 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:22.302Z,1663800562.302 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:22.368Z,1663800562.368 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:22.370Z,1663800562.370 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:22.374Z,1663800562.374 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:22.382Z,1663800562.382 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-09-21T22:49:22.447Z,1663800562.447 [logger ThreadHandler](INFO): Thread cancelled.