2024-07-03T19:01:51.485Z,1720033311.485 [Supervisor](DEBUG): Initializing supervisor.
2024-07-03T19:01:51.489Z,1720033311.489 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-07-03T19:01:51.490Z,1720033311.490 [SyncHandler](INFO): Protected caller Thread ID is 837
2024-07-03T19:01:51.490Z,1720033311.490 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-07-03T19:01:51.491Z,1720033311.491 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-07-03T19:01:51.491Z,1720033311.491 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838
2024-07-03T19:01:51.495Z,1720033311.495 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-07-03T19:01:51.513Z,1720033311.513 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-07-03T19:01:51.514Z,1720033311.514 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-07-03T19:01:51.514Z,1720033311.514 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839
2024-07-03T19:01:51.518Z,1720033311.518 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-07-03T19:01:51.519Z,1720033311.519 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-07-03T19:01:51.519Z,1720033311.519 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840
2024-07-03T19:01:51.521Z,1720033311.521 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-07-03T19:01:51.522Z,1720033311.522 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-07-03T19:01:51.523Z,1720033311.523 [logger ThreadHandler](INFO): Protected caller Thread ID is 841
2024-07-03T19:01:51.526Z,1720033311.526 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-07-03T19:01:51.527Z,1720033311.527 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-07-03T19:01:51.531Z,1720033311.531 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-07-03T19:01:52.022Z,1720033312.022 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-07-03T19:01:52.022Z,1720033312.022 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-07-03T19:01:52.262Z,1720033312.262 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-07-03T19:01:52.264Z,1720033312.264 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-07-03T19:01:52.358Z,1720033312.358 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-07-03T19:01:52.607Z,1720033312.607 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-07-03T19:01:52.609Z,1720033312.609 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-07-03T19:01:52.699Z,1720033312.699 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-07-03T19:01:52.805Z,1720033312.805 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-07-03T19:01:52.807Z,1720033312.807 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-07-03T19:01:53.169Z,1720033313.169 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-07-03T19:01:53.170Z,1720033313.170 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-07-03T19:01:53.315Z,1720033313.315 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-07-03T19:01:53.316Z,1720033313.316 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-07-03T19:01:53.887Z,1720033313.887 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-07-03T19:01:53.889Z,1720033313.889 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-07-03T19:01:54.093Z,1720033314.093 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-07-03T19:01:54.093Z,1720033314.093 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-07-03T19:01:54.522Z,1720033314.522 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-07-03T19:01:54.523Z,1720033314.523 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-07-03T19:01:54.832Z,1720033314.832 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-07-03T19:01:54.833Z,1720033314.833 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-07-03T19:01:55.510Z,1720033315.510 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-07-03T19:01:55.512Z,1720033315.512 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-07-03T19:01:56.380Z,1720033316.380 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-07-03T19:01:56.382Z,1720033316.382 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-07-03T19:01:56.755Z,1720033316.755 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-07-03T19:01:56.757Z,1720033316.757 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-07-03T19:01:57.003Z,1720033317.003 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-07-03T19:01:57.004Z,1720033317.004 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2024-07-03T19:01:57.008Z,1720033317.008 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2024-07-03T19:01:57.138Z,1720033317.138 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2024-07-03T19:01:57.233Z,1720033317.233 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2024-07-03T19:01:57.383Z,1720033317.383 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2024-07-03T19:01:57.481Z,1720033317.481 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2024-07-03T19:01:57.755Z,1720033317.755 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-07-03T19:01:57.756Z,1720033317.756 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2024-07-03T19:01:57.872Z,1720033317.872 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2024-07-03T19:01:57.990Z,1720033317.990 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2024-07-03T19:01:58.098Z,1720033318.098 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2024-07-03T19:01:58.261Z,1720033318.261 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2024-07-03T19:01:58.370Z,1720033318.370 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2024-07-03T19:01:58.467Z,1720033318.467 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-07-03T19:01:58.475Z,1720033318.475 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-07-03T19:01:58.492Z,1720033318.492 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-07-03T19:01:58.493Z,1720033318.493 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-07-03T19:01:58.638Z,1720033318.638 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-07-03T19:01:58.638Z,1720033318.638 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-07-03T19:01:58.708Z,1720033318.708 [VerticalControl](DEBUG): Construct VerticalControl.
2024-07-03T19:01:58.768Z,1720033318.768 [VerticalControl] Loaded
2024-07-03T19:01:58.768Z,1720033318.768 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-07-03T19:01:58.771Z,1720033318.771 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-07-03T19:01:58.818Z,1720033318.818 [HorizontalControl] Loaded
2024-07-03T19:01:58.818Z,1720033318.818 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-07-03T19:01:58.820Z,1720033318.820 [SpeedControl](DEBUG): Construct SpeedControl.
2024-07-03T19:01:58.823Z,1720033318.823 [SpeedControl] Loaded
2024-07-03T19:01:58.824Z,1720033318.824 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-07-03T19:01:58.826Z,1720033318.826 [LoopControl](DEBUG): Construct LoopControl.
2024-07-03T19:01:58.827Z,1720033318.827 [LoopControl] Loaded
2024-07-03T19:01:58.827Z,1720033318.827 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-07-03T19:01:58.827Z,1720033318.827 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-07-03T19:01:58.828Z,1720033318.828 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-07-03T19:01:59.012Z,1720033319.012 [BuoyancyServo] Loaded
2024-07-03T19:01:59.012Z,1720033319.012 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-07-03T19:01:59.034Z,1720033319.034 [ElevatorServo] Loaded
2024-07-03T19:01:59.034Z,1720033319.034 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-07-03T19:01:59.055Z,1720033319.055 [MassServo] Loaded
2024-07-03T19:01:59.055Z,1720033319.055 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-07-03T19:01:59.075Z,1720033319.075 [RudderServo] Loaded
2024-07-03T19:01:59.075Z,1720033319.075 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-07-03T19:01:59.092Z,1720033319.092 [ThrusterHE] Loaded
2024-07-03T19:01:59.092Z,1720033319.092 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-07-03T19:01:59.092Z,1720033319.092 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-07-03T19:01:59.093Z,1720033319.093 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-07-03T19:01:59.164Z,1720033319.164 [DepthRateCalculator] Loaded
2024-07-03T19:01:59.164Z,1720033319.164 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-07-03T19:01:59.169Z,1720033319.169 [PitchRateCalculator] Loaded
2024-07-03T19:01:59.169Z,1720033319.169 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-07-03T19:01:59.178Z,1720033319.178 [SpeedCalculator] Loaded
2024-07-03T19:01:59.179Z,1720033319.179 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-07-03T19:01:59.183Z,1720033319.183 [YawRateCalculator] Loaded
2024-07-03T19:01:59.183Z,1720033319.183 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-07-03T19:01:59.201Z,1720033319.201 [ElevatorOffsetCalculator] Loaded
2024-07-03T19:01:59.202Z,1720033319.202 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-07-03T19:01:59.202Z,1720033319.202 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-07-03T19:01:59.203Z,1720033319.203 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-07-03T19:01:59.338Z,1720033319.338 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-07-03T19:01:59.338Z,1720033319.338 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-07-03T19:01:59.352Z,1720033319.352 [NavChart] Loaded
2024-07-03T19:01:59.352Z,1720033319.352 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-07-03T19:01:59.357Z,1720033319.357 [UniversalFixResidualReporter] Loaded
2024-07-03T19:01:59.358Z,1720033319.358 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-07-03T19:01:59.358Z,1720033319.358 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-07-03T19:01:59.359Z,1720033319.359 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-07-03T19:02:00.504Z,1720033320.504 [BackseatComponent] Loaded
2024-07-03T19:02:00.505Z,1720033320.505 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-07-03T19:02:00.506Z,1720033320.506 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4090B4E0
2024-07-03T19:02:00.506Z,1720033320.506 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 924
2024-07-03T19:02:00.509Z,1720033320.509 [LcmUniversalReporter] Loaded
2024-07-03T19:02:00.509Z,1720033320.509 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-07-03T19:02:00.516Z,1720033320.516 [BioacousticsDataBridge] Loaded
2024-07-03T19:02:00.516Z,1720033320.516 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2024-07-03T19:02:01.340Z,1720033321.340 [BPC1] Loaded
2024-07-03T19:02:01.340Z,1720033321.340 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-07-03T19:02:01.478Z,1720033321.478 [DataOverHttps] Loaded
2024-07-03T19:02:01.479Z,1720033321.479 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-07-03T19:02:01.479Z,1720033321.479 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4093B4E0
2024-07-03T19:02:01.480Z,1720033321.480 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 925
2024-07-03T19:02:01.503Z,1720033321.503 [Depth_Keller] Loaded
2024-07-03T19:02:01.503Z,1720033321.503 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-07-03T19:02:01.508Z,1720033321.508 [DropWeight] Loaded
2024-07-03T19:02:01.508Z,1720033321.508 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-07-03T19:02:01.572Z,1720033321.572 [NAL9602] Loaded
2024-07-03T19:02:01.572Z,1720033321.572 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-07-03T19:02:01.602Z,1720033321.602 [Onboard] Loaded
2024-07-03T19:02:01.602Z,1720033321.602 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-07-03T19:02:01.603Z,1720033321.603 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4096B4E0
2024-07-03T19:02:01.603Z,1720033321.603 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 926
2024-07-03T19:02:01.617Z,1720033321.617 [Power24vConverter] Loaded
2024-07-03T19:02:01.617Z,1720033321.617 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-07-03T19:02:01.633Z,1720033321.633 [Radio_Surface] Loaded
2024-07-03T19:02:01.633Z,1720033321.633 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-07-03T19:02:01.634Z,1720033321.634 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4099B4E0
2024-07-03T19:02:01.634Z,1720033321.634 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 927
2024-07-03T19:02:01.649Z,1720033321.649 [Sonardyne_Nano] Loaded
2024-07-03T19:02:01.650Z,1720033321.650 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-07-03T19:02:01.650Z,1720033321.650 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-07-03T19:02:01.651Z,1720033321.651 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-07-03T19:02:01.736Z,1720033321.736 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-07-03T19:02:01.737Z,1720033321.737 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-07-03T19:02:01.944Z,1720033321.944 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-07-03T19:02:01.945Z,1720033321.945 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-07-03T19:02:01.987Z,1720033321.987 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-07-03T19:02:01.988Z,1720033321.988 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-07-03T19:02:02.233Z,1720033322.233 [CTD_Seabird] Loaded
2024-07-03T19:02:02.233Z,1720033322.233 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-07-03T19:02:02.235Z,1720033322.235 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ADB4E0
2024-07-03T19:02:02.235Z,1720033322.235 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 928
2024-07-03T19:02:02.324Z,1720033322.324 [PAR_Licor] Loaded
2024-07-03T19:02:02.324Z,1720033322.324 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-07-03T19:02:02.685Z,1720033322.685 [WetLabsBB2FL] Loaded
2024-07-03T19:02:02.685Z,1720033322.685 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-07-03T19:02:02.686Z,1720033322.686 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B0B4E0
2024-07-03T19:02:02.686Z,1720033322.686 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 929
2024-07-03T19:02:02.687Z,1720033322.687 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-07-03T19:02:02.688Z,1720033322.688 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-07-03T19:02:03.161Z,1720033323.161 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-07-03T19:02:03.163Z,1720033323.163 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-07-03T19:02:03.330Z,1720033323.330 [SBIT](DEBUG): Construct Startup Built In Test.
2024-07-03T19:02:03.340Z,1720033323.340 [SBIT] Loaded
2024-07-03T19:02:03.340Z,1720033323.340 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-07-03T19:02:03.343Z,1720033323.343 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-07-03T19:02:03.356Z,1720033323.356 [IBIT] Loaded
2024-07-03T19:02:03.356Z,1720033323.356 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-07-03T19:02:03.362Z,1720033323.362 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-07-03T19:02:03.466Z,1720033323.466 [CBIT] Loaded
2024-07-03T19:02:03.466Z,1720033323.466 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-07-03T19:02:03.467Z,1720033323.467 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-07-03T19:02:03.473Z,1720033323.473 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-07-03T19:02:03.476Z,1720033323.476 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-07-03T19:02:03.487Z,1720033323.487 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-07-03T19:02:03.488Z,1720033323.488 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0
2024-07-03T19:02:03.488Z,1720033323.488 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 930
2024-07-03T19:02:03.493Z,1720033323.493 [Supervisor](INFO): Main Thread ID is 830
2024-07-03T19:02:03.493Z,1720033323.493 [Supervisor](DEBUG): Running supervisor.
2024-07-03T19:02:03.494Z,1720033323.494 [CommandExec ThreadHandler](INFO): Handler Thread ID is 931
2024-07-03T19:02:03.494Z,1720033323.494 [CommandExec](INFO): Initializing the command executive.
2024-07-03T19:02:03.495Z,1720033323.495 [CommandLine ThreadHandler](INFO): Handler Thread ID is 932
2024-07-03T19:02:03.498Z,1720033323.498 [controlThread ThreadHandler](INFO): Handler Thread ID is 933
2024-07-03T19:02:03.498Z,1720033323.498 [controlThread](DEBUG): Initializing ControlThread
2024-07-03T19:02:03.499Z,1720033323.499 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-07-03T19:02:03.500Z,1720033323.500 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-07-03T19:02:03.501Z,1720033323.501 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-07-03T19:02:03.502Z,1720033323.502 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-07-03T19:02:03.503Z,1720033323.503 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-07-03T19:02:03.503Z,1720033323.503 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-07-03T19:02:03.504Z,1720033323.504 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-07-03T19:02:03.504Z,1720033323.504 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-07-03T19:02:03.504Z,1720033323.504 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-07-03T19:02:03.505Z,1720033323.505 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-07-03T19:02:03.506Z,1720033323.506 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-07-03T19:02:03.513Z,1720033323.513 [SBIT](INFO): Initialize SBIT Component.
2024-07-03T19:02:03.513Z,1720033323.513 [SBIT](IMPORTANT): git: 2024-06-03
2024-07-03T19:02:03.514Z,1720033323.514 [SBIT](INFO): git hash: 7fa19da3a148abf4baea4b65318d99ca26f8c915
2024-07-03T19:02:03.514Z,1720033323.514 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-07-03T19:02:03.515Z,1720033323.515 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-07-03T19:02:03.516Z,1720033323.516 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2024-07-03T19:02:03.517Z,1720033323.517 [IBIT](INFO): Initialize IBIT Component.
2024-07-03T19:02:03.518Z,1720033323.518 [CBIT](DEBUG): Initialize CBIT Component.
2024-07-03T19:02:03.519Z,1720033323.519 [logger ThreadHandler](INFO): Handler Thread ID is 934
2024-07-03T19:02:03.529Z,1720033323.529 [CBIT](DEBUG): Initialized mux pins.
2024-07-03T19:02:03.529Z,1720033323.529 [CBIT](DEBUG): Initializing the watchdog timer.
2024-07-03T19:02:03.537Z,1720033323.537 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 935
2024-07-03T19:02:03.549Z,1720033323.549 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 936
2024-07-03T19:02:03.551Z,1720033323.551 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-07-03T19:02:03.553Z,1720033323.553 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-07-03T19:02:03.553Z,1720033323.553 [CBIT](DEBUG): Initializing heartbeat.
2024-07-03T19:02:03.561Z,1720033323.561 [Onboard ThreadHandler](INFO): Handler Thread ID is 937
2024-07-03T19:02:03.579Z,1720033323.579 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 938
2024-07-03T19:02:03.601Z,1720033323.601 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 939
2024-07-03T19:02:03.602Z,1720033323.602 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-07-03T19:02:03.606Z,1720033323.606 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 941
2024-07-03T19:02:03.609Z,1720033323.609 [WetLabsBB2FL](INFO): Powering up
2024-07-03T19:02:03.610Z,1720033323.610 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 943
2024-07-03T19:02:03.618Z,1720033323.618 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-07-03T19:02:03.618Z,1720033323.618 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-07-03T19:02:03.618Z,1720033323.618 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-07-03T19:02:03.618Z,1720033323.618 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-07-03T19:02:03.618Z,1720033323.618 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-07-03T19:02:03.618Z,1720033323.618 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-07-03T19:02:03.619Z,1720033323.619 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-07-03T19:02:03.619Z,1720033323.619 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-07-03T19:02:03.625Z,1720033323.625 [CBIT](DEBUG): Deactivating GF circuits.
2024-07-03T19:02:03.625Z,1720033323.625 [CBIT](DEBUG): Deactivating emergency mode.
2024-07-03T19:02:03.665Z,1720033323.665 [CBIT](DEBUG): Backplane powered.
2024-07-03T19:02:03.665Z,1720033323.665 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-07-03T19:02:03.666Z,1720033323.666 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-07-03T19:02:03.666Z,1720033323.666 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-07-03T19:02:03.671Z,1720033323.671 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-07-03T19:02:03.722Z,1720033323.722 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-07-03T19:02:03.742Z,1720033323.742 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-07-03T19:02:03.762Z,1720033323.762 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
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-07-03T19:02:03.763Z,1720033323.763 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-07-03T19:02:03.763Z,1720033323.763 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-07-03T19:02:03.777Z,1720033323.777 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-07-03T19:02:03.985Z,1720033323.985 [Radio_Surface](INFO): Powering up
2024-07-03T19:02:04.049Z,1720033324.049 [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-07-03T19:02:04.071Z,1720033324.071 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-07-03T19:02:04.072Z,1720033324.072 [Default:A.Wait](DEBUG): Construct Wait.
2024-07-03T19:02:04.074Z,1720033324.074 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-07-03T19:02:04.123Z,1720033324.123 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-07-03T19:02:04.125Z,1720033324.125 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-07-03T19:02:04.147Z,1720033324.147 [Default:E.Execute](DEBUG): Construct Execute.
2024-07-03T19:02:04.166Z,1720033324.166 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
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-07-03T19:02:04.171Z,1720033324.171 [controlThread](DEBUG): Component order: CycleStarter,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-07-03T19:02:04.220Z,1720033324.220 [Power24vConverter](INFO): Powering up.
2024-07-03T19:02:04.221Z,1720033324.221 [Sonardyne_Nano](INFO): Initializing.
2024-07-03T19:02:04.488Z,1720033324.488 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-07-03T19:02:04.497Z,1720033324.497 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-07-03T19:02:04.498Z,1720033324.498 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-07-03T19:02:04.509Z,1720033324.509 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-07-03T19:02:04.510Z,1720033324.510 [MassServo](DEBUG): Initializing EZServoServo.
2024-07-03T19:02:04.517Z,1720033324.517 [MassServo](DEBUG): Initializing MassServo.
2024-07-03T19:02:04.518Z,1720033324.518 [RudderServo](DEBUG): Initializing EZServoServo.
2024-07-03T19:02:04.525Z,1720033324.525 [RudderServo](DEBUG): Initializing RudderServo.
2024-07-03T19:02:04.526Z,1720033324.526 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-07-03T19:02:04.533Z,1720033324.533 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-07-03T19:02:04.624Z,1720033324.624 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2024-07-03T19:02:04.624Z,1720033324.624 [DropWeight] Hardware Fault, FailCount= 1
2024-07-03T19:02:04.624Z,1720033324.624 [DropWeight](ERROR): Hardware Fault
2024-07-03T19:02:04.650Z,1720033324.650 [CommandExec](FAULT): Scheduling is paused
2024-07-03T19:02:04.650Z,1720033324.650 [CBIT](INFO): Critical error at 20240703T190204
2024-07-03T19:02:04.650Z,1720033324.650 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-07-03T19:02:04.661Z,1720033324.661 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-07-03T19:02:04.661Z,1720033324.661 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-07-03T19:02:05.014Z,1720033325.014 [CBIT](INFO): Critical error at 20240703T190204
2024-07-03T19:02:05.717Z,1720033325.717 [WetLabsBB2FL](INFO): Powering down
2024-07-03T19:02:06.199Z,1720033326.199 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-07-03T19:02:07.413Z,1720033327.413 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107
2024-07-03T19:02:07.816Z,1720033327.816 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-07-03T19:02:10.002Z,1720033330.002 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-07-03T19:02:10.653Z,1720033330.653 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2024-07-03T19:02:13.905Z,1720033333.905 [CBIT](CRITICAL): Environmental Failure. Press:14.868899 PSI. Humidity:49%. Temp:23 C. ABORTING MISSION
2024-07-03T19:02:14.307Z,1720033334.307 [CBIT](INFO): Critical error at 20240703T190213
2024-07-03T19:02:24.006Z,1720033344.006 [SBIT](IMPORTANT): Beginning Startup BIT
2024-07-03T19:02:24.015Z,1720033344.015 [CBIT](IMPORTANT): Beginning ground fault scan
2024-07-03T19:02:31.650Z,1720033351.650 [NAL9602](INFO): Powering up NAL9602
2024-07-03T19:02:34.908Z,1720033354.908 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005399
CHAN A1 (24V): -0.006000
CHAN A2 (12V): -0.004224
CHAN A3 (5V): -0.002308
CHAN B0 (3.3V): -0.000530
CHAN B1 (3.15aV): -0.001323
CHAN B2 (3.15bV): -0.001517
CHAN B3 (GND): -0.000410
OPEN: 0.004899
Full Scale: +/- 1 mA
2024-07-03T19:02:42.562Z,1720033362.562 [NAL9602](INFO): NAL9602 initialized
2024-07-03T19:03:18.125Z,1720033398.125 [SBIT](IMPORTANT): SBIT PASSED
2024-07-03T19:03:18.126Z,1720033398.126 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-07-03T19:03:18.126Z,1720033398.126 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;
2024-07-03T19:03:18.126Z,1720033398.126 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;
2024-07-03T19:03:18.127Z,1720033398.127 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-07-03T19:03:18.519Z,1720033398.519 [MissionManager](IMPORTANT): Started mission Startup
2024-07-03T19:03:18.520Z,1720033398.520 [Startup] Running Loop=1
2024-07-03T19:03:18.520Z,1720033398.520 [Startup](DEBUG): Aggregate::initialize Startup
2024-07-03T19:03:18.520Z,1720033398.520 [Startup:A.GoToSurface] Running Loop=1
2024-07-03T19:03:18.520Z,1720033398.520 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-07-03T19:03:18.521Z,1720033398.521 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-07-03T19:03:18.521Z,1720033398.521 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-07-03T19:03:18.521Z,1720033398.521 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-07-03T19:03:18.522Z,1720033398.522 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-07-03T19:03:18.522Z,1720033398.522 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-07-03T19:03:18.522Z,1720033398.522 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-07-03T19:03:18.524Z,1720033398.524 [Startup:StartupSatComms] Running Loop=1
2024-07-03T19:03:18.524Z,1720033398.524 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-07-03T19:03:18.524Z,1720033398.524 [Startup:StartupSatComms:A] Running Loop=1
2024-07-03T19:03:18.934Z,1720033398.934 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-07-03T19:04:03.789Z,1720033443.789 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2024-07-03T19:04:03.789Z,1720033443.789 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-07-03T19:04:03.789Z,1720033443.789 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-03T19:04:03.822Z,1720033443.822 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-03T19:04:04.206Z,1720033444.206 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-03T19:04:04.206Z,1720033444.206 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-07-03T19:04:18.746Z,1720033458.746 [Startup:StartupSatComms:A](INFO): Timed out from 2024-07-03T19:03:18.5Z
2024-07-03T19:04:18.746Z,1720033458.746 [Startup:StartupSatComms:A] Stopped
2024-07-03T19:04:18.746Z,1720033458.746 [Startup:StartupSatComms:B] Running Loop=1
2024-07-03T19:04:19.181Z,1720033459.181 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-07-03T19:05:18.918Z,1720033518.918 [Startup:StartupSatComms:B](INFO): Timed out from 2024-07-03T19:04:18.7Z
2024-07-03T19:05:18.918Z,1720033518.918 [Startup:StartupSatComms:B] Stopped
2024-07-03T19:05:18.918Z,1720033518.918 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-07-03T19:05:18.918Z,1720033518.918 [Startup:StartupSatComms] Stopped
2024-07-03T19:05:18.918Z,1720033518.918 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-07-03T19:05:18.919Z,1720033518.919 [Startup](INFO): Completed Startup
2024-07-03T19:05:18.919Z,1720033518.919 [MissionManager](INFO): Startup is completed.
2024-07-03T19:05:18.919Z,1720033518.919 [MissionManager](INFO): Uninitializing Mission Startup
2024-07-03T19:05:18.919Z,1720033518.919 [Startup] Stopped
2024-07-03T19:05:18.919Z,1720033518.919 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-07-03T19:05:18.919Z,1720033518.919 [Startup:A.GoToSurface] Stopped
2024-07-03T19:05:18.919Z,1720033518.919 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-07-03T19:05:19.322Z,1720033519.322 [MissionManager](IMPORTANT): Started mission Default
2024-07-03T19:05:19.322Z,1720033519.322 [Default] Running Loop=1
2024-07-03T19:05:19.322Z,1720033519.322 [Default](DEBUG): Aggregate::initialize Default
2024-07-03T19:05:19.322Z,1720033519.322 [Default:B.GoToSurface] Running Loop=1
2024-07-03T19:05:19.323Z,1720033519.323 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-07-03T19:05:19.323Z,1720033519.323 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-07-03T19:05:19.323Z,1720033519.323 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-07-03T19:05:19.323Z,1720033519.323 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-07-03T19:05:19.324Z,1720033519.324 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-07-03T19:05:19.324Z,1720033519.324 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-07-03T19:05:19.324Z,1720033519.324 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-07-03T19:05:19.325Z,1720033519.325 [Default:A.Wait] Running Loop=1
2024-07-03T19:05:19.325Z,1720033519.325 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-07-03T19:05:32.682Z,1720033532.682 [Default:A.Wait](INFO): Done Waiting.
2024-07-03T19:05:32.682Z,1720033532.682 [Default:A.Wait] Stopped
2024-07-03T19:05:32.682Z,1720033532.682 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-07-03T19:05:33.088Z,1720033533.088 [Default:CheckIn] Running Loop=1
2024-07-03T19:05:33.088Z,1720033533.088 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-03T19:05:33.088Z,1720033533.088 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-03T19:05:33.482Z,1720033533.482 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-07-03T19:05:35.410Z,1720033535.410 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004037
2024-07-03T19:05:39.555Z,1720033539.555 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-03T19:05:39.910Z,1720033539.910 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-03T19:05:54.859Z,1720033554.859 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-03T19:05:56.070Z,1720033556.070 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-03T19:06:04.986Z,1720033564.986 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2024-07-03T19:06:04.986Z,1720033564.986 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-07-03T19:06:04.986Z,1720033564.986 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-03T19:06:05.016Z,1720033565.016 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-03T19:06:05.402Z,1720033565.402 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-03T19:06:05.402Z,1720033565.402 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-07-03T19:06:10.210Z,1720033570.210 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-03T19:06:11.018Z,1720033571.018 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-03T19:06:26.370Z,1720033586.370 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-03T19:06:26.376Z,1720033586.376 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-03T19:06:41.318Z,1720033601.318 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-03T19:06:42.141Z,1720033602.141 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-03T19:06:56.670Z,1720033616.670 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-03T19:06:57.882Z,1720033617.882 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-03T19:07:12.022Z,1720033632.022 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-03T19:07:12.845Z,1720033632.845 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-03T19:07:27.374Z,1720033647.374 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-03T19:07:28.193Z,1720033648.193 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-03T19:07:42.322Z,1720033662.322 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-03T19:07:43.145Z,1720033663.145 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-03T19:07:45.159Z,1720033665.159 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-03T19:07:51.219Z,1720033671.219 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-03T19:07:57.281Z,1720033677.281 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-07-03T19:07:58.089Z,1720033678.089 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-03T19:08:06.173Z,1720033686.173 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2024-07-03T19:08:06.173Z,1720033686.173 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-07-03T19:08:06.173Z,1720033686.173 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-03T19:08:06.184Z,1720033686.184 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-03T19:08:06.601Z,1720033686.601 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-03T19:08:06.601Z,1720033686.601 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-07-03T19:10:07.004Z,1720033807.004 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2024-07-03T19:10:07.004Z,1720033807.004 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-07-03T19:10:07.004Z,1720033807.004 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-03T19:10:07.015Z,1720033807.015 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-03T19:10:07.394Z,1720033807.394 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-03T19:10:07.394Z,1720033807.394 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-07-03T19:10:29.990Z,1720033829.990 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 5.
2024-07-03T19:10:29.994Z,1720033829.994 [BPC1](INFO): Received data from all battery sticks.
2024-07-03T19:10:33.232Z,1720033833.232 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-03T19:05:33.1Z
2024-07-03T19:10:33.232Z,1720033833.232 [Default:CheckIn:Read_GPS] Stopped
2024-07-03T19:10:33.233Z,1720033833.233 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-03T19:10:33.650Z,1720033833.650 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-07-03T19:10:46.882Z,1720033846.882 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240703T183612/Courier0013.lzma
2024-07-03T19:10:47.635Z,1720033847.635 [DataOverHttps](INFO): Moved sent file to Logs/20240703T183612/Courier0013.lzma.bak
2024-07-03T19:10:47.635Z,1720033847.635 [DataOverHttps](INFO): SBD MOMSN=21321552
2024-07-03T19:11:04.809Z,1720033864.809 [DataOverHttps](INFO): Sending 318 bytes from file Logs/20240703T190151/Courier0000.lzma
2024-07-03T19:11:05.810Z,1720033865.810 [DataOverHttps](INFO): Moved sent file to Logs/20240703T190151/Courier0000.lzma.bak
2024-07-03T19:11:05.811Z,1720033865.811 [DataOverHttps](INFO): SBD MOMSN=21321557
2024-07-03T19:11:23.004Z,1720033883.004 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20240703T190151/Courier0004.lzma
2024-07-03T19:11:24.007Z,1720033884.007 [DataOverHttps](INFO): Moved sent file to Logs/20240703T190151/Courier0004.lzma.bak
2024-07-03T19:11:24.007Z,1720033884.007 [DataOverHttps](INFO): SBD MOMSN=21321566
2024-07-03T19:11:31.289Z,1720033891.289 [CommandExec](IMPORTANT): got command quit
2024-07-03T19:11:32.293Z,1720033892.293 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-03T19:11:32.293Z,1720033892.293 [CommandExec](INFO): Uninitializing the command executive.
2024-07-03T19:11:32.293Z,1720033892.293 [CommandExec](INFO): Uninitializing the command scheduler.
2024-07-03T19:11:32.293Z,1720033892.293 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:32.441Z,1720033892.441 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-07-03T19:11:32.441Z,1720033892.441 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-07-03T19:11:32.441Z,1720033892.441 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:32.442Z,1720033892.442 [NavChartDb](INFO): Join timeout helper Thread ID is 969
2024-07-03T19:11:32.449Z,1720033892.449 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-03T19:11:32.449Z,1720033892.449 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:32.465Z,1720033892.465 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-07-03T19:11:32.465Z,1720033892.465 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:32.465Z,1720033892.465 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 970
2024-07-03T19:11:32.729Z,1720033892.729 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-03T19:11:32.729Z,1720033892.729 [WetLabsBB2FL](INFO): Powering down
2024-07-03T19:11:32.730Z,1720033892.730 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:32.755Z,1720033892.755 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-07-03T19:11:32.755Z,1720033892.755 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:32.755Z,1720033892.755 [CTD_Seabird](INFO): Join timeout helper Thread ID is 971
2024-07-03T19:11:32.937Z,1720033892.937 [CTD_Seabird](INFO): Powering down
2024-07-03T19:11:32.949Z,1720033892.949 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-03T19:11:32.949Z,1720033892.949 [CTD_Seabird](INFO): Powering down
2024-07-03T19:11:32.961Z,1720033892.961 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:32.973Z,1720033892.973 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-07-03T19:11:32.973Z,1720033892.973 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:32.973Z,1720033892.973 [Radio_Surface](INFO): Join timeout helper Thread ID is 972
2024-07-03T19:11:33.221Z,1720033893.221 [Radio_Surface](INFO): Powering down
2024-07-03T19:11:33.222Z,1720033893.222 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-03T19:11:33.222Z,1720033893.222 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:33.233Z,1720033893.233 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-07-03T19:11:33.233Z,1720033893.233 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:33.233Z,1720033893.233 [Onboard](INFO): Join timeout helper Thread ID is 973
2024-07-03T19:11:34.029Z,1720033894.029 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-03T19:11:34.029Z,1720033894.029 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:34.048Z,1720033894.048 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-07-03T19:11:34.048Z,1720033894.048 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:34.048Z,1720033894.048 [DataOverHttps](INFO): Join timeout helper Thread ID is 974
2024-07-03T19:11:38.058Z,1720033898.058 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-03T19:11:38.061Z,1720033898.061 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:38.069Z,1720033898.069 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-07-03T19:11:38.069Z,1720033898.069 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:38.070Z,1720033898.070 [BackseatComponent](INFO): Join timeout helper Thread ID is 975
2024-07-03T19:11:38.157Z,1720033898.157 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-03T19:11:38.157Z,1720033898.157 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:38.169Z,1720033898.169 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-07-03T19:11:38.170Z,1720033898.170 [logger ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:38.170Z,1720033898.170 [logger](INFO): Join timeout helper Thread ID is 976
2024-07-03T19:11:38.197Z,1720033898.197 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-03T19:11:38.197Z,1720033898.197 [logger ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:38.209Z,1720033898.209 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-07-03T19:11:38.209Z,1720033898.209 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:38.210Z,1720033898.210 [CommandLine](INFO): Join timeout helper Thread ID is 977
2024-07-03T19:11:38.269Z,1720033898.269 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-03T19:11:38.269Z,1720033898.269 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:38.274Z,1720033898.274 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-07-03T19:11:38.274Z,1720033898.274 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:38.274Z,1720033898.274 [CommandExec](INFO): Join timeout helper Thread ID is 978
2024-07-03T19:11:38.287Z,1720033898.287 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-07-03T19:11:38.287Z,1720033898.287 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:38.287Z,1720033898.287 [controlThread](INFO): Join timeout helper Thread ID is 979
2024-07-03T19:11:38.657Z,1720033898.657 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-03T19:11:38.657Z,1720033898.657 [controlThread](DEBUG): Uninitializing ControlThread
2024-07-03T19:11:38.658Z,1720033898.658 [NAL9602](INFO): Powering down
2024-07-03T19:11:38.659Z,1720033898.659 [Sonardyne_Nano](INFO): Powering down
2024-07-03T19:11:38.778Z,1720033898.778 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-07-03T19:11:38.778Z,1720033898.778 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-07-03T19:11:38.779Z,1720033898.779 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-07-03T19:11:38.779Z,1720033898.779 [MissionManager](INFO): Uninitializing Mission Default
2024-07-03T19:11:38.779Z,1720033898.779 [Default] Stopped
2024-07-03T19:11:38.779Z,1720033898.779 [Default](DEBUG): Aggregate::uninitialize Default
2024-07-03T19:11:38.780Z,1720033898.780 [Default:B.GoToSurface] Stopped
2024-07-03T19:11:38.780Z,1720033898.780 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-07-03T19:11:38.780Z,1720033898.780 [Default:CheckIn] Stopped
2024-07-03T19:11:38.780Z,1720033898.780 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-03T19:11:38.780Z,1720033898.780 [Default:CheckIn:Read_Iridium] Stopped
2024-07-03T19:11:38.783Z,1720033898.783 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-07-03T19:11:38.783Z,1720033898.783 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-07-03T19:11:38.783Z,1720033898.783 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-07-03T19:11:38.783Z,1720033898.783 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-07-03T19:11:38.784Z,1720033898.784 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-07-03T19:11:38.784Z,1720033898.784 [BuoyancyServo](INFO): Powering down
2024-07-03T19:11:38.797Z,1720033898.797 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-07-03T19:11:38.797Z,1720033898.797 [ElevatorServo](INFO): Powering down
2024-07-03T19:11:38.798Z,1720033898.798 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-07-03T19:11:38.798Z,1720033898.798 [MassServo](INFO): Powering down
2024-07-03T19:11:38.799Z,1720033898.799 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-07-03T19:11:38.799Z,1720033898.799 [RudderServo](INFO): Powering down
2024-07-03T19:11:38.800Z,1720033898.800 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-07-03T19:11:38.800Z,1720033898.800 [ThrusterHE](INFO): Powering down
2024-07-03T19:11:38.800Z,1720033898.800 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-07-03T19:11:38.801Z,1720033898.801 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-07-03T19:11:38.801Z,1720033898.801 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-07-03T19:11:38.801Z,1720033898.801 [CBIT](DEBUG): Powering off loads.
2024-07-03T19:11:38.812Z,1720033898.812 [CBIT](DEBUG): Disabling WDT.
2024-07-03T19:11:38.824Z,1720033898.824 [CBIT](DEBUG): Opening all GF detection circuits.
2024-07-03T19:11:38.826Z,1720033898.826 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:38.887Z,1720033898.887 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:38.889Z,1720033898.889 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:38.895Z,1720033898.895 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:38.938Z,1720033898.938 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:38.975Z,1720033898.975 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:38.979Z,1720033898.979 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:39.011Z,1720033899.011 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-07-03T19:11:39.076Z,1720033899.076 [logger ThreadHandler](INFO): Thread cancelled.