2022-12-19T17:58:51.514Z,1671472731.514 [Supervisor](DEBUG): Initializing supervisor.
2022-12-19T17:58:51.563Z,1671472731.563 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-12-19T17:58:51.563Z,1671472731.563 [SyncHandler](INFO): Protected caller Thread ID is 3422
2022-12-19T17:58:51.564Z,1671472731.564 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-12-19T17:58:51.565Z,1671472731.565 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-12-19T17:58:51.565Z,1671472731.565 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3423
2022-12-19T17:58:51.569Z,1671472731.569 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-12-19T17:58:51.587Z,1671472731.587 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-12-19T17:58:51.588Z,1671472731.588 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-12-19T17:58:51.588Z,1671472731.588 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3424
2022-12-19T17:58:51.592Z,1671472731.592 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-12-19T17:58:51.593Z,1671472731.593 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-12-19T17:58:51.594Z,1671472731.594 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3425
2022-12-19T17:58:51.596Z,1671472731.596 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-12-19T17:58:51.597Z,1671472731.597 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-12-19T17:58:51.597Z,1671472731.597 [logger ThreadHandler](INFO): Protected caller Thread ID is 3426
2022-12-19T17:58:51.601Z,1671472731.601 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-12-19T17:58:51.601Z,1671472731.601 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-12-19T17:58:51.604Z,1671472731.604 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-12-19T17:58:52.144Z,1671472732.144 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-12-19T17:58:52.145Z,1671472732.145 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-12-19T17:58:52.349Z,1671472732.349 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-12-19T17:58:52.351Z,1671472732.351 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-12-19T17:58:52.434Z,1671472732.434 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-12-19T17:58:52.647Z,1671472732.647 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-12-19T17:58:52.649Z,1671472732.649 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-12-19T17:58:52.730Z,1671472732.730 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-12-19T17:58:52.827Z,1671472732.827 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-12-19T17:58:52.828Z,1671472732.828 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-12-19T17:58:53.206Z,1671472733.206 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-12-19T17:58:53.206Z,1671472733.206 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-12-19T17:58:53.337Z,1671472733.337 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-12-19T17:58:53.338Z,1671472733.338 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-12-19T17:58:53.809Z,1671472733.809 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-12-19T17:58:53.810Z,1671472733.810 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-12-19T17:58:53.991Z,1671472733.991 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-12-19T17:58:53.992Z,1671472733.992 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-12-19T17:58:54.377Z,1671472734.377 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-12-19T17:58:54.378Z,1671472734.378 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-12-19T17:58:54.651Z,1671472734.651 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-12-19T17:58:54.653Z,1671472734.653 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-12-19T17:58:54.763Z,1671472734.763 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-12-19T17:58:54.765Z,1671472734.765 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-12-19T17:58:55.591Z,1671472735.591 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-12-19T17:58:55.592Z,1671472735.592 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-12-19T17:58:56.464Z,1671472736.464 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-12-19T17:58:56.465Z,1671472736.465 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-12-19T17:58:56.704Z,1671472736.704 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-12-19T17:58:56.706Z,1671472736.706 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2022-12-19T17:58:56.707Z,1671472736.707 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2022-12-19T17:58:56.891Z,1671472736.891 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2022-12-19T17:58:56.976Z,1671472736.976 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2022-12-19T17:58:57.110Z,1671472737.110 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2022-12-19T17:58:57.197Z,1671472737.197 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2022-12-19T17:58:57.456Z,1671472737.456 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-12-19T17:58:57.456Z,1671472737.456 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2022-12-19T17:58:57.560Z,1671472737.560 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2022-12-19T17:58:57.672Z,1671472737.672 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2022-12-19T17:58:57.784Z,1671472737.784 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2022-12-19T17:58:57.925Z,1671472737.925 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2022-12-19T17:58:58.025Z,1671472738.025 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2022-12-19T17:58:58.120Z,1671472738.120 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-12-19T17:58:58.133Z,1671472738.133 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-12-19T17:58:58.149Z,1671472738.149 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-12-19T17:58:58.150Z,1671472738.150 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-12-19T17:58:58.270Z,1671472738.270 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-12-19T17:58:58.270Z,1671472738.270 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-12-19T17:58:58.335Z,1671472738.335 [VerticalControl](DEBUG): Construct VerticalControl.
2022-12-19T17:58:58.394Z,1671472738.394 [VerticalControl] Loaded
2022-12-19T17:58:58.395Z,1671472738.395 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-12-19T17:58:58.397Z,1671472738.397 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-12-19T17:58:58.437Z,1671472738.437 [HorizontalControl] Loaded
2022-12-19T17:58:58.438Z,1671472738.438 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-12-19T17:58:58.440Z,1671472738.440 [SpeedControl](DEBUG): Construct SpeedControl.
2022-12-19T17:58:58.443Z,1671472738.443 [SpeedControl] Loaded
2022-12-19T17:58:58.443Z,1671472738.443 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-12-19T17:58:58.446Z,1671472738.446 [LoopControl](DEBUG): Construct LoopControl.
2022-12-19T17:58:58.446Z,1671472738.446 [LoopControl] Loaded
2022-12-19T17:58:58.447Z,1671472738.447 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-12-19T17:58:58.447Z,1671472738.447 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-12-19T17:58:58.448Z,1671472738.448 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-12-19T17:58:58.601Z,1671472738.601 [BuoyancyServo] Loaded
2022-12-19T17:58:58.602Z,1671472738.602 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-12-19T17:58:58.621Z,1671472738.621 [ElevatorServo] Loaded
2022-12-19T17:58:58.621Z,1671472738.621 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-12-19T17:58:58.640Z,1671472738.640 [MassServo] Loaded
2022-12-19T17:58:58.640Z,1671472738.640 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-12-19T17:58:58.658Z,1671472738.658 [RudderServo] Loaded
2022-12-19T17:58:58.658Z,1671472738.658 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-12-19T17:58:58.673Z,1671472738.673 [ThrusterHE] Loaded
2022-12-19T17:58:58.673Z,1671472738.673 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-12-19T17:58:58.673Z,1671472738.673 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-12-19T17:58:58.674Z,1671472738.674 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-12-19T17:58:58.741Z,1671472738.741 [DepthRateCalculator] Loaded
2022-12-19T17:58:58.742Z,1671472738.742 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-12-19T17:58:58.746Z,1671472738.746 [PitchRateCalculator] Loaded
2022-12-19T17:58:58.747Z,1671472738.747 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-12-19T17:58:58.756Z,1671472738.756 [SpeedCalculator] Loaded
2022-12-19T17:58:58.757Z,1671472738.757 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-12-19T17:58:58.761Z,1671472738.761 [YawRateCalculator] Loaded
2022-12-19T17:58:58.761Z,1671472738.761 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-12-19T17:58:58.779Z,1671472738.779 [ElevatorOffsetCalculator] Loaded
2022-12-19T17:58:58.779Z,1671472738.779 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-12-19T17:58:58.780Z,1671472738.780 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-12-19T17:58:58.780Z,1671472738.780 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-12-19T17:58:58.920Z,1671472738.920 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-12-19T17:58:58.920Z,1671472738.920 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-12-19T17:58:58.933Z,1671472738.933 [NavChart] Loaded
2022-12-19T17:58:58.933Z,1671472738.933 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-12-19T17:58:58.939Z,1671472738.939 [UniversalFixResidualReporter] Loaded
2022-12-19T17:58:58.939Z,1671472738.939 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-12-19T17:58:58.940Z,1671472738.940 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-12-19T17:58:58.940Z,1671472738.940 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-12-19T17:58:59.615Z,1671472739.615 [AHRS_M2] Loaded
2022-12-19T17:58:59.615Z,1671472739.615 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-12-19T17:58:59.867Z,1671472739.867 [BackseatComponent] Loaded
2022-12-19T17:58:59.867Z,1671472739.867 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-12-19T17:58:59.893Z,1671472739.893 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408D14E0
2022-12-19T17:58:59.893Z,1671472739.893 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 3508
2022-12-19T17:58:59.896Z,1671472739.896 [LcmUniversalReporter] Loaded
2022-12-19T17:58:59.897Z,1671472739.897 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-12-19T17:59:00.722Z,1671472740.722 [BPC1] Loaded
2022-12-19T17:59:00.722Z,1671472740.722 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-12-19T17:59:00.817Z,1671472740.817 [DataOverHttps] Loaded
2022-12-19T17:59:00.818Z,1671472740.818 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-12-19T17:59:00.819Z,1671472740.819 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409014E0
2022-12-19T17:59:00.819Z,1671472740.819 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3509
2022-12-19T17:59:00.839Z,1671472740.839 [Depth_Keller] Loaded
2022-12-19T17:59:00.840Z,1671472740.840 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-12-19T17:59:00.845Z,1671472740.845 [DropWeight] Loaded
2022-12-19T17:59:00.845Z,1671472740.845 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-12-19T17:59:00.907Z,1671472740.907 [NAL9602] Loaded
2022-12-19T17:59:00.907Z,1671472740.907 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-12-19T17:59:00.937Z,1671472740.937 [Onboard] Loaded
2022-12-19T17:59:00.937Z,1671472740.937 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-12-19T17:59:00.938Z,1671472740.938 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409314E0
2022-12-19T17:59:00.939Z,1671472740.939 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3510
2022-12-19T17:59:00.950Z,1671472740.950 [Power24vConverter] Loaded
2022-12-19T17:59:00.951Z,1671472740.951 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-12-19T17:59:00.964Z,1671472740.964 [Radio_Surface] Loaded
2022-12-19T17:59:00.964Z,1671472740.964 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-12-19T17:59:00.965Z,1671472740.965 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409614E0
2022-12-19T17:59:00.965Z,1671472740.965 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3511
2022-12-19T17:59:01.057Z,1671472741.057 [DAT] Loaded
2022-12-19T17:59:01.057Z,1671472741.057 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2022-12-19T17:59:01.058Z,1671472741.058 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409914E0
2022-12-19T17:59:01.058Z,1671472741.058 [DAT ThreadHandler](INFO): Protected caller Thread ID is 3512
2022-12-19T17:59:01.059Z,1671472741.059 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-12-19T17:59:01.059Z,1671472741.059 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-12-19T17:59:01.556Z,1671472741.556 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-12-19T17:59:01.557Z,1671472741.557 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-12-19T17:59:02.276Z,1671472742.276 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-12-19T17:59:02.276Z,1671472742.276 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-12-19T17:59:02.322Z,1671472742.322 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-12-19T17:59:02.322Z,1671472742.322 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-12-19T17:59:02.542Z,1671472742.542 [CTD_Seabird] Loaded
2022-12-19T17:59:02.542Z,1671472742.542 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-12-19T17:59:02.587Z,1671472742.587 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ABC4E0
2022-12-19T17:59:02.588Z,1671472742.588 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3513
2022-12-19T17:59:02.608Z,1671472742.608 [PAR_Licor] Loaded
2022-12-19T17:59:02.609Z,1671472742.609 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-12-19T17:59:02.639Z,1671472742.639 [WetLabsBB2FL] Loaded
2022-12-19T17:59:02.639Z,1671472742.639 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-12-19T17:59:02.640Z,1671472742.640 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AEC4E0
2022-12-19T17:59:02.641Z,1671472742.641 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3514
2022-12-19T17:59:02.642Z,1671472742.642 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-12-19T17:59:02.642Z,1671472742.642 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-12-19T17:59:02.991Z,1671472742.991 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-12-19T17:59:02.992Z,1671472742.992 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-12-19T17:59:03.155Z,1671472743.155 [SBIT](DEBUG): Construct Startup Built In Test.
2022-12-19T17:59:03.164Z,1671472743.164 [SBIT] Loaded
2022-12-19T17:59:03.164Z,1671472743.164 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-12-19T17:59:03.167Z,1671472743.167 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-12-19T17:59:03.180Z,1671472743.180 [IBIT] Loaded
2022-12-19T17:59:03.181Z,1671472743.181 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-12-19T17:59:03.186Z,1671472743.186 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-12-19T17:59:03.289Z,1671472743.289 [CBIT] Loaded
2022-12-19T17:59:03.289Z,1671472743.289 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-12-19T17:59:03.290Z,1671472743.290 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-12-19T17:59:03.296Z,1671472743.296 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-12-19T17:59:03.298Z,1671472743.298 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-12-19T17:59:03.309Z,1671472743.309 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-12-19T17:59:03.313Z,1671472743.313 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCF4E0
2022-12-19T17:59:03.313Z,1671472743.313 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3515
2022-12-19T17:59:03.320Z,1671472743.320 [Supervisor](INFO): Main Thread ID is 3421
2022-12-19T17:59:03.320Z,1671472743.320 [Supervisor](DEBUG): Running supervisor.
2022-12-19T17:59:03.320Z,1671472743.320 [CommandExec ThreadHandler](INFO): Handler Thread ID is 3516
2022-12-19T17:59:03.321Z,1671472743.321 [CommandExec](INFO): Initializing the command executive.
2022-12-19T17:59:03.322Z,1671472743.322 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3517
2022-12-19T17:59:03.325Z,1671472743.325 [controlThread ThreadHandler](INFO): Handler Thread ID is 3518
2022-12-19T17:59:03.325Z,1671472743.325 [controlThread](DEBUG): Initializing ControlThread
2022-12-19T17:59:03.326Z,1671472743.326 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-12-19T17:59:03.328Z,1671472743.328 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-12-19T17:59:03.328Z,1671472743.328 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-12-19T17:59:03.329Z,1671472743.329 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-12-19T17:59:03.330Z,1671472743.330 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-12-19T17:59:03.331Z,1671472743.331 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-12-19T17:59:03.331Z,1671472743.331 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-12-19T17:59:03.331Z,1671472743.331 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-12-19T17:59:03.332Z,1671472743.332 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-12-19T17:59:03.333Z,1671472743.333 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-12-19T17:59:03.333Z,1671472743.333 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-12-19T17:59:03.339Z,1671472743.339 [SBIT](INFO): Initialize SBIT Component.
2022-12-19T17:59:03.340Z,1671472743.340 [SBIT](IMPORTANT): git: 2022-12-14
2022-12-19T17:59:03.340Z,1671472743.340 [SBIT](INFO): git hash: c3aea31d7798b2e77c1fbaec46e28ec3366e0fcc
2022-12-19T17:59:03.340Z,1671472743.340 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-12-19T17:59:03.341Z,1671472743.341 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2022-12-19T17:59:03.343Z,1671472743.343 [SBIT](INFO): Beginning SBIT in 99.000000 seconds.
2022-12-19T17:59:03.343Z,1671472743.343 [IBIT](INFO): Initialize IBIT Component.
2022-12-19T17:59:03.344Z,1671472743.344 [CBIT](DEBUG): Initialize CBIT Component.
2022-12-19T17:59:03.345Z,1671472743.345 [logger ThreadHandler](INFO): Handler Thread ID is 3519
2022-12-19T17:59:03.355Z,1671472743.355 [CBIT](DEBUG): Initialized mux pins.
2022-12-19T17:59:03.355Z,1671472743.355 [CBIT](DEBUG): Initializing the watchdog timer.
2022-12-19T17:59:03.364Z,1671472743.364 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 3520
2022-12-19T17:59:03.376Z,1671472743.376 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3521
2022-12-19T17:59:03.376Z,1671472743.376 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-12-19T17:59:03.379Z,1671472743.379 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-12-19T17:59:03.380Z,1671472743.380 [CBIT](DEBUG): Initializing heartbeat.
2022-12-19T17:59:03.388Z,1671472743.388 [Onboard ThreadHandler](INFO): Handler Thread ID is 3522
2022-12-19T17:59:03.405Z,1671472743.405 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3523
2022-12-19T17:59:03.428Z,1671472743.428 [DAT ThreadHandler](INFO): Handler Thread ID is 3524
2022-12-19T17:59:03.428Z,1671472743.428 [DAT](INFO): Powering up
2022-12-19T17:59:03.428Z,1671472743.428 [DAT](DEBUG): Initializing DAT.
2022-12-19T17:59:03.433Z,1671472743.433 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3526
2022-12-19T17:59:03.433Z,1671472743.433 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-12-19T17:59:03.436Z,1671472743.436 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3528
2022-12-19T17:59:03.440Z,1671472743.440 [WetLabsBB2FL](INFO): Powering up
2022-12-19T17:59:03.441Z,1671472743.441 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3530
2022-12-19T17:59:03.446Z,1671472743.446 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-12-19T17:59:03.447Z,1671472743.447 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-12-19T17:59:03.447Z,1671472743.447 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-12-19T17:59:03.447Z,1671472743.447 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-12-19T17:59:03.448Z,1671472743.448 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-12-19T17:59:03.448Z,1671472743.448 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-12-19T17:59:03.448Z,1671472743.448 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-12-19T17:59:03.448Z,1671472743.448 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-12-19T17:59:03.451Z,1671472743.451 [CBIT](DEBUG): Deactivating GF circuits.
2022-12-19T17:59:03.451Z,1671472743.451 [CBIT](DEBUG): Deactivating emergency mode.
2022-12-19T17:59:03.487Z,1671472743.487 [CBIT](DEBUG): Backplane powered.
2022-12-19T17:59:03.488Z,1671472743.488 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-12-19T17:59:03.515Z,1671472743.515 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-12-19T17:59:03.550Z,1671472743.550 [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>


2022-12-19T17:59:03.567Z,1671472743.567 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-12-19T17:59:03.660Z,1671472743.660 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-12-19T17:59:03.661Z,1671472743.661 [Default:A.Wait](DEBUG): Construct Wait.
2022-12-19T17:59:03.664Z,1671472743.664 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-12-19T17:59:03.710Z,1671472743.710 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-12-19T17:59:03.725Z,1671472743.725 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-12-19T17:59:03.730Z,1671472743.730 [Default:E.Execute](DEBUG): Construct Execute.
2022-12-19T17:59:03.754Z,1671472743.754 [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>


2022-12-19T17:59:03.763Z,1671472743.763 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-12-19T17:59:03.771Z,1671472743.771 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-12-19T17:59:03.815Z,1671472743.815 [Radio_Surface](INFO): Powering up
2022-12-19T17:59:03.854Z,1671472743.854 [Power24vConverter](INFO): Powering up.
2022-12-19T17:59:03.911Z,1671472743.911 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-12-19T17:59:03.923Z,1671472743.923 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-12-19T17:59:03.925Z,1671472743.925 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-12-19T17:59:03.932Z,1671472743.932 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-12-19T17:59:03.933Z,1671472743.933 [MassServo](DEBUG): Initializing EZServoServo.
2022-12-19T17:59:03.940Z,1671472743.940 [MassServo](DEBUG): Initializing MassServo.
2022-12-19T17:59:03.941Z,1671472743.941 [RudderServo](DEBUG): Initializing EZServoServo.
2022-12-19T17:59:03.947Z,1671472743.947 [RudderServo](DEBUG): Initializing RudderServo.
2022-12-19T17:59:03.948Z,1671472743.948 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-12-19T17:59:03.955Z,1671472743.955 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-12-19T17:59:04.221Z,1671472744.221 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-12-19T17:59:04.221Z,1671472744.221 [DropWeight] Hardware Fault, FailCount= 1
2022-12-19T17:59:04.221Z,1671472744.221 [DropWeight](ERROR): Hardware Fault
2022-12-19T17:59:04.293Z,1671472744.293 [CommandExec](FAULT): Scheduling is paused
2022-12-19T17:59:04.293Z,1671472744.293 [CBIT](INFO): Critical error at 20221219T175904
2022-12-19T17:59:04.294Z,1671472744.294 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2022-12-19T17:59:04.304Z,1671472744.304 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-12-19T17:59:04.305Z,1671472744.305 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-12-19T17:59:04.915Z,1671472744.915 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-12-19T17:59:04.916Z,1671472744.916 [RudderServo](FAULT): Rudder failed to initialize
2022-12-19T17:59:04.916Z,1671472744.916 [RudderServo] Communications Fault, FailCount= 1
2022-12-19T17:59:04.916Z,1671472744.916 [RudderServo](ERROR): Communications Fault
2022-12-19T17:59:04.920Z,1671472744.920 [CBIT](INFO): Critical error at 20221219T175904
2022-12-19T17:59:04.924Z,1671472744.924 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-12-19T17:59:05.140Z,1671472745.140 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-12-19T17:59:05.140Z,1671472745.140 [RudderServo](INFO): Powering down
2022-12-19T17:59:05.547Z,1671472745.547 [WetLabsBB2FL](INFO): Powering down
2022-12-19T17:59:05.812Z,1671472745.812 [RudderServo](DEBUG): Initializing EZServoServo.
2022-12-19T17:59:05.934Z,1671472745.934 [RudderServo](DEBUG): Initializing RudderServo.
2022-12-19T17:59:05.938Z,1671472745.938 [CBIT](INFO): Clearing failed state for component RudderServo
2022-12-19T17:59:05.938Z,1671472745.938 [RudderServo] No Fault, FailCount= 1
2022-12-19T17:59:09.728Z,1671472749.728 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-12-19T17:59:15.524Z,1671472755.524 [DAT](INFO): DAT read: 

2022-12-19T17:59:15.525Z,1671472755.525 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2022-12-19T17:59:16.970Z,1671472756.970 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004856
2022-12-19T17:59:17.288Z,1671472757.288 [DAT](INFO): DAT read: MF Frequency Band

2022-12-19T17:59:17.289Z,1671472757.289 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2022-12-19T17:59:17.290Z,1671472757.290 [DAT](INFO): DAT read: Dec 19 2022 17:59:11 

2022-12-19T17:59:18.296Z,1671472758.296 [DAT](INFO): DAT read: Features enabled [Bearing]

2022-12-19T17:59:18.297Z,1671472758.297 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2022-12-19T17:59:18.298Z,1671472758.298 [DAT](INFO): commRate: 800
2022-12-19T17:59:20.363Z,1671472760.363 [DAT](INFO): entering command mode
2022-12-19T17:59:20.564Z,1671472760.564 [DAT](INFO): DAT read: 

2022-12-19T17:59:20.564Z,1671472760.564 [DAT](INFO): DAT read: user:1>

2022-12-19T17:59:20.565Z,1671472760.565 [DAT](INFO): setting verbose to 3
2022-12-19T17:59:20.816Z,1671472760.816 [DAT](INFO): DAT read: user:1>

2022-12-19T17:59:20.817Z,1671472760.817 [DAT](INFO): DAT read: Verbose         | 3           

2022-12-19T17:59:20.817Z,1671472760.817 [DAT](INFO): set verbose to 3
2022-12-19T17:59:20.818Z,1671472760.818 [DAT](INFO): setting DatVerbose to 27440
2022-12-19T17:59:21.068Z,1671472761.068 [DAT](INFO): DAT read: user:2>

2022-12-19T17:59:21.069Z,1671472761.069 [DAT](INFO): DAT read: DatVerbose      | 27440       

2022-12-19T17:59:21.069Z,1671472761.069 [DAT](INFO): set DatVerbose to 27440
2022-12-19T17:59:21.069Z,1671472761.069 [DAT](INFO): setting transmit power to 8
2022-12-19T17:59:21.320Z,1671472761.320 [DAT](INFO): DAT read: user:3>

2022-12-19T17:59:21.321Z,1671472761.321 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2022-12-19T17:59:21.321Z,1671472761.321 [DAT](INFO): set transmit power to 8
2022-12-19T17:59:21.321Z,1671472761.321 [DAT](INFO): setting local address to 5
2022-12-19T17:59:21.574Z,1671472761.574 [DAT](INFO): DAT read: user:4>

2022-12-19T17:59:21.575Z,1671472761.575 [DAT](INFO): DAT read: LocalAddr       | 5           

2022-12-19T17:59:21.576Z,1671472761.576 [DAT](INFO): set local address to 5
2022-12-19T17:59:21.577Z,1671472761.577 [DAT](INFO): Setting time to: 17:59:21 And date to:12/19/2022
2022-12-19T17:59:21.828Z,1671472761.828 [DAT](INFO): DAT read: user:5>

2022-12-19T17:59:21.829Z,1671472761.829 [DAT](INFO): DAT read: Mon Dec 19, 2022  17:59:21  

2022-12-19T17:59:21.830Z,1671472761.830 [DAT](INFO): Local DAT time set to Mon Dec 19, 2022  17:59:21  

2022-12-19T17:59:31.245Z,1671472771.245 [NAL9602](INFO): Powering up NAL9602
2022-12-19T17:59:42.157Z,1671472782.157 [NAL9602](INFO): NAL9602 initialized
2022-12-19T18:00:42.793Z,1671472842.793 [SBIT](IMPORTANT): Beginning Startup BIT
2022-12-19T18:00:42.797Z,1671472842.797 [CBIT](IMPORTANT): Beginning ground fault scan
2022-12-19T18:00:53.749Z,1671472853.749 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.014552
CHAN A1 (24V): -0.008311
CHAN A2 (12V): -0.004907
CHAN A3 (5V): -0.002442
CHAN B0 (3.3V): -0.000858
CHAN B1 (3.15aV): -0.001341
CHAN B2 (3.15bV): -0.001076
CHAN B3 (GND): 0.000286
OPEN: 0.004712
Full Scale: +/- 1 mA
2022-12-19T18:01:32.448Z,1671472892.448 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-19T18:01:36.918Z,1671472896.918 [SBIT](IMPORTANT): SBIT PASSED
2022-12-19T18:01:36.919Z,1671472896.919 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-12-19T18:01:36.920Z,1671472896.920 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;

2022-12-19T18:01:36.920Z,1671472896.920 [SBIT](IMPORTANT): BackseatComponent.alwaysOn=0 bool;

2022-12-19T18:01:36.920Z,1671472896.920 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool;

2022-12-19T18:01:36.921Z,1671472896.921 [SBIT](IMPORTANT): CTD_Seabird.minSalinityBound=0 practical_salinity_unit;

2022-12-19T18:01:36.921Z,1671472896.921 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2022-12-19T18:01:36.921Z,1671472896.921 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water;

2022-12-19T18:01:36.921Z,1671472896.921 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree;

2022-12-19T18:01:36.921Z,1671472896.921 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=30 ampere_hour;

2022-12-19T18:01:36.921Z,1671472896.921 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt;

2022-12-19T18:01:36.921Z,1671472896.921 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=324.282117 cubic_centimeter;

2022-12-19T18:01:36.921Z,1671472896.921 [SBIT](IMPORTANT): VerticalControl.massDefault=20 millimeter;

2022-12-19T18:01:37.309Z,1671472897.309 [MissionManager](IMPORTANT): Started mission Startup
2022-12-19T18:01:37.309Z,1671472897.309 [Startup] Running Loop=1
2022-12-19T18:01:37.309Z,1671472897.309 [Startup](DEBUG): Aggregate::initialize Startup
2022-12-19T18:01:37.309Z,1671472897.309 [Startup:A.GoToSurface] Running Loop=1
2022-12-19T18:01:37.309Z,1671472897.309 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-12-19T18:01:37.310Z,1671472897.310 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-12-19T18:01:37.310Z,1671472897.310 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-12-19T18:01:37.311Z,1671472897.311 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-12-19T18:01:37.311Z,1671472897.311 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-12-19T18:01:37.311Z,1671472897.311 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-12-19T18:01:37.313Z,1671472897.313 [Startup:StartupSatComms] Running Loop=1
2022-12-19T18:01:37.313Z,1671472897.313 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-12-19T18:01:37.313Z,1671472897.313 [Startup:StartupSatComms:A] Running Loop=1
2022-12-19T18:01:37.710Z,1671472897.710 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-12-19T18:01:45.775Z,1671472905.775 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-19T18:01:59.107Z,1671472919.107 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-19T18:02:03.596Z,1671472923.596 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-12-19T18:02:03.597Z,1671472923.597 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-12-19T18:02:03.619Z,1671472923.619 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-12-19T18:02:04.010Z,1671472924.010 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-12-19T18:02:04.010Z,1671472924.010 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-12-19T18:02:12.846Z,1671472932.846 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-19T18:02:26.175Z,1671472946.175 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-19T18:02:37.578Z,1671472957.578 [Startup:StartupSatComms:A](INFO): Timed out from 2022-12-19T18:01:37.3Z
2022-12-19T18:02:37.578Z,1671472957.578 [Startup:StartupSatComms:A] Stopped
2022-12-19T18:02:37.578Z,1671472957.578 [Startup:StartupSatComms:B] Running Loop=1
2022-12-19T18:02:37.939Z,1671472957.939 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-12-19T18:02:39.910Z,1671472959.910 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-19T18:02:53.118Z,1671472973.118 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20221214T175659/Courier0070.lzma
2022-12-19T18:02:53.645Z,1671472973.645 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-19T18:02:54.117Z,1671472974.117 [DataOverHttps](INFO): Moved sent file to Logs/20221214T175659/Courier0070.lzma.bak
2022-12-19T18:02:54.118Z,1671472974.118 [DataOverHttps](INFO): SBD MOMSN=17398981
2022-12-19T18:03:06.978Z,1671472986.978 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-19T18:03:09.747Z,1671472989.747 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20221219T175851/Courier0000.lzma
2022-12-19T18:03:10.749Z,1671472990.749 [DataOverHttps](INFO): Moved sent file to Logs/20221219T175851/Courier0000.lzma.bak
2022-12-19T18:03:10.749Z,1671472990.749 [DataOverHttps](INFO): SBD MOMSN=17398983
2022-12-19T18:03:20.309Z,1671473000.309 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-19T18:03:28.608Z,1671473008.608 [DataOverHttps](INFO): Sending 235 bytes from file Logs/20221214T175659/Express0071.lzma
2022-12-19T18:03:29.393Z,1671473009.393 [DataOverHttps](INFO): Moved sent file to Logs/20221214T175659/Express0071.lzma.bak
2022-12-19T18:03:29.393Z,1671473009.393 [DataOverHttps](INFO): SBD MOMSN=17398988
2022-12-19T18:03:33.655Z,1671473013.655 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-19T18:03:37.696Z,1671473017.696 [Startup:StartupSatComms:B](INFO): Timed out from 2022-12-19T18:02:37.6Z
2022-12-19T18:03:37.696Z,1671473017.696 [Startup:StartupSatComms:B] Stopped
2022-12-19T18:03:37.696Z,1671473017.696 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-12-19T18:03:37.696Z,1671473017.696 [Startup:StartupSatComms] Stopped
2022-12-19T18:03:37.696Z,1671473017.696 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-12-19T18:03:37.697Z,1671473017.697 [Startup](INFO): Completed Startup
2022-12-19T18:03:37.697Z,1671473017.697 [MissionManager](INFO): Startup is completed.
2022-12-19T18:03:37.697Z,1671473017.697 [MissionManager](INFO): Uninitializing Mission Startup
2022-12-19T18:03:37.698Z,1671473017.698 [Startup] Stopped
2022-12-19T18:03:37.698Z,1671473017.698 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-12-19T18:03:37.698Z,1671473017.698 [Startup:A.GoToSurface] Stopped
2022-12-19T18:03:37.698Z,1671473017.698 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-12-19T18:03:38.101Z,1671473018.101 [MissionManager](IMPORTANT): Started mission Default
2022-12-19T18:03:38.101Z,1671473018.101 [Default] Running Loop=1
2022-12-19T18:03:38.102Z,1671473018.102 [Default](DEBUG): Aggregate::initialize Default
2022-12-19T18:03:38.102Z,1671473018.102 [Default:B.GoToSurface] Running Loop=1
2022-12-19T18:03:38.102Z,1671473018.102 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-12-19T18:03:38.102Z,1671473018.102 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-12-19T18:03:38.102Z,1671473018.102 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-12-19T18:03:38.103Z,1671473018.103 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-12-19T18:03:38.103Z,1671473018.103 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-12-19T18:03:38.103Z,1671473018.103 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-12-19T18:03:38.104Z,1671473018.104 [Default:A.Wait] Running Loop=1
2022-12-19T18:03:38.104Z,1671473018.104 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-12-19T18:03:46.520Z,1671473026.520 [DataOverHttps](INFO): Sending 1045 bytes from file Logs/20221219T175851/Express0001.lzma
2022-12-19T18:03:47.378Z,1671473027.378 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-12-19T18:03:47.521Z,1671473027.521 [DataOverHttps](INFO): Moved sent file to Logs/20221219T175851/Express0001.lzma.bak
2022-12-19T18:03:47.521Z,1671473027.521 [DataOverHttps](INFO): SBD MOMSN=17398993
2022-12-19T18:03:51.450Z,1671473031.450 [Default:A.Wait](INFO): Done Waiting.
2022-12-19T18:03:51.450Z,1671473031.450 [Default:A.Wait] Stopped
2022-12-19T18:03:51.450Z,1671473031.450 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-12-19T18:03:51.862Z,1671473031.862 [Default:CheckIn] Running Loop=1
2022-12-19T18:03:51.862Z,1671473031.862 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-19T18:03:51.862Z,1671473031.862 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-19T18:03:52.258Z,1671473032.258 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-12-19T18:04:45.558Z,1671473085.558 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-12-19T18:05:04.612Z,1671473104.612 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-12-19T18:05:04.612Z,1671473104.612 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-12-19T18:05:04.642Z,1671473104.642 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-12-19T18:05:04.970Z,1671473104.970 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-12-19T18:05:04.970Z,1671473104.970 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-12-19T18:06:31.812Z,1671473191.812 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 5.
2022-12-19T18:06:31.815Z,1671473191.815 [BPC1](INFO): Received data from all battery sticks.
2022-12-19T18:08:05.546Z,1671473285.546 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2022-12-19T18:08:05.547Z,1671473285.547 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-12-19T18:08:05.559Z,1671473285.559 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-12-19T18:08:05.999Z,1671473286.000 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-12-19T18:08:05.000Z,1671473286.000 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2022-12-19T18:08:52.009Z,1671473332.009 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-12-19T18:03:51.9Z
2022-12-19T18:08:52.009Z,1671473332.009 [Default:CheckIn:Read_GPS] Stopped
2022-12-19T18:08:52.009Z,1671473332.009 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-19T18:08:52.418Z,1671473332.418 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-12-19T18:08:59.195Z,1671473339.195 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20221219T175851/Courier0004.lzma
2022-12-19T18:09:00.197Z,1671473340.197 [DataOverHttps](INFO): Moved sent file to Logs/20221219T175851/Courier0004.lzma.bak
2022-12-19T18:09:00.197Z,1671473340.197 [DataOverHttps](INFO): SBD MOMSN=17399017
2022-12-19T18:09:17.136Z,1671473357.136 [DataOverHttps](INFO): Sending 199 bytes from file Logs/20221219T175851/Express0005.lzma
2022-12-19T18:09:18.137Z,1671473358.137 [DataOverHttps](INFO): Moved sent file to Logs/20221219T175851/Express0005.lzma.bak
2022-12-19T18:09:18.137Z,1671473358.137 [DataOverHttps](INFO): SBD MOMSN=17399019
2022-12-19T18:09:20.705Z,1671473360.705 [Default:CheckIn:Read_Iridium] Stopped
2022-12-19T18:09:20.706Z,1671473360.706 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-19T18:09:20.706Z,1671473360.706 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-19T18:09:44.114Z,1671473384.114 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-12-19T18:09:44.114Z,1671473384.114 [NAL9602] Data Fault, FailCount= 1
2022-12-19T18:09:44.114Z,1671473384.114 [NAL9602](ERROR): Data Fault
2022-12-19T18:09:44.139Z,1671473384.139 [CBIT](ERROR): Data Fault in component: NAL9602
2022-12-19T18:09:44.522Z,1671473384.522 [NAL9602](INFO): Powering down
2022-12-19T18:09:45.346Z,1671473385.346 [CBIT](INFO): Clearing failed state for component NAL9602
2022-12-19T18:09:45.346Z,1671473385.346 [NAL9602] No Fault, FailCount= 1
2022-12-19T18:10:14.820Z,1671473414.820 [NAL9602](INFO): Powering up NAL9602
2022-12-19T18:10:25.726Z,1671473425.726 [NAL9602](INFO): NAL9602 initialized
2022-12-19T18:11:06.541Z,1671473466.541 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2022-12-19T18:11:06.541Z,1671473466.541 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-12-19T18:11:06.551Z,1671473466.551 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-12-19T18:11:06.966Z,1671473466.966 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-12-19T18:11:06.966Z,1671473466.966 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2022-12-19T18:14:04.311Z,1671473644.311 [CBIT](INFO): Clearing failed state for component DropWeight
2022-12-19T18:14:04.311Z,1671473644.311 [DropWeight] No Fault, FailCount= 1
2022-12-19T18:14:07.545Z,1671473647.545 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2022-12-19T18:14:07.545Z,1671473647.545 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-12-19T18:14:07.577Z,1671473647.577 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-12-19T18:14:07.966Z,1671473647.966 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-12-19T18:14:07.966Z,1671473647.966 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2022-12-19T18:14:21.285Z,1671473661.285 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-19T18:14:21.286Z,1671473661.286 [Default:CheckIn:C.Wait] Stopped
2022-12-19T18:14:21.286Z,1671473661.286 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-19T18:14:21.286Z,1671473661.286 [Default:CheckIn:D] Running Loop=1
2022-12-19T18:14:21.674Z,1671473661.674 [Default:CheckIn:D] Stopped
2022-12-19T18:14:21.674Z,1671473661.674 [Default:CheckIn:E] Running Loop=1
2022-12-19T18:14:22.086Z,1671473662.086 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.726203 min 
2022-12-19T18:14:22.086Z,1671473662.086 [Default:CheckIn:E] Stopped
2022-12-19T18:14:22.086Z,1671473662.086 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-19T18:14:22.086Z,1671473662.086 [Default:CheckIn] Stopped
2022-12-19T18:14:22.086Z,1671473662.086 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-19T18:14:22.087Z,1671473662.087 [Default:CheckIn](INFO): Running loop #2
2022-12-19T18:14:22.087Z,1671473662.087 [Default:CheckIn] Running Loop=2
2022-12-19T18:14:22.087Z,1671473662.087 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-19T18:14:22.087Z,1671473662.087 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-19T18:17:08.535Z,1671473828.535 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2022-12-19T18:17:08.535Z,1671473828.535 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-12-19T18:17:08.546Z,1671473828.546 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-12-19T18:17:08.961Z,1671473828.961 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-12-19T18:17:08.961Z,1671473828.961 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2022-12-19T18:19:22.298Z,1671473962.298 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-12-19T18:14:22.1Z
2022-12-19T18:19:22.298Z,1671473962.298 [Default:CheckIn:Read_GPS] Stopped
2022-12-19T18:19:22.298Z,1671473962.298 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-19T18:19:29.282Z,1671473969.282 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20221219T175851/Courier0007.lzma
2022-12-19T18:19:30.285Z,1671473970.285 [DataOverHttps](INFO): Moved sent file to Logs/20221219T175851/Courier0007.lzma.bak
2022-12-19T18:19:30.285Z,1671473970.285 [DataOverHttps](INFO): SBD MOMSN=17399023
2022-12-19T18:19:47.083Z,1671473987.083 [DataOverHttps](INFO): Sending 192 bytes from file Logs/20221219T175851/Express0008.lzma
2022-12-19T18:19:48.085Z,1671473988.085 [DataOverHttps](INFO): Moved sent file to Logs/20221219T175851/Express0008.lzma.bak
2022-12-19T18:19:48.085Z,1671473988.085 [DataOverHttps](INFO): SBD MOMSN=17399025
2022-12-19T18:19:50.569Z,1671473990.569 [Default:CheckIn:Read_Iridium] Stopped
2022-12-19T18:19:50.570Z,1671473990.570 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-19T18:19:50.570Z,1671473990.570 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-19T18:20:09.576Z,1671474009.576 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2022-12-19T18:20:09.576Z,1671474009.576 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-12-19T18:20:09.586Z,1671474009.586 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-12-19T18:20:09.985Z,1671474009.985 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-12-19T18:20:09.985Z,1671474009.985 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2022-12-19T18:20:27.345Z,1671474027.345 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-12-19T18:20:27.345Z,1671474027.345 [NAL9602] Data Fault, FailCount= 2
2022-12-19T18:20:27.345Z,1671474027.345 [NAL9602](ERROR): Data Fault
2022-12-19T18:20:27.468Z,1671474027.468 [CBIT](ERROR): Data Fault in component: NAL9602
2022-12-19T18:20:27.741Z,1671474027.741 [NAL9602](INFO): Powering down
2022-12-19T18:20:28.574Z,1671474028.574 [CBIT](INFO): Clearing failed state for component NAL9602
2022-12-19T18:20:28.574Z,1671474028.574 [NAL9602] No Fault, FailCount= 2
2022-12-19T18:20:58.045Z,1671474058.045 [NAL9602](INFO): Powering up NAL9602
2022-12-19T18:21:08.946Z,1671474068.946 [NAL9602](INFO): NAL9602 initialized
2022-12-19T18:23:10.576Z,1671474190.576 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2022-12-19T18:23:10.576Z,1671474190.576 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-12-19T18:23:10.587Z,1671474190.587 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-12-19T18:23:11.002Z,1671474191.002 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-12-19T18:23:11.002Z,1671474191.002 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2022-12-19T18:24:51.169Z,1671474291.169 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-19T18:24:51.169Z,1671474291.169 [Default:CheckIn:C.Wait] Stopped
2022-12-19T18:24:51.169Z,1671474291.169 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-19T18:24:51.169Z,1671474291.169 [Default:CheckIn:D] Running Loop=1
2022-12-19T18:24:51.565Z,1671474291.565 [Default:CheckIn:D] Stopped
2022-12-19T18:24:51.565Z,1671474291.565 [Default:CheckIn:E] Running Loop=1
2022-12-19T18:24:51.986Z,1671474291.986 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.224390 min 
2022-12-19T18:24:51.986Z,1671474291.986 [Default:CheckIn:E] Stopped
2022-12-19T18:24:51.986Z,1671474291.986 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-19T18:24:51.987Z,1671474291.987 [Default:CheckIn] Stopped
2022-12-19T18:24:51.987Z,1671474291.987 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-19T18:24:52.007Z,1671474292.007 [Default:CheckIn](INFO): Running loop #3
2022-12-19T18:24:52.008Z,1671474292.008 [Default:CheckIn] Running Loop=3
2022-12-19T18:24:52.008Z,1671474292.008 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-19T18:24:52.009Z,1671474292.009 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-19T18:26:11.584Z,1671474371.584 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2022-12-19T18:26:11.584Z,1671474371.584 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-12-19T18:26:11.594Z,1671474371.594 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-12-19T18:26:11.983Z,1671474371.983 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-12-19T18:26:11.983Z,1671474371.983 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2022-12-19T18:29:12.576Z,1671474552.576 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2022-12-19T18:29:12.576Z,1671474552.576 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-12-19T18:29:12.586Z,1671474552.586 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-12-19T18:29:12.959Z,1671474552.959 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-12-19T18:29:12.959Z,1671474552.959 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2022-12-19T18:29:52.165Z,1671474592.165 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-12-19T18:24:52.0Z
2022-12-19T18:29:52.165Z,1671474592.165 [Default:CheckIn:Read_GPS] Stopped
2022-12-19T18:29:52.165Z,1671474592.165 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-19T18:29:59.383Z,1671474599.383 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20221219T175851/Courier0010.lzma
2022-12-19T18:30:00.385Z,1671474600.385 [DataOverHttps](INFO): Moved sent file to Logs/20221219T175851/Courier0010.lzma.bak
2022-12-19T18:30:00.385Z,1671474600.385 [DataOverHttps](INFO): SBD MOMSN=17399029
2022-12-19T18:30:18.685Z,1671474618.685 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20221219T175851/Express0011.lzma
2022-12-19T18:30:19.685Z,1671474619.685 [DataOverHttps](INFO): Moved sent file to Logs/20221219T175851/Express0011.lzma.bak
2022-12-19T18:30:19.685Z,1671474619.685 [DataOverHttps](INFO): SBD MOMSN=17399031
2022-12-19T18:30:22.456Z,1671474622.456 [Default:CheckIn:Read_Iridium] Stopped
2022-12-19T18:30:22.456Z,1671474622.456 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-19T18:30:22.456Z,1671474622.456 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-19T18:31:10.913Z,1671474670.913 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-12-19T18:31:10.913Z,1671474670.913 [NAL9602] Data Fault, FailCount= 3
2022-12-19T18:31:10.913Z,1671474670.913 [NAL9602](ERROR): Data Fault
2022-12-19T18:31:10.950Z,1671474670.950 [CBIT](ERROR): Data Fault in component: NAL9602
2022-12-19T18:31:11.316Z,1671474671.316 [NAL9602](INFO): Powering down
2022-12-19T18:31:12.142Z,1671474672.142 [CBIT](INFO): Clearing failed state for component NAL9602
2022-12-19T18:31:12.142Z,1671474672.142 [NAL9602] No Fault, FailCount= 3
2022-12-19T18:31:41.617Z,1671474701.617 [NAL9602](INFO): Powering up NAL9602
2022-12-19T18:31:52.525Z,1671474712.525 [NAL9602](INFO): NAL9602 initialized
2022-12-19T18:32:13.560Z,1671474733.560 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2022-12-19T18:32:13.561Z,1671474733.561 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-12-19T18:32:13.571Z,1671474733.571 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-12-19T18:32:13.970Z,1671474733.970 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-12-19T18:32:13.970Z,1671474733.970 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2022-12-19T18:35:14.615Z,1671474914.615 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2022-12-19T18:35:14.615Z,1671474914.615 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-12-19T18:35:14.626Z,1671474914.626 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-12-19T18:35:15.038Z,1671474915.038 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-12-19T18:35:15.038Z,1671474915.038 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2022-12-19T18:35:23.074Z,1671474923.074 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-19T18:35:23.074Z,1671474923.074 [Default:CheckIn:C.Wait] Stopped
2022-12-19T18:35:23.074Z,1671474923.074 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-19T18:35:23.074Z,1671474923.074 [Default:CheckIn:D] Running Loop=1
2022-12-19T18:35:23.494Z,1671474923.494 [Default:CheckIn:D] Stopped
2022-12-19T18:35:23.494Z,1671474923.494 [Default:CheckIn:E] Running Loop=1
2022-12-19T18:35:23.902Z,1671474923.902 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.756547 min 
2022-12-19T18:35:23.902Z,1671474923.902 [Default:CheckIn:E] Stopped
2022-12-19T18:35:23.902Z,1671474923.902 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-19T18:35:23.902Z,1671474923.902 [Default:CheckIn] Stopped
2022-12-19T18:35:23.902Z,1671474923.902 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-19T18:35:23.902Z,1671474923.902 [Default:CheckIn](INFO): Running loop #4
2022-12-19T18:35:23.902Z,1671474923.902 [Default:CheckIn] Running Loop=4
2022-12-19T18:35:23.902Z,1671474923.902 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-19T18:35:23.902Z,1671474923.902 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-19T18:38:15.583Z,1671475095.583 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2022-12-19T18:38:15.583Z,1671475095.583 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-12-19T18:38:15.594Z,1671475095.594 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-12-19T18:38:15.998Z,1671475095.998 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-12-19T18:38:15.998Z,1671475095.998 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2022-12-19T18:40:24.067Z,1671475224.067 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-12-19T18:35:23.9Z
2022-12-19T18:40:24.067Z,1671475224.067 [Default:CheckIn:Read_GPS] Stopped
2022-12-19T18:40:24.067Z,1671475224.067 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-12-19T18:40:31.455Z,1671475231.455 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20221219T175851/Courier0013.lzma
2022-12-19T18:40:32.457Z,1671475232.457 [DataOverHttps](INFO): Moved sent file to Logs/20221219T175851/Courier0013.lzma.bak
2022-12-19T18:40:32.457Z,1671475232.457 [DataOverHttps](INFO): SBD MOMSN=17399035
2022-12-19T18:40:49.231Z,1671475249.231 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20221219T175851/Express0014.lzma
2022-12-19T18:40:50.233Z,1671475250.233 [DataOverHttps](INFO): Moved sent file to Logs/20221219T175851/Express0014.lzma.bak
2022-12-19T18:40:50.233Z,1671475250.233 [DataOverHttps](INFO): SBD MOMSN=17399037
2022-12-19T18:40:52.766Z,1671475252.766 [Default:CheckIn:Read_Iridium] Stopped
2022-12-19T18:40:52.766Z,1671475252.766 [Default:CheckIn:C.Wait] Running Loop=1
2022-12-19T18:40:52.766Z,1671475252.766 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-12-19T18:41:16.605Z,1671475276.605 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2022-12-19T18:41:16.605Z,1671475276.605 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-12-19T18:41:16.616Z,1671475276.616 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-12-19T18:41:17.030Z,1671475277.030 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-12-19T18:41:17.030Z,1671475277.030 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2022-12-19T18:41:53.357Z,1671475313.357 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-12-19T18:41:53.357Z,1671475313.357 [NAL9602] Data Fault, FailCount= 4
2022-12-19T18:41:53.357Z,1671475313.357 [NAL9602](ERROR): Data Fault
2022-12-19T18:41:53.375Z,1671475313.375 [CBIT](ERROR): Data Fault in component: NAL9602
2022-12-19T18:41:53.765Z,1671475313.765 [NAL9602](INFO): Powering down
2022-12-19T18:41:54.589Z,1671475314.589 [CBIT](INFO): Clearing failed state for component NAL9602
2022-12-19T18:41:54.589Z,1671475314.589 [NAL9602] No Fault, FailCount= 4
2022-12-19T18:42:24.065Z,1671475344.065 [NAL9602](INFO): Powering up NAL9602
2022-12-19T18:42:34.969Z,1671475354.969 [NAL9602](INFO): NAL9602 initialized
2022-12-19T18:44:05.868Z,1671475445.868 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-12-19T18:44:05.869Z,1671475445.869 [DropWeight] Hardware Fault, FailCount= 1
2022-12-19T18:44:05.869Z,1671475445.869 [DropWeight](ERROR): Hardware Fault
2022-12-19T18:44:05.917Z,1671475445.917 [CBIT](INFO): Critical error at 20221219T184405
2022-12-19T18:44:05.919Z,1671475445.919 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-12-19T18:44:05.920Z,1671475445.920 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-12-19T18:44:06.320Z,1671475446.320 [CBIT](INFO): Critical error at 20221219T184405
2022-12-19T18:44:17.616Z,1671475457.616 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2022-12-19T18:44:17.617Z,1671475457.617 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-12-19T18:44:17.627Z,1671475457.627 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-12-19T18:44:18.026Z,1671475458.026 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-12-19T18:44:18.026Z,1671475458.026 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2022-12-19T18:45:53.365Z,1671475553.365 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-12-19T18:45:53.365Z,1671475553.365 [Default:CheckIn:C.Wait] Stopped
2022-12-19T18:45:53.366Z,1671475553.366 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-12-19T18:45:53.366Z,1671475553.366 [Default:CheckIn:D] Running Loop=1
2022-12-19T18:45:53.768Z,1671475553.768 [Default:CheckIn:D] Stopped
2022-12-19T18:45:53.768Z,1671475553.768 [Default:CheckIn:E] Running Loop=1
2022-12-19T18:45:54.152Z,1671475554.152 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.261104 min 
2022-12-19T18:45:54.152Z,1671475554.152 [Default:CheckIn:E] Stopped
2022-12-19T18:45:54.152Z,1671475554.152 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-12-19T18:45:54.152Z,1671475554.152 [Default:CheckIn] Stopped
2022-12-19T18:45:54.152Z,1671475554.152 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-12-19T18:45:54.152Z,1671475554.152 [Default:CheckIn](INFO): Running loop #5
2022-12-19T18:45:54.152Z,1671475554.152 [Default:CheckIn] Running Loop=5
2022-12-19T18:45:54.152Z,1671475554.152 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-12-19T18:45:54.153Z,1671475554.153 [Default:CheckIn:Read_GPS] Running Loop=1
2022-12-19T18:47:18.595Z,1671475638.595 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2022-12-19T18:47:18.595Z,1671475638.595 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-12-19T18:47:18.605Z,1671475638.605 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-12-19T18:47:19.021Z,1671475639.021 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-12-19T18:47:19.022Z,1671475639.022 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2022-12-19T18:50:19.589Z,1671475819.589 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2022-12-19T18:50:19.589Z,1671475819.589 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-12-19T18:50:19.624Z