2023-05-11T16:55:21.651Z,1683824121.651 [Supervisor](DEBUG): Initializing supervisor.
2023-05-11T16:55:21.656Z,1683824121.656 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-05-11T16:55:21.657Z,1683824121.657 [SyncHandler](INFO): Protected caller Thread ID is 9379
2023-05-11T16:55:21.657Z,1683824121.657 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-05-11T16:55:21.659Z,1683824121.659 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-05-11T16:55:21.659Z,1683824121.659 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9380
2023-05-11T16:55:21.663Z,1683824121.663 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-05-11T16:55:21.681Z,1683824121.681 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-05-11T16:55:21.682Z,1683824121.682 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-05-11T16:55:21.682Z,1683824121.682 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 9381
2023-05-11T16:55:21.687Z,1683824121.687 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-05-11T16:55:21.688Z,1683824121.688 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-05-11T16:55:21.688Z,1683824121.688 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9382
2023-05-11T16:55:21.690Z,1683824121.690 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-05-11T16:55:21.691Z,1683824121.691 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-05-11T16:55:21.691Z,1683824121.691 [logger ThreadHandler](INFO): Protected caller Thread ID is 9383
2023-05-11T16:55:21.695Z,1683824121.695 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-05-11T16:55:21.695Z,1683824121.695 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-05-11T16:55:21.697Z,1683824121.697 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-05-11T16:55:22.147Z,1683824122.147 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-05-11T16:55:22.147Z,1683824122.147 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-05-11T16:55:22.351Z,1683824122.351 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-05-11T16:55:22.352Z,1683824122.352 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-05-11T16:55:22.430Z,1683824122.430 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-05-11T16:55:22.640Z,1683824122.640 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-05-11T16:55:22.641Z,1683824122.641 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-05-11T16:55:22.718Z,1683824122.718 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-05-11T16:55:22.811Z,1683824122.811 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-05-11T16:55:22.812Z,1683824122.812 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-05-11T16:55:23.136Z,1683824123.136 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-05-11T16:55:23.136Z,1683824123.136 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-05-11T16:55:23.265Z,1683824123.265 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-05-11T16:55:23.265Z,1683824123.265 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-05-11T16:55:23.754Z,1683824123.754 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-05-11T16:55:23.755Z,1683824123.755 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-05-11T16:55:23.930Z,1683824123.930 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-05-11T16:55:23.931Z,1683824123.931 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-05-11T16:55:24.315Z,1683824124.315 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-05-11T16:55:24.315Z,1683824124.315 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-05-11T16:55:24.584Z,1683824124.584 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-05-11T16:55:24.585Z,1683824124.585 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-05-11T16:55:24.692Z,1683824124.692 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-05-11T16:55:24.693Z,1683824124.693 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-05-11T16:55:25.982Z,1683824125.982 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-05-11T16:55:25.982Z,1683824125.982 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-05-11T16:55:26.301Z,1683824126.301 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-05-11T16:55:26.302Z,1683824126.302 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-05-11T16:55:26.515Z,1683824126.515 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-05-11T16:55:26.517Z,1683824126.517 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2023-05-11T16:55:26.518Z,1683824126.518 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2023-05-11T16:55:26.697Z,1683824126.697 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2023-05-11T16:55:26.780Z,1683824126.780 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2023-05-11T16:55:26.910Z,1683824126.910 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2023-05-11T16:55:26.994Z,1683824126.994 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2023-05-11T16:55:27.254Z,1683824127.254 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-05-11T16:55:27.254Z,1683824127.254 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2023-05-11T16:55:27.356Z,1683824127.356 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2023-05-11T16:55:27.460Z,1683824127.460 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2023-05-11T16:55:27.570Z,1683824127.570 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2023-05-11T16:55:27.714Z,1683824127.714 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2023-05-11T16:55:27.811Z,1683824127.811 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2023-05-11T16:55:27.904Z,1683824127.904 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2023-05-11T16:55:27.905Z,1683824127.905 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-05-11T16:55:27.917Z,1683824127.917 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-05-11T16:55:27.927Z,1683824127.927 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-05-11T16:55:27.928Z,1683824127.928 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-05-11T16:55:28.026Z,1683824128.026 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-05-11T16:55:28.027Z,1683824128.027 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-05-11T16:55:28.076Z,1683824128.076 [VerticalControl](DEBUG): Construct VerticalControl.
2023-05-11T16:55:28.136Z,1683824128.136 [VerticalControl] Loaded
2023-05-11T16:55:28.136Z,1683824128.136 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-05-11T16:55:28.139Z,1683824128.139 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-05-11T16:55:28.179Z,1683824128.179 [HorizontalControl] Loaded
2023-05-11T16:55:28.180Z,1683824128.180 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-05-11T16:55:28.182Z,1683824128.182 [SpeedControl](DEBUG): Construct SpeedControl.
2023-05-11T16:55:28.185Z,1683824128.185 [SpeedControl] Loaded
2023-05-11T16:55:28.185Z,1683824128.185 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-05-11T16:55:28.188Z,1683824128.188 [LoopControl](DEBUG): Construct LoopControl.
2023-05-11T16:55:28.188Z,1683824128.188 [LoopControl] Loaded
2023-05-11T16:55:28.189Z,1683824128.189 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-05-11T16:55:28.189Z,1683824128.189 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-05-11T16:55:28.190Z,1683824128.190 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-05-11T16:55:28.345Z,1683824128.345 [BuoyancyServo] Loaded
2023-05-11T16:55:28.345Z,1683824128.345 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-05-11T16:55:28.365Z,1683824128.365 [ElevatorServo] Loaded
2023-05-11T16:55:28.366Z,1683824128.366 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-05-11T16:55:28.384Z,1683824128.384 [MassServo] Loaded
2023-05-11T16:55:28.385Z,1683824128.385 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-05-11T16:55:28.403Z,1683824128.403 [RudderServo] Loaded
2023-05-11T16:55:28.403Z,1683824128.403 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-05-11T16:55:28.417Z,1683824128.417 [ThrusterHE] Loaded
2023-05-11T16:55:28.418Z,1683824128.418 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-05-11T16:55:28.418Z,1683824128.418 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-05-11T16:55:28.419Z,1683824128.419 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-05-11T16:55:28.469Z,1683824128.469 [DepthRateCalculator] Loaded
2023-05-11T16:55:28.469Z,1683824128.469 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-05-11T16:55:28.474Z,1683824128.474 [PitchRateCalculator] Loaded
2023-05-11T16:55:28.474Z,1683824128.474 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-05-11T16:55:28.484Z,1683824128.484 [SpeedCalculator] Loaded
2023-05-11T16:55:28.484Z,1683824128.484 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-05-11T16:55:28.488Z,1683824128.488 [YawRateCalculator] Loaded
2023-05-11T16:55:28.488Z,1683824128.488 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-05-11T16:55:28.507Z,1683824128.507 [ElevatorOffsetCalculator] Loaded
2023-05-11T16:55:28.507Z,1683824128.507 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-05-11T16:55:28.508Z,1683824128.508 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-05-11T16:55:28.508Z,1683824128.508 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-05-11T16:55:28.620Z,1683824128.620 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-05-11T16:55:28.620Z,1683824128.620 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-05-11T16:55:28.634Z,1683824128.634 [NavChart] Loaded
2023-05-11T16:55:28.634Z,1683824128.634 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-05-11T16:55:28.640Z,1683824128.640 [UniversalFixResidualReporter] Loaded
2023-05-11T16:55:28.640Z,1683824128.640 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-05-11T16:55:28.640Z,1683824128.640 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-05-11T16:55:28.641Z,1683824128.641 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-05-11T16:55:29.100Z,1683824129.100 [AHRS_M2] Loaded
2023-05-11T16:55:29.100Z,1683824129.100 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-05-11T16:55:29.136Z,1683824129.136 [BackseatComponent] Loaded
2023-05-11T16:55:29.136Z,1683824129.136 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-05-11T16:55:29.137Z,1683824129.137 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408F64E0
2023-05-11T16:55:29.137Z,1683824129.137 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 9465
2023-05-11T16:55:29.140Z,1683824129.140 [LcmUniversalReporter] Loaded
2023-05-11T16:55:29.140Z,1683824129.140 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-05-11T16:55:29.962Z,1683824129.962 [BPC1] Loaded
2023-05-11T16:55:29.962Z,1683824129.962 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-05-11T16:55:30.047Z,1683824130.047 [DATMMP] Loaded
2023-05-11T16:55:30.047Z,1683824130.047 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2023-05-11T16:55:30.048Z,1683824130.048 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 409274E0
2023-05-11T16:55:30.049Z,1683824130.049 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 9466
2023-05-11T16:55:30.121Z,1683824130.121 [DataOverHttps] Loaded
2023-05-11T16:55:30.122Z,1683824130.122 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-05-11T16:55:30.123Z,1683824130.123 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409574E0
2023-05-11T16:55:30.123Z,1683824130.123 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9467
2023-05-11T16:55:30.144Z,1683824130.144 [Depth_Keller] Loaded
2023-05-11T16:55:30.144Z,1683824130.144 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-05-11T16:55:30.205Z,1683824130.205 [NAL9602] Loaded
2023-05-11T16:55:30.206Z,1683824130.206 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-05-11T16:55:30.235Z,1683824130.235 [Onboard] Loaded
2023-05-11T16:55:30.235Z,1683824130.235 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-05-11T16:55:30.236Z,1683824130.236 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409874E0
2023-05-11T16:55:30.237Z,1683824130.237 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 9468
2023-05-11T16:55:30.249Z,1683824130.249 [Power24vConverter] Loaded
2023-05-11T16:55:30.249Z,1683824130.249 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-05-11T16:55:30.262Z,1683824130.262 [Radio_Surface] Loaded
2023-05-11T16:55:30.262Z,1683824130.262 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-05-11T16:55:30.263Z,1683824130.263 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409B74E0
2023-05-11T16:55:30.264Z,1683824130.264 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9469
2023-05-11T16:55:30.265Z,1683824130.265 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-05-11T16:55:30.265Z,1683824130.265 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-05-11T16:55:30.538Z,1683824130.538 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-05-11T16:55:30.539Z,1683824130.539 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-05-11T16:55:30.751Z,1683824130.751 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-05-11T16:55:30.752Z,1683824130.752 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-05-11T16:55:30.778Z,1683824130.778 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-05-11T16:55:30.779Z,1683824130.779 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-05-11T16:55:31.112Z,1683824131.112 [CTD_Seabird] Loaded
2023-05-11T16:55:31.112Z,1683824131.112 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-05-11T16:55:31.113Z,1683824131.113 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AEE4E0
2023-05-11T16:55:31.114Z,1683824131.114 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9470
2023-05-11T16:55:31.146Z,1683824131.146 [ESPComponent] Loaded
2023-05-11T16:55:31.147Z,1683824131.147 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-05-11T16:55:31.166Z,1683824131.166 [PAR_Licor] Loaded
2023-05-11T16:55:31.167Z,1683824131.167 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-05-11T16:55:31.197Z,1683824131.197 [WetLabsBB2FL] Loaded
2023-05-11T16:55:31.198Z,1683824131.198 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-05-11T16:55:31.198Z,1683824131.198 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B1E4E0
2023-05-11T16:55:31.199Z,1683824131.199 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9471
2023-05-11T16:55:31.200Z,1683824131.200 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-05-11T16:55:31.200Z,1683824131.200 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-05-11T16:55:31.507Z,1683824131.507 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-05-11T16:55:31.507Z,1683824131.507 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-05-11T16:55:31.645Z,1683824131.645 [SBIT](DEBUG): Construct Startup Built In Test.
2023-05-11T16:55:31.655Z,1683824131.655 [SBIT] Loaded
2023-05-11T16:55:31.655Z,1683824131.655 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-05-11T16:55:31.658Z,1683824131.658 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-05-11T16:55:31.671Z,1683824131.671 [IBIT] Loaded
2023-05-11T16:55:31.671Z,1683824131.671 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-05-11T16:55:31.677Z,1683824131.677 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-05-11T16:55:31.782Z,1683824131.782 [CBIT] Loaded
2023-05-11T16:55:31.783Z,1683824131.783 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-05-11T16:55:31.783Z,1683824131.783 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-05-11T16:55:31.789Z,1683824131.789 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-05-11T16:55:31.792Z,1683824131.792 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-05-11T16:55:31.803Z,1683824131.803 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-05-11T16:55:31.804Z,1683824131.804 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C014E0
2023-05-11T16:55:31.805Z,1683824131.805 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9472
2023-05-11T16:55:31.809Z,1683824131.809 [Supervisor](INFO): Main Thread ID is 9039
2023-05-11T16:55:31.809Z,1683824131.809 [Supervisor](DEBUG): Running supervisor.
2023-05-11T16:55:31.810Z,1683824131.810 [CommandExec ThreadHandler](INFO): Handler Thread ID is 9473
2023-05-11T16:55:31.810Z,1683824131.810 [CommandExec](INFO): Initializing the command executive.
2023-05-11T16:55:31.812Z,1683824131.812 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9474
2023-05-11T16:55:31.814Z,1683824131.814 [controlThread ThreadHandler](INFO): Handler Thread ID is 9475
2023-05-11T16:55:31.815Z,1683824131.815 [controlThread](DEBUG): Initializing ControlThread
2023-05-11T16:55:31.815Z,1683824131.815 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-05-11T16:55:31.817Z,1683824131.817 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-05-11T16:55:31.818Z,1683824131.818 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-05-11T16:55:31.818Z,1683824131.818 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-05-11T16:55:31.820Z,1683824131.820 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-05-11T16:55:31.820Z,1683824131.820 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-05-11T16:55:31.820Z,1683824131.820 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-05-11T16:55:31.821Z,1683824131.821 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-05-11T16:55:31.821Z,1683824131.821 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-05-11T16:55:31.822Z,1683824131.822 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-05-11T16:55:31.823Z,1683824131.823 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-05-11T16:55:31.830Z,1683824131.830 [SBIT](INFO): Initialize SBIT Component.
2023-05-11T16:55:31.830Z,1683824131.830 [SBIT](IMPORTANT): git: 2023-04-26-68-gd8052d973
2023-05-11T16:55:31.830Z,1683824131.830 [SBIT](INFO): git hash: d8052d9734beefc1bd50265fa9cca972428f086c
2023-05-11T16:55:31.831Z,1683824131.831 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-05-11T16:55:31.832Z,1683824131.832 [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-11T16:55:31.833Z,1683824131.833 [SBIT](INFO): Beginning SBIT in 81.000000 seconds.
2023-05-11T16:55:31.834Z,1683824131.834 [IBIT](INFO): Initialize IBIT Component.
2023-05-11T16:55:31.835Z,1683824131.835 [CBIT](DEBUG): Initialize CBIT Component.
2023-05-11T16:55:31.835Z,1683824131.835 [logger ThreadHandler](INFO): Handler Thread ID is 9476
2023-05-11T16:55:31.845Z,1683824131.845 [CBIT](DEBUG): Initialized mux pins.
2023-05-11T16:55:31.846Z,1683824131.846 [CBIT](DEBUG): Initializing the watchdog timer.
2023-05-11T16:55:31.854Z,1683824131.854 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 9477
2023-05-11T16:55:31.866Z,1683824131.866 [DATMMP ThreadHandler](INFO): Handler Thread ID is 9478
2023-05-11T16:55:31.866Z,1683824131.866 [DATMMP](INFO): Start
2023-05-11T16:55:31.867Z,1683824131.867 [DATMMP](INFO): Powering up
2023-05-11T16:55:31.867Z,1683824131.867 [DATMMP](DEBUG): Initializing DATMMP.
2023-05-11T16:55:31.869Z,1683824131.869 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-05-11T16:55:31.870Z,1683824131.870 [CBIT](DEBUG): Initializing heartbeat.
2023-05-11T16:55:31.872Z,1683824131.872 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9480
2023-05-11T16:55:31.873Z,1683824131.873 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-05-11T16:55:31.882Z,1683824131.882 [Onboard ThreadHandler](INFO): Handler Thread ID is 9481
2023-05-11T16:55:31.903Z,1683824131.903 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9482
2023-05-11T16:55:31.915Z,1683824131.915 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9483
2023-05-11T16:55:31.916Z,1683824131.916 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-05-11T16:55:31.926Z,1683824131.926 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9485
2023-05-11T16:55:31.930Z,1683824131.930 [WetLabsBB2FL](INFO): Powering up
2023-05-11T16:55:31.931Z,1683824131.931 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9487
2023-05-11T16:55:31.934Z,1683824131.934 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-05-11T16:55:31.934Z,1683824131.934 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-05-11T16:55:31.934Z,1683824131.934 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-05-11T16:55:31.934Z,1683824131.934 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-05-11T16:55:31.935Z,1683824131.935 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-05-11T16:55:31.935Z,1683824131.935 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-05-11T16:55:31.935Z,1683824131.935 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-05-11T16:55:31.935Z,1683824131.935 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-05-11T16:55:31.941Z,1683824131.941 [CBIT](DEBUG): Deactivating GF circuits.
2023-05-11T16:55:31.942Z,1683824131.942 [CBIT](DEBUG): Deactivating emergency mode.
2023-05-11T16:55:31.981Z,1683824131.981 [CBIT](DEBUG): Backplane powered.
2023-05-11T16:55:31.982Z,1683824131.982 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-05-11T16:55:32.005Z,1683824132.005 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-11T16:55:32.021Z,1683824132.021 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2023-05-11T16:55:32.022Z,1683824132.022 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-05-11T16:55:32.095Z,1683824132.095 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-05-11T16:55:32.097Z,1683824132.097 [Default:A.Wait](DEBUG): Construct Wait.
2023-05-11T16:55:32.115Z,1683824132.115 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-11T16:55:32.118Z,1683824132.118 [DATMMP](INFO): Starting
2023-05-11T16:55:32.166Z,1683824132.166 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-05-11T16:55:32.169Z,1683824132.169 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-05-11T16:55:32.190Z,1683824132.190 [Default:E.Execute](DEBUG): Construct Execute.
2023-05-11T16:55:32.194Z,1683824132.194 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2023-05-11T16:55:32.214Z,1683824132.214 [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-11T16:55:32.220Z,1683824132.220 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-05-11T16:55:32.304Z,1683824132.304 [Power24vConverter](INFO): Powering up.
2023-05-11T16:55:32.310Z,1683824132.310 [Radio_Surface](INFO): Powering up
2023-05-11T16:55:32.338Z,1683824132.338 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-05-11T16:55:32.346Z,1683824132.346 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-05-11T16:55:32.347Z,1683824132.347 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-05-11T16:55:32.358Z,1683824132.358 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-05-11T16:55:32.359Z,1683824132.359 [MassServo](DEBUG): Initializing EZServoServo.
2023-05-11T16:55:32.366Z,1683824132.366 [MassServo](DEBUG): Initializing MassServo.
2023-05-11T16:55:32.367Z,1683824132.367 [RudderServo](DEBUG): Initializing EZServoServo.
2023-05-11T16:55:32.370Z,1683824132.370 [DATMMP](INFO): Starting
2023-05-11T16:55:32.382Z,1683824132.382 [RudderServo](DEBUG): Initializing RudderServo.
2023-05-11T16:55:32.383Z,1683824132.383 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-05-11T16:55:32.390Z,1683824132.390 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-05-11T16:55:32.622Z,1683824132.622 [DATMMP](INFO): Starting
2023-05-11T16:55:32.874Z,1683824132.874 [DATMMP](INFO): Starting
2023-05-11T16:55:33.125Z,1683824133.125 [DATMMP](INFO): Starting
2023-05-11T16:55:33.378Z,1683824133.378 [DATMMP](INFO): Starting
2023-05-11T16:55:33.630Z,1683824133.630 [DATMMP](INFO): Starting
2023-05-11T16:55:33.882Z,1683824133.882 [DATMMP](INFO): Starting
2023-05-11T16:55:34.038Z,1683824134.038 [WetLabsBB2FL](INFO): Powering down
2023-05-11T16:55:34.134Z,1683824134.134 [DATMMP](INFO): Starting
2023-05-11T16:55:34.386Z,1683824134.386 [DATMMP](INFO): Starting
2023-05-11T16:55:34.637Z,1683824134.637 [DATMMP](INFO): Starting
2023-05-11T16:55:34.890Z,1683824134.890 [DATMMP](INFO): Starting
2023-05-11T16:55:35.143Z,1683824135.143 [DATMMP](INFO): Starting
2023-05-11T16:55:35.394Z,1683824135.394 [DATMMP](INFO): Starting
2023-05-11T16:55:35.645Z,1683824135.645 [DATMMP](INFO): Starting
2023-05-11T16:55:35.897Z,1683824135.897 [DATMMP](INFO): Starting
2023-05-11T16:55:36.150Z,1683824136.150 [DATMMP](INFO): Starting
2023-05-11T16:55:36.402Z,1683824136.402 [DATMMP](INFO): Starting
2023-05-11T16:55:36.654Z,1683824136.654 [DATMMP](INFO): Starting
2023-05-11T16:55:36.906Z,1683824136.906 [DATMMP](INFO): Starting
2023-05-11T16:55:37.157Z,1683824137.157 [DATMMP](INFO): Starting
2023-05-11T16:55:37.410Z,1683824137.410 [DATMMP](INFO): Starting
2023-05-11T16:55:37.662Z,1683824137.662 [DATMMP](INFO): Starting
2023-05-11T16:55:37.913Z,1683824137.913 [DATMMP](INFO): Starting
2023-05-11T16:55:38.122Z,1683824138.122 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-05-11T16:55:38.166Z,1683824138.166 [DATMMP](INFO): Starting
2023-05-11T16:55:38.418Z,1683824138.418 [DATMMP](INFO): Starting
2023-05-11T16:55:38.670Z,1683824138.670 [DATMMP](INFO): Starting
2023-05-11T16:55:38.922Z,1683824138.922 [DATMMP](INFO): Starting
2023-05-11T16:55:39.173Z,1683824139.173 [DATMMP](INFO): Starting
2023-05-11T16:55:39.425Z,1683824139.425 [DATMMP](INFO): Starting
2023-05-11T16:55:39.678Z,1683824139.678 [DATMMP](INFO): Starting
2023-05-11T16:55:39.930Z,1683824139.930 [DATMMP](INFO): Starting
2023-05-11T16:55:40.182Z,1683824140.182 [DATMMP](INFO): Starting
2023-05-11T16:55:40.434Z,1683824140.434 [DATMMP](INFO): Starting
2023-05-11T16:55:40.686Z,1683824140.686 [DATMMP](INFO): Starting
2023-05-11T16:55:40.938Z,1683824140.938 [DATMMP](INFO): Starting
2023-05-11T16:55:41.190Z,1683824141.190 [DATMMP](INFO): Starting
2023-05-11T16:55:41.442Z,1683824141.442 [DATMMP](INFO): Starting
2023-05-11T16:55:41.694Z,1683824141.694 [DATMMP](INFO): Starting
2023-05-11T16:55:41.946Z,1683824141.946 [DATMMP](INFO): Starting
2023-05-11T16:55:42.198Z,1683824142.198 [DATMMP](INFO): Starting
2023-05-11T16:55:42.450Z,1683824142.450 [DATMMP](INFO): Starting
2023-05-11T16:55:42.702Z,1683824142.702 [DATMMP](INFO): Starting
2023-05-11T16:55:42.954Z,1683824142.954 [DATMMP](INFO): Starting
2023-05-11T16:55:43.206Z,1683824143.206 [DATMMP](INFO): Starting
2023-05-11T16:55:43.458Z,1683824143.458 [DATMMP](INFO): Starting
2023-05-11T16:55:43.710Z,1683824143.710 [DATMMP](INFO): Starting
2023-05-11T16:55:43.962Z,1683824143.962 [DATMMP](INFO): Starting
2023-05-11T16:55:43.963Z,1683824143.963 [DATMMP](INFO): DAT read: 

2023-05-11T16:55:43.964Z,1683824143.964 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-05-11T16:55:44.214Z,1683824144.214 [DATMMP](INFO): Starting
2023-05-11T16:55:44.466Z,1683824144.466 [DATMMP](INFO): Starting
2023-05-11T16:55:44.718Z,1683824144.718 [DATMMP](INFO): Starting
2023-05-11T16:55:44.970Z,1683824144.970 [DATMMP](INFO): Starting
2023-05-11T16:55:45.222Z,1683824145.222 [DATMMP](INFO): Starting
2023-05-11T16:55:45.474Z,1683824145.474 [DATMMP](INFO): Starting
2023-05-11T16:55:45.726Z,1683824145.726 [DATMMP](INFO): Starting
2023-05-11T16:55:45.726Z,1683824145.726 [DATMMP](INFO): DAT read: MF Frequency Band

2023-05-11T16:55:45.728Z,1683824145.728 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-05-11T16:55:45.728Z,1683824145.728 [DATMMP](INFO): DAT read: Apr 11 2000 16:55:40 

2023-05-11T16:55:45.978Z,1683824145.978 [DATMMP](INFO): Starting
2023-05-11T16:55:46.230Z,1683824146.230 [DATMMP](INFO): Starting
2023-05-11T16:55:46.482Z,1683824146.482 [DATMMP](INFO): Starting
2023-05-11T16:55:46.734Z,1683824146.734 [DATMMP](INFO): Starting
2023-05-11T16:55:46.985Z,1683824146.985 [DATMMP](INFO): Starting
2023-05-11T16:55:46.986Z,1683824146.986 [DATMMP](INFO): DAT read: Features enabled [Bearing]

2023-05-11T16:55:46.988Z,1683824146.988 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-05-11T16:55:46.988Z,1683824146.988 [DATMMP](INFO): commRate: 800
2023-05-11T16:55:47.238Z,1683824147.238 [DATMMP](INFO): Starting
2023-05-11T16:55:47.492Z,1683824147.492 [DATMMP](INFO): Starting
2023-05-11T16:55:47.742Z,1683824147.742 [DATMMP](INFO): Starting
2023-05-11T16:55:47.993Z,1683824147.993 [DATMMP](INFO): Starting
2023-05-11T16:55:48.246Z,1683824148.246 [DATMMP](INFO): Starting
2023-05-11T16:55:48.498Z,1683824148.498 [DATMMP](INFO): Starting
2023-05-11T16:55:48.750Z,1683824148.750 [DATMMP](INFO): Starting
2023-05-11T16:55:49.002Z,1683824149.002 [DATMMP](INFO): Starting
2023-05-11T16:55:49.054Z,1683824149.054 [DATMMP](INFO): entering command mode
2023-05-11T16:55:49.254Z,1683824149.254 [DATMMP](INFO): Starting
2023-05-11T16:55:49.254Z,1683824149.254 [DATMMP](INFO): DAT read: 

2023-05-11T16:55:49.254Z,1683824149.254 [DATMMP](INFO): DAT read: user:1>

2023-05-11T16:55:49.255Z,1683824149.255 [DATMMP](DEBUG): read user prompt 1: user:1>

2023-05-11T16:55:49.255Z,1683824149.255 [DATMMP](INFO): entering MMP mode
2023-05-11T16:55:49.505Z,1683824149.505 [DATMMP](INFO): Starting
2023-05-11T16:55:49.506Z,1683824149.506 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:55:49.508Z,1683824149.508 [DATMMP](INFO): status rx: 	 x1
2023-05-11T16:55:49.508Z,1683824149.508 [DATMMP](INFO): MMP status: 1: started
2023-05-11T16:55:49.509Z,1683824149.509 [DATMMP](INFO): Received message type: status
2023-05-11T16:55:49.509Z,1683824149.509 [DATMMP](INFO): Handled
2023-05-11T16:55:49.758Z,1683824149.758 [DATMMP](INFO): Starting
2023-05-11T16:55:49.759Z,1683824149.759 [DATMMP](INFO): Sent Tx Power Config 8
2023-05-11T16:55:49.759Z,1683824149.759 [DATMMP](INFO): Setting local address: 12
2023-05-11T16:55:49.761Z,1683824149.761 [DATMMP](INFO): Setting time to: 16:55:49 And date to:5/11/2023
2023-05-11T16:55:50.009Z,1683824150.009 [DATMMP](INFO): Starting
2023-05-11T16:55:50.010Z,1683824150.010 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:55:50.011Z,1683824150.011 [DATMMP](INFO): mdmpower rx: 	 x8	 x0	 x5	 x0
2023-05-11T16:55:50.011Z,1683824150.011 [DATMMP](INFO): Received message type: mdmpower
2023-05-11T16:55:50.011Z,1683824150.011 [DATMMP](INFO): Handled
2023-05-11T16:55:50.262Z,1683824150.262 [DATMMP](INFO): Starting
2023-05-11T16:55:50.262Z,1683824150.262 [DATMMP](INFO): GSXN notify for xid: 10
2023-05-11T16:55:50.263Z,1683824150.263 [DATMMP](INFO): sreg_txpower rx: 	 x8
2023-05-11T16:55:50.263Z,1683824150.263 [DATMMP](INFO): Received message type: sreg_txpower
2023-05-11T16:55:50.263Z,1683824150.263 [DATMMP](INFO): Match for : sreg_txpower
2023-05-11T16:55:50.263Z,1683824150.263 [DATMMP](INFO): ACK
2023-05-11T16:55:50.514Z,1683824150.514 [DATMMP](INFO): Starting
2023-05-11T16:55:50.514Z,1683824150.514 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:55:50.515Z,1683824150.515 [DATMMP](INFO): mdmlocal rx: 	 xC	 x0	 x0	 x0
2023-05-11T16:55:50.515Z,1683824150.515 [DATMMP](INFO): Received message type: mdmlocal
2023-05-11T16:55:50.515Z,1683824150.515 [DATMMP](INFO): Handled
2023-05-11T16:55:50.766Z,1683824150.766 [DATMMP](INFO): Starting
2023-05-11T16:55:50.766Z,1683824150.766 [DATMMP](INFO): GSXN notify for xid: 11
2023-05-11T16:55:50.767Z,1683824150.767 [DATMMP](INFO): sreg_locaddr rx: 	 xC
2023-05-11T16:55:50.767Z,1683824150.767 [DATMMP](INFO): Received message type: sreg_locaddr
2023-05-11T16:55:50.767Z,1683824150.767 [DATMMP](INFO): Match for : sreg_locaddr
2023-05-11T16:55:50.767Z,1683824150.767 [DATMMP](INFO): ACK
2023-05-11T16:55:51.018Z,1683824151.018 [DATMMP](INFO): Starting
2023-05-11T16:55:51.018Z,1683824151.018 [DATMMP](INFO): GSXN notify for xid: 12
2023-05-11T16:55:51.019Z,1683824151.019 [DATMMP](INFO): timedate rx: 	 x0	 x10	 x37	 x31	 x4	 xB	 x7B	 x0
2023-05-11T16:55:51.020Z,1683824151.020 [DATMMP](INFO): Received message type: timedate
2023-05-11T16:55:51.020Z,1683824151.020 [DATMMP](INFO): Match for : timedate
2023-05-11T16:55:51.020Z,1683824151.020 [DATMMP](INFO): ACK
2023-05-11T16:55:51.269Z,1683824151.269 [DATMMP](INFO): Starting
2023-05-11T16:55:51.270Z,1683824151.270 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:55:51.271Z,1683824151.271 [DATMMP](INFO): sys_verb rx: 	 x0	 x0	 x0	 x0
2023-05-11T16:55:51.271Z,1683824151.271 [DATMMP](INFO): Received message type: sys_verb
2023-05-11T16:55:51.271Z,1683824151.271 [DATMMP](INFO): Handled
2023-05-11T16:55:51.521Z,1683824151.521 [DATMMP](INFO): Starting
2023-05-11T16:55:51.522Z,1683824151.522 [DATMMP](INFO): GSXN notify for xid: 13
2023-05-11T16:55:51.522Z,1683824151.522 [DATMMP](INFO): sreg_verbosity rx: 	 x0
2023-05-11T16:55:51.523Z,1683824151.523 [DATMMP](INFO): Received message type: sreg_verbosity
2023-05-11T16:55:51.523Z,1683824151.523 [DATMMP](INFO): Match for : sreg_verbosity
2023-05-11T16:55:51.523Z,1683824151.523 [DATMMP](INFO): ACK
2023-05-11T16:55:51.774Z,1683824151.774 [DATMMP](INFO): Starting
2023-05-11T16:55:51.774Z,1683824151.774 [DATMMP](INFO): GSXN notify for xid: 14
2023-05-11T16:55:51.775Z,1683824151.775 [DATMMP](INFO): version rx: 	 x2	 x5
2023-05-11T16:55:51.775Z,1683824151.775 [DATMMP](INFO): DatMMPVersion: 2.5
2023-05-11T16:55:51.775Z,1683824151.775 [DATMMP](INFO): Received message type: version
2023-05-11T16:55:51.775Z,1683824151.775 [DATMMP](INFO): Match for : version
2023-05-11T16:55:51.776Z,1683824151.776 [DATMMP](INFO): ACK
2023-05-11T16:55:51.776Z,1683824151.776 [DATMMP](INFO): in MMP mode: config complete going to runnable
2023-05-11T16:56:00.523Z,1683824160.523 [NAL9602](INFO): Powering up NAL9602
2023-05-11T16:56:06.390Z,1683824166.390 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:56:06.391Z,1683824166.391 [DATMMP](INFO): timesync rx: 	 x0	 x1	 x1	 x0
2023-05-11T16:56:06.391Z,1683824166.391 [DATMMP](INFO): Received message type: timesync
2023-05-11T16:56:06.391Z,1683824166.391 [DATMMP](INFO): Handled
2023-05-11T16:56:11.439Z,1683824171.439 [NAL9602](INFO): NAL9602 initialized
2023-05-11T16:56:16.218Z,1683824176.218 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-11T16:56:16.219Z,1683824176.219 [DATMMP](INFO): timesync rx: 	 x1	 x0	 x1	 x0
2023-05-11T16:56:16.219Z,1683824176.219 [DATMMP](INFO): Received message type: timesync
2023-05-11T16:56:16.219Z,1683824176.219 [DATMMP](INFO): Handled
2023-05-11T16:56:47.140Z,1683824207.140 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.8 degree
2023-05-11T16:56:47.144Z,1683824207.144 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2023-05-11T16:56:47.144Z,1683824207.144 [CommandExec](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.9 degree
2023-05-11T16:56:47.175Z,1683824207.175 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2023-05-11T16:56:47.175Z,1683824207.175 [CommandExec](IMPORTANT): got command maintain sensor Depth_Keller.depth 0.8 meter
2023-05-11T16:56:47.179Z,1683824207.179 [ComponentRegistry](DEBUG): SyncComponent "Maintain_Depth_Keller.depth" handled in the control thread.
2023-05-11T16:56:47.179Z,1683824207.179 [CommandExec](IMPORTANT): got command report touch DATMMP.acoustic_contact_range
2023-05-11T16:56:47.180Z,1683824207.180 [CommandExec](IMPORTANT): got command report touch acoustic_contact_range
2023-05-11T16:56:47.437Z,1683824207.437 [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-11T16:56:53.515Z,1683824213.515 [SBIT](IMPORTANT): Beginning Startup BIT
2023-05-11T16:56:53.522Z,1683824213.522 [CBIT](IMPORTANT): Beginning ground fault scan
2023-05-11T16:56:57.233Z,1683824217.233 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1683824207 second
2023-05-11T16:57:04.386Z,1683824224.386 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.009934
CHAN A1 (24V): -0.007904
CHAN A2 (12V): -0.004648
CHAN A3 (5V): -0.002720
CHAN B0 (3.3V): -0.001167
CHAN B1 (3.15aV): -0.000997
CHAN B2 (3.15bV): -0.000750
CHAN B3 (GND): -0.000395
OPEN: 0.004538
Full Scale: +/- 1 mA
2023-05-11T16:57:47.634Z,1683824267.634 [SBIT](IMPORTANT): SBIT PASSED
2023-05-11T16:57:47.634Z,1683824267.634 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-05-11T16:57:47.635Z,1683824267.635 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;

2023-05-11T16:57:47.635Z,1683824267.635 [SBIT](IMPORTANT): BackseatComponent.alwaysOn=0 bool;

2023-05-11T16:57:47.636Z,1683824267.636 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2023-05-11T16:57:47.636Z,1683824267.636 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2023-05-11T16:57:47.636Z,1683824267.636 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=10 minute;

2023-05-11T16:57:47.636Z,1683824267.636 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2023-05-11T16:57:47.636Z,1683824267.636 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2023-05-11T16:57:47.636Z,1683824267.636 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water;

2023-05-11T16:57:47.636Z,1683824267.636 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree;

2023-05-11T16:57:47.636Z,1683824267.636 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=184.896478 cubic_centimeter;

2023-05-11T16:57:47.636Z,1683824267.636 [SBIT](IMPORTANT): VerticalControl.elevDeadband=0.1 angular_degree;

2023-05-11T16:57:47.637Z,1683824267.637 [SBIT](IMPORTANT): VerticalControl.massDefault=15.484465 millimeter;

2023-05-11T16:57:48.033Z,1683824268.033 [MissionManager](IMPORTANT): Started mission Startup
2023-05-11T16:57:48.034Z,1683824268.034 [Startup] Running Loop=1
2023-05-11T16:57:48.034Z,1683824268.034 [Startup](DEBUG): Aggregate::initialize Startup
2023-05-11T16:57:48.034Z,1683824268.034 [Startup:A.GoToSurface] Running Loop=1
2023-05-11T16:57:48.034Z,1683824268.034 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-11T16:57:48.034Z,1683824268.034 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-11T16:57:48.035Z,1683824268.035 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-11T16:57:48.036Z,1683824268.036 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-11T16:57:48.036Z,1683824268.036 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-11T16:57:48.037Z,1683824268.037 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-11T16:57:48.038Z,1683824268.038 [Startup:StartupSatComms] Running Loop=1
2023-05-11T16:57:48.038Z,1683824268.038 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-05-11T16:57:48.038Z,1683824268.038 [Startup:StartupSatComms:A] Running Loop=1
2023-05-11T16:57:48.411Z,1683824268.411 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-05-11T16:57:48.412Z,1683824268.412 [Startup:StartupSatComms:A] Stopped
2023-05-11T16:57:48.412Z,1683824268.412 [Startup:StartupSatComms:B] Running Loop=1
2023-05-11T16:57:48.826Z,1683824268.826 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-11T16:57:59.885Z,1683824279.885 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005804
2023-05-11T16:58:00.186Z,1683824280.186 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:58:07.277Z,1683824287.277 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230511T164537/Courier0007.lzma
2023-05-11T16:58:08.279Z,1683824288.279 [DataOverHttps](INFO): Moved sent file to Logs/20230511T164537/Courier0007.lzma.bak
2023-05-11T16:58:08.279Z,1683824288.279 [DataOverHttps](INFO): SBD MOMSN=18290511
2023-05-11T16:58:14.259Z,1683824294.259 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:58:14.265Z,1683824294.265 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:58:24.301Z,1683824304.301 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20230511T165521/Courier0000.lzma
2023-05-11T16:58:25.303Z,1683824305.303 [DataOverHttps](INFO): Moved sent file to Logs/20230511T165521/Courier0000.lzma.bak
2023-05-11T16:58:25.303Z,1683824305.303 [DataOverHttps](INFO): SBD MOMSN=18290513
2023-05-11T16:58:27.000Z,1683824308.000 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:58:28.005Z,1683824308.005 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:58:40.914Z,1683824320.914 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:58:40.920Z,1683824320.920 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:58:43.705Z,1683824323.705 [CommandExec](IMPORTANT): got command set NAL9602.time_fix 1683824308 second
2023-05-11T16:58:44.849Z,1683824324.849 [DataOverHttps](INFO): Sending 122 bytes from file Logs/20230511T164537/Express0008.lzma
2023-05-11T16:58:45.851Z,1683824325.851 [DataOverHttps](INFO): Moved sent file to Logs/20230511T164537/Express0008.lzma.bak
2023-05-11T16:58:45.852Z,1683824325.852 [DataOverHttps](INFO): SBD MOMSN=18290516
2023-05-11T16:58:48.607Z,1683824328.607 [Startup:StartupSatComms:B](INFO): Timed out from 2023-05-11T16:57:48.4Z
2023-05-11T16:58:48.607Z,1683824328.607 [Startup:StartupSatComms:B] Stopped
2023-05-11T16:58:48.607Z,1683824328.607 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-05-11T16:58:48.607Z,1683824328.607 [Startup:StartupSatComms] Stopped
2023-05-11T16:58:48.607Z,1683824328.607 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-05-11T16:58:48.608Z,1683824328.608 [Startup](INFO): Completed Startup
2023-05-11T16:58:48.608Z,1683824328.608 [MissionManager](INFO): Startup is completed.
2023-05-11T16:58:48.608Z,1683824328.608 [MissionManager](INFO): Uninitializing Mission Startup
2023-05-11T16:58:48.608Z,1683824328.608 [Startup] Stopped
2023-05-11T16:58:48.608Z,1683824328.608 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-05-11T16:58:48.609Z,1683824328.609 [Startup:A.GoToSurface] Stopped
2023-05-11T16:58:48.609Z,1683824328.609 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-11T16:58:49.010Z,1683824329.010 [MissionManager](IMPORTANT): Started mission Default
2023-05-11T16:58:49.011Z,1683824329.011 [Default] Running Loop=1
2023-05-11T16:58:49.011Z,1683824329.011 [Default](DEBUG): Aggregate::initialize Default
2023-05-11T16:58:49.011Z,1683824329.011 [Default:B.GoToSurface] Running Loop=1
2023-05-11T16:58:49.011Z,1683824329.011 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-11T16:58:49.011Z,1683824329.011 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-11T16:58:49.011Z,1683824329.011 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-11T16:58:49.012Z,1683824329.012 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-11T16:58:49.012Z,1683824329.012 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-11T16:58:49.012Z,1683824329.012 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-11T16:58:49.013Z,1683824329.013 [Default:A.Wait] Running Loop=1
2023-05-11T16:58:49.013Z,1683824329.013 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-05-11T16:58:54.716Z,1683824334.716 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:58:54.722Z,1683824334.722 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:59:02.355Z,1683824342.355 [Default:A.Wait](INFO): Done Waiting.
2023-05-11T16:59:02.355Z,1683824342.355 [Default:A.Wait] Stopped
2023-05-11T16:59:02.355Z,1683824342.355 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-11T16:59:02.763Z,1683824342.763 [Default:CheckIn] Running Loop=1
2023-05-11T16:59:02.763Z,1683824342.763 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-11T16:59:02.763Z,1683824342.763 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-11T16:59:03.059Z,1683824343.059 [DataOverHttps](INFO): Sending 1220 bytes from file Logs/20230511T165521/Express0001.lzma
2023-05-11T16:59:03.167Z,1683824343.167 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-05-11T16:59:04.060Z,1683824344.060 [DataOverHttps](INFO): Moved sent file to Logs/20230511T165521/Express0001.lzma.bak
2023-05-11T16:59:04.060Z,1683824344.060 [DataOverHttps](INFO): SBD MOMSN=18290519
2023-05-11T16:59:07.983Z,1683824347.983 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:59:07.988Z,1683824347.988 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:59:21.314Z,1683824361.314 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:59:21.320Z,1683824361.320 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:59:35.152Z,1683824375.152 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:59:35.166Z,1683824375.166 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:59:48.444Z,1683824388.444 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T16:59:48.450Z,1683824388.450 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T17:00:01.777Z,1683824401.777 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T17:00:01.783Z,1683824401.783 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T17:00:15.046Z,1683824415.046 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T17:00:15.072Z,1683824415.072 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T17:01:15.242Z,1683824475.242 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-11T17:02:43.317Z,1683824563.317 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2023-05-11T17:02:55.863Z,1683824575.863 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2023-05-11T17:02:55.866Z,1683824575.866 [BPC1](INFO): Received data from all battery sticks.
2023-05-11T17:04:02.916Z,1683824642.916 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-11T16:59:02.8Z
2023-05-11T17:04:02.917Z,1683824642.917 [Default:CheckIn:Read_GPS] Stopped
2023-05-11T17:04:02.917Z,1683824642.917 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-11T17:04:03.319Z,1683824643.319 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-11T17:04:10.489Z,1683824650.489 [DataOverHttps](INFO): Sending 184 bytes from file Logs/20230511T165521/Courier0004.lzma
2023-05-11T17:04:11.491Z,1683824651.491 [DataOverHttps](INFO): Moved sent file to Logs/20230511T165521/Courier0004.lzma.bak
2023-05-11T17:04:11.491Z,1683824651.491 [DataOverHttps](INFO): SBD MOMSN=18290548
2023-05-11T17:04:28.536Z,1683824668.536 [DataOverHttps](INFO): Sending 263 bytes from file Logs/20230511T165521/Express0005.lzma
2023-05-11T17:04:29.986Z,1683824669.986 [DataOverHttps](INFO): Moved sent file to Logs/20230511T165521/Express0005.lzma.bak
2023-05-11T17:04:29.987Z,1683824669.987 [DataOverHttps](INFO): SBD MOMSN=18290550
2023-05-11T17:04:32.028Z,1683824672.028 [Default:CheckIn:Read_Iridium] Stopped
2023-05-11T17:04:32.028Z,1683824672.028 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-11T17:04:32.028Z,1683824672.028 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-11T17:04:34.463Z,1683824674.463 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-11T17:09:32.631Z,1683824972.631 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-11T17:09:32.631Z,1683824972.631 [Default:CheckIn:C.Wait] Stopped
2023-05-11T17:09:32.631Z,1683824972.631 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-11T17:09:32.631Z,1683824972.631 [Default:CheckIn:D] Running Loop=1
2023-05-11T17:09:32.999Z,1683824972.999 [Default:CheckIn:D] Stopped
2023-05-11T17:09:32.999Z,1683824972.999 [Default:CheckIn:E] Running Loop=1
2023-05-11T17:09:33.409Z,1683824973.409 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.733145 min 
2023-05-11T17:09:33.409Z,1683824973.409 [Default:CheckIn:E] Stopped
2023-05-11T17:09:33.409Z,1683824973.409 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-11T17:09:33.409Z,1683824973.409 [Default:CheckIn] Stopped
2023-05-11T17:09:33.409Z,1683824973.409 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-11T17:09:33.409Z,1683824973.409 [Default:CheckIn](INFO): Running loop #2
2023-05-11T17:09:33.409Z,1683824973.409 [Default:CheckIn] Running Loop=2
2023-05-11T17:09:33.410Z,1683824973.410 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-11T17:09:33.410Z,1683824973.410 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-11T17:09:35.018Z,1683824975.018 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-11T17:09:35.018Z,1683824975.018 [NAL9602] Data Fault, FailCount= 1
2023-05-11T17:09:35.018Z,1683824975.018 [NAL9602](ERROR): Data Fault
2023-05-11T17:09:35.038Z,1683824975.038 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-11T17:09:35.413Z,1683824975.413 [NAL9602](INFO): Powering down
2023-05-11T17:09:36.245Z,1683824976.245 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-11T17:09:36.245Z,1683824976.245 [NAL9602] No Fault, FailCount= 1
2023-05-11T17:10:05.714Z,1683825005.714 [NAL9602](INFO): Powering up NAL9602
2023-05-11T17:10:16.623Z,1683825016.623 [NAL9602](INFO): NAL9602 initialized
2023-05-11T17:14:33.649Z,1683825273.649 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-11T17:09:33.4Z
2023-05-11T17:14:33.649Z,1683825273.649 [Default:CheckIn:Read_GPS] Stopped
2023-05-11T17:14:33.649Z,1683825273.649 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-11T17:14:41.349Z,1683825281.349 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230511T165521/Courier0007.lzma
2023-05-11T17:14:42.351Z,1683825282.351 [DataOverHttps](INFO): Moved sent file to Logs/20230511T165521/Courier0007.lzma.bak
2023-05-11T17:14:42.351Z,1683825282.351 [DataOverHttps](INFO): SBD MOMSN=18290555
2023-05-11T17:15:00.461Z,1683825300.461 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20230511T165521/Express0008.lzma
2023-05-11T17:15:01.464Z,1683825301.464 [DataOverHttps](INFO): Moved sent file to Logs/20230511T165521/Express0008.lzma.bak
2023-05-11T17:15:01.464Z,1683825301.464 [DataOverHttps](INFO): SBD MOMSN=18290557
2023-05-11T17:15:03.971Z,1683825303.971 [Default:CheckIn:Read_Iridium] Stopped
2023-05-11T17:15:03.971Z,1683825303.971 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-11T17:15:03.971Z,1683825303.971 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-11T17:15:20.502Z,1683825320.502 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-11T17:15:51.202Z,1683825351.202 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-11T17:20:04.525Z,1683825604.525 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-11T17:20:04.525Z,1683825604.525 [Default:CheckIn:C.Wait] Stopped
2023-05-11T17:20:04.525Z,1683825604.525 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-11T17:20:04.525Z,1683825604.525 [Default:CheckIn:D] Running Loop=1
2023-05-11T17:20:04.938Z,1683825604.938 [Default:CheckIn:D] Stopped
2023-05-11T17:20:04.938Z,1683825604.938 [Default:CheckIn:E] Running Loop=1
2023-05-11T17:20:05.344Z,1683825605.344 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.265444 min 
2023-05-11T17:20:05.344Z,1683825605.344 [Default:CheckIn:E] Stopped
2023-05-11T17:20:05.344Z,1683825605.344 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-11T17:20:05.344Z,1683825605.344 [Default:CheckIn] Stopped
2023-05-11T17:20:05.344Z,1683825605.344 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-11T17:20:05.344Z,1683825605.344 [Default:CheckIn](INFO): Running loop #3
2023-05-11T17:20:05.344Z,1683825605.344 [Default:CheckIn] Running Loop=3
2023-05-11T17:20:05.344Z,1683825605.344 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-11T17:20:05.345Z,1683825605.345 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-11T17:20:38.051Z,1683825638.051 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-05-11T17:20:38.130Z,1683825638.130 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-05-11T17:25:05.527Z,1683825905.527 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-11T17:20:05.3Z
2023-05-11T17:25:05.527Z,1683825905.527 [Default:CheckIn:Read_GPS] Stopped
2023-05-11T17:25:05.527Z,1683825905.527 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-11T17:25:09.135Z,1683825909.135 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-11T17:25:12.521Z,1683825912.521 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230511T165521/Courier0010.lzma
2023-05-11T17:25:13.523Z,1683825913.523 [DataOverHttps](INFO): Moved sent file to Logs/20230511T165521/Courier0010.lzma.bak
2023-05-11T17:25:13.523Z,1683825913.523 [DataOverHttps](INFO): SBD MOMSN=18290561
2023-05-11T17:25:30.773Z,1683825930.773 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20230511T165521/Express0011.lzma
2023-05-11T17:25:31.775Z,1683825931.775 [DataOverHttps](INFO): Moved sent file to Logs/20230511T165521/Express0011.lzma.bak
2023-05-11T17:25:31.775Z,1683825931.775 [DataOverHttps](INFO): SBD MOMSN=18290563
2023-05-11T17:25:34.611Z,1683825934.611 [Default:CheckIn:Read_Iridium] Stopped
2023-05-11T17:25:34.611Z,1683825934.611 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-11T17:25:34.611Z,1683825934.611 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-11T17:25:39.838Z,1683825939.838 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-11T17:30:35.183Z,1683826235.183 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-11T17:30:35.183Z,1683826235.183 [Default:CheckIn:C.Wait] Stopped
2023-05-11T17:30:35.183Z,1683826235.183 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-11T17:30:35.183Z,1683826235.183 [Default:CheckIn:D] Running Loop=1
2023-05-11T17:30:35.600Z,1683826235.600 [Default:CheckIn:D] Stopped
2023-05-11T17:30:35.600Z,1683826235.600 [Default:CheckIn:E] Running Loop=1
2023-05-11T17:30:35.989Z,1683826235.989 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.776495 min 
2023-05-11T17:30:35.989Z,1683826235.989 [Default:CheckIn:E] Stopped
2023-05-11T17:30:35.990Z,1683826235.990 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-11T17:30:35.990Z,1683826235.990 [Default:CheckIn] Stopped
2023-05-11T17:30:35.990Z,1683826235.990 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-11T17:30:35.990Z,1683826235.990 [Default:CheckIn](INFO): Running loop #4
2023-05-11T17:30:35.990Z,1683826235.990 [Default:CheckIn] Running Loop=4
2023-05-11T17:30:35.990Z,1683826235.990 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-11T17:30:35.990Z,1683826235.990 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-11T17:30:37.590Z,1683826237.590 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-11T17:30:37.590Z,1683826237.590 [NAL9602] Data Fault, FailCount= 2
2023-05-11T17:30:37.590Z,1683826237.590 [NAL9602](ERROR): Data Fault
2023-05-11T17:30:37.610Z,1683826237.610 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-11T17:30:37.999Z,1683826237.999 [NAL9602](INFO): Powering down
2023-05-11T17:30:38.820Z,1683826238.820 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-11T17:30:38.820Z,1683826238.820 [NAL9602] No Fault, FailCount= 2
2023-05-11T17:31:08.298Z,1683826268.298 [NAL9602](INFO): Powering up NAL9602
2023-05-11T17:31:19.616Z,1683826279.616 [NAL9602](INFO): NAL9602 initialized
2023-05-11T17:35:36.170Z,1683826536.170 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-11T17:30:35.0Z
2023-05-11T17:35:36.170Z,1683826536.170 [Default:CheckIn:Read_GPS] Stopped
2023-05-11T17:35:36.170Z,1683826536.170 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-11T17:35:43.077Z,1683826543.077 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230511T165521/Courier0013.lzma
2023-05-11T17:35:45.219Z,1683826545.219 [DataOverHttps](INFO): Moved sent file to Logs/20230511T165521/Courier0013.lzma.bak
2023-05-11T17:35:45.219Z,1683826545.219 [DataOverHttps](INFO): SBD MOMSN=18290567
2023-05-11T17:36:01.605Z,1683826561.605 [DataOverHttps](INFO): Sending 234 bytes from file Logs/20230511T165521/Express0014.lzma
2023-05-11T17:36:02.607Z,1683826562.607 [DataOverHttps](INFO): Moved sent file to Logs/20230511T165521/Express0014.lzma.bak
2023-05-11T17:36:02.607Z,1683826562.607 [DataOverHttps](INFO): SBD MOMSN=18290569
2023-05-11T17:36:05.265Z,1683826565.265 [Default:CheckIn:Read_Iridium] Stopped
2023-05-11T17:36:05.265Z,1683826565.265 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-11T17:36:05.265Z,1683826565.265 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-11T17:36:23.019Z,1683826583.019 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-11T17:36:53.722Z,1683826613.722 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-11T17:41:05.860Z,1683826865.860 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-11T17:41:05.860Z,1683826865.860 [Default:CheckIn:C.Wait] Stopped
2023-05-11T17:41:05.860Z,1683826865.860 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-11T17:41:05.860Z,1683826865.860 [Default:CheckIn:D] Running Loop=1
2023-05-11T17:41:06.271Z,1683826866.271 [Default:CheckIn:D] Stopped
2023-05-11T17:41:06.271Z,1683826866.271 [Default:CheckIn:E] Running Loop=1
2023-05-11T17:41:06.664Z,1683826866.664 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.287675 min 
2023-05-11T17:41:06.664Z,1683826866.664 [Default:CheckIn:E] Stopped
2023-05-11T17:41:06.664Z,1683826866.664 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-11T17:41:06.664Z,1683826866.664 [Default:CheckIn] Stopped
2023-05-11T17:41:06.664Z,1683826866.664 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-11T17:41:06.665Z,1683826866.665 [Default:CheckIn](INFO): Running loop #5
2023-05-11T17:41:06.665Z,1683826866.665 [Default:CheckIn] Running Loop=5
2023-05-11T17:41:06.665Z,1683826866.665 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-11T17:41:06.665Z,1683826866.665 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-11T17:41:39.411Z,1683826899.411 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-05-11T17:41:39.490Z,1683826899.490 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-05-11T17:46:06.880Z,1683827166.880 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-11T17:41:06.7Z
2023-05-11T17:46:06.880Z,1683827166.880 [Default:CheckIn:Read_GPS] Stopped
2023-05-11T17:46:06.880Z,1683827166.880 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-11T17:46:11.302Z,1683827171.302 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-11T17:46:13.729Z,1683827173.729 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230511T165521/Courier0016.lzma
2023-05-11T17:46:14.731Z,1683827174.731 [DataOverHttps](INFO): Moved sent file to Logs/20230511T165521/Courier0016.lzma.bak
2023-05-11T17:46:14.731Z,1683827174.731 [DataOverHttps](INFO): SBD MOMSN=18290574
2023-05-11T17:46:31.821Z,1683827191.821 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20230511T165521/Express0017.lzma
2023-05-11T17:46:32.827Z,1683827192.827 [DataOverHttps](INFO): Moved sent file to Logs/20230511T165521/Express0017.lzma.bak
2023-05-11T17:46:32.827Z,1683827192.827 [DataOverHttps](INFO): SBD MOMSN=18290576
2023-05-11T17:46:35.551Z,1683827195.551 [Default:CheckIn:Read_Iridium] Stopped
2023-05-11T17:46:35.551Z,1683827195.551 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-11T17:46:35.551Z,1683827195.551 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-11T17:46:42.010Z,1683827202.010 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-11T17:51:36.196Z,1683827496.196 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-11T17:51:36.196Z,1683827496.196 [Default:CheckIn:C.Wait] Stopped
2023-05-11T17:51:36.196Z,1683827496.196 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-11T17:51:36.196Z,1683827496.196 [Default:CheckIn:D] Running Loop=1
2023-05-11T17:51:36.609Z,1683827496.609 [Default:CheckIn:D] Stopped
2023-05-11T17:51:36.609Z,1683827496.609 [Default:CheckIn:E] Running Loop=1
2023-05-11T17:51:37.019Z,1683827497.019 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.793298 min 
2023-05-11T17:51:37.019Z,1683827497.019 [Default:CheckIn:E] Stopped
2023-05-11T17:51:37.019Z,1683827497.019 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-11T17:51:37.019Z,1683827497.019 [Default:CheckIn] Stopped
2023-05-11T17:51:37.019Z,1683827497.019 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-11T17:51:37.019Z,1683827497.019 [Default:CheckIn](INFO): Running loop #6
2023-05-11T17:51:37.019Z,1683827497.019 [Default:CheckIn] Running Loop=6
2023-05-11T17:51:37.019Z,1683827497.019 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-11T17:51:37.019Z,1683827497.019 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-11T17:51:38.615Z,1683827498.615 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-11T17:51:38.615Z,1683827498.615 [NAL9602] Data Fault, FailCount= 3
2023-05-11T17:51:38.615Z,1683827498.615 [NAL9602](ERROR): Data Fault
2023-05-11T17:51:38.643Z,1683827498.643 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-11T17:51:39.014Z,1683827499.014 [NAL9602](INFO): Powering down
2023-05-11T17:51:39.845Z,1683827499.845 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-11T17:51:39.845Z,1683827499.845 [NAL9602] No Fault, FailCount= 3
2023-05-11T17:52:09.323Z,1683827529.323 [NAL9602](INFO): Powering up NAL9602
2023-05-11T17:52:20.228Z,1683827540.228 [NAL9602](INFO): NAL9602 initialized
2023-05-11T17:56:37.179Z,1683827797.179 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-11T17:51:37.0Z
2023-05-11T17:56:37.179Z,1683827797.179 [Default:CheckIn:Read_GPS] Stopped
2023-05-11T17:56:37.179Z,1683827797.179 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-11T17:56:44.473Z,1683827804.473 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230511T165521/Courier0019.lzma
2023-05-11T17:56:45.475Z,1683827805.475 [DataOverHttps](INFO): Moved sent file to Logs/20230511T165521/Courier0019.lzma.bak
2023-05-11T17:56:45.475Z,1683827805.475 [DataOverHttps](INFO): SBD MOMSN=18290580
2023-05-11T17:57:02.585Z,1683827822.585 [DataOverHttps](INFO): Sending 236 bytes from file Logs/20230511T165521/Express0020.lzma
2023-05-11T17:57:03.587Z,1683827823.587 [DataOverHttps](INFO): Moved sent file to Logs/20230511T165521/Express0020.lzma.bak
2023-05-11T17:57:03.587Z,1683827823.587 [DataOverHttps](INFO): SBD MOMSN=18290582
2023-05-11T17:57:06.280Z,1683827826.280 [Default:CheckIn:Read_Iridium] Stopped
2023-05-11T17:57:06.280Z,1683827826.280 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-11T17:57:06.280Z,1683827826.280 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-11T17:57:24.035Z,1683827844.035 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-11T17:57:54.734Z,1683827874.734 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-11T18:02:06.903Z,1683828126.903 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-11T18:02:06.903Z,1683828126.903 [Default:CheckIn:C.Wait] Stopped
2023-05-11T18:02:06.903Z,1683828126.903 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-11T18:02:06.903Z,1683828126.903 [Default:CheckIn:D] Running Loop=1
2023-05-11T18:02:07.295Z,1683828127.295 [Default:CheckIn:D] Stopped
2023-05-11T18:02:07.295Z,1683828127.295 [Default:CheckIn:E] Running Loop=1
2023-05-11T18:02:07.704Z,1683828127.704 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.304740 min 
2023-05-11T18:02:07.704Z,1683828127.704 [Default:CheckIn:E] Stopped
2023-05-11T18:02:07.704Z,1683828127.704 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-11T18:02:07.705Z,1683828127.705 [Default:CheckIn] Stopped
2023-05-11T18:02:07.705Z,1683828127.705 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-11T18:02:07.705Z,1683828127.705 [Default:CheckIn](INFO): Running loop #7
2023-05-11T18:02:07.705Z,1683828127.705 [Default:CheckIn] Running Loop=7
2023-05-11T18:02:07.705Z,1683828127.705 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-11T18:02:07.705Z,1683828127.705 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-11T18:02:40.420Z,1683828160.420 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-05-11T18:02:40.501Z,1683828160.501 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-05-11T18:05:23.700Z,1683828323.700 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T18:05:36.559Z,1683828336.559 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T18:05:36.565Z,1683828336.565 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T18:05:49.890Z,1683828349.890 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T18:05:49.895Z,1683828349.895 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T18:06:03.223Z,1683828363.223 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T18:06:03.228Z,1683828363.228 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T18:06:16.555Z,1683828376.555 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T18:06:16.561Z,1683828376.561 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T18:06:29.886Z,1683828389.886 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T18:06:29.892Z,1683828389.892 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T18:06:43.218Z,1683828403.218 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T18:06:43.223Z,1683828403.223 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T18:06:56.955Z,1683828416.955 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T18:06:56.960Z,1683828416.960 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T18:07:07.878Z,1683828427.878 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-11T18:02:07.7Z
2023-05-11T18:07:07.878Z,1683828427.878 [Default:CheckIn:Read_GPS] Stopped
2023-05-11T18:07:07.879Z,1683828427.879 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-11T18:07:10.287Z,1683828430.287 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T18:07:10.293Z,1683828430.293 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T18:07:11.918Z,1683828431.918 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-11T18:07:15.217Z,1683828435.217 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230511T165521/Courier0022.lzma
2023-05-11T18:07:16.221Z,1683828436.221 [DataOverHttps](INFO): Moved sent file to Logs/20230511T165521/Courier0022.lzma.bak
2023-05-11T18:07:16.221Z,1683828436.221 [DataOverHttps](INFO): SBD MOMSN=18290587
2023-05-11T18:07:23.619Z,1683828443.619 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T18:07:23.625Z,1683828443.625 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T18:07:32.465Z,1683828452.465 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20230511T165521/Express0023.lzma
2023-05-11T18:07:33.467Z,1683828453.467 [DataOverHttps](INFO): Moved sent file to Logs/20230511T165521/Express0023.lzma.bak
2023-05-11T18:07:33.467Z,1683828453.467 [DataOverHttps](INFO): SBD MOMSN=18290589
2023-05-11T18:07:36.173Z,1683828456.173 [Default:CheckIn:Read_Iridium] Stopped
2023-05-11T18:07:36.173Z,1683828456.173 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-11T18:07:36.173Z,1683828456.173 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-11T18:07:37.442Z,1683828457.442 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T18:07:37.448Z,1683828457.448 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T18:07:42.606Z,1683828462.606 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-11T18:07:50.781Z,1683828470.781 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-05-11T18:08:04.434Z,1683828484.434 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2023-05-11T18:10:18.162Z,1683828618.162 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 5.
2023-05-11T18:10:18.165Z,1683828618.165 [BPC1](INFO): Received data from all battery sticks.
2023-05-11T18:12:36.768Z,1683828756.768 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-11T18:12:36.768Z,1683828756.768 [Default:CheckIn:C.Wait] Stopped
2023-05-11T18:12:36.768Z,1683828756.768 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-11T18:12:36.769Z,1683828756.769 [Default:CheckIn:D] Running Loop=1
2023-05-11T18:12:37.172Z,1683828757.172 [Default:CheckIn:D] Stopped
2023-05-11T18:12:37.172Z,1683828757.172 [Default:CheckIn:E] Running Loop=1
2023-05-11T18:12:37.573Z,1683828757.573 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.802686 min 
2023-05-11T18:12:37.573Z,1683828757.573 [Default:CheckIn:E] Stopped
2023-05-11T18:12:37.573Z,1683828757.573 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-11T18:12:37.574Z,1683828757.574 [Default:CheckIn] Stopped
2023-05-11T18:12:37.574Z,1683828757.574 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-11T18:12:37.574Z,1683828757.574 [Default:CheckIn](INFO): Running loop #8
2023-05-11T18:12:37.574Z,1683828757.574 [Default:CheckIn] Running Loop=8
2023-05-11T18:12:37.574Z,1683828757.574 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-11T18:12:37.574Z,1683828757.574 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-11T18:12:39.183Z,1683828759.183 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-11T18:12:39.183Z,1683828759.183 [NAL9602] Data Fault, FailCount= 4
2023-05-11T18:12:39.183Z,1683828759.183 [NAL9602](ERROR): Data Fault
2023-05-11T18:12:39.202Z,1683828759.202 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-11T18:12:39.586Z,1683828759.586 [NAL9602](INFO): Powering down
2023-05-11T18:12:40.410Z,1683828760.410 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-11T18:12:40.410Z,1683828760.410 [NAL9602] No Fault, FailCount= 4
2023-05-11T18:13:09.887Z,1683828789.887 [NAL9602](INFO): Powering up NAL9602
2023-05-11T18:13:20.791Z,1683828800.791 [NAL9602](INFO): NAL9602 initialized
2023-05-11T18:17:37.766Z,1683829057.766 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-11T18:12:37.6Z
2023-05-11T18:17:37.766Z,1683829057.766 [Default:CheckIn:Read_GPS] Stopped
2023-05-11T18:17:37.766Z,1683829057.766 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-11T18:17:44.789Z,1683829064.789 [DataOverHttps](INFO): Sending 51 bytes from file Logs/20230511T165521/Courier0025.lzma
2023-05-11T18:17:45.791Z,1683829065.791 [DataOverHttps](INFO): Moved sent file to Logs/20230511T165521/Courier0025.lzma.bak
2023-05-11T18:17:45.791Z,1683829065.791 [DataOverHttps](INFO): SBD MOMSN=18290593
2023-05-11T18:18:01.933Z,1683829081.933 [DataOverHttps](INFO): Sending 234 bytes from file Logs/20230511T165521/Express0026.lzma
2023-05-11T18:18:02.935Z,1683829082.935 [DataOverHttps](INFO): Moved sent file to Logs/20230511T165521/Express0026.lzma.bak
2023-05-11T18:18:02.935Z,1683829082.935 [DataOverHttps](INFO): SBD MOMSN=18290595
2023-05-11T18:18:05.673Z,1683829085.673 [Default:CheckIn:Read_Iridium] Stopped
2023-05-11T18:18:05.673Z,1683829085.673 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-11T18:18:05.673Z,1683829085.673 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-11T18:18:24.615Z,1683829104.615 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-11T18:18:55.326Z,1683829135.326 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-11T18:23:06.223Z,1683829386.223 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-11T18:23:06.223Z,1683829386.223 [Default:CheckIn:C.Wait] Stopped
2023-05-11T18:23:06.223Z,1683829386.223 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-11T18:23:06.223Z,1683829386.223 [Default:CheckIn:D] Running Loop=1
2023-05-11T18:23:06.640Z,1683829386.640 [Default:CheckIn:D] Stopped
2023-05-11T18:23:06.640Z,1683829386.640 [Default:CheckIn:E] Running Loop=1
2023-05-11T18:23:07.025Z,1683829387.025 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.293823 min 
2023-05-11T18:23:07.025Z,1683829387.025 [Default:CheckIn:E] Stopped
2023-05-11T18:23:07.025Z,1683829387.025 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-11T18:23:07.025Z,1683829387.025 [Default:CheckIn] Stopped
2023-05-11T18:23:07.025Z,1683829387.025 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-11T18:23:07.025Z,1683829387.025 [Default:CheckIn](INFO): Running loop #9
2023-05-11T18:23:07.025Z,1683829387.025 [Default:CheckIn] Running Loop=9
2023-05-11T18:23:07.026Z,1683829387.026 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-11T18:23:07.026Z,1683829387.026 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-11T18:23:39.738Z,1683829419.738 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-05-11T18:23:39.813Z,1683829419.813 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-05-11T18:28:07.202Z,1683829687.202 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-11T18:23:07.0Z
2023-05-11T18:28:07.203Z,1683829687.203 [Default:CheckIn:Read_GPS] Stopped
2023-05-11T18:28:07.203Z,1683829687.203 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-11T18:28:11.227Z,1683829691.227 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-11T18:28:14.777Z,1683829694.777 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230511T165521/Courier0028.lzma
2023-05-11T18:28:15.779Z,1683829695.779 [DataOverHttps](INFO): Moved sent file to Logs/20230511T165521/Courier0028.lzma.bak
2023-05-11T18:28:15.779Z,1683829695.779 [DataOverHttps](INFO): SBD MOMSN=18290600
2023-05-11T18:28:32.793Z,1683829712.793 [DataOverHttps](INFO): Sending 218 bytes from file Logs/20230511T165521/Express0029.lzma
2023-05-11T18:28:33.795Z,1683829713.795 [DataOverHttps](INFO): Moved sent file to Logs/20230511T165521/Express0029.lzma.bak
2023-05-11T18:28:33.795Z,1683829713.795 [DataOverHttps](INFO): SBD MOMSN=18290602
2023-05-11T18:28:36.301Z,1683829716.301 [Default:CheckIn:Read_Iridium] Stopped
2023-05-11T18:28:36.301Z,1683829716.301 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-11T18:28:36.301Z,1683829716.301 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-11T18:28:41.950Z,1683829721.950 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-11T18:33:36.876Z,1683830016.876 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-11T18:33:36.876Z,1683830016.876 [Default:CheckIn:C.Wait] Stopped
2023-05-11T18:33:36.876Z,1683830016.876 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-11T18:33:36.876Z,1683830016.876 [Default:CheckIn:D] Running Loop=1
2023-05-11T18:33:37.285Z,1683830017.285 [Default:CheckIn:D] Stopped
2023-05-11T18:33:37.285Z,1683830017.285 [Default:CheckIn:E] Running Loop=1
2023-05-11T18:33:37.686Z,1683830017.686 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.804565 min 
2023-05-11T18:33:37.686Z,1683830017.686 [Default:CheckIn:E] Stopped
2023-05-11T18:33:37.686Z,1683830017.686 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-11T18:33:37.686Z,1683830017.686 [Default:CheckIn] Stopped
2023-05-11T18:33:37.686Z,1683830017.686 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-11T18:33:37.687Z,1683830017.687 [Default:CheckIn](INFO): Running loop #10
2023-05-11T18:33:37.687Z,1683830017.687 [Default:CheckIn] Running Loop=10
2023-05-11T18:33:37.687Z,1683830017.687 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-11T18:33:37.687Z,1683830017.687 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-11T18:33:39.290Z,1683830019.290 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-11T18:33:39.290Z,1683830019.290 [NAL9602] Data Fault, FailCount= 5
2023-05-11T18:33:39.290Z,1683830019.290 [NAL9602](ERROR): Data Fault
2023-05-11T18:33:39.310Z,1683830019.310 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-11T18:33:39.310Z,1683830019.310 [CBIT](CRITICAL): Data Fault in component: NAL9602
2023-05-11T18:33:39.699Z,1683830019.699 [NAL9602](INFO): Powering down
2023-05-11T18:33:39.729Z,1683830019.729 [CommandExec](FAULT): Scheduling is paused
2023-05-11T18:33:39.729Z,1683830019.729 [CBIT](INFO): Critical error at 20230511T183339
2023-05-11T18:33:39.729Z,1683830019.729 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-05-11T18:35:39.298Z,1683830139.298 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-11T18:35:39.298Z,1683830139.298 [NAL9602] No Fault, FailCount= 5
2023-05-11T18:35:39.683Z,1683830139.683 [NAL9602](INFO): Powering up NAL9602
2023-05-11T18:35:50.599Z,1683830150.599 [NAL9602](INFO): NAL9602 initialized
2023-05-11T18:38:37.884Z,1683830317.884 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-11T18:33:37.7Z
2023-05-11T18:38:37.884Z,1683830317.884 [Default:CheckIn:Read_GPS] Stopped
2023-05-11T18:38:37.884Z,1683830317.884 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-11T18:38:45.505Z,1683830325.505 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20230511T165521/Courier0031.lzma
2023-05-11T18:38:46.507Z,1683830326.507 [DataOverHttps](INFO): Moved sent file to Logs/20230511T165521/Courier0031.lzma.bak
2023-05-11T18:38:46.507Z,1683830326.507 [DataOverHttps](INFO): SBD MOMSN=18290606
2023-05-11T18:39:03.808Z,1683830343.808 [DataOverHttps](INFO): Sending 255 bytes from file Logs/20230511T165521/Express0032.lzma
2023-05-11T18:39:04.807Z,1683830344.807 [DataOverHttps](INFO): Moved sent file to Logs/20230511T165521/Express0032.lzma.bak
2023-05-11T18:39:04.807Z,1683830344.807 [DataOverHttps](INFO): SBD MOMSN=18290609
2023-05-11T18:39:07.373Z,1683830347.373 [Default:CheckIn:Read_Iridium] Stopped
2023-05-11T18:39:07.373Z,1683830347.373 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-11T18:39:07.373Z,1683830347.373 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-11T18:40:54.402Z,1683830454.402 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-11T18:41:25.107Z,1683830485.107 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-11T18:44:07.936Z,1683830647.936 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-11T18:44:07.936Z,1683830647.936 [Default:CheckIn:C.Wait] Stopped
2023-05-11T18:44:07.936Z,1683830647.936 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-11T18:44:07.937Z,1683830647.937 [Default:CheckIn:D] Running Loop=1
2023-05-11T18:44:08.327Z,1683830648.327 [Default:CheckIn:D] Stopped
2023-05-11T18:44:08.327Z,1683830648.327 [Default:CheckIn:E] Running Loop=1
2023-05-11T18:44:08.737Z,1683830648.737 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.321940 min 
2023-05-11T18:44:08.737Z,1683830648.737 [Default:CheckIn:E] Stopped
2023-05-11T18:44:08.737Z,1683830648.737 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-11T18:44:08.737Z,1683830648.737 [Default:CheckIn] Stopped
2023-05-11T18:44:08.737Z,1683830648.737 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-11T18:44:08.737Z,1683830648.737 [Default:CheckIn](INFO): Running loop #11
2023-05-11T18:44:08.737Z,1683830648.737 [Default:CheckIn] Running Loop=11
2023-05-11T18:44:08.738Z,1683830648.738 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-11T18:44:08.738Z,1683830648.738 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-11T18:44:41.447Z,1683830681.447 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-05-11T18:44:41.521Z,1683830681.521 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-05-11T18:47:15.747Z,1683830835.747 [CommandExec](IMPORTANT): got command restart application
2023-05-11T18:47:16.750Z,1683830836.750 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-11T18:47:16.750Z,1683830836.750 [CommandExec](INFO): Uninitializing the command executive.
2023-05-11T18:47:16.750Z,1683830836.750 [CommandExec](INFO): Uninitializing the command scheduler.
2023-05-11T18:47:16.750Z,1683830836.750 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:16.929Z,1683830836.929 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-05-11T18:47:16.929Z,1683830836.929 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-05-11T18:47:16.930Z,1683830836.930 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:16.930Z,1683830836.930 [NavChartDb](INFO): Join timeout helper Thread ID is 9565
2023-05-11T18:47:17.114Z,1683830837.114 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-11T18:47:17.114Z,1683830837.114 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:17.126Z,1683830837.126 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-05-11T18:47:17.126Z,1683830837.126 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:17.126Z,1683830837.126 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9566
2023-05-11T18:47:17.182Z,1683830837.182 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-11T18:47:17.182Z,1683830837.182 [WetLabsBB2FL](INFO): Powering down
2023-05-11T18:47:17.183Z,1683830837.183 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:17.194Z,1683830837.194 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-05-11T18:47:17.194Z,1683830837.194 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:17.195Z,1683830837.195 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9567
2023-05-11T18:47:17.722Z,1683830837.722 [CTD_Seabird](INFO): Powering down
2023-05-11T18:47:17.733Z,1683830837.733 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-11T18:47:17.734Z,1683830837.734 [CTD_Seabird](INFO): Powering down
2023-05-11T18:47:17.745Z,1683830837.745 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:17.746Z,1683830837.746 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-05-11T18:47:17.746Z,1683830837.746 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:17.748Z,1683830837.748 [Radio_Surface](INFO): Join timeout helper Thread ID is 9568
2023-05-11T18:47:17.950Z,1683830837.950 [Radio_Surface](INFO): Powering down
2023-05-11T18:47:17.950Z,1683830837.950 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-11T18:47:17.951Z,1683830837.951 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:17.965Z,1683830837.965 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-05-11T18:47:17.965Z,1683830837.965 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:17.966Z,1683830837.966 [Onboard](INFO): Join timeout helper Thread ID is 9569
2023-05-11T18:47:18.458Z,1683830838.458 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-05-11T18:47:22.677Z,1683830842.677 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-11T18:47:22.678Z,1683830842.678 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:22.686Z,1683830842.686 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-05-11T18:47:22.687Z,1683830842.687 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:22.687Z,1683830842.687 [DataOverHttps](INFO): Join timeout helper Thread ID is 9570
2023-05-11T18:47:23.478Z,1683830843.478 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-11T18:47:23.478Z,1683830843.478 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:23.486Z,1683830843.486 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler
2023-05-11T18:47:23.486Z,1683830843.486 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:23.487Z,1683830843.487 [DATMMP](INFO): Join timeout helper Thread ID is 9571
2023-05-11T18:47:23.673Z,1683830843.673 [DATMMP](INFO): Stop
2023-05-11T18:47:23.674Z,1683830843.674 [DATMMP](INFO): uninitialize
2023-05-11T18:47:23.674Z,1683830843.674 [DATMMP](INFO): Powering down
2023-05-11T18:47:23.746Z,1683830843.746 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-11T18:47:23.746Z,1683830843.746 [DATMMP](INFO): uninitialize
2023-05-11T18:47:23.746Z,1683830843.746 [DATMMP](INFO): Powering down
2023-05-11T18:47:23.747Z,1683830843.747 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:23.764Z,1683830843.764 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-05-11T18:47:23.764Z,1683830843.764 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:23.765Z,1683830843.765 [BackseatComponent](INFO): Join timeout helper Thread ID is 9572
2023-05-11T18:47:23.790Z,1683830843.790 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-11T18:47:23.790Z,1683830843.790 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:23.810Z,1683830843.810 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-05-11T18:47:23.810Z,1683830843.810 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:23.811Z,1683830843.811 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-11T18:47:23.811Z,1683830843.811 [logger](INFO): Join timeout helper Thread ID is 9573
2023-05-11T18:47:23.811Z,1683830843.811 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:23.830Z,1683830843.830 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-05-11T18:47:23.830Z,1683830843.830 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:23.831Z,1683830843.831 [CommandLine](INFO): Join timeout helper Thread ID is 9574
2023-05-11T18:47:23.930Z,1683830843.930 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-11T18:47:23.930Z,1683830843.930 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:23.950Z,1683830843.950 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-05-11T18:47:23.950Z,1683830843.950 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:23.951Z,1683830843.951 [CommandExec](INFO): Join timeout helper Thread ID is 9575
2023-05-11T18:47:23.952Z,1683830843.952 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-05-11T18:47:23.952Z,1683830843.952 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:23.952Z,1683830843.952 [controlThread](INFO): Join timeout helper Thread ID is 9576
2023-05-11T18:47:24.270Z,1683830844.270 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-11T18:47:24.270Z,1683830844.270 [controlThread](DEBUG): Uninitializing ControlThread
2023-05-11T18:47:24.270Z,1683830844.270 [AHRS_M2](INFO): Powering down
2023-05-11T18:47:24.342Z,1683830844.342 [NAL9602](INFO): Powering down
2023-05-11T18:47:24.345Z,1683830844.345 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-05-11T18:47:24.346Z,1683830844.346 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-05-11T18:47:24.346Z,1683830844.346 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-05-11T18:47:24.347Z,1683830844.347 [MissionManager](INFO): Uninitializing Mission Default
2023-05-11T18:47:24.347Z,1683830844.347 [Default] Stopped
2023-05-11T18:47:24.347Z,1683830844.347 [Default](DEBUG): Aggregate::uninitialize Default
2023-05-11T18:47:24.347Z,1683830844.347 [Default:B.GoToSurface] Stopped
2023-05-11T18:47:24.347Z,1683830844.347 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-11T18:47:24.347Z,1683830844.347 [Default:CheckIn] Stopped
2023-05-11T18:47:24.347Z,1683830844.347 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-11T18:47:24.347Z,1683830844.347 [Default:CheckIn:Read_GPS] Stopped
2023-05-11T18:47:24.350Z,1683830844.350 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-05-11T18:47:24.350Z,1683830844.350 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-05-11T18:47:24.351Z,1683830844.351 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-05-11T18:47:24.351Z,1683830844.351 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-05-11T18:47:24.351Z,1683830844.351 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-05-11T18:47:24.351Z,1683830844.351 [BuoyancyServo](INFO): Powering down
2023-05-11T18:47:24.366Z,1683830844.366 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-05-11T18:47:24.366Z,1683830844.366 [ElevatorServo](INFO): Powering down
2023-05-11T18:47:24.367Z,1683830844.367 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-05-11T18:47:24.367Z,1683830844.367 [MassServo](INFO): Powering down
2023-05-11T18:47:24.367Z,1683830844.367 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-05-11T18:47:24.368Z,1683830844.368 [RudderServo](INFO): Powering down
2023-05-11T18:47:24.368Z,1683830844.368 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-05-11T18:47:24.369Z,1683830844.369 [ThrusterHE](INFO): Powering down
2023-05-11T18:47:24.370Z,1683830844.370 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-05-11T18:47:24.370Z,1683830844.370 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-05-11T18:47:24.370Z,1683830844.370 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-05-11T18:47:24.370Z,1683830844.370 [CBIT](DEBUG): Powering off loads.
2023-05-11T18:47:24.381Z,1683830844.381 [CBIT](DEBUG): Disabling WDT.
2023-05-11T18:47:24.393Z,1683830844.393 [CBIT](DEBUG): Opening all GF detection circuits.
2023-05-11T18:47:24.394Z,1683830844.394 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:24.449Z,1683830844.449 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:24.452Z,1683830844.452 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:24.458Z,1683830844.458 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:24.464Z,1683830844.464 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:24.506Z,1683830844.506 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:24.553Z,1683830844.553 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:24.558Z,1683830844.558 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:24.591Z,1683830844.591 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-11T18:47:24.654Z,1683830844.654 [logger ThreadHandler](INFO): Thread cancelled.