2023-05-09T19:00:36.426Z,1683658836.426 [Supervisor](DEBUG): Initializing supervisor.
2023-05-09T19:00:36.440Z,1683658836.440 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-05-09T19:00:36.441Z,1683658836.441 [SyncHandler](INFO): Protected caller Thread ID is 835
2023-05-09T19:00:36.441Z,1683658836.441 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-05-09T19:00:36.442Z,1683658836.442 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-05-09T19:00:36.442Z,1683658836.442 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 836
2023-05-09T19:00:36.446Z,1683658836.446 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-05-09T19:00:36.464Z,1683658836.464 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-05-09T19:00:36.465Z,1683658836.465 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-05-09T19:00:36.465Z,1683658836.465 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 837
2023-05-09T19:00:36.469Z,1683658836.469 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-05-09T19:00:36.470Z,1683658836.470 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-05-09T19:00:36.471Z,1683658836.471 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 838
2023-05-09T19:00:36.473Z,1683658836.473 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-05-09T19:00:36.474Z,1683658836.474 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-05-09T19:00:36.474Z,1683658836.474 [logger ThreadHandler](INFO): Protected caller Thread ID is 839
2023-05-09T19:00:36.478Z,1683658836.478 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-05-09T19:00:36.478Z,1683658836.478 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-05-09T19:00:36.482Z,1683658836.482 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-05-09T19:00:36.960Z,1683658836.960 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-05-09T19:00:36.962Z,1683658836.962 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-05-09T19:00:37.179Z,1683658837.179 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-05-09T19:00:37.181Z,1683658837.181 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-05-09T19:00:37.263Z,1683658837.263 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-05-09T19:00:37.486Z,1683658837.486 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-05-09T19:00:37.488Z,1683658837.488 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-05-09T19:00:37.569Z,1683658837.569 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-05-09T19:00:37.665Z,1683658837.665 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-05-09T19:00:37.667Z,1683658837.667 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-05-09T19:00:38.031Z,1683658838.031 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-05-09T19:00:38.032Z,1683658838.032 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-05-09T19:00:38.165Z,1683658838.165 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-05-09T19:00:38.166Z,1683658838.166 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-05-09T19:00:38.677Z,1683658838.677 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-05-09T19:00:38.678Z,1683658838.678 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-05-09T19:00:38.869Z,1683658838.869 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-05-09T19:00:38.870Z,1683658838.870 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-05-09T19:00:39.276Z,1683658839.276 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-05-09T19:00:39.278Z,1683658839.278 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-05-09T19:00:39.564Z,1683658839.564 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-05-09T19:00:39.566Z,1683658839.566 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-05-09T19:00:39.677Z,1683658839.677 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-05-09T19:00:39.679Z,1683658839.679 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-05-09T19:00:41.110Z,1683658841.110 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-05-09T19:00:41.112Z,1683658841.112 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-05-09T19:00:41.484Z,1683658841.484 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-05-09T19:00:41.486Z,1683658841.486 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-05-09T19:00:41.709Z,1683658841.709 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-05-09T19:00:41.711Z,1683658841.711 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2023-05-09T19:00:41.714Z,1683658841.714 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2023-05-09T19:00:41.906Z,1683658841.906 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2023-05-09T19:00:41.990Z,1683658841.990 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2023-05-09T19:00:42.127Z,1683658842.127 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2023-05-09T19:00:42.213Z,1683658842.213 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2023-05-09T19:00:42.481Z,1683658842.481 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-05-09T19:00:42.481Z,1683658842.481 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2023-05-09T19:00:42.587Z,1683658842.587 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2023-05-09T19:00:42.702Z,1683658842.702 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2023-05-09T19:00:42.819Z,1683658842.819 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2023-05-09T19:00:42.973Z,1683658842.973 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2023-05-09T19:00:43.216Z,1683658843.216 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2023-05-09T19:00:43.485Z,1683658843.485 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2023-05-09T19:00:43.490Z,1683658843.490 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-05-09T19:00:43.507Z,1683658843.507 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-05-09T19:00:43.507Z,1683658843.507 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-05-09T19:00:43.637Z,1683658843.637 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-05-09T19:00:43.637Z,1683658843.637 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-05-09T19:00:43.708Z,1683658843.708 [VerticalControl](DEBUG): Construct VerticalControl.
2023-05-09T19:00:43.769Z,1683658843.769 [VerticalControl] Loaded
2023-05-09T19:00:43.770Z,1683658843.770 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-05-09T19:00:43.772Z,1683658843.772 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-05-09T19:00:43.814Z,1683658843.814 [HorizontalControl] Loaded
2023-05-09T19:00:43.814Z,1683658843.814 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-05-09T19:00:43.817Z,1683658843.817 [SpeedControl](DEBUG): Construct SpeedControl.
2023-05-09T19:00:43.820Z,1683658843.820 [SpeedControl] Loaded
2023-05-09T19:00:43.821Z,1683658843.821 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-05-09T19:00:43.823Z,1683658843.823 [LoopControl](DEBUG): Construct LoopControl.
2023-05-09T19:00:43.824Z,1683658843.824 [LoopControl] Loaded
2023-05-09T19:00:43.824Z,1683658843.824 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-05-09T19:00:43.824Z,1683658843.824 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-05-09T19:00:43.826Z,1683658843.826 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-05-09T19:00:44.017Z,1683658844.017 [BuoyancyServo] Loaded
2023-05-09T19:00:44.017Z,1683658844.017 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-05-09T19:00:44.037Z,1683658844.037 [ElevatorServo] Loaded
2023-05-09T19:00:44.037Z,1683658844.037 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-05-09T19:00:44.057Z,1683658844.057 [MassServo] Loaded
2023-05-09T19:00:44.057Z,1683658844.057 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-05-09T19:00:44.075Z,1683658844.075 [RudderServo] Loaded
2023-05-09T19:00:44.076Z,1683658844.076 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-05-09T19:00:44.090Z,1683658844.090 [ThrusterHE] Loaded
2023-05-09T19:00:44.091Z,1683658844.091 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-05-09T19:00:44.091Z,1683658844.091 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-05-09T19:00:44.092Z,1683658844.092 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-05-09T19:00:44.163Z,1683658844.163 [DepthRateCalculator] Loaded
2023-05-09T19:00:44.163Z,1683658844.163 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-05-09T19:00:44.168Z,1683658844.168 [PitchRateCalculator] Loaded
2023-05-09T19:00:44.168Z,1683658844.168 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-05-09T19:00:44.178Z,1683658844.178 [SpeedCalculator] Loaded
2023-05-09T19:00:44.178Z,1683658844.178 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-05-09T19:00:44.183Z,1683658844.183 [YawRateCalculator] Loaded
2023-05-09T19:00:44.183Z,1683658844.183 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-05-09T19:00:44.201Z,1683658844.201 [ElevatorOffsetCalculator] Loaded
2023-05-09T19:00:44.202Z,1683658844.202 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-05-09T19:00:44.202Z,1683658844.202 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-05-09T19:00:44.204Z,1683658844.204 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-05-09T19:00:44.344Z,1683658844.344 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-05-09T19:00:44.344Z,1683658844.344 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-05-09T19:00:44.358Z,1683658844.358 [NavChart] Loaded
2023-05-09T19:00:44.358Z,1683658844.358 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-05-09T19:00:44.364Z,1683658844.364 [UniversalFixResidualReporter] Loaded
2023-05-09T19:00:44.364Z,1683658844.364 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-05-09T19:00:44.365Z,1683658844.365 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-05-09T19:00:44.365Z,1683658844.365 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-05-09T19:00:45.118Z,1683658845.118 [AHRS_M2] Loaded
2023-05-09T19:00:45.118Z,1683658845.118 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-05-09T19:00:45.369Z,1683658845.369 [BackseatComponent] Loaded
2023-05-09T19:00:45.369Z,1683658845.369 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-05-09T19:00:45.370Z,1683658845.370 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408E74E0
2023-05-09T19:00:45.370Z,1683658845.370 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 921
2023-05-09T19:00:45.373Z,1683658845.373 [LcmUniversalReporter] Loaded
2023-05-09T19:00:45.373Z,1683658845.373 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-05-09T19:00:46.216Z,1683658846.216 [BPC1] Loaded
2023-05-09T19:00:46.216Z,1683658846.216 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-05-09T19:00:46.357Z,1683658846.357 [DataOverHttps] Loaded
2023-05-09T19:00:46.358Z,1683658846.358 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-05-09T19:00:46.359Z,1683658846.359 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409174E0
2023-05-09T19:00:46.359Z,1683658846.359 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 922
2023-05-09T19:00:46.380Z,1683658846.380 [Depth_Keller] Loaded
2023-05-09T19:00:46.380Z,1683658846.380 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-05-09T19:00:46.385Z,1683658846.385 [DropWeight] Loaded
2023-05-09T19:00:46.385Z,1683658846.385 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-05-09T19:00:46.448Z,1683658846.448 [NAL9602] Loaded
2023-05-09T19:00:46.448Z,1683658846.448 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-05-09T19:00:46.479Z,1683658846.479 [Onboard] Loaded
2023-05-09T19:00:46.479Z,1683658846.479 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-05-09T19:00:46.480Z,1683658846.480 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409474E0
2023-05-09T19:00:46.480Z,1683658846.480 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 923
2023-05-09T19:00:46.492Z,1683658846.492 [Power24vConverter] Loaded
2023-05-09T19:00:46.493Z,1683658846.493 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-05-09T19:00:46.506Z,1683658846.506 [Radio_Surface] Loaded
2023-05-09T19:00:46.506Z,1683658846.506 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-05-09T19:00:46.507Z,1683658846.507 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409774E0
2023-05-09T19:00:46.508Z,1683658846.508 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 924
2023-05-09T19:00:46.598Z,1683658846.598 [DATMMP] Loaded
2023-05-09T19:00:46.598Z,1683658846.598 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2023-05-09T19:00:46.599Z,1683658846.599 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 409A84E0
2023-05-09T19:00:46.599Z,1683658846.599 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 925
2023-05-09T19:00:46.600Z,1683658846.600 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-05-09T19:00:47.103Z,1683658847.103 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-05-09T19:00:47.230Z,1683658847.230 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-05-09T19:00:47.231Z,1683658847.231 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-05-09T19:00:47.805Z,1683658847.805 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-05-09T19:00:47.806Z,1683658847.806 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-05-09T19:00:47.850Z,1683658847.850 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-05-09T19:00:47.851Z,1683658847.851 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-05-09T19:00:48.071Z,1683658848.071 [CTD_Seabird] Loaded
2023-05-09T19:00:48.071Z,1683658848.071 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-05-09T19:00:48.072Z,1683658848.072 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ADE4E0
2023-05-09T19:00:48.072Z,1683658848.072 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 926
2023-05-09T19:00:48.093Z,1683658848.093 [PAR_Licor] Loaded
2023-05-09T19:00:48.093Z,1683658848.093 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-05-09T19:00:48.125Z,1683658848.125 [WetLabsBB2FL] Loaded
2023-05-09T19:00:48.125Z,1683658848.125 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-05-09T19:00:48.126Z,1683658848.126 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B0E4E0
2023-05-09T19:00:48.127Z,1683658848.127 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 927
2023-05-09T19:00:48.127Z,1683658848.127 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-05-09T19:00:48.128Z,1683658848.128 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-05-09T19:00:48.500Z,1683658848.500 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-05-09T19:00:48.501Z,1683658848.501 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-05-09T19:00:48.674Z,1683658848.674 [SBIT](DEBUG): Construct Startup Built In Test.
2023-05-09T19:00:48.683Z,1683658848.683 [SBIT] Loaded
2023-05-09T19:00:48.683Z,1683658848.683 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-05-09T19:00:48.686Z,1683658848.686 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-05-09T19:00:48.700Z,1683658848.700 [IBIT] Loaded
2023-05-09T19:00:48.700Z,1683658848.700 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-05-09T19:00:48.706Z,1683658848.706 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-05-09T19:00:48.813Z,1683658848.813 [CBIT] Loaded
2023-05-09T19:00:48.813Z,1683658848.813 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-05-09T19:00:48.813Z,1683658848.813 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-05-09T19:00:48.820Z,1683658848.820 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-05-09T19:00:48.823Z,1683658848.823 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-05-09T19:00:48.834Z,1683658848.834 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-05-09T19:00:48.835Z,1683658848.835 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BF14E0
2023-05-09T19:00:48.835Z,1683658848.835 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 928
2023-05-09T19:00:48.840Z,1683658848.840 [Supervisor](INFO): Main Thread ID is 829
2023-05-09T19:00:48.840Z,1683658848.840 [Supervisor](DEBUG): Running supervisor.
2023-05-09T19:00:48.840Z,1683658848.840 [CommandExec ThreadHandler](INFO): Handler Thread ID is 929
2023-05-09T19:00:48.841Z,1683658848.841 [CommandExec](INFO): Initializing the command executive.
2023-05-09T19:00:48.842Z,1683658848.842 [CommandLine ThreadHandler](INFO): Handler Thread ID is 930
2023-05-09T19:00:48.844Z,1683658848.844 [controlThread ThreadHandler](INFO): Handler Thread ID is 931
2023-05-09T19:00:48.845Z,1683658848.845 [controlThread](DEBUG): Initializing ControlThread
2023-05-09T19:00:48.846Z,1683658848.846 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-05-09T19:00:48.847Z,1683658848.847 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-05-09T19:00:48.848Z,1683658848.848 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-05-09T19:00:48.849Z,1683658848.849 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-05-09T19:00:48.850Z,1683658848.850 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-05-09T19:00:48.850Z,1683658848.850 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-05-09T19:00:48.850Z,1683658848.850 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-05-09T19:00:48.851Z,1683658848.851 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-05-09T19:00:48.851Z,1683658848.851 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-05-09T19:00:48.852Z,1683658848.852 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-05-09T19:00:48.852Z,1683658848.852 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-05-09T19:00:48.861Z,1683658848.861 [SBIT](INFO): Initialize SBIT Component.
2023-05-09T19:00:48.861Z,1683658848.861 [SBIT](IMPORTANT): git: 2023-01-24-38-g3a865bb8c
2023-05-09T19:00:48.862Z,1683658848.862 [SBIT](INFO): git hash: 3a865bb8cac9b788bcd3aa40e502ce5ce0bbf34f
2023-05-09T19:00:48.862Z,1683658848.862 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-05-09T19:00:48.863Z,1683658848.863 [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-09T19:00:48.864Z,1683658848.864 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2023-05-09T19:00:48.865Z,1683658848.865 [IBIT](INFO): Initialize IBIT Component.
2023-05-09T19:00:48.866Z,1683658848.866 [CBIT](DEBUG): Initialize CBIT Component.
2023-05-09T19:00:48.867Z,1683658848.867 [logger ThreadHandler](INFO): Handler Thread ID is 932
2023-05-09T19:00:48.877Z,1683658848.877 [CBIT](DEBUG): Initialized mux pins.
2023-05-09T19:00:48.877Z,1683658848.877 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2023-05-09T19:00:48.877Z,1683658848.877 [CBIT](DEBUG): Initializing the watchdog timer.
2023-05-09T19:00:48.885Z,1683658848.885 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 933
2023-05-09T19:00:48.897Z,1683658848.897 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 934
2023-05-09T19:00:48.898Z,1683658848.898 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-05-09T19:00:48.901Z,1683658848.901 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2023-05-09T19:00:48.906Z,1683658848.906 [CBIT](FAULT): Found backtrace file:
1683658272.000557 [DATMMP]: SIGSEGV: address not mapped to object
1683658272.000557 [DATMMP]: Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x1b019c]
Modules/Sensor.so(_ZN6DATMMP7setTimeEv+0x1e8) [0x40694fa8]
Modules/Sensor.so(_ZN6DATMMP8startingEv+0x108) [0x4069a078]
./bin/LRAUV(_ZN9Component15executeRunStateEv+0x6c) [0xebc7c]
./bin/LRAUV(_ZN7PCaller10PThreadRunEPv+0xc8) [0x1afbe8]
1683658275.801129 [DATMMP]: SIGSEGV: address not mapped to object
1683658275.801129 [DATMMP]: Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x1b019c]
Modules/Sensor.so(_ZN6DATMMP7setTimeEv+0x1e8) [0x40694fa8]
Modules/Sensor.so(_ZN6DATMMP8startingEv+0x108) [0x4069a078]
./bin/LRAUV(_ZN9Component15executeRunStateEv+0x6c) [0xebc7c]
./bin/LRAUV(_ZN7PCaller10PThreadRunEPv+0xc8) [0x1afbe8]
1683658279.542424 [DATMMP]: SIGSEGV: address not mapped to object
1683658279.542424 [DATMMP]: Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x1b019c]
Modules/Sensor.so(_ZN6DATMMP8startingEv+0x108) [0x4069a078]
2023-05-09T19:00:48.908Z,1683658848.908 [CBIT](FAULT): Renaming backtrace file to Data/backtrace3
2023-05-09T19:00:48.908Z,1683658848.908 [CBIT](DEBUG): Initializing heartbeat.
2023-05-09T19:00:48.909Z,1683658848.909 [Onboard ThreadHandler](INFO): Handler Thread ID is 935
2023-05-09T19:00:48.927Z,1683658848.927 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 936
2023-05-09T19:00:48.949Z,1683658848.949 [DATMMP ThreadHandler](INFO): Handler Thread ID is 937
2023-05-09T19:00:48.950Z,1683658848.950 [DATMMP](INFO): Start
2023-05-09T19:00:48.950Z,1683658848.950 [DATMMP](INFO): Powering up
2023-05-09T19:00:48.950Z,1683658848.950 [DATMMP](DEBUG): Initializing DATMMP.
2023-05-09T19:00:48.954Z,1683658848.954 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 939
2023-05-09T19:00:48.955Z,1683658848.955 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-05-09T19:00:48.958Z,1683658848.958 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 941
2023-05-09T19:00:48.961Z,1683658848.961 [WetLabsBB2FL](INFO): Powering up
2023-05-09T19:00:48.962Z,1683658848.962 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 943
2023-05-09T19:00:48.970Z,1683658848.970 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-05-09T19:00:48.970Z,1683658848.970 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-05-09T19:00:48.970Z,1683658848.970 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-05-09T19:00:48.971Z,1683658848.971 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-05-09T19:00:48.971Z,1683658848.971 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-05-09T19:00:48.971Z,1683658848.971 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-05-09T19:00:48.971Z,1683658848.971 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-05-09T19:00:48.971Z,1683658848.971 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-05-09T19:00:48.977Z,1683658848.977 [CBIT](DEBUG): Deactivating GF circuits.
2023-05-09T19:00:48.977Z,1683658848.977 [CBIT](DEBUG): Deactivating emergency mode.
2023-05-09T19:00:49.013Z,1683658849.013 [CBIT](DEBUG): Backplane powered.
2023-05-09T19:00:49.014Z,1683658849.014 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-05-09T19:00:49.040Z,1683658849.040 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-09T19:00:49.072Z,1683658849.072 [MissionManager](DEBUG):
2023-05-09T19:00:49.089Z,1683658849.089 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-05-09T19:00:49.161Z,1683658849.161 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-05-09T19:00:49.162Z,1683658849.162 [Default:A.Wait](DEBUG): Construct Wait.
2023-05-09T19:00:49.164Z,1683658849.164 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-09T19:00:49.215Z,1683658849.215 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-05-09T19:00:49.218Z,1683658849.218 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-05-09T19:00:49.239Z,1683658849.239 [Default:E.Execute](DEBUG): Construct Execute.
2023-05-09T19:00:49.251Z,1683658849.251 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2023-05-09T19:00:49.255Z,1683658849.255 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,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-09T19:00:49.277Z,1683658849.277 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-05-09T19:00:49.333Z,1683658849.333 [Radio_Surface](INFO): Powering up
2023-05-09T19:00:49.420Z,1683658849.420 [Power24vConverter](INFO): Powering up.
2023-05-09T19:00:49.443Z,1683658849.443 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-05-09T19:00:49.449Z,1683658849.449 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-05-09T19:00:49.450Z,1683658849.450 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-05-09T19:00:49.457Z,1683658849.457 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-05-09T19:00:49.458Z,1683658849.458 [MassServo](DEBUG): Initializing EZServoServo.
2023-05-09T19:00:49.465Z,1683658849.465 [MassServo](DEBUG): Initializing MassServo.
2023-05-09T19:00:49.466Z,1683658849.466 [RudderServo](DEBUG): Initializing EZServoServo.
2023-05-09T19:00:49.473Z,1683658849.473 [RudderServo](DEBUG): Initializing RudderServo.
2023-05-09T19:00:49.474Z,1683658849.474 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-05-09T19:00:49.481Z,1683658849.481 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-05-09T19:00:49.483Z,1683658849.483 [CommandExec](FAULT): Scheduling is paused
2023-05-09T19:00:49.483Z,1683658849.483 [CBIT](INFO): Critical error at 20230509T190048
2023-05-09T19:00:49.483Z,1683658849.483 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-05-09T19:00:49.706Z,1683658849.706 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2023-05-09T19:00:49.706Z,1683658849.706 [DropWeight] Hardware Fault, FailCount= 1
2023-05-09T19:00:49.706Z,1683658849.706 [DropWeight](ERROR): Hardware Fault
2023-05-09T19:00:49.783Z,1683658849.783 [CBIT](INFO): Critical error at 20230509T190049
2023-05-09T19:00:49.794Z,1683658849.794 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-05-09T19:00:49.794Z,1683658849.794 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-05-09T19:00:50.409Z,1683658850.409 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2023-05-09T19:00:50.409Z,1683658850.409 [RudderServo](FAULT): Rudder failed to initialize
2023-05-09T19:00:50.409Z,1683658850.409 [RudderServo] Communications Fault, FailCount= 1
2023-05-09T19:00:50.409Z,1683658850.409 [RudderServo](ERROR): Communications Fault
2023-05-09T19:00:50.411Z,1683658850.411 [CBIT](INFO): Critical error at 20230509T190049
2023-05-09T19:00:50.413Z,1683658850.413 [CBIT](ERROR): Communications Fault in component: RudderServo
2023-05-09T19:00:50.634Z,1683658850.634 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-05-09T19:00:50.634Z,1683658850.634 [RudderServo](INFO): Powering down
2023-05-09T19:00:51.069Z,1683658851.069 [WetLabsBB2FL](INFO): Powering down
2023-05-09T19:00:51.301Z,1683658851.301 [RudderServo](DEBUG): Initializing EZServoServo.
2023-05-09T19:00:51.422Z,1683658851.422 [RudderServo](DEBUG): Initializing RudderServo.
2023-05-09T19:00:51.426Z,1683658851.426 [CBIT](INFO): Clearing failed state for component RudderServo
2023-05-09T19:00:51.426Z,1683658851.426 [RudderServo] No Fault, FailCount= 1
2023-05-09T19:00:55.281Z,1683658855.281 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-05-09T19:00:55.985Z,1683658855.985 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-05-09T19:01:01.297Z,1683658861.297 [DATMMP](INFO): DAT read:
2023-05-09T19:01:01.298Z,1683658861.298 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series
2023-05-09T19:01:02.811Z,1683658862.811 [DATMMP](INFO): DAT read: MF Frequency Band
2023-05-09T19:01:02.813Z,1683658862.813 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2023-05-09T19:01:02.814Z,1683658862.814 [DATMMP](INFO): DAT read: Apr 9 2000 19:00:51
2023-05-09T19:01:04.070Z,1683658864.070 [DATMMP](INFO): DAT read: Features enabled [Bearing]
2023-05-09T19:01:04.071Z,1683658864.071 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2023-05-09T19:01:04.071Z,1683658864.071 [DATMMP](INFO): commRate: 800
2023-05-09T19:01:06.137Z,1683658866.137 [DATMMP](INFO): entering command mode
2023-05-09T19:01:06.337Z,1683658866.337 [DATMMP](INFO): DAT read:
2023-05-09T19:01:06.337Z,1683658866.337 [DATMMP](INFO): DAT read: user:1>
2023-05-09T19:01:06.338Z,1683658866.338 [DATMMP](DEBUG): read user prompt 1: user:1>
2023-05-09T19:01:06.338Z,1683658866.338 [DATMMP](INFO): entering MMP mode
2023-05-09T19:01:06.589Z,1683658866.589 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-09T19:01:06.591Z,1683658866.591 [DATMMP](INFO): status rx: x1
2023-05-09T19:01:06.591Z,1683658866.591 [DATMMP](INFO): MMP status: 1: started
2023-05-09T19:01:06.591Z,1683658866.591 [DATMMP](INFO): Received message type: status
2023-05-09T19:01:06.592Z,1683658866.592 [DATMMP](INFO): Handled
2023-05-09T19:01:06.842Z,1683658866.842 [DATMMP](INFO): Sent Tx Power Config 8
2023-05-09T19:01:06.843Z,1683658866.843 [DATMMP](INFO): Setting local address: 5
2023-05-09T19:01:06.844Z,1683658866.844 [DATMMP](INFO): Setting time to: 19:1:6 And date to:5/9/2023
2023-05-09T19:01:07.093Z,1683658867.093 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-09T19:01:07.094Z,1683658867.094 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0
2023-05-09T19:01:07.094Z,1683658867.094 [DATMMP](INFO): Received message type: mdmpower
2023-05-09T19:01:07.095Z,1683658867.095 [DATMMP](INFO): Handled
2023-05-09T19:01:07.345Z,1683658867.345 [DATMMP](INFO): GSXN notify for xid: 10
2023-05-09T19:01:07.346Z,1683658867.346 [DATMMP](INFO): sreg_txpower rx: x8
2023-05-09T19:01:07.346Z,1683658867.346 [DATMMP](INFO): Received message type: sreg_txpower
2023-05-09T19:01:07.346Z,1683658867.346 [DATMMP](INFO): Match for : sreg_txpower
2023-05-09T19:01:07.347Z,1683658867.347 [DATMMP](INFO): ACK
2023-05-09T19:01:07.597Z,1683658867.597 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-09T19:01:07.598Z,1683658867.598 [DATMMP](INFO): mdmlocal rx: x5 x0 x0 x0
2023-05-09T19:01:07.598Z,1683658867.598 [DATMMP](INFO): Received message type: mdmlocal
2023-05-09T19:01:07.598Z,1683658867.598 [DATMMP](INFO): Handled
2023-05-09T19:01:07.849Z,1683658867.849 [DATMMP](INFO): GSXN notify for xid: 11
2023-05-09T19:01:07.850Z,1683658867.850 [DATMMP](INFO): sreg_locaddr rx: x5
2023-05-09T19:01:07.850Z,1683658867.850 [DATMMP](INFO): Received message type: sreg_locaddr
2023-05-09T19:01:07.850Z,1683658867.850 [DATMMP](INFO): Match for : sreg_locaddr
2023-05-09T19:01:07.850Z,1683658867.850 [DATMMP](INFO): ACK
2023-05-09T19:01:08.101Z,1683658868.101 [DATMMP](INFO): GSXN notify for xid: 12
2023-05-09T19:01:08.102Z,1683658868.102 [DATMMP](INFO): timedate rx: x0 x13 x1 x6 x4 x9 x7B x0
2023-05-09T19:01:08.102Z,1683658868.102 [DATMMP](INFO): Received message type: timedate
2023-05-09T19:01:08.103Z,1683658868.103 [DATMMP](INFO): Match for : timedate
2023-05-09T19:01:08.103Z,1683658868.103 [DATMMP](INFO): ACK
2023-05-09T19:01:08.353Z,1683658868.353 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-09T19:01:08.354Z,1683658868.354 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0
2023-05-09T19:01:08.354Z,1683658868.354 [DATMMP](INFO): Received message type: sys_verb
2023-05-09T19:01:08.354Z,1683658868.354 [DATMMP](INFO): Handled
2023-05-09T19:01:08.605Z,1683658868.605 [DATMMP](INFO): GSXN notify for xid: 13
2023-05-09T19:01:08.606Z,1683658868.606 [DATMMP](INFO): sreg_verbosity rx: x0
2023-05-09T19:01:08.606Z,1683658868.606 [DATMMP](INFO): Received message type: sreg_verbosity
2023-05-09T19:01:08.606Z,1683658868.606 [DATMMP](INFO): Match for : sreg_verbosity
2023-05-09T19:01:08.606Z,1683658868.606 [DATMMP](INFO): ACK
2023-05-09T19:01:08.857Z,1683658868.857 [DATMMP](INFO): GSXN notify for xid: 14
2023-05-09T19:01:08.858Z,1683658868.858 [DATMMP](INFO): version rx: x2 x5
2023-05-09T19:01:08.858Z,1683658868.858 [DATMMP](INFO): DatMMPVersion: 2.5
2023-05-09T19:01:08.859Z,1683658868.859 [DATMMP](INFO): Received message type: version
2023-05-09T19:01:08.859Z,1683658868.859 [DATMMP](INFO): Match for : version
2023-05-09T19:01:08.859Z,1683658868.859 [DATMMP](INFO): ACK
2023-05-09T19:01:08.859Z,1683658868.859 [DATMMP](INFO): in MMP mode: config complete going to runnable
2023-05-09T19:01:09.566Z,1683658869.566 [SBIT](IMPORTANT): Beginning Startup BIT
2023-05-09T19:01:09.570Z,1683658869.570 [CBIT](IMPORTANT): Beginning ground fault scan
2023-05-09T19:01:16.770Z,1683658876.770 [NAL9602](INFO): Powering up NAL9602
2023-05-09T19:01:20.577Z,1683658880.577 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.008510
CHAN A1 (24V): -0.007518
CHAN A2 (12V): -0.005176
CHAN A3 (5V): -0.003142
CHAN B0 (3.3V): -0.001464
CHAN B1 (3.15aV): -0.001199
CHAN B2 (3.15bV): -0.001120
CHAN B3 (GND): -0.000380
OPEN: 0.004549
Full Scale: +/- 1 mA
2023-05-09T19:01:23.477Z,1683658883.477 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-09T19:01:23.478Z,1683658883.478 [DATMMP](INFO): timesync rx: x0 x1 x1 x0
2023-05-09T19:01:23.478Z,1683658883.478 [DATMMP](INFO): Received message type: timesync
2023-05-09T19:01:23.478Z,1683658883.478 [DATMMP](INFO): Handled
2023-05-09T19:01:27.681Z,1683658887.681 [NAL9602](INFO): NAL9602 initialized
2023-05-09T19:01:33.305Z,1683658893.305 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-09T19:01:33.306Z,1683658893.306 [DATMMP](INFO): timesync rx: x1 x0 x1 x0
2023-05-09T19:01:33.306Z,1683658893.306 [DATMMP](INFO): Received message type: timesync
2023-05-09T19:01:33.306Z,1683658893.306 [DATMMP](INFO): Handled
2023-05-09T19:01:56.772Z,1683658916.772 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:02:03.651Z,1683658923.651 [SBIT](IMPORTANT): SBIT PASSED
2023-05-09T19:02:03.651Z,1683658923.651 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2023-05-09T19:02:04.054Z,1683658924.054 [MissionManager](IMPORTANT): Started mission Startup
2023-05-09T19:02:04.054Z,1683658924.054 [Startup] Running Loop=1
2023-05-09T19:02:04.054Z,1683658924.054 [Startup](DEBUG): Aggregate::initialize Startup
2023-05-09T19:02:04.055Z,1683658924.055 [Startup:A.GoToSurface] Running Loop=1
2023-05-09T19:02:04.055Z,1683658924.055 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-09T19:02:04.055Z,1683658924.055 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-09T19:02:04.056Z,1683658924.056 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-09T19:02:04.056Z,1683658924.056 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-09T19:02:04.056Z,1683658924.056 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-09T19:02:04.057Z,1683658924.057 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-09T19:02:04.058Z,1683658924.058 [Startup:StartupSatComms] Running Loop=1
2023-05-09T19:02:04.058Z,1683658924.058 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-05-09T19:02:04.058Z,1683658924.058 [Startup:StartupSatComms:A] Running Loop=1
2023-05-09T19:02:04.459Z,1683658924.459 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-05-09T19:02:10.159Z,1683658930.159 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:02:23.498Z,1683658943.498 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:02:37.170Z,1683658957.170 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:02:50.518Z,1683658970.518 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:03:03.844Z,1683658983.844 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:03:04.291Z,1683658984.291 [Startup:StartupSatComms:A](INFO): Timed out from 2023-05-09T19:02:04.1Z
2023-05-09T19:03:04.291Z,1683658984.291 [Startup:StartupSatComms:A] Stopped
2023-05-09T19:03:04.291Z,1683658984.291 [Startup:StartupSatComms:B] Running Loop=1
2023-05-09T19:03:04.692Z,1683658984.692 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-09T19:03:17.168Z,1683658997.168 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:03:29.735Z,1683659009.735 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004569
2023-05-09T19:03:30.498Z,1683659010.498 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:03:36.973Z,1683659016.973 [DataOverHttps](INFO): Sending 286 bytes from file Logs/20230509T171422/Courier0031.lzma
2023-05-09T19:03:37.975Z,1683659017.975 [DataOverHttps](INFO): Moved sent file to Logs/20230509T171422/Courier0031.lzma.bak
2023-05-09T19:03:37.975Z,1683659017.975 [DataOverHttps](INFO): SBD MOMSN=18283698
2023-05-09T19:03:38.291Z,1683659018.291 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:03:49.288Z,1683659029.288 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-05-09T19:03:49.288Z,1683659029.288 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-09T19:03:49.308Z,1683659029.308 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-09T19:03:49.674Z,1683659029.674 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-09T19:03:49.675Z,1683659029.675 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-05-09T19:03:51.712Z,1683659031.712 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:03:53.920Z,1683659033.920 [DataOverHttps](INFO): Sending 282 bytes from file Logs/20230509T190036/Courier0000.lzma
2023-05-09T19:03:54.931Z,1683659034.931 [DataOverHttps](INFO): Moved sent file to Logs/20230509T190036/Courier0000.lzma.bak
2023-05-09T19:03:54.931Z,1683659034.931 [DataOverHttps](INFO): SBD MOMSN=18283773
2023-05-09T19:04:04.635Z,1683659044.635 [Startup:StartupSatComms:B](INFO): Timed out from 2023-05-09T19:03:04.3Z
2023-05-09T19:04:04.635Z,1683659044.635 [Startup:StartupSatComms:B] Stopped
2023-05-09T19:04:04.636Z,1683659044.636 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-05-09T19:04:04.636Z,1683659044.636 [Startup:StartupSatComms] Stopped
2023-05-09T19:04:04.636Z,1683659044.636 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-05-09T19:04:04.637Z,1683659044.637 [Startup](INFO): Completed Startup
2023-05-09T19:04:04.637Z,1683659044.637 [MissionManager](INFO): Startup is completed.
2023-05-09T19:04:04.637Z,1683659044.637 [MissionManager](INFO): Uninitializing Mission Startup
2023-05-09T19:04:04.637Z,1683659044.637 [Startup] Stopped
2023-05-09T19:04:04.637Z,1683659044.637 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-05-09T19:04:04.637Z,1683659044.637 [Startup:A.GoToSurface] Stopped
2023-05-09T19:04:04.637Z,1683659044.637 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-09T19:04:04.948Z,1683659044.948 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:04:04.991Z,1683659044.991 [MissionManager](IMPORTANT): Started mission Default
2023-05-09T19:04:04.991Z,1683659044.991 [Default] Running Loop=1
2023-05-09T19:04:04.991Z,1683659044.991 [Default](DEBUG): Aggregate::initialize Default
2023-05-09T19:04:04.991Z,1683659044.991 [Default:B.GoToSurface] Running Loop=1
2023-05-09T19:04:04.991Z,1683659044.991 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-09T19:04:04.992Z,1683659044.992 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-09T19:04:04.992Z,1683659044.992 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-09T19:04:04.992Z,1683659044.992 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-09T19:04:04.992Z,1683659044.992 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-09T19:04:04.993Z,1683659044.993 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-09T19:04:04.993Z,1683659044.993 [Default:A.Wait] Running Loop=1
2023-05-09T19:04:04.993Z,1683659044.993 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-05-09T19:04:11.864Z,1683659051.864 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230509T171422/Express0032.lzma
2023-05-09T19:04:12.867Z,1683659052.867 [DataOverHttps](INFO): Moved sent file to Logs/20230509T171422/Express0032.lzma.bak
2023-05-09T19:04:12.867Z,1683659052.867 [DataOverHttps](INFO): SBD MOMSN=18283779
2023-05-09T19:04:18.320Z,1683659058.320 [Default:A.Wait](INFO): Done Waiting.
2023-05-09T19:04:18.320Z,1683659058.320 [Default:A.Wait] Stopped
2023-05-09T19:04:18.320Z,1683659058.320 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-09T19:04:18.778Z,1683659058.778 [Default:CheckIn] Running Loop=1
2023-05-09T19:04:18.778Z,1683659058.778 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-09T19:04:18.778Z,1683659058.778 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-09T19:04:19.143Z,1683659059.143 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-05-09T19:06:30.391Z,1683659190.391 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-09T19:06:50.218Z,1683659210.218 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-05-09T19:06:50.218Z,1683659210.218 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-09T19:06:50.229Z,1683659210.229 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-09T19:06:50.622Z,1683659210.622 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-09T19:06:50.622Z,1683659210.622 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-05-09T19:09:18.940Z,1683659358.940 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-09T19:04:18.8Z
2023-05-09T19:09:18.940Z,1683659358.940 [Default:CheckIn:Read_GPS] Stopped
2023-05-09T19:09:18.940Z,1683659358.940 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-09T19:09:19.306Z,1683659359.306 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-09T19:09:26.144Z,1683659366.144 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230509T190036/Courier0004.lzma
2023-05-09T19:09:27.147Z,1683659367.147 [DataOverHttps](INFO): Moved sent file to Logs/20230509T190036/Courier0004.lzma.bak
2023-05-09T19:09:27.147Z,1683659367.147 [DataOverHttps](INFO): SBD MOMSN=18283787
2023-05-09T19:09:44.487Z,1683659384.487 [DataOverHttps](INFO): Sending 997 bytes from file Logs/20230509T190036/Express0001.lzma
2023-05-09T19:09:45.487Z,1683659385.487 [DataOverHttps](INFO): Moved sent file to Logs/20230509T190036/Express0001.lzma.bak
2023-05-09T19:09:45.487Z,1683659385.487 [DataOverHttps](INFO): SBD MOMSN=18283789
2023-05-09T19:09:51.209Z,1683659391.209 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-05-09T19:09:51.209Z,1683659391.209 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-09T19:09:51.228Z,1683659391.228 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-09T19:09:51.635Z,1683659391.635 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-09T19:09:51.635Z,1683659391.635 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-05-09T19:10:02.396Z,1683659402.396 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20230509T190036/Express0005.lzma
2023-05-09T19:10:03.399Z,1683659403.399 [DataOverHttps](INFO): Moved sent file to Logs/20230509T190036/Express0005.lzma.bak
2023-05-09T19:10:03.399Z,1683659403.399 [DataOverHttps](INFO): SBD MOMSN=18283805
2023-05-09T19:10:06.158Z,1683659406.158 [Default:CheckIn:Read_Iridium] Stopped
2023-05-09T19:10:06.158Z,1683659406.158 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-09T19:10:06.158Z,1683659406.158 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-09T19:11:28.581Z,1683659488.581 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-09T19:11:28.581Z,1683659488.581 [NAL9602] Data Fault, FailCount= 1
2023-05-09T19:11:28.581Z,1683659488.581 [NAL9602](ERROR): Data Fault
2023-05-09T19:11:28.659Z,1683659488.659 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-09T19:11:29.006Z,1683659489.006 [NAL9602](INFO): Powering down
2023-05-09T19:11:29.879Z,1683659489.879 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-09T19:11:29.879Z,1683659489.879 [NAL9602] No Fault, FailCount= 1
2023-05-09T19:11:33.449Z,1683659493.449 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:11:46.779Z,1683659506.779 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:11:59.310Z,1683659519.310 [NAL9602](INFO): Powering up NAL9602
2023-05-09T19:12:00.112Z,1683659520.112 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:12:10.218Z,1683659530.218 [NAL9602](INFO): NAL9602 initialized
2023-05-09T19:12:13.848Z,1683659533.848 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:12:27.179Z,1683659547.179 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:12:40.511Z,1683659560.511 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:12:52.266Z,1683659572.266 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-05-09T19:12:52.266Z,1683659572.266 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-09T19:12:52.277Z,1683659572.277 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-09T19:12:52.684Z,1683659572.684 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-09T19:12:52.684Z,1683659572.684 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-05-09T19:12:53.843Z,1683659573.843 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:13:07.579Z,1683659587.579 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:13:20.912Z,1683659600.912 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:13:34.243Z,1683659614.243 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:13:47.576Z,1683659627.576 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:14:57.774Z,1683659697.774 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-09T19:14:57.774Z,1683659697.774 [DATMMP](INFO): timesync rx: x2 x0 x0 x0
2023-05-09T19:14:57.775Z,1683659697.775 [DATMMP](INFO): Received message type: timesync
2023-05-09T19:14:57.775Z,1683659697.775 [DATMMP](INFO): Handled
2023-05-09T19:15:06.775Z,1683659706.775 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-09T19:15:06.775Z,1683659706.775 [Default:CheckIn:C.Wait] Stopped
2023-05-09T19:15:06.775Z,1683659706.775 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-09T19:15:06.775Z,1683659706.775 [Default:CheckIn:D] Running Loop=1
2023-05-09T19:15:07.188Z,1683659707.188 [Default:CheckIn:D] Stopped
2023-05-09T19:15:07.188Z,1683659707.188 [Default:CheckIn:E] Running Loop=1
2023-05-09T19:15:07.664Z,1683659707.664 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.036622 min
2023-05-09T19:15:07.664Z,1683659707.664 [Default:CheckIn:E] Stopped
2023-05-09T19:15:07.664Z,1683659707.664 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-09T19:15:07.664Z,1683659707.664 [Default:CheckIn] Stopped
2023-05-09T19:15:07.664Z,1683659707.664 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-09T19:15:07.664Z,1683659707.664 [Default:CheckIn](INFO): Running loop #2
2023-05-09T19:15:07.664Z,1683659707.664 [Default:CheckIn] Running Loop=2
2023-05-09T19:15:07.664Z,1683659707.664 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-09T19:15:07.664Z,1683659707.664 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-09T19:15:07.873Z,1683659707.873 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-09T19:15:07.874Z,1683659707.874 [DATMMP](INFO): timesync rx: x2 x1 x1 x0
2023-05-09T19:15:07.875Z,1683659707.875 [DATMMP](INFO): Received message type: timesync
2023-05-09T19:15:07.875Z,1683659707.875 [DATMMP](INFO): Handled
2023-05-09T19:15:17.958Z,1683659717.958 [DATMMP](INFO): GSXN notify for xid: 0
2023-05-09T19:15:17.963Z,1683659717.963 [DATMMP](INFO): timesync rx: x1 x0 x1 x0
2023-05-09T19:15:17.964Z,1683659717.964 [DATMMP](INFO): Received message type: timesync
2023-05-09T19:15:17.964Z,1683659717.964 [DATMMP](INFO): Handled
2023-05-09T19:15:50.023Z,1683659750.023 [CBIT](INFO): Clearing failed state for component DropWeight
2023-05-09T19:15:50.024Z,1683659750.024 [DropWeight] No Fault, FailCount= 1
2023-05-09T19:15:53.262Z,1683659753.262 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-05-09T19:15:53.262Z,1683659753.262 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-09T19:15:53.273Z,1683659753.273 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-09T19:15:53.662Z,1683659753.662 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-09T19:15:53.662Z,1683659753.662 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-05-09T19:18:54.254Z,1683659934.254 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2023-05-09T19:18:54.254Z,1683659934.254 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-09T19:18:54.314Z,1683659934.314 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-09T19:18:54.676Z,1683659934.676 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-09T19:18:54.676Z,1683659934.676 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2023-05-09T19:19:07.567Z,1683659947.567 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:20:07.796Z,1683660007.796 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-09T19:15:07.7Z
2023-05-09T19:20:07.796Z,1683660007.796 [Default:CheckIn:Read_GPS] Stopped
2023-05-09T19:20:07.796Z,1683660007.796 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-09T19:20:14.354Z,1683660014.354 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230509T190036/Courier0007.lzma
2023-05-09T19:20:15.351Z,1683660015.351 [DataOverHttps](INFO): Moved sent file to Logs/20230509T190036/Courier0007.lzma.bak
2023-05-09T19:20:15.351Z,1683660015.351 [DataOverHttps](INFO): SBD MOMSN=18283816
2023-05-09T19:20:32.101Z,1683660032.101 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20230509T190036/Express0008.lzma
2023-05-09T19:20:33.103Z,1683660033.103 [DataOverHttps](INFO): Moved sent file to Logs/20230509T190036/Express0008.lzma.bak
2023-05-09T19:20:33.103Z,1683660033.103 [DataOverHttps](INFO): SBD MOMSN=18283818
2023-05-09T19:20:35.663Z,1683660035.663 [Default:CheckIn:Read_Iridium] Stopped
2023-05-09T19:20:35.663Z,1683660035.663 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-09T19:20:35.663Z,1683660035.663 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-09T19:21:08.361Z,1683660068.361 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:21:21.692Z,1683660081.692 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:21:35.041Z,1683660095.041 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:21:48.371Z,1683660108.371 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:21:55.257Z,1683660115.257 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2023-05-09T19:21:55.257Z,1683660115.257 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-09T19:21:55.268Z,1683660115.268 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-09T19:21:55.673Z,1683660115.673 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-09T19:21:55.673Z,1683660115.673 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2023-05-09T19:22:01.704Z,1683660121.704 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:22:11.406Z,1683660131.406 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-09T19:22:11.407Z,1683660131.407 [NAL9602] Data Fault, FailCount= 2
2023-05-09T19:22:11.407Z,1683660131.407 [NAL9602](ERROR): Data Fault
2023-05-09T19:22:11.423Z,1683660131.423 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-09T19:22:11.806Z,1683660131.806 [NAL9602](INFO): Powering down
2023-05-09T19:22:12.650Z,1683660132.650 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-09T19:22:12.651Z,1683660132.651 [NAL9602] No Fault, FailCount= 2
2023-05-09T19:22:15.439Z,1683660135.439 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:22:28.773Z,1683660148.773 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:22:42.103Z,1683660162.103 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:22:42.110Z,1683660162.110 [NAL9602](INFO): Powering up NAL9602
2023-05-09T19:22:53.075Z,1683660173.075 [NAL9602](INFO): NAL9602 initialized
2023-05-09T19:22:55.436Z,1683660175.436 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:23:08.772Z,1683660188.772 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:23:22.100Z,1683660202.100 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2023-05-09T19:24:56.275Z,1683660296.275 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2023-05-09T19:24:56.275Z,1683660296.275 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-09T19:24:56.310Z,1683660296.310 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-09T19:24:56.707Z,1683660296.707 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-09T19:24:56.707Z,1683660296.707 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2023-05-09T19:25:36.336Z,1683660336.336 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-09T19:25:36.336Z,1683660336.336 [Default:CheckIn:C.Wait] Stopped
2023-05-09T19:25:36.336Z,1683660336.336 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-09T19:25:36.336Z,1683660336.336 [Default:CheckIn:D] Running Loop=1
2023-05-09T19:25:36.647Z,1683660336.647 [Default:CheckIn:D] Stopped
2023-05-09T19:25:36.647Z,1683660336.647 [Default:CheckIn:E] Running Loop=1
2023-05-09T19:25:37.063Z,1683660337.063 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.527594 min
2023-05-09T19:25:37.063Z,1683660337.063 [Default:CheckIn:E] Stopped
2023-05-09T19:25:37.063Z,1683660337.063 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-09T19:25:37.063Z,1683660337.063 [Default:CheckIn] Stopped
2023-05-09T19:25:37.063Z,1683660337.063 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-09T19:25:37.064Z,1683660337.064 [Default:CheckIn](INFO): Running loop #3
2023-05-09T19:25:37.064Z,1683660337.064 [Default:CheckIn] Running Loop=3
2023-05-09T19:25:37.064Z,1683660337.064 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-09T19:25:37.064Z,1683660337.064 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-09T19:27:21.106Z,1683660441.106 [CommandExec](IMPORTANT): got command restart system
2023-05-09T19:27:23.185Z,1683660443.185 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-09T19:27:23.185Z,1683660443.185 [CommandExec](INFO): Uninitializing the command executive.
2023-05-09T19:27:23.186Z,1683660443.186 [CommandExec](INFO): Uninitializing the command scheduler.
2023-05-09T19:27:23.186Z,1683660443.186 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:23.309Z,1683660443.309 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2023-05-09T19:27:23.309Z,1683660443.309 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-05-09T19:27:23.309Z,1683660443.309 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:23.310Z,1683660443.310 [NavChartDb](INFO): Join timeout helper Thread ID is 996
2023-05-09T19:27:23.442Z,1683660443.442 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-09T19:27:23.443Z,1683660443.443 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:23.457Z,1683660443.457 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-05-09T19:27:23.457Z,1683660443.457 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:23.458Z,1683660443.458 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 997
2023-05-09T19:27:23.773Z,1683660443.773 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-09T19:27:23.773Z,1683660443.773 [WetLabsBB2FL](INFO): Powering down
2023-05-09T19:27:23.774Z,1683660443.774 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:23.789Z,1683660443.789 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-05-09T19:27:23.789Z,1683660443.789 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:23.789Z,1683660443.789 [CTD_Seabird](INFO): Join timeout helper Thread ID is 998
2023-05-09T19:27:24.066Z,1683660444.066 [CTD_Seabird](INFO): Powering down
2023-05-09T19:27:24.077Z,1683660444.077 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-09T19:27:24.077Z,1683660444.077 [CTD_Seabird](INFO): Powering down
2023-05-09T19:27:24.089Z,1683660444.089 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:24.109Z,1683660444.109 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler
2023-05-09T19:27:24.109Z,1683660444.109 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:24.109Z,1683660444.109 [DATMMP](INFO): Join timeout helper Thread ID is 999
2023-05-09T19:27:24.137Z,1683660444.137 [DATMMP](INFO): Stop
2023-05-09T19:27:24.137Z,1683660444.137 [DATMMP](INFO): uninitialize
2023-05-09T19:27:24.137Z,1683660444.137 [DATMMP](INFO): Powering down
2023-05-09T19:27:24.210Z,1683660444.210 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-09T19:27:24.210Z,1683660444.210 [DATMMP](INFO): uninitialize
2023-05-09T19:27:24.210Z,1683660444.210 [DATMMP](INFO): Powering down
2023-05-09T19:27:24.211Z,1683660444.211 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:24.230Z,1683660444.230 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-05-09T19:27:24.230Z,1683660444.230 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:24.230Z,1683660444.230 [Radio_Surface](INFO): Join timeout helper Thread ID is 1000
2023-05-09T19:27:24.273Z,1683660444.273 [Radio_Surface](INFO): Powering down
2023-05-09T19:27:24.274Z,1683660444.274 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-09T19:27:24.275Z,1683660444.275 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:24.282Z,1683660444.282 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-05-09T19:27:24.282Z,1683660444.282 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:24.283Z,1683660444.283 [Onboard](INFO): Join timeout helper Thread ID is 1001
2023-05-09T19:27:24.709Z,1683660444.709 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-05-09T19:27:24.737Z,1683660444.737 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-09T19:27:24.738Z,1683660444.738 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:24.758Z,1683660444.758 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-05-09T19:27:24.758Z,1683660444.758 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:24.758Z,1683660444.758 [DataOverHttps](INFO): Join timeout helper Thread ID is 1002
2023-05-09T19:27:25.713Z,1683660445.713 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-09T19:27:25.713Z,1683660445.713 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:25.722Z,1683660445.722 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-05-09T19:27:25.722Z,1683660445.722 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:25.722Z,1683660445.722 [BackseatComponent](INFO): Join timeout helper Thread ID is 1003
2023-05-09T19:27:25.901Z,1683660445.901 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-09T19:27:25.902Z,1683660445.902 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:25.922Z,1683660445.922 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-05-09T19:27:25.922Z,1683660445.922 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:25.922Z,1683660445.922 [logger](INFO): Join timeout helper Thread ID is 1004
2023-05-09T19:27:25.941Z,1683660445.941 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-09T19:27:25.942Z,1683660445.942 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:25.962Z,1683660445.962 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-05-09T19:27:25.962Z,1683660445.962 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:25.962Z,1683660445.962 [CommandLine](INFO): Join timeout helper Thread ID is 1005
2023-05-09T19:27:26.061Z,1683660446.061 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-09T19:27:26.061Z,1683660446.061 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:26.082Z,1683660446.082 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-05-09T19:27:26.082Z,1683660446.082 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:26.082Z,1683660446.082 [CommandExec](INFO): Join timeout helper Thread ID is 1006
2023-05-09T19:27:26.083Z,1683660446.083 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-05-09T19:27:26.083Z,1683660446.083 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:26.084Z,1683660446.084 [controlThread](INFO): Join timeout helper Thread ID is 1007
2023-05-09T19:27:26.113Z,1683660446.113 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-09T19:27:26.113Z,1683660446.113 [controlThread](DEBUG): Uninitializing ControlThread
2023-05-09T19:27:26.114Z,1683660446.114 [AHRS_M2](INFO): Powering down
2023-05-09T19:27:26.186Z,1683660446.186 [NAL9602](INFO): Powering down
2023-05-09T19:27:26.188Z,1683660446.188 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-05-09T19:27:26.189Z,1683660446.189 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-05-09T19:27:26.189Z,1683660446.189 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-05-09T19:27:26.190Z,1683660446.190 [MissionManager](INFO): Uninitializing Mission Default
2023-05-09T19:27:26.190Z,1683660446.190 [Default] Stopped
2023-05-09T19:27:26.190Z,1683660446.190 [Default](DEBUG): Aggregate::uninitialize Default
2023-05-09T19:27:26.190Z,1683660446.190 [Default:B.GoToSurface] Stopped
2023-05-09T19:27:26.190Z,1683660446.190 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-09T19:27:26.190Z,1683660446.190 [Default:CheckIn] Stopped
2023-05-09T19:27:26.191Z,1683660446.191 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-09T19:27:26.191Z,1683660446.191 [Default:CheckIn:Read_GPS] Stopped
2023-05-09T19:27:26.194Z,1683660446.194 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-05-09T19:27:26.194Z,1683660446.194 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-05-09T19:27:26.194Z,1683660446.194 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-05-09T19:27:26.195Z,1683660446.195 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-05-09T19:27:26.195Z,1683660446.195 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-05-09T19:27:26.195Z,1683660446.195 [BuoyancyServo](INFO): Powering down
2023-05-09T19:27:26.209Z,1683660446.209 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-05-09T19:27:26.209Z,1683660446.209 [ElevatorServo](INFO): Powering down
2023-05-09T19:27:26.210Z,1683660446.210 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-05-09T19:27:26.210Z,1683660446.210 [MassServo](INFO): Powering down
2023-05-09T19:27:26.211Z,1683660446.211 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-05-09T19:27:26.211Z,1683660446.211 [RudderServo](INFO): Powering down
2023-05-09T19:27:26.212Z,1683660446.212 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-05-09T19:27:26.212Z,1683660446.212 [ThrusterHE](INFO): Powering down
2023-05-09T19:27:26.213Z,1683660446.213 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-05-09T19:27:26.214Z,1683660446.214 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-05-09T19:27:26.214Z,1683660446.214 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-05-09T19:27:26.214Z,1683660446.214 [CBIT](DEBUG): Powering off loads.
2023-05-09T19:27:26.225Z,1683660446.225 [CBIT](DEBUG): Disabling WDT.
2023-05-09T19:27:26.237Z,1683660446.237 [CBIT](DEBUG): Opening all GF detection circuits.
2023-05-09T19:27:26.238Z,1683660446.238 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:26.284Z,1683660446.284 [DATMMP ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:26.290Z,1683660446.290 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:26.292Z,1683660446.292 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:26.298Z,1683660446.298 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:26.347Z,1683660446.347 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:26.393Z,1683660446.393 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:26.396Z,1683660446.396 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:26.431Z,1683660446.431 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-09T19:27:26.493Z,1683660446.493 [logger ThreadHandler](INFO): Thread cancelled.