2018-11-06T17:27:03.411Z,1541525223.411 [Supervisor](DEBUG): Initializing supervisor.
2018-11-06T17:27:03.413Z,1541525223.413 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-11-06T17:27:03.414Z,1541525223.414 [SyncHandler](INFO): Protected caller Thread ID is 6411
2018-11-06T17:27:03.414Z,1541525223.414 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-11-06T17:27:03.415Z,1541525223.415 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-11-06T17:27:03.415Z,1541525223.415 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6412
2018-11-06T17:27:03.418Z,1541525223.418 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-11-06T17:27:03.430Z,1541525223.430 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-11-06T17:27:03.431Z,1541525223.431 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-11-06T17:27:03.431Z,1541525223.431 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6413
2018-11-06T17:27:03.432Z,1541525223.432 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-11-06T17:27:03.433Z,1541525223.433 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-11-06T17:27:03.433Z,1541525223.433 [logger ThreadHandler](INFO): Protected caller Thread ID is 6414
2018-11-06T17:27:03.435Z,1541525223.435 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-11-06T17:27:03.435Z,1541525223.435 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-11-06T17:27:03.437Z,1541525223.437 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-11-06T17:27:03.777Z,1541525223.777 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-11-06T17:27:03.777Z,1541525223.777 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-11-06T17:27:03.982Z,1541525223.982 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-11-06T17:27:03.983Z,1541525223.983 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-11-06T17:27:04.297Z,1541525224.297 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-11-06T17:27:04.297Z,1541525224.297 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-11-06T17:27:04.445Z,1541525224.445 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-11-06T17:27:04.446Z,1541525224.446 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-11-06T17:27:04.545Z,1541525224.545 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-11-06T17:27:04.545Z,1541525224.545 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-11-06T17:27:05.025Z,1541525225.025 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-11-06T17:27:05.026Z,1541525225.026 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-11-06T17:27:05.499Z,1541525225.499 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-11-06T17:27:05.500Z,1541525225.500 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-11-06T17:27:05.982Z,1541525225.982 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-11-06T17:27:05.983Z,1541525225.983 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-11-06T17:27:06.088Z,1541525226.088 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-11-06T17:27:06.088Z,1541525226.088 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-11-06T17:27:06.184Z,1541525226.184 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-11-06T17:27:06.185Z,1541525226.185 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-11-06T17:27:06.408Z,1541525226.408 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-11-06T17:27:06.408Z,1541525226.408 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-11-06T17:27:06.617Z,1541525226.617 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-11-06T17:27:06.617Z,1541525226.617 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-11-06T17:27:06.978Z,1541525226.978 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-11-06T17:27:06.979Z,1541525226.979 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-11-06T17:27:07.127Z,1541525227.127 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-11-06T17:27:07.128Z,1541525227.128 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-11-06T17:27:07.279Z,1541525227.279 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2018-11-06T17:27:07.280Z,1541525227.280 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2018-11-06T17:27:07.386Z,1541525227.386 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2018-11-06T17:27:07.506Z,1541525227.506 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2018-11-06T17:27:07.601Z,1541525227.601 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2018-11-06T17:27:07.686Z,1541525227.686 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2018-11-06T17:27:07.856Z,1541525227.856 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2018-11-06T17:27:08.041Z,1541525228.041 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2018-11-06T17:27:08.116Z,1541525228.116 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2018-11-06T17:27:08.361Z,1541525228.361 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-11-06T17:27:08.362Z,1541525228.362 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2018-11-06T17:27:08.646Z,1541525228.646 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2018-11-06T17:27:08.862Z,1541525228.862 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2018-11-06T17:27:09.074Z,1541525229.074 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2018-11-06T17:27:09.330Z,1541525229.330 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2018-11-06T17:27:09.330Z,1541525229.330 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-11-06T17:27:09.339Z,1541525229.339 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-11-06T17:27:09.571Z,1541525229.571 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-11-06T17:27:09.572Z,1541525229.572 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-11-06T17:27:09.613Z,1541525229.613 [DepthRateCalculator] Loaded
2018-11-06T17:27:09.613Z,1541525229.613 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-11-06T17:27:09.619Z,1541525229.619 [PitchRateCalculator] Loaded
2018-11-06T17:27:09.619Z,1541525229.619 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-11-06T17:27:09.636Z,1541525229.636 [SpeedCalculator] Loaded
2018-11-06T17:27:09.636Z,1541525229.636 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-11-06T17:27:09.657Z,1541525229.657 [TempGradientCalculator] Loaded
2018-11-06T17:27:09.658Z,1541525229.658 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-11-06T17:27:09.663Z,1541525229.663 [YawRateCalculator] Loaded
2018-11-06T17:27:09.664Z,1541525229.664 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-11-06T17:27:09.703Z,1541525229.703 [ElevatorOffsetCalculator] Loaded
2018-11-06T17:27:09.703Z,1541525229.703 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-11-06T17:27:09.703Z,1541525229.703 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-11-06T17:27:09.704Z,1541525229.704 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-11-06T17:27:09.768Z,1541525229.768 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-11-06T17:27:09.768Z,1541525229.768 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-11-06T17:27:09.878Z,1541525229.878 [BuoyancyServo] Loaded
2018-11-06T17:27:09.879Z,1541525229.879 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-11-06T17:27:09.893Z,1541525229.893 [ElevatorServo] Loaded
2018-11-06T17:27:09.893Z,1541525229.893 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-11-06T17:27:09.908Z,1541525229.908 [MassServo] Loaded
2018-11-06T17:27:09.908Z,1541525229.908 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-11-06T17:27:09.923Z,1541525229.923 [RudderServo] Loaded
2018-11-06T17:27:09.924Z,1541525229.924 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-11-06T17:27:09.938Z,1541525229.938 [ThrusterServo] Loaded
2018-11-06T17:27:09.938Z,1541525229.938 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-11-06T17:27:09.938Z,1541525229.938 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-11-06T17:27:09.939Z,1541525229.939 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-11-06T17:27:09.952Z,1541525229.952 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-11-06T17:27:09.953Z,1541525229.953 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-11-06T17:27:10.049Z,1541525230.049 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-11-06T17:27:10.050Z,1541525230.050 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-11-06T17:27:10.333Z,1541525230.333 [DataOverHttps] Loaded
2018-11-06T17:27:10.333Z,1541525230.333 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-11-06T17:27:10.346Z,1541525230.346 [Depth_Keller] Loaded
2018-11-06T17:27:10.347Z,1541525230.347 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-11-06T17:27:10.351Z,1541525230.351 [DropWeight] Loaded
2018-11-06T17:27:10.352Z,1541525230.352 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-11-06T17:27:10.448Z,1541525230.448 [NAL9602] Loaded
2018-11-06T17:27:10.449Z,1541525230.449 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-11-06T17:27:10.464Z,1541525230.464 [Onboard] Loaded
2018-11-06T17:27:10.465Z,1541525230.465 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-11-06T17:27:10.475Z,1541525230.475 [Radio_Surface] Loaded
2018-11-06T17:27:10.476Z,1541525230.476 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-11-06T17:27:10.477Z,1541525230.477 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4088F4E0
2018-11-06T17:27:10.477Z,1541525230.477 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6497
2018-11-06T17:27:10.523Z,1541525230.523 [PNI_TCM] Loaded
2018-11-06T17:27:10.523Z,1541525230.523 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-11-06T17:27:10.583Z,1541525230.583 [Rowe_600LCM] Loaded
2018-11-06T17:27:10.584Z,1541525230.584 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-11-06T17:27:10.584Z,1541525230.584 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 408BF4E0
2018-11-06T17:27:10.585Z,1541525230.585 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 6498
2018-11-06T17:27:12.331Z,1541525232.331 [BPC1] Loaded
2018-11-06T17:27:12.331Z,1541525232.331 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-11-06T17:27:12.332Z,1541525232.332 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-11-06T17:27:12.332Z,1541525232.332 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-11-06T17:27:12.457Z,1541525232.457 [SBIT](DEBUG): Construct Startup Built In Test.
2018-11-06T17:27:12.479Z,1541525232.479 [SBIT] Loaded
2018-11-06T17:27:12.479Z,1541525232.479 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-11-06T17:27:12.480Z,1541525232.480 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-11-06T17:27:12.492Z,1541525232.492 [IBIT] Loaded
2018-11-06T17:27:12.492Z,1541525232.492 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-11-06T17:27:12.495Z,1541525232.495 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-11-06T17:27:12.643Z,1541525232.643 [CBIT] Loaded
2018-11-06T17:27:12.644Z,1541525232.644 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-11-06T17:27:12.644Z,1541525232.644 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-11-06T17:27:12.645Z,1541525232.645 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-11-06T17:27:12.797Z,1541525232.797 [Aanderaa_O2] Loaded
2018-11-06T17:27:12.797Z,1541525232.797 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-11-06T17:27:12.874Z,1541525232.874 [CTD_NeilBrown] Loaded
2018-11-06T17:27:12.875Z,1541525232.875 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2018-11-06T17:27:12.876Z,1541525232.876 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409894E0
2018-11-06T17:27:12.876Z,1541525232.876 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 6499
2018-11-06T17:27:12.890Z,1541525232.890 [PAR_Licor] Loaded
2018-11-06T17:27:12.891Z,1541525232.891 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-11-06T17:27:12.939Z,1541525232.939 [WetLabsBB2FL] Loaded
2018-11-06T17:27:12.939Z,1541525232.939 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-11-06T17:27:12.940Z,1541525232.940 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409B94E0
2018-11-06T17:27:12.941Z,1541525232.941 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6500
2018-11-06T17:27:12.942Z,1541525232.942 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-11-06T17:27:12.942Z,1541525232.942 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-11-06T17:27:13.008Z,1541525233.008 [VerticalControl](DEBUG): Construct VerticalControl.
2018-11-06T17:27:13.092Z,1541525233.092 [VerticalControl] Loaded
2018-11-06T17:27:13.092Z,1541525233.092 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-11-06T17:27:13.093Z,1541525233.093 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-11-06T17:27:13.149Z,1541525233.149 [HorizontalControl] Loaded
2018-11-06T17:27:13.150Z,1541525233.150 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-11-06T17:27:13.150Z,1541525233.150 [SpeedControl](DEBUG): Construct SpeedControl.
2018-11-06T17:27:13.152Z,1541525233.152 [SpeedControl] Loaded
2018-11-06T17:27:13.152Z,1541525233.152 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-11-06T17:27:13.153Z,1541525233.153 [LoopControl](DEBUG): Construct LoopControl.
2018-11-06T17:27:13.153Z,1541525233.153 [LoopControl] Loaded
2018-11-06T17:27:13.154Z,1541525233.154 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-11-06T17:27:13.154Z,1541525233.154 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-11-06T17:27:13.155Z,1541525233.155 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-11-06T17:27:13.179Z,1541525233.179 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-11-06T17:27:13.179Z,1541525233.179 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-11-06T17:27:13.270Z,1541525233.270 [DeadReckonUsingSpeedCalculator] Loaded
2018-11-06T17:27:13.270Z,1541525233.270 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-11-06T17:27:13.286Z,1541525233.286 [NavChart] Loaded
2018-11-06T17:27:13.286Z,1541525233.286 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-11-06T17:27:13.290Z,1541525233.290 [UniversalFixResidualReporter] Loaded
2018-11-06T17:27:13.291Z,1541525233.291 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-11-06T17:27:13.291Z,1541525233.291 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-11-06T17:27:13.295Z,1541525233.295 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-11-06T17:27:13.296Z,1541525233.296 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-11-06T17:27:13.302Z,1541525233.302 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-11-06T17:27:13.303Z,1541525233.303 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A454E0
2018-11-06T17:27:13.304Z,1541525233.304 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6501
2018-11-06T17:27:13.308Z,1541525233.308 [Supervisor](INFO): Main Thread ID is 6410
2018-11-06T17:27:13.308Z,1541525233.308 [Supervisor](DEBUG): Running supervisor.
2018-11-06T17:27:13.309Z,1541525233.309 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6502
2018-11-06T17:27:13.311Z,1541525233.311 [controlThread ThreadHandler](INFO): Handler Thread ID is 6503
2018-11-06T17:27:13.312Z,1541525233.312 [controlThread](DEBUG): Initializing ControlThread
2018-11-06T17:27:13.313Z,1541525233.313 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-06T17:27:13.313Z,1541525233.313 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-11-06T17:27:13.314Z,1541525233.314 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-11-06T17:27:13.314Z,1541525233.314 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-11-06T17:27:13.315Z,1541525233.315 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-11-06T17:27:13.315Z,1541525233.315 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-11-06T17:27:13.324Z,1541525233.324 [SBIT](INFO): Initialize SBIT Component.
2018-11-06T17:27:13.324Z,1541525233.324 [SBIT](IMPORTANT): git: 2018-10-09
2018-11-06T17:27:13.324Z,1541525233.324 [SBIT](INFO): git hash: e647870a3e94d94bcb418e3013bcadd95d09d9aa
2018-11-06T17:27:13.325Z,1541525233.325 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-11-06T17:27:13.325Z,1541525233.325 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-11-06T17:27:13.326Z,1541525233.326 [SBIT](INFO): Beginning SBIT in 36.000000 seconds.
2018-11-06T17:27:13.327Z,1541525233.327 [IBIT](INFO): Initialize IBIT Component.
2018-11-06T17:27:13.328Z,1541525233.328 [CBIT](DEBUG): Initialize CBIT Component.
2018-11-06T17:27:13.328Z,1541525233.328 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-11-06T17:27:13.329Z,1541525233.329 [logger ThreadHandler](INFO): Handler Thread ID is 6504
2018-11-06T17:27:13.349Z,1541525233.349 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6505
2018-11-06T17:27:13.355Z,1541525233.355 [Radio_Surface](INFO): Powering up
2018-11-06T17:27:13.361Z,1541525233.361 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 6506
2018-11-06T17:27:13.362Z,1541525233.362 [Rowe_600LCM](INFO): Initializing
2018-11-06T17:27:13.363Z,1541525233.363 [Rowe_600LCM](INFO): Checking LCM
2018-11-06T17:27:13.371Z,1541525233.371 [Rowe_600LCM](INFO): LCM OK
2018-11-06T17:27:13.372Z,1541525233.372 [Rowe_600LCM](INFO): Powering up
2018-11-06T17:27:13.378Z,1541525233.378 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 6507
2018-11-06T17:27:13.378Z,1541525233.378 [CTD_NeilBrown](INFO): Powering down
2018-11-06T17:27:13.405Z,1541525233.405 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6508
2018-11-06T17:27:13.406Z,1541525233.406 [WetLabsBB2FL](INFO): Powering down
2018-11-06T17:27:13.437Z,1541525233.437 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-11-06T17:27:13.439Z,1541525233.439 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-11-06T17:27:13.440Z,1541525233.440 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-11-06T17:27:13.440Z,1541525233.440 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-11-06T17:27:13.441Z,1541525233.441 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T17:27:13.442Z,1541525233.442 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T17:27:13.442Z,1541525233.442 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T17:27:13.443Z,1541525233.443 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-11-06T17:27:13.443Z,1541525233.443 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-11-06T17:27:13.444Z,1541525233.444 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-11-06T17:27:13.465Z,1541525233.465 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6509
2018-11-06T17:27:13.468Z,1541525233.468 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-11-06T17:27:13.469Z,1541525233.469 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-11-06T17:27:13.469Z,1541525233.469 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-11-06T17:27:13.469Z,1541525233.469 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-11-06T17:27:13.469Z,1541525233.469 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-11-06T17:27:13.470Z,1541525233.470 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-11-06T17:27:13.470Z,1541525233.470 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-11-06T17:27:13.470Z,1541525233.470 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-11-06T17:27:13.470Z,1541525233.470 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-11-06T17:27:13.470Z,1541525233.470 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-11-06T17:27:13.471Z,1541525233.471 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-11-06T17:27:13.471Z,1541525233.471 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-11-06T17:27:13.471Z,1541525233.471 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-11-06T17:27:13.471Z,1541525233.471 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-11-06T17:27:13.471Z,1541525233.471 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-11-06T17:27:13.472Z,1541525233.472 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-11-06T17:27:13.503Z,1541525233.503 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-06T17:27:13.614Z,1541525233.614 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2018-11-06T17:27:13.615Z,1541525233.615 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-11-06T17:27:13.730Z,1541525233.730 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-11-06T17:27:13.731Z,1541525233.731 [Default:A.Wait](DEBUG): Construct Wait.
2018-11-06T17:27:13.749Z,1541525233.749 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-06T17:27:13.781Z,1541525233.781 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-11-06T17:27:13.784Z,1541525233.784 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-11-06T17:27:13.813Z,1541525233.813 [Default:E.Execute](DEBUG): Construct Execute.
2018-11-06T17:27:13.816Z,1541525233.816 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2018-11-06T17:27:13.835Z,1541525233.835 [controlThread](DEBUG): Component order: CycleStarter,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-11-06T17:27:13.860Z,1541525233.860 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-11-06T17:27:13.885Z,1541525233.885 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2018-11-06T17:27:14.087Z,1541525234.087 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-06T17:27:14.182Z,1541525234.182 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T17:27:14.309Z,1541525234.309 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-11-06T17:27:14.317Z,1541525234.317 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-11-06T17:27:14.340Z,1541525234.340 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-11-06T17:27:14.345Z,1541525234.345 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-11-06T17:27:14.373Z,1541525234.373 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-06T17:27:14.381Z,1541525234.381 [MassServo](DEBUG): Initializing MassServo.
2018-11-06T17:27:14.387Z,1541525234.387 [RudderServo](DEBUG): Initializing EZServoServo.
2018-11-06T17:27:14.393Z,1541525234.393 [RudderServo](DEBUG): Initializing RudderServo.
2018-11-06T17:27:14.418Z,1541525234.418 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-11-06T17:27:14.425Z,1541525234.425 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-11-06T17:27:14.691Z,1541525234.691 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-11-06T17:27:14.691Z,1541525234.691 [DropWeight] Hardware Fault, FailCount= 1
2018-11-06T17:27:14.691Z,1541525234.691 [DropWeight](ERROR): Hardware Fault
2018-11-06T17:27:14.747Z,1541525234.747 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T17:27:14.773Z,1541525234.773 [CommandLine](FAULT): Scheduling is paused
2018-11-06T17:27:14.774Z,1541525234.774 [CBIT](INFO): Critical error at 20181106T172714
2018-11-06T17:27:14.774Z,1541525234.774 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-11-06T17:27:14.777Z,1541525234.777 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-11-06T17:27:14.777Z,1541525234.777 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-11-06T17:27:17.663Z,1541525237.663 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-11-06T17:27:17.780Z,1541525237.780 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-11-06T17:27:17.850Z,1541525237.850 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-11-06T17:27:17.850Z,1541525237.850 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-11-06T17:27:17.851Z,1541525237.851 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-11-06T17:27:24.613Z,1541525244.613 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T17:27:24.923Z,1541525244.923 [CBIT](INFO): Critical error at 20181106T172714
2018-11-06T17:27:24.999Z,1541525244.999 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T17:27:25.370Z,1541525245.370 [Aanderaa_O2](INFO): Powering down
2018-11-06T17:27:25.496Z,1541525245.496 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T17:27:25.739Z,1541525245.739 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004258
2018-11-06T17:27:25.788Z,1541525245.788 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T17:27:26.184Z,1541525246.184 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T17:27:26.618Z,1541525246.618 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-11-06T17:27:40.543Z,1541525260.543 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:27:49.834Z,1541525269.834 [SBIT](IMPORTANT): Beginning Startup BIT
2018-11-06T17:27:49.848Z,1541525269.848 [CBIT](IMPORTANT): Beginning ground fault scan
2018-11-06T17:28:01.659Z,1541525281.659 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:28:01.659Z,1541525281.659 [NAL9602] Communications Fault, FailCount= 1
2018-11-06T17:28:01.659Z,1541525281.659 [NAL9602](ERROR): Communications Fault
2018-11-06T17:28:01.806Z,1541525281.806 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:28:02.058Z,1541525282.058 [NAL9602](INFO): Powering down
2018-11-06T17:28:02.160Z,1541525282.160 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.008538
CHAN A1 (24V): -0.011634
CHAN A2 (12V): -0.001848
CHAN A3 (5V): -0.001554
CHAN B0 (3.3V): -0.000222
CHAN B1 (3.15aV): -0.000578
CHAN B2 (3.15bV): -0.000681
CHAN B3 (GND): -0.000065
OPEN: -0.000241
Full Scale Calc: 4.765 mA, -1.589 mA
2018-11-06T17:28:03.073Z,1541525283.073 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:28:03.082Z,1541525283.082 [NAL9602] No Fault, FailCount= 1
2018-11-06T17:28:32.381Z,1541525312.381 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:28:43.651Z,1541525323.651 [SBIT](IMPORTANT): SBIT PASSED
2018-11-06T17:28:43.729Z,1541525323.729 [CommandLine](IMPORTANT): got command configSet list
2018-11-06T17:28:43.730Z,1541525323.730 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-11-06T17:28:43.730Z,1541525323.730 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=28 count;

2018-11-06T17:28:43.731Z,1541525323.731 [CommandLine](IMPORTANT): BPC1.batteryMuxCycleTime=10 minute;

2018-11-06T17:28:43.731Z,1541525323.731 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2018-11-06T17:28:43.731Z,1541525323.731 [CommandLine](IMPORTANT): Express none VerticalTemperatureHomogeneityIndexCalculator.vertical_temperature_homogeneity_index;

2018-11-06T17:28:43.731Z,1541525323.731 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2018-11-06T17:28:43.731Z,1541525323.731 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=1 bool;

2018-11-06T17:28:43.731Z,1541525323.731 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=213 cubic_centimeter;

2018-11-06T17:28:43.731Z,1541525323.731 [CommandLine](IMPORTANT): VerticalControl.massDefault=4 millimeter;

2018-11-06T17:28:44.030Z,1541525324.030 [MissionManager](IMPORTANT): Started mission Startup
2018-11-06T17:28:44.031Z,1541525324.031 [Startup] Running Loop=1
2018-11-06T17:28:44.031Z,1541525324.031 [Startup](DEBUG): Aggregate::initialize Startup
2018-11-06T17:28:44.031Z,1541525324.031 [Startup:A.GoToSurface] Running Loop=1
2018-11-06T17:28:44.031Z,1541525324.031 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-11-06T17:28:44.032Z,1541525324.032 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-11-06T17:28:44.032Z,1541525324.032 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-11-06T17:28:44.049Z,1541525324.049 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-11-06T17:28:44.050Z,1541525324.050 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-11-06T17:28:44.050Z,1541525324.050 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-11-06T17:28:44.066Z,1541525324.066 [Startup:StartupSatComms] Running Loop=1
2018-11-06T17:28:44.066Z,1541525324.066 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-11-06T17:28:44.066Z,1541525324.066 [Startup:StartupSatComms:A] Running Loop=1
2018-11-06T17:28:44.442Z,1541525324.442 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-11-06T17:28:53.190Z,1541525333.190 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:28:53.190Z,1541525333.190 [NAL9602] Communications Fault, FailCount= 2
2018-11-06T17:28:53.191Z,1541525333.191 [NAL9602](ERROR): Communications Fault
2018-11-06T17:28:53.272Z,1541525333.272 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:28:53.582Z,1541525333.582 [NAL9602](INFO): Powering down
2018-11-06T17:28:53.699Z,1541525333.699 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T17:28:54.047Z,1541525334.047 [BPC1](FAULT): Battery stick #1 (s/n: 0138) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-11-06T17:28:54.047Z,1541525334.047 [BPC1](FAULT): Battery stick #9 (s/n: 0100) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-11-06T17:28:54.048Z,1541525334.048 [BPC1](FAULT): Battery stick #14 (s/n: 011D) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-11-06T17:28:54.049Z,1541525334.049 [BPC1](FAULT): Battery stick #15 (s/n: 014E) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-11-06T17:28:54.050Z,1541525334.050 [BPC1](FAULT): Battery stick #32 (s/n: 0157) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-11-06T17:28:54.051Z,1541525334.051 [BPC1](FAULT): Battery stick #40 (s/n: 0095) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-11-06T17:28:54.052Z,1541525334.052 [BPC1](FAULT): Battery stick #43 (s/n: 00AE) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-11-06T17:28:54.053Z,1541525334.053 [BPC1](FAULT): Battery stick #44 (s/n: 00CB) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-11-06T17:28:54.053Z,1541525334.053 [BPC1](FAULT): Battery stick #45 (s/n: 00F7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-11-06T17:28:54.054Z,1541525334.054 [BPC1](FAULT): Battery stick #46 (s/n: 00A1) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-11-06T17:28:54.055Z,1541525334.055 [BPC1](FAULT): Battery stick #49 (s/n: 0103) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-11-06T17:28:54.056Z,1541525334.056 [BPC1](FAULT): Battery stick #50 (s/n: 0152) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-11-06T17:28:54.073Z,1541525334.073 [BPC1](FAULT): Battery stick #51 (s/n: 014A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-11-06T17:28:54.074Z,1541525334.074 [BPC1](FAULT): Battery stick #53 (s/n: 015E) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-11-06T17:28:54.074Z,1541525334.074 [BPC1](FAULT): Battery stick #54 (s/n: 008E) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-11-06T17:28:54.075Z,1541525334.075 [BPC1](INFO): Calculating totals. Valid battery stick count: 18. Valid reserve battery stick count: 4.
2018-11-06T17:28:54.577Z,1541525334.577 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:28:54.577Z,1541525334.577 [NAL9602] No Fault, FailCount= 2
2018-11-06T17:29:23.770Z,1541525363.770 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:29:44.223Z,1541525384.223 [Startup:StartupSatComms:A](INFO): Timed out from 2018-11-06T17:28:44.1Z
2018-11-06T17:29:44.223Z,1541525384.223 [Startup:StartupSatComms:A] Stopped
2018-11-06T17:29:44.223Z,1541525384.223 [Startup:StartupSatComms:B] Running Loop=1
2018-11-06T17:29:44.572Z,1541525384.572 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:29:44.572Z,1541525384.572 [NAL9602] Communications Fault, FailCount= 3
2018-11-06T17:29:44.572Z,1541525384.572 [NAL9602](ERROR): Communications Fault
2018-11-06T17:29:44.658Z,1541525384.658 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-11-06T17:29:44.680Z,1541525384.680 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:29:45.065Z,1541525385.065 [NAL9602](INFO): Powering down
2018-11-06T17:29:48.955Z,1541525388.955 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:29:48.956Z,1541525388.956 [NAL9602] No Fault, FailCount= 3
2018-11-06T17:29:48.980Z,1541525388.980 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20181105T235340/Courier0000.lzma
2018-11-06T17:29:49.780Z,1541525389.780 [DataOverHttps](INFO): Moved sent file to Logs/20181105T235340/Courier0000.lzma.bak
2018-11-06T17:29:49.780Z,1541525389.780 [DataOverHttps](INFO): SBD MOMSN=8796070
2018-11-06T17:29:59.194Z,1541525399.194 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20181105T235409/Courier0000.lzma
2018-11-06T17:29:59.984Z,1541525399.984 [DataOverHttps](INFO): Moved sent file to Logs/20181105T235409/Courier0000.lzma.bak
2018-11-06T17:29:59.984Z,1541525399.984 [DataOverHttps](INFO): SBD MOMSN=8796075
2018-11-06T17:30:09.362Z,1541525409.362 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20181105T235743/Courier0000.lzma
2018-11-06T17:30:10.160Z,1541525410.160 [DataOverHttps](INFO): Moved sent file to Logs/20181105T235743/Courier0000.lzma.bak
2018-11-06T17:30:10.160Z,1541525410.160 [DataOverHttps](INFO): SBD MOMSN=8796080
2018-11-06T17:30:14.322Z,1541525414.322 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-06T17:30:14.322Z,1541525414.322 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-11-06T17:30:14.322Z,1541525414.322 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T17:30:14.336Z,1541525414.336 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T17:30:14.579Z,1541525414.579 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T17:30:14.579Z,1541525414.579 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-11-06T17:30:14.816Z,1541525414.816 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T17:30:14.817Z,1541525414.817 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T17:30:14.817Z,1541525414.817 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T17:30:15.158Z,1541525415.158 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:30:19.626Z,1541525419.626 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181106T000735/Courier0004.lzma
2018-11-06T17:30:20.423Z,1541525420.423 [DataOverHttps](INFO): Moved sent file to Logs/20181106T000735/Courier0004.lzma.bak
2018-11-06T17:30:20.424Z,1541525420.424 [DataOverHttps](INFO): SBD MOMSN=8796087
2018-11-06T17:30:29.639Z,1541525429.639 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20181106T005942/Courier0000.lzma
2018-11-06T17:30:30.422Z,1541525430.422 [DataOverHttps](INFO): Moved sent file to Logs/20181106T005942/Courier0000.lzma.bak
2018-11-06T17:30:30.422Z,1541525430.422 [DataOverHttps](INFO): SBD MOMSN=8796090
2018-11-06T17:30:35.939Z,1541525435.939 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:30:35.939Z,1541525435.939 [NAL9602] Communications Fault, FailCount= 4
2018-11-06T17:30:35.939Z,1541525435.939 [NAL9602](ERROR): Communications Fault
2018-11-06T17:30:36.027Z,1541525436.027 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:30:36.338Z,1541525436.338 [NAL9602](INFO): Powering down
2018-11-06T17:30:39.936Z,1541525439.936 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181106T010028/Courier0004.lzma
2018-11-06T17:30:40.057Z,1541525440.057 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:30:40.058Z,1541525440.058 [NAL9602] No Fault, FailCount= 4
2018-11-06T17:30:40.716Z,1541525440.716 [DataOverHttps](INFO): Moved sent file to Logs/20181106T010028/Courier0004.lzma.bak
2018-11-06T17:30:40.716Z,1541525440.716 [DataOverHttps](INFO): SBD MOMSN=8796097
2018-11-06T17:30:45.078Z,1541525445.078 [Startup:StartupSatComms:B](INFO): Timed out from 2018-11-06T17:29:44.2Z
2018-11-06T17:30:45.078Z,1541525445.078 [Startup:StartupSatComms:B] Stopped
2018-11-06T17:30:45.079Z,1541525445.079 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-11-06T17:30:45.079Z,1541525445.079 [Startup:StartupSatComms] Stopped
2018-11-06T17:30:45.079Z,1541525445.079 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-11-06T17:30:45.080Z,1541525445.080 [Startup](INFO): Completed Startup
2018-11-06T17:30:45.080Z,1541525445.080 [MissionManager](INFO): Startup is completed.
2018-11-06T17:30:45.080Z,1541525445.080 [MissionManager](INFO): Uninitializing Mission Startup
2018-11-06T17:30:45.080Z,1541525445.080 [Startup] Stopped
2018-11-06T17:30:45.080Z,1541525445.080 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-11-06T17:30:45.080Z,1541525445.080 [Startup:A.GoToSurface] Stopped
2018-11-06T17:30:45.081Z,1541525445.081 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-11-06T17:30:45.243Z,1541525445.243 [MissionManager](IMPORTANT): Started mission Default
2018-11-06T17:30:45.243Z,1541525445.243 [Default] Running Loop=1
2018-11-06T17:30:45.243Z,1541525445.243 [Default](DEBUG): Aggregate::initialize Default
2018-11-06T17:30:45.243Z,1541525445.243 [Default:B.GoToSurface] Running Loop=1
2018-11-06T17:30:45.244Z,1541525445.244 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-11-06T17:30:45.244Z,1541525445.244 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-11-06T17:30:45.244Z,1541525445.244 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-11-06T17:30:45.249Z,1541525445.249 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-11-06T17:30:45.249Z,1541525445.249 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-11-06T17:30:45.250Z,1541525445.250 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-11-06T17:30:45.250Z,1541525445.250 [Default:A.Wait] Running Loop=1
2018-11-06T17:30:45.250Z,1541525445.250 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-11-06T17:30:50.131Z,1541525450.131 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20181106T043830/Courier0000.lzma
2018-11-06T17:30:50.927Z,1541525450.927 [DataOverHttps](INFO): Moved sent file to Logs/20181106T043830/Courier0000.lzma.bak
2018-11-06T17:30:50.927Z,1541525450.927 [DataOverHttps](INFO): SBD MOMSN=8796100
2018-11-06T17:30:58.641Z,1541525458.641 [Default:A.Wait](INFO): Done Waiting.
2018-11-06T17:30:58.642Z,1541525458.642 [Default:A.Wait] Stopped
2018-11-06T17:30:58.642Z,1541525458.642 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-11-06T17:30:58.991Z,1541525458.991 [Default:CheckIn] Running Loop=1
2018-11-06T17:30:58.991Z,1541525458.991 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-11-06T17:30:58.992Z,1541525458.992 [Default:CheckIn:Read_GPS] Running Loop=1
2018-11-06T17:30:59.395Z,1541525459.395 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-11-06T17:31:06.526Z,1541525466.526 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:31:27.726Z,1541525487.726 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:31:27.726Z,1541525487.726 [NAL9602] Communications Fault, FailCount= 5
2018-11-06T17:31:27.727Z,1541525487.727 [NAL9602](ERROR): Communications Fault
2018-11-06T17:31:27.816Z,1541525487.816 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:31:27.816Z,1541525487.816 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2018-11-06T17:31:28.126Z,1541525488.126 [NAL9602](INFO): Powering down
2018-11-06T17:31:28.209Z,1541525488.209 [CBIT](INFO): Critical error at 20181106T173127
2018-11-06T17:33:01.159Z,1541525581.159 [CommandLine](IMPORTANT): got command configSet list
2018-11-06T17:33:01.159Z,1541525581.159 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-11-06T17:33:01.160Z,1541525581.160 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=28 count;

2018-11-06T17:33:01.160Z,1541525581.160 [CommandLine](IMPORTANT): BPC1.batteryMuxCycleTime=10 minute;

2018-11-06T17:33:01.160Z,1541525581.160 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2018-11-06T17:33:01.160Z,1541525581.160 [CommandLine](IMPORTANT): Express none VerticalTemperatureHomogeneityIndexCalculator.vertical_temperature_homogeneity_index;

2018-11-06T17:33:01.160Z,1541525581.160 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2018-11-06T17:33:01.160Z,1541525581.160 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=1 bool;

2018-11-06T17:33:01.161Z,1541525581.161 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=213 cubic_centimeter;

2018-11-06T17:33:01.161Z,1541525581.161 [CommandLine](IMPORTANT): VerticalControl.massDefault=4 millimeter;

2018-11-06T17:33:08.534Z,1541525588.534 [CommandLine](INFO): End of History
2018-11-06T17:33:15.150Z,1541525595.150 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-06T17:33:15.150Z,1541525595.150 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-11-06T17:33:15.151Z,1541525595.151 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T17:33:15.164Z,1541525595.164 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T17:33:15.563Z,1541525595.563 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T17:33:15.564Z,1541525595.564 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-11-06T17:33:15.947Z,1541525595.947 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T17:33:15.948Z,1541525595.948 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T17:33:15.948Z,1541525595.948 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T17:33:19.559Z,1541525599.559 [CommandLine](IMPORTANT): got command burn 20.000000
2018-11-06T17:33:27.973Z,1541525607.973 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:33:27.973Z,1541525607.973 [NAL9602] No Fault, FailCount= 5
2018-11-06T17:33:28.298Z,1541525608.298 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:33:39.573Z,1541525619.573 [CommandLine](IMPORTANT): Deactivating dropweight wire
2018-11-06T17:33:50.983Z,1541525630.983 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:33:50.983Z,1541525630.983 [NAL9602] Communications Fault, FailCount= 1
2018-11-06T17:33:50.984Z,1541525630.984 [NAL9602](ERROR): Communications Fault
2018-11-06T17:33:51.064Z,1541525631.064 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:33:51.095Z,1541525631.095 [NAL9602](INFO): Powering down
2018-11-06T17:33:51.946Z,1541525631.946 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:33:51.946Z,1541525631.946 [NAL9602] No Fault, FailCount= 1
2018-11-06T17:34:21.482Z,1541525661.482 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:34:26.231Z,1541525666.231 [CommandLine](IMPORTANT): got command burn off
2018-11-06T17:34:26.231Z,1541525666.231 [CommandLine](IMPORTANT): Deactivating dropweight wire
2018-11-06T17:34:30.588Z,1541525670.588 [CommandLine](IMPORTANT): got command burn on
2018-11-06T17:34:30.588Z,1541525670.588 [CommandLine](IMPORTANT): Activating dropweight wire
2018-11-06T17:34:42.682Z,1541525682.682 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:34:42.682Z,1541525682.682 [NAL9602] Communications Fault, FailCount= 2
2018-11-06T17:34:42.682Z,1541525682.682 [NAL9602](ERROR): Communications Fault
2018-11-06T17:34:42.781Z,1541525682.781 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:34:43.086Z,1541525683.086 [NAL9602](INFO): Powering down
2018-11-06T17:34:43.949Z,1541525683.949 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:34:43.949Z,1541525683.949 [NAL9602] No Fault, FailCount= 2
2018-11-06T17:35:13.470Z,1541525713.470 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:35:34.667Z,1541525734.667 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:35:34.667Z,1541525734.667 [NAL9602] Communications Fault, FailCount= 3
2018-11-06T17:35:34.667Z,1541525734.667 [NAL9602](ERROR): Communications Fault
2018-11-06T17:35:34.768Z,1541525734.768 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:35:35.062Z,1541525735.062 [NAL9602](INFO): Powering down
2018-11-06T17:35:35.988Z,1541525735.988 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:35:35.993Z,1541525735.993 [NAL9602] No Fault, FailCount= 3
2018-11-06T17:35:59.288Z,1541525759.288 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-11-06T17:30:58.0Z
2018-11-06T17:35:59.288Z,1541525759.288 [Default:CheckIn:Read_GPS] Stopped
2018-11-06T17:35:59.288Z,1541525759.288 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-11-06T17:35:59.698Z,1541525759.698 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-11-06T17:36:04.011Z,1541525764.011 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20181106T043951/Courier0000.lzma
2018-11-06T17:36:04.807Z,1541525764.807 [DataOverHttps](INFO): Moved sent file to Logs/20181106T043951/Courier0000.lzma.bak
2018-11-06T17:36:04.808Z,1541525764.808 [DataOverHttps](INFO): SBD MOMSN=8796107
2018-11-06T17:36:05.311Z,1541525765.311 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:36:14.207Z,1541525774.207 [DataOverHttps](INFO): Sending 284 bytes from file Logs/20181106T172703/Courier0000.lzma
2018-11-06T17:36:15.011Z,1541525775.011 [DataOverHttps](INFO): Moved sent file to Logs/20181106T172703/Courier0000.lzma.bak
2018-11-06T17:36:15.011Z,1541525775.011 [DataOverHttps](INFO): SBD MOMSN=8796114
2018-11-06T17:36:19.338Z,1541525779.338 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-06T17:36:19.338Z,1541525779.338 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-11-06T17:36:19.338Z,1541525779.338 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T17:36:19.372Z,1541525779.372 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T17:36:19.511Z,1541525779.511 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T17:36:19.511Z,1541525779.511 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-11-06T17:36:19.843Z,1541525779.843 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T17:36:19.844Z,1541525779.844 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T17:36:19.845Z,1541525779.845 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T17:36:24.527Z,1541525784.527 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20181106T172703/Courier0004.lzma
2018-11-06T17:36:25.331Z,1541525785.331 [DataOverHttps](INFO): Moved sent file to Logs/20181106T172703/Courier0004.lzma.bak
2018-11-06T17:36:25.331Z,1541525785.331 [DataOverHttps](INFO): SBD MOMSN=8796119
2018-11-06T17:36:25.922Z,1541525785.922 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:36:25.922Z,1541525785.922 [NAL9602] Communications Fault, FailCount= 4
2018-11-06T17:36:25.922Z,1541525785.922 [NAL9602](ERROR): Communications Fault
2018-11-06T17:36:26.012Z,1541525786.012 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:36:26.140Z,1541525786.140 [NAL9602](INFO): Powering down
2018-11-06T17:36:29.214Z,1541525789.214 [CommandLine](IMPORTANT): got command burn off
2018-11-06T17:36:29.214Z,1541525789.214 [CommandLine](IMPORTANT): Deactivating dropweight wire
2018-11-06T17:36:29.848Z,1541525789.848 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:36:29.848Z,1541525789.848 [NAL9602] No Fault, FailCount= 4
2018-11-06T17:36:34.955Z,1541525794.955 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20181105T213126/Express0049.lzma
2018-11-06T17:36:35.162Z,1541525795.162 [CommandLine](IMPORTANT): got command burn 20.000000
2018-11-06T17:36:35.720Z,1541525795.720 [DataOverHttps](INFO): Moved sent file to Logs/20181105T213126/Express0049.lzma.bak
2018-11-06T17:36:35.720Z,1541525795.720 [DataOverHttps](INFO): SBD MOMSN=8796122
2018-11-06T17:36:45.128Z,1541525805.128 [DataOverHttps](INFO): Sending 984 bytes from file Logs/20181105T233955/Express0001.lzma
2018-11-06T17:36:45.923Z,1541525805.923 [DataOverHttps](INFO): Moved sent file to Logs/20181105T233955/Express0001.lzma.bak
2018-11-06T17:36:45.924Z,1541525805.924 [DataOverHttps](INFO): SBD MOMSN=8796129
2018-11-06T17:36:55.181Z,1541525815.181 [CommandLine](IMPORTANT): Deactivating dropweight wire
2018-11-06T17:36:55.363Z,1541525815.363 [DataOverHttps](INFO): Sending 408 bytes from file Logs/20181105T234244/Express0001.lzma
2018-11-06T17:36:56.151Z,1541525816.151 [DataOverHttps](INFO): Moved sent file to Logs/20181105T234244/Express0001.lzma.bak
2018-11-06T17:36:56.152Z,1541525816.152 [DataOverHttps](INFO): SBD MOMSN=8796156
2018-11-06T17:36:56.158Z,1541525816.158 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:37:05.539Z,1541525825.539 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181105T234324/Express0001.lzma
2018-11-06T17:37:06.340Z,1541525826.340 [DataOverHttps](INFO): Moved sent file to Logs/20181105T234324/Express0001.lzma.bak
2018-11-06T17:37:06.340Z,1541525826.340 [DataOverHttps](INFO): SBD MOMSN=8796165
2018-11-06T17:37:15.580Z,1541525835.580 [DataOverHttps](INFO): Sending 708 bytes from file Logs/20181105T234610/Express0001.lzma
2018-11-06T17:37:16.362Z,1541525836.362 [DataOverHttps](INFO): Moved sent file to Logs/20181105T234610/Express0001.lzma.bak
2018-11-06T17:37:16.363Z,1541525836.363 [DataOverHttps](INFO): SBD MOMSN=8796167
2018-11-06T17:37:17.170Z,1541525837.170 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:37:17.171Z,1541525837.171 [NAL9602] Communications Fault, FailCount= 5
2018-11-06T17:37:17.171Z,1541525837.171 [NAL9602](ERROR): Communications Fault
2018-11-06T17:37:17.257Z,1541525837.257 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:37:17.258Z,1541525837.258 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2018-11-06T17:37:20.498Z,1541525840.498 [NAL9602](INFO): Powering down
2018-11-06T17:37:20.584Z,1541525840.584 [CBIT](INFO): Critical error at 20181106T173717
2018-11-06T17:37:25.772Z,1541525845.772 [DataOverHttps](INFO): Sending 509 bytes from file Logs/20181105T234757/Express0001.lzma
2018-11-06T17:37:26.576Z,1541525846.576 [DataOverHttps](INFO): Moved sent file to Logs/20181105T234757/Express0001.lzma.bak
2018-11-06T17:37:26.576Z,1541525846.576 [DataOverHttps](INFO): SBD MOMSN=8796186
2018-11-06T17:37:36.293Z,1541525856.293 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181105T234906/Express0001.lzma
2018-11-06T17:37:37.038Z,1541525857.038 [DataOverHttps](INFO): Moved sent file to Logs/20181105T234906/Express0001.lzma.bak
2018-11-06T17:37:37.038Z,1541525857.038 [DataOverHttps](INFO): SBD MOMSN=8796200
2018-11-06T17:37:46.496Z,1541525866.496 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181105T234954/Express0001.lzma
2018-11-06T17:37:47.264Z,1541525867.264 [DataOverHttps](INFO): Moved sent file to Logs/20181105T234954/Express0001.lzma.bak
2018-11-06T17:37:47.264Z,1541525867.264 [DataOverHttps](INFO): SBD MOMSN=8796202
2018-11-06T17:37:56.838Z,1541525876.838 [DataOverHttps](INFO): Sending 669 bytes from file Logs/20181105T235030/Express0001.lzma
2018-11-06T17:37:57.600Z,1541525877.600 [DataOverHttps](INFO): Moved sent file to Logs/20181105T235030/Express0001.lzma.bak
2018-11-06T17:37:57.600Z,1541525877.600 [DataOverHttps](INFO): SBD MOMSN=8796205
2018-11-06T17:38:06.809Z,1541525886.809 [DataOverHttps](INFO): Sending 369 bytes from file Logs/20181105T235340/Express0001.lzma
2018-11-06T17:38:07.609Z,1541525887.609 [DataOverHttps](INFO): Moved sent file to Logs/20181105T235340/Express0001.lzma.bak
2018-11-06T17:38:07.609Z,1541525887.609 [DataOverHttps](INFO): SBD MOMSN=8796223
2018-11-06T17:38:17.012Z,1541525897.012 [DataOverHttps](INFO): Sending 893 bytes from file Logs/20181105T235409/Express0001.lzma
2018-11-06T17:38:17.783Z,1541525897.783 [DataOverHttps](INFO): Moved sent file to Logs/20181105T235409/Express0001.lzma.bak
2018-11-06T17:38:17.784Z,1541525897.784 [DataOverHttps](INFO): SBD MOMSN=8796231
2018-11-06T17:38:27.962Z,1541525907.962 [DataOverHttps](INFO): Sending 744 bytes from file Logs/20181105T235743/Express0001.lzma
2018-11-06T17:38:28.724Z,1541525908.724 [DataOverHttps](INFO): Moved sent file to Logs/20181105T235743/Express0001.lzma.bak
2018-11-06T17:38:28.724Z,1541525908.724 [DataOverHttps](INFO): SBD MOMSN=8796254
2018-11-06T17:38:38.107Z,1541525918.107 [DataOverHttps](INFO): Sending 472 bytes from file Logs/20181106T000136/Express0001.lzma
2018-11-06T17:38:38.883Z,1541525918.883 [DataOverHttps](INFO): Moved sent file to Logs/20181106T000136/Express0001.lzma.bak
2018-11-06T17:38:38.884Z,1541525918.884 [DataOverHttps](INFO): SBD MOMSN=8796283
2018-11-06T17:38:48.304Z,1541525928.304 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181106T000346/Express0001.lzma
2018-11-06T17:38:49.095Z,1541525929.095 [DataOverHttps](INFO): Moved sent file to Logs/20181106T000346/Express0001.lzma.bak
2018-11-06T17:38:49.095Z,1541525929.095 [DataOverHttps](INFO): SBD MOMSN=8796297
2018-11-06T17:38:58.486Z,1541525938.486 [DataOverHttps](INFO): Sending 657 bytes from file Logs/20181106T000605/Express0001.lzma
2018-11-06T17:38:59.251Z,1541525939.251 [DataOverHttps](INFO): Moved sent file to Logs/20181106T000605/Express0001.lzma.bak
2018-11-06T17:38:59.252Z,1541525939.252 [DataOverHttps](INFO): SBD MOMSN=8796300
2018-11-06T17:39:09.052Z,1541525949.052 [DataOverHttps](INFO): Sending 910 bytes from file Logs/20181106T000735/Express0001.lzma
2018-11-06T17:39:09.846Z,1541525949.846 [DataOverHttps](INFO): Moved sent file to Logs/20181106T000735/Express0001.lzma.bak
2018-11-06T17:39:09.846Z,1541525949.846 [DataOverHttps](INFO): SBD MOMSN=8796316
2018-11-06T17:39:19.251Z,1541525959.251 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:39:19.251Z,1541525959.251 [NAL9602] No Fault, FailCount= 5
2018-11-06T17:39:19.283Z,1541525959.283 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20181106T000735/Express0005.lzma
2018-11-06T17:39:19.287Z,1541525959.287 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:39:20.059Z,1541525960.059 [DataOverHttps](INFO): Moved sent file to Logs/20181106T000735/Express0005.lzma.bak
2018-11-06T17:39:20.060Z,1541525960.060 [DataOverHttps](INFO): SBD MOMSN=8796343
2018-11-06T17:39:20.140Z,1541525960.140 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-06T17:39:20.140Z,1541525960.140 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-11-06T17:39:20.140Z,1541525960.140 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T17:39:20.171Z,1541525960.171 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T17:39:20.828Z,1541525960.828 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T17:39:20.830Z,1541525960.830 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-11-06T17:39:20.923Z,1541525960.923 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T17:39:20.924Z,1541525960.924 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T17:39:20.924Z,1541525960.924 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T17:39:29.455Z,1541525969.455 [DataOverHttps](INFO): Sending 343 bytes from file Logs/20181106T001042/Express0001.lzma
2018-11-06T17:39:30.248Z,1541525970.248 [DataOverHttps](INFO): Moved sent file to Logs/20181106T001042/Express0001.lzma.bak
2018-11-06T17:39:30.248Z,1541525970.248 [DataOverHttps](INFO): SBD MOMSN=8796346
2018-11-06T17:39:39.688Z,1541525979.688 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:39:39.688Z,1541525979.688 [NAL9602] Communications Fault, FailCount= 1
2018-11-06T17:39:39.688Z,1541525979.688 [NAL9602](ERROR): Communications Fault
2018-11-06T17:39:39.800Z,1541525979.800 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:39:39.822Z,1541525979.822 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20181106T001110/Express0001.lzma
2018-11-06T17:39:39.848Z,1541525979.848 [NAL9602](INFO): Powering down
2018-11-06T17:39:40.614Z,1541525980.614 [DataOverHttps](INFO): Moved sent file to Logs/20181106T001110/Express0001.lzma.bak
2018-11-06T17:39:40.615Z,1541525980.615 [DataOverHttps](INFO): SBD MOMSN=8796354
2018-11-06T17:39:40.870Z,1541525980.870 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:39:40.870Z,1541525980.870 [NAL9602] No Fault, FailCount= 1
2018-11-06T17:39:50.327Z,1541525990.327 [DataOverHttps](INFO): Sending 627 bytes from file Logs/20181106T005529/Express0001.lzma
2018-11-06T17:39:51.120Z,1541525991.120 [DataOverHttps](INFO): Moved sent file to Logs/20181106T005529/Express0001.lzma.bak
2018-11-06T17:39:51.120Z,1541525991.120 [DataOverHttps](INFO): SBD MOMSN=8796357
2018-11-06T17:40:00.718Z,1541526000.718 [DataOverHttps](INFO): Sending 627 bytes from file Logs/20181106T005740/Express0001.lzma
2018-11-06T17:40:01.495Z,1541526001.495 [DataOverHttps](INFO): Moved sent file to Logs/20181106T005740/Express0001.lzma.bak
2018-11-06T17:40:01.496Z,1541526001.496 [DataOverHttps](INFO): SBD MOMSN=8796372
2018-11-06T17:40:10.641Z,1541526010.641 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:40:10.752Z,1541526010.752 [DataOverHttps](INFO): Sending 718 bytes from file Logs/20181106T005942/Express0001.lzma
2018-11-06T17:40:11.548Z,1541526011.548 [DataOverHttps](INFO): Moved sent file to Logs/20181106T005942/Express0001.lzma.bak
2018-11-06T17:40:11.548Z,1541526011.548 [DataOverHttps](INFO): SBD MOMSN=8796387
2018-11-06T17:40:21.113Z,1541526021.113 [DataOverHttps](INFO): Sending 909 bytes from file Logs/20181106T010028/Express0001.lzma
2018-11-06T17:40:21.880Z,1541526021.880 [DataOverHttps](INFO): Moved sent file to Logs/20181106T010028/Express0001.lzma.bak
2018-11-06T17:40:21.880Z,1541526021.880 [DataOverHttps](INFO): SBD MOMSN=8796415
2018-11-06T17:40:31.183Z,1541526031.183 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:40:31.183Z,1541526031.183 [NAL9602] Communications Fault, FailCount= 2
2018-11-06T17:40:31.183Z,1541526031.183 [NAL9602](ERROR): Communications Fault
2018-11-06T17:40:31.285Z,1541526031.285 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:40:31.296Z,1541526031.296 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20181106T010028/Express0005.lzma
2018-11-06T17:40:31.332Z,1541526031.332 [NAL9602](INFO): Powering down
2018-11-06T17:40:32.096Z,1541526032.096 [DataOverHttps](INFO): Moved sent file to Logs/20181106T010028/Express0005.lzma.bak
2018-11-06T17:40:32.097Z,1541526032.097 [DataOverHttps](INFO): SBD MOMSN=8796442
2018-11-06T17:40:32.186Z,1541526032.186 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:40:32.186Z,1541526032.186 [NAL9602] No Fault, FailCount= 2
2018-11-06T17:40:41.512Z,1541526041.512 [DataOverHttps](INFO): Sending 392 bytes from file Logs/20181106T010427/Express0001.lzma
2018-11-06T17:40:42.299Z,1541526042.299 [DataOverHttps](INFO): Moved sent file to Logs/20181106T010427/Express0001.lzma.bak
2018-11-06T17:40:42.300Z,1541526042.300 [DataOverHttps](INFO): SBD MOMSN=8796446
2018-11-06T17:40:52.135Z,1541526052.135 [DataOverHttps](INFO): Sending 353 bytes from file Logs/20181106T043805/Express0001.lzma
2018-11-06T17:40:52.939Z,1541526052.939 [DataOverHttps](INFO): Moved sent file to Logs/20181106T043805/Express0001.lzma.bak
2018-11-06T17:40:52.939Z,1541526052.939 [DataOverHttps](INFO): SBD MOMSN=8796456
2018-11-06T17:41:02.276Z,1541526062.276 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:41:02.373Z,1541526062.373 [DataOverHttps](INFO): Sending 623 bytes from file Logs/20181106T043830/Express0001.lzma
2018-11-06T17:41:03.171Z,1541526063.171 [DataOverHttps](INFO): Moved sent file to Logs/20181106T043830/Express0001.lzma.bak
2018-11-06T17:41:03.172Z,1541526063.172 [DataOverHttps](INFO): SBD MOMSN=8796464
2018-11-06T17:41:12.750Z,1541526072.750 [DataOverHttps](INFO): Sending 443 bytes from file Logs/20181106T043857/Express0001.lzma
2018-11-06T17:41:13.508Z,1541526073.508 [DataOverHttps](INFO): Moved sent file to Logs/20181106T043857/Express0001.lzma.bak
2018-11-06T17:41:13.508Z,1541526073.508 [DataOverHttps](INFO): SBD MOMSN=8796482
2018-11-06T17:41:22.728Z,1541526082.728 [DataOverHttps](INFO): Sending 551 bytes from file Logs/20181106T043951/Express0001.lzma
2018-11-06T17:41:23.127Z,1541526083.127 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:41:23.127Z,1541526083.127 [NAL9602] Communications Fault, FailCount= 3
2018-11-06T17:41:23.127Z,1541526083.127 [NAL9602](ERROR): Communications Fault
2018-11-06T17:41:23.218Z,1541526083.218 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:41:23.515Z,1541526083.515 [DataOverHttps](INFO): Moved sent file to Logs/20181106T043951/Express0001.lzma.bak
2018-11-06T17:41:23.516Z,1541526083.516 [DataOverHttps](INFO): SBD MOMSN=8796495
2018-11-06T17:41:23.522Z,1541526083.522 [NAL9602](INFO): Powering down
2018-11-06T17:41:24.523Z,1541526084.523 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:41:24.523Z,1541526084.523 [NAL9602] No Fault, FailCount= 3
2018-11-06T17:41:32.947Z,1541526092.947 [DataOverHttps](INFO): Sending 384 bytes from file Logs/20181106T044012/Express0001.lzma
2018-11-06T17:41:33.748Z,1541526093.748 [DataOverHttps](INFO): Moved sent file to Logs/20181106T044012/Express0001.lzma.bak
2018-11-06T17:41:33.748Z,1541526093.748 [DataOverHttps](INFO): SBD MOMSN=8796511
2018-11-06T17:41:43.751Z,1541526103.751 [DataOverHttps](INFO): Sending 595 bytes from file Logs/20181106T044620/Express0001.lzma
2018-11-06T17:41:44.550Z,1541526104.550 [DataOverHttps](INFO): Moved sent file to Logs/20181106T044620/Express0001.lzma.bak
2018-11-06T17:41:44.550Z,1541526104.550 [DataOverHttps](INFO): SBD MOMSN=8796520
2018-11-06T17:41:53.901Z,1541526113.901 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:41:54.014Z,1541526114.014 [DataOverHttps](INFO): Sending 352 bytes from file Logs/20181106T044722/Express0001.lzma
2018-11-06T17:41:54.812Z,1541526114.812 [DataOverHttps](INFO): Moved sent file to Logs/20181106T044722/Express0001.lzma.bak
2018-11-06T17:41:54.812Z,1541526114.812 [DataOverHttps](INFO): SBD MOMSN=8796534
2018-11-06T17:42:04.177Z,1541526124.177 [DataOverHttps](INFO): Sending 356 bytes from file Logs/20181106T044750/Express0001.lzma
2018-11-06T17:42:04.985Z,1541526124.985 [DataOverHttps](INFO): Moved sent file to Logs/20181106T044750/Express0001.lzma.bak
2018-11-06T17:42:04.985Z,1541526124.985 [DataOverHttps](INFO): SBD MOMSN=8796542
2018-11-06T17:42:14.301Z,1541526134.301 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:42:14.301Z,1541526134.301 [NAL9602] Communications Fault, FailCount= 4
2018-11-06T17:42:14.301Z,1541526134.301 [NAL9602](ERROR): Communications Fault
2018-11-06T17:42:14.387Z,1541526134.387 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:42:14.408Z,1541526134.408 [DataOverHttps](INFO): Sending 355 bytes from file Logs/20181106T044818/Express0001.lzma
2018-11-06T17:42:14.430Z,1541526134.430 [NAL9602](INFO): Powering down
2018-11-06T17:42:14.901Z,1541526134.901 [CBIT](INFO): Clearing failed state for component DropWeight
2018-11-06T17:42:14.901Z,1541526134.901 [DropWeight] No Fault, FailCount= 1
2018-11-06T17:42:15.208Z,1541526135.208 [DataOverHttps](INFO): Moved sent file to Logs/20181106T044818/Express0001.lzma.bak
2018-11-06T17:42:15.208Z,1541526135.208 [DataOverHttps](INFO): SBD MOMSN=8796550
2018-11-06T17:42:15.299Z,1541526135.299 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:42:15.299Z,1541526135.299 [NAL9602] No Fault, FailCount= 4
2018-11-06T17:42:21.238Z,1541526141.238 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-06T17:42:21.238Z,1541526141.238 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-11-06T17:42:21.238Z,1541526141.238 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T17:42:21.260Z,1541526141.260 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T17:42:24.753Z,1541526144.753 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T17:42:24.753Z,1541526144.753 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-11-06T17:42:24.765Z,1541526144.765 [DataOverHttps](INFO): Sending 355 bytes from file Logs/20181106T044845/Express0001.lzma
2018-11-06T17:42:24.852Z,1541526144.852 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T17:42:24.853Z,1541526144.853 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T17:42:24.853Z,1541526144.853 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T17:42:25.564Z,1541526145.564 [DataOverHttps](INFO): Moved sent file to Logs/20181106T044845/Express0001.lzma.bak
2018-11-06T17:42:25.564Z,1541526145.564 [DataOverHttps](INFO): SBD MOMSN=8796558
2018-11-06T17:42:35.335Z,1541526155.335 [DataOverHttps](INFO): Sending 581 bytes from file Logs/20181106T044914/Express0001.lzma
2018-11-06T17:42:36.132Z,1541526156.132 [DataOverHttps](INFO): Moved sent file to Logs/20181106T044914/Express0001.lzma.bak
2018-11-06T17:42:36.132Z,1541526156.132 [DataOverHttps](INFO): SBD MOMSN=8796566
2018-11-06T17:42:45.393Z,1541526165.393 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:42:45.517Z,1541526165.517 [DataOverHttps](INFO): Sending 1098 bytes from file Logs/20181106T172703/Express0001.lzma
2018-11-06T17:42:46.296Z,1541526166.296 [DataOverHttps](INFO): Moved sent file to Logs/20181106T172703/Express0001.lzma.bak
2018-11-06T17:42:46.296Z,1541526166.296 [DataOverHttps](INFO): SBD MOMSN=8796580
2018-11-06T17:42:55.758Z,1541526175.758 [DataOverHttps](INFO): Sending 486 bytes from file Logs/20181106T172703/Express0005.lzma
2018-11-06T17:42:56.556Z,1541526176.556 [DataOverHttps](INFO): Moved sent file to Logs/20181106T172703/Express0005.lzma.bak
2018-11-06T17:42:56.556Z,1541526176.556 [DataOverHttps](INFO): SBD MOMSN=8796617
2018-11-06T17:42:57.204Z,1541526177.204 [Default:CheckIn:Read_Iridium] Stopped
2018-11-06T17:42:57.204Z,1541526177.204 [Default:CheckIn:C.Wait] Running Loop=1
2018-11-06T17:42:57.205Z,1541526177.205 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-11-06T17:43:05.971Z,1541526185.971 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:43:05.971Z,1541526185.971 [NAL9602] Communications Fault, FailCount= 5
2018-11-06T17:43:05.971Z,1541526185.971 [NAL9602](ERROR): Communications Fault
2018-11-06T17:43:06.063Z,1541526186.063 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:43:06.064Z,1541526186.064 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2018-11-06T17:43:06.386Z,1541526186.386 [NAL9602](INFO): Powering down
2018-11-06T17:43:06.539Z,1541526186.539 [CBIT](INFO): Critical error at 20181106T174306
2018-11-06T17:45:05.974Z,1541526305.974 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:45:05.974Z,1541526305.974 [NAL9602] No Fault, FailCount= 5
2018-11-06T17:45:06.319Z,1541526306.319 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:45:25.169Z,1541526325.169 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-06T17:45:25.170Z,1541526325.170 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2018-11-06T17:45:25.170Z,1541526325.170 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T17:45:25.195Z,1541526325.195 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T17:45:25.580Z,1541526325.580 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T17:45:25.580Z,1541526325.580 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2018-11-06T17:45:25.976Z,1541526325.976 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T17:45:25.976Z,1541526325.976 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T17:45:25.977Z,1541526325.977 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T17:45:27.519Z,1541526327.519 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:45:27.519Z,1541526327.519 [NAL9602] Communications Fault, FailCount= 1
2018-11-06T17:45:27.519Z,1541526327.519 [NAL9602](ERROR): Communications Fault
2018-11-06T17:45:27.610Z,1541526327.610 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:45:27.919Z,1541526327.919 [NAL9602](INFO): Powering down
2018-11-06T17:45:28.796Z,1541526328.796 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:45:28.796Z,1541526328.796 [NAL9602] No Fault, FailCount= 1
2018-11-06T17:45:58.331Z,1541526358.331 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:46:19.395Z,1541526379.395 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:46:19.395Z,1541526379.395 [NAL9602] Communications Fault, FailCount= 2
2018-11-06T17:46:19.395Z,1541526379.395 [NAL9602](ERROR): Communications Fault
2018-11-06T17:46:19.483Z,1541526379.483 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:46:19.787Z,1541526379.787 [NAL9602](INFO): Powering down
2018-11-06T17:46:20.695Z,1541526380.695 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:46:20.695Z,1541526380.695 [NAL9602] No Fault, FailCount= 2
2018-11-06T17:46:50.187Z,1541526410.187 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:47:12.511Z,1541526432.511 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:47:12.511Z,1541526432.511 [NAL9602] Communications Fault, FailCount= 3
2018-11-06T17:47:12.511Z,1541526432.511 [NAL9602](ERROR): Communications Fault
2018-11-06T17:47:12.610Z,1541526432.610 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:47:12.915Z,1541526432.915 [NAL9602](INFO): Powering down
2018-11-06T17:47:13.801Z,1541526433.801 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:47:13.802Z,1541526433.802 [NAL9602] No Fault, FailCount= 3
2018-11-06T17:47:43.311Z,1541526463.311 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:47:57.812Z,1541526477.812 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-11-06T17:47:57.812Z,1541526477.812 [Default:CheckIn:C.Wait] Stopped
2018-11-06T17:47:57.812Z,1541526477.812 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-11-06T17:47:57.812Z,1541526477.812 [Default:CheckIn:D] Running Loop=1
2018-11-06T17:47:58.166Z,1541526478.166 [Default:CheckIn:D] Stopped
2018-11-06T17:47:58.166Z,1541526478.166 [Default:CheckIn:E] Running Loop=1
2018-11-06T17:47:58.619Z,1541526478.619 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.215273 min 
2018-11-06T17:47:58.621Z,1541526478.621 [Default:CheckIn:E] Stopped
2018-11-06T17:47:58.622Z,1541526478.622 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-11-06T17:47:58.622Z,1541526478.622 [Default:CheckIn] Stopped
2018-11-06T17:47:58.622Z,1541526478.622 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-11-06T17:47:58.622Z,1541526478.622 [Default:CheckIn](INFO): Running loop #2
2018-11-06T17:47:58.622Z,1541526478.622 [Default:CheckIn] Running Loop=2
2018-11-06T17:47:58.622Z,1541526478.622 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-11-06T17:47:58.622Z,1541526478.622 [Default:CheckIn:Read_GPS] Running Loop=1
2018-11-06T17:48:04.331Z,1541526484.331 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:48:04.331Z,1541526484.331 [NAL9602] Communications Fault, FailCount= 4
2018-11-06T17:48:04.331Z,1541526484.331 [NAL9602](ERROR): Communications Fault
2018-11-06T17:48:04.420Z,1541526484.420 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:48:04.747Z,1541526484.747 [NAL9602](INFO): Powering down
2018-11-06T17:48:05.603Z,1541526485.603 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:48:05.603Z,1541526485.603 [NAL9602] No Fault, FailCount= 4
2018-11-06T17:48:26.450Z,1541526506.450 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-06T17:48:26.450Z,1541526506.450 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2018-11-06T17:48:26.450Z,1541526506.450 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T17:48:26.524Z,1541526506.524 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T17:48:26.798Z,1541526506.798 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T17:48:26.798Z,1541526506.798 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2018-11-06T17:48:27.171Z,1541526507.171 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T17:48:27.172Z,1541526507.172 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T17:48:27.172Z,1541526507.172 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T17:48:35.139Z,1541526515.139 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:48:56.331Z,1541526536.331 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:48:56.331Z,1541526536.331 [NAL9602] Communications Fault, FailCount= 5
2018-11-06T17:48:56.331Z,1541526536.331 [NAL9602](ERROR): Communications Fault
2018-11-06T17:48:56.556Z,1541526536.556 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:48:56.559Z,1541526536.559 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2018-11-06T17:48:56.731Z,1541526536.731 [NAL9602](INFO): Powering down
2018-11-06T17:48:56.809Z,1541526536.809 [CBIT](INFO): Critical error at 20181106T174856
2018-11-06T17:50:15.255Z,1541526615.255 [CommandLine](IMPORTANT): got command burn 20.000000
2018-11-06T17:50:16.110Z,1541526616.110 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-11-06T17:50:16.110Z,1541526616.110 [DropWeight] Hardware Fault, FailCount= 1
2018-11-06T17:50:16.110Z,1541526616.110 [DropWeight](ERROR): Hardware Fault
2018-11-06T17:50:16.215Z,1541526616.215 [CBIT](INFO): Critical error at 20181106T175016
2018-11-06T17:50:16.221Z,1541526616.221 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-11-06T17:50:16.222Z,1541526616.222 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-11-06T17:50:16.618Z,1541526616.618 [CBIT](INFO): Critical error at 20181106T175016
2018-11-06T17:50:35.269Z,1541526635.269 [CommandLine](IMPORTANT): Deactivating dropweight wire
2018-11-06T17:50:56.569Z,1541526656.569 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:50:56.569Z,1541526656.569 [NAL9602] No Fault, FailCount= 5
2018-11-06T17:50:56.915Z,1541526656.915 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:51:18.102Z,1541526678.102 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:51:18.102Z,1541526678.102 [NAL9602] Communications Fault, FailCount= 1
2018-11-06T17:51:18.102Z,1541526678.102 [NAL9602](ERROR): Communications Fault
2018-11-06T17:51:18.205Z,1541526678.205 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:51:18.518Z,1541526678.518 [NAL9602](INFO): Powering down
2018-11-06T17:51:19.360Z,1541526679.360 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:51:19.360Z,1541526679.360 [NAL9602] No Fault, FailCount= 1
2018-11-06T17:51:27.344Z,1541526687.344 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-06T17:51:27.344Z,1541526687.344 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2018-11-06T17:51:27.344Z,1541526687.344 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T17:51:27.375Z,1541526687.375 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T17:51:27.754Z,1541526687.754 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T17:51:27.754Z,1541526687.754 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2018-11-06T17:51:28.151Z,1541526688.151 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T17:51:28.152Z,1541526688.152 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T17:51:28.152Z,1541526688.152 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T17:51:43.127Z,1541526703.127 [CommandLine](IMPORTANT): got command burn off
2018-11-06T17:51:43.127Z,1541526703.127 [CommandLine](IMPORTANT): Deactivating dropweight wire
2018-11-06T17:51:48.688Z,1541526708.688 [CommandLine](IMPORTANT): got command burn on
2018-11-06T17:51:48.688Z,1541526708.688 [CommandLine](IMPORTANT): Activating dropweight wire
2018-11-06T17:51:48.902Z,1541526708.902 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:52:09.594Z,1541526729.594 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:52:09.595Z,1541526729.595 [NAL9602] Communications Fault, FailCount= 2
2018-11-06T17:52:09.595Z,1541526729.595 [NAL9602](ERROR): Communications Fault
2018-11-06T17:52:09.699Z,1541526729.699 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:52:09.994Z,1541526729.994 [NAL9602](INFO): Powering down
2018-11-06T17:52:10.855Z,1541526730.855 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:52:10.855Z,1541526730.855 [NAL9602] No Fault, FailCount= 2
2018-11-06T17:52:34.634Z,1541526754.634 [CommandLine](IMPORTANT): got command burn off
2018-11-06T17:52:34.635Z,1541526754.635 [CommandLine](IMPORTANT): Deactivating dropweight wire
2018-11-06T17:52:40.401Z,1541526760.401 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:52:58.873Z,1541526778.873 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-11-06T17:47:58.6Z
2018-11-06T17:52:58.873Z,1541526778.873 [Default:CheckIn:Read_GPS] Stopped
2018-11-06T17:52:58.873Z,1541526778.873 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-11-06T17:53:03.555Z,1541526783.555 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:53:03.555Z,1541526783.555 [NAL9602] Communications Fault, FailCount= 3
2018-11-06T17:53:03.555Z,1541526783.555 [NAL9602](ERROR): Communications Fault
2018-11-06T17:53:03.643Z,1541526783.643 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:53:03.653Z,1541526783.653 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20181106T172703/Courier0007.lzma
2018-11-06T17:53:03.714Z,1541526783.714 [NAL9602](INFO): Powering down
2018-11-06T17:53:04.455Z,1541526784.455 [DataOverHttps](INFO): Moved sent file to Logs/20181106T172703/Courier0007.lzma.bak
2018-11-06T17:53:04.455Z,1541526784.455 [DataOverHttps](INFO): SBD MOMSN=8796636
2018-11-06T17:53:04.616Z,1541526784.616 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:53:04.616Z,1541526784.616 [NAL9602] No Fault, FailCount= 3
2018-11-06T17:53:13.869Z,1541526793.869 [DataOverHttps](INFO): Sending 248 bytes from file Logs/20181106T172703/Express0008.lzma
2018-11-06T17:53:14.484Z,1541526794.484 [CommandLine](IMPORTANT): got command burn on
2018-11-06T17:53:14.484Z,1541526794.484 [CommandLine](IMPORTANT): Activating dropweight wire
2018-11-06T17:53:14.659Z,1541526794.659 [DataOverHttps](INFO): Moved sent file to Logs/20181106T172703/Express0008.lzma.bak
2018-11-06T17:53:14.660Z,1541526794.660 [DataOverHttps](INFO): SBD MOMSN=8796643
2018-11-06T17:53:15.355Z,1541526795.355 [Default:CheckIn:Read_Iridium] Stopped
2018-11-06T17:53:15.356Z,1541526795.356 [Default:CheckIn:C.Wait] Running Loop=1
2018-11-06T17:53:15.356Z,1541526795.356 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-11-06T17:53:33.874Z,1541526813.874 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:53:54.688Z,1541526834.688 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:53:54.689Z,1541526834.689 [NAL9602] Communications Fault, FailCount= 4
2018-11-06T17:53:54.690Z,1541526834.690 [NAL9602](ERROR): Communications Fault
2018-11-06T17:53:54.873Z,1541526834.873 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:53:55.074Z,1541526835.074 [NAL9602](INFO): Powering down
2018-11-06T17:53:55.967Z,1541526835.967 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:53:55.967Z,1541526835.967 [NAL9602] No Fault, FailCount= 4
2018-11-06T17:54:25.454Z,1541526865.454 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:54:28.297Z,1541526868.297 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-06T17:54:28.297Z,1541526868.297 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2018-11-06T17:54:28.297Z,1541526868.297 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T17:54:28.311Z,1541526868.311 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T17:54:28.699Z,1541526868.699 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T17:54:28.699Z,1541526868.699 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2018-11-06T17:54:29.092Z,1541526869.092 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T17:54:29.092Z,1541526869.092 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T17:54:29.093Z,1541526869.093 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T17:54:46.646Z,1541526886.646 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:54:46.647Z,1541526886.647 [NAL9602] Communications Fault, FailCount= 5
2018-11-06T17:54:46.647Z,1541526886.647 [NAL9602](ERROR): Communications Fault
2018-11-06T17:54:46.753Z,1541526886.753 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:54:46.754Z,1541526886.754 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2018-11-06T17:54:47.050Z,1541526887.050 [NAL9602](INFO): Powering down
2018-11-06T17:54:47.126Z,1541526887.126 [CBIT](INFO): Critical error at 20181106T175446
2018-11-06T17:55:01.234Z,1541526901.234 [CommandLine](IMPORTANT): got command burn off
2018-11-06T17:55:01.234Z,1541526901.234 [CommandLine](IMPORTANT): Deactivating dropweight wire
2018-11-06T17:55:30.374Z,1541526930.374 [CommandLine](IMPORTANT): got command burn on
2018-11-06T17:55:30.375Z,1541526930.375 [CommandLine](IMPORTANT): Activating dropweight wire
2018-11-06T17:55:40.439Z,1541526940.439 [CommandLine](IMPORTANT): got command burn off
2018-11-06T17:55:40.440Z,1541526940.440 [CommandLine](IMPORTANT): Deactivating dropweight wire
2018-11-06T17:56:00.648Z,1541526960.648 [CommandLine](IMPORTANT): got command burn on
2018-11-06T17:56:00.648Z,1541526960.648 [CommandLine](IMPORTANT): Activating dropweight wire
2018-11-06T17:56:32.339Z,1541526992.339 [CommandLine](IMPORTANT): got command burn off
2018-11-06T17:56:32.339Z,1541526992.339 [CommandLine](IMPORTANT): Deactivating dropweight wire
2018-11-06T17:56:47.079Z,1541527007.079 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:56:47.079Z,1541527007.079 [NAL9602] No Fault, FailCount= 5
2018-11-06T17:56:47.294Z,1541527007.294 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:57:08.499Z,1541527028.499 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:57:08.499Z,1541527028.499 [NAL9602] Communications Fault, FailCount= 1
2018-11-06T17:57:08.499Z,1541527028.499 [NAL9602](ERROR): Communications Fault
2018-11-06T17:57:08.674Z,1541527028.674 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:57:08.894Z,1541527028.894 [NAL9602](INFO): Powering down
2018-11-06T17:57:09.750Z,1541527029.750 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:57:09.750Z,1541527029.750 [NAL9602] No Fault, FailCount= 1
2018-11-06T17:57:25.622Z,1541527045.622 [CommandLine](IMPORTANT): got command burn on
2018-11-06T17:57:25.622Z,1541527045.622 [CommandLine](IMPORTANT): Activating dropweight wire
2018-11-06T17:57:29.167Z,1541527049.167 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-06T17:57:29.167Z,1541527049.167 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2018-11-06T17:57:29.167Z,1541527049.167 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T17:57:29.220Z,1541527049.220 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T17:57:29.572Z,1541527049.572 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T17:57:29.572Z,1541527049.572 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2018-11-06T17:57:29.958Z,1541527049.958 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T17:57:29.958Z,1541527049.958 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T17:57:29.959Z,1541527049.959 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T17:57:39.102Z,1541527059.102 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:57:51.463Z,1541527071.463 [CommandLine](IMPORTANT): got command burn off
2018-11-06T17:57:51.463Z,1541527071.463 [CommandLine](IMPORTANT): Deactivating dropweight wire
2018-11-06T17:57:59.903Z,1541527079.903 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:57:59.903Z,1541527079.903 [NAL9602] Communications Fault, FailCount= 2
2018-11-06T17:57:59.903Z,1541527079.903 [NAL9602](ERROR): Communications Fault
2018-11-06T17:58:00.017Z,1541527080.017 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:58:00.302Z,1541527080.302 [NAL9602](INFO): Powering down
2018-11-06T17:58:01.161Z,1541527081.161 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:58:01.161Z,1541527081.161 [NAL9602] No Fault, FailCount= 2
2018-11-06T17:58:15.549Z,1541527095.549 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-11-06T17:58:15.549Z,1541527095.549 [Default:CheckIn:C.Wait] Stopped
2018-11-06T17:58:15.549Z,1541527095.549 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-11-06T17:58:15.550Z,1541527095.550 [Default:CheckIn:D] Running Loop=1
2018-11-06T17:58:15.945Z,1541527095.945 [Default:CheckIn:D] Stopped
2018-11-06T17:58:15.945Z,1541527095.945 [Default:CheckIn:E] Running Loop=1
2018-11-06T17:58:16.380Z,1541527096.380 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.511696 min 
2018-11-06T17:58:16.381Z,1541527096.381 [Default:CheckIn:E] Stopped
2018-11-06T17:58:16.382Z,1541527096.382 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-11-06T17:58:16.382Z,1541527096.382 [Default:CheckIn] Stopped
2018-11-06T17:58:16.382Z,1541527096.382 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-11-06T17:58:16.382Z,1541527096.382 [Default:CheckIn](INFO): Running loop #3
2018-11-06T17:58:16.382Z,1541527096.382 [Default:CheckIn] Running Loop=3
2018-11-06T17:58:16.382Z,1541527096.382 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-11-06T17:58:16.383Z,1541527096.383 [Default:CheckIn:Read_GPS] Running Loop=1
2018-11-06T17:58:30.492Z,1541527110.492 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:58:39.647Z,1541527119.647 [CommandLine](IMPORTANT): got command burn on
2018-11-06T17:58:39.647Z,1541527119.647 [CommandLine](IMPORTANT): Activating dropweight wire
2018-11-06T17:58:51.690Z,1541527131.690 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:58:51.691Z,1541527131.691 [NAL9602] Communications Fault, FailCount= 3
2018-11-06T17:58:51.691Z,1541527131.691 [NAL9602](ERROR): Communications Fault
2018-11-06T17:58:51.761Z,1541527131.761 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:58:52.082Z,1541527132.082 [NAL9602](INFO): Powering down
2018-11-06T17:58:53.075Z,1541527133.075 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:58:53.075Z,1541527133.075 [NAL9602] No Fault, FailCount= 3
2018-11-06T17:59:22.310Z,1541527162.310 [NAL9602](INFO): Powering up NAL9602
2018-11-06T17:59:25.222Z,1541527165.222 [CommandLine](IMPORTANT): got command burn off
2018-11-06T17:59:25.222Z,1541527165.222 [CommandLine](IMPORTANT): Deactivating dropweight wire
2018-11-06T17:59:43.507Z,1541527183.507 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T17:59:43.507Z,1541527183.507 [NAL9602] Communications Fault, FailCount= 4
2018-11-06T17:59:43.507Z,1541527183.507 [NAL9602](ERROR): Communications Fault
2018-11-06T17:59:43.594Z,1541527183.594 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T17:59:43.906Z,1541527183.906 [NAL9602](INFO): Powering down
2018-11-06T17:59:44.862Z,1541527184.862 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T17:59:44.862Z,1541527184.862 [NAL9602] No Fault, FailCount= 4
2018-11-06T18:00:13.910Z,1541527213.910 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:00:30.325Z,1541527230.325 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-06T18:00:30.325Z,1541527230.325 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2018-11-06T18:00:30.325Z,1541527230.325 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T18:00:30.340Z,1541527230.340 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T18:00:30.755Z,1541527230.755 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T18:00:30.755Z,1541527230.755 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2018-11-06T18:00:31.155Z,1541527231.155 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T18:00:31.155Z,1541527231.155 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T18:00:31.156Z,1541527231.156 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T18:00:35.083Z,1541527235.083 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:00:35.083Z,1541527235.083 [NAL9602] Communications Fault, FailCount= 5
2018-11-06T18:00:35.083Z,1541527235.083 [NAL9602](ERROR): Communications Fault
2018-11-06T18:00:35.185Z,1541527235.185 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:00:35.186Z,1541527235.186 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2018-11-06T18:00:35.482Z,1541527235.482 [NAL9602](INFO): Powering down
2018-11-06T18:00:35.563Z,1541527235.563 [CBIT](INFO): Critical error at 20181106T180035
2018-11-06T18:02:35.144Z,1541527355.144 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:02:35.144Z,1541527355.144 [NAL9602] No Fault, FailCount= 5
2018-11-06T18:02:35.362Z,1541527355.362 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:02:56.563Z,1541527376.563 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:02:56.564Z,1541527376.564 [NAL9602] Communications Fault, FailCount= 1
2018-11-06T18:02:56.564Z,1541527376.564 [NAL9602](ERROR): Communications Fault
2018-11-06T18:02:56.683Z,1541527376.683 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:02:56.958Z,1541527376.958 [NAL9602](INFO): Powering down
2018-11-06T18:02:57.840Z,1541527377.840 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:02:57.840Z,1541527377.840 [NAL9602] No Fault, FailCount= 1
2018-11-06T18:03:16.628Z,1541527396.628 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-11-06T17:58:16.4Z
2018-11-06T18:03:16.628Z,1541527396.628 [Default:CheckIn:Read_GPS] Stopped
2018-11-06T18:03:16.628Z,1541527396.628 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-11-06T18:03:21.348Z,1541527401.348 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20181106T172703/Courier0010.lzma
2018-11-06T18:03:22.148Z,1541527402.148 [DataOverHttps](INFO): Moved sent file to Logs/20181106T172703/Courier0010.lzma.bak
2018-11-06T18:03:22.148Z,1541527402.148 [DataOverHttps](INFO): SBD MOMSN=8796658
2018-11-06T18:03:27.194Z,1541527407.194 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:03:31.540Z,1541527411.540 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-06T18:03:31.541Z,1541527411.541 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12
2018-11-06T18:03:31.541Z,1541527411.541 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T18:03:31.554Z,1541527411.554 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T18:03:31.566Z,1541527411.566 [DataOverHttps](INFO): Sending 235 bytes from file Logs/20181106T172703/Express0011.lzma
2018-11-06T18:03:31.700Z,1541527411.700 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T18:03:31.700Z,1541527411.700 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12
2018-11-06T18:03:32.020Z,1541527412.020 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T18:03:32.020Z,1541527412.020 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T18:03:32.025Z,1541527412.025 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T18:03:32.364Z,1541527412.364 [DataOverHttps](INFO): Moved sent file to Logs/20181106T172703/Express0011.lzma.bak
2018-11-06T18:03:32.364Z,1541527412.364 [DataOverHttps](INFO): SBD MOMSN=8796662
2018-11-06T18:03:33.209Z,1541527413.209 [Default:CheckIn:Read_Iridium] Stopped
2018-11-06T18:03:33.209Z,1541527413.209 [Default:CheckIn:C.Wait] Running Loop=1
2018-11-06T18:03:33.209Z,1541527413.209 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-11-06T18:03:48.015Z,1541527428.015 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:03:48.015Z,1541527428.015 [NAL9602] Communications Fault, FailCount= 2
2018-11-06T18:03:48.015Z,1541527428.015 [NAL9602](ERROR): Communications Fault
2018-11-06T18:03:48.120Z,1541527428.120 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:03:48.414Z,1541527428.414 [NAL9602](INFO): Powering down
2018-11-06T18:03:48.810Z,1541527428.810 [NAL9602](FAULT): LCB fault:  Current Limiter Activated.
2018-11-06T18:03:48.810Z,1541527428.810 [NAL9602] Hardware Fault, FailCount= 2
2018-11-06T18:03:48.810Z,1541527428.810 [NAL9602](ERROR): Hardware Fault
2018-11-06T18:03:49.285Z,1541527429.285 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:03:49.285Z,1541527429.285 [NAL9602] No Fault, FailCount= 2
2018-11-06T18:04:18.810Z,1541527458.810 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:04:39.975Z,1541527479.975 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:04:39.975Z,1541527479.975 [NAL9602] Communications Fault, FailCount= 3
2018-11-06T18:04:39.975Z,1541527479.975 [NAL9602](ERROR): Communications Fault
2018-11-06T18:04:40.070Z,1541527480.070 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:04:40.370Z,1541527480.370 [NAL9602](INFO): Powering down
2018-11-06T18:04:41.238Z,1541527481.238 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:04:41.238Z,1541527481.238 [NAL9602] No Fault, FailCount= 3
2018-11-06T18:05:10.770Z,1541527510.770 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:05:16.555Z,1541527516.555 [CBIT](INFO): Clearing failed state for component DropWeight
2018-11-06T18:05:16.556Z,1541527516.556 [DropWeight] No Fault, FailCount= 1
2018-11-06T18:05:31.575Z,1541527531.575 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:05:31.575Z,1541527531.575 [NAL9602] Communications Fault, FailCount= 4
2018-11-06T18:05:31.575Z,1541527531.575 [NAL9602](ERROR): Communications Fault
2018-11-06T18:05:31.653Z,1541527531.653 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:05:31.971Z,1541527531.971 [NAL9602](INFO): Powering down
2018-11-06T18:05:32.851Z,1541527532.851 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:05:32.852Z,1541527532.852 [NAL9602] No Fault, FailCount= 4
2018-11-06T18:06:01.987Z,1541527561.987 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:06:23.187Z,1541527583.187 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:06:23.187Z,1541527583.187 [NAL9602] Communications Fault, FailCount= 5
2018-11-06T18:06:23.187Z,1541527583.187 [NAL9602](ERROR): Communications Fault
2018-11-06T18:06:23.269Z,1541527583.269 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:06:23.270Z,1541527583.270 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2018-11-06T18:06:23.591Z,1541527583.591 [NAL9602](INFO): Powering down
2018-11-06T18:06:23.682Z,1541527583.682 [CBIT](INFO): Critical error at 20181106T180623
2018-11-06T18:06:32.032Z,1541527592.032 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-06T18:06:32.032Z,1541527592.032 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13
2018-11-06T18:06:32.033Z,1541527592.033 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T18:06:32.067Z,1541527592.067 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T18:06:32.440Z,1541527592.440 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T18:06:32.440Z,1541527592.440 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13
2018-11-06T18:06:32.835Z,1541527592.835 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T18:06:32.836Z,1541527592.836 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T18:06:32.836Z,1541527592.836 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T18:08:23.440Z,1541527703.440 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:08:23.440Z,1541527703.440 [NAL9602] No Fault, FailCount= 5
2018-11-06T18:08:23.779Z,1541527703.779 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:08:33.434Z,1541527713.434 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-11-06T18:08:33.434Z,1541527713.434 [Default:CheckIn:C.Wait] Stopped
2018-11-06T18:08:33.434Z,1541527713.434 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-11-06T18:08:33.434Z,1541527713.434 [Default:CheckIn:D] Running Loop=1
2018-11-06T18:08:33.841Z,1541527713.841 [Default:CheckIn:D] Stopped
2018-11-06T18:08:33.842Z,1541527713.842 [Default:CheckIn:E] Running Loop=1
2018-11-06T18:08:34.238Z,1541527714.238 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.809965 min 
2018-11-06T18:08:34.238Z,1541527714.238 [Default:CheckIn:E] Stopped
2018-11-06T18:08:34.238Z,1541527714.238 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-11-06T18:08:34.238Z,1541527714.238 [Default:CheckIn] Stopped
2018-11-06T18:08:34.238Z,1541527714.238 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-11-06T18:08:34.239Z,1541527714.239 [Default:CheckIn](INFO): Running loop #4
2018-11-06T18:08:34.239Z,1541527714.239 [Default:CheckIn] Running Loop=4
2018-11-06T18:08:34.239Z,1541527714.239 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-11-06T18:08:34.239Z,1541527714.239 [Default:CheckIn:Read_GPS] Running Loop=1
2018-11-06T18:08:44.779Z,1541527724.779 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:08:44.779Z,1541527724.779 [NAL9602] Communications Fault, FailCount= 1
2018-11-06T18:08:44.779Z,1541527724.779 [NAL9602](ERROR): Communications Fault
2018-11-06T18:08:44.860Z,1541527724.860 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:08:45.178Z,1541527725.178 [NAL9602](INFO): Powering down
2018-11-06T18:08:46.053Z,1541527726.053 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:08:46.054Z,1541527726.054 [NAL9602] No Fault, FailCount= 1
2018-11-06T18:09:15.583Z,1541527755.583 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:09:32.864Z,1541527772.864 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-06T18:09:32.864Z,1541527772.864 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14
2018-11-06T18:09:32.864Z,1541527772.864 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T18:09:32.877Z,1541527772.877 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T18:09:33.234Z,1541527773.234 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T18:09:33.235Z,1541527773.235 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14
2018-11-06T18:09:33.626Z,1541527773.626 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T18:09:33.626Z,1541527773.626 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T18:09:33.627Z,1541527773.627 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T18:09:38.100Z,1541527778.100 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:09:38.101Z,1541527778.101 [NAL9602] Communications Fault, FailCount= 2
2018-11-06T18:09:38.101Z,1541527778.101 [NAL9602](ERROR): Communications Fault
2018-11-06T18:09:38.198Z,1541527778.198 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:09:38.247Z,1541527778.247 [NAL9602](INFO): Powering down
2018-11-06T18:09:39.085Z,1541527779.085 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:09:39.085Z,1541527779.085 [NAL9602] No Fault, FailCount= 2
2018-11-06T18:10:08.627Z,1541527808.627 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:10:29.433Z,1541527829.433 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:10:29.433Z,1541527829.433 [NAL9602] Communications Fault, FailCount= 3
2018-11-06T18:10:29.433Z,1541527829.433 [NAL9602](ERROR): Communications Fault
2018-11-06T18:10:29.515Z,1541527829.515 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:10:29.826Z,1541527829.826 [NAL9602](INFO): Powering down
2018-11-06T18:10:30.700Z,1541527830.700 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:10:30.700Z,1541527830.700 [NAL9602] No Fault, FailCount= 3
2018-11-06T18:10:59.955Z,1541527859.955 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:11:20.377Z,1541527880.377 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:11:20.378Z,1541527880.378 [NAL9602] Communications Fault, FailCount= 4
2018-11-06T18:11:20.378Z,1541527880.378 [NAL9602](ERROR): Communications Fault
2018-11-06T18:11:20.593Z,1541527880.593 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:11:20.759Z,1541527880.759 [NAL9602](INFO): Powering down
2018-11-06T18:11:21.631Z,1541527881.631 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:11:21.632Z,1541527881.632 [NAL9602] No Fault, FailCount= 4
2018-11-06T18:11:51.155Z,1541527911.155 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:12:05.150Z,1541527925.150 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-11-06T18:12:11.955Z,1541527931.955 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:12:11.955Z,1541527931.955 [NAL9602] Communications Fault, FailCount= 5
2018-11-06T18:12:11.955Z,1541527931.955 [NAL9602](ERROR): Communications Fault
2018-11-06T18:12:12.054Z,1541527932.054 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:12:12.054Z,1541527932.054 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2018-11-06T18:12:12.363Z,1541527932.363 [NAL9602](INFO): Powering down
2018-11-06T18:12:12.570Z,1541527932.570 [CBIT](INFO): Critical error at 20181106T181212
2018-11-06T18:12:34.012Z,1541527954.012 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-06T18:12:34.012Z,1541527954.012 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15
2018-11-06T18:12:34.012Z,1541527954.012 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T18:12:34.026Z,1541527954.026 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T18:12:34.437Z,1541527954.437 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T18:12:34.437Z,1541527954.437 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15
2018-11-06T18:12:34.849Z,1541527954.849 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T18:12:34.849Z,1541527954.849 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T18:12:34.850Z,1541527954.850 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T18:13:05.150Z,1541527985.150 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2018-11-06T18:13:34.409Z,1541528014.409 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-11-06T18:08:34.2Z
2018-11-06T18:13:34.409Z,1541528014.409 [Default:CheckIn:Read_GPS] Stopped
2018-11-06T18:13:34.409Z,1541528014.409 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-11-06T18:13:41.279Z,1541528021.279 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.127716
2018-11-06T18:13:49.482Z,1541528029.482 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003095
2018-11-06T18:13:54.074Z,1541528034.074 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20181106T172703/Courier0013.lzma
2018-11-06T18:13:54.872Z,1541528034.872 [DataOverHttps](INFO): Moved sent file to Logs/20181106T172703/Courier0013.lzma.bak
2018-11-06T18:13:54.872Z,1541528034.872 [DataOverHttps](INFO): SBD MOMSN=8796685
2018-11-06T18:14:04.309Z,1541528044.309 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20181106T172703/Express0014.lzma
2018-11-06T18:14:05.092Z,1541528045.092 [DataOverHttps](INFO): Moved sent file to Logs/20181106T172703/Express0014.lzma.bak
2018-11-06T18:14:05.092Z,1541528045.092 [DataOverHttps](INFO): SBD MOMSN=8796689
2018-11-06T18:14:05.735Z,1541528045.735 [Default:CheckIn:Read_Iridium] Stopped
2018-11-06T18:14:05.735Z,1541528045.735 [Default:CheckIn:C.Wait] Running Loop=1
2018-11-06T18:14:05.735Z,1541528045.735 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-11-06T18:14:12.342Z,1541528052.342 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:14:12.343Z,1541528052.343 [NAL9602] No Fault, FailCount= 5
2018-11-06T18:14:12.668Z,1541528052.668 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:14:33.863Z,1541528073.863 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:14:33.863Z,1541528073.863 [NAL9602] Communications Fault, FailCount= 1
2018-11-06T18:14:33.863Z,1541528073.863 [NAL9602](ERROR): Communications Fault
2018-11-06T18:14:33.970Z,1541528073.970 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:14:34.267Z,1541528074.267 [NAL9602](INFO): Powering down
2018-11-06T18:14:35.120Z,1541528075.120 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:14:35.120Z,1541528075.120 [NAL9602] No Fault, FailCount= 1
2018-11-06T18:15:04.663Z,1541528104.663 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:15:25.671Z,1541528125.671 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:15:25.671Z,1541528125.671 [NAL9602] Communications Fault, FailCount= 2
2018-11-06T18:15:25.671Z,1541528125.671 [NAL9602](ERROR): Communications Fault
2018-11-06T18:15:25.751Z,1541528125.751 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:15:26.070Z,1541528126.070 [NAL9602](INFO): Powering down
2018-11-06T18:15:27.120Z,1541528127.120 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:15:27.120Z,1541528127.120 [NAL9602] No Fault, FailCount= 2
2018-11-06T18:15:34.937Z,1541528134.937 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-06T18:15:34.937Z,1541528134.937 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16
2018-11-06T18:15:34.937Z,1541528134.937 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T18:15:34.976Z,1541528134.976 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T18:15:35.335Z,1541528135.335 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T18:15:35.335Z,1541528135.335 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16
2018-11-06T18:15:35.726Z,1541528135.726 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T18:15:35.727Z,1541528135.727 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T18:15:35.727Z,1541528135.727 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T18:15:56.476Z,1541528156.476 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:16:20.035Z,1541528180.035 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:16:20.035Z,1541528180.035 [NAL9602] Communications Fault, FailCount= 3
2018-11-06T18:16:20.035Z,1541528180.035 [NAL9602](ERROR): Communications Fault
2018-11-06T18:16:20.125Z,1541528180.125 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:16:20.431Z,1541528180.431 [NAL9602](INFO): Powering down
2018-11-06T18:16:21.295Z,1541528181.295 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:16:21.295Z,1541528181.295 [NAL9602] No Fault, FailCount= 3
2018-11-06T18:16:50.831Z,1541528210.831 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:17:11.419Z,1541528231.419 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:17:11.419Z,1541528231.419 [NAL9602] Communications Fault, FailCount= 4
2018-11-06T18:17:11.419Z,1541528231.419 [NAL9602](ERROR): Communications Fault
2018-11-06T18:17:11.512Z,1541528231.512 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:17:11.823Z,1541528231.823 [NAL9602](INFO): Powering down
2018-11-06T18:17:12.702Z,1541528232.702 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:17:12.702Z,1541528232.702 [NAL9602] No Fault, FailCount= 4
2018-11-06T18:17:42.223Z,1541528262.223 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:18:03.427Z,1541528283.427 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:18:03.427Z,1541528283.427 [NAL9602] Communications Fault, FailCount= 5
2018-11-06T18:18:03.427Z,1541528283.427 [NAL9602](ERROR): Communications Fault
2018-11-06T18:18:03.520Z,1541528283.520 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:18:03.520Z,1541528283.520 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2018-11-06T18:18:03.823Z,1541528283.823 [NAL9602](INFO): Powering down
2018-11-06T18:18:03.920Z,1541528283.920 [CBIT](INFO): Critical error at 20181106T181803
2018-11-06T18:18:36.070Z,1541528316.070 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-06T18:18:36.070Z,1541528316.070 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 17
2018-11-06T18:18:36.070Z,1541528316.070 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T18:18:36.084Z,1541528316.084 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T18:18:36.645Z,1541528316.645 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T18:18:36.645Z,1541528316.645 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 17
2018-11-06T18:18:36.883Z,1541528316.883 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T18:18:36.883Z,1541528316.883 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T18:18:36.884Z,1541528316.884 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T18:19:06.081Z,1541528346.081 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-11-06T18:19:06.081Z,1541528346.081 [Default:CheckIn:C.Wait] Stopped
2018-11-06T18:19:06.081Z,1541528346.081 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-11-06T18:19:06.081Z,1541528346.081 [Default:CheckIn:D] Running Loop=1
2018-11-06T18:19:06.472Z,1541528346.472 [Default:CheckIn:D] Stopped
2018-11-06T18:19:06.472Z,1541528346.472 [Default:CheckIn:E] Running Loop=1
2018-11-06T18:19:06.881Z,1541528346.881 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.353813 min 
2018-11-06T18:19:06.881Z,1541528346.881 [Default:CheckIn:E] Stopped
2018-11-06T18:19:06.881Z,1541528346.881 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-11-06T18:19:06.882Z,1541528346.882 [Default:CheckIn] Stopped
2018-11-06T18:19:06.882Z,1541528346.882 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-11-06T18:19:06.882Z,1541528346.882 [Default:CheckIn](INFO): Running loop #5
2018-11-06T18:19:06.882Z,1541528346.882 [Default:CheckIn] Running Loop=5
2018-11-06T18:19:06.882Z,1541528346.882 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-11-06T18:19:06.882Z,1541528346.882 [Default:CheckIn:Read_GPS] Running Loop=1
2018-11-06T18:20:03.492Z,1541528403.492 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:20:03.513Z,1541528403.513 [NAL9602] No Fault, FailCount= 5
2018-11-06T18:20:03.843Z,1541528403.843 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:20:24.875Z,1541528424.875 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:20:24.875Z,1541528424.875 [NAL9602] Communications Fault, FailCount= 1
2018-11-06T18:20:24.875Z,1541528424.875 [NAL9602](ERROR): Communications Fault
2018-11-06T18:20:24.970Z,1541528424.970 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:20:25.275Z,1541528425.275 [NAL9602](INFO): Powering down
2018-11-06T18:20:26.124Z,1541528426.124 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:20:26.124Z,1541528426.124 [NAL9602] No Fault, FailCount= 1
2018-11-06T18:20:55.671Z,1541528455.671 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:21:16.443Z,1541528476.443 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:21:16.443Z,1541528476.443 [NAL9602] Communications Fault, FailCount= 2
2018-11-06T18:21:16.443Z,1541528476.443 [NAL9602](ERROR): Communications Fault
2018-11-06T18:21:16.531Z,1541528476.531 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:21:16.847Z,1541528476.847 [NAL9602](INFO): Powering down
2018-11-06T18:21:17.696Z,1541528477.696 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:21:17.696Z,1541528477.696 [NAL9602] No Fault, FailCount= 2
2018-11-06T18:21:36.896Z,1541528496.896 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-06T18:21:36.896Z,1541528496.896 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 18
2018-11-06T18:21:36.896Z,1541528496.896 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T18:21:36.910Z,1541528496.910 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T18:21:37.298Z,1541528497.298 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T18:21:37.298Z,1541528497.298 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 18
2018-11-06T18:21:37.695Z,1541528497.695 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T18:21:37.696Z,1541528497.696 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T18:21:37.696Z,1541528497.696 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T18:21:47.247Z,1541528507.247 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:22:11.555Z,1541528531.555 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:22:11.555Z,1541528531.555 [NAL9602] Communications Fault, FailCount= 3
2018-11-06T18:22:11.555Z,1541528531.555 [NAL9602](ERROR): Communications Fault
2018-11-06T18:22:11.652Z,1541528531.652 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:22:11.699Z,1541528531.699 [NAL9602](INFO): Powering down
2018-11-06T18:22:12.541Z,1541528532.541 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:22:12.541Z,1541528532.541 [NAL9602] No Fault, FailCount= 3
2018-11-06T18:22:42.071Z,1541528562.071 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:23:03.271Z,1541528583.271 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:23:03.271Z,1541528583.271 [NAL9602] Communications Fault, FailCount= 4
2018-11-06T18:23:03.271Z,1541528583.271 [NAL9602](ERROR): Communications Fault
2018-11-06T18:23:03.365Z,1541528583.365 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:23:03.671Z,1541528583.671 [NAL9602](INFO): Powering down
2018-11-06T18:23:04.537Z,1541528584.537 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:23:04.537Z,1541528584.537 [NAL9602] No Fault, FailCount= 4
2018-11-06T18:23:34.039Z,1541528614.039 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:23:55.239Z,1541528635.239 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:23:55.239Z,1541528635.239 [NAL9602] Communications Fault, FailCount= 5
2018-11-06T18:23:55.239Z,1541528635.239 [NAL9602](ERROR): Communications Fault
2018-11-06T18:23:55.342Z,1541528635.342 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:23:55.343Z,1541528635.343 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2018-11-06T18:23:55.639Z,1541528635.639 [NAL9602](INFO): Powering down
2018-11-06T18:23:55.712Z,1541528635.712 [CBIT](INFO): Critical error at 20181106T182355
2018-11-06T18:24:06.886Z,1541528646.886 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-11-06T18:19:06.9Z
2018-11-06T18:24:06.887Z,1541528646.887 [Default:CheckIn:Read_GPS] Stopped
2018-11-06T18:24:06.887Z,1541528646.887 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-11-06T18:24:11.553Z,1541528651.553 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20181106T172703/Courier0016.lzma
2018-11-06T18:24:12.320Z,1541528652.320 [DataOverHttps](INFO): Moved sent file to Logs/20181106T172703/Courier0016.lzma.bak
2018-11-06T18:24:12.320Z,1541528652.320 [DataOverHttps](INFO): SBD MOMSN=8796693
2018-11-06T18:24:21.769Z,1541528661.769 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20181106T172703/Express0017.lzma
2018-11-06T18:24:22.555Z,1541528662.555 [DataOverHttps](INFO): Moved sent file to Logs/20181106T172703/Express0017.lzma.bak
2018-11-06T18:24:22.555Z,1541528662.555 [DataOverHttps](INFO): SBD MOMSN=8796697
2018-11-06T18:24:23.207Z,1541528663.207 [Default:CheckIn:Read_Iridium] Stopped
2018-11-06T18:24:23.207Z,1541528663.207 [Default:CheckIn:C.Wait] Running Loop=1
2018-11-06T18:24:23.207Z,1541528663.207 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-11-06T18:24:37.772Z,1541528677.772 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-06T18:24:37.772Z,1541528677.772 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 19
2018-11-06T18:24:37.772Z,1541528677.772 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T18:24:37.797Z,1541528677.797 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T18:24:38.204Z,1541528678.204 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T18:24:38.204Z,1541528678.204 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 19
2018-11-06T18:24:38.604Z,1541528678.604 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T18:24:38.605Z,1541528678.605 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T18:24:38.605Z,1541528678.605 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T18:25:55.360Z,1541528755.360 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:25:55.365Z,1541528755.365 [NAL9602] No Fault, FailCount= 5
2018-11-06T18:25:55.715Z,1541528755.715 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:26:16.903Z,1541528776.903 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:26:16.903Z,1541528776.903 [NAL9602] Communications Fault, FailCount= 1
2018-11-06T18:26:16.903Z,1541528776.903 [NAL9602](ERROR): Communications Fault
2018-11-06T18:26:17.023Z,1541528777.023 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:26:17.303Z,1541528777.303 [NAL9602](INFO): Powering down
2018-11-06T18:26:18.178Z,1541528778.178 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:26:18.178Z,1541528778.178 [NAL9602] No Fault, FailCount= 1
2018-11-06T18:26:47.691Z,1541528807.691 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:27:08.504Z,1541528828.504 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:27:08.504Z,1541528828.504 [NAL9602] Communications Fault, FailCount= 2
2018-11-06T18:27:08.504Z,1541528828.504 [NAL9602](ERROR): Communications Fault
2018-11-06T18:27:08.623Z,1541528828.623 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:27:08.887Z,1541528828.887 [NAL9602](INFO): Powering down
2018-11-06T18:27:09.740Z,1541528829.740 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:27:09.740Z,1541528829.740 [NAL9602] No Fault, FailCount= 2
2018-11-06T18:27:38.749Z,1541528858.749 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-11-06T18:27:38.749Z,1541528858.749 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 20
2018-11-06T18:27:38.749Z,1541528858.749 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T18:27:38.763Z,1541528858.763 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T18:27:38.763Z,1541528858.763 [CBIT](FAULT): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T18:27:39.095Z,1541528859.095 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:27:59.891Z,1541528879.891 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:27:59.891Z,1541528879.891 [NAL9602] Communications Fault, FailCount= 3
2018-11-06T18:27:59.891Z,1541528879.891 [NAL9602](ERROR): Communications Fault
2018-11-06T18:27:59.968Z,1541528879.968 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:28:00.290Z,1541528880.290 [NAL9602](INFO): Powering down
2018-11-06T18:28:01.171Z,1541528881.171 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:28:01.171Z,1541528881.171 [NAL9602] No Fault, FailCount= 3
2018-11-06T18:28:30.531Z,1541528910.531 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:28:51.331Z,1541528931.331 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:28:51.331Z,1541528931.331 [NAL9602] Communications Fault, FailCount= 4
2018-11-06T18:28:51.331Z,1541528931.331 [NAL9602](ERROR): Communications Fault
2018-11-06T18:28:51.419Z,1541528931.419 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:28:51.731Z,1541528931.731 [NAL9602](INFO): Powering down
2018-11-06T18:28:52.648Z,1541528932.648 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:28:52.648Z,1541528932.648 [NAL9602] No Fault, FailCount= 4
2018-11-06T18:29:22.131Z,1541528962.131 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:29:23.777Z,1541528963.777 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-11-06T18:29:23.777Z,1541528963.777 [Default:CheckIn:C.Wait] Stopped
2018-11-06T18:29:23.777Z,1541528963.777 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-11-06T18:29:23.777Z,1541528963.777 [Default:CheckIn:D] Running Loop=1
2018-11-06T18:29:24.176Z,1541528964.176 [Default:CheckIn:D] Stopped
2018-11-06T18:29:24.176Z,1541528964.176 [Default:CheckIn:E] Running Loop=1
2018-11-06T18:29:24.595Z,1541528964.595 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 58.648881 min 
2018-11-06T18:29:24.595Z,1541528964.595 [Default:CheckIn:E] Stopped
2018-11-06T18:29:24.595Z,1541528964.595 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-11-06T18:29:24.595Z,1541528964.595 [Default:CheckIn] Stopped
2018-11-06T18:29:24.596Z,1541528964.596 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-11-06T18:29:24.596Z,1541528964.596 [Default:CheckIn](INFO): Running loop #6
2018-11-06T18:29:24.596Z,1541528964.596 [Default:CheckIn] Running Loop=6
2018-11-06T18:29:24.596Z,1541528964.596 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-11-06T18:29:24.596Z,1541528964.596 [Default:CheckIn:Read_GPS] Running Loop=1
2018-11-06T18:29:43.239Z,1541528983.239 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:29:43.239Z,1541528983.239 [NAL9602] Communications Fault, FailCount= 5
2018-11-06T18:29:43.239Z,1541528983.239 [NAL9602](ERROR): Communications Fault
2018-11-06T18:29:43.328Z,1541528983.328 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:29:43.333Z,1541528983.333 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2018-11-06T18:29:43.646Z,1541528983.646 [NAL9602](INFO): Powering down
2018-11-06T18:29:43.710Z,1541528983.710 [CBIT](INFO): Critical error at 20181106T182943
2018-11-06T18:30:00.572Z,1541529000.572 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2018-11-06T18:30:00.886Z,1541529000.886 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:30:11.351Z,1541529011.351 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2018-11-06T18:30:21.748Z,1541529021.748 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2018-11-06T18:30:25.343Z,1541529025.343 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:30:31.264Z,1541529031.264 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:30:31.344Z,1541529031.344 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2018-11-06T18:30:34.466Z,1541529034.466 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:30:37.655Z,1541529037.655 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:30:40.868Z,1541529040.868 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:30:41.742Z,1541529041.742 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2018-11-06T18:30:44.063Z,1541529044.063 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:30:47.266Z,1541529047.266 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:30:50.467Z,1541529050.467 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:30:52.121Z,1541529052.121 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2018-11-06T18:30:54.067Z,1541529054.067 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:30:57.266Z,1541529057.266 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:31:00.464Z,1541529060.464 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:31:02.128Z,1541529062.128 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2018-11-06T18:31:03.264Z,1541529063.264 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:31:06.560Z,1541529066.560 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:31:09.672Z,1541529069.672 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:31:12.644Z,1541529072.644 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2018-11-06T18:31:12.868Z,1541529072.868 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:31:16.060Z,1541529076.060 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:31:18.908Z,1541529078.908 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:31:21.673Z,1541529081.673 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:31:22.624Z,1541529082.624 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2018-11-06T18:31:24.856Z,1541529084.856 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:31:29.659Z,1541529089.659 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:31:32.520Z,1541529092.520 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2018-11-06T18:31:32.891Z,1541529092.891 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:31:35.660Z,1541529095.660 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:31:38.868Z,1541529098.868 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:31:42.536Z,1541529102.536 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2018-11-06T18:31:42.865Z,1541529102.865 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:31:43.312Z,1541529103.312 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:31:43.312Z,1541529103.312 [NAL9602] No Fault, FailCount= 5
2018-11-06T18:31:43.631Z,1541529103.631 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:31:46.059Z,1541529106.059 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:31:49.260Z,1541529109.260 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:31:52.488Z,1541529112.488 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:31:52.624Z,1541529112.624 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2018-11-06T18:31:55.662Z,1541529115.662 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:31:58.917Z,1541529118.917 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:32:01.658Z,1541529121.658 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:32:02.932Z,1541529122.932 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2018-11-06T18:32:04.827Z,1541529124.827 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:32:04.827Z,1541529124.827 [NAL9602] Communications Fault, FailCount= 1
2018-11-06T18:32:04.827Z,1541529124.827 [NAL9602](ERROR): Communications Fault
2018-11-06T18:32:04.880Z,1541529124.880 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:32:04.922Z,1541529124.922 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:32:05.223Z,1541529125.223 [NAL9602](INFO): Powering down
2018-11-06T18:32:06.088Z,1541529126.088 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:32:06.088Z,1541529126.088 [NAL9602] No Fault, FailCount= 1
2018-11-06T18:32:08.056Z,1541529128.056 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:32:11.263Z,1541529131.263 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:32:13.090Z,1541529133.090 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2018-11-06T18:32:14.456Z,1541529134.456 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:32:17.658Z,1541529137.658 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:32:20.874Z,1541529140.874 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:32:22.930Z,1541529142.930 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2018-11-06T18:32:24.065Z,1541529144.065 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:32:29.686Z,1541529149.686 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:32:32.882Z,1541529152.882 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:32:32.944Z,1541529152.944 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2018-11-06T18:32:35.235Z,1541529155.235 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:32:36.079Z,1541529156.079 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:32:39.269Z,1541529159.269 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:32:42.471Z,1541529162.471 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:32:43.338Z,1541529163.338 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2018-11-06T18:32:45.669Z,1541529165.669 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:32:48.864Z,1541529168.864 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:32:52.086Z,1541529172.086 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:32:56.439Z,1541529176.439 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:32:56.439Z,1541529176.439 [NAL9602] Communications Fault, FailCount= 2
2018-11-06T18:32:56.439Z,1541529176.439 [NAL9602](ERROR): Communications Fault
2018-11-06T18:32:56.527Z,1541529176.527 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:32:56.835Z,1541529176.835 [NAL9602](INFO): Powering down
2018-11-06T18:32:57.757Z,1541529177.757 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:32:57.757Z,1541529177.757 [NAL9602] No Fault, FailCount= 2
2018-11-06T18:33:10.530Z,1541529190.530 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:33:22.126Z,1541529202.126 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:33:26.843Z,1541529206.843 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:33:33.187Z,1541529213.187 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2018-11-06T18:33:47.891Z,1541529227.891 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:33:47.891Z,1541529227.891 [NAL9602] Communications Fault, FailCount= 3
2018-11-06T18:33:47.891Z,1541529227.891 [NAL9602](ERROR): Communications Fault
2018-11-06T18:33:47.970Z,1541529227.970 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:33:48.291Z,1541529228.291 [NAL9602](INFO): Powering down
2018-11-06T18:33:49.157Z,1541529229.157 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:33:49.161Z,1541529229.161 [NAL9602] No Fault, FailCount= 3
2018-11-06T18:34:18.698Z,1541529258.698 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:34:22.736Z,1541529262.736 [BPC1](ERROR): Battery stick #1 (s/n: 0138) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-11-06T18:34:22.737Z,1541529262.737 [BPC1](ERROR): Battery stick #9 (s/n: 0100) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-11-06T18:34:22.738Z,1541529262.738 [BPC1](ERROR): Battery stick #14 (s/n: 011D) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-11-06T18:34:22.739Z,1541529262.739 [BPC1](ERROR): Battery stick #15 (s/n: 014E) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-11-06T18:34:22.740Z,1541529262.740 [BPC1](FAULT): Battery stick #18 (s/n: 0140) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-11-06T18:34:22.740Z,1541529262.740 [BPC1](FAULT): Battery stick #21 (s/n: 00CD) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-11-06T18:34:22.741Z,1541529262.741 [BPC1](FAULT): Battery stick #22 (s/n: 009F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-11-06T18:34:22.742Z,1541529262.742 [BPC1](FAULT): Battery stick #25 (s/n: 00E8) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-11-06T18:34:22.743Z,1541529262.743 [BPC1](FAULT): Battery stick #26 (s/n: 00D1) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-11-06T18:34:22.744Z,1541529262.744 [BPC1](FAULT): Battery stick #28 (s/n: 0092) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-11-06T18:34:22.745Z,1541529262.745 [BPC1](FAULT): Battery stick #30 (s/n: 00E9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-11-06T18:34:22.746Z,1541529262.746 [BPC1](ERROR): Battery stick #32 (s/n: 0157) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-11-06T18:34:22.746Z,1541529262.746 [BPC1](FAULT): Battery stick #39 (s/n: 00FD) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-11-06T18:34:22.747Z,1541529262.747 [BPC1](ERROR): Battery stick #40 (s/n: 0095) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-11-06T18:34:22.748Z,1541529262.748 [BPC1](ERROR): Battery stick #43 (s/n: 00AE) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-11-06T18:34:22.749Z,1541529262.749 [BPC1](ERROR): Battery stick #44 (s/n: 00CB) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-11-06T18:34:22.750Z,1541529262.750 [BPC1](ERROR): Battery stick #45 (s/n: 00F7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-11-06T18:34:22.751Z,1541529262.751 [BPC1](ERROR): Battery stick #46 (s/n: 00A1) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-11-06T18:34:22.751Z,1541529262.751 [BPC1](ERROR): Battery stick #49 (s/n: 0103) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-11-06T18:34:22.752Z,1541529262.752 [BPC1](ERROR): Battery stick #50 (s/n: 0152) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-11-06T18:34:22.753Z,1541529262.753 [BPC1](ERROR): Battery stick #51 (s/n: 014A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-11-06T18:34:22.754Z,1541529262.754 [BPC1](ERROR): Battery stick #53 (s/n: 015E) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2018-11-06T18:34:22.755Z,1541529262.755 [BPC1](ERROR): Battery stick #54 (s/n: 008E) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-11-06T18:34:22.755Z,1541529262.755 [BPC1](FAULT): Battery stick #56 (s/n: 0091) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2018-11-06T18:34:22.756Z,1541529262.756 [BPC1](FAULT): Battery stick #57 (s/n: 0153) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2018-11-06T18:34:22.757Z,1541529262.757 [BPC1](INFO): Calculating totals. Valid battery stick count: 23. Valid reserve battery stick count: 2.
2018-11-06T18:34:24.731Z,1541529264.731 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-11-06T18:29:24.6Z
2018-11-06T18:34:24.731Z,1541529264.731 [Default:CheckIn:Read_GPS] Stopped
2018-11-06T18:34:24.731Z,1541529264.731 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-11-06T18:34:29.478Z,1541529269.478 [DataOverHttps](INFO): Sending 96 bytes from file Logs/20181106T172703/Courier0019.lzma
2018-11-06T18:34:30.252Z,1541529270.252 [DataOverHttps](INFO): Moved sent file to Logs/20181106T172703/Courier0019.lzma.bak
2018-11-06T18:34:30.252Z,1541529270.252 [DataOverHttps](INFO): SBD MOMSN=8796700
2018-11-06T18:34:39.773Z,1541529279.773 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:34:39.773Z,1541529279.773 [NAL9602] Communications Fault, FailCount= 4
2018-11-06T18:34:39.773Z,1541529279.773 [NAL9602](ERROR): Communications Fault
2018-11-06T18:34:39.862Z,1541529279.862 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:34:39.875Z,1541529279.875 [DataOverHttps](INFO): Sending 321 bytes from file Logs/20181106T172703/Express0020.lzma
2018-11-06T18:34:39.935Z,1541529279.935 [NAL9602](INFO): Powering down
2018-11-06T18:34:40.672Z,1541529280.672 [DataOverHttps](INFO): Moved sent file to Logs/20181106T172703/Express0020.lzma.bak
2018-11-06T18:34:40.672Z,1541529280.672 [DataOverHttps](INFO): SBD MOMSN=8796703
2018-11-06T18:34:40.757Z,1541529280.757 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:34:40.757Z,1541529280.757 [NAL9602] No Fault, FailCount= 4
2018-11-06T18:34:41.328Z,1541529281.328 [Default:CheckIn:Read_Iridium] Stopped
2018-11-06T18:34:41.328Z,1541529281.328 [Default:CheckIn:C.Wait] Running Loop=1
2018-11-06T18:34:41.328Z,1541529281.328 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-11-06T18:35:10.263Z,1541529310.263 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:35:31.463Z,1541529331.463 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:35:31.463Z,1541529331.463 [NAL9602] Communications Fault, FailCount= 5
2018-11-06T18:35:31.463Z,1541529331.463 [NAL9602](ERROR): Communications Fault
2018-11-06T18:35:31.560Z,1541529331.560 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:35:31.560Z,1541529331.560 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2018-11-06T18:35:31.863Z,1541529331.863 [NAL9602](INFO): Powering down
2018-11-06T18:35:31.942Z,1541529331.942 [CBIT](INFO): Critical error at 20181106T183531
2018-11-06T18:37:31.534Z,1541529451.534 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:37:31.534Z,1541529451.534 [NAL9602] No Fault, FailCount= 5
2018-11-06T18:37:31.883Z,1541529451.883 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:37:55.675Z,1541529475.675 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:37:55.675Z,1541529475.675 [NAL9602] Communications Fault, FailCount= 1
2018-11-06T18:37:55.675Z,1541529475.675 [NAL9602](ERROR): Communications Fault
2018-11-06T18:37:55.763Z,1541529475.763 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:37:56.075Z,1541529476.075 [NAL9602](INFO): Powering down
2018-11-06T18:37:56.959Z,1541529476.959 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:37:56.960Z,1541529476.960 [NAL9602] No Fault, FailCount= 1
2018-11-06T18:38:26.479Z,1541529506.479 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:38:47.110Z,1541529527.110 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:38:47.110Z,1541529527.110 [NAL9602] Communications Fault, FailCount= 2
2018-11-06T18:38:47.110Z,1541529527.110 [NAL9602](ERROR): Communications Fault
2018-11-06T18:38:47.237Z,1541529527.237 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:38:47.495Z,1541529527.495 [NAL9602](INFO): Powering down
2018-11-06T18:38:48.572Z,1541529528.572 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:38:48.589Z,1541529528.589 [NAL9602] No Fault, FailCount= 2
2018-11-06T18:39:17.895Z,1541529557.895 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:39:39.095Z,1541529579.095 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:39:39.095Z,1541529579.095 [NAL9602] Communications Fault, FailCount= 3
2018-11-06T18:39:39.095Z,1541529579.095 [NAL9602](ERROR): Communications Fault
2018-11-06T18:39:39.179Z,1541529579.179 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:39:39.495Z,1541529579.495 [NAL9602](INFO): Powering down
2018-11-06T18:39:40.353Z,1541529580.353 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:39:40.353Z,1541529580.353 [NAL9602] No Fault, FailCount= 3
2018-11-06T18:39:41.548Z,1541529581.548 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-11-06T18:39:41.557Z,1541529581.557 [Default:CheckIn:C.Wait] Stopped
2018-11-06T18:39:41.557Z,1541529581.557 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-11-06T18:39:41.557Z,1541529581.557 [Default:CheckIn:D] Running Loop=1
2018-11-06T18:39:41.958Z,1541529581.958 [Default:CheckIn:D] Stopped
2018-11-06T18:39:41.958Z,1541529581.958 [Default:CheckIn:E] Running Loop=1
2018-11-06T18:39:42.337Z,1541529582.337 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.945247 min 
2018-11-06T18:39:42.338Z,1541529582.338 [Default:CheckIn:E] Stopped
2018-11-06T18:39:42.338Z,1541529582.338 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-11-06T18:39:42.338Z,1541529582.338 [Default:CheckIn] Stopped
2018-11-06T18:39:42.338Z,1541529582.338 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-11-06T18:39:42.338Z,1541529582.338 [Default:CheckIn](INFO): Running loop #7
2018-11-06T18:39:42.338Z,1541529582.338 [Default:CheckIn] Running Loop=7
2018-11-06T18:39:42.338Z,1541529582.338 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-11-06T18:39:42.339Z,1541529582.339 [Default:CheckIn:Read_GPS] Running Loop=1
2018-11-06T18:40:09.703Z,1541529609.703 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:40:30.924Z,1541529630.924 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:40:30.924Z,1541529630.924 [NAL9602] Communications Fault, FailCount= 4
2018-11-06T18:40:30.924Z,1541529630.924 [NAL9602](ERROR): Communications Fault
2018-11-06T18:40:31.076Z,1541529631.076 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:40:31.307Z,1541529631.307 [NAL9602](INFO): Powering down
2018-11-06T18:40:32.178Z,1541529632.178 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:40:32.178Z,1541529632.178 [NAL9602] No Fault, FailCount= 4
2018-11-06T18:41:01.487Z,1541529661.487 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:41:22.687Z,1541529682.687 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:41:22.687Z,1541529682.687 [NAL9602] Communications Fault, FailCount= 5
2018-11-06T18:41:22.687Z,1541529682.687 [NAL9602](ERROR): Communications Fault
2018-11-06T18:41:22.783Z,1541529682.783 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:41:22.784Z,1541529682.784 [CBIT](CRITICAL): Communications Fault in component: NAL9602
2018-11-06T18:41:23.087Z,1541529683.087 [NAL9602](INFO): Powering down
2018-11-06T18:41:23.161Z,1541529683.161 [CBIT](INFO): Critical error at 20181106T184122
2018-11-06T18:42:39.061Z,1541529759.061 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T18:42:39.061Z,1541529759.061 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 20
2018-11-06T18:42:39.356Z,1541529759.356 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T18:42:39.356Z,1541529759.356 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T18:42:39.357Z,1541529759.357 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T18:43:21.798Z,1541529801.798 [CommandLine](IMPORTANT): got command configSet list
2018-11-06T18:43:21.798Z,1541529801.798 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-11-06T18:43:21.799Z,1541529801.799 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=28 count;

2018-11-06T18:43:21.799Z,1541529801.799 [CommandLine](IMPORTANT): BPC1.batteryMuxCycleTime=10 minute;

2018-11-06T18:43:21.799Z,1541529801.799 [CommandLine](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2018-11-06T18:43:21.799Z,1541529801.799 [CommandLine](IMPORTANT): Express none VerticalTemperatureHomogeneityIndexCalculator.vertical_temperature_homogeneity_index;

2018-11-06T18:43:21.800Z,1541529801.800 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;

2018-11-06T18:43:21.800Z,1541529801.800 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=1 bool;

2018-11-06T18:43:21.800Z,1541529801.800 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=213 cubic_centimeter;

2018-11-06T18:43:21.800Z,1541529801.800 [CommandLine](IMPORTANT): VerticalControl.massDefault=4 millimeter;

2018-11-06T18:43:22.811Z,1541529802.811 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:43:22.812Z,1541529802.812 [NAL9602] No Fault, FailCount= 5
2018-11-06T18:43:23.143Z,1541529803.143 [NAL9602](INFO): Powering up NAL9602
2018-11-06T18:43:44.516Z,1541529824.516 [CommandLine](IMPORTANT): got command configSet BPC1.loadAtStartup 0.000000 bool persist
2018-11-06T18:43:44.517Z,1541529824.517 [CommandLine](IMPORTANT): configSet BPC1.loadAtStartup requires a restart to take effect.
2018-11-06T18:43:47.206Z,1541529827.206 [NAL9602](ERROR): NAL9602 initialization error.
2018-11-06T18:43:47.206Z,1541529827.206 [NAL9602] Communications Fault, FailCount= 1
2018-11-06T18:43:47.206Z,1541529827.206 [NAL9602](ERROR): Communications Fault
2018-11-06T18:43:47.298Z,1541529827.298 [CBIT](ERROR): Communications Fault in component: NAL9602
2018-11-06T18:43:47.379Z,1541529827.379 [NAL9602](INFO): Powering down
2018-11-06T18:43:48.168Z,1541529828.168 [CBIT](INFO): Clearing failed state for component NAL9602
2018-11-06T18:43:48.169Z,1541529828.169 [NAL9602] No Fault, FailCount= 1
2018-11-06T18:43:50.606Z,1541529830.606 [CommandLine](IMPORTANT): got command restart application
2018-11-06T18:43:51.612Z,1541529831.612 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T18:43:51.612Z,1541529831.612 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-06T18:43:51.805Z,1541529831.805 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-11-06T18:43:51.805Z,1541529831.805 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-06T18:43:51.806Z,1541529831.806 [CommandLine](INFO): Join timeout helper Thread ID is 6605
2018-11-06T18:43:51.817Z,1541529831.817 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-11-06T18:43:51.817Z,1541529831.817 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-06T18:43:51.817Z,1541529831.817 [NavChartDb](INFO): Join timeout helper Thread ID is 6606
2018-11-06T18:43:51.933Z,1541529831.933 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T18:43:51.933Z,1541529831.933 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-06T18:43:51.949Z,1541529831.949 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-11-06T18:43:51.949Z,1541529831.949 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-06T18:43:51.949Z,1541529831.949 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6607
2018-11-06T18:43:52.117Z,1541529832.117 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T18:43:52.117Z,1541529832.117 [WetLabsBB2FL](INFO): Powering down
2018-11-06T18:43:52.118Z,1541529832.118 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-06T18:43:52.134Z,1541529832.134 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-11-06T18:43:52.134Z,1541529832.134 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-11-06T18:43:52.134Z,1541529832.134 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 6608
2018-11-06T18:43:52.329Z,1541529832.329 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T18:43:52.329Z,1541529832.329 [CTD_NeilBrown](INFO): Powering down
2018-11-06T18:43:52.330Z,1541529832.330 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-11-06T18:43:52.337Z,1541529832.337 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2018-11-06T18:43:52.337Z,1541529832.337 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-11-06T18:43:52.337Z,1541529832.337 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 6609
2018-11-06T18:43:52.874Z,1541529832.874 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T18:43:52.874Z,1541529832.874 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-06T18:43:53.167Z,1541529833.167 [Rowe_600LCM](INFO): Powering down
2018-11-06T18:43:53.169Z,1541529833.169 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-11-06T18:43:53.171Z,1541529833.171 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-11-06T18:43:53.171Z,1541529833.171 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-06T18:43:53.171Z,1541529833.171 [Radio_Surface](INFO): Join timeout helper Thread ID is 6611
2018-11-06T18:43:53.521Z,1541529833.521 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T18:43:53.522Z,1541529833.522 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-06T18:43:53.527Z,1541529833.527 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-11-06T18:43:53.527Z,1541529833.527 [logger ThreadHandler](INFO): Thread cancelled.
2018-11-06T18:43:53.527Z,1541529833.527 [logger](INFO): Join timeout helper Thread ID is 6612
2018-11-06T18:43:53.565Z,1541529833.565 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T18:43:53.565Z,1541529833.565 [logger ThreadHandler](INFO): Thread cancelled.
2018-11-06T18:43:53.585Z,1541529833.585 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-11-06T18:43:53.586Z,1541529833.586 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-06T18:43:53.586Z,1541529833.586 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-11-06T18:43:53.586Z,1541529833.586 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-11-06T18:43:53.586Z,1541529833.586 [controlThread](INFO): Join timeout helper Thread ID is 6613
2018-11-06T18:43:53.701Z,1541529833.701 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T18:43:53.701Z,1541529833.701 [controlThread](DEBUG): Uninitializing ControlThread
2018-11-06T18:43:53.702Z,1541529833.702 [NAL9602](INFO): Powering down
2018-11-06T18:43:53.703Z,1541529833.703 [PNI_TCM](INFO): Powering down
2018-11-06T18:43:53.789Z,1541529833.789 [Aanderaa_O2](INFO): Powering down
2018-11-06T18:43:53.791Z,1541529833.791 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-06T18:43:53.792Z,1541529833.792 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-11-06T18:43:53.792Z,1541529833.792 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-11-06T18:43:53.793Z,1541529833.793 [MissionManager](INFO): Uninitializing Mission Default
2018-11-06T18:43:53.793Z,1541529833.793 [Default] Stopped
2018-11-06T18:43:53.794Z,1541529833.794 [Default](DEBUG): Aggregate::uninitialize Default
2018-11-06T18:43:53.794Z,1541529833.794 [Default:B.GoToSurface] Stopped
2018-11-06T18:43:53.794Z,1541529833.794 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-11-06T18:43:53.794Z,1541529833.794 [Default:CheckIn] Stopped
2018-11-06T18:43:53.794Z,1541529833.794 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-11-06T18:43:53.794Z,1541529833.794 [Default:CheckIn:Read_GPS] Stopped
2018-11-06T18:43:53.798Z,1541529833.798 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-11-06T18:43:53.799Z,1541529833.799 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-11-06T18:43:53.799Z,1541529833.799 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-11-06T18:43:53.799Z,1541529833.799 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-11-06T18:43:53.799Z,1541529833.799 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-11-06T18:43:53.800Z,1541529833.800 [BuoyancyServo](INFO): Powering down
2018-11-06T18:43:53.813Z,1541529833.813 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-11-06T18:43:53.813Z,1541529833.813 [ElevatorServo](INFO): Powering down
2018-11-06T18:43:53.814Z,1541529833.814 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-11-06T18:43:53.814Z,1541529833.814 [MassServo](INFO): Powering down
2018-11-06T18:43:53.815Z,1541529833.815 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-11-06T18:43:53.815Z,1541529833.815 [RudderServo](INFO): Powering down
2018-11-06T18:43:53.816Z,1541529833.816 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-11-06T18:43:53.816Z,1541529833.816 [ThrusterServo](INFO): Powering down
2018-11-06T18:43:53.818Z,1541529833.818 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-11-06T18:43:53.818Z,1541529833.818 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-11-06T18:43:53.818Z,1541529833.818 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-11-06T18:43:53.819Z,1541529833.819 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-11-06T18:43:53.931Z,1541529833.931 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-11-06T18:43:53.938Z,1541529833.938 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-06T18:43:53.975Z,1541529833.975 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-06T18:43:53.978Z,1541529833.978 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-11-06T18:43:54.005Z,1541529834.005 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-06T18:43:54.067Z,1541529834.067 [logger ThreadHandler](INFO): Thread cancelled.