2020-02-08T21:25:22.840Z,1581197122.840 [Supervisor](DEBUG): Initializing supervisor.
2020-02-08T21:25:22.843Z,1581197122.843 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-02-08T21:25:22.843Z,1581197122.843 [SyncHandler](INFO): Protected caller Thread ID is 820
2020-02-08T21:25:22.844Z,1581197122.844 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-02-08T21:25:22.845Z,1581197122.845 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-02-08T21:25:22.845Z,1581197122.845 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 821
2020-02-08T21:25:22.848Z,1581197122.848 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-02-08T21:25:22.860Z,1581197122.860 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-02-08T21:25:22.861Z,1581197122.861 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-02-08T21:25:22.862Z,1581197122.862 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 822
2020-02-08T21:25:22.862Z,1581197122.862 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-02-08T21:25:22.863Z,1581197122.863 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-02-08T21:25:22.864Z,1581197122.864 [logger ThreadHandler](INFO): Protected caller Thread ID is 823
2020-02-08T21:25:22.866Z,1581197122.866 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-02-08T21:25:22.866Z,1581197122.866 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-02-08T21:25:22.870Z,1581197122.870 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-02-08T21:25:23.305Z,1581197123.305 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-02-08T21:25:23.307Z,1581197123.307 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-02-08T21:25:23.404Z,1581197123.404 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-02-08T21:25:23.406Z,1581197123.406 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-02-08T21:25:23.719Z,1581197123.719 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-02-08T21:25:23.721Z,1581197123.721 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-02-08T21:25:23.858Z,1581197123.858 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-02-08T21:25:23.860Z,1581197123.860 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-02-08T21:25:24.051Z,1581197124.051 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-02-08T21:25:24.051Z,1581197124.051 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-02-08T21:25:24.502Z,1581197124.502 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-02-08T21:25:24.504Z,1581197124.504 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-02-08T21:25:24.718Z,1581197124.718 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-02-08T21:25:24.720Z,1581197124.720 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-02-08T21:25:24.865Z,1581197124.865 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-02-08T21:25:24.866Z,1581197124.866 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-02-08T21:25:25.056Z,1581197125.056 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-02-08T21:25:25.057Z,1581197125.057 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-02-08T21:25:25.153Z,1581197125.153 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-02-08T21:25:25.154Z,1581197125.154 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-02-08T21:25:25.451Z,1581197125.451 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-02-08T21:25:25.453Z,1581197125.453 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-02-08T21:25:25.533Z,1581197125.533 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-02-08T21:25:25.636Z,1581197125.636 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-02-08T21:25:25.637Z,1581197125.637 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-02-08T21:25:26.340Z,1581197126.340 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-02-08T21:25:26.754Z,1581197126.754 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-02-08T21:25:27.436Z,1581197127.436 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-02-08T21:25:27.438Z,1581197127.438 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-02-08T21:25:27.440Z,1581197127.440 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-02-08T21:25:27.649Z,1581197127.649 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-02-08T21:25:27.749Z,1581197127.749 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-02-08T21:25:27.848Z,1581197127.848 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-02-08T21:25:28.073Z,1581197128.073 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-02-08T21:25:28.074Z,1581197128.074 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-02-08T21:25:28.159Z,1581197128.159 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-02-08T21:25:28.254Z,1581197128.254 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-02-08T21:25:28.351Z,1581197128.351 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-02-08T21:25:28.434Z,1581197128.434 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-02-08T21:25:28.542Z,1581197128.542 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/workSite.cfg
2020-02-08T21:25:28.635Z,1581197128.635 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-02-08T21:25:28.819Z,1581197128.819 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-02-08T21:25:28.950Z,1581197128.950 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-02-08T21:25:28.963Z,1581197128.963 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-02-08T21:25:29.504Z,1581197129.504 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-02-08T21:25:29.509Z,1581197129.509 [AHRS_M2](INFO): created writer for : platform_orientation
2020-02-08T21:25:29.511Z,1581197129.511 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-02-08T21:25:29.515Z,1581197129.515 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-02-08T21:25:29.516Z,1581197129.516 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-02-08T21:25:29.521Z,1581197129.521 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-02-08T21:25:29.521Z,1581197129.521 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-02-08T21:25:29.526Z,1581197129.526 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-02-08T21:25:29.700Z,1581197129.700 [AHRS_M2] Loaded
2020-02-08T21:25:29.700Z,1581197129.700 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-02-08T21:25:29.847Z,1581197129.847 [DataOverHttps] Loaded
2020-02-08T21:25:29.848Z,1581197129.848 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-02-08T21:25:29.849Z,1581197129.849 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407684E0
2020-02-08T21:25:29.849Z,1581197129.849 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 905
2020-02-08T21:25:29.872Z,1581197129.872 [DDM] Loaded
2020-02-08T21:25:29.872Z,1581197129.872 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-02-08T21:25:29.885Z,1581197129.885 [Depth_Keller] Loaded
2020-02-08T21:25:29.885Z,1581197129.885 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-02-08T21:25:29.890Z,1581197129.890 [DropWeight] Loaded
2020-02-08T21:25:29.890Z,1581197129.890 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-02-08T21:25:29.949Z,1581197129.949 [DUSBL_Hydroid] Loaded
2020-02-08T21:25:29.950Z,1581197129.950 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-02-08T21:25:29.995Z,1581197129.995 [Micromodem] Loaded
2020-02-08T21:25:29.995Z,1581197129.995 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-02-08T21:25:30.088Z,1581197130.088 [NAL9602] Loaded
2020-02-08T21:25:30.088Z,1581197130.088 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-02-08T21:25:30.127Z,1581197130.127 [Onboard] Loaded
2020-02-08T21:25:30.127Z,1581197130.127 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-02-08T21:25:30.133Z,1581197130.133 [Radio_Surface] Loaded
2020-02-08T21:25:30.134Z,1581197130.134 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-02-08T21:25:30.134Z,1581197130.134 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407984E0
2020-02-08T21:25:30.135Z,1581197130.135 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 906
2020-02-08T21:25:30.177Z,1581197130.177 [RDI_Pathfinder] Loaded
2020-02-08T21:25:30.177Z,1581197130.177 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-02-08T21:25:31.593Z,1581197131.593 [BPC1] Loaded
2020-02-08T21:25:31.593Z,1581197131.593 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-02-08T21:25:31.594Z,1581197131.594 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-02-08T21:25:31.594Z,1581197131.594 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-02-08T21:25:31.629Z,1581197131.629 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-02-08T21:25:32.252Z,1581197132.252 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-02-08T21:25:32.395Z,1581197132.395 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-02-08T21:25:32.396Z,1581197132.396 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-02-08T21:25:32.416Z,1581197132.416 [NavChart] Loaded
2020-02-08T21:25:32.416Z,1581197132.416 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-02-08T21:25:32.420Z,1581197132.420 [UniversalFixResidualReporter] Loaded
2020-02-08T21:25:32.420Z,1581197132.420 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-02-08T21:25:32.434Z,1581197132.434 [WorkSite] Loaded
2020-02-08T21:25:32.434Z,1581197132.434 [ComponentRegistry](DEBUG): SyncComponent "WorkSite" handled in the control thread.
2020-02-08T21:25:32.435Z,1581197132.435 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-02-08T21:25:32.435Z,1581197132.435 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-02-08T21:25:32.563Z,1581197132.563 [BuoyancyServo] Loaded
2020-02-08T21:25:32.564Z,1581197132.564 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-02-08T21:25:32.578Z,1581197132.578 [ElevatorServo] Loaded
2020-02-08T21:25:32.579Z,1581197132.579 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-02-08T21:25:32.593Z,1581197132.593 [MassServo] Loaded
2020-02-08T21:25:32.594Z,1581197132.594 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-02-08T21:25:32.609Z,1581197132.609 [RudderServo] Loaded
2020-02-08T21:25:32.609Z,1581197132.609 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-02-08T21:25:32.623Z,1581197132.623 [ThrusterServo] Loaded
2020-02-08T21:25:32.623Z,1581197132.623 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-02-08T21:25:32.624Z,1581197132.624 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-02-08T21:25:32.624Z,1581197132.624 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-02-08T21:25:32.891Z,1581197132.891 [PAR_Licor] Loaded
2020-02-08T21:25:32.891Z,1581197132.891 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-02-08T21:25:32.934Z,1581197132.934 [WetLabsSeaOWL_UV_A] Loaded
2020-02-08T21:25:32.934Z,1581197132.934 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-02-08T21:25:32.935Z,1581197132.935 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408F04E0
2020-02-08T21:25:32.936Z,1581197132.936 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 908
2020-02-08T21:25:32.936Z,1581197132.936 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-02-08T21:25:32.938Z,1581197132.938 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-02-08T21:25:33.335Z,1581197133.335 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-02-08T21:25:33.337Z,1581197133.337 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-02-08T21:25:33.391Z,1581197133.391 [DepthRateCalculator] Loaded
2020-02-08T21:25:33.391Z,1581197133.391 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-02-08T21:25:33.396Z,1581197133.396 [PitchRateCalculator] Loaded
2020-02-08T21:25:33.397Z,1581197133.397 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-02-08T21:25:33.409Z,1581197133.409 [SpeedCalculator] Loaded
2020-02-08T21:25:33.409Z,1581197133.409 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-02-08T21:25:33.429Z,1581197133.429 [TempGradientCalculator] Loaded
2020-02-08T21:25:33.430Z,1581197133.430 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-02-08T21:25:33.435Z,1581197133.435 [YawRateCalculator] Loaded
2020-02-08T21:25:33.435Z,1581197133.435 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-02-08T21:25:33.475Z,1581197133.475 [ElevatorOffsetCalculator] Loaded
2020-02-08T21:25:33.475Z,1581197133.475 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-02-08T21:25:33.476Z,1581197133.476 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-02-08T21:25:33.477Z,1581197133.477 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-02-08T21:25:33.637Z,1581197133.637 [SBIT](DEBUG): Construct Startup Built In Test.
2020-02-08T21:25:33.659Z,1581197133.659 [SBIT] Loaded
2020-02-08T21:25:33.659Z,1581197133.659 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-02-08T21:25:33.660Z,1581197133.660 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-02-08T21:25:33.672Z,1581197133.672 [IBIT] Loaded
2020-02-08T21:25:33.672Z,1581197133.672 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-02-08T21:25:33.675Z,1581197133.675 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-02-08T21:25:33.810Z,1581197133.810 [CBIT] Loaded
2020-02-08T21:25:33.811Z,1581197133.811 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-02-08T21:25:33.811Z,1581197133.811 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-02-08T21:25:33.813Z,1581197133.813 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-02-08T21:25:33.936Z,1581197133.936 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-02-08T21:25:33.937Z,1581197133.937 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-02-08T21:25:34.057Z,1581197134.057 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-02-08T21:25:34.058Z,1581197134.058 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-02-08T21:25:34.120Z,1581197134.120 [VerticalControl](DEBUG): Construct VerticalControl.
2020-02-08T21:25:34.201Z,1581197134.201 [VerticalControl] Loaded
2020-02-08T21:25:34.201Z,1581197134.201 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-02-08T21:25:34.202Z,1581197134.202 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-02-08T21:25:34.270Z,1581197134.270 [HorizontalControl] Loaded
2020-02-08T21:25:34.270Z,1581197134.270 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-02-08T21:25:34.271Z,1581197134.271 [SpeedControl](DEBUG): Construct SpeedControl.
2020-02-08T21:25:34.272Z,1581197134.272 [SpeedControl] Loaded
2020-02-08T21:25:34.273Z,1581197134.273 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-02-08T21:25:34.273Z,1581197134.273 [LoopControl](DEBUG): Construct LoopControl.
2020-02-08T21:25:34.274Z,1581197134.274 [LoopControl] Loaded
2020-02-08T21:25:34.274Z,1581197134.274 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-02-08T21:25:34.275Z,1581197134.275 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-02-08T21:25:34.275Z,1581197134.275 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-02-08T21:25:34.323Z,1581197134.323 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-02-08T21:25:34.326Z,1581197134.326 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-02-08T21:25:34.327Z,1581197134.327 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-02-08T21:25:34.334Z,1581197134.334 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-02-08T21:25:34.335Z,1581197134.335 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ADE4E0
2020-02-08T21:25:34.335Z,1581197134.335 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 909
2020-02-08T21:25:34.340Z,1581197134.340 [Supervisor](INFO): Main Thread ID is 816
2020-02-08T21:25:34.340Z,1581197134.340 [Supervisor](DEBUG): Running supervisor.
2020-02-08T21:25:34.340Z,1581197134.340 [CommandLine ThreadHandler](INFO): Handler Thread ID is 910
2020-02-08T21:25:34.343Z,1581197134.343 [controlThread ThreadHandler](INFO): Handler Thread ID is 911
2020-02-08T21:25:34.343Z,1581197134.343 [controlThread](DEBUG): Initializing ControlThread
2020-02-08T21:25:34.352Z,1581197134.352 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-02-08T21:25:34.352Z,1581197134.352 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-02-08T21:25:34.352Z,1581197134.352 [WorkSite](DEBUG): Initializing WorkSite component.
2020-02-08T21:25:34.357Z,1581197134.357 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-02-08T21:25:34.357Z,1581197134.357 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-02-08T21:25:34.358Z,1581197134.358 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-02-08T21:25:34.358Z,1581197134.358 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-02-08T21:25:34.359Z,1581197134.359 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-02-08T21:25:34.359Z,1581197134.359 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-02-08T21:25:34.360Z,1581197134.360 [SBIT](INFO): Initialize SBIT Component.
2020-02-08T21:25:34.360Z,1581197134.360 [SBIT](IMPORTANT): git: 2020-02-07
2020-02-08T21:25:34.360Z,1581197134.360 [SBIT](INFO): git hash: 6707874467016a4fb4c344b7e74bc4738258579e
2020-02-08T21:25:34.361Z,1581197134.361 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-02-08T21:25:34.362Z,1581197134.362 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Nov 13 12:38:10 PST 2019
2020-02-08T21:25:34.363Z,1581197134.363 [SBIT](INFO): Beginning SBIT in 29.000000 seconds.
2020-02-08T21:25:34.363Z,1581197134.363 [IBIT](INFO): Initialize IBIT Component.
2020-02-08T21:25:34.364Z,1581197134.364 [CBIT](DEBUG): Initialize CBIT Component.
2020-02-08T21:25:34.365Z,1581197134.365 [logger ThreadHandler](INFO): Handler Thread ID is 912
2020-02-08T21:25:34.377Z,1581197134.377 [CBIT](DEBUG): Initialized mux pins.
2020-02-08T21:25:34.377Z,1581197134.377 [CBIT](DEBUG): Initializing the watchdog timer.
2020-02-08T21:25:34.385Z,1581197134.385 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 913
2020-02-08T21:25:34.386Z,1581197134.386 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-02-08T21:25:34.397Z,1581197134.397 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 914
2020-02-08T21:25:34.401Z,1581197134.401 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-02-08T21:25:34.401Z,1581197134.401 [CBIT](DEBUG): Initializing heartbeat.
2020-02-08T21:25:34.409Z,1581197134.409 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 915
2020-02-08T21:25:34.410Z,1581197134.410 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-02-08T21:25:34.437Z,1581197134.437 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 916
2020-02-08T21:25:34.446Z,1581197134.446 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-02-08T21:25:34.446Z,1581197134.446 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-02-08T21:25:34.446Z,1581197134.446 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-02-08T21:25:34.446Z,1581197134.446 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-02-08T21:25:34.447Z,1581197134.447 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-02-08T21:25:34.447Z,1581197134.447 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-02-08T21:25:34.447Z,1581197134.447 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-02-08T21:25:34.447Z,1581197134.447 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-02-08T21:25:34.447Z,1581197134.447 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-02-08T21:25:34.447Z,1581197134.447 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-02-08T21:25:34.448Z,1581197134.448 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-02-08T21:25:34.448Z,1581197134.448 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-02-08T21:25:34.448Z,1581197134.448 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-02-08T21:25:34.448Z,1581197134.448 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-02-08T21:25:34.448Z,1581197134.448 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-02-08T21:25:34.448Z,1581197134.448 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-02-08T21:25:34.473Z,1581197134.473 [CBIT](DEBUG): Deactivating GF circuits.
2020-02-08T21:25:34.473Z,1581197134.473 [CBIT](DEBUG): Deactivating emergency mode.
2020-02-08T21:25:34.509Z,1581197134.509 [CBIT](DEBUG): Backplane powered.
2020-02-08T21:25:34.509Z,1581197134.509 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-02-08T21:25:34.511Z,1581197134.511 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-02-08T21:25:34.511Z,1581197134.511 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-02-08T21:25:34.512Z,1581197134.512 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-02-08T21:25:34.513Z,1581197134.513 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-02-08T21:25:34.537Z,1581197134.537 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-02-08T21:25:34.567Z,1581197134.567 [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>


2020-02-08T21:25:34.568Z,1581197134.568 [MissionManager](INFO): Loading Mission: Missions/underIce/DefaultWithUndock.xml
2020-02-08T21:25:34.655Z,1581197134.655 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min
2020-02-08T21:25:34.674Z,1581197134.674 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 30.000000 min
2020-02-08T21:25:34.678Z,1581197134.678 [MissionManager](INFO): DefineArg DefaultWithUndock.MicromodemCommsInterval = 2.000000 min
2020-02-08T21:25:34.679Z,1581197134.679 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait.
2020-02-08T21:25:34.680Z,1581197134.680 [DefaultWithUndock:B.Undock](DEBUG): Construct.
2020-02-08T21:25:34.725Z,1581197134.725 [DefaultWithUndock:C.GoToSurface](DEBUG): Construct GoToSurface.
2020-02-08T21:25:34.755Z,1581197134.755 [DefaultWithUndock:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-02-08T21:25:34.770Z,1581197134.770 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-02-08T21:25:34.775Z,1581197134.775 [DefaultWithUndock:F.Execute](DEBUG): Construct Execute.
2020-02-08T21:25:34.795Z,1581197134.795 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="DefaultWithUndock">

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

    <DefineArg Name="DetachTimeout"><Description>
        Maximum time duration for the vehicle to reach the approach depth after detaching form the dock.
        The vehicle will switch over to midcourse guidance after the timeout is expired.
    </Description><Units:minute/><Value>30</Value></DefineArg>

    <DefineArg Name="MicromodemCommsInterval"><Description>
        How often to trigger Umodem communications
    </Description><Units:minute/><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>

    <!-- Leave the dock if necessary --> 
    <Guidance:Undock>
        <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>
    </Guidance:Undock>

    <!-- 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>

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

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

</Mission>


2020-02-08T21:25:34.800Z,1581197134.800 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,RDI_Pathfinder,BPC1,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,
2020-02-08T21:25:34.801Z,1581197134.801 [Radio_Surface](INFO): Powering up
2020-02-08T21:25:34.827Z,1581197134.827 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-02-08T21:25:34.951Z,1581197134.951 [DDM](INFO): Powering up
2020-02-08T21:25:34.951Z,1581197134.951 [DDM](DEBUG): Initializing DDM.
2020-02-08T21:25:34.974Z,1581197134.974 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2020-02-08T21:25:34.974Z,1581197134.974 [DUSBL_Hydroid](INFO): Powering up
2020-02-08T21:25:34.975Z,1581197134.975 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-02-08T21:25:35.151Z,1581197135.151 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-02-08T21:25:35.221Z,1581197135.221 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-02-08T21:25:35.229Z,1581197135.229 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-02-08T21:25:35.230Z,1581197135.230 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-02-08T21:25:35.237Z,1581197135.237 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-02-08T21:25:35.238Z,1581197135.238 [MassServo](DEBUG): Initializing EZServoServo.
2020-02-08T21:25:35.245Z,1581197135.245 [MassServo](DEBUG): Initializing MassServo.
2020-02-08T21:25:35.246Z,1581197135.246 [RudderServo](DEBUG): Initializing EZServoServo.
2020-02-08T21:25:35.253Z,1581197135.253 [RudderServo](DEBUG): Initializing RudderServo.
2020-02-08T21:25:35.254Z,1581197135.254 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-02-08T21:25:35.261Z,1581197135.261 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-02-08T21:25:35.363Z,1581197135.363 [Micromodem](INFO): Powering up
2020-02-08T21:25:35.363Z,1581197135.363 [Micromodem](DEBUG): Initializing Micromodem.
2020-02-08T21:25:35.413Z,1581197135.413 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-02-08T21:25:39.460Z,1581197139.460 [WorkSite](IMPORTANT): Reading workSite position from Data/workSite.cfg
2020-02-08T21:25:39.471Z,1581197139.471 [WorkSite](IMPORTANT): WorkSite fix at 20200208T212257: (44.837100, -67.564597)
2020-02-08T21:25:40.164Z,1581197140.164 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-02-08T21:25:42.183Z,1581197142.183 [Micromodem](INFO): Nmea in: $CATMG,2020-02-08T21:25:26.762914Z,RTC,RTC*55

2020-02-08T21:25:42.183Z,1581197142.183 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-02-08T21:25:26.762914Z,RTC,RTC*55

2020-02-08T21:25:48.443Z,1581197148.443 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-02-08T21:25:50.440Z,1581197150.440 [DDM](INFO): Dynamic Docking Module:
2020-02-08T21:25:51.251Z,1581197151.251 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-02-08T21:25:51.679Z,1581197151.679 [Micromodem](INFO): Nmea in: $CAERR,212537,NI  ,12,Unknown command*4C

2020-02-08T21:25:51.680Z,1581197151.680 [Micromodem](ERROR): Got error from modem: $CAERR,212537,NI  ,12,Unknown command*4C

2020-02-08T21:25:52.886Z,1581197152.886 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-02-08T21:26:00.163Z,1581197160.163 [NAL9602](INFO): Powering up NAL9602
2020-02-08T21:26:02.586Z,1581197162.586 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2020-02-08T21:26:02.990Z,1581197162.990 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-02-08T21:26:02.991Z,1581197162.991 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-02-08T21:26:03.410Z,1581197163.410 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-02-08T21:26:03.411Z,1581197163.411 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-02-08T21:26:03.795Z,1581197163.795 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-02-08T21:26:03.795Z,1581197163.795 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-02-08T21:26:03.877Z,1581197163.877 [SBIT](IMPORTANT): Beginning Startup BIT
2020-02-08T21:26:03.889Z,1581197163.889 [CBIT](IMPORTANT): Beginning ground fault scan
2020-02-08T21:26:04.203Z,1581197164.203 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-02-08T21:26:04.203Z,1581197164.203 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-02-08T21:26:04.611Z,1581197164.611 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-02-08T21:26:04.611Z,1581197164.611 [Micromodem](INFO): Nmea out: $CCCFG,FC0,14500*47

2020-02-08T21:26:05.007Z,1581197165.007 [Micromodem](INFO): Nmea in: $CACFG,FC0,14500*45

2020-02-08T21:26:05.007Z,1581197165.007 [Micromodem](INFO): Nmea out: $CCCFG,BW0,2000*65

2020-02-08T21:26:05.410Z,1581197165.410 [Micromodem](INFO): Nmea in: $CACFG,BW0,2000*67

2020-02-08T21:26:05.411Z,1581197165.411 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-02-08T21:26:05.815Z,1581197165.815 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-02-08T21:26:05.815Z,1581197165.815 [Micromodem](INFO): Nmea out: $CCCLK,2020,02,08,21,26,06*4F

2020-02-08T21:26:05.972Z,1581197165.972 [ElevatorServo](FAULT): Overload Error
2020-02-08T21:26:05.972Z,1581197165.972 [ElevatorServo] Hardware Fault, FailCount= 1
2020-02-08T21:26:05.972Z,1581197165.972 [ElevatorServo](ERROR): Hardware Fault
2020-02-08T21:26:06.077Z,1581197166.077 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2020-02-08T21:26:06.219Z,1581197166.219 [Micromodem](INFO): Nmea in: $CACLK,2020,2,8,21,26,6*7D

2020-02-08T21:26:06.272Z,1581197166.272 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-02-08T21:26:06.272Z,1581197166.272 [ElevatorServo](INFO): Powering down
2020-02-08T21:26:06.875Z,1581197166.875 [Micromodem](INFO): Nmea in: $CATMS,0,2020-02-08T21:26:07Z*7C

2020-02-08T21:26:06.920Z,1581197166.920 [Micromodem](INFO): Nmea in: $CATMG,2020-02-08T21:26:07.026308Z,USER_CMD,RTC*14

2020-02-08T21:26:07.312Z,1581197167.312 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-02-08T21:26:07.430Z,1581197167.430 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-02-08T21:26:07.498Z,1581197167.498 [CBIT](INFO): Clearing failed state for component ElevatorServo
2020-02-08T21:26:07.498Z,1581197167.498 [ElevatorServo] No Fault, FailCount= 1
2020-02-08T21:26:08.137Z,1581197168.137 [DDM](FAULT): Could not parse whisker state
2020-02-08T21:26:08.137Z,1581197168.137 [DDM] Communications Fault, FailCount= 1
2020-02-08T21:26:08.137Z,1581197168.137 [DDM](ERROR): Communications Fault
2020-02-08T21:26:08.360Z,1581197168.360 [CBIT](ERROR): Communications Fault in component: DDM
2020-02-08T21:26:08.446Z,1581197168.446 [DDM](INFO): Powering down
2020-02-08T21:26:09.402Z,1581197169.402 [CBIT](INFO): Clearing failed state for component DDM
2020-02-08T21:26:09.402Z,1581197169.402 [DDM] No Fault, FailCount= 1
2020-02-08T21:26:11.279Z,1581197171.279 [NAL9602](INFO): NAL9602 initialized
2020-02-08T21:26:11.707Z,1581197171.707 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-08T21:26:11.707Z,1581197171.707 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020814390, -0.1,   0.0,1448.9,  0
2020-02-08T21:26:12.201Z,1581197172.201 [ElevatorServo](FAULT): Overload Error
2020-02-08T21:26:12.201Z,1581197172.201 [ElevatorServo] Hardware Fault, FailCount= 2
2020-02-08T21:26:12.201Z,1581197172.201 [ElevatorServo](ERROR): Hardware Fault
2020-02-08T21:26:12.278Z,1581197172.278 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2020-02-08T21:26:12.583Z,1581197172.583 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-02-08T21:26:12.583Z,1581197172.583 [ElevatorServo](INFO): Powering down
2020-02-08T21:26:13.390Z,1581197173.390 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-02-08T21:26:13.506Z,1581197173.506 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-02-08T21:26:13.553Z,1581197173.553 [CBIT](INFO): Clearing failed state for component ElevatorServo
2020-02-08T21:26:13.554Z,1581197173.554 [ElevatorServo] No Fault, FailCount= 2
2020-02-08T21:26:13.698Z,1581197173.698 [DDM](INFO): Powering up
2020-02-08T21:26:13.698Z,1581197173.698 [DDM](DEBUG): Initializing DDM.
2020-02-08T21:26:14.681Z,1581197174.681 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.004853
CHAN A1 (24V): -0.025680
CHAN A2 (12V): -0.007270
CHAN A3 (5V): -0.002434
CHAN B0 (3.3V): -0.000555
CHAN B1 (3.15aV): -0.002080
CHAN B2 (3.15bV): -0.001103
CHAN B3 (GND): 0.001486
OPEN: 0.006951
Full Scale Calc: 4.765 mA, -1.589 mA
2020-02-08T21:26:17.063Z,1581197177.063 [SBIT](FAULT): Elevator: EXPECTED:15.000000 ACTUAL:-0.021994
2020-02-08T21:26:18.645Z,1581197178.645 [ElevatorServo](FAULT): Overload Error
2020-02-08T21:26:18.645Z,1581197178.645 [ElevatorServo] Hardware Fault, FailCount= 3
2020-02-08T21:26:18.645Z,1581197178.645 [ElevatorServo](ERROR): Hardware Fault
2020-02-08T21:26:18.733Z,1581197178.733 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2020-02-08T21:26:18.734Z,1581197178.734 [CBIT](CRITICAL): Hardware Fault in component: ElevatorServo
2020-02-08T21:26:19.027Z,1581197179.027 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-02-08T21:26:19.027Z,1581197179.027 [ElevatorServo](INFO): Powering down
2020-02-08T21:26:19.471Z,1581197179.471 [CommandLine](FAULT): Scheduling is paused
2020-02-08T21:26:19.471Z,1581197179.471 [CBIT](INFO): Critical error at 20200208T212618
2020-02-08T21:26:19.472Z,1581197179.472 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-02-08T21:26:19.956Z,1581197179.956 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-02-08T21:26:20.074Z,1581197180.074 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-02-08T21:26:29.183Z,1581197189.183 [DDM](INFO): Dynamic Docking Module:!C REMUS Capture Rev 1.1
2020-02-08T21:26:42.116Z,1581197202.116 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-08T21:26:43.774Z,1581197203.774 [SBIT](ERROR): Could not read elevatorAngleReader_.
2020-02-08T21:26:43.774Z,1581197203.774 [SBIT](FAULT): Control surface position failure.
2020-02-08T21:26:57.586Z,1581197217.586 [SBIT](ERROR): Could not read elevatorAngleReader_.
2020-02-08T21:26:57.586Z,1581197217.586 [SBIT](FAULT): Control surface position failure.
2020-02-08T21:26:58.009Z,1581197218.009 [SBIT](CRITICAL): SBIT FAILED
2020-02-08T21:26:58.010Z,1581197218.010 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-02-08T21:26:58.010Z,1581197218.010 [SBIT](IMPORTANT): DUSBL_Hydroid.verbosity=3 enum;

2020-02-08T21:26:58.010Z,1581197218.010 [SBIT](IMPORTANT): Express linearApproximation DUSBL_Hydroid.acoustic_contact_range 10.000000 meter;

2020-02-08T21:26:58.011Z,1581197218.011 [SBIT](IMPORTANT): Express linearApproximation acoustic_contact_range 10.000000 meter;

2020-02-08T21:26:58.011Z,1581197218.011 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=0 bool;

2020-02-08T21:26:58.011Z,1581197218.011 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=320 cubic_centimeter;

2020-02-08T21:26:58.011Z,1581197218.011 [SBIT](IMPORTANT): VerticalControl.massDefault=2.4 millimeter;

2020-02-08T21:26:58.013Z,1581197218.013 [CBIT](INFO): Critical error at 20200208T212658
2020-02-08T21:26:58.419Z,1581197218.419 [MissionManager](IMPORTANT): Started mission Startup
2020-02-08T21:26:58.419Z,1581197218.419 [Startup] Running Loop=1
2020-02-08T21:26:58.419Z,1581197218.419 [Startup](DEBUG): Aggregate::initialize Startup
2020-02-08T21:26:58.419Z,1581197218.419 [Startup:A.GoToSurface] Running Loop=1
2020-02-08T21:26:58.419Z,1581197218.419 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-02-08T21:26:58.420Z,1581197218.420 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-02-08T21:26:58.420Z,1581197218.420 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-02-08T21:26:58.421Z,1581197218.421 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-02-08T21:26:58.421Z,1581197218.421 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-02-08T21:26:58.423Z,1581197218.423 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-02-08T21:26:58.430Z,1581197218.430 [Startup:StartupSatComms] Running Loop=1
2020-02-08T21:26:58.430Z,1581197218.430 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-02-08T21:26:58.430Z,1581197218.430 [Startup:StartupSatComms:A] Running Loop=1
2020-02-08T21:26:58.780Z,1581197218.780 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-02-08T21:27:00.365Z,1581197220.365 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212644.00,A,4450.23610,N,06733.86174,W,1.127,229.79,080220,,,A*7F
2020-02-08T21:27:00.368Z,1581197220.368 [NAL9602](INFO): GPS fix at 20200208T212644: (44.837268, -67.564362)
2020-02-08T21:27:00.450Z,1581197220.450 [Startup:StartupSatComms:A] Stopped
2020-02-08T21:27:00.450Z,1581197220.450 [Startup:StartupSatComms:B] Running Loop=1
2020-02-08T21:27:00.827Z,1581197220.827 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-02-08T21:27:02.878Z,1581197222.878 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-02-08T21:27:08.060Z,1581197228.060 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-08T21:27:08.061Z,1581197228.061 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200208144007 -0.1,   0.0,1448.9,  0
2020-02-08T21:27:10.061Z,1581197230.061 [NAL9602](INFO): SBD MO Status=0, MOMSN=10611, MT Status=0, MTMSN=0
2020-02-08T21:27:10.061Z,1581197230.061 [NAL9602](INFO): No messages in MT queue
2020-02-08T21:27:15.831Z,1581197235.831 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-02-08T21:27:40.763Z,1581197260.763 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-08T21:28:00.602Z,1581197280.602 [Startup:StartupSatComms:B](INFO): Timed out from 2020-02-08T21:27:00.5Z
2020-02-08T21:28:00.602Z,1581197280.602 [Startup:StartupSatComms:B] Stopped
2020-02-08T21:28:00.602Z,1581197280.602 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-02-08T21:28:00.602Z,1581197280.602 [Startup:StartupSatComms] Stopped
2020-02-08T21:28:00.602Z,1581197280.602 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-02-08T21:28:00.603Z,1581197280.603 [Startup](INFO): Completed Startup
2020-02-08T21:28:00.603Z,1581197280.603 [MissionManager](INFO): Startup is completed.
2020-02-08T21:28:00.603Z,1581197280.603 [MissionManager](INFO): Uninitializing Mission Startup
2020-02-08T21:28:00.604Z,1581197280.603 [Startup] Stopped
2020-02-08T21:28:00.604Z,1581197280.604 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-02-08T21:28:00.604Z,1581197280.604 [Startup:A.GoToSurface] Stopped
2020-02-08T21:28:00.604Z,1581197280.604 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-02-08T21:28:00.996Z,1581197280.996 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2020-02-08T21:28:00.996Z,1581197280.996 [DefaultWithUndock] Running Loop=1
2020-02-08T21:28:00.996Z,1581197280.996 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2020-02-08T21:28:00.997Z,1581197280.997 [DefaultWithUndock:C.GoToSurface] Running Loop=1
2020-02-08T21:28:00.997Z,1581197280.997 [DefaultWithUndock:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-02-08T21:28:00.997Z,1581197280.997 [DefaultWithUndock:C.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-02-08T21:28:00.997Z,1581197280.997 [DefaultWithUndock:C.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-02-08T21:28:00.998Z,1581197280.998 [DefaultWithUndock:C.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-02-08T21:28:00.998Z,1581197280.998 [DefaultWithUndock:C.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-02-08T21:28:00.998Z,1581197280.998 [DefaultWithUndock:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-02-08T21:28:00.998Z,1581197280.998 [DefaultWithUndock:A.Wait] Running Loop=1
2020-02-08T21:28:00.998Z,1581197280.998 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2020-02-08T21:28:23.681Z,1581197303.681 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-02-08T21:28:31.289Z,1581197311.289 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2020-02-08T21:28:31.289Z,1581197311.289 [DefaultWithUndock:A.Wait] Stopped
2020-02-08T21:28:31.290Z,1581197311.290 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2020-02-08T21:28:31.290Z,1581197311.290 [DefaultWithUndock:B.Undock] Running Loop=1
2020-02-08T21:28:31.290Z,1581197311.290 [DefaultWithUndock:B.Undock](DEBUG): Initialize.
2020-02-08T21:28:47.933Z,1581197327.933 [DefaultWithUndock:B.Undock](INFO): Detaching from dock.
2020-02-08T21:28:48.298Z,1581197328.298 [DefaultWithUndock:B.Undock](INFO): Detected possible detachment. Starting timer.
2020-02-08T21:28:57.083Z,1581197337.083 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004861
2020-02-08T21:29:04.612Z,1581197344.612 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-02-08T21:29:08.493Z,1581197348.493 [DefaultWithUndock:B.Undock](INFO): Detached at range: nan m. Transitioning DDM to standby.
2020-02-08T21:29:12.985Z,1581197352.985 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-02-08T21:29:26.715Z,1581197366.715 [DefaultWithUndock:B.Undock](INFO): DDM at standby.
2020-02-08T21:29:27.136Z,1581197367.136 [DefaultWithUndock:B.Undock](IMPORTANT): Undocking sequence complete.
2020-02-08T21:29:27.136Z,1581197367.136 [DefaultWithUndock:B.Undock] Stopped
2020-02-08T21:29:27.449Z,1581197367.449 [DefaultWithUndock:CheckIn] Running Loop=1
2020-02-08T21:29:27.449Z,1581197367.449 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-02-08T21:29:27.449Z,1581197367.449 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-02-08T21:29:27.882Z,1581197367.882 [DefaultWithUndock:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-02-08T21:29:29.435Z,1581197369.435 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212913.00,A,4450.21898,N,06733.87044,W,0.097,83.80,080220,,,A*42
2020-02-08T21:29:29.438Z,1581197369.438 [NAL9602](INFO): GPS fix at 20200208T212913: (44.836983, -67.564507)
2020-02-08T21:29:29.492Z,1581197369.492 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-02-08T21:29:29.492Z,1581197369.492 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-02-08T21:29:29.893Z,1581197369.893 [DefaultWithUndock:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-02-08T21:29:36.707Z,1581197376.707 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200207T170705/Courier0823.lzma
2020-02-08T21:29:38.707Z,1581197378.707 [DataOverHttps](INFO): Moved sent file to Logs/20200207T170705/Courier0823.lzma.bak
2020-02-08T21:29:38.707Z,1581197378.707 [DataOverHttps](INFO): SBD MOMSN=12237732
2020-02-08T21:29:39.552Z,1581197379.552 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-08T21:29:39.552Z,1581197379.552 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020814423841,35.0, -0.1,   0.0,144+0.00,   0.00,230.00
2020-02-08T21:29:57.786Z,1581197397.786 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20200207T170705/Courier0826.lzma
2020-02-08T21:29:59.791Z,1581197399.791 [DataOverHttps](INFO): Moved sent file to Logs/20200207T170705/Courier0826.lzma.bak
2020-02-08T21:29:59.791Z,1581197399.791 [DataOverHttps](INFO): SBD MOMSN=12237735
2020-02-08T21:30:13.198Z,1581197413.198 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-02-08T21:30:17.243Z,1581197417.243 [DataOverHttps](INFO): Sending 344 bytes from file Logs/20200208T212522/Courier0000.lzma
2020-02-08T21:30:19.246Z,1581197419.246 [DataOverHttps](INFO): Moved sent file to Logs/20200208T212522/Courier0000.lzma.bak
2020-02-08T21:30:19.247Z,1581197419.247 [DataOverHttps](INFO): SBD MOMSN=12237737
2020-02-08T21:30:44.712Z,1581197444.712 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200208T212522/Courier0004.lzma
2020-02-08T21:30:46.719Z,1581197446.719 [DataOverHttps](INFO): Moved sent file to Logs/20200208T212522/Courier0004.lzma.bak
2020-02-08T21:30:46.719Z,1581197446.719 [DataOverHttps](INFO): SBD MOMSN=12237743
2020-02-08T21:30:50.039Z,1581197450.039 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-02-08T21:30:50.384Z,1581197450.384 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-02-08T21:30:54.423Z,1581197454.423 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-08T21:30:54.423Z,1581197454.423 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020814435441,35.0, 0.0,1448.9,  0
2020-02-08T21:31:04.810Z,1581197464.810 [DataOverHttps](INFO): Sending 163 bytes from file Logs/20200207T170705/Express0824.lzma
2020-02-08T21:31:06.815Z,1581197466.815 [DataOverHttps](INFO): Moved sent file to Logs/20200207T170705/Express0824.lzma.bak
2020-02-08T21:31:06.815Z,1581197466.815 [DataOverHttps](INFO): SBD MOMSN=12237746
2020-02-08T21:31:23.428Z,1581197483.428 [NAL9602](INFO): SBD MO Status=0, MOMSN=10612, MT Status=0, MTMSN=0
2020-02-08T21:31:23.428Z,1581197483.428 [NAL9602](INFO): No messages in MT queue
2020-02-08T21:31:24.682Z,1581197484.682 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20200207T170705/Express0827.lzma
2020-02-08T21:31:26.691Z,1581197486.691 [DataOverHttps](INFO): Moved sent file to Logs/20200207T170705/Express0827.lzma.bak
2020-02-08T21:31:26.692Z,1581197486.692 [DataOverHttps](INFO): SBD MOMSN=12237750
2020-02-08T21:31:34.804Z,1581197494.804 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-02-08T21:31:39.745Z,1581197499.745 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-02-08T21:31:44.494Z,1581197504.494 [DataOverHttps](INFO): Sending 1042 bytes from file Logs/20200208T212522/Express0001.lzma
2020-02-08T21:31:46.498Z,1581197506.498 [DataOverHttps](INFO): Moved sent file to Logs/20200208T212522/Express0001.lzma.bak
2020-02-08T21:31:46.498Z,1581197506.498 [DataOverHttps](INFO): SBD MOMSN=12237757
2020-02-08T21:31:54.128Z,1581197514.128 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-08T21:32:04.676Z,1581197524.676 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20200208T212522/Express0005.lzma
2020-02-08T21:32:06.686Z,1581197526.686 [DataOverHttps](INFO): Moved sent file to Logs/20200208T212522/Express0005.lzma.bak
2020-02-08T21:32:06.687Z,1581197526.687 [DataOverHttps](INFO): SBD MOMSN=12237783
2020-02-08T21:32:08.304Z,1581197528.304 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-02-08T21:32:08.304Z,1581197528.304 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-02-08T21:32:08.304Z,1581197528.304 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-08T21:33:36.424Z,1581197616.424 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-02-08T21:34:03.069Z,1581197643.069 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-02-08T21:34:03.072Z,1581197643.072 [BPC1](INFO): Received data from all battery sticks.
2020-02-08T21:36:27.714Z,1581197787.714 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-08T21:36:27.715Z,1581197787.715 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,
2020-02-08T21:37:08.066Z,1581197828.066 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-08T21:37:08.066Z,1581197828.066 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,

2020-02-08T21:37:08.891Z,1581197828.891 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-08T21:37:08.891Z,1581197828.891 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-02-08T21:37:08.891Z,1581197828.891 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-08T21:37:08.892Z,1581197828.892 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-02-08T21:37:09.304Z,1581197829.304 [DefaultWithUndock:CheckIn:D] Stopped
2020-02-08T21:37:09.304Z,1581197829.304 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-02-08T21:37:09.721Z,1581197829.721 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 9.138457 min 
2020-02-08T21:37:09.721Z,1581197829.721 [DefaultWithUndock:CheckIn:E] Stopped
2020-02-08T21:37:09.721Z,1581197829.721 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-02-08T21:37:09.722Z,1581197829.722 [DefaultWithUndock:CheckIn] Stopped
2020-02-08T21:37:09.722Z,1581197829.722 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-02-08T21:37:09.722Z,1581197829.722 [DefaultWithUndock:CheckIn](INFO): Running loop #2
2020-02-08T21:37:09.722Z,1581197829.722 [DefaultWithUndock:CheckIn] Running Loop=2
2020-02-08T21:37:09.722Z,1581197829.722 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-02-08T21:37:09.722Z,1581197829.722 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-02-08T21:37:11.685Z,1581197831.685 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213656.00,A,4450.22532,N,06733.87112,W,0.156,83.80,080220,,,D*48
2020-02-08T21:37:11.687Z,1581197831.687 [NAL9602](INFO): GPS fix at 20200208T213656: (44.837089, -67.564519)
2020-02-08T21:37:11.761Z,1581197831.761 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-02-08T21:37:11.761Z,1581197831.761 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-02-08T21:37:19.112Z,1581197839.112 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20200208T212522/Courier0007.lzma
2020-02-08T21:37:21.118Z,1581197841.118 [DataOverHttps](INFO): Moved sent file to Logs/20200208T212522/Courier0007.lzma.bak
2020-02-08T21:37:21.118Z,1581197841.118 [DataOverHttps](INFO): SBD MOMSN=12237789
2020-02-08T21:37:38.764Z,1581197858.764 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20200208T212522/Express0008.lzma
2020-02-08T21:37:39.622Z,1581197859.622 [NAL9602](INFO): SBD MO Status=2, MOMSN=10613, MT Status=2, MTMSN=0
2020-02-08T21:37:39.622Z,1581197859.622 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-02-08T21:37:40.778Z,1581197860.778 [DataOverHttps](INFO): Moved sent file to Logs/20200208T212522/Express0008.lzma.bak
2020-02-08T21:37:40.778Z,1581197860.778 [DataOverHttps](INFO): SBD MOMSN=12237792
2020-02-08T21:37:42.853Z,1581197862.853 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-02-08T21:37:42.853Z,1581197862.853 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-02-08T21:37:42.853Z,1581197862.853 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-08T21:37:51.356Z,1581197871.356 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-08T21:37:51.356Z,1581197871.356 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020814505141,35.0, -0.1,   0.0,1448.RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-02-08T21:37:53.384Z,1581197873.384 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-02-08T21:38:28.064Z,1581197908.064 [NAL9602](INFO): SBD MO Status=0, MOMSN=10613, MT Status=0, MTMSN=0
2020-02-08T21:38:28.076Z,1581197908.076 [NAL9602](INFO): No messages in MT queue
2020-02-08T21:38:58.760Z,1581197938.760 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-08T21:41:18.652Z,1581198078.652 [CBIT](INFO): Clearing failed state for component ElevatorServo
2020-02-08T21:41:18.652Z,1581198078.652 [ElevatorServo] No Fault, FailCount= 3
2020-02-08T21:41:27.090Z,1581198087.090 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-02-08T21:42:06.718Z,1581198126.718 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-08T21:42:06.718Z,1581198126.718 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-02-08T21:42:43.476Z,1581198163.476 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-08T21:42:43.476Z,1581198163.476 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-02-08T21:42:43.476Z,1581198163.476 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-08T21:42:43.476Z,1581198163.476 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-02-08T21:42:43.869Z,1581198163.869 [DefaultWithUndock:CheckIn:D] Stopped
2020-02-08T21:42:43.869Z,1581198163.869 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-02-08T21:42:44.265Z,1581198164.265 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 14.714551 min 
2020-02-08T21:42:44.266Z,1581198164.266 [DefaultWithUndock:CheckIn:E] Stopped
2020-02-08T21:42:44.266Z,1581198164.266 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-02-08T21:42:44.266Z,1581198164.266 [DefaultWithUndock:CheckIn] Stopped
2020-02-08T21:42:44.266Z,1581198164.266 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-02-08T21:42:44.266Z,1581198164.266 [DefaultWithUndock:CheckIn](INFO): Running loop #3
2020-02-08T21:42:44.266Z,1581198164.266 [DefaultWithUndock:CheckIn] Running Loop=3
2020-02-08T21:42:44.266Z,1581198164.266 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-02-08T21:42:44.266Z,1581198164.266 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-02-08T21:42:46.271Z,1581198166.271 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214230.00,A,4450.22364,N,06733.87349,W,0.039,83.80,080220,,,D*4A
2020-02-08T21:42:46.273Z,1581198166.273 [NAL9602](INFO): GPS fix at 20200208T214230: (44.837061, -67.564558)
2020-02-08T21:42:46.297Z,1581198166.297 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-02-08T21:42:46.298Z,1581198166.298 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-02-08T21:42:53.972Z,1581198173.972 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200208T212522/Courier0010.lzma
2020-02-08T21:42:55.978Z,1581198175.978 [DataOverHttps](INFO): Moved sent file to Logs/20200208T212522/Courier0010.lzma.bak
2020-02-08T21:42:55.978Z,1581198175.978 [DataOverHttps](INFO): SBD MOMSN=12237803
2020-02-08T21:42:59.592Z,1581198179.592 [NAL9602](INFO): SBD MO Status=0, MOMSN=10614, MT Status=0, MTMSN=0
2020-02-08T21:42:59.592Z,1581198179.592 [NAL9602](INFO): No messages in MT queue
2020-02-08T21:43:13.737Z,1581198193.737 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200208T212522/Express0011.lzma
2020-02-08T21:43:15.742Z,1581198195.742 [DataOverHttps](INFO): Moved sent file to Logs/20200208T212522/Express0011.lzma.bak
2020-02-08T21:43:15.742Z,1581198195.742 [DataOverHttps](INFO): SBD MOMSN=12237806
2020-02-08T21:43:17.404Z,1581198197.404 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-02-08T21:43:17.404Z,1581198197.404 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-02-08T21:43:17.404Z,1581198197.404 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-08T21:43:30.304Z,1581198210.304 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-08T21:48:18.021Z,1581198498.021 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-08T21:48:18.021Z,1581198498.021 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-02-08T21:48:18.021Z,1581198498.021 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-08T21:48:18.021Z,1581198498.021 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-02-08T21:48:18.478Z,1581198498.478 [DefaultWithUndock:CheckIn:D] Stopped
2020-02-08T21:48:18.478Z,1581198498.478 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-02-08T21:48:18.805Z,1581198498.805 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 20.291357 min 
2020-02-08T21:48:18.805Z,1581198498.805 [DefaultWithUndock:CheckIn:E] Stopped
2020-02-08T21:48:18.805Z,1581198498.805 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-02-08T21:48:18.805Z,1581198498.805 [DefaultWithUndock:CheckIn] Stopped
2020-02-08T21:48:18.805Z,1581198498.805 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-02-08T21:48:18.806Z,1581198498.806 [DefaultWithUndock:CheckIn](INFO): Running loop #4
2020-02-08T21:48:18.806Z,1581198498.806 [DefaultWithUndock:CheckIn] Running Loop=4
2020-02-08T21:48:18.806Z,1581198498.806 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-02-08T21:48:18.806Z,1581198498.806 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-02-08T21:48:20.799Z,1581198500.799 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214805.00,A,4450.22629,N,06733.86572,W,0.175,57.23,080220,,,A*49
2020-02-08T21:48:20.801Z,1581198500.801 [NAL9602](INFO): GPS fix at 20200208T214805: (44.837105, -67.564429)
2020-02-08T21:48:20.832Z,1581198500.832 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-02-08T21:48:20.833Z,1581198500.833 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-02-08T21:48:28.548Z,1581198508.548 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200208T212522/Courier0013.lzma
2020-02-08T21:48:30.554Z,1581198510.554 [DataOverHttps](INFO): Moved sent file to Logs/20200208T212522/Courier0013.lzma.bak
2020-02-08T21:48:30.554Z,1581198510.554 [DataOverHttps](INFO): SBD MOMSN=12237811
2020-02-08T21:48:43.522Z,1581198523.522 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2020-02-08T21:48:43.523Z,1581198523.523 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00
2020-02-08T21:48:48.188Z,1581198528.188 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200208T212522/Express0014.lzma
2020-02-08T21:48:49.561Z,1581198529.561 [NAL9602](INFO): SBD MO Status=2, MOMSN=10615, MT Status=2, MTMSN=0
2020-02-08T21:48:49.561Z,1581198529.561 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-02-08T21:48:50.194Z,1581198530.194 [DataOverHttps](INFO): Moved sent file to Logs/20200208T212522/Express0014.lzma.bak
2020-02-08T21:48:50.195Z,1581198530.195 [DataOverHttps](INFO): SBD MOMSN=12237814
2020-02-08T21:48:52.018Z,1581198532.018 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-02-08T21:48:52.018Z,1581198532.018 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-02-08T21:48:52.018Z,1581198532.018 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-08T21:49:06.528Z,1581198546.528 [NAL9602](INFO): SBD MO Status=2, MOMSN=10615, MT Status=2, MTMSN=0
2020-02-08T21:49:06.538Z,1581198546.538 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-02-08T21:49:28.380Z,1581198568.380 [NAL9602](INFO): SBD MO Status=2, MOMSN=10615, MT Status=2, MTMSN=0
2020-02-08T21:49:28.381Z,1581198568.381 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-02-08T21:50:18.438Z,1581198618.438 [NAL9602](INFO): SBD MO Status=0, MOMSN=10615, MT Status=0, MTMSN=0
2020-02-08T21:50:18.438Z,1581198618.438 [NAL9602](INFO): No messages in MT queue
2020-02-08T21:50:27.693Z,1581198627.693 [CommandLine](IMPORTANT): got command failComponent hardware DDM
2020-02-08T21:50:27.694Z,1581198627.694 [DDM] Hardware Fault, FailCount= 1
2020-02-08T21:50:27.694Z,1581198627.694 [DDM](ERROR): Hardware Fault
2020-02-08T21:50:27.695Z,1581198627.695 [CommandLine](IMPORTANT): DDM failureMode is Hardware Fault
2020-02-08T21:50:27.739Z,1581198627.739 [DDM](INFO): Powering down
2020-02-08T21:50:27.858Z,1581198627.858 [CBIT](ERROR): Hardware Fault in component: DDM
2020-02-08T21:50:28.597Z,1581198628.597 [CBIT](INFO): Clearing failed state for component DDM
2020-02-08T21:50:28.597Z,1581198628.597 [DDM] No Fault, FailCount= 1
2020-02-08T21:50:32.980Z,1581198632.980 [DDM](INFO): Powering up
2020-02-08T21:50:32.980Z,1581198632.980 [DDM](DEBUG): Initializing DDM.
2020-02-08T21:50:48.737Z,1581198648.737 [DDM](INFO): Dynamic Docking Module:!C REMUS Capture Rev 1.1
2020-02-08T21:50:48.871Z,1581198648.871 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-08T21:50:56.401Z,1581198656.401 [CommandLine](IMPORTANT): got command show variable whisker
2020-02-08T21:50:56.525Z,1581198656.525 [CommandLine](IMPORTANT): DDM.whiskerState (count)
2020-02-08T21:51:01.641Z,1581198661.641 [CommandLine](IMPORTANT): got command get 
2020-02-08T21:51:01.641Z,1581198661.641 [CommandLine](FAULT): Incomplete syntax. Try: help get
2020-02-08T21:51:07.765Z,1581198667.765 [CommandLine](IMPORTANT): got command get DDM.whiskerState
2020-02-08T21:51:07.766Z,1581198667.766 [CommandLine](IMPORTANT): DDM.whiskerState 2 count
2020-02-08T21:53:52.604Z,1581198832.604 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-08T21:53:52.604Z,1581198832.604 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-02-08T21:53:52.604Z,1581198832.604 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-08T21:53:52.604Z,1581198832.604 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-02-08T21:53:53.006Z,1581198833.006 [DefaultWithUndock:CheckIn:D] Stopped
2020-02-08T21:53:53.006Z,1581198833.006 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-02-08T21:53:53.433Z,1581198833.433 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 25.866829 min 
2020-02-08T21:53:53.433Z,1581198833.433 [DefaultWithUndock:CheckIn:E] Stopped
2020-02-08T21:53:53.433Z,1581198833.433 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-02-08T21:53:53.433Z,1581198833.433 [DefaultWithUndock:CheckIn] Stopped
2020-02-08T21:53:53.433Z,1581198833.433 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-02-08T21:53:53.433Z,1581198833.433 [DefaultWithUndock:CheckIn](INFO): Running loop #5
2020-02-08T21:53:53.434Z,1581198833.434 [DefaultWithUndock:CheckIn] Running Loop=5
2020-02-08T21:53:53.434Z,1581198833.434 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-02-08T21:53:53.434Z,1581198833.434 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-02-08T21:53:55.398Z,1581198835.398 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215340.00,A,4450.22699,N,06733.86708,W,0.019,57.23,080220,,,D*48
2020-02-08T21:53:55.400Z,1581198835.400 [NAL9602](INFO): GPS fix at 20200208T215340: (44.837117, -67.564451)
2020-02-08T21:53:55.424Z,1581198835.424 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-02-08T21:53:55.424Z,1581198835.424 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-02-08T21:54:03.528Z,1581198843.528 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200208T212522/Courier0016.lzma
2020-02-08T21:54:05.534Z,1581198845.534 [DataOverHttps](INFO): Moved sent file to Logs/20200208T212522/Courier0016.lzma.bak
2020-02-08T21:54:05.535Z,1581198845.535 [DataOverHttps](INFO): SBD MOMSN=12237826
2020-02-08T21:54:10.749Z,1581198850.749 [NAL9602](INFO): SBD MO Status=0, MOMSN=10616, MT Status=0, MTMSN=0
2020-02-08T21:54:10.749Z,1581198850.749 [NAL9602](INFO): No messages in MT queue
2020-02-08T21:54:26.257Z,1581198866.257 [DataOverHttps](INFO): Sending 297 bytes from file Logs/20200208T212522/Express0017.lzma
2020-02-08T21:54:28.262Z,1581198868.262 [DataOverHttps](INFO): Moved sent file to Logs/20200208T212522/Express0017.lzma.bak
2020-02-08T21:54:28.262Z,1581198868.262 [DataOverHttps](INFO): SBD MOMSN=12237829
2020-02-08T21:54:30.195Z,1581198870.195 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-02-08T21:54:30.195Z,1581198870.195 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-02-08T21:54:30.196Z,1581198870.196 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-08T21:54:41.464Z,1581198881.464 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-08T21:56:05.548Z,1581198965.548 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-08T21:56:05.548Z,1581198965.548 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-32768-32768,-32768,-32768,V
2020-02-08T21:58:45.555Z,1581199125.555 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-02-08T21:58:45.556Z,1581199125.556 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.   +0.00,        +0.00,   0.00,1976.84
2020-02-08T21:59:30.774Z,1581199170.774 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-08T21:59:30.774Z,1581199170.774 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-02-08T21:59:30.774Z,1581199170.774 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-08T21:59:30.775Z,1581199170.775 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-02-08T21:59:31.184Z,1581199171.184 [DefaultWithUndock:CheckIn:D] Stopped
2020-02-08T21:59:31.185Z,1581199171.185 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-02-08T21:59:31.605Z,1581199171.605 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 31.503135 min 
2020-02-08T21:59:31.605Z,1581199171.605 [DefaultWithUndock:CheckIn:E] Stopped
2020-02-08T21:59:31.605Z,1581199171.605 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-02-08T21:59:31.605Z,1581199171.605 [DefaultWithUndock:CheckIn] Stopped
2020-02-08T21:59:31.605Z,1581199171.605 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-02-08T21:59:31.606Z,1581199171.606 [DefaultWithUndock:CheckIn](INFO): Running loop #6
2020-02-08T21:59:31.606Z,1581199171.606 [DefaultWithUndock:CheckIn] Running Loop=6
2020-02-08T21:59:31.606Z,1581199171.606 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-02-08T21:59:31.606Z,1581199171.606 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-02-08T21:59:33.585Z,1581199173.585 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215918.00,A,4450.22520,N,06733.87161,W,0.233,57.23,080220,,,D*4C
2020-02-08T21:59:33.587Z,1581199173.587 [NAL9602](INFO): GPS fix at 20200208T215918: (44.837087, -67.564527)
2020-02-08T21:59:33.644Z,1581199173.644 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-02-08T21:59:33.644Z,1581199173.644 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-02-08T21:59:41.680Z,1581199181.680 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200208T212522/Courier0019.lzma
2020-02-08T21:59:43.686Z,1581199183.686 [DataOverHttps](INFO): Moved sent file to Logs/20200208T212522/Courier0019.lzma.bak
2020-02-08T21:59:43.686Z,1581199183.686 [DataOverHttps](INFO): SBD MOMSN=12237846
2020-02-08T22:00:01.084Z,1581199201.084 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200208T212522/Express0020.lzma
2020-02-08T22:00:03.091Z,1581199203.091 [DataOverHttps](INFO): Moved sent file to Logs/20200208T212522/Express0020.lzma.bak
2020-02-08T22:00:03.091Z,1581199203.091 [DataOverHttps](INFO): SBD MOMSN=12237853
2020-02-08T22:00:04.745Z,1581199204.745 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-02-08T22:00:04.745Z,1581199204.745 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-02-08T22:00:04.746Z,1581199204.746 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-08T22:00:08.391Z,1581199208.391 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-02-08T22:00:16.404Z,1581199216.404 [NAL9602](INFO): SBD MO Status=0, MOMSN=10617, MT Status=0, MTMSN=0
2020-02-08T22:00:16.404Z,1581199216.404 [NAL9602](INFO): No messages in MT queue
2020-02-08T22:00:47.107Z,1581199247.107 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-08T22:03:25.902Z,1581199405.902 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-08T22:03:25.902Z,1581199405.902 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-02-08T22:05:05.328Z,1581199505.328 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-08T22:05:05.328Z,1581199505.328 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-02-08T22:05:05.328Z,1581199505.328 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-08T22:05:05.328Z,1581199505.328 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-02-08T22:05:05.742Z,1581199505.742 [DefaultWithUndock:CheckIn:D] Stopped
2020-02-08T22:05:05.742Z,1581199505.742 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-02-08T22:05:06.146Z,1581199506.146 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 37.079097 min 
2020-02-08T22:05:06.146Z,1581199506.146 [DefaultWithUndock:CheckIn:E] Stopped
2020-02-08T22:05:06.147Z,1581199506.147 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-02-08T22:05:06.147Z,1581199506.147 [DefaultWithUndock:CheckIn] Stopped
2020-02-08T22:05:06.147Z,1581199506.147 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-02-08T22:05:06.147Z,1581199506.147 [DefaultWithUndock:CheckIn](INFO): Running loop #7
2020-02-08T22:05:06.147Z,1581199506.147 [DefaultWithUndock:CheckIn] Running Loop=7
2020-02-08T22:05:06.147Z,1581199506.147 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-02-08T22:05:06.147Z,1581199506.147 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-02-08T22:05:08.129Z,1581199508.129 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220452.00,A,4450.23652,N,06733.87145,W,0.156,57.23,080220,,,A*4D
2020-02-08T22:05:08.131Z,1581199508.131 [NAL9602](INFO): GPS fix at 20200208T220452: (44.837275, -67.564524)
2020-02-08T22:05:08.155Z,1581199508.155 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-02-08T22:05:08.155Z,1581199508.155 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-02-08T22:05:15.844Z,1581199515.844 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200208T212522/Courier0022.lzma
2020-02-08T22:05:17.850Z,1581199517.850 [DataOverHttps](INFO): Moved sent file to Logs/20200208T212522/Courier0022.lzma.bak
2020-02-08T22:05:17.851Z,1581199517.851 [DataOverHttps](INFO): SBD MOMSN=12237858
2020-02-08T22:05:35.404Z,1581199535.404 [DataOverHttps](INFO): Sending 117 bytes from file Logs/20200208T212522/Express0023.lzma
2020-02-08T22:05:37.410Z,1581199537.410 [DataOverHttps](INFO): Moved sent file to Logs/20200208T212522/Express0023.lzma.bak
2020-02-08T22:05:37.410Z,1581199537.410 [DataOverHttps](INFO): SBD MOMSN=12237861
2020-02-08T22:05:39.272Z,1581199539.272 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-02-08T22:05:39.272Z,1581199539.272 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-02-08T22:05:39.273Z,1581199539.273 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-08T22:06:14.802Z,1581199574.802 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-08T22:06:14.802Z,1581199574.802 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-02-08T22:07:41.729Z,1581199661.729 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-08T22:07:41.729Z,1581199661.729 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020815204141,35.0, -0.1,   .9,  0
2020-02-08T22:08:03.542Z,1581199683.542 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2020-02-08T22:08:03.542Z,1581199683.542 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,  

2020-02-08T22:08:07.447Z,1581199687.447 [CommandLine](IMPORTANT): got command get latitude
2020-02-08T22:08:07.448Z,1581199687.448 [CommandLine](IMPORTANT): latitude 44.837275 arcdeg
2020-02-08T22:08:17.723Z,1581199697.723 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-08T22:08:17.723Z,1581199697.723 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-32768-32768,-32768,-32768,V
2020-02-08T22:08:21.733Z,1581199701.733 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-08T22:08:21.734Z,1581199701.734 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020815212141,35.0, -0.1,   0.0,1448:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-02-08T22:08:42.696Z,1581199722.696 [NAL9602](INFO): SBD MO Status=0, MOMSN=10618, MT Status=0, MTMSN=0
2020-02-08T22:08:42.696Z,1581199722.696 [NAL9602](INFO): No messages in MT queue
2020-02-08T22:08:42.730Z,1581199722.730 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-02-08T22:09:13.403Z,1581199753.403 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-08T22:10:39.898Z,1581199839.898 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-08T22:10:39.898Z,1581199839.898 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-02-08T22:10:39.898Z,1581199839.898 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-08T22:10:39.898Z,1581199839.898 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-02-08T22:10:40.289Z,1581199840.289 [DefaultWithUndock:CheckIn:D] Stopped
2020-02-08T22:10:40.289Z,1581199840.289 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-02-08T22:10:40.706Z,1581199840.706 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 42.654867 min 
2020-02-08T22:10:40.706Z,1581199840.706 [DefaultWithUndock:CheckIn:E] Stopped
2020-02-08T22:10:40.706Z,1581199840.706 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-02-08T22:10:40.707Z,1581199840.707 [DefaultWithUndock:CheckIn] Stopped
2020-02-08T22:10:40.707Z,1581199840.707 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-02-08T22:10:40.707Z,1581199840.707 [DefaultWithUndock:CheckIn](INFO): Running loop #8
2020-02-08T22:10:40.707Z,1581199840.707 [DefaultWithUndock:CheckIn] Running Loop=8
2020-02-08T22:10:40.707Z,1581199840.707 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-02-08T22:10:40.707Z,1581199840.707 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-02-08T22:10:42.683Z,1581199842.683 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221027.00,A,4450.23049,N,06733.86692,W,0.097,52.50,080220,,,A*47
2020-02-08T22:10:42.685Z,1581199842.685 [NAL9602](INFO): GPS fix at 20200208T221027: (44.837175, -67.564449)
2020-02-08T22:10:42.735Z,1581199842.735 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-02-08T22:10:42.735Z,1581199842.735 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-02-08T22:10:50.640Z,1581199850.640 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200208T212522/Courier0025.lzma
2020-02-08T22:10:52.646Z,1581199852.646 [DataOverHttps](INFO): Moved sent file to Logs/20200208T212522/Courier0025.lzma.bak
2020-02-08T22:10:52.646Z,1581199852.646 [DataOverHttps](INFO): SBD MOMSN=12237872
2020-02-08T22:11:10.504Z,1581199870.504 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20200208T212522/Express0026.lzma
2020-02-08T22:11:12.511Z,1581199872.511 [DataOverHttps](INFO): Moved sent file to Logs/20200208T212522/Express0026.lzma.bak
2020-02-08T22:11:12.511Z,1581199872.511 [DataOverHttps](INFO): SBD MOMSN=12237875
2020-02-08T22:11:14.224Z,1581199874.224 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-02-08T22:11:14.224Z,1581199874.224 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-02-08T22:11:14.241Z,1581199874.241 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-08T22:11:15.408Z,1581199875.408 [NAL9602](INFO): SBD MO Status=2, MOMSN=10619, MT Status=2, MTMSN=0
2020-02-08T22:11:15.408Z,1581199875.408 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-02-08T22:11:37.228Z,1581199897.228 [NAL9602](INFO): SBD MO Status=0, MOMSN=10619, MT Status=0, MTMSN=0
2020-02-08T22:11:37.228Z,1581199897.228 [NAL9602](INFO): No messages in MT queue
2020-02-08T22:12:06.763Z,1581199926.763 [RDI_Pathfinder](ERROR): Failed to parse:
:B  +0.00,        +0.00,        +0.00,   0.00,2776.66
2020-02-08T22:12:07.931Z,1581199927.931 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-08T22:12:54.814Z,1581199974.814 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2020-02-08T22:14:54.437Z,1581200094.437 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-08T22:14:54.437Z,1581200094.437 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-02-08T22:15:34.826Z,1581200134.826 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-08T22:15:34.827Z,1581200134.827 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200208152.0, -0.1,   0.0,1448.9,  0
2020-02-08T22:16:14.813Z,1581200174.813 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-08T22:16:14.813Z,1581200174.813 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-02-08T22:16:14.813Z,1581200174.813 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-08T22:16:14.813Z,1581200174.813 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-02-08T22:16:15.223Z,1581200175.223 [DefaultWithUndock:CheckIn:D] Stopped
2020-02-08T22:16:15.223Z,1581200175.223 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-02-08T22:16:15.633Z,1581200175.633 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 48.237113 min 
2020-02-08T22:16:15.633Z,1581200175.633 [DefaultWithUndock:CheckIn:E] Stopped
2020-02-08T22:16:15.633Z,1581200175.633 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-02-08T22:16:15.633Z,1581200175.633 [DefaultWithUndock:CheckIn] Stopped
2020-02-08T22:16:15.633Z,1581200175.633 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-02-08T22:16:15.633Z,1581200175.633 [DefaultWithUndock:CheckIn](INFO): Running loop #9
2020-02-08T22:16:15.633Z,1581200175.633 [DefaultWithUndock:CheckIn] Running Loop=9
2020-02-08T22:16:15.633Z,1581200175.633 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-02-08T22:16:15.634Z,1581200175.634 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-02-08T22:16:17.608Z,1581200177.608 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221602.00,A,4450.22565,N,06733.87286,W,0.194,40.21,080220,,,A*4B
2020-02-08T22:16:17.627Z,1581200177.627 [NAL9602](INFO): GPS fix at 20200208T221602: (44.837094, -67.564548)
2020-02-08T22:16:17.738Z,1581200177.738 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-02-08T22:16:17.738Z,1581200177.738 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-02-08T22:16:33.176Z,1581200193.176 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200208T212522/Courier0028.lzma
2020-02-08T22:16:35.182Z,1581200195.182 [DataOverHttps](INFO): Moved sent file to Logs/20200208T212522/Courier0028.lzma.bak
2020-02-08T22:16:35.182Z,1581200195.182 [DataOverHttps](INFO): SBD MOMSN=12237883
2020-02-08T22:16:52.700Z,1581200212.700 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200208T212522/Express0029.lzma
2020-02-08T22:16:54.706Z,1581200214.706 [DataOverHttps](INFO): Moved sent file to Logs/20200208T212522/Express0029.lzma.bak
2020-02-08T22:16:54.706Z,1581200214.706 [DataOverHttps](INFO): SBD MOMSN=12237886
2020-02-08T22:16:56.294Z,1581200216.294 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-02-08T22:16:56.294Z,1581200216.294 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-02-08T22:16:56.294Z,1581200216.294 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-08T22:17:37.872Z,1581200257.872 [NAL9602](INFO): SBD MO Status=2, MOMSN=10620, MT Status=2, MTMSN=0
2020-02-08T22:17:37.873Z,1581200257.873 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-02-08T22:19:51.602Z,1581200391.602 [NAL9602](INFO): SBD MO Status=0, MOMSN=10620, MT Status=0, MTMSN=0
2020-02-08T22:19:51.602Z,1581200391.602 [NAL9602](INFO): No messages in MT queue
2020-02-08T22:20:22.303Z,1581200422.303 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-08T22:21:56.882Z,1581200516.882 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-08T22:21:56.882Z,1581200516.882 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-02-08T22:21:56.882Z,1581200516.882 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-08T22:21:56.882Z,1581200516.882 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-02-08T22:21:57.286Z,1581200517.286 [DefaultWithUndock:CheckIn:D] Stopped
2020-02-08T22:21:57.286Z,1581200517.286 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-02-08T22:21:57.678Z,1581200517.678 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 53.938155 min 
2020-02-08T22:21:57.678Z,1581200517.678 [DefaultWithUndock:CheckIn:E] Stopped
2020-02-08T22:21:57.678Z,1581200517.678 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-02-08T22:21:57.678Z,1581200517.678 [DefaultWithUndock:CheckIn] Stopped
2020-02-08T22:21:57.678Z,1581200517.678 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-02-08T22:21:57.679Z,1581200517.679 [DefaultWithUndock:CheckIn](INFO): Running loop #10
2020-02-08T22:21:57.679Z,1581200517.679 [DefaultWithUndock:CheckIn] Running Loop=10
2020-02-08T22:21:57.679Z,1581200517.679 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-02-08T22:21:57.679Z,1581200517.679 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-02-08T22:21:59.678Z,1581200519.678 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222144.00,A,4450.22419,N,06733.87811,W,0.117,40.21,080220,,,A*48
2020-02-08T22:21:59.680Z,1581200519.680 [NAL9602](INFO): GPS fix at 20200208T222144: (44.837070, -67.564635)
2020-02-08T22:21:59.714Z,1581200519.714 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-02-08T22:21:59.714Z,1581200519.714 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-02-08T22:22:07.520Z,1581200527.520 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200208T212522/Courier0031.lzma
2020-02-08T22:22:09.539Z,1581200529.539 [DataOverHttps](INFO): Moved sent file to Logs/20200208T212522/Courier0031.lzma.bak
2020-02-08T22:22:09.539Z,1581200529.539 [DataOverHttps](INFO): SBD MOMSN=12237897
2020-02-08T22:22:27.904Z,1581200547.904 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20200208T212522/Express0032.lzma
2020-02-08T22:22:29.910Z,1581200549.910 [DataOverHttps](INFO): Moved sent file to Logs/20200208T212522/Express0032.lzma.bak
2020-02-08T22:22:29.910Z,1581200549.910 [DataOverHttps](INFO): SBD MOMSN=12237900
2020-02-08T22:22:31.627Z,1581200551.627 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-02-08T22:22:31.627Z,1581200551.627 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-02-08T22:22:31.628Z,1581200551.628 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-08T22:23:08.399Z,1581200588.399 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-02-08T22:23:08.400Z,1581200588.400 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0    +0.00,        +0.00,   0.00,3439.49
2020-02-08T22:23:09.964Z,1581200589.964 [NAL9602](INFO): SBD MO Status=2, MOMSN=10621, MT Status=2, MTMSN=0
2020-02-08T22:23:09.964Z,1581200589.964 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-02-08T22:24:28.388Z,1581200668.388 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-08T22:24:28.389Z,1581200668.389 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-02-08T22:25:14.446Z,1581200714.446 [RDI_Pathfinder](ERROR): Failed to parse:
68,-32768,-32768,V
2020-02-08T22:25:29.412Z,1581200729.412 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-02-08T22:25:29.412Z,1581200729.412 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,     0.46
2020-02-08T22:26:25.548Z,1581200785.548 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-08T22:26:25.548Z,1581200785.548 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-3278,-32768,V
2020-02-08T22:27:02.266Z,1581200822.266 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-02-08T22:27:32.183Z,1581200852.183 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-08T22:27:32.183Z,1581200852.183 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-02-08T22:27:32.183Z,1581200852.183 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-08T22:27:32.183Z,1581200852.183 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-02-08T22:27:32.614Z,1581200852.614 [DefaultWithUndock:CheckIn:D] Stopped
2020-02-08T22:27:32.614Z,1581200852.614 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-02-08T22:27:32.971Z,1581200852.971 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-08T22:27:33.025Z,1581200853.025 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 59.526953 min 
2020-02-08T22:27:33.025Z,1581200853.025 [DefaultWithUndock:CheckIn:E] Stopped
2020-02-08T22:27:33.025Z,1581200853.025 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-02-08T22:27:33.025Z,1581200853.025 [DefaultWithUndock:CheckIn] Stopped
2020-02-08T22:27:33.025Z,1581200853.025 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-02-08T22:27:33.026Z,1581200853.026 [DefaultWithUndock:CheckIn](INFO): Running loop #11
2020-02-08T22:27:33.026Z,1581200853.026 [DefaultWithUndock:CheckIn] Running Loop=11
2020-02-08T22:27:33.026Z,1581200853.026 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-02-08T22:27:33.026Z,1581200853.026 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-02-08T22:27:34.995Z,1581200854.995 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222719.00,A,4450.22913,N,06733.86342,W,0.058,345.70,080220,,,A*75
2020-02-08T22:27:34.998Z,1581200854.998 [NAL9602](INFO): GPS fix at 20200208T222719: (44.837152, -67.564390)
2020-02-08T22:27:35.044Z,1581200855.044 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-02-08T22:27:35.044Z,1581200855.044 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-02-08T22:27:42.964Z,1581200862.964 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200208T212522/Courier0034.lzma
2020-02-08T22:27:44.973Z,1581200864.973 [DataOverHttps](INFO): Moved sent file to Logs/20200208T212522/Courier0034.lzma.bak
2020-02-08T22:27:44.973Z,1581200864.973 [DataOverHttps](INFO): SBD MOMSN=12237903
2020-02-08T22:27:58.657Z,1581200878.657 [CommandLine](IMPORTANT): got command restart application
2020-02-08T22:27:59.661Z,1581200879.661 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-08T22:27:59.661Z,1581200879.661 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-08T22:27:59.741Z,1581200879.741 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-02-08T22:27:59.742Z,1581200879.742 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-08T22:27:59.744Z,1581200879.744 [CommandLine](INFO): Join timeout helper Thread ID is 1231
2020-02-08T22:27:59.753Z,1581200879.753 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-02-08T22:27:59.753Z,1581200879.753 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-08T22:27:59.753Z,1581200879.753 [NavChartDb](INFO): Join timeout helper Thread ID is 1232
2020-02-08T22:27:59.785Z,1581200879.785 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-08T22:27:59.785Z,1581200879.785 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-08T22:27:59.790Z,1581200879.790 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-02-08T22:27:59.790Z,1581200879.790 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-02-08T22:27:59.790Z,1581200879.790 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1233
2020-02-08T22:27:59.829Z,1581200879.829 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-08T22:27:59.829Z,1581200879.829 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-02-08T22:27:59.830Z,1581200879.830 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-02-08T22:27:59.841Z,1581200879.841 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-02-08T22:27:59.841Z,1581200879.841 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-08T22:27:59.841Z,1581200879.841 [Radio_Surface](INFO): Join timeout helper Thread ID is 1234
2020-02-08T22:28:00.193Z,1581200880.193 [Radio_Surface](INFO): Powering down
2020-02-08T22:28:00.194Z,1581200880.194 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-08T22:28:00.194Z,1581200880.194 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-08T22:28:00.197Z,1581200880.197 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-02-08T22:28:00.197Z,1581200880.197 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-08T22:28:00.197Z,1581200880.197 [DataOverHttps](INFO): Join timeout helper Thread ID is 1235
2020-02-08T22:28:01.832Z,1581200881.832 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-08T22:28:01.836Z,1581200881.836 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-08T22:28:01.850Z,1581200881.850 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-02-08T22:28:01.850Z,1581200881.850 [logger ThreadHandler](INFO): Thread cancelled.
2020-02-08T22:28:01.850Z,1581200881.850 [logger](INFO): Join timeout helper Thread ID is 1236
2020-02-08T22:28:01.853Z,1581200881.853 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-08T22:28:01.853Z,1581200881.853 [logger ThreadHandler](INFO): Thread cancelled.
2020-02-08T22:28:01.869Z,1581200881.869 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-02-08T22:28:01.870Z,1581200881.870 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-08T22:28:01.870Z,1581200881.870 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-02-08T22:28:01.870Z,1581200881.870 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-02-08T22:28:01.870Z,1581200881.870 [controlThread](INFO): Join timeout helper Thread ID is 1237
2020-02-08T22:28:02.037Z,1581200882.037 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-08T22:28:02.037Z,1581200882.037 [controlThread](DEBUG): Uninitializing ControlThread
2020-02-08T22:28:02.038Z,1581200882.038 [AHRS_M2](INFO): Powering down
2020-02-08T22:28:02.109Z,1581200882.109 [DDM](INFO): Powering down
2020-02-08T22:28:02.181Z,1581200882.181 [DUSBL_Hydroid](INFO): Powering down
2020-02-08T22:28:02.273Z,1581200882.273 [Micromodem](INFO): Powering down
2020-02-08T22:28:02.369Z,1581200882.369 [NAL9602](INFO): Powering down
2020-02-08T22:28:02.441Z,1581200882.441 [RDI_Pathfinder](INFO): Powering down
2020-02-08T22:28:02.442Z,1581200882.442 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-02-08T22:28:02.443Z,1581200882.443 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-02-08T22:28:02.444Z,1581200882.444 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-02-08T22:28:02.444Z,1581200882.444 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock
2020-02-08T22:28:02.444Z,1581200882.444 [DefaultWithUndock] Stopped
2020-02-08T22:28:02.445Z,1581200882.445 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2020-02-08T22:28:02.445Z,1581200882.445 [DefaultWithUndock:C.GoToSurface] Stopped
2020-02-08T22:28:02.445Z,1581200882.445 [DefaultWithUndock:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-02-08T22:28:02.445Z,1581200882.445 [DefaultWithUndock:CheckIn] Stopped
2020-02-08T22:28:02.445Z,1581200882.445 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-02-08T22:28:02.445Z,1581200882.445 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-02-08T22:28:02.448Z,1581200882.448 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-02-08T22:28:02.448Z,1581200882.448 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-02-08T22:28:02.448Z,1581200882.448 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-02-08T22:28:02.449Z,1581200882.449 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-02-08T22:28:02.449Z,1581200882.449 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-02-08T22:28:02.449Z,1581200882.449 [BuoyancyServo](INFO): Powering down
2020-02-08T22:28:02.461Z,1581200882.461 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-02-08T22:28:02.461Z,1581200882.461 [ElevatorServo](INFO): Powering down
2020-02-08T22:28:02.462Z,1581200882.462 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-02-08T22:28:02.462Z,1581200882.462 [MassServo](INFO): Powering down
2020-02-08T22:28:02.463Z,1581200882.463 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-02-08T22:28:02.463Z,1581200882.463 [RudderServo](INFO): Powering down
2020-02-08T22:28:02.463Z,1581200882.463 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-02-08T22:28:02.463Z,1581200882.463 [ThrusterServo](INFO): Powering down
2020-02-08T22:28:02.464Z,1581200882.464 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-02-08T22:28:02.464Z,1581200882.464 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-02-08T22:28:02.465Z,1581200882.465 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-02-08T22:28:02.465Z,1581200882.465 [CBIT](DEBUG): Powering off loads.
2020-02-08T22:28:02.477Z,1581200882.477 [CBIT](DEBUG): Disabling WDT.
2020-02-08T22:28:02.489Z,1581200882.489 [CBIT](DEBUG): Opening all GF detection circuits.
2020-02-08T22:28:02.490Z,1581200882.490 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-02-08T22:28:02.546Z,1581200882.546 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-08T22:28:02.556Z,1581200882.556 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-08T22:28:02.599Z,1581200882.599 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-02-08T22:28:02.656Z,1581200882.656 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-08T22:28:02.722Z,1581200882.722 [logger ThreadHandler](INFO): Thread cancelled.