2022-03-03T23:53:43.792Z,1646351623.792 [Supervisor](DEBUG): Initializing supervisor.
2022-03-03T23:53:43.796Z,1646351623.796 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-03-03T23:53:43.797Z,1646351623.797 [SyncHandler](INFO): Protected caller Thread ID is 8723
2022-03-03T23:53:43.797Z,1646351623.797 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-03-03T23:53:43.798Z,1646351623.798 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-03-03T23:53:43.798Z,1646351623.798 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8724
2022-03-03T23:53:43.803Z,1646351623.803 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-03-03T23:53:43.822Z,1646351623.822 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-03-03T23:53:43.823Z,1646351623.823 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-03-03T23:53:43.823Z,1646351623.823 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 8725
2022-03-03T23:53:43.827Z,1646351623.827 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-03-03T23:53:43.829Z,1646351623.829 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-03-03T23:53:43.829Z,1646351623.829 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8726
2022-03-03T23:53:43.831Z,1646351623.831 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-03-03T23:53:43.832Z,1646351623.832 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-03-03T23:53:43.832Z,1646351623.832 [logger ThreadHandler](INFO): Protected caller Thread ID is 8727
2022-03-03T23:53:43.836Z,1646351623.836 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-03-03T23:53:43.837Z,1646351623.837 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-03-03T23:53:43.838Z,1646351623.838 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-03-03T23:53:43.933Z,1646351623.933 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-03-03T23:53:43.934Z,1646351623.934 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-03-03T23:53:44.146Z,1646351624.146 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-03-03T23:53:44.147Z,1646351624.147 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-03-03T23:53:44.410Z,1646351624.410 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-03-03T23:53:44.411Z,1646351624.411 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-03-03T23:53:44.519Z,1646351624.519 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-03-03T23:53:44.519Z,1646351624.519 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-03-03T23:53:45.175Z,1646351625.175 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-03-03T23:53:45.176Z,1646351625.176 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-03-03T23:53:46.400Z,1646351626.400 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-03-03T23:53:46.400Z,1646351626.400 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-03-03T23:53:46.990Z,1646351626.990 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-03-03T23:53:46.991Z,1646351626.991 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-03-03T23:53:47.373Z,1646351627.373 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-03-03T23:53:47.374Z,1646351627.374 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-03-03T23:53:47.719Z,1646351627.719 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-03-03T23:53:47.720Z,1646351627.720 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-03-03T23:53:48.086Z,1646351628.086 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-03-03T23:53:48.086Z,1646351628.086 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-03-03T23:53:48.305Z,1646351628.305 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-03-03T23:53:48.306Z,1646351628.306 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-03-03T23:53:48.434Z,1646351628.434 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-03-03T23:53:48.435Z,1646351628.435 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-03-03T23:53:48.515Z,1646351628.515 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-03-03T23:53:48.594Z,1646351628.594 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-03-03T23:53:48.787Z,1646351628.787 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-03-03T23:53:48.788Z,1646351628.788 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-03-03T23:53:49.008Z,1646351629.008 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-03-03T23:53:49.010Z,1646351629.010 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2022-03-03T23:53:49.011Z,1646351629.011 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2022-03-03T23:53:49.093Z,1646351629.093 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2022-03-03T23:53:49.354Z,1646351629.354 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-03-03T23:53:49.355Z,1646351629.355 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2022-03-03T23:53:49.450Z,1646351629.450 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2022-03-03T23:53:49.610Z,1646351629.610 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2022-03-03T23:53:49.796Z,1646351629.796 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2022-03-03T23:53:49.880Z,1646351629.880 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2022-03-03T23:53:49.985Z,1646351629.985 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2022-03-03T23:53:50.086Z,1646351630.086 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2022-03-03T23:53:50.248Z,1646351630.248 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2022-03-03T23:53:50.351Z,1646351630.351 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2022-03-03T23:53:50.450Z,1646351630.450 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2022-03-03T23:53:50.451Z,1646351630.451 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-03-03T23:53:50.459Z,1646351630.459 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-03-03T23:53:50.604Z,1646351630.604 [InternalSim] Loaded
2022-03-03T23:53:50.604Z,1646351630.604 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2022-03-03T23:53:50.605Z,1646351630.605 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-03-03T23:53:50.606Z,1646351630.606 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-03-03T23:53:50.757Z,1646351630.757 [BuoyancyServo] Loaded
2022-03-03T23:53:50.757Z,1646351630.757 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-03-03T23:53:50.776Z,1646351630.776 [MassServo] Loaded
2022-03-03T23:53:50.776Z,1646351630.776 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-03-03T23:53:50.791Z,1646351630.791 [ThrusterHE] Loaded
2022-03-03T23:53:50.791Z,1646351630.791 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-03-03T23:53:50.792Z,1646351630.792 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-03-03T23:53:50.792Z,1646351630.792 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-03-03T23:53:50.936Z,1646351630.936 [SBIT](DEBUG): Construct Startup Built In Test.
2022-03-03T23:53:50.951Z,1646351630.951 [SBIT] Loaded
2022-03-03T23:53:50.951Z,1646351630.951 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-03-03T23:53:50.954Z,1646351630.954 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-03-03T23:53:50.973Z,1646351630.973 [IBIT] Loaded
2022-03-03T23:53:50.973Z,1646351630.973 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-03-03T23:53:50.979Z,1646351630.979 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-03-03T23:53:51.192Z,1646351631.192 [CBIT] Loaded
2022-03-03T23:53:51.193Z,1646351631.193 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-03-03T23:53:51.193Z,1646351631.193 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-03-03T23:53:51.194Z,1646351631.194 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-03-03T23:53:51.465Z,1646351631.465 [CTD_NeilBrown] Loaded
2022-03-03T23:53:51.466Z,1646351631.466 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2022-03-03T23:53:51.467Z,1646351631.467 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 406614E0
2022-03-03T23:53:51.467Z,1646351631.467 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 8810
2022-03-03T23:53:51.654Z,1646351631.654 [CTD_Seabird] Loaded
2022-03-03T23:53:51.693Z,1646351631.693 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-03-03T23:53:51.694Z,1646351631.694 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406914E0
2022-03-03T23:53:51.694Z,1646351631.694 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8811
2022-03-03T23:53:51.734Z,1646351631.734 [ESPComponent] Loaded
2022-03-03T23:53:51.734Z,1646351631.734 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2022-03-03T23:53:51.810Z,1646351631.810 [PAR_Licor] Loaded
2022-03-03T23:53:51.840Z,1646351631.840 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-03-03T23:53:51.841Z,1646351631.841 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-03-03T23:53:51.841Z,1646351631.841 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-03-03T23:53:51.952Z,1646351631.952 [DepthRateCalculator] Loaded
2022-03-03T23:53:51.952Z,1646351631.952 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-03-03T23:53:51.971Z,1646351631.971 [PitchRateCalculator] Loaded
2022-03-03T23:53:51.971Z,1646351631.971 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-03-03T23:53:51.982Z,1646351631.982 [SpeedCalculator] Loaded
2022-03-03T23:53:51.982Z,1646351631.982 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-03-03T23:53:51.987Z,1646351631.987 [YawRateCalculator] Loaded
2022-03-03T23:53:51.987Z,1646351631.987 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-03-03T23:53:52.092Z,1646351632.092 [ElevatorOffsetCalculator] Loaded
2022-03-03T23:53:52.092Z,1646351632.092 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-03-03T23:53:52.093Z,1646351632.093 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-03-03T23:53:52.093Z,1646351632.093 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-03-03T23:53:52.249Z,1646351632.249 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-03-03T23:53:52.250Z,1646351632.250 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-03-03T23:53:52.278Z,1646351632.278 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-03-03T23:53:52.278Z,1646351632.278 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-03-03T23:53:52.390Z,1646351632.390 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-03-03T23:53:52.390Z,1646351632.390 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-03-03T23:53:52.404Z,1646351632.404 [NavChart] Loaded
2022-03-03T23:53:52.404Z,1646351632.404 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-03-03T23:53:52.410Z,1646351632.410 [UniversalFixResidualReporter] Loaded
2022-03-03T23:53:52.410Z,1646351632.410 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-03-03T23:53:52.411Z,1646351632.411 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-03-03T23:53:52.411Z,1646351632.411 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-03-03T23:53:53.490Z,1646351633.490 [BPC1] Loaded
2022-03-03T23:53:53.490Z,1646351633.490 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-03-03T23:53:53.563Z,1646351633.563 [DataOverHttps] Loaded
2022-03-03T23:53:53.564Z,1646351633.564 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-03-03T23:53:53.565Z,1646351633.565 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A0B4E0
2022-03-03T23:53:53.565Z,1646351633.565 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8812
2022-03-03T23:53:53.585Z,1646351633.585 [Depth_Keller] Loaded
2022-03-03T23:53:53.586Z,1646351633.586 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-03-03T23:53:53.590Z,1646351633.590 [DropWeight] Loaded
2022-03-03T23:53:53.591Z,1646351633.591 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-03-03T23:53:53.649Z,1646351633.649 [NAL9602] Loaded
2022-03-03T23:53:53.650Z,1646351633.650 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-03-03T23:53:53.675Z,1646351633.675 [Onboard] Loaded
2022-03-03T23:53:53.675Z,1646351633.675 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-03-03T23:53:53.676Z,1646351633.676 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A3B4E0
2022-03-03T23:53:53.677Z,1646351633.677 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 8813
2022-03-03T23:53:53.689Z,1646351633.689 [Power24vConverter] Loaded
2022-03-03T23:53:53.689Z,1646351633.689 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-03-03T23:53:53.702Z,1646351633.702 [Radio_Surface] Loaded
2022-03-03T23:53:53.702Z,1646351633.702 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-03-03T23:53:53.703Z,1646351633.703 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A6B4E0
2022-03-03T23:53:53.704Z,1646351633.704 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8814
2022-03-03T23:53:53.788Z,1646351633.788 [DAT] Loaded
2022-03-03T23:53:53.788Z,1646351633.788 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2022-03-03T23:53:53.789Z,1646351633.789 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A9B4E0
2022-03-03T23:53:53.789Z,1646351633.789 [DAT ThreadHandler](INFO): Protected caller Thread ID is 8815
2022-03-03T23:53:53.790Z,1646351633.790 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-03-03T23:53:53.790Z,1646351633.790 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-03-03T23:53:53.848Z,1646351633.848 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-03-03T23:53:53.848Z,1646351633.848 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-03-03T23:53:53.902Z,1646351633.902 [VerticalControl](DEBUG): Construct VerticalControl.
2022-03-03T23:53:53.955Z,1646351633.955 [VerticalControl] Loaded
2022-03-03T23:53:53.955Z,1646351633.955 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-03-03T23:53:53.958Z,1646351633.958 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-03-03T23:53:53.994Z,1646351633.994 [HorizontalControl] Loaded
2022-03-03T23:53:53.994Z,1646351633.994 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-03-03T23:53:53.996Z,1646351633.996 [SpeedControl](DEBUG): Construct SpeedControl.
2022-03-03T23:53:53.998Z,1646351633.998 [SpeedControl] Loaded
2022-03-03T23:53:53.998Z,1646351633.998 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-03-03T23:53:54.001Z,1646351634.001 [LoopControl](DEBUG): Construct LoopControl.
2022-03-03T23:53:54.002Z,1646351634.002 [LoopControl] Loaded
2022-03-03T23:53:54.002Z,1646351634.002 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-03-03T23:53:54.002Z,1646351634.002 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-03-03T23:53:54.003Z,1646351634.003 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-03-03T23:53:54.013Z,1646351634.013 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-03-03T23:53:54.014Z,1646351634.014 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-03-03T23:53:54.331Z,1646351634.331 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-03-03T23:53:54.337Z,1646351634.337 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-03-03T23:53:54.340Z,1646351634.340 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-03-03T23:53:54.351Z,1646351634.351 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-03-03T23:53:54.352Z,1646351634.352 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BA44E0
2022-03-03T23:53:54.352Z,1646351634.352 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8816
2022-03-03T23:53:54.357Z,1646351634.357 [Supervisor](INFO): Main Thread ID is 8722
2022-03-03T23:53:54.357Z,1646351634.357 [Supervisor](DEBUG): Running supervisor.
2022-03-03T23:53:54.358Z,1646351634.358 [CommandExec ThreadHandler](INFO): Handler Thread ID is 8817
2022-03-03T23:53:54.358Z,1646351634.358 [CommandExec](INFO): Initializing the command executive.
2022-03-03T23:53:54.360Z,1646351634.360 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8818
2022-03-03T23:53:54.362Z,1646351634.362 [controlThread ThreadHandler](INFO): Handler Thread ID is 8819
2022-03-03T23:53:54.362Z,1646351634.362 [controlThread](DEBUG): Initializing ControlThread
2022-03-03T23:53:54.363Z,1646351634.363 [InternalSim](DEBUG):  InternalSim initializing...
2022-03-03T23:53:54.370Z,1646351634.370 [InternalSim](DEBUG): oceanModelVarCount=0
2022-03-03T23:53:54.396Z,1646351634.396 [logger ThreadHandler](INFO): Handler Thread ID is 8820
2022-03-03T23:53:54.424Z,1646351634.424 [SBIT](INFO): Initialize SBIT Component.
2022-03-03T23:53:54.424Z,1646351634.424 [SBIT](IMPORTANT): git: 2022-02-18-48-g1549343b4
2022-03-03T23:53:54.424Z,1646351634.424 [SBIT](INFO): git hash: 1549343b4e331ba79affae8faa75cfd33e9b170a
2022-03-03T23:53:54.425Z,1646351634.425 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-03-03T23:53:54.426Z,1646351634.426 [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
2022-03-03T23:53:54.427Z,1646351634.427 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2022-03-03T23:53:54.427Z,1646351634.427 [IBIT](INFO): Initialize IBIT Component.
2022-03-03T23:53:54.436Z,1646351634.436 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 8821
2022-03-03T23:53:54.437Z,1646351634.437 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2022-03-03T23:53:54.437Z,1646351634.437 [CBIT](DEBUG): Initialize CBIT Component.
2022-03-03T23:53:54.448Z,1646351634.448 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8822
2022-03-03T23:53:54.449Z,1646351634.449 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-03-03T23:53:54.452Z,1646351634.452 [CBIT](DEBUG): Initialized mux pins.
2022-03-03T23:53:54.452Z,1646351634.452 [CBIT](DEBUG): Initializing the watchdog timer.
2022-03-03T23:53:54.454Z,1646351634.454 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8824
2022-03-03T23:53:54.455Z,1646351634.455 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-03-03T23:53:54.476Z,1646351634.476 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2022-03-03T23:53:54.476Z,1646351634.476 [CBIT](DEBUG): Initializing heartbeat.
2022-03-03T23:53:54.477Z,1646351634.477 [Onboard ThreadHandler](INFO): Handler Thread ID is 8825
2022-03-03T23:53:54.494Z,1646351634.494 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8826
2022-03-03T23:53:54.512Z,1646351634.512 [DAT ThreadHandler](INFO): Handler Thread ID is 8827
2022-03-03T23:53:54.513Z,1646351634.513 [DAT](INFO): Powering up
2022-03-03T23:53:54.513Z,1646351634.513 [DAT](DEBUG): Initializing DAT.
2022-03-03T23:53:54.517Z,1646351634.517 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8829
2022-03-03T23:53:54.520Z,1646351634.520 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI01M.000
2022-03-03T23:53:54.520Z,1646351634.520 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI01M.000
2022-03-03T23:53:54.520Z,1646351634.520 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI89M.000
2022-03-03T23:53:54.520Z,1646351634.520 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI89M.000
2022-03-03T23:53:54.520Z,1646351634.520 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI11M.000
2022-03-03T23:53:54.521Z,1646351634.521 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI11M.000
2022-03-03T23:53:54.521Z,1646351634.521 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI07M.000
2022-03-03T23:53:54.521Z,1646351634.521 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5MI07M.000
2022-03-03T23:53:54.521Z,1646351634.521 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5OH07M.000
2022-03-03T23:53:54.521Z,1646351634.521 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5OH07M.000
2022-03-03T23:53:54.521Z,1646351634.521 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5OH31M.000
2022-03-03T23:53:54.522Z,1646351634.522 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5OH31M.000
2022-03-03T23:53:54.548Z,1646351634.548 [CBIT](DEBUG): Deactivating GF circuits.
2022-03-03T23:53:54.548Z,1646351634.548 [CBIT](DEBUG): Deactivating emergency mode.
2022-03-03T23:53:54.584Z,1646351634.584 [CBIT](DEBUG): Backplane powered.
2022-03-03T23:53:54.588Z,1646351634.588 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-03-03T23:53:54.588Z,1646351634.588 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-03-03T23:53:54.589Z,1646351634.589 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-03-03T23:53:54.589Z,1646351634.589 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-03-03T23:53:54.589Z,1646351634.589 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-03-03T23:53:54.591Z,1646351634.591 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-03-03T23:53:54.591Z,1646351634.591 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-03-03T23:53:54.598Z,1646351634.598 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-03-03T23:53:54.599Z,1646351634.599 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-03-03T23:53:54.608Z,1646351634.608 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-03-03T23:53:54.609Z,1646351634.609 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-03-03T23:53:54.610Z,1646351634.610 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-03-03T23:53:54.650Z,1646351634.650 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-03-03T23:53:54.698Z,1646351634.698 [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-03-03T23:53:54.698Z,1646351634.698 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-03-03T23:53:54.793Z,1646351634.793 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-03-03T23:53:54.794Z,1646351634.794 [Default:A.Wait](DEBUG): Construct Wait.
2022-03-03T23:53:54.797Z,1646351634.797 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-03-03T23:53:54.849Z,1646351634.849 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-03-03T23:53:54.877Z,1646351634.877 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-03-03T23:53:54.883Z,1646351634.883 [Default:E.Execute](DEBUG): Construct Execute.
2022-03-03T23:53:54.900Z,1646351634.900 [Radio_Surface](INFO): Powering up
2022-03-03T23:53:54.910Z,1646351634.910 [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="P2H">
                <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-03-03T23:53:54.914Z,1646351634.914 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,ESPComponent,PAR_Licor,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,MassServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2022-03-03T23:53:55.020Z,1646351635.020 [Depth_Keller](ERROR): Pressure reading out of range: 1896.040405 decibar
2022-03-03T23:53:55.022Z,1646351635.022 [Power24vConverter](INFO): Powering up.
2022-03-03T23:53:55.049Z,1646351635.049 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-03-03T23:53:55.056Z,1646351635.056 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-03-03T23:53:55.057Z,1646351635.057 [MassServo](DEBUG): Initializing EZServoServo.
2022-03-03T23:53:55.064Z,1646351635.064 [MassServo](DEBUG): Initializing MassServo.
2022-03-03T23:53:55.065Z,1646351635.065 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-03-03T23:53:55.072Z,1646351635.072 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-03-03T23:53:55.074Z,1646351635.074 [CommandExec](FAULT): Scheduling is paused
2022-03-03T23:53:55.074Z,1646351635.074 [CBIT](INFO): Critical error at 20220303T235354
2022-03-03T23:53:55.075Z,1646351635.075 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2022-03-03T23:53:55.367Z,1646351635.367 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-03-03T23:53:55.367Z,1646351635.367 [DropWeight] Hardware Fault, FailCount= 1
2022-03-03T23:53:55.367Z,1646351635.367 [DropWeight](ERROR): Hardware Fault
2022-03-03T23:53:55.428Z,1646351635.428 [CBIT](INFO): Critical error at 20220303T235355
2022-03-03T23:53:55.430Z,1646351635.430 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-03-03T23:53:55.431Z,1646351635.431 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-03-03T23:53:55.781Z,1646351635.781 [CBIT](INFO): Critical error at 20220303T235355
2022-03-03T23:54:00.780Z,1646351640.780 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-03-03T23:54:04.684Z,1646351644.684 [CBIT](CRITICAL): Environmental Failure. Press:14.639530 PSI. Humidity:35%. Temp:24 C. ABORTING MISSION
2022-03-03T23:54:05.082Z,1646351645.082 [CBIT](INFO): Critical error at 20220303T235404
2022-03-03T23:54:07.501Z,1646351647.501 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-03-03T23:54:07.501Z,1646351647.501 [CTD_Seabird] Communications Fault, FailCount= 1
2022-03-03T23:54:07.501Z,1646351647.501 [CTD_Seabird](ERROR): Communications Fault
2022-03-03T23:54:07.512Z,1646351647.512 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-03-03T23:54:07.905Z,1646351647.905 [CTD_Seabird](INFO): Powering down
2022-03-03T23:54:08.626Z,1646351648.626 [DAT](INFO): commRate: 1200
2022-03-03T23:54:09.132Z,1646351649.132 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-03-03T23:54:09.132Z,1646351649.132 [CTD_Seabird] No Fault, FailCount= 1
2022-03-03T23:54:09.248Z,1646351649.248 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-03-03T23:54:10.692Z,1646351650.692 [DAT](INFO): entering command mode
2022-03-03T23:54:10.893Z,1646351650.893 [DAT](INFO): setting verbose to 3
2022-03-03T23:54:11.146Z,1646351651.146 [DAT](INFO): set verbose to 3
2022-03-03T23:54:11.146Z,1646351651.146 [DAT](INFO): setting DatVerbose to 27440
2022-03-03T23:54:11.397Z,1646351651.397 [DAT](INFO): set DatVerbose to 27440
2022-03-03T23:54:11.398Z,1646351651.398 [DAT](INFO): setting transmit power to 8
2022-03-03T23:54:11.649Z,1646351651.649 [DAT](INFO): set transmit power to 8
2022-03-03T23:54:11.650Z,1646351651.650 [DAT](INFO): setting local address to 4
2022-03-03T23:54:11.901Z,1646351651.901 [DAT](INFO): set local address to 4
2022-03-03T23:54:11.902Z,1646351651.902 [DAT](INFO): Setting time to: 23:54:11 And date to:3/3/2022
2022-03-03T23:54:12.154Z,1646351652.154 [DAT](INFO): Local DAT time set to Thu Mar 3, 2022  23:54:11  

2022-03-03T23:54:22.300Z,1646351662.300 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-03-03T23:54:22.300Z,1646351662.300 [CTD_Seabird] Communications Fault, FailCount= 2
2022-03-03T23:54:22.300Z,1646351662.300 [CTD_Seabird](ERROR): Communications Fault
2022-03-03T23:54:22.443Z,1646351662.443 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-03-03T23:54:22.704Z,1646351662.704 [CTD_Seabird](INFO): Powering down
2022-03-03T23:54:23.656Z,1646351663.656 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-03-03T23:54:23.656Z,1646351663.656 [CTD_Seabird] No Fault, FailCount= 2
2022-03-03T23:54:24.019Z,1646351664.019 [NAL9602](INFO): Powering up NAL9602
2022-03-03T23:54:24.052Z,1646351664.052 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-03-03T23:54:34.935Z,1646351674.935 [NAL9602](INFO): NAL9602 initialized
2022-03-03T23:54:37.104Z,1646351677.104 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-03-03T23:54:37.104Z,1646351677.104 [CTD_Seabird] Communications Fault, FailCount= 3
2022-03-03T23:54:37.104Z,1646351677.104 [CTD_Seabird](ERROR): Communications Fault
2022-03-03T23:54:37.363Z,1646351677.363 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-03-03T23:54:37.364Z,1646351677.364 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2022-03-03T23:54:37.508Z,1646351677.508 [CTD_Seabird](INFO): Powering down
2022-03-03T23:54:53.959Z,1646351693.959 [SBIT](IMPORTANT): Beginning Startup BIT
2022-03-03T23:54:53.963Z,1646351693.963 [CBIT](IMPORTANT): Beginning ground fault scan
2022-03-03T23:55:04.891Z,1646351704.891 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.032092
CHAN A1 (24V): 0.000348
CHAN A2 (12V): -0.005532
CHAN A3 (5V): -0.002849
CHAN B0 (3.3V): -0.001028
CHAN B1 (3.15aV): -0.001505
CHAN B2 (3.15bV): -0.000931
CHAN B3 (GND): -0.000026
OPEN: -0.000736
Full Scale: +/- 1 mA
2022-03-03T23:55:47.662Z,1646351747.662 [SBIT](IMPORTANT): SBIT PASSED
2022-03-03T23:55:47.663Z,1646351747.663 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-03-03T23:55:47.664Z,1646351747.664 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2022-03-03T23:55:47.664Z,1646351747.664 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2022-03-03T23:55:47.664Z,1646351747.664 [SBIT](IMPORTANT): ElevatorServo.loadAtStartup=0 bool;

2022-03-03T23:55:47.664Z,1646351747.664 [SBIT](IMPORTANT): InternalSim.loadAtStartup=1 bool;

2022-03-03T23:55:47.664Z,1646351747.664 [SBIT](IMPORTANT): RudderServo.loadAtStartup=0 bool;

2022-03-03T23:55:47.664Z,1646351747.664 [SBIT](IMPORTANT): ThrusterServo.loadAtStartup=0 bool;

2022-03-03T23:55:47.664Z,1646351747.664 [SBIT](IMPORTANT): WetLabsBB2FL.loadAtStartup=0 bool;

2022-03-03T23:55:48.076Z,1646351748.076 [MissionManager](IMPORTANT): Started mission Startup
2022-03-03T23:55:48.077Z,1646351748.077 [Startup] Running Loop=1
2022-03-03T23:55:48.077Z,1646351748.077 [Startup](DEBUG): Aggregate::initialize Startup
2022-03-03T23:55:48.077Z,1646351748.077 [Startup:A.GoToSurface] Running Loop=1
2022-03-03T23:55:48.077Z,1646351748.077 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-03-03T23:55:48.078Z,1646351748.078 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-03-03T23:55:48.078Z,1646351748.078 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-03-03T23:55:48.078Z,1646351748.078 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-03-03T23:55:48.079Z,1646351748.079 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-03-03T23:55:48.079Z,1646351748.079 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-03-03T23:55:48.081Z,1646351748.081 [Startup:StartupSatComms] Running Loop=1
2022-03-03T23:55:48.081Z,1646351748.081 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-03-03T23:55:48.081Z,1646351748.081 [Startup:StartupSatComms:A] Running Loop=1
2022-03-03T23:55:48.462Z,1646351748.462 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-03-03T23:55:54.988Z,1646351754.988 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2022-03-03T23:55:54.989Z,1646351754.989 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-03-03T23:55:54.989Z,1646351754.989 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-03-03T23:55:54.999Z,1646351754.999 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-03-03T23:55:55.350Z,1646351755.350 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-03-03T23:55:55.350Z,1646351755.350 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-03-03T23:56:37.306Z,1646351797.306 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004681
2022-03-03T23:56:48.251Z,1646351808.251 [Startup:StartupSatComms:A](INFO): Timed out from 2022-03-03T23:55:48.1Z
2022-03-03T23:56:48.251Z,1646351808.251 [Startup:StartupSatComms:A] Stopped
2022-03-03T23:56:48.251Z,1646351808.251 [Startup:StartupSatComms:B] Running Loop=1
2022-03-03T23:56:48.750Z,1646351808.750 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-03-03T23:56:58.520Z,1646351818.520 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220303T234429/Courier0004.lzma
2022-03-03T23:56:59.522Z,1646351819.522 [DataOverHttps](INFO): Moved sent file to Logs/20220303T234429/Courier0004.lzma.bak
2022-03-03T23:56:59.522Z,1646351819.522 [DataOverHttps](INFO): SBD MOMSN=16533845
2022-03-03T23:57:07.298Z,1646351827.298 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-03-03T23:57:07.298Z,1646351827.298 [CTD_Seabird] No Fault, FailCount= 3
2022-03-03T23:57:07.524Z,1646351827.524 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-03-03T23:57:15.876Z,1646351835.876 [DataOverHttps](INFO): Sending 357 bytes from file Logs/20220303T234932/Courier0000.lzma
2022-03-03T23:57:16.877Z,1646351836.877 [DataOverHttps](INFO): Moved sent file to Logs/20220303T234932/Courier0000.lzma.bak
2022-03-03T23:57:16.878Z,1646351836.878 [DataOverHttps](INFO): SBD MOMSN=16533847
2022-03-03T23:57:20.576Z,1646351840.576 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-03-03T23:57:20.576Z,1646351840.576 [CTD_Seabird] Communications Fault, FailCount= 1
2022-03-03T23:57:20.576Z,1646351840.576 [CTD_Seabird](ERROR): Communications Fault
2022-03-03T23:57:20.653Z,1646351840.653 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-03-03T23:57:20.981Z,1646351840.981 [CTD_Seabird](INFO): Powering down
2022-03-03T23:57:22.221Z,1646351842.221 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-03-03T23:57:22.221Z,1646351842.221 [CTD_Seabird] No Fault, FailCount= 1
2022-03-03T23:57:22.320Z,1646351842.320 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-03-03T23:57:35.372Z,1646351855.372 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-03-03T23:57:35.372Z,1646351855.372 [CTD_Seabird] Communications Fault, FailCount= 2
2022-03-03T23:57:35.372Z,1646351855.372 [CTD_Seabird](ERROR): Communications Fault
2022-03-03T23:57:35.545Z,1646351855.545 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-03-03T23:57:35.776Z,1646351855.776 [CTD_Seabird](INFO): Powering down
2022-03-03T23:57:35.956Z,1646351855.956 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20220303T234932/Courier0004.lzma
2022-03-03T23:57:36.746Z,1646351856.746 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-03-03T23:57:36.746Z,1646351856.746 [CTD_Seabird] No Fault, FailCount= 2
2022-03-03T23:57:36.958Z,1646351856.958 [DataOverHttps](INFO): Moved sent file to Logs/20220303T234932/Courier0004.lzma.bak
2022-03-03T23:57:36.958Z,1646351856.958 [DataOverHttps](INFO): SBD MOMSN=16533854
2022-03-03T23:57:37.124Z,1646351857.124 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-03-03T23:57:48.447Z,1646351868.447 [Startup:StartupSatComms:B](INFO): Timed out from 2022-03-03T23:56:48.3Z
2022-03-03T23:57:48.447Z,1646351868.447 [Startup:StartupSatComms:B] Stopped
2022-03-03T23:57:48.447Z,1646351868.447 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-03-03T23:57:48.447Z,1646351868.447 [Startup:StartupSatComms] Stopped
2022-03-03T23:57:48.447Z,1646351868.447 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-03-03T23:57:48.448Z,1646351868.448 [Startup](INFO): Completed Startup
2022-03-03T23:57:48.449Z,1646351868.449 [MissionManager](INFO): Startup is completed.
2022-03-03T23:57:48.449Z,1646351868.449 [MissionManager](INFO): Uninitializing Mission Startup
2022-03-03T23:57:48.449Z,1646351868.449 [Startup] Stopped
2022-03-03T23:57:48.449Z,1646351868.449 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-03-03T23:57:48.449Z,1646351868.449 [Startup:A.GoToSurface] Stopped
2022-03-03T23:57:48.449Z,1646351868.449 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-03-03T23:57:48.865Z,1646351868.865 [MissionManager](IMPORTANT): Started mission Default
2022-03-03T23:57:48.865Z,1646351868.865 [Default] Running Loop=1
2022-03-03T23:57:48.865Z,1646351868.865 [Default](DEBUG): Aggregate::initialize Default
2022-03-03T23:57:48.865Z,1646351868.865 [Default:B.GoToSurface] Running Loop=1
2022-03-03T23:57:48.865Z,1646351868.865 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-03-03T23:57:48.866Z,1646351868.866 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-03-03T23:57:48.866Z,1646351868.866 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-03-03T23:57:48.866Z,1646351868.866 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-03-03T23:57:48.866Z,1646351868.866 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-03-03T23:57:48.867Z,1646351868.867 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-03-03T23:57:48.867Z,1646351868.867 [Default:A.Wait] Running Loop=1
2022-03-03T23:57:48.867Z,1646351868.867 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-03-03T23:57:50.204Z,1646351870.204 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-03-03T23:57:50.204Z,1646351870.204 [CTD_Seabird] Communications Fault, FailCount= 3
2022-03-03T23:57:50.204Z,1646351870.204 [CTD_Seabird](ERROR): Communications Fault
2022-03-03T23:57:50.500Z,1646351870.500 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-03-03T23:57:50.500Z,1646351870.500 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2022-03-03T23:57:50.609Z,1646351870.609 [CTD_Seabird](INFO): Powering down
2022-03-03T23:57:54.393Z,1646351874.393 [DataOverHttps](INFO): Sending 358 bytes from file Logs/20220303T235343/Courier0000.lzma
2022-03-03T23:57:55.393Z,1646351875.393 [DataOverHttps](INFO): Moved sent file to Logs/20220303T235343/Courier0000.lzma.bak
2022-03-03T23:57:55.394Z,1646351875.394 [DataOverHttps](INFO): SBD MOMSN=16533856
2022-03-03T23:57:56.212Z,1646351876.212 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2022-03-03T23:57:56.212Z,1646351876.212 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-03-03T23:57:56.212Z,1646351876.212 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-03-03T23:57:56.252Z,1646351876.252 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-03-03T23:57:56.572Z,1646351876.572 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-03-03T23:57:56.573Z,1646351876.573 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-03-03T23:58:02.214Z,1646351882.214 [Default:A.Wait](INFO): Done Waiting.
2022-03-03T23:58:02.214Z,1646351882.214 [Default:A.Wait] Stopped
2022-03-03T23:58:02.214Z,1646351882.214 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-03-03T23:58:02.598Z,1646351882.598 [Default:CheckIn] Running Loop=1
2022-03-03T23:58:02.598Z,1646351882.598 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-03T23:58:02.598Z,1646351882.598 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-03T23:58:02.995Z,1646351882.995 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-03-03T23:59:38.330Z,1646351978.330 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-03-03T23:59:57.004Z,1646351997.004 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2022-03-03T23:59:57.004Z,1646351997.004 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2022-03-03T23:59:57.004Z,1646351997.004 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-03-03T23:59:57.014Z,1646351997.014 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-03-03T23:59:57.381Z,1646351997.381 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-03-03T23:59:57.381Z,1646351997.381 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2022-03-04T00:00:20.365Z,1646352020.365 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-03-04T00:00:20.365Z,1646352020.365 [CTD_Seabird] No Fault, FailCount= 3
2022-03-04T00:00:20.620Z,1646352020.620 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-03-04T00:00:33.672Z,1646352033.672 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-03-04T00:00:33.672Z,1646352033.672 [CTD_Seabird] Communications Fault, FailCount= 1
2022-03-04T00:00:33.672Z,1646352033.672 [CTD_Seabird](ERROR): Communications Fault
2022-03-04T00:00:33.739Z,1646352033.739 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-03-04T00:00:34.076Z,1646352034.076 [CTD_Seabird](INFO): Powering down
2022-03-04T00:00:35.317Z,1646352035.317 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-03-04T00:00:35.317Z,1646352035.317 [CTD_Seabird] No Fault, FailCount= 1
2022-03-04T00:00:35.416Z,1646352035.416 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-03-04T00:00:48.468Z,1646352048.468 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-03-04T00:00:48.468Z,1646352048.468 [CTD_Seabird] Communications Fault, FailCount= 2
2022-03-04T00:00:48.468Z,1646352048.468 [CTD_Seabird](ERROR): Communications Fault
2022-03-04T00:00:48.665Z,1646352048.665 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-03-04T00:00:48.872Z,1646352048.872 [CTD_Seabird](INFO): Powering down
2022-03-04T00:00:49.894Z,1646352049.894 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-03-04T00:00:49.895Z,1646352049.895 [CTD_Seabird] No Fault, FailCount= 2
2022-03-04T00:00:50.216Z,1646352050.216 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-03-04T00:01:03.268Z,1646352063.268 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-03-04T00:01:03.268Z,1646352063.268 [CTD_Seabird] Communications Fault, FailCount= 3
2022-03-04T00:01:03.268Z,1646352063.268 [CTD_Seabird](ERROR): Communications Fault
2022-03-04T00:01:03.613Z,1646352063.613 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-03-04T00:01:03.614Z,1646352063.614 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2022-03-04T00:01:03.672Z,1646352063.672 [CTD_Seabird](INFO): Powering down
2022-03-04T00:01:57.786Z,1646352117.786 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2022-03-04T00:01:57.786Z,1646352117.786 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2022-03-04T00:01:57.786Z,1646352117.786 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-03-04T00:01:57.828Z,1646352117.828 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-03-04T00:01:58.144Z,1646352118.144 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-03-04T00:01:58.145Z,1646352118.145 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2022-03-04T00:03:02.789Z,1646352182.789 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-03-03T23:58:02.6Z
2022-03-04T00:03:02.789Z,1646352182.789 [Default:CheckIn:Read_GPS] Stopped
2022-03-04T00:03:02.789Z,1646352182.789 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-04T00:03:03.190Z,1646352183.190 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-03-04T00:03:11.988Z,1646352191.988 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220303T235343/Courier0004.lzma
2022-03-04T00:03:12.990Z,1646352192.990 [DataOverHttps](INFO): Moved sent file to Logs/20220303T235343/Courier0004.lzma.bak
2022-03-04T00:03:12.990Z,1646352192.990 [DataOverHttps](INFO): SBD MOMSN=16533863
2022-03-04T00:03:33.543Z,1646352213.543 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-03-04T00:03:33.543Z,1646352213.543 [CTD_Seabird] No Fault, FailCount= 3
2022-03-04T00:03:33.712Z,1646352213.712 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-03-04T00:03:35.299Z,1646352215.299 [DataOverHttps](INFO): Sending 1176 bytes from file Logs/20211203T000658/Express0001.lzma
2022-03-04T00:03:36.298Z,1646352216.298 [DataOverHttps](INFO): Moved sent file to Logs/20211203T000658/Express0001.lzma.bak
2022-03-04T00:03:36.298Z,1646352216.298 [DataOverHttps](INFO): SBD MOMSN=16533865
2022-03-04T00:03:46.764Z,1646352226.764 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-03-04T00:03:46.764Z,1646352226.764 [CTD_Seabird] Communications Fault, FailCount= 1
2022-03-04T00:03:46.764Z,1646352226.764 [CTD_Seabird](ERROR): Communications Fault
2022-03-04T00:03:46.836Z,1646352226.836 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-03-04T00:03:47.168Z,1646352227.168 [CTD_Seabird](INFO): Powering down
2022-03-04T00:03:48.441Z,1646352228.441 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-03-04T00:03:48.441Z,1646352228.441 [CTD_Seabird] No Fault, FailCount= 1
2022-03-04T00:03:48.512Z,1646352228.512 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-03-04T00:03:54.924Z,1646352234.924 [DataOverHttps](INFO): Sending 444 bytes from file Logs/20211203T000658/Express0005.lzma
2022-03-04T00:03:57.188Z,1646352237.188 [DataOverHttps](INFO): Moved sent file to Logs/20211203T000658/Express0005.lzma.bak
2022-03-04T00:03:57.188Z,1646352237.188 [DataOverHttps](INFO): SBD MOMSN=16533921
2022-03-04T00:03:58.942Z,1646352238.942 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2022-03-04T00:03:58.943Z,1646352238.943 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2022-03-04T00:03:58.943Z,1646352238.943 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-03-04T00:03:59.012Z,1646352239.012 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-03-04T00:03:59.373Z,1646352239.373 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-03-04T00:03:59.373Z,1646352239.373 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2022-03-04T00:04:01.564Z,1646352241.564 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-03-04T00:04:01.564Z,1646352241.564 [CTD_Seabird] Communications Fault, FailCount= 2
2022-03-04T00:04:01.564Z,1646352241.564 [CTD_Seabird](ERROR): Communications Fault
2022-03-04T00:04:01.829Z,1646352241.829 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-03-04T00:04:01.969Z,1646352241.969 [CTD_Seabird](INFO): Powering down
2022-03-04T00:04:03.022Z,1646352243.022 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-03-04T00:04:03.022Z,1646352243.022 [CTD_Seabird] No Fault, FailCount= 2
2022-03-04T00:04:03.308Z,1646352243.308 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-03-04T00:04:15.158Z,1646352255.158 [DataOverHttps](INFO): Sending 629 bytes from file Logs/20211203T000658/Express0008.lzma
2022-03-04T00:04:16.158Z,1646352256.158 [DataOverHttps](INFO): Moved sent file to Logs/20211203T000658/Express0008.lzma.bak
2022-03-04T00:04:16.158Z,1646352256.158 [DataOverHttps](INFO): SBD MOMSN=16533957
2022-03-04T00:04:16.360Z,1646352256.360 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-03-04T00:04:16.360Z,1646352256.360 [CTD_Seabird] Communications Fault, FailCount= 3
2022-03-04T00:04:16.360Z,1646352256.360 [CTD_Seabird](ERROR): Communications Fault
2022-03-04T00:04:16.717Z,1646352256.717 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-03-04T00:04:16.718Z,1646352256.718 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2022-03-04T00:04:16.764Z,1646352256.764 [CTD_Seabird](INFO): Powering down
2022-03-04T00:04:33.661Z,1646352273.661 [DataOverHttps](INFO): Sending 448 bytes from file Logs/20211203T000658/Express0011.lzma
2022-03-04T00:04:34.662Z,1646352274.662 [DataOverHttps](INFO): Moved sent file to Logs/20211203T000658/Express0011.lzma.bak
2022-03-04T00:04:34.662Z,1646352274.662 [DataOverHttps](INFO): SBD MOMSN=16534003
2022-03-04T00:04:37.697Z,1646352277.697 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-03-04T00:04:37.697Z,1646352277.697 [NAL9602] Data Fault, FailCount= 1
2022-03-04T00:04:37.697Z,1646352277.697 [NAL9602](ERROR): Data Fault
2022-03-04T00:04:37.777Z,1646352277.777 [CBIT](ERROR): Data Fault in component: NAL9602
2022-03-04T00:04:38.105Z,1646352278.105 [NAL9602](INFO): Powering down
2022-03-04T00:04:38.959Z,1646352278.959 [CBIT](INFO): Clearing failed state for component NAL9602
2022-03-04T00:04:38.959Z,1646352278.959 [NAL9602] No Fault, FailCount= 1
2022-03-04T00:04:54.805Z,1646352294.805 [DataOverHttps](INFO): Sending 421 bytes from file Logs/20211203T000658/Express0014.lzma
2022-03-04T00:04:55.806Z,1646352295.806 [DataOverHttps](INFO): Moved sent file to Logs/20211203T000658/Express0014.lzma.bak
2022-03-04T00:04:55.806Z,1646352295.806 [DataOverHttps](INFO): SBD MOMSN=16534036
2022-03-04T00:05:08.406Z,1646352308.406 [NAL9602](INFO): Powering up NAL9602
2022-03-04T00:05:15.961Z,1646352315.961 [DataOverHttps](INFO): Sending 404 bytes from file Logs/20211203T000658/Express0017.lzma
2022-03-04T00:05:16.962Z,1646352316.962 [DataOverHttps](INFO): Moved sent file to Logs/20211203T000658/Express0017.lzma.bak
2022-03-04T00:05:16.962Z,1646352316.962 [DataOverHttps](INFO): SBD MOMSN=16534066
2022-03-04T00:05:19.327Z,1646352319.327 [NAL9602](INFO): NAL9602 initialized
2022-03-04T00:05:36.481Z,1646352336.481 [DataOverHttps](INFO): Sending 450 bytes from file Logs/20211203T000658/Express0020.lzma
2022-03-04T00:05:37.482Z,1646352337.482 [DataOverHttps](INFO): Moved sent file to Logs/20211203T000658/Express0020.lzma.bak
2022-03-04T00:05:37.482Z,1646352337.482 [DataOverHttps](INFO): SBD MOMSN=16534092
2022-03-04T00:05:56.209Z,1646352356.209 [DataOverHttps](INFO): Sending 423 bytes from file Logs/20211203T000658/Express0023.lzma
2022-03-04T00:05:57.210Z,1646352357.210 [DataOverHttps](INFO): Moved sent file to Logs/20211203T000658/Express0023.lzma.bak
2022-03-04T00:05:57.210Z,1646352357.210 [DataOverHttps](INFO): SBD MOMSN=16534123
2022-03-04T00:05:59.752Z,1646352359.752 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2022-03-04T00:05:59.752Z,1646352359.752 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2022-03-04T00:05:59.752Z,1646352359.752 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-03-04T00:05:59.803Z,1646352359.803 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-03-04T00:06:00.170Z,1646352360.170 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-03-04T00:06:00.171Z,1646352360.171 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2022-03-04T00:06:15.743Z,1646352375.743 [DataOverHttps](INFO): Sending 1172 bytes from file Logs/20211203T002702/Express0001.lzma
2022-03-04T00:06:16.742Z,1646352376.742 [DataOverHttps](INFO): Moved sent file to Logs/20211203T002702/Express0001.lzma.bak
2022-03-04T00:06:16.742Z,1646352376.742 [DataOverHttps](INFO): SBD MOMSN=16534151
2022-03-04T00:06:37.129Z,1646352397.129 [DataOverHttps](INFO): Sending 431 bytes from file Logs/20211203T002702/Express0005.lzma
2022-03-04T00:06:38.130Z,1646352398.130 [DataOverHttps](INFO): Moved sent file to Logs/20211203T002702/Express0005.lzma.bak
2022-03-04T00:06:38.130Z,1646352398.130 [DataOverHttps](INFO): SBD MOMSN=16534207
2022-03-04T00:06:46.636Z,1646352406.636 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-03-04T00:06:46.636Z,1646352406.636 [CTD_Seabird] No Fault, FailCount= 3
2022-03-04T00:06:46.784Z,1646352406.784 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-03-04T00:06:56.509Z,1646352416.509 [DataOverHttps](INFO): Sending 622 bytes from file Logs/20211203T002702/Express0008.lzma
2022-03-04T00:06:57.518Z,1646352417.518 [DataOverHttps](INFO): Moved sent file to Logs/20211203T002702/Express0008.lzma.bak
2022-03-04T00:06:57.518Z,1646352417.518 [DataOverHttps](INFO): SBD MOMSN=16534242
2022-03-04T00:06:59.836Z,1646352419.836 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-03-04T00:06:59.836Z,1646352419.836 [CTD_Seabird] Communications Fault, FailCount= 1
2022-03-04T00:06:59.836Z,1646352419.836 [CTD_Seabird](ERROR): Communications Fault
2022-03-04T00:06:59.943Z,1646352419.943 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-03-04T00:07:00.240Z,1646352420.240 [CTD_Seabird](INFO): Powering down
2022-03-04T00:07:01.557Z,1646352421.557 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-03-04T00:07:01.557Z,1646352421.557 [CTD_Seabird] No Fault, FailCount= 1
2022-03-04T00:07:01.588Z,1646352421.588 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-03-04T00:07:14.640Z,1646352434.640 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-03-04T00:07:14.640Z,1646352434.640 [CTD_Seabird] Communications Fault, FailCount= 2
2022-03-04T00:07:14.640Z,1646352434.640 [CTD_Seabird](ERROR): Communications Fault
2022-03-04T00:07:14.905Z,1646352434.905 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-03-04T00:07:15.044Z,1646352435.044 [CTD_Seabird](INFO): Powering down
2022-03-04T00:07:16.081Z,1646352436.081 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-03-04T00:07:16.081Z,1646352436.081 [CTD_Seabird] No Fault, FailCount= 2
2022-03-04T00:07:16.197Z,1646352436.197 [DataOverHttps](INFO): Sending 392 bytes from file Logs/20211203T002702/Express0011.lzma
2022-03-04T00:07:16.384Z,1646352436.384 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-03-04T00:07:17.198Z,1646352437.198 [DataOverHttps](INFO): Moved sent file to Logs/20211203T002702/Express0011.lzma.bak
2022-03-04T00:07:17.198Z,1646352437.198 [DataOverHttps](INFO): SBD MOMSN=16534289
2022-03-04T00:07:29.436Z,1646352449.436 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-03-04T00:07:29.436Z,1646352449.436 [CTD_Seabird] Communications Fault, FailCount= 3
2022-03-04T00:07:29.436Z,1646352449.436 [CTD_Seabird](ERROR): Communications Fault
2022-03-04T00:07:29.840Z,1646352449.840 [CTD_Seabird](INFO): Powering down
2022-03-04T00:07:29.842Z,1646352449.842 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-03-04T00:07:29.843Z,1646352449.843 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2022-03-04T00:07:35.774Z,1646352455.774 [DataOverHttps](INFO): Sending 1171 bytes from file Logs/20211203T003730/Express0001.lzma
2022-03-04T00:07:36.774Z,1646352456.774 [DataOverHttps](INFO): Moved sent file to Logs/20211203T003730/Express0001.lzma.bak
2022-03-04T00:07:36.774Z,1646352456.774 [DataOverHttps](INFO): SBD MOMSN=16534311
2022-03-04T00:07:56.674Z,1646352476.674 [DataOverHttps](INFO): Sending 420 bytes from file Logs/20211203T003730/Express0005.lzma
2022-03-04T00:07:57.673Z,1646352477.673 [DataOverHttps](INFO): Moved sent file to Logs/20211203T003730/Express0005.lzma.bak
2022-03-04T00:07:57.674Z,1646352477.674 [DataOverHttps](INFO): SBD MOMSN=16534367
2022-03-04T00:08:00.529Z,1646352480.529 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2022-03-04T00:08:00.529Z,1646352480.529 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2022-03-04T00:08:00.529Z,1646352480.529 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-03-04T00:08:00.584Z,1646352480.584 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-03-04T00:08:00.961Z,1646352480.961 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-03-04T00:08:00.961Z,1646352480.961 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2022-03-04T00:08:16.202Z,1646352496.202 [DataOverHttps](INFO): Sending 625 bytes from file Logs/20211203T003730/Express0008.lzma
2022-03-04T00:08:17.202Z,1646352497.202 [DataOverHttps](INFO): Moved sent file to Logs/20211203T003730/Express0008.lzma.bak
2022-03-04T00:08:17.202Z,1646352497.202 [DataOverHttps](INFO): SBD MOMSN=16534402
2022-03-04T00:08:37.261Z,1646352517.261 [DataOverHttps](INFO): Sending 423 bytes from file Logs/20211203T003730/Express0011.lzma
2022-03-04T00:08:38.262Z,1646352518.262 [DataOverHttps](INFO): Moved sent file to Logs/20211203T003730/Express0011.lzma.bak
2022-03-04T00:08:38.262Z,1646352518.262 [DataOverHttps](INFO): SBD MOMSN=16534449
2022-03-04T00:08:55.485Z,1646352535.485 [CBIT](INFO): Clearing failed state for component DropWeight
2022-03-04T00:08:55.485Z,1646352535.485 [DropWeight] No Fault, FailCount= 1
2022-03-04T00:08:56.865Z,1646352536.865 [DataOverHttps](INFO): Sending 325 bytes from file Logs/20211203T003730/Express0014.lzma
2022-03-04T00:08:57.866Z,1646352537.866 [DataOverHttps](INFO): Moved sent file to Logs/20211203T003730/Express0014.lzma.bak
2022-03-04T00:08:57.866Z,1646352537.866 [DataOverHttps](INFO): SBD MOMSN=16534476
2022-03-04T00:09:16.779Z,1646352556.779 [DataOverHttps](INFO): Sending 1157 bytes from file Logs/20211203T004953/Express0001.lzma
2022-03-04T00:09:17.778Z,1646352557.778 [DataOverHttps](INFO): Moved sent file to Logs/20211203T004953/Express0001.lzma.bak
2022-03-04T00:09:17.778Z,1646352557.778 [DataOverHttps](INFO): SBD MOMSN=16534493
2022-03-04T00:09:37.074Z,1646352577.074 [DataOverHttps](INFO): Sending 433 bytes from file Logs/20211203T004953/Express0005.lzma
2022-03-04T00:09:37.542Z,1646352577.542 [DataOverHttps](INFO): Moved sent file to Logs/20211203T004953/Express0005.lzma.bak
2022-03-04T00:09:37.542Z,1646352577.542 [DataOverHttps](INFO): SBD MOMSN=16534549
2022-03-04T00:09:55.986Z,1646352595.986 [DataOverHttps](INFO): Sending 620 bytes from file Logs/20211203T004953/Express0008.lzma
2022-03-04T00:09:56.986Z,1646352596.986 [DataOverHttps](INFO): Moved sent file to Logs/20211203T004953/Express0008.lzma.bak
2022-03-04T00:09:56.986Z,1646352596.986 [DataOverHttps](INFO): SBD MOMSN=16534584
2022-03-04T00:09:59.848Z,1646352599.848 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-03-04T00:09:59.848Z,1646352599.848 [CTD_Seabird] No Fault, FailCount= 3
2022-03-04T00:09:59.864Z,1646352599.864 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-03-04T00:10:01.732Z,1646352601.732 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2022-03-04T00:10:01.732Z,1646352601.732 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2022-03-04T00:10:01.732Z,1646352601.732 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-03-04T00:10:01.742Z,1646352601.742 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-03-04T00:10:02.125Z,1646352602.125 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-03-04T00:10:02.125Z,1646352602.125 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2022-03-04T00:10:12.916Z,1646352612.916 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-03-04T00:10:12.916Z,1646352612.916 [CTD_Seabird] Communications Fault, FailCount= 1
2022-03-04T00:10:12.916Z,1646352612.916 [CTD_Seabird](ERROR): Communications Fault
2022-03-04T00:10:13.065Z,1646352613.065 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-03-04T00:10:13.320Z,1646352613.320 [CTD_Seabird](INFO): Powering down
2022-03-04T00:10:14.288Z,1646352614.288 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-03-04T00:10:14.288Z,1646352614.288 [CTD_Seabird] No Fault, FailCount= 1
2022-03-04T00:10:14.668Z,1646352614.668 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-03-04T00:10:15.957Z,1646352615.957 [DataOverHttps](INFO): Sending 423 bytes from file Logs/20211203T004953/Express0011.lzma
2022-03-04T00:10:16.973Z,1646352616.973 [DataOverHttps](INFO): Moved sent file to Logs/20211203T004953/Express0011.lzma.bak
2022-03-04T00:10:16.974Z,1646352616.974 [DataOverHttps](INFO): SBD MOMSN=16534631
2022-03-04T00:10:27.732Z,1646352627.732 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-03-04T00:10:27.732Z,1646352627.732 [CTD_Seabird] Communications Fault, FailCount= 2
2022-03-04T00:10:27.732Z,1646352627.732 [CTD_Seabird](ERROR): Communications Fault
2022-03-04T00:10:28.021Z,1646352628.021 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-03-04T00:10:28.136Z,1646352628.136 [CTD_Seabird](INFO): Powering down
2022-03-04T00:10:29.190Z,1646352629.190 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-03-04T00:10:29.190Z,1646352629.190 [CTD_Seabird] No Fault, FailCount= 2
2022-03-04T00:10:29.480Z,1646352629.480 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-03-04T00:10:35.361Z,1646352635.361 [DataOverHttps](INFO): Sending 416 bytes from file Logs/20211203T004953/Express0014.lzma
2022-03-04T00:10:36.362Z,1646352636.362 [DataOverHttps](INFO): Moved sent file to Logs/20211203T004953/Express0014.lzma.bak
2022-03-04T00:10:36.362Z,1646352636.362 [DataOverHttps](INFO): SBD MOMSN=16534658
2022-03-04T00:10:42.536Z,1646352642.536 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-03-04T00:10:42.536Z,1646352642.536 [CTD_Seabird] Communications Fault, FailCount= 3
2022-03-04T00:10:42.536Z,1646352642.536 [CTD_Seabird](ERROR): Communications Fault
2022-03-04T00:10:42.565Z,1646352642.565 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-03-04T00:10:42.566Z,1646352642.566 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2022-03-04T00:10:42.940Z,1646352642.940 [CTD_Seabird](INFO): Powering down
2022-03-04T00:10:55.713Z,1646352655.713 [DataOverHttps](INFO): Sending 423 bytes from file Logs/20211203T004953/Express0017.lzma
2022-03-04T00:10:56.714Z,1646352656.714 [DataOverHttps](INFO): Moved sent file to Logs/20211203T004953/Express0017.lzma.bak
2022-03-04T00:10:56.714Z,1646352656.714 [DataOverHttps](INFO): SBD MOMSN=16534688
2022-03-04T00:11:16.181Z,1646352676.181 [DataOverHttps](INFO): Sending 456 bytes from file Logs/20211203T004953/Express0020.lzma
2022-03-04T00:11:17.182Z,1646352677.182 [DataOverHttps](INFO): Moved sent file to Logs/20211203T004953/Express0020.lzma.bak
2022-03-04T00:11:17.182Z,1646352677.182 [DataOverHttps](INFO): SBD MOMSN=16534718
2022-03-04T00:11:37.105Z,1646352697.105 [DataOverHttps](INFO): Sending 439 bytes from file Logs/20211203T004953/Express0023.lzma
2022-03-04T00:11:38.114Z,1646352698.114 [DataOverHttps](INFO): Moved sent file to Logs/20211203T004953/Express0023.lzma.bak
2022-03-04T00:11:38.114Z,1646352698.114 [DataOverHttps](INFO): SBD MOMSN=16534750
2022-03-04T00:11:56.517Z,1646352716.517 [DataOverHttps](INFO): Sending 397 bytes from file Logs/20211203T004953/Express0026.lzma
2022-03-04T00:11:57.518Z,1646352717.518 [DataOverHttps](INFO): Moved sent file to Logs/20211203T004953/Express0026.lzma.bak
2022-03-04T00:11:57.519Z,1646352717.519 [DataOverHttps](INFO): SBD MOMSN=16534781
2022-03-04T00:12:02.965Z,1646352722.965 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2022-03-04T00:12:02.965Z,1646352722.965 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2022-03-04T00:12:02.965Z,1646352722.965 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-03-04T00:12:02.976Z,1646352722.976 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-03-04T00:12:03.333Z,1646352723.333 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-03-04T00:12:03.333Z,1646352723.333 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2022-03-04T00:12:16.777Z,1646352736.777 [DataOverHttps](INFO): Sending 393 bytes from file Logs/20211203T004953/Express0029.lzma
2022-03-04T00:12:17.778Z,1646352737.778 [DataOverHttps](INFO): Moved sent file to Logs/20211203T004953/Express0029.lzma.bak
2022-03-04T00:12:17.778Z,1646352737.778 [DataOverHttps](INFO): SBD MOMSN=16534806
2022-03-04T00:12:38.646Z,1646352758.646 [DataOverHttps](INFO): Sending 438 bytes from file Logs/20211203T004953/Express0032.lzma
2022-03-04T00:12:39.646Z,1646352759.646 [DataOverHttps](INFO): Moved sent file to Logs/20211203T004953/Express0032.lzma.bak
2022-03-04T00:12:39.646Z,1646352759.646 [DataOverHttps](INFO): SBD MOMSN=16534833
2022-03-04T00:13:11.198Z,1646352791.198 [DataOverHttps](INFO): Sending 391 bytes from file Logs/20211203T004953/Express0035.lzma
2022-03-04T00:13:12.198Z,1646352792.198 [DataOverHttps](INFO): Moved sent file to Logs/20211203T004953/Express0035.lzma.bak
2022-03-04T00:13:12.198Z,1646352792.198 [DataOverHttps](INFO): SBD MOMSN=16534864
2022-03-04T00:13:12.915Z,1646352792.915 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-03-04T00:13:12.915Z,1646352792.915 [CTD_Seabird] No Fault, FailCount= 3
2022-03-04T00:13:13.072Z,1646352793.072 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-03-04T00:13:26.124Z,1646352806.124 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-03-04T00:13:26.124Z,1646352806.124 [CTD_Seabird] Communications Fault, FailCount= 1
2022-03-04T00:13:26.124Z,1646352806.124 [CTD_Seabird](ERROR): Communications Fault
2022-03-04T00:13:26.313Z,1646352806.313 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-03-04T00:13:26.528Z,1646352806.528 [CTD_Seabird](INFO): Powering down
2022-03-04T00:13:28.171Z,1646352808.171 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-03-04T00:13:28.171Z,1646352808.171 [CTD_Seabird] No Fault, FailCount= 1
2022-03-04T00:13:28.284Z,1646352808.284 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-03-04T00:13:33.225Z,1646352813.225 [DataOverHttps](INFO): Sending 462 bytes from file Logs/20211203T004953/Express0038.lzma
2022-03-04T00:13:34.226Z,1646352814.226 [DataOverHttps](INFO): Moved sent file to Logs/20211203T004953/Express0038.lzma.bak
2022-03-04T00:13:34.226Z,1646352814.226 [DataOverHttps](INFO): SBD MOMSN=16534891
2022-03-04T00:13:41.336Z,1646352821.336 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-03-04T00:13:41.336Z,1646352821.336 [CTD_Seabird] Communications Fault, FailCount= 2
2022-03-04T00:13:41.336Z,1646352821.336 [CTD_Seabird](ERROR): Communications Fault
2022-03-04T00:13:41.645Z,1646352821.645 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-03-04T00:13:41.740Z,1646352821.740 [CTD_Seabird](INFO): Powering down
2022-03-04T00:13:42.849Z,1646352822.849 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2022-03-04T00:13:42.849Z,1646352822.849 [CTD_Seabird] No Fault, FailCount= 2
2022-03-04T00:13:43.080Z,1646352823.080 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-03-04T00:13:52.981Z,1646352832.981 [DataOverHttps](INFO): Sending 498 bytes from file Logs/20211203T004953/Express0041.lzma
2022-03-04T00:13:53.982Z,1646352833.982 [DataOverHttps](INFO): Moved sent file to Logs/20211203T004953/Express0041.lzma.bak
2022-03-04T00:13:53.982Z,1646352833.982 [DataOverHttps](INFO): SBD MOMSN=16534925
2022-03-04T00:13:56.132Z,1646352836.132 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2022-03-04T00:13:56.132Z,1646352836.132 [CTD_Seabird] Communications Fault, FailCount= 3
2022-03-04T00:13:56.132Z,1646352836.132 [CTD_Seabird](ERROR): Communications Fault
2022-03-04T00:13:56.165Z,1646352836.165 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2022-03-04T00:13:56.166Z,1646352836.166 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2022-03-04T00:13:56.536Z,1646352836.536 [CTD_Seabird](INFO): Powering down
2022-03-04T00:14:03.829Z,1646352843.829 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2022-03-04T00:14:03.829Z,1646352843.829 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2022-03-04T00:14:03.829Z,1646352843.829 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-03-04T00:14:03.839Z,1646352843.839 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-03-04T00:14:04.279Z,1646352844.279 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-03-04T00:14:04.279Z,1646352844.279 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2022-03-04T00:14:12.037Z,1646352852.037 [DataOverHttps](INFO): Sending 411 bytes from file Logs/20211203T004953/Express0044.lzma
2022-03-04T00:14:13.038Z,1646352853.038 [DataOverHttps](INFO): Moved sent file to Logs/20211203T004953/Express0044.lzma.bak
2022-03-04T00:14:13.038Z,1646352853.038 [DataOverHttps](INFO): SBD MOMSN=16534961
2022-03-04T00:14:18.598Z,1646352858.598 [CommandExec](IMPORTANT): got command run ./Missions/Demo/senddata_direct_multiple_test.xml
2022-03-04T00:14:18.599Z,1646352858.599 [MissionManager](INFO): Loading Mission from file: ./Missions/Demo/senddata_direct_multiple_test.xml
2022-03-04T00:14:18.631Z,1646352858.631 [MissionManager](INFO): DefineArg senddata_direct_multiple_test.modemId1 = 1.000000 enum
2022-03-04T00:14:18.635Z,1646352858.635 [MissionManager](INFO): DefineArg senddata_direct_multiple_test.modemId2 = 4.000000 enum
2022-03-04T00:14:18.638Z,1646352858.638 [MissionManager](INFO): DefineOutput senddata_direct_multiple_test.ModemID = 1.000000 enum
2022-03-04T00:14:18.680Z,1646352858.680 [MissionManager](INFO): DefineArg senddata_direct_multiple_test.ApproachDepth = 15.000000 m
2022-03-04T00:14:18.724Z,1646352858.724 [senddata_direct_multiple_test:H.Wait](DEBUG): Construct Wait.
2022-03-04T00:14:18.728Z,1646352858.728 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
    xmlns:Sensor="Tethys/Sensor"
    xmlns:Units="Tethys/Units"
    xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
    xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                        Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd
                        Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd"
    Id="senddata_direct_multiple_test">

    <DefineArg Name="modemId1"><Description>
        Modem ID1. Initialized to 1.
    </Description><Units:enum/><Value>1</Value></DefineArg>

    <DefineArg Name="modemId2"><Description>
        Modem ID2. Initialized to 4.
    </Description><Units:enum/><Value>4</Value></DefineArg>

    <DefineOutput Name="ModemID"><Description>
        Modem ID. Set to modemId1 or modemId2. Initialized to 1.
    </Description><Units:enum/><Value>1</Value></DefineOutput>
    
    <DefineArg Name="ApproachDepth"><Description>
        Depth for initial approach to station.
    </Description><Units:meter/><Value>15</Value></DefineArg>    
    

  <!--  <SendData Service="Direct" Destination="modem:1:_.pressure">
        <Sensor:Onboard.Pressure/><Units:atmosphere/>
    </SendData>

    <SendData Service="Direct" Destination="modem:1:_.temperature">
        <Sensor:Onboard.Temperature/><Units:celsius/>
    </SendData>

    <SendData Service="Direct" Destination="modem:1:_.humidity">
        <Sensor:Onboard.Humidity/><Units:percent/>
    </SendData>
-->


   <Aggregate Id="DoSendDirect">

        <When>
            <Called/>
        </When>

        <Syslog Severity="Important">ModemID:<Arg Name="ModemID"/><Units:enum/></Syslog>
        <Sensor:SendDirect>
            <Sequence/>
            <Setting><Sensor:SendDirect.destType/><String>modem</String></Setting>
            <Setting><Sensor:SendDirect.destId/><Arg Name="ModemID"/></Setting>
            <Setting><Sensor:SendDirect.destName/><String>_.depth</String></Setting>
            <Setting><Sensor:SendDirect.value/><Universal:depth/></Setting>
            <Setting><Sensor:SendDirect.unit/><String>meter</String></Setting>
        </Sensor:SendDirect>        
        
   </Aggregate>

        
            <Assign><Sequence/><Arg Name="ModemID"/><Arg Name="modemId1"/></Assign>
            <Syslog Severity="Important">Set ModemID to:<Arg Name="modemId1"/><Units:enum/>,<Arg Name="ModemID"/><Units:enum/>.</Syslog>

            <Call RefId="DoSendDirect"/>

            <Assign><Sequence/><Arg Name="ModemID"/><Arg Name="modemId2"/></Assign>
            <Syslog Severity="Important">Set ModemID to:<Arg Name="modemId2"/><Units:enum/>,<Arg Name="ModemID"/><Units:enum/>.</Syslog>

            <Call RefId="DoSendDirect"/>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:second/><Value>500</Value></Setting>
        </Guidance:Wait>
        
</Mission>




2022-03-04T00:14:18.731Z,1646352858.731 [CommandExec](IMPORTANT): Running ./Missions/Demo/senddata_direct_multiple_test.xml
2022-03-04T00:14:18.800Z,1646352858.800 [Default] Stopped
2022-03-04T00:14:18.800Z,1646352858.800 [Default](DEBUG): Aggregate::uninitialize Default
2022-03-04T00:14:18.800Z,1646352858.800 [Default:B.GoToSurface] Stopped
2022-03-04T00:14:18.800Z,1646352858.800 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-03-04T00:14:18.800Z,1646352858.800 [Default:CheckIn] Stopped
2022-03-04T00:14:18.800Z,1646352858.800 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-04T00:14:18.800Z,1646352858.800 [Default:CheckIn:Read_Iridium] Stopped
2022-03-04T00:14:18.800Z,1646352858.800 [MissionManager](IMPORTANT): Started mission senddata_direct_multiple_test
2022-03-04T00:14:18.801Z,1646352858.801 [senddata_direct_multiple_test] Running Loop=1
2022-03-04T00:14:18.801Z,1646352858.801 [senddata_direct_multiple_test](DEBUG): Aggregate::initialize senddata_direct_multiple_test
2022-03-04T00:14:18.801Z,1646352858.801 [senddata_direct_multiple_test:B] Running Loop=1
2022-03-04T00:14:18.801Z,1646352858.801 [senddata_direct_multiple_test:B] Stopped
2022-03-04T00:14:18.801Z,1646352858.801 [senddata_direct_multiple_test:C] Running Loop=1
2022-03-04T00:14:19.174Z,1646352859.174 [senddata_direct_multiple_test:C](IMPORTANT): Set ModemID to: 1.000000 enum , 1.000000 enum .
2022-03-04T00:14:19.174Z,1646352859.174 [senddata_direct_multiple_test:C] Stopped
2022-03-04T00:14:19.174Z,1646352859.174 [senddata_direct_multiple_test:D] Running Loop=1
2022-03-04T00:14:19.175Z,1646352859.175 [senddata_direct_multiple_test:D](DEBUG): Aggregate::initialize senddata_direct_multiple_test:D
2022-03-04T00:14:19.573Z,1646352859.573 [senddata_direct_multiple_test:DoSendDirect] Running Loop=1
2022-03-04T00:14:19.573Z,1646352859.573 [senddata_direct_multiple_test:DoSendDirect](DEBUG): Aggregate::initialize senddata_direct_multiple_test:DoSendDirect
2022-03-04T00:14:19.573Z,1646352859.573 [senddata_direct_multiple_test:DoSendDirect:A] Running Loop=1
2022-03-04T00:14:19.574Z,1646352859.574 [senddata_direct_multiple_test:DoSendDirect:A](IMPORTANT): ModemID: 1.000000 enum 
2022-03-04T00:14:19.574Z,1646352859.574 [senddata_direct_multiple_test:DoSendDirect:A] Stopped
2022-03-04T00:14:19.574Z,1646352859.574 [senddata_direct_multiple_test:DoSendDirect:B.SendDirect] Running Loop=1
2022-03-04T00:14:20.005Z,1646352860.005 [senddata_direct_multiple_test:DoSendDirect:B.SendDirect] Stopped
2022-03-04T00:14:20.005Z,1646352860.005 [senddata_direct_multiple_test:DoSendDirect](INFO): Completed senddata_direct_multiple_test:DoSendDirect
2022-03-04T00:14:20.005Z,1646352860.005 [senddata_direct_multiple_test:DoSendDirect] Stopped
2022-03-04T00:14:20.005Z,1646352860.005 [senddata_direct_multiple_test:DoSendDirect](DEBUG): Aggregate::uninitialize senddata_direct_multiple_test:DoSendDirect
2022-03-04T00:14:20.397Z,1646352860.397 [senddata_direct_multiple_test:D](INFO): Completed senddata_direct_multiple_test:D
2022-03-04T00:14:20.397Z,1646352860.397 [senddata_direct_multiple_test:D] Stopped
2022-03-04T00:14:20.397Z,1646352860.397 [senddata_direct_multiple_test:D](DEBUG): Aggregate::uninitialize senddata_direct_multiple_test:D
2022-03-04T00:14:20.397Z,1646352860.397 [senddata_direct_multiple_test:E] Running Loop=1
2022-03-04T00:14:20.810Z,1646352860.810 [senddata_direct_multiple_test:E] Stopped
2022-03-04T00:14:20.810Z,1646352860.810 [senddata_direct_multiple_test:F] Running Loop=1
2022-03-04T00:14:21.192Z,1646352861.192 [senddata_direct_multiple_test:F](IMPORTANT): Set ModemID to: 4.000000 enum , 4.000000 enum .
2022-03-04T00:14:21.193Z,1646352861.193 [senddata_direct_multiple_test:F] Stopped
2022-03-04T00:14:21.193Z,1646352861.193 [senddata_direct_multiple_test:G] Running Loop=1
2022-03-04T00:14:21.193Z,1646352861.193 [senddata_direct_multiple_test:G](DEBUG): Aggregate::initialize senddata_direct_multiple_test:G
2022-03-04T00:14:21.603Z,1646352861.603 [senddata_direct_multiple_test:DoSendDirect] Running Loop=1
2022-03-04T00:14:21.603Z,1646352861.603 [senddata_direct_multiple_test:DoSendDirect](DEBUG): Aggregate::initialize senddata_direct_multiple_test:DoSendDirect
2022-03-04T00:14:21.608Z,1646352861.608 [senddata_direct_multiple_test:DoSendDirect:A] Running Loop=1
2022-03-04T00:14:21.608Z,1646352861.608 [senddata_direct_multiple_test:DoSendDirect:A](IMPORTANT): ModemID: 4.000000 enum 
2022-03-04T00:14:21.608Z,1646352861.608 [senddata_direct_multiple_test:DoSendDirect:A] Stopped
2022-03-04T00:14:21.609Z,1646352861.609 [senddata_direct_multiple_test:DoSendDirect:B.SendDirect] Running Loop=1
2022-03-04T00:14:22.105Z,1646352862.105 [senddata_direct_multiple_test:DoSendDirect:B.SendDirect] Stopped
2022-03-04T00:14:22.105Z,1646352862.105 [senddata_direct_multiple_test:DoSendDirect](INFO): Completed senddata_direct_multiple_test:DoSendDirect
2022-03-04T00:14:22.105Z,1646352862.105 [senddata_direct_multiple_test:DoSendDirect] Stopped
2022-03-04T00:14:22.105Z,1646352862.105 [senddata_direct_multiple_test:DoSendDirect](DEBUG): Aggregate::uninitialize senddata_direct_multiple_test:DoSendDirect
2022-03-04T00:14:22.447Z,1646352862.447 [senddata_direct_multiple_test:G](INFO): Completed senddata_direct_multiple_test:G
2022-03-04T00:14:22.447Z,1646352862.447 [senddata_direct_multiple_test:G] Stopped
2022-03-04T00:14:22.447Z,1646352862.447 [senddata_direct_multiple_test:G](DEBUG): Aggregate::uninitialize senddata_direct_multiple_test:G
2022-03-04T00:14:22.452Z,1646352862.452 [senddata_direct_multiple_test:H.Wait] Running Loop=1
2022-03-04T00:14:22.452Z,1646352862.452 [senddata_direct_multiple_test:H.Wait](DEBUG): Initialize Wait Component.
2022-03-04T00:14:27.194Z,1646352867.194 [CommandExec](IMPORTANT): got command maintain sensor Depth_Keller.depth 1.5 meter
2022-03-04T00:14:27.198Z,1646352867.198 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2022-03-04T00:14:27.277Z,1646352867.277 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,ESPComponent,PAR_Licor,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,Maintain_Depth_Keller.depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,MassServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2022-03-04T00:14:27.865Z,1646352867.865 [DAT](INFO): modem://4: set _.depth 0.361153 meter
2022-03-04T00:14:27.866Z,1646352867.866 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2022-03-04T00:14:28.117Z,1646352868.117 [DAT](INFO): setting remote address to 4
2022-03-04T00:14:28.370Z,1646352868.370 [DAT](INFO): set remote address to 4
2022-03-04T00:14:28.370Z,1646352868.370 [DAT](INFO): entering online mode
2022-03-04T00:14:28.622Z,1646352868.622 [DAT](INFO): commRate: 1200
2022-03-04T00:14:28.622Z,1646352868.622 [DAT](INFO): online mode acknowledged
2022-03-04T00:14:28.622Z,1646352868.622 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2022-03-04T00:14:31.897Z,1646352871.897 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2022-03-04T00:14:32.029Z,1646352872.029 [DataOverHttps](INFO): Sending 400 bytes from file Logs/20211203T004953/Express0047.lzma
2022-03-04T00:14:33.030Z,1646352873.030 [DataOverHttps](INFO): Moved sent file to Logs/20211203T004953/Express0047.lzma.bak
2022-03-04T00:14:33.030Z,1646352873.030 [DataOverHttps](INFO): SBD MOMSN=16534991
2022-03-04T00:14:41.988Z,1646352881.988 [DAT](FAULT): Ack receipt timeout failure.
2022-03-04T00:14:41.988Z,1646352881.988 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER