2024-05-22T15:02:41.627Z,1716390161.627 [Supervisor](DEBUG): Initializing supervisor.
2024-05-22T15:02:41.632Z,1716390161.632 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-05-22T15:02:41.633Z,1716390161.633 [SyncHandler](INFO): Protected caller Thread ID is 9710
2024-05-22T15:02:41.633Z,1716390161.633 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-05-22T15:02:41.634Z,1716390161.634 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-05-22T15:02:41.635Z,1716390161.635 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9711
2024-05-22T15:02:41.639Z,1716390161.639 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-05-22T15:02:41.656Z,1716390161.656 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-05-22T15:02:41.657Z,1716390161.657 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-05-22T15:02:41.658Z,1716390161.658 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 9712
2024-05-22T15:02:41.662Z,1716390161.662 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-05-22T15:02:41.663Z,1716390161.663 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-05-22T15:02:41.663Z,1716390161.663 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9713
2024-05-22T15:02:41.665Z,1716390161.665 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-05-22T15:02:41.666Z,1716390161.666 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-05-22T15:02:41.667Z,1716390161.667 [logger ThreadHandler](INFO): Protected caller Thread ID is 9714
2024-05-22T15:02:41.670Z,1716390161.670 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-05-22T15:02:41.670Z,1716390161.670 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-05-22T15:02:41.672Z,1716390161.672 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-05-22T15:02:41.949Z,1716390161.949 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-05-22T15:02:41.949Z,1716390161.949 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-05-22T15:02:42.042Z,1716390162.042 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-05-22T15:02:42.592Z,1716390162.592 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-05-22T15:02:42.592Z,1716390162.592 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-05-22T15:02:42.952Z,1716390162.952 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-05-22T15:02:42.952Z,1716390162.952 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-05-22T15:02:43.178Z,1716390163.178 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-05-22T15:02:43.178Z,1716390163.178 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-05-22T15:02:43.598Z,1716390163.598 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-05-22T15:02:43.599Z,1716390163.599 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-05-22T15:02:43.826Z,1716390163.826 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-05-22T15:02:43.827Z,1716390163.827 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-05-22T15:02:44.367Z,1716390164.367 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-05-22T15:02:44.368Z,1716390164.368 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-05-22T15:02:44.516Z,1716390164.516 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-05-22T15:02:44.517Z,1716390164.517 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-05-22T15:02:44.608Z,1716390164.608 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-05-22T15:02:45.457Z,1716390165.457 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-05-22T15:02:45.458Z,1716390165.458 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-05-22T15:02:45.877Z,1716390165.877 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-05-22T15:02:45.877Z,1716390165.877 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-05-22T15:02:46.355Z,1716390166.355 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-05-22T15:02:46.355Z,1716390166.355 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-05-22T15:02:46.536Z,1716390166.536 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-05-22T15:02:46.536Z,1716390166.536 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-05-22T15:02:46.777Z,1716390166.777 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-05-22T15:02:46.778Z,1716390166.778 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-05-22T15:02:47.042Z,1716390167.042 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-05-22T15:02:47.043Z,1716390167.043 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2024-05-22T15:02:47.044Z,1716390167.044 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2024-05-22T15:02:47.148Z,1716390167.148 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2024-05-22T15:02:47.246Z,1716390167.246 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2024-05-22T15:02:47.361Z,1716390167.361 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2024-05-22T15:02:47.457Z,1716390167.457 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2024-05-22T15:02:47.572Z,1716390167.572 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2024-05-22T15:02:47.677Z,1716390167.677 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2024-05-22T15:02:47.803Z,1716390167.803 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2024-05-22T15:02:47.969Z,1716390167.969 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2024-05-22T15:02:48.121Z,1716390168.121 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2024-05-22T15:02:48.243Z,1716390168.243 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2024-05-22T15:02:48.517Z,1716390168.517 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-05-22T15:02:48.518Z,1716390168.518 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2024-05-22T15:02:48.519Z,1716390168.519 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-05-22T15:02:48.520Z,1716390168.520 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-05-22T15:02:48.573Z,1716390168.573 [VerticalControl](DEBUG): Construct VerticalControl.
2024-05-22T15:02:48.632Z,1716390168.632 [VerticalControl] Loaded
2024-05-22T15:02:48.632Z,1716390168.632 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-05-22T15:02:48.635Z,1716390168.635 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-05-22T15:02:48.681Z,1716390168.681 [HorizontalControl] Loaded
2024-05-22T15:02:48.681Z,1716390168.681 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-05-22T15:02:48.683Z,1716390168.683 [SpeedControl](DEBUG): Construct SpeedControl.
2024-05-22T15:02:48.686Z,1716390168.686 [SpeedControl] Loaded
2024-05-22T15:02:48.687Z,1716390168.687 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-05-22T15:02:48.689Z,1716390168.689 [LoopControl](DEBUG): Construct LoopControl.
2024-05-22T15:02:48.690Z,1716390168.690 [LoopControl] Loaded
2024-05-22T15:02:48.690Z,1716390168.690 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-05-22T15:02:48.690Z,1716390168.690 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-05-22T15:02:48.691Z,1716390168.691 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-05-22T15:02:48.701Z,1716390168.701 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-05-22T15:02:48.701Z,1716390168.701 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-05-22T15:02:48.872Z,1716390168.872 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-05-22T15:02:48.872Z,1716390168.872 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-05-22T15:02:49.023Z,1716390169.023 [BuoyancyServo] Loaded
2024-05-22T15:02:49.023Z,1716390169.023 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-05-22T15:02:49.043Z,1716390169.043 [ElevatorServo] Loaded
2024-05-22T15:02:49.043Z,1716390169.043 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-05-22T15:02:49.061Z,1716390169.061 [MassServo] Loaded
2024-05-22T15:02:49.062Z,1716390169.062 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-05-22T15:02:49.080Z,1716390169.080 [RudderServo] Loaded
2024-05-22T15:02:49.080Z,1716390169.080 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-05-22T15:02:49.094Z,1716390169.094 [ThrusterHE] Loaded
2024-05-22T15:02:49.095Z,1716390169.095 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-05-22T15:02:49.095Z,1716390169.095 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-05-22T15:02:49.096Z,1716390169.096 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-05-22T15:02:49.205Z,1716390169.205 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-05-22T15:02:49.206Z,1716390169.206 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-05-22T15:02:49.219Z,1716390169.219 [NavChart] Loaded
2024-05-22T15:02:49.219Z,1716390169.219 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-05-22T15:02:49.225Z,1716390169.225 [UniversalFixResidualReporter] Loaded
2024-05-22T15:02:49.225Z,1716390169.225 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-05-22T15:02:49.226Z,1716390169.226 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-05-22T15:02:49.226Z,1716390169.226 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-05-22T15:02:49.281Z,1716390169.281 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-05-22T15:02:49.281Z,1716390169.281 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-05-22T15:02:49.706Z,1716390169.706 [AHRS_M2] Loaded
2024-05-22T15:02:49.706Z,1716390169.706 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-05-22T15:02:50.508Z,1716390170.508 [BPC1] Loaded
2024-05-22T15:02:50.508Z,1716390170.508 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-05-22T15:02:50.578Z,1716390170.578 [DataOverHttps] Loaded
2024-05-22T15:02:50.578Z,1716390170.578 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-05-22T15:02:50.579Z,1716390170.579 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409314E0
2024-05-22T15:02:50.580Z,1716390170.580 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9796
2024-05-22T15:02:50.600Z,1716390170.600 [Depth_Keller] Loaded
2024-05-22T15:02:50.600Z,1716390170.600 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-05-22T15:02:50.605Z,1716390170.605 [DropWeight] Loaded
2024-05-22T15:02:50.605Z,1716390170.605 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-05-22T15:02:50.666Z,1716390170.666 [NAL9602] Loaded
2024-05-22T15:02:50.666Z,1716390170.666 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-05-22T15:02:50.696Z,1716390170.696 [Onboard] Loaded
2024-05-22T15:02:50.696Z,1716390170.696 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-05-22T15:02:50.697Z,1716390170.697 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409614E0
2024-05-22T15:02:50.697Z,1716390170.697 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 9797
2024-05-22T15:02:50.705Z,1716390170.705 [PowerOnly](INFO): Adding load control power supply at /dev/loadC1
2024-05-22T15:02:50.716Z,1716390170.716 [PowerOnly] Loaded
2024-05-22T15:02:50.716Z,1716390170.716 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2024-05-22T15:02:50.728Z,1716390170.728 [Power24vConverter] Loaded
2024-05-22T15:02:50.728Z,1716390170.728 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-05-22T15:02:50.741Z,1716390170.741 [Radio_Surface] Loaded
2024-05-22T15:02:50.741Z,1716390170.741 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-05-22T15:02:50.742Z,1716390170.742 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409914E0
2024-05-22T15:02:50.743Z,1716390170.743 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9798
2024-05-22T15:02:50.834Z,1716390170.834 [DAT] Loaded
2024-05-22T15:02:50.834Z,1716390170.834 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-05-22T15:02:50.835Z,1716390170.835 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409C14E0
2024-05-22T15:02:50.836Z,1716390170.836 [DAT ThreadHandler](INFO): Protected caller Thread ID is 9799
2024-05-22T15:02:50.836Z,1716390170.836 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-05-22T15:02:50.837Z,1716390170.837 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-05-22T15:02:50.886Z,1716390170.886 [DepthRateCalculator] Loaded
2024-05-22T15:02:50.886Z,1716390170.886 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-05-22T15:02:50.891Z,1716390170.891 [PitchRateCalculator] Loaded
2024-05-22T15:02:50.891Z,1716390170.891 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-05-22T15:02:50.900Z,1716390170.900 [SpeedCalculator] Loaded
2024-05-22T15:02:50.900Z,1716390170.900 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-05-22T15:02:50.905Z,1716390170.905 [YawRateCalculator] Loaded
2024-05-22T15:02:50.905Z,1716390170.905 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-05-22T15:02:50.923Z,1716390170.923 [ElevatorOffsetCalculator] Loaded
2024-05-22T15:02:50.923Z,1716390170.923 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-05-22T15:02:50.924Z,1716390170.924 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-05-22T15:02:50.924Z,1716390170.924 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-05-22T15:02:51.089Z,1716390171.089 [CANONSampler] Loaded
2024-05-22T15:02:51.090Z,1716390171.090 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2024-05-22T15:02:51.150Z,1716390171.150 [CTD_Seabird] Loaded
2024-05-22T15:02:51.151Z,1716390171.151 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-05-22T15:02:51.152Z,1716390171.152 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A7D4E0
2024-05-22T15:02:51.152Z,1716390171.152 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9800
2024-05-22T15:02:51.173Z,1716390171.173 [PAR_Licor] Loaded
2024-05-22T15:02:51.173Z,1716390171.173 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-05-22T15:02:51.271Z,1716390171.271 [WetLabsBB2FL] Loaded
2024-05-22T15:02:51.271Z,1716390171.271 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-05-22T15:02:51.272Z,1716390171.272 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AAD4E0
2024-05-22T15:02:51.272Z,1716390171.272 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9801
2024-05-22T15:02:51.273Z,1716390171.273 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-05-22T15:02:51.273Z,1716390171.273 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-05-22T15:02:51.300Z,1716390171.300 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-05-22T15:02:51.300Z,1716390171.300 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-05-22T15:02:51.890Z,1716390171.890 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-05-22T15:02:51.891Z,1716390171.891 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-05-22T15:02:52.028Z,1716390172.028 [SBIT](DEBUG): Construct Startup Built In Test.
2024-05-22T15:02:52.037Z,1716390172.037 [SBIT] Loaded
2024-05-22T15:02:52.038Z,1716390172.038 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-05-22T15:02:52.040Z,1716390172.040 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-05-22T15:02:52.053Z,1716390172.053 [IBIT] Loaded
2024-05-22T15:02:52.053Z,1716390172.053 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-05-22T15:02:52.059Z,1716390172.059 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-05-22T15:02:52.162Z,1716390172.162 [CBIT] Loaded
2024-05-22T15:02:52.163Z,1716390172.163 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-05-22T15:02:52.163Z,1716390172.163 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-05-22T15:02:52.164Z,1716390172.164 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-05-22T15:02:52.275Z,1716390172.275 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-05-22T15:02:52.281Z,1716390172.281 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-05-22T15:02:52.284Z,1716390172.284 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-05-22T15:02:52.295Z,1716390172.295 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-05-22T15:02:52.296Z,1716390172.296 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0
2024-05-22T15:02:52.297Z,1716390172.297 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9802
2024-05-22T15:02:52.301Z,1716390172.301 [Supervisor](INFO): Main Thread ID is 9389
2024-05-22T15:02:52.301Z,1716390172.301 [Supervisor](DEBUG): Running supervisor.
2024-05-22T15:02:52.302Z,1716390172.302 [CommandExec ThreadHandler](INFO): Handler Thread ID is 9803
2024-05-22T15:02:52.302Z,1716390172.302 [CommandExec](INFO): Initializing the command executive.
2024-05-22T15:02:52.305Z,1716390172.305 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9804
2024-05-22T15:02:52.307Z,1716390172.307 [controlThread ThreadHandler](INFO): Handler Thread ID is 9805
2024-05-22T15:02:52.307Z,1716390172.307 [controlThread](DEBUG): Initializing ControlThread
2024-05-22T15:02:52.308Z,1716390172.308 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-05-22T15:02:52.310Z,1716390172.310 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-05-22T15:02:52.311Z,1716390172.311 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-05-22T15:02:52.311Z,1716390172.311 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-05-22T15:02:52.313Z,1716390172.313 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-05-22T15:02:52.313Z,1716390172.313 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-05-22T15:02:52.318Z,1716390172.318 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-05-22T15:02:52.318Z,1716390172.318 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-05-22T15:02:52.319Z,1716390172.319 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-05-22T15:02:52.319Z,1716390172.319 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-05-22T15:02:52.319Z,1716390172.319 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-05-22T15:02:52.324Z,1716390172.324 [SBIT](INFO): Initialize SBIT Component.
2024-05-22T15:02:52.324Z,1716390172.324 [SBIT](IMPORTANT): git: 2024-04-26_A
2024-05-22T15:02:52.325Z,1716390172.325 [SBIT](INFO): git hash: 26b86b0032c96ca2fff2505cfdfca7953dc64339
2024-05-22T15:02:52.325Z,1716390172.325 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-05-22T15:02:52.326Z,1716390172.326 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-05-22T15:02:52.327Z,1716390172.327 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2024-05-22T15:02:52.328Z,1716390172.328 [IBIT](INFO): Initialize IBIT Component.
2024-05-22T15:02:52.329Z,1716390172.329 [CBIT](DEBUG): Initialize CBIT Component.
2024-05-22T15:02:52.329Z,1716390172.329 [logger ThreadHandler](INFO): Handler Thread ID is 9806
2024-05-22T15:02:52.340Z,1716390172.340 [CBIT](DEBUG): Initialized mux pins.
2024-05-22T15:02:52.340Z,1716390172.340 [CBIT](DEBUG): Initializing the watchdog timer.
2024-05-22T15:02:52.348Z,1716390172.348 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9807
2024-05-22T15:02:52.350Z,1716390172.350 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-05-22T15:02:52.360Z,1716390172.360 [Onboard ThreadHandler](INFO): Handler Thread ID is 9808
2024-05-22T15:02:52.364Z,1716390172.364 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-05-22T15:02:52.365Z,1716390172.365 [CBIT](DEBUG): Initializing heartbeat.
2024-05-22T15:02:52.382Z,1716390172.382 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9809
2024-05-22T15:02:52.400Z,1716390172.400 [DAT ThreadHandler](INFO): Handler Thread ID is 9810
2024-05-22T15:02:52.401Z,1716390172.401 [DAT](INFO): Powering up
2024-05-22T15:02:52.401Z,1716390172.401 [DAT](DEBUG): Initializing DAT.
2024-05-22T15:02:52.406Z,1716390172.406 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9812
2024-05-22T15:02:52.406Z,1716390172.406 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-05-22T15:02:52.413Z,1716390172.413 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9814
2024-05-22T15:02:52.416Z,1716390172.416 [WetLabsBB2FL](INFO): Powering up
2024-05-22T15:02:52.418Z,1716390172.418 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9816
2024-05-22T15:02:52.421Z,1716390172.421 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-05-22T15:02:52.421Z,1716390172.421 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-05-22T15:02:52.421Z,1716390172.421 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-05-22T15:02:52.421Z,1716390172.421 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-05-22T15:02:52.421Z,1716390172.421 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-05-22T15:02:52.421Z,1716390172.421 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-05-22T15:02:52.422Z,1716390172.422 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-05-22T15:02:52.422Z,1716390172.422 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-05-22T15:02:52.436Z,1716390172.436 [CBIT](DEBUG): Deactivating GF circuits.
2024-05-22T15:02:52.436Z,1716390172.436 [CBIT](DEBUG): Deactivating emergency mode.
2024-05-22T15:02:52.476Z,1716390172.476 [CBIT](DEBUG): Backplane powered.
2024-05-22T15:02:52.476Z,1716390172.476 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-05-22T15:02:52.477Z,1716390172.477 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-05-22T15:02:52.477Z,1716390172.477 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-05-22T15:02:52.478Z,1716390172.478 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-05-22T15:02:52.519Z,1716390172.519 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-05-22T15:02:52.523Z,1716390172.523 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-05-22T15:02:52.571Z,1716390172.571 [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-05-22T15:02:52.572Z,1716390172.572 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-05-22T15:02:52.572Z,1716390172.572 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-05-22T15:02:52.573Z,1716390172.573 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-05-22T15:02:52.788Z,1716390172.788 [Radio_Surface](INFO): Powering up
2024-05-22T15:02:52.880Z,1716390172.880 [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-05-22T15:02:52.886Z,1716390172.886 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-05-22T15:02:52.887Z,1716390172.887 [Default:A.Wait](DEBUG): Construct Wait.
2024-05-22T15:02:52.913Z,1716390172.913 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-05-22T15:02:52.946Z,1716390172.946 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-05-22T15:02:52.973Z,1716390172.973 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-05-22T15:02:52.980Z,1716390172.980 [Default:E.Execute](DEBUG): Construct Execute.
2024-05-22T15:02:52.983Z,1716390172.983 [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-05-22T15:02:52.000Z,1716390173.000 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,CANONSampler,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,Reporter,LogSplitter,
2024-05-22T15:02:53.025Z,1716390173.025 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-05-22T15:02:53.099Z,1716390173.099 [PowerOnly](INFO): Powering up loadControl
2024-05-22T15:02:53.116Z,1716390173.116 [Power24vConverter](INFO): Powering up.
2024-05-22T15:02:53.117Z,1716390173.117 [CANONSampler](INFO): Powering down
2024-05-22T15:02:53.164Z,1716390173.164 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-05-22T15:02:53.172Z,1716390173.172 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-05-22T15:02:53.173Z,1716390173.173 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-05-22T15:02:53.180Z,1716390173.180 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-05-22T15:02:53.181Z,1716390173.181 [MassServo](DEBUG): Initializing EZServoServo.
2024-05-22T15:02:53.188Z,1716390173.188 [MassServo](DEBUG): Initializing MassServo.
2024-05-22T15:02:53.189Z,1716390173.189 [RudderServo](DEBUG): Initializing EZServoServo.
2024-05-22T15:02:53.196Z,1716390173.196 [RudderServo](DEBUG): Initializing RudderServo.
2024-05-22T15:02:53.197Z,1716390173.197 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-05-22T15:02:53.204Z,1716390173.204 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-05-22T15:02:54.524Z,1716390174.524 [WetLabsBB2FL](INFO): Powering down
2024-05-22T15:02:58.905Z,1716390178.905 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-05-22T15:03:05.004Z,1716390185.004 [DAT](INFO): DAT read:
2024-05-22T15:03:05.006Z,1716390185.006 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-05-22T15:03:06.769Z,1716390186.769 [DAT](INFO): DAT read: MF Frequency Band
2024-05-22T15:03:06.770Z,1716390186.770 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-05-22T15:03:06.771Z,1716390186.771 [DAT](INFO): DAT read: May 22 2024 15:03:00
2024-05-22T15:03:08.029Z,1716390188.029 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-05-22T15:03:08.030Z,1716390188.030 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2024-05-22T15:03:08.030Z,1716390188.030 [DAT](INFO): commRate: 1200
2024-05-22T15:03:10.096Z,1716390190.096 [DAT](INFO): entering command mode
2024-05-22T15:03:10.296Z,1716390190.296 [DAT](INFO): DAT read:
2024-05-22T15:03:10.297Z,1716390190.297 [DAT](INFO): DAT read: user:1>
2024-05-22T15:03:10.297Z,1716390190.297 [DAT](INFO): setting verbose to 3
2024-05-22T15:03:10.548Z,1716390190.548 [DAT](INFO): DAT read: user:1>
2024-05-22T15:03:10.550Z,1716390190.550 [DAT](INFO): DAT read: Verbose | 3
2024-05-22T15:03:10.550Z,1716390190.550 [DAT](INFO): set verbose to 3
2024-05-22T15:03:10.550Z,1716390190.550 [DAT](INFO): setting DatVerbose to 27440
2024-05-22T15:03:10.800Z,1716390190.800 [DAT](INFO): DAT read: user:2>
2024-05-22T15:03:10.801Z,1716390190.801 [DAT](INFO): DAT read: DatVerbose | 27440
2024-05-22T15:03:10.802Z,1716390190.802 [DAT](INFO): set DatVerbose to 27440
2024-05-22T15:03:10.802Z,1716390190.802 [DAT](INFO): setting transmit power to 8
2024-05-22T15:03:11.052Z,1716390191.052 [DAT](INFO): DAT read: user:3>
2024-05-22T15:03:11.053Z,1716390191.053 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-05-22T15:03:11.054Z,1716390191.054 [DAT](INFO): set transmit power to 8
2024-05-22T15:03:11.054Z,1716390191.054 [DAT](INFO): setting local address to 8
2024-05-22T15:03:11.305Z,1716390191.305 [DAT](INFO): DAT read: user:4>
2024-05-22T15:03:11.306Z,1716390191.306 [DAT](INFO): DAT read: LocalAddr | 8
2024-05-22T15:03:11.306Z,1716390191.306 [DAT](INFO): set local address to 8
2024-05-22T15:03:11.307Z,1716390191.307 [DAT](INFO): Setting time to: 15:3:11 And date to:5/22/2024
2024-05-22T15:03:11.556Z,1716390191.556 [DAT](INFO): DAT read: user:5>
2024-05-22T15:03:11.557Z,1716390191.557 [DAT](INFO): DAT read: Wed May 22, 2024 15:03:11
2024-05-22T15:03:11.558Z,1716390191.558 [DAT](INFO): Local DAT time set to Wed May 22, 2024 15:03:11
2024-05-22T15:03:20.897Z,1716390200.897 [NAL9602](INFO): Powering up NAL9602
2024-05-22T15:03:31.797Z,1716390211.797 [NAL9602](INFO): NAL9602 initialized
2024-05-22T15:03:52.039Z,1716390232.039 [SBIT](IMPORTANT): Beginning Startup BIT
2024-05-22T15:03:52.066Z,1716390232.066 [CBIT](IMPORTANT): Beginning ground fault scan
2024-05-22T15:04:02.927Z,1716390242.927 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-05-22T15:04:03.335Z,1716390243.335 [CBIT](IMPORTANT): Beginning ground fault scan
2024-05-22T15:04:14.320Z,1716390254.320 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-05-22T15:04:14.724Z,1716390254.724 [CBIT](IMPORTANT): Beginning ground fault scan
2024-05-22T15:04:19.115Z,1716390259.115 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-05-22T15:04:25.604Z,1716390265.604 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-05-22T15:04:25.605Z,1716390265.605 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2024-05-22T15:04:31.532Z,1716390271.532 [CommandExec](IMPORTANT): got command configSet list
2024-05-22T15:04:31.533Z,1716390271.533 [CommandExec](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-05-22T15:04:31.533Z,1716390271.533 [CommandExec](IMPORTANT): No configSet variables persisted
2024-05-22T15:04:46.216Z,1716390286.216 [SBIT](IMPORTANT): SBIT PASSED
2024-05-22T15:04:46.217Z,1716390286.217 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-05-22T15:04:46.217Z,1716390286.217 [SBIT](IMPORTANT): No configSet variables persisted
2024-05-22T15:04:46.549Z,1716390286.549 [MissionManager](IMPORTANT): Started mission Startup
2024-05-22T15:04:46.550Z,1716390286.550 [Startup] Running Loop=1
2024-05-22T15:04:46.550Z,1716390286.550 [Startup](DEBUG): Aggregate::initialize Startup
2024-05-22T15:04:46.550Z,1716390286.550 [Startup:A.GoToSurface] Running Loop=1
2024-05-22T15:04:46.550Z,1716390286.550 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-05-22T15:04:46.550Z,1716390286.550 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-05-22T15:04:46.551Z,1716390286.551 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-05-22T15:04:46.551Z,1716390286.551 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-05-22T15:04:46.552Z,1716390286.552 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-05-22T15:04:46.552Z,1716390286.552 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-05-22T15:04:46.554Z,1716390286.554 [Startup:StartupSatComms] Running Loop=1
2024-05-22T15:04:46.554Z,1716390286.554 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-05-22T15:04:46.554Z,1716390286.554 [Startup:StartupSatComms:A] Running Loop=1
2024-05-22T15:04:46.954Z,1716390286.954 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-05-22T15:05:34.671Z,1716390334.671 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-05-22T15:05:35.278Z,1716390335.278 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005064
2024-05-22T15:05:46.790Z,1716390346.790 [Startup:StartupSatComms:A](INFO): Timed out from 2024-05-22T15:04:46.6Z
2024-05-22T15:05:46.790Z,1716390346.790 [Startup:StartupSatComms:A] Stopped
2024-05-22T15:05:46.790Z,1716390346.790 [Startup:StartupSatComms:B] Running Loop=1
2024-05-22T15:05:47.197Z,1716390347.197 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-05-22T15:05:52.437Z,1716390352.437 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-05-22T15:05:52.437Z,1716390352.437 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-22T15:05:52.481Z,1716390352.481 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-22T15:05:52.842Z,1716390352.842 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-22T15:05:52.842Z,1716390352.842 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-05-22T15:05:53.624Z,1716390353.624 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240522T145738/Courier0004.lzma
2024-05-22T15:05:54.626Z,1716390354.626 [DataOverHttps](INFO): Moved sent file to Logs/20240522T145738/Courier0004.lzma.bak
2024-05-22T15:05:54.626Z,1716390354.626 [DataOverHttps](INFO): SBD MOMSN=21068443
2024-05-22T15:06:10.284Z,1716390370.284 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240522T150241/Courier0000.lzma
2024-05-22T15:06:11.286Z,1716390371.286 [DataOverHttps](INFO): Moved sent file to Logs/20240522T150241/Courier0000.lzma.bak
2024-05-22T15:06:11.286Z,1716390371.286 [DataOverHttps](INFO): SBD MOMSN=21068445
2024-05-22T15:06:26.984Z,1716390386.984 [DataOverHttps](INFO): Sending 117 bytes from file Logs/20240522T145738/Express0005.lzma
2024-05-22T15:06:27.986Z,1716390387.986 [DataOverHttps](INFO): Moved sent file to Logs/20240522T145738/Express0005.lzma.bak
2024-05-22T15:06:27.986Z,1716390387.986 [DataOverHttps](INFO): SBD MOMSN=21068448
2024-05-22T15:06:43.873Z,1716390403.873 [DataOverHttps](INFO): Sending 612 bytes from file Logs/20240522T150241/Express0001.lzma
2024-05-22T15:06:44.873Z,1716390404.873 [DataOverHttps](INFO): Moved sent file to Logs/20240522T150241/Express0001.lzma.bak
2024-05-22T15:06:44.874Z,1716390404.874 [DataOverHttps](INFO): SBD MOMSN=21068455
2024-05-22T15:06:46.138Z,1716390406.138 [Startup:StartupSatComms:B] Stopped
2024-05-22T15:06:46.138Z,1716390406.138 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-05-22T15:06:46.138Z,1716390406.138 [Startup:StartupSatComms] Stopped
2024-05-22T15:06:46.138Z,1716390406.138 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-05-22T15:06:46.139Z,1716390406.139 [Startup](INFO): Completed Startup
2024-05-22T15:06:46.140Z,1716390406.140 [MissionManager](INFO): Startup is completed.
2024-05-22T15:06:46.140Z,1716390406.140 [MissionManager](INFO): Uninitializing Mission Startup
2024-05-22T15:06:46.140Z,1716390406.140 [Startup] Stopped
2024-05-22T15:06:46.140Z,1716390406.140 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-05-22T15:06:46.140Z,1716390406.140 [Startup:A.GoToSurface] Stopped
2024-05-22T15:06:46.140Z,1716390406.140 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-05-22T15:06:46.550Z,1716390406.550 [MissionManager](IMPORTANT): Started mission Default
2024-05-22T15:06:46.550Z,1716390406.550 [Default] Running Loop=1
2024-05-22T15:06:46.550Z,1716390406.550 [Default](DEBUG): Aggregate::initialize Default
2024-05-22T15:06:46.550Z,1716390406.550 [Default:B.GoToSurface] Running Loop=1
2024-05-22T15:06:46.550Z,1716390406.550 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-05-22T15:06:46.550Z,1716390406.550 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-05-22T15:06:46.551Z,1716390406.551 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-05-22T15:06:46.551Z,1716390406.551 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-05-22T15:06:46.551Z,1716390406.551 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-05-22T15:06:46.556Z,1716390406.556 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-05-22T15:06:46.556Z,1716390406.556 [Default:A.Wait] Running Loop=1
2024-05-22T15:06:46.556Z,1716390406.556 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-05-22T15:06:59.891Z,1716390419.891 [Default:A.Wait](INFO): Done Waiting.
2024-05-22T15:06:59.891Z,1716390419.891 [Default:A.Wait] Stopped
2024-05-22T15:06:59.891Z,1716390419.891 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-05-22T15:07:00.297Z,1716390420.297 [Default:CheckIn] Running Loop=1
2024-05-22T15:07:00.298Z,1716390420.298 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-05-22T15:07:00.298Z,1716390420.298 [Default:CheckIn:Read_GPS] Running Loop=1
2024-05-22T15:07:00.698Z,1716390420.698 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-05-22T15:07:02.336Z,1716390422.336 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-05-22T15:08:00.914Z,1716390480.914 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-05-22T15:08:09.385Z,1716390489.385 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-05-22T15:08:27.522Z,1716390507.522 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-22T15:08:35.613Z,1716390515.613 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-05-22T15:08:46.114Z,1716390526.114 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-22T15:08:53.413Z,1716390533.413 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-05-22T15:08:53.414Z,1716390533.414 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-22T15:08:53.449Z,1716390533.449 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-22T15:08:53.853Z,1716390533.853 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-22T15:08:53.853Z,1716390533.853 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-05-22T15:08:59.906Z,1716390539.906 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-05-22T15:09:04.285Z,1716390544.285 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-22T15:09:22.466Z,1716390562.466 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-22T15:09:26.965Z,1716390566.965 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-05-22T15:09:40.646Z,1716390580.646 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-22T15:09:58.825Z,1716390598.825 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-22T15:10:17.005Z,1716390617.005 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-22T15:10:33.628Z,1716390633.628 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-05-22T15:11:08.370Z,1716390668.370 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-05-22T15:11:42.555Z,1716390702.555 [CommandExec](IMPORTANT): got command strobe off
2024-05-22T15:11:42.556Z,1716390702.556 [CommandExec](IMPORTANT): Deactivating strobe
2024-05-22T15:11:54.404Z,1716390714.404 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-05-22T15:11:54.404Z,1716390714.404 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-22T15:11:54.423Z,1716390714.423 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-22T15:11:54.831Z,1716390714.831 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-22T15:11:54.831Z,1716390714.831 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-05-22T15:12:00.467Z,1716390720.467 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-05-22T15:07:00.3Z
2024-05-22T15:12:00.467Z,1716390720.467 [Default:CheckIn:Read_GPS] Stopped
2024-05-22T15:12:00.467Z,1716390720.467 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-05-22T15:12:00.855Z,1716390720.855 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-05-22T15:12:08.327Z,1716390728.327 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240522T150241/Courier0004.lzma
2024-05-22T15:12:09.329Z,1716390729.329 [DataOverHttps](INFO): Moved sent file to Logs/20240522T150241/Courier0004.lzma.bak
2024-05-22T15:12:09.329Z,1716390729.329 [DataOverHttps](INFO): SBD MOMSN=21068499
2024-05-22T15:12:25.008Z,1716390745.008 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20240522T150241/Express0005.lzma
2024-05-22T15:12:26.009Z,1716390746.009 [DataOverHttps](INFO): Moved sent file to Logs/20240522T150241/Express0005.lzma.bak
2024-05-22T15:12:26.010Z,1716390746.010 [DataOverHttps](INFO): SBD MOMSN=21068504
2024-05-22T15:12:27.124Z,1716390747.124 [Default:CheckIn:Read_Iridium] Stopped
2024-05-22T15:12:27.124Z,1716390747.124 [Default:CheckIn:C.Wait] Running Loop=1
2024-05-22T15:12:27.124Z,1716390747.124 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-05-22T15:13:20.900Z,1716390800.900 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-05-22T15:13:34.573Z,1716390814.573 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-05-22T15:13:34.573Z,1716390814.573 [NAL9602] Data Fault, FailCount= 1
2024-05-22T15:13:34.573Z,1716390814.573 [NAL9602](ERROR): Data Fault
2024-05-22T15:13:34.636Z,1716390814.636 [CBIT](ERROR): Data Fault in component: NAL9602
2024-05-22T15:13:34.977Z,1716390814.977 [NAL9602](INFO): Powering down
2024-05-22T15:13:35.863Z,1716390815.863 [CBIT](INFO): Clearing failed state for component NAL9602
2024-05-22T15:13:35.863Z,1716390815.863 [NAL9602] No Fault, FailCount= 1
2024-05-22T15:13:43.505Z,1716390823.505 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-05-22T15:14:05.280Z,1716390845.280 [NAL9602](INFO): Powering up NAL9602
2024-05-22T15:14:16.200Z,1716390856.200 [NAL9602](INFO): NAL9602 initialized
2024-05-22T15:14:55.408Z,1716390895.408 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-05-22T15:14:55.408Z,1716390895.408 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-22T15:14:55.419Z,1716390895.419 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-22T15:14:55.834Z,1716390895.834 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-22T15:14:55.835Z,1716390895.835 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-05-22T15:15:58.445Z,1716390958.445 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-05-22T15:16:00.073Z,1716390960.073 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-05-22T15:16:52.980Z,1716391012.980 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-05-22T15:17:14.403Z,1716391034.403 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-05-22T15:17:27.689Z,1716391047.689 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-05-22T15:17:27.689Z,1716391047.689 [Default:CheckIn:C.Wait] Stopped
2024-05-22T15:17:27.689Z,1716391047.689 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-05-22T15:17:27.689Z,1716391047.689 [Default:CheckIn:D] Running Loop=1
2024-05-22T15:17:28.124Z,1716391048.124 [Default:CheckIn:D] Stopped
2024-05-22T15:17:28.124Z,1716391048.124 [Default:CheckIn:E] Running Loop=1
2024-05-22T15:17:28.498Z,1716391048.498 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.692900 min
2024-05-22T15:17:28.498Z,1716391048.498 [Default:CheckIn:E] Stopped
2024-05-22T15:17:28.498Z,1716391048.498 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-05-22T15:17:28.498Z,1716391048.498 [Default:CheckIn] Stopped
2024-05-22T15:17:28.498Z,1716391048.498 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-05-22T15:17:28.498Z,1716391048.498 [Default:CheckIn](INFO): Running loop #2
2024-05-22T15:17:28.499Z,1716391048.499 [Default:CheckIn] Running Loop=2
2024-05-22T15:17:28.499Z,1716391048.499 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-05-22T15:17:28.499Z,1716391048.499 [Default:CheckIn:Read_GPS] Running Loop=1
2024-05-22T15:17:43.833Z,1716391063.833 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-22T15:17:56.389Z,1716391076.389 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2024-05-22T15:17:56.389Z,1716391076.389 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-22T15:17:56.404Z,1716391076.404 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-22T15:17:56.804Z,1716391076.804 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-22T15:17:56.804Z,1716391076.804 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2024-05-22T15:17:59.253Z,1716391079.253 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-05-22T15:18:02.421Z,1716391082.421 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-22T15:18:21.001Z,1716391101.001 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-22T15:18:39.585Z,1716391119.585 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-22T15:18:57.770Z,1716391137.770 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-22T15:19:15.881Z,1716391155.881 [CommandExec](IMPORTANT): got command burn on
2024-05-22T15:19:15.881Z,1716391155.881 [CommandExec](IMPORTANT): Activating dropweight wire
2024-05-22T15:19:15.954Z,1716391155.954 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-22T15:19:21.933Z,1716391161.933 [CommandExec](IMPORTANT): got command burn off
2024-05-22T15:19:21.933Z,1716391161.933 [CommandExec](IMPORTANT): Deactivating dropweight wire
2024-05-22T15:19:32.147Z,1716391172.147 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-05-22T15:19:50.285Z,1716391190.285 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-22T15:20:44.886Z,1716391244.886 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-05-22T15:20:57.385Z,1716391257.385 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2024-05-22T15:20:57.385Z,1716391257.385 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-22T15:20:57.397Z,1716391257.397 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-22T15:20:57.802Z,1716391257.802 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-22T15:20:57.802Z,1716391257.802 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2024-05-22T15:21:05.888Z,1716391265.888 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-05-22T15:21:58.413Z,1716391318.413 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-05-22T15:22:28.686Z,1716391348.686 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-05-22T15:17:28.5Z
2024-05-22T15:22:28.686Z,1716391348.686 [Default:CheckIn:Read_GPS] Stopped
2024-05-22T15:22:28.686Z,1716391348.686 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-05-22T15:22:34.008Z,1716391354.008 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-05-22T15:22:35.963Z,1716391355.963 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240522T150241/Courier0007.lzma
2024-05-22T15:22:36.965Z,1716391356.965 [DataOverHttps](INFO): Moved sent file to Logs/20240522T150241/Courier0007.lzma.bak
2024-05-22T15:22:36.966Z,1716391356.966 [DataOverHttps](INFO): SBD MOMSN=21068553
2024-05-22T15:22:37.592Z,1716391357.592 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-05-22T15:22:51.656Z,1716391371.656 [DataOverHttps](INFO): Sending 248 bytes from file Logs/20240522T150241/Express0008.lzma
2024-05-22T15:22:52.657Z,1716391372.657 [DataOverHttps](INFO): Moved sent file to Logs/20240522T150241/Express0008.lzma.bak
2024-05-22T15:22:52.658Z,1716391372.658 [DataOverHttps](INFO): SBD MOMSN=21068555
2024-05-22T15:22:53.742Z,1716391373.742 [Default:CheckIn:Read_Iridium] Stopped
2024-05-22T15:22:53.742Z,1716391373.742 [Default:CheckIn:C.Wait] Running Loop=1
2024-05-22T15:22:53.742Z,1716391373.742 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-05-22T15:23:32.551Z,1716391412.551 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-05-22T15:23:46.688Z,1716391426.688 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-05-22T15:23:58.509Z,1716391438.509 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2024-05-22T15:23:58.509Z,1716391438.509 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-22T15:23:58.564Z,1716391438.564 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-22T15:23:58.847Z,1716391438.847 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-22T15:23:58.847Z,1716391438.847 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2024-05-22T15:24:01.578Z,1716391441.578 [BPC1](ERROR): BPC1B: No match for serial number 0000 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2024-05-22T15:24:18.957Z,1716391458.957 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-05-22T15:24:18.958Z,1716391458.958 [NAL9602] Data Fault, FailCount= 2
2024-05-22T15:24:18.958Z,1716391458.958 [NAL9602](ERROR): Data Fault
2024-05-22T15:24:18.976Z,1716391458.976 [CBIT](ERROR): Data Fault in component: NAL9602
2024-05-22T15:24:19.353Z,1716391459.353 [NAL9602](INFO): Powering down
2024-05-22T15:24:20.183Z,1716391460.183 [CBIT](INFO): Clearing failed state for component NAL9602
2024-05-22T15:24:20.183Z,1716391460.183 [NAL9602] No Fault, FailCount= 2
2024-05-22T15:24:49.657Z,1716391489.657 [NAL9602](INFO): Powering up NAL9602
2024-05-22T15:24:56.571Z,1716391496.571 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-05-22T15:25:00.561Z,1716391500.561 [NAL9602](INFO): NAL9602 initialized
2024-05-22T15:25:41.829Z,1716391541.829 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-05-22T15:26:59.369Z,1716391619.369 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2024-05-22T15:26:59.369Z,1716391619.369 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-22T15:26:59.412Z,1716391619.412 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-22T15:26:59.885Z,1716391619.885 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-22T15:26:59.885Z,1716391619.885 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2024-05-22T15:27:00.143Z,1716391620.143 [BPC1](ERROR): BPC1B: No match for serial number 0000 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2024-05-22T15:27:14.282Z,1716391634.282 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2024-05-22T15:27:21.959Z,1716391641.959 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-22T15:27:40.542Z,1716391660.542 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-22T15:27:54.317Z,1716391674.317 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-05-22T15:27:54.317Z,1716391674.317 [Default:CheckIn:C.Wait] Stopped
2024-05-22T15:27:54.317Z,1716391674.317 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-05-22T15:27:54.317Z,1716391674.317 [Default:CheckIn:D] Running Loop=1
2024-05-22T15:27:54.712Z,1716391674.712 [Default:CheckIn:D] Stopped
2024-05-22T15:27:54.712Z,1716391674.712 [Default:CheckIn:E] Running Loop=1
2024-05-22T15:27:55.129Z,1716391675.129 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.136039 min
2024-05-22T15:27:55.129Z,1716391675.129 [Default:CheckIn:E] Stopped
2024-05-22T15:27:55.129Z,1716391675.129 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-05-22T15:27:55.130Z,1716391675.130 [Default:CheckIn] Stopped
2024-05-22T15:27:55.130Z,1716391675.130 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-05-22T15:27:55.130Z,1716391675.130 [Default:CheckIn](INFO): Running loop #3
2024-05-22T15:27:55.130Z,1716391675.130 [Default:CheckIn] Running Loop=3
2024-05-22T15:27:55.130Z,1716391675.130 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-05-22T15:27:55.130Z,1716391675.130 [Default:CheckIn:Read_GPS] Running Loop=1
2024-05-22T15:27:59.191Z,1716391679.191 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-22T15:28:17.306Z,1716391697.306 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-22T15:28:35.486Z,1716391715.486 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-22T15:28:54.071Z,1716391734.071 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-22T15:29:12.250Z,1716391752.250 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-22T15:29:29.665Z,1716391769.665 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-05-22T15:30:00.381Z,1716391800.381 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2024-05-22T15:30:00.381Z,1716391800.381 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-22T15:30:00.391Z,1716391800.391 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-22T15:30:00.783Z,1716391800.783 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-22T15:30:00.783Z,1716391800.783 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2024-05-22T15:31:16.730Z,1716391876.730 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-05-22T15:32:29.062Z,1716391949.062 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-05-22T15:32:38.747Z,1716391958.747 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-05-22T15:32:41.173Z,1716391961.173 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-05-22T15:32:53.765Z,1716391973.765 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-05-22T15:32:55.302Z,1716391975.302 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-05-22T15:27:55.1Z
2024-05-22T15:32:55.302Z,1716391975.302 [Default:CheckIn:Read_GPS] Stopped
2024-05-22T15:32:55.302Z,1716391975.302 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-05-22T15:33:01.356Z,1716391981.356 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2024-05-22T15:33:01.356Z,1716391981.356 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-22T15:33:01.375Z,1716391981.375 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-22T15:33:01.787Z,1716391981.787 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-22T15:33:01.787Z,1716391981.787 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2024-05-22T15:33:02.643Z,1716391982.643 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240522T150241/Courier0010.lzma
2024-05-22T15:33:03.645Z,1716391983.645 [DataOverHttps](INFO): Moved sent file to Logs/20240522T150241/Courier0010.lzma.bak
2024-05-22T15:33:03.646Z,1716391983.646 [DataOverHttps](INFO): SBD MOMSN=21068603
2024-05-22T15:33:19.340Z,1716391999.340 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240522T150241/Express0011.lzma
2024-05-22T15:33:20.358Z,1716392000.358 [DataOverHttps](INFO): Moved sent file to Logs/20240522T150241/Express0011.lzma.bak
2024-05-22T15:33:20.358Z,1716392000.358 [DataOverHttps](INFO): SBD MOMSN=21068605
2024-05-22T15:33:21.561Z,1716392001.561 [Default:CheckIn:Read_Iridium] Stopped
2024-05-22T15:33:21.561Z,1716392001.561 [Default:CheckIn:C.Wait] Running Loop=1
2024-05-22T15:33:21.561Z,1716392001.561 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-05-22T15:33:38.549Z,1716392018.549 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-05-22T15:33:39.356Z,1716392019.356 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-05-22T15:33:53.444Z,1716392033.444 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2024-05-22T15:33:53.447Z,1716392033.447 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2024-05-22T15:35:03.328Z,1716392103.328 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-05-22T15:35:03.328Z,1716392103.328 [NAL9602] Data Fault, FailCount= 3
2024-05-22T15:35:03.328Z,1716392103.328 [NAL9602](ERROR): Data Fault
2024-05-22T15:35:03.346Z,1716392103.346 [CBIT](ERROR): Data Fault in component: NAL9602
2024-05-22T15:35:03.737Z,1716392103.737 [NAL9602](INFO): Powering down
2024-05-22T15:35:04.574Z,1716392104.574 [CBIT](INFO): Clearing failed state for component NAL9602
2024-05-22T15:35:04.574Z,1716392104.574 [NAL9602] No Fault, FailCount= 3
2024-05-22T15:35:34.037Z,1716392134.037 [NAL9602](INFO): Powering up NAL9602
2024-05-22T15:35:44.946Z,1716392144.946 [NAL9602](INFO): NAL9602 initialized
2024-05-22T15:36:02.325Z,1716392162.325 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2024-05-22T15:36:02.325Z,1716392162.325 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-22T15:36:02.363Z,1716392162.363 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-22T15:36:02.739Z,1716392162.739 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-22T15:36:02.739Z,1716392162.739 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2024-05-22T15:38:22.109Z,1716392302.109 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-05-22T15:38:22.110Z,1716392302.110 [Default:CheckIn:C.Wait] Stopped
2024-05-22T15:38:22.110Z,1716392302.110 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-05-22T15:38:22.110Z,1716392302.110 [Default:CheckIn:D] Running Loop=1
2024-05-22T15:38:22.522Z,1716392302.522 [Default:CheckIn:D] Stopped
2024-05-22T15:38:22.522Z,1716392302.522 [Default:CheckIn:E] Running Loop=1
2024-05-22T15:38:22.918Z,1716392302.918 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.599532 min
2024-05-22T15:38:22.918Z,1716392302.918 [Default:CheckIn:E] Stopped
2024-05-22T15:38:22.919Z,1716392302.919 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-05-22T15:38:22.919Z,1716392302.919 [Default:CheckIn] Stopped
2024-05-22T15:38:22.919Z,1716392302.919 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-05-22T15:38:22.919Z,1716392302.919 [Default:CheckIn](INFO): Running loop #4
2024-05-22T15:38:22.919Z,1716392302.919 [Default:CheckIn] Running Loop=4
2024-05-22T15:38:22.919Z,1716392302.919 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-05-22T15:38:22.919Z,1716392302.919 [Default:CheckIn:Read_GPS] Running Loop=1
2024-05-22T15:38:40.247Z,1716392320.247 [CommandExec](IMPORTANT): got command configSet Normal all WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water persist
2024-05-22T15:39:03.325Z,1716392343.325 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2024-05-22T15:39:03.325Z,1716392343.325 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-22T15:39:03.353Z,1716392343.353 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-22T15:39:03.754Z,1716392343.754 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-22T15:39:03.754Z,1716392343.754 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2024-05-22T15:39:35.332Z,1716392375.332 [CommandExec](IMPORTANT): got command configSet Normal all WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water persist
2024-05-22T15:42:04.340Z,1716392524.340 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2024-05-22T15:42:04.340Z,1716392524.340 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-22T15:42:04.351Z,1716392524.351 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-22T15:42:04.755Z,1716392524.755 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-22T15:42:04.755Z,1716392524.755 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2024-05-22T15:43:23.098Z,1716392603.098 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-05-22T15:38:22.9Z
2024-05-22T15:43:23.098Z,1716392603.098 [Default:CheckIn:Read_GPS] Stopped
2024-05-22T15:43:23.098Z,1716392603.098 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-05-22T15:43:29.944Z,1716392609.944 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20240522T150241/Courier0013.lzma
2024-05-22T15:43:30.945Z,1716392610.945 [DataOverHttps](INFO): Moved sent file to Logs/20240522T150241/Courier0013.lzma.bak
2024-05-22T15:43:30.946Z,1716392610.946 [DataOverHttps](INFO): SBD MOMSN=21068656
2024-05-22T15:43:46.684Z,1716392626.684 [DataOverHttps](INFO): Sending 254 bytes from file Logs/20240522T150241/Express0014.lzma
2024-05-22T15:43:47.686Z,1716392627.686 [DataOverHttps](INFO): Moved sent file to Logs/20240522T150241/Express0014.lzma.bak
2024-05-22T15:43:47.686Z,1716392627.686 [DataOverHttps](INFO): SBD MOMSN=21068658
2024-05-22T15:43:48.966Z,1716392628.966 [Default:CheckIn:Read_Iridium] Stopped
2024-05-22T15:43:48.966Z,1716392628.966 [Default:CheckIn:C.Wait] Running Loop=1
2024-05-22T15:43:48.966Z,1716392628.966 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-05-22T15:45:05.337Z,1716392705.337 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2024-05-22T15:45:05.337Z,1716392705.337 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-22T15:45:05.347Z,1716392705.347 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-22T15:45:05.750Z,1716392705.750 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-22T15:45:05.750Z,1716392705.750 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2024-05-22T15:45:47.717Z,1716392747.717 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-05-22T15:45:47.717Z,1716392747.717 [NAL9602] Data Fault, FailCount= 4
2024-05-22T15:45:47.717Z,1716392747.717 [NAL9602](ERROR): Data Fault
2024-05-22T15:45:47.763Z,1716392747.763 [CBIT](ERROR): Data Fault in component: NAL9602
2024-05-22T15:45:48.117Z,1716392748.117 [NAL9602](INFO): Powering down
2024-05-22T15:45:48.954Z,1716392748.954 [CBIT](INFO): Clearing failed state for component NAL9602
2024-05-22T15:45:48.954Z,1716392748.954 [NAL9602] No Fault, FailCount= 4
2024-05-22T15:46:18.425Z,1716392778.425 [NAL9602](INFO): Powering up NAL9602
2024-05-22T15:46:29.333Z,1716392789.333 [NAL9602](INFO): NAL9602 initialized
2024-05-22T15:48:06.302Z,1716392886.302 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2024-05-22T15:48:06.302Z,1716392886.302 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-22T15:48:06.341Z,1716392886.341 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-22T15:48:06.715Z,1716392886.715 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-22T15:48:06.715Z,1716392886.715 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2024-05-22T15:48:49.530Z,1716392929.530 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-05-22T15:48:49.530Z,1716392929.530 [Default:CheckIn:C.Wait] Stopped
2024-05-22T15:48:49.530Z,1716392929.530 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-05-22T15:48:49.530Z,1716392929.530 [Default:CheckIn:D] Running Loop=1
2024-05-22T15:48:49.943Z,1716392929.943 [Default:CheckIn:D] Stopped
2024-05-22T15:48:49.943Z,1716392929.943 [Default:CheckIn:E] Running Loop=1
2024-05-22T15:48:50.366Z,1716392930.366 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.056551 min
2024-05-22T15:48:50.366Z,1716392930.366 [Default:CheckIn:E] Stopped
2024-05-22T15:48:50.366Z,1716392930.366 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-05-22T15:48:50.366Z,1716392930.366 [Default:CheckIn] Stopped
2024-05-22T15:48:50.367Z,1716392930.367 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-05-22T15:48:50.367Z,1716392930.367 [Default:CheckIn](INFO): Running loop #5
2024-05-22T15:48:50.367Z,1716392930.367 [Default:CheckIn] Running Loop=5
2024-05-22T15:48:50.367Z,1716392930.367 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-05-22T15:48:50.367Z,1716392930.367 [Default:CheckIn:Read_GPS] Running Loop=1
2024-05-22T15:51:07.310Z,1716393067.310 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2024-05-22T15:51:07.310Z,1716393067.310 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-22T15:51:07.320Z,1716393067.320 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-22T15:51:07.730Z,1716393067.730 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-22T15:51:07.730Z,1716393067.730 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2024-05-22T15:53:50.545Z,1716393230.545 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-05-22T15:48:50.4Z
2024-05-22T15:53:50.545Z,1716393230.545 [Default:CheckIn:Read_GPS] Stopped
2024-05-22T15:53:50.545Z,1716393230.545 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-05-22T15:53:57.483Z,1716393237.483 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240522T150241/Courier0016.lzma
2024-05-22T15:53:58.486Z,1716393238.486 [DataOverHttps](INFO): Moved sent file to Logs/20240522T150241/Courier0016.lzma.bak
2024-05-22T15:53:58.486Z,1716393238.486 [DataOverHttps](INFO): SBD MOMSN=21068701
2024-05-22T15:54:08.309Z,1716393248.309 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2024-05-22T15:54:08.309Z,1716393248.309 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-22T15:54:08.320Z,1716393248.320 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-22T15:54:08.732Z,1716393248.732 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-22T15:54:08.732Z,1716393248.732 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2024-05-22T15:54:14.020Z,1716393254.020 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240522T150241/Express0017.lzma
2024-05-22T15:54:15.022Z,1716393255.022 [DataOverHttps](INFO): Moved sent file to Logs/20240522T150241/Express0017.lzma.bak
2024-05-22T15:54:15.022Z,1716393255.022 [DataOverHttps](INFO): SBD MOMSN=21068704
2024-05-22T15:54:16.366Z,1716393256.366 [Default:CheckIn:Read_Iridium] Stopped
2024-05-22T15:54:16.366Z,1716393256.366 [Default:CheckIn:C.Wait] Running Loop=1
2024-05-22T15:54:16.366Z,1716393256.366 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-05-22T15:56:32.101Z,1716393392.101 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-05-22T15:56:32.101Z,1716393392.101 [NAL9602] Data Fault, FailCount= 5
2024-05-22T15:56:32.101Z,1716393392.101 [NAL9602](ERROR): Data Fault
2024-05-22T15:56:32.140Z,1716393392.140 [CBIT](ERROR): Data Fault in component: NAL9602
2024-05-22T15:56:32.140Z,1716393392.140 [CBIT](CRITICAL): Data Fault in component: NAL9602
2024-05-22T15:56:32.505Z,1716393392.505 [NAL9602](INFO): Powering down
2024-05-22T15:56:32.542Z,1716393392.542 [CommandExec](FAULT): Scheduling is paused
2024-05-22T15:56:32.542Z,1716393392.542 [CBIT](INFO): Critical error at 20240522T155632
2024-05-22T15:56:32.542Z,1716393392.542 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-05-22T15:57:09.276Z,1716393429.276 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2024-05-22T15:57:09.276Z,1716393429.276 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-22T15:57:09.287Z,1716393429.287 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-22T15:57:09.690Z,1716393429.690 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-22T15:57:09.690Z,1716393429.690 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2024-05-22T15:58:32.238Z,1716393512.238 [CBIT](INFO): Clearing failed state for component NAL9602
2024-05-22T15:58:32.238Z,1716393512.238 [NAL9602] No Fault, FailCount= 5
2024-05-22T15:58:32.533Z,1716393512.533 [NAL9602](INFO): Powering up NAL9602
2024-05-22T15:58:43.441Z,1716393523.441 [NAL9602](INFO): NAL9602 initialized
2024-05-22T15:59:17.013Z,1716393557.013 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-05-22T15:59:17.014Z,1716393557.014 [Default:CheckIn:C.Wait] Stopped
2024-05-22T15:59:17.014Z,1716393557.014 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-05-22T15:59:17.014Z,1716393557.014 [Default:CheckIn:D] Running Loop=1
2024-05-22T15:59:17.434Z,1716393557.434 [Default:CheckIn:D] Stopped
2024-05-22T15:59:17.434Z,1716393557.434 [Default:CheckIn:E] Running Loop=1
2024-05-22T15:59:17.840Z,1716393557.840 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.514734 min
2024-05-22T15:59:17.840Z,1716393557.840 [Default:CheckIn:E] Stopped
2024-05-22T15:59:17.840Z,1716393557.840 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-05-22T15:59:17.840Z,1716393557.840 [Default:CheckIn] Stopped
2024-05-22T15:59:17.840Z,1716393557.840 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-05-22T15:59:17.840Z,1716393557.840 [Default:CheckIn](INFO): Running loop #6
2024-05-22T15:59:17.840Z,1716393557.840 [Default:CheckIn] Running Loop=6
2024-05-22T15:59:17.841Z,1716393557.841 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-05-22T15:59:17.841Z,1716393557.841 [Default:CheckIn:Read_GPS] Running Loop=1
2024-05-22T16:00:10.413Z,1716393610.413 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2024-05-22T16:00:10.413Z,1716393610.413 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-22T16:00:10.428Z,1716393610.428 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-22T16:00:10.841Z,1716393610.841 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-22T16:00:10.841Z,1716393610.841 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2024-05-22T16:03:11.381Z,1716393791.381 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2024-05-22T16:03:11.381Z,1716393791.381 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-22T16:03:11.392Z,1716393791.392 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-22T16:03:11.392Z,1716393791.392 [CBIT](FAULT): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-22T16:04:18.089Z,1716393858.089 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-05-22T15:59:17.8Z
2024-05-22T16:04:18.089Z,1716393858.089 [Default:CheckIn:Read_GPS] Stopped
2024-05-22T16:04:18.089Z,1716393858.089 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-05-22T16:04:24.221Z,1716393864.221 [CommandExec](IMPORTANT): got command restart system
2024-05-22T16:04:25.736Z,1716393865.736 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20240522T150241/Courier0019.lzma
2024-05-22T16:04:26.276Z,1716393866.276 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-22T16:04:26.276Z,1716393866.276 [CommandExec](INFO): Uninitializing the command executive.
2024-05-22T16:04:26.276Z,1716393866.276 [CommandExec](INFO): Uninitializing the command scheduler.
2024-05-22T16:04:26.276Z,1716393866.276 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:26.432Z,1716393866.432 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-05-22T16:04:26.432Z,1716393866.432 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-05-22T16:04:26.432Z,1716393866.432 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:26.433Z,1716393866.433 [NavChartDb](INFO): Join timeout helper Thread ID is 9891
2024-05-22T16:04:26.737Z,1716393866.737 [DataOverHttps](INFO): Moved sent file to Logs/20240522T150241/Courier0019.lzma.bak
2024-05-22T16:04:26.738Z,1716393866.738 [DataOverHttps](INFO): SBD MOMSN=21068748
2024-05-22T16:04:26.804Z,1716393866.804 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-22T16:04:26.804Z,1716393866.804 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:26.808Z,1716393866.808 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-05-22T16:04:26.808Z,1716393866.808 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:26.808Z,1716393866.808 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9892
2024-05-22T16:04:26.988Z,1716393866.988 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-22T16:04:26.988Z,1716393866.988 [WetLabsBB2FL](INFO): Powering down
2024-05-22T16:04:26.989Z,1716393866.989 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:26.996Z,1716393866.996 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-05-22T16:04:26.996Z,1716393866.996 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:26.996Z,1716393866.996 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9893
2024-05-22T16:04:27.820Z,1716393867.820 [CTD_Seabird](INFO): Powering down
2024-05-22T16:04:27.832Z,1716393867.832 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-22T16:04:27.832Z,1716393867.832 [CTD_Seabird](INFO): Powering down
2024-05-22T16:04:27.849Z,1716393867.849 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:27.858Z,1716393867.858 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2024-05-22T16:04:27.858Z,1716393867.858 [DAT ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:27.859Z,1716393867.859 [DAT](INFO): Join timeout helper Thread ID is 9894
2024-05-22T16:04:27.888Z,1716393867.888 [DAT](INFO): Powering down
2024-05-22T16:04:27.960Z,1716393867.960 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-22T16:04:27.961Z,1716393867.961 [DAT](INFO): Powering down
2024-05-22T16:04:27.961Z,1716393867.961 [DAT ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:27.972Z,1716393867.972 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-05-22T16:04:27.972Z,1716393867.972 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:27.972Z,1716393867.972 [Radio_Surface](INFO): Join timeout helper Thread ID is 9895
2024-05-22T16:04:28.360Z,1716393868.360 [Radio_Surface](INFO): Powering down
2024-05-22T16:04:28.361Z,1716393868.361 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-22T16:04:28.361Z,1716393868.361 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:28.369Z,1716393868.369 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-05-22T16:04:28.369Z,1716393868.369 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:28.369Z,1716393868.369 [Onboard](INFO): Join timeout helper Thread ID is 9896
2024-05-22T16:04:30.416Z,1716393870.416 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-22T16:04:30.416Z,1716393870.416 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:30.436Z,1716393870.436 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-05-22T16:04:30.436Z,1716393870.436 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:30.437Z,1716393870.437 [DataOverHttps](INFO): Join timeout helper Thread ID is 9897
2024-05-22T16:04:30.768Z,1716393870.768 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-22T16:04:30.769Z,1716393870.769 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:30.776Z,1716393870.776 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-05-22T16:04:30.776Z,1716393870.776 [logger ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:30.777Z,1716393870.777 [logger](INFO): Join timeout helper Thread ID is 9898
2024-05-22T16:04:30.812Z,1716393870.812 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-22T16:04:30.812Z,1716393870.812 [logger ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:30.816Z,1716393870.816 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-05-22T16:04:30.816Z,1716393870.816 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:30.817Z,1716393870.817 [CommandLine](INFO): Join timeout helper Thread ID is 9899
2024-05-22T16:04:30.824Z,1716393870.824 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-22T16:04:30.824Z,1716393870.824 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:30.836Z,1716393870.836 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-05-22T16:04:30.836Z,1716393870.836 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:30.837Z,1716393870.837 [CommandExec](INFO): Join timeout helper Thread ID is 9900
2024-05-22T16:04:30.838Z,1716393870.838 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-05-22T16:04:30.838Z,1716393870.838 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:30.838Z,1716393870.838 [controlThread](INFO): Join timeout helper Thread ID is 9901
2024-05-22T16:04:30.936Z,1716393870.936 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-22T16:04:30.936Z,1716393870.936 [controlThread](DEBUG): Uninitializing ControlThread
2024-05-22T16:04:30.937Z,1716393870.937 [AHRS_M2](INFO): Powering down
2024-05-22T16:04:31.009Z,1716393871.009 [NAL9602](INFO): Powering down
2024-05-22T16:04:31.011Z,1716393871.011 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-05-22T16:04:31.012Z,1716393871.012 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-05-22T16:04:31.013Z,1716393871.013 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-05-22T16:04:31.013Z,1716393871.013 [MissionManager](INFO): Uninitializing Mission Default
2024-05-22T16:04:31.013Z,1716393871.013 [Default] Stopped
2024-05-22T16:04:31.013Z,1716393871.013 [Default](DEBUG): Aggregate::uninitialize Default
2024-05-22T16:04:31.013Z,1716393871.013 [Default:B.GoToSurface] Stopped
2024-05-22T16:04:31.014Z,1716393871.014 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-05-22T16:04:31.014Z,1716393871.014 [Default:CheckIn] Stopped
2024-05-22T16:04:31.014Z,1716393871.014 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-05-22T16:04:31.014Z,1716393871.014 [Default:CheckIn:Read_Iridium] Stopped
2024-05-22T16:04:31.017Z,1716393871.017 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-05-22T16:04:31.017Z,1716393871.017 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-05-22T16:04:31.018Z,1716393871.018 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-05-22T16:04:31.018Z,1716393871.018 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-05-22T16:04:31.018Z,1716393871.018 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-05-22T16:04:31.018Z,1716393871.018 [BuoyancyServo](INFO): Powering down
2024-05-22T16:04:31.032Z,1716393871.032 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-05-22T16:04:31.032Z,1716393871.032 [ElevatorServo](INFO): Powering down
2024-05-22T16:04:31.033Z,1716393871.033 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-05-22T16:04:31.033Z,1716393871.033 [MassServo](INFO): Powering down
2024-05-22T16:04:31.034Z,1716393871.034 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-05-22T16:04:31.034Z,1716393871.034 [RudderServo](INFO): Powering down
2024-05-22T16:04:31.035Z,1716393871.035 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-05-22T16:04:31.035Z,1716393871.035 [ThrusterHE](INFO): Powering down
2024-05-22T16:04:31.036Z,1716393871.036 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-05-22T16:04:31.036Z,1716393871.036 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-05-22T16:04:31.037Z,1716393871.037 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-05-22T16:04:31.037Z,1716393871.037 [CBIT](DEBUG): Powering off loads.
2024-05-22T16:04:31.047Z,1716393871.047 [CBIT](DEBUG): Disabling WDT.
2024-05-22T16:04:31.059Z,1716393871.059 [CBIT](DEBUG): Opening all GF detection circuits.
2024-05-22T16:04:31.060Z,1716393871.060 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:31.195Z,1716393871.195 [DAT ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:31.201Z,1716393871.201 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:31.203Z,1716393871.203 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:31.209Z,1716393871.209 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:31.291Z,1716393871.291 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:31.295Z,1716393871.295 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:31.348Z,1716393871.348 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-05-22T16:04:31.415Z,1716393871.415 [logger ThreadHandler](INFO): Thread cancelled.