2023-05-10T17:43:55.660Z,1683740635.660 [Supervisor](DEBUG): Initializing supervisor.
2023-05-10T17:43:55.665Z,1683740635.665 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-05-10T17:43:55.666Z,1683740635.666 [SyncHandler](INFO): Protected caller Thread ID is 4596
2023-05-10T17:43:55.666Z,1683740635.666 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-05-10T17:43:55.667Z,1683740635.667 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-05-10T17:43:55.668Z,1683740635.668 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4597
2023-05-10T17:43:55.672Z,1683740635.672 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-05-10T17:43:55.689Z,1683740635.689 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-05-10T17:43:55.690Z,1683740635.690 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-05-10T17:43:55.691Z,1683740635.691 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4598
2023-05-10T17:43:55.695Z,1683740635.695 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-05-10T17:43:55.696Z,1683740635.696 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-05-10T17:43:55.696Z,1683740635.696 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4599
2023-05-10T17:43:55.698Z,1683740635.698 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-05-10T17:43:55.699Z,1683740635.699 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-05-10T17:43:55.700Z,1683740635.700 [logger ThreadHandler](INFO): Protected caller Thread ID is 4600
2023-05-10T17:43:55.703Z,1683740635.703 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-05-10T17:43:55.704Z,1683740635.704 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-05-10T17:43:55.705Z,1683740635.705 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-05-10T17:43:56.150Z,1683740636.150 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-05-10T17:43:56.150Z,1683740636.150 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-05-10T17:43:56.354Z,1683740636.354 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-05-10T17:43:56.355Z,1683740636.355 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-05-10T17:43:56.433Z,1683740636.433 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-05-10T17:43:56.643Z,1683740636.643 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-05-10T17:43:56.644Z,1683740636.644 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-05-10T17:43:56.720Z,1683740636.720 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-05-10T17:43:56.813Z,1683740636.813 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-05-10T17:43:56.814Z,1683740636.814 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-05-10T17:43:57.137Z,1683740637.137 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-05-10T17:43:57.137Z,1683740637.137 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-05-10T17:43:57.265Z,1683740637.265 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-05-10T17:43:57.266Z,1683740637.266 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-05-10T17:43:57.742Z,1683740637.742 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-05-10T17:43:57.743Z,1683740637.743 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-05-10T17:43:57.917Z,1683740637.917 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-05-10T17:43:57.918Z,1683740637.918 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-05-10T17:43:58.298Z,1683740638.298 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-05-10T17:43:58.299Z,1683740638.299 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-05-10T17:43:58.567Z,1683740638.567 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-05-10T17:43:58.567Z,1683740638.567 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-05-10T17:43:58.673Z,1683740638.673 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-05-10T17:43:58.674Z,1683740638.674 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-05-10T17:43:59.418Z,1683740639.418 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-05-10T17:43:59.419Z,1683740639.419 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-05-10T17:43:59.789Z,1683740639.789 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-05-10T17:43:59.790Z,1683740639.790 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-05-10T17:44:00.188Z,1683740640.188 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-05-10T17:44:00.190Z,1683740640.190 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2023-05-10T17:44:00.191Z,1683740640.191 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2023-05-10T17:44:00.534Z,1683740640.534 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2023-05-10T17:44:00.813Z,1683740640.813 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2023-05-10T17:44:00.000Z,1683740641.000 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2023-05-10T17:44:01.084Z,1683740641.084 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2023-05-10T17:44:01.341Z,1683740641.341 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-05-10T17:44:01.342Z,1683740641.342 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2023-05-10T17:44:01.443Z,1683740641.443 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2023-05-10T17:44:01.553Z,1683740641.553 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2023-05-10T17:44:01.661Z,1683740641.661 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2023-05-10T17:44:01.805Z,1683740641.805 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2023-05-10T17:44:01.902Z,1683740641.902 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2023-05-10T17:44:01.994Z,1683740641.994 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2023-05-10T17:44:01.994Z,1683740641.994 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-05-10T17:44:02.006Z,1683740642.006 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-05-10T17:44:02.017Z,1683740642.017 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-05-10T17:44:02.018Z,1683740642.018 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-05-10T17:44:02.115Z,1683740642.115 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-05-10T17:44:02.115Z,1683740642.115 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-05-10T17:44:02.165Z,1683740642.165 [VerticalControl](DEBUG): Construct VerticalControl.
2023-05-10T17:44:02.223Z,1683740642.223 [VerticalControl] Loaded
2023-05-10T17:44:02.224Z,1683740642.224 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-05-10T17:44:02.226Z,1683740642.226 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-05-10T17:44:02.266Z,1683740642.266 [HorizontalControl] Loaded
2023-05-10T17:44:02.267Z,1683740642.267 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-05-10T17:44:02.269Z,1683740642.269 [SpeedControl](DEBUG): Construct SpeedControl.
2023-05-10T17:44:02.272Z,1683740642.272 [SpeedControl] Loaded
2023-05-10T17:44:02.272Z,1683740642.272 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-05-10T17:44:02.275Z,1683740642.275 [LoopControl](DEBUG): Construct LoopControl.
2023-05-10T17:44:02.275Z,1683740642.275 [LoopControl] Loaded
2023-05-10T17:44:02.276Z,1683740642.276 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-05-10T17:44:02.276Z,1683740642.276 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-05-10T17:44:02.277Z,1683740642.277 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-05-10T17:44:02.428Z,1683740642.428 [BuoyancyServo] Loaded
2023-05-10T17:44:02.428Z,1683740642.428 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-05-10T17:44:02.448Z,1683740642.448 [ElevatorServo] Loaded
2023-05-10T17:44:02.448Z,1683740642.448 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-05-10T17:44:02.467Z,1683740642.467 [MassServo] Loaded
2023-05-10T17:44:02.467Z,1683740642.467 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-05-10T17:44:02.485Z,1683740642.485 [RudderServo] Loaded
2023-05-10T17:44:02.486Z,1683740642.486 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-05-10T17:44:02.500Z,1683740642.500 [ThrusterHE] Loaded
2023-05-10T17:44:02.500Z,1683740642.500 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-05-10T17:44:02.500Z,1683740642.500 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-05-10T17:44:02.501Z,1683740642.501 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-05-10T17:44:02.550Z,1683740642.550 [DepthRateCalculator] Loaded
2023-05-10T17:44:02.551Z,1683740642.551 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-05-10T17:44:02.555Z,1683740642.555 [PitchRateCalculator] Loaded
2023-05-10T17:44:02.556Z,1683740642.556 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-05-10T17:44:02.565Z,1683740642.565 [SpeedCalculator] Loaded
2023-05-10T17:44:02.565Z,1683740642.565 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-05-10T17:44:02.569Z,1683740642.569 [YawRateCalculator] Loaded
2023-05-10T17:44:02.570Z,1683740642.570 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-05-10T17:44:02.588Z,1683740642.588 [ElevatorOffsetCalculator] Loaded
2023-05-10T17:44:02.588Z,1683740642.588 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-05-10T17:44:02.589Z,1683740642.589 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-05-10T17:44:02.589Z,1683740642.589 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-05-10T17:44:02.700Z,1683740642.700 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-05-10T17:44:02.701Z,1683740642.701 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-05-10T17:44:02.714Z,1683740642.714 [NavChart] Loaded
2023-05-10T17:44:02.714Z,1683740642.714 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-05-10T17:44:02.720Z,1683740642.720 [UniversalFixResidualReporter] Loaded
2023-05-10T17:44:02.720Z,1683740642.720 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-05-10T17:44:02.721Z,1683740642.721 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-05-10T17:44:02.721Z,1683740642.721 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-05-10T17:44:03.144Z,1683740643.144 [AHRS_M2] Loaded
2023-05-10T17:44:03.144Z,1683740643.144 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-05-10T17:44:03.179Z,1683740643.179 [BackseatComponent] Loaded
2023-05-10T17:44:03.179Z,1683740643.179 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-05-10T17:44:03.181Z,1683740643.181 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408E74E0
2023-05-10T17:44:03.181Z,1683740643.181 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 4682
2023-05-10T17:44:03.184Z,1683740643.184 [LcmUniversalReporter] Loaded
2023-05-10T17:44:03.184Z,1683740643.184 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-05-10T17:44:04.006Z,1683740644.006 [BPC1] Loaded
2023-05-10T17:44:04.006Z,1683740644.006 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-05-10T17:44:04.087Z,1683740644.087 [DataOverHttps] Loaded
2023-05-10T17:44:04.087Z,1683740644.087 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-05-10T17:44:04.088Z,1683740644.088 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409174E0
2023-05-10T17:44:04.088Z,1683740644.088 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4683
2023-05-10T17:44:04.109Z,1683740644.109 [Depth_Keller] Loaded
2023-05-10T17:44:04.109Z,1683740644.109 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-05-10T17:44:04.171Z,1683740644.171 [NAL9602] Loaded
2023-05-10T17:44:04.172Z,1683740644.172 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-05-10T17:44:04.202Z,1683740644.202 [Onboard] Loaded
2023-05-10T17:44:04.202Z,1683740644.202 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-05-10T17:44:04.203Z,1683740644.203 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409474E0
2023-05-10T17:44:04.203Z,1683740644.203 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4684
2023-05-10T17:44:04.215Z,1683740644.215 [Power24vConverter] Loaded
2023-05-10T17:44:04.216Z,1683740644.216 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-05-10T17:44:04.229Z,1683740644.229 [Radio_Surface] Loaded
2023-05-10T17:44:04.229Z,1683740644.229 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-05-10T17:44:04.230Z,1683740644.230 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409774E0
2023-05-10T17:44:04.231Z,1683740644.231 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4685
2023-05-10T17:44:04.317Z,1683740644.317 [DATMMP] Loaded
2023-05-10T17:44:04.317Z,1683740644.317 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2023-05-10T17:44:04.318Z,1683740644.318 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 409A84E0
2023-05-10T17:44:04.319Z,1683740644.319 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 4686
2023-05-10T17:44:04.319Z,1683740644.319 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-05-10T17:44:04.320Z,1683740644.320 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-05-10T17:44:04.392Z,1683740644.392 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-05-10T17:44:04.392Z,1683740644.392 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-05-10T17:44:04.539Z,1683740644.539 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-05-10T17:44:04.540Z,1683740644.540 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-05-10T17:44:04.566Z,1683740644.566 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-05-10T17:44:04.567Z,1683740644.567 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-05-10T17:44:04.733Z,1683740644.733 [CTD_Seabird] Loaded
2023-05-10T17:44:04.733Z,1683740644.733 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-05-10T17:44:04.734Z,1683740644.734 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ADE4E0
2023-05-10T17:44:04.734Z,1683740644.734 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4687
2023-05-10T17:44:04.767Z,1683740644.767 [ESPComponent] Loaded
2023-05-10T17:44:04.767Z,1683740644.767 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-05-10T17:44:04.787Z,1683740644.787 [PAR_Licor] Loaded
2023-05-10T17:44:04.787Z,1683740644.787 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-05-10T17:44:04.818Z,1683740644.818 [WetLabsBB2FL] Loaded
2023-05-10T17:44:04.818Z,1683740644.818 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-05-10T17:44:04.819Z,1683740644.819 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B0E4E0
2023-05-10T17:44:04.820Z,1683740644.820 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4688
2023-05-10T17:44:04.821Z,1683740644.821 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-05-10T17:44:04.821Z,1683740644.821 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-05-10T17:44:05.537Z,1683740645.537 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-05-10T17:44:05.538Z,1683740645.538 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-05-10T17:44:05.674Z,1683740645.674 [SBIT](DEBUG): Construct Startup Built In Test.
2023-05-10T17:44:05.683Z,1683740645.683 [SBIT] Loaded
2023-05-10T17:44:05.684Z,1683740645.684 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-05-10T17:44:05.687Z,1683740645.687 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-05-10T17:44:05.699Z,1683740645.699 [IBIT] Loaded
2023-05-10T17:44:05.699Z,1683740645.699 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-05-10T17:44:05.705Z,1683740645.705 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-05-10T17:44:05.810Z,1683740645.810 [CBIT] Loaded
2023-05-10T17:44:05.810Z,1683740645.810 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-05-10T17:44:05.810Z,1683740645.810 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-05-10T17:44:05.817Z,1683740645.817 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-05-10T17:44:05.819Z,1683740645.819 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-05-10T17:44:05.830Z,1683740645.830 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-05-10T17:44:05.831Z,1683740645.831 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BF14E0
2023-05-10T17:44:05.832Z,1683740645.832 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4689
2023-05-10T17:44:05.836Z,1683740645.836 [Supervisor](INFO): Main Thread ID is 4122
2023-05-10T17:44:05.836Z,1683740645.836 [Supervisor](DEBUG): Running supervisor.
2023-05-10T17:44:05.837Z,1683740645.837 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4690
2023-05-10T17:44:05.838Z,1683740645.838 [CommandExec](INFO): Initializing the command executive.
2023-05-10T17:44:05.839Z,1683740645.839 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4691
2023-05-10T17:44:05.841Z,1683740645.841 [controlThread ThreadHandler](INFO): Handler Thread ID is 4692
2023-05-10T17:44:05.842Z,1683740645.842 [controlThread](DEBUG): Initializing ControlThread
2023-05-10T17:44:05.842Z,1683740645.842 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-05-10T17:44:05.844Z,1683740645.844 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-05-10T17:44:05.845Z,1683740645.845 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-05-10T17:44:05.845Z,1683740645.845 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-05-10T17:44:05.847Z,1683740645.847 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-05-10T17:44:05.847Z,1683740645.847 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-05-10T17:44:05.847Z,1683740645.847 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-05-10T17:44:05.848Z,1683740645.848 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-05-10T17:44:05.848Z,1683740645.848 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-05-10T17:44:05.849Z,1683740645.849 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-05-10T17:44:05.850Z,1683740645.850 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-05-10T17:44:05.857Z,1683740645.857 [SBIT](INFO): Initialize SBIT Component.
2023-05-10T17:44:05.857Z,1683740645.857 [SBIT](IMPORTANT): git: 2023-01-24-38-g3a865bb8c
2023-05-10T17:44:05.858Z,1683740645.858 [SBIT](INFO): git hash: 3a865bb8cac9b788bcd3aa40e502ce5ce0bbf34f
2023-05-10T17:44:05.858Z,1683740645.858 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-05-10T17:44:05.859Z,1683740645.859 [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-10T17:44:05.860Z,1683740645.860 [SBIT](INFO): Beginning SBIT in 81.000000 seconds.
2023-05-10T17:44:05.861Z,1683740645.861 [IBIT](INFO): Initialize IBIT Component.
2023-05-10T17:44:05.862Z,1683740645.862 [CBIT](DEBUG): Initialize CBIT Component.
2023-05-10T17:44:05.862Z,1683740645.862 [logger ThreadHandler](INFO): Handler Thread ID is 4693
2023-05-10T17:44:05.873Z,1683740645.873 [CBIT](DEBUG): Initialized mux pins.
2023-05-10T17:44:05.873Z,1683740645.873 [CBIT](DEBUG): Initializing the watchdog timer.
2023-05-10T17:44:05.881Z,1683740645.881 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 4694
2023-05-10T17:44:05.893Z,1683740645.893 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4695
2023-05-10T17:44:05.894Z,1683740645.894 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-05-10T17:44:05.897Z,1683740645.897 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-05-10T17:44:05.897Z,1683740645.897 [CBIT](DEBUG): Initializing heartbeat.
2023-05-10T17:44:05.905Z,1683740645.905 [Onboard ThreadHandler](INFO): Handler Thread ID is 4696
2023-05-10T17:44:05.923Z,1683740645.923 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4697
2023-05-10T17:44:05.945Z,1683740645.945 [DATMMP ThreadHandler](INFO): Handler Thread ID is 4698
2023-05-10T17:44:05.946Z,1683740645.946 [DATMMP](INFO): Start
2023-05-10T17:44:05.946Z,1683740645.946 [DATMMP](INFO): Powering up
2023-05-10T17:44:05.946Z,1683740645.946 [DATMMP](DEBUG): Initializing DATMMP.
2023-05-10T17:44:05.950Z,1683740645.950 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4700
2023-05-10T17:44:05.950Z,1683740645.950 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-05-10T17:44:05.954Z,1683740645.954 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4702
2023-05-10T17:44:05.957Z,1683740645.957 [WetLabsBB2FL](INFO): Powering up
2023-05-10T17:44:05.958Z,1683740645.958 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4704
2023-05-10T17:44:05.961Z,1683740645.961 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-05-10T17:44:05.961Z,1683740645.961 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-05-10T17:44:05.962Z,1683740645.962 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-05-10T17:44:05.962Z,1683740645.962 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-05-10T17:44:05.962Z,1683740645.962 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-05-10T17:44:05.962Z,1683740645.962 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-05-10T17:44:05.962Z,1683740645.962 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-05-10T17:44:05.962Z,1683740645.962 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-05-10T17:44:05.969Z,1683740645.969 [CBIT](DEBUG): Deactivating GF circuits.
2023-05-10T17:44:05.969Z,1683740645.969 [CBIT](DEBUG): Deactivating emergency mode.
2023-05-10T17:44:06.005Z,1683740646.005 [CBIT](DEBUG): Backplane powered.
2023-05-10T17:44:06.006Z,1683740646.006 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-05-10T17:44:06.018Z,1683740646.018 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-10T17:44:06.041Z,1683740646.041 [MissionManager](DEBUG):
2023-05-10T17:44:06.042Z,1683740646.042 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-05-10T17:44:06.121Z,1683740646.121 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-05-10T17:44:06.122Z,1683740646.122 [Default:A.Wait](DEBUG): Construct Wait.
2023-05-10T17:44:06.124Z,1683740646.124 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-10T17:44:06.190Z,1683740646.190 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-05-10T17:44:06.193Z,1683740646.193 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-05-10T17:44:06.197Z,1683740646.197 [DATMMP](INFO): Starting
2023-05-10T17:44:06.218Z,1683740646.218 [Default:E.Execute](DEBUG): Construct Execute.
2023-05-10T17:44:06.238Z,1683740646.238 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2023-05-10T17:44:06.242Z,1683740646.242 [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-10T17:44:06.265Z,1683740646.265 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-05-10T17:44:06.329Z,1683740646.329 [Radio_Surface](INFO): Powering up
2023-05-10T17:44:06.359Z,1683740646.359 [Power24vConverter](INFO): Powering up.
2023-05-10T17:44:06.407Z,1683740646.407 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-05-10T17:44:06.413Z,1683740646.413 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-05-10T17:44:06.414Z,1683740646.414 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-05-10T17:44:06.421Z,1683740646.421 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-05-10T17:44:06.422Z,1683740646.422 [MassServo](DEBUG): Initializing EZServoServo.
2023-05-10T17:44:06.429Z,1683740646.429 [MassServo](DEBUG): Initializing MassServo.
2023-05-10T17:44:06.430Z,1683740646.430 [RudderServo](DEBUG): Initializing EZServoServo.
2023-05-10T17:44:06.437Z,1683740646.437 [RudderServo](DEBUG): Initializing RudderServo.
2023-05-10T17:44:06.438Z,1683740646.438 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-05-10T17:44:06.445Z,1683740646.445 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-05-10T17:44:06.449Z,1683740646.449 [DATMMP](INFO): Starting
2023-05-10T17:44:06.701Z,1683740646.701 [DATMMP](INFO): Starting
2023-05-10T17:44:06.953Z,1683740646.953 [DATMMP](INFO): Starting
2023-05-10T17:44:07.205Z,1683740647.205 [DATMMP](INFO): Starting
2023-05-10T17:44:07.389Z,1683740647.389 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2023-05-10T17:44:07.389Z,1683740647.389 [RudderServo](FAULT): Rudder failed to initialize
2023-05-10T17:44:07.389Z,1683740647.389 [RudderServo] Communications Fault, FailCount= 1
2023-05-10T17:44:07.389Z,1683740647.389 [RudderServo](ERROR): Communications Fault
2023-05-10T17:44:07.393Z,1683740647.393 [CBIT](ERROR): Communications Fault in component: RudderServo
2023-05-10T17:44:07.457Z,1683740647.457 [DATMMP](INFO): Starting
2023-05-10T17:44:07.598Z,1683740647.598 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-05-10T17:44:07.598Z,1683740647.598 [RudderServo](INFO): Powering down
2023-05-10T17:44:07.709Z,1683740647.709 [DATMMP](INFO): Starting
2023-05-10T17:44:07.961Z,1683740647.961 [DATMMP](INFO): Starting
2023-05-10T17:44:08.065Z,1683740648.065 [WetLabsBB2FL](INFO): Powering down
2023-05-10T17:44:08.213Z,1683740648.213 [DATMMP](INFO): Starting
2023-05-10T17:44:08.288Z,1683740648.288 [RudderServo](DEBUG): Initializing EZServoServo.
2023-05-10T17:44:08.409Z,1683740648.409 [RudderServo](DEBUG): Initializing RudderServo.
2023-05-10T17:44:08.413Z,1683740648.413 [CBIT](INFO): Clearing failed state for component RudderServo
2023-05-10T17:44:08.414Z,1683740648.414 [RudderServo] No Fault, FailCount= 1
2023-05-10T17:44:08.465Z,1683740648.465 [DATMMP](INFO): Starting
2023-05-10T17:44:08.717Z,1683740648.717 [DATMMP](INFO): Starting
2023-05-10T17:44:08.969Z,1683740648.969 [DATMMP](INFO): Starting
2023-05-10T17:44:09.221Z,1683740649.221 [DATMMP](INFO): Starting
2023-05-10T17:44:09.473Z,1683740649.473 [DATMMP](INFO): Starting
2023-05-10T17:44:09.725Z,1683740649.725 [DATMMP](INFO): Starting
2023-05-10T17:44:09.978Z,1683740649.978 [DATMMP](INFO): Starting
2023-05-10T17:44:10.229Z,1683740650.229 [DATMMP](INFO): Starting
2023-05-10T17:44:10.481Z,1683740650.481 [DATMMP](INFO): Starting
2023-05-10T17:44:10.733Z,1683740650.733 [DATMMP](INFO): Starting
2023-05-10T17:44:10.985Z,1683740650.985 [DATMMP](INFO): Starting
2023-05-10T17:44:11.237Z,1683740651.237 [DATMMP](INFO): Starting
2023-05-10T17:44:11.489Z,1683740651.489 [DATMMP](INFO): Starting
2023-05-10T17:44:11.741Z,1683740651.741 [DATMMP](INFO): Starting
2023-05-10T17:44:11.993Z,1683740651.993 [DATMMP](INFO): Starting
2023-05-10T17:44:12.225Z,1683740652.225 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-05-10T17:44:12.245Z,1683740652.245 [DATMMP](INFO): Starting
2023-05-10T17:44:12.497Z,1683740652.497 [DATMMP](INFO): Starting
2023-05-10T17:44:12.749Z,1683740652.749 [DATMMP](INFO): Starting
2023-05-10T17:44:13.001Z,1683740653.001 [DATMMP](INFO): Starting
2023-05-10T17:44:13.253Z,1683740653.253 [DATMMP](INFO): Starting
2023-05-10T17:44:13.505Z,1683740653.505 [DATMMP](INFO): Starting
2023-05-10T17:44:13.757Z,1683740653.757 [DATMMP](INFO): Starting
2023-05-10T17:44:14.009Z,1683740654.009 [DATMMP](INFO): Starting
2023-05-10T17:44:14.261Z,1683740654.261 [DATMMP](INFO): Starting
2023-05-10T17:44:14.513Z,1683740654.513 [DATMMP](INFO): Starting
2023-05-10T17:44:14.765Z,1683740654.765 [DATMMP](INFO): Starting
2023-05-10T17:44:15.017Z,1683740655.017 [DATMMP](INFO): Starting
2023-05-10T17:44:15.269Z,1683740655.269 [DATMMP](INFO): Starting
2023-05-10T17:44:15.521Z,1683740655.521 [DATMMP](INFO): Starting
2023-05-10T17:44:15.773Z,1683740655.773 [DATMMP](INFO): Starting
2023-05-10T17:44:16.025Z,1683740656.025 [DATMMP](INFO): Starting
2023-05-10T17:44:16.277Z,1683740656.277 [DATMMP](INFO): Starting
2023-05-10T17:44:16.529Z,1683740656.529 [DATMMP](INFO): Starting
2023-05-10T17:44:16.781Z,1683740656.781 [DATMMP](INFO): Starting
2023-05-10T17:44:17.033Z,1683740657.033 [DATMMP](INFO): Starting
2023-05-10T17:44:17.285Z,1683740657.285 [DATMMP](INFO): Starting
2023-05-10T17:44:17.537Z,1683740657.537 [DATMMP](INFO): Starting
2023-05-10T17:44:17.789Z,1683740657.789 [DATMMP](INFO): Starting
2023-05-10T17:44:18.041Z,1683740658.041 [DATMMP](INFO): Starting
2023-05-10T17:44:18.041Z,1683740658.041 [DATMMP](INFO): DAT read:
2023-05-10T17:44:18.042Z,1683740658.042 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series
2023-05-10T17:44:18.293Z,1683740658.293 [DATMMP](INFO): Starting
2023-05-10T17:44:18.545Z,1683740658.545 [DATMMP](INFO): Starting
2023-05-10T17:44:18.797Z,1683740658.797 [DATMMP](INFO): Starting
2023-05-10T17:44:19.049Z,1683740659.049 [DATMMP](INFO): Starting
2023-05-10T17:44:19.301Z,1683740659.301 [DATMMP](INFO): Starting
2023-05-10T17:44:19.553Z,1683740659.553 [DATMMP](INFO): Starting
2023-05-10T17:44:19.805Z,1683740659.805 [DATMMP](INFO): Starting
2023-05-10T17:44:19.805Z,1683740659.805 [DATMMP](INFO): DAT read: MF Frequency Band
2023-05-10T17:44:19.807Z,1683740659.807 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2023-05-10T17:44:19.807Z,1683740659.807 [DATMMP](INFO): DAT read: Apr 10 2000 17:44:14
2023-05-10T17:44:20.057Z,1683740660.057 [DATMMP](INFO): Starting
2023-05-10T17:44:20.309Z,1683740660.309 [DATMMP](INFO): Starting
2023-05-10T17:44:20.561Z,1683740660.561 [DATMMP](INFO): Starting
2023-05-10T17:44:20.813Z,1683740660.813 [DATMMP](INFO): Starting
2023-05-10T17:44:21.065Z,1683740661.065 [DATMMP](INFO): Starting
2023-05-10T17:44:21.066Z,1683740661.066 [DATMMP](INFO): DAT read: Features enabled [Bearing]
2023-05-10T17:44:21.067Z,1683740661.067 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2023-05-10T17:44:21.067Z,1683740661.067 [DATMMP](INFO): commRate: 800
2023-05-10T17:44:21.317Z,1683740661.317 [DATMMP](INFO): Starting
2023-05-10T17:44:21.569Z,1683740661.569 [DATMMP](INFO): Starting
2023-05-10T17:44:21.821Z,1683740661.821 [DATMMP](INFO): Starting
2023-05-10T17:44:22.073Z,1683740662.073 [DATMMP](INFO): Starting
2023-05-10T17:44:22.325Z,1683740662.325 [DATMMP](INFO): Starting
2023-05-10T17:44:22.577Z,1683740662.577 [DATMMP](INFO): Starting
2023-05-10T17:44:22.829Z,1683740662.829 [DATMMP](INFO): Starting
2023-05-10T17:44:23.081Z,1683740663.081 [DATMMP](INFO): Starting
2023-05-10T17:44:23.133Z,1683740663.133 [DATMMP](INFO): entering command mode
2023-05-10T17:44:23.333Z,1683740663.333 [DATMMP](INFO): Starting
2023-05-10T17:44:23.333Z,1683740663.333 [DATMMP](INFO): DAT read:
2023-05-10T17:44:23.333Z,1683740663.333 [DATMMP](INFO): DAT read: user:1>
2023-05-10T17:44:23.334Z,1683740663.334 [DATMMP](DEBUG): read user prompt 1: user:1>
2023-05-10T17:44:23.334Z,1683740663.334 [DATMMP](INFO): entering MMP mode
2023-05-10T17:44:23.585Z,1683740663.585 [DATMMP](INFO): Starting
2023-05-10T17:44:23.585Z,1683740663.585 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-10T17:44:23.587Z,1683740663.587 [DATMMP](INFO): status rx: x1
2023-05-10T17:44:23.587Z,1683740663.587 [DATMMP](INFO): MMP status: 1: started
2023-05-10T17:44:23.588Z,1683740663.588 [DATMMP](INFO): Received message type: status
2023-05-10T17:44:23.588Z,1683740663.588 [DATMMP](INFO): Handled
2023-05-10T17:44:23.837Z,1683740663.837 [DATMMP](INFO): Starting
2023-05-10T17:44:23.838Z,1683740663.838 [DATMMP](INFO): Sent Tx Power Config 8
2023-05-10T17:44:23.839Z,1683740663.839 [DATMMP](INFO): Setting local address: 5
2023-05-10T17:44:23.840Z,1683740663.840 [DATMMP](INFO): Setting time to: 17:44:23 And date to:5/10/2023
2023-05-10T17:44:24.089Z,1683740664.089 [DATMMP](INFO): Starting
2023-05-10T17:44:24.089Z,1683740664.089 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-10T17:44:24.090Z,1683740664.090 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0
2023-05-10T17:44:24.091Z,1683740664.091 [DATMMP](INFO): Received message type: mdmpower
2023-05-10T17:44:24.091Z,1683740664.091 [DATMMP](INFO): Handled
2023-05-10T17:44:24.341Z,1683740664.341 [DATMMP](INFO): Starting
2023-05-10T17:44:24.341Z,1683740664.341 [DATMMP](INFO): GSXN notify for xid: 10
2023-05-10T17:44:24.342Z,1683740664.342 [DATMMP](INFO): sreg_txpower rx: x8
2023-05-10T17:44:24.342Z,1683740664.342 [DATMMP](INFO): Received message type: sreg_txpower
2023-05-10T17:44:24.342Z,1683740664.342 [DATMMP](INFO): Match for : sreg_txpower
2023-05-10T17:44:24.342Z,1683740664.342 [DATMMP](INFO): ACK
2023-05-10T17:44:24.593Z,1683740664.593 [DATMMP](INFO): Starting
2023-05-10T17:44:24.593Z,1683740664.593 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-10T17:44:24.594Z,1683740664.594 [DATMMP](INFO): mdmlocal rx: x5 x0 x0 x0
2023-05-10T17:44:24.594Z,1683740664.594 [DATMMP](INFO): Received message type: mdmlocal
2023-05-10T17:44:24.594Z,1683740664.594 [DATMMP](INFO): Handled
2023-05-10T17:44:24.845Z,1683740664.845 [DATMMP](INFO): Starting
2023-05-10T17:44:24.845Z,1683740664.845 [DATMMP](INFO): GSXN notify for xid: 11
2023-05-10T17:44:24.846Z,1683740664.846 [DATMMP](INFO): sreg_locaddr rx: x5
2023-05-10T17:44:24.846Z,1683740664.846 [DATMMP](INFO): Received message type: sreg_locaddr
2023-05-10T17:44:24.846Z,1683740664.846 [DATMMP](INFO): Match for : sreg_locaddr
2023-05-10T17:44:24.846Z,1683740664.846 [DATMMP](INFO): ACK
2023-05-10T17:44:25.097Z,1683740665.097 [DATMMP](INFO): Starting
2023-05-10T17:44:25.099Z,1683740665.099 [DATMMP](INFO): GSXN notify for xid: 12
2023-05-10T17:44:25.110Z,1683740665.110 [DATMMP](INFO): timedate rx: x0 x11 x2C x17 x4 xA x7B x0
2023-05-10T17:44:25.110Z,1683740665.110 [DATMMP](INFO): Received message type: timedate
2023-05-10T17:44:25.110Z,1683740665.110 [DATMMP](INFO): Match for : timedate
2023-05-10T17:44:25.110Z,1683740665.110 [DATMMP](INFO): ACK
2023-05-10T17:44:25.349Z,1683740665.349 [DATMMP](INFO): Starting
2023-05-10T17:44:25.349Z,1683740665.349 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-10T17:44:25.350Z,1683740665.350 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0
2023-05-10T17:44:25.350Z,1683740665.350 [DATMMP](INFO): Received message type: sys_verb
2023-05-10T17:44:25.350Z,1683740665.350 [DATMMP](INFO): Handled
2023-05-10T17:44:25.601Z,1683740665.601 [DATMMP](INFO): Starting
2023-05-10T17:44:25.601Z,1683740665.601 [DATMMP](INFO): GSXN notify for xid: 13
2023-05-10T17:44:25.602Z,1683740665.602 [DATMMP](INFO): sreg_verbosity rx: x0
2023-05-10T17:44:25.602Z,1683740665.602 [DATMMP](INFO): Received message type: sreg_verbosity
2023-05-10T17:44:25.602Z,1683740665.602 [DATMMP](INFO): Match for : sreg_verbosity
2023-05-10T17:44:25.602Z,1683740665.602 [DATMMP](INFO): ACK
2023-05-10T17:44:25.853Z,1683740665.853 [DATMMP](INFO): Starting
2023-05-10T17:44:25.853Z,1683740665.853 [DATMMP](INFO): GSXN notify for xid: 14
2023-05-10T17:44:25.854Z,1683740665.854 [DATMMP](INFO): version rx: x2 x5
2023-05-10T17:44:25.854Z,1683740665.854 [DATMMP](INFO): DatMMPVersion: 2.5
2023-05-10T17:44:25.854Z,1683740665.854 [DATMMP](INFO): Received message type: version
2023-05-10T17:44:25.854Z,1683740665.854 [DATMMP](INFO): Match for : version
2023-05-10T17:44:25.854Z,1683740665.854 [DATMMP](INFO): ACK
2023-05-10T17:44:25.855Z,1683740665.855 [DATMMP](INFO): in MMP mode: config complete going to runnable
2023-05-10T17:44:34.534Z,1683740674.534 [NAL9602](INFO): Powering up NAL9602
2023-05-10T17:44:39.461Z,1683740679.461 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-10T17:44:39.462Z,1683740679.462 [DATMMP](INFO): timesync rx: x0 x1 x1 x0
2023-05-10T17:44:39.462Z,1683740679.462 [DATMMP](INFO): Received message type: timesync
2023-05-10T17:44:39.462Z,1683740679.462 [DATMMP](INFO): Handled
2023-05-10T17:44:45.446Z,1683740685.446 [NAL9602](INFO): NAL9602 initialized
2023-05-10T17:44:49.289Z,1683740689.289 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-10T17:44:49.290Z,1683740689.290 [DATMMP](INFO): timesync rx: x1 x0 x1 x0
2023-05-10T17:44:49.290Z,1683740689.290 [DATMMP](INFO): Received message type: timesync
2023-05-10T17:44:49.290Z,1683740689.290 [DATMMP](INFO): Handled
2023-05-10T17:45:27.476Z,1683740727.476 [SBIT](IMPORTANT): Beginning Startup BIT
2023-05-10T17:45:27.501Z,1683740727.501 [CBIT](IMPORTANT): Beginning ground fault scan
2023-05-10T17:45:38.391Z,1683740738.391 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.009259
CHAN A1 (24V): -0.007683
CHAN A2 (12V): -0.004269
CHAN A3 (5V): -0.002822
CHAN B0 (3.3V): -0.001240
CHAN B1 (3.15aV): -0.001237
CHAN B2 (3.15bV): -0.000967
CHAN B3 (GND): -0.000877
OPEN: 0.004574
Full Scale: +/- 1 mA
2023-05-10T17:46:12.562Z,1683740772.562 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.8 degree
2023-05-10T17:46:12.565Z,1683740772.565 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2023-05-10T17:46:12.566Z,1683740772.566 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.9 degree
2023-05-10T17:46:12.597Z,1683740772.597 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2023-05-10T17:46:12.598Z,1683740772.598 [CommandExec](IMPORTANT): got command maintain sensor Depth_Keller.depth 0.8 meter
2023-05-10T17:46:12.602Z,1683740772.602 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2023-05-10T17:46:12.602Z,1683740772.602 [CommandExec](IMPORTANT): got command report touch DATMMP.acoustic_contact_range
2023-05-10T17:46:12.603Z,1683740772.603 [CommandExec](IMPORTANT): got command report touch acoustic_contact_range
2023-05-10T17:46:12.951Z,1683740772.951 [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-10T17:46:21.659Z,1683740781.659 [SBIT](IMPORTANT): SBIT PASSED
2023-05-10T17:46:21.659Z,1683740781.659 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-05-10T17:46:21.660Z,1683740781.660 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;
2023-05-10T17:46:21.660Z,1683740781.660 [SBIT](IMPORTANT): BackseatComponent.alwaysOn=0 bool;
2023-05-10T17:46:21.661Z,1683740781.661 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;
2023-05-10T17:46:21.661Z,1683740781.661 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;
2023-05-10T17:46:21.661Z,1683740781.661 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=10 minute;
2023-05-10T17:46:21.661Z,1683740781.661 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;
2023-05-10T17:46:21.661Z,1683740781.661 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;
2023-05-10T17:46:21.661Z,1683740781.661 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water;
2023-05-10T17:46:21.661Z,1683740781.661 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree;
2023-05-10T17:46:21.661Z,1683740781.661 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=184.896478 cubic_centimeter;
2023-05-10T17:46:21.662Z,1683740781.662 [SBIT](IMPORTANT): VerticalControl.elevDeadband=0.1 angular_degree;
2023-05-10T17:46:21.662Z,1683740781.662 [SBIT](IMPORTANT): VerticalControl.massDefault=15.484465 millimeter;
2023-05-10T17:46:22.054Z,1683740782.054 [MissionManager](IMPORTANT): Started mission Startup
2023-05-10T17:46:22.055Z,1683740782.055 [Startup] Running Loop=1
2023-05-10T17:46:22.055Z,1683740782.055 [Startup](DEBUG): Aggregate::initialize Startup
2023-05-10T17:46:22.055Z,1683740782.055 [Startup:A.GoToSurface] Running Loop=1
2023-05-10T17:46:22.055Z,1683740782.055 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-10T17:46:22.055Z,1683740782.055 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-10T17:46:22.056Z,1683740782.056 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-10T17:46:22.056Z,1683740782.056 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-10T17:46:22.077Z,1683740782.077 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-10T17:46:22.077Z,1683740782.077 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-10T17:46:22.078Z,1683740782.078 [Startup:StartupSatComms] Running Loop=1
2023-05-10T17:46:22.079Z,1683740782.079 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-05-10T17:46:22.079Z,1683740782.079 [Startup:StartupSatComms:A] Running Loop=1
2023-05-10T17:46:22.417Z,1683740782.417 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-05-10T17:46:22.418Z,1683740782.418 [Startup:StartupSatComms:A] Stopped
2023-05-10T17:46:22.418Z,1683740782.418 [Startup:StartupSatComms:B] Running Loop=1
2023-05-10T17:46:22.821Z,1683740782.821 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-10T17:46:25.931Z,1683740785.931 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1683740772 second
2023-05-10T17:46:32.510Z,1683740792.510 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-10T17:46:33.875Z,1683740793.875 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004706
2023-05-10T17:46:41.264Z,1683740801.264 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20230510T172607/Courier0007.lzma
2023-05-10T17:46:42.266Z,1683740802.266 [DataOverHttps](INFO): Moved sent file to Logs/20230510T172607/Courier0007.lzma.bak
2023-05-10T17:46:42.267Z,1683740802.267 [DataOverHttps](INFO): SBD MOMSN=18286617
2023-05-10T17:46:45.837Z,1683740805.837 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-10T17:46:52.870Z,1683740812.870 [CommandExec](IMPORTANT): got command set NAL9602.platform_communications 1 bool
2023-05-10T17:46:53.132Z,1683740813.132 [Startup:StartupSatComms:B] Stopped
2023-05-10T17:46:53.133Z,1683740813.133 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-05-10T17:46:53.133Z,1683740813.133 [Startup:StartupSatComms] Stopped
2023-05-10T17:46:53.133Z,1683740813.133 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-05-10T17:46:53.134Z,1683740813.134 [Startup](INFO): Completed Startup
2023-05-10T17:46:53.134Z,1683740813.134 [MissionManager](INFO): Startup is completed.
2023-05-10T17:46:53.134Z,1683740813.134 [MissionManager](INFO): Uninitializing Mission Startup
2023-05-10T17:46:53.134Z,1683740813.134 [Startup] Stopped
2023-05-10T17:46:53.134Z,1683740813.134 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-05-10T17:46:53.134Z,1683740813.134 [Startup:A.GoToSurface] Stopped
2023-05-10T17:46:53.134Z,1683740813.134 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-10T17:46:53.527Z,1683740813.527 [MissionManager](IMPORTANT): Started mission Default
2023-05-10T17:46:53.528Z,1683740813.528 [Default] Running Loop=1
2023-05-10T17:46:53.528Z,1683740813.528 [Default](DEBUG): Aggregate::initialize Default
2023-05-10T17:46:53.528Z,1683740813.528 [Default:B.GoToSurface] Running Loop=1
2023-05-10T17:46:53.528Z,1683740813.528 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-10T17:46:53.528Z,1683740813.528 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-10T17:46:53.545Z,1683740813.545 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-10T17:46:53.545Z,1683740813.545 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-10T17:46:53.545Z,1683740813.545 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-10T17:46:53.546Z,1683740813.546 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-10T17:46:53.546Z,1683740813.546 [Default:A.Wait] Running Loop=1
2023-05-10T17:46:53.546Z,1683740813.546 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-05-10T17:46:59.171Z,1683740819.171 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-10T17:47:00.484Z,1683740820.484 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230510T174355/Courier0000.lzma
2023-05-10T17:47:00.660Z,1683740820.660 [CommandExec](IMPORTANT): got command run ./Missions/Demo/senddata_direct_and_track_test.xml
2023-05-10T17:47:00.660Z,1683740820.660 [MissionManager](INFO): Loading Mission from file: ./Missions/Demo/senddata_direct_and_track_test.xml
2023-05-10T17:47:00.713Z,1683740820.713 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcousticTargetContactLabel = 9.000000 count
2023-05-10T17:47:00.716Z,1683740820.716 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcousticTargetDepth = 0.000000 m
2023-05-10T17:47:00.719Z,1683740820.719 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.TrackingUpdatePeriod = 15.000000 s
2023-05-10T17:47:00.722Z,1683740820.722 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfPings = 1.000000 count
2023-05-10T17:47:00.758Z,1683740820.758 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfFixesLowPass = 2.000000 count
2023-05-10T17:47:00.767Z,1683740820.767 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.NumberOfStartingFixesToIgnore = 2.000000 count
2023-05-10T17:47:00.802Z,1683740820.802 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.AcommsInterval = 1.000000 min
2023-05-10T17:47:00.817Z,1683740820.817 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.modemId1 = 9.000000 enum
2023-05-10T17:47:00.820Z,1683740820.820 [MissionManager](INFO): DefineArg senddata_direct_and_track_test.modemId2 = 5.000000 enum
2023-05-10T17:47:00.831Z,1683740820.831 [MissionManager](INFO): DefineOutput senddata_direct_and_track_test.ModemID = 1.000000 enum
2023-05-10T17:47:00.951Z,1683740820.951 [senddata_direct_and_track_test:DirectData:A.Wait](DEBUG): Construct Wait.
2023-05-10T17:47:00.988Z,1683740820.988 [MissionManager](DEBUG):
The acoustic address of the asset to be tracked. 21 is waveglider Tiny
9
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.
0
How long to wait between acoustic queries
15
Number of return pings to request with each acoustic query (more than 1
will activate oneway mode)
1
Number of fixes to average to produce smoothed lat/lon/dep output
2
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.
2
1
Modem ID1.
9
Modem ID2.
5
Modem ID. Set to modemId1 or modemId2. Initialized to 1.
1
2023-05-10T17:47:00.991Z,1683740820.991 [CommandExec](IMPORTANT): Running ./Missions/Demo/senddata_direct_and_track_test.xml
2023-05-10T17:47:01.262Z,1683740821.262 [Default] Stopped
2023-05-10T17:47:01.262Z,1683740821.262 [Default](DEBUG): Aggregate::uninitialize Default
2023-05-10T17:47:01.262Z,1683740821.262 [Default:A.Wait] Stopped
2023-05-10T17:47:01.262Z,1683740821.262 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-10T17:47:01.262Z,1683740821.262 [Default:B.GoToSurface] Stopped
2023-05-10T17:47:01.262Z,1683740821.262 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-10T17:47:01.262Z,1683740821.262 [MissionManager](IMPORTANT): Started mission senddata_direct_and_track_test
2023-05-10T17:47:01.262Z,1683740821.262 [senddata_direct_and_track_test] Running Loop=1
2023-05-10T17:47:01.262Z,1683740821.262 [senddata_direct_and_track_test](DEBUG): Aggregate::initialize senddata_direct_and_track_test
2023-05-10T17:47:01.263Z,1683740821.263 [senddata_direct_and_track_test:TrackAC.] Running Loop=1
2023-05-10T17:47:01.263Z,1683740821.263 [senddata_direct_and_track_test:TrackAC.](INFO): Initializing TrackAcousticContact.
2023-05-10T17:47:01.263Z,1683740821.263 [senddata_direct_and_track_test:DirectData] Running Loop=1
2023-05-10T17:47:01.263Z,1683740821.263 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::initialize senddata_direct_and_track_test:DirectData
2023-05-10T17:47:01.263Z,1683740821.263 [senddata_direct_and_track_test:DirectData:A.Wait] Running Loop=1
2023-05-10T17:47:01.263Z,1683740821.263 [senddata_direct_and_track_test:DirectData:A.Wait](DEBUG): Initialize Wait Component.
2023-05-10T17:47:01.264Z,1683740821.264 [senddata_direct_and_track_test:TrackAC.] Running Loop=1
2023-05-10T17:47:01.486Z,1683740821.486 [DataOverHttps](INFO): Moved sent file to Logs/20230510T174355/Courier0000.lzma.bak
2023-05-10T17:47:01.486Z,1683740821.486 [DataOverHttps](INFO): SBD MOMSN=18286619
2023-05-10T17:47:12.501Z,1683740832.501 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-10T17:47:16.558Z,1683740836.558 [senddata_direct_and_track_test:TrackAC.](INFO): *** querying acoustic contact ***
2023-05-10T17:47:16.797Z,1683740836.797 [DATMMP](INFO): ****** received valid address query ******
2023-05-10T17:47:16.797Z,1683740836.797 [DATMMP](INFO): ****** received valid ping request ******
2023-05-10T17:47:16.798Z,1683740836.798 [DATMMP](INFO): Querying Benthos address 9
2023-05-10T17:47:16.798Z,1683740836.798 [DATMMP](INFO): type: 1 tat: 1
2023-05-10T17:47:17.049Z,1683740837.049 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-10T17:47:17.050Z,1683740837.050 [DATMMP](INFO): timestamp rx: x0 x11 x2F xF x3DC
2023-05-10T17:47:17.050Z,1683740837.050 [DATMMP](INFO): Received message type: timestamp
2023-05-10T17:47:17.050Z,1683740837.050 [DATMMP](INFO): Handled
2023-05-10T17:47:17.051Z,1683740837.051 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:17.301Z,1683740837.301 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:17.553Z,1683740837.553 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:17.805Z,1683740837.805 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:18.057Z,1683740838.057 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:18.309Z,1683740838.309 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:18.561Z,1683740838.561 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:18.813Z,1683740838.813 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:19.065Z,1683740839.065 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:19.317Z,1683740839.317 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-10T17:47:19.318Z,1683740839.318 [DATMMP](INFO): doppler rx: x0
2023-05-10T17:47:19.318Z,1683740839.318 [DATMMP](INFO): Received message type: doppler
2023-05-10T17:47:19.318Z,1683740839.318 [DATMMP](INFO): Handled
2023-05-10T17:47:19.318Z,1683740839.318 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:19.569Z,1683740839.569 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-10T17:47:19.570Z,1683740839.570 [DATMMP](INFO): timestamp rx: x1 x11 x2F x11 x50C
2023-05-10T17:47:19.571Z,1683740839.571 [DATMMP](INFO): Rx dataTimestamp_ set to:1683740849.647168
2023-05-10T17:47:19.571Z,1683740839.571 [DATMMP](INFO): Rx ping set to:1683740839.570218
2023-05-10T17:47:19.571Z,1683740839.571 [DATMMP](INFO): Received message type: timestamp
2023-05-10T17:47:19.571Z,1683740839.571 [DATMMP](INFO): Handled
2023-05-10T17:47:19.572Z,1683740839.572 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:19.821Z,1683740839.821 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:20.073Z,1683740840.073 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-10T17:47:20.074Z,1683740840.074 [DATMMP](INFO): nav_stat rx: x0 x1 x1
2023-05-10T17:47:20.074Z,1683740840.074 [DATMMP](INFO): Received message type: nav_stat
2023-05-10T17:47:20.074Z,1683740840.074 [DATMMP](INFO): Handled
2023-05-10T17:47:20.074Z,1683740840.074 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:20.326Z,1683740840.326 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-10T17:47:20.327Z,1683740840.327 [DATMMP](INFO): directional_debug rx: xDF7 x251 x23 x114 x1080 x12A1 x1212 x1633
2023-05-10T17:47:20.328Z,1683740840.328 [DATMMP](INFO): Received message type: directional_debug
2023-05-10T17:47:20.329Z,1683740840.329 [DATMMP](INFO): Handled
2023-05-10T17:47:20.329Z,1683740840.329 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:20.577Z,1683740840.577 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-10T17:47:20.579Z,1683740840.579 [DATMMP](INFO): directional rx: xDF1 x21F x5F5 xFDAF xBD1 xFDB5 x0 x5FA x4 xFFED x27B5966 x-43569E0
2023-05-10T17:47:20.581Z,1683740840.581 [DATMMP](INFO): Received message type: directional
2023-05-10T17:47:20.581Z,1683740840.581 [DATMMP](INFO): Handled
2023-05-10T17:47:20.581Z,1683740840.581 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:20.829Z,1683740840.829 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-10T17:47:20.830Z,1683740840.830 [DATMMP](INFO): remote_header rx: x0 x7A x0 x46 xC2 x5 x9 x0
2023-05-10T17:47:20.831Z,1683740840.831 [DATMMP](INFO): DATA Src=9, Dst=5
2023-05-10T17:47:20.831Z,1683740840.831 [DATMMP](INFO): Received message type: remote_header
2023-05-10T17:47:20.831Z,1683740840.831 [DATMMP](INFO): Handled
2023-05-10T17:47:20.831Z,1683740840.831 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:21.082Z,1683740841.082 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-10T17:47:21.084Z,1683740841.084 [DATMMP](INFO): acstats rx: x9 x0 x0 x2A4 x22 xA1 xBC xBA xD xFFFF x0 x24 x0 x0
2023-05-10T17:47:21.084Z,1683740841.084 [DATMMP](INFO): Received message type: acstats
2023-05-10T17:47:21.084Z,1683740841.084 [DATMMP](INFO): Handled
2023-05-10T17:47:21.084Z,1683740841.084 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:21.333Z,1683740841.333 [DATMMP](INFO): GSXN notify for xid: 15
2023-05-10T17:47:21.334Z,1683740841.334 [DATMMP](INFO): cmd_result rx: x2 x9 x0 x4
2023-05-10T17:47:21.335Z,1683740841.335 [DATMMP](ERROR): 4:Command error: data
2023-05-10T17:47:21.335Z,1683740841.335 [DATMMP](ERROR): 4:COMPLETE
2023-05-10T17:47:21.335Z,1683740841.335 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:21.588Z,1683740841.588 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:21.837Z,1683740841.837 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:22.089Z,1683740842.089 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:22.341Z,1683740842.341 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:22.593Z,1683740842.593 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:22.845Z,1683740842.845 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:23.097Z,1683740843.097 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:23.349Z,1683740843.349 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:23.601Z,1683740843.601 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:23.853Z,1683740843.853 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:24.105Z,1683740844.105 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:24.357Z,1683740844.357 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:24.609Z,1683740844.609 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:24.861Z,1683740844.861 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:25.113Z,1683740845.113 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:25.365Z,1683740845.365 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:25.617Z,1683740845.617 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:25.833Z,1683740845.833 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-10T17:47:25.869Z,1683740845.869 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:26.121Z,1683740846.121 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:26.373Z,1683740846.373 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:26.625Z,1683740846.625 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:26.877Z,1683740846.877 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:31.928Z,1683740851.928 [senddata_direct_and_track_test:TrackAC.](INFO): *** querying acoustic contact ***
2023-05-10T17:47:32.173Z,1683740852.173 [DATMMP](INFO): ****** received valid address query ******
2023-05-10T17:47:32.173Z,1683740852.173 [DATMMP](INFO): ****** received valid ping request ******
2023-05-10T17:47:32.174Z,1683740852.174 [DATMMP](INFO): Querying Benthos address 9
2023-05-10T17:47:32.174Z,1683740852.174 [DATMMP](INFO): type: 1 tat: 1
2023-05-10T17:47:32.425Z,1683740852.425 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-10T17:47:32.426Z,1683740852.426 [DATMMP](INFO): timestamp rx: x0 x11 x2F x1E x137B
2023-05-10T17:47:32.426Z,1683740852.426 [DATMMP](INFO): Received message type: timestamp
2023-05-10T17:47:32.427Z,1683740852.427 [DATMMP](INFO): Handled
2023-05-10T17:47:32.427Z,1683740852.427 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:32.677Z,1683740852.677 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:32.929Z,1683740852.929 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:33.181Z,1683740853.181 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:33.433Z,1683740853.433 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:33.685Z,1683740853.685 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:33.939Z,1683740853.939 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:34.189Z,1683740854.189 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:34.441Z,1683740854.441 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:34.693Z,1683740854.693 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-10T17:47:34.694Z,1683740854.694 [DATMMP](INFO): doppler rx: x0
2023-05-10T17:47:34.694Z,1683740854.694 [DATMMP](INFO): Received message type: doppler
2023-05-10T17:47:34.694Z,1683740854.694 [DATMMP](INFO): Handled
2023-05-10T17:47:34.694Z,1683740854.694 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:34.945Z,1683740854.945 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-10T17:47:34.946Z,1683740854.946 [DATMMP](INFO): timestamp rx: x1 x11 x2F x20 x14AB
2023-05-10T17:47:34.947Z,1683740854.947 [DATMMP](INFO): Rx dataTimestamp_ set to:1683740903.702464
2023-05-10T17:47:34.947Z,1683740854.947 [DATMMP](INFO): Rx ping set to:1683740854.946191
2023-05-10T17:47:34.947Z,1683740854.947 [DATMMP](INFO): Received message type: timestamp
2023-05-10T17:47:34.947Z,1683740854.947 [DATMMP](INFO): Handled
2023-05-10T17:47:34.947Z,1683740854.947 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:35.197Z,1683740855.197 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:35.449Z,1683740855.449 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-10T17:47:35.450Z,1683740855.450 [DATMMP](INFO): nav_stat rx: x0 x1 x1
2023-05-10T17:47:35.450Z,1683740855.450 [DATMMP](INFO): Received message type: nav_stat
2023-05-10T17:47:35.450Z,1683740855.450 [DATMMP](INFO): Handled
2023-05-10T17:47:35.450Z,1683740855.450 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:35.701Z,1683740855.701 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-10T17:47:35.702Z,1683740855.702 [DATMMP](INFO): directional_debug rx: x87C x28 x21 x7C7 x10B0 x12D1 x11F2 x15F3
2023-05-10T17:47:35.703Z,1683740855.703 [DATMMP](INFO): Received message type: directional_debug
2023-05-10T17:47:35.703Z,1683740855.703 [DATMMP](INFO): Handled
2023-05-10T17:47:35.704Z,1683740855.704 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:35.953Z,1683740855.953 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-10T17:47:35.955Z,1683740855.955 [DATMMP](INFO): directional rx: x87C xB xB70 xFFD8 x37A xFFC7 x0 x619 x4 xFFED x27B5966 x-43569E0
2023-05-10T17:47:35.956Z,1683740855.956 [DATMMP](INFO): Received message type: directional
2023-05-10T17:47:35.956Z,1683740855.956 [DATMMP](INFO): Handled
2023-05-10T17:47:35.957Z,1683740855.957 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:36.205Z,1683740856.205 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-10T17:47:36.206Z,1683740856.206 [DATMMP](INFO): remote_header rx: x0 x7A x0 x46 x10 x5 x9 x0
2023-05-10T17:47:36.207Z,1683740856.207 [DATMMP](INFO): DATA Src=9, Dst=5
2023-05-10T17:47:36.207Z,1683740856.207 [DATMMP](INFO): Received message type: remote_header
2023-05-10T17:47:36.207Z,1683740856.207 [DATMMP](INFO): Handled
2023-05-10T17:47:36.207Z,1683740856.207 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:36.457Z,1683740856.457 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-10T17:47:36.459Z,1683740856.459 [DATMMP](INFO): acstats rx: x9 x0 x0 x2C5 x22 x8D xCF xBF xD xFFFF x0 x22 x0 x0
2023-05-10T17:47:36.459Z,1683740856.459 [DATMMP](INFO): Received message type: acstats
2023-05-10T17:47:36.459Z,1683740856.459 [DATMMP](INFO): Handled
2023-05-10T17:47:36.459Z,1683740856.459 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:36.709Z,1683740856.709 [DATMMP](INFO): GSXN notify for xid: 16
2023-05-10T17:47:36.710Z,1683740856.710 [DATMMP](INFO): cmd_result rx: x2 x9 x0 x4
2023-05-10T17:47:36.710Z,1683740856.710 [DATMMP](ERROR): 4:Command error: data
2023-05-10T17:47:36.711Z,1683740856.711 [DATMMP](ERROR): 4:COMPLETE
2023-05-10T17:47:36.711Z,1683740856.711 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:36.961Z,1683740856.961 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:37.213Z,1683740857.213 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:37.465Z,1683740857.465 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:37.717Z,1683740857.717 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:37.969Z,1683740857.969 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:38.221Z,1683740858.221 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:38.473Z,1683740858.473 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:38.725Z,1683740858.725 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:38.977Z,1683740858.977 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:39.168Z,1683740859.168 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-10T17:47:39.229Z,1683740859.229 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:39.481Z,1683740859.481 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:39.733Z,1683740859.733 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:39.985Z,1683740859.985 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:40.237Z,1683740860.237 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:40.489Z,1683740860.489 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:40.741Z,1683740860.741 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:40.993Z,1683740860.993 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:41.258Z,1683740861.258 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:41.510Z,1683740861.510 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:41.761Z,1683740861.761 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:42.013Z,1683740862.013 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:42.265Z,1683740862.265 [DATMMP](INFO): Range requested while waiting for previous to complete.*
2023-05-10T17:47:42.669Z,1683740862.669 [CommandExec](IMPORTANT): got command stop
2023-05-10T17:47:42.669Z,1683740862.669 [CommandExec](IMPORTANT): Scheduling is paused
2023-05-10T17:47:42.670Z,1683740862.670 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2023-05-10T17:47:42.836Z,1683740862.836 [MissionManager](INFO): MissionManager is completed.
2023-05-10T17:47:42.841Z,1683740862.841 [MissionManager](INFO): Uninitializing Mission senddata_direct_and_track_test
2023-05-10T17:47:42.841Z,1683740862.841 [senddata_direct_and_track_test] Stopped
2023-05-10T17:47:42.841Z,1683740862.841 [senddata_direct_and_track_test](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test
2023-05-10T17:47:42.841Z,1683740862.841 [senddata_direct_and_track_test:TrackAC.] Stopped
2023-05-10T17:47:42.841Z,1683740862.841 [senddata_direct_and_track_test:TrackAC.](DEBUG): Uninitializing TrackAcousticContact.
2023-05-10T17:47:42.841Z,1683740862.841 [senddata_direct_and_track_test:DirectData] Stopped
2023-05-10T17:47:42.842Z,1683740862.842 [senddata_direct_and_track_test:DirectData](DEBUG): Aggregate::uninitialize senddata_direct_and_track_test:DirectData
2023-05-10T17:47:42.842Z,1683740862.842 [senddata_direct_and_track_test:DirectData:A.Wait] Stopped
2023-05-10T17:47:42.842Z,1683740862.842 [senddata_direct_and_track_test:DirectData:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-10T17:47:43.219Z,1683740863.219 [MissionManager](IMPORTANT): Started mission Default
2023-05-10T17:47:43.220Z,1683740863.220 [Default] Running Loop=1
2023-05-10T17:47:43.220Z,1683740863.220 [Default](DEBUG): Aggregate::initialize Default
2023-05-10T17:47:43.220Z,1683740863.220 [Default:B.GoToSurface] Running Loop=1
2023-05-10T17:47:43.220Z,1683740863.220 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-10T17:47:43.220Z,1683740863.220 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-10T17:47:43.221Z,1683740863.221 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-10T17:47:43.221Z,1683740863.221 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-10T17:47:43.222Z,1683740863.222 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-10T17:47:43.222Z,1683740863.222 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-10T17:47:43.223Z,1683740863.223 [Default:A.Wait] Running Loop=1
2023-05-10T17:47:43.223Z,1683740863.223 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-05-10T17:47:52.903Z,1683740872.903 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-10T17:47:56.570Z,1683740876.570 [Default:A.Wait](INFO): Done Waiting.
2023-05-10T17:47:56.570Z,1683740876.570 [Default:A.Wait] Stopped
2023-05-10T17:47:56.570Z,1683740876.570 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-10T17:47:56.978Z,1683740876.978 [Default:CheckIn] Running Loop=1
2023-05-10T17:47:56.978Z,1683740876.978 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-10T17:47:56.978Z,1683740876.978 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-10T17:47:57.390Z,1683740877.390 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-05-10T17:48:06.233Z,1683740886.233 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-10T17:48:19.565Z,1683740899.565 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-10T17:48:32.898Z,1683740912.898 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-10T17:48:46.230Z,1683740926.230 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-10T17:48:59.562Z,1683740939.562 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-10T17:49:49.258Z,1683740989.258 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-10T17:52:57.134Z,1683741177.134 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-10T17:47:56.0Z
2023-05-10T17:52:57.134Z,1683741177.134 [Default:CheckIn:Read_GPS] Stopped
2023-05-10T17:52:57.135Z,1683741177.135 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-10T17:52:57.569Z,1683741177.569 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-10T17:53:05.048Z,1683741185.048 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20230510T174355/Courier0004.lzma
2023-05-10T17:53:06.050Z,1683741186.050 [DataOverHttps](INFO): Moved sent file to Logs/20230510T174355/Courier0004.lzma.bak
2023-05-10T17:53:06.051Z,1683741186.051 [DataOverHttps](INFO): SBD MOMSN=18286622
2023-05-10T17:53:23.366Z,1683741203.366 [DataOverHttps](INFO): Sending 738 bytes from file Logs/20230510T172607/Express0008.lzma
2023-05-10T17:53:24.362Z,1683741204.362 [DataOverHttps](INFO): Moved sent file to Logs/20230510T172607/Express0008.lzma.bak
2023-05-10T17:53:24.363Z,1683741204.363 [DataOverHttps](INFO): SBD MOMSN=18286624
2023-05-10T17:53:28.233Z,1683741208.233 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-10T17:53:41.910Z,1683741221.910 [DataOverHttps](INFO): Sending 1223 bytes from file Logs/20230510T174355/Express0001.lzma
2023-05-10T17:53:42.910Z,1683741222.910 [DataOverHttps](INFO): Moved sent file to Logs/20230510T174355/Express0001.lzma.bak
2023-05-10T17:53:42.910Z,1683741222.910 [DataOverHttps](INFO): SBD MOMSN=18286648
2023-05-10T17:53:59.289Z,1683741239.289 [DataOverHttps](INFO): Sending 410 bytes from file Logs/20230510T174355/Express0005.lzma
2023-05-10T17:54:00.290Z,1683741240.290 [DataOverHttps](INFO): Moved sent file to Logs/20230510T174355/Express0005.lzma.bak
2023-05-10T17:54:00.291Z,1683741240.291 [DataOverHttps](INFO): SBD MOMSN=18286677
2023-05-10T17:54:03.013Z,1683741243.013 [Default:CheckIn:Read_Iridium] Stopped
2023-05-10T17:54:03.014Z,1683741243.014 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-10T17:54:03.014Z,1683741243.014 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-10T17:56:20.335Z,1683741380.335 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-10T17:56:33.665Z,1683741393.665 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-10T17:56:47.401Z,1683741407.401 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-10T17:57:00.734Z,1683741420.734 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-10T17:57:14.064Z,1683741434.064 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-10T17:57:27.397Z,1683741447.397 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-10T17:57:41.132Z,1683741461.132 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-10T17:57:54.464Z,1683741474.464 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-10T17:58:07.801Z,1683741487.801 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-10T17:58:21.128Z,1683741501.128 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-10T17:58:34.462Z,1683741514.462 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-10T17:59:03.567Z,1683741543.567 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-10T17:59:03.567Z,1683741543.567 [Default:CheckIn:C.Wait] Stopped
2023-05-10T17:59:03.568Z,1683741543.568 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-10T17:59:03.568Z,1683741543.568 [Default:CheckIn:D] Running Loop=1
2023-05-10T17:59:03.971Z,1683741543.971 [Default:CheckIn:D] Stopped
2023-05-10T17:59:03.971Z,1683741543.971 [Default:CheckIn:E] Running Loop=1
2023-05-10T17:59:04.438Z,1683741544.438 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.345857 min
2023-05-10T17:59:04.438Z,1683741544.438 [Default:CheckIn:E] Stopped
2023-05-10T17:59:04.438Z,1683741544.438 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-10T17:59:04.438Z,1683741544.438 [Default:CheckIn] Stopped
2023-05-10T17:59:04.438Z,1683741544.438 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-10T17:59:04.438Z,1683741544.438 [Default:CheckIn](INFO): Running loop #2
2023-05-10T17:59:04.438Z,1683741544.438 [Default:CheckIn] Running Loop=2
2023-05-10T17:59:04.439Z,1683741544.439 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-10T17:59:04.439Z,1683741544.439 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-10T17:59:05.972Z,1683741545.972 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-10T17:59:05.972Z,1683741545.972 [NAL9602] Data Fault, FailCount= 1
2023-05-10T17:59:05.972Z,1683741545.972 [NAL9602](ERROR): Data Fault
2023-05-10T17:59:06.016Z,1683741546.016 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-10T17:59:06.381Z,1683741546.381 [NAL9602](INFO): Powering down
2023-05-10T17:59:07.207Z,1683741547.207 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-10T17:59:07.207Z,1683741547.207 [NAL9602] No Fault, FailCount= 1
2023-05-10T17:59:36.677Z,1683741576.677 [NAL9602](INFO): Powering up NAL9602
2023-05-10T17:59:40.719Z,1683741580.719 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-10T17:59:47.590Z,1683741587.590 [NAL9602](INFO): NAL9602 initialized
2023-05-10T18:04:04.588Z,1683741844.588 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-10T17:59:04.4Z
2023-05-10T18:04:04.588Z,1683741844.588 [Default:CheckIn:Read_GPS] Stopped
2023-05-10T18:04:04.609Z,1683741844.609 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-10T18:04:12.100Z,1683741852.100 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20230510T174355/Courier0007.lzma
2023-05-10T18:04:13.102Z,1683741853.102 [DataOverHttps](INFO): Moved sent file to Logs/20230510T174355/Courier0007.lzma.bak
2023-05-10T18:04:13.102Z,1683741853.102 [DataOverHttps](INFO): SBD MOMSN=18286695
2023-05-10T18:04:30.437Z,1683741870.437 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20230510T174355/Express0008.lzma
2023-05-10T18:04:31.440Z,1683741871.440 [DataOverHttps](INFO): Moved sent file to Logs/20230510T174355/Express0008.lzma.bak
2023-05-10T18:04:31.440Z,1683741871.440 [DataOverHttps](INFO): SBD MOMSN=18286697
2023-05-10T18:04:34.106Z,1683741874.106 [Default:CheckIn:Read_Iridium] Stopped
2023-05-10T18:04:34.106Z,1683741874.106 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-10T18:04:34.106Z,1683741874.106 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-10T18:04:51.443Z,1683741891.443 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-10T18:05:22.154Z,1683741922.154 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-10T18:05:45.578Z,1683741945.578 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-10T18:05:45.579Z,1683741945.579 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 5.
2023-05-10T18:05:45.582Z,1683741945.582 [BPC1](INFO): Received data from all battery sticks.
2023-05-10T18:09:34.655Z,1683742174.655 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-10T18:09:34.655Z,1683742174.655 [Default:CheckIn:C.Wait] Stopped
2023-05-10T18:09:34.655Z,1683742174.655 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-10T18:09:34.656Z,1683742174.656 [Default:CheckIn:D] Running Loop=1
2023-05-10T18:09:35.055Z,1683742175.055 [Default:CheckIn:D] Stopped
2023-05-10T18:09:35.055Z,1683742175.055 [Default:CheckIn:E] Running Loop=1
2023-05-10T18:09:35.468Z,1683742175.468 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.863926 min
2023-05-10T18:09:35.468Z,1683742175.468 [Default:CheckIn:E] Stopped
2023-05-10T18:09:35.468Z,1683742175.468 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-10T18:09:35.468Z,1683742175.468 [Default:CheckIn] Stopped
2023-05-10T18:09:35.468Z,1683742175.468 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-10T18:09:35.469Z,1683742175.469 [Default:CheckIn](INFO): Running loop #3
2023-05-10T18:09:35.469Z,1683742175.469 [Default:CheckIn] Running Loop=3
2023-05-10T18:09:35.469Z,1683742175.469 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-10T18:09:35.469Z,1683742175.469 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-10T18:10:08.178Z,1683742208.178 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-05-10T18:10:08.257Z,1683742208.257 [NAL9602](ERROR): received:
+CSQ:0
OK
2023-05-10T18:14:11.730Z,1683742451.730 [CommandExec](IMPORTANT): got command restart application
2023-05-10T18:14:12.733Z,1683742452.733 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-10T18:14:12.733Z,1683742452.733 [CommandExec](INFO): Uninitializing the command executive.
2023-05-10T18:14:12.733Z,1683742452.733 [CommandExec](INFO): Uninitializing the command scheduler.
2023-05-10T18:14:12.733Z,1683742452.733 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:12.836Z,1683742452.836 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2023-05-10T18:14:12.837Z,1683742452.837 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-05-10T18:14:12.837Z,1683742452.837 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:12.837Z,1683742452.837 [NavChartDb](INFO): Join timeout helper Thread ID is 4748
2023-05-10T18:14:13.169Z,1683742453.169 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-10T18:14:13.169Z,1683742453.169 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:13.173Z,1683742453.173 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-05-10T18:14:13.173Z,1683742453.173 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:13.173Z,1683742453.173 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4749
2023-05-10T18:14:13.269Z,1683742453.269 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-10T18:14:13.269Z,1683742453.269 [WetLabsBB2FL](INFO): Powering down
2023-05-10T18:14:13.270Z,1683742453.270 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:13.281Z,1683742453.281 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-05-10T18:14:13.281Z,1683742453.281 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:13.281Z,1683742453.281 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4750
2023-05-10T18:14:13.493Z,1683742453.493 [CTD_Seabird](INFO): Powering down
2023-05-10T18:14:13.505Z,1683742453.505 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-10T18:14:13.505Z,1683742453.505 [CTD_Seabird](INFO): Powering down
2023-05-10T18:14:13.517Z,1683742453.517 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:13.529Z,1683742453.529 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler
2023-05-10T18:14:13.529Z,1683742453.529 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:13.529Z,1683742453.529 [DATMMP](INFO): Join timeout helper Thread ID is 4751
2023-05-10T18:14:13.669Z,1683742453.669 [DATMMP](INFO): Stop
2023-05-10T18:14:13.669Z,1683742453.669 [DATMMP](INFO): uninitialize
2023-05-10T18:14:13.669Z,1683742453.669 [DATMMP](INFO): Powering down
2023-05-10T18:14:13.741Z,1683742453.741 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-10T18:14:13.742Z,1683742453.742 [DATMMP](INFO): uninitialize
2023-05-10T18:14:13.742Z,1683742453.742 [DATMMP](INFO): Powering down
2023-05-10T18:14:13.743Z,1683742453.743 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:13.752Z,1683742453.752 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-05-10T18:14:13.752Z,1683742453.752 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:13.753Z,1683742453.753 [Radio_Surface](INFO): Join timeout helper Thread ID is 4752
2023-05-10T18:14:14.017Z,1683742454.017 [Radio_Surface](INFO): Powering down
2023-05-10T18:14:14.018Z,1683742454.018 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-10T18:14:14.018Z,1683742454.018 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:14.034Z,1683742454.034 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-05-10T18:14:14.034Z,1683742454.034 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:14.034Z,1683742454.034 [Onboard](INFO): Join timeout helper Thread ID is 4753
2023-05-10T18:14:17.469Z,1683742457.469 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-10T18:14:17.469Z,1683742457.469 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:17.485Z,1683742457.485 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-05-10T18:14:17.486Z,1683742457.486 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:17.486Z,1683742457.486 [DataOverHttps](INFO): Join timeout helper Thread ID is 4754
2023-05-10T18:14:18.159Z,1683742458.159 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-10T18:14:18.174Z,1683742458.174 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:18.193Z,1683742458.193 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-05-10T18:14:18.193Z,1683742458.193 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:18.194Z,1683742458.194 [BackseatComponent](INFO): Join timeout helper Thread ID is 4755
2023-05-10T18:14:18.377Z,1683742458.377 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-10T18:14:18.377Z,1683742458.377 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:18.385Z,1683742458.385 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-05-10T18:14:18.385Z,1683742458.385 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:18.386Z,1683742458.386 [logger](INFO): Join timeout helper Thread ID is 4756
2023-05-10T18:14:18.429Z,1683742458.429 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-10T18:14:18.429Z,1683742458.429 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:18.445Z,1683742458.445 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-05-10T18:14:18.445Z,1683742458.445 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:18.446Z,1683742458.446 [CommandLine](INFO): Join timeout helper Thread ID is 4757
2023-05-10T18:14:18.537Z,1683742458.537 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-10T18:14:18.537Z,1683742458.537 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:18.545Z,1683742458.545 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-05-10T18:14:18.545Z,1683742458.545 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:18.546Z,1683742458.546 [CommandExec](INFO): Join timeout helper Thread ID is 4758
2023-05-10T18:14:18.547Z,1683742458.547 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-05-10T18:14:18.547Z,1683742458.547 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:18.548Z,1683742458.547 [controlThread](INFO): Join timeout helper Thread ID is 4759
2023-05-10T18:14:18.665Z,1683742458.665 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-10T18:14:18.665Z,1683742458.665 [controlThread](DEBUG): Uninitializing ControlThread
2023-05-10T18:14:18.666Z,1683742458.666 [AHRS_M2](INFO): Powering down
2023-05-10T18:14:18.737Z,1683742458.737 [NAL9602](INFO): Powering down
2023-05-10T18:14:18.740Z,1683742458.740 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-05-10T18:14:18.741Z,1683742458.741 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-05-10T18:14:18.741Z,1683742458.741 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-05-10T18:14:18.742Z,1683742458.742 [MissionManager](INFO): Uninitializing Mission Default
2023-05-10T18:14:18.742Z,1683742458.742 [Default] Stopped
2023-05-10T18:14:18.742Z,1683742458.742 [Default](DEBUG): Aggregate::uninitialize Default
2023-05-10T18:14:18.742Z,1683742458.742 [Default:B.GoToSurface] Stopped
2023-05-10T18:14:18.742Z,1683742458.742 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-10T18:14:18.742Z,1683742458.742 [Default:CheckIn] Stopped
2023-05-10T18:14:18.742Z,1683742458.742 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-10T18:14:18.742Z,1683742458.742 [Default:CheckIn:Read_GPS] Stopped
2023-05-10T18:14:18.745Z,1683742458.745 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-05-10T18:14:18.745Z,1683742458.745 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-05-10T18:14:18.746Z,1683742458.746 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-05-10T18:14:18.746Z,1683742458.746 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-05-10T18:14:18.746Z,1683742458.746 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-05-10T18:14:18.746Z,1683742458.746 [BuoyancyServo](INFO): Powering down
2023-05-10T18:14:18.761Z,1683742458.761 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-05-10T18:14:18.761Z,1683742458.761 [ElevatorServo](INFO): Powering down
2023-05-10T18:14:18.762Z,1683742458.762 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-05-10T18:14:18.762Z,1683742458.762 [MassServo](INFO): Powering down
2023-05-10T18:14:18.763Z,1683742458.763 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-05-10T18:14:18.763Z,1683742458.763 [RudderServo](INFO): Powering down
2023-05-10T18:14:18.763Z,1683742458.763 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-05-10T18:14:18.764Z,1683742458.764 [ThrusterHE](INFO): Powering down
2023-05-10T18:14:18.765Z,1683742458.765 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-05-10T18:14:18.765Z,1683742458.765 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-05-10T18:14:18.765Z,1683742458.765 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-05-10T18:14:18.765Z,1683742458.765 [CBIT](DEBUG): Powering off loads.
2023-05-10T18:14:18.776Z,1683742458.776 [CBIT](DEBUG): Disabling WDT.
2023-05-10T18:14:18.788Z,1683742458.788 [CBIT](DEBUG): Opening all GF detection circuits.
2023-05-10T18:14:18.789Z,1683742458.789 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:18.846Z,1683742458.846 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:18.851Z,1683742458.851 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:18.853Z,1683742458.853 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:18.859Z,1683742458.859 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:18.902Z,1683742458.902 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:18.951Z,1683742458.951 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:18.956Z,1683742458.956 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:18.992Z,1683742458.992 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-10T18:14:19.054Z,1683742459.054 [logger ThreadHandler](INFO): Thread cancelled.