2024-04-10T21:39:31.773Z,1712785171.773 [Supervisor](DEBUG): Initializing supervisor. 2024-04-10T21:39:31.777Z,1712785171.777 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-04-10T21:39:31.778Z,1712785171.778 [SyncHandler](INFO): Protected caller Thread ID is 4658 2024-04-10T21:39:31.778Z,1712785171.778 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-04-10T21:39:31.779Z,1712785171.779 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-04-10T21:39:31.780Z,1712785171.780 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4659 2024-04-10T21:39:31.784Z,1712785171.784 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-04-10T21:39:31.802Z,1712785171.802 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-04-10T21:39:31.803Z,1712785171.803 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-04-10T21:39:31.803Z,1712785171.803 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4660 2024-04-10T21:39:31.807Z,1712785171.807 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-04-10T21:39:31.808Z,1712785171.808 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-04-10T21:39:31.809Z,1712785171.809 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4661 2024-04-10T21:39:31.811Z,1712785171.811 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-04-10T21:39:31.812Z,1712785171.812 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-04-10T21:39:31.812Z,1712785171.812 [logger ThreadHandler](INFO): Protected caller Thread ID is 4662 2024-04-10T21:39:31.816Z,1712785171.816 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-04-10T21:39:31.816Z,1712785171.816 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-04-10T21:39:31.818Z,1712785171.818 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-04-10T21:39:32.038Z,1712785172.038 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-04-10T21:39:32.038Z,1712785172.038 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-04-10T21:39:32.151Z,1712785172.151 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-04-10T21:39:32.151Z,1712785172.151 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-04-10T21:39:32.250Z,1712785172.250 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-04-10T21:39:32.250Z,1712785172.250 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-04-10T21:39:32.751Z,1712785172.751 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-04-10T21:39:32.751Z,1712785172.751 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-04-10T21:39:33.118Z,1712785173.118 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-04-10T21:39:33.119Z,1712785173.119 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-04-10T21:39:33.478Z,1712785173.478 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-04-10T21:39:33.478Z,1712785173.478 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-04-10T21:39:33.681Z,1712785173.681 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-04-10T21:39:33.682Z,1712785173.682 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-04-10T21:39:33.763Z,1712785173.763 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-04-10T21:39:33.846Z,1712785173.846 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-04-10T21:39:34.274Z,1712785174.274 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-04-10T21:39:34.275Z,1712785174.275 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-04-10T21:39:34.572Z,1712785174.572 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-04-10T21:39:34.572Z,1712785174.572 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-04-10T21:39:35.112Z,1712785175.112 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-04-10T21:39:35.113Z,1712785175.113 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-04-10T21:39:35.346Z,1712785175.346 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-04-10T21:39:35.346Z,1712785175.346 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-04-10T21:39:35.603Z,1712785175.603 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-04-10T21:39:35.604Z,1712785175.604 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-04-10T21:39:35.747Z,1712785175.747 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-04-10T21:39:35.748Z,1712785175.748 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-04-10T21:39:36.624Z,1712785176.624 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-04-10T21:39:36.626Z,1712785176.626 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2024-04-10T21:39:36.627Z,1712785176.627 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2024-04-10T21:39:36.713Z,1712785176.713 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2024-04-10T21:39:36.927Z,1712785176.927 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2024-04-10T21:39:37.952Z,1712785177.952 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-04-10T21:39:37.953Z,1712785177.953 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2024-04-10T21:39:38.083Z,1712785178.083 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2024-04-10T21:39:38.187Z,1712785178.187 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2024-04-10T21:39:38.284Z,1712785178.284 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2024-04-10T21:39:38.395Z,1712785178.395 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2024-04-10T21:39:38.495Z,1712785178.495 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2024-04-10T21:39:38.583Z,1712785178.583 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2024-04-10T21:39:38.676Z,1712785178.676 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/workSite.cfg 2024-04-10T21:39:38.761Z,1712785178.761 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2024-04-10T21:39:38.934Z,1712785178.934 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2024-04-10T21:39:38.941Z,1712785178.941 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-04-10T21:39:38.992Z,1712785178.992 [DepthRateCalculator] Loaded 2024-04-10T21:39:38.992Z,1712785178.992 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-04-10T21:39:38.998Z,1712785178.998 [PitchRateCalculator] Loaded 2024-04-10T21:39:38.998Z,1712785178.998 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-04-10T21:39:39.009Z,1712785179.009 [SpeedCalculator] Loaded 2024-04-10T21:39:39.009Z,1712785179.009 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-04-10T21:39:39.014Z,1712785179.014 [YawRateCalculator] Loaded 2024-04-10T21:39:39.014Z,1712785179.014 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-04-10T21:39:39.035Z,1712785179.035 [ElevatorOffsetCalculator] Loaded 2024-04-10T21:39:39.035Z,1712785179.035 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-04-10T21:39:39.036Z,1712785179.036 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-04-10T21:39:39.036Z,1712785179.036 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-04-10T21:39:39.063Z,1712785179.063 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-04-10T21:39:39.064Z,1712785179.064 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-04-10T21:39:39.074Z,1712785179.074 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-04-10T21:39:39.074Z,1712785179.074 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-04-10T21:39:39.523Z,1712785179.523 [AHRS_M2] Loaded 2024-04-10T21:39:39.523Z,1712785179.523 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-04-10T21:39:39.558Z,1712785179.558 [BackseatComponent] Loaded 2024-04-10T21:39:39.558Z,1712785179.558 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-04-10T21:39:39.559Z,1712785179.559 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4084E4E0 2024-04-10T21:39:39.560Z,1712785179.560 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 4748 2024-04-10T21:39:39.562Z,1712785179.562 [LcmUniversalReporter] Loaded 2024-04-10T21:39:39.563Z,1712785179.563 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-04-10T21:39:40.357Z,1712785180.357 [BPC1] Loaded 2024-04-10T21:39:40.357Z,1712785180.357 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-04-10T21:39:40.436Z,1712785180.436 [DataOverHttps] Loaded 2024-04-10T21:39:40.437Z,1712785180.437 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-04-10T21:39:40.438Z,1712785180.438 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087E4E0 2024-04-10T21:39:40.438Z,1712785180.438 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4749 2024-04-10T21:39:40.458Z,1712785180.458 [Depth_Keller] Loaded 2024-04-10T21:39:40.458Z,1712785180.458 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-04-10T21:39:40.464Z,1712785180.464 [DropWeight] Loaded 2024-04-10T21:39:40.464Z,1712785180.464 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-04-10T21:39:40.483Z,1712785180.483 [MultiRay] Loaded 2024-04-10T21:39:40.483Z,1712785180.483 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread. 2024-04-10T21:39:40.544Z,1712785180.544 [NAL9602] Loaded 2024-04-10T21:39:40.544Z,1712785180.544 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-04-10T21:39:40.574Z,1712785180.574 [Onboard] Loaded 2024-04-10T21:39:40.574Z,1712785180.574 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-04-10T21:39:40.575Z,1712785180.575 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408AE4E0 2024-04-10T21:39:40.575Z,1712785180.575 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4750 2024-04-10T21:39:40.581Z,1712785180.581 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3 2024-04-10T21:39:40.593Z,1712785180.593 [PowerOnly] Loaded 2024-04-10T21:39:40.593Z,1712785180.593 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2024-04-10T21:39:40.605Z,1712785180.605 [Power24vConverter] Loaded 2024-04-10T21:39:40.605Z,1712785180.605 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-04-10T21:39:40.619Z,1712785180.619 [Radio_Surface] Loaded 2024-04-10T21:39:40.619Z,1712785180.619 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-04-10T21:39:40.620Z,1712785180.620 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408DE4E0 2024-04-10T21:39:40.621Z,1712785180.621 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4751 2024-04-10T21:39:40.711Z,1712785180.711 [DAT] Loaded 2024-04-10T21:39:40.712Z,1712785180.712 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-04-10T21:39:40.713Z,1712785180.713 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4090E4E0 2024-04-10T21:39:40.713Z,1712785180.713 [DAT ThreadHandler](INFO): Protected caller Thread ID is 4752 2024-04-10T21:39:40.714Z,1712785180.714 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-04-10T21:39:40.714Z,1712785180.714 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-04-10T21:39:40.825Z,1712785180.825 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-04-10T21:39:40.825Z,1712785180.825 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-04-10T21:39:40.867Z,1712785180.867 [DeadReckonUsingSpeedCalculator] Loaded 2024-04-10T21:39:40.867Z,1712785180.867 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2024-04-10T21:39:40.881Z,1712785180.881 [NavChart] Loaded 2024-04-10T21:39:40.881Z,1712785180.881 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-04-10T21:39:40.887Z,1712785180.887 [UniversalFixResidualReporter] Loaded 2024-04-10T21:39:40.887Z,1712785180.887 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-04-10T21:39:40.887Z,1712785180.887 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-04-10T21:39:40.888Z,1712785180.888 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-04-10T21:39:41.029Z,1712785181.029 [SBIT](DEBUG): Construct Startup Built In Test. 2024-04-10T21:39:41.044Z,1712785181.044 [SBIT] Loaded 2024-04-10T21:39:41.044Z,1712785181.044 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-04-10T21:39:41.047Z,1712785181.047 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-04-10T21:39:41.060Z,1712785181.060 [IBIT] Loaded 2024-04-10T21:39:41.060Z,1712785181.060 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-04-10T21:39:41.066Z,1712785181.066 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-04-10T21:39:41.170Z,1712785181.170 [CBIT] Loaded 2024-04-10T21:39:41.170Z,1712785181.170 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-04-10T21:39:41.171Z,1712785181.171 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-04-10T21:39:41.171Z,1712785181.171 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-04-10T21:39:41.340Z,1712785181.340 [CTD_Seabird] Loaded 2024-04-10T21:39:41.341Z,1712785181.341 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-04-10T21:39:41.342Z,1712785181.342 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A164E0 2024-04-10T21:39:41.342Z,1712785181.342 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4753 2024-04-10T21:39:41.362Z,1712785181.362 [PAR_Licor] Loaded 2024-04-10T21:39:41.362Z,1712785181.362 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-04-10T21:39:41.404Z,1712785181.404 [WetLabsBB2FL] Loaded 2024-04-10T21:39:41.405Z,1712785181.405 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-04-10T21:39:41.406Z,1712785181.406 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0 2024-04-10T21:39:41.406Z,1712785181.406 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4754 2024-04-10T21:39:41.407Z,1712785181.407 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-04-10T21:39:41.407Z,1712785181.407 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-04-10T21:39:41.539Z,1712785181.539 [BuoyancyServo] Loaded 2024-04-10T21:39:41.540Z,1712785181.540 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-04-10T21:39:41.561Z,1712785181.561 [ElevatorServo] Loaded 2024-04-10T21:39:41.562Z,1712785181.562 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-04-10T21:39:41.582Z,1712785181.582 [MassServo] Loaded 2024-04-10T21:39:41.583Z,1712785181.583 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-04-10T21:39:41.604Z,1712785181.604 [RudderServo] Loaded 2024-04-10T21:39:41.604Z,1712785181.604 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-04-10T21:39:41.620Z,1712785181.620 [ThrusterHE] Loaded 2024-04-10T21:39:41.620Z,1712785181.620 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-04-10T21:39:41.621Z,1712785181.621 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-04-10T21:39:41.621Z,1712785181.621 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-04-10T21:39:41.673Z,1712785181.673 [VerticalControl](DEBUG): Construct VerticalControl. 2024-04-10T21:39:41.734Z,1712785181.734 [VerticalControl] Loaded 2024-04-10T21:39:41.734Z,1712785181.734 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-04-10T21:39:41.737Z,1712785181.737 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-04-10T21:39:41.776Z,1712785181.776 [HorizontalControl] Loaded 2024-04-10T21:39:41.777Z,1712785181.777 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-04-10T21:39:41.779Z,1712785181.779 [SpeedControl](DEBUG): Construct SpeedControl. 2024-04-10T21:39:41.781Z,1712785181.781 [SpeedControl] Loaded 2024-04-10T21:39:41.781Z,1712785181.781 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-04-10T21:39:41.784Z,1712785181.784 [LoopControl](DEBUG): Construct LoopControl. 2024-04-10T21:39:41.784Z,1712785181.784 [LoopControl] Loaded 2024-04-10T21:39:41.785Z,1712785181.785 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-04-10T21:39:41.785Z,1712785181.785 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-04-10T21:39:41.786Z,1712785181.786 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-04-10T21:39:41.900Z,1712785181.900 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-04-10T21:39:41.901Z,1712785181.901 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-04-10T21:39:42.048Z,1712785182.048 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-04-10T21:39:42.049Z,1712785182.049 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-04-10T21:39:42.719Z,1712785182.719 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-04-10T21:39:42.720Z,1712785182.720 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-04-10T21:39:42.777Z,1712785182.777 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-04-10T21:39:42.783Z,1712785182.783 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-04-10T21:39:42.786Z,1712785182.786 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-04-10T21:39:42.797Z,1712785182.797 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-04-10T21:39:42.798Z,1712785182.798 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C234E0 2024-04-10T21:39:42.798Z,1712785182.798 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4755 2024-04-10T21:39:42.803Z,1712785182.803 [Supervisor](INFO): Main Thread ID is 4657 2024-04-10T21:39:42.803Z,1712785182.803 [Supervisor](DEBUG): Running supervisor. 2024-04-10T21:39:42.804Z,1712785182.804 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4756 2024-04-10T21:39:42.804Z,1712785182.804 [CommandExec](INFO): Initializing the command executive. 2024-04-10T21:39:42.806Z,1712785182.806 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4757 2024-04-10T21:39:42.808Z,1712785182.808 [controlThread ThreadHandler](INFO): Handler Thread ID is 4758 2024-04-10T21:39:42.808Z,1712785182.808 [controlThread](DEBUG): Initializing ControlThread 2024-04-10T21:39:42.809Z,1712785182.809 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-04-10T21:39:42.810Z,1712785182.810 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-04-10T21:39:42.810Z,1712785182.810 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-04-10T21:39:42.810Z,1712785182.810 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-04-10T21:39:42.811Z,1712785182.811 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-04-10T21:39:42.816Z,1712785182.816 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-04-10T21:39:42.817Z,1712785182.817 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-04-10T21:39:42.817Z,1712785182.817 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-04-10T21:39:42.817Z,1712785182.817 [SBIT](INFO): Initialize SBIT Component. 2024-04-10T21:39:42.818Z,1712785182.818 [SBIT](IMPORTANT): git: 2024-03-26 2024-04-10T21:39:42.818Z,1712785182.818 [SBIT](INFO): git hash: ad310a373d447f5044d653e05ec2c3eb1a52d830 2024-04-10T21:39:42.818Z,1712785182.818 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-04-10T21:39:42.819Z,1712785182.819 [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-10T21:39:42.821Z,1712785182.821 [SBIT](INFO): Beginning SBIT in 24.000000 seconds. 2024-04-10T21:39:42.821Z,1712785182.821 [IBIT](INFO): Initialize IBIT Component. 2024-04-10T21:39:42.822Z,1712785182.822 [CBIT](DEBUG): Initialize CBIT Component. 2024-04-10T21:39:42.823Z,1712785182.823 [logger ThreadHandler](INFO): Handler Thread ID is 4759 2024-04-10T21:39:42.834Z,1712785182.834 [CBIT](DEBUG): Initialized mux pins. 2024-04-10T21:39:42.834Z,1712785182.834 [CBIT](DEBUG): Initializing the watchdog timer. 2024-04-10T21:39:42.842Z,1712785182.842 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 4760 2024-04-10T21:39:42.854Z,1712785182.854 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4761 2024-04-10T21:39:42.856Z,1712785182.856 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-04-10T21:39:42.858Z,1712785182.858 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-04-10T21:39:42.858Z,1712785182.858 [CBIT](DEBUG): Initializing heartbeat. 2024-04-10T21:39:42.866Z,1712785182.866 [Onboard ThreadHandler](INFO): Handler Thread ID is 4762 2024-04-10T21:39:42.883Z,1712785182.883 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4763 2024-04-10T21:39:42.906Z,1712785182.906 [DAT ThreadHandler](INFO): Handler Thread ID is 4764 2024-04-10T21:39:42.907Z,1712785182.907 [DAT](INFO): Powering up 2024-04-10T21:39:42.907Z,1712785182.907 [DAT](DEBUG): Initializing DAT. 2024-04-10T21:39:42.911Z,1712785182.911 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4766 2024-04-10T21:39:42.912Z,1712785182.912 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-04-10T21:39:42.915Z,1712785182.915 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4768 2024-04-10T21:39:42.918Z,1712785182.918 [WetLabsBB2FL](INFO): Powering up 2024-04-10T21:39:42.919Z,1712785182.919 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4770 2024-04-10T21:39:42.922Z,1712785182.922 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-04-10T21:39:42.922Z,1712785182.922 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-04-10T21:39:42.923Z,1712785182.923 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-04-10T21:39:42.923Z,1712785182.923 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-04-10T21:39:42.923Z,1712785182.923 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-04-10T21:39:42.923Z,1712785182.923 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-04-10T21:39:42.923Z,1712785182.923 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-04-10T21:39:42.923Z,1712785182.923 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-04-10T21:39:42.930Z,1712785182.930 [CBIT](DEBUG): Deactivating GF circuits. 2024-04-10T21:39:42.930Z,1712785182.930 [CBIT](DEBUG): Deactivating emergency mode. 2024-04-10T21:39:42.969Z,1712785182.969 [CBIT](DEBUG): Backplane powered. 2024-04-10T21:39:42.975Z,1712785182.975 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-04-10T21:39:42.977Z,1712785182.977 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-04-10T21:39:42.978Z,1712785182.978 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-04-10T21:39:42.978Z,1712785182.978 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-04-10T21:39:42.979Z,1712785182.979 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-04-10T21:39:42.980Z,1712785182.980 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-04-10T21:39:42.980Z,1712785182.980 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-04-10T21:39:42.981Z,1712785182.981 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-04-10T21:39:43.022Z,1712785183.022 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-04-10T21:39:43.039Z,1712785183.039 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-04-10T21:39:43.053Z,1712785183.053 [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-04-10T21:39:43.054Z,1712785183.054 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2024-04-10T21:39:43.127Z,1712785183.127 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-04-10T21:39:43.128Z,1712785183.128 [Default:A.Wait](DEBUG): Construct Wait. 2024-04-10T21:39:43.147Z,1712785183.147 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-04-10T21:39:43.183Z,1712785183.183 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-04-10T21:39:43.185Z,1712785183.185 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-04-10T21:39:43.203Z,1712785183.203 [Default:E.Execute](DEBUG): Construct Execute. 2024-04-10T21:39:43.206Z,1712785183.206 [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 2024-04-10T21:39:43.211Z,1712785183.211 [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-10T21:39:43.236Z,1712785183.236 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-04-10T21:39:43.290Z,1712785183.290 [Radio_Surface](INFO): Powering up 2024-04-10T21:39:43.296Z,1712785183.296 [MultiRay](INFO): Powering up MultiRay Lights 2024-04-10T21:39:43.335Z,1712785183.335 [PowerOnly](INFO): Powering up loadControl 2024-04-10T21:39:43.354Z,1712785183.354 [Power24vConverter](INFO): Powering up. 2024-04-10T21:39:43.395Z,1712785183.395 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-04-10T21:39:43.402Z,1712785183.402 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-04-10T21:39:43.403Z,1712785183.403 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-04-10T21:39:43.411Z,1712785183.411 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-04-10T21:39:43.412Z,1712785183.412 [MassServo](DEBUG): Initializing EZServoServo. 2024-04-10T21:39:43.418Z,1712785183.418 [MassServo](DEBUG): Initializing MassServo. 2024-04-10T21:39:43.419Z,1712785183.419 [RudderServo](DEBUG): Initializing EZServoServo. 2024-04-10T21:39:43.426Z,1712785183.426 [RudderServo](DEBUG): Initializing RudderServo. 2024-04-10T21:39:43.427Z,1712785183.427 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-04-10T21:39:43.438Z,1712785183.438 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-04-10T21:39:43.653Z,1712785183.653 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-04-10T21:39:43.654Z,1712785183.654 [DropWeight] Hardware Fault, FailCount= 1 2024-04-10T21:39:43.654Z,1712785183.654 [DropWeight](ERROR): Hardware Fault 2024-04-10T21:39:43.714Z,1712785183.714 [CommandExec](FAULT): Scheduling is paused 2024-04-10T21:39:43.714Z,1712785183.714 [CBIT](INFO): Critical error at 20240410T213943 2024-04-10T21:39:43.714Z,1712785183.714 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2024-04-10T21:39:43.717Z,1712785183.717 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-04-10T21:39:43.717Z,1712785183.717 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-04-10T21:39:44.061Z,1712785184.061 [CBIT](INFO): Critical error at 20240410T213943 2024-04-10T21:39:45.530Z,1712785185.530 [WetLabsBB2FL](INFO): Powering down 2024-04-10T21:39:49.007Z,1712785189.007 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-04-10T21:39:49.943Z,1712785189.943 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2024-04-10T21:39:55.258Z,1712785195.258 [DAT](INFO): DAT read: 2024-04-10T21:39:55.259Z,1712785195.259 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-04-10T21:39:55.715Z,1712785195.715 [CommandExec](IMPORTANT): got command restart system 2024-04-10T21:39:57.024Z,1712785197.024 [DAT](INFO): DAT read: MF Frequency Band 2024-04-10T21:39:57.275Z,1712785197.275 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-04-10T21:39:57.276Z,1712785197.276 [DAT](INFO): DAT read: Apr 10 2024 21:39:51 2024-04-10T21:39:57.778Z,1712785197.778 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-10T21:39:57.778Z,1712785197.778 [CommandExec](INFO): Uninitializing the command executive. 2024-04-10T21:39:57.778Z,1712785197.778 [CommandExec](INFO): Uninitializing the command scheduler. 2024-04-10T21:39:57.778Z,1712785197.778 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-04-10T21:39:57.878Z,1712785197.878 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-04-10T21:39:57.878Z,1712785197.878 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-04-10T21:39:57.880Z,1712785197.880 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-10T21:39:57.893Z,1712785197.893 [NavChartDb](INFO): Join timeout helper Thread ID is 4782 2024-04-10T21:39:57.974Z,1712785197.974 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-10T21:39:57.974Z,1712785197.974 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-10T21:39:57.978Z,1712785197.978 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-04-10T21:39:57.978Z,1712785197.978 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-10T21:39:57.978Z,1712785197.978 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4783 2024-04-10T21:39:58.034Z,1712785198.034 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-10T21:39:58.034Z,1712785198.034 [WetLabsBB2FL](INFO): Powering down 2024-04-10T21:39:58.035Z,1712785198.035 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-10T21:39:58.038Z,1712785198.038 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-04-10T21:39:58.038Z,1712785198.038 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-10T21:39:58.038Z,1712785198.038 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4784 2024-04-10T21:39:58.283Z,1712785198.283 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-04-10T21:39:58.284Z,1712785198.284 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2024-04-10T21:39:58.284Z,1712785198.284 [DAT](INFO): commRate: 800 2024-04-10T21:39:58.974Z,1712785198.974 [CTD_Seabird](INFO): Powering down 2024-04-10T21:39:58.994Z,1712785198.994 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-10T21:39:58.994Z,1712785198.994 [CTD_Seabird](INFO): Powering down 2024-04-10T21:39:59.005Z,1712785199.005 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-10T21:39:59.022Z,1712785199.022 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler