2020-01-10T02:16:10.252Z,1578622570.252 [Supervisor](DEBUG): Initializing supervisor.
2020-01-10T02:16:10.255Z,1578622570.255 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-01-10T02:16:10.256Z,1578622570.256 [SyncHandler](INFO): Protected caller Thread ID is 9188
2020-01-10T02:16:10.257Z,1578622570.257 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-01-10T02:16:10.258Z,1578622570.258 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-01-10T02:16:10.258Z,1578622570.258 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9189
2020-01-10T02:16:10.261Z,1578622570.261 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-01-10T02:16:10.273Z,1578622570.273 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-01-10T02:16:10.274Z,1578622570.274 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-01-10T02:16:10.274Z,1578622570.274 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9190
2020-01-10T02:16:10.275Z,1578622570.275 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-01-10T02:16:10.276Z,1578622570.276 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-01-10T02:16:10.276Z,1578622570.276 [logger ThreadHandler](INFO): Protected caller Thread ID is 9191
2020-01-10T02:16:10.278Z,1578622570.278 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-01-10T02:16:10.278Z,1578622570.278 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-01-10T02:16:10.280Z,1578622570.280 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-01-10T02:16:10.694Z,1578622570.694 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-01-10T02:16:10.695Z,1578622570.695 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-01-10T02:16:10.789Z,1578622570.789 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-01-10T02:16:10.790Z,1578622570.790 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-01-10T02:16:11.100Z,1578622571.100 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-01-10T02:16:11.100Z,1578622571.100 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-01-10T02:16:11.234Z,1578622571.234 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-01-10T02:16:11.234Z,1578622571.234 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-01-10T02:16:11.416Z,1578622571.416 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-01-10T02:16:11.417Z,1578622571.417 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-01-10T02:16:11.848Z,1578622571.848 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-01-10T02:16:11.849Z,1578622571.849 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-01-10T02:16:12.050Z,1578622572.050 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-01-10T02:16:12.051Z,1578622572.051 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-01-10T02:16:12.190Z,1578622572.190 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-01-10T02:16:12.191Z,1578622572.191 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-01-10T02:16:12.370Z,1578622572.370 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-01-10T02:16:12.370Z,1578622572.370 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-01-10T02:16:12.463Z,1578622572.463 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-01-10T02:16:12.464Z,1578622572.464 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-01-10T02:16:12.752Z,1578622572.752 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-01-10T02:16:12.753Z,1578622572.753 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-01-10T02:16:12.831Z,1578622572.831 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-01-10T02:16:12.961Z,1578622572.961 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-01-10T02:16:12.962Z,1578622572.962 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-01-10T02:16:13.925Z,1578622573.925 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-01-10T02:16:13.925Z,1578622573.925 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-01-10T02:16:14.324Z,1578622574.324 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-01-10T02:16:14.326Z,1578622574.326 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2020-01-10T02:16:14.327Z,1578622574.327 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2020-01-10T02:16:14.523Z,1578622574.523 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2020-01-10T02:16:14.619Z,1578622574.619 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2020-01-10T02:16:14.714Z,1578622574.714 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2020-01-10T02:16:14.938Z,1578622574.938 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-01-10T02:16:14.939Z,1578622574.939 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2020-01-10T02:16:15.021Z,1578622575.021 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2020-01-10T02:16:15.111Z,1578622575.111 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2020-01-10T02:16:15.205Z,1578622575.205 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2020-01-10T02:16:15.286Z,1578622575.286 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2020-01-10T02:16:15.390Z,1578622575.390 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2020-01-10T02:16:15.564Z,1578622575.564 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2020-01-10T02:16:15.690Z,1578622575.690 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2020-01-10T02:16:15.690Z,1578622575.690 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-01-10T02:16:15.713Z,1578622575.713 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-01-10T02:16:16.028Z,1578622576.028 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-01-10T02:16:16.033Z,1578622576.033 [AHRS_M2](INFO): created writer for : platform_orientation
2020-01-10T02:16:16.035Z,1578622576.035 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-01-10T02:16:16.040Z,1578622576.040 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-01-10T02:16:16.041Z,1578622576.041 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-01-10T02:16:16.046Z,1578622576.046 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-01-10T02:16:16.046Z,1578622576.046 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-01-10T02:16:16.051Z,1578622576.051 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-01-10T02:16:16.122Z,1578622576.122 [AHRS_M2] Loaded
2020-01-10T02:16:16.122Z,1578622576.122 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-01-10T02:16:16.202Z,1578622576.202 [DataOverHttps] Loaded
2020-01-10T02:16:16.202Z,1578622576.202 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-01-10T02:16:16.203Z,1578622576.203 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407684E0
2020-01-10T02:16:16.203Z,1578622576.203 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9270
2020-01-10T02:16:16.227Z,1578622576.227 [DDM] Loaded
2020-01-10T02:16:16.228Z,1578622576.228 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2020-01-10T02:16:16.240Z,1578622576.240 [Depth_Keller] Loaded
2020-01-10T02:16:16.241Z,1578622576.241 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-01-10T02:16:16.246Z,1578622576.246 [DropWeight] Loaded
2020-01-10T02:16:16.246Z,1578622576.246 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-01-10T02:16:16.307Z,1578622576.307 [DUSBL_Hydroid] Loaded
2020-01-10T02:16:16.307Z,1578622576.307 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2020-01-10T02:16:16.353Z,1578622576.353 [Micromodem] Loaded
2020-01-10T02:16:16.353Z,1578622576.353 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2020-01-10T02:16:16.448Z,1578622576.448 [NAL9602] Loaded
2020-01-10T02:16:16.449Z,1578622576.449 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-01-10T02:16:16.486Z,1578622576.486 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now
2020-01-10T02:16:16.486Z,1578622576.486 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now
2020-01-10T02:16:16.487Z,1578622576.487 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now
2020-01-10T02:16:16.487Z,1578622576.487 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full
2020-01-10T02:16:16.488Z,1578622576.488 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now
2020-01-10T02:16:16.488Z,1578622576.488 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now
2020-01-10T02:16:16.489Z,1578622576.489 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now
2020-01-10T02:16:16.489Z,1578622576.489 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full
2020-01-10T02:16:16.490Z,1578622576.490 [Onboard] Loaded
2020-01-10T02:16:16.490Z,1578622576.490 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-01-10T02:16:16.496Z,1578622576.496 [PowerOnly] Loaded
2020-01-10T02:16:16.496Z,1578622576.496 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2020-01-10T02:16:16.502Z,1578622576.502 [Radio_Surface] Loaded
2020-01-10T02:16:16.503Z,1578622576.503 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-01-10T02:16:16.503Z,1578622576.503 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407984E0
2020-01-10T02:16:16.504Z,1578622576.504 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9271
2020-01-10T02:16:16.547Z,1578622576.547 [RDI_Pathfinder] Loaded
2020-01-10T02:16:16.547Z,1578622576.547 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2020-01-10T02:16:18.041Z,1578622578.041 [BPC1] Loaded
2020-01-10T02:16:18.041Z,1578622578.041 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-01-10T02:16:18.042Z,1578622578.042 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-01-10T02:16:18.042Z,1578622578.042 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-01-10T02:16:18.065Z,1578622578.065 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-01-10T02:16:18.066Z,1578622578.066 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-01-10T02:16:18.336Z,1578622578.336 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-01-10T02:16:18.336Z,1578622578.336 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-01-10T02:16:18.363Z,1578622578.363 [NavChart] Loaded
2020-01-10T02:16:18.363Z,1578622578.363 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-01-10T02:16:18.367Z,1578622578.367 [UniversalFixResidualReporter] Loaded
2020-01-10T02:16:18.367Z,1578622578.367 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-01-10T02:16:18.368Z,1578622578.368 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-01-10T02:16:18.368Z,1578622578.368 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-01-10T02:16:18.573Z,1578622578.573 [BuoyancyServo] Loaded
2020-01-10T02:16:18.573Z,1578622578.573 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-01-10T02:16:18.588Z,1578622578.588 [ElevatorServo] Loaded
2020-01-10T02:16:18.589Z,1578622578.589 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-01-10T02:16:18.618Z,1578622578.618 [MassServo] Loaded
2020-01-10T02:16:18.618Z,1578622578.618 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-01-10T02:16:18.720Z,1578622578.720 [RudderServo] Loaded
2020-01-10T02:16:18.721Z,1578622578.721 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-01-10T02:16:18.738Z,1578622578.738 [ThrusterServo] Loaded
2020-01-10T02:16:18.738Z,1578622578.738 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-01-10T02:16:18.739Z,1578622578.739 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-01-10T02:16:18.739Z,1578622578.739 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-01-10T02:16:18.991Z,1578622578.991 [CTD_NeilBrown] Loaded
2020-01-10T02:16:18.991Z,1578622578.991 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2020-01-10T02:16:18.992Z,1578622578.992 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408EC4E0
2020-01-10T02:16:18.993Z,1578622578.993 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 9272
2020-01-10T02:16:19.007Z,1578622579.007 [PAR_Licor] Loaded
2020-01-10T02:16:19.007Z,1578622579.007 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-01-10T02:16:19.050Z,1578622579.050 [WetLabsSeaOWL_UV_A] Loaded
2020-01-10T02:16:19.051Z,1578622579.051 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2020-01-10T02:16:19.052Z,1578622579.052 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 4091C4E0
2020-01-10T02:16:19.052Z,1578622579.052 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 9273
2020-01-10T02:16:19.052Z,1578622579.052 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-01-10T02:16:19.053Z,1578622579.053 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-01-10T02:16:19.355Z,1578622579.355 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-01-10T02:16:19.356Z,1578622579.356 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-01-10T02:16:19.395Z,1578622579.395 [DepthRateCalculator] Loaded
2020-01-10T02:16:19.395Z,1578622579.395 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-01-10T02:16:19.401Z,1578622579.401 [PitchRateCalculator] Loaded
2020-01-10T02:16:19.401Z,1578622579.401 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-01-10T02:16:19.413Z,1578622579.413 [SpeedCalculator] Loaded
2020-01-10T02:16:19.414Z,1578622579.414 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-01-10T02:16:19.434Z,1578622579.434 [TempGradientCalculator] Loaded
2020-01-10T02:16:19.434Z,1578622579.434 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-01-10T02:16:19.440Z,1578622579.440 [YawRateCalculator] Loaded
2020-01-10T02:16:19.440Z,1578622579.440 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-01-10T02:16:19.479Z,1578622579.479 [ElevatorOffsetCalculator] Loaded
2020-01-10T02:16:19.480Z,1578622579.480 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-01-10T02:16:19.480Z,1578622579.480 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-01-10T02:16:19.481Z,1578622579.481 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-01-10T02:16:19.613Z,1578622579.613 [SBIT](DEBUG): Construct Startup Built In Test.
2020-01-10T02:16:19.635Z,1578622579.635 [SBIT] Loaded
2020-01-10T02:16:19.635Z,1578622579.635 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-01-10T02:16:19.636Z,1578622579.636 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-01-10T02:16:19.648Z,1578622579.648 [IBIT] Loaded
2020-01-10T02:16:19.648Z,1578622579.648 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-01-10T02:16:19.651Z,1578622579.651 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-01-10T02:16:19.789Z,1578622579.789 [CBIT] Loaded
2020-01-10T02:16:19.789Z,1578622579.789 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-01-10T02:16:19.789Z,1578622579.789 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-01-10T02:16:19.790Z,1578622579.790 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-01-10T02:16:19.871Z,1578622579.871 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-01-10T02:16:19.871Z,1578622579.871 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-01-10T02:16:19.966Z,1578622579.966 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-01-10T02:16:19.967Z,1578622579.967 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-01-10T02:16:20.015Z,1578622580.015 [VerticalControl](DEBUG): Construct VerticalControl.
2020-01-10T02:16:20.096Z,1578622580.096 [VerticalControl] Loaded
2020-01-10T02:16:20.096Z,1578622580.096 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-01-10T02:16:20.097Z,1578622580.097 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-01-10T02:16:20.166Z,1578622580.166 [HorizontalControl] Loaded
2020-01-10T02:16:20.166Z,1578622580.166 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-01-10T02:16:20.167Z,1578622580.167 [SpeedControl](DEBUG): Construct SpeedControl.
2020-01-10T02:16:20.169Z,1578622580.169 [SpeedControl] Loaded
2020-01-10T02:16:20.169Z,1578622580.169 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-01-10T02:16:20.170Z,1578622580.170 [LoopControl](DEBUG): Construct LoopControl.
2020-01-10T02:16:20.170Z,1578622580.170 [LoopControl] Loaded
2020-01-10T02:16:20.171Z,1578622580.171 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-01-10T02:16:20.171Z,1578622580.171 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-01-10T02:16:20.172Z,1578622580.172 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-01-10T02:16:20.204Z,1578622580.204 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-01-10T02:16:20.207Z,1578622580.207 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-01-10T02:16:20.208Z,1578622580.208 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-01-10T02:16:20.215Z,1578622580.215 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-01-10T02:16:20.216Z,1578622580.216 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AFB4E0
2020-01-10T02:16:20.216Z,1578622580.216 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9274
2020-01-10T02:16:20.220Z,1578622580.220 [Supervisor](INFO): Main Thread ID is 8534
2020-01-10T02:16:20.221Z,1578622580.221 [Supervisor](DEBUG): Running supervisor.
2020-01-10T02:16:20.221Z,1578622580.221 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9275
2020-01-10T02:16:20.224Z,1578622580.224 [controlThread ThreadHandler](INFO): Handler Thread ID is 9276
2020-01-10T02:16:20.224Z,1578622580.224 [controlThread](DEBUG): Initializing ControlThread
2020-01-10T02:16:20.233Z,1578622580.233 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-01-10T02:16:20.233Z,1578622580.233 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-01-10T02:16:20.237Z,1578622580.237 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-01-10T02:16:20.237Z,1578622580.237 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-01-10T02:16:20.238Z,1578622580.238 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-01-10T02:16:20.238Z,1578622580.238 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-01-10T02:16:20.238Z,1578622580.238 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-01-10T02:16:20.239Z,1578622580.239 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-01-10T02:16:20.239Z,1578622580.239 [SBIT](INFO): Initialize SBIT Component.
2020-01-10T02:16:20.240Z,1578622580.240 [SBIT](IMPORTANT): git: 2019-12-03-17-g4812c94
2020-01-10T02:16:20.240Z,1578622580.240 [SBIT](INFO): git hash: 4812c9429720da7730f61311b178b42396aebb36
2020-01-10T02:16:20.240Z,1578622580.240 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-01-10T02:16:20.242Z,1578622580.242 [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-10T02:16:20.243Z,1578622580.243 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2020-01-10T02:16:20.243Z,1578622580.243 [IBIT](INFO): Initialize IBIT Component.
2020-01-10T02:16:20.244Z,1578622580.244 [CBIT](DEBUG): Initialize CBIT Component.
2020-01-10T02:16:20.245Z,1578622580.245 [logger ThreadHandler](INFO): Handler Thread ID is 9277
2020-01-10T02:16:20.257Z,1578622580.257 [CBIT](DEBUG): Initialized mux pins.
2020-01-10T02:16:20.257Z,1578622580.257 [CBIT](DEBUG): Initializing the watchdog timer.
2020-01-10T02:16:20.265Z,1578622580.265 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9278
2020-01-10T02:16:20.266Z,1578622580.266 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-01-10T02:16:20.277Z,1578622580.277 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9279
2020-01-10T02:16:20.281Z,1578622580.281 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-01-10T02:16:20.281Z,1578622580.281 [CBIT](DEBUG): Initializing heartbeat.
2020-01-10T02:16:20.289Z,1578622580.289 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 9280
2020-01-10T02:16:20.290Z,1578622580.290 [CTD_NeilBrown](INFO): Powering down
2020-01-10T02:16:20.309Z,1578622580.309 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 9281
2020-01-10T02:16:20.310Z,1578622580.310 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-10T02:16:20.342Z,1578622580.342 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9282
2020-01-10T02:16:20.345Z,1578622580.345 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-01-10T02:16:20.345Z,1578622580.345 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-01-10T02:16:20.345Z,1578622580.345 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-01-10T02:16:20.345Z,1578622580.345 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-01-10T02:16:20.346Z,1578622580.346 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-01-10T02:16:20.346Z,1578622580.346 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-01-10T02:16:20.346Z,1578622580.346 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-01-10T02:16:20.346Z,1578622580.346 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-01-10T02:16:20.346Z,1578622580.346 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-01-10T02:16:20.346Z,1578622580.346 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-01-10T02:16:20.347Z,1578622580.347 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-01-10T02:16:20.347Z,1578622580.347 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-01-10T02:16:20.347Z,1578622580.347 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-01-10T02:16:20.347Z,1578622580.347 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-01-10T02:16:20.347Z,1578622580.347 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-01-10T02:16:20.348Z,1578622580.348 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-01-10T02:16:20.353Z,1578622580.353 [CBIT](DEBUG): Deactivating GF circuits.
2020-01-10T02:16:20.353Z,1578622580.353 [CBIT](DEBUG): Deactivating emergency mode.
2020-01-10T02:16:20.389Z,1578622580.389 [CBIT](DEBUG): Backplane powered.
2020-01-10T02:16:20.389Z,1578622580.389 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-01-10T02:16:20.391Z,1578622580.391 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-01-10T02:16:20.391Z,1578622580.391 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-01-10T02:16:20.392Z,1578622580.392 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-01-10T02:16:20.393Z,1578622580.393 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-01-10T02:16:20.403Z,1578622580.403 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-10T02:16:20.421Z,1578622580.421 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2020-01-10T02:16:20.422Z,1578622580.422 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-01-10T02:16:20.496Z,1578622580.496 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-01-10T02:16:20.497Z,1578622580.497 [Default:A.Wait](DEBUG): Construct Wait.
2020-01-10T02:16:20.499Z,1578622580.499 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-01-10T02:16:20.547Z,1578622580.547 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-01-10T02:16:20.564Z,1578622580.564 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-01-10T02:16:20.578Z,1578622580.578 [Default:E.Execute](DEBUG): Construct Execute.
2020-01-10T02:16:20.603Z,1578622580.603 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2020-01-10T02:16:20.608Z,1578622580.608 [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-10T02:16:20.637Z,1578622580.637 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-01-10T02:16:20.676Z,1578622580.676 [DDM](INFO): Powering up
2020-01-10T02:16:20.676Z,1578622580.676 [DDM](DEBUG): Initializing DDM.
2020-01-10T02:16:20.681Z,1578622580.681 [Radio_Surface](INFO): Powering up
2020-01-10T02:16:20.703Z,1578622580.703 [DUSBL_Hydroid](INFO): Powering up
2020-01-10T02:16:20.703Z,1578622580.703 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2020-01-10T02:16:20.902Z,1578622580.902 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-01-10T02:16:20.909Z,1578622580.909 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-01-10T02:16:20.910Z,1578622580.910 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-01-10T02:16:20.917Z,1578622580.917 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-01-10T02:16:20.918Z,1578622580.918 [MassServo](DEBUG): Initializing EZServoServo.
2020-01-10T02:16:20.925Z,1578622580.925 [MassServo](DEBUG): Initializing MassServo.
2020-01-10T02:16:20.926Z,1578622580.926 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-10T02:16:20.933Z,1578622580.933 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-10T02:16:20.934Z,1578622580.934 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-01-10T02:16:20.941Z,1578622580.941 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-01-10T02:16:21.130Z,1578622581.130 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-01-10T02:16:21.130Z,1578622581.130 [DropWeight] Hardware Fault, FailCount= 1
2020-01-10T02:16:21.130Z,1578622581.130 [DropWeight](ERROR): Hardware Fault
2020-01-10T02:16:21.132Z,1578622581.132 [Micromodem](INFO): Powering up
2020-01-10T02:16:21.132Z,1578622581.132 [Micromodem](DEBUG): Initializing Micromodem.
2020-01-10T02:16:21.214Z,1578622581.214 [CommandLine](FAULT): Scheduling is paused
2020-01-10T02:16:21.215Z,1578622581.215 [CBIT](INFO): Critical error at 20200110T021621
2020-01-10T02:16:21.215Z,1578622581.215 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-01-10T02:16:21.217Z,1578622581.217 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-01-10T02:16:21.218Z,1578622581.218 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-01-10T02:16:21.793Z,1578622581.793 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-01-10T02:16:21.793Z,1578622581.793 [RudderServo](FAULT): Rudder failed to initialize
2020-01-10T02:16:21.793Z,1578622581.793 [RudderServo] Communications Fault, FailCount= 1
2020-01-10T02:16:21.793Z,1578622581.793 [RudderServo](ERROR): Communications Fault
2020-01-10T02:16:21.902Z,1578622581.902 [CBIT](INFO): Critical error at 20200110T021621
2020-01-10T02:16:21.905Z,1578622581.905 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-01-10T02:16:22.124Z,1578622582.124 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-10T02:16:22.124Z,1578622582.124 [RudderServo](INFO): Powering down
2020-01-10T02:16:22.763Z,1578622582.763 [RudderServo](DEBUG): Initializing EZServoServo.
2020-01-10T02:16:22.882Z,1578622582.882 [RudderServo](DEBUG): Initializing RudderServo.
2020-01-10T02:16:22.886Z,1578622582.886 [CBIT](INFO): Clearing failed state for component RudderServo
2020-01-10T02:16:22.886Z,1578622582.886 [RudderServo] No Fault, FailCount= 1
2020-01-10T02:16:25.993Z,1578622585.993 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2020-01-10T02:16:26.399Z,1578622586.399 [Micromodem](INFO): Nmea in: $CATMG,2020-01-10T02:16:25.980112Z,RTC,RTC*51

2020-01-10T02:16:26.400Z,1578622586.400 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2020-01-10T02:16:25.980112Z,RTC,RTC*51

2020-01-10T02:16:26.875Z,1578622586.875 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2020-01-10T02:16:26.875Z,1578622586.875 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2020-01-10T02:16:27.279Z,1578622587.279 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2020-01-10T02:16:27.279Z,1578622587.279 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2020-01-10T02:16:27.687Z,1578622587.687 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2020-01-10T02:16:27.687Z,1578622587.687 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2020-01-10T02:16:28.091Z,1578622588.091 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2020-01-10T02:16:28.091Z,1578622588.091 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2020-01-10T02:16:28.495Z,1578622588.495 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2020-01-10T02:16:28.495Z,1578622588.495 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2020-01-10T02:16:28.895Z,1578622588.895 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2020-01-10T02:16:28.895Z,1578622588.895 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2020-01-10T02:16:29.311Z,1578622589.311 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2020-01-10T02:16:29.311Z,1578622589.311 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2020-01-10T02:16:29.679Z,1578622589.679 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2020-01-10T02:16:29.680Z,1578622589.680 [Micromodem](INFO): Nmea out: $CCCLK,2020,01,10,02,16,30*42

2020-01-10T02:16:30.131Z,1578622590.131 [Micromodem](INFO): Nmea in: $CACLK,2020,1,10,2,16,30*40

2020-01-10T02:16:30.132Z,1578622590.132 [Micromodem](INFO): Nmea in: $CATMS,0,2020-01-10T02:16:31Z*71

2020-01-10T02:16:30.142Z,1578622590.142 [Micromodem](INFO): Nmea in: $CATMG,2020-01-10T02:16:31.026107Z,USER_CMD,RTC*14

2020-01-10T02:16:31.297Z,1578622591.297 [DDM](INFO): Dynamic Docking Module:C REMUS Capture Rev 1.1
2020-01-10T02:16:34.153Z,1578622594.153 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2020-01-10T02:16:38.570Z,1578622598.570 [DUSBL_Hydroid](INFO): DUSBL Version:O
2020-01-10T02:16:46.639Z,1578622606.639 [NAL9602](INFO): Powering up NAL9602
2020-01-10T02:16:57.555Z,1578622617.555 [NAL9602](INFO): NAL9602 initialized
2020-01-10T02:16:59.611Z,1578622619.611 [SBIT](IMPORTANT): Beginning Startup BIT
2020-01-10T02:16:59.636Z,1578622619.636 [CBIT](IMPORTANT): Beginning ground fault scan
2020-01-10T02:17:10.705Z,1578622630.705 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.011866
CHAN A1 (24V): -0.029206
CHAN A2 (12V): -0.007385
CHAN A3 (5V): -0.002028
CHAN B0 (3.3V): 0.000234
CHAN B1 (3.15aV): 0.000017
CHAN B2 (3.15bV): -0.000031
CHAN B3 (GND): 0.002304
OPEN: 0.005006
Full Scale Calc: 4.765 mA, -1.589 mA
2020-01-10T02:17:14.245Z,1578622634.245 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-10T02:17:14.245Z,1578622634.245 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010919300451,35.0, -0.1,   0.0, 0
2020-01-10T02:17:22.082Z,1578622642.082 [Micromodem](INFO): Nmea in: $CACYC,1,12,1,1,0,2*6B

2020-01-10T02:17:22.083Z,1578622642.083 [Micromodem](INFO): Have incoming data
2020-01-10T02:17:22.114Z,1578622642.114 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,1,858A1AB878DB70DBE89704525FDC95B5C5FE9F4147C09B083E4A8CE30F9EA23DB7C2EC1A3F0C8416B2D6BA469C63028C955B1EDAD054116D0BA2C541AD2A74E9*5A

2020-01-10T02:17:22.115Z,1578622642.115 [Micromodem](INFO): FrameSize_:127
2020-01-10T02:17:22.115Z,1578622642.115 [Micromodem](INFO): Frame Count:2
2020-01-10T02:17:22.115Z,1578622642.115 [Micromodem](INFO): Data End:143
2020-01-10T02:17:22.115Z,1578622642.115 [Micromodem](INFO): Data At:16
2020-01-10T02:17:22.115Z,1578622642.115 [Micromodem](INFO): FrameSize_:127
2020-01-10T02:17:22.116Z,1578622642.116 [Micromodem](INFO): Frame Count:2
2020-01-10T02:17:22.116Z,1578622642.116 [Micromodem](INFO): Data End:143
2020-01-10T02:17:22.116Z,1578622642.116 [Micromodem](INFO): Data At:16
2020-01-10T02:17:22.484Z,1578622642.484 [Micromodem](INFO): Nmea in: $CARXD,12,1,0,2,4EEE1310BA042F91D0BD31A76379D458F2E8C122*52

2020-01-10T02:17:22.485Z,1578622642.485 [Micromodem](INFO): FrameSize_:40
2020-01-10T02:17:22.485Z,1578622642.485 [Micromodem](INFO): Frame Count:2
2020-01-10T02:17:22.485Z,1578622642.485 [Micromodem](INFO): Data End:56
2020-01-10T02:17:22.486Z,1578622642.486 [Micromodem](INFO): Data At:16
2020-01-10T02:17:22.486Z,1578622642.486 [Micromodem](INFO): FrameSize_:40
2020-01-10T02:17:22.486Z,1578622642.486 [Micromodem](INFO): Frame Count:2
2020-01-10T02:17:22.486Z,1578622642.486 [Micromodem](INFO): Data End:56
2020-01-10T02:17:22.486Z,1578622642.486 [Micromodem](INFO): Data At:16
2020-01-10T02:17:22.487Z,1578622642.487 [Micromodem](INFO): Deserialize. FrameCount:2
2020-01-10T02:17:22.487Z,1578622642.487 [Micromodem](INFO): Outer loop. Size:40
2020-01-10T02:17:22.487Z,1578622642.487 [Micromodem](INFO): Inner loop. C:133
2020-01-10T02:17:22.487Z,1578622642.487 [Micromodem](INFO): Inner loop. C:138
2020-01-10T02:17:22.487Z,1578622642.487 [Micromodem](INFO): Inner loop. C:26
2020-01-10T02:17:22.487Z,1578622642.487 [Micromodem](INFO): Inner loop. C:184
2020-01-10T02:17:22.488Z,1578622642.488 [Micromodem](INFO): Inner loop. C:120
2020-01-10T02:17:22.488Z,1578622642.488 [Micromodem](INFO): Inner loop. C:219
2020-01-10T02:17:22.488Z,1578622642.488 [Micromodem](INFO): Inner loop. C:112
2020-01-10T02:17:22.488Z,1578622642.488 [Micromodem](INFO): Inner loop. C:219
2020-01-10T02:17:22.488Z,1578622642.488 [Micromodem](INFO): Inner loop. C:232
2020-01-10T02:17:22.488Z,1578622642.488 [Micromodem](INFO): Inner loop. C:151
2020-01-10T02:17:22.489Z,1578622642.489 [Micromodem](INFO): Inner loop. C:4
2020-01-10T02:17:22.489Z,1578622642.489 [Micromodem](INFO): Inner loop. C:82
2020-01-10T02:17:22.489Z,1578622642.489 [Micromodem](INFO): Inner loop. C:95
2020-01-10T02:17:22.489Z,1578622642.489 [Micromodem](INFO): Inner loop. C:220
2020-01-10T02:17:22.490Z,1578622642.490 [Micromodem](INFO): Inner loop. C:149
2020-01-10T02:17:22.490Z,1578622642.490 [Micromodem](INFO): Inner loop. C:181
2020-01-10T02:17:22.490Z,1578622642.490 [Micromodem](INFO): Inner loop. C:197
2020-01-10T02:17:22.490Z,1578622642.490 [Micromodem](INFO): Inner loop. C:254
2020-01-10T02:17:22.490Z,1578622642.490 [Micromodem](INFO): Inner loop. C:159
2020-01-10T02:17:22.490Z,1578622642.490 [Micromodem](INFO): Inner loop. C:65
2020-01-10T02:17:22.491Z,1578622642.491 [Micromodem](INFO): Inner loop. C:71
2020-01-10T02:17:22.491Z,1578622642.491 [Micromodem](INFO): Inner loop. C:192
2020-01-10T02:17:22.491Z,1578622642.491 [Micromodem](INFO): Inner loop. C:155
2020-01-10T02:17:22.491Z,1578622642.491 [Micromodem](INFO): Inner loop. C:8
2020-01-10T02:17:22.491Z,1578622642.491 [Micromodem](INFO): Inner loop. C:62
2020-01-10T02:17:22.491Z,1578622642.491 [Micromodem](INFO): Inner loop. C:74
2020-01-10T02:17:22.492Z,1578622642.492 [Micromodem](INFO): Inner loop. C:140
2020-01-10T02:17:22.492Z,1578622642.492 [Micromodem](INFO): Inner loop. C:227
2020-01-10T02:17:22.492Z,1578622642.492 [Micromodem](INFO): Inner loop. C:15
2020-01-10T02:17:22.492Z,1578622642.492 [Micromodem](INFO): Inner loop. C:158
2020-01-10T02:17:22.492Z,1578622642.492 [Micromodem](INFO): Inner loop. C:162
2020-01-10T02:17:22.492Z,1578622642.492 [Micromodem](INFO): Inner loop. C:61
2020-01-10T02:17:22.493Z,1578622642.493 [Micromodem](INFO): Inner loop. C:183
2020-01-10T02:17:22.493Z,1578622642.493 [Micromodem](INFO): Inner loop. C:194
2020-01-10T02:17:22.493Z,1578622642.493 [Micromodem](INFO): Inner loop. C:236
2020-01-10T02:17:22.493Z,1578622642.493 [Micromodem](INFO): Inner loop. C:26
2020-01-10T02:17:22.493Z,1578622642.493 [Micromodem](INFO): Inner loop. C:63
2020-01-10T02:17:22.494Z,1578622642.494 [Micromodem](INFO): Inner loop. C:12
2020-01-10T02:17:22.494Z,1578622642.494 [Micromodem](INFO): Inner loop. C:132
2020-01-10T02:17:22.494Z,1578622642.494 [Micromodem](INFO): Inner loop. C:22
2020-01-10T02:17:22.494Z,1578622642.494 [Micromodem](INFO): Outer loop. Size:40
2020-01-10T02:17:22.494Z,1578622642.494 [Micromodem](INFO): Inner loop. C:78
2020-01-10T02:17:22.494Z,1578622642.494 [Micromodem](INFO): Inner loop. C:238
2020-01-10T02:17:22.495Z,1578622642.495 [Micromodem](INFO): Inner loop. C:19
2020-01-10T02:17:22.495Z,1578622642.495 [Micromodem](INFO): Inner loop. C:16
2020-01-10T02:17:22.495Z,1578622642.495 [Micromodem](INFO): Inner loop. C:186
2020-01-10T02:17:22.495Z,1578622642.495 [Micromodem](INFO): Inner loop. C:4
2020-01-10T02:17:22.495Z,1578622642.495 [Micromodem](INFO): Inner loop. C:47
2020-01-10T02:17:22.496Z,1578622642.496 [Micromodem](INFO): Inner loop. C:145
2020-01-10T02:17:22.496Z,1578622642.496 [Micromodem](INFO): Inner loop. C:208
2020-01-10T02:17:22.496Z,1578622642.496 [Micromodem](INFO): Inner loop. C:189
2020-01-10T02:17:22.496Z,1578622642.496 [Micromodem](INFO): Inner loop. C:49
2020-01-10T02:17:22.496Z,1578622642.496 [Micromodem](INFO): Inner loop. C:167
2020-01-10T02:17:22.496Z,1578622642.496 [Micromodem](INFO): Inner loop. C:99
2020-01-10T02:17:22.497Z,1578622642.497 [Micromodem](INFO): Inner loop. C:121
2020-01-10T02:17:22.497Z,1578622642.497 [Micromodem](INFO): Inner loop. C:212
2020-01-10T02:17:22.497Z,1578622642.497 [Micromodem](INFO): Inner loop. C:88
2020-01-10T02:17:22.497Z,1578622642.497 [Micromodem](INFO): Inner loop. C:242
2020-01-10T02:17:22.498Z,1578622642.498 [Micromodem](INFO): Inner loop. C:232
2020-01-10T02:17:22.498Z,1578622642.498 [Micromodem](INFO): Inner loop. C:193
2020-01-10T02:17:22.498Z,1578622642.498 [Micromodem](INFO): Inner loop. C:34
2020-01-10T02:17:22.498Z,1578622642.498 [Micromodem](INFO): Inner loop. C:5
2020-01-10T02:17:22.498Z,1578622642.498 [Micromodem](INFO): Inner loop. C:32
2020-01-10T02:17:22.498Z,1578622642.498 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:17:22.499Z,1578622642.499 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:17:22.499Z,1578622642.499 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:17:22.499Z,1578622642.499 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:17:22.499Z,1578622642.499 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:17:22.499Z,1578622642.499 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:17:22.499Z,1578622642.499 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:17:22.500Z,1578622642.500 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:17:22.500Z,1578622642.500 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:17:22.500Z,1578622642.500 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:17:22.500Z,1578622642.500 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:17:22.500Z,1578622642.500 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:17:22.500Z,1578622642.500 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:17:22.501Z,1578622642.501 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:17:22.501Z,1578622642.501 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:17:22.501Z,1578622642.501 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:17:22.501Z,1578622642.501 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:17:22.501Z,1578622642.501 [Micromodem](INFO): Inner loop. C:0
2020-01-10T02:17:22.502Z,1578622642.502 [Micromodem](CRITICAL): Packet size is not N*16+4: 0x50
2020-01-10T02:17:22.508Z,1578622642.508 [Micromodem](CRITICAL): Failed to parse uplink message:858A1AB878DB70DBE89704525FDC95B5C5FE9F4147C09B083E4A8CE30F9EA23DB7C2EC1A3F0C84164EEE1310BA042F91D0BD31A76379D458F2E8C1220520000000000000000000000000000000000000
2020-01-10T02:17:22.513Z,1578622642.513 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110021719.828231,06,2171,33,0154,0150,59,02,02,00,02,1,012,001,0,3,2,0,150,26.8,8.00,00,-5.00,-01,-0.02,25,10000,4000*72

2020-01-10T02:17:22.731Z,1578622642.731 [CBIT](INFO): Critical error at 20200110T021722
2020-01-10T02:17:45.233Z,1578622665.233 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-10T02:17:45.233Z,1578622665.233 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010919303551,35.0, -0.1,   0.0,1448.9A,  0.00,   0.00,   0.00,   0.00,   0.00
2020-01-10T02:17:52.932Z,1578622672.932 [SBIT](IMPORTANT): SBIT PASSED
2020-01-10T02:17:53.001Z,1578622673.001 [CommandLine](IMPORTANT): got command configSet list
2020-01-10T02:17:53.001Z,1578622673.001 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-01-10T02:17:53.002Z,1578622673.002 [CommandLine](IMPORTANT): Courier linearApproximation depth 5.000000 meter;

2020-01-10T02:17:53.002Z,1578622673.002 [CommandLine](IMPORTANT): Courier linearApproximation latitude 0.000500 angular_degree;

2020-01-10T02:17:53.002Z,1578622673.002 [CommandLine](IMPORTANT): Courier linearApproximation longitude 0.000500 angular_degree;

2020-01-10T02:17:53.003Z,1578622673.003 [CommandLine](IMPORTANT): DDM.loadAtStartup=1 bool;

2020-01-10T02:17:53.003Z,1578622673.003 [CommandLine](IMPORTANT): DDM.verbosity=3 count;

2020-01-10T02:17:53.003Z,1578622673.003 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=75 count;

2020-01-10T02:17:53.003Z,1578622673.003 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout=40 millisecond;

2020-01-10T02:17:53.003Z,1578622673.003 [CommandLine](IMPORTANT): Express linearApproximation DDM.DDMMode 1.000000 count;

2020-01-10T02:17:53.003Z,1578622673.003 [CommandLine](IMPORTANT): Express all DDM.cablePresent;

2020-01-10T02:17:53.003Z,1578622673.003 [CommandLine](IMPORTANT): Express linearApproximation DDM.latchState 1.000000 count;

2020-01-10T02:17:53.003Z,1578622673.003 [CommandLine](IMPORTANT): Express linearApproximation DDM.whiskerState 1.000000 count;

2020-01-10T02:17:53.003Z,1578622673.003 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.acoustic_contact_range 1.000000 meter;

2020-01-10T02:17:53.003Z,1578622673.003 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 2.000000 degree;

2020-01-10T02:17:53.004Z,1578622673.004 [CommandLine](IMPORTANT): Express linearApproximation RDI_Pathfinder.height_above_sea_floor 2.000000 meter;

2020-01-10T02:17:53.004Z,1578622673.004 [CommandLine](IMPORTANT): Express linearApproximation platform_orientation 5.000000 degree;

2020-01-10T02:17:53.004Z,1578622673.004 [CommandLine](IMPORTANT): Express linearApproximation platform_x_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-10T02:17:53.004Z,1578622673.004 [CommandLine](IMPORTANT): Express linearApproximation platform_y_velocity_wrt_ground 0.100000 meter_per_second;

2020-01-10T02:17:53.004Z,1578622673.004 [CommandLine](IMPORTANT): HorizontalControl.kiHeading=0.002 reciprocal_second;

2020-01-10T02:17:53.004Z,1578622673.004 [CommandLine](IMPORTANT): HorizontalControl.kpHeading=0.8 none;

2020-01-10T02:17:53.004Z,1578622673.004 [CommandLine](IMPORTANT): HorizontalControl.rudDeadband=0.05 degree;

2020-01-10T02:17:53.004Z,1578622673.004 [CommandLine](IMPORTANT): Micromodem.destinationAddress=12 enum;

2020-01-10T02:17:53.004Z,1578622673.004 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold=0 meter;

2020-01-10T02:17:53.005Z,1578622673.005 [CommandLine](IMPORTANT): PowerOnly.sampleTime=90 second;

2020-01-10T02:17:53.006Z,1578622673.006 [CommandLine](IMPORTANT): RudderServo.deviationAngle=0.0001 degree;

2020-01-10T02:17:53.006Z,1578622673.006 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=105 cubic_centimeter;

2020-01-10T02:17:53.006Z,1578622673.006 [CommandLine](IMPORTANT): VerticalControl.massDefault=-5 millimeter;

2020-01-10T02:17:53.343Z,1578622673.343 [MissionManager](IMPORTANT): Started mission Startup
2020-01-10T02:17:53.344Z,1578622673.344 [Startup] Running Loop=1
2020-01-10T02:17:53.344Z,1578622673.344 [Startup](DEBUG): Aggregate::initialize Startup
2020-01-10T02:17:53.344Z,1578622673.344 [Startup:A.GoToSurface] Running Loop=1
2020-01-10T02:17:53.344Z,1578622673.344 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-10T02:17:53.349Z,1578622673.349 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-10T02:17:53.349Z,1578622673.349 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-10T02:17:53.350Z,1578622673.350 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-10T02:17:53.350Z,1578622673.350 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-10T02:17:53.351Z,1578622673.351 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-10T02:17:53.352Z,1578622673.352 [Startup:StartupSatComms] Running Loop=1
2020-01-10T02:17:53.352Z,1578622673.352 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-01-10T02:17:53.352Z,1578622673.352 [Startup:StartupSatComms:A] Running Loop=1
2020-01-10T02:17:53.748Z,1578622673.748 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-01-10T02:18:43.424Z,1578622723.424 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2020-01-10T02:18:49.474Z,1578622729.474 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004467
2020-01-10T02:18:53.530Z,1578622733.530 [Startup:StartupSatComms:A](INFO): Timed out from 2020-01-10T02:17:53.4Z
2020-01-10T02:18:53.530Z,1578622733.530 [Startup:StartupSatComms:A] Stopped
2020-01-10T02:18:53.530Z,1578622733.530 [Startup:StartupSatComms:B] Running Loop=1
2020-01-10T02:18:53.941Z,1578622733.941 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-10T02:18:55.147Z,1578622735.147 [Micromodem](INFO): Outgoing frame #1, 47 bytes: 96DD175E040000005D00000400140000000000000000151D80030C5669C08C34EE79F93394A0989FB3623FE7014B00
2020-01-10T02:18:55.148Z,1578622735.148 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,1*6B

2020-01-10T02:18:55.547Z,1578622735.547 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,1*69

2020-01-10T02:18:55.917Z,1578622735.917 [Micromodem](INFO): Nmea in: $CADRQ,021856,1,12,0,64,1*7C

2020-01-10T02:18:55.917Z,1578622735.917 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,96DD175E040000005D00000400140000000000000000151D80030C5669C08C34EE79F93394A0989FB3623FE7014B00*3A

2020-01-10T02:18:56.315Z,1578622736.315 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,47*4A

2020-01-10T02:18:56.730Z,1578622736.730 [Micromodem](INFO): Nmea in: $CATXP,47*71

2020-01-10T02:18:57.935Z,1578622737.935 [Micromodem](INFO): Nmea in: $CATXF,47*67

2020-01-10T02:18:58.344Z,1578622738.344 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,021856.976902,3,0,200,4000,10000,1,1,12,1,1,1,3,47*48

2020-01-10T02:18:59.143Z,1578622739.143 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-10T02:18:59.144Z,1578622739.144 [Micromodem](INFO): Sent 39 bytes from file Logs/20200110T021238/Courier0004.lzma.parts
2020-01-10T02:18:59.144Z,1578622739.144 [Micromodem](INFO): Packets left to send: 0
2020-01-10T02:18:59.558Z,1578622739.558 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110021858.979420,06,764,28,0107,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,22.2,7.00,15,-4.00,-01,-0.05,26,10000,4000*42

2020-01-10T02:19:00.621Z,1578622740.621 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 6ADE175E000002005D000004003F0200000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6
2020-01-10T02:19:00.622Z,1578622740.622 [Micromodem](INFO): Outgoing frame #2, 64 bytes: AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE367846
2020-01-10T02:19:00.622Z,1578622740.622 [Micromodem](INFO): Outgoing frame #3, 64 bytes: B89CB1B28396224553BB15B410DCB5DDCD7A4D30A8EBDBD38F0B1D208FA0E6554B3739017D668FDD08C144A15E6A9A1C4036255C27BE951FA1EFEFF3E4EF7B9D
2020-01-10T02:19:00.623Z,1578622740.623 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-10T02:19:01.484Z,1578622741.484 [DataOverHttps](INFO): Sending 410 bytes from file Logs/20200110T021610/Courier0000.lzma
2020-01-10T02:19:01.623Z,1578622741.623 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-10T02:19:02.027Z,1578622742.027 [Micromodem](INFO): Nmea in: $CADRQ,021901,1,12,0,64,1*7F

2020-01-10T02:19:02.028Z,1578622742.028 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,6ADE175E000002005D000004003F0200000000000000151D80030C5669BF3148E2FA22188B6A388EEB0BC45C9E58EC1F659FAAC29EC6B6829A7CC0606A7B8EA6*39

2020-01-10T02:19:02.431Z,1578622742.431 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T02:19:02.602Z,1578622742.602 [DataOverHttps](INFO): Moved sent file to Logs/20200110T021610/Courier0000.lzma.bak
2020-01-10T02:19:02.602Z,1578622742.602 [DataOverHttps](INFO): SBD MOMSN=12188828
2020-01-10T02:19:02.839Z,1578622742.839 [Micromodem](INFO): Nmea in: $CADRQ,021903,1,12,0,64,2*7E

2020-01-10T02:19:02.839Z,1578622742.839 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,AA422B2385EC73C0FABC6B0C6D70893EB78F88D46452A4A3C7E9D8907F12032C01CF700820374B1C8E5D82357819DDFA5B46F5DFA93FEDD106818297EE367846*30

2020-01-10T02:19:03.243Z,1578622743.243 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T02:19:03.643Z,1578622743.643 [Micromodem](INFO): Nmea in: $CADRQ,021903,1,12,0,64,3*7F

2020-01-10T02:19:03.643Z,1578622743.643 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,B89CB1B28396224553BB15B410DCB5DDCD7A4D30A8EBDBD38F0B1D208FA0E6554B3739017D668FDD08C144A15E6A9A1C4036255C27BE951FA1EFEFF3E4EF7B9D*47

2020-01-10T02:19:04.059Z,1578622744.059 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T02:19:04.450Z,1578622744.450 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-10T02:19:08.086Z,1578622748.086 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-10T02:19:08.500Z,1578622748.500 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,021904.746316,3,0,200,4000,10000,1,1,12,1,3,3,3,192*75

2020-01-10T02:19:09.316Z,1578622749.316 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-10T02:19:09.711Z,1578622749.711 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-10T02:19:10.107Z,1578622750.107 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-10T02:19:10.107Z,1578622750.107 [Micromodem](INFO): Sent 184 bytes from file Logs/20200110T021610/Courier0000.lzma.parts
2020-01-10T02:19:10.108Z,1578622750.108 [Micromodem](INFO): Packets left to send: 2
2020-01-10T02:19:10.514Z,1578622750.514 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110021909.299352,06,627,27,0112,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,23.4,6.00,13,-3.00,-01,-0.03,25,10000,4000*4F

2020-01-10T02:19:11.401Z,1578622751.401 [Startup:StartupSatComms:B] Stopped
2020-01-10T02:19:11.401Z,1578622751.401 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-01-10T02:19:11.401Z,1578622751.401 [Startup:StartupSatComms] Stopped
2020-01-10T02:19:11.401Z,1578622751.401 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-01-10T02:19:11.402Z,1578622751.402 [Startup](INFO): Completed Startup
2020-01-10T02:19:11.402Z,1578622751.402 [MissionManager](INFO): Startup is completed.
2020-01-10T02:19:11.403Z,1578622751.403 [MissionManager](INFO): Uninitializing Mission Startup
2020-01-10T02:19:11.403Z,1578622751.403 [Startup] Stopped
2020-01-10T02:19:11.403Z,1578622751.403 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-01-10T02:19:11.403Z,1578622751.403 [Startup:A.GoToSurface] Stopped
2020-01-10T02:19:11.403Z,1578622751.403 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-10T02:19:11.538Z,1578622751.538 [MissionManager](IMPORTANT): Started mission Default
2020-01-10T02:19:11.538Z,1578622751.538 [Default] Running Loop=1
2020-01-10T02:19:11.538Z,1578622751.538 [Default](DEBUG): Aggregate::initialize Default
2020-01-10T02:19:11.538Z,1578622751.538 [Default:B.GoToSurface] Running Loop=1
2020-01-10T02:19:11.538Z,1578622751.538 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-01-10T02:19:11.539Z,1578622751.539 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-01-10T02:19:11.539Z,1578622751.539 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-01-10T02:19:11.539Z,1578622751.539 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-01-10T02:19:11.540Z,1578622751.540 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-01-10T02:19:11.540Z,1578622751.540 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-01-10T02:19:11.540Z,1578622751.540 [Default:A.Wait] Running Loop=1
2020-01-10T02:19:11.540Z,1578622751.540 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-01-10T02:19:20.366Z,1578622760.366 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-01-10T02:19:20.366Z,1578622760.366 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-10T02:19:20.384Z,1578622760.384 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-10T02:19:20.774Z,1578622760.774 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-10T02:19:20.774Z,1578622760.774 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-01-10T02:19:21.840Z,1578622761.840 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20200109T191734/Express0026.lzma
2020-01-10T02:19:23.845Z,1578622763.845 [DataOverHttps](INFO): Moved sent file to Logs/20200109T191734/Express0026.lzma.bak
2020-01-10T02:19:23.846Z,1578622763.846 [DataOverHttps](INFO): SBD MOMSN=12188835
2020-01-10T02:19:24.810Z,1578622764.810 [Default:A.Wait](INFO): Done Waiting.
2020-01-10T02:19:24.810Z,1578622764.810 [Default:A.Wait] Stopped
2020-01-10T02:19:24.810Z,1578622764.810 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-01-10T02:19:25.220Z,1578622765.220 [Default:CheckIn] Running Loop=1
2020-01-10T02:19:25.220Z,1578622765.220 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-10T02:19:25.220Z,1578622765.220 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-10T02:19:25.599Z,1578622765.599 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-01-10T02:22:01.135Z,1578622921.135 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-01-10T02:22:07.225Z,1578622927.225 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-10T02:22:07.225Z,1578622927.225 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-10T02:22:21.406Z,1578622941.406 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-01-10T02:22:21.406Z,1578622941.406 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-01-10T02:22:21.416Z,1578622941.416 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-01-10T02:22:21.777Z,1578622941.777 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-01-10T02:22:21.777Z,1578622941.777 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-01-10T02:22:43.596Z,1578622963.596 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-10T02:22:43.596Z,1578622963.596 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-10T02:23:42.601Z,1578623022.601 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-01-10T02:23:42.604Z,1578623022.604 [BPC1](INFO): Received data from all battery sticks.
2020-01-10T02:24:25.407Z,1578623065.407 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-01-10T02:19:25.2Z
2020-01-10T02:24:25.407Z,1578623065.407 [Default:CheckIn:Read_GPS] Stopped
2020-01-10T02:24:25.408Z,1578623065.408 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-10T02:24:25.838Z,1578623065.838 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-01-10T02:24:26.909Z,1578623066.909 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 6ADE175E040001005D00000400600100000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A
2020-01-10T02:24:26.909Z,1578623066.909 [Micromodem](INFO): Outgoing frame #2, 64 bytes: EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C42BBF445E54CD5F36943A3BDD9E89309E9017DB79DD009A333A47CF42F3BE4E4AD4301D597F
2020-01-10T02:24:26.910Z,1578623066.910 [Micromodem](INFO): Outgoing frame #3, 64 bytes: FFA3EC7FA24353C087A3A1B3BD161F92F69707865251F3B93C9772C25CD67B52231335EF84B8E92D5FFA309E6A3CA3D540BEA5F759655942B12D649F7BBA4622
2020-01-10T02:24:26.910Z,1578623066.910 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-10T02:24:27.123Z,1578623067.123 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-10T02:24:27.467Z,1578623067.467 [Micromodem](INFO): Nmea in: $CADRQ,022427,1,12,0,64,1*75

2020-01-10T02:24:27.467Z,1578623067.467 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,6ADE175E040001005D00000400600100000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A*4A

2020-01-10T02:24:27.875Z,1578623067.875 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T02:24:28.285Z,1578623068.285 [Micromodem](INFO): Nmea in: $CADRQ,022428,1,12,0,64,2*79

2020-01-10T02:24:28.286Z,1578623068.286 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C42BBF445E54CD5F36943A3BDD9E89309E9017DB79DD009A333A47CF42F3BE4E4AD4301D597F*3D

2020-01-10T02:24:28.687Z,1578623068.687 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T02:24:29.091Z,1578623069.091 [Micromodem](INFO): Nmea in: $CADRQ,022429,1,12,0,64,3*79

2020-01-10T02:24:29.091Z,1578623069.091 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,FFA3EC7FA24353C087A3A1B3BD161F92F69707865251F3B93C9772C25CD67B52231335EF84B8E92D5FFA309E6A3CA3D540BEA5F759655942B12D649F7BBA4622*32

2020-01-10T02:24:29.487Z,1578623069.487 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T02:24:29.919Z,1578623069.919 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-10T02:24:33.284Z,1578623073.284 [DataOverHttps](INFO): Sending 198 bytes from file Logs/20200110T021610/Courier0004.lzma
2020-01-10T02:24:33.527Z,1578623073.527 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-10T02:24:33.932Z,1578623073.932 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,022430.190418,3,0,200,4000,10000,1,1,12,1,3,3,3,192*78

2020-01-10T02:24:35.147Z,1578623075.147 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-10T02:24:35.290Z,1578623075.290 [DataOverHttps](INFO): Moved sent file to Logs/20200110T021610/Courier0004.lzma.bak
2020-01-10T02:24:35.290Z,1578623075.290 [DataOverHttps](INFO): SBD MOMSN=12188842
2020-01-10T02:24:35.547Z,1578623075.547 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-10T02:24:35.957Z,1578623075.957 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-10T02:24:35.958Z,1578623075.958 [Micromodem](INFO): Sent 184 bytes from file Logs/20200110T021610/Courier0004.lzma.parts
2020-01-10T02:24:35.958Z,1578623075.958 [Micromodem](INFO): Packets left to send: 1
2020-01-10T02:24:36.358Z,1578623076.358 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110022434.747680,06,702,28,0106,0150,59,02,02,00,04,1,012,001,0,4,1,0,150,12.0,8.00,21,-5.00,-01,-0.02,36,10000,4000*44

2020-01-10T02:24:37.436Z,1578623077.436 [Default:CheckIn:Read_Iridium] Stopped
2020-01-10T02:24:37.436Z,1578623077.436 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-10T02:24:37.436Z,1578623077.436 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-10T02:25:20.285Z,1578623120.285 [DataOverHttps](INFO): Sending 347 bytes from file Logs/20200109T191734/Express0029.lzma
2020-01-10T02:25:22.290Z,1578623122.290 [DataOverHttps](INFO): Moved sent file to Logs/20200109T191734/Express0029.lzma.bak
2020-01-10T02:25:22.291Z,1578623122.291 [DataOverHttps](INFO): SBD MOMSN=12188844
2020-01-10T02:25:22.361Z,1578623122.361 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,022521.00,A,3648.16579,N,12147.29535,W,1.497,0.00,100120,,,A*7F
2020-01-10T02:25:22.364Z,1578623122.364 [NAL9602](INFO): GPS fix at 20200110T022521: (36.802763, -121.788256)
2020-01-10T02:25:25.215Z,1578623125.215 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-10T02:25:25.216Z,1578623125.216 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010919381551,35.0, -00,1448.9,  0
2020-01-10T02:25:54.683Z,1578623154.683 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-10T02:26:26.229Z,1578623186.229 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-10T02:26:26.230Z,1578623186.230 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,  BI,-32768,-32768,-32768,-32768,V
2020-01-10T02:29:02.568Z,1578623342.568 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-10T02:29:02.568Z,1578623342.568 [RDI_Pathfinder](ERROR): Failed to parse:
:TS9415251,35.0, -0.1,   0.0,1448.9,  0
2020-01-10T02:29:07.844Z,1578623347.844 [RDI_Pathfinder](ERROR): Failed to parse:
:B,-32768,-32768,V
2020-01-10T02:29:37.724Z,1578623377.724 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-01-10T02:29:37.725Z,1578623377.725 [Default:CheckIn:C.Wait] Stopped
2020-01-10T02:29:37.725Z,1578623377.725 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-10T02:29:37.725Z,1578623377.725 [Default:CheckIn:D] Running Loop=1
2020-01-10T02:29:38.136Z,1578623378.136 [Default:CheckIn:D] Stopped
2020-01-10T02:29:38.136Z,1578623378.136 [Default:CheckIn:E] Running Loop=1
2020-01-10T02:29:38.544Z,1578623378.544 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.443295 min 
2020-01-10T02:29:38.545Z,1578623378.545 [Default:CheckIn:E] Stopped
2020-01-10T02:29:38.545Z,1578623378.545 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-01-10T02:29:38.545Z,1578623378.545 [Default:CheckIn] Stopped
2020-01-10T02:29:38.545Z,1578623378.545 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-10T02:29:38.545Z,1578623378.545 [Default:CheckIn](INFO): Running loop #2
2020-01-10T02:29:38.545Z,1578623378.545 [Default:CheckIn] Running Loop=2
2020-01-10T02:29:38.546Z,1578623378.546 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-01-10T02:29:38.546Z,1578623378.546 [Default:CheckIn:Read_GPS] Running Loop=1
2020-01-10T02:29:40.526Z,1578623380.526 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,022940.00,A,3648.16139,N,12147.29258,W,0.428,0.00,100120,,,A*7D
2020-01-10T02:29:40.528Z,1578623380.528 [NAL9602](INFO): GPS fix at 20200110T022940: (36.802690, -121.788210)
2020-01-10T02:29:40.552Z,1578623380.552 [Default:CheckIn:Read_GPS] Stopped
2020-01-10T02:29:40.552Z,1578623380.552 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-01-10T02:29:42.406Z,1578623382.406 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 6ADE175E070001005D00000400360300000000000000151D80030C5669BF3148E2FA22188B6A3173E6960F887FF8A99E669AC91D474B0DB47F940EC581E0D567
2020-01-10T02:29:42.407Z,1578623382.407 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 266CC0E6F1A517638CB0C0B8BE372A0B00F6D61E05F93094AA77DEFC753FE61F094712E1114D8E0AD7B6106F0F7B016211E5CA62E332602D1D41ACBF7CDB6A31
2020-01-10T02:29:42.407Z,1578623382.407 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 6612EED8A8416BB29191C1CCB1D608CF1AC7D5382E0295D324E3FB9C1C2302F2C3889792149C6D6DF406A218E47F75290FD65E23824F901508F051383D0A8ED3
2020-01-10T02:29:42.408Z,1578623382.408 [Micromodem](INFO): Nmea out: $CCCYC,0,1,12,1,0,3*69

2020-01-10T02:29:42.590Z,1578623382.590 [Micromodem](INFO): Nmea in: $CACYC,0,1,12,1,0,3*6B

2020-01-10T02:29:42.970Z,1578623382.970 [Micromodem](INFO): Nmea in: $CADRQ,022943,1,12,0,64,1*7A

2020-01-10T02:29:42.971Z,1578623382.971 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,6ADE175E070001005D00000400360300000000000000151D80030C5669BF3148E2FA22188B6A3173E6960F887FF8A99E669AC91D474B0DB47F940EC581E0D567*31

2020-01-10T02:29:43.359Z,1578623383.359 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T02:29:43.772Z,1578623383.772 [Micromodem](INFO): Nmea in: $CADRQ,022944,1,12,0,64,2*7E

2020-01-10T02:29:43.773Z,1578623383.773 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,266CC0E6F1A517638CB0C0B8BE372A0B00F6D61E05F93094AA77DEFC753FE61F094712E1114D8E0AD7B6106F0F7B016211E5CA62E332602D1D41ACBF7CDB6A31*3C

2020-01-10T02:29:44.187Z,1578623384.187 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T02:29:44.575Z,1578623384.575 [Micromodem](INFO): Nmea in: $CADRQ,022944,1,12,0,64,3*7F

2020-01-10T02:29:44.575Z,1578623384.575 [Micromodem](INFO): Nmea out: $CCTXD,1,12,1,6612EED8A8416BB29191C1CCB1D608CF1AC7D5382E0295D324E3FB9C1C2302F2C3889792149C6D6DF406A218E47F75290FD65E23824F901508F051383D0A8ED3*39

2020-01-10T02:29:44.988Z,1578623384.988 [Micromodem](INFO): Nmea in: $CATXD,1,12,1,64*4B

2020-01-10T02:29:45.390Z,1578623385.390 [Micromodem](INFO): Nmea in: $CATXP,192*48

2020-01-10T02:29:45.849Z,1578623385.849 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-10T02:29:45.849Z,1578623385.849 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010919423, -0.1,   0.0,1448.9,  0
2020-01-10T02:29:49.019Z,1578623389.019 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2020-01-10T02:29:49.173Z,1578623389.173 [DataOverHttps](INFO): Sending 286 bytes from file Logs/20200110T021610/Courier0007.lzma
2020-01-10T02:29:49.424Z,1578623389.424 [Micromodem](INFO): Nmea in: $CAXST,6,20200110,022945.681023,3,0,200,4000,10000,1,1,12,1,3,3,3,192*7C

2020-01-10T02:29:50.632Z,1578623390.632 [Micromodem](INFO): Nmea in: $CAACK,12,1,1,1*79

2020-01-10T02:29:51.035Z,1578623391.035 [Micromodem](INFO): Nmea in: $CAACK,12,1,2,1*7A

2020-01-10T02:29:51.178Z,1578623391.178 [DataOverHttps](INFO): Moved sent file to Logs/20200110T021610/Courier0007.lzma.bak
2020-01-10T02:29:51.178Z,1578623391.178 [DataOverHttps](INFO): SBD MOMSN=12188851
2020-01-10T02:29:51.439Z,1578623391.439 [Micromodem](INFO): Nmea in: $CAACK,12,1,3,1*7B

2020-01-10T02:29:51.440Z,1578623391.440 [Micromodem](INFO): Sent 184 bytes from file Logs/20200110T021610/Courier0007.lzma.parts
2020-01-10T02:29:51.440Z,1578623391.440 [Micromodem](INFO): Packets left to send: 1
2020-01-10T02:29:51.850Z,1578623391.850 [Micromodem](INFO): Nmea in: $CACST,6,0,20200110022950.232444,06,693,28,0105,0150,59,01,01,00,03,1,012,001,0,4,1,0,150,22.9,6.00,14,-3.00,-01,-0.04,26,10000,4000*48

2020-01-10T02:29:53.511Z,1578623393.511 [Default:CheckIn:Read_Iridium] Stopped
2020-01-10T02:29:53.511Z,1578623393.511 [Default:CheckIn:C.Wait] Running Loop=1
2020-01-10T02:29:53.511Z,1578623393.511 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-01-10T02:29:55.213Z,1578623395.213 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-10T02:29:55.213Z,1578623395.213 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010919424551,35.0, -0.1,   .9,  0
2020-01-10T02:30:10.947Z,1578623410.947 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-01-10T02:30:11.022Z,1578623411.022 [NAL9602](FAULT): received: 
+CSQ:1

OK
2020-01-10T02:30:11.022Z,1578623411.022 [NAL9602] Data Fault, FailCount= 1
2020-01-10T02:30:11.022Z,1578623411.022 [NAL9602](ERROR): Data Fault
2020-01-10T02:30:11.064Z,1578623411.064 [CBIT](ERROR): Data Fault in component: NAL9602
2020-01-10T02:30:11.355Z,1578623411.355 [NAL9602](INFO): Powering down
2020-01-10T02:30:12.246Z,1578623412.246 [CBIT](INFO): Clearing failed state for component NAL9602
2020-01-10T02:30:12.246Z,1578623412.246 [NAL9602] No Fault, FailCount= 1
2020-01-10T02:30:36.500Z,1578623436.500 [DataOverHttps](INFO): Sending 198 bytes from file Logs/20200109T191734/Express0032.lzma
2020-01-10T02:30:38.507Z,1578623438.507 [DataOverHttps](INFO): Moved sent file to Logs/20200109T191734/Express0032.lzma.bak
2020-01-10T02:30:38.507Z,1578623438.507 [DataOverHttps](INFO): SBD MOMSN=12188855
2020-01-10T02:30:41.647Z,1578623441.647 [NAL9602](INFO): Powering up NAL9602
2020-01-10T02:30:52.559Z,1578623452.559 [NAL9602](INFO): NAL9602 initialized
2020-01-10T02:31:21.295Z,1578623481.295 [CBIT](INFO): Clearing failed state for component DropWeight
2020-01-10T02:31:21.295Z,1578623481.295 [DropWeight] No Fault, FailCount= 1
2020-01-10T02:31:23.678Z,1578623483.678 [NAL9602](INFO): Not Powering down - fast GPS
2020-01-10T02:31:57.240Z,1578623517.240 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-01-10T02:31:57.240Z,1578623517.240 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-

2020-01-10T02:31:59.248Z,1578623519.248 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-01-10T02:31:59.249Z,1578623519.249 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,20010919444951,35,   0.0,1448.9,  0
2020-01-10T02:32:12.580Z,1578623532.580 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-10T02:32:12.580Z,1578623532.580 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-10T02:32:33.200Z,1578623553.200 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-10T02:32:33.200Z,1578623553.200 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-10T02:32:39.237Z,1578623559.237 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-01-10T02:32:39.237Z,1578623559.237 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2020-01-10T02:32:47.902Z,1578623567.902 [CommandLine](IMPORTANT): got command restart application
2020-01-10T02:32:48.909Z,1578623568.909 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T02:32:48.909Z,1578623568.909 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:32:48.922Z,1578623568.922 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-01-10T02:32:48.922Z,1578623568.922 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:32:48.923Z,1578623568.923 [CommandLine](INFO): Join timeout helper Thread ID is 9319
2020-01-10T02:32:48.923Z,1578623568.923 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-01-10T02:32:48.924Z,1578623568.924 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:32:48.953Z,1578623568.953 [NavChartDb](INFO): Join timeout helper Thread ID is 9320
2020-01-10T02:32:49.229Z,1578623569.229 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T02:32:49.229Z,1578623569.229 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:32:49.249Z,1578623569.249 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-01-10T02:32:49.249Z,1578623569.249 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:32:49.249Z,1578623569.249 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 9321
2020-01-10T02:32:49.413Z,1578623569.413 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T02:32:49.413Z,1578623569.413 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-01-10T02:32:49.414Z,1578623569.414 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:32:49.433Z,1578623569.433 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2020-01-10T02:32:49.433Z,1578623569.433 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:32:49.433Z,1578623569.433 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 9322
2020-01-10T02:32:49.573Z,1578623569.573 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T02:32:49.573Z,1578623569.573 [CTD_NeilBrown](INFO): Powering down
2020-01-10T02:32:49.585Z,1578623569.585 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:32:49.597Z,1578623569.597 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-01-10T02:32:49.597Z,1578623569.597 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:32:49.597Z,1578623569.597 [Radio_Surface](INFO): Join timeout helper Thread ID is 9323
2020-01-10T02:32:49.813Z,1578623569.813 [Radio_Surface](INFO): Powering down
2020-01-10T02:32:49.814Z,1578623569.814 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T02:32:49.814Z,1578623569.814 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:32:49.822Z,1578623569.822 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-01-10T02:32:49.822Z,1578623569.822 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:32:49.822Z,1578623569.822 [DataOverHttps](INFO): Join timeout helper Thread ID is 9324
2020-01-10T02:32:50.781Z,1578623570.781 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T02:32:50.781Z,1578623570.781 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:32:50.786Z,1578623570.786 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-01-10T02:32:50.786Z,1578623570.786 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:32:50.786Z,1578623570.786 [logger](INFO): Join timeout helper Thread ID is 9325
2020-01-10T02:32:50.801Z,1578623570.801 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T02:32:50.801Z,1578623570.801 [logger ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:32:50.806Z,1578623570.806 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-01-10T02:32:50.806Z,1578623570.806 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:32:50.806Z,1578623570.806 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-01-10T02:32:50.806Z,1578623570.806 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:32:50.806Z,1578623570.806 [controlThread](INFO): Join timeout helper Thread ID is 9326
2020-01-10T02:32:50.913Z,1578623570.913 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-01-10T02:32:50.913Z,1578623570.913 [controlThread](DEBUG): Uninitializing ControlThread
2020-01-10T02:32:50.914Z,1578623570.914 [AHRS_M2](INFO): Powering down
2020-01-10T02:32:50.986Z,1578623570.986 [DDM](INFO): Powering down
2020-01-10T02:32:51.098Z,1578623571.098 [DUSBL_Hydroid](INFO): Powering down
2020-01-10T02:32:51.189Z,1578623571.189 [Micromodem](INFO): Powering down
2020-01-10T02:32:51.285Z,1578623571.285 [NAL9602](INFO): Powering down
2020-01-10T02:32:51.357Z,1578623571.357 [RDI_Pathfinder](INFO): Powering down
2020-01-10T02:32:51.358Z,1578623571.358 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-01-10T02:32:51.359Z,1578623571.359 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-01-10T02:32:51.360Z,1578623571.360 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-01-10T02:32:51.360Z,1578623571.360 [MissionManager](INFO): Uninitializing Mission Default
2020-01-10T02:32:51.360Z,1578623571.360 [Default] Stopped
2020-01-10T02:32:51.360Z,1578623571.360 [Default](DEBUG): Aggregate::uninitialize Default
2020-01-10T02:32:51.360Z,1578623571.360 [Default:B.GoToSurface] Stopped
2020-01-10T02:32:51.361Z,1578623571.361 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-01-10T02:32:51.361Z,1578623571.361 [Default:CheckIn] Stopped
2020-01-10T02:32:51.361Z,1578623571.361 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-01-10T02:32:51.361Z,1578623571.361 [Default:CheckIn:C.Wait] Stopped
2020-01-10T02:32:51.361Z,1578623571.361 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-01-10T02:32:51.363Z,1578623571.363 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-01-10T02:32:51.364Z,1578623571.364 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-01-10T02:32:51.364Z,1578623571.364 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-01-10T02:32:51.364Z,1578623571.364 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-01-10T02:32:51.364Z,1578623571.364 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-01-10T02:32:51.364Z,1578623571.364 [BuoyancyServo](INFO): Powering down
2020-01-10T02:32:51.377Z,1578623571.377 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-01-10T02:32:51.377Z,1578623571.377 [ElevatorServo](INFO): Powering down
2020-01-10T02:32:51.378Z,1578623571.378 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-01-10T02:32:51.378Z,1578623571.378 [MassServo](INFO): Powering down
2020-01-10T02:32:51.379Z,1578623571.379 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-01-10T02:32:51.379Z,1578623571.379 [RudderServo](INFO): Powering down
2020-01-10T02:32:51.379Z,1578623571.379 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-01-10T02:32:51.380Z,1578623571.380 [ThrusterServo](INFO): Powering down
2020-01-10T02:32:51.380Z,1578623571.380 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-01-10T02:32:51.381Z,1578623571.381 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-01-10T02:32:51.381Z,1578623571.381 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-01-10T02:32:51.381Z,1578623571.381 [CBIT](DEBUG): Powering off loads.
2020-01-10T02:32:51.393Z,1578623571.393 [CBIT](DEBUG): Disabling WDT.
2020-01-10T02:32:51.405Z,1578623571.405 [CBIT](DEBUG): Opening all GF detection circuits.
2020-01-10T02:32:51.406Z,1578623571.406 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:32:51.464Z,1578623571.464 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:32:51.475Z,1578623571.475 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:32:51.523Z,1578623571.523 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:32:51.525Z,1578623571.525 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:32:51.585Z,1578623571.585 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-01-10T02:32:51.660Z,1578623571.660 [logger ThreadHandler](INFO): Thread cancelled.