2020-01-14T01:02:11.960Z,1578963731.960 [Supervisor](DEBUG): Initializing supervisor.
2020-01-14T01:02:11.963Z,1578963731.963 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-01-14T01:02:11.964Z,1578963731.964 [SyncHandler](INFO): Protected caller Thread ID is 8507
2020-01-14T01:02:11.964Z,1578963731.964 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-01-14T01:02:11.965Z,1578963731.965 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-01-14T01:02:11.966Z,1578963731.966 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8508
2020-01-14T01:02:11.969Z,1578963731.969 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-01-14T01:02:11.981Z,1578963731.981 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-01-14T01:02:11.982Z,1578963731.982 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-01-14T01:02:11.982Z,1578963731.982 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8509
2020-01-14T01:02:11.983Z,1578963731.983 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-01-14T01:02:11.984Z,1578963731.984 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-01-14T01:02:11.984Z,1578963731.984 [logger ThreadHandler](INFO): Protected caller Thread ID is 8510
2020-01-14T01:02:11.986Z,1578963731.986 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-01-14T01:02:11.987Z,1578963731.987 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-01-14T01:02:11.988Z,1578963731.988 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-01-14T01:02:12.437Z,1578963732.437 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-01-14T01:02:12.438Z,1578963732.438 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-01-14T01:02:12.534Z,1578963732.534 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-01-14T01:02:12.535Z,1578963732.535 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-01-14T01:02:12.859Z,1578963732.859 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-01-14T01:02:12.860Z,1578963732.860 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-01-14T01:02:12.999Z,1578963732.999 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-01-14T01:02:12.000Z,1578963733.000 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-01-14T01:02:13.191Z,1578963733.191 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-01-14T01:02:13.192Z,1578963733.192 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-01-14T01:02:13.649Z,1578963733.649 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-01-14T01:02:13.649Z,1578963733.649 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-01-14T01:02:13.861Z,1578963733.861 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-01-14T01:02:13.862Z,1578963733.862 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-01-14T01:02:14.007Z,1578963734.007 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-01-14T01:02:14.008Z,1578963734.008 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-01-14T01:02:14.203Z,1578963734.203 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-01-14T01:02:14.203Z,1578963734.203 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-01-14T01:02:14.298Z,1578963734.298 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-01-14T01:02:14.299Z,1578963734.299 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-01-14T01:02:14.607Z,1578963734.607 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-01-14T01:02:14.607Z,1578963734.607 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-01-14T01:02:14.687Z,1578963734.687 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-01-14T01:02:14.790Z,1578963734.790 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-01-14T01:02:14.790Z,1578963734.790 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-01-14T01:02:15.451Z,1578963735.451 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-01-14T01:02:15.451Z,1578963735.451 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-01-14T01:02:15.872Z,1578963735.872 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-01-14T01:02:15.874Z,1578963735.874 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-01-14T01:02:15.874Z,1578963735.874 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-01-14T01:02:16.084Z,1578963736.084 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-01-14T01:02:16.189Z,1578963736.189 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-01-14T01:02:16.287Z,1578963736.287 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-01-14T01:02:16.514Z,1578963736.514 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-01-14T01:02:16.515Z,1578963736.515 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-01-14T01:02:16.598Z,1578963736.598 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-01-14T01:02:16.711Z,1578963736.711 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-01-14T01:02:16.996Z,1578963736.996 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-01-14T01:02:17.204Z,1578963737.204 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-01-14T01:02:17.427Z,1578963737.427 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-01-14T01:02:17.709Z,1578963737.709 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-01-14T01:02:17.963Z,1578963737.963 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2020-01-14T01:02:17.963Z,1578963737.963 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-01-14T01:02:17.977Z,1578963737.977 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-01-14T01:02:18.266Z,1578963738.266 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-01-14T01:02:18.272Z,1578963738.272 [AHRS_M2](INFO): created writer for : platform_orientation
2020-01-14T01:02:18.273Z,1578963738.273 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-01-14T01:02:18.279Z,1578963738.279 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-01-14T01:02:18.279Z,1578963738.279 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-01-14T01:02:18.284Z,1578963738.284 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-01-14T01:02:18.285Z,1578963738.285 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-01-14T01:02:18.290Z,1578963738.290 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-01-14T01:02:18.361Z,1578963738.361 [AHRS_M2] Loaded
2020-01-14T01:02:18.361Z,1578963738.361 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-01-14T01:02:18.438Z,1578963738.438 [DataOverHttps] Loaded
2020-01-14T01:02:18.438Z,1578963738.438 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-01-14T01:02:18.439Z,1578963738.439 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4076F4E0
2020-01-14T01:02:18.439Z,1578963738.439 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8589
2020-01-14T01:02:18.464Z,1578963738.464 [DDM] Loaded
2020-01-14T01:02:18.464Z,1578963738.464 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-01-14T01:02:18.477Z,1578963738.477 [Depth_Keller] Loaded
2020-01-14T01:02:18.477Z,1578963738.477 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-01-14T01:02:18.482Z,1578963738.482 [DropWeight] Loaded
2020-01-14T01:02:18.482Z,1578963738.482 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-01-14T01:02:18.544Z,1578963738.544 [DUSBL_Hydroid] Loaded
2020-01-14T01:02:18.545Z,1578963738.545 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-01-14T01:02:18.592Z,1578963738.592 [Micromodem] Loaded
2020-01-14T01:02:18.592Z,1578963738.592 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-01-14T01:02:18.690Z,1578963738.690 [NAL9602] Loaded
2020-01-14T01:02:18.690Z,1578963738.690 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-01-14T01:02:18.727Z,1578963738.727 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2020-01-14T01:02:18.728Z,1578963738.728 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2020-01-14T01:02:18.729Z,1578963738.729 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2020-01-14T01:02:18.729Z,1578963738.729 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2020-01-14T01:02:18.730Z,1578963738.730 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2020-01-14T01:02:18.730Z,1578963738.730 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2020-01-14T01:02:18.731Z,1578963738.731 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2020-01-14T01:02:18.731Z,1578963738.731 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2020-01-14T01:02:18.732Z,1578963738.732 [Onboard] Loaded
2020-01-14T01:02:18.732Z,1578963738.732 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-01-14T01:02:18.738Z,1578963738.738 [PowerOnly] Loaded
2020-01-14T01:02:18.738Z,1578963738.738 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-01-14T01:02:18.745Z,1578963738.745 [Radio_Surface] Loaded
2020-01-14T01:02:18.745Z,1578963738.745 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-01-14T01:02:18.746Z,1578963738.746 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4079F4E0
2020-01-14T01:02:18.746Z,1578963738.746 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8590
2020-01-14T01:02:18.790Z,1578963738.790 [RDI_Pathfinder] Loaded
2020-01-14T01:02:18.791Z,1578963738.791 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-01-14T01:02:21.146Z,1578963741.146 [BPC1] Loaded
2020-01-14T01:02:21.147Z,1578963741.147 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-01-14T01:02:21.147Z,1578963741.147 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-01-14T01:02:21.148Z,1578963741.148 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-01-14T01:02:21.173Z,1578963741.173 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-01-14T01:02:21.173Z,1578963741.173 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-01-14T01:02:21.389Z,1578963741.389 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-01-14T01:02:21.389Z,1578963741.389 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-01-14T01:02:21.434Z,1578963741.434 [NavChart] Loaded
2020-01-14T01:02:21.435Z,1578963741.435 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-01-14T01:02:21.439Z,1578963741.439 [UniversalFixResidualReporter] Loaded
2020-01-14T01:02:21.439Z,1578963741.439 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-01-14T01:02:21.439Z,1578963741.439 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-01-14T01:02:21.440Z,1578963741.440 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-01-14T01:02:21.658Z,1578963741.658 [BuoyancyServo] Loaded
2020-01-14T01:02:21.659Z,1578963741.659 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-01-14T01:02:21.691Z,1578963741.691 [ElevatorServo] Loaded
2020-01-14T01:02:21.691Z,1578963741.691 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-01-14T01:02:21.739Z,1578963741.739 [MassServo] Loaded
2020-01-14T01:02:21.739Z,1578963741.739 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-01-14T01:02:21.755Z,1578963741.755 [RudderServo] Loaded
2020-01-14T01:02:21.755Z,1578963741.755 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-01-14T01:02:21.786Z,1578963741.786 [ThrusterServo] Loaded
2020-01-14T01:02:21.786Z,1578963741.786 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-01-14T01:02:21.786Z,1578963741.786 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-01-14T01:02:21.787Z,1578963741.787 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-01-14T01:02:22.464Z,1578963742.464 [CTD_NeilBrown] Loaded
2020-01-14T01:02:22.464Z,1578963742.464 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2020-01-14T01:02:22.485Z,1578963742.485 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408F34E0
2020-01-14T01:02:22.485Z,1578963742.485 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 8596
2020-01-14T01:02:22.528Z,1578963742.528 [PAR_Licor] Loaded
2020-01-14T01:02:22.529Z,1578963742.529 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-01-14T01:02:22.632Z,1578963742.632 [WetLabsSeaOWL_UV_A] Loaded
2020-01-14T01:02:22.633Z,1578963742.633 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-01-14T01:02:22.649Z,1578963742.649 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409234E0
2020-01-14T01:02:22.650Z,1578963742.650 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 8597
2020-01-14T01:02:22.650Z,1578963742.650 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-01-14T01:02:22.651Z,1578963742.651 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-01-14T01:02:23.429Z,1578963743.429 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-01-14T01:02:23.430Z,1578963743.430 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-01-14T01:02:23.515Z,1578963743.515 [DepthRateCalculator] Loaded
2020-01-14T01:02:23.516Z,1578963743.516 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-01-14T01:02:23.542Z,1578963743.542 [PitchRateCalculator] Loaded
2020-01-14T01:02:23.542Z,1578963743.542 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-01-14T01:02:23.565Z,1578963743.565 [SpeedCalculator] Loaded
2020-01-14T01:02:23.566Z,1578963743.566 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-01-14T01:02:23.619Z,1578963743.619 [TempGradientCalculator] Loaded
2020-01-14T01:02:23.620Z,1578963743.620 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-01-14T01:02:23.646Z,1578963743.646 [YawRateCalculator] Loaded
2020-01-14T01:02:23.646Z,1578963743.646 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-01-14T01:02:23.735Z,1578963743.735 [ElevatorOffsetCalculator] Loaded
2020-01-14T01:02:23.736Z,1578963743.736 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-01-14T01:02:23.736Z,1578963743.736 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-01-14T01:02:23.753Z,1578963743.753 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-01-14T01:02:24.071Z,1578963744.071 [SBIT](DEBUG): Construct Startup Built In Test.
2020-01-14T01:02:24.120Z,1578963744.120 [SBIT] Loaded
2020-01-14T01:02:24.133Z,1578963744.133 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-01-14T01:02:24.133Z,1578963744.133 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-01-14T01:02:24.166Z,1578963744.166 [IBIT] Loaded
2020-01-14T01:02:24.167Z,1578963744.167 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-01-14T01:02:24.170Z,1578963744.170 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-01-14T01:02:24.514Z,1578963744.514 [CBIT] Loaded
2020-01-14T01:02:24.515Z,1578963744.515 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-01-14T01:02:24.515Z,1578963744.515 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-01-14T01:02:24.516Z,1578963744.516 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-01-14T01:02:24.698Z,1578963744.698 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-01-14T01:02:24.699Z,1578963744.699 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-01-14T01:02:24.927Z,1578963744.927 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-01-14T01:02:24.927Z,1578963744.927 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-01-14T01:02:25.043Z,1578963745.043 [VerticalControl](DEBUG): Construct VerticalControl.
2020-01-14T01:02:25.265Z,1578963745.265 [VerticalControl] Loaded
2020-01-14T01:02:25.265Z,1578963745.265 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-01-14T01:02:25.266Z,1578963745.266 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-01-14T01:02:25.428Z,1578963745.428 [HorizontalControl] Loaded
2020-01-14T01:02:25.442Z,1578963745.442 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-01-14T01:02:25.443Z,1578963745.443 [SpeedControl](DEBUG): Construct SpeedControl.
2020-01-14T01:02:25.445Z,1578963745.445 [SpeedControl] Loaded
2020-01-14T01:02:25.445Z,1578963745.445 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-01-14T01:02:25.446Z,1578963745.446 [LoopControl](DEBUG): Construct LoopControl.
2020-01-14T01:02:25.446Z,1578963745.446 [LoopControl] Loaded
2020-01-14T01:02:25.447Z,1578963745.447 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-01-14T01:02:25.447Z,1578963745.447 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-01-14T01:02:25.448Z,1578963745.448 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-01-14T01:02:25.531Z,1578963745.531 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-01-14T01:02:25.535Z,1578963745.535 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-01-14T01:02:25.536Z,1578963745.536 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-01-14T01:02:25.555Z,1578963745.555 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-01-14T01:02:25.577Z,1578963745.577 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B024E0
2020-01-14T01:02:25.577Z,1578963745.577 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8598
2020-01-14T01:02:25.582Z,1578963745.582 [Supervisor](INFO): Main Thread ID is 8506
2020-01-14T01:02:25.582Z,1578963745.582 [Supervisor](DEBUG): Running supervisor.
2020-01-14T01:02:25.583Z,1578963745.583 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8599
2020-01-14T01:02:25.605Z,1578963745.605 [controlThread ThreadHandler](INFO): Handler Thread ID is 8600
2020-01-14T01:02:25.606Z,1578963745.606 [controlThread](DEBUG): Initializing ControlThread
2020-01-14T01:02:25.612Z,1578963745.612 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-01-14T01:02:25.612Z,1578963745.612 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-01-14T01:02:25.617Z,1578963745.617 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-01-14T01:02:25.617Z,1578963745.617 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-01-14T01:02:25.618Z,1578963745.618 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-01-14T01:02:25.618Z,1578963745.618 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-01-14T01:02:25.618Z,1578963745.618 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-01-14T01:02:25.619Z,1578963745.619 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-01-14T01:02:25.619Z,1578963745.619 [SBIT](INFO): Initialize SBIT Component.
2020-01-14T01:02:25.620Z,1578963745.620 [SBIT](IMPORTANT): git: 2019-12-03-34-g1420f60
2020-01-14T01:02:25.620Z,1578963745.620 [SBIT](INFO): git hash: 1420f6075bfd9e2317cf67bc08ebc33a04e592a5
2020-01-14T01:02:25.620Z,1578963745.620 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-01-14T01:02:25.621Z,1578963745.621 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #2 PREEMPT Wed May 15 08:34:03 PDT 2019
2020-01-14T01:02:25.622Z,1578963745.622 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-01-14T01:02:25.623Z,1578963745.623 [IBIT](INFO): Initialize IBIT Component.
2020-01-14T01:02:25.624Z,1578963745.624 [CBIT](DEBUG): Initialize CBIT Component.
2020-01-14T01:02:25.629Z,1578963745.629 [logger ThreadHandler](INFO): Handler Thread ID is 8601
2020-01-14T01:02:25.637Z,1578963745.637 [CBIT](DEBUG): Initialized mux pins.
2020-01-14T01:02:25.637Z,1578963745.637 [CBIT](DEBUG): Initializing the watchdog timer.
2020-01-14T01:02:25.661Z,1578963745.661 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8602
2020-01-14T01:02:25.662Z,1578963745.662 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-01-14T01:02:25.665Z,1578963745.665 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-01-14T01:02:25.665Z,1578963745.665 [CBIT](DEBUG): Initializing heartbeat.
2020-01-14T01:02:25.677Z,1578963745.677 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8603
2020-01-14T01:02:25.702Z,1578963745.702 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 8604
2020-01-14T01:02:25.702Z,1578963745.702 [CTD_NeilBrown](INFO): Powering down
2020-01-14T01:02:25.737Z,1578963745.737 [CBIT](DEBUG): Deactivating GF circuits.
2020-01-14T01:02:25.737Z,1578963745.737 [CBIT](DEBUG): Deactivating emergency mode.
2020-01-14T01:02:25.738Z,1578963745.738 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 8605
2020-01-14T01:02:25.738Z,1578963745.738 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-14T01:02:25.769Z,1578963745.769 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8606
2020-01-14T01:02:25.772Z,1578963745.772 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-01-14T01:02:25.773Z,1578963745.773 [CBIT](DEBUG): Backplane powered.
2020-01-14T01:02:25.773Z,1578963745.773 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-01-14T01:02:25.775Z,1578963745.775 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-01-14T01:02:25.776Z,1578963745.776 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-01-14T01:02:25.776Z,1578963745.776 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-01-14T01:02:25.777Z,1578963745.777 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-01-14T01:02:25.772Z,1578963745.772 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-01-14T01:02:25.781Z,1578963745.781 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-01-14T01:02:25.781Z,1578963745.781 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-01-14T01:02:25.781Z,1578963745.781 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-01-14T01:02:25.782Z,1578963745.782 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-01-14T01:02:25.782Z,1578963745.782 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-01-14T01:02:25.782Z,1578963745.782 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-01-14T01:02:25.782Z,1578963745.782 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-01-14T01:02:25.782Z,1578963745.782 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-01-14T01:02:25.782Z,1578963745.782 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-01-14T01:02:25.783Z,1578963745.783 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-01-14T01:02:25.783Z,1578963745.783 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-01-14T01:02:25.783Z,1578963745.783 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-01-14T01:02:25.783Z,1578963745.783 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-01-14T01:02:25.783Z,1578963745.783 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-01-14T01:02:25.795Z,1578963745.795 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-14T01:02:25.842Z,1578963745.842 [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-01-14T01:02:25.843Z,1578963745.843 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-01-14T01:02:25.943Z,1578963745.943 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-01-14T01:02:25.944Z,1578963745.944 [Default:A.Wait](DEBUG): Construct Wait.
2020-01-14T01:02:25.946Z,1578963745.946 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-14T01:02:26.013Z,1578963746.013 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-01-14T01:02:26.016Z,1578963746.016 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-01-14T01:02:26.045Z,1578963746.045 [Default:E.Execute](DEBUG): Construct Execute.
2020-01-14T01:02:26.049Z,1578963746.049 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

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

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

</Mission>


2020-01-14T01:02:26.069Z,1578963746.069 [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,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-01-14T01:02:26.081Z,1578963746.081 [Radio_Surface](INFO): Powering up
2020-01-14T01:02:26.082Z,1578963746.082 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-14T01:02:26.129Z,1578963746.129 [DDM](INFO): Powering up
2020-01-14T01:02:26.129Z,1578963746.129 [DDM](DEBUG): Initializing DDM.
2020-01-14T01:02:26.201Z,1578963746.201 [DUSBL_Hydroid](INFO): Powering up
2020-01-14T01:02:26.202Z,1578963746.202 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-14T01:02:26.442Z,1578963746.442 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-01-14T01:02:26.449Z,1578963746.449 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-01-14T01:02:26.450Z,1578963746.450 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-01-14T01:02:26.457Z,1578963746.457 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-01-14T01:02:26.458Z,1578963746.458 [MassServo](DEBUG): Initializing EZServoServo.
2020-01-14T01:02:26.473Z,1578963746.473 [MassServo](DEBUG): Initializing MassServo.
2020-01-14T01:02:26.474Z,1578963746.474 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-14T01:02:26.486Z,1578963746.486 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-14T01:02:26.486Z,1578963746.486 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-01-14T01:02:26.497Z,1578963746.497 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-01-14T01:02:26.577Z,1578963746.577 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-01-14T01:02:26.577Z,1578963746.577 [DropWeight] Hardware Fault, FailCount= 1
2020-01-14T01:02:26.577Z,1578963746.577 [DropWeight](ERROR): Hardware Fault
2020-01-14T01:02:26.579Z,1578963746.579 [Micromodem](INFO): Powering up
2020-01-14T01:02:26.579Z,1578963746.579 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-14T01:02:26.652Z,1578963746.652 [CommandLine](FAULT): Scheduling is paused
2020-01-14T01:02:26.653Z,1578963746.653 [CBIT](INFO): Critical error at 20200114T010226
2020-01-14T01:02:26.653Z,1578963746.653 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-01-14T01:02:26.655Z,1578963746.655 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-01-14T01:02:26.656Z,1578963746.656 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-01-14T01:02:27.297Z,1578963747.297 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-01-14T01:02:27.297Z,1578963747.297 [RudderServo](FAULT): Rudder failed to initialize
2020-01-14T01:02:27.297Z,1578963747.297 [RudderServo] Communications Fault, FailCount= 1
2020-01-14T01:02:27.297Z,1578963747.297 [RudderServo](ERROR): Communications Fault
2020-01-14T01:02:27.430Z,1578963747.430 [CBIT](INFO): Critical error at 20200114T010226
2020-01-14T01:02:27.432Z,1578963747.432 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-01-14T01:02:27.650Z,1578963747.650 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-14T01:02:27.650Z,1578963747.650 [RudderServo](INFO): Powering down
2020-01-14T01:02:28.325Z,1578963748.325 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-14T01:02:28.446Z,1578963748.446 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-14T01:02:28.450Z,1578963748.450 [CBIT](INFO): Clearing failed state for component RudderServo
2020-01-14T01:02:28.450Z,1578963748.450 [RudderServo] No Fault, FailCount= 1
2020-01-14T01:02:31.522Z,1578963751.522 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-14T01:02:31.923Z,1578963751.923 [Micromodem](INFO): Nmea in: $CATMG,2020-01-14T01:02:31.332090Z,RTC,RTC*5E

2020-01-14T01:02:31.924Z,1578963751.924 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-14T01:02:31.332090Z,RTC,RTC*5E

2020-01-14T01:02:32.363Z,1578963752.363 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-14T01:02:32.363Z,1578963752.363 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-14T01:02:32.759Z,1578963752.759 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-01-14T01:02:32.759Z,1578963752.759 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-01-14T01:02:33.167Z,1578963753.167 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-01-14T01:02:33.167Z,1578963753.167 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-01-14T01:02:33.571Z,1578963753.571 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-01-14T01:02:33.571Z,1578963753.571 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-14T01:02:33.979Z,1578963753.979 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-14T01:02:33.979Z,1578963753.979 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-14T01:02:34.375Z,1578963754.375 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-14T01:02:34.375Z,1578963754.375 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-14T01:02:34.779Z,1578963754.779 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-14T01:02:34.779Z,1578963754.779 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-14T01:02:35.160Z,1578963755.160 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-14T01:02:35.161Z,1578963755.161 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,14,01,02,36*46

2020-01-14T01:02:35.585Z,1578963755.585 [Micromodem](INFO): Nmea in: $CACLK,2020,1,14,1,2,36*74

2020-01-14T01:02:36.374Z,1578963756.374 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-14T01:02:37Z*75

2020-01-14T01:02:36.445Z,1578963756.445 [Micromodem](INFO): Nmea in: $CATMG,2020-01-14T01:02:37.026381Z,USER_CMD,RTC*1C

2020-01-14T01:02:36.775Z,1578963756.775 [DDM](INFO): Dynamic Docking Module:…
2020-01-14T01:02:39.632Z,1578963759.632 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-14T01:02:44.046Z,1578963764.046 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-01-14T01:02:44.861Z,1578963764.861 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004484
2020-01-14T01:02:48.887Z,1578963768.887 [NAL9602](INFO): Powering up NAL9602
2020-01-14T01:02:59.795Z,1578963779.795 [NAL9602](INFO): NAL9602 initialized
2020-01-14T01:03:05.126Z,1578963785.126 [SBIT](IMPORTANT): Beginning Startup BIT
2020-01-14T01:03:05.158Z,1578963785.158 [CBIT](IMPORTANT): Beginning ground fault scan
2020-01-14T01:03:12.584Z,1578963792.584 [RDI_Pathfinder](ERROR): Failed to parse:
:   +0.00,        +0.00,        +0.00,   0.00, 32.00
2020-01-14T01:03:15.906Z,1578963795.906 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.012494
CHAN A1 (24V): -0.029205
CHAN A2 (12V): -0.007167
CHAN A3 (5V): -0.002127
CHAN B0 (3.3V): 0.000097
CHAN B1 (3.15aV): 0.000145
CHAN B2 (3.15bV): 0.000022
CHAN B3 (GND): 0.002259
OPEN: 0.005430
Full Scale Calc: 4.765 mA, -1.589 mA
2020-01-14T01:03:32.884Z,1578963812.884 [NAL9602](INFO): SBD MO Status=0, MOMSN=10160, MT Status=0, MTMSN=0
2020-01-14T01:03:32.884Z,1578963812.884 [NAL9602](INFO): No messages in MT queue
2020-01-14T01:03:46.661Z,1578963826.661 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2020-01-14T01:03:59.353Z,1578963839.353 [SBIT](IMPORTANT): SBIT PASSED
2020-01-14T01:03:59.410Z,1578963839.410 [CommandLine](IMPORTANT): got command configSet list
2020-01-14T01:03:59.411Z,1578963839.411 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-01-14T01:03:59.411Z,1578963839.411 [CommandLine](IMPORTANT): DDM.loadAtStartup=1 bool;

2020-01-14T01:03:59.412Z,1578963839.412 [CommandLine](IMPORTANT): DDM.verbosity=3 count;

2020-01-14T01:03:59.412Z,1578963839.412 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=25 count;

2020-01-14T01:03:59.412Z,1578963839.412 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout=40 millisecond;

2020-01-14T01:03:59.412Z,1578963839.412 [CommandLine](IMPORTANT): Express linearApproximation DDM.DDMMode 1.000000 count;

2020-01-14T01:03:59.412Z,1578963839.412 [CommandLine](IMPORTANT): Express all DDM.cablePresent;

2020-01-14T01:03:59.412Z,1578963839.412 [CommandLine](IMPORTANT): HorizontalControl.kiHeading=0.002 reciprocal_second;

2020-01-14T01:03:59.412Z,1578963839.412 [CommandLine](IMPORTANT): HorizontalControl.kpHeading=0.8 none;

2020-01-14T01:03:59.412Z,1578963839.412 [CommandLine](IMPORTANT): HorizontalControl.rudDeadband=0.05 degree;

2020-01-14T01:03:59.412Z,1578963839.412 [CommandLine](IMPORTANT): Micromodem.destinationAddress=12 enum;

2020-01-14T01:03:59.413Z,1578963839.413 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold=0 meter;

2020-01-14T01:03:59.413Z,1578963839.413 [CommandLine](IMPORTANT): PowerOnly.sampleTime=90 second;

2020-01-14T01:03:59.414Z,1578963839.414 [CommandLine](IMPORTANT): RudderServo.deviationAngle=0.0001 degree;

2020-01-14T01:03:59.414Z,1578963839.414 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter;

2020-01-14T01:03:59.414Z,1578963839.414 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter;

2020-01-14T01:03:59.788Z,1578963839.788 [MissionManager](IMPORTANT): Started mission Startup
2020-01-14T01:03:59.788Z,1578963839.788 [Startup] Running Loop=1
2020-01-14T01:03:59.788Z,1578963839.788 [Startup](DEBUG): Aggregate::initialize Startup
2020-01-14T01:03:59.788Z,1578963839.788 [Startup:A.GoToSurface] Running Loop=1
2020-01-14T01:03:59.788Z,1578963839.788 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-14T01:03:59.789Z,1578963839.789 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-14T01:03:59.790Z,1578963839.790 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-14T01:03:59.790Z,1578963839.790 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-14T01:03:59.790Z,1578963839.790 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-14T01:03:59.791Z,1578963839.791 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-14T01:03:59.793Z,1578963839.793 [Startup:StartupSatComms] Running Loop=1
2020-01-14T01:03:59.793Z,1578963839.793 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-01-14T01:03:59.793Z,1578963839.793 [Startup:StartupSatComms:A] Running Loop=1
2020-01-14T01:04:00.156Z,1578963840.156 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-01-14T01:04:59.886Z,1578963899.886 [Startup:StartupSatComms:A](INFO): Timed out from 2020-01-14T01:03:59.8Z
2020-01-14T01:04:59.886Z,1578963899.886 [Startup:StartupSatComms:A] Stopped
2020-01-14T01:04:59.886Z,1578963899.886 [Startup:StartupSatComms:B] Running Loop=1
2020-01-14T01:05:00.295Z,1578963900.295 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-14T01:05:01.418Z,1578963901.418 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 3F0C1D5E0D0000005D00000400820000000000000000151D80030C5669BF7A7A157245733386EAAB516916443473165C71AE6DBBC632E9FCD653613CB9B7D6A5
2020-01-14T01:05:01.418Z,1578963901.418 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 47A06224619FC288B646560B49FD6DB52915036CEFDCBE50C0216FA6654DDB3E8E6793EF4D07AF951A8C3E8EB360AB97E1C26FCB8F783492691FB4E1AC1B4B6A
2020-01-14T01:05:01.419Z,1578963901.419 [Micromodem](INFO): Outgoing frame #3, 17 bytes: 7D34ED6B71E47B6E348DDAB4AB377E6A00
2020-01-14T01:05:01.419Z,1578963901.419 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-14T01:05:01.995Z,1578963901.995 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-14T01:05:02.411Z,1578963902.411 [Micromodem](INFO): Nmea in: $CADRQ,010502,1,12,0,64,1*72

2020-01-14T01:05:02.411Z,1578963902.411 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,3F0C1D5E0D0000005D00000400820000000000000000151D80030C5669BF7A7A157245733386EAAB516916443473165C71AE6DBBC632E9FCD653613CB9B7D6A5*47

2020-01-14T01:05:02.795Z,1578963902.795 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-14T01:05:03.199Z,1578963903.199 [Micromodem](INFO): Nmea in: $CADRQ,010503,1,12,0,64,2*70

2020-01-14T01:05:03.199Z,1578963903.199 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,47A06224619FC288B646560B49FD6DB52915036CEFDCBE50C0216FA6654DDB3E8E6793EF4D07AF951A8C3E8EB360AB97E1C26FCB8F783492691FB4E1AC1B4B6A*44

2020-01-14T01:05:03.607Z,1578963903.607 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-14T01:05:04.003Z,1578963904.003 [Micromodem](INFO): Nmea in: $CADRQ,010504,1,12,0,64,3*76

2020-01-14T01:05:04.003Z,1578963904.003 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,7D34ED6B71E47B6E348DDAB4AB377E6A00*31

2020-01-14T01:05:04.415Z,1578963904.415 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,17*4F

2020-01-14T01:05:04.815Z,1578963904.815 [Micromodem](INFO): Nmea in: $CATXP,145*42

2020-01-14T01:05:08.172Z,1578963908.172 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20200114T003303/Courier0013.lzma
2020-01-14T01:05:08.455Z,1578963908.455 [Micromodem](INFO): Nmea in: $CATXF,145*54

2020-01-14T01:05:08.860Z,1578963908.860 [Micromodem](INFO): Nmea in: $CAXST,6,20200114,010504.932017,3,0,200,4000,10000,1,1,12,1,3,3,3,145*7A

2020-01-14T01:05:10.179Z,1578963910.179 [DataOverHttps](INFO): Moved sent file to Logs/20200114T003303/Courier0013.lzma.bak
2020-01-14T01:05:10.179Z,1578963910.179 [DataOverHttps](INFO): SBD MOMSN=12192236
2020-01-14T01:05:11.683Z,1578963911.683 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-14T01:05:12.087Z,1578963912.087 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-14T01:05:12.491Z,1578963912.491 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-14T01:05:12.492Z,1578963912.492 [Micromodem](INFO): Sent 137 bytes from file Logs/20200114T003303/Courier0013.lzma.parts
2020-01-14T01:05:12.492Z,1578963912.492 [Micromodem](INFO): Packets left to send: 0
2020-01-14T01:05:12.904Z,1578963912.904 [Micromodem](INFO): Nmea in: $CACST,6,0,20200114010511.288568,06,221,23,0132,0150,119,02,02,00,04,1,012,001,0,4,1,0,150,19.5,7.00,19,-4.00,-01,0.01,31,10000,4000*5B

2020-01-14T01:05:13.816Z,1578963913.816 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 13131D5E000001005D00000400610200000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6
2020-01-14T01:05:13.816Z,1578963913.816 [Micromodem](INFO): Outgoing frame #2, 64 bytes: AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE367918
2020-01-14T01:05:13.817Z,1578963913.817 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 5E009F0E4791009846F37AD5B4E8735BA1B2D3D0153A413775DF7B70FAE8596BCD964621EC6580153DB6A77488311FFF42DF213A2450741B7E2400409FD16CC2
2020-01-14T01:05:13.817Z,1578963913.817 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-14T01:05:14.824Z,1578963914.824 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-14T01:05:15.215Z,1578963915.215 [Micromodem](INFO): Nmea in: $CADRQ,010514,1,12,0,64,1*75

2020-01-14T01:05:15.215Z,1578963915.215 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,13131D5E000001005D00000400610200000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6*4D

2020-01-14T01:05:15.631Z,1578963915.631 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-14T01:05:16.023Z,1578963916.023 [Micromodem](INFO): Nmea in: $CADRQ,010516,1,12,0,64,2*74

2020-01-14T01:05:16.023Z,1578963916.023 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE367918*3A

2020-01-14T01:05:16.427Z,1578963916.427 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-14T01:05:16.831Z,1578963916.831 [Micromodem](INFO): Nmea in: $CADRQ,010516,1,12,0,64,3*75

2020-01-14T01:05:16.831Z,1578963916.831 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,5E009F0E4791009846F37AD5B4E8735BA1B2D3D0153A413775DF7B70FAE8596BCD964621EC6580153DB6A77488311FFF42DF213A2450741B7E2400409FD16CC2*4C

2020-01-14T01:05:17.243Z,1578963917.243 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-14T01:05:17.638Z,1578963917.638 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-14T01:05:21.275Z,1578963921.275 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-14T01:05:21.684Z,1578963921.684 [Micromodem](INFO): Nmea in: $CAXST,6,20200114,010517.813736,3,0,200,4000,10000,1,1,12,1,3,3,3,192*74

2020-01-14T01:05:25.742Z,1578963925.742 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC, Data Header,2*00

2020-01-14T01:05:25.823Z,1578963925.823 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-01-14T01:05:25.823Z,1578963925.823 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-14T01:05:25.865Z,1578963925.865 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-14T01:05:26.130Z,1578963926.130 [Micromodem](INFO): Nmea in: $CACST,6,1,20200114010524.662167,06,146,15,0102,0150,119,02,02,01,02,1,-01,-01,2,3,1,0,150,17.9,3.00,00,0.00,-01,0.25,32,10000,4000*71

2020-01-14T01:05:26.236Z,1578963926.236 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-14T01:05:26.236Z,1578963926.236 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-01-14T01:05:28.490Z,1578963928.490 [DataOverHttps](INFO): Sending 320 bytes from file Logs/20200114T010211/Courier0000.lzma
2020-01-14T01:05:30.494Z,1578963930.494 [DataOverHttps](INFO): Moved sent file to Logs/20200114T010211/Courier0000.lzma.bak
2020-01-14T01:05:30.494Z,1578963930.494 [DataOverHttps](INFO): SBD MOMSN=12192241
2020-01-14T01:05:49.204Z,1578963949.204 [DataOverHttps](INFO): Sending 1421 bytes from file Logs/20200110T182802/Express0001.lzma
2020-01-14T01:05:51.206Z,1578963951.206 [DataOverHttps](INFO): Moved sent file to Logs/20200110T182802/Express0001.lzma.bak
2020-01-14T01:05:51.207Z,1578963951.207 [DataOverHttps](INFO): SBD MOMSN=12192246
2020-01-14T01:06:00.101Z,1578963960.101 [Startup:StartupSatComms:B](INFO): Timed out from 2020-01-14T01:04:59.9Z
2020-01-14T01:06:00.101Z,1578963960.101 [Startup:StartupSatComms:B] Stopped
2020-01-14T01:06:00.101Z,1578963960.101 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-01-14T01:06:00.101Z,1578963960.101 [Startup:StartupSatComms] Stopped
2020-01-14T01:06:00.102Z,1578963960.102 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-01-14T01:06:00.102Z,1578963960.102 [Startup](INFO): Completed Startup
2020-01-14T01:06:00.102Z,1578963960.102 [MissionManager](INFO): Startup is completed.
2020-01-14T01:06:00.102Z,1578963960.102 [MissionManager](INFO): Uninitializing Mission Startup
2020-01-14T01:06:00.103Z,1578963960.103 [Startup] Stopped
2020-01-14T01:06:00.103Z,1578963960.103 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-01-14T01:06:00.103Z,1578963960.103 [Startup:A.GoToSurface] Stopped
2020-01-14T01:06:00.103Z,1578963960.103 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-14T01:06:00.554Z,1578963960.554 [MissionManager](IMPORTANT): Started mission Default
2020-01-14T01:06:00.555Z,1578963960.555 [Default] Running Loop=1
2020-01-14T01:06:00.555Z,1578963960.555 [Default](DEBUG): Aggregate::initialize Default
2020-01-14T01:06:00.555Z,1578963960.555 [Default:B.GoToSurface] Running Loop=1
2020-01-14T01:06:00.555Z,1578963960.555 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-14T01:06:00.555Z,1578963960.555 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-14T01:06:00.555Z,1578963960.555 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-14T01:06:00.556Z,1578963960.556 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-14T01:06:00.556Z,1578963960.556 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-14T01:06:00.556Z,1578963960.556 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-14T01:06:00.557Z,1578963960.557 [Default:A.Wait] Running Loop=1
2020-01-14T01:06:00.557Z,1578963960.557 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-14T01:06:09.411Z,1578963969.411 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-14T01:06:09.411Z,1578963969.411 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-14T01:06:09.824Z,1578963969.824 [DataOverHttps](INFO): Sending 1393 bytes from file Logs/20200110T182930/Express0001.lzma
2020-01-14T01:06:11.826Z,1578963971.826 [DataOverHttps](INFO): Moved sent file to Logs/20200110T182930/Express0001.lzma.bak
2020-01-14T01:06:11.826Z,1578963971.826 [DataOverHttps](INFO): SBD MOMSN=12192294
2020-01-14T01:06:13.859Z,1578963973.859 [Default:A.Wait](INFO): Done Waiting.
2020-01-14T01:06:13.859Z,1578963973.859 [Default:A.Wait] Stopped
2020-01-14T01:06:13.859Z,1578963973.859 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-14T01:06:14.311Z,1578963974.311 [Default:CheckIn] Running Loop=1
2020-01-14T01:06:14.312Z,1578963974.312 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-14T01:06:14.312Z,1578963974.312 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-14T01:06:14.677Z,1578963974.677 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-01-14T01:08:26.827Z,1578964106.827 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-01-14T01:08:26.827Z,1578964106.827 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-14T01:08:26.845Z,1578964106.845 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-14T01:08:27.294Z,1578964107.294 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-14T01:08:27.294Z,1578964107.294 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-01-14T01:09:50.033Z,1578964190.033 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-01-14T01:09:50.036Z,1578964190.036 [BPC1](INFO): Received data from all battery sticks.
2020-01-14T01:10:03.386Z,1578964203.386 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-14T01:10:03.387Z,1578964203.387 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-14T01:10:22.760Z,1578964222.760 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-14T01:10:22.760Z,1578964222.760 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-14T01:10:37.273Z,1578964237.273 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,011037.00,A,3648.15528,N,12147.29933,W,0.797,42.52,140120,,,A*47
2020-01-14T01:10:37.276Z,1578964237.276 [NAL9602](INFO): GPS fix at 20200114T011037: (36.802588, -121.788322)
2020-01-14T01:10:37.340Z,1578964237.340 [Default:CheckIn:Read_GPS] Stopped
2020-01-14T01:10:37.340Z,1578964237.340 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-14T01:10:37.769Z,1578964237.769 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-14T01:10:38.077Z,1578964238.077 [Micromodem](ERROR): Buffer fill timout failure.
2020-01-14T01:10:39.774Z,1578964239.774 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 13131D5E040001005D00000400640400000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A
2020-01-14T01:10:39.774Z,1578964239.774 [Micromodem](INFO): Outgoing frame #2, 64 bytes: EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C42BBF445E54CD5F36943A3BDD9E89309E9017DB79DD009A333A47CF42F3BE4E4AD4301D597F
2020-01-14T01:10:39.775Z,1578964239.775 [Micromodem](INFO): Outgoing frame #3, 64 bytes: FFA3EC7FA24353C087A3A1B3BD161F92F69707865251F3B93C9772C25CD67B52231335EF84B8E92D5FFA3505415BCA8B6F49DB1D0DD4D95B0AEBEF6F0AF82124
2020-01-14T01:10:39.775Z,1578964239.775 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-14T01:10:40.029Z,1578964240.029 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-14T01:10:40.392Z,1578964240.392 [Micromodem](INFO): Nmea in: $CADRQ,011040,1,12,0,64,1*70

2020-01-14T01:10:40.397Z,1578964240.397 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,13131D5E040001005D00000400640400000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A*4E

2020-01-14T01:10:40.794Z,1578964240.794 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-14T01:10:41.194Z,1578964241.194 [Micromodem](INFO): Nmea in: $CADRQ,011041,1,12,0,64,2*72

2020-01-14T01:10:41.195Z,1578964241.195 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C42BBF445E54CD5F36943A3BDD9E89309E9017DB79DD009A333A47CF42F3BE4E4AD4301D597F*3D

2020-01-14T01:10:41.598Z,1578964241.598 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-14T01:10:41.994Z,1578964241.994 [Micromodem](INFO): Nmea in: $CADRQ,011042,1,12,0,64,3*70

2020-01-14T01:10:41.995Z,1578964241.995 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,FFA3EC7FA24353C087A3A1B3BD161F92F69707865251F3B93C9772C25CD67B52231335EF84B8E92D5FFA3505415BCA8B6F49DB1D0DD4D95B0AEBEF6F0AF82124*40

2020-01-14T01:10:42.398Z,1578964242.398 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-14T01:10:42.802Z,1578964242.802 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-14T01:10:46.442Z,1578964246.442 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-14T01:10:47.651Z,1578964247.651 [Micromodem](INFO): Nmea in: $CAXST,6,20200114,011042.976020,3,0,200,4000,10000,1,1,12,1,3,3,3,192*72

2020-01-14T01:10:48.781Z,1578964248.781 [DataOverHttps](INFO): Sending 356 bytes from file Logs/20200114T010211/Courier0004.lzma
2020-01-14T01:10:49.671Z,1578964249.671 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-14T01:10:50.079Z,1578964250.079 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-14T01:10:50.483Z,1578964250.483 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-14T01:10:50.483Z,1578964250.483 [Micromodem](INFO): Sent 184 bytes from file Logs/20200114T010211/Courier0004.lzma.parts
2020-01-14T01:10:50.483Z,1578964250.483 [Micromodem](INFO): Packets left to send: 1
2020-01-14T01:10:50.786Z,1578964250.786 [DataOverHttps](INFO): Moved sent file to Logs/20200114T010211/Courier0004.lzma.bak
2020-01-14T01:10:50.786Z,1578964250.786 [DataOverHttps](INFO): SBD MOMSN=12192342
2020-01-14T01:10:50.897Z,1578964250.897 [Micromodem](INFO): Nmea in: $CACST,6,0,20200114011049.380591,06,315,18,0134,0150,59,04,04,01,04,1,012,001,0,4,1,0,150,17.5,6.00,11,-3.00,-01,0.00,22,10000,4000*62

2020-01-14T01:10:51.589Z,1578964251.589 [Default:CheckIn:Read_Iridium] Stopped
2020-01-14T01:10:51.589Z,1578964251.589 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-14T01:10:51.589Z,1578964251.589 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-14T01:10:58.108Z,1578964258.108 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-14T01:10:58.108Z,1578964258.108 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20011318235180,35.0, -0.1,   0.0  0
2020-01-14T01:11:10.206Z,1578964270.206 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-14T01:11:19.542Z,1578964279.542 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2020-01-14T01:12:42.385Z,1578964362.385 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-14T01:12:42.386Z,1578964362.386 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,200113182536800.1,   0.0,1448.9,  0
2020-01-14T01:13:19.125Z,1578964399.125 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2020-01-14T01:14:00.754Z,1578964440.754 [CommandLine](IMPORTANT): got command restart application
2020-01-14T01:14:01.761Z,1578964441.761 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-14T01:14:01.761Z,1578964441.761 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-14T01:14:01.841Z,1578964441.841 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-01-14T01:14:01.841Z,1578964441.841 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-14T01:14:01.842Z,1578964441.842 [CommandLine](INFO): Join timeout helper Thread ID is 8636
2020-01-14T01:14:01.842Z,1578964441.842 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-01-14T01:14:01.843Z,1578964441.843 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-14T01:14:01.843Z,1578964441.843 [NavChartDb](INFO): Join timeout helper Thread ID is 8637
2020-01-14T01:14:02.225Z,1578964442.225 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-14T01:14:02.225Z,1578964442.225 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-14T01:14:02.229Z,1578964442.229 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-01-14T01:14:02.230Z,1578964442.230 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-14T01:14:02.231Z,1578964442.231 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 8638
2020-01-14T01:14:02.285Z,1578964442.285 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-14T01:14:02.285Z,1578964442.285 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-14T01:14:02.286Z,1578964442.286 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-14T01:14:02.289Z,1578964442.289 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2020-01-14T01:14:02.289Z,1578964442.289 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-14T01:14:02.289Z,1578964442.289 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 8639
2020-01-14T01:14:02.453Z,1578964442.453 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-14T01:14:02.453Z,1578964442.453 [CTD_NeilBrown](INFO): Powering down
2020-01-14T01:14:02.465Z,1578964442.465 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-14T01:14:02.473Z,1578964442.473 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-01-14T01:14:02.473Z,1578964442.473 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-14T01:14:02.473Z,1578964442.473 [Radio_Surface](INFO): Join timeout helper Thread ID is 8640
2020-01-14T01:14:02.625Z,1578964442.625 [Radio_Surface](INFO): Powering down
2020-01-14T01:14:02.626Z,1578964442.626 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-14T01:14:02.626Z,1578964442.626 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-14T01:14:02.634Z,1578964442.634 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-01-14T01:14:02.634Z,1578964442.634 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-14T01:14:02.634Z,1578964442.634 [DataOverHttps](INFO): Join timeout helper Thread ID is 8641
2020-01-14T01:14:03.121Z,1578964443.121 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-14T01:14:03.121Z,1578964443.121 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-14T01:14:03.142Z,1578964443.142 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-01-14T01:14:03.142Z,1578964443.142 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-14T01:14:03.143Z,1578964443.143 [logger](INFO): Join timeout helper Thread ID is 8642
2020-01-14T01:14:03.169Z,1578964443.169 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-14T01:14:03.169Z,1578964443.169 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-14T01:14:03.185Z,1578964443.185 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-01-14T01:14:03.186Z,1578964443.186 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-14T01:14:03.186Z,1578964443.186 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-01-14T01:14:03.186Z,1578964443.186 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-14T01:14:03.186Z,1578964443.186 [controlThread](INFO): Join timeout helper Thread ID is 8643
2020-01-14T01:14:03.509Z,1578964443.509 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-14T01:14:03.509Z,1578964443.509 [controlThread](DEBUG): Uninitializing ControlThread
2020-01-14T01:14:03.510Z,1578964443.510 [AHRS_M2](INFO): Powering down
2020-01-14T01:14:03.581Z,1578964443.581 [DDM](INFO): Powering down
2020-01-14T01:14:03.670Z,1578964443.670 [DUSBL_Hydroid](INFO): Powering down
2020-01-14T01:14:03.761Z,1578964443.761 [Micromodem](INFO): Powering down
2020-01-14T01:14:03.857Z,1578964443.857 [NAL9602](INFO): Powering down
2020-01-14T01:14:03.929Z,1578964443.929 [RDI_Pathfinder](INFO): Powering down
2020-01-14T01:14:03.930Z,1578964443.930 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-01-14T01:14:03.931Z,1578964443.931 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-01-14T01:14:03.932Z,1578964443.932 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-01-14T01:14:03.932Z,1578964443.932 [MissionManager](INFO): Uninitializing Mission Default
2020-01-14T01:14:03.932Z,1578964443.932 [Default] Stopped
2020-01-14T01:14:03.932Z,1578964443.932 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-14T01:14:03.932Z,1578964443.932 [Default:B.GoToSurface] Stopped
2020-01-14T01:14:03.933Z,1578964443.933 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-14T01:14:03.933Z,1578964443.933 [Default:CheckIn] Stopped
2020-01-14T01:14:03.933Z,1578964443.933 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-14T01:14:03.933Z,1578964443.933 [Default:CheckIn:C.Wait] Stopped
2020-01-14T01:14:03.933Z,1578964443.933 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-14T01:14:03.935Z,1578964443.935 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-01-14T01:14:03.935Z,1578964443.935 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-01-14T01:14:03.936Z,1578964443.936 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-01-14T01:14:03.936Z,1578964443.936 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-01-14T01:14:03.936Z,1578964443.936 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-01-14T01:14:03.936Z,1578964443.936 [BuoyancyServo](INFO): Powering down
2020-01-14T01:14:03.949Z,1578964443.949 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-01-14T01:14:03.949Z,1578964443.949 [ElevatorServo](INFO): Powering down
2020-01-14T01:14:03.950Z,1578964443.950 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-01-14T01:14:03.950Z,1578964443.950 [MassServo](INFO): Powering down
2020-01-14T01:14:03.951Z,1578964443.951 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-14T01:14:03.951Z,1578964443.951 [RudderServo](INFO): Powering down
2020-01-14T01:14:03.951Z,1578964443.951 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-01-14T01:14:03.952Z,1578964443.952 [ThrusterServo](INFO): Powering down
2020-01-14T01:14:03.952Z,1578964443.952 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-01-14T01:14:03.953Z,1578964443.953 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-01-14T01:14:03.953Z,1578964443.953 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-01-14T01:14:03.953Z,1578964443.953 [CBIT](DEBUG): Powering off loads.
2020-01-14T01:14:03.965Z,1578964443.965 [CBIT](DEBUG): Disabling WDT.
2020-01-14T01:14:03.977Z,1578964443.977 [CBIT](DEBUG): Opening all GF detection circuits.
2020-01-14T01:14:03.978Z,1578964443.978 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-14T01:14:04.026Z,1578964444.026 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-14T01:14:04.036Z,1578964444.036 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-14T01:14:04.077Z,1578964444.077 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-14T01:14:04.080Z,1578964444.080 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-14T01:14:04.136Z,1578964444.136 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-14T01:14:04.209Z,1578964444.209 [logger ThreadHandler](INFO): Thread cancelled.