2024-02-06T19:26:46.526Z,1707247606.526 [Supervisor](DEBUG): Initializing supervisor. 2024-02-06T19:26:46.530Z,1707247606.530 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-02-06T19:26:46.531Z,1707247606.531 [SyncHandler](INFO): Protected caller Thread ID is 9640 2024-02-06T19:26:46.531Z,1707247606.531 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-02-06T19:26:46.532Z,1707247606.532 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-02-06T19:26:46.532Z,1707247606.532 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9641 2024-02-06T19:26:46.536Z,1707247606.536 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-02-06T19:26:46.553Z,1707247606.553 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-02-06T19:26:46.554Z,1707247606.554 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-02-06T19:26:46.554Z,1707247606.554 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 9642 2024-02-06T19:26:46.558Z,1707247606.558 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-02-06T19:26:46.559Z,1707247606.559 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-02-06T19:26:46.560Z,1707247606.560 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9643 2024-02-06T19:26:46.562Z,1707247606.562 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-02-06T19:26:46.562Z,1707247606.562 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-02-06T19:26:46.563Z,1707247606.563 [logger ThreadHandler](INFO): Protected caller Thread ID is 9644 2024-02-06T19:26:46.566Z,1707247606.566 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-02-06T19:26:46.567Z,1707247606.567 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-02-06T19:26:46.568Z,1707247606.568 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-02-06T19:26:46.776Z,1707247606.776 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-02-06T19:26:46.776Z,1707247606.776 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-02-06T19:26:46.883Z,1707247606.883 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-02-06T19:26:46.883Z,1707247606.883 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-02-06T19:26:46.978Z,1707247606.978 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-02-06T19:26:46.979Z,1707247606.979 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-02-06T19:26:47.448Z,1707247607.448 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-02-06T19:26:47.449Z,1707247607.449 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-02-06T19:26:47.867Z,1707247607.867 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-02-06T19:26:47.868Z,1707247607.868 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-02-06T19:26:48.202Z,1707247608.202 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-02-06T19:26:48.202Z,1707247608.202 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-02-06T19:26:48.394Z,1707247608.394 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-02-06T19:26:48.394Z,1707247608.394 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-02-06T19:26:48.475Z,1707247608.475 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-02-06T19:26:48.557Z,1707247608.557 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-02-06T19:26:48.955Z,1707247608.955 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-02-06T19:26:48.955Z,1707247608.955 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-02-06T19:26:49.234Z,1707247609.234 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-02-06T19:26:49.234Z,1707247609.234 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-02-06T19:26:49.753Z,1707247609.753 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-02-06T19:26:49.754Z,1707247609.754 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-02-06T19:26:49.975Z,1707247609.975 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-02-06T19:26:49.975Z,1707247609.975 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-02-06T19:26:50.218Z,1707247610.218 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-02-06T19:26:50.218Z,1707247610.218 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-02-06T19:26:50.357Z,1707247610.357 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-02-06T19:26:50.358Z,1707247610.358 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-02-06T19:26:51.192Z,1707247611.192 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-02-06T19:26:51.194Z,1707247611.194 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2024-02-06T19:26:51.195Z,1707247611.195 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2024-02-06T19:26:51.435Z,1707247611.435 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2024-02-06T19:26:51.636Z,1707247611.636 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2024-02-06T19:26:52.094Z,1707247612.094 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-02-06T19:26:52.095Z,1707247612.095 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2024-02-06T19:26:52.224Z,1707247612.224 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2024-02-06T19:26:52.327Z,1707247612.327 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2024-02-06T19:26:52.422Z,1707247612.422 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2024-02-06T19:26:52.531Z,1707247612.531 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2024-02-06T19:26:52.628Z,1707247612.628 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2024-02-06T19:26:52.715Z,1707247612.715 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2024-02-06T19:26:52.817Z,1707247612.817 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2024-02-06T19:26:52.986Z,1707247612.986 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/ 2024-02-06T19:26:52.987Z,1707247612.987 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2024-02-06T19:26:53.001Z,1707247613.001 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-02-06T19:26:53.050Z,1707247613.050 [DepthRateCalculator] Loaded 2024-02-06T19:26:53.050Z,1707247613.050 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-02-06T19:26:53.055Z,1707247613.055 [PitchRateCalculator] Loaded 2024-02-06T19:26:53.056Z,1707247613.056 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-02-06T19:26:53.066Z,1707247613.066 [SpeedCalculator] Loaded 2024-02-06T19:26:53.066Z,1707247613.066 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-02-06T19:26:53.071Z,1707247613.071 [YawRateCalculator] Loaded 2024-02-06T19:26:53.071Z,1707247613.071 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-02-06T19:26:53.091Z,1707247613.091 [ElevatorOffsetCalculator] Loaded 2024-02-06T19:26:53.091Z,1707247613.091 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-02-06T19:26:53.092Z,1707247613.092 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-02-06T19:26:53.092Z,1707247613.092 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-02-06T19:26:53.118Z,1707247613.118 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-02-06T19:26:53.118Z,1707247613.118 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-02-06T19:26:53.128Z,1707247613.128 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-02-06T19:26:53.129Z,1707247613.129 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-02-06T19:26:53.537Z,1707247613.537 [AHRS_M2] Loaded 2024-02-06T19:26:53.538Z,1707247613.538 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-02-06T19:26:53.572Z,1707247613.572 [BackseatComponent] Loaded 2024-02-06T19:26:53.572Z,1707247613.572 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-02-06T19:26:53.573Z,1707247613.573 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4084F4E0 2024-02-06T19:26:53.574Z,1707247613.574 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 9727 2024-02-06T19:26:53.576Z,1707247613.576 [LcmUniversalReporter] Loaded 2024-02-06T19:26:53.577Z,1707247613.577 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-02-06T19:26:54.352Z,1707247614.352 [BPC1] Loaded 2024-02-06T19:26:54.352Z,1707247614.352 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-02-06T19:26:54.425Z,1707247614.425 [DataOverHttps] Loaded 2024-02-06T19:26:54.425Z,1707247614.425 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-02-06T19:26:54.426Z,1707247614.426 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087F4E0 2024-02-06T19:26:54.426Z,1707247614.426 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9728 2024-02-06T19:26:54.446Z,1707247614.446 [Depth_Keller] Loaded 2024-02-06T19:26:54.446Z,1707247614.446 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-02-06T19:26:54.451Z,1707247614.451 [DropWeight] Loaded 2024-02-06T19:26:54.451Z,1707247614.451 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-02-06T19:26:54.510Z,1707247614.510 [NAL9602] Loaded 2024-02-06T19:26:54.511Z,1707247614.511 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-02-06T19:26:54.539Z,1707247614.539 [Onboard] Loaded 2024-02-06T19:26:54.539Z,1707247614.539 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-02-06T19:26:54.540Z,1707247614.540 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408AF4E0 2024-02-06T19:26:54.541Z,1707247614.541 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 9729 2024-02-06T19:26:54.552Z,1707247614.552 [Power24vConverter] Loaded 2024-02-06T19:26:54.552Z,1707247614.552 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-02-06T19:26:54.566Z,1707247614.566 [Radio_Surface] Loaded 2024-02-06T19:26:54.566Z,1707247614.566 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-02-06T19:26:54.567Z,1707247614.567 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408DF4E0 2024-02-06T19:26:54.568Z,1707247614.568 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9730 2024-02-06T19:26:54.654Z,1707247614.654 [DAT] Loaded 2024-02-06T19:26:54.655Z,1707247614.655 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-02-06T19:26:54.656Z,1707247614.656 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4090F4E0 2024-02-06T19:26:54.656Z,1707247614.656 [DAT ThreadHandler](INFO): Protected caller Thread ID is 9731 2024-02-06T19:26:54.656Z,1707247614.656 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-02-06T19:26:54.657Z,1707247614.657 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-02-06T19:26:54.764Z,1707247614.764 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-02-06T19:26:54.764Z,1707247614.764 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-02-06T19:26:54.804Z,1707247614.804 [DeadReckonUsingSpeedCalculator] Loaded 2024-02-06T19:26:54.804Z,1707247614.804 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2024-02-06T19:26:54.818Z,1707247614.818 [NavChart] Loaded 2024-02-06T19:26:54.818Z,1707247614.818 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-02-06T19:26:54.823Z,1707247614.823 [UniversalFixResidualReporter] Loaded 2024-02-06T19:26:54.823Z,1707247614.823 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-02-06T19:26:54.824Z,1707247614.824 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-02-06T19:26:54.824Z,1707247614.824 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-02-06T19:26:54.958Z,1707247614.958 [SBIT](DEBUG): Construct Startup Built In Test. 2024-02-06T19:26:54.971Z,1707247614.971 [SBIT] Loaded 2024-02-06T19:26:54.971Z,1707247614.971 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-02-06T19:26:54.974Z,1707247614.974 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-02-06T19:26:54.986Z,1707247614.986 [IBIT] Loaded 2024-02-06T19:26:54.987Z,1707247614.987 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-02-06T19:26:54.992Z,1707247614.992 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-02-06T19:26:55.093Z,1707247615.093 [CBIT] Loaded 2024-02-06T19:26:55.093Z,1707247615.093 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-02-06T19:26:55.093Z,1707247615.093 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-02-06T19:26:55.094Z,1707247615.094 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-02-06T19:26:55.258Z,1707247615.258 [CTD_Seabird] Loaded 2024-02-06T19:26:55.259Z,1707247615.259 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-02-06T19:26:55.260Z,1707247615.260 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A164E0 2024-02-06T19:26:55.260Z,1707247615.260 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9732 2024-02-06T19:26:55.279Z,1707247615.279 [PAR_Licor] Loaded 2024-02-06T19:26:55.280Z,1707247615.280 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-02-06T19:26:55.318Z,1707247615.318 [WetLabsBB2FL] Loaded 2024-02-06T19:26:55.319Z,1707247615.319 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-02-06T19:26:55.320Z,1707247615.320 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0 2024-02-06T19:26:55.320Z,1707247615.320 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9733 2024-02-06T19:26:55.321Z,1707247615.321 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-02-06T19:26:55.321Z,1707247615.321 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-02-06T19:26:55.448Z,1707247615.448 [BuoyancyServo] Loaded 2024-02-06T19:26:55.449Z,1707247615.449 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-02-06T19:26:55.470Z,1707247615.470 [ElevatorServo] Loaded 2024-02-06T19:26:55.470Z,1707247615.470 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-02-06T19:26:55.490Z,1707247615.490 [MassServo] Loaded 2024-02-06T19:26:55.490Z,1707247615.490 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-02-06T19:26:55.510Z,1707247615.510 [RudderServo] Loaded 2024-02-06T19:26:55.510Z,1707247615.510 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-02-06T19:26:55.525Z,1707247615.525 [ThrusterHE] Loaded 2024-02-06T19:26:55.526Z,1707247615.526 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-02-06T19:26:55.526Z,1707247615.526 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-02-06T19:26:55.527Z,1707247615.527 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-02-06T19:26:55.575Z,1707247615.575 [VerticalControl](DEBUG): Construct VerticalControl. 2024-02-06T19:26:55.623Z,1707247615.623 [VerticalControl] Loaded 2024-02-06T19:26:55.624Z,1707247615.624 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-02-06T19:26:55.626Z,1707247615.626 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-02-06T19:26:55.663Z,1707247615.663 [HorizontalControl] Loaded 2024-02-06T19:26:55.663Z,1707247615.663 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-02-06T19:26:55.665Z,1707247615.665 [SpeedControl](DEBUG): Construct SpeedControl. 2024-02-06T19:26:55.667Z,1707247615.667 [SpeedControl] Loaded 2024-02-06T19:26:55.667Z,1707247615.667 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-02-06T19:26:55.670Z,1707247615.670 [LoopControl](DEBUG): Construct LoopControl. 2024-02-06T19:26:55.670Z,1707247615.670 [LoopControl] Loaded 2024-02-06T19:26:55.671Z,1707247615.671 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-02-06T19:26:55.671Z,1707247615.671 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-02-06T19:26:55.671Z,1707247615.671 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-02-06T19:26:55.778Z,1707247615.778 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-02-06T19:26:55.779Z,1707247615.779 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-02-06T19:26:55.919Z,1707247615.919 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-02-06T19:26:55.920Z,1707247615.920 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-02-06T19:26:56.241Z,1707247616.241 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-02-06T19:26:56.241Z,1707247616.241 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-02-06T19:26:56.442Z,1707247616.442 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-02-06T19:26:56.466Z,1707247616.466 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-02-06T19:26:56.471Z,1707247616.471 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-02-06T19:26:56.498Z,1707247616.498 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-02-06T19:26:56.504Z,1707247616.504 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C114E0 2024-02-06T19:26:56.505Z,1707247616.505 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9734 2024-02-06T19:26:56.537Z,1707247616.537 [Supervisor](INFO): Main Thread ID is 9639 2024-02-06T19:26:56.537Z,1707247616.537 [Supervisor](DEBUG): Running supervisor. 2024-02-06T19:26:56.538Z,1707247616.538 [CommandExec ThreadHandler](INFO): Handler Thread ID is 9735 2024-02-06T19:26:56.538Z,1707247616.538 [CommandExec](INFO): Initializing the command executive. 2024-02-06T19:26:56.553Z,1707247616.553 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9736 2024-02-06T19:26:56.556Z,1707247616.556 [controlThread ThreadHandler](INFO): Handler Thread ID is 9737 2024-02-06T19:26:56.558Z,1707247616.558 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 9739 2024-02-06T19:26:56.558Z,1707247616.558 [controlThread](DEBUG): Initializing ControlThread 2024-02-06T19:26:56.559Z,1707247616.559 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-02-06T19:26:56.559Z,1707247616.559 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-02-06T19:26:56.559Z,1707247616.559 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-02-06T19:26:56.560Z,1707247616.560 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-02-06T19:26:56.560Z,1707247616.560 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-02-06T19:26:56.567Z,1707247616.567 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-02-06T19:26:56.568Z,1707247616.568 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-02-06T19:26:56.568Z,1707247616.568 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-02-06T19:26:56.568Z,1707247616.568 [SBIT](INFO): Initialize SBIT Component. 2024-02-06T19:26:56.569Z,1707247616.569 [SBIT](IMPORTANT): git: 2024-02-05_A 2024-02-06T19:26:56.569Z,1707247616.569 [SBIT](INFO): git hash: 89bfde8c3b4737bb79d532c259c4b57f363cbf19 2024-02-06T19:26:56.570Z,1707247616.570 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-02-06T19:26:56.571Z,1707247616.571 [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 2024-02-06T19:26:56.572Z,1707247616.572 [SBIT](INFO): Beginning SBIT in 35.000000 seconds. 2024-02-06T19:26:56.572Z,1707247616.572 [IBIT](INFO): Initialize IBIT Component. 2024-02-06T19:26:56.573Z,1707247616.573 [CBIT](DEBUG): Initialize CBIT Component. 2024-02-06T19:26:56.574Z,1707247616.574 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9740 2024-02-06T19:26:56.577Z,1707247616.577 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-02-06T19:26:56.579Z,1707247616.579 [Onboard ThreadHandler](INFO): Handler Thread ID is 9741 2024-02-06T19:26:56.557Z,1707247616.557 [logger ThreadHandler](INFO): Handler Thread ID is 9738 2024-02-06T19:26:56.635Z,1707247616.635 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9742 2024-02-06T19:26:56.634Z,1707247616.634 [CBIT](DEBUG): Initialized mux pins. 2024-02-06T19:26:56.637Z,1707247616.637 [CBIT](DEBUG): Initializing the watchdog timer. 2024-02-06T19:26:56.645Z,1707247616.645 [DAT ThreadHandler](INFO): Handler Thread ID is 9743 2024-02-06T19:26:56.646Z,1707247616.646 [DAT](INFO): Powering up 2024-02-06T19:26:56.646Z,1707247616.646 [DAT](DEBUG): Initializing DAT. 2024-02-06T19:26:56.648Z,1707247616.648 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9745 2024-02-06T19:26:56.648Z,1707247616.648 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-02-06T19:26:56.651Z,1707247616.651 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9746 2024-02-06T19:26:56.661Z,1707247616.661 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-02-06T19:26:56.661Z,1707247616.661 [CBIT](DEBUG): Initializing heartbeat. 2024-02-06T19:26:56.713Z,1707247616.713 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9747 2024-02-06T19:26:56.716Z,1707247616.716 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-02-06T19:26:56.716Z,1707247616.716 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-02-06T19:26:56.716Z,1707247616.716 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-02-06T19:26:56.716Z,1707247616.716 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-02-06T19:26:56.716Z,1707247616.716 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-02-06T19:26:56.717Z,1707247616.717 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-02-06T19:26:56.717Z,1707247616.717 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-02-06T19:26:56.717Z,1707247616.717 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-02-06T19:26:56.733Z,1707247616.733 [CBIT](DEBUG): Deactivating GF circuits. 2024-02-06T19:26:56.733Z,1707247616.733 [CBIT](DEBUG): Deactivating emergency mode. 2024-02-06T19:26:56.773Z,1707247616.773 [CBIT](DEBUG): Backplane powered. 2024-02-06T19:26:56.782Z,1707247616.782 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-02-06T19:26:56.784Z,1707247616.784 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-02-06T19:26:56.785Z,1707247616.785 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-02-06T19:26:56.786Z,1707247616.786 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-02-06T19:26:56.787Z,1707247616.787 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-02-06T19:26:56.787Z,1707247616.787 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Startup.tx 2024-02-06T19:26:56.832Z,1707247616.832 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-02-06T19:26:56.836Z,1707247616.836 [WetLabsBB2FL](INFO): Powering up 2024-02-06T19:26:56.867Z,1707247616.867 [MissionManager](DEBUG): mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2024-02-06T19:26:56.868Z,1707247616.868 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-02-06T19:26:56.868Z,1707247616.868 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Default.tx 2024-02-06T19:26:56.907Z,1707247616.907 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-02-06T19:26:56.908Z,1707247616.908 [Default:A.Wait](DEBUG): Construct Wait. 2024-02-06T19:26:56.916Z,1707247616.916 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-02-06T19:26:56.927Z,1707247616.927 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-02-06T19:26:56.930Z,1707247616.930 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-02-06T19:26:56.935Z,1707247616.935 [Default:E.Execute](DEBUG): Construct Execute. 2024-02-06T19:26:56.940Z,1707247616.940 [MissionManager](DEBUG): mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2024-02-06T19:26:56.954Z,1707247616.954 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-02-06T19:26:56.973Z,1707247616.973 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-02-06T19:26:57.024Z,1707247617.024 [Power24vConverter](INFO): Powering up. 2024-02-06T19:26:57.041Z,1707247617.041 [Radio_Surface](INFO): Powering up 2024-02-06T19:26:57.071Z,1707247617.071 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-02-06T19:26:57.077Z,1707247617.077 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-02-06T19:26:57.078Z,1707247617.078 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-02-06T19:26:57.085Z,1707247617.085 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-02-06T19:26:57.086Z,1707247617.086 [MassServo](DEBUG): Initializing EZServoServo. 2024-02-06T19:26:57.093Z,1707247617.093 [MassServo](DEBUG): Initializing MassServo. 2024-02-06T19:26:57.094Z,1707247617.094 [RudderServo](DEBUG): Initializing EZServoServo. 2024-02-06T19:26:57.109Z,1707247617.109 [RudderServo](DEBUG): Initializing RudderServo. 2024-02-06T19:26:57.110Z,1707247617.110 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-02-06T19:26:57.125Z,1707247617.125 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-02-06T19:26:59.769Z,1707247619.769 [WetLabsBB2FL](INFO): Powering down 2024-02-06T19:27:02.778Z,1707247622.778 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-02-06T19:27:04.682Z,1707247624.682 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2024-02-06T19:27:07.427Z,1707247627.427 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004792 2024-02-06T19:27:12.527Z,1707247632.527 [DAT](INFO): commRate: 800 2024-02-06T19:27:13.234Z,1707247633.234 [CommandExec](IMPORTANT): got command restart system 2024-02-06T19:27:14.593Z,1707247634.593 [DAT](INFO): entering command mode 2024-02-06T19:27:14.794Z,1707247634.794 [DAT](INFO): setting verbose to 3 2024-02-06T19:27:15.047Z,1707247635.047 [DAT](INFO): set verbose to 3 2024-02-06T19:27:15.047Z,1707247635.047 [DAT](INFO): setting DatVerbose to 27440 2024-02-06T19:27:15.299Z,1707247635.299 [DAT](INFO): set DatVerbose to 27440 2024-02-06T19:27:15.299Z,1707247635.299 [DAT](INFO): setting transmit power to 8 2024-02-06T19:27:15.305Z,1707247635.305 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2024-02-06T19:27:15.306Z,1707247635.306 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-02-06T19:27:15.306Z,1707247635.306 [CommandExec](INFO): Uninitializing the command executive. 2024-02-06T19:27:15.306Z,1707247635.306 [CommandExec](INFO): Uninitializing the command scheduler. 2024-02-06T19:27:15.306Z,1707247635.306 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-02-06T19:27:15.497Z,1707247635.497 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-02-06T19:27:15.497Z,1707247635.497 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-02-06T19:27:15.497Z,1707247635.497 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-02-06T19:27:15.498Z,1707247635.498 [NavChartDb](INFO): Join timeout helper Thread ID is 9759 2024-02-06T19:27:15.550Z,1707247635.550 [DAT](INFO): set transmit power to 8 2024-02-06T19:27:15.551Z,1707247635.551 [DAT](INFO): setting local address to 9 2024-02-06T19:27:15.802Z,1707247635.802 [DAT](INFO): set local address to 9 2024-02-06T19:27:15.804Z,1707247635.804 [DAT](INFO): Setting time to: 19:27:15 And date to:2/6/2024 2024-02-06T19:27:15.809Z,1707247635.809 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-02-06T19:27:15.810Z,1707247635.810 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-02-06T19:27:15.818Z,1707247635.818 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-02-06T19:27:15.818Z,1707247635.818 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-02-06T19:27:15.820Z,1707247635.820 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9760 2024-02-06T19:27:16.055Z,1707247636.055 [DAT](INFO): Local DAT time set to Tue Feb 6, 2024 19:27:15