2021-07-23T17:51:26.458Z,1627062686.458 [Supervisor](DEBUG): Initializing supervisor.
2021-07-23T17:51:26.462Z,1627062686.462 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-07-23T17:51:26.463Z,1627062686.463 [SyncHandler](INFO): Protected caller Thread ID is 1362
2021-07-23T17:51:26.463Z,1627062686.463 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-07-23T17:51:26.464Z,1627062686.464 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-07-23T17:51:26.465Z,1627062686.465 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1363
2021-07-23T17:51:26.469Z,1627062686.469 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-07-23T17:51:26.490Z,1627062686.490 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-07-23T17:51:26.491Z,1627062686.491 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-07-23T17:51:26.492Z,1627062686.492 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1364
2021-07-23T17:51:26.494Z,1627062686.494 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-07-23T17:51:26.495Z,1627062686.495 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-07-23T17:51:26.495Z,1627062686.495 [logger ThreadHandler](INFO): Protected caller Thread ID is 1365
2021-07-23T17:51:26.500Z,1627062686.500 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-07-23T17:51:26.500Z,1627062686.500 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-07-23T17:51:26.502Z,1627062686.502 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-07-23T17:51:26.736Z,1627062686.736 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-07-23T17:51:26.737Z,1627062686.737 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-07-23T17:51:26.817Z,1627062686.817 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-07-23T17:51:27.378Z,1627062687.378 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-07-23T17:51:27.379Z,1627062687.379 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-07-23T17:51:27.860Z,1627062687.860 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-07-23T17:51:27.861Z,1627062687.861 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-07-23T17:51:27.957Z,1627062687.957 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-07-23T17:51:27.958Z,1627062687.958 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-07-23T17:51:28.345Z,1627062688.345 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-07-23T17:51:28.345Z,1627062688.345 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-07-23T17:51:28.551Z,1627062688.551 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-07-23T17:51:28.552Z,1627062688.552 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-07-23T17:51:29.030Z,1627062689.030 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-07-23T17:51:29.031Z,1627062689.031 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-07-23T17:51:29.159Z,1627062689.159 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-07-23T17:51:29.159Z,1627062689.159 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-07-23T17:51:29.238Z,1627062689.238 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-07-23T17:51:29.931Z,1627062689.931 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-07-23T17:51:29.931Z,1627062689.931 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-07-23T17:51:30.309Z,1627062690.309 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-07-23T17:51:30.310Z,1627062690.310 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-07-23T17:51:30.511Z,1627062690.511 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-07-23T17:51:30.511Z,1627062690.511 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-07-23T17:51:30.624Z,1627062690.624 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-07-23T17:51:30.624Z,1627062690.624 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-07-23T17:51:30.780Z,1627062690.780 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-07-23T17:51:30.781Z,1627062690.781 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-07-23T17:51:31.020Z,1627062691.020 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-07-23T17:51:31.022Z,1627062691.022 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2021-07-23T17:51:31.023Z,1627062691.023 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2021-07-23T17:51:31.114Z,1627062691.114 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2021-07-23T17:51:31.200Z,1627062691.200 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2021-07-23T17:51:31.303Z,1627062691.303 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2021-07-23T17:51:31.387Z,1627062691.387 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2021-07-23T17:51:31.485Z,1627062691.485 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2021-07-23T17:51:31.589Z,1627062691.589 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2021-07-23T17:51:31.787Z,1627062691.787 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2021-07-23T17:51:31.862Z,1627062691.862 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2021-07-23T17:51:32.016Z,1627062692.016 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2021-07-23T17:51:32.159Z,1627062692.159 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2021-07-23T17:51:32.277Z,1627062692.277 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2021-07-23T17:51:32.616Z,1627062692.616 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-07-23T17:51:32.617Z,1627062692.617 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-07-23T17:51:32.627Z,1627062692.627 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-07-23T17:51:32.751Z,1627062692.751 [VerticalControl](DEBUG): Construct VerticalControl.
2021-07-23T17:51:33.013Z,1627062693.013 [VerticalControl] Loaded
2021-07-23T17:51:33.013Z,1627062693.013 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-07-23T17:51:33.016Z,1627062693.016 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-07-23T17:51:33.074Z,1627062693.074 [HorizontalControl] Loaded
2021-07-23T17:51:33.075Z,1627062693.075 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-07-23T17:51:33.116Z,1627062693.116 [SpeedControl](DEBUG): Construct SpeedControl.
2021-07-23T17:51:33.119Z,1627062693.119 [SpeedControl] Loaded
2021-07-23T17:51:33.120Z,1627062693.120 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-07-23T17:51:33.122Z,1627062693.122 [LoopControl](DEBUG): Construct LoopControl.
2021-07-23T17:51:33.123Z,1627062693.123 [LoopControl] Loaded
2021-07-23T17:51:33.123Z,1627062693.123 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-07-23T17:51:33.124Z,1627062693.124 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-07-23T17:51:33.124Z,1627062693.124 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-07-23T17:51:33.136Z,1627062693.136 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-07-23T17:51:33.154Z,1627062693.154 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-07-23T17:51:33.331Z,1627062693.331 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-07-23T17:51:33.331Z,1627062693.331 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-07-23T17:51:33.592Z,1627062693.592 [BuoyancyServo] Loaded
2021-07-23T17:51:33.593Z,1627062693.593 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-07-23T17:51:33.613Z,1627062693.613 [ElevatorServo] Loaded
2021-07-23T17:51:33.613Z,1627062693.613 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-07-23T17:51:33.632Z,1627062693.632 [MassServo] Loaded
2021-07-23T17:51:33.632Z,1627062693.632 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-07-23T17:51:33.651Z,1627062693.651 [RudderServo] Loaded
2021-07-23T17:51:33.651Z,1627062693.651 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-07-23T17:51:33.670Z,1627062693.670 [ThrusterServo] Loaded
2021-07-23T17:51:33.670Z,1627062693.670 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-07-23T17:51:33.670Z,1627062693.670 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-07-23T17:51:33.671Z,1627062693.671 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-07-23T17:51:33.754Z,1627062693.754 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-07-23T17:51:33.755Z,1627062693.755 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-07-23T17:51:33.768Z,1627062693.768 [NavChart] Loaded
2021-07-23T17:51:33.769Z,1627062693.769 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-07-23T17:51:33.775Z,1627062693.775 [UniversalFixResidualReporter] Loaded
2021-07-23T17:51:33.775Z,1627062693.775 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-07-23T17:51:33.775Z,1627062693.775 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-07-23T17:51:33.776Z,1627062693.776 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-07-23T17:51:33.836Z,1627062693.836 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-07-23T17:51:33.836Z,1627062693.836 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-07-23T17:51:34.213Z,1627062694.213 [AHRS_M2] Loaded
2021-07-23T17:51:34.213Z,1627062694.213 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-07-23T17:51:35.031Z,1627062695.031 [BPC1] Loaded
2021-07-23T17:51:35.032Z,1627062695.032 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-07-23T17:51:35.107Z,1627062695.107 [DataOverHttps] Loaded
2021-07-23T17:51:35.107Z,1627062695.107 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-07-23T17:51:35.108Z,1627062695.108 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408854E0
2021-07-23T17:51:35.109Z,1627062695.109 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1450
2021-07-23T17:51:35.129Z,1627062695.129 [Depth_Keller] Loaded
2021-07-23T17:51:35.129Z,1627062695.129 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-07-23T17:51:35.134Z,1627062695.134 [DropWeight] Loaded
2021-07-23T17:51:35.134Z,1627062695.134 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2021-07-23T17:51:35.195Z,1627062695.195 [NAL9602] Loaded
2021-07-23T17:51:35.195Z,1627062695.195 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-07-23T17:51:35.224Z,1627062695.224 [Onboard] Loaded
2021-07-23T17:51:35.225Z,1627062695.225 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-07-23T17:51:35.226Z,1627062695.226 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408B54E0
2021-07-23T17:51:35.226Z,1627062695.226 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1451
2021-07-23T17:51:35.239Z,1627062695.239 [Power24vConverter] Loaded
2021-07-23T17:51:35.239Z,1627062695.239 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2021-07-23T17:51:35.252Z,1627062695.252 [Radio_Surface] Loaded
2021-07-23T17:51:35.253Z,1627062695.253 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-07-23T17:51:35.254Z,1627062695.254 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408E54E0
2021-07-23T17:51:35.254Z,1627062695.254 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1452
2021-07-23T17:51:35.255Z,1627062695.255 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-07-23T17:51:35.256Z,1627062695.256 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-07-23T17:51:35.299Z,1627062695.299 [DepthRateCalculator] Loaded
2021-07-23T17:51:35.299Z,1627062695.299 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-07-23T17:51:35.305Z,1627062695.305 [PitchRateCalculator] Loaded
2021-07-23T17:51:35.305Z,1627062695.305 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-07-23T17:51:35.315Z,1627062695.315 [SpeedCalculator] Loaded
2021-07-23T17:51:35.315Z,1627062695.315 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-07-23T17:51:35.329Z,1627062695.329 [TempGradientCalculator] Loaded
2021-07-23T17:51:35.330Z,1627062695.330 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-07-23T17:51:35.334Z,1627062695.334 [YawRateCalculator] Loaded
2021-07-23T17:51:35.334Z,1627062695.334 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-07-23T17:51:35.354Z,1627062695.354 [ElevatorOffsetCalculator] Loaded
2021-07-23T17:51:35.354Z,1627062695.354 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-07-23T17:51:35.354Z,1627062695.354 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-07-23T17:51:35.355Z,1627062695.355 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-07-23T17:51:35.498Z,1627062695.498 [CANONSampler] Loaded
2021-07-23T17:51:35.498Z,1627062695.498 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2021-07-23T17:51:35.558Z,1627062695.558 [CTD_Seabird] Loaded
2021-07-23T17:51:35.558Z,1627062695.558 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-07-23T17:51:35.559Z,1627062695.559 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4099A4E0
2021-07-23T17:51:35.560Z,1627062695.560 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1453
2021-07-23T17:51:35.580Z,1627062695.580 [PAR_Licor] Loaded
2021-07-23T17:51:35.581Z,1627062695.581 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-07-23T17:51:35.614Z,1627062695.614 [WetLabsBB2FL] Loaded
2021-07-23T17:51:35.614Z,1627062695.614 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-07-23T17:51:35.615Z,1627062695.615 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409CA4E0
2021-07-23T17:51:35.616Z,1627062695.616 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1454
2021-07-23T17:51:35.617Z,1627062695.617 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-07-23T17:51:35.617Z,1627062695.617 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-07-23T17:51:35.646Z,1627062695.646 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-07-23T17:51:35.647Z,1627062695.647 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-07-23T17:51:35.987Z,1627062695.987 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-07-23T17:51:35.988Z,1627062695.988 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-07-23T17:51:36.144Z,1627062696.144 [SBIT](DEBUG): Construct Startup Built In Test.
2021-07-23T17:51:36.153Z,1627062696.153 [SBIT] Loaded
2021-07-23T17:51:36.154Z,1627062696.154 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-07-23T17:51:36.157Z,1627062696.157 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-07-23T17:51:36.171Z,1627062696.171 [IBIT] Loaded
2021-07-23T17:51:36.171Z,1627062696.171 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-07-23T17:51:36.177Z,1627062696.177 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-07-23T17:51:36.282Z,1627062696.282 [CBIT] Loaded
2021-07-23T17:51:36.282Z,1627062696.282 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-07-23T17:51:36.283Z,1627062696.283 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-07-23T17:51:36.283Z,1627062696.283 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-07-23T17:51:36.385Z,1627062696.385 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-07-23T17:51:36.391Z,1627062696.391 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-07-23T17:51:36.394Z,1627062696.394 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-07-23T17:51:36.406Z,1627062696.406 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-07-23T17:51:36.407Z,1627062696.407 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AFE4E0
2021-07-23T17:51:36.408Z,1627062696.408 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1455
2021-07-23T17:51:36.413Z,1627062696.413 [Supervisor](INFO): Main Thread ID is 829
2021-07-23T17:51:36.413Z,1627062696.413 [Supervisor](DEBUG): Running supervisor.
2021-07-23T17:51:36.414Z,1627062696.414 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1456
2021-07-23T17:51:36.416Z,1627062696.416 [controlThread ThreadHandler](INFO): Handler Thread ID is 1457
2021-07-23T17:51:36.416Z,1627062696.416 [controlThread](DEBUG): Initializing ControlThread
2021-07-23T17:51:36.417Z,1627062696.417 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-07-23T17:51:36.419Z,1627062696.419 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-07-23T17:51:36.420Z,1627062696.420 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-07-23T17:51:36.420Z,1627062696.420 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-07-23T17:51:36.422Z,1627062696.422 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-07-23T17:51:36.423Z,1627062696.423 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-07-23T17:51:36.430Z,1627062696.430 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-07-23T17:51:36.430Z,1627062696.430 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-07-23T17:51:36.430Z,1627062696.430 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-07-23T17:51:36.431Z,1627062696.431 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-07-23T17:51:36.431Z,1627062696.431 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-07-23T17:51:36.432Z,1627062696.432 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-07-23T17:51:36.436Z,1627062696.436 [SBIT](INFO): Initialize SBIT Component.
2021-07-23T17:51:36.437Z,1627062696.437 [SBIT](IMPORTANT): git: 2021-06-24-6-gba3a55f22
2021-07-23T17:51:36.437Z,1627062696.437 [SBIT](INFO): git hash: ba3a55f2224e7c70e8e462f655364809e4684817
2021-07-23T17:51:36.437Z,1627062696.437 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-07-23T17:51:36.438Z,1627062696.438 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2021-07-23T17:51:36.440Z,1627062696.440 [SBIT](INFO): Beginning SBIT in 87.000000 seconds.
2021-07-23T17:51:36.440Z,1627062696.440 [IBIT](INFO): Initialize IBIT Component.
2021-07-23T17:51:36.441Z,1627062696.441 [CBIT](DEBUG): Initialize CBIT Component.
2021-07-23T17:51:36.442Z,1627062696.442 [logger ThreadHandler](INFO): Handler Thread ID is 1458
2021-07-23T17:51:36.456Z,1627062696.456 [CBIT](DEBUG): Initialized mux pins.
2021-07-23T17:51:36.456Z,1627062696.456 [CBIT](DEBUG): Initializing the watchdog timer.
2021-07-23T17:51:36.460Z,1627062696.460 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1459
2021-07-23T17:51:36.461Z,1627062696.461 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-07-23T17:51:36.468Z,1627062696.468 [Onboard ThreadHandler](INFO): Handler Thread ID is 1460
2021-07-23T17:51:36.480Z,1627062696.480 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-07-23T17:51:36.480Z,1627062696.480 [CBIT](DEBUG): Initializing heartbeat.
2021-07-23T17:51:36.486Z,1627062696.486 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1461
2021-07-23T17:51:36.500Z,1627062696.500 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1462
2021-07-23T17:51:36.501Z,1627062696.501 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-07-23T17:51:36.505Z,1627062696.505 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1464
2021-07-23T17:51:36.508Z,1627062696.508 [WetLabsBB2FL](INFO): Powering up
2021-07-23T17:51:36.510Z,1627062696.510 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1466
2021-07-23T17:51:36.513Z,1627062696.513 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-07-23T17:51:36.513Z,1627062696.513 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-07-23T17:51:36.514Z,1627062696.514 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-07-23T17:51:36.514Z,1627062696.514 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-07-23T17:51:36.514Z,1627062696.514 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-07-23T17:51:36.514Z,1627062696.514 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-07-23T17:51:36.514Z,1627062696.514 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-07-23T17:51:36.515Z,1627062696.515 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-07-23T17:51:36.515Z,1627062696.515 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-07-23T17:51:36.515Z,1627062696.515 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-07-23T17:51:36.515Z,1627062696.515 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-07-23T17:51:36.515Z,1627062696.515 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-07-23T17:51:36.515Z,1627062696.515 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-07-23T17:51:36.516Z,1627062696.516 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-07-23T17:51:36.516Z,1627062696.516 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-07-23T17:51:36.517Z,1627062696.517 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-07-23T17:51:36.552Z,1627062696.552 [CBIT](DEBUG): Deactivating GF circuits.
2021-07-23T17:51:36.552Z,1627062696.552 [CBIT](DEBUG): Deactivating emergency mode.
2021-07-23T17:51:36.588Z,1627062696.588 [CBIT](DEBUG): Backplane powered.
2021-07-23T17:51:36.589Z,1627062696.589 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-07-23T17:51:36.602Z,1627062696.602 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-07-23T17:51:36.622Z,1627062696.622 [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>


2021-07-23T17:51:36.622Z,1627062696.622 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-07-23T17:51:36.701Z,1627062696.701 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-07-23T17:51:36.703Z,1627062696.703 [Default:A.Wait](DEBUG): Construct Wait.
2021-07-23T17:51:36.718Z,1627062696.718 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-07-23T17:51:36.739Z,1627062696.739 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-07-23T17:51:36.769Z,1627062696.769 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-07-23T17:51:36.775Z,1627062696.775 [Default:E.Execute](DEBUG): Construct Execute.
2021-07-23T17:51:36.795Z,1627062696.795 [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="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="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>


2021-07-23T17:51:36.808Z,1627062696.808 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-07-23T17:51:36.823Z,1627062696.823 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-07-23T17:51:36.890Z,1627062696.890 [Power24vConverter](INFO): Powering up.
2021-07-23T17:51:36.891Z,1627062696.891 [CANONSampler](INFO): Powering down
2021-07-23T17:51:36.900Z,1627062696.900 [Radio_Surface](INFO): Powering up
2021-07-23T17:51:36.934Z,1627062696.934 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-07-23T17:51:36.940Z,1627062696.940 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-07-23T17:51:36.941Z,1627062696.941 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-07-23T17:51:36.948Z,1627062696.948 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-07-23T17:51:36.949Z,1627062696.949 [MassServo](DEBUG): Initializing EZServoServo.
2021-07-23T17:51:36.956Z,1627062696.956 [MassServo](DEBUG): Initializing MassServo.
2021-07-23T17:51:36.957Z,1627062696.957 [RudderServo](DEBUG): Initializing EZServoServo.
2021-07-23T17:51:36.964Z,1627062696.964 [RudderServo](DEBUG): Initializing RudderServo.
2021-07-23T17:51:36.965Z,1627062696.965 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-07-23T17:51:36.972Z,1627062696.972 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-07-23T17:51:38.616Z,1627062698.616 [WetLabsBB2FL](INFO): Powering down
2021-07-23T17:52:05.506Z,1627062725.506 [NAL9602](INFO): Powering up NAL9602
2021-07-23T17:52:16.422Z,1627062736.422 [NAL9602](INFO): NAL9602 initialized
2021-07-23T17:52:43.902Z,1627062763.902 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2021-07-23T17:53:00.051Z,1627062780.051 [NAL9602](INFO): SBD MO Status=2, MOMSN=28507, MT Status=2, MTMSN=0
2021-07-23T17:53:00.051Z,1627062780.051 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-07-23T17:53:04.107Z,1627062784.107 [SBIT](IMPORTANT): Beginning Startup BIT
2021-07-23T17:53:04.112Z,1627062784.112 [CBIT](IMPORTANT): Beginning ground fault scan
2021-07-23T17:53:15.019Z,1627062795.019 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.002529
CHAN A1 (24V): -0.180558
CHAN A2 (12V): -0.002556
CHAN A3 (5V): -0.001569
CHAN B0 (3.3V): -0.000010
CHAN B1 (3.15aV): 4.767929
CHAN B2 (3.15bV): -0.000478
CHAN B3 (GND): 0.000000
OPEN: -0.000530
Full Scale Calc: 4.765 mA, -1.589 mA
2021-07-23T17:53:30.765Z,1627062810.765 [NAL9602](INFO): SBD MO Status=2, MOMSN=28507, MT Status=2, MTMSN=0
2021-07-23T17:53:30.766Z,1627062810.766 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-07-23T17:53:57.019Z,1627062837.019 [NAL9602](INFO): SBD MO Status=2, MOMSN=28507, MT Status=2, MTMSN=0
2021-07-23T17:53:57.019Z,1627062837.019 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-07-23T17:53:58.239Z,1627062838.239 [SBIT](IMPORTANT): SBIT PASSED
2021-07-23T17:53:58.240Z,1627062838.240 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-07-23T17:53:58.241Z,1627062838.241 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2021-07-23T17:53:58.241Z,1627062838.241 [SBIT](IMPORTANT): BuoyancyServo.fastPumpDepth=6 meter;

2021-07-23T17:53:58.241Z,1627062838.241 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2021-07-23T17:53:58.241Z,1627062838.241 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2021-07-23T17:53:58.241Z,1627062838.241 [SBIT](IMPORTANT): DAT.verbosity=3 count;

2021-07-23T17:53:58.241Z,1627062838.241 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=417.701960 cubic_centimeter;

2021-07-23T17:53:58.242Z,1627062838.242 [SBIT](IMPORTANT): VerticalControl.kdDepthBuoy=2680 second;

2021-07-23T17:53:58.242Z,1627062838.242 [SBIT](IMPORTANT): VerticalControl.kiDepthBuoy=0 reciprocal_second;

2021-07-23T17:53:58.242Z,1627062838.242 [SBIT](IMPORTANT): VerticalControl.kpDepthBuoy=62 ratio;

2021-07-23T17:53:58.242Z,1627062838.242 [SBIT](IMPORTANT): VerticalControl.massDefault=-16.879249 millimeter;

2021-07-23T17:53:58.647Z,1627062838.647 [MissionManager](IMPORTANT): Started mission Startup
2021-07-23T17:53:58.647Z,1627062838.647 [Startup] Running Loop=1
2021-07-23T17:53:58.647Z,1627062838.647 [Startup](DEBUG): Aggregate::initialize Startup
2021-07-23T17:53:58.647Z,1627062838.647 [Startup:A.GoToSurface] Running Loop=1
2021-07-23T17:53:58.647Z,1627062838.647 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-07-23T17:53:58.648Z,1627062838.648 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-07-23T17:53:58.649Z,1627062838.649 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-07-23T17:53:58.649Z,1627062838.649 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-07-23T17:53:58.650Z,1627062838.650 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-07-23T17:53:58.650Z,1627062838.650 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-07-23T17:53:58.651Z,1627062838.651 [Startup:StartupSatComms] Running Loop=1
2021-07-23T17:53:58.652Z,1627062838.652 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-07-23T17:53:58.652Z,1627062838.652 [Startup:StartupSatComms:A] Running Loop=1
2021-07-23T17:53:59.057Z,1627062839.057 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-07-23T17:54:32.993Z,1627062872.993 [NAL9602](INFO): SBD MO Status=0, MOMSN=28507, MT Status=0, MTMSN=0
2021-07-23T17:54:32.994Z,1627062872.994 [NAL9602](INFO): No messages in MT queue
2021-07-23T17:54:36.613Z,1627062876.613 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-07-23T17:54:36.613Z,1627062876.613 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-07-23T17:54:36.624Z,1627062876.624 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-07-23T17:54:37.060Z,1627062877.060 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-07-23T17:54:37.060Z,1627062877.060 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-07-23T17:54:58.863Z,1627062898.863 [Startup:StartupSatComms:A](INFO): Timed out from 2021-07-23T17:53:58.7Z
2021-07-23T17:54:58.863Z,1627062898.863 [Startup:StartupSatComms:A] Stopped
2021-07-23T17:54:58.863Z,1627062898.863 [Startup:StartupSatComms:B] Running Loop=1
2021-07-23T17:54:59.303Z,1627062899.303 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-07-23T17:55:11.482Z,1627062911.482 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004369
2021-07-23T17:55:19.795Z,1627062919.795 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210723T171457/Courier0016.lzma
2021-07-23T17:55:20.798Z,1627062920.798 [DataOverHttps](INFO): Moved sent file to Logs/20210723T171457/Courier0016.lzma.bak
2021-07-23T17:55:20.798Z,1627062920.798 [DataOverHttps](INFO): SBD MOMSN=15758318
2021-07-23T17:55:23.519Z,1627062923.519 [NAL9602](INFO): SBD MO Status=2, MOMSN=28508, MT Status=2, MTMSN=0
2021-07-23T17:55:23.519Z,1627062923.519 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-07-23T17:55:37.603Z,1627062937.603 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20210723T175126/Courier0000.lzma
2021-07-23T17:55:38.606Z,1627062938.606 [DataOverHttps](INFO): Moved sent file to Logs/20210723T175126/Courier0000.lzma.bak
2021-07-23T17:55:38.606Z,1627062938.606 [DataOverHttps](INFO): SBD MOMSN=15758320
2021-07-23T17:55:55.984Z,1627062955.984 [DataOverHttps](INFO): Sending 828 bytes from file Logs/20210723T171457/Express0014.lzma
2021-07-23T17:55:56.982Z,1627062956.982 [DataOverHttps](INFO): Moved sent file to Logs/20210723T171457/Express0014.lzma.bak
2021-07-23T17:55:56.982Z,1627062956.982 [DataOverHttps](INFO): SBD MOMSN=15758325
2021-07-23T17:55:59.092Z,1627062959.092 [Startup:StartupSatComms:B](INFO): Timed out from 2021-07-23T17:54:58.9Z
2021-07-23T17:55:59.092Z,1627062959.092 [Startup:StartupSatComms:B] Stopped
2021-07-23T17:55:59.092Z,1627062959.092 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-07-23T17:55:59.093Z,1627062959.093 [Startup:StartupSatComms] Stopped
2021-07-23T17:55:59.093Z,1627062959.093 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-07-23T17:55:59.094Z,1627062959.094 [Startup](INFO): Completed Startup
2021-07-23T17:55:59.094Z,1627062959.094 [MissionManager](INFO): Startup is completed.
2021-07-23T17:55:59.094Z,1627062959.094 [MissionManager](INFO): Uninitializing Mission Startup
2021-07-23T17:55:59.094Z,1627062959.094 [Startup] Stopped
2021-07-23T17:55:59.094Z,1627062959.094 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-07-23T17:55:59.094Z,1627062959.094 [Startup:A.GoToSurface] Stopped
2021-07-23T17:55:59.094Z,1627062959.094 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-07-23T17:55:59.516Z,1627062959.516 [MissionManager](IMPORTANT): Started mission Default
2021-07-23T17:55:59.516Z,1627062959.516 [Default] Running Loop=1
2021-07-23T17:55:59.516Z,1627062959.516 [Default](DEBUG): Aggregate::initialize Default
2021-07-23T17:55:59.516Z,1627062959.516 [Default:B.GoToSurface] Running Loop=1
2021-07-23T17:55:59.516Z,1627062959.516 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-07-23T17:55:59.517Z,1627062959.517 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-07-23T17:55:59.517Z,1627062959.517 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-07-23T17:55:59.517Z,1627062959.517 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-07-23T17:55:59.518Z,1627062959.518 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-07-23T17:55:59.518Z,1627062959.518 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-07-23T17:55:59.518Z,1627062959.518 [Default:A.Wait] Running Loop=1
2021-07-23T17:55:59.518Z,1627062959.518 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-07-23T17:56:00.291Z,1627062960.291 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175548.00,A,3648.15897,N,12147.28739,W,0.117,64.63,230721,,,A*4E
2021-07-23T17:56:00.295Z,1627062960.295 [NAL9602](INFO): GPS fix at 20210723T175548: (36.802650, -121.788123)
2021-07-23T17:56:12.821Z,1627062972.821 [Default:A.Wait](INFO): Done Waiting.
2021-07-23T17:56:12.822Z,1627062972.822 [Default:A.Wait] Stopped
2021-07-23T17:56:12.822Z,1627062972.822 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-07-23T17:56:13.237Z,1627062973.237 [Default:CheckIn] Running Loop=1
2021-07-23T17:56:13.237Z,1627062973.237 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-23T17:56:13.238Z,1627062973.238 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-23T17:56:13.642Z,1627062973.642 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-07-23T17:56:15.269Z,1627062975.269 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175603.00,A,3648.15970,N,12147.28646,W,1.069,64.63,230721,,,A*4A
2021-07-23T17:56:15.271Z,1627062975.271 [NAL9602](INFO): GPS fix at 20210723T175603: (36.802662, -121.788108)
2021-07-23T17:56:15.282Z,1627062975.282 [Default:CheckIn:Read_GPS] Stopped
2021-07-23T17:56:15.282Z,1627062975.282 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-23T17:56:15.696Z,1627062975.696 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-07-23T17:56:22.916Z,1627062982.916 [DataOverHttps](INFO): Sending 281 bytes from file Logs/20210723T171457/Express0017.lzma
2021-07-23T17:56:23.918Z,1627062983.918 [DataOverHttps](INFO): Moved sent file to Logs/20210723T171457/Express0017.lzma.bak
2021-07-23T17:56:23.918Z,1627062983.918 [DataOverHttps](INFO): SBD MOMSN=15758342
2021-07-23T17:56:48.145Z,1627063008.145 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-23T17:56:48.688Z,1627063008.688 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20210723T175126/Courier0004.lzma
2021-07-23T17:56:49.690Z,1627063009.690 [DataOverHttps](INFO): Moved sent file to Logs/20210723T175126/Courier0004.lzma.bak
2021-07-23T17:56:49.690Z,1627063009.690 [DataOverHttps](INFO): SBD MOMSN=15758349
2021-07-23T17:57:07.903Z,1627063027.903 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20210723T171457/Express0020.lzma
2021-07-23T17:57:08.906Z,1627063028.906 [DataOverHttps](INFO): Moved sent file to Logs/20210723T171457/Express0020.lzma.bak
2021-07-23T17:57:08.906Z,1627063028.906 [DataOverHttps](INFO): SBD MOMSN=15758359
2021-07-23T17:57:27.030Z,1627063047.030 [DataOverHttps](INFO): Sending 815 bytes from file Logs/20210723T175126/Express0001.lzma
2021-07-23T17:57:28.030Z,1627063048.030 [DataOverHttps](INFO): Moved sent file to Logs/20210723T175126/Express0001.lzma.bak
2021-07-23T17:57:28.030Z,1627063048.030 [DataOverHttps](INFO): SBD MOMSN=15758361
2021-07-23T17:57:45.908Z,1627063065.908 [DataOverHttps](INFO): Sending 236 bytes from file Logs/20210723T175126/Express0005.lzma
2021-07-23T17:57:46.910Z,1627063066.910 [DataOverHttps](INFO): Moved sent file to Logs/20210723T175126/Express0005.lzma.bak
2021-07-23T17:57:46.910Z,1627063066.910 [DataOverHttps](INFO): SBD MOMSN=15758382
2021-07-23T17:57:50.406Z,1627063070.406 [Default:CheckIn:Read_Iridium] Stopped
2021-07-23T17:57:50.407Z,1627063070.407 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-23T17:57:50.407Z,1627063070.407 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-23T17:57:58.574Z,1627063078.574 [DataOverHttps](IMPORTANT): SBD MTMSN=20210723T175747
2021-07-23T17:58:06.288Z,1627063086.288 [DataOverHttps](INFO): Received command:sched asap "load Maintenance/tank_ballast_and_trim.xml;set ballast_and_trim.MissionTimeout 30 min;set ballast_and_trim.Depth1 2 m;set ballast_and_trim.SurfaceThreshold 1 m;set ballast_and_trim.EstimationTimeout 15 min" 1msk8 1 2
2021-07-23T17:58:06.298Z,1627063086.298 [CommandLine](IMPORTANT): got command schedule asap "load Maintenance/tank_ballast_and_trim.xml;set ballast_and_trim.MissionTimeout 30 min;set ballast_and_trim.Depth1 2 m;set ballast_and_trim.SurfaceThreshold 1 m;set ballast_and_trim.EstimationTimeout 15 min" 1msk8 1 2.000000
2021-07-23T17:58:06.298Z,1627063086.298 [CommandLine](IMPORTANT): Scheduling command #1 of 2 with id=1msk8
2021-07-23T17:58:06.299Z,1627063086.299 [CommandLine](IMPORTANT): Scheduled #1 (#1 of 2 with id='1msk8'): "load Maintenance/tank_ballast_and_trim.xml;set ballast_and_trim.MissionTimeout 30 min;set ballast_and_trim.Depth1 2 m;set ballast_and_trim.SurfaceThreshold 1 m;set ballast_and_trim.EstimationTimeout 15 min" ASAP
2021-07-23T17:58:07.290Z,1627063087.290 [DataOverHttps](IMPORTANT): SBD MTMSN=20210723T175755
2021-07-23T17:58:14.624Z,1627063094.624 [DataOverHttps](INFO): Received command:sched asap "set ballast_and_trim.MassEstimationErrorBound 1 mm;set ballast_and_trim.BuoyEstimationErrorBound 25 cc;set ballast_and_trim.EstimationConfidence 98.0 %;run" 1msk8 2 2
2021-07-23T17:58:14.701Z,1627063094.701 [CommandLine](IMPORTANT): got command schedule asap "set ballast_and_trim.MassEstimationErrorBound 1 mm;set ballast_and_trim.BuoyEstimationErrorBound 25 cc;set ballast_and_trim.EstimationConfidence 98.0 %;run" 1msk8 2 2.000000
2021-07-23T17:58:14.701Z,1627063094.701 [CommandLine](IMPORTANT): Scheduling command #2 of 2 with id=1msk8
2021-07-23T17:58:14.702Z,1627063094.702 [CommandLine](IMPORTANT): Scheduled #2 (#2 of 2 with id='1msk8'): "set ballast_and_trim.MassEstimationErrorBound 1 mm;set ballast_and_trim.BuoyEstimationErrorBound 25 cc;set ballast_and_trim.EstimationConfidence 98.0 %;run" ASAP
2021-07-23T17:58:14.777Z,1627063094.777 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/tank_ballast_and_trim.xml
2021-07-23T17:58:14.777Z,1627063094.777 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/tank_ballast_and_trim.xml
2021-07-23T17:58:14.835Z,1627063094.835 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 120.000000 min
2021-07-23T17:58:14.838Z,1627063094.838 [MissionManager](INFO): DefineArg ballast_and_trim.SkipComms = 0 bool
2021-07-23T17:58:14.841Z,1627063094.841 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 7.000000 m
2021-07-23T17:58:14.844Z,1627063094.844 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m
2021-07-23T17:58:14.847Z,1627063094.847 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 0.100000 m
2021-07-23T17:58:14.850Z,1627063094.850 [MissionManager](INFO): DefineArg ballast_and_trim.SurfaceThreshold = 2.500000 m
2021-07-23T17:58:14.853Z,1627063094.853 [MissionManager](INFO): DefineArg ballast_and_trim.MaxWaitOnSurface = 20.000000 min
2021-07-23T17:58:14.856Z,1627063094.856 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min
2021-07-23T17:58:14.859Z,1627063094.859 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min
2021-07-23T17:58:14.890Z,1627063094.890 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min
2021-07-23T17:58:14.894Z,1627063094.894 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm
2021-07-23T17:58:14.908Z,1627063094.908 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc
2021-07-23T17:58:14.911Z,1627063094.911 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900000 %
2021-07-23T17:58:14.918Z,1627063094.918 [MissionManager](INFO): DefineArg ballast_and_trim.WaitToFloat = 5.000000 min
2021-07-23T17:58:14.919Z,1627063094.919 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2021-07-23T17:58:15.297Z,1627063095.297 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool
2021-07-23T17:58:15.300Z,1627063095.300 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min
2021-07-23T17:58:15.302Z,1627063095.302 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool
2021-07-23T17:58:15.305Z,1627063095.305 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestSaltPeakReportActive = 0 bool
2021-07-23T17:58:15.354Z,1627063095.354 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestOilPeakReportActive = 0 bool
2021-07-23T17:58:15.357Z,1627063095.357 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool
2021-07-23T17:58:15.369Z,1627063095.369 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count
2021-07-23T17:58:15.372Z,1627063095.372 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count
2021-07-23T17:58:15.375Z,1627063095.375 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 %
2021-07-23T17:58:15.378Z,1627063095.378 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool
2021-07-23T17:58:15.427Z,1627063095.427 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool
2021-07-23T17:58:15.430Z,1627063095.430 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool
2021-07-23T17:58:15.453Z,1627063095.453 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectSalinityActive = 0 bool
2021-07-23T17:58:15.455Z,1627063095.455 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2021-07-23T17:58:15.459Z,1627063095.459 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0.000000 bool
2021-07-23T17:58:15.476Z,1627063095.476 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0.000000 bool
2021-07-23T17:58:15.488Z,1627063095.488 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 1.000000 bool
2021-07-23T17:58:15.500Z,1627063095.500 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 1.000000 bool
2021-07-23T17:58:15.504Z,1627063095.504 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2021-07-23T17:58:15.507Z,1627063095.507 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 0.000000 bool
2021-07-23T17:58:15.511Z,1627063095.511 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count
2021-07-23T17:58:15.518Z,1627063095.518 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count
2021-07-23T17:58:15.536Z,1627063095.536 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakShallowBound = nan m
2021-07-23T17:58:15.544Z,1627063095.544 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDeepBound = nan m
2021-07-23T17:58:15.547Z,1627063095.547 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2021-07-23T17:58:15.558Z,1627063095.558 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = 0.000000 ug/l
2021-07-23T17:58:15.562Z,1627063095.562 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = 0.000000 m
2021-07-23T17:58:15.567Z,1627063095.567 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = 0.000000 degC
2021-07-23T17:58:15.579Z,1627063095.579 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg
2021-07-23T17:58:15.582Z,1627063095.582 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg
2021-07-23T17:58:15.599Z,1627063095.599 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = 0.000000 umol/l
2021-07-23T17:58:15.606Z,1627063095.606 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = 0.000000 m
2021-07-23T17:58:15.610Z,1627063095.610 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg
2021-07-23T17:58:15.621Z,1627063095.621 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg
2021-07-23T17:58:15.633Z,1627063095.633 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = 0.000000 kg/m3
2021-07-23T17:58:15.640Z,1627063095.640 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = 0.000000 m
2021-07-23T17:58:15.643Z,1627063095.643 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg
2021-07-23T17:58:15.651Z,1627063095.651 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg
2021-07-23T17:58:15.658Z,1627063095.658 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = 0.000000 ppb
2021-07-23T17:58:15.666Z,1627063095.666 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = 0.000000 m
2021-07-23T17:58:15.681Z,1627063095.681 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMTemperature = nan degC
2021-07-23T17:58:15.696Z,1627063095.696 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg
2021-07-23T17:58:15.703Z,1627063095.703 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg
2021-07-23T17:58:15.715Z,1627063095.715 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchFDOMDistance = nan m
2021-07-23T17:58:15.718Z,1627063095.718 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSalt = 0.000000 psu
2021-07-23T17:58:15.722Z,1627063095.722 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltDepth = 0.000000 m
2021-07-23T17:58:15.725Z,1627063095.725 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltTemperature = 0.000000 degC
2021-07-23T17:58:15.736Z,1627063095.736 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLatitude = nan arcdeg
2021-07-23T17:58:15.739Z,1627063095.739 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLongitude = nan arcdeg
2021-07-23T17:58:15.743Z,1627063095.743 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = 0.000000 ug/l
2021-07-23T17:58:15.747Z,1627063095.747 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = 0.000000 m
2021-07-23T17:58:15.762Z,1627063095.762 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg
2021-07-23T17:58:15.769Z,1627063095.769 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg
2021-07-23T17:58:15.780Z,1627063095.780 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m
2021-07-23T17:58:15.784Z,1627063095.784 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSalt = 0.000000 psu
2021-07-23T17:58:15.787Z,1627063095.787 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDepth = 0.000000 m
2021-07-23T17:58:15.830Z,1627063095.830 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLatitude = nan arcdeg
2021-07-23T17:58:15.834Z,1627063095.834 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLongitude = nan arcdeg
2021-07-23T17:58:15.863Z,1627063095.863 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDistance = nan m
2021-07-23T17:58:15.875Z,1627063095.875 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOil = 0.000000 psu
2021-07-23T17:58:15.878Z,1627063095.878 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDepth = 0.000000 m
2021-07-23T17:58:15.881Z,1627063095.881 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLatitude = nan arcdeg
2021-07-23T17:58:15.897Z,1627063095.897 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLongitude = nan arcdeg
2021-07-23T17:58:15.900Z,1627063095.900 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDistance = nan m
2021-07-23T17:58:15.966Z,1627063095.966 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-07-23T17:58:15.987Z,1627063095.987 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-07-23T17:58:16.015Z,1627063096.015 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-07-23T17:58:16.065Z,1627063096.065 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-07-23T17:58:16.078Z,1627063096.078 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-07-23T17:58:16.131Z,1627063096.131 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-07-23T17:58:16.151Z,1627063096.151 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-07-23T17:58:16.178Z,1627063096.178 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-07-23T17:58:16.242Z,1627063096.242 [MissionManager](INFO): Inserting Stack: Missions/Insert/BallastAndTrim.xml
2021-07-23T17:58:16.456Z,1627063096.456 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepth = 25.000000 m
2021-07-23T17:58:16.459Z,1627063096.459 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s
2021-07-23T17:58:16.474Z,1627063096.474 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s
2021-07-23T17:58:16.477Z,1627063096.477 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg
2021-07-23T17:58:16.491Z,1627063096.491 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min
2021-07-23T17:58:16.499Z,1627063096.499 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min
2021-07-23T17:58:16.502Z,1627063096.502 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = 10.000000 min
2021-07-23T17:58:16.513Z,1627063096.513 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = 45.000000 min
2021-07-23T17:58:16.517Z,1627063096.517 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = 10.000000 min
2021-07-23T17:58:16.532Z,1627063096.532 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2021-07-23T17:58:16.535Z,1627063096.535 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2021-07-23T17:58:16.539Z,1627063096.539 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = 99.900000 %
2021-07-23T17:58:16.562Z,1627063096.562 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2021-07-23T17:58:16.566Z,1627063096.566 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertKiDepthBuoy = 0.300000 1/s
2021-07-23T17:58:16.570Z,1627063096.570 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = 0.250000 mm
2021-07-23T17:58:16.649Z,1627063096.649 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct.
2021-07-23T17:58:16.670Z,1627063096.670 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy.
2021-07-23T17:58:16.674Z,1627063096.674 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct.
2021-07-23T17:58:16.695Z,1627063096.695 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait.
2021-07-23T17:58:16.707Z,1627063096.707 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct.
2021-07-23T17:58:16.711Z,1627063096.711 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct.
2021-07-23T17:58:16.731Z,1627063096.731 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2021-07-23T17:58:16.734Z,1627063096.734 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct.
2021-07-23T17:58:16.738Z,1627063096.738 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct.
2021-07-23T17:58:16.766Z,1627063096.766 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait.
2021-07-23T17:58:16.777Z,1627063096.777 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](DEBUG): Construct BallastAndTrim.
2021-07-23T17:58:16.841Z,1627063096.841 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband
2021-07-23T17:58:16.843Z,1627063096.843 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = reader:ballast_and_trim.MaxWaitOnSurface
2021-07-23T17:58:16.850Z,1627063096.850 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime
2021-07-23T17:58:16.856Z,1627063096.856 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout
2021-07-23T17:58:16.861Z,1627063096.861 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime
2021-07-23T17:58:16.863Z,1627063096.863 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound
2021-07-23T17:58:16.865Z,1627063096.865 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound
2021-07-23T17:58:16.867Z,1627063096.867 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence
2021-07-23T17:58:16.891Z,1627063096.891 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy.
2021-07-23T17:58:16.899Z,1627063096.899 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Construct Wait.
2021-07-23T17:58:16.951Z,1627063096.951 [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="ballast_and_trim">

    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission
    </Description><Units:minute/><Value>120</Value></DefineArg>

    <DefineArg Name="SkipComms"><Description>
        Skip communications at start of mission.
    </Description><False/></DefineArg>

    <DefineArg Name="Depth1"><Units:meter/><Value>7</Value></DefineArg>

    <DefineArg Name="Depth2"><Units:meter/><Value>NaN</Value></DefineArg>

    <DefineArg Name="DepthDeadband"><Description>
        How much vertical drift from the specified depth is allowed durnig the
        mission.
    </Description><Units:meter/><Value>0.1</Value></DefineArg>

    <DefineArg Name="SurfaceThreshold"><Description>
        Surface threshold depth.
    </Description><Units:meter/><Value>2.5</Value></DefineArg>

    <DefineArg Name="MaxWaitOnSurface"><Description>
        Maximum wait time for the vehicle to go under the surface (i.e., reach
        the surface threshold depth).
    </Description><Units:minute/><Value>20</Value></DefineArg>

    <DefineArg Name="SettleTime"><Description>
        How long to wait after reaching target depth before starting ballast and
        trim.
    </Description><Units:minute/><Value>10</Value></DefineArg>

    <DefineArg Name="EstimationTimeout"><Description>
        Timeout for ballast and trim estimation.
    </Description><Units:minute/><Value>45</Value></DefineArg>

    <DefineArg Name="MinEstimationTime"><Description>
        Minimum time to run ballast and trim estimation before determining
        convergence (must be shorter than timeout).
    </Description><Units:minute/><Value>10</Value></DefineArg>

    <DefineArg Name="MassEstimationErrorBound"><Description>
        Desired range of the estimated sample mean from the true mean statistic
        (smaller values take longer to converge).
    </Description><Units:millimeter/><Value>0.25</Value></DefineArg>

    <DefineArg Name="BuoyEstimationErrorBound"><Description>
        Desired range of the estimated sample mean from the true mean statistic
        (smaller values take longer to converge).
    </Description><Units:cubic_centimeter/><Value>15</Value></DefineArg>

    <DefineArg Name="EstimationConfidence"><Description>
        Confidence level that the estimator has reached the desired error range
        (larger values take longer to converge).
    </Description><Units:percent/><Value>99.9</Value></DefineArg>

    <DefineArg Name="WaitToFloat"><Units:minute/><Value>5</Value></DefineArg>

    <Timeout Duration="MissionTimeout"/>

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

    <Aggregate Id="RunBallastAndTrim">

        <Sequence/>

        <Insert Filename="Insert/BallastAndTrim.xml">
            <RedefineArg Name="InsertDepthDeadband"><Arg Name="DepthDeadband"/></RedefineArg>
            <RedefineArg Name="InsertApproachDepthTimeout"><Arg Name="MaxWaitOnSurface"/></RedefineArg>
            <RedefineArg Name="InsertSettleTime"><Arg Name="SettleTime"/></RedefineArg>
            <RedefineArg Name="EstTimeout"><Arg Name="EstimationTimeout"/></RedefineArg>
            <RedefineArg Name="MinEstTime"><Arg Name="MinEstimationTime"/></RedefineArg>
            <RedefineArg Name="MassEstErrorBound"><Arg Name="MassEstimationErrorBound"/></RedefineArg>
            <RedefineArg Name="BuoyEstErrorBound"><Arg Name="BuoyEstimationErrorBound"/></RedefineArg>
            <RedefineArg Name="EstConfidence"><Arg Name="EstimationConfidence"/></RedefineArg>
        </Insert>

        <Aggregate Id="Depth1">

            <Sequence/>

            <Break>
                <IsNaN><Arg Name="Depth1"/>
                </IsNaN>
            </Break>

            <Assign><Sequence/><Arg Name="BallastAndTrim:InsertDepth"/><Arg Name="Depth1"/></Assign>

            <Call RefId="BallastAndTrim"/>

        </Aggregate>

        <Aggregate Id="Depth2">

            <Sequence/>

            <Break>
                <IsNaN><Arg Name="Depth2"/>
                </IsNaN>
            </Break>

            <Assign><Sequence/><Arg Name="BallastAndTrim:InsertDepth"/><Arg Name="Depth1"/></Assign>

            <Call RefId="BallastAndTrim"/>

        </Aggregate>

    </Aggregate>

    <Aggregate Id="Float_Up">

        <Description>
            Float to surface instead of driving.
        </Description>

        <Sequence/>

        <Break>
            <Universal:depth/>
            <Lt><Control:VerticalControl.surfaceThreshold/></Lt>
        </Break>

        <Guidance:Buoyancy>
            <Parallel/>
            <Setting><Guidance:Buoyancy.position/><Control:VerticalControl.buoyancyDefault/></Setting>
        </Guidance:Buoyancy>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Arg Name="WaitToFloat"/></Setting>
        </Guidance:Wait>

    </Aggregate>

</Mission>


2021-07-23T17:58:16.972Z,1627063096.972 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/tank_ballast_and_trim.xml
2021-07-23T17:58:31.714Z,1627063111.714 [CommandLine](IMPORTANT): got command set ballast_and_trim.MissionTimeout 30.000000 minute
2021-07-23T17:58:31.715Z,1627063111.715 [CommandLine](IMPORTANT): got command set ballast_and_trim.Depth1 2.000000 meter
2021-07-23T17:58:31.716Z,1627063111.716 [CommandLine](IMPORTANT): got command set ballast_and_trim.SurfaceThreshold 1.000000 meter
2021-07-23T17:58:31.717Z,1627063111.717 [CommandLine](IMPORTANT): got command set ballast_and_trim.EstimationTimeout 15.000000 minute
2021-07-23T17:58:56.516Z,1627063136.516 [CommandLine](IMPORTANT): got command set ballast_and_trim.MassEstimationErrorBound 1.000000 millimeter
2021-07-23T17:58:56.517Z,1627063136.517 [CommandLine](IMPORTANT): got command set ballast_and_trim.BuoyEstimationErrorBound 25.000000 cubic_centimeter
2021-07-23T17:58:56.518Z,1627063136.518 [CommandLine](IMPORTANT): got command set ballast_and_trim.EstimationConfidence 98.000000 percent
2021-07-23T17:58:56.518Z,1627063136.518 [CommandLine](IMPORTANT): got command run
2021-07-23T17:58:56.530Z,1627063136.530 [CommandLine](IMPORTANT): Running 
2021-07-23T17:58:56.578Z,1627063136.578 [Default] Stopped
2021-07-23T17:58:56.578Z,1627063136.578 [Default](DEBUG): Aggregate::uninitialize Default
2021-07-23T17:58:56.578Z,1627063136.578 [Default:B.GoToSurface] Stopped
2021-07-23T17:58:56.579Z,1627063136.579 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-07-23T17:58:56.579Z,1627063136.579 [Default:CheckIn] Stopped
2021-07-23T17:58:56.579Z,1627063136.579 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-23T17:58:56.579Z,1627063136.579 [Default:CheckIn:C.Wait] Stopped
2021-07-23T17:58:56.579Z,1627063136.579 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-23T17:58:56.579Z,1627063136.579 [MissionManager](IMPORTANT): Started mission ballast_and_trim
2021-07-23T17:58:56.579Z,1627063136.579 [ballast_and_trim] Running Loop=1
2021-07-23T17:58:56.588Z,1627063136.588 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim
2021-07-23T17:58:56.588Z,1627063136.588 [ballast_and_trim:Science] Running Loop=1
2021-07-23T17:58:56.588Z,1627063136.588 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science
2021-07-23T17:58:56.588Z,1627063136.588 [ballast_and_trim:Science:A] Running Loop=1
2021-07-23T17:58:56.588Z,1627063136.588 [ballast_and_trim:Science:B] Running Loop=1
2021-07-23T17:58:56.588Z,1627063136.588 [ballast_and_trim:Science:C] Running Loop=1
2021-07-23T17:58:56.588Z,1627063136.588 [ballast_and_trim:Science:D] Running Loop=1
2021-07-23T17:58:56.589Z,1627063136.589 [ballast_and_trim:Science:E] Running Loop=1
2021-07-23T17:58:56.589Z,1627063136.589 [ballast_and_trim:Science:F] Running Loop=1
2021-07-23T17:58:56.589Z,1627063136.589 [ballast_and_trim:Science:Read_Oil] Running Loop=1
2021-07-23T17:58:56.589Z,1627063136.589 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1
2021-07-23T17:58:56.589Z,1627063136.589 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl
2021-07-23T17:58:56.589Z,1627063136.589 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2021-07-23T17:58:56.589Z,1627063136.589 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-07-23T17:58:56.591Z,1627063136.591 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1
2021-07-23T17:58:56.591Z,1627063136.591 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport
2021-07-23T17:58:56.600Z,1627063136.600 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-07-23T17:58:56.600Z,1627063136.600 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-07-23T17:58:56.601Z,1627063136.601 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1
2021-07-23T17:58:56.601Z,1627063136.601 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3
2021-07-23T17:58:56.601Z,1627063136.601 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2021-07-23T17:58:56.601Z,1627063136.601 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-07-23T17:58:56.603Z,1627063136.603 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1
2021-07-23T17:58:56.603Z,1627063136.603 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil
2021-07-23T17:58:56.603Z,1627063136.603 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2021-07-23T17:58:56.603Z,1627063136.603 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-07-23T17:58:56.614Z,1627063136.614 [ballast_and_trim:Science:HighestOilPeakReport] Running Loop=1
2021-07-23T17:58:56.614Z,1627063136.614 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestOilPeakReport
2021-07-23T17:58:56.614Z,1627063136.614 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-07-23T17:58:56.614Z,1627063136.614 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-07-23T17:58:56.615Z,1627063136.615 [ballast_and_trim:Science:PeakDetectFDOM] Running Loop=1
2021-07-23T17:58:56.615Z,1627063136.615 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectFDOM
2021-07-23T17:58:56.615Z,1627063136.615 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2021-07-23T17:58:56.615Z,1627063136.615 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-07-23T17:58:56.626Z,1627063136.626 [ballast_and_trim:Science:PeakDetectSalinity] Running Loop=1
2021-07-23T17:58:56.626Z,1627063136.626 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectSalinity
2021-07-23T17:58:56.626Z,1627063136.626 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2021-07-23T17:58:56.626Z,1627063136.626 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-07-23T17:58:56.637Z,1627063136.637 [ballast_and_trim:Science:HighestSaltPeakReport] Running Loop=1
2021-07-23T17:58:56.637Z,1627063136.637 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestSaltPeakReport
2021-07-23T17:58:56.637Z,1627063136.637 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-07-23T17:58:56.637Z,1627063136.637 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-07-23T17:58:56.638Z,1627063136.638 [ballast_and_trim:Science:OceanCurrent] Running Loop=1
2021-07-23T17:58:56.638Z,1627063136.638 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::initialize ballast_and_trim:Science:OceanCurrent
2021-07-23T17:58:56.638Z,1627063136.638 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2021-07-23T17:58:56.638Z,1627063136.638 [ballast_and_trim:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2021-07-23T17:58:56.638Z,1627063136.638 [ballast_and_trim:RunBallastAndTrim] Running Loop=1
2021-07-23T17:58:56.639Z,1627063136.639 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim
2021-07-23T17:58:56.639Z,1627063136.639 [ballast_and_trim:RunBallastAndTrim:Depth1] Running Loop=1
2021-07-23T17:58:56.639Z,1627063136.639 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1
2021-07-23T17:58:56.639Z,1627063136.639 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Running Loop=1
2021-07-23T17:58:56.649Z,1627063136.649 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Stopped
2021-07-23T17:58:56.649Z,1627063136.649 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Running Loop=1
2021-07-23T17:58:56.649Z,1627063136.649 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2021-07-23T17:58:56.649Z,1627063136.649 [ballast_and_trim:Science] Running Loop=1
2021-07-23T17:58:56.650Z,1627063136.650 [ballast_and_trim:Science:HighestSaltPeakReport] Stopped
2021-07-23T17:58:56.650Z,1627063136.650 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestSaltPeakReport
2021-07-23T17:58:56.650Z,1627063136.650 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2021-07-23T17:58:56.650Z,1627063136.650 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-07-23T17:58:56.651Z,1627063136.651 [ballast_and_trim:Science:PeakDetectSalinity] Stopped
2021-07-23T17:58:56.651Z,1627063136.651 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectSalinity
2021-07-23T17:58:56.651Z,1627063136.651 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2021-07-23T17:58:56.651Z,1627063136.651 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-07-23T17:58:56.651Z,1627063136.651 [ballast_and_trim:Science:PeakDetectFDOM] Stopped
2021-07-23T17:58:56.651Z,1627063136.651 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectFDOM
2021-07-23T17:58:56.651Z,1627063136.651 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2021-07-23T17:58:56.651Z,1627063136.651 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-07-23T17:58:56.651Z,1627063136.651 [ballast_and_trim:Science:HighestOilPeakReport] Stopped
2021-07-23T17:58:56.651Z,1627063136.651 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestOilPeakReport
2021-07-23T17:58:56.664Z,1627063136.664 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2021-07-23T17:58:56.664Z,1627063136.664 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-07-23T17:58:56.664Z,1627063136.664 [ballast_and_trim:Science:PeakDetectOil] Stopped
2021-07-23T17:58:56.664Z,1627063136.664 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil
2021-07-23T17:58:56.664Z,1627063136.664 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2021-07-23T17:58:56.664Z,1627063136.664 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-07-23T17:58:56.664Z,1627063136.664 [ballast_and_trim:Science:PeakDetectNO3] Stopped
2021-07-23T17:58:56.665Z,1627063136.665 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3
2021-07-23T17:58:56.665Z,1627063136.665 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2021-07-23T17:58:56.665Z,1627063136.665 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-07-23T17:58:56.665Z,1627063136.665 [ballast_and_trim:Science:HighestChlPeakReport] Stopped
2021-07-23T17:58:56.665Z,1627063136.665 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport
2021-07-23T17:58:56.665Z,1627063136.665 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2021-07-23T17:58:56.665Z,1627063136.665 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-07-23T17:58:56.665Z,1627063136.665 [ballast_and_trim:Science:PeakDetectChl] Stopped
2021-07-23T17:58:56.665Z,1627063136.665 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl
2021-07-23T17:58:56.665Z,1627063136.665 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2021-07-23T17:58:56.665Z,1627063136.665 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-07-23T17:58:56.666Z,1627063136.666 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2021-07-23T17:58:56.666Z,1627063136.666 [ballast_and_trim:Science:Read_Oil] Stopped
2021-07-23T17:58:56.666Z,1627063136.666 [ballast_and_trim:Science:F] Stopped
2021-07-23T17:58:56.666Z,1627063136.666 [ballast_and_trim:Science:E] Running Loop=1
2021-07-23T17:58:56.667Z,1627063136.667 [ballast_and_trim:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2021-07-23T17:58:56.667Z,1627063136.667 [ballast_and_trim:Science:D] Stopped
2021-07-23T17:58:56.667Z,1627063136.667 [ballast_and_trim:Science:C] Running Loop=1
2021-07-23T17:58:56.675Z,1627063136.675 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2021-07-23T17:58:56.675Z,1627063136.675 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2021-07-23T17:58:56.688Z,1627063136.688 [ballast_and_trim:Science:B] Running Loop=1
2021-07-23T17:58:56.689Z,1627063136.689 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2021-07-23T17:58:56.689Z,1627063136.689 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2021-07-23T17:58:56.690Z,1627063136.690 [ballast_and_trim:Science:A] Stopped
2021-07-23T17:58:56.895Z,1627063136.895 [WetLabsBB2FL](INFO): Powering up
2021-07-23T17:58:56.962Z,1627063136.962 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Running Loop=1
2021-07-23T17:58:56.962Z,1627063136.962 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2021-07-23T17:58:56.962Z,1627063136.962 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2021-07-23T17:58:56.962Z,1627063136.962 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2021-07-23T17:58:56.962Z,1627063136.962 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2021-07-23T17:58:56.962Z,1627063136.962 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2021-07-23T17:58:56.962Z,1627063136.962 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2021-07-23T17:58:56.962Z,1627063136.962 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1
2021-07-23T17:58:56.963Z,1627063136.963 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:G] Running Loop=1
2021-07-23T17:58:56.965Z,1627063136.965 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:G](INFO): Going to target depth. Speed set to 0.000000 m/s 
2021-07-23T17:58:56.965Z,1627063136.965 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:G] Stopped
2021-07-23T17:58:56.966Z,1627063136.966 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Running Loop=1
2021-07-23T17:58:56.966Z,1627063136.966 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2021-07-23T17:58:56.966Z,1627063136.966 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2021-07-23T17:58:56.966Z,1627063136.966 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1
2021-07-23T17:58:56.966Z,1627063136.966 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2021-07-23T17:58:56.966Z,1627063136.966 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1
2021-07-23T17:58:56.966Z,1627063136.966 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize.
2021-07-23T17:58:56.966Z,1627063136.966 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1
2021-07-23T17:58:56.966Z,1627063136.966 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2021-07-23T17:58:56.967Z,1627063136.967 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1
2021-07-23T17:58:56.967Z,1627063136.967 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize.
2021-07-23T17:58:56.967Z,1627063136.967 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1
2021-07-23T17:58:56.967Z,1627063136.967 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2021-07-23T17:58:56.967Z,1627063136.967 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1
2021-07-23T17:58:56.967Z,1627063136.967 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1
2021-07-23T17:58:56.968Z,1627063136.968 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2021-07-23T17:58:56.968Z,1627063136.968 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2021-07-23T17:58:56.968Z,1627063136.968 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2021-07-23T17:58:56.969Z,1627063136.969 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2021-07-23T17:58:56.969Z,1627063136.969 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2021-07-23T17:58:57.371Z,1627063137.371 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped
2021-07-23T17:58:57.371Z,1627063137.371 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2021-07-23T17:58:57.371Z,1627063137.371 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped
2021-07-23T17:58:57.371Z,1627063137.371 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1
2021-07-23T17:58:57.371Z,1627063137.371 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2021-07-23T17:58:57.371Z,1627063137.371 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2021-07-23T17:58:57.371Z,1627063137.371 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize.
2021-07-23T17:58:57.384Z,1627063137.384 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1
2021-07-23T17:58:57.384Z,1627063137.384 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped
2021-07-23T17:58:57.384Z,1627063137.384 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2021-07-23T17:58:57.384Z,1627063137.384 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped
2021-07-23T17:58:57.384Z,1627063137.384 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped
2021-07-23T17:58:57.384Z,1627063137.384 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2021-07-23T17:58:57.384Z,1627063137.384 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped
2021-07-23T17:58:57.384Z,1627063137.384 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2021-07-23T17:58:57.734Z,1627063137.734 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2021-07-23T17:58:57.734Z,1627063137.734 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 2.000000 m 
2021-07-23T17:58:57.734Z,1627063137.734 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped
2021-07-23T17:58:57.735Z,1627063137.735 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1
2021-07-23T17:58:57.735Z,1627063137.735 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize.
2021-07-23T17:59:00.501Z,1627063140.501 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2021-07-23T17:59:00.550Z,1627063140.550 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2021-07-23T17:59:00.554Z,1627063140.554 [BPC1](INFO): Received data from all battery sticks.
2021-07-23T18:01:10.276Z,1627063270.276 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-23T18:01:17.909Z,1627063277.909 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped
2021-07-23T18:01:17.909Z,1627063277.909 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2021-07-23T18:01:17.909Z,1627063277.909 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped
2021-07-23T18:01:17.909Z,1627063277.909 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2021-07-23T18:01:17.909Z,1627063277.909 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped
2021-07-23T18:01:17.910Z,1627063277.910 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize.
2021-07-23T18:01:17.910Z,1627063277.910 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2021-07-23T18:01:17.910Z,1627063277.910 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Stopped
2021-07-23T18:01:17.910Z,1627063277.910 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2021-07-23T18:01:17.910Z,1627063277.910 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Stopped
2021-07-23T18:01:17.910Z,1627063277.910 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1
2021-07-23T18:01:17.911Z,1627063277.911 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2021-07-23T18:01:17.911Z,1627063277.911 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2021-07-23T18:01:17.911Z,1627063277.911 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Initialize.
2021-07-23T18:01:17.911Z,1627063277.911 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2021-07-23T18:01:17.911Z,1627063277.911 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Initialize.
2021-07-23T18:01:17.911Z,1627063277.911 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Running Loop=1
2021-07-23T18:01:18.317Z,1627063278.317 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C](INFO): Waiting for the vehicle to settle. Depth = 2.013052 m 
2021-07-23T18:01:18.318Z,1627063278.318 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Stopped
2021-07-23T18:01:18.318Z,1627063278.318 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Running Loop=1
2021-07-23T18:01:18.318Z,1627063278.318 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Initialize Wait Component.
2021-07-23T18:01:18.318Z,1627063278.318 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2021-07-23T18:01:18.318Z,1627063278.318 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2021-07-23T18:01:37.697Z,1627063297.697 [Power24vConverter](INFO): Powering down.
2021-07-23T18:01:41.402Z,1627063301.402 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-23T18:01:45.944Z,1627063305.944 [Radio_Surface](INFO): Powering down
2021-07-23T18:02:12.524Z,1627063332.524 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-23T18:02:13.528Z,1627063333.528 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2021-07-23T18:03:18.816Z,1627063398.816 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.787247
2021-07-23T18:06:25.933Z,1627063585.933 [Radio_Surface](INFO): Powering up
2021-07-23T18:06:31.636Z,1627063591.636 [DataOverHttps](INFO): Radio surface powered ON.
2021-07-23T18:07:01.756Z,1627063621.756 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-23T18:07:32.880Z,1627063652.880 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-23T18:07:36.633Z,1627063656.633 [Radio_Surface](INFO): Powering down
2021-07-23T18:08:04.008Z,1627063684.008 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-23T18:08:05.012Z,1627063685.012 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2021-07-23T18:11:19.108Z,1627063879.108 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](INFO): Done Waiting.
2021-07-23T18:11:19.108Z,1627063879.108 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Stopped
2021-07-23T18:11:19.108Z,1627063879.108 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Uninitialize Wait Component.
2021-07-23T18:11:19.108Z,1627063879.108 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E] Running Loop=1
2021-07-23T18:11:19.481Z,1627063879.481 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E](INFO): Running ballast and trim. Depth = 2.408632 m 
2021-07-23T18:11:19.481Z,1627063879.481 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E] Stopped
2021-07-23T18:11:19.481Z,1627063879.481 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.] Running Loop=1
2021-07-23T18:11:19.481Z,1627063879.481 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](INFO): Initializing BallastAndTrim.
2021-07-23T18:21:20.254Z,1627064480.254 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): BallastAndTrim completed. Estimation time: 10.01 minutes.
2021-07-23T18:21:20.255Z,1627064480.255 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Estimated buoyancyNeutral: 421.572995 +/- 6.062357 cc (conf. level 98.00%, sigma: 33.060681 cc).
2021-07-23T18:21:20.259Z,1627064480.259 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Estimated massDefault: -16.831449 +/- 0.130938 mm (conf. level 98.00%, sigma: 0.714063 mm).
2021-07-23T18:21:20.291Z,1627064480.291 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Changing persisted config values to: 421.572995 cc buoyancy neutral and -16.831449 mm mass default
2021-07-23T18:21:20.295Z,1627064480.295 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-07-23T18:21:20.295Z,1627064480.295 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2021-07-23T18:21:20.296Z,1627064480.296 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): BuoyancyServo.fastPumpDepth=6 meter;

2021-07-23T18:21:20.296Z,1627064480.296 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2021-07-23T18:21:20.296Z,1627064480.296 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): DAT.loadAtStartup=0 bool;

2021-07-23T18:21:20.296Z,1627064480.296 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): DAT.verbosity=3 count;

2021-07-23T18:21:20.296Z,1627064480.296 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.buoyancyNeutral=421.572995 cubic_centimeter;

2021-07-23T18:21:20.296Z,1627064480.296 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.kdDepthBuoy=2680 second;

2021-07-23T18:21:20.296Z,1627064480.296 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.kiDepthBuoy=0 reciprocal_second;

2021-07-23T18:21:20.297Z,1627064480.297 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.kpDepthBuoy=62 ratio;

2021-07-23T18:21:20.297Z,1627064480.297 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.massDefault=-16.831449 millimeter;

2021-07-23T18:21:20.297Z,1627064480.297 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.] Stopped
2021-07-23T18:21:20.297Z,1627064480.297 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](INFO): Uninitializing BallastAndTrim.
2021-07-23T18:21:20.298Z,1627064480.298 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2021-07-23T18:21:20.298Z,1627064480.298 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped
2021-07-23T18:21:20.299Z,1627064480.299 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2021-07-23T18:21:20.299Z,1627064480.299 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Stopped
2021-07-23T18:21:20.299Z,1627064480.299 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Uninitialize.
2021-07-23T18:21:20.299Z,1627064480.299 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Stopped
2021-07-23T18:21:20.320Z,1627064480.320 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2021-07-23T18:21:20.320Z,1627064480.320 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Stopped
2021-07-23T18:21:20.320Z,1627064480.320 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2021-07-23T18:21:20.320Z,1627064480.320 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Stopped
2021-07-23T18:21:20.320Z,1627064480.320 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Stopped
2021-07-23T18:21:20.321Z,1627064480.321 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Stopped
2021-07-23T18:21:20.321Z,1627064480.321 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Stopped
2021-07-23T18:21:20.321Z,1627064480.321 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Stopped
2021-07-23T18:21:20.321Z,1627064480.321 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Stopped
2021-07-23T18:21:20.660Z,1627064480.660 [ballast_and_trim:RunBallastAndTrim:Depth1:B](INFO): Completed ballast_and_trim:RunBallastAndTrim:Depth1:B
2021-07-23T18:21:20.660Z,1627064480.660 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Stopped
2021-07-23T18:21:20.660Z,1627064480.660 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2021-07-23T18:21:20.660Z,1627064480.660 [ballast_and_trim:RunBallastAndTrim:Depth1](INFO): Completed ballast_and_trim:RunBallastAndTrim:Depth1
2021-07-23T18:21:20.661Z,1627064480.661 [ballast_and_trim:RunBallastAndTrim:Depth1] Stopped
2021-07-23T18:21:20.661Z,1627064480.661 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1
2021-07-23T18:21:20.661Z,1627064480.661 [ballast_and_trim:RunBallastAndTrim:Depth2] Running Loop=1
2021-07-23T18:21:20.661Z,1627064480.661 [ballast_and_trim:RunBallastAndTrim:Depth2](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth2
2021-07-23T18:21:20.661Z,1627064480.661 [ballast_and_trim:RunBallastAndTrim:Depth2:A] Running Loop=1
2021-07-23T18:21:21.069Z,1627064481.069 [ballast_and_trim:RunBallastAndTrim:Depth2] Stopped
2021-07-23T18:21:21.070Z,1627064481.070 [ballast_and_trim:RunBallastAndTrim:Depth2](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth2
2021-07-23T18:21:21.070Z,1627064481.070 [ballast_and_trim:RunBallastAndTrim:Depth2:A] Stopped
2021-07-23T18:21:21.070Z,1627064481.070 [ballast_and_trim:RunBallastAndTrim](INFO): Completed ballast_and_trim:RunBallastAndTrim
2021-07-23T18:21:21.070Z,1627064481.070 [ballast_and_trim:RunBallastAndTrim] Stopped
2021-07-23T18:21:21.070Z,1627064481.070 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim
2021-07-23T18:21:21.070Z,1627064481.070 [ballast_and_trim:Float_Up] Running Loop=1
2021-07-23T18:21:21.070Z,1627064481.070 [ballast_and_trim:Float_Up](DEBUG): Aggregate::initialize ballast_and_trim:Float_Up
2021-07-23T18:21:21.070Z,1627064481.070 [ballast_and_trim:Float_Up:A.Buoyancy] Running Loop=1
2021-07-23T18:21:21.071Z,1627064481.071 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2021-07-23T18:21:21.071Z,1627064481.071 [ballast_and_trim:Float_Up:B.Wait] Running Loop=1
2021-07-23T18:21:21.071Z,1627064481.071 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Initialize Wait Component.
2021-07-23T18:21:21.484Z,1627064481.484 [ballast_and_trim:Float_Up:A.Buoyancy] Running Loop=1
2021-07-23T18:21:54.623Z,1627064514.623 [ballast_and_trim:Float_Up] Stopped
2021-07-23T18:21:54.623Z,1627064514.623 [ballast_and_trim:Float_Up](DEBUG): Aggregate::uninitialize ballast_and_trim:Float_Up
2021-07-23T18:21:54.623Z,1627064514.623 [ballast_and_trim:Float_Up:A.Buoyancy] Stopped
2021-07-23T18:21:54.623Z,1627064514.623 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2021-07-23T18:21:54.623Z,1627064514.623 [ballast_and_trim:Float_Up:B.Wait] Stopped
2021-07-23T18:21:54.624Z,1627064514.624 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Uninitialize Wait Component.
2021-07-23T18:21:54.625Z,1627064514.625 [ballast_and_trim](INFO): Completed ballast_and_trim
2021-07-23T18:21:54.625Z,1627064514.625 [MissionManager](INFO): ballast_and_trim is completed.
2021-07-23T18:21:54.626Z,1627064514.626 [MissionManager](INFO): Uninitializing Mission ballast_and_trim
2021-07-23T18:21:54.626Z,1627064514.626 [ballast_and_trim] Stopped
2021-07-23T18:21:54.626Z,1627064514.626 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim
2021-07-23T18:21:54.626Z,1627064514.626 [ballast_and_trim:Science] Stopped
2021-07-23T18:21:54.626Z,1627064514.626 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science
2021-07-23T18:21:54.626Z,1627064514.626 [ballast_and_trim:Science:B] Stopped
2021-07-23T18:21:54.626Z,1627064514.626 [ballast_and_trim:Science:C] Stopped
2021-07-23T18:21:54.626Z,1627064514.626 [ballast_and_trim:Science:E] Stopped
2021-07-23T18:21:54.626Z,1627064514.626 [ballast_and_trim:Science:OceanCurrent] Stopped
2021-07-23T18:21:54.626Z,1627064514.626 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:OceanCurrent
2021-07-23T18:21:54.627Z,1627064514.627 [ballast_and_trim:Science:OceanCurrent:A.] Stopped
2021-07-23T18:21:55.001Z,1627064515.001 [MissionManager](IMPORTANT): Started mission Default
2021-07-23T18:21:55.001Z,1627064515.001 [Default] Running Loop=1
2021-07-23T18:21:55.002Z,1627064515.002 [Default](DEBUG): Aggregate::initialize Default
2021-07-23T18:21:55.002Z,1627064515.002 [Default:B.GoToSurface] Running Loop=1
2021-07-23T18:21:55.002Z,1627064515.002 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-07-23T18:21:55.002Z,1627064515.002 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-07-23T18:21:55.002Z,1627064515.002 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-07-23T18:21:55.003Z,1627064515.003 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-07-23T18:21:55.003Z,1627064515.003 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-07-23T18:21:55.003Z,1627064515.003 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-07-23T18:21:55.004Z,1627064515.004 [Default:A.Wait] Running Loop=1
2021-07-23T18:21:55.004Z,1627064515.004 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-07-23T18:21:55.148Z,1627064515.148 [WetLabsBB2FL](INFO): Powering down
2021-07-23T18:21:55.168Z,1627064515.168 [Radio_Surface](INFO): Powering up
2021-07-23T18:22:00.484Z,1627064520.484 [DataOverHttps](INFO): Radio surface powered ON.
2021-07-23T18:22:08.346Z,1627064528.346 [Default:A.Wait](INFO): Done Waiting.
2021-07-23T18:22:08.346Z,1627064528.346 [Default:A.Wait] Stopped
2021-07-23T18:22:08.346Z,1627064528.346 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-07-23T18:22:08.735Z,1627064528.735 [Default:CheckIn] Running Loop=1
2021-07-23T18:22:08.760Z,1627064528.760 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-23T18:22:08.760Z,1627064528.760 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-23T18:22:30.620Z,1627064550.620 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-23T18:23:01.744Z,1627064581.744 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-23T18:23:32.881Z,1627064612.881 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-23T18:24:04.004Z,1627064644.004 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-23T18:24:35.128Z,1627064675.128 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-23T18:25:06.252Z,1627064706.252 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2021-07-23T18:25:34.888Z,1627064734.888 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003237
2021-07-23T18:27:08.965Z,1627064828.965 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-07-23T18:22:08.8Z
2021-07-23T18:27:08.965Z,1627064828.965 [Default:CheckIn:Read_GPS] Stopped
2021-07-23T18:27:08.966Z,1627064828.966 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-23T18:27:12.977Z,1627064832.977 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-07-23T18:27:17.468Z,1627064837.468 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20210723T175126/Courier0007.lzma
2021-07-23T18:27:18.470Z,1627064838.470 [DataOverHttps](INFO): Moved sent file to Logs/20210723T175126/Courier0007.lzma.bak
2021-07-23T18:27:18.470Z,1627064838.470 [DataOverHttps](INFO): SBD MOMSN=15758428
2021-07-23T18:27:36.370Z,1627064856.370 [DataOverHttps](INFO): Sending 1399 bytes from file Logs/20210723T175126/Express0008.lzma
2021-07-23T18:27:37.370Z,1627064857.370 [DataOverHttps](INFO): Moved sent file to Logs/20210723T175126/Express0008.lzma.bak
2021-07-23T18:27:37.370Z,1627064857.370 [DataOverHttps](INFO): SBD MOMSN=15758432
2021-07-23T18:27:43.693Z,1627064863.693 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-23T18:27:56.355Z,1627064876.355 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20210723T175126/Express0011.lzma
2021-07-23T18:27:57.358Z,1627064877.358 [DataOverHttps](INFO): Moved sent file to Logs/20210723T175126/Express0011.lzma.bak
2021-07-23T18:27:57.358Z,1627064877.358 [DataOverHttps](INFO): SBD MOMSN=15758472
2021-07-23T18:28:00.267Z,1627064880.267 [Default:CheckIn:Read_Iridium] Stopped
2021-07-23T18:28:00.267Z,1627064880.267 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-23T18:28:00.267Z,1627064880.267 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-23T18:33:00.859Z,1627065180.859 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-23T18:33:00.859Z,1627065180.859 [Default:CheckIn:C.Wait] Stopped
2021-07-23T18:33:00.859Z,1627065180.859 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-23T18:33:00.859Z,1627065180.859 [Default:CheckIn:D] Running Loop=1
2021-07-23T18:33:01.266Z,1627065181.266 [Default:CheckIn:D] Stopped
2021-07-23T18:33:01.266Z,1627065181.266 [Default:CheckIn:E] Running Loop=1
2021-07-23T18:33:01.670Z,1627065181.670 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.104397 min 
2021-07-23T18:33:01.670Z,1627065181.670 [Default:CheckIn:E] Stopped
2021-07-23T18:33:01.670Z,1627065181.670 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-23T18:33:01.670Z,1627065181.670 [Default:CheckIn] Stopped
2021-07-23T18:33:01.670Z,1627065181.670 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-23T18:33:01.671Z,1627065181.671 [Default:CheckIn](INFO): Running loop #2
2021-07-23T18:33:01.671Z,1627065181.671 [Default:CheckIn] Running Loop=2
2021-07-23T18:33:01.671Z,1627065181.671 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-23T18:33:01.671Z,1627065181.671 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-23T18:33:03.280Z,1627065183.280 [NAL9602](FAULT): GPS failed to acquire within timeout.
2021-07-23T18:33:03.281Z,1627065183.281 [NAL9602] Data Fault, FailCount= 1
2021-07-23T18:33:03.281Z,1627065183.281 [NAL9602](ERROR): Data Fault
2021-07-23T18:33:03.301Z,1627065183.301 [CBIT](ERROR): Data Fault in component: NAL9602
2021-07-23T18:33:03.677Z,1627065183.677 [NAL9602](INFO): Powering down
2021-07-23T18:33:04.503Z,1627065184.503 [CBIT](INFO): Clearing failed state for component NAL9602
2021-07-23T18:33:04.503Z,1627065184.503 [NAL9602] No Fault, FailCount= 1
2021-07-23T18:33:33.975Z,1627065213.975 [NAL9602](INFO): Powering up NAL9602
2021-07-23T18:33:44.881Z,1627065224.881 [NAL9602](INFO): NAL9602 initialized
2021-07-23T18:35:24.521Z,1627065324.521 [DataOverHttps](IMPORTANT): SBD MTMSN=20210723T183513