2020-01-10T18:44:43.597Z,1578681883.597 [Supervisor](DEBUG): Initializing supervisor. 2020-01-10T18:44:43.600Z,1578681883.600 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-01-10T18:44:43.601Z,1578681883.601 [SyncHandler](INFO): Protected caller Thread ID is 9460 2020-01-10T18:44:43.602Z,1578681883.602 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-01-10T18:44:43.603Z,1578681883.603 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-01-10T18:44:43.603Z,1578681883.603 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9461 2020-01-10T18:44:43.607Z,1578681883.607 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-01-10T18:44:43.618Z,1578681883.618 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-01-10T18:44:43.619Z,1578681883.619 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-01-10T18:44:43.620Z,1578681883.620 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9462 2020-01-10T18:44:43.621Z,1578681883.621 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-01-10T18:44:43.622Z,1578681883.622 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-01-10T18:44:43.622Z,1578681883.622 [logger ThreadHandler](INFO): Protected caller Thread ID is 9463 2020-01-10T18:44:43.624Z,1578681883.624 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-01-10T18:44:43.624Z,1578681883.624 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-01-10T18:44:43.626Z,1578681883.626 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-01-10T18:44:44.045Z,1578681884.045 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-01-10T18:44:44.046Z,1578681884.046 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-01-10T18:44:44.140Z,1578681884.140 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-01-10T18:44:44.141Z,1578681884.141 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-01-10T18:44:44.452Z,1578681884.452 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-01-10T18:44:44.453Z,1578681884.453 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-01-10T18:44:44.597Z,1578681884.597 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-01-10T18:44:44.598Z,1578681884.598 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-01-10T18:44:44.852Z,1578681884.852 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-01-10T18:44:44.852Z,1578681884.852 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-01-10T18:44:45.307Z,1578681885.307 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-01-10T18:44:45.308Z,1578681885.308 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-01-10T18:44:45.509Z,1578681885.509 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-01-10T18:44:45.510Z,1578681885.510 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-01-10T18:44:45.649Z,1578681885.649 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-01-10T18:44:45.650Z,1578681885.650 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-01-10T18:44:45.830Z,1578681885.830 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-01-10T18:44:45.830Z,1578681885.830 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-01-10T18:44:45.924Z,1578681885.924 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-01-10T18:44:45.924Z,1578681885.924 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-01-10T18:44:46.216Z,1578681886.216 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-01-10T18:44:46.217Z,1578681886.217 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-01-10T18:44:46.295Z,1578681886.295 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-01-10T18:44:46.395Z,1578681886.395 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-01-10T18:44:46.396Z,1578681886.396 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-01-10T18:44:47.019Z,1578681887.019 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-01-10T18:44:47.020Z,1578681887.020 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-01-10T18:44:47.421Z,1578681887.421 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-01-10T18:44:47.423Z,1578681887.423 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/ 2020-01-10T18:44:47.424Z,1578681887.424 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg 2020-01-10T18:44:47.622Z,1578681887.622 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg 2020-01-10T18:44:47.717Z,1578681887.717 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg 2020-01-10T18:44:47.812Z,1578681887.812 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg 2020-01-10T18:44:48.039Z,1578681888.039 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-01-10T18:44:48.039Z,1578681888.039 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg 2020-01-10T18:44:48.122Z,1578681888.122 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg 2020-01-10T18:44:48.212Z,1578681888.212 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg 2020-01-10T18:44:48.306Z,1578681888.306 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg 2020-01-10T18:44:48.386Z,1578681888.386 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg 2020-01-10T18:44:48.490Z,1578681888.490 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg 2020-01-10T18:44:48.666Z,1578681888.666 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg 2020-01-10T18:44:48.791Z,1578681888.791 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/ 2020-01-10T18:44:48.792Z,1578681888.792 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2020-01-10T18:44:48.813Z,1578681888.813 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-01-10T18:44:49.120Z,1578681889.120 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2020-01-10T18:44:49.126Z,1578681889.126 [AHRS_M2](INFO): created writer for : platform_orientation 2020-01-10T18:44:49.127Z,1578681889.127 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2020-01-10T18:44:49.132Z,1578681889.132 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2020-01-10T18:44:49.133Z,1578681889.133 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2020-01-10T18:44:49.139Z,1578681889.139 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2020-01-10T18:44:49.139Z,1578681889.139 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2020-01-10T18:44:49.145Z,1578681889.145 [AHRS_M2](INFO): created writer for : platform_roll_angle 2020-01-10T18:44:49.217Z,1578681889.217 [AHRS_M2] Loaded 2020-01-10T18:44:49.218Z,1578681889.218 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2020-01-10T18:44:49.300Z,1578681889.300 [DataOverHttps] Loaded 2020-01-10T18:44:49.300Z,1578681889.300 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-01-10T18:44:49.301Z,1578681889.301 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407684E0 2020-01-10T18:44:49.301Z,1578681889.301 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9542 2020-01-10T18:44:49.326Z,1578681889.326 [DDM] Loaded 2020-01-10T18:44:49.327Z,1578681889.327 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread. 2020-01-10T18:44:49.340Z,1578681889.340 [Depth_Keller] Loaded 2020-01-10T18:44:49.341Z,1578681889.341 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-01-10T18:44:49.346Z,1578681889.346 [DropWeight] Loaded 2020-01-10T18:44:49.346Z,1578681889.346 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2020-01-10T18:44:49.410Z,1578681889.410 [DUSBL_Hydroid] Loaded 2020-01-10T18:44:49.410Z,1578681889.410 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread. 2020-01-10T18:44:49.458Z,1578681889.458 [Micromodem] Loaded 2020-01-10T18:44:49.458Z,1578681889.458 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread. 2020-01-10T18:44:49.558Z,1578681889.558 [NAL9602] Loaded 2020-01-10T18:44:49.559Z,1578681889.559 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-01-10T18:44:49.597Z,1578681889.597 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now 2020-01-10T18:44:49.598Z,1578681889.598 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now 2020-01-10T18:44:49.598Z,1578681889.598 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now 2020-01-10T18:44:49.599Z,1578681889.599 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full 2020-01-10T18:44:49.599Z,1578681889.599 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now 2020-01-10T18:44:49.600Z,1578681889.600 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now 2020-01-10T18:44:49.600Z,1578681889.600 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now 2020-01-10T18:44:49.601Z,1578681889.601 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full 2020-01-10T18:44:49.601Z,1578681889.601 [Onboard] Loaded 2020-01-10T18:44:49.601Z,1578681889.601 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2020-01-10T18:44:49.608Z,1578681889.608 [PowerOnly] Loaded 2020-01-10T18:44:49.608Z,1578681889.608 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2020-01-10T18:44:49.615Z,1578681889.615 [Radio_Surface] Loaded 2020-01-10T18:44:49.615Z,1578681889.615 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-01-10T18:44:49.616Z,1578681889.616 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407984E0 2020-01-10T18:44:49.616Z,1578681889.616 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9543 2020-01-10T18:44:49.662Z,1578681889.662 [RDI_Pathfinder] Loaded 2020-01-10T18:44:49.662Z,1578681889.662 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2020-01-10T18:44:51.713Z,1578681891.713 [BPC1] Loaded 2020-01-10T18:44:51.713Z,1578681891.713 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-01-10T18:44:51.714Z,1578681891.714 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-01-10T18:44:51.714Z,1578681891.714 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-01-10T18:44:51.728Z,1578681891.728 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-01-10T18:44:51.728Z,1578681891.728 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-01-10T18:44:51.835Z,1578681891.835 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-01-10T18:44:51.835Z,1578681891.835 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-01-10T18:44:51.856Z,1578681891.856 [NavChart] Loaded 2020-01-10T18:44:51.857Z,1578681891.857 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-01-10T18:44:51.861Z,1578681891.861 [UniversalFixResidualReporter] Loaded 2020-01-10T18:44:51.861Z,1578681891.861 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-01-10T18:44:51.861Z,1578681891.861 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-01-10T18:44:51.862Z,1578681891.862 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-01-10T18:44:51.970Z,1578681891.970 [BuoyancyServo] Loaded 2020-01-10T18:44:51.970Z,1578681891.970 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-01-10T18:44:51.986Z,1578681891.986 [ElevatorServo] Loaded 2020-01-10T18:44:51.986Z,1578681891.986 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-01-10T18:44:52.001Z,1578681892.001 [MassServo] Loaded 2020-01-10T18:44:52.002Z,1578681892.002 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-01-10T18:44:52.017Z,1578681892.017 [RudderServo] Loaded 2020-01-10T18:44:52.017Z,1578681892.017 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-01-10T18:44:52.032Z,1578681892.032 [ThrusterServo] Loaded 2020-01-10T18:44:52.033Z,1578681892.033 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-01-10T18:44:52.033Z,1578681892.033 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-01-10T18:44:52.034Z,1578681892.034 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-01-10T18:44:52.292Z,1578681892.292 [CTD_NeilBrown] Loaded 2020-01-10T18:44:52.293Z,1578681892.293 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2020-01-10T18:44:52.294Z,1578681892.294 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408EC4E0 2020-01-10T18:44:52.294Z,1578681892.294 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 9544 2020-01-10T18:44:52.309Z,1578681892.309 [PAR_Licor] Loaded 2020-01-10T18:44:52.309Z,1578681892.309 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2020-01-10T18:44:52.354Z,1578681892.354 [WetLabsSeaOWL_UV_A] Loaded 2020-01-10T18:44:52.355Z,1578681892.355 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2020-01-10T18:44:52.356Z,1578681892.356 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 4091C4E0 2020-01-10T18:44:52.356Z,1578681892.356 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 9545 2020-01-10T18:44:52.357Z,1578681892.357 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-01-10T18:44:52.357Z,1578681892.357 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-01-10T18:44:52.666Z,1578681892.666 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-01-10T18:44:52.667Z,1578681892.667 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-01-10T18:44:52.707Z,1578681892.707 [DepthRateCalculator] Loaded 2020-01-10T18:44:52.707Z,1578681892.707 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-01-10T18:44:52.713Z,1578681892.713 [PitchRateCalculator] Loaded 2020-01-10T18:44:52.714Z,1578681892.714 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-01-10T18:44:52.726Z,1578681892.726 [SpeedCalculator] Loaded 2020-01-10T18:44:52.726Z,1578681892.726 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-01-10T18:44:52.748Z,1578681892.748 [TempGradientCalculator] Loaded 2020-01-10T18:44:52.748Z,1578681892.748 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-01-10T18:44:52.754Z,1578681892.754 [YawRateCalculator] Loaded 2020-01-10T18:44:52.755Z,1578681892.755 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-01-10T18:44:52.796Z,1578681892.796 [ElevatorOffsetCalculator] Loaded 2020-01-10T18:44:52.797Z,1578681892.797 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-01-10T18:44:52.797Z,1578681892.797 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-01-10T18:44:52.798Z,1578681892.798 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-01-10T18:44:52.933Z,1578681892.933 [SBIT](DEBUG): Construct Startup Built In Test. 2020-01-10T18:44:52.956Z,1578681892.956 [SBIT] Loaded 2020-01-10T18:44:52.956Z,1578681892.956 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-01-10T18:44:52.957Z,1578681892.957 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-01-10T18:44:52.970Z,1578681892.970 [IBIT] Loaded 2020-01-10T18:44:52.970Z,1578681892.970 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-01-10T18:44:52.973Z,1578681892.973 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-01-10T18:44:53.117Z,1578681893.117 [CBIT] Loaded 2020-01-10T18:44:53.117Z,1578681893.117 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-01-10T18:44:53.118Z,1578681893.118 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-01-10T18:44:53.118Z,1578681893.118 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-01-10T18:44:53.201Z,1578681893.201 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-01-10T18:44:53.201Z,1578681893.201 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-01-10T18:44:53.299Z,1578681893.299 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-01-10T18:44:53.299Z,1578681893.299 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-01-10T18:44:53.348Z,1578681893.348 [VerticalControl](DEBUG): Construct VerticalControl. 2020-01-10T18:44:53.434Z,1578681893.434 [VerticalControl] Loaded 2020-01-10T18:44:53.434Z,1578681893.434 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-01-10T18:44:53.435Z,1578681893.435 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-01-10T18:44:53.508Z,1578681893.508 [HorizontalControl] Loaded 2020-01-10T18:44:53.508Z,1578681893.508 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-01-10T18:44:53.509Z,1578681893.509 [SpeedControl](DEBUG): Construct SpeedControl. 2020-01-10T18:44:53.511Z,1578681893.511 [SpeedControl] Loaded 2020-01-10T18:44:53.511Z,1578681893.511 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-01-10T18:44:53.511Z,1578681893.511 [LoopControl](DEBUG): Construct LoopControl. 2020-01-10T18:44:53.512Z,1578681893.512 [LoopControl] Loaded 2020-01-10T18:44:53.512Z,1578681893.512 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-01-10T18:44:53.513Z,1578681893.513 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-01-10T18:44:53.513Z,1578681893.513 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-01-10T18:44:53.546Z,1578681893.546 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-01-10T18:44:53.550Z,1578681893.550 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-01-10T18:44:53.550Z,1578681893.550 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-01-10T18:44:53.557Z,1578681893.557 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-01-10T18:44:53.558Z,1578681893.558 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AFB4E0 2020-01-10T18:44:53.559Z,1578681893.559 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9546 2020-01-10T18:44:53.563Z,1578681893.563 [Supervisor](INFO): Main Thread ID is 9339 2020-01-10T18:44:53.563Z,1578681893.563 [Supervisor](DEBUG): Running supervisor. 2020-01-10T18:44:53.564Z,1578681893.564 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9547 2020-01-10T18:44:53.566Z,1578681893.566 [controlThread ThreadHandler](INFO): Handler Thread ID is 9548 2020-01-10T18:44:53.567Z,1578681893.567 [controlThread](DEBUG): Initializing ControlThread 2020-01-10T18:44:53.572Z,1578681893.572 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-01-10T18:44:53.572Z,1578681893.572 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-01-10T18:44:53.577Z,1578681893.577 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-01-10T18:44:53.577Z,1578681893.577 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-01-10T18:44:53.578Z,1578681893.578 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-01-10T18:44:53.578Z,1578681893.578 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-01-10T18:44:53.578Z,1578681893.578 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-01-10T18:44:53.579Z,1578681893.579 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-01-10T18:44:53.579Z,1578681893.579 [SBIT](INFO): Initialize SBIT Component. 2020-01-10T18:44:53.580Z,1578681893.580 [SBIT](IMPORTANT): git: 2019-12-03-17-g4812c94 2020-01-10T18:44:53.580Z,1578681893.580 [SBIT](INFO): git hash: 4812c9429720da7730f61311b178b42396aebb36 2020-01-10T18:44:53.580Z,1578681893.580 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-01-10T18:44:53.582Z,1578681893.582 [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:44:53.583Z,1578681893.583 [SBIT](INFO): Beginning SBIT in 39.000000 seconds. 2020-01-10T18:44:53.583Z,1578681893.583 [IBIT](INFO): Initialize IBIT Component. 2020-01-10T18:44:53.584Z,1578681893.584 [CBIT](DEBUG): Initialize CBIT Component. 2020-01-10T18:44:53.585Z,1578681893.585 [logger ThreadHandler](INFO): Handler Thread ID is 9549 2020-01-10T18:44:53.597Z,1578681893.597 [CBIT](DEBUG): Initialized mux pins. 2020-01-10T18:44:53.597Z,1578681893.597 [CBIT](DEBUG): Initializing the watchdog timer. 2020-01-10T18:44:53.605Z,1578681893.605 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9550 2020-01-10T18:44:53.606Z,1578681893.606 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-01-10T18:44:53.617Z,1578681893.617 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9551 2020-01-10T18:44:53.621Z,1578681893.621 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2020-01-10T18:44:53.621Z,1578681893.621 [CBIT](DEBUG): Initializing heartbeat. 2020-01-10T18:44:53.629Z,1578681893.629 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 9552 2020-01-10T18:44:53.630Z,1578681893.630 [CTD_NeilBrown](INFO): Powering down 2020-01-10T18:44:53.654Z,1578681893.654 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 9553 2020-01-10T18:44:53.657Z,1578681893.657 [WetLabsSeaOWL_UV_A](INFO): Powering down 2020-01-10T18:44:53.681Z,1578681893.681 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9554 2020-01-10T18:44:53.684Z,1578681893.684 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-01-10T18:44:53.684Z,1578681893.684 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-01-10T18:44:53.685Z,1578681893.685 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-01-10T18:44:53.685Z,1578681893.685 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-01-10T18:44:53.685Z,1578681893.685 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-01-10T18:44:53.685Z,1578681893.685 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-01-10T18:44:53.686Z,1578681893.686 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-01-10T18:44:53.686Z,1578681893.686 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-01-10T18:44:53.686Z,1578681893.686 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-01-10T18:44:53.686Z,1578681893.686 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-01-10T18:44:53.686Z,1578681893.686 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-01-10T18:44:53.686Z,1578681893.686 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-01-10T18:44:53.687Z,1578681893.687 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-01-10T18:44:53.687Z,1578681893.687 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-01-10T18:44:53.687Z,1578681893.687 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-01-10T18:44:53.687Z,1578681893.687 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-01-10T18:44:53.693Z,1578681893.693 [CBIT](DEBUG): Deactivating GF circuits. 2020-01-10T18:44:53.693Z,1578681893.693 [CBIT](DEBUG): Deactivating emergency mode. 2020-01-10T18:44:53.729Z,1578681893.729 [CBIT](DEBUG): Backplane powered. 2020-01-10T18:44:53.729Z,1578681893.729 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-01-10T18:44:53.731Z,1578681893.731 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-01-10T18:44:53.731Z,1578681893.731 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-01-10T18:44:53.732Z,1578681893.732 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-01-10T18:44:53.733Z,1578681893.733 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-01-10T18:44:53.743Z,1578681893.743 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-01-10T18:44:53.766Z,1578681893.766 [MissionManager](DEBUG): 2020-01-10T18:44:53.766Z,1578681893.766 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-01-10T18:44:53.844Z,1578681893.844 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-01-10T18:44:53.845Z,1578681893.845 [Default:A.Wait](DEBUG): Construct Wait. 2020-01-10T18:44:53.847Z,1578681893.847 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-01-10T18:44:53.891Z,1578681893.891 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-01-10T18:44:53.894Z,1578681893.894 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-01-10T18:44:53.915Z,1578681893.915 [Default:E.Execute](DEBUG): Construct Execute. 2020-01-10T18:44:53.919Z,1578681893.919 [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:44:53.933Z,1578681893.933 [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:44:53.947Z,1578681893.947 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-01-10T18:44:53.979Z,1578681893.979 [DDM](INFO): Powering up 2020-01-10T18:44:53.979Z,1578681893.979 [DDM](DEBUG): Initializing DDM. 2020-01-10T18:44:54.007Z,1578681894.007 [DUSBL_Hydroid](INFO): Powering up 2020-01-10T18:44:54.007Z,1578681894.007 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2020-01-10T18:44:54.021Z,1578681894.021 [Radio_Surface](INFO): Powering up 2020-01-10T18:44:54.239Z,1578681894.239 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-01-10T18:44:54.245Z,1578681894.245 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-01-10T18:44:54.246Z,1578681894.246 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-01-10T18:44:54.253Z,1578681894.253 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-01-10T18:44:54.254Z,1578681894.254 [MassServo](DEBUG): Initializing EZServoServo. 2020-01-10T18:44:54.261Z,1578681894.261 [MassServo](DEBUG): Initializing MassServo. 2020-01-10T18:44:54.262Z,1578681894.262 [RudderServo](DEBUG): Initializing EZServoServo. 2020-01-10T18:44:54.269Z,1578681894.269 [RudderServo](DEBUG): Initializing RudderServo. 2020-01-10T18:44:54.270Z,1578681894.270 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-01-10T18:44:54.277Z,1578681894.277 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-01-10T18:44:54.358Z,1578681894.358 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2020-01-10T18:44:54.358Z,1578681894.358 [DropWeight] Hardware Fault, FailCount= 1 2020-01-10T18:44:54.358Z,1578681894.358 [DropWeight](ERROR): Hardware Fault 2020-01-10T18:44:54.360Z,1578681894.360 [Micromodem](INFO): Powering up 2020-01-10T18:44:54.360Z,1578681894.360 [Micromodem](DEBUG): Initializing Micromodem. 2020-01-10T18:44:54.436Z,1578681894.436 [CommandLine](FAULT): Scheduling is paused 2020-01-10T18:44:54.436Z,1578681894.436 [CBIT](INFO): Critical error at 20200110T184454 2020-01-10T18:44:54.437Z,1578681894.437 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2020-01-10T18:44:54.439Z,1578681894.439 [CBIT](ERROR): Hardware Fault in component: DropWeight 2020-01-10T18:44:54.440Z,1578681894.440 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2020-01-10T18:44:55.109Z,1578681895.109 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2020-01-10T18:44:55.109Z,1578681895.109 [RudderServo](FAULT): Rudder failed to initialize 2020-01-10T18:44:55.109Z,1578681895.109 [RudderServo] Communications Fault, FailCount= 1 2020-01-10T18:44:55.109Z,1578681895.109 [RudderServo](ERROR): Communications Fault 2020-01-10T18:44:55.218Z,1578681895.218 [CBIT](INFO): Critical error at 20200110T184454 2020-01-10T18:44:55.221Z,1578681895.221 [CBIT](ERROR): Communications Fault in component: RudderServo 2020-01-10T18:44:55.394Z,1578681895.394 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-01-10T18:44:55.394Z,1578681895.394 [RudderServo](INFO): Powering down 2020-01-10T18:44:56.081Z,1578681896.081 [RudderServo](DEBUG): Initializing EZServoServo. 2020-01-10T18:44:56.202Z,1578681896.202 [RudderServo](DEBUG): Initializing RudderServo. 2020-01-10T18:44:56.206Z,1578681896.206 [CBIT](INFO): Clearing failed state for component RudderServo 2020-01-10T18:44:56.206Z,1578681896.206 [RudderServo] No Fault, FailCount= 1 2020-01-10T18:44:59.309Z,1578681899.309 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33 2020-01-10T18:44:59.719Z,1578681899.719 [Micromodem](INFO): Nmea in: $CATMG,2020-01-10T18:44:59.196593Z,RTC,RTC*54 2020-01-10T18:44:59.719Z,1578681899.719 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-10T18:44:59.196593Z,RTC,RTC*54 2020-01-10T18:45:00.115Z,1578681900.115 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31 2020-01-10T18:45:00.115Z,1578681900.115 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31 2020-01-10T18:45:00.519Z,1578681900.519 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33 2020-01-10T18:45:00.519Z,1578681900.519 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64 2020-01-10T18:45:00.923Z,1578681900.923 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66 2020-01-10T18:45:00.923Z,1578681900.923 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F 2020-01-10T18:45:01.331Z,1578681901.331 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D 2020-01-10T18:45:01.331Z,1578681901.331 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E 2020-01-10T18:45:01.735Z,1578681901.735 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C 2020-01-10T18:45:01.735Z,1578681901.735 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46 2020-01-10T18:45:02.123Z,1578681902.123 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44 2020-01-10T18:45:02.123Z,1578681902.123 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63 2020-01-10T18:45:02.543Z,1578681902.543 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61 2020-01-10T18:45:02.543Z,1578681902.543 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A 2020-01-10T18:45:02.919Z,1578681902.919 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38 2020-01-10T18:45:02.920Z,1578681902.920 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,10,18,45,03*4F 2020-01-10T18:45:03.331Z,1578681903.331 [Micromodem](INFO): Nmea in: $CACLK,2020,1,10,18,45,3*4D 2020-01-10T18:45:03.332Z,1578681903.332 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-10T18:45:04Z*7A 2020-01-10T18:45:03.334Z,1578681903.334 [Micromodem](INFO): Nmea in: $CATMG,2020-01-10T18:45:04.026650Z,USER_CMD,RTC*1A 2020-01-10T18:45:04.534Z,1578681904.534 [DDM](INFO): Dynamic Docking Module:!C REMUS Capture Rev 1.1 2020-01-10T18:45:07.389Z,1578681907.389 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2020-01-10T18:45:12.210Z,1578681912.210 [DUSBL_Hydroid](INFO): DUSBL Version:O 2020-01-10T18:45:19.887Z,1578681919.887 [NAL9602](INFO): Powering up NAL9602 2020-01-10T18:45:25.132Z,1578681925.132 [Micromodem](INFO): Nmea in: $CACYC,1,12,1,1,0,1*68 2020-01-10T18:45:25.132Z,1578681925.132 [Micromodem](INFO): Have incoming data 2020-01-10T18:45:25.135Z,1578681925.135 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,1,3D90B8D172D0E11A94BB9A0D6EC0434A2AB488F6*51 2020-01-10T18:45:25.135Z,1578681925.135 [Micromodem](INFO): Frame:1 2020-01-10T18:45:25.135Z,1578681925.135 [Micromodem](INFO): Frame Size:-18 2020-01-10T18:45:25.136Z,1578681925.136 [Micromodem](INFO): Data End:-2 2020-01-10T18:45:25.136Z,1578681925.136 [Micromodem](INFO): Data At:16 2020-01-10T18:45:25.136Z,1578681925.136 [Micromodem](INFO): Deserialize. FrameCount:1 2020-01-10T18:45:25.136Z,1578681925.136 [Micromodem](INFO): Outer loop. Size:-18 2020-01-10T18:45:25.136Z,1578681925.136 [Micromodem](INFO): Sending to RXSBD Size:0 2020-01-10T18:45:25.185Z,1578681925.185 [Micromodem](CRITICAL): Packet size is not N*16+4: 0x0 2020-01-10T18:45:25.185Z,1578681925.185 [Micromodem](CRITICAL): Failed to parse uplink message: 2020-01-10T18:45:25.287Z,1578681925.287 [CBIT](INFO): Critical error at 20200110T184525 2020-01-10T18:45:25.546Z,1578681925.546 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110184523.847106,06,1783,32,0136,0150,59,02,02,00,03,1,012,001,0,3,1,0,150,7.8,8.00,00,-5.00,-01,-0.02,42,10000,4000*44 2020-01-10T18:45:30.791Z,1578681930.791 [NAL9602](INFO): NAL9602 initialized 2020-01-10T18:45:33.303Z,1578681933.303 [SBIT](IMPORTANT): Beginning Startup BIT 2020-01-10T18:45:33.311Z,1578681933.311 [CBIT](IMPORTANT): Beginning ground fault scan 2020-01-10T18:45:44.463Z,1578681944.463 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.012176 CHAN A1 (24V): -0.029119 CHAN A2 (12V): -0.007700 CHAN A3 (5V): -0.002119 CHAN B0 (3.3V): 0.000382 CHAN B1 (3.15aV): -0.000008 CHAN B2 (3.15bV): 0.000204 CHAN B3 (GND): 0.001824 OPEN: 0.005020 Full Scale Calc: 4.765 mA, -1.589 mA 2020-01-10T18:45:56.655Z,1578681956.655 [Micromodem](INFO): Nmea in: $CACYC,1,12,1,1,0,1*68 2020-01-10T18:45:56.656Z,1578681956.656 [Micromodem](INFO): Have incoming data 2020-01-10T18:45:56.658Z,1578681956.658 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,1,3D90B8D172D0E11A94BB9A0D6EC0434A2AB488F6*51 2020-01-10T18:45:56.659Z,1578681956.659 [Micromodem](INFO): Frame:1 2020-01-10T18:45:56.659Z,1578681956.659 [Micromodem](INFO): Frame Size:41 2020-01-10T18:45:56.659Z,1578681956.659 [Micromodem](INFO): Data End:57 2020-01-10T18:45:56.659Z,1578681956.659 [Micromodem](INFO): Data At:16 2020-01-10T18:45:56.659Z,1578681956.659 [Micromodem](INFO): Deserialize. FrameCount:1 2020-01-10T18:45:56.660Z,1578681956.660 [Micromodem](INFO): Outer loop. Size:41 2020-01-10T18:45:56.660Z,1578681956.660 [Micromodem](INFO): Inner loop. C:61 2020-01-10T18:45:56.660Z,1578681956.660 [Micromodem](INFO): Inner loop. C:144 2020-01-10T18:45:56.660Z,1578681956.660 [Micromodem](INFO): Inner loop. C:184 2020-01-10T18:45:56.660Z,1578681956.660 [Micromodem](INFO): Inner loop. C:209 2020-01-10T18:45:56.660Z,1578681956.660 [Micromodem](INFO): Inner loop. C:114 2020-01-10T18:45:56.661Z,1578681956.661 [Micromodem](INFO): Inner loop. C:208 2020-01-10T18:45:56.661Z,1578681956.661 [Micromodem](INFO): Inner loop. C:225 2020-01-10T18:45:56.661Z,1578681956.661 [Micromodem](INFO): Inner loop. C:26 2020-01-10T18:45:56.661Z,1578681956.661 [Micromodem](INFO): Inner loop. C:148 2020-01-10T18:45:56.662Z,1578681956.662 [Micromodem](INFO): Inner loop. C:187 2020-01-10T18:45:56.662Z,1578681956.662 [Micromodem](INFO): Inner loop. C:154 2020-01-10T18:45:56.662Z,1578681956.662 [Micromodem](INFO): Inner loop. C:13 2020-01-10T18:45:56.662Z,1578681956.662 [Micromodem](INFO): Inner loop. C:110 2020-01-10T18:45:56.662Z,1578681956.662 [Micromodem](INFO): Inner loop. C:192 2020-01-10T18:45:56.663Z,1578681956.663 [Micromodem](INFO): Inner loop. C:67 2020-01-10T18:45:56.663Z,1578681956.663 [Micromodem](INFO): Inner loop. C:74 2020-01-10T18:45:56.663Z,1578681956.663 [Micromodem](INFO): Inner loop. C:42 2020-01-10T18:45:56.663Z,1578681956.663 [Micromodem](INFO): Inner loop. C:180 2020-01-10T18:45:56.663Z,1578681956.663 [Micromodem](INFO): Inner loop. C:136 2020-01-10T18:45:56.663Z,1578681956.663 [Micromodem](INFO): Inner loop. C:246 2020-01-10T18:45:56.664Z,1578681956.664 [Micromodem](INFO): Sending to RXSBD Size:20 2020-01-10T18:45:56.665Z,1578681956.665 [Micromodem](INFO): Received command:stop 2020-01-10T18:45:56.686Z,1578681956.686 [CommandLine](IMPORTANT): got command stop 2020-01-10T18:45:56.686Z,1578681956.686 [CommandLine](IMPORTANT): Scheduling is paused 2020-01-10T18:45:57.062Z,1578681957.062 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110184555.490894,06,1938,32,0145,0150,59,02,02,00,03,1,012,001,0,3,1,0,150,26.0,8.00,00,-5.00,-01,-0.02,25,10000,4000*71 2020-01-10T18:46:12.927Z,1578681972.927 [SBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-15.011020 2020-01-10T18:46:12.927Z,1578681972.927 [SBIT](FAULT): Control surface position failure. 2020-01-10T18:46:27.146Z,1578681987.146 [SBIT](CRITICAL): SBIT FAILED 2020-01-10T18:46:27.208Z,1578681987.208 [CommandLine](FAULT): Scheduling is paused 2020-01-10T18:46:27.208Z,1578681987.208 [CBIT](INFO): Critical error at 20200110T184627 2020-01-10T18:46:27.237Z,1578681987.237 [CommandLine](IMPORTANT): got command configSet list 2020-01-10T18:46:27.238Z,1578681987.238 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-01-10T18:46:27.239Z,1578681987.239 [CommandLine](IMPORTANT): DDM.loadAtStartup=1 bool; 2020-01-10T18:46:27.239Z,1578681987.239 [CommandLine](IMPORTANT): DDM.verbosity=3 count; 2020-01-10T18:46:27.239Z,1578681987.239 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=75 count; 2020-01-10T18:46:27.239Z,1578681987.239 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout=40 millisecond; 2020-01-10T18:46:27.239Z,1578681987.239 [CommandLine](IMPORTANT): Express linearApproximation DDM.DDMMode 1.000000 count; 2020-01-10T18:46:27.239Z,1578681987.239 [CommandLine](IMPORTANT): Express all DDM.cablePresent; 2020-01-10T18:46:27.239Z,1578681987.239 [CommandLine](IMPORTANT): Express linearApproximation DDM.latchState 1.000000 count; 2020-01-10T18:46:27.239Z,1578681987.239 [CommandLine](IMPORTANT): Express linearApproximation DDM.whiskerState 1.000000 count; 2020-01-10T18:46:27.239Z,1578681987.239 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.acoustic_contact_range 1.000000 meter; 2020-01-10T18:46:27.240Z,1578681987.240 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 2.000000 degree; 2020-01-10T18:46:27.240Z,1578681987.240 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.height_above_sea_floor 2.000000 meter; 2020-01-10T18:46:27.240Z,1578681987.240 [CommandLine](IMPORTANT): Express linearApproximation platform_orientation 5.000000 degree; 2020-01-10T18:46:27.240Z,1578681987.240 [CommandLine](IMPORTANT): Express linearApproximation platform_x_velocity_wrt_ground 0.100000 meter_per_second; 2020-01-10T18:46:27.240Z,1578681987.240 [CommandLine](IMPORTANT): Express linearApproximation platform_y_velocity_wrt_ground 0.100000 meter_per_second; 2020-01-10T18:46:27.240Z,1578681987.240 [CommandLine](IMPORTANT): HorizontalControl.kiHeading=0.002 reciprocal_second; 2020-01-10T18:46:27.240Z,1578681987.240 [CommandLine](IMPORTANT): HorizontalControl.kpHeading=0.8 none; 2020-01-10T18:46:27.240Z,1578681987.240 [CommandLine](IMPORTANT): HorizontalControl.rudDeadband=0.05 degree; 2020-01-10T18:46:27.240Z,1578681987.240 [CommandLine](IMPORTANT): Micromodem.destinationAddress=12 enum; 2020-01-10T18:46:27.241Z,1578681987.241 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold=0 meter; 2020-01-10T18:46:27.241Z,1578681987.241 [CommandLine](IMPORTANT): PowerOnly.sampleTime=90 second; 2020-01-10T18:46:27.241Z,1578681987.241 [CommandLine](IMPORTANT): RudderServo.deviationAngle=0.0001 degree; 2020-01-10T18:46:27.241Z,1578681987.241 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter; 2020-01-10T18:46:27.241Z,1578681987.241 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter; 2020-01-10T18:46:27.582Z,1578681987.582 [MissionManager](IMPORTANT): Started mission Startup 2020-01-10T18:46:27.582Z,1578681987.582 [Startup] Running Loop=1 2020-01-10T18:46:27.582Z,1578681987.582 [Startup](DEBUG): Aggregate::initialize Startup 2020-01-10T18:46:27.582Z,1578681987.582 [Startup:A.GoToSurface] Running Loop=1 2020-01-10T18:46:27.582Z,1578681987.582 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-01-10T18:46:27.583Z,1578681987.583 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-01-10T18:46:27.584Z,1578681987.584 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-01-10T18:46:27.584Z,1578681987.584 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-01-10T18:46:27.584Z,1578681987.584 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-01-10T18:46:27.589Z,1578681987.589 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-01-10T18:46:27.590Z,1578681987.590 [Startup:StartupSatComms] Running Loop=1 2020-01-10T18:46:27.591Z,1578681987.591 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2020-01-10T18:46:27.591Z,1578681987.591 [Startup:StartupSatComms:A] Running Loop=1 2020-01-10T18:46:27.921Z,1578681987.921 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2020-01-10T18:46:37.981Z,1578681997.981 [Micromodem](INFO): Nmea in: $CACYC,1,12,1,1,0,2*6B 2020-01-10T18:46:37.982Z,1578681997.982 [Micromodem](INFO): Have incoming data 2020-01-10T18:46:37.990Z,1578681997.990 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,1,858A1AB878DB70DBE89704525FDC95B5C5FE9F4147C09B083E4A8CE30F9EA23DB7C2EC1A3F0C8416B2D6BA469C63028C955B1EDAD054116D0BA2C541AD2A74E9*5A 2020-01-10T18:46:37.991Z,1578681997.991 [Micromodem](INFO): Frame:1 2020-01-10T18:46:37.991Z,1578681997.991 [Micromodem](INFO): Frame Size:129 2020-01-10T18:46:37.992Z,1578681997.992 [Micromodem](INFO): Data End:145 2020-01-10T18:46:37.992Z,1578681997.992 [Micromodem](INFO): Data At:16 2020-01-10T18:46:38.389Z,1578681998.389 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,2,4EEE1310BA042F91D0BD31A76379D458F2E8C122*52 2020-01-10T18:46:38.389Z,1578681998.389 [Micromodem](INFO): Frame:2 2020-01-10T18:46:38.390Z,1578681998.390 [Micromodem](INFO): Frame Size:41 2020-01-10T18:46:38.390Z,1578681998.390 [Micromodem](INFO): Data End:57 2020-01-10T18:46:38.390Z,1578681998.390 [Micromodem](INFO): Data At:16 2020-01-10T18:46:38.390Z,1578681998.390 [Micromodem](INFO): Deserialize. FrameCount:2 2020-01-10T18:46:38.390Z,1578681998.390 [Micromodem](INFO): Outer loop. Size:129 2020-01-10T18:46:38.391Z,1578681998.391 [Micromodem](INFO): Inner loop. C:133 2020-01-10T18:46:38.391Z,1578681998.391 [Micromodem](INFO): Inner loop. C:138 2020-01-10T18:46:38.391Z,1578681998.391 [Micromodem](INFO): Inner loop. C:26 2020-01-10T18:46:38.391Z,1578681998.391 [Micromodem](INFO): Inner loop. C:184 2020-01-10T18:46:38.391Z,1578681998.391 [Micromodem](INFO): Inner loop. C:120 2020-01-10T18:46:38.391Z,1578681998.391 [Micromodem](INFO): Inner loop. C:219 2020-01-10T18:46:38.392Z,1578681998.392 [Micromodem](INFO): Inner loop. C:112 2020-01-10T18:46:38.392Z,1578681998.392 [Micromodem](INFO): Inner loop. C:219 2020-01-10T18:46:38.392Z,1578681998.392 [Micromodem](INFO): Inner loop. C:232 2020-01-10T18:46:38.392Z,1578681998.392 [Micromodem](INFO): Inner loop. C:151 2020-01-10T18:46:38.392Z,1578681998.392 [Micromodem](INFO): Inner loop. C:4 2020-01-10T18:46:38.425Z,1578681998.425 [Micromodem](INFO): Inner loop. C:82 2020-01-10T18:46:38.425Z,1578681998.425 [Micromodem](INFO): Inner loop. C:95 2020-01-10T18:46:38.425Z,1578681998.425 [Micromodem](INFO): Inner loop. C:220 2020-01-10T18:46:38.425Z,1578681998.425 [Micromodem](INFO): Inner loop. C:149 2020-01-10T18:46:38.426Z,1578681998.426 [Micromodem](INFO): Inner loop. C:181 2020-01-10T18:46:38.426Z,1578681998.426 [Micromodem](INFO): Inner loop. C:197 2020-01-10T18:46:38.426Z,1578681998.426 [Micromodem](INFO): Inner loop. C:254 2020-01-10T18:46:38.426Z,1578681998.426 [Micromodem](INFO): Inner loop. C:159 2020-01-10T18:46:38.426Z,1578681998.426 [Micromodem](INFO): Inner loop. C:65 2020-01-10T18:46:38.427Z,1578681998.427 [Micromodem](INFO): Inner loop. C:71 2020-01-10T18:46:38.427Z,1578681998.427 [Micromodem](INFO): Inner loop. C:192 2020-01-10T18:46:38.427Z,1578681998.427 [Micromodem](INFO): Inner loop. C:155 2020-01-10T18:46:38.427Z,1578681998.427 [Micromodem](INFO): Inner loop. C:8 2020-01-10T18:46:38.427Z,1578681998.427 [Micromodem](INFO): Inner loop. C:62 2020-01-10T18:46:38.427Z,1578681998.427 [Micromodem](INFO): Inner loop. C:74 2020-01-10T18:46:38.428Z,1578681998.428 [Micromodem](INFO): Inner loop. C:140 2020-01-10T18:46:38.428Z,1578681998.428 [Micromodem](INFO): Inner loop. C:227 2020-01-10T18:46:38.428Z,1578681998.428 [Micromodem](INFO): Inner loop. C:15 2020-01-10T18:46:38.428Z,1578681998.428 [Micromodem](INFO): Inner loop. C:158 2020-01-10T18:46:38.428Z,1578681998.428 [Micromodem](INFO): Inner loop. C:162 2020-01-10T18:46:38.428Z,1578681998.428 [Micromodem](INFO): Inner loop. C:61 2020-01-10T18:46:38.433Z,1578681998.433 [Micromodem](INFO): Inner loop. C:183 2020-01-10T18:46:38.434Z,1578681998.434 [Micromodem](INFO): Inner loop. C:194 2020-01-10T18:46:38.434Z,1578681998.434 [Micromodem](INFO): Inner loop. C:236 2020-01-10T18:46:38.434Z,1578681998.434 [Micromodem](INFO): Inner loop. C:26 2020-01-10T18:46:38.434Z,1578681998.434 [Micromodem](INFO): Inner loop. C:63 2020-01-10T18:46:38.434Z,1578681998.434 [Micromodem](INFO): Inner loop. C:12 2020-01-10T18:46:38.435Z,1578681998.435 [Micromodem](INFO): Inner loop. C:132 2020-01-10T18:46:38.435Z,1578681998.435 [Micromodem](INFO): Inner loop. C:22 2020-01-10T18:46:38.435Z,1578681998.435 [Micromodem](INFO): Inner loop. C:178 2020-01-10T18:46:38.435Z,1578681998.435 [Micromodem](INFO): Inner loop. C:214 2020-01-10T18:46:38.435Z,1578681998.435 [Micromodem](INFO): Inner loop. C:186 2020-01-10T18:46:38.435Z,1578681998.435 [Micromodem](INFO): Inner loop. C:70 2020-01-10T18:46:38.436Z,1578681998.436 [Micromodem](INFO): Inner loop. C:156 2020-01-10T18:46:38.436Z,1578681998.436 [Micromodem](INFO): Inner loop. C:99 2020-01-10T18:46:38.436Z,1578681998.436 [Micromodem](INFO): Inner loop. C:2 2020-01-10T18:46:38.436Z,1578681998.436 [Micromodem](INFO): Inner loop. C:140 2020-01-10T18:46:38.436Z,1578681998.436 [Micromodem](INFO): Inner loop. C:149 2020-01-10T18:46:38.449Z,1578681998.449 [Micromodem](INFO): Inner loop. C:91 2020-01-10T18:46:38.449Z,1578681998.449 [Micromodem](INFO): Inner loop. C:30 2020-01-10T18:46:38.449Z,1578681998.449 [Micromodem](INFO): Inner loop. C:218 2020-01-10T18:46:38.449Z,1578681998.449 [Micromodem](INFO): Inner loop. C:208 2020-01-10T18:46:38.450Z,1578681998.450 [Micromodem](INFO): Inner loop. C:84 2020-01-10T18:46:38.450Z,1578681998.450 [Micromodem](INFO): Inner loop. C:17 2020-01-10T18:46:38.450Z,1578681998.450 [Micromodem](INFO): Inner loop. C:109 2020-01-10T18:46:38.450Z,1578681998.450 [Micromodem](INFO): Inner loop. C:11 2020-01-10T18:46:38.450Z,1578681998.450 [Micromodem](INFO): Inner loop. C:162 2020-01-10T18:46:38.450Z,1578681998.450 [Micromodem](INFO): Inner loop. C:197 2020-01-10T18:46:38.451Z,1578681998.451 [Micromodem](INFO): Inner loop. C:65 2020-01-10T18:46:38.451Z,1578681998.451 [Micromodem](INFO): Inner loop. C:173 2020-01-10T18:46:38.451Z,1578681998.451 [Micromodem](INFO): Inner loop. C:42 2020-01-10T18:46:38.451Z,1578681998.451 [Micromodem](INFO): Inner loop. C:116 2020-01-10T18:46:38.451Z,1578681998.451 [Micromodem](INFO): Inner loop. C:233 2020-01-10T18:46:38.452Z,1578681998.452 [Micromodem](INFO): Outer loop. Size:41 2020-01-10T18:46:38.452Z,1578681998.452 [Micromodem](INFO): Inner loop. C:78 2020-01-10T18:46:38.452Z,1578681998.452 [Micromodem](INFO): Inner loop. C:238 2020-01-10T18:46:38.452Z,1578681998.452 [Micromodem](INFO): Inner loop. C:19 2020-01-10T18:46:38.452Z,1578681998.452 [Micromodem](INFO): Inner loop. C:16 2020-01-10T18:46:38.452Z,1578681998.452 [Micromodem](INFO): Inner loop. C:186 2020-01-10T18:46:38.461Z,1578681998.461 [Micromodem](INFO): Inner loop. C:4 2020-01-10T18:46:38.461Z,1578681998.461 [Micromodem](INFO): Inner loop. C:47 2020-01-10T18:46:38.461Z,1578681998.461 [Micromodem](INFO): Inner loop. C:145 2020-01-10T18:46:38.461Z,1578681998.461 [Micromodem](INFO): Inner loop. C:208 2020-01-10T18:46:38.462Z,1578681998.462 [Micromodem](INFO): Inner loop. C:189 2020-01-10T18:46:38.462Z,1578681998.462 [Micromodem](INFO): Inner loop. C:49 2020-01-10T18:46:38.462Z,1578681998.462 [Micromodem](INFO): Inner loop. C:167 2020-01-10T18:46:38.462Z,1578681998.462 [Micromodem](INFO): Inner loop. C:99 2020-01-10T18:46:38.462Z,1578681998.462 [Micromodem](INFO): Inner loop. C:121 2020-01-10T18:46:38.462Z,1578681998.462 [Micromodem](INFO): Inner loop. C:212 2020-01-10T18:46:38.463Z,1578681998.463 [Micromodem](INFO): Inner loop. C:88 2020-01-10T18:46:38.463Z,1578681998.463 [Micromodem](INFO): Inner loop. C:242 2020-01-10T18:46:38.463Z,1578681998.463 [Micromodem](INFO): Inner loop. C:232 2020-01-10T18:46:38.463Z,1578681998.463 [Micromodem](INFO): Inner loop. C:193 2020-01-10T18:46:38.463Z,1578681998.463 [Micromodem](INFO): Inner loop. C:34 2020-01-10T18:46:38.464Z,1578681998.464 [Micromodem](INFO): Sending to RXSBD Size:84 2020-01-10T18:46:38.464Z,1578681998.464 [Micromodem](INFO): Received command:failc;stop;configSet list;get platform_orientation degree;stop;failc 2020-01-10T18:46:41.542Z,1578682001.542 [CommandLine](IMPORTANT): got command failComponent 2020-01-10T18:46:41.542Z,1578682001.542 [CommandLine](IMPORTANT): Failed components: 2020-01-10T18:46:41.542Z,1578682001.542 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2020-01-10T18:46:41.543Z,1578682001.543 [CommandLine](IMPORTANT): got command stop 2020-01-10T18:46:41.543Z,1578682001.543 [CommandLine](IMPORTANT): Scheduling is paused 2020-01-10T18:46:41.543Z,1578682001.543 [CommandLine](IMPORTANT): got command configSet list 2020-01-10T18:46:41.543Z,1578682001.543 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-01-10T18:46:41.544Z,1578682001.544 [CommandLine](IMPORTANT): DDM.loadAtStartup=1 bool; 2020-01-10T18:46:41.544Z,1578682001.544 [CommandLine](IMPORTANT): DDM.verbosity=3 count; 2020-01-10T18:46:41.545Z,1578682001.545 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=75 count; 2020-01-10T18:46:41.546Z,1578682001.546 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout=40 millisecond; 2020-01-10T18:46:41.546Z,1578682001.546 [CommandLine](IMPORTANT): Express linearApproximation DDM.DDMMode 1.000000 count; 2020-01-10T18:46:41.546Z,1578682001.546 [CommandLine](IMPORTANT): Express all DDM.cablePresent; 2020-01-10T18:46:41.546Z,1578682001.546 [CommandLine](IMPORTANT): Express linearApproximation DDM.latchState 1.000000 count; 2020-01-10T18:46:41.546Z,1578682001.546 [CommandLine](IMPORTANT): Express linearApproximation DDM.whiskerState 1.000000 count; 2020-01-10T18:46:41.546Z,1578682001.546 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.acoustic_contact_range 1.000000 meter; 2020-01-10T18:46:41.546Z,1578682001.546 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 2.000000 degree; 2020-01-10T18:46:41.546Z,1578682001.546 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.height_above_sea_floor 2.000000 meter; 2020-01-10T18:46:41.546Z,1578682001.546 [CommandLine](IMPORTANT): Express linearApproximation platform_orientation 5.000000 degree; 2020-01-10T18:46:41.547Z,1578682001.547 [CommandLine](IMPORTANT): Express linearApproximation platform_x_velocity_wrt_ground 0.100000 meter_per_second; 2020-01-10T18:46:41.547Z,1578682001.547 [CommandLine](IMPORTANT): Express linearApproximation platform_y_velocity_wrt_ground 0.100000 meter_per_second; 2020-01-10T18:46:41.547Z,1578682001.547 [CommandLine](IMPORTANT): HorizontalControl.kiHeading=0.002 reciprocal_second; 2020-01-10T18:46:41.547Z,1578682001.547 [CommandLine](IMPORTANT): HorizontalControl.kpHeading=0.8 none; 2020-01-10T18:46:41.547Z,1578682001.547 [CommandLine](IMPORTANT): HorizontalControl.rudDeadband=0.05 degree; 2020-01-10T18:46:41.547Z,1578682001.547 [CommandLine](IMPORTANT): Micromodem.destinationAddress=12 enum; 2020-01-10T18:46:41.547Z,1578682001.547 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold=0 meter; 2020-01-10T18:46:41.547Z,1578682001.547 [CommandLine](IMPORTANT): PowerOnly.sampleTime=90 second; 2020-01-10T18:46:41.547Z,1578682001.547 [CommandLine](IMPORTANT): RudderServo.deviationAngle=0.0001 degree; 2020-01-10T18:46:41.548Z,1578682001.548 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter; 2020-01-10T18:46:41.548Z,1578682001.548 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter; 2020-01-10T18:46:41.548Z,1578682001.548 [CommandLine](IMPORTANT): got command get platform_orientation degree 2020-01-10T18:46:41.549Z,1578682001.549 [CommandLine](IMPORTANT): platform_orientation 72.689503 arcdeg 2020-01-10T18:46:41.549Z,1578682001.549 [CommandLine](IMPORTANT): got command stop 2020-01-10T18:46:41.550Z,1578682001.550 [CommandLine](IMPORTANT): got command failComponent 2020-01-10T18:46:41.550Z,1578682001.550 [CommandLine](IMPORTANT): Failed components: 2020-01-10T18:46:41.550Z,1578682001.550 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2020-01-10T18:46:41.582Z,1578682001.582 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110184635.769270,06,1606,32,0122,0150,59,02,02,00,03,1,012,001,0,3,2,0,150,26.7,8.00,00,-5.00,-01,-0.02,24,10000,4000*77 2020-01-10T18:46:59.453Z,1578682019.453 [NAL9602](INFO): SBD MO Status=2, MOMSN=10156, MT Status=2, MTMSN=0 2020-01-10T18:46:59.453Z,1578682019.453 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-01-10T18:47:19.648Z,1578682039.648 [NAL9602](INFO): SBD MO Status=2, MOMSN=10156, MT Status=2, MTMSN=0 2020-01-10T18:47:19.648Z,1578682039.648 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-01-10T18:47:22.851Z,1578682042.851 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004978 2020-01-10T18:47:27.799Z,1578682047.799 [Startup:StartupSatComms:A](INFO): Timed out from 2020-01-10T18:46:27.6Z 2020-01-10T18:47:27.799Z,1578682047.799 [Startup:StartupSatComms:A] Stopped 2020-01-10T18:47:27.799Z,1578682047.799 [Startup:StartupSatComms:B] Running Loop=1 2020-01-10T18:47:28.276Z,1578682048.276 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-01-10T18:47:30.667Z,1578682050.667 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 8AC2185E000000005D00000400900000000000000000151D80030C5669BF31555EAE1409A708CE2489EC102F971AAFC35C3D87A14EFD88D7E667BF45C1E3A334 2020-01-10T18:47:30.668Z,1578682050.668 [Micromodem](INFO): Outgoing frame #2, 64 bytes: DEE7DE88DE21C57ECB16047A05CB4DEC750D7E1827D955ADD8BEBC7E9CD59055B48A8F61691C351C17E66CA151234BE2AD587A82E3BD9F77FEBFB98898985D41 2020-01-10T18:47:30.668Z,1578682050.668 [Micromodem](INFO): Outgoing frame #3, 16 bytes: F0A722DB0DB2A914AB66E1E4FD3B0000 2020-01-10T18:47:30.668Z,1578682050.668 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69 2020-01-10T18:47:30.721Z,1578682050.721 [NAL9602](INFO): SBD MO Status=2, MOMSN=10156, MT Status=2, MTMSN=0 2020-01-10T18:47:30.721Z,1578682050.721 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-01-10T18:47:30.906Z,1578682050.906 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B 2020-01-10T18:47:31.259Z,1578682051.259 [Micromodem](INFO): Nmea in: $CADRQ,184731,1,12,0,64,1*7C 2020-01-10T18:47:31.259Z,1578682051.259 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,8AC2185E000000005D00000400900000000000000000151D80030C5669BF31555EAE1409A708CE2489EC102F971AAFC35C3D87A14EFD88D7E667BF45C1E3A334*40 2020-01-10T18:47:31.655Z,1578682051.655 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B 2020-01-10T18:47:32.059Z,1578682052.059 [Micromodem](INFO): Nmea in: $CADRQ,184732,1,12,0,64,2*7C 2020-01-10T18:47:32.059Z,1578682052.059 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,DEE7DE88DE21C57ECB16047A05CB4DEC750D7E1827D955ADD8BEBC7E9CD59055B48A8F61691C351C17E66CA151234BE2AD587A82E3BD9F77FEBFB98898985D41*3B 2020-01-10T18:47:32.463Z,1578682052.463 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B 2020-01-10T18:47:32.505Z,1578682052.505 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2020-01-10T18:47:32.915Z,1578682052.915 [Micromodem](INFO): Nmea in: $CADRQ,184733,1,12,0,64,3*7C 2020-01-10T18:47:32.915Z,1578682052.915 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,F0A722DB0DB2A914AB66E1E4FD3B0000*41 2020-01-10T18:47:33.271Z,1578682053.271 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,16*4E 2020-01-10T18:47:33.675Z,1578682053.675 [Micromodem](INFO): Nmea in: $CATXP,144*43 2020-01-10T18:47:37.315Z,1578682057.315 [Micromodem](INFO): Nmea in: $CATXF,144*55 2020-01-10T18:47:37.720Z,1578682057.720 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,184733.954159,3,0,200,4000,10000,1,1,12,1,3,3,3,144*7E 2020-01-10T18:47:38.060Z,1578682058.060 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20200110T182930/Courier0000.lzma 2020-01-10T18:47:38.537Z,1578682058.537 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79 2020-01-10T18:47:38.927Z,1578682058.927 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A 2020-01-10T18:47:39.331Z,1578682059.331 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B 2020-01-10T18:47:39.332Z,1578682059.332 [Micromodem](INFO): Sent 136 bytes from file Logs/20200110T182930/Courier0000.lzma.parts 2020-01-10T18:47:39.332Z,1578682059.332 [Micromodem](INFO): Packets left to send: 0 2020-01-10T18:47:39.744Z,1578682059.744 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110184738.456313,06,548,27,0093,0150,89,02,02,00,03,1,012,001,0,4,1,0,150,24.7,6.00,13,-3.00,-01,-0.04,28,10000,4000*4C 2020-01-10T18:47:40.563Z,1578682060.563 [Micromodem](INFO): Outgoing frame #1, 64 bytes: E8C2185E000001005D00000400000100000000000000151D80030C5669BF31555EAE1409A708CE2489EC102F971AAFC35C3D87A14EFD88D7E667BF45C1E3A334 2020-01-10T18:47:40.564Z,1578682060.564 [Micromodem](INFO): Outgoing frame #2, 64 bytes: DDB172E7C23FF3C388E048FBCF78BD4A167FF5F691B6AC442AAF0122057393C1A9D315A5EC8E30F3F35E5945AE1EEB1C30CD0C97D6CF1E227EBDE91CDA7A8D96 2020-01-10T18:47:40.564Z,1578682060.564 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 5F8D0FEBFECE70F057C5E2B035329EA93BC1C02D6254E81B49244EDD0B80698394863C0FCAB2C33EAE494FE5CDA1DBC95979AFFE2DE860C1B107C9AD51E24125 2020-01-10T18:47:40.565Z,1578682060.565 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69 2020-01-10T18:47:41.287Z,1578682061.287 [DataOverHttps](FAULT): Server acknowledged file Logs/20200110T182930/Courier0000.lzma, notLogs/20200110T183104/Courier0000.lzma 2020-01-10T18:47:41.287Z,1578682061.287 [DataOverHttps](INFO): SBD MOMSN=12190397 2020-01-10T18:47:41.815Z,1578682061.815 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B 2020-01-10T18:47:42.218Z,1578682062.218 [Micromodem](INFO): Nmea in: $CADRQ,184741,1,12,0,64,1*7B 2020-01-10T18:47:42.219Z,1578682062.219 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,E8C2185E000001005D00000400000100000000000000151D80030C5669BF31555EAE1409A708CE2489EC102F971AAFC35C3D87A14EFD88D7E667BF45C1E3A334*4D 2020-01-10T18:47:42.625Z,1578682062.625 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B 2020-01-10T18:47:43.027Z,1578682063.027 [Micromodem](INFO): Nmea in: $CADRQ,184743,1,12,0,64,2*7A 2020-01-10T18:47:43.027Z,1578682063.027 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,DDB172E7C23FF3C388E048FBCF78BD4A167FF5F691B6AC442AAF0122057393C1A9D315A5EC8E30F3F35E5945AE1EEB1C30CD0C97D6CF1E227EBDE91CDA7A8D96*32 2020-01-10T18:47:43.435Z,1578682063.435 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B 2020-01-10T18:47:43.831Z,1578682063.831 [Micromodem](INFO): Nmea in: $CADRQ,184744,1,12,0,64,3*7C 2020-01-10T18:47:43.831Z,1578682063.831 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,5F8D0FEBFECE70F057C5E2B035329EA93BC1C02D6254E81B49244EDD0B80698394863C0FCAB2C33EAE494FE5CDA1DBC95979AFFE2DE860C1B107C9AD51E24125*48 2020-01-10T18:47:44.239Z,1578682064.239 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B 2020-01-10T18:47:44.655Z,1578682064.655 [Micromodem](INFO): Nmea in: $CATXP,192*48 2020-01-10T18:47:48.275Z,1578682068.275 [Micromodem](INFO): Nmea in: $CATXF,192*5E 2020-01-10T18:47:48.680Z,1578682068.680 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,184744.922042,3,0,200,4000,10000,1,1,12,1,3,3,3,192*7F 2020-01-10T18:47:49.907Z,1578682069.907 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79 2020-01-10T18:47:50.295Z,1578682070.295 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A 2020-01-10T18:47:50.699Z,1578682070.699 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B 2020-01-10T18:47:50.700Z,1578682070.700 [Micromodem](INFO): Sent 184 bytes from file Logs/20200110T183104/Courier0000.lzma.parts 2020-01-10T18:47:50.700Z,1578682070.700 [Micromodem](INFO): Packets left to send: 1 2020-01-10T18:47:51.109Z,1578682071.109 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110184749.474443,06,208,23,0109,0150,89,02,02,00,04,1,012,001,0,4,1,0,150,22.8,6.00,14,-3.00,-01,-0.04,28,10000,4000*44 2020-01-10T18:47:52.243Z,1578682072.243 [Micromodem](INFO): Outgoing frame #1, 34 bytes: E8C2185E0000000055AA281B1AA194113E3F256AD53E42EC88C5B5BB9130111683DA 2020-01-10T18:47:52.243Z,1578682072.243 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,1*6B 2020-01-10T18:47:53.182Z,1578682073.182 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,1*69 2020-01-10T18:47:53.579Z,1578682073.579 [Micromodem](INFO): Nmea in: $CADRQ,184753,1,12,0,64,1*78 2020-01-10T18:47:53.580Z,1578682073.580 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,E8C2185E0000000055AA281B1AA194113E3F256AD53E42EC88C5B5BB9130111683DA*3D 2020-01-10T18:47:53.754Z,1578682073.754 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2020-01-10T18:47:53.754Z,1578682073.754 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-01-10T18:47:53.764Z,1578682073.764 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-01-10T18:47:53.983Z,1578682073.983 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,34*4E 2020-01-10T18:47:54.125Z,1578682074.125 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-01-10T18:47:54.126Z,1578682074.126 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2020-01-10T18:47:54.387Z,1578682074.387 [Micromodem](INFO): Nmea in: $CATXP,34*75 2020-01-10T18:47:55.603Z,1578682075.603 [Micromodem](INFO): Nmea in: $CATXF,34*63 2020-01-10T18:47:56.004Z,1578682076.004 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,184754.611990,3,0,200,4000,10000,1,1,12,1,1,1,3,34*4A 2020-01-10T18:47:56.811Z,1578682076.811 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79 2020-01-10T18:47:56.812Z,1578682076.812 [Micromodem](INFO): Sent 26 bytes from file Logs/20200110T183104/Courier0000.lzma.parts 2020-01-10T18:47:56.812Z,1578682076.812 [Micromodem](INFO): Packets left to send: 0 2020-01-10T18:47:57.218Z,1578682077.218 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110184756.600695,06,206,23,0108,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,25.0,6.00,16,-3.00,-01,-0.03,23,10000,4000*46 2020-01-10T18:47:58.256Z,1578682078.256 [Micromodem](INFO): Outgoing frame #1, 64 bytes: E9C3185E000001005D00000400210200000000000000151D80030C5669BF31555EAE1409A708CE2489EC102F971AAFC35C3D87A14EFD88D7E667BF45C1E3A334 2020-01-10T18:47:58.256Z,1578682078.256 [Micromodem](INFO): Outgoing frame #2, 64 bytes: DD742CB783CE70CE85A212BC685C3E90D7241D746CF300AACA56D8981229C4CB25ED85B9C3799F0DF1699F0796FEF96E9622D6DCF0EC568933A84F628D8BAE8D 2020-01-10T18:47:58.257Z,1578682078.257 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 9B80E9B9C02E4BE6D915CE866219D71854ACA908EFD57529FA1EB337970DE1A6B21686E2108F0FEF35BEDC5603F302F807C3787895966A90ACCDD983A5C8EADE 2020-01-10T18:47:58.257Z,1578682078.257 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69 2020-01-10T18:47:59.211Z,1578682079.211 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B 2020-01-10T18:47:59.607Z,1578682079.607 [Micromodem](INFO): Nmea in: $CADRQ,184759,1,12,0,64,1*72 2020-01-10T18:47:59.607Z,1578682079.607 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,E9C3185E000001005D00000400210200000000000000151D80030C5669BF31555EAE1409A708CE2489EC102F971AAFC35C3D87A14EFD88D7E667BF45C1E3A334*4D 2020-01-10T18:48:00.027Z,1578682080.027 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B 2020-01-10T18:48:00.415Z,1578682080.415 [Micromodem](INFO): Nmea in: $CADRQ,184800,1,12,0,64,2*72 2020-01-10T18:48:00.415Z,1578682080.415 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,DD742CB783CE70CE85A212BC685C3E90D7241D746CF300AACA56D8981229C4CB25ED85B9C3799F0DF1699F0796FEF96E9622D6DCF0EC568933A84F628D8BAE8D*46 2020-01-10T18:48:00.819Z,1578682080.819 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B 2020-01-10T18:48:01.235Z,1578682081.235 [Micromodem](INFO): Nmea in: $CADRQ,184801,1,12,0,64,3*72 2020-01-10T18:48:01.235Z,1578682081.235 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,9B80E9B9C02E4BE6D915CE866219D71854ACA908EFD57529FA1EB337970DE1A6B21686E2108F0FEF35BEDC5603F302F807C3787895966A90ACCDD983A5C8EADE*36 2020-01-10T18:48:01.627Z,1578682081.627 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B 2020-01-10T18:48:02.030Z,1578682082.030 [Micromodem](INFO): Nmea in: $CATXP,192*48 2020-01-10T18:48:03.377Z,1578682083.377 [DataOverHttps](INFO): Sending 265 bytes from file Logs/20200110T183521/Courier0000.lzma 2020-01-10T18:48:05.382Z,1578682085.382 [DataOverHttps](INFO): Moved sent file to Logs/20200110T183521/Courier0000.lzma.bak 2020-01-10T18:48:05.382Z,1578682085.382 [DataOverHttps](INFO): SBD MOMSN=12190418 2020-01-10T18:48:05.673Z,1578682085.673 [Micromodem](INFO): Nmea in: $CATXF,192*5E 2020-01-10T18:48:06.072Z,1578682086.072 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,184802.322066,3,0,200,4000,10000,1,1,12,1,3,3,3,192*7E 2020-01-10T18:48:06.884Z,1578682086.884 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79 2020-01-10T18:48:07.283Z,1578682087.283 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A 2020-01-10T18:48:07.687Z,1578682087.687 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B 2020-01-10T18:48:07.688Z,1578682087.688 [Micromodem](INFO): Sent 184 bytes from file Logs/20200110T183521/Courier0000.lzma.parts 2020-01-10T18:48:07.688Z,1578682087.688 [Micromodem](INFO): Packets left to send: 1 2020-01-10T18:48:08.094Z,1578682088.094 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110184806.874381,06,242,23,0117,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,25.7,6.00,14,-3.00,-01,-0.02,22,10000,4000*4A 2020-01-10T18:48:08.937Z,1578682088.937 [Micromodem](INFO): Outgoing frame #1, 64 bytes: E9C3185E040001005D00000400DB0000000000000000151D80030C5669BF7A7B1C3D1C6303E24BB3C67CE6242CD21D69D9408EF4CA72E6A538CA40DF5121E8FE 2020-01-10T18:48:08.938Z,1578682088.938 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 2B81811205C75B8E5E5CD4E068F4E8F38B27DA11A689D4929F041F50294C6B343D5F972B45699DE158EDAA6722F9FF3D42B0A17F0A5E442D9C4E43D1A396A0B1 2020-01-10T18:48:08.938Z,1578682088.938 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 8C8160623C254ED2153F04CA4231993E1EEDB903D433780F9ED895DE03B72CC34892D49BBD2EF9E285673BEC1022DCEF2BB352C35133A4BFC0C78EC0CC39D763 2020-01-10T18:48:08.938Z,1578682088.938 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69 2020-01-10T18:48:09.943Z,1578682089.943 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B 2020-01-10T18:48:10.347Z,1578682090.347 [Micromodem](INFO): Nmea in: $CADRQ,184809,1,12,0,64,1*78 2020-01-10T18:48:10.347Z,1578682090.347 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,E9C3185E040001005D00000400DB0000000000000000151D80030C5669BF7A7B1C3D1C6303E24BB3C67CE6242CD21D69D9408EF4CA72E6A538CA40DF5121E8FE*42 2020-01-10T18:48:10.757Z,1578682090.757 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B 2020-01-10T18:48:11.173Z,1578682091.173 [Micromodem](INFO): Nmea in: $CADRQ,184811,1,12,0,64,2*72 2020-01-10T18:48:11.173Z,1578682091.173 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,2B81811205C75B8E5E5CD4E068F4E8F38B27DA11A689D4929F041F50294C6B343D5F972B45699DE158EDAA6722F9FF3D42B0A17F0A5E442D9C4E43D1A396A0B1*39 2020-01-10T18:48:11.559Z,1578682091.559 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B 2020-01-10T18:48:11.967Z,1578682091.967 [Micromodem](INFO): Nmea in: $CADRQ,184812,1,12,0,64,3*70 2020-01-10T18:48:11.967Z,1578682091.967 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,8C8160623C254ED2153F04CA4231993E1EEDB903D433780F9ED895DE03B72CC34892D49BBD2EF9E285673BEC1022DCEF2BB352C35133A4BFC0C78EC0CC39D763*4D 2020-01-10T18:48:12.367Z,1578682092.367 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B 2020-01-10T18:48:12.770Z,1578682092.770 [Micromodem](INFO): Nmea in: $CATXP,192*48 2020-01-10T18:48:16.411Z,1578682096.411 [Micromodem](INFO): Nmea in: $CATXF,192*5E 2020-01-10T18:48:16.818Z,1578682096.818 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,184813.056116,3,0,200,4000,10000,1,1,12,1,3,3,3,192*78 2020-01-10T18:48:17.624Z,1578682097.624 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79 2020-01-10T18:48:17.629Z,1578682097.629 [NAL9602](INFO): SBD MO Status=2, MOMSN=10156, MT Status=2, MTMSN=0 2020-01-10T18:48:17.629Z,1578682097.629 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-01-10T18:48:18.029Z,1578682098.029 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A 2020-01-10T18:48:18.427Z,1578682098.427 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B 2020-01-10T18:48:18.428Z,1578682098.428 [Micromodem](INFO): Sent 184 bytes from file Logs/20200110T183521/Courier0004.lzma.parts 2020-01-10T18:48:18.428Z,1578682098.428 [Micromodem](INFO): Packets left to send: 1 2020-01-10T18:48:18.834Z,1578682098.834 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110184817.609322,06,612,27,0115,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,26.3,6.00,15,-3.00,-01,-0.04,23,10000,4000*41 2020-01-10T18:48:19.392Z,1578682099.392 [Micromodem](INFO): Using rate 1 for 32 bytes. 2020-01-10T18:48:19.392Z,1578682099.392 [Micromodem](INFO): Outgoing frame #1, 32 bytes: E9C3185E040000008A167B3A99A1215BCBA5AC313D60727DB0305C11BADB6312 2020-01-10T18:48:19.392Z,1578682099.392 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,1*6B 2020-01-10T18:48:20.311Z,1578682100.311 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,1*69 2020-01-10T18:48:20.719Z,1578682100.719 [Micromodem](INFO): Nmea in: $CADRQ,184820,1,12,0,64,1*73 2020-01-10T18:48:20.719Z,1578682100.719 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32 2020-01-10T18:48:21.812Z,1578682101.812 [Micromodem](INFO): Using rate 0 for 32 bytes. 2020-01-10T18:48:21.812Z,1578682101.812 [Micromodem](INFO): Outgoing frame #1, 32 bytes: E9C3185E040000008A167B3A99A1215BCBA5AC313D60727DB0305C11BADB6312 2020-01-10T18:48:21.813Z,1578682101.813 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,1*6B 2020-01-10T18:48:22.259Z,1578682102.259 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,1*69 2020-01-10T18:48:22.643Z,1578682102.643 [Micromodem](INFO): Nmea in: $CADRQ,184822,1,12,0,64,1*71 2020-01-10T18:48:22.643Z,1578682102.643 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32 2020-01-10T18:48:23.719Z,1578682103.719 [Micromodem](INFO): Using rate 0 for 32 bytes. 2020-01-10T18:48:23.720Z,1578682103.720 [Micromodem](INFO): Outgoing frame #1, 32 bytes: E9C3185E040000008A167B3A99A1215BCBA5AC313D60727DB0305C11BADB6312 2020-01-10T18:48:23.720Z,1578682103.720 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,1*6B 2020-01-10T18:48:23.911Z,1578682103.911 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,1*69 2020-01-10T18:48:24.295Z,1578682104.295 [Micromodem](INFO): Nmea in: $CADRQ,184824,1,12,0,64,1*77 2020-01-10T18:48:24.295Z,1578682104.295 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32 2020-01-10T18:48:25.351Z,1578682105.351 [Micromodem](INFO): Using rate 0 for 32 bytes. 2020-01-10T18:48:25.351Z,1578682105.351 [Micromodem](INFO): Outgoing frame #1, 32 bytes: E9C3185E040000008A167B3A99A1215BCBA5AC313D60727DB0305C11BADB6312 2020-01-10T18:48:25.352Z,1578682105.352 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,1*6B 2020-01-10T18:48:25.585Z,1578682105.585 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,1*69 2020-01-10T18:48:25.972Z,1578682105.972 [Micromodem](INFO): Nmea in: $CADRQ,184826,1,12,0,64,1*75 2020-01-10T18:48:25.972Z,1578682105.972 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32 2020-01-10T18:48:26.570Z,1578682106.570 [Micromodem](INFO): Using rate 0 for 32 bytes. 2020-01-10T18:48:26.570Z,1578682106.570 [Micromodem](INFO): Outgoing frame #1, 32 bytes: E9C3185E040000008A167B3A99A1215BCBA5AC313D60727DB0305C11BADB6312 2020-01-10T18:48:26.571Z,1578682106.571 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,1*6B 2020-01-10T18:48:26.595Z,1578682106.595 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20200110T183521/Courier0004.lzma 2020-01-10T18:48:26.735Z,1578682106.735 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,1*69 2020-01-10T18:48:27.097Z,1578682107.097 [Micromodem](INFO): Nmea in: $CADRQ,184827,1,12,0,64,1*74 2020-01-10T18:48:27.098Z,1578682107.098 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32 2020-01-10T18:48:27.660Z,1578682107.660 [Micromodem](INFO): Using rate 0 for 32 bytes. 2020-01-10T18:48:27.661Z,1578682107.661 [Micromodem](INFO): Outgoing frame #1, 32 bytes: E9C3185E040000008A167B3A99A1215BCBA5AC313D60727DB0305C11BADB6312 2020-01-10T18:48:27.662Z,1578682107.662 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,1*6B 2020-01-10T18:48:27.791Z,1578682107.791 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,1*69 2020-01-10T18:48:27.833Z,1578682107.833 [Startup:StartupSatComms:B](INFO): Timed out from 2020-01-10T18:47:27.8Z 2020-01-10T18:48:27.833Z,1578682107.833 [Startup:StartupSatComms:B] Stopped 2020-01-10T18:48:27.833Z,1578682107.833 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2020-01-10T18:48:27.833Z,1578682107.833 [Startup:StartupSatComms] Stopped 2020-01-10T18:48:27.833Z,1578682107.833 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2020-01-10T18:48:27.834Z,1578682107.834 [Startup](INFO): Completed Startup 2020-01-10T18:48:27.834Z,1578682107.834 [MissionManager](INFO): Startup is completed. 2020-01-10T18:48:27.834Z,1578682107.834 [MissionManager](INFO): Uninitializing Mission Startup 2020-01-10T18:48:27.835Z,1578682107.835 [Startup] Stopped 2020-01-10T18:48:27.835Z,1578682107.835 [Startup](DEBUG): Aggregate::uninitialize Startup 2020-01-10T18:48:27.835Z,1578682107.835 [Startup:A.GoToSurface] Stopped 2020-01-10T18:48:27.835Z,1578682107.835 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-01-10T18:48:28.172Z,1578682108.172 [Micromodem](INFO): Nmea in: $CADRQ,184828,1,12,0,64,1*7B 2020-01-10T18:48:28.173Z,1578682108.173 [Micromodem](ERROR): Received CADRQ with bad size: 64 expected <= 32 2020-01-10T18:48:28.225Z,1578682108.225 [MissionManager](IMPORTANT): Started mission Default 2020-01-10T18:48:28.225Z,1578682108.225 [Default] Running Loop=1 2020-01-10T18:48:28.225Z,1578682108.225 [Default](DEBUG): Aggregate::initialize Default 2020-01-10T18:48:28.225Z,1578682108.225 [Default:B.GoToSurface] Running Loop=1 2020-01-10T18:48:28.225Z,1578682108.225 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-01-10T18:48:28.226Z,1578682108.226 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-01-10T18:48:28.226Z,1578682108.226 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-01-10T18:48:28.226Z,1578682108.226 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-01-10T18:48:28.228Z,1578682108.228 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-01-10T18:48:28.237Z,1578682108.237 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-01-10T18:48:28.237Z,1578682108.237 [Default:A.Wait] Running Loop=1 2020-01-10T18:48:28.237Z,1578682108.237 [Default:A.Wait](DEBUG): Initialize Wait Component. 2020-01-10T18:48:28.598Z,1578682108.598 [DataOverHttps](INFO): Moved sent file to Logs/20200110T183521/Courier0004.lzma.bak 2020-01-10T18:48:28.598Z,1578682108.598 [DataOverHttps](INFO): SBD MOMSN=12190436 2020-01-10T18:48:39.483Z,1578682119.483 [NAL9602](INFO): SBD MO Status=2, MOMSN=10156, MT Status=2, MTMSN=0 2020-01-10T18:48:39.483Z,1578682119.483 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-01-10T18:48:41.540Z,1578682121.540 [Default:A.Wait](INFO): Done Waiting. 2020-01-10T18:48:41.541Z,1578682121.541 [Default:A.Wait] Stopped 2020-01-10T18:48:41.541Z,1578682121.541 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2020-01-10T18:48:41.937Z,1578682121.937 [Default:CheckIn] Running Loop=1 2020-01-10T18:48:41.937Z,1578682121.937 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-01-10T18:48:41.937Z,1578682121.937 [Default:CheckIn:Read_GPS] Running Loop=1 2020-01-10T18:48:42.353Z,1578682122.353 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2020-01-10T18:48:58.067Z,1578682138.067 [Micromodem](INFO): Nmea in: $CAERR,184858,DATA_TIMEOUT,1*46 2020-01-10T18:49:19.072Z,1578682159.072 [NAL9602](INFO): SBD MO Status=2, MOMSN=10156, MT Status=2, MTMSN=0 2020-01-10T18:49:19.077Z,1578682159.077 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-01-10T18:49:43.337Z,1578682183.337 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00, 0.00 2020-01-10T18:50:09.571Z,1578682209.571 [CommandLine](IMPORTANT): got command restart application 2020-01-10T18:50:10.577Z,1578682210.577 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2020-01-10T18:50:10.577Z,1578682210.577 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-01-10T18:50:10.745Z,1578682210.745 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2020-01-10T18:50:10.745Z,1578682210.745 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-01-10T18:50:10.746Z,1578682210.746 [CommandLine](INFO): Join timeout helper Thread ID is 9585 2020-01-10T18:50:10.746Z,1578682210.746 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2020-01-10T18:50:10.746Z,1578682210.746 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-01-10T18:50:10.746Z,1578682210.746 [NavChartDb](INFO): Join timeout helper Thread ID is 9586 2020-01-10T18:50:11.085Z,1578682211.085 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2020-01-10T18:50:11.085Z,1578682211.085 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-01-10T18:50:11.101Z,1578682211.101 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler 2020-01-10T18:50:11.102Z,1578682211.102 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2020-01-10T18:50:11.103Z,1578682211.103 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 9587 2020-01-10T18:50:11.389Z,1578682211.389 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread. 2020-01-10T18:50:11.389Z,1578682211.389 [WetLabsSeaOWL_UV_A](INFO): Powering down 2020-01-10T18:50:11.390Z,1578682211.390 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2020-01-10T18:50:11.405Z,1578682211.405 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2020-01-10T18:50:11.405Z,1578682211.405 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2020-01-10T18:50:11.405Z,1578682211.405 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 9588 2020-01-10T18:50:11.409Z,1578682211.409 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2020-01-10T18:50:11.409Z,1578682211.409 [CTD_NeilBrown](INFO): Powering down 2020-01-10T18:50:11.421Z,1578682211.421 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2020-01-10T18:50:11.425Z,1578682211.425 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2020-01-10T18:50:11.425Z,1578682211.425 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-01-10T18:50:11.425Z,1578682211.425 [Radio_Surface](INFO): Join timeout helper Thread ID is 9589 2020-01-10T18:50:11.681Z,1578682211.681 [Radio_Surface](INFO): Powering down 2020-01-10T18:50:11.682Z,1578682211.682 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2020-01-10T18:50:11.682Z,1578682211.682 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-01-10T18:50:11.694Z,1578682211.694 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2020-01-10T18:50:11.694Z,1578682211.694 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-01-10T18:50:11.694Z,1578682211.694 [DataOverHttps](INFO): Join timeout helper Thread ID is 9590 2020-01-10T18:50:11.713Z,1578682211.713 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2020-01-10T18:50:11.713Z,1578682211.713 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-01-10T18:50:11.715Z,1578682211.715 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2020-01-10T18:50:11.715Z,1578682211.715 [logger ThreadHandler](INFO): Thread cancelled. 2020-01-10T18:50:11.715Z,1578682211.715 [logger](INFO): Join timeout helper Thread ID is 9591 2020-01-10T18:50:11.726Z,1578682211.726 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2020-01-10T18:50:11.726Z,1578682211.726 [logger ThreadHandler](INFO): Thread cancelled. 2020-01-10T18:50:11.742Z,1578682211.742 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2020-01-10T18:50:11.742Z,1578682211.742 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-01-10T18:50:11.742Z,1578682211.742 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2020-01-10T18:50:11.742Z,1578682211.742 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-01-10T18:50:11.742Z,1578682211.742 [controlThread](INFO): Join timeout helper Thread ID is 9592 2020-01-10T18:50:11.977Z,1578682211.977 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2020-01-10T18:50:11.977Z,1578682211.977 [controlThread](DEBUG): Uninitializing ControlThread 2020-01-10T18:50:11.978Z,1578682211.978 [AHRS_M2](INFO): Powering down 2020-01-10T18:50:12.049Z,1578682212.049 [DDM](INFO): Powering down 2020-01-10T18:50:12.143Z,1578682212.143 [DUSBL_Hydroid](INFO): Powering down 2020-01-10T18:50:12.261Z,1578682212.261 [Micromodem](INFO): Powering down 2020-01-10T18:50:12.357Z,1578682212.357 [NAL9602](INFO): Powering down 2020-01-10T18:50:12.429Z,1578682212.429 [RDI_Pathfinder](INFO): Powering down 2020-01-10T18:50:12.431Z,1578682212.431 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2020-01-10T18:50:12.432Z,1578682212.432 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2020-01-10T18:50:12.432Z,1578682212.432 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2020-01-10T18:50:12.433Z,1578682212.433 [MissionManager](INFO): Uninitializing Mission Default 2020-01-10T18:50:12.433Z,1578682212.433 [Default] Stopped 2020-01-10T18:50:12.433Z,1578682212.433 [Default](DEBUG): Aggregate::uninitialize Default 2020-01-10T18:50:12.433Z,1578682212.433 [Default:B.GoToSurface] Stopped 2020-01-10T18:50:12.433Z,1578682212.433 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-01-10T18:50:12.433Z,1578682212.433 [Default:CheckIn] Stopped 2020-01-10T18:50:12.433Z,1578682212.433 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-01-10T18:50:12.434Z,1578682212.434 [Default:CheckIn:Read_GPS] Stopped 2020-01-10T18:50:12.436Z,1578682212.436 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2020-01-10T18:50:12.436Z,1578682212.436 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2020-01-10T18:50:12.436Z,1578682212.436 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2020-01-10T18:50:12.437Z,1578682212.437 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2020-01-10T18:50:12.437Z,1578682212.437 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-01-10T18:50:12.437Z,1578682212.437 [BuoyancyServo](INFO): Powering down 2020-01-10T18:50:12.449Z,1578682212.449 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2020-01-10T18:50:12.449Z,1578682212.449 [ElevatorServo](INFO): Powering down 2020-01-10T18:50:12.450Z,1578682212.450 [MassServo](DEBUG): Uninitialize Mass Servo. 2020-01-10T18:50:12.450Z,1578682212.450 [MassServo](INFO): Powering down 2020-01-10T18:50:12.451Z,1578682212.451 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-01-10T18:50:12.451Z,1578682212.451 [RudderServo](INFO): Powering down 2020-01-10T18:50:12.451Z,1578682212.451 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2020-01-10T18:50:12.451Z,1578682212.451 [ThrusterServo](INFO): Powering down 2020-01-10T18:50:12.452Z,1578682212.452 [SBIT](DEBUG): Uninitialize SBIT Component. 2020-01-10T18:50:12.453Z,1578682212.453 [IBIT](DEBUG): Uninitialize IBIT Component. 2020-01-10T18:50:12.453Z,1578682212.453 [CBIT](DEBUG): Uninitialize CBIT Component. 2020-01-10T18:50:12.453Z,1578682212.453 [CBIT](DEBUG): Powering off loads. 2020-01-10T18:50:12.465Z,1578682212.465 [CBIT](DEBUG): Disabling WDT. 2020-01-10T18:50:12.477Z,1578682212.477 [CBIT](DEBUG): Opening all GF detection circuits. 2020-01-10T18:50:12.478Z,1578682212.478 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-01-10T18:50:12.521Z,1578682212.521 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-01-10T18:50:12.532Z,1578682212.532 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-01-10T18:50:12.584Z,1578682212.584 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2020-01-10T18:50:12.587Z,1578682212.587 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2020-01-10T18:50:12.652Z,1578682212.652 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-01-10T18:50:12.715Z,1578682212.715 [logger ThreadHandler](INFO): Thread cancelled.