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.