2022-08-19T20:45:48.521Z,1660941948.521 [Supervisor](DEBUG): Initializing supervisor.
2022-08-19T20:45:48.539Z,1660941948.539 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-08-19T20:45:48.540Z,1660941948.540 [SyncHandler](INFO): Protected caller Thread ID is 834
2022-08-19T20:45:48.540Z,1660941948.540 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-08-19T20:45:48.541Z,1660941948.541 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-08-19T20:45:48.542Z,1660941948.542 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 835
2022-08-19T20:45:48.546Z,1660941948.546 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-08-19T20:45:48.563Z,1660941948.563 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-08-19T20:45:48.564Z,1660941948.564 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-08-19T20:45:48.564Z,1660941948.564 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 836
2022-08-19T20:45:48.568Z,1660941948.568 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-08-19T20:45:48.569Z,1660941948.569 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-08-19T20:45:48.570Z,1660941948.570 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 837
2022-08-19T20:45:48.572Z,1660941948.572 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-08-19T20:45:48.572Z,1660941948.572 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-08-19T20:45:48.573Z,1660941948.573 [logger ThreadHandler](INFO): Protected caller Thread ID is 838
2022-08-19T20:45:48.576Z,1660941948.576 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-08-19T20:45:48.577Z,1660941948.577 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-08-19T20:45:48.582Z,1660941948.582 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-08-19T20:45:48.985Z,1660941948.985 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-08-19T20:45:48.987Z,1660941948.987 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-08-19T20:45:49.197Z,1660941949.197 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-08-19T20:45:49.199Z,1660941949.199 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-08-19T20:45:49.280Z,1660941949.280 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-08-19T20:45:49.492Z,1660941949.492 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-08-19T20:45:49.494Z,1660941949.494 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-08-19T20:45:49.575Z,1660941949.575 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-08-19T20:45:49.668Z,1660941949.668 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-08-19T20:45:49.670Z,1660941949.670 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-08-19T20:45:50.100Z,1660941950.100 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-08-19T20:45:50.102Z,1660941950.102 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-08-19T20:45:50.234Z,1660941950.234 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-08-19T20:45:50.234Z,1660941950.234 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-08-19T20:45:50.725Z,1660941950.725 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-08-19T20:45:50.727Z,1660941950.727 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-08-19T20:45:50.905Z,1660941950.905 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-08-19T20:45:50.907Z,1660941950.907 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-08-19T20:45:51.267Z,1660941951.267 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-08-19T20:45:51.268Z,1660941951.268 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-08-19T20:45:51.539Z,1660941951.539 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-08-19T20:45:51.541Z,1660941951.541 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-08-19T20:45:51.649Z,1660941951.649 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-08-19T20:45:51.651Z,1660941951.651 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-08-19T20:45:52.728Z,1660941952.728 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-08-19T20:45:52.729Z,1660941952.729 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-08-19T20:45:53.123Z,1660941953.123 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-08-19T20:45:53.125Z,1660941953.125 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-08-19T20:45:53.344Z,1660941953.344 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-08-19T20:45:53.346Z,1660941953.346 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2022-08-19T20:45:53.349Z,1660941953.349 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2022-08-19T20:45:53.538Z,1660941953.538 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2022-08-19T20:45:53.622Z,1660941953.622 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2022-08-19T20:45:53.758Z,1660941953.758 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2022-08-19T20:45:53.843Z,1660941953.843 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2022-08-19T20:45:54.103Z,1660941954.103 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-08-19T20:45:54.103Z,1660941954.103 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2022-08-19T20:45:54.205Z,1660941954.205 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2022-08-19T20:45:54.314Z,1660941954.314 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2022-08-19T20:45:54.420Z,1660941954.420 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2022-08-19T20:45:54.558Z,1660941954.558 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2022-08-19T20:45:54.656Z,1660941954.656 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2022-08-19T20:45:54.748Z,1660941954.748 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2022-08-19T20:45:54.748Z,1660941954.748 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-08-19T20:45:54.762Z,1660941954.762 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-08-19T20:45:54.778Z,1660941954.778 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-08-19T20:45:54.779Z,1660941954.779 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-08-19T20:45:54.895Z,1660941954.895 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-08-19T20:45:54.897Z,1660941954.897 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-08-19T20:45:54.963Z,1660941954.963 [VerticalControl](DEBUG): Construct VerticalControl.
2022-08-19T20:45:55.021Z,1660941955.021 [VerticalControl] Loaded
2022-08-19T20:45:55.021Z,1660941955.021 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-08-19T20:45:55.024Z,1660941955.024 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-08-19T20:45:55.065Z,1660941955.065 [HorizontalControl] Loaded
2022-08-19T20:45:55.065Z,1660941955.065 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-08-19T20:45:55.067Z,1660941955.067 [SpeedControl](DEBUG): Construct SpeedControl.
2022-08-19T20:45:55.071Z,1660941955.071 [SpeedControl] Loaded
2022-08-19T20:45:55.071Z,1660941955.071 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-08-19T20:45:55.073Z,1660941955.073 [LoopControl](DEBUG): Construct LoopControl.
2022-08-19T20:45:55.074Z,1660941955.074 [LoopControl] Loaded
2022-08-19T20:45:55.074Z,1660941955.074 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-08-19T20:45:55.075Z,1660941955.075 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-08-19T20:45:55.075Z,1660941955.075 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-08-19T20:45:55.244Z,1660941955.244 [BuoyancyServo] Loaded
2022-08-19T20:45:55.245Z,1660941955.245 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-08-19T20:45:55.264Z,1660941955.264 [ElevatorServo] Loaded
2022-08-19T20:45:55.264Z,1660941955.264 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-08-19T20:45:55.283Z,1660941955.283 [MassServo] Loaded
2022-08-19T20:45:55.283Z,1660941955.283 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-08-19T20:45:55.302Z,1660941955.302 [RudderServo] Loaded
2022-08-19T20:45:55.302Z,1660941955.302 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-08-19T20:45:55.316Z,1660941955.316 [ThrusterHE] Loaded
2022-08-19T20:45:55.317Z,1660941955.317 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-08-19T20:45:55.317Z,1660941955.317 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-08-19T20:45:55.318Z,1660941955.318 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-08-19T20:45:55.387Z,1660941955.387 [DepthRateCalculator] Loaded
2022-08-19T20:45:55.388Z,1660941955.388 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-08-19T20:45:55.393Z,1660941955.393 [PitchRateCalculator] Loaded
2022-08-19T20:45:55.393Z,1660941955.393 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-08-19T20:45:55.402Z,1660941955.402 [SpeedCalculator] Loaded
2022-08-19T20:45:55.403Z,1660941955.403 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-08-19T20:45:55.407Z,1660941955.407 [YawRateCalculator] Loaded
2022-08-19T20:45:55.407Z,1660941955.407 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-08-19T20:45:55.425Z,1660941955.425 [ElevatorOffsetCalculator] Loaded
2022-08-19T20:45:55.425Z,1660941955.425 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-08-19T20:45:55.426Z,1660941955.426 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-08-19T20:45:55.426Z,1660941955.426 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-08-19T20:45:55.560Z,1660941955.560 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-08-19T20:45:55.560Z,1660941955.560 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-08-19T20:45:55.574Z,1660941955.574 [NavChart] Loaded
2022-08-19T20:45:55.574Z,1660941955.574 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-08-19T20:45:55.580Z,1660941955.580 [UniversalFixResidualReporter] Loaded
2022-08-19T20:45:55.580Z,1660941955.580 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-08-19T20:45:55.580Z,1660941955.580 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-08-19T20:45:55.581Z,1660941955.581 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-08-19T20:45:56.274Z,1660941956.274 [AHRS_M2] Loaded
2022-08-19T20:45:56.274Z,1660941956.274 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-08-19T20:45:56.523Z,1660941956.523 [BackseatComponent] Loaded
2022-08-19T20:45:56.523Z,1660941956.523 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-08-19T20:45:56.524Z,1660941956.524 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408D04E0
2022-08-19T20:45:56.525Z,1660941956.525 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 921
2022-08-19T20:45:56.528Z,1660941956.528 [LcmUniversalReporter] Loaded
2022-08-19T20:45:56.528Z,1660941956.528 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-08-19T20:45:57.315Z,1660941957.315 [BPC1] Loaded
2022-08-19T20:45:57.315Z,1660941957.315 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-08-19T20:45:57.455Z,1660941957.455 [DataOverHttps] Loaded
2022-08-19T20:45:57.455Z,1660941957.455 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-08-19T20:45:57.456Z,1660941957.456 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409004E0
2022-08-19T20:45:57.456Z,1660941957.456 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 922
2022-08-19T20:45:57.477Z,1660941957.477 [Depth_Keller] Loaded
2022-08-19T20:45:57.477Z,1660941957.477 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-08-19T20:45:57.482Z,1660941957.482 [DropWeight] Loaded
2022-08-19T20:45:57.482Z,1660941957.482 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-08-19T20:45:57.542Z,1660941957.542 [NAL9602] Loaded
2022-08-19T20:45:57.542Z,1660941957.542 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-08-19T20:45:57.571Z,1660941957.571 [Onboard] Loaded
2022-08-19T20:45:57.572Z,1660941957.572 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-08-19T20:45:57.573Z,1660941957.573 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409304E0
2022-08-19T20:45:57.573Z,1660941957.573 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 923
2022-08-19T20:45:57.585Z,1660941957.585 [Power24vConverter] Loaded
2022-08-19T20:45:57.585Z,1660941957.585 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-08-19T20:45:57.598Z,1660941957.598 [Radio_Surface] Loaded
2022-08-19T20:45:57.598Z,1660941957.598 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-08-19T20:45:57.599Z,1660941957.599 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409604E0
2022-08-19T20:45:57.599Z,1660941957.599 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 924
2022-08-19T20:45:57.690Z,1660941957.690 [DAT] Loaded
2022-08-19T20:45:57.691Z,1660941957.691 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2022-08-19T20:45:57.692Z,1660941957.692 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409904E0
2022-08-19T20:45:57.692Z,1660941957.692 [DAT ThreadHandler](INFO): Protected caller Thread ID is 925
2022-08-19T20:45:57.692Z,1660941957.692 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-08-19T20:45:57.693Z,1660941957.693 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-08-19T20:45:57.974Z,1660941957.974 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-08-19T20:45:57.974Z,1660941957.974 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-08-19T20:45:58.526Z,1660941958.526 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-08-19T20:45:58.526Z,1660941958.526 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-08-19T20:45:58.583Z,1660941958.583 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-08-19T20:45:58.584Z,1660941958.584 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-08-19T20:45:58.799Z,1660941958.799 [CTD_Seabird] Loaded
2022-08-19T20:45:58.799Z,1660941958.799 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-08-19T20:45:58.800Z,1660941958.800 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ABB4E0
2022-08-19T20:45:58.801Z,1660941958.801 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 926
2022-08-19T20:45:58.820Z,1660941958.820 [PAR_Licor] Loaded
2022-08-19T20:45:58.821Z,1660941958.821 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-08-19T20:45:58.851Z,1660941958.851 [WetLabsBB2FL] Loaded
2022-08-19T20:45:58.851Z,1660941958.851 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-08-19T20:45:58.852Z,1660941958.852 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AEB4E0
2022-08-19T20:45:58.853Z,1660941958.853 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 927
2022-08-19T20:45:58.854Z,1660941958.854 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-08-19T20:45:58.854Z,1660941958.854 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-08-19T20:45:59.207Z,1660941959.207 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-08-19T20:45:59.209Z,1660941959.209 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-08-19T20:45:59.375Z,1660941959.375 [SBIT](DEBUG): Construct Startup Built In Test.
2022-08-19T20:45:59.384Z,1660941959.384 [SBIT] Loaded
2022-08-19T20:45:59.384Z,1660941959.384 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-08-19T20:45:59.387Z,1660941959.387 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-08-19T20:45:59.400Z,1660941959.400 [IBIT] Loaded
2022-08-19T20:45:59.400Z,1660941959.400 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-08-19T20:45:59.406Z,1660941959.406 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-08-19T20:45:59.509Z,1660941959.509 [CBIT] Loaded
2022-08-19T20:45:59.510Z,1660941959.510 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-08-19T20:45:59.510Z,1660941959.510 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-08-19T20:45:59.516Z,1660941959.516 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-08-19T20:45:59.519Z,1660941959.519 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-08-19T20:45:59.530Z,1660941959.530 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-08-19T20:45:59.531Z,1660941959.531 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCE4E0
2022-08-19T20:45:59.531Z,1660941959.531 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 928
2022-08-19T20:45:59.535Z,1660941959.535 [Supervisor](INFO): Main Thread ID is 829
2022-08-19T20:45:59.536Z,1660941959.536 [Supervisor](DEBUG): Running supervisor.
2022-08-19T20:45:59.536Z,1660941959.536 [CommandExec ThreadHandler](INFO): Handler Thread ID is 929
2022-08-19T20:45:59.537Z,1660941959.537 [CommandExec](INFO): Initializing the command executive.
2022-08-19T20:45:59.538Z,1660941959.538 [CommandLine ThreadHandler](INFO): Handler Thread ID is 930
2022-08-19T20:45:59.540Z,1660941959.540 [controlThread ThreadHandler](INFO): Handler Thread ID is 931
2022-08-19T20:45:59.541Z,1660941959.541 [controlThread](DEBUG): Initializing ControlThread
2022-08-19T20:45:59.542Z,1660941959.542 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-08-19T20:45:59.543Z,1660941959.543 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-08-19T20:45:59.544Z,1660941959.544 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-08-19T20:45:59.545Z,1660941959.545 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-08-19T20:45:59.546Z,1660941959.546 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-08-19T20:45:59.546Z,1660941959.546 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-08-19T20:45:59.547Z,1660941959.547 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-08-19T20:45:59.547Z,1660941959.547 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-08-19T20:45:59.547Z,1660941959.547 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-08-19T20:45:59.549Z,1660941959.549 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-08-19T20:45:59.549Z,1660941959.549 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-08-19T20:45:59.557Z,1660941959.557 [SBIT](INFO): Initialize SBIT Component.
2022-08-19T20:45:59.558Z,1660941959.558 [SBIT](IMPORTANT): git: 2022-08-02
2022-08-19T20:45:59.558Z,1660941959.558 [SBIT](INFO): git hash: f84e5919489b70ff94ab72b61eefa777bac519a0
2022-08-19T20:45:59.558Z,1660941959.558 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-08-19T20:45:59.559Z,1660941959.559 [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
2022-08-19T20:45:59.560Z,1660941959.560 [SBIT](INFO): Beginning SBIT in 54.000000 seconds.
2022-08-19T20:45:59.561Z,1660941959.561 [IBIT](INFO): Initialize IBIT Component.
2022-08-19T20:45:59.562Z,1660941959.562 [CBIT](DEBUG): Initialize CBIT Component.
2022-08-19T20:45:59.563Z,1660941959.563 [logger ThreadHandler](INFO): Handler Thread ID is 932
2022-08-19T20:45:59.573Z,1660941959.573 [CBIT](DEBUG): Initialized mux pins.
2022-08-19T20:45:59.573Z,1660941959.573 [CBIT](DEBUG): Initializing the watchdog timer.
2022-08-19T20:45:59.581Z,1660941959.581 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 933
2022-08-19T20:45:59.593Z,1660941959.593 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 934
2022-08-19T20:45:59.594Z,1660941959.594 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-08-19T20:45:59.597Z,1660941959.597 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-08-19T20:45:59.598Z,1660941959.598 [CBIT](DEBUG): Initializing heartbeat.
2022-08-19T20:45:59.605Z,1660941959.605 [Onboard ThreadHandler](INFO): Handler Thread ID is 935
2022-08-19T20:45:59.623Z,1660941959.623 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 936
2022-08-19T20:45:59.642Z,1660941959.642 [DAT ThreadHandler](INFO): Handler Thread ID is 937
2022-08-19T20:45:59.643Z,1660941959.643 [DAT](INFO): Powering up
2022-08-19T20:45:59.643Z,1660941959.643 [DAT](DEBUG): Initializing DAT.
2022-08-19T20:45:59.646Z,1660941959.646 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 939
2022-08-19T20:45:59.647Z,1660941959.647 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-08-19T20:45:59.650Z,1660941959.650 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 941
2022-08-19T20:45:59.653Z,1660941959.653 [WetLabsBB2FL](INFO): Powering up
2022-08-19T20:45:59.655Z,1660941959.655 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 943
2022-08-19T20:45:59.666Z,1660941959.666 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI01M.000
2022-08-19T20:45:59.666Z,1660941959.666 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI01M.000
2022-08-19T20:45:59.667Z,1660941959.667 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI80M.000
2022-08-19T20:45:59.667Z,1660941959.667 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI80M.000
2022-08-19T20:45:59.667Z,1660941959.667 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI57M.000
2022-08-19T20:45:59.667Z,1660941959.667 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI57M.000
2022-08-19T20:45:59.667Z,1660941959.667 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI56M.000
2022-08-19T20:45:59.667Z,1660941959.667 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI56M.000
2022-08-19T20:45:59.667Z,1660941959.667 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI90M.000
2022-08-19T20:45:59.668Z,1660941959.668 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI90M.000
2022-08-19T20:45:59.668Z,1660941959.668 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI89M.000
2022-08-19T20:45:59.668Z,1660941959.668 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI89M.000
2022-08-19T20:45:59.668Z,1660941959.668 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI79M.000
2022-08-19T20:45:59.668Z,1660941959.668 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI79M.000
2022-08-19T20:45:59.668Z,1660941959.668 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1DD.000
2022-08-19T20:45:59.669Z,1660941959.669 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1DD.000
2022-08-19T20:45:59.669Z,1660941959.669 [CBIT](DEBUG): Deactivating GF circuits.
2022-08-19T20:45:59.669Z,1660941959.669 [CBIT](DEBUG): Deactivating emergency mode.
2022-08-19T20:45:59.669Z,1660941959.669 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1DE.000
2022-08-19T20:45:59.670Z,1660941959.670 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1DE.000
2022-08-19T20:45:59.670Z,1660941959.670 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1DF.000
2022-08-19T20:45:59.670Z,1660941959.670 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1DF.000
2022-08-19T20:45:59.670Z,1660941959.670 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1DG.000
2022-08-19T20:45:59.670Z,1660941959.670 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1DG.000
2022-08-19T20:45:59.670Z,1660941959.670 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1DH.000
2022-08-19T20:45:59.670Z,1660941959.670 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1DH.000
2022-08-19T20:45:59.671Z,1660941959.671 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1CC.000
2022-08-19T20:45:59.671Z,1660941959.671 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1CC.000
2022-08-19T20:45:59.671Z,1660941959.671 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1CD.000
2022-08-19T20:45:59.671Z,1660941959.671 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1CD.000
2022-08-19T20:45:59.671Z,1660941959.671 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1CE.000
2022-08-19T20:45:59.672Z,1660941959.672 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1CE.000
2022-08-19T20:45:59.672Z,1660941959.672 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1QE.000
2022-08-19T20:45:59.672Z,1660941959.672 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1QE.000
2022-08-19T20:45:59.672Z,1660941959.672 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1QF.000
2022-08-19T20:45:59.672Z,1660941959.672 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1QF.000
2022-08-19T20:45:59.672Z,1660941959.672 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1QG.000
2022-08-19T20:45:59.673Z,1660941959.673 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1QG.000
2022-08-19T20:45:59.673Z,1660941959.673 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PB.000
2022-08-19T20:45:59.673Z,1660941959.673 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PB.000
2022-08-19T20:45:59.673Z,1660941959.673 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PC.000
2022-08-19T20:45:59.674Z,1660941959.674 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PC.000
2022-08-19T20:45:59.674Z,1660941959.674 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PD.000
2022-08-19T20:45:59.674Z,1660941959.674 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PD.000
2022-08-19T20:45:59.674Z,1660941959.674 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PE.000
2022-08-19T20:45:59.674Z,1660941959.674 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PE.000
2022-08-19T20:45:59.674Z,1660941959.674 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PF.000
2022-08-19T20:45:59.675Z,1660941959.675 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PF.000
2022-08-19T20:45:59.675Z,1660941959.675 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PG.000
2022-08-19T20:45:59.675Z,1660941959.675 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PG.000
2022-08-19T20:45:59.675Z,1660941959.675 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5WI22M.000
2022-08-19T20:45:59.675Z,1660941959.675 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5WI22M.000
2022-08-19T20:45:59.705Z,1660941959.705 [CBIT](DEBUG): Backplane powered.
2022-08-19T20:45:59.706Z,1660941959.706 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-08-19T20:45:59.741Z,1660941959.741 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-08-19T20:45:59.769Z,1660941959.769 [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>


2022-08-19T20:45:59.770Z,1660941959.770 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-08-19T20:45:59.854Z,1660941959.854 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-08-19T20:45:59.859Z,1660941959.859 [Default:A.Wait](DEBUG): Construct Wait.
2022-08-19T20:45:59.871Z,1660941959.871 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-08-19T20:45:59.907Z,1660941959.907 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-08-19T20:45:59.923Z,1660941959.923 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-08-19T20:45:59.936Z,1660941959.936 [Default:E.Execute](DEBUG): Construct Execute.
2022-08-19T20:45:59.963Z,1660941959.963 [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>


2022-08-19T20:45:59.967Z,1660941959.967 [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,
2022-08-19T20:45:59.993Z,1660941959.993 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-08-19T20:46:00.029Z,1660941960.029 [Radio_Surface](INFO): Powering up
2022-08-19T20:46:00.188Z,1660941960.188 [Power24vConverter](INFO): Powering up.
2022-08-19T20:46:00.224Z,1660941960.224 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-08-19T20:46:00.229Z,1660941960.229 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-08-19T20:46:00.230Z,1660941960.230 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-08-19T20:46:00.237Z,1660941960.237 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-08-19T20:46:00.238Z,1660941960.238 [MassServo](DEBUG): Initializing EZServoServo.
2022-08-19T20:46:00.245Z,1660941960.245 [MassServo](DEBUG): Initializing MassServo.
2022-08-19T20:46:00.246Z,1660941960.246 [RudderServo](DEBUG): Initializing EZServoServo.
2022-08-19T20:46:00.253Z,1660941960.253 [RudderServo](DEBUG): Initializing RudderServo.
2022-08-19T20:46:00.254Z,1660941960.254 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-08-19T20:46:00.261Z,1660941960.261 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-08-19T20:46:01.145Z,1660941961.145 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-08-19T20:46:01.145Z,1660941961.145 [RudderServo](FAULT): Rudder failed to initialize
2022-08-19T20:46:01.145Z,1660941961.145 [RudderServo] Communications Fault, FailCount= 1
2022-08-19T20:46:01.145Z,1660941961.145 [RudderServo](ERROR): Communications Fault
2022-08-19T20:46:01.150Z,1660941961.150 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-08-19T20:46:01.346Z,1660941961.346 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-08-19T20:46:01.346Z,1660941961.346 [RudderServo](INFO): Powering down
2022-08-19T20:46:01.761Z,1660941961.761 [WetLabsBB2FL](INFO): Powering down
2022-08-19T20:46:02.006Z,1660941962.006 [RudderServo](DEBUG): Initializing EZServoServo.
2022-08-19T20:46:02.126Z,1660941962.126 [RudderServo](DEBUG): Initializing RudderServo.
2022-08-19T20:46:02.130Z,1660941962.130 [CBIT](INFO): Clearing failed state for component RudderServo
2022-08-19T20:46:02.130Z,1660941962.130 [RudderServo] No Fault, FailCount= 1
2022-08-19T20:46:05.922Z,1660941965.922 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-08-19T20:46:15.267Z,1660941975.267 [DAT](INFO): commRate: 800
2022-08-19T20:46:17.333Z,1660941977.333 [DAT](INFO): entering command mode
2022-08-19T20:46:17.534Z,1660941977.534 [DAT](INFO): setting verbose to 3
2022-08-19T20:46:17.787Z,1660941977.787 [DAT](INFO): set verbose to 3
2022-08-19T20:46:17.787Z,1660941977.787 [DAT](INFO): setting DatVerbose to 27440
2022-08-19T20:46:18.038Z,1660941978.038 [DAT](INFO): set DatVerbose to 27440
2022-08-19T20:46:18.039Z,1660941978.039 [DAT](INFO): setting transmit power to 8
2022-08-19T20:46:18.291Z,1660941978.291 [DAT](INFO): set transmit power to 8
2022-08-19T20:46:18.291Z,1660941978.291 [DAT](INFO): setting local address to 5
2022-08-19T20:46:18.542Z,1660941978.542 [DAT](INFO): set local address to 5
2022-08-19T20:46:18.543Z,1660941978.543 [DAT](INFO): Setting time to: 20:46:18 And date to:8/19/2022
2022-08-19T20:46:18.795Z,1660941978.795 [DAT](INFO): Local DAT time set to Fri Aug 19, 2022  20:46:18  

2022-08-19T20:46:27.859Z,1660941987.859 [NAL9602](INFO): Powering up NAL9602
2022-08-19T20:46:32.034Z,1660941992.034 [CommandExec](IMPORTANT): got command failComponent
2022-08-19T20:46:32.034Z,1660941992.034 [CommandExec](IMPORTANT): Failed components:
2022-08-19T20:46:32.034Z,1660941992.034 [CommandExec](IMPORTANT): No failed Components.
2022-08-19T20:46:38.763Z,1660941998.763 [NAL9602](INFO): NAL9602 initialized
2022-08-19T20:46:54.131Z,1660942014.131 [SBIT](IMPORTANT): Beginning Startup BIT
2022-08-19T20:46:54.162Z,1660942014.162 [CBIT](IMPORTANT): Beginning ground fault scan
2022-08-19T20:47:05.113Z,1660942025.113 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006026
CHAN A1 (24V): -0.007366
CHAN A2 (12V): -0.005322
CHAN A3 (5V): -0.003096
CHAN B0 (3.3V): -0.000699
CHAN B1 (3.15aV): -0.000955
CHAN B2 (3.15bV): -0.001117
CHAN B3 (GND): 0.000071
OPEN: -0.003905
Full Scale: +/- 1 mA
2022-08-19T20:47:06.656Z,1660942026.656 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:47:07.135Z,1660942027.135 [SBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.549142
2022-08-19T20:47:47.860Z,1660942067.860 [SBIT](CRITICAL): SBIT FAILED
2022-08-19T20:47:47.861Z,1660942067.861 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-08-19T20:47:47.862Z,1660942067.862 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=4 count;

2022-08-19T20:47:47.862Z,1660942067.862 [SBIT](IMPORTANT): BackseatComponent.alwaysOn=0 bool;

2022-08-19T20:47:47.862Z,1660942067.862 [SBIT](IMPORTANT): CTD_Seabird.minSalinityBound=0 practical_salinity_unit;

2022-08-19T20:47:47.862Z,1660942067.862 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2022-08-19T20:47:47.862Z,1660942067.862 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water;

2022-08-19T20:47:47.862Z,1660942067.862 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree;

2022-08-19T20:47:47.862Z,1660942067.862 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=10 ampere_hour;

2022-08-19T20:47:47.862Z,1660942067.862 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt;

2022-08-19T20:47:47.863Z,1660942067.863 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=480 cubic_centimeter;

2022-08-19T20:47:47.863Z,1660942067.863 [SBIT](IMPORTANT): VerticalControl.massDefault=8.711214 millimeter;

2022-08-19T20:47:47.864Z,1660942067.864 [CommandExec](FAULT): Scheduling is paused
2022-08-19T20:47:47.864Z,1660942067.864 [CBIT](INFO): Critical error at 20220819T204747
2022-08-19T20:47:47.865Z,1660942067.865 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2022-08-19T20:47:48.303Z,1660942068.303 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:47:48.324Z,1660942068.324 [MissionManager](IMPORTANT): Started mission Startup
2022-08-19T20:47:48.324Z,1660942068.324 [Startup] Running Loop=1
2022-08-19T20:47:48.325Z,1660942068.325 [Startup](DEBUG): Aggregate::initialize Startup
2022-08-19T20:47:48.325Z,1660942068.325 [Startup:A.GoToSurface] Running Loop=1
2022-08-19T20:47:48.325Z,1660942068.325 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-08-19T20:47:48.326Z,1660942068.326 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-08-19T20:47:48.326Z,1660942068.326 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-08-19T20:47:48.326Z,1660942068.326 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-08-19T20:47:48.327Z,1660942068.327 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-08-19T20:47:48.327Z,1660942068.327 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-08-19T20:47:48.328Z,1660942068.328 [Startup:StartupSatComms] Running Loop=1
2022-08-19T20:47:48.328Z,1660942068.328 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-08-19T20:47:48.329Z,1660942068.329 [Startup:StartupSatComms:A] Running Loop=1
2022-08-19T20:47:48.700Z,1660942068.700 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-08-19T20:48:13.313Z,1660942093.313 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:48:24.273Z,1660942104.273 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:48:35.586Z,1660942115.586 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:48:42.040Z,1660942122.040 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:48:46.489Z,1660942126.489 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:48:48.458Z,1660942128.458 [Startup:StartupSatComms:A](INFO): Timed out from 2022-08-19T20:47:48.3Z
2022-08-19T20:48:48.458Z,1660942128.458 [Startup:StartupSatComms:A] Stopped
2022-08-19T20:48:48.459Z,1660942128.459 [Startup:StartupSatComms:B] Running Loop=1
2022-08-19T20:48:48.867Z,1660942128.867 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-08-19T20:48:57.812Z,1660942137.812 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:48:59.800Z,1660942139.800 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-08-19T20:48:59.800Z,1660942139.800 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-08-19T20:48:59.811Z,1660942139.811 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-08-19T20:49:00.186Z,1660942140.186 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-08-19T20:49:00.187Z,1660942140.187 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-08-19T20:49:09.117Z,1660942149.117 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:49:19.620Z,1660942159.620 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:49:21.224Z,1660942161.224 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:49:28.735Z,1660942168.735 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004429
2022-08-19T20:49:30.534Z,1660942170.534 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:49:32.190Z,1660942172.190 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:49:36.113Z,1660942176.113 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20220818T155302/Courier0483.lzma
2022-08-19T20:49:37.115Z,1660942177.115 [DataOverHttps](INFO): Moved sent file to Logs/20220818T155302/Courier0483.lzma.bak
2022-08-19T20:49:37.115Z,1660942177.115 [DataOverHttps](INFO): SBD MOMSN=17115458
2022-08-19T20:49:41.905Z,1660942181.905 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:49:43.110Z,1660942183.110 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:49:45.046Z,1660942185.046 [CommandExec](IMPORTANT): got command failComponent
2022-08-19T20:49:45.046Z,1660942185.046 [CommandExec](IMPORTANT): Failed components:
2022-08-19T20:49:45.046Z,1660942185.046 [CommandExec](IMPORTANT): No failed Components.
2022-08-19T20:49:48.660Z,1660942188.660 [Startup:StartupSatComms:B](INFO): Timed out from 2022-08-19T20:48:48.5Z
2022-08-19T20:49:48.660Z,1660942188.660 [Startup:StartupSatComms:B] Stopped
2022-08-19T20:49:48.660Z,1660942188.660 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-08-19T20:49:48.660Z,1660942188.660 [Startup:StartupSatComms] Stopped
2022-08-19T20:49:48.660Z,1660942188.660 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-08-19T20:49:48.665Z,1660942188.665 [Startup](INFO): Completed Startup
2022-08-19T20:49:48.665Z,1660942188.665 [MissionManager](INFO): Startup is completed.
2022-08-19T20:49:48.665Z,1660942188.665 [MissionManager](INFO): Uninitializing Mission Startup
2022-08-19T20:49:48.665Z,1660942188.665 [Startup] Stopped
2022-08-19T20:49:48.666Z,1660942188.666 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-08-19T20:49:48.666Z,1660942188.666 [Startup:A.GoToSurface] Stopped
2022-08-19T20:49:48.666Z,1660942188.666 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-08-19T20:49:49.071Z,1660942189.071 [MissionManager](IMPORTANT): Started mission Default
2022-08-19T20:49:49.071Z,1660942189.071 [Default] Running Loop=1
2022-08-19T20:49:49.071Z,1660942189.071 [Default](DEBUG): Aggregate::initialize Default
2022-08-19T20:49:49.071Z,1660942189.071 [Default:B.GoToSurface] Running Loop=1
2022-08-19T20:49:49.071Z,1660942189.071 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-08-19T20:49:49.071Z,1660942189.071 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-08-19T20:49:49.072Z,1660942189.072 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-08-19T20:49:49.072Z,1660942189.072 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-08-19T20:49:49.072Z,1660942189.072 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-08-19T20:49:49.073Z,1660942189.073 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-08-19T20:49:49.077Z,1660942189.077 [Default:A.Wait] Running Loop=1
2022-08-19T20:49:49.077Z,1660942189.077 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-08-19T20:49:52.748Z,1660942192.748 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:49:52.962Z,1660942192.962 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20220819T204328/Courier0000.lzma
2022-08-19T20:49:53.960Z,1660942193.960 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:49:53.963Z,1660942193.963 [DataOverHttps](INFO): Moved sent file to Logs/20220819T204328/Courier0000.lzma.bak
2022-08-19T20:49:53.963Z,1660942193.963 [DataOverHttps](INFO): SBD MOMSN=17115460
2022-08-19T20:50:02.412Z,1660942202.412 [Default:A.Wait](INFO): Done Waiting.
2022-08-19T20:50:02.412Z,1660942202.412 [Default:A.Wait] Stopped
2022-08-19T20:50:02.412Z,1660942202.412 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-08-19T20:50:02.822Z,1660942202.822 [Default:CheckIn] Running Loop=1
2022-08-19T20:50:02.822Z,1660942202.822 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-19T20:50:02.822Z,1660942202.822 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-19T20:50:03.220Z,1660942203.220 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-08-19T20:50:03.660Z,1660942203.660 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:50:05.276Z,1660942205.276 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:50:14.565Z,1660942214.565 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:50:16.178Z,1660942216.178 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:50:25.483Z,1660942225.483 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:50:27.095Z,1660942227.095 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:50:36.116Z,1660942236.116 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:50:38.192Z,1660942238.192 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:50:46.998Z,1660942246.998 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:50:49.019Z,1660942249.019 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:50:57.855Z,1660942257.855 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:50:59.901Z,1660942259.901 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:51:08.788Z,1660942268.788 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:51:10.837Z,1660942270.837 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:51:19.677Z,1660942279.677 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:51:22.100Z,1660942282.100 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:51:30.585Z,1660942290.585 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:51:33.007Z,1660942293.007 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:51:41.489Z,1660942301.489 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:51:42.247Z,1660942302.247 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-08-19T20:51:43.507Z,1660942303.507 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:51:52.400Z,1660942312.400 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:51:54.426Z,1660942314.426 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:52:00.843Z,1660942320.843 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-08-19T20:52:00.843Z,1660942320.843 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-08-19T20:52:00.854Z,1660942320.854 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-08-19T20:52:01.256Z,1660942321.256 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-08-19T20:52:01.256Z,1660942321.256 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-08-19T20:52:03.708Z,1660942323.708 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:52:05.728Z,1660942325.728 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:52:15.031Z,1660942335.031 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:52:17.049Z,1660942337.049 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:52:26.333Z,1660942346.333 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:52:27.950Z,1660942347.950 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:52:37.305Z,1660942357.305 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:52:38.860Z,1660942358.860 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:52:48.214Z,1660942368.214 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:52:49.360Z,1660942369.360 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:53:00.255Z,1660942380.255 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:53:00.330Z,1660942380.330 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:53:05.459Z,1660942385.459 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:53:11.176Z,1660942391.176 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:53:17.224Z,1660942397.224 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:53:22.086Z,1660942402.086 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:53:28.931Z,1660942408.931 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:53:32.639Z,1660942412.639 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:53:40.651Z,1660942420.651 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:53:43.504Z,1660942423.504 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:53:52.363Z,1660942432.363 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:53:54.810Z,1660942434.810 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:54:04.083Z,1660942444.083 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T20:54:06.178Z,1660942446.178 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T20:54:10.089Z,1660942450.089 [BPC1](ERROR): Failed to receive data from all battery packs.
2022-08-19T20:54:10.090Z,1660942450.090 [BPC1] Communications Fault, FailCount= 1
2022-08-19T20:54:10.090Z,1660942450.090 [BPC1](ERROR): Communications Fault
2022-08-19T20:54:10.141Z,1660942450.141 [CBIT](ERROR): Communications Fault in component: BPC1
2022-08-19T20:55:01.842Z,1660942501.842 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2022-08-19T20:55:01.842Z,1660942501.842 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-08-19T20:55:01.852Z,1660942501.852 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-08-19T20:55:02.258Z,1660942502.258 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-08-19T20:55:02.258Z,1660942502.258 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2022-08-19T20:55:03.051Z,1660942503.051 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-08-19T20:50:02.8Z
2022-08-19T20:55:03.051Z,1660942503.051 [Default:CheckIn:Read_GPS] Stopped
2022-08-19T20:55:03.051Z,1660942503.051 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-19T20:55:03.476Z,1660942503.476 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-08-19T20:55:10.009Z,1660942510.009 [DataOverHttps](INFO): Sending 184 bytes from file Logs/20220819T204548/Courier0000.lzma
2022-08-19T20:55:11.011Z,1660942511.011 [DataOverHttps](INFO): Moved sent file to Logs/20220819T204548/Courier0000.lzma.bak
2022-08-19T20:55:11.011Z,1660942511.011 [DataOverHttps](INFO): SBD MOMSN=17115474
2022-08-19T20:55:26.612Z,1660942526.612 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220819T204548/Courier0004.lzma
2022-08-19T20:55:27.615Z,1660942527.615 [DataOverHttps](INFO): Moved sent file to Logs/20220819T204548/Courier0004.lzma.bak
2022-08-19T20:55:27.615Z,1660942527.615 [DataOverHttps](INFO): SBD MOMSN=17115478
2022-08-19T20:55:44.048Z,1660942544.048 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20220818T155302/Express0484.lzma
2022-08-19T20:55:45.051Z,1660942545.051 [DataOverHttps](INFO): Moved sent file to Logs/20220818T155302/Express0484.lzma.bak
2022-08-19T20:55:45.051Z,1660942545.051 [DataOverHttps](INFO): SBD MOMSN=17115480
2022-08-19T20:56:00.567Z,1660942560.567 [DataOverHttps](INFO): Sending 837 bytes from file Logs/20220819T204328/Express0001.lzma
2022-08-19T20:56:01.657Z,1660942561.657 [DataOverHttps](INFO): Moved sent file to Logs/20220819T204328/Express0001.lzma.bak
2022-08-19T20:56:01.658Z,1660942561.658 [DataOverHttps](INFO): SBD MOMSN=17115482
2022-08-19T20:56:17.094Z,1660942577.094 [DataOverHttps](INFO): Sending 1069 bytes from file Logs/20220819T204548/Express0001.lzma
2022-08-19T20:56:18.095Z,1660942578.095 [DataOverHttps](INFO): Moved sent file to Logs/20220819T204548/Express0001.lzma.bak
2022-08-19T20:56:18.095Z,1660942578.095 [DataOverHttps](INFO): SBD MOMSN=17115516
2022-08-19T20:56:37.853Z,1660942597.853 [DataOverHttps](INFO): Sending 265 bytes from file Logs/20220819T204548/Express0005.lzma
2022-08-19T20:56:38.855Z,1660942598.855 [DataOverHttps](INFO): Moved sent file to Logs/20220819T204548/Express0005.lzma.bak
2022-08-19T20:56:38.855Z,1660942598.855 [DataOverHttps](INFO): SBD MOMSN=17115542
2022-08-19T20:56:39.676Z,1660942599.676 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-08-19T20:56:39.676Z,1660942599.676 [NAL9602] Data Fault, FailCount= 1
2022-08-19T20:56:39.676Z,1660942599.676 [NAL9602](ERROR): Data Fault
2022-08-19T20:56:39.763Z,1660942599.763 [CBIT](ERROR): Data Fault in component: NAL9602
2022-08-19T20:56:40.062Z,1660942600.062 [NAL9602](INFO): Powering down
2022-08-19T20:56:40.979Z,1660942600.979 [CBIT](INFO): Clearing failed state for component NAL9602
2022-08-19T20:56:40.979Z,1660942600.979 [NAL9602] No Fault, FailCount= 1
2022-08-19T20:56:41.743Z,1660942601.743 [Default:CheckIn:Read_Iridium] Stopped
2022-08-19T20:56:41.743Z,1660942601.743 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-19T20:56:41.743Z,1660942601.743 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-19T20:57:10.435Z,1660942630.435 [NAL9602](INFO): Powering up NAL9602
2022-08-19T20:57:21.347Z,1660942641.347 [NAL9602](INFO): NAL9602 initialized
2022-08-19T20:58:02.993Z,1660942682.993 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2022-08-19T20:58:02.993Z,1660942682.993 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-08-19T20:58:03.004Z,1660942683.004 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-08-19T20:58:03.396Z,1660942683.396 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-08-19T20:58:03.396Z,1660942683.396 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2022-08-19T20:59:59.170Z,1660942799.170 [DataOverHttps](IMPORTANT): SBD MTMSN=20220819T205947
2022-08-19T21:00:06.425Z,1660942806.425 [DataOverHttps](INFO): Received command: run Maintenance/run_backseat_on_surface.xml
2022-08-19T21:00:06.455Z,1660942806.455 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/run_backseat_on_surface.xml
2022-08-19T21:00:06.456Z,1660942806.456 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/run_backseat_on_surface.xml
2022-08-19T21:00:06.489Z,1660942806.489 [MissionManager](INFO): DefineArg run_backseat_on_surface.ElapsedSinceMissionStarted = 0.000000 min
2022-08-19T21:00:06.490Z,1660942806.490 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.xml
2022-08-19T21:00:06.783Z,1660942806.783 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectChlActive = 0 bool
2022-08-19T21:00:06.786Z,1660942806.786 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.TimeWindowPeakReport = nan min
2022-08-19T21:00:06.788Z,1660942806.788 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestChlPeakReportActive = 0 bool
2022-08-19T21:00:06.791Z,1660942806.791 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestSaltPeakReportActive = 0 bool
2022-08-19T21:00:06.794Z,1660942806.794 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestOilPeakReportActive = 0 bool
2022-08-19T21:00:06.796Z,1660942806.796 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PatchTracking = 0 bool
2022-08-19T21:00:06.799Z,1660942806.799 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.DetectTrough = 0 bool
2022-08-19T21:00:06.803Z,1660942806.803 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.FilterWidthHorizontal = 3.000000 count
2022-08-19T21:00:06.806Z,1660942806.806 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.NumProfilesSlidingwindow = 100.000000 count
2022-08-19T21:00:06.809Z,1660942806.809 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.OffPeakFractionHorizontal = 80.000000 %
2022-08-19T21:00:06.811Z,1660942806.811 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectNO3Active = 0 bool
2022-08-19T21:00:06.814Z,1660942806.814 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectOilActive = 0 bool
2022-08-19T21:00:06.816Z,1660942806.816 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectFDOMActive = 0 bool
2022-08-19T21:00:06.820Z,1660942806.820 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectSalinityActive = 0 bool
2022-08-19T21:00:06.823Z,1660942806.823 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2022-08-19T21:00:06.826Z,1660942806.826 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledAanderaaO2 = 0 bool
2022-08-19T21:00:06.846Z,1660942806.846 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledNeilBrown = 0 bool
2022-08-19T21:00:06.854Z,1660942806.854 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledSeabird = 1 bool
2022-08-19T21:00:06.861Z,1660942806.861 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsBB2FL = 1 bool
2022-08-19T21:00:06.864Z,1660942806.864 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool
2022-08-19T21:00:06.872Z,1660942806.872 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsUBAT = 0 bool
2022-08-19T21:00:06.879Z,1660942806.879 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.LowPassWindowLength = 20.000000 count
2022-08-19T21:00:06.882Z,1660942806.882 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.MedianFilterLen = 5.000000 count
2022-08-19T21:00:06.893Z,1660942806.893 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakShallowBound = nan m
2022-08-19T21:00:06.896Z,1660942806.896 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDeepBound = nan m
2022-08-19T21:00:06.914Z,1660942806.914 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2022-08-19T21:00:06.917Z,1660942806.917 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChl = 0.000000 ug/l
2022-08-19T21:00:06.924Z,1660942806.924 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlDepth = 0.000000 m
2022-08-19T21:00:06.931Z,1660942806.931 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlTemperature = 0.000000 degC
2022-08-19T21:00:06.942Z,1660942806.942 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlLatitude = nan arcdeg
2022-08-19T21:00:06.944Z,1660942806.944 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlLongitude = nan arcdeg
2022-08-19T21:00:06.955Z,1660942806.955 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3 = 0.000000 umol/l
2022-08-19T21:00:06.958Z,1660942806.958 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Depth = 0.000000 m
2022-08-19T21:00:06.966Z,1660942806.966 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Latitude = nan arcdeg
2022-08-19T21:00:06.968Z,1660942806.968 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Longitude = nan arcdeg
2022-08-19T21:00:06.979Z,1660942806.979 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOil = 0.000000 kg/m3
2022-08-19T21:00:06.983Z,1660942806.983 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilDepth = 0.000000 m
2022-08-19T21:00:06.986Z,1660942806.986 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilLatitude = nan arcdeg
2022-08-19T21:00:06.989Z,1660942806.989 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilLongitude = nan arcdeg
2022-08-19T21:00:06.991Z,1660942806.991 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOM = 0.000000 ppb
2022-08-19T21:00:07.009Z,1660942807.009 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMDepth = 0.000000 m
2022-08-19T21:00:07.012Z,1660942807.012 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMTemperature = nan degC
2022-08-19T21:00:07.015Z,1660942807.015 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMLatitude = nan arcdeg
2022-08-19T21:00:07.036Z,1660942807.036 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMLongitude = nan arcdeg
2022-08-19T21:00:07.063Z,1660942807.063 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchFDOMDistance = nan m
2022-08-19T21:00:07.070Z,1660942807.070 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSalt = 0.000000 psu
2022-08-19T21:00:07.081Z,1660942807.081 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltDepth = 0.000000 m
2022-08-19T21:00:07.084Z,1660942807.084 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltTemperature = 0.000000 degC
2022-08-19T21:00:07.100Z,1660942807.100 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltLatitude = nan arcdeg
2022-08-19T21:00:07.115Z,1660942807.115 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltLongitude = nan arcdeg
2022-08-19T21:00:07.130Z,1660942807.130 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChl = 0.000000 ug/l
2022-08-19T21:00:07.133Z,1660942807.133 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlDepth = 0.000000 m
2022-08-19T21:00:07.140Z,1660942807.140 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlLatitude = nan arcdeg
2022-08-19T21:00:07.154Z,1660942807.154 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlLongitude = nan arcdeg
2022-08-19T21:00:07.157Z,1660942807.157 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlDistance = nan m
2022-08-19T21:00:07.168Z,1660942807.168 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSalt = 0.000000 psu
2022-08-19T21:00:07.172Z,1660942807.172 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltDepth = 0.000000 m
2022-08-19T21:00:07.187Z,1660942807.187 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltLatitude = nan arcdeg
2022-08-19T21:00:07.191Z,1660942807.191 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltLongitude = nan arcdeg
2022-08-19T21:00:07.202Z,1660942807.202 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltDistance = nan m
2022-08-19T21:00:07.210Z,1660942807.210 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOil = 0.000000 psu
2022-08-19T21:00:07.213Z,1660942807.213 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilDepth = 0.000000 m
2022-08-19T21:00:07.220Z,1660942807.220 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilLatitude = nan arcdeg
2022-08-19T21:00:07.224Z,1660942807.224 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilLongitude = nan arcdeg
2022-08-19T21:00:07.235Z,1660942807.235 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilDistance = nan m
2022-08-19T21:00:07.314Z,1660942807.314 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-08-19T21:00:07.346Z,1660942807.346 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2022-08-19T21:00:07.371Z,1660942807.371 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-08-19T21:00:07.393Z,1660942807.393 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-08-19T21:00:07.416Z,1660942807.416 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2022-08-19T21:00:07.477Z,1660942807.477 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-08-19T21:00:07.508Z,1660942807.508 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-08-19T21:00:07.536Z,1660942807.536 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2022-08-19T21:00:07.567Z,1660942807.567 [run_backseat_on_surface:B.BackseatDriver](DEBUG): Construct BackseatDriver.
2022-08-19T21:00:07.574Z,1660942807.574 [run_backseat_on_surface:C.GoToSurface](DEBUG): Construct GoToSurface.
2022-08-19T21:00:07.604Z,1660942807.604 [run_backseat_on_surface:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-08-19T21:00:07.607Z,1660942807.607 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-08-19T21:00:07.613Z,1660942807.613 [run_backseat_on_surface:F.Execute](DEBUG): Construct Execute.
2022-08-19T21:00:07.659Z,1660942807.659 [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="run_backseat_on_surface">

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

    <!-- Power the science payload. -->

    <Insert Filename="Insert/Science.xml" Id="Science"></Insert>

    <!-- Power the Backseat payload. -->

    <Guidance:BackseatDriver>
        <Parallel/>
    </Guidance:BackseatDriver>

    <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="P2H">
                <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="ElapsedSinceMissionStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

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

    </Aggregate>

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

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

</Mission>


2022-08-19T21:00:07.670Z,1660942807.670 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/run_backseat_on_surface.xml
2022-08-19T21:00:07.882Z,1660942807.882 [Default] Stopped
2022-08-19T21:00:07.883Z,1660942807.883 [Default](DEBUG): Aggregate::uninitialize Default
2022-08-19T21:00:07.883Z,1660942807.883 [Default:B.GoToSurface] Stopped
2022-08-19T21:00:07.883Z,1660942807.883 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-08-19T21:00:07.883Z,1660942807.883 [Default:CheckIn] Stopped
2022-08-19T21:00:07.883Z,1660942807.883 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-19T21:00:07.883Z,1660942807.883 [Default:CheckIn:C.Wait] Stopped
2022-08-19T21:00:07.883Z,1660942807.883 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-19T21:00:07.883Z,1660942807.883 [MissionManager](IMPORTANT): Started mission run_backseat_on_surface
2022-08-19T21:00:07.883Z,1660942807.883 [run_backseat_on_surface] Running Loop=1
2022-08-19T21:00:07.884Z,1660942807.884 [run_backseat_on_surface](DEBUG): Aggregate::initialize run_backseat_on_surface
2022-08-19T21:00:07.884Z,1660942807.884 [run_backseat_on_surface:Science] Running Loop=1
2022-08-19T21:00:07.884Z,1660942807.884 [run_backseat_on_surface:Science](DEBUG): Aggregate::initialize run_backseat_on_surface:Science
2022-08-19T21:00:07.884Z,1660942807.884 [run_backseat_on_surface:Science:A] Running Loop=1
2022-08-19T21:00:07.884Z,1660942807.884 [run_backseat_on_surface:Science:B] Running Loop=1
2022-08-19T21:00:07.884Z,1660942807.884 [run_backseat_on_surface:Science:C] Running Loop=1
2022-08-19T21:00:07.884Z,1660942807.884 [run_backseat_on_surface:Science:D] Running Loop=1
2022-08-19T21:00:07.884Z,1660942807.884 [run_backseat_on_surface:Science:E] Running Loop=1
2022-08-19T21:00:07.884Z,1660942807.884 [run_backseat_on_surface:Science:F] Running Loop=1
2022-08-19T21:00:07.884Z,1660942807.884 [run_backseat_on_surface:Science:Read_Oil] Running Loop=1
2022-08-19T21:00:07.884Z,1660942807.884 [run_backseat_on_surface:Science:PeakDetectChl] Running Loop=1
2022-08-19T21:00:07.884Z,1660942807.884 [run_backseat_on_surface:Science:PeakDetectChl](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectChl
2022-08-19T21:00:07.884Z,1660942807.884 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2022-08-19T21:00:07.885Z,1660942807.885 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-08-19T21:00:07.891Z,1660942807.891 [run_backseat_on_surface:Science:HighestChlPeakReport] Running Loop=1
2022-08-19T21:00:07.891Z,1660942807.891 [run_backseat_on_surface:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestChlPeakReport
2022-08-19T21:00:07.891Z,1660942807.891 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2022-08-19T21:00:07.892Z,1660942807.892 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2022-08-19T21:00:07.892Z,1660942807.892 [run_backseat_on_surface:Science:PeakDetectNO3] Running Loop=1
2022-08-19T21:00:07.892Z,1660942807.892 [run_backseat_on_surface:Science:PeakDetectNO3](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectNO3
2022-08-19T21:00:07.892Z,1660942807.892 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2022-08-19T21:00:07.892Z,1660942807.892 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-08-19T21:00:07.899Z,1660942807.899 [run_backseat_on_surface:Science:PeakDetectOil] Running Loop=1
2022-08-19T21:00:07.899Z,1660942807.899 [run_backseat_on_surface:Science:PeakDetectOil](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectOil
2022-08-19T21:00:07.899Z,1660942807.899 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2022-08-19T21:00:07.899Z,1660942807.899 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-08-19T21:00:07.901Z,1660942807.901 [run_backseat_on_surface:Science:HighestOilPeakReport] Running Loop=1
2022-08-19T21:00:07.902Z,1660942807.902 [run_backseat_on_surface:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestOilPeakReport
2022-08-19T21:00:07.902Z,1660942807.902 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2022-08-19T21:00:07.902Z,1660942807.902 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2022-08-19T21:00:07.902Z,1660942807.902 [run_backseat_on_surface:Science:PeakDetectFDOM] Running Loop=1
2022-08-19T21:00:07.902Z,1660942807.902 [run_backseat_on_surface:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectFDOM
2022-08-19T21:00:07.902Z,1660942807.902 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2022-08-19T21:00:07.903Z,1660942807.903 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-08-19T21:00:07.904Z,1660942807.904 [run_backseat_on_surface:Science:PeakDetectSalinity] Running Loop=1
2022-08-19T21:00:07.904Z,1660942807.904 [run_backseat_on_surface:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectSalinity
2022-08-19T21:00:07.905Z,1660942807.905 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2022-08-19T21:00:07.905Z,1660942807.905 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-08-19T21:00:07.910Z,1660942807.910 [run_backseat_on_surface:Science:HighestSaltPeakReport] Running Loop=1
2022-08-19T21:00:07.910Z,1660942807.910 [run_backseat_on_surface:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestSaltPeakReport
2022-08-19T21:00:07.911Z,1660942807.911 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2022-08-19T21:00:07.911Z,1660942807.911 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2022-08-19T21:00:07.911Z,1660942807.911 [run_backseat_on_surface:Science:OceanCurrent] Running Loop=1
2022-08-19T21:00:07.911Z,1660942807.911 [run_backseat_on_surface:Science:OceanCurrent](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:OceanCurrent
2022-08-19T21:00:07.911Z,1660942807.911 [run_backseat_on_surface:Science:OceanCurrent:A.] Running Loop=1
2022-08-19T21:00:07.912Z,1660942807.912 [run_backseat_on_surface:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2022-08-19T21:00:07.912Z,1660942807.912 [run_backseat_on_surface:B.BackseatDriver] Running Loop=1
2022-08-19T21:00:07.912Z,1660942807.912 [run_backseat_on_surface:B.BackseatDriver](DEBUG): Initialize.
2022-08-19T21:00:08.001Z,1660942808.001 [BackseatComponent](INFO): Powering up
2022-08-19T21:00:08.002Z,1660942808.002 [BackseatComponent](INFO): Subscribing to LCM channels.
2022-08-19T21:00:08.030Z,1660942808.030 [run_backseat_on_surface:C.GoToSurface] Running Loop=1
2022-08-19T21:00:08.030Z,1660942808.030 [run_backseat_on_surface:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-08-19T21:00:08.031Z,1660942808.031 [run_backseat_on_surface:C.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-08-19T21:00:08.031Z,1660942808.031 [run_backseat_on_surface:C.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-08-19T21:00:08.031Z,1660942808.031 [run_backseat_on_surface:C.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-08-19T21:00:08.032Z,1660942808.032 [run_backseat_on_surface:C.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-08-19T21:00:08.032Z,1660942808.032 [run_backseat_on_surface:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-08-19T21:00:08.041Z,1660942808.041 [run_backseat_on_surface:CheckIn] Running Loop=1
2022-08-19T21:00:08.045Z,1660942808.045 [run_backseat_on_surface:CheckIn](DEBUG): Aggregate::initialize run_backseat_on_surface:CheckIn
2022-08-19T21:00:08.045Z,1660942808.045 [run_backseat_on_surface:CheckIn:Read_GPS] Running Loop=1
2022-08-19T21:00:08.045Z,1660942808.045 [run_backseat_on_surface:B.BackseatDriver] Running Loop=1
2022-08-19T21:00:08.049Z,1660942808.049 [run_backseat_on_surface:Science] Running Loop=1
2022-08-19T21:00:08.050Z,1660942808.050 [run_backseat_on_surface:Science:HighestSaltPeakReport] Stopped
2022-08-19T21:00:08.050Z,1660942808.050 [run_backseat_on_surface:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestSaltPeakReport
2022-08-19T21:00:08.051Z,1660942808.051 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2022-08-19T21:00:08.051Z,1660942808.051 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2022-08-19T21:00:08.051Z,1660942808.051 [run_backseat_on_surface:Science:PeakDetectSalinity] Stopped
2022-08-19T21:00:08.051Z,1660942808.051 [run_backseat_on_surface:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectSalinity
2022-08-19T21:00:08.051Z,1660942808.051 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2022-08-19T21:00:08.051Z,1660942808.051 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-08-19T21:00:08.051Z,1660942808.051 [run_backseat_on_surface:Science:PeakDetectFDOM] Stopped
2022-08-19T21:00:08.051Z,1660942808.051 [run_backseat_on_surface:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectFDOM
2022-08-19T21:00:08.051Z,1660942808.051 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2022-08-19T21:00:08.051Z,1660942808.051 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-08-19T21:00:08.051Z,1660942808.051 [run_backseat_on_surface:Science:HighestOilPeakReport] Stopped
2022-08-19T21:00:08.051Z,1660942808.051 [run_backseat_on_surface:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestOilPeakReport
2022-08-19T21:00:08.051Z,1660942808.051 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2022-08-19T21:00:08.052Z,1660942808.052 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2022-08-19T21:00:08.052Z,1660942808.052 [run_backseat_on_surface:Science:PeakDetectOil] Stopped
2022-08-19T21:00:08.052Z,1660942808.052 [run_backseat_on_surface:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectOil
2022-08-19T21:00:08.052Z,1660942808.052 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2022-08-19T21:00:08.052Z,1660942808.052 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-08-19T21:00:08.052Z,1660942808.052 [run_backseat_on_surface:Science:PeakDetectNO3] Stopped
2022-08-19T21:00:08.052Z,1660942808.052 [run_backseat_on_surface:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectNO3
2022-08-19T21:00:08.052Z,1660942808.052 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2022-08-19T21:00:08.052Z,1660942808.052 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-08-19T21:00:08.052Z,1660942808.052 [run_backseat_on_surface:Science:HighestChlPeakReport] Stopped
2022-08-19T21:00:08.052Z,1660942808.052 [run_backseat_on_surface:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestChlPeakReport
2022-08-19T21:00:08.052Z,1660942808.052 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2022-08-19T21:00:08.053Z,1660942808.053 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2022-08-19T21:00:08.053Z,1660942808.053 [run_backseat_on_surface:Science:PeakDetectChl] Stopped
2022-08-19T21:00:08.053Z,1660942808.053 [run_backseat_on_surface:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectChl
2022-08-19T21:00:08.053Z,1660942808.053 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2022-08-19T21:00:08.053Z,1660942808.053 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-08-19T21:00:08.053Z,1660942808.053 [run_backseat_on_surface:Science:OceanCurrent:A.] Running Loop=1
2022-08-19T21:00:08.053Z,1660942808.053 [run_backseat_on_surface:Science:Read_Oil] Stopped
2022-08-19T21:00:08.054Z,1660942808.054 [run_backseat_on_surface:Science:F] Stopped
2022-08-19T21:00:08.054Z,1660942808.054 [run_backseat_on_surface:Science:E] Running Loop=1
2022-08-19T21:00:08.054Z,1660942808.054 [run_backseat_on_surface:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2022-08-19T21:00:08.054Z,1660942808.054 [run_backseat_on_surface:Science:D] Stopped
2022-08-19T21:00:08.054Z,1660942808.054 [run_backseat_on_surface:Science:C] Running Loop=1
2022-08-19T21:00:08.055Z,1660942808.055 [run_backseat_on_surface:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2022-08-19T21:00:08.056Z,1660942808.056 [run_backseat_on_surface:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2022-08-19T21:00:08.056Z,1660942808.056 [run_backseat_on_surface:Science:B] Running Loop=1
2022-08-19T21:00:08.056Z,1660942808.056 [run_backseat_on_surface:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2022-08-19T21:00:08.057Z,1660942808.057 [run_backseat_on_surface:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2022-08-19T21:00:08.057Z,1660942808.057 [run_backseat_on_surface:Science:A] Stopped
2022-08-19T21:00:08.290Z,1660942808.290 [run_backseat_on_surface:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-08-19T21:00:08.358Z,1660942808.358 [WetLabsBB2FL](INFO): Powering up
2022-08-19T21:00:11.267Z,1660942811.267 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2022-08-19T21:01:04.078Z,1660942864.078 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2022-08-19T21:01:04.078Z,1660942864.078 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-08-19T21:01:04.091Z,1660942864.091 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-08-19T21:01:04.499Z,1660942864.499 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-08-19T21:01:04.499Z,1660942864.499 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2022-08-19T21:04:05.226Z,1660943045.226 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2022-08-19T21:04:05.226Z,1660943045.226 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-08-19T21:04:05.242Z,1660943045.242 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-08-19T21:04:05.646Z,1660943045.646 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-08-19T21:04:05.646Z,1660943045.646 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2022-08-19T21:04:11.351Z,1660943051.351 [CBIT](INFO): Clearing failed state for component BPC1
2022-08-19T21:04:11.351Z,1660943051.351 [BPC1] No Fault, FailCount= 1
2022-08-19T21:05:08.246Z,1660943108.246 [run_backseat_on_surface:CheckIn:Read_GPS](INFO): Timed out from 2022-08-19T21:00:08.0Z
2022-08-19T21:05:08.246Z,1660943108.246 [run_backseat_on_surface:CheckIn:Read_GPS] Stopped
2022-08-19T21:05:08.246Z,1660943108.246 [run_backseat_on_surface:CheckIn:Read_Iridium] Running Loop=1
2022-08-19T21:05:08.662Z,1660943108.662 [run_backseat_on_surface:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-08-19T21:05:15.449Z,1660943115.449 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220819T204548/Courier0007.lzma
2022-08-19T21:05:16.450Z,1660943116.450 [DataOverHttps](INFO): Moved sent file to Logs/20220819T204548/Courier0007.lzma.bak
2022-08-19T21:05:16.450Z,1660943116.450 [DataOverHttps](INFO): SBD MOMSN=17115570
2022-08-19T21:05:23.536Z,1660943123.536 [BackseatComponent](FAULT): Failed to receive device response within the specified timeout.
2022-08-19T21:05:23.536Z,1660943123.536 [BackseatComponent] Communications Fault, FailCount= 1
2022-08-19T21:05:23.536Z,1660943123.536 [BackseatComponent](ERROR): Communications Fault
2022-08-19T21:05:23.663Z,1660943123.663 [CBIT](ERROR): Communications Fault in component: BackseatComponent
2022-08-19T21:05:23.946Z,1660943123.946 [BackseatComponent](INFO): Sent LCM shutdown request.
2022-08-19T21:05:33.379Z,1660943133.379 [DataOverHttps](INFO): Sending 470 bytes from file Logs/20220819T204548/Express0008.lzma
2022-08-19T21:05:34.378Z,1660943134.378 [DataOverHttps](INFO): Moved sent file to Logs/20220819T204548/Express0008.lzma.bak
2022-08-19T21:05:34.378Z,1660943134.378 [DataOverHttps](INFO): SBD MOMSN=17115572
2022-08-19T21:05:36.570Z,1660943136.570 [run_backseat_on_surface:CheckIn:Read_Iridium] Stopped
2022-08-19T21:05:36.570Z,1660943136.570 [run_backseat_on_surface:CheckIn:C.Wait] Running Loop=1
2022-08-19T21:05:36.570Z,1660943136.570 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-19T21:05:39.297Z,1660943139.297 [BackseatComponent](INFO): Powering down and starting shutdown timer (15.00 sec).
2022-08-19T21:05:39.297Z,1660943139.297 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2022-08-19T21:05:39.298Z,1660943139.298 [LcmUniversalReporter](INFO): Deactivating messaging.
2022-08-19T21:05:55.057Z,1660943155.057 [BackseatComponent](INFO): Powering up
2022-08-19T21:05:55.057Z,1660943155.057 [BackseatComponent](INFO): Subscribing to LCM channels.
2022-08-19T21:05:55.158Z,1660943155.158 [CBIT](INFO): Clearing failed state for component BackseatComponent
2022-08-19T21:05:55.158Z,1660943155.158 [BackseatComponent] No Fault, FailCount= 1
2022-08-19T21:06:32.807Z,1660943192.807 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T21:06:37.683Z,1660943197.683 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-08-19T21:06:44.149Z,1660943204.149 [BackseatComponent](IMPORTANT): IBASE917-01: Running bioacoustics backseat app. Logging to: 20220819T210646
2022-08-19T21:06:46.855Z,1660943206.855 [BackseatComponent](INFO): Created universal data reader for depth with code: 6
2022-08-19T21:06:46.855Z,1660943206.855 [BackseatComponent](INFO): Requesting data from depth (6). Requested size is: 1
2022-08-19T21:06:46.863Z,1660943206.863 [BackseatComponent](INFO): Created universal data reader for latitude with code: 23
2022-08-19T21:06:46.863Z,1660943206.863 [BackseatComponent](INFO): Requesting data from latitude (23). Requested size is: 2
2022-08-19T21:06:46.869Z,1660943206.869 [BackseatComponent](INFO): Created universal data reader for longitude with code: 26
2022-08-19T21:06:46.869Z,1660943206.869 [BackseatComponent](INFO): Requesting data from longitude (26). Requested size is: 3
2022-08-19T21:06:46.871Z,1660943206.871 [BackseatComponent](INFO): Created universal data reader for platform_orientation with code: 52
2022-08-19T21:06:46.904Z,1660943206.904 [BackseatComponent](INFO): Requesting data from platform_orientation (52). Requested size is: 4
2022-08-19T21:06:46.912Z,1660943206.912 [BackseatComponent](INFO): Created universal data reader for platform_pitch_angle with code: 54
2022-08-19T21:06:46.918Z,1660943206.918 [BackseatComponent](INFO): Requesting data from platform_pitch_angle (54). Requested size is: 5
2022-08-19T21:06:46.923Z,1660943206.923 [BackseatComponent](INFO): Created universal data reader for platform_roll_angle with code: 59
2022-08-19T21:06:46.924Z,1660943206.924 [BackseatComponent](INFO): Requesting data from platform_roll_angle (59). Requested size is: 6
2022-08-19T21:06:46.931Z,1660943206.931 [BackseatComponent](INFO): Created universal data reader for platform_x_velocity_wrt_sea_water with code: 70
2022-08-19T21:06:46.932Z,1660943206.932 [BackseatComponent](INFO): Requesting data from platform_x_velocity_wrt_sea_water (70). Requested size is: 7
2022-08-19T21:07:06.287Z,1660943226.287 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2022-08-19T21:07:06.287Z,1660943226.287 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-08-19T21:07:06.306Z,1660943226.306 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-08-19T21:07:06.706Z,1660943226.706 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-08-19T21:07:06.706Z,1660943226.706 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2022-08-19T21:07:21.258Z,1660943241.258 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T21:07:22.427Z,1660943242.427 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-08-19T21:07:22.427Z,1660943242.427 [NAL9602] Data Fault, FailCount= 2
2022-08-19T21:07:22.427Z,1660943242.427 [NAL9602](ERROR): Data Fault
2022-08-19T21:07:22.446Z,1660943242.446 [CBIT](ERROR): Data Fault in component: NAL9602
2022-08-19T21:07:22.835Z,1660943242.835 [NAL9602](INFO): Powering down
2022-08-19T21:07:23.658Z,1660943243.658 [CBIT](INFO): Clearing failed state for component NAL9602
2022-08-19T21:07:23.658Z,1660943243.658 [NAL9602] No Fault, FailCount= 2
2022-08-19T21:07:32.187Z,1660943252.187 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T21:07:43.097Z,1660943263.097 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T21:07:53.129Z,1660943273.129 [NAL9602](INFO): Powering up NAL9602
2022-08-19T21:07:58.777Z,1660943278.777 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T21:08:04.045Z,1660943284.045 [NAL9602](INFO): NAL9602 initialized
2022-08-19T21:08:07.637Z,1660943287.637 [CommandExec](IMPORTANT): got command stop
2022-08-19T21:08:07.637Z,1660943287.637 [CommandExec](IMPORTANT): Scheduling is paused
2022-08-19T21:08:07.637Z,1660943287.637 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2022-08-19T21:08:07.717Z,1660943287.717 [MissionManager](INFO): MissionManager is completed.
2022-08-19T21:08:07.717Z,1660943287.717 [MissionManager](INFO): Uninitializing Mission run_backseat_on_surface
2022-08-19T21:08:07.718Z,1660943287.718 [run_backseat_on_surface] Stopped
2022-08-19T21:08:07.718Z,1660943287.718 [run_backseat_on_surface](DEBUG): Aggregate::uninitialize run_backseat_on_surface
2022-08-19T21:08:07.718Z,1660943287.718 [run_backseat_on_surface:Science] Stopped
2022-08-19T21:08:07.718Z,1660943287.718 [run_backseat_on_surface:Science](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science
2022-08-19T21:08:07.718Z,1660943287.718 [run_backseat_on_surface:Science:B] Stopped
2022-08-19T21:08:07.718Z,1660943287.718 [run_backseat_on_surface:Science:C] Stopped
2022-08-19T21:08:07.718Z,1660943287.718 [run_backseat_on_surface:Science:E] Stopped
2022-08-19T21:08:07.718Z,1660943287.718 [run_backseat_on_surface:Science:OceanCurrent] Stopped
2022-08-19T21:08:07.718Z,1660943287.718 [run_backseat_on_surface:Science:OceanCurrent](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:OceanCurrent
2022-08-19T21:08:07.718Z,1660943287.718 [run_backseat_on_surface:Science:OceanCurrent:A.] Stopped
2022-08-19T21:08:07.718Z,1660943287.718 [run_backseat_on_surface:B.BackseatDriver] Stopped
2022-08-19T21:08:07.718Z,1660943287.718 [run_backseat_on_surface:B.BackseatDriver](DEBUG): Uninitialize.
2022-08-19T21:08:07.719Z,1660943287.719 [run_backseat_on_surface:C.GoToSurface] Stopped
2022-08-19T21:08:07.719Z,1660943287.719 [run_backseat_on_surface:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-08-19T21:08:07.719Z,1660943287.719 [run_backseat_on_surface:CheckIn] Stopped
2022-08-19T21:08:07.719Z,1660943287.719 [run_backseat_on_surface:CheckIn](DEBUG): Aggregate::uninitialize run_backseat_on_surface:CheckIn
2022-08-19T21:08:07.719Z,1660943287.719 [run_backseat_on_surface:CheckIn:C.Wait] Stopped
2022-08-19T21:08:07.719Z,1660943287.719 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-19T21:08:07.824Z,1660943287.824 [WetLabsBB2FL](INFO): Powering down
2022-08-19T21:08:07.933Z,1660943287.933 [BackseatComponent](INFO): Sent LCM shutdown request.
2022-08-19T21:08:08.087Z,1660943288.087 [MissionManager](IMPORTANT): Started mission Default
2022-08-19T21:08:08.087Z,1660943288.087 [Default] Running Loop=1
2022-08-19T21:08:08.087Z,1660943288.087 [Default](DEBUG): Aggregate::initialize Default
2022-08-19T21:08:08.087Z,1660943288.087 [Default:B.GoToSurface] Running Loop=1
2022-08-19T21:08:08.087Z,1660943288.087 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-08-19T21:08:08.088Z,1660943288.088 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-08-19T21:08:08.088Z,1660943288.088 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-08-19T21:08:08.089Z,1660943288.089 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-08-19T21:08:08.089Z,1660943288.089 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-08-19T21:08:08.089Z,1660943288.089 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-08-19T21:08:08.090Z,1660943288.090 [Default:A.Wait] Running Loop=1
2022-08-19T21:08:08.090Z,1660943288.090 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-08-19T21:08:09.755Z,1660943289.755 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-08-19T21:08:14.921Z,1660943294.921 [CommandExec](IMPORTANT): got command quit
2022-08-19T21:08:15.924Z,1660943295.924 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-19T21:08:15.924Z,1660943295.924 [CommandExec](INFO): Uninitializing the command executive.
2022-08-19T21:08:15.924Z,1660943295.924 [CommandExec](INFO): Uninitializing the command scheduler.
2022-08-19T21:08:15.925Z,1660943295.925 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:16.084Z,1660943296.084 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-08-19T21:08:16.084Z,1660943296.084 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-08-19T21:08:16.084Z,1660943296.084 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:16.085Z,1660943296.085 [NavChartDb](INFO): Join timeout helper Thread ID is 1074
2022-08-19T21:08:16.401Z,1660943296.401 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-19T21:08:16.401Z,1660943296.401 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:16.412Z,1660943296.412 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-08-19T21:08:16.412Z,1660943296.412 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:16.412Z,1660943296.412 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1075
2022-08-19T21:08:16.800Z,1660943296.800 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-19T21:08:16.801Z,1660943296.801 [WetLabsBB2FL](INFO): Powering down
2022-08-19T21:08:16.801Z,1660943296.801 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:16.820Z,1660943296.820 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-08-19T21:08:16.820Z,1660943296.820 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:16.820Z,1660943296.820 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1076
2022-08-19T21:08:17.572Z,1660943297.572 [CTD_Seabird](INFO): Powering down
2022-08-19T21:08:17.584Z,1660943297.584 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-19T21:08:17.585Z,1660943297.585 [CTD_Seabird](INFO): Powering down
2022-08-19T21:08:17.609Z,1660943297.609 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:17.624Z,1660943297.624 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2022-08-19T21:08:17.624Z,1660943297.624 [DAT ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:17.625Z,1660943297.625 [DAT](INFO): Join timeout helper Thread ID is 1080
2022-08-19T21:08:17.660Z,1660943297.660 [DAT](INFO): Powering down
2022-08-19T21:08:17.733Z,1660943297.733 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-19T21:08:17.733Z,1660943297.733 [DAT](INFO): Powering down
2022-08-19T21:08:17.734Z,1660943297.734 [DAT ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:17.752Z,1660943297.752 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-08-19T21:08:17.752Z,1660943297.752 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:17.753Z,1660943297.753 [Radio_Surface](INFO): Join timeout helper Thread ID is 1081
2022-08-19T21:08:17.980Z,1660943297.980 [Radio_Surface](INFO): Powering down
2022-08-19T21:08:17.981Z,1660943297.981 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-19T21:08:17.982Z,1660943297.982 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:17.997Z,1660943297.997 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-08-19T21:08:17.998Z,1660943297.998 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:17.998Z,1660943297.998 [Onboard](INFO): Join timeout helper Thread ID is 1082
2022-08-19T21:08:18.236Z,1660943298.236 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-08-19T21:08:19.965Z,1660943299.965 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-19T21:08:19.965Z,1660943299.965 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:19.973Z,1660943299.973 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-08-19T21:08:19.973Z,1660943299.973 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:19.973Z,1660943299.973 [DataOverHttps](INFO): Join timeout helper Thread ID is 1083
2022-08-19T21:08:20.244Z,1660943300.244 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-19T21:08:20.245Z,1660943300.245 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:20.262Z,1660943300.262 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-08-19T21:08:20.262Z,1660943300.262 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:20.263Z,1660943300.263 [BackseatComponent](INFO): Join timeout helper Thread ID is 1084
2022-08-19T21:08:20.376Z,1660943300.376 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-19T21:08:20.377Z,1660943300.377 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:20.389Z,1660943300.389 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-08-19T21:08:20.389Z,1660943300.389 [logger ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:20.389Z,1660943300.389 [logger](INFO): Join timeout helper Thread ID is 1085
2022-08-19T21:08:20.392Z,1660943300.392 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-19T21:08:20.393Z,1660943300.393 [logger ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:20.409Z,1660943300.409 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-08-19T21:08:20.409Z,1660943300.409 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:20.410Z,1660943300.410 [CommandLine](INFO): Join timeout helper Thread ID is 1086
2022-08-19T21:08:20.508Z,1660943300.508 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-19T21:08:20.509Z,1660943300.509 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:20.510Z,1660943300.510 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-08-19T21:08:20.510Z,1660943300.510 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:20.511Z,1660943300.511 [CommandExec](INFO): Join timeout helper Thread ID is 1087
2022-08-19T21:08:20.512Z,1660943300.512 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-08-19T21:08:20.512Z,1660943300.512 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:20.512Z,1660943300.512 [controlThread](INFO): Join timeout helper Thread ID is 1088
2022-08-19T21:08:20.584Z,1660943300.584 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-19T21:08:20.585Z,1660943300.585 [controlThread](DEBUG): Uninitializing ControlThread
2022-08-19T21:08:20.585Z,1660943300.585 [AHRS_M2](INFO): Powering down
2022-08-19T21:08:20.657Z,1660943300.657 [NAL9602](INFO): Powering down
2022-08-19T21:08:20.659Z,1660943300.659 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-08-19T21:08:20.660Z,1660943300.660 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-08-19T21:08:20.661Z,1660943300.661 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-08-19T21:08:20.661Z,1660943300.661 [MissionManager](INFO): Uninitializing Mission Default
2022-08-19T21:08:20.661Z,1660943300.661 [Default] Stopped
2022-08-19T21:08:20.661Z,1660943300.661 [Default](DEBUG): Aggregate::uninitialize Default
2022-08-19T21:08:20.661Z,1660943300.661 [Default:A.Wait] Stopped
2022-08-19T21:08:20.661Z,1660943300.661 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-08-19T21:08:20.662Z,1660943300.662 [Default:B.GoToSurface] Stopped
2022-08-19T21:08:20.662Z,1660943300.662 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-08-19T21:08:20.664Z,1660943300.664 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-08-19T21:08:20.665Z,1660943300.665 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-08-19T21:08:20.665Z,1660943300.665 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-08-19T21:08:20.665Z,1660943300.665 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-08-19T21:08:20.665Z,1660943300.665 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-08-19T21:08:20.666Z,1660943300.666 [BuoyancyServo](INFO): Powering down
2022-08-19T21:08:20.680Z,1660943300.680 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-08-19T21:08:20.680Z,1660943300.680 [ElevatorServo](INFO): Powering down
2022-08-19T21:08:20.681Z,1660943300.681 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-08-19T21:08:20.681Z,1660943300.681 [MassServo](INFO): Powering down
2022-08-19T21:08:20.682Z,1660943300.682 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-08-19T21:08:20.682Z,1660943300.682 [RudderServo](INFO): Powering down
2022-08-19T21:08:20.683Z,1660943300.683 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-08-19T21:08:20.683Z,1660943300.683 [ThrusterHE](INFO): Powering down
2022-08-19T21:08:20.684Z,1660943300.684 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-08-19T21:08:20.685Z,1660943300.685 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-08-19T21:08:20.685Z,1660943300.685 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-08-19T21:08:20.685Z,1660943300.685 [CBIT](DEBUG): Powering off loads.
2022-08-19T21:08:20.696Z,1660943300.696 [CBIT](DEBUG): Disabling WDT.
2022-08-19T21:08:20.708Z,1660943300.708 [CBIT](DEBUG): Opening all GF detection circuits.
2022-08-19T21:08:20.709Z,1660943300.709 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:20.767Z,1660943300.767 [DAT ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:20.777Z,1660943300.777 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:20.778Z,1660943300.778 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:20.785Z,1660943300.785 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:20.853Z,1660943300.853 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:20.905Z,1660943300.905 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:20.915Z,1660943300.915 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:20.963Z,1660943300.963 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-08-19T21:08:21.023Z,1660943301.023 [logger ThreadHandler](INFO): Thread cancelled.