2021-04-01T23:04:36.048Z,1617318276.048 [Supervisor](DEBUG): Initializing supervisor.
2021-04-01T23:04:36.052Z,1617318276.052 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-04-01T23:04:36.053Z,1617318276.053 [SyncHandler](INFO): Protected caller Thread ID is 6162
2021-04-01T23:04:36.053Z,1617318276.053 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-04-01T23:04:36.054Z,1617318276.054 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-04-01T23:04:36.055Z,1617318276.055 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6163
2021-04-01T23:04:36.059Z,1617318276.059 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-04-01T23:04:36.077Z,1617318276.077 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-04-01T23:04:36.078Z,1617318276.078 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-04-01T23:04:36.078Z,1617318276.078 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6164
2021-04-01T23:04:36.080Z,1617318276.080 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-04-01T23:04:36.081Z,1617318276.081 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-04-01T23:04:36.082Z,1617318276.082 [logger ThreadHandler](INFO): Protected caller Thread ID is 6165
2021-04-01T23:04:36.085Z,1617318276.085 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-04-01T23:04:36.086Z,1617318276.086 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-04-01T23:04:36.087Z,1617318276.087 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-04-01T23:04:36.561Z,1617318276.561 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-04-01T23:04:36.562Z,1617318276.562 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-04-01T23:04:36.644Z,1617318276.644 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-04-01T23:04:36.974Z,1617318276.974 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-04-01T23:04:36.975Z,1617318276.975 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-04-01T23:04:37.078Z,1617318277.078 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-04-01T23:04:37.079Z,1617318277.079 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-04-01T23:04:37.277Z,1617318277.277 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-04-01T23:04:37.278Z,1617318277.278 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-04-01T23:04:37.786Z,1617318277.786 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-04-01T23:04:37.787Z,1617318277.787 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-04-01T23:04:38.026Z,1617318278.026 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-04-01T23:04:38.026Z,1617318278.026 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-04-01T23:04:38.178Z,1617318278.178 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-04-01T23:04:38.179Z,1617318278.179 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-04-01T23:04:38.431Z,1617318278.431 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-04-01T23:04:38.432Z,1617318278.432 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-04-01T23:04:38.531Z,1617318278.531 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-04-01T23:04:38.531Z,1617318278.531 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-04-01T23:04:38.756Z,1617318278.756 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-04-01T23:04:38.757Z,1617318278.757 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-04-01T23:04:39.119Z,1617318279.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-04-01T23:04:39.120Z,1617318279.120 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-04-01T23:04:39.204Z,1617318279.204 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-04-01T23:04:39.332Z,1617318279.332 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-04-01T23:04:39.334Z,1617318279.334 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-04-01T23:04:40.003Z,1617318280.003 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-04-01T23:04:40.004Z,1617318280.004 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-04-01T23:04:41.533Z,1617318281.533 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-04-01T23:04:41.536Z,1617318281.536 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2021-04-01T23:04:41.537Z,1617318281.537 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2021-04-01T23:04:41.740Z,1617318281.740 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2021-04-01T23:04:41.844Z,1617318281.844 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2021-04-01T23:04:41.947Z,1617318281.947 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2021-04-01T23:04:42.203Z,1617318282.203 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-04-01T23:04:42.204Z,1617318282.204 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2021-04-01T23:04:42.292Z,1617318282.292 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2021-04-01T23:04:42.391Z,1617318282.391 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2021-04-01T23:04:42.506Z,1617318282.506 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2021-04-01T23:04:42.595Z,1617318282.595 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Dock.cfg
2021-04-01T23:04:42.684Z,1617318282.684 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2021-04-01T23:04:42.797Z,1617318282.797 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/workSite.cfg
2021-04-01T23:04:42.898Z,1617318282.898 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2021-04-01T23:04:43.093Z,1617318283.093 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2021-04-01T23:04:43.259Z,1617318283.259 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-04-01T23:04:43.269Z,1617318283.269 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-04-01T23:04:43.636Z,1617318283.636 [AHRS_M2] Loaded
2021-04-01T23:04:43.636Z,1617318283.636 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-04-01T23:04:43.664Z,1617318283.664 [BackseatComponent] Loaded
2021-04-01T23:04:43.664Z,1617318283.664 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2021-04-01T23:04:43.665Z,1617318283.665 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 407934E0
2021-04-01T23:04:43.666Z,1617318283.666 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 6253
2021-04-01T23:04:43.668Z,1617318283.668 [LcmUniversalReporter] Loaded
2021-04-01T23:04:43.669Z,1617318283.669 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2021-04-01T23:04:44.398Z,1617318284.398 [BPC1] Loaded
2021-04-01T23:04:44.398Z,1617318284.398 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-04-01T23:04:44.468Z,1617318284.468 [DataOverHttps] Loaded
2021-04-01T23:04:44.469Z,1617318284.469 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-04-01T23:04:44.470Z,1617318284.470 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407C34E0
2021-04-01T23:04:44.470Z,1617318284.470 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6254
2021-04-01T23:04:44.488Z,1617318284.488 [Depth_Keller] Loaded
2021-04-01T23:04:44.489Z,1617318284.489 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-04-01T23:04:44.493Z,1617318284.493 [DropWeight] Loaded
2021-04-01T23:04:44.493Z,1617318284.493 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2021-04-01T23:04:44.529Z,1617318284.529 [Micromodem] Loaded
2021-04-01T23:04:44.530Z,1617318284.530 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2021-04-01T23:04:44.585Z,1617318284.585 [NAL9602] Loaded
2021-04-01T23:04:44.585Z,1617318284.585 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-04-01T23:04:44.612Z,1617318284.612 [Onboard] Loaded
2021-04-01T23:04:44.613Z,1617318284.613 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-04-01T23:04:44.613Z,1617318284.613 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 407F34E0
2021-04-01T23:04:44.614Z,1617318284.614 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 6255
2021-04-01T23:04:44.619Z,1617318284.619 [PowerOnly](INFO): Adding load control power supply at /dev/loadC1
2021-04-01T23:04:44.629Z,1617318284.629 [PowerOnly] Loaded
2021-04-01T23:04:44.629Z,1617318284.629 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2021-04-01T23:04:44.642Z,1617318284.642 [Radio_Surface] Loaded
2021-04-01T23:04:44.642Z,1617318284.642 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-04-01T23:04:44.644Z,1617318284.644 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408234E0
2021-04-01T23:04:44.644Z,1617318284.644 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6256
2021-04-01T23:04:44.673Z,1617318284.673 [RDI_Pathfinder] Loaded
2021-04-01T23:04:44.673Z,1617318284.673 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2021-04-01T23:04:44.698Z,1617318284.698 [RDI_PathfinderUp] Loaded
2021-04-01T23:04:44.698Z,1617318284.698 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2021-04-01T23:04:44.699Z,1617318284.699 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-04-01T23:04:44.699Z,1617318284.699 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-04-01T23:04:44.710Z,1617318284.710 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-04-01T23:04:44.710Z,1617318284.710 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-04-01T23:04:44.786Z,1617318284.786 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-04-01T23:04:44.786Z,1617318284.786 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-04-01T23:04:44.798Z,1617318284.798 [NavChart] Loaded
2021-04-01T23:04:44.799Z,1617318284.799 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-04-01T23:04:44.804Z,1617318284.804 [UniversalFixResidualReporter] Loaded
2021-04-01T23:04:44.804Z,1617318284.804 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-04-01T23:04:44.814Z,1617318284.814 [WorkSite] Loaded
2021-04-01T23:04:44.814Z,1617318284.814 [ComponentRegistry](DEBUG): SyncComponent "WorkSite" handled in the control thread.
2021-04-01T23:04:44.815Z,1617318284.815 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-04-01T23:04:44.815Z,1617318284.815 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-04-01T23:04:44.900Z,1617318284.900 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-04-01T23:04:44.900Z,1617318284.900 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-04-01T23:04:45.006Z,1617318285.006 [BuoyancyServo] Loaded
2021-04-01T23:04:45.006Z,1617318285.006 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-04-01T23:04:45.026Z,1617318285.026 [ElevatorServo] Loaded
2021-04-01T23:04:45.027Z,1617318285.027 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-04-01T23:04:45.047Z,1617318285.047 [MassServo] Loaded
2021-04-01T23:04:45.047Z,1617318285.047 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-04-01T23:04:45.066Z,1617318285.066 [RudderServo] Loaded
2021-04-01T23:04:45.066Z,1617318285.066 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-04-01T23:04:45.084Z,1617318285.084 [ThrusterServo] Loaded
2021-04-01T23:04:45.085Z,1617318285.085 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-04-01T23:04:45.085Z,1617318285.085 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-04-01T23:04:45.085Z,1617318285.085 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-04-01T23:04:45.825Z,1617318285.825 [CTD_Seabird] Loaded
2021-04-01T23:04:45.826Z,1617318285.826 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-04-01T23:04:45.839Z,1617318285.839 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409414E0
2021-04-01T23:04:45.840Z,1617318285.840 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6257
2021-04-01T23:04:45.914Z,1617318285.914 [PAR_Licor] Loaded
2021-04-01T23:04:45.914Z,1617318285.914 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-04-01T23:04:46.033Z,1617318286.033 [WetLabsSeaOWL_UV_A] Loaded
2021-04-01T23:04:46.033Z,1617318286.033 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2021-04-01T23:04:46.034Z,1617318286.034 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409714E0
2021-04-01T23:04:46.034Z,1617318286.034 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 6258
2021-04-01T23:04:46.035Z,1617318286.035 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-04-01T23:04:46.036Z,1617318286.036 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-04-01T23:04:46.487Z,1617318286.487 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-04-01T23:04:46.488Z,1617318286.488 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-04-01T23:04:46.527Z,1617318286.527 [DepthRateCalculator] Loaded
2021-04-01T23:04:46.527Z,1617318286.527 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-04-01T23:04:46.532Z,1617318286.532 [PitchRateCalculator] Loaded
2021-04-01T23:04:46.533Z,1617318286.533 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-04-01T23:04:46.542Z,1617318286.542 [SpeedCalculator] Loaded
2021-04-01T23:04:46.542Z,1617318286.542 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-04-01T23:04:46.555Z,1617318286.555 [TempGradientCalculator] Loaded
2021-04-01T23:04:46.555Z,1617318286.555 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-04-01T23:04:46.560Z,1617318286.560 [YawRateCalculator] Loaded
2021-04-01T23:04:46.560Z,1617318286.560 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-04-01T23:04:46.580Z,1617318286.580 [ElevatorOffsetCalculator] Loaded
2021-04-01T23:04:46.581Z,1617318286.581 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-04-01T23:04:46.581Z,1617318286.581 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-04-01T23:04:46.581Z,1617318286.581 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-04-01T23:04:46.719Z,1617318286.719 [SBIT](DEBUG): Construct Startup Built In Test.
2021-04-01T23:04:46.730Z,1617318286.730 [SBIT] Loaded
2021-04-01T23:04:46.731Z,1617318286.731 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-04-01T23:04:46.733Z,1617318286.733 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-04-01T23:04:46.746Z,1617318286.746 [IBIT] Loaded
2021-04-01T23:04:46.746Z,1617318286.746 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-04-01T23:04:46.752Z,1617318286.752 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-04-01T23:04:46.848Z,1617318286.848 [CBIT] Loaded
2021-04-01T23:04:46.848Z,1617318286.848 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-04-01T23:04:46.848Z,1617318286.848 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-04-01T23:04:46.849Z,1617318286.849 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-04-01T23:04:46.901Z,1617318286.901 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-04-01T23:04:46.901Z,1617318286.901 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-04-01T23:04:46.999Z,1617318286.999 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-04-01T23:04:46.999Z,1617318286.999 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-04-01T23:04:47.048Z,1617318287.048 [VerticalControl](DEBUG): Construct VerticalControl.
2021-04-01T23:04:47.099Z,1617318287.099 [VerticalControl] Loaded
2021-04-01T23:04:47.099Z,1617318287.099 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-04-01T23:04:47.102Z,1617318287.102 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-04-01T23:04:47.136Z,1617318287.136 [HorizontalControl] Loaded
2021-04-01T23:04:47.136Z,1617318287.136 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-04-01T23:04:47.138Z,1617318287.138 [SpeedControl](DEBUG): Construct SpeedControl.
2021-04-01T23:04:47.140Z,1617318287.140 [SpeedControl] Loaded
2021-04-01T23:04:47.141Z,1617318287.141 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-04-01T23:04:47.143Z,1617318287.143 [LoopControl](DEBUG): Construct LoopControl.
2021-04-01T23:04:47.144Z,1617318287.144 [LoopControl] Loaded
2021-04-01T23:04:47.144Z,1617318287.144 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-04-01T23:04:47.144Z,1617318287.144 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-04-01T23:04:47.145Z,1617318287.145 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-04-01T23:04:47.172Z,1617318287.172 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-04-01T23:04:47.178Z,1617318287.178 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-04-01T23:04:47.181Z,1617318287.181 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-04-01T23:04:47.191Z,1617318287.191 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-04-01T23:04:47.192Z,1617318287.192 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B1B4E0
2021-04-01T23:04:47.192Z,1617318287.192 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6259
2021-04-01T23:04:47.197Z,1617318287.197 [Supervisor](INFO): Main Thread ID is 3313
2021-04-01T23:04:47.197Z,1617318287.197 [Supervisor](DEBUG): Running supervisor.
2021-04-01T23:04:47.198Z,1617318287.198 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6260
2021-04-01T23:04:47.200Z,1617318287.200 [controlThread ThreadHandler](INFO): Handler Thread ID is 6261
2021-04-01T23:04:47.200Z,1617318287.200 [controlThread](DEBUG): Initializing ControlThread
2021-04-01T23:04:47.206Z,1617318287.206 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-04-01T23:04:47.207Z,1617318287.207 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-04-01T23:04:47.207Z,1617318287.207 [WorkSite](DEBUG): Initializing WorkSite component.
2021-04-01T23:04:47.212Z,1617318287.212 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-04-01T23:04:47.212Z,1617318287.212 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-04-01T23:04:47.213Z,1617318287.213 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-04-01T23:04:47.213Z,1617318287.213 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-04-01T23:04:47.214Z,1617318287.214 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-04-01T23:04:47.214Z,1617318287.214 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-04-01T23:04:47.215Z,1617318287.215 [SBIT](INFO): Initialize SBIT Component.
2021-04-01T23:04:47.215Z,1617318287.215 [SBIT](IMPORTANT): git: 2021-03-30_A
2021-04-01T23:04:47.216Z,1617318287.216 [SBIT](INFO): git hash: cd879fcb970100e74ebf57bcee2c6db5cbfff587
2021-04-01T23:04:47.216Z,1617318287.216 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-04-01T23:04:47.217Z,1617318287.217 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Aug 6 12:04:53 PDT 2020
2021-04-01T23:04:47.218Z,1617318287.218 [SBIT](INFO): Beginning SBIT in 32.000000 seconds.
2021-04-01T23:04:47.219Z,1617318287.219 [IBIT](INFO): Initialize IBIT Component.
2021-04-01T23:04:47.220Z,1617318287.220 [CBIT](DEBUG): Initialize CBIT Component.
2021-04-01T23:04:47.221Z,1617318287.221 [logger ThreadHandler](INFO): Handler Thread ID is 6262
2021-04-01T23:04:47.232Z,1617318287.232 [CBIT](DEBUG): Initialized mux pins.
2021-04-01T23:04:47.232Z,1617318287.232 [CBIT](DEBUG): Initializing the watchdog timer.
2021-04-01T23:04:47.240Z,1617318287.240 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 6263
2021-04-01T23:04:47.252Z,1617318287.252 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6264
2021-04-01T23:04:47.253Z,1617318287.253 [DataOverHttps](DEBUG): dashIP=128.128.181.155 starts with a digit so assuming it is a numeric IP
2021-04-01T23:04:47.256Z,1617318287.256 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-04-01T23:04:47.256Z,1617318287.256 [CBIT](DEBUG): Initializing heartbeat.
2021-04-01T23:04:47.264Z,1617318287.264 [Onboard ThreadHandler](INFO): Handler Thread ID is 6265
2021-04-01T23:04:47.282Z,1617318287.282 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6266
2021-04-01T23:04:47.304Z,1617318287.304 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6267
2021-04-01T23:04:47.305Z,1617318287.305 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-04-01T23:04:47.309Z,1617318287.309 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 6269
2021-04-01T23:04:47.310Z,1617318287.310 [WetLabsSeaOWL_UV_A](INFO): Powering down
2021-04-01T23:04:47.320Z,1617318287.320 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6270
2021-04-01T23:04:47.326Z,1617318287.326 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-04-01T23:04:47.326Z,1617318287.326 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-04-01T23:04:47.326Z,1617318287.326 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-04-01T23:04:47.326Z,1617318287.326 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-04-01T23:04:47.327Z,1617318287.327 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-04-01T23:04:47.327Z,1617318287.327 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-04-01T23:04:47.327Z,1617318287.327 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-04-01T23:04:47.327Z,1617318287.327 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-04-01T23:04:47.328Z,1617318287.328 [CBIT](DEBUG): Deactivating GF circuits.
2021-04-01T23:04:47.328Z,1617318287.328 [CBIT](DEBUG): Deactivating emergency mode.
2021-04-01T23:04:47.327Z,1617318287.327 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-04-01T23:04:47.328Z,1617318287.328 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-04-01T23:04:47.328Z,1617318287.328 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-04-01T23:04:47.329Z,1617318287.329 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-04-01T23:04:47.329Z,1617318287.329 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-04-01T23:04:47.329Z,1617318287.329 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-04-01T23:04:47.329Z,1617318287.329 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-04-01T23:04:47.329Z,1617318287.329 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-04-01T23:04:47.364Z,1617318287.364 [CBIT](DEBUG): Backplane powered.
2021-04-01T23:04:47.364Z,1617318287.364 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-04-01T23:04:47.366Z,1617318287.366 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-04-01T23:04:47.366Z,1617318287.366 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-04-01T23:04:47.367Z,1617318287.367 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-04-01T23:04:47.368Z,1617318287.368 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-04-01T23:04:47.383Z,1617318287.383 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-04-01T23:04:47.427Z,1617318287.427 [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-04-01T23:04:47.428Z,1617318287.428 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-04-01T23:04:47.490Z,1617318287.490 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-04-01T23:04:47.491Z,1617318287.491 [Default:A.Wait](DEBUG): Construct Wait.
2021-04-01T23:04:47.513Z,1617318287.513 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-04-01T23:04:47.541Z,1617318287.541 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-04-01T23:04:47.543Z,1617318287.543 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-04-01T23:04:47.571Z,1617318287.571 [Default:E.Execute](DEBUG): Construct Execute.
2021-04-01T23:04:47.587Z,1617318287.587 [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-04-01T23:04:47.596Z,1617318287.596 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,Micromodem,NAL9602,PowerOnly,RDI_Pathfinder,RDI_PathfinderUp,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,WorkSite,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2021-04-01T23:04:47.602Z,1617318287.602 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-04-01T23:04:47.688Z,1617318287.688 [Radio_Surface](INFO): Powering up
2021-04-01T23:04:47.821Z,1617318287.821 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-04-01T23:04:47.828Z,1617318287.828 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-04-01T23:04:47.829Z,1617318287.829 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-04-01T23:04:47.836Z,1617318287.836 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-04-01T23:04:47.837Z,1617318287.837 [MassServo](DEBUG): Initializing EZServoServo.
2021-04-01T23:04:47.844Z,1617318287.844 [MassServo](DEBUG): Initializing MassServo.
2021-04-01T23:04:47.845Z,1617318287.845 [RudderServo](DEBUG): Initializing EZServoServo.
2021-04-01T23:04:47.852Z,1617318287.852 [RudderServo](DEBUG): Initializing RudderServo.
2021-04-01T23:04:47.853Z,1617318287.853 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-04-01T23:04:47.860Z,1617318287.860 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-04-01T23:04:48.022Z,1617318288.022 [Micromodem](INFO): Powering up
2021-04-01T23:04:48.023Z,1617318288.023 [Micromodem](DEBUG): Initializing Micromodem.
2021-04-01T23:04:48.740Z,1617318288.740 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2021-04-01T23:04:48.740Z,1617318288.740 [RudderServo](FAULT): Rudder failed to initialize
2021-04-01T23:04:48.740Z,1617318288.740 [RudderServo] Communications Fault, FailCount= 1
2021-04-01T23:04:48.740Z,1617318288.740 [RudderServo](ERROR): Communications Fault
2021-04-01T23:04:48.840Z,1617318288.840 [CBIT](ERROR): Communications Fault in component: RudderServo
2021-04-01T23:04:49.049Z,1617318289.049 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-04-01T23:04:49.049Z,1617318289.049 [RudderServo](INFO): Powering down
2021-04-01T23:04:49.676Z,1617318289.676 [RudderServo](DEBUG): Initializing EZServoServo.
2021-04-01T23:04:49.797Z,1617318289.797 [RudderServo](DEBUG): Initializing RudderServo.
2021-04-01T23:04:49.801Z,1617318289.801 [CBIT](INFO): Clearing failed state for component RudderServo
2021-04-01T23:04:49.801Z,1617318289.801 [RudderServo] No Fault, FailCount= 1
2021-04-01T23:04:52.928Z,1617318292.928 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2021-04-01T23:04:53.335Z,1617318293.335 [Micromodem](INFO): Nmea in: $CATMG,2021-04-01T23:04:53.292080Z,RTC,RTC*56

2021-04-01T23:04:53.336Z,1617318293.336 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2021-04-01T23:04:53.292080Z,RTC,RTC*56

2021-04-01T23:04:53.737Z,1617318293.737 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2021-04-01T23:04:53.738Z,1617318293.738 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2021-04-01T23:04:54.142Z,1617318294.142 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2021-04-01T23:04:54.142Z,1617318294.142 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2021-04-01T23:04:54.372Z,1617318294.372 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-04-01T23:04:54.542Z,1617318294.542 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2021-04-01T23:04:54.542Z,1617318294.542 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2021-04-01T23:04:54.946Z,1617318294.946 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2021-04-01T23:04:54.946Z,1617318294.946 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2021-04-01T23:04:55.354Z,1617318295.354 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2021-04-01T23:04:55.355Z,1617318295.355 [Micromodem](INFO): Nmea out: $CCCFG,FC0,14500*47

2021-04-01T23:04:55.742Z,1617318295.742 [Micromodem](INFO): Nmea in: $CACFG,FC0,14500*45

2021-04-01T23:04:55.742Z,1617318295.742 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2021-04-01T23:04:56.150Z,1617318296.150 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2021-04-01T23:04:56.150Z,1617318296.150 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2021-04-01T23:04:56.537Z,1617318296.537 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2021-04-01T23:04:56.538Z,1617318296.538 [Micromodem](INFO): Nmea out: $CCCLK,2021,04,01,23,04,57*47

2021-04-01T23:04:56.935Z,1617318296.935 [Micromodem](INFO): Nmea in: $CACLK,2021,4,1,23,4,57*75

2021-04-01T23:04:56.936Z,1617318296.936 [Micromodem](INFO): Nmea in: $CATMS,0,2021-04-01T23:04:58Z*7A

2021-04-01T23:04:56.938Z,1617318296.938 [Micromodem](INFO): Nmea in: $CATMG,2021-04-01T23:04:58.026170Z,USER_CMD,RTC*1F

2021-04-01T23:05:14.737Z,1617318314.737 [NAL9602](INFO): Powering up NAL9602
2021-04-01T23:05:19.591Z,1617318319.591 [SBIT](IMPORTANT): Beginning Startup BIT
2021-04-01T23:05:19.599Z,1617318319.599 [CBIT](IMPORTANT): Beginning ground fault scan
2021-04-01T23:05:25.614Z,1617318325.614 [NAL9602](INFO): NAL9602 initialized
2021-04-01T23:05:30.561Z,1617318330.561 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.009540
CHAN A1 (24V): -0.029238
CHAN A2 (12V): -0.007703
CHAN A3 (5V): -0.002006
CHAN B0 (3.3V): 0.000044
CHAN B1 (3.15aV): -0.000229
CHAN B2 (3.15bV): 0.000281
CHAN B3 (GND): 0.002436
OPEN: 0.005397
Full Scale Calc: 4.765 mA, -1.589 mA
2021-04-01T23:05:51.101Z,1617318351.101 [NAL9602](INFO): SBD MO Status=0, MOMSN=14680, MT Status=0, MTMSN=0
2021-04-01T23:05:51.102Z,1617318351.102 [NAL9602](INFO): No messages in MT queue
2021-04-01T23:06:02.390Z,1617318362.390 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-01T23:06:13.738Z,1617318373.738 [SBIT](IMPORTANT): SBIT PASSED
2021-04-01T23:06:13.739Z,1617318373.739 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-04-01T23:06:13.740Z,1617318373.740 [SBIT](IMPORTANT): CBIT.gfScanTimeout=2 hour;

2021-04-01T23:06:13.740Z,1617318373.740 [SBIT](IMPORTANT): Express none CurrentEstimator.current_direction_navigation_frame;

2021-04-01T23:06:13.740Z,1617318373.740 [SBIT](IMPORTANT): Express none CurrentEstimator.current_speed_navigation_frame;

2021-04-01T23:06:13.741Z,1617318373.741 [SBIT](IMPORTANT): Express linearApproximation mass_concentration_of_chlorophyll_in_sea_water 2.000000 microgram_per_liter;

2021-04-01T23:06:13.743Z,1617318373.743 [SBIT](IMPORTANT): Express none mass_concentration_of_petroleum_hydrocarbons_in_sea_water;

2021-04-01T23:06:13.743Z,1617318373.743 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=350.469958 cubic_centimeter;

2021-04-01T23:06:13.743Z,1617318373.743 [SBIT](IMPORTANT): VerticalControl.massDefault=3.187962 millimeter;

2021-04-01T23:06:14.161Z,1617318374.161 [WorkSite](IMPORTANT): Reading workSite position from Data/workSite.cfg
2021-04-01T23:06:14.164Z,1617318374.164 [WorkSite](IMPORTANT): WorkSite fix at 20210401T230418: (40.729737, -70.888260)
2021-04-01T23:06:14.165Z,1617318374.165 [MissionManager](IMPORTANT): Started mission Startup
2021-04-01T23:06:14.166Z,1617318374.166 [Startup] Running Loop=1
2021-04-01T23:06:14.166Z,1617318374.166 [Startup](DEBUG): Aggregate::initialize Startup
2021-04-01T23:06:14.166Z,1617318374.166 [Startup:A.GoToSurface] Running Loop=1
2021-04-01T23:06:14.166Z,1617318374.166 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-04-01T23:06:14.167Z,1617318374.167 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-04-01T23:06:14.167Z,1617318374.167 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-04-01T23:06:14.167Z,1617318374.167 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-04-01T23:06:14.168Z,1617318374.168 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-04-01T23:06:14.168Z,1617318374.168 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-04-01T23:06:14.170Z,1617318374.170 [Startup:StartupSatComms] Running Loop=1
2021-04-01T23:06:14.170Z,1617318374.170 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-04-01T23:06:14.170Z,1617318374.170 [Startup:StartupSatComms:A] Running Loop=1
2021-04-01T23:06:14.526Z,1617318374.526 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-04-01T23:07:14.337Z,1617318434.337 [Startup:StartupSatComms:A](INFO): Timed out from 2021-04-01T23:06:14.2Z
2021-04-01T23:07:14.338Z,1617318434.338 [Startup:StartupSatComms:A] Stopped
2021-04-01T23:07:14.338Z,1617318434.338 [Startup:StartupSatComms:B] Running Loop=1
2021-04-01T23:07:14.726Z,1617318434.726 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-04-01T23:07:25.739Z,1617318445.739 [NAL9602](INFO): SBD MO Status=1, MOMSN=14681, MT Status=0, MTMSN=0
2021-04-01T23:07:25.788Z,1617318445.788 [NAL9602](INFO): Sent 52 bytes from file Logs/20210401T030741/Courier0070.lzma
2021-04-01T23:07:25.788Z,1617318445.788 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:07:45.238Z,1617318465.238 [NAL9602](INFO): SBD MO Status=1, MOMSN=14682, MT Status=0, MTMSN=0
2021-04-01T23:07:45.306Z,1617318465.306 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Courier0000.lzma
2021-04-01T23:07:45.307Z,1617318465.307 [NAL9602](INFO): Packets left to send: 1
2021-04-01T23:08:11.245Z,1617318491.245 [NAL9602](INFO): SBD MO Status=1, MOMSN=14683, MT Status=0, MTMSN=0
2021-04-01T23:08:11.296Z,1617318491.296 [NAL9602](INFO): Sent 76 bytes from file Logs/20210401T230436/Courier0000.lzma
2021-04-01T23:08:11.296Z,1617318491.296 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:08:14.623Z,1617318494.623 [Startup:StartupSatComms:B](INFO): Timed out from 2021-04-01T23:07:14.3Z
2021-04-01T23:08:14.628Z,1617318494.628 [Startup:StartupSatComms:B] Stopped
2021-04-01T23:08:14.628Z,1617318494.628 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-04-01T23:08:14.628Z,1617318494.628 [Startup:StartupSatComms] Stopped
2021-04-01T23:08:14.628Z,1617318494.628 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-04-01T23:08:14.629Z,1617318494.629 [Startup](INFO): Completed Startup
2021-04-01T23:08:14.629Z,1617318494.629 [MissionManager](INFO): Startup is completed.
2021-04-01T23:08:14.629Z,1617318494.629 [MissionManager](INFO): Uninitializing Mission Startup
2021-04-01T23:08:14.629Z,1617318494.629 [Startup] Stopped
2021-04-01T23:08:14.629Z,1617318494.629 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-04-01T23:08:14.629Z,1617318494.629 [Startup:A.GoToSurface] Stopped
2021-04-01T23:08:14.629Z,1617318494.629 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-04-01T23:08:15.057Z,1617318495.057 [MissionManager](IMPORTANT): Started mission Default
2021-04-01T23:08:15.058Z,1617318495.058 [Default] Running Loop=1
2021-04-01T23:08:15.058Z,1617318495.058 [Default](DEBUG): Aggregate::initialize Default
2021-04-01T23:08:15.058Z,1617318495.058 [Default:B.GoToSurface] Running Loop=1
2021-04-01T23:08:15.058Z,1617318495.058 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-04-01T23:08:15.058Z,1617318495.058 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-04-01T23:08:15.059Z,1617318495.059 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-04-01T23:08:15.059Z,1617318495.059 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-04-01T23:08:15.059Z,1617318495.059 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-04-01T23:08:15.060Z,1617318495.060 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-04-01T23:08:15.060Z,1617318495.060 [Default:A.Wait] Running Loop=1
2021-04-01T23:08:15.060Z,1617318495.060 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-04-01T23:08:28.364Z,1617318508.364 [Default:A.Wait](INFO): Done Waiting.
2021-04-01T23:08:28.364Z,1617318508.364 [Default:A.Wait] Stopped
2021-04-01T23:08:28.364Z,1617318508.364 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-04-01T23:08:28.774Z,1617318508.774 [Default:CheckIn] Running Loop=1
2021-04-01T23:08:28.774Z,1617318508.774 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-01T23:08:28.774Z,1617318508.774 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-01T23:08:29.190Z,1617318509.190 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-04-01T23:08:31.985Z,1617318511.985 [NAL9602](INFO): SBD MO Status=1, MOMSN=14684, MT Status=0, MTMSN=0
2021-04-01T23:08:32.032Z,1617318512.032 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T030741/Express0068.lzma
2021-04-01T23:08:32.032Z,1617318512.032 [NAL9602](INFO): Packets left to send: 11
2021-04-01T23:08:33.187Z,1617318513.187 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230832.00,A,4043.76980,N,07053.31129,W,1.322,143.53,010421,,,A*7A
2021-04-01T23:08:33.190Z,1617318513.190 [NAL9602](INFO): GPS fix at 20210401T230832: (40.729497, -70.888521)
2021-04-01T23:08:33.244Z,1617318513.244 [Default:CheckIn:Read_GPS] Stopped
2021-04-01T23:08:33.248Z,1617318513.248 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-01T23:08:33.629Z,1617318513.629 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-04-01T23:08:54.715Z,1617318534.715 [NAL9602](INFO): SBD MO Status=1, MOMSN=14685, MT Status=0, MTMSN=0
2021-04-01T23:08:54.772Z,1617318534.772 [NAL9602](INFO): Sent 77 bytes from file Logs/20210401T230436/Courier0004.lzma
2021-04-01T23:08:54.772Z,1617318534.772 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:09:08.935Z,1617318548.935 [NAL9602](INFO): SBD MO Status=1, MOMSN=14686, MT Status=0, MTMSN=0
2021-04-01T23:09:08.992Z,1617318548.992 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T030741/Express0068.lzma
2021-04-01T23:09:08.992Z,1617318548.992 [NAL9602](INFO): Packets left to send: 10
2021-04-01T23:09:12.259Z,1617318552.259 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-01T23:09:19.523Z,1617318559.523 [NAL9602](INFO): SBD MO Status=1, MOMSN=14687, MT Status=0, MTMSN=0
2021-04-01T23:09:19.572Z,1617318559.572 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T030741/Express0068.lzma
2021-04-01T23:09:19.572Z,1617318559.572 [NAL9602](INFO): Packets left to send: 9
2021-04-01T23:09:28.179Z,1617318568.179 [NAL9602](INFO): SBD MO Status=1, MOMSN=14688, MT Status=0, MTMSN=0
2021-04-01T23:09:28.236Z,1617318568.236 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T030741/Express0068.lzma
2021-04-01T23:09:28.236Z,1617318568.236 [NAL9602](INFO): Packets left to send: 8
2021-04-01T23:09:40.011Z,1617318580.011 [NAL9602](INFO): SBD MO Status=1, MOMSN=14689, MT Status=0, MTMSN=0
2021-04-01T23:09:40.074Z,1617318580.074 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T030741/Express0068.lzma
2021-04-01T23:09:40.074Z,1617318580.074 [NAL9602](INFO): Packets left to send: 7
2021-04-01T23:09:51.824Z,1617318591.824 [NAL9602](INFO): SBD MO Status=1, MOMSN=14690, MT Status=0, MTMSN=0
2021-04-01T23:09:51.872Z,1617318591.872 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T030741/Express0068.lzma
2021-04-01T23:09:51.872Z,1617318591.872 [NAL9602](INFO): Packets left to send: 6
2021-04-01T23:10:05.655Z,1617318605.655 [NAL9602](INFO): SBD MO Status=1, MOMSN=14691, MT Status=0, MTMSN=0
2021-04-01T23:10:05.712Z,1617318605.712 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T030741/Express0068.lzma
2021-04-01T23:10:05.712Z,1617318605.712 [NAL9602](INFO): Packets left to send: 5
2021-04-01T23:10:13.029Z,1617318613.029 [NAL9602](INFO): SBD MO Status=1, MOMSN=14692, MT Status=0, MTMSN=0
2021-04-01T23:10:13.078Z,1617318613.078 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T030741/Express0068.lzma
2021-04-01T23:10:13.078Z,1617318613.078 [NAL9602](INFO): Packets left to send: 4
2021-04-01T23:10:32.133Z,1617318632.133 [NAL9602](INFO): SBD MO Status=1, MOMSN=14693, MT Status=0, MTMSN=0
2021-04-01T23:10:32.188Z,1617318632.188 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T030741/Express0068.lzma
2021-04-01T23:10:32.188Z,1617318632.188 [NAL9602](INFO): Packets left to send: 3
2021-04-01T23:10:43.899Z,1617318643.899 [NAL9602](INFO): SBD MO Status=1, MOMSN=14694, MT Status=0, MTMSN=0
2021-04-01T23:10:43.956Z,1617318643.956 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T030741/Express0068.lzma
2021-04-01T23:10:43.956Z,1617318643.956 [NAL9602](INFO): Packets left to send: 2
2021-04-01T23:10:55.723Z,1617318655.723 [NAL9602](INFO): SBD MO Status=1, MOMSN=14695, MT Status=0, MTMSN=0
2021-04-01T23:10:55.776Z,1617318655.776 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T030741/Express0068.lzma
2021-04-01T23:10:55.776Z,1617318655.776 [NAL9602](INFO): Packets left to send: 1
2021-04-01T23:11:06.343Z,1617318666.343 [NAL9602](INFO): SBD MO Status=1, MOMSN=14696, MT Status=0, MTMSN=0
2021-04-01T23:11:06.400Z,1617318666.400 [NAL9602](INFO): Sent 168 bytes from file Logs/20210401T030741/Express0068.lzma
2021-04-01T23:11:06.400Z,1617318666.400 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:11:14.943Z,1617318674.943 [NAL9602](INFO): SBD MO Status=1, MOMSN=14697, MT Status=0, MTMSN=0
2021-04-01T23:11:15.004Z,1617318675.004 [NAL9602](INFO): Sent 145 bytes from file Logs/20210401T030741/Express0071.lzma
2021-04-01T23:11:15.004Z,1617318675.004 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:11:44.505Z,1617318704.505 [NAL9602](INFO): SBD MO Status=2, MOMSN=14698, MT Status=2, MTMSN=0
2021-04-01T23:11:44.506Z,1617318704.506 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-01T23:12:02.028Z,1617318722.028 [NAL9602](INFO): SBD MO Status=1, MOMSN=14698, MT Status=0, MTMSN=0
2021-04-01T23:12:02.081Z,1617318722.081 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0001.lzma
2021-04-01T23:12:02.082Z,1617318722.082 [NAL9602](INFO): Packets left to send: 5
2021-04-01T23:12:11.818Z,1617318731.818 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2021-04-01T23:12:11.821Z,1617318731.821 [BPC1](INFO): Received data from all battery sticks.
2021-04-01T23:12:12.220Z,1617318732.220 [NAL9602](INFO): SBD MO Status=1, MOMSN=14699, MT Status=0, MTMSN=0
2021-04-01T23:12:12.268Z,1617318732.268 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0001.lzma
2021-04-01T23:12:12.268Z,1617318732.268 [NAL9602](INFO): Packets left to send: 4
2021-04-01T23:12:20.389Z,1617318740.389 [NAL9602](INFO): SBD MO Status=1, MOMSN=14700, MT Status=0, MTMSN=0
2021-04-01T23:12:20.438Z,1617318740.438 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0001.lzma
2021-04-01T23:12:20.438Z,1617318740.438 [NAL9602](INFO): Packets left to send: 3
2021-04-01T23:12:29.368Z,1617318749.368 [NAL9602](INFO): SBD MO Status=1, MOMSN=14701, MT Status=0, MTMSN=0
2021-04-01T23:12:29.417Z,1617318749.417 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0001.lzma
2021-04-01T23:12:29.417Z,1617318749.417 [NAL9602](INFO): Packets left to send: 2
2021-04-01T23:12:38.343Z,1617318758.343 [NAL9602](INFO): SBD MO Status=1, MOMSN=14702, MT Status=0, MTMSN=0
2021-04-01T23:12:38.392Z,1617318758.392 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0001.lzma
2021-04-01T23:12:38.392Z,1617318758.392 [NAL9602](INFO): Packets left to send: 1
2021-04-01T23:12:49.344Z,1617318769.344 [NAL9602](INFO): SBD MO Status=1, MOMSN=14703, MT Status=0, MTMSN=0
2021-04-01T23:12:49.396Z,1617318769.396 [NAL9602](INFO): Sent 63 bytes from file Logs/20210401T230436/Express0001.lzma
2021-04-01T23:12:49.396Z,1617318769.396 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:13:00.771Z,1617318780.771 [NAL9602](INFO): SBD MO Status=1, MOMSN=14704, MT Status=0, MTMSN=0
2021-04-01T23:13:00.840Z,1617318780.840 [NAL9602](INFO): Sent 93 bytes from file Logs/20210401T230436/Express0005.lzma
2021-04-01T23:13:00.840Z,1617318780.840 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:13:12.893Z,1617318792.893 [NAL9602](INFO): SBD MO Status=0, MOMSN=14705, MT Status=0, MTMSN=0
2021-04-01T23:13:12.972Z,1617318792.972 [Default:CheckIn:Read_Iridium] Stopped
2021-04-01T23:13:12.972Z,1617318792.972 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-01T23:13:12.972Z,1617318792.972 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-01T23:13:43.587Z,1617318823.587 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-01T23:15:52.465Z,1617318952.465 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-01T23:15:53.299Z,1617318953.299 [RDI_PathfinderUp](ERROR): only read 1 of 4 data items
2021-04-01T23:16:53.474Z,1617319013.474 [RDI_Pathfinder](ERROR): DVL BIT error. See manual. Result code: -32768
2021-04-01T23:16:56.307Z,1617319016.307 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2021-04-01T23:18:13.507Z,1617319093.507 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-01T23:18:13.507Z,1617319093.507 [Default:CheckIn:C.Wait] Stopped
2021-04-01T23:18:13.507Z,1617319093.507 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-01T23:18:13.507Z,1617319093.507 [Default:CheckIn:D] Running Loop=1
2021-04-01T23:18:13.890Z,1617319093.890 [Default:CheckIn:D] Stopped
2021-04-01T23:18:13.890Z,1617319093.890 [Default:CheckIn:E] Running Loop=1
2021-04-01T23:18:14.304Z,1617319094.304 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 9.980532 min 
2021-04-01T23:18:14.304Z,1617319094.304 [Default:CheckIn:E] Stopped
2021-04-01T23:18:14.304Z,1617319094.304 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-01T23:18:14.305Z,1617319094.305 [Default:CheckIn] Stopped
2021-04-01T23:18:14.305Z,1617319094.305 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-01T23:18:14.305Z,1617319094.305 [Default:CheckIn](INFO): Running loop #2
2021-04-01T23:18:14.305Z,1617319094.305 [Default:CheckIn] Running Loop=2
2021-04-01T23:18:14.305Z,1617319094.305 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-01T23:18:14.305Z,1617319094.305 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-01T23:18:16.301Z,1617319096.301 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231815.00,A,4043.74662,N,07053.35012,W,0.836,287.85,010421,,,A*7D
2021-04-01T23:18:16.303Z,1617319096.303 [NAL9602](INFO): GPS fix at 20210401T231815: (40.729110, -70.889169)
2021-04-01T23:18:16.335Z,1617319096.335 [Default:CheckIn:Read_GPS] Stopped
2021-04-01T23:18:16.335Z,1617319096.335 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-01T23:18:40.230Z,1617319120.230 [NAL9602](INFO): SBD MO Status=1, MOMSN=14706, MT Status=0, MTMSN=0
2021-04-01T23:18:40.283Z,1617319120.283 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Courier0007.lzma
2021-04-01T23:18:40.283Z,1617319120.283 [NAL9602](INFO): Packets left to send: 1
2021-04-01T23:18:48.455Z,1617319128.455 [NAL9602](INFO): SBD MO Status=1, MOMSN=14707, MT Status=0, MTMSN=0
2021-04-01T23:18:48.504Z,1617319128.504 [NAL9602](INFO): Sent 17 bytes from file Logs/20210401T230436/Courier0007.lzma
2021-04-01T23:18:48.504Z,1617319128.504 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:18:57.456Z,1617319137.456 [NAL9602](INFO): SBD MO Status=1, MOMSN=14708, MT Status=0, MTMSN=0
2021-04-01T23:18:57.504Z,1617319137.504 [NAL9602](INFO): Sent 151 bytes from file Logs/20210401T230436/Express0008.lzma
2021-04-01T23:18:57.504Z,1617319137.504 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:19:05.939Z,1617319145.939 [NAL9602](INFO): SBD MO Status=0, MOMSN=14709, MT Status=0, MTMSN=0
2021-04-01T23:19:06.017Z,1617319146.017 [Default:CheckIn:Read_Iridium] Stopped
2021-04-01T23:19:06.017Z,1617319146.017 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-01T23:19:06.017Z,1617319146.017 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-01T23:19:36.648Z,1617319176.648 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-01T23:20:38.459Z,1617319238.459 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-01T23:20:48.153Z,1617319248.153 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-01T23:20:48.182Z,1617319248.182 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2021-04-01T23:21:23.302Z,1617319283.302 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2021-04-01T23:21:23.307Z,1617319283.307 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2021-04-01T23:24:06.534Z,1617319446.534 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-01T23:24:06.534Z,1617319446.534 [Default:CheckIn:C.Wait] Stopped
2021-04-01T23:24:06.534Z,1617319446.534 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-01T23:24:06.534Z,1617319446.534 [Default:CheckIn:D] Running Loop=1
2021-04-01T23:24:06.946Z,1617319446.946 [Default:CheckIn:D] Stopped
2021-04-01T23:24:06.947Z,1617319446.947 [Default:CheckIn:E] Running Loop=1
2021-04-01T23:24:07.358Z,1617319447.358 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 15.864803 min 
2021-04-01T23:24:07.358Z,1617319447.358 [Default:CheckIn:E] Stopped
2021-04-01T23:24:07.358Z,1617319447.358 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-01T23:24:07.358Z,1617319447.358 [Default:CheckIn] Stopped
2021-04-01T23:24:07.358Z,1617319447.358 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-01T23:24:07.358Z,1617319447.358 [Default:CheckIn](INFO): Running loop #3
2021-04-01T23:24:07.358Z,1617319447.358 [Default:CheckIn] Running Loop=3
2021-04-01T23:24:07.358Z,1617319447.358 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-01T23:24:07.358Z,1617319447.358 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-01T23:24:09.348Z,1617319449.348 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232408.00,A,4043.73332,N,07053.37709,W,0.350,135.05,010421,,,A*7F
2021-04-01T23:24:09.350Z,1617319449.350 [NAL9602](INFO): GPS fix at 20210401T232408: (40.728889, -70.889618)
2021-04-01T23:24:09.414Z,1617319449.414 [Default:CheckIn:Read_GPS] Stopped
2021-04-01T23:24:09.414Z,1617319449.414 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-01T23:24:25.608Z,1617319465.608 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=14710, MT Status=1, MTMSN=579
2021-04-01T23:24:25.660Z,1617319465.660 [NAL9602](INFO): Sent 76 bytes from file Logs/20210401T230436/Courier0010.lzma
2021-04-01T23:24:25.660Z,1617319465.660 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:24:26.084Z,1617319466.084 [NAL9602](INFO): Received command:gfscan
2021-04-01T23:24:26.122Z,1617319466.122 [CommandLine](IMPORTANT): got command gfscan
2021-04-01T23:24:26.156Z,1617319466.156 [CBIT](IMPORTANT): Beginning ground fault scan
2021-04-01T23:24:38.779Z,1617319478.779 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.010096
CHAN A1 (24V): -0.029808
CHAN A2 (12V): -0.007008
CHAN A3 (5V): -0.002518
CHAN B0 (3.3V): 0.000381
CHAN B1 (3.15aV): -0.000229
CHAN B2 (3.15bV): 0.000269
CHAN B3 (GND): 0.002236
OPEN: 0.005498
Full Scale Calc: 4.765 mA, -1.589 mA
2021-04-01T23:24:40.790Z,1617319480.790 [NAL9602](INFO): SBD MO Status=1, MOMSN=14711, MT Status=0, MTMSN=0
2021-04-01T23:24:40.852Z,1617319480.852 [NAL9602](INFO): Sent 118 bytes from file Logs/20210401T230436/Express0011.lzma
2021-04-01T23:24:40.852Z,1617319480.852 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:24:56.949Z,1617319496.949 [NAL9602](INFO): SBD MO Status=0, MOMSN=14712, MT Status=0, MTMSN=0
2021-04-01T23:25:15.642Z,1617319515.642 [NAL9602](INFO): SBD MO Status=1, MOMSN=14713, MT Status=0, MTMSN=0
2021-04-01T23:25:15.696Z,1617319515.696 [NAL9602](INFO): Sent 52 bytes from file Logs/20210401T230436/Courier0013.lzma
2021-04-01T23:25:15.696Z,1617319515.696 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:25:25.019Z,1617319525.019 [NAL9602](INFO): SBD MO Status=1, MOMSN=14714, MT Status=0, MTMSN=0
2021-04-01T23:25:25.076Z,1617319525.076 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0014.lzma
2021-04-01T23:25:25.076Z,1617319525.076 [NAL9602](INFO): Packets left to send: 1
2021-04-01T23:25:32.372Z,1617319532.372 [NAL9602](INFO): SBD MO Status=1, MOMSN=14715, MT Status=0, MTMSN=0
2021-04-01T23:25:32.428Z,1617319532.428 [NAL9602](INFO): Sent 170 bytes from file Logs/20210401T230436/Express0014.lzma
2021-04-01T23:25:32.428Z,1617319532.428 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:25:40.457Z,1617319540.457 [NAL9602](INFO): SBD MO Status=0, MOMSN=14716, MT Status=0, MTMSN=0
2021-04-01T23:25:40.551Z,1617319540.551 [Default:CheckIn:Read_Iridium] Stopped
2021-04-01T23:25:40.551Z,1617319540.551 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-01T23:25:40.572Z,1617319540.572 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-01T23:26:11.155Z,1617319571.155 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-01T23:30:07.114Z,1617319807.114 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2021-04-01T23:30:41.038Z,1617319841.038 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-01T23:30:41.038Z,1617319841.038 [Default:CheckIn:C.Wait] Stopped
2021-04-01T23:30:41.038Z,1617319841.038 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-01T23:30:41.038Z,1617319841.038 [Default:CheckIn:D] Running Loop=1
2021-04-01T23:30:41.454Z,1617319841.454 [Default:CheckIn:D] Stopped
2021-04-01T23:30:41.454Z,1617319841.454 [Default:CheckIn:E] Running Loop=1
2021-04-01T23:30:41.872Z,1617319841.872 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.439935 min 
2021-04-01T23:30:41.872Z,1617319841.872 [Default:CheckIn:E] Stopped
2021-04-01T23:30:41.872Z,1617319841.872 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-01T23:30:41.872Z,1617319841.872 [Default:CheckIn] Stopped
2021-04-01T23:30:41.872Z,1617319841.872 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-01T23:30:41.872Z,1617319841.872 [Default:CheckIn](INFO): Running loop #4
2021-04-01T23:30:41.872Z,1617319841.872 [Default:CheckIn] Running Loop=4
2021-04-01T23:30:41.872Z,1617319841.872 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-01T23:30:41.873Z,1617319841.873 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-01T23:30:43.855Z,1617319843.855 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233042.00,A,4043.72006,N,07053.40722,W,0.292,279.41,010421,,,A*7C
2021-04-01T23:30:43.858Z,1617319843.858 [NAL9602](INFO): GPS fix at 20210401T233042: (40.728668, -70.890120)
2021-04-01T23:30:43.873Z,1617319843.873 [Default:CheckIn:Read_GPS] Stopped
2021-04-01T23:30:43.873Z,1617319843.873 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-01T23:31:05.778Z,1617319865.778 [NAL9602](INFO): SBD MO Status=1, MOMSN=14717, MT Status=0, MTMSN=0
2021-04-01T23:31:05.833Z,1617319865.833 [NAL9602](INFO): Sent 72 bytes from file Logs/20210401T230436/Courier0016.lzma
2021-04-01T23:31:05.833Z,1617319865.833 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:31:13.169Z,1617319873.169 [NAL9602](INFO): SBD MO Status=1, MOMSN=14718, MT Status=0, MTMSN=0
2021-04-01T23:31:13.216Z,1617319873.216 [NAL9602](INFO): Sent 120 bytes from file Logs/20210401T230436/Express0017.lzma
2021-04-01T23:31:13.216Z,1617319873.216 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:31:21.649Z,1617319881.649 [NAL9602](INFO): SBD MO Status=0, MOMSN=14719, MT Status=0, MTMSN=0
2021-04-01T23:31:21.735Z,1617319881.735 [Default:CheckIn:Read_Iridium] Stopped
2021-04-01T23:31:21.735Z,1617319881.735 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-01T23:31:21.735Z,1617319881.735 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-01T23:31:52.362Z,1617319912.362 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-01T23:36:22.303Z,1617320182.303 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-01T23:36:22.303Z,1617320182.303 [Default:CheckIn:C.Wait] Stopped
2021-04-01T23:36:22.303Z,1617320182.303 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-01T23:36:22.303Z,1617320182.303 [Default:CheckIn:D] Running Loop=1
2021-04-01T23:36:22.644Z,1617320182.644 [Default:CheckIn:D] Stopped
2021-04-01T23:36:22.644Z,1617320182.644 [Default:CheckIn:E] Running Loop=1
2021-04-01T23:36:23.046Z,1617320183.046 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.126436 min 
2021-04-01T23:36:23.046Z,1617320183.046 [Default:CheckIn:E] Stopped
2021-04-01T23:36:23.046Z,1617320183.046 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-01T23:36:23.046Z,1617320183.046 [Default:CheckIn] Stopped
2021-04-01T23:36:23.046Z,1617320183.046 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-01T23:36:23.047Z,1617320183.047 [Default:CheckIn](INFO): Running loop #5
2021-04-01T23:36:23.047Z,1617320183.047 [Default:CheckIn] Running Loop=5
2021-04-01T23:36:23.047Z,1617320183.047 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-01T23:36:23.047Z,1617320183.047 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-01T23:36:25.094Z,1617320185.094 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233624.00,A,4043.70737,N,07053.43443,W,0.933,265.28,010421,,,A*78
2021-04-01T23:36:25.103Z,1617320185.103 [NAL9602](INFO): GPS fix at 20210401T233624: (40.728456, -70.890574)
2021-04-01T23:36:25.177Z,1617320185.177 [Default:CheckIn:Read_GPS] Stopped
2021-04-01T23:36:25.177Z,1617320185.177 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-01T23:36:55.053Z,1617320215.053 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=14720, MT Status=1, MTMSN=580
2021-04-01T23:36:55.100Z,1617320215.100 [NAL9602](INFO): Sent 71 bytes from file Logs/20210401T230436/Courier0019.lzma
2021-04-01T23:36:55.100Z,1617320215.100 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:36:55.556Z,1617320215.556 [NAL9602](INFO): Received command:resume
2021-04-01T23:36:55.648Z,1617320215.648 [CommandLine](IMPORTANT): got command resume
2021-04-01T23:36:55.649Z,1617320215.648 [MissionManager](ERROR): No mission loaded to resume.
2021-04-01T23:36:55.649Z,1617320215.649 [CommandLine](CRITICAL): Could not resume mission.
2021-04-01T23:36:55.891Z,1617320215.891 [CommandLine](FAULT): Scheduling is paused
2021-04-01T23:36:55.891Z,1617320215.891 [CBIT](INFO): Critical error at 20210401T233655
2021-04-01T23:36:55.891Z,1617320215.891 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2021-04-01T23:37:17.810Z,1617320237.810 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=14721, MT Status=1, MTMSN=581
2021-04-01T23:37:17.860Z,1617320237.860 [NAL9602](INFO): Sent 119 bytes from file Logs/20210401T230436/Express0020.lzma
2021-04-01T23:37:17.860Z,1617320237.860 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:37:18.296Z,1617320238.296 [NAL9602](INFO): Received command:stop
2021-04-01T23:37:18.400Z,1617320238.400 [CommandLine](IMPORTANT): got command stop
2021-04-01T23:37:18.400Z,1617320238.400 [CommandLine](IMPORTANT): Scheduling is paused
2021-04-01T23:37:30.731Z,1617320250.731 [NAL9602](INFO): SBD MO Status=0, MOMSN=14722, MT Status=0, MTMSN=0
2021-04-01T23:37:57.927Z,1617320277.927 [NAL9602](INFO): SBD MO Status=1, MOMSN=14723, MT Status=0, MTMSN=0
2021-04-01T23:37:57.976Z,1617320277.976 [NAL9602](INFO): Sent 115 bytes from file Logs/20210401T230436/Courier0022.lzma
2021-04-01T23:37:57.976Z,1617320277.976 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:38:08.565Z,1617320288.565 [NAL9602](INFO): SBD MO Status=1, MOMSN=14724, MT Status=0, MTMSN=0
2021-04-01T23:38:08.616Z,1617320288.616 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0023.lzma
2021-04-01T23:38:08.616Z,1617320288.616 [NAL9602](INFO): Packets left to send: 1
2021-04-01T23:38:23.179Z,1617320303.179 [NAL9602](INFO): SBD MO Status=1, MOMSN=14725, MT Status=0, MTMSN=0
2021-04-01T23:38:23.236Z,1617320303.236 [NAL9602](INFO): Sent 1 bytes from file Logs/20210401T230436/Express0023.lzma
2021-04-01T23:38:23.236Z,1617320303.236 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:38:33.683Z,1617320313.683 [NAL9602](INFO): SBD MO Status=0, MOMSN=14726, MT Status=0, MTMSN=0
2021-04-01T23:38:33.792Z,1617320313.792 [Default:CheckIn:Read_Iridium] Stopped
2021-04-01T23:38:33.792Z,1617320313.792 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-01T23:38:33.792Z,1617320313.792 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-01T23:39:04.385Z,1617320344.385 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-01T23:40:59.178Z,1617320459.178 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-01T23:41:34.308Z,1617320494.308 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2021-04-01T23:41:39.175Z,1617320499.175 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-01T23:43:34.299Z,1617320614.299 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-01T23:43:34.299Z,1617320614.299 [Default:CheckIn:C.Wait] Stopped
2021-04-01T23:43:34.300Z,1617320614.300 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-01T23:43:34.300Z,1617320614.300 [Default:CheckIn:D] Running Loop=1
2021-04-01T23:43:34.685Z,1617320614.685 [Default:CheckIn:D] Stopped
2021-04-01T23:43:34.685Z,1617320614.685 [Default:CheckIn:E] Running Loop=1
2021-04-01T23:43:35.143Z,1617320615.143 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.327112 min 
2021-04-01T23:43:35.143Z,1617320615.143 [Default:CheckIn:E] Stopped
2021-04-01T23:43:35.143Z,1617320615.143 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-01T23:43:35.148Z,1617320615.148 [Default:CheckIn] Stopped
2021-04-01T23:43:35.148Z,1617320615.148 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-01T23:43:35.148Z,1617320615.148 [Default:CheckIn](INFO): Running loop #6
2021-04-01T23:43:35.148Z,1617320615.148 [Default:CheckIn] Running Loop=6
2021-04-01T23:43:35.148Z,1617320615.148 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-01T23:43:35.148Z,1617320615.148 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-01T23:43:37.108Z,1617320617.108 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234336.00,A,4043.69529,N,07053.45980,W,1.069,313.12,010421,,,D*73
2021-04-01T23:43:37.110Z,1617320617.110 [NAL9602](INFO): GPS fix at 20210401T234336: (40.728255, -70.890997)
2021-04-01T23:43:37.183Z,1617320617.183 [Default:CheckIn:Read_GPS] Stopped
2021-04-01T23:43:37.183Z,1617320617.183 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-01T23:43:52.512Z,1617320632.512 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=14727, MT Status=1, MTMSN=582
2021-04-01T23:43:52.564Z,1617320632.564 [NAL9602](INFO): Sent 76 bytes from file Logs/20210401T230436/Courier0025.lzma
2021-04-01T23:43:52.564Z,1617320632.564 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:43:53.012Z,1617320633.012 [NAL9602](INFO): Received command:gfscan
2021-04-01T23:43:53.113Z,1617320633.113 [CommandLine](IMPORTANT): got command gfscan
2021-04-01T23:43:53.364Z,1617320633.364 [CBIT](IMPORTANT): Beginning ground fault scan
2021-04-01T23:44:05.261Z,1617320645.261 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.009562
CHAN A1 (24V): -0.028732
CHAN A2 (12V): -0.007106
CHAN A3 (5V): -0.001984
CHAN B0 (3.3V): 0.000179
CHAN B1 (3.15aV): -0.000541
CHAN B2 (3.15bV): 0.000413
CHAN B3 (GND): 0.001821
OPEN: 0.005167
Full Scale Calc: 4.765 mA, -1.589 mA
2021-04-01T23:44:17.305Z,1617320657.305 [NAL9602](INFO): SBD MO Status=1, MOMSN=14728, MT Status=0, MTMSN=0
2021-04-01T23:44:17.354Z,1617320657.354 [NAL9602](INFO): Sent 120 bytes from file Logs/20210401T230436/Express0026.lzma
2021-04-01T23:44:17.354Z,1617320657.354 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:44:34.339Z,1617320674.339 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-04-01T23:44:35.473Z,1617320675.473 [NAL9602](INFO): SBD MO Status=2, MOMSN=14729, MT Status=2, MTMSN=0
2021-04-01T23:44:35.473Z,1617320675.473 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-01T23:44:52.040Z,1617320692.040 [NAL9602](INFO): SBD MO Status=0, MOMSN=14729, MT Status=0, MTMSN=0
2021-04-01T23:45:22.031Z,1617320722.031 [NAL9602](INFO): SBD MO Status=1, MOMSN=14730, MT Status=0, MTMSN=0
2021-04-01T23:45:22.084Z,1617320722.084 [NAL9602](INFO): Sent 52 bytes from file Logs/20210401T230436/Courier0028.lzma
2021-04-01T23:45:22.084Z,1617320722.084 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:45:36.284Z,1617320736.284 [NAL9602](INFO): SBD MO Status=1, MOMSN=14731, MT Status=0, MTMSN=0
2021-04-01T23:45:36.335Z,1617320736.335 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0029.lzma
2021-04-01T23:45:36.335Z,1617320736.335 [NAL9602](INFO): Packets left to send: 1
2021-04-01T23:45:47.335Z,1617320747.335 [NAL9602](INFO): SBD MO Status=1, MOMSN=14732, MT Status=0, MTMSN=0
2021-04-01T23:45:47.392Z,1617320747.392 [NAL9602](INFO): Sent 140 bytes from file Logs/20210401T230436/Express0029.lzma
2021-04-01T23:45:47.392Z,1617320747.392 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:46:10.364Z,1617320770.364 [NAL9602](INFO): SBD MO Status=2, MOMSN=14733, MT Status=2, MTMSN=0
2021-04-01T23:46:10.364Z,1617320770.364 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-01T23:46:18.443Z,1617320778.443 [NAL9602](INFO): SBD MO Status=0, MOMSN=14733, MT Status=0, MTMSN=0
2021-04-01T23:46:18.561Z,1617320778.561 [Default:CheckIn:Read_Iridium] Stopped
2021-04-01T23:46:18.561Z,1617320778.561 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-01T23:46:18.561Z,1617320778.561 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-01T23:46:49.165Z,1617320809.165 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-01T23:51:19.027Z,1617321079.027 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-01T23:51:19.027Z,1617321079.027 [Default:CheckIn:C.Wait] Stopped
2021-04-01T23:51:19.028Z,1617321079.028 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-01T23:51:19.028Z,1617321079.028 [Default:CheckIn:D] Running Loop=1
2021-04-01T23:51:19.456Z,1617321079.456 [Default:CheckIn:D] Stopped
2021-04-01T23:51:19.456Z,1617321079.456 [Default:CheckIn:E] Running Loop=1
2021-04-01T23:51:19.847Z,1617321079.847 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.073295 min 
2021-04-01T23:51:19.848Z,1617321079.848 [Default:CheckIn:E] Stopped
2021-04-01T23:51:19.848Z,1617321079.848 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-01T23:51:19.848Z,1617321079.848 [Default:CheckIn] Stopped
2021-04-01T23:51:19.848Z,1617321079.848 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-01T23:51:19.848Z,1617321079.848 [Default:CheckIn](INFO): Running loop #7
2021-04-01T23:51:19.848Z,1617321079.848 [Default:CheckIn] Running Loop=7
2021-04-01T23:51:19.848Z,1617321079.848 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-01T23:51:19.848Z,1617321079.848 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-01T23:51:21.844Z,1617321081.844 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235120.00,A,4043.68131,N,07053.49542,W,0.369,273.49,010421,,,D*7E
2021-04-01T23:51:21.846Z,1617321081.846 [NAL9602](INFO): GPS fix at 20210401T235120: (40.728022, -70.891590)
2021-04-01T23:51:21.902Z,1617321081.902 [Default:CheckIn:Read_GPS] Stopped
2021-04-01T23:51:21.902Z,1617321081.902 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-01T23:51:37.308Z,1617321097.308 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=14734, MT Status=1, MTMSN=583
2021-04-01T23:51:37.376Z,1617321097.376 [NAL9602](INFO): Sent 71 bytes from file Logs/20210401T230436/Courier0031.lzma
2021-04-01T23:51:37.376Z,1617321097.376 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:51:38.065Z,1617321098.065 [NAL9602](INFO): Received command:sched asap "load Science/sci2.xml;set sci2.MissionTimeout 7 hour;set sci2.NeedCommsTime 120 minute;set sci2.Lat1 40.6967 degree;set sci2.Lon1 -70.8833 degree;set sci2.Lat2 40.573 degree;set sci2.Lon2 -70.8833 degree" z61y 1 2
2021-04-01T23:51:38.125Z,1617321098.125 [CommandLine](IMPORTANT): got command schedule asap "load Science/sci2.xml;set sci2.MissionTimeout 7 hour;set sci2.NeedCommsTime 120 minute;set sci2.Lat1 40.6967 degree;set sci2.Lon1 -70.8833 degree;set sci2.Lat2 40.573 degree;set sci2.Lon2 -70.8833 degree" z61y 1 2.000000
2021-04-01T23:51:38.126Z,1617321098.126 [CommandLine](IMPORTANT): Scheduling command #1 of 2 with id=z61y
2021-04-01T23:51:38.126Z,1617321098.126 [CommandLine](IMPORTANT): Scheduled #1 (#1 of 2 with id='z61y'): "load Science/sci2.xml;set sci2.MissionTimeout 7 hour;set sci2.NeedCommsTime 120 minute;set sci2.Lat1 40.6967 degree;set sci2.Lon1 -70.8833 degree;set sci2.Lat2 40.573 degree;set sci2.Lon2 -70.8833 degree" ASAP
2021-04-01T23:51:48.397Z,1617321108.397 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=14735, MT Status=1, MTMSN=584
2021-04-01T23:51:48.448Z,1617321108.448 [NAL9602](INFO): Sent 120 bytes from file Logs/20210401T230436/Express0032.lzma
2021-04-01T23:51:48.448Z,1617321108.448 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:51:48.988Z,1617321108.988 [NAL9602](INFO): Received command:sched asap "set sci2.YoYoMinDepth 3 meter;set sci2.YoYoMinAltitude 7 meter;run" z61y 2 2
2021-04-01T23:51:49.029Z,1617321109.029 [CommandLine](IMPORTANT): got command schedule asap "set sci2.YoYoMinDepth 3 meter;set sci2.YoYoMinAltitude 7 meter;run" z61y 2 2.000000
2021-04-01T23:51:49.029Z,1617321109.029 [CommandLine](IMPORTANT): Scheduling command #2 of 2 with id=z61y
2021-04-01T23:51:49.030Z,1617321109.030 [CommandLine](IMPORTANT): Scheduled #2 (#2 of 2 with id='z61y'): "set sci2.YoYoMinDepth 3 meter;set sci2.YoYoMinAltitude 7 meter;run" ASAP
2021-04-01T23:51:49.218Z,1617321109.218 [CommandLine](IMPORTANT): got command load ./Missions/Science/sci2.xml
2021-04-01T23:51:49.219Z,1617321109.219 [MissionManager](INFO): Loading Mission: ./Missions/Science/sci2.xml
2021-04-01T23:51:49.393Z,1617321109.392 [MissionManager](INFO): DefineArg sci2.MissionTimeout = 2.000000 h
2021-04-01T23:51:49.395Z,1617321109.395 [MissionManager](INFO): DefineArg sci2.NeedCommsTime = 60.000000 min
2021-04-01T23:51:49.398Z,1617321109.398 [MissionManager](INFO): DefineArg sci2.Repeat = 1.000000 count
2021-04-01T23:51:49.401Z,1617321109.401 [MissionManager](INFO): DefineArg sci2.Lat1 = nan arcdeg
2021-04-01T23:51:49.404Z,1617321109.404 [MissionManager](INFO): DefineArg sci2.Lon1 = nan arcdeg
2021-04-01T23:51:49.406Z,1617321109.406 [MissionManager](INFO): DefineArg sci2.Lat2 = nan arcdeg
2021-04-01T23:51:49.409Z,1617321109.409 [MissionManager](INFO): DefineArg sci2.Lon2 = nan arcdeg
2021-04-01T23:51:49.428Z,1617321109.428 [MissionManager](INFO): DefineArg sci2.Lat3 = nan arcdeg
2021-04-01T23:51:49.431Z,1617321109.431 [MissionManager](INFO): DefineArg sci2.Lon3 = nan arcdeg
2021-04-01T23:51:49.438Z,1617321109.438 [MissionManager](INFO): DefineArg sci2.Lat4 = nan arcdeg
2021-04-01T23:51:49.452Z,1617321109.452 [MissionManager](INFO): DefineArg sci2.Lon4 = nan arcdeg
2021-04-01T23:51:49.454Z,1617321109.454 [MissionManager](INFO): DefineArg sci2.Lat5 = nan arcdeg
2021-04-01T23:51:49.468Z,1617321109.468 [MissionManager](INFO): DefineArg sci2.Lon5 = nan arcdeg
2021-04-01T23:51:49.478Z,1617321109.478 [MissionManager](INFO): DefineArg sci2.Lat6 = nan arcdeg
2021-04-01T23:51:49.485Z,1617321109.485 [MissionManager](INFO): DefineArg sci2.Lon6 = nan arcdeg
2021-04-01T23:51:49.492Z,1617321109.492 [MissionManager](INFO): DefineArg sci2.Lat7 = nan arcdeg
2021-04-01T23:51:49.498Z,1617321109.498 [MissionManager](INFO): DefineArg sci2.Lon7 = nan arcdeg
2021-04-01T23:51:49.501Z,1617321109.501 [MissionManager](INFO): DefineArg sci2.Speed = 1.000000 m/s
2021-04-01T23:51:49.503Z,1617321109.503 [MissionManager](INFO): DefineArg sci2.CaptureRadius = nan m
2021-04-01T23:51:49.510Z,1617321109.510 [MissionManager](INFO): DefineArg sci2.YoYoMinDepth = 2.000000 m
2021-04-01T23:51:49.513Z,1617321109.513 [MissionManager](INFO): DefineArg sci2.YoYoMaxDepth = 200.000000 m
2021-04-01T23:51:49.528Z,1617321109.528 [MissionManager](INFO): DefineArg sci2.YoYoMinAltitude = 9.000000 m
2021-04-01T23:51:49.531Z,1617321109.531 [MissionManager](INFO): DefineArg sci2.YoYoUpPitch = 20.000000 arcdeg
2021-04-01T23:51:49.538Z,1617321109.538 [MissionManager](INFO): DefineArg sci2.YoYoDownPitch = -20.000000 arcdeg
2021-04-01T23:51:49.546Z,1617321109.546 [MissionManager](INFO): DefineArg sci2.BuoyancyNeutral = 350.469956 cc
2021-04-01T23:51:49.550Z,1617321109.550 [MissionManager](INFO): DefineArg sci2.MassDefault = 0.318796 cm
2021-04-01T23:51:49.560Z,1617321109.560 [MissionManager](INFO): DefineArg sci2.MinAltitude = 5.000000 m
2021-04-01T23:51:49.563Z,1617321109.563 [MissionManager](INFO): DefineArg sci2.MaxDepth = 205.000000 m
2021-04-01T23:51:49.570Z,1617321109.570 [MissionManager](INFO): DefineArg sci2.MinOffshore = 2.000000 km
2021-04-01T23:51:49.571Z,1617321109.571 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2021-04-01T23:51:49.961Z,1617321109.961 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectChlActive = 0 bool
2021-04-01T23:51:49.968Z,1617321109.968 [MissionManager](INFO): DefineArg sci2:Science.TimeWindowPeakReport = nan min
2021-04-01T23:51:49.974Z,1617321109.974 [MissionManager](INFO): DefineArg sci2:Science.HighestChlPeakReportActive = 0 bool
2021-04-01T23:51:49.976Z,1617321109.976 [MissionManager](INFO): DefineArg sci2:Science.PatchTracking = 0 bool
2021-04-01T23:51:49.979Z,1617321109.979 [MissionManager](INFO): DefineArg sci2:Science.FilterWidthHorizontal = 3.000000 count
2021-04-01T23:51:49.986Z,1617321109.986 [MissionManager](INFO): DefineArg sci2:Science.NumProfilesSlidingwindow = 100.000000 count
2021-04-01T23:51:49.990Z,1617321109.990 [MissionManager](INFO): DefineArg sci2:Science.OffPeakFractionHorizontal = 80.000000 %
2021-04-01T23:51:49.993Z,1617321109.993 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectNO3Active = 0 bool
2021-04-01T23:51:49.995Z,1617321109.995 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectOilActive = 0 bool
2021-04-01T23:51:50.022Z,1617321110.022 [MissionManager](INFO): DefineArg sci2:Science.PeakDetectFDOMActive = 0 bool
2021-04-01T23:51:50.029Z,1617321110.029 [MissionManager](INFO): DefineArg sci2:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2021-04-01T23:51:50.034Z,1617321110.034 [MissionManager](INFO): DefineArg sci2:Science.EnabledAanderaaO2 = 0.000000 bool
2021-04-01T23:51:50.092Z,1617321110.092 [MissionManager](INFO): DefineArg sci2:Science.EnabledNeilBrown = 0.000000 bool
2021-04-01T23:51:50.095Z,1617321110.095 [MissionManager](INFO): DefineArg sci2:Science.EnabledSeabird = 1.000000 bool
2021-04-01T23:51:50.107Z,1617321110.107 [MissionManager](INFO): DefineArg sci2:Science.EnabledWetLabsBB2FL = 0.000000 bool
2021-04-01T23:51:50.134Z,1617321110.134 [MissionManager](INFO): DefineArg sci2:Science.EnabledWetLabsSeaOWL_UV_A = 1.000000 bool
2021-04-01T23:51:50.145Z,1617321110.145 [MissionManager](INFO): DefineArg sci2:Science.EnabledWetLabsUBAT = 0.000000 bool
2021-04-01T23:51:50.148Z,1617321110.148 [MissionManager](INFO): DefineArg sci2:Science.LowPassWindowLength = 20.000000 count
2021-04-01T23:51:50.151Z,1617321110.151 [MissionManager](INFO): DefineArg sci2:Science.MedianFilterLen = 5.000000 count
2021-04-01T23:51:50.154Z,1617321110.154 [MissionManager](INFO): DefineArg sci2:Science.PeakShallowBound = nan m
2021-04-01T23:51:50.165Z,1617321110.165 [MissionManager](INFO): DefineArg sci2:Science.PeakDeepBound = nan m
2021-04-01T23:51:50.177Z,1617321110.177 [MissionManager](INFO): DefineOutput sci2:Science.PeakChl = 0.000000 ug/l
2021-04-01T23:51:50.184Z,1617321110.184 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlDepth = 0.000000 m
2021-04-01T23:51:50.187Z,1617321110.187 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlTemperature = 0.000000 degC
2021-04-01T23:51:50.194Z,1617321110.194 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlLatitude = nan arcdeg
2021-04-01T23:51:50.197Z,1617321110.197 [MissionManager](INFO): DefineOutput sci2:Science.PeakChlLongitude = nan arcdeg
2021-04-01T23:51:50.208Z,1617321110.208 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3 = 0.000000 umol/l
2021-04-01T23:51:50.211Z,1617321110.211 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Depth = 0.000000 m
2021-04-01T23:51:50.218Z,1617321110.218 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Latitude = nan arcdeg
2021-04-01T23:51:50.222Z,1617321110.222 [MissionManager](INFO): DefineOutput sci2:Science.PeakNO3Longitude = nan arcdeg
2021-04-01T23:51:50.233Z,1617321110.233 [MissionManager](INFO): DefineOutput sci2:Science.PatchChl = 0.000000 m
2021-04-01T23:51:50.240Z,1617321110.240 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlDepth = 0.000000 m
2021-04-01T23:51:50.243Z,1617321110.243 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlLatitude = nan arcdeg
2021-04-01T23:51:50.250Z,1617321110.250 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlLongitude = nan arcdeg
2021-04-01T23:51:50.257Z,1617321110.257 [MissionManager](INFO): DefineOutput sci2:Science.PatchChlDistance = nan m
2021-04-01T23:51:50.261Z,1617321110.261 [MissionManager](INFO): DefineOutput sci2:Science.PeakOil = 0.000000 kg/m3
2021-04-01T23:51:50.272Z,1617321110.272 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilDepth = 0.000000 m
2021-04-01T23:51:50.275Z,1617321110.275 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilLatitude = nan arcdeg
2021-04-01T23:51:50.282Z,1617321110.282 [MissionManager](INFO): DefineOutput sci2:Science.PeakOilLongitude = nan arcdeg
2021-04-01T23:51:50.289Z,1617321110.289 [MissionManager](INFO): DefineOutput sci2:Science.PeakFDOM = 0.000000 ppb
2021-04-01T23:51:50.292Z,1617321110.292 [MissionManager](INFO): DefineOutput sci2:Science.PeakFDOMDepth = 0.000000 m
2021-04-01T23:51:50.304Z,1617321110.304 [MissionManager](INFO): DefineOutput sci2:Science.PeakFDOMTemperature = nan degC
2021-04-01T23:51:50.307Z,1617321110.307 [MissionManager](INFO): DefineOutput sci2:Science.PeakFDOMLatitude = nan arcdeg
2021-04-01T23:51:50.314Z,1617321110.314 [MissionManager](INFO): DefineOutput sci2:Science.PeakFDOMLongitude = nan arcdeg
2021-04-01T23:51:50.318Z,1617321110.318 [MissionManager](INFO): DefineOutput sci2:Science.PatchFDOMDistance = nan m
2021-04-01T23:51:50.391Z,1617321110.391 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-04-01T23:51:50.451Z,1617321110.451 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2021-04-01T23:51:50.502Z,1617321110.502 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-04-01T23:51:50.527Z,1617321110.527 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-04-01T23:51:50.550Z,1617321110.550 [sci2:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2021-04-01T23:51:50.576Z,1617321110.576 [MissionManager](INFO): RedefineArg sci2:Science.PeakDetectChlActive = value:1 bool
2021-04-01T23:51:50.576Z,1617321110.576 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2021-04-01T23:51:50.673Z,1617321110.673 [MissionManager](INFO): DefineArg sci2:NeedComms.DiveInterval = 3.000000 h
2021-04-01T23:51:50.677Z,1617321110.677 [MissionManager](INFO): DefineArg sci2:NeedComms.WaitForPitchUp = 10.000000 min
2021-04-01T23:51:50.688Z,1617321110.688 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfacePitch = 20.000000 arcdeg
2021-04-01T23:51:50.691Z,1617321110.691 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfaceDepthRate = nan m/s
2021-04-01T23:51:50.698Z,1617321110.698 [MissionManager](INFO): DefineArg sci2:NeedComms.SurfaceSpeed = 1.000000 m/s
2021-04-01T23:51:50.703Z,1617321110.703 [MissionManager](INFO): DefineArg sci2:NeedComms.GPSTimeout = 7.000000 min
2021-04-01T23:51:50.714Z,1617321110.714 [MissionManager](INFO): DefineArg sci2:NeedComms.CommsTimeout = 30.000000 min
2021-04-01T23:51:50.737Z,1617321110.737 [sci2:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-04-01T23:51:50.770Z,1617321110.770 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2021-04-01T23:51:50.802Z,1617321110.802 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MinAltitude = 5.000000 m
2021-04-01T23:51:50.852Z,1617321110.852 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MaxDepth = 200.000000 m
2021-04-01T23:51:50.855Z,1617321110.855 [MissionManager](INFO): DefineArg sci2:StandardEnvelopes.MinOffshore = 2000.000000 m
2021-04-01T23:51:50.864Z,1617321110.864 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2021-04-01T23:51:50.890Z,1617321110.890 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2021-04-01T23:51:50.924Z,1617321110.924 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2021-04-01T23:51:50.951Z,1617321110.951 [sci2:I.Pitch](DEBUG): Construct.
2021-04-01T23:51:50.969Z,1617321110.969 [sci2:J.Buoyancy](DEBUG): Construct Buoyancy.
2021-04-01T23:51:50.976Z,1617321110.976 [sci2:K.SetSpeed](DEBUG): Construct.
2021-04-01T23:51:50.983Z,1617321110.983 [sci2:L.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2021-04-01T23:51:51.006Z,1617321111.006 [sci2:M.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2021-04-01T23:51:51.042Z,1617321111.042 [sci2:N.YoYo](DEBUG): Construct YoYo.
2021-04-01T23:51:51.105Z,1617321111.105 [sci2:Lap:Wpt1:A.Waypoint](DEBUG): Construct Waypoint.
2021-04-01T23:51:51.121Z,1617321111.121 [sci2:Lap:Wpt2:A.Waypoint](DEBUG): Construct Waypoint.
2021-04-01T23:51:51.136Z,1617321111.136 [sci2:Lap:Wpt3:A.Waypoint](DEBUG): Construct Waypoint.
2021-04-01T23:51:51.154Z,1617321111.154 [sci2:Lap:Wpt4:A.Waypoint](DEBUG): Construct Waypoint.
2021-04-01T23:51:51.184Z,1617321111.184 [sci2:Lap:Wpt5:A.Waypoint](DEBUG): Construct Waypoint.
2021-04-01T23:51:51.191Z,1617321111.191 [sci2:Lap:Wpt6:A.Waypoint](DEBUG): Construct Waypoint.
2021-04-01T23:51:51.244Z,1617321111.244 [sci2:Lap:Wpt7:A.Waypoint](DEBUG): Construct Waypoint.
2021-04-01T23:51:51.313Z,1617321111.313 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Control="Tethys/Control"
         xmlns:Guidance="Tethys/Guidance"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd"
       Id="sci2">

    <Description>
        Vehicle yo-yo's to the specified waypoints, with science turned on.
    </Description>

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

    <DefineArg Name="NeedCommsTime"><Description>
        How often to surface for commumications
    </Description><Units:minute/><Value>60</Value></DefineArg>

    <DefineArg Name="Repeat"><Description>
        Number of times the vehicle should try to cycle through waypoints..
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="Lat1"><Description>
        Latitude of waypoint 1. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon1"><Description>
        Longitude of waypoint 1. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat2"><Description>
        Latitude of waypoint 2. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon2"><Description>
        Longitude of waypoint 2. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat3"><Description>
        Latitude of waypoint 3. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon3"><Description>
        Longitude of waypoint 3. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat4"><Description>
        Latitude of waypoint 4. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon4"><Description>
        Longitude of waypoint 4. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat5"><Description>
        Latitude of waypoint 5. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon5"><Description>
        Longitude of waypoint 5. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat6"><Description>
        Latitude of waypoint 6. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon6"><Description>
        Longitude of waypoint 6. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lat7"><Description>
        Latitude of waypoint 7. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Lon7"><Description>
        Longitude of waypoint 7. If nan, waypoint is skipped.
    </Description><Units:degree/><Value>NaN</Value></DefineArg>

    <DefineArg Name="Speed"><Description>
        Speed while performing the YoYo behavior.
    </Description><Units:meter_per_second/><Value>1</Value></DefineArg>

    <DefineArg Name="CaptureRadius"><Description>
        Speed while performing the YoYo behavior.
    </Description><Units:meter/><Value>NaN</Value></DefineArg>

    <DefineArg Name="YoYoMinDepth"><Description>
        Minimum depth while performing the YoYo behavior.
    </Description><Units:meter/><Value>2</Value></DefineArg>

    <DefineArg Name="YoYoMaxDepth"><Description>
        Maximum depth while performing the YoYo behavior.
    </Description><Units:meter/><Value>200</Value></DefineArg>

    <DefineArg Name="YoYoMinAltitude"><Description>
        Minimum altitude while performing the YoYo behavior (for
        bottom-terminated YoYos).
    </Description><Units:meter/><Value>9</Value></DefineArg>

    <DefineArg Name="YoYoUpPitch"><Description>
        Vehicle up pitch while performing the YoYo behavior.
    </Description><Units:degree/><Value>20</Value></DefineArg>

    <DefineArg Name="YoYoDownPitch"><Description>
        Vehicle down pitch while performing the YoYo behavior.
    </Description><Units:degree/><Value>-20</Value></DefineArg>

    <DefineArg Name="BuoyancyNeutral"><Description>
        Buoyancy bladder position while performing the YoYo behavior. Defaults
        to buoyancyNeutral setting in the Config/Control.cfg file. Set to NaN cc
        for active buoyancy
    </Description><Control:VerticalControl.buoyancyNeutral/></DefineArg>

    <DefineArg Name="MassDefault"><Description>
        Static setting for mass during the mission. Set to NaN mm for active
        mass position
    </Description><Control:VerticalControl.massDefault/></DefineArg>

    <DefineArg Name="MinAltitude"><Description>
        Minimum height above the sea floor for the entire mission.
    </Description><Units:meter/><Value>5</Value></DefineArg>

    <DefineArg Name="MaxDepth"><Description>
        Maximum depth for the entire mission.
    </Description><Units:meter/><Value>205</Value></DefineArg>

    <DefineArg Name="MinOffshore"><Description>
        Minimum offshore distance for the entire mission.
    </Description><Units:kilometer/><Value>2</Value></DefineArg>

    <Timeout Duration="MissionTimeout"/>

    <Insert Filename="Insert/Science.xml">
        <Description>
            Most missions will run the science sensors. If you don't place this
            aggregate above NeedComms, science instruments get turned off on the
            last upcast and while floating on the surface.
        </Description>
        <RedefineArg Name="PeakDetectChlActive"><True/></RedefineArg>
    </Insert>

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

    <Assign><Sequence/><Arg Name="NeedComms:DiveInterval"/><Arg Name="NeedCommsTime"/></Assign>

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

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinAltitude"/><Arg Name="MinAltitude"/></Assign>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MaxDepth"/><Arg Name="MaxDepth"/></Assign>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinOffshore"/><Arg Name="MinOffshore"/></Assign>

    <Call Id="StartingMission" RefId="NeedComms"/>

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Arg Name="MassDefault"/></Setting>
    </Guidance:Pitch>

    <Guidance:Buoyancy>
        <Parallel/>
        <Setting><Guidance:Buoyancy.position/><Arg Name="BuoyancyNeutral"/></Setting>
    </Guidance:Buoyancy>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><Arg Name="Speed"/></Setting>
    </Guidance:SetSpeed>

    <Guidance:DepthEnvelope>
        <Description>
            Another depth envelope for the YoYo behavior. This envelope should
            fall within the limits of the standard safety envelopes in
            Insert/StandardEnvelopes.xml in order to avoid commanding high pitch
            angles for depth-terminated YoYos.
        </Description>
        <Parallel/>
        <Setting><Guidance:DepthEnvelope.minDepth/><Arg Name="YoYoMinDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.maxDepth/><Arg Name="YoYoMaxDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.downPitch/><Arg Name="YoYoDownPitch"/></Setting>
        <Setting><Guidance:DepthEnvelope.upPitch/><Arg Name="YoYoUpPitch"/></Setting>
    </Guidance:DepthEnvelope>

    <Guidance:AltitudeEnvelope>
        <Description>
            Another altitude envelope for the YoYo behavior. This envelope
            should fall within the limits of the standard safety envelopes in
            Insert/StandardEnvelopes.xml in order to avoid commanding high pitch
            angles for bottom-terminated YoYos.
        </Description>
        <Parallel/>
        <Setting><Guidance:AltitudeEnvelope.minAltitude/><Arg Name="YoYoMinAltitude"/></Setting>
        <Setting><Guidance:AltitudeEnvelope.upPitch/><Arg Name="YoYoUpPitch"/></Setting>
    </Guidance:AltitudeEnvelope>

    <Guidance:YoYo>
        <Parallel/>
        <Setting><Guidance:YoYo.downPitch/><Arg Name="YoYoDownPitch"/></Setting>
        <Setting><Guidance:YoYo.upPitch/><Arg Name="YoYoUpPitch"/></Setting>
    </Guidance:YoYo>

    <Aggregate Id="Lap">

        <Sequence Repeat="Repeat"/>

        <Aggregate Id="Wpt1">

            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat1"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon1"/></Setting>
                <Setting><Guidance:Waypoint.captureRadius/><Arg Name="CaptureRadius"/></Setting>
            </Guidance:Waypoint>

        </Aggregate>

        <Aggregate Id="Wpt2">

            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat2"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon2"/></Setting>
            </Guidance:Waypoint>

        </Aggregate>

        <Aggregate Id="Wpt3">

            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat3"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon3"/></Setting>
            </Guidance:Waypoint>

        </Aggregate>

        <Aggregate Id="Wpt4">

            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat4"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon4"/></Setting>
            </Guidance:Waypoint>

        </Aggregate>

        <Aggregate Id="Wpt5">

            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat5"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon5"/></Setting>
            </Guidance:Waypoint>

        </Aggregate>

        <Aggregate Id="Wpt6">

            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat6"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon6"/></Setting>
            </Guidance:Waypoint>

        </Aggregate>

        <Aggregate Id="Wpt7">

            <Sequence/>

            <Guidance:Waypoint>
                <Sequence/>
                <Setting><Guidance:Waypoint.latitude/><Arg Name="Lat7"/></Setting>
                <Setting><Guidance:Waypoint.longitude/><Arg Name="Lon7"/></Setting>
            </Guidance:Waypoint>

        </Aggregate>

    </Aggregate>

</Mission>


2021-04-01T23:51:51.314Z,1617321111.314 [CommandLine](IMPORTANT): Loaded ./Missions/Science/sci2.xml
2021-04-01T23:51:56.119Z,1617321116.119 [NAL9602](INFO): SBD MO Status=0, MOMSN=14736, MT Status=0, MTMSN=0
2021-04-01T23:51:58.970Z,1617321118.970 [CommandLine](IMPORTANT): got command set sci2.MissionTimeout 7.000000 hour
2021-04-01T23:51:58.971Z,1617321118.971 [CommandLine](IMPORTANT): got command set sci2.NeedCommsTime 120.000000 minute
2021-04-01T23:51:58.972Z,1617321118.972 [CommandLine](IMPORTANT): got command set sci2.Lat1 40.696701 degree
2021-04-01T23:51:58.973Z,1617321118.973 [CommandLine](IMPORTANT): got command set sci2.Lon1 -70.883301 degree
2021-04-01T23:51:58.973Z,1617321118.973 [CommandLine](IMPORTANT): got command set sci2.Lat2 40.573002 degree
2021-04-01T23:51:58.974Z,1617321118.974 [CommandLine](IMPORTANT): got command set sci2.Lon2 -70.883301 degree
2021-04-01T23:52:01.992Z,1617321121.992 [CommandLine](IMPORTANT): got command set sci2.YoYoMinDepth 3.000000 meter
2021-04-01T23:52:01.993Z,1617321121.993 [CommandLine](IMPORTANT): got command set sci2.YoYoMinAltitude 7.000000 meter
2021-04-01T23:52:01.994Z,1617321121.994 [CommandLine](IMPORTANT): got command run
2021-04-01T23:52:01.996Z,1617321121.996 [CommandLine](IMPORTANT): Running 
2021-04-01T23:52:03.257Z,1617321123.257 [Default] Stopped
2021-04-01T23:52:03.258Z,1617321123.258 [Default](DEBUG): Aggregate::uninitialize Default
2021-04-01T23:52:03.258Z,1617321123.258 [Default:B.GoToSurface] Stopped
2021-04-01T23:52:03.259Z,1617321123.259 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-04-01T23:52:03.259Z,1617321123.259 [Default:CheckIn] Stopped
2021-04-01T23:52:03.259Z,1617321123.259 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-01T23:52:03.260Z,1617321123.260 [Default:CheckIn:Read_Iridium] Stopped
2021-04-01T23:52:03.261Z,1617321123.261 [MissionManager](IMPORTANT): Started mission sci2
2021-04-01T23:52:03.262Z,1617321123.262 [sci2] Running Loop=1
2021-04-01T23:52:03.262Z,1617321123.262 [sci2](DEBUG): Aggregate::initialize sci2
2021-04-01T23:52:03.262Z,1617321123.262 [sci2:Science] Running Loop=1
2021-04-01T23:52:03.262Z,1617321123.262 [sci2:Science](DEBUG): Aggregate::initialize sci2:Science
2021-04-01T23:52:03.262Z,1617321123.262 [sci2:Science:A] Running Loop=1
2021-04-01T23:52:03.263Z,1617321123.263 [sci2:Science:B] Running Loop=1
2021-04-01T23:52:03.263Z,1617321123.263 [sci2:Science:C] Running Loop=1
2021-04-01T23:52:03.263Z,1617321123.263 [sci2:Science:D] Running Loop=1
2021-04-01T23:52:03.263Z,1617321123.263 [sci2:Science:E] Running Loop=1
2021-04-01T23:52:03.263Z,1617321123.263 [sci2:Science:F] Running Loop=1
2021-04-01T23:52:03.263Z,1617321123.263 [sci2:Science:Read_Oil] Running Loop=1
2021-04-01T23:52:03.263Z,1617321123.263 [sci2:Science:PeakDetectChl] Running Loop=1
2021-04-01T23:52:03.263Z,1617321123.263 [sci2:Science:PeakDetectChl](DEBUG): Aggregate::initialize sci2:Science:PeakDetectChl
2021-04-01T23:52:03.263Z,1617321123.263 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2021-04-01T23:52:03.263Z,1617321123.263 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-04-01T23:52:03.265Z,1617321123.265 [sci2:Science:HighestChlPeakReport] Running Loop=1
2021-04-01T23:52:03.266Z,1617321123.266 [sci2:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize sci2:Science:HighestChlPeakReport
2021-04-01T23:52:03.266Z,1617321123.266 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2021-04-01T23:52:03.266Z,1617321123.266 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2021-04-01T23:52:03.266Z,1617321123.266 [sci2:Science:PeakDetectNO3] Running Loop=1
2021-04-01T23:52:03.266Z,1617321123.266 [sci2:Science:PeakDetectNO3](DEBUG): Aggregate::initialize sci2:Science:PeakDetectNO3
2021-04-01T23:52:03.266Z,1617321123.266 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2021-04-01T23:52:03.267Z,1617321123.267 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-04-01T23:52:03.269Z,1617321123.269 [sci2:Science:PeakDetectOil] Running Loop=1
2021-04-01T23:52:03.269Z,1617321123.269 [sci2:Science:PeakDetectOil](DEBUG): Aggregate::initialize sci2:Science:PeakDetectOil
2021-04-01T23:52:03.269Z,1617321123.269 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2021-04-01T23:52:03.269Z,1617321123.269 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-04-01T23:52:03.270Z,1617321123.270 [sci2:Science:PeakDetectFDOM] Running Loop=1
2021-04-01T23:52:03.271Z,1617321123.271 [sci2:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize sci2:Science:PeakDetectFDOM
2021-04-01T23:52:03.271Z,1617321123.271 [sci2:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2021-04-01T23:52:03.271Z,1617321123.271 [sci2:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2021-04-01T23:52:03.273Z,1617321123.273 [sci2:Science:OceanCurrent] Running Loop=1
2021-04-01T23:52:03.273Z,1617321123.273 [sci2:Science:OceanCurrent](DEBUG): Aggregate::initialize sci2:Science:OceanCurrent
2021-04-01T23:52:03.273Z,1617321123.273 [sci2:Science:OceanCurrent:A.] Running Loop=1
2021-04-01T23:52:03.273Z,1617321123.273 [sci2:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2021-04-01T23:52:03.274Z,1617321123.274 [sci2:StandardEnvelopes] Running Loop=1
2021-04-01T23:52:03.274Z,1617321123.274 [sci2:StandardEnvelopes](DEBUG): Aggregate::initialize sci2:StandardEnvelopes
2021-04-01T23:52:03.274Z,1617321123.274 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2021-04-01T23:52:03.274Z,1617321123.274 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2021-04-01T23:52:03.274Z,1617321123.274 [sci2:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2021-04-01T23:52:03.274Z,1617321123.274 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2021-04-01T23:52:03.274Z,1617321123.274 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2021-04-01T23:52:03.274Z,1617321123.274 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2021-04-01T23:52:03.275Z,1617321123.275 [sci2:I.Pitch] Running Loop=1
2021-04-01T23:52:03.275Z,1617321123.275 [sci2:I.Pitch](DEBUG): Initialize.
2021-04-01T23:52:03.275Z,1617321123.275 [sci2:J.Buoyancy] Running Loop=1
2021-04-01T23:52:03.275Z,1617321123.275 [sci2:J.Buoyancy](DEBUG): Initialize Buoyancy Component.
2021-04-01T23:52:03.275Z,1617321123.275 [sci2:K.SetSpeed] Running Loop=1
2021-04-01T23:52:03.275Z,1617321123.275 [sci2:K.SetSpeed](DEBUG): Initialize.
2021-04-01T23:52:03.275Z,1617321123.275 [sci2:L.DepthEnvelope] Running Loop=1
2021-04-01T23:52:03.275Z,1617321123.275 [sci2:L.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2021-04-01T23:52:03.276Z,1617321123.276 [sci2:M.AltitudeEnvelope] Running Loop=1
2021-04-01T23:52:03.276Z,1617321123.276 [sci2:M.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2021-04-01T23:52:03.276Z,1617321123.276 [sci2:N.YoYo] Running Loop=1
2021-04-01T23:52:03.276Z,1617321123.276 [sci2:N.YoYo](DEBUG): Initialize YoYoComponent.
2021-04-01T23:52:03.277Z,1617321123.277 [sci2:C] Running Loop=1
2021-04-01T23:52:03.277Z,1617321123.277 [sci2:N.YoYo] Running Loop=1
2021-04-01T23:52:03.278Z,1617321123.278 [sci2:M.AltitudeEnvelope] Running Loop=1
2021-04-01T23:52:03.278Z,1617321123.278 [sci2:L.DepthEnvelope] Running Loop=1
2021-04-01T23:52:03.280Z,1617321123.280 [sci2:K.SetSpeed] Running Loop=1
2021-04-01T23:52:03.280Z,1617321123.280 [sci2:J.Buoyancy] Running Loop=1
2021-04-01T23:52:03.280Z,1617321123.280 [sci2:I.Pitch] Running Loop=1
2021-04-01T23:52:03.281Z,1617321123.281 [sci2:StandardEnvelopes] Running Loop=1
2021-04-01T23:52:03.281Z,1617321123.281 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2021-04-01T23:52:03.281Z,1617321123.281 [sci2:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2021-04-01T23:52:03.281Z,1617321123.281 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2021-04-01T23:52:03.282Z,1617321123.282 [sci2:C] Stopped
2021-04-01T23:52:03.282Z,1617321123.282 [sci2:E] Running Loop=1
2021-04-01T23:52:03.282Z,1617321123.282 [sci2:Science] Running Loop=1
2021-04-01T23:52:03.283Z,1617321123.283 [sci2:Science:PeakDetectFDOM] Stopped
2021-04-01T23:52:03.283Z,1617321123.283 [sci2:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectFDOM
2021-04-01T23:52:03.283Z,1617321123.283 [sci2:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2021-04-01T23:52:03.283Z,1617321123.283 [sci2:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-04-01T23:52:03.304Z,1617321123.304 [sci2:Science:PeakDetectOil] Stopped
2021-04-01T23:52:03.304Z,1617321123.304 [sci2:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectOil
2021-04-01T23:52:03.304Z,1617321123.304 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2021-04-01T23:52:03.304Z,1617321123.304 [sci2:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-04-01T23:52:03.304Z,1617321123.304 [sci2:Science:PeakDetectNO3] Stopped
2021-04-01T23:52:03.304Z,1617321123.304 [sci2:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectNO3
2021-04-01T23:52:03.304Z,1617321123.304 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2021-04-01T23:52:03.304Z,1617321123.304 [sci2:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-04-01T23:52:03.304Z,1617321123.304 [sci2:Science:HighestChlPeakReport] Stopped
2021-04-01T23:52:03.304Z,1617321123.304 [sci2:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize sci2:Science:HighestChlPeakReport
2021-04-01T23:52:03.304Z,1617321123.304 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2021-04-01T23:52:03.305Z,1617321123.305 [sci2:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2021-04-01T23:52:03.305Z,1617321123.305 [sci2:Science:PeakDetectChl] Running Loop=1
2021-04-01T23:52:03.305Z,1617321123.305 [sci2:Science:OceanCurrent:A.] Running Loop=1
2021-04-01T23:52:03.305Z,1617321123.305 [sci2:Science:Read_Oil] Running Loop=1
2021-04-01T23:52:03.306Z,1617321123.306 [sci2:Science:Read_Oil](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_petroleum_hydrocarbons_in_sea_water
2021-04-01T23:52:03.306Z,1617321123.306 [sci2:Science:F] Stopped
2021-04-01T23:52:03.306Z,1617321123.306 [sci2:Science:E] Running Loop=1
2021-04-01T23:52:03.307Z,1617321123.307 [sci2:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2021-04-01T23:52:03.307Z,1617321123.307 [sci2:Science:D] Stopped
2021-04-01T23:52:03.307Z,1617321123.307 [sci2:Science:C] Running Loop=1
2021-04-01T23:52:03.309Z,1617321123.309 [sci2:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2021-04-01T23:52:03.310Z,1617321123.310 [sci2:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2021-04-01T23:52:03.310Z,1617321123.310 [sci2:Science:B] Running Loop=1
2021-04-01T23:52:03.311Z,1617321123.311 [sci2:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2021-04-01T23:52:03.311Z,1617321123.311 [sci2:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2021-04-01T23:52:03.311Z,1617321123.311 [sci2:Science:A] Stopped
2021-04-01T23:52:03.417Z,1617321123.417 [sci2:E] Stopped
2021-04-01T23:52:03.417Z,1617321123.417 [sci2:F] Running Loop=1
2021-04-01T23:52:03.677Z,1617321123.677 [WetLabsSeaOWL_UV_A](INFO): Powering up
2021-04-01T23:52:03.786Z,1617321123.786 [sci2:F] Stopped
2021-04-01T23:52:03.787Z,1617321123.787 [sci2:G] Running Loop=1
2021-04-01T23:52:04.214Z,1617321124.214 [sci2:G] Stopped
2021-04-01T23:52:04.214Z,1617321124.214 [sci2:StartingMission] Running Loop=1
2021-04-01T23:52:04.214Z,1617321124.214 [sci2:StartingMission](DEBUG): Aggregate::initialize sci2:StartingMission
2021-04-01T23:52:04.589Z,1617321124.589 [sci2:NeedComms] Running Loop=1
2021-04-01T23:52:04.589Z,1617321124.589 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms
2021-04-01T23:52:04.589Z,1617321124.589 [sci2:NeedComms:B.GoToSurface] Running Loop=1
2021-04-01T23:52:04.589Z,1617321124.589 [sci2:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-04-01T23:52:04.589Z,1617321124.589 [sci2:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2021-04-01T23:52:04.590Z,1617321124.590 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2021-04-01T23:52:04.590Z,1617321124.590 [sci2:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2021-04-01T23:52:04.590Z,1617321124.590 [sci2:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-04-01T23:52:04.591Z,1617321124.591 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-04-01T23:52:04.591Z,1617321124.591 [sci2:NeedComms:A] Running Loop=1
2021-04-01T23:52:04.592Z,1617321124.592 [sci2:NeedComms:A](INFO): last time_fix was: 1617321080.000000 second since 1970/01/01T00:00:00Z 
2021-04-01T23:52:04.592Z,1617321124.592 [sci2:NeedComms:A] Stopped
2021-04-01T23:52:05.004Z,1617321125.004 [sci2:NeedComms:C] Running Loop=1
2021-04-01T23:52:05.446Z,1617321125.446 [sci2:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-04-01T23:52:07.001Z,1617321127.001 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2021-04-01T23:52:10.235Z,1617321130.235 [NAL9602](INFO): SBD MO Status=1, MOMSN=14737, MT Status=0, MTMSN=0
2021-04-01T23:52:10.288Z,1617321130.288 [NAL9602](INFO): Sent 52 bytes from file Logs/20210401T230436/Courier0034.lzma
2021-04-01T23:52:10.288Z,1617321130.288 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:52:11.456Z,1617321131.456 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235210.00,A,4043.67972,N,07053.49955,W,0.914,88.59,010421,,,D*43
2021-04-01T23:52:11.459Z,1617321131.459 [NAL9602](INFO): GPS fix at 20210401T235210: (40.727995, -70.891659)
2021-04-01T23:52:11.472Z,1617321131.472 [sci2:NeedComms:C] Stopped
2021-04-01T23:52:11.472Z,1617321131.472 [sci2:NeedComms:D] Running Loop=1
2021-04-01T23:52:11.891Z,1617321131.891 [sci2:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-04-01T23:52:27.755Z,1617321147.755 [NAL9602](INFO): SBD MO Status=1, MOMSN=14738, MT Status=0, MTMSN=0
2021-04-01T23:52:27.804Z,1617321147.804 [NAL9602](INFO): Sent 72 bytes from file Logs/20210401T230436/Courier0037.lzma
2021-04-01T23:52:27.804Z,1617321147.804 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:52:36.753Z,1617321156.753 [NAL9602](INFO): SBD MO Status=1, MOMSN=14739, MT Status=0, MTMSN=0
2021-04-01T23:52:36.808Z,1617321156.808 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0035.lzma
2021-04-01T23:52:36.808Z,1617321156.808 [NAL9602](INFO): Packets left to send: 2
2021-04-01T23:52:45.754Z,1617321165.754 [NAL9602](INFO): SBD MO Status=1, MOMSN=14740, MT Status=0, MTMSN=0
2021-04-01T23:52:45.804Z,1617321165.804 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0035.lzma
2021-04-01T23:52:45.804Z,1617321165.804 [NAL9602](INFO): Packets left to send: 1
2021-04-01T23:52:54.399Z,1617321174.399 [NAL9602](INFO): SBD MO Status=1, MOMSN=14741, MT Status=0, MTMSN=0
2021-04-01T23:52:54.452Z,1617321174.452 [NAL9602](INFO): Sent 59 bytes from file Logs/20210401T230436/Express0035.lzma
2021-04-01T23:52:54.452Z,1617321174.452 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:53:04.611Z,1617321184.611 [NAL9602](INFO): SBD MO Status=1, MOMSN=14742, MT Status=0, MTMSN=0
2021-04-01T23:53:04.664Z,1617321184.664 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0038.lzma
2021-04-01T23:53:04.664Z,1617321184.664 [NAL9602](INFO): Packets left to send: 2
2021-04-01T23:53:16.427Z,1617321196.427 [NAL9602](INFO): SBD MO Status=1, MOMSN=14743, MT Status=0, MTMSN=0
2021-04-01T23:53:16.476Z,1617321196.476 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0038.lzma
2021-04-01T23:53:16.476Z,1617321196.476 [NAL9602](INFO): Packets left to send: 1
2021-04-01T23:53:22.642Z,1617321202.642 [NAL9602](INFO): SBD MO Status=1, MOMSN=14744, MT Status=0, MTMSN=0
2021-04-01T23:53:22.688Z,1617321202.688 [NAL9602](INFO): Sent 62 bytes from file Logs/20210401T230436/Express0038.lzma
2021-04-01T23:53:22.688Z,1617321202.688 [NAL9602](INFO): Packets left to send: 0
2021-04-01T23:53:32.334Z,1617321212.334 [NAL9602](INFO): SBD MO Status=0, MOMSN=14745, MT Status=0, MTMSN=0
2021-04-01T23:53:32.453Z,1617321212.453 [sci2:NeedComms:D] Stopped
2021-04-01T23:53:32.453Z,1617321212.453 [sci2:NeedComms:E] Running Loop=1
2021-04-01T23:53:32.763Z,1617321212.763 [sci2:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-04-01T23:53:34.777Z,1617321214.777 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235333.00,A,4043.67735,N,07053.50717,W,1.069,218.35,010421,,,D*7D
2021-04-01T23:53:34.780Z,1617321214.780 [NAL9602](INFO): GPS fix at 20210401T235333: (40.727956, -70.891786)
2021-04-01T23:53:34.793Z,1617321214.793 [sci2:NeedComms:E] Stopped
2021-04-01T23:53:34.794Z,1617321214.794 [sci2:NeedComms](INFO): Completed sci2:NeedComms
2021-04-01T23:53:34.794Z,1617321214.794 [sci2:NeedComms] Stopped
2021-04-01T23:53:34.794Z,1617321214.794 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms
2021-04-01T23:53:34.794Z,1617321214.794 [sci2:NeedComms:B.GoToSurface] Stopped
2021-04-01T23:53:34.794Z,1617321214.794 [sci2:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-04-01T23:53:35.204Z,1617321215.204 [sci2:StartingMission](INFO): Completed sci2:StartingMission
2021-04-01T23:53:35.204Z,1617321215.204 [sci2:StartingMission] Stopped
2021-04-01T23:53:35.205Z,1617321215.205 [sci2:StartingMission](DEBUG): Aggregate::uninitialize sci2:StartingMission
2021-04-01T23:53:35.205Z,1617321215.205 [sci2:Lap] Running Loop=1
2021-04-01T23:53:35.205Z,1617321215.205 [sci2:Lap](DEBUG): Aggregate::initialize sci2:Lap
2021-04-01T23:53:35.205Z,1617321215.205 [sci2:Lap:Wpt1] Running Loop=1
2021-04-01T23:53:35.205Z,1617321215.205 [sci2:Lap:Wpt1](DEBUG): Aggregate::initialize sci2:Lap:Wpt1
2021-04-01T23:53:35.205Z,1617321215.205 [sci2:Lap:Wpt1:A.Waypoint] Running Loop=1
2021-04-01T23:53:35.205Z,1617321215.205 [sci2:Lap:Wpt1:A.Waypoint](DEBUG): Initialize WaypointComponent.
2021-04-01T23:54:01.073Z,1617321241.073 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch -20.00 deg, mass-position 3.19 mm (1 active estimators).
2021-04-01T23:54:06.709Z,1617321246.709 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-01T23:55:01.384Z,1617321301.384 [Radio_Surface](INFO): Powering down
2021-04-01T23:55:05.692Z,1617321305.692 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2021-04-01T23:57:19.823Z,1617321439.823 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 3.19 mm (2 active estimators).
2021-04-01T23:57:39.225Z,1617321459.225 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2021-04-01T23:59:55.371Z,1617321595.371 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:00:25.308Z,1617321625.308 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:00:43.864Z,1617321643.864 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2021-04-02T00:00:43.864Z,1617321643.864 [RDI_Pathfinder] Communications Fault, FailCount= 1
2021-04-02T00:00:43.864Z,1617321643.864 [RDI_Pathfinder](ERROR): Communications Fault
2021-04-02T00:00:44.023Z,1617321644.023 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-04-02T00:00:44.312Z,1617321644.312 [RDI_Pathfinder](INFO): Powering down
2021-04-02T00:00:45.219Z,1617321645.219 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-04-02T00:00:45.219Z,1617321645.219 [RDI_Pathfinder] No Fault, FailCount= 1
2021-04-02T00:01:00.409Z,1617321660.409 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2021-04-02T00:01:05.277Z,1617321665.277 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2021-04-02T00:01:10.155Z,1617321670.155 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2021-04-02T00:01:16.192Z,1617321676.192 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2021-04-02T00:01:25.458Z,1617321685.458 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:01:25.866Z,1617321685.866 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2021-04-02T00:01:30.311Z,1617321690.311 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:01:35.556Z,1617321695.556 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:01:40.411Z,1617321700.411 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:01:50.505Z,1617321710.505 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:02:00.219Z,1617321720.219 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:02:50.384Z,1617321770.384 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2021-04-02T00:02:50.645Z,1617321770.645 [CTD_Seabird](ERROR): Failed to parse device response: .26
2021-04-02T00:04:53.937Z,1617321893.937 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:05:10.503Z,1617321910.503 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2021-04-02T00:06:32.149Z,1617321992.149 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:09:33.898Z,1617322173.898 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2021-04-02T00:10:34.916Z,1617322234.916 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2021-04-02T00:11:20.952Z,1617322280.952 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2021-04-02T00:11:25.804Z,1617322285.804 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2021-04-02T00:11:35.902Z,1617322295.902 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2021-04-02T00:11:46.016Z,1617322306.016 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:11:50.849Z,1617322310.849 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2021-04-02T00:11:55.697Z,1617322315.697 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2021-04-02T00:12:00.953Z,1617322320.953 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:12:23.206Z,1617322343.206 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2021-04-02T00:12:33.271Z,1617322353.271 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2021-04-02T00:12:55.890Z,1617322375.890 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:13:20.938Z,1617322400.938 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:19:36.263Z,1617322776.263 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2021-04-02T00:19:36.265Z,1617322776.265 [BPC1](INFO): Received data from all battery sticks.
2021-04-02T00:21:55.388Z,1617322915.388 [CTD_Seabird](ERROR): Failed to parse device response: 3,3.12902,4795.20
2021-04-02T00:22:56.239Z,1617322976.239 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2021-04-02T00:24:09.873Z,1617323049.873 [CTD_Seabird](ERROR): Failed to parse device response: 48.51,5.9505,3.248434
2021-04-02T00:24:36.443Z,1617323076.443 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2021-04-02T00:25:26.186Z,1617323126.186 [CTD_Seabird](ERROR): Failed to parse device response: 5.1205,3.13563,4749.63
2021-04-02T00:26:23.496Z,1617323183.496 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:27:49.546Z,1617323269.546 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:29:46.727Z,1617323386.727 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:29:53.273Z,1617323393.273 [CTD_Seabird](ERROR): Failed to parse device response: 53.73,6.365909
2021-04-02T00:29:56.824Z,1617323396.824 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2021-04-02T00:30:01.651Z,1617323401.651 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-02T00:30:11.746Z,1617323411.746 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:30:26.701Z,1617323426.701 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2021-04-02T00:30:31.969Z,1617323431.969 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2021-04-02T00:30:56.663Z,1617323456.663 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2021-04-02T00:31:01.846Z,1617323461.846 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-02T00:31:06.696Z,1617323466.696 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2021-04-02T00:31:09.923Z,1617323469.923 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:31:11.965Z,1617323471.965 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:32:02.047Z,1617323522.047 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-02T00:32:11.745Z,1617323531.745 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:32:14.204Z,1617323534.204 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:32:17.395Z,1617323537.395 [RDI_PathfinderUp](ERROR): only read 0 of 4 data items
2021-04-02T00:40:12.137Z,1617324012.137 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:41:16.378Z,1617324076.378 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-02T00:41:42.237Z,1617324102.237 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:41:52.347Z,1617324112.347 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2021-04-02T00:41:57.191Z,1617324117.191 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2021-04-02T00:42:42.427Z,1617324162.427 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-02T00:45:17.549Z,1617324317.549 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:47:49.062Z,1617324469.062 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-02T00:48:07.636Z,1617324487.636 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2021-04-02T00:48:54.898Z,1617324534.898 [RDI_Pathfinder](ERROR): only read 0 of 4 data items
2021-04-02T00:49:37.729Z,1617324577.729 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:50:02.771Z,1617324602.771 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-02T00:50:22.606Z,1617324622.606 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2021-04-02T00:51:42.965Z,1617324702.965 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:51:47.813Z,1617324707.813 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2021-04-02T00:51:52.690Z,1617324712.690 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:53:02.953Z,1617324782.953 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-02T00:53:41.737Z,1617324821.737 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T00:57:00.178Z,1617325020.178 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2021-04-02T00:59:33.258Z,1617325173.258 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2021-04-02T01:01:03.364Z,1617325263.364 [RDI_PathfinderUp](ERROR): DVL uart error: serial timeout
2021-04-02T01:01:03.364Z,1617325263.364 [RDI_PathfinderUp] Communications Fault, FailCount= 1
2021-04-02T01:01:03.364Z,1617325263.364 [RDI_PathfinderUp](ERROR): Communications Fault
2021-04-02T01:01:03.448Z,1617325263.448 [CBIT](ERROR): Communications Fault in component: RDI_PathfinderUp
2021-04-02T01:01:03.824Z,1617325263.824 [RDI_PathfinderUp](INFO): Powering down
2021-04-02T01:01:04.625Z,1617325264.625 [CBIT](INFO): Clearing failed state for component RDI_PathfinderUp
2021-04-02T01:01:04.625Z,1617325264.625 [RDI_PathfinderUp] No Fault, FailCount= 1
2021-04-02T01:02:03.554Z,1617325323.554 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T01:02:18.482Z,1617325338.482 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-02T01:03:03.352Z,1617325383.352 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2021-04-02T01:03:03.352Z,1617325383.352 [RDI_Pathfinder] Communications Fault, FailCount= 1
2021-04-02T01:03:03.352Z,1617325383.352 [RDI_Pathfinder](ERROR): Communications Fault
2021-04-02T01:03:03.532Z,1617325383.532 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-04-02T01:03:03.812Z,1617325383.812 [RDI_Pathfinder](INFO): Powering down
2021-04-02T01:03:04.655Z,1617325384.655 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-04-02T01:03:04.655Z,1617325384.655 [RDI_Pathfinder] No Fault, FailCount= 1
2021-04-02T01:03:55.464Z,1617325435.464 [sci2:Lap:Wpt1:A.Waypoint](IMPORTANT): Reached Waypoint: 40.696702,-70.883300
2021-04-02T01:03:55.464Z,1617325435.464 [sci2:Lap:Wpt1:A.Waypoint] Stopped
2021-04-02T01:03:55.464Z,1617325435.464 [sci2:Lap:Wpt1:A.Waypoint](DEBUG): Uninitialize WaypointComponent.
2021-04-02T01:03:55.465Z,1617325435.465 [sci2:Lap:Wpt1](INFO): Completed sci2:Lap:Wpt1
2021-04-02T01:03:55.465Z,1617325435.465 [sci2:Lap:Wpt1] Stopped
2021-04-02T01:03:55.465Z,1617325435.465 [sci2:Lap:Wpt1](DEBUG): Aggregate::uninitialize sci2:Lap:Wpt1
2021-04-02T01:03:55.465Z,1617325435.465 [sci2:Lap:Wpt2] Running Loop=1
2021-04-02T01:03:55.465Z,1617325435.465 [sci2:Lap:Wpt2](DEBUG): Aggregate::initialize sci2:Lap:Wpt2
2021-04-02T01:03:55.465Z,1617325435.465 [sci2:Lap:Wpt2:A.Waypoint] Running Loop=1
2021-04-02T01:03:55.465Z,1617325435.465 [sci2:Lap:Wpt2:A.Waypoint](DEBUG): Initialize WaypointComponent.
2021-04-02T01:07:47.355Z,1617325667.355 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T01:08:18.537Z,1617325698.537 [RDI_PathfinderUp](ERROR): only read 0 of 4 data items
2021-04-02T01:10:26.943Z,1617325826.943 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T01:11:13.442Z,1617325873.442 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T01:16:04.325Z,1617326164.325 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2021-04-02T01:17:13.053Z,1617326233.053 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2021-04-02T01:19:54.215Z,1617326394.215 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-02T01:19:54.239Z,1617326394.239 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2021-04-02T01:19:59.082Z,1617326399.082 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T01:20:04.320Z,1617326404.320 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T01:20:04.325Z,1617326404.325 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T01:20:14.070Z,1617326414.070 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2021-04-02T01:20:19.270Z,1617326419.270 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2021-04-02T01:20:29.390Z,1617326429.390 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2021-04-02T01:21:49.384Z,1617326509.384 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T01:22:24.511Z,1617326544.511 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-02T01:22:49.206Z,1617326569.206 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2021-04-02T01:22:54.413Z,1617326574.413 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2021-04-02T01:22:59.288Z,1617326579.288 [RDI_PathfinderUp](ERROR): DVL uart error: serial timeout
2021-04-02T01:22:59.288Z,1617326579.288 [RDI_PathfinderUp] Communications Fault, FailCount= 1
2021-04-02T01:22:59.288Z,1617326579.288 [RDI_PathfinderUp](ERROR): Communications Fault
2021-04-02T01:22:59.370Z,1617326579.370 [CBIT](ERROR): Communications Fault in component: RDI_PathfinderUp
2021-04-02T01:22:59.728Z,1617326579.728 [RDI_PathfinderUp](INFO): Powering down
2021-04-02T01:23:00.539Z,1617326580.539 [CBIT](INFO): Clearing failed state for component RDI_PathfinderUp
2021-04-02T01:23:00.540Z,1617326580.540 [RDI_PathfinderUp] No Fault, FailCount= 1
2021-04-02T01:24:05.116Z,1617326645.116 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-02T01:24:13.191Z,1617326653.191 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2021-04-02T01:24:24.086Z,1617326664.086 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-02T01:26:58.426Z,1617326818.426 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2021-04-02T01:26:58.430Z,1617326818.430 [BPC1](INFO): Received data from all battery sticks.
2021-04-02T01:30:30.120Z,1617327030.120 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-02T01:30:54.775Z,1617327054.775 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2021-04-02T01:31:47.312Z,1617327107.312 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-02T01:33:02.091Z,1617327182.091 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-02T01:35:23.060Z,1617327323.060 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2021-04-02T01:37:45.264Z,1617327465.264 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T01:37:55.010Z,1617327475.010 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-02T01:38:05.050Z,1617327485.050 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2021-04-02T01:38:05.057Z,1617327485.057 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2021-04-02T01:38:15.166Z,1617327495.166 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2021-04-02T01:38:20.015Z,1617327500.015 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-02T01:38:20.024Z,1617327500.024 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2021-04-02T01:38:55.148Z,1617327535.148 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-02T01:39:06.069Z,1617327546.069 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2021-04-02T01:39:45.243Z,1617327585.243 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-02T01:40:50.304Z,1617327650.304 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2021-04-02T01:40:50.304Z,1617327650.304 [RDI_Pathfinder] Communications Fault, FailCount= 1
2021-04-02T01:40:50.304Z,1617327650.304 [RDI_Pathfinder](ERROR): Communications Fault
2021-04-02T01:40:50.368Z,1617327650.368 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-04-02T01:40:50.760Z,1617327650.760 [RDI_Pathfinder](INFO): Powering down
2021-04-02T01:40:51.555Z,1617327651.555 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-04-02T01:40:51.556Z,1617327651.556 [RDI_Pathfinder] No Fault, FailCount= 1
2021-04-02T01:41:50.492Z,1617327710.492 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T01:41:56.151Z,1617327716.151 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-02T01:43:25.049Z,1617327805.049 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2021-04-02T01:44:05.484Z,1617327845.484 [CBIT](IMPORTANT): Beginning ground fault scan
2021-04-02T01:44:05.609Z,1617327845.609 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2021-04-02T01:44:06.614Z,1617327846.614 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2021-04-02T01:44:07.618Z,1617327847.618 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2021-04-02T01:44:08.622Z,1617327848.622 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2021-04-02T01:44:09.629Z,1617327849.629 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2021-04-02T01:44:10.629Z,1617327850.629 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2021-04-02T01:44:11.634Z,1617327851.634 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2021-04-02T01:44:12.638Z,1617327852.638 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2021-04-02T01:44:13.641Z,1617327853.641 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2021-04-02T01:44:14.646Z,1617327854.646 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2021-04-02T01:44:15.649Z,1617327855.649 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2021-04-02T01:44:16.423Z,1617327856.423 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.230552
CHAN A1 (24V): 0.375814
CHAN A2 (12V): 0.156686
CHAN A3 (5V): 0.094677
CHAN B0 (3.3V): 0.063191
CHAN B1 (3.15aV): 0.040394
CHAN B2 (3.15bV): 0.058747
CHAN B3 (GND): 0.015310
OPEN: 0.014387
Full Scale Calc: 4.765 mA, -1.589 mA
2021-04-02T01:44:50.809Z,1617327890.809 [CTD_Seabird](ERROR): Failed to parse device response: 2.17551,4830.13
2021-04-02T01:45:20.571Z,1617327920.571 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2021-04-02T01:45:39.003Z,1617327939.003 [CTD_Seabird](ERROR): Failed to parse device response: 35703.78
2021-04-02T01:46:15.518Z,1617327975.518 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T01:47:09.651Z,1617328029.651 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2021-04-02T01:47:33.102Z,1617328053.102 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T01:47:39.145Z,1617328059.145 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T01:50:23.999Z,1617328223.999 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T01:50:29.238Z,1617328229.238 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2021-04-02T01:53:20.150Z,1617328400.150 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2021-04-02T01:53:28.225Z,1617328408.225 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T01:53:46.810Z,1617328426.810 [sci2:NeedComms] Running Loop=1
2021-04-02T01:53:46.810Z,1617328426.810 [sci2:NeedComms](DEBUG): Aggregate::initialize sci2:NeedComms
2021-04-02T01:53:46.810Z,1617328426.810 [sci2:NeedComms:B.GoToSurface] Running Loop=1
2021-04-02T01:53:46.810Z,1617328426.810 [sci2:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-04-02T01:53:46.810Z,1617328426.810 [sci2:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2021-04-02T01:53:46.811Z,1617328426.811 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2021-04-02T01:53:46.811Z,1617328426.811 [sci2:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2021-04-02T01:53:46.812Z,1617328426.812 [sci2:NeedComms:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-04-02T01:53:46.812Z,1617328426.812 [sci2:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-04-02T01:53:46.812Z,1617328426.812 [sci2:NeedComms:A] Running Loop=1
2021-04-02T01:53:46.814Z,1617328426.814 [sci2:NeedComms:A](INFO): last time_fix was: 1617321213.000000 second since 1970/01/01T00:00:00Z 
2021-04-02T01:53:46.814Z,1617328426.814 [sci2:NeedComms:A] Stopped
2021-04-02T01:54:23.999Z,1617328463.999 [sci2:NeedComms:C] Running Loop=1
2021-04-02T01:54:24.728Z,1617328464.728 [Radio_Surface](INFO): Powering up
2021-04-02T01:54:28.202Z,1617328468.202 [CTD_Seabird](ERROR): Salinity reading out of range: 14.553364 psu
2021-04-02T01:54:30.372Z,1617328470.372 [DataOverHttps](INFO): Radio surface powered ON.
2021-04-02T01:54:44.582Z,1617328484.582 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=14746, MT Status=1, MTMSN=585
2021-04-02T01:54:44.583Z,1617328484.583 [NAL9602](INFO): Data available in MT queue
2021-04-02T01:54:45.064Z,1617328485.064 [NAL9602](INFO): Received command:stop
2021-04-02T01:54:45.157Z,1617328485.157 [CommandLine](IMPORTANT): got command stop
2021-04-02T01:54:45.158Z,1617328485.158 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2021-04-02T01:54:45.402Z,1617328485.402 [MissionManager](INFO): MissionManager is completed.
2021-04-02T01:54:45.402Z,1617328485.402 [MissionManager](INFO): Uninitializing Mission sci2
2021-04-02T01:54:45.403Z,1617328485.403 [sci2] Stopped
2021-04-02T01:54:45.403Z,1617328485.403 [sci2](DEBUG): Aggregate::uninitialize sci2
2021-04-02T01:54:45.403Z,1617328485.403 [sci2:Science] Stopped
2021-04-02T01:54:45.403Z,1617328485.403 [sci2:Science](DEBUG): Aggregate::uninitialize sci2:Science
2021-04-02T01:54:45.403Z,1617328485.403 [sci2:Science:B] Stopped
2021-04-02T01:54:45.403Z,1617328485.403 [sci2:Science:C] Stopped
2021-04-02T01:54:45.403Z,1617328485.403 [sci2:Science:E] Stopped
2021-04-02T01:54:45.403Z,1617328485.403 [sci2:Science:Read_Oil] Stopped
2021-04-02T01:54:45.403Z,1617328485.403 [sci2:Science:OceanCurrent] Stopped
2021-04-02T01:54:45.403Z,1617328485.403 [sci2:Science:OceanCurrent](DEBUG): Aggregate::uninitialize sci2:Science:OceanCurrent
2021-04-02T01:54:45.403Z,1617328485.403 [sci2:Science:OceanCurrent:A.] Stopped
2021-04-02T01:54:45.403Z,1617328485.403 [sci2:Science:PeakDetectChl] Stopped
2021-04-02T01:54:45.412Z,1617328485.412 [sci2:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize sci2:Science:PeakDetectChl
2021-04-02T01:54:45.412Z,1617328485.412 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2021-04-02T01:54:45.412Z,1617328485.412 [sci2:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2021-04-02T01:54:45.412Z,1617328485.412 [sci2:NeedComms] Stopped
2021-04-02T01:54:45.412Z,1617328485.412 [sci2:NeedComms](DEBUG): Aggregate::uninitialize sci2:NeedComms
2021-04-02T01:54:45.412Z,1617328485.412 [sci2:NeedComms:B.GoToSurface] Stopped
2021-04-02T01:54:45.412Z,1617328485.412 [sci2:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-04-02T01:54:45.412Z,1617328485.412 [sci2:NeedComms:C] Stopped
2021-04-02T01:54:45.412Z,1617328485.412 [sci2:StandardEnvelopes] Stopped
2021-04-02T01:54:45.412Z,1617328485.412 [sci2:StandardEnvelopes](DEBUG): Aggregate::uninitialize sci2:StandardEnvelopes
2021-04-02T01:54:45.413Z,1617328485.413 [sci2:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2021-04-02T01:54:45.413Z,1617328485.413 [sci2:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2021-04-02T01:54:45.413Z,1617328485.413 [sci2:StandardEnvelopes:B.DepthEnvelope] Stopped
2021-04-02T01:54:45.413Z,1617328485.413 [sci2:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2021-04-02T01:54:45.413Z,1617328485.413 [sci2:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2021-04-02T01:54:45.413Z,1617328485.413 [sci2:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2021-04-02T01:54:45.413Z,1617328485.413 [sci2:I.Pitch] Stopped
2021-04-02T01:54:45.413Z,1617328485.413 [sci2:J.Buoyancy] Stopped
2021-04-02T01:54:45.413Z,1617328485.413 [sci2:J.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2021-04-02T01:54:45.413Z,1617328485.413 [sci2:K.SetSpeed] Stopped
2021-04-02T01:54:45.413Z,1617328485.413 [sci2:K.SetSpeed](DEBUG): Uninitialize.
2021-04-02T01:54:45.413Z,1617328485.413 [sci2:L.DepthEnvelope] Stopped
2021-04-02T01:54:45.413Z,1617328485.413 [sci2:L.DepthEnvelope](DEBUG): Uninitialize.
2021-04-02T01:54:45.413Z,1617328485.413 [sci2:M.AltitudeEnvelope] Stopped
2021-04-02T01:54:45.413Z,1617328485.413 [sci2:M.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2021-04-02T01:54:45.414Z,1617328485.414 [sci2:N.YoYo] Stopped
2021-04-02T01:54:45.414Z,1617328485.414 [sci2:N.YoYo](DEBUG): Uninitialize YoYoComponent.
2021-04-02T01:54:45.414Z,1617328485.414 [sci2:Lap] Stopped
2021-04-02T01:54:45.414Z,1617328485.414 [sci2:Lap](DEBUG): Aggregate::uninitialize sci2:Lap
2021-04-02T01:54:45.414Z,1617328485.414 [sci2:Lap:Wpt2] Stopped
2021-04-02T01:54:45.414Z,1617328485.414 [sci2:Lap:Wpt2](DEBUG): Aggregate::uninitialize sci2:Lap:Wpt2
2021-04-02T01:54:45.414Z,1617328485.414 [sci2:Lap:Wpt2:A.Waypoint] Stopped
2021-04-02T01:54:45.414Z,1617328485.414 [sci2:Lap:Wpt2:A.Waypoint](DEBUG): Uninitialize WaypointComponent.
2021-04-02T01:54:45.724Z,1617328485.724 [WetLabsSeaOWL_UV_A](INFO): Powering down
2021-04-02T01:54:45.868Z,1617328485.868 [MissionManager](IMPORTANT): Started mission Default
2021-04-02T01:54:45.868Z,1617328485.868 [Default] Running Loop=1
2021-04-02T01:54:45.868Z,1617328485.868 [Default](DEBUG): Aggregate::initialize Default
2021-04-02T01:54:45.868Z,1617328485.868 [Default:B.GoToSurface] Running Loop=1
2021-04-02T01:54:45.868Z,1617328485.868 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-04-02T01:54:45.868Z,1617328485.868 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-04-02T01:54:45.869Z,1617328485.869 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-04-02T01:54:45.869Z,1617328485.869 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-04-02T01:54:45.869Z,1617328485.869 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-04-02T01:54:45.870Z,1617328485.870 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-04-02T01:54:45.870Z,1617328485.870 [Default:A.Wait] Running Loop=1
2021-04-02T01:54:45.870Z,1617328485.870 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-04-02T01:54:46.191Z,1617328486.191 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,015445.00,A,4040.70262,N,07053.17738,W,1.516,318.38,020421,,,A*70
2021-04-02T01:54:46.194Z,1617328486.194 [NAL9602](INFO): GPS fix at 20210402T015445: (40.678377, -70.886290)
2021-04-02T01:54:46.243Z,1617328486.243 [UniversalFixResidualReporter](INFO): Fix residual: 4.7 %DT, over the last 5994.9 m. Residual distance 282.0 m at bearing 117.2 degrees. Fix at (40.6784, -70.8863) with 5532.4 m made good.
2021-04-02T01:54:59.136Z,1617328499.136 [Default:A.Wait](INFO): Done Waiting.
2021-04-02T01:54:59.137Z,1617328499.137 [Default:A.Wait] Stopped
2021-04-02T01:54:59.137Z,1617328499.137 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-04-02T01:54:59.541Z,1617328499.541 [Default:CheckIn] Running Loop=1
2021-04-02T01:54:59.541Z,1617328499.541 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-02T01:54:59.541Z,1617328499.541 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-02T01:55:01.531Z,1617328501.532 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,015500.00,A,4040.70198,N,07053.17649,W,0.467,189.56,020421,,,A*75
2021-04-02T01:55:01.534Z,1617328501.534 [NAL9602](INFO): GPS fix at 20210402T015500: (40.678366, -70.886275)
2021-04-02T01:55:01.576Z,1617328501.576 [Default:CheckIn:Read_GPS] Stopped
2021-04-02T01:55:01.576Z,1617328501.576 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-02T01:55:14.216Z,1617328514.216 [BuoyancyServo](FAULT): Overload Error
2021-04-02T01:55:14.216Z,1617328514.216 [BuoyancyServo] Hardware Fault, FailCount= 1
2021-04-02T01:55:14.216Z,1617328514.216 [BuoyancyServo](ERROR): Hardware Fault
2021-04-02T01:55:14.221Z,1617328514.221 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo
2021-04-02T01:55:15.872Z,1617328515.872 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-04-02T01:55:15.872Z,1617328515.872 [BuoyancyServo](INFO): Powering down
2021-04-02T01:55:16.693Z,1617328516.693 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2021-04-02T01:55:16.693Z,1617328516.693 [BuoyancyServo] No Fault, FailCount= 1
2021-04-02T01:55:17.118Z,1617328517.118 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-04-02T01:55:17.124Z,1617328517.124 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-04-02T01:55:18.687Z,1617328518.687 [NAL9602](INFO): SBD MO Status=1, MOMSN=14747, MT Status=0, MTMSN=0
2021-04-02T01:55:18.740Z,1617328518.740 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Courier0040.lzma
2021-04-02T01:55:18.740Z,1617328518.740 [NAL9602](INFO): Packets left to send: 1
2021-04-02T01:55:28.087Z,1617328528.087 [NAL9602](INFO): SBD MO Status=1, MOMSN=14748, MT Status=0, MTMSN=0
2021-04-02T01:55:28.140Z,1617328528.140 [NAL9602](INFO): Sent 32 bytes from file Logs/20210401T230436/Courier0040.lzma
2021-04-02T01:55:28.140Z,1617328528.140 [NAL9602](INFO): Packets left to send: 0
2021-04-02T01:55:36.679Z,1617328536.679 [NAL9602](INFO): SBD MO Status=1, MOMSN=14749, MT Status=0, MTMSN=0
2021-04-02T01:55:36.732Z,1617328536.732 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0041.lzma
2021-04-02T01:55:36.732Z,1617328536.732 [NAL9602](INFO): Packets left to send: 15
2021-04-02T01:55:45.637Z,1617328545.637 [NAL9602](INFO): SBD MO Status=1, MOMSN=14750, MT Status=0, MTMSN=0
2021-04-02T01:55:45.692Z,1617328545.692 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0041.lzma
2021-04-02T01:55:45.692Z,1617328545.692 [NAL9602](INFO): Packets left to send: 14
2021-04-02T01:55:57.842Z,1617328557.842 [NAL9602](INFO): SBD MO Status=1, MOMSN=14751, MT Status=0, MTMSN=0
2021-04-02T01:55:57.896Z,1617328557.896 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0041.lzma
2021-04-02T01:55:57.896Z,1617328557.896 [NAL9602](INFO): Packets left to send: 13
2021-04-02T01:56:06.805Z,1617328566.805 [NAL9602](INFO): SBD MO Status=1, MOMSN=14752, MT Status=0, MTMSN=0
2021-04-02T01:56:06.852Z,1617328566.852 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0041.lzma
2021-04-02T01:56:06.852Z,1617328566.852 [NAL9602](INFO): Packets left to send: 12
2021-04-02T01:56:15.367Z,1617328575.367 [NAL9602](INFO): SBD MO Status=1, MOMSN=14753, MT Status=0, MTMSN=0
2021-04-02T01:56:15.424Z,1617328575.424 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0041.lzma
2021-04-02T01:56:15.424Z,1617328575.424 [NAL9602](INFO): Packets left to send: 11
2021-04-02T01:56:21.957Z,1617328581.957 [NAL9602](INFO): SBD MO Status=1, MOMSN=14754, MT Status=0, MTMSN=0
2021-04-02T01:56:22.017Z,1617328582.017 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0041.lzma
2021-04-02T01:56:22.018Z,1617328582.018 [NAL9602](INFO): Packets left to send: 10
2021-04-02T01:56:28.103Z,1617328588.103 [NAL9602](INFO): SBD MO Status=1, MOMSN=14755, MT Status=0, MTMSN=0
2021-04-02T01:56:28.160Z,1617328588.160 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0041.lzma
2021-04-02T01:56:28.160Z,1617328588.160 [NAL9602](INFO): Packets left to send: 9
2021-04-02T01:56:36.307Z,1617328596.307 [NAL9602](INFO): SBD MO Status=1, MOMSN=14756, MT Status=0, MTMSN=0
2021-04-02T01:56:36.364Z,1617328596.364 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0041.lzma
2021-04-02T01:56:36.364Z,1617328596.364 [NAL9602](INFO): Packets left to send: 8
2021-04-02T01:56:46.479Z,1617328606.479 [NAL9602](INFO): SBD MO Status=1, MOMSN=14757, MT Status=0, MTMSN=0
2021-04-02T01:56:46.532Z,1617328606.532 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0041.lzma
2021-04-02T01:56:46.532Z,1617328606.532 [NAL9602](INFO): Packets left to send: 7
2021-04-02T01:56:58.283Z,1617328618.283 [NAL9602](INFO): SBD MO Status=1, MOMSN=14758, MT Status=0, MTMSN=0
2021-04-02T01:56:58.332Z,1617328618.332 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0041.lzma
2021-04-02T01:56:58.332Z,1617328618.332 [NAL9602](INFO): Packets left to send: 6
2021-04-02T01:57:04.431Z,1617328624.431 [NAL9602](INFO): SBD MO Status=1, MOMSN=14759, MT Status=0, MTMSN=0
2021-04-02T01:57:04.488Z,1617328624.488 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0041.lzma
2021-04-02T01:57:04.488Z,1617328624.488 [NAL9602](INFO): Packets left to send: 5
2021-04-02T01:57:07.289Z,1617328627.289 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T01:57:13.796Z,1617328633.796 [NAL9602](INFO): SBD MO Status=1, MOMSN=14760, MT Status=0, MTMSN=0
2021-04-02T01:57:13.846Z,1617328633.846 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0041.lzma
2021-04-02T01:57:13.846Z,1617328633.846 [NAL9602](INFO): Packets left to send: 4
2021-04-02T01:57:21.155Z,1617328641.155 [NAL9602](INFO): SBD MO Status=1, MOMSN=14761, MT Status=0, MTMSN=0
2021-04-02T01:57:21.212Z,1617328641.212 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0041.lzma
2021-04-02T01:57:21.212Z,1617328641.212 [NAL9602](INFO): Packets left to send: 3
2021-04-02T01:57:30.171Z,1617328650.171 [NAL9602](INFO): SBD MO Status=1, MOMSN=14762, MT Status=0, MTMSN=0
2021-04-02T01:57:30.216Z,1617328650.216 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0041.lzma
2021-04-02T01:57:30.216Z,1617328650.216 [NAL9602](INFO): Packets left to send: 2
2021-04-02T01:57:39.559Z,1617328659.559 [NAL9602](INFO): SBD MO Status=1, MOMSN=14763, MT Status=0, MTMSN=0
2021-04-02T01:57:39.612Z,1617328659.612 [NAL9602](INFO): Sent 184 bytes from file Logs/20210401T230436/Express0041.lzma
2021-04-02T01:57:39.612Z,1617328659.612 [NAL9602](INFO): Packets left to send: 1
2021-04-02T01:57:42.414Z,1617328662.414 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2021-04-02T01:57:50.124Z,1617328670.124 [NAL9602](INFO): SBD MO Status=1, MOMSN=14764, MT Status=0, MTMSN=0
2021-04-02T01:57:50.176Z,1617328670.176 [NAL9602](INFO): Sent 39 bytes from file Logs/20210401T230436/Express0041.lzma
2021-04-02T01:57:50.176Z,1617328670.176 [NAL9602](INFO): Packets left to send: 0
2021-04-02T01:57:56.210Z,1617328676.210 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2021-04-02T01:57:56.210Z,1617328676.210 [RDI_Pathfinder] Communications Fault, FailCount= 1
2021-04-02T01:57:56.210Z,1617328676.210 [RDI_Pathfinder](ERROR): Communications Fault
2021-04-02T01:57:56.237Z,1617328676.237 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-04-02T01:57:56.660Z,1617328676.660 [RDI_Pathfinder](INFO): Powering down
2021-04-02T01:57:57.427Z,1617328677.427 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-04-02T01:57:57.427Z,1617328677.427 [RDI_Pathfinder] No Fault, FailCount= 1
2021-04-02T01:58:00.640Z,1617328680.640 [NAL9602](INFO): SBD MO Status=0, MOMSN=14765, MT Status=0, MTMSN=0
2021-04-02T01:58:10.816Z,1617328690.816 [NAL9602](INFO): SBD MO Status=1, MOMSN=14766, MT Status=0, MTMSN=0
2021-04-02T01:58:10.869Z,1617328690.869 [NAL9602](INFO): Sent 52 bytes from file Logs/20210401T230436/Courier0043.lzma
2021-04-02T01:58:10.869Z,1617328690.869 [NAL9602](INFO): Packets left to send: 0
2021-04-02T01:58:21.431Z,1617328701.431 [NAL9602](INFO): SBD MO Status=1, MOMSN=14767, MT Status=0, MTMSN=0
2021-04-02T01:58:21.492Z,1617328701.492 [NAL9602](INFO): Sent 119 bytes from file Logs/20210401T230436/Express0044.lzma
2021-04-02T01:58:21.492Z,1617328701.492 [NAL9602](INFO): Packets left to send: 0
2021-04-02T01:58:26.675Z,1617328706.675 [NAL9602](INFO): SBD MO Status=0, MOMSN=14768, MT Status=0, MTMSN=0
2021-04-02T01:58:26.755Z,1617328706.755 [Default:CheckIn:Read_Iridium] Stopped
2021-04-02T01:58:26.755Z,1617328706.755 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-02T01:58:26.755Z,1617328706.755 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-02T01:58:57.385Z,1617328737.385 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-02T01:59:56.384Z,1617328796.384 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2021-04-02T02:03:27.287Z,1617329007.287 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-02T02:03:27.287Z,1617329007.287 [Default:CheckIn:C.Wait] Stopped
2021-04-02T02:03:27.287Z,1617329007.287 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-02T02:03:27.288Z,1617329007.288 [Default:CheckIn:D] Running Loop=1
2021-04-02T02:03:27.689Z,1617329007.689 [Default:CheckIn:D] Stopped
2021-04-02T02:03:27.690Z,1617329007.690 [Default:CheckIn:E] Running Loop=1
2021-04-02T02:03:28.118Z,1617329008.118 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.697025 min 
2021-04-02T02:03:28.119Z,1617329008.119 [Default:CheckIn:E] Stopped
2021-04-02T02:03:28.119Z,1617329008.119 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-02T02:03:28.119Z,1617329008.119 [Default:CheckIn] Stopped
2021-04-02T02:03:28.119Z,1617329008.119 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-02T02:03:28.119Z,1617329008.119 [Default:CheckIn](INFO): Running loop #2
2021-04-02T02:03:28.119Z,1617329008.119 [Default:CheckIn] Running Loop=2
2021-04-02T02:03:28.119Z,1617329008.119 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-02T02:03:28.120Z,1617329008.120 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-02T02:03:30.088Z,1617329010.088 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,020329.00,A,4040.72480,N,07053.17445,W,0.194,69.10,020421,,,D*4F
2021-04-02T02:03:30.094Z,1617329010.094 [NAL9602](INFO): GPS fix at 20210402T020329: (40.678747, -70.886241)
2021-04-02T02:03:30.142Z,1617329010.142 [Default:CheckIn:Read_GPS] Stopped
2021-04-02T02:03:30.142Z,1617329010.142 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-02T02:03:57.687Z,1617329037.687 [NAL9602](INFO): SBD MO Status=1, MOMSN=14769, MT Status=0, MTMSN=0
2021-04-02T02:03:57.748Z,1617329037.748 [NAL9602](INFO): Sent 72 bytes from file Logs/20210401T230436/Courier0046.lzma
2021-04-02T02:03:57.748Z,1617329037.748 [NAL9602](INFO): Packets left to send: 0
2021-04-02T02:04:10.311Z,1617329050.311 [NAL9602](INFO): SBD MO Status=1, MOMSN=14770, MT Status=0, MTMSN=0
2021-04-02T02:04:10.368Z,1617329050.368 [NAL9602](INFO): Sent 119 bytes from file Logs/20210401T230436/Express0047.lzma
2021-04-02T02:04:10.368Z,1617329050.368 [NAL9602](INFO): Packets left to send: 0
2021-04-02T02:04:25.261Z,1617329065.261 [NAL9602](INFO): SBD MO Status=2, MOMSN=14771, MT Status=2, MTMSN=0
2021-04-02T02:04:25.261Z,1617329065.261 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-04-02T02:04:38.984Z,1617329078.984 [NAL9602](INFO): SBD MO Status=0, MOMSN=14771, MT Status=0, MTMSN=0
2021-04-02T02:04:39.082Z,1617329079.082 [Default:CheckIn:Read_Iridium] Stopped
2021-04-02T02:04:39.082Z,1617329079.082 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-02T02:04:39.082Z,1617329079.082 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-02T02:05:09.694Z,1617329109.694 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-02T02:09:39.623Z,1617329379.623 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-02T02:09:39.624Z,1617329379.624 [Default:CheckIn:C.Wait] Stopped
2021-04-02T02:09:39.624Z,1617329379.624 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-02T02:09:39.625Z,1617329379.625 [Default:CheckIn:D] Running Loop=1
2021-04-02T02:09:40.005Z,1617329380.005 [Default:CheckIn:D] Stopped
2021-04-02T02:09:40.005Z,1617329380.005 [Default:CheckIn:E] Running Loop=1
2021-04-02T02:09:40.410Z,1617329380.410 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.902289 min 
2021-04-02T02:09:40.411Z,1617329380.411 [Default:CheckIn:E] Stopped
2021-04-02T02:09:40.411Z,1617329380.411 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-02T02:09:40.411Z,1617329380.411 [Default:CheckIn] Stopped
2021-04-02T02:09:40.411Z,1617329380.411 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-02T02:09:40.411Z,1617329380.411 [Default:CheckIn](INFO): Running loop #3
2021-04-02T02:09:40.411Z,1617329380.411 [Default:CheckIn] Running Loop=3
2021-04-02T02:09:40.411Z,1617329380.411 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-02T02:09:40.416Z,1617329380.416 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-02T02:09:42.396Z,1617329382.396 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,020941.00,A,4040.74380,N,07053.16836,W,1.263,111.96,020421,,,D*79
2021-04-02T02:09:42.398Z,1617329382.398 [NAL9602](INFO): GPS fix at 20210402T020941: (40.679063, -70.886139)
2021-04-02T02:09:42.439Z,1617329382.439 [Default:CheckIn:Read_GPS] Stopped
2021-04-02T02:09:42.439Z,1617329382.439 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-02T02:10:06.711Z,1617329406.711 [NAL9602](INFO): SBD MO Status=1, MOMSN=14772, MT Status=0, MTMSN=0
2021-04-02T02:10:06.768Z,1617329406.768 [NAL9602](INFO): Sent 79 bytes from file Logs/20210401T230436/Courier0049.lzma
2021-04-02T02:10:06.768Z,1617329406.768 [NAL9602](INFO): Packets left to send: 0
2021-04-02T02:10:26.177Z,1617329426.177 [NAL9602](INFO): SBD MO Status=1, MOMSN=14773, MT Status=0, MTMSN=0
2021-04-02T02:10:26.228Z,1617329426.228 [NAL9602](INFO): Sent 119 bytes from file Logs/20210401T230436/Express0050.lzma
2021-04-02T02:10:26.228Z,1617329426.228 [NAL9602](INFO): Packets left to send: 0
2021-04-02T02:10:36.273Z,1617329436.273 [NAL9602](INFO): SBD MO Status=0, MOMSN=14774, MT Status=0, MTMSN=0
2021-04-02T02:10:36.377Z,1617329436.377 [Default:CheckIn:Read_Iridium] Stopped
2021-04-02T02:10:36.377Z,1617329436.377 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-02T02:10:36.377Z,1617329436.377 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-02T02:11:06.979Z,1617329466.979 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-02T02:15:36.878Z,1617329736.878 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-02T02:15:36.878Z,1617329736.878 [Default:CheckIn:C.Wait] Stopped
2021-04-02T02:15:36.879Z,1617329736.879 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-02T02:15:36.879Z,1617329736.879 [Default:CheckIn:D] Running Loop=1
2021-04-02T02:15:37.293Z,1617329737.293 [Default:CheckIn:D] Stopped
2021-04-02T02:15:37.293Z,1617329737.293 [Default:CheckIn:E] Running Loop=1
2021-04-02T02:15:37.684Z,1617329737.684 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.857090 min 
2021-04-02T02:15:37.684Z,1617329737.684 [Default:CheckIn:E] Stopped
2021-04-02T02:15:37.684Z,1617329737.684 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-02T02:15:37.684Z,1617329737.684 [Default:CheckIn] Stopped
2021-04-02T02:15:37.684Z,1617329737.684 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-02T02:15:37.684Z,1617329737.684 [Default:CheckIn](INFO): Running loop #4
2021-04-02T02:15:37.684Z,1617329737.684 [Default:CheckIn] Running Loop=4
2021-04-02T02:15:37.685Z,1617329737.685 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-02T02:15:37.685Z,1617329737.685 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-02T02:15:39.687Z,1617329739.687 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,021538.00,A,4040.75635,N,07053.16242,W,0.408,345.26,020421,,,D*7B
2021-04-02T02:15:39.690Z,1617329739.690 [NAL9602](INFO): GPS fix at 20210402T021538: (40.679273, -70.886040)
2021-04-02T02:15:39.727Z,1617329739.727 [Default:CheckIn:Read_GPS] Stopped
2021-04-02T02:15:39.727Z,1617329739.727 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-02T02:15:54.788Z,1617329754.788 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=14775, MT Status=1, MTMSN=586
2021-04-02T02:15:54.844Z,1617329754.844 [NAL9602](INFO): Sent 72 bytes from file Logs/20210401T230436/Courier0052.lzma
2021-04-02T02:15:54.844Z,1617329754.844 [NAL9602](INFO): Packets left to send: 0
2021-04-02T02:15:55.436Z,1617329755.436 [NAL9602](INFO): Received command:!echo i > /dev/loadC6;echo i > /dev/loadC4;echo i > /dev/loadC2;echo i > /dev/loadC1;echo i > /dev/loadA6;echo i > /dev/loadA0;echo i > /dev/loadC5
2021-04-02T02:15:55.477Z,1617329755.477 [CommandLine](IMPORTANT): got command ! i > /dev/loadC6;echo i > /dev/loadC4;echo i > /dev/loadC2;echo i > /dev/loadC1;echo i > /dev/loadA6;echo i > /dev/loadA0;echo i > /dev/loadC5
2021-04-02T02:16:05.411Z,1617329765.411 [NAL9602](INFO): SBD MO Status=1, MOMSN=14776, MT Status=0, MTMSN=0
2021-04-02T02:16:05.464Z,1617329765.464 [NAL9602](INFO): Sent 120 bytes from file Logs/20210401T230436/Express0053.lzma
2021-04-02T02:16:05.464Z,1617329765.464 [NAL9602](INFO): Packets left to send: 0
2021-04-02T02:16:13.487Z,1617329773.487 [NAL9602](INFO): SBD MO Status=0, MOMSN=14777, MT Status=0, MTMSN=0
2021-04-02T02:16:29.345Z,1617329789.345 [NAL9602](INFO): SBD MO Status=1, MOMSN=14778, MT Status=0, MTMSN=0
2021-04-02T02:16:29.392Z,1617329789.392 [NAL9602](INFO): Sent 52 bytes from file Logs/20210401T230436/Courier0055.lzma
2021-04-02T02:16:29.392Z,1617329789.392 [NAL9602](INFO): Packets left to send: 0
2021-04-02T02:16:37.099Z,1617329797.099 [NAL9602](INFO): SBD MO Status=1, MOMSN=14779, MT Status=0, MTMSN=0
2021-04-02T02:16:37.152Z,1617329797.152 [NAL9602](INFO): Sent 171 bytes from file Logs/20210401T230436/Express0056.lzma
2021-04-02T02:16:37.152Z,1617329797.152 [NAL9602](INFO): Packets left to send: 0
2021-04-02T02:16:45.167Z,1617329805.167 [NAL9602](INFO): SBD MO Status=0, MOMSN=14780, MT Status=0, MTMSN=0
2021-04-02T02:16:45.282Z,1617329805.282 [Default:CheckIn:Read_Iridium] Stopped
2021-04-02T02:16:45.283Z,1617329805.283 [Default:CheckIn:C.Wait] Running Loop=1
2021-04-02T02:16:45.283Z,1617329805.283 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-04-02T02:16:53.262Z,1617329813.262 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T02:17:15.874Z,1617329835.874 [NAL9602](INFO): Not Powering down - fast GPS
2021-04-02T02:18:07.195Z,1617329887.195 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2021-04-02T02:18:32.252Z,1617329912.252 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2021-04-02T02:18:32.252Z,1617329912.252 [RDI_Pathfinder] Communications Fault, FailCount= 1
2021-04-02T02:18:32.252Z,1617329912.252 [RDI_Pathfinder](ERROR): Communications Fault
2021-04-02T02:18:32.313Z,1617329912.313 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2021-04-02T02:18:32.700Z,1617329912.700 [RDI_Pathfinder](INFO): Powering down
2021-04-02T02:18:33.468Z,1617329913.468 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2021-04-02T02:18:33.468Z,1617329913.468 [RDI_Pathfinder] No Fault, FailCount= 1
2021-04-02T02:18:54.502Z,1617329934.502 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2021-04-02T02:21:45.751Z,1617330105.751 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-04-02T02:21:45.751Z,1617330105.751 [Default:CheckIn:C.Wait] Stopped
2021-04-02T02:21:45.751Z,1617330105.751 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-04-02T02:21:45.752Z,1617330105.752 [Default:CheckIn:D] Running Loop=1
2021-04-02T02:21:46.211Z,1617330106.211 [Default:CheckIn:D] Stopped
2021-04-02T02:21:46.211Z,1617330106.211 [Default:CheckIn:E] Running Loop=1
2021-04-02T02:21:46.559Z,1617330106.559 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.005709 min 
2021-04-02T02:21:46.575Z,1617330106.575 [Default:CheckIn:E] Stopped
2021-04-02T02:21:46.576Z,1617330106.576 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-04-02T02:21:46.576Z,1617330106.576 [Default:CheckIn] Stopped
2021-04-02T02:21:46.576Z,1617330106.576 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-02T02:21:46.576Z,1617330106.576 [Default:CheckIn](INFO): Running loop #5
2021-04-02T02:21:46.576Z,1617330106.576 [Default:CheckIn] Running Loop=5
2021-04-02T02:21:46.576Z,1617330106.576 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-04-02T02:21:46.576Z,1617330106.576 [Default:CheckIn:Read_GPS] Running Loop=1
2021-04-02T02:21:48.572Z,1617330108.572 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,022147.00,A,4040.76959,N,07053.15143,W,0.525,118.96,020421,,,D*7C
2021-04-02T02:21:48.574Z,1617330108.574 [NAL9602](INFO): GPS fix at 20210402T022147: (40.679493, -70.885857)
2021-04-02T02:21:48.589Z,1617330108.589 [Default:CheckIn:Read_GPS] Stopped
2021-04-02T02:21:48.589Z,1617330108.589 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-04-02T02:22:15.403Z,1617330135.403 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=14781, MT Status=1, MTMSN=587
2021-04-02T02:22:15.469Z,1617330135.469 [NAL9602](INFO): Sent 79 bytes from file Logs/20210401T230436/Courier0058.lzma
2021-04-02T02:22:15.470Z,1617330135.470 [NAL9602](INFO): Packets left to send: 0
2021-04-02T02:22:16.060Z,1617330136.060 [NAL9602](INFO): Received command:configSet BackseatComponent.loadAtStartup 0 bool persist;configSet PAR_Licor.loadAtStartup 0 bool persist;configSet WetLabsSeaOWL_UV_A.loadAtStartup 0 bool persist
2021-04-02T02:22:16.604Z,1617330136.604 [CommandLine](IMPORTANT): got command configSet BackseatComponent.loadAtStartup 0.000000 bool persist
2021-04-02T02:22:16.604Z,1617330136.604 [CommandLine](IMPORTANT): configSet BackseatComponent.loadAtStartup requires a restart to take effect.
2021-04-02T02:22:16.607Z,1617330136.607 [CommandLine](IMPORTANT): got command configSet PAR_Licor.loadAtStartup 0.000000 bool persist
2021-04-02T02:22:16.607Z,1617330136.607 [CommandLine](IMPORTANT): configSet PAR_Licor.loadAtStartup requires a restart to take effect.
2021-04-02T02:22:16.610Z,1617330136.610 [CommandLine](IMPORTANT): got command configSet WetLabsSeaOWL_UV_A.loadAtStartup 0.000000 bool persist
2021-04-02T02:22:16.610Z,1617330136.610 [CommandLine](IMPORTANT): configSet WetLabsSeaOWL_UV_A.loadAtStartup requires a restart to take effect.
2021-04-02T02:22:33.394Z,1617330153.394 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=14782, MT Status=1, MTMSN=588
2021-04-02T02:22:33.444Z,1617330153.444 [NAL9602](INFO): Sent 120 bytes from file Logs/20210401T230436/Express0059.lzma
2021-04-02T02:22:33.444Z,1617330153.444 [NAL9602](INFO): Packets left to send: 0
2021-04-02T02:22:33.892Z,1617330153.892 [NAL9602](INFO): Received command:restart app
2021-04-02T02:22:33.994Z,1617330153.994 [CommandLine](IMPORTANT): got command restart application
2021-04-02T02:22:34.996Z,1617330154.996 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-02T02:22:34.996Z,1617330154.996 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:35.167Z,1617330155.167 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-04-02T02:22:35.168Z,1617330155.168 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:35.168Z,1617330155.168 [CommandLine](INFO): Join timeout helper Thread ID is 7821
2021-04-02T02:22:35.169Z,1617330155.169 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-04-02T02:22:35.169Z,1617330155.169 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:35.170Z,1617330155.170 [NavChartDb](INFO): Join timeout helper Thread ID is 7822
2021-04-02T02:22:35.516Z,1617330155.516 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-02T02:22:35.516Z,1617330155.516 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:35.532Z,1617330155.532 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2021-04-02T02:22:35.532Z,1617330155.532 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:35.532Z,1617330155.532 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 7823
2021-04-02T02:22:35.544Z,1617330155.544 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-02T02:22:35.544Z,1617330155.544 [WetLabsSeaOWL_UV_A](INFO): Powering down
2021-04-02T02:22:35.545Z,1617330155.545 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:35.552Z,1617330155.552 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2021-04-02T02:22:35.552Z,1617330155.552 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:35.552Z,1617330155.552 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7824
2021-04-02T02:22:36.232Z,1617330156.232 [CTD_Seabird](INFO): Powering down
2021-04-02T02:22:36.244Z,1617330156.244 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-02T02:22:36.244Z,1617330156.244 [CTD_Seabird](INFO): Powering down
2021-04-02T02:22:36.256Z,1617330156.256 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:36.256Z,1617330156.256 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-04-02T02:22:36.256Z,1617330156.256 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:36.257Z,1617330156.257 [Radio_Surface](INFO): Join timeout helper Thread ID is 7825
2021-04-02T02:22:36.392Z,1617330156.392 [Radio_Surface](INFO): Powering down
2021-04-02T02:22:36.393Z,1617330156.393 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-02T02:22:36.393Z,1617330156.393 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:36.405Z,1617330156.405 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-04-02T02:22:36.405Z,1617330156.405 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:36.405Z,1617330156.405 [Onboard](INFO): Join timeout helper Thread ID is 7826
2021-04-02T02:22:37.188Z,1617330157.188 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-02T02:22:37.188Z,1617330157.188 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:37.196Z,1617330157.196 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2021-04-02T02:22:37.196Z,1617330157.196 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:37.196Z,1617330157.196 [DataOverHttps](INFO): Join timeout helper Thread ID is 7827
2021-04-02T02:22:37.312Z,1617330157.312 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-02T02:22:37.312Z,1617330157.312 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:37.316Z,1617330157.316 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2021-04-02T02:22:37.316Z,1617330157.316 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:37.317Z,1617330157.317 [BackseatComponent](INFO): Join timeout helper Thread ID is 7828
2021-04-02T02:22:37.404Z,1617330157.404 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-02T02:22:37.404Z,1617330157.404 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:37.424Z,1617330157.424 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-04-02T02:22:37.425Z,1617330157.425 [logger ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:37.425Z,1617330157.425 [logger](INFO): Join timeout helper Thread ID is 7829
2021-04-02T02:22:37.452Z,1617330157.452 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-02T02:22:37.452Z,1617330157.452 [logger ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:37.456Z,1617330157.456 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-04-02T02:22:37.457Z,1617330157.457 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:37.457Z,1617330157.457 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-04-02T02:22:37.457Z,1617330157.457 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:37.457Z,1617330157.457 [controlThread](INFO): Join timeout helper Thread ID is 7830
2021-04-02T02:22:37.461Z,1617330157.461 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-04-02T02:22:37.461Z,1617330157.461 [controlThread](DEBUG): Uninitializing ControlThread
2021-04-02T02:22:37.462Z,1617330157.462 [AHRS_M2](INFO): Powering down
2021-04-02T02:22:37.533Z,1617330157.533 [Micromodem](INFO): Powering down
2021-04-02T02:22:37.628Z,1617330157.628 [NAL9602](INFO): Powering down
2021-04-02T02:22:37.700Z,1617330157.700 [RDI_Pathfinder](INFO): Powering down
2021-04-02T02:22:37.772Z,1617330157.772 [RDI_PathfinderUp](INFO): Powering down
2021-04-02T02:22:37.773Z,1617330157.773 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-04-02T02:22:37.774Z,1617330157.774 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-04-02T02:22:37.774Z,1617330157.774 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-04-02T02:22:37.775Z,1617330157.775 [MissionManager](INFO): Uninitializing Mission Default
2021-04-02T02:22:37.775Z,1617330157.775 [Default] Stopped
2021-04-02T02:22:37.775Z,1617330157.775 [Default](DEBUG): Aggregate::uninitialize Default
2021-04-02T02:22:37.775Z,1617330157.775 [Default:B.GoToSurface] Stopped
2021-04-02T02:22:37.776Z,1617330157.776 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-04-02T02:22:37.776Z,1617330157.776 [Default:CheckIn] Stopped
2021-04-02T02:22:37.776Z,1617330157.776 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-04-02T02:22:37.776Z,1617330157.776 [Default:CheckIn:Read_Iridium] Stopped
2021-04-02T02:22:37.779Z,1617330157.779 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-04-02T02:22:37.779Z,1617330157.779 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-04-02T02:22:37.779Z,1617330157.779 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-04-02T02:22:37.779Z,1617330157.779 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-04-02T02:22:37.780Z,1617330157.780 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-04-02T02:22:37.780Z,1617330157.780 [BuoyancyServo](INFO): Powering down
2021-04-02T02:22:37.792Z,1617330157.792 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-04-02T02:22:37.792Z,1617330157.792 [ElevatorServo](INFO): Powering down
2021-04-02T02:22:37.793Z,1617330157.793 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-04-02T02:22:37.793Z,1617330157.793 [MassServo](INFO): Powering down
2021-04-02T02:22:37.794Z,1617330157.794 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-04-02T02:22:37.794Z,1617330157.794 [RudderServo](INFO): Powering down
2021-04-02T02:22:37.794Z,1617330157.794 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2021-04-02T02:22:37.794Z,1617330157.794 [ThrusterServo](INFO): Powering down
2021-04-02T02:22:37.795Z,1617330157.795 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-04-02T02:22:37.796Z,1617330157.796 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-04-02T02:22:37.796Z,1617330157.796 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-04-02T02:22:37.796Z,1617330157.796 [CBIT](DEBUG): Powering off loads.
2021-04-02T02:22:37.807Z,1617330157.807 [CBIT](DEBUG): Disabling WDT.
2021-04-02T02:22:37.819Z,1617330157.819 [CBIT](DEBUG): Opening all GF detection circuits.
2021-04-02T02:22:37.821Z,1617330157.821 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:37.844Z,1617330157.844 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:37.847Z,1617330157.847 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:37.855Z,1617330157.855 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:37.898Z,1617330157.898 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:37.942Z,1617330157.942 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:37.946Z,1617330157.946 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:38.004Z,1617330158.004 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-04-02T02:22:38.073Z,1617330158.073 [logger ThreadHandler](INFO): Thread cancelled.