2019-03-14T20:40:42.182Z,1552596042.182 [Supervisor](DEBUG): Initializing supervisor. 2019-03-14T20:40:42.186Z,1552596042.186 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-03-14T20:40:42.186Z,1552596042.186 [SyncHandler](INFO): Protected caller Thread ID is 936 2019-03-14T20:40:42.187Z,1552596042.187 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-03-14T20:40:42.188Z,1552596042.188 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-03-14T20:40:42.188Z,1552596042.188 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 937 2019-03-14T20:40:42.191Z,1552596042.191 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-03-14T20:40:42.204Z,1552596042.204 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-03-14T20:40:42.205Z,1552596042.205 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-03-14T20:40:42.205Z,1552596042.205 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 938 2019-03-14T20:40:42.206Z,1552596042.206 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-03-14T20:40:42.207Z,1552596042.207 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-03-14T20:40:42.207Z,1552596042.207 [logger ThreadHandler](INFO): Protected caller Thread ID is 939 2019-03-14T20:40:42.209Z,1552596042.209 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-03-14T20:40:42.210Z,1552596042.210 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-03-14T20:40:42.211Z,1552596042.211 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-03-14T20:40:42.644Z,1552596042.644 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-03-14T20:40:42.644Z,1552596042.644 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-03-14T20:40:42.743Z,1552596042.743 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-03-14T20:40:42.743Z,1552596042.743 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-03-14T20:40:43.079Z,1552596043.079 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-03-14T20:40:43.080Z,1552596043.080 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-03-14T20:40:43.225Z,1552596043.225 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-03-14T20:40:43.225Z,1552596043.225 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-03-14T20:40:43.422Z,1552596043.422 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-03-14T20:40:43.422Z,1552596043.422 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-03-14T20:40:43.892Z,1552596043.892 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-03-14T20:40:43.893Z,1552596043.893 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-03-14T20:40:44.108Z,1552596044.108 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-03-14T20:40:44.109Z,1552596044.109 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-03-14T20:40:44.257Z,1552596044.257 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-03-14T20:40:44.257Z,1552596044.257 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-03-14T20:40:44.460Z,1552596044.460 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-03-14T20:40:44.460Z,1552596044.460 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-03-14T20:40:44.556Z,1552596044.556 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-03-14T20:40:44.557Z,1552596044.557 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-03-14T20:40:44.875Z,1552596044.875 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-03-14T20:40:44.875Z,1552596044.875 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-03-14T20:40:44.956Z,1552596044.956 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-03-14T20:40:45.060Z,1552596045.060 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-03-14T20:40:45.060Z,1552596045.060 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-03-14T20:40:45.568Z,1552596045.568 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-03-14T20:40:45.569Z,1552596045.569 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-03-14T20:40:45.971Z,1552596045.971 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-03-14T20:40:45.973Z,1552596045.973 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/ 2019-03-14T20:40:45.974Z,1552596045.974 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg 2019-03-14T20:40:46.188Z,1552596046.188 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg 2019-03-14T20:40:46.299Z,1552596046.299 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg 2019-03-14T20:40:46.522Z,1552596046.522 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg 2019-03-14T20:40:47.110Z,1552596047.110 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-03-14T20:40:47.111Z,1552596047.111 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg 2019-03-14T20:40:47.195Z,1552596047.195 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg 2019-03-14T20:40:47.290Z,1552596047.290 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg 2019-03-14T20:40:47.389Z,1552596047.389 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg 2019-03-14T20:40:47.473Z,1552596047.473 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg 2019-03-14T20:40:47.583Z,1552596047.583 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg 2019-03-14T20:40:47.761Z,1552596047.761 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg 2019-03-14T20:40:47.897Z,1552596047.897 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/ 2019-03-14T20:40:47.897Z,1552596047.897 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2019-03-14T20:40:47.902Z,1552596047.902 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-03-14T20:40:48.212Z,1552596048.212 [DataOverHttps] Loaded 2019-03-14T20:40:48.213Z,1552596048.213 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-03-14T20:40:48.214Z,1552596048.214 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4074D4E0 2019-03-14T20:40:48.214Z,1552596048.214 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1018 2019-03-14T20:40:48.254Z,1552596048.254 [Depth_Keller] Loaded 2019-03-14T20:40:48.254Z,1552596048.254 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-03-14T20:40:48.259Z,1552596048.259 [DropWeight] Loaded 2019-03-14T20:40:48.259Z,1552596048.259 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-03-14T20:40:48.307Z,1552596048.307 [DUSBL_Hydroid] Loaded 2019-03-14T20:40:48.307Z,1552596048.307 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread. 2019-03-14T20:40:48.348Z,1552596048.348 [Micromodem] Loaded 2019-03-14T20:40:48.348Z,1552596048.348 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread. 2019-03-14T20:40:48.443Z,1552596048.443 [NAL9602] Loaded 2019-03-14T20:40:48.443Z,1552596048.443 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-03-14T20:40:48.459Z,1552596048.459 [Onboard] Loaded 2019-03-14T20:40:48.459Z,1552596048.459 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-03-14T20:40:48.466Z,1552596048.466 [Radio_Surface] Loaded 2019-03-14T20:40:48.466Z,1552596048.466 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-03-14T20:40:48.467Z,1552596048.467 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4077D4E0 2019-03-14T20:40:48.467Z,1552596048.467 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1019 2019-03-14T20:40:48.527Z,1552596048.527 [RDI_Pathfinder] Loaded 2019-03-14T20:40:48.527Z,1552596048.527 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2019-03-14T20:40:48.549Z,1552596048.549 [RDI_PathfinderUp] Loaded 2019-03-14T20:40:48.549Z,1552596048.549 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread. 2019-03-14T20:40:50.295Z,1552596050.295 [BPC1] Loaded 2019-03-14T20:40:50.295Z,1552596050.295 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-03-14T20:40:50.296Z,1552596050.296 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-03-14T20:40:50.296Z,1552596050.296 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-03-14T20:40:50.309Z,1552596050.309 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-03-14T20:40:50.309Z,1552596050.309 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-03-14T20:40:50.415Z,1552596050.415 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-03-14T20:40:50.415Z,1552596050.415 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-03-14T20:40:50.474Z,1552596050.474 [DeadReckonUsingSpeedCalculator] Loaded 2019-03-14T20:40:50.474Z,1552596050.474 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2019-03-14T20:40:50.534Z,1552596050.534 [DeadReckonWithRespectToSeafloor] Loaded 2019-03-14T20:40:50.534Z,1552596050.534 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2019-03-14T20:40:50.555Z,1552596050.555 [NavChart] Loaded 2019-03-14T20:40:50.555Z,1552596050.555 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-03-14T20:40:50.559Z,1552596050.559 [UniversalFixResidualReporter] Loaded 2019-03-14T20:40:50.560Z,1552596050.560 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-03-14T20:40:50.560Z,1552596050.560 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-03-14T20:40:50.561Z,1552596050.561 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-03-14T20:40:50.662Z,1552596050.662 [BuoyancyServo] Loaded 2019-03-14T20:40:50.662Z,1552596050.662 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-03-14T20:40:50.677Z,1552596050.677 [ElevatorServo] Loaded 2019-03-14T20:40:50.678Z,1552596050.678 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-03-14T20:40:50.692Z,1552596050.692 [MassServo] Loaded 2019-03-14T20:40:50.693Z,1552596050.693 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-03-14T20:40:50.708Z,1552596050.708 [RudderServo] Loaded 2019-03-14T20:40:50.708Z,1552596050.708 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-03-14T20:40:50.723Z,1552596050.723 [ThrusterServo] Loaded 2019-03-14T20:40:50.723Z,1552596050.723 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-03-14T20:40:50.724Z,1552596050.724 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-03-14T20:40:50.724Z,1552596050.724 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-03-14T20:40:50.968Z,1552596050.968 [CTD_NeilBrown] Loaded 2019-03-14T20:40:50.968Z,1552596050.968 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2019-03-14T20:40:50.969Z,1552596050.969 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408C24E0 2019-03-14T20:40:50.970Z,1552596050.970 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1020 2019-03-14T20:40:51.014Z,1552596051.014 [WetLabsSeaOWL_UV_A] Loaded 2019-03-14T20:40:51.014Z,1552596051.014 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2019-03-14T20:40:51.015Z,1552596051.015 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408F24E0 2019-03-14T20:40:51.016Z,1552596051.016 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1021 2019-03-14T20:40:51.016Z,1552596051.016 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-03-14T20:40:51.017Z,1552596051.017 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-03-14T20:40:51.248Z,1552596051.248 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-03-14T20:40:51.248Z,1552596051.248 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-03-14T20:40:51.288Z,1552596051.288 [DepthRateCalculator] Loaded 2019-03-14T20:40:51.288Z,1552596051.288 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-03-14T20:40:51.294Z,1552596051.294 [PitchRateCalculator] Loaded 2019-03-14T20:40:51.294Z,1552596051.294 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-03-14T20:40:51.307Z,1552596051.307 [SpeedCalculator] Loaded 2019-03-14T20:40:51.307Z,1552596051.307 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-03-14T20:40:51.328Z,1552596051.328 [TempGradientCalculator] Loaded 2019-03-14T20:40:51.328Z,1552596051.328 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-03-14T20:40:51.334Z,1552596051.334 [YawRateCalculator] Loaded 2019-03-14T20:40:51.334Z,1552596051.334 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-03-14T20:40:51.375Z,1552596051.375 [ElevatorOffsetCalculator] Loaded 2019-03-14T20:40:51.375Z,1552596051.375 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-03-14T20:40:51.375Z,1552596051.375 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-03-14T20:40:51.376Z,1552596051.376 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-03-14T20:40:51.618Z,1552596051.618 [SBIT](DEBUG): Construct Startup Built In Test. 2019-03-14T20:40:51.641Z,1552596051.641 [SBIT] Loaded 2019-03-14T20:40:51.641Z,1552596051.641 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-03-14T20:40:51.642Z,1552596051.642 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-03-14T20:40:51.654Z,1552596051.654 [IBIT] Loaded 2019-03-14T20:40:51.654Z,1552596051.654 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-03-14T20:40:51.657Z,1552596051.657 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-03-14T20:40:51.905Z,1552596051.905 [CBIT] Loaded 2019-03-14T20:40:51.906Z,1552596051.906 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-03-14T20:40:51.906Z,1552596051.906 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-03-14T20:40:51.907Z,1552596051.907 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-03-14T20:40:51.968Z,1552596051.968 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-03-14T20:40:51.969Z,1552596051.969 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-03-14T20:40:52.063Z,1552596052.063 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-03-14T20:40:52.064Z,1552596052.064 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-03-14T20:40:52.128Z,1552596052.128 [VerticalControl](DEBUG): Construct VerticalControl. 2019-03-14T20:40:52.212Z,1552596052.212 [VerticalControl] Loaded 2019-03-14T20:40:52.212Z,1552596052.212 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-03-14T20:40:52.213Z,1552596052.213 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-03-14T20:40:52.271Z,1552596052.271 [HorizontalControl] Loaded 2019-03-14T20:40:52.271Z,1552596052.271 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-03-14T20:40:52.272Z,1552596052.272 [SpeedControl](DEBUG): Construct SpeedControl. 2019-03-14T20:40:52.273Z,1552596052.273 [SpeedControl] Loaded 2019-03-14T20:40:52.274Z,1552596052.274 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-03-14T20:40:52.275Z,1552596052.275 [LoopControl](DEBUG): Construct LoopControl. 2019-03-14T20:40:52.275Z,1552596052.275 [LoopControl] Loaded 2019-03-14T20:40:52.275Z,1552596052.275 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-03-14T20:40:52.276Z,1552596052.276 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-03-14T20:40:52.276Z,1552596052.276 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-03-14T20:40:52.303Z,1552596052.303 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-03-14T20:40:52.306Z,1552596052.306 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-03-14T20:40:52.307Z,1552596052.307 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-03-14T20:40:52.314Z,1552596052.314 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-03-14T20:40:52.315Z,1552596052.315 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AB04E0 2019-03-14T20:40:52.315Z,1552596052.315 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1022 2019-03-14T20:40:52.320Z,1552596052.320 [Supervisor](INFO): Main Thread ID is 800 2019-03-14T20:40:52.320Z,1552596052.320 [Supervisor](DEBUG): Running supervisor. 2019-03-14T20:40:52.321Z,1552596052.321 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1023 2019-03-14T20:40:52.323Z,1552596052.323 [controlThread ThreadHandler](INFO): Handler Thread ID is 1024 2019-03-14T20:40:52.324Z,1552596052.324 [controlThread](DEBUG): Initializing ControlThread 2019-03-14T20:40:52.329Z,1552596052.329 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-14T20:40:52.330Z,1552596052.330 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-03-14T20:40:52.331Z,1552596052.331 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-03-14T20:40:52.332Z,1552596052.332 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-03-14T20:40:52.332Z,1552596052.332 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-03-14T20:40:52.332Z,1552596052.332 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-03-14T20:40:52.332Z,1552596052.332 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-03-14T20:40:52.333Z,1552596052.333 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-03-14T20:40:52.333Z,1552596052.333 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-03-14T20:40:52.334Z,1552596052.334 [SBIT](INFO): Initialize SBIT Component. 2019-03-14T20:40:52.335Z,1552596052.335 [SBIT](IMPORTANT): git: 2019-03-04-36-g743def3 2019-03-14T20:40:52.335Z,1552596052.335 [SBIT](INFO): git hash: 743def31acf12ef8e20e672f0fefb031590b97fa 2019-03-14T20:40:52.335Z,1552596052.335 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-03-14T20:40:52.335Z,1552596052.335 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2019-03-14T20:40:52.336Z,1552596052.336 [SBIT](INFO): Beginning SBIT in 28.000000 seconds. 2019-03-14T20:40:52.337Z,1552596052.337 [IBIT](INFO): Initialize IBIT Component. 2019-03-14T20:40:52.338Z,1552596052.338 [CBIT](DEBUG): Initialize CBIT Component. 2019-03-14T20:40:52.339Z,1552596052.339 [logger ThreadHandler](INFO): Handler Thread ID is 1025 2019-03-14T20:40:52.350Z,1552596052.350 [CBIT](DEBUG): Initialized mux pins. 2019-03-14T20:40:52.350Z,1552596052.350 [CBIT](DEBUG): Initializing the watchdog timer. 2019-03-14T20:40:52.359Z,1552596052.359 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1026 2019-03-14T20:40:52.360Z,1552596052.360 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-03-14T20:40:52.371Z,1552596052.371 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1027 2019-03-14T20:40:52.374Z,1552596052.374 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2019-03-14T20:40:52.374Z,1552596052.374 [CBIT](DEBUG): Initializing heartbeat. 2019-03-14T20:40:52.383Z,1552596052.383 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1028 2019-03-14T20:40:52.383Z,1552596052.383 [CTD_NeilBrown](INFO): Powering down 2019-03-14T20:40:52.411Z,1552596052.411 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1029 2019-03-14T20:40:52.412Z,1552596052.412 [WetLabsSeaOWL_UV_A](INFO): Powering down 2019-03-14T20:40:52.446Z,1552596052.446 [CBIT](DEBUG): Deactivating GF circuits. 2019-03-14T20:40:52.446Z,1552596052.446 [CBIT](DEBUG): Deactivating emergency mode. 2019-03-14T20:40:52.447Z,1552596052.447 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1030 2019-03-14T20:40:52.451Z,1552596052.451 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-03-14T20:40:52.451Z,1552596052.451 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-03-14T20:40:52.451Z,1552596052.451 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-03-14T20:40:52.451Z,1552596052.451 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-03-14T20:40:52.451Z,1552596052.451 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-03-14T20:40:52.451Z,1552596052.451 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-03-14T20:40:52.452Z,1552596052.452 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-03-14T20:40:52.452Z,1552596052.452 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-03-14T20:40:52.452Z,1552596052.452 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-03-14T20:40:52.452Z,1552596052.452 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-03-14T20:40:52.452Z,1552596052.452 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-03-14T20:40:52.453Z,1552596052.453 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-03-14T20:40:52.453Z,1552596052.453 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-03-14T20:40:52.453Z,1552596052.453 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-03-14T20:40:52.453Z,1552596052.453 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-03-14T20:40:52.453Z,1552596052.453 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-03-14T20:40:52.482Z,1552596052.482 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-03-14T20:40:52.484Z,1552596052.484 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-03-14T20:40:52.485Z,1552596052.485 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-03-14T20:40:52.485Z,1552596052.485 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-03-14T20:40:52.486Z,1552596052.486 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-03-14T20:40:52.495Z,1552596052.495 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-03-14T20:40:52.523Z,1552596052.523 [MissionManager](DEBUG): 2019-03-14T20:40:52.523Z,1552596052.523 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-03-14T20:40:52.594Z,1552596052.594 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-03-14T20:40:52.595Z,1552596052.595 [Default:A.Wait](DEBUG): Construct Wait. 2019-03-14T20:40:52.597Z,1552596052.597 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-03-14T20:40:52.616Z,1552596052.616 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-03-14T20:40:52.639Z,1552596052.639 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-03-14T20:40:52.644Z,1552596052.644 [Default:E.Execute](DEBUG): Construct Execute. 2019-03-14T20:40:52.663Z,1552596052.663 [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 2019-03-14T20:40:52.668Z,1552596052.668 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2019-03-14T20:40:52.682Z,1552596052.682 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar 2019-03-14T20:40:52.683Z,1552596052.683 [DUSBL_Hydroid](INFO): Powering up 2019-03-14T20:40:52.683Z,1552596052.683 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid. 2019-03-14T20:40:52.702Z,1552596052.702 [Micromodem](INFO): Start 2019-03-14T20:40:52.764Z,1552596052.764 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-03-14T20:40:52.774Z,1552596052.774 [Radio_Surface](INFO): Powering up 2019-03-14T20:40:52.777Z,1552596052.777 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-03-14T20:40:52.782Z,1552596052.782 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-03-14T20:40:52.783Z,1552596052.783 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-03-14T20:40:52.794Z,1552596052.794 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-03-14T20:40:52.795Z,1552596052.795 [MassServo](DEBUG): Initializing EZServoServo. 2019-03-14T20:40:52.802Z,1552596052.802 [MassServo](DEBUG): Initializing MassServo. 2019-03-14T20:40:52.803Z,1552596052.803 [RudderServo](DEBUG): Initializing EZServoServo. 2019-03-14T20:40:52.814Z,1552596052.814 [RudderServo](DEBUG): Initializing RudderServo. 2019-03-14T20:40:52.823Z,1552596052.823 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-03-14T20:40:52.830Z,1552596052.830 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-03-14T20:40:52.832Z,1552596052.832 [CommandLine](FAULT): Scheduling is paused 2019-03-14T20:40:52.833Z,1552596052.833 [CBIT](INFO): Critical error at 20190314T204052 2019-03-14T20:40:52.833Z,1552596052.833 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2019-03-14T20:40:53.092Z,1552596053.092 [Micromodem](INFO): Starting 2019-03-14T20:40:53.092Z,1552596053.092 [Micromodem](INFO): Powering up 2019-03-14T20:40:53.092Z,1552596053.092 [Micromodem](DEBUG): Initializing Micromodem. 2019-03-14T20:40:53.190Z,1552596053.190 [RDI_PathfinderUp](INFO): Powering down 2019-03-14T20:40:53.230Z,1552596053.230 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-03-14T20:40:53.483Z,1552596053.483 [Micromodem](INFO): Starting 2019-03-14T20:40:53.502Z,1552596053.502 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-03-14T20:40:53.889Z,1552596053.889 [Micromodem](INFO): Starting 2019-03-14T20:40:54.292Z,1552596054.292 [Micromodem](INFO): Starting 2019-03-14T20:40:54.696Z,1552596054.696 [Micromodem](INFO): Starting 2019-03-14T20:40:55.100Z,1552596055.100 [Micromodem](INFO): Starting 2019-03-14T20:40:55.504Z,1552596055.504 [Micromodem](INFO): Starting 2019-03-14T20:40:55.908Z,1552596055.908 [Micromodem](INFO): Starting 2019-03-14T20:40:56.312Z,1552596056.312 [Micromodem](INFO): Starting 2019-03-14T20:40:56.716Z,1552596056.716 [Micromodem](INFO): Starting 2019-03-14T20:40:57.120Z,1552596057.120 [Micromodem](INFO): Starting 2019-03-14T20:40:57.524Z,1552596057.524 [Micromodem](INFO): Starting 2019-03-14T20:40:57.928Z,1552596057.928 [Micromodem](INFO): Starting 2019-03-14T20:40:57.928Z,1552596057.928 [Micromodem](INFO): Starting 2019-03-14T20:40:57.928Z,1552596057.928 [Micromodem](DEBUG): Nmea buf: $CCCFG,ALL,0*33 2019-03-14T20:40:57.928Z,1552596057.928 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,ALL,0*33 2019-03-14T20:40:57.929Z,1552596057.929 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33 2019-03-14T20:40:57.929Z,1552596057.929 [Micromodem](DEBUG): Nmea buf: $CCCFG,SRC,1*31 2019-03-14T20:40:57.929Z,1552596057.929 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,SRC,1*31 2019-03-14T20:40:57.929Z,1552596057.929 [Micromodem](DEBUG): Nmea buf: $CCCFG,nav.dt.txtrig_gpio4,1*64 2019-03-14T20:40:57.929Z,1552596057.929 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,nav.dt.txtrig_gpio4,1*64 2019-03-14T20:40:57.930Z,1552596057.930 [Micromodem](DEBUG): Nmea buf: $CCCFG,BND,1*3B 2019-03-14T20:40:57.930Z,1552596057.930 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,BND,1*3B 2019-03-14T20:40:58.332Z,1552596058.332 [Micromodem](INFO): Starting 2019-03-14T20:40:58.736Z,1552596058.736 [Micromodem](INFO): Starting 2019-03-14T20:40:59.140Z,1552596059.140 [Micromodem](INFO): Starting 2019-03-14T20:40:59.544Z,1552596059.544 [Micromodem](INFO): Starting 2019-03-14T20:40:59.948Z,1552596059.948 [Micromodem](INFO): Starting 2019-03-14T20:41:00.352Z,1552596060.352 [Micromodem](INFO): Starting 2019-03-14T20:41:00.756Z,1552596060.756 [Micromodem](INFO): Starting 2019-03-14T20:41:01.160Z,1552596061.160 [Micromodem](INFO): Starting 2019-03-14T20:41:01.564Z,1552596061.564 [Micromodem](INFO): Starting 2019-03-14T20:41:01.968Z,1552596061.968 [Micromodem](INFO): Starting 2019-03-14T20:41:02.372Z,1552596062.372 [Micromodem](INFO): Starting 2019-03-14T20:41:02.776Z,1552596062.776 [Micromodem](INFO): Starting 2019-03-14T20:41:03.180Z,1552596063.180 [Micromodem](INFO): Starting 2019-03-14T20:41:03.584Z,1552596063.584 [Micromodem](INFO): Starting 2019-03-14T20:41:03.988Z,1552596063.988 [Micromodem](INFO): Starting 2019-03-14T20:41:04.392Z,1552596064.392 [Micromodem](INFO): Starting 2019-03-14T20:41:04.796Z,1552596064.796 [Micromodem](INFO): Starting 2019-03-14T20:41:05.200Z,1552596065.200 [Micromodem](INFO): Starting 2019-03-14T20:41:05.604Z,1552596065.604 [Micromodem](INFO): Starting 2019-03-14T20:41:06.008Z,1552596066.008 [Micromodem](INFO): Starting 2019-03-14T20:41:06.033Z,1552596066.033 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2019-03-14T20:41:06.412Z,1552596066.412 [Micromodem](INFO): Starting 2019-03-14T20:41:06.816Z,1552596066.816 [Micromodem](INFO): Starting 2019-03-14T20:41:07.220Z,1552596067.220 [Micromodem](INFO): Starting 2019-03-14T20:41:07.624Z,1552596067.624 [Micromodem](INFO): Starting 2019-03-14T20:41:08.028Z,1552596068.028 [Micromodem](INFO): Starting 2019-03-14T20:41:08.432Z,1552596068.432 [Micromodem](INFO): Starting 2019-03-14T20:41:08.847Z,1552596068.847 [Micromodem](INFO): Starting 2019-03-14T20:41:09.240Z,1552596069.240 [Micromodem](INFO): Starting 2019-03-14T20:41:09.241Z,1552596069.241 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2019-03-14T20:41:09.644Z,1552596069.644 [Micromodem](INFO): Starting 2019-03-14T20:41:10.049Z,1552596070.049 [Micromodem](INFO): Starting 2019-03-14T20:41:10.453Z,1552596070.453 [Micromodem](INFO): Starting 2019-03-14T20:41:10.867Z,1552596070.867 [DUSBL_Hydroid](INFO): DUSBL Version:O 2019-03-14T20:41:10.878Z,1552596070.878 [Micromodem](INFO): Starting 2019-03-14T20:41:11.260Z,1552596071.260 [Micromodem](INFO): Starting 2019-03-14T20:41:11.664Z,1552596071.664 [Micromodem](INFO): Starting 2019-03-14T20:41:12.067Z,1552596072.067 [Micromodem](INFO): Starting 2019-03-14T20:41:12.472Z,1552596072.472 [Micromodem](INFO): Starting 2019-03-14T20:41:12.875Z,1552596072.875 [Micromodem](INFO): Starting 2019-03-14T20:41:13.279Z,1552596073.279 [Micromodem](INFO): Starting 2019-03-14T20:41:13.684Z,1552596073.684 [Micromodem](INFO): Starting 2019-03-14T20:41:14.087Z,1552596074.087 [Micromodem](INFO): Starting 2019-03-14T20:41:14.492Z,1552596074.492 [Micromodem](INFO): Starting 2019-03-14T20:41:14.895Z,1552596074.895 [Micromodem](INFO): Starting 2019-03-14T20:41:15.299Z,1552596075.299 [Micromodem](INFO): Starting 2019-03-14T20:41:15.703Z,1552596075.703 [Micromodem](INFO): Starting 2019-03-14T20:41:16.107Z,1552596076.107 [Micromodem](INFO): Starting 2019-03-14T20:41:16.509Z,1552596076.509 [Micromodem](INFO): Starting 2019-03-14T20:41:16.915Z,1552596076.915 [Micromodem](INFO): Starting 2019-03-14T20:41:17.319Z,1552596077.319 [Micromodem](INFO): Starting 2019-03-14T20:41:17.723Z,1552596077.723 [Micromodem](INFO): Starting 2019-03-14T20:41:18.127Z,1552596078.127 [Micromodem](INFO): Starting 2019-03-14T20:41:18.531Z,1552596078.531 [Micromodem](INFO): Starting 2019-03-14T20:41:18.532Z,1552596078.532 [NAL9602](INFO): Powering up NAL9602 2019-03-14T20:41:18.935Z,1552596078.935 [Micromodem](INFO): Starting 2019-03-14T20:41:19.339Z,1552596079.339 [Micromodem](INFO): Starting 2019-03-14T20:41:19.743Z,1552596079.743 [Micromodem](INFO): Starting 2019-03-14T20:41:20.149Z,1552596080.149 [Micromodem](INFO): Starting 2019-03-14T20:41:20.551Z,1552596080.551 [Micromodem](INFO): Starting 2019-03-14T20:41:20.553Z,1552596080.553 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33 2019-03-14T20:41:20.952Z,1552596080.952 [Micromodem](INFO): Starting 2019-03-14T20:41:21.017Z,1552596081.017 [SBIT](IMPORTANT): Beginning Startup BIT 2019-03-14T20:41:21.026Z,1552596081.026 [CBIT](IMPORTANT): Beginning ground fault scan 2019-03-14T20:41:21.363Z,1552596081.363 [Micromodem](INFO): Starting 2019-03-14T20:41:21.763Z,1552596081.763 [Micromodem](INFO): Starting 2019-03-14T20:41:22.167Z,1552596082.167 [Micromodem](INFO): Starting 2019-03-14T20:41:22.571Z,1552596082.571 [Micromodem](INFO): Starting 2019-03-14T20:41:22.975Z,1552596082.975 [Micromodem](INFO): Starting 2019-03-14T20:41:23.379Z,1552596083.379 [Micromodem](INFO): Starting 2019-03-14T20:41:24.005Z,1552596084.005 [Micromodem](INFO): Starting 2019-03-14T20:41:24.407Z,1552596084.407 [Micromodem](INFO): Starting 2019-03-14T20:41:24.811Z,1552596084.811 [Micromodem](INFO): Starting 2019-03-14T20:41:25.220Z,1552596085.220 [Micromodem](INFO): Starting 2019-03-14T20:41:25.619Z,1552596085.619 [Micromodem](INFO): Starting 2019-03-14T20:41:26.023Z,1552596086.023 [Micromodem](INFO): Starting 2019-03-14T20:41:26.428Z,1552596086.428 [Micromodem](INFO): Starting 2019-03-14T20:41:26.843Z,1552596086.843 [Micromodem](INFO): Starting 2019-03-14T20:41:27.235Z,1552596087.235 [Micromodem](INFO): Starting 2019-03-14T20:41:27.640Z,1552596087.640 [Micromodem](INFO): Starting 2019-03-14T20:41:28.043Z,1552596088.043 [Micromodem](INFO): Starting 2019-03-14T20:41:28.447Z,1552596088.447 [Micromodem](INFO): Starting 2019-03-14T20:41:28.855Z,1552596088.855 [Micromodem](INFO): Starting 2019-03-14T20:41:29.255Z,1552596089.255 [Micromodem](INFO): Starting 2019-03-14T20:41:29.659Z,1552596089.659 [Micromodem](INFO): Starting 2019-03-14T20:41:29.660Z,1552596089.660 [NAL9602](INFO): NAL9602 initialized 2019-03-14T20:41:30.063Z,1552596090.063 [Micromodem](INFO): Starting 2019-03-14T20:41:30.468Z,1552596090.468 [Micromodem](INFO): Starting 2019-03-14T20:41:30.468Z,1552596090.468 [Micromodem](IMPORTANT): CACFG stsring$CACFG,ALL,0*31 2019-03-14T20:41:30.469Z,1552596090.469 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31 2019-03-14T20:41:30.482Z,1552596090.482 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:41:30.884Z,1552596090.884 [Micromodem](INFO): Starting 2019-03-14T20:41:31.275Z,1552596091.275 [Micromodem](INFO): Starting 2019-03-14T20:41:31.679Z,1552596091.679 [Micromodem](INFO): Starting 2019-03-14T20:41:31.779Z,1552596091.779 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.011960 CHAN A1 (24V): -0.027345 CHAN A2 (12V): -0.007224 CHAN A3 (5V): -0.001357 CHAN B0 (3.3V): 0.000434 CHAN B1 (3.15aV): -0.000054 CHAN B2 (3.15bV): 0.000119 CHAN B3 (GND): 0.002200 OPEN: 0.006288 Full Scale Calc: 4.765 mA, -1.589 mA 2019-03-14T20:41:32.092Z,1552596092.092 [Micromodem](INFO): Starting 2019-03-14T20:41:32.487Z,1552596092.487 [Micromodem](INFO): Starting 2019-03-14T20:41:32.891Z,1552596092.891 [Micromodem](INFO): Starting 2019-03-14T20:41:33.295Z,1552596093.295 [Micromodem](INFO): Starting 2019-03-14T20:41:33.699Z,1552596093.699 [Micromodem](INFO): Starting 2019-03-14T20:41:34.101Z,1552596094.101 [Micromodem](INFO): Starting 2019-03-14T20:41:34.507Z,1552596094.507 [Micromodem](INFO): Starting 2019-03-14T20:41:34.911Z,1552596094.911 [Micromodem](INFO): Starting 2019-03-14T20:41:35.315Z,1552596095.315 [Micromodem](INFO): Starting 2019-03-14T20:41:35.719Z,1552596095.719 [Micromodem](INFO): Starting 2019-03-14T20:41:36.123Z,1552596096.123 [Micromodem](INFO): Starting 2019-03-14T20:41:36.527Z,1552596096.527 [Micromodem](INFO): Starting 2019-03-14T20:41:37.003Z,1552596097.003 [Micromodem](INFO): Starting 2019-03-14T20:41:37.403Z,1552596097.403 [Micromodem](INFO): Starting 2019-03-14T20:41:37.809Z,1552596097.809 [Micromodem](INFO): Starting 2019-03-14T20:41:37.930Z,1552596097.930 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2019-03-14T20:41:37.930Z,1552596097.930 [RDI_Pathfinder] Communications Fault, FailCount= 1 2019-03-14T20:41:37.931Z,1552596097.931 [RDI_Pathfinder](ERROR): Communications Fault 2019-03-14T20:41:37.931Z,1552596097.931 [RDI_Pathfinder](ERROR): Failed to parse: 2019-03-14T20:41:38.071Z,1552596098.071 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2019-03-14T20:41:38.211Z,1552596098.211 [Micromodem](INFO): Starting 2019-03-14T20:41:38.298Z,1552596098.298 [RDI_Pathfinder](INFO): Powering down 2019-03-14T20:41:38.615Z,1552596098.615 [Micromodem](INFO): Starting 2019-03-14T20:41:39.019Z,1552596099.019 [Micromodem](INFO): Starting 2019-03-14T20:41:39.167Z,1552596099.167 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2019-03-14T20:41:39.167Z,1552596099.167 [RDI_Pathfinder] No Fault, FailCount= 1 2019-03-14T20:41:39.423Z,1552596099.423 [Micromodem](INFO): Starting 2019-03-14T20:41:39.827Z,1552596099.827 [Micromodem](INFO): Starting 2019-03-14T20:41:40.233Z,1552596100.233 [Micromodem](INFO): Starting 2019-03-14T20:41:40.635Z,1552596100.635 [Micromodem](INFO): Starting 2019-03-14T20:41:40.636Z,1552596100.636 [Micromodem](IMPORTANT): CACFG stsring$CACFG,SRC,1*33 2019-03-14T20:41:40.636Z,1552596100.636 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64 2019-03-14T20:41:41.039Z,1552596101.039 [Micromodem](INFO): Starting 2019-03-14T20:41:41.443Z,1552596101.443 [Micromodem](INFO): Starting 2019-03-14T20:41:41.847Z,1552596101.847 [Micromodem](INFO): Starting 2019-03-14T20:41:42.251Z,1552596102.251 [Micromodem](INFO): Starting 2019-03-14T20:41:42.655Z,1552596102.655 [Micromodem](INFO): Starting 2019-03-14T20:41:43.059Z,1552596103.059 [Micromodem](INFO): Starting 2019-03-14T20:41:43.463Z,1552596103.463 [Micromodem](INFO): Starting 2019-03-14T20:41:43.867Z,1552596103.867 [Micromodem](INFO): Starting 2019-03-14T20:41:44.271Z,1552596104.271 [Micromodem](INFO): Starting 2019-03-14T20:41:44.675Z,1552596104.675 [Micromodem](INFO): Starting 2019-03-14T20:41:45.079Z,1552596105.079 [Micromodem](INFO): Starting 2019-03-14T20:41:45.483Z,1552596105.483 [Micromodem](INFO): Starting 2019-03-14T20:41:45.887Z,1552596105.887 [Micromodem](INFO): Starting 2019-03-14T20:41:46.292Z,1552596106.292 [Micromodem](INFO): Starting 2019-03-14T20:41:46.695Z,1552596106.695 [Micromodem](INFO): Starting 2019-03-14T20:41:47.099Z,1552596107.099 [Micromodem](INFO): Starting 2019-03-14T20:41:47.507Z,1552596107.507 [Micromodem](INFO): Starting 2019-03-14T20:41:47.912Z,1552596107.912 [Micromodem](INFO): Starting 2019-03-14T20:41:48.315Z,1552596108.315 [Micromodem](INFO): Starting 2019-03-14T20:41:48.712Z,1552596108.712 [Micromodem](INFO): Starting 2019-03-14T20:41:49.119Z,1552596109.119 [Micromodem](INFO): Starting 2019-03-14T20:41:49.527Z,1552596109.527 [Micromodem](INFO): Starting 2019-03-14T20:41:49.924Z,1552596109.924 [Micromodem](INFO): Starting 2019-03-14T20:41:50.332Z,1552596110.332 [Micromodem](INFO): Starting 2019-03-14T20:41:50.334Z,1552596110.334 [Micromodem](IMPORTANT): CACFG stsring$CACFG,nav.dt.txtrig_gpio4,1*66 2019-03-14T20:41:50.334Z,1552596110.334 [Micromodem](INFO): Nmea out: $CCCFG,BND,1*3B 2019-03-14T20:41:50.741Z,1552596110.741 [Micromodem](INFO): Starting 2019-03-14T20:41:50.742Z,1552596110.742 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAREV,204150,AUV,2.0.276017 2019-03-14T20:41:51.139Z,1552596111.139 [Micromodem](INFO): Starting 2019-03-14T20:41:51.543Z,1552596111.543 [Micromodem](INFO): Starting 2019-03-14T20:41:51.951Z,1552596111.951 [Micromodem](INFO): Starting 2019-03-14T20:41:51.952Z,1552596111.952 [Micromodem](IMPORTANT): CACFG stsring$CACFG,BND,1*39 2019-03-14T20:41:51.953Z,1552596111.953 [Micromodem](DEBUG): Nmea buf: $CCTMS,2019-03-14T20:41:52Z,0*78 2019-03-14T20:41:51.953Z,1552596111.953 [Micromodem](IMPORTANT): Nmea buf: $CCTMS,2019-03-14T20:41:52Z,0*78 2019-03-14T20:41:51.953Z,1552596111.953 [Micromodem](INFO): Nmea out: $CCTMS,2019-03-14T20:41:52Z,0*78 2019-03-14T20:41:52.355Z,1552596112.355 [Micromodem](INFO): Starting 2019-03-14T20:41:52.356Z,1552596112.356 [Micromodem](ERROR): Response from modem unexpected: $CADQF,201,1*53 2019-03-14T20:41:52.372Z,1552596112.372 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2019-03-14T20:41:52.755Z,1552596112.755 [Micromodem](INFO): Starting 2019-03-14T20:41:52.757Z,1552596112.757 [Micromodem](ERROR): Response from modem unexpected: $CAMSG,BAD_CRC,0*21 2019-03-14T20:41:53.160Z,1552596113.160 [Micromodem](INFO): Starting 2019-03-14T20:41:53.165Z,1552596113.165 [Micromodem](ERROR): Response from modem unexpected: $CACST,6,1,20190314204150.540972,05,163,16,0098,0150,246,00,00,01,02,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,201,-0.05,-999,9760,4000*7A 2019-03-14T20:41:53.564Z,1552596113.564 [Micromodem](INFO): Starting 2019-03-14T20:41:53.967Z,1552596113.967 [Micromodem](INFO): Starting 2019-03-14T20:41:54.375Z,1552596114.375 [Micromodem](INFO): Starting 2019-03-14T20:42:06.578Z,1552596126.578 [NAL9602](INFO): SBD MO Status=2, MOMSN=2276, MT Status=2, MTMSN=0 2019-03-14T20:42:06.578Z,1552596126.578 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-03-14T20:42:14.723Z,1552596134.723 [SBIT](IMPORTANT): SBIT PASSED 2019-03-14T20:42:14.739Z,1552596134.739 [CommandLine](IMPORTANT): got command configSet list 2019-03-14T20:42:14.739Z,1552596134.739 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-03-14T20:42:14.740Z,1552596134.740 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool; 2019-03-14T20:42:14.740Z,1552596134.740 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool; 2019-03-14T20:42:14.740Z,1552596134.740 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=30 count; 2019-03-14T20:42:15.088Z,1552596135.088 [MissionManager](IMPORTANT): Started mission Startup 2019-03-14T20:42:15.088Z,1552596135.088 [Startup] Running Loop=1 2019-03-14T20:42:15.088Z,1552596135.088 [Startup](DEBUG): Aggregate::initialize Startup 2019-03-14T20:42:15.088Z,1552596135.088 [Startup:A.GoToSurface] Running Loop=1 2019-03-14T20:42:15.088Z,1552596135.088 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-03-14T20:42:15.089Z,1552596135.089 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-03-14T20:42:15.089Z,1552596135.089 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-03-14T20:42:15.090Z,1552596135.090 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-03-14T20:42:15.090Z,1552596135.090 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-03-14T20:42:15.091Z,1552596135.091 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-03-14T20:42:15.092Z,1552596135.092 [Startup:StartupSatComms] Running Loop=1 2019-03-14T20:42:15.092Z,1552596135.092 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-03-14T20:42:15.092Z,1552596135.092 [Startup:StartupSatComms:A] Running Loop=1 2019-03-14T20:42:15.494Z,1552596135.494 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-03-14T20:42:32.430Z,1552596152.430 [NAL9602](INFO): SBD MO Status=2, MOMSN=2276, MT Status=2, MTMSN=0 2019-03-14T20:42:32.430Z,1552596152.430 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-03-14T20:42:40.576Z,1552596160.576 [CommandLine](IMPORTANT): got command failComponent 2019-03-14T20:42:40.576Z,1552596160.576 [CommandLine](IMPORTANT): Failed components: 2019-03-14T20:42:40.576Z,1552596160.576 [CommandLine](IMPORTANT): No failed Components. 2019-03-14T20:42:51.821Z,1552596171.821 [NAL9602](INFO): SBD MO Status=0, MOMSN=2276, MT Status=0, MTMSN=0 2019-03-14T20:42:51.822Z,1552596171.822 [NAL9602](INFO): No messages in MT queue 2019-03-14T20:42:52.646Z,1552596172.646 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:42:52.696Z,1552596172.696 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:42:52.696Z,1552596172.696 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-03-14T20:42:52.696Z,1552596172.696 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-14T20:42:52.698Z,1552596172.698 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:42:52.698Z,1552596172.698 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2019-03-14T20:42:52.698Z,1552596172.698 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-14T20:42:52.699Z,1552596172.699 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:42:52.700Z,1552596172.700 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2019-03-14T20:42:52.700Z,1552596172.700 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-14T20:42:52.715Z,1552596172.715 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-14T20:42:52.715Z,1552596172.715 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-14T20:42:52.715Z,1552596172.715 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-14T20:42:53.079Z,1552596173.079 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-14T20:42:53.079Z,1552596173.079 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-03-14T20:42:53.080Z,1552596173.080 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-14T20:42:53.080Z,1552596173.080 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2019-03-14T20:42:53.080Z,1552596173.080 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-14T20:42:53.080Z,1552596173.080 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2019-03-14T20:42:53.481Z,1552596173.481 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-14T20:42:55.066Z,1552596175.066 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:42:57.902Z,1552596177.902 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:42:58.693Z,1552596178.693 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAREV,204257,AUV,2.06*46 2019-03-14T20:43:00.726Z,1552596180.726 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:43:03.958Z,1552596183.958 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:43:06.786Z,1552596186.786 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:43:10.014Z,1552596190.014 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:43:12.846Z,1552596192.846 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:43:15.287Z,1552596195.287 [Startup:StartupSatComms:A](INFO): Timed out from 2019-03-14T20:42:15.1Z 2019-03-14T20:43:15.288Z,1552596195.288 [Startup:StartupSatComms:A] Stopped 2019-03-14T20:43:15.288Z,1552596195.288 [Startup:StartupSatComms:B] Running Loop=1 2019-03-14T20:43:15.700Z,1552596195.700 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-03-14T20:43:16.078Z,1552596196.078 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:43:24.754Z,1552596204.754 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:43:25.416Z,1552596205.416 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005043 2019-03-14T20:43:27.986Z,1552596207.986 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:43:30.349Z,1552596210.349 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190314T202350/Courier0007.lzma 2019-03-14T20:43:30.814Z,1552596210.814 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:43:31.156Z,1552596211.156 [DataOverHttps](INFO): Moved sent file to Logs/20190314T202350/Courier0007.lzma.bak 2019-03-14T20:43:31.156Z,1552596211.156 [DataOverHttps](INFO): SBD MOMSN=10139499 2019-03-14T20:43:34.046Z,1552596214.046 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:43:36.874Z,1552596216.874 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:43:40.106Z,1552596220.106 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:43:42.062Z,1552596222.062 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20190314T203901/Courier0000.lzma 2019-03-14T20:43:42.868Z,1552596222.868 [DataOverHttps](INFO): Moved sent file to Logs/20190314T203901/Courier0000.lzma.bak 2019-03-14T20:43:42.868Z,1552596222.868 [DataOverHttps](INFO): SBD MOMSN=10139501 2019-03-14T20:43:42.934Z,1552596222.934 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:43:45.762Z,1552596225.762 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:43:48.190Z,1552596228.190 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:43:51.014Z,1552596231.014 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:43:53.818Z,1552596233.818 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20190314T204042/Courier0000.lzma 2019-03-14T20:43:54.254Z,1552596234.254 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:43:54.624Z,1552596234.624 [DataOverHttps](INFO): Moved sent file to Logs/20190314T204042/Courier0000.lzma.bak 2019-03-14T20:43:54.625Z,1552596234.625 [DataOverHttps](INFO): SBD MOMSN=10139505 2019-03-14T20:43:57.074Z,1552596237.074 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:43:59.902Z,1552596239.902 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:44:02.730Z,1552596242.730 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:44:05.889Z,1552596245.889 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190314T202350/Express0008.lzma 2019-03-14T20:44:05.962Z,1552596245.962 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:44:06.695Z,1552596246.695 [DataOverHttps](INFO): Moved sent file to Logs/20190314T202350/Express0008.lzma.bak 2019-03-14T20:44:06.696Z,1552596246.696 [DataOverHttps](INFO): SBD MOMSN=10139510 2019-03-14T20:44:08.790Z,1552596248.790 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:44:12.022Z,1552596252.022 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:44:14.850Z,1552596254.850 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:44:15.715Z,1552596255.715 [Startup:StartupSatComms:B](INFO): Timed out from 2019-03-14T20:43:15.3Z 2019-03-14T20:44:15.715Z,1552596255.715 [Startup:StartupSatComms:B] Stopped 2019-03-14T20:44:15.715Z,1552596255.715 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2019-03-14T20:44:15.716Z,1552596255.716 [Startup:StartupSatComms] Stopped 2019-03-14T20:44:15.716Z,1552596255.716 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2019-03-14T20:44:15.716Z,1552596255.716 [Startup](INFO): Completed Startup 2019-03-14T20:44:15.717Z,1552596255.717 [MissionManager](INFO): Startup is completed. 2019-03-14T20:44:15.717Z,1552596255.717 [MissionManager](INFO): Uninitializing Mission Startup 2019-03-14T20:44:15.717Z,1552596255.717 [Startup] Stopped 2019-03-14T20:44:15.717Z,1552596255.717 [Startup](DEBUG): Aggregate::uninitialize Startup 2019-03-14T20:44:15.717Z,1552596255.717 [Startup:A.GoToSurface] Stopped 2019-03-14T20:44:15.717Z,1552596255.717 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-03-14T20:44:16.072Z,1552596256.072 [MissionManager](IMPORTANT): Started mission Default 2019-03-14T20:44:16.072Z,1552596256.072 [Default] Running Loop=1 2019-03-14T20:44:16.072Z,1552596256.072 [Default](DEBUG): Aggregate::initialize Default 2019-03-14T20:44:16.072Z,1552596256.072 [Default:B.GoToSurface] Running Loop=1 2019-03-14T20:44:16.072Z,1552596256.072 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-03-14T20:44:16.072Z,1552596256.072 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-03-14T20:44:16.073Z,1552596256.073 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-03-14T20:44:16.073Z,1552596256.073 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-03-14T20:44:16.073Z,1552596256.073 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-03-14T20:44:16.074Z,1552596256.074 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-03-14T20:44:16.074Z,1552596256.074 [Default:A.Wait] Running Loop=1 2019-03-14T20:44:16.074Z,1552596256.074 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-03-14T20:44:17.536Z,1552596257.536 [DataOverHttps](INFO): Sending 1074 bytes from file Logs/20190314T203901/Express0001.lzma 2019-03-14T20:44:18.082Z,1552596258.082 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:44:18.339Z,1552596258.339 [DataOverHttps](INFO): Moved sent file to Logs/20190314T203901/Express0001.lzma.bak 2019-03-14T20:44:18.340Z,1552596258.340 [DataOverHttps](INFO): SBD MOMSN=10139512 2019-03-14T20:44:20.910Z,1552596260.910 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:44:24.150Z,1552596264.150 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:44:26.974Z,1552596266.974 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:44:29.420Z,1552596269.420 [Default:A.Wait](INFO): Done Waiting. 2019-03-14T20:44:29.420Z,1552596269.420 [Default:A.Wait] Stopped 2019-03-14T20:44:29.420Z,1552596269.420 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-03-14T20:44:29.816Z,1552596269.816 [Default:CheckIn] Running Loop=1 2019-03-14T20:44:29.816Z,1552596269.816 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-03-14T20:44:29.816Z,1552596269.816 [Default:CheckIn:Read_GPS] Running Loop=1 2019-03-14T20:44:30.202Z,1552596270.202 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:44:30.232Z,1552596270.232 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2019-03-14T20:44:33.038Z,1552596273.038 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:44:36.266Z,1552596276.266 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:44:39.090Z,1552596279.090 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:44:41.918Z,1552596281.918 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:44:44.750Z,1552596284.750 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:44:47.978Z,1552596287.978 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:44:50.814Z,1552596290.814 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:44:53.663Z,1552596293.663 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:44:53.663Z,1552596293.663 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2019-03-14T20:44:53.663Z,1552596293.663 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-14T20:44:53.667Z,1552596293.667 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:44:53.667Z,1552596293.667 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2019-03-14T20:44:53.667Z,1552596293.667 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-14T20:44:53.671Z,1552596293.671 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:44:53.671Z,1552596293.671 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2019-03-14T20:44:53.671Z,1552596293.671 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-14T20:44:53.689Z,1552596293.689 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-14T20:44:53.690Z,1552596293.690 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-14T20:44:53.690Z,1552596293.690 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-14T20:44:54.050Z,1552596294.050 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:44:54.092Z,1552596294.092 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-14T20:44:54.093Z,1552596294.093 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2019-03-14T20:44:54.093Z,1552596294.093 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-14T20:44:54.093Z,1552596294.093 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2019-03-14T20:44:54.094Z,1552596294.094 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-14T20:44:54.094Z,1552596294.094 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2019-03-14T20:44:54.481Z,1552596294.481 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-14T20:44:56.866Z,1552596296.866 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:44:59.704Z,1552596299.704 [RDI_Pathfinder](ERROR): only read 3 of 4 data items 2019-03-14T20:44:59.704Z,1552596299.704 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00 2019-03-14T20:45:00.102Z,1552596300.102 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:45:02.930Z,1552596302.930 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:45:06.158Z,1552596306.158 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:45:08.986Z,1552596308.986 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:45:12.222Z,1552596312.222 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:45:12.729Z,1552596312.729 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2019-03-14T20:45:15.050Z,1552596315.050 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:45:18.286Z,1552596318.286 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:45:18.715Z,1552596318.715 [RDI_Pathfinder](ERROR): only read 3 of 5 data items 2019-03-14T20:45:18.715Z,1552596318.715 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-3278,-32768,V 2019-03-14T20:45:21.110Z,1552596321.110 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:45:23.942Z,1552596323.942 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:45:26.770Z,1552596326.770 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:45:30.006Z,1552596330.006 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:45:32.834Z,1552596332.834 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:45:36.062Z,1552596336.062 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:45:38.890Z,1552596338.890 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:45:42.122Z,1552596342.122 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:45:44.958Z,1552596344.958 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:45:48.182Z,1552596348.182 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:45:51.014Z,1552596351.014 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:45:53.838Z,1552596353.838 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:45:54.236Z,1552596354.236 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204024.00,A,3648.16366,N,12147.28097,W,0.194,0.00,140319,,,A*76 2019-03-14T20:45:54.239Z,1552596354.239 [NAL9602](INFO): GPS fix at 20190314T204024: (36.802728, -121.788016) 2019-03-14T20:45:54.268Z,1552596354.268 [Default:CheckIn:Read_GPS] Stopped 2019-03-14T20:45:54.268Z,1552596354.268 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-03-14T20:45:54.704Z,1552596354.704 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2019-03-14T20:46:06.350Z,1552596366.350 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20190314T204042/Courier0004.lzma 2019-03-14T20:46:07.156Z,1552596367.156 [DataOverHttps](INFO): Moved sent file to Logs/20190314T204042/Courier0004.lzma.bak 2019-03-14T20:46:07.156Z,1552596367.156 [DataOverHttps](INFO): SBD MOMSN=10139567 2019-03-14T20:46:26.712Z,1552596386.712 [NAL9602](INFO): Powering down 2019-03-14T20:46:29.644Z,1552596389.644 [DataOverHttps](INFO): Sending 1066 bytes from file Logs/20190314T204042/Express0001.lzma 2019-03-14T20:46:30.447Z,1552596390.447 [DataOverHttps](INFO): Moved sent file to Logs/20190314T204042/Express0001.lzma.bak 2019-03-14T20:46:30.448Z,1552596390.448 [DataOverHttps](INFO): SBD MOMSN=10139576 2019-03-14T20:46:41.806Z,1552596401.806 [DataOverHttps](INFO): Sending 297 bytes from file Logs/20190314T204042/Express0005.lzma 2019-03-14T20:46:42.612Z,1552596402.612 [DataOverHttps](INFO): Moved sent file to Logs/20190314T204042/Express0005.lzma.bak 2019-03-14T20:46:42.612Z,1552596402.612 [DataOverHttps](INFO): SBD MOMSN=10139639 2019-03-14T20:46:43.737Z,1552596403.737 [Default:CheckIn:Read_Iridium] Stopped 2019-03-14T20:46:43.737Z,1552596403.737 [Default:CheckIn:C.Wait] Running Loop=1 2019-03-14T20:46:43.737Z,1552596403.737 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-03-14T20:46:54.621Z,1552596414.621 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:46:54.621Z,1552596414.621 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-03-14T20:46:54.621Z,1552596414.621 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-14T20:46:54.627Z,1552596414.627 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:46:54.627Z,1552596414.627 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2019-03-14T20:46:54.627Z,1552596414.627 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-14T20:46:54.629Z,1552596414.629 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:46:54.629Z,1552596414.629 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2019-03-14T20:46:54.629Z,1552596414.629 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-14T20:46:54.641Z,1552596414.641 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-14T20:46:54.642Z,1552596414.642 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-14T20:46:54.642Z,1552596414.642 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-14T20:46:55.046Z,1552596415.046 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-14T20:46:55.046Z,1552596415.046 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-03-14T20:46:55.047Z,1552596415.047 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-14T20:46:55.047Z,1552596415.047 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2019-03-14T20:46:55.047Z,1552596415.047 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-14T20:46:55.047Z,1552596415.047 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2019-03-14T20:46:55.426Z,1552596415.426 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-14T20:47:46.349Z,1552596466.349 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2019-03-14T20:48:55.817Z,1552596535.817 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:48:55.817Z,1552596535.817 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2019-03-14T20:48:55.817Z,1552596535.817 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-14T20:48:55.819Z,1552596535.819 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:48:55.819Z,1552596535.819 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2019-03-14T20:48:55.819Z,1552596535.819 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-14T20:48:55.821Z,1552596535.821 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:48:55.821Z,1552596535.821 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2019-03-14T20:48:55.821Z,1552596535.821 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-14T20:48:55.832Z,1552596535.832 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-14T20:48:55.832Z,1552596535.832 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-14T20:48:55.832Z,1552596535.832 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-14T20:48:56.235Z,1552596536.235 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-14T20:48:56.235Z,1552596536.235 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2019-03-14T20:48:56.236Z,1552596536.236 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-14T20:48:56.236Z,1552596536.236 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2019-03-14T20:48:56.237Z,1552596536.237 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-14T20:48:56.237Z,1552596536.237 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2019-03-14T20:48:56.632Z,1552596536.632 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-14T20:49:31.766Z,1552596571.766 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2019-03-14T20:49:31.766Z,1552596571.766 [RDI_Pathfinder](ERROR): Failed to parse: :TS,19031413522286,35.0, -00,1448.9, 0 2019-03-14T20:50:56.669Z,1552596656.669 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:50:56.669Z,1552596656.669 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2019-03-14T20:50:56.669Z,1552596656.669 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-14T20:50:56.675Z,1552596656.675 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:50:56.676Z,1552596656.676 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2019-03-14T20:50:56.676Z,1552596656.676 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-14T20:50:56.677Z,1552596656.677 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:50:56.677Z,1552596656.677 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3 2019-03-14T20:50:56.678Z,1552596656.678 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-14T20:50:56.779Z,1552596656.779 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-14T20:50:56.779Z,1552596656.779 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-14T20:50:56.780Z,1552596656.780 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-14T20:50:57.041Z,1552596657.041 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-14T20:50:57.041Z,1552596657.041 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2019-03-14T20:50:57.042Z,1552596657.042 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-14T20:50:57.042Z,1552596657.042 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2019-03-14T20:50:57.043Z,1552596657.043 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-14T20:50:57.043Z,1552596657.043 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3 2019-03-14T20:50:57.421Z,1552596657.421 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-14T20:51:44.289Z,1552596704.289 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-03-14T20:51:44.289Z,1552596704.289 [Default:CheckIn:C.Wait] Stopped 2019-03-14T20:51:44.289Z,1552596704.289 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-03-14T20:51:44.289Z,1552596704.289 [Default:CheckIn:D] Running Loop=1 2019-03-14T20:51:44.734Z,1552596704.734 [Default:CheckIn:D] Stopped 2019-03-14T20:51:44.754Z,1552596704.754 [Default:CheckIn:E] Running Loop=1 2019-03-14T20:51:45.093Z,1552596705.093 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.477699 min 2019-03-14T20:51:45.093Z,1552596705.093 [Default:CheckIn:E] Stopped 2019-03-14T20:51:45.093Z,1552596705.093 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-03-14T20:51:45.094Z,1552596705.094 [Default:CheckIn] Stopped 2019-03-14T20:51:45.094Z,1552596705.094 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-03-14T20:51:45.094Z,1552596705.094 [Default:CheckIn](INFO): Running loop #2 2019-03-14T20:51:45.094Z,1552596705.094 [Default:CheckIn] Running Loop=2 2019-03-14T20:51:45.094Z,1552596705.094 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-03-14T20:51:45.094Z,1552596705.094 [Default:CheckIn:Read_GPS] Running Loop=1 2019-03-14T20:51:45.872Z,1552596705.872 [NAL9602](INFO): Powering up 2019-03-14T20:51:56.784Z,1552596716.784 [NAL9602](INFO): NAL9602 initialized 2019-03-14T20:51:57.602Z,1552596717.602 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:52:38.393Z,1552596758.393 [NAL9602](INFO): SBD MO Status=0, MOMSN=2277, MT Status=0, MTMSN=0 2019-03-14T20:52:38.393Z,1552596758.393 [NAL9602](INFO): No messages in MT queue 2019-03-14T20:52:39.214Z,1552596759.214 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:52:42.042Z,1552596762.042 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:52:44.870Z,1552596764.870 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:52:48.106Z,1552596768.106 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:52:50.930Z,1552596770.930 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:52:54.170Z,1552596774.170 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:52:56.990Z,1552596776.990 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:52:57.431Z,1552596777.431 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:52:57.431Z,1552596777.431 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2019-03-14T20:52:57.431Z,1552596777.431 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-14T20:52:57.433Z,1552596777.433 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:52:57.433Z,1552596777.433 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2019-03-14T20:52:57.433Z,1552596777.433 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-14T20:52:57.435Z,1552596777.435 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:52:57.435Z,1552596777.435 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4 2019-03-14T20:52:57.435Z,1552596777.435 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-14T20:52:57.446Z,1552596777.446 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-14T20:52:57.447Z,1552596777.447 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-14T20:52:57.447Z,1552596777.447 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-14T20:52:57.851Z,1552596777.851 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-14T20:52:57.851Z,1552596777.851 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2019-03-14T20:52:57.851Z,1552596777.851 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-14T20:52:57.851Z,1552596777.851 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2019-03-14T20:52:57.852Z,1552596777.852 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-14T20:52:57.852Z,1552596777.852 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4 2019-03-14T20:52:58.213Z,1552596778.213 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-14T20:53:00.226Z,1552596780.226 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:53:03.050Z,1552596783.050 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:53:05.874Z,1552596785.874 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:53:08.706Z,1552596788.706 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:53:10.726Z,1552596790.726 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:53:13.962Z,1552596793.962 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:53:16.782Z,1552596796.782 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:53:20.019Z,1552596800.019 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:53:22.850Z,1552596802.850 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:53:26.078Z,1552596806.078 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:53:28.906Z,1552596808.906 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:53:31.734Z,1552596811.734 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:53:34.966Z,1552596814.966 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:53:37.798Z,1552596817.798 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:53:41.030Z,1552596821.030 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:53:43.858Z,1552596823.858 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:53:47.086Z,1552596827.086 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:53:49.914Z,1552596829.914 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:53:52.742Z,1552596832.742 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:53:55.978Z,1552596835.978 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:53:58.802Z,1552596838.802 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:54:02.038Z,1552596842.038 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:54:04.862Z,1552596844.862 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:54:07.694Z,1552596847.694 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:54:09.710Z,1552596849.710 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:54:12.134Z,1552596852.134 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:54:14.958Z,1552596854.958 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:54:17.794Z,1552596857.794 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:54:21.026Z,1552596861.026 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:54:24.258Z,1552596864.258 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:54:27.110Z,1552596867.110 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:54:29.910Z,1552596869.910 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:54:32.738Z,1552596872.738 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:54:35.974Z,1552596875.974 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:54:38.798Z,1552596878.798 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:54:42.034Z,1552596882.034 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:54:44.858Z,1552596884.858 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:54:48.094Z,1552596888.094 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:54:50.922Z,1552596890.922 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:54:54.154Z,1552596894.154 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:54:56.978Z,1552596896.978 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:54:58.605Z,1552596898.605 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:54:58.605Z,1552596898.605 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2019-03-14T20:54:58.605Z,1552596898.605 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-14T20:54:58.607Z,1552596898.607 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:54:58.608Z,1552596898.608 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2019-03-14T20:54:58.608Z,1552596898.608 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-14T20:54:58.609Z,1552596898.609 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:54:58.609Z,1552596898.609 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5 2019-03-14T20:54:58.609Z,1552596898.609 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-14T20:54:58.624Z,1552596898.624 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-14T20:54:58.624Z,1552596898.624 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-14T20:54:58.624Z,1552596898.624 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-14T20:54:59.028Z,1552596899.028 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-14T20:54:59.028Z,1552596899.028 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2019-03-14T20:54:59.029Z,1552596899.029 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-14T20:54:59.029Z,1552596899.029 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2019-03-14T20:54:59.029Z,1552596899.029 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-14T20:54:59.029Z,1552596899.029 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5 2019-03-14T20:54:59.423Z,1552596899.423 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-14T20:55:00.214Z,1552596900.214 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:55:03.038Z,1552596903.038 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:55:05.874Z,1552596905.874 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:55:09.098Z,1552596909.098 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:55:11.926Z,1552596911.926 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:55:14.754Z,1552596914.754 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:55:17.990Z,1552596917.990 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:55:20.818Z,1552596920.818 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:55:24.046Z,1552596924.046 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:55:26.878Z,1552596926.878 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:55:30.114Z,1552596930.114 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:55:32.938Z,1552596932.938 [NAL9602](DEBUG): Fix Requested 2019-03-14T20:55:33.327Z,1552596933.327 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205004.00,A,3648.16614,N,12147.28942,W,0.058,0.00,140319,,,A*75 2019-03-14T20:55:33.329Z,1552596933.329 [NAL9602](INFO): GPS fix at 20190314T205004: (36.802769, -121.788157) 2019-03-14T20:55:33.365Z,1552596933.365 [Default:CheckIn:Read_GPS] Stopped 2019-03-14T20:55:33.365Z,1552596933.365 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-03-14T20:55:38.810Z,1552596938.810 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20190314T204042/Courier0007.lzma 2019-03-14T20:55:39.616Z,1552596939.616 [DataOverHttps](INFO): Moved sent file to Logs/20190314T204042/Courier0007.lzma.bak 2019-03-14T20:55:39.616Z,1552596939.616 [DataOverHttps](INFO): SBD MOMSN=10139660 2019-03-14T20:55:53.793Z,1552596953.793 [DataOverHttps](INFO): Sending 295 bytes from file Logs/20190314T204042/Express0008.lzma 2019-03-14T20:55:54.564Z,1552596954.564 [DataOverHttps](INFO): Moved sent file to Logs/20190314T204042/Express0008.lzma.bak 2019-03-14T20:55:54.564Z,1552596954.564 [DataOverHttps](INFO): SBD MOMSN=10139663 2019-03-14T20:55:55.611Z,1552596955.611 [Default:CheckIn:Read_Iridium] Stopped 2019-03-14T20:55:55.611Z,1552596955.611 [Default:CheckIn:C.Wait] Running Loop=1 2019-03-14T20:55:55.611Z,1552596955.611 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-03-14T20:56:06.078Z,1552596966.078 [NAL9602](INFO): Powering down 2019-03-14T20:56:59.436Z,1552597019.436 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:56:59.437Z,1552597019.437 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-03-14T20:56:59.437Z,1552597019.437 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-14T20:56:59.439Z,1552597019.439 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:56:59.439Z,1552597019.439 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2019-03-14T20:56:59.439Z,1552597019.439 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-14T20:56:59.441Z,1552597019.441 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:56:59.441Z,1552597019.441 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2019-03-14T20:56:59.441Z,1552597019.441 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-14T20:56:59.451Z,1552597019.451 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-14T20:56:59.452Z,1552597019.452 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-14T20:56:59.452Z,1552597019.452 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-14T20:56:59.867Z,1552597019.867 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-14T20:56:59.867Z,1552597019.867 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-03-14T20:56:59.867Z,1552597019.867 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-14T20:56:59.867Z,1552597019.867 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2019-03-14T20:56:59.868Z,1552597019.868 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-14T20:56:59.868Z,1552597019.868 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2019-03-14T20:57:00.237Z,1552597020.237 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-14T20:59:00.653Z,1552597140.653 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:59:00.653Z,1552597140.653 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2019-03-14T20:59:00.653Z,1552597140.653 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-14T20:59:00.655Z,1552597140.655 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:59:00.655Z,1552597140.655 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2019-03-14T20:59:00.655Z,1552597140.655 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-14T20:59:00.657Z,1552597140.657 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T20:59:00.657Z,1552597140.657 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2019-03-14T20:59:00.657Z,1552597140.657 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-14T20:59:00.680Z,1552597140.680 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-14T20:59:00.680Z,1552597140.680 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-14T20:59:00.680Z,1552597140.680 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-14T20:59:01.051Z,1552597141.051 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-14T20:59:01.051Z,1552597141.051 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2019-03-14T20:59:01.052Z,1552597141.052 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-14T20:59:01.052Z,1552597141.052 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2019-03-14T20:59:01.053Z,1552597141.053 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-14T20:59:01.053Z,1552597141.053 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2019-03-14T20:59:01.454Z,1552597141.454 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-14T21:00:43.892Z,1552597243.892 [CommandLine](IMPORTANT): got command failComponent 2019-03-14T21:00:43.892Z,1552597243.892 [CommandLine](IMPORTANT): Failed components: 2019-03-14T21:00:43.892Z,1552597243.892 [CommandLine](IMPORTANT): No failed Components. 2019-03-14T21:00:50.784Z,1552597250.784 [CommandLine](IMPORTANT): got command report 2019-03-14T21:00:56.173Z,1552597256.173 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-03-14T21:00:56.173Z,1552597256.173 [Default:CheckIn:C.Wait] Stopped 2019-03-14T21:00:56.173Z,1552597256.173 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-03-14T21:00:56.173Z,1552597256.173 [Default:CheckIn:D] Running Loop=1 2019-03-14T21:00:56.601Z,1552597256.601 [Default:CheckIn:D] Stopped 2019-03-14T21:00:56.601Z,1552597256.601 [Default:CheckIn:E] Running Loop=1 2019-03-14T21:00:56.997Z,1552597256.997 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 16.675492 min 2019-03-14T21:00:56.997Z,1552597256.997 [Default:CheckIn:E] Stopped 2019-03-14T21:00:56.997Z,1552597256.997 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-03-14T21:00:56.997Z,1552597256.997 [Default:CheckIn] Stopped 2019-03-14T21:00:56.998Z,1552597256.998 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-03-14T21:00:56.998Z,1552597256.998 [Default:CheckIn](INFO): Running loop #3 2019-03-14T21:00:56.998Z,1552597256.998 [Default:CheckIn] Running Loop=3 2019-03-14T21:00:56.998Z,1552597256.998 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-03-14T21:00:56.998Z,1552597256.998 [Default:CheckIn:Read_GPS] Running Loop=1 2019-03-14T21:00:57.768Z,1552597257.768 [NAL9602](INFO): Powering up 2019-03-14T21:01:01.095Z,1552597261.095 [CommandLine](IMPORTANT): got command show variable usbl 2019-03-14T21:01:01.159Z,1552597261.159 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadAtStartup (bool) 2019-03-14T21:01:01.160Z,1552597261.160 [CommandLine](IMPORTANT): DUSBL_Hydroid.simulateHardware (bool) 2019-03-14T21:01:01.160Z,1552597261.160 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultTurnAroundTime (meter_per_second) 2019-03-14T21:01:01.160Z,1552597261.160 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultSoundSpeed (meter_per_second) 2019-03-14T21:01:01.161Z,1552597261.161 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count) 2019-03-14T21:01:01.161Z,1552597261.161 [CommandLine](IMPORTANT): DUSBL_Hydroid.recieveTimeout (millisecond) 2019-03-14T21:01:01.161Z,1552597261.161 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout (millisecond) 2019-03-14T21:01:01.162Z,1552597261.162 [CommandLine](IMPORTANT): DUSBL_Hydroid.transponderCode (enum) 2019-03-14T21:01:01.162Z,1552597261.162 [CommandLine](IMPORTANT): DUSBL_Hydroid.numberOfPingsRequested (count) 2019-03-14T21:01:01.163Z,1552597261.163 [CommandLine](IMPORTANT): DUSBL_Hydroid.verbosity (enum) 2019-03-14T21:01:01.163Z,1552597261.163 [CommandLine](IMPORTANT): DUSBL_Hydroid.xCenter (angular_degree) 2019-03-14T21:01:01.163Z,1552597261.163 [CommandLine](IMPORTANT): DUSBL_Hydroid.yCenter (angular_degree) 2019-03-14T21:01:01.173Z,1552597261.173 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadControl (none) 2019-03-14T21:01:01.173Z,1552597261.173 [CommandLine](IMPORTANT): DUSBL_Hydroid.uart (none) 2019-03-14T21:01:01.173Z,1552597261.173 [CommandLine](IMPORTANT): DUSBL_Hydroid.baud (bit_per_second) 2019-03-14T21:01:01.180Z,1552597261.180 [CommandLine](IMPORTANT): Micromodem.dusblPingCode (enum) 2019-03-14T21:01:01.180Z,1552597261.180 [CommandLine](IMPORTANT): DUSBL_Hydroid.xAngle (angular_degree) 2019-03-14T21:01:01.181Z,1552597261.181 [CommandLine](IMPORTANT): DUSBL_Hydroid.yAngle (angular_degree) 2019-03-14T21:01:01.181Z,1552597261.181 [CommandLine](IMPORTANT): DUSBL_Hydroid.travelTime (microsecond) 2019-03-14T21:01:01.181Z,1552597261.181 [CommandLine](IMPORTANT): DUSBL_Hydroid.latency (microsecond) 2019-03-14T21:01:01.182Z,1552597261.182 [CommandLine](IMPORTANT): DUSBL_Hydroid.gain (ratio) 2019-03-14T21:01:01.183Z,1552597261.183 [CommandLine](IMPORTANT): DUSBL_Hydroid.inBandSignalToNoise (ratio) 2019-03-14T21:01:01.183Z,1552597261.183 [CommandLine](IMPORTANT): DUSBL_Hydroid.outBandSignalToNoise (ratio) 2019-03-14T21:01:01.184Z,1552597261.184 [CommandLine](IMPORTANT): DUSBL_Hydroid.range (meter) 2019-03-14T21:01:01.184Z,1552597261.184 [CommandLine](IMPORTANT): DUSBL_Hydroid.direction (none) 2019-03-14T21:01:01.476Z,1552597261.476 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T21:01:01.476Z,1552597261.476 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2019-03-14T21:01:01.476Z,1552597261.476 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-14T21:01:01.487Z,1552597261.487 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T21:01:01.487Z,1552597261.487 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2019-03-14T21:01:01.487Z,1552597261.487 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-14T21:01:01.489Z,1552597261.489 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T21:01:01.489Z,1552597261.489 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3 2019-03-14T21:01:01.489Z,1552597261.489 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-14T21:01:01.512Z,1552597261.512 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-14T21:01:01.512Z,1552597261.512 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-14T21:01:01.512Z,1552597261.512 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-14T21:01:01.834Z,1552597261.834 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-14T21:01:01.834Z,1552597261.834 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2019-03-14T21:01:01.835Z,1552597261.835 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-14T21:01:01.835Z,1552597261.835 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2019-03-14T21:01:01.836Z,1552597261.836 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-14T21:01:01.836Z,1552597261.836 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3 2019-03-14T21:01:02.233Z,1552597262.233 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-14T21:01:08.675Z,1552597268.675 [NAL9602](INFO): NAL9602 initialized 2019-03-14T21:01:09.498Z,1552597269.498 [NAL9602](DEBUG): Fix Requested 2019-03-14T21:01:17.653Z,1552597277.653 [CommandLine](IMPORTANT): got command show variable usbl 2019-03-14T21:01:17.711Z,1552597277.711 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadAtStartup (bool) 2019-03-14T21:01:17.711Z,1552597277.711 [CommandLine](IMPORTANT): DUSBL_Hydroid.simulateHardware (bool) 2019-03-14T21:01:17.712Z,1552597277.712 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultTurnAroundTime (meter_per_second) 2019-03-14T21:01:17.712Z,1552597277.712 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultSoundSpeed (meter_per_second) 2019-03-14T21:01:17.712Z,1552597277.712 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count) 2019-03-14T21:01:17.713Z,1552597277.713 [CommandLine](IMPORTANT): DUSBL_Hydroid.recieveTimeout (millisecond) 2019-03-14T21:01:17.713Z,1552597277.713 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout (millisecond) 2019-03-14T21:01:17.713Z,1552597277.713 [CommandLine](IMPORTANT): DUSBL_Hydroid.transponderCode (enum) 2019-03-14T21:01:17.714Z,1552597277.714 [CommandLine](IMPORTANT): DUSBL_Hydroid.numberOfPingsRequested (count) 2019-03-14T21:01:17.714Z,1552597277.714 [CommandLine](IMPORTANT): DUSBL_Hydroid.verbosity (enum) 2019-03-14T21:01:17.714Z,1552597277.714 [CommandLine](IMPORTANT): DUSBL_Hydroid.xCenter (angular_degree) 2019-03-14T21:01:17.715Z,1552597277.715 [CommandLine](IMPORTANT): DUSBL_Hydroid.yCenter (angular_degree) 2019-03-14T21:01:17.724Z,1552597277.724 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadControl (none) 2019-03-14T21:01:17.725Z,1552597277.725 [CommandLine](IMPORTANT): DUSBL_Hydroid.uart (none) 2019-03-14T21:01:17.725Z,1552597277.725 [CommandLine](IMPORTANT): DUSBL_Hydroid.baud (bit_per_second) 2019-03-14T21:01:17.728Z,1552597277.728 [CommandLine](IMPORTANT): Micromodem.dusblPingCode (enum) 2019-03-14T21:01:17.728Z,1552597277.728 [CommandLine](IMPORTANT): DUSBL_Hydroid.xAngle (angular_degree) 2019-03-14T21:01:17.729Z,1552597277.729 [CommandLine](IMPORTANT): DUSBL_Hydroid.yAngle (angular_degree) 2019-03-14T21:01:17.729Z,1552597277.729 [CommandLine](IMPORTANT): DUSBL_Hydroid.travelTime (microsecond) 2019-03-14T21:01:17.729Z,1552597277.729 [CommandLine](IMPORTANT): DUSBL_Hydroid.latency (microsecond) 2019-03-14T21:01:17.730Z,1552597277.730 [CommandLine](IMPORTANT): DUSBL_Hydroid.gain (ratio) 2019-03-14T21:01:17.730Z,1552597277.730 [CommandLine](IMPORTANT): DUSBL_Hydroid.inBandSignalToNoise (ratio) 2019-03-14T21:01:17.730Z,1552597277.730 [CommandLine](IMPORTANT): DUSBL_Hydroid.outBandSignalToNoise (ratio) 2019-03-14T21:01:17.731Z,1552597277.731 [CommandLine](IMPORTANT): DUSBL_Hydroid.range (meter) 2019-03-14T21:01:17.731Z,1552597277.731 [CommandLine](IMPORTANT): DUSBL_Hydroid.direction (none) 2019-03-14T21:02:10.517Z,1552597330.517 [RDI_Pathfinder](ERROR): Failed to parse: :RA, 0.00, 0.00, 0.00, 0.00, 0.00 2019-03-14T21:02:35.537Z,1552597355.537 [NAL9602](INFO): SBD MO Status=2, MOMSN=2278, MT Status=2, MTMSN=0 2019-03-14T21:02:35.538Z,1552597355.538 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-03-14T21:03:02.637Z,1552597382.637 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T21:03:02.637Z,1552597382.637 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2019-03-14T21:03:02.637Z,1552597382.637 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-14T21:03:02.643Z,1552597382.643 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T21:03:02.643Z,1552597382.643 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2019-03-14T21:03:02.643Z,1552597382.643 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-14T21:03:02.648Z,1552597382.648 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T21:03:02.649Z,1552597382.649 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4 2019-03-14T21:03:02.649Z,1552597382.649 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-14T21:03:02.674Z,1552597382.674 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-14T21:03:02.674Z,1552597382.674 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-14T21:03:02.675Z,1552597382.675 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-14T21:03:03.083Z,1552597383.083 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-14T21:03:03.084Z,1552597383.084 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2019-03-14T21:03:03.084Z,1552597383.084 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-14T21:03:03.084Z,1552597383.084 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2019-03-14T21:03:03.085Z,1552597383.085 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-14T21:03:03.085Z,1552597383.085 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4 2019-03-14T21:03:03.449Z,1552597383.449 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-14T21:05:03.455Z,1552597503.455 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T21:05:03.455Z,1552597503.455 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2019-03-14T21:05:03.456Z,1552597503.456 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-03-14T21:05:03.458Z,1552597503.457 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T21:05:03.458Z,1552597503.458 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2019-03-14T21:05:03.458Z,1552597503.458 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2019-03-14T21:05:03.460Z,1552597503.460 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-03-14T21:05:03.460Z,1552597503.460 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5 2019-03-14T21:05:03.460Z,1552597503.460 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2019-03-14T21:05:03.471Z,1552597503.471 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-03-14T21:05:03.471Z,1552597503.471 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2019-03-14T21:05:03.471Z,1552597503.471 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2019-03-14T21:05:03.851Z,1552597503.851 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-03-14T21:05:03.851Z,1552597503.851 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2019-03-14T21:05:03.852Z,1552597503.852 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2019-03-14T21:05:03.852Z,1552597503.852 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2019-03-14T21:05:03.852Z,1552597503.852 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2019-03-14T21:05:03.852Z,1552597503.852 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5 2019-03-14T21:05:04.229Z,1552597504.229 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2019-03-14T21:05:08.204Z,1552597508.204 [CommandLine](IMPORTANT): got command show variable turnaround 2019-03-14T21:05:08.375Z,1552597508.375 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultTurnAroundTime (meter_per_second) 2019-03-14T21:05:24.377Z,1552597524.377 [CommandLine](IMPORTANT): got command get DUSBL_Hydroid.defaultTurnAroundTime 2019-03-14T21:05:24.378Z,1552597524.378 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultTurnAroundTime 50.000000 m/s 2019-03-14T21:05:34.563Z,1552597534.563 [CommandLine](IMPORTANT): got command restart application 2019-03-14T21:05:35.566Z,1552597535.566 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-14T21:05:35.566Z,1552597535.566 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-03-14T21:05:35.702Z,1552597535.702 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-03-14T21:05:35.702Z,1552597535.702 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-03-14T21:05:35.703Z,1552597535.703 [CommandLine](INFO): Join timeout helper Thread ID is 1084 2019-03-14T21:05:35.704Z,1552597535.704 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-03-14T21:05:35.704Z,1552597535.704 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-03-14T21:05:35.705Z,1552597535.705 [NavChartDb](INFO): Join timeout helper Thread ID is 1085 2019-03-14T21:05:35.898Z,1552597535.898 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-14T21:05:35.898Z,1552597535.898 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-03-14T21:05:35.902Z,1552597535.902 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler 2019-03-14T21:05:35.902Z,1552597535.902 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2019-03-14T21:05:35.903Z,1552597535.903 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1086 2019-03-14T21:05:35.950Z,1552597535.950 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-14T21:05:35.950Z,1552597535.950 [WetLabsSeaOWL_UV_A](INFO): Powering down 2019-03-14T21:05:35.951Z,1552597535.951 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2019-03-14T21:05:35.962Z,1552597535.962 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler 2019-03-14T21:05:35.962Z,1552597535.962 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-03-14T21:05:35.963Z,1552597535.963 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1087 2019-03-14T21:05:36.026Z,1552597536.026 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-14T21:05:36.027Z,1552597536.027 [CTD_NeilBrown](INFO): Powering down 2019-03-14T21:05:36.038Z,1552597536.038 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-03-14T21:05:36.042Z,1552597536.042 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-03-14T21:05:36.042Z,1552597536.042 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-03-14T21:05:36.043Z,1552597536.043 [Radio_Surface](INFO): Join timeout helper Thread ID is 1088 2019-03-14T21:05:36.311Z,1552597536.311 [Radio_Surface](INFO): Powering down 2019-03-14T21:05:36.313Z,1552597536.313 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-14T21:05:36.313Z,1552597536.313 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-03-14T21:05:36.329Z,1552597536.329 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2019-03-14T21:05:36.329Z,1552597536.329 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-03-14T21:05:36.331Z,1552597536.331 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-14T21:05:36.332Z,1552597536.332 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-03-14T21:05:36.332Z,1552597536.332 [DataOverHttps](INFO): Join timeout helper Thread ID is 1089 2019-03-14T21:05:36.351Z,1552597536.351 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-03-14T21:05:36.351Z,1552597536.351 [logger ThreadHandler](INFO): Thread cancelled. 2019-03-14T21:05:36.351Z,1552597536.351 [logger](INFO): Join timeout helper Thread ID is 1090 2019-03-14T21:05:36.398Z,1552597536.398 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-14T21:05:36.399Z,1552597536.399 [logger ThreadHandler](INFO): Thread cancelled. 2019-03-14T21:05:36.411Z,1552597536.411 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-03-14T21:05:36.411Z,1552597536.411 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-03-14T21:05:36.411Z,1552597536.411 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-03-14T21:05:36.411Z,1552597536.411 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-03-14T21:05:36.412Z,1552597536.412 [controlThread](INFO): Join timeout helper Thread ID is 1091 2019-03-14T21:05:36.514Z,1552597536.514 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-03-14T21:05:36.515Z,1552597536.515 [controlThread](DEBUG): Uninitializing ControlThread 2019-03-14T21:05:36.515Z,1552597536.515 [DUSBL_Hydroid](INFO): Powering down 2019-03-14T21:05:36.607Z,1552597536.607 [Micromodem](INFO): uninitialize 2019-03-14T21:05:36.607Z,1552597536.607 [Micromodem](INFO): Powering down 2019-03-14T21:05:36.702Z,1552597536.702 [NAL9602](INFO): Powering down 2019-03-14T21:05:36.774Z,1552597536.774 [RDI_Pathfinder](INFO): Powering down 2019-03-14T21:05:36.775Z,1552597536.775 [RDI_PathfinderUp](INFO): Powering down 2019-03-14T21:05:36.776Z,1552597536.776 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-03-14T21:05:36.777Z,1552597536.777 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-03-14T21:05:36.778Z,1552597536.778 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-03-14T21:05:36.779Z,1552597536.779 [MissionManager](INFO): Uninitializing Mission Default 2019-03-14T21:05:36.779Z,1552597536.779 [Default] Stopped 2019-03-14T21:05:36.779Z,1552597536.779 [Default](DEBUG): Aggregate::uninitialize Default 2019-03-14T21:05:36.779Z,1552597536.779 [Default:B.GoToSurface] Stopped 2019-03-14T21:05:36.779Z,1552597536.779 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-03-14T21:05:36.779Z,1552597536.779 [Default:CheckIn] Stopped 2019-03-14T21:05:36.779Z,1552597536.779 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-03-14T21:05:36.779Z,1552597536.779 [Default:CheckIn:Read_GPS] Stopped 2019-03-14T21:05:36.781Z,1552597536.781 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-03-14T21:05:36.782Z,1552597536.782 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-03-14T21:05:36.782Z,1552597536.782 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-03-14T21:05:36.782Z,1552597536.782 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-03-14T21:05:36.783Z,1552597536.783 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-03-14T21:05:36.783Z,1552597536.783 [BuoyancyServo](INFO): Powering down 2019-03-14T21:05:36.794Z,1552597536.794 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-03-14T21:05:36.794Z,1552597536.794 [ElevatorServo](INFO): Powering down 2019-03-14T21:05:36.795Z,1552597536.795 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-03-14T21:05:36.795Z,1552597536.795 [MassServo](INFO): Powering down 2019-03-14T21:05:36.796Z,1552597536.796 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-03-14T21:05:36.796Z,1552597536.796 [RudderServo](INFO): Powering down 2019-03-14T21:05:36.797Z,1552597536.797 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-03-14T21:05:36.797Z,1552597536.797 [ThrusterServo](INFO): Powering down 2019-03-14T21:05:36.798Z,1552597536.798 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-03-14T21:05:36.798Z,1552597536.798 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-03-14T21:05:36.798Z,1552597536.798 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-03-14T21:05:36.798Z,1552597536.798 [CBIT](DEBUG): Powering off loads. 2019-03-14T21:05:36.810Z,1552597536.810 [CBIT](DEBUG): Disabling WDT. 2019-03-14T21:05:36.822Z,1552597536.822 [CBIT](DEBUG): Opening all GF detection circuits. 2019-03-14T21:05:36.823Z,1552597536.823 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-03-14T21:05:36.880Z,1552597536.880 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-03-14T21:05:36.889Z,1552597536.889 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-03-14T21:05:36.928Z,1552597536.928 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled. 2019-03-14T21:05:36.931Z,1552597536.931 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled. 2019-03-14T21:05:36.984Z,1552597536.984 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-03-14T21:05:37.052Z,1552597537.052 [logger ThreadHandler](INFO): Thread cancelled.