2018-07-31T18:09:18.561Z,1533060558.561 [Supervisor](DEBUG): Initializing supervisor. 2018-07-31T18:09:18.564Z,1533060558.564 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-07-31T18:09:18.565Z,1533060558.565 [SyncHandler](INFO): Protected caller Thread ID is 1097 2018-07-31T18:09:18.565Z,1533060558.565 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-07-31T18:09:18.566Z,1533060558.566 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-07-31T18:09:18.566Z,1533060558.566 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1098 2018-07-31T18:09:18.569Z,1533060558.569 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-07-31T18:09:18.582Z,1533060558.582 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-07-31T18:09:18.583Z,1533060558.583 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-07-31T18:09:18.583Z,1533060558.583 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1099 2018-07-31T18:09:18.584Z,1533060558.584 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-07-31T18:09:18.585Z,1533060558.585 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-07-31T18:09:18.585Z,1533060558.585 [logger ThreadHandler](INFO): Protected caller Thread ID is 1100 2018-07-31T18:09:18.587Z,1533060558.587 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-07-31T18:09:18.588Z,1533060558.588 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-07-31T18:09:18.589Z,1533060558.589 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-07-31T18:09:18.807Z,1533060558.807 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-07-31T18:09:18.808Z,1533060558.808 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-07-31T18:09:18.961Z,1533060558.961 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-07-31T18:09:18.961Z,1533060558.961 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-07-31T18:09:19.357Z,1533060559.357 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-07-31T18:09:19.357Z,1533060559.357 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-07-31T18:09:19.506Z,1533060559.506 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-07-31T18:09:19.506Z,1533060559.506 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-07-31T18:09:19.590Z,1533060559.590 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-07-31T18:09:19.812Z,1533060559.812 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-07-31T18:09:19.813Z,1533060559.813 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-07-31T18:09:19.916Z,1533060559.916 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-07-31T18:09:19.916Z,1533060559.916 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-07-31T18:09:20.295Z,1533060560.295 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-07-31T18:09:20.296Z,1533060560.296 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-07-31T18:09:20.783Z,1533060560.783 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-07-31T18:09:20.783Z,1533060560.783 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-07-31T18:09:21.126Z,1533060561.126 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-07-31T18:09:21.127Z,1533060561.127 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-07-31T18:09:21.684Z,1533060561.684 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-07-31T18:09:21.685Z,1533060561.685 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-07-31T18:09:21.968Z,1533060561.968 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-07-31T18:09:21.968Z,1533060561.968 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-07-31T18:09:22.191Z,1533060562.191 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-07-31T18:09:22.192Z,1533060562.192 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-07-31T18:09:22.732Z,1533060562.732 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-07-31T18:09:22.732Z,1533060562.732 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-07-31T18:09:22.845Z,1533060562.845 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-07-31T18:09:22.847Z,1533060562.847 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/ 2018-07-31T18:09:22.848Z,1533060562.848 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg 2018-07-31T18:09:23.126Z,1533060563.126 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-07-31T18:09:23.127Z,1533060563.127 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg 2018-07-31T18:09:23.232Z,1533060563.232 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg 2018-07-31T18:09:23.330Z,1533060563.330 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg 2018-07-31T18:09:23.439Z,1533060563.439 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg 2018-07-31T18:09:23.528Z,1533060563.528 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg 2018-07-31T18:09:23.672Z,1533060563.672 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg 2018-07-31T18:09:23.856Z,1533060563.856 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg 2018-07-31T18:09:23.975Z,1533060563.975 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg 2018-07-31T18:09:24.063Z,1533060564.063 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg 2018-07-31T18:09:24.149Z,1533060564.149 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg 2018-07-31T18:09:24.359Z,1533060564.359 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2018-07-31T18:09:24.371Z,1533060564.371 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-07-31T18:09:24.533Z,1533060564.533 [SBIT](DEBUG): Construct Startup Built In Test. 2018-07-31T18:09:24.565Z,1533060564.565 [SBIT] Loaded 2018-07-31T18:09:24.565Z,1533060564.565 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-07-31T18:09:24.566Z,1533060564.566 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-07-31T18:09:24.594Z,1533060564.594 [IBIT] Loaded 2018-07-31T18:09:24.594Z,1533060564.594 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-07-31T18:09:24.598Z,1533060564.598 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-07-31T18:09:24.760Z,1533060564.760 [CBIT] Loaded 2018-07-31T18:09:24.761Z,1533060564.761 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-07-31T18:09:24.761Z,1533060564.761 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-07-31T18:09:24.762Z,1533060564.762 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-07-31T18:09:24.830Z,1533060564.830 [VerticalControl](DEBUG): Construct VerticalControl. 2018-07-31T18:09:24.934Z,1533060564.934 [VerticalControl] Loaded 2018-07-31T18:09:24.934Z,1533060564.934 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-07-31T18:09:24.935Z,1533060564.935 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-07-31T18:09:24.998Z,1533060564.998 [HorizontalControl] Loaded 2018-07-31T18:09:24.999Z,1533060564.999 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-07-31T18:09:24.999Z,1533060564.999 [SpeedControl](DEBUG): Construct SpeedControl. 2018-07-31T18:09:25.005Z,1533060565.005 [SpeedControl] Loaded 2018-07-31T18:09:25.006Z,1533060565.006 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-07-31T18:09:25.006Z,1533060565.006 [LoopControl](DEBUG): Construct LoopControl. 2018-07-31T18:09:25.007Z,1533060565.007 [LoopControl] Loaded 2018-07-31T18:09:25.007Z,1533060565.007 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-07-31T18:09:25.008Z,1533060565.008 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-07-31T18:09:25.008Z,1533060565.008 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-07-31T18:09:25.050Z,1533060565.050 [DepthRateCalculator] Loaded 2018-07-31T18:09:25.050Z,1533060565.050 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-07-31T18:09:25.056Z,1533060565.056 [PitchRateCalculator] Loaded 2018-07-31T18:09:25.056Z,1533060565.056 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-07-31T18:09:25.073Z,1533060565.073 [SpeedCalculator] Loaded 2018-07-31T18:09:25.073Z,1533060565.073 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-07-31T18:09:25.095Z,1533060565.095 [TempGradientCalculator] Loaded 2018-07-31T18:09:25.096Z,1533060565.096 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-07-31T18:09:25.112Z,1533060565.112 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2018-07-31T18:09:25.112Z,1533060565.112 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2018-07-31T18:09:25.117Z,1533060565.117 [YawRateCalculator] Loaded 2018-07-31T18:09:25.118Z,1533060565.118 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-07-31T18:09:25.148Z,1533060565.148 [ElevatorOffsetCalculator] Loaded 2018-07-31T18:09:25.149Z,1533060565.149 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2018-07-31T18:09:25.149Z,1533060565.149 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-07-31T18:09:25.150Z,1533060565.150 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-07-31T18:09:25.235Z,1533060565.235 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2018-07-31T18:09:25.235Z,1533060565.235 [StratificationFrontDetector](DEBUG): (re)initializing 2018-07-31T18:09:25.235Z,1533060565.235 [StratificationFrontDetector] Loaded 2018-07-31T18:09:25.235Z,1533060565.235 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2018-07-31T18:09:25.236Z,1533060565.236 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-07-31T18:09:25.237Z,1533060565.237 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-07-31T18:09:25.485Z,1533060565.485 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-07-31T18:09:25.486Z,1533060565.486 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-07-31T18:09:25.585Z,1533060565.585 [DeadReckonUsingMultipleVelocitySources] Loaded 2018-07-31T18:09:25.585Z,1533060565.585 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2018-07-31T18:09:25.642Z,1533060565.642 [DeadReckonUsingSpeedCalculator] Loaded 2018-07-31T18:09:25.642Z,1533060565.642 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-07-31T18:09:25.888Z,1533060565.888 [DeadReckonWithRespectToSeafloor] Loaded 2018-07-31T18:09:25.888Z,1533060565.888 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2018-07-31T18:09:25.950Z,1533060565.950 [NavChart] Loaded 2018-07-31T18:09:25.950Z,1533060565.950 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-07-31T18:09:25.955Z,1533060565.955 [UniversalFixResidualReporter] Loaded 2018-07-31T18:09:25.955Z,1533060565.955 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-07-31T18:09:25.956Z,1533060565.956 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-07-31T18:09:25.956Z,1533060565.956 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-07-31T18:09:25.969Z,1533060565.969 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-07-31T18:09:25.970Z,1533060565.970 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-07-31T18:09:26.127Z,1533060566.127 [Aanderaa_O2] Loaded 2018-07-31T18:09:26.128Z,1533060566.128 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-07-31T18:09:26.179Z,1533060566.179 [WetLabsBB2FL] Loaded 2018-07-31T18:09:26.180Z,1533060566.180 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-07-31T18:09:26.181Z,1533060566.181 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406D44E0 2018-07-31T18:09:26.181Z,1533060566.181 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1179 2018-07-31T18:09:26.182Z,1533060566.182 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-07-31T18:09:26.182Z,1533060566.182 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-07-31T18:09:26.475Z,1533060566.475 [AHRS_M2] Loaded 2018-07-31T18:09:26.476Z,1533060566.476 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2018-07-31T18:09:26.545Z,1533060566.545 [DataOverHttps] Loaded 2018-07-31T18:09:26.545Z,1533060566.545 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-07-31T18:09:26.560Z,1533060566.560 [Depth_Keller] Loaded 2018-07-31T18:09:26.560Z,1533060566.560 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-07-31T18:09:26.565Z,1533060566.565 [DropWeight] Loaded 2018-07-31T18:09:26.565Z,1533060566.565 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-07-31T18:09:26.660Z,1533060566.660 [NAL9602] Loaded 2018-07-31T18:09:26.660Z,1533060566.660 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-07-31T18:09:26.666Z,1533060566.666 [Onboard] Loaded 2018-07-31T18:09:26.666Z,1533060566.666 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-07-31T18:09:26.673Z,1533060566.673 [Radio_Surface] Loaded 2018-07-31T18:09:26.674Z,1533060566.674 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-07-31T18:09:26.675Z,1533060566.675 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4092A4E0 2018-07-31T18:09:26.675Z,1533060566.675 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1180 2018-07-31T18:09:26.705Z,1533060566.705 [RDI_Pathfinder] Loaded 2018-07-31T18:09:26.705Z,1533060566.705 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2018-07-31T18:09:26.712Z,1533060566.712 [SCPI] Loaded 2018-07-31T18:09:26.712Z,1533060566.712 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread. 2018-07-31T18:09:28.623Z,1533060568.623 [BPC1] Loaded 2018-07-31T18:09:28.623Z,1533060568.623 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-07-31T18:09:28.623Z,1533060568.623 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-07-31T18:09:28.624Z,1533060568.624 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-07-31T18:09:28.724Z,1533060568.724 [BuoyancyServo] Loaded 2018-07-31T18:09:28.724Z,1533060568.724 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-07-31T18:09:28.737Z,1533060568.737 [ElevatorServo] Loaded 2018-07-31T18:09:28.737Z,1533060568.737 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-07-31T18:09:28.757Z,1533060568.757 [MassServo] Loaded 2018-07-31T18:09:28.758Z,1533060568.758 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-07-31T18:09:28.890Z,1533060568.890 [RudderServo] Loaded 2018-07-31T18:09:28.890Z,1533060568.890 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-07-31T18:09:28.913Z,1533060568.913 [ThrusterServo] Loaded 2018-07-31T18:09:28.913Z,1533060568.913 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-07-31T18:09:28.914Z,1533060568.914 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-07-31T18:09:28.914Z,1533060568.914 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-07-31T18:09:29.072Z,1533060569.072 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-07-31T18:09:29.072Z,1533060569.072 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-07-31T18:09:29.097Z,1533060569.097 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-07-31T18:09:29.101Z,1533060569.101 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-07-31T18:09:29.102Z,1533060569.102 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-07-31T18:09:29.109Z,1533060569.109 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-07-31T18:09:29.110Z,1533060569.110 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 409D34E0 2018-07-31T18:09:29.110Z,1533060569.110 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1181 2018-07-31T18:09:29.115Z,1533060569.115 [Supervisor](INFO): Main Thread ID is 795 2018-07-31T18:09:29.115Z,1533060569.115 [Supervisor](DEBUG): Running supervisor. 2018-07-31T18:09:29.116Z,1533060569.116 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1182 2018-07-31T18:09:29.118Z,1533060569.118 [controlThread ThreadHandler](INFO): Handler Thread ID is 1183 2018-07-31T18:09:29.119Z,1533060569.119 [controlThread](DEBUG): Initializing ControlThread 2018-07-31T18:09:29.120Z,1533060569.120 [SBIT](INFO): Initialize SBIT Component. 2018-07-31T18:09:29.120Z,1533060569.120 [SBIT](IMPORTANT): git: 2018-07-31 2018-07-31T18:09:29.120Z,1533060569.120 [SBIT](INFO): git hash: f162f5471e539e4e3a23169adacc16f438d84f2d 2018-07-31T18:09:29.121Z,1533060569.121 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-07-31T18:09:29.122Z,1533060569.122 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 2018-07-31T18:09:29.123Z,1533060569.123 [SBIT](INFO): Beginning SBIT in 24.000000 seconds. 2018-07-31T18:09:29.124Z,1533060569.124 [IBIT](INFO): Initialize IBIT Component. 2018-07-31T18:09:29.125Z,1533060569.125 [CBIT](DEBUG): Initialize CBIT Component. 2018-07-31T18:09:29.125Z,1533060569.125 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2018-07-31T18:09:29.126Z,1533060569.126 [logger ThreadHandler](INFO): Handler Thread ID is 1184 2018-07-31T18:09:29.152Z,1533060569.152 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1185 2018-07-31T18:09:29.172Z,1533060569.172 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1186 2018-07-31T18:09:29.178Z,1533060569.178 [Radio_Surface](INFO): Powering up 2018-07-31T18:09:29.196Z,1533060569.196 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1187 2018-07-31T18:09:29.199Z,1533060569.199 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-07-31T18:09:29.199Z,1533060569.199 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-07-31T18:09:29.200Z,1533060569.200 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-07-31T18:09:29.200Z,1533060569.200 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-07-31T18:09:29.200Z,1533060569.200 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-07-31T18:09:29.200Z,1533060569.200 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-07-31T18:09:29.200Z,1533060569.200 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-07-31T18:09:29.200Z,1533060569.200 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-07-31T18:09:29.201Z,1533060569.201 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-07-31T18:09:29.201Z,1533060569.201 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-07-31T18:09:29.201Z,1533060569.201 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-07-31T18:09:29.201Z,1533060569.201 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-07-31T18:09:29.201Z,1533060569.201 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-07-31T18:09:29.202Z,1533060569.202 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-07-31T18:09:29.202Z,1533060569.202 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-07-31T18:09:29.202Z,1533060569.202 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-07-31T18:09:29.232Z,1533060569.232 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-07-31T18:09:29.233Z,1533060569.233 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-07-31T18:09:29.234Z,1533060569.234 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-07-31T18:09:29.235Z,1533060569.235 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-07-31T18:09:29.235Z,1533060569.235 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-07-31T18:09:29.235Z,1533060569.235 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-07-31T18:09:29.236Z,1533060569.236 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-07-31T18:09:29.236Z,1533060569.236 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-07-31T18:09:29.237Z,1533060569.237 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2018-07-31T18:09:29.237Z,1533060569.237 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-07-31T18:09:29.238Z,1533060569.238 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2018-07-31T18:09:29.239Z,1533060569.239 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-07-31T18:09:29.239Z,1533060569.239 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-07-31T18:09:29.240Z,1533060569.240 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-07-31T18:09:29.240Z,1533060569.240 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-07-31T18:09:29.241Z,1533060569.241 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-07-31T18:09:29.241Z,1533060569.241 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-07-31T18:09:29.241Z,1533060569.241 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-07-31T18:09:29.242Z,1533060569.242 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-07-31T18:09:29.242Z,1533060569.242 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-07-31T18:09:29.243Z,1533060569.243 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-07-31T18:09:29.243Z,1533060569.243 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-07-31T18:09:29.253Z,1533060569.253 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-07-31T18:09:29.280Z,1533060569.280 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-07-31T18:09:29.324Z,1533060569.324 [MissionManager](DEBUG): 2018-07-31T18:09:29.325Z,1533060569.325 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-07-31T18:09:29.398Z,1533060569.398 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-07-31T18:09:29.399Z,1533060569.399 [Default:A.Wait](DEBUG): Construct Wait. 2018-07-31T18:09:29.425Z,1533060569.425 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-07-31T18:09:29.449Z,1533060569.449 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-07-31T18:09:29.468Z,1533060569.468 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-07-31T18:09:29.474Z,1533060569.474 [Default:E.Execute](DEBUG): Construct Execute. 2018-07-31T18:09:29.493Z,1533060569.493 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn 300 Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2018-07-31T18:09:29.508Z,1533060569.508 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,SCPI,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-07-31T18:09:29.562Z,1533060569.562 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2018-07-31T18:09:29.679Z,1533060569.679 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-07-31T18:09:29.718Z,1533060569.718 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar 2018-07-31T18:09:29.984Z,1533060569.984 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-07-31T18:09:30.080Z,1533060570.080 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:30.104Z,1533060570.104 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:30.116Z,1533060570.116 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:30.216Z,1533060570.216 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-31T18:09:30.224Z,1533060570.224 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-31T18:09:30.238Z,1533060570.238 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-07-31T18:09:30.244Z,1533060570.244 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-07-31T18:09:30.258Z,1533060570.258 [MassServo](DEBUG): Initializing EZServoServo. 2018-07-31T18:09:30.264Z,1533060570.264 [MassServo](DEBUG): Initializing MassServo. 2018-07-31T18:09:30.270Z,1533060570.270 [RudderServo](DEBUG): Initializing EZServoServo. 2018-07-31T18:09:30.276Z,1533060570.276 [RudderServo](DEBUG): Initializing RudderServo. 2018-07-31T18:09:30.286Z,1533060570.286 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-07-31T18:09:30.292Z,1533060570.292 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-07-31T18:09:30.532Z,1533060570.532 [DepthRateCalculator](ERROR): Depth measurement is not active 2018-07-31T18:09:30.536Z,1533060570.536 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:30.537Z,1533060570.537 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:30.538Z,1533060570.538 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:30.837Z,1533060570.837 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:30.838Z,1533060570.838 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:30.839Z,1533060570.839 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:31.188Z,1533060571.188 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-07-31T18:09:31.188Z,1533060571.188 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-07-31T18:09:31.188Z,1533060571.188 [BuoyancyServo] Communications Fault, FailCount= 1 2018-07-31T18:09:31.188Z,1533060571.188 [BuoyancyServo](ERROR): Communications Fault 2018-07-31T18:09:31.404Z,1533060571.404 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-07-31T18:09:31.460Z,1533060571.460 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:31.461Z,1533060571.461 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:31.462Z,1533060571.462 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:31.485Z,1533060571.485 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-07-31T18:09:31.486Z,1533060571.486 [BuoyancyServo](INFO): Powering down 2018-07-31T18:09:31.844Z,1533060571.844 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:31.845Z,1533060571.845 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:31.846Z,1533060571.846 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:32.240Z,1533060572.240 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:32.241Z,1533060572.241 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:32.242Z,1533060572.242 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:32.732Z,1533060572.732 [Aanderaa_O2](INFO): Powering down 2018-07-31T18:09:32.836Z,1533060572.836 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:32.837Z,1533060572.837 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:32.838Z,1533060572.838 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:33.120Z,1533060573.120 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:33.121Z,1533060573.121 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:33.122Z,1533060573.122 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:33.468Z,1533060573.468 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:33.469Z,1533060573.469 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:33.470Z,1533060573.470 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:33.509Z,1533060573.509 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-07-31T18:09:33.509Z,1533060573.509 [BuoyancyServo] No Fault, FailCount= 1 2018-07-31T18:09:33.868Z,1533060573.868 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:33.869Z,1533060573.869 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:33.870Z,1533060573.870 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:33.885Z,1533060573.885 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-07-31T18:09:34.004Z,1533060574.004 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-07-31T18:09:34.320Z,1533060574.320 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:34.321Z,1533060574.321 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:34.322Z,1533060574.322 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:34.750Z,1533060574.750 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:34.751Z,1533060574.751 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:34.760Z,1533060574.760 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:35.153Z,1533060575.153 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:35.154Z,1533060575.154 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:35.155Z,1533060575.155 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:35.553Z,1533060575.553 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:35.554Z,1533060575.554 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:35.554Z,1533060575.554 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:35.953Z,1533060575.953 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:35.954Z,1533060575.954 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:35.955Z,1533060575.955 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:36.353Z,1533060576.353 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:36.354Z,1533060576.354 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:36.354Z,1533060576.354 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:36.729Z,1533060576.729 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:36.730Z,1533060576.730 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:36.731Z,1533060576.731 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:37.125Z,1533060577.125 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:37.126Z,1533060577.126 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:37.127Z,1533060577.127 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:37.553Z,1533060577.553 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:37.554Z,1533060577.554 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:37.555Z,1533060577.555 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2018-07-31T18:09:43.536Z,1533060583.536 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2018-07-31T18:09:53.583Z,1533060593.583 [SBIT](IMPORTANT): Beginning Startup BIT 2018-07-31T18:09:53.600Z,1533060593.600 [CBIT](IMPORTANT): Beginning ground fault scan 2018-07-31T18:09:56.874Z,1533060596.874 [NAL9602](INFO): Powering up NAL9602 2018-07-31T18:10:04.629Z,1533060604.629 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.018806 CHAN A1 (24V): -0.022189 CHAN A2 (12V): -0.007165 CHAN A3 (5V): -0.001871 CHAN B0 (3.3V): 0.000263 CHAN B1 (3.15aV): 0.000170 CHAN B2 (3.15bV): 0.000001 CHAN B3 (GND): 0.002368 OPEN: 0.005299 Full Scale Calc: 4.765 mA, -1.589 mA 2018-07-31T18:10:08.093Z,1533060608.093 [NAL9602](INFO): NAL9602 initialized 2018-07-31T18:10:27.883Z,1533060627.883 [NAL9602](INFO): SBD MO Status=0, MOMSN=578, MT Status=0, MTMSN=0 2018-07-31T18:10:27.883Z,1533060627.883 [NAL9602](INFO): No messages in MT queue 2018-07-31T18:10:31.890Z,1533060631.890 [NAL9602](INFO): GPS fix at 20180731T181030: (36.802663, -121.786924) 2018-07-31T18:10:47.712Z,1533060647.712 [SBIT](IMPORTANT): SBIT PASSED 2018-07-31T18:10:47.754Z,1533060647.754 [CommandLine](IMPORTANT): got command configSet list 2018-07-31T18:10:47.754Z,1533060647.754 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2018-07-31T18:10:47.755Z,1533060647.755 [CommandLine](IMPORTANT): AHRS_M2.readAccelerations=1 bool; 2018-07-31T18:10:47.756Z,1533060647.756 [CommandLine](IMPORTANT): AHRS_M2.readMagnetics=1 bool; 2018-07-31T18:10:47.756Z,1533060647.756 [CommandLine](IMPORTANT): DeadReckonWithRespectToSeafloor.loadAtStartup=1 bool; 2018-07-31T18:10:47.756Z,1533060647.756 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter; 2018-07-31T18:10:47.756Z,1533060647.756 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=160 cubic_centimeter; 2018-07-31T18:10:47.756Z,1533060647.756 [CommandLine](IMPORTANT): VerticalControl.massDefault=-1 millimeter; 2018-07-31T18:10:48.059Z,1533060648.059 [MissionManager](IMPORTANT): Started mission Startup 2018-07-31T18:10:48.060Z,1533060648.060 [Startup] Running Loop=1 2018-07-31T18:10:48.060Z,1533060648.060 [Startup](DEBUG): Aggregate::initialize Startup 2018-07-31T18:10:48.060Z,1533060648.060 [Startup:A.GoToSurface] Running Loop=1 2018-07-31T18:10:48.060Z,1533060648.060 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-07-31T18:10:48.061Z,1533060648.061 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-07-31T18:10:48.061Z,1533060648.061 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-07-31T18:10:48.062Z,1533060648.062 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-07-31T18:10:48.062Z,1533060648.062 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-07-31T18:10:48.062Z,1533060648.062 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-07-31T18:10:48.069Z,1533060648.069 [Startup:StartupSatComms] Running Loop=1 2018-07-31T18:10:48.069Z,1533060648.069 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2018-07-31T18:10:48.069Z,1533060648.069 [Startup:StartupSatComms:A] Running Loop=1 2018-07-31T18:10:48.479Z,1533060648.479 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2018-07-31T18:10:50.016Z,1533060650.016 [NAL9602](INFO): GPS fix at 20180731T181048: (36.802667, -121.786917) 2018-07-31T18:10:50.079Z,1533060650.079 [Startup:StartupSatComms:A] Stopped 2018-07-31T18:10:50.079Z,1533060650.079 [Startup:StartupSatComms:B] Running Loop=1 2018-07-31T18:10:50.525Z,1533060650.525 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-07-31T18:11:20.606Z,1533060680.606 [NAL9602](INFO): SBD MO Status=2, MOMSN=579, MT Status=2, MTMSN=0 2018-07-31T18:11:20.606Z,1533060680.606 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-07-31T18:11:43.551Z,1533060703.551 [NAL9602](INFO): SBD MO Status=2, MOMSN=579, MT Status=2, MTMSN=0 2018-07-31T18:11:43.551Z,1533060703.551 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-07-31T18:11:50.850Z,1533060710.850 [Startup:StartupSatComms:B](INFO): Timed out from 2018-07-31T18:10:50.1Z 2018-07-31T18:11:50.850Z,1533060710.850 [Startup:StartupSatComms:B] Stopped 2018-07-31T18:11:50.850Z,1533060710.850 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2018-07-31T18:11:50.850Z,1533060710.850 [Startup:StartupSatComms] Stopped 2018-07-31T18:11:50.850Z,1533060710.850 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2018-07-31T18:11:50.851Z,1533060710.851 [Startup](INFO): Completed Startup 2018-07-31T18:11:50.856Z,1533060710.856 [MissionManager](INFO): Startup is completed. 2018-07-31T18:11:50.856Z,1533060710.856 [MissionManager](INFO): Uninitializing Mission Startup 2018-07-31T18:11:50.856Z,1533060710.856 [Startup] Stopped 2018-07-31T18:11:50.856Z,1533060710.856 [Startup](DEBUG): Aggregate::uninitialize Startup 2018-07-31T18:11:50.856Z,1533060710.856 [Startup:A.GoToSurface] Stopped 2018-07-31T18:11:50.856Z,1533060710.856 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-07-31T18:11:50.993Z,1533060710.993 [MissionManager](IMPORTANT): Started mission Default 2018-07-31T18:11:50.993Z,1533060710.993 [Default] Running Loop=1 2018-07-31T18:11:50.994Z,1533060710.994 [Default](DEBUG): Aggregate::initialize Default 2018-07-31T18:11:50.994Z,1533060710.994 [Default:B.GoToSurface] Running Loop=1 2018-07-31T18:11:50.994Z,1533060710.994 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-07-31T18:11:50.994Z,1533060710.994 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-07-31T18:11:50.994Z,1533060710.994 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-07-31T18:11:50.995Z,1533060710.995 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-07-31T18:11:50.999Z,1533060710.999 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-07-31T18:11:50.000Z,1533060711.000 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-07-31T18:11:51.000Z,1533060711.000 [Default:A.Wait] Running Loop=1 2018-07-31T18:11:51.000Z,1533060711.000 [Default:A.Wait](DEBUG): Initialize Wait Component. 2018-07-31T18:12:04.168Z,1533060724.168 [Default:A.Wait](INFO): Done Waiting. 2018-07-31T18:12:04.168Z,1533060724.168 [Default:A.Wait] Stopped 2018-07-31T18:12:04.168Z,1533060724.168 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2018-07-31T18:12:04.568Z,1533060724.568 [Default:CheckIn] Running Loop=1 2018-07-31T18:12:04.568Z,1533060724.568 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-07-31T18:12:04.568Z,1533060724.568 [Default:CheckIn:Read_GPS] Running Loop=1 2018-07-31T18:12:04.983Z,1533060724.983 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2018-07-31T18:12:10.499Z,1533060730.499 [NAL9602](INFO): SBD MO Status=2, MOMSN=579, MT Status=2, MTMSN=0 2018-07-31T18:12:10.499Z,1533060730.499 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-07-31T18:12:11.717Z,1533060731.717 [NAL9602](INFO): GPS fix at 20180731T181211: (36.802693, -121.786897) 2018-07-31T18:12:11.785Z,1533060731.785 [Default:CheckIn:Read_GPS] Stopped 2018-07-31T18:12:11.785Z,1533060731.785 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-07-31T18:12:12.166Z,1533060732.166 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2018-07-31T18:12:19.888Z,1533060739.888 [NAL9602](INFO): SBD MO Status=1, MOMSN=579, MT Status=0, MTMSN=0 2018-07-31T18:12:19.936Z,1533060739.936 [NAL9602](INFO): Sent 25 bytes from file Logs/20180731T170055/Courier0028.lzma 2018-07-31T18:12:19.936Z,1533060739.936 [NAL9602](INFO): Packets left to send: 0 2018-07-31T18:12:19.944Z,1533060739.944 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180731T170055/Courier0028.lzma.parts/0000.sbd 2018-07-31T18:12:19.944Z,1533060739.944 [NAL9602](DEBUG): Completed sending Logs/20180731T170055/Courier0028.lzma 2018-07-31T18:12:29.319Z,1533060749.319 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-07-31T18:12:29.320Z,1533060749.320 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2018-07-31T18:12:29.320Z,1533060749.320 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-07-31T18:12:29.383Z,1533060749.383 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-07-31T18:12:29.736Z,1533060749.736 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-07-31T18:12:29.736Z,1533060749.736 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2018-07-31T18:12:30.078Z,1533060750.078 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-07-31T18:12:30.079Z,1533060750.079 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-07-31T18:12:30.083Z,1533060750.083 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-07-31T18:12:36.035Z,1533060756.035 [NAL9602](INFO): SBD MO Status=2, MOMSN=580, MT Status=2, MTMSN=0 2018-07-31T18:12:36.035Z,1533060756.035 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-07-31T18:12:55.823Z,1533060775.823 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003938 2018-07-31T18:13:02.142Z,1533060782.142 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20180731T180918/Courier0000.lzma 2018-07-31T18:13:02.920Z,1533060782.920 [DataOverHttps](INFO): Moved sent file to Logs/20180731T180918/Courier0000.lzma.bak 2018-07-31T18:13:02.920Z,1533060782.920 [DataOverHttps](INFO): SBD MOMSN=8388384 2018-07-31T18:13:02.964Z,1533060782.964 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-32768,-32768,V 2018-07-31T18:13:11.933Z,1533060791.933 [NAL9602](INFO): SBD MO Status=2, MOMSN=580, MT Status=2, MTMSN=0 2018-07-31T18:13:11.933Z,1533060791.933 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-07-31T18:13:12.487Z,1533060792.487 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20180731T180918/Courier0004.lzma 2018-07-31T18:13:13.299Z,1533060793.299 [DataOverHttps](INFO): Moved sent file to Logs/20180731T180918/Courier0004.lzma.bak 2018-07-31T18:13:13.300Z,1533060793.300 [DataOverHttps](INFO): SBD MOMSN=8388388 2018-07-31T18:13:22.785Z,1533060802.785 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20180731T170055/Express0029.lzma 2018-07-31T18:13:23.580Z,1533060803.580 [DataOverHttps](INFO): Moved sent file to Logs/20180731T170055/Express0029.lzma.bak 2018-07-31T18:13:23.580Z,1533060803.580 [DataOverHttps](INFO): SBD MOMSN=8388391 2018-07-31T18:13:32.734Z,1533060812.734 [DataOverHttps](INFO): Sending 877 bytes from file Logs/20180731T180918/Express0001.lzma 2018-07-31T18:13:33.510Z,1533060813.510 [DataOverHttps](INFO): Moved sent file to Logs/20180731T180918/Express0001.lzma.bak 2018-07-31T18:13:33.510Z,1533060813.510 [DataOverHttps](INFO): SBD MOMSN=8388398 2018-07-31T18:13:33.561Z,1533060813.561 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-32768,-32768,V 2018-07-31T18:13:42.526Z,1533060822.526 [NAL9602](INFO): Powering down 2018-07-31T18:13:42.734Z,1533060822.734 [DataOverHttps](INFO): Sending 94 bytes from file Logs/20180731T180918/Express0005.lzma 2018-07-31T18:13:43.506Z,1533060823.506 [DataOverHttps](INFO): Moved sent file to Logs/20180731T180918/Express0005.lzma.bak 2018-07-31T18:13:43.506Z,1533060823.506 [DataOverHttps](INFO): SBD MOMSN=8388417 2018-07-31T18:13:43.538Z,1533060823.538 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2018-07-31T18:13:44.031Z,1533060824.031 [Default:CheckIn:Read_Iridium] Stopped 2018-07-31T18:13:44.031Z,1533060824.031 [Default:CheckIn:C.Wait] Running Loop=1 2018-07-31T18:13:44.031Z,1533060824.031 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-07-31T18:15:30.305Z,1533060930.305 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-07-31T18:15:30.305Z,1533060930.305 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2018-07-31T18:15:30.305Z,1533060930.305 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-07-31T18:15:30.332Z,1533060930.332 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-07-31T18:15:30.724Z,1533060930.724 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-07-31T18:15:30.725Z,1533060930.725 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2018-07-31T18:15:31.074Z,1533060931.074 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-07-31T18:15:31.075Z,1533060931.075 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-07-31T18:15:31.075Z,1533060931.075 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-07-31T18:18:31.083Z,1533061111.083 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-07-31T18:18:31.088Z,1533061111.088 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3 2018-07-31T18:18:31.088Z,1533061111.088 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-07-31T18:18:31.114Z,1533061111.114 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-07-31T18:18:31.512Z,1533061111.512 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-07-31T18:18:31.512Z,1533061111.512 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3 2018-07-31T18:18:31.880Z,1533061111.880 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-07-31T18:18:31.880Z,1533061111.880 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-07-31T18:18:31.880Z,1533061111.880 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-07-31T18:18:44.698Z,1533061124.698 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-07-31T18:18:44.698Z,1533061124.698 [Default:CheckIn:C.Wait] Stopped 2018-07-31T18:18:44.698Z,1533061124.698 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-07-31T18:18:44.698Z,1533061124.698 [Default:CheckIn:D] Running Loop=1 2018-07-31T18:18:45.082Z,1533061125.082 [Default:CheckIn:D] Stopped 2018-07-31T18:18:45.082Z,1533061125.082 [Default:CheckIn:E] Running Loop=1 2018-07-31T18:18:45.501Z,1533061125.501 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.901441 min 2018-07-31T18:18:45.503Z,1533061125.503 [Default:CheckIn:E] Stopped 2018-07-31T18:18:45.503Z,1533061125.503 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-07-31T18:18:45.503Z,1533061125.503 [Default:CheckIn] Stopped 2018-07-31T18:18:45.507Z,1533061125.507 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-07-31T18:18:45.508Z,1533061125.508 [Default:CheckIn](INFO): Running loop #2 2018-07-31T18:18:45.508Z,1533061125.508 [Default:CheckIn] Running Loop=2 2018-07-31T18:18:45.508Z,1533061125.508 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-07-31T18:18:45.508Z,1533061125.508 [Default:CheckIn:Read_GPS] Running Loop=1 2018-07-31T18:18:48.850Z,1533061128.850 [NAL9602](INFO): Powering up 2018-07-31T18:19:00.029Z,1533061140.029 [NAL9602](INFO): NAL9602 initialized 2018-07-31T18:19:32.038Z,1533061172.038 [NAL9602](INFO): SBD MO Status=2, MOMSN=580, MT Status=2, MTMSN=0 2018-07-31T18:19:32.039Z,1533061172.039 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-07-31T18:19:50.567Z,1533061190.567 [NAL9602](INFO): SBD MO Status=2, MOMSN=580, MT Status=2, MTMSN=0 2018-07-31T18:19:50.567Z,1533061190.567 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-07-31T18:20:00.562Z,1533061200.562 [NAL9602](INFO): SBD MO Status=0, MOMSN=580, MT Status=0, MTMSN=0 2018-07-31T18:20:00.563Z,1533061200.563 [NAL9602](INFO): No messages in MT queue 2018-07-31T18:20:01.766Z,1533061201.766 [NAL9602](INFO): GPS fix at 20180731T182000: (36.808592, -121.785389) 2018-07-31T18:20:01.859Z,1533061201.859 [Default:CheckIn:Read_GPS] Stopped 2018-07-31T18:20:01.859Z,1533061201.859 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-07-31T18:20:06.362Z,1533061206.362 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20180731T180918/Courier0007.lzma 2018-07-31T18:20:07.128Z,1533061207.128 [DataOverHttps](INFO): Moved sent file to Logs/20180731T180918/Courier0007.lzma.bak 2018-07-31T18:20:07.128Z,1533061207.128 [DataOverHttps](INFO): SBD MOMSN=8388428 2018-07-31T18:20:16.168Z,1533061216.168 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20180731T180918/Express0008.lzma 2018-07-31T18:20:16.944Z,1533061216.944 [DataOverHttps](INFO): Moved sent file to Logs/20180731T180918/Express0008.lzma.bak 2018-07-31T18:20:16.944Z,1533061216.944 [DataOverHttps](INFO): SBD MOMSN=8388431 2018-07-31T18:20:17.479Z,1533061217.479 [Default:CheckIn:Read_Iridium] Stopped 2018-07-31T18:20:17.480Z,1533061217.480 [Default:CheckIn:C.Wait] Running Loop=1 2018-07-31T18:20:17.480Z,1533061217.480 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-07-31T18:20:36.443Z,1533061236.443 [NAL9602](INFO): Powering down 2018-07-31T18:21:32.183Z,1533061292.183 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-07-31T18:21:32.183Z,1533061292.183 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2018-07-31T18:21:32.183Z,1533061292.183 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-07-31T18:21:32.210Z,1533061292.210 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-07-31T18:21:32.666Z,1533061292.666 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-07-31T18:21:32.666Z,1533061292.666 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2018-07-31T18:21:33.010Z,1533061293.010 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-07-31T18:21:33.010Z,1533061293.010 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-07-31T18:21:33.011Z,1533061293.011 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-07-31T18:24:33.247Z,1533061473.247 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-07-31T18:24:33.247Z,1533061473.247 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2018-07-31T18:24:33.251Z,1533061473.251 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-07-31T18:24:33.299Z,1533061473.299 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-07-31T18:24:33.679Z,1533061473.679 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-07-31T18:24:33.680Z,1533061473.680 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2018-07-31T18:24:34.030Z,1533061474.030 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-07-31T18:24:34.031Z,1533061474.031 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-07-31T18:24:34.035Z,1533061474.035 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-07-31T18:25:18.040Z,1533061518.040 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-07-31T18:25:18.040Z,1533061518.040 [Default:CheckIn:C.Wait] Stopped 2018-07-31T18:25:18.040Z,1533061518.040 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-07-31T18:25:18.040Z,1533061518.040 [Default:CheckIn:D] Running Loop=1 2018-07-31T18:25:18.456Z,1533061518.456 [Default:CheckIn:D] Stopped 2018-07-31T18:25:18.456Z,1533061518.456 [Default:CheckIn:E] Running Loop=1 2018-07-31T18:25:18.851Z,1533061518.851 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.457709 min 2018-07-31T18:25:18.852Z,1533061518.852 [Default:CheckIn:E] Stopped 2018-07-31T18:25:18.852Z,1533061518.852 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-07-31T18:25:18.852Z,1533061518.852 [Default:CheckIn] Stopped 2018-07-31T18:25:18.852Z,1533061518.852 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-07-31T18:25:18.852Z,1533061518.852 [Default:CheckIn](INFO): Running loop #3 2018-07-31T18:25:18.853Z,1533061518.853 [Default:CheckIn] Running Loop=3 2018-07-31T18:25:18.853Z,1533061518.853 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-07-31T18:25:18.853Z,1533061518.853 [Default:CheckIn:Read_GPS] Running Loop=1 2018-07-31T18:25:22.141Z,1533061522.141 [NAL9602](INFO): Powering up 2018-07-31T18:25:33.309Z,1533061533.309 [NAL9602](INFO): NAL9602 initialized 2018-07-31T18:27:09.939Z,1533061629.939 [NAL9602](INFO): SBD MO Status=2, MOMSN=581, MT Status=2, MTMSN=0 2018-07-31T18:27:09.939Z,1533061629.939 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-07-31T18:27:29.795Z,1533061649.795 [NAL9602](INFO): SBD MO Status=0, MOMSN=581, MT Status=0, MTMSN=0 2018-07-31T18:27:29.795Z,1533061649.795 [NAL9602](INFO): No messages in MT queue 2018-07-31T18:27:34.274Z,1533061654.274 [DeadReckonWithRespectToSeafloor](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-07-31T18:27:34.274Z,1533061654.274 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3 2018-07-31T18:27:34.274Z,1533061654.274 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2018-07-31T18:27:34.302Z,1533061654.302 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2018-07-31T18:27:34.690Z,1533061654.690 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2018-07-31T18:27:34.690Z,1533061654.690 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3 2018-07-31T18:27:35.034Z,1533061655.034 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2018-07-31T18:27:35.034Z,1533061655.034 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2018-07-31T18:27:35.035Z,1533061655.035 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2018-07-31T18:28:23.504Z,1533061703.504 [DataOverHttps](IMPORTANT): SBD MTMSN=20180731T182822 2018-07-31T18:28:23.559Z,1533061703.559 [RDI_Pathfinder](ERROR): Failed to parse: 2018-07-31T18:28:27.751Z,1533061707.751 [DataOverHttps](INFO): Received command:restart logs