2023-02-21T19:16:02.805Z,1677006962.805 [Supervisor](DEBUG): Initializing supervisor.
2023-02-21T19:16:02.810Z,1677006962.810 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-02-21T19:16:02.810Z,1677006962.810 [SyncHandler](INFO): Protected caller Thread ID is 7584
2023-02-21T19:16:02.811Z,1677006962.811 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-02-21T19:16:02.812Z,1677006962.812 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-02-21T19:16:02.813Z,1677006962.813 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7585
2023-02-21T19:16:02.817Z,1677006962.817 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-02-21T19:16:02.834Z,1677006962.834 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-02-21T19:16:02.835Z,1677006962.835 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-02-21T19:16:02.836Z,1677006962.836 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 7586
2023-02-21T19:16:02.840Z,1677006962.840 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-02-21T19:16:02.841Z,1677006962.841 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-02-21T19:16:02.841Z,1677006962.841 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7587
2023-02-21T19:16:02.843Z,1677006962.843 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-02-21T19:16:02.844Z,1677006962.844 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-02-21T19:16:02.845Z,1677006962.845 [logger ThreadHandler](INFO): Protected caller Thread ID is 7588
2023-02-21T19:16:02.848Z,1677006962.848 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-02-21T19:16:02.849Z,1677006962.849 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-02-21T19:16:02.850Z,1677006962.850 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-02-21T19:16:02.945Z,1677006962.945 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-02-21T19:16:02.946Z,1677006962.946 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-02-21T19:16:03.158Z,1677006963.158 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-02-21T19:16:03.159Z,1677006963.159 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-02-21T19:16:03.408Z,1677006963.408 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-02-21T19:16:03.409Z,1677006963.409 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-02-21T19:16:03.530Z,1677006963.530 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-02-21T19:16:03.530Z,1677006963.530 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-02-21T19:16:04.280Z,1677006964.280 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-02-21T19:16:04.280Z,1677006964.280 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-02-21T19:16:04.751Z,1677006964.751 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-02-21T19:16:04.752Z,1677006964.752 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-02-21T19:16:05.229Z,1677006965.229 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-02-21T19:16:05.230Z,1677006965.230 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-02-21T19:16:05.620Z,1677006965.620 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-02-21T19:16:05.620Z,1677006965.620 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-02-21T19:16:05.938Z,1677006965.938 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-02-21T19:16:05.938Z,1677006965.938 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-02-21T19:16:06.301Z,1677006966.301 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-02-21T19:16:06.302Z,1677006966.302 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-02-21T19:16:06.523Z,1677006966.523 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-02-21T19:16:06.524Z,1677006966.524 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-02-21T19:16:06.662Z,1677006966.662 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-02-21T19:16:06.662Z,1677006966.662 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-02-21T19:16:06.743Z,1677006966.743 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-02-21T19:16:06.822Z,1677006966.822 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-02-21T19:16:07.007Z,1677006967.007 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-02-21T19:16:07.008Z,1677006967.008 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-02-21T19:16:07.227Z,1677006967.227 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-02-21T19:16:07.229Z,1677006967.229 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2023-02-21T19:16:07.230Z,1677006967.230 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2023-02-21T19:16:07.312Z,1677006967.312 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2023-02-21T19:16:07.575Z,1677006967.575 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-02-21T19:16:07.576Z,1677006967.576 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2023-02-21T19:16:07.673Z,1677006967.673 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2023-02-21T19:16:07.828Z,1677006967.828 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2023-02-21T19:16:08.002Z,1677006968.002 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2023-02-21T19:16:08.086Z,1677006968.086 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2023-02-21T19:16:08.190Z,1677006968.190 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2023-02-21T19:16:08.292Z,1677006968.292 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2023-02-21T19:16:08.444Z,1677006968.444 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2023-02-21T19:16:08.560Z,1677006968.560 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2023-02-21T19:16:08.708Z,1677006968.708 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2023-02-21T19:16:08.708Z,1677006968.708 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-02-21T19:16:08.724Z,1677006968.724 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-02-21T19:16:09.298Z,1677006969.298 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-02-21T19:16:09.299Z,1677006969.299 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-02-21T19:16:09.545Z,1677006969.545 [BuoyancyServo] Loaded
2023-02-21T19:16:09.545Z,1677006969.545 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-02-21T19:16:09.566Z,1677006969.566 [ElevatorServo] Loaded
2023-02-21T19:16:09.566Z,1677006969.566 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-02-21T19:16:09.587Z,1677006969.587 [MassServo] Loaded
2023-02-21T19:16:09.587Z,1677006969.587 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-02-21T19:16:09.607Z,1677006969.607 [RudderServo] Loaded
2023-02-21T19:16:09.607Z,1677006969.607 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-02-21T19:16:09.623Z,1677006969.623 [ThrusterHE] Loaded
2023-02-21T19:16:09.623Z,1677006969.623 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-02-21T19:16:09.624Z,1677006969.624 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-02-21T19:16:09.624Z,1677006969.624 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-02-21T19:16:09.773Z,1677006969.773 [SBIT](DEBUG): Construct Startup Built In Test.
2023-02-21T19:16:09.788Z,1677006969.788 [SBIT] Loaded
2023-02-21T19:16:09.788Z,1677006969.788 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-02-21T19:16:09.791Z,1677006969.791 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-02-21T19:16:09.812Z,1677006969.812 [IBIT] Loaded
2023-02-21T19:16:09.812Z,1677006969.812 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-02-21T19:16:09.818Z,1677006969.818 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-02-21T19:16:10.035Z,1677006970.035 [CBIT] Loaded
2023-02-21T19:16:10.035Z,1677006970.035 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-02-21T19:16:10.036Z,1677006970.036 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-02-21T19:16:10.036Z,1677006970.036 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-02-21T19:16:10.182Z,1677006970.182 [ESPComponent] Loaded
2023-02-21T19:16:10.182Z,1677006970.182 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-02-21T19:16:10.202Z,1677006970.202 [PAR_Licor] Loaded
2023-02-21T19:16:10.202Z,1677006970.202 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-02-21T19:16:10.203Z,1677006970.203 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-02-21T19:16:10.203Z,1677006970.203 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-02-21T19:16:10.255Z,1677006970.255 [DepthRateCalculator] Loaded
2023-02-21T19:16:10.255Z,1677006970.255 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-02-21T19:16:10.260Z,1677006970.260 [PitchRateCalculator] Loaded
2023-02-21T19:16:10.261Z,1677006970.261 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-02-21T19:16:10.270Z,1677006970.270 [SpeedCalculator] Loaded
2023-02-21T19:16:10.271Z,1677006970.271 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-02-21T19:16:10.275Z,1677006970.275 [YawRateCalculator] Loaded
2023-02-21T19:16:10.276Z,1677006970.276 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-02-21T19:16:10.295Z,1677006970.295 [ElevatorOffsetCalculator] Loaded
2023-02-21T19:16:10.295Z,1677006970.295 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-02-21T19:16:10.296Z,1677006970.296 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-02-21T19:16:10.296Z,1677006970.296 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-02-21T19:16:10.401Z,1677006970.401 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-02-21T19:16:10.402Z,1677006970.402 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-02-21T19:16:10.430Z,1677006970.430 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-02-21T19:16:10.430Z,1677006970.430 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-02-21T19:16:10.542Z,1677006970.542 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-02-21T19:16:10.543Z,1677006970.543 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-02-21T19:16:10.556Z,1677006970.556 [NavChart] Loaded
2023-02-21T19:16:10.556Z,1677006970.556 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-02-21T19:16:10.562Z,1677006970.562 [UniversalFixResidualReporter] Loaded
2023-02-21T19:16:10.562Z,1677006970.562 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-02-21T19:16:10.563Z,1677006970.563 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-02-21T19:16:10.563Z,1677006970.563 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-02-21T19:16:10.999Z,1677006970.999 [AHRS_M2] Loaded
2023-02-21T19:16:10.999Z,1677006970.999 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-02-21T19:16:11.034Z,1677006971.034 [BackseatComponent] Loaded
2023-02-21T19:16:11.034Z,1677006971.034 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-02-21T19:16:11.035Z,1677006971.035 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409C44E0
2023-02-21T19:16:11.036Z,1677006971.036 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 7670
2023-02-21T19:16:11.038Z,1677006971.038 [LcmUniversalReporter] Loaded
2023-02-21T19:16:11.039Z,1677006971.039 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-02-21T19:16:11.056Z,1677006971.056 [BPC1](INFO): BPC1A: got serial number for stick 1: 0C1E
2023-02-21T19:16:11.057Z,1677006971.057 [BPC1](INFO): BPC1A: got serial number for stick 2: 0481
2023-02-21T19:16:11.058Z,1677006971.058 [BPC1](INFO): BPC1A: got serial number for stick 3: 047B
2023-02-21T19:16:11.059Z,1677006971.059 [BPC1](INFO): BPC1A: got serial number for stick 4: 0464
2023-02-21T19:16:11.060Z,1677006971.060 [BPC1](INFO): BPC1A: got serial number for stick 5: 045C
2023-02-21T19:16:11.061Z,1677006971.061 [BPC1](INFO): BPC1A: got serial number for stick 6: 046A
2023-02-21T19:16:11.061Z,1677006971.061 [BPC1](INFO): BPC1A: got serial number for stick 7: 045A
2023-02-21T19:16:11.062Z,1677006971.062 [BPC1](INFO): BPC1A: got serial number for stick 8: 0487
2023-02-21T19:16:11.063Z,1677006971.063 [BPC1](INFO): BPC1A: marked stick 8 as reserve.
2023-02-21T19:16:11.063Z,1677006971.063 [BPC1](INFO): BPC1A: got serial number for stick 9: 03FE
2023-02-21T19:16:11.064Z,1677006971.064 [BPC1](INFO): BPC1A: got serial number for stick 10: 0465
2023-02-21T19:16:11.065Z,1677006971.065 [BPC1](INFO): BPC1A: got serial number for stick 11: 046B
2023-02-21T19:16:11.066Z,1677006971.066 [BPC1](INFO): BPC1A: got serial number for stick 12: 0445
2023-02-21T19:16:11.067Z,1677006971.067 [BPC1](INFO): BPC1A: got serial number for stick 13: 040D
2023-02-21T19:16:11.068Z,1677006971.068 [BPC1](INFO): BPC1A: got serial number for stick 14: 04AA
2023-02-21T19:16:11.068Z,1677006971.068 [BPC1](INFO): BPC1A: got serial number for stick 15: 049A
2023-02-21T19:16:11.069Z,1677006971.069 [BPC1](INFO): BPC1A: got serial number for stick 16: 04C6
2023-02-21T19:16:11.069Z,1677006971.069 [BPC1](INFO): BPC1A: marked stick 16 as reserve.
2023-02-21T19:16:11.070Z,1677006971.070 [BPC1](INFO): BPC1A: got serial number for stick 17: 0486
2023-02-21T19:16:11.071Z,1677006971.071 [BPC1](INFO): BPC1A: got serial number for stick 18: 0442
2023-02-21T19:16:11.072Z,1677006971.072 [BPC1](INFO): BPC1A: got serial number for stick 19: 0457
2023-02-21T19:16:11.073Z,1677006971.073 [BPC1](INFO): BPC1A: got serial number for stick 20: 0455
2023-02-21T19:16:11.074Z,1677006971.074 [BPC1](INFO): BPC1A: got serial number for stick 21: 04C0
2023-02-21T19:16:11.074Z,1677006971.074 [BPC1](INFO): BPC1A: got serial number for stick 22: 0459
2023-02-21T19:16:11.075Z,1677006971.075 [BPC1](INFO): BPC1A: got serial number for stick 23: 045F
2023-02-21T19:16:11.076Z,1677006971.076 [BPC1](INFO): BPC1A: got serial number for stick 24: 0449
2023-02-21T19:16:11.076Z,1677006971.076 [BPC1](INFO): BPC1A: marked stick 24 as reserve.
2023-02-21T19:16:11.077Z,1677006971.077 [BPC1](INFO): BPC1A: got serial number for stick 25: 0441
2023-02-21T19:16:11.078Z,1677006971.078 [BPC1](INFO): BPC1A: got serial number for stick 26: 0460
2023-02-21T19:16:11.079Z,1677006971.079 [BPC1](INFO): BPC1A: got serial number for stick 27: 04A8
2023-02-21T19:16:11.080Z,1677006971.080 [BPC1](INFO): BPC1A: got serial number for stick 28: 04BF
2023-02-21T19:16:11.080Z,1677006971.080 [BPC1](INFO): BPC1A: got serial number for stick 29: 045D
2023-02-21T19:16:11.081Z,1677006971.081 [BPC1](INFO): BPC1A: got serial number for stick 30: 04A4
2023-02-21T19:16:11.082Z,1677006971.082 [BPC1](INFO): BPC1A: got serial number for stick 31: 04D9
2023-02-21T19:16:11.083Z,1677006971.083 [BPC1](INFO): BPC1B: got serial number for stick 32: 0BE5
2023-02-21T19:16:11.084Z,1677006971.084 [BPC1](INFO): BPC1B: got serial number for stick 33: 0124
2023-02-21T19:16:11.085Z,1677006971.085 [BPC1](INFO): BPC1B: got serial number for stick 34: 0162
2023-02-21T19:16:11.086Z,1677006971.086 [BPC1](INFO): BPC1B: got serial number for stick 35: 017A
2023-02-21T19:16:11.087Z,1677006971.087 [BPC1](INFO): BPC1B: got serial number for stick 36: 0495
2023-02-21T19:16:11.088Z,1677006971.088 [BPC1](INFO): BPC1B: got serial number for stick 37: 0458
2023-02-21T19:16:11.088Z,1677006971.088 [BPC1](INFO): BPC1B: got serial number for stick 38: 0139
2023-02-21T19:16:11.089Z,1677006971.089 [BPC1](INFO): BPC1B: got serial number for stick 39: 00F0
2023-02-21T19:16:11.090Z,1677006971.090 [BPC1](INFO): BPC1B: marked stick 39 as reserve.
2023-02-21T19:16:11.090Z,1677006971.090 [BPC1](INFO): BPC1B: got serial number for stick 40: 0451
2023-02-21T19:16:11.091Z,1677006971.091 [BPC1](INFO): BPC1B: got serial number for stick 41: 049E
2023-02-21T19:16:11.092Z,1677006971.092 [BPC1](INFO): BPC1B: got serial number for stick 42: 04A2
2023-02-21T19:16:11.093Z,1677006971.093 [BPC1](INFO): BPC1B: got serial number for stick 43: 044F
2023-02-21T19:16:11.094Z,1677006971.094 [BPC1](INFO): BPC1B: got serial number for stick 44: 047F
2023-02-21T19:16:11.095Z,1677006971.095 [BPC1](INFO): BPC1B: got serial number for stick 45: 0496
2023-02-21T19:16:11.095Z,1677006971.095 [BPC1](INFO): BPC1B: got serial number for stick 46: 04CE
2023-02-21T19:16:11.096Z,1677006971.096 [BPC1](INFO): BPC1B: got serial number for stick 47: 04A5
2023-02-21T19:16:11.096Z,1677006971.096 [BPC1](INFO): BPC1B: marked stick 47 as reserve.
2023-02-21T19:16:11.097Z,1677006971.097 [BPC1](INFO): BPC1B: got serial number for stick 48: 04D7
2023-02-21T19:16:11.098Z,1677006971.098 [BPC1](INFO): BPC1B: got serial number for stick 49: 04CA
2023-02-21T19:16:11.099Z,1677006971.099 [BPC1](INFO): BPC1B: got serial number for stick 50: 04A1
2023-02-21T19:16:11.100Z,1677006971.100 [BPC1](INFO): BPC1B: got serial number for stick 51: 049B
2023-02-21T19:16:11.101Z,1677006971.101 [BPC1](INFO): BPC1B: got serial number for stick 52: 04BD
2023-02-21T19:16:11.101Z,1677006971.101 [BPC1](INFO): BPC1B: got serial number for stick 53: 0494
2023-02-21T19:16:11.102Z,1677006971.102 [BPC1](INFO): BPC1B: got serial number for stick 54: 0466
2023-02-21T19:16:11.103Z,1677006971.103 [BPC1](INFO): BPC1B: got serial number for stick 55: 0447
2023-02-21T19:16:11.103Z,1677006971.103 [BPC1](INFO): BPC1B: marked stick 55 as reserve.
2023-02-21T19:16:11.104Z,1677006971.104 [BPC1](INFO): BPC1B: got serial number for stick 56: 0480
2023-02-21T19:16:11.105Z,1677006971.105 [BPC1](INFO): BPC1B: got serial number for stick 57: 04A3
2023-02-21T19:16:11.106Z,1677006971.106 [BPC1](INFO): BPC1B: got serial number for stick 58: 04C5
2023-02-21T19:16:11.106Z,1677006971.106 [BPC1](INFO): BPC1B: got serial number for stick 59: 046D
2023-02-21T19:16:11.107Z,1677006971.107 [BPC1](INFO): BPC1B: got serial number for stick 60: 0409
2023-02-21T19:16:11.108Z,1677006971.108 [BPC1](INFO): BPC1B: got serial number for stick 61: 04D3
2023-02-21T19:16:11.109Z,1677006971.109 [BPC1](INFO): BPC1B: got serial number for stick 62: 0493
2023-02-21T19:16:11.802Z,1677006971.802 [BPC1] Loaded
2023-02-21T19:16:11.803Z,1677006971.803 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-02-21T19:16:11.877Z,1677006971.877 [DataOverHttps] Loaded
2023-02-21T19:16:11.877Z,1677006971.877 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-02-21T19:16:11.878Z,1677006971.878 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409F44E0
2023-02-21T19:16:11.879Z,1677006971.879 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7671
2023-02-21T19:16:11.900Z,1677006971.900 [Depth_Keller] Loaded
2023-02-21T19:16:11.900Z,1677006971.900 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-02-21T19:16:11.919Z,1677006971.919 [MultiRay] Loaded
2023-02-21T19:16:11.919Z,1677006971.919 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread.
2023-02-21T19:16:11.980Z,1677006971.980 [NAL9602] Loaded
2023-02-21T19:16:11.981Z,1677006971.981 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-02-21T19:16:12.007Z,1677006972.007 [Onboard] Loaded
2023-02-21T19:16:12.007Z,1677006972.007 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-02-21T19:16:12.008Z,1677006972.008 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A244E0
2023-02-21T19:16:12.009Z,1677006972.009 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7672
2023-02-21T19:16:12.021Z,1677006972.021 [Power24vConverter] Loaded
2023-02-21T19:16:12.021Z,1677006972.021 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-02-21T19:16:12.035Z,1677006972.035 [Radio_Surface] Loaded
2023-02-21T19:16:12.035Z,1677006972.035 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-02-21T19:16:12.036Z,1677006972.036 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A544E0
2023-02-21T19:16:12.036Z,1677006972.036 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7673
2023-02-21T19:16:12.129Z,1677006972.129 [DAT] Loaded
2023-02-21T19:16:12.129Z,1677006972.129 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2023-02-21T19:16:12.130Z,1677006972.130 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A844E0
2023-02-21T19:16:12.130Z,1677006972.130 [DAT ThreadHandler](INFO): Protected caller Thread ID is 7674
2023-02-21T19:16:12.131Z,1677006972.131 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-02-21T19:16:12.131Z,1677006972.131 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-02-21T19:16:12.192Z,1677006972.192 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-02-21T19:16:12.192Z,1677006972.192 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-02-21T19:16:12.245Z,1677006972.245 [VerticalControl](DEBUG): Construct VerticalControl.
2023-02-21T19:16:12.296Z,1677006972.296 [VerticalControl] Loaded
2023-02-21T19:16:12.296Z,1677006972.296 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-02-21T19:16:12.299Z,1677006972.299 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-02-21T19:16:12.349Z,1677006972.349 [HorizontalControl] Loaded
2023-02-21T19:16:12.349Z,1677006972.349 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-02-21T19:16:12.352Z,1677006972.352 [SpeedControl](DEBUG): Construct SpeedControl.
2023-02-21T19:16:12.353Z,1677006972.353 [SpeedControl] Loaded
2023-02-21T19:16:12.354Z,1677006972.354 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-02-21T19:16:12.357Z,1677006972.357 [LoopControl](DEBUG): Construct LoopControl.
2023-02-21T19:16:12.357Z,1677006972.357 [LoopControl] Loaded
2023-02-21T19:16:12.357Z,1677006972.357 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-02-21T19:16:12.358Z,1677006972.358 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-02-21T19:16:12.358Z,1677006972.358 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-02-21T19:16:12.369Z,1677006972.369 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-02-21T19:16:12.370Z,1677006972.370 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-02-21T19:16:12.689Z,1677006972.689 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-02-21T19:16:12.696Z,1677006972.696 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-02-21T19:16:12.698Z,1677006972.698 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-02-21T19:16:12.709Z,1677006972.709 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-02-21T19:16:12.710Z,1677006972.710 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B7E4E0
2023-02-21T19:16:12.711Z,1677006972.711 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7675
2023-02-21T19:16:12.715Z,1677006972.715 [Supervisor](INFO): Main Thread ID is 6340
2023-02-21T19:16:12.715Z,1677006972.715 [Supervisor](DEBUG): Running supervisor.
2023-02-21T19:16:12.716Z,1677006972.716 [CommandExec ThreadHandler](INFO): Handler Thread ID is 7676
2023-02-21T19:16:12.717Z,1677006972.717 [CommandExec](INFO): Initializing the command executive.
2023-02-21T19:16:12.718Z,1677006972.718 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7677
2023-02-21T19:16:12.720Z,1677006972.720 [controlThread ThreadHandler](INFO): Handler Thread ID is 7678
2023-02-21T19:16:12.721Z,1677006972.721 [controlThread](DEBUG): Initializing ControlThread
2023-02-21T19:16:12.722Z,1677006972.722 [SBIT](INFO): Initialize SBIT Component.
2023-02-21T19:16:12.723Z,1677006972.723 [SBIT](IMPORTANT): git: 2023-02-15A-1-g98c560e2e
2023-02-21T19:16:12.723Z,1677006972.723 [SBIT](INFO): git hash: 98c560e2e862bfc439cbc2881c3a6b20e1bb1b72
2023-02-21T19:16:12.723Z,1677006972.723 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-02-21T19:16:12.725Z,1677006972.725 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-02-21T19:16:12.726Z,1677006972.726 [SBIT](INFO): Beginning SBIT in 46.000000 seconds.
2023-02-21T19:16:12.726Z,1677006972.726 [IBIT](INFO): Initialize IBIT Component.
2023-02-21T19:16:12.727Z,1677006972.727 [CBIT](DEBUG): Initialize CBIT Component.
2023-02-21T19:16:12.728Z,1677006972.728 [logger ThreadHandler](INFO): Handler Thread ID is 7679
2023-02-21T19:16:12.740Z,1677006972.740 [CBIT](DEBUG): Initialized mux pins.
2023-02-21T19:16:12.740Z,1677006972.740 [CBIT](DEBUG): Initializing the watchdog timer.
2023-02-21T19:16:12.748Z,1677006972.748 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 7680
2023-02-21T19:16:12.763Z,1677006972.763 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-02-21T19:16:12.764Z,1677006972.764 [CBIT](DEBUG): Initializing heartbeat.
2023-02-21T19:16:12.765Z,1677006972.765 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7681
2023-02-21T19:16:12.766Z,1677006972.766 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-02-21T19:16:12.776Z,1677006972.776 [Onboard ThreadHandler](INFO): Handler Thread ID is 7682
2023-02-21T19:16:12.793Z,1677006972.793 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7683
2023-02-21T19:16:12.816Z,1677006972.816 [DAT ThreadHandler](INFO): Handler Thread ID is 7684
2023-02-21T19:16:12.817Z,1677006972.817 [DAT](INFO): Powering up
2023-02-21T19:16:12.817Z,1677006972.817 [DAT](DEBUG): Initializing DAT.
2023-02-21T19:16:12.832Z,1677006972.832 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7686
2023-02-21T19:16:12.835Z,1677006972.835 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-02-21T19:16:12.835Z,1677006972.835 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-02-21T19:16:12.835Z,1677006972.835 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-02-21T19:16:12.835Z,1677006972.835 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-02-21T19:16:12.836Z,1677006972.836 [CBIT](DEBUG): Deactivating GF circuits.
2023-02-21T19:16:12.836Z,1677006972.836 [CBIT](DEBUG): Deactivating emergency mode.
2023-02-21T19:16:12.836Z,1677006972.836 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-02-21T19:16:12.836Z,1677006972.836 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-02-21T19:16:12.836Z,1677006972.836 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-02-21T19:16:12.836Z,1677006972.836 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-02-21T19:16:12.837Z,1677006972.837 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA69M.000
2023-02-21T19:16:12.837Z,1677006972.837 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA69M.000
2023-02-21T19:16:12.872Z,1677006972.872 [CBIT](DEBUG): Backplane powered.
2023-02-21T19:16:12.876Z,1677006972.876 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-02-21T19:16:12.876Z,1677006972.876 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-02-21T19:16:12.877Z,1677006972.877 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-02-21T19:16:12.877Z,1677006972.877 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-02-21T19:16:12.878Z,1677006972.878 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-02-21T19:16:12.879Z,1677006972.879 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-02-21T19:16:12.879Z,1677006972.879 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-02-21T19:16:12.885Z,1677006972.885 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-02-21T19:16:12.886Z,1677006972.886 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-02-21T19:16:12.887Z,1677006972.887 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-02-21T19:16:12.888Z,1677006972.888 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-02-21T19:16:12.889Z,1677006972.889 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-02-21T19:16:12.910Z,1677006972.910 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-02-21T19:16:12.945Z,1677006972.945 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2023-02-21T19:16:12.946Z,1677006972.946 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-02-21T19:16:13.032Z,1677006973.032 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-02-21T19:16:13.033Z,1677006973.033 [Default:A.Wait](DEBUG): Construct Wait.
2023-02-21T19:16:13.056Z,1677006973.056 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-02-21T19:16:13.087Z,1677006973.087 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-02-21T19:16:13.089Z,1677006973.089 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-02-21T19:16:13.103Z,1677006973.103 [Default:E.Execute](DEBUG): Construct Execute.
2023-02-21T19:16:13.107Z,1677006973.107 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2023-02-21T19:16:13.124Z,1677006973.124 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,MultiRay,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-02-21T19:16:13.134Z,1677006973.134 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-02-21T19:16:13.200Z,1677006973.200 [Radio_Surface](INFO): Powering up
2023-02-21T19:16:13.208Z,1677006973.208 [Depth_Keller](ERROR): Pressure reading out of range: 1895.444336 decibar
2023-02-21T19:16:13.209Z,1677006973.209 [MultiRay](INFO): Powering up MultiRay Lights
2023-02-21T19:16:13.249Z,1677006973.249 [Power24vConverter](INFO): Powering up.
2023-02-21T19:16:13.256Z,1677006973.256 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-02-21T19:16:13.298Z,1677006973.298 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-02-21T19:16:13.304Z,1677006973.304 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-02-21T19:16:13.305Z,1677006973.305 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-02-21T19:16:13.312Z,1677006973.312 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-02-21T19:16:13.313Z,1677006973.313 [MassServo](DEBUG): Initializing EZServoServo.
2023-02-21T19:16:13.321Z,1677006973.321 [MassServo](DEBUG): Initializing MassServo.
2023-02-21T19:16:13.321Z,1677006973.321 [RudderServo](DEBUG): Initializing EZServoServo.
2023-02-21T19:16:13.328Z,1677006973.328 [RudderServo](DEBUG): Initializing RudderServo.
2023-02-21T19:16:13.329Z,1677006973.329 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-02-21T19:16:13.336Z,1677006973.336 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-02-21T19:16:14.284Z,1677006974.284 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2023-02-21T19:16:14.284Z,1677006974.284 [RudderServo](FAULT): Rudder failed to initialize
2023-02-21T19:16:14.284Z,1677006974.284 [RudderServo] Communications Fault, FailCount= 1
2023-02-21T19:16:14.284Z,1677006974.284 [RudderServo](ERROR): Communications Fault
2023-02-21T19:16:14.288Z,1677006974.288 [CBIT](ERROR): Communications Fault in component: RudderServo
2023-02-21T19:16:14.725Z,1677006974.725 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-02-21T19:16:14.725Z,1677006974.725 [RudderServo](INFO): Powering down
2023-02-21T19:16:15.168Z,1677006975.168 [RudderServo](DEBUG): Initializing EZServoServo.
2023-02-21T19:16:15.289Z,1677006975.289 [RudderServo](DEBUG): Initializing RudderServo.
2023-02-21T19:16:15.292Z,1677006975.292 [CBIT](INFO): Clearing failed state for component RudderServo
2023-02-21T19:16:15.292Z,1677006975.292 [RudderServo] No Fault, FailCount= 1
2023-02-21T19:16:19.004Z,1677006979.004 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-02-21T19:16:24.408Z,1677006984.408 [DAT](INFO): DAT read: 

2023-02-21T19:16:24.410Z,1677006984.410 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-02-21T19:16:25.921Z,1677006985.921 [DAT](INFO): DAT read: MF Frequency Band

2023-02-21T19:16:25.922Z,1677006985.922 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-02-21T19:16:25.922Z,1677006985.922 [DAT](INFO): DAT read: Feb 21 2023 19:16:20 

2023-02-21T19:16:27.181Z,1677006987.181 [DAT](INFO): DAT read: Features enabled [Bearing]

2023-02-21T19:16:27.182Z,1677006987.182 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC

2023-02-21T19:16:27.182Z,1677006987.182 [DAT](INFO): commRate: 1200
2023-02-21T19:16:29.248Z,1677006989.248 [DAT](INFO): entering command mode
2023-02-21T19:16:29.448Z,1677006989.448 [DAT](INFO): DAT read: 

2023-02-21T19:16:29.449Z,1677006989.449 [DAT](INFO): DAT read: user:1>

2023-02-21T19:16:29.449Z,1677006989.449 [DAT](INFO): setting verbose to 3
2023-02-21T19:16:29.700Z,1677006989.700 [DAT](INFO): DAT read: user:1>

2023-02-21T19:16:29.701Z,1677006989.701 [DAT](INFO): DAT read: Verbose         | 3           

2023-02-21T19:16:29.702Z,1677006989.702 [DAT](INFO): set verbose to 3
2023-02-21T19:16:29.702Z,1677006989.702 [DAT](INFO): setting DatVerbose to 27440
2023-02-21T19:16:29.952Z,1677006989.952 [DAT](INFO): DAT read: user:2>

2023-02-21T19:16:29.953Z,1677006989.953 [DAT](INFO): DAT read: DatVerbose      | 27440       

2023-02-21T19:16:29.954Z,1677006989.954 [DAT](INFO): set DatVerbose to 27440
2023-02-21T19:16:29.954Z,1677006989.954 [DAT](INFO): setting transmit power to 8
2023-02-21T19:16:30.204Z,1677006990.204 [DAT](INFO): DAT read: user:3>

2023-02-21T19:16:30.205Z,1677006990.205 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2023-02-21T19:16:30.206Z,1677006990.206 [DAT](INFO): set transmit power to 8
2023-02-21T19:16:30.206Z,1677006990.206 [DAT](INFO): setting local address to 4
2023-02-21T19:16:30.456Z,1677006990.456 [DAT](INFO): DAT read: user:4>

2023-02-21T19:16:30.457Z,1677006990.457 [DAT](INFO): DAT read: LocalAddr       | 4           

2023-02-21T19:16:30.458Z,1677006990.458 [DAT](INFO): set local address to 4
2023-02-21T19:16:30.459Z,1677006990.459 [DAT](INFO): Setting time to: 19:16:30 And date to:2/21/2023
2023-02-21T19:16:30.708Z,1677006990.708 [DAT](INFO): DAT read: user:5>

2023-02-21T19:16:30.709Z,1677006990.709 [DAT](INFO): DAT read: Tue Feb 21, 2023  19:16:30  

2023-02-21T19:16:30.709Z,1677006990.709 [DAT](INFO): Local DAT time set to Tue Feb 21, 2023  19:16:30  

2023-02-21T19:16:42.229Z,1677007002.229 [NAL9602](INFO): Powering up NAL9602
2023-02-21T19:16:53.135Z,1677007013.135 [NAL9602](INFO): NAL9602 initialized
2023-02-21T19:16:59.207Z,1677007019.207 [SBIT](IMPORTANT): Beginning Startup BIT
2023-02-21T19:16:59.211Z,1677007019.211 [CBIT](IMPORTANT): Beginning ground fault scan
2023-02-21T19:17:10.219Z,1677007030.219 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.006192
CHAN A1 (24V): 0.001360
CHAN A2 (12V): -0.004873
CHAN A3 (5V): -0.002401
CHAN B0 (3.3V): -0.000766
CHAN B1 (3.15aV): -0.000896
CHAN B2 (3.15bV): -0.000922
CHAN B3 (GND): -0.000203
OPEN: 0.006789
Full Scale: +/- 1 mA
2023-02-21T19:17:12.214Z,1677007032.214 [SBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.527149
2023-02-21T19:17:14.152Z,1677007034.152 [BPC1](INFO): BPC1A: initialized.
2023-02-21T19:17:14.164Z,1677007034.164 [BPC1](INFO): BPC1B: initialized.
2023-02-21T19:17:21.415Z,1677007041.415 [BPC1](INFO): BPC1A: sending IBPS break.
2023-02-21T19:17:21.816Z,1677007041.816 [BPC1](INFO): BPC1B: sending IBPS break.
2023-02-21T19:17:25.856Z,1677007045.856 [BPC1](INFO): BPC1A: validating data format.
2023-02-21T19:17:25.930Z,1677007045.930 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2023-02-21T19:17:25.930Z,1677007045.930 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2023-02-21T19:17:26.260Z,1677007046.260 [BPC1](INFO): BPC1A got IPBS message with 8 sticks.
2023-02-21T19:17:26.261Z,1677007046.261 [BPC1](INFO): BPC1A parsing battery stick message: $B11,02,000A,01,02A8,03,0001,08,0B91,09,3B83,0A,FFFC,0B,FFFE%4D
$B11,0C,0001,0D,003D,0E,003A,0F,0F51,10,196E,11,E5BF,12,FFFF%3D
$B11,13,FFFF,14,0BB8,15,41A0,16,00C0,17,000A,18,1A90,19,3840%49
$B11,1A,0031,1B,4E6B,1C,04D7%43

2023-02-21T19:17:26.261Z,1677007046.261 [BPC1](INFO): Looking for stick 04D7to match 0C1E
2023-02-21T19:17:26.261Z,1677007046.261 [BPC1](INFO): Looking for stick 04D7to match 0481
2023-02-21T19:17:26.262Z,1677007046.262 [BPC1](INFO): Looking for stick 04D7to match 047B
2023-02-21T19:17:26.262Z,1677007046.262 [BPC1](INFO): Looking for stick 04D7to match 0464
2023-02-21T19:17:26.262Z,1677007046.262 [BPC1](INFO): Looking for stick 04D7to match 045C
2023-02-21T19:17:26.263Z,1677007046.263 [BPC1](INFO): Looking for stick 04D7to match 046A
2023-02-21T19:17:26.263Z,1677007046.263 [BPC1](INFO): Looking for stick 04D7to match 045A
2023-02-21T19:17:26.264Z,1677007046.264 [BPC1](INFO): Looking for stick 04D7to match 0487
2023-02-21T19:17:26.264Z,1677007046.264 [BPC1](INFO): Looking for stick 04D7to match 03FE
2023-02-21T19:17:26.264Z,1677007046.264 [BPC1](INFO): Looking for stick 04D7to match 0465
2023-02-21T19:17:26.265Z,1677007046.265 [BPC1](INFO): Looking for stick 04D7to match 046B
2023-02-21T19:17:26.265Z,1677007046.265 [BPC1](INFO): Looking for stick 04D7to match 0445
2023-02-21T19:17:26.265Z,1677007046.265 [BPC1](INFO): Looking for stick 04D7to match 040D
2023-02-21T19:17:26.266Z,1677007046.266 [BPC1](INFO): Looking for stick 04D7to match 04AA
2023-02-21T19:17:26.266Z,1677007046.266 [BPC1](INFO): Looking for stick 04D7to match 049A
2023-02-21T19:17:26.266Z,1677007046.266 [BPC1](INFO): Looking for stick 04D7to match 04C6
2023-02-21T19:17:26.267Z,1677007046.267 [BPC1](INFO): Looking for stick 04D7to match 0486
2023-02-21T19:17:26.267Z,1677007046.267 [BPC1](INFO): Looking for stick 04D7to match 0442
2023-02-21T19:17:26.267Z,1677007046.267 [BPC1](INFO): Looking for stick 04D7to match 0457
2023-02-21T19:17:26.268Z,1677007046.268 [BPC1](INFO): Looking for stick 04D7to match 0455
2023-02-21T19:17:26.269Z,1677007046.269 [BPC1](INFO): Looking for stick 04D7to match 04C0
2023-02-21T19:17:26.269Z,1677007046.269 [BPC1](INFO): Looking for stick 04D7to match 0459
2023-02-21T19:17:26.269Z,1677007046.269 [BPC1](INFO): Looking for stick 04D7to match 045F
2023-02-21T19:17:26.270Z,1677007046.270 [BPC1](INFO): Looking for stick 04D7to match 0449
2023-02-21T19:17:26.270Z,1677007046.270 [BPC1](INFO): Looking for stick 04D7to match 0441
2023-02-21T19:17:26.270Z,1677007046.270 [BPC1](INFO): Looking for stick 04D7to match 0460
2023-02-21T19:17:26.271Z,1677007046.271 [BPC1](INFO): Looking for stick 04D7to match 04A8
2023-02-21T19:17:26.271Z,1677007046.271 [BPC1](INFO): Looking for stick 04D7to match 04BF
2023-02-21T19:17:26.271Z,1677007046.271 [BPC1](INFO): Looking for stick 04D7to match 045D
2023-02-21T19:17:26.272Z,1677007046.272 [BPC1](INFO): Looking for stick 04D7to match 04A4
2023-02-21T19:17:26.272Z,1677007046.272 [BPC1](INFO): Looking for stick 04D7to match 04D9
2023-02-21T19:17:26.273Z,1677007046.273 [BPC1](ERROR): BPC1A: No match for serial number 04D7 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2023-02-21T19:17:26.273Z,1677007046.273 [BPC1](INFO): BPC1B: validating data format.
2023-02-21T19:17:26.418Z,1677007046.418 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with LONG_IBPS_MENU.
2023-02-21T19:17:26.418Z,1677007046.418 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2023-02-21T19:17:26.675Z,1677007046.675 [BPC1](INFO): BPC1B got IPBS message with 8 sticks.
2023-02-21T19:17:26.676Z,1677007046.676 [BPC1](INFO): BPC1B parsing battery stick message: $B11,02,000A,01,02A8,03,0001,08,0B86,09,3B4F,0A,0007,0B,0005%36
$B11,0C,0001,0D,003D,0E,003B,0F,0F8F,10,19E4,11,FFFF,12,FFFF%32
$B11,13,7BFC,14,0BB8,15,41A0,16,00C0,17,000E,18,1A90,19,3840%3D
$B11,1A,0031,1B,4E68,1C,0486%44

2023-02-21T19:17:26.677Z,1677007046.677 [BPC1](INFO): Looking for stick 0486to match 0BE5
2023-02-21T19:17:26.677Z,1677007046.677 [BPC1](INFO): Looking for stick 0486to match 0124
2023-02-21T19:17:26.677Z,1677007046.677 [BPC1](INFO): Looking for stick 0486to match 0162
2023-02-21T19:17:26.678Z,1677007046.678 [BPC1](INFO): Looking for stick 0486to match 017A
2023-02-21T19:17:26.678Z,1677007046.678 [BPC1](INFO): Looking for stick 0486to match 0495
2023-02-21T19:17:26.678Z,1677007046.678 [BPC1](INFO): Looking for stick 0486to match 0458
2023-02-21T19:17:26.679Z,1677007046.679 [BPC1](INFO): Looking for stick 0486to match 0139
2023-02-21T19:17:26.679Z,1677007046.679 [BPC1](INFO): Looking for stick 0486to match 00F0
2023-02-21T19:17:26.679Z,1677007046.679 [BPC1](INFO): Looking for stick 0486to match 0451
2023-02-21T19:17:26.681Z,1677007046.681 [BPC1](INFO): Looking for stick 0486to match 049E
2023-02-21T19:17:26.681Z,1677007046.681 [BPC1](INFO): Looking for stick 0486to match 04A2
2023-02-21T19:17:26.681Z,1677007046.681 [BPC1](INFO): Looking for stick 0486to match 044F
2023-02-21T19:17:26.682Z,1677007046.682 [BPC1](INFO): Looking for stick 0486to match 047F
2023-02-21T19:17:26.682Z,1677007046.682 [BPC1](INFO): Looking for stick 0486to match 0496
2023-02-21T19:17:26.682Z,1677007046.682 [BPC1](INFO): Looking for stick 0486to match 04CE
2023-02-21T19:17:26.683Z,1677007046.683 [BPC1](INFO): Looking for stick 0486to match 04A5
2023-02-21T19:17:26.683Z,1677007046.683 [BPC1](INFO): Looking for stick 0486to match 04D7
2023-02-21T19:17:26.683Z,1677007046.683 [BPC1](INFO): Looking for stick 0486to match 04CA
2023-02-21T19:17:26.684Z,1677007046.684 [BPC1](INFO): Looking for stick 0486to match 04A1
2023-02-21T19:17:26.684Z,1677007046.684 [BPC1](INFO): Looking for stick 0486to match 049B
2023-02-21T19:17:26.684Z,1677007046.684 [BPC1](INFO): Looking for stick 0486to match 04BD
2023-02-21T19:17:26.685Z,1677007046.685 [BPC1](INFO): Looking for stick 0486to match 0494
2023-02-21T19:17:26.685Z,1677007046.685 [BPC1](INFO): Looking for stick 0486to match 0466
2023-02-21T19:17:26.685Z,1677007046.685 [BPC1](INFO): Looking for stick 0486to match 0447
2023-02-21T19:17:26.686Z,1677007046.686 [BPC1](INFO): Looking for stick 0486to match 0480
2023-02-21T19:17:26.686Z,1677007046.686 [BPC1](INFO): Looking for stick 0486to match 04A3
2023-02-21T19:17:26.686Z,1677007046.686 [BPC1](INFO): Looking for stick 0486to match 04C5
2023-02-21T19:17:26.687Z,1677007046.687 [BPC1](INFO): Looking for stick 0486to match 046D
2023-02-21T19:17:26.687Z,1677007046.687 [BPC1](INFO): Looking for stick 0486to match 0409
2023-02-21T19:17:26.688Z,1677007046.688 [BPC1](INFO): Looking for stick 0486to match 04D3
2023-02-21T19:17:26.689Z,1677007046.689 [BPC1](INFO): Looking for stick 0486to match 0493
2023-02-21T19:17:26.689Z,1677007046.689 [BPC1](ERROR): BPC1B: No match for serial number 0486 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-02-21T19:17:26.690Z,1677007046.690 [BPC1](FAULT): Failed to parse data from all battery packs.
2023-02-21T19:17:26.690Z,1677007046.690 [BPC1] Data Fault, FailCount= 1
2023-02-21T19:17:26.690Z,1677007046.690 [BPC1](ERROR): Data Fault
2023-02-21T19:17:26.748Z,1677007046.748 [CBIT](ERROR): Data Fault in component: BPC1
2023-02-21T19:17:52.945Z,1677007072.945 [SBIT](CRITICAL): SBIT FAILED
2023-02-21T19:17:52.946Z,1677007072.946 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-02-21T19:17:52.946Z,1677007072.946 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool;

2023-02-21T19:17:52.947Z,1677007072.947 [SBIT](IMPORTANT): CBIT.vehicleOpen=1 bool;

2023-02-21T19:17:52.947Z,1677007072.947 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2023-02-21T19:17:52.947Z,1677007072.947 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;

2023-02-21T19:17:52.947Z,1677007072.947 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum;

2023-02-21T19:17:52.947Z,1677007072.947 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2023-02-21T19:17:52.947Z,1677007072.947 [SBIT](IMPORTANT): Depth_Keller.offset=-0.8 decibar;

2023-02-21T19:17:52.947Z,1677007072.947 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2023-02-21T19:17:52.948Z,1677007072.948 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=4 minute;

2023-02-21T19:17:52.948Z,1677007072.948 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2023-02-21T19:17:52.948Z,1677007072.948 [SBIT](IMPORTANT): ElevatorServo.offsetAngle=1 degree;

2023-02-21T19:17:52.948Z,1677007072.948 [SBIT](IMPORTANT): Express linearApproximation NAL9602.sigQuality 1.000000 count;

2023-02-21T19:17:52.948Z,1677007072.948 [SBIT](IMPORTANT): Express none WetLabsBB2FL.BackscatteringCoeff470nm;

2023-02-21T19:17:52.948Z,1677007072.948 [SBIT](IMPORTANT): Express none WetLabsBB2FL.BackscatteringCoeff650nm;

2023-02-21T19:17:52.948Z,1677007072.948 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=86.344810 cubic_centimeter;

2023-02-21T19:17:52.949Z,1677007072.949 [SBIT](IMPORTANT): VerticalControl.massDefault=-6.557592 millimeter;

2023-02-21T19:17:52.949Z,1677007072.949 [SBIT](IMPORTANT): WetLabsBB2FL.loadAtStartup=0 bool;

2023-02-21T19:17:52.950Z,1677007072.950 [CommandExec](FAULT): Scheduling is paused
2023-02-21T19:17:52.951Z,1677007072.951 [CBIT](INFO): Critical error at 20230221T191752
2023-02-21T19:17:52.951Z,1677007072.951 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-02-21T19:17:53.361Z,1677007073.361 [MissionManager](IMPORTANT): Started mission Startup
2023-02-21T19:17:53.362Z,1677007073.362 [Startup] Running Loop=1
2023-02-21T19:17:53.362Z,1677007073.362 [Startup](DEBUG): Aggregate::initialize Startup
2023-02-21T19:17:53.362Z,1677007073.362 [Startup:A.GoToSurface] Running Loop=1
2023-02-21T19:17:53.362Z,1677007073.362 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-02-21T19:17:53.362Z,1677007073.362 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-02-21T19:17:53.363Z,1677007073.363 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-02-21T19:17:53.363Z,1677007073.363 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-02-21T19:17:53.364Z,1677007073.364 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-02-21T19:17:53.364Z,1677007073.364 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-02-21T19:17:53.366Z,1677007073.366 [Startup:StartupSatComms] Running Loop=1
2023-02-21T19:17:53.366Z,1677007073.366 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-02-21T19:17:53.366Z,1677007073.366 [Startup:StartupSatComms:A] Running Loop=1
2023-02-21T19:17:53.769Z,1677007073.769 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-02-21T19:18:53.552Z,1677007133.552 [Startup:StartupSatComms:A](INFO): Timed out from 2023-02-21T19:17:53.4Z
2023-02-21T19:18:53.552Z,1677007133.552 [Startup:StartupSatComms:A] Stopped
2023-02-21T19:18:53.552Z,1677007133.552 [Startup:StartupSatComms:B] Running Loop=1
2023-02-21T19:18:53.952Z,1677007133.952 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-02-21T19:18:55.718Z,1677007135.718 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004971
2023-02-21T19:19:06.934Z,1677007146.934 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230221T185859/Courier0004.lzma
2023-02-21T19:19:07.934Z,1677007147.934 [DataOverHttps](INFO): Moved sent file to Logs/20230221T185859/Courier0004.lzma.bak
2023-02-21T19:19:07.935Z,1677007147.935 [DataOverHttps](INFO): SBD MOMSN=17484317
2023-02-21T19:19:12.971Z,1677007152.971 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-02-21T19:19:12.971Z,1677007152.971 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-21T19:19:12.981Z,1677007152.981 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-21T19:19:13.377Z,1677007153.377 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-21T19:19:13.377Z,1677007153.377 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-02-21T19:19:30.107Z,1677007170.107 [DataOverHttps](INFO): Sending 184 bytes from file Logs/20230221T190635/Courier0000.lzma
2023-02-21T19:19:31.109Z,1677007171.109 [DataOverHttps](INFO): Moved sent file to Logs/20230221T190635/Courier0000.lzma.bak
2023-02-21T19:19:31.110Z,1677007171.110 [DataOverHttps](INFO): SBD MOMSN=17484319
2023-02-21T19:19:50.779Z,1677007190.779 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230221T190635/Courier0004.lzma
2023-02-21T19:19:51.781Z,1677007191.781 [DataOverHttps](INFO): Moved sent file to Logs/20230221T190635/Courier0004.lzma.bak
2023-02-21T19:19:51.782Z,1677007191.782 [DataOverHttps](INFO): SBD MOMSN=17484323
2023-02-21T19:19:53.753Z,1677007193.753 [Startup:StartupSatComms:B](INFO): Timed out from 2023-02-21T19:18:53.6Z
2023-02-21T19:19:53.753Z,1677007193.753 [Startup:StartupSatComms:B] Stopped
2023-02-21T19:19:53.753Z,1677007193.753 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-02-21T19:19:53.753Z,1677007193.753 [Startup:StartupSatComms] Stopped
2023-02-21T19:19:53.753Z,1677007193.753 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-02-21T19:19:53.754Z,1677007193.754 [Startup](INFO): Completed Startup
2023-02-21T19:19:53.754Z,1677007193.754 [MissionManager](INFO): Startup is completed.
2023-02-21T19:19:53.754Z,1677007193.754 [MissionManager](INFO): Uninitializing Mission Startup
2023-02-21T19:19:53.754Z,1677007193.754 [Startup] Stopped
2023-02-21T19:19:53.754Z,1677007193.754 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-02-21T19:19:53.754Z,1677007193.754 [Startup:A.GoToSurface] Stopped
2023-02-21T19:19:53.754Z,1677007193.754 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-02-21T19:19:54.166Z,1677007194.166 [MissionManager](IMPORTANT): Started mission Default
2023-02-21T19:19:54.166Z,1677007194.166 [Default] Running Loop=1
2023-02-21T19:19:54.167Z,1677007194.167 [Default](DEBUG): Aggregate::initialize Default
2023-02-21T19:19:54.167Z,1677007194.167 [Default:B.GoToSurface] Running Loop=1
2023-02-21T19:19:54.167Z,1677007194.167 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-02-21T19:19:54.167Z,1677007194.167 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-02-21T19:19:54.172Z,1677007194.172 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-02-21T19:19:54.172Z,1677007194.172 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-02-21T19:19:54.172Z,1677007194.172 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-02-21T19:19:54.173Z,1677007194.173 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-02-21T19:19:54.173Z,1677007194.173 [Default:A.Wait] Running Loop=1
2023-02-21T19:19:54.173Z,1677007194.173 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-02-21T19:20:07.508Z,1677007207.508 [Default:A.Wait](INFO): Done Waiting.
2023-02-21T19:20:07.509Z,1677007207.509 [Default:A.Wait] Stopped
2023-02-21T19:20:07.509Z,1677007207.509 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-02-21T19:20:07.904Z,1677007207.904 [Default:CheckIn] Running Loop=1
2023-02-21T19:20:07.904Z,1677007207.904 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-02-21T19:20:07.904Z,1677007207.904 [Default:CheckIn:Read_GPS] Running Loop=1
2023-02-21T19:20:08.286Z,1677007208.286 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-02-21T19:20:33.735Z,1677007233.735 [DataOverHttps](INFO): Sending 184 bytes from file Logs/20230221T190943/Courier0000.lzma
2023-02-21T19:20:34.737Z,1677007234.737 [DataOverHttps](INFO): Moved sent file to Logs/20230221T190943/Courier0000.lzma.bak
2023-02-21T19:20:34.738Z,1677007234.738 [DataOverHttps](INFO): SBD MOMSN=17484325
2023-02-21T19:21:56.130Z,1677007316.130 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-02-21T19:22:13.932Z,1677007333.932 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-02-21T19:22:13.932Z,1677007333.932 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-21T19:22:13.947Z,1677007333.947 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-21T19:22:14.402Z,1677007334.402 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-21T19:22:14.402Z,1677007334.402 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-02-21T19:25:08.069Z,1677007508.069 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-02-21T19:20:07.9Z
2023-02-21T19:25:08.069Z,1677007508.069 [Default:CheckIn:Read_GPS] Stopped
2023-02-21T19:25:08.070Z,1677007508.070 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-02-21T19:25:08.455Z,1677007508.455 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-02-21T19:25:14.916Z,1677007514.916 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-02-21T19:25:14.916Z,1677007514.916 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-21T19:25:14.926Z,1677007514.926 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-21T19:25:15.337Z,1677007515.337 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-21T19:25:15.337Z,1677007515.337 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-02-21T19:25:19.674Z,1677007519.674 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230221T190943/Courier0004.lzma
2023-02-21T19:25:20.673Z,1677007520.673 [DataOverHttps](INFO): Moved sent file to Logs/20230221T190943/Courier0004.lzma.bak
2023-02-21T19:25:20.673Z,1677007520.673 [DataOverHttps](INFO): SBD MOMSN=17484337
2023-02-21T19:25:47.236Z,1677007547.236 [DataOverHttps](INFO): Sending 184 bytes from file Logs/20230221T191251/Courier0000.lzma
2023-02-21T19:25:48.237Z,1677007548.237 [DataOverHttps](INFO): Moved sent file to Logs/20230221T191251/Courier0000.lzma.bak
2023-02-21T19:25:48.238Z,1677007548.238 [DataOverHttps](INFO): SBD MOMSN=17484339
2023-02-21T19:26:09.195Z,1677007569.195 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230221T191251/Courier0004.lzma
2023-02-21T19:26:11.882Z,1677007571.882 [DataOverHttps](INFO): Moved sent file to Logs/20230221T191251/Courier0004.lzma.bak
2023-02-21T19:26:11.882Z,1677007571.882 [DataOverHttps](INFO): SBD MOMSN=17484343
2023-02-21T19:26:33.427Z,1677007593.427 [DataOverHttps](INFO): Sending 184 bytes from file Logs/20230221T191602/Courier0000.lzma
2023-02-21T19:26:34.430Z,1677007594.430 [DataOverHttps](INFO): Moved sent file to Logs/20230221T191602/Courier0000.lzma.bak
2023-02-21T19:26:34.430Z,1677007594.430 [DataOverHttps](INFO): SBD MOMSN=17484345
2023-02-21T19:26:54.298Z,1677007614.298 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-02-21T19:26:54.298Z,1677007614.298 [NAL9602] Data Fault, FailCount= 1
2023-02-21T19:26:54.298Z,1677007614.298 [NAL9602](ERROR): Data Fault
2023-02-21T19:26:54.366Z,1677007614.366 [CBIT](ERROR): Data Fault in component: NAL9602
2023-02-21T19:26:54.770Z,1677007614.770 [NAL9602](INFO): Powering down
2023-02-21T19:26:55.139Z,1677007615.139 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230221T191602/Courier0004.lzma
2023-02-21T19:26:55.637Z,1677007615.637 [CBIT](INFO): Clearing failed state for component NAL9602
2023-02-21T19:26:55.637Z,1677007615.637 [NAL9602] No Fault, FailCount= 1
2023-02-21T19:26:56.141Z,1677007616.141 [DataOverHttps](INFO): Moved sent file to Logs/20230221T191602/Courier0004.lzma.bak
2023-02-21T19:26:56.142Z,1677007616.142 [DataOverHttps](INFO): SBD MOMSN=17484349
2023-02-21T19:27:22.456Z,1677007642.456 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20230221T175633/Express0020.lzma
2023-02-21T19:27:23.457Z,1677007643.457 [DataOverHttps](INFO): Moved sent file to Logs/20230221T175633/Express0020.lzma.bak
2023-02-21T19:27:23.458Z,1677007643.458 [DataOverHttps](INFO): SBD MOMSN=17484351
2023-02-21T19:27:25.003Z,1677007645.003 [NAL9602](INFO): Powering up NAL9602
2023-02-21T19:27:27.888Z,1677007647.888 [CBIT](INFO): Clearing failed state for component BPC1
2023-02-21T19:27:27.888Z,1677007647.888 [BPC1] No Fault, FailCount= 1
2023-02-21T19:27:35.909Z,1677007655.909 [NAL9602](INFO): NAL9602 initialized
2023-02-21T19:27:52.278Z,1677007672.278 [DataOverHttps](INFO): Sending 1062 bytes from file Logs/20230221T185422/Express0001.lzma
2023-02-21T19:27:53.233Z,1677007673.233 [DataOverHttps](INFO): Moved sent file to Logs/20230221T185422/Express0001.lzma.bak
2023-02-21T19:27:53.234Z,1677007673.234 [DataOverHttps](INFO): SBD MOMSN=17484356
2023-02-21T19:28:15.928Z,1677007695.928 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-02-21T19:28:15.928Z,1677007695.928 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-21T19:28:15.943Z,1677007695.943 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-21T19:28:16.356Z,1677007696.356 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-21T19:28:16.356Z,1677007696.356 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-02-21T19:28:17.924Z,1677007697.924 [DataOverHttps](INFO): Sending 125 bytes from file Logs/20230221T185422/Express0005.lzma
2023-02-21T19:28:18.926Z,1677007698.926 [DataOverHttps](INFO): Moved sent file to Logs/20230221T185422/Express0005.lzma.bak
2023-02-21T19:28:18.926Z,1677007698.926 [DataOverHttps](INFO): SBD MOMSN=17484385
2023-02-21T19:28:29.264Z,1677007709.264 [BPC1](INFO): BPC1A: initialized.
2023-02-21T19:28:29.276Z,1677007709.276 [BPC1](INFO): BPC1B: initialized.
2023-02-21T19:28:30.868Z,1677007710.868 [BPC1](INFO): BPC1A: sending IBPS break.
2023-02-21T19:28:30.868Z,1677007710.868 [BPC1](INFO): BPC1B: sending IBPS break.
2023-02-21T19:28:34.924Z,1677007714.924 [BPC1](INFO): BPC1B: validating data format.
2023-02-21T19:28:34.977Z,1677007714.977 [BPC1](INFO): BPC1B: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2023-02-21T19:28:34.977Z,1677007714.977 [BPC1](INFO): BPC1B: got valid message (1824 bytes).
2023-02-21T19:28:35.312Z,1677007715.312 [BPC1](INFO): BPC1A: validating data format.
2023-02-21T19:28:35.357Z,1677007715.357 [BPC1](INFO): BPC1A: got 8 stick msg (1824 bytes) with SHORT_IBPS_MENU.
2023-02-21T19:28:35.358Z,1677007715.358 [BPC1](INFO): BPC1A: got valid message (1824 bytes).
2023-02-21T19:28:35.358Z,1677007715.358 [BPC1](INFO): BPC1B got IPBS message with 8 sticks.
2023-02-21T19:28:35.359Z,1677007715.359 [BPC1](INFO): BPC1B parsing battery stick message: $B11,02,000A,01,02A8,03,0001,08,0B86,09,3BD6,0A,FFFB,0B,FFFB%34
$B11,0C,0001,0D,003E,0E,003B,0F,0F9C,10,1940,11,BB50,12,BB50%42
$B11,13,FFFF,14,0BB8,15,41A0,16,00C0,17,000E,18,1A90,19,3840%4D
$B11,1A,0031,1B,4E6B,1C,04CC%30

2023-02-21T19:28:35.376Z,1677007715.376 [BPC1](INFO): Looking for stick 04CCto match 0BE5
2023-02-21T19:28:35.376Z,1677007715.376 [BPC1](INFO): Looking for stick 04CCto match 0124
2023-02-21T19:28:35.376Z,1677007715.376 [BPC1](INFO): Looking for stick 04CCto match 0162
2023-02-21T19:28:35.377Z,1677007715.377 [BPC1](INFO): Looking for stick 04CCto match 017A
2023-02-21T19:28:35.377Z,1677007715.377 [BPC1](INFO): Looking for stick 04CCto match 0495
2023-02-21T19:28:35.377Z,1677007715.377 [BPC1](INFO): Looking for stick 04CCto match 0458
2023-02-21T19:28:35.378Z,1677007715.378 [BPC1](INFO): Looking for stick 04CCto match 0139
2023-02-21T19:28:35.378Z,1677007715.378 [BPC1](INFO): Looking for stick 04CCto match 00F0
2023-02-21T19:28:35.378Z,1677007715.378 [BPC1](INFO): Looking for stick 04CCto match 0451
2023-02-21T19:28:35.379Z,1677007715.379 [BPC1](INFO): Looking for stick 04CCto match 049E
2023-02-21T19:28:35.379Z,1677007715.379 [BPC1](INFO): Looking for stick 04CCto match 04A2
2023-02-21T19:28:35.379Z,1677007715.379 [BPC1](INFO): Looking for stick 04CCto match 044F
2023-02-21T19:28:35.384Z,1677007715.384 [BPC1](INFO): Looking for stick 04CCto match 047F
2023-02-21T19:28:35.385Z,1677007715.385 [BPC1](INFO): Looking for stick 04CCto match 0496
2023-02-21T19:28:35.396Z,1677007715.396 [BPC1](INFO): Looking for stick 04CCto match 04CE
2023-02-21T19:28:35.396Z,1677007715.396 [BPC1](INFO): Looking for stick 04CCto match 04A5
2023-02-21T19:28:35.397Z,1677007715.397 [BPC1](INFO): Looking for stick 04CCto match 04D7
2023-02-21T19:28:35.397Z,1677007715.397 [BPC1](INFO): Looking for stick 04CCto match 04CA
2023-02-21T19:28:35.397Z,1677007715.397 [BPC1](INFO): Looking for stick 04CCto match 04A1
2023-02-21T19:28:35.397Z,1677007715.397 [BPC1](INFO): Looking for stick 04CCto match 049B
2023-02-21T19:28:35.398Z,1677007715.398 [BPC1](INFO): Looking for stick 04CCto match 04BD
2023-02-21T19:28:35.398Z,1677007715.398 [BPC1](INFO): Looking for stick 04CCto match 0494
2023-02-21T19:28:35.398Z,1677007715.398 [BPC1](INFO): Looking for stick 04CCto match 0466
2023-02-21T19:28:35.399Z,1677007715.399 [BPC1](INFO): Looking for stick 04CCto match 0447
2023-02-21T19:28:35.399Z,1677007715.399 [BPC1](INFO): Looking for stick 04CCto match 0480
2023-02-21T19:28:35.408Z,1677007715.408 [BPC1](INFO): Looking for stick 04CCto match 04A3
2023-02-21T19:28:35.408Z,1677007715.408 [BPC1](INFO): Looking for stick 04CCto match 04C5
2023-02-21T19:28:35.408Z,1677007715.408 [BPC1](INFO): Looking for stick 04CCto match 046D
2023-02-21T19:28:35.409Z,1677007715.409 [BPC1](INFO): Looking for stick 04CCto match 0409
2023-02-21T19:28:35.409Z,1677007715.409 [BPC1](INFO): Looking for stick 04CCto match 04D3
2023-02-21T19:28:35.409Z,1677007715.409 [BPC1](INFO): Looking for stick 04CCto match 0493
2023-02-21T19:28:35.410Z,1677007715.410 [BPC1](ERROR): BPC1B: No match for serial number 04CC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-02-21T19:28:35.716Z,1677007715.716 [BPC1](INFO): BPC1A got IPBS message with 8 sticks.
2023-02-21T19:28:35.717Z,1677007715.717 [BPC1](INFO): BPC1A parsing battery stick message: $B11,02,000A,01,02A8,03,0001,08,0B82,09,3B8E,0A,0000,0B,0000%3F
$B11,0C,0002,0D,0041,0E,003F,0F,10A3,10,19F0,11,FFFF,12,FFFF%3B
$B11,13,FFFF,14,0BB8,15,41A0,16,00C0,17,001A,18,1A90,19,3840%48
$B11,1A,0031,1B,458F,1C,018D%33

2023-02-21T19:28:35.717Z,1677007715.717 [BPC1](INFO): Looking for stick 018Dto match 0C1E
2023-02-21T19:28:35.717Z,1677007715.717 [BPC1](INFO): Looking for stick 018Dto match 0481
2023-02-21T19:28:35.718Z,1677007715.718 [BPC1](INFO): Looking for stick 018Dto match 047B
2023-02-21T19:28:35.718Z,1677007715.718 [BPC1](INFO): Looking for stick 018Dto match 0464
2023-02-21T19:28:35.718Z,1677007715.718 [BPC1](INFO): Looking for stick 018Dto match 045C
2023-02-21T19:28:35.719Z,1677007715.719 [BPC1](INFO): Looking for stick 018Dto match 046A
2023-02-21T19:28:35.719Z,1677007715.719 [BPC1](INFO): Looking for stick 018Dto match 045A
2023-02-21T19:28:35.719Z,1677007715.719 [BPC1](INFO): Looking for stick 018Dto match 0487
2023-02-21T19:28:35.720Z,1677007715.720 [BPC1](INFO): Looking for stick 018Dto match 03FE
2023-02-21T19:28:35.720Z,1677007715.720 [BPC1](INFO): Looking for stick 018Dto match 0465
2023-02-21T19:28:35.721Z,1677007715.721 [BPC1](INFO): Looking for stick 018Dto match 046B
2023-02-21T19:28:35.721Z,1677007715.721 [BPC1](INFO): Looking for stick 018Dto match 0445
2023-02-21T19:28:35.721Z,1677007715.721 [BPC1](INFO): Looking for stick 018Dto match 040D
2023-02-21T19:28:35.721Z,1677007715.721 [BPC1](INFO): Looking for stick 018Dto match 04AA
2023-02-21T19:28:35.722Z,1677007715.722 [BPC1](INFO): Looking for stick 018Dto match 049A
2023-02-21T19:28:35.722Z,1677007715.722 [BPC1](INFO): Looking for stick 018Dto match 04C6
2023-02-21T19:28:35.722Z,1677007715.722 [BPC1](INFO): Looking for stick 018Dto match 0486
2023-02-21T19:28:35.723Z,1677007715.723 [BPC1](INFO): Looking for stick 018Dto match 0442
2023-02-21T19:28:35.723Z,1677007715.723 [BPC1](INFO): Looking for stick 018Dto match 0457
2023-02-21T19:28:35.724Z,1677007715.724 [BPC1](INFO): Looking for stick 018Dto match 0455
2023-02-21T19:28:35.724Z,1677007715.724 [BPC1](INFO): Looking for stick 018Dto match 04C0
2023-02-21T19:28:35.724Z,1677007715.724 [BPC1](INFO): Looking for stick 018Dto match 0459
2023-02-21T19:28:35.725Z,1677007715.725 [BPC1](INFO): Looking for stick 018Dto match 045F
2023-02-21T19:28:35.725Z,1677007715.725 [BPC1](INFO): Looking for stick 018Dto match 0449
2023-02-21T19:28:35.725Z,1677007715.725 [BPC1](INFO): Looking for stick 018Dto match 0441
2023-02-21T19:28:35.725Z,1677007715.725 [BPC1](INFO): Looking for stick 018Dto match 0460
2023-02-21T19:28:35.726Z,1677007715.726 [BPC1](INFO): Looking for stick 018Dto match 04A8
2023-02-21T19:28:35.726Z,1677007715.726 [BPC1](INFO): Looking for stick 018Dto match 04BF
2023-02-21T19:28:35.726Z,1677007715.726 [BPC1](INFO): Looking for stick 018Dto match 045D
2023-02-21T19:28:35.727Z,1677007715.727 [BPC1](INFO): Looking for stick 018Dto match 04A4
2023-02-21T19:28:35.727Z,1677007715.727 [BPC1](INFO): Looking for stick 018Dto match 04D9
2023-02-21T19:28:35.728Z,1677007715.728 [BPC1](ERROR): BPC1A: No match for serial number 018D in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2023-02-21T19:28:35.740Z,1677007715.740 [BPC1](FAULT): Failed to parse data from all battery packs.
2023-02-21T19:28:35.740Z,1677007715.740 [BPC1] Data Fault, FailCount= 2
2023-02-21T19:28:35.740Z,1677007715.740 [BPC1](ERROR): Data Fault
2023-02-21T19:28:35.828Z,1677007715.828 [CBIT](ERROR): Data Fault in component: BPC1
2023-02-21T19:28:49.134Z,1677007729.134 [DataOverHttps](INFO): Sending 1101 bytes from file Logs/20230221T185859/Express0001.lzma
2023-02-21T19:28:50.133Z,1677007730.133 [DataOverHttps](INFO): Moved sent file to Logs/20230221T185859/Express0001.lzma.bak
2023-02-21T19:28:50.134Z,1677007730.134 [DataOverHttps](INFO): SBD MOMSN=17484389
2023-02-21T19:29:13.371Z,1677007753.371 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20230221T185859/Express0005.lzma
2023-02-21T19:29:14.374Z,1677007754.374 [DataOverHttps](INFO): Moved sent file to Logs/20230221T185859/Express0005.lzma.bak
2023-02-21T19:29:14.374Z,1677007754.374 [DataOverHttps](INFO): SBD MOMSN=17484419
2023-02-21T19:29:54.569Z,1677007794.569 [DataOverHttps](INFO): Sending 1058 bytes from file Logs/20230221T190635/Express0001.lzma
2023-02-21T19:29:55.569Z,1677007795.569 [DataOverHttps](INFO): Moved sent file to Logs/20230221T190635/Express0001.lzma.bak
2023-02-21T19:29:55.570Z,1677007795.570 [DataOverHttps](INFO): SBD MOMSN=17484424
2023-02-21T19:30:19.443Z,1677007819.443 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20230221T190635/Express0005.lzma
2023-02-21T19:30:20.445Z,1677007820.445 [DataOverHttps](INFO): Moved sent file to Logs/20230221T190635/Express0005.lzma.bak
2023-02-21T19:30:20.446Z,1677007820.446 [DataOverHttps](INFO): SBD MOMSN=17484453
2023-02-21T19:30:55.906Z,1677007855.906 [DataOverHttps](INFO): Sending 1109 bytes from file Logs/20230221T190943/Express0001.lzma
2023-02-21T19:30:58.614Z,1677007858.614 [DataOverHttps](INFO): Moved sent file to Logs/20230221T190943/Express0001.lzma.bak
2023-02-21T19:30:58.614Z,1677007858.614 [DataOverHttps](INFO): SBD MOMSN=17484456
2023-02-21T19:31:03.777Z,1677007863.777 [CommandExec](IMPORTANT): got command burn 1250441.000000
2023-02-21T19:31:10.228Z,1677007870.228 [CommandExec](IMPORTANT): got command burn 
2023-02-21T19:31:10.228Z,1677007870.228 [CommandExec](FAULT): Incomplete syntax. Try: help burn
2023-02-21T19:31:13.352Z,1677007873.352 [CommandExec](IMPORTANT): got command burn off
2023-02-21T19:31:13.353Z,1677007873.353 [CommandExec](IMPORTANT): Deactivating dropweight wire
2023-02-21T19:31:16.982Z,1677007876.982 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-02-21T19:31:16.982Z,1677007876.982 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-02-21T19:31:17.042Z,1677007877.042 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-02-21T19:31:17.496Z,1677007877.496 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-02-21T19:31:17.497Z,1677007877.497 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-02-21T19:31:25.209Z,1677007885.209 [CommandExec](IMPORTANT): got command restart application
2023-02-21T19:31:26.212Z,1677007886.212 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-21T19:31:26.212Z,1677007886.212 [CommandExec](INFO): Uninitializing the command executive.
2023-02-21T19:31:26.212Z,1677007886.212 [CommandExec](INFO): Uninitializing the command scheduler.
2023-02-21T19:31:26.212Z,1677007886.212 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-02-21T19:31:26.228Z,1677007886.228 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-02-21T19:31:26.228Z,1677007886.228 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-02-21T19:31:26.228Z,1677007886.228 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-02-21T19:31:26.229Z,1677007886.229 [NavChartDb](INFO): Join timeout helper Thread ID is 7714
2023-02-21T19:31:26.380Z,1677007886.380 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-21T19:31:26.380Z,1677007886.380 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-02-21T19:31:26.381Z,1677007886.381 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2023-02-21T19:31:26.381Z,1677007886.381 [DAT ThreadHandler](INFO): Thread cancelled.
2023-02-21T19:31:26.381Z,1677007886.381 [DAT](INFO): Join timeout helper Thread ID is 7715
2023-02-21T19:31:26.544Z,1677007886.544 [DAT](INFO): Powering down
2023-02-21T19:31:26.628Z,1677007886.628 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-21T19:31:26.629Z,1677007886.629 [DAT](INFO): Powering down
2023-02-21T19:31:26.630Z,1677007886.630 [DAT ThreadHandler](INFO): Thread cancelled.
2023-02-21T19:31:26.648Z,1677007886.648 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-02-21T19:31:26.648Z,1677007886.648 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-02-21T19:31:26.648Z,1677007886.648 [Radio_Surface](INFO): Join timeout helper Thread ID is 7716
2023-02-21T19:31:26.752Z,1677007886.752 [Radio_Surface](INFO): Powering down
2023-02-21T19:31:26.753Z,1677007886.753 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-21T19:31:26.753Z,1677007886.753 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-02-21T19:31:26.768Z,1677007886.768 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-02-21T19:31:26.768Z,1677007886.768 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-02-21T19:31:26.769Z,1677007886.769 [Onboard](INFO): Join timeout helper Thread ID is 7717
2023-02-21T19:31:28.532Z,1677007888.532 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-21T19:31:28.532Z,1677007888.532 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-02-21T19:31:28.544Z,1677007888.544 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-02-21T19:31:28.544Z,1677007888.544 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-02-21T19:31:28.544Z,1677007888.544 [DataOverHttps](INFO): Join timeout helper Thread ID is 7718
2023-02-21T19:31:29.396Z,1677007889.396 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-21T19:31:29.399Z,1677007889.399 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-02-21T19:31:29.408Z,1677007889.408 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-02-21T19:31:29.409Z,1677007889.409 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-02-21T19:31:29.409Z,1677007889.409 [BackseatComponent](INFO): Join timeout helper Thread ID is 7719
2023-02-21T19:31:29.420Z,1677007889.420 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-21T19:31:29.420Z,1677007889.420 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-02-21T19:31:29.428Z,1677007889.428 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-02-21T19:31:29.428Z,1677007889.428 [logger ThreadHandler](INFO): Thread cancelled.
2023-02-21T19:31:29.429Z,1677007889.429 [logger](INFO): Join timeout helper Thread ID is 7720
2023-02-21T19:31:29.445Z,1677007889.445 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-21T19:31:29.445Z,1677007889.445 [logger ThreadHandler](INFO): Thread cancelled.
2023-02-21T19:31:29.453Z,1677007889.453 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-02-21T19:31:29.453Z,1677007889.453 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-02-21T19:31:29.453Z,1677007889.453 [CommandLine](INFO): Join timeout helper Thread ID is 7721
2023-02-21T19:31:29.536Z,1677007889.536 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-21T19:31:29.536Z,1677007889.536 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-02-21T19:31:29.540Z,1677007889.540 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-02-21T19:31:29.540Z,1677007889.540 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-02-21T19:31:29.541Z,1677007889.541 [CommandExec](INFO): Join timeout helper Thread ID is 7722
2023-02-21T19:31:29.542Z,1677007889.542 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-02-21T19:31:29.542Z,1677007889.542 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-02-21T19:31:29.543Z,1677007889.543 [controlThread](INFO): Join timeout helper Thread ID is 7723
2023-02-21T19:31:29.844Z,1677007889.844 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-02-21T19:31:29.844Z,1677007889.844 [controlThread](DEBUG): Uninitializing ControlThread
2023-02-21T19:31:29.845Z,1677007889.845 [AHRS_M2](INFO): Powering down
2023-02-21T19:31:29.916Z,1677007889.916 [MultiRay](INFO): Powering down.
2023-02-21T19:31:29.917Z,1677007889.917 [NAL9602](INFO): Powering down
2023-02-21T19:31:29.919Z,1677007889.919 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-02-21T19:31:29.920Z,1677007889.920 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-02-21T19:31:29.920Z,1677007889.920 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-02-21T19:31:29.921Z,1677007889.921 [MissionManager](INFO): Uninitializing Mission Default
2023-02-21T19:31:29.921Z,1677007889.921 [Default] Stopped
2023-02-21T19:31:29.921Z,1677007889.921 [Default](DEBUG): Aggregate::uninitialize Default
2023-02-21T19:31:29.921Z,1677007889.921 [Default:B.GoToSurface] Stopped
2023-02-21T19:31:29.921Z,1677007889.921 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-02-21T19:31:29.921Z,1677007889.921 [Default:CheckIn] Stopped
2023-02-21T19:31:29.921Z,1677007889.921 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-02-21T19:31:29.921Z,1677007889.921 [Default:CheckIn:Read_Iridium] Stopped
2023-02-21T19:31:29.924Z,1677007889.924 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-02-21T19:31:29.924Z,1677007889.924 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-02-21T19:31:29.925Z,1677007889.925 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-02-21T19:31:29.925Z,1677007889.925 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-02-21T19:31:29.925Z,1677007889.925 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-02-21T19:31:29.925Z,1677007889.925 [BuoyancyServo](INFO): Powering down
2023-02-21T19:31:29.940Z,1677007889.940 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-02-21T19:31:29.940Z,1677007889.940 [ElevatorServo](INFO): Powering down
2023-02-21T19:31:29.941Z,1677007889.941 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-02-21T19:31:29.941Z,1677007889.941 [MassServo](INFO): Powering down
2023-02-21T19:31:29.942Z,1677007889.942 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-02-21T19:31:29.942Z,1677007889.942 [RudderServo](INFO): Powering down
2023-02-21T19:31:29.943Z,1677007889.943 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-02-21T19:31:29.943Z,1677007889.943 [ThrusterHE](INFO): Powering down
2023-02-21T19:31:29.944Z,1677007889.944 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-02-21T19:31:29.944Z,1677007889.944 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-02-21T19:31:29.944Z,1677007889.944 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-02-21T19:31:29.944Z,1677007889.944 [CBIT](DEBUG): Powering off loads.
2023-02-21T19:31:29.955Z,1677007889.955 [CBIT](DEBUG): Disabling WDT.
2023-02-21T19:31:29.967Z,1677007889.967 [CBIT](DEBUG): Opening all GF detection circuits.
2023-02-21T19:31:29.969Z,1677007889.969 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-02-21T19:31:30.038Z,1677007890.038 [DAT ThreadHandler](INFO): Thread cancelled.
2023-02-21T19:31:30.044Z,1677007890.044 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-02-21T19:31:30.045Z,1677007890.045 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-02-21T19:31:30.052Z,1677007890.052 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-02-21T19:31:30.096Z,1677007890.096 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-02-21T19:31:30.152Z,1677007890.152 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-02-21T19:31:30.212Z,1677007890.212 [logger ThreadHandler](INFO): Thread cancelled.