2022-07-20T00:42:46.901Z,1658277766.901 [Supervisor](DEBUG): Initializing supervisor. 2022-07-20T00:42:46.906Z,1658277766.906 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2022-07-20T00:42:46.906Z,1658277766.906 [SyncHandler](INFO): Protected caller Thread ID is 835 2022-07-20T00:42:46.907Z,1658277766.907 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2022-07-20T00:42:46.908Z,1658277766.908 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2022-07-20T00:42:46.908Z,1658277766.908 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 836 2022-07-20T00:42:46.912Z,1658277766.912 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2022-07-20T00:42:46.930Z,1658277766.930 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2022-07-20T00:42:46.931Z,1658277766.931 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2022-07-20T00:42:46.931Z,1658277766.931 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 837 2022-07-20T00:42:46.935Z,1658277766.935 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2022-07-20T00:42:46.937Z,1658277766.937 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2022-07-20T00:42:46.937Z,1658277766.937 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 838 2022-07-20T00:42:46.939Z,1658277766.939 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2022-07-20T00:42:46.940Z,1658277766.940 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2022-07-20T00:42:46.940Z,1658277766.940 [logger ThreadHandler](INFO): Protected caller Thread ID is 839 2022-07-20T00:42:46.944Z,1658277766.944 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2022-07-20T00:42:46.944Z,1658277766.944 [Supervisor](INFO): Looking for Config files in directory: Config/ 2022-07-20T00:42:46.948Z,1658277766.948 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2022-07-20T00:42:47.201Z,1658277767.201 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2022-07-20T00:42:47.202Z,1658277767.202 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2022-07-20T00:42:47.284Z,1658277767.284 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2022-07-20T00:42:47.815Z,1658277767.815 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2022-07-20T00:42:47.817Z,1658277767.817 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2022-07-20T00:42:48.138Z,1658277768.138 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2022-07-20T00:42:48.139Z,1658277768.139 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2022-07-20T00:42:48.235Z,1658277768.235 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2022-07-20T00:42:48.237Z,1658277768.237 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2022-07-20T00:42:48.599Z,1658277768.599 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2022-07-20T00:42:48.601Z,1658277768.601 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2022-07-20T00:42:48.810Z,1658277768.810 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2022-07-20T00:42:48.811Z,1658277768.811 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2022-07-20T00:42:49.260Z,1658277769.260 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2022-07-20T00:42:49.261Z,1658277769.261 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2022-07-20T00:42:49.391Z,1658277769.391 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2022-07-20T00:42:49.393Z,1658277769.393 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2022-07-20T00:42:49.474Z,1658277769.474 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2022-07-20T00:42:50.151Z,1658277770.151 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2022-07-20T00:42:50.153Z,1658277770.153 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2022-07-20T00:42:51.085Z,1658277771.085 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2022-07-20T00:42:51.086Z,1658277771.086 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2022-07-20T00:42:51.274Z,1658277771.274 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2022-07-20T00:42:51.276Z,1658277771.276 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2022-07-20T00:42:51.387Z,1658277771.387 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2022-07-20T00:42:51.388Z,1658277771.388 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2022-07-20T00:42:51.607Z,1658277771.607 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2022-07-20T00:42:51.608Z,1658277771.608 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2022-07-20T00:42:51.833Z,1658277771.833 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2022-07-20T00:42:51.835Z,1658277771.835 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2022-07-20T00:42:51.838Z,1658277771.838 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2022-07-20T00:42:51.930Z,1658277771.930 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2022-07-20T00:42:52.016Z,1658277772.016 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2022-07-20T00:42:52.120Z,1658277772.120 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2022-07-20T00:42:52.204Z,1658277772.204 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2022-07-20T00:42:52.302Z,1658277772.302 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2022-07-20T00:42:52.404Z,1658277772.404 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2022-07-20T00:42:52.590Z,1658277772.590 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2022-07-20T00:42:52.666Z,1658277772.666 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2022-07-20T00:42:52.812Z,1658277772.812 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2022-07-20T00:42:52.947Z,1658277772.947 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2022-07-20T00:42:53.062Z,1658277773.062 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2022-07-20T00:42:53.322Z,1658277773.322 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2022-07-20T00:42:53.323Z,1658277773.323 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2022-07-20T00:42:53.339Z,1658277773.339 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2022-07-20T00:42:53.410Z,1658277773.409 [VerticalControl](DEBUG): Construct VerticalControl. 2022-07-20T00:42:53.470Z,1658277773.470 [VerticalControl] Loaded 2022-07-20T00:42:53.470Z,1658277773.470 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2022-07-20T00:42:53.473Z,1658277773.473 [HorizontalControl](DEBUG): Construct HorizontalControl. 2022-07-20T00:42:53.512Z,1658277773.512 [HorizontalControl] Loaded 2022-07-20T00:42:53.512Z,1658277773.512 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2022-07-20T00:42:53.515Z,1658277773.515 [SpeedControl](DEBUG): Construct SpeedControl. 2022-07-20T00:42:53.518Z,1658277773.518 [SpeedControl] Loaded 2022-07-20T00:42:53.518Z,1658277773.518 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2022-07-20T00:42:53.521Z,1658277773.521 [LoopControl](DEBUG): Construct LoopControl. 2022-07-20T00:42:53.521Z,1658277773.521 [LoopControl] Loaded 2022-07-20T00:42:53.521Z,1658277773.521 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2022-07-20T00:42:53.522Z,1658277773.522 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2022-07-20T00:42:53.524Z,1658277773.524 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2022-07-20T00:42:53.539Z,1658277773.539 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2022-07-20T00:42:53.540Z,1658277773.540 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2022-07-20T00:42:53.679Z,1658277773.679 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2022-07-20T00:42:53.680Z,1658277773.680 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2022-07-20T00:42:53.852Z,1658277773.852 [BuoyancyServo] Loaded 2022-07-20T00:42:53.852Z,1658277773.852 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2022-07-20T00:42:53.871Z,1658277773.871 [ElevatorServo] Loaded 2022-07-20T00:42:53.871Z,1658277773.871 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2022-07-20T00:42:53.889Z,1658277773.889 [MassServo] Loaded 2022-07-20T00:42:53.890Z,1658277773.890 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2022-07-20T00:42:53.907Z,1658277773.907 [RudderServo] Loaded 2022-07-20T00:42:53.907Z,1658277773.907 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2022-07-20T00:42:53.925Z,1658277773.925 [ThrusterServo] Loaded 2022-07-20T00:42:53.925Z,1658277773.925 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2022-07-20T00:42:53.926Z,1658277773.926 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2022-07-20T00:42:53.926Z,1658277773.926 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2022-07-20T00:42:54.063Z,1658277774.063 [DeadReckonUsingMultipleVelocitySources] Loaded 2022-07-20T00:42:54.063Z,1658277774.063 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2022-07-20T00:42:54.077Z,1658277774.077 [NavChart] Loaded 2022-07-20T00:42:54.077Z,1658277774.077 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2022-07-20T00:42:54.082Z,1658277774.082 [UniversalFixResidualReporter] Loaded 2022-07-20T00:42:54.083Z,1658277774.083 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2022-07-20T00:42:54.083Z,1658277774.083 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2022-07-20T00:42:54.084Z,1658277774.084 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2022-07-20T00:42:54.166Z,1658277774.166 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2022-07-20T00:42:54.167Z,1658277774.167 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2022-07-20T00:42:54.863Z,1658277774.863 [AHRS_M2] Loaded 2022-07-20T00:42:54.863Z,1658277774.863 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2022-07-20T00:42:55.661Z,1658277775.661 [BPC1] Loaded 2022-07-20T00:42:55.661Z,1658277775.661 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2022-07-20T00:42:55.681Z,1658277775.681 [Depth_Keller] Loaded 2022-07-20T00:42:55.681Z,1658277775.681 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2022-07-20T00:42:55.741Z,1658277775.741 [NAL9602] Loaded 2022-07-20T00:42:55.741Z,1658277775.741 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2022-07-20T00:42:55.769Z,1658277775.769 [Onboard] Loaded 2022-07-20T00:42:55.769Z,1658277775.769 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2022-07-20T00:42:55.771Z,1658277775.771 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408F14E0 2022-07-20T00:42:55.771Z,1658277775.771 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 924 2022-07-20T00:42:55.784Z,1658277775.784 [Power24vConverter] Loaded 2022-07-20T00:42:55.784Z,1658277775.784 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2022-07-20T00:42:55.798Z,1658277775.798 [Radio_Surface] Loaded 2022-07-20T00:42:55.798Z,1658277775.798 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2022-07-20T00:42:55.799Z,1658277775.799 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409214E0 2022-07-20T00:42:55.799Z,1658277775.799 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 925 2022-07-20T00:42:55.800Z,1658277775.800 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2022-07-20T00:42:55.801Z,1658277775.801 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2022-07-20T00:42:55.869Z,1658277775.869 [DepthRateCalculator] Loaded 2022-07-20T00:42:55.869Z,1658277775.869 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2022-07-20T00:42:55.874Z,1658277775.874 [PitchRateCalculator] Loaded 2022-07-20T00:42:55.874Z,1658277775.874 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2022-07-20T00:42:55.883Z,1658277775.883 [SpeedCalculator] Loaded 2022-07-20T00:42:55.883Z,1658277775.883 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2022-07-20T00:42:55.888Z,1658277775.888 [YawRateCalculator] Loaded 2022-07-20T00:42:55.888Z,1658277775.888 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2022-07-20T00:42:55.906Z,1658277775.906 [ElevatorOffsetCalculator] Loaded 2022-07-20T00:42:55.906Z,1658277775.906 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2022-07-20T00:42:55.907Z,1658277775.907 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2022-07-20T00:42:55.907Z,1658277775.907 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2022-07-20T00:42:56.082Z,1658277776.082 [CANONSampler] Loaded 2022-07-20T00:42:56.083Z,1658277776.083 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2022-07-20T00:42:56.141Z,1658277776.141 [CTD_Seabird] Loaded 2022-07-20T00:42:56.142Z,1658277776.142 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2022-07-20T00:42:56.143Z,1658277776.143 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409D94E0 2022-07-20T00:42:56.143Z,1658277776.143 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 926 2022-07-20T00:42:56.162Z,1658277776.162 [PAR_Licor] Loaded 2022-07-20T00:42:56.163Z,1658277776.163 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2022-07-20T00:42:56.194Z,1658277776.194 [WetLabsBB2FL] Loaded 2022-07-20T00:42:56.194Z,1658277776.194 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2022-07-20T00:42:56.195Z,1658277776.195 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A094E0 2022-07-20T00:42:56.196Z,1658277776.196 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 927 2022-07-20T00:42:56.197Z,1658277776.197 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2022-07-20T00:42:56.197Z,1658277776.197 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2022-07-20T00:42:56.304Z,1658277776.304 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2022-07-20T00:42:56.305Z,1658277776.305 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2022-07-20T00:42:57.431Z,1658277777.431 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2022-07-20T00:42:57.433Z,1658277777.433 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2022-07-20T00:42:57.599Z,1658277777.599 [SBIT](DEBUG): Construct Startup Built In Test. 2022-07-20T00:42:57.608Z,1658277777.608 [SBIT] Loaded 2022-07-20T00:42:57.609Z,1658277777.609 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2022-07-20T00:42:57.611Z,1658277777.611 [IBIT](DEBUG): Construct Initiated Built In Test. 2022-07-20T00:42:57.624Z,1658277777.624 [IBIT] Loaded 2022-07-20T00:42:57.624Z,1658277777.624 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2022-07-20T00:42:57.630Z,1658277777.630 [CBIT](DEBUG): Construct Continuous Built In Test. 2022-07-20T00:42:57.733Z,1658277777.733 [CBIT] Loaded 2022-07-20T00:42:57.734Z,1658277777.734 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2022-07-20T00:42:57.734Z,1658277777.734 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2022-07-20T00:42:57.735Z,1658277777.735 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2022-07-20T00:42:57.851Z,1658277777.851 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2022-07-20T00:42:57.857Z,1658277777.857 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2022-07-20T00:42:57.859Z,1658277777.859 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2022-07-20T00:42:57.870Z,1658277777.870 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2022-07-20T00:42:57.871Z,1658277777.871 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B454E0 2022-07-20T00:42:57.871Z,1658277777.871 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 928 2022-07-20T00:42:57.875Z,1658277777.875 [Supervisor](INFO): Main Thread ID is 830 2022-07-20T00:42:57.876Z,1658277777.876 [Supervisor](DEBUG): Running supervisor. 2022-07-20T00:42:57.876Z,1658277777.876 [CommandExec ThreadHandler](INFO): Handler Thread ID is 929 2022-07-20T00:42:57.877Z,1658277777.877 [CommandExec](INFO): Initializing the command executive. 2022-07-20T00:42:57.878Z,1658277777.878 [CommandLine ThreadHandler](INFO): Handler Thread ID is 930 2022-07-20T00:42:57.880Z,1658277777.880 [controlThread ThreadHandler](INFO): Handler Thread ID is 931 2022-07-20T00:42:57.881Z,1658277777.881 [controlThread](DEBUG): Initializing ControlThread 2022-07-20T00:42:57.882Z,1658277777.882 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2022-07-20T00:42:57.883Z,1658277777.883 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2022-07-20T00:42:57.884Z,1658277777.884 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2022-07-20T00:42:57.884Z,1658277777.884 [LoopControl](DEBUG): Initialize LoopControlComponent. 2022-07-20T00:42:57.886Z,1658277777.886 [NavChart](DEBUG): Initialize NavChart Navigation. 2022-07-20T00:42:57.887Z,1658277777.887 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2022-07-20T00:42:57.889Z,1658277777.889 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2022-07-20T00:42:57.889Z,1658277777.889 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2022-07-20T00:42:57.889Z,1658277777.889 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2022-07-20T00:42:57.889Z,1658277777.889 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2022-07-20T00:42:57.890Z,1658277777.890 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2022-07-20T00:42:57.892Z,1658277777.892 [SBIT](INFO): Initialize SBIT Component. 2022-07-20T00:42:57.893Z,1658277777.893 [SBIT](IMPORTANT): git: 2022-04-11 2022-07-20T00:42:57.893Z,1658277777.893 [SBIT](INFO): git hash: 059cb0c4bb1d2c42f72e645cf8ce57fde1aeb3d9 2022-07-20T00:42:57.893Z,1658277777.893 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2022-07-20T00:42:57.894Z,1658277777.894 [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 2022-07-20T00:42:57.895Z,1658277777.895 [SBIT](INFO): Beginning SBIT in 93.000000 seconds. 2022-07-20T00:42:57.896Z,1658277777.896 [IBIT](INFO): Initialize IBIT Component. 2022-07-20T00:42:57.897Z,1658277777.897 [CBIT](DEBUG): Initialize CBIT Component. 2022-07-20T00:42:57.898Z,1658277777.898 [logger ThreadHandler](INFO): Handler Thread ID is 932 2022-07-20T00:42:57.908Z,1658277777.908 [CBIT](DEBUG): Initialized mux pins. 2022-07-20T00:42:57.908Z,1658277777.908 [CBIT](DEBUG): Initializing the watchdog timer. 2022-07-20T00:42:57.916Z,1658277777.916 [Onboard ThreadHandler](INFO): Handler Thread ID is 933 2022-07-20T00:42:57.932Z,1658277777.932 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2022-07-20T00:42:57.932Z,1658277777.932 [CBIT](DEBUG): Initializing heartbeat. 2022-07-20T00:42:57.935Z,1658277777.935 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 934 2022-07-20T00:42:57.948Z,1658277777.948 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 935 2022-07-20T00:42:57.949Z,1658277777.949 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2022-07-20T00:42:57.952Z,1658277777.952 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 937 2022-07-20T00:42:57.956Z,1658277777.956 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 939 2022-07-20T00:42:57.957Z,1658277777.957 [WetLabsBB2FL](INFO): Powering up 2022-07-20T00:42:57.966Z,1658277777.966 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2022-07-20T00:42:57.966Z,1658277777.966 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2022-07-20T00:42:57.966Z,1658277777.966 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2022-07-20T00:42:57.966Z,1658277777.966 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2022-07-20T00:42:57.966Z,1658277777.966 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2022-07-20T00:42:57.966Z,1658277777.966 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2022-07-20T00:42:57.967Z,1658277777.967 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2022-07-20T00:42:57.967Z,1658277777.967 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2022-07-20T00:42:57.967Z,1658277777.967 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2022-07-20T00:42:57.967Z,1658277777.967 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2022-07-20T00:42:57.967Z,1658277777.967 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2022-07-20T00:42:57.968Z,1658277777.968 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2022-07-20T00:42:57.968Z,1658277777.968 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2022-07-20T00:42:57.968Z,1658277777.968 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2022-07-20T00:42:57.969Z,1658277777.969 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2022-07-20T00:42:57.969Z,1658277777.969 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2022-07-20T00:42:58.004Z,1658277778.004 [CBIT](DEBUG): Deactivating GF circuits. 2022-07-20T00:42:58.004Z,1658277778.004 [CBIT](DEBUG): Deactivating emergency mode. 2022-07-20T00:42:58.040Z,1658277778.040 [CBIT](DEBUG): Backplane powered. 2022-07-20T00:42:58.041Z,1658277778.041 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2022-07-20T00:42:58.066Z,1658277778.066 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2022-07-20T00:42:58.106Z,1658277778.106 [MissionManager](DEBUG): 2022-07-20T00:42:58.106Z,1658277778.106 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2022-07-20T00:42:58.163Z,1658277778.163 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2022-07-20T00:42:58.179Z,1658277778.179 [Default:A.Wait](DEBUG): Construct Wait. 2022-07-20T00:42:58.197Z,1658277778.197 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2022-07-20T00:42:58.224Z,1658277778.224 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2022-07-20T00:42:58.227Z,1658277778.227 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2022-07-20T00:42:58.249Z,1658277778.249 [Default:E.Execute](DEBUG): Construct Execute. 2022-07-20T00:42:58.272Z,1658277778.272 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2022-07-20T00:42:58.277Z,1658277778.277 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,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,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2022-07-20T00:42:58.290Z,1658277778.290 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-07-20T00:42:58.344Z,1658277778.344 [Radio_Surface](INFO): Powering up 2022-07-20T00:42:58.434Z,1658277778.434 [Power24vConverter](INFO): Powering up. 2022-07-20T00:42:58.435Z,1658277778.435 [CANONSampler](INFO): Powering down 2022-07-20T00:42:58.519Z,1658277778.519 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2022-07-20T00:42:58.528Z,1658277778.528 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2022-07-20T00:42:58.529Z,1658277778.529 [ElevatorServo](DEBUG): Initializing EZServoServo. 2022-07-20T00:42:58.536Z,1658277778.536 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2022-07-20T00:42:58.537Z,1658277778.537 [MassServo](DEBUG): Initializing EZServoServo. 2022-07-20T00:42:58.544Z,1658277778.544 [MassServo](DEBUG): Initializing MassServo. 2022-07-20T00:42:58.545Z,1658277778.545 [RudderServo](DEBUG): Initializing EZServoServo. 2022-07-20T00:42:58.552Z,1658277778.552 [RudderServo](DEBUG): Initializing RudderServo. 2022-07-20T00:42:58.553Z,1658277778.553 [ThrusterServo](DEBUG): Initializing EZServoServo. 2022-07-20T00:42:58.560Z,1658277778.560 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2022-07-20T00:42:59.432Z,1658277779.432 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2022-07-20T00:42:59.432Z,1658277779.432 [RudderServo](FAULT): Rudder failed to initialize 2022-07-20T00:42:59.432Z,1658277779.432 [RudderServo] Communications Fault, FailCount= 1 2022-07-20T00:42:59.432Z,1658277779.432 [RudderServo](ERROR): Communications Fault 2022-07-20T00:42:59.535Z,1658277779.535 [CBIT](ERROR): Communications Fault in component: RudderServo 2022-07-20T00:42:59.733Z,1658277779.733 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2022-07-20T00:42:59.733Z,1658277779.733 [RudderServo](INFO): Powering down 2022-07-20T00:43:00.064Z,1658277780.064 [WetLabsBB2FL](INFO): Powering down 2022-07-20T00:43:00.380Z,1658277780.380 [RudderServo](DEBUG): Initializing EZServoServo. 2022-07-20T00:43:00.501Z,1658277780.501 [RudderServo](DEBUG): Initializing RudderServo. 2022-07-20T00:43:00.505Z,1658277780.505 [CBIT](INFO): Clearing failed state for component RudderServo 2022-07-20T00:43:00.505Z,1658277780.505 [RudderServo] No Fault, FailCount= 1 2022-07-20T00:43:25.809Z,1658277805.809 [NAL9602](INFO): Powering up NAL9602 2022-07-20T00:43:36.721Z,1658277816.721 [NAL9602](INFO): NAL9602 initialized 2022-07-20T00:44:31.678Z,1658277871.678 [SBIT](IMPORTANT): Beginning Startup BIT 2022-07-20T00:44:31.699Z,1658277871.699 [CBIT](IMPORTANT): Beginning ground fault scan 2022-07-20T00:44:42.639Z,1658277882.639 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.000366 CHAN A1 (24V): 0.000528 CHAN A2 (12V): -0.002089 CHAN A3 (5V): -0.001562 CHAN B0 (3.3V): -0.000224 CHAN B1 (3.15aV): 0.000345 CHAN B2 (3.15bV): -0.000766 CHAN B3 (GND): 0.000168 OPEN: -0.000164 Full Scale: +/- 1 mA 2022-07-20T00:45:25.944Z,1658277925.944 [SBIT](IMPORTANT): SBIT PASSED 2022-07-20T00:45:25.945Z,1658277925.945 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2022-07-20T00:45:25.945Z,1658277925.945 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool; 2022-07-20T00:45:25.946Z,1658277925.946 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=3 count; 2022-07-20T00:45:25.946Z,1658277925.946 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool; 2022-07-20T00:45:25.946Z,1658277925.946 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2022-07-20T00:45:25.946Z,1658277925.946 [SBIT](IMPORTANT): DataOverHttps.loadAtStartup=0 bool; 2022-07-20T00:45:25.946Z,1658277925.946 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2022-07-20T00:45:25.946Z,1658277925.946 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=0 bool; 2022-07-20T00:45:25.946Z,1658277925.946 [SBIT](IMPORTANT): Express linearApproximation NAL9602.sigQuality 1.000000 count; 2022-07-20T00:45:25.946Z,1658277925.946 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=346.918397 cubic_centimeter; 2022-07-20T00:45:25.947Z,1658277925.947 [SBIT](IMPORTANT): VerticalControl.massDefault=-18.268598 millimeter; 2022-07-20T00:45:26.249Z,1658277926.249 [MissionManager](IMPORTANT): Started mission Startup 2022-07-20T00:45:26.249Z,1658277926.249 [Startup] Running Loop=1 2022-07-20T00:45:26.249Z,1658277926.249 [Startup](DEBUG): Aggregate::initialize Startup 2022-07-20T00:45:26.249Z,1658277926.249 [Startup:A.GoToSurface] Running Loop=1 2022-07-20T00:45:26.249Z,1658277926.249 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-07-20T00:45:26.250Z,1658277926.250 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-07-20T00:45:26.250Z,1658277926.250 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-07-20T00:45:26.250Z,1658277926.250 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-07-20T00:45:26.251Z,1658277926.251 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-07-20T00:45:26.251Z,1658277926.251 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-07-20T00:45:26.257Z,1658277926.257 [Startup:StartupSatComms] Running Loop=1 2022-07-20T00:45:26.257Z,1658277926.257 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2022-07-20T00:45:26.257Z,1658277926.257 [Startup:StartupSatComms:A] Running Loop=1 2022-07-20T00:45:26.642Z,1658277926.642 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2022-07-20T00:45:57.960Z,1658277957.960 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2022-07-20T00:45:57.960Z,1658277957.960 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-07-20T00:45:57.970Z,1658277957.970 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-07-20T00:45:58.387Z,1658277958.387 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-07-20T00:45:58.387Z,1658277958.387 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2022-07-20T00:46:26.194Z,1658277986.194 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-20T00:46:26.627Z,1658277986.627 [Startup:StartupSatComms:A](INFO): Timed out from 2022-07-20T00:45:26.3Z 2022-07-20T00:46:26.627Z,1658277986.627 [Startup:StartupSatComms:A] Stopped 2022-07-20T00:46:26.627Z,1658277986.627 [Startup:StartupSatComms:B] Running Loop=1 2022-07-20T00:46:27.024Z,1658277987.024 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2022-07-20T00:46:32.657Z,1658277992.657 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-20T00:46:47.606Z,1658278007.606 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-20T00:47:02.555Z,1658278022.555 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-20T00:47:17.905Z,1658278037.905 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-07-20T00:47:26.840Z,1658278046.840 [Startup:StartupSatComms:B](INFO): Timed out from 2022-07-20T00:46:26.6Z 2022-07-20T00:47:26.840Z,1658278046.840 [Startup:StartupSatComms:B] Stopped 2022-07-20T00:47:26.840Z,1658278046.840 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2022-07-20T00:47:26.840Z,1658278046.840 [Startup:StartupSatComms] Stopped 2022-07-20T00:47:26.840Z,1658278046.840 [Star