2024-04-04T20:27:41.483Z,1712262461.483 [Supervisor](DEBUG): Initializing supervisor.
2024-04-04T20:27:41.487Z,1712262461.487 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-04-04T20:27:41.488Z,1712262461.488 [SyncHandler](INFO): Protected caller Thread ID is 3582
2024-04-04T20:27:41.488Z,1712262461.488 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-04-04T20:27:41.489Z,1712262461.489 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-04-04T20:27:41.489Z,1712262461.489 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3583
2024-04-04T20:27:41.493Z,1712262461.493 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-04-04T20:27:41.511Z,1712262461.511 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-04-04T20:27:41.512Z,1712262461.512 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-04-04T20:27:41.512Z,1712262461.512 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3584
2024-04-04T20:27:41.516Z,1712262461.516 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-04-04T20:27:41.517Z,1712262461.517 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-04-04T20:27:41.517Z,1712262461.517 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3585
2024-04-04T20:27:41.520Z,1712262461.520 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-04-04T20:27:41.520Z,1712262461.520 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-04-04T20:27:41.521Z,1712262461.521 [logger ThreadHandler](INFO): Protected caller Thread ID is 3586
2024-04-04T20:27:41.524Z,1712262461.524 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-04-04T20:27:41.525Z,1712262461.525 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-04-04T20:27:41.526Z,1712262461.526 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-04-04T20:27:41.859Z,1712262461.859 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-04-04T20:27:41.860Z,1712262461.860 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-04-04T20:27:41.943Z,1712262461.943 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-04-04T20:27:42.051Z,1712262462.051 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-04-04T20:27:42.052Z,1712262462.052 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-04-04T20:27:42.184Z,1712262462.184 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-04-04T20:27:42.184Z,1712262462.184 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-04-04T20:27:42.540Z,1712262462.540 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-04-04T20:27:42.540Z,1712262462.540 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-04-04T20:27:42.640Z,1712262462.640 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-04-04T20:27:42.640Z,1712262462.640 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-04-04T20:27:43.040Z,1712262463.040 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-04-04T20:27:43.041Z,1712262463.041 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-04-04T20:27:43.256Z,1712262463.256 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-04-04T20:27:43.256Z,1712262463.256 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-04-04T20:27:43.338Z,1712262463.338 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-04-04T20:27:43.576Z,1712262463.576 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-04-04T20:27:43.577Z,1712262463.577 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-04-04T20:27:43.799Z,1712262463.799 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-04-04T20:27:43.800Z,1712262463.800 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-04-04T20:27:44.323Z,1712262464.323 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-04-04T20:27:44.323Z,1712262464.323 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-04-04T20:27:44.521Z,1712262464.521 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-04-04T20:27:44.522Z,1712262464.522 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-04-04T20:27:44.811Z,1712262464.811 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-04-04T20:27:44.812Z,1712262464.812 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-04-04T20:27:45.703Z,1712262465.703 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-04-04T20:27:45.704Z,1712262465.704 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-04-04T20:27:46.237Z,1712262466.237 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-04-04T20:27:46.239Z,1712262466.239 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/
2024-04-04T20:27:46.240Z,1712262466.240 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg
2024-04-04T20:27:46.353Z,1712262466.353 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg
2024-04-04T20:27:46.474Z,1712262466.474 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg
2024-04-04T20:27:46.559Z,1712262466.559 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg
2024-04-04T20:27:46.669Z,1712262466.669 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg
2024-04-04T20:27:46.759Z,1712262466.759 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg
2024-04-04T20:27:47.018Z,1712262467.018 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-04-04T20:27:47.019Z,1712262467.019 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg
2024-04-04T20:27:47.131Z,1712262467.131 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg
2024-04-04T20:27:47.227Z,1712262467.227 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg
2024-04-04T20:27:47.332Z,1712262467.332 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg
2024-04-04T20:27:47.470Z,1712262467.470 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg
2024-04-04T20:27:47.558Z,1712262467.558 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/root/
2024-04-04T20:27:47.558Z,1712262467.558 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2024-04-04T20:27:47.578Z,1712262467.578 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-04-04T20:27:47.692Z,1712262467.692 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-04-04T20:27:47.693Z,1712262467.693 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-04-04T20:27:47.746Z,1712262467.746 [VerticalControl](DEBUG): Construct VerticalControl.
2024-04-04T20:27:47.805Z,1712262467.805 [VerticalControl] Loaded
2024-04-04T20:27:47.805Z,1712262467.805 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-04-04T20:27:47.808Z,1712262467.808 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-04-04T20:27:47.850Z,1712262467.850 [HorizontalControl] Loaded
2024-04-04T20:27:47.850Z,1712262467.850 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-04-04T20:27:47.852Z,1712262467.852 [SpeedControl](DEBUG): Construct SpeedControl.
2024-04-04T20:27:47.855Z,1712262467.855 [SpeedControl] Loaded
2024-04-04T20:27:47.856Z,1712262467.856 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-04-04T20:27:47.858Z,1712262467.858 [LoopControl](DEBUG): Construct LoopControl.
2024-04-04T20:27:47.859Z,1712262467.859 [LoopControl] Loaded
2024-04-04T20:27:47.859Z,1712262467.859 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-04-04T20:27:47.859Z,1712262467.859 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-04-04T20:27:47.860Z,1712262467.860 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-04-04T20:27:47.916Z,1712262467.916 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-04-04T20:27:47.917Z,1712262467.917 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-04-04T20:27:48.342Z,1712262468.342 [AHRS_M2] Loaded
2024-04-04T20:27:48.342Z,1712262468.342 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-04-04T20:27:48.377Z,1712262468.377 [BackseatComponent] Loaded
2024-04-04T20:27:48.378Z,1712262468.378 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-04-04T20:27:48.379Z,1712262468.379 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408924E0
2024-04-04T20:27:48.379Z,1712262468.379 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 3669
2024-04-04T20:27:48.382Z,1712262468.382 [LcmUniversalReporter] Loaded
2024-04-04T20:27:48.382Z,1712262468.382 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-04-04T20:27:49.175Z,1712262469.175 [BPC1] Loaded
2024-04-04T20:27:49.176Z,1712262469.176 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-04-04T20:27:49.251Z,1712262469.251 [DataOverHttps] Loaded
2024-04-04T20:27:49.251Z,1712262469.251 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-04-04T20:27:49.252Z,1712262469.252 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408C24E0
2024-04-04T20:27:49.252Z,1712262469.252 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3670
2024-04-04T20:27:49.273Z,1712262469.273 [Depth_Keller] Loaded
2024-04-04T20:27:49.273Z,1712262469.273 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-04-04T20:27:49.278Z,1712262469.278 [DropWeight] Loaded
2024-04-04T20:27:49.278Z,1712262469.278 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-04-04T20:27:49.339Z,1712262469.339 [NAL9602] Loaded
2024-04-04T20:27:49.339Z,1712262469.339 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-04-04T20:27:49.369Z,1712262469.369 [Onboard] Loaded
2024-04-04T20:27:49.369Z,1712262469.369 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-04-04T20:27:49.370Z,1712262469.370 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408F24E0
2024-04-04T20:27:49.370Z,1712262469.370 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3671
2024-04-04T20:27:49.383Z,1712262469.383 [Power24vConverter] Loaded
2024-04-04T20:27:49.383Z,1712262469.383 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-04-04T20:27:49.396Z,1712262469.396 [Radio_Surface] Loaded
2024-04-04T20:27:49.396Z,1712262469.396 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-04-04T20:27:49.398Z,1712262469.398 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409224E0
2024-04-04T20:27:49.398Z,1712262469.398 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3672
2024-04-04T20:27:49.411Z,1712262469.411 [Sonardyne_Nano] Loaded
2024-04-04T20:27:49.412Z,1712262469.412 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-04-04T20:27:49.501Z,1712262469.501 [DAT] Loaded
2024-04-04T20:27:49.501Z,1712262469.501 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-04-04T20:27:49.502Z,1712262469.502 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409524E0
2024-04-04T20:27:49.503Z,1712262469.503 [DAT ThreadHandler](INFO): Protected caller Thread ID is 3673
2024-04-04T20:27:49.532Z,1712262469.532 [Waterlinked] Loaded
2024-04-04T20:27:49.533Z,1712262469.533 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2024-04-04T20:27:49.533Z,1712262469.533 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-04-04T20:27:49.534Z,1712262469.534 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-04-04T20:27:49.544Z,1712262469.544 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-04-04T20:27:49.545Z,1712262469.545 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-04-04T20:27:49.571Z,1712262469.571 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-04-04T20:27:49.572Z,1712262469.572 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-04-04T20:27:49.714Z,1712262469.714 [SBIT](DEBUG): Construct Startup Built In Test.
2024-04-04T20:27:49.723Z,1712262469.723 [SBIT] Loaded
2024-04-04T20:27:49.723Z,1712262469.723 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-04-04T20:27:49.726Z,1712262469.726 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-04-04T20:27:49.739Z,1712262469.739 [IBIT] Loaded
2024-04-04T20:27:49.739Z,1712262469.739 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-04-04T20:27:49.745Z,1712262469.745 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-04-04T20:27:49.847Z,1712262469.847 [CBIT] Loaded
2024-04-04T20:27:49.847Z,1712262469.847 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-04-04T20:27:49.848Z,1712262469.848 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-04-04T20:27:49.848Z,1712262469.848 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-04-04T20:27:50.186Z,1712262470.186 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-04-04T20:27:50.187Z,1712262470.187 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-04-04T20:27:50.239Z,1712262470.239 [DepthRateCalculator] Loaded
2024-04-04T20:27:50.239Z,1712262470.239 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-04-04T20:27:50.244Z,1712262470.244 [PitchRateCalculator] Loaded
2024-04-04T20:27:50.244Z,1712262470.244 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-04-04T20:27:50.254Z,1712262470.254 [SpeedCalculator] Loaded
2024-04-04T20:27:50.254Z,1712262470.254 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-04-04T20:27:50.259Z,1712262470.259 [YawRateCalculator] Loaded
2024-04-04T20:27:50.259Z,1712262470.259 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-04-04T20:27:50.277Z,1712262470.277 [ElevatorOffsetCalculator] Loaded
2024-04-04T20:27:50.278Z,1712262470.278 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-04-04T20:27:50.278Z,1712262470.278 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-04-04T20:27:50.279Z,1712262470.279 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-04-04T20:27:50.427Z,1712262470.427 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-04-04T20:27:50.427Z,1712262470.427 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-04-04T20:27:50.539Z,1712262470.539 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-04-04T20:27:50.539Z,1712262470.539 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-04-04T20:27:50.580Z,1712262470.580 [DeadReckonUsingSpeedCalculator] Loaded
2024-04-04T20:27:50.581Z,1712262470.581 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2024-04-04T20:27:50.625Z,1712262470.625 [DeadReckonWithRespectToSeafloor] Loaded
2024-04-04T20:27:50.625Z,1712262470.625 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2024-04-04T20:27:50.638Z,1712262470.638 [NavChart] Loaded
2024-04-04T20:27:50.639Z,1712262470.639 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-04-04T20:27:50.644Z,1712262470.644 [UniversalFixResidualReporter] Loaded
2024-04-04T20:27:50.645Z,1712262470.645 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-04-04T20:27:50.645Z,1712262470.645 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-04-04T20:27:50.646Z,1712262470.646 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-04-04T20:27:50.776Z,1712262470.776 [BuoyancyServo] Loaded
2024-04-04T20:27:50.776Z,1712262470.776 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-04-04T20:27:50.796Z,1712262470.796 [ElevatorServo] Loaded
2024-04-04T20:27:50.797Z,1712262470.797 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-04-04T20:27:50.816Z,1712262470.816 [MassServo] Loaded
2024-04-04T20:27:50.817Z,1712262470.817 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-04-04T20:27:50.835Z,1712262470.835 [RudderServo] Loaded
2024-04-04T20:27:50.835Z,1712262470.835 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-04-04T20:27:50.850Z,1712262470.850 [ThrusterHE] Loaded
2024-04-04T20:27:50.850Z,1712262470.850 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-04-04T20:27:50.851Z,1712262470.851 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-04-04T20:27:50.851Z,1712262470.851 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-04-04T20:27:51.025Z,1712262471.025 [CTD_Seabird] Loaded
2024-04-04T20:27:51.025Z,1712262471.025 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-04-04T20:27:51.026Z,1712262471.026 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BC34E0
2024-04-04T20:27:51.027Z,1712262471.027 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3674
2024-04-04T20:27:51.060Z,1712262471.060 [ESPComponent] Loaded
2024-04-04T20:27:51.060Z,1712262471.060 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2024-04-04T20:27:51.080Z,1712262471.080 [PAR_Licor] Loaded
2024-04-04T20:27:51.080Z,1712262471.080 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-04-04T20:27:51.120Z,1712262471.120 [WetLabsBB2FL] Loaded
2024-04-04T20:27:51.121Z,1712262471.121 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-04-04T20:27:51.122Z,1712262471.122 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BF34E0
2024-04-04T20:27:51.122Z,1712262471.122 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3675
2024-04-04T20:27:51.123Z,1712262471.123 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-04-04T20:27:51.129Z,1712262471.129 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-04-04T20:27:51.132Z,1712262471.132 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-04-04T20:27:51.143Z,1712262471.143 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-04-04T20:27:51.144Z,1712262471.144 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C234E0
2024-04-04T20:27:51.144Z,1712262471.144 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3676
2024-04-04T20:27:51.148Z,1712262471.148 [Supervisor](INFO): Main Thread ID is 3581
2024-04-04T20:27:51.148Z,1712262471.148 [Supervisor](DEBUG): Running supervisor.
2024-04-04T20:27:51.149Z,1712262471.149 [CommandExec ThreadHandler](INFO): Handler Thread ID is 3677
2024-04-04T20:27:51.150Z,1712262471.150 [CommandExec](INFO): Initializing the command executive.
2024-04-04T20:27:51.151Z,1712262471.151 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3678
2024-04-04T20:27:51.153Z,1712262471.153 [controlThread ThreadHandler](INFO): Handler Thread ID is 3679
2024-04-04T20:27:51.154Z,1712262471.154 [controlThread](DEBUG): Initializing ControlThread
2024-04-04T20:27:51.155Z,1712262471.155 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-04-04T20:27:51.156Z,1712262471.156 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-04-04T20:27:51.157Z,1712262471.157 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-04-04T20:27:51.158Z,1712262471.158 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-04-04T20:27:51.164Z,1712262471.164 [SBIT](INFO): Initialize SBIT Component.
2024-04-04T20:27:51.164Z,1712262471.164 [SBIT](IMPORTANT): git: 2024-03-18
2024-04-04T20:27:51.164Z,1712262471.164 [SBIT](INFO): git hash: d75f12dcd22668161297bf4aa5fb732dee116921
2024-04-04T20:27:51.165Z,1712262471.165 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-04-04T20:27:51.166Z,1712262471.166 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #1 PREEMPT Tue Nov 28 15:04:22 PST 2023
2024-04-04T20:27:51.167Z,1712262471.167 [SBIT](INFO): Beginning SBIT in 88.000000 seconds.
2024-04-04T20:27:51.168Z,1712262471.168 [IBIT](INFO): Initialize IBIT Component.
2024-04-04T20:27:51.168Z,1712262471.168 [CBIT](DEBUG): Initialize CBIT Component.
2024-04-04T20:27:51.169Z,1712262471.169 [logger ThreadHandler](INFO): Handler Thread ID is 3680
2024-04-04T20:27:51.181Z,1712262471.181 [CBIT](DEBUG): Initialized mux pins.
2024-04-04T20:27:51.181Z,1712262471.181 [CBIT](DEBUG): Initializing the watchdog timer.
2024-04-04T20:27:51.189Z,1712262471.189 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 3681
2024-04-04T20:27:51.201Z,1712262471.201 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3682
2024-04-04T20:27:51.203Z,1712262471.203 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-04-04T20:27:51.205Z,1712262471.205 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-04-04T20:27:51.206Z,1712262471.206 [CBIT](DEBUG): Initializing heartbeat.
2024-04-04T20:27:51.213Z,1712262471.213 [Onboard ThreadHandler](INFO): Handler Thread ID is 3683
2024-04-04T20:27:51.231Z,1712262471.231 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3684
2024-04-04T20:27:51.250Z,1712262471.250 [DAT ThreadHandler](INFO): Handler Thread ID is 3685
2024-04-04T20:27:51.251Z,1712262471.251 [DAT](INFO): Powering up
2024-04-04T20:27:51.251Z,1712262471.251 [DAT](DEBUG): Initializing DAT.
2024-04-04T20:27:51.255Z,1712262471.255 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3687
2024-04-04T20:27:51.255Z,1712262471.255 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-04-04T20:27:51.258Z,1712262471.258 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3689
2024-04-04T20:27:51.261Z,1712262471.261 [WetLabsBB2FL](INFO): Powering up
2024-04-04T20:27:51.263Z,1712262471.263 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3691
2024-04-04T20:27:51.266Z,1712262471.266 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000
2024-04-04T20:27:51.266Z,1712262471.266 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000
2024-04-04T20:27:51.266Z,1712262471.266 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000
2024-04-04T20:27:51.266Z,1712262471.266 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000
2024-04-04T20:27:51.267Z,1712262471.267 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2024-04-04T20:27:51.267Z,1712262471.267 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2024-04-04T20:27:51.267Z,1712262471.267 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2024-04-04T20:27:51.267Z,1712262471.267 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2024-04-04T20:27:51.267Z,1712262471.267 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000
2024-04-04T20:27:51.267Z,1712262471.267 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000
2024-04-04T20:27:51.267Z,1712262471.267 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000
2024-04-04T20:27:51.268Z,1712262471.268 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000
2024-04-04T20:27:51.277Z,1712262471.277 [CBIT](DEBUG): Deactivating GF circuits.
2024-04-04T20:27:51.277Z,1712262471.277 [CBIT](DEBUG): Deactivating emergency mode.
2024-04-04T20:27:51.317Z,1712262471.317 [CBIT](DEBUG): Backplane powered.
2024-04-04T20:27:51.317Z,1712262471.317 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-04-04T20:27:51.318Z,1712262471.318 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-04-04T20:27:51.318Z,1712262471.318 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-04-04T20:27:51.318Z,1712262471.318 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-04-04T20:27:51.319Z,1712262471.319 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-04-04T20:27:51.320Z,1712262471.320 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-04T20:27:51.321Z,1712262471.321 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-04-04T20:27:51.321Z,1712262471.321 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-04-04T20:27:51.326Z,1712262471.326 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-04-04T20:27:51.326Z,1712262471.326 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-04-04T20:27:51.327Z,1712262471.327 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-04-04T20:27:51.328Z,1712262471.328 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-04-04T20:27:51.405Z,1712262471.405 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-04-04T20:27:51.426Z,1712262471.426 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-04-04T20:27:51.452Z,1712262471.452 [MissionManager](DEBUG):
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2024-04-04T20:27:51.453Z,1712262471.453 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-04-04T20:27:51.453Z,1712262471.453 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-04-04T20:27:51.454Z,1712262471.454 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-04-04T20:27:51.637Z,1712262471.637 [Radio_Surface](INFO): Powering up
2024-04-04T20:27:51.770Z,1712262471.770 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-04-04T20:27:51.780Z,1712262471.780 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-04-04T20:27:51.781Z,1712262471.781 [Default:A.Wait](DEBUG): Construct Wait.
2024-04-04T20:27:51.783Z,1712262471.783 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-04-04T20:27:51.832Z,1712262471.832 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-04-04T20:27:51.851Z,1712262471.851 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-04-04T20:27:51.857Z,1712262471.857 [Default:E.Execute](DEBUG): Construct Execute.
2024-04-04T20:27:51.875Z,1712262471.875 [MissionManager](DEBUG):
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2024-04-04T20:27:51.881Z,1712262471.881 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,Waterlinked,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-04-04T20:27:51.887Z,1712262471.887 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-04-04T20:27:51.951Z,1712262471.951 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m
2024-04-04T20:27:51.952Z,1712262471.952 [Power24vConverter](INFO): Powering up.
2024-04-04T20:27:51.953Z,1712262471.953 [Sonardyne_Nano](INFO): Initializing.
2024-04-04T20:27:52.014Z,1712262472.014 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-04-04T20:27:52.044Z,1712262472.044 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-04-04T20:27:52.053Z,1712262472.053 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-04-04T20:27:52.054Z,1712262472.054 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-04-04T20:27:52.061Z,1712262472.061 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-04-04T20:27:52.062Z,1712262472.062 [MassServo](DEBUG): Initializing EZServoServo.
2024-04-04T20:27:52.069Z,1712262472.069 [MassServo](DEBUG): Initializing MassServo.
2024-04-04T20:27:52.070Z,1712262472.070 [RudderServo](DEBUG): Initializing EZServoServo.
2024-04-04T20:27:52.077Z,1712262472.077 [RudderServo](DEBUG): Initializing RudderServo.
2024-04-04T20:27:52.078Z,1712262472.078 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-04-04T20:27:52.089Z,1712262472.089 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-04-04T20:27:52.351Z,1712262472.351 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2024-04-04T20:27:52.351Z,1712262472.351 [DropWeight] Hardware Fault, FailCount= 1
2024-04-04T20:27:52.351Z,1712262472.351 [DropWeight](ERROR): Hardware Fault
2024-04-04T20:27:52.393Z,1712262472.393 [CommandExec](FAULT): Scheduling is paused
2024-04-04T20:27:52.393Z,1712262472.393 [CBIT](INFO): Critical error at 20240404T202752
2024-04-04T20:27:52.394Z,1712262472.394 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-04-04T20:27:52.396Z,1712262472.396 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-04-04T20:27:52.397Z,1712262472.397 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-04-04T20:27:52.720Z,1712262472.720 [CBIT](INFO): Critical error at 20240404T202752
2024-04-04T20:27:53.369Z,1712262473.369 [WetLabsBB2FL](INFO): Powering down
2024-04-04T20:27:53.911Z,1712262473.911 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-04-04T20:27:55.197Z,1712262475.197 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311
2024-04-04T20:27:57.794Z,1712262477.794 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-04-04T20:28:03.602Z,1712262483.602 [DAT](INFO): DAT read:
2024-04-04T20:28:03.603Z,1712262483.603 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-04-04T20:28:05.032Z,1712262485.032 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005251
2024-04-04T20:28:05.114Z,1712262485.114 [DAT](INFO): DAT read: MF Frequency Band
2024-04-04T20:28:05.115Z,1712262485.115 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-04-04T20:28:05.116Z,1712262485.116 [DAT](INFO): DAT read: Apr 3 2024 22:39:14
2024-04-04T20:28:06.122Z,1712262486.122 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-04-04T20:28:06.123Z,1712262486.123 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2024-04-04T20:28:06.124Z,1712262486.124 [DAT](INFO): commRate: 800
2024-04-04T20:28:08.189Z,1712262488.189 [DAT](INFO): entering command mode
2024-04-04T20:28:08.390Z,1712262488.390 [DAT](INFO): DAT read:
2024-04-04T20:28:08.390Z,1712262488.390 [DAT](INFO): DAT read: user:1>
2024-04-04T20:28:08.391Z,1712262488.391 [DAT](INFO): setting verbose to 3
2024-04-04T20:28:08.642Z,1712262488.642 [DAT](INFO): DAT read: user:1>
2024-04-04T20:28:08.643Z,1712262488.643 [DAT](INFO): DAT read: Verbose | 3
2024-04-04T20:28:08.643Z,1712262488.643 [DAT](INFO): set verbose to 3
2024-04-04T20:28:08.643Z,1712262488.643 [DAT](INFO): setting DatVerbose to 27440
2024-04-04T20:28:08.894Z,1712262488.894 [DAT](INFO): DAT read: user:2>
2024-04-04T20:28:08.895Z,1712262488.895 [DAT](INFO): DAT read: DatVerbose | 27440
2024-04-04T20:28:08.895Z,1712262488.895 [DAT](INFO): set DatVerbose to 27440
2024-04-04T20:28:08.895Z,1712262488.895 [DAT](INFO): setting transmit power to 8
2024-04-04T20:28:09.146Z,1712262489.146 [DAT](INFO): DAT read: user:3>
2024-04-04T20:28:09.147Z,1712262489.147 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-04-04T20:28:09.147Z,1712262489.147 [DAT](INFO): set transmit power to 8
2024-04-04T20:28:09.147Z,1712262489.147 [DAT](INFO): setting local address to 1
2024-04-04T20:28:09.398Z,1712262489.398 [DAT](INFO): DAT read: user:4>
2024-04-04T20:28:09.399Z,1712262489.399 [DAT](INFO): DAT read: LocalAddr | 1
2024-04-04T20:28:09.399Z,1712262489.399 [DAT](INFO): set local address to 1
2024-04-04T20:28:09.400Z,1712262489.400 [DAT](INFO): Setting time to: 20:28:9 And date to:4/4/2024
2024-04-04T20:28:09.650Z,1712262489.650 [DAT](INFO): DAT read: user:5>
2024-04-04T20:28:09.651Z,1712262489.651 [DAT](INFO): DAT read: Thu Apr 4, 2024 20:28:09
2024-04-04T20:28:09.651Z,1712262489.651 [DAT](INFO): Local DAT time set to Thu Apr 4, 2024 20:28:09
2024-04-04T20:28:19.392Z,1712262499.392 [NAL9602](INFO): Powering up NAL9602
2024-04-04T20:28:19.586Z,1712262499.586 [Waterlinked](ERROR): only read 0 of 1 data item for altitude. Device response::BD, +0. +0.00, +0.00, 0.09, 0.00
2024-04-04T20:28:30.322Z,1712262510.322 [NAL9602](INFO): NAL9602 initialized
2024-04-04T20:28:46.611Z,1712262526.611 [Waterlinked](ERROR): only read 0 of 1 data item for altitude. Device response::BD, +0.00, +0. +0.00, 000
2024-04-04T20:28:53.447Z,1712262533.447 [Waterlinked](ERROR): only read 0 of 1 data item for BIT error. Device response::TS,22032019562129, 0.0, 0.0,1475.0, 0
2024-04-04T20:29:11.685Z,1712262551.685 [Waterlinked](INFO): Powering down
2024-04-04T20:29:19.683Z,1712262559.683 [SBIT](IMPORTANT): Beginning Startup BIT
2024-04-04T20:29:19.688Z,1712262559.688 [CBIT](IMPORTANT): Beginning ground fault scan
2024-04-04T20:29:30.762Z,1712262570.762 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000524
CHAN A1 (24V): 0.000154
CHAN A2 (12V): 0.000312
CHAN A3 (5V): -0.001395
CHAN B0 (3.3V): -0.000286
CHAN B1 (3.15aV): 0.000536
CHAN B2 (3.15bV): -0.000434
CHAN B3 (GND): 0.000027
OPEN: 0.006539
Full Scale: +/- 1 mA
2024-04-04T20:29:39.099Z,1712262579.099 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-04-04T20:29:45.902Z,1712262585.902 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-04T20:30:01.256Z,1712262601.256 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-04T20:30:13.927Z,1712262613.927 [SBIT](IMPORTANT): SBIT PASSED
2024-04-04T20:30:13.927Z,1712262613.927 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-04-04T20:30:13.928Z,1712262613.928 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count;
2024-04-04T20:30:13.928Z,1712262613.928 [SBIT](IMPORTANT): BuoyancyServo.pidW=3000 count;
2024-04-04T20:30:13.928Z,1712262613.928 [SBIT](IMPORTANT): BuoyancyServo.pidX=0 count;
2024-04-04T20:30:13.928Z,1712262613.928 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=1 bool;
2024-04-04T20:30:13.929Z,1712262613.929 [SBIT](IMPORTANT): CBIT.stopDepth=290 meter;
2024-04-04T20:30:13.929Z,1712262613.929 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_mean_sea_water_temperature;
2024-04-04T20:30:13.929Z,1712262613.929 [SBIT](IMPORTANT): Express linearApproximation CTD_Seabird.bin_median_sea_water_salinity 0.030000 practical_salinity_unit;
2024-04-04T20:30:13.929Z,1712262613.929 [SBIT](IMPORTANT): Express linearApproximation CTD_Seabird.bin_median_sea_water_temperature 1.000000 celsius;
2024-04-04T20:30:13.929Z,1712262613.929 [SBIT](IMPORTANT): Express linearApproximation DAT.acoustic_contact_range 10.000000 meter;
2024-04-04T20:30:13.929Z,1712262613.929 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;
2024-04-04T20:30:13.930Z,1712262613.930 [SBIT](IMPORTANT): Express linearApproximation WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 0.300000 microgram_per_liter;
2024-04-04T20:30:13.930Z,1712262613.930 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_HM_AvgRois 0.600000 count_per_second;
2024-04-04T20:30:13.930Z,1712262613.930 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 0.600000 count_per_second;
2024-04-04T20:30:13.930Z,1712262613.930 [SBIT](IMPORTANT): Express linearApproximation depth 5.000000 meter;
2024-04-04T20:30:13.930Z,1712262613.930 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;
2024-04-04T20:30:13.930Z,1712262613.930 [SBIT](IMPORTANT): Vehicle.useTlLoader=1 bool;
2024-04-04T20:30:13.930Z,1712262613.930 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=197.596302 cubic_centimeter;
2024-04-04T20:30:13.930Z,1712262613.930 [SBIT](IMPORTANT): VerticalControl.kdPitchMass=0.9375 second;
2024-04-04T20:30:13.931Z,1712262613.931 [SBIT](IMPORTANT): VerticalControl.kiPitchMass=0.9375 reciprocal_second;
2024-04-04T20:30:13.931Z,1712262613.931 [SBIT](IMPORTANT): VerticalControl.kpPitchMass=0.9375 none;
2024-04-04T20:30:13.931Z,1712262613.931 [SBIT](IMPORTANT): VerticalControl.massDefault=17.109584 millimeter;
2024-04-04T20:30:13.931Z,1712262613.931 [SBIT](IMPORTANT): VerticalControl.massOnlyForceInt=1 bool;
2024-04-04T20:30:14.297Z,1712262614.297 [MissionManager](IMPORTANT): Started mission Startup
2024-04-04T20:30:14.297Z,1712262614.297 [Startup] Running Loop=1
2024-04-04T20:30:14.298Z,1712262614.298 [Startup](DEBUG): Aggregate::initialize Startup
2024-04-04T20:30:14.298Z,1712262614.298 [Startup:A.GoToSurface] Running Loop=1
2024-04-04T20:30:14.298Z,1712262614.298 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-04-04T20:30:14.298Z,1712262614.298 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-04-04T20:30:14.299Z,1712262614.299 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-04-04T20:30:14.299Z,1712262614.299 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-04-04T20:30:14.299Z,1712262614.299 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-04-04T20:30:14.300Z,1712262614.300 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-04-04T20:30:14.317Z,1712262614.317 [Startup:StartupSatComms] Running Loop=1
2024-04-04T20:30:14.318Z,1712262614.318 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-04-04T20:30:14.318Z,1712262614.318 [Startup:StartupSatComms:A] Running Loop=1
2024-04-04T20:30:14.647Z,1712262614.647 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-04-04T20:30:16.606Z,1712262616.606 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-04T20:30:31.554Z,1712262631.554 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-04T20:30:46.907Z,1712262646.907 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-04T20:30:51.404Z,1712262651.404 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-04-04T20:30:51.404Z,1712262651.404 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-04T20:30:51.405Z,1712262651.405 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2024-04-04T20:30:51.405Z,1712262651.405 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-04-04T20:30:51.407Z,1712262651.407 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2024-04-04T20:30:51.407Z,1712262651.407 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-04-04T20:30:51.418Z,1712262651.418 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-04T20:30:51.418Z,1712262651.418 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-04-04T20:30:51.418Z,1712262651.418 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-04-04T20:30:51.784Z,1712262651.784 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-04T20:30:51.784Z,1712262651.784 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-04-04T20:30:51.786Z,1712262651.786 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-04-04T20:30:51.786Z,1712262651.786 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2024-04-04T20:30:51.786Z,1712262651.786 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-04-04T20:30:51.786Z,1712262651.786 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2024-04-04T20:30:52.182Z,1712262652.182 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-04T20:30:57.298Z,1712262657.298 [CommandExec](IMPORTANT): got command failComponent
2024-04-04T20:30:57.298Z,1712262657.298 [CommandExec](IMPORTANT): Failed components:
2024-04-04T20:30:57.298Z,1712262657.298 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2024-04-04T20:31:01.054Z,1712262661.054 [CommandExec](IMPORTANT): got command failComponent
2024-04-04T20:31:01.054Z,1712262661.054 [CommandExec](IMPORTANT): Failed components:
2024-04-04T20:31:01.054Z,1712262661.054 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2024-04-04T20:31:03.077Z,1712262663.077 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-04T20:31:04.694Z,1712262664.694 [CommandExec](IMPORTANT): got command gfscan
2024-04-04T20:31:04.731Z,1712262664.731 [CBIT](IMPORTANT): Beginning ground fault scan
2024-04-04T20:31:14.448Z,1712262674.448 [Startup:StartupSatComms:A](INFO): Timed out from 2024-04-04T20:30:14.3Z
2024-04-04T20:31:14.448Z,1712262674.448 [Startup:StartupSatComms:A] Stopped
2024-04-04T20:31:14.448Z,1712262674.448 [Startup:StartupSatComms:B] Running Loop=1
2024-04-04T20:31:14.832Z,1712262674.832 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-04-04T20:31:15.651Z,1712262675.651 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000362
CHAN A1 (24V): 0.000182
CHAN A2 (12V): -0.000428
CHAN A3 (5V): -0.001590
CHAN B0 (3.3V): 0.000078
CHAN B1 (3.15aV): -0.000116
CHAN B2 (3.15bV): 0.000080
CHAN B3 (GND): -0.000283
OPEN: 0.006789
Full Scale: +/- 1 mA
2024-04-04T20:31:18.015Z,1712262678.015 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-04T20:31:21.301Z,1712262681.301 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20240403T222352/Courier0004.lzma
2024-04-04T20:31:22.279Z,1712262682.279 [DataOverHttps](INFO): Moved sent file to Logs/20240403T222352/Courier0004.lzma.bak
2024-04-04T20:31:22.279Z,1712262682.279 [DataOverHttps](INFO): SBD MOMSN=19378561
2024-04-04T20:31:29.071Z,1712262689.071 [CommandExec](IMPORTANT): got command get Onboard.Pressure
2024-04-04T20:31:29.075Z,1712262689.075 [CommandExec](IMPORTANT): Onboard.Pressure 68454.593750 Pa
2024-04-04T20:31:31.731Z,1712262691.731 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch
2024-04-04T20:31:31.732Z,1712262691.732 [CommandExec](IMPORTANT): Onboard.Pressure 9.918434 psi
2024-04-04T20:31:32.962Z,1712262692.962 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-04T20:31:38.166Z,1712262698.166 [CommandExec](IMPORTANT): got command get Onboard.Humidity
2024-04-04T20:31:38.167Z,1712262698.167 [CommandExec](IMPORTANT): Onboard.Humidity 19.576365 %
2024-04-04T20:31:40.009Z,1712262700.009 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20240404T202741/Courier0000.lzma
2024-04-04T20:31:41.011Z,1712262701.011 [DataOverHttps](INFO): Moved sent file to Logs/20240404T202741/Courier0000.lzma.bak
2024-04-04T20:31:41.011Z,1712262701.011 [DataOverHttps](INFO): SBD MOMSN=19378563
2024-04-04T20:31:47.910Z,1712262707.910 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-04T20:31:56.683Z,1712262716.683 [DataOverHttps](INFO): Sending 1326 bytes from file Logs/20240403T222010/Express0001.lzma
2024-04-04T20:31:57.683Z,1712262717.683 [DataOverHttps](INFO): Moved sent file to Logs/20240403T222010/Express0001.lzma.bak
2024-04-04T20:31:57.683Z,1712262717.683 [DataOverHttps](INFO): SBD MOMSN=19378569
2024-04-04T20:32:13.396Z,1712262733.396 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240403T222010/Express0005.lzma
2024-04-04T20:32:14.399Z,1712262734.399 [DataOverHttps](INFO): Moved sent file to Logs/20240403T222010/Express0005.lzma.bak
2024-04-04T20:32:14.399Z,1712262734.399 [DataOverHttps](INFO): SBD MOMSN=19378613
2024-04-04T20:32:14.617Z,1712262734.617 [Startup:StartupSatComms:B](INFO): Timed out from 2024-04-04T20:31:14.4Z
2024-04-04T20:32:14.617Z,1712262734.617 [Startup:StartupSatComms:B] Stopped
2024-04-04T20:32:14.617Z,1712262734.617 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-04-04T20:32:14.617Z,1712262734.617 [Startup:StartupSatComms] Stopped
2024-04-04T20:32:14.617Z,1712262734.617 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-04-04T20:32:14.618Z,1712262734.618 [Startup](INFO): Completed Startup
2024-04-04T20:32:14.618Z,1712262734.618 [MissionManager](INFO): Startup is completed.
2024-04-04T20:32:14.618Z,1712262734.618 [MissionManager](INFO): Uninitializing Mission Startup
2024-04-04T20:32:14.618Z,1712262734.618 [Startup] Stopped
2024-04-04T20:32:14.619Z,1712262734.619 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-04-04T20:32:14.619Z,1712262734.619 [Startup:A.GoToSurface] Stopped
2024-04-04T20:32:14.619Z,1712262734.619 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-04-04T20:32:15.065Z,1712262735.065 [MissionManager](IMPORTANT): Started mission Default
2024-04-04T20:32:15.065Z,1712262735.065 [Default] Running Loop=1
2024-04-04T20:32:15.065Z,1712262735.065 [Default](DEBUG): Aggregate::initialize Default
2024-04-04T20:32:15.065Z,1712262735.065 [Default:B.GoToSurface] Running Loop=1
2024-04-04T20:32:15.065Z,1712262735.065 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-04-04T20:32:15.066Z,1712262735.066 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-04-04T20:32:15.066Z,1712262735.066 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-04-04T20:32:15.066Z,1712262735.066 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-04-04T20:32:15.067Z,1712262735.067 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-04-04T20:32:15.067Z,1712262735.067 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-04-04T20:32:15.067Z,1712262735.067 [Default:A.Wait] Running Loop=1
2024-04-04T20:32:15.067Z,1712262735.067 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-04-04T20:32:28.341Z,1712262748.341 [Default:A.Wait](INFO): Done Waiting.
2024-04-04T20:32:28.342Z,1712262748.342 [Default:A.Wait] Stopped
2024-04-04T20:32:28.342Z,1712262748.342 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-04-04T20:32:28.741Z,1712262748.741 [Default:CheckIn] Running Loop=1
2024-04-04T20:32:28.742Z,1712262748.742 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-04T20:32:28.742Z,1712262748.742 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-04T20:32:29.138Z,1712262749.138 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-04-04T20:33:34.172Z,1712262814.172 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-04-04T20:33:52.391Z,1712262832.391 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-04-04T20:33:52.391Z,1712262832.391 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-04T20:33:52.392Z,1712262832.392 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2024-04-04T20:33:52.392Z,1712262832.392 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-04-04T20:33:52.394Z,1712262832.394 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2024-04-04T20:33:52.394Z,1712262832.394 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-04-04T20:33:52.417Z,1712262832.417 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-04T20:33:52.417Z,1712262832.417 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-04-04T20:33:52.418Z,1712262832.418 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-04-04T20:33:52.820Z,1712262832.820 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-04T20:33:52.820Z,1712262832.820 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-04-04T20:33:52.820Z,1712262832.820 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-04-04T20:33:52.820Z,1712262832.820 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2024-04-04T20:33:52.821Z,1712262832.821 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-04-04T20:33:52.821Z,1712262832.821 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2024-04-04T20:33:53.214Z,1712262833.214 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-04T20:34:30.318Z,1712262870.318 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-04-04T20:34:30.325Z,1712262870.325 [BPC1](INFO): Calculating totals. Valid battery stick count: 45. Valid reserve battery stick count: 5.
2024-04-04T20:34:30.328Z,1712262870.328 [BPC1](INFO): Received data from all battery sticks.
2024-04-04T20:36:53.369Z,1712263013.369 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-04-04T20:36:53.369Z,1712263013.369 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-04T20:36:53.370Z,1712263013.370 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2024-04-04T20:36:53.370Z,1712263013.370 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-04-04T20:36:53.372Z,1712263013.372 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2024-04-04T20:36:53.372Z,1712263013.372 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-04-04T20:36:53.396Z,1712263013.396 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-04T20:36:53.396Z,1712263013.396 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-04-04T20:36:53.396Z,1712263013.396 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-04-04T20:36:53.795Z,1712263013.795 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-04T20:36:53.795Z,1712263013.795 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-04-04T20:36:53.795Z,1712263013.795 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-04-04T20:36:53.795Z,1712263013.795 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2024-04-04T20:36:53.796Z,1712263013.796 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-04-04T20:36:53.796Z,1712263013.796 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2024-04-04T20:36:54.169Z,1712263014.169 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-04T20:37:28.916Z,1712263048.916 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-04T20:32:28.7Z
2024-04-04T20:37:28.917Z,1712263048.917 [Default:CheckIn:Read_GPS] Stopped
2024-04-04T20:37:28.917Z,1712263048.917 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-04T20:37:29.329Z,1712263049.329 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-04-04T20:37:36.317Z,1712263056.317 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20240404T202741/Courier0004.lzma
2024-04-04T20:37:37.319Z,1712263057.319 [DataOverHttps](INFO): Moved sent file to Logs/20240404T202741/Courier0004.lzma.bak
2024-04-04T20:37:37.319Z,1712263057.319 [DataOverHttps](INFO): SBD MOMSN=19378621
2024-04-04T20:37:52.992Z,1712263072.992 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20240403T222010/Express0008.lzma
2024-04-04T20:37:53.995Z,1712263073.995 [DataOverHttps](INFO): Moved sent file to Logs/20240403T222010/Express0008.lzma.bak
2024-04-04T20:37:53.995Z,1712263073.995 [DataOverHttps](INFO): SBD MOMSN=19378623
2024-04-04T20:38:09.583Z,1712263089.583 [DataOverHttps](INFO): Sending 1215 bytes from file Logs/20240403T222352/Express0001.lzma
2024-04-04T20:38:10.583Z,1712263090.583 [DataOverHttps](INFO): Moved sent file to Logs/20240403T222352/Express0001.lzma.bak
2024-04-04T20:38:10.583Z,1712263090.583 [DataOverHttps](INFO): SBD MOMSN=19378626
2024-04-04T20:38:26.149Z,1712263106.149 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20240403T222352/Express0005.lzma
2024-04-04T20:38:27.151Z,1712263107.151 [DataOverHttps](INFO): Moved sent file to Logs/20240403T222352/Express0005.lzma.bak
2024-04-04T20:38:27.151Z,1712263107.151 [DataOverHttps](INFO): SBD MOMSN=19378662
2024-04-04T20:38:32.322Z,1712263112.322 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-04-04T20:38:32.322Z,1712263112.322 [NAL9602] Data Fault, FailCount= 1
2024-04-04T20:38:32.322Z,1712263112.322 [NAL9602](ERROR): Data Fault
2024-04-04T20:38:32.401Z,1712263112.401 [CBIT](ERROR): Data Fault in component: NAL9602
2024-04-04T20:38:32.726Z,1712263112.726 [NAL9602](INFO): Powering down
2024-04-04T20:38:33.561Z,1712263113.561 [CBIT](INFO): Clearing failed state for component NAL9602
2024-04-04T20:38:33.561Z,1712263113.561 [NAL9602] No Fault, FailCount= 1
2024-04-04T20:38:42.735Z,1712263122.735 [DataOverHttps](INFO): Sending 1351 bytes from file Logs/20240404T202741/Express0001.lzma
2024-04-04T20:38:43.735Z,1712263123.735 [DataOverHttps](INFO): Moved sent file to Logs/20240404T202741/Express0001.lzma.bak
2024-04-04T20:38:43.735Z,1712263123.735 [DataOverHttps](INFO): SBD MOMSN=19378667
2024-04-04T20:38:59.285Z,1712263139.285 [DataOverHttps](INFO): Sending 465 bytes from file Logs/20240404T202741/Express0005.lzma
2024-04-04T20:39:00.287Z,1712263140.287 [DataOverHttps](INFO): Moved sent file to Logs/20240404T202741/Express0005.lzma.bak
2024-04-04T20:39:00.287Z,1712263140.287 [DataOverHttps](INFO): SBD MOMSN=19378711
2024-04-04T20:39:01.841Z,1712263141.841 [Default:CheckIn:Read_Iridium] Stopped
2024-04-04T20:39:01.841Z,1712263141.841 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-04T20:39:01.841Z,1712263141.841 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-04T20:39:03.029Z,1712263143.029 [NAL9602](INFO): Powering up NAL9602
2024-04-04T20:39:13.932Z,1712263153.932 [NAL9602](INFO): NAL9602 initialized
2024-04-04T20:39:54.339Z,1712263194.339 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-04-04T20:39:54.339Z,1712263194.339 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-04T20:39:54.340Z,1712263194.340 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2024-04-04T20:39:54.340Z,1712263194.340 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-04-04T20:39:54.342Z,1712263194.342 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2024-04-04T20:39:54.342Z,1712263194.342 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-04-04T20:39:54.353Z,1712263194.353 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-04T20:39:54.353Z,1712263194.353 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-04-04T20:39:54.353Z,1712263194.353 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-04-04T20:39:54.759Z,1712263194.759 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-04T20:39:54.759Z,1712263194.759 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-04-04T20:39:54.759Z,1712263194.759 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-04-04T20:39:54.759Z,1712263194.759 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2024-04-04T20:39:54.760Z,1712263194.760 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-04-04T20:39:54.760Z,1712263194.760 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2024-04-04T20:39:55.158Z,1712263195.158 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-04T20:42:52.536Z,1712263372.536 [CBIT](INFO): Clearing failed state for component DropWeight
2024-04-04T20:42:52.536Z,1712263372.536 [DropWeight] No Fault, FailCount= 1
2024-04-04T20:42:55.343Z,1712263375.343 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2024-04-04T20:42:55.343Z,1712263375.343 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-04T20:42:55.344Z,1712263375.344 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2024-04-04T20:42:55.344Z,1712263375.344 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-04-04T20:42:55.346Z,1712263375.346 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5
2024-04-04T20:42:55.346Z,1712263375.346 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-04-04T20:42:55.357Z,1712263375.357 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-04T20:42:55.357Z,1712263375.357 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-04-04T20:42:55.357Z,1712263375.357 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-04-04T20:42:55.769Z,1712263375.769 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-04T20:42:55.769Z,1712263375.769 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2024-04-04T20:42:55.770Z,1712263375.770 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-04-04T20:42:55.770Z,1712263375.770 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2024-04-04T20:42:55.770Z,1712263375.770 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-04-04T20:42:55.770Z,1712263375.770 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5
2024-04-04T20:42:56.148Z,1712263376.148 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-04T20:44:02.411Z,1712263442.411 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-04T20:44:02.411Z,1712263442.411 [Default:CheckIn:C.Wait] Stopped
2024-04-04T20:44:02.411Z,1712263442.411 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-04T20:44:02.411Z,1712263442.411 [Default:CheckIn:D] Running Loop=1
2024-04-04T20:44:02.805Z,1712263442.805 [Default:CheckIn:D] Stopped
2024-04-04T20:44:02.805Z,1712263442.805 [Default:CheckIn:E] Running Loop=1
2024-04-04T20:44:03.217Z,1712263443.217 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.795675 min
2024-04-04T20:44:03.217Z,1712263443.217 [Default:CheckIn:E] Stopped
2024-04-04T20:44:03.217Z,1712263443.217 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-04T20:44:03.217Z,1712263443.217 [Default:CheckIn] Stopped
2024-04-04T20:44:03.218Z,1712263443.218 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-04T20:44:03.218Z,1712263443.218 [Default:CheckIn](INFO): Running loop #2
2024-04-04T20:44:03.218Z,1712263443.218 [Default:CheckIn] Running Loop=2
2024-04-04T20:44:03.218Z,1712263443.218 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-04T20:44:03.218Z,1712263443.218 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-04T20:45:56.330Z,1712263556.330 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2024-04-04T20:45:56.330Z,1712263556.330 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-04T20:45:56.331Z,1712263556.331 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2024-04-04T20:45:56.331Z,1712263556.331 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-04-04T20:45:56.332Z,1712263556.332 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6
2024-04-04T20:45:56.333Z,1712263556.333 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-04-04T20:45:56.343Z,1712263556.343 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-04T20:45:56.343Z,1712263556.343 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-04-04T20:45:56.343Z,1712263556.343 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-04-04T20:45:56.768Z,1712263556.768 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-04T20:45:56.768Z,1712263556.768 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2024-04-04T20:45:56.768Z,1712263556.768 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-04-04T20:45:56.768Z,1712263556.768 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2024-04-04T20:45:56.770Z,1712263556.770 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-04-04T20:45:56.770Z,1712263556.770 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6
2024-04-04T20:45:57.165Z,1712263557.165 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-04T20:48:57.336Z,1712263737.336 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2024-04-04T20:48:57.336Z,1712263737.336 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-04T20:48:57.338Z,1712263737.338 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2024-04-04T20:48:57.338Z,1712263737.338 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-04-04T20:48:57.339Z,1712263737.339 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 7
2024-04-04T20:48:57.339Z,1712263737.339 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-04-04T20:48:57.350Z,1712263737.350 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-04T20:48:57.350Z,1712263737.350 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-04-04T20:48:57.350Z,1712263737.350 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-04-04T20:48:57.758Z,1712263737.758 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-04T20:48:57.759Z,1712263737.759 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2024-04-04T20:48:57.759Z,1712263737.759 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-04-04T20:48:57.759Z,1712263737.759 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2024-04-04T20:48:57.760Z,1712263737.760 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-04-04T20:48:57.760Z,1712263737.760 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 7
2024-04-04T20:48:58.130Z,1712263738.130 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-04T20:49:03.406Z,1712263743.406 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-04T20:44:03.2Z
2024-04-04T20:49:03.406Z,1712263743.406 [Default:CheckIn:Read_GPS] Stopped
2024-04-04T20:49:03.406Z,1712263743.406 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-04T20:49:11.004Z,1712263751.004 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240404T202741/Courier0007.lzma
2024-04-04T20:49:12.008Z,1712263752.008 [DataOverHttps](INFO): Moved sent file to Logs/20240404T202741/Courier0007.lzma.bak
2024-04-04T20:49:12.008Z,1712263752.008 [DataOverHttps](INFO): SBD MOMSN=19378775
2024-04-04T20:49:17.519Z,1712263757.519 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-04-04T20:49:17.519Z,1712263757.519 [NAL9602] Data Fault, FailCount= 2
2024-04-04T20:49:17.519Z,1712263757.519 [NAL9602](ERROR): Data Fault
2024-04-04T20:49:17.589Z,1712263757.589 [CBIT](ERROR): Data Fault in component: NAL9602
2024-04-04T20:49:17.918Z,1712263757.918 [NAL9602](INFO): Powering down
2024-04-04T20:49:18.750Z,1712263758.750 [CBIT](INFO): Clearing failed state for component NAL9602
2024-04-04T20:49:18.750Z,1712263758.750 [NAL9602] No Fault, FailCount= 2
2024-04-04T20:49:27.661Z,1712263767.661 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20240404T202741/Express0008.lzma
2024-04-04T20:49:28.663Z,1712263768.663 [DataOverHttps](INFO): Moved sent file to Logs/20240404T202741/Express0008.lzma.bak
2024-04-04T20:49:28.663Z,1712263768.663 [DataOverHttps](INFO): SBD MOMSN=19378777
2024-04-04T20:49:30.083Z,1712263770.083 [Default:CheckIn:Read_Iridium] Stopped
2024-04-04T20:49:30.083Z,1712263770.083 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-04T20:49:30.083Z,1712263770.083 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-04T20:49:48.228Z,1712263788.228 [NAL9602](INFO): Powering up NAL9602
2024-04-04T20:49:59.131Z,1712263799.131 [NAL9602](INFO): NAL9602 initialized
2024-04-04T20:51:58.375Z,1712263918.375 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2024-04-04T20:51:58.375Z,1712263918.375 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-04T20:51:58.376Z,1712263918.376 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2024-04-04T20:51:58.376Z,1712263918.376 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-04-04T20:51:58.378Z,1712263918.378 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 8
2024-04-04T20:51:58.378Z,1712263918.378 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-04-04T20:51:58.421Z,1712263918.421 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-04T20:51:58.421Z,1712263918.421 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-04-04T20:51:58.422Z,1712263918.422 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-04-04T20:51:58.792Z,1712263918.792 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-04T20:51:58.792Z,1712263918.792 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2024-04-04T20:51:58.793Z,1712263918.793 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-04-04T20:51:58.793Z,1712263918.793 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2024-04-04T20:51:58.793Z,1712263918.793 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-04-04T20:51:58.793Z,1712263918.793 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 8
2024-04-04T20:51:59.206Z,1712263919.206 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-04T20:54:30.735Z,1712264070.735 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-04T20:54:30.735Z,1712264070.735 [Default:CheckIn:C.Wait] Stopped
2024-04-04T20:54:30.735Z,1712264070.735 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-04T20:54:30.735Z,1712264070.735 [Default:CheckIn:D] Running Loop=1
2024-04-04T20:54:31.148Z,1712264071.148 [Default:CheckIn:D] Stopped
2024-04-04T20:54:31.148Z,1712264071.148 [Default:CheckIn:E] Running Loop=1
2024-04-04T20:54:31.528Z,1712264071.528 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.268052 min
2024-04-04T20:54:31.529Z,1712264071.529 [Default:CheckIn:E] Stopped
2024-04-04T20:54:31.529Z,1712264071.529 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-04T20:54:31.529Z,1712264071.529 [Default:CheckIn] Stopped
2024-04-04T20:54:31.529Z,1712264071.529 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-04T20:54:31.529Z,1712264071.529 [Default:CheckIn](INFO): Running loop #3
2024-04-04T20:54:31.529Z,1712264071.529 [Default:CheckIn] Running Loop=3
2024-04-04T20:54:31.529Z,1712264071.529 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-04T20:54:31.530Z,1712264071.530 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-04T20:54:59.407Z,1712264099.407 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2024-04-04T20:54:59.407Z,1712264099.407 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-04T20:54:59.409Z,1712264099.409 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2024-04-04T20:54:59.409Z,1712264099.409 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-04-04T20:54:59.410Z,1712264099.410 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 9
2024-04-04T20:54:59.410Z,1712264099.410 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2024-04-04T20:54:59.421Z,1712264099.421 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-04T20:54:59.421Z,1712264099.421 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-04-04T20:54:59.421Z,1712264099.421 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2024-04-04T20:54:59.832Z,1712264099.832 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-04T20:54:59.832Z,1712264099.832 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2024-04-04T20:54:59.832Z,1712264099.832 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-04-04T20:54:59.833Z,1712264099.833 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2024-04-04T20:54:59.833Z,1712264099.833 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2024-04-04T20:54:59.833Z,1712264099.833 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 9
2024-04-04T20:55:00.210Z,1712264100.210 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-04T20:55:20.486Z,1712264120.486 [CommandExec](IMPORTANT): got command failComponent
2024-04-04T20:55:20.486Z,1712264120.486 [CommandExec](IMPORTANT): Failed components:
2024-04-04T20:55:20.486Z,1712264120.486 [CommandExec](IMPORTANT): No failed Components.
2024-04-04T20:55:23.918Z,1712264123.918 [CommandExec](IMPORTANT): got command restart system
2024-04-04T20:55:25.977Z,1712264125.977 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-04T20:55:25.978Z,1712264125.978 [CommandExec](INFO): Uninitializing the command executive.
2024-04-04T20:55:25.978Z,1712264125.978 [CommandExec](INFO): Uninitializing the command scheduler.
2024-04-04T20:55:25.978Z,1712264125.978 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-04-04T20:55:26.050Z,1712264126.050 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-04-04T20:55:26.050Z,1712264126.050 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-04-04T20:55:26.051Z,1712264126.051 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-04-04T20:55:26.051Z,1712264126.051 [NavChartDb](INFO): Join timeout helper Thread ID is 3725
2024-04-04T20:55:26.145Z,1712264126.145 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-04T20:55:26.145Z,1712264126.145 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-04-04T20:55:26.149Z,1712264126.149 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-04-04T20:55:26.149Z,1712264126.149 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-04-04T20:55:26.150Z,1712264126.150 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3726
2024-04-04T20:55:26.209Z,1712264126.209 [WetLab