2022-05-11T01:05:08.389Z,1652231108.389 [Supervisor](DEBUG): Initializing supervisor.
2022-05-11T01:05:08.394Z,1652231108.394 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-05-11T01:05:08.394Z,1652231108.394 [SyncHandler](INFO): Protected caller Thread ID is 996
2022-05-11T01:05:08.395Z,1652231108.395 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-05-11T01:05:08.396Z,1652231108.396 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-05-11T01:05:08.396Z,1652231108.396 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 997
2022-05-11T01:05:08.400Z,1652231108.400 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-05-11T01:05:08.420Z,1652231108.420 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-05-11T01:05:08.420Z,1652231108.420 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-05-11T01:05:08.421Z,1652231108.421 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 998
2022-05-11T01:05:08.425Z,1652231108.425 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-05-11T01:05:08.426Z,1652231108.426 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-05-11T01:05:08.427Z,1652231108.427 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 999
2022-05-11T01:05:08.429Z,1652231108.429 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-05-11T01:05:08.430Z,1652231108.430 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-05-11T01:05:08.430Z,1652231108.430 [logger ThreadHandler](INFO): Protected caller Thread ID is 1000
2022-05-11T01:05:08.434Z,1652231108.434 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-05-11T01:05:08.434Z,1652231108.434 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-05-11T01:05:08.436Z,1652231108.436 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-05-11T01:05:08.857Z,1652231108.857 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-05-11T01:05:08.858Z,1652231108.858 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-05-11T01:05:09.806Z,1652231109.806 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-05-11T01:05:09.807Z,1652231109.807 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-05-11T01:05:09.945Z,1652231109.945 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-05-11T01:05:09.945Z,1652231109.945 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-05-11T01:05:10.726Z,1652231110.726 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-05-11T01:05:10.727Z,1652231110.727 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-05-11T01:05:10.836Z,1652231110.836 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-05-11T01:05:10.836Z,1652231110.836 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-05-11T01:05:10.992Z,1652231110.992 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-05-11T01:05:11.513Z,1652231111.513 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-05-11T01:05:11.513Z,1652231111.513 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-05-11T01:05:11.823Z,1652231111.823 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-05-11T01:05:11.824Z,1652231111.824 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-05-11T01:05:12.022Z,1652231112.022 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-05-11T01:05:12.022Z,1652231112.022 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-05-11T01:05:12.361Z,1652231112.361 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-05-11T01:05:12.362Z,1652231112.362 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-05-11T01:05:13.068Z,1652231113.068 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-05-11T01:05:13.069Z,1652231113.069 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-05-11T01:05:13.577Z,1652231113.577 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-05-11T01:05:13.578Z,1652231113.578 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-05-11T01:05:13.807Z,1652231113.807 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-05-11T01:05:14.412Z,1652231114.412 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-05-11T01:05:14.413Z,1652231114.413 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-05-11T01:05:14.995Z,1652231114.995 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-05-11T01:05:14.996Z,1652231114.996 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-05-11T01:05:15.736Z,1652231115.736 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-05-11T01:05:15.738Z,1652231115.738 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2022-05-11T01:05:15.764Z,1652231115.764 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2022-05-11T01:05:16.064Z,1652231116.064 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2022-05-11T01:05:16.437Z,1652231116.437 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2022-05-11T01:05:16.663Z,1652231116.663 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2022-05-11T01:05:16.826Z,1652231116.826 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2022-05-11T01:05:17.119Z,1652231117.119 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2022-05-11T01:05:17.566Z,1652231117.566 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2022-05-11T01:05:18.294Z,1652231118.294 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-05-11T01:05:18.295Z,1652231118.295 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2022-05-11T01:05:18.587Z,1652231118.587 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2022-05-11T01:05:19.174Z,1652231119.174 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2022-05-11T01:05:19.392Z,1652231119.392 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2022-05-11T01:05:19.647Z,1652231119.647 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2022-05-11T01:05:19.649Z,1652231119.649 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-05-11T01:05:19.906Z,1652231119.906 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-05-11T01:05:19.913Z,1652231119.913 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-05-11T01:05:19.936Z,1652231119.936 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-05-11T01:05:19.937Z,1652231119.937 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-05-11T01:05:20.057Z,1652231120.057 [DepthRateCalculator] Loaded
2022-05-11T01:05:20.057Z,1652231120.057 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-05-11T01:05:20.071Z,1652231120.071 [PitchRateCalculator] Loaded
2022-05-11T01:05:20.071Z,1652231120.071 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-05-11T01:05:20.090Z,1652231120.090 [SpeedCalculator] Loaded
2022-05-11T01:05:20.095Z,1652231120.095 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-05-11T01:05:20.105Z,1652231120.105 [YawRateCalculator] Loaded
2022-05-11T01:05:20.105Z,1652231120.105 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-05-11T01:05:20.152Z,1652231120.152 [ElevatorOffsetCalculator] Loaded
2022-05-11T01:05:20.152Z,1652231120.152 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-05-11T01:05:20.153Z,1652231120.153 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-05-11T01:05:20.153Z,1652231120.153 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-05-11T01:05:20.347Z,1652231120.347 [VerticalControl](DEBUG): Construct VerticalControl.
2022-05-11T01:05:20.483Z,1652231120.483 [VerticalControl] Loaded
2022-05-11T01:05:20.483Z,1652231120.483 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-05-11T01:05:20.486Z,1652231120.486 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-05-11T01:05:20.573Z,1652231120.573 [HorizontalControl] Loaded
2022-05-11T01:05:20.574Z,1652231120.574 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-05-11T01:05:20.580Z,1652231120.580 [SpeedControl](DEBUG): Construct SpeedControl.
2022-05-11T01:05:20.605Z,1652231120.605 [SpeedControl] Loaded
2022-05-11T01:05:20.606Z,1652231120.606 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-05-11T01:05:20.612Z,1652231120.612 [LoopControl](DEBUG): Construct LoopControl.
2022-05-11T01:05:20.613Z,1652231120.613 [LoopControl] Loaded
2022-05-11T01:05:20.613Z,1652231120.613 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-05-11T01:05:20.614Z,1652231120.614 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-05-11T01:05:20.614Z,1652231120.614 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-05-11T01:05:20.910Z,1652231120.910 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-05-11T01:05:20.915Z,1652231120.915 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-05-11T01:05:20.941Z,1652231120.941 [NavChart] Loaded
2022-05-11T01:05:20.942Z,1652231120.942 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-05-11T01:05:20.956Z,1652231120.956 [UniversalFixResidualReporter] Loaded
2022-05-11T01:05:20.956Z,1652231120.956 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-05-11T01:05:20.957Z,1652231120.957 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-05-11T01:05:20.957Z,1652231120.957 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-05-11T01:05:21.277Z,1652231121.277 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-05-11T01:05:21.278Z,1652231121.278 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-05-11T01:05:22.401Z,1652231122.401 [AHRS_M2] Loaded
2022-05-11T01:05:22.401Z,1652231122.401 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-05-11T01:05:22.440Z,1652231122.440 [BackseatComponent] Loaded
2022-05-11T01:05:22.440Z,1652231122.440 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-05-11T01:05:22.441Z,1652231122.441 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408DD4E0
2022-05-11T01:05:22.442Z,1652231122.442 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1082
2022-05-11T01:05:22.445Z,1652231122.445 [LcmUniversalReporter] Loaded
2022-05-11T01:05:22.445Z,1652231122.445 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-05-11T01:05:22.525Z,1652231122.525 [DataOverHttps] Loaded
2022-05-11T01:05:22.526Z,1652231122.526 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-05-11T01:05:22.527Z,1652231122.527 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090D4E0
2022-05-11T01:05:22.527Z,1652231122.527 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1083
2022-05-11T01:05:22.548Z,1652231122.548 [Depth_Keller] Loaded
2022-05-11T01:05:22.549Z,1652231122.549 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-05-11T01:05:22.553Z,1652231122.553 [DropWeight] Loaded
2022-05-11T01:05:22.554Z,1652231122.554 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-05-11T01:05:22.592Z,1652231122.592 [DVL_micro] Loaded
2022-05-11T01:05:22.592Z,1652231122.592 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2022-05-11T01:05:22.725Z,1652231122.725 [NAL9602] Loaded
2022-05-11T01:05:22.725Z,1652231122.725 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-05-11T01:05:22.757Z,1652231122.757 [Onboard] Loaded
2022-05-11T01:05:22.757Z,1652231122.757 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-05-11T01:05:22.758Z,1652231122.758 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093D4E0
2022-05-11T01:05:22.759Z,1652231122.759 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1084
2022-05-11T01:05:22.771Z,1652231122.771 [Power24vConverter] Loaded
2022-05-11T01:05:22.771Z,1652231122.771 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-05-11T01:05:22.784Z,1652231122.784 [Radio_Surface] Loaded
2022-05-11T01:05:22.785Z,1652231122.785 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-05-11T01:05:22.786Z,1652231122.786 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096D4E0
2022-05-11T01:05:22.786Z,1652231122.786 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1085
2022-05-11T01:05:22.888Z,1652231122.888 [DAT] Loaded
2022-05-11T01:05:22.888Z,1652231122.888 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2022-05-11T01:05:22.889Z,1652231122.889 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4099D4E0
2022-05-11T01:05:22.890Z,1652231122.890 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1086
2022-05-11T01:05:22.890Z,1652231122.890 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-05-11T01:05:22.891Z,1652231122.891 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-05-11T01:05:23.060Z,1652231123.060 [BuoyancyServo] Loaded
2022-05-11T01:05:23.061Z,1652231123.061 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-05-11T01:05:23.109Z,1652231123.109 [ElevatorServo] Loaded
2022-05-11T01:05:23.109Z,1652231123.109 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-05-11T01:05:23.131Z,1652231123.131 [MassServo] Loaded
2022-05-11T01:05:23.131Z,1652231123.131 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-05-11T01:05:23.150Z,1652231123.150 [RudderServo] Loaded
2022-05-11T01:05:23.150Z,1652231123.150 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-05-11T01:05:23.166Z,1652231123.166 [ThrusterHE] Loaded
2022-05-11T01:05:23.166Z,1652231123.166 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-05-11T01:05:23.166Z,1652231123.166 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-05-11T01:05:23.168Z,1652231123.168 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-05-11T01:05:23.241Z,1652231123.241 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-05-11T01:05:23.241Z,1652231123.241 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-05-11T01:05:23.270Z,1652231123.270 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-05-11T01:05:23.270Z,1652231123.270 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-05-11T01:05:23.671Z,1652231123.671 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-05-11T01:05:23.672Z,1652231123.672 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-05-11T01:05:24.032Z,1652231124.032 [CTD_Seabird] Loaded
2022-05-11T01:05:24.032Z,1652231124.032 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-05-11T01:05:24.033Z,1652231124.033 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B394E0
2022-05-11T01:05:24.034Z,1652231124.034 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1087
2022-05-11T01:05:24.069Z,1652231124.069 [ESPComponent] Loaded
2022-05-11T01:05:24.069Z,1652231124.069 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2022-05-11T01:05:24.090Z,1652231124.090 [PAR_Licor] Loaded
2022-05-11T01:05:24.091Z,1652231124.091 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-05-11T01:05:24.124Z,1652231124.124 [WetLabsBB2FL] Loaded
2022-05-11T01:05:24.125Z,1652231124.125 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-05-11T01:05:24.126Z,1652231124.126 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B694E0
2022-05-11T01:05:24.126Z,1652231124.126 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1088
2022-05-11T01:05:24.127Z,1652231124.127 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-05-11T01:05:24.128Z,1652231124.128 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-05-11T01:05:24.348Z,1652231124.348 [SBIT](DEBUG): Construct Startup Built In Test.
2022-05-11T01:05:24.357Z,1652231124.357 [SBIT] Loaded
2022-05-11T01:05:24.358Z,1652231124.358 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-05-11T01:05:24.360Z,1652231124.360 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-05-11T01:05:24.377Z,1652231124.377 [IBIT] Loaded
2022-05-11T01:05:24.377Z,1652231124.377 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-05-11T01:05:24.383Z,1652231124.383 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-05-11T01:05:24.693Z,1652231124.693 [CBIT] Loaded
2022-05-11T01:05:24.693Z,1652231124.693 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-05-11T01:05:24.694Z,1652231124.694 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-05-11T01:05:24.708Z,1652231124.708 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-05-11T01:05:24.711Z,1652231124.711 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-05-11T01:05:24.742Z,1652231124.742 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-05-11T01:05:24.743Z,1652231124.743 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BD74E0
2022-05-11T01:05:24.743Z,1652231124.743 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1089
2022-05-11T01:05:24.748Z,1652231124.748 [Supervisor](INFO): Main Thread ID is 995
2022-05-11T01:05:24.748Z,1652231124.748 [Supervisor](DEBUG): Running supervisor.
2022-05-11T01:05:24.748Z,1652231124.748 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1090
2022-05-11T01:05:24.749Z,1652231124.749 [CommandExec](INFO): Initializing the command executive.
2022-05-11T01:05:24.751Z,1652231124.751 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1091
2022-05-11T01:05:24.753Z,1652231124.753 [controlThread ThreadHandler](INFO): Handler Thread ID is 1092
2022-05-11T01:05:24.753Z,1652231124.753 [controlThread](DEBUG): Initializing ControlThread
2022-05-11T01:05:24.754Z,1652231124.754 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-05-11T01:05:24.754Z,1652231124.754 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-05-11T01:05:24.756Z,1652231124.756 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-05-11T01:05:24.756Z,1652231124.756 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-05-11T01:05:24.757Z,1652231124.757 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-05-11T01:05:24.757Z,1652231124.757 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-05-11T01:05:24.759Z,1652231124.759 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-05-11T01:05:24.760Z,1652231124.760 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-05-11T01:05:24.760Z,1652231124.760 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-05-11T01:05:24.761Z,1652231124.761 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-05-11T01:05:24.761Z,1652231124.761 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-05-11T01:05:24.767Z,1652231124.767 [SBIT](INFO): Initialize SBIT Component.
2022-05-11T01:05:24.768Z,1652231124.768 [SBIT](IMPORTANT): git: 2022-05-11
2022-05-11T01:05:24.768Z,1652231124.768 [SBIT](INFO): git hash: 7696a1969a630744ed6dd937befa6455a7154426
2022-05-11T01:05:24.768Z,1652231124.768 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-05-11T01:05:24.769Z,1652231124.769 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Sep 24 11:12:27 PDT 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2022-05-11T01:05:24.770Z,1652231124.770 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2022-05-11T01:05:24.771Z,1652231124.771 [IBIT](INFO): Initialize IBIT Component.
2022-05-11T01:05:24.772Z,1652231124.772 [CBIT](DEBUG): Initialize CBIT Component.
2022-05-11T01:05:24.773Z,1652231124.773 [logger ThreadHandler](INFO): Handler Thread ID is 1093
2022-05-11T01:05:24.783Z,1652231124.783 [CBIT](DEBUG): Initialized mux pins.
2022-05-11T01:05:24.783Z,1652231124.783 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2022-05-11T01:05:24.783Z,1652231124.783 [CBIT](DEBUG): Initializing the watchdog timer.
2022-05-11T01:05:24.792Z,1652231124.792 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1094
2022-05-11T01:05:24.803Z,1652231124.803 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1095
2022-05-11T01:05:24.804Z,1652231124.804 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-05-11T01:05:24.807Z,1652231124.807 [CBIT](CRITICAL): Watchdog Timer failed to initialize.
2022-05-11T01:05:24.807Z,1652231124.807 [CBIT] Hardware Fault, FailCount= 1
2022-05-11T01:05:24.807Z,1652231124.807 [CBIT](ERROR): Hardware Fault
2022-05-11T01:05:24.807Z,1652231124.807 [CBIT](DEBUG): Initializing heartbeat.
2022-05-11T01:05:24.815Z,1652231124.815 [Onboard ThreadHandler](INFO): Handler Thread ID is 1096
2022-05-11T01:05:24.845Z,1652231124.845 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1097
2022-05-11T01:05:24.863Z,1652231124.863 [DAT ThreadHandler](INFO): Handler Thread ID is 1098
2022-05-11T01:05:24.864Z,1652231124.864 [DAT](INFO): Powering up
2022-05-11T01:05:24.864Z,1652231124.864 [DAT](DEBUG): Initializing DAT.
2022-05-11T01:05:24.871Z,1652231124.871 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1099
2022-05-11T01:05:24.872Z,1652231124.872 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-05-11T01:05:24.876Z,1652231124.876 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1101
2022-05-11T01:05:24.879Z,1652231124.879 [CBIT](DEBUG): Deactivating GF circuits.
2022-05-11T01:05:24.879Z,1652231124.879 [CBIT](DEBUG): Deactivating emergency mode.
2022-05-11T01:05:24.880Z,1652231124.880 [WetLabsBB2FL](INFO): Powering up
2022-05-11T01:05:24.881Z,1652231124.881 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1103
2022-05-11T01:05:24.885Z,1652231124.885 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-05-11T01:05:24.885Z,1652231124.885 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-05-11T01:05:24.885Z,1652231124.885 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-05-11T01:05:24.885Z,1652231124.885 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-05-11T01:05:24.885Z,1652231124.885 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-05-11T01:05:24.885Z,1652231124.885 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-05-11T01:05:24.886Z,1652231124.886 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-05-11T01:05:24.886Z,1652231124.886 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-05-11T01:05:24.886Z,1652231124.886 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA69M.000
2022-05-11T01:05:24.886Z,1652231124.886 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA69M.000
2022-05-11T01:05:24.915Z,1652231124.915 [CBIT](DEBUG): Backplane powered.
2022-05-11T01:05:24.916Z,1652231124.916 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-05-11T01:05:24.929Z,1652231124.929 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-05-11T01:05:24.962Z,1652231124.962 [MissionManager](DEBUG):
2022-05-11T01:05:24.962Z,1652231124.962 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-05-11T01:05:25.069Z,1652231125.069 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-05-11T01:05:25.070Z,1652231125.070 [Default:A.Wait](DEBUG): Construct Wait.
2022-05-11T01:05:25.080Z,1652231125.080 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-05-11T01:05:25.138Z,1652231125.138 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-05-11T01:05:25.166Z,1652231125.166 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-05-11T01:05:25.211Z,1652231125.211 [Default:E.Execute](DEBUG): Construct Execute.
2022-05-11T01:05:25.239Z,1652231125.239 [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-05-11T01:05:25.247Z,1652231125.247 [Radio_Surface](INFO): Powering up
2022-05-11T01:05:25.253Z,1652231125.253 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-05-11T01:05:25.283Z,1652231125.283 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-05-11T01:05:25.333Z,1652231125.333 [Power24vConverter](INFO): Powering up.
2022-05-11T01:05:25.384Z,1652231125.384 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-05-11T01:05:25.391Z,1652231125.391 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-05-11T01:05:25.392Z,1652231125.392 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-05-11T01:05:25.399Z,1652231125.399 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-05-11T01:05:25.400Z,1652231125.400 [MassServo](DEBUG): Initializing EZServoServo.
2022-05-11T01:05:25.407Z,1652231125.407 [MassServo](DEBUG): Initializing MassServo.
2022-05-11T01:05:25.408Z,1652231125.408 [RudderServo](DEBUG): Initializing EZServoServo.
2022-05-11T01:05:25.416Z,1652231125.416 [RudderServo](DEBUG): Initializing RudderServo.
2022-05-11T01:05:25.417Z,1652231125.417 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-05-11T01:05:25.417Z,1652231125.417 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-05-11T01:05:25.418Z,1652231125.418 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-05-11T01:05:25.418Z,1652231125.418 [CBIT](DEBUG): Powering off loads.
2022-05-11T01:05:25.431Z,1652231125.431 [CBIT](DEBUG): Disabling WDT.
2022-05-11T01:05:25.446Z,1652231125.446 [CBIT](DEBUG): Opening all GF detection circuits.
2022-05-11T01:05:26.447Z,1652231126.447 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-05-11T01:05:26.447Z,1652231126.447 [RudderServo](FAULT): Rudder failed to initialize
2022-05-11T01:05:26.447Z,1652231126.447 [RudderServo] Communications Fault, FailCount= 1
2022-05-11T01:05:26.447Z,1652231126.447 [RudderServo](ERROR): Communications Fault
2022-05-11T01:05:26.835Z,1652231126.835 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2022-05-11T01:05:26.835Z,1652231126.835 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2022-05-11T01:05:26.835Z,1652231126.835 [BuoyancyServo] Communications Fault, FailCount= 1
2022-05-11T01:05:26.835Z,1652231126.835 [BuoyancyServo](ERROR): Communications Fault
2022-05-11T01:05:27.151Z,1652231127.151 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2022-05-11T01:05:27.151Z,1652231127.151 [ElevatorServo](FAULT): Elevator failed to initialize
2022-05-11T01:05:27.151Z,1652231127.151 [ElevatorServo] Communications Fault, FailCount= 1
2022-05-11T01:05:27.151Z,1652231127.151 [ElevatorServo](ERROR): Communications Fault
2022-05-11T01:05:27.154Z,1652231127.154 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-05-11T01:05:27.155Z,1652231127.155 [RudderServo](INFO): Powering down
2022-05-11T01:05:27.214Z,1652231127.214 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-05-11T01:05:27.219Z,1652231127.219 [ElevatorServo](INFO): Powering down
2022-05-11T01:05:27.223Z,1652231127.223 [RudderServo](FAULT): LCB fault: Current Limiter Activated.
2022-05-11T01:05:27.224Z,1652231127.224 [RudderServo] Hardware Fault, FailCount= 1
2022-05-11T01:05:27.224Z,1652231127.224 [RudderServo](ERROR): Hardware Fault
2022-05-11T01:05:27.684Z,1652231127.684 [RudderServo](DEBUG): Initializing EZServoServo.
2022-05-11T01:05:27.806Z,1652231127.806 [RudderServo](DEBUG): Initializing RudderServo.
2022-05-11T01:05:27.810Z,1652231127.810 [ThrusterHE](FAULT): Thruster failed to initialize
2022-05-11T01:05:27.811Z,1652231127.811 [ThrusterHE] Communications Fault, FailCount= 1
2022-05-11T01:05:27.812Z,1652231127.812 [ThrusterHE](ERROR): Communications Fault
2022-05-11T01:05:28.034Z,1652231128.034 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-05-11T01:05:28.034Z,1652231128.034 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-05-11T01:05:28.047Z,1652231128.047 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-05-11T01:05:28.047Z,1652231128.047 [ThrusterHE](INFO): Powering down
2022-05-11T01:05:28.803Z,1652231128.803 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2022-05-11T01:05:28.803Z,1652231128.803 [MassServo] Communications Fault, FailCount= 1
2022-05-11T01:05:28.803Z,1652231128.803 [MassServo](ERROR): Communications Fault
2022-05-11T01:05:28.899Z,1652231128.899 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-05-11T01:05:28.899Z,1652231128.899 [MassServo](INFO): Powering down
2022-05-11T01:05:29.307Z,1652231129.307 [AHRS_M2](ERROR): Set Trigger UART error: serial timeout
2022-05-11T01:05:29.348Z,1652231129.348 [MassServo](FAULT): LCB fault: Current Limiter Activated.
2022-05-11T01:05:29.348Z,1652231129.348 [MassServo] Hardware Fault, FailCount= 1
2022-05-11T01:05:29.348Z,1652231129.348 [MassServo](ERROR): Hardware Fault
2022-05-11T01:05:29.679Z,1652231129.679 [MassServo](DEBUG): Initializing EZServoServo.
2022-05-11T01:05:29.800Z,1652231129.800 [MassServo](DEBUG): Initializing MassServo.
2022-05-11T01:05:30.175Z,1652231130.175 [WetLabsBB2FL] Communications Fault, FailCount= 1
2022-05-11T01:05:30.175Z,1652231130.175 [WetLabsBB2FL](ERROR): Communications Fault
2022-05-11T01:05:30.631Z,1652231130.631 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2022-05-11T01:05:30.679Z,1652231130.679 [WetLabsBB2FL](INFO): Powering down
2022-05-11T01:05:30.927Z,1652231130.927 [AHRS_M2](ERROR): Subscribe Roll UART error: serial timeout
2022-05-11T01:05:31.731Z,1652231131.731 [AHRS_M2](ERROR): Subscribe Yaw UART error: serial timeout
2022-05-11T01:05:32.943Z,1652231132.943 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout
2022-05-11T01:05:34.979Z,1652231134.979 [AHRS_M2](ERROR): Subscribe MagBufferActiveIndex UART error: serial timeout
2022-05-11T01:05:37.923Z,1652231137.923 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-05-11T01:05:37.923Z,1652231137.923 [CTD_Seabird] Communications Fault, FailCount= 1
2022-05-11T01:05:37.923Z,1652231137.923 [CTD_Seabird](ERROR): Communications Fault
2022-05-11T01:05:38.345Z,1652231138.345 [CTD_Seabird](INFO): Powering down
2022-05-11T01:05:42.636Z,1652231142.636 [DVL_micro](FAULT): Failed to initialize within timeout.
2022-05-11T01:05:42.636Z,1652231142.636 [DVL_micro] Communications Fault, FailCount= 1
2022-05-11T01:05:42.636Z,1652231142.636 [DVL_micro](ERROR): Communications Fault
2022-05-11T01:05:43.135Z,1652231143.135 [DVL_micro](INFO): Powering down
2022-05-11T01:05:45.787Z,1652231145.787 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2022-05-11T01:05:45.787Z,1652231145.787 [AHRS_M2] Data Fault, FailCount= 1
2022-05-11T01:05:45.787Z,1652231145.787 [AHRS_M2](ERROR): Data Fault
2022-05-11T01:05:46.179Z,1652231146.179 [AHRS_M2](INFO): Powering down
2022-05-11T01:05:53.052Z,1652231153.052 [NAL9602](INFO): Powering up NAL9602
2022-05-11T01:06:04.416Z,1652231164.416 [SBIT](IMPORTANT): Beginning Startup BIT
2022-05-11T01:06:13.694Z,1652231173.694 [NAL9602](ERROR): NAL9602 initialization error.
2022-05-11T01:06:13.695Z,1652231173.695 [NAL9602] Communications Fault, FailCount= 1
2022-05-11T01:06:13.695Z,1652231173.695 [NAL9602](ERROR): Communications Fault
2022-05-11T01:06:14.109Z,1652231174.109 [NAL9602](INFO): Powering down
2022-05-11T01:06:17.738Z,1652231177.738 [SBIT](ERROR): Could not read elevatorAngleReader_.
2022-05-11T01:06:17.738Z,1652231177.738 [SBIT](ERROR): Could not read rudderAngleReader_.
2022-05-11T01:06:17.739Z,1652231177.739 [SBIT](ERROR): Could not read massPosReader_.
2022-05-11T01:06:24.899Z,1652231184.899 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2022-05-11T01:06:24.899Z,1652231184.899 [DAT] Communications Fault, FailCount= 1
2022-05-11T01:06:24.899Z,1652231184.899 [DAT](ERROR): Communications Fault
2022-05-11T01:06:25.303Z,1652231185.303 [DAT](INFO): Powering down
2022-05-11T01:06:25.779Z,1652231185.779 [DAT](FAULT): LCB fault: Current Limiter Activated.
2022-05-11T01:06:25.779Z,1652231185.779 [DAT] Hardware Fault, FailCount= 1
2022-05-11T01:06:25.779Z,1652231185.779 [DAT](ERROR): Hardware Fault
2022-05-11T01:06:44.419Z,1652231204.419 [SBIT](ERROR): Could not read elevatorAngleReader_.
2022-05-11T01:06:44.419Z,1652231204.419 [SBIT](ERROR): Could not read rudderAngleReader_.
2022-05-11T01:06:44.419Z,1652231204.419 [SBIT](ERROR): Could not read massPosReader_.
2022-05-11T01:06:44.419Z,1652231204.419 [SBIT](FAULT): Control surface position failure.
2022-05-11T01:06:58.135Z,1652231218.135 [SBIT](ERROR): Could not read elevatorAngleReader_.
2022-05-11T01:06:58.135Z,1652231218.135 [SBIT](ERROR): Could not read rudderAngleReader_.
2022-05-11T01:06:58.135Z,1652231218.135 [SBIT](ERROR): Could not read massPosReader_.
2022-05-11T01:06:58.136Z,1652231218.136 [SBIT](FAULT): Control surface position failure.
2022-05-11T01:06:58.554Z,1652231218.554 [SBIT](CRITICAL): SBIT FAILED
2022-05-11T01:06:58.554Z,1652231218.554 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2022-05-11T01:06:58.951Z,1652231218.951 [MissionManager](IMPORTANT): Started mission Startup
2022-05-11T01:06:58.952Z,1652231218.952 [Startup] Running Loop=1
2022-05-11T01:06:58.952Z,1652231218.952 [Startup](DEBUG): Aggregate::initialize Startup
2022-05-11T01:06:58.952Z,1652231218.952 [Startup:A.GoToSurface] Running Loop=1
2022-05-11T01:06:58.952Z,1652231218.952 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-05-11T01:06:58.953Z,1652231218.953 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-05-11T01:06:58.953Z,1652231218.953 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-05-11T01:06:58.953Z,1652231218.953 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-05-11T01:06:58.954Z,1652231218.954 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-05-11T01:06:58.954Z,1652231218.954 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-05-11T01:06:58.955Z,1652231218.955 [Startup:StartupSatComms] Running Loop=1
2022-05-11T01:06:58.955Z,16522