2020-01-10T03:09:55.553Z,1578625795.553 [Supervisor](DEBUG): Initializing supervisor. 2020-01-10T03:09:55.556Z,1578625795.556 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-01-10T03:09:55.557Z,1578625795.557 [SyncHandler](INFO): Protected caller Thread ID is 3490 2020-01-10T03:09:55.558Z,1578625795.558 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-01-10T03:09:55.559Z,1578625795.559 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-01-10T03:09:55.559Z,1578625795.559 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3491 2020-01-10T03:09:55.562Z,1578625795.562 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-01-10T03:09:55.574Z,1578625795.574 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-01-10T03:09:55.575Z,1578625795.575 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-01-10T03:09:55.576Z,1578625795.576 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3492 2020-01-10T03:09:55.576Z,1578625795.576 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-01-10T03:09:55.577Z,1578625795.577 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-01-10T03:09:55.578Z,1578625795.578 [logger ThreadHandler](INFO): Protected caller Thread ID is 3493 2020-01-10T03:09:55.580Z,1578625795.580 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-01-10T03:09:55.580Z,1578625795.580 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-01-10T03:09:55.582Z,1578625795.582 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-01-10T03:09:56.005Z,1578625796.005 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-01-10T03:09:56.006Z,1578625796.006 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-01-10T03:09:56.100Z,1578625796.100 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-01-10T03:09:56.101Z,1578625796.101 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-01-10T03:09:56.414Z,1578625796.414 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-01-10T03:09:56.415Z,1578625796.415 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-01-10T03:09:56.549Z,1578625796.549 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-01-10T03:09:56.550Z,1578625796.550 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-01-10T03:09:56.733Z,1578625796.733 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-01-10T03:09:56.734Z,1578625796.734 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-01-10T03:09:57.175Z,1578625797.175 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-01-10T03:09:57.176Z,1578625797.176 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-01-10T03:09:57.377Z,1578625797.377 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-01-10T03:09:57.378Z,1578625797.378 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-01-10T03:09:57.518Z,1578625797.518 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-01-10T03:09:57.519Z,1578625797.519 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-01-10T03:09:57.699Z,1578625797.699 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-01-10T03:09:57.700Z,1578625797.700 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-01-10T03:09:57.793Z,1578625797.793 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-01-10T03:09:57.794Z,1578625797.794 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-01-10T03:09:58.337Z,1578625798.337 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-01-10T03:09:58.337Z,1578625798.337 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-01-10T03:09:58.423Z,1578625798.423 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-01-10T03:09:58.577Z,1578625798.577 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-01-10T03:09:58.577Z,1578625798.577 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-01-10T03:09:59.340Z,1578625799.340 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-01-10T03:09:59.341Z,1578625799.341 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-01-10T03:09:59.746Z,1578625799.746 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-01-10T03:09:59.748Z,1578625799.748 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/ 2020-01-10T03:09:59.748Z,1578625799.748 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg 2020-01-10T03:09:59.947Z,1578625799.947 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg 2020-01-10T03:10:00.043Z,1578625800.043 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg 2020-01-10T03:10:00.138Z,1578625800.138 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg 2020-01-10T03:10:00.366Z,1578625800.366 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-01-10T03:10:00.366Z,1578625800.366 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg 2020-01-10T03:10:00.449Z,1578625800.449 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg 2020-01-10T03:10:00.539Z,1578625800.539 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg 2020-01-10T03:10:00.633Z,1578625800.633 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg 2020-01-10T03:10:00.714Z,1578625800.714 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg 2020-01-10T03:10:00.818Z,1578625800.818 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg 2020-01-10T03:10:00.992Z,1578625800.992 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg 2020-01-10T03:10:01.119Z,1578625801.119 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/ 2020-01-10T03:10:01.119Z,1578625801.119 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2020-01-10T03:10:01.143Z,1578625801.143 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-01-10T03:10:01.458Z,1578625801.458 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2020-01-10T03:10:01.464Z,1578625801.464 [AHRS_M2](INFO): created writer for : platform_orientation 2020-01-10T03:10:01.466Z,1578625801.466 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2020-01-10T03:10:01.471Z,1578625801.471 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2020-01-10T03:10:01.472Z,1578625801.472 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2020-01-10T03:10:01.478Z,1578625801.478 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2020-01-10T03:10:01.478Z,1578625801.478 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2020-01-10T03:10:01.484Z,1578625801.484 [AHRS_M2](INFO): created writer for : platform_roll_angle 2020-01-10T03:10:01.559Z,1578625801.559 [AHRS_M2] Loaded 2020-01-10T03:10:01.559Z,1578625801.559 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2020-01-10T03:10:01.639Z,1578625801.639 [DataOverHttps] Loaded 2020-01-10T03:10:01.640Z,1578625801.640 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-01-10T03:10:01.641Z,1578625801.641 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407684E0 2020-01-10T03:10:01.641Z,1578625801.641 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3572 2020-01-10T03:10:01.667Z,1578625801.667 [DDM] Loaded 2020-01-10T03:10:01.667Z,1578625801.667 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread. 2020-01-10T03:10:01.681Z,1578625801.681 [Depth_Keller] Loaded 2020-01-10T03:10:01.681Z,1578625801.681 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-01-10T03:10:01.686Z,1578625801.686 [DropWeight] Loaded 2020-01-10T03:10:01.686Z,1578625801.686 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2020-01-10T03:10:01.751Z,1578625801.751 [DUSBL_Hydroid] Loaded 2020-01-10T03:10:01.751Z,1578625801.751 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread. 2020-01-10T03:10:01.800Z,1578625801.800 [Micromodem] Loaded 2020-01-10T03:10:01.801Z,1578625801.801 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread. 2020-01-10T03:10:01.905Z,1578625801.905 [NAL9602] Loaded 2020-01-10T03:10:01.905Z,1578625801.905 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-01-10T03:10:01.944Z,1578625801.944 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now 2020-01-10T03:10:01.945Z,1578625801.945 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now 2020-01-10T03:10:01.946Z,1578625801.946 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now 2020-01-10T03:10:01.946Z,1578625801.946 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full 2020-01-10T03:10:01.947Z,1578625801.947 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now 2020-01-10T03:10:01.947Z,1578625801.947 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now 2020-01-10T03:10:01.948Z,1578625801.948 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now 2020-01-10T03:10:01.948Z,1578625801.948 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full 2020-01-10T03:10:01.948Z,1578625801.948 [Onboard] Loaded 2020-01-10T03:10:01.949Z,1578625801.949 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2020-01-10T03:10:01.955Z,1578625801.955 [PowerOnly] Loaded 2020-01-10T03:10:01.955Z,1578625801.955 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2020-01-10T03:10:01.962Z,1578625801.962 [Radio_Surface] Loaded 2020-01-10T03:10:01.962Z,1578625801.962 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-01-10T03:10:01.963Z,1578625801.963 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407984E0 2020-01-10T03:10:01.963Z,1578625801.963 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3573 2020-01-10T03:10:02.010Z,1578625802.010 [RDI_Pathfinder] Loaded 2020-01-10T03:10:02.010Z,1578625802.010 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2020-01-10T03:10:03.596Z,1578625803.596 [BPC1] Loaded 2020-01-10T03:10:03.596Z,1578625803.596 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-01-10T03:10:03.596Z,1578625803.596 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-01-10T03:10:03.597Z,1578625803.597 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-01-10T03:10:03.632Z,1578625803.632 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-01-10T03:10:03.633Z,1578625803.633 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-01-10T03:10:04.060Z,1578625804.060 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-01-10T03:10:04.060Z,1578625804.060 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-01-10T03:10:04.104Z,1578625804.104 [NavChart] Loaded 2020-01-10T03:10:04.104Z,1578625804.104 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-01-10T03:10:04.108Z,1578625804.108 [UniversalFixResidualReporter] Loaded 2020-01-10T03:10:04.108Z,1578625804.108 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-01-10T03:10:04.109Z,1578625804.109 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-01-10T03:10:04.109Z,1578625804.109 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-01-10T03:10:04.321Z,1578625804.321 [BuoyancyServo] Loaded 2020-01-10T03:10:04.321Z,1578625804.321 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-01-10T03:10:04.337Z,1578625804.337 [ElevatorServo] Loaded 2020-01-10T03:10:04.337Z,1578625804.337 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-01-10T03:10:04.352Z,1578625804.352 [MassServo] Loaded 2020-01-10T03:10:04.352Z,1578625804.352 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-01-10T03:10:04.368Z,1578625804.368 [RudderServo] Loaded 2020-01-10T03:10:04.368Z,1578625804.368 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-01-10T03:10:04.383Z,1578625804.383 [ThrusterServo] Loaded 2020-01-10T03:10:04.383Z,1578625804.383 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-01-10T03:10:04.384Z,1578625804.384 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-01-10T03:10:04.384Z,1578625804.384 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-01-10T03:10:04.652Z,1578625804.652 [CTD_NeilBrown] Loaded 2020-01-10T03:10:04.653Z,1578625804.653 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2020-01-10T03:10:04.654Z,1578625804.654 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408EC4E0 2020-01-10T03:10:04.654Z,1578625804.654 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 3574 2020-01-10T03:10:04.669Z,1578625804.669 [PAR_Licor] Loaded 2020-01-10T03:10:04.669Z,1578625804.669 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2020-01-10T03:10:04.715Z,1578625804.715 [WetLabsSeaOWL_UV_A] Loaded 2020-01-10T03:10:04.716Z,1578625804.716 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2020-01-10T03:10:04.717Z,1578625804.717 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 4091C4E0 2020-01-10T03:10:04.717Z,1578625804.717 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 3575 2020-01-10T03:10:04.718Z,1578625804.718 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-01-10T03:10:04.718Z,1578625804.718 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-01-10T03:10:05.042Z,1578625805.042 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-01-10T03:10:05.043Z,1578625805.043 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-01-10T03:10:05.084Z,1578625805.084 [DepthRateCalculator] Loaded 2020-01-10T03:10:05.085Z,1578625805.085 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-01-10T03:10:05.091Z,1578625805.091 [PitchRateCalculator] Loaded 2020-01-10T03:10:05.091Z,1578625805.091 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-01-10T03:10:05.104Z,1578625805.104 [SpeedCalculator] Loaded 2020-01-10T03:10:05.104Z,1578625805.104 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-01-10T03:10:05.126Z,1578625805.126 [TempGradientCalculator] Loaded 2020-01-10T03:10:05.126Z,1578625805.126 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-01-10T03:10:05.132Z,1578625805.132 [YawRateCalculator] Loaded 2020-01-10T03:10:05.132Z,1578625805.132 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-01-10T03:10:05.174Z,1578625805.174 [ElevatorOffsetCalculator] Loaded 2020-01-10T03:10:05.175Z,1578625805.175 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-01-10T03:10:05.175Z,1578625805.175 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-01-10T03:10:05.176Z,1578625805.176 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-01-10T03:10:05.319Z,1578625805.319 [SBIT](DEBUG): Construct Startup Built In Test. 2020-01-10T03:10:05.342Z,1578625805.342 [SBIT] Loaded 2020-01-10T03:10:05.342Z,1578625805.342 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-01-10T03:10:05.343Z,1578625805.343 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-01-10T03:10:05.355Z,1578625805.355 [IBIT] Loaded 2020-01-10T03:10:05.356Z,1578625805.356 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-01-10T03:10:05.359Z,1578625805.359 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-01-10T03:10:05.504Z,1578625805.504 [CBIT] Loaded 2020-01-10T03:10:05.504Z,1578625805.504 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-01-10T03:10:05.505Z,1578625805.505 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-01-10T03:10:05.505Z,1578625805.505 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-01-10T03:10:05.590Z,1578625805.590 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-01-10T03:10:05.591Z,1578625805.591 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-01-10T03:10:05.693Z,1578625805.693 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-01-10T03:10:05.694Z,1578625805.694 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-01-10T03:10:05.744Z,1578625805.744 [VerticalControl](DEBUG): Construct VerticalControl. 2020-01-10T03:10:05.830Z,1578625805.830 [VerticalControl] Loaded 2020-01-10T03:10:05.830Z,1578625805.830 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-01-10T03:10:05.831Z,1578625805.831 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-01-10T03:10:05.905Z,1578625805.905 [HorizontalControl] Loaded 2020-01-10T03:10:05.905Z,1578625805.905 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-01-10T03:10:05.906Z,1578625805.906 [SpeedControl](DEBUG): Construct SpeedControl. 2020-01-10T03:10:05.908Z,1578625805.908 [SpeedControl] Loaded 2020-01-10T03:10:05.908Z,1578625805.908 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-01-10T03:10:05.909Z,1578625805.909 [LoopControl](DEBUG): Construct LoopControl. 2020-01-10T03:10:05.909Z,1578625805.909 [LoopControl] Loaded 2020-01-10T03:10:05.909Z,1578625805.909 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-01-10T03:10:05.910Z,1578625805.910 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-01-10T03:10:05.910Z,1578625805.910 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-01-10T03:10:05.944Z,1578625805.944 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-01-10T03:10:05.948Z,1578625805.948 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-01-10T03:10:05.949Z,1578625805.949 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-01-10T03:10:05.955Z,1578625805.955 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-01-10T03:10:05.956Z,1578625805.956 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AFB4E0 2020-01-10T03:10:05.957Z,1578625805.957 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3576 2020-01-10T03:10:05.961Z,1578625805.961 [Supervisor](INFO): Main Thread ID is 3489 2020-01-10T03:10:05.961Z,1578625805.961 [Supervisor](DEBUG): Running supervisor. 2020-01-10T03:10:05.962Z,1578625805.962 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3577 2020-01-10T03:10:05.964Z,1578625805.964 [controlThread ThreadHandler](INFO): Handler Thread ID is 3578 2020-01-10T03:10:05.965Z,1578625805.965 [controlThread](DEBUG): Initializing ControlThread 2020-01-10T03:10:05.973Z,1578625805.973 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-01-10T03:10:05.973Z,1578625805.973 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-01-10T03:10:05.977Z,1578625805.977 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-01-10T03:10:05.977Z,1578625805.977 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-01-10T03:10:05.978Z,1578625805.978 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-01-10T03:10:05.978Z,1578625805.978 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-01-10T03:10:05.978Z,1578625805.978 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-01-10T03:10:05.979Z,1578625805.979 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-01-10T03:10:05.979Z,1578625805.979 [SBIT](INFO): Initialize SBIT Component. 2020-01-10T03:10:05.980Z,1578625805.980 [SBIT](IMPORTANT): git: 2019-12-03-17-g4812c94 2020-01-10T03:10:05.980Z,1578625805.980 [SBIT](INFO): git hash: 4812c9429720da7730f61311b178b42396aebb36 2020-01-10T03:10:05.980Z,1578625805.980 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-01-10T03:10:05.982Z,1578625805.982 [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-10T03:10:05.983Z,1578625805.983 [SBIT](INFO): Beginning SBIT in 39.000000 seconds. 2020-01-10T03:10:05.983Z,1578625805.983 [IBIT](INFO): Initialize IBIT Component. 2020-01-10T03:10:05.984Z,1578625805.984 [CBIT](DEBUG): Initialize CBIT Component. 2020-01-10T03:10:05.985Z,1578625805.985 [logger ThreadHandler](INFO): Handler Thread ID is 3579 2020-01-10T03:10:05.997Z,1578625805.997 [CBIT](DEBUG): Initialized mux pins. 2020-01-10T03:10:05.997Z,1578625805.997 [CBIT](DEBUG): Initializing the watchdog timer. 2020-01-10T03:10:06.005Z,1578625806.005 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3580 2020-01-10T03:10:06.006Z,1578625806.006 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-01-10T03:10:06.017Z,1578625806.017 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3581 2020-01-10T03:10:06.021Z,1578625806.021 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2020-01-10T03:10:06.021Z,1578625806.021 [CBIT](DEBUG): Initializing heartbeat. 2020-01-10T03:10:06.029Z,1578625806.029 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 3582 2020-01-10T03:10:06.030Z,1578625806.030 [CTD_NeilBrown](INFO): Powering down 2020-01-10T03:10:06.049Z,1578625806.049 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 3583 2020-01-10T03:10:06.050Z,1578625806.050 [WetLabsSeaOWL_UV_A](INFO): Powering down 2020-01-10T03:10:06.082Z,1578625806.082 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3584 2020-01-10T03:10:06.085Z,1578625806.085 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-01-10T03:10:06.085Z,1578625806.085 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-01-10T03:10:06.086Z,1578625806.086 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-01-10T03:10:06.086Z,1578625806.086 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-01-10T03:10:06.086Z,1578625806.086 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-01-10T03:10:06.086Z,1578625806.086 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-01-10T03:10:06.086Z,1578625806.086 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-01-10T03:10:06.087Z,1578625806.087 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-01-10T03:10:06.087Z,1578625806.087 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-01-10T03:10:06.087Z,1578625806.087 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-01-10T03:10:06.087Z,1578625806.087 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-01-10T03:10:06.087Z,1578625806.087 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-01-10T03:10:06.087Z,1578625806.087 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-01-10T03:10:06.088Z,1578625806.088 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-01-10T03:10:06.088Z,1578625806.088 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-01-10T03:10:06.088Z,1578625806.088 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-01-10T03:10:06.093Z,1578625806.093 [CBIT](DEBUG): Deactivating GF circuits. 2020-01-10T03:10:06.093Z,1578625806.093 [CBIT](DEBUG): Deactivating emergency mode. 2020-01-10T03:10:06.129Z,1578625806.129 [CBIT](DEBUG): Backplane powered. 2020-01-10T03:10:06.129Z,1578625806.129 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-01-10T03:10:06.131Z,1578625806.131 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-01-10T03:10:06.131Z,1578625806.131 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-01-10T03:10:06.132Z,1578625806.132 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-01-10T03:10:06.133Z,1578625806.133 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-01-10T03:10:06.143Z,1578625806.143 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-01-10T03:10:06.161Z,1578625806.161 [MissionManager](DEBUG): 2020-01-10T03:10:06.162Z,1578625806.162 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-01-10T03:10:06.236Z,1578625806.236 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-01-10T03:10:06.237Z,1578625806.237 [Default:A.Wait](DEBUG): Construct Wait. 2020-01-10T03:10:06.239Z,1578625806.239 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-01-10T03:10:06.287Z,1578625806.287 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-01-10T03:10:06.289Z,1578625806.289 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-01-10T03:10:06.315Z,1578625806.315 [Default:E.Execute](DEBUG): Construct Execute. 2020-01-10T03:10:06.342Z,1578625806.342 [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-10T03:10:06.347Z,1578625806.347 [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-10T03:10:06.359Z,1578625806.359 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-01-10T03:10:06.401Z,1578625806.401 [DDM](INFO): Powering up 2020-01-10T03:10:06.401Z,1578625806.401 [DDM](DEBUG): Initializing DDM. 2020-01-10T03:10:06.421Z,1578625806.421 [Radio_Surface](INFO): Powering up 2020-01-10T03:10:06.428Z,1578625806.428 [DUSBL_Hydroid](INFO): Powering up 2020-01-10T03:10:06.428Z,1578625806.428 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2020-01-10T03:10:06.671Z,1578625806.671 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-01-10T03:10:06.677Z,1578625806.677 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-01-10T03:10:06.678Z,1578625806.678 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-01-10T03:10:06.685Z,1578625806.685 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-01-10T03:10:06.686Z,1578625806.686 [MassServo](DEBUG): Initializing EZServoServo. 2020-01-10T03:10:06.693Z,1578625806.693 [MassServo](DEBUG): Initializing MassServo. 2020-01-10T03:10:06.694Z,1578625806.694 [RudderServo](DEBUG): Initializing EZServoServo. 2020-01-10T03:10:06.701Z,1578625806.701 [RudderServo](DEBUG): Initializing RudderServo. 2020-01-10T03:10:06.702Z,1578625806.702 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-01-10T03:10:06.709Z,1578625806.709 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-01-10T03:10:06.870Z,1578625806.870 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2020-01-10T03:10:06.870Z,1578625806.870 [DropWeight] Hardware Fault, FailCount= 1 2020-01-10T03:10:06.870Z,1578625806.870 [DropWeight](ERROR): Hardware Fault 2020-01-10T03:10:06.872Z,1578625806.872 [Micromodem](INFO): Powering up 2020-01-10T03:10:06.872Z,1578625806.872 [Micromodem](DEBUG): Initializing Micromodem. 2020-01-10T03:10:06.930Z,1578625806.930 [CommandLine](FAULT): Scheduling is paused 2020-01-10T03:10:06.931Z,1578625806.931 [CBIT](INFO): Critical error at 20200110T031006 2020-01-10T03:10:06.931Z,1578625806.931 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2020-01-10T03:10:06.933Z,1578625806.933 [CBIT](ERROR): Hardware Fault in component: DropWeight 2020-01-10T03:10:06.934Z,1578625806.934 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2020-01-10T03:10:07.557Z,1578625807.557 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2020-01-10T03:10:07.557Z,1578625807.557 [RudderServo](FAULT): Rudder failed to initialize 2020-01-10T03:10:07.557Z,1578625807.557 [RudderServo] Communications Fault, FailCount= 1 2020-01-10T03:10:07.557Z,1578625807.557 [RudderServo](ERROR): Communications Fault 2020-01-10T03:10:07.666Z,1578625807.666 [CBIT](INFO): Critical error at 20200110T031006 2020-01-10T03:10:07.669Z,1578625807.669 [CBIT](ERROR): Communications Fault in component: RudderServo 2020-01-10T03:10:07.874Z,1578625807.874 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-01-10T03:10:07.874Z,1578625807.874 [RudderServo](INFO): Powering down 2020-01-10T03:10:08.551Z,1578625808.551 [RudderServo](DEBUG): Initializing EZServoServo. 2020-01-10T03:10:08.670Z,1578625808.670 [RudderServo](DEBUG): Initializing RudderServo. 2020-01-10T03:10:08.674Z,1578625808.674 [CBIT](INFO): Clearing failed state for component RudderServo 2020-01-10T03:10:08.674Z,1578625808.674 [RudderServo] No Fault, FailCount= 1 2020-01-10T03:10:11.761Z,1578625811.761 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33 2020-01-10T03:10:12.160Z,1578625812.160 [Micromodem](INFO): Nmea in: $CATMG,2020-01-10T03:10:11.743057Z,RTC,RTC*50 2020-01-10T03:10:12.160Z,1578625812.160 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-10T03:10:11.743057Z,RTC,RTC*50 2020-01-10T03:10:12.567Z,1578625812.567 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31 2020-01-10T03:10:12.567Z,1578625812.567 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31 2020-01-10T03:10:12.967Z,1578625812.967 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33 2020-01-10T03:10:12.967Z,1578625812.967 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64 2020-01-10T03:10:13.380Z,1578625813.380 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66 2020-01-10T03:10:13.380Z,1578625813.380 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F 2020-01-10T03:10:13.775Z,1578625813.775 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D 2020-01-10T03:10:13.775Z,1578625813.775 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E 2020-01-10T03:10:14.187Z,1578625814.187 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C 2020-01-10T03:10:14.187Z,1578625814.187 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46 2020-01-10T03:10:14.587Z,1578625814.587 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44 2020-01-10T03:10:14.588Z,1578625814.588 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63 2020-01-10T03:10:15.003Z,1578625815.003 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61 2020-01-10T03:10:15.003Z,1578625815.003 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A 2020-01-10T03:10:15.371Z,1578625815.371 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38 2020-01-10T03:10:15.372Z,1578625815.372 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,10,03,10,16*41 2020-01-10T03:10:15.805Z,1578625815.805 [Micromodem](INFO): Nmea in: $CACLK,2020,1,10,3,10,16*43 2020-01-10T03:10:16.175Z,1578625816.175 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-10T03:10:17Z*72 2020-01-10T03:10:16.177Z,1578625816.177 [Micromodem](INFO): Nmea in: $CATMG,2020-01-10T03:10:17.027651Z,USER_CMD,RTC*12 2020-01-10T03:10:16.000Z,1578625817.000 [DDM](INFO): Dynamic Docking Module: 2020-01-10T03:10:19.858Z,1578625819.858 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2020-01-10T03:10:21.931Z,1578625821.931 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004630 2020-01-10T03:10:24.246Z,1578625824.246 [DUSBL_Hydroid](INFO): DUSBL Version:O 2020-01-10T03:10:31.939Z,1578625831.939 [NAL9602](INFO): Powering up NAL9602 2020-01-10T03:10:41.628Z,1578625841.628 [Micromodem](INFO): Nmea in: $CACYC,1,12,1,1,0,2*6B 2020-01-10T03:10:41.628Z,1578625841.628 [Micromodem](INFO): Have incoming data 2020-01-10T03:10:41.639Z,1578625841.639 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,1,858A1AB878DB70DBE89704525FDC95B5C5FE9F4147C09B083E4A8CE30F9EA23DB7C2EC1A3F0C8416B2D6BA469C63028C955B1EDAD054116D0BA2C541AD2A74E9*5A 2020-01-10T03:10:41.639Z,1578625841.639 [Micromodem](INFO): Frame:1 2020-01-10T03:10:41.640Z,1578625841.640 [Micromodem](INFO): Frame Size:127 2020-01-10T03:10:41.640Z,1578625841.640 [Micromodem](INFO): Data End:143 2020-01-10T03:10:41.640Z,1578625841.640 [Micromodem](INFO): Data At:16 2020-01-10T03:10:42.024Z,1578625842.024 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,2,4EEE1310BA042F91D0BD31A76379D458F2E8C122*52 2020-01-10T03:10:42.047Z,1578625842.047 [Micromodem](INFO): Frame:2 2020-01-10T03:10:42.047Z,1578625842.047 [Micromodem](INFO): Frame Size:40 2020-01-10T03:10:42.047Z,1578625842.047 [Micromodem](INFO): Data End:56 2020-01-10T03:10:42.047Z,1578625842.047 [Micromodem](INFO): Data At:16 2020-01-10T03:10:42.048Z,1578625842.048 [Micromodem](INFO): Deserialize. FrameCount:2 2020-01-10T03:10:42.048Z,1578625842.048 [Micromodem](INFO): Outer loop. Size:127 2020-01-10T03:10:42.048Z,1578625842.048 [Micromodem](INFO): Inner loop. C:133 2020-01-10T03:10:42.048Z,1578625842.048 [Micromodem](INFO): Inner loop. C:138 2020-01-10T03:10:42.048Z,1578625842.048 [Micromodem](INFO): Inner loop. C:26 2020-01-10T03:10:42.049Z,1578625842.049 [Micromodem](INFO): Inner loop. C:184 2020-01-10T03:10:42.049Z,1578625842.049 [Micromodem](INFO): Inner loop. C:120 2020-01-10T03:10:42.049Z,1578625842.049 [Micromodem](INFO): Inner loop. C:219 2020-01-10T03:10:42.049Z,1578625842.049 [Micromodem](INFO): Inner loop. C:112 2020-01-10T03:10:42.049Z,1578625842.049 [Micromodem](INFO): Inner loop. C:219 2020-01-10T03:10:42.050Z,1578625842.050 [Micromodem](INFO): Inner loop. C:232 2020-01-10T03:10:42.050Z,1578625842.050 [Micromodem](INFO): Inner loop. C:151 2020-01-10T03:10:42.050Z,1578625842.050 [Micromodem](INFO): Inner loop. C:4 2020-01-10T03:10:42.050Z,1578625842.050 [Micromodem](INFO): Inner loop. C:82 2020-01-10T03:10:42.050Z,1578625842.050 [Micromodem](INFO): Inner loop. C:95 2020-01-10T03:10:42.050Z,1578625842.050 [Micromodem](INFO): Inner loop. C:220 2020-01-10T03:10:42.051Z,1578625842.051 [Micromodem](INFO): Inner loop. C:149 2020-01-10T03:10:42.051Z,1578625842.051 [Micromodem](INFO): Inner loop. C:181 2020-01-10T03:10:42.051Z,1578625842.051 [Micromodem](INFO): Inner loop. C:197 2020-01-10T03:10:42.051Z,1578625842.051 [Micromodem](INFO): Inner loop. C:254 2020-01-10T03:10:42.051Z,1578625842.051 [Micromodem](INFO): Inner loop. C:159 2020-01-10T03:10:42.052Z,1578625842.052 [Micromodem](INFO): Inner loop. C:65 2020-01-10T03:10:42.052Z,1578625842.052 [Micromodem](INFO): Inner loop. C:71 2020-01-10T03:10:42.052Z,1578625842.052 [Micromodem](INFO): Inner loop. C:192 2020-01-10T03:10:42.052Z,1578625842.052 [Micromodem](INFO): Inner loop. C:155 2020-01-10T03:10:42.052Z,1578625842.052 [Micromodem](INFO): Inner loop. C:8 2020-01-10T03:10:42.052Z,1578625842.052 [Micromodem](INFO): Inner loop. C:62 2020-01-10T03:10:42.053Z,1578625842.053 [Micromodem](INFO): Inner loop. C:74 2020-01-10T03:10:42.053Z,1578625842.053 [Micromodem](INFO): Inner loop. C:140 2020-01-10T03:10:42.053Z,1578625842.053 [Micromodem](INFO): Inner loop. C:227 2020-01-10T03:10:42.054Z,1578625842.054 [Micromodem](INFO): Inner loop. C:15 2020-01-10T03:10:42.054Z,1578625842.054 [Micromodem](INFO): Inner loop. C:158 2020-01-10T03:10:42.054Z,1578625842.054 [Micromodem](INFO): Inner loop. C:162 2020-01-10T03:10:42.054Z,1578625842.054 [Micromodem](INFO): Inner loop. C:61 2020-01-10T03:10:42.054Z,1578625842.054 [Micromodem](INFO): Inner loop. C:183 2020-01-10T03:10:42.055Z,1578625842.055 [Micromodem](INFO): Inner loop. C:194 2020-01-10T03:10:42.055Z,1578625842.055 [Micromodem](INFO): Inner loop. C:236 2020-01-10T03:10:42.055Z,1578625842.055 [Micromodem](INFO): Inner loop. C:26 2020-01-10T03:10:42.055Z,1578625842.055 [Micromodem](INFO): Inner loop. C:63 2020-01-10T03:10:42.055Z,1578625842.055 [Micromodem](INFO): Inner loop. C:12 2020-01-10T03:10:42.055Z,1578625842.055 [Micromodem](INFO): Inner loop. C:132 2020-01-10T03:10:42.056Z,1578625842.056 [Micromodem](INFO): Inner loop. C:22 2020-01-10T03:10:42.056Z,1578625842.056 [Micromodem](INFO): Inner loop. C:178 2020-01-10T03:10:42.056Z,1578625842.056 [Micromodem](INFO): Inner loop. C:214 2020-01-10T03:10:42.056Z,1578625842.056 [Micromodem](INFO): Inner loop. C:186 2020-01-10T03:10:42.056Z,1578625842.056 [Micromodem](INFO): Inner loop. C:70 2020-01-10T03:10:42.057Z,1578625842.057 [Micromodem](INFO): Inner loop. C:156 2020-01-10T03:10:42.057Z,1578625842.057 [Micromodem](INFO): Inner loop. C:99 2020-01-10T03:10:42.057Z,1578625842.057 [Micromodem](INFO): Inner loop. C:2 2020-01-10T03:10:42.057Z,1578625842.057 [Micromodem](INFO): Inner loop. C:140 2020-01-10T03:10:42.057Z,1578625842.057 [Micromodem](INFO): Inner loop. C:149 2020-01-10T03:10:42.058Z,1578625842.058 [Micromodem](INFO): Inner loop. C:91 2020-01-10T03:10:42.058Z,1578625842.058 [Micromodem](INFO): Inner loop. C:30 2020-01-10T03:10:42.058Z,1578625842.058 [Micromodem](INFO): Inner loop. C:218 2020-01-10T03:10:42.058Z,1578625842.058 [Micromodem](INFO): Inner loop. C:208 2020-01-10T03:10:42.058Z,1578625842.058 [Micromodem](INFO): Inner loop. C:84 2020-01-10T03:10:42.058Z,1578625842.058 [Micromodem](INFO): Inner loop. C:17 2020-01-10T03:10:42.059Z,1578625842.059 [Micromodem](INFO): Inner loop. C:109 2020-01-10T03:10:42.059Z,1578625842.059 [Micromodem](INFO): Inner loop. C:11 2020-01-10T03:10:42.059Z,1578625842.059 [Micromodem](INFO): Inner loop. C:162 2020-01-10T03:10:42.059Z,1578625842.059 [Micromodem](INFO): Inner loop. C:197 2020-01-10T03:10:42.059Z,1578625842.059 [Micromodem](INFO): Inner loop. C:65 2020-01-10T03:10:42.060Z,1578625842.060 [Micromodem](INFO): Inner loop. C:173 2020-01-10T03:10:42.060Z,1578625842.060 [Micromodem](INFO): Inner loop. C:42 2020-01-10T03:10:42.060Z,1578625842.060 [Micromodem](INFO): Inner loop. C:116 2020-01-10T03:10:42.060Z,1578625842.060 [Micromodem](INFO): Inner loop. C:233 2020-01-10T03:10:42.060Z,1578625842.060 [Micromodem](INFO): Inner loop. C:5 2020-01-10T03:10:42.060Z,1578625842.060 [Micromodem](INFO): Inner loop. C:160 2020-01-10T03:10:42.061Z,1578625842.061 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.061Z,1578625842.061 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.061Z,1578625842.061 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.061Z,1578625842.061 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.061Z,1578625842.061 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.062Z,1578625842.062 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.062Z,1578625842.062 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.062Z,1578625842.062 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.062Z,1578625842.062 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.062Z,1578625842.062 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.062Z,1578625842.062 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.063Z,1578625842.063 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.063Z,1578625842.063 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.063Z,1578625842.063 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.063Z,1578625842.063 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.063Z,1578625842.063 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.064Z,1578625842.064 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.064Z,1578625842.064 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.064Z,1578625842.064 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.064Z,1578625842.064 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.064Z,1578625842.064 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.064Z,1578625842.064 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.109Z,1578625842.109 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.109Z,1578625842.109 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.109Z,1578625842.109 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.110Z,1578625842.110 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.110Z,1578625842.110 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.110Z,1578625842.110 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.110Z,1578625842.110 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.110Z,1578625842.110 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.111Z,1578625842.111 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.111Z,1578625842.111 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.111Z,1578625842.111 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.111Z,1578625842.111 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.126Z,1578625842.126 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.127Z,1578625842.127 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.128Z,1578625842.128 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.129Z,1578625842.129 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.129Z,1578625842.129 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.129Z,1578625842.129 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.129Z,1578625842.129 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.129Z,1578625842.129 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.129Z,1578625842.129 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.130Z,1578625842.130 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.130Z,1578625842.130 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.130Z,1578625842.130 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.130Z,1578625842.130 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.130Z,1578625842.130 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.131Z,1578625842.131 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.131Z,1578625842.131 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.131Z,1578625842.131 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.131Z,1578625842.131 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.131Z,1578625842.131 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.131Z,1578625842.131 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.132Z,1578625842.132 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.132Z,1578625842.132 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.132Z,1578625842.132 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.132Z,1578625842.132 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.132Z,1578625842.132 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.157Z,1578625842.157 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.157Z,1578625842.157 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.157Z,1578625842.157 [Micromodem](INFO): Outer loop. Size:40 2020-01-10T03:10:42.157Z,1578625842.157 [Micromodem](INFO): Inner loop. C:78 2020-01-10T03:10:42.158Z,1578625842.158 [Micromodem](INFO): Inner loop. C:238 2020-01-10T03:10:42.158Z,1578625842.158 [Micromodem](INFO): Inner loop. C:19 2020-01-10T03:10:42.158Z,1578625842.158 [Micromodem](INFO): Inner loop. C:16 2020-01-10T03:10:42.158Z,1578625842.158 [Micromodem](INFO): Inner loop. C:186 2020-01-10T03:10:42.158Z,1578625842.158 [Micromodem](INFO): Inner loop. C:4 2020-01-10T03:10:42.158Z,1578625842.158 [Micromodem](INFO): Inner loop. C:47 2020-01-10T03:10:42.159Z,1578625842.159 [Micromodem](INFO): Inner loop. C:145 2020-01-10T03:10:42.159Z,1578625842.159 [Micromodem](INFO): Inner loop. C:208 2020-01-10T03:10:42.159Z,1578625842.159 [Micromodem](INFO): Inner loop. C:189 2020-01-10T03:10:42.159Z,1578625842.159 [Micromodem](INFO): Inner loop. C:49 2020-01-10T03:10:42.159Z,1578625842.159 [Micromodem](INFO): Inner loop. C:167 2020-01-10T03:10:42.159Z,1578625842.159 [Micromodem](INFO): Inner loop. C:99 2020-01-10T03:10:42.160Z,1578625842.160 [Micromodem](INFO): Inner loop. C:121 2020-01-10T03:10:42.160Z,1578625842.160 [Micromodem](INFO): Inner loop. C:212 2020-01-10T03:10:42.160Z,1578625842.160 [Micromodem](INFO): Inner loop. C:88 2020-01-10T03:10:42.160Z,1578625842.160 [Micromodem](INFO): Inner loop. C:242 2020-01-10T03:10:42.160Z,1578625842.160 [Micromodem](INFO): Inner loop. C:232 2020-01-10T03:10:42.161Z,1578625842.161 [Micromodem](INFO): Inner loop. C:193 2020-01-10T03:10:42.161Z,1578625842.161 [Micromodem](INFO): Inner loop. C:34 2020-01-10T03:10:42.161Z,1578625842.161 [Micromodem](INFO): Inner loop. C:5 2020-01-10T03:10:42.161Z,1578625842.161 [Micromodem](INFO): Inner loop. C:32 2020-01-10T03:10:42.161Z,1578625842.161 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.161Z,1578625842.161 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.162Z,1578625842.162 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.162Z,1578625842.162 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.162Z,1578625842.162 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.162Z,1578625842.162 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.162Z,1578625842.162 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.163Z,1578625842.163 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.163Z,1578625842.163 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.163Z,1578625842.163 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.163Z,1578625842.163 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.163Z,1578625842.163 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.163Z,1578625842.163 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.164Z,1578625842.164 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.164Z,1578625842.164 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.164Z,1578625842.164 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.164Z,1578625842.164 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.164Z,1578625842.164 [Micromodem](INFO): Inner loop. C:0 2020-01-10T03:10:42.181Z,1578625842.181 [Micromodem](CRITICAL): Packet size is not N*16+4: 0xA7 2020-01-10T03:10:42.216Z,1578625842.216 [Micromodem](CRITICAL): Failed to parse uplink message:858A1AB878DB70DBE89704525FDC95B5C5FE9F4147C09B083E4A8CE30F9EA23DB7C2EC1A3F0C8416B2D6BA469C63028C955B1EDAD054116D0BA2C541AD2A74E905A0000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000004EEE1310BA042F91D0BD31A76379D458F2E8C1220520000000000000000000000000000000000000 2020-01-10T03:10:42.236Z,1578625842.236 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110031039.201775,06,2215,33,0165,0150,29,01,01,00,03,1,012,001,0,3,2,0,150,26.2,7.00,00,-4.00,-01,-0.02,20,10000,4000*76 2020-01-10T03:10:42.336Z,1578625842.336 [CBIT](INFO): Critical error at 20200110T031042 2020-01-10T03:10:42.831Z,1578625842.831 [NAL9602](INFO): NAL9602 initialized 2020-01-10T03:10:45.796Z,1578625845.796 [SBIT](IMPORTANT): Beginning Startup BIT 2020-01-10T03:10:45.812Z,1578625845.812 [CBIT](IMPORTANT): Beginning ground fault scan 2020-01-10T03:10:56.960Z,1578625856.960 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.011302 CHAN A1 (24V): -0.029170 CHAN A2 (12V): -0.007308 CHAN A3 (5V): -0.001993 CHAN B0 (3.3V): 0.000576 CHAN B1 (3.15aV): -0.000240 CHAN B2 (3.15bV): 0.000124 CHAN B3 (GND): 0.001747 OPEN: 0.005312 Full Scale Calc: 4.765 mA, -1.589 mA 2020-01-10T03:11:39.179Z,1578625899.179 [SBIT](FAULT): Rudder: EXPECTED:0.000000 ACTUAL:0.006598 2020-01-10T03:11:39.179Z,1578625899.179 [SBIT](FAULT): Control surface position failure. 2020-01-10T03:11:39.579Z,1578625899.579 [SBIT](CRITICAL): SBIT FAILED 2020-01-10T03:11:39.631Z,1578625899.631 [CommandLine](IMPORTANT): got command configSet list 2020-01-10T03:11:39.632Z,1578625899.632 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-01-10T03:11:39.635Z,1578625899.635 [CommandLine](IMPORTANT): Courier linearApproximation depth 5.000000 meter; 2020-01-10T03:11:39.635Z,1578625899.635 [CommandLine](IMPORTANT): Courier linearApproximation latitude 0.000500 angular_degree; 2020-01-10T03:11:39.635Z,1578625899.635 [CommandLine](IMPORTANT): Courier linearApproximation longitude 0.000500 angular_degree; 2020-01-10T03:11:39.635Z,1578625899.635 [CommandLine](IMPORTANT): DDM.loadAtStartup=1 bool; 2020-01-10T03:11:39.635Z,1578625899.635 [CommandLine](IMPORTANT): DDM.verbosity=3 count; 2020-01-10T03:11:39.635Z,1578625899.635 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=75 count; 2020-01-10T03:11:39.635Z,1578625899.635 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout=40 millisecond; 2020-01-10T03:11:39.635Z,1578625899.635 [CommandLine](IMPORTANT): Express linearApproximation DDM.DDMMode 1.000000 count; 2020-01-10T03:11:39.635Z,1578625899.635 [CommandLine](IMPORTANT): Express all DDM.cablePresent; 2020-01-10T03:11:39.636Z,1578625899.636 [CommandLine](IMPORTANT): Express linearApproximation DDM.latchState 1.000000 count; 2020-01-10T03:11:39.636Z,1578625899.636 [CommandLine](IMPORTANT): Express linearApproximation DDM.whiskerState 1.000000 count; 2020-01-10T03:11:39.636Z,1578625899.636 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.acoustic_contact_range 1.000000 meter; 2020-01-10T03:11:39.636Z,1578625899.636 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 2.000000 degree; 2020-01-10T03:11:39.636Z,1578625899.636 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.height_above_sea_floor 2.000000 meter; 2020-01-10T03:11:39.636Z,1578625899.636 [CommandLine](IMPORTANT): Express linearApproximation platform_orientation 5.000000 degree; 2020-01-10T03:11:39.636Z,1578625899.636 [CommandLine](IMPORTANT): Express linearApproximation platform_x_velocity_wrt_ground 0.100000 meter_per_second; 2020-01-10T03:11:39.636Z,1578625899.636 [CommandLine](IMPORTANT): Express linearApproximation platform_y_velocity_wrt_ground 0.100000 meter_per_second; 2020-01-10T03:11:39.636Z,1578625899.636 [CommandLine](IMPORTANT): HorizontalControl.kiHeading=0.002 reciprocal_second; 2020-01-10T03:11:39.657Z,1578625899.657 [CommandLine](IMPORTANT): HorizontalControl.kpHeading=0.8 none; 2020-01-10T03:11:39.657Z,1578625899.657 [CommandLine](IMPORTANT): HorizontalControl.rudDeadband=0.05 degree; 2020-01-10T03:11:39.657Z,1578625899.657 [CommandLine](IMPORTANT): Micromodem.destinationAddress=12 enum; 2020-01-10T03:11:39.657Z,1578625899.657 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold=0 meter; 2020-01-10T03:11:39.657Z,1578625899.657 [CommandLine](IMPORTANT): PowerOnly.sampleTime=90 second; 2020-01-10T03:11:39.657Z,1578625899.657 [CommandLine](IMPORTANT): RudderServo.deviationAngle=0.0001 degree; 2020-01-10T03:11:39.657Z,1578625899.657 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter; 2020-01-10T03:11:39.658Z,1578625899.658 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter; 2020-01-10T03:11:39.660Z,1578625899.660 [CBIT](INFO): Critical error at 20200110T031139 2020-01-10T03:11:39.996Z,1578625899.996 [MissionManager](IMPORTANT): Started mission Startup 2020-01-10T03:11:39.997Z,1578625899.997 [Startup] Running Loop=1 2020-01-10T03:11:39.997Z,1578625899.997 [Startup](DEBUG): Aggregate::initialize Startup 2020-01-10T03:11:39.997Z,1578625899.997 [Startup:A.GoToSurface] Running Loop=1 2020-01-10T03:11:39.997Z,1578625899.997 [St