2023-02-06T19:50:16.815Z,1675713016.815 [Supervisor](DEBUG): Initializing supervisor. 2023-02-06T19:50:16.822Z,1675713016.822 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-02-06T19:50:16.823Z,1675713016.823 [SyncHandler](INFO): Protected caller Thread ID is 8887 2023-02-06T19:50:16.823Z,1675713016.823 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-02-06T19:50:16.824Z,1675713016.824 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-02-06T19:50:16.825Z,1675713016.825 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8888 2023-02-06T19:50:16.829Z,1675713016.829 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-02-06T19:50:16.846Z,1675713016.846 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-02-06T19:50:16.847Z,1675713016.847 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-02-06T19:50:16.847Z,1675713016.847 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 8889 2023-02-06T19:50:16.851Z,1675713016.851 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-02-06T19:50:16.852Z,1675713016.852 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-02-06T19:50:16.853Z,1675713016.853 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8890 2023-02-06T19:50:16.855Z,1675713016.855 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-02-06T19:50:16.856Z,1675713016.856 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-02-06T19:50:16.856Z,1675713016.856 [logger ThreadHandler](INFO): Protected caller Thread ID is 8891 2023-02-06T19:50:16.860Z,1675713016.860 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-02-06T19:50:16.860Z,1675713016.860 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-02-06T19:50:16.862Z,1675713016.862 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-02-06T19:50:17.089Z,1675713017.089 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-02-06T19:50:17.090Z,1675713017.090 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-02-06T19:50:17.192Z,1675713017.192 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-02-06T19:50:17.193Z,1675713017.193 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-02-06T19:50:17.285Z,1675713017.285 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-02-06T19:50:17.286Z,1675713017.286 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-02-06T19:50:17.719Z,1675713017.719 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-02-06T19:50:17.720Z,1675713017.720 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-02-06T19:50:18.050Z,1675713018.050 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-02-06T19:50:18.051Z,1675713018.051 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-02-06T19:50:18.368Z,1675713018.368 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-02-06T19:50:18.369Z,1675713018.369 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-02-06T19:50:18.548Z,1675713018.548 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-02-06T19:50:18.549Z,1675713018.549 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-02-06T19:50:18.625Z,1675713018.625 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-02-06T19:50:18.703Z,1675713018.703 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-02-06T19:50:19.077Z,1675713019.077 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-02-06T19:50:19.078Z,1675713019.078 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-02-06T19:50:19.348Z,1675713019.348 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-02-06T19:50:19.349Z,1675713019.349 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-02-06T19:50:19.828Z,1675713019.828 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-02-06T19:50:19.828Z,1675713019.828 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-02-06T19:50:20.033Z,1675713020.033 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-02-06T19:50:20.034Z,1675713020.034 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-02-06T19:50:20.263Z,1675713020.263 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-02-06T19:50:20.263Z,1675713020.263 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-02-06T19:50:20.396Z,1675713020.396 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-02-06T19:50:20.396Z,1675713020.396 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-02-06T19:50:21.274Z,1675713021.274 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-02-06T19:50:21.276Z,1675713021.276 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2023-02-06T19:50:21.277Z,1675713021.277 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2023-02-06T19:50:21.360Z,1675713021.360 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2023-02-06T19:50:21.574Z,1675713021.574 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2023-02-06T19:50:21.913Z,1675713021.913 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-02-06T19:50:21.914Z,1675713021.914 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2023-02-06T19:50:22.031Z,1675713022.031 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2023-02-06T19:50:22.127Z,1675713022.127 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2023-02-06T19:50:22.216Z,1675713022.216 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2023-02-06T19:50:22.323Z,1675713022.323 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2023-02-06T19:50:22.414Z,1675713022.414 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2023-02-06T19:50:22.496Z,1675713022.496 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2023-02-06T19:50:22.591Z,1675713022.591 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2023-02-06T19:50:22.742Z,1675713022.742 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-02-06T19:50:22.753Z,1675713022.753 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-02-06T19:50:22.803Z,1675713022.803 [DepthRateCalculator] Loaded 2023-02-06T19:50:22.803Z,1675713022.803 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-02-06T19:50:22.809Z,1675713022.809 [PitchRateCalculator] Loaded 2023-02-06T19:50:22.809Z,1675713022.809 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-02-06T19:50:22.819Z,1675713022.819 [SpeedCalculator] Loaded 2023-02-06T19:50:22.819Z,1675713022.819 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-02-06T19:50:22.824Z,1675713022.824 [YawRateCalculator] Loaded 2023-02-06T19:50:22.824Z,1675713022.824 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-02-06T19:50:22.845Z,1675713022.845 [ElevatorOffsetCalculator] Loaded 2023-02-06T19:50:22.845Z,1675713022.845 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-02-06T19:50:22.845Z,1675713022.845 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-02-06T19:50:22.846Z,1675713022.846 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-02-06T19:50:22.872Z,1675713022.872 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-02-06T19:50:22.873Z,1675713022.873 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-02-06T19:50:22.883Z,1675713022.883 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-02-06T19:50:22.883Z,1675713022.883 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-02-06T19:50:23.427Z,1675713023.427 [AHRS_M2] Loaded 2023-02-06T19:50:23.427Z,1675713023.427 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-02-06T19:50:23.693Z,1675713023.693 [BackseatComponent] Loaded 2023-02-06T19:50:23.694Z,1675713023.694 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-02-06T19:50:23.725Z,1675713023.725 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4083F4E0 2023-02-06T19:50:23.726Z,1675713023.726 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 8973 2023-02-06T19:50:23.728Z,1675713023.728 [LcmUniversalReporter] Loaded 2023-02-06T19:50:23.729Z,1675713023.729 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-02-06T19:50:24.520Z,1675713024.520 [BPC1] Loaded 2023-02-06T19:50:24.520Z,1675713024.520 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-02-06T19:50:24.622Z,1675713024.622 [DataOverHttps] Loaded 2023-02-06T19:50:24.622Z,1675713024.622 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-02-06T19:50:24.623Z,1675713024.623 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4086F4E0 2023-02-06T19:50:24.623Z,1675713024.623 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8974 2023-02-06T19:50:24.643Z,1675713024.643 [Depth_Keller] Loaded 2023-02-06T19:50:24.643Z,1675713024.643 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-02-06T19:50:24.648Z,1675713024.648 [DropWeight] Loaded 2023-02-06T19:50:24.648Z,1675713024.648 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-02-06T19:50:24.709Z,1675713024.709 [NAL9602] Loaded 2023-02-06T19:50:24.709Z,1675713024.709 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-02-06T19:50:24.739Z,1675713024.739 [Onboard] Loaded 2023-02-06T19:50:24.739Z,1675713024.739 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-02-06T19:50:24.740Z,1675713024.740 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4089F4E0 2023-02-06T19:50:24.740Z,1675713024.740 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 8975 2023-02-06T19:50:24.746Z,1675713024.746 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3 2023-02-06T19:50:24.757Z,1675713024.757 [PowerOnly] Loaded 2023-02-06T19:50:24.757Z,1675713024.757 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2023-02-06T19:50:24.768Z,1675713024.768 [Power24vConverter] Loaded 2023-02-06T19:50:24.769Z,1675713024.769 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-02-06T19:50:24.783Z,1675713024.783 [Radio_Surface] Loaded 2023-02-06T19:50:24.783Z,1675713024.783 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-02-06T19:50:24.784Z,1675713024.784 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408CF4E0 2023-02-06T19:50:24.785Z,1675713024.785 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8976 2023-02-06T19:50:24.807Z,1675713024.807 [Waterlinked] Loaded 2023-02-06T19:50:24.807Z,1675713024.807 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread. 2023-02-06T19:50:24.808Z,1675713024.808 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-02-06T19:50:24.808Z,1675713024.808 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-02-06T19:50:24.917Z,1675713024.917 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-02-06T19:50:24.917Z,1675713024.917 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-02-06T19:50:24.958Z,1675713024.958 [DeadReckonUsingSpeedCalculator] Loaded 2023-02-06T19:50:24.959Z,1675713024.959 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2023-02-06T19:50:24.972Z,1675713024.972 [NavChart] Loaded 2023-02-06T19:50:24.972Z,1675713024.972 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-02-06T19:50:24.977Z,1675713024.977 [UniversalFixResidualReporter] Loaded 2023-02-06T19:50:24.978Z,1675713024.978 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-02-06T19:50:24.978Z,1675713024.978 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-02-06T19:50:24.979Z,1675713024.979 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-02-06T19:50:25.275Z,1675713025.275 [SBIT](DEBUG): Construct Startup Built In Test. 2023-02-06T19:50:25.329Z,1675713025.329 [SBIT] Loaded 2023-02-06T19:50:25.329Z,1675713025.329 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-02-06T19:50:25.332Z,1675713025.332 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-02-06T19:50:25.344Z,1675713025.344 [IBIT] Loaded 2023-02-06T19:50:25.345Z,1675713025.345 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-02-06T19:50:25.350Z,1675713025.350 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-02-06T19:50:25.573Z,1675713025.573 [CBIT] Loaded 2023-02-06T19:50:25.573Z,1675713025.573 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-02-06T19:50:25.573Z,1675713025.573 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-02-06T19:50:25.574Z,1675713025.574 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-02-06T19:50:25.824Z,1675713025.824 [CTD_Seabird] Loaded 2023-02-06T19:50:25.824Z,1675713025.824 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-02-06T19:50:25.834Z,1675713025.834 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409D34E0 2023-02-06T19:50:25.834Z,1675713025.834 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8980 2023-02-06T19:50:25.862Z,1675713025.862 [PAR_Licor] Loaded 2023-02-06T19:50:25.862Z,1675713025.862 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-02-06T19:50:25.909Z,1675713025.909 [WetLabsBB2FL] Loaded 2023-02-06T19:50:25.909Z,1675713025.909 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-02-06T19:50:25.910Z,1675713025.910 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A034E0 2023-02-06T19:50:25.911Z,1675713025.911 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8981 2023-02-06T19:50:25.911Z,1675713025.911 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-02-06T19:50:25.912Z,1675713025.912 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-02-06T19:50:26.106Z,1675713026.106 [BuoyancyServo] Loaded 2023-02-06T19:50:26.107Z,1675713026.107 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-02-06T19:50:26.136Z,1675713026.136 [ElevatorServo] Loaded 2023-02-06T19:50:26.136Z,1675713026.136 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-02-06T19:50:26.173Z,1675713026.173 [MassServo] Loaded 2023-02-06T19:50:26.173Z,1675713026.173 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-02-06T19:50:26.202Z,1675713026.202 [RudderServo] Loaded 2023-02-06T19:50:26.202Z,1675713026.202 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-02-06T19:50:26.226Z,1675713026.226 [ThrusterHE] Loaded 2023-02-06T19:50:26.226Z,1675713026.226 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2023-02-06T19:50:26.227Z,1675713026.227 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-02-06T19:50:26.227Z,1675713026.227 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-02-06T19:50:26.302Z,1675713026.302 [VerticalControl](DEBUG): Construct VerticalControl. 2023-02-06T19:50:26.376Z,1675713026.376 [VerticalControl] Loaded 2023-02-06T19:50:26.377Z,1675713026.377 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-02-06T19:50:26.388Z,1675713026.388 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-02-06T19:50:26.441Z,1675713026.441 [HorizontalControl] Loaded 2023-02-06T19:50:26.449Z,1675713026.449 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-02-06T19:50:26.452Z,1675713026.452 [SpeedControl](DEBUG): Construct SpeedControl. 2023-02-06T19:50:26.453Z,1675713026.453 [SpeedControl] Loaded 2023-02-06T19:50:26.454Z,1675713026.454 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-02-06T19:50:26.456Z,1675713026.456 [LoopControl](DEBUG): Construct LoopControl. 2023-02-06T19:50:26.457Z,1675713026.457 [LoopControl] Loaded 2023-02-06T19:50:26.457Z,1675713026.457 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-02-06T19:50:26.458Z,1675713026.458 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-02-06T19:50:26.458Z,1675713026.458 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-02-06T19:50:26.885Z,1675713026.885 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-02-06T19:50:26.885Z,1675713026.885 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-02-06T19:50:27.226Z,1675713027.226 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-02-06T19:50:27.227Z,1675713027.227 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-02-06T19:50:27.737Z,1675713027.737 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-02-06T19:50:27.738Z,1675713027.738 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-02-06T19:50:27.843Z,1675713027.843 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-02-06T19:50:27.857Z,1675713027.857 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-02-06T19:50:27.860Z,1675713027.860 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-02-06T19:50:27.879Z,1675713027.879 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-02-06T19:50:27.881Z,1675713027.881 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BA64E0 2023-02-06T19:50:27.882Z,1675713027.882 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8982 2023-02-06T19:50:27.886Z,1675713027.886 [Supervisor](INFO): Main Thread ID is 8886 2023-02-06T19:50:27.886Z,1675713027.886 [Supervisor](DEBUG): Running supervisor. 2023-02-06T19:50:27.887Z,1675713027.887 [CommandExec ThreadHandler](INFO): Handler Thread ID is 8983 2023-02-06T19:50:27.887Z,1675713027.887 [CommandExec](INFO): Initializing the command executive. 2023-02-06T19:50:27.889Z,1675713027.889 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8984 2023-02-06T19:50:27.899Z,1675713027.899 [controlThread ThreadHandler](INFO): Handler Thread ID is 8985 2023-02-06T19:50:27.899Z,1675713027.899 [controlThread](DEBUG): Initializing ControlThread 2023-02-06T19:50:27.900Z,1675713027.900 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-02-06T19:50:27.901Z,1675713027.901 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-02-06T19:50:27.901Z,1675713027.901 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-02-06T19:50:27.901Z,1675713027.901 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-02-06T19:50:27.902Z,1675713027.902 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-02-06T19:50:27.908Z,1675713027.908 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-02-06T19:50:27.908Z,1675713027.908 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-02-06T19:50:27.909Z,1675713027.909 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-02-06T19:50:27.909Z,1675713027.909 [SBIT](INFO): Initialize SBIT Component. 2023-02-06T19:50:27.910Z,1675713027.910 [SBIT](IMPORTANT): git: 2023-01-26 2023-02-06T19:50:27.910Z,1675713027.910 [SBIT](INFO): git hash: fbcd350f88251f9a867f3fca05699dec8f096e0d 2023-02-06T19:50:27.910Z,1675713027.910 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-02-06T19:50:27.911Z,1675713027.911 [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 2023-02-06T19:50:27.912Z,1675713027.912 [SBIT](INFO): Beginning SBIT in 91.000000 seconds. 2023-02-06T19:50:27.913Z,1675713027.913 [IBIT](INFO): Initialize IBIT Component. 2023-02-06T19:50:27.922Z,1675713027.922 [CBIT](DEBUG): Initialize CBIT Component. 2023-02-06T19:50:27.923Z,1675713027.923 [logger ThreadHandler](INFO): Handler Thread ID is 8986 2023-02-06T19:50:27.934Z,1675713027.934 [CBIT](DEBUG): Initialized mux pins. 2023-02-06T19:50:27.934Z,1675713027.934 [CBIT](DEBUG): Initializing the watchdog timer. 2023-02-06T19:50:27.950Z,1675713027.950 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 8987 2023-02-06T19:50:27.957Z,1675713027.957 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-02-06T19:50:27.958Z,1675713027.958 [CBIT](DEBUG): Initializing heartbeat. 2023-02-06T19:50:27.959Z,1675713027.959 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8988 2023-02-06T19:50:27.959Z,1675713027.959 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-02-06T19:50:27.974Z,1675713027.974 [Onboard ThreadHandler](INFO): Handler Thread ID is 8989 2023-02-06T19:50:28.002Z,1675713028.002 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8990 2023-02-06T19:50:28.019Z,1675713028.019 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8991 2023-02-06T19:50:28.019Z,1675713028.019 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-02-06T19:50:28.023Z,1675713028.023 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8993 2023-02-06T19:50:28.026Z,1675713028.026 [WetLabsBB2FL](INFO): Powering up 2023-02-06T19:50:28.027Z,1675713028.027 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8995 2023-02-06T19:50:28.031Z,1675713028.031 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2023-02-06T19:50:28.031Z,1675713028.031 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2023-02-06T19:50:28.031Z,1675713028.031 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2023-02-06T19:50:28.032Z,1675713028.032 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2023-02-06T19:50:28.032Z,1675713028.032 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2023-02-06T19:50:28.032Z,1675713028.032 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2023-02-06T19:50:28.032Z,1675713028.032 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2023-02-06T19:50:28.032Z,1675713028.032 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2023-02-06T19:50:28.042Z,1675713028.042 [CBIT](DEBUG): Deactivating GF circuits. 2023-02-06T19:50:28.042Z,1675713028.042 [CBIT](DEBUG): Deactivating emergency mode. 2023-02-06T19:50:28.077Z,1675713028.077 [CBIT](DEBUG): Backplane powered. 2023-02-06T19:50:28.091Z,1675713028.091 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-02-06T19:50:28.093Z,1675713028.093 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-02-06T19:50:28.094Z,1675713028.094 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-02-06T19:50:28.094Z,1675713028.094 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-02-06T19:50:28.095Z,1675713028.095 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-02-06T19:50:28.126Z,1675713028.126 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-02-06T19:50:28.163Z,1675713028.163 [MissionManager](DEBUG): 2023-02-06T19:50:28.164Z,1675713028.164 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-02-06T19:50:28.236Z,1675713028.236 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-02-06T19:50:28.237Z,1675713028.237 [Default:A.Wait](DEBUG): Construct Wait. 2023-02-06T19:50:28.239Z,1675713028.239 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-02-06T19:50:28.283Z,1675713028.283 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-02-06T19:50:28.285Z,1675713028.285 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-02-06T19:50:28.307Z,1675713028.307 [Default:E.Execute](DEBUG): Construct Execute. 2023-02-06T19:50:28.311Z,1675713028.311 [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 2023-02-06T19:50:28.318Z,1675713028.318 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,Waterlinked,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-02-06T19:50:28.341Z,1675713028.341 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-02-06T19:50:28.410Z,1675713028.410 [Radio_Surface](INFO): Powering up 2023-02-06T19:50:28.429Z,1675713028.429 [Power24vConverter](INFO): Powering up. 2023-02-06T19:50:28.484Z,1675713028.484 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-02-06T19:50:28.486Z,1675713028.486 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-02-06T19:50:28.487Z,1675713028.487 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-02-06T19:50:28.498Z,1675713028.498 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-02-06T19:50:28.499Z,1675713028.499 [MassServo](DEBUG): Initializing EZServoServo. 2023-02-06T19:50:28.502Z,1675713028.502 [MassServo](DEBUG): Initializing MassServo. 2023-02-06T19:50:28.502Z,1675713028.502 [RudderServo](DEBUG): Initializing EZServoServo. 2023-02-06T19:50:28.506Z,1675713028.506 [RudderServo](DEBUG): Initializing RudderServo. 2023-02-06T19:50:28.507Z,1675713028.507 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-02-06T19:50:28.510Z,1675713028.510 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-02-06T19:50:28.815Z,1675713028.815 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-02-06T19:50:28.815Z,1675713028.815 [DropWeight] Hardware Fault, FailCount= 1 2023-02-06T19:50:28.815Z,1675713028.815 [DropWeight](ERROR): Hardware Fault 2023-02-06T19:50:28.845Z,1675713028.845 [CommandExec](FAULT): Scheduling is paused 2023-02-06T19:50:28.846Z,1675713028.846 [CBIT](INFO): Critical error at 20230206T195028 2023-02-06T19:50:28.846Z,1675713028.846 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2023-02-06T19:50:28.848Z,1675713028.848 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-02-06T19:50:28.849Z,1675713028.849 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-02-06T19:50:29.505Z,1675713029.505 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2023-02-06T19:50:29.506Z,1675713029.506 [RudderServo](FAULT): Rudder failed to initialize 2023-02-06T19:50:29.506Z,1675713029.506 [RudderServo] Communications Fault, FailCount= 1 2023-02-06T19:50:29.506Z,1675713029.506 [RudderServo](ERROR): Communications Fault 2023-02-06T19:50:29.507Z,1675713029.507 [CBIT](INFO): Critical error at 20230206T195028 2023-02-06T19:50:29.510Z,1675713029.510 [CBIT](ERROR): Communications Fault in component: RudderServo 2023-02-06T19:50:29.728Z,1675713029.728 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-02-06T19:50:29.728Z,1675713029.728 [RudderServo](INFO): Powering down 2023-02-06T19:50:30.411Z,1675713030.411 [RudderServo](DEBUG): Initializing EZServoServo. 2023-02-06T19:50:30.526Z,1675713030.526 [RudderServo](DEBUG): Initializing RudderServo. 2023-02-06T19:50:30.533Z,1675713030.533 [CBIT](INFO): Clearing failed state for component RudderServo 2023-02-06T19:50:30.533Z,1675713030.533 [RudderServo] No Fault, FailCount= 1 2023-02-06T19:50:30.654Z,1675713030.654 [WetLabsBB2FL](INFO): Powering down 2023-02-06T19:50:34.351Z,1675713034.351 [ThrusterHE](ERROR): Zero Speed Commanded. 2023-02-06T19:50:42.943Z,1675713042.943 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004220 2023-02-06T19:50:54.735Z,1675713054.735 [NAL9602](INFO): Powering up NAL9602 2023-02-06T19:50:58.369Z,1675713058.369 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error 2023-02-06T19:51:05.645Z,1675713065.645 [NAL9602](INFO): NAL9602 initialized 2023-02-06T19:51:10.946Z,1675713070.946 [Waterlinked](INFO): Powering down 2023-02-06T19:51:35.922Z,1675713095.922 [CommandExec](IMPORTANT): got command failComponent 2023-02-06T19:51:35.926Z,1675713095.926 [CommandExec](IMPORTANT): Failed components: 2023-02-06T19:51:35.926Z,1675713095.926 [CommandExec](IMPORTANT): DropWeight: Hardware Fault 2023-02-06T19:51:39.778Z,1675713099.778 [CommandExec](IMPORTANT): got command failComponent 2023-02-06T19:51:39.778Z,1675713099.778 [CommandExec](IMPORTANT): Failed components: 2023-02-06T19:51:39.778Z,1675713099.778 [CommandExec](IMPORTANT): DropWeight: Hardware Fault 2023-02-06T19:51:44.251Z,1675713104.251 [CommandExec](IMPORTANT): got command show stack 2023-02-06T19:51:44.251Z,1675713104.251 [CommandExec](IMPORTANT): Behavior Stack: 2023-02-06T19:51:44.251Z,1675713104.251 [MissionManager](IMPORTANT): Mission loaded, but not running. 2023-02-06T19:51:59.371Z,1675713119.371 [SBIT](IMPORTANT): Beginning Startup BIT 2023-02-06T19:51:59.375Z,1675713119.375 [CBIT](IMPORTANT): Beginning ground fault scan 2023-02-06T19:52:10.390Z,1675713130.390 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.000311 CHAN A1 (24V): 0.000466 CHAN A2 (12V): -0.005649 CHAN A3 (5V): -0.002602 CHAN B0 (3.3V): -0.001288 CHAN B1 (3.15aV): -0.001102 CHAN B2 (3.15bV): -0.001144 CHAN B3 (GND): -0.000634 OPEN: 0.004820 Full Scale: +/- 1 mA 2023-02-06T19:52:53.107Z,1675713173.107 [SBIT](IMPORTANT): SBIT PASSED 2023-02-06T19:52:53.107Z,1675713173.107 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-02-06T19:52:53.108Z,1675713173.108 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=1 bool; 2023-02-06T19:52:53.108Z,1675713173.108 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2023-02-06T19:52:53.108Z,1675713173.108 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_disk_space 100.000000 gigabyte; 2023-02-06T19:52:53.108Z,1675713173.108 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_counts 300.000000 count_per_second; 2023-02-06T19:52:53.109Z,1675713173.109 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_shape 10.000000 count_per_second; 2023-02-06T19:52:53.109Z,1675713173.109 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_slope 0.200000 count_per_millimeter; 2023-02-06T19:52:53.109Z,1675713173.109 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter; 2023-02-06T19:52:53.109Z,1675713173.109 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=236.612869 cubic_centimeter; 2023-02-06T19:52:53.114Z,1675713173.114 [SBIT](IMPORTANT): VerticalControl.massDefault=17.883769 millimeter; 2023-02-06T19:52:53.114Z,1675713173.114 [SBIT](IMPORTANT): WetLabsBB2FL.loadAtStartup=1 bool; 2023-02-06T19:52:53.549Z,1675713173.549 [MissionManager](IMPORTANT): Started mission Startup 2023-02-06T19:52:53.557Z,1675713173.557 [Startup] Running Loop=1 2023-02-06T19:52:53.558Z,1675713173.558 [Startup](DEBUG): Aggregate::initialize Startup 2023-02-06T19:52:53.558Z,1675713173.558 [Startup:A.GoToSurface] Running Loop=1 2023-02-06T19:52:53.558Z,1675713173.558 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-02-06T19:52:53.558Z,1675713173.558 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-02-06T19:52:53.559Z,1675713173.559 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-02-06T19:52:53.559Z,1675713173.559 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-02-06T19:52:53.559Z,1675713173.559 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-02-06T19:52:53.560Z,1675713173.560 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-02-06T19:52:53.561Z,1675713173.561 [Startup:StartupSatComms] Running Loop=1 2023-02-06T19:52:53.569Z,1675713173.569 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-02-06T19:52:53.569Z,1675713173.569 [Startup:StartupSatComms:A] Running Loop=1 2023-02-06T19:52:53.942Z,1675713173.942 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-02-06T19:53:28.243Z,1675713208.243 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2023-02-06T19:53:28.243Z,1675713208.243 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-02-06T19:53:28.244Z,1675713208.244 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2023-02-06T19:53:28.244Z,1675713208.244 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-02-06T19:53:28.311Z,1675713208.311 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-02-06T19:53:28.311Z,1675713208.311 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-02-06T19:53:28.692Z,1675713208.692 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-02-06T19:53:28.692Z,1675713208.692 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2023-02-06T19:53:28.692Z,1675713208.692 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-02-06T19:53:28.692Z,1675713208.692 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2023-02-06T19:53:29.054Z,1675713209.054 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-02-06T19:53:53.692Z,1675713233.692 [Startup:StartupSatComms:A](INFO): Timed out from 2023-02-06T19:52:53.6Z 2023-02-06T19:53:53.692Z,1675713233.692 [Startup:StartupSatComms:A] Stopped 2023-02-06T19:53:53.692Z,1675713233.692 [Startup:StartupSatComms:B] Running Loop=1 2023-02-06T19:53:54.108Z,1675713234.108 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-02-06T19:54:00.713Z,1675713240.713 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230127T195934/Courier0027.lzma 2023-02-06T19:54:01.715Z,1675713241.715 [DataOverHttps](INFO): Moved sent file to Logs/20230127T195934/Courier0027.lzma.bak 2023-02-06T19:54:01.715Z,1675713241.715 [DataOverHttps](INFO): SBD MOMSN=17414761 2023-02-06T19:54:18.289Z,1675713258.289 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20230206T195016/Courier0000.lzma 2023-02-06T19:54:19.291Z,1675713259.291 [DataOverHttps](INFO): Moved sent file to Logs/20230206T195016/Courier0000.lzma.bak 2023-02-06T19:54:19.291Z,1675713259.291 [DataOverHttps](INFO): SBD MOMSN=17414763 2023-02-06T19:54:35.737Z,1675713275.737 [DataOverHttps](INFO): Sending 192 bytes from file Logs/20230127T195934/Express0028.lzma 2023-02-06T19:54:36.739Z,1675713276.739 [DataOverHttps](INFO): Moved sent file to Logs/20230127T195934/Express0028.lzma.bak 2023-02-06T19:54:36.739Z,1675713276.739 [DataOverHttps](INFO): SBD MOMSN=17414768 2023-02-06T19:54:52.363Z,1675713292.363 [DataOverHttps](INFO): Sending 1074 bytes from file Logs/20230206T195016/Express0001.lzma 2023-02-06T19:54:53.363Z,1675713293.363 [DataOverHttps](INFO): Moved sent file to Logs/20230206T195016/Express0001.lzma.bak 2023-02-06T19:54:53.363Z,1675713293.363 [DataOverHttps](INFO): SBD MOMSN=17414773 2023-02-06T19:54:53.956Z,1675713293.956 [Startup:StartupSatComms:B](INFO): Timed out from 2023-02-06T19:53:53.7Z 2023-02-06T19:54:53.956Z,1675713293.956 [Startup:StartupSatComms:B] Stopped 2023-02-06T19:54:53.956Z,1675713293.956 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2023-02-06T19:54:53.956Z,1675713293.956 [Startup:StartupSatComms] Stopped 2023-02-06T19:54:53.956Z,1675713293.956 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-02-06T19:54:53.957Z,1675713293.957 [Startup](INFO): Completed Startup 2023-02-06T19:54:53.957Z,1675713293.957 [MissionManager](INFO): Startup is completed. 2023-02-06T19:54:53.957Z,1675713293.957 [MissionManager](INFO): Uninitializing Mission Startup 2023-02-06T19:54:53.957Z,1675713293.957 [Startup] Stopped 2023-02-06T19:54:53.958Z,1675713293.958 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-02-06T19:54:53.958Z,1675713293.958 [Startup:A.GoToSurface] Stopped 2023-02-06T19:54:53.958Z,1675713293.958 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-02-06T19:54:54.357Z,1675713294.357 [MissionManager](IMPORTANT): Started mission Default 2023-02-06T19:54:54.357Z,1675713294.357 [Default] Running Loop=1 2023-02-06T19:54:54.357Z,1675713294.357 [Default](DEBUG): Aggregate::initialize Default 2023-02-06T19:54:54.357Z,1675713294.357 [Default:B.GoToSurface] Running Loop=1 2023-02-06T19:54:54.357Z,1675713294.357 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-02-06T19:54:54.358Z,1675713294.358 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-02-06T19:54:54.358Z,1675713294.358 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-02-06T19:54:54.358Z,1675713294.358 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-02-06T19:54:54.359Z,1675713294.359 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-02-06T19:54:54.359Z,1675713294.359 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-02-06T19:54:54.360Z,1675713294.360 [Default:A.Wait] Running Loop=1 2023-02-06T19:54:54.360Z,1675713294.360 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-02-06T19:54:55.884Z,1675713295.884 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 13 degree 2023-02-06T19:54:55.888Z,1675713295.888 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.elevatorAngleAction" handled in the control thread. 2023-02-06T19:54:56.038Z,1675713296.038 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,Waterlinked,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.elevatorAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-02-06T19:55:07.688Z,1675713307.688 [Default:A.Wait](INFO): Done Waiting. 2023-02-06T19:55:07.688Z,1675713307.688 [Default:A.Wait] Stopped 2023-02-06T19:55:07.688Z,1675713307.688 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-02-06T19:55:08.080Z,1675713308.080 [Default:CheckIn] Running Loop=1 2023-02-06T19:55:08.080Z,1675713308.080 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-06T19:55:08.081Z,1675713308.081 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-06T19:55:08.485Z,1675713308.485 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-02-06T19:55:19.967Z,1675713319.967 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction -13 degree 2023-02-06T19:55:59.594Z,1675713359.594 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 0 degree 2023-02-06T19:56:09.071Z,1675713369.071 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-02-06T19:56:14.799Z,1675713374.799 [CommandExec](IMPORTANT): got command show variable elevatorOffset 2023-02-06T19:56:14.873Z,1675713374.873 [CommandExec](IMPORTANT): ElevatorOffsetCalculator.loadAtStartup (bool) 2023-02-06T19:56:14.873Z,1675713374.873 [CommandExec](IMPORTANT): ElevatorOffsetCalculator.targetConfidenceLevel (percent) 2023-02-06T19:56:14.874Z,1675713374.874 [CommandExec](IMPORTANT): ElevatorOffsetCalculator.targetErrorBound (degree) 2023-02-06T19:56:14.874Z,1675713374.874 [CommandExec](IMPORTANT): ElevatorOffsetCalculator.verbosity (count) 2023-02-06T19:56:15.012Z,1675713375.012 [CommandExec](IMPORTANT): ElevatorOffsetCalculator.enableBroadcast (bool) 2023-02-06T19:56:15.012Z,1675713375.012 [CommandExec](IMPORTANT): ElevatorOffsetCalculator.elevator_angle_average (radian) 2023-02-06T19:56:15.013Z,1675713375.013 [CommandExec](IMPORTANT): ElevatorOffsetCalculator.elevator_angle_variance (radian) 2023-02-06T19:56:15.013Z,1675713375.013 [CommandExec](IMPORTANT): ElevatorOffsetCalculator.elevator_angle_error_bound (radian) 2023-02-06T19:56:15.014Z,1675713375.014 [CommandExec](IMPORTANT): ElevatorOffsetCalculator.elevator_angle_cmd_speed_identifier (meter_per_second) 2023-02-06T19:56:15.014Z,1675713375.014 [CommandExec](IMPORTANT): ElevatorOffsetCalculator.elevator_angle_cmd_pitch_identifier (radian) 2023-02-06T19:56:15.015Z,1675713375.015 [CommandExec](IMPORTANT): ElevatorOffsetCalculator.elevator_angle_cmd_mass_position_identifier (meter) 2023-02-06T19:56:24.397Z,1675713384.397 [CommandExec](IMPORTANT): got command get 2023-02-06T19:56:24.397Z,1675713384.397 [CommandExec](FAULT): Incomplete syntax. Try: help get 2023-02-06T19:56:28.972Z,1675713388.972 [CommandExec](IMPORTANT): got command get ElevatorServo.offsetAngle 2023-02-06T19:56:28.973Z,1675713388.973 [CommandExec](IMPORTANT): ElevatorServo.offsetAngle 0.000000 arcdeg 2023-02-06T19:56:29.355Z,1675713389.355 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2023-02-06T19:56:29.355Z,1675713389.355 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-02-06T19:56:29.356Z,1675713389.356 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2023-02-06T19:56:29.356Z,1675713389.356 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-02-06T19:56:29.367Z,1675713389.367 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-02-06T19:56:29.368Z,1675713389.368 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-02-06T19:56:29.776Z,1675713389.776 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-02-06T19:56:29.776Z,1675713389.776 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2023-02-06T19:56:29.776Z,1675713389.776 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-02-06T19:56:29.777Z,1675713389.777 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2023-02-06T19:56:30.138Z,1675713390.138 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-02-06T19:57:56.550Z,1675713476.550 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-02-06T19:57:56.553Z,1675713476.553 [BPC1](INFO): Received data from all battery sticks. 2023-02-06T19:59:30.287Z,1675713570.287 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2023-02-06T19:59:30.287Z,1675713570.287 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-02-06T19:59:30.288Z,1675713570.288 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2023-02-06T19:59:30.288Z,1675713570.288 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-02-06T19:59:30.335Z,1675713570.335 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-02-06T19:59:30.335Z,1675713570.335 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-02-06T19:59:30.701Z,1675713570.701 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-02-06T19:59:30.701Z,1675713570.701 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2023-02-06T19:59:30.701Z,1675713570.701 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-02-06T19:59:30.702Z,1675713570.702 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2023-02-06T19:59:31.094Z,1675713571.094 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-02-06T20:00:08.272Z,1675713608.272 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-02-06T19:55:08.1Z 2023-02-06T20:00:08.272Z,1675713608.272 [Default:CheckIn:Read_GPS] Stopped 2023-02-06T20:00:08.272Z,1675713608.272 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-06T20:00:08.672Z,1675713608.672 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-02-06T20:00:15.017Z,1675713615.017 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20230206T195016/Courier0004.lzma 2023-02-06T20:00:16.019Z,1675713616.019 [DataOverHttps](INFO): Moved sent file to Logs/20230206T195016/Courier0004.lzma.bak 2023-02-06T20:00:16.019Z,1675713616.019 [DataOverHttps](INFO): SBD MOMSN=17414805 2023-02-06T20:00:28.093Z,1675713628.093 [CBIT](FAULT): Could not open coulomb counter log file 2023-02-06T20:00:35.818Z,1675713635.818 [DataOverHttps](INFO): Sending 595 bytes from file Logs/20230206T195016/Express0005.lzma 2023-02-06T20:00:36.819Z,1675713636.819 [DataOverHttps](INFO): Moved sent file to Logs/20230206T195016/Express0005.lzma.bak 2023-02-06T20:00:36.820Z,1675713636.820 [DataOverHttps](INFO): SBD MOMSN=17414807 2023-02-06T20:00:38.621Z,1675713638.621 [Default:CheckIn:Read_Iridium] Stopped 2023-02-06T20:00:38.621Z,1675713638.621 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-06T20:00:38.621Z,1675713638.621 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-06T20:01:07.287Z,1675713667.287 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-02-06T20:01:07.287Z,1675713667.287 [NAL9602] Data Fault, FailCount= 1 2023-02-06T20:01:07.287Z,1675713667.287 [NAL9602](ERROR): Data Fault 2023-02-06T20:01:07.305Z,1675713667.305 [CBIT](ERROR): Data Fault in component: NAL9602 2023-02-06T20:01:07.690Z,1675713667.690 [NAL9602](INFO): Powering down 2023-02-06T20:01:08.516Z,1675713668.516 [CBIT](INFO): Clearing failed state for component NAL9602 2023-02-06T20:01:08.517Z,1675713668.517 [NAL9602] No Fault, FailCount= 1 2023-02-06T20:01:11.727Z,1675713671.727 [Power24vConverter](INFO): Powering down. 2023-02-06T20:01:37.991Z,1675713697.991 [NAL9602](INFO): Powering up NAL9602 2023-02-06T20:01:48.899Z,1675713708.899 [NAL9602](INFO): NAL9602 initialized 2023-02-06T20:02:31.346Z,1675713751.346 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2023-02-06T20:02:31.346Z,1675713751.346 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-02-06T20:02:31.347Z,1675713751.347 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2023-02-06T20:02:31.348Z,1675713751.348 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-02-06T20:02:31.359Z,1675713751.359 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-02-06T20:02:31.359Z,1675713751.359 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-02-06T20:02:31.765Z,1675713751.765 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-02-06T20:02:31.765Z,1675713751.765 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2023-02-06T20:02:31.766Z,1675713751.766 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-02-06T20:02:31.766Z,1675713751.766 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2023-02-06T20:02:32.159Z,1675713752.159 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-02-06T20:03:20.819Z,1675713800.819 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 13 degree 2023-02-06T20:04:13.080Z,1675713853.080 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 15 degree 2023-02-06T20:04:44.216Z,1675713884.216 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 18 degree 2023-02-06T20:04:46.714Z,1675713886.714 [ElevatorServo](FAULT): Overload Error 2023-02-06T20:04:46.714Z,1675713886.714 [ElevatorServo] Hardware Fault, FailCount= 1 2023-02-06T20:04:46.714Z,1675713886.714 [ElevatorServo](ERROR): Hardware Fault 2023-02-06T20:04:46.756Z,1675713886.756 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2023-02-06T20:04:47.087Z,1675713887.087 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-02-06T20:04:47.087Z,1675713887.087 [ElevatorServo](INFO): Powering down 2023-02-06T20:04:47.885Z,1675713887.885 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-02-06T20:04:47.998Z,1675713887.998 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-02-06T20:04:48.003Z,1675713888.003 [CBIT](INFO): Clearing failed state for component ElevatorServo 2023-02-06T20:04:48.003Z,1675713888.003 [ElevatorServo] No Fault, FailCount= 1 2023-02-06T20:04:57.209Z,1675713897.209 [ElevatorServo](FAULT): Overload Error 2023-02-06T20:04:57.209Z,1675713897.209 [ElevatorServo] Hardware Fault, FailCount= 2 2023-02-06T20:04:57.209Z,1675713897.209 [ElevatorServo](ERROR): Hardware Fault 2023-02-06T20:04:57.252Z,1675713897.252 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2023-02-06T20:04:57.600Z,1675713897.600 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-02-06T20:04:57.601Z,1675713897.601 [ElevatorServo](INFO): Powering down 2023-02-06T20:04:58.403Z,1675713898.403 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-02-06T20:04:58.518Z,1675713898.518 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-02-06T20:04:58.525Z,1675713898.525 [CBIT](INFO): Clearing failed state for component ElevatorServo 2023-02-06T20:04:58.525Z,1675713898.525 [ElevatorServo] No Fault, FailCount= 2 2023-02-06T20:05:07.729Z,1675713907.729 [ElevatorServo](FAULT): Overload Error 2023-02-06T20:05:07.729Z,1675713907.729 [ElevatorServo] Hardware Fault, FailCount= 3 2023-02-06T20:05:07.730Z,1675713907.730 [ElevatorServo](ERROR): Hardware Fault 2023-02-06T20:05:07.770Z,1675713907.770 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2023-02-06T20:05:07.771Z,1675713907.771 [CBIT](CRITICAL): Hardware Fault in component: ElevatorServo 2023-02-06T20:05:08.100Z,1675713908.100 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-02-06T20:05:08.100Z,1675713908.100 [ElevatorServo](INFO): Powering down 2023-02-06T20:05:08.103Z,1675713908.103 [CBIT](INFO): Critical error at 20230206T200507 2023-02-06T20:05:08.892Z,1675713908.892 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-02-06T20:05:09.011Z,1675713909.011 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-02-06T20:05:22.983Z,1675713922.983 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction -15 degree 2023-02-06T20:05:29.126Z,1675713929.126 [CBIT](INFO): Clearing failed state for component DropWeight 2023-02-06T20:05:29.126Z,1675713929.126 [DropWeight] No Fault, FailCount= 1 2023-02-06T20:05:32.347Z,1675713932.347 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2023-02-06T20:05:32.347Z,1675713932.347 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-02-06T20:05:32.349Z,1675713932.349 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2023-02-06T20:05:32.349Z,1675713932.349 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-02-06T20:05:32.360Z,1675713932.360 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-02-06T20:05:32.360Z,1675713932.360 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-02-06T20:05:32.733Z,1675713932.733 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-02-06T20:05:32.734Z,1675713932.734 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2023-02-06T20:05:32.734Z,1675713932.734 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-02-06T20:05:32.734Z,1675713932.734 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2023-02-06T20:05:33.134Z,1675713933.134 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-02-06T20:05:39.217Z,1675713939.217 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-06T20:05:39.217Z,1675713939.217 [Default:CheckIn:C.Wait] Stopped 2023-02-06T20:05:39.217Z,1675713939.217 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-06T20:05:39.217Z,1675713939.217 [Default:CheckIn:D] Running Loop=1 2023-02-06T20:05:39.604Z,1675713939.604 [Default:CheckIn:D] Stopped 2023-02-06T20:05:39.604Z,1675713939.604 [Default:CheckIn:E] Running Loop=1 2023-02-06T20:05:40.009Z,1675713940.009 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.754120 min 2023-02-06T20:05:40.009Z,1675713940.009 [Default:CheckIn:E] Stopped 2023-02-06T20:05:40.009Z,1675713940.009 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-06T20:05:40.009Z,1675713940.009 [Default:CheckIn] Stopped 2023-02-06T20:05:40.009Z,1675713940.009 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-06T20:05:40.009Z,1675713940.009 [Default:CheckIn](INFO): Running loop #2 2023-02-06T20:05:40.009Z,1675713940.009 [Default:CheckIn] Running Loop=2 2023-02-06T20:05:40.010Z,1675713940.010 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-06T20:05:40.010Z,1675713940.010 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-06T20:05:56.490Z,1675713956.490 [CommandExec](IMPORTANT): got command failComponent 2023-02-06T20:05:56.490Z,1675713956.490 [CommandExec](IMPORTANT): Failed components: 2023-02-06T20:05:56.490Z,1675713956.490 [CommandExec](IMPORTANT): ElevatorServo: Hardware Fault 2023-02-06T20:06:37.820Z,1675713997.820 [CommandExec](IMPORTANT): got command failComponent none ElevatorServo 2023-02-06T20:06:37.820Z,1675713997.820 [ElevatorServo] No Fault, FailCount= 3 2023-02-06T20:06:37.821Z,1675713997.821 [CommandExec](IMPORTANT): ElevatorServo failureMode is No Fault 2023-02-06T20:06:58.318Z,1675714018.318 [CommandExec](IMPORTANT): got command failComponent 2023-02-06T20:06:58.318Z,1675714018.318 [CommandExec](IMPORTANT): Failed components: 2023-02-06T20:06:58.318Z,1675714018.318 [CommandExec](IMPORTANT): No failed Components. 2023-02-06T20:07:15.706Z,1675714035.706 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 15 degree 2023-02-06T20:07:33.480Z,1675714053.480 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction -15 degree 2023-02-06T20:07:58.481Z,1675714078.481 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 0 degree 2023-02-06T20:08:33.334Z,1675714113.334 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2023-02-06T20:08:33.334Z,1675714113.334 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-02-06T20:08:33.335Z,1675714113.335 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2023-02-06T20:08:33.335Z,1675714113.335 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-02-06T20:08:33.346Z,1675714113.346 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-02-06T20:08:33.347Z,1675714113.347 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-02-06T20:08:33.724Z,1675714113.724 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-02-06T20:08:33.724Z,1675714113.724 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2023-02-06T20:08:33.724Z,1675714113.724 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-02-06T20:08:33.724Z,1675714113.724 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2023-02-06T20:08:34.116Z,1675714114.116 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-02-06T20:10:28.472Z,1675714228.472 [CBIT](FAULT): Could not open coulomb counter log file 2023-02-06T20:10:40.189Z,1675714240.189 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-02-06T20:05:40.0Z 2023-02-06T20:10:40.189Z,1675714240.189 [Default:CheckIn:Read_GPS] Stopped 2023-02-06T20:10:40.189Z,1675714240.189 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-06T20:10:46.161Z,1675714246.161 [DataOverHttps](INFO): Sending 90 bytes from file Logs/20230206T195016/Courier0007.lzma 2023-02-06T20:10:47.163Z,1675714247.163 [DataOverHttps](INFO): Moved sent file to Logs/20230206T195016/Courier0007.lzma.bak 2023-02-06T20:10:47.163Z,1675714247.163 [DataOverHttps](INFO): SBD MOMSN=17414830 2023-02-06T20:11:02.522Z,1675714262.522 [DataOverHttps](INFO): Sending 491 bytes from file Logs/20230206T195016/Express0008.lzma 2023-02-06T20:11:03.523Z,1675714263.523 [DataOverHttps](INFO): Moved sent file to Logs/20230206T195016/Express0008.lzma.bak 2023-02-06T20:11:03.523Z,1675714263.523 [DataOverHttps](INFO): SBD MOMSN=17414833 2023-02-06T20:11:05.231Z,1675714265.231 [Default:CheckIn:Read_Iridium] Stopped 2023-02-06T20:11:05.231Z,1675714265.231 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-06T20:11:05.232Z,1675714265.232 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-06T20:11:34.328Z,1675714294.328 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2023-02-06T20:11:34.328Z,1675714294.328 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-02-06T20:11:34.329Z,1675714294.329 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7 2023-02-06T20:11:34.329Z,1675714294.329 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-02-06T20:11:34.340Z,1675714294.340 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-02-06T20:11:34.340Z,1675714294.340 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-02-06T20:11:34.736Z,1675714294.736 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-02-06T20:11:34.736Z,1675714294.736 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2023-02-06T20:11:34.737Z,1675714294.737 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-02-06T20:11:34.737Z,1675714294.737 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7 2023-02-06T20:11:35.121Z,1675714295.121 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-02-06T20:11:52.483Z,1675714312.483 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-02-06T20:11:52.483Z,1675714312.483 [NAL9602] Data Fault, FailCount= 2 2023-02-06T20:11:52.484Z,1675714312.484 [NAL9602](ERROR): Data Fault 2023-02-06T20:11:52.501Z,1675714312.501 [CBIT](ERROR): Data Fault in component: NAL9602 2023-02-06T20:11:52.883Z,1675714312.883 [NAL9602](INFO): Powering down 2023-02-06T20:11:53.719Z,1675714313.719 [CBIT](INFO): Clearing failed state for component NAL9602 2023-02-06T20:11:53.719Z,1675714313.719 [NAL9602] No Fault, FailCount= 2 2023-02-06T20:12:14.682Z,1675714334.682 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 15 degree 2023-02-06T20:12:23.188Z,1675714343.188 [NAL9602](INFO): Powering up NAL9602 2023-02-06T20:12:34.117Z,1675714354.117 [NAL9602](INFO): NAL9602 initialized 2023-02-06T20:12:44.176Z,1675714364.176 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction -15 degree 2023-02-06T20:13:09.929Z,1675714389.929 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 0 degree 2023-02-06T20:14:35.316Z,1675714475.316 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2023-02-06T20:14:35.316Z,1675714475.316 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-02-06T20:14:35.317Z,1675714475.317 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8 2023-02-06T20:14:35.317Z,1675714475.317 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-02-06T20:14:35.328Z,1675714475.328 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-02-06T20:14:35.328Z,1675714475.328 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-02-06T20:14:35.739Z,1675714475.739 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-02-06T20:14:35.739Z,1675714475.739 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2023-02-06T20:14:35.739Z,1675714475.739 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-02-06T20:14:35.739Z,1675714475.739 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8 2023-02-06T20:14:36.117Z,1675714476.117 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-02-06T20:16:05.817Z,1675714565.817 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-06T20:16:05.817Z,1675714565.817 [Default:CheckIn:C.Wait] Stopped 2023-02-06T20:16:05.818Z,1675714565.818 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-06T20:16:05.818Z,1675714565.818 [Default:CheckIn:D] Running Loop=1 2023-02-06T20:16:06.228Z,1675714566.228 [Default:CheckIn:D] Stopped 2023-02-06T20:16:06.228Z,1675714566.228 [Default:CheckIn:E] Running Loop=1 2023-02-06T20:16:06.623Z,1675714566.623 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.197856 min 2023-02-06T20:16:06.623Z,1675714566.623 [Default:CheckIn:E] Stopped 2023-02-06T20:16:06.623Z,1675714566.623 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-06T20:16:06.623Z,1675714566.623 [Default:CheckIn] Stopped 2023-02-06T20:16:06.624Z,1675714566.624 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-06T20:16:06.624Z,1675714566.624 [Default:CheckIn](INFO): Running loop #3 2023-02-06T20:16:06.624Z,1675714566.624 [Default:CheckIn] Running Loop=3 2023-02-06T20:16:06.624Z,1675714566.624 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-06T20:16:06.624Z,1675714566.624 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-06T20:16:36.830Z,1675714596.830 [CommandExec](IMPORTANT): got command quit 2023-02-06T20:16:37.833Z,1675714597.833 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-06T20:16:37.834Z,1675714597.834 [CommandExec](INFO): Uninitializing the command executive. 2023-02-06T20:16:37.834Z,1675714597.834 [CommandExec](INFO): Uninitializing the command scheduler. 2023-02-06T20:16:37.834Z,1675714597.834 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:37.893Z,1675714597.893 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-02-06T20:16:37.893Z,1675714597.893 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-02-06T20:16:37.894Z,1675714597.894 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:37.894Z,1675714597.894 [NavChartDb](INFO): Join timeout helper Thread ID is 9089 2023-02-06T20:16:38.278Z,1675714598.278 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-06T20:16:38.278Z,1675714598.278 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:38.285Z,1675714598.285 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-02-06T20:16:38.286Z,1675714598.286 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:38.286Z,1675714598.286 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9090 2023-02-06T20:16:38.533Z,1675714598.533 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-06T20:16:38.534Z,1675714598.534 [WetLabsBB2FL](INFO): Powering down 2023-02-06T20:16:38.534Z,1675714598.534 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:38.537Z,1675714598.537 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-02-06T20:16:38.538Z,1675714598.538 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:38.538Z,1675714598.538 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9091 2023-02-06T20:16:38.654Z,1675714598.654 [CTD_Seabird](INFO): Powering down 2023-02-06T20:16:38.665Z,1675714598.665 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-06T20:16:38.665Z,1675714598.665 [CTD_Seabird](INFO): Powering down 2023-02-06T20:16:38.677Z,1675714598.677 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:38.678Z,1675714598.678 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-02-06T20:16:38.678Z,1675714598.678 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:38.678Z,1675714598.678 [Radio_Surface](INFO): Join timeout helper Thread ID is 9092 2023-02-06T20:16:39.029Z,1675714599.029 [Radio_Surface](INFO): Powering down 2023-02-06T20:16:39.030Z,1675714599.030 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-06T20:16:39.031Z,1675714599.031 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:39.049Z,1675714599.049 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-02-06T20:16:39.049Z,1675714599.049 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:39.050Z,1675714599.050 [Onboard](INFO): Join timeout helper Thread ID is 9093 2023-02-06T20:16:39.289Z,1675714599.289 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-06T20:16:39.290Z,1675714599.290 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:39.294Z,1675714599.294 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-02-06T20:16:39.294Z,1675714599.294 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:39.295Z,1675714599.295 [DataOverHttps](INFO): Join timeout helper Thread ID is 9094 2023-02-06T20:16:39.578Z,1675714599.578 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-06T20:16:39.578Z,1675714599.578 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:39.586Z,1675714599.586 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-02-06T20:16:39.586Z,1675714599.586 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:39.587Z,1675714599.587 [BackseatComponent](INFO): Join timeout helper Thread ID is 9095 2023-02-06T20:16:39.765Z,1675714599.765 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-06T20:16:39.766Z,1675714599.766 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:39.774Z,1675714599.774 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-02-06T20:16:39.774Z,1675714599.774 [logger ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:39.775Z,1675714599.775 [logger](INFO): Join timeout helper Thread ID is 9096 2023-02-06T20:16:39.789Z,1675714599.789 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-06T20:16:39.790Z,1675714599.790 [logger ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:39.806Z,1675714599.806 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-02-06T20:16:39.806Z,1675714599.806 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:39.807Z,1675714599.807 [CommandLine](INFO): Join timeout helper Thread ID is 9097 2023-02-06T20:16:39.877Z,1675714599.877 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-06T20:16:39.878Z,1675714599.878 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:39.894Z,1675714599.894 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-02-06T20:16:39.894Z,1675714599.894 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:39.895Z,1675714599.895 [CommandExec](INFO): Join timeout helper Thread ID is 9098 2023-02-06T20:16:39.896Z,1675714599.896 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-02-06T20:16:39.896Z,1675714599.896 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:39.896Z,1675714599.896 [controlThread](INFO): Join timeout helper Thread ID is 9099 2023-02-06T20:16:40.121Z,1675714600.121 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-06T20:16:40.122Z,1675714600.122 [controlThread](DEBUG): Uninitializing ControlThread 2023-02-06T20:16:40.122Z,1675714600.122 [AHRS_M2](INFO): Powering down 2023-02-06T20:16:40.194Z,1675714600.194 [NAL9602](INFO): Powering down 2023-02-06T20:16:40.196Z,1675714600.196 [Waterlinked](INFO): Powering down 2023-02-06T20:16:40.197Z,1675714600.197 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-02-06T20:16:40.198Z,1675714600.198 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-02-06T20:16:40.199Z,1675714600.199 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-02-06T20:16:40.199Z,1675714600.199 [MissionManager](INFO): Uninitializing Mission Default 2023-02-06T20:16:40.199Z,1675714600.199 [Default] Stopped 2023-02-06T20:16:40.199Z,1675714600.199 [Default](DEBUG): Aggregate::uninitialize Default 2023-02-06T20:16:40.199Z,1675714600.199 [Default:B.GoToSurface] Stopped 2023-02-06T20:16:40.199Z,1675714600.199 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-02-06T20:16:40.199Z,1675714600.199 [Default:CheckIn] Stopped 2023-02-06T20:16:40.200Z,1675714600.200 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-06T20:16:40.200Z,1675714600.200 [Default:CheckIn:Read_GPS] Stopped 2023-02-06T20:16:40.203Z,1675714600.203 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-02-06T20:16:40.203Z,1675714600.203 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-02-06T20:16:40.203Z,1675714600.203 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-02-06T20:16:40.203Z,1675714600.203 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-02-06T20:16:40.204Z,1675714600.204 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-02-06T20:16:40.204Z,1675714600.204 [BuoyancyServo](INFO): Powering down 2023-02-06T20:16:40.217Z,1675714600.217 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-02-06T20:16:40.218Z,1675714600.218 [ElevatorServo](INFO): Powering down 2023-02-06T20:16:40.218Z,1675714600.218 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-02-06T20:16:40.218Z,1675714600.218 [MassServo](INFO): Powering down 2023-02-06T20:16:40.219Z,1675714600.219 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-02-06T20:16:40.219Z,1675714600.219 [RudderServo](INFO): Powering down 2023-02-06T20:16:40.220Z,1675714600.220 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2023-02-06T20:16:40.220Z,1675714600.220 [ThrusterHE](INFO): Powering down 2023-02-06T20:16:40.221Z,1675714600.221 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-02-06T20:16:40.222Z,1675714600.222 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-02-06T20:16:40.222Z,1675714600.222 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-02-06T20:16:40.222Z,1675714600.222 [CBIT](DEBUG): Powering off loads. 2023-02-06T20:16:40.233Z,1675714600.233 [CBIT](DEBUG): Disabling WDT. 2023-02-06T20:16:40.245Z,1675714600.245 [CBIT](DEBUG): Opening all GF detection circuits. 2023-02-06T20:16:40.246Z,1675714600.246 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:40.267Z,1675714600.267 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:40.271Z,1675714600.271 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:40.277Z,1675714600.277 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:40.319Z,1675714600.319 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:40.366Z,1675714600.366 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:40.370Z,1675714600.370 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:40.419Z,1675714600.419 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-02-06T20:16:40.463Z,1675714600.463 [logger ThreadHandler](INFO): Thread cancelled.