2022-08-08T19:25:31.655Z,1659986731.655 [Supervisor](DEBUG): Initializing supervisor. 2022-08-08T19:25:31.661Z,1659986731.661 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2022-08-08T19:25:31.662Z,1659986731.662 [SyncHandler](INFO): Protected caller Thread ID is 9672 2022-08-08T19:25:31.662Z,1659986731.662 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2022-08-08T19:25:31.663Z,1659986731.663 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2022-08-08T19:25:31.663Z,1659986731.663 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9673 2022-08-08T19:25:31.668Z,1659986731.668 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2022-08-08T19:25:31.686Z,1659986731.686 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2022-08-08T19:25:31.687Z,1659986731.687 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2022-08-08T19:25:31.687Z,1659986731.687 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 9674 2022-08-08T19:25:31.691Z,1659986731.691 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2022-08-08T19:25:31.692Z,1659986731.692 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2022-08-08T19:25:31.693Z,1659986731.693 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9675 2022-08-08T19:25:31.695Z,1659986731.695 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2022-08-08T19:25:31.696Z,1659986731.696 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2022-08-08T19:25:31.696Z,1659986731.696 [logger ThreadHandler](INFO): Protected caller Thread ID is 9676 2022-08-08T19:25:31.700Z,1659986731.700 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2022-08-08T19:25:31.700Z,1659986731.700 [Supervisor](INFO): Looking for Config files in directory: Config/ 2022-08-08T19:25:31.705Z,1659986731.705 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2022-08-08T19:25:32.029Z,1659986732.029 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2022-08-08T19:25:32.031Z,1659986732.031 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2022-08-08T19:25:32.114Z,1659986732.114 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2022-08-08T19:25:32.643Z,1659986732.643 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2022-08-08T19:25:32.645Z,1659986732.645 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2022-08-08T19:25:32.969Z,1659986732.969 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2022-08-08T19:25:32.970Z,1659986732.970 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2022-08-08T19:25:33.066Z,1659986733.066 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2022-08-08T19:25:33.068Z,1659986733.068 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2022-08-08T19:25:33.490Z,1659986733.490 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2022-08-08T19:25:33.499Z,1659986733.499 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2022-08-08T19:25:33.710Z,1659986733.710 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2022-08-08T19:25:33.711Z,1659986733.711 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2022-08-08T19:25:34.165Z,1659986734.165 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2022-08-08T19:25:34.166Z,1659986734.166 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2022-08-08T19:25:34.292Z,1659986734.292 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2022-08-08T19:25:34.294Z,1659986734.294 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2022-08-08T19:25:34.374Z,1659986734.374 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2022-08-08T19:25:35.125Z,1659986735.125 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2022-08-08T19:25:35.127Z,1659986735.127 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2022-08-08T19:25:35.861Z,1659986735.861 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2022-08-08T19:25:35.861Z,1659986735.861 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2022-08-08T19:25:36.049Z,1659986736.049 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2022-08-08T19:25:36.051Z,1659986736.051 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2022-08-08T19:25:36.161Z,1659986736.161 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2022-08-08T19:25:36.161Z,1659986736.161 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2022-08-08T19:25:36.388Z,1659986736.388 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2022-08-08T19:25:36.394Z,1659986736.394 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2022-08-08T19:25:36.622Z,1659986736.622 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2022-08-08T19:25:36.624Z,1659986736.624 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2022-08-08T19:25:36.627Z,1659986736.627 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2022-08-08T19:25:36.719Z,1659986736.719 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2022-08-08T19:25:36.806Z,1659986736.806 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2022-08-08T19:25:36.910Z,1659986736.910 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2022-08-08T19:25:36.994Z,1659986736.994 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2022-08-08T19:25:37.092Z,1659986737.092 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2022-08-08T19:25:37.194Z,1659986737.194 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2022-08-08T19:25:37.383Z,1659986737.383 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2022-08-08T19:25:37.459Z,1659986737.459 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2022-08-08T19:25:37.606Z,1659986737.606 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2022-08-08T19:25:37.744Z,1659986737.744 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2022-08-08T19:25:37.858Z,1659986737.858 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2022-08-08T19:25:38.123Z,1659986738.123 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2022-08-08T19:25:38.124Z,1659986738.124 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2022-08-08T19:25:38.140Z,1659986738.140 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2022-08-08T19:25:38.213Z,1659986738.213 [VerticalControl](DEBUG): Construct VerticalControl. 2022-08-08T19:25:38.273Z,1659986738.273 [VerticalControl] Loaded 2022-08-08T19:25:38.274Z,1659986738.274 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2022-08-08T19:25:38.276Z,1659986738.276 [HorizontalControl](DEBUG): Construct HorizontalControl. 2022-08-08T19:25:38.315Z,1659986738.315 [HorizontalControl] Loaded 2022-08-08T19:25:38.315Z,1659986738.315 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2022-08-08T19:25:38.318Z,1659986738.318 [SpeedControl](DEBUG): Construct SpeedControl. 2022-08-08T19:25:38.321Z,1659986738.321 [SpeedControl] Loaded 2022-08-08T19:25:38.321Z,1659986738.321 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2022-08-08T19:25:38.323Z,1659986738.323 [LoopControl](DEBUG): Construct LoopControl. 2022-08-08T19:25:38.324Z,1659986738.324 [LoopControl] Loaded 2022-08-08T19:25:38.324Z,1659986738.324 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2022-08-08T19:25:38.325Z,1659986738.325 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2022-08-08T19:25:38.325Z,1659986738.325 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2022-08-08T19:25:38.342Z,1659986738.342 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2022-08-08T19:25:38.343Z,1659986738.343 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2022-08-08T19:25:38.492Z,1659986738.492 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2022-08-08T19:25:38.493Z,1659986738.493 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2022-08-08T19:25:38.648Z,1659986738.648 [BuoyancyServo] Loaded 2022-08-08T19:25:38.649Z,1659986738.649 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2022-08-08T19:25:38.669Z,1659986738.669 [ElevatorServo] Loaded 2022-08-08T19:25:38.669Z,1659986738.669 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2022-08-08T19:25:38.688Z,1659986738.688 [MassServo] Loaded 2022-08-08T19:25:38.688Z,1659986738.688 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2022-08-08T19:25:38.706Z,1659986738.706 [RudderServo] Loaded 2022-08-08T19:25:38.706Z,1659986738.706 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2022-08-08T19:25:38.724Z,1659986738.724 [ThrusterServo] Loaded 2022-08-08T19:25:38.724Z,1659986738.724 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2022-08-08T19:25:38.725Z,1659986738.725 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2022-08-08T19:25:38.725Z,1659986738.725 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2022-08-08T19:25:38.930Z,1659986738.930 [DeadReckonUsingMultipleVelocitySources] Loaded 2022-08-08T19:25:38.931Z,1659986738.931 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2022-08-08T19:25:38.956Z,1659986738.956 [NavChart] Loaded 2022-08-08T19:25:38.956Z,1659986738.956 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2022-08-08T19:25:38.962Z,1659986738.962 [UniversalFixResidualReporter] Loaded 2022-08-08T19:25:38.966Z,1659986738.966 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2022-08-08T19:25:38.967Z,1659986738.967 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2022-08-08T19:25:38.968Z,1659986738.968 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2022-08-08T19:25:39.054Z,1659986739.054 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2022-08-08T19:25:39.055Z,1659986739.055 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2022-08-08T19:25:39.829Z,1659986739.829 [AHRS_M2] Loaded 2022-08-08T19:25:39.829Z,1659986739.829 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2022-08-08T19:25:40.653Z,1659986740.653 [BPC1] Loaded 2022-08-08T19:25:40.654Z,1659986740.654 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2022-08-08T19:25:41.369Z,1659986741.369 [DataOverHttps] Loaded 2022-08-08T19:25:41.369Z,1659986741.369 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2022-08-08T19:25:41.370Z,1659986741.370 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408F14E0 2022-08-08T19:25:41.370Z,1659986741.370 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9762 2022-08-08T19:25:41.390Z,1659986741.390 [Depth_Keller] Loaded 2022-08-08T19:25:41.391Z,1659986741.391 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2022-08-08T19:25:41.395Z,1659986741.395 [DropWeight] Loaded 2022-08-08T19:25:41.396Z,1659986741.396 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2022-08-08T19:25:41.455Z,1659986741.455 [NAL9602] Loaded 2022-08-08T19:25:41.455Z,1659986741.455 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2022-08-08T19:25:41.490Z,1659986741.490 [Onboard] Loaded 2022-08-08T19:25:41.490Z,1659986741.490 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2022-08-08T19:25:41.492Z,1659986741.492 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409214E0 2022-08-08T19:25:41.493Z,1659986741.493 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 9763 2022-08-08T19:25:41.506Z,1659986741.506 [Power24vConverter] Loaded 2022-08-08T19:25:41.506Z,1659986741.506 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2022-08-08T19:25:41.519Z,1659986741.519 [Radio_Surface] Loaded 2022-08-08T19:25:41.519Z,1659986741.519 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2022-08-08T19:25:41.520Z,1659986741.520 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409514E0 2022-08-08T19:25:41.521Z,1659986741.521 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9764 2022-08-08T19:25:41.522Z,1659986741.522 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2022-08-08T19:25:41.522Z,1659986741.522 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2022-08-08T19:25:41.594Z,1659986741.594 [DepthRateCalculator] Loaded 2022-08-08T19:25:41.594Z,1659986741.594 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2022-08-08T19:25:41.599Z,1659986741.599 [PitchRateCalculator] Loaded 2022-08-08T19:25:41.599Z,1659986741.599 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2022-08-08T19:25:41.608Z,1659986741.608 [SpeedCalculator] Loaded 2022-08-08T19:25:41.608Z,1659986741.608 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2022-08-08T19:25:41.613Z,1659986741.613 [YawRateCalculator] Loaded 2022-08-08T19:25:41.613Z,1659986741.613 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2022-08-08T19:25:41.631Z,1659986741.631 [ElevatorOffsetCalculator] Loaded 2022-08-08T19:25:41.631Z,1659986741.631 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2022-08-08T19:25:41.632Z,1659986741.632 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2022-08-08T19:25:41.632Z,1659986741.632 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2022-08-08T19:25:41.818Z,1659986741.818 [CANONSampler] Loaded 2022-08-08T19:25:41.818Z,1659986741.818 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2022-08-08T19:25:41.878Z,1659986741.878 [CTD_Seabird] Loaded 2022-08-08T19:25:41.878Z,1659986741.878 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2022-08-08T19:25:41.879Z,1659986741.879 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A094E0 2022-08-08T19:25:41.879Z,1659986741.879 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9765 2022-08-08T19:25:41.900Z,1659986741.900 [PAR_Licor] Loaded 2022-08-08T19:25:41.901Z,1659986741.901 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2022-08-08T19:25:41.932Z,1659986741.932 [WetLabsBB2FL] Loaded 2022-08-08T19:25:41.932Z,1659986741.932 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2022-08-08T19:25:41.933Z,1659986741.933 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A394E0 2022-08-08T19:25:41.933Z,1659986741.933 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9766 2022-08-08T19:25:41.934Z,1659986741.934 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2022-08-08T19:25:41.935Z,1659986741.935 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2022-08-08T19:25:41.978Z,1659986741.978 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2022-08-08T19:25:41.979Z,1659986741.979 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2022-08-08T19:25:42.358Z,1659986742.358 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2022-08-08T19:25:42.360Z,1659986742.360 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2022-08-08T19:25:42.537Z,1659986742.537 [SBIT](DEBUG): Construct Startup Built In Test. 2022-08-08T19:25:42.547Z,1659986742.547 [SBIT] Loaded 2022-08-08T19:25:42.547Z,1659986742.547 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2022-08-08T19:25:42.550Z,1659986742.550 [IBIT](DEBUG): Construct Initiated Built In Test. 2022-08-08T19:25:42.563Z,1659986742.563 [IBIT] Loaded 2022-08-08T19:25:42.563Z,1659986742.563 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2022-08-08T19:25:42.568Z,1659986742.568 [CBIT](DEBUG): Construct Continuous Built In Test. 2022-08-08T19:25:42.674Z,1659986742.674 [CBIT] Loaded 2022-08-08T19:25:42.674Z,1659986742.674 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2022-08-08T19:25:42.674Z,1659986742.674 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2022-08-08T19:25:42.675Z,1659986742.675 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2022-08-08T19:25:42.798Z,1659986742.798 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2022-08-08T19:25:42.804Z,1659986742.804 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2022-08-08T19:25:42.807Z,1659986742.807 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2022-08-08T19:25:42.818Z,1659986742.818 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2022-08-08T19:25:42.819Z,1659986742.819 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B754E0 2022-08-08T19:25:42.819Z,1659986742.819 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9767 2022-08-08T19:25:42.823Z,1659986742.823 [Supervisor](INFO): Main Thread ID is 9671 2022-08-08T19:25:42.824Z,1659986742.824 [Supervisor](DEBUG): Running supervisor. 2022-08-08T19:25:42.824Z,1659986742.824 [CommandExec ThreadHandler](INFO): Handler Thread ID is 9768 2022-08-08T19:25:42.825Z,1659986742.825 [CommandExec](INFO): Initializing the command executive. 2022-08-08T19:25:42.826Z,1659986742.826 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9769 2022-08-08T19:25:42.829Z,1659986742.829 [controlThread ThreadHandler](INFO): Handler Thread ID is 9770 2022-08-08T19:25:42.829Z,1659986742.829 [controlThread](DEBUG): Initializing ControlThread 2022-08-08T19:25:42.830Z,1659986742.830 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2022-08-08T19:25:42.831Z,1659986742.831 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2022-08-08T19:25:42.833Z,1659986742.833 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2022-08-08T19:25:42.834Z,1659986742.834 [LoopControl](DEBUG): Initialize LoopControlComponent. 2022-08-08T19:25:42.835Z,1659986742.835 [NavChart](DEBUG): Initialize NavChart Navigation. 2022-08-08T19:25:42.836Z,1659986742.836 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2022-08-08T19:25:42.840Z,1659986742.840 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2022-08-08T19:25:42.841Z,1659986742.841 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2022-08-08T19:25:42.841Z,1659986742.841 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2022-08-08T19:25:42.841Z,1659986742.841 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2022-08-08T19:25:42.841Z,1659986742.841 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2022-08-08T19:25:42.847Z,1659986742.847 [SBIT](INFO): Initialize SBIT Component. 2022-08-08T19:25:42.847Z,1659986742.847 [SBIT](IMPORTANT): git: 2022-04-11 2022-08-08T19:25:42.847Z,1659986742.847 [SBIT](INFO): git hash: 059cb0c4bb1d2c42f72e645cf8ce57fde1aeb3d9 2022-08-08T19:25:42.848Z,1659986742.848 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2022-08-08T19:25:42.849Z,1659986742.849 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2022-08-08T19:25:42.850Z,1659986742.850 [SBIT](INFO): Beginning SBIT in 66.000000 seconds. 2022-08-08T19:25:42.851Z,1659986742.851 [logger ThreadHandler](INFO): Handler Thread ID is 9771 2022-08-08T19:25:42.867Z,1659986742.867 [IBIT](INFO): Initialize IBIT Component. 2022-08-08T19:25:42.868Z,1659986742.868 [CBIT](DEBUG): Initialize CBIT Component. 2022-08-08T19:25:42.875Z,1659986742.875 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9772 2022-08-08T19:25:42.876Z,1659986742.876 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2022-08-08T19:25:42.879Z,1659986742.879 [CBIT](DEBUG): Initialized mux pins. 2022-08-08T19:25:42.879Z,1659986742.879 [CBIT](DEBUG): Initializing the watchdog timer. 2022-08-08T19:25:42.887Z,1659986742.887 [Onboard ThreadHandler](INFO): Handler Thread ID is 9773 2022-08-08T19:25:42.902Z,1659986742.902 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2022-08-08T19:25:42.903Z,1659986742.903 [CBIT](DEBUG): Initializing heartbeat. 2022-08-08T19:25:42.905Z,1659986742.905 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9774 2022-08-08T19:25:42.927Z,1659986742.927 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9775 2022-08-08T19:25:42.928Z,1659986742.928 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2022-08-08T19:25:42.939Z,1659986742.939 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9777 2022-08-08T19:25:42.943Z,1659986742.943 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9779 2022-08-08T19:25:42.945Z,1659986742.945 [WetLabsBB2FL](INFO): Powering up 2022-08-08T19:25:42.953Z,1659986742.953 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2022-08-08T19:25:42.953Z,1659986742.953 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2022-08-08T19:25:42.953Z,1659986742.953 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2022-08-08T19:25:42.953Z,1659986742.953 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2022-08-08T19:25:42.953Z,1659986742.953 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2022-08-08T19:25:42.953Z,1659986742.953 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2022-08-08T19:25:42.954Z,1659986742.954 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2022-08-08T19:25:42.954Z,1659986742.954 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2022-08-08T19:25:42.954Z,1659986742.954 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2022-08-08T19:25:42.954Z,1659986742.954 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2022-08-08T19:25:42.955Z,1659986742.955 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2022-08-08T19:25:42.955Z,1659986742.955 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2022-08-08T19:25:42.955Z,1659986742.955 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2022-08-08T19:25:42.955Z,1659986742.955 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2022-08-08T19:25:42.955Z,1659986742.955 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2022-08-08T19:25:42.955Z,1659986742.955 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2022-08-08T19:25:42.974Z,1659986742.974 [CBIT](DEBUG): Deactivating GF circuits. 2022-08-08T19:25:42.974Z,1659986742.974 [CBIT](DEBUG): Deactivating emergency mode. 2022-08-08T19:25:43.010Z,1659986743.010 [CBIT](DEBUG): Backplane powered. 2022-08-08T19:25:43.011Z,1659986743.011 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2022-08-08T19:25:43.043Z,1659986743.043 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2022-08-08T19:25:43.055Z,1659986743.055 [MissionManager](DEBUG): 2022-08-08T19:25:43.055Z,1659986743.055 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2022-08-08T19:25:43.143Z,1659986743.143 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2022-08-08T19:25:43.144Z,1659986743.144 [Default:A.Wait](DEBUG): Construct Wait. 2022-08-08T19:25:43.147Z,1659986743.147 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2022-08-08T19:25:43.190Z,1659986743.190 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2022-08-08T19:25:43.193Z,1659986743.193 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2022-08-08T19:25:43.218Z,1659986743.218 [Default:E.Execute](DEBUG): Construct Execute. 2022-08-08T19:25:43.229Z,1659986743.229 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2022-08-08T19:25:43.233Z,1659986743.233 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2022-08-08T19:25:43.261Z,1659986743.261 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2022-08-08T19:25:43.310Z,1659986743.310 [Radio_Surface](INFO): Powering up 2022-08-08T19:25:43.387Z,1659986743.387 [Power24vConverter](INFO): Powering up. 2022-08-08T19:25:43.388Z,1659986743.388 [CANONSampler](INFO): Powering down 2022-08-08T19:25:43.500Z,1659986743.500 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2022-08-08T19:25:43.507Z,1659986743.507 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2022-08-08T19:25:43.508Z,1659986743.508 [ElevatorServo](DEBUG): Initializing EZServoServo. 2022-08-08T19:25:43.515Z,1659986743.515 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2022-08-08T19:25:43.516Z,1659986743.516 [MassServo](DEBUG): Initializing EZServoServo. 2022-08-08T19:25:43.523Z,1659986743.523 [MassServo](DEBUG): Initializing MassServo. 2022-08-08T19:25:43.523Z,1659986743.523 [RudderServo](DEBUG): Initializing EZServoServo. 2022-08-08T19:25:43.531Z,1659986743.531 [RudderServo](DEBUG): Initializing RudderServo. 2022-08-08T19:25:43.532Z,1659986743.532 [ThrusterServo](DEBUG): Initializing EZServoServo. 2022-08-08T19:25:43.539Z,1659986743.539 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2022-08-08T19:25:44.382Z,1659986744.382 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2022-08-08T19:25:44.383Z,1659986744.383 [RudderServo](FAULT): Rudder failed to initialize 2022-08-08T19:25:44.383Z,1659986744.383 [RudderServo] Communications Fault, FailCount= 1 2022-08-08T19:25:44.383Z,1659986744.383 [RudderServo](ERROR): Communications Fault 2022-08-08T19:25:44.486Z,1659986744.486 [CBIT](ERROR): Communications Fault in component: RudderServo 2022-08-08T19:25:44.651Z,1659986744.651 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2022-08-08T19:25:44.652Z,1659986744.652 [RudderServo](INFO): Powering down 2022-08-08T19:25:45.051Z,1659986745.051 [WetLabsBB2FL](INFO): Powering down 2022-08-08T19:25:45.323Z,1659986745.323 [RudderServo](DEBUG): Initializing EZServoServo. 2022-08-08T19:25:45.443Z,1659986745.443 [RudderServo](DEBUG): Initializing RudderServo. 2022-08-08T19:25:45.447Z,1659986745.447 [CBIT](INFO): Clearing failed state for component RudderServo 2022-08-08T19:25:45.447Z,1659986745.447 [RudderServo] No Fault, FailCount= 1 2022-08-08T19:25:57.737Z,1659986757.737 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004931 2022-08-08T19:26:11.564Z,1659986771.564 [NAL9602](INFO): Powering up NAL9602 2022-08-08T19:26:22.472Z,1659986782.472 [NAL9602](INFO): NAL9602 initialized 2022-08-08T19:26:49.607Z,1659986809.607 [SBIT](IMPORTANT): Beginning Startup BIT 2022-08-08T19:26:49.611Z,1659986809.611 [CBIT](IMPORTANT): Beginning ground fault scan 2022-08-08T19:27:00.540Z,1659986820.540 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2022-08-08T19:27:00.936Z,1659986820.936 [CBIT](IMPORTANT): Beginning ground fault scan 2022-08-08T19:27:11.827Z,1659986831.827 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2022-08-08T19:27:12.219Z,1659986832.219 [CBIT](IMPORTANT): Beginning ground fault scan 2022-08-08T19:27:23.131Z,1659986843.131 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.002742 CHAN A1 (24V): 0.000345 CHAN A2 (12V): -0.002229 CHAN A3 (5V): -0.001693 CHAN B0 (3.3V): 0.000379 CHAN B1 (3.15aV): -0.000261 CHAN B2 (3.15bV): -0.000092 CHAN B3 (GND): 0.000238 OPEN: -0.000150 Full Scale: +/- 1 mA 2022-08-08T19:27:43.714Z,1659986863.714 [SBIT](IMPORTANT): SBIT PASSED 2022-08-08T19:27:43.714Z,1659986863.714 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2022-08-08T19:27:43.715Z,1659986863.715 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool; 2022-08-08T19:27:43.715Z,1659986863.715 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=3 count; 2022-08-08T19:27:43.715Z,1659986863.715 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool; 2022-08-08T19:27:43.715Z,1659986863.715 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool; 2022-08-08T19:27:43.716Z,1659986863.716 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2022-08-08T19:27:43.716Z,1659986863.716 [SBIT](IMPORTANT): DropWeight.loadAtStartup=1 bool; 2022-08-08T19:27:43.716Z,1659986863.716 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=0 bool; 2022-08-08T19:27:43.716Z,1659986863.716 [SBIT](IMPORTANT): Express linearApproximation NAL9602.sigQuality 1.000000 count; 2022-08-08T19:27:43.716Z,1659986863.716 [SBIT](IMPORTANT): Express linearApproximation ThrusterServo.component_avgCurrent 10.000000 milliampere; 2022-08-08T19:27:43.716Z,1659986863.716 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=249.912 cubic_centimeter; 2022-08-08T19:27:43.716Z,1659986863.716 [SBIT](IMPORTANT): VerticalControl.massDefault=-1.16043 centimeter; 2022-08-08T19:27:44.177Z,1659986864.177 [MissionManager](IMPORTANT): Started mission Startup 2022-08-08T19:27:44.177Z,1659986864.177 [Startup] Running Loop=1 2022-08-08T19:27:44.177Z,1659986864.177 [Startup](DEBUG): Aggregate::initialize Startup 2022-08-08T19:27:44.177Z,1659986864.177 [Startup:A.GoToSurface] Running Loop=1 2022-08-08T19:27:44.177Z,1659986864.177 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-08-08T19:27:44.178Z,1659986864.178 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-08-08T19:27:44.179Z,1659986864.179 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-08-08T19:27:44.179Z,1659986864.179 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-08-08T19:27:44.179Z,1659986864.179 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-08-08T19:27:44.180Z,1659986864.180 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-08-08T19:27:44.181Z,1659986864.181 [Startup:StartupSatComms] Running Loop=1 2022-08-08T19:27:44.181Z,1659986864.181 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2022-08-08T19:27:44.181Z,1659986864.181 [Startup:StartupSatComms:A] Running Loop=1 2022-08-08T19:27:44.609Z,1659986864.609 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2022-08-08T19:28:14.332Z,1659986894.332 [CommandExec](IMPORTANT): got command show variable prop 2022-08-08T19:28:14.412Z,1659986894.412 [CommandExec](IMPORTANT): platform_propeller_rotation_rate (radian_per_second) 2022-08-08T19:28:14.440Z,1659986894.440 [CommandExec](IMPORTANT): Config/Simulator.designPropEff (none) 2022-08-08T19:28:14.448Z,1659986894.448 [CommandExec](IMPORTANT): SpeedControl.propPitch (meter_per_radian) 2022-08-08T19:28:14.513Z,1659986894.513 [CommandExec](IMPORTANT): SpeedControl.propOmegaAction (radian_per_second) 2022-08-08T19:28:14.516Z,1659986894.516 [CommandExec](IMPORTANT): ThrusterServo.platform_propeller_rotation_rate (radian_per_second) 2022-08-08T19:28:43.103Z,1659986923.103 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2022-08-08T19:28:43.103Z,1659986923.103 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-08-08T19:28:43.113Z,1659986923.113 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-08-08T19:28:43.515Z,1659986923.515 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-08-08T19:28:43.515Z,1659986923.515 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2022-08-08T19:28:44.298Z,1659986924.298 [Startup:StartupSatComms:A](INFO): Timed out from 2022-08-08T19:27:44.2Z 2022-08-08T19:28:44.298Z,1659986924.298 [Startup:StartupSatComms:A] Stopped 2022-08-08T19:28:44.298Z,1659986924.298 [Startup:StartupSatComms:B] Running Loop=1 2022-08-08T19:28:44.715Z,1659986924.715 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2022-08-08T19:28:51.727Z,1659986931.727 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20220722T190204/Courier0129.lzma 2022-08-08T19:28:52.729Z,1659986932.729 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Courier0129.lzma.bak 2022-08-08T19:28:52.729Z,1659986932.729 [DataOverHttps](INFO): SBD MOMSN=17096845 2022-08-08T19:28:57.097Z,1659986937.097 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 200 revolution_per_minute 2022-08-08T19:28:57.104Z,1659986937.104 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread. 2022-08-08T19:28:57.284Z,1659986937.284 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2022-08-08T19:29:10.302Z,1659986950.302 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20220808T192531/Courier0000.lzma 2022-08-08T19:29:11.304Z,1659986951.304 [DataOverHttps](INFO): Moved sent file to Logs/20220808T192531/Courier0000.lzma.bak 2022-08-08T19:29:11.304Z,1659986951.304 [DataOverHttps](INFO): SBD MOMSN=17096847 2022-08-08T19:29:26.997Z,1659986966.997 [DataOverHttps](INFO): Sending 362 bytes from file Logs/20220722T190204/Express0130.lzma 2022-08-08T19:29:27.997Z,1659986967.997 [DataOverHttps](INFO): Moved sent file to Logs/20220722T190204/Express0130.lzma.bak 2022-08-08T19:29:27.997Z,1659986967.997 [DataOverHttps](INFO): SBD MOMSN=17096850 2022-08-08T19:29:43.205Z,1659986983.205 [DataOverHttps](INFO): Sending 1140 bytes from file Logs/20220808T192531/Express0001.lzma 2022-08-08T19:29:44.200Z,1659986984.200 [DataOverHttps](INFO): Moved sent file to Logs/20220808T192531/Express0001.lzma.bak 2022-08-08T19:29:44.201Z,1659986984.201 [DataOverHttps](INFO): SBD MOMSN=17096855 2022-08-08T19:29:44.532Z,1659986984.532 [Startup:StartupSatComms:B](INFO): Timed out from 2022-08-08T19:28:44.3Z 2022-08-08T19:29:44.532Z,1659986984.532 [Startup:StartupSatComms:B] Stopped 2022-08-08T19:29:44.533Z,1659986984.533 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2022-08-08T19:29:44.533Z,1659986984.533 [Startup:StartupSatComms] Stopped 2022-08-08T19:29:44.533Z,1659986984.533 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2022-08-08T19:29:44.533Z,1659986984.533 [Startup](INFO): Completed Startup 2022-08-08T19:29:44.534Z,1659986984.534 [MissionManager](INFO): Startup is completed. 2022-08-08T19:29:44.534Z,1659986984.534 [MissionManager](INFO): Uninitializing Mission Startup 2022-08-08T19:29:44.534Z,1659986984.534 [Startup] Stopped 2022-08-08T19:29:44.534Z,1659986984.534 [Startup](DEBUG): Aggregate::uninitialize Startup 2022-08-08T19:29:44.534Z,1659986984.534 [Startup:A.GoToSurface] Stopped 2022-08-08T19:29:44.535Z,1659986984.535 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-08-08T19:29:44.920Z,1659986984.920 [MissionManager](IMPORTANT): Started mission Default 2022-08-08T19:29:44.920Z,1659986984.920 [Default] Running Loop=1 2022-08-08T19:29:44.920Z,1659986984.920 [Default](DEBUG): Aggregate::initialize Default 2022-08-08T19:29:44.920Z,1659986984.920 [Default:B.GoToSurface] Running Loop=1 2022-08-08T19:29:44.920Z,1659986984.920 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2022-08-08T19:29:44.921Z,1659986984.921 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2022-08-08T19:29:44.921Z,1659986984.921 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2022-08-08T19:29:44.921Z,1659986984.921 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2022-08-08T19:29:44.922Z,1659986984.922 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2022-08-08T19:29:44.922Z,1659986984.922 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2022-08-08T19:29:44.922Z,1659986984.922 [Default:A.Wait] Running Loop=1 2022-08-08T19:29:44.923Z,1659986984.923 [Default:A.Wait](DEBUG): Initialize Wait Component. 2022-08-08T19:29:46.292Z,1659986986.292 [CommandExec](IMPORTANT): got command maintain clear 2022-08-08T19:29:46.647Z,1659986986.647 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2022-08-08T19:29:58.240Z,1659986998.240 [Default:A.Wait](INFO): Done Waiting. 2022-08-08T19:29:58.240Z,1659986998.240 [Default:A.Wait] Stopped 2022-08-08T19:29:58.240Z,1659986998.240 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2022-08-08T19:29:58.645Z,1659986998.645 [Default:CheckIn] Running Loop=1 2022-08-08T19:29:58.645Z,1659986998.645 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-08-08T19:29:58.645Z,1659986998.645 [Default:CheckIn:Read_GPS] Running Loop=1 2022-08-08T19:29:59.107Z,1659986999.107 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2022-08-08T19:30:42.306Z,1659987042.306 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-08-08T19:30:57.255Z,1659987057.255 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-08-08T19:31:12.606Z,1659987072.606 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-08-08T19:31:25.948Z,1659987085.948 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-08-08T19:31:27.958Z,1659987087.958 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-08-08T19:31:42.906Z,1659987102.906 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-08-08T19:31:44.141Z,1659987104.141 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2022-08-08T19:31:44.141Z,1659987104.141 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-08-08T19:31:44.151Z,1659987104.151 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-08-08T19:31:44.566Z,1659987104.566 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-08-08T19:31:44.566Z,1659987104.566 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2022-08-08T19:31:58.258Z,1659987118.258 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-08-08T19:32:14.418Z,1659987134.418 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-08-08T19:32:30.176Z,1659987150.176 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-08-08T19:32:40.827Z,1659987160.827 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 200 revolution_per_minute 2022-08-08T19:32:40.828Z,1659987160.828 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread. 2022-08-08T19:32:41.160Z,1659987161.160 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2022-08-08T19:32:45.526Z,1659987165.526 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2022-08-08T19:33:00.481Z,1659987180.481 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2022-08-08T19:33:06.958Z,1659987186.958 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6. 2022-08-08T19:33:06.961Z,1659987186.961 [BPC1](INFO): Received data from all battery sticks. 2022-08-08T19:33:36.364Z,1659987216.364 [CommandExec](IMPORTANT): got command maintain clear 2022-08-08T19:33:36.531Z,1659987216.531 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2022-08-08T19:33:42.399Z,1659987222.399 [CommandExec](IMPORTANT): got command gfscan 2022-08-08T19:33:42.573Z,1659987222.573 [CBIT](IMPORTANT): Beginning ground fault scan 2022-08-08T19:33:48.231Z,1659987228.231 [CommandExec](IMPORTANT): got command failComponent 2022-08-08T19:33:48.231Z,1659987228.231 [CommandExec](IMPORTANT): Failed components: 2022-08-08T19:33:48.231Z,1659987228.231 [CommandExec](IMPORTANT): No failed Components. 2022-08-08T19:33:53.454Z,1659987233.454 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.003616 CHAN A1 (24V): 0.000362 CHAN A2 (12V): -0.001705 CHAN A3 (5V): -0.001530 CHAN B0 (3.3V): -0.000225 CHAN B1 (3.15aV): -0.000122 CHAN B2 (3.15bV): -0.000060 CHAN B3 (GND): 0.000536 OPEN: -0.000404 Full Scale: +/- 1 mA 2022-08-08T19:34:45.143Z,1659987285.143 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2022-08-08T19:34:45.143Z,1659987285.143 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2022-08-08T19:34:45.153Z,1659987285.153 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2022-08-08T19:34:45.279Z,1659987285.279 [CommandExec](IMPORTANT): got command quit 2022-08-08T19:34:45.561Z,1659987285.561 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2022-08-08T19:34:45.561Z,1659987285.561 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2022-08-08T19:34:46.282Z,1659987286.282 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2022-08-08T19:34:46.283Z,1659987286.283 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2022-08-08T19:34:46.283Z,1659987286.283 [CommandExec](INFO): Uninitializing the command executive. 2022-08-08T19:34:46.283Z,1659987286.283 [CommandExec](INFO): Uninitializing the command scheduler. 2022-08-08T19:34:46.283Z,1659987286.283 [CommandExec ThreadHandler](INFO): Thread cancelled. 2022-08-08T19:34:46.482Z,1659987286.482 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2022-08-08T19:34:46.482Z,1659987286.482 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2022-08-08T19:34:46.483Z,1659987286.483 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-08-08T19:34:46.483Z,1659987286.483 [NavChartDb](INFO): Join timeout helper Thread ID is 9801 2022-08-08T19:34:46.611Z,1659987286.611 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2022-08-08T19:34:46.611Z,1659987286.611 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-08-08T19:34:46.631Z,1659987286.631 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2022-08-08T19:34:46.631Z,1659987286.631 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-08-08T19:34:46.631Z,1659987286.631 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9802 2022-08-08T19:34:46.951Z,1659987286.951 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2022-08-08T19:34:46.951Z,1659987286.951 [WetLabsBB2FL](INFO): Powering down 2022-08-08T19:34:46.952Z,1659987286.952 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-08-08T19:34:46.962Z,1659987286.962 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2022-08-08T19:34:46.962Z,1659987286.962 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-08-08T19:34:46.963Z,1659987286.963 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9803 2022-08-08T19:34:47.219Z,1659987287.219 [CTD_Seabird](INFO): Powering down 2022-08-08T19:34:47.230Z,1659987287.230 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2022-08-08T19:34:47.231Z,1659987287.231 [CTD_Seabird](INFO): Powering down 2022-08-08T19:34:47.247Z,1659987287.247 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-08-08T19:34:47.251Z,1659987287.251 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2022-08-08T19:34:47.251Z,1659987287.251 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-08-08T19:34:47.251Z,1659987287.251 [Radio_Surface](INFO): Join timeout helper Thread ID is 9804 2022-08-08T19:34:47.295Z,1659987287.295 [Radio_Surface](INFO): Powering down 2022-08-08T19:34:47.296Z,1659987287.296 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2022-08-08T19:34:47.296Z,1659987287.296 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-08-08T19:34:47.314Z,1659987287.314 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2022-08-08T19:34:47.314Z,1659987287.314 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-08-08T19:34:47.315Z,1659987287.315 [Onboard](INFO): Join timeout helper Thread ID is 9805 2022-08-08T19:34:47.331Z,1659987287.331 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2022-08-08T19:34:48.330Z,1659987288.330 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2022-08-08T19:34:48.331Z,1659987288.331 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-08-08T19:34:48.343Z,1659987288.343 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2022-08-08T19:34:48.343Z,1659987288.343 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-08-08T19:34:48.344Z,1659987288.344 [DataOverHttps](INFO): Join timeout helper Thread ID is 9806 2022-08-08T19:34:49.339Z,1659987289.339 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2022-08-08T19:34:49.339Z,1659987289.339 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-08-08T19:34:49.359Z,1659987289.359 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2022-08-08T19:34:49.359Z,1659987289.359 [logger ThreadHandler](INFO): Thread cancelled. 2022-08-08T19:34:49.360Z,1659987289.360 [logger](INFO): Join timeout helper Thread ID is 9807 2022-08-08T19:34:49.394Z,1659987289.394 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2022-08-08T19:34:49.395Z,1659987289.395 [logger ThreadHandler](INFO): Thread cancelled. 2022-08-08T19:34:49.399Z,1659987289.399 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2022-08-08T19:34:49.399Z,1659987289.399 [CommandLine ThreadHandler](INFO): Thread cancelled. 2022-08-08T19:34:49.400Z,1659987289.400 [CommandLine](INFO): Join timeout helper Thread ID is 9808 2022-08-08T19:34:49.462Z,1659987289.462 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2022-08-08T19:34:49.463Z,1659987289.463 [CommandLine ThreadHandler](INFO): Thread cancelled. 2022-08-08T19:34:49.479Z,1659987289.479 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2022-08-08T19:34:49.479Z,1659987289.479 [CommandExec ThreadHandler](INFO): Thread cancelled. 2022-08-08T19:34:49.480Z,1659987289.480 [CommandExec](INFO): Join timeout helper Thread ID is 9809 2022-08-08T19:34:49.481Z,1659987289.481 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2022-08-08T19:34:49.481Z,1659987289.481 [controlThread ThreadHandler](INFO): Thread cancelled. 2022-08-08T19:34:49.481Z,1659987289.481 [controlThread](INFO): Join timeout helper Thread ID is 9810 2022-08-08T19:34:49.566Z,1659987289.566 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2022-08-08T19:34:49.567Z,1659987289.567 [controlThread](DEBUG): Uninitializing ControlThread 2022-08-08T19:34:49.567Z,1659987289.567 [AHRS_M2](INFO): Powering down 2022-08-08T19:34:49.639Z,1659987289.639 [NAL9602](INFO): Powering down 2022-08-08T19:34:49.642Z,1659987289.642 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2022-08-08T19:34:49.643Z,1659987289.643 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2022-08-08T19:34:49.643Z,1659987289.643 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2022-08-08T19:34:49.644Z,1659987289.644 [MissionManager](INFO): Uninitializing Mission Default 2022-08-08T19:34:49.644Z,1659987289.644 [Default] Stopped 2022-08-08T19:34:49.644Z,1659987289.644 [Default](DEBUG): Aggregate::uninitialize Default 2022-08-08T19:34:49.644Z,1659987289.644 [Default:B.GoToSurface] Stopped 2022-08-08T19:34:49.644Z,1659987289.644 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-08-08T19:34:49.644Z,1659987289.644 [Default:CheckIn] Stopped 2022-08-08T19:34:49.644Z,1659987289.644 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-08-08T19:34:49.644Z,1659987289.644 [Default:CheckIn:Read_GPS] Stopped 2022-08-08T19:34:49.647Z,1659987289.647 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2022-08-08T19:34:49.648Z,1659987289.648 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2022-08-08T19:34:49.648Z,1659987289.648 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2022-08-08T19:34:49.648Z,1659987289.648 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2022-08-08T19:34:49.648Z,1659987289.648 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2022-08-08T19:34:49.649Z,1659987289.649 [BuoyancyServo](INFO): Powering down 2022-08-08T19:34:49.663Z,1659987289.663 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2022-08-08T19:34:49.663Z,1659987289.663 [ElevatorServo](INFO): Powering down 2022-08-08T19:34:49.664Z,1659987289.664 [MassServo](DEBUG): Uninitialize Mass Servo. 2022-08-08T19:34:49.664Z,1659987289.664 [MassServo](INFO): Powering down 2022-08-08T19:34:49.665Z,1659987289.665 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2022-08-08T19:34:49.665Z,1659987289.665 [RudderServo](INFO): Powering down 2022-08-08T19:34:49.665Z,1659987289.665 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2022-08-08T19:34:49.666Z,1659987289.666 [ThrusterServo](INFO): Powering down 2022-08-08T19:34:49.667Z,1659987289.667 [SBIT](DEBUG): Uninitialize SBIT Component. 2022-08-08T19:34:49.667Z,1659987289.667 [IBIT](DEBUG): Uninitialize IBIT Component. 2022-08-08T19:34:49.667Z,1659987289.667 [CBIT](DEBUG): Uninitialize CBIT Component. 2022-08-08T19:34:49.667Z,1659987289.667 [CBIT](DEBUG): Powering off loads. 2022-08-08T19:34:49.678Z,1659987289.678 [CBIT](DEBUG): Disabling WDT. 2022-08-08T19:34:49.690Z,1659987289.690 [CBIT](DEBUG): Opening all GF detection circuits. 2022-08-08T19:34:49.691Z,1659987289.691 [controlThread ThreadHandler](INFO): Thread cancelled. 2022-08-08T19:34:49.735Z,1659987289.735 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-08-08T19:34:49.738Z,1659987289.738 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-08-08T19:34:49.743Z,1659987289.743 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-08-08T19:34:49.815Z,1659987289.815 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-08-08T19:34:49.818Z,1659987289.818 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-08-08T19:34:49.858Z,1659987289.858 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-08-08T19:34:49.921Z,1659987289.921 [logger ThreadHandler](INFO): Thread cancelled.