2020-02-08T22:28:03.709Z,1581200883.709 [Supervisor](DEBUG): Initializing supervisor.
2020-02-08T22:28:03.711Z,1581200883.711 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-02-08T22:28:03.712Z,1581200883.712 [SyncHandler](INFO): Protected caller Thread ID is 1238
2020-02-08T22:28:03.712Z,1581200883.712 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-02-08T22:28:03.713Z,1581200883.713 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-02-08T22:28:03.714Z,1581200883.714 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1239
2020-02-08T22:28:03.716Z,1581200883.716 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-02-08T22:28:03.728Z,1581200883.728 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-02-08T22:28:03.729Z,1581200883.729 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-02-08T22:28:03.729Z,1581200883.729 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1240
2020-02-08T22:28:03.730Z,1581200883.730 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-02-08T22:28:03.731Z,1581200883.731 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-02-08T22:28:03.731Z,1581200883.731 [logger ThreadHandler](INFO): Protected caller Thread ID is 1241
2020-02-08T22:28:03.733Z,1581200883.733 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-02-08T22:28:03.734Z,1581200883.734 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-02-08T22:28:03.735Z,1581200883.735 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-02-08T22:28:04.168Z,1581200884.168 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-02-08T22:28:04.168Z,1581200884.168 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-02-08T22:28:04.266Z,1581200884.266 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-02-08T22:28:04.266Z,1581200884.266 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-02-08T22:28:04.579Z,1581200884.579 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-02-08T22:28:04.580Z,1581200884.580 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-02-08T22:28:04.721Z,1581200884.721 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-02-08T22:28:04.722Z,1581200884.722 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-02-08T22:28:04.955Z,1581200884.955 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-02-08T22:28:04.956Z,1581200884.956 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-02-08T22:28:05.725Z,1581200885.725 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-02-08T22:28:05.725Z,1581200885.725 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-02-08T22:28:05.939Z,1581200885.939 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-02-08T22:28:05.940Z,1581200885.940 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-02-08T22:28:06.085Z,1581200886.085 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-02-08T22:28:06.086Z,1581200886.086 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-02-08T22:28:06.275Z,1581200886.275 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-02-08T22:28:06.276Z,1581200886.276 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-02-08T22:28:06.372Z,1581200886.372 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-02-08T22:28:06.373Z,1581200886.373 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-02-08T22:28:06.670Z,1581200886.670 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-02-08T22:28:06.670Z,1581200886.670 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-02-08T22:28:06.751Z,1581200886.751 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-02-08T22:28:06.853Z,1581200886.853 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-02-08T22:28:06.854Z,1581200886.854 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-02-08T22:28:07.487Z,1581200887.487 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-02-08T22:28:07.487Z,1581200887.487 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-02-08T22:28:07.893Z,1581200887.893 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-02-08T22:28:07.895Z,1581200887.895 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-02-08T22:28:07.896Z,1581200887.896 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-02-08T22:28:08.104Z,1581200888.104 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-02-08T22:28:08.204Z,1581200888.204 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-02-08T22:28:08.303Z,1581200888.303 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-02-08T22:28:08.527Z,1581200888.527 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-02-08T22:28:08.527Z,1581200888.527 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-02-08T22:28:08.612Z,1581200888.612 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-02-08T22:28:08.706Z,1581200888.706 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-02-08T22:28:08.803Z,1581200888.803 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-02-08T22:28:08.886Z,1581200888.886 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-02-08T22:28:08.994Z,1581200888.994 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/workSite.cfg
2020-02-08T22:28:09.088Z,1581200889.088 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-02-08T22:28:09.272Z,1581200889.272 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-02-08T22:28:09.403Z,1581200889.403 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2020-02-08T22:28:09.404Z,1581200889.404 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-02-08T22:28:09.411Z,1581200889.411 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-02-08T22:28:09.708Z,1581200889.708 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-02-08T22:28:09.713Z,1581200889.713 [AHRS_M2](INFO): created writer for : platform_orientation
2020-02-08T22:28:09.715Z,1581200889.715 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-02-08T22:28:09.719Z,1581200889.719 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-02-08T22:28:09.720Z,1581200889.720 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-02-08T22:28:09.725Z,1581200889.725 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-02-08T22:28:09.725Z,1581200889.725 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-02-08T22:28:09.730Z,1581200889.730 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-02-08T22:28:09.798Z,1581200889.798 [AHRS_M2] Loaded
2020-02-08T22:28:09.799Z,1581200889.799 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-02-08T22:28:09.877Z,1581200889.877 [DataOverHttps] Loaded
2020-02-08T22:28:09.878Z,1581200889.878 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-02-08T22:28:09.879Z,1581200889.879 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407684E0
2020-02-08T22:28:09.879Z,1581200889.879 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1323
2020-02-08T22:28:09.902Z,1581200889.902 [DDM] Loaded
2020-02-08T22:28:09.902Z,1581200889.902 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-02-08T22:28:09.915Z,1581200889.915 [Depth_Keller] Loaded
2020-02-08T22:28:09.915Z,1581200889.915 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-02-08T22:28:09.919Z,1581200889.919 [DropWeight] Loaded
2020-02-08T22:28:09.920Z,1581200889.920 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-02-08T22:28:09.979Z,1581200889.979 [DUSBL_Hydroid] Loaded
2020-02-08T22:28:09.979Z,1581200889.979 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-02-08T22:28:10.023Z,1581200890.023 [Micromodem] Loaded
2020-02-08T22:28:10.024Z,1581200890.024 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-02-08T22:28:10.115Z,1581200890.115 [NAL9602] Loaded
2020-02-08T22:28:10.115Z,1581200890.115 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-02-08T22:28:10.153Z,1581200890.153 [Onboard] Loaded
2020-02-08T22:28:10.153Z,1581200890.153 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-02-08T22:28:10.159Z,1581200890.159 [PowerOnly] Loaded
2020-02-08T22:28:10.159Z,1581200890.159 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-02-08T22:28:10.166Z,1581200890.166 [Radio_Surface] Loaded
2020-02-08T22:28:10.166Z,1581200890.166 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-02-08T22:28:10.167Z,1581200890.167 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407984E0
2020-02-08T22:28:10.167Z,1581200890.167 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1324
2020-02-08T22:28:10.208Z,1581200890.208 [RDI_Pathfinder] Loaded
2020-02-08T22:28:10.209Z,1581200890.209 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-02-08T22:28:12.473Z,1581200892.473 [BPC1] Loaded
2020-02-08T22:28:12.473Z,1581200892.473 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-02-08T22:28:12.474Z,1581200892.474 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-02-08T22:28:12.474Z,1581200892.474 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-02-08T22:28:12.487Z,1581200892.487 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-02-08T22:28:12.487Z,1581200892.487 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-02-08T22:28:12.600Z,1581200892.600 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-02-08T22:28:12.600Z,1581200892.600 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-02-08T22:28:12.620Z,1581200892.620 [NavChart] Loaded
2020-02-08T22:28:12.620Z,1581200892.620 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-02-08T22:28:12.624Z,1581200892.624 [UniversalFixResidualReporter] Loaded
2020-02-08T22:28:12.625Z,1581200892.625 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-02-08T22:28:12.638Z,1581200892.638 [WorkSite] Loaded
2020-02-08T22:28:12.638Z,1581200892.638 [ComponentRegistry](DEBUG): SyncComponent "WorkSite" handled in the control thread.
2020-02-08T22:28:12.639Z,1581200892.639 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-02-08T22:28:12.639Z,1581200892.639 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-02-08T22:28:12.743Z,1581200892.743 [BuoyancyServo] Loaded
2020-02-08T22:28:12.743Z,1581200892.743 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-02-08T22:28:12.758Z,1581200892.758 [ElevatorServo] Loaded
2020-02-08T22:28:12.758Z,1581200892.758 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-02-08T22:28:12.772Z,1581200892.772 [MassServo] Loaded
2020-02-08T22:28:12.773Z,1581200892.773 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-02-08T22:28:12.787Z,1581200892.787 [RudderServo] Loaded
2020-02-08T22:28:12.787Z,1581200892.787 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-02-08T22:28:12.801Z,1581200892.801 [ThrusterServo] Loaded
2020-02-08T22:28:12.802Z,1581200892.802 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-02-08T22:28:12.802Z,1581200892.802 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-02-08T22:28:12.803Z,1581200892.803 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-02-08T22:28:12.983Z,1581200892.983 [PAR_Licor] Loaded
2020-02-08T22:28:12.984Z,1581200892.984 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-02-08T22:28:13.026Z,1581200893.026 [WetLabsSeaOWL_UV_A] Loaded
2020-02-08T22:28:13.026Z,1581200893.026 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-02-08T22:28:13.027Z,1581200893.027 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408F04E0
2020-02-08T22:28:13.028Z,1581200893.028 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1325
2020-02-08T22:28:13.028Z,1581200893.028 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-02-08T22:28:13.029Z,1581200893.029 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-02-08T22:28:13.360Z,1581200893.360 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-02-08T22:28:13.360Z,1581200893.360 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-02-08T22:28:13.399Z,1581200893.399 [DepthRateCalculator] Loaded
2020-02-08T22:28:13.399Z,1581200893.399 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-02-08T22:28:13.405Z,1581200893.405 [PitchRateCalculator] Loaded
2020-02-08T22:28:13.405Z,1581200893.405 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-02-08T22:28:13.417Z,1581200893.417 [SpeedCalculator] Loaded
2020-02-08T22:28:13.417Z,1581200893.417 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-02-08T22:28:13.437Z,1581200893.437 [TempGradientCalculator] Loaded
2020-02-08T22:28:13.438Z,1581200893.438 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-02-08T22:28:13.443Z,1581200893.443 [YawRateCalculator] Loaded
2020-02-08T22:28:13.443Z,1581200893.443 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-02-08T22:28:13.482Z,1581200893.482 [ElevatorOffsetCalculator] Loaded
2020-02-08T22:28:13.482Z,1581200893.482 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-02-08T22:28:13.482Z,1581200893.482 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-02-08T22:28:13.483Z,1581200893.483 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-02-08T22:28:13.615Z,1581200893.615 [SBIT](DEBUG): Construct Startup Built In Test.
2020-02-08T22:28:13.636Z,1581200893.636 [SBIT] Loaded
2020-02-08T22:28:13.637Z,1581200893.637 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-02-08T22:28:13.637Z,1581200893.637 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-02-08T22:28:13.649Z,1581200893.649 [IBIT] Loaded
2020-02-08T22:28:13.650Z,1581200893.650 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-02-08T22:28:13.653Z,1581200893.653 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-02-08T22:28:13.785Z,1581200893.785 [CBIT] Loaded
2020-02-08T22:28:13.786Z,1581200893.786 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-02-08T22:28:13.786Z,1581200893.786 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-02-08T22:28:13.786Z,1581200893.786 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-02-08T22:28:13.864Z,1581200893.864 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-02-08T22:28:13.865Z,1581200893.865 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-02-08T22:28:13.958Z,1581200893.958 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-02-08T22:28:13.959Z,1581200893.959 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-02-08T22:28:14.004Z,1581200894.004 [VerticalControl](DEBUG): Construct VerticalControl.
2020-02-08T22:28:14.083Z,1581200894.083 [VerticalControl] Loaded
2020-02-08T22:28:14.083Z,1581200894.083 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-02-08T22:28:14.084Z,1581200894.084 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-02-08T22:28:14.151Z,1581200894.151 [HorizontalControl] Loaded
2020-02-08T22:28:14.151Z,1581200894.151 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-02-08T22:28:14.152Z,1581200894.152 [SpeedControl](DEBUG): Construct SpeedControl.
2020-02-08T22:28:14.153Z,1581200894.153 [SpeedControl] Loaded
2020-02-08T22:28:14.154Z,1581200894.154 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-02-08T22:28:14.154Z,1581200894.154 [LoopControl](DEBUG): Construct LoopControl.
2020-02-08T22:28:14.155Z,1581200894.155 [LoopControl] Loaded
2020-02-08T22:28:14.155Z,1581200894.155 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-02-08T22:28:14.155Z,1581200894.155 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-02-08T22:28:14.156Z,1581200894.156 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-02-08T22:28:14.188Z,1581200894.188 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-02-08T22:28:14.191Z,1581200894.191 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-02-08T22:28:14.192Z,1581200894.192 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-02-08T22:28:14.199Z,1581200894.199 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-02-08T22:28:14.200Z,1581200894.200 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ADE4E0
2020-02-08T22:28:14.200Z,1581200894.200 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1326
2020-02-08T22:28:14.205Z,1581200894.205 [Supervisor](INFO): Main Thread ID is 816
2020-02-08T22:28:14.205Z,1581200894.205 [Supervisor](DEBUG): Running supervisor.
2020-02-08T22:28:14.205Z,1581200894.205 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1327
2020-02-08T22:28:14.209Z,1581200894.209 [controlThread ThreadHandler](INFO): Handler Thread ID is 1328
2020-02-08T22:28:14.209Z,1581200894.209 [controlThread](DEBUG): Initializing ControlThread
2020-02-08T22:28:14.216Z,1581200894.216 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-02-08T22:28:14.216Z,1581200894.216 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-02-08T22:28:14.217Z,1581200894.217 [WorkSite](DEBUG): Initializing WorkSite component.
2020-02-08T22:28:14.221Z,1581200894.221 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-02-08T22:28:14.221Z,1581200894.221 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-02-08T22:28:14.222Z,1581200894.222 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-02-08T22:28:14.222Z,1581200894.222 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-02-08T22:28:14.223Z,1581200894.223 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-02-08T22:28:14.223Z,1581200894.223 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-02-08T22:28:14.224Z,1581200894.224 [SBIT](INFO): Initialize SBIT Component.
2020-02-08T22:28:14.224Z,1581200894.224 [SBIT](IMPORTANT): git: 2020-02-07
2020-02-08T22:28:14.224Z,1581200894.224 [SBIT](INFO): git hash: 6707874467016a4fb4c344b7e74bc4738258579e
2020-02-08T22:28:14.225Z,1581200894.225 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-02-08T22:28:14.226Z,1581200894.226 [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-08T22:28:14.227Z,1581200894.227 [SBIT](INFO): Beginning SBIT in 29.000000 seconds.
2020-02-08T22:28:14.227Z,1581200894.227 [IBIT](INFO): Initialize IBIT Component.
2020-02-08T22:28:14.228Z,1581200894.228 [CBIT](DEBUG): Initialize CBIT Component.
2020-02-08T22:28:14.229Z,1581200894.229 [logger ThreadHandler](INFO): Handler Thread ID is 1329
2020-02-08T22:28:14.241Z,1581200894.241 [CBIT](DEBUG): Initialized mux pins.
2020-02-08T22:28:14.241Z,1581200894.241 [CBIT](DEBUG): Initializing the watchdog timer.
2020-02-08T22:28:14.249Z,1581200894.249 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1330
2020-02-08T22:28:14.250Z,1581200894.250 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-02-08T22:28:14.261Z,1581200894.261 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1331
2020-02-08T22:28:14.265Z,1581200894.265 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-02-08T22:28:14.265Z,1581200894.265 [CBIT](DEBUG): Initializing heartbeat.
2020-02-08T22:28:14.273Z,1581200894.273 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1332
2020-02-08T22:28:14.274Z,1581200894.274 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-02-08T22:28:14.297Z,1581200894.297 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1333
2020-02-08T22:28:14.300Z,1581200894.300 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-02-08T22:28:14.300Z,1581200894.300 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-02-08T22:28:14.300Z,1581200894.300 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-02-08T22:28:14.301Z,1581200894.301 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-02-08T22:28:14.301Z,1581200894.301 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-02-08T22:28:14.301Z,1581200894.301 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-02-08T22:28:14.302Z,1581200894.302 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-02-08T22:28:14.302Z,1581200894.302 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-02-08T22:28:14.302Z,1581200894.302 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-02-08T22:28:14.302Z,1581200894.302 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-02-08T22:28:14.302Z,1581200894.302 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-02-08T22:28:14.302Z,1581200894.302 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-02-08T22:28:14.302Z,1581200894.302 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-02-08T22:28:14.303Z,1581200894.303 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-02-08T22:28:14.303Z,1581200894.303 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-02-08T22:28:14.303Z,1581200894.303 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-02-08T22:28:14.337Z,1581200894.337 [CBIT](DEBUG): Deactivating GF circuits.
2020-02-08T22:28:14.337Z,1581200894.337 [CBIT](DEBUG): Deactivating emergency mode.
2020-02-08T22:28:14.373Z,1581200894.373 [CBIT](DEBUG): Backplane powered.
2020-02-08T22:28:14.373Z,1581200894.373 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-02-08T22:28:14.375Z,1581200894.375 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-02-08T22:28:14.375Z,1581200894.375 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-02-08T22:28:14.376Z,1581200894.376 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-02-08T22:28:14.377Z,1581200894.377 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-02-08T22:28:14.386Z,1581200894.386 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-02-08T22:28:14.413Z,1581200894.413 [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-08T22:28:14.413Z,1581200894.413 [MissionManager](INFO): Loading Mission: Missions/underIce/DefaultWithUndock.xml
2020-02-08T22:28:14.487Z,1581200894.487 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min
2020-02-08T22:28:14.503Z,1581200894.503 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 30.000000 min
2020-02-08T22:28:14.534Z,1581200894.534 [MissionManager](INFO): DefineArg DefaultWithUndock.MicromodemCommsInterval = 2.000000 min
2020-02-08T22:28:14.535Z,1581200894.535 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait.
2020-02-08T22:28:14.537Z,1581200894.537 [DefaultWithUndock:B.Undock](DEBUG): Construct.
2020-02-08T22:28:14.558Z,1581200894.558 [DefaultWithUndock:C.GoToSurface](DEBUG): Construct GoToSurface.
2020-02-08T22:28:14.600Z,1581200894.600 [DefaultWithUndock:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-02-08T22:28:14.623Z,1581200894.623 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-02-08T22:28:14.645Z,1581200894.645 [DefaultWithUndock:F.Execute](DEBUG): Construct Execute.
2020-02-08T22:28:14.649Z,1581200894.649 [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-08T22:28:14.663Z,1581200894.663 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DDM,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,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-08T22:28:14.665Z,1581200894.665 [Radio_Surface](INFO): Powering up
2020-02-08T22:28:14.685Z,1581200894.685 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-02-08T22:28:14.746Z,1581200894.746 [DDM](INFO): Powering up
2020-02-08T22:28:14.747Z,1581200894.747 [DDM](DEBUG): Initializing DDM.
2020-02-08T22:28:14.771Z,1581200894.771 [DUSBL_Hydroid](INFO): Powering up
2020-02-08T22:28:14.771Z,1581200894.771 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-02-08T22:28:14.982Z,1581200894.982 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-02-08T22:28:14.989Z,1581200894.989 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-02-08T22:28:14.990Z,1581200894.990 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-02-08T22:28:14.997Z,1581200894.997 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-02-08T22:28:14.998Z,1581200894.998 [MassServo](DEBUG): Initializing EZServoServo.
2020-02-08T22:28:15.005Z,1581200895.005 [MassServo](DEBUG): Initializing MassServo.
2020-02-08T22:28:15.006Z,1581200895.006 [RudderServo](DEBUG): Initializing EZServoServo.
2020-02-08T22:28:15.013Z,1581200895.013 [RudderServo](DEBUG): Initializing RudderServo.
2020-02-08T22:28:15.014Z,1581200895.014 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-02-08T22:28:15.021Z,1581200895.021 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-02-08T22:28:15.089Z,1581200895.089 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2020-02-08T22:28:15.092Z,1581200895.092 [Micromodem](INFO): Powering up
2020-02-08T22:28:15.092Z,1581200895.092 [Micromodem](DEBUG): Initializing Micromodem.
2020-02-08T22:28:15.849Z,1581200895.849 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-02-08T22:28:15.849Z,1581200895.849 [RudderServo](FAULT): Rudder failed to initialize
2020-02-08T22:28:15.849Z,1581200895.849 [RudderServo] Communications Fault, FailCount= 1
2020-02-08T22:28:15.849Z,1581200895.849 [RudderServo](ERROR): Communications Fault
2020-02-08T22:28:15.984Z,1581200895.984 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-02-08T22:28:16.160Z,1581200896.160 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-02-08T22:28:16.160Z,1581200896.160 [RudderServo](INFO): Powering down
2020-02-08T22:28:16.849Z,1581200896.849 [RudderServo](DEBUG): Initializing EZServoServo.
2020-02-08T22:28:16.970Z,1581200896.970 [RudderServo](DEBUG): Initializing RudderServo.
2020-02-08T22:28:16.974Z,1581200896.974 [CBIT](INFO): Clearing failed state for component RudderServo
2020-02-08T22:28:16.974Z,1581200896.974 [RudderServo] No Fault, FailCount= 1
2020-02-08T22:28:19.358Z,1581200899.358 [WorkSite](IMPORTANT): Reading workSite position from Data/workSite.cfg
2020-02-08T22:28:19.360Z,1581200899.360 [WorkSite](ERROR): Failed to parse: 

2020-02-08T22:28:19.361Z,1581200899.361 [WorkSite](IMPORTANT): WorkSite fix at 20200208T222546: (38.837070, -122.564635)
2020-02-08T22:28:20.073Z,1581200900.073 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-02-08T22:28:20.475Z,1581200900.475 [Micromodem](INFO): Nmea in: $CATMG,2020-02-08T22:28:19.330470Z,RTC,RTC*5B

2020-02-08T22:28:20.476Z,1581200900.476 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-02-08T22:28:19.330470Z,RTC,RTC*5B

2020-02-08T22:28:20.879Z,1581200900.879 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-02-08T22:28:20.879Z,1581200900.879 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-02-08T22:28:21.283Z,1581200901.283 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-02-08T22:28:21.284Z,1581200901.284 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-02-08T22:28:21.687Z,1581200901.687 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-02-08T22:28:21.687Z,1581200901.687 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-02-08T22:28:22.091Z,1581200902.091 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-02-08T22:28:22.091Z,1581200902.091 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-02-08T22:28:22.499Z,1581200902.499 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-02-08T22:28:22.499Z,1581200902.499 [Micromodem](INFO): Nmea out: $CCCFG,FC0,14500*47

2020-02-08T22:28:22.887Z,1581200902.887 [Micromodem](INFO): Nmea in: $CACFG,FC0,14500*45

2020-02-08T22:28:22.887Z,1581200902.887 [Micromodem](INFO): Nmea out: $CCCFG,BW0,2000*65

2020-02-08T22:28:23.321Z,1581200903.321 [Micromodem](INFO): Nmea in: $CACFG,BW0,2000*67

2020-02-08T22:28:23.321Z,1581200903.321 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-02-08T22:28:23.687Z,1581200903.687 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-02-08T22:28:23.687Z,1581200903.687 [Micromodem](INFO): Nmea out: $CCCLK,2020,02,08,22,28,24*42

2020-02-08T22:28:24.107Z,1581200904.107 [Micromodem](INFO): Nmea in: $CACLK,2020,2,8,22,28,24*40

2020-02-08T22:28:24.895Z,1581200904.895 [Micromodem](INFO): Nmea in: $CATMS,0,2020-02-08T22:28:25Z*71

2020-02-08T22:28:24.905Z,1581200904.905 [Micromodem](INFO): Nmea in: $CATMG,2020-02-08T22:28:25.027917Z,USER_CMD,RTC*1C

2020-02-08T22:28:28.173Z,1581200908.173 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-02-08T22:28:30.543Z,1581200910.543 [DDM](INFO): Dynamic Docking Module:
2020-02-08T22:28:32.618Z,1581200912.618 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-02-08T22:28:40.296Z,1581200920.296 [NAL9602](INFO): Powering up NAL9602
2020-02-08T22:28:44.008Z,1581200924.008 [SBIT](IMPORTANT): Beginning Startup BIT
2020-02-08T22:28:44.016Z,1581200924.016 [CBIT](IMPORTANT): Beginning ground fault scan
2020-02-08T22:28:46.025Z,1581200926.025 [ElevatorServo](FAULT): Overload Error
2020-02-08T22:28:46.025Z,1581200926.025 [ElevatorServo] Hardware Fault, FailCount= 1
2020-02-08T22:28:46.025Z,1581200926.025 [ElevatorServo](ERROR): Hardware Fault
2020-02-08T22:28:46.137Z,1581200926.137 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2020-02-08T22:28:46.435Z,1581200926.435 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-02-08T22:28:46.435Z,1581200926.435 [ElevatorServo](INFO): Powering down
2020-02-08T22:28:47.511Z,1581200927.511 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-02-08T22:28:47.632Z,1581200927.632 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-02-08T22:28:47.698Z,1581200927.698 [CBIT](INFO): Clearing failed state for component ElevatorServo
2020-02-08T22:28:47.698Z,1581200927.698 [ElevatorServo] No Fault, FailCount= 1
2020-02-08T22:28:51.483Z,1581200931.483 [NAL9602](INFO): NAL9602 initialized
2020-02-08T22:28:52.773Z,1581200932.773 [ElevatorServo](FAULT): Overload Error
2020-02-08T22:28:52.773Z,1581200932.773 [ElevatorServo] Hardware Fault, FailCount= 2
2020-02-08T22:28:52.773Z,1581200932.773 [ElevatorServo](ERROR): Hardware Fault
2020-02-08T22:28:52.854Z,1581200932.854 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2020-02-08T22:28:53.176Z,1581200933.176 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-02-08T22:28:53.176Z,1581200933.176 [ElevatorServo](INFO): Powering down
2020-02-08T22:28:53.982Z,1581200933.982 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-02-08T22:28:54.102Z,1581200934.102 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-02-08T22:28:54.149Z,1581200934.149 [CBIT](INFO): Clearing failed state for component ElevatorServo
2020-02-08T22:28:54.149Z,1581200934.149 [ElevatorServo] No Fault, FailCount= 2
2020-02-08T22:28:54.849Z,1581200934.849 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.005252
CHAN A1 (24V): -0.027807
CHAN A2 (12V): -0.007691
CHAN A3 (5V): -0.001875
CHAN B0 (3.3V): 0.000375
CHAN B1 (3.15aV): -0.001073
CHAN B2 (3.15bV): 0.000131
CHAN B3 (GND): 0.002003
OPEN: 0.005109
Full Scale Calc: 4.765 mA, -1.589 mA
2020-02-08T22:28:57.217Z,1581200937.217 [SBIT](FAULT): Elevator: EXPECTED:15.000000 ACTUAL:-0.015396
2020-02-08T22:28:59.257Z,1581200939.257 [ElevatorServo](FAULT): Overload Error
2020-02-08T22:28:59.257Z,1581200939.257 [ElevatorServo] Hardware Fault, FailCount= 3
2020-02-08T22:28:59.257Z,1581200939.257 [ElevatorServo](ERROR): Hardware Fault
2020-02-08T22:28:59.405Z,1581200939.405 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2020-02-08T22:28:59.406Z,1581200939.406 [CBIT](CRITICAL): Hardware Fault in component: ElevatorServo
2020-02-08T22:28:59.603Z,1581200939.603 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-02-08T22:28:59.604Z,1581200939.604 [ElevatorServo](INFO): Powering down
2020-02-08T22:29:00.040Z,1581200940.040 [CommandLine](FAULT): Scheduling is paused
2020-02-08T22:29:00.041Z,1581200940.041 [CBIT](INFO): Critical error at 20200208T222859
2020-02-08T22:29:00.041Z,1581200940.041 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-02-08T22:29:00.532Z,1581200940.532 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-02-08T22:29:00.646Z,1581200940.646 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-02-08T22:29:02.951Z,1581200942.951 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-02-08T22:29:02.951Z,1581200942.951 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.   +0.00,   0.00, 35.00
2020-02-08T22:29:22.684Z,1581200962.684 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-08T22:29:24.002Z,1581200964.002 [SBIT](ERROR): Could not read elevatorAngleReader_.
2020-02-08T22:29:24.002Z,1581200964.002 [SBIT](FAULT): Control surface position failure.
2020-02-08T22:29:33.740Z,1581200973.740 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-02-08T22:29:37.750Z,1581200977.750 [SBIT](ERROR): Could not read elevatorAngleReader_.
2020-02-08T22:29:37.750Z,1581200977.750 [SBIT](FAULT): Control surface position failure.
2020-02-08T22:29:38.167Z,1581200978.167 [SBIT](CRITICAL): SBIT FAILED
2020-02-08T22:29:38.167Z,1581200978.167 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-02-08T22:29:38.168Z,1581200978.168 [SBIT](IMPORTANT): DUSBL_Hydroid.verbosity=3 enum;

2020-02-08T22:29:38.168Z,1581200978.168 [SBIT](IMPORTANT): Express linearApproximation DUSBL_Hydroid.acoustic_contact_range 10.000000 meter;

2020-02-08T22:29:38.168Z,1581200978.168 [SBIT](IMPORTANT): Express linearApproximation acoustic_contact_range 10.000000 meter;

2020-02-08T22:29:38.168Z,1581200978.168 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=320 cubic_centimeter;

2020-02-08T22:29:38.168Z,1581200978.168 [SBIT](IMPORTANT): VerticalControl.massDefault=2.4 millimeter;

2020-02-08T22:29:38.170Z,1581200978.170 [CBIT](INFO): Critical error at 20200208T222938
2020-02-08T22:29:38.552Z,1581200978.552 [MissionManager](IMPORTANT): Started mission Startup
2020-02-08T22:29:38.553Z,1581200978.553 [Startup] Running Loop=1
2020-02-08T22:29:38.553Z,1581200978.553 [Startup](DEBUG): Aggregate::initialize Startup
2020-02-08T22:29:38.553Z,1581200978.553 [Startup:A.GoToSurface] Running Loop=1
2020-02-08T22:29:38.553Z,1581200978.553 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-02-08T22:29:38.554Z,1581200978.554 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-02-08T22:29:38.554Z,1581200978.554 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-02-08T22:29:38.554Z,1581200978.554 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-02-08T22:29:38.555Z,1581200978.555 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-02-08T22:29:38.555Z,1581200978.555 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-02-08T22:29:38.556Z,1581200978.556 [Startup:StartupSatComms] Running Loop=1
2020-02-08T22:29:38.557Z,1581200978.557 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-02-08T22:29:38.557Z,1581200978.557 [Startup:StartupSatComms:A] Running Loop=1
2020-02-08T22:29:38.975Z,1581200978.975 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-02-08T22:29:52.295Z,1581200992.295 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-08T22:29:52.295Z,1581200992.295 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-02-08T22:29:54.384Z,1581200994.384 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-02-08T22:30:24.985Z,1581201024.985 [NAL9602](INFO): SBD MO Status=2, MOMSN=10621, MT Status=2, MTMSN=0
2020-02-08T22:30:24.985Z,1581201024.985 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-02-08T22:30:38.758Z,1581201038.758 [Startup:StartupSatComms:A](INFO): Timed out from 2020-02-08T22:29:38.6Z
2020-02-08T22:30:38.758Z,1581201038.758 [Startup:StartupSatComms:A] Stopped
2020-02-08T22:30:38.758Z,1581201038.758 [Startup:StartupSatComms:B] Running Loop=1
2020-02-08T22:30:39.189Z,1581201039.189 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-02-08T22:30:48.107Z,1581201048.107 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-02-08T22:30:50.834Z,1581201050.834 [NAL9602](INFO): SBD MO Status=2, MOMSN=10621, MT Status=2, MTMSN=0
2020-02-08T22:30:50.834Z,1581201050.834 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-02-08T22:31:38.962Z,1581201098.962 [Startup:StartupSatComms:B](INFO): Timed out from 2020-02-08T22:30:38.8Z
2020-02-08T22:31:38.962Z,1581201098.962 [Startup:StartupSatComms:B] Stopped
2020-02-08T22:31:38.962Z,1581201098.962 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-02-08T22:31:38.962Z,1581201098.962 [Startup:StartupSatComms] Stopped
2020-02-08T22:31:38.962Z,1581201098.962 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-02-08T22:31:38.963Z,1581201098.963 [Startup](INFO): Completed Startup
2020-02-08T22:31:38.963Z,1581201098.963 [MissionManager](INFO): Startup is completed.
2020-02-08T22:31:38.963Z,1581201098.963 [MissionManager](INFO): Uninitializing Mission Startup
2020-02-08T22:31:38.963Z,1581201098.963 [Startup] Stopped
2020-02-08T22:31:38.963Z,1581201098.963 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-02-08T22:31:38.963Z,1581201098.963 [Startup:A.GoToSurface] Stopped
2020-02-08T22:31:38.963Z,1581201098.963 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-02-08T22:31:39.362Z,1581201099.362 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2020-02-08T22:31:39.362Z,1581201099.362 [DefaultWithUndock] Running Loop=1
2020-02-08T22:31:39.362Z,1581201099.362 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2020-02-08T22:31:39.362Z,1581201099.362 [DefaultWithUndock:C.GoToSurface] Running Loop=1
2020-02-08T22:31:39.362Z,1581201099.362 [DefaultWithUndock:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-02-08T22:31:39.363Z,1581201099.363 [DefaultWithUndock:C.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-02-08T22:31:39.363Z,1581201099.363 [DefaultWithUndock:C.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-02-08T22:31:39.363Z,1581201099.363 [DefaultWithUndock:C.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-02-08T22:31:39.364Z,1581201099.364 [DefaultWithUndock:C.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-02-08T22:31:39.364Z,1581201099.364 [DefaultWithUndock:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-02-08T22:31:39.364Z,1581201099.364 [DefaultWithUndock:A.Wait] Running Loop=1
2020-02-08T22:31:39.364Z,1581201099.364 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2020-02-08T22:31:39.763Z,1581201099.763 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005139
2020-02-08T22:32:07.269Z,1581201127.269 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-02-08T22:32:09.708Z,1581201129.708 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2020-02-08T22:32:09.708Z,1581201129.708 [DefaultWithUndock:A.Wait] Stopped
2020-02-08T22:32:09.708Z,1581201129.708 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2020-02-08T22:32:09.708Z,1581201129.708 [DefaultWithUndock:B.Undock] Running Loop=1
2020-02-08T22:32:09.708Z,1581201129.708 [DefaultWithUndock:B.Undock](DEBUG): Initialize.
2020-02-08T22:32:10.454Z,1581201130.454 [PowerOnly](INFO): Powering up
2020-02-08T22:32:31.536Z,1581201151.536 [DefaultWithUndock:B.Undock](INFO): Detaching from dock.
2020-02-08T22:32:31.977Z,1581201151.977 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-02-08T22:32:31.977Z,1581201151.977 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-02-08T22:32:31.977Z,1581201151.977 [RDI_Pathfinder](ERROR): Communications Fault
2020-02-08T22:32:31.977Z,1581201151.977 [RDI_Pathfinder](ERROR): Failed to parse:
2020-02-08T22:32:31.999Z,1581201151.999 [DefaultWithUndock:B.Undock](INFO): Detected possible detachment. Starting timer.
2020-02-08T22:32:32.035Z,1581201152.035 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-02-08T22:32:32.365Z,1581201152.365 [RDI_Pathfinder](INFO): Powering down
2020-02-08T22:32:33.124Z,1581201153.124 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-02-08T22:32:33.124Z,1581201153.124 [RDI_Pathfinder] No Fault, FailCount= 1
2020-02-08T22:32:46.431Z,1581201166.431 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-02-08T22:32:52.113Z,1581201172.113 [DefaultWithUndock:B.Undock](INFO): Detached at range: nan m. Transitioning DDM to standby.
2020-02-08T22:33:12.323Z,1581201192.323 [DefaultWithUndock:B.Undock](INFO): DDM at standby.
2020-02-08T22:33:12.748Z,1581201192.748 [DefaultWithUndock:B.Undock](IMPORTANT): Undocking sequence complete.
2020-02-08T22:33:12.748Z,1581201192.748 [DefaultWithUndock:B.Undock] Stopped
2020-02-08T22:33:13.105Z,1581201193.105 [DefaultWithUndock:CheckIn] Running Loop=1
2020-02-08T22:33:13.105Z,1581201193.105 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-02-08T22:33:13.105Z,1581201193.105 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-02-08T22:33:13.538Z,1581201193.538 [DefaultWithUndock:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-02-08T22:33:41.745Z,1581201221.745 [PowerOnly](INFO): Powering down
2020-02-08T22:33:54.256Z,1581201234.256 [NAL9602](INFO): SBD MO Status=2, MOMSN=10621, MT Status=2, MTMSN=0
2020-02-08T22:33:54.256Z,1581201234.256 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-02-08T22:33:57.588Z,1581201237.588 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-02-08T22:33:58.793Z,1581201238.793 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-02-08T22:34:37.021Z,1581201277.021 [CommandLine](IMPORTANT): got command get latitude
2020-02-08T22:34:37.022Z,1581201277.022 [CommandLine](IMPORTANT): latitude 38.837070 arcdeg
2020-02-08T22:34:42.571Z,1581201282.571 [CommandLine](IMPORTANT): got command strobe off
2020-02-08T22:34:42.571Z,1581201282.571 [CommandLine](IMPORTANT): Deactivating strobe
2020-02-08T22:34:42.731Z,1581201282.731 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-02-08T22:34:43.949Z,1581201283.949 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223427.00,A,4450.22569,N,06733.88996,W,0.447,189.70,080220,,,A*7E
2020-02-08T22:34:43.952Z,1581201283.952 [NAL9602](INFO): GPS fix at 20200208T223427: (44.837095, -67.564833)
2020-02-08T22:34:43.988Z,1581201283.988 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-02-08T22:34:43.988Z,1581201283.988 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-02-08T22:34:44.406Z,1581201284.406 [DefaultWithUndock:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-02-08T22:34:49.711Z,1581201289.711 [CommandLine](IMPORTANT): got command get latitude
2020-02-08T22:34:49.711Z,1581201289.711 [CommandLine](IMPORTANT): latitude 44.837095 arcdeg
2020-02-08T22:34:50.000Z,1581201290.000 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200208T212522/Courier0037.lzma
2020-02-08T22:34:52.016Z,1581201292.016 [DataOverHttps](INFO): Moved sent file to Logs/20200208T212522/Courier0037.lzma.bak
2020-02-08T22:34:52.016Z,1581201292.016 [DataOverHttps](INFO): SBD MOMSN=12237913
2020-02-08T22:34:57.712Z,1581201297.712 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-08T22:34:57.712Z,1581201297.712 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020815475737,35.0, -0.1,   0.0  0
2020-02-08T22:35:09.705Z,1581201309.705 [DataOverHttps](INFO): Sending 348 bytes from file Logs/20200208T222803/Courier0000.lzma
2020-02-08T22:35:11.710Z,1581201311.710 [DataOverHttps](INFO): Moved sent file to Logs/20200208T222803/Courier0000.lzma.bak
2020-02-08T22:35:11.710Z,1581201311.710 [DataOverHttps](INFO): SBD MOMSN=12237915
2020-02-08T22:35:16.263Z,1581201316.263 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-08T22:35:29.251Z,1581201329.251 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200208T222803/Courier0004.lzma
2020-02-08T22:35:31.250Z,1581201331.250 [DataOverHttps](INFO): Moved sent file to Logs/20200208T222803/Courier0004.lzma.bak
2020-02-08T22:35:31.250Z,1581201331.250 [DataOverHttps](INFO): SBD MOMSN=12237921
2020-02-08T22:35:48.973Z,1581201348.973 [DataOverHttps](INFO): Sending 218 bytes from file Logs/20200208T212522/Express0035.lzma
2020-02-08T22:35:50.974Z,1581201350.974 [DataOverHttps](INFO): Moved sent file to Logs/20200208T212522/Express0035.lzma.bak
2020-02-08T22:35:50.974Z,1581201350.974 [DataOverHttps](INFO): SBD MOMSN=12237924
2020-02-08T22:36:01.144Z,1581201361.144 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-02-08T22:36:01.148Z,1581201361.148 [BPC1](INFO): Received data from all battery sticks.
2020-02-08T22:36:08.356Z,1581201368.356 [DataOverHttps](INFO): Sending 100 bytes from file Logs/20200208T212522/Express0038.lzma
2020-02-08T22:36:10.365Z,1581201370.365 [DataOverHttps](INFO): Moved sent file to Logs/20200208T212522/Express0038.lzma.bak
2020-02-08T22:36:10.366Z,1581201370.366 [DataOverHttps](INFO): SBD MOMSN=12237928
2020-02-08T22:36:29.815Z,1581201389.815 [DataOverHttps](INFO): Sending 1020 bytes from file Logs/20200208T222803/Express0001.lzma
2020-02-08T22:36:31.818Z,1581201391.818 [DataOverHttps](INFO): Moved sent file to Logs/20200208T222803/Express0001.lzma.bak
2020-02-08T22:36:31.818Z,1581201391.818 [DataOverHttps](INFO): SBD MOMSN=12237931
2020-02-08T22:36:38.352Z,1581201398.352 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-02-08T22:36:49.201Z,1581201409.201 [DataOverHttps](INFO): Sending 344 bytes from file Logs/20200208T222803/Express0005.lzma
2020-02-08T22:36:51.206Z,1581201411.206 [DataOverHttps](INFO): Moved sent file to Logs/20200208T222803/Express0005.lzma.bak
2020-02-08T22:36:51.206Z,1581201411.206 [DataOverHttps](INFO): SBD MOMSN=12237956
2020-02-08T22:36:52.860Z,1581201412.860 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-02-08T22:36:52.860Z,1581201412.860 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-02-08T22:36:52.860Z,1581201412.860 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-08T22:40:00.335Z,1581201600.335 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-08T22:40:00.335Z,1581201600.335 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-3278,-3:BE,-32768,-32768,-32768,V
2020-02-08T22:40:16.062Z,1581201616.062 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-08T22:40:16.062Z,1581201616.062 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-02-08T22:40:56.056Z,1581201656.056 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-08T22:40:56.056Z,1581201656.056 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-02-08T22:41:53.437Z,1581201713.437 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-08T22:41:53.438Z,1581201713.438 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-02-08T22:41:53.438Z,1581201713.438 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-08T22:41:53.438Z,1581201713.438 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-02-08T22:41:53.849Z,1581201713.849 [DefaultWithUndock:CheckIn:D] Stopped
2020-02-08T22:41:53.849Z,1581201713.849 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-02-08T22:41:54.253Z,1581201714.253 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 10.241439 min 
2020-02-08T22:41:54.253Z,1581201714.253 [DefaultWithUndock:CheckIn:E] Stopped
2020-02-08T22:41:54.253Z,1581201714.253 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-02-08T22:41:54.253Z,1581201714.253 [DefaultWithUndock:CheckIn] Stopped
2020-02-08T22:41:54.253Z,1581201714.253 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-02-08T22:41:54.253Z,1581201714.253 [DefaultWithUndock:CheckIn](INFO): Running loop #2
2020-02-08T22:41:54.253Z,1581201714.253 [DefaultWithUndock:CheckIn] Running Loop=2
2020-02-08T22:41:54.253Z,1581201714.253 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-02-08T22:41:54.254Z,1581201714.254 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-02-08T22:41:56.240Z,1581201716.240 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224139.00,A,4450.20050,N,06733.87286,W,0.505,189.70,080220,,,A*7C
2020-02-08T22:41:56.243Z,1581201716.243 [NAL9602](INFO): GPS fix at 20200208T224139: (44.836675, -67.564548)
2020-02-08T22:41:56.293Z,1581201716.293 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-02-08T22:41:56.293Z,1581201716.293 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-02-08T22:42:04.084Z,1581201724.084 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20200208T222803/Courier0007.lzma
2020-02-08T22:42:06.090Z,1581201726.090 [DataOverHttps](INFO): Moved sent file to Logs/20200208T222803/Courier0007.lzma.bak
2020-02-08T22:42:06.090Z,1581201726.090 [DataOverHttps](INFO): SBD MOMSN=12237976
2020-02-08T22:42:25.860Z,1581201745.860 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20200208T222803/Express0008.lzma
2020-02-08T22:42:26.943Z,1581201746.943 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-02-08T22:42:27.017Z,1581201747.017 [NAL9602](FAULT): received: 
+CSQ:0

OK621, 2, 0, 0, 0

OK
2020-02-08T22:42:27.017Z,1581201747.017 [NAL9602] Data Fault, FailCount= 1
2020-02-08T22:42:27.017Z,1581201747.017 [NAL9602](ERROR): Data Fault
2020-02-08T22:42:27.098Z,1581201747.098 [CBIT](ERROR): Data Fault in component: NAL9602
2020-02-08T22:42:27.353Z,1581201747.353 [NAL9602](INFO): Powering down
2020-02-08T22:42:27.866Z,1581201747.866 [DataOverHttps](INFO): Moved sent file to Logs/20200208T222803/Express0008.lzma.bak
2020-02-08T22:42:27.866Z,1581201747.866 [DataOverHttps](INFO): SBD MOMSN=12237979
2020-02-08T22:42:28.197Z,1581201748.197 [CBIT](INFO): Clearing failed state for component NAL9602
2020-02-08T22:42:28.197Z,1581201748.197 [NAL9602] No Fault, FailCount= 1
2020-02-08T22:42:29.804Z,1581201749.804 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-02-08T22:42:29.804Z,1581201749.804 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-02-08T22:42:29.804Z,1581201749.804 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-08T22:42:57.647Z,1581201777.647 [NAL9602](INFO): Powering up NAL9602
2020-02-08T22:43:08.551Z,1581201788.551 [NAL9602](INFO): NAL9602 initialized
2020-02-08T22:43:39.656Z,1581201819.656 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-08T22:43:59.527Z,1581201839.527 [CBIT](INFO): Clearing failed state for component ElevatorServo
2020-02-08T22:43:59.527Z,1581201839.527 [ElevatorServo] No Fault, FailCount= 3
2020-02-08T22:44:59.694Z,1581201899.694 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-08T22:44:59.694Z,1581201899.694 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020815575937,35.0, -0.1,   09,  0
2020-02-08T22:47:20.690Z,1581202040.690 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-02-08T22:47:30.432Z,1581202050.432 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-08T22:47:30.432Z,1581202050.432 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-02-08T22:47:30.432Z,1581202050.432 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-08T22:47:30.432Z,1581202050.432 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-02-08T22:47:30.805Z,1581202050.805 [DefaultWithUndock:CheckIn:D] Stopped
2020-02-08T22:47:30.805Z,1581202050.805 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-02-08T22:47:31.208Z,1581202051.208 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 15.857370 min 
2020-02-08T22:47:31.208Z,1581202051.208 [DefaultWithUndock:CheckIn:E] Stopped
2020-02-08T22:47:31.208Z,1581202051.208 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-02-08T22:47:31.208Z,1581202051.208 [DefaultWithUndock:CheckIn] Stopped
2020-02-08T22:47:31.209Z,1581202051.209 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-02-08T22:47:31.209Z,1581202051.209 [DefaultWithUndock:CheckIn](INFO): Running loop #3
2020-02-08T22:47:31.209Z,1581202051.209 [DefaultWithUndock:CheckIn] Running Loop=3
2020-02-08T22:47:31.209Z,1581202051.209 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-02-08T22:47:31.209Z,1581202051.209 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-02-08T22:47:33.204Z,1581202053.204 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224716.00,A,4450.22004,N,06733.87254,W,0.078,129.11,080220,,,A*79
2020-02-08T22:47:33.207Z,1581202053.207 [NAL9602](INFO): GPS fix at 20200208T224716: (44.837001, -67.564542)
2020-02-08T22:47:33.250Z,1581202053.250 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-02-08T22:47:33.250Z,1581202053.250 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-02-08T22:47:34.819Z,1581202054.819 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-02-08T22:47:40.892Z,1581202060.892 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200208T222803/Courier0010.lzma
2020-02-08T22:47:42.898Z,1581202062.898 [DataOverHttps](INFO): Moved sent file to Logs/20200208T222803/Courier0010.lzma.bak
2020-02-08T22:47:42.898Z,1581202062.898 [DataOverHttps](INFO): SBD MOMSN=12237984
2020-02-08T22:48:01.008Z,1581202081.008 [DataOverHttps](INFO): Sending 163 bytes from file Logs/20200208T222803/Express0011.lzma
2020-02-08T22:48:03.014Z,1581202083.014 [DataOverHttps](INFO): Moved sent file to Logs/20200208T222803/Express0011.lzma.bak
2020-02-08T22:48:03.014Z,1581202083.014 [DataOverHttps](INFO): SBD MOMSN=12237987
2020-02-08T22:48:04.751Z,1581202084.751 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-02-08T22:48:04.751Z,1581202084.751 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-02-08T22:48:04.751Z,1581202084.751 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-08T22:48:05.523Z,1581202085.523 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-08T22:50:06.366Z,1581202206.366 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-02-08T22:52:03.523Z,1581202323.523 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-02-08T22:53:05.345Z,1581202385.345 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-08T22:53:05.345Z,1581202385.345 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-02-08T22:53:05.345Z,1581202385.345 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-08T22:53:05.345Z,1581202385.345 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-02-08T22:53:05.765Z,1581202385.765 [DefaultWithUndock:CheckIn:D] Stopped
2020-02-08T22:53:05.765Z,1581202385.765 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-02-08T22:53:06.153Z,1581202386.153 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 21.440037 min 
2020-02-08T22:53:06.153Z,1581202386.153 [DefaultWithUndock:CheckIn:E] Stopped
2020-02-08T22:53:06.154Z,1581202386.154 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-02-08T22:53:06.154Z,1581202386.154 [DefaultWithUndock:CheckIn] Stopped
2020-02-08T22:53:06.154Z,1581202386.154 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-02-08T22:53:06.154Z,1581202386.154 [DefaultWithUndock:CheckIn](INFO): Running loop #4
2020-02-08T22:53:06.154Z,1581202386.154 [DefaultWithUndock:CheckIn] Running Loop=4
2020-02-08T22:53:06.154Z,1581202386.154 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-02-08T22:53:06.154Z,1581202386.154 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-02-08T22:53:08.143Z,1581202388.143 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225252.00,A,4450.22780,N,06733.87065,W,0.369,129.11,080220,,,A*75
2020-02-08T22:53:08.145Z,1581202388.145 [NAL9602](INFO): GPS fix at 20200208T225252: (44.837130, -67.564511)
2020-02-08T22:53:08.181Z,1581202388.181 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-02-08T22:53:08.181Z,1581202388.181 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-02-08T22:53:15.972Z,1581202395.972 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200208T222803/Courier0013.lzma
2020-02-08T22:53:17.978Z,1581202397.978 [DataOverHttps](INFO): Moved sent file to Logs/20200208T222803/Courier0013.lzma.bak
2020-02-08T22:53:17.978Z,1581202397.978 [DataOverHttps](INFO): SBD MOMSN=12237997
2020-02-08T22:53:35.251Z,1581202415.251 [DataOverHttps](INFO): Sending 197 bytes from file Logs/20200208T222803/Express0014.lzma
2020-02-08T22:53:37.254Z,1581202417.254 [DataOverHttps](INFO): Moved sent file to Logs/20200208T222803/Express0014.lzma.bak
2020-02-08T22:53:37.255Z,1581202417.254 [DataOverHttps](INFO): SBD MOMSN=12238000
2020-02-08T22:53:38.880Z,1581202418.880 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-02-08T22:53:38.880Z,1581202418.880 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-02-08T22:53:38.880Z,1581202418.880 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-08T22:54:00.342Z,1581202440.342 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-02-08T22:54:59.708Z,1581202499.708 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2020-02-08T22:55:02.559Z,1581202502.559 [RDI_Pathfinder](ERROR): Failed to parse: 0 +0.00,        +0.00,   0.00,1334.92
2020-02-08T22:55:15.080Z,1581202515.080 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-08T22:55:15.080Z,1581202515.080 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-02-08T22:58:09.980Z,1581202689.980 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-02-08T22:58:39.501Z,1581202719.501 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-08T22:58:39.501Z,1581202719.501 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-02-08T22:58:39.501Z,1581202719.501 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-08T22:58:39.502Z,1581202719.501 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-02-08T22:58:39.895Z,1581202719.895 [DefaultWithUndock:CheckIn:D] Stopped
2020-02-08T22:58:39.895Z,1581202719.895 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-02-08T22:58:40.331Z,1581202720.331 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 27.008875 min 
2020-02-08T22:58:40.331Z,1581202720.331 [DefaultWithUndock:CheckIn:E] Stopped
2020-02-08T22:58:40.331Z,1581202720.331 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-02-08T22:58:40.332Z,1581202720.332 [DefaultWithUndock:CheckIn] Stopped
2020-02-08T22:58:40.332Z,1581202720.332 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-02-08T22:58:40.332Z,1581202720.332 [DefaultWithUndock:CheckIn](INFO): Running loop #5
2020-02-08T22:58:40.333Z,1581202720.333 [DefaultWithUndock:CheckIn] Running Loop=5
2020-02-08T22:58:40.333Z,1581202720.333 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-02-08T22:58:40.334Z,1581202720.334 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-02-08T22:58:40.673Z,1581202720.673 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-08T22:58:42.297Z,1581202722.297 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225826.00,A,4450.22534,N,06733.87014,W,0.156,0.00,080220,,,A*73
2020-02-08T22:58:42.299Z,1581202722.299 [NAL9602](INFO): GPS fix at 20200208T225826: (44.837089, -67.564502)
2020-02-08T22:58:42.344Z,1581202722.344 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-02-08T22:58:42.344Z,1581202722.344 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-02-08T22:58:49.740Z,1581202729.740 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20200208T222803/Courier0016.lzma
2020-02-08T22:58:51.747Z,1581202731.747 [DataOverHttps](INFO): Moved sent file to Logs/20200208T222803/Courier0016.lzma.bak
2020-02-08T22:58:51.747Z,1581202731.747 [DataOverHttps](INFO): SBD MOMSN=12238004
2020-02-08T22:59:09.104Z,1581202749.104 [DataOverHttps](INFO): Sending 196 bytes from file Logs/20200208T222803/Express0017.lzma
2020-02-08T22:59:11.110Z,1581202751.110 [DataOverHttps](INFO): Moved sent file to Logs/20200208T222803/Express0017.lzma.bak
2020-02-08T22:59:11.110Z,1581202751.110 [DataOverHttps](INFO): SBD MOMSN=12238008
2020-02-08T22:59:13.029Z,1581202753.029 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-02-08T22:59:13.029Z,1581202753.029 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-02-08T22:59:13.029Z,1581202753.029 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-08T23:01:26.362Z,1581202886.362 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-02-08T23:01:26.363Z,1581202886.363 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-02-08T23:01:33.640Z,1581202893.640 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2020-02-08T23:03:44.896Z,1581203024.896 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-02-08T23:04:03.524Z,1581203043.524 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-02-08T23:04:03.524Z,1581203043.524 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00, :BI,-32768,-32768,-32768,-32768,V
2020-02-08T23:04:13.615Z,1581203053.615 [DefaultWithUndock:CheckIn:C.Wait](INFO): Done Waiting.
2020-02-08T23:04:13.615Z,1581203053.615 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-02-08T23:04:13.615Z,1581203053.615 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-08T23:04:13.615Z,1581203053.615 [DefaultWithUndock:CheckIn:D] Running Loop=1
2020-02-08T23:04:14.006Z,1581203054.006 [DefaultWithUndock:CheckIn:D] Stopped
2020-02-08T23:04:14.006Z,1581203054.006 [DefaultWithUndock:CheckIn:E] Running Loop=1
2020-02-08T23:04:14.411Z,1581203054.411 [DefaultWithUndock:CheckIn:E](IMPORTANT): Default mission has been running for 32.577395 min 
2020-02-08T23:04:14.411Z,1581203054.411 [DefaultWithUndock:CheckIn:E] Stopped
2020-02-08T23:04:14.411Z,1581203054.411 [DefaultWithUndock:CheckIn](INFO): Completed DefaultWithUndock:CheckIn
2020-02-08T23:04:14.411Z,1581203054.411 [DefaultWithUndock:CheckIn] Stopped
2020-02-08T23:04:14.411Z,1581203054.411 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-02-08T23:04:14.411Z,1581203054.411 [DefaultWithUndock:CheckIn](INFO): Running loop #6
2020-02-08T23:04:14.411Z,1581203054.411 [DefaultWithUndock:CheckIn] Running Loop=6
2020-02-08T23:04:14.411Z,1581203054.411 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:CheckIn
2020-02-08T23:04:14.411Z,1581203054.411 [DefaultWithUndock:CheckIn:Read_GPS] Running Loop=1
2020-02-08T23:04:16.405Z,1581203056.405 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230401.00,A,4450.23060,N,06733.87473,W,0.039,215.05,080220,,,A*75
2020-02-08T23:04:16.407Z,1581203056.407 [NAL9602](INFO): GPS fix at 20200208T230401: (44.837177, -67.564579)
2020-02-08T23:04:16.477Z,1581203056.477 [DefaultWithUndock:CheckIn:Read_GPS] Stopped
2020-02-08T23:04:16.477Z,1581203056.477 [DefaultWithUndock:CheckIn:Read_Iridium] Running Loop=1
2020-02-08T23:04:20.725Z,1581203060.725 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-02-08T23:04:20.726Z,1581203060.726 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20020816172037,35.0, -0.1,   0.0,1448:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2020-02-08T23:04:26.456Z,1581203066.456 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20200208T222803/Courier0019.lzma
2020-02-08T23:04:28.462Z,1581203068.462 [DataOverHttps](INFO): Moved sent file to Logs/20200208T222803/Courier0019.lzma.bak
2020-02-08T23:04:28.462Z,1581203068.462 [DataOverHttps](INFO): SBD MOMSN=12238019
2020-02-08T23:04:45.789Z,1581203085.789 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20200208T222803/Express0020.lzma
2020-02-08T23:04:47.794Z,1581203087.794 [DataOverHttps](INFO): Moved sent file to Logs/20200208T222803/Express0020.lzma.bak
2020-02-08T23:04:47.794Z,1581203087.794 [DataOverHttps](INFO): SBD MOMSN=12238022
2020-02-08T23:04:49.367Z,1581203089.367 [NAL9602](INFO): Not Powering down - fast GPS
2020-02-08T23:04:49.829Z,1581203089.829 [DefaultWithUndock:CheckIn:Read_Iridium] Stopped
2020-02-08T23:04:49.829Z,1581203089.829 [DefaultWithUndock:CheckIn:C.Wait] Running Loop=1
2020-02-08T23:04:49.829Z,1581203089.829 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-02-08T23:05:06.242Z,1581203106.242 [CommandLine](IMPORTANT): got command restart application
2020-02-08T23:05:07.249Z,1581203107.249 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-08T23:05:07.249Z,1581203107.249 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:05:07.377Z,1581203107.377 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-02-08T23:05:07.377Z,1581203107.377 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:05:07.377Z,1581203107.377 [CommandLine](INFO): Join timeout helper Thread ID is 1500
2020-02-08T23:05:07.378Z,1581203107.378 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-02-08T23:05:07.378Z,1581203107.378 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:05:07.378Z,1581203107.378 [NavChartDb](INFO): Join timeout helper Thread ID is 1501
2020-02-08T23:05:07.473Z,1581203107.473 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-08T23:05:07.473Z,1581203107.473 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:05:07.477Z,1581203107.477 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-02-08T23:05:07.477Z,1581203107.477 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:05:07.477Z,1581203107.477 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1502
2020-02-08T23:05:07.561Z,1581203107.561 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-08T23:05:07.561Z,1581203107.561 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-02-08T23:05:07.562Z,1581203107.562 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:05:07.563Z,1581203107.563 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-02-08T23:05:07.563Z,1581203107.563 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:05:07.563Z,1581203107.563 [Radio_Surface](INFO): Join timeout helper Thread ID is 1503
2020-02-08T23:05:07.637Z,1581203107.637 [Radio_Surface](INFO): Powering down
2020-02-08T23:05:07.638Z,1581203107.638 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-08T23:05:07.638Z,1581203107.638 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:05:07.649Z,1581203107.649 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-02-08T23:05:07.649Z,1581203107.649 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:05:07.649Z,1581203107.649 [DataOverHttps](INFO): Join timeout helper Thread ID is 1504
2020-02-08T23:05:08.101Z,1581203108.101 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-08T23:05:08.101Z,1581203108.101 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:05:08.114Z,1581203108.114 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-02-08T23:05:08.114Z,1581203108.114 [logger ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:05:08.114Z,1581203108.114 [logger](INFO): Join timeout helper Thread ID is 1505
2020-02-08T23:05:08.157Z,1581203108.157 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-08T23:05:08.157Z,1581203108.157 [logger ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:05:08.173Z,1581203108.173 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-02-08T23:05:08.174Z,1581203108.174 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:05:08.174Z,1581203108.174 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-02-08T23:05:08.174Z,1581203108.174 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:05:08.174Z,1581203108.174 [controlThread](INFO): Join timeout helper Thread ID is 1506
2020-02-08T23:05:08.337Z,1581203108.337 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-02-08T23:05:08.338Z,1581203108.338 [controlThread](DEBUG): Uninitializing ControlThread
2020-02-08T23:05:08.339Z,1581203108.339 [AHRS_M2](INFO): Powering down
2020-02-08T23:05:08.409Z,1581203108.409 [DDM](INFO): Powering down
2020-02-08T23:05:08.497Z,1581203108.497 [DUSBL_Hydroid](INFO): Powering down
2020-02-08T23:05:08.585Z,1581203108.585 [Micromodem](INFO): Powering down
2020-02-08T23:05:08.681Z,1581203108.681 [NAL9602](INFO): Powering down
2020-02-08T23:05:08.753Z,1581203108.753 [RDI_Pathfinder](INFO): Powering down
2020-02-08T23:05:08.754Z,1581203108.754 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-02-08T23:05:08.755Z,1581203108.755 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-02-08T23:05:08.756Z,1581203108.756 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-02-08T23:05:08.756Z,1581203108.756 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock
2020-02-08T23:05:08.756Z,1581203108.756 [DefaultWithUndock] Stopped
2020-02-08T23:05:08.756Z,1581203108.756 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2020-02-08T23:05:08.757Z,1581203108.757 [DefaultWithUndock:C.GoToSurface] Stopped
2020-02-08T23:05:08.757Z,1581203108.757 [DefaultWithUndock:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-02-08T23:05:08.757Z,1581203108.757 [DefaultWithUndock:CheckIn] Stopped
2020-02-08T23:05:08.757Z,1581203108.757 [DefaultWithUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:CheckIn
2020-02-08T23:05:08.757Z,1581203108.757 [DefaultWithUndock:CheckIn:C.Wait] Stopped
2020-02-08T23:05:08.757Z,1581203108.757 [DefaultWithUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-02-08T23:05:08.760Z,1581203108.760 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-02-08T23:05:08.760Z,1581203108.760 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-02-08T23:05:08.761Z,1581203108.761 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-02-08T23:05:08.761Z,1581203108.761 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-02-08T23:05:08.761Z,1581203108.761 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-02-08T23:05:08.761Z,1581203108.761 [BuoyancyServo](INFO): Powering down
2020-02-08T23:05:08.773Z,1581203108.773 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-02-08T23:05:08.773Z,1581203108.773 [ElevatorServo](INFO): Powering down
2020-02-08T23:05:08.774Z,1581203108.774 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-02-08T23:05:08.774Z,1581203108.774 [MassServo](INFO): Powering down
2020-02-08T23:05:08.775Z,1581203108.775 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-02-08T23:05:08.775Z,1581203108.775 [RudderServo](INFO): Powering down
2020-02-08T23:05:08.775Z,1581203108.775 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-02-08T23:05:08.775Z,1581203108.775 [ThrusterServo](INFO): Powering down
2020-02-08T23:05:08.776Z,1581203108.776 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-02-08T23:05:08.777Z,1581203108.777 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-02-08T23:05:08.777Z,1581203108.777 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-02-08T23:05:08.777Z,1581203108.777 [CBIT](DEBUG): Powering off loads.
2020-02-08T23:05:08.789Z,1581203108.789 [CBIT](DEBUG): Disabling WDT.
2020-02-08T23:05:08.801Z,1581203108.801 [CBIT](DEBUG): Opening all GF detection circuits.
2020-02-08T23:05:08.802Z,1581203108.802 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:05:08.854Z,1581203108.854 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:05:08.864Z,1581203108.864 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:05:08.907Z,1581203108.907 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:05:08.965Z,1581203108.965 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-02-08T23:05:09.032Z,1581203109.032 [logger ThreadHandler](INFO): Thread cancelled.