2023-05-03T21:46:26.962Z,1683150386.962 [Supervisor](DEBUG): Initializing supervisor.
2023-05-03T21:46:26.967Z,1683150386.967 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-05-03T21:46:26.967Z,1683150386.967 [SyncHandler](INFO): Protected caller Thread ID is 1746
2023-05-03T21:46:26.968Z,1683150386.968 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-05-03T21:46:26.969Z,1683150386.969 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-05-03T21:46:26.970Z,1683150386.970 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1747
2023-05-03T21:46:26.974Z,1683150386.974 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-05-03T21:46:26.991Z,1683150386.991 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-05-03T21:46:26.992Z,1683150386.992 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-05-03T21:46:26.992Z,1683150386.992 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1748
2023-05-03T21:46:26.996Z,1683150386.996 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-05-03T21:46:26.997Z,1683150386.997 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-05-03T21:46:26.998Z,1683150386.998 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1749
2023-05-03T21:46:26.000Z,1683150387.000 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-05-03T21:46:27.001Z,1683150387.001 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-05-03T21:46:27.001Z,1683150387.001 [logger ThreadHandler](INFO): Protected caller Thread ID is 1750
2023-05-03T21:46:27.005Z,1683150387.005 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-05-03T21:46:27.005Z,1683150387.005 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-05-03T21:46:27.007Z,1683150387.007 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-05-03T21:46:27.456Z,1683150387.456 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-05-03T21:46:27.457Z,1683150387.457 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-05-03T21:46:27.663Z,1683150387.663 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-05-03T21:46:27.663Z,1683150387.663 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-05-03T21:46:27.744Z,1683150387.744 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-05-03T21:46:27.956Z,1683150387.956 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-05-03T21:46:27.956Z,1683150387.956 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-05-03T21:46:28.034Z,1683150388.034 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-05-03T21:46:28.129Z,1683150388.129 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-05-03T21:46:28.130Z,1683150388.130 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-05-03T21:46:28.454Z,1683150388.454 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-05-03T21:46:28.454Z,1683150388.454 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-05-03T21:46:28.583Z,1683150388.583 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-05-03T21:46:28.584Z,1683150388.584 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-05-03T21:46:29.065Z,1683150389.065 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-05-03T21:46:29.066Z,1683150389.066 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-05-03T21:46:29.243Z,1683150389.243 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-05-03T21:46:29.244Z,1683150389.244 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-05-03T21:46:29.627Z,1683150389.627 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-05-03T21:46:29.628Z,1683150389.628 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-05-03T21:46:29.900Z,1683150389.900 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-05-03T21:46:29.900Z,1683150389.900 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-05-03T21:46:30.008Z,1683150390.008 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-05-03T21:46:30.009Z,1683150390.009 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-05-03T21:46:30.758Z,1683150390.758 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-05-03T21:46:30.759Z,1683150390.759 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-05-03T21:46:31.548Z,1683150391.548 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-05-03T21:46:31.549Z,1683150391.549 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-05-03T21:46:32.029Z,1683150392.029 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-05-03T21:46:32.031Z,1683150392.031 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2023-05-03T21:46:32.032Z,1683150392.032 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2023-05-03T21:46:32.268Z,1683150392.268 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2023-05-03T21:46:32.438Z,1683150392.438 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2023-05-03T21:46:32.741Z,1683150392.741 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2023-05-03T21:46:32.977Z,1683150392.977 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2023-05-03T21:46:33.236Z,1683150393.236 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-05-03T21:46:33.236Z,1683150393.236 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2023-05-03T21:46:33.339Z,1683150393.339 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2023-05-03T21:46:33.449Z,1683150393.449 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2023-05-03T21:46:33.561Z,1683150393.561 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2023-05-03T21:46:33.707Z,1683150393.707 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2023-05-03T21:46:33.805Z,1683150393.805 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2023-05-03T21:46:33.898Z,1683150393.898 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2023-05-03T21:46:33.898Z,1683150393.898 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-05-03T21:46:33.910Z,1683150393.910 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-05-03T21:46:33.921Z,1683150393.921 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-05-03T21:46:33.921Z,1683150393.921 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-05-03T21:46:34.018Z,1683150394.018 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-05-03T21:46:34.018Z,1683150394.018 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-05-03T21:46:34.067Z,1683150394.067 [VerticalControl](DEBUG): Construct VerticalControl.
2023-05-03T21:46:34.126Z,1683150394.126 [VerticalControl] Loaded
2023-05-03T21:46:34.126Z,1683150394.126 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-05-03T21:46:34.129Z,1683150394.129 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-05-03T21:46:34.169Z,1683150394.169 [HorizontalControl] Loaded
2023-05-03T21:46:34.170Z,1683150394.170 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-05-03T21:46:34.172Z,1683150394.172 [SpeedControl](DEBUG): Construct SpeedControl.
2023-05-03T21:46:34.175Z,1683150394.175 [SpeedControl] Loaded
2023-05-03T21:46:34.175Z,1683150394.175 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-05-03T21:46:34.178Z,1683150394.178 [LoopControl](DEBUG): Construct LoopControl.
2023-05-03T21:46:34.178Z,1683150394.178 [LoopControl] Loaded
2023-05-03T21:46:34.179Z,1683150394.179 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-05-03T21:46:34.179Z,1683150394.179 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-05-03T21:46:34.179Z,1683150394.179 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-05-03T21:46:34.332Z,1683150394.332 [BuoyancyServo] Loaded
2023-05-03T21:46:34.332Z,1683150394.332 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-05-03T21:46:34.352Z,1683150394.352 [ElevatorServo] Loaded
2023-05-03T21:46:34.352Z,1683150394.352 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-05-03T21:46:34.370Z,1683150394.370 [MassServo] Loaded
2023-05-03T21:46:34.371Z,1683150394.371 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-05-03T21:46:34.389Z,1683150394.389 [RudderServo] Loaded
2023-05-03T21:46:34.389Z,1683150394.389 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-05-03T21:46:34.403Z,1683150394.403 [ThrusterHE] Loaded
2023-05-03T21:46:34.403Z,1683150394.403 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-05-03T21:46:34.404Z,1683150394.404 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-05-03T21:46:34.404Z,1683150394.404 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-05-03T21:46:34.453Z,1683150394.453 [DepthRateCalculator] Loaded
2023-05-03T21:46:34.453Z,1683150394.453 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-05-03T21:46:34.458Z,1683150394.458 [PitchRateCalculator] Loaded
2023-05-03T21:46:34.458Z,1683150394.458 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-05-03T21:46:34.468Z,1683150394.468 [SpeedCalculator] Loaded
2023-05-03T21:46:34.468Z,1683150394.468 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-05-03T21:46:34.472Z,1683150394.472 [YawRateCalculator] Loaded
2023-05-03T21:46:34.472Z,1683150394.472 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-05-03T21:46:34.491Z,1683150394.491 [ElevatorOffsetCalculator] Loaded
2023-05-03T21:46:34.491Z,1683150394.491 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-05-03T21:46:34.492Z,1683150394.492 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-05-03T21:46:34.492Z,1683150394.492 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-05-03T21:46:34.602Z,1683150394.602 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-05-03T21:46:34.602Z,1683150394.602 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-05-03T21:46:34.616Z,1683150394.616 [NavChart] Loaded
2023-05-03T21:46:34.616Z,1683150394.616 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-05-03T21:46:34.622Z,1683150394.622 [UniversalFixResidualReporter] Loaded
2023-05-03T21:46:34.622Z,1683150394.622 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-05-03T21:46:34.622Z,1683150394.622 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-05-03T21:46:34.623Z,1683150394.623 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-05-03T21:46:35.041Z,1683150395.041 [AHRS_M2] Loaded
2023-05-03T21:46:35.042Z,1683150395.042 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-05-03T21:46:35.077Z,1683150395.077 [BackseatComponent] Loaded
2023-05-03T21:46:35.077Z,1683150395.077 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-05-03T21:46:35.078Z,1683150395.078 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408E74E0
2023-05-03T21:46:35.078Z,1683150395.078 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1832
2023-05-03T21:46:35.081Z,1683150395.081 [LcmUniversalReporter] Loaded
2023-05-03T21:46:35.081Z,1683150395.081 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-05-03T21:46:35.902Z,1683150395.902 [BPC1] Loaded
2023-05-03T21:46:35.903Z,1683150395.903 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-05-03T21:46:35.974Z,1683150395.974 [DataOverHttps] Loaded
2023-05-03T21:46:35.974Z,1683150395.974 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-05-03T21:46:35.975Z,1683150395.975 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409174E0
2023-05-03T21:46:35.975Z,1683150395.975 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1833
2023-05-03T21:46:35.996Z,1683150395.996 [Depth_Keller] Loaded
2023-05-03T21:46:35.996Z,1683150395.996 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-05-03T21:46:36.057Z,1683150396.057 [NAL9602] Loaded
2023-05-03T21:46:36.057Z,1683150396.057 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-05-03T21:46:36.087Z,1683150396.087 [Onboard] Loaded
2023-05-03T21:46:36.087Z,1683150396.087 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-05-03T21:46:36.088Z,1683150396.088 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409474E0
2023-05-03T21:46:36.088Z,1683150396.088 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1834
2023-05-03T21:46:36.100Z,1683150396.100 [Power24vConverter] Loaded
2023-05-03T21:46:36.100Z,1683150396.100 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-05-03T21:46:36.114Z,1683150396.114 [Radio_Surface] Loaded
2023-05-03T21:46:36.114Z,1683150396.114 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-05-03T21:46:36.115Z,1683150396.115 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409774E0
2023-05-03T21:46:36.115Z,1683150396.115 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1835
2023-05-03T21:46:36.201Z,1683150396.201 [DATMMP] Loaded
2023-05-03T21:46:36.201Z,1683150396.201 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2023-05-03T21:46:36.202Z,1683150396.202 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 409A84E0
2023-05-03T21:46:36.202Z,1683150396.202 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 1836
2023-05-03T21:46:36.203Z,1683150396.203 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-05-03T21:46:36.203Z,1683150396.203 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-05-03T21:46:36.274Z,1683150396.274 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-05-03T21:46:36.275Z,1683150396.275 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-05-03T21:46:36.421Z,1683150396.421 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-05-03T21:46:36.421Z,1683150396.421 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-05-03T21:46:36.447Z,1683150396.447 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-05-03T21:46:36.447Z,1683150396.447 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-05-03T21:46:36.612Z,1683150396.612 [CTD_Seabird] Loaded
2023-05-03T21:46:36.612Z,1683150396.612 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-05-03T21:46:36.613Z,1683150396.613 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ADD4E0
2023-05-03T21:46:36.614Z,1683150396.614 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1837
2023-05-03T21:46:36.646Z,1683150396.646 [ESPComponent] Loaded
2023-05-03T21:46:36.646Z,1683150396.646 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-05-03T21:46:36.666Z,1683150396.666 [PAR_Licor] Loaded
2023-05-03T21:46:36.666Z,1683150396.666 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-05-03T21:46:36.736Z,1683150396.736 [WetLabsBB2FL] Loaded
2023-05-03T21:46:36.737Z,1683150396.737 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-05-03T21:46:36.738Z,1683150396.738 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B0D4E0
2023-05-03T21:46:36.738Z,1683150396.738 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1838
2023-05-03T21:46:36.739Z,1683150396.739 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-05-03T21:46:36.739Z,1683150396.739 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-05-03T21:46:37.487Z,1683150397.487 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-05-03T21:46:37.487Z,1683150397.487 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-05-03T21:46:37.623Z,1683150397.623 [SBIT](DEBUG): Construct Startup Built In Test.
2023-05-03T21:46:37.633Z,1683150397.633 [SBIT] Loaded
2023-05-03T21:46:37.633Z,1683150397.633 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-05-03T21:46:37.636Z,1683150397.636 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-05-03T21:46:37.649Z,1683150397.649 [IBIT] Loaded
2023-05-03T21:46:37.649Z,1683150397.649 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-05-03T21:46:37.655Z,1683150397.655 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-05-03T21:46:37.760Z,1683150397.760 [CBIT] Loaded
2023-05-03T21:46:37.760Z,1683150397.760 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-05-03T21:46:37.761Z,1683150397.761 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-05-03T21:46:37.767Z,1683150397.767 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-05-03T21:46:37.770Z,1683150397.770 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-05-03T21:46:37.780Z,1683150397.780 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-05-03T21:46:37.781Z,1683150397.781 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BF04E0
2023-05-03T21:46:37.782Z,1683150397.782 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1839
2023-05-03T21:46:37.786Z,1683150397.786 [Supervisor](INFO): Main Thread ID is 829
2023-05-03T21:46:37.786Z,1683150397.786 [Supervisor](DEBUG): Running supervisor.
2023-05-03T21:46:37.787Z,1683150397.787 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1840
2023-05-03T21:46:37.787Z,1683150397.787 [CommandExec](INFO): Initializing the command executive.
2023-05-03T21:46:37.789Z,1683150397.789 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1841
2023-05-03T21:46:37.791Z,1683150397.791 [controlThread ThreadHandler](INFO): Handler Thread ID is 1842
2023-05-03T21:46:37.791Z,1683150397.791 [controlThread](DEBUG): Initializing ControlThread
2023-05-03T21:46:37.792Z,1683150397.792 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-05-03T21:46:37.794Z,1683150397.794 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-05-03T21:46:37.795Z,1683150397.795 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-05-03T21:46:37.795Z,1683150397.795 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-05-03T21:46:37.797Z,1683150397.797 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-05-03T21:46:37.797Z,1683150397.797 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-05-03T21:46:37.797Z,1683150397.797 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-05-03T21:46:37.798Z,1683150397.798 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-05-03T21:46:37.798Z,1683150397.798 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-05-03T21:46:37.799Z,1683150397.799 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-05-03T21:46:37.800Z,1683150397.800 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-05-03T21:46:37.808Z,1683150397.808 [SBIT](INFO): Initialize SBIT Component.
2023-05-03T21:46:37.809Z,1683150397.809 [SBIT](IMPORTANT): git: 2023-01-24-38-g3a865bb8c
2023-05-03T21:46:37.809Z,1683150397.809 [SBIT](INFO): git hash: 3a865bb8cac9b788bcd3aa40e502ce5ce0bbf34f
2023-05-03T21:46:37.809Z,1683150397.809 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-05-03T21:46:37.810Z,1683150397.810 [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-03T21:46:37.811Z,1683150397.811 [SBIT](INFO): Beginning SBIT in 81.000000 seconds.
2023-05-03T21:46:37.812Z,1683150397.812 [IBIT](INFO): Initialize IBIT Component.
2023-05-03T21:46:37.813Z,1683150397.813 [CBIT](DEBUG): Initialize CBIT Component.
2023-05-03T21:46:37.814Z,1683150397.814 [logger ThreadHandler](INFO): Handler Thread ID is 1843
2023-05-03T21:46:37.824Z,1683150397.824 [CBIT](DEBUG): Initialized mux pins.
2023-05-03T21:46:37.824Z,1683150397.824 [CBIT](DEBUG): Initializing the watchdog timer.
2023-05-03T21:46:37.832Z,1683150397.832 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1844
2023-05-03T21:46:37.844Z,1683150397.844 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1845
2023-05-03T21:46:37.845Z,1683150397.845 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-05-03T21:46:37.848Z,1683150397.848 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-05-03T21:46:37.848Z,1683150397.848 [CBIT](DEBUG): Initializing heartbeat.
2023-05-03T21:46:37.856Z,1683150397.856 [Onboard ThreadHandler](INFO): Handler Thread ID is 1846
2023-05-03T21:46:37.877Z,1683150397.877 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1847
2023-05-03T21:46:37.892Z,1683150397.892 [DATMMP ThreadHandler](INFO): Handler Thread ID is 1848
2023-05-03T21:46:37.893Z,1683150397.893 [DATMMP](INFO): Start
2023-05-03T21:46:37.893Z,1683150397.893 [DATMMP](INFO): Powering up
2023-05-03T21:46:37.893Z,1683150397.893 [DATMMP](DEBUG): Initializing DATMMP.
2023-05-03T21:46:37.897Z,1683150397.897 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1850
2023-05-03T21:46:37.898Z,1683150397.898 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-05-03T21:46:37.901Z,1683150397.901 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1852
2023-05-03T21:46:37.904Z,1683150397.904 [WetLabsBB2FL](INFO): Powering up
2023-05-03T21:46:37.906Z,1683150397.906 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1854
2023-05-03T21:46:37.909Z,1683150397.909 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-05-03T21:46:37.909Z,1683150397.909 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-05-03T21:46:37.909Z,1683150397.909 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-05-03T21:46:37.909Z,1683150397.909 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-05-03T21:46:37.909Z,1683150397.909 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-05-03T21:46:37.909Z,1683150397.909 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-05-03T21:46:37.910Z,1683150397.910 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-05-03T21:46:37.910Z,1683150397.910 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-05-03T21:46:37.920Z,1683150397.920 [CBIT](DEBUG): Deactivating GF circuits.
2023-05-03T21:46:37.921Z,1683150397.921 [CBIT](DEBUG): Deactivating emergency mode.
2023-05-03T21:46:37.956Z,1683150397.956 [CBIT](DEBUG): Backplane powered.
2023-05-03T21:46:37.957Z,1683150397.957 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-05-03T21:46:37.970Z,1683150397.970 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-03T21:46:37.989Z,1683150397.989 [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-03T21:46:37.990Z,1683150397.990 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-05-03T21:46:38.064Z,1683150398.064 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-05-03T21:46:38.066Z,1683150398.066 [Default:A.Wait](DEBUG): Construct Wait.
2023-05-03T21:46:38.088Z,1683150398.088 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-03T21:46:38.121Z,1683150398.121 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-05-03T21:46:38.145Z,1683150398.145 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-05-03T21:46:38.150Z,1683150398.150 [Default:E.Execute](DEBUG): Construct Execute.
2023-05-03T21:46:38.170Z,1683150398.170 [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-03T21:46:38.174Z,1683150398.174 [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-03T21:46:38.197Z,1683150398.197 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-05-03T21:46:38.274Z,1683150398.274 [Power24vConverter](INFO): Powering up.
2023-05-03T21:46:38.280Z,1683150398.280 [Radio_Surface](INFO): Powering up
2023-05-03T21:46:38.342Z,1683150398.342 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-05-03T21:46:38.348Z,1683150398.348 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-05-03T21:46:38.349Z,1683150398.349 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-05-03T21:46:38.357Z,1683150398.357 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-05-03T21:46:38.358Z,1683150398.358 [MassServo](DEBUG): Initializing EZServoServo.
2023-05-03T21:46:38.365Z,1683150398.365 [MassServo](DEBUG): Initializing MassServo.
2023-05-03T21:46:38.365Z,1683150398.365 [RudderServo](DEBUG): Initializing EZServoServo.
2023-05-03T21:46:38.372Z,1683150398.372 [RudderServo](DEBUG): Initializing RudderServo.
2023-05-03T21:46:38.373Z,1683150398.373 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-05-03T21:46:38.380Z,1683150398.380 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-05-03T21:46:39.336Z,1683150399.336 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2023-05-03T21:46:39.336Z,1683150399.336 [RudderServo](FAULT): Rudder failed to initialize
2023-05-03T21:46:39.336Z,1683150399.336 [RudderServo] Communications Fault, FailCount= 1
2023-05-03T21:46:39.336Z,1683150399.336 [RudderServo](ERROR): Communications Fault
2023-05-03T21:46:39.340Z,1683150399.340 [CBIT](ERROR): Communications Fault in component: RudderServo
2023-05-03T21:46:39.577Z,1683150399.577 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-05-03T21:46:39.577Z,1683150399.577 [RudderServo](INFO): Powering down
2023-05-03T21:46:40.012Z,1683150400.012 [WetLabsBB2FL](INFO): Powering down
2023-05-03T21:46:40.224Z,1683150400.224 [RudderServo](DEBUG): Initializing EZServoServo.
2023-05-03T21:46:40.345Z,1683150400.345 [RudderServo](DEBUG): Initializing RudderServo.
2023-05-03T21:46:40.349Z,1683150400.349 [CBIT](INFO): Clearing failed state for component RudderServo
2023-05-03T21:46:40.349Z,1683150400.349 [RudderServo] No Fault, FailCount= 1
2023-05-03T21:46:44.233Z,1683150404.233 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-05-03T21:46:49.988Z,1683150409.988 [DATMMP](INFO): DAT read: 

2023-05-03T21:46:49.989Z,1683150409.989 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-05-03T21:46:51.753Z,1683150411.753 [DATMMP](INFO): DAT read: MF Frequency Band

2023-05-03T21:46:51.754Z,1683150411.754 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-05-03T21:46:51.754Z,1683150411.754 [DATMMP](INFO): DAT read: Apr  3 2000 21:46:46 

2023-05-03T21:46:53.013Z,1683150413.013 [DATMMP](INFO): DAT read: Features enabled [Bearing]

2023-05-03T21:46:53.014Z,1683150413.014 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-05-03T21:46:53.014Z,1683150413.014 [DATMMP](INFO): commRate: 800
2023-05-03T21:46:55.080Z,1683150415.080 [DATMMP](INFO): entering command mode
2023-05-03T21:46:55.280Z,1683150415.280 [DATMMP](INFO): DAT read: 

2023-05-03T21:46:55.281Z,1683150415.281 [DATMMP](INFO): DAT read: user:1>

2023-05-03T21:46:55.281Z,1683150415.281 [DATMMP](DEBUG): read user prompt 1: user:1>

2023-05-03T21:46:55.282Z,1683150415.282 [DATMMP](INFO): entering MMP mode
2023-05-03T21:46:55.533Z,1683150415.533 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:46:55.534Z,1683150415.534 [DATMMP](INFO): status rx: 	 x1
2023-05-03T21:46:55.535Z,1683150415.535 [DATMMP](INFO): MMP status: 1: started
2023-05-03T21:46:55.535Z,1683150415.535 [DATMMP](INFO): Received message type: status
2023-05-03T21:46:55.535Z,1683150415.535 [DATMMP](INFO): Handled
2023-05-03T21:46:55.785Z,1683150415.785 [DATMMP](INFO): Sent Tx Power Config 8
2023-05-03T21:46:55.786Z,1683150415.786 [DATMMP](INFO): Setting local address: 5
2023-05-03T21:46:55.787Z,1683150415.787 [DATMMP](INFO): Setting time to: 21:46:55 And date to:5/3/2023
2023-05-03T21:46:56.036Z,1683150416.036 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:46:56.037Z,1683150416.037 [DATMMP](INFO): mdmpower rx: 	 x8	 x0	 x5	 x0
2023-05-03T21:46:56.037Z,1683150416.037 [DATMMP](INFO): Received message type: mdmpower
2023-05-03T21:46:56.038Z,1683150416.038 [DATMMP](INFO): Handled
2023-05-03T21:46:56.288Z,1683150416.288 [DATMMP](INFO): GSXN notify for xid: 10
2023-05-03T21:46:56.289Z,1683150416.289 [DATMMP](INFO): sreg_txpower rx: 	 x8
2023-05-03T21:46:56.289Z,1683150416.289 [DATMMP](INFO): Received message type: sreg_txpower
2023-05-03T21:46:56.289Z,1683150416.289 [DATMMP](INFO): Match for : sreg_txpower
2023-05-03T21:46:56.289Z,1683150416.289 [DATMMP](INFO): ACK
2023-05-03T21:46:56.540Z,1683150416.540 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:46:56.541Z,1683150416.541 [DATMMP](INFO): mdmlocal rx: 	 x5	 x0	 x0	 x0
2023-05-03T21:46:56.541Z,1683150416.541 [DATMMP](INFO): Received message type: mdmlocal
2023-05-03T21:46:56.542Z,1683150416.542 [DATMMP](INFO): Handled
2023-05-03T21:46:56.792Z,1683150416.792 [DATMMP](INFO): GSXN notify for xid: 11
2023-05-03T21:46:56.793Z,1683150416.793 [DATMMP](INFO): sreg_locaddr rx: 	 x5
2023-05-03T21:46:56.793Z,1683150416.793 [DATMMP](INFO): Received message type: sreg_locaddr
2023-05-03T21:46:56.793Z,1683150416.793 [DATMMP](INFO): Match for : sreg_locaddr
2023-05-03T21:46:56.793Z,1683150416.793 [DATMMP](INFO): ACK
2023-05-03T21:46:57.045Z,1683150417.045 [DATMMP](INFO): GSXN notify for xid: 12
2023-05-03T21:46:57.046Z,1683150417.046 [DATMMP](INFO): timedate rx: 	 x0	 x15	 x2E	 x37	 x4	 x3	 x7B	 x0
2023-05-03T21:46:57.046Z,1683150417.046 [DATMMP](INFO): Received message type: timedate
2023-05-03T21:46:57.046Z,1683150417.046 [DATMMP](INFO): Match for : timedate
2023-05-03T21:46:57.047Z,1683150417.047 [DATMMP](INFO): ACK
2023-05-03T21:46:57.297Z,1683150417.297 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:46:57.298Z,1683150417.298 [DATMMP](INFO): sys_verb rx: 	 x0	 x0	 x0	 x0
2023-05-03T21:46:57.298Z,1683150417.298 [DATMMP](INFO): Received message type: sys_verb
2023-05-03T21:46:57.298Z,1683150417.298 [DATMMP](INFO): Handled
2023-05-03T21:46:57.548Z,1683150417.548 [DATMMP](INFO): GSXN notify for xid: 13
2023-05-03T21:46:57.549Z,1683150417.549 [DATMMP](INFO): sreg_verbosity rx: 	 x0
2023-05-03T21:46:57.549Z,1683150417.549 [DATMMP](INFO): Received message type: sreg_verbosity
2023-05-03T21:46:57.549Z,1683150417.549 [DATMMP](INFO): Match for : sreg_verbosity
2023-05-03T21:46:57.549Z,1683150417.549 [DATMMP](INFO): ACK
2023-05-03T21:46:57.800Z,1683150417.800 [DATMMP](INFO): GSXN notify for xid: 14
2023-05-03T21:46:57.801Z,1683150417.801 [DATMMP](INFO): version rx: 	 x2	 x5
2023-05-03T21:46:57.801Z,1683150417.801 [DATMMP](INFO): DatMMPVersion: 2.5
2023-05-03T21:46:57.802Z,1683150417.802 [DATMMP](INFO): Received message type: version
2023-05-03T21:46:57.802Z,1683150417.802 [DATMMP](INFO): Match for : version
2023-05-03T21:46:57.802Z,1683150417.802 [DATMMP](INFO): ACK
2023-05-03T21:46:57.802Z,1683150417.802 [DATMMP](INFO): in MMP mode: config complete going to runnable
2023-05-03T21:47:06.129Z,1683150426.129 [NAL9602](INFO): Powering up NAL9602
2023-05-03T21:47:11.417Z,1683150431.417 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:47:11.418Z,1683150431.418 [DATMMP](INFO): timesync rx: 	 x0	 x1	 x1	 x0
2023-05-03T21:47:11.418Z,1683150431.418 [DATMMP](INFO): Received message type: timesync
2023-05-03T21:47:11.418Z,1683150431.418 [DATMMP](INFO): Handled
2023-05-03T21:47:17.042Z,1683150437.042 [NAL9602](INFO): NAL9602 initialized
2023-05-03T21:47:21.261Z,1683150441.261 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:47:21.261Z,1683150441.261 [DATMMP](INFO): timesync rx: 	 x1	 x0	 x1	 x0
2023-05-03T21:47:21.262Z,1683150441.262 [DATMMP](INFO): Received message type: timesync
2023-05-03T21:47:21.262Z,1683150441.262 [DATMMP](INFO): Handled
2023-05-03T21:47:38.582Z,1683150458.582 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.8 degree
2023-05-03T21:47:38.586Z,1683150458.586 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2023-05-03T21:47:38.586Z,1683150458.586 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.9 degree
2023-05-03T21:47:38.590Z,1683150458.590 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2023-05-03T21:47:38.912Z,1683150458.912 [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-03T21:47:54.426Z,1683150474.426 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1683150458 second
2023-05-03T21:47:59.526Z,1683150479.526 [SBIT](IMPORTANT): Beginning Startup BIT
2023-05-03T21:47:59.540Z,1683150479.540 [CBIT](IMPORTANT): Beginning ground fault scan
2023-05-03T21:48:10.419Z,1683150490.419 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.010593
CHAN A1 (24V): -0.008311
CHAN A2 (12V): -0.004214
CHAN A3 (5V): -0.003498
CHAN B0 (3.3V): -0.001690
CHAN B1 (3.15aV): -0.001382
CHAN B2 (3.15bV): -0.000696
CHAN B3 (GND): -0.000431
OPEN: 0.004531
Full Scale: +/- 1 mA
2023-05-03T21:48:53.257Z,1683150533.257 [SBIT](IMPORTANT): SBIT PASSED
2023-05-03T21:48:53.257Z,1683150533.257 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-05-03T21:48:53.258Z,1683150533.258 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;

2023-05-03T21:48:53.258Z,1683150533.258 [SBIT](IMPORTANT): BackseatComponent.alwaysOn=0 bool;

2023-05-03T21:48:53.258Z,1683150533.258 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2023-05-03T21:48:53.258Z,1683150533.258 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2023-05-03T21:48:53.258Z,1683150533.258 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=10 minute;

2023-05-03T21:48:53.258Z,1683150533.258 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2023-05-03T21:48:53.258Z,1683150533.258 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2023-05-03T21:48:53.259Z,1683150533.259 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water;

2023-05-03T21:48:53.259Z,1683150533.259 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree;

2023-05-03T21:48:53.259Z,1683150533.259 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=184.896478 cubic_centimeter;

2023-05-03T21:48:53.259Z,1683150533.259 [SBIT](IMPORTANT): VerticalControl.elevDeadband=0.1 angular_degree;

2023-05-03T21:48:53.259Z,1683150533.259 [SBIT](IMPORTANT): VerticalControl.massDefault=15.484465 millimeter;

2023-05-03T21:48:53.639Z,1683150533.639 [MissionManager](IMPORTANT): Started mission Startup
2023-05-03T21:48:53.640Z,1683150533.640 [Startup] Running Loop=1
2023-05-03T21:48:53.640Z,1683150533.640 [Startup](DEBUG): Aggregate::initialize Startup
2023-05-03T21:48:53.640Z,1683150533.640 [Startup:A.GoToSurface] Running Loop=1
2023-05-03T21:48:53.640Z,1683150533.640 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-03T21:48:53.641Z,1683150533.641 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-03T21:48:53.641Z,1683150533.641 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-03T21:48:53.642Z,1683150533.642 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-03T21:48:53.642Z,1683150533.642 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-03T21:48:53.642Z,1683150533.642 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-03T21:48:53.644Z,1683150533.644 [Startup:StartupSatComms] Running Loop=1
2023-05-03T21:48:53.644Z,1683150533.644 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-05-03T21:48:53.644Z,1683150533.644 [Startup:StartupSatComms:A] Running Loop=1
2023-05-03T21:48:54.037Z,1683150534.037 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-05-03T21:48:54.038Z,1683150534.038 [Startup:StartupSatComms:A] Stopped
2023-05-03T21:48:54.038Z,1683150534.038 [Startup:StartupSatComms:B] Running Loop=1
2023-05-03T21:48:54.419Z,1683150534.419 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-03T21:49:05.849Z,1683150545.849 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004666
2023-05-03T21:49:06.194Z,1683150546.194 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T21:49:13.168Z,1683150553.168 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230503T212951/Courier0003.lzma
2023-05-03T21:49:14.170Z,1683150554.170 [DataOverHttps](INFO): Moved sent file to Logs/20230503T212951/Courier0003.lzma.bak
2023-05-03T21:49:14.170Z,1683150554.170 [DataOverHttps](INFO): SBD MOMSN=18280248
2023-05-03T21:49:19.453Z,1683150559.453 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T21:49:30.100Z,1683150570.100 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20230503T214626/Courier0000.lzma
2023-05-03T21:49:31.102Z,1683150571.102 [DataOverHttps](INFO): Moved sent file to Logs/20230503T214626/Courier0000.lzma.bak
2023-05-03T21:49:31.102Z,1683150571.102 [DataOverHttps](INFO): SBD MOMSN=18280250
2023-05-03T21:49:32.380Z,1683150572.380 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T21:49:45.712Z,1683150585.712 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T21:49:46.625Z,1683150586.625 [CommandExec](IMPORTANT): got command show stack
2023-05-03T21:49:46.625Z,1683150586.625 [CommandExec](IMPORTANT): Behavior Stack:

2023-05-03T21:49:46.626Z,1683150586.626 [Startup](IMPORTANT): Priority 0: Startup:A.GoToSurface
2023-05-03T21:49:46.626Z,1683150586.626 [Startup:StartupSatComms](IMPORTANT): Priority 1: Startup:StartupSatComms:B
2023-05-03T21:49:48.421Z,1683150588.421 [DataOverHttps](INFO): Sending 295 bytes from file Logs/20230503T212951/Express0004.lzma
2023-05-03T21:49:49.430Z,1683150589.430 [DataOverHttps](INFO): Moved sent file to Logs/20230503T212951/Express0004.lzma.bak
2023-05-03T21:49:49.430Z,1683150589.430 [DataOverHttps](INFO): SBD MOMSN=18280253
2023-05-03T21:49:54.217Z,1683150594.217 [Startup:StartupSatComms:B](INFO): Timed out from 2023-05-03T21:48:54.0Z
2023-05-03T21:49:54.217Z,1683150594.217 [Startup:StartupSatComms:B] Stopped
2023-05-03T21:49:54.218Z,1683150594.218 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-05-03T21:49:54.218Z,1683150594.218 [Startup:StartupSatComms] Stopped
2023-05-03T21:49:54.218Z,1683150594.218 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-05-03T21:49:54.219Z,1683150594.219 [Startup](INFO): Completed Startup
2023-05-03T21:49:54.219Z,1683150594.219 [MissionManager](INFO): Startup is completed.
2023-05-03T21:49:54.219Z,1683150594.219 [MissionManager](INFO): Uninitializing Mission Startup
2023-05-03T21:49:54.219Z,1683150594.219 [Startup] Stopped
2023-05-03T21:49:54.219Z,1683150594.219 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-05-03T21:49:54.219Z,1683150594.219 [Startup:A.GoToSurface] Stopped
2023-05-03T21:49:54.219Z,1683150594.219 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-03T21:49:54.620Z,1683150594.620 [MissionManager](IMPORTANT): Started mission Default
2023-05-03T21:49:54.620Z,1683150594.620 [Default] Running Loop=1
2023-05-03T21:49:54.621Z,1683150594.621 [Default](DEBUG): Aggregate::initialize Default
2023-05-03T21:49:54.621Z,1683150594.621 [Default:B.GoToSurface] Running Loop=1
2023-05-03T21:49:54.621Z,1683150594.621 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-03T21:49:54.621Z,1683150594.621 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-03T21:49:54.621Z,1683150594.621 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-03T21:49:54.622Z,1683150594.622 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-03T21:49:54.622Z,1683150594.622 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-03T21:49:54.622Z,1683150594.622 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-03T21:49:54.622Z,1683150594.622 [Default:A.Wait] Running Loop=1
2023-05-03T21:49:54.623Z,1683150594.623 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-05-03T21:49:59.044Z,1683150599.044 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T21:50:07.002Z,1683150607.002 [CommandExec](IMPORTANT): got command maintain sensor Depth_Keller.depth 0.8 meter
2023-05-03T21:50:07.009Z,1683150607.009 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2023-05-03T21:50:07.205Z,1683150607.205 [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-03T21:50:07.982Z,1683150607.982 [Default:A.Wait](INFO): Done Waiting.
2023-05-03T21:50:07.982Z,1683150607.982 [Default:A.Wait] Stopped
2023-05-03T21:50:07.982Z,1683150607.982 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-03T21:50:08.396Z,1683150608.396 [Default:CheckIn] Running Loop=1
2023-05-03T21:50:08.396Z,1683150608.396 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-03T21:50:08.397Z,1683150608.397 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-03T21:50:08.786Z,1683150608.786 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-05-03T21:50:10.074Z,1683150610.074 [DataOverHttps](INFO): Sending 1186 bytes from file Logs/20230503T214626/Express0001.lzma
2023-05-03T21:50:11.074Z,1683150611.074 [DataOverHttps](INFO): Moved sent file to Logs/20230503T214626/Express0001.lzma.bak
2023-05-03T21:50:11.074Z,1683150611.074 [DataOverHttps](INFO): SBD MOMSN=18280259
2023-05-03T21:50:12.376Z,1683150612.376 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T21:50:15.507Z,1683150615.507 [CommandExec](IMPORTANT): got command run ./Missions/Demo/senddata_direct_and_track_test.xml
2023-05-03T21:50:15.507Z,1683150615.507 [MissionManager](INFO): Loading Mission from file: ./Missions/Demo/senddata_direct_and_track_test.xml
2023-05-03T21:50:15.562Z,1683150615.562 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcousticTargetContactLabel = 6.000000 count
2023-05-03T21:50:15.566Z,1683150615.566 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcousticTargetDepth = 0.000000 m
2023-05-03T21:50:15.569Z,1683150615.569 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.TrackingUpdatePeriod = 15.000000 s
2023-05-03T21:50:15.628Z,1683150615.628 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfPings = 1.000000 count
2023-05-03T21:50:15.631Z,1683150615.631 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfFixesLowPass = 2.000000 count
2023-05-03T21:50:15.634Z,1683150615.634 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfStartingFixesToIgnore = 2.000000 count
2023-05-03T21:50:15.649Z,1683150615.649 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcommsInterval = 1.000000 min
2023-05-03T21:50:15.651Z,1683150615.651 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.modemId1 = 6.000000 enum
2023-05-03T21:50:15.654Z,1683150615.654 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.modemId2 = 5.000000 enum
2023-05-03T21:50:15.681Z,1683150615.681 [MissionManager](INFO): DefineOutput senddata_direct_and_track_test.ModemID = 1.000000 enum
2023-05-03T21:50:15.791Z,1683150615.791 [senddata_direct_and_track_test:DirectData:A.Wait](DEBUG): Construct Wait.
2023-05-03T21:50:15.805Z,1683150615.805 [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-03T21:50:15.807Z,1683150615.807 [CommandExec](IMPORTANT): Running ./Missions/Demo/senddata_direct_and_track_test.xml
2023-05-03T21:50:16.025Z,1683150616.025 [Default] Stopped
2023-05-03T21:50:16.025Z,1683150616.025 [Default](DEBUG): Aggregate::uninitialize Default
2023-05-03T21:50:16.025Z,1683150616.025 [Default:B.GoToSurface] Stopped
2023-05-03T21:50:16.025Z,1683150616.025 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-03T21:50:16.026Z,1683150616.026 [Default:CheckIn] Stopped
2023-05-03T21:50:16.026Z,1683150616.026 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-03T21:50:16.026Z,1683150616.026 [Default:CheckIn:Read_GPS] Stopped
2023-05-03T21:50:16.026Z,1683150616.026 [MissionManager](IMPORTANT): Started mission senddata_direct_and_track_test
2023-05-03T21:50:16.026Z,1683150616.026 [senddata_direct_and_track_test] Running Loop=1
2023-05-03T21:50:16.026Z,1683150616.026 [senddata_direct_and_track_test](DEBUG): Aggregate::initialize senddata_direct_and_track_test
2023-05-03T21:50:16.026Z,1683150616.026 [senddata_direct_and_track_test:A.] Running Loop=1
2023-05-03T21:50:16.026Z,1683150616.026 [senddata_direct_and_track_test:A.](INFO): Initializing Tracking.
2023-05-03T21:50:16.027Z,1683150616.027 [senddata_direct_and_track_test:DirectData] Running Loop=1
2023-05-03T21:50:16.027Z,1683150616.027 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::initialize senddata_direct_and_track_test:DirectData
2023-05-03T21:50:16.027Z,1683150616.027 [senddata_direct_and_track_test:DirectData:A.Wait] Running Loop=1
2023-05-03T21:50:16.027Z,1683150616.027 [senddata_direct_and_track_test:DirectData:A.Wait](DEBUG): Initialize Wait Component.
2023-05-03T21:50:16.027Z,1683150616.027 [senddata_direct_and_track_test:A.] Running Loop=1
2023-05-03T21:50:16.027Z,1683150616.027 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:16.431Z,1683150616.431 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:16.850Z,1683150616.850 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:17.258Z,1683150617.258 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:17.666Z,1683150617.666 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:18.083Z,1683150618.083 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:18.452Z,1683150618.452 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:18.877Z,1683150618.877 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:19.292Z,1683150619.292 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:19.319Z,1683150619.319 [CommandExec](IMPORTANT): got command get depth
2023-05-03T21:50:19.319Z,1683150619.319 [CommandExec](IMPORTANT): depth 0.800000 m
2023-05-03T21:50:19.661Z,1683150619.661 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:20.067Z,1683150620.067 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:20.481Z,1683150620.481 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:20.943Z,1683150620.943 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:21.316Z,1683150621.316 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:21.731Z,1683150621.731 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:22.110Z,1683150622.110 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:22.529Z,1683150622.529 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:22.906Z,1683150622.906 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:23.349Z,1683150623.349 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:23.719Z,1683150623.719 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:24.120Z,1683150624.120 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:24.555Z,1683150624.555 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:24.919Z,1683150624.919 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:25.409Z,1683150625.409 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:25.709Z,1683150625.709 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T21:50:25.777Z,1683150625.777 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:26.169Z,1683150626.169 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:26.569Z,1683150626.569 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:26.939Z,1683150626.939 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:27.367Z,1683150627.367 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:27.463Z,1683150627.463 [CommandExec](IMPORTANT): got command report touch DATMMP.acoustic_contact_range
2023-05-03T21:50:27.811Z,1683150627.811 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:28.182Z,1683150628.182 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:28.581Z,1683150628.581 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:28.967Z,1683150628.967 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:29.355Z,1683150629.355 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:29.759Z,1683150629.759 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:30.167Z,1683150630.167 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:30.583Z,1683150630.583 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:30.970Z,1683150630.970 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:31.380Z,1683150631.380 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:31.381Z,1683150631.381 [senddata_direct_and_track_test:A.](INFO): update period (15.000000 s) has elapsed, querying...
2023-05-03T21:50:31.381Z,1683150631.381 [senddata_direct_and_track_test:A.](INFO): *** querying DAT ***
2023-05-03T21:50:31.624Z,1683150631.624 [DATMMP](INFO): ****** received valid address query ******
2023-05-03T21:50:31.625Z,1683150631.625 [DATMMP](INFO): ****** received valid ping request ******
2023-05-03T21:50:31.625Z,1683150631.625 [DATMMP](INFO): Querying Benthos address 6
2023-05-03T21:50:31.779Z,1683150631.779 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:31.877Z,1683150631.877 [DATMMP](INFO): GSXN notify for xid: 15
2023-05-03T21:50:31.878Z,1683150631.878 [DATMMP](INFO): cmd_result rx: 	 x2	 x19	 x0	 x2
2023-05-03T21:50:31.878Z,1683150631.878 [DATMMP](INFO): Progress on cmd
2023-05-03T21:50:31.878Z,1683150631.878 [DATMMP](INFO): Received message type: cmd_result
2023-05-03T21:50:31.878Z,1683150631.878 [DATMMP](INFO): Handled
2023-05-03T21:50:31.878Z,1683150631.878 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:32.129Z,1683150632.129 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:50:32.130Z,1683150632.130 [DATMMP](INFO): timestamp rx: 	 x0	 x15	 x32	 x1D	 x2618
2023-05-03T21:50:32.130Z,1683150632.130 [DATMMP](INFO): Received message type: timestamp
2023-05-03T21:50:32.130Z,1683150632.130 [DATMMP](INFO): Handled
2023-05-03T21:50:32.130Z,1683150632.130 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:32.210Z,1683150632.210 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:32.381Z,1683150632.381 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:50:32.381Z,1683150632.381 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-03T21:50:32.382Z,1683150632.382 [DATMMP](INFO): Received message type: nav_stat
2023-05-03T21:50:32.382Z,1683150632.382 [DATMMP](INFO): Handled
2023-05-03T21:50:32.382Z,1683150632.382 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:32.605Z,1683150632.605 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:32.632Z,1683150632.632 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:32.884Z,1683150632.884 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:33.010Z,1683150633.010 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:33.137Z,1683150633.137 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:33.388Z,1683150633.388 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:33.423Z,1683150633.423 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:33.640Z,1683150633.640 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:33.830Z,1683150633.830 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:33.892Z,1683150633.892 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:34.144Z,1683150634.144 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:34.215Z,1683150634.215 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:34.397Z,1683150634.397 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:34.612Z,1683150634.612 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:34.661Z,1683150634.661 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:50:34.661Z,1683150634.661 [DATMMP](INFO): doppler rx: 	 xFFFF
2023-05-03T21:50:34.662Z,1683150634.662 [DATMMP](INFO): Received message type: doppler
2023-05-03T21:50:34.662Z,1683150634.662 [DATMMP](INFO): Handled
2023-05-03T21:50:34.662Z,1683150634.662 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:34.913Z,1683150634.913 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:50:34.913Z,1683150634.913 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-03T21:50:34.914Z,1683150634.914 [DATMMP](INFO): Received message type: nav_stat
2023-05-03T21:50:34.914Z,1683150634.914 [DATMMP](INFO): Handled
2023-05-03T21:50:34.914Z,1683150634.914 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:35.020Z,1683150635.020 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:35.165Z,1683150635.165 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:50:35.166Z,1683150635.166 [DATMMP](INFO): directional_debug rx: 	 x3F2	 x14	 x2D	 x59	 x2540	 x2AC1	 x2822	 x33C3
2023-05-03T21:50:35.168Z,1683150635.168 [DATMMP](INFO): Received message type: directional_debug
2023-05-03T21:50:35.168Z,1683150635.168 [DATMMP](INFO): Handled
2023-05-03T21:50:35.169Z,1683150635.169 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:35.419Z,1683150635.419 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:50:35.420Z,1683150635.420 [DATMMP](INFO): directional rx: 	 x3E2	 x17	 x1EA	 xFFEC	 x669	 xFFFD	 x6E32	 x47F	 x7	 xFFEF	 x27B5966	 x-43569E0
2023-05-03T21:50:35.422Z,1683150635.422 [DATMMP](INFO): Received message type: directional
2023-05-03T21:50:35.422Z,1683150635.422 [DATMMP](INFO): Handled
2023-05-03T21:50:35.423Z,1683150635.423 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:35.431Z,1683150635.431 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:35.432Z,1683150635.432 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:35.433Z,1683150635.433 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:35.483Z,1683150635.483 [Reporter](INFO): DATMMP.acoustic_contact_range 28210.000000 m
2023-05-03T21:50:35.669Z,1683150635.669 [DATMMP](INFO): GSXN notify for xid: 15
2023-05-03T21:50:35.670Z,1683150635.670 [DATMMP](INFO): cmd_result rx: 	 x2	 x19	 x1	 x6
2023-05-03T21:50:35.670Z,1683150635.670 [DATMMP](INFO): Progress on cmd
2023-05-03T21:50:35.670Z,1683150635.670 [DATMMP](INFO): Received message type: cmd_result
2023-05-03T21:50:35.670Z,1683150635.670 [DATMMP](INFO): Handled
2023-05-03T21:50:35.670Z,1683150635.670 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:35.831Z,1683150635.831 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:35.831Z,1683150635.831 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:35.832Z,1683150635.832 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:35.921Z,1683150635.921 [DATMMP](INFO): GSXN notify for xid: 15
2023-05-03T21:50:35.921Z,1683150635.921 [DATMMP](INFO): cmd_result rx: 	 x2	 x19	 x2	 x3
2023-05-03T21:50:35.922Z,1683150635.922 [DATMMP](ERROR): 3:COMPLETE
2023-05-03T21:50:35.922Z,1683150635.922 [DATMMP](INFO): Received message type: cmd_result
2023-05-03T21:50:35.922Z,1683150635.922 [DATMMP](INFO): Handled
2023-05-03T21:50:35.922Z,1683150635.922 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:36.173Z,1683150636.173 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T21:50:36.173Z,1683150636.173 [DATMMP](INFO): nav_stat rx: 	 x0	 x1	 x1
2023-05-03T21:50:36.174Z,1683150636.174 [DATMMP](INFO): Received message type: nav_stat
2023-05-03T21:50:36.174Z,1683150636.174 [DATMMP](INFO): Handled
2023-05-03T21:50:36.174Z,1683150636.174 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:36.247Z,1683150636.247 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:36.247Z,1683150636.247 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:36.252Z,1683150636.252 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:36.425Z,1683150636.425 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:36.636Z,1683150636.636 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:36.637Z,1683150636.637 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:36.637Z,1683150636.637 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:36.677Z,1683150636.677 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:36.928Z,1683150636.928 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:37.043Z,1683150637.043 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:37.044Z,1683150637.044 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:37.045Z,1683150637.045 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:37.180Z,1683150637.180 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:37.432Z,1683150637.432 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:37.457Z,1683150637.457 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:37.457Z,1683150637.457 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:37.458Z,1683150637.458 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:37.684Z,1683150637.684 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:37.868Z,1683150637.868 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:37.869Z,1683150637.869 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:37.869Z,1683150637.869 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:37.936Z,1683150637.936 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:38.188Z,1683150638.188 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:38.283Z,1683150638.283 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:38.284Z,1683150638.284 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:38.285Z,1683150638.285 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:38.441Z,1683150638.441 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:38.692Z,1683150638.692 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:38.729Z,1683150638.729 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:38.729Z,1683150638.729 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:38.730Z,1683150638.730 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:38.944Z,1683150638.944 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:39.040Z,1683150639.040 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T21:50:39.078Z,1683150639.078 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:39.078Z,1683150639.078 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:39.079Z,1683150639.079 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:39.196Z,1683150639.196 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:39.448Z,1683150639.448 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:39.488Z,1683150639.488 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:39.489Z,1683150639.489 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:39.490Z,1683150639.490 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:39.701Z,1683150639.701 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:39.879Z,1683150639.879 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:39.880Z,1683150639.880 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:39.881Z,1683150639.881 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:39.952Z,1683150639.952 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:40.205Z,1683150640.205 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:40.301Z,1683150640.301 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:40.302Z,1683150640.302 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:40.302Z,1683150640.302 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:40.457Z,1683150640.457 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:40.694Z,1683150640.694 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:40.695Z,1683150640.695 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:40.695Z,1683150640.695 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:40.708Z,1683150640.708 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:40.960Z,1683150640.960 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:41.212Z,1683150641.212 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:41.213Z,1683150641.213 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:41.214Z,1683150641.214 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:41.214Z,1683150641.214 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:41.464Z,1683150641.464 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:41.500Z,1683150641.500 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:41.501Z,1683150641.501 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:41.501Z,1683150641.501 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:41.716Z,1683150641.716 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:41.908Z,1683150641.908 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:41.912Z,1683150641.912 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:41.913Z,1683150641.913 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:41.969Z,1683150641.969 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-03T21:50:42.317Z,1683150642.317 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:42.317Z,1683150642.317 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:42.318Z,1683150642.318 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:42.696Z,1683150642.696 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:42.697Z,1683150642.697 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:42.697Z,1683150642.697 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:43.125Z,1683150643.125 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:43.125Z,1683150643.125 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:43.126Z,1683150643.126 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:43.525Z,1683150643.525 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:43.526Z,1683150643.526 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:43.527Z,1683150643.527 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:43.931Z,1683150643.931 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:43.932Z,1683150643.932 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:43.937Z,1683150643.937 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:44.325Z,1683150644.325 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:44.325Z,1683150644.325 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:44.326Z,1683150644.326 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:44.711Z,1683150644.711 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:44.712Z,1683150644.712 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:44.713Z,1683150644.713 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:45.110Z,1683150645.110 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:45.110Z,1683150645.110 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:45.111Z,1683150645.111 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:45.515Z,1683150645.515 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:45.516Z,1683150645.516 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:45.517Z,1683150645.517 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:45.924Z,1683150645.924 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:45.925Z,1683150645.925 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:45.925Z,1683150645.925 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:46.329Z,1683150646.329 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:46.329Z,1683150646.329 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:46.330Z,1683150646.330 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:46.726Z,1683150646.726 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:46.727Z,1683150646.727 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:46.727Z,1683150646.727 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:47.135Z,1683150647.135 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:47.136Z,1683150647.136 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:47.137Z,1683150647.137 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:47.535Z,1683150647.535 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:47.536Z,1683150647.536 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:47.537Z,1683150647.537 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:47.948Z,1683150647.948 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:47.948Z,1683150647.948 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:47.949Z,1683150647.949 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:48.363Z,1683150648.363 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:48.364Z,1683150648.364 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:48.365Z,1683150648.365 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:48.780Z,1683150648.780 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:48.781Z,1683150648.781 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:48.782Z,1683150648.782 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:49.173Z,1683150649.173 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:49.173Z,1683150649.173 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:49.174Z,1683150649.174 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:49.570Z,1683150649.570 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:49.570Z,1683150649.570 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:49.571Z,1683150649.571 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:49.959Z,1683150649.959 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:49.960Z,1683150649.960 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:49.977Z,1683150649.977 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:50.363Z,1683150650.363 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:50.363Z,1683150650.363 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:50.364Z,1683150650.364 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:50.771Z,1683150650.771 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:50.772Z,1683150650.772 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:50.772Z,1683150650.772 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:51.176Z,1683150651.176 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:51.177Z,1683150651.177 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:51.178Z,1683150651.178 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:51.575Z,1683150651.575 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:51.575Z,1683150651.575 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:51.592Z,1683150651.592 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:51.978Z,1683150651.978 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:51.979Z,1683150651.979 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:51.979Z,1683150651.979 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:52.445Z,1683150652.445 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:52.446Z,1683150652.446 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:52.447Z,1683150652.447 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:52.783Z,1683150652.783 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T21:50:52.795Z,1683150652.795 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:52.796Z,1683150652.796 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:52.797Z,1683150652.797 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:53.213Z,1683150653.213 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:53.214Z,1683150653.214 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:53.214Z,1683150653.214 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:53.633Z,1683150653.633 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:53.633Z,1683150653.633 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:53.634Z,1683150653.634 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:54.021Z,1683150654.021 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:54.021Z,1683150654.021 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:54.022Z,1683150654.022 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:54.478Z,1683150654.478 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:54.478Z,1683150654.478 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:54.479Z,1683150654.479 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:54.848Z,1683150654.848 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:54.849Z,1683150654.849 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:54.849Z,1683150654.849 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:55.238Z,1683150655.238 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:55.238Z,1683150655.238 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:55.239Z,1683150655.239 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:55.632Z,1683150655.632 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:55.632Z,1683150655.632 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:55.633Z,1683150655.633 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:56.025Z,1683150656.025 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:56.025Z,1683150656.025 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:56.026Z,1683150656.026 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:56.422Z,1683150656.422 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:56.423Z,1683150656.423 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:56.423Z,1683150656.423 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:56.831Z,1683150656.831 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:56.832Z,1683150656.832 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:56.833Z,1683150656.833 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:57.235Z,1683150657.235 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:57.236Z,1683150657.236 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:57.237Z,1683150657.237 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:57.640Z,1683150657.640 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:57.641Z,1683150657.641 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:57.641Z,1683150657.641 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:58.055Z,1683150658.055 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:58.055Z,1683150658.055 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:58.056Z,1683150658.056 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:58.442Z,1683150658.442 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:58.442Z,1683150658.442 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:58.443Z,1683150658.443 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:58.865Z,1683150658.865 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:58.865Z,1683150658.865 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:58.866Z,1683150658.866 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:59.270Z,1683150659.270 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:59.270Z,1683150659.270 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:59.271Z,1683150659.271 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:50:59.681Z,1683150659.681 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:50:59.681Z,1683150659.681 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:50:59.682Z,1683150659.682 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:51:00.087Z,1683150660.087 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:51:00.087Z,1683150660.087 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:51:00.088Z,1683150660.088 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:51:00.468Z,1683150660.468 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:51:00.468Z,1683150660.468 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:51:00.469Z,1683150660.469 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:51:00.873Z,1683150660.873 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:51:00.873Z,1683150660.873 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:51:00.874Z,1683150660.874 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:51:01.286Z,1683150661.286 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:51:01.287Z,1683150661.287 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:51:01.287Z,1683150661.287 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:51:01.680Z,1683150661.680 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:51:01.681Z,1683150661.681 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:51:01.682Z,1683150661.682 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:51:02.080Z,1683150662.080 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:51:02.080Z,1683150662.080 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:51:02.081Z,1683150662.081 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:51:02.488Z,1683150662.488 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:51:02.489Z,1683150662.489 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:51:02.490Z,1683150662.490 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:51:02.908Z,1683150662.908 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:51:02.909Z,1683150662.909 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:51:02.909Z,1683150662.909 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:51:03.291Z,1683150663.291 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:51:03.292Z,1683150663.292 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:51:03.293Z,1683150663.293 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:51:03.317Z,1683150663.317 [CommandExec](IMPORTANT): got command stop
2023-05-03T21:51:03.317Z,1683150663.317 [CommandExec](IMPORTANT): Scheduling is paused
2023-05-03T21:51:03.317Z,1683150663.317 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2023-05-03T21:51:03.708Z,1683150663.708 [senddata_direct_and_track_test:A.](DEBUG): Tracking...
2023-05-03T21:51:03.709Z,1683150663.709 [senddata_direct_and_track_test:A.](INFO): DAT did not provide matching contact label.
2023-05-03T21:51:03.710Z,1683150663.710 [senddata_direct_and_track_test:A.](INFO): direction in vehicle frame: [ 0.000000 forward, 0.000000 starboard, 0.000000 keelward ]
2023-05-03T21:51:03.710Z,1683150663.710 [MissionManager](INFO): MissionManager is completed.
2023-05-03T21:51:03.710Z,1683150663.710 [MissionManager](INFO): Uninitializing Mission senddata_direct_and_track_test
2023-05-03T21:51:03.710Z,1683150663.710 [senddata_direct_and_track_test] Stopped
2023-05-03T21:51:03.710Z,1683150663.710 [senddata_direct_and_track_test](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test
2023-05-03T21:51:03.710Z,1683150663.710 [senddata_direct_and_track_test:A.] Stopped
2023-05-03T21:51:03.710Z,1683150663.710 [senddata_direct_and_track_test:A.](DEBUG): Uninitializing Tracking.
2023-05-03T21:51:03.710Z,1683150663.710 [senddata_direct_and_track_test:DirectData] Stopped
2023-05-03T21:51:03.710Z,1683150663.710 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test:DirectData
2023-05-03T21:51:03.710Z,1683150663.710 [senddata_direct_and_track_test:DirectData:A.Wait] Stopped
2023-05-03T21:51:03.711Z,1683150663.711 [senddata_direct_and_track_test:DirectData:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-03T21:51:04.130Z,1683150664.130 [MissionManager](IMPORTANT): Started mission Default
2023-05-03T21:51:04.130Z,1683150664.130 [Default] Running Loop=1
2023-05-03T21:51:04.130Z,1683150664.130 [Default](DEBUG): Aggregate::initialize Default
2023-05-03T21:51:04.130Z,1683150664.130 [Default:B.GoToSurface] Running Loop=1
2023-05-03T21:51:04.130Z,1683150664.130 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-03T21:51:04.131Z,1683150664.131 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-03T21:51:04.131Z,1683150664.131 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-03T21:51:04.131Z,1683150664.131 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-03T21:51:04.131Z,1683150664.131 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-03T21:51:04.132Z,1683150664.132 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-03T21:51:04.133Z,1683150664.133 [Default:A.Wait] Running Loop=1
2023-05-03T21:51:04.133Z,1683150664.133 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-05-03T21:51:06.112Z,1683150666.112 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T21:51:17.436Z,1683150677.436 [Default:A.Wait](INFO): Done Waiting.
2023-05-03T21:51:17.436Z,1683150677.436 [Default:A.Wait] Stopped
2023-05-03T21:51:17.437Z,1683150677.437 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-03T21:51:17.856Z,1683150677.856 [Default:CheckIn] Running Loop=1
2023-05-03T21:51:17.856Z,1683150677.856 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-03T21:51:17.856Z,1683150677.856 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-03T21:51:19.036Z,1683150679.036 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T21:52:20.897Z,1683150740.897 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-03T21:54:03.123Z,1683150843.123 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 5.
2023-05-03T21:54:03.126Z,1683150843.126 [BPC1](INFO): Received data from all battery sticks.
2023-05-03T21:56:18.071Z,1683150978.071 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-03T21:51:17.9Z
2023-05-03T21:56:18.071Z,1683150978.071 [Default:CheckIn:Read_GPS] Stopped
2023-05-03T21:56:18.072Z,1683150978.072 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-03T21:56:18.493Z,1683150978.493 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-03T21:56:25.892Z,1683150985.892 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20230503T214626/Courier0004.lzma
2023-05-03T21:56:26.894Z,1683150986.894 [DataOverHttps](INFO): Moved sent file to Logs/20230503T214626/Courier0004.lzma.bak
2023-05-03T21:56:26.894Z,1683150986.894 [DataOverHttps](INFO): SBD MOMSN=18280305
2023-05-03T21:56:43.817Z,1683151003.817 [DataOverHttps](INFO): Sending 546 bytes from file Logs/20230503T214626/Express0005.lzma
2023-05-03T21:56:44.818Z,1683151004.818 [DataOverHttps](INFO): Moved sent file to Logs/20230503T214626/Express0005.lzma.bak
2023-05-03T21:56:44.818Z,1683151004.818 [DataOverHttps](INFO): SBD MOMSN=18280307
2023-05-03T21:56:48.406Z,1683151008.406 [Default:CheckIn:Read_Iridium] Stopped
2023-05-03T21:56:48.406Z,1683151008.406 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-03T21:56:48.406Z,1683151008.406 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-03T21:56:51.181Z,1683151011.181 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-03T22:01:48.937Z,1683151308.937 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-03T22:01:48.937Z,1683151308.937 [Default:CheckIn:C.Wait] Stopped
2023-05-03T22:01:48.937Z,1683151308.937 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-03T22:01:48.938Z,1683151308.938 [Default:CheckIn:D] Running Loop=1
2023-05-03T22:01:49.342Z,1683151309.342 [Default:CheckIn:D] Stopped
2023-05-03T22:01:49.342Z,1683151309.342 [Default:CheckIn:E] Running Loop=1
2023-05-03T22:01:49.748Z,1683151309.748 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.753530 min 
2023-05-03T22:01:49.748Z,1683151309.748 [Default:CheckIn:E] Stopped
2023-05-03T22:01:49.748Z,1683151309.748 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-03T22:01:49.748Z,1683151309.748 [Default:CheckIn] Stopped
2023-05-03T22:01:49.748Z,1683151309.748 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-03T22:01:49.748Z,1683151309.748 [Default:CheckIn](INFO): Running loop #2
2023-05-03T22:01:49.748Z,1683151309.748 [Default:CheckIn] Running Loop=2
2023-05-03T22:01:49.749Z,1683151309.749 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-03T22:01:49.749Z,1683151309.749 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-03T22:01:51.357Z,1683151311.357 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-03T22:01:51.357Z,1683151311.357 [NAL9602] Data Fault, FailCount= 1
2023-05-03T22:01:51.357Z,1683151311.357 [NAL9602](ERROR): Data Fault
2023-05-03T22:01:51.386Z,1683151311.386 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-03T22:01:51.757Z,1683151311.757 [NAL9602](INFO): Powering down
2023-05-03T22:01:52.584Z,1683151312.584 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-03T22:01:52.584Z,1683151312.584 [NAL9602] No Fault, FailCount= 1
2023-05-03T22:02:22.061Z,1683151342.061 [NAL9602](INFO): Powering up NAL9602
2023-05-03T22:02:32.973Z,1683151352.973 [NAL9602](INFO): NAL9602 initialized
2023-05-03T22:06:49.927Z,1683151609.927 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-03T22:01:49.7Z
2023-05-03T22:06:49.927Z,1683151609.927 [Default:CheckIn:Read_GPS] Stopped
2023-05-03T22:06:49.927Z,1683151609.927 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-03T22:06:57.523Z,1683151617.523 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230503T214626/Courier0007.lzma
2023-05-03T22:06:58.526Z,1683151618.526 [DataOverHttps](INFO): Moved sent file to Logs/20230503T214626/Courier0007.lzma.bak
2023-05-03T22:06:58.527Z,1683151618.527 [DataOverHttps](INFO): SBD MOMSN=18280337
2023-05-03T22:07:14.567Z,1683151634.567 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20230503T214626/Express0008.lzma
2023-05-03T22:07:15.542Z,1683151635.542 [DataOverHttps](INFO): Moved sent file to Logs/20230503T214626/Express0008.lzma.bak
2023-05-03T22:07:15.542Z,1683151635.542 [DataOverHttps](INFO): SBD MOMSN=18280339
2023-05-03T22:07:18.253Z,1683151638.253 [Default:CheckIn:Read_Iridium] Stopped
2023-05-03T22:07:18.253Z,1683151638.253 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-03T22:07:18.253Z,1683151638.253 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-03T22:07:36.777Z,1683151656.777 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-03T22:08:07.485Z,1683151687.485 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-03T22:12:18.783Z,1683151938.783 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-03T22:12:18.783Z,1683151938.783 [Default:CheckIn:C.Wait] Stopped
2023-05-03T22:12:18.783Z,1683151938.783 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-03T22:12:18.783Z,1683151938.783 [Default:CheckIn:D] Running Loop=1
2023-05-03T22:12:19.191Z,1683151939.191 [Default:CheckIn:D] Stopped
2023-05-03T22:12:19.191Z,1683151939.191 [Default:CheckIn:E] Running Loop=1
2023-05-03T22:12:19.599Z,1683151939.599 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.251011 min 
2023-05-03T22:12:19.599Z,1683151939.599 [Default:CheckIn:E] Stopped
2023-05-03T22:12:19.599Z,1683151939.599 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-03T22:12:19.599Z,1683151939.599 [Default:CheckIn] Stopped
2023-05-03T22:12:19.599Z,1683151939.599 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-03T22:12:19.600Z,1683151939.600 [Default:CheckIn](INFO): Running loop #3
2023-05-03T22:12:19.600Z,1683151939.600 [Default:CheckIn] Running Loop=3
2023-05-03T22:12:19.600Z,1683151939.600 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-03T22:12:19.600Z,1683151939.600 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-03T22:12:52.305Z,1683151972.305 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-05-03T22:12:52.388Z,1683151972.388 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-05-03T22:17:19.777Z,1683152239.777 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-03T22:12:19.6Z
2023-05-03T22:17:19.778Z,1683152239.778 [Default:CheckIn:Read_GPS] Stopped
2023-05-03T22:17:19.778Z,1683152239.778 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-03T22:17:23.793Z,1683152243.793 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-03T22:17:27.104Z,1683152247.104 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230503T214626/Courier0010.lzma
2023-05-03T22:17:28.106Z,1683152248.106 [DataOverHttps](INFO): Moved sent file to Logs/20230503T214626/Courier0010.lzma.bak
2023-05-03T22:17:28.106Z,1683152248.106 [DataOverHttps](INFO): SBD MOMSN=18280361
2023-05-03T22:17:45.068Z,1683152265.068 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20230503T214626/Express0011.lzma
2023-05-03T22:17:46.070Z,1683152266.070 [DataOverHttps](INFO): Moved sent file to Logs/20230503T214626/Express0011.lzma.bak
2023-05-03T22:17:46.070Z,1683152266.070 [DataOverHttps](INFO): SBD MOMSN=18280363
2023-05-03T22:17:48.474Z,1683152268.474 [Default:CheckIn:Read_Iridium] Stopped
2023-05-03T22:17:48.474Z,1683152268.474 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-03T22:17:48.474Z,1683152268.474 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-03T22:17:54.525Z,1683152274.525 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-03T22:22:49.045Z,1683152569.045 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-03T22:22:49.045Z,1683152569.045 [Default:CheckIn:C.Wait] Stopped
2023-05-03T22:22:49.045Z,1683152569.045 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-03T22:22:49.046Z,1683152569.046 [Default:CheckIn:D] Running Loop=1
2023-05-03T22:22:49.461Z,1683152569.461 [Default:CheckIn:D] Stopped
2023-05-03T22:22:49.461Z,1683152569.461 [Default:CheckIn:E] Running Loop=1
2023-05-03T22:22:49.853Z,1683152569.853 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.755509 min 
2023-05-03T22:22:49.854Z,1683152569.854 [Default:CheckIn:E] Stopped
2023-05-03T22:22:49.854Z,1683152569.854 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-03T22:22:49.854Z,1683152569.854 [Default:CheckIn] Stopped
2023-05-03T22:22:49.854Z,1683152569.854 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-03T22:22:49.854Z,1683152569.854 [Default:CheckIn](INFO): Running loop #4
2023-05-03T22:22:49.854Z,1683152569.854 [Default:CheckIn] Running Loop=4
2023-05-03T22:22:49.854Z,1683152569.854 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-03T22:22:49.854Z,1683152569.854 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-03T22:22:51.459Z,1683152571.459 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-03T22:22:51.459Z,1683152571.459 [NAL9602] Data Fault, FailCount= 2
2023-05-03T22:22:51.459Z,1683152571.459 [NAL9602](ERROR): Data Fault
2023-05-03T22:22:51.479Z,1683152571.479 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-03T22:22:51.869Z,1683152571.869 [NAL9602](INFO): Powering down
2023-05-03T22:22:52.699Z,1683152572.699 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-03T22:22:52.699Z,1683152572.699 [NAL9602] No Fault, FailCount= 2
2023-05-03T22:23:22.169Z,1683152602.169 [NAL9602](INFO): Powering up NAL9602
2023-05-03T22:23:33.078Z,1683152613.078 [NAL9602](INFO): NAL9602 initialized
2023-05-03T22:27:50.049Z,1683152870.049 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-03T22:22:49.9Z
2023-05-03T22:27:50.049Z,1683152870.049 [Default:CheckIn:Read_GPS] Stopped
2023-05-03T22:27:50.050Z,1683152870.050 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-03T22:27:56.935Z,1683152876.935 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230503T214626/Courier0013.lzma
2023-05-03T22:27:57.938Z,1683152877.938 [DataOverHttps](INFO): Moved sent file to Logs/20230503T214626/Courier0013.lzma.bak
2023-05-03T22:27:57.938Z,1683152877.938 [DataOverHttps](INFO): SBD MOMSN=18280383
2023-05-03T22:28:01.301Z,1683152881.301 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T22:28:01.301Z,1683152881.301 [DATMMP](INFO): timesync rx: 	 x2	 x0	 x0	 x0
2023-05-03T22:28:01.302Z,1683152881.302 [DATMMP](INFO): Received message type: timesync
2023-05-03T22:28:01.302Z,1683152881.302 [DATMMP](INFO): Handled
2023-05-03T22:28:11.385Z,1683152891.385 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T22:28:11.386Z,1683152891.386 [DATMMP](INFO): timesync rx: 	 x2	 x1	 x1	 x0
2023-05-03T22:28:11.386Z,1683152891.386 [DATMMP](INFO): Received message type: timesync
2023-05-03T22:28:11.386Z,1683152891.386 [DATMMP](INFO): Handled
2023-05-03T22:28:14.968Z,1683152894.968 [DataOverHttps](INFO): Sending 235 bytes from file Logs/20230503T214626/Express0014.lzma
2023-05-03T22:28:15.970Z,1683152895.970 [DataOverHttps](INFO): Moved sent file to Logs/20230503T214626/Express0014.lzma.bak
2023-05-03T22:28:15.970Z,1683152895.970 [DataOverHttps](INFO): SBD MOMSN=18280385
2023-05-03T22:28:18.355Z,1683152898.355 [Default:CheckIn:Read_Iridium] Stopped
2023-05-03T22:28:18.355Z,1683152898.355 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-03T22:28:18.355Z,1683152898.355 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-03T22:28:21.213Z,1683152901.213 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-03T22:28:21.214Z,1683152901.214 [DATMMP](INFO): timesync rx: 	 x1	 x0	 x1	 x0
2023-05-03T22:28:21.214Z,1683152901.214 [DATMMP](INFO): Received message type: timesync
2023-05-03T22:28:21.214Z,1683152901.214 [DATMMP](INFO): Handled
2023-05-03T22:28:36.881Z,1683152916.881 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-03T22:29:07.581Z,1683152947.581 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-03T22:33:18.931Z,1683153198.931 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-03T22:33:18.931Z,1683153198.931 [Default:CheckIn:C.Wait] Stopped
2023-05-03T22:33:18.931Z,1683153198.931 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-03T22:33:18.931Z,1683153198.931 [Default:CheckIn:D] Running Loop=1
2023-05-03T22:33:19.322Z,1683153199.322 [Default:CheckIn:D] Stopped
2023-05-03T22:33:19.322Z,1683153199.322 [Default:CheckIn:E] Running Loop=1
2023-05-03T22:33:19.731Z,1683153199.731 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.253194 min 
2023-05-03T22:33:19.731Z,1683153199.731 [Default:CheckIn:E] Stopped
2023-05-03T22:33:19.731Z,1683153199.731 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-03T22:33:19.731Z,1683153199.731 [Default:CheckIn] Stopped
2023-05-03T22:33:19.731Z,1683153199.731 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-03T22:33:19.731Z,1683153199.731 [Default:CheckIn](INFO): Running loop #5
2023-05-03T22:33:19.731Z,1683153199.731 [Default:CheckIn] Running Loop=5
2023-05-03T22:33:19.732Z,1683153199.732 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-03T22:33:19.732Z,1683153199.732 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-03T22:33:52.440Z,1683153232.440 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-05-03T22:33:52.512Z,1683153232.512 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-05-03T22:38:19.905Z,1683153499.905 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-03T22:33:19.7Z
2023-05-03T22:38:19.906Z,1683153499.906 [Default:CheckIn:Read_GPS] Stopped
2023-05-03T22:38:19.906Z,1683153499.906 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-03T22:38:23.929Z,1683153503.929 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-03T22:38:27.327Z,1683153507.327 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230503T214626/Courier0016.lzma
2023-05-03T22:38:28.330Z,1683153508.330 [DataOverHttps](INFO): Moved sent file to Logs/20230503T214626/Courier0016.lzma.bak
2023-05-03T22:38:28.330Z,1683153508.330 [DataOverHttps](INFO): SBD MOMSN=18280401
2023-05-03T22:38:45.904Z,1683153525.904 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20230503T214626/Express0017.lzma
2023-05-03T22:38:46.906Z,1683153526.906 [DataOverHttps](INFO): Moved sent file to Logs/20230503T214626/Express0017.lzma.bak
2023-05-03T22:38:46.906Z,1683153526.906 [DataOverHttps](INFO): SBD MOMSN=18280403
2023-05-03T22:38:49.420Z,1683153529.420 [Default:CheckIn:Read_Iridium] Stopped
2023-05-03T22:38:49.420Z,1683153529.420 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-03T22:38:49.420Z,1683153529.420 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-03T22:38:54.633Z,1683153534.633 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-03T22:43:50.003Z,1683153830.003 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-03T22:43:50.003Z,1683153830.003 [Default:CheckIn:C.Wait] Stopped
2023-05-03T22:43:50.003Z,1683153830.003 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-03T22:43:50.004Z,1683153830.004 [Default:CheckIn:D] Running Loop=1
2023-05-03T22:43:50.412Z,1683153830.412 [Default:CheckIn:D] Stopped
2023-05-03T22:43:50.412Z,1683153830.412 [Default:CheckIn:E] Running Loop=1
2023-05-03T22:43:50.810Z,1683153830.810 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.771362 min 
2023-05-03T22:43:50.810Z,1683153830.810 [Default:CheckIn:E] Stopped
2023-05-03T22:43:50.810Z,1683153830.810 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-03T22:43:50.811Z,1683153830.811 [Default:CheckIn] Stopped
2023-05-03T22:43:50.811Z,1683153830.811 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-03T22:43:50.811Z,1683153830.811 [Default:CheckIn](INFO): Running loop #6
2023-05-03T22:43:50.811Z,1683153830.811 [Default:CheckIn] Running Loop=6
2023-05-03T22:43:50.811Z,1683153830.811 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-03T22:43:50.811Z,1683153830.811 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-03T22:43:52.415Z,1683153832.415 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-03T22:43:52.416Z,1683153832.416 [NAL9602] Data Fault, FailCount= 3
2023-05-03T22:43:52.416Z,1683153832.416 [NAL9602](ERROR): Data Fault
2023-05-03T22:43:52.460Z,1683153832.460 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-03T22:43:52.825Z,1683153832.825 [NAL9602](INFO): Powering down
2023-05-03T22:43:53.653Z,1683153833.653 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-03T22:43:53.653Z,1683153833.653 [NAL9602] No Fault, FailCount= 3
2023-05-03T22:44:23.121Z,1683153863.121 [NAL9602](INFO): Powering up NAL9602
2023-05-03T22:44:34.039Z,1683153874.039 [NAL9602](INFO): NAL9602 initialized
2023-05-03T22:48:51.014Z,1683154131.014 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-03T22:43:50.8Z
2023-05-03T22:48:51.014Z,1683154131.014 [Default:CheckIn:Read_GPS] Stopped
2023-05-03T22:48:51.015Z,1683154131.015 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-03T22:48:58.259Z,1683154138.259 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230503T214626/Courier0019.lzma
2023-05-03T22:48:59.262Z,1683154139.262 [DataOverHttps](INFO): Moved sent file to Logs/20230503T214626/Courier0019.lzma.bak
2023-05-03T22:48:59.263Z,1683154139.263 [DataOverHttps](INFO): SBD MOMSN=18280419
2023-05-03T22:49:16.308Z,1683154156.308 [DataOverHttps](INFO): Sending 233 bytes from file Logs/20230503T214626/Express0020.lzma
2023-05-03T22:49:17.310Z,1683154157.310 [DataOverHttps](INFO): Moved sent file to Logs/20230503T214626/Express0020.lzma.bak
2023-05-03T22:49:17.310Z,1683154157.310 [DataOverHttps](INFO): SBD MOMSN=18280421
2023-05-03T22:49:19.685Z,1683154159.685 [Default:CheckIn:Read_Iridium] Stopped
2023-05-03T22:49:19.686Z,1683154159.686 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-03T22:49:19.686Z,1683154159.686 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-03T22:49:37.841Z,1683154177.841 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-03T22:50:08.541Z,1683154208.541 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-03T22:54:20.246Z,1683154460.246 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-03T22:54:20.246Z,1683154460.246 [Default:CheckIn:C.Wait] Stopped
2023-05-03T22:54:20.246Z,1683154460.246 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-03T22:54:20.247Z,1683154460.247 [Default:CheckIn:D] Running Loop=1
2023-05-03T22:54:20.647Z,1683154460.647 [Default:CheckIn:D] Stopped
2023-05-03T22:54:20.647Z,1683154460.647 [Default:CheckIn:E] Running Loop=1
2023-05-03T22:54:21.061Z,1683154461.061 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.275281 min 
2023-05-03T22:54:21.062Z,1683154461.062 [Default:CheckIn:E] Stopped
2023-05-03T22:54:21.062Z,1683154461.062 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-03T22:54:21.062Z,1683154461.062 [Default:CheckIn] Stopped
2023-05-03T22:54:21.062Z,1683154461.062 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-03T22:54:21.062Z,1683154461.062 [Default:CheckIn](INFO): Running loop #7
2023-05-03T22:54:21.062Z,1683154461.062 [Default:CheckIn] Running Loop=7
2023-05-03T22:54:21.062Z,1683154461.062 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-03T22:54:21.062Z,1683154461.062 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-03T22:54:53.764Z,1683154493.764 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-05-03T22:54:53.840Z,1683154493.840 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-05-03T22:56:29.943Z,1683154589.943 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T22:56:43.269Z,1683154603.269 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T22:56:56.607Z,1683154616.607 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T22:57:09.932Z,1683154629.932 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T22:57:23.264Z,1683154643.264 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T22:57:36.596Z,1683154656.596 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T22:57:49.929Z,1683154669.929 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T22:58:03.260Z,1683154683.260 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T22:58:16.593Z,1683154696.593 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T22:58:29.924Z,1683154709.924 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T22:58:43.660Z,1683154723.660 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T22:58:56.994Z,1683154736.994 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T22:59:21.265Z,1683154761.265 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-03T22:54:21.1Z
2023-05-03T22:59:21.265Z,1683154761.265 [Default:CheckIn:Read_GPS] Stopped
2023-05-03T22:59:21.266Z,1683154761.266 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-03T22:59:25.273Z,1683154765.273 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-03T22:59:28.128Z,1683154768.128 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230503T214626/Courier0022.lzma
2023-05-03T22:59:29.130Z,1683154769.130 [DataOverHttps](INFO): Moved sent file to Logs/20230503T214626/Courier0022.lzma.bak
2023-05-03T22:59:29.130Z,1683154769.130 [DataOverHttps](INFO): SBD MOMSN=18280438
2023-05-03T22:59:46.172Z,1683154786.172 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20230503T214626/Express0023.lzma
2023-05-03T22:59:47.174Z,1683154787.174 [DataOverHttps](INFO): Moved sent file to Logs/20230503T214626/Express0023.lzma.bak
2023-05-03T22:59:47.174Z,1683154787.174 [DataOverHttps](INFO): SBD MOMSN=18280440
2023-05-03T22:59:49.647Z,1683154789.647 [Default:CheckIn:Read_Iridium] Stopped
2023-05-03T22:59:49.647Z,1683154789.647 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-03T22:59:49.647Z,1683154789.647 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-03T22:59:55.993Z,1683154795.993 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-03T23:04:50.127Z,1683155090.127 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-03T23:04:50.127Z,1683155090.127 [Default:CheckIn:C.Wait] Stopped
2023-05-03T23:04:50.127Z,1683155090.127 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-03T23:04:50.128Z,1683155090.128 [Default:CheckIn:D] Running Loop=1
2023-05-03T23:04:50.523Z,1683155090.523 [Default:CheckIn:D] Stopped
2023-05-03T23:04:50.523Z,1683155090.523 [Default:CheckIn:E] Running Loop=1
2023-05-03T23:04:50.912Z,1683155090.912 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.773218 min 
2023-05-03T23:04:50.912Z,1683155090.912 [Default:CheckIn:E] Stopped
2023-05-03T23:04:50.912Z,1683155090.912 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-03T23:04:50.912Z,1683155090.912 [Default:CheckIn] Stopped
2023-05-03T23:04:50.912Z,1683155090.912 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-03T23:04:50.912Z,1683155090.912 [Default:CheckIn](INFO): Running loop #8
2023-05-03T23:04:50.912Z,1683155090.912 [Default:CheckIn] Running Loop=8
2023-05-03T23:04:50.913Z,1683155090.913 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-03T23:04:50.913Z,1683155090.913 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-03T23:04:52.514Z,1683155092.514 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-03T23:04:52.514Z,1683155092.514 [NAL9602] Data Fault, FailCount= 4
2023-05-03T23:04:52.514Z,1683155092.514 [NAL9602](ERROR): Data Fault
2023-05-03T23:04:52.534Z,1683155092.534 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-03T23:04:52.917Z,1683155092.917 [NAL9602](INFO): Powering down
2023-05-03T23:04:53.748Z,1683155093.748 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-03T23:04:53.748Z,1683155093.748 [NAL9602] No Fault, FailCount= 4
2023-05-03T23:05:23.221Z,1683155123.221 [NAL9602](INFO): Powering up NAL9602
2023-05-03T23:05:34.129Z,1683155134.129 [NAL9602](INFO): NAL9602 initialized
2023-05-03T23:06:17.760Z,1683155177.760 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T23:06:31.094Z,1683155191.094 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T23:06:44.424Z,1683155204.424 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T23:06:57.752Z,1683155217.752 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T23:07:11.084Z,1683155231.084 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T23:07:24.821Z,1683155244.821 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T23:07:38.154Z,1683155258.154 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T23:07:51.484Z,1683155271.484 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T23:08:04.816Z,1683155284.816 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T23:08:17.744Z,1683155297.744 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T23:08:31.077Z,1683155311.077 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T23:09:51.091Z,1683155391.091 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-03T23:04:50.9Z
2023-05-03T23:09:51.091Z,1683155391.091 [Default:CheckIn:Read_GPS] Stopped
2023-05-03T23:09:51.092Z,1683155391.092 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-03T23:09:58.843Z,1683155398.843 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230503T214626/Courier0025.lzma
2023-05-03T23:09:59.846Z,1683155399.846 [DataOverHttps](INFO): Moved sent file to Logs/20230503T214626/Courier0025.lzma.bak
2023-05-03T23:09:59.846Z,1683155399.846 [DataOverHttps](INFO): SBD MOMSN=18280457
2023-05-03T23:10:16.996Z,1683155416.996 [DataOverHttps](INFO): Sending 234 bytes from file Logs/20230503T214626/Express0026.lzma
2023-05-03T23:10:17.998Z,1683155417.998 [DataOverHttps](INFO): Moved sent file to Logs/20230503T214626/Express0026.lzma.bak
2023-05-03T23:10:17.998Z,1683155417.998 [DataOverHttps](INFO): SBD MOMSN=18280459
2023-05-03T23:10:20.602Z,1683155420.602 [Default:CheckIn:Read_Iridium] Stopped
2023-05-03T23:10:20.602Z,1683155420.602 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-03T23:10:20.602Z,1683155420.602 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-03T23:10:37.933Z,1683155437.933 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-03T23:11:08.637Z,1683155468.637 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-03T23:15:21.189Z,1683155721.189 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-03T23:15:21.189Z,1683155721.189 [Default:CheckIn:C.Wait] Stopped
2023-05-03T23:15:21.189Z,1683155721.189 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-03T23:15:21.189Z,1683155721.189 [Default:CheckIn:D] Running Loop=1
2023-05-03T23:15:21.596Z,1683155721.596 [Default:CheckIn:D] Stopped
2023-05-03T23:15:21.596Z,1683155721.596 [Default:CheckIn:E] Running Loop=1
2023-05-03T23:15:21.979Z,1683155721.979 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.291097 min 
2023-05-03T23:15:21.979Z,1683155721.979 [Default:CheckIn:E] Stopped
2023-05-03T23:15:21.979Z,1683155721.979 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-03T23:15:21.979Z,1683155721.979 [Default:CheckIn] Stopped
2023-05-03T23:15:21.980Z,1683155721.980 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-03T23:15:21.980Z,1683155721.980 [Default:CheckIn](INFO): Running loop #9
2023-05-03T23:15:21.980Z,1683155721.980 [Default:CheckIn] Running Loop=9
2023-05-03T23:15:21.980Z,1683155721.980 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-03T23:15:21.980Z,1683155721.980 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-03T23:15:50.245Z,1683155750.245 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T23:15:54.694Z,1683155754.694 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-05-03T23:15:54.768Z,1683155754.768 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-05-03T23:16:03.583Z,1683155763.583 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T23:16:16.908Z,1683155776.908 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T23:16:30.241Z,1683155790.241 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T23:16:43.977Z,1683155803.977 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T23:16:57.308Z,1683155817.308 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T23:17:10.640Z,1683155830.640 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T23:17:23.973Z,1683155843.973 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T23:17:37.305Z,1683155857.305 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T23:17:50.638Z,1683155870.638 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T23:18:03.970Z,1683155883.970 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-03T23:20:22.178Z,1683156022.178 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-03T23:15:21.0Z
2023-05-03T23:20:22.178Z,1683156022.178 [Default:CheckIn:Read_GPS] Stopped
2023-05-03T23:20:22.178Z,1683156022.178 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-03T23:20:25.773Z,1683156025.773 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-03T23:20:29.739Z,1683156029.739 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230503T214626/Courier0028.lzma
2023-05-03T23:20:30.742Z,1683156030.742 [DataOverHttps](INFO): Moved sent file to Logs/20230503T214626/Courier0028.lzma.bak
2023-05-03T23:20:30.742Z,1683156030.742 [DataOverHttps](INFO): SBD MOMSN=18280475
2023-05-03T23:20:47.792Z,1683156047.792 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20230503T214626/Express0029.lzma
2023-05-03T23:20:48.794Z,1683156048.794 [DataOverHttps](INFO): Moved sent file to Logs/20230503T214626/Express0029.lzma.bak
2023-05-03T23:20:48.794Z,1683156048.794 [DataOverHttps](INFO): SBD MOMSN=18280477
2023-05-03T23:20:51.249Z,1683156051.249 [Default:CheckIn:Read_Iridium] Stopped
2023-05-03T23:20:51.249Z,1683156051.249 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-03T23:20:51.249Z,1683156051.249 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-03T23:20:56.481Z,1683156056.481 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-03T23:25:04.141Z,1683156304.141 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 5.
2023-05-03T23:25:04.143Z,1683156304.143 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2023-05-03T23:25:51.816Z,1683156351.816 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-03T23:25:51.816Z,1683156351.816 [Default:CheckIn:C.Wait] Stopped
2023-05-03T23:25:51.816Z,1683156351.816 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-03T23:25:51.817Z,1683156351.817 [Default:CheckIn:D] Running Loop=1
2023-05-03T23:25:52.219Z,1683156352.219 [Default:CheckIn:D] Stopped
2023-05-03T23:25:52.219Z,1683156352.219 [Default:CheckIn:E] Running Loop=1
2023-05-03T23:25:52.631Z,1683156352.631 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.801481 min 
2023-05-03T23:25:52.631Z,1683156352.631 [Default:CheckIn:E] Stopped
2023-05-03T23:25:52.632Z,1683156352.632 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-03T23:25:52.632Z,1683156352.632 [Default:CheckIn] Stopped
2023-05-03T23:25:52.632Z,1683156352.632 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-03T23:25:52.632Z,1683156352.632 [Default:CheckIn](INFO): Running loop #10
2023-05-03T23:25:52.632Z,1683156352.632 [Default:CheckIn] Running Loop=10
2023-05-03T23:25:52.632Z,1683156352.632 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-03T23:25:52.632Z,1683156352.632 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-03T23:25:54.229Z,1683156354.229 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-03T23:25:54.229Z,1683156354.229 [NAL9602] Data Fault, FailCount= 5
2023-05-03T23:25:54.229Z,1683156354.229 [NAL9602](ERROR): Data Fault
2023-05-03T23:25:54.283Z,1683156354.283 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-03T23:25:54.283Z,1683156354.283 [CBIT](CRITICAL): Data Fault in component: NAL9602
2023-05-03T23:25:54.629Z,1683156354.629 [NAL9602](INFO): Powering down
2023-05-03T23:25:54.664Z,1683156354.664 [CommandExec](FAULT): Scheduling is paused
2023-05-03T23:25:54.665Z,1683156354.665 [CBIT](INFO): Critical error at 20230503T232554
2023-05-03T23:27:54.230Z,1683156474.230 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-03T23:27:54.230Z,1683156474.230 [NAL9602] No Fault, FailCount= 5
2023-05-03T23:27:54.621Z,1683156474.621 [NAL9602](INFO): Powering up NAL9602
2023-05-03T23:28:05.529Z,1683156485.529 [NAL9602](INFO): NAL9602 initialized
2023-05-03T23:30:52.808Z,1683156652.808 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-03T23:25:52.6Z
2023-05-03T23:30:52.808Z,1683156652.808 [Default:CheckIn:Read_GPS] Stopped
2023-05-03T23:30:52.809Z,1683156652.809 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-03T23:31:00.064Z,1683156660.064 [DataOverHttps](INFO): Sending 116 bytes from file Logs/20230503T214626/Courier0031.lzma
2023-05-03T23:31:01.066Z,1683156661.066 [DataOverHttps](INFO): Moved sent file to Logs/20230503T214626/Courier0031.lzma.bak
2023-05-03T23:31:01.066Z,1683156661.066 [DataOverHttps](INFO): SBD MOMSN=18280510
2023-05-03T23:31:18.120Z,1683156678.120 [DataOverHttps](INFO): Sending 258 bytes from file Logs/20230503T214626/Express0032.lzma
2023-05-03T23:31:19.122Z,1683156679.122 [DataOverHttps](INFO): Moved sent file to Logs/20230503T214626/Express0032.lzma.bak
2023-05-03T23:31:19.122Z,1683156679.122 [DataOverHttps](INFO): SBD MOMSN=18280513
2023-05-03T23:31:21.502Z,1683156681.502 [Default:CheckIn:Read_Iridium] Stopped
2023-05-03T23:31:21.502Z,1683156681.502 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-03T23:31:21.502Z,1683156681.502 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-03T23:33:09.331Z,1683156789.331 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-03T23:33:35.529Z,1683156815.529 [CommandExec](IMPORTANT): got command quit
2023-05-03T23:33:36.532Z,1683156816.532 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-03T23:33:36.532Z,1683156816.532 [CommandExec](INFO): Uninitializing the command executive.
2023-05-03T23:33:36.532Z,1683156816.532 [CommandExec](INFO): Uninitializing the command scheduler.
2023-05-03T23:33:36.533Z,1683156816.533 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:36.696Z,1683156816.696 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-05-03T23:33:36.696Z,1683156816.696 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-05-03T23:33:36.696Z,1683156816.696 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:36.697Z,1683156816.697 [NavChartDb](INFO): Join timeout helper Thread ID is 1926
2023-05-03T23:33:37.012Z,1683156817.012 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-03T23:33:37.012Z,1683156817.012 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:37.020Z,1683156817.020 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-05-03T23:33:37.020Z,1683156817.020 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:37.021Z,1683156817.021 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1927
2023-05-03T23:33:37.308Z,1683156817.308 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-03T23:33:37.308Z,1683156817.308 [WetLabsBB2FL](INFO): Powering down
2023-05-03T23:33:37.309Z,1683156817.309 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:37.328Z,1683156817.328 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-05-03T23:33:37.328Z,1683156817.328 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:37.328Z,1683156817.328 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1928
2023-05-03T23:33:37.556Z,1683156817.556 [CTD_Seabird](INFO): Powering down
2023-05-03T23:33:37.568Z,1683156817.568 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-03T23:33:37.568Z,1683156817.568 [CTD_Seabird](INFO): Powering down
2023-05-03T23:33:37.580Z,1683156817.580 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:37.592Z,1683156817.592 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler
2023-05-03T23:33:37.592Z,1683156817.592 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:37.593Z,1683156817.593 [DATMMP](INFO): Join timeout helper Thread ID is 1929
2023-05-03T23:33:37.664Z,1683156817.664 [DATMMP](INFO): Stop
2023-05-03T23:33:37.664Z,1683156817.664 [DATMMP](INFO): uninitialize
2023-05-03T23:33:37.665Z,1683156817.665 [DATMMP](INFO): Powering down
2023-05-03T23:33:37.737Z,1683156817.737 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-03T23:33:37.737Z,1683156817.737 [DATMMP](INFO): uninitialize
2023-05-03T23:33:37.737Z,1683156817.737 [DATMMP](INFO): Powering down
2023-05-03T23:33:37.738Z,1683156817.738 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:37.740Z,1683156817.740 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-05-03T23:33:37.740Z,1683156817.740 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:37.740Z,1683156817.740 [Radio_Surface](INFO): Join timeout helper Thread ID is 1930
2023-05-03T23:33:37.760Z,1683156817.760 [Radio_Surface](INFO): Powering down
2023-05-03T23:33:37.761Z,1683156817.761 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-03T23:33:37.761Z,1683156817.761 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:37.772Z,1683156817.772 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-05-03T23:33:37.772Z,1683156817.772 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:37.773Z,1683156817.773 [Onboard](INFO): Join timeout helper Thread ID is 1931
2023-05-03T23:33:38.160Z,1683156818.160 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-03T23:33:38.160Z,1683156818.160 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:38.181Z,1683156818.181 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-05-03T23:33:38.181Z,1683156818.181 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:38.181Z,1683156818.181 [DataOverHttps](INFO): Join timeout helper Thread ID is 1932
2023-05-03T23:33:38.516Z,1683156818.516 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-03T23:33:38.516Z,1683156818.516 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:38.530Z,1683156818.530 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-05-03T23:33:38.530Z,1683156818.530 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:38.531Z,1683156818.531 [BackseatComponent](INFO): Join timeout helper Thread ID is 1933
2023-05-03T23:33:38.693Z,1683156818.693 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-03T23:33:38.693Z,1683156818.693 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:38.712Z,1683156818.712 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-05-03T23:33:38.712Z,1683156818.712 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:38.713Z,1683156818.713 [logger](INFO): Join timeout helper Thread ID is 1934
2023-05-03T23:33:38.713Z,1683156818.713 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-03T23:33:38.713Z,1683156818.713 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:38.714Z,1683156818.714 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-05-03T23:33:38.714Z,1683156818.714 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:38.715Z,1683156818.715 [CommandLine](INFO): Join timeout helper Thread ID is 1935
2023-05-03T23:33:38.764Z,1683156818.764 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-03T23:33:38.764Z,1683156818.764 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:38.773Z,1683156818.773 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-05-03T23:33:38.773Z,1683156818.773 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:38.773Z,1683156818.773 [CommandExec](INFO): Join timeout helper Thread ID is 1936
2023-05-03T23:33:38.775Z,1683156818.775 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-05-03T23:33:38.775Z,1683156818.775 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:38.775Z,1683156818.775 [controlThread](INFO): Join timeout helper Thread ID is 1937
2023-05-03T23:33:38.808Z,1683156818.808 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-03T23:33:38.808Z,1683156818.808 [controlThread](DEBUG): Uninitializing ControlThread
2023-05-03T23:33:38.809Z,1683156818.809 [AHRS_M2](INFO): Powering down
2023-05-03T23:33:38.881Z,1683156818.881 [NAL9602](INFO): Powering down
2023-05-03T23:33:38.883Z,1683156818.883 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-05-03T23:33:38.884Z,1683156818.884 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-05-03T23:33:38.884Z,1683156818.884 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-05-03T23:33:38.885Z,1683156818.885 [MissionManager](INFO): Uninitializing Mission Default
2023-05-03T23:33:38.885Z,1683156818.885 [Default] Stopped
2023-05-03T23:33:38.885Z,1683156818.885 [Default](DEBUG): Aggregate::uninitialize Default
2023-05-03T23:33:38.885Z,1683156818.885 [Default:B.GoToSurface] Stopped
2023-05-03T23:33:38.885Z,1683156818.885 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-03T23:33:38.885Z,1683156818.885 [Default:CheckIn] Stopped
2023-05-03T23:33:38.886Z,1683156818.886 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-03T23:33:38.886Z,1683156818.886 [Default:CheckIn:C.Wait] Stopped
2023-05-03T23:33:38.886Z,1683156818.886 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-03T23:33:38.888Z,1683156818.888 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-05-03T23:33:38.889Z,1683156818.889 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-05-03T23:33:38.889Z,1683156818.889 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-05-03T23:33:38.889Z,1683156818.889 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-05-03T23:33:38.890Z,1683156818.890 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-05-03T23:33:38.890Z,1683156818.890 [BuoyancyServo](INFO): Powering down
2023-05-03T23:33:38.904Z,1683156818.904 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-05-03T23:33:38.904Z,1683156818.904 [ElevatorServo](INFO): Powering down
2023-05-03T23:33:38.905Z,1683156818.905 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-05-03T23:33:38.905Z,1683156818.905 [MassServo](INFO): Powering down
2023-05-03T23:33:38.906Z,1683156818.906 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-05-03T23:33:38.906Z,1683156818.906 [RudderServo](INFO): Powering down
2023-05-03T23:33:38.907Z,1683156818.907 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-05-03T23:33:38.907Z,1683156818.907 [ThrusterHE](INFO): Powering down
2023-05-03T23:33:38.908Z,1683156818.908 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-05-03T23:33:38.908Z,1683156818.908 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-05-03T23:33:38.908Z,1683156818.908 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-05-03T23:33:38.909Z,1683156818.909 [CBIT](DEBUG): Powering off loads.
2023-05-03T23:33:38.920Z,1683156818.920 [CBIT](DEBUG): Disabling WDT.
2023-05-03T23:33:38.932Z,1683156818.932 [CBIT](DEBUG): Opening all GF detection circuits.
2023-05-03T23:33:38.933Z,1683156818.933 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:38.991Z,1683156818.991 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:38.996Z,1683156818.996 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:38.998Z,1683156818.998 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:39.004Z,1683156819.004 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:39.047Z,1683156819.047 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:39.084Z,1683156819.084 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:39.089Z,1683156819.089 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:39.121Z,1683156819.121 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-03T23:33:39.183Z,1683156819.183 [logger ThreadHandler](INFO): Thread cancelled.