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.