2021-01-26T16:05:28.213Z,1611677128.213 [Supervisor](DEBUG): Initializing supervisor.
2021-01-26T16:05:28.217Z,1611677128.217 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-01-26T16:05:28.218Z,1611677128.218 [SyncHandler](INFO): Protected caller Thread ID is 1555
2021-01-26T16:05:28.218Z,1611677128.218 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-01-26T16:05:28.219Z,1611677128.219 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-01-26T16:05:28.219Z,1611677128.219 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1556
2021-01-26T16:05:28.224Z,1611677128.224 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-01-26T16:05:28.242Z,1611677128.242 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-01-26T16:05:28.243Z,1611677128.243 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-01-26T16:05:28.244Z,1611677128.244 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1557
2021-01-26T16:05:28.246Z,1611677128.246 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-01-26T16:05:28.247Z,1611677128.247 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-01-26T16:05:28.247Z,1611677128.247 [logger ThreadHandler](INFO): Protected caller Thread ID is 1558
2021-01-26T16:05:28.251Z,1611677128.251 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-01-26T16:05:28.251Z,1611677128.251 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-01-26T16:05:28.253Z,1611677128.253 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-01-26T16:05:28.642Z,1611677128.642 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-01-26T16:05:28.643Z,1611677128.643 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-01-26T16:05:28.720Z,1611677128.720 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-01-26T16:05:29.026Z,1611677129.026 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-01-26T16:05:29.026Z,1611677129.026 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-01-26T16:05:29.124Z,1611677129.124 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-01-26T16:05:29.125Z,1611677129.125 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-01-26T16:05:29.312Z,1611677129.312 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-01-26T16:05:29.313Z,1611677129.313 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-01-26T16:05:29.799Z,1611677129.799 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-01-26T16:05:29.800Z,1611677129.800 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-01-26T16:05:30.026Z,1611677130.026 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-01-26T16:05:30.027Z,1611677130.027 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-01-26T16:05:30.170Z,1611677130.170 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-01-26T16:05:30.171Z,1611677130.171 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-01-26T16:05:30.360Z,1611677130.360 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-01-26T16:05:30.360Z,1611677130.360 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-01-26T16:05:30.456Z,1611677130.456 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-01-26T16:05:30.457Z,1611677130.457 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-01-26T16:05:30.667Z,1611677130.667 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-01-26T16:05:30.667Z,1611677130.667 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-01-26T16:05:30.960Z,1611677130.960 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-01-26T16:05:30.961Z,1611677130.961 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-01-26T16:05:31.041Z,1611677131.041 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-01-26T16:05:31.165Z,1611677131.165 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-01-26T16:05:31.166Z,1611677131.166 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-01-26T16:05:31.785Z,1611677131.785 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-01-26T16:05:31.785Z,1611677131.785 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-01-26T16:05:32.128Z,1611677132.128 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-01-26T16:05:32.130Z,1611677132.130 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2021-01-26T16:05:32.131Z,1611677132.131 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2021-01-26T16:05:32.396Z,1611677132.396 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2021-01-26T16:05:32.494Z,1611677132.494 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2021-01-26T16:05:32.737Z,1611677132.737 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2021-01-26T16:05:33.361Z,1611677133.361 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-01-26T16:05:33.361Z,1611677133.361 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2021-01-26T16:05:33.496Z,1611677133.496 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2021-01-26T16:05:33.590Z,1611677133.590 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2021-01-26T16:05:33.702Z,1611677133.702 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2021-01-26T16:05:33.786Z,1611677133.786 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Dock.cfg
2021-01-26T16:05:33.871Z,1611677133.871 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2021-01-26T16:05:33.978Z,1611677133.978 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/workSite.cfg
2021-01-26T16:05:34.075Z,1611677134.075 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2021-01-26T16:05:34.253Z,1611677134.253 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2021-01-26T16:05:34.384Z,1611677134.384 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-01-26T16:05:34.397Z,1611677134.397 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-01-26T16:05:34.744Z,1611677134.744 [AHRS_M2] Loaded
2021-01-26T16:05:34.744Z,1611677134.744 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-01-26T16:05:35.493Z,1611677135.493 [BPC1] Loaded
2021-01-26T16:05:35.493Z,1611677135.493 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-01-26T16:05:35.567Z,1611677135.567 [DataOverHttps] Loaded
2021-01-26T16:05:35.567Z,1611677135.567 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-01-26T16:05:35.568Z,1611677135.568 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407924E0
2021-01-26T16:05:35.568Z,1611677135.568 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1646
2021-01-26T16:05:35.590Z,1611677135.590 [DDM] Loaded
2021-01-26T16:05:35.590Z,1611677135.590 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2021-01-26T16:05:35.608Z,1611677135.608 [Depth_Keller] Loaded
2021-01-26T16:05:35.609Z,1611677135.609 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-01-26T16:05:35.613Z,1611677135.613 [DropWeight] Loaded
2021-01-26T16:05:35.613Z,1611677135.613 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2021-01-26T16:05:35.655Z,1611677135.655 [DUSBL_Hydroid] Loaded
2021-01-26T16:05:35.655Z,1611677135.655 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2021-01-26T16:05:35.690Z,1611677135.690 [Micromodem] Loaded
2021-01-26T16:05:35.690Z,1611677135.690 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2021-01-26T16:05:35.747Z,1611677135.747 [NAL9602] Loaded
2021-01-26T16:05:35.747Z,1611677135.747 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-01-26T16:05:35.775Z,1611677135.775 [Onboard] Loaded
2021-01-26T16:05:35.775Z,1611677135.775 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-01-26T16:05:35.776Z,1611677135.776 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 407C24E0
2021-01-26T16:05:35.776Z,1611677135.776 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1647
2021-01-26T16:05:35.790Z,1611677135.790 [Radio_Surface] Loaded
2021-01-26T16:05:35.790Z,1611677135.790 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-01-26T16:05:35.791Z,1611677135.791 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407F24E0
2021-01-26T16:05:35.791Z,1611677135.791 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1648
2021-01-26T16:05:35.820Z,1611677135.820 [RDI_Pathfinder] Loaded
2021-01-26T16:05:35.821Z,1611677135.821 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2021-01-26T16:05:35.846Z,1611677135.846 [RDI_PathfinderUp] Loaded
2021-01-26T16:05:35.846Z,1611677135.846 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2021-01-26T16:05:35.847Z,1611677135.847 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-01-26T16:05:35.848Z,1611677135.848 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-01-26T16:05:35.858Z,1611677135.858 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-01-26T16:05:35.859Z,1611677135.859 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-01-26T16:05:35.936Z,1611677135.936 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-01-26T16:05:35.936Z,1611677135.936 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-01-26T16:05:35.949Z,1611677135.949 [NavChart] Loaded
2021-01-26T16:05:35.949Z,1611677135.949 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-01-26T16:05:35.955Z,1611677135.955 [UniversalFixResidualReporter] Loaded
2021-01-26T16:05:35.955Z,1611677135.955 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-01-26T16:05:35.965Z,1611677135.965 [WorkSite] Loaded
2021-01-26T16:05:35.965Z,1611677135.965 [ComponentRegistry](DEBUG): SyncComponent "WorkSite" handled in the control thread.
2021-01-26T16:05:35.966Z,1611677135.966 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-01-26T16:05:35.966Z,1611677135.966 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-01-26T16:05:36.050Z,1611677136.050 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-01-26T16:05:36.050Z,1611677136.050 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-01-26T16:05:36.166Z,1611677136.166 [BuoyancyServo] Loaded
2021-01-26T16:05:36.166Z,1611677136.166 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-01-26T16:05:36.187Z,1611677136.187 [ElevatorServo] Loaded
2021-01-26T16:05:36.187Z,1611677136.187 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-01-26T16:05:36.207Z,1611677136.207 [MassServo] Loaded
2021-01-26T16:05:36.207Z,1611677136.207 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-01-26T16:05:36.226Z,1611677136.226 [RudderServo] Loaded
2021-01-26T16:05:36.227Z,1611677136.227 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-01-26T16:05:36.245Z,1611677136.245 [ThrusterServo] Loaded
2021-01-26T16:05:36.245Z,1611677136.245 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-01-26T16:05:36.246Z,1611677136.246 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-01-26T16:05:36.246Z,1611677136.246 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-01-26T16:05:36.413Z,1611677136.413 [CTD_Seabird] Loaded
2021-01-26T16:05:36.414Z,1611677136.414 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-01-26T16:05:36.415Z,1611677136.415 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409154E0
2021-01-26T16:05:36.415Z,1611677136.415 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1649
2021-01-26T16:05:36.434Z,1611677136.434 [PAR_Licor] Loaded
2021-01-26T16:05:36.434Z,1611677136.434 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-01-26T16:05:36.466Z,1611677136.466 [WetLabsSeaOWL_UV_A] Loaded
2021-01-26T16:05:36.466Z,1611677136.466 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2021-01-26T16:05:36.467Z,1611677136.467 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409454E0
2021-01-26T16:05:36.468Z,1611677136.468 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1650
2021-01-26T16:05:36.469Z,1611677136.469 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-01-26T16:05:36.469Z,1611677136.469 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-01-26T16:05:36.771Z,1611677136.771 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-01-26T16:05:36.772Z,1611677136.772 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-01-26T16:05:36.811Z,1611677136.811 [DepthRateCalculator] Loaded
2021-01-26T16:05:36.811Z,1611677136.811 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-01-26T16:05:36.816Z,1611677136.816 [PitchRateCalculator] Loaded
2021-01-26T16:05:36.817Z,1611677136.817 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-01-26T16:05:36.826Z,1611677136.826 [SpeedCalculator] Loaded
2021-01-26T16:05:36.826Z,1611677136.826 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-01-26T16:05:36.839Z,1611677136.839 [TempGradientCalculator] Loaded
2021-01-26T16:05:36.839Z,1611677136.839 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-01-26T16:05:36.844Z,1611677136.844 [YawRateCalculator] Loaded
2021-01-26T16:05:36.844Z,1611677136.844 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-01-26T16:05:36.866Z,1611677136.866 [ElevatorOffsetCalculator] Loaded
2021-01-26T16:05:36.866Z,1611677136.866 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-01-26T16:05:36.867Z,1611677136.867 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-01-26T16:05:36.867Z,1611677136.867 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-01-26T16:05:37.003Z,1611677137.003 [SBIT](DEBUG): Construct Startup Built In Test.
2021-01-26T16:05:37.014Z,1611677137.014 [SBIT] Loaded
2021-01-26T16:05:37.015Z,1611677137.015 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-01-26T16:05:37.018Z,1611677137.018 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-01-26T16:05:37.030Z,1611677137.030 [IBIT] Loaded
2021-01-26T16:05:37.031Z,1611677137.031 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-01-26T16:05:37.036Z,1611677137.036 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-01-26T16:05:37.136Z,1611677137.136 [CBIT] Loaded
2021-01-26T16:05:37.137Z,1611677137.137 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-01-26T16:05:37.137Z,1611677137.137 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-01-26T16:05:37.138Z,1611677137.138 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-01-26T16:05:37.191Z,1611677137.191 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-01-26T16:05:37.191Z,1611677137.191 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-01-26T16:05:37.291Z,1611677137.291 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-01-26T16:05:37.292Z,1611677137.292 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-01-26T16:05:37.341Z,1611677137.341 [VerticalControl](DEBUG): Construct VerticalControl.
2021-01-26T16:05:37.393Z,1611677137.393 [VerticalControl] Loaded
2021-01-26T16:05:37.393Z,1611677137.393 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-01-26T16:05:37.396Z,1611677137.396 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-01-26T16:05:37.431Z,1611677137.431 [HorizontalControl] Loaded
2021-01-26T16:05:37.431Z,1611677137.431 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-01-26T16:05:37.434Z,1611677137.434 [SpeedControl](DEBUG): Construct SpeedControl.
2021-01-26T16:05:37.435Z,1611677137.435 [SpeedControl] Loaded
2021-01-26T16:05:37.436Z,1611677137.436 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-01-26T16:05:37.438Z,1611677137.438 [LoopControl](DEBUG): Construct LoopControl.
2021-01-26T16:05:37.439Z,1611677137.439 [LoopControl] Loaded
2021-01-26T16:05:37.439Z,1611677137.439 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-01-26T16:05:37.440Z,1611677137.440 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-01-26T16:05:37.440Z,1611677137.440 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-01-26T16:05:37.525Z,1611677137.525 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-01-26T16:05:37.531Z,1611677137.531 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-01-26T16:05:37.573Z,1611677137.573 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-01-26T16:05:37.650Z,1611677137.650 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-01-26T16:05:37.651Z,1611677137.651 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AED4E0
2021-01-26T16:05:37.651Z,1611677137.651 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1651
2021-01-26T16:05:37.656Z,1611677137.656 [Supervisor](INFO): Main Thread ID is 823
2021-01-26T16:05:37.656Z,1611677137.656 [Supervisor](DEBUG): Running supervisor.
2021-01-26T16:05:37.657Z,1611677137.657 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1652
2021-01-26T16:05:37.720Z,1611677137.720 [controlThread ThreadHandler](INFO): Handler Thread ID is 1653
2021-01-26T16:05:37.720Z,1611677137.720 [controlThread](DEBUG): Initializing ControlThread
2021-01-26T16:05:37.726Z,1611677137.726 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-01-26T16:05:37.727Z,1611677137.727 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-01-26T16:05:37.727Z,1611677137.727 [WorkSite](DEBUG): Initializing WorkSite component.
2021-01-26T16:05:37.732Z,1611677137.732 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-01-26T16:05:37.732Z,1611677137.732 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-01-26T16:05:37.733Z,1611677137.733 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-01-26T16:05:37.733Z,1611677137.733 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-01-26T16:05:37.734Z,1611677137.734 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-01-26T16:05:37.734Z,1611677137.734 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-01-26T16:05:37.735Z,1611677137.735 [SBIT](INFO): Initialize SBIT Component.
2021-01-26T16:05:37.735Z,1611677137.735 [SBIT](IMPORTANT): git: 2021-01-25
2021-01-26T16:05:37.735Z,1611677137.735 [SBIT](INFO): git hash: e8097416d22531cddaa880496e10ca880d93ff57
2021-01-26T16:05:37.736Z,1611677137.736 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-01-26T16:05:37.737Z,1611677137.737 [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-01-26T16:05:37.738Z,1611677137.738 [SBIT](INFO): Beginning SBIT in 21.000000 seconds.
2021-01-26T16:05:37.739Z,1611677137.739 [IBIT](INFO): Initialize IBIT Component.
2021-01-26T16:05:37.740Z,1611677137.740 [CBIT](DEBUG): Initialize CBIT Component.
2021-01-26T16:05:37.741Z,1611677137.741 [logger ThreadHandler](INFO): Handler Thread ID is 1654
2021-01-26T16:05:37.742Z,1611677137.742 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1655
2021-01-26T16:05:37.743Z,1611677137.743 [Onboard ThreadHandler](INFO): Handler Thread ID is 1656
2021-01-26T16:05:37.747Z,1611677137.747 [DataOverHttps](DEBUG): dashIP=128.128.181.155 starts with a digit so assuming it is a numeric IP
2021-01-26T16:05:37.752Z,1611677137.752 [CBIT](DEBUG): Initialized mux pins.
2021-01-26T16:05:37.752Z,1611677137.752 [CBIT](DEBUG): Initializing the watchdog timer.
2021-01-26T16:05:37.800Z,1611677137.800 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1657
2021-01-26T16:05:37.803Z,1611677137.803 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-01-26T16:05:37.804Z,1611677137.804 [CBIT](DEBUG): Initializing heartbeat.
2021-01-26T16:05:37.812Z,1611677137.812 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1658
2021-01-26T16:05:37.813Z,1611677137.813 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-01-26T16:05:37.816Z,1611677137.816 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1660
2021-01-26T16:05:37.817Z,1611677137.817 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1661
2021-01-26T16:05:37.820Z,1611677137.820 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-01-26T16:05:37.821Z,1611677137.821 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-01-26T16:05:37.821Z,1611677137.821 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-01-26T16:05:37.821Z,1611677137.821 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-01-26T16:05:37.821Z,1611677137.821 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-01-26T16:05:37.821Z,1611677137.821 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-01-26T16:05:37.821Z,1611677137.821 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-01-26T16:05:37.822Z,1611677137.822 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-01-26T16:05:37.822Z,1611677137.822 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-01-26T16:05:37.822Z,1611677137.822 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-01-26T16:05:37.822Z,1611677137.822 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-01-26T16:05:37.822Z,1611677137.822 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-01-26T16:05:37.822Z,1611677137.822 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-01-26T16:05:37.823Z,1611677137.823 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-01-26T16:05:37.823Z,1611677137.823 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-01-26T16:05:37.823Z,1611677137.823 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-01-26T16:05:37.875Z,1611677137.875 [CBIT](DEBUG): Deactivating GF circuits.
2021-01-26T16:05:37.875Z,1611677137.875 [CBIT](DEBUG): Deactivating emergency mode.
2021-01-26T16:05:37.876Z,1611677137.876 [WetLabsSeaOWL_UV_A](INFO): Powering down
2021-01-26T16:05:37.911Z,1611677137.911 [CBIT](DEBUG): Backplane powered.
2021-01-26T16:05:37.912Z,1611677137.912 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-01-26T16:05:37.913Z,1611677137.913 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-01-26T16:05:37.914Z,1611677137.914 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-01-26T16:05:37.915Z,1611677137.915 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-01-26T16:05:37.915Z,1611677137.915 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-01-26T16:05:37.929Z,1611677137.929 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-01-26T16:05:37.949Z,1611677137.949 [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-01-26T16:05:37.949Z,1611677137.949 [MissionManager](INFO): Loading Mission: Missions/underIce/DefaultWithUndock.xml
2021-01-26T16:05:38.195Z,1611677138.195 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min
2021-01-26T16:05:38.198Z,1611677138.198 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 30.000000 min
2021-01-26T16:05:38.204Z,1611677138.204 [Radio_Surface](INFO): Powering up
2021-01-26T16:05:38.214Z,1611677138.214 [MissionManager](INFO): DefineArg DefaultWithUndock.TransponderCode = 2.000000 count
2021-01-26T16:05:38.218Z,1611677138.218 [MissionManager](INFO): DefineArg DefaultWithUndock.TrackingUpdatePeriod = 2.500000 s
2021-01-26T16:05:38.230Z,1611677138.230 [MissionManager](INFO): DefineArg DefaultWithUndock.NumberOfPings = 1.000000 count
2021-01-26T16:05:38.234Z,1611677138.234 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachModeDDM = 2.000000 count
2021-01-26T16:05:38.235Z,1611677138.235 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait.
2021-01-26T16:05:38.370Z,1611677138.370 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Construct.
2021-01-26T16:05:38.370Z,1611677138.370 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2021-01-26T16:05:38.422Z,1611677138.422 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-01-26T16:05:38.474Z,1611677138.474 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-01-26T16:05:38.477Z,1611677138.477 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-01-26T16:05:38.494Z,1611677138.494 [DefaultWithUndock:E.Execute](DEBUG): Construct Execute.
2021-01-26T16:05:38.524Z,1611677138.524 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Control="Tethys/Control"
         xmlns:Dock="Tethys/Dock"
         xmlns:Estimation="Tethys/Estimation"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Sensor="Tethys/Sensor"
         xmlns:Units="Tethys/Units"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                             Tethys/Dock http://okeanids.mbari.org/tethys/Xml/Dock.xsd
                             Tethys/Estimation http://okeanids.mbari.org/tethys/Xml/Estimation.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Sensor http://okeanids.mbari.org/tethys/Xml/Sensor.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="DefaultWithUndock">

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

    <DefineArg Name="DetachTimeout"><Description>
        Maximum time duration for the vehicle to detach form the dock.
    </Description><Units:minute/><Value>30</Value></DefineArg>

    <DefineArg Name="TransponderCode"><Description>
        Dock transponder address.
    </Description><Dock:Dock.transponderCode/></DefineArg>

    <DefineArg Name="TrackingUpdatePeriod"><Description>
        Time duration between acoustic range queries while the
        mission is detaching from the dock.
    </Description><Units:second/><Value>2.5</Value></DefineArg>

    <DefineArg Name="NumberOfPings"><Description>
        Number of pings requested each time.
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="DetachModeDDM"><Description>
        DDM DETACH mode enumeration (whiskers retracted, latch open).
    </Description><Units:count/><Value>2</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>30</Value></Setting>
    </Guidance:Wait>

    <Aggregate Id="LeaveDock">
        <Sequence/>

        <Timeout Duration="DetachTimeout">
            <Syslog Severity="Important">Timed out while trying to detach from dock at current depth of <Universal:depth/><Units:meter/>.</Syslog>
        </Timeout>

        <!-- Acoustic tracking directives -->
        <Estimation:TrackAcousticContact>
            <Parallel/>
            <Setting><Estimation:TrackAcousticContact.contactLabelSetting/><Arg Name="TransponderCode"/></Setting>
            <Setting><Estimation:TrackAcousticContact.numberOfSamplesSetting/><Arg Name="NumberOfPings"/></Setting>
            <Setting><Estimation:TrackAcousticContact.updatePeriodSetting/><Arg Name="TrackingUpdatePeriod"/></Setting>
        </Estimation:TrackAcousticContact>

        <!-- Leave the dock -->
        <Dock:Undock>
            <Sequence/>
        </Dock:Undock>

    </Aggregate>


    <Aggregate Id="SurfaceDefault">

        <Sequence/>

        <Aggregate Id="HoldLatchOpen">

            <While>
                <Universal:depth/>
                <Gt><Control:VerticalControl.surfaceThreshold/></Gt>
            </While>

            <!-- Power the DDM -->
            <ReadData>
                <Sensor:DDM.DDMMode/>
            </ReadData>

            <!-- Hold the DDM in DETACH mode (whiskers retracted, latch open) -->
            <Assign><Parallel/><Sensor:DDM.DDMModeCmd/><Arg Name="DetachModeDDM"/></Assign>

        </Aggregate>

        <!-- This mission assumes open water so from here on it's the same old default that's always used -->
        <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>

    </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-01-26T16:05:38.529Z,1611677138.529 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,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,Reporter,LogSplitter,
2021-01-26T16:05:38.535Z,1611677138.535 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-01-26T16:05:38.600Z,1611677138.600 [DDM](INFO): Powering up
2021-01-26T16:05:38.600Z,1611677138.600 [DDM](DEBUG): Initializing DDM.
2021-01-26T16:05:38.630Z,1611677138.630 [DUSBL_Hydroid](INFO): Powering up
2021-01-26T16:05:38.630Z,1611677138.630 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2021-01-26T16:05:38.705Z,1611677138.705 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-01-26T16:05:38.708Z,1611677138.708 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-01-26T16:05:38.709Z,1611677138.709 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-01-26T16:05:38.716Z,1611677138.716 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-01-26T16:05:38.717Z,1611677138.717 [MassServo](DEBUG): Initializing EZServoServo.
2021-01-26T16:05:38.724Z,1611677138.724 [MassServo](DEBUG): Initializing MassServo.
2021-01-26T16:05:38.725Z,1611677138.725 [RudderServo](DEBUG): Initializing EZServoServo.
2021-01-26T16:05:38.732Z,1611677138.732 [RudderServo](DEBUG): Initializing RudderServo.
2021-01-26T16:05:38.733Z,1611677138.733 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-01-26T16:05:38.740Z,1611677138.740 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-01-26T16:05:38.956Z,1611677138.956 [Micromodem](INFO): Powering up
2021-01-26T16:05:38.956Z,1611677138.956 [Micromodem](DEBUG): Initializing Micromodem.
2021-01-26T16:05:39.676Z,1611677139.676 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2021-01-26T16:05:39.676Z,1611677139.676 [RudderServo](FAULT): Rudder failed to initialize
2021-01-26T16:05:39.676Z,1611677139.676 [RudderServo] Communications Fault, FailCount= 1
2021-01-26T16:05:39.676Z,1611677139.676 [RudderServo](ERROR): Communications Fault
2021-01-26T16:05:39.776Z,1611677139.776 [CBIT](ERROR): Communications Fault in component: RudderServo
2021-01-26T16:05:39.941Z,1611677139.941 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-01-26T16:05:39.941Z,1611677139.941 [RudderServo](INFO): Powering down
2021-01-26T16:05:40.612Z,1611677140.612 [RudderServo](DEBUG): Initializing EZServoServo.
2021-01-26T16:05:40.732Z,1611677140.732 [RudderServo](DEBUG): Initializing RudderServo.
2021-01-26T16:05:40.736Z,1611677140.736 [CBIT](INFO): Clearing failed state for component RudderServo
2021-01-26T16:05:40.737Z,1611677140.737 [RudderServo] No Fault, FailCount= 1
2021-01-26T16:05:43.867Z,1611677143.867 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2021-01-26T16:05:45.849Z,1611677145.849 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-01-26T16:05:46.778Z,1611677146.778 [Micromodem](INFO): Nmea in: $CATMG,2021-01-26T16:05:45.864536Z,RTC,RTC*5D

2021-01-26T16:05:46.779Z,1611677146.779 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2021-01-26T16:05:45.864536Z,RTC,RTC*5D

2021-01-26T16:05:54.412Z,1611677154.412 [DDM](INFO): Dynamic Docking Module:
2021-01-26T16:05:55.251Z,1611677155.251 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2021-01-26T16:05:56.034Z,1611677156.034 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2021-01-26T16:05:56.034Z,1611677156.034 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2021-01-26T16:05:56.438Z,1611677156.438 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2021-01-26T16:05:56.438Z,1611677156.438 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2021-01-26T16:05:56.853Z,1611677156.853 [DUSBL_Hydroid](INFO): DUSBL Version:O
2021-01-26T16:05:56.869Z,1611677156.869 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2021-01-26T16:05:56.869Z,1611677156.869 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2021-01-26T16:05:57.254Z,1611677157.254 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2021-01-26T16:05:57.254Z,1611677157.254 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2021-01-26T16:05:57.649Z,1611677157.649 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2021-01-26T16:05:57.650Z,1611677157.650 [Micromodem](INFO): Nmea out: $CCCFG,FC0,14500*47

2021-01-26T16:05:58.058Z,1611677158.058 [Micromodem](INFO): Nmea in: $CACFG,FC0,14500*45

2021-01-26T16:05:58.058Z,1611677158.058 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2021-01-26T16:05:58.457Z,1611677158.457 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2021-01-26T16:05:58.458Z,1611677158.458 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2021-01-26T16:05:58.870Z,1611677158.870 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2021-01-26T16:05:58.870Z,1611677158.870 [Micromodem](INFO): Nmea out: $CCCLK,2021,01,26,16,05,59*4E

2021-01-26T16:05:59.273Z,1611677159.273 [Micromodem](INFO): Nmea in: $CACLK,2021,1,26,16,5,59*4C

2021-01-26T16:05:59.274Z,1611677159.274 [Micromodem](INFO): Nmea in: $CATMS,0,2021-01-26T16:06:00Z*73

2021-01-26T16:05:59.276Z,1611677159.276 [Micromodem](INFO): Nmea in: $CATMG,2021-01-26T16:06:00.028908Z,USER_CMD,RTC*1F

2021-01-26T16:05:59.480Z,1611677159.480 [SBIT](IMPORTANT): Beginning Startup BIT
2021-01-26T16:05:59.524Z,1611677159.524 [CBIT](IMPORTANT): Beginning ground fault scan
2021-01-26T16:06:06.134Z,1611677166.134 [NAL9602](INFO): Powering up NAL9602
2021-01-26T16:06:10.323Z,1611677170.323 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.009122
CHAN A1 (24V): -0.028302
CHAN A2 (12V): -0.007900
CHAN A3 (5V): -0.002846
CHAN B0 (3.3V): 0.000063
CHAN B1 (3.15aV): -0.000395
CHAN B2 (3.15bV): -0.000075
CHAN B3 (GND): 0.002130
OPEN: 0.005462
Full Scale Calc: 4.765 mA, -1.589 mA
2021-01-26T16:06:17.050Z,1611677177.050 [NAL9602](INFO): NAL9602 initialized
2021-01-26T16:06:40.890Z,1611677200.890 [NAL9602](INFO): SBD MO Status=0, MOMSN=12990, MT Status=0, MTMSN=0
2021-01-26T16:06:40.890Z,1611677200.890 [NAL9602](INFO): No messages in MT queue
2021-01-26T16:06:53.464Z,1611677213.464 [SBIT](IMPORTANT): SBIT PASSED
2021-01-26T16:06:53.464Z,1611677213.464 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-01-26T16:06:53.465Z,1611677213.465 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2021-01-26T16:06:53.465Z,1611677213.465 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_temperature;

2021-01-26T16:06:53.465Z,1611677213.465 [SBIT](IMPORTANT): Express none CurrentEstimator.current_direction_navigation_frame;

2021-01-26T16:06:53.465Z,1611677213.465 [SBIT](IMPORTANT): Express none CurrentEstimator.current_direction_vehicle_frame;

2021-01-26T16:06:53.465Z,1611677213.465 [SBIT](IMPORTANT): Express none CurrentEstimator.current_speed_navigation_frame;

2021-01-26T16:06:53.465Z,1611677213.465 [SBIT](IMPORTANT): Express none CurrentEstimator.current_speed_vehicle_frame;

2021-01-26T16:06:53.466Z,1611677213.466 [SBIT](IMPORTANT): LBLNavigation.loadAtStartup=0 bool;

2021-01-26T16:06:53.466Z,1611677213.466 [SBIT](IMPORTANT): LineCapture.armSpeed=1 meter_per_second;

2021-01-26T16:06:53.466Z,1611677213.466 [SBIT](IMPORTANT): LineCapture.navigationGain=4 none;

2021-01-26T16:06:53.466Z,1611677213.466 [SBIT](IMPORTANT): LineCapture.rolloutDistance=200 meter;

2021-01-26T16:06:53.466Z,1611677213.466 [SBIT](IMPORTANT): Micromodem.destinationAddress=10 enum;

2021-01-26T16:06:53.466Z,1611677213.466 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=0 bool;

2021-01-26T16:06:53.466Z,1611677213.466 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=114.655585 cubic_centimeter;

2021-01-26T16:06:53.466Z,1611677213.466 [SBIT](IMPORTANT): VerticalControl.massDefault=-0.364238 millimeter;

2021-01-26T16:06:53.898Z,1611677213.898 [WorkSite](IMPORTANT): Reading workSite position from Data/workSite.cfg
2021-01-26T16:06:53.901Z,1611677213.901 [WorkSite](IMPORTANT): WorkSite fix at 20210126T160516: (41.551587, -70.710368)
2021-01-26T16:06:53.903Z,1611677213.903 [MissionManager](IMPORTANT): Started mission Startup
2021-01-26T16:06:53.903Z,1611677213.903 [Startup] Running Loop=1
2021-01-26T16:06:53.903Z,1611677213.903 [Startup](DEBUG): Aggregate::initialize Startup
2021-01-26T16:06:53.904Z,1611677213.904 [Startup:A.GoToSurface] Running Loop=1
2021-01-26T16:06:53.904Z,1611677213.904 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-01-26T16:06:53.904Z,1611677213.904 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-01-26T16:06:53.905Z,1611677213.905 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-01-26T16:06:53.905Z,1611677213.905 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-01-26T16:06:53.905Z,1611677213.905 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-01-26T16:06:53.906Z,1611677213.906 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-01-26T16:06:53.907Z,1611677213.907 [Startup:StartupSatComms] Running Loop=1
2021-01-26T16:06:53.907Z,1611677213.907 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-01-26T16:06:53.907Z,1611677213.907 [Startup:StartupSatComms:A] Running Loop=1
2021-01-26T16:06:54.261Z,1611677214.261 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-01-26T16:07:02.708Z,1611677222.708 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160701.00,A,4133.06203,N,07042.67150,W,0.952,0.00,260121,,,A*73
2021-01-26T16:07:02.713Z,1611677222.713 [NAL9602](INFO): GPS fix at 20210126T160701: (41.551034, -70.711192)
2021-01-26T16:07:02.776Z,1611677222.776 [Startup:StartupSatComms:A] Stopped
2021-01-26T16:07:02.776Z,1611677222.776 [Startup:StartupSatComms:B] Running Loop=1
2021-01-26T16:07:03.239Z,1611677223.239 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-01-26T16:07:19.427Z,1611677239.427 [NAL9602](INFO): SBD MO Status=1, MOMSN=12991, MT Status=0, MTMSN=0
2021-01-26T16:07:19.476Z,1611677239.476 [NAL9602](INFO): Sent 52 bytes from file Logs/20210126T135224/Courier0067.lzma
2021-01-26T16:07:19.476Z,1611677239.476 [NAL9602](INFO): Packets left to send: 0
2021-01-26T16:07:40.953Z,1611677260.953 [NAL9602](INFO): SBD MO Status=1, MOMSN=12992, MT Status=0, MTMSN=0
2021-01-26T16:07:41.004Z,1611677261.004 [NAL9602](INFO): Sent 184 bytes from file Logs/20210126T160528/Courier0000.lzma
2021-01-26T16:07:41.004Z,1611677261.004 [NAL9602](INFO): Packets left to send: 1
2021-01-26T16:07:50.443Z,1611677270.443 [NAL9602](INFO): SBD MO Status=1, MOMSN=12993, MT Status=0, MTMSN=0
2021-01-26T16:07:50.492Z,1611677270.492 [NAL9602](INFO): Sent 69 bytes from file Logs/20210126T160528/Courier0000.lzma
2021-01-26T16:07:50.492Z,1611677270.492 [NAL9602](INFO): Packets left to send: 0
2021-01-26T16:07:59.043Z,1611677279.043 [NAL9602](INFO): SBD MO Status=1, MOMSN=12994, MT Status=0, MTMSN=0
2021-01-26T16:07:59.100Z,1611677279.100 [NAL9602](INFO): Sent 101 bytes from file Logs/20210126T135224/Express0068.lzma
2021-01-26T16:07:59.100Z,1611677279.100 [NAL9602](INFO): Packets left to send: 0
2021-01-26T16:08:02.816Z,1611677282.816 [Startup:StartupSatComms:B](INFO): Timed out from 2021-01-26T16:07:02.8Z
2021-01-26T16:08:02.816Z,1611677282.816 [Startup:StartupSatComms:B] Stopped
2021-01-26T16:08:02.816Z,1611677282.816 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-01-26T16:08:02.816Z,1611677282.816 [Startup:StartupSatComms] Stopped
2021-01-26T16:08:02.817Z,1611677282.817 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-01-26T16:08:02.818Z,1611677282.818 [Startup](INFO): Completed Startup
2021-01-26T16:08:02.819Z,1611677282.819 [MissionManager](INFO): Startup is completed.
2021-01-26T16:08:02.835Z,1611677282.835 [MissionManager](INFO): Uninitializing Mission Startup
2021-01-26T16:08:02.835Z,1611677282.835 [Startup] Stopped
2021-01-26T16:08:02.836Z,1611677282.836 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-01-26T16:08:02.836Z,1611677282.836 [Startup:A.GoToSurface] Stopped
2021-01-26T16:08:02.836Z,1611677282.836 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-01-26T16:08:03.182Z,1611677283.182 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2021-01-26T16:08:03.183Z,1611677283.183 [DefaultWithUndock] Running Loop=1
2021-01-26T16:08:03.183Z,1611677283.183 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2021-01-26T16:08:03.183Z,1611677283.183 [DefaultWithUndock:A.Wait] Running Loop=1
2021-01-26T16:08:03.183Z,1611677283.183 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2021-01-26T16:08:05.192Z,1611677285.192 [NAL9602](INFO): SBD MO Status=1, MOMSN=12995, MT Status=0, MTMSN=0
2021-01-26T16:08:05.248Z,1611677285.248 [NAL9602](INFO): Sent 184 bytes from file Logs/20210126T160528/Express0001.lzma
2021-01-26T16:08:05.248Z,1611677285.248 [NAL9602](INFO): Packets left to send: 5
2021-01-26T16:08:33.477Z,1611677313.477 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2021-01-26T16:08:33.477Z,1611677313.477 [DefaultWithUndock:A.Wait] Stopped
2021-01-26T16:08:33.477Z,1611677313.477 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2021-01-26T16:08:33.477Z,1611677313.477 [DefaultWithUndock:LeaveDock] Running Loop=1
2021-01-26T16:08:33.477Z,1611677313.477 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock
2021-01-26T16:08:33.477Z,1611677313.477 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2021-01-26T16:08:33.477Z,1611677313.477 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact.
2021-01-26T16:08:33.478Z,1611677313.478 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1
2021-01-26T16:08:33.478Z,1611677313.478 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2021-01-26T16:08:33.478Z,1611677313.478 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2021-01-26T16:08:33.896Z,1611677313.896 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2021-01-26T16:08:35.922Z,1611677315.922 [NAL9602](INFO): Not Powering down - fast GPS
2021-01-26T16:08:35.983Z,1611677315.983 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-01-26T16:08:36.739Z,1611677316.739 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-01-26T16:08:37.256Z,1611677317.256 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-01-26T16:08:38.086Z,1611677318.086 [Micromodem](INFO): Nmea in: $SNTTA,,,,,160837.71*53

2021-01-26T16:08:38.118Z,1611677318.118 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detaching from dock.
2021-01-26T16:08:38.361Z,1611677318.361 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer.
2021-01-26T16:08:38.780Z,1611677318.780 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-01-26T16:08:40.361Z,1611677320.361 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-01-26T16:08:41.596Z,1611677321.596 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-01-26T16:08:42.382Z,1611677322.382 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-01-26T16:08:42.834Z,1611677322.834 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-01-26T16:08:43.613Z,1611677323.613 [Micromodem](INFO): Nmea in: $SNTTA,,,,,160843.35*50

2021-01-26T16:08:44.160Z,1611677324.160 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-01-26T16:08:46.017Z,1611677326.017 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-01-26T16:08:46.903Z,1611677326.903 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-01-26T16:08:47.634Z,1611677327.634 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-01-26T16:08:48.050Z,1611677328.050 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-01-26T16:08:48.868Z,1611677328.868 [Micromodem](INFO): Nmea in: $SNTTA,,,,,160848.61*5A

2021-01-26T16:08:49.677Z,1611677329.677 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-01-26T16:08:51.293Z,1611677331.293 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-01-26T16:08:52.496Z,1611677332.496 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-01-26T16:08:53.294Z,1611677333.294 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-01-26T16:08:53.704Z,1611677333.704 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-01-26T16:08:54.526Z,1611677334.526 [Micromodem](INFO): Nmea in: $SNTTA,,,,,160854.27*55

2021-01-26T16:08:55.331Z,1611677335.331 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-01-26T16:08:56.925Z,1611677336.925 [DUSBL_Hydroid](ERROR): No response from remote modem.
2021-01-26T16:08:57.589Z,1611677337.589 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004257
2021-01-26T16:08:58.172Z,1611677338.172 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-01-26T16:08:58.572Z,1611677338.572 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: nan m. Transitioning DDM to standby.
2021-01-26T16:08:59.002Z,1611677339.002 [Micromodem](INFO): Nmea out: $CCPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*76

2021-01-26T16:08:59.352Z,1611677339.352 [Micromodem](INFO): Nmea in: $SNPGT,1,14500,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6B

2021-01-26T16:09:00.197Z,1611677340.197 [Micromodem](INFO): Nmea in: $SNTTA,,,,,160859.97*53

2021-01-26T16:09:00.984Z,1611677340.984 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2021-01-26T16:09:01.448Z,1611677341.448 [DefaultWithUndock:LeaveDock:B.Undock](INFO): DDM at standby.
2021-01-26T16:09:01.853Z,1611677341.853 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete.
2021-01-26T16:09:01.853Z,1611677341.853 [DefaultWithUndock:LeaveDock:B.Undock] Stopped
2021-01-26T16:09:01.853Z,1611677341.853 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock
2021-01-26T16:09:01.853Z,1611677341.853 [DefaultWithUndock:LeaveDock] Stopped
2021-01-26T16:09:01.853Z,1611677341.853 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock
2021-01-26T16:09:01.854Z,1611677341.854 [DefaultWithUndock:LeaveDock:A.] Stopped
2021-01-26T16:09:01.854Z,1611677341.854 [DefaultWithUndock:SurfaceDefault] Running Loop=1
2021-01-26T16:09:01.854Z,1611677341.854 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault
2021-01-26T16:09:01.854Z,1611677341.854 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen] Running Loop=1
2021-01-26T16:09:01.854Z,1611677341.854 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:HoldLatchOpen
2021-01-26T16:09:01.854Z,1611677341.854 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:A] Running Loop=1
2021-01-26T16:09:01.854Z,1611677341.854 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:B] Running Loop=1
2021-01-26T16:09:01.854Z,1611677341.854 [DefaultWithUndock:SurfaceDefault:B.GoToSurface] Running Loop=1
2021-01-26T16:09:01.854Z,1611677341.854 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-01-26T16:09:01.855Z,1611677341.855 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-01-26T16:09:01.855Z,1611677341.855 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-01-26T16:09:01.855Z,1611677341.855 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-01-26T16:09:01.856Z,1611677341.856 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-01-26T16:09:01.856Z,1611677341.856 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-01-26T16:09:02.173Z,1611677342.173 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1
2021-01-26T16:09:02.173Z,1611677342.173 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2021-01-26T16:09:02.173Z,1611677342.173 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2021-01-26T16:09:02.173Z,1611677342.173 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen] Stopped
2021-01-26T16:09:02.174Z,1611677342.174 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:HoldLatchOpen
2021-01-26T16:09:02.174Z,1611677342.174 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:A] Stopped
2021-01-26T16:09:02.174Z,1611677342.174 [DefaultWithUndock:SurfaceDefault:HoldLatchOpen:B] Stopped
2021-01-26T16:09:02.600Z,1611677342.600 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-01-26T16:09:04.211Z,1611677344.211 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160902.00,A,4133.04012,N,07042.69989,W,0.952,262.05,260121,,,A*7F
2021-01-26T16:09:04.217Z,1611677344.217 [NAL9602](INFO): GPS fix at 20210126T160902: (41.550669, -70.711665)
2021-01-26T16:09:04.236Z,1611677344.236 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2021-01-26T16:09:04.236Z,1611677344.236 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2021-01-26T16:09:04.632Z,1611677344.632 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-01-26T16:09:08.631Z,1611677348.631 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20210126T160528/Courier0004.lzma
2021-01-26T16:09:09.634Z,1611677349.634 [DataOverHttps](INFO): Moved sent file to Logs/20210126T160528/Courier0004.lzma.bak
2021-01-26T16:09:09.634Z,1611677349.634 [DataOverHttps](INFO): SBD MOMSN=67250
2021-01-26T16:09:18.673Z,1611677358.673 [DataOverHttps](INFO): Sending 1069 bytes from file Logs/20210126T160528/Express0001.lzma
2021-01-26T16:09:19.674Z,1611677359.674 [DataOverHttps](INFO): Moved sent file to Logs/20210126T160528/Express0001.lzma.bak
2021-01-26T16:09:19.674Z,1611677359.674 [DataOverHttps](INFO): SBD MOMSN=67253
2021-01-26T16:09:23.163Z,1611677363.163 [NAL9602](INFO): SBD MO Status=0, MOMSN=12996, MT Status=0, MTMSN=0
2021-01-26T16:09:23.163Z,1611677363.163 [NAL9602](INFO): No messages in MT queue
2021-01-26T16:09:28.712Z,1611677368.712 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20210126T160528/Express0005.lzma
2021-01-26T16:09:29.713Z,1611677369.713 [DataOverHttps](INFO): Moved sent file to Logs/20210126T160528/Express0005.lzma.bak
2021-01-26T16:09:29.713Z,1611677369.713 [DataOverHttps](INFO): SBD MOMSN=67281
2021-01-26T16:09:30.915Z,1611677370.915 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2021-01-26T16:09:30.915Z,1611677370.915 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2021-01-26T16:09:30.916Z,1611677370.916 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-01-26T16:09:53.861Z,1611677393.861 [NAL9602](INFO): Not Powering down - fast GPS
2021-01-26T16:12:30.473Z,1611677550.473 [DataOverHttps](IMPORTANT): SBD MTMSN=20210126T161229
2021-01-26T16:12:34.499Z,1611677554.499 [DataOverHttps](INFO): Received command:restart logs