2023-08-18T13:34:49.219Z,1692365689.219 [Supervisor](DEBUG): Initializing supervisor. 2023-08-18T13:34:49.223Z,1692365689.223 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-08-18T13:34:49.224Z,1692365689.224 [SyncHandler](INFO): Protected caller Thread ID is 1957 2023-08-18T13:34:49.224Z,1692365689.224 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-08-18T13:34:49.225Z,1692365689.225 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-08-18T13:34:49.226Z,1692365689.226 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1958 2023-08-18T13:34:49.230Z,1692365689.230 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-08-18T13:34:49.247Z,1692365689.247 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-08-18T13:34:49.248Z,1692365689.248 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-08-18T13:34:49.249Z,1692365689.249 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1959 2023-08-18T13:34:49.253Z,1692365689.253 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-08-18T13:34:49.254Z,1692365689.254 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-08-18T13:34:49.254Z,1692365689.254 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1960 2023-08-18T13:34:49.256Z,1692365689.256 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-08-18T13:34:49.257Z,1692365689.257 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-08-18T13:34:49.257Z,1692365689.257 [logger ThreadHandler](INFO): Protected caller Thread ID is 1961 2023-08-18T13:34:49.261Z,1692365689.261 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-08-18T13:34:49.261Z,1692365689.261 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-08-18T13:34:49.263Z,1692365689.263 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-08-18T13:34:49.485Z,1692365689.485 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-08-18T13:34:49.486Z,1692365689.486 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-08-18T13:34:49.594Z,1692365689.594 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-08-18T13:34:49.595Z,1692365689.595 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-08-18T13:34:49.691Z,1692365689.691 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-08-18T13:34:49.691Z,1692365689.691 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-08-18T13:34:50.179Z,1692365690.179 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-08-18T13:34:50.179Z,1692365690.179 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-08-18T13:34:50.534Z,1692365690.534 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-08-18T13:34:50.534Z,1692365690.534 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-08-18T13:34:50.874Z,1692365690.874 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-08-18T13:34:50.874Z,1692365690.874 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-08-18T13:34:51.068Z,1692365691.068 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-08-18T13:34:51.069Z,1692365691.069 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-08-18T13:34:51.149Z,1692365691.149 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-08-18T13:34:51.230Z,1692365691.230 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-08-18T13:34:51.642Z,1692365691.642 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-08-18T13:34:51.642Z,1692365691.642 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-08-18T13:34:51.928Z,1692365691.928 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-08-18T13:34:51.929Z,1692365691.929 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-08-18T13:34:52.462Z,1692365692.462 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-08-18T13:34:52.463Z,1692365692.463 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-08-18T13:34:52.690Z,1692365692.690 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-08-18T13:34:52.690Z,1692365692.690 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-08-18T13:34:52.947Z,1692365692.947 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-08-18T13:34:52.948Z,1692365692.948 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-08-18T13:34:53.144Z,1692365693.144 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-08-18T13:34:53.145Z,1692365693.145 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-08-18T13:34:53.993Z,1692365693.993 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-08-18T13:34:53.995Z,1692365693.995 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2023-08-18T13:34:53.996Z,1692365693.996 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2023-08-18T13:34:54.119Z,1692365694.119 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2023-08-18T13:34:54.306Z,1692365694.306 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2023-08-18T13:34:54.685Z,1692365694.685 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-08-18T13:34:54.686Z,1692365694.686 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2023-08-18T13:34:54.923Z,1692365694.923 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2023-08-18T13:34:55.026Z,1692365695.026 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2023-08-18T13:34:55.122Z,1692365695.122 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2023-08-18T13:34:55.233Z,1692365695.233 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2023-08-18T13:34:55.330Z,1692365695.330 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2023-08-18T13:34:55.418Z,1692365695.418 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2023-08-18T13:34:55.521Z,1692365695.521 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2023-08-18T13:34:55.692Z,1692365695.692 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/ 2023-08-18T13:34:55.692Z,1692365695.692 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-08-18T13:34:55.711Z,1692365695.711 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-08-18T13:34:55.762Z,1692365695.762 [DepthRateCalculator] Loaded 2023-08-18T13:34:55.762Z,1692365695.762 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-08-18T13:34:55.768Z,1692365695.768 [PitchRateCalculator] Loaded 2023-08-18T13:34:55.768Z,1692365695.768 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-08-18T13:34:55.778Z,1692365695.778 [SpeedCalculator] Loaded 2023-08-18T13:34:55.779Z,1692365695.779 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-08-18T13:34:55.783Z,1692365695.783 [YawRateCalculator] Loaded 2023-08-18T13:34:55.784Z,1692365695.784 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-08-18T13:34:55.805Z,1692365695.805 [ElevatorOffsetCalculator] Loaded 2023-08-18T13:34:55.805Z,1692365695.805 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-08-18T13:34:55.806Z,1692365695.806 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-08-18T13:34:55.806Z,1692365695.806 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-08-18T13:34:55.834Z,1692365695.834 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-08-18T13:34:55.835Z,1692365695.835 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-08-18T13:34:55.845Z,1692365695.845 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-08-18T13:34:55.845Z,1692365695.845 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-08-18T13:34:56.274Z,1692365696.274 [AHRS_M2] Loaded 2023-08-18T13:34:56.275Z,1692365696.275 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-08-18T13:34:56.310Z,1692365696.310 [BackseatComponent] Loaded 2023-08-18T13:34:56.310Z,1692365696.310 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-08-18T13:34:56.311Z,1692365696.311 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4084E4E0 2023-08-18T13:34:56.311Z,1692365696.311 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 2044 2023-08-18T13:34:56.314Z,1692365696.314 [LcmUniversalReporter] Loaded 2023-08-18T13:34:56.314Z,1692365696.314 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-08-18T13:34:57.123Z,1692365697.123 [BPC1] Loaded 2023-08-18T13:34:57.123Z,1692365697.123 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-08-18T13:34:57.200Z,1692365697.200 [DataOverHttps] Loaded 2023-08-18T13:34:57.200Z,1692365697.200 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-08-18T13:34:57.201Z,1692365697.201 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087E4E0 2023-08-18T13:34:57.202Z,1692365697.202 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2045 2023-08-18T13:34:57.222Z,1692365697.222 [Depth_Keller] Loaded 2023-08-18T13:34:57.223Z,1692365697.223 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-08-18T13:34:57.227Z,1692365697.227 [DropWeight] Loaded 2023-08-18T13:34:57.228Z,1692365697.228 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-08-18T13:34:57.291Z,1692365697.291 [NAL9602] Loaded 2023-08-18T13:34:57.291Z,1692365697.291 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-08-18T13:34:57.322Z,1692365697.322 [Onboard] Loaded 2023-08-18T13:34:57.322Z,1692365697.322 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-08-18T13:34:57.323Z,1692365697.323 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408AE4E0 2023-08-18T13:34:57.323Z,1692365697.323 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2046 2023-08-18T13:34:57.329Z,1692365697.329 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3 2023-08-18T13:34:57.341Z,1692365697.341 [PowerOnly] Loaded 2023-08-18T13:34:57.341Z,1692365697.341 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2023-08-18T13:34:57.353Z,1692365697.353 [Power24vConverter] Loaded 2023-08-18T13:34:57.353Z,1692365697.353 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-08-18T13:34:57.367Z,1692365697.367 [Radio_Surface] Loaded 2023-08-18T13:34:57.368Z,1692365697.368 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-08-18T13:34:57.369Z,1692365697.369 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408DE4E0 2023-08-18T13:34:57.369Z,1692365697.369 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2047 2023-08-18T13:34:57.370Z,1692365697.370 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-08-18T13:34:57.371Z,1692365697.371 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-08-18T13:34:57.484Z,1692365697.484 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-08-18T13:34:57.484Z,1692365697.484 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-08-18T13:34:57.525Z,1692365697.525 [DeadReckonUsingSpeedCalculator] Loaded 2023-08-18T13:34:57.526Z,1692365697.526 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2023-08-18T13:34:57.539Z,1692365697.539 [NavChart] Loaded 2023-08-18T13:34:57.540Z,1692365697.540 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-08-18T13:34:57.545Z,1692365697.545 [UniversalFixResidualReporter] Loaded 2023-08-18T13:34:57.546Z,1692365697.546 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-08-18T13:34:57.546Z,1692365697.546 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-08-18T13:34:57.547Z,1692365697.547 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-08-18T13:34:57.692Z,1692365697.692 [SBIT](DEBUG): Construct Startup Built In Test. 2023-08-18T13:34:57.706Z,1692365697.706 [SBIT] Loaded 2023-08-18T13:34:57.707Z,1692365697.707 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-08-18T13:34:57.709Z,1692365697.709 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-08-18T13:34:57.722Z,1692365697.722 [IBIT] Loaded 2023-08-18T13:34:57.722Z,1692365697.722 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-08-18T13:34:57.728Z,1692365697.728 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-08-18T13:34:57.831Z,1692365697.831 [CBIT] Loaded 2023-08-18T13:34:57.831Z,1692365697.831 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-08-18T13:34:57.832Z,1692365697.832 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-08-18T13:34:57.832Z,1692365697.832 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-08-18T13:34:58.007Z,1692365698.007 [CTD_Seabird] Loaded 2023-08-18T13:34:58.008Z,1692365698.008 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-08-18T13:34:58.009Z,1692365698.009 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409E54E0 2023-08-18T13:34:58.009Z,1692365698.009 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2048 2023-08-18T13:34:58.030Z,1692365698.030 [PAR_Licor] Loaded 2023-08-18T13:34:58.030Z,1692365698.030 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-08-18T13:34:58.070Z,1692365698.070 [WetLabsBB2FL] Loaded 2023-08-18T13:34:58.071Z,1692365698.071 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-08-18T13:34:58.072Z,1692365698.072 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A154E0 2023-08-18T13:34:58.072Z,1692365698.072 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2049 2023-08-18T13:34:58.073Z,1692365698.073 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-08-18T13:34:58.074Z,1692365698.074 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-08-18T13:34:58.210Z,1692365698.210 [BuoyancyServo] Loaded 2023-08-18T13:34:58.210Z,1692365698.210 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-08-18T13:34:58.232Z,1692365698.232 [ElevatorServo] Loaded 2023-08-18T13:34:58.232Z,1692365698.232 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-08-18T13:34:58.254Z,1692365698.254 [MassServo] Loaded 2023-08-18T13:34:58.255Z,1692365698.255 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-08-18T13:34:58.275Z,1692365698.275 [RudderServo] Loaded 2023-08-18T13:34:58.275Z,1692365698.275 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-08-18T13:34:58.292Z,1692365698.292 [ThrusterHE] Loaded 2023-08-18T13:34:58.292Z,1692365698.292 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2023-08-18T13:34:58.292Z,1692365698.292 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-08-18T13:34:58.293Z,1692365698.293 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-08-18T13:34:58.345Z,1692365698.345 [VerticalControl](DEBUG): Construct VerticalControl. 2023-08-18T13:34:58.396Z,1692365698.396 [VerticalControl] Loaded 2023-08-18T13:34:58.396Z,1692365698.396 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-08-18T13:34:58.399Z,1692365698.399 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-08-18T13:34:58.436Z,1692365698.436 [HorizontalControl] Loaded 2023-08-18T13:34:58.436Z,1692365698.436 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-08-18T13:34:58.439Z,1692365698.439 [SpeedControl](DEBUG): Construct SpeedControl. 2023-08-18T13:34:58.441Z,1692365698.441 [SpeedControl] Loaded 2023-08-18T13:34:58.441Z,1692365698.441 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-08-18T13:34:58.444Z,1692365698.444 [LoopControl](DEBUG): Construct LoopControl. 2023-08-18T13:34:58.444Z,1692365698.444 [LoopControl] Loaded 2023-08-18T13:34:58.444Z,1692365698.444 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-08-18T13:34:58.445Z,1692365698.445 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-08-18T13:34:58.445Z,1692365698.445 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-08-18T13:34:58.549Z,1692365698.549 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-08-18T13:34:58.549Z,1692365698.549 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-08-18T13:34:58.702Z,1692365698.702 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-08-18T13:34:58.702Z,1692365698.702 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-08-18T13:34:59.022Z,1692365699.022 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-08-18T13:34:59.022Z,1692365699.022 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-08-18T13:34:59.303Z,1692365699.303 [StratificationFrontDetector](INFO): thresholdVTHI set to: 0.399988 degC 2023-08-18T13:34:59.303Z,1692365699.303 [StratificationFrontDetector](DEBUG): (re)initializing 2023-08-18T13:34:59.303Z,1692365699.303 [StratificationFrontDetector] Loaded 2023-08-18T13:34:59.304Z,1692365699.304 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2023-08-18T13:34:59.304Z,1692365699.304 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-08-18T13:34:59.431Z,1692365699.431 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-08-18T13:34:59.434Z,1692365699.434 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-08-18T13:34:59.445Z,1692365699.445 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-08-18T13:34:59.446Z,1692365699.446 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCA4E0 2023-08-18T13:34:59.446Z,1692365699.446 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2050 2023-08-18T13:34:59.451Z,1692365699.451 [Supervisor](INFO): Main Thread ID is 1956 2023-08-18T13:34:59.451Z,1692365699.451 [Supervisor](DEBUG): Running supervisor. 2023-08-18T13:34:59.451Z,1692365699.451 [CommandExec ThreadHandler](INFO): Handler Thread ID is 2051 2023-08-18T13:34:59.452Z,1692365699.452 [CommandExec](INFO): Initializing the command executive. 2023-08-18T13:34:59.454Z,1692365699.454 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2052 2023-08-18T13:34:59.456Z,1692365699.456 [controlThread ThreadHandler](INFO): Handler Thread ID is 2053 2023-08-18T13:34:59.456Z,1692365699.456 [controlThread](DEBUG): Initializing ControlThread 2023-08-18T13:34:59.457Z,1692365699.457 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-08-18T13:34:59.457Z,1692365699.457 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-08-18T13:34:59.458Z,1692365699.458 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-08-18T13:34:59.458Z,1692365699.458 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-08-18T13:34:59.458Z,1692365699.458 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-08-18T13:34:59.467Z,1692365699.467 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-08-18T13:34:59.467Z,1692365699.467 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-08-18T13:34:59.467Z,1692365699.467 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-08-18T13:34:59.468Z,1692365699.468 [SBIT](INFO): Initialize SBIT Component. 2023-08-18T13:34:59.468Z,1692365699.468 [SBIT](IMPORTANT): git: 2023-08-14 2023-08-18T13:34:59.469Z,1692365699.469 [SBIT](INFO): git hash: bdd4e91c226466d9783395f134d3c36bc976a95b 2023-08-18T13:34:59.469Z,1692365699.469 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-08-18T13:34:59.470Z,1692365699.470 [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-08-18T13:34:59.471Z,1692365699.471 [SBIT](INFO): Beginning SBIT in 59.000000 seconds. 2023-08-18T13:34:59.472Z,1692365699.472 [IBIT](INFO): Initialize IBIT Component. 2023-08-18T13:34:59.473Z,1692365699.473 [CBIT](DEBUG): Initialize CBIT Component. 2023-08-18T13:34:59.474Z,1692365699.474 [logger ThreadHandler](INFO): Handler Thread ID is 2054 2023-08-18T13:34:59.484Z,1692365699.484 [CBIT](DEBUG): Initialized mux pins. 2023-08-18T13:34:59.484Z,1692365699.484 [CBIT](DEBUG): Initializing the watchdog timer. 2023-08-18T13:34:59.493Z,1692365699.493 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 2055 2023-08-18T13:34:59.505Z,1692365699.505 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2056 2023-08-18T13:34:59.506Z,1692365699.506 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-08-18T13:34:59.508Z,1692365699.508 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-08-18T13:34:59.509Z,1692365699.509 [CBIT](DEBUG): Initializing heartbeat. 2023-08-18T13:34:59.517Z,1692365699.517 [Onboard ThreadHandler](INFO): Handler Thread ID is 2057 2023-08-18T13:34:59.534Z,1692365699.534 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2058 2023-08-18T13:34:59.553Z,1692365699.553 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2059 2023-08-18T13:34:59.553Z,1692365699.553 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-08-18T13:34:59.558Z,1692365699.558 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2061 2023-08-18T13:34:59.561Z,1692365699.561 [WetLabsBB2FL](INFO): Powering up 2023-08-18T13:34:59.562Z,1692365699.562 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2063 2023-08-18T13:34:59.564Z,1692365699.564 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2023-08-18T13:34:59.564Z,1692365699.564 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2023-08-18T13:34:59.565Z,1692365699.565 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2023-08-18T13:34:59.565Z,1692365699.565 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2023-08-18T13:34:59.565Z,1692365699.565 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2023-08-18T13:34:59.565Z,1692365699.565 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2023-08-18T13:34:59.565Z,1692365699.565 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2023-08-18T13:34:59.566Z,1692365699.566 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2023-08-18T13:34:59.580Z,1692365699.580 [CBIT](DEBUG): Deactivating GF circuits. 2023-08-18T13:34:59.580Z,1692365699.580 [CBIT](DEBUG): Deactivating emergency mode. 2023-08-18T13:34:59.620Z,1692365699.620 [CBIT](DEBUG): Backplane powered. 2023-08-18T13:34:59.626Z,1692365699.626 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-08-18T13:34:59.627Z,1692365699.627 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-08-18T13:34:59.628Z,1692365699.628 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-08-18T13:34:59.629Z,1692365699.629 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-08-18T13:34:59.630Z,1692365699.630 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-08-18T13:34:59.647Z,1692365699.647 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-08-18T13:34:59.675Z,1692365699.675 [MissionManager](DEBUG): 2023-08-18T13:34:59.676Z,1692365699.676 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-08-18T13:34:59.740Z,1692365699.740 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-08-18T13:34:59.741Z,1692365699.741 [Default:A.Wait](DEBUG): Construct Wait. 2023-08-18T13:34:59.743Z,1692365699.743 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-08-18T13:34:59.771Z,1692365699.771 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-08-18T13:34:59.773Z,1692365699.773 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-08-18T13:34:59.779Z,1692365699.779 [Default:E.Execute](DEBUG): Construct Execute. 2023-08-18T13:34:59.795Z,1692365699.795 [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-08-18T13:34:59.809Z,1692365699.809 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-08-18T13:34:59.815Z,1692365699.815 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-08-18T13:34:59.869Z,1692365699.869 [Power24vConverter](INFO): Powering up. 2023-08-18T13:34:59.884Z,1692365699.884 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-08-18T13:34:59.896Z,1692365699.896 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-08-18T13:34:59.897Z,1692365699.897 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-08-18T13:34:59.905Z,1692365699.905 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-08-18T13:34:59.905Z,1692365699.905 [MassServo](DEBUG): Initializing EZServoServo. 2023-08-18T13:34:59.912Z,1692365699.912 [MassServo](DEBUG): Initializing MassServo. 2023-08-18T13:34:59.913Z,1692365699.913 [RudderServo](DEBUG): Initializing EZServoServo. 2023-08-18T13:34:59.928Z,1692365699.928 [RudderServo](DEBUG): Initializing RudderServo. 2023-08-18T13:34:59.929Z,1692365699.929 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-08-18T13:34:59.936Z,1692365699.936 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-08-18T13:34:59.940Z,1692365699.940 [Radio_Surface](INFO): Powering up 2023-08-18T13:35:00.269Z,1692365700.269 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-08-18T13:35:00.269Z,1692365700.269 [DropWeight] Hardware Fault, FailCount= 1 2023-08-18T13:35:00.269Z,1692365700.269 [DropWeight](ERROR): Hardware Fault 2023-08-18T13:35:00.297Z,1692365700.297 [CommandExec](FAULT): Scheduling is paused 2023-08-18T13:35:00.297Z,1692365700.297 [CBIT](INFO): Critical error at 20230818T133500 2023-08-18T13:35:00.297Z,1692365700.297 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2023-08-18T13:35:00.300Z,1692365700.300 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-08-18T13:35:00.304Z,1692365700.304 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-08-18T13:35:00.655Z,1692365700.655 [CBIT](INFO): Critical error at 20230818T133500 2023-08-18T13:35:02.172Z,1692365702.172 [WetLabsBB2FL](INFO): Powering down 2023-08-18T13:35:05.710Z,1692365705.710 [ThrusterHE](ERROR): Zero Speed Commanded. 2023-08-18T13:35:27.303Z,1692365727.303 [NAL9602](INFO): Powering up NAL9602 2023-08-18T13:35:38.206Z,1692365738.206 [NAL9602](INFO): NAL9602 initialized 2023-08-18T13:35:59.228Z,1692365759.228 [SBIT](IMPORTANT): Beginning Startup BIT 2023-08-18T13:35:59.233Z,1692365759.233 [CBIT](IMPORTANT): Beginning ground fault scan 2023-08-18T13:36:10.193Z,1692365770.193 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.021041 CHAN A1 (24V): 0.004229 CHAN A2 (12V): -0.002380 CHAN A3 (5V): -0.003313 CHAN B0 (3.3V): -0.002673 CHAN B1 (3.15aV): -0.002612 CHAN B2 (3.15bV): -0.001517 CHAN B3 (GND): -0.000918 OPEN: 0.004771 Full Scale: +/- 1 mA 2023-08-18T13:36:52.383Z,1692365812.383 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004412 2023-08-18T13:36:53.390Z,1692365813.390 [SBIT](IMPORTANT): SBIT PASSED 2023-08-18T13:36:53.391Z,1692365813.391 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-08-18T13:36:53.391Z,1692365813.391 [SBIT](IMPORTANT): CBIT.gfScanTimeout=1 hour; 2023-08-18T13:36:53.392Z,1692365813.392 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2023-08-18T13:36:53.392Z,1692365813.392 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=2 minute; 2023-08-18T13:36:53.392Z,1692365813.392 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity; 2023-08-18T13:36:53.392Z,1692365813.392 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_temperature; 2023-08-18T13:36:53.392Z,1692365813.392 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water; 2023-08-18T13:36:53.392Z,1692365813.392 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water; 2023-08-18T13:36:53.393Z,1692365813.393 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_disk_space 100.000000 gigabyte; 2023-08-18T13:36:53.393Z,1692365813.393 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_counts 300.000000 count_per_second; 2023-08-18T13:36:53.393Z,1692365813.393 [SBIT](IMPORTANT): Express none _.ayeris_particle_shape; 2023-08-18T13:36:53.393Z,1692365813.393 [SBIT](IMPORTANT): Express none _.ayeris_particle_slope; 2023-08-18T13:36:53.393Z,1692365813.393 [SBIT](IMPORTANT): Express linearApproximation depth 5.000000 meter; 2023-08-18T13:36:53.393Z,1692365813.393 [SBIT](IMPORTANT): Express linearApproximation latitude 0.005000 degree; 2023-08-18T13:36:53.393Z,1692365813.393 [SBIT](IMPORTANT): Express linearApproximation longitude 0.005000 degree; 2023-08-18T13:36:53.393Z,1692365813.393 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool; 2023-08-18T13:36:53.393Z,1692365813.393 [SBIT](IMPORTANT): StratificationFrontDetector.loadAtStartup=1 bool; 2023-08-18T13:36:53.394Z,1692365813.394 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=381.902688 cubic_centimeter; 2023-08-18T13:36:53.394Z,1692365813.394 [SBIT](IMPORTANT): VerticalControl.massDefault=-9.969354 millimeter; 2023-08-18T13:36:53.394Z,1692365813.394 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth3=20 meter; 2023-08-18T13:36:53.394Z,1692365813.394 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth4=30 meter; 2023-08-18T13:36:53.394Z,1692365813.394 [SBIT](IMPORTANT): Waterlinked.loadAtStartup=0 bool; 2023-08-18T13:36:53.787Z,1692365813.787 [MissionManager](IMPORTANT): Started mission Startup 2023-08-18T13:36:53.788Z,1692365813.788 [Startup] Running Loop=1 2023-08-18T13:36:53.789Z,1692365813.789 [Startup](DEBUG): Aggregate::initialize Startup 2023-08-18T13:36:53.789Z,1692365813.789 [Startup:A.GoToSurface] Running Loop=1 2023-08-18T13:36:53.789Z,1692365813.789 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-08-18T13:36:53.789Z,1692365813.789 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-08-18T13:36:53.790Z,1692365813.790 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-08-18T13:36:53.790Z,1692365813.790 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-08-18T13:36:53.790Z,1692365813.790 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-08-18T13:36:53.791Z,1692365813.791 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-08-18T13:36:53.792Z,1692365813.792 [Startup:StartupSatComms] Running Loop=1 2023-08-18T13:36:53.793Z,1692365813.793 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-08-18T13:36:53.793Z,1692365813.793 [Startup:StartupSatComms:A] Running Loop=1 2023-08-18T13:36:54.179Z,1692365814.179 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-08-18T13:37:54.015Z,1692365874.015 [Startup:StartupSatComms:A](INFO): Timed out from 2023-08-18T13:36:53.8Z 2023-08-18T13:37:54.015Z,1692365874.015 [Startup:StartupSatComms:A] Stopped 2023-08-18T13:37:54.015Z,1692365874.015 [Startup:StartupSatComms:B] Running Loop=1 2023-08-18T13:37:54.533Z,1692365874.533 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-08-18T13:37:59.481Z,1692365879.481 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2023-08-18T13:37:59.481Z,1692365879.481 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-08-18T13:37:59.482Z,1692365879.482 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2023-08-18T13:37:59.482Z,1692365879.482 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-08-18T13:37:59.501Z,1692365879.501 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-08-18T13:37:59.501Z,1692365879.501 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-08-18T13:37:59.911Z,1692365879.911 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-08-18T13:37:59.911Z,1692365879.911 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2023-08-18T13:37:59.911Z,1692365879.911 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-08-18T13:37:59.911Z,1692365879.911 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2023-08-18T13:38:00.285Z,1692365880.285 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-08-18T13:38:54.410Z,1692365934.410 [Startup:StartupSatComms:B](INFO): Timed out from 2023-08-18T13:37:54.0Z 2023-08-18T13:38:54.410Z,1692365934.410 [Startup:StartupSatComms:B] Stopped 2023-08-18T13:38:54.411Z,1692365934.411 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2023-08-18T13:38:54.411Z,1692365934.411 [Startup:StartupSatComms] Stopped 2023-08-18T13:38:54.411Z,1692365934.411 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-08-18T13:38:54.412Z,1692365934.412 [Startup](INFO): Completed Startup 2023-08-18T13:38:54.412Z,1692365934.412 [MissionManager](INFO): Startup is completed. 2023-08-18T13:38:54.412Z,1692365934.412 [MissionManager](INFO): Uninitializing Mission Startup 2023-08-18T13:38:54.412Z,1692365934.412 [Startup] Stopped 2023-08-18T13:38:54.412Z,1692365934.412 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-08-18T13:38:54.412Z,1692365934.412 [Startup:A.GoToSurface] Stopped 2023-08-18T13:38:54.412Z,1692365934.412 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-08-18T13:38:54.835Z,1692365934.835 [MissionManager](IMPORTANT): Started mission Default 2023-08-18T13:38:54.835Z,1692365934.835 [Default] Running Loop=1 2023-08-18T13:38:54.835Z,1692365934.835 [Default](DEBUG): Aggregate::initialize Default 2023-08-18T13:38:54.836Z,1692365934.836 [Default:B.GoToSurface] Running Loop=1 2023-08-18T13:38:54.836Z,1692365934.836 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-08-18T13:38:54.836Z,1692365934.836 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-08-18T13:38:54.836Z,1692365934.836 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-08-18T13:38:54.837Z,1692365934.837 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-08-18T13:38:54.837Z,1692365934.837 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-08-18T13:38:54.837Z,1692365934.837 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-08-18T13:38:54.838Z,1692365934.838 [Default:A.Wait] Running Loop=1 2023-08-18T13:38:54.838Z,1692365934.838 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-08-18T13:39:08.147Z,1692365948.147 [Default:A.Wait](INFO): Done Waiting. 2023-08-18T13:39:08.147Z,1692365948.147 [Default:A.Wait] Stopped 2023-08-18T13:39:08.147Z,1692365948.147 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-08-18T13:39:08.554Z,1692365948.554 [Default:CheckIn] Running Loop=1 2023-08-18T13:39:08.554Z,1692365948.554 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-08-18T13:39:08.554Z,1692365948.554 [Default:CheckIn:Read_GPS] Running Loop=1 2023-08-18T13:39:08.968Z,1692365948.968 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-08-18T13:40:41.461Z,1692366041.461 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-08-18T13:41:00.461Z,1692366060.461 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2023-08-18T13:41:00.461Z,1692366060.461 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-08-18T13:41:00.462Z,1692366060.462 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2023-08-18T13:41:00.462Z,1692366060.462 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-08-18T13:41:00.473Z,1692366060.473 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-08-18T13:41:00.473Z,1692366060.473 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-08-18T13:41:00.930Z,1692366060.930 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-08-18T13:41:00.930Z,1692366060.930 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2023-08-18T13:41:00.931Z,1692366060.931 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-08-18T13:41:00.931Z,1692366060.931 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2023-08-18T13:41:01.265Z,1692366061.265 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-08-18T13:42:27.774Z,1692366147.774 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-08-18T13:42:27.777Z,1692366147.777 [BPC1](INFO): Received data from all battery sticks. 2023-08-18T13:44:01.504Z,1692366241.504 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2023-08-18T13:44:01.504Z,1692366241.504 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-08-18T13:44:01.506Z,1692366241.506 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2023-08-18T13:44:01.506Z,1692366241.506 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-08-18T13:44:01.545Z,1692366241.545 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-08-18T13:44:01.545Z,1692366241.545 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-08-18T13:44:01.919Z,1692366241.919 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-08-18T13:44:01.920Z,1692366241.920 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2023-08-18T13:44:01.920Z,1692366241.920 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-08-18T13:44:01.920Z,1692366241.920 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2023-08-18T13:44:02.316Z,1692366242.316 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-08-18T13:44:08.796Z,1692366248.796 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-08-18T13:39:08.6Z 2023-08-18T13:44:08.796Z,1692366248.796 [Default:CheckIn:Read_GPS] Stopped 2023-08-18T13:44:08.796Z,1692366248.796 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-08-18T13:44:09.204Z,1692366249.204 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-08-18T13:45:00.901Z,1692366300.901 [Power24vConverter](INFO): Powering down. 2023-08-18T13:45:41.330Z,1692366341.330 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-08-18T13:45:41.330Z,1692366341.330 [NAL9602] Data Fault, FailCount= 1 2023-08-18T13:45:41.330Z,1692366341.330 [NAL9602](ERROR): Data Fault 2023-08-18T13:45:41.381Z,1692366341.381 [CBIT](ERROR): Data Fault in component: NAL9602 2023-08-18T13:45:41.733Z,1692366341.733 [NAL9602](INFO): Powering down 2023-08-18T13:45:42.562Z,1692366342.562 [CBIT](INFO): Clearing failed state for component NAL9602 2023-08-18T13:45:42.562Z,1692366342.562 [NAL9602] No Fault, FailCount= 1 2023-08-18T13:46:12.031Z,1692366372.031 [NAL9602](INFO): Powering up NAL9602 2023-08-18T13:46:22.947Z,1692366382.947 [NAL9602](INFO): NAL9602 initialized 2023-08-18T13:47:02.569Z,1692366422.569 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2023-08-18T13:47:02.569Z,1692366422.569 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-08-18T13:47:02.570Z,1692366422.570 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2023-08-18T13:47:02.570Z,1692366422.570 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2023-08-18T13:47:02.597Z,1692366422.597 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-08-18T13:47:02.597Z,1692366422.597 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2023-08-18T13:47:02.998Z,1692366422.998 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-08-18T13:47:02.998Z,1692366422.998 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2023-08-18T13:47:02.998Z,1692366422.998 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2023-08-18T13:47:02.998Z,1692366422.998 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2023-08-18T13:47:03.365Z,1692366423.365 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-08-18T13:47:05.771Z,1692366425.771 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170530.00,A,3648.17515,N,12147.28758,W,0.564,217.72,180823,,,A*75 2023-08-18T13:47:05.775Z,1692366425.775 [NAL9602](INFO): GPS fix at 20230818T170530: (36.802919, -121.788126) 2023-08-18T13:47:38.127Z,1692366458.127 [NAL9602](INFO): Not Powering down - fast GPS 2023-08-18T13:47:53.389Z,1692366473.389 [CommandExec](IMPORTANT): got command failComponent 2023-08-18T13:47:53.389Z,1692366473.389 [CommandExec](IMPORTANT): Failed components: 2023-08-18T13:47:53.389Z,1692366473.389 [CommandExec](IMPORTANT): DropWeight: Hardware Fault 2023-08-18T13:50:00.413Z,1692366600.413 [CBIT](INFO): Clearing failed state for component DropWeight 2023-08-18T13:50:00.413Z,1692366600.413 [DropWeight] No Fault, FailCount= 1 2023-08-18T13:54:23.241Z,1692366863.241 [CommandExec](IMPORTANT): got command failComponent 2023-08-18T13:54:23.241Z,1692366863.241 [CommandExec](IMPORTANT): Failed components: 2023-08-18T13:54:23.241Z,1692366863.241 [CommandExec](IMPORTANT): No failed Components. 2023-08-18T13:54:27.844Z,1692366867.844 [CommandLine](INFO): End of History 2023-08-18T13:54:27.994Z,1692366867.994 [CommandLine](INFO): End of History 2023-08-18T13:54:27.995Z,1692366867.995 [CommandLine](INFO): End of History 2023-08-18T13:54:27.996Z,1692366867.996 [CommandLine](INFO): End of History 2023-08-18T13:54:28.071Z,1692366868.071 [CommandLine](INFO): End of History 2023-08-18T13:54:28.072Z,1692366868.072 [CommandLine](INFO): End of History 2023-08-18T13:54:28.073Z,1692366868.073 [CommandLine](INFO): End of History 2023-08-18T13:54:28.420Z,1692366868.420 [CommandLine](INFO): End of History 2023-08-18T13:54:28.428Z,1692366868.428 [CommandLine](INFO): End of History 2023-08-18T13:54:28.434Z,1692366868.434 [CommandLine](INFO): End of History 2023-08-18T13:54:28.438Z,1692366868.438 [CommandLine](INFO): End of History 2023-08-18T13:54:28.442Z,1692366868.442 [CommandLine](INFO): End of History 2023-08-18T13:54:28.447Z,1692366868.447 [CommandLine](INFO): End of History 2023-08-18T13:54:28.523Z,1692366868.523 [CommandLine](INFO): End of History 2023-08-18T13:54:28.524Z,1692366868.524 [CommandLine](INFO): End of History 2023-08-18T13:54:28.525Z,1692366868.525 [CommandLine](INFO): End of History 2023-08-18T13:54:28.598Z,1692366868.598 [CommandLine](INFO): End of History 2023-08-18T13:54:28.599Z,1692366868.599 [CommandLine](INFO): End of History 2023-08-18T13:54:28.600Z,1692366868.600 [CommandLine](INFO): End of History 2023-08-18T13:54:36.041Z,1692366876.041 [CommandExec](IMPORTANT): got command ibit 2023-08-18T13:54:36.166Z,1692366876.166 [IBIT](IMPORTANT): Beginning Initiated BIT 2023-08-18T13:54:36.167Z,1692366876.167 [IBIT](IMPORTANT): Beginning control surface checks. 2023-08-18T13:54:36.170Z,1692366876.170 [CBIT](IMPORTANT): Beginning ground fault scan 2023-08-18T13:54:37.723Z,1692366877.723 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171302.00,A,3648.17495,N,12147.28602,W,0.019,217.72,180823,,,D*7E 2023-08-18T13:54:37.725Z,1692366877.725 [NAL9602](INFO): GPS fix at 20230818T171302: (36.802916, -121.788100) 2023-08-18T13:54:39.025Z,1692366879.025 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2023-08-18T13:54:47.047Z,1692366887.047 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.005508 CHAN A1 (24V) N/A reporting off CHAN A2 (12V): -0.005098 CHAN A3 (5V): -0.002572 CHAN B0 (3.3V): -0.000858 CHAN B1 (3.15aV): -0.000937 CHAN B2 (3.15bV): -0.001791 CHAN B3 (GND): -0.000812 OPEN: 0.004906 Full Scale: +/- 1 mA 2023-08-18T13:54:53.067Z,1692366893.067 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=13495, MT Status=1, MTMSN=584 2023-08-18T13:54:53.067Z,1692366893.067 [NAL9602](INFO): Data available in MT queue 2023-08-18T13:54:53.553Z,1692366893.553 [NAL9602](INFO): Received command: failc 2023-08-18T13:54:53.641Z,1692366893.641 [CommandExec](IMPORTANT): got command failComponent 2023-08-18T13:54:53.641Z,1692366893.641 [CommandExec](IMPORTANT): Failed components: 2023-08-18T13:54:53.641Z,1692366893.641 [CommandExec](IMPORTANT): No failed Components. 2023-08-18T13:54:54.691Z,1692366894.691 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171319.00,A,3648.17480,N,12147.28562,W,0.039,217.72,180823,,,D*77 2023-08-18T13:54:54.694Z,1692366894.694 [NAL9602](INFO): GPS fix at 20230818T171319: (36.802913, -121.788094) 2023-08-18T13:54:57.914Z,1692366897.914 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171322.00,A,3648.17475,N,12147.28555,W,0.019,217.72,180823,,,D*73 2023-08-18T13:54:57.917Z,1692366897.917 [NAL9602](INFO): GPS fix at 20230818T171322: (36.802912, -121.788093) 2023-08-18T13:55:00.743Z,1692366900.743 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171325.00,A,3648.17468,N,12147.28555,W,0.039,217.72,180823,,,D*7A 2023-08-18T13:55:00.745Z,1692366900.745 [NAL9602](INFO): GPS fix at 20230818T171325: (36.802911, -121.788093) 2023-08-18T13:55:03.979Z,1692366903.979 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171328.00,A,3648.17461,N,12147.28560,W,0.039,217.72,180823,,,D*78 2023-08-18T13:55:03.981Z,1692366903.981 [NAL9602](INFO): GPS fix at 20230818T171328: (36.802910, -121.788093) 2023-08-18T13:55:06.808Z,1692366906.808 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171331.00,A,3648.17455,N,12147.28560,W,0.039,217.72,180823,,,D*77 2023-08-18T13:55:06.810Z,1692366906.810 [NAL9602](INFO): GPS fix at 20230818T171331: (36.802909, -121.788093) 2023-08-18T13:55:09.631Z,1692366909.631 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171334.00,A,3648.17452,N,12147.28560,W,0.000,217.72,180823,,,D*7F 2023-08-18T13:55:09.633Z,1692366909.633 [NAL9602](INFO): GPS fix at 20230818T171334: (36.802909, -121.788093) 2023-08-18T13:55:12.862Z,1692366912.862 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171338.00,A,3648.17445,N,12147.28557,W,0.019,217.72,180823,,,D*79 2023-08-18T13:55:12.864Z,1692366912.864 [NAL9602](INFO): GPS fix at 20230818T171338: (36.802908, -121.788093) 2023-08-18T13:55:15.699Z,1692366915.699 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171341.00,A,3648.17439,N,12147.28557,W,0.019,217.72,180823,,,D*7C 2023-08-18T13:55:15.701Z,1692366915.701 [NAL9602](INFO): GPS fix at 20230818T171341: (36.802906, -121.788093) 2023-08-18T13:55:18.963Z,1692366918.963 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171344.00,A,3648.17435,N,12147.28561,W,0.019,217.72,180823,,,D*70 2023-08-18T13:55:18.965Z,1692366918.965 [NAL9602](INFO): GPS fix at 20230818T171344: (36.802906, -121.788094) 2023-08-18T13:55:21.815Z,1692366921.815 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171347.00,A,3648.17429,N,12147.28560,W,0.058,217.72,180823,,,D*7A 2023-08-18T13:55:21.817Z,1692366921.817 [NAL9602](INFO): GPS fix at 20230818T171347: (36.802905, -121.788093) 2023-08-18T13:55:22.238Z,1692366922.238 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 5 Latitude: 36.802906 Longitude: -121.788094 2023-08-18T13:55:22.624Z,1692366922.624 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 14.986000 2023-08-18T13:55:22.624Z,1692366922.624 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2023-08-18T13:55:22.625Z,1692366922.625 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2023-08-18T13:55:23.085Z,1692366923.085 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2023-08-18T13:55:23.085Z,1692366923.085 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2023-08-18T13:55:23.085Z,1692366923.085 [IBIT](IMPORTANT): Pressure:15.602199 PSI 2023-08-18T13:55:23.086Z,1692366923.086 [IBIT](IMPORTANT): Humidity:45.232571 % 2023-08-18T13:55:23.439Z,1692366923.439 [IBIT](IMPORTANT): Vehicle Pitch:-1.280078 degrees 2023-08-18T13:55:23.440Z,1692366923.440 [IBIT](IMPORTANT): Vehicle Roll:-3.060483 degrees 2023-08-18T13:55:23.464Z,1692366923.464 [IBIT](IMPORTANT): Vehicle Heading:67.414925 degrees 2023-08-18T13:55:23.856Z,1692366923.856 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2023-08-18T13:55:23.857Z,1692366923.857 [IBIT](IMPORTANT): buoyancyNeutral: 381.902679 cc 2023-08-18T13:55:23.857Z,1692366923.857 [IBIT](IMPORTANT): massDefault: -0.996935 cm 2023-08-18T13:55:23.857Z,1692366923.857 [IBIT](IMPORTANT): stopDepth: 290.000000 m 2023-08-18T13:55:23.858Z,1692366923.858 [IBIT](IMPORTANT): abortDepth: 300.000000 m 2023-08-18T13:55:23.858Z,1692366923.858 [IBIT](IMPORTANT): IBIT PASSED 2023-08-18T13:55:28.739Z,1692366928.739 [CommandLine](INFO): End of History 2023-08-18T13:55:28.739Z,1692366928.739 [CommandLine](INFO): End of History 2023-08-18T13:55:28.740Z,1692366928.740 [CommandLine](INFO): End of History 2023-08-18T13:55:28.778Z,1692366928.778 [CommandLine](INFO): End of History 2023-08-18T13:55:28.779Z,1692366928.779 [CommandLine](INFO): End of History 2023-08-18T13:55:28.779Z,1692366928.779 [CommandLine](INFO): End of History 2023-08-18T13:55:28.804Z,1692366928.804 [CommandLine](INFO): End of History 2023-08-18T13:55:28.804Z,1692366928.804 [CommandLine](INFO): End of History 2023-08-18T13:55:28.805Z,1692366928.805 [CommandLine](INFO): End of History 2023-08-18T13:55:28.852Z,1692366928.852 [CommandLine](INFO): End of History 2023-08-18T13:55:28.853Z,1692366928.853 [CommandLine](INFO): End of History 2023-08-18T13:55:28.854Z,1692366928.854 [CommandLine](INFO): End of History 2023-08-18T13:55:54.524Z,1692366954.524 [NAL9602](INFO): Not Powering down - fast GPS 2023-08-18T13:56:40.748Z,1692367000.748 [CommandLine](INFO): End of History 2023-08-18T13:56:40.749Z,1692367000.749 [CommandLine](INFO): End of History 2023-08-18T13:56:40.750Z,1692367000.750 [CommandLine](INFO): End of History 2023-08-18T13:56:40.750Z,1692367000.750 [CommandLine](INFO): End of History 2023-08-18T13:56:40.751Z,1692367000.751 [CommandLine](INFO): End of History 2023-08-18T13:56:40.752Z,1692367000.752 [CommandLine](INFO): End of History 2023-08-18T13:56:40.826Z,1692367000.826 [CommandLine](INFO): End of History 2023-08-18T13:56:40.827Z,1692367000.827 [CommandLine](INFO): End of History 2023-08-18T13:56:40.827Z,1692367000.827 [CommandLine](INFO): End of History 2023-08-18T13:56:44.909Z,1692367004.909 [CommandLine](INFO): End of History 2023-08-18T13:56:44.910Z,1692367004.910 [CommandLine](INFO): End of History 2023-08-18T13:56:44.910Z,1692367004.910 [CommandLine](INFO): End of History 2023-08-18T13:56:44.911Z,1692367004.911 [CommandLine](INFO): End of History 2023-08-18T13:56:44.912Z,1692367004.912 [CommandLine](INFO): End of History 2023-08-18T13:56:44.913Z,1692367004.913 [CommandLine](INFO): End of History 2023-08-18T13:56:44.913Z,1692367004.913 [CommandLine](INFO): End of History 2023-08-18T13:56:44.914Z,1692367004.914 [CommandLine](INFO): End of History 2023-08-18T13:56:44.915Z,1692367004.915 [CommandLine](INFO): End of History 2023-08-18T13:56:44.915Z,1692367004.915 [CommandLine](INFO): End of History 2023-08-18T13:56:44.924Z,1692367004.924 [CommandLine](INFO): End of History 2023-08-18T13:56:44.924Z,1692367004.924 [CommandLine](INFO): End of History 2023-08-18T13:56:44.925Z,1692367004.925 [CommandLine](INFO): End of History 2023-08-18T13:56:44.926Z,1692367004.926 [CommandLine](INFO): End of History 2023-08-18T13:56:44.927Z,1692367004.927 [CommandLine](INFO): End of History 2023-08-18T13:56:44.927Z,1692367004.927 [CommandLine](INFO): End of History 2023-08-18T13:56:44.952Z,1692367004.952 [CommandLine](INFO): End of History 2023-08-18T13:56:44.953Z,1692367004.953 [CommandLine](INFO): End of History 2023-08-18T13:56:53.705Z,1692367013.705 [CommandExec](IMPORTANT): got command ibit 2023-08-18T13:56:53.933Z,1692367013.933 [IBIT](IMPORTANT): Beginning Initiated BIT 2023-08-18T13:56:53.933Z,1692367013.933 [IBIT](IMPORTANT): Beginning control surface checks. 2023-08-18T13:56:53.936Z,1692367013.936 [CBIT](IMPORTANT): Beginning ground fault scan 2023-08-18T13:56:55.531Z,1692367015.531 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171520.00,A,3648.17419,N,12147.28600,W,0.019,217.72,180823,,,D*7E 2023-08-18T13:56:55.534Z,1692367015.534 [NAL9602](INFO): GPS fix at 20230818T171520: (36.802903, -121.788100) 2023-08-18T13:56:56.841Z,1692367016.841 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2023-08-18T13:57:04.902Z,1692367024.902 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.006173 CHAN A1 (24V) N/A reporting off CHAN A2 (12V): -0.004902 CHAN A3 (5V): -0.002698 CHAN B0 (3.3V): -0.001455 CHAN B1 (3.15aV): -0.001491 CHAN B2 (3.15bV): -0.001461 CHAN B3 (GND): -0.000566 OPEN: 0.004882 Full Scale: +/- 1 mA 2023-08-18T13:57:09.271Z,1692367029.271 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=13496, MT Status=1, MTMSN=585 2023-08-18T13:57:09.271Z,1692367029.271 [NAL9602](INFO): Data available in MT queue 2023-08-18T13:57:09.765Z,1692367029.765 [NAL9602](INFO): Received command: ibit 2023-08-18T13:57:09.769Z,1692367029.769 [CommandExec](IMPORTANT): got command ibit 2023-08-18T13:57:09.791Z,1692367029.791 [IBIT](IMPORTANT): Beginning Initiated BIT 2023-08-18T13:57:09.792Z,1692367029.792 [IBIT](IMPORTANT): Beginning control surface checks. 2023-08-18T13:57:09.795Z,1692367029.795 [CBIT](IMPORTANT): Beginning ground fault scan 2023-08-18T13:57:10.887Z,1692367030.887 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171536.00,A,3648.17431,N,12147.28631,W,0.019,217.72,180823,,,D*71 2023-08-18T13:57:10.890Z,1692367030.890 [NAL9602](INFO): GPS fix at 20230818T171536: (36.802905, -121.788105) 2023-08-18T13:57:12.588Z,1692367032.588 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2023-08-18T13:57:13.711Z,1692367033.711 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171539.00,A,3648.17442,N,12147.28633,W,0.019,217.72,180823,,,D*78 2023-08-18T13:57:13.713Z,1692367033.713 [NAL9602](INFO): GPS fix at 20230818T171539: (36.802907, -121.788106) 2023-08-18T13:57:16.942Z,1692367036.942 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171542.00,A,3648.17448,N,12147.28635,W,0.039,217.72,180823,,,D*7A 2023-08-18T13:57:16.945Z,1692367036.945 [NAL9602](INFO): GPS fix at 20230818T171542: (36.802908, -121.788106) 2023-08-18T13:57:19.772Z,1692367039.772 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171545.00,A,3648.17455,N,12147.28635,W,0.019,217.72,180823,,,D*73 2023-08-18T13:57:19.774Z,1692367039.774 [NAL9602](INFO): GPS fix at 20230818T171545: (36.802909, -121.788106) 2023-08-18T13:57:20.631Z,1692367040.631 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.005813 CHAN A1 (24V) N/A reporting off CHAN A2 (12V): -0.005758 CHAN A3 (5V): -0.003332 CHAN B0 (3.3V): -0.001113 CHAN B1 (3.15aV): -0.001146 CHAN B2 (3.15bV): -0.001776 CHAN B3 (GND): -0.000362 OPEN: 0.004667 Full Scale: +/- 1 mA 2023-08-18T13:57:23.031Z,1692367043.031 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171548.00,A,3648.17462,N,12147.28635,W,0.019,217.72,180823,,,D*7A 2023-08-18T13:57:23.033Z,1692367043.033 [NAL9602](INFO): GPS fix at 20230818T171548: (36.802910, -121.788106) 2023-08-18T13:57:25.887Z,1692367045.887 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171551.00,A,3648.17469,N,12147.28630,W,0.019,217.72,180823,,,D*7C 2023-08-18T13:57:25.890Z,1692367045.890 [NAL9602](INFO): GPS fix at 20230818T171551: (36.802912, -121.788105) 2023-08-18T13:57:28.707Z,1692367048.707 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171554.00,A,3648.17475,N,12147.28620,W,0.019,217.72,180823,,,D*75 2023-08-18T13:57:28.709Z,1692367048.709 [NAL9602](INFO): GPS fix at 20230818T171554: (36.802912, -121.788103) 2023-08-18T13:57:31.939Z,1692367051.939 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171557.00,A,3648.17478,N,12147.28609,W,0.019,217.72,180823,,,D*70 2023-08-18T13:57:31.941Z,1692367051.941 [NAL9602](INFO): GPS fix at 20230818T171557: (36.802913, -121.788101) 2023-08-18T13:57:34.771Z,1692367054.771 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171600.00,A,3648.17482,N,12147.28607,W,0.000,217.72,180823,,,D*72 2023-08-18T13:57:34.778Z,1692367054.778 [NAL9602](INFO): GPS fix at 20230818T171600: (36.802914, -121.788101) 2023-08-18T13:57:38.003Z,1692367058.003 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171603.00,A,3648.17489,N,12147.28610,W,0.039,217.72,180823,,,D*76 2023-08-18T13:57:38.005Z,1692367058.005 [NAL9602](INFO): GPS fix at 20230818T171603: (36.802915, -121.788102) 2023-08-18T13:57:40.879Z,1692367060.879 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171606.00,A,3648.17503,N,12147.28612,W,0.000,217.72,180823,,,D*78 2023-08-18T13:57:40.882Z,1692367060.882 [NAL9602](INFO): GPS fix at 20230818T171606: (36.802917, -121.788102) 2023-08-18T13:57:43.683Z,1692367063.683 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171609.00,A,3648.17518,N,12147.28616,W,0.058,217.72,180823,,,D*74 2023-08-18T13:57:43.686Z,1692367063.686 [NAL9602](INFO): GPS fix at 20230818T171609: (36.802920, -121.788103) 2023-08-18T13:57:46.919Z,1692367066.919 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171612.00,A,3648.17533,N,12147.28625,W,0.000,217.72,180823,,,D*7A 2023-08-18T13:57:46.921Z,1692367066.921 [NAL9602](INFO): GPS fix at 20230818T171612: (36.802922, -121.788104) 2023-08-18T13:57:49.747Z,1692367069.747 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171615.00,A,3648.17543,N,12147.28627,W,0.019,217.72,180823,,,D*70 2023-08-18T13:57:49.749Z,1692367069.749 [NAL9602](INFO): GPS fix at 20230818T171615: (36.802924, -121.788105) 2023-08-18T13:57:52.975Z,1692367072.975 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171618.00,A,3648.17550,N,12147.28629,W,0.039,217.72,180823,,,D*73 2023-08-18T13:57:52.977Z,1692367072.977 [NAL9602](INFO): GPS fix at 20230818T171618: (36.802925, -121.788105) 2023-08-18T13:57:55.807Z,1692367075.807 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171621.00,A,3648.17555,N,12147.28638,W,0.019,217.72,180823,,,D*7E 2023-08-18T13:57:55.809Z,1692367075.809 [NAL9602](INFO): GPS fix at 20230818T171621: (36.802926, -121.788106) 2023-08-18T13:57:56.260Z,1692367076.260 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 2 Latitude: 36.802925 Longitude: -121.788109 2023-08-18T13:57:56.631Z,1692367076.631 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 14.946000 2023-08-18T13:57:56.631Z,1692367076.631 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2023-08-18T13:57:56.631Z,1692367076.631 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2023-08-18T13:57:57.035Z,1692367077.035 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2023-08-18T13:57:57.035Z,1692367077.035 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2023-08-18T13:57:57.036Z,1692367077.036 [IBIT](IMPORTANT): Pressure:15.543154 PSI 2023-08-18T13:57:57.036Z,1692367077.036 [IBIT](IMPORTANT): Humidity:43.602734 % 2023-08-18T13:57:57.457Z,1692367077.457 [IBIT](IMPORTANT): Vehicle Pitch:-1.272949 degrees 2023-08-18T13:57:57.457Z,1692367077.457 [IBIT](IMPORTANT): Vehicle Roll:-3.071726 degrees 2023-08-18T13:57:57.457Z,1692367077.457 [IBIT](IMPORTANT): Vehicle Heading:67.402008 degrees 2023-08-18T13:57:57.890Z,1692367077.890 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2023-08-18T13:57:57.890Z,1692367077.890 [IBIT](IMPORTANT): buoyancyNeutral: 381.902679 cc 2023-08-18T13:57:57.891Z,1692367077.891 [IBIT](IMPORTANT): massDefault: -0.996935 cm 2023-08-18T13:57:57.891Z,1692367077.891 [IBIT](IMPORTANT): stopDepth: 290.000000 m 2023-08-18T13:57:57.891Z,1692367077.891 [IBIT](IMPORTANT): abortDepth: 300.000000 m 2023-08-18T13:57:57.891Z,1692367077.891 [IBIT](IMPORTANT): IBIT PASSED 2023-08-18T13:58:28.524Z,1692367108.524 [NAL9602](INFO): Not Powering down - fast GPS 2023-08-18T14:20:01.824Z,1692368401.824 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-08-18T14:20:01.824Z,1692368401.824 [DropWeight] Hardware Fault, FailCount= 1 2023-08-18T14:20:01.824Z,1692368401.824 [DropWeight](ERROR): Hardware Fault 2023-08-18T14:20:01.843Z,1692368401.843 [CBIT](INFO): Critical error at 20230818T142001 2023-08-18T14:20:01.845Z,1692368401.845 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-08-18T14:20:01.846Z,1692368401.846 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-08-18T14:20:02.253Z,1692368402.253 [CBIT](INFO): Critical error at 20230818T142001 2023-08-18T14:24:09.817Z,1692368649.817 [CommandExec](IMPORTANT): got command quit 2023-08-18T14:24:10.820Z,1692368650.820 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-08-18T14:24:10.820Z,1692368650.820 [CommandExec](INFO): Uninitializing the command executive. 2023-08-18T14:24:10.821Z,1692368650.821 [CommandExec](INFO): Uninitializing the command scheduler. 2023-08-18T14:24:10.821Z,1692368650.821 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:10.000Z,1692368651.000 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-08-18T14:24:11.000Z,1692368651.000 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-08-18T14:24:11.000Z,1692368651.000 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:11.001Z,1692368651.001 [NavChartDb](INFO): Join timeout helper Thread ID is 2230 2023-08-18T14:24:11.027Z,1692368651.027 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-08-18T14:24:11.027Z,1692368651.027 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:11.040Z,1692368651.040 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-08-18T14:24:11.040Z,1692368651.040 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:11.041Z,1692368651.041 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2231 2023-08-18T14:24:11.084Z,1692368651.084 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-08-18T14:24:11.085Z,1692368651.085 [WetLabsBB2FL](INFO): Powering down 2023-08-18T14:24:11.085Z,1692368651.085 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:11.092Z,1692368651.092 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-08-18T14:24:11.092Z,1692368651.092 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:11.093Z,1692368651.093 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2232 2023-08-18T14:24:11.696Z,1692368651.696 [CTD_Seabird](INFO): Powering down 2023-08-18T14:24:11.708Z,1692368651.708 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-08-18T14:24:11.708Z,1692368651.708 [CTD_Seabird](INFO): Powering down 2023-08-18T14:24:11.727Z,1692368651.727 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:11.736Z,1692368651.736 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-08-18T14:24:11.736Z,1692368651.736 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:11.737Z,1692368651.737 [Radio_Surface](INFO): Join timeout helper Thread ID is 2233 2023-08-18T14:24:11.944Z,1692368651.944 [Radio_Surface](INFO): Powering down 2023-08-18T14:24:11.945Z,1692368651.945 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-08-18T14:24:11.945Z,1692368651.945 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:11.964Z,1692368651.964 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-08-18T14:24:11.964Z,1692368651.964 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:11.964Z,1692368651.964 [Onboard](INFO): Join timeout helper Thread ID is 2234 2023-08-18T14:24:12.229Z,1692368652.229 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-08-18T14:24:12.229Z,1692368652.229 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:12.237Z,1692368652.237 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-08-18T14:24:12.237Z,1692368652.237 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:12.238Z,1692368652.238 [DataOverHttps](INFO): Join timeout helper Thread ID is 2235 2023-08-18T14:24:12.808Z,1692368652.808 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-08-18T14:24:12.809Z,1692368652.809 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:12.813Z,1692368652.813 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-08-18T14:24:12.813Z,1692368652.813 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:12.813Z,1692368652.813 [BackseatComponent](INFO): Join timeout helper Thread ID is 2236 2023-08-18T14:24:12.840Z,1692368652.840 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-08-18T14:24:12.841Z,1692368652.841 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:12.861Z,1692368652.861 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-08-18T14:24:12.861Z,1692368652.861 [logger ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:12.861Z,1692368652.861 [logger](INFO): Join timeout helper Thread ID is 2237 2023-08-18T14:24:12.871Z,1692368652.871 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-08-18T14:24:12.871Z,1692368652.871 [logger ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:12.873Z,1692368652.873 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-08-18T14:24:12.873Z,1692368652.873 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:12.873Z,1692368652.873 [CommandLine](INFO): Join timeout helper Thread ID is 2238 2023-08-18T14:24:12.936Z,1692368652.936 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-08-18T14:24:12.936Z,1692368652.936 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:12.953Z,1692368652.953 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-08-18T14:24:12.953Z,1692368652.953 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:12.953Z,1692368652.953 [CommandExec](INFO): Join timeout helper Thread ID is 2239 2023-08-18T14:24:12.954Z,1692368652.954 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-08-18T14:24:12.954Z,1692368652.954 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:12.955Z,1692368652.955 [controlThread](INFO): Join timeout helper Thread ID is 2240 2023-08-18T14:24:13.180Z,1692368653.180 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-08-18T14:24:13.180Z,1692368653.180 [controlThread](DEBUG): Uninitializing ControlThread 2023-08-18T14:24:13.181Z,1692368653.181 [AHRS_M2](INFO): Powering down 2023-08-18T14:24:13.253Z,1692368653.253 [NAL9602](INFO): Powering down 2023-08-18T14:24:13.255Z,1692368653.255 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-08-18T14:24:13.256Z,1692368653.256 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-08-18T14:24:13.257Z,1692368653.257 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-08-18T14:24:13.257Z,1692368653.257 [MissionManager](INFO): Uninitializing Mission Default 2023-08-18T14:24:13.257Z,1692368653.257 [Default] Stopped 2023-08-18T14:24:13.257Z,1692368653.257 [Default](DEBUG): Aggregate::uninitialize Default 2023-08-18T14:24:13.257Z,1692368653.257 [Default:B.GoToSurface] Stopped 2023-08-18T14:24:13.257Z,1692368653.257 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-08-18T14:24:13.258Z,1692368653.258 [Default:CheckIn] Stopped 2023-08-18T14:24:13.258Z,1692368653.258 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-08-18T14:24:13.258Z,1692368653.258 [Default:CheckIn:Read_Iridium] Stopped 2023-08-18T14:24:13.260Z,1692368653.260 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-08-18T14:24:13.261Z,1692368653.261 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-08-18T14:24:13.261Z,1692368653.261 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-08-18T14:24:13.261Z,1692368653.261 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-08-18T14:24:13.262Z,1692368653.262 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-08-18T14:24:13.262Z,1692368653.262 [BuoyancyServo](INFO): Powering down 2023-08-18T14:24:13.276Z,1692368653.276 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-08-18T14:24:13.276Z,1692368653.276 [ElevatorServo](INFO): Powering down 2023-08-18T14:24:13.277Z,1692368653.277 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-08-18T14:24:13.277Z,1692368653.277 [MassServo](INFO): Powering down 2023-08-18T14:24:13.278Z,1692368653.278 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-08-18T14:24:13.278Z,1692368653.278 [RudderServo](INFO): Powering down 2023-08-18T14:24:13.279Z,1692368653.279 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2023-08-18T14:24:13.279Z,1692368653.279 [ThrusterHE](INFO): Powering down 2023-08-18T14:24:13.280Z,1692368653.280 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-08-18T14:24:13.280Z,1692368653.280 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-08-18T14:24:13.281Z,1692368653.281 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-08-18T14:24:13.281Z,1692368653.281 [CBIT](DEBUG): Powering off loads. 2023-08-18T14:24:13.292Z,1692368653.292 [CBIT](DEBUG): Disabling WDT. 2023-08-18T14:24:13.304Z,1692368653.304 [CBIT](DEBUG): Opening all GF detection circuits. 2023-08-18T14:24:13.305Z,1692368653.305 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:13.325Z,1692368653.325 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:13.329Z,1692368653.329 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:13.335Z,1692368653.335 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:13.380Z,1692368653.380 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:13.429Z,1692368653.429 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:13.433Z,1692368653.433 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:13.484Z,1692368653.484 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-08-18T14:24:13.531Z,1692368653.531 [logger ThreadHandler](INFO): Thread cancelled.