2024-04-30T16:20:51.711Z,1714494051.711 [Supervisor](DEBUG): Initializing supervisor. 2024-04-30T16:20:51.715Z,1714494051.715 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-04-30T16:20:51.716Z,1714494051.716 [SyncHandler](INFO): Protected caller Thread ID is 4740 2024-04-30T16:20:51.716Z,1714494051.716 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-04-30T16:20:51.717Z,1714494051.717 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-04-30T16:20:51.717Z,1714494051.717 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4741 2024-04-30T16:20:51.721Z,1714494051.721 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-04-30T16:20:51.739Z,1714494051.739 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-04-30T16:20:51.740Z,1714494051.740 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-04-30T16:20:51.740Z,1714494051.740 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4742 2024-04-30T16:20:51.744Z,1714494051.744 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-04-30T16:20:51.745Z,1714494051.745 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-04-30T16:20:51.746Z,1714494051.746 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4743 2024-04-30T16:20:51.748Z,1714494051.748 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-04-30T16:20:51.749Z,1714494051.749 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-04-30T16:20:51.749Z,1714494051.749 [logger ThreadHandler](INFO): Protected caller Thread ID is 4744 2024-04-30T16:20:51.753Z,1714494051.753 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-04-30T16:20:51.753Z,1714494051.753 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-04-30T16:20:51.755Z,1714494051.755 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-04-30T16:20:51.984Z,1714494051.984 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-04-30T16:20:51.985Z,1714494051.985 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-04-30T16:20:52.109Z,1714494052.109 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-04-30T16:20:52.109Z,1714494052.109 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-04-30T16:20:52.218Z,1714494052.218 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-04-30T16:20:52.219Z,1714494052.219 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-04-30T16:20:52.747Z,1714494052.747 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-04-30T16:20:52.748Z,1714494052.748 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-04-30T16:20:53.123Z,1714494053.123 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-04-30T16:20:53.124Z,1714494053.124 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-04-30T16:20:53.490Z,1714494053.490 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-04-30T16:20:53.490Z,1714494053.490 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-04-30T16:20:53.702Z,1714494053.702 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-04-30T16:20:53.702Z,1714494053.702 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-04-30T16:20:53.793Z,1714494053.793 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-04-30T16:20:53.887Z,1714494053.887 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-04-30T16:20:54.323Z,1714494054.323 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-04-30T16:20:54.323Z,1714494054.323 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-04-30T16:20:54.637Z,1714494054.637 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-04-30T16:20:54.638Z,1714494054.638 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-04-30T16:20:55.233Z,1714494055.233 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-04-30T16:20:55.234Z,1714494055.234 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-04-30T16:20:55.476Z,1714494055.476 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-04-30T16:20:55.477Z,1714494055.477 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-04-30T16:20:55.742Z,1714494055.742 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-04-30T16:20:55.743Z,1714494055.743 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-04-30T16:20:55.895Z,1714494055.895 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-04-30T16:20:55.896Z,1714494055.896 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-04-30T16:20:57.629Z,1714494057.629 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-04-30T16:20:57.631Z,1714494057.631 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2024-04-30T16:20:57.634Z,1714494057.634 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2024-04-30T16:20:57.730Z,1714494057.730 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2024-04-30T16:20:57.870Z,1714494057.870 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2024-04-30T16:20:58.148Z,1714494058.148 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-04-30T16:20:58.149Z,1714494058.149 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2024-04-30T16:20:58.288Z,1714494058.288 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2024-04-30T16:20:58.400Z,1714494058.400 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2024-04-30T16:20:58.507Z,1714494058.507 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2024-04-30T16:20:58.629Z,1714494058.629 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2024-04-30T16:20:58.737Z,1714494058.737 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2024-04-30T16:20:58.835Z,1714494058.835 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2024-04-30T16:20:58.938Z,1714494058.938 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2024-04-30T16:20:59.121Z,1714494059.121 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2024-04-30T16:20:59.129Z,1714494059.129 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-04-30T16:20:59.192Z,1714494059.192 [DepthRateCalculator] Loaded 2024-04-30T16:20:59.192Z,1714494059.192 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-04-30T16:20:59.198Z,1714494059.198 [PitchRateCalculator] Loaded 2024-04-30T16:20:59.198Z,1714494059.198 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-04-30T16:20:59.208Z,1714494059.208 [SpeedCalculator] Loaded 2024-04-30T16:20:59.208Z,1714494059.208 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-04-30T16:20:59.213Z,1714494059.213 [YawRateCalculator] Loaded 2024-04-30T16:20:59.213Z,1714494059.213 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-04-30T16:20:59.234Z,1714494059.234 [ElevatorOffsetCalculator] Loaded 2024-04-30T16:20:59.235Z,1714494059.235 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-04-30T16:20:59.235Z,1714494059.235 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-04-30T16:20:59.236Z,1714494059.236 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-04-30T16:20:59.272Z,1714494059.272 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-04-30T16:20:59.273Z,1714494059.273 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-04-30T16:20:59.287Z,1714494059.287 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-04-30T16:20:59.288Z,1714494059.288 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-04-30T16:20:59.982Z,1714494059.982 [AHRS_M2] Loaded 2024-04-30T16:20:59.983Z,1714494059.983 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-04-30T16:21:00.235Z,1714494060.235 [BackseatComponent] Loaded 2024-04-30T16:21:00.235Z,1714494060.235 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-04-30T16:21:00.237Z,1714494060.237 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408584E0 2024-04-30T16:21:00.237Z,1714494060.237 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 4826 2024-04-30T16:21:00.240Z,1714494060.240 [LcmUniversalReporter] Loaded 2024-04-30T16:21:00.240Z,1714494060.240 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-04-30T16:21:01.033Z,1714494061.033 [BPC1] Loaded 2024-04-30T16:21:01.033Z,1714494061.033 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-04-30T16:21:01.129Z,1714494061.129 [DataOverHttps] Loaded 2024-04-30T16:21:01.129Z,1714494061.129 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-04-30T16:21:01.130Z,1714494061.130 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408884E0 2024-04-30T16:21:01.130Z,1714494061.130 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4827 2024-04-30T16:21:01.151Z,1714494061.151 [Depth_Keller] Loaded 2024-04-30T16:21:01.151Z,1714494061.151 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-04-30T16:21:01.156Z,1714494061.156 [DropWeight] Loaded 2024-04-30T16:21:01.156Z,1714494061.156 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-04-30T16:21:01.174Z,1714494061.174 [MultiRay] Loaded 2024-04-30T16:21:01.174Z,1714494061.174 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread. 2024-04-30T16:21:01.237Z,1714494061.237 [NAL9602] Loaded 2024-04-30T16:21:01.237Z,1714494061.237 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-04-30T16:21:01.266Z,1714494061.266 [Onboard] Loaded 2024-04-30T16:21:01.267Z,1714494061.267 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-04-30T16:21:01.268Z,1714494061.268 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408B84E0 2024-04-30T16:21:01.268Z,1714494061.268 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4828 2024-04-30T16:21:01.274Z,1714494061.274 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3 2024-04-30T16:21:01.285Z,1714494061.285 [PowerOnly] Loaded 2024-04-30T16:21:01.286Z,1714494061.286 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2024-04-30T16:21:01.297Z,1714494061.297 [Power24vConverter] Loaded 2024-04-30T16:21:01.298Z,1714494061.298 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-04-30T16:21:01.312Z,1714494061.312 [Radio_Surface] Loaded 2024-04-30T16:21:01.312Z,1714494061.312 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-04-30T16:21:01.313Z,1714494061.313 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408E84E0 2024-04-30T16:21:01.313Z,1714494061.313 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4829 2024-04-30T16:21:01.404Z,1714494061.404 [DAT] Loaded 2024-04-30T16:21:01.404Z,1714494061.404 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-04-30T16:21:01.405Z,1714494061.405 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409184E0 2024-04-30T16:21:01.406Z,1714494061.406 [DAT ThreadHandler](INFO): Protected caller Thread ID is 4830 2024-04-30T16:21:01.406Z,1714494061.406 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-04-30T16:21:01.407Z,1714494061.407 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-04-30T16:21:01.542Z,1714494061.542 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-04-30T16:21:01.542Z,1714494061.542 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-04-30T16:21:01.583Z,1714494061.583 [DeadReckonUsingSpeedCalculator] Loaded 2024-04-30T16:21:01.583Z,1714494061.583 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2024-04-30T16:21:01.597Z,1714494061.597 [NavChart] Loaded 2024-04-30T16:21:01.597Z,1714494061.597 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-04-30T16:21:01.603Z,1714494061.603 [UniversalFixResidualReporter] Loaded 2024-04-30T16:21:01.603Z,1714494061.603 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-04-30T16:21:01.604Z,1714494061.604 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-04-30T16:21:01.604Z,1714494061.604 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-04-30T16:21:01.773Z,1714494061.773 [SBIT](DEBUG): Construct Startup Built In Test. 2024-04-30T16:21:01.787Z,1714494061.787 [SBIT] Loaded 2024-04-30T16:21:01.787Z,1714494061.787 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-04-30T16:21:01.790Z,1714494061.790 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-04-30T16:21:01.803Z,1714494061.803 [IBIT] Loaded 2024-04-30T16:21:01.803Z,1714494061.803 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-04-30T16:21:01.809Z,1714494061.809 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-04-30T16:21:01.912Z,1714494061.912 [CBIT] Loaded 2024-04-30T16:21:01.912Z,1714494061.912 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-04-30T16:21:01.913Z,1714494061.913 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-04-30T16:21:01.913Z,1714494061.913 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-04-30T16:21:02.135Z,1714494062.135 [CTD_Seabird] Loaded 2024-04-30T16:21:02.135Z,1714494062.135 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-04-30T16:21:02.136Z,1714494062.136 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A204E0 2024-04-30T16:21:02.137Z,1714494062.137 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4831 2024-04-30T16:21:02.156Z,1714494062.156 [PAR_Licor] Loaded 2024-04-30T16:21:02.157Z,1714494062.157 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-04-30T16:21:02.201Z,1714494062.201 [WetLabsBB2FL] Loaded 2024-04-30T16:21:02.201Z,1714494062.201 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-04-30T16:21:02.202Z,1714494062.202 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A504E0 2024-04-30T16:21:02.203Z,1714494062.203 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4832 2024-04-30T16:21:02.204Z,1714494062.204 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-04-30T16:21:02.204Z,1714494062.204 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-04-30T16:21:02.365Z,1714494062.365 [BuoyancyServo] Loaded 2024-04-30T16:21:02.366Z,1714494062.366 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-04-30T16:21:02.387Z,1714494062.387 [ElevatorServo] Loaded 2024-04-30T16:21:02.388Z,1714494062.388 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-04-30T16:21:02.408Z,1714494062.408 [MassServo] Loaded 2024-04-30T16:21:02.409Z,1714494062.409 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-04-30T16:21:02.438Z,1714494062.438 [RudderServo] Loaded 2024-04-30T16:21:02.438Z,1714494062.438 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-04-30T16:21:02.486Z,1714494062.486 [ThrusterHE] Loaded 2024-04-30T16:21:02.487Z,1714494062.487 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-04-30T16:21:02.487Z,1714494062.487 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-04-30T16:21:02.487Z,1714494062.487 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-04-30T16:21:02.909Z,1714494062.909 [VerticalControl](DEBUG): Construct VerticalControl. 2024-04-30T16:21:02.960Z,1714494062.960 [VerticalControl] Loaded 2024-04-30T16:21:02.960Z,1714494062.960 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-04-30T16:21:02.963Z,1714494062.963 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-04-30T16:21:03.068Z,1714494063.068 [HorizontalControl] Loaded 2024-04-30T16:21:03.070Z,1714494063.070 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-04-30T16:21:03.072Z,1714494063.072 [SpeedControl](DEBUG): Construct SpeedControl. 2024-04-30T16:21:03.074Z,1714494063.074 [SpeedControl] Loaded 2024-04-30T16:21:03.074Z,1714494063.074 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-04-30T16:21:03.077Z,1714494063.077 [LoopControl](DEBUG): Construct LoopControl. 2024-04-30T16:21:03.078Z,1714494063.078 [LoopControl] Loaded 2024-04-30T16:21:03.078Z,1714494063.078 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-04-30T16:21:03.078Z,1714494063.078 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-04-30T16:21:03.079Z,1714494063.079 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-04-30T16:21:03.222Z,1714494063.222 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-04-30T16:21:03.222Z,1714494063.222 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-04-30T16:21:03.436Z,1714494063.436 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-04-30T16:21:03.436Z,1714494063.436 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-04-30T16:21:03.839Z,1714494063.839 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-04-30T16:21:03.840Z,1714494063.840 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-04-30T16:21:03.915Z,1714494063.915 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-04-30T16:21:03.922Z,1714494063.922 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-04-30T16:21:03.925Z,1714494063.925 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-04-30T16:21:03.936Z,1714494063.936 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-04-30T16:21:03.937Z,1714494063.937 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C374E0 2024-04-30T16:21:03.937Z,1714494063.937 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4833 2024-04-30T16:21:03.942Z,1714494063.942 [Supervisor](INFO): Main Thread ID is 4739 2024-04-30T16:21:03.942Z,1714494063.942 [Supervisor](DEBUG): Running supervisor. 2024-04-30T16:21:03.943Z,1714494063.943 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4834 2024-04-30T16:21:03.943Z,1714494063.943 [CommandExec](INFO): Initializing the command executive. 2024-04-30T16:21:03.945Z,1714494063.945 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4835 2024-04-30T16:21:03.947Z,1714494063.947 [controlThread ThreadHandler](INFO): Handler Thread ID is 4836 2024-04-30T16:21:03.947Z,1714494063.947 [controlThread](DEBUG): Initializing ControlThread 2024-04-30T16:21:03.948Z,1714494063.948 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-04-30T16:21:03.948Z,1714494063.948 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-04-30T16:21:03.949Z,1714494063.949 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-04-30T16:21:03.949Z,1714494063.949 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-04-30T16:21:03.949Z,1714494063.949 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-04-30T16:21:03.955Z,1714494063.955 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-04-30T16:21:03.956Z,1714494063.956 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-04-30T16:21:03.956Z,1714494063.956 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-04-30T16:21:03.957Z,1714494063.957 [SBIT](INFO): Initialize SBIT Component. 2024-04-30T16:21:03.957Z,1714494063.957 [SBIT](IMPORTANT): git: 2024-04-26_A 2024-04-30T16:21:03.957Z,1714494063.957 [SBIT](INFO): git hash: 26b86b0032c96ca2fff2505cfdfca7953dc64339 2024-04-30T16:21:03.958Z,1714494063.958 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-04-30T16:21:03.959Z,1714494063.959 [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-04-30T16:21:03.960Z,1714494063.960 [SBIT](INFO): Beginning SBIT in 84.000000 seconds. 2024-04-30T16:21:03.960Z,1714494063.960 [IBIT](INFO): Initialize IBIT Component. 2024-04-30T16:21:03.961Z,1714494063.961 [CBIT](DEBUG): Initialize CBIT Component. 2024-04-30T16:21:03.962Z,1714494063.962 [logger ThreadHandler](INFO): Handler Thread ID is 4837 2024-04-30T16:21:03.972Z,1714494063.972 [CBIT](DEBUG): Initialized mux pins. 2024-04-30T16:21:03.972Z,1714494063.972 [CBIT](DEBUG): Initializing the watchdog timer. 2024-04-30T16:21:03.981Z,1714494063.981 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 4838 2024-04-30T16:21:03.992Z,1714494063.992 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4839 2024-04-30T16:21:03.994Z,1714494063.994 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-04-30T16:21:03.996Z,1714494063.996 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-04-30T16:21:03.997Z,1714494063.997 [CBIT](DEBUG): Initializing heartbeat. 2024-04-30T16:21:04.005Z,1714494064.005 [Onboard ThreadHandler](INFO): Handler Thread ID is 4840 2024-04-30T16:21:04.022Z,1714494064.022 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4841 2024-04-30T16:21:04.045Z,1714494064.045 [DAT ThreadHandler](INFO): Handler Thread ID is 4842 2024-04-30T16:21:04.046Z,1714494064.046 [DAT](INFO): Powering up 2024-04-30T16:21:04.046Z,1714494064.046 [DAT](DEBUG): Initializing DAT. 2024-04-30T16:21:04.049Z,1714494064.049 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4844 2024-04-30T16:21:04.050Z,1714494064.050 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-04-30T16:21:04.053Z,1714494064.053 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4846 2024-04-30T16:21:04.057Z,1714494064.057 [WetLabsBB2FL](INFO): Powering up 2024-04-30T16:21:04.058Z,1714494064.058 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4848 2024-04-30T16:21:04.061Z,1714494064.061 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-04-30T16:21:04.061Z,1714494064.061 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-04-30T16:21:04.061Z,1714494064.061 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-04-30T16:21:04.061Z,1714494064.061 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-04-30T16:21:04.061Z,1714494064.061 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-04-30T16:21:04.062Z,1714494064.062 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-04-30T16:21:04.062Z,1714494064.062 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-04-30T16:21:04.062Z,1714494064.062 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-04-30T16:21:04.068Z,1714494064.068 [CBIT](DEBUG): Deactivating GF circuits. 2024-04-30T16:21:04.068Z,1714494064.068 [CBIT](DEBUG): Deactivating emergency mode. 2024-04-30T16:21:04.108Z,1714494064.108 [CBIT](DEBUG): Backplane powered. 2024-04-30T16:21:04.118Z,1714494064.118 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-04-30T16:21:04.119Z,1714494064.119 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-04-30T16:21:04.120Z,1714494064.120 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-04-30T16:21:04.121Z,1714494064.121 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-04-30T16:21:04.121Z,1714494064.121 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-04-30T16:21:04.122Z,1714494064.122 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-04-30T16:21:04.122Z,1714494064.122 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-04-30T16:21:04.124Z,1714494064.124 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-04-30T16:21:04.175Z,1714494064.175 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-04-30T16:21:04.196Z,1714494064.196 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-04-30T16:21:04.208Z,1714494064.208 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. 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-04-30T16:21:04.221Z,1714494064.221 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-04-30T16:21:04.221Z,1714494064.221 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2024-04-30T16:21:04.222Z,1714494064.222 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2024-04-30T16:21:04.429Z,1714494064.429 [Radio_Surface](INFO): Powering up 2024-04-30T16:21:04.430Z,1714494064.430 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into: 0 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2024-04-30T16:21:04.442Z,1714494064.442 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-04-30T16:21:04.443Z,1714494064.443 [Default:A.Wait](DEBUG): Construct Wait. 2024-04-30T16:21:04.457Z,1714494064.457 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-04-30T16:21:04.470Z,1714494064.470 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-04-30T16:21:04.484Z,1714494064.484 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-04-30T16:21:04.490Z,1714494064.490 [Default:E.Execute](DEBUG): Construct Execute. 2024-04-30T16:21:04.493Z,1714494064.493 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. 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-04-30T16:21:04.508Z,1714494064.508 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,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-04-30T16:21:04.516Z,1714494064.516 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-04-30T16:21:04.575Z,1714494064.575 [MultiRay](INFO): Powering up MultiRay Lights 2024-04-30T16:21:04.613Z,1714494064.613 [PowerOnly](INFO): Powering up loadControl 2024-04-30T16:21:04.633Z,1714494064.633 [Power24vConverter](INFO): Powering up. 2024-04-30T16:21:04.666Z,1714494064.666 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-04-30T16:21:04.672Z,1714494064.672 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-04-30T16:21:04.673Z,1714494064.673 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-04-30T16:21:04.680Z,1714494064.680 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-04-30T16:21:04.681Z,1714494064.681 [MassServo](DEBUG): Initializing EZServoServo. 2024-04-30T16:21:04.688Z,1714494064.688 [MassServo](DEBUG): Initializing MassServo. 2024-04-30T16:21:04.689Z,1714494064.689 [RudderServo](DEBUG): Initializing EZServoServo. 2024-04-30T16:21:04.696Z,1714494064.696 [RudderServo](DEBUG): Initializing RudderServo. 2024-04-30T16:21:04.697Z,1714494064.697 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-04-30T16:21:04.704Z,1714494064.704 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-04-30T16:21:06.668Z,1714494066.668 [WetLabsBB2FL](INFO): Powering down 2024-04-30T16:21:14.679Z,1714494074.679 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005254 2024-04-30T16:21:17.150Z,1714494077.150 [DAT](INFO): DAT read: 2024-04-30T16:21:17.153Z,1714494077.153 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-04-30T16:21:19.165Z,1714494079.165 [DAT](INFO): DAT read: MF Frequency Band 2024-04-30T16:21:19.166Z,1714494079.166 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-04-30T16:21:19.167Z,1714494079.167 [DAT](INFO): DAT read: Apr 30 2024 00:28:46 2024-04-30T16:21:20.173Z,1714494080.173 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-04-30T16:21:20.174Z,1714494080.174 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2024-04-30T16:21:20.175Z,1714494080.175 [DAT](INFO): commRate: 800 2024-04-30T16:21:22.240Z,1714494082.240 [DAT](INFO): entering command mode 2024-04-30T16:21:22.442Z,1714494082.442 [DAT](INFO): DAT read: 2024-04-30T16:21:22.442Z,1714494082.442 [DAT](INFO): DAT read: user:1> 2024-04-30T16:21:22.443Z,1714494082.443 [DAT](INFO): setting verbose to 3 2024-04-30T16:21:22.693Z,1714494082.693 [DAT](INFO): DAT read: user:1> 2024-04-30T16:21:22.694Z,1714494082.694 [DAT](INFO): DAT read: Verbose | 3 2024-04-30T16:21:22.694Z,1714494082.694 [DAT](INFO): set verbose to 3 2024-04-30T16:21:22.694Z,1714494082.694 [DAT](INFO): setting DatVerbose to 27440 2024-04-30T16:21:22.945Z,1714494082.945 [DAT](INFO): DAT read: user:2> 2024-04-30T16:21:22.946Z,1714494082.946 [DAT](INFO): DAT read: DatVerbose | 27440 2024-04-30T16:21:22.946Z,1714494082.946 [DAT](INFO): set DatVerbose to 27440 2024-04-30T16:21:22.946Z,1714494082.946 [DAT](INFO): setting transmit power to 8 2024-04-30T16:21:23.197Z,1714494083.197 [DAT](INFO): DAT read: user:3> 2024-04-30T16:21:23.198Z,1714494083.198 [DAT](INFO): DAT read: TxPower | 8 (Max) 2024-04-30T16:21:23.198Z,1714494083.198 [DAT](INFO): set transmit power to 8 2024-04-30T16:21:23.198Z,1714494083.198 [DAT](INFO): setting local address to 9 2024-04-30T16:21:23.449Z,1714494083.449 [DAT](INFO): DAT read: user:4> 2024-04-30T16:21:23.450Z,1714494083.450 [DAT](INFO): DAT read: LocalAddr | 9 2024-04-30T16:21:23.450Z,1714494083.450 [DAT](INFO): set local address to 9 2024-04-30T16:21:23.451Z,1714494083.451 [DAT](INFO): Setting time to: 16:21:23 And date to:4/30/2024 2024-04-30T16:21:23.701Z,1714494083.701 [DAT](INFO): DAT read: user:5> 2024-04-30T16:21:23.702Z,1714494083.702 [DAT](INFO): DAT read: Tue Apr 30, 2024 16:21:23 2024-04-30T16:21:23.702Z,1714494083.702 [DAT](INFO): Local DAT time set to Tue Apr 30, 2024 16:21:23 2024-04-30T16:21:31.266Z,1714494091.266 [NAL9602](INFO): Powering up NAL9602 2024-04-30T16:21:36.334Z,1714494096.334 [CommandExec](IMPORTANT): got command restart system 2024-04-30T16:21:38.452Z,1714494098.452 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2024-04-30T16:21:38.453Z,1714494098.453 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-30T16:21:38.453Z,1714494098.453 [CommandExec](INFO): Uninitializing the command executive. 2024-04-30T16:21:38.453Z,1714494098.453 [CommandExec](INFO): Uninitializing the command scheduler. 2024-04-30T16:21:38.453Z,1714494098.453 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-04-30T16:21:38.616Z,1714494098.616 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-04-30T16:21:38.616Z,1714494098.616 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-04-30T16:21:38.617Z,1714494098.617 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-30T16:21:38.617Z,1714494098.617 [NavChartDb](INFO): Join timeout helper Thread ID is 4861 2024-04-30T16:21:38.909Z,1714494098.909 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-30T16:21:38.909Z,1714494098.909 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-30T16:21:38.916Z,1714494098.916 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-04-30T16:21:38.916Z,1714494098.916 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-30T16:21:38.917Z,1714494098.917 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4862 2024-04-30T16:21:39.392Z,1714494099.392 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-30T16:21:39.393Z,1714494099.393 [WetLabsBB2FL](INFO): Powering down 2024-04-30T16:21:39.393Z,1714494099.393 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-30T16:21:39.412Z,1714494099.412 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-04-30T16:21:39.412Z,1714494099.412 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-30T16:21:39.413Z,1714494099.413 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4863 2024-04-30T16:21:40.196Z,1714494100.196 [CTD_Seabird](INFO): Powering down 2024-04-30T16:21:40.209Z,1714494100.209 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-30T16:21:40.209Z,1714494100.209 [CTD_Seabird](INFO): Powering down 2024-04-30T16:21:40.227Z,1714494100.227 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-30T16:21:40.241Z,1714494100.241 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2024-04-30T16:21:40.241Z,1714494100.241 [DAT ThreadHandler](INFO): Thread cancelled. 2024-04-30T16:21:40.241Z,1714494100.241 [DAT](INFO): Join timeout helper Thread ID is 4864 2024-04-30T16:21:40.340Z,1714494100.340 [DAT](INFO): Powering down 2024-04-30T16:21:40.409Z,1714494100.409 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-30T16:21:40.409Z,1714494100.409 [DAT](INFO): Powering down 2024-04-30T16:21:40.410Z,1714494100.410 [DAT ThreadHandler](INFO): Thread cancelled. 2024-04-30T16:21:40.424Z,1714494100.424 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-04-30T16:21:40.424Z,1714494100.424 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-04-30T16:21:40.424Z,1714494100.424 [Radio_Surface](INFO): Join timeout helper Thread ID is 4865 2024-04-30T16:21:40.788Z,1714494100.788 [Radio_Surface](INFO): Powering down 2024-04-30T16:21:40.790Z,1714494100.790 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-30T16:2