2023-05-04T20:28:41.647Z,1683232121.647 [Supervisor](DEBUG): Initializing supervisor.
2023-05-04T20:28:41.653Z,1683232121.653 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-05-04T20:28:41.653Z,1683232121.653 [SyncHandler](INFO): Protected caller Thread ID is 3633
2023-05-04T20:28:41.654Z,1683232121.654 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-05-04T20:28:41.655Z,1683232121.655 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-05-04T20:28:41.655Z,1683232121.655 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3634
2023-05-04T20:28:41.660Z,1683232121.660 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-05-04T20:28:41.678Z,1683232121.678 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-05-04T20:28:41.678Z,1683232121.678 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-05-04T20:28:41.679Z,1683232121.679 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3635
2023-05-04T20:28:41.683Z,1683232121.683 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-05-04T20:28:41.684Z,1683232121.684 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-05-04T20:28:41.684Z,1683232121.684 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3636
2023-05-04T20:28:41.686Z,1683232121.686 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-05-04T20:28:41.687Z,1683232121.687 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-05-04T20:28:41.688Z,1683232121.688 [logger ThreadHandler](INFO): Protected caller Thread ID is 3637
2023-05-04T20:28:41.691Z,1683232121.691 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-05-04T20:28:41.692Z,1683232121.692 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-05-04T20:28:41.694Z,1683232121.694 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-05-04T20:28:42.203Z,1683232122.203 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-05-04T20:28:42.203Z,1683232122.203 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-05-04T20:28:42.407Z,1683232122.407 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-05-04T20:28:42.408Z,1683232122.408 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-05-04T20:28:42.487Z,1683232122.487 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-05-04T20:28:42.697Z,1683232122.697 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-05-04T20:28:42.698Z,1683232122.698 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-05-04T20:28:42.774Z,1683232122.774 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-05-04T20:28:42.867Z,1683232122.867 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-05-04T20:28:42.868Z,1683232122.868 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-05-04T20:28:43.190Z,1683232123.190 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-05-04T20:28:43.191Z,1683232123.191 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-05-04T20:28:43.318Z,1683232123.318 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-05-04T20:28:43.318Z,1683232123.318 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-05-04T20:28:43.798Z,1683232123.798 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-05-04T20:28:43.798Z,1683232123.798 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-05-04T20:28:43.974Z,1683232123.974 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-05-04T20:28:43.974Z,1683232123.974 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-05-04T20:28:44.354Z,1683232124.354 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-05-04T20:28:44.354Z,1683232124.354 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-05-04T20:28:44.622Z,1683232124.622 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-05-04T20:28:44.622Z,1683232124.622 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-05-04T20:28:44.729Z,1683232124.729 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-05-04T20:28:44.730Z,1683232124.730 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-05-04T20:28:45.486Z,1683232125.486 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-05-04T20:28:45.486Z,1683232125.486 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-05-04T20:28:45.804Z,1683232125.804 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-05-04T20:28:45.804Z,1683232125.804 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-05-04T20:28:46.016Z,1683232126.016 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-05-04T20:28:46.018Z,1683232126.018 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2023-05-04T20:28:46.019Z,1683232126.019 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2023-05-04T20:28:46.278Z,1683232126.278 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2023-05-04T20:28:46.445Z,1683232126.445 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2023-05-04T20:28:46.686Z,1683232126.686 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2023-05-04T20:28:46.771Z,1683232126.771 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2023-05-04T20:28:47.056Z,1683232127.056 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-05-04T20:28:47.057Z,1683232127.057 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2023-05-04T20:28:47.368Z,1683232127.368 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2023-05-04T20:28:47.653Z,1683232127.653 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2023-05-04T20:28:47.766Z,1683232127.766 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2023-05-04T20:28:47.909Z,1683232127.909 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2023-05-04T20:28:48.006Z,1683232128.006 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2023-05-04T20:28:48.098Z,1683232128.098 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2023-05-04T20:28:48.098Z,1683232128.098 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-05-04T20:28:48.110Z,1683232128.110 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-05-04T20:28:48.121Z,1683232128.121 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-05-04T20:28:48.121Z,1683232128.121 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-05-04T20:28:48.219Z,1683232128.219 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-05-04T20:28:48.220Z,1683232128.220 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-05-04T20:28:48.269Z,1683232128.269 [VerticalControl](DEBUG): Construct VerticalControl.
2023-05-04T20:28:48.328Z,1683232128.328 [VerticalControl] Loaded
2023-05-04T20:28:48.328Z,1683232128.328 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-05-04T20:28:48.331Z,1683232128.331 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-05-04T20:28:48.371Z,1683232128.371 [HorizontalControl] Loaded
2023-05-04T20:28:48.371Z,1683232128.371 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-05-04T20:28:48.373Z,1683232128.373 [SpeedControl](DEBUG): Construct SpeedControl.
2023-05-04T20:28:48.376Z,1683232128.376 [SpeedControl] Loaded
2023-05-04T20:28:48.377Z,1683232128.377 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-05-04T20:28:48.379Z,1683232128.379 [LoopControl](DEBUG): Construct LoopControl.
2023-05-04T20:28:48.380Z,1683232128.380 [LoopControl] Loaded
2023-05-04T20:28:48.380Z,1683232128.380 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-05-04T20:28:48.380Z,1683232128.380 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-05-04T20:28:48.381Z,1683232128.381 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-05-04T20:28:48.535Z,1683232128.535 [BuoyancyServo] Loaded
2023-05-04T20:28:48.536Z,1683232128.536 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-05-04T20:28:48.555Z,1683232128.555 [ElevatorServo] Loaded
2023-05-04T20:28:48.556Z,1683232128.556 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-05-04T20:28:48.574Z,1683232128.574 [MassServo] Loaded
2023-05-04T20:28:48.575Z,1683232128.575 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-05-04T20:28:48.593Z,1683232128.593 [RudderServo] Loaded
2023-05-04T20:28:48.593Z,1683232128.593 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-05-04T20:28:48.607Z,1683232128.607 [ThrusterHE] Loaded
2023-05-04T20:28:48.608Z,1683232128.608 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-05-04T20:28:48.608Z,1683232128.608 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-05-04T20:28:48.608Z,1683232128.608 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-05-04T20:28:48.658Z,1683232128.658 [DepthRateCalculator] Loaded
2023-05-04T20:28:48.659Z,1683232128.659 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-05-04T20:28:48.663Z,1683232128.663 [PitchRateCalculator] Loaded
2023-05-04T20:28:48.664Z,1683232128.664 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-05-04T20:28:48.673Z,1683232128.673 [SpeedCalculator] Loaded
2023-05-04T20:28:48.673Z,1683232128.673 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-05-04T20:28:48.678Z,1683232128.678 [YawRateCalculator] Loaded
2023-05-04T20:28:48.678Z,1683232128.678 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-05-04T20:28:48.697Z,1683232128.697 [ElevatorOffsetCalculator] Loaded
2023-05-04T20:28:48.697Z,1683232128.697 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-05-04T20:28:48.698Z,1683232128.698 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-05-04T20:28:48.698Z,1683232128.698 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-05-04T20:28:48.809Z,1683232128.809 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-05-04T20:28:48.810Z,1683232128.810 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-05-04T20:28:48.823Z,1683232128.823 [NavChart] Loaded
2023-05-04T20:28:48.823Z,1683232128.823 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-05-04T20:28:48.829Z,1683232128.829 [UniversalFixResidualReporter] Loaded
2023-05-04T20:28:48.829Z,1683232128.829 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-05-04T20:28:48.829Z,1683232128.829 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-05-04T20:28:48.830Z,1683232128.830 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-05-04T20:28:49.257Z,1683232129.257 [AHRS_M2] Loaded
2023-05-04T20:28:49.257Z,1683232129.257 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-05-04T20:28:49.292Z,1683232129.292 [BackseatComponent] Loaded
2023-05-04T20:28:49.292Z,1683232129.292 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-05-04T20:28:49.293Z,1683232129.293 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408E74E0
2023-05-04T20:28:49.294Z,1683232129.294 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 3719
2023-05-04T20:28:49.297Z,1683232129.297 [LcmUniversalReporter] Loaded
2023-05-04T20:28:49.297Z,1683232129.297 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-05-04T20:28:50.119Z,1683232130.119 [BPC1] Loaded
2023-05-04T20:28:50.119Z,1683232130.119 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-05-04T20:28:50.191Z,1683232130.191 [DataOverHttps] Loaded
2023-05-04T20:28:50.191Z,1683232130.191 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-05-04T20:28:50.192Z,1683232130.192 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409174E0
2023-05-04T20:28:50.192Z,1683232130.192 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3720
2023-05-04T20:28:50.213Z,1683232130.213 [Depth_Keller] Loaded
2023-05-04T20:28:50.213Z,1683232130.213 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-05-04T20:28:50.274Z,1683232130.274 [NAL9602] Loaded
2023-05-04T20:28:50.275Z,1683232130.275 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-05-04T20:28:50.304Z,1683232130.304 [Onboard] Loaded
2023-05-04T20:28:50.305Z,1683232130.305 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-05-04T20:28:50.306Z,1683232130.306 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409474E0
2023-05-04T20:28:50.306Z,1683232130.306 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3721
2023-05-04T20:28:50.318Z,1683232130.318 [Power24vConverter] Loaded
2023-05-04T20:28:50.318Z,1683232130.318 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-05-04T20:28:50.331Z,1683232130.331 [Radio_Surface] Loaded
2023-05-04T20:28:50.332Z,1683232130.332 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-05-04T20:28:50.333Z,1683232130.333 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409774E0
2023-05-04T20:28:50.333Z,1683232130.333 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3722
2023-05-04T20:28:50.419Z,1683232130.419 [DATMMP] Loaded
2023-05-04T20:28:50.419Z,1683232130.419 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2023-05-04T20:28:50.421Z,1683232130.421 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 409A84E0
2023-05-04T20:28:50.421Z,1683232130.421 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 3723
2023-05-04T20:28:50.421Z,1683232130.421 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-05-04T20:28:50.422Z,1683232130.422 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-05-04T20:28:50.495Z,1683232130.495 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-05-04T20:28:50.495Z,1683232130.495 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-05-04T20:28:50.643Z,1683232130.643 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-05-04T20:28:50.644Z,1683232130.644 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-05-04T20:28:50.670Z,1683232130.670 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-05-04T20:28:50.671Z,1683232130.671 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-05-04T20:28:50.838Z,1683232130.838 [CTD_Seabird] Loaded
2023-05-04T20:28:50.838Z,1683232130.838 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-05-04T20:28:50.839Z,1683232130.839 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ADD4E0
2023-05-04T20:28:50.840Z,1683232130.840 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3724
2023-05-04T20:28:50.872Z,1683232130.872 [ESPComponent] Loaded
2023-05-04T20:28:50.872Z,1683232130.872 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-05-04T20:28:50.892Z,1683232130.892 [PAR_Licor] Loaded
2023-05-04T20:28:50.892Z,1683232130.892 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-05-04T20:28:50.923Z,1683232130.923 [WetLabsBB2FL] Loaded
2023-05-04T20:28:50.923Z,1683232130.923 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-05-04T20:28:50.924Z,1683232130.924 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B0D4E0
2023-05-04T20:28:50.925Z,1683232130.925 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3725
2023-05-04T20:28:50.925Z,1683232130.925 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-05-04T20:28:50.926Z,1683232130.926 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-05-04T20:28:51.254Z,1683232131.254 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-05-04T20:28:51.254Z,1683232131.254 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-05-04T20:28:51.392Z,1683232131.392 [SBIT](DEBUG): Construct Startup Built In Test.
2023-05-04T20:28:51.402Z,1683232131.402 [SBIT] Loaded
2023-05-04T20:28:51.402Z,1683232131.402 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-05-04T20:28:51.405Z,1683232131.405 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-05-04T20:28:51.418Z,1683232131.418 [IBIT] Loaded
2023-05-04T20:28:51.418Z,1683232131.418 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-05-04T20:28:51.424Z,1683232131.424 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-05-04T20:28:51.527Z,1683232131.527 [CBIT] Loaded
2023-05-04T20:28:51.527Z,1683232131.527 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-05-04T20:28:51.528Z,1683232131.528 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-05-04T20:28:51.556Z,1683232131.556 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-05-04T20:28:51.559Z,1683232131.559 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-05-04T20:28:51.605Z,1683232131.605 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-05-04T20:28:51.606Z,1683232131.606 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BF04E0
2023-05-04T20:28:51.607Z,1683232131.607 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3726
2023-05-04T20:28:51.611Z,1683232131.611 [Supervisor](INFO): Main Thread ID is 3379
2023-05-04T20:28:51.612Z,1683232131.612 [Supervisor](DEBUG): Running supervisor.
2023-05-04T20:28:51.636Z,1683232131.636 [CommandExec ThreadHandler](INFO): Handler Thread ID is 3727
2023-05-04T20:28:51.637Z,1683232131.637 [CommandExec](INFO): Initializing the command executive.
2023-05-04T20:28:51.639Z,1683232131.639 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3728
2023-05-04T20:28:51.639Z,1683232131.639 [controlThread ThreadHandler](INFO): Handler Thread ID is 3729
2023-05-04T20:28:51.640Z,1683232131.640 [logger ThreadHandler](INFO): Handler Thread ID is 3730
2023-05-04T20:28:51.642Z,1683232131.642 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 3731
2023-05-04T20:28:51.642Z,1683232131.642 [controlThread](DEBUG): Initializing ControlThread
2023-05-04T20:28:51.643Z,1683232131.643 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-05-04T20:28:51.645Z,1683232131.645 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-05-04T20:28:51.645Z,1683232131.645 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-05-04T20:28:51.646Z,1683232131.646 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-05-04T20:28:51.647Z,1683232131.647 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-05-04T20:28:51.647Z,1683232131.647 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-05-04T20:28:51.648Z,1683232131.648 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-05-04T20:28:51.648Z,1683232131.648 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-05-04T20:28:51.648Z,1683232131.648 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-05-04T20:28:51.649Z,1683232131.649 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-05-04T20:28:51.650Z,1683232131.650 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-05-04T20:28:51.656Z,1683232131.656 [SBIT](INFO): Initialize SBIT Component.
2023-05-04T20:28:51.657Z,1683232131.657 [SBIT](IMPORTANT): git: 2023-01-24-38-g3a865bb8c
2023-05-04T20:28:51.657Z,1683232131.657 [SBIT](INFO): git hash: 3a865bb8cac9b788bcd3aa40e502ce5ce0bbf34f
2023-05-04T20:28:51.657Z,1683232131.657 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-05-04T20:28:51.658Z,1683232131.658 [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
2023-05-04T20:28:51.660Z,1683232131.660 [SBIT](INFO): Beginning SBIT in 81.000000 seconds.
2023-05-04T20:28:51.685Z,1683232131.685 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3732
2023-05-04T20:28:51.686Z,1683232131.686 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-05-04T20:28:51.728Z,1683232131.728 [Onboard ThreadHandler](INFO): Handler Thread ID is 3733
2023-05-04T20:28:51.749Z,1683232131.749 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3737
2023-05-04T20:28:51.753Z,1683232131.753 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3738
2023-05-04T20:28:51.757Z,1683232131.757 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-05-04T20:28:51.757Z,1683232131.757 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-05-04T20:28:51.757Z,1683232131.757 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-05-04T20:28:51.757Z,1683232131.757 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-05-04T20:28:51.757Z,1683232131.757 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-05-04T20:28:51.757Z,1683232131.757 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-05-04T20:28:51.758Z,1683232131.758 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-05-04T20:28:51.758Z,1683232131.758 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-05-04T20:28:51.769Z,1683232131.769 [IBIT](INFO): Initialize IBIT Component.
2023-05-04T20:28:51.770Z,1683232131.770 [CBIT](DEBUG): Initialize CBIT Component.
2023-05-04T20:28:51.780Z,1683232131.780 [DATMMP ThreadHandler](INFO): Handler Thread ID is 3735
2023-05-04T20:28:51.781Z,1683232131.781 [DATMMP](INFO): Start
2023-05-04T20:28:51.781Z,1683232131.781 [DATMMP](INFO): Powering up
2023-05-04T20:28:51.781Z,1683232131.781 [DATMMP](DEBUG): Initializing DATMMP.
2023-05-04T20:28:51.784Z,1683232131.784 [CBIT](DEBUG): Initialized mux pins.
2023-05-04T20:28:51.784Z,1683232131.784 [CBIT](DEBUG): Initializing the watchdog timer.
2023-05-04T20:28:51.785Z,1683232131.785 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3736
2023-05-04T20:28:51.786Z,1683232131.786 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-05-04T20:28:51.806Z,1683232131.806 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3734
2023-05-04T20:28:51.810Z,1683232131.810 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-05-04T20:28:51.810Z,1683232131.810 [CBIT](DEBUG): Initializing heartbeat.
2023-05-04T20:28:51.824Z,1683232131.824 [WetLabsBB2FL](INFO): Powering up
2023-05-04T20:28:51.880Z,1683232131.880 [CBIT](DEBUG): Deactivating GF circuits.
2023-05-04T20:28:51.880Z,1683232131.880 [CBIT](DEBUG): Deactivating emergency mode.
2023-05-04T20:28:51.916Z,1683232131.916 [CBIT](DEBUG): Backplane powered.
2023-05-04T20:28:51.917Z,1683232131.917 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-05-04T20:28:51.930Z,1683232131.930 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-04T20:28:51.957Z,1683232131.957 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2023-05-04T20:28:51.957Z,1683232131.957 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-05-04T20:28:52.065Z,1683232132.065 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-05-04T20:28:52.066Z,1683232132.066 [Default:A.Wait](DEBUG): Construct Wait.
2023-05-04T20:28:52.081Z,1683232132.081 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-04T20:28:52.108Z,1683232132.108 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-05-04T20:28:52.111Z,1683232132.111 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-05-04T20:28:52.132Z,1683232132.132 [Default:E.Execute](DEBUG): Construct Execute.
2023-05-04T20:28:52.152Z,1683232132.152 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2023-05-04T20:28:52.157Z,1683232132.157 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,ESPComponent,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,
2023-05-04T20:28:52.184Z,1683232132.184 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-05-04T20:28:52.212Z,1683232132.212 [Radio_Surface](INFO): Powering up
2023-05-04T20:28:52.274Z,1683232132.274 [Power24vConverter](INFO): Powering up.
2023-05-04T20:28:52.341Z,1683232132.341 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-05-04T20:28:52.344Z,1683232132.344 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-05-04T20:28:52.345Z,1683232132.345 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-05-04T20:28:52.352Z,1683232132.352 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-05-04T20:28:52.353Z,1683232132.353 [MassServo](DEBUG): Initializing EZServoServo.
2023-05-04T20:28:52.360Z,1683232132.360 [MassServo](DEBUG): Initializing MassServo.
2023-05-04T20:28:52.361Z,1683232132.361 [RudderServo](DEBUG): Initializing EZServoServo.
2023-05-04T20:28:52.368Z,1683232132.368 [RudderServo](DEBUG): Initializing RudderServo.
2023-05-04T20:28:52.369Z,1683232132.369 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-05-04T20:28:52.376Z,1683232132.376 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-05-04T20:28:53.304Z,1683232133.304 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2023-05-04T20:28:53.305Z,1683232133.305 [RudderServo](FAULT): Rudder failed to initialize
2023-05-04T20:28:53.305Z,1683232133.305 [RudderServo] Communications Fault, FailCount= 1
2023-05-04T20:28:53.305Z,1683232133.305 [RudderServo](ERROR): Communications Fault
2023-05-04T20:28:53.312Z,1683232133.312 [CBIT](ERROR): Communications Fault in component: RudderServo
2023-05-04T20:28:53.537Z,1683232133.537 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-05-04T20:28:53.538Z,1683232133.538 [RudderServo](INFO): Powering down
2023-05-04T20:28:53.860Z,1683232133.860 [WetLabsBB2FL](INFO): Powering down
2023-05-04T20:28:54.200Z,1683232134.200 [RudderServo](DEBUG): Initializing EZServoServo.
2023-05-04T20:28:54.321Z,1683232134.321 [RudderServo](DEBUG): Initializing RudderServo.
2023-05-04T20:28:54.325Z,1683232134.325 [CBIT](INFO): Clearing failed state for component RudderServo
2023-05-04T20:28:54.325Z,1683232134.325 [RudderServo] No Fault, FailCount= 1
2023-05-04T20:28:58.177Z,1683232138.177 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-05-04T20:28:59.821Z,1683232139.821 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-05-04T20:29:03.904Z,1683232143.904 [DATMMP](INFO): DAT read: 

2023-05-04T20:29:03.905Z,1683232143.905 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-05-04T20:29:05.669Z,1683232145.669 [DATMMP](INFO): DAT read: MF Frequency Band

2023-05-04T20:29:05.670Z,1683232145.670 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-05-04T20:29:05.671Z,1683232145.671 [DATMMP](INFO): DAT read: Apr  4 2000 20:29:00 

2023-05-04T20:29:06.677Z,1683232146.677 [DATMMP](INFO): DAT read: Features enabled [Bearing]

2023-05-04T20:29:06.678Z,1683232146.678 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-05-04T20:29:06.678Z,1683232146.678 [DATMMP](INFO): commRate: 800
2023-05-04T20:29:08.744Z,1683232148.744 [DATMMP](INFO): entering command mode
2023-05-04T20:29:08.944Z,1683232148.944 [DATMMP](INFO): DAT read: 

2023-05-04T20:29:08.945Z,1683232148.945 [DATMMP](INFO): DAT read: user:1>

2023-05-04T20:29:08.945Z,1683232148.945 [DATMMP](DEBUG): read user prompt 1: user:1>

2023-05-04T20:29:08.945Z,1683232148.945 [DATMMP](INFO): entering MMP mode
2023-05-04T20:29:09.197Z,1683232149.197 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T20:29:09.199Z,1683232149.199 [DATMMP](INFO): status rx: 	 x1
2023-05-04T20:29:09.200Z,1683232149.200 [DATMMP](INFO): MMP status: 1: started
2023-05-04T20:29:09.200Z,1683232149.200 [DATMMP](INFO): Received message type: status
2023-05-04T20:29:09.200Z,1683232149.200 [DATMMP](INFO): Handled
2023-05-04T20:29:09.449Z,1683232149.449 [DATMMP](INFO): Sent Tx Power Config 8
2023-05-04T20:29:09.450Z,1683232149.450 [DATMMP](INFO): Setting local address: 5
2023-05-04T20:29:09.451Z,1683232149.451 [DATMMP](INFO): Setting time to: 20:29:9 And date to:5/4/2023
2023-05-04T20:29:09.700Z,1683232149.700 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T20:29:09.701Z,1683232149.701 [DATMMP](INFO): mdmpower rx: 	 x8	 x0	 x5	 x0
2023-05-04T20:29:09.701Z,1683232149.701 [DATMMP](INFO): Received message type: mdmpower
2023-05-04T20:29:09.702Z,1683232149.702 [DATMMP](INFO): Handled
2023-05-04T20:29:09.952Z,1683232149.952 [DATMMP](INFO): GSXN notify for xid: 10
2023-05-04T20:29:09.953Z,1683232149.953 [DATMMP](INFO): sreg_txpower rx: 	 x8
2023-05-04T20:29:09.953Z,1683232149.953 [DATMMP](INFO): Received message type: sreg_txpower
2023-05-04T20:29:09.953Z,1683232149.953 [DATMMP](INFO): Match for : sreg_txpower
2023-05-04T20:29:09.953Z,1683232149.953 [DATMMP](INFO): ACK
2023-05-04T20:29:10.204Z,1683232150.204 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T20:29:10.205Z,1683232150.205 [DATMMP](INFO): mdmlocal rx: 	 x5	 x0	 x0	 x0
2023-05-04T20:29:10.205Z,1683232150.205 [DATMMP](INFO): Received message type: mdmlocal
2023-05-04T20:29:10.205Z,1683232150.205 [DATMMP](INFO): Handled
2023-05-04T20:29:10.456Z,1683232150.456 [DATMMP](INFO): GSXN notify for xid: 11
2023-05-04T20:29:10.457Z,1683232150.457 [DATMMP](INFO): sreg_locaddr rx: 	 x5
2023-05-04T20:29:10.457Z,1683232150.457 [DATMMP](INFO): Received message type: sreg_locaddr
2023-05-04T20:29:10.457Z,1683232150.457 [DATMMP](INFO): Match for : sreg_locaddr
2023-05-04T20:29:10.457Z,1683232150.457 [DATMMP](INFO): ACK
2023-05-04T20:29:10.709Z,1683232150.709 [DATMMP](INFO): GSXN notify for xid: 12
2023-05-04T20:29:10.710Z,1683232150.710 [DATMMP](INFO): timedate rx: 	 x0	 x14	 x1D	 x9	 x4	 x4	 x7B	 x0
2023-05-04T20:29:10.710Z,1683232150.710 [DATMMP](INFO): Received message type: timedate
2023-05-04T20:29:10.710Z,1683232150.710 [DATMMP](INFO): Match for : timedate
2023-05-04T20:29:10.710Z,1683232150.710 [DATMMP](INFO): ACK
2023-05-04T20:29:10.960Z,1683232150.960 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T20:29:10.961Z,1683232150.961 [DATMMP](INFO): sys_verb rx: 	 x0	 x0	 x0	 x0
2023-05-04T20:29:10.961Z,1683232150.961 [DATMMP](INFO): Received message type: sys_verb
2023-05-04T20:29:10.962Z,1683232150.962 [DATMMP](INFO): Handled
2023-05-04T20:29:11.212Z,1683232151.212 [DATMMP](INFO): GSXN notify for xid: 13
2023-05-04T20:29:11.213Z,1683232151.213 [DATMMP](INFO): sreg_verbosity rx: 	 x0
2023-05-04T20:29:11.213Z,1683232151.213 [DATMMP](INFO): Received message type: sreg_verbosity
2023-05-04T20:29:11.214Z,1683232151.214 [DATMMP](INFO): Match for : sreg_verbosity
2023-05-04T20:29:11.214Z,1683232151.214 [DATMMP](INFO): ACK
2023-05-04T20:29:11.464Z,1683232151.464 [DATMMP](INFO): GSXN notify for xid: 14
2023-05-04T20:29:11.465Z,1683232151.465 [DATMMP](INFO): version rx: 	 x2	 x5
2023-05-04T20:29:11.465Z,1683232151.465 [DATMMP](INFO): DatMMPVersion: 2.5
2023-05-04T20:29:11.465Z,1683232151.465 [DATMMP](INFO): Received message type: version
2023-05-04T20:29:11.466Z,1683232151.466 [DATMMP](INFO): Match for : version
2023-05-04T20:29:11.466Z,1683232151.466 [DATMMP](INFO): ACK
2023-05-04T20:29:11.466Z,1683232151.466 [DATMMP](INFO): in MMP mode: config complete going to runnable
2023-05-04T20:29:20.465Z,1683232160.465 [NAL9602](INFO): Powering up NAL9602
2023-05-04T20:29:25.829Z,1683232165.829 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T20:29:25.830Z,1683232165.830 [DATMMP](INFO): timesync rx: 	 x0	 x1	 x1	 x0
2023-05-04T20:29:25.830Z,1683232165.830 [DATMMP](INFO): Received message type: timesync
2023-05-04T20:29:25.830Z,1683232165.830 [DATMMP](INFO): Handled
2023-05-04T20:29:31.374Z,1683232171.374 [NAL9602](INFO): NAL9602 initialized
2023-05-04T20:29:35.681Z,1683232175.681 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T20:29:35.681Z,1683232175.681 [DATMMP](INFO): timesync rx: 	 x1	 x0	 x1	 x0
2023-05-04T20:29:35.682Z,1683232175.682 [DATMMP](INFO): Received message type: timesync
2023-05-04T20:29:35.682Z,1683232175.682 [DATMMP](INFO): Handled
2023-05-04T20:29:42.141Z,1683232182.141 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.8 degree
2023-05-04T20:29:42.144Z,1683232182.144 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2023-05-04T20:29:42.145Z,1683232182.145 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.9 degree
2023-05-04T20:29:42.148Z,1683232182.148 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2023-05-04T20:29:42.305Z,1683232182.305 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-05-04T20:29:49.240Z,1683232189.240 [CommandExec](IMPORTANT): got command maintain sensor Depth_Keller.depth 0.8 meter
2023-05-04T20:29:49.244Z,1683232189.244 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2023-05-04T20:29:49.592Z,1683232189.592 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,Maintain_Depth_Keller.depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-05-04T20:30:01.540Z,1683232201.540 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1683232182 second
2023-05-04T20:30:10.289Z,1683232210.289 [CommandExec](IMPORTANT): got command show stack
2023-05-04T20:30:10.289Z,1683232210.289 [CommandExec](IMPORTANT): Behavior Stack:

2023-05-04T20:30:10.290Z,1683232210.290 [MissionManager](IMPORTANT): Mission loaded, but not running.
2023-05-04T20:30:13.409Z,1683232213.409 [SBIT](IMPORTANT): Beginning Startup BIT
2023-05-04T20:30:13.429Z,1683232213.429 [CBIT](IMPORTANT): Beginning ground fault scan
2023-05-04T20:30:24.357Z,1683232224.357 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.007800
CHAN A1 (24V): -0.008201
CHAN A2 (12V): -0.004875
CHAN A3 (5V): -0.001965
CHAN B0 (3.3V): -0.001066
CHAN B1 (3.15aV): -0.001588
CHAN B2 (3.15bV): -0.000622
CHAN B3 (GND): -0.000954
OPEN: 0.004437
Full Scale: +/- 1 mA
2023-05-04T20:31:07.569Z,1683232267.569 [SBIT](IMPORTANT): SBIT PASSED
2023-05-04T20:31:07.569Z,1683232267.569 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-05-04T20:31:07.570Z,1683232267.570 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;

2023-05-04T20:31:07.570Z,1683232267.570 [SBIT](IMPORTANT): BackseatComponent.alwaysOn=0 bool;

2023-05-04T20:31:07.570Z,1683232267.570 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2023-05-04T20:31:07.570Z,1683232267.570 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2023-05-04T20:31:07.570Z,1683232267.570 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=10 minute;

2023-05-04T20:31:07.570Z,1683232267.570 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2023-05-04T20:31:07.570Z,1683232267.570 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2023-05-04T20:31:07.571Z,1683232267.571 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water;

2023-05-04T20:31:07.571Z,1683232267.571 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree;

2023-05-04T20:31:07.571Z,1683232267.571 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=184.896478 cubic_centimeter;

2023-05-04T20:31:07.571Z,1683232267.571 [SBIT](IMPORTANT): VerticalControl.elevDeadband=0.1 angular_degree;

2023-05-04T20:31:07.571Z,1683232267.571 [SBIT](IMPORTANT): VerticalControl.massDefault=15.484465 millimeter;

2023-05-04T20:31:07.961Z,1683232267.961 [MissionManager](IMPORTANT): Started mission Startup
2023-05-04T20:31:07.961Z,1683232267.961 [Startup] Running Loop=1
2023-05-04T20:31:07.961Z,1683232267.961 [Startup](DEBUG): Aggregate::initialize Startup
2023-05-04T20:31:07.961Z,1683232267.961 [Startup:A.GoToSurface] Running Loop=1
2023-05-04T20:31:07.962Z,1683232267.962 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-04T20:31:07.962Z,1683232267.962 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-04T20:31:07.963Z,1683232267.963 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-04T20:31:07.963Z,1683232267.963 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-04T20:31:07.963Z,1683232267.963 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-04T20:31:07.964Z,1683232267.964 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-04T20:31:07.989Z,1683232267.989 [Startup:StartupSatComms] Running Loop=1
2023-05-04T20:31:07.989Z,1683232267.989 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-05-04T20:31:07.990Z,1683232267.990 [Startup:StartupSatComms:A] Running Loop=1
2023-05-04T20:31:08.357Z,1683232268.357 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-05-04T20:31:08.357Z,1683232268.357 [Startup:StartupSatComms:A] Stopped
2023-05-04T20:31:08.357Z,1683232268.357 [Startup:StartupSatComms:B] Running Loop=1
2023-05-04T20:31:08.756Z,1683232268.756 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-04T20:31:19.606Z,1683232279.606 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004826
2023-05-04T20:31:27.008Z,1683232287.008 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230504T185113/Courier0022.lzma
2023-05-04T20:31:28.010Z,1683232288.010 [DataOverHttps](INFO): Moved sent file to Logs/20230504T185113/Courier0022.lzma.bak
2023-05-04T20:31:28.010Z,1683232288.010 [DataOverHttps](INFO): SBD MOMSN=18281310
2023-05-04T20:31:43.787Z,1683232303.787 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230504T200509/Courier0000.lzma
2023-05-04T20:31:44.790Z,1683232304.790 [DataOverHttps](INFO): Moved sent file to Logs/20230504T200509/Courier0000.lzma.bak
2023-05-04T20:31:44.790Z,1683232304.790 [DataOverHttps](INFO): SBD MOMSN=18281312
2023-05-04T20:32:06.976Z,1683232326.976 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20230504T202841/Courier0000.lzma
2023-05-04T20:32:07.978Z,1683232327.978 [DataOverHttps](INFO): Moved sent file to Logs/20230504T202841/Courier0000.lzma.bak
2023-05-04T20:32:07.978Z,1683232327.978 [DataOverHttps](INFO): SBD MOMSN=18281315
2023-05-04T20:32:08.685Z,1683232328.685 [Startup:StartupSatComms:B](INFO): Timed out from 2023-05-04T20:31:08.4Z
2023-05-04T20:32:08.685Z,1683232328.685 [Startup:StartupSatComms:B] Stopped
2023-05-04T20:32:08.685Z,1683232328.685 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-05-04T20:32:08.685Z,1683232328.685 [Startup:StartupSatComms] Stopped
2023-05-04T20:32:08.685Z,1683232328.685 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-05-04T20:32:08.686Z,1683232328.686 [Startup](INFO): Completed Startup
2023-05-04T20:32:08.686Z,1683232328.686 [MissionManager](INFO): Startup is completed.
2023-05-04T20:32:08.686Z,1683232328.686 [MissionManager](INFO): Uninitializing Mission Startup
2023-05-04T20:32:08.687Z,1683232328.687 [Startup] Stopped
2023-05-04T20:32:08.687Z,1683232328.687 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-05-04T20:32:08.687Z,1683232328.687 [Startup:A.GoToSurface] Stopped
2023-05-04T20:32:08.687Z,1683232328.687 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-04T20:32:09.081Z,1683232329.081 [MissionManager](IMPORTANT): Started mission Default
2023-05-04T20:32:09.081Z,1683232329.081 [Default] Running Loop=1
2023-05-04T20:32:09.081Z,1683232329.081 [Default](DEBUG): Aggregate::initialize Default
2023-05-04T20:32:09.081Z,1683232329.081 [Default:B.GoToSurface] Running Loop=1
2023-05-04T20:32:09.081Z,1683232329.081 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-04T20:32:09.082Z,1683232329.082 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-04T20:32:09.082Z,1683232329.082 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-04T20:32:09.082Z,1683232329.082 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-04T20:32:09.083Z,1683232329.083 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-04T20:32:09.083Z,1683232329.083 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-04T20:32:09.083Z,1683232329.083 [Default:A.Wait] Running Loop=1
2023-05-04T20:32:09.083Z,1683232329.083 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-05-04T20:32:22.400Z,1683232342.400 [Default:A.Wait](INFO): Done Waiting.
2023-05-04T20:32:22.400Z,1683232342.400 [Default:A.Wait] Stopped
2023-05-04T20:32:22.400Z,1683232342.400 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-04T20:32:22.802Z,1683232342.802 [Default:CheckIn] Running Loop=1
2023-05-04T20:32:22.802Z,1683232342.802 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-04T20:32:22.803Z,1683232342.803 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-04T20:32:23.205Z,1683232343.205 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-05-04T20:34:35.285Z,1683232475.285 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-04T20:34:41.346Z,1683232481.346 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:34:55.080Z,1683232495.080 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:35:08.009Z,1683232508.009 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:35:21.743Z,1683232521.743 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:35:35.081Z,1683232535.081 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:35:48.409Z,1683232548.409 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:36:01.741Z,1683232561.741 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:36:15.072Z,1683232575.072 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:36:28.000Z,1683232588.000 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:36:40.928Z,1683232600.928 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:36:54.664Z,1683232614.664 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:37:22.964Z,1683232642.964 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-04T20:32:22.8Z
2023-05-04T20:37:22.964Z,1683232642.964 [Default:CheckIn:Read_GPS] Stopped
2023-05-04T20:37:22.964Z,1683232642.964 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-04T20:37:23.361Z,1683232643.361 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-04T20:37:30.035Z,1683232650.035 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230504T202841/Courier0004.lzma
2023-05-04T20:37:31.038Z,1683232651.038 [DataOverHttps](INFO): Moved sent file to Logs/20230504T202841/Courier0004.lzma.bak
2023-05-04T20:37:31.038Z,1683232651.038 [DataOverHttps](INFO): SBD MOMSN=18281575
2023-05-04T20:37:48.932Z,1683232668.932 [DataOverHttps](INFO): Sending 354 bytes from file Logs/20230504T185113/Express0023.lzma
2023-05-04T20:37:49.934Z,1683232669.934 [DataOverHttps](INFO): Moved sent file to Logs/20230504T185113/Express0023.lzma.bak
2023-05-04T20:37:49.934Z,1683232669.934 [DataOverHttps](INFO): SBD MOMSN=18281577
2023-05-04T20:37:54.051Z,1683232674.051 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-04T20:38:06.940Z,1683232686.940 [DataOverHttps](INFO): Sending 538 bytes from file Logs/20230504T200509/Express0001.lzma
2023-05-04T20:38:07.942Z,1683232687.942 [DataOverHttps](INFO): Moved sent file to Logs/20230504T200509/Express0001.lzma.bak
2023-05-04T20:38:07.942Z,1683232687.942 [DataOverHttps](INFO): SBD MOMSN=18281593
2023-05-04T20:38:26.002Z,1683232706.002 [DataOverHttps](INFO): Sending 1267 bytes from file Logs/20230504T202841/Express0001.lzma
2023-05-04T20:38:27.002Z,1683232707.002 [DataOverHttps](INFO): Moved sent file to Logs/20230504T202841/Express0001.lzma.bak
2023-05-04T20:38:27.002Z,1683232707.002 [DataOverHttps](INFO): SBD MOMSN=18281602
2023-05-04T20:38:44.208Z,1683232724.208 [DataOverHttps](INFO): Sending 218 bytes from file Logs/20230504T202841/Express0005.lzma
2023-05-04T20:38:45.210Z,1683232725.210 [DataOverHttps](INFO): Moved sent file to Logs/20230504T202841/Express0005.lzma.bak
2023-05-04T20:38:45.210Z,1683232725.210 [DataOverHttps](INFO): SBD MOMSN=18281633
2023-05-04T20:38:47.794Z,1683232727.794 [Default:CheckIn:Read_Iridium] Stopped
2023-05-04T20:38:47.795Z,1683232727.795 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-04T20:38:47.795Z,1683232727.795 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-04T20:43:48.381Z,1683233028.381 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-04T20:43:48.381Z,1683233028.381 [Default:CheckIn:C.Wait] Stopped
2023-05-04T20:43:48.381Z,1683233028.381 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-04T20:43:48.382Z,1683233028.382 [Default:CheckIn:D] Running Loop=1
2023-05-04T20:43:48.779Z,1683233028.779 [Default:CheckIn:D] Stopped
2023-05-04T20:43:48.779Z,1683233028.779 [Default:CheckIn:E] Running Loop=1
2023-05-04T20:43:49.178Z,1683233029.178 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.661627 min 
2023-05-04T20:43:49.178Z,1683233029.178 [Default:CheckIn:E] Stopped
2023-05-04T20:43:49.179Z,1683233029.179 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-04T20:43:49.179Z,1683233029.179 [Default:CheckIn] Stopped
2023-05-04T20:43:49.179Z,1683233029.179 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-04T20:43:49.179Z,1683233029.179 [Default:CheckIn](INFO): Running loop #2
2023-05-04T20:43:49.179Z,1683233029.179 [Default:CheckIn] Running Loop=2
2023-05-04T20:43:49.179Z,1683233029.179 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-04T20:43:49.179Z,1683233029.179 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-04T20:43:50.792Z,1683233030.792 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-04T20:43:50.792Z,1683233030.792 [NAL9602] Data Fault, FailCount= 1
2023-05-04T20:43:50.792Z,1683233030.792 [NAL9602](ERROR): Data Fault
2023-05-04T20:43:50.812Z,1683233030.812 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-04T20:43:51.205Z,1683233031.205 [NAL9602](INFO): Powering down
2023-05-04T20:43:52.020Z,1683233032.020 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-04T20:43:52.044Z,1683233032.044 [NAL9602] No Fault, FailCount= 1
2023-05-04T20:44:17.852Z,1683233057.852 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:44:21.490Z,1683233061.490 [NAL9602](INFO): Powering up NAL9602
2023-05-04T20:44:31.184Z,1683233071.184 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:44:32.397Z,1683233072.397 [NAL9602](INFO): NAL9602 initialized
2023-05-04T20:44:44.519Z,1683233084.519 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:44:57.848Z,1683233097.848 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:45:11.584Z,1683233111.584 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:45:24.512Z,1683233124.512 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:45:37.849Z,1683233137.849 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:45:51.178Z,1683233151.178 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:46:04.508Z,1683233164.508 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:46:17.840Z,1683233177.840 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:46:31.576Z,1683233191.576 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:48:49.414Z,1683233329.414 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-04T20:43:49.2Z
2023-05-04T20:48:49.414Z,1683233329.414 [Default:CheckIn:Read_GPS] Stopped
2023-05-04T20:48:49.414Z,1683233329.414 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-04T20:48:56.159Z,1683233336.159 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230504T202841/Courier0007.lzma
2023-05-04T20:48:57.162Z,1683233337.162 [DataOverHttps](INFO): Moved sent file to Logs/20230504T202841/Courier0007.lzma.bak
2023-05-04T20:48:57.162Z,1683233337.162 [DataOverHttps](INFO): SBD MOMSN=18281655
2023-05-04T20:49:15.720Z,1683233355.720 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20230504T202841/Express0008.lzma
2023-05-04T20:49:16.722Z,1683233356.722 [DataOverHttps](INFO): Moved sent file to Logs/20230504T202841/Express0008.lzma.bak
2023-05-04T20:49:16.722Z,1683233356.722 [DataOverHttps](INFO): SBD MOMSN=18281657
2023-05-04T20:49:19.379Z,1683233359.379 [Default:CheckIn:Read_Iridium] Stopped
2023-05-04T20:49:19.379Z,1683233359.379 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-04T20:49:19.379Z,1683233359.379 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-04T20:49:36.305Z,1683233376.305 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-04T20:50:07.005Z,1683233407.005 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-04T20:51:42.647Z,1683233502.647 [CommandExec](IMPORTANT): got command get depth
2023-05-04T20:51:42.648Z,1683233502.648 [CommandExec](IMPORTANT): depth 0.800000 m
2023-05-04T20:51:46.255Z,1683233506.255 [CommandExec](IMPORTANT): got command run ./Missions/Demo/senddata_direct_and_track_test.xml
2023-05-04T20:51:46.256Z,1683233506.256 [MissionManager](INFO): Loading Mission from file: ./Missions/Demo/senddata_direct_and_track_test.xml
2023-05-04T20:51:46.308Z,1683233506.308 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcousticTargetContactLabel = 6.000000 count
2023-05-04T20:51:46.310Z,1683233506.310 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcousticTargetDepth = 0.000000 m
2023-05-04T20:51:46.313Z,1683233506.313 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.TrackingUpdatePeriod = 15.000000 s
2023-05-04T20:51:46.316Z,1683233506.316 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfPings = 1.000000 count
2023-05-04T20:51:46.319Z,1683233506.319 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfFixesLowPass = 2.000000 count
2023-05-04T20:51:46.321Z,1683233506.321 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfStartingFixesToIgnore = 2.000000 count
2023-05-04T20:51:46.324Z,1683233506.324 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcommsInterval = 1.000000 min
2023-05-04T20:51:46.327Z,1683233506.327 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.modemId1 = 6.000000 enum
2023-05-04T20:51:46.350Z,1683233506.350 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.modemId2 = 5.000000 enum
2023-05-04T20:51:46.353Z,1683233506.353 [MissionManager](INFO): DefineOutput senddata_direct_and_track_test.ModemID = 1.000000 enum
2023-05-04T20:51:46.497Z,1683233506.497 [senddata_direct_and_track_test:DirectData:A.Wait](DEBUG): Construct Wait.
2023-05-04T20:51:46.511Z,1683233506.511 [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_and_track_test">


    <DefineArg Name="AcousticTargetContactLabel"><Description>
        The acoustic address of the asset to be tracked. 21 is waveglider Tiny
    </Description><Units:count/><Value>6</Value></DefineArg>

    <DefineArg Name="AcousticTargetDepth"><Description>
        Depth of acoustic target if known and fixed (or nearly fixed). For
        example, if the acoustic target is a Wave Glider, set it to zero. This
        will improve 2D projected position estimates in the Earth reference
        frame. Defaults to NaN.
    </Description><Units:meter/><Value>0</Value></DefineArg>

    <DefineArg Name="TrackingUpdatePeriod"><Description>
        How long to wait between acoustic queries
    </Description><Units:second/><Value>15</Value></DefineArg>

    <DefineArg Name="NumberOfPings"><Description>
        Number of return pings to request with each acoustic query (more than 1
        will activate oneway mode)
    </Description><Units:count/><Value>1</Value></DefineArg>    

    <DefineArg Name="NumberOfFixesLowPass"><Description>
        Number of fixes to average to produce smoothed lat/lon/dep output
    </Description><Units:count/><Value>2</Value></DefineArg>
    
    <DefineArg Name="NumberOfStartingFixesToIgnore"><Description>
        Number of fixes to ignore at the start of mission (as the vehicle just
        leaves surface, contact's location estimate especailly bearing can be
        erroneous. Initialized to 8, corresponding to 2 minutes if query
        interval TrackingUpdatePeriod = 15 s.
    </Description><Units:count/><Value>2</Value></DefineArg>
    
    <DefineArg Name="AcommsInterval"><Description></Description><Units:minute/><Value>1</Value></DefineArg>    
    
    <DefineArg Name="modemId1"><Description>
        Modem ID1.
    </Description><Units:enum/><Value>6</Value></DefineArg>

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

    <DefineOutput Name="ModemID"><Description>
        Modem ID. Set to modemId1 or modemId2. Initialized to 1.
    </Description><Units:enum/><Value>1</Value></DefineOutput>            


        <Estimation:Tracking>
            <Parallel/>
            <Setting><Estimation:Tracking.contactLabelSetting/><Arg Name="AcousticTargetContactLabel"/></Setting>
            <Setting><Estimation:Tracking.numberOfSamplesSetting/><Arg Name="NumberOfPings"/></Setting>
            <Setting><Estimation:Tracking.updatePeriodSetting/><Arg Name="TrackingUpdatePeriod"/></Setting>
            <Setting><Estimation:Tracking.numFixesLowPassSetting/><Arg Name="NumberOfFixesLowPass"/></Setting>
            <Setting><Estimation:Tracking.numStartingFixesToIgnoreSetting/><Arg Name="NumberOfStartingFixesToIgnore"/></Setting>
            <Setting><Estimation:Tracking.contactDepthSetting/><Arg Name="AcousticTargetDepth"/></Setting>
        </Estimation:Tracking>



  <Aggregate Id="DirectData">
  <Sequence Repeat="4"/>
    <Guidance:Wait>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Arg Name="AcommsInterval"/></Setting>
    </Guidance:Wait>
                
    <SendData Service="Direct" Destination="modem:6:_.pressure">
        <Sensor:Onboard.Pressure/><Units:atmosphere/>
    </SendData>

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

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

  </Aggregate>

</Mission>


2023-05-04T20:51:46.530Z,1683233506.530 [CommandExec](IMPORTANT): Running ./Missions/Demo/senddata_direct_and_track_test.xml
2023-05-04T20:51:46.821Z,1683233506.821 [Default] Stopped
2023-05-04T20:51:46.821Z,1683233506.821 [Default](DEBUG): Aggregate::uninitialize Default
2023-05-04T20:51:46.821Z,1683233506.821 [Default:B.GoToSurface] Stopped
2023-05-04T20:51:46.821Z,1683233506.821 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-04T20:51:46.821Z,1683233506.821 [Default:CheckIn] Stopped
2023-05-04T20:51:46.821Z,1683233506.821 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-04T20:51:46.821Z,1683233506.821 [Default:CheckIn:C.Wait] Stopped
2023-05-04T20:51:46.821Z,1683233506.821 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-04T20:51:46.821Z,1683233506.821 [MissionManager](IMPORTANT): Started mission senddata_direct_and_track_test
2023-05-04T20:51:46.822Z,1683233506.822 [senddata_direct_and_track_test] Running Loop=1
2023-05-04T20:51:46.822Z,1683233506.822 [senddata_direct_and_track_test](DEBUG): Aggregate::initialize senddata_direct_and_track_test
2023-05-04T20:51:46.822Z,1683233506.822 [senddata_direct_and_track_test:A.] Running Loop=1
2023-05-04T20:51:46.822Z,1683233506.822 [senddata_direct_and_track_test:A.](INFO): Initializing Tracking.
2023-05-04T20:51:46.822Z,1683233506.822 [senddata_direct_and_track_test:DirectData] Running Loop=1
2023-05-04T20:51:46.822Z,1683233506.822 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::initialize senddata_direct_and_track_test:DirectData
2023-05-04T20:51:46.823Z,1683233506.823 [senddata_direct_and_track_test:DirectData:A.Wait] Running Loop=1
2023-05-04T20:51:46.823Z,1683233506.823 [senddata_direct_and_track_test:DirectData:A.Wait](DEBUG): Initialize Wait Component.
2023-05-04T20:51:46.823Z,1683233506.823 [senddata_direct_and_track_test:A.] Running Loop=1
2023-05-04T20:51:46.823Z,1683233506.823 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:46.824Z,1683233506.824 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:47.216Z,1683233507.216 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:47.216Z,1683233507.216 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:47.611Z,1683233507.611 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:47.611Z,1683233507.611 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:48.019Z,1683233508.019 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:48.019Z,1683233508.019 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:48.429Z,1683233508.429 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:48.429Z,1683233508.429 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:48.824Z,1683233508.824 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:48.825Z,1683233508.825 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:49.226Z,1683233509.226 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:49.227Z,1683233509.227 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:49.632Z,1683233509.632 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:49.633Z,1683233509.633 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:50.098Z,1683233510.098 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:50.099Z,1683233510.099 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:50.445Z,1683233510.445 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:50.446Z,1683233510.446 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:50.877Z,1683233510.877 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:50.878Z,1683233510.878 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:51.267Z,1683233511.267 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:51.267Z,1683233511.267 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:51.710Z,1683233511.710 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:51.711Z,1683233511.711 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:52.114Z,1683233512.114 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:52.114Z,1683233512.114 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:52.715Z,1683233512.715 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:52.752Z,1683233512.752 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:53.018Z,1683233513.018 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:53.019Z,1683233513.019 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:53.363Z,1683233513.363 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:53.364Z,1683233513.364 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:53.781Z,1683233513.781 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:53.782Z,1683233513.782 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:54.166Z,1683233514.166 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:54.167Z,1683233514.167 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:54.584Z,1683233514.584 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:54.585Z,1683233514.585 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:54.994Z,1683233514.994 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:54.995Z,1683233514.995 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:55.401Z,1683233515.401 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:55.402Z,1683233515.402 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:55.798Z,1683233515.798 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:55.798Z,1683233515.798 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:56.210Z,1683233516.210 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:56.210Z,1683233516.210 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:56.610Z,1683233516.610 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:56.610Z,1683233516.610 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:57.018Z,1683233517.018 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:57.019Z,1683233517.019 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:57.413Z,1683233517.413 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:57.414Z,1683233517.414 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:57.837Z,1683233517.837 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:57.837Z,1683233517.837 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:58.209Z,1683233518.209 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:58.209Z,1683233518.209 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:58.613Z,1683233518.613 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:58.614Z,1683233518.614 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:59.006Z,1683233519.006 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:59.007Z,1683233519.007 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:59.406Z,1683233519.406 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:59.407Z,1683233519.407 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:51:59.819Z,1683233519.819 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:51:59.820Z,1683233519.820 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:52:00.227Z,1683233520.227 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:00.228Z,1683233520.228 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:52:00.615Z,1683233520.615 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:00.616Z,1683233520.616 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:52:01.019Z,1683233521.019 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:01.029Z,1683233521.029 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:52:01.435Z,1683233521.435 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:01.437Z,1683233521.437 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:52:01.826Z,1683233521.826 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:01.827Z,1683233521.827 [senddata_direct_and_track_test:A.](INFO): update period (15.000000 s) has elapsed, querying...
2023-05-04T20:52:01.827Z,1683233521.827 [senddata_direct_and_track_test:A.](INFO): *** querying DAT ***
2023-05-04T20:52:01.941Z,1683233521.941 [DATMMP](INFO): ****** received valid address query ******
2023-05-04T20:52:01.941Z,1683233521.941 [DATMMP](INFO): ****** received valid ping request ******
2023-05-04T20:52:01.942Z,1683233521.942 [DATMMP](INFO): Querying Benthos address 6
2023-05-04T20:52:02.193Z,1683233522.193 [DATMMP](INFO): GSXN notify for xid: 15
2023-05-04T20:52:02.194Z,1683233522.194 [DATMMP](INFO): cmd_result rx: 	 x2	 x19	 x0	 x2
2023-05-04T20:52:02.194Z,1683233522.194 [DATMMP](INFO): Progress on cmd
2023-05-04T20:52:02.194Z,1683233522.194 [DATMMP](INFO): Received message type: cmd_result
2023-05-04T20:52:02.194Z,1683233522.194 [DATMMP](INFO): Handled
2023-05-04T20:52:02.194Z,1683233522.194 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:02.283Z,1683233522.283 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:02.288Z,1683233522.288 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:52:02.445Z,1683233522.445 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T20:52:02.446Z,1683233522.446 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-04T20:52:02.446Z,1683233522.446 [DATMMP](INFO): Received message type: nav_stat
2023-05-04T20:52:02.446Z,1683233522.446 [DATMMP](INFO): Handled
2023-05-04T20:52:02.446Z,1683233522.446 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:02.657Z,1683233522.657 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:02.658Z,1683233522.658 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:52:02.697Z,1683233522.697 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T20:52:02.698Z,1683233522.698 [DATMMP](INFO): timestamp rx: 	 x0	 x14	 x34	 x0	 x1910
2023-05-04T20:52:02.698Z,1683233522.698 [DATMMP](INFO): Received message type: timestamp
2023-05-04T20:52:02.698Z,1683233522.698 [DATMMP](INFO): Handled
2023-05-04T20:52:02.698Z,1683233522.698 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:02.949Z,1683233522.949 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:03.047Z,1683233523.047 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:03.048Z,1683233523.048 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:52:03.201Z,1683233523.201 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:03.452Z,1683233523.452 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:03.649Z,1683233523.649 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:03.649Z,1683233523.649 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:52:03.705Z,1683233523.705 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:03.925Z,1683233523.925 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:03.925Z,1683233523.925 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:52:03.957Z,1683233523.957 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:04.209Z,1683233524.209 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:04.321Z,1683233524.321 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:04.322Z,1683233524.322 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:52:04.461Z,1683233524.461 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:04.696Z,1683233524.696 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:04.697Z,1683233524.697 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:52:04.713Z,1683233524.713 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:04.965Z,1683233524.965 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T20:52:04.966Z,1683233524.966 [DATMMP](INFO): doppler rx: 	 xFFFF
2023-05-04T20:52:04.966Z,1683233524.966 [DATMMP](INFO): Received message type: doppler
2023-05-04T20:52:04.966Z,1683233524.966 [DATMMP](INFO): Handled
2023-05-04T20:52:04.966Z,1683233524.966 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:05.101Z,1683233525.101 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:05.102Z,1683233525.102 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:52:05.217Z,1683233525.217 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T20:52:05.218Z,1683233525.218 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-04T20:52:05.218Z,1683233525.218 [DATMMP](INFO): Received message type: nav_stat
2023-05-04T20:52:05.218Z,1683233525.218 [DATMMP](INFO): Handled
2023-05-04T20:52:05.218Z,1683233525.218 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:05.469Z,1683233525.469 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T20:52:05.470Z,1683233525.470 [DATMMP](INFO): directional_debug rx: 	 x3D5	 xE4	 x2F	 x49	 x22F0	 x2A91	 x24E2	 x3383
2023-05-04T20:52:05.471Z,1683233525.471 [DATMMP](INFO): Received message type: directional_debug
2023-05-04T20:52:05.471Z,1683233525.471 [DATMMP](INFO): Handled
2023-05-04T20:52:05.471Z,1683233525.471 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:05.715Z,1683233525.715 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:05.715Z,1683233525.715 [senddata_direct_and_track_test:A.](INFO): waiting for update period (15.000000 s) to elapse
2023-05-04T20:52:05.721Z,1683233525.721 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T20:52:05.722Z,1683233525.722 [DATMMP](INFO): directional rx: 	 x3C8	 xA0	 x207	 xFF1C	 x772	 xFF2E	 x6E28	 x569	 x8	 xFFF0	 x27B5966	 x-43569E0
2023-05-04T20:52:05.724Z,1683233525.724 [DATMMP](INFO): Received message type: directional
2023-05-04T20:52:05.724Z,1683233525.724 [DATMMP](INFO): Handled
2023-05-04T20:52:05.724Z,1683233525.724 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:05.913Z,1683233525.913 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:05.914Z,1683233525.914 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:05.915Z,1683233525.915 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:05.973Z,1683233525.973 [DATMMP](INFO): GSXN notify for xid: 15
2023-05-04T20:52:05.974Z,1683233525.974 [DATMMP](INFO): cmd_result rx: 	 x2	 x19	 x1	 x6
2023-05-04T20:52:05.974Z,1683233525.974 [DATMMP](INFO): Progress on cmd
2023-05-04T20:52:05.974Z,1683233525.974 [DATMMP](INFO): Received message type: cmd_result
2023-05-04T20:52:05.974Z,1683233525.974 [DATMMP](INFO): Handled
2023-05-04T20:52:05.974Z,1683233525.974 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:06.225Z,1683233526.225 [DATMMP](INFO): GSXN notify for xid: 15
2023-05-04T20:52:06.226Z,1683233526.226 [DATMMP](INFO): cmd_result rx: 	 x2	 x19	 x2	 x3
2023-05-04T20:52:06.226Z,1683233526.226 [DATMMP](ERROR): 3:COMPLETE
2023-05-04T20:52:06.226Z,1683233526.226 [DATMMP](INFO): Received message type: cmd_result
2023-05-04T20:52:06.226Z,1683233526.226 [DATMMP](INFO): Handled
2023-05-04T20:52:06.227Z,1683233526.227 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:06.350Z,1683233526.350 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:06.351Z,1683233526.351 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:06.372Z,1683233526.372 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:06.477Z,1683233526.477 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-04T20:52:06.478Z,1683233526.478 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-04T20:52:06.478Z,1683233526.478 [DATMMP](INFO): Received message type: nav_stat
2023-05-04T20:52:06.478Z,1683233526.478 [DATMMP](INFO): Handled
2023-05-04T20:52:06.478Z,1683233526.478 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:06.728Z,1683233526.728 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:06.758Z,1683233526.758 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:06.759Z,1683233526.759 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:06.760Z,1683233526.760 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:06.981Z,1683233526.981 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:07.121Z,1683233527.121 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:07.122Z,1683233527.122 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:07.122Z,1683233527.122 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:07.233Z,1683233527.233 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:07.485Z,1683233527.485 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:07.530Z,1683233527.530 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:07.531Z,1683233527.531 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:07.532Z,1683233527.532 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:07.737Z,1683233527.737 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:07.938Z,1683233527.938 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:07.939Z,1683233527.939 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:07.940Z,1683233527.940 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:07.988Z,1683233527.988 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:08.240Z,1683233528.240 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:08.319Z,1683233528.319 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:08.320Z,1683233528.320 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:08.321Z,1683233528.321 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:08.492Z,1683233528.492 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:08.733Z,1683233528.733 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:08.734Z,1683233528.734 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:08.735Z,1683233528.735 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:08.744Z,1683233528.744 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:08.996Z,1683233528.996 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:09.122Z,1683233529.122 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:09.123Z,1683233529.123 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:09.124Z,1683233529.124 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:09.248Z,1683233529.248 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:09.500Z,1683233529.500 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:09.533Z,1683233529.533 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:09.534Z,1683233529.534 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:09.535Z,1683233529.535 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:09.753Z,1683233529.753 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:09.932Z,1683233529.932 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:09.933Z,1683233529.933 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:09.934Z,1683233529.934 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:10.004Z,1683233530.004 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:10.256Z,1683233530.256 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:10.347Z,1683233530.347 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:10.348Z,1683233530.348 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:10.349Z,1683233530.349 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:10.508Z,1683233530.508 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:10.743Z,1683233530.743 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:10.744Z,1683233530.744 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:10.745Z,1683233530.745 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:10.760Z,1683233530.760 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:11.012Z,1683233531.012 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:11.144Z,1683233531.144 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:11.145Z,1683233531.145 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:11.145Z,1683233531.145 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:11.264Z,1683233531.264 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:11.516Z,1683233531.516 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:11.558Z,1683233531.558 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:11.559Z,1683233531.559 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:11.559Z,1683233531.559 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:11.768Z,1683233531.768 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:11.951Z,1683233531.951 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:11.952Z,1683233531.952 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:11.969Z,1683233531.969 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:12.021Z,1683233532.021 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:12.272Z,1683233532.272 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:12.355Z,1683233532.355 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:12.357Z,1683233532.357 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:12.357Z,1683233532.357 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:12.525Z,1683233532.525 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-04T20:52:12.801Z,1683233532.801 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:12.802Z,1683233532.802 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:12.803Z,1683233532.803 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:13.163Z,1683233533.163 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:13.164Z,1683233533.164 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:13.165Z,1683233533.165 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:13.578Z,1683233533.578 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:13.578Z,1683233533.578 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:13.579Z,1683233533.579 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:13.975Z,1683233533.975 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:13.975Z,1683233533.975 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:13.976Z,1683233533.976 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:14.375Z,1683233534.375 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:14.376Z,1683233534.376 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:14.377Z,1683233534.377 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:14.798Z,1683233534.798 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:14.799Z,1683233534.799 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:14.800Z,1683233534.800 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:15.205Z,1683233535.205 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:15.206Z,1683233535.206 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:15.207Z,1683233535.207 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:15.619Z,1683233535.619 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:15.628Z,1683233535.628 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:15.629Z,1683233535.629 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:16.034Z,1683233536.034 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:16.035Z,1683233536.035 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:16.044Z,1683233536.044 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:16.432Z,1683233536.432 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:16.433Z,1683233536.433 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:16.433Z,1683233536.433 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:16.967Z,1683233536.967 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:16.988Z,1683233536.988 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:16.989Z,1683233536.989 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:17.257Z,1683233537.257 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:17.258Z,1683233537.258 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:17.259Z,1683233537.259 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:17.641Z,1683233537.641 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:17.641Z,1683233537.641 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:17.642Z,1683233537.642 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:18.035Z,1683233538.035 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:18.036Z,1683233538.036 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:18.037Z,1683233538.037 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:18.419Z,1683233538.419 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:18.420Z,1683233538.420 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:18.421Z,1683233538.421 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:18.885Z,1683233538.885 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:18.886Z,1683233538.886 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:18.887Z,1683233538.887 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:19.252Z,1683233539.252 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:19.253Z,1683233539.253 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:19.254Z,1683233539.254 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:19.651Z,1683233539.651 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:19.652Z,1683233539.652 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:19.653Z,1683233539.653 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:20.043Z,1683233540.043 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:20.044Z,1683233540.044 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:20.045Z,1683233540.045 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:20.439Z,1683233540.439 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:20.440Z,1683233540.440 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:20.441Z,1683233540.441 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:20.850Z,1683233540.850 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:20.851Z,1683233540.851 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:20.852Z,1683233540.852 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:21.328Z,1683233541.328 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:21.329Z,1683233541.329 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:21.330Z,1683233541.330 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:21.678Z,1683233541.678 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:21.679Z,1683233541.679 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:21.679Z,1683233541.679 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:22.107Z,1683233542.107 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:22.115Z,1683233542.115 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:22.119Z,1683233542.119 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:22.475Z,1683233542.475 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:22.477Z,1683233542.477 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:22.480Z,1683233542.480 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:22.860Z,1683233542.860 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:22.861Z,1683233542.861 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:22.862Z,1683233542.862 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:23.267Z,1683233543.267 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:23.268Z,1683233543.268 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:23.269Z,1683233543.269 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:23.670Z,1683233543.670 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:23.671Z,1683233543.671 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:23.673Z,1683233543.673 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:24.070Z,1683233544.070 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:24.070Z,1683233544.070 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:24.071Z,1683233544.071 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:24.474Z,1683233544.474 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:24.474Z,1683233544.474 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:24.475Z,1683233544.475 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:24.883Z,1683233544.883 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:24.884Z,1683233544.884 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:24.885Z,1683233544.885 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:25.282Z,1683233545.282 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:25.283Z,1683233545.283 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:25.284Z,1683233545.284 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:25.686Z,1683233545.686 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:25.687Z,1683233545.687 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:25.688Z,1683233545.688 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:26.095Z,1683233546.095 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:26.095Z,1683233546.095 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:26.096Z,1683233546.096 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:26.494Z,1683233546.494 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:26.495Z,1683233546.495 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:26.495Z,1683233546.495 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:26.898Z,1683233546.898 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:26.899Z,1683233546.899 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:26.900Z,1683233546.900 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:27.344Z,1683233547.344 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:27.345Z,1683233547.345 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:27.346Z,1683233547.346 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:27.719Z,1683233547.719 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:27.724Z,1683233547.724 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:27.725Z,1683233547.725 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:28.124Z,1683233548.124 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:28.125Z,1683233548.125 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:28.126Z,1683233548.126 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:28.548Z,1683233548.548 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:28.549Z,1683233548.549 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:28.549Z,1683233548.549 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:28.923Z,1683233548.923 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:28.928Z,1683233548.928 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:28.929Z,1683233548.929 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:29.341Z,1683233549.341 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:29.342Z,1683233549.342 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:29.342Z,1683233549.342 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:29.740Z,1683233549.740 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:29.741Z,1683233549.741 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:29.742Z,1683233549.742 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:30.263Z,1683233550.263 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:30.268Z,1683233550.268 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:30.269Z,1683233550.269 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:30.547Z,1683233550.547 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:30.548Z,1683233550.548 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:30.549Z,1683233550.549 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:30.959Z,1683233550.959 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:30.959Z,1683233550.959 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:30.960Z,1683233550.960 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:31.363Z,1683233551.363 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:31.364Z,1683233551.364 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:31.365Z,1683233551.365 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:31.746Z,1683233551.746 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:31.747Z,1683233551.747 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:31.748Z,1683233551.748 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:32.214Z,1683233552.214 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:32.215Z,1683233552.215 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:32.216Z,1683233552.216 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:32.562Z,1683233552.562 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:32.563Z,1683233552.563 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:32.564Z,1683233552.564 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:32.983Z,1683233552.983 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:32.984Z,1683233552.984 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:32.985Z,1683233552.985 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:33.380Z,1683233553.380 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:33.381Z,1683233553.381 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:33.381Z,1683233553.381 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:33.776Z,1683233553.776 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:33.777Z,1683233553.777 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:33.778Z,1683233553.778 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:34.175Z,1683233554.175 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:34.176Z,1683233554.176 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:34.177Z,1683233554.177 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:34.583Z,1683233554.583 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:34.584Z,1683233554.584 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:34.585Z,1683233554.585 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:34.985Z,1683233554.985 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:34.986Z,1683233554.986 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:34.987Z,1683233554.987 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:35.387Z,1683233555.387 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:35.388Z,1683233555.388 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:35.389Z,1683233555.389 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:35.791Z,1683233555.791 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:35.792Z,1683233555.792 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:35.793Z,1683233555.793 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:36.190Z,1683233556.190 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:36.191Z,1683233556.191 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:36.192Z,1683233556.192 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:36.594Z,1683233556.594 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:36.595Z,1683233556.595 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:36.596Z,1683233556.596 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:37.003Z,1683233557.003 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:37.004Z,1683233557.004 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:37.004Z,1683233557.004 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:37.402Z,1683233557.402 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:37.403Z,1683233557.403 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:37.404Z,1683233557.404 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:37.806Z,1683233557.806 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:37.807Z,1683233557.807 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:37.808Z,1683233557.808 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:38.215Z,1683233558.215 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:38.216Z,1683233558.216 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:38.216Z,1683233558.216 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:38.614Z,1683233558.614 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:38.615Z,1683233558.615 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:38.616Z,1683233558.616 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:39.019Z,1683233559.019 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:39.020Z,1683233559.020 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:39.021Z,1683233559.021 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:39.422Z,1683233559.422 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:39.423Z,1683233559.423 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:39.424Z,1683233559.424 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:39.828Z,1683233559.828 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:39.829Z,1683233559.829 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:39.830Z,1683233559.830 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:40.248Z,1683233560.248 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:40.249Z,1683233560.249 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:40.250Z,1683233560.250 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:40.664Z,1683233560.664 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:40.665Z,1683233560.665 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:40.666Z,1683233560.666 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:41.045Z,1683233561.045 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:41.046Z,1683233561.046 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:41.047Z,1683233561.047 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:41.461Z,1683233561.461 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:41.462Z,1683233561.462 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:41.463Z,1683233561.463 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:41.850Z,1683233561.850 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:41.851Z,1683233561.851 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:41.851Z,1683233561.851 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:42.257Z,1683233562.257 [CommandExec](IMPORTANT): got command stop
2023-05-04T20:52:42.257Z,1683233562.257 [CommandExec](IMPORTANT): Scheduling is paused
2023-05-04T20:52:42.257Z,1683233562.257 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2023-05-04T20:52:42.259Z,1683233562.259 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-04T20:52:42.262Z,1683233562.262 [senddata_direct_and_track_test:A.](INFO): range timestamp (1683233525.720170 .And dataStartTime_:1683233521.827358
2023-05-04T20:52:42.262Z,1683233562.262 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-04T20:52:42.263Z,1683233562.263 [MissionManager](INFO): MissionManager is completed.
2023-05-04T20:52:42.263Z,1683233562.263 [MissionManager](INFO): Uninitializing Mission senddata_direct_and_track_test
2023-05-04T20:52:42.263Z,1683233562.263 [senddata_direct_and_track_test] Stopped
2023-05-04T20:52:42.263Z,1683233562.263 [senddata_direct_and_track_test](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test
2023-05-04T20:52:42.263Z,1683233562.263 [senddata_direct_and_track_test:A.] Stopped
2023-05-04T20:52:42.263Z,1683233562.263 [senddata_direct_and_track_test:A.](DEBUG): Uninitializing Tracking.
2023-05-04T20:52:42.263Z,1683233562.263 [senddata_direct_and_track_test:DirectData] Stopped
2023-05-04T20:52:42.263Z,1683233562.263 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test:DirectData
2023-05-04T20:52:42.263Z,1683233562.263 [senddata_direct_and_track_test:DirectData:A.Wait] Stopped
2023-05-04T20:52:42.263Z,1683233562.263 [senddata_direct_and_track_test:DirectData:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-04T20:52:42.666Z,1683233562.666 [MissionManager](IMPORTANT): Started mission Default
2023-05-04T20:52:42.666Z,1683233562.666 [Default] Running Loop=1
2023-05-04T20:52:42.666Z,1683233562.666 [Default](DEBUG): Aggregate::initialize Default
2023-05-04T20:52:42.666Z,1683233562.666 [Default:B.GoToSurface] Running Loop=1
2023-05-04T20:52:42.666Z,1683233562.666 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-04T20:52:42.667Z,1683233562.667 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-04T20:52:42.667Z,1683233562.667 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-04T20:52:42.667Z,1683233562.667 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-04T20:52:42.667Z,1683233562.667 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-04T20:52:42.668Z,1683233562.668 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-04T20:52:42.668Z,1683233562.668 [Default:A.Wait] Running Loop=1
2023-05-04T20:52:42.668Z,1683233562.668 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-05-04T20:52:55.991Z,1683233575.991 [Default:A.Wait](INFO): Done Waiting.
2023-05-04T20:52:55.991Z,1683233575.991 [Default:A.Wait] Stopped
2023-05-04T20:52:55.991Z,1683233575.991 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-04T20:52:56.390Z,1683233576.390 [Default:CheckIn] Running Loop=1
2023-05-04T20:52:56.390Z,1683233576.390 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-04T20:52:56.390Z,1683233576.390 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-04T20:53:29.109Z,1683233609.109 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-05-04T20:53:29.184Z,1683233609.184 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-05-04T20:53:52.554Z,1683233632.554 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:54:05.882Z,1683233645.882 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:54:19.214Z,1683233659.214 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:54:32.951Z,1683233672.951 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:54:46.282Z,1683233686.282 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:54:59.614Z,1683233699.614 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:55:13.350Z,1683233713.350 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:55:26.690Z,1683233726.690 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:55:40.014Z,1683233740.014 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:55:53.346Z,1683233753.346 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-04T20:55:56.621Z,1683233756.621 [CommandExec](IMPORTANT): got command quit
2023-05-04T20:55:57.624Z,1683233757.624 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T20:55:57.624Z,1683233757.624 [CommandExec](INFO): Uninitializing the command executive.
2023-05-04T20:55:57.624Z,1683233757.624 [CommandExec](INFO): Uninitializing the command scheduler.
2023-05-04T20:55:57.625Z,1683233757.625 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:55:57.820Z,1683233757.820 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-05-04T20:55:57.820Z,1683233757.820 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-05-04T20:55:57.820Z,1683233757.820 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:55:57.821Z,1683233757.821 [NavChartDb](INFO): Join timeout helper Thread ID is 3773
2023-05-04T20:55:57.908Z,1683233757.908 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T20:55:57.908Z,1683233757.908 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:55:57.928Z,1683233757.928 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-05-04T20:55:57.928Z,1683233757.928 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:55:57.929Z,1683233757.929 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3774
2023-05-04T20:55:57.957Z,1683233757.957 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T20:55:57.957Z,1683233757.957 [WetLabsBB2FL](INFO): Powering down
2023-05-04T20:55:57.958Z,1683233757.958 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:55:57.977Z,1683233757.977 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-05-04T20:55:57.977Z,1683233757.977 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:55:57.977Z,1683233757.977 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3775
2023-05-04T20:55:58.720Z,1683233758.720 [CTD_Seabird](INFO): Powering down
2023-05-04T20:55:58.732Z,1683233758.732 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T20:55:58.732Z,1683233758.732 [CTD_Seabird](INFO): Powering down
2023-05-04T20:55:58.744Z,1683233758.744 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:55:58.744Z,1683233758.744 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler
2023-05-04T20:55:58.744Z,1683233758.744 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:55:58.745Z,1683233758.745 [DATMMP](INFO): Join timeout helper Thread ID is 3776
2023-05-04T20:55:58.964Z,1683233758.964 [DATMMP](INFO): Stop
2023-05-04T20:55:58.964Z,1683233758.964 [DATMMP](INFO): uninitialize
2023-05-04T20:55:58.964Z,1683233758.964 [DATMMP](INFO): Powering down
2023-05-04T20:55:59.033Z,1683233759.033 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T20:55:59.033Z,1683233759.033 [DATMMP](INFO): uninitialize
2023-05-04T20:55:59.033Z,1683233759.033 [DATMMP](INFO): Powering down
2023-05-04T20:55:59.034Z,1683233759.034 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:55:59.048Z,1683233759.048 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-05-04T20:55:59.048Z,1683233759.048 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:55:59.049Z,1683233759.049 [Radio_Surface](INFO): Join timeout helper Thread ID is 3777
2023-05-04T20:55:59.132Z,1683233759.132 [Radio_Surface](INFO): Powering down
2023-05-04T20:55:59.133Z,1683233759.133 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T20:55:59.133Z,1683233759.133 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:55:59.153Z,1683233759.153 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-05-04T20:55:59.153Z,1683233759.153 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:55:59.153Z,1683233759.153 [Onboard](INFO): Join timeout helper Thread ID is 3778
2023-05-04T20:56:03.060Z,1683233763.060 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T20:56:03.061Z,1683233763.061 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:56:03.065Z,1683233763.065 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-05-04T20:56:03.065Z,1683233763.065 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:56:03.065Z,1683233763.065 [DataOverHttps](INFO): Join timeout helper Thread ID is 3779
2023-05-04T20:56:03.301Z,1683233763.301 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T20:56:03.304Z,1683233763.304 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:56:03.325Z,1683233763.325 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-05-04T20:56:03.325Z,1683233763.325 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:56:03.325Z,1683233763.325 [BackseatComponent](INFO): Join timeout helper Thread ID is 3780
2023-05-04T20:56:03.392Z,1683233763.392 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T20:56:03.392Z,1683233763.392 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:56:03.405Z,1683233763.405 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-05-04T20:56:03.405Z,1683233763.405 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:56:03.405Z,1683233763.405 [logger](INFO): Join timeout helper Thread ID is 3781
2023-05-04T20:56:03.432Z,1683233763.432 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T20:56:03.433Z,1683233763.433 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:56:03.434Z,1683233763.434 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-05-04T20:56:03.434Z,1683233763.434 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:56:03.435Z,1683233763.435 [CommandLine](INFO): Join timeout helper Thread ID is 3782
2023-05-04T20:56:03.484Z,1683233763.484 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T20:56:03.484Z,1683233763.484 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:56:03.501Z,1683233763.501 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-05-04T20:56:03.501Z,1683233763.501 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:56:03.501Z,1683233763.501 [CommandExec](INFO): Join timeout helper Thread ID is 3783
2023-05-04T20:56:03.502Z,1683233763.502 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-05-04T20:56:03.502Z,1683233763.502 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:56:03.503Z,1683233763.503 [controlThread](INFO): Join timeout helper Thread ID is 3784
2023-05-04T20:56:03.820Z,1683233763.820 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-04T20:56:03.821Z,1683233763.821 [controlThread](DEBUG): Uninitializing ControlThread
2023-05-04T20:56:03.821Z,1683233763.821 [AHRS_M2](INFO): Powering down
2023-05-04T20:56:03.893Z,1683233763.893 [NAL9602](INFO): Powering down
2023-05-04T20:56:03.895Z,1683233763.895 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-05-04T20:56:03.896Z,1683233763.896 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-05-04T20:56:03.897Z,1683233763.897 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-05-04T20:56:03.897Z,1683233763.897 [MissionManager](INFO): Uninitializing Mission Default
2023-05-04T20:56:03.898Z,1683233763.898 [Default] Stopped
2023-05-04T20:56:03.898Z,1683233763.898 [Default](DEBUG): Aggregate::uninitialize Default
2023-05-04T20:56:03.898Z,1683233763.898 [Default:B.GoToSurface] Stopped
2023-05-04T20:56:03.898Z,1683233763.898 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-04T20:56:03.898Z,1683233763.898 [Default:CheckIn] Stopped
2023-05-04T20:56:03.898Z,1683233763.898 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-04T20:56:03.898Z,1683233763.898 [Default:CheckIn:Read_GPS] Stopped
2023-05-04T20:56:03.901Z,1683233763.901 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-05-04T20:56:03.901Z,1683233763.901 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-05-04T20:56:03.901Z,1683233763.901 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-05-04T20:56:03.902Z,1683233763.902 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-05-04T20:56:03.902Z,1683233763.902 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-05-04T20:56:03.902Z,1683233763.902 [BuoyancyServo](INFO): Powering down
2023-05-04T20:56:03.916Z,1683233763.916 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-05-04T20:56:03.916Z,1683233763.916 [ElevatorServo](INFO): Powering down
2023-05-04T20:56:03.917Z,1683233763.917 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-05-04T20:56:03.917Z,1683233763.917 [MassServo](INFO): Powering down
2023-05-04T20:56:03.918Z,1683233763.918 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-05-04T20:56:03.918Z,1683233763.918 [RudderServo](INFO): Powering down
2023-05-04T20:56:03.919Z,1683233763.919 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-05-04T20:56:03.919Z,1683233763.919 [ThrusterHE](INFO): Powering down
2023-05-04T20:56:03.920Z,1683233763.920 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-05-04T20:56:03.920Z,1683233763.920 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-05-04T20:56:03.921Z,1683233763.921 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-05-04T20:56:03.921Z,1683233763.921 [CBIT](DEBUG): Powering off loads.
2023-05-04T20:56:03.932Z,1683233763.932 [CBIT](DEBUG): Disabling WDT.
2023-05-04T20:56:03.944Z,1683233763.944 [CBIT](DEBUG): Opening all GF detection circuits.
2023-05-04T20:56:03.945Z,1683233763.945 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:56:03.999Z,1683233763.999 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:56:04.004Z,1683233764.004 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:56:04.006Z,1683233764.006 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:56:04.012Z,1683233764.012 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:56:04.055Z,1683233764.055 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:56:04.092Z,1683233764.092 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:56:04.097Z,1683233764.097 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:56:04.129Z,1683233764.129 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-04T20:56:04.191Z,1683233764.191 [logger ThreadHandler](INFO): Thread cancelled.