2020-01-10T18:11:44.475Z,1578679904.475 [Supervisor](DEBUG): Initializing supervisor.
2020-01-10T18:11:44.478Z,1578679904.478 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-01-10T18:11:44.479Z,1578679904.479 [SyncHandler](INFO): Protected caller Thread ID is 4531
2020-01-10T18:11:44.479Z,1578679904.479 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-01-10T18:11:44.480Z,1578679904.480 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-01-10T18:11:44.481Z,1578679904.481 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4532
2020-01-10T18:11:44.484Z,1578679904.484 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-01-10T18:11:44.496Z,1578679904.496 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-01-10T18:11:44.497Z,1578679904.497 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-01-10T18:11:44.497Z,1578679904.497 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4533
2020-01-10T18:11:44.498Z,1578679904.498 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-01-10T18:11:44.499Z,1578679904.499 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-01-10T18:11:44.499Z,1578679904.499 [logger ThreadHandler](INFO): Protected caller Thread ID is 4534
2020-01-10T18:11:44.501Z,1578679904.501 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-01-10T18:11:44.501Z,1578679904.501 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-01-10T18:11:44.503Z,1578679904.503 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-01-10T18:11:44.924Z,1578679904.924 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-01-10T18:11:44.924Z,1578679904.924 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-01-10T18:11:45.019Z,1578679905.019 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-01-10T18:11:45.019Z,1578679905.019 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-01-10T18:11:45.332Z,1578679905.332 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-01-10T18:11:45.332Z,1578679905.332 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-01-10T18:11:45.467Z,1578679905.467 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-01-10T18:11:45.468Z,1578679905.468 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-01-10T18:11:45.650Z,1578679905.650 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-01-10T18:11:45.651Z,1578679905.651 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-01-10T18:11:46.091Z,1578679906.091 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-01-10T18:11:46.092Z,1578679906.092 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-01-10T18:11:46.293Z,1578679906.293 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-01-10T18:11:46.294Z,1578679906.294 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-01-10T18:11:46.436Z,1578679906.436 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-01-10T18:11:46.436Z,1578679906.436 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-01-10T18:11:46.616Z,1578679906.616 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-01-10T18:11:46.617Z,1578679906.617 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-01-10T18:11:46.710Z,1578679906.710 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-01-10T18:11:46.710Z,1578679906.710 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-01-10T18:11:47.001Z,1578679907.001 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-01-10T18:11:47.002Z,1578679907.002 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-01-10T18:11:47.080Z,1578679907.080 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-01-10T18:11:47.180Z,1578679907.180 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-01-10T18:11:47.181Z,1578679907.181 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-01-10T18:11:47.806Z,1578679907.806 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-01-10T18:11:47.807Z,1578679907.807 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-01-10T18:11:48.211Z,1578679908.211 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-01-10T18:11:48.213Z,1578679908.213 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-01-10T18:11:48.214Z,1578679908.214 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-01-10T18:11:48.412Z,1578679908.412 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-01-10T18:11:48.507Z,1578679908.507 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-01-10T18:11:48.601Z,1578679908.601 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-01-10T18:11:48.828Z,1578679908.828 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-01-10T18:11:48.829Z,1578679908.829 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-01-10T18:11:48.911Z,1578679908.911 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-01-10T18:11:49.001Z,1578679909.001 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-01-10T18:11:49.094Z,1578679909.094 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-01-10T18:11:49.186Z,1578679909.186 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-01-10T18:11:49.366Z,1578679909.366 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-01-10T18:11:49.749Z,1578679909.749 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-01-10T18:11:50.030Z,1578679910.030 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2020-01-10T18:11:50.031Z,1578679910.031 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-01-10T18:11:50.055Z,1578679910.055 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-01-10T18:11:50.345Z,1578679910.345 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-01-10T18:11:50.350Z,1578679910.350 [AHRS_M2](INFO): created writer for : platform_orientation
2020-01-10T18:11:50.352Z,1578679910.352 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-01-10T18:11:50.357Z,1578679910.357 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-01-10T18:11:50.358Z,1578679910.358 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-01-10T18:11:50.363Z,1578679910.363 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-01-10T18:11:50.364Z,1578679910.364 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-01-10T18:11:50.369Z,1578679910.369 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-01-10T18:11:50.441Z,1578679910.441 [AHRS_M2] Loaded
2020-01-10T18:11:50.442Z,1578679910.442 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-01-10T18:11:50.523Z,1578679910.523 [DataOverHttps] Loaded
2020-01-10T18:11:50.524Z,1578679910.524 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-01-10T18:11:50.525Z,1578679910.525 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407684E0
2020-01-10T18:11:50.525Z,1578679910.525 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4613
2020-01-10T18:11:50.550Z,1578679910.550 [DDM] Loaded
2020-01-10T18:11:50.550Z,1578679910.550 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-01-10T18:11:50.563Z,1578679910.563 [Depth_Keller] Loaded
2020-01-10T18:11:50.563Z,1578679910.563 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-01-10T18:11:50.568Z,1578679910.568 [DropWeight] Loaded
2020-01-10T18:11:50.569Z,1578679910.569 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-01-10T18:11:50.631Z,1578679910.631 [DUSBL_Hydroid] Loaded
2020-01-10T18:11:50.631Z,1578679910.631 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-01-10T18:11:50.679Z,1578679910.679 [Micromodem] Loaded
2020-01-10T18:11:50.679Z,1578679910.679 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-01-10T18:11:50.779Z,1578679910.779 [NAL9602] Loaded
2020-01-10T18:11:50.779Z,1578679910.779 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-01-10T18:11:50.817Z,1578679910.817 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2020-01-10T18:11:50.817Z,1578679910.817 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2020-01-10T18:11:50.818Z,1578679910.818 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2020-01-10T18:11:50.818Z,1578679910.818 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2020-01-10T18:11:50.819Z,1578679910.819 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2020-01-10T18:11:50.819Z,1578679910.819 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2020-01-10T18:11:50.820Z,1578679910.820 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2020-01-10T18:11:50.820Z,1578679910.820 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2020-01-10T18:11:50.821Z,1578679910.821 [Onboard] Loaded
2020-01-10T18:11:50.821Z,1578679910.821 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-01-10T18:11:50.827Z,1578679910.827 [PowerOnly] Loaded
2020-01-10T18:11:50.827Z,1578679910.827 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-01-10T18:11:50.834Z,1578679910.834 [Radio_Surface] Loaded
2020-01-10T18:11:50.834Z,1578679910.834 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-01-10T18:11:50.835Z,1578679910.835 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407984E0
2020-01-10T18:11:50.835Z,1578679910.835 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4614
2020-01-10T18:11:50.880Z,1578679910.880 [RDI_Pathfinder] Loaded
2020-01-10T18:11:50.880Z,1578679910.880 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-01-10T18:11:52.744Z,1578679912.744 [BPC1] Loaded
2020-01-10T18:11:52.744Z,1578679912.744 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-01-10T18:11:52.745Z,1578679912.745 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-01-10T18:11:52.745Z,1578679912.745 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-01-10T18:11:52.758Z,1578679912.758 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-01-10T18:11:52.759Z,1578679912.759 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-01-10T18:11:52.864Z,1578679912.864 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-01-10T18:11:52.864Z,1578679912.864 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-01-10T18:11:52.884Z,1578679912.884 [NavChart] Loaded
2020-01-10T18:11:52.885Z,1578679912.885 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-01-10T18:11:52.889Z,1578679912.889 [UniversalFixResidualReporter] Loaded
2020-01-10T18:11:52.889Z,1578679912.889 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-01-10T18:11:52.889Z,1578679912.889 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-01-10T18:11:52.890Z,1578679912.890 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-01-10T18:11:52.999Z,1578679912.999 [BuoyancyServo] Loaded
2020-01-10T18:11:52.999Z,1578679912.999 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-01-10T18:11:53.015Z,1578679913.015 [ElevatorServo] Loaded
2020-01-10T18:11:53.015Z,1578679913.015 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-01-10T18:11:53.030Z,1578679913.030 [MassServo] Loaded
2020-01-10T18:11:53.030Z,1578679913.030 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-01-10T18:11:53.045Z,1578679913.045 [RudderServo] Loaded
2020-01-10T18:11:53.045Z,1578679913.045 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-01-10T18:11:53.060Z,1578679913.060 [ThrusterServo] Loaded
2020-01-10T18:11:53.060Z,1578679913.060 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-01-10T18:11:53.060Z,1578679913.060 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-01-10T18:11:53.061Z,1578679913.061 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-01-10T18:11:53.323Z,1578679913.323 [CTD_NeilBrown] Loaded
2020-01-10T18:11:53.323Z,1578679913.323 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2020-01-10T18:11:53.325Z,1578679913.325 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408EC4E0
2020-01-10T18:11:53.325Z,1578679913.325 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 4615
2020-01-10T18:11:53.339Z,1578679913.339 [PAR_Licor] Loaded
2020-01-10T18:11:53.340Z,1578679913.340 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-01-10T18:11:53.384Z,1578679913.384 [WetLabsSeaOWL_UV_A] Loaded
2020-01-10T18:11:53.384Z,1578679913.384 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-01-10T18:11:53.385Z,1578679913.385 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 4091C4E0
2020-01-10T18:11:53.385Z,1578679913.385 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 4616
2020-01-10T18:11:53.386Z,1578679913.386 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-01-10T18:11:53.386Z,1578679913.386 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-01-10T18:11:53.704Z,1578679913.704 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-01-10T18:11:53.705Z,1578679913.705 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-01-10T18:11:53.745Z,1578679913.745 [DepthRateCalculator] Loaded
2020-01-10T18:11:53.746Z,1578679913.746 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-01-10T18:11:53.751Z,1578679913.751 [PitchRateCalculator] Loaded
2020-01-10T18:11:53.752Z,1578679913.752 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-01-10T18:11:53.764Z,1578679913.764 [SpeedCalculator] Loaded
2020-01-10T18:11:53.764Z,1578679913.764 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-01-10T18:11:53.785Z,1578679913.785 [TempGradientCalculator] Loaded
2020-01-10T18:11:53.785Z,1578679913.785 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-01-10T18:11:53.791Z,1578679913.791 [YawRateCalculator] Loaded
2020-01-10T18:11:53.791Z,1578679913.791 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-01-10T18:11:53.832Z,1578679913.832 [ElevatorOffsetCalculator] Loaded
2020-01-10T18:11:53.832Z,1578679913.832 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-01-10T18:11:53.833Z,1578679913.833 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-01-10T18:11:53.833Z,1578679913.833 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-01-10T18:11:53.973Z,1578679913.973 [SBIT](DEBUG): Construct Startup Built In Test.
2020-01-10T18:11:53.995Z,1578679913.995 [SBIT] Loaded
2020-01-10T18:11:53.995Z,1578679913.995 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-01-10T18:11:53.996Z,1578679913.996 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-01-10T18:11:54.008Z,1578679914.008 [IBIT] Loaded
2020-01-10T18:11:54.009Z,1578679914.009 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-01-10T18:11:54.012Z,1578679914.012 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-01-10T18:11:54.153Z,1578679914.153 [CBIT] Loaded
2020-01-10T18:11:54.153Z,1578679914.153 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-01-10T18:11:54.154Z,1578679914.154 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-01-10T18:11:54.154Z,1578679914.154 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-01-10T18:11:54.238Z,1578679914.238 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-01-10T18:11:54.238Z,1578679914.238 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-01-10T18:11:54.410Z,1578679914.410 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-01-10T18:11:54.411Z,1578679914.411 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-01-10T18:11:54.461Z,1578679914.461 [VerticalControl](DEBUG): Construct VerticalControl.
2020-01-10T18:11:54.544Z,1578679914.544 [VerticalControl] Loaded
2020-01-10T18:11:54.544Z,1578679914.544 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-01-10T18:11:54.545Z,1578679914.545 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-01-10T18:11:54.616Z,1578679914.616 [HorizontalControl] Loaded
2020-01-10T18:11:54.616Z,1578679914.616 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-01-10T18:11:54.617Z,1578679914.617 [SpeedControl](DEBUG): Construct SpeedControl.
2020-01-10T18:11:54.618Z,1578679914.618 [SpeedControl] Loaded
2020-01-10T18:11:54.619Z,1578679914.619 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-01-10T18:11:54.619Z,1578679914.619 [LoopControl](DEBUG): Construct LoopControl.
2020-01-10T18:11:54.620Z,1578679914.620 [LoopControl] Loaded
2020-01-10T18:11:54.620Z,1578679914.620 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-01-10T18:11:54.620Z,1578679914.620 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-01-10T18:11:54.621Z,1578679914.621 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-01-10T18:11:54.654Z,1578679914.654 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-01-10T18:11:54.657Z,1578679914.657 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-01-10T18:11:54.658Z,1578679914.658 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-01-10T18:11:54.665Z,1578679914.665 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-01-10T18:11:54.666Z,1578679914.666 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AFB4E0
2020-01-10T18:11:54.666Z,1578679914.666 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4617
2020-01-10T18:11:54.670Z,1578679914.670 [Supervisor](INFO): Main Thread ID is 4530
2020-01-10T18:11:54.671Z,1578679914.671 [Supervisor](DEBUG): Running supervisor.
2020-01-10T18:11:54.671Z,1578679914.671 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4618
2020-01-10T18:11:54.674Z,1578679914.674 [controlThread ThreadHandler](INFO): Handler Thread ID is 4619
2020-01-10T18:11:54.674Z,1578679914.674 [controlThread](DEBUG): Initializing ControlThread
2020-01-10T18:11:54.680Z,1578679914.680 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-01-10T18:11:54.680Z,1578679914.680 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-01-10T18:11:54.685Z,1578679914.685 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-01-10T18:11:54.685Z,1578679914.685 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-01-10T18:11:54.686Z,1578679914.686 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-01-10T18:11:54.686Z,1578679914.686 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-01-10T18:11:54.687Z,1578679914.687 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-01-10T18:11:54.687Z,1578679914.687 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-01-10T18:11:54.688Z,1578679914.688 [SBIT](INFO): Initialize SBIT Component.
2020-01-10T18:11:54.688Z,1578679914.688 [SBIT](IMPORTANT): git: 2019-12-03-17-g4812c94
2020-01-10T18:11:54.688Z,1578679914.688 [SBIT](INFO): git hash: 4812c9429720da7730f61311b178b42396aebb36
2020-01-10T18:11:54.689Z,1578679914.689 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-01-10T18:11:54.690Z,1578679914.690 [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-10T18:11:54.691Z,1578679914.691 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-01-10T18:11:54.692Z,1578679914.692 [IBIT](INFO): Initialize IBIT Component.
2020-01-10T18:11:54.693Z,1578679914.693 [CBIT](DEBUG): Initialize CBIT Component.
2020-01-10T18:11:54.694Z,1578679914.694 [logger ThreadHandler](INFO): Handler Thread ID is 4620
2020-01-10T18:11:54.705Z,1578679914.705 [CBIT](DEBUG): Initialized mux pins.
2020-01-10T18:11:54.705Z,1578679914.705 [CBIT](DEBUG): Initializing the watchdog timer.
2020-01-10T18:11:54.713Z,1578679914.713 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4621
2020-01-10T18:11:54.714Z,1578679914.714 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-01-10T18:11:54.725Z,1578679914.725 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4622
2020-01-10T18:11:54.729Z,1578679914.729 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-01-10T18:11:54.729Z,1578679914.729 [CBIT](DEBUG): Initializing heartbeat.
2020-01-10T18:11:54.737Z,1578679914.737 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 4623
2020-01-10T18:11:54.738Z,1578679914.738 [CTD_NeilBrown](INFO): Powering down
2020-01-10T18:11:54.758Z,1578679914.758 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 4624
2020-01-10T18:11:54.758Z,1578679914.758 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-10T18:11:54.790Z,1578679914.790 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4625
2020-01-10T18:11:54.793Z,1578679914.793 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-01-10T18:11:54.793Z,1578679914.793 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-01-10T18:11:54.793Z,1578679914.793 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-01-10T18:11:54.793Z,1578679914.793 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-01-10T18:11:54.794Z,1578679914.794 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-01-10T18:11:54.794Z,1578679914.794 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-01-10T18:11:54.794Z,1578679914.794 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-01-10T18:11:54.794Z,1578679914.794 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-01-10T18:11:54.794Z,1578679914.794 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-01-10T18:11:54.795Z,1578679914.795 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-01-10T18:11:54.795Z,1578679914.795 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-01-10T18:11:54.795Z,1578679914.795 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-01-10T18:11:54.795Z,1578679914.795 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-01-10T18:11:54.795Z,1578679914.795 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-01-10T18:11:54.795Z,1578679914.795 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-01-10T18:11:54.796Z,1578679914.796 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-01-10T18:11:54.801Z,1578679914.801 [CBIT](DEBUG): Deactivating GF circuits.
2020-01-10T18:11:54.801Z,1578679914.801 [CBIT](DEBUG): Deactivating emergency mode.
2020-01-10T18:11:54.837Z,1578679914.837 [CBIT](DEBUG): Backplane powered.
2020-01-10T18:11:54.837Z,1578679914.837 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-01-10T18:11:54.839Z,1578679914.839 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-01-10T18:11:54.839Z,1578679914.839 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-01-10T18:11:54.840Z,1578679914.840 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-01-10T18:11:54.841Z,1578679914.841 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-01-10T18:11:54.851Z,1578679914.851 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-10T18:11:54.869Z,1578679914.869 [MissionManager](DEBUG):
2020-01-10T18:11:54.870Z,1578679914.870 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-01-10T18:11:54.944Z,1578679914.944 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-01-10T18:11:54.945Z,1578679914.945 [Default:A.Wait](DEBUG): Construct Wait.
2020-01-10T18:11:54.947Z,1578679914.947 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-10T18:11:54.990Z,1578679914.990 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-01-10T18:11:55.021Z,1578679915.021 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-01-10T18:11:55.026Z,1578679915.026 [Default:E.Execute](DEBUG): Construct Execute.
2020-01-10T18:11:55.046Z,1578679915.046 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2020-01-10T18:11:55.051Z,1578679915.051 [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-10T18:11:55.069Z,1578679915.069 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-10T18:11:55.109Z,1578679915.109 [DDM](INFO): Powering up
2020-01-10T18:11:55.109Z,1578679915.109 [DDM](DEBUG): Initializing DDM.
2020-01-10T18:11:55.129Z,1578679915.129 [Radio_Surface](INFO): Powering up
2020-01-10T18:11:55.134Z,1578679915.134 [Depth_Keller](ERROR): Pressure reading out of range: 957.962097 decibar
2020-01-10T18:11:55.135Z,1578679915.135 [DUSBL_Hydroid](INFO): Powering up
2020-01-10T18:11:55.135Z,1578679915.135 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-10T18:11:55.349Z,1578679915.349 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-01-10T18:11:55.370Z,1578679915.370 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-01-10T18:11:55.377Z,1578679915.377 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-01-10T18:11:55.378Z,1578679915.378 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-01-10T18:11:55.385Z,1578679915.385 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-01-10T18:11:55.386Z,1578679915.386 [MassServo](DEBUG): Initializing EZServoServo.
2020-01-10T18:11:55.393Z,1578679915.393 [MassServo](DEBUG): Initializing MassServo.
2020-01-10T18:11:55.394Z,1578679915.394 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-10T18:11:55.401Z,1578679915.401 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-10T18:11:55.402Z,1578679915.402 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-01-10T18:11:55.409Z,1578679915.409 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-01-10T18:11:55.487Z,1578679915.487 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2020-01-10T18:11:55.487Z,1578679915.487 [DropWeight] Hardware Fault, FailCount= 1
2020-01-10T18:11:55.487Z,1578679915.487 [DropWeight](ERROR): Hardware Fault
2020-01-10T18:11:55.488Z,1578679915.488 [Micromodem](INFO): Powering up
2020-01-10T18:11:55.489Z,1578679915.489 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-10T18:11:55.554Z,1578679915.554 [CommandLine](FAULT): Scheduling is paused
2020-01-10T18:11:55.554Z,1578679915.554 [CBIT](INFO): Critical error at 20200110T181155
2020-01-10T18:11:55.555Z,1578679915.555 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-01-10T18:11:55.557Z,1578679915.557 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-01-10T18:11:55.558Z,1578679915.558 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-01-10T18:11:56.217Z,1578679916.217 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-01-10T18:11:56.217Z,1578679916.217 [RudderServo](FAULT): Rudder failed to initialize
2020-01-10T18:11:56.217Z,1578679916.217 [RudderServo] Communications Fault, FailCount= 1
2020-01-10T18:11:56.217Z,1578679916.217 [RudderServo](ERROR): Communications Fault
2020-01-10T18:11:56.350Z,1578679916.350 [CBIT](INFO): Critical error at 20200110T181155
2020-01-10T18:11:56.353Z,1578679916.353 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-01-10T18:11:56.558Z,1578679916.558 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-10T18:11:56.558Z,1578679916.558 [RudderServo](INFO): Powering down
2020-01-10T18:11:57.215Z,1578679917.215 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-10T18:11:57.334Z,1578679917.334 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-10T18:11:57.338Z,1578679917.338 [CBIT](INFO): Clearing failed state for component RudderServo
2020-01-10T18:11:57.338Z,1578679917.338 [RudderServo] No Fault, FailCount= 1
2020-01-10T18:12:00.441Z,1578679920.441 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33
2020-01-10T18:12:00.844Z,1578679920.844 [Micromodem](INFO): Nmea in: $CATMG,2020-01-10T18:12:00.355625Z,RTC,RTC*58
2020-01-10T18:12:00.844Z,1578679920.844 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-10T18:12:00.355625Z,RTC,RTC*58
2020-01-10T18:12:01.251Z,1578679921.251 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31
2020-01-10T18:12:01.251Z,1578679921.251 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31
2020-01-10T18:12:01.651Z,1578679921.651 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33
2020-01-10T18:12:01.651Z,1578679921.651 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64
2020-01-10T18:12:02.059Z,1578679922.059 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66
2020-01-10T18:12:02.059Z,1578679922.059 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F
2020-01-10T18:12:02.463Z,1578679922.463 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D
2020-01-10T18:12:02.463Z,1578679922.463 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E
2020-01-10T18:12:02.867Z,1578679922.867 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C
2020-01-10T18:12:02.867Z,1578679922.867 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46
2020-01-10T18:12:03.267Z,1578679923.267 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44
2020-01-10T18:12:03.267Z,1578679923.267 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63
2020-01-10T18:12:03.707Z,1578679923.707 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61
2020-01-10T18:12:03.707Z,1578679923.707 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A
2020-01-10T18:12:04.056Z,1578679924.056 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38
2020-01-10T18:12:04.057Z,1578679924.057 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,10,18,12,05*4B
2020-01-10T18:12:04.463Z,1578679924.463 [Micromodem](INFO): Nmea in: $CACLK,2020,1,10,18,12,5*49
2020-01-10T18:12:05.262Z,1578679925.262 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-10T18:12:06Z*7A
2020-01-10T18:12:05.283Z,1578679925.283 [Micromodem](INFO): Nmea in: $CATMG,2020-01-10T18:12:06.026059Z,USER_CMD,RTC*15
2020-01-10T18:12:05.682Z,1578679925.682 [DDM](INFO): Dynamic Docking Module:
2020-01-10T18:12:08.541Z,1578679928.541 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-10T18:12:13.338Z,1578679933.338 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-01-10T18:12:21.011Z,1578679941.011 [NAL9602](INFO): Powering up NAL9602
2020-01-10T18:12:31.919Z,1578679951.919 [NAL9602](INFO): NAL9602 initialized
2020-01-10T18:12:34.436Z,1578679954.436 [SBIT](IMPORTANT): Beginning Startup BIT
2020-01-10T18:12:34.440Z,1578679954.440 [CBIT](IMPORTANT): Beginning ground fault scan
2020-01-10T18:12:40.615Z,1578679960.615 [Micromodem](INFO): Nmea in: $CACYC,1,12,1,1,0,1*68
2020-01-10T18:12:40.616Z,1578679960.616 [Micromodem](INFO): Have incoming data
2020-01-10T18:12:40.618Z,1578679960.618 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,1,3D90B8D172D0E11A94BB9A0D6EC0434A2AB488F6*51
2020-01-10T18:12:40.619Z,1578679960.619 [Micromodem](INFO): Frame:1
2020-01-10T18:12:40.619Z,1578679960.619 [Micromodem](INFO): Frame Size:-18
2020-01-10T18:12:40.619Z,1578679960.619 [Micromodem](INFO): Data End:-2
2020-01-10T18:12:40.620Z,1578679960.620 [Micromodem](INFO): Data At:16
2020-01-10T18:12:40.620Z,1578679960.620 [Micromodem](INFO): Deserialize. FrameCount:1
2020-01-10T18:12:40.620Z,1578679960.620 [Micromodem](INFO): Outer loop. Size:-18
2020-01-10T18:12:40.620Z,1578679960.620 [Micromodem](INFO): Sending to RXSBD Size:0
2020-01-10T18:12:40.620Z,1578679960.620 [Micromodem](CRITICAL): Packet size is not N*16+4: 0x0
2020-01-10T18:12:40.625Z,1578679960.625 [Micromodem](CRITICAL): Failed to parse uplink message:
2020-01-10T18:12:40.827Z,1578679960.827 [CBIT](INFO): Critical error at 20200110T181240
2020-01-10T18:12:41.018Z,1578679961.018 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110181239.502610,06,2142,33,0152,0150,29,01,01,00,03,1,012,001,0,3,1,0,150,24.9,8.00,00,-5.00,-01,-0.02,22,10000,4000*7B
2020-01-10T18:12:46.022Z,1578679966.022 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.012761
CHAN A1 (24V): -0.029488
CHAN A2 (12V): -0.007186
CHAN A3 (5V): -0.002113
CHAN B0 (3.3V): 0.000057
CHAN B1 (3.15aV): -0.000176
CHAN B2 (3.15bV): 0.000192
CHAN B3 (GND): 0.002360
OPEN: 0.005100
Full Scale Calc: 4.765 mA, -1.589 mA
2020-01-10T18:13:11.417Z,1578679991.417 [NAL9602](INFO): SBD MO Status=2, MOMSN=10155, MT Status=2, MTMSN=0
2020-01-10T18:13:11.417Z,1578679991.417 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-10T18:13:28.133Z,1578680008.133 [SBIT](IMPORTANT): SBIT PASSED
2020-01-10T18:13:28.190Z,1578680008.190 [CommandLine](IMPORTANT): got command configSet list
2020-01-10T18:13:28.191Z,1578680008.191 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-01-10T18:13:28.192Z,1578680008.192 [CommandLine](IMPORTANT): Courier linearApproximation depth 5.000000 meter;
2020-01-10T18:13:28.192Z,1578680008.192 [CommandLine](IMPORTANT): Courier linearApproximation latitude 0.000500 angular_degree;
2020-01-10T18:13:28.192Z,1578680008.192 [CommandLine](IMPORTANT): Courier linearApproximation longitude 0.000500 angular_degree;
2020-01-10T18:13:28.192Z,1578680008.192 [CommandLine](IMPORTANT): DDM.loadAtStartup=1 bool;
2020-01-10T18:13:28.192Z,1578680008.192 [CommandLine](IMPORTANT): DDM.verbosity=3 count;
2020-01-10T18:13:28.192Z,1578680008.192 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=75 count;
2020-01-10T18:13:28.193Z,1578680008.193 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout=40 millisecond;
2020-01-10T18:13:28.193Z,1578680008.193 [CommandLine](IMPORTANT): Express linearApproximation DDM.DDMMode 1.000000 count;
2020-01-10T18:13:28.193Z,1578680008.193 [CommandLine](IMPORTANT): Express all DDM.cablePresent;
2020-01-10T18:13:28.193Z,1578680008.193 [CommandLine](IMPORTANT): Express linearApproximation DDM.latchState 1.000000 count;
2020-01-10T18:13:28.193Z,1578680008.193 [CommandLine](IMPORTANT): Express linearApproximation DDM.whiskerState 1.000000 count;
2020-01-10T18:13:28.193Z,1578680008.193 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.acoustic_contact_range 1.000000 meter;
2020-01-10T18:13:28.193Z,1578680008.193 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 2.000000 degree;
2020-01-10T18:13:28.193Z,1578680008.193 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.height_above_sea_floor 2.000000 meter;
2020-01-10T18:13:28.194Z,1578680008.194 [CommandLine](IMPORTANT): Express linearApproximation platform_orientation 5.000000 degree;
2020-01-10T18:13:28.194Z,1578680008.194 [CommandLine](IMPORTANT): Express linearApproximation platform_x_velocity_wrt_ground 0.100000 meter_per_second;
2020-01-10T18:13:28.194Z,1578680008.194 [CommandLine](IMPORTANT): Express linearApproximation platform_y_velocity_wrt_ground 0.100000 meter_per_second;
2020-01-10T18:13:28.194Z,1578680008.194 [CommandLine](IMPORTANT): HorizontalControl.kiHeading=0.002 reciprocal_second;
2020-01-10T18:13:28.194Z,1578680008.194 [CommandLine](IMPORTANT): HorizontalControl.kpHeading=0.8 none;
2020-01-10T18:13:28.194Z,1578680008.194 [CommandLine](IMPORTANT): HorizontalControl.rudDeadband=0.05 degree;
2020-01-10T18:13:28.195Z,1578680008.195 [CommandLine](IMPORTANT): Micromodem.destinationAddress=12 enum;
2020-01-10T18:13:28.195Z,1578680008.195 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold=0 meter;
2020-01-10T18:13:28.195Z,1578680008.195 [CommandLine](IMPORTANT): PowerOnly.sampleTime=90 second;
2020-01-10T18:13:28.195Z,1578680008.195 [CommandLine](IMPORTANT): RudderServo.deviationAngle=0.0001 degree;
2020-01-10T18:13:28.195Z,1578680008.195 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter;
2020-01-10T18:13:28.195Z,1578680008.195 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter;
2020-01-10T18:13:28.654Z,1578680008.654 [MissionManager](IMPORTANT): Started mission Startup
2020-01-10T18:13:28.654Z,1578680008.654 [Startup] Running Loop=1
2020-01-10T18:13:28.654Z,1578680008.654 [Startup](DEBUG): Aggregate::initialize Startup
2020-01-10T18:13:28.654Z,1578680008.654 [Startup:A.GoToSurface] Running Loop=1
2020-01-10T18:13:28.655Z,1578680008.655 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-10T18:13:28.655Z,1578680008.655 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-10T18:13:28.656Z,1578680008.656 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-10T18:13:28.656Z,1578680008.656 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-10T18:13:28.656Z,1578680008.656 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-10T18:13:28.657Z,1578680008.657 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-10T18:13:28.658Z,1578680008.658 [Startup:StartupSatComms] Running Loop=1
2020-01-10T18:13:28.658Z,1578680008.658 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-01-10T18:13:28.659Z,1578680008.659 [Startup:StartupSatComms:A] Running Loop=1
2020-01-10T18:13:28.998Z,1578680008.998 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-01-10T18:13:33.740Z,1578680013.740 [NAL9602](INFO): SBD MO Status=2, MOMSN=10155, MT Status=2, MTMSN=0
2020-01-10T18:13:33.740Z,1578680013.740 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-01-10T18:13:48.336Z,1578680028.336 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-10T18:13:48.336Z,1578680028.336 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-3278,V
2020-01-10T18:13:48.700Z,1578680028.700 [Micromodem](INFO): Nmea in: $CACYC,1,12,1,1,0,1*68
2020-01-10T18:13:48.700Z,1578680028.700 [Micromodem](INFO): Have incoming data
2020-01-10T18:13:48.703Z,1578680028.703 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,1,EA49DA6859B6D6FDD7866B1D923D9D8A099CA094*52
2020-01-10T18:13:48.703Z,1578680028.703 [Micromodem](INFO): Frame:1
2020-01-10T18:13:48.704Z,1578680028.704 [Micromodem](INFO): Frame Size:41
2020-01-10T18:13:48.704Z,1578680028.704 [Micromodem](INFO): Data End:57
2020-01-10T18:13:48.705Z,1578680028.705 [Micromodem](INFO): Data At:16
2020-01-10T18:13:48.706Z,1578680028.706 [Micromodem](INFO): Deserialize. FrameCount:1
2020-01-10T18:13:48.707Z,1578680028.707 [Micromodem](INFO): Outer loop. Size:41
2020-01-10T18:13:48.708Z,1578680028.708 [Micromodem](INFO): Inner loop. C:234
2020-01-10T18:13:48.708Z,1578680028.708 [Micromodem](INFO): Inner loop. C:73
2020-01-10T18:13:48.709Z,1578680028.709 [Micromodem](INFO): Inner loop. C:218
2020-01-10T18:13:48.710Z,1578680028.710 [Micromodem](INFO): Inner loop. C:104
2020-01-10T18:13:48.710Z,1578680028.710 [Micromodem](INFO): Inner loop. C:89
2020-01-10T18:13:48.710Z,1578680028.710 [Micromodem](INFO): Inner loop. C:182
2020-01-10T18:13:48.710Z,1578680028.710 [Micromodem](INFO): Inner loop. C:214
2020-01-10T18:13:48.711Z,1578680028.711 [Micromodem](INFO): Inner loop. C:253
2020-01-10T18:13:48.711Z,1578680028.711 [Micromodem](INFO): Inner loop. C:215
2020-01-10T18:13:48.711Z,1578680028.711 [Micromodem](INFO): Inner loop. C:134
2020-01-10T18:13:48.711Z,1578680028.711 [Micromodem](INFO): Inner loop. C:107
2020-01-10T18:13:48.711Z,1578680028.711 [Micromodem](INFO): Inner loop. C:29
2020-01-10T18:13:48.711Z,1578680028.711 [Micromodem](INFO): Inner loop. C:146
2020-01-10T18:13:48.712Z,1578680028.712 [Micromodem](INFO): Inner loop. C:61
2020-01-10T18:13:48.712Z,1578680028.712 [Micromodem](INFO): Inner loop. C:157
2020-01-10T18:13:48.712Z,1578680028.712 [Micromodem](INFO): Inner loop. C:138
2020-01-10T18:13:48.712Z,1578680028.712 [Micromodem](INFO): Inner loop. C:9
2020-01-10T18:13:48.712Z,1578680028.712 [Micromodem](INFO): Inner loop. C:156
2020-01-10T18:13:48.713Z,1578680028.713 [Micromodem](INFO): Inner loop. C:160
2020-01-10T18:13:48.713Z,1578680028.713 [Micromodem](INFO): Inner loop. C:148
2020-01-10T18:13:48.713Z,1578680028.713 [Micromodem](INFO): Sending to RXSBD Size:20
2020-01-10T18:13:48.714Z,1578680028.714 [Micromodem](INFO): Received command:failc
2020-01-10T18:13:48.781Z,1578680028.781 [CommandLine](IMPORTANT): got command failComponent
2020-01-10T18:13:48.781Z,1578680028.781 [CommandLine](IMPORTANT): Failed components:
2020-01-10T18:13:48.781Z,1578680028.781 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2020-01-10T18:13:49.094Z,1578680029.094 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110181347.550072,06,916,29,0107,0150,59,02,02,00,03,1,012,001,0,3,1,0,150,26.9,8.00,00,-5.00,-01,-0.02,24,10000,4000*45
2020-01-10T18:13:56.788Z,1578680036.788 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004494
2020-01-10T18:14:28.713Z,1578680068.713 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-10T18:14:28.714Z,1578680068.714 [RDI_Pathfinder](ERROR): Failed to parse:
:RA, 0.00, 0.00, 0.00, 0.00,
2020-01-10T18:14:28.721Z,1578680068.721 [Startup:StartupSatComms:A](INFO): Timed out from 2020-01-10T18:13:28.7Z
2020-01-10T18:14:28.721Z,1578680068.721 [Startup:StartupSatComms:A] Stopped
2020-01-10T18:14:28.722Z,1578680068.722 [Startup:StartupSatComms:B] Running Loop=1
2020-01-10T18:14:29.118Z,1578680069.118 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-10T18:14:30.469Z,1578680070.469 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 6EB8185E040001005D00000400300100000000000000151D80030C5669BF31555EAE1409A708CE2489B190EA4D9D68DCD8D7AAA11AD9F0ED77F7C27FB6E9EF60
2020-01-10T18:14:30.470Z,1578680070.470 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 78721F864134088EEB050233EEBD0D7AB050D10C57417A83372266795797D2606D8A934802DABC8DD9C734B34A1728750B642C7F2E6EE3DBEBAFB781534CA5DD
2020-01-10T18:14:30.470Z,1578680070.470 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 5D580C6B0BD0E74855FECB6AA73A4154BEA963D4372410B221B822E035CD52CE39AB17BFEBBEA9546C0626C5902AE1CE108EF9AF4F01D2CB1A6729A3E49C5C07
2020-01-10T18:14:30.471Z,1578680070.471 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69
2020-01-10T18:14:30.627Z,1578680070.627 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B
2020-01-10T18:14:30.995Z,1578680070.995 [Micromodem](INFO): Nmea in: $CADRQ,181431,1,12,0,64,1*7A
2020-01-10T18:14:30.996Z,1578680070.996 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,6EB8185E040001005D00000400300100000000000000151D80030C5669BF31555EAE1409A708CE2489B190EA4D9D68DCD8D7AAA11AD9F0ED77F7C27FB6E9EF60*4E
2020-01-10T18:14:31.409Z,1578680071.409 [Micromodem](INFO): Nmea in: $CACYC,1,12,1,,0,1*68
2020-01-10T18:14:31.409Z,1578680071.409 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CACYC,1,12,1,,0,1*68
expected 59
2020-01-10T18:14:31.410Z,1578680071.410 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,6EB8185E040001005D00000400300100000000000000151D80030C5669BF31555EAE1409A708CE2489B190EA4D9D68DCD8D7AAA11AD9F0ED77F7C27FB6E9EF60*4E
2020-01-10T18:14:31.809Z,1578680071.809 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,1,3D90B8D172D0E11A94BB9A0D6EC0434A2AB488F6*51
2020-01-10T18:14:31.809Z,1578680071.809 [Micromodem](ERROR): TXD response from modem unexpected: $CARXD,12,1,0,1,3D90B8D172D0E11A94BB9A0D6EC0434A2AB488F6*51
2020-01-10T18:14:32.215Z,1578680072.215 [Micromodem](INFO): Nmea in: $CACST,6,,20200110181430.148676,06,1614,32,0120,0150,29,01,01,00,03,1,012,001,0,3,1,0,150,26.3,8.00,00,-5.00,-01,-0.02,21,10000,4000*76
2020-01-10T18:14:32.216Z,1578680072.216 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CACST,6,,20200110181430.148676,06,1614,32,0120,0150,29,01,01,00,03,1,012,001,0,3,1,0,150,26.3,8.00,00,-5.00,-01,-0.02,21,10000,4000*76
expected 46
2020-01-10T18:14:32.216Z,1578680072.216 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,6EB8185E040001005D00000400300100000000000000151D80030C5669BF31555EAE1409A708CE2489B190EA4D9D68DCD8D7AAA11AD9F0ED77F7C27FB6E9EF60*4E
2020-01-10T18:14:33.431Z,1578680073.431 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B
2020-01-10T18:14:33.839Z,1578680073.839 [Micromodem](INFO): Nmea in: $CADRQ,181432,1,12,0,64,2*7A
2020-01-10T18:14:33.839Z,1578680073.839 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,78721F864134088EEB050233EEBD0D7AB050D10C57417A83372266795797D2606D8A934802DABC8DD9C734B34A1728750B642C7F2E6EE3DBEBAFB781534CA5DD*38
2020-01-10T18:14:34.235Z,1578680074.235 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B
2020-01-10T18:14:34.631Z,1578680074.631 [Micromodem](INFO): Nmea in: $CADRQ,181432,1,12,0,64,3*7B
2020-01-10T18:14:34.631Z,1578680074.631 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,5D580C6B0BD0E74855FECB6AA73A4154BEA963D4372410B221B822E035CD52CE39AB17BFEBBEA9546C0626C5902AE1CE108EF9AF4F01D2CB1A6729A3E49C5C07*41
2020-01-10T18:14:35.047Z,1578680075.047 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B
2020-01-10T18:14:35.444Z,1578680075.444 [Micromodem](INFO): Nmea in: $CATXP,192*48
2020-01-10T18:14:36.667Z,1578680076.667 [Micromodem](INFO): Nmea in: $CATXF,192*5E
2020-01-10T18:14:36.724Z,1578680076.724 [RDI_Pathfinder](ERROR): Failed to parse:
:B +0.00, +0.00, +0.00, 0.00,147.00
2020-01-10T18:14:37.060Z,1578680077.060 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,181433.314090,3,0,200,4000,10000,1,1,12,1,3,3,3,192*79
2020-01-10T18:14:37.475Z,1578680077.475 [DataOverHttps](INFO): Sending 276 bytes from file Logs/20200110T174622/Courier0004.lzma
2020-01-10T18:14:37.883Z,1578680077.883 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79
2020-01-10T18:14:38.267Z,1578680078.267 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A
2020-01-10T18:14:38.675Z,1578680078.675 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B
2020-01-10T18:14:38.676Z,1578680078.676 [Micromodem](INFO): Sent 184 bytes from file Logs/20200110T174622/Courier0004.lzma.parts
2020-01-10T18:14:38.676Z,1578680078.676 [Micromodem](INFO): Packets left to send: 1
2020-01-10T18:14:39.086Z,1578680079.086 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110181437.865733,06,227,23,0106,0150,29,01,01,00,03,1,012,001,0,4,1,0,150,24.5,6.00,14,-3.00,-01,-0.02,19,10000,4000*43
2020-01-10T18:14:39.478Z,1578680079.478 [DataOverHttps](INFO): Moved sent file to Logs/20200110T174622/Courier0004.lzma.bak
2020-01-10T18:14:39.478Z,1578680079.478 [DataOverHttps](INFO): SBD MOMSN=12190289
2020-01-10T18:14:39.656Z,1578680079.656 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 03BC185E000002005D000004004B0200000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6
2020-01-10T18:14:39.656Z,1578680079.656 [Micromodem](INFO): Outgoing frame #2, 64 bytes: AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE36661E
2020-01-10T18:14:39.657Z,1578680079.657 [Micromodem](INFO): Outgoing frame #3, 64 bytes: FBF3EFC175D6DFF908DAE0B1CC0E0D12B667AB901F529778C101C8DF295625945FDD200A1429C5886930E0C634372A2F7AA694ABAAB7524FDFD9CB9F2E49C068
2020-01-10T18:14:39.657Z,1578680079.657 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69
2020-01-10T18:14:40.563Z,1578680080.563 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B
2020-01-10T18:14:40.967Z,1578680080.967 [Micromodem](INFO): Nmea in: $CADRQ,181440,1,12,0,64,1*7C
2020-01-10T18:14:40.967Z,1578680080.967 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,03BC185E000002005D000004004B0200000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6*41
2020-01-10T18:14:41.371Z,1578680081.371 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B
2020-01-10T18:14:41.775Z,1578680081.775 [Micromodem](INFO): Nmea in: $CADRQ,181442,1,12,0,64,2*7D
2020-01-10T18:14:41.775Z,1578680081.775 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE36661E*49
2020-01-10T18:14:42.192Z,1578680082.192 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B
2020-01-10T18:14:42.591Z,1578680082.591 [Micromodem](INFO): Nmea in: $CADRQ,181442,1,12,0,64,3*7C
2020-01-10T18:14:42.591Z,1578680082.591 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,FBF3EFC175D6DFF908DAE0B1CC0E0D12B667AB901F529778C101C8DF295625945FDD200A1429C5886930E0C634372A2F7AA694ABAAB7524FDFD9CB9F2E49C068*30
2020-01-10T18:14:42.987Z,1578680082.987 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B
2020-01-10T18:14:43.395Z,1578680083.395 [Micromodem](INFO): Nmea in: $CATXP,192*48
2020-01-10T18:14:47.031Z,1578680087.031 [Micromodem](INFO): Nmea in: $CATXF,192*5E
2020-01-10T18:14:47.440Z,1578680087.440 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,181443.687470,3,0,200,4000,10000,1,1,12,1,3,3,3,192*7B
2020-01-10T18:14:48.248Z,1578680088.248 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79
2020-01-10T18:14:48.643Z,1578680088.643 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A
2020-01-10T18:14:49.051Z,1578680089.051 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B
2020-01-10T18:14:49.052Z,1578680089.052 [Micromodem](INFO): Sent 184 bytes from file Logs/20200110T180139/Courier0000.lzma.parts
2020-01-10T18:14:49.052Z,1578680089.052 [Micromodem](INFO): Packets left to send: 2
2020-01-10T18:14:49.458Z,1578680089.458 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110181448.240364,06,259,24,0117,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,26.9,6.00,15,-3.00,-01,-0.04,23,10000,4000*49
2020-01-10T18:14:49.978Z,1578680089.978 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 03BC185E00000100359E52EDB77FAB33F3DF8A8A598D30EAC1E8D085FE022C119D90A66EDFAB1CC2F35B5920DE6931576978CB036F734E4A7C4719B8A55F7392
2020-01-10T18:14:49.978Z,1578680089.978 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 08F04BDAF7908FF6443C7E646DE57F82466F76204A95790476FAEFE5674F36656A277801F550EA4E97B3C700566A3385EA1032318AB093FED209F7B897B742B4
2020-01-10T18:14:49.979Z,1578680089.979 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 6887138DF37DB7FDB1859897A476ED15C00B4F2398065B45305894962205B486061DC78BF2A2A63CE1E8EF1ABF3E26643B95BA841F530F6163C94BE992EA777D
2020-01-10T18:14:49.979Z,1578680089.979 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69
2020-01-10T18:14:50.935Z,1578680090.935 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B
2020-01-10T18:14:51.339Z,1578680091.339 [Micromodem](INFO): Nmea in: $CADRQ,181450,1,12,0,64,1*7D
2020-01-10T18:14:51.339Z,1578680091.339 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,03BC185E00000100359E52EDB77FAB33F3DF8A8A598D30EAC1E8D085FE022C119D90A66EDFAB1CC2F35B5920DE6931576978CB036F734E4A7C4719B8A55F7392*43
2020-01-10T18:14:51.739Z,1578680091.739 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B
2020-01-10T18:14:52.151Z,1578680092.151 [Micromodem](INFO): Nmea in: $CADRQ,181452,1,12,0,64,2*7C
2020-01-10T18:14:52.151Z,1578680092.151 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,08F04BDAF7908FF6443C7E646DE57F82466F76204A95790476FAEFE5674F36656A277801F550EA4E97B3C700566A3385EA1032318AB093FED209F7B897B742B4*3D
2020-01-10T18:14:52.551Z,1578680092.551 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B
2020-01-10T18:14:52.951Z,1578680092.951 [Micromodem](INFO): Nmea in: $CADRQ,181453,1,12,0,64,3*7C
2020-01-10T18:14:52.951Z,1578680092.951 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,6887138DF37DB7FDB1859897A476ED15C00B4F2398065B45305894962205B486061DC78BF2A2A63CE1E8EF1ABF3E26643B95BA841F530F6163C94BE992EA777D*3F
2020-01-10T18:14:53.355Z,1578680093.355 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B
2020-01-10T18:14:53.758Z,1578680093.758 [Micromodem](INFO): Nmea in: $CATXP,192*48
2020-01-10T18:14:55.019Z,1578680095.019 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-01-10T18:14:55.019Z,1578680095.019 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-10T18:14:55.037Z,1578680095.037 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-10T18:14:55.443Z,1578680095.443 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-10T18:14:55.444Z,1578680095.444 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-01-10T18:14:57.073Z,1578680097.073 [DataOverHttps](INFO): Sending 439 bytes from file Logs/20200110T180139/Courier0000.lzma
2020-01-10T18:14:57.407Z,1578680097.407 [Micromodem](INFO): Nmea in: $CATXF,192*5E
2020-01-10T18:14:57.800Z,1578680097.800 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,181454.049250,3,0,200,4000,10000,1,1,12,1,3,3,3,192*7D
2020-01-10T18:14:58.628Z,1578680098.628 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79
2020-01-10T18:14:59.014Z,1578680099.014 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A
2020-01-10T18:14:59.078Z,1578680099.078 [DataOverHttps](INFO): Moved sent file to Logs/20200110T180139/Courier0000.lzma.bak
2020-01-10T18:14:59.079Z,1578680099.079 [DataOverHttps](INFO): SBD MOMSN=12190293
2020-01-10T18:14:59.416Z,1578680099.416 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B
2020-01-10T18:14:59.417Z,1578680099.417 [Micromodem](INFO): Sent 184 bytes from file Logs/20200110T180139/Courier0000.lzma.parts
2020-01-10T18:14:59.417Z,1578680099.417 [Micromodem](INFO): Packets left to send: 1
2020-01-10T18:14:59.830Z,1578680099.830 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110181458.602678,06,239,23,0116,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,25.4,6.00,15,-3.00,-01,-0.03,22,10000,4000*4A
2020-01-10T18:15:00.681Z,1578680100.681 [Micromodem](INFO): Outgoing frame #1, 64 bytes: F0BD185E000001005D000004002D0100000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6
2020-01-10T18:15:00.681Z,1578680100.681 [Micromodem](INFO): Outgoing frame #2, 64 bytes: AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE3678B3
2020-01-10T18:15:00.682Z,1578680100.682 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 336856ABAD201C56DA9459B2972F92771E52AFD3125AFE32B12BECEC01F085231029EB7855A2DC476123E9AA0209C8ED5B7C9CCF252EBB8660CD1248705CCACB
2020-01-10T18:15:00.682Z,1578680100.682 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69
2020-01-10T18:15:01.643Z,1578680101.643 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B
2020-01-10T18:15:02.047Z,1578680102.047 [Micromodem](INFO): Nmea in: $CADRQ,181501,1,12,0,64,1*78
2020-01-10T18:15:02.047Z,1578680102.047 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,F0BD185E000001005D000004002D0100000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6*33
2020-01-10T18:15:02.459Z,1578680102.459 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B
2020-01-10T18:15:02.863Z,1578680102.863 [Micromodem](INFO): Nmea in: $CADRQ,181503,1,12,0,64,2*79
2020-01-10T18:15:02.863Z,1578680102.863 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE3678B3*43
2020-01-10T18:15:03.259Z,1578680103.259 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B
2020-01-10T18:15:03.671Z,1578680103.671 [Micromodem](INFO): Nmea in: $CADRQ,181503,1,12,0,64,3*78
2020-01-10T18:15:03.671Z,1578680103.671 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,336856ABAD201C56DA9459B2972F92771E52AFD3125AFE32B12BECEC01F085231029EB7855A2DC476123E9AA0209C8ED5B7C9CCF252EBB8660CD1248705CCACB*38
2020-01-10T18:15:04.087Z,1578680104.087 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B
2020-01-10T18:15:04.479Z,1578680104.479 [Micromodem](INFO): Nmea in: $CATXP,192*48
2020-01-10T18:15:08.107Z,1578680108.107 [Micromodem](INFO): Nmea in: $CATXF,192*5E
2020-01-10T18:15:08.516Z,1578680108.516 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,181504.765317,3,0,200,4000,10000,1,1,12,1,3,3,3,192*72
2020-01-10T18:15:09.328Z,1578680109.328 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79
2020-01-10T18:15:09.735Z,1578680109.735 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A
2020-01-10T18:15:10.131Z,1578680110.131 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B
2020-01-10T18:15:10.132Z,1578680110.132 [Micromodem](INFO): Sent 184 bytes from file Logs/20200110T180952/Courier0000.lzma.parts
2020-01-10T18:15:10.132Z,1578680110.132 [Micromodem](INFO): Packets left to send: 1
2020-01-10T18:15:10.538Z,1578680110.538 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110181509.318835,06,250,23,0136,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,21.5,6.00,15,-3.00,-01,-0.04,27,10000,4000*4C
2020-01-10T18:15:11.072Z,1578680111.072 [Micromodem](INFO): Outgoing frame #1, 58 bytes: F0BD185E000000001B0DEC2E53FAF36293906E1ABD86A7A3BE74A331D0DD26E6A0D6101D00DAF131D55BF8EAEB62E7717C6020EB6507C2E87640
2020-01-10T18:15:11.073Z,1578680111.073 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,1*6B
2020-01-10T18:15:11.995Z,1578680111.995 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,1*69
2020-01-10T18:15:12.395Z,1578680112.395 [Micromodem](INFO): Nmea in: $CADRQ,181512,1,12,0,64,1*7A
2020-01-10T18:15:12.395Z,1578680112.395 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,F0BD185E000000001B0DEC2E53FAF36293906E1ABD86A7A3BE74A331D0DD26E6A0D6101D00DAF131D55BF8EAEB62E7717C6020EB6507C2E87640*49
2020-01-10T18:15:12.799Z,1578680112.799 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,58*44
2020-01-10T18:15:13.210Z,1578680113.210 [Micromodem](INFO): Nmea in: $CATXP,58*7F
2020-01-10T18:15:14.415Z,1578680114.415 [Micromodem](INFO): Nmea in: $CATXF,58*69
2020-01-10T18:15:14.538Z,1578680114.538 [CommandLine](IMPORTANT): got command restart application
2020-01-10T18:15:14.832Z,1578680114.832 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,181513.460259,3,0,200,4000,10000,1,1,12,1,1,1,3,58*4E
2020-01-10T18:15:15.541Z,1578680115.541 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T18:15:15.541Z,1578680115.541 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-10T18:15:15.613Z,1578680115.613 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2020-01-10T18:15:15.613Z,1578680115.613 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-10T18:15:15.614Z,1578680115.614 [CommandLine](INFO): Join timeout helper Thread ID is 4647
2020-01-10T18:15:15.633Z,1578680115.633 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-01-10T18:15:15.633Z,1578680115.633 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-10T18:15:15.634Z,1578680115.634 [NavChartDb](INFO): Join timeout helper Thread ID is 4648
2020-01-10T18:15:15.636Z,1578680115.636 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79
2020-01-10T18:15:15.636Z,1578680115.636 [Micromodem](INFO): Sent 50 bytes from file Logs/20200110T180952/Courier0000.lzma.parts
2020-01-10T18:15:15.638Z,1578680115.638 [Micromodem](INFO): Packets left to send: 0
2020-01-10T18:15:15.685Z,1578680115.685 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T18:15:15.685Z,1578680115.685 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-10T18:15:15.686Z,1578680115.686 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-01-10T18:15:15.686Z,1578680115.686 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-10T18:15:15.686Z,1578680115.686 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 4649
2020-01-10T18:15:15.701Z,1578680115.701 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T18:15:15.701Z,1578680115.701 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-10T18:15:15.702Z,1578680115.702 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-10T18:15:15.702Z,1578680115.702 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2020-01-10T18:15:15.702Z,1578680115.702 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-10T18:15:15.703Z,1578680115.703 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 4650
2020-01-10T18:15:15.881Z,1578680115.881 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T18:15:15.881Z,1578680115.881 [CTD_NeilBrown](INFO): Powering down
2020-01-10T18:15:15.893Z,1578680115.893 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-10T18:15:15.901Z,1578680115.901 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-01-10T18:15:15.901Z,1578680115.901 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-10T18:15:15.901Z,1578680115.901 [Radio_Surface](INFO): Join timeout helper Thread ID is 4651
2020-01-10T18:15:16.038Z,1578680116.038 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110181515.473694,06,238,23,0119,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,25.8,7.00,17,-4.00,-01,-0.04,23,10000,4000*44
2020-01-10T18:15:16.041Z,1578680116.041 [Radio_Surface](INFO): Powering down
2020-01-10T18:15:16.042Z,1578680116.042 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T18:15:16.042Z,1578680116.042 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-10T18:15:16.050Z,1578680116.050 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-01-10T18:15:16.050Z,1578680116.050 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-10T18:15:16.050Z,1578680116.050 [DataOverHttps](INFO): Join timeout helper Thread ID is 4652
2020-01-10T18:15:16.677Z,1578680116.677 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T18:15:16.680Z,1578680116.680 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-10T18:15:16.686Z,1578680116.686 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-01-10T18:15:16.686Z,1578680116.686 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-10T18:15:16.686Z,1578680116.686 [logger](INFO): Join timeout helper Thread ID is 4653
2020-01-10T18:15:16.701Z,1578680116.701 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T18:15:16.701Z,1578680116.701 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-10T18:15:16.714Z,1578680116.714 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-01-10T18:15:16.714Z,1578680116.714 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-10T18:15:16.714Z,1578680116.714 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-01-10T18:15:16.714Z,1578680116.714 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-10T18:15:16.714Z,1578680116.714 [controlThread](INFO): Join timeout helper Thread ID is 4654
2020-01-10T18:15:16.886Z,1578680116.886 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 60BE185E000001005D000004009D0100000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6
2020-01-10T18:15:16.886Z,1578680116.886 [Micromodem](INFO): Outgoing frame #2, 64 bytes: AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE367CDD
2020-01-10T18:15:16.887Z,1578680116.887 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 69571810B7495D6CAF9FD6ABB2DA9F801967DEEE5C99E9C5412D90BAC7130D24E64197165DEE7DC1899F1EEE77C1DDEC70C2778818E89A8F049B55819DEC2CBE
2020-01-10T18:15:16.887Z,1578680116.887 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69
2020-01-10T18:15:17.068Z,1578680117.068 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T18:15:17.068Z,1578680117.068 [controlThread](DEBUG): Uninitializing ControlThread
2020-01-10T18:15:17.069Z,1578680117.069 [AHRS_M2](INFO): Powering down
2020-01-10T18:15:17.141Z,1578680117.141 [DDM](INFO): Powering down
2020-01-10T18:15:17.233Z,1578680117.233 [DUSBL_Hydroid](INFO): Powering down
2020-01-10T18:15:17.325Z,1578680117.325 [Micromodem](INFO): Powering down
2020-01-10T18:15:17.421Z,1578680117.421 [NAL9602](INFO): Powering down
2020-01-10T18:15:17.493Z,1578680117.493 [RDI_Pathfinder](INFO): Powering down
2020-01-10T18:15:17.495Z,1578680117.495 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-01-10T18:15:17.496Z,1578680117.496 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-01-10T18:15:17.496Z,1578680117.496 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-01-10T18:15:17.497Z,1578680117.497 [MissionManager](INFO): Uninitializing Mission Default
2020-01-10T18:15:17.499Z,1578680117.499 [MissionManager](INFO): Uninitializing Mission Startup
2020-01-10T18:15:17.499Z,1578680117.499 [Startup] Stopped
2020-01-10T18:15:17.499Z,1578680117.499 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-01-10T18:15:17.499Z,1578680117.499 [Startup:A.GoToSurface] Stopped
2020-01-10T18:15:17.499Z,1578680117.499 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-10T18:15:17.499Z,1578680117.499 [Startup:StartupSatComms] Stopped
2020-01-10T18:15:17.499Z,1578680117.499 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-01-10T18:15:17.499Z,1578680117.499 [Startup:StartupSatComms:B] Stopped
2020-01-10T18:15:17.501Z,1578680117.501 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-01-10T18:15:17.501Z,1578680117.501 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-01-10T18:15:17.501Z,1578680117.501 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-01-10T18:15:17.502Z,1578680117.502 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-01-10T18:15:17.502Z,1578680117.502 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-01-10T18:15:17.502Z,1578680117.502 [BuoyancyServo](INFO): Powering down
2020-01-10T18:15:17.517Z,1578680117.517 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-01-10T18:15:17.517Z,1578680117.517 [ElevatorServo](INFO): Powering down
2020-01-10T18:15:17.518Z,1578680117.518 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-01-10T18:15:17.518Z,1578680117.518 [MassServo](INFO): Powering down
2020-01-10T18:15:17.519Z,1578680117.519 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-10T18:15:17.519Z,1578680117.519 [RudderServo](INFO): Powering down
2020-01-10T18:15:17.519Z,1578680117.519 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-01-10T18:15:17.520Z,1578680117.520 [ThrusterServo](INFO): Powering down
2020-01-10T18:15:17.520Z,1578680117.520 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-01-10T18:15:17.521Z,1578680117.521 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-01-10T18:15:17.521Z,1578680117.521 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-01-10T18:15:17.521Z,1578680117.521 [CBIT](DEBUG): Powering off loads.
2020-01-10T18:15:17.533Z,1578680117.533 [CBIT](DEBUG): Disabling WDT.
2020-01-10T18:15:17.545Z,1578680117.545 [CBIT](DEBUG): Opening all GF detection circuits.
2020-01-10T18:15:17.545Z,1578680117.545 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-10T18:15:17.590Z,1578680117.590 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-10T18:15:17.601Z,1578680117.601 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-10T18:15:17.643Z,1578680117.643 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-10T18:15:17.646Z,1578680117.646 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-10T18:15:17.706Z,1578680117.706 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-10T18:15:17.767Z,1578680117.767 [logger ThreadHandler](INFO): Thread cancelled.